2026-01-17 00:14:38.554959 | Job console starting... 2026-01-17 00:14:38.565159 | Updating repositories 2026-01-17 00:14:38.708417 | Preparing job workspace 2026-01-17 00:14:42.575761 | Running Ansible setup... 2026-01-17 00:14:47.266917 | PRE-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master] 2026-01-17 00:14:47.932000 | 2026-01-17 00:14:47.932195 | PLAY [localhost] 2026-01-17 00:14:47.940475 | 2026-01-17 00:14:47.940549 | TASK [Gathering Facts] 2026-01-17 00:14:49.028417 | localhost | ok 2026-01-17 00:14:49.059323 | 2026-01-17 00:14:49.059671 | TASK [Setup log path fact] 2026-01-17 00:14:49.084314 | localhost | ok 2026-01-17 00:14:49.103905 | 2026-01-17 00:14:49.104127 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-01-17 00:14:49.146240 | localhost | ok 2026-01-17 00:14:49.158795 | 2026-01-17 00:14:49.158919 | TASK [emit-job-header : Print job information] 2026-01-17 00:14:49.215474 | # Job Information 2026-01-17 00:14:49.215836 | Ansible Version: 2.15.12 2026-01-17 00:14:49.215921 | Job: ansible-test-sanity-docker-stable-2.11 2026-01-17 00:14:49.215984 | Pipeline: periodic 2026-01-17 00:14:49.216032 | Executor: ze04.softwarefactory-project.io 2026-01-17 00:14:49.216075 | Triggered by: https://github.com/ansible-collections/ansible.network 2026-01-17 00:14:49.216124 | Log URL (when completed): https://ansible.softwarefactory-project.io/logs/3a1/ansible/3a1cc2d50b5042998bac27bb8819ea0f/ 2026-01-17 00:14:49.216169 | Event ID: 1d1f0d08e7134262830bd5487e0cab0e 2026-01-17 00:14:49.225733 | 2026-01-17 00:14:49.225875 | LOOP [emit-job-header : Print node information] 2026-01-17 00:14:49.349618 | localhost | ok: 2026-01-17 00:14:49.350084 | localhost | # Node Information 2026-01-17 00:14:49.350149 | localhost | Inventory Hostname: controller 2026-01-17 00:14:49.350196 | localhost | Hostname: np0005586319 2026-01-17 00:14:49.350240 | localhost | Username: zuul 2026-01-17 00:14:49.350299 | localhost | Distro: Fedora 37 2026-01-17 00:14:49.350342 | localhost | Provider: ansible-vexxhost-ca-ymq-1 2026-01-17 00:14:49.350381 | localhost | Region: ca-ymq-1 2026-01-17 00:14:49.350419 | localhost | Label: ansible-fedora-37-1vcpu 2026-01-17 00:14:49.350456 | localhost | Product Name: OpenStack Nova 2026-01-17 00:14:49.350494 | localhost | Interface IP: 199.204.45.210 2026-01-17 00:14:49.374673 | 2026-01-17 00:14:49.374926 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-01-17 00:14:49.803181 | localhost -> localhost | changed 2026-01-17 00:14:49.809318 | 2026-01-17 00:14:49.809402 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-01-17 00:14:50.775235 | localhost -> localhost | changed 2026-01-17 00:14:50.801674 | 2026-01-17 00:14:50.801792 | PLAY [all:!appliance*] 2026-01-17 00:14:50.822610 | 2026-01-17 00:14:50.822731 | TASK [include_role : start-zuul-console] 2026-01-17 00:14:50.841289 | controller | ok 2026-01-17 00:14:50.854653 | 2026-01-17 00:14:50.854767 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-01-17 00:14:51.222723 | controller | ok 2026-01-17 00:14:51.235424 | 2026-01-17 00:14:51.235574 | TASK [use-our-mirror : Retrieve the IP address] 2026-01-17 00:15:25.372193 | controller | ok: Status code was 404 and not [200]: HTTP Error 404: Not Found 2026-01-17 00:15:25.379064 | 2026-01-17 00:15:25.379134 | TASK [use-our-mirror : Ensure the registry service is running on the mirror] 2026-01-17 00:15:25.912949 | controller | skipping: Conditional result was False 2026-01-17 00:15:25.923296 | 2026-01-17 00:15:25.923399 | TASK [use-our-mirror : Write the IP in /etc/hosts] 2026-01-17 00:15:25.949365 | controller | skipping: Conditional result was False 2026-01-17 00:15:25.959307 | 2026-01-17 00:15:25.959438 | TASK [use-our-mirror : Create the podman configuration directory] 2026-01-17 00:15:25.985115 | controller | skipping: Conditional result was False 2026-01-17 00:15:25.995313 | 2026-01-17 00:15:25.995441 | TASK [use-our-mirror : Copy the podman configuration] 2026-01-17 00:15:26.021662 | controller | skipping: Conditional result was False 2026-01-17 00:15:26.031856 | 2026-01-17 00:15:26.031980 | TASK [use-our-mirror : Copy the Yum configuration (main)] 2026-01-17 00:15:26.056991 | controller | skipping: Conditional result was False 2026-01-17 00:15:26.066589 | 2026-01-17 00:15:26.066726 | TASK [use-our-mirror : Copy the Yum configuration (update)] 2026-01-17 00:15:26.091818 | controller | skipping: Conditional result was False 2026-01-17 00:15:26.106639 | 2026-01-17 00:15:26.106837 | TASK [Disable Fedora Modular] 2026-01-17 00:15:26.490190 | controller | changed 2026-01-17 00:15:26.499852 | 2026-01-17 00:15:26.499954 | TASK [Enable EPEL] 2026-01-17 00:15:26.526610 | controller | skipping: Conditional result was False 2026-01-17 00:15:26.537216 | 2026-01-17 00:15:26.537324 | TASK [Register the RHEL node] 2026-01-17 00:15:27.112898 | 2026-01-17 00:15:27.113246 | TASK [Show the subscription-manager status] 2026-01-17 00:15:27.688844 | controller | skipping: Conditional result was False 2026-01-17 00:15:27.703074 | 2026-01-17 00:15:27.703208 | TASK [Enable EPEL on RHEL] 2026-01-17 00:15:27.777055 | controller | skipping: Conditional result was False 2026-01-17 00:15:27.792664 | 2026-01-17 00:15:27.792838 | TASK [Install git and tox] 2026-01-17 00:16:15.303991 | controller | changed 2026-01-17 00:16:15.311829 | 2026-01-17 00:16:15.311951 | TASK [include_role : prepare-workspace] 2026-01-17 00:16:15.343564 | controller | ok 2026-01-17 00:16:15.368484 | 2026-01-17 00:16:15.368613 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-01-17 00:16:15.612027 | controller | ok 2026-01-17 00:16:15.622244 | 2026-01-17 00:16:15.622352 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-01-17 00:16:29.452975 | controller | Output suppressed because no_log was given 2026-01-17 00:16:29.470183 | 2026-01-17 00:16:29.470368 | TASK [include_role : prepare-workspace-openshift] 2026-01-17 00:16:29.498519 | controller | skipping: Conditional result was False 2026-01-17 00:16:29.540814 | 2026-01-17 00:16:29.540979 | PLAY [all:!appliance] 2026-01-17 00:16:29.565666 | 2026-01-17 00:16:29.565852 | TASK [Run add-build-sshkey role (RSA)] 2026-01-17 00:16:29.601396 | controller | ok 2026-01-17 00:16:29.623338 | 2026-01-17 00:16:29.623476 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-01-17 00:16:29.919209 | controller -> localhost | ok 2026-01-17 00:16:29.935638 | 2026-01-17 00:16:29.935927 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-01-17 00:16:29.983816 | controller | ok 2026-01-17 00:16:29.998974 | controller | included: /var/lib/zuul/builds/3a1cc2d50b5042998bac27bb8819ea0f/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-01-17 00:16:30.005125 | 2026-01-17 00:16:30.005257 | TASK [add-build-sshkey : Create Temp SSH key] 2026-01-17 00:16:30.579824 | controller -> localhost | Generating public/private rsa key pair. 2026-01-17 00:16:30.580072 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/3a1cc2d50b5042998bac27bb8819ea0f/work/3a1cc2d50b5042998bac27bb8819ea0f_id_rsa. 2026-01-17 00:16:30.580102 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/3a1cc2d50b5042998bac27bb8819ea0f/work/3a1cc2d50b5042998bac27bb8819ea0f_id_rsa.pub. 2026-01-17 00:16:30.580123 | controller -> localhost | The key fingerprint is: 2026-01-17 00:16:30.580142 | controller -> localhost | SHA256:W2RT0mRNR6QtZByF+FBb3hiZiDb10cgqIhsqQap5O/A zuul-build-sshkey 2026-01-17 00:16:30.580160 | controller -> localhost | The key's randomart image is: 2026-01-17 00:16:30.580178 | controller -> localhost | +---[RSA 2048]----+ 2026-01-17 00:16:30.580196 | controller -> localhost | | .==OO@*| 2026-01-17 00:16:30.580215 | controller -> localhost | | . ++=+O@o| 2026-01-17 00:16:30.580232 | controller -> localhost | | o .+. +* +| 2026-01-17 00:16:30.580248 | controller -> localhost | |. . o .o... .. | 2026-01-17 00:16:30.580265 | controller -> localhost | |.. . . +S... | 2026-01-17 00:16:30.580292 | controller -> localhost | |+ o . . o | 2026-01-17 00:16:30.580313 | controller -> localhost | | + o . | 2026-01-17 00:16:30.580332 | controller -> localhost | | E | 2026-01-17 00:16:30.580350 | controller -> localhost | | . | 2026-01-17 00:16:30.580371 | controller -> localhost | +----[SHA256]-----+ 2026-01-17 00:16:30.580418 | controller -> localhost | ok: Runtime: 0:00:00.054296 2026-01-17 00:16:30.615282 | 2026-01-17 00:16:30.615400 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-01-17 00:16:30.646161 | controller | ok 2026-01-17 00:16:30.656705 | controller | included: /var/lib/zuul/builds/3a1cc2d50b5042998bac27bb8819ea0f/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-01-17 00:16:30.666087 | 2026-01-17 00:16:30.666160 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-01-17 00:16:30.682366 | controller | skipping: Conditional result was False 2026-01-17 00:16:30.694047 | 2026-01-17 00:16:30.694305 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-01-17 00:16:31.129308 | controller | changed 2026-01-17 00:16:31.139532 | 2026-01-17 00:16:31.139656 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-01-17 00:16:31.381129 | controller | ok 2026-01-17 00:16:31.394013 | 2026-01-17 00:16:31.394150 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-01-17 00:16:32.069998 | controller | changed 2026-01-17 00:16:32.085998 | 2026-01-17 00:16:32.086206 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-01-17 00:16:32.733848 | controller | changed 2026-01-17 00:16:32.739983 | 2026-01-17 00:16:32.740046 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-01-17 00:16:32.774385 | controller | skipping: Conditional result was False 2026-01-17 00:16:32.793129 | 2026-01-17 00:16:32.793284 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-01-17 00:16:33.194483 | controller -> localhost | changed 2026-01-17 00:16:33.207278 | 2026-01-17 00:16:33.207372 | TASK [add-build-sshkey : Add back temp key] 2026-01-17 00:16:33.486564 | controller -> localhost | Identity added: /var/lib/zuul/builds/3a1cc2d50b5042998bac27bb8819ea0f/work/3a1cc2d50b5042998bac27bb8819ea0f_id_rsa (zuul-build-sshkey) 2026-01-17 00:16:33.487042 | controller -> localhost | ok: Runtime: 0:00:00.013829 2026-01-17 00:16:33.501066 | 2026-01-17 00:16:33.501204 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-01-17 00:16:33.820475 | controller | ok 2026-01-17 00:16:33.826099 | 2026-01-17 00:16:33.826207 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-01-17 00:16:33.860951 | controller | skipping: Conditional result was False 2026-01-17 00:16:33.873726 | 2026-01-17 00:16:33.873811 | TASK [Run add-build-sshkey role (ECDSA)] 2026-01-17 00:16:33.914244 | controller | ok 2026-01-17 00:16:33.945122 | 2026-01-17 00:16:33.945251 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-01-17 00:16:34.197252 | controller -> localhost | ok 2026-01-17 00:16:34.215871 | 2026-01-17 00:16:34.216257 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-01-17 00:16:34.260184 | controller | ok 2026-01-17 00:16:34.285098 | controller | included: /var/lib/zuul/builds/3a1cc2d50b5042998bac27bb8819ea0f/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-01-17 00:16:34.293715 | 2026-01-17 00:16:34.293800 | TASK [add-build-sshkey : Create Temp SSH key] 2026-01-17 00:16:34.595844 | controller -> localhost | Generating public/private ecdsa key pair. 2026-01-17 00:16:34.596222 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/3a1cc2d50b5042998bac27bb8819ea0f/work/3a1cc2d50b5042998bac27bb8819ea0f_id_ecdsa. 2026-01-17 00:16:34.596295 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/3a1cc2d50b5042998bac27bb8819ea0f/work/3a1cc2d50b5042998bac27bb8819ea0f_id_ecdsa.pub. 2026-01-17 00:16:34.596361 | controller -> localhost | The key fingerprint is: 2026-01-17 00:16:34.596405 | controller -> localhost | SHA256:Wj10Nbft9X6QRu78L+OciuFS15hUYT4OkW4zsl5VRr8 zuul-build-sshkey 2026-01-17 00:16:34.596445 | controller -> localhost | The key's randomart image is: 2026-01-17 00:16:34.596485 | controller -> localhost | +---[ECDSA 521]---+ 2026-01-17 00:16:34.596523 | controller -> localhost | | ..=oo| 2026-01-17 00:16:34.596564 | controller -> localhost | | .=.o*| 2026-01-17 00:16:34.596602 | controller -> localhost | | ..o.+++| 2026-01-17 00:16:34.596639 | controller -> localhost | | o..*=.++| 2026-01-17 00:16:34.596713 | controller -> localhost | | S o= B*Eo| 2026-01-17 00:16:34.596763 | controller -> localhost | | o o.=+.o | 2026-01-17 00:16:34.596802 | controller -> localhost | | . o.o o o| 2026-01-17 00:16:34.596838 | controller -> localhost | | ...o .oo.| 2026-01-17 00:16:34.596876 | controller -> localhost | | .o .o+o+| 2026-01-17 00:16:34.596914 | controller -> localhost | +----[SHA256]-----+ 2026-01-17 00:16:34.597008 | controller -> localhost | ok: Runtime: 0:00:00.008632 2026-01-17 00:16:34.614078 | 2026-01-17 00:16:34.614282 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-01-17 00:16:34.640174 | controller | ok 2026-01-17 00:16:34.652380 | controller | included: /var/lib/zuul/builds/3a1cc2d50b5042998bac27bb8819ea0f/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-01-17 00:16:34.666062 | 2026-01-17 00:16:34.666216 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-01-17 00:16:34.680989 | controller | skipping: Conditional result was False 2026-01-17 00:16:34.690920 | 2026-01-17 00:16:34.691065 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-01-17 00:16:34.966364 | controller | changed 2026-01-17 00:16:34.972594 | 2026-01-17 00:16:34.972659 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-01-17 00:16:35.224641 | controller | ok 2026-01-17 00:16:35.238868 | 2026-01-17 00:16:35.239064 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-01-17 00:16:35.910947 | controller | changed 2026-01-17 00:16:35.920855 | 2026-01-17 00:16:35.921006 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-01-17 00:16:36.587945 | controller | changed 2026-01-17 00:16:36.603257 | 2026-01-17 00:16:36.603620 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-01-17 00:16:36.631104 | controller | skipping: Conditional result was False 2026-01-17 00:16:36.644353 | 2026-01-17 00:16:36.644535 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-01-17 00:16:36.908009 | controller -> localhost | changed 2026-01-17 00:16:36.925085 | 2026-01-17 00:16:36.925250 | TASK [add-build-sshkey : Add back temp key] 2026-01-17 00:16:37.245797 | controller -> localhost | Identity added: /var/lib/zuul/builds/3a1cc2d50b5042998bac27bb8819ea0f/work/3a1cc2d50b5042998bac27bb8819ea0f_id_ecdsa (zuul-build-sshkey) 2026-01-17 00:16:37.246105 | controller -> localhost | ok: Runtime: 0:00:00.016362 2026-01-17 00:16:37.257106 | 2026-01-17 00:16:37.257205 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-01-17 00:16:37.486125 | controller | ok 2026-01-17 00:16:37.494722 | 2026-01-17 00:16:37.494854 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-01-17 00:16:37.520443 | controller | skipping: Conditional result was False 2026-01-17 00:16:37.542523 | 2026-01-17 00:16:37.542623 | TASK [include_role : remove-zuul-sshkey] 2026-01-17 00:16:37.568265 | controller | skipping: Conditional result was False 2026-01-17 00:16:37.575129 | 2026-01-17 00:16:37.575212 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-01-17 00:16:37.813233 | controller | ok: "logs" 2026-01-17 00:16:37.813671 | controller | ok: All items complete 2026-01-17 00:16:37.813773 | 2026-01-17 00:16:38.016333 | controller | ok: "artifacts" 2026-01-17 00:16:38.231491 | controller | ok: "docs" 2026-01-17 00:16:38.256650 | 2026-01-17 00:16:38.256923 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-01-17 00:16:38.544193 | controller | changed: "logs" 2026-01-17 00:16:38.804296 | controller | changed: "artifacts" 2026-01-17 00:16:38.949616 | controller | changed: "docs" 2026-01-17 00:16:39.011840 | 2026-01-17 00:16:39.011978 | PLAY RECAP 2026-01-17 00:16:39.012037 | controller | ok: 30 changed: 16 unreachable: 0 failed: 0 skipped: 18 rescued: 0 ignored: 0 2026-01-17 00:16:39.012076 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-01-17 00:16:39.012105 | 2026-01-17 00:16:39.154541 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master] 2026-01-17 00:16:39.155424 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2026-01-17 00:16:39.780844 | 2026-01-17 00:16:39.780989 | PLAY [all] 2026-01-17 00:16:39.805318 | 2026-01-17 00:16:39.805461 | TASK [Install binary dependencies] 2026-01-17 00:16:39.905282 | controller | ok 2026-01-17 00:16:39.942183 | 2026-01-17 00:16:39.942386 | TASK [bindep : Include find tasks] 2026-01-17 00:16:39.989231 | controller | ok 2026-01-17 00:16:40.000931 | controller | included: /var/lib/zuul/builds/3a1cc2d50b5042998bac27bb8819ea0f/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find.yaml 2026-01-17 00:16:40.011999 | 2026-01-17 00:16:40.012098 | TASK [bindep : Look for bindep.txt] 2026-01-17 00:16:40.397896 | controller | ok 2026-01-17 00:16:40.409418 | 2026-01-17 00:16:40.409567 | TASK [bindep : Define bindep_file fact] 2026-01-17 00:16:40.435935 | controller | skipping: Conditional result was False 2026-01-17 00:16:40.446728 | 2026-01-17 00:16:40.446918 | TASK [bindep : Look for other-requirements.txt] 2026-01-17 00:16:40.664953 | controller | ok 2026-01-17 00:16:40.672528 | 2026-01-17 00:16:40.672645 | TASK [bindep : Define bindep_file fact] 2026-01-17 00:16:40.697097 | controller | skipping: Conditional result was False 2026-01-17 00:16:40.703908 | 2026-01-17 00:16:40.704020 | TASK [bindep : Look for bindep fallback file] 2026-01-17 00:16:40.728652 | controller | skipping: Conditional result was False 2026-01-17 00:16:40.735792 | 2026-01-17 00:16:40.735895 | TASK [bindep : Define bindep_file fact] 2026-01-17 00:16:40.770829 | controller | skipping: Conditional result was False 2026-01-17 00:16:40.785854 | 2026-01-17 00:16:40.786057 | TASK [bindep : Include bindep tasks] 2026-01-17 00:16:40.811326 | controller | skipping: Conditional result was False 2026-01-17 00:16:40.821730 | 2026-01-17 00:16:40.821870 | TASK [bindep : Include install tasks] 2026-01-17 00:16:40.846887 | controller | skipping: Conditional result was False 2026-01-17 00:16:40.856108 | 2026-01-17 00:16:40.856227 | LOOP [bindep : Include package tasks] 2026-01-17 00:16:40.934663 | 2026-01-17 00:16:40.934858 | TASK [Run test-setup role] 2026-01-17 00:16:40.956932 | controller | ok 2026-01-17 00:16:40.974744 | 2026-01-17 00:16:40.974860 | TASK [test-setup : Check if project's tools/test-setup.sh exists] 2026-01-17 00:16:41.219090 | controller | ok 2026-01-17 00:16:41.238372 | 2026-01-17 00:16:41.238537 | TASK [test-setup : Run tools/test-setup.sh] 2026-01-17 00:16:41.788598 | controller | skipping: Conditional result was False 2026-01-17 00:16:41.824649 | 2026-01-17 00:16:41.824770 | PLAY RECAP 2026-01-17 00:16:41.824812 | controller | ok: 4 changed: 0 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2026-01-17 00:16:41.824834 | 2026-01-17 00:16:41.929365 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2026-01-17 00:16:41.930294 | PRE-RUN START: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master] 2026-01-17 00:16:42.554168 | 2026-01-17 00:16:42.554307 | PLAY [controller] 2026-01-17 00:16:42.576083 | 2026-01-17 00:16:42.576182 | TASK [Create the /root directory] 2026-01-17 00:16:42.976296 | controller | ok 2026-01-17 00:16:42.984039 | 2026-01-17 00:16:42.984187 | TASK [Install glibc-langpack-en] 2026-01-17 00:16:46.996847 | controller | ok: Nothing to do 2026-01-17 00:16:47.005511 | 2026-01-17 00:16:47.005616 | TASK [Ensure controller directory exists] 2026-01-17 00:16:47.226725 | controller | changed 2026-01-17 00:16:47.232643 | 2026-01-17 00:16:47.232747 | TASK [Install container runtime] 2026-01-17 00:16:47.303250 | controller | ok 2026-01-17 00:16:47.343900 | 2026-01-17 00:16:47.344022 | LOOP [ensure-docker : Gather variables for each operating system] 2026-01-17 00:16:47.387807 | controller | ok: "/var/lib/zuul/builds/3a1cc2d50b5042998bac27bb8819ea0f/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/vars/fedora.yaml" 2026-01-17 00:16:47.405058 | 2026-01-17 00:16:47.405168 | TASK [ensure-docker : Sanity check for distro selection] 2026-01-17 00:16:47.419109 | controller | skipping: Conditional result was False 2026-01-17 00:16:47.425482 | 2026-01-17 00:16:47.425609 | TASK [ensure-docker : Sanity check for distro install] 2026-01-17 00:16:47.449928 | controller | skipping: Conditional result was False 2026-01-17 00:16:47.456555 | 2026-01-17 00:16:47.456701 | TASK [ensure-docker : Sanity check for upstream install] 2026-01-17 00:16:47.480735 | controller | skipping: Conditional result was False 2026-01-17 00:16:47.487295 | 2026-01-17 00:16:47.487382 | TASK [ensure-docker : Sanity check for upstream install mirrors] 2026-01-17 00:16:47.511496 | controller | skipping: Conditional result was False 2026-01-17 00:16:47.519055 | 2026-01-17 00:16:47.519149 | TASK [ensure-docker : Include tasks for docker install] 2026-01-17 00:16:47.538417 | controller | ok 2026-01-17 00:16:47.547288 | controller | included: /var/lib/zuul/builds/3a1cc2d50b5042998bac27bb8819ea0f/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/tasks/docker-upstream.yaml 2026-01-17 00:16:47.556216 | 2026-01-17 00:16:47.556507 | TASK [ensure-docker : Not Implemented] 2026-01-17 00:16:47.570304 | controller | skipping: Conditional result was False 2026-01-17 00:16:47.577340 | 2026-01-17 00:16:47.577437 | TASK [Set up docker mirrors] 2026-01-17 00:16:47.596331 | controller | ok 2026-01-17 00:16:47.617980 | 2026-01-17 00:16:47.618094 | TASK [use-docker-mirror : Set docker_mirror fact from mirror_info] 2026-01-17 00:16:47.642254 | controller | skipping: Conditional result was False 2026-01-17 00:16:47.648749 | 2026-01-17 00:16:47.648840 | TASK [use-docker-mirror : Set docker_mirror fact from zuul_site_mirror_info] 2026-01-17 00:16:47.672780 | controller | skipping: Conditional result was False 2026-01-17 00:16:47.679520 | 2026-01-17 00:16:47.679610 | TASK [use-docker-mirror : Set docker_mirror fact] 2026-01-17 00:16:47.703436 | controller | skipping: Conditional result was False 2026-01-17 00:16:47.711606 | 2026-01-17 00:16:47.711756 | TASK [use-docker-mirror : Set up docker mirrors] 2026-01-17 00:16:47.735858 | controller | skipping: Conditional result was False 2026-01-17 00:16:47.750069 | 2026-01-17 00:16:47.750179 | TASK [Add all repositories] 2026-01-17 00:16:47.770703 | controller | ok 2026-01-17 00:16:47.797413 | 2026-01-17 00:16:47.797533 | LOOP [ensure-package-repositories : Install repositories] 2026-01-17 00:16:47.826476 | controller | ok: "/var/lib/zuul/builds/3a1cc2d50b5042998bac27bb8819ea0f/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-package-repositories/tasks/RedHat.yaml" 2026-01-17 00:16:47.826725 | controller | ok: All items complete 2026-01-17 00:16:47.826755 | 2026-01-17 00:16:47.875329 | controller | included: /var/lib/zuul/builds/3a1cc2d50b5042998bac27bb8819ea0f/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-package-repositories/tasks/RedHat.yaml 2026-01-17 00:16:47.882513 | 2026-01-17 00:16:47.882587 | TASK [ensure-package-repositories : Create GPG key temp directory] 2026-01-17 00:16:48.208337 | controller | changed 2026-01-17 00:16:48.225223 | 2026-01-17 00:16:48.225408 | LOOP [ensure-package-repositories : Upload GPG keys] 2026-01-17 00:16:49.089149 | controller | changed: 2026-01-17 00:16:49.089468 | controller | { 2026-01-17 00:16:49.089525 | 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-01-17 00:16:49.089572 | controller | } 2026-01-17 00:16:49.109383 | 2026-01-17 00:16:49.109569 | LOOP [ensure-package-repositories : Import GPG keys] 2026-01-17 00:16:49.773342 | controller | changed: 2026-01-17 00:16:49.773562 | controller | { 2026-01-17 00:16:49.773592 | 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-01-17 00:16:49.773618 | controller | } 2026-01-17 00:16:49.773655 | controller | changed: All items complete 2026-01-17 00:16:49.773699 | 2026-01-17 00:16:49.784442 | 2026-01-17 00:16:49.784610 | TASK [ensure-package-repositories : Remove GPG temp directory] 2026-01-17 00:16:50.014737 | controller | changed 2026-01-17 00:16:50.023730 | 2026-01-17 00:16:50.023877 | LOOP [ensure-package-repositories : Add repositories] 2026-01-17 00:16:50.400036 | controller | changed: 2026-01-17 00:16:50.404864 | controller | { 2026-01-17 00:16:50.405014 | controller | "baseurl": "https://download.docker.com/linux/fedora/$releasever/$basearch/stable", 2026-01-17 00:16:50.405063 | controller | "description": "Docker CE Stable - $basearch", 2026-01-17 00:16:50.405092 | controller | "gpgcheck": true, 2026-01-17 00:16:50.405118 | controller | "name": "docker-ce-stable" 2026-01-17 00:16:50.405143 | controller | } 2026-01-17 00:16:50.428300 | 2026-01-17 00:16:50.428464 | TASK [ensure-docker : Install pre-reqs] 2026-01-17 00:16:53.288508 | controller | ok: Nothing to do 2026-01-17 00:16:53.304134 | 2026-01-17 00:16:53.304345 | TASK [ensure-docker : Remove packages] 2026-01-17 00:16:54.619780 | controller | ok: Nothing to do 2026-01-17 00:16:54.625860 | 2026-01-17 00:16:54.625925 | TASK [ensure-docker : Install upstream docker using package] 2026-01-17 00:17:40.393513 | controller | changed 2026-01-17 00:17:40.403985 | 2026-01-17 00:17:40.404119 | TASK [ensure-docker : Setup docker] 2026-01-17 00:17:40.437655 | controller | ok 2026-01-17 00:17:40.455632 | controller | included: /var/lib/zuul/builds/3a1cc2d50b5042998bac27bb8819ea0f/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/tasks/docker-setup.yaml 2026-01-17 00:17:40.476284 | 2026-01-17 00:17:40.476404 | TASK [ensure-docker : Ensure "docker" group exists] 2026-01-17 00:17:40.875151 | controller | ok 2026-01-17 00:17:40.889713 | 2026-01-17 00:17:40.889826 | TASK [ensure-docker : Add user to docker group] 2026-01-17 00:17:41.414183 | controller | changed 2026-01-17 00:17:41.420502 | 2026-01-17 00:17:41.420586 | TASK [Update docker daemon proxy configuration] 2026-01-17 00:17:41.456019 | controller | skipping: Conditional result was False 2026-01-17 00:17:41.473779 | 2026-01-17 00:17:41.473929 | TASK [Lower default MTU] 2026-01-17 00:17:41.499561 | controller | skipping: Conditional result was False 2026-01-17 00:17:41.511260 | 2026-01-17 00:17:41.511410 | TASK [ensure-docker : Get the status of the docket.socket unit] 2026-01-17 00:17:41.918481 | controller | inactive 2026-01-17 00:17:42.101016 | controller | ok: Runtime: 0:00:00.016965 2026-01-17 00:17:42.120326 | 2026-01-17 00:17:42.120507 | TASK [ensure-docker : Get the status of the docket unit] 2026-01-17 00:17:42.375924 | controller | inactive 2026-01-17 00:17:42.674862 | controller | ok: Runtime: 0:00:00.009162 2026-01-17 00:17:42.682871 | 2026-01-17 00:17:42.682957 | TASK [ensure-docker : Restart docker] 2026-01-17 00:17:42.746904 | Trigger a Docker restart 2026-01-17 00:17:42.760674 | 2026-01-17 00:17:42.760857 | TASK [ensure-docker : Reset ssh connection to pick up docker group] 2026-01-17 00:17:42.794511 | 2026-01-17 00:17:42.794670 | TASK [ensure-docker : Install docker-compose] 2026-01-17 00:17:42.810845 | controller | skipping: Conditional result was False 2026-01-17 00:17:42.821740 | 2026-01-17 00:17:42.821887 | TASK [ensure-docker : Flush handlers before role exit] 2026-01-17 00:17:42.841290 | 2026-01-17 00:17:42.841427 | TASK [ensure-docker : Stop docker.socket to avoid any conflict] 2026-01-17 00:17:44.189238 | controller | changed 2026-01-17 00:17:44.198255 | 2026-01-17 00:17:44.198398 | TASK [ensure-docker : Assure docker service is running] 2026-01-17 00:17:46.017984 | controller | changed 2026-01-17 00:17:46.025472 | 2026-01-17 00:17:46.025550 | TASK [ensure-docker : Assure docker.socket service is running] 2026-01-17 00:17:47.041769 | controller | ok 2026-01-17 00:17:47.059603 | 2026-01-17 00:17:47.059745 | TASK [ensure-docker : Correct group ownership on docker sock] 2026-01-17 00:17:47.666410 | controller | ok 2026-01-17 00:17:47.677474 | 2026-01-17 00:17:47.677581 | TASK [ensure-docker : Validate ability to talk with docker] 2026-01-17 00:17:48.251357 | controller | CONTAINER ID IMAGE COMMAND CREATED STATUS PORTS NAMES 2026-01-17 00:17:48.726553 | controller | ok: Runtime: 0:00:00.058316 2026-01-17 00:17:48.733546 | 2026-01-17 00:17:48.733669 | TASK [ensure-docker : Get version details] 2026-01-17 00:17:49.033477 | controller | Client: Docker Engine - Community 2026-01-17 00:17:49.033545 | controller | Version: 24.0.7 2026-01-17 00:17:49.033555 | controller | API version: 1.43 2026-01-17 00:17:49.033561 | controller | Go version: go1.20.10 2026-01-17 00:17:49.033574 | controller | Git commit: afdd53b 2026-01-17 00:17:49.033580 | controller | Built: Thu Oct 26 09:09:39 2023 2026-01-17 00:17:49.033585 | controller | OS/Arch: linux/amd64 2026-01-17 00:17:49.033591 | controller | Context: default 2026-01-17 00:17:49.033597 | controller | 2026-01-17 00:17:49.033602 | controller | Server: Docker Engine - Community 2026-01-17 00:17:49.033607 | controller | Engine: 2026-01-17 00:17:49.033613 | controller | Version: 24.0.7 2026-01-17 00:17:49.033635 | controller | API version: 1.43 (minimum version 1.12) 2026-01-17 00:17:49.033641 | controller | Go version: go1.20.10 2026-01-17 00:17:49.033645 | controller | Git commit: 311b9ff 2026-01-17 00:17:49.033650 | controller | Built: Thu Oct 26 09:08:11 2023 2026-01-17 00:17:49.033661 | controller | OS/Arch: linux/amd64 2026-01-17 00:17:49.033666 | controller | Experimental: false 2026-01-17 00:17:49.033670 | controller | containerd: 2026-01-17 00:17:49.033674 | controller | Version: 1.6.25 2026-01-17 00:17:49.033678 | controller | GitCommit: d8f198a4ed8892c764191ef7b3b06d8a2eeb5c7f 2026-01-17 00:17:49.033683 | controller | runc: 2026-01-17 00:17:49.033689 | controller | Version: 1.1.10 2026-01-17 00:17:49.034800 | controller | GitCommit: v1.1.10-0-g18a0cb0 2026-01-17 00:17:49.034816 | controller | docker-init: 2026-01-17 00:17:49.034821 | controller | Version: 0.19.0 2026-01-17 00:17:49.034825 | controller | GitCommit: de40ad0 2026-01-17 00:17:49.276550 | controller | ok: Runtime: 0:00:00.032939 2026-01-17 00:17:49.292034 | 2026-01-17 00:17:49.292210 | TASK [ensure-docker : Dump installed docker details] 2026-01-17 00:17:49.346025 | {'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-01-17 00:17:49.002190', 'end': '2026-01-17 00:17:49.035129', 'delta': '0:00:00.032939', 'msg': '', 'zuul_log_id': 'fa163e3b-3c83-161d-6e28-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-01-17 00:17:49.362028 | 2026-01-17 00:17:49.362487 | TASK [Use buildset registry] 2026-01-17 00:17:49.379852 | controller | skipping: Conditional result was False 2026-01-17 00:17:49.396341 | 2026-01-17 00:17:49.396476 | TASK [Ensure python3.8 is present] 2026-01-17 00:17:49.411851 | controller | skipping: Conditional result was False 2026-01-17 00:17:49.420502 | 2026-01-17 00:17:49.420582 | TASK [Run our-ensure-python role (Fedora only for now)] 2026-01-17 00:17:49.442227 | controller | ok 2026-01-17 00:17:49.464808 | 2026-01-17 00:17:49.464907 | TASK [our-ensure-python : Install the right Python version (rpm)] 2026-01-17 00:17:51.720038 | controller | ok: Nothing to do 2026-01-17 00:17:51.731642 | 2026-01-17 00:17:51.731878 | TASK [our-ensure-python : Also install python3-devel] 2026-01-17 00:18:03.440381 | controller | changed 2026-01-17 00:18:03.471417 | 2026-01-17 00:18:03.471583 | TASK [Run ensure-virtualenv role] 2026-01-17 00:18:03.497220 | controller | ok 2026-01-17 00:18:03.530191 | 2026-01-17 00:18:03.530350 | TASK [ensure-virtualenv : Check if virtualenv is installed] 2026-01-17 00:18:03.767192 | controller | /usr/bin/virtualenv 2026-01-17 00:18:04.071394 | controller | ok: Runtime: 0:00:00.003992 2026-01-17 00:18:04.085976 | 2026-01-17 00:18:04.086798 | LOOP [ensure-virtualenv : Install virtualenv package] 2026-01-17 00:18:04.120931 | controller | skipping: Conditional result was False 2026-01-17 00:18:04.121357 | controller | changed: All items complete 2026-01-17 00:18:04.121416 | 2026-01-17 00:18:04.152818 | 2026-01-17 00:18:04.153009 | TASK [Find the full path of the Python interpreter] 2026-01-17 00:18:04.414875 | controller | /usr/bin/python3.9 2026-01-17 00:18:04.729668 | controller | ok 2026-01-17 00:18:04.743288 | 2026-01-17 00:18:04.743435 | TASK [Create virtualenv for ansible-test with a specific interpreter] 2026-01-17 00:18:06.278871 | controller | created virtual environment CPython3.9.18.final.0-64 in 866ms 2026-01-17 00:18:06.341799 | controller | creator CPython3Posix(dest=/home/zuul/venv, clear=False, no_vcs_ignore=False, global=False) 2026-01-17 00:18:06.341844 | 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-01-17 00:18:06.341856 | controller | added seed packages: pip==22.2.2, setuptools==62.6.0, wheel==0.37.1 2026-01-17 00:18:06.341870 | controller | activators BashActivator,CShellActivator,FishActivator,NushellActivator,PowerShellActivator,PythonActivator 2026-01-17 00:18:06.804316 | controller | changed 2026-01-17 00:18:06.818279 | 2026-01-17 00:18:06.818517 | TASK [Set selinux package] 2026-01-17 00:18:06.851222 | controller | ok 2026-01-17 00:18:06.863570 | 2026-01-17 00:18:06.863744 | TASK [Set selinux package (Fedora)] 2026-01-17 00:18:06.924613 | controller | ok 2026-01-17 00:18:06.936020 | 2026-01-17 00:18:06.936149 | TASK [Install selinux into virtualenv] 2026-01-17 00:18:22.240660 | controller | Collecting selinux-please-lie-to-me 2026-01-17 00:18:34.503575 | controller | Downloading selinux_please_lie_to_me-1.0.1-py2.py3-none-any.whl (3.8 kB) 2026-01-17 00:18:34.854897 | controller | Collecting setuptools<50.0.0 2026-01-17 00:18:34.871600 | controller | Downloading setuptools-49.6.0-py3-none-any.whl (803 kB) 2026-01-17 00:18:34.908335 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 803.3/803.3 kB 24.9 MB/s eta 0:00:00 2026-01-17 00:18:34.988645 | controller | Installing collected packages: setuptools, selinux-please-lie-to-me 2026-01-17 00:18:34.989083 | controller | Attempting uninstall: setuptools 2026-01-17 00:18:34.989120 | controller | Found existing installation: setuptools 62.6.0 2026-01-17 00:18:35.067568 | controller | Uninstalling setuptools-62.6.0: 2026-01-17 00:18:35.076185 | controller | Successfully uninstalled setuptools-62.6.0 2026-01-17 00:18:35.415606 | controller | Successfully installed selinux-please-lie-to-me-1.0.1 setuptools-49.6.0 2026-01-17 00:18:46.886384 | controller | 2026-01-17 00:18:46.964268 | controller | [notice] A new release of pip available: 22.2.2 -> 25.3 2026-01-17 00:18:46.964305 | controller | [notice] To update, run: /home/zuul/venv/bin/python -m pip install --upgrade pip 2026-01-17 00:18:47.011200 | controller | ok: Runtime: 0:00:39.705372 2026-01-17 00:18:47.024558 | 2026-01-17 00:18:47.024769 | TASK [Install pytest-forked into virtualenv] 2026-01-17 00:19:11.100086 | controller | Collecting pytest-forked 2026-01-17 00:19:35.365197 | controller | Downloading pytest_forked-1.6.0-py3-none-any.whl (4.9 kB) 2026-01-17 00:19:35.504949 | controller | Collecting pytest>=3.10 2026-01-17 00:19:35.509700 | controller | Downloading pytest-8.4.2-py3-none-any.whl (365 kB) 2026-01-17 00:19:35.613116 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 365.8/365.8 kB 3.7 MB/s eta 0:00:00 2026-01-17 00:19:35.673924 | controller | Collecting py 2026-01-17 00:19:35.679751 | controller | Downloading py-1.11.0-py2.py3-none-any.whl (98 kB) 2026-01-17 00:19:35.714469 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 98.7/98.7 kB 2.9 MB/s eta 0:00:00 2026-01-17 00:19:35.805782 | controller | Collecting exceptiongroup>=1 2026-01-17 00:19:35.810411 | controller | Downloading exceptiongroup-1.3.1-py3-none-any.whl (16 kB) 2026-01-17 00:19:35.926568 | controller | Collecting packaging>=20 2026-01-17 00:19:35.931341 | controller | Downloading packaging-25.0-py3-none-any.whl (66 kB) 2026-01-17 00:19:36.026316 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 66.5/66.5 kB 623.1 kB/s eta 0:00:00 2026-01-17 00:19:36.123788 | controller | Collecting tomli>=1 2026-01-17 00:19:36.128027 | controller | Downloading tomli-2.4.0-py3-none-any.whl (14 kB) 2026-01-17 00:19:36.228292 | controller | Collecting iniconfig>=1 2026-01-17 00:19:36.234689 | controller | Downloading iniconfig-2.1.0-py3-none-any.whl (6.0 kB) 2026-01-17 00:19:36.306861 | controller | Collecting pluggy<2,>=1.5 2026-01-17 00:19:36.310838 | controller | Downloading pluggy-1.6.0-py3-none-any.whl (20 kB) 2026-01-17 00:19:36.368910 | controller | Collecting pygments>=2.7.2 2026-01-17 00:19:36.376807 | controller | Downloading pygments-2.19.2-py3-none-any.whl (1.2 MB) 2026-01-17 00:19:36.403221 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 1.2/1.2 MB 56.3 MB/s eta 0:00:00 2026-01-17 00:19:36.471083 | controller | Collecting typing-extensions>=4.6.0 2026-01-17 00:19:36.475397 | controller | Downloading typing_extensions-4.15.0-py3-none-any.whl (44 kB) 2026-01-17 00:19:36.482993 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 44.6/44.6 kB 7.7 MB/s eta 0:00:00 2026-01-17 00:19:36.558276 | controller | Installing collected packages: typing-extensions, tomli, pygments, py, pluggy, packaging, iniconfig, exceptiongroup, pytest, pytest-forked 2026-01-17 00:19:37.683844 | controller | Successfully installed exceptiongroup-1.3.1 iniconfig-2.1.0 packaging-25.0 pluggy-1.6.0 py-1.11.0 pygments-2.19.2 pytest-8.4.2 pytest-forked-1.6.0 tomli-2.4.0 typing-extensions-4.15.0 2026-01-17 00:19:37.688759 | controller | 2026-01-17 00:19:37.773002 | controller | [notice] A new release of pip available: 22.2.2 -> 25.3 2026-01-17 00:19:37.773055 | controller | [notice] To update, run: /home/zuul/venv/bin/python -m pip install --upgrade pip 2026-01-17 00:19:38.108931 | controller | ok: Runtime: 0:00:50.520561 2026-01-17 00:19:38.128789 | 2026-01-17 00:19:38.129057 | TASK [Update pip] 2026-01-17 00:19:38.665208 | controller | Requirement already satisfied: pip in ./venv/lib/python3.9/site-packages (22.2.2) 2026-01-17 00:19:51.120668 | controller | Collecting pip 2026-01-17 00:20:03.466583 | controller | Downloading pip-25.3-py3-none-any.whl (1.8 MB) 2026-01-17 00:20:03.546842 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 1.8/1.8 MB 24.3 MB/s eta 0:00:00 2026-01-17 00:20:03.614620 | controller | Installing collected packages: pip 2026-01-17 00:20:03.614909 | controller | Attempting uninstall: pip 2026-01-17 00:20:03.615735 | controller | Found existing installation: pip 22.2.2 2026-01-17 00:20:03.788390 | controller | Uninstalling pip-22.2.2: 2026-01-17 00:20:03.809736 | controller | Successfully uninstalled pip-22.2.2 2026-01-17 00:20:04.681812 | controller | Successfully installed pip-25.3 2026-01-17 00:20:05.207162 | controller | ok: Runtime: 0:00:26.444086 2026-01-17 00:20:05.221315 | 2026-01-17 00:20:05.221461 | TASK [Ensure pip temp directory exists in /var/tmp] 2026-01-17 00:20:05.438148 | controller | changed 2026-01-17 00:20:05.454637 | 2026-01-17 00:20:05.454870 | TASK [Install ansible into virtualenv] 2026-01-17 00:20:05.974806 | controller | Processing ./src/github.com/ansible/ansible 2026-01-17 00:20:05.978916 | controller | Installing build dependencies: started 2026-01-17 00:20:28.344825 | controller | Installing build dependencies: finished with status 'done' 2026-01-17 00:20:28.345722 | controller | Getting requirements to build wheel: started 2026-01-17 00:20:28.973043 | controller | Getting requirements to build wheel: finished with status 'done' 2026-01-17 00:20:28.974217 | controller | Preparing metadata (pyproject.toml): started 2026-01-17 00:20:29.435241 | controller | Preparing metadata (pyproject.toml): finished with status 'done' 2026-01-17 00:20:40.367253 | controller | Collecting jinja2 (from ansible-core==2.11.12.post0) 2026-01-17 00:20:52.616745 | controller | Downloading jinja2-3.1.6-py3-none-any.whl.metadata (2.9 kB) 2026-01-17 00:20:52.702244 | controller | Collecting PyYAML (from ansible-core==2.11.12.post0) 2026-01-17 00:20:52.712638 | 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-01-17 00:20:53.031774 | controller | Collecting cryptography (from ansible-core==2.11.12.post0) 2026-01-17 00:20:53.039505 | controller | Downloading cryptography-46.0.3-cp38-abi3-manylinux_2_34_x86_64.whl.metadata (5.7 kB) 2026-01-17 00:20:53.057897 | controller | Requirement already satisfied: packaging in ./venv/lib/python3.9/site-packages (from ansible-core==2.11.12.post0) (25.0) 2026-01-17 00:20:53.077278 | controller | Collecting resolvelib<0.6.0,>=0.5.3 (from ansible-core==2.11.12.post0) 2026-01-17 00:20:53.085024 | controller | Downloading resolvelib-0.5.4-py2.py3-none-any.whl.metadata (3.7 kB) 2026-01-17 00:20:53.257118 | controller | Collecting cffi>=2.0.0 (from cryptography->ansible-core==2.11.12.post0) 2026-01-17 00:20:53.263343 | controller | Downloading cffi-2.0.0-cp39-cp39-manylinux2014_x86_64.manylinux_2_17_x86_64.whl.metadata (2.6 kB) 2026-01-17 00:20:53.270795 | 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-01-17 00:20:53.285827 | controller | Collecting pycparser (from cffi>=2.0.0->cryptography->ansible-core==2.11.12.post0) 2026-01-17 00:20:53.289911 | controller | Downloading pycparser-2.23-py3-none-any.whl.metadata (993 bytes) 2026-01-17 00:20:53.376661 | controller | Collecting MarkupSafe>=2.0 (from jinja2->ansible-core==2.11.12.post0) 2026-01-17 00:20:53.382367 | 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-01-17 00:20:53.397552 | controller | Downloading resolvelib-0.5.4-py2.py3-none-any.whl (12 kB) 2026-01-17 00:20:53.406201 | controller | Downloading cryptography-46.0.3-cp38-abi3-manylinux_2_34_x86_64.whl (4.5 MB) 2026-01-17 00:20:53.489554 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 4.5/4.5 MB 61.3 MB/s 0:00:00 2026-01-17 00:20:53.499684 | controller | Downloading cffi-2.0.0-cp39-cp39-manylinux2014_x86_64.manylinux_2_17_x86_64.whl (216 kB) 2026-01-17 00:20:53.512014 | controller | Downloading jinja2-3.1.6-py3-none-any.whl (134 kB) 2026-01-17 00:20:53.522949 | 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-01-17 00:20:53.532082 | controller | Downloading pycparser-2.23-py3-none-any.whl (118 kB) 2026-01-17 00:20:53.546218 | 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-01-17 00:20:53.591040 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 750.8/750.8 kB 12.8 MB/s 0:00:00 2026-01-17 00:20:53.606291 | controller | Building wheels for collected packages: ansible-core 2026-01-17 00:20:53.607403 | controller | Building wheel for ansible-core (pyproject.toml): started 2026-01-17 00:20:55.166154 | controller | Building wheel for ansible-core (pyproject.toml): finished with status 'done' 2026-01-17 00:20:55.170578 | controller | Created wheel for ansible-core: filename=ansible_core-2.11.12.post0-py3-none-any.whl size=1961250 sha256=9dd30d16c832abe03e9e91c9977ece310f0e36cea14a81819e6e35e4009e12d6 2026-01-17 00:20:55.170814 | controller | Stored in directory: /var/tmp/ansible-test-pip/pip-ephem-wheel-cache-cbwirhk2/wheels/0f/a0/93/ab85b739e1eb1e0467171316f13571c14efdf87e0d5d44a33e 2026-01-17 00:20:55.181486 | controller | Successfully built ansible-core 2026-01-17 00:20:55.218669 | controller | Installing collected packages: resolvelib, PyYAML, pycparser, MarkupSafe, jinja2, cffi, cryptography, ansible-core 2026-01-17 00:20:56.723713 | controller | 2026-01-17 00:20:56.727897 | 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 2026-01-17 00:20:57.035890 | controller | ok: Runtime: 0:00:51.153649 2026-01-17 00:20:57.051000 | 2026-01-17 00:20:57.051292 | TASK [Setup download-artifact-fork role] 2026-01-17 00:20:57.084185 | controller | ok 2026-01-17 00:20:57.120609 | 2026-01-17 00:20:57.120779 | TASK [download-artifact-fork : Parse inventory response] 2026-01-17 00:20:57.165772 | controller | ok 2026-01-17 00:20:57.176663 | 2026-01-17 00:20:57.176807 | TASK [download-artifact-fork : Fetch info from Zuul API] 2026-01-17 00:20:57.202185 | controller | skipping: Conditional result was False 2026-01-17 00:20:57.212164 | 2026-01-17 00:20:57.212273 | TASK [download-artifact-fork : Parse build response] 2026-01-17 00:20:57.258125 | controller | skipping: Conditional result was False 2026-01-17 00:20:57.268967 | 2026-01-17 00:20:57.269179 | TASK [download-artifact-fork : Ensure artifacts directory exists] 2026-01-17 00:20:57.483129 | controller | changed 2026-01-17 00:20:57.497183 | 2026-01-17 00:20:57.497530 | LOOP [download-artifact-fork : Download archive by type] 2026-01-17 00:20:57.535064 | controller | skipping: Conditional result was False 2026-01-17 00:20:57.535512 | 2026-01-17 00:20:58.325076 | controller | changed: OK (20217 bytes) 2026-01-17 00:20:58.340260 | 2026-01-17 00:20:58.340387 | TASK [deploy-artifacts : define __collections] 2026-01-17 00:20:58.360605 | controller | ok 2026-01-17 00:20:58.367819 | 2026-01-17 00:20:58.368111 | LOOP [deploy-artifacts : Create list of collections] 2026-01-17 00:20:58.393185 | controller | skipping: Conditional result was False 2026-01-17 00:20:58.406976 | controller | ok: 2026-01-17 00:20:58.407296 | controller | { 2026-01-17 00:20:58.407348 | controller | "branch": "main", 2026-01-17 00:20:58.407381 | controller | "job": "build-ansible-collection", 2026-01-17 00:20:58.407410 | controller | "metadata": { 2026-01-17 00:20:58.407438 | controller | "type": "ansible_collection", 2026-01-17 00:20:58.407472 | controller | "version": "5.0.0" 2026-01-17 00:20:58.407501 | controller | }, 2026-01-17 00:20:58.407529 | controller | "name": "ansible.network", 2026-01-17 00:20:58.407556 | controller | "project": "ansible-collections/ansible.network", 2026-01-17 00:20:58.407583 | controller | "url": "https://3e93aea80da6297696de-f8db71afff3b564ab05ad6479d1e1938.ssl.cf1.rackcdn.com/ansible/ff195b73f8164aa78a0e00fe8fc28043/artifacts/ansible-network-5.0.0.tar.gz" 2026-01-17 00:20:58.407620 | controller | } 2026-01-17 00:20:58.423725 | 2026-01-17 00:20:58.423837 | TASK [deploy-artifacts : Install require-project collection using ansible-galaxy] 2026-01-17 00:20:59.005827 | controller | Starting galaxy collection install process 2026-01-17 00:20:59.006069 | controller | Process install dependency map 2026-01-17 00:21:53.652480 | controller | [WARNING]: Skipping Galaxy server https://galaxy.ansible.com/api/. Got an 2026-01-17 00:21:53.652741 | controller | unexpected error when getting available versions of collection 2026-01-17 00:21:53.652778 | controller | ansible.netcommon: '/api/v3/plugin/ansible/content/published/collections/index/ 2026-01-17 00:21:53.652790 | controller | ansible/netcommon/versions/' 2026-01-17 00:21:53.652806 | controller | ERROR! Unexpected Exception, this is probably a bug: '/api/v3/plugin/ansible/content/published/collections/index/ansible/netcommon/versions/' 2026-01-17 00:21:53.699959 | controller | to see the full traceback, use -vvv 2026-01-17 00:21:53.992933 | controller | ERROR 2026-01-17 00:21:53.993486 | controller | { 2026-01-17 00:21:53.993593 | controller | "delta": "0:00:55.083029", 2026-01-17 00:21:53.993649 | controller | "end": "2026-01-17 00:21:53.700516", 2026-01-17 00:21:53.993962 | controller | "msg": "non-zero return code", 2026-01-17 00:21:53.994031 | controller | "rc": 250, 2026-01-17 00:21:53.994071 | controller | "start": "2026-01-17 00:20:58.617487" 2026-01-17 00:21:53.994107 | controller | } failure 2026-01-17 00:21:53.997547 | 2026-01-17 00:21:53.997653 | PLAY RECAP 2026-01-17 00:21:53.997808 | controller | ok: 44 changed: 24 unreachable: 0 failed: 1 skipped: 17 rescued: 0 ignored: 0 2026-01-17 00:21:53.997992 | 2026-01-17 00:21:54.174094 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master] 2026-01-17 00:21:54.176054 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2026-01-17 00:21:54.765671 | 2026-01-17 00:21:54.765830 | PLAY [all] 2026-01-17 00:21:54.788605 | 2026-01-17 00:21:54.788757 | TASK [fetch-subunit-output : Find stestr or testr executable] 2026-01-17 00:21:55.277490 | controller | changed: non-zero return code 2026-01-17 00:21:55.292234 | 2026-01-17 00:21:55.292496 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2026-01-17 00:21:55.322066 | controller | skipping: Conditional result was False 2026-01-17 00:21:55.337073 | 2026-01-17 00:21:55.337263 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2026-01-17 00:21:55.381719 | 2026-01-17 00:21:55.382065 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2026-01-17 00:21:55.424253 | 2026-01-17 00:21:55.424577 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2026-01-17 00:21:55.451724 | controller | skipping: Conditional result was False 2026-01-17 00:21:55.467831 | 2026-01-17 00:21:55.467991 | LOOP [fetch-subunit-output : Generate subunit file] 2026-01-17 00:21:55.507288 | 2026-01-17 00:21:55.507580 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2026-01-17 00:21:55.534530 | controller | skipping: Conditional result was False 2026-01-17 00:21:55.543039 | 2026-01-17 00:21:55.543134 | TASK [fetch-subunit-output : Remove the temporary file] 2026-01-17 00:21:55.568043 | controller | skipping: Conditional result was False 2026-01-17 00:21:55.576208 | 2026-01-17 00:21:55.576308 | TASK [fetch-subunit-output : Process and fetch subunit results] 2026-01-17 00:21:55.591047 | controller | skipping: Conditional result was False 2026-01-17 00:21:55.621658 | 2026-01-17 00:21:55.621796 | PLAY RECAP 2026-01-17 00:21:55.621843 | controller | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2026-01-17 00:21:55.621864 | 2026-01-17 00:21:55.776027 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2026-01-17 00:21:55.776957 | POST-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/post.yaml@master] 2026-01-17 00:21:56.381858 | 2026-01-17 00:21:56.381990 | PLAY [all:!appliance*] 2026-01-17 00:21:56.406479 | 2026-01-17 00:21:56.406631 | TASK [unregister the node] 2026-01-17 00:21:56.942842 | controller | skipping: Conditional result was False 2026-01-17 00:21:56.950507 | 2026-01-17 00:21:56.950628 | TASK [include_role : fetch-output] 2026-01-17 00:21:57.010303 | controller | ok 2026-01-17 00:21:57.054035 | 2026-01-17 00:21:57.054204 | TASK [fetch-output : Set log path for multiple nodes] 2026-01-17 00:21:57.131447 | controller | skipping: Conditional result was False 2026-01-17 00:21:57.139627 | 2026-01-17 00:21:57.139805 | TASK [fetch-output : Set log path for single node] 2026-01-17 00:21:57.183116 | controller | ok 2026-01-17 00:21:57.191273 | 2026-01-17 00:21:57.191370 | LOOP [fetch-output : Ensure local output dirs] 2026-01-17 00:21:57.649403 | controller -> localhost | ok: "/var/lib/zuul/builds/3a1cc2d50b5042998bac27bb8819ea0f/work/logs" 2026-01-17 00:21:57.914204 | controller -> localhost | changed: "/var/lib/zuul/builds/3a1cc2d50b5042998bac27bb8819ea0f/work/artifacts" 2026-01-17 00:21:58.175233 | controller -> localhost | changed: "/var/lib/zuul/builds/3a1cc2d50b5042998bac27bb8819ea0f/work/docs" 2026-01-17 00:21:58.185046 | 2026-01-17 00:21:58.185174 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-01-17 00:21:58.964310 | controller | changed: 2026-01-17 00:21:58.964760 | controller | .d..t...... ./ 2026-01-17 00:21:58.964806 | controller | cd+++++++++ controller/ 2026-01-17 00:21:58.964845 | controller | changed: All items complete 2026-01-17 00:21:58.964866 | 2026-01-17 00:21:59.485439 | controller | changed: .d..t...... ./ 2026-01-17 00:22:00.020572 | controller | changed: .d..t...... ./ 2026-01-17 00:22:00.056951 | 2026-01-17 00:22:00.057103 | TASK [include_role : fetch-output-openshift] 2026-01-17 00:22:00.082867 | controller | skipping: Conditional result was False 2026-01-17 00:22:00.093760 | 2026-01-17 00:22:00.093898 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-01-17 00:22:00.133435 | controller | skipping: Conditional result was False 2026-01-17 00:22:00.146456 | controller | skipping: Conditional result was False 2026-01-17 00:22:00.196402 | 2026-01-17 00:22:00.196535 | PLAY [localhost] 2026-01-17 00:22:00.218016 | 2026-01-17 00:22:00.218157 | TASK [Run Zuul manifest role] 2026-01-17 00:22:00.240405 | localhost | ok 2026-01-17 00:22:00.261816 | 2026-01-17 00:22:00.261968 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-01-17 00:22:00.642333 | localhost | changed 2026-01-17 00:22:00.650238 | 2026-01-17 00:22:00.650345 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-01-17 00:22:00.680544 | localhost | ok 2026-01-17 00:22:00.693204 | 2026-01-17 00:22:00.693306 | TASK [Set zuul-log-path fact] 2026-01-17 00:22:00.715735 | localhost | ok 2026-01-17 00:22:00.741985 | 2026-01-17 00:22:00.742137 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-01-17 00:22:00.774825 | localhost | ok 2026-01-17 00:22:00.791514 | 2026-01-17 00:22:00.791652 | LOOP [Run upload-logs-swift role] 2026-01-17 00:22:00.817635 | localhost | Output suppressed because no_log was given 2026-01-17 00:22:00.852023 | 2026-01-17 00:22:00.852136 | TASK [Set zuul-log-path fact] 2026-01-17 00:22:00.886554 | localhost | skipping: Conditional result was False 2026-01-17 00:22:00.895297 | 2026-01-17 00:22:00.895441 | TASK [upload-logs-swift : Ensure logs are readable before uploading] 2026-01-17 00:22:01.339196 | localhost -> localhost | ok: Runtime: 0:00:00.005023 2026-01-17 00:22:01.351885 | 2026-01-17 00:22:01.352059 | TASK [upload-logs-swift : Upload logs to swift]