2025-10-22 00:12:11.255446 | Job console starting... 2025-10-22 00:12:11.265169 | Updating repositories 2025-10-22 00:12:11.396073 | Preparing job workspace 2025-10-22 00:12:15.082478 | Running Ansible setup... 2025-10-22 00:12:21.071053 | PRE-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master] 2025-10-22 00:12:21.709066 | 2025-10-22 00:12:21.709264 | PLAY [localhost] 2025-10-22 00:12:21.717625 | 2025-10-22 00:12:21.717717 | TASK [Gathering Facts] 2025-10-22 00:12:22.776069 | localhost | ok 2025-10-22 00:12:22.799549 | 2025-10-22 00:12:22.799831 | TASK [Setup log path fact] 2025-10-22 00:12:22.820420 | localhost | ok 2025-10-22 00:12:22.833600 | 2025-10-22 00:12:22.833711 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-10-22 00:12:22.875652 | localhost | ok 2025-10-22 00:12:22.883798 | 2025-10-22 00:12:22.883879 | TASK [emit-job-header : Print job information] 2025-10-22 00:12:22.923260 | # Job Information 2025-10-22 00:12:22.923405 | Ansible Version: 2.15.12 2025-10-22 00:12:22.923437 | Job: ansible-test-sanity-docker-stable-2.11 2025-10-22 00:12:22.923458 | Pipeline: periodic 2025-10-22 00:12:22.923476 | Executor: ze02.softwarefactory-project.io 2025-10-22 00:12:22.923494 | Triggered by: https://github.com/ansible-collections/ansible.network 2025-10-22 00:12:22.923516 | Log URL (when completed): https://ansible.softwarefactory-project.io/logs/0e0/ansible/0e0a0cdde7014b3f90440e3291eaa212/ 2025-10-22 00:12:22.923535 | Event ID: 29cce05ecf284232acebc18c8d35e894 2025-10-22 00:12:22.927382 | 2025-10-22 00:12:22.927449 | LOOP [emit-job-header : Print node information] 2025-10-22 00:12:23.047902 | localhost | ok: 2025-10-22 00:12:23.048182 | localhost | # Node Information 2025-10-22 00:12:23.048240 | localhost | Inventory Hostname: controller 2025-10-22 00:12:23.048281 | localhost | Hostname: ip-172-16-26-57 2025-10-22 00:12:23.048321 | localhost | Username: zuul-worker 2025-10-22 00:12:23.048365 | localhost | Distro: Fedora 37 2025-10-22 00:12:23.048403 | localhost | Provider: ansible-us-east-2 2025-10-22 00:12:23.048439 | localhost | Region: us-east-2 2025-10-22 00:12:23.048474 | localhost | Label: ansible-fedora-37-1vcpu 2025-10-22 00:12:23.048510 | localhost | Product Name: t3.small 2025-10-22 00:12:23.048545 | localhost | Interface IP: 3.142.248.175 2025-10-22 00:12:23.064923 | 2025-10-22 00:12:23.065115 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-10-22 00:12:23.527799 | localhost -> localhost | changed 2025-10-22 00:12:23.533699 | 2025-10-22 00:12:23.533768 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-10-22 00:12:24.364692 | localhost -> localhost | changed 2025-10-22 00:12:24.384859 | 2025-10-22 00:12:24.384928 | PLAY [all:!appliance*] 2025-10-22 00:12:24.400409 | 2025-10-22 00:12:24.400476 | TASK [include_role : start-zuul-console] 2025-10-22 00:12:24.421105 | controller | ok 2025-10-22 00:12:24.434799 | 2025-10-22 00:12:24.434864 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-10-22 00:12:25.108311 | controller | ok 2025-10-22 00:12:25.120532 | 2025-10-22 00:12:25.120824 | TASK [use-our-mirror : Retrieve the IP address] 2025-10-22 00:12:26.627931 | controller | ok: Status code was 404 and not [200]: HTTP Error 404: Not Found 2025-10-22 00:12:26.634502 | 2025-10-22 00:12:26.634567 | TASK [use-our-mirror : Ensure the registry service is running on the mirror] 2025-10-22 00:12:26.770800 | controller | skipping: Conditional result was False 2025-10-22 00:12:26.785239 | 2025-10-22 00:12:26.785400 | TASK [use-our-mirror : Write the IP in /etc/hosts] 2025-10-22 00:12:26.825561 | controller | skipping: Conditional result was False 2025-10-22 00:12:26.841638 | 2025-10-22 00:12:26.841840 | TASK [use-our-mirror : Create the podman configuration directory] 2025-10-22 00:12:26.870086 | controller | skipping: Conditional result was False 2025-10-22 00:12:26.885727 | 2025-10-22 00:12:26.885949 | TASK [use-our-mirror : Copy the podman configuration] 2025-10-22 00:12:26.924997 | controller | skipping: Conditional result was False 2025-10-22 00:12:26.934231 | 2025-10-22 00:12:26.934443 | TASK [use-our-mirror : Copy the Yum configuration (main)] 2025-10-22 00:12:26.961362 | controller | skipping: Conditional result was False 2025-10-22 00:12:26.971270 | 2025-10-22 00:12:26.971412 | TASK [use-our-mirror : Copy the Yum configuration (update)] 2025-10-22 00:12:26.998712 | controller | skipping: Conditional result was False 2025-10-22 00:12:27.013380 | 2025-10-22 00:12:27.013528 | TASK [Disable Fedora Modular] 2025-10-22 00:12:27.744142 | controller | changed 2025-10-22 00:12:27.752172 | 2025-10-22 00:12:27.752257 | TASK [Enable EPEL] 2025-10-22 00:12:27.790179 | controller | skipping: Conditional result was False 2025-10-22 00:12:27.803318 | 2025-10-22 00:12:27.803477 | TASK [Register the RHEL node] 2025-10-22 00:12:27.981222 | 2025-10-22 00:12:27.981588 | TASK [Show the subscription-manager status] 2025-10-22 00:12:28.145940 | controller | skipping: Conditional result was False 2025-10-22 00:12:28.213003 | 2025-10-22 00:12:28.213186 | TASK [Enable EPEL on RHEL] 2025-10-22 00:12:28.362836 | controller | skipping: Conditional result was False 2025-10-22 00:12:28.378594 | 2025-10-22 00:12:28.378784 | TASK [Install git and tox] 2025-10-22 00:13:55.178048 | controller | changed 2025-10-22 00:13:55.196734 | 2025-10-22 00:13:55.196928 | TASK [include_role : prepare-workspace] 2025-10-22 00:13:55.234554 | controller | ok 2025-10-22 00:13:55.268354 | 2025-10-22 00:13:55.268528 | TASK [prepare-workspace : Start zuul_console daemon.] 2025-10-22 00:13:55.773617 | controller | ok 2025-10-22 00:13:55.789095 | 2025-10-22 00:13:55.789237 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2025-10-22 00:14:09.051343 | controller | Output suppressed because no_log was given 2025-10-22 00:14:09.072300 | 2025-10-22 00:14:09.072400 | TASK [include_role : prepare-workspace-openshift] 2025-10-22 00:14:09.102068 | controller | skipping: Conditional result was False 2025-10-22 00:14:09.143740 | 2025-10-22 00:14:09.143837 | PLAY [all:!appliance] 2025-10-22 00:14:09.166290 | 2025-10-22 00:14:09.166383 | TASK [Run add-build-sshkey role (RSA)] 2025-10-22 00:14:09.192044 | controller | ok 2025-10-22 00:14:09.213523 | 2025-10-22 00:14:09.213629 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-10-22 00:14:09.493695 | controller -> localhost | ok 2025-10-22 00:14:09.534051 | 2025-10-22 00:14:09.534203 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-10-22 00:14:09.575737 | controller | ok 2025-10-22 00:14:09.591475 | controller | included: /var/lib/zuul/builds/0e0a0cdde7014b3f90440e3291eaa212/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-10-22 00:14:09.597970 | 2025-10-22 00:14:09.598037 | TASK [add-build-sshkey : Create Temp SSH key] 2025-10-22 00:14:10.100805 | controller -> localhost | Generating public/private rsa key pair. 2025-10-22 00:14:10.101065 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/0e0a0cdde7014b3f90440e3291eaa212/work/0e0a0cdde7014b3f90440e3291eaa212_id_rsa. 2025-10-22 00:14:10.101094 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/0e0a0cdde7014b3f90440e3291eaa212/work/0e0a0cdde7014b3f90440e3291eaa212_id_rsa.pub. 2025-10-22 00:14:10.101115 | controller -> localhost | The key fingerprint is: 2025-10-22 00:14:10.101134 | controller -> localhost | SHA256:wBJMOL8h6o1AAXFeXJXXGpSkp+IWraFmw8o1Rg9x1Eg zuul-build-sshkey 2025-10-22 00:14:10.101152 | controller -> localhost | The key's randomart image is: 2025-10-22 00:14:10.101170 | controller -> localhost | +---[RSA 2048]----+ 2025-10-22 00:14:10.101187 | controller -> localhost | |+..=+oE+.+oo | 2025-10-22 00:14:10.101206 | controller -> localhost | | +o.o+. o.+ . | 2025-10-22 00:14:10.101223 | controller -> localhost | | ooo + ...o | 2025-10-22 00:14:10.101240 | controller -> localhost | | .. o+ o o. | 2025-10-22 00:14:10.101256 | controller -> localhost | |.. .oo+ S | 2025-10-22 00:14:10.101284 | controller -> localhost | |o o.= = | 2025-10-22 00:14:10.101306 | controller -> localhost | |o o X = | 2025-10-22 00:14:10.101326 | controller -> localhost | | + B + | 2025-10-22 00:14:10.101345 | controller -> localhost | | o | 2025-10-22 00:14:10.101366 | controller -> localhost | +----[SHA256]-----+ 2025-10-22 00:14:10.101416 | controller -> localhost | ok: Runtime: 0:00:00.061509 2025-10-22 00:14:10.108320 | 2025-10-22 00:14:10.108387 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-10-22 00:14:10.155989 | controller | ok 2025-10-22 00:14:10.166323 | controller | included: /var/lib/zuul/builds/0e0a0cdde7014b3f90440e3291eaa212/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-10-22 00:14:10.175087 | 2025-10-22 00:14:10.175154 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-10-22 00:14:10.200826 | controller | skipping: Conditional result was False 2025-10-22 00:14:10.210405 | 2025-10-22 00:14:10.210488 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-10-22 00:14:10.847988 | controller | changed 2025-10-22 00:14:10.861078 | 2025-10-22 00:14:10.861305 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-10-22 00:14:11.211412 | controller | ok 2025-10-22 00:14:11.226369 | 2025-10-22 00:14:11.226532 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-10-22 00:14:12.576209 | controller | changed 2025-10-22 00:14:12.590693 | 2025-10-22 00:14:12.590851 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-10-22 00:14:13.907840 | controller | changed 2025-10-22 00:14:13.921165 | 2025-10-22 00:14:13.921304 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-10-22 00:14:13.948829 | controller | skipping: Conditional result was False 2025-10-22 00:14:13.964795 | 2025-10-22 00:14:13.964970 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-10-22 00:14:14.382554 | controller -> localhost | changed 2025-10-22 00:14:14.401202 | 2025-10-22 00:14:14.401368 | TASK [add-build-sshkey : Add back temp key] 2025-10-22 00:14:14.655838 | controller -> localhost | Identity added: /var/lib/zuul/builds/0e0a0cdde7014b3f90440e3291eaa212/work/0e0a0cdde7014b3f90440e3291eaa212_id_rsa (zuul-build-sshkey) 2025-10-22 00:14:14.656251 | controller -> localhost | ok: Runtime: 0:00:00.007409 2025-10-22 00:14:14.670746 | 2025-10-22 00:14:14.670897 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-10-22 00:14:15.122090 | controller | ok 2025-10-22 00:14:15.137003 | 2025-10-22 00:14:15.137196 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-10-22 00:14:15.177001 | controller | skipping: Conditional result was False 2025-10-22 00:14:15.205900 | 2025-10-22 00:14:15.206318 | TASK [Run add-build-sshkey role (ECDSA)] 2025-10-22 00:14:15.243939 | controller | ok 2025-10-22 00:14:15.267401 | 2025-10-22 00:14:15.267535 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-10-22 00:14:15.589293 | controller -> localhost | ok 2025-10-22 00:14:15.596464 | 2025-10-22 00:14:15.596542 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-10-22 00:14:15.627374 | controller | ok 2025-10-22 00:14:15.641044 | controller | included: /var/lib/zuul/builds/0e0a0cdde7014b3f90440e3291eaa212/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-10-22 00:14:15.648742 | 2025-10-22 00:14:15.648816 | TASK [add-build-sshkey : Create Temp SSH key] 2025-10-22 00:14:15.937293 | controller -> localhost | Generating public/private ecdsa key pair. 2025-10-22 00:14:15.937492 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/0e0a0cdde7014b3f90440e3291eaa212/work/0e0a0cdde7014b3f90440e3291eaa212_id_ecdsa. 2025-10-22 00:14:15.937520 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/0e0a0cdde7014b3f90440e3291eaa212/work/0e0a0cdde7014b3f90440e3291eaa212_id_ecdsa.pub. 2025-10-22 00:14:15.937550 | controller -> localhost | The key fingerprint is: 2025-10-22 00:14:15.937570 | controller -> localhost | SHA256:UUXlJkTUzT70MxU/4ZTGEMunxtDEg7yvRL0W0CtZHyU zuul-build-sshkey 2025-10-22 00:14:15.937589 | controller -> localhost | The key's randomart image is: 2025-10-22 00:14:15.937607 | controller -> localhost | +---[ECDSA 521]---+ 2025-10-22 00:14:15.937624 | controller -> localhost | | o=X*E*=| 2025-10-22 00:14:15.937641 | controller -> localhost | | . =+*=B*| 2025-10-22 00:14:15.937659 | controller -> localhost | | . .B+O==| 2025-10-22 00:14:15.937718 | controller -> localhost | | . =o*o=+| 2025-10-22 00:14:15.937737 | controller -> localhost | | S . o+o +| 2025-10-22 00:14:15.937753 | controller -> localhost | | ..+ | 2025-10-22 00:14:15.937770 | controller -> localhost | | . o | 2025-10-22 00:14:15.937787 | controller -> localhost | | . | 2025-10-22 00:14:15.937804 | controller -> localhost | | | 2025-10-22 00:14:15.937845 | controller -> localhost | +----[SHA256]-----+ 2025-10-22 00:14:15.937905 | controller -> localhost | ok: Runtime: 0:00:00.008999 2025-10-22 00:14:15.944984 | 2025-10-22 00:14:15.945051 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-10-22 00:14:15.975817 | controller | ok 2025-10-22 00:14:15.984723 | controller | included: /var/lib/zuul/builds/0e0a0cdde7014b3f90440e3291eaa212/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-10-22 00:14:15.994527 | 2025-10-22 00:14:15.994604 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-10-22 00:14:16.019202 | controller | skipping: Conditional result was False 2025-10-22 00:14:16.026725 | 2025-10-22 00:14:16.026802 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-10-22 00:14:16.495725 | controller | changed 2025-10-22 00:14:16.508973 | 2025-10-22 00:14:16.509120 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-10-22 00:14:16.858911 | controller | ok 2025-10-22 00:14:16.874181 | 2025-10-22 00:14:16.874322 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-10-22 00:14:18.168563 | controller | changed 2025-10-22 00:14:18.177481 | 2025-10-22 00:14:18.177573 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-10-22 00:14:19.555751 | controller | changed 2025-10-22 00:14:19.564587 | 2025-10-22 00:14:19.564701 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-10-22 00:14:19.602526 | controller | skipping: Conditional result was False 2025-10-22 00:14:19.619646 | 2025-10-22 00:14:19.619896 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-10-22 00:14:19.925442 | controller -> localhost | changed 2025-10-22 00:14:19.951916 | 2025-10-22 00:14:19.952105 | TASK [add-build-sshkey : Add back temp key] 2025-10-22 00:14:20.264434 | controller -> localhost | Identity added: /var/lib/zuul/builds/0e0a0cdde7014b3f90440e3291eaa212/work/0e0a0cdde7014b3f90440e3291eaa212_id_ecdsa (zuul-build-sshkey) 2025-10-22 00:14:20.264892 | controller -> localhost | ok: Runtime: 0:00:00.013733 2025-10-22 00:14:20.279277 | 2025-10-22 00:14:20.279456 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-10-22 00:14:20.641541 | controller | ok 2025-10-22 00:14:20.649452 | 2025-10-22 00:14:20.649546 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-10-22 00:14:20.675546 | controller | skipping: Conditional result was False 2025-10-22 00:14:20.693427 | 2025-10-22 00:14:20.693551 | TASK [include_role : remove-zuul-sshkey] 2025-10-22 00:14:20.710359 | controller | skipping: Conditional result was False 2025-10-22 00:14:20.726562 | 2025-10-22 00:14:20.726771 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2025-10-22 00:14:21.075484 | controller | ok: "logs" 2025-10-22 00:14:21.076205 | controller | ok: All items complete 2025-10-22 00:14:21.076280 | 2025-10-22 00:14:21.366731 | controller | ok: "artifacts" 2025-10-22 00:14:21.658557 | controller | ok: "docs" 2025-10-22 00:14:21.726566 | 2025-10-22 00:14:21.726798 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2025-10-22 00:14:22.074838 | controller | changed: "logs" 2025-10-22 00:14:22.375657 | controller | changed: "artifacts" 2025-10-22 00:14:22.660873 | controller | changed: "docs" 2025-10-22 00:14:22.709559 | 2025-10-22 00:14:22.709758 | PLAY RECAP 2025-10-22 00:14:22.709826 | controller | ok: 30 changed: 16 unreachable: 0 failed: 0 skipped: 18 rescued: 0 ignored: 0 2025-10-22 00:14:22.709862 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-10-22 00:14:22.709887 | 2025-10-22 00:14:22.847517 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master] 2025-10-22 00:14:22.848569 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2025-10-22 00:14:23.443774 | 2025-10-22 00:14:23.443926 | PLAY [all] 2025-10-22 00:14:23.466035 | 2025-10-22 00:14:23.466130 | TASK [Install binary dependencies] 2025-10-22 00:14:23.530322 | controller | ok 2025-10-22 00:14:23.558723 | 2025-10-22 00:14:23.558846 | TASK [bindep : Include find tasks] 2025-10-22 00:14:23.603332 | controller | ok 2025-10-22 00:14:23.616351 | controller | included: /var/lib/zuul/builds/0e0a0cdde7014b3f90440e3291eaa212/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find.yaml 2025-10-22 00:14:23.626232 | 2025-10-22 00:14:23.626329 | TASK [bindep : Look for bindep.txt] 2025-10-22 00:14:24.167639 | controller | ok 2025-10-22 00:14:24.174810 | 2025-10-22 00:14:24.174891 | TASK [bindep : Define bindep_file fact] 2025-10-22 00:14:24.191753 | controller | skipping: Conditional result was False 2025-10-22 00:14:24.198892 | 2025-10-22 00:14:24.198984 | TASK [bindep : Look for other-requirements.txt] 2025-10-22 00:14:24.501774 | controller | ok 2025-10-22 00:14:24.514269 | 2025-10-22 00:14:24.514430 | TASK [bindep : Define bindep_file fact] 2025-10-22 00:14:24.544206 | controller | skipping: Conditional result was False 2025-10-22 00:14:24.558799 | 2025-10-22 00:14:24.559002 | TASK [bindep : Look for bindep fallback file] 2025-10-22 00:14:24.589218 | controller | skipping: Conditional result was False 2025-10-22 00:14:24.603704 | 2025-10-22 00:14:24.603890 | TASK [bindep : Define bindep_file fact] 2025-10-22 00:14:24.633705 | controller | skipping: Conditional result was False 2025-10-22 00:14:24.647049 | 2025-10-22 00:14:24.647205 | TASK [bindep : Include bindep tasks] 2025-10-22 00:14:24.676318 | controller | skipping: Conditional result was False 2025-10-22 00:14:24.694243 | 2025-10-22 00:14:24.694399 | TASK [bindep : Include install tasks] 2025-10-22 00:14:24.723473 | controller | skipping: Conditional result was False 2025-10-22 00:14:24.737275 | 2025-10-22 00:14:24.737421 | LOOP [bindep : Include package tasks] 2025-10-22 00:14:24.814504 | 2025-10-22 00:14:24.814966 | TASK [Run test-setup role] 2025-10-22 00:14:24.841213 | controller | ok 2025-10-22 00:14:24.868405 | 2025-10-22 00:14:24.868507 | TASK [test-setup : Check if project's tools/test-setup.sh exists] 2025-10-22 00:14:25.179891 | controller | ok 2025-10-22 00:14:25.188465 | 2025-10-22 00:14:25.188559 | TASK [test-setup : Run tools/test-setup.sh] 2025-10-22 00:14:25.342286 | controller | skipping: Conditional result was False 2025-10-22 00:14:25.395434 | 2025-10-22 00:14:25.395612 | PLAY RECAP 2025-10-22 00:14:25.395734 | controller | ok: 4 changed: 0 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2025-10-22 00:14:25.395962 | 2025-10-22 00:14:25.594629 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2025-10-22 00:14:25.595649 | PRE-RUN START: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master] 2025-10-22 00:14:26.234567 | 2025-10-22 00:14:26.234781 | PLAY [controller] 2025-10-22 00:14:26.256269 | 2025-10-22 00:14:26.256370 | TASK [Create the /root directory] 2025-10-22 00:14:27.013045 | controller | ok 2025-10-22 00:14:27.026010 | 2025-10-22 00:14:27.026173 | TASK [Install glibc-langpack-en] 2025-10-22 00:14:35.132713 | controller | ok: Nothing to do 2025-10-22 00:14:35.156862 | 2025-10-22 00:14:35.157362 | TASK [Ensure controller directory exists] 2025-10-22 00:14:35.574848 | controller | changed 2025-10-22 00:14:35.588841 | 2025-10-22 00:14:35.589114 | TASK [Install container runtime] 2025-10-22 00:14:35.674918 | controller | ok 2025-10-22 00:14:35.746606 | 2025-10-22 00:14:35.746819 | LOOP [ensure-docker : Gather variables for each operating system] 2025-10-22 00:14:35.800987 | controller | ok: "/var/lib/zuul/builds/0e0a0cdde7014b3f90440e3291eaa212/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/vars/fedora.yaml" 2025-10-22 00:14:35.819715 | 2025-10-22 00:14:35.819985 | TASK [ensure-docker : Sanity check for distro selection] 2025-10-22 00:14:35.850648 | controller | skipping: Conditional result was False 2025-10-22 00:14:35.865583 | 2025-10-22 00:14:35.865884 | TASK [ensure-docker : Sanity check for distro install] 2025-10-22 00:14:35.896924 | controller | skipping: Conditional result was False 2025-10-22 00:14:35.912469 | 2025-10-22 00:14:35.912790 | TASK [ensure-docker : Sanity check for upstream install] 2025-10-22 00:14:35.943470 | controller | skipping: Conditional result was False 2025-10-22 00:14:35.954903 | 2025-10-22 00:14:35.955113 | TASK [ensure-docker : Sanity check for upstream install mirrors] 2025-10-22 00:14:35.993917 | controller | skipping: Conditional result was False 2025-10-22 00:14:36.010585 | 2025-10-22 00:14:36.010876 | TASK [ensure-docker : Include tasks for docker install] 2025-10-22 00:14:36.049980 | controller | ok 2025-10-22 00:14:36.063891 | controller | included: /var/lib/zuul/builds/0e0a0cdde7014b3f90440e3291eaa212/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/tasks/docker-upstream.yaml 2025-10-22 00:14:36.078288 | 2025-10-22 00:14:36.078526 | TASK [ensure-docker : Not Implemented] 2025-10-22 00:14:36.105899 | controller | skipping: Conditional result was False 2025-10-22 00:14:36.116269 | 2025-10-22 00:14:36.116493 | TASK [Set up docker mirrors] 2025-10-22 00:14:36.145494 | controller | ok 2025-10-22 00:14:36.184113 | 2025-10-22 00:14:36.184322 | TASK [use-docker-mirror : Set docker_mirror fact from mirror_info] 2025-10-22 00:14:36.221935 | controller | skipping: Conditional result was False 2025-10-22 00:14:36.232473 | 2025-10-22 00:14:36.232689 | TASK [use-docker-mirror : Set docker_mirror fact from zuul_site_mirror_info] 2025-10-22 00:14:36.271619 | controller | skipping: Conditional result was False 2025-10-22 00:14:36.287207 | 2025-10-22 00:14:36.287477 | TASK [use-docker-mirror : Set docker_mirror fact] 2025-10-22 00:14:36.336658 | controller | skipping: Conditional result was False 2025-10-22 00:14:36.351373 | 2025-10-22 00:14:36.351602 | TASK [use-docker-mirror : Set up docker mirrors] 2025-10-22 00:14:36.381986 | controller | skipping: Conditional result was False 2025-10-22 00:14:36.407994 | 2025-10-22 00:14:36.408164 | TASK [Add all repositories] 2025-10-22 00:14:36.439307 | controller | ok 2025-10-22 00:14:36.520392 | 2025-10-22 00:14:36.520615 | LOOP [ensure-package-repositories : Install repositories] 2025-10-22 00:14:36.551933 | controller | ok: "/var/lib/zuul/builds/0e0a0cdde7014b3f90440e3291eaa212/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-package-repositories/tasks/RedHat.yaml" 2025-10-22 00:14:36.552158 | controller | ok: All items complete 2025-10-22 00:14:36.552184 | 2025-10-22 00:14:36.572085 | controller | included: /var/lib/zuul/builds/0e0a0cdde7014b3f90440e3291eaa212/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-package-repositories/tasks/RedHat.yaml 2025-10-22 00:14:36.582222 | 2025-10-22 00:14:36.582318 | TASK [ensure-package-repositories : Create GPG key temp directory] 2025-10-22 00:14:37.113922 | controller | changed 2025-10-22 00:14:37.127359 | 2025-10-22 00:14:37.127497 | LOOP [ensure-package-repositories : Upload GPG keys] 2025-10-22 00:14:38.728656 | controller | changed: 2025-10-22 00:14:38.728984 | controller | { 2025-10-22 00:14:38.729082 | controller | "data": "-----BEGIN PGP PUBLIC KEY BLOCK-----\n\nmQINBFit5IEBEADDt86QpYKz5flnCsOyZ/fk3WwBKxfDjwHf/GIflo+4GWAXS7wJ\n1PSzPsvSDATV10J44i5WQzh99q+lZvFCVRFiNhRmlmcXG+rk1QmDh3fsCCj9Q/yP\nw8jn3Hx0zDtz8PIB/18ReftYJzUo34COLiHn8WiY20uGCF2pjdPgfxE+K454c4G7\ngKFqVUFYgPug2CS0quaBB5b0rpFUdzTeI5RCStd27nHCpuSDCvRYAfdv+4Y1yiVh\nKKdoe3Smj+RnXeVMgDxtH9FJibZ3DK7WnMN2yeob6VqXox+FvKYJCCLkbQgQmE50\nuVK0uN71A1mQDcTRKQ2q3fFGlMTqJbbzr3LwnCBE6hV0a36t+DABtZTmz5O69xdJ\nWGdBeePCnWVqtDb/BdEYz7hPKskcZBarygCCe2Xi7sZieoFZuq6ltPoCsdfEdfbO\n+VBVKJnExqNZCcFUTEnbH4CldWROOzMS8BGUlkGpa59Sl1t0QcmWlw1EbkeMQNrN\nspdR8lobcdNS9bpAJQqSHRZh3cAM9mA3Yq/bssUS/P2quRXLjJ9mIv3dky9C3udM\n+q2unvnbNpPtIUly76FJ3s8g8sHeOnmYcKqNGqHq2Q3kMdA2eIbI0MqfOIo2+Xk0\nrNt3ctq3g+cQiorcN3rdHPsTRSAcp+NCz1QF9TwXYtH1XV24A6QMO0+CZwARAQAB\ntCtEb2NrZXIgUmVsZWFzZSAoQ0UgcnBtKSA8ZG9ja2VyQGRvY2tlci5jb20+iQI3\nBBMBCgAhBQJYrep4AhsvBQsJCAcDBRUKCQgLBRYCAwEAAh4BAheAAAoJEMUv62ti\nHp816C0P/iP+1uhSa6Qq3TIc5sIFE5JHxOO6y0R97cUdAmCbEqBiJHUPNQDQaaRG\nVYBm0K013Q1gcJeUJvS32gthmIvhkstw7KTodwOM8Kl11CCqZ07NPFef1b2SaJ7l\nTYpyUsT9+e343ph+O4C1oUQw6flaAJe+8ATCmI/4KxfhIjD2a/Q1voR5tUIxfexC\n/LZTx05gyf2mAgEWlRm/cGTStNfqDN1uoKMlV+WFuB1j2oTUuO1/dr8mL+FgZAM3\nntWFo9gQCllNV9ahYOON2gkoZoNuPUnHsf4Bj6BQJnIXbAhMk9H2sZzwUi9bgObZ\nXO8+OrP4D4B9kCAKqqaQqA+O46LzO2vhN74lm/Fy6PumHuviqDBdN+HgtRPMUuao\nxnuVJSvBu9sPdgT/pR1N9u/KnfAnnLtR6g+fx4mWz+ts/riB/KRHzXd+44jGKZra\nIhTMfniguMJNsyEOO0AN8Tqcl0eRBxcOArcri7xu8HFvvl+e+ILymu4buusbYEVL\nGBkYP5YMmScfKn+jnDVN4mWoN1Bq2yMhMGx6PA3hOvzPNsUoYy2BwDxNZyflzuAi\ng59mgJm2NXtzNbSRJbMamKpQ69mzLWGdFNsRd4aH7PT7uPAURaf7B5BVp3UyjERW\n5alSGnBqsZmvlRnVH5BDUhYsWZMPRQS9rRr4iGW0l+TH+O2VJ8aQ\n=0Zqq\n-----END PGP PUBLIC KEY BLOCK-----\n" 2025-10-22 00:14:38.729168 | controller | } 2025-10-22 00:14:38.745573 | 2025-10-22 00:14:38.745779 | LOOP [ensure-package-repositories : Import GPG keys] 2025-10-22 00:14:39.512905 | controller | changed: 2025-10-22 00:14:39.513176 | controller | { 2025-10-22 00:14:39.513232 | controller | "data": "-----BEGIN PGP PUBLIC KEY BLOCK-----\n\nmQINBFit5IEBEADDt86QpYKz5flnCsOyZ/fk3WwBKxfDjwHf/GIflo+4GWAXS7wJ\n1PSzPsvSDATV10J44i5WQzh99q+lZvFCVRFiNhRmlmcXG+rk1QmDh3fsCCj9Q/yP\nw8jn3Hx0zDtz8PIB/18ReftYJzUo34COLiHn8WiY20uGCF2pjdPgfxE+K454c4G7\ngKFqVUFYgPug2CS0quaBB5b0rpFUdzTeI5RCStd27nHCpuSDCvRYAfdv+4Y1yiVh\nKKdoe3Smj+RnXeVMgDxtH9FJibZ3DK7WnMN2yeob6VqXox+FvKYJCCLkbQgQmE50\nuVK0uN71A1mQDcTRKQ2q3fFGlMTqJbbzr3LwnCBE6hV0a36t+DABtZTmz5O69xdJ\nWGdBeePCnWVqtDb/BdEYz7hPKskcZBarygCCe2Xi7sZieoFZuq6ltPoCsdfEdfbO\n+VBVKJnExqNZCcFUTEnbH4CldWROOzMS8BGUlkGpa59Sl1t0QcmWlw1EbkeMQNrN\nspdR8lobcdNS9bpAJQqSHRZh3cAM9mA3Yq/bssUS/P2quRXLjJ9mIv3dky9C3udM\n+q2unvnbNpPtIUly76FJ3s8g8sHeOnmYcKqNGqHq2Q3kMdA2eIbI0MqfOIo2+Xk0\nrNt3ctq3g+cQiorcN3rdHPsTRSAcp+NCz1QF9TwXYtH1XV24A6QMO0+CZwARAQAB\ntCtEb2NrZXIgUmVsZWFzZSAoQ0UgcnBtKSA8ZG9ja2VyQGRvY2tlci5jb20+iQI3\nBBMBCgAhBQJYrep4AhsvBQsJCAcDBRUKCQgLBRYCAwEAAh4BAheAAAoJEMUv62ti\nHp816C0P/iP+1uhSa6Qq3TIc5sIFE5JHxOO6y0R97cUdAmCbEqBiJHUPNQDQaaRG\nVYBm0K013Q1gcJeUJvS32gthmIvhkstw7KTodwOM8Kl11CCqZ07NPFef1b2SaJ7l\nTYpyUsT9+e343ph+O4C1oUQw6flaAJe+8ATCmI/4KxfhIjD2a/Q1voR5tUIxfexC\n/LZTx05gyf2mAgEWlRm/cGTStNfqDN1uoKMlV+WFuB1j2oTUuO1/dr8mL+FgZAM3\nntWFo9gQCllNV9ahYOON2gkoZoNuPUnHsf4Bj6BQJnIXbAhMk9H2sZzwUi9bgObZ\nXO8+OrP4D4B9kCAKqqaQqA+O46LzO2vhN74lm/Fy6PumHuviqDBdN+HgtRPMUuao\nxnuVJSvBu9sPdgT/pR1N9u/KnfAnnLtR6g+fx4mWz+ts/riB/KRHzXd+44jGKZra\nIhTMfniguMJNsyEOO0AN8Tqcl0eRBxcOArcri7xu8HFvvl+e+ILymu4buusbYEVL\nGBkYP5YMmScfKn+jnDVN4mWoN1Bq2yMhMGx6PA3hOvzPNsUoYy2BwDxNZyflzuAi\ng59mgJm2NXtzNbSRJbMamKpQ69mzLWGdFNsRd4aH7PT7uPAURaf7B5BVp3UyjERW\n5alSGnBqsZmvlRnVH5BDUhYsWZMPRQS9rRr4iGW0l+TH+O2VJ8aQ\n=0Zqq\n-----END PGP PUBLIC KEY BLOCK-----\n" 2025-10-22 00:14:39.513278 | controller | } 2025-10-22 00:14:39.513336 | controller | changed: All items complete 2025-10-22 00:14:39.513378 | 2025-10-22 00:14:39.527474 | 2025-10-22 00:14:39.527633 | TASK [ensure-package-repositories : Remove GPG temp directory] 2025-10-22 00:14:39.900878 | controller | changed 2025-10-22 00:14:39.910533 | 2025-10-22 00:14:39.910689 | LOOP [ensure-package-repositories : Add repositories] 2025-10-22 00:14:40.374249 | controller | changed: 2025-10-22 00:14:40.374521 | controller | { 2025-10-22 00:14:40.374557 | controller | "baseurl": "https://download.docker.com/linux/fedora/$releasever/$basearch/stable", 2025-10-22 00:14:40.374582 | controller | "description": "Docker CE Stable - $basearch", 2025-10-22 00:14:40.374606 | controller | "gpgcheck": true, 2025-10-22 00:14:40.374628 | controller | "name": "docker-ce-stable" 2025-10-22 00:14:40.374650 | controller | } 2025-10-22 00:14:40.395279 | 2025-10-22 00:14:40.395447 | TASK [ensure-docker : Install pre-reqs] 2025-10-22 00:14:43.819356 | controller | ok: Nothing to do 2025-10-22 00:14:43.841373 | 2025-10-22 00:14:43.841596 | TASK [ensure-docker : Remove packages] 2025-10-22 00:14:46.000390 | controller | ok: Nothing to do 2025-10-22 00:14:46.015637 | 2025-10-22 00:14:46.015994 | TASK [ensure-docker : Install upstream docker using package] 2025-10-22 00:15:38.018987 | controller | changed 2025-10-22 00:15:38.033586 | 2025-10-22 00:15:38.033786 | TASK [ensure-docker : Setup docker] 2025-10-22 00:15:38.075275 | controller | ok 2025-10-22 00:15:38.099012 | controller | included: /var/lib/zuul/builds/0e0a0cdde7014b3f90440e3291eaa212/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/tasks/docker-setup.yaml 2025-10-22 00:15:38.118453 | 2025-10-22 00:15:38.118627 | TASK [ensure-docker : Ensure "docker" group exists] 2025-10-22 00:15:38.763046 | controller | ok 2025-10-22 00:15:38.768909 | 2025-10-22 00:15:38.768971 | TASK [ensure-docker : Add user to docker group] 2025-10-22 00:15:39.451551 | controller | changed 2025-10-22 00:15:39.465451 | 2025-10-22 00:15:39.465732 | TASK [Update docker daemon proxy configuration] 2025-10-22 00:15:39.505019 | controller | skipping: Conditional result was False 2025-10-22 00:15:39.515122 | 2025-10-22 00:15:39.515261 | TASK [Lower default MTU] 2025-10-22 00:15:39.543839 | controller | skipping: Conditional result was False 2025-10-22 00:15:39.555182 | 2025-10-22 00:15:39.555272 | TASK [ensure-docker : Get the status of the docket.socket unit] 2025-10-22 00:15:40.049906 | controller | inactive 2025-10-22 00:15:40.235185 | controller | ok: Runtime: 0:00:00.011492 2025-10-22 00:15:40.247399 | 2025-10-22 00:15:40.247856 | TASK [ensure-docker : Get the status of the docket unit] 2025-10-22 00:15:40.614449 | controller | inactive 2025-10-22 00:15:40.909263 | controller | ok: Runtime: 0:00:00.011253 2025-10-22 00:15:40.927491 | 2025-10-22 00:15:40.928059 | TASK [ensure-docker : Restart docker] 2025-10-22 00:15:40.983811 | Trigger a Docker restart 2025-10-22 00:15:40.995752 | 2025-10-22 00:15:40.995849 | TASK [ensure-docker : Reset ssh connection to pick up docker group] 2025-10-22 00:15:41.046732 | 2025-10-22 00:15:41.046914 | TASK [ensure-docker : Install docker-compose] 2025-10-22 00:15:41.073314 | controller | skipping: Conditional result was False 2025-10-22 00:15:41.080288 | 2025-10-22 00:15:41.080361 | TASK [ensure-docker : Flush handlers before role exit] 2025-10-22 00:15:41.090295 | 2025-10-22 00:15:41.090363 | TASK [ensure-docker : Stop docker.socket to avoid any conflict] 2025-10-22 00:15:43.494928 | controller | changed 2025-10-22 00:15:43.511099 | 2025-10-22 00:15:43.511244 | TASK [ensure-docker : Assure docker service is running] 2025-10-22 00:15:46.390879 | controller | changed 2025-10-22 00:15:46.403110 | 2025-10-22 00:15:46.403214 | TASK [ensure-docker : Assure docker.socket service is running] 2025-10-22 00:15:47.847845 | controller | ok 2025-10-22 00:15:47.868959 | 2025-10-22 00:15:47.869198 | TASK [ensure-docker : Correct group ownership on docker sock] 2025-10-22 00:15:48.734990 | controller | ok 2025-10-22 00:15:48.742912 | 2025-10-22 00:15:48.743008 | TASK [ensure-docker : Validate ability to talk with docker] 2025-10-22 00:15:49.501927 | controller | CONTAINER ID IMAGE COMMAND CREATED STATUS PORTS NAMES 2025-10-22 00:15:49.891274 | controller | ok: Runtime: 0:00:00.059764 2025-10-22 00:15:49.906370 | 2025-10-22 00:15:49.906529 | TASK [ensure-docker : Get version details] 2025-10-22 00:15:50.482613 | controller | Client: Docker Engine - Community 2025-10-22 00:15:50.482693 | controller | Version: 24.0.7 2025-10-22 00:15:50.483089 | controller | API version: 1.43 2025-10-22 00:15:50.483113 | controller | Go version: go1.20.10 2025-10-22 00:15:50.483138 | controller | Git commit: afdd53b 2025-10-22 00:15:50.483149 | controller | Built: Thu Oct 26 09:09:39 2023 2025-10-22 00:15:50.483159 | controller | OS/Arch: linux/amd64 2025-10-22 00:15:50.483172 | controller | Context: default 2025-10-22 00:15:50.483184 | controller | 2025-10-22 00:15:50.483194 | controller | Server: Docker Engine - Community 2025-10-22 00:15:50.483203 | controller | Engine: 2025-10-22 00:15:50.483216 | controller | Version: 24.0.7 2025-10-22 00:15:50.485337 | controller | API version: 1.43 (minimum version 1.12) 2025-10-22 00:15:50.485392 | controller | Go version: go1.20.10 2025-10-22 00:15:50.485404 | controller | Git commit: 311b9ff 2025-10-22 00:15:50.485413 | controller | Built: Thu Oct 26 09:08:11 2023 2025-10-22 00:15:50.485423 | controller | OS/Arch: linux/amd64 2025-10-22 00:15:50.485432 | controller | Experimental: false 2025-10-22 00:15:50.485441 | controller | containerd: 2025-10-22 00:15:50.485449 | controller | Version: 1.6.25 2025-10-22 00:15:50.485458 | controller | GitCommit: d8f198a4ed8892c764191ef7b3b06d8a2eeb5c7f 2025-10-22 00:15:50.485466 | controller | runc: 2025-10-22 00:15:50.485475 | controller | Version: 1.1.10 2025-10-22 00:15:50.485483 | controller | GitCommit: v1.1.10-0-g18a0cb0 2025-10-22 00:15:50.485498 | controller | docker-init: 2025-10-22 00:15:50.485506 | controller | Version: 0.19.0 2025-10-22 00:15:50.485515 | controller | GitCommit: de40ad0 2025-10-22 00:15:50.580790 | controller | ok: Runtime: 0:00:00.065960 2025-10-22 00:15:50.595052 | 2025-10-22 00:15:50.595208 | TASK [ensure-docker : Dump installed docker details] 2025-10-22 00:15:50.646452 | {'changed': True, 'stdout': 'Client: Docker Engine - Community\n Version: 24.0.7\n API version: 1.43\n Go version: go1.20.10\n Git commit: afdd53b\n Built: Thu Oct 26 09:09:39 2023\n OS/Arch: linux/amd64\n Context: default\n\nServer: Docker Engine - Community\n Engine:\n Version: 24.0.7\n API version: 1.43 (minimum version 1.12)\n Go version: go1.20.10\n Git commit: 311b9ff\n Built: Thu Oct 26 09:08:11 2023\n OS/Arch: linux/amd64\n Experimental: false\n containerd:\n Version: 1.6.25\n GitCommit: d8f198a4ed8892c764191ef7b3b06d8a2eeb5c7f\n runc:\n Version: 1.1.10\n GitCommit: v1.1.10-0-g18a0cb0\n docker-init:\n Version: 0.19.0\n GitCommit: de40ad0', 'stderr': '', 'rc': 0, 'cmd': ['docker', 'version'], 'start': '2025-10-22 00:15:50.420010', 'end': '2025-10-22 00:15:50.485970', 'delta': '0:00:00.065960', 'msg': '', 'zuul_log_id': 'fa163efc-24cc-7b3e-182e-000000000061-1-controller', 'stdout_lines': ['Client: Docker Engine - Community', ' Version: 24.0.7', ' API version: 1.43', ' Go version: go1.20.10', ' Git commit: afdd53b', ' Built: Thu Oct 26 09:09:39 2023', ' OS/Arch: linux/amd64', ' Context: default', '', 'Server: Docker Engine - Community', ' Engine:', ' Version: 24.0.7', ' API version: 1.43 (minimum version 1.12)', ' Go version: go1.20.10', ' Git commit: 311b9ff', ' Built: Thu Oct 26 09:08:11 2023', ' OS/Arch: linux/amd64', ' Experimental: false', ' containerd:', ' Version: 1.6.25', ' GitCommit: d8f198a4ed8892c764191ef7b3b06d8a2eeb5c7f', ' runc:', ' Version: 1.1.10', ' GitCommit: v1.1.10-0-g18a0cb0', ' docker-init:', ' Version: 0.19.0', ' GitCommit: de40ad0'], 'stderr_lines': [], 'failed': False} 2025-10-22 00:15:50.661902 | 2025-10-22 00:15:50.662155 | TASK [Use buildset registry] 2025-10-22 00:15:50.691204 | controller | skipping: Conditional result was False 2025-10-22 00:15:50.708815 | 2025-10-22 00:15:50.708919 | TASK [Ensure python3.8 is present] 2025-10-22 00:15:50.735648 | controller | skipping: Conditional result was False 2025-10-22 00:15:50.744637 | 2025-10-22 00:15:50.744761 | TASK [Run our-ensure-python role (Fedora only for now)] 2025-10-22 00:15:50.769611 | controller | ok 2025-10-22 00:15:50.797155 | 2025-10-22 00:15:50.797364 | TASK [our-ensure-python : Install the right Python version (rpm)] 2025-10-22 00:15:56.925263 | controller | ok: Nothing to do 2025-10-22 00:15:56.938540 | 2025-10-22 00:15:56.938750 | TASK [our-ensure-python : Also install python3-devel] 2025-10-22 00:16:11.239142 | controller | changed 2025-10-22 00:16:11.263969 | 2025-10-22 00:16:11.264113 | TASK [Run ensure-virtualenv role] 2025-10-22 00:16:11.298779 | controller | ok 2025-10-22 00:16:11.329722 | 2025-10-22 00:16:11.329880 | TASK [ensure-virtualenv : Check if virtualenv is installed] 2025-10-22 00:16:11.715926 | controller | /usr/bin/virtualenv 2025-10-22 00:16:11.969206 | controller | ok: Runtime: 0:00:00.004271 2025-10-22 00:16:11.981125 | 2025-10-22 00:16:11.981361 | LOOP [ensure-virtualenv : Install virtualenv package] 2025-10-22 00:16:12.024946 | controller | skipping: Conditional result was False 2025-10-22 00:16:12.025483 | controller | changed: All items complete 2025-10-22 00:16:12.025545 | 2025-10-22 00:16:12.048265 | 2025-10-22 00:16:12.048476 | TASK [Find the full path of the Python interpreter] 2025-10-22 00:16:12.505103 | controller | /usr/bin/python3.9 2025-10-22 00:16:12.710574 | controller | ok 2025-10-22 00:16:12.718079 | 2025-10-22 00:16:12.718164 | TASK [Create virtualenv for ansible-test with a specific interpreter] 2025-10-22 00:16:14.900464 | controller | created virtual environment CPython3.9.18.final.0-64 in 1185ms 2025-10-22 00:16:14.964830 | controller | creator CPython3Posix(dest=/home/zuul-worker/venv, clear=False, no_vcs_ignore=False, global=False) 2025-10-22 00:16:14.964883 | controller | seeder FromAppData(extra_search_dir=/usr/share/python-wheels,download=False, pip=bundle, setuptools=bundle, wheel=bundle, via=copy, app_data_dir=/home/zuul-worker/.local/share/virtualenv) 2025-10-22 00:16:14.964901 | controller | added seed packages: pip==22.2.2, setuptools==62.6.0, wheel==0.37.1 2025-10-22 00:16:14.964924 | controller | activators BashActivator,CShellActivator,FishActivator,NushellActivator,PowerShellActivator,PythonActivator 2025-10-22 00:16:15.364372 | controller | changed 2025-10-22 00:16:15.378725 | 2025-10-22 00:16:15.378875 | TASK [Set selinux package] 2025-10-22 00:16:15.404136 | controller | ok 2025-10-22 00:16:15.412155 | 2025-10-22 00:16:15.412246 | TASK [Set selinux package (Fedora)] 2025-10-22 00:16:15.456605 | controller | ok 2025-10-22 00:16:15.464238 | 2025-10-22 00:16:15.464327 | TASK [Install selinux into virtualenv] 2025-10-22 00:16:18.646878 | controller | Collecting selinux-please-lie-to-me 2025-10-22 00:16:18.771713 | controller | Downloading selinux_please_lie_to_me-1.0.1-py2.py3-none-any.whl (3.8 kB) 2025-10-22 00:16:19.628061 | controller | Collecting setuptools<50.0.0 2025-10-22 00:16:19.643995 | controller | Downloading setuptools-49.6.0-py3-none-any.whl (803 kB) 2025-10-22 00:16:19.751560 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 803.3/803.3 kB 8.3 MB/s eta 0:00:00 2025-10-22 00:16:19.997313 | controller | Installing collected packages: setuptools, selinux-please-lie-to-me 2025-10-22 00:16:20.001684 | controller | Attempting uninstall: setuptools 2025-10-22 00:16:20.004353 | controller | Found existing installation: setuptools 62.6.0 2025-10-22 00:16:20.175301 | controller | Uninstalling setuptools-62.6.0: 2025-10-22 00:16:20.192165 | controller | Successfully uninstalled setuptools-62.6.0 2025-10-22 00:16:21.091858 | controller | Successfully installed selinux-please-lie-to-me-1.0.1 setuptools-49.6.0 2025-10-22 00:16:21.342714 | controller | 2025-10-22 00:16:21.535682 | controller | [notice] A new release of pip available: 22.2.2 -> 25.2 2025-10-22 00:16:21.535716 | controller | [notice] To update, run: /home/zuul-worker/venv/bin/python -m pip install --upgrade pip 2025-10-22 00:16:21.622497 | controller | ok: Runtime: 0:00:05.524368 2025-10-22 00:16:21.637185 | 2025-10-22 00:16:21.637430 | TASK [Install pytest-forked into virtualenv] 2025-10-22 00:16:22.571361 | controller | Collecting pytest-forked 2025-10-22 00:16:22.650068 | controller | Downloading pytest_forked-1.6.0-py3-none-any.whl (4.9 kB) 2025-10-22 00:16:22.714835 | controller | Collecting py 2025-10-22 00:16:22.728867 | controller | Downloading py-1.11.0-py2.py3-none-any.whl (98 kB) 2025-10-22 00:16:22.762787 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 98.7/98.7 kB 3.4 MB/s eta 0:00:00 2025-10-22 00:16:23.103681 | controller | Collecting pytest>=3.10 2025-10-22 00:16:23.119026 | controller | Downloading pytest-8.4.2-py3-none-any.whl (365 kB) 2025-10-22 00:16:23.149156 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 365.8/365.8 kB 13.7 MB/s eta 0:00:00 2025-10-22 00:16:23.300177 | controller | Collecting tomli>=1 2025-10-22 00:16:23.315394 | controller | Downloading tomli-2.3.0-py3-none-any.whl (14 kB) 2025-10-22 00:16:23.371236 | controller | Collecting iniconfig>=1 2025-10-22 00:16:23.384640 | controller | Downloading iniconfig-2.1.0-py3-none-any.whl (6.0 kB) 2025-10-22 00:16:23.464208 | controller | Collecting exceptiongroup>=1 2025-10-22 00:16:23.480145 | controller | Downloading exceptiongroup-1.3.0-py3-none-any.whl (16 kB) 2025-10-22 00:16:23.601176 | controller | Collecting packaging>=20 2025-10-22 00:16:23.619077 | controller | Downloading packaging-25.0-py3-none-any.whl (66 kB) 2025-10-22 00:16:23.629607 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 66.5/66.5 kB 9.5 MB/s eta 0:00:00 2025-10-22 00:16:23.776324 | controller | Collecting pygments>=2.7.2 2025-10-22 00:16:23.793874 | controller | Downloading pygments-2.19.2-py3-none-any.whl (1.2 MB) 2025-10-22 00:16:23.821458 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 1.2/1.2 MB 52.4 MB/s eta 0:00:00 2025-10-22 00:16:23.921045 | controller | Collecting pluggy<2,>=1.5 2025-10-22 00:16:23.935214 | controller | Downloading pluggy-1.6.0-py3-none-any.whl (20 kB) 2025-10-22 00:16:24.069192 | controller | Collecting typing-extensions>=4.6.0 2025-10-22 00:16:24.087318 | controller | Downloading typing_extensions-4.15.0-py3-none-any.whl (44 kB) 2025-10-22 00:16:24.096707 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 44.6/44.6 kB 6.9 MB/s eta 0:00:00 2025-10-22 00:16:24.222668 | controller | Installing collected packages: typing-extensions, tomli, pygments, py, pluggy, packaging, iniconfig, exceptiongroup, pytest, pytest-forked 2025-10-22 00:16:26.734898 | controller | Successfully installed exceptiongroup-1.3.0 iniconfig-2.1.0 packaging-25.0 pluggy-1.6.0 py-1.11.0 pygments-2.19.2 pytest-8.4.2 pytest-forked-1.6.0 tomli-2.3.0 typing-extensions-4.15.0 2025-10-22 00:16:26.741434 | controller | 2025-10-22 00:16:26.904826 | controller | [notice] A new release of pip available: 22.2.2 -> 25.2 2025-10-22 00:16:26.904876 | controller | [notice] To update, run: /home/zuul-worker/venv/bin/python -m pip install --upgrade pip 2025-10-22 00:16:27.299221 | controller | ok: Runtime: 0:00:04.864191 2025-10-22 00:16:27.314358 | 2025-10-22 00:16:27.314508 | TASK [Update pip] 2025-10-22 00:16:29.015355 | controller | Requirement already satisfied: pip in ./venv/lib/python3.9/site-packages (22.2.2) 2025-10-22 00:16:29.422935 | controller | Collecting pip 2025-10-22 00:16:29.511221 | controller | Downloading pip-25.2-py3-none-any.whl (1.8 MB) 2025-10-22 00:16:29.709135 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 1.8/1.8 MB 9.1 MB/s eta 0:00:00 2025-10-22 00:16:29.925765 | controller | Installing collected packages: pip 2025-10-22 00:16:29.928043 | controller | Attempting uninstall: pip 2025-10-22 00:16:29.929956 | controller | Found existing installation: pip 22.2.2 2025-10-22 00:16:30.512253 | controller | Uninstalling pip-22.2.2: 2025-10-22 00:16:30.573793 | controller | Successfully uninstalled pip-22.2.2 2025-10-22 00:16:32.657913 | controller | Successfully installed pip-25.2 2025-10-22 00:16:32.983331 | controller | ok: Runtime: 0:00:04.769926 2025-10-22 00:16:32.990372 | 2025-10-22 00:16:32.990462 | TASK [Ensure pip temp directory exists in /var/tmp] 2025-10-22 00:16:33.786067 | controller | changed 2025-10-22 00:16:33.804040 | 2025-10-22 00:16:33.804223 | TASK [Install ansible into virtualenv] 2025-10-22 00:16:35.465465 | controller | Processing ./src/github.com/ansible/ansible 2025-10-22 00:16:35.467474 | controller | Preparing metadata (setup.py): started 2025-10-22 00:16:37.028898 | controller | Preparing metadata (setup.py): finished with status 'done' 2025-10-22 00:16:37.163364 | controller | Collecting jinja2 (from ansible-core==2.11.12.post0) 2025-10-22 00:16:37.258196 | controller | Downloading jinja2-3.1.6-py3-none-any.whl.metadata (2.9 kB) 2025-10-22 00:16:37.381797 | controller | Collecting PyYAML (from ansible-core==2.11.12.post0) 2025-10-22 00:16:37.396234 | controller | Downloading pyyaml-6.0.3-cp39-cp39-manylinux2014_x86_64.manylinux_2_17_x86_64.manylinux_2_28_x86_64.whl.metadata (2.4 kB) 2025-10-22 00:16:37.904761 | controller | Collecting cryptography (from ansible-core==2.11.12.post0) 2025-10-22 00:16:37.919209 | controller | Downloading cryptography-46.0.3-cp38-abi3-manylinux_2_34_x86_64.whl.metadata (5.7 kB) 2025-10-22 00:16:37.944256 | controller | Requirement already satisfied: packaging in ./venv/lib/python3.9/site-packages (from ansible-core==2.11.12.post0) (25.0) 2025-10-22 00:16:37.968631 | controller | Collecting resolvelib<0.6.0,>=0.5.3 (from ansible-core==2.11.12.post0) 2025-10-22 00:16:38.037548 | controller | Downloading resolvelib-0.5.4-py2.py3-none-any.whl.metadata (3.7 kB) 2025-10-22 00:16:38.317680 | controller | Collecting cffi>=2.0.0 (from cryptography->ansible-core==2.11.12.post0) 2025-10-22 00:16:38.333313 | controller | Downloading cffi-2.0.0-cp39-cp39-manylinux2014_x86_64.manylinux_2_17_x86_64.whl.metadata (2.6 kB) 2025-10-22 00:16:38.344166 | controller | Requirement already satisfied: typing-extensions>=4.13.2 in ./venv/lib/python3.9/site-packages (from cryptography->ansible-core==2.11.12.post0) (4.15.0) 2025-10-22 00:16:38.368895 | controller | Collecting pycparser (from cffi>=2.0.0->cryptography->ansible-core==2.11.12.post0) 2025-10-22 00:16:38.383058 | controller | Downloading pycparser-2.23-py3-none-any.whl.metadata (993 bytes) 2025-10-22 00:16:38.525526 | controller | Collecting MarkupSafe>=2.0 (from jinja2->ansible-core==2.11.12.post0) 2025-10-22 00:16:38.540544 | controller | Downloading markupsafe-3.0.3-cp39-cp39-manylinux2014_x86_64.manylinux_2_17_x86_64.manylinux_2_28_x86_64.whl.metadata (2.7 kB) 2025-10-22 00:16:38.569390 | controller | Downloading resolvelib-0.5.4-py2.py3-none-any.whl (12 kB) 2025-10-22 00:16:38.593425 | controller | Downloading cryptography-46.0.3-cp38-abi3-manylinux_2_34_x86_64.whl (4.5 MB) 2025-10-22 00:16:38.698556 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 4.5/4.5 MB 67.3 MB/s 0:00:00 2025-10-22 00:16:38.713846 | controller | Downloading cffi-2.0.0-cp39-cp39-manylinux2014_x86_64.manylinux_2_17_x86_64.whl (216 kB) 2025-10-22 00:16:38.738008 | controller | Downloading jinja2-3.1.6-py3-none-any.whl (134 kB) 2025-10-22 00:16:38.762207 | controller | Downloading markupsafe-3.0.3-cp39-cp39-manylinux2014_x86_64.manylinux_2_17_x86_64.manylinux_2_28_x86_64.whl (20 kB) 2025-10-22 00:16:38.785096 | controller | Downloading pycparser-2.23-py3-none-any.whl (118 kB) 2025-10-22 00:16:38.809148 | controller | Downloading pyyaml-6.0.3-cp39-cp39-manylinux2014_x86_64.manylinux_2_17_x86_64.manylinux_2_28_x86_64.whl (750 kB) 2025-10-22 00:16:38.827205 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 750.8/750.8 kB 37.5 MB/s 0:00:00 2025-10-22 00:16:38.870748 | controller | Building wheels for collected packages: ansible-core 2025-10-22 00:16:38.872191 | controller | DEPRECATION: Building 'ansible-core' using the legacy setup.py bdist_wheel mechanism, which will be removed in a future version. pip 25.3 will enforce this behaviour change. A possible replacement is to use the standardized build interface by setting the `--use-pep517` option, (possibly combined with `--no-build-isolation`), or adding a `pyproject.toml` file to the source tree of 'ansible-core'. Discussion can be found at https://github.com/pypa/pip/issues/6334 2025-10-22 00:16:38.872590 | controller | Building wheel for ansible-core (setup.py): started 2025-10-22 00:16:41.153882 | controller | Building wheel for ansible-core (setup.py): finished with status 'done' 2025-10-22 00:16:41.160682 | controller | Created wheel for ansible-core: filename=ansible_core-2.11.12.post0-py3-none-any.whl size=1961168 sha256=2ab23cb820dad76f10f5ada21667ddbb480d8bf38f13d5f47d382e5bd5e68eba 2025-10-22 00:16:41.161189 | controller | Stored in directory: /var/tmp/ansible-test-pip/pip-ephem-wheel-cache-avo0tgc_/wheels/e8/fd/e9/6250c469bad44a7c6855190836e9d22c0bb11cb641caf57fbe 2025-10-22 00:16:41.177392 | controller | Successfully built ansible-core 2025-10-22 00:16:41.246191 | controller | Installing collected packages: resolvelib, PyYAML, pycparser, MarkupSafe, jinja2, cffi, cryptography, ansible-core 2025-10-22 00:16:43.708770 | controller | 2025-10-22 00:16:43.712261 | controller | Successfully installed MarkupSafe-3.0.3 PyYAML-6.0.3 ansible-core-2.11.12.post0 cffi-2.0.0 cryptography-46.0.3 jinja2-3.1.6 pycparser-2.23 resolvelib-0.5.4 2025-10-22 00:16:43.964773 | controller | ok: Runtime: 0:00:09.403488 2025-10-22 00:16:43.976817 | 2025-10-22 00:16:43.977029 | TASK [Setup download-artifact-fork role] 2025-10-22 00:16:44.008636 | controller | ok 2025-10-22 00:16:44.026929 | 2025-10-22 00:16:44.027007 | TASK [download-artifact-fork : Parse inventory response] 2025-10-22 00:16:44.070433 | controller | ok 2025-10-22 00:16:44.078220 | 2025-10-22 00:16:44.078296 | TASK [download-artifact-fork : Fetch info from Zuul API] 2025-10-22 00:16:44.105349 | controller | skipping: Conditional result was False 2025-10-22 00:16:44.114358 | 2025-10-22 00:16:44.114468 | TASK [download-artifact-fork : Parse build response] 2025-10-22 00:16:44.140976 | controller | skipping: Conditional result was False 2025-10-22 00:16:44.150163 | 2025-10-22 00:16:44.150251 | TASK [download-artifact-fork : Ensure artifacts directory exists] 2025-10-22 00:16:44.662151 | controller | changed 2025-10-22 00:16:44.677796 | 2025-10-22 00:16:44.677959 | LOOP [download-artifact-fork : Download archive by type] 2025-10-22 00:16:44.718926 | controller | skipping: Conditional result was False 2025-10-22 00:16:44.719462 | 2025-10-22 00:16:47.064095 | controller | changed: OK (20215 bytes) 2025-10-22 00:16:47.107011 | 2025-10-22 00:16:47.107232 | TASK [deploy-artifacts : define __collections] 2025-10-22 00:16:47.142575 | controller | ok 2025-10-22 00:16:47.149985 | 2025-10-22 00:16:47.150116 | LOOP [deploy-artifacts : Create list of collections] 2025-10-22 00:16:47.187176 | controller | skipping: Conditional result was False 2025-10-22 00:16:47.210224 | controller | ok: 2025-10-22 00:16:47.211264 | controller | { 2025-10-22 00:16:47.211495 | controller | "branch": "main", 2025-10-22 00:16:47.211532 | controller | "job": "build-ansible-collection", 2025-10-22 00:16:47.211557 | controller | "metadata": { 2025-10-22 00:16:47.211579 | controller | "type": "ansible_collection", 2025-10-22 00:16:47.211602 | controller | "version": "5.0.0" 2025-10-22 00:16:47.211624 | controller | }, 2025-10-22 00:16:47.211645 | controller | "name": "ansible.network", 2025-10-22 00:16:47.211684 | controller | "project": "ansible-collections/ansible.network", 2025-10-22 00:16:47.211714 | controller | "url": "https://137ddd93599451f2e08e-2fef3458e3556330b9d0a36f830634d1.ssl.cf2.rackcdn.com/ansible/28964ecd6e194fdd9aa351f53e7ed95f/artifacts/ansible-network-5.0.0.tar.gz" 2025-10-22 00:16:47.211748 | controller | } 2025-10-22 00:16:47.241194 | 2025-10-22 00:16:47.241430 | TASK [deploy-artifacts : Install require-project collection using ansible-galaxy] 2025-10-22 00:16:48.493901 | controller | Starting galaxy collection install process 2025-10-22 00:16:48.494327 | controller | Process install dependency map 2025-10-22 00:16:51.147574 | controller | [WARNING]: Skipping Galaxy server https://galaxy.ansible.com/api/. Got an 2025-10-22 00:16:51.148003 | controller | unexpected error when getting available versions of collection 2025-10-22 00:16:51.148046 | controller | ansible.netcommon: '/api/v3/plugin/ansible/content/published/collections/index/ 2025-10-22 00:16:51.148057 | controller | ansible/netcommon/versions/' 2025-10-22 00:16:51.148074 | controller | ERROR! Unexpected Exception, this is probably a bug: '/api/v3/plugin/ansible/content/published/collections/index/ansible/netcommon/versions/' 2025-10-22 00:16:51.148137 | controller | to see the full traceback, use -vvv 2025-10-22 00:16:51.401693 | controller | ERROR 2025-10-22 00:16:51.401911 | controller | { 2025-10-22 00:16:51.401949 | controller | "delta": "0:00:03.510271", 2025-10-22 00:16:51.401974 | controller | "end": "2025-10-22 00:16:51.223963", 2025-10-22 00:16:51.401997 | controller | "msg": "non-zero return code", 2025-10-22 00:16:51.402019 | controller | "rc": 250, 2025-10-22 00:16:51.402043 | controller | "start": "2025-10-22 00:16:47.713692" 2025-10-22 00:16:51.402065 | controller | } failure 2025-10-22 00:16:51.404851 | 2025-10-22 00:16:51.404915 | PLAY RECAP 2025-10-22 00:16:51.404979 | controller | ok: 44 changed: 24 unreachable: 0 failed: 1 skipped: 17 rescued: 0 ignored: 0 2025-10-22 00:16:51.405009 | 2025-10-22 00:16:51.557068 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master] 2025-10-22 00:16:51.558127 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2025-10-22 00:16:52.273601 | 2025-10-22 00:16:52.273757 | PLAY [all] 2025-10-22 00:16:52.297078 | 2025-10-22 00:16:52.297279 | TASK [fetch-subunit-output : Find stestr or testr executable] 2025-10-22 00:16:53.804792 | controller | changed: non-zero return code 2025-10-22 00:16:53.817595 | 2025-10-22 00:16:53.817792 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2025-10-22 00:16:53.845659 | controller | skipping: Conditional result was False 2025-10-22 00:16:53.859377 | 2025-10-22 00:16:53.859590 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2025-10-22 00:16:53.902330 | 2025-10-22 00:16:53.902606 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2025-10-22 00:16:53.944151 | 2025-10-22 00:16:53.944611 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2025-10-22 00:16:53.971253 | controller | skipping: Conditional result was False 2025-10-22 00:16:53.987709 | 2025-10-22 00:16:53.987880 | LOOP [fetch-subunit-output : Generate subunit file] 2025-10-22 00:16:54.023716 | 2025-10-22 00:16:54.023902 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2025-10-22 00:16:54.069579 | controller | skipping: Conditional result was False 2025-10-22 00:16:54.078154 | 2025-10-22 00:16:54.078258 | TASK [fetch-subunit-output : Remove the temporary file] 2025-10-22 00:16:54.103178 | controller | skipping: Conditional result was False 2025-10-22 00:16:54.111802 | 2025-10-22 00:16:54.111898 | TASK [fetch-subunit-output : Process and fetch subunit results] 2025-10-22 00:16:54.137033 | controller | skipping: Conditional result was False 2025-10-22 00:16:54.182346 | 2025-10-22 00:16:54.182470 | PLAY RECAP 2025-10-22 00:16:54.182533 | controller | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2025-10-22 00:16:54.182565 | 2025-10-22 00:16:54.306057 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2025-10-22 00:16:54.307643 | POST-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/post.yaml@master] 2025-10-22 00:16:55.001757 | 2025-10-22 00:16:55.001923 | PLAY [all:!appliance*] 2025-10-22 00:16:55.028746 | 2025-10-22 00:16:55.028966 | TASK [unregister the node] 2025-10-22 00:16:56.174337 | controller | skipping: Conditional result was False 2025-10-22 00:16:56.186862 | 2025-10-22 00:16:56.187027 | TASK [include_role : fetch-output] 2025-10-22 00:16:56.236382 | controller | ok 2025-10-22 00:16:56.273911 | 2025-10-22 00:16:56.274021 | TASK [fetch-output : Set log path for multiple nodes] 2025-10-22 00:16:56.330753 | controller | skipping: Conditional result was False 2025-10-22 00:16:56.343869 | 2025-10-22 00:16:56.344147 | TASK [fetch-output : Set log path for single node] 2025-10-22 00:16:56.389188 | controller | ok 2025-10-22 00:16:56.404888 | 2025-10-22 00:16:56.405055 | LOOP [fetch-output : Ensure local output dirs] 2025-10-22 00:16:56.897371 | controller -> localhost | ok: "/var/lib/zuul/builds/0e0a0cdde7014b3f90440e3291eaa212/work/logs" 2025-10-22 00:16:57.173306 | controller -> localhost | changed: "/var/lib/zuul/builds/0e0a0cdde7014b3f90440e3291eaa212/work/artifacts" 2025-10-22 00:16:57.454529 | controller -> localhost | changed: "/var/lib/zuul/builds/0e0a0cdde7014b3f90440e3291eaa212/work/docs" 2025-10-22 00:16:57.481721 | 2025-10-22 00:16:57.481973 | LOOP [fetch-output : Collect logs, artifacts and docs] 2025-10-22 00:16:58.909717 | controller | changed: 2025-10-22 00:16:58.910053 | controller | .d..t...... ./ 2025-10-22 00:16:58.910111 | controller | cd+++++++++ controller/ 2025-10-22 00:16:58.910176 | controller | changed: All items complete 2025-10-22 00:16:58.910218 | 2025-10-22 00:17:00.022141 | controller | changed: .d..t...... ./ 2025-10-22 00:17:01.170072 | controller | changed: .d..t...... ./ 2025-10-22 00:17:01.191930 | 2025-10-22 00:17:01.192033 | TASK [include_role : fetch-output-openshift] 2025-10-22 00:17:01.217305 | controller | skipping: Conditional result was False 2025-10-22 00:17:01.226131 | 2025-10-22 00:17:01.226241 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2025-10-22 00:17:01.273439 | controller | skipping: Conditional result was False 2025-10-22 00:17:01.284021 | controller | skipping: Conditional result was False 2025-10-22 00:17:01.327349 | 2025-10-22 00:17:01.327457 | PLAY [localhost] 2025-10-22 00:17:01.345488 | 2025-10-22 00:17:01.345574 | TASK [Run Zuul manifest role] 2025-10-22 00:17:01.366592 | localhost | ok 2025-10-22 00:17:01.385727 | 2025-10-22 00:17:01.385803 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-10-22 00:17:01.740411 | localhost | changed 2025-10-22 00:17:01.745525 | 2025-10-22 00:17:01.745595 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-10-22 00:17:01.784326 | localhost | ok 2025-10-22 00:17:01.817792 | 2025-10-22 00:17:01.817926 | TASK [Set zuul-log-path fact] 2025-10-22 00:17:01.845078 | localhost | ok 2025-10-22 00:17:01.866297 | 2025-10-22 00:17:01.866438 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-10-22 00:17:01.895969 | localhost | ok 2025-10-22 00:17:01.905332 | 2025-10-22 00:17:01.905608 | LOOP [Run upload-logs-swift role] 2025-10-22 00:17:01.944684 | localhost | Output suppressed because no_log was given 2025-10-22 00:17:01.978547 | 2025-10-22 00:17:01.978683 | TASK [Set zuul-log-path fact] 2025-10-22 00:17:02.013002 | localhost | skipping: Conditional result was False 2025-10-22 00:17:02.017875 | 2025-10-22 00:17:02.017937 | TASK [upload-logs-swift : Ensure logs are readable before uploading] 2025-10-22 00:17:02.515006 | localhost -> localhost | ok: Runtime: 0:00:00.010198 2025-10-22 00:17:02.526986 | 2025-10-22 00:17:02.527127 | TASK [upload-logs-swift : Upload logs to swift]