2026-01-15 00:15:59.042472 | Job console starting... 2026-01-15 00:15:59.053612 | Updating repositories 2026-01-15 00:15:59.165515 | Preparing job workspace 2026-01-15 00:16:03.011317 | Running Ansible setup... 2026-01-15 00:16:07.794956 | PRE-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master] 2026-01-15 00:16:08.522824 | 2026-01-15 00:16:08.523053 | PLAY [localhost] 2026-01-15 00:16:08.533685 | 2026-01-15 00:16:08.533844 | TASK [Gathering Facts] 2026-01-15 00:16:09.679923 | localhost | ok 2026-01-15 00:16:09.709856 | 2026-01-15 00:16:09.710049 | TASK [Setup log path fact] 2026-01-15 00:16:09.734507 | localhost | ok 2026-01-15 00:16:09.763104 | 2026-01-15 00:16:09.763283 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-01-15 00:16:09.792535 | localhost | ok 2026-01-15 00:16:09.800666 | 2026-01-15 00:16:09.800801 | TASK [emit-job-header : Print job information] 2026-01-15 00:16:09.829449 | # Job Information 2026-01-15 00:16:09.829668 | Ansible Version: 2.15.12 2026-01-15 00:16:09.829704 | Job: ansible-test-sanity-docker-stable-2.11 2026-01-15 00:16:09.829727 | Pipeline: periodic 2026-01-15 00:16:09.829746 | Executor: ze01.softwarefactory-project.io 2026-01-15 00:16:09.829764 | Triggered by: https://github.com/ansible-collections/ansible.network 2026-01-15 00:16:09.829785 | Log URL (when completed): https://ansible.softwarefactory-project.io/logs/a9a/ansible/a9ac3b96db594bc0a84e613a53f568e0/ 2026-01-15 00:16:09.829804 | Event ID: 8b3815b1a9ff4b788b5ab1e219793ecd 2026-01-15 00:16:09.833952 | 2026-01-15 00:16:09.834098 | LOOP [emit-job-header : Print node information] 2026-01-15 00:16:09.971212 | localhost | ok: 2026-01-15 00:16:09.971436 | localhost | # Node Information 2026-01-15 00:16:09.971466 | localhost | Inventory Hostname: controller 2026-01-15 00:16:09.971487 | localhost | Hostname: np0005584530 2026-01-15 00:16:09.971506 | localhost | Username: zuul 2026-01-15 00:16:09.971531 | localhost | Distro: Fedora 37 2026-01-15 00:16:09.971551 | localhost | Provider: ansible-vexxhost-ca-ymq-1 2026-01-15 00:16:09.971569 | localhost | Region: ca-ymq-1 2026-01-15 00:16:09.971585 | localhost | Label: ansible-fedora-37-1vcpu 2026-01-15 00:16:09.971601 | localhost | Product Name: OpenStack Nova 2026-01-15 00:16:09.971618 | localhost | Interface IP: 162.253.55.212 2026-01-15 00:16:09.981498 | 2026-01-15 00:16:09.981632 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-01-15 00:16:10.479917 | localhost -> localhost | changed 2026-01-15 00:16:10.494111 | 2026-01-15 00:16:10.494310 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-01-15 00:16:11.734715 | localhost -> localhost | changed 2026-01-15 00:16:11.762390 | 2026-01-15 00:16:11.762556 | PLAY [all:!appliance*] 2026-01-15 00:16:11.784312 | 2026-01-15 00:16:11.784445 | TASK [include_role : start-zuul-console] 2026-01-15 00:16:11.806499 | controller | ok 2026-01-15 00:16:11.825189 | 2026-01-15 00:16:11.825309 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-01-15 00:16:12.253062 | controller | ok 2026-01-15 00:16:12.278280 | 2026-01-15 00:16:12.278528 | TASK [use-our-mirror : Retrieve the IP address] 2026-01-15 00:16:13.275160 | controller | ok: Status code was 404 and not [200]: HTTP Error 404: Not Found 2026-01-15 00:16:13.289865 | 2026-01-15 00:16:13.289950 | TASK [use-our-mirror : Ensure the registry service is running on the mirror] 2026-01-15 00:16:13.840478 | controller | skipping: Conditional result was False 2026-01-15 00:16:13.852801 | 2026-01-15 00:16:13.852962 | TASK [use-our-mirror : Write the IP in /etc/hosts] 2026-01-15 00:16:13.880354 | controller | skipping: Conditional result was False 2026-01-15 00:16:13.892175 | 2026-01-15 00:16:13.892520 | TASK [use-our-mirror : Create the podman configuration directory] 2026-01-15 00:16:13.909448 | controller | skipping: Conditional result was False 2026-01-15 00:16:13.917291 | 2026-01-15 00:16:13.917410 | TASK [use-our-mirror : Copy the podman configuration] 2026-01-15 00:16:13.941079 | controller | skipping: Conditional result was False 2026-01-15 00:16:13.947746 | 2026-01-15 00:16:13.947826 | TASK [use-our-mirror : Copy the Yum configuration (main)] 2026-01-15 00:16:13.972224 | controller | skipping: Conditional result was False 2026-01-15 00:16:13.983651 | 2026-01-15 00:16:13.983795 | TASK [use-our-mirror : Copy the Yum configuration (update)] 2026-01-15 00:16:14.008945 | controller | skipping: Conditional result was False 2026-01-15 00:16:14.026363 | 2026-01-15 00:16:14.026505 | TASK [Disable Fedora Modular] 2026-01-15 00:16:14.265286 | controller | changed 2026-01-15 00:16:14.274140 | 2026-01-15 00:16:14.274236 | TASK [Enable EPEL] 2026-01-15 00:16:14.300518 | controller | skipping: Conditional result was False 2026-01-15 00:16:14.310921 | 2026-01-15 00:16:14.311068 | TASK [Register the RHEL node] 2026-01-15 00:16:14.868637 | 2026-01-15 00:16:14.868849 | TASK [Show the subscription-manager status] 2026-01-15 00:16:15.484973 | controller | skipping: Conditional result was False 2026-01-15 00:16:15.498125 | 2026-01-15 00:16:15.498309 | TASK [Enable EPEL on RHEL] 2026-01-15 00:16:16.055149 | controller | skipping: Conditional result was False 2026-01-15 00:16:16.061697 | 2026-01-15 00:16:16.061769 | TASK [Install git and tox] 2026-01-15 00:18:15.203757 | controller | changed 2026-01-15 00:18:15.214089 | 2026-01-15 00:18:15.214267 | TASK [include_role : prepare-workspace] 2026-01-15 00:18:15.252298 | controller | ok 2026-01-15 00:18:15.279197 | 2026-01-15 00:18:15.279352 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-01-15 00:18:15.551286 | controller | ok 2026-01-15 00:18:15.560973 | 2026-01-15 00:18:15.561112 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-01-15 00:18:28.563073 | controller | Output suppressed because no_log was given 2026-01-15 00:18:28.578684 | 2026-01-15 00:18:28.578811 | TASK [include_role : prepare-workspace-openshift] 2026-01-15 00:18:28.606086 | controller | skipping: Conditional result was False 2026-01-15 00:18:28.681103 | 2026-01-15 00:18:28.681200 | PLAY [all:!appliance] 2026-01-15 00:18:28.697503 | 2026-01-15 00:18:28.697574 | TASK [Run add-build-sshkey role (RSA)] 2026-01-15 00:18:28.728997 | controller | ok 2026-01-15 00:18:28.746083 | 2026-01-15 00:18:28.746182 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-01-15 00:18:29.039528 | controller -> localhost | ok 2026-01-15 00:18:29.049282 | 2026-01-15 00:18:29.049382 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-01-15 00:18:29.074697 | controller | ok 2026-01-15 00:18:29.100715 | controller | included: /var/lib/zuul/builds/a9ac3b96db594bc0a84e613a53f568e0/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-01-15 00:18:29.111694 | 2026-01-15 00:18:29.111816 | TASK [add-build-sshkey : Create Temp SSH key] 2026-01-15 00:18:29.564615 | controller -> localhost | Generating public/private rsa key pair. 2026-01-15 00:18:29.564819 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/a9ac3b96db594bc0a84e613a53f568e0/work/a9ac3b96db594bc0a84e613a53f568e0_id_rsa. 2026-01-15 00:18:29.564849 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/a9ac3b96db594bc0a84e613a53f568e0/work/a9ac3b96db594bc0a84e613a53f568e0_id_rsa.pub. 2026-01-15 00:18:29.564872 | controller -> localhost | The key fingerprint is: 2026-01-15 00:18:29.564892 | controller -> localhost | SHA256:29cgIi4T5l9B+HXFfeYBQ/RWWkjhHwh2flUZBPTGAow zuul-build-sshkey 2026-01-15 00:18:29.564913 | controller -> localhost | The key's randomart image is: 2026-01-15 00:18:29.564934 | controller -> localhost | +---[RSA 2048]----+ 2026-01-15 00:18:29.564958 | controller -> localhost | | o++@B*O| 2026-01-15 00:18:29.564978 | controller -> localhost | | . E..=oO=*| 2026-01-15 00:18:29.564997 | controller -> localhost | | . . . .+o@o| 2026-01-15 00:18:29.565038 | controller -> localhost | | o . . =.o| 2026-01-15 00:18:29.565068 | controller -> localhost | | o . S . . .| 2026-01-15 00:18:29.565100 | controller -> localhost | | o o . = . o | 2026-01-15 00:18:29.565125 | controller -> localhost | | + . o . . . | 2026-01-15 00:18:29.565146 | controller -> localhost | | + . . | 2026-01-15 00:18:29.565166 | controller -> localhost | | . | 2026-01-15 00:18:29.565192 | controller -> localhost | +----[SHA256]-----+ 2026-01-15 00:18:29.565243 | controller -> localhost | ok: Runtime: 0:00:00.039968 2026-01-15 00:18:29.572285 | 2026-01-15 00:18:29.572350 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-01-15 00:18:29.602342 | controller | ok 2026-01-15 00:18:29.612767 | controller | included: /var/lib/zuul/builds/a9ac3b96db594bc0a84e613a53f568e0/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-01-15 00:18:29.622140 | 2026-01-15 00:18:29.622233 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-01-15 00:18:29.637681 | controller | skipping: Conditional result was False 2026-01-15 00:18:29.647485 | 2026-01-15 00:18:29.647584 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-01-15 00:18:30.081358 | controller | changed 2026-01-15 00:18:30.095967 | 2026-01-15 00:18:30.096160 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-01-15 00:18:30.343000 | controller | ok 2026-01-15 00:18:30.354557 | 2026-01-15 00:18:30.354699 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-01-15 00:18:31.031446 | controller | changed 2026-01-15 00:18:31.044602 | 2026-01-15 00:18:31.044744 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-01-15 00:18:31.767118 | controller | changed 2026-01-15 00:18:31.777003 | 2026-01-15 00:18:31.777115 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-01-15 00:18:31.802005 | controller | skipping: Conditional result was False 2026-01-15 00:18:31.813333 | 2026-01-15 00:18:31.813447 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-01-15 00:18:32.279930 | controller -> localhost | changed 2026-01-15 00:18:32.293639 | 2026-01-15 00:18:32.293769 | TASK [add-build-sshkey : Add back temp key] 2026-01-15 00:18:32.645114 | controller -> localhost | Identity added: /var/lib/zuul/builds/a9ac3b96db594bc0a84e613a53f568e0/work/a9ac3b96db594bc0a84e613a53f568e0_id_rsa (zuul-build-sshkey) 2026-01-15 00:18:32.645554 | controller -> localhost | ok: Runtime: 0:00:00.015011 2026-01-15 00:18:32.659402 | 2026-01-15 00:18:32.659542 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-01-15 00:18:33.025885 | controller | ok 2026-01-15 00:18:33.040424 | 2026-01-15 00:18:33.040571 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-01-15 00:18:33.087729 | controller | skipping: Conditional result was False 2026-01-15 00:18:33.113642 | 2026-01-15 00:18:33.113801 | TASK [Run add-build-sshkey role (ECDSA)] 2026-01-15 00:18:33.147529 | controller | ok 2026-01-15 00:18:33.169237 | 2026-01-15 00:18:33.169345 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-01-15 00:18:33.471340 | controller -> localhost | ok 2026-01-15 00:18:33.489760 | 2026-01-15 00:18:33.489921 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-01-15 00:18:33.531340 | controller | ok 2026-01-15 00:18:33.558902 | controller | included: /var/lib/zuul/builds/a9ac3b96db594bc0a84e613a53f568e0/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-01-15 00:18:33.574163 | 2026-01-15 00:18:33.574312 | TASK [add-build-sshkey : Create Temp SSH key] 2026-01-15 00:18:33.932468 | controller -> localhost | Generating public/private ecdsa key pair. 2026-01-15 00:18:33.932837 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/a9ac3b96db594bc0a84e613a53f568e0/work/a9ac3b96db594bc0a84e613a53f568e0_id_ecdsa. 2026-01-15 00:18:33.932901 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/a9ac3b96db594bc0a84e613a53f568e0/work/a9ac3b96db594bc0a84e613a53f568e0_id_ecdsa.pub. 2026-01-15 00:18:33.932959 | controller -> localhost | The key fingerprint is: 2026-01-15 00:18:33.933004 | controller -> localhost | SHA256:Tt1M1YkVaBflpt7qx5uP6Z6W0FptgHeljIHBuuLPQwE zuul-build-sshkey 2026-01-15 00:18:33.933085 | controller -> localhost | The key's randomart image is: 2026-01-15 00:18:33.933129 | controller -> localhost | +---[ECDSA 521]---+ 2026-01-15 00:18:33.933168 | controller -> localhost | | ..o ==*| 2026-01-15 00:18:33.933211 | controller -> localhost | | E o .= +o| 2026-01-15 00:18:33.933267 | controller -> localhost | | . . o=..+| 2026-01-15 00:18:33.933334 | controller -> localhost | | o. +o =o.| 2026-01-15 00:18:33.933405 | controller -> localhost | | So. oo.+ | 2026-01-15 00:18:33.933458 | controller -> localhost | | .oo ..o.o| 2026-01-15 00:18:33.933500 | controller -> localhost | | . o. +.+.| 2026-01-15 00:18:33.933540 | controller -> localhost | | ... . o=+| 2026-01-15 00:18:33.933578 | controller -> localhost | | .o. =O++| 2026-01-15 00:18:33.933616 | controller -> localhost | +----[SHA256]-----+ 2026-01-15 00:18:33.933723 | controller -> localhost | ok: Runtime: 0:00:00.014365 2026-01-15 00:18:33.949810 | 2026-01-15 00:18:33.949951 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-01-15 00:18:33.995495 | controller | ok 2026-01-15 00:18:34.012667 | controller | included: /var/lib/zuul/builds/a9ac3b96db594bc0a84e613a53f568e0/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-01-15 00:18:34.039548 | 2026-01-15 00:18:34.039694 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-01-15 00:18:34.065690 | controller | skipping: Conditional result was False 2026-01-15 00:18:34.073134 | 2026-01-15 00:18:34.073229 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-01-15 00:18:34.349380 | controller | changed 2026-01-15 00:18:34.357756 | 2026-01-15 00:18:34.357881 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-01-15 00:18:34.601378 | controller | ok 2026-01-15 00:18:34.612882 | 2026-01-15 00:18:34.613050 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-01-15 00:18:35.277371 | controller | changed 2026-01-15 00:18:35.290785 | 2026-01-15 00:18:35.290945 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-01-15 00:18:35.982387 | controller | changed 2026-01-15 00:18:35.998328 | 2026-01-15 00:18:35.998530 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-01-15 00:18:36.028985 | controller | skipping: Conditional result was False 2026-01-15 00:18:36.040658 | 2026-01-15 00:18:36.040806 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-01-15 00:18:36.339336 | controller -> localhost | changed 2026-01-15 00:18:36.358327 | 2026-01-15 00:18:36.358481 | TASK [add-build-sshkey : Add back temp key] 2026-01-15 00:18:36.725072 | controller -> localhost | Identity added: /var/lib/zuul/builds/a9ac3b96db594bc0a84e613a53f568e0/work/a9ac3b96db594bc0a84e613a53f568e0_id_ecdsa (zuul-build-sshkey) 2026-01-15 00:18:36.725430 | controller -> localhost | ok: Runtime: 0:00:00.014322 2026-01-15 00:18:36.807237 | 2026-01-15 00:18:36.807394 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-01-15 00:18:37.053930 | controller | ok 2026-01-15 00:18:37.068902 | 2026-01-15 00:18:37.069111 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-01-15 00:18:37.095913 | controller | skipping: Conditional result was False 2026-01-15 00:18:37.118670 | 2026-01-15 00:18:37.119005 | TASK [include_role : remove-zuul-sshkey] 2026-01-15 00:18:37.136438 | controller | skipping: Conditional result was False 2026-01-15 00:18:37.144588 | 2026-01-15 00:18:37.144688 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-01-15 00:18:37.392103 | controller | ok: "logs" 2026-01-15 00:18:37.392635 | controller | ok: All items complete 2026-01-15 00:18:37.392721 | 2026-01-15 00:18:37.616664 | controller | ok: "artifacts" 2026-01-15 00:18:37.822145 | controller | ok: "docs" 2026-01-15 00:18:37.840900 | 2026-01-15 00:18:37.841098 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-01-15 00:18:38.080188 | controller | changed: "logs" 2026-01-15 00:18:38.289404 | controller | changed: "artifacts" 2026-01-15 00:18:38.485813 | controller | changed: "docs" 2026-01-15 00:18:38.555490 | 2026-01-15 00:18:38.555729 | PLAY RECAP 2026-01-15 00:18:38.555798 | controller | ok: 30 changed: 16 unreachable: 0 failed: 0 skipped: 18 rescued: 0 ignored: 0 2026-01-15 00:18:38.555835 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-01-15 00:18:38.555860 | 2026-01-15 00:18:38.738605 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master] 2026-01-15 00:18:38.743683 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2026-01-15 00:18:39.389925 | 2026-01-15 00:18:39.390130 | PLAY [all] 2026-01-15 00:18:39.416976 | 2026-01-15 00:18:39.417214 | TASK [Install binary dependencies] 2026-01-15 00:18:39.513637 | controller | ok 2026-01-15 00:18:39.545870 | 2026-01-15 00:18:39.546054 | TASK [bindep : Include find tasks] 2026-01-15 00:18:39.598372 | controller | ok 2026-01-15 00:18:39.607401 | controller | included: /var/lib/zuul/builds/a9ac3b96db594bc0a84e613a53f568e0/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find.yaml 2026-01-15 00:18:39.614275 | 2026-01-15 00:18:39.614356 | TASK [bindep : Look for bindep.txt] 2026-01-15 00:18:40.011947 | controller | ok 2026-01-15 00:18:40.025590 | 2026-01-15 00:18:40.025741 | TASK [bindep : Define bindep_file fact] 2026-01-15 00:18:40.055401 | controller | skipping: Conditional result was False 2026-01-15 00:18:40.070576 | 2026-01-15 00:18:40.070735 | TASK [bindep : Look for other-requirements.txt] 2026-01-15 00:18:40.322063 | controller | ok 2026-01-15 00:18:40.331394 | 2026-01-15 00:18:40.331559 | TASK [bindep : Define bindep_file fact] 2026-01-15 00:18:40.359470 | controller | skipping: Conditional result was False 2026-01-15 00:18:40.366629 | 2026-01-15 00:18:40.366709 | TASK [bindep : Look for bindep fallback file] 2026-01-15 00:18:40.401694 | controller | skipping: Conditional result was False 2026-01-15 00:18:40.413100 | 2026-01-15 00:18:40.413216 | TASK [bindep : Define bindep_file fact] 2026-01-15 00:18:40.452557 | controller | skipping: Conditional result was False 2026-01-15 00:18:40.463070 | 2026-01-15 00:18:40.463273 | TASK [bindep : Include bindep tasks] 2026-01-15 00:18:40.494378 | controller | skipping: Conditional result was False 2026-01-15 00:18:40.504285 | 2026-01-15 00:18:40.504375 | TASK [bindep : Include install tasks] 2026-01-15 00:18:40.531554 | controller | skipping: Conditional result was False 2026-01-15 00:18:40.542898 | 2026-01-15 00:18:40.543010 | LOOP [bindep : Include package tasks] 2026-01-15 00:18:40.625939 | 2026-01-15 00:18:40.626382 | TASK [Run test-setup role] 2026-01-15 00:18:40.655256 | controller | ok 2026-01-15 00:18:40.681387 | 2026-01-15 00:18:40.681568 | TASK [test-setup : Check if project's tools/test-setup.sh exists] 2026-01-15 00:18:40.928341 | controller | ok 2026-01-15 00:18:40.946107 | 2026-01-15 00:18:40.946398 | TASK [test-setup : Run tools/test-setup.sh] 2026-01-15 00:18:41.501183 | controller | skipping: Conditional result was False 2026-01-15 00:18:41.532932 | 2026-01-15 00:18:41.533101 | PLAY RECAP 2026-01-15 00:18:41.533153 | controller | ok: 4 changed: 0 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2026-01-15 00:18:41.533174 | 2026-01-15 00:18:41.668878 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2026-01-15 00:18:41.670907 | PRE-RUN START: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master] 2026-01-15 00:18:42.318261 | 2026-01-15 00:18:42.318439 | PLAY [controller] 2026-01-15 00:18:42.340471 | 2026-01-15 00:18:42.340684 | TASK [Create the /root directory] 2026-01-15 00:18:42.730094 | controller | ok 2026-01-15 00:18:42.744821 | 2026-01-15 00:18:42.744997 | TASK [Install glibc-langpack-en] 2026-01-15 00:18:46.687336 | controller | ok: Nothing to do 2026-01-15 00:18:46.701652 | 2026-01-15 00:18:46.701876 | TASK [Ensure controller directory exists] 2026-01-15 00:18:46.902705 | controller | changed 2026-01-15 00:18:46.908688 | 2026-01-15 00:18:46.908764 | TASK [Install container runtime] 2026-01-15 00:18:46.961569 | controller | ok 2026-01-15 00:18:47.007032 | 2026-01-15 00:18:47.007183 | LOOP [ensure-docker : Gather variables for each operating system] 2026-01-15 00:18:47.065988 | controller | ok: "/var/lib/zuul/builds/a9ac3b96db594bc0a84e613a53f568e0/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/vars/fedora.yaml" 2026-01-15 00:18:47.078570 | 2026-01-15 00:18:47.078681 | TASK [ensure-docker : Sanity check for distro selection] 2026-01-15 00:18:47.103881 | controller | skipping: Conditional result was False 2026-01-15 00:18:47.110438 | 2026-01-15 00:18:47.110513 | TASK [ensure-docker : Sanity check for distro install] 2026-01-15 00:18:47.146099 | controller | skipping: Conditional result was False 2026-01-15 00:18:47.152712 | 2026-01-15 00:18:47.152804 | TASK [ensure-docker : Sanity check for upstream install] 2026-01-15 00:18:47.178432 | controller | skipping: Conditional result was False 2026-01-15 00:18:47.185483 | 2026-01-15 00:18:47.185582 | TASK [ensure-docker : Sanity check for upstream install mirrors] 2026-01-15 00:18:47.210271 | controller | skipping: Conditional result was False 2026-01-15 00:18:47.218639 | 2026-01-15 00:18:47.218751 | TASK [ensure-docker : Include tasks for docker install] 2026-01-15 00:18:47.250132 | controller | ok 2026-01-15 00:18:47.259181 | controller | included: /var/lib/zuul/builds/a9ac3b96db594bc0a84e613a53f568e0/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/tasks/docker-upstream.yaml 2026-01-15 00:18:47.268131 | 2026-01-15 00:18:47.268232 | TASK [ensure-docker : Not Implemented] 2026-01-15 00:18:47.292749 | controller | skipping: Conditional result was False 2026-01-15 00:18:47.299690 | 2026-01-15 00:18:47.299769 | TASK [Set up docker mirrors] 2026-01-15 00:18:47.320504 | controller | ok 2026-01-15 00:18:47.343449 | 2026-01-15 00:18:47.343594 | TASK [use-docker-mirror : Set docker_mirror fact from mirror_info] 2026-01-15 00:18:47.368183 | controller | skipping: Conditional result was False 2026-01-15 00:18:47.375121 | 2026-01-15 00:18:47.375212 | TASK [use-docker-mirror : Set docker_mirror fact from zuul_site_mirror_info] 2026-01-15 00:18:47.399953 | controller | skipping: Conditional result was False 2026-01-15 00:18:47.406935 | 2026-01-15 00:18:47.407054 | TASK [use-docker-mirror : Set docker_mirror fact] 2026-01-15 00:18:47.432048 | controller | skipping: Conditional result was False 2026-01-15 00:18:47.439466 | 2026-01-15 00:18:47.439712 | TASK [use-docker-mirror : Set up docker mirrors] 2026-01-15 00:18:47.464581 | controller | skipping: Conditional result was False 2026-01-15 00:18:47.478330 | 2026-01-15 00:18:47.478461 | TASK [Add all repositories] 2026-01-15 00:18:47.499316 | controller | ok 2026-01-15 00:18:47.526523 | 2026-01-15 00:18:47.526756 | LOOP [ensure-package-repositories : Install repositories] 2026-01-15 00:18:47.550221 | controller | ok: "/var/lib/zuul/builds/a9ac3b96db594bc0a84e613a53f568e0/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-package-repositories/tasks/RedHat.yaml" 2026-01-15 00:18:47.550519 | controller | ok: All items complete 2026-01-15 00:18:47.550551 | 2026-01-15 00:18:47.571610 | controller | included: /var/lib/zuul/builds/a9ac3b96db594bc0a84e613a53f568e0/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-package-repositories/tasks/RedHat.yaml 2026-01-15 00:18:47.620398 | 2026-01-15 00:18:47.620539 | TASK [ensure-package-repositories : Create GPG key temp directory] 2026-01-15 00:18:47.946690 | controller | changed 2026-01-15 00:18:47.954349 | 2026-01-15 00:18:47.954455 | LOOP [ensure-package-repositories : Upload GPG keys] 2026-01-15 00:18:48.899418 | controller | changed: 2026-01-15 00:18:48.899773 | controller | { 2026-01-15 00:18:48.899832 | 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-15 00:18:48.899881 | controller | } 2026-01-15 00:18:48.920259 | 2026-01-15 00:18:48.920706 | LOOP [ensure-package-repositories : Import GPG keys] 2026-01-15 00:18:49.578164 | controller | changed: 2026-01-15 00:18:49.578555 | controller | { 2026-01-15 00:18:49.578614 | 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-15 00:18:49.578669 | controller | } 2026-01-15 00:18:49.578738 | controller | changed: All items complete 2026-01-15 00:18:49.578782 | 2026-01-15 00:18:49.590552 | 2026-01-15 00:18:49.590735 | TASK [ensure-package-repositories : Remove GPG temp directory] 2026-01-15 00:18:49.832601 | controller | changed 2026-01-15 00:18:49.843517 | 2026-01-15 00:18:49.843713 | LOOP [ensure-package-repositories : Add repositories] 2026-01-15 00:18:50.230237 | controller | changed: 2026-01-15 00:18:50.230638 | controller | { 2026-01-15 00:18:50.230733 | controller | "baseurl": "https://download.docker.com/linux/fedora/$releasever/$basearch/stable", 2026-01-15 00:18:50.230812 | controller | "description": "Docker CE Stable - $basearch", 2026-01-15 00:18:50.231092 | controller | "gpgcheck": true, 2026-01-15 00:18:50.231169 | controller | "name": "docker-ce-stable" 2026-01-15 00:18:50.231241 | controller | } 2026-01-15 00:18:50.265551 | 2026-01-15 00:18:50.265987 | TASK [ensure-docker : Install pre-reqs] 2026-01-15 00:18:53.069864 | controller | ok: Nothing to do 2026-01-15 00:18:53.079401 | 2026-01-15 00:18:53.079523 | TASK [ensure-docker : Remove packages] 2026-01-15 00:18:54.482650 | controller | ok: Nothing to do 2026-01-15 00:18:54.497689 | 2026-01-15 00:18:54.497917 | TASK [ensure-docker : Install upstream docker using package] 2026-01-15 00:19:41.427685 | controller | changed 2026-01-15 00:19:41.437321 | 2026-01-15 00:19:41.437434 | TASK [ensure-docker : Setup docker] 2026-01-15 00:19:41.462849 | controller | ok 2026-01-15 00:19:41.478632 | controller | included: /var/lib/zuul/builds/a9ac3b96db594bc0a84e613a53f568e0/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/tasks/docker-setup.yaml 2026-01-15 00:19:41.495925 | 2026-01-15 00:19:41.496084 | TASK [ensure-docker : Ensure "docker" group exists] 2026-01-15 00:19:41.935538 | controller | ok 2026-01-15 00:19:41.955341 | 2026-01-15 00:19:41.955633 | TASK [ensure-docker : Add user to docker group] 2026-01-15 00:19:42.548670 | controller | changed 2026-01-15 00:19:42.566423 | 2026-01-15 00:19:42.566665 | TASK [Update docker daemon proxy configuration] 2026-01-15 00:19:42.606983 | controller | skipping: Conditional result was False 2026-01-15 00:19:42.619467 | 2026-01-15 00:19:42.619633 | TASK [Lower default MTU] 2026-01-15 00:19:42.648183 | controller | skipping: Conditional result was False 2026-01-15 00:19:42.668980 | 2026-01-15 00:19:42.669292 | TASK [ensure-docker : Get the status of the docket.socket unit] 2026-01-15 00:19:43.122577 | controller | inactive 2026-01-15 00:19:43.293516 | controller | ok: Runtime: 0:00:00.013915 2026-01-15 00:19:43.302432 | 2026-01-15 00:19:43.302526 | TASK [ensure-docker : Get the status of the docket unit] 2026-01-15 00:19:43.582276 | controller | inactive 2026-01-15 00:19:43.853674 | controller | ok: Runtime: 0:00:00.008365 2026-01-15 00:19:43.861459 | 2026-01-15 00:19:43.861544 | TASK [ensure-docker : Restart docker] 2026-01-15 00:19:43.938516 | Trigger a Docker restart 2026-01-15 00:19:43.944651 | 2026-01-15 00:19:43.944764 | TASK [ensure-docker : Reset ssh connection to pick up docker group] 2026-01-15 00:19:43.990113 | 2026-01-15 00:19:43.990281 | TASK [ensure-docker : Install docker-compose] 2026-01-15 00:19:44.016770 | controller | skipping: Conditional result was False 2026-01-15 00:19:44.024931 | 2026-01-15 00:19:44.025147 | TASK [ensure-docker : Flush handlers before role exit] 2026-01-15 00:19:44.040124 | 2026-01-15 00:19:44.040474 | TASK [ensure-docker : Stop docker.socket to avoid any conflict] 2026-01-15 00:19:45.196338 | controller | changed 2026-01-15 00:19:45.212231 | 2026-01-15 00:19:45.212399 | TASK [ensure-docker : Assure docker service is running] 2026-01-15 00:19:47.282375 | controller | changed 2026-01-15 00:19:47.291236 | 2026-01-15 00:19:47.291339 | TASK [ensure-docker : Assure docker.socket service is running] 2026-01-15 00:19:48.259155 | controller | ok 2026-01-15 00:19:48.270466 | 2026-01-15 00:19:48.270592 | TASK [ensure-docker : Correct group ownership on docker sock] 2026-01-15 00:19:48.849545 | controller | ok 2026-01-15 00:19:48.866110 | 2026-01-15 00:19:48.866319 | TASK [ensure-docker : Validate ability to talk with docker] 2026-01-15 00:19:49.503011 | controller | CONTAINER ID IMAGE COMMAND CREATED STATUS PORTS NAMES 2026-01-15 00:19:49.921153 | controller | ok: Runtime: 0:00:00.052255 2026-01-15 00:19:49.932506 | 2026-01-15 00:19:49.932642 | TASK [ensure-docker : Get version details] 2026-01-15 00:19:50.577137 | controller | Client: Docker Engine - Community 2026-01-15 00:19:50.577175 | controller | Version: 24.0.7 2026-01-15 00:19:50.577182 | controller | API version: 1.43 2026-01-15 00:19:50.577187 | controller | Go version: go1.20.10 2026-01-15 00:19:50.577198 | controller | Git commit: afdd53b 2026-01-15 00:19:50.577203 | controller | Built: Thu Oct 26 09:09:39 2023 2026-01-15 00:19:50.577208 | controller | OS/Arch: linux/amd64 2026-01-15 00:19:50.577214 | controller | Context: default 2026-01-15 00:19:50.577220 | controller | 2026-01-15 00:19:50.577227 | controller | Server: Docker Engine - Community 2026-01-15 00:19:50.577260 | controller | Engine: 2026-01-15 00:19:50.577267 | controller | Version: 24.0.7 2026-01-15 00:19:50.577271 | controller | API version: 1.43 (minimum version 1.12) 2026-01-15 00:19:50.577276 | controller | Go version: go1.20.10 2026-01-15 00:19:50.577280 | controller | Git commit: 311b9ff 2026-01-15 00:19:50.577286 | controller | Built: Thu Oct 26 09:08:11 2023 2026-01-15 00:19:50.577317 | controller | OS/Arch: linux/amd64 2026-01-15 00:19:50.577322 | controller | Experimental: false 2026-01-15 00:19:50.577326 | controller | containerd: 2026-01-15 00:19:50.577332 | controller | Version: 1.6.25 2026-01-15 00:19:50.577348 | controller | GitCommit: d8f198a4ed8892c764191ef7b3b06d8a2eeb5c7f 2026-01-15 00:19:50.577353 | controller | runc: 2026-01-15 00:19:50.577358 | controller | Version: 1.1.10 2026-01-15 00:19:50.577372 | controller | GitCommit: v1.1.10-0-g18a0cb0 2026-01-15 00:19:50.577379 | controller | docker-init: 2026-01-15 00:19:50.577391 | controller | Version: 0.19.0 2026-01-15 00:19:50.579923 | controller | GitCommit: de40ad0 2026-01-15 00:19:51.009497 | controller | ok: Runtime: 0:00:00.067638 2026-01-15 00:19:51.024666 | 2026-01-15 00:19:51.024829 | TASK [ensure-docker : Dump installed docker details] 2026-01-15 00:19:51.073876 | {'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-15 00:19:50.512518', 'end': '2026-01-15 00:19:50.580156', 'delta': '0:00:00.067638', 'msg': '', 'zuul_log_id': 'fa163ec2-ffbe-553e-bd68-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-15 00:19:51.084883 | 2026-01-15 00:19:51.085066 | TASK [Use buildset registry] 2026-01-15 00:19:51.102642 | controller | skipping: Conditional result was False 2026-01-15 00:19:51.121291 | 2026-01-15 00:19:51.121499 | TASK [Ensure python3.8 is present] 2026-01-15 00:19:51.150038 | controller | skipping: Conditional result was False 2026-01-15 00:19:51.160318 | 2026-01-15 00:19:51.160514 | TASK [Run our-ensure-python role (Fedora only for now)] 2026-01-15 00:19:51.193642 | controller | ok 2026-01-15 00:19:51.220222 | 2026-01-15 00:19:51.220403 | TASK [our-ensure-python : Install the right Python version (rpm)] 2026-01-15 00:19:53.574132 | controller | ok: Nothing to do 2026-01-15 00:19:53.589064 | 2026-01-15 00:19:53.589337 | TASK [our-ensure-python : Also install python3-devel] 2026-01-15 00:20:40.250617 | controller | changed 2026-01-15 00:20:40.281618 | 2026-01-15 00:20:40.281801 | TASK [Run ensure-virtualenv role] 2026-01-15 00:20:40.308247 | controller | ok 2026-01-15 00:20:40.341893 | 2026-01-15 00:20:40.342118 | TASK [ensure-virtualenv : Check if virtualenv is installed] 2026-01-15 00:20:40.571448 | controller | /usr/bin/virtualenv 2026-01-15 00:20:40.875931 | controller | ok: Runtime: 0:00:00.003470 2026-01-15 00:20:40.883418 | 2026-01-15 00:20:40.883573 | LOOP [ensure-virtualenv : Install virtualenv package] 2026-01-15 00:20:40.922302 | controller | skipping: Conditional result was False 2026-01-15 00:20:40.922671 | controller | changed: All items complete 2026-01-15 00:20:40.922713 | 2026-01-15 00:20:40.943887 | 2026-01-15 00:20:40.944061 | TASK [Find the full path of the Python interpreter] 2026-01-15 00:20:41.157199 | controller | /usr/bin/python3.9 2026-01-15 00:20:41.525909 | controller | ok 2026-01-15 00:20:41.539738 | 2026-01-15 00:20:41.539946 | TASK [Create virtualenv for ansible-test with a specific interpreter] 2026-01-15 00:20:43.070494 | controller | created virtual environment CPython3.9.18.final.0-64 in 732ms 2026-01-15 00:20:43.087297 | controller | creator CPython3Posix(dest=/home/zuul/venv, clear=False, no_vcs_ignore=False, global=False) 2026-01-15 00:20:43.087313 | 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-15 00:20:43.087330 | controller | added seed packages: pip==22.2.2, setuptools==62.6.0, wheel==0.37.1 2026-01-15 00:20:43.087342 | controller | activators BashActivator,CShellActivator,FishActivator,NushellActivator,PowerShellActivator,PythonActivator 2026-01-15 00:20:43.136195 | controller | changed 2026-01-15 00:20:43.146462 | 2026-01-15 00:20:43.146696 | TASK [Set selinux package] 2026-01-15 00:20:43.176050 | controller | ok 2026-01-15 00:20:43.189473 | 2026-01-15 00:20:43.189679 | TASK [Set selinux package (Fedora)] 2026-01-15 00:20:43.234907 | controller | ok 2026-01-15 00:20:43.241160 | 2026-01-15 00:20:43.241326 | TASK [Install selinux into virtualenv] 2026-01-15 00:20:59.593273 | controller | Collecting selinux-please-lie-to-me 2026-01-15 00:21:11.969137 | controller | Downloading selinux_please_lie_to_me-1.0.1-py2.py3-none-any.whl (3.8 kB) 2026-01-15 00:21:12.323147 | controller | Collecting setuptools<50.0.0 2026-01-15 00:21:12.332026 | controller | Downloading setuptools-49.6.0-py3-none-any.whl (803 kB) 2026-01-15 00:21:12.379254 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 803.3/803.3 kB 18.6 MB/s eta 0:00:00 2026-01-15 00:21:12.459669 | controller | Installing collected packages: setuptools, selinux-please-lie-to-me 2026-01-15 00:21:12.459883 | controller | Attempting uninstall: setuptools 2026-01-15 00:21:12.460588 | controller | Found existing installation: setuptools 62.6.0 2026-01-15 00:21:12.534506 | controller | Uninstalling setuptools-62.6.0: 2026-01-15 00:21:12.543064 | controller | Successfully uninstalled setuptools-62.6.0 2026-01-15 00:21:12.880804 | controller | Successfully installed selinux-please-lie-to-me-1.0.1 setuptools-49.6.0 2026-01-15 00:21:24.346103 | controller | 2026-01-15 00:21:24.516125 | controller | [notice] A new release of pip available: 22.2.2 -> 25.3 2026-01-15 00:21:24.516201 | controller | [notice] To update, run: /home/zuul/venv/bin/python -m pip install --upgrade pip 2026-01-15 00:21:24.799991 | controller | ok: Runtime: 0:00:40.749724 2026-01-15 00:21:24.807337 | 2026-01-15 00:21:24.807434 | TASK [Install pytest-forked into virtualenv] 2026-01-15 00:21:36.557115 | controller | Collecting pytest-forked 2026-01-15 00:21:48.803983 | controller | Downloading pytest_forked-1.6.0-py3-none-any.whl (4.9 kB) 2026-01-15 00:21:48.855516 | controller | Collecting py 2026-01-15 00:21:48.860892 | controller | Downloading py-1.11.0-py2.py3-none-any.whl (98 kB) 2026-01-15 00:21:48.885434 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 98.7/98.7 kB 4.7 MB/s eta 0:00:00 2026-01-15 00:21:49.012826 | controller | Collecting pytest>=3.10 2026-01-15 00:21:49.016791 | controller | Downloading pytest-8.4.2-py3-none-any.whl (365 kB) 2026-01-15 00:21:49.037274 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 365.8/365.8 kB 21.7 MB/s eta 0:00:00 2026-01-15 00:21:49.091580 | controller | Collecting pluggy<2,>=1.5 2026-01-15 00:21:49.096471 | controller | Downloading pluggy-1.6.0-py3-none-any.whl (20 kB) 2026-01-15 00:21:49.149921 | controller | Collecting packaging>=20 2026-01-15 00:21:49.153483 | controller | Downloading packaging-25.0-py3-none-any.whl (66 kB) 2026-01-15 00:21:49.162472 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 66.5/66.5 kB 10.0 MB/s eta 0:00:00 2026-01-15 00:21:49.210715 | controller | Collecting pygments>=2.7.2 2026-01-15 00:21:49.214496 | controller | Downloading pygments-2.19.2-py3-none-any.whl (1.2 MB) 2026-01-15 00:21:49.242469 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 1.2/1.2 MB 47.3 MB/s eta 0:00:00 2026-01-15 00:21:49.276851 | controller | Collecting exceptiongroup>=1 2026-01-15 00:21:49.280253 | controller | Downloading exceptiongroup-1.3.1-py3-none-any.whl (16 kB) 2026-01-15 00:21:49.333580 | controller | Collecting tomli>=1 2026-01-15 00:21:49.337914 | controller | Downloading tomli-2.4.0-py3-none-any.whl (14 kB) 2026-01-15 00:21:49.360391 | controller | Collecting iniconfig>=1 2026-01-15 00:21:49.364303 | controller | Downloading iniconfig-2.1.0-py3-none-any.whl (6.0 kB) 2026-01-15 00:21:49.413516 | controller | Collecting typing-extensions>=4.6.0 2026-01-15 00:21:49.417429 | controller | Downloading typing_extensions-4.15.0-py3-none-any.whl (44 kB) 2026-01-15 00:21:49.426444 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 44.6/44.6 kB 5.2 MB/s eta 0:00:00 2026-01-15 00:21:49.492875 | controller | Installing collected packages: typing-extensions, tomli, pygments, py, pluggy, packaging, iniconfig, exceptiongroup, pytest, pytest-forked 2026-01-15 00:21:50.594516 | 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-15 00:21:50.597628 | controller | 2026-01-15 00:21:50.672844 | controller | [notice] A new release of pip available: 22.2.2 -> 25.3 2026-01-15 00:21:50.672891 | controller | [notice] To update, run: /home/zuul/venv/bin/python -m pip install --upgrade pip 2026-01-15 00:21:50.859187 | controller | ok: Runtime: 0:00:25.663273 2026-01-15 00:21:50.865417 | 2026-01-15 00:21:50.865527 | TASK [Update pip] 2026-01-15 00:21:51.439744 | controller | Requirement already satisfied: pip in ./venv/lib/python3.9/site-packages (22.2.2) 2026-01-15 00:22:01.831689 | controller | Collecting pip 2026-01-15 00:22:12.991845 | controller | Downloading pip-25.3-py3-none-any.whl (1.8 MB) 2026-01-15 00:22:13.048925 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 1.8/1.8 MB 34.2 MB/s eta 0:00:00 2026-01-15 00:22:13.107075 | controller | Installing collected packages: pip 2026-01-15 00:22:13.107179 | controller | Attempting uninstall: pip 2026-01-15 00:22:13.107787 | controller | Found existing installation: pip 22.2.2 2026-01-15 00:22:13.274476 | controller | Uninstalling pip-22.2.2: 2026-01-15 00:22:13.291333 | controller | Successfully uninstalled pip-22.2.2 2026-01-15 00:22:14.146716 | controller | Successfully installed pip-25.3 2026-01-15 00:22:14.452377 | controller | ok: Runtime: 0:00:23.109854 2026-01-15 00:22:14.462759 | 2026-01-15 00:22:14.462950 | TASK [Ensure pip temp directory exists in /var/tmp] 2026-01-15 00:22:14.704128 | controller | changed 2026-01-15 00:22:14.717105 | 2026-01-15 00:22:14.717296 | TASK [Install ansible into virtualenv] 2026-01-15 00:22:15.214565 | controller | Processing ./src/github.com/ansible/ansible 2026-01-15 00:22:15.218769 | controller | Installing build dependencies: started 2026-01-15 00:22:37.885924 | controller | Installing build dependencies: finished with status 'done' 2026-01-15 00:22:37.886745 | controller | Getting requirements to build wheel: started 2026-01-15 00:22:38.503405 | controller | Getting requirements to build wheel: finished with status 'done' 2026-01-15 00:22:38.504706 | controller | Preparing metadata (pyproject.toml): started 2026-01-15 00:22:38.973395 | controller | Preparing metadata (pyproject.toml): finished with status 'done' 2026-01-15 00:22:49.898527 | controller | Collecting jinja2 (from ansible-core==2.11.12.post0) 2026-01-15 00:23:03.237598 | controller | Downloading jinja2-3.1.6-py3-none-any.whl.metadata (2.9 kB) 2026-01-15 00:23:03.317361 | controller | Collecting PyYAML (from ansible-core==2.11.12.post0) 2026-01-15 00:23:03.323834 | 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-15 00:23:03.633026 | controller | Collecting cryptography (from ansible-core==2.11.12.post0) 2026-01-15 00:23:03.642486 | controller | Downloading cryptography-46.0.3-cp38-abi3-manylinux_2_34_x86_64.whl.metadata (5.7 kB) 2026-01-15 00:23:03.664689 | controller | Requirement already satisfied: packaging in ./venv/lib/python3.9/site-packages (from ansible-core==2.11.12.post0) (25.0) 2026-01-15 00:23:03.683611 | controller | Collecting resolvelib<0.6.0,>=0.5.3 (from ansible-core==2.11.12.post0) 2026-01-15 00:23:03.689377 | controller | Downloading resolvelib-0.5.4-py2.py3-none-any.whl.metadata (3.7 kB) 2026-01-15 00:23:03.859982 | controller | Collecting cffi>=2.0.0 (from cryptography->ansible-core==2.11.12.post0) 2026-01-15 00:23:03.865013 | controller | Downloading cffi-2.0.0-cp39-cp39-manylinux2014_x86_64.manylinux_2_17_x86_64.whl.metadata (2.6 kB) 2026-01-15 00:23:03.872285 | 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-15 00:23:03.888334 | controller | Collecting pycparser (from cffi>=2.0.0->cryptography->ansible-core==2.11.12.post0) 2026-01-15 00:23:03.894386 | controller | Downloading pycparser-2.23-py3-none-any.whl.metadata (993 bytes) 2026-01-15 00:23:03.988867 | controller | Collecting MarkupSafe>=2.0 (from jinja2->ansible-core==2.11.12.post0) 2026-01-15 00:23:03.994628 | 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-15 00:23:04.010170 | controller | Downloading resolvelib-0.5.4-py2.py3-none-any.whl (12 kB) 2026-01-15 00:23:04.020217 | controller | Downloading cryptography-46.0.3-cp38-abi3-manylinux_2_34_x86_64.whl (4.5 MB) 2026-01-15 00:23:04.121490 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 4.5/4.5 MB 48.1 MB/s 0:00:00 2026-01-15 00:23:04.127327 | controller | Downloading cffi-2.0.0-cp39-cp39-manylinux2014_x86_64.manylinux_2_17_x86_64.whl (216 kB) 2026-01-15 00:23:04.141227 | controller | Downloading jinja2-3.1.6-py3-none-any.whl (134 kB) 2026-01-15 00:23:04.158921 | 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-15 00:23:04.169666 | controller | Downloading pycparser-2.23-py3-none-any.whl (118 kB) 2026-01-15 00:23:04.205442 | 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-15 00:23:04.223595 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 750.8/750.8 kB 35.3 MB/s 0:00:00 2026-01-15 00:23:04.237192 | controller | Building wheels for collected packages: ansible-core 2026-01-15 00:23:04.238028 | controller | Building wheel for ansible-core (pyproject.toml): started 2026-01-15 00:23:05.713506 | controller | Building wheel for ansible-core (pyproject.toml): finished with status 'done' 2026-01-15 00:23:05.716070 | controller | Created wheel for ansible-core: filename=ansible_core-2.11.12.post0-py3-none-any.whl size=1961250 sha256=dbfab2db8d731da880351bf17f8547001ee3ae7ebe9d809700e518f975e65c80 2026-01-15 00:23:05.716208 | controller | Stored in directory: /var/tmp/ansible-test-pip/pip-ephem-wheel-cache-b9i96ix5/wheels/0f/a0/93/ab85b739e1eb1e0467171316f13571c14efdf87e0d5d44a33e 2026-01-15 00:23:05.724863 | controller | Successfully built ansible-core 2026-01-15 00:23:05.760762 | controller | Installing collected packages: resolvelib, PyYAML, pycparser, MarkupSafe, jinja2, cffi, cryptography, ansible-core 2026-01-15 00:23:07.208366 | controller | 2026-01-15 00:23:07.212931 | 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-15 00:23:07.812494 | controller | ok: Runtime: 0:00:52.390979 2026-01-15 00:23:07.824708 | 2026-01-15 00:23:07.824790 | TASK [Setup download-artifact-fork role] 2026-01-15 00:23:07.855497 | controller | ok 2026-01-15 00:23:07.900117 | 2026-01-15 00:23:07.900292 | TASK [download-artifact-fork : Parse inventory response] 2026-01-15 00:23:07.948298 | controller | ok 2026-01-15 00:23:07.958295 | 2026-01-15 00:23:07.958386 | TASK [download-artifact-fork : Fetch info from Zuul API] 2026-01-15 00:23:07.994178 | controller | skipping: Conditional result was False 2026-01-15 00:23:08.003140 | 2026-01-15 00:23:08.003234 | TASK [download-artifact-fork : Parse build response] 2026-01-15 00:23:08.038792 | controller | skipping: Conditional result was False 2026-01-15 00:23:08.048034 | 2026-01-15 00:23:08.048162 | TASK [download-artifact-fork : Ensure artifacts directory exists] 2026-01-15 00:23:08.270782 | controller | changed 2026-01-15 00:23:08.284890 | 2026-01-15 00:23:08.285188 | LOOP [download-artifact-fork : Download archive by type] 2026-01-15 00:23:08.321882 | controller | skipping: Conditional result was False 2026-01-15 00:23:08.322566 | 2026-01-15 00:23:09.088676 | controller | changed: OK (20199 bytes) 2026-01-15 00:23:09.121791 | 2026-01-15 00:23:09.121993 | TASK [deploy-artifacts : define __collections] 2026-01-15 00:23:09.153411 | controller | ok 2026-01-15 00:23:09.165870 | 2026-01-15 00:23:09.166094 | LOOP [deploy-artifacts : Create list of collections] 2026-01-15 00:23:09.206091 | controller | skipping: Conditional result was False 2026-01-15 00:23:09.229743 | controller | ok: 2026-01-15 00:23:09.229981 | controller | { 2026-01-15 00:23:09.230057 | controller | "branch": "main", 2026-01-15 00:23:09.230105 | controller | "job": "build-ansible-collection", 2026-01-15 00:23:09.230138 | controller | "metadata": { 2026-01-15 00:23:09.230163 | controller | "type": "ansible_collection", 2026-01-15 00:23:09.230187 | controller | "version": "5.0.0" 2026-01-15 00:23:09.230210 | controller | }, 2026-01-15 00:23:09.230233 | controller | "name": "ansible.network", 2026-01-15 00:23:09.230256 | controller | "project": "ansible-collections/ansible.network", 2026-01-15 00:23:09.230279 | controller | "url": "https://c3203d1915423ac3d67f-e5c3e988860f980179bf060d6add0a12.ssl.cf5.rackcdn.com/ansible/8103cc31ad7d469bab68d7ee6a29b2f8/artifacts/ansible-network-5.0.0.tar.gz" 2026-01-15 00:23:09.230312 | controller | } 2026-01-15 00:23:09.245234 | 2026-01-15 00:23:09.245419 | TASK [deploy-artifacts : Install require-project collection using ansible-galaxy] 2026-01-15 00:23:09.864068 | controller | Starting galaxy collection install process 2026-01-15 00:23:09.864264 | controller | Process install dependency map 2026-01-15 00:24:05.813389 | controller | [WARNING]: Skipping Galaxy server https://galaxy.ansible.com/api/. Got an 2026-01-15 00:24:05.813883 | controller | unexpected error when getting available versions of collection 2026-01-15 00:24:05.813930 | controller | ansible.netcommon: '/api/v3/plugin/ansible/content/published/collections/index/ 2026-01-15 00:24:05.813943 | controller | ansible/netcommon/versions/' 2026-01-15 00:24:05.813961 | controller | ERROR! Unexpected Exception, this is probably a bug: '/api/v3/plugin/ansible/content/published/collections/index/ansible/netcommon/versions/' 2026-01-15 00:24:05.814048 | controller | to see the full traceback, use -vvv 2026-01-15 00:24:06.334648 | controller | ERROR 2026-01-15 00:24:06.335310 | controller | { 2026-01-15 00:24:06.335406 | controller | "delta": "0:00:56.385318", 2026-01-15 00:24:06.335456 | controller | "end": "2026-01-15 00:24:05.852625", 2026-01-15 00:24:06.335496 | controller | "msg": "non-zero return code", 2026-01-15 00:24:06.335533 | controller | "rc": 250, 2026-01-15 00:24:06.335569 | controller | "start": "2026-01-15 00:23:09.467307" 2026-01-15 00:24:06.335604 | controller | } failure 2026-01-15 00:24:06.340681 | 2026-01-15 00:24:06.340786 | PLAY RECAP 2026-01-15 00:24:06.340879 | controller | ok: 44 changed: 24 unreachable: 0 failed: 1 skipped: 17 rescued: 0 ignored: 0 2026-01-15 00:24:06.340925 | 2026-01-15 00:24:06.521206 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master] 2026-01-15 00:24:06.522181 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2026-01-15 00:24:07.133664 | 2026-01-15 00:24:07.133806 | PLAY [all] 2026-01-15 00:24:07.157456 | 2026-01-15 00:24:07.157587 | TASK [fetch-subunit-output : Find stestr or testr executable] 2026-01-15 00:24:07.610743 | controller | changed: non-zero return code 2026-01-15 00:24:07.619075 | 2026-01-15 00:24:07.619175 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2026-01-15 00:24:07.644060 | controller | skipping: Conditional result was False 2026-01-15 00:24:07.652987 | 2026-01-15 00:24:07.653108 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2026-01-15 00:24:07.688354 | 2026-01-15 00:24:07.688571 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2026-01-15 00:24:07.728529 | 2026-01-15 00:24:07.728823 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2026-01-15 00:24:07.756641 | controller | skipping: Conditional result was False 2026-01-15 00:24:07.771696 | 2026-01-15 00:24:07.771881 | LOOP [fetch-subunit-output : Generate subunit file] 2026-01-15 00:24:07.815991 | 2026-01-15 00:24:07.816239 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2026-01-15 00:24:07.844994 | controller | skipping: Conditional result was False 2026-01-15 00:24:07.851563 | 2026-01-15 00:24:07.851629 | TASK [fetch-subunit-output : Remove the temporary file] 2026-01-15 00:24:07.864965 | controller | skipping: Conditional result was False 2026-01-15 00:24:07.872456 | 2026-01-15 00:24:07.872585 | TASK [fetch-subunit-output : Process and fetch subunit results] 2026-01-15 00:24:07.898441 | controller | skipping: Conditional result was False 2026-01-15 00:24:07.928496 | 2026-01-15 00:24:07.928616 | PLAY RECAP 2026-01-15 00:24:07.928662 | controller | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2026-01-15 00:24:07.928689 | 2026-01-15 00:24:08.059213 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2026-01-15 00:24:08.063997 | POST-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/post.yaml@master] 2026-01-15 00:24:08.722981 | 2026-01-15 00:24:08.723126 | PLAY [all:!appliance*] 2026-01-15 00:24:08.759896 | 2026-01-15 00:24:08.760142 | TASK [unregister the node] 2026-01-15 00:24:09.306780 | controller | skipping: Conditional result was False 2026-01-15 00:24:09.314431 | 2026-01-15 00:24:09.314583 | TASK [include_role : fetch-output] 2026-01-15 00:24:09.346567 | controller | ok 2026-01-15 00:24:09.371207 | 2026-01-15 00:24:09.371358 | TASK [fetch-output : Set log path for multiple nodes] 2026-01-15 00:24:09.416764 | controller | skipping: Conditional result was False 2026-01-15 00:24:09.424249 | 2026-01-15 00:24:09.424378 | TASK [fetch-output : Set log path for single node] 2026-01-15 00:24:09.465725 | controller | ok 2026-01-15 00:24:09.472853 | 2026-01-15 00:24:09.472944 | LOOP [fetch-output : Ensure local output dirs] 2026-01-15 00:24:09.915157 | controller -> localhost | ok: "/var/lib/zuul/builds/a9ac3b96db594bc0a84e613a53f568e0/work/logs" 2026-01-15 00:24:10.181627 | controller -> localhost | changed: "/var/lib/zuul/builds/a9ac3b96db594bc0a84e613a53f568e0/work/artifacts" 2026-01-15 00:24:10.509523 | controller -> localhost | changed: "/var/lib/zuul/builds/a9ac3b96db594bc0a84e613a53f568e0/work/docs" 2026-01-15 00:24:10.523867 | 2026-01-15 00:24:10.523998 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-01-15 00:24:11.391011 | controller | changed: 2026-01-15 00:24:11.391657 | controller | .d..t...... ./ 2026-01-15 00:24:11.391731 | controller | cd+++++++++ controller/ 2026-01-15 00:24:11.391807 | controller | changed: All items complete 2026-01-15 00:24:11.391884 | 2026-01-15 00:24:12.017282 | controller | changed: .d..t...... ./ 2026-01-15 00:24:12.559757 | controller | changed: .d..t...... ./ 2026-01-15 00:24:12.596850 | 2026-01-15 00:24:12.597002 | TASK [include_role : fetch-output-openshift] 2026-01-15 00:24:12.622274 | controller | skipping: Conditional result was False 2026-01-15 00:24:12.632599 | 2026-01-15 00:24:12.632722 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-01-15 00:24:12.669280 | controller | skipping: Conditional result was False 2026-01-15 00:24:12.681753 | controller | skipping: Conditional result was False 2026-01-15 00:24:12.746401 | 2026-01-15 00:24:12.746525 | PLAY [localhost] 2026-01-15 00:24:12.766386 | 2026-01-15 00:24:12.766504 | TASK [Run Zuul manifest role] 2026-01-15 00:24:12.788720 | localhost | ok 2026-01-15 00:24:12.807640 | 2026-01-15 00:24:12.807787 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-01-15 00:24:13.205458 | localhost | changed 2026-01-15 00:24:13.210680 | 2026-01-15 00:24:13.210748 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-01-15 00:24:13.262820 | localhost | ok 2026-01-15 00:24:13.271411 | 2026-01-15 00:24:13.271947 | TASK [Set zuul-log-path fact] 2026-01-15 00:24:13.291713 | localhost | ok 2026-01-15 00:24:13.308541 | 2026-01-15 00:24:13.308658 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-01-15 00:24:13.347387 | localhost | ok 2026-01-15 00:24:13.356388 | 2026-01-15 00:24:13.356484 | LOOP [Run upload-logs-swift role] 2026-01-15 00:24:13.402977 | localhost | Output suppressed because no_log was given 2026-01-15 00:24:13.433199 | 2026-01-15 00:24:13.433355 | TASK [Set zuul-log-path fact] 2026-01-15 00:24:13.458437 | localhost | skipping: Conditional result was False 2026-01-15 00:24:13.465390 | 2026-01-15 00:24:13.465479 | TASK [upload-logs-swift : Ensure logs are readable before uploading] 2026-01-15 00:24:13.951640 | localhost -> localhost | ok: Runtime: 0:00:00.007518 2026-01-15 00:24:13.957138 | 2026-01-15 00:24:13.957209 | TASK [upload-logs-swift : Upload logs to swift]