2026-03-14 00:23:33.994229 | Job console starting... 2026-03-14 00:23:34.007571 | Updating repositories 2026-03-14 00:23:34.153858 | Preparing job workspace 2026-03-14 00:23:37.969902 | Running Ansible setup... 2026-03-14 00:23:42.821999 | PRE-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master] 2026-03-14 00:23:43.470088 | 2026-03-14 00:23:43.470209 | PLAY [localhost] 2026-03-14 00:23:43.478551 | 2026-03-14 00:23:43.478621 | TASK [Gathering Facts] 2026-03-14 00:23:44.529765 | localhost | ok 2026-03-14 00:23:44.559632 | 2026-03-14 00:23:44.559836 | TASK [Setup log path fact] 2026-03-14 00:23:44.586232 | localhost | ok 2026-03-14 00:23:44.610827 | 2026-03-14 00:23:44.610940 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-03-14 00:23:44.655290 | localhost | ok 2026-03-14 00:23:44.675109 | 2026-03-14 00:23:44.675292 | TASK [emit-job-header : Print job information] 2026-03-14 00:23:44.727991 | # Job Information 2026-03-14 00:23:44.728208 | Ansible Version: 2.15.12 2026-03-14 00:23:44.728279 | Job: ansible-test-sanity-docker-stable-2.11 2026-03-14 00:23:44.728328 | Pipeline: periodic 2026-03-14 00:23:44.728358 | Executor: ze04.softwarefactory-project.io 2026-03-14 00:23:44.728385 | Triggered by: https://github.com/ansible-collections/ansible.network 2026-03-14 00:23:44.728416 | Log URL (when completed): https://ansible.softwarefactory-project.io/logs/4f4/ansible/4f45cdf82a334907ba7266322551a995/ 2026-03-14 00:23:44.728442 | Event ID: 5eb9dcce8b23451ebec78fa1a4f594cb 2026-03-14 00:23:44.734399 | 2026-03-14 00:23:44.734488 | LOOP [emit-job-header : Print node information] 2026-03-14 00:23:44.859913 | localhost | ok: 2026-03-14 00:23:44.860151 | localhost | # Node Information 2026-03-14 00:23:44.860179 | localhost | Inventory Hostname: controller 2026-03-14 00:23:44.860199 | localhost | Hostname: np0005646812 2026-03-14 00:23:44.860219 | localhost | Username: zuul 2026-03-14 00:23:44.860243 | localhost | Distro: Fedora 37 2026-03-14 00:23:44.860262 | localhost | Provider: ansible-vexxhost-ca-ymq-1 2026-03-14 00:23:44.860279 | localhost | Region: ca-ymq-1 2026-03-14 00:23:44.860296 | localhost | Label: ansible-fedora-37-1vcpu 2026-03-14 00:23:44.860313 | localhost | Product Name: OpenStack Nova 2026-03-14 00:23:44.860330 | localhost | Interface IP: 162.253.55.207 2026-03-14 00:23:44.882176 | 2026-03-14 00:23:44.882399 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-03-14 00:23:45.350575 | localhost -> localhost | changed 2026-03-14 00:23:45.362229 | 2026-03-14 00:23:45.362370 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-03-14 00:23:46.259931 | localhost -> localhost | changed 2026-03-14 00:23:46.280467 | 2026-03-14 00:23:46.280592 | PLAY [all:!appliance*] 2026-03-14 00:23:46.297019 | 2026-03-14 00:23:46.297108 | TASK [include_role : start-zuul-console] 2026-03-14 00:23:46.316326 | controller | ok 2026-03-14 00:23:46.331192 | 2026-03-14 00:23:46.331278 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-03-14 00:23:46.683498 | controller | ok 2026-03-14 00:23:46.707488 | 2026-03-14 00:23:46.707625 | TASK [use-our-mirror : Retrieve the IP address] 2026-03-14 00:23:47.723915 | controller | ok: Status code was 404 and not [200]: HTTP Error 404: Not Found 2026-03-14 00:23:47.730726 | 2026-03-14 00:23:47.730805 | TASK [use-our-mirror : Ensure the registry service is running on the mirror] 2026-03-14 00:23:48.261819 | controller | skipping: Conditional result was False 2026-03-14 00:23:48.276749 | 2026-03-14 00:23:48.277268 | TASK [use-our-mirror : Write the IP in /etc/hosts] 2026-03-14 00:23:48.304315 | controller | skipping: Conditional result was False 2026-03-14 00:23:48.320755 | 2026-03-14 00:23:48.320911 | TASK [use-our-mirror : Create the podman configuration directory] 2026-03-14 00:23:48.347971 | controller | skipping: Conditional result was False 2026-03-14 00:23:48.363801 | 2026-03-14 00:23:48.363989 | TASK [use-our-mirror : Copy the podman configuration] 2026-03-14 00:23:48.391090 | controller | skipping: Conditional result was False 2026-03-14 00:23:48.407645 | 2026-03-14 00:23:48.407890 | TASK [use-our-mirror : Copy the Yum configuration (main)] 2026-03-14 00:23:48.435546 | controller | skipping: Conditional result was False 2026-03-14 00:23:48.451967 | 2026-03-14 00:23:48.452451 | TASK [use-our-mirror : Copy the Yum configuration (update)] 2026-03-14 00:23:48.479207 | controller | skipping: Conditional result was False 2026-03-14 00:23:48.508308 | 2026-03-14 00:23:48.508517 | TASK [Disable Fedora Modular] 2026-03-14 00:23:48.774245 | controller | changed 2026-03-14 00:23:48.793616 | 2026-03-14 00:23:48.793874 | TASK [Enable EPEL] 2026-03-14 00:23:48.833533 | controller | skipping: Conditional result was False 2026-03-14 00:23:48.843134 | 2026-03-14 00:23:48.843253 | TASK [Register the RHEL node] 2026-03-14 00:23:49.394540 | 2026-03-14 00:23:49.394871 | TASK [Show the subscription-manager status] 2026-03-14 00:23:49.982946 | controller | skipping: Conditional result was False 2026-03-14 00:23:49.996928 | 2026-03-14 00:23:49.997075 | TASK [Enable EPEL on RHEL] 2026-03-14 00:23:50.552112 | controller | skipping: Conditional result was False 2026-03-14 00:23:50.561562 | 2026-03-14 00:23:50.561657 | TASK [Install git and tox] 2026-03-14 00:25:13.005263 | controller | changed 2026-03-14 00:25:13.019464 | 2026-03-14 00:25:13.019605 | TASK [include_role : prepare-workspace] 2026-03-14 00:25:13.054172 | controller | ok 2026-03-14 00:25:13.089530 | 2026-03-14 00:25:13.089654 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-03-14 00:25:13.435831 | controller | ok 2026-03-14 00:25:13.454889 | 2026-03-14 00:25:13.455093 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-03-14 00:25:26.044360 | controller | Output suppressed because no_log was given 2026-03-14 00:25:26.056095 | 2026-03-14 00:25:26.056218 | TASK [include_role : prepare-workspace-openshift] 2026-03-14 00:25:26.081830 | controller | skipping: Conditional result was False 2026-03-14 00:25:26.140895 | 2026-03-14 00:25:26.140972 | PLAY [all:!appliance] 2026-03-14 00:25:26.156379 | 2026-03-14 00:25:26.156468 | TASK [Run add-build-sshkey role (RSA)] 2026-03-14 00:25:26.186891 | controller | ok 2026-03-14 00:25:26.201500 | 2026-03-14 00:25:26.201607 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-03-14 00:25:26.449871 | controller -> localhost | ok 2026-03-14 00:25:26.457063 | 2026-03-14 00:25:26.457196 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-03-14 00:25:26.487741 | controller | ok 2026-03-14 00:25:26.503667 | controller | included: /var/lib/zuul/builds/4f45cdf82a334907ba7266322551a995/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-03-14 00:25:26.510320 | 2026-03-14 00:25:26.510460 | TASK [add-build-sshkey : Create Temp SSH key] 2026-03-14 00:25:27.014551 | controller -> localhost | Generating public/private rsa key pair. 2026-03-14 00:25:27.014821 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/4f45cdf82a334907ba7266322551a995/work/4f45cdf82a334907ba7266322551a995_id_rsa. 2026-03-14 00:25:27.014863 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/4f45cdf82a334907ba7266322551a995/work/4f45cdf82a334907ba7266322551a995_id_rsa.pub. 2026-03-14 00:25:27.014893 | controller -> localhost | The key fingerprint is: 2026-03-14 00:25:27.014921 | controller -> localhost | SHA256:WzyH0WwfbLeuG+ZhbUvBkxvKiqYgUT2PIoFPCFdQkH4 zuul-build-sshkey 2026-03-14 00:25:27.014947 | controller -> localhost | The key's randomart image is: 2026-03-14 00:25:27.014975 | controller -> localhost | +---[RSA 2048]----+ 2026-03-14 00:25:27.015001 | controller -> localhost | |. +*o | 2026-03-14 00:25:27.015028 | controller -> localhost | |..+ . o . | 2026-03-14 00:25:27.015054 | controller -> localhost | | + o . o . + + .| 2026-03-14 00:25:27.015079 | controller -> localhost | | + E +. + o.oo| 2026-03-14 00:25:27.015105 | controller -> localhost | | = . .S.= . .B | 2026-03-14 00:25:27.015143 | controller -> localhost | | o . o o. + =| 2026-03-14 00:25:27.015177 | controller -> localhost | | . . . B * | 2026-03-14 00:25:27.015204 | controller -> localhost | | . . .. = * .| 2026-03-14 00:25:27.015231 | controller -> localhost | | .o. . +.. | 2026-03-14 00:25:27.015261 | controller -> localhost | +----[SHA256]-----+ 2026-03-14 00:25:27.015319 | controller -> localhost | ok: Runtime: 0:00:00.102149 2026-03-14 00:25:27.025972 | 2026-03-14 00:25:27.026071 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-03-14 00:25:27.059083 | controller | ok 2026-03-14 00:25:27.072892 | controller | included: /var/lib/zuul/builds/4f45cdf82a334907ba7266322551a995/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-03-14 00:25:27.086802 | 2026-03-14 00:25:27.086890 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-03-14 00:25:27.111927 | controller | skipping: Conditional result was False 2026-03-14 00:25:27.121908 | 2026-03-14 00:25:27.122044 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-03-14 00:25:27.595394 | controller | changed 2026-03-14 00:25:27.604584 | 2026-03-14 00:25:27.604731 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-03-14 00:25:27.847664 | controller | ok 2026-03-14 00:25:27.863964 | 2026-03-14 00:25:27.864110 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-03-14 00:25:28.547808 | controller | changed 2026-03-14 00:25:28.560883 | 2026-03-14 00:25:28.561056 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-03-14 00:25:29.234901 | controller | changed 2026-03-14 00:25:29.249781 | 2026-03-14 00:25:29.249961 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-03-14 00:25:29.279209 | controller | skipping: Conditional result was False 2026-03-14 00:25:29.296618 | 2026-03-14 00:25:29.296903 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-03-14 00:25:29.716218 | controller -> localhost | changed 2026-03-14 00:25:29.741777 | 2026-03-14 00:25:29.741961 | TASK [add-build-sshkey : Add back temp key] 2026-03-14 00:25:30.096644 | controller -> localhost | Identity added: /var/lib/zuul/builds/4f45cdf82a334907ba7266322551a995/work/4f45cdf82a334907ba7266322551a995_id_rsa (zuul-build-sshkey) 2026-03-14 00:25:30.097124 | controller -> localhost | ok: Runtime: 0:00:00.015361 2026-03-14 00:25:30.112821 | 2026-03-14 00:25:30.112967 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-03-14 00:25:30.539690 | controller | ok 2026-03-14 00:25:30.545944 | 2026-03-14 00:25:30.546058 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-03-14 00:25:30.582835 | controller | skipping: Conditional result was False 2026-03-14 00:25:30.595462 | 2026-03-14 00:25:30.595591 | TASK [Run add-build-sshkey role (ECDSA)] 2026-03-14 00:25:30.628398 | controller | ok 2026-03-14 00:25:30.649375 | 2026-03-14 00:25:30.649640 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-03-14 00:25:30.920705 | controller -> localhost | ok 2026-03-14 00:25:30.931363 | 2026-03-14 00:25:30.931486 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-03-14 00:25:30.987074 | controller | ok 2026-03-14 00:25:31.012296 | controller | included: /var/lib/zuul/builds/4f45cdf82a334907ba7266322551a995/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-03-14 00:25:31.021435 | 2026-03-14 00:25:31.021531 | TASK [add-build-sshkey : Create Temp SSH key] 2026-03-14 00:25:31.328278 | controller -> localhost | Generating public/private ecdsa key pair. 2026-03-14 00:25:31.328501 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/4f45cdf82a334907ba7266322551a995/work/4f45cdf82a334907ba7266322551a995_id_ecdsa. 2026-03-14 00:25:31.328530 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/4f45cdf82a334907ba7266322551a995/work/4f45cdf82a334907ba7266322551a995_id_ecdsa.pub. 2026-03-14 00:25:31.328556 | controller -> localhost | The key fingerprint is: 2026-03-14 00:25:31.328576 | controller -> localhost | SHA256:obdsI7C8V6Uf231j0HSEtK4p++N1u9xzv9GJXm5QrO0 zuul-build-sshkey 2026-03-14 00:25:31.328594 | controller -> localhost | The key's randomart image is: 2026-03-14 00:25:31.328611 | controller -> localhost | +---[ECDSA 521]---+ 2026-03-14 00:25:31.328627 | controller -> localhost | | ... | 2026-03-14 00:25:31.328644 | controller -> localhost | | ...| 2026-03-14 00:25:31.328660 | controller -> localhost | | . o. | 2026-03-14 00:25:31.328692 | controller -> localhost | | . .. . +.| 2026-03-14 00:25:31.328714 | controller -> localhost | | . . So B .| 2026-03-14 00:25:31.328732 | controller -> localhost | | . o oo.. *.oo| 2026-03-14 00:25:31.328748 | controller -> localhost | | o ..=..+oo=+o| 2026-03-14 00:25:31.328764 | controller -> localhost | | ..o .oo+.=E*| 2026-03-14 00:25:31.328780 | controller -> localhost | | .. .ooooBX| 2026-03-14 00:25:31.328796 | controller -> localhost | +----[SHA256]-----+ 2026-03-14 00:25:31.328872 | controller -> localhost | ok: Runtime: 0:00:00.007960 2026-03-14 00:25:31.335864 | 2026-03-14 00:25:31.335933 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-03-14 00:25:31.377203 | controller | ok 2026-03-14 00:25:31.384784 | controller | included: /var/lib/zuul/builds/4f45cdf82a334907ba7266322551a995/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-03-14 00:25:31.393671 | 2026-03-14 00:25:31.393755 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-03-14 00:25:31.428869 | controller | skipping: Conditional result was False 2026-03-14 00:25:31.435429 | 2026-03-14 00:25:31.435493 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-03-14 00:25:31.738419 | controller | changed 2026-03-14 00:25:31.752741 | 2026-03-14 00:25:31.753498 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-03-14 00:25:31.985511 | controller | ok 2026-03-14 00:25:31.994948 | 2026-03-14 00:25:31.995112 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-03-14 00:25:32.723608 | controller | changed 2026-03-14 00:25:32.739903 | 2026-03-14 00:25:32.740083 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-03-14 00:25:33.407056 | controller | changed 2026-03-14 00:25:33.413361 | 2026-03-14 00:25:33.413449 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-03-14 00:25:33.449573 | controller | skipping: Conditional result was False 2026-03-14 00:25:33.459374 | 2026-03-14 00:25:33.459658 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-03-14 00:25:33.711360 | controller -> localhost | changed 2026-03-14 00:25:33.756695 | 2026-03-14 00:25:33.756828 | TASK [add-build-sshkey : Add back temp key] 2026-03-14 00:25:34.121285 | controller -> localhost | Identity added: /var/lib/zuul/builds/4f45cdf82a334907ba7266322551a995/work/4f45cdf82a334907ba7266322551a995_id_ecdsa (zuul-build-sshkey) 2026-03-14 00:25:34.121567 | controller -> localhost | ok: Runtime: 0:00:00.015593 2026-03-14 00:25:34.130990 | 2026-03-14 00:25:34.131084 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-03-14 00:25:34.365345 | controller | ok 2026-03-14 00:25:34.378315 | 2026-03-14 00:25:34.378491 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-03-14 00:25:34.428040 | controller | skipping: Conditional result was False 2026-03-14 00:25:34.440921 | 2026-03-14 00:25:34.441036 | TASK [include_role : remove-zuul-sshkey] 2026-03-14 00:25:34.467652 | controller | skipping: Conditional result was False 2026-03-14 00:25:34.477586 | 2026-03-14 00:25:34.477753 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-03-14 00:25:34.727721 | controller | ok: "logs" 2026-03-14 00:25:34.728007 | controller | ok: All items complete 2026-03-14 00:25:34.728046 | 2026-03-14 00:25:34.934794 | controller | ok: "artifacts" 2026-03-14 00:25:35.130873 | controller | ok: "docs" 2026-03-14 00:25:35.153740 | 2026-03-14 00:25:35.153928 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-03-14 00:25:35.409947 | controller | changed: "logs" 2026-03-14 00:25:35.605354 | controller | changed: "artifacts" 2026-03-14 00:25:35.821616 | controller | changed: "docs" 2026-03-14 00:25:35.883841 | 2026-03-14 00:25:35.883956 | PLAY RECAP 2026-03-14 00:25:35.884014 | controller | ok: 30 changed: 16 unreachable: 0 failed: 0 skipped: 18 rescued: 0 ignored: 0 2026-03-14 00:25:35.884050 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-03-14 00:25:35.884075 | 2026-03-14 00:25:36.004753 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master] 2026-03-14 00:25:36.006589 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2026-03-14 00:25:36.688282 | 2026-03-14 00:25:36.688409 | PLAY [all] 2026-03-14 00:25:36.711147 | 2026-03-14 00:25:36.711253 | TASK [Install binary dependencies] 2026-03-14 00:25:36.801800 | controller | ok 2026-03-14 00:25:36.834939 | 2026-03-14 00:25:36.835074 | TASK [bindep : Include find tasks] 2026-03-14 00:25:36.869566 | controller | ok 2026-03-14 00:25:36.881073 | controller | included: /var/lib/zuul/builds/4f45cdf82a334907ba7266322551a995/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find.yaml 2026-03-14 00:25:36.890197 | 2026-03-14 00:25:36.890315 | TASK [bindep : Look for bindep.txt] 2026-03-14 00:25:37.329032 | controller | ok 2026-03-14 00:25:37.342542 | 2026-03-14 00:25:37.342741 | TASK [bindep : Define bindep_file fact] 2026-03-14 00:25:37.371023 | controller | skipping: Conditional result was False 2026-03-14 00:25:37.385414 | 2026-03-14 00:25:37.385587 | TASK [bindep : Look for other-requirements.txt] 2026-03-14 00:25:37.619582 | controller | ok 2026-03-14 00:25:37.627427 | 2026-03-14 00:25:37.627523 | TASK [bindep : Define bindep_file fact] 2026-03-14 00:25:37.662940 | controller | skipping: Conditional result was False 2026-03-14 00:25:37.672018 | 2026-03-14 00:25:37.672159 | TASK [bindep : Look for bindep fallback file] 2026-03-14 00:25:37.717763 | controller | skipping: Conditional result was False 2026-03-14 00:25:37.733993 | 2026-03-14 00:25:37.734226 | TASK [bindep : Define bindep_file fact] 2026-03-14 00:25:37.764061 | controller | skipping: Conditional result was False 2026-03-14 00:25:37.773753 | 2026-03-14 00:25:37.773896 | TASK [bindep : Include bindep tasks] 2026-03-14 00:25:37.810596 | controller | skipping: Conditional result was False 2026-03-14 00:25:37.819788 | 2026-03-14 00:25:37.819894 | TASK [bindep : Include install tasks] 2026-03-14 00:25:37.856425 | controller | skipping: Conditional result was False 2026-03-14 00:25:37.870521 | 2026-03-14 00:25:37.870665 | LOOP [bindep : Include package tasks] 2026-03-14 00:25:37.965012 | 2026-03-14 00:25:37.965332 | TASK [Run test-setup role] 2026-03-14 00:25:38.000112 | controller | ok 2026-03-14 00:25:38.029238 | 2026-03-14 00:25:38.029390 | TASK [test-setup : Check if project's tools/test-setup.sh exists] 2026-03-14 00:25:38.253850 | controller | ok 2026-03-14 00:25:38.267672 | 2026-03-14 00:25:38.267854 | TASK [test-setup : Run tools/test-setup.sh] 2026-03-14 00:25:38.831258 | controller | skipping: Conditional result was False 2026-03-14 00:25:38.886784 | 2026-03-14 00:25:38.886886 | PLAY RECAP 2026-03-14 00:25:38.886942 | controller | ok: 4 changed: 0 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2026-03-14 00:25:38.886969 | 2026-03-14 00:25:38.993941 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2026-03-14 00:25:38.994821 | PRE-RUN START: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master] 2026-03-14 00:25:39.603664 | 2026-03-14 00:25:39.603815 | PLAY [controller] 2026-03-14 00:25:39.624371 | 2026-03-14 00:25:39.624497 | TASK [Create the /root directory] 2026-03-14 00:25:40.009660 | controller | ok 2026-03-14 00:25:40.023877 | 2026-03-14 00:25:40.024169 | TASK [Install glibc-langpack-en] 2026-03-14 00:25:44.078346 | controller | ok: Nothing to do 2026-03-14 00:25:44.090505 | 2026-03-14 00:25:44.090639 | TASK [Ensure controller directory exists] 2026-03-14 00:25:44.304248 | controller | changed 2026-03-14 00:25:44.318285 | 2026-03-14 00:25:44.318420 | TASK [Install container runtime] 2026-03-14 00:25:44.399820 | controller | ok 2026-03-14 00:25:44.466968 | 2026-03-14 00:25:44.467094 | LOOP [ensure-docker : Gather variables for each operating system] 2026-03-14 00:25:44.531273 | controller | ok: "/var/lib/zuul/builds/4f45cdf82a334907ba7266322551a995/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/vars/fedora.yaml" 2026-03-14 00:25:44.549879 | 2026-03-14 00:25:44.550087 | TASK [ensure-docker : Sanity check for distro selection] 2026-03-14 00:25:44.576969 | controller | skipping: Conditional result was False 2026-03-14 00:25:44.585345 | 2026-03-14 00:25:44.585438 | TASK [ensure-docker : Sanity check for distro install] 2026-03-14 00:25:44.610649 | controller | skipping: Conditional result was False 2026-03-14 00:25:44.619074 | 2026-03-14 00:25:44.619172 | TASK [ensure-docker : Sanity check for upstream install] 2026-03-14 00:25:44.644748 | controller | skipping: Conditional result was False 2026-03-14 00:25:44.658282 | 2026-03-14 00:25:44.658425 | TASK [ensure-docker : Sanity check for upstream install mirrors] 2026-03-14 00:25:44.685875 | controller | skipping: Conditional result was False 2026-03-14 00:25:44.695992 | 2026-03-14 00:25:44.696086 | TASK [ensure-docker : Include tasks for docker install] 2026-03-14 00:25:44.717376 | controller | ok 2026-03-14 00:25:44.726321 | controller | included: /var/lib/zuul/builds/4f45cdf82a334907ba7266322551a995/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/tasks/docker-upstream.yaml 2026-03-14 00:25:44.734744 | 2026-03-14 00:25:44.734841 | TASK [ensure-docker : Not Implemented] 2026-03-14 00:25:44.759426 | controller | skipping: Conditional result was False 2026-03-14 00:25:44.769898 | 2026-03-14 00:25:44.769996 | TASK [Set up docker mirrors] 2026-03-14 00:25:44.793148 | controller | ok 2026-03-14 00:25:44.822411 | 2026-03-14 00:25:44.822540 | TASK [use-docker-mirror : Set docker_mirror fact from mirror_info] 2026-03-14 00:25:44.847239 | controller | skipping: Conditional result was False 2026-03-14 00:25:44.854476 | 2026-03-14 00:25:44.854565 | TASK [use-docker-mirror : Set docker_mirror fact from zuul_site_mirror_info] 2026-03-14 00:25:44.879217 | controller | skipping: Conditional result was False 2026-03-14 00:25:44.886043 | 2026-03-14 00:25:44.886129 | TASK [use-docker-mirror : Set docker_mirror fact] 2026-03-14 00:25:44.920731 | controller | skipping: Conditional result was False 2026-03-14 00:25:44.927441 | 2026-03-14 00:25:44.927518 | TASK [use-docker-mirror : Set up docker mirrors] 2026-03-14 00:25:44.951947 | controller | skipping: Conditional result was False 2026-03-14 00:25:44.964956 | 2026-03-14 00:25:44.965040 | TASK [Add all repositories] 2026-03-14 00:25:44.986171 | controller | ok 2026-03-14 00:25:45.009941 | 2026-03-14 00:25:45.010019 | LOOP [ensure-package-repositories : Install repositories] 2026-03-14 00:25:45.031146 | controller | ok: "/var/lib/zuul/builds/4f45cdf82a334907ba7266322551a995/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-package-repositories/tasks/RedHat.yaml" 2026-03-14 00:25:45.031274 | controller | ok: All items complete 2026-03-14 00:25:45.031302 | 2026-03-14 00:25:45.071031 | controller | included: /var/lib/zuul/builds/4f45cdf82a334907ba7266322551a995/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-package-repositories/tasks/RedHat.yaml 2026-03-14 00:25:45.077578 | 2026-03-14 00:25:45.077648 | TASK [ensure-package-repositories : Create GPG key temp directory] 2026-03-14 00:25:45.373156 | controller | changed 2026-03-14 00:25:45.388194 | 2026-03-14 00:25:45.388337 | LOOP [ensure-package-repositories : Upload GPG keys] 2026-03-14 00:25:46.287952 | controller | changed: 2026-03-14 00:25:46.288562 | controller | { 2026-03-14 00:25:46.288639 | 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-14 00:25:46.288742 | controller | } 2026-03-14 00:25:46.306864 | 2026-03-14 00:25:46.307108 | LOOP [ensure-package-repositories : Import GPG keys] 2026-03-14 00:25:46.932008 | controller | changed: 2026-03-14 00:25:46.932517 | controller | { 2026-03-14 00:25:46.932597 | 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-14 00:25:46.932650 | controller | } 2026-03-14 00:25:46.932747 | controller | changed: All items complete 2026-03-14 00:25:46.932799 | 2026-03-14 00:25:46.958465 | 2026-03-14 00:25:46.958719 | TASK [ensure-package-repositories : Remove GPG temp directory] 2026-03-14 00:25:47.187988 | controller | changed 2026-03-14 00:25:47.202771 | 2026-03-14 00:25:47.202940 | LOOP [ensure-package-repositories : Add repositories] 2026-03-14 00:25:47.524680 | controller | changed: 2026-03-14 00:25:47.525615 | controller | { 2026-03-14 00:25:47.525656 | controller | "baseurl": "https://download.docker.com/linux/fedora/$releasever/$basearch/stable", 2026-03-14 00:25:47.525719 | controller | "description": "Docker CE Stable - $basearch", 2026-03-14 00:25:47.525756 | controller | "gpgcheck": true, 2026-03-14 00:25:47.525783 | controller | "name": "docker-ce-stable" 2026-03-14 00:25:47.526409 | controller | } 2026-03-14 00:25:47.546149 | 2026-03-14 00:25:47.546294 | TASK [ensure-docker : Install pre-reqs] 2026-03-14 00:25:50.342820 | controller | ok: Nothing to do 2026-03-14 00:25:50.348670 | 2026-03-14 00:25:50.348812 | TASK [ensure-docker : Remove packages] 2026-03-14 00:25:51.650111 | controller | ok: Nothing to do 2026-03-14 00:25:51.655771 | 2026-03-14 00:25:51.655839 | TASK [ensure-docker : Install upstream docker using package] 2026-03-14 00:26:37.712470 | controller | changed 2026-03-14 00:26:37.730204 | 2026-03-14 00:26:37.730385 | TASK [ensure-docker : Setup docker] 2026-03-14 00:26:37.762613 | controller | ok 2026-03-14 00:26:37.783495 | controller | included: /var/lib/zuul/builds/4f45cdf82a334907ba7266322551a995/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/tasks/docker-setup.yaml 2026-03-14 00:26:37.802041 | 2026-03-14 00:26:37.802178 | TASK [ensure-docker : Ensure "docker" group exists] 2026-03-14 00:26:38.153889 | controller | ok 2026-03-14 00:26:38.165245 | 2026-03-14 00:26:38.165390 | TASK [ensure-docker : Add user to docker group] 2026-03-14 00:26:38.709082 | controller | changed 2026-03-14 00:26:38.716057 | 2026-03-14 00:26:38.716169 | TASK [Update docker daemon proxy configuration] 2026-03-14 00:26:38.741258 | controller | skipping: Conditional result was False 2026-03-14 00:26:38.748108 | 2026-03-14 00:26:38.748185 | TASK [Lower default MTU] 2026-03-14 00:26:38.762696 | controller | skipping: Conditional result was False 2026-03-14 00:26:38.770793 | 2026-03-14 00:26:38.770887 | TASK [ensure-docker : Get the status of the docket.socket unit] 2026-03-14 00:26:39.163333 | controller | inactive 2026-03-14 00:26:39.337542 | controller | ok: Runtime: 0:00:00.017558 2026-03-14 00:26:39.346750 | 2026-03-14 00:26:39.346872 | TASK [ensure-docker : Get the status of the docket unit] 2026-03-14 00:26:39.588828 | controller | inactive 2026-03-14 00:26:39.887322 | controller | ok: Runtime: 0:00:00.012130 2026-03-14 00:26:39.897947 | 2026-03-14 00:26:39.898075 | TASK [ensure-docker : Restart docker] 2026-03-14 00:26:39.953199 | Trigger a Docker restart 2026-03-14 00:26:39.961986 | 2026-03-14 00:26:39.962129 | TASK [ensure-docker : Reset ssh connection to pick up docker group] 2026-03-14 00:26:40.002502 | 2026-03-14 00:26:40.002641 | TASK [ensure-docker : Install docker-compose] 2026-03-14 00:26:40.028379 | controller | skipping: Conditional result was False 2026-03-14 00:26:40.038427 | 2026-03-14 00:26:40.038549 | TASK [ensure-docker : Flush handlers before role exit] 2026-03-14 00:26:40.054147 | 2026-03-14 00:26:40.054270 | TASK [ensure-docker : Stop docker.socket to avoid any conflict] 2026-03-14 00:26:41.193560 | controller | changed 2026-03-14 00:26:41.211145 | 2026-03-14 00:26:41.211325 | TASK [ensure-docker : Assure docker service is running] 2026-03-14 00:26:43.215785 | controller | changed 2026-03-14 00:26:43.234951 | 2026-03-14 00:26:43.235180 | TASK [ensure-docker : Assure docker.socket service is running] 2026-03-14 00:26:43.980895 | controller | ok 2026-03-14 00:26:43.993205 | 2026-03-14 00:26:43.993384 | TASK [ensure-docker : Correct group ownership on docker sock] 2026-03-14 00:26:44.542454 | controller | ok 2026-03-14 00:26:44.556892 | 2026-03-14 00:26:44.557107 | TASK [ensure-docker : Validate ability to talk with docker] 2026-03-14 00:26:44.921060 | controller | CONTAINER ID IMAGE COMMAND CREATED STATUS PORTS NAMES 2026-03-14 00:26:45.108924 | controller | ok: Runtime: 0:00:00.050675 2026-03-14 00:26:45.122227 | 2026-03-14 00:26:45.122358 | TASK [ensure-docker : Get version details] 2026-03-14 00:26:45.709076 | controller | Client: Docker Engine - Community 2026-03-14 00:26:45.710941 | controller | Version: 24.0.7 2026-03-14 00:26:45.710960 | controller | API version: 1.43 2026-03-14 00:26:45.710968 | controller | Go version: go1.20.10 2026-03-14 00:26:45.711003 | controller | Git commit: afdd53b 2026-03-14 00:26:45.711014 | controller | Built: Thu Oct 26 09:09:39 2023 2026-03-14 00:26:45.711021 | controller | OS/Arch: linux/amd64 2026-03-14 00:26:45.711029 | controller | Context: default 2026-03-14 00:26:45.711035 | controller | 2026-03-14 00:26:45.711039 | controller | Server: Docker Engine - Community 2026-03-14 00:26:45.711044 | controller | Engine: 2026-03-14 00:26:45.711048 | controller | Version: 24.0.7 2026-03-14 00:26:45.711053 | controller | API version: 1.43 (minimum version 1.12) 2026-03-14 00:26:45.711057 | controller | Go version: go1.20.10 2026-03-14 00:26:45.711061 | controller | Git commit: 311b9ff 2026-03-14 00:26:45.711065 | controller | Built: Thu Oct 26 09:08:11 2023 2026-03-14 00:26:45.711070 | controller | OS/Arch: linux/amd64 2026-03-14 00:26:45.711074 | controller | Experimental: false 2026-03-14 00:26:45.711079 | controller | containerd: 2026-03-14 00:26:45.711083 | controller | Version: 1.6.25 2026-03-14 00:26:45.711087 | controller | GitCommit: d8f198a4ed8892c764191ef7b3b06d8a2eeb5c7f 2026-03-14 00:26:45.711091 | controller | runc: 2026-03-14 00:26:45.711095 | controller | Version: 1.1.10 2026-03-14 00:26:45.711100 | controller | GitCommit: v1.1.10-0-g18a0cb0 2026-03-14 00:26:45.711106 | controller | docker-init: 2026-03-14 00:26:45.711110 | controller | Version: 0.19.0 2026-03-14 00:26:45.711114 | controller | GitCommit: de40ad0 2026-03-14 00:26:46.178600 | controller | ok: Runtime: 0:00:00.066384 2026-03-14 00:26:46.185240 | 2026-03-14 00:26:46.185312 | TASK [ensure-docker : Dump installed docker details] 2026-03-14 00:26:46.215728 | {'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-14 00:26:45.644962', 'end': '2026-03-14 00:26:45.711346', 'delta': '0:00:00.066384', 'msg': '', 'zuul_log_id': 'fa163e3b-3c83-b5c1-2ba3-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-14 00:26:46.221749 | 2026-03-14 00:26:46.221813 | TASK [Use buildset registry] 2026-03-14 00:26:46.238542 | controller | skipping: Conditional result was False 2026-03-14 00:26:46.252642 | 2026-03-14 00:26:46.252810 | TASK [Ensure python3.8 is present] 2026-03-14 00:26:46.266908 | controller | skipping: Conditional result was False 2026-03-14 00:26:46.273621 | 2026-03-14 00:26:46.273710 | TASK [Run our-ensure-python role (Fedora only for now)] 2026-03-14 00:26:46.311555 | controller | ok 2026-03-14 00:26:46.350153 | 2026-03-14 00:26:46.350311 | TASK [our-ensure-python : Install the right Python version (rpm)] 2026-03-14 00:26:48.323633 | controller | ok: Nothing to do 2026-03-14 00:26:48.336919 | 2026-03-14 00:26:48.337097 | TASK [our-ensure-python : Also install python3-devel] 2026-03-14 00:26:59.015952 | controller | changed 2026-03-14 00:26:59.027775 | 2026-03-14 00:26:59.027910 | TASK [Run ensure-virtualenv role] 2026-03-14 00:26:59.050760 | controller | ok 2026-03-14 00:26:59.090666 | 2026-03-14 00:26:59.090790 | TASK [ensure-virtualenv : Check if virtualenv is installed] 2026-03-14 00:26:59.746615 | controller | /usr/bin/virtualenv 2026-03-14 00:27:00.099936 | controller | ok: Runtime: 0:00:00.003173 2026-03-14 00:27:00.108710 | 2026-03-14 00:27:00.108813 | LOOP [ensure-virtualenv : Install virtualenv package] 2026-03-14 00:27:00.137266 | controller | skipping: Conditional result was False 2026-03-14 00:27:00.137533 | controller | changed: All items complete 2026-03-14 00:27:00.137563 | 2026-03-14 00:27:00.160591 | 2026-03-14 00:27:00.160812 | TASK [Find the full path of the Python interpreter] 2026-03-14 00:27:00.366001 | controller | /usr/bin/python3.9 2026-03-14 00:27:00.693437 | controller | ok 2026-03-14 00:27:00.699599 | 2026-03-14 00:27:00.699666 | TASK [Create virtualenv for ansible-test with a specific interpreter] 2026-03-14 00:27:02.249323 | controller | created virtual environment CPython3.9.18.final.0-64 in 756ms 2026-03-14 00:27:02.267379 | controller | creator CPython3Posix(dest=/home/zuul/venv, clear=False, no_vcs_ignore=False, global=False) 2026-03-14 00:27:02.267417 | 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-14 00:27:02.267426 | controller | added seed packages: pip==22.2.2, setuptools==62.6.0, wheel==0.37.1 2026-03-14 00:27:02.267440 | controller | activators BashActivator,CShellActivator,FishActivator,NushellActivator,PowerShellActivator,PythonActivator 2026-03-14 00:27:02.733283 | controller | changed 2026-03-14 00:27:02.739810 | 2026-03-14 00:27:02.739892 | TASK [Set selinux package] 2026-03-14 00:27:02.770698 | controller | ok 2026-03-14 00:27:02.777291 | 2026-03-14 00:27:02.777398 | TASK [Set selinux package (Fedora)] 2026-03-14 00:27:02.811273 | controller | ok 2026-03-14 00:27:02.823753 | 2026-03-14 00:27:02.823871 | TASK [Install selinux into virtualenv] 2026-03-14 00:27:42.226293 | controller | Collecting selinux-please-lie-to-me 2026-03-14 00:27:57.349211 | controller | Downloading selinux_please_lie_to_me-1.0.1-py2.py3-none-any.whl (3.8 kB) 2026-03-14 00:27:57.698861 | controller | Collecting setuptools<50.0.0 2026-03-14 00:27:57.722274 | controller | Downloading setuptools-49.6.0-py3-none-any.whl (803 kB) 2026-03-14 00:27:57.846725 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 803.3/803.3 kB 7.0 MB/s eta 0:00:00 2026-03-14 00:27:57.926521 | controller | Installing collected packages: setuptools, selinux-please-lie-to-me 2026-03-14 00:27:57.926688 | controller | Attempting uninstall: setuptools 2026-03-14 00:27:57.927409 | controller | Found existing installation: setuptools 62.6.0 2026-03-14 00:27:57.999499 | controller | Uninstalling setuptools-62.6.0: 2026-03-14 00:27:58.008439 | controller | Successfully uninstalled setuptools-62.6.0 2026-03-14 00:27:58.362313 | controller | Successfully installed selinux-please-lie-to-me-1.0.1 setuptools-49.6.0 2026-03-14 00:28:10.808120 | controller | 2026-03-14 00:28:10.889882 | controller | [notice] A new release of pip available: 22.2.2 -> 26.0.1 2026-03-14 00:28:10.889928 | controller | [notice] To update, run: /home/zuul/venv/bin/python -m pip install --upgrade pip 2026-03-14 00:28:10.939484 | controller | ok: Runtime: 0:01:07.497868 2026-03-14 00:28:10.993572 | 2026-03-14 00:28:10.993893 | TASK [Install pytest-forked into virtualenv] 2026-03-14 00:28:23.067953 | controller | Collecting pytest-forked 2026-03-14 00:28:26.127981 | controller | Downloading pytest_forked-1.6.0-py3-none-any.whl (4.9 kB) 2026-03-14 00:28:26.183528 | controller | Collecting py 2026-03-14 00:28:26.212132 | controller | Downloading py-1.11.0-py2.py3-none-any.whl (98 kB) 2026-03-14 00:28:26.286632 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 98.7/98.7 kB 1.3 MB/s eta 0:00:00 2026-03-14 00:28:26.410987 | controller | Collecting pytest>=3.10 2026-03-14 00:28:26.439153 | controller | Downloading pytest-8.4.2-py3-none-any.whl (365 kB) 2026-03-14 00:28:26.586126 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 365.8/365.8 kB 2.5 MB/s eta 0:00:00 2026-03-14 00:28:26.662503 | controller | Collecting pygments>=2.7.2 2026-03-14 00:28:26.695218 | controller | Downloading pygments-2.19.2-py3-none-any.whl (1.2 MB) 2026-03-14 00:28:27.132862 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 1.2/1.2 MB 2.8 MB/s eta 0:00:00 2026-03-14 00:28:27.188418 | controller | Collecting packaging>=20 2026-03-14 00:28:27.214249 | controller | Downloading packaging-26.0-py3-none-any.whl (74 kB) 2026-03-14 00:28:27.243572 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 74.4/74.4 kB 2.5 MB/s eta 0:00:00 2026-03-14 00:28:27.279280 | controller | Collecting pluggy<2,>=1.5 2026-03-14 00:28:27.305396 | controller | Downloading pluggy-1.6.0-py3-none-any.whl (20 kB) 2026-03-14 00:28:27.347096 | controller | Collecting exceptiongroup>=1 2026-03-14 00:28:27.371946 | controller | Downloading exceptiongroup-1.3.1-py3-none-any.whl (16 kB) 2026-03-14 00:28:27.395614 | controller | Collecting iniconfig>=1 2026-03-14 00:28:27.421129 | controller | Downloading iniconfig-2.1.0-py3-none-any.whl (6.0 kB) 2026-03-14 00:28:27.476295 | controller | Collecting tomli>=1 2026-03-14 00:28:27.501885 | controller | Downloading tomli-2.4.0-py3-none-any.whl (14 kB) 2026-03-14 00:28:27.563927 | controller | Collecting typing-extensions>=4.6.0 2026-03-14 00:28:27.589772 | controller | Downloading typing_extensions-4.15.0-py3-none-any.whl (44 kB) 2026-03-14 00:28:27.609289 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 44.6/44.6 kB 2.4 MB/s eta 0:00:00 2026-03-14 00:28:27.677534 | controller | Installing collected packages: typing-extensions, tomli, pygments, py, pluggy, packaging, iniconfig, exceptiongroup, pytest, pytest-forked 2026-03-14 00:28:28.707308 | 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-14 00:28:28.710572 | controller | 2026-03-14 00:28:28.787323 | controller | [notice] A new release of pip available: 22.2.2 -> 26.0.1 2026-03-14 00:28:28.787389 | controller | [notice] To update, run: /home/zuul/venv/bin/python -m pip install --upgrade pip 2026-03-14 00:28:29.039226 | controller | ok: Runtime: 0:00:17.569425 2026-03-14 00:28:29.053442 | 2026-03-14 00:28:29.053658 | TASK [Update pip] 2026-03-14 00:28:29.603982 | controller | Requirement already satisfied: pip in ./venv/lib/python3.9/site-packages (22.2.2) 2026-03-14 00:28:42.051121 | controller | Collecting pip 2026-03-14 00:28:45.079621 | controller | Downloading pip-26.0.1-py3-none-any.whl (1.8 MB) 2026-03-14 00:28:45.234327 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 1.8/1.8 MB 11.9 MB/s eta 0:00:00 2026-03-14 00:28:45.295584 | controller | Installing collected packages: pip 2026-03-14 00:28:45.295798 | controller | Attempting uninstall: pip 2026-03-14 00:28:45.296423 | controller | Found existing installation: pip 22.2.2 2026-03-14 00:28:45.459496 | controller | Uninstalling pip-22.2.2: 2026-03-14 00:28:45.476534 | controller | Successfully uninstalled pip-22.2.2 2026-03-14 00:28:46.252739 | controller | Successfully installed pip-26.0.1 2026-03-14 00:28:46.628832 | controller | ok: Runtime: 0:00:17.018696 2026-03-14 00:28:46.639456 | 2026-03-14 00:28:46.639578 | TASK [Ensure pip temp directory exists in /var/tmp] 2026-03-14 00:28:46.855913 | controller | changed 2026-03-14 00:28:46.863190 | 2026-03-14 00:28:46.863255 | TASK [Install ansible into virtualenv] 2026-03-14 00:28:47.353140 | controller | Processing ./src/github.com/ansible/ansible 2026-03-14 00:28:47.356292 | controller | Installing build dependencies: started 2026-03-14 00:29:01.451479 | controller | Installing build dependencies: finished with status 'done' 2026-03-14 00:29:01.451535 | controller | Getting requirements to build wheel: started 2026-03-14 00:29:02.071988 | controller | Getting requirements to build wheel: finished with status 'done' 2026-03-14 00:29:02.073252 | controller | Preparing metadata (pyproject.toml): started 2026-03-14 00:29:02.559014 | controller | Preparing metadata (pyproject.toml): finished with status 'done' 2026-03-14 00:29:14.922859 | controller | Collecting jinja2 (from ansible-core==2.11.12.post0) 2026-03-14 00:29:18.027620 | controller | Downloading jinja2-3.1.6-py3-none-any.whl.metadata (2.9 kB) 2026-03-14 00:29:18.106911 | controller | Collecting PyYAML (from ansible-core==2.11.12.post0) 2026-03-14 00:29:18.130165 | 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-14 00:29:18.468761 | controller | Collecting cryptography (from ansible-core==2.11.12.post0) 2026-03-14 00:29:18.492493 | controller | Downloading cryptography-46.0.5-cp38-abi3-manylinux_2_34_x86_64.whl.metadata (5.7 kB) 2026-03-14 00:29:18.509055 | controller | Requirement already satisfied: packaging in ./venv/lib/python3.9/site-packages (from ansible-core==2.11.12.post0) (26.0) 2026-03-14 00:29:18.539509 | controller | Collecting resolvelib<0.6.0,>=0.5.3 (from ansible-core==2.11.12.post0) 2026-03-14 00:29:18.563490 | controller | Downloading resolvelib-0.5.4-py2.py3-none-any.whl.metadata (3.7 kB) 2026-03-14 00:29:18.723841 | controller | Collecting cffi>=2.0.0 (from cryptography->ansible-core==2.11.12.post0) 2026-03-14 00:29:18.748874 | controller | Downloading cffi-2.0.0-cp39-cp39-manylinux2014_x86_64.manylinux_2_17_x86_64.whl.metadata (2.6 kB) 2026-03-14 00:29:18.756772 | 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-14 00:29:18.777308 | controller | Collecting pycparser (from cffi>=2.0.0->cryptography->ansible-core==2.11.12.post0) 2026-03-14 00:29:18.800629 | controller | Downloading pycparser-2.23-py3-none-any.whl.metadata (993 bytes) 2026-03-14 00:29:18.885256 | controller | Collecting MarkupSafe>=2.0 (from jinja2->ansible-core==2.11.12.post0) 2026-03-14 00:29:18.908638 | 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-14 00:29:18.943307 | controller | Downloading resolvelib-0.5.4-py2.py3-none-any.whl (12 kB) 2026-03-14 00:29:18.975664 | controller | Downloading cryptography-46.0.5-cp38-abi3-manylinux_2_34_x86_64.whl (4.5 MB) 2026-03-14 00:29:19.150794 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 4.5/4.5 MB 35.1 MB/s 0:00:00 2026-03-14 00:29:19.179744 | controller | Downloading cffi-2.0.0-cp39-cp39-manylinux2014_x86_64.manylinux_2_17_x86_64.whl (216 kB) 2026-03-14 00:29:19.212094 | controller | Downloading jinja2-3.1.6-py3-none-any.whl (134 kB) 2026-03-14 00:29:19.245225 | 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-14 00:29:19.277162 | controller | Downloading pycparser-2.23-py3-none-any.whl (118 kB) 2026-03-14 00:29:19.311455 | 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-14 00:29:19.337213 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 750.8/750.8 kB 25.0 MB/s 0:00:00 2026-03-14 00:29:19.350637 | controller | Building wheels for collected packages: ansible-core 2026-03-14 00:29:19.351434 | controller | Building wheel for ansible-core (pyproject.toml): started 2026-03-14 00:29:20.742094 | controller | Building wheel for ansible-core (pyproject.toml): finished with status 'done' 2026-03-14 00:29:20.744679 | controller | Created wheel for ansible-core: filename=ansible_core-2.11.12.post0-py3-none-any.whl size=1961250 sha256=9e4467584caaf1a005d53d3b03a7ba0ef52b620c32e988b7a16e53d63801f59a 2026-03-14 00:29:20.744844 | controller | Stored in directory: /var/tmp/ansible-test-pip/pip-ephem-wheel-cache-7mo19s8z/wheels/0f/a0/93/ab85b739e1eb1e0467171316f13571c14efdf87e0d5d44a33e 2026-03-14 00:29:20.753548 | controller | Successfully built ansible-core 2026-03-14 00:29:20.784909 | controller | Installing collected packages: resolvelib, PyYAML, pycparser, MarkupSafe, jinja2, cffi, cryptography, ansible-core 2026-03-14 00:29:22.251222 | controller | 2026-03-14 00:29:22.254111 | 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-14 00:29:22.417981 | controller | ok: Runtime: 0:00:35.301183 2026-03-14 00:29:22.431768 | 2026-03-14 00:29:22.432920 | TASK [Setup download-artifact-fork role] 2026-03-14 00:29:22.457381 | controller | ok 2026-03-14 00:29:22.484040 | 2026-03-14 00:29:22.484147 | TASK [download-artifact-fork : Parse inventory response] 2026-03-14 00:29:22.531297 | controller | ok 2026-03-14 00:29:22.542424 | 2026-03-14 00:29:22.542509 | TASK [download-artifact-fork : Fetch info from Zuul API] 2026-03-14 00:29:22.577580 | controller | skipping: Conditional result was False 2026-03-14 00:29:22.586546 | 2026-03-14 00:29:22.586638 | TASK [download-artifact-fork : Parse build response] 2026-03-14 00:29:22.611675 | controller | skipping: Conditional result was False 2026-03-14 00:29:22.625476 | 2026-03-14 00:29:22.625635 | TASK [download-artifact-fork : Ensure artifacts directory exists] 2026-03-14 00:29:22.850076 | controller | changed 2026-03-14 00:29:22.862398 | 2026-03-14 00:29:22.862533 | LOOP [download-artifact-fork : Download archive by type] 2026-03-14 00:29:22.900263 | controller | skipping: Conditional result was False 2026-03-14 00:29:22.900766 | 2026-03-14 00:29:23.690366 | controller | changed: OK (20202 bytes) 2026-03-14 00:29:23.724082 | 2026-03-14 00:29:23.724294 | TASK [deploy-artifacts : define __collections] 2026-03-14 00:29:23.758368 | controller | ok 2026-03-14 00:29:23.766555 | 2026-03-14 00:29:23.766665 | LOOP [deploy-artifacts : Create list of collections] 2026-03-14 00:29:23.789632 | controller | skipping: Conditional result was False 2026-03-14 00:29:23.799009 | controller | ok: 2026-03-14 00:29:23.799113 | controller | { 2026-03-14 00:29:23.799148 | controller | "branch": "main", 2026-03-14 00:29:23.799175 | controller | "job": "build-ansible-collection", 2026-03-14 00:29:23.799199 | controller | "metadata": { 2026-03-14 00:29:23.799223 | controller | "type": "ansible_collection", 2026-03-14 00:29:23.799246 | controller | "version": "5.0.0" 2026-03-14 00:29:23.799268 | controller | }, 2026-03-14 00:29:23.799291 | controller | "name": "ansible.network", 2026-03-14 00:29:23.799313 | controller | "project": "ansible-collections/ansible.network", 2026-03-14 00:29:23.799336 | controller | "url": "https://37583fd57b2f38de0882-ca5bce7feff73ce685e7c8109acd08a6.ssl.cf1.rackcdn.com/ansible/ea4b63013917479e88daaa7bf1fb1aec/artifacts/ansible-network-5.0.0.tar.gz" 2026-03-14 00:29:23.799367 | controller | } 2026-03-14 00:29:23.812153 | 2026-03-14 00:29:23.813320 | TASK [deploy-artifacts : Install require-project collection using ansible-galaxy] 2026-03-14 00:29:24.434470 | controller | Starting galaxy collection install process 2026-03-14 00:29:24.434677 | controller | Process install dependency map 2026-03-14 00:30:36.110566 | controller | [WARNING]: Skipping Galaxy server https://galaxy.ansible.com/api/. Got an 2026-03-14 00:30:36.111073 | controller | unexpected error when getting available versions of collection 2026-03-14 00:30:36.111116 | controller | ansible.netcommon: '/api/v3/plugin/ansible/content/published/collections/index/ 2026-03-14 00:30:36.111130 | controller | ansible/netcommon/versions/' 2026-03-14 00:30:36.111146 | controller | ERROR! Unexpected Exception, this is probably a bug: '/api/v3/plugin/ansible/content/published/collections/index/ansible/netcommon/versions/' 2026-03-14 00:30:36.111233 | controller | to see the full traceback, use -vvv 2026-03-14 00:30:36.405578 | controller | ERROR 2026-03-14 00:30:36.405999 | controller | { 2026-03-14 00:30:36.406084 | controller | "delta": "0:01:12.103349", 2026-03-14 00:30:36.406131 | controller | "end": "2026-03-14 00:30:36.148063", 2026-03-14 00:30:36.406169 | controller | "msg": "non-zero return code", 2026-03-14 00:30:36.406205 | controller | "rc": 250, 2026-03-14 00:30:36.406240 | controller | "start": "2026-03-14 00:29:24.044714" 2026-03-14 00:30:36.406273 | controller | } failure 2026-03-14 00:30:36.409069 | 2026-03-14 00:30:36.409166 | PLAY RECAP 2026-03-14 00:30:36.409247 | controller | ok: 44 changed: 24 unreachable: 0 failed: 1 skipped: 17 rescued: 0 ignored: 0 2026-03-14 00:30:36.409291 | 2026-03-14 00:30:36.570740 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master] 2026-03-14 00:30:36.573108 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2026-03-14 00:30:37.285309 | 2026-03-14 00:30:37.285476 | PLAY [all] 2026-03-14 00:30:37.328866 | 2026-03-14 00:30:37.329019 | TASK [fetch-subunit-output : Find stestr or testr executable] 2026-03-14 00:30:37.724935 | controller | changed: non-zero return code 2026-03-14 00:30:37.738139 | 2026-03-14 00:30:37.738308 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2026-03-14 00:30:37.765026 | controller | skipping: Conditional result was False 2026-03-14 00:30:37.779072 | 2026-03-14 00:30:37.779246 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2026-03-14 00:30:37.821316 | 2026-03-14 00:30:37.821594 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2026-03-14 00:30:37.861904 | 2026-03-14 00:30:37.862173 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2026-03-14 00:30:37.888382 | controller | skipping: Conditional result was False 2026-03-14 00:30:37.903893 | 2026-03-14 00:30:37.904055 | LOOP [fetch-subunit-output : Generate subunit file] 2026-03-14 00:30:37.943786 | 2026-03-14 00:30:37.944059 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2026-03-14 00:30:37.970361 | controller | skipping: Conditional result was False 2026-03-14 00:30:37.984261 | 2026-03-14 00:30:37.984407 | TASK [fetch-subunit-output : Remove the temporary file] 2026-03-14 00:30:38.011139 | controller | skipping: Conditional result was False 2026-03-14 00:30:38.024875 | 2026-03-14 00:30:38.025044 | TASK [fetch-subunit-output : Process and fetch subunit results] 2026-03-14 00:30:38.051610 | controller | skipping: Conditional result was False 2026-03-14 00:30:38.100374 | 2026-03-14 00:30:38.100460 | PLAY RECAP 2026-03-14 00:30:38.100513 | controller | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2026-03-14 00:30:38.100539 | 2026-03-14 00:30:38.195547 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2026-03-14 00:30:38.197354 | POST-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/post.yaml@master] 2026-03-14 00:30:38.748248 | 2026-03-14 00:30:38.748369 | PLAY [all:!appliance*] 2026-03-14 00:30:38.771035 | 2026-03-14 00:30:38.771158 | TASK [unregister the node] 2026-03-14 00:30:39.301281 | controller | skipping: Conditional result was False 2026-03-14 00:30:39.315531 | 2026-03-14 00:30:39.315756 | TASK [include_role : fetch-output] 2026-03-14 00:30:39.353231 | controller | ok 2026-03-14 00:30:39.391858 | 2026-03-14 00:30:39.392003 | TASK [fetch-output : Set log path for multiple nodes] 2026-03-14 00:30:39.469041 | controller | skipping: Conditional result was False 2026-03-14 00:30:39.482971 | 2026-03-14 00:30:39.483176 | TASK [fetch-output : Set log path for single node] 2026-03-14 00:30:39.532180 | controller | ok 2026-03-14 00:30:39.544496 | 2026-03-14 00:30:39.544657 | LOOP [fetch-output : Ensure local output dirs] 2026-03-14 00:30:39.992469 | controller -> localhost | ok: "/var/lib/zuul/builds/4f45cdf82a334907ba7266322551a995/work/logs" 2026-03-14 00:30:40.272234 | controller -> localhost | changed: "/var/lib/zuul/builds/4f45cdf82a334907ba7266322551a995/work/artifacts" 2026-03-14 00:30:40.565118 | controller -> localhost | changed: "/var/lib/zuul/builds/4f45cdf82a334907ba7266322551a995/work/docs" 2026-03-14 00:30:40.578540 | 2026-03-14 00:30:40.578637 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-03-14 00:30:41.251170 | controller | changed: 2026-03-14 00:30:41.251403 | controller | .d..t...... ./ 2026-03-14 00:30:41.251442 | controller | cd+++++++++ controller/ 2026-03-14 00:30:41.251486 | controller | changed: All items complete 2026-03-14 00:30:41.251528 | 2026-03-14 00:30:41.806234 | controller | changed: .d..t...... ./ 2026-03-14 00:30:42.380279 | controller | changed: .d..t...... ./ 2026-03-14 00:30:42.402804 | 2026-03-14 00:30:42.402925 | TASK [include_role : fetch-output-openshift] 2026-03-14 00:30:42.427044 | controller | skipping: Conditional result was False 2026-03-14 00:30:42.433911 | 2026-03-14 00:30:42.433984 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-03-14 00:30:42.474032 | controller | skipping: Conditional result was False 2026-03-14 00:30:42.489485 | controller | skipping: Conditional result was False 2026-03-14 00:30:42.550511 | 2026-03-14 00:30:42.550656 | PLAY [localhost] 2026-03-14 00:30:42.569782 | 2026-03-14 00:30:42.569870 | TASK [Run Zuul manifest role] 2026-03-14 00:30:42.591931 | localhost | ok 2026-03-14 00:30:42.621465 | 2026-03-14 00:30:42.621567 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-03-14 00:30:43.036685 | localhost | changed 2026-03-14 00:30:43.041719 | 2026-03-14 00:30:43.041791 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-03-14 00:30:43.080345 | localhost | ok 2026-03-14 00:30:43.088468 | 2026-03-14 00:30:43.088531 | TASK [Set zuul-log-path fact] 2026-03-14 00:30:43.106409 | localhost | ok 2026-03-14 00:30:43.120498 | 2026-03-14 00:30:43.120595 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-03-14 00:30:43.148922 | localhost | ok 2026-03-14 00:30:43.157426 | 2026-03-14 00:30:43.157503 | LOOP [Set zuul_log_targets (Swift with random Rackspace region)] 2026-03-14 00:30:43.180984 | localhost | ok: 2026-03-14 00:30:43.181257 | localhost | { 2026-03-14 00:30:43.181284 | localhost | "auth": { 2026-03-14 00:30:43.181305 | localhost | "password": "IonWCwcuOFib&&<}wp^:[2G/", 2026-03-14 00:30:43.181324 | localhost | "username": "9LF91O0RK7JU" 2026-03-14 00:30:43.181341 | localhost | }, 2026-03-14 00:30:43.181358 | localhost | "profile": "rackspace", 2026-03-14 00:30:43.181374 | localhost | "region_name": "ORD" 2026-03-14 00:30:43.181390 | localhost | } 2026-03-14 00:30:43.181416 | 2026-03-14 00:30:43.196661 | 2026-03-14 00:30:43.196798 | TASK [Run upload-logs-failover role (S3 then Swift)] 2026-03-14 00:30:43.215280 | localhost | Output suppressed because no_log was given 2026-03-14 00:30:43.228768 | 2026-03-14 00:30:43.228874 | TASK [upload-logs-failover : Initialize control vars] 2026-03-14 00:30:43.258008 | localhost | ok 2026-03-14 00:30:43.262997 | 2026-03-14 00:30:43.263069 | LOOP [upload-logs-failover : Perform log upload] 2026-03-14 00:30:43.299287 | localhost | Output suppressed because no_log was given 2026-03-14 00:30:43.339747 | localhost | included: /var/lib/zuul/builds/4f45cdf82a334907ba7266322551a995/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/upload-logs-failover/tasks/upload.yaml 2026-03-14 00:30:43.346423 | 2026-03-14 00:30:43.346521 | LOOP [upload-logs-failover : Unpack vars for swift upload] 2026-03-14 00:30:43.424338 | localhost | Output suppressed because no_log was given 2026-03-14 00:30:43.433669 | 2026-03-14 00:30:43.433815 | TASK [Upload logs to swift] 2026-03-14 00:30:43.457349 | localhost | ok 2026-03-14 00:30:43.488436 | 2026-03-14 00:30:43.488545 | TASK [Set zuul-log-path fact] 2026-03-14 00:30:43.513136 | localhost | skipping: Conditional result was False 2026-03-14 00:30:43.518964 | 2026-03-14 00:30:43.519037 | TASK [upload-logs-swift : Ensure logs are readable before uploading] 2026-03-14 00:30:43.913006 | localhost -> localhost | ok: Runtime: 0:00:00.005927 2026-03-14 00:30:43.927643 | 2026-03-14 00:30:43.927892 | TASK [upload-logs-swift : Upload logs to swift]