2025-10-26 00:06:37.888502 | Job console starting... 2025-10-26 00:06:37.897191 | Updating repositories 2025-10-26 00:06:38.031397 | Preparing job workspace 2025-10-26 00:06:41.977229 | Running Ansible setup... 2025-10-26 00:06:47.908990 | PRE-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master] 2025-10-26 00:06:48.485095 | 2025-10-26 00:06:48.485231 | PLAY [localhost] 2025-10-26 00:06:48.493732 | 2025-10-26 00:06:48.493803 | TASK [Gathering Facts] 2025-10-26 00:06:49.505288 | localhost | ok 2025-10-26 00:06:49.524321 | 2025-10-26 00:06:49.524421 | TASK [Setup log path fact] 2025-10-26 00:06:49.545652 | localhost | ok 2025-10-26 00:06:49.562909 | 2025-10-26 00:06:49.563229 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-10-26 00:06:49.603335 | localhost | ok 2025-10-26 00:06:49.626742 | 2025-10-26 00:06:49.626927 | TASK [emit-job-header : Print job information] 2025-10-26 00:06:49.672481 | # Job Information 2025-10-26 00:06:49.673767 | Ansible Version: 2.15.12 2025-10-26 00:06:49.673802 | Job: ansible-test-sanity-docker-stable-2.11 2025-10-26 00:06:49.673823 | Pipeline: periodic 2025-10-26 00:06:49.673841 | Executor: ze02.softwarefactory-project.io 2025-10-26 00:06:49.673859 | Triggered by: https://github.com/ansible-collections/ansible.network 2025-10-26 00:06:49.673881 | Log URL (when completed): https://ansible.softwarefactory-project.io/logs/c87/ansible/c871b78df3dd42b6b23c847411e407e9/ 2025-10-26 00:06:49.673900 | Event ID: c2e72cdc87924775bd86541f38e3bebc 2025-10-26 00:06:49.678027 | 2025-10-26 00:06:49.678091 | LOOP [emit-job-header : Print node information] 2025-10-26 00:06:49.774578 | localhost | ok: 2025-10-26 00:06:49.774805 | localhost | # Node Information 2025-10-26 00:06:49.774836 | localhost | Inventory Hostname: controller 2025-10-26 00:06:49.774856 | localhost | Hostname: ip-172-16-65-203 2025-10-26 00:06:49.774874 | localhost | Username: zuul-worker 2025-10-26 00:06:49.774895 | localhost | Distro: Fedora 37 2025-10-26 00:06:49.774912 | localhost | Provider: ansible-us-east-2 2025-10-26 00:06:49.774929 | localhost | Region: us-east-2 2025-10-26 00:06:49.774945 | localhost | Label: ansible-fedora-37-1vcpu 2025-10-26 00:06:49.774961 | localhost | Product Name: t3.small 2025-10-26 00:06:49.774977 | localhost | Interface IP: 18.219.41.44 2025-10-26 00:06:49.791880 | 2025-10-26 00:06:49.791946 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-10-26 00:06:50.274529 | localhost -> localhost | changed 2025-10-26 00:06:50.280054 | 2025-10-26 00:06:50.280122 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-10-26 00:06:51.230006 | localhost -> localhost | changed 2025-10-26 00:06:51.254070 | 2025-10-26 00:06:51.254225 | PLAY [all:!appliance*] 2025-10-26 00:06:51.269309 | 2025-10-26 00:06:51.269374 | TASK [include_role : start-zuul-console] 2025-10-26 00:06:51.288328 | controller | ok 2025-10-26 00:06:51.302077 | 2025-10-26 00:06:51.302142 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-10-26 00:06:51.970283 | controller | ok 2025-10-26 00:06:51.994487 | 2025-10-26 00:06:51.994711 | TASK [use-our-mirror : Retrieve the IP address] 2025-10-26 00:06:53.400998 | controller | ok: Status code was 404 and not [200]: HTTP Error 404: Not Found 2025-10-26 00:06:53.415306 | 2025-10-26 00:06:53.415429 | TASK [use-our-mirror : Ensure the registry service is running on the mirror] 2025-10-26 00:06:53.561129 | controller | skipping: Conditional result was False 2025-10-26 00:06:53.577875 | 2025-10-26 00:06:53.578145 | TASK [use-our-mirror : Write the IP in /etc/hosts] 2025-10-26 00:06:53.617792 | controller | skipping: Conditional result was False 2025-10-26 00:06:53.632216 | 2025-10-26 00:06:53.632382 | TASK [use-our-mirror : Create the podman configuration directory] 2025-10-26 00:06:53.659289 | controller | skipping: Conditional result was False 2025-10-26 00:06:53.673647 | 2025-10-26 00:06:53.684271 | TASK [use-our-mirror : Copy the podman configuration] 2025-10-26 00:06:53.716040 | controller | skipping: Conditional result was False 2025-10-26 00:06:53.725873 | 2025-10-26 00:06:53.726006 | TASK [use-our-mirror : Copy the Yum configuration (main)] 2025-10-26 00:06:53.762028 | controller | skipping: Conditional result was False 2025-10-26 00:06:53.768642 | 2025-10-26 00:06:53.768739 | TASK [use-our-mirror : Copy the Yum configuration (update)] 2025-10-26 00:06:53.793950 | controller | skipping: Conditional result was False 2025-10-26 00:06:53.812282 | 2025-10-26 00:06:53.812446 | TASK [Disable Fedora Modular] 2025-10-26 00:06:54.590210 | controller | changed 2025-10-26 00:06:54.598851 | 2025-10-26 00:06:54.598978 | TASK [Enable EPEL] 2025-10-26 00:06:54.625116 | controller | skipping: Conditional result was False 2025-10-26 00:06:54.633047 | 2025-10-26 00:06:54.633144 | TASK [Register the RHEL node] 2025-10-26 00:06:54.794091 | 2025-10-26 00:06:54.794258 | TASK [Show the subscription-manager status] 2025-10-26 00:06:54.945217 | controller | skipping: Conditional result was False 2025-10-26 00:06:54.991802 | 2025-10-26 00:06:54.991932 | TASK [Enable EPEL on RHEL] 2025-10-26 00:06:55.144476 | controller | skipping: Conditional result was False 2025-10-26 00:06:55.154069 | 2025-10-26 00:06:55.154155 | TASK [Install git and tox] 2025-10-26 00:08:30.604451 | controller | changed 2025-10-26 00:08:30.619639 | 2025-10-26 00:08:30.619781 | TASK [include_role : prepare-workspace] 2025-10-26 00:08:30.653425 | controller | ok 2025-10-26 00:08:30.682878 | 2025-10-26 00:08:30.683009 | TASK [prepare-workspace : Start zuul_console daemon.] 2025-10-26 00:08:31.238957 | controller | ok 2025-10-26 00:08:31.248255 | 2025-10-26 00:08:31.248360 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2025-10-26 00:08:44.518329 | controller | Output suppressed because no_log was given 2025-10-26 00:08:44.537643 | 2025-10-26 00:08:44.537744 | TASK [include_role : prepare-workspace-openshift] 2025-10-26 00:08:44.563097 | controller | skipping: Conditional result was False 2025-10-26 00:08:44.591449 | 2025-10-26 00:08:44.591505 | PLAY [all:!appliance] 2025-10-26 00:08:44.605803 | 2025-10-26 00:08:44.605878 | TASK [Run add-build-sshkey role (RSA)] 2025-10-26 00:08:44.636820 | controller | ok 2025-10-26 00:08:44.656955 | 2025-10-26 00:08:44.657044 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-10-26 00:08:44.925072 | controller -> localhost | ok 2025-10-26 00:08:44.931438 | 2025-10-26 00:08:44.931523 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-10-26 00:08:44.949906 | controller | ok 2025-10-26 00:08:44.963016 | controller | included: /var/lib/zuul/builds/c871b78df3dd42b6b23c847411e407e9/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-10-26 00:08:44.968527 | 2025-10-26 00:08:44.968586 | TASK [add-build-sshkey : Create Temp SSH key] 2025-10-26 00:08:45.553130 | controller -> localhost | Generating public/private rsa key pair. 2025-10-26 00:08:45.553363 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/c871b78df3dd42b6b23c847411e407e9/work/c871b78df3dd42b6b23c847411e407e9_id_rsa. 2025-10-26 00:08:45.553402 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/c871b78df3dd42b6b23c847411e407e9/work/c871b78df3dd42b6b23c847411e407e9_id_rsa.pub. 2025-10-26 00:08:45.553430 | controller -> localhost | The key fingerprint is: 2025-10-26 00:08:45.553455 | controller -> localhost | SHA256:wAz+76Ize268IiBOfimVidX7XiuitIMnqVlFaU0DtyY zuul-build-sshkey 2025-10-26 00:08:45.553481 | controller -> localhost | The key's randomart image is: 2025-10-26 00:08:45.553506 | controller -> localhost | +---[RSA 2048]----+ 2025-10-26 00:08:45.553531 | controller -> localhost | | o.+ | 2025-10-26 00:08:45.553681 | controller -> localhost | | . O o | 2025-10-26 00:08:45.553739 | controller -> localhost | | E B | 2025-10-26 00:08:45.553935 | controller -> localhost | | + = . | 2025-10-26 00:08:45.553972 | controller -> localhost | | o + o S | 2025-10-26 00:08:45.554017 | controller -> localhost | |oo = . . | 2025-10-26 00:08:45.554046 | controller -> localhost | |=.=.... .. | 2025-10-26 00:08:45.554072 | controller -> localhost | | Xo==.=+. . | 2025-10-26 00:08:45.554097 | controller -> localhost | |+ =+=X=+o. | 2025-10-26 00:08:45.554124 | controller -> localhost | +----[SHA256]-----+ 2025-10-26 00:08:45.554180 | controller -> localhost | ok: Runtime: 0:00:00.062991 2025-10-26 00:08:45.562016 | 2025-10-26 00:08:45.562101 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-10-26 00:08:45.596624 | controller | ok 2025-10-26 00:08:45.610402 | controller | included: /var/lib/zuul/builds/c871b78df3dd42b6b23c847411e407e9/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-10-26 00:08:45.622301 | 2025-10-26 00:08:45.622387 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-10-26 00:08:45.647903 | controller | skipping: Conditional result was False 2025-10-26 00:08:45.655474 | 2025-10-26 00:08:45.655545 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-10-26 00:08:46.293703 | controller | changed 2025-10-26 00:08:46.305227 | 2025-10-26 00:08:46.305358 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-10-26 00:08:46.661333 | controller | ok 2025-10-26 00:08:46.673790 | 2025-10-26 00:08:46.673934 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-10-26 00:08:47.985213 | controller | changed 2025-10-26 00:08:47.990744 | 2025-10-26 00:08:47.990810 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-10-26 00:08:49.265703 | controller | changed 2025-10-26 00:08:49.271257 | 2025-10-26 00:08:49.271318 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-10-26 00:08:49.295869 | controller | skipping: Conditional result was False 2025-10-26 00:08:49.301968 | 2025-10-26 00:08:49.302035 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-10-26 00:08:49.690728 | controller -> localhost | changed 2025-10-26 00:08:49.703332 | 2025-10-26 00:08:49.703414 | TASK [add-build-sshkey : Add back temp key] 2025-10-26 00:08:50.073388 | controller -> localhost | Identity added: /var/lib/zuul/builds/c871b78df3dd42b6b23c847411e407e9/work/c871b78df3dd42b6b23c847411e407e9_id_rsa (zuul-build-sshkey) 2025-10-26 00:08:50.073586 | controller -> localhost | ok: Runtime: 0:00:00.013344 2025-10-26 00:08:50.079790 | 2025-10-26 00:08:50.079852 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-10-26 00:08:50.555026 | controller | ok 2025-10-26 00:08:50.570774 | 2025-10-26 00:08:50.570854 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-10-26 00:08:50.615390 | controller | skipping: Conditional result was False 2025-10-26 00:08:50.628232 | 2025-10-26 00:08:50.628302 | TASK [Run add-build-sshkey role (ECDSA)] 2025-10-26 00:08:50.657833 | controller | ok 2025-10-26 00:08:50.672660 | 2025-10-26 00:08:50.672770 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-10-26 00:08:50.931432 | controller -> localhost | ok 2025-10-26 00:08:50.937720 | 2025-10-26 00:08:50.937787 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-10-26 00:08:50.964724 | controller | ok 2025-10-26 00:08:50.975166 | controller | included: /var/lib/zuul/builds/c871b78df3dd42b6b23c847411e407e9/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-10-26 00:08:50.980610 | 2025-10-26 00:08:50.980689 | TASK [add-build-sshkey : Create Temp SSH key] 2025-10-26 00:08:51.218440 | controller -> localhost | Generating public/private ecdsa key pair. 2025-10-26 00:08:51.218637 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/c871b78df3dd42b6b23c847411e407e9/work/c871b78df3dd42b6b23c847411e407e9_id_ecdsa. 2025-10-26 00:08:51.218705 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/c871b78df3dd42b6b23c847411e407e9/work/c871b78df3dd42b6b23c847411e407e9_id_ecdsa.pub. 2025-10-26 00:08:51.218744 | controller -> localhost | The key fingerprint is: 2025-10-26 00:08:51.218764 | controller -> localhost | SHA256:k5grGq7rO/tn1utsX1Uqyh/mLmwbS/8GxkJhmeY/oMA zuul-build-sshkey 2025-10-26 00:08:51.218782 | controller -> localhost | The key's randomart image is: 2025-10-26 00:08:51.218800 | controller -> localhost | +---[ECDSA 521]---+ 2025-10-26 00:08:51.218817 | controller -> localhost | | o | 2025-10-26 00:08:51.218833 | controller -> localhost | | * | 2025-10-26 00:08:51.218850 | controller -> localhost | | . + . . | 2025-10-26 00:08:51.218865 | controller -> localhost | | E o = o | 2025-10-26 00:08:51.218881 | controller -> localhost | | + S +. o | 2025-10-26 00:08:51.218897 | controller -> localhost | | o.o.*o | 2025-10-26 00:08:51.218912 | controller -> localhost | | . . o .=o+o | 2025-10-26 00:08:51.218928 | controller -> localhost | | o o =.o.+O .. | 2025-10-26 00:08:51.218943 | controller -> localhost | |+*B.+ o++++=o. | 2025-10-26 00:08:51.218959 | controller -> localhost | +----[SHA256]-----+ 2025-10-26 00:08:51.219007 | controller -> localhost | ok: Runtime: 0:00:00.008053 2025-10-26 00:08:51.225594 | 2025-10-26 00:08:51.225656 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-10-26 00:08:51.246678 | controller | ok 2025-10-26 00:08:51.254310 | controller | included: /var/lib/zuul/builds/c871b78df3dd42b6b23c847411e407e9/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-10-26 00:08:51.274893 | 2025-10-26 00:08:51.275017 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-10-26 00:08:51.288783 | controller | skipping: Conditional result was False 2025-10-26 00:08:51.296169 | 2025-10-26 00:08:51.296284 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-10-26 00:08:51.754325 | controller | changed 2025-10-26 00:08:51.766901 | 2025-10-26 00:08:51.767030 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-10-26 00:08:52.125463 | controller | ok 2025-10-26 00:08:52.130857 | 2025-10-26 00:08:52.130916 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-10-26 00:08:53.438773 | controller | changed 2025-10-26 00:08:53.444960 | 2025-10-26 00:08:53.445026 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-10-26 00:08:54.914495 | controller | changed 2025-10-26 00:08:54.920245 | 2025-10-26 00:08:54.920308 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-10-26 00:08:54.934860 | controller | skipping: Conditional result was False 2025-10-26 00:08:54.941774 | 2025-10-26 00:08:54.941847 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-10-26 00:08:55.166042 | controller -> localhost | changed 2025-10-26 00:08:55.183157 | 2025-10-26 00:08:55.183307 | TASK [add-build-sshkey : Add back temp key] 2025-10-26 00:08:55.443392 | controller -> localhost | Identity added: /var/lib/zuul/builds/c871b78df3dd42b6b23c847411e407e9/work/c871b78df3dd42b6b23c847411e407e9_id_ecdsa (zuul-build-sshkey) 2025-10-26 00:08:55.443647 | controller -> localhost | ok: Runtime: 0:00:00.008152 2025-10-26 00:08:55.450793 | 2025-10-26 00:08:55.451123 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-10-26 00:08:55.771194 | controller | ok 2025-10-26 00:08:55.778427 | 2025-10-26 00:08:55.778510 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-10-26 00:08:55.804358 | controller | skipping: Conditional result was False 2025-10-26 00:08:55.819567 | 2025-10-26 00:08:55.819658 | TASK [include_role : remove-zuul-sshkey] 2025-10-26 00:08:55.835545 | controller | skipping: Conditional result was False 2025-10-26 00:08:55.844898 | 2025-10-26 00:08:55.844987 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2025-10-26 00:08:56.160595 | controller | ok: "logs" 2025-10-26 00:08:56.163341 | controller | ok: All items complete 2025-10-26 00:08:56.163388 | 2025-10-26 00:08:56.445059 | controller | ok: "artifacts" 2025-10-26 00:08:56.737187 | controller | ok: "docs" 2025-10-26 00:08:56.754442 | 2025-10-26 00:08:56.754589 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2025-10-26 00:08:57.084867 | controller | changed: "logs" 2025-10-26 00:08:57.372176 | controller | changed: "artifacts" 2025-10-26 00:08:57.731929 | controller | changed: "docs" 2025-10-26 00:08:57.775451 | 2025-10-26 00:08:57.775523 | PLAY RECAP 2025-10-26 00:08:57.775562 | controller | ok: 30 changed: 16 unreachable: 0 failed: 0 skipped: 18 rescued: 0 ignored: 0 2025-10-26 00:08:57.775588 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-10-26 00:08:57.775606 | 2025-10-26 00:08:57.879271 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master] 2025-10-26 00:08:57.880178 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2025-10-26 00:08:58.418281 | 2025-10-26 00:08:58.418388 | PLAY [all] 2025-10-26 00:08:58.439467 | 2025-10-26 00:08:58.439551 | TASK [Install binary dependencies] 2025-10-26 00:08:58.499732 | controller | ok 2025-10-26 00:08:58.518863 | 2025-10-26 00:08:58.518942 | TASK [bindep : Include find tasks] 2025-10-26 00:08:58.548472 | controller | ok 2025-10-26 00:08:58.555998 | controller | included: /var/lib/zuul/builds/c871b78df3dd42b6b23c847411e407e9/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find.yaml 2025-10-26 00:08:58.562088 | 2025-10-26 00:08:58.562149 | TASK [bindep : Look for bindep.txt] 2025-10-26 00:08:59.108717 | controller | ok 2025-10-26 00:08:59.119475 | 2025-10-26 00:08:59.119592 | TASK [bindep : Define bindep_file fact] 2025-10-26 00:08:59.144159 | controller | skipping: Conditional result was False 2025-10-26 00:08:59.150541 | 2025-10-26 00:08:59.150612 | TASK [bindep : Look for other-requirements.txt] 2025-10-26 00:08:59.469034 | controller | ok 2025-10-26 00:08:59.475170 | 2025-10-26 00:08:59.475248 | TASK [bindep : Define bindep_file fact] 2025-10-26 00:08:59.509623 | controller | skipping: Conditional result was False 2025-10-26 00:08:59.517771 | 2025-10-26 00:08:59.517866 | TASK [bindep : Look for bindep fallback file] 2025-10-26 00:08:59.561929 | controller | skipping: Conditional result was False 2025-10-26 00:08:59.569267 | 2025-10-26 00:08:59.569349 | TASK [bindep : Define bindep_file fact] 2025-10-26 00:08:59.594477 | controller | skipping: Conditional result was False 2025-10-26 00:08:59.602705 | 2025-10-26 00:08:59.602789 | TASK [bindep : Include bindep tasks] 2025-10-26 00:08:59.633605 | controller | skipping: Conditional result was False 2025-10-26 00:08:59.642227 | 2025-10-26 00:08:59.642307 | TASK [bindep : Include install tasks] 2025-10-26 00:08:59.682564 | controller | skipping: Conditional result was False 2025-10-26 00:08:59.688457 | 2025-10-26 00:08:59.688521 | LOOP [bindep : Include package tasks] 2025-10-26 00:08:59.734834 | 2025-10-26 00:08:59.735047 | TASK [Run test-setup role] 2025-10-26 00:08:59.757405 | controller | ok 2025-10-26 00:08:59.776862 | 2025-10-26 00:08:59.776944 | TASK [test-setup : Check if project's tools/test-setup.sh exists] 2025-10-26 00:09:00.154899 | controller | ok 2025-10-26 00:09:00.161273 | 2025-10-26 00:09:00.161340 | TASK [test-setup : Run tools/test-setup.sh] 2025-10-26 00:09:00.306814 | controller | skipping: Conditional result was False 2025-10-26 00:09:00.331136 | 2025-10-26 00:09:00.331194 | PLAY RECAP 2025-10-26 00:09:00.331235 | controller | ok: 4 changed: 0 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2025-10-26 00:09:00.331255 | 2025-10-26 00:09:00.428291 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2025-10-26 00:09:00.429136 | PRE-RUN START: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master] 2025-10-26 00:09:01.015732 | 2025-10-26 00:09:01.015855 | PLAY [controller] 2025-10-26 00:09:01.034766 | 2025-10-26 00:09:01.034845 | TASK [Create the /root directory] 2025-10-26 00:09:01.957392 | controller | ok 2025-10-26 00:09:01.970521 | 2025-10-26 00:09:01.970727 | TASK [Install glibc-langpack-en] 2025-10-26 00:09:10.120869 | controller | ok: Nothing to do 2025-10-26 00:09:10.134035 | 2025-10-26 00:09:10.134188 | TASK [Ensure controller directory exists] 2025-10-26 00:09:10.551538 | controller | changed 2025-10-26 00:09:10.557410 | 2025-10-26 00:09:10.557479 | TASK [Install container runtime] 2025-10-26 00:09:10.631587 | controller | ok 2025-10-26 00:09:10.673861 | 2025-10-26 00:09:10.673990 | LOOP [ensure-docker : Gather variables for each operating system] 2025-10-26 00:09:10.746694 | controller | ok: "/var/lib/zuul/builds/c871b78df3dd42b6b23c847411e407e9/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/vars/fedora.yaml" 2025-10-26 00:09:10.760956 | 2025-10-26 00:09:10.761110 | TASK [ensure-docker : Sanity check for distro selection] 2025-10-26 00:09:10.788389 | controller | skipping: Conditional result was False 2025-10-26 00:09:10.798389 | 2025-10-26 00:09:10.798524 | TASK [ensure-docker : Sanity check for distro install] 2025-10-26 00:09:10.836588 | controller | skipping: Conditional result was False 2025-10-26 00:09:10.849857 | 2025-10-26 00:09:10.850040 | TASK [ensure-docker : Sanity check for upstream install] 2025-10-26 00:09:10.889949 | controller | skipping: Conditional result was False 2025-10-26 00:09:10.898706 | 2025-10-26 00:09:10.898839 | TASK [ensure-docker : Sanity check for upstream install mirrors] 2025-10-26 00:09:10.927280 | controller | skipping: Conditional result was False 2025-10-26 00:09:10.935323 | 2025-10-26 00:09:10.935398 | TASK [ensure-docker : Include tasks for docker install] 2025-10-26 00:09:10.956643 | controller | ok 2025-10-26 00:09:10.968848 | controller | included: /var/lib/zuul/builds/c871b78df3dd42b6b23c847411e407e9/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/tasks/docker-upstream.yaml 2025-10-26 00:09:10.983503 | 2025-10-26 00:09:10.983884 | TASK [ensure-docker : Not Implemented] 2025-10-26 00:09:11.009222 | controller | skipping: Conditional result was False 2025-10-26 00:09:11.017606 | 2025-10-26 00:09:11.017721 | TASK [Set up docker mirrors] 2025-10-26 00:09:11.043027 | controller | ok 2025-10-26 00:09:11.074067 | 2025-10-26 00:09:11.074217 | TASK [use-docker-mirror : Set docker_mirror fact from mirror_info] 2025-10-26 00:09:11.121514 | controller | skipping: Conditional result was False 2025-10-26 00:09:11.128843 | 2025-10-26 00:09:11.128921 | TASK [use-docker-mirror : Set docker_mirror fact from zuul_site_mirror_info] 2025-10-26 00:09:11.163916 | controller | skipping: Conditional result was False 2025-10-26 00:09:11.170467 | 2025-10-26 00:09:11.170557 | TASK [use-docker-mirror : Set docker_mirror fact] 2025-10-26 00:09:11.205535 | controller | skipping: Conditional result was False 2025-10-26 00:09:11.212523 | 2025-10-26 00:09:11.212597 | TASK [use-docker-mirror : Set up docker mirrors] 2025-10-26 00:09:11.247911 | controller | skipping: Conditional result was False 2025-10-26 00:09:11.261054 | 2025-10-26 00:09:11.261136 | TASK [Add all repositories] 2025-10-26 00:09:11.283117 | controller | ok 2025-10-26 00:09:11.348419 | 2025-10-26 00:09:11.348555 | LOOP [ensure-package-repositories : Install repositories] 2025-10-26 00:09:11.387366 | controller | ok: "/var/lib/zuul/builds/c871b78df3dd42b6b23c847411e407e9/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-package-repositories/tasks/RedHat.yaml" 2025-10-26 00:09:11.387558 | controller | ok: All items complete 2025-10-26 00:09:11.387587 | 2025-10-26 00:09:11.396873 | controller | included: /var/lib/zuul/builds/c871b78df3dd42b6b23c847411e407e9/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-package-repositories/tasks/RedHat.yaml 2025-10-26 00:09:11.402820 | 2025-10-26 00:09:11.402880 | TASK [ensure-package-repositories : Create GPG key temp directory] 2025-10-26 00:09:11.936865 | controller | changed 2025-10-26 00:09:11.943553 | 2025-10-26 00:09:11.943640 | LOOP [ensure-package-repositories : Upload GPG keys] 2025-10-26 00:09:13.612462 | controller | changed: 2025-10-26 00:09:13.613009 | controller | { 2025-10-26 00:09:13.613085 | controller | "data": "-----BEGIN PGP PUBLIC KEY BLOCK-----\n\nmQINBFit5IEBEADDt86QpYKz5flnCsOyZ/fk3WwBKxfDjwHf/GIflo+4GWAXS7wJ\n1PSzPsvSDATV10J44i5WQzh99q+lZvFCVRFiNhRmlmcXG+rk1QmDh3fsCCj9Q/yP\nw8jn3Hx0zDtz8PIB/18ReftYJzUo34COLiHn8WiY20uGCF2pjdPgfxE+K454c4G7\ngKFqVUFYgPug2CS0quaBB5b0rpFUdzTeI5RCStd27nHCpuSDCvRYAfdv+4Y1yiVh\nKKdoe3Smj+RnXeVMgDxtH9FJibZ3DK7WnMN2yeob6VqXox+FvKYJCCLkbQgQmE50\nuVK0uN71A1mQDcTRKQ2q3fFGlMTqJbbzr3LwnCBE6hV0a36t+DABtZTmz5O69xdJ\nWGdBeePCnWVqtDb/BdEYz7hPKskcZBarygCCe2Xi7sZieoFZuq6ltPoCsdfEdfbO\n+VBVKJnExqNZCcFUTEnbH4CldWROOzMS8BGUlkGpa59Sl1t0QcmWlw1EbkeMQNrN\nspdR8lobcdNS9bpAJQqSHRZh3cAM9mA3Yq/bssUS/P2quRXLjJ9mIv3dky9C3udM\n+q2unvnbNpPtIUly76FJ3s8g8sHeOnmYcKqNGqHq2Q3kMdA2eIbI0MqfOIo2+Xk0\nrNt3ctq3g+cQiorcN3rdHPsTRSAcp+NCz1QF9TwXYtH1XV24A6QMO0+CZwARAQAB\ntCtEb2NrZXIgUmVsZWFzZSAoQ0UgcnBtKSA8ZG9ja2VyQGRvY2tlci5jb20+iQI3\nBBMBCgAhBQJYrep4AhsvBQsJCAcDBRUKCQgLBRYCAwEAAh4BAheAAAoJEMUv62ti\nHp816C0P/iP+1uhSa6Qq3TIc5sIFE5JHxOO6y0R97cUdAmCbEqBiJHUPNQDQaaRG\nVYBm0K013Q1gcJeUJvS32gthmIvhkstw7KTodwOM8Kl11CCqZ07NPFef1b2SaJ7l\nTYpyUsT9+e343ph+O4C1oUQw6flaAJe+8ATCmI/4KxfhIjD2a/Q1voR5tUIxfexC\n/LZTx05gyf2mAgEWlRm/cGTStNfqDN1uoKMlV+WFuB1j2oTUuO1/dr8mL+FgZAM3\nntWFo9gQCllNV9ahYOON2gkoZoNuPUnHsf4Bj6BQJnIXbAhMk9H2sZzwUi9bgObZ\nXO8+OrP4D4B9kCAKqqaQqA+O46LzO2vhN74lm/Fy6PumHuviqDBdN+HgtRPMUuao\nxnuVJSvBu9sPdgT/pR1N9u/KnfAnnLtR6g+fx4mWz+ts/riB/KRHzXd+44jGKZra\nIhTMfniguMJNsyEOO0AN8Tqcl0eRBxcOArcri7xu8HFvvl+e+ILymu4buusbYEVL\nGBkYP5YMmScfKn+jnDVN4mWoN1Bq2yMhMGx6PA3hOvzPNsUoYy2BwDxNZyflzuAi\ng59mgJm2NXtzNbSRJbMamKpQ69mzLWGdFNsRd4aH7PT7uPAURaf7B5BVp3UyjERW\n5alSGnBqsZmvlRnVH5BDUhYsWZMPRQS9rRr4iGW0l+TH+O2VJ8aQ\n=0Zqq\n-----END PGP PUBLIC KEY BLOCK-----\n" 2025-10-26 00:09:13.613135 | controller | } 2025-10-26 00:09:13.637086 | 2025-10-26 00:09:13.637287 | LOOP [ensure-package-repositories : Import GPG keys] 2025-10-26 00:09:14.500555 | controller | changed: 2025-10-26 00:09:14.501738 | controller | { 2025-10-26 00:09:14.501827 | controller | "data": "-----BEGIN PGP PUBLIC KEY BLOCK-----\n\nmQINBFit5IEBEADDt86QpYKz5flnCsOyZ/fk3WwBKxfDjwHf/GIflo+4GWAXS7wJ\n1PSzPsvSDATV10J44i5WQzh99q+lZvFCVRFiNhRmlmcXG+rk1QmDh3fsCCj9Q/yP\nw8jn3Hx0zDtz8PIB/18ReftYJzUo34COLiHn8WiY20uGCF2pjdPgfxE+K454c4G7\ngKFqVUFYgPug2CS0quaBB5b0rpFUdzTeI5RCStd27nHCpuSDCvRYAfdv+4Y1yiVh\nKKdoe3Smj+RnXeVMgDxtH9FJibZ3DK7WnMN2yeob6VqXox+FvKYJCCLkbQgQmE50\nuVK0uN71A1mQDcTRKQ2q3fFGlMTqJbbzr3LwnCBE6hV0a36t+DABtZTmz5O69xdJ\nWGdBeePCnWVqtDb/BdEYz7hPKskcZBarygCCe2Xi7sZieoFZuq6ltPoCsdfEdfbO\n+VBVKJnExqNZCcFUTEnbH4CldWROOzMS8BGUlkGpa59Sl1t0QcmWlw1EbkeMQNrN\nspdR8lobcdNS9bpAJQqSHRZh3cAM9mA3Yq/bssUS/P2quRXLjJ9mIv3dky9C3udM\n+q2unvnbNpPtIUly76FJ3s8g8sHeOnmYcKqNGqHq2Q3kMdA2eIbI0MqfOIo2+Xk0\nrNt3ctq3g+cQiorcN3rdHPsTRSAcp+NCz1QF9TwXYtH1XV24A6QMO0+CZwARAQAB\ntCtEb2NrZXIgUmVsZWFzZSAoQ0UgcnBtKSA8ZG9ja2VyQGRvY2tlci5jb20+iQI3\nBBMBCgAhBQJYrep4AhsvBQsJCAcDBRUKCQgLBRYCAwEAAh4BAheAAAoJEMUv62ti\nHp816C0P/iP+1uhSa6Qq3TIc5sIFE5JHxOO6y0R97cUdAmCbEqBiJHUPNQDQaaRG\nVYBm0K013Q1gcJeUJvS32gthmIvhkstw7KTodwOM8Kl11CCqZ07NPFef1b2SaJ7l\nTYpyUsT9+e343ph+O4C1oUQw6flaAJe+8ATCmI/4KxfhIjD2a/Q1voR5tUIxfexC\n/LZTx05gyf2mAgEWlRm/cGTStNfqDN1uoKMlV+WFuB1j2oTUuO1/dr8mL+FgZAM3\nntWFo9gQCllNV9ahYOON2gkoZoNuPUnHsf4Bj6BQJnIXbAhMk9H2sZzwUi9bgObZ\nXO8+OrP4D4B9kCAKqqaQqA+O46LzO2vhN74lm/Fy6PumHuviqDBdN+HgtRPMUuao\nxnuVJSvBu9sPdgT/pR1N9u/KnfAnnLtR6g+fx4mWz+ts/riB/KRHzXd+44jGKZra\nIhTMfniguMJNsyEOO0AN8Tqcl0eRBxcOArcri7xu8HFvvl+e+ILymu4buusbYEVL\nGBkYP5YMmScfKn+jnDVN4mWoN1Bq2yMhMGx6PA3hOvzPNsUoYy2BwDxNZyflzuAi\ng59mgJm2NXtzNbSRJbMamKpQ69mzLWGdFNsRd4aH7PT7uPAURaf7B5BVp3UyjERW\n5alSGnBqsZmvlRnVH5BDUhYsWZMPRQS9rRr4iGW0l+TH+O2VJ8aQ\n=0Zqq\n-----END PGP PUBLIC KEY BLOCK-----\n" 2025-10-26 00:09:14.501883 | controller | } 2025-10-26 00:09:14.501950 | controller | changed: All items complete 2025-10-26 00:09:14.501996 | 2025-10-26 00:09:14.519552 | 2025-10-26 00:09:14.519759 | TASK [ensure-package-repositories : Remove GPG temp directory] 2025-10-26 00:09:14.912787 | controller | changed 2025-10-26 00:09:14.932183 | 2025-10-26 00:09:14.932338 | LOOP [ensure-package-repositories : Add repositories] 2025-10-26 00:09:15.387345 | controller | changed: 2025-10-26 00:09:15.387604 | controller | { 2025-10-26 00:09:15.387659 | controller | "baseurl": "https://download.docker.com/linux/fedora/$releasever/$basearch/stable", 2025-10-26 00:09:15.387738 | controller | "description": "Docker CE Stable - $basearch", 2025-10-26 00:09:15.387778 | controller | "gpgcheck": true, 2025-10-26 00:09:15.387814 | controller | "name": "docker-ce-stable" 2025-10-26 00:09:15.387849 | controller | } 2025-10-26 00:09:15.416147 | 2025-10-26 00:09:15.416317 | TASK [ensure-docker : Install pre-reqs] 2025-10-26 00:09:18.972451 | controller | ok: Nothing to do 2025-10-26 00:09:18.985170 | 2025-10-26 00:09:18.985310 | TASK [ensure-docker : Remove packages] 2025-10-26 00:09:21.284291 | controller | ok: Nothing to do 2025-10-26 00:09:21.291625 | 2025-10-26 00:09:21.291709 | TASK [ensure-docker : Install upstream docker using package] 2025-10-26 00:10:20.515306 | controller | changed 2025-10-26 00:10:20.527468 | 2025-10-26 00:10:20.527604 | TASK [ensure-docker : Setup docker] 2025-10-26 00:10:20.556453 | controller | ok 2025-10-26 00:10:20.577721 | controller | included: /var/lib/zuul/builds/c871b78df3dd42b6b23c847411e407e9/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/tasks/docker-setup.yaml 2025-10-26 00:10:20.592884 | 2025-10-26 00:10:20.592982 | TASK [ensure-docker : Ensure "docker" group exists] 2025-10-26 00:10:21.187762 | controller | ok 2025-10-26 00:10:21.194645 | 2025-10-26 00:10:21.194734 | TASK [ensure-docker : Add user to docker group] 2025-10-26 00:10:21.820129 | controller | changed 2025-10-26 00:10:21.828918 | 2025-10-26 00:10:21.829009 | TASK [Update docker daemon proxy configuration] 2025-10-26 00:10:21.854354 | controller | skipping: Conditional result was False 2025-10-26 00:10:21.863921 | 2025-10-26 00:10:21.864021 | TASK [Lower default MTU] 2025-10-26 00:10:21.879344 | controller | skipping: Conditional result was False 2025-10-26 00:10:21.890495 | 2025-10-26 00:10:21.890612 | TASK [ensure-docker : Get the status of the docket.socket unit] 2025-10-26 00:10:22.428676 | controller | inactive 2025-10-26 00:10:22.547431 | controller | ok: Runtime: 0:00:00.011797 2025-10-26 00:10:22.561755 | 2025-10-26 00:10:22.561945 | TASK [ensure-docker : Get the status of the docket unit] 2025-10-26 00:10:23.062205 | controller | inactive 2025-10-26 00:10:23.252700 | controller | ok: Runtime: 0:00:00.016716 2025-10-26 00:10:23.265255 | 2025-10-26 00:10:23.265396 | TASK [ensure-docker : Restart docker] 2025-10-26 00:10:23.324350 | Trigger a Docker restart 2025-10-26 00:10:23.335559 | 2025-10-26 00:10:23.335770 | TASK [ensure-docker : Reset ssh connection to pick up docker group] 2025-10-26 00:10:23.402263 | 2025-10-26 00:10:23.402426 | TASK [ensure-docker : Install docker-compose] 2025-10-26 00:10:23.419749 | controller | skipping: Conditional result was False 2025-10-26 00:10:23.432627 | 2025-10-26 00:10:23.432794 | TASK [ensure-docker : Flush handlers before role exit] 2025-10-26 00:10:23.451908 | 2025-10-26 00:10:23.452014 | TASK [ensure-docker : Stop docker.socket to avoid any conflict] 2025-10-26 00:10:25.724142 | controller | changed 2025-10-26 00:10:25.737948 | 2025-10-26 00:10:25.738047 | TASK [ensure-docker : Assure docker service is running] 2025-10-26 00:10:28.711876 | controller | changed 2025-10-26 00:10:28.723037 | 2025-10-26 00:10:28.723157 | TASK [ensure-docker : Assure docker.socket service is running] 2025-10-26 00:10:30.152523 | controller | ok 2025-10-26 00:10:30.163511 | 2025-10-26 00:10:30.163649 | TASK [ensure-docker : Correct group ownership on docker sock] 2025-10-26 00:10:30.996498 | controller | ok 2025-10-26 00:10:31.013102 | 2025-10-26 00:10:31.013485 | TASK [ensure-docker : Validate ability to talk with docker] 2025-10-26 00:10:31.754512 | controller | CONTAINER ID IMAGE COMMAND CREATED STATUS PORTS NAMES 2025-10-26 00:10:32.178185 | controller | ok: Runtime: 0:00:00.052795 2025-10-26 00:10:32.192658 | 2025-10-26 00:10:32.192868 | TASK [ensure-docker : Get version details] 2025-10-26 00:10:32.740888 | controller | Client: Docker Engine - Community 2025-10-26 00:10:32.741032 | controller | Version: 24.0.7 2025-10-26 00:10:32.741050 | controller | API version: 1.43 2025-10-26 00:10:32.741060 | controller | Go version: go1.20.10 2025-10-26 00:10:32.741084 | controller | Git commit: afdd53b 2025-10-26 00:10:32.741094 | controller | Built: Thu Oct 26 09:09:39 2023 2025-10-26 00:10:32.741105 | controller | OS/Arch: linux/amd64 2025-10-26 00:10:32.741118 | controller | Context: default 2025-10-26 00:10:32.741131 | controller | 2025-10-26 00:10:32.741141 | controller | Server: Docker Engine - Community 2025-10-26 00:10:32.741150 | controller | Engine: 2025-10-26 00:10:32.741160 | controller | Version: 24.0.7 2025-10-26 00:10:32.741169 | controller | API version: 1.43 (minimum version 1.12) 2025-10-26 00:10:32.741178 | controller | Go version: go1.20.10 2025-10-26 00:10:32.741187 | controller | Git commit: 311b9ff 2025-10-26 00:10:32.741196 | controller | Built: Thu Oct 26 09:08:11 2023 2025-10-26 00:10:32.741205 | controller | OS/Arch: linux/amd64 2025-10-26 00:10:32.741214 | controller | Experimental: false 2025-10-26 00:10:32.741223 | controller | containerd: 2025-10-26 00:10:32.741232 | controller | Version: 1.6.25 2025-10-26 00:10:32.741241 | controller | GitCommit: d8f198a4ed8892c764191ef7b3b06d8a2eeb5c7f 2025-10-26 00:10:32.741250 | controller | runc: 2025-10-26 00:10:32.741258 | controller | Version: 1.1.10 2025-10-26 00:10:32.741267 | controller | GitCommit: v1.1.10-0-g18a0cb0 2025-10-26 00:10:32.741279 | controller | docker-init: 2025-10-26 00:10:32.741292 | controller | Version: 0.19.0 2025-10-26 00:10:32.743139 | controller | GitCommit: de40ad0 2025-10-26 00:10:32.857227 | controller | ok: Runtime: 0:00:00.041655 2025-10-26 00:10:32.869976 | 2025-10-26 00:10:32.870109 | TASK [ensure-docker : Dump installed docker details] 2025-10-26 00:10:32.920786 | {'changed': True, 'stdout': 'Client: Docker Engine - Community\n Version: 24.0.7\n API version: 1.43\n Go version: go1.20.10\n Git commit: afdd53b\n Built: Thu Oct 26 09:09:39 2023\n OS/Arch: linux/amd64\n Context: default\n\nServer: Docker Engine - Community\n Engine:\n Version: 24.0.7\n API version: 1.43 (minimum version 1.12)\n Go version: go1.20.10\n Git commit: 311b9ff\n Built: Thu Oct 26 09:08:11 2023\n OS/Arch: linux/amd64\n Experimental: false\n containerd:\n Version: 1.6.25\n GitCommit: d8f198a4ed8892c764191ef7b3b06d8a2eeb5c7f\n runc:\n Version: 1.1.10\n GitCommit: v1.1.10-0-g18a0cb0\n docker-init:\n Version: 0.19.0\n GitCommit: de40ad0', 'stderr': '', 'rc': 0, 'cmd': ['docker', 'version'], 'start': '2025-10-26 00:10:32.701945', 'end': '2025-10-26 00:10:32.743600', 'delta': '0:00:00.041655', 'msg': '', 'zuul_log_id': 'fa163efc-24cc-6091-66f8-000000000061-1-controller', 'stdout_lines': ['Client: Docker Engine - Community', ' Version: 24.0.7', ' API version: 1.43', ' Go version: go1.20.10', ' Git commit: afdd53b', ' Built: Thu Oct 26 09:09:39 2023', ' OS/Arch: linux/amd64', ' Context: default', '', 'Server: Docker Engine - Community', ' Engine:', ' Version: 24.0.7', ' API version: 1.43 (minimum version 1.12)', ' Go version: go1.20.10', ' Git commit: 311b9ff', ' Built: Thu Oct 26 09:08:11 2023', ' OS/Arch: linux/amd64', ' Experimental: false', ' containerd:', ' Version: 1.6.25', ' GitCommit: d8f198a4ed8892c764191ef7b3b06d8a2eeb5c7f', ' runc:', ' Version: 1.1.10', ' GitCommit: v1.1.10-0-g18a0cb0', ' docker-init:', ' Version: 0.19.0', ' GitCommit: de40ad0'], 'stderr_lines': [], 'failed': False} 2025-10-26 00:10:32.933491 | 2025-10-26 00:10:32.933811 | TASK [Use buildset registry] 2025-10-26 00:10:32.964220 | controller | skipping: Conditional result was False 2025-10-26 00:10:32.994204 | 2025-10-26 00:10:32.994295 | TASK [Ensure python3.8 is present] 2025-10-26 00:10:33.010462 | controller | skipping: Conditional result was False 2025-10-26 00:10:33.019888 | 2025-10-26 00:10:33.020021 | TASK [Run our-ensure-python role (Fedora only for now)] 2025-10-26 00:10:33.053182 | controller | ok 2025-10-26 00:10:33.079719 | 2025-10-26 00:10:33.079800 | TASK [our-ensure-python : Install the right Python version (rpm)] 2025-10-26 00:10:39.720571 | controller | ok: Nothing to do 2025-10-26 00:10:39.743716 | 2025-10-26 00:10:39.743952 | TASK [our-ensure-python : Also install python3-devel] 2025-10-26 00:10:54.947888 | controller | changed 2025-10-26 00:10:54.973144 | 2025-10-26 00:10:54.973250 | TASK [Run ensure-virtualenv role] 2025-10-26 00:10:55.003633 | controller | ok 2025-10-26 00:10:55.028574 | 2025-10-26 00:10:55.028733 | TASK [ensure-virtualenv : Check if virtualenv is installed] 2025-10-26 00:10:55.478374 | controller | /usr/bin/virtualenv 2025-10-26 00:10:55.673020 | controller | ok: Runtime: 0:00:00.004611 2025-10-26 00:10:55.679124 | 2025-10-26 00:10:55.679215 | LOOP [ensure-virtualenv : Install virtualenv package] 2025-10-26 00:10:55.735762 | controller | skipping: Conditional result was False 2025-10-26 00:10:55.736119 | controller | changed: All items complete 2025-10-26 00:10:55.736154 | 2025-10-26 00:10:55.760144 | 2025-10-26 00:10:55.760318 | TASK [Find the full path of the Python interpreter] 2025-10-26 00:10:56.231440 | controller | /usr/bin/python3.9 2025-10-26 00:10:56.413377 | controller | ok 2025-10-26 00:10:56.422116 | 2025-10-26 00:10:56.422252 | TASK [Create virtualenv for ansible-test with a specific interpreter] 2025-10-26 00:10:58.627023 | controller | created virtual environment CPython3.9.18.final.0-64 in 1165ms 2025-10-26 00:10:58.668824 | controller | creator CPython3Posix(dest=/home/zuul-worker/venv, clear=False, no_vcs_ignore=False, global=False) 2025-10-26 00:10:58.668883 | 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-worker/.local/share/virtualenv) 2025-10-26 00:10:58.668901 | controller | added seed packages: pip==22.2.2, setuptools==62.6.0, wheel==0.37.1 2025-10-26 00:10:58.668923 | controller | activators BashActivator,CShellActivator,FishActivator,NushellActivator,PowerShellActivator,PythonActivator 2025-10-26 00:10:59.097043 | controller | changed 2025-10-26 00:10:59.103424 | 2025-10-26 00:10:59.103545 | TASK [Set selinux package] 2025-10-26 00:10:59.165284 | controller | ok 2025-10-26 00:10:59.173710 | 2025-10-26 00:10:59.173819 | TASK [Set selinux package (Fedora)] 2025-10-26 00:10:59.227015 | controller | ok 2025-10-26 00:10:59.233428 | 2025-10-26 00:10:59.233555 | TASK [Install selinux into virtualenv] 2025-10-26 00:11:02.872588 | controller | Collecting selinux-please-lie-to-me 2025-10-26 00:11:02.988685 | controller | Downloading selinux_please_lie_to_me-1.0.1-py2.py3-none-any.whl (3.8 kB) 2025-10-26 00:11:03.778529 | controller | Collecting setuptools<50.0.0 2025-10-26 00:11:03.796190 | controller | Downloading setuptools-49.6.0-py3-none-any.whl (803 kB) 2025-10-26 00:11:03.911438 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 803.3/803.3 kB 7.5 MB/s eta 0:00:00 2025-10-26 00:11:04.095122 | controller | Installing collected packages: setuptools, selinux-please-lie-to-me 2025-10-26 00:11:04.095455 | controller | Attempting uninstall: setuptools 2025-10-26 00:11:04.100323 | controller | Found existing installation: setuptools 62.6.0 2025-10-26 00:11:04.265757 | controller | Uninstalling setuptools-62.6.0: 2025-10-26 00:11:04.286323 | controller | Successfully uninstalled setuptools-62.6.0 2025-10-26 00:11:05.169496 | controller | Successfully installed selinux-please-lie-to-me-1.0.1 setuptools-49.6.0 2025-10-26 00:11:05.473545 | controller | 2025-10-26 00:11:05.867589 | controller | [notice] A new release of pip available: 22.2.2 -> 25.3 2025-10-26 00:11:05.867640 | controller | [notice] To update, run: /home/zuul-worker/venv/bin/python -m pip install --upgrade pip 2025-10-26 00:11:05.940280 | controller | ok: Runtime: 0:00:06.065655 2025-10-26 00:11:05.946212 | 2025-10-26 00:11:05.946297 | TASK [Install pytest-forked into virtualenv] 2025-10-26 00:11:07.589202 | controller | Collecting pytest-forked 2025-10-26 00:11:07.681095 | controller | Downloading pytest_forked-1.6.0-py3-none-any.whl (4.9 kB) 2025-10-26 00:11:07.788801 | controller | Collecting py 2025-10-26 00:11:07.801902 | controller | Downloading py-1.11.0-py2.py3-none-any.whl (98 kB) 2025-10-26 00:11:07.839266 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 98.7/98.7 kB 2.9 MB/s eta 0:00:00 2025-10-26 00:11:08.213314 | controller | Collecting pytest>=3.10 2025-10-26 00:11:08.229466 | controller | Downloading pytest-8.4.2-py3-none-any.whl (365 kB) 2025-10-26 00:11:08.291402 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 365.8/365.8 kB 6.3 MB/s eta 0:00:00 2025-10-26 00:11:08.417062 | controller | Collecting pluggy<2,>=1.5 2025-10-26 00:11:08.433039 | controller | Downloading pluggy-1.6.0-py3-none-any.whl (20 kB) 2025-10-26 00:11:08.569302 | controller | Collecting pygments>=2.7.2 2025-10-26 00:11:08.581715 | controller | Downloading pygments-2.19.2-py3-none-any.whl (1.2 MB) 2025-10-26 00:11:08.701853 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 1.2/1.2 MB 10.5 MB/s eta 0:00:00 2025-10-26 00:11:08.762992 | controller | Collecting iniconfig>=1 2025-10-26 00:11:08.780585 | controller | Downloading iniconfig-2.1.0-py3-none-any.whl (6.0 kB) 2025-10-26 00:11:08.906004 | controller | Collecting packaging>=20 2025-10-26 00:11:08.918816 | controller | Downloading packaging-25.0-py3-none-any.whl (66 kB) 2025-10-26 00:11:08.929260 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 66.5/66.5 kB 9.2 MB/s eta 0:00:00 2025-10-26 00:11:08.974149 | controller | Collecting exceptiongroup>=1 2025-10-26 00:11:08.988800 | controller | Downloading exceptiongroup-1.3.0-py3-none-any.whl (16 kB) 2025-10-26 00:11:09.120123 | controller | Collecting tomli>=1 2025-10-26 00:11:09.134004 | controller | Downloading tomli-2.3.0-py3-none-any.whl (14 kB) 2025-10-26 00:11:09.221041 | controller | Collecting typing-extensions>=4.6.0 2025-10-26 00:11:09.234545 | controller | Downloading typing_extensions-4.15.0-py3-none-any.whl (44 kB) 2025-10-26 00:11:09.244381 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 44.6/44.6 kB 6.1 MB/s eta 0:00:00 2025-10-26 00:11:09.373242 | controller | Installing collected packages: typing-extensions, tomli, pygments, py, pluggy, packaging, iniconfig, exceptiongroup, pytest, pytest-forked 2025-10-26 00:11:11.635842 | controller | Successfully installed exceptiongroup-1.3.0 iniconfig-2.1.0 packaging-25.0 pluggy-1.6.0 py-1.11.0 pygments-2.19.2 pytest-8.4.2 pytest-forked-1.6.0 tomli-2.3.0 typing-extensions-4.15.0 2025-10-26 00:11:11.642639 | controller | 2025-10-26 00:11:11.818359 | controller | [notice] A new release of pip available: 22.2.2 -> 25.3 2025-10-26 00:11:11.818404 | controller | [notice] To update, run: /home/zuul-worker/venv/bin/python -m pip install --upgrade pip 2025-10-26 00:11:12.111353 | controller | ok: Runtime: 0:00:05.301780 2025-10-26 00:11:12.124281 | 2025-10-26 00:11:12.124354 | TASK [Update pip] 2025-10-26 00:11:14.029605 | controller | Requirement already satisfied: pip in ./venv/lib/python3.9/site-packages (22.2.2) 2025-10-26 00:11:14.453808 | controller | Collecting pip 2025-10-26 00:11:14.583578 | controller | Downloading pip-25.3-py3-none-any.whl (1.8 MB) 2025-10-26 00:11:14.796363 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 1.8/1.8 MB 8.6 MB/s eta 0:00:00 2025-10-26 00:11:15.043653 | controller | Installing collected packages: pip 2025-10-26 00:11:15.045706 | controller | Attempting uninstall: pip 2025-10-26 00:11:15.047842 | controller | Found existing installation: pip 22.2.2 2025-10-26 00:11:15.639767 | controller | Uninstalling pip-22.2.2: 2025-10-26 00:11:15.703826 | controller | Successfully uninstalled pip-22.2.2 2025-10-26 00:11:17.406947 | controller | Successfully installed pip-25.3 2025-10-26 00:11:17.815429 | controller | ok: Runtime: 0:00:04.719105 2025-10-26 00:11:17.821586 | 2025-10-26 00:11:17.821656 | TASK [Ensure pip temp directory exists in /var/tmp] 2025-10-26 00:11:18.668690 | controller | changed 2025-10-26 00:11:18.673971 | 2025-10-26 00:11:18.674034 | TASK [Install ansible into virtualenv] 2025-10-26 00:11:20.358605 | controller | Processing ./src/github.com/ansible/ansible 2025-10-26 00:11:20.379119 | controller | Installing build dependencies: started 2025-10-26 00:11:22.881341 | controller | Installing build dependencies: finished with status 'done' 2025-10-26 00:11:22.883393 | controller | Getting requirements to build wheel: started 2025-10-26 00:11:24.124875 | controller | Getting requirements to build wheel: finished with status 'done' 2025-10-26 00:11:24.125948 | controller | Preparing metadata (pyproject.toml): started 2025-10-26 00:11:25.047867 | controller | Preparing metadata (pyproject.toml): finished with status 'done' 2025-10-26 00:11:25.173494 | controller | Collecting jinja2 (from ansible-core==2.11.12.post0) 2025-10-26 00:11:25.263295 | controller | Downloading jinja2-3.1.6-py3-none-any.whl.metadata (2.9 kB) 2025-10-26 00:11:25.384500 | controller | Collecting PyYAML (from ansible-core==2.11.12.post0) 2025-10-26 00:11:25.400087 | controller | Downloading pyyaml-6.0.3-cp39-cp39-manylinux2014_x86_64.manylinux_2_17_x86_64.manylinux_2_28_x86_64.whl.metadata (2.4 kB) 2025-10-26 00:11:25.948656 | controller | Collecting cryptography (from ansible-core==2.11.12.post0) 2025-10-26 00:11:25.961972 | controller | Downloading cryptography-46.0.3-cp38-abi3-manylinux_2_34_x86_64.whl.metadata (5.7 kB) 2025-10-26 00:11:25.987186 | controller | Requirement already satisfied: packaging in ./venv/lib/python3.9/site-packages (from ansible-core==2.11.12.post0) (25.0) 2025-10-26 00:11:26.019406 | controller | Collecting resolvelib<0.6.0,>=0.5.3 (from ansible-core==2.11.12.post0) 2025-10-26 00:11:26.033758 | controller | Downloading resolvelib-0.5.4-py2.py3-none-any.whl.metadata (3.7 kB) 2025-10-26 00:11:26.312645 | controller | Collecting cffi>=2.0.0 (from cryptography->ansible-core==2.11.12.post0) 2025-10-26 00:11:26.326012 | controller | Downloading cffi-2.0.0-cp39-cp39-manylinux2014_x86_64.manylinux_2_17_x86_64.whl.metadata (2.6 kB) 2025-10-26 00:11:26.337013 | controller | Requirement already satisfied: typing-extensions>=4.13.2 in ./venv/lib/python3.9/site-packages (from cryptography->ansible-core==2.11.12.post0) (4.15.0) 2025-10-26 00:11:26.359054 | controller | Collecting pycparser (from cffi>=2.0.0->cryptography->ansible-core==2.11.12.post0) 2025-10-26 00:11:26.373421 | controller | Downloading pycparser-2.23-py3-none-any.whl.metadata (993 bytes) 2025-10-26 00:11:26.515444 | controller | Collecting MarkupSafe>=2.0 (from jinja2->ansible-core==2.11.12.post0) 2025-10-26 00:11:26.528597 | controller | Downloading markupsafe-3.0.3-cp39-cp39-manylinux2014_x86_64.manylinux_2_17_x86_64.manylinux_2_28_x86_64.whl.metadata (2.7 kB) 2025-10-26 00:11:26.558091 | controller | Downloading resolvelib-0.5.4-py2.py3-none-any.whl (12 kB) 2025-10-26 00:11:26.578795 | controller | Downloading cryptography-46.0.3-cp38-abi3-manylinux_2_34_x86_64.whl (4.5 MB) 2025-10-26 00:11:26.660610 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 4.5/4.5 MB 79.5 MB/s 0:00:00 2025-10-26 00:11:26.673486 | controller | Downloading cffi-2.0.0-cp39-cp39-manylinux2014_x86_64.manylinux_2_17_x86_64.whl (216 kB) 2025-10-26 00:11:26.696273 | controller | Downloading jinja2-3.1.6-py3-none-any.whl (134 kB) 2025-10-26 00:11:26.718644 | controller | Downloading markupsafe-3.0.3-cp39-cp39-manylinux2014_x86_64.manylinux_2_17_x86_64.manylinux_2_28_x86_64.whl (20 kB) 2025-10-26 00:11:26.739021 | controller | Downloading pycparser-2.23-py3-none-any.whl (118 kB) 2025-10-26 00:11:26.761373 | controller | Downloading pyyaml-6.0.3-cp39-cp39-manylinux2014_x86_64.manylinux_2_17_x86_64.manylinux_2_28_x86_64.whl (750 kB) 2025-10-26 00:11:26.779934 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 750.8/750.8 kB 37.0 MB/s 0:00:00 2025-10-26 00:11:26.824209 | controller | Building wheels for collected packages: ansible-core 2025-10-26 00:11:26.825931 | controller | Building wheel for ansible-core (pyproject.toml): started 2025-10-26 00:11:28.928333 | controller | Building wheel for ansible-core (pyproject.toml): finished with status 'done' 2025-10-26 00:11:28.935653 | controller | Created wheel for ansible-core: filename=ansible_core-2.11.12.post0-py3-none-any.whl size=1961250 sha256=8a49783dcdc920b392263b3a382c25845f09c9bde098b8070de509234aa5804c 2025-10-26 00:11:28.935923 | controller | Stored in directory: /var/tmp/ansible-test-pip/pip-ephem-wheel-cache-o9pf6ff5/wheels/e8/fd/e9/6250c469bad44a7c6855190836e9d22c0bb11cb641caf57fbe 2025-10-26 00:11:28.945146 | controller | Successfully built ansible-core 2025-10-26 00:11:28.985101 | controller | Installing collected packages: resolvelib, PyYAML, pycparser, MarkupSafe, jinja2, cffi, cryptography, ansible-core 2025-10-26 00:11:31.220916 | controller | 2025-10-26 00:11:31.224380 | controller | Successfully installed MarkupSafe-3.0.3 PyYAML-6.0.3 ansible-core-2.11.12.post0 cffi-2.0.0 cryptography-46.0.3 jinja2-3.1.6 pycparser-2.23 resolvelib-0.5.4 2025-10-26 00:11:31.940075 | controller | ok: Runtime: 0:00:11.969656 2025-10-26 00:11:31.949118 | 2025-10-26 00:11:31.949260 | TASK [Setup download-artifact-fork role] 2025-10-26 00:11:31.981699 | controller | ok 2025-10-26 00:11:32.011147 | 2025-10-26 00:11:32.011446 | TASK [download-artifact-fork : Parse inventory response] 2025-10-26 00:11:32.077966 | controller | ok 2025-10-26 00:11:32.088965 | 2025-10-26 00:11:32.089070 | TASK [download-artifact-fork : Fetch info from Zuul API] 2025-10-26 00:11:32.143680 | controller | skipping: Conditional result was False 2025-10-26 00:11:32.155059 | 2025-10-26 00:11:32.155187 | TASK [download-artifact-fork : Parse build response] 2025-10-26 00:11:32.194010 | controller | skipping: Conditional result was False 2025-10-26 00:11:32.206152 | 2025-10-26 00:11:32.206739 | TASK [download-artifact-fork : Ensure artifacts directory exists] 2025-10-26 00:11:32.709520 | controller | changed 2025-10-26 00:11:32.718780 | 2025-10-26 00:11:32.718926 | LOOP [download-artifact-fork : Download archive by type] 2025-10-26 00:11:32.763449 | controller | skipping: Conditional result was False 2025-10-26 00:11:32.763784 | 2025-10-26 00:11:34.496133 | controller | changed: OK (20193 bytes) 2025-10-26 00:11:34.519646 | 2025-10-26 00:11:34.519778 | TASK [deploy-artifacts : define __collections] 2025-10-26 00:11:34.566398 | controller | ok 2025-10-26 00:11:34.571990 | 2025-10-26 00:11:34.572055 | LOOP [deploy-artifacts : Create list of collections] 2025-10-26 00:11:34.622149 | controller | skipping: Conditional result was False 2025-10-26 00:11:34.639381 | controller | ok: 2025-10-26 00:11:34.639502 | controller | { 2025-10-26 00:11:34.639541 | controller | "branch": "main", 2025-10-26 00:11:34.639571 | controller | "job": "build-ansible-collection", 2025-10-26 00:11:34.639602 | controller | "metadata": { 2025-10-26 00:11:34.639628 | controller | "type": "ansible_collection", 2025-10-26 00:11:34.639649 | controller | "version": "5.0.0" 2025-10-26 00:11:34.639705 | controller | }, 2025-10-26 00:11:34.639729 | controller | "name": "ansible.network", 2025-10-26 00:11:34.639749 | controller | "project": "ansible-collections/ansible.network", 2025-10-26 00:11:34.639770 | controller | "url": "https://342c67fb4825aa7713cc-acfa7f4e4687eac5aa5184b6633e56ae.ssl.cf5.rackcdn.com/ansible/6caaaf3b29e848358f33f65ee789bfd2/artifacts/ansible-network-5.0.0.tar.gz" 2025-10-26 00:11:34.639797 | controller | } 2025-10-26 00:11:34.665041 | 2025-10-26 00:11:34.665204 | TASK [deploy-artifacts : Install require-project collection using ansible-galaxy] 2025-10-26 00:11:36.026719 | controller | Starting galaxy collection install process 2025-10-26 00:11:36.027224 | controller | Process install dependency map 2025-10-26 00:11:38.351867 | controller | [WARNING]: Skipping Galaxy server https://galaxy.ansible.com/api/. Got an 2025-10-26 00:11:38.436027 | controller | unexpected error when getting available versions of collection 2025-10-26 00:11:38.436106 | controller | ansible.netcommon: '/api/v3/plugin/ansible/content/published/collections/index/ 2025-10-26 00:11:38.436118 | controller | ansible/netcommon/versions/' 2025-10-26 00:11:38.436129 | controller | ERROR! Unexpected Exception, this is probably a bug: '/api/v3/plugin/ansible/content/published/collections/index/ansible/netcommon/versions/' 2025-10-26 00:11:38.436144 | controller | to see the full traceback, use -vvv 2025-10-26 00:11:38.865881 | controller | ERROR 2025-10-26 00:11:38.866110 | controller | { 2025-10-26 00:11:38.866153 | controller | "delta": "0:00:03.214434", 2025-10-26 00:11:38.866181 | controller | "end": "2025-10-26 00:11:38.436695", 2025-10-26 00:11:38.866208 | controller | "msg": "non-zero return code", 2025-10-26 00:11:38.866233 | controller | "rc": 250, 2025-10-26 00:11:38.866258 | controller | "start": "2025-10-26 00:11:35.222261" 2025-10-26 00:11:38.866282 | controller | } failure 2025-10-26 00:11:38.868979 | 2025-10-26 00:11:38.869039 | PLAY RECAP 2025-10-26 00:11:38.869099 | controller | ok: 44 changed: 24 unreachable: 0 failed: 1 skipped: 17 rescued: 0 ignored: 0 2025-10-26 00:11:38.869131 | 2025-10-26 00:11:39.075476 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master] 2025-10-26 00:11:39.076533 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2025-10-26 00:11:39.931775 | 2025-10-26 00:11:39.931921 | PLAY [all] 2025-10-26 00:11:39.956266 | 2025-10-26 00:11:39.956415 | TASK [fetch-subunit-output : Find stestr or testr executable] 2025-10-26 00:11:41.496776 | controller | changed: non-zero return code 2025-10-26 00:11:41.502939 | 2025-10-26 00:11:41.503034 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2025-10-26 00:11:41.517383 | controller | skipping: Conditional result was False 2025-10-26 00:11:41.524657 | 2025-10-26 00:11:41.524778 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2025-10-26 00:11:41.550258 | 2025-10-26 00:11:41.550516 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2025-10-26 00:11:41.577867 | 2025-10-26 00:11:41.578133 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2025-10-26 00:11:41.617378 | controller | skipping: Conditional result was False 2025-10-26 00:11:41.624982 | 2025-10-26 00:11:41.625374 | LOOP [fetch-subunit-output : Generate subunit file] 2025-10-26 00:11:41.647291 | 2025-10-26 00:11:41.647515 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2025-10-26 00:11:41.676228 | controller | skipping: Conditional result was False 2025-10-26 00:11:41.683850 | 2025-10-26 00:11:41.684029 | TASK [fetch-subunit-output : Remove the temporary file] 2025-10-26 00:11:41.729259 | controller | skipping: Conditional result was False 2025-10-26 00:11:41.736412 | 2025-10-26 00:11:41.736526 | TASK [fetch-subunit-output : Process and fetch subunit results] 2025-10-26 00:11:41.781889 | controller | skipping: Conditional result was False 2025-10-26 00:11:41.842417 | 2025-10-26 00:11:41.842543 | PLAY RECAP 2025-10-26 00:11:41.842588 | controller | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2025-10-26 00:11:41.842608 | 2025-10-26 00:11:42.096828 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2025-10-26 00:11:42.097789 | POST-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/post.yaml@master] 2025-10-26 00:11:43.344007 | 2025-10-26 00:11:43.344132 | PLAY [all:!appliance*] 2025-10-26 00:11:43.394342 | 2025-10-26 00:11:43.394475 | TASK [unregister the node] 2025-10-26 00:11:43.551014 | controller | skipping: Conditional result was False 2025-10-26 00:11:43.558407 | 2025-10-26 00:11:43.558527 | TASK [include_role : fetch-output] 2025-10-26 00:11:43.612520 | controller | ok 2025-10-26 00:11:43.637702 | 2025-10-26 00:11:43.637816 | TASK [fetch-output : Set log path for multiple nodes] 2025-10-26 00:11:43.683285 | controller | skipping: Conditional result was False 2025-10-26 00:11:43.689504 | 2025-10-26 00:11:43.689597 | TASK [fetch-output : Set log path for single node] 2025-10-26 00:11:43.738739 | controller | ok 2025-10-26 00:11:43.745066 | 2025-10-26 00:11:43.745198 | LOOP [fetch-output : Ensure local output dirs] 2025-10-26 00:11:44.243802 | controller -> localhost | ok: "/var/lib/zuul/builds/c871b78df3dd42b6b23c847411e407e9/work/logs" 2025-10-26 00:11:44.493051 | controller -> localhost | changed: "/var/lib/zuul/builds/c871b78df3dd42b6b23c847411e407e9/work/artifacts" 2025-10-26 00:11:44.781905 | controller -> localhost | changed: "/var/lib/zuul/builds/c871b78df3dd42b6b23c847411e407e9/work/docs" 2025-10-26 00:11:44.793288 | 2025-10-26 00:11:44.793406 | LOOP [fetch-output : Collect logs, artifacts and docs] 2025-10-26 00:11:46.030971 | controller | changed: 2025-10-26 00:11:46.031442 | controller | .d..t...... ./ 2025-10-26 00:11:46.031483 | controller | cd+++++++++ controller/ 2025-10-26 00:11:46.031521 | controller | changed: All items complete 2025-10-26 00:11:46.031541 | 2025-10-26 00:11:47.116347 | controller | changed: .d..t...... ./ 2025-10-26 00:11:48.194311 | controller | changed: .d..t...... ./ 2025-10-26 00:11:48.206897 | 2025-10-26 00:11:48.207012 | TASK [include_role : fetch-output-openshift] 2025-10-26 00:11:48.221321 | controller | skipping: Conditional result was False 2025-10-26 00:11:48.228358 | 2025-10-26 00:11:48.228464 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2025-10-26 00:11:48.274938 | controller | skipping: Conditional result was False 2025-10-26 00:11:48.280558 | controller | skipping: Conditional result was False 2025-10-26 00:11:48.318139 | 2025-10-26 00:11:48.318231 | PLAY [localhost] 2025-10-26 00:11:48.331727 | 2025-10-26 00:11:48.331845 | TASK [Run Zuul manifest role] 2025-10-26 00:11:48.360827 | localhost | ok 2025-10-26 00:11:48.381465 | 2025-10-26 00:11:48.381585 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-10-26 00:11:48.839871 | localhost | changed 2025-10-26 00:11:48.845208 | 2025-10-26 00:11:48.845278 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-10-26 00:11:48.874458 | localhost | ok 2025-10-26 00:11:48.882591 | 2025-10-26 00:11:48.882714 | TASK [Set zuul-log-path fact] 2025-10-26 00:11:48.900776 | localhost | ok 2025-10-26 00:11:48.915021 | 2025-10-26 00:11:48.915129 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-10-26 00:11:48.943632 | localhost | ok 2025-10-26 00:11:48.960880 | 2025-10-26 00:11:48.960991 | LOOP [Run upload-logs-swift role] 2025-10-26 00:11:48.995263 | localhost | Output suppressed because no_log was given 2025-10-26 00:11:49.020851 | 2025-10-26 00:11:49.020963 | TASK [Set zuul-log-path fact] 2025-10-26 00:11:49.045171 | localhost | skipping: Conditional result was False 2025-10-26 00:11:49.050483 | 2025-10-26 00:11:49.050566 | TASK [upload-logs-swift : Ensure logs are readable before uploading] 2025-10-26 00:11:49.575790 | localhost -> localhost | ok: Runtime: 0:00:00.011917 2025-10-26 00:11:49.581498 | 2025-10-26 00:11:49.581577 | TASK [upload-logs-swift : Upload logs to swift]