2025-11-21 00:06:52.768171 | Job console starting... 2025-11-21 00:06:52.779140 | Updating repositories 2025-11-21 00:06:55.470826 | Preparing job workspace 2025-11-21 00:06:59.966360 | Running Ansible setup... 2025-11-21 00:07:07.772983 | PRE-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master] 2025-11-21 00:07:08.391917 | 2025-11-21 00:07:08.392037 | PLAY [localhost] 2025-11-21 00:07:08.400721 | 2025-11-21 00:07:08.400795 | TASK [Gathering Facts] 2025-11-21 00:07:09.341551 | localhost | ok 2025-11-21 00:07:09.369276 | 2025-11-21 00:07:09.369436 | TASK [Setup log path fact] 2025-11-21 00:07:09.388915 | localhost | ok 2025-11-21 00:07:09.405289 | 2025-11-21 00:07:09.405381 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-11-21 00:07:09.434560 | localhost | ok 2025-11-21 00:07:09.445066 | 2025-11-21 00:07:09.445157 | TASK [emit-job-header : Print job information] 2025-11-21 00:07:09.487575 | # Job Information 2025-11-21 00:07:09.487832 | Ansible Version: 2.15.12 2025-11-21 00:07:09.487901 | Job: ansible-test-sanity-docker-stable-2.11 2025-11-21 00:07:09.487946 | Pipeline: periodic 2025-11-21 00:07:09.487986 | Executor: ze04.softwarefactory-project.io 2025-11-21 00:07:09.488026 | Triggered by: https://github.com/ansible-collections/ansible.network 2025-11-21 00:07:09.488071 | Log URL (when completed): https://ansible.softwarefactory-project.io/logs/53d/ansible/53d55a8d43e24d39aa98eaa91c7dc0bf/ 2025-11-21 00:07:09.488112 | Event ID: 18af9d3ade2c4224bc51dd3a20737ff4 2025-11-21 00:07:09.495941 | 2025-11-21 00:07:09.496080 | LOOP [emit-job-header : Print node information] 2025-11-21 00:07:09.605830 | localhost | ok: 2025-11-21 00:07:09.606061 | localhost | # Node Information 2025-11-21 00:07:09.606088 | localhost | Inventory Hostname: controller 2025-11-21 00:07:09.606108 | localhost | Hostname: np0005530278 2025-11-21 00:07:09.606127 | localhost | Username: zuul 2025-11-21 00:07:09.606151 | localhost | Distro: Fedora 37 2025-11-21 00:07:09.606170 | localhost | Provider: ansible-vexxhost-ams1 2025-11-21 00:07:09.606188 | localhost | Region: ams1 2025-11-21 00:07:09.606205 | localhost | Label: ansible-fedora-37-1vcpu 2025-11-21 00:07:09.606222 | localhost | Product Name: OpenStack Nova 2025-11-21 00:07:09.606238 | localhost | Interface IP: 38.129.16.123 2025-11-21 00:07:09.633519 | 2025-11-21 00:07:09.633771 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-11-21 00:07:10.101618 | localhost -> localhost | changed 2025-11-21 00:07:10.113707 | 2025-11-21 00:07:10.113871 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-11-21 00:07:10.930383 | localhost -> localhost | changed 2025-11-21 00:07:10.951208 | 2025-11-21 00:07:10.951300 | PLAY [all:!appliance*] 2025-11-21 00:07:10.967660 | 2025-11-21 00:07:10.967746 | TASK [include_role : start-zuul-console] 2025-11-21 00:07:10.989887 | controller | ok 2025-11-21 00:07:11.009241 | 2025-11-21 00:07:11.009309 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-11-21 00:07:11.954626 | controller | ok 2025-11-21 00:07:11.983542 | 2025-11-21 00:07:11.983769 | TASK [use-our-mirror : Retrieve the IP address] 2025-11-21 00:07:14.264542 | controller | ok: Status code was 404 and not [200]: HTTP Error 404: Not Found 2025-11-21 00:07:14.279844 | 2025-11-21 00:07:14.279993 | TASK [use-our-mirror : Ensure the registry service is running on the mirror] 2025-11-21 00:07:14.646805 | controller | skipping: Conditional result was False 2025-11-21 00:07:14.663847 | 2025-11-21 00:07:14.664107 | TASK [use-our-mirror : Write the IP in /etc/hosts] 2025-11-21 00:07:14.701967 | controller | skipping: Conditional result was False 2025-11-21 00:07:14.718593 | 2025-11-21 00:07:14.718866 | TASK [use-our-mirror : Create the podman configuration directory] 2025-11-21 00:07:14.758629 | controller | skipping: Conditional result was False 2025-11-21 00:07:14.773795 | 2025-11-21 00:07:14.773947 | TASK [use-our-mirror : Copy the podman configuration] 2025-11-21 00:07:14.811440 | controller | skipping: Conditional result was False 2025-11-21 00:07:14.824570 | 2025-11-21 00:07:14.824769 | TASK [use-our-mirror : Copy the Yum configuration (main)] 2025-11-21 00:07:14.853057 | controller | skipping: Conditional result was False 2025-11-21 00:07:14.868783 | 2025-11-21 00:07:14.868952 | TASK [use-our-mirror : Copy the Yum configuration (update)] 2025-11-21 00:07:14.906378 | controller | skipping: Conditional result was False 2025-11-21 00:07:14.940044 | 2025-11-21 00:07:14.940305 | TASK [Disable Fedora Modular] 2025-11-21 00:07:15.863229 | controller | changed 2025-11-21 00:07:15.876342 | 2025-11-21 00:07:15.876473 | TASK [Enable EPEL] 2025-11-21 00:07:15.903719 | controller | skipping: Conditional result was False 2025-11-21 00:07:15.918164 | 2025-11-21 00:07:15.918314 | TASK [Register the RHEL node] 2025-11-21 00:07:16.339221 | 2025-11-21 00:07:16.339449 | TASK [Show the subscription-manager status] 2025-11-21 00:07:16.705172 | controller | skipping: Conditional result was False 2025-11-21 00:07:16.721125 | 2025-11-21 00:07:16.721289 | TASK [Enable EPEL on RHEL] 2025-11-21 00:07:17.102424 | controller | skipping: Conditional result was False 2025-11-21 00:07:17.116058 | 2025-11-21 00:07:17.116215 | TASK [Install git and tox] 2025-11-21 00:14:08.983674 | controller | changed 2025-11-21 00:14:08.994128 | 2025-11-21 00:14:08.994302 | TASK [include_role : prepare-workspace] 2025-11-21 00:14:09.030378 | controller | ok 2025-11-21 00:14:09.067404 | 2025-11-21 00:14:09.067591 | TASK [prepare-workspace : Start zuul_console daemon.] 2025-11-21 00:14:09.832371 | controller | ok 2025-11-21 00:14:09.839204 | 2025-11-21 00:14:09.839293 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2025-11-21 00:14:25.560813 | controller | Output suppressed because no_log was given 2025-11-21 00:14:25.577843 | 2025-11-21 00:14:25.577961 | TASK [include_role : prepare-workspace-openshift] 2025-11-21 00:14:25.606541 | controller | skipping: Conditional result was False 2025-11-21 00:14:25.644469 | 2025-11-21 00:14:25.644573 | PLAY [all:!appliance] 2025-11-21 00:14:25.669478 | 2025-11-21 00:14:25.669619 | TASK [Run add-build-sshkey role (RSA)] 2025-11-21 00:14:25.703319 | controller | ok 2025-11-21 00:14:25.724651 | 2025-11-21 00:14:25.724836 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-11-21 00:14:25.985932 | controller -> localhost | ok 2025-11-21 00:14:25.992972 | 2025-11-21 00:14:25.993089 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-11-21 00:14:26.013363 | controller | ok 2025-11-21 00:14:26.028874 | controller | included: /var/lib/zuul/builds/53d55a8d43e24d39aa98eaa91c7dc0bf/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-11-21 00:14:26.035129 | 2025-11-21 00:14:26.035204 | TASK [add-build-sshkey : Create Temp SSH key] 2025-11-21 00:14:26.609450 | controller -> localhost | Generating public/private rsa key pair. 2025-11-21 00:14:26.609701 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/53d55a8d43e24d39aa98eaa91c7dc0bf/work/53d55a8d43e24d39aa98eaa91c7dc0bf_id_rsa. 2025-11-21 00:14:26.609735 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/53d55a8d43e24d39aa98eaa91c7dc0bf/work/53d55a8d43e24d39aa98eaa91c7dc0bf_id_rsa.pub. 2025-11-21 00:14:26.609757 | controller -> localhost | The key fingerprint is: 2025-11-21 00:14:26.609778 | controller -> localhost | SHA256:Nz/aEdrtAJzQUaa53zFoCoWjlYfhvocgyNhtdG8rkdw zuul-build-sshkey 2025-11-21 00:14:26.609800 | controller -> localhost | The key's randomart image is: 2025-11-21 00:14:26.609819 | controller -> localhost | +---[RSA 2048]----+ 2025-11-21 00:14:26.609837 | controller -> localhost | | . ..o | 2025-11-21 00:14:26.609856 | controller -> localhost | | . * = | 2025-11-21 00:14:26.609875 | controller -> localhost | | . . O * | 2025-11-21 00:14:26.609893 | controller -> localhost | | + + o B * o . | 2025-11-21 00:14:26.609910 | controller -> localhost | |. + + * E B + o | 2025-11-21 00:14:26.609938 | controller -> localhost | | . . + * X + o | 2025-11-21 00:14:26.609958 | controller -> localhost | | . + + B o | 2025-11-21 00:14:26.609977 | controller -> localhost | | . . o = | 2025-11-21 00:14:26.609995 | controller -> localhost | | . . . | 2025-11-21 00:14:26.610015 | controller -> localhost | +----[SHA256]-----+ 2025-11-21 00:14:26.610078 | controller -> localhost | ok: Runtime: 0:00:00.166640 2025-11-21 00:14:26.617498 | 2025-11-21 00:14:26.617617 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-11-21 00:14:26.674985 | controller | ok 2025-11-21 00:14:26.700094 | controller | included: /var/lib/zuul/builds/53d55a8d43e24d39aa98eaa91c7dc0bf/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-11-21 00:14:26.731482 | 2025-11-21 00:14:26.731630 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-11-21 00:14:26.764637 | controller | skipping: Conditional result was False 2025-11-21 00:14:26.772649 | 2025-11-21 00:14:26.772799 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-11-21 00:14:27.847710 | controller | changed 2025-11-21 00:14:27.854889 | 2025-11-21 00:14:27.854980 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-11-21 00:14:28.469300 | controller | ok 2025-11-21 00:14:28.484844 | 2025-11-21 00:14:28.485033 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-11-21 00:14:31.379484 | controller | changed 2025-11-21 00:14:31.385845 | 2025-11-21 00:14:31.385928 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-11-21 00:14:34.273356 | controller | changed 2025-11-21 00:14:34.285329 | 2025-11-21 00:14:34.285432 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-11-21 00:14:34.310597 | controller | skipping: Conditional result was False 2025-11-21 00:14:34.320587 | 2025-11-21 00:14:34.320713 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-11-21 00:14:34.876293 | controller -> localhost | changed 2025-11-21 00:14:34.902069 | 2025-11-21 00:14:34.902236 | TASK [add-build-sshkey : Add back temp key] 2025-11-21 00:14:35.256730 | controller -> localhost | Identity added: /var/lib/zuul/builds/53d55a8d43e24d39aa98eaa91c7dc0bf/work/53d55a8d43e24d39aa98eaa91c7dc0bf_id_rsa (zuul-build-sshkey) 2025-11-21 00:14:35.257047 | controller -> localhost | ok: Runtime: 0:00:00.020421 2025-11-21 00:14:35.268621 | 2025-11-21 00:14:35.268787 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-11-21 00:14:36.148145 | controller | ok 2025-11-21 00:14:36.156009 | 2025-11-21 00:14:36.156081 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-11-21 00:14:36.206355 | controller | skipping: Conditional result was False 2025-11-21 00:14:36.220877 | 2025-11-21 00:14:36.220954 | TASK [Run add-build-sshkey role (ECDSA)] 2025-11-21 00:14:36.252832 | controller | ok 2025-11-21 00:14:36.279343 | 2025-11-21 00:14:36.279511 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-11-21 00:14:36.613085 | controller -> localhost | ok 2025-11-21 00:14:36.620053 | 2025-11-21 00:14:36.620122 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-11-21 00:14:36.650522 | controller | ok 2025-11-21 00:14:36.661734 | controller | included: /var/lib/zuul/builds/53d55a8d43e24d39aa98eaa91c7dc0bf/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-11-21 00:14:36.667720 | 2025-11-21 00:14:36.667787 | TASK [add-build-sshkey : Create Temp SSH key] 2025-11-21 00:14:36.969995 | controller -> localhost | Generating public/private ecdsa key pair. 2025-11-21 00:14:36.970433 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/53d55a8d43e24d39aa98eaa91c7dc0bf/work/53d55a8d43e24d39aa98eaa91c7dc0bf_id_ecdsa. 2025-11-21 00:14:36.970498 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/53d55a8d43e24d39aa98eaa91c7dc0bf/work/53d55a8d43e24d39aa98eaa91c7dc0bf_id_ecdsa.pub. 2025-11-21 00:14:36.970563 | controller -> localhost | The key fingerprint is: 2025-11-21 00:14:36.970612 | controller -> localhost | SHA256:SXlb1mvS6M+U5vc3an0fJuKsVkyhcHGodaimXh1FS8g zuul-build-sshkey 2025-11-21 00:14:36.970656 | controller -> localhost | The key's randomart image is: 2025-11-21 00:14:36.970757 | controller -> localhost | +---[ECDSA 521]---+ 2025-11-21 00:14:36.970805 | controller -> localhost | | ..*+ | 2025-11-21 00:14:36.970846 | controller -> localhost | | . Eo+.. | 2025-11-21 00:14:36.970880 | controller -> localhost | | O =.+ . | 2025-11-21 00:14:36.970912 | controller -> localhost | | = = = o . | 2025-11-21 00:14:36.970945 | controller -> localhost | | o S = o + | 2025-11-21 00:14:36.970977 | controller -> localhost | | . . . + o . | 2025-11-21 00:14:36.971007 | controller -> localhost | | . . ....=o | 2025-11-21 00:14:36.971033 | controller -> localhost | | . .o .Boo=| 2025-11-21 00:14:36.971058 | controller -> localhost | | ...o..=.O| 2025-11-21 00:14:36.971083 | controller -> localhost | +----[SHA256]-----+ 2025-11-21 00:14:36.971171 | controller -> localhost | ok: Runtime: 0:00:00.017581 2025-11-21 00:14:36.990591 | 2025-11-21 00:14:36.990733 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-11-21 00:14:37.027554 | controller | ok 2025-11-21 00:14:37.042028 | controller | included: /var/lib/zuul/builds/53d55a8d43e24d39aa98eaa91c7dc0bf/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-11-21 00:14:37.113783 | 2025-11-21 00:14:37.114095 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-11-21 00:14:37.132165 | controller | skipping: Conditional result was False 2025-11-21 00:14:37.145101 | 2025-11-21 00:14:37.145252 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-11-21 00:14:37.998173 | controller | changed 2025-11-21 00:14:38.006537 | 2025-11-21 00:14:38.006634 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-11-21 00:14:38.624230 | controller | ok 2025-11-21 00:14:38.632907 | 2025-11-21 00:14:38.633033 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-11-21 00:14:41.506157 | controller | changed 2025-11-21 00:14:41.512519 | 2025-11-21 00:14:41.512599 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-11-21 00:14:44.322349 | controller | changed 2025-11-21 00:14:44.328123 | 2025-11-21 00:14:44.328186 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-11-21 00:14:44.352143 | controller | skipping: Conditional result was False 2025-11-21 00:14:44.358464 | 2025-11-21 00:14:44.358545 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-11-21 00:14:44.639449 | controller -> localhost | changed 2025-11-21 00:14:44.657332 | 2025-11-21 00:14:44.657441 | TASK [add-build-sshkey : Add back temp key] 2025-11-21 00:14:44.979315 | controller -> localhost | Identity added: /var/lib/zuul/builds/53d55a8d43e24d39aa98eaa91c7dc0bf/work/53d55a8d43e24d39aa98eaa91c7dc0bf_id_ecdsa (zuul-build-sshkey) 2025-11-21 00:14:44.979509 | controller -> localhost | ok: Runtime: 0:00:00.008849 2025-11-21 00:14:44.986529 | 2025-11-21 00:14:44.986613 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-11-21 00:14:45.638101 | controller | ok 2025-11-21 00:14:45.650973 | 2025-11-21 00:14:45.651118 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-11-21 00:14:45.682027 | controller | skipping: Conditional result was False 2025-11-21 00:14:45.713340 | 2025-11-21 00:14:45.713484 | TASK [include_role : remove-zuul-sshkey] 2025-11-21 00:14:45.739726 | controller | skipping: Conditional result was False 2025-11-21 00:14:45.755063 | 2025-11-21 00:14:45.755217 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2025-11-21 00:14:46.283446 | controller | ok: "logs" 2025-11-21 00:14:46.284079 | controller | ok: All items complete 2025-11-21 00:14:46.284121 | 2025-11-21 00:14:46.799858 | controller | ok: "artifacts" 2025-11-21 00:14:47.303961 | controller | ok: "docs" 2025-11-21 00:14:47.335881 | 2025-11-21 00:14:47.337863 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2025-11-21 00:14:47.886053 | controller | changed: "logs" 2025-11-21 00:14:48.396495 | controller | changed: "artifacts" 2025-11-21 00:14:48.918038 | controller | changed: "docs" 2025-11-21 00:14:48.965733 | 2025-11-21 00:14:48.965927 | PLAY RECAP 2025-11-21 00:14:48.966028 | controller | ok: 30 changed: 16 unreachable: 0 failed: 0 skipped: 18 rescued: 0 ignored: 0 2025-11-21 00:14:48.966085 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-11-21 00:14:48.966124 | 2025-11-21 00:14:49.193493 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master] 2025-11-21 00:14:49.194454 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2025-11-21 00:14:49.819591 | 2025-11-21 00:14:49.819824 | PLAY [all] 2025-11-21 00:14:49.852875 | 2025-11-21 00:14:49.853042 | TASK [Install binary dependencies] 2025-11-21 00:14:49.916225 | controller | ok 2025-11-21 00:14:49.952059 | 2025-11-21 00:14:49.952292 | TASK [bindep : Include find tasks] 2025-11-21 00:14:49.984803 | controller | ok 2025-11-21 00:14:49.994997 | controller | included: /var/lib/zuul/builds/53d55a8d43e24d39aa98eaa91c7dc0bf/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find.yaml 2025-11-21 00:14:50.002672 | 2025-11-21 00:14:50.002765 | TASK [bindep : Look for bindep.txt] 2025-11-21 00:14:50.897647 | controller | ok 2025-11-21 00:14:50.907340 | 2025-11-21 00:14:50.907488 | TASK [bindep : Define bindep_file fact] 2025-11-21 00:14:50.935334 | controller | skipping: Conditional result was False 2025-11-21 00:14:50.946615 | 2025-11-21 00:14:50.946810 | TASK [bindep : Look for other-requirements.txt] 2025-11-21 00:14:51.539431 | controller | ok 2025-11-21 00:14:51.547016 | 2025-11-21 00:14:51.547122 | TASK [bindep : Define bindep_file fact] 2025-11-21 00:14:51.571894 | controller | skipping: Conditional result was False 2025-11-21 00:14:51.582198 | 2025-11-21 00:14:51.582304 | TASK [bindep : Look for bindep fallback file] 2025-11-21 00:14:51.618243 | controller | skipping: Conditional result was False 2025-11-21 00:14:51.627728 | 2025-11-21 00:14:51.627829 | TASK [bindep : Define bindep_file fact] 2025-11-21 00:14:51.653073 | controller | skipping: Conditional result was False 2025-11-21 00:14:51.661813 | 2025-11-21 00:14:51.661914 | TASK [bindep : Include bindep tasks] 2025-11-21 00:14:51.687403 | controller | skipping: Conditional result was False 2025-11-21 00:14:51.696087 | 2025-11-21 00:14:51.696202 | TASK [bindep : Include install tasks] 2025-11-21 00:14:51.721187 | controller | skipping: Conditional result was False 2025-11-21 00:14:51.729798 | 2025-11-21 00:14:51.729897 | LOOP [bindep : Include package tasks] 2025-11-21 00:14:51.814132 | 2025-11-21 00:14:51.814491 | TASK [Run test-setup role] 2025-11-21 00:14:51.840728 | controller | ok 2025-11-21 00:14:51.860321 | 2025-11-21 00:14:51.860431 | TASK [test-setup : Check if project's tools/test-setup.sh exists] 2025-11-21 00:14:52.539012 | controller | ok 2025-11-21 00:14:52.553395 | 2025-11-21 00:14:52.553551 | TASK [test-setup : Run tools/test-setup.sh] 2025-11-21 00:14:52.924725 | controller | skipping: Conditional result was False 2025-11-21 00:14:52.962881 | 2025-11-21 00:14:52.962999 | PLAY RECAP 2025-11-21 00:14:52.963055 | controller | ok: 4 changed: 0 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2025-11-21 00:14:52.963083 | 2025-11-21 00:14:53.070537 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2025-11-21 00:14:53.071484 | PRE-RUN START: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master] 2025-11-21 00:14:53.717912 | 2025-11-21 00:14:53.718042 | PLAY [controller] 2025-11-21 00:14:53.738447 | 2025-11-21 00:14:53.738545 | TASK [Create the /root directory] 2025-11-21 00:14:54.705941 | controller | ok 2025-11-21 00:14:54.720733 | 2025-11-21 00:14:54.721021 | TASK [Install glibc-langpack-en] 2025-11-21 00:15:03.875016 | controller | ok: Nothing to do 2025-11-21 00:15:03.898256 | 2025-11-21 00:15:03.898419 | TASK [Ensure controller directory exists] 2025-11-21 00:15:04.655782 | controller | changed 2025-11-21 00:15:04.664555 | 2025-11-21 00:15:04.664652 | TASK [Install container runtime] 2025-11-21 00:15:04.729499 | controller | ok 2025-11-21 00:15:04.785768 | 2025-11-21 00:15:04.785937 | LOOP [ensure-docker : Gather variables for each operating system] 2025-11-21 00:15:04.830233 | controller | ok: "/var/lib/zuul/builds/53d55a8d43e24d39aa98eaa91c7dc0bf/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/vars/fedora.yaml" 2025-11-21 00:15:04.839775 | 2025-11-21 00:15:04.839908 | TASK [ensure-docker : Sanity check for distro selection] 2025-11-21 00:15:04.854600 | controller | skipping: Conditional result was False 2025-11-21 00:15:04.861782 | 2025-11-21 00:15:04.861858 | TASK [ensure-docker : Sanity check for distro install] 2025-11-21 00:15:04.886651 | controller | skipping: Conditional result was False 2025-11-21 00:15:04.894012 | 2025-11-21 00:15:04.894119 | TASK [ensure-docker : Sanity check for upstream install] 2025-11-21 00:15:04.919764 | controller | skipping: Conditional result was False 2025-11-21 00:15:04.933617 | 2025-11-21 00:15:04.933899 | TASK [ensure-docker : Sanity check for upstream install mirrors] 2025-11-21 00:15:04.961874 | controller | skipping: Conditional result was False 2025-11-21 00:15:04.968935 | 2025-11-21 00:15:04.969023 | TASK [ensure-docker : Include tasks for docker install] 2025-11-21 00:15:04.991436 | controller | ok 2025-11-21 00:15:05.005470 | controller | included: /var/lib/zuul/builds/53d55a8d43e24d39aa98eaa91c7dc0bf/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/tasks/docker-upstream.yaml 2025-11-21 00:15:05.017836 | 2025-11-21 00:15:05.018023 | TASK [ensure-docker : Not Implemented] 2025-11-21 00:15:05.042257 | controller | skipping: Conditional result was False 2025-11-21 00:15:05.049427 | 2025-11-21 00:15:05.049507 | TASK [Set up docker mirrors] 2025-11-21 00:15:05.069769 | controller | ok 2025-11-21 00:15:05.091644 | 2025-11-21 00:15:05.091767 | TASK [use-docker-mirror : Set docker_mirror fact from mirror_info] 2025-11-21 00:15:05.167299 | controller | skipping: Conditional result was False 2025-11-21 00:15:05.213810 | 2025-11-21 00:15:05.213973 | TASK [use-docker-mirror : Set docker_mirror fact from zuul_site_mirror_info] 2025-11-21 00:15:05.238253 | controller | skipping: Conditional result was False 2025-11-21 00:15:05.245633 | 2025-11-21 00:15:05.245806 | TASK [use-docker-mirror : Set docker_mirror fact] 2025-11-21 00:15:05.270016 | controller | skipping: Conditional result was False 2025-11-21 00:15:05.277751 | 2025-11-21 00:15:05.277920 | TASK [use-docker-mirror : Set up docker mirrors] 2025-11-21 00:15:05.302412 | controller | skipping: Conditional result was False 2025-11-21 00:15:05.321097 | 2025-11-21 00:15:05.321286 | TASK [Add all repositories] 2025-11-21 00:15:05.346990 | controller | ok 2025-11-21 00:15:05.382656 | 2025-11-21 00:15:05.382818 | LOOP [ensure-package-repositories : Install repositories] 2025-11-21 00:15:05.411197 | controller | ok: "/var/lib/zuul/builds/53d55a8d43e24d39aa98eaa91c7dc0bf/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-package-repositories/tasks/RedHat.yaml" 2025-11-21 00:15:05.411378 | controller | ok: All items complete 2025-11-21 00:15:05.411406 | 2025-11-21 00:15:05.428674 | controller | included: /var/lib/zuul/builds/53d55a8d43e24d39aa98eaa91c7dc0bf/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-package-repositories/tasks/RedHat.yaml 2025-11-21 00:15:05.436297 | 2025-11-21 00:15:05.436444 | TASK [ensure-package-repositories : Create GPG key temp directory] 2025-11-21 00:15:06.298382 | controller | changed 2025-11-21 00:15:06.313166 | 2025-11-21 00:15:06.313348 | LOOP [ensure-package-repositories : Upload GPG keys] 2025-11-21 00:15:09.442256 | controller | changed: 2025-11-21 00:15:09.442466 | controller | { 2025-11-21 00:15:09.442494 | 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-11-21 00:15:09.442516 | controller | } 2025-11-21 00:15:09.456494 | 2025-11-21 00:15:09.456566 | LOOP [ensure-package-repositories : Import GPG keys] 2025-11-21 00:15:10.632776 | controller | changed: 2025-11-21 00:15:10.633271 | controller | { 2025-11-21 00:15:10.633517 | 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-11-21 00:15:10.633584 | controller | } 2025-11-21 00:15:10.633658 | controller | changed: All items complete 2025-11-21 00:15:10.633749 | 2025-11-21 00:15:10.660658 | 2025-11-21 00:15:10.662377 | TASK [ensure-package-repositories : Remove GPG temp directory] 2025-11-21 00:15:11.323784 | controller | changed 2025-11-21 00:15:11.329988 | 2025-11-21 00:15:11.330058 | LOOP [ensure-package-repositories : Add repositories] 2025-11-21 00:15:12.121653 | controller | changed: 2025-11-21 00:15:12.121870 | controller | { 2025-11-21 00:15:12.121897 | controller | "baseurl": "https://download.docker.com/linux/fedora/$releasever/$basearch/stable", 2025-11-21 00:15:12.121917 | controller | "description": "Docker CE Stable - $basearch", 2025-11-21 00:15:12.121934 | controller | "gpgcheck": true, 2025-11-21 00:15:12.121950 | controller | "name": "docker-ce-stable" 2025-11-21 00:15:12.121967 | controller | } 2025-11-21 00:15:12.143303 | 2025-11-21 00:15:12.143496 | TASK [ensure-docker : Install pre-reqs] 2025-11-21 00:15:16.673379 | controller | ok: Nothing to do 2025-11-21 00:15:16.685906 | 2025-11-21 00:15:16.686045 | TASK [ensure-docker : Remove packages] 2025-11-21 00:15:20.027856 | controller | ok: Nothing to do 2025-11-21 00:15:20.041113 | 2025-11-21 00:15:20.041251 | TASK [ensure-docker : Install upstream docker using package] 2025-11-21 00:16:33.369863 | controller | changed 2025-11-21 00:16:33.379316 | 2025-11-21 00:16:33.379420 | TASK [ensure-docker : Setup docker] 2025-11-21 00:16:33.403265 | controller | ok 2025-11-21 00:16:33.426229 | controller | included: /var/lib/zuul/builds/53d55a8d43e24d39aa98eaa91c7dc0bf/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/tasks/docker-setup.yaml 2025-11-21 00:16:33.501219 | 2025-11-21 00:16:33.501382 | TASK [ensure-docker : Ensure "docker" group exists] 2025-11-21 00:16:34.418850 | controller | ok 2025-11-21 00:16:34.429584 | 2025-11-21 00:16:34.429705 | TASK [ensure-docker : Add user to docker group] 2025-11-21 00:16:35.364090 | controller | changed 2025-11-21 00:16:35.380082 | 2025-11-21 00:16:35.380256 | TASK [Update docker daemon proxy configuration] 2025-11-21 00:16:35.408922 | controller | skipping: Conditional result was False 2025-11-21 00:16:35.420135 | 2025-11-21 00:16:35.420260 | TASK [Lower default MTU] 2025-11-21 00:16:35.446190 | controller | skipping: Conditional result was False 2025-11-21 00:16:35.459483 | 2025-11-21 00:16:35.459617 | TASK [ensure-docker : Get the status of the docket.socket unit] 2025-11-21 00:16:36.164596 | controller | inactive 2025-11-21 00:16:36.329878 | controller | ok: Runtime: 0:00:00.013725 2025-11-21 00:16:36.338154 | 2025-11-21 00:16:36.338251 | TASK [ensure-docker : Get the status of the docket unit] 2025-11-21 00:16:36.950487 | controller | inactive 2025-11-21 00:16:37.199549 | controller | ok: Runtime: 0:00:00.013828 2025-11-21 00:16:37.214718 | 2025-11-21 00:16:37.214869 | TASK [ensure-docker : Restart docker] 2025-11-21 00:16:37.267443 | Trigger a Docker restart 2025-11-21 00:16:37.280741 | 2025-11-21 00:16:37.280903 | TASK [ensure-docker : Reset ssh connection to pick up docker group] 2025-11-21 00:16:37.331980 | 2025-11-21 00:16:37.332168 | TASK [ensure-docker : Install docker-compose] 2025-11-21 00:16:37.359547 | controller | skipping: Conditional result was False 2025-11-21 00:16:37.368282 | 2025-11-21 00:16:37.368375 | TASK [ensure-docker : Flush handlers before role exit] 2025-11-21 00:16:37.385945 | 2025-11-21 00:16:37.386076 | TASK [ensure-docker : Stop docker.socket to avoid any conflict] 2025-11-21 00:16:40.722979 | controller | changed 2025-11-21 00:16:40.742779 | 2025-11-21 00:16:40.742969 | TASK [ensure-docker : Assure docker service is running] 2025-11-21 00:16:44.358595 | controller | changed 2025-11-21 00:16:44.378887 | 2025-11-21 00:16:44.379094 | TASK [ensure-docker : Assure docker.socket service is running] 2025-11-21 00:16:46.479612 | controller | ok 2025-11-21 00:16:46.501929 | 2025-11-21 00:16:46.502107 | TASK [ensure-docker : Correct group ownership on docker sock] 2025-11-21 00:16:47.339351 | controller | ok 2025-11-21 00:16:47.355814 | 2025-11-21 00:16:47.356013 | TASK [ensure-docker : Validate ability to talk with docker] 2025-11-21 00:16:47.953885 | controller | CONTAINER ID IMAGE COMMAND CREATED STATUS PORTS NAMES 2025-11-21 00:16:48.226379 | controller | ok: Runtime: 0:00:00.030783 2025-11-21 00:16:48.240140 | 2025-11-21 00:16:48.240285 | TASK [ensure-docker : Get version details] 2025-11-21 00:16:48.972917 | controller | Client: Docker Engine - Community 2025-11-21 00:16:48.974349 | controller | Version: 24.0.7 2025-11-21 00:16:48.974365 | controller | API version: 1.43 2025-11-21 00:16:48.974372 | controller | Go version: go1.20.10 2025-11-21 00:16:48.974389 | controller | Git commit: afdd53b 2025-11-21 00:16:48.974395 | controller | Built: Thu Oct 26 09:09:39 2023 2025-11-21 00:16:48.974402 | controller | OS/Arch: linux/amd64 2025-11-21 00:16:48.974410 | controller | Context: default 2025-11-21 00:16:48.974418 | controller | 2025-11-21 00:16:48.974424 | controller | Server: Docker Engine - Community 2025-11-21 00:16:48.974430 | controller | Engine: 2025-11-21 00:16:48.974435 | controller | Version: 24.0.7 2025-11-21 00:16:48.974441 | controller | API version: 1.43 (minimum version 1.12) 2025-11-21 00:16:48.974447 | controller | Go version: go1.20.10 2025-11-21 00:16:48.974452 | controller | Git commit: 311b9ff 2025-11-21 00:16:48.974458 | controller | Built: Thu Oct 26 09:08:11 2023 2025-11-21 00:16:48.974464 | controller | OS/Arch: linux/amd64 2025-11-21 00:16:48.974469 | controller | Experimental: false 2025-11-21 00:16:48.974475 | controller | containerd: 2025-11-21 00:16:48.974480 | controller | Version: 1.6.25 2025-11-21 00:16:48.974486 | controller | GitCommit: d8f198a4ed8892c764191ef7b3b06d8a2eeb5c7f 2025-11-21 00:16:48.974492 | controller | runc: 2025-11-21 00:16:48.974497 | controller | Version: 1.1.10 2025-11-21 00:16:48.974503 | controller | GitCommit: v1.1.10-0-g18a0cb0 2025-11-21 00:16:48.974510 | controller | docker-init: 2025-11-21 00:16:48.974516 | controller | Version: 0.19.0 2025-11-21 00:16:48.974521 | controller | GitCommit: de40ad0 2025-11-21 00:16:49.154356 | controller | ok: Runtime: 0:00:00.036039 2025-11-21 00:16:49.163652 | 2025-11-21 00:16:49.163846 | TASK [ensure-docker : Dump installed docker details] 2025-11-21 00:16:49.197210 | {'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-11-21 00:16:48.940125', 'end': '2025-11-21 00:16:48.976164', 'delta': '0:00:00.036039', 'msg': '', 'zuul_log_id': 'fa163e3b-3c83-6fe6-d2d3-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-11-21 00:16:49.205161 | 2025-11-21 00:16:49.205252 | TASK [Use buildset registry] 2025-11-21 00:16:49.230970 | controller | skipping: Conditional result was False 2025-11-21 00:16:49.249747 | 2025-11-21 00:16:49.249902 | TASK [Ensure python3.8 is present] 2025-11-21 00:16:49.266242 | controller | skipping: Conditional result was False 2025-11-21 00:16:49.276243 | 2025-11-21 00:16:49.276332 | TASK [Run our-ensure-python role (Fedora only for now)] 2025-11-21 00:16:49.301891 | controller | ok 2025-11-21 00:16:49.324773 | 2025-11-21 00:16:49.324915 | TASK [our-ensure-python : Install the right Python version (rpm)] 2025-11-21 00:16:55.901094 | controller | ok: Nothing to do 2025-11-21 00:16:55.914236 | 2025-11-21 00:16:55.914387 | TASK [our-ensure-python : Also install python3-devel] 2025-11-21 00:19:09.049388 | controller | changed 2025-11-21 00:19:09.060079 | 2025-11-21 00:19:09.060145 | TASK [Run ensure-virtualenv role] 2025-11-21 00:19:09.081349 | controller | ok 2025-11-21 00:19:09.103262 | 2025-11-21 00:19:09.103368 | TASK [ensure-virtualenv : Check if virtualenv is installed] 2025-11-21 00:19:09.812550 | controller | /usr/bin/virtualenv 2025-11-21 00:19:09.958928 | controller | ok: Runtime: 0:00:00.004440 2025-11-21 00:19:09.985845 | 2025-11-21 00:19:09.986048 | LOOP [ensure-virtualenv : Install virtualenv package] 2025-11-21 00:19:10.031665 | controller | skipping: Conditional result was False 2025-11-21 00:19:10.031973 | controller | changed: All items complete 2025-11-21 00:19:10.032002 | 2025-11-21 00:19:10.053654 | 2025-11-21 00:19:10.053825 | TASK [Find the full path of the Python interpreter] 2025-11-21 00:19:10.607863 | controller | /usr/bin/python3.9 2025-11-21 00:19:10.914630 | controller | ok 2025-11-21 00:19:10.929452 | 2025-11-21 00:19:10.929741 | TASK [Create virtualenv for ansible-test with a specific interpreter] 2025-11-21 00:19:13.365640 | controller | created virtual environment CPython3.9.18.final.0-64 in 1086ms 2025-11-21 00:19:13.406925 | controller | creator CPython3Posix(dest=/home/zuul/venv, clear=False, no_vcs_ignore=False, global=False) 2025-11-21 00:19:13.406980 | 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) 2025-11-21 00:19:13.406993 | controller | added seed packages: pip==22.2.2, setuptools==62.6.0, wheel==0.37.1 2025-11-21 00:19:13.407021 | controller | activators BashActivator,CShellActivator,FishActivator,NushellActivator,PowerShellActivator,PythonActivator 2025-11-21 00:19:13.816962 | controller | changed 2025-11-21 00:19:13.831452 | 2025-11-21 00:19:13.831610 | TASK [Set selinux package] 2025-11-21 00:19:13.872981 | controller | ok 2025-11-21 00:19:13.881425 | 2025-11-21 00:19:13.881587 | TASK [Set selinux package (Fedora)] 2025-11-21 00:19:13.923762 | controller | ok 2025-11-21 00:19:13.930638 | 2025-11-21 00:19:13.930775 | TASK [Install selinux into virtualenv] 2025-11-21 00:19:18.993995 | controller | Collecting selinux-please-lie-to-me 2025-11-21 00:19:19.211479 | controller | Downloading selinux_please_lie_to_me-1.0.1-py2.py3-none-any.whl (3.8 kB) 2025-11-21 00:19:20.639032 | controller | Collecting setuptools<50.0.0 2025-11-21 00:19:20.648670 | controller | Downloading setuptools-49.6.0-py3-none-any.whl (803 kB) 2025-11-21 00:19:20.741112 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 803.3/803.3 kB 9.7 MB/s eta 0:00:00 2025-11-21 00:19:20.988911 | controller | Installing collected packages: setuptools, selinux-please-lie-to-me 2025-11-21 00:19:20.989133 | controller | Attempting uninstall: setuptools 2025-11-21 00:19:20.990178 | controller | Found existing installation: setuptools 62.6.0 2025-11-21 00:19:21.095638 | controller | Uninstalling setuptools-62.6.0: 2025-11-21 00:19:21.108868 | controller | Successfully uninstalled setuptools-62.6.0 2025-11-21 00:19:21.657641 | controller | Successfully installed selinux-please-lie-to-me-1.0.1 setuptools-49.6.0 2025-11-21 00:19:21.827659 | controller | 2025-11-21 00:19:22.039893 | controller | [notice] A new release of pip available: 22.2.2 -> 25.3 2025-11-21 00:19:22.039954 | controller | [notice] To update, run: /home/zuul/venv/bin/python -m pip install --upgrade pip 2025-11-21 00:19:22.291914 | controller | ok: Runtime: 0:00:07.080271 2025-11-21 00:19:22.305056 | 2025-11-21 00:19:22.305186 | TASK [Install pytest-forked into virtualenv] 2025-11-21 00:19:24.386884 | controller | Collecting pytest-forked 2025-11-21 00:19:24.444018 | controller | Downloading pytest_forked-1.6.0-py3-none-any.whl (4.9 kB) 2025-11-21 00:19:24.624086 | controller | Collecting pytest>=3.10 2025-11-21 00:19:24.629804 | controller | Downloading pytest-8.4.2-py3-none-any.whl (365 kB) 2025-11-21 00:19:24.668534 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 365.8/365.8 kB 10.8 MB/s eta 0:00:00 2025-11-21 00:19:24.721086 | controller | Collecting py 2025-11-21 00:19:24.727050 | controller | Downloading py-1.11.0-py2.py3-none-any.whl (98 kB) 2025-11-21 00:19:24.737602 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 98.7/98.7 kB 13.9 MB/s eta 0:00:00 2025-11-21 00:19:24.805075 | controller | Collecting pluggy<2,>=1.5 2025-11-21 00:19:24.810168 | controller | Downloading pluggy-1.6.0-py3-none-any.whl (20 kB) 2025-11-21 00:19:24.881413 | controller | Collecting pygments>=2.7.2 2025-11-21 00:19:24.887216 | controller | Downloading pygments-2.19.2-py3-none-any.whl (1.2 MB) 2025-11-21 00:19:24.915353 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 1.2/1.2 MB 53.9 MB/s eta 0:00:00 2025-11-21 00:19:24.988024 | controller | Collecting tomli>=1 2025-11-21 00:19:24.993388 | controller | Downloading tomli-2.3.0-py3-none-any.whl (14 kB) 2025-11-21 00:19:25.038130 | controller | Collecting exceptiongroup>=1 2025-11-21 00:19:25.043877 | controller | Downloading exceptiongroup-1.3.0-py3-none-any.whl (16 kB) 2025-11-21 00:19:25.109965 | controller | Collecting packaging>=20 2025-11-21 00:19:25.115370 | controller | Downloading packaging-25.0-py3-none-any.whl (66 kB) 2025-11-21 00:19:25.124699 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 66.5/66.5 kB 10.7 MB/s eta 0:00:00 2025-11-21 00:19:25.156240 | controller | Collecting iniconfig>=1 2025-11-21 00:19:25.161417 | controller | Downloading iniconfig-2.1.0-py3-none-any.whl (6.0 kB) 2025-11-21 00:19:25.238021 | controller | Collecting typing-extensions>=4.6.0 2025-11-21 00:19:25.243702 | controller | Downloading typing_extensions-4.15.0-py3-none-any.whl (44 kB) 2025-11-21 00:19:25.251347 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 44.6/44.6 kB 9.2 MB/s eta 0:00:00 2025-11-21 00:19:25.349293 | controller | Installing collected packages: typing-extensions, tomli, pygments, py, pluggy, packaging, iniconfig, exceptiongroup, pytest, pytest-forked 2025-11-21 00:19:27.437145 | 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-11-21 00:19:27.443172 | controller | 2025-11-21 00:19:27.801619 | controller | [notice] A new release of pip available: 22.2.2 -> 25.3 2025-11-21 00:19:27.801675 | controller | [notice] To update, run: /home/zuul/venv/bin/python -m pip install --upgrade pip 2025-11-21 00:19:28.190787 | controller | ok: Runtime: 0:00:04.412313 2025-11-21 00:19:28.199415 | 2025-11-21 00:19:28.199531 | TASK [Update pip] 2025-11-21 00:19:30.870853 | controller | Requirement already satisfied: pip in ./venv/lib/python3.9/site-packages (22.2.2) 2025-11-21 00:19:31.344415 | controller | Collecting pip 2025-11-21 00:19:31.471788 | controller | Downloading pip-25.3-py3-none-any.whl (1.8 MB) 2025-11-21 00:19:31.612560 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 1.8/1.8 MB 13.9 MB/s eta 0:00:00 2025-11-21 00:19:31.842177 | controller | Installing collected packages: pip 2025-11-21 00:19:31.842350 | controller | Attempting uninstall: pip 2025-11-21 00:19:31.843404 | controller | Found existing installation: pip 22.2.2 2025-11-21 00:19:32.390989 | controller | Uninstalling pip-22.2.2: 2025-11-21 00:19:32.421529 | controller | Successfully uninstalled pip-22.2.2 2025-11-21 00:19:33.845479 | controller | Successfully installed pip-25.3 2025-11-21 00:19:34.564718 | controller | ok: Runtime: 0:00:04.905153 2025-11-21 00:19:34.581522 | 2025-11-21 00:19:34.581750 | TASK [Ensure pip temp directory exists in /var/tmp] 2025-11-21 00:19:35.935774 | controller | changed 2025-11-21 00:19:35.949210 | 2025-11-21 00:19:35.949358 | TASK [Install ansible into virtualenv] 2025-11-21 00:19:38.448983 | controller | Processing ./src/github.com/ansible/ansible 2025-11-21 00:19:38.459029 | controller | Installing build dependencies: started 2025-11-21 00:19:39.953149 | controller | Installing build dependencies: finished with status 'done' 2025-11-21 00:19:39.954890 | controller | Getting requirements to build wheel: started 2025-11-21 00:19:41.071101 | controller | Getting requirements to build wheel: finished with status 'done' 2025-11-21 00:19:41.073389 | controller | Preparing metadata (pyproject.toml): started 2025-11-21 00:19:41.857856 | controller | Preparing metadata (pyproject.toml): finished with status 'done' 2025-11-21 00:19:41.962907 | controller | Collecting jinja2 (from ansible-core==2.11.12.post0) 2025-11-21 00:19:42.020370 | controller | Downloading jinja2-3.1.6-py3-none-any.whl.metadata (2.9 kB) 2025-11-21 00:19:42.122791 | controller | Collecting PyYAML (from ansible-core==2.11.12.post0) 2025-11-21 00:19:42.128152 | 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-11-21 00:19:42.598094 | controller | Collecting cryptography (from ansible-core==2.11.12.post0) 2025-11-21 00:19:42.605816 | controller | Downloading cryptography-46.0.3-cp38-abi3-manylinux_2_34_x86_64.whl.metadata (5.7 kB) 2025-11-21 00:19:42.632519 | controller | Requirement already satisfied: packaging in ./venv/lib/python3.9/site-packages (from ansible-core==2.11.12.post0) (25.0) 2025-11-21 00:19:42.652040 | controller | Collecting resolvelib<0.6.0,>=0.5.3 (from ansible-core==2.11.12.post0) 2025-11-21 00:19:42.659844 | controller | Downloading resolvelib-0.5.4-py2.py3-none-any.whl.metadata (3.7 kB) 2025-11-21 00:19:42.912860 | controller | Collecting cffi>=2.0.0 (from cryptography->ansible-core==2.11.12.post0) 2025-11-21 00:19:42.918244 | controller | Downloading cffi-2.0.0-cp39-cp39-manylinux2014_x86_64.manylinux_2_17_x86_64.whl.metadata (2.6 kB) 2025-11-21 00:19:42.928170 | 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-11-21 00:19:42.947370 | controller | Collecting pycparser (from cffi>=2.0.0->cryptography->ansible-core==2.11.12.post0) 2025-11-21 00:19:42.951988 | controller | Downloading pycparser-2.23-py3-none-any.whl.metadata (993 bytes) 2025-11-21 00:19:43.076407 | controller | Collecting MarkupSafe>=2.0 (from jinja2->ansible-core==2.11.12.post0) 2025-11-21 00:19:43.082079 | 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-11-21 00:19:43.101128 | controller | Downloading resolvelib-0.5.4-py2.py3-none-any.whl (12 kB) 2025-11-21 00:19:43.110616 | controller | Downloading cryptography-46.0.3-cp38-abi3-manylinux_2_34_x86_64.whl (4.5 MB) 2025-11-21 00:19:43.175350 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 4.5/4.5 MB 86.7 MB/s 0:00:00 2025-11-21 00:19:43.182076 | controller | Downloading cffi-2.0.0-cp39-cp39-manylinux2014_x86_64.manylinux_2_17_x86_64.whl (216 kB) 2025-11-21 00:19:43.201433 | controller | Downloading jinja2-3.1.6-py3-none-any.whl (134 kB) 2025-11-21 00:19:43.214776 | 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-11-21 00:19:43.225459 | controller | Downloading pycparser-2.23-py3-none-any.whl (118 kB) 2025-11-21 00:19:43.237996 | 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-11-21 00:19:43.254496 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 750.8/750.8 kB 47.1 MB/s 0:00:00 2025-11-21 00:19:43.273515 | controller | Building wheels for collected packages: ansible-core 2025-11-21 00:19:43.275319 | controller | Building wheel for ansible-core (pyproject.toml): started 2025-11-21 00:19:45.641122 | controller | Building wheel for ansible-core (pyproject.toml): finished with status 'done' 2025-11-21 00:19:45.645387 | controller | Created wheel for ansible-core: filename=ansible_core-2.11.12.post0-py3-none-any.whl size=1961250 sha256=811bc26c246c6c25f1e86d8c2109a4fd01aaedb20c2ab54f790c8dd01b07515a 2025-11-21 00:19:45.645656 | controller | Stored in directory: /var/tmp/ansible-test-pip/pip-ephem-wheel-cache-xf8wqbv6/wheels/0f/a0/93/ab85b739e1eb1e0467171316f13571c14efdf87e0d5d44a33e 2025-11-21 00:19:45.658421 | controller | Successfully built ansible-core 2025-11-21 00:19:45.717072 | controller | Installing collected packages: resolvelib, PyYAML, pycparser, MarkupSafe, jinja2, cffi, cryptography, ansible-core 2025-11-21 00:19:48.207445 | controller | 2025-11-21 00:19:48.212630 | 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-11-21 00:19:48.830737 | controller | ok: Runtime: 0:00:11.660228 2025-11-21 00:19:48.847631 | 2025-11-21 00:19:48.847882 | TASK [Setup download-artifact-fork role] 2025-11-21 00:19:48.879777 | controller | ok 2025-11-21 00:19:48.925617 | 2025-11-21 00:19:48.925820 | TASK [download-artifact-fork : Parse inventory response] 2025-11-21 00:19:48.972826 | controller | ok 2025-11-21 00:19:48.981863 | 2025-11-21 00:19:48.982164 | TASK [download-artifact-fork : Fetch info from Zuul API] 2025-11-21 00:19:49.017776 | controller | skipping: Conditional result was False 2025-11-21 00:19:49.029232 | 2025-11-21 00:19:49.029358 | TASK [download-artifact-fork : Parse build response] 2025-11-21 00:19:49.056121 | controller | skipping: Conditional result was False 2025-11-21 00:19:49.065978 | 2025-11-21 00:19:49.066088 | TASK [download-artifact-fork : Ensure artifacts directory exists] 2025-11-21 00:19:49.891195 | controller | changed 2025-11-21 00:19:49.905063 | 2025-11-21 00:19:49.905221 | LOOP [download-artifact-fork : Download archive by type] 2025-11-21 00:19:49.933942 | controller | skipping: Conditional result was False 2025-11-21 00:19:49.934340 | 2025-11-21 00:19:52.314353 | controller | changed: OK (20206 bytes) 2025-11-21 00:19:52.354087 | 2025-11-21 00:19:52.354293 | TASK [deploy-artifacts : define __collections] 2025-11-21 00:19:52.395388 | controller | ok 2025-11-21 00:19:52.408897 | 2025-11-21 00:19:52.409085 | LOOP [deploy-artifacts : Create list of collections] 2025-11-21 00:19:52.437631 | controller | skipping: Conditional result was False 2025-11-21 00:19:52.450619 | controller | ok: 2025-11-21 00:19:52.450754 | controller | { 2025-11-21 00:19:52.450799 | controller | "branch": "main", 2025-11-21 00:19:52.450826 | controller | "job": "build-ansible-collection", 2025-11-21 00:19:52.450850 | controller | "metadata": { 2025-11-21 00:19:52.450873 | controller | "type": "ansible_collection", 2025-11-21 00:19:52.450896 | controller | "version": "5.0.0" 2025-11-21 00:19:52.450919 | controller | }, 2025-11-21 00:19:52.450941 | controller | "name": "ansible.network", 2025-11-21 00:19:52.450963 | controller | "project": "ansible-collections/ansible.network", 2025-11-21 00:19:52.450985 | controller | "url": "https://ce9a38be53519dd9d0fd-508106cf064a80a123607d17d8e88ded.ssl.cf1.rackcdn.com/ansible/37b7b9c4158e4f5da9dd086b2ef3f4cc/artifacts/ansible-network-5.0.0.tar.gz" 2025-11-21 00:19:52.451015 | controller | } 2025-11-21 00:19:52.476146 | 2025-11-21 00:19:52.476369 | TASK [deploy-artifacts : Install require-project collection using ansible-galaxy] 2025-11-21 00:19:53.987025 | controller | Starting galaxy collection install process 2025-11-21 00:19:53.987359 | controller | Process install dependency map 2025-11-21 00:19:56.239069 | controller | [WARNING]: Skipping Galaxy server https://galaxy.ansible.com/api/. Got an 2025-11-21 00:19:56.239625 | controller | unexpected error when getting available versions of collection 2025-11-21 00:19:56.239666 | controller | ansible.netcommon: '/api/v3/plugin/ansible/content/published/collections/index/ 2025-11-21 00:19:56.239677 | controller | ansible/netcommon/versions/' 2025-11-21 00:19:56.239689 | controller | ERROR! Unexpected Exception, this is probably a bug: '/api/v3/plugin/ansible/content/published/collections/index/ansible/netcommon/versions/' 2025-11-21 00:19:56.239794 | controller | to see the full traceback, use -vvv 2025-11-21 00:19:56.899923 | controller | ERROR 2025-11-21 00:19:56.900265 | controller | { 2025-11-21 00:19:56.900331 | controller | "delta": "0:00:03.121504", 2025-11-21 00:19:56.900390 | controller | "end": "2025-11-21 00:19:56.380709", 2025-11-21 00:19:56.900451 | controller | "msg": "non-zero return code", 2025-11-21 00:19:56.900510 | controller | "rc": 250, 2025-11-21 00:19:56.900552 | controller | "start": "2025-11-21 00:19:53.259205" 2025-11-21 00:19:56.900589 | controller | } failure 2025-11-21 00:19:56.904057 | 2025-11-21 00:19:56.904163 | PLAY RECAP 2025-11-21 00:19:56.904259 | controller | ok: 44 changed: 24 unreachable: 0 failed: 1 skipped: 17 rescued: 0 ignored: 0 2025-11-21 00:19:56.904305 | 2025-11-21 00:19:57.067157 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master] 2025-11-21 00:19:57.069325 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2025-11-21 00:19:57.724669 | 2025-11-21 00:19:57.724854 | PLAY [all] 2025-11-21 00:19:57.752651 | 2025-11-21 00:19:57.752830 | TASK [fetch-subunit-output : Find stestr or testr executable] 2025-11-21 00:20:00.764478 | controller | changed: non-zero return code 2025-11-21 00:20:00.780085 | 2025-11-21 00:20:00.780275 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2025-11-21 00:20:00.809908 | controller | skipping: Conditional result was False 2025-11-21 00:20:00.831357 | 2025-11-21 00:20:00.831586 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2025-11-21 00:20:00.880533 | 2025-11-21 00:20:00.881001 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2025-11-21 00:20:00.927265 | 2025-11-21 00:20:00.927580 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2025-11-21 00:20:00.957375 | controller | skipping: Conditional result was False 2025-11-21 00:20:00.975610 | 2025-11-21 00:20:00.975863 | LOOP [fetch-subunit-output : Generate subunit file] 2025-11-21 00:20:01.030761 | 2025-11-21 00:20:01.031061 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2025-11-21 00:20:01.059710 | controller | skipping: Conditional result was False 2025-11-21 00:20:01.080402 | 2025-11-21 00:20:01.080629 | TASK [fetch-subunit-output : Remove the temporary file] 2025-11-21 00:20:01.108840 | controller | skipping: Conditional result was False 2025-11-21 00:20:01.118335 | 2025-11-21 00:20:01.118448 | TASK [fetch-subunit-output : Process and fetch subunit results] 2025-11-21 00:20:01.144797 | controller | skipping: Conditional result was False 2025-11-21 00:20:01.187388 | 2025-11-21 00:20:01.187528 | PLAY RECAP 2025-11-21 00:20:01.187591 | controller | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2025-11-21 00:20:01.187619 | 2025-11-21 00:20:01.365966 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2025-11-21 00:20:01.367308 | POST-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/post.yaml@master] 2025-11-21 00:20:02.114306 | 2025-11-21 00:20:02.114514 | PLAY [all:!appliance*] 2025-11-21 00:20:02.143403 | 2025-11-21 00:20:02.143546 | TASK [unregister the node] 2025-11-21 00:20:02.501868 | controller | skipping: Conditional result was False 2025-11-21 00:20:02.518381 | 2025-11-21 00:20:02.518609 | TASK [include_role : fetch-output] 2025-11-21 00:20:02.581020 | controller | ok 2025-11-21 00:20:02.615478 | 2025-11-21 00:20:02.615604 | TASK [fetch-output : Set log path for multiple nodes] 2025-11-21 00:20:02.690353 | controller | skipping: Conditional result was False 2025-11-21 00:20:02.710439 | 2025-11-21 00:20:02.710621 | TASK [fetch-output : Set log path for single node] 2025-11-21 00:20:02.761043 | controller | ok 2025-11-21 00:20:02.770774 | 2025-11-21 00:20:02.770885 | LOOP [fetch-output : Ensure local output dirs] 2025-11-21 00:20:03.246048 | controller -> localhost | ok: "/var/lib/zuul/builds/53d55a8d43e24d39aa98eaa91c7dc0bf/work/logs" 2025-11-21 00:20:03.495248 | controller -> localhost | changed: "/var/lib/zuul/builds/53d55a8d43e24d39aa98eaa91c7dc0bf/work/artifacts" 2025-11-21 00:20:03.733350 | controller -> localhost | changed: "/var/lib/zuul/builds/53d55a8d43e24d39aa98eaa91c7dc0bf/work/docs" 2025-11-21 00:20:03.754767 | 2025-11-21 00:20:03.755101 | LOOP [fetch-output : Collect logs, artifacts and docs] 2025-11-21 00:20:06.062634 | controller | changed: 2025-11-21 00:20:06.063056 | controller | .d..t...... ./ 2025-11-21 00:20:06.063124 | controller | cd+++++++++ controller/ 2025-11-21 00:20:06.063199 | controller | changed: All items complete 2025-11-21 00:20:06.063245 | 2025-11-21 00:20:08.217540 | controller | changed: .d..t...... ./ 2025-11-21 00:20:10.220126 | controller | changed: .d..t...... ./ 2025-11-21 00:20:10.249571 | 2025-11-21 00:20:10.249748 | TASK [include_role : fetch-output-openshift] 2025-11-21 00:20:10.265545 | controller | skipping: Conditional result was False 2025-11-21 00:20:10.276357 | 2025-11-21 00:20:10.276459 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2025-11-21 00:20:10.306337 | controller | skipping: Conditional result was False 2025-11-21 00:20:10.315030 | controller | skipping: Conditional result was False 2025-11-21 00:20:10.362653 | 2025-11-21 00:20:10.362803 | PLAY [localhost] 2025-11-21 00:20:10.383257 | 2025-11-21 00:20:10.383433 | TASK [Run Zuul manifest role] 2025-11-21 00:20:10.405131 | localhost | ok 2025-11-21 00:20:10.425771 | 2025-11-21 00:20:10.425884 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-11-21 00:20:10.827423 | localhost | changed 2025-11-21 00:20:10.840756 | 2025-11-21 00:20:10.840959 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-11-21 00:20:10.875666 | localhost | ok 2025-11-21 00:20:10.891301 | 2025-11-21 00:20:10.891409 | TASK [Set zuul-log-path fact] 2025-11-21 00:20:10.942927 | localhost | ok 2025-11-21 00:20:10.957081 | 2025-11-21 00:20:10.957156 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-11-21 00:20:10.986674 | localhost | ok 2025-11-21 00:20:10.998049 | 2025-11-21 00:20:10.998162 | LOOP [Run upload-logs-swift role] 2025-11-21 00:20:11.032628 | localhost | Output suppressed because no_log was given 2025-11-21 00:20:11.064490 | 2025-11-21 00:20:11.064616 | TASK [Set zuul-log-path fact] 2025-11-21 00:20:11.089856 | localhost | skipping: Conditional result was False 2025-11-21 00:20:11.100971 | 2025-11-21 00:20:11.101120 | TASK [upload-logs-swift : Ensure logs are readable before uploading] 2025-11-21 00:20:11.474059 | localhost -> localhost | ok: Runtime: 0:00:00.005694 2025-11-21 00:20:11.479777 | 2025-11-21 00:20:11.479843 | TASK [upload-logs-swift : Upload logs to swift]