2026-01-26 00:22:14.851304 | Job console starting... 2026-01-26 00:22:14.860271 | Updating repositories 2026-01-26 00:22:15.015907 | Preparing job workspace 2026-01-26 00:22:18.787437 | Running Ansible setup... 2026-01-26 00:22:26.799035 | PRE-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master] 2026-01-26 00:22:27.401823 | 2026-01-26 00:22:27.401993 | PLAY [localhost] 2026-01-26 00:22:27.410974 | 2026-01-26 00:22:27.411069 | TASK [Gathering Facts] 2026-01-26 00:22:28.414533 | localhost | ok 2026-01-26 00:22:28.434926 | 2026-01-26 00:22:28.435073 | TASK [Setup log path fact] 2026-01-26 00:22:28.457142 | localhost | ok 2026-01-26 00:22:28.474545 | 2026-01-26 00:22:28.474647 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-01-26 00:22:28.505073 | localhost | ok 2026-01-26 00:22:28.516699 | 2026-01-26 00:22:28.516800 | TASK [emit-job-header : Print job information] 2026-01-26 00:22:28.568019 | # Job Information 2026-01-26 00:22:28.568219 | Ansible Version: 2.15.12 2026-01-26 00:22:28.568266 | Job: ansible-test-sanity-docker-stable-2.12 2026-01-26 00:22:28.568297 | Pipeline: periodic 2026-01-26 00:22:28.568325 | Executor: ze04.softwarefactory-project.io 2026-01-26 00:22:28.568359 | Triggered by: https://github.com/ansible-collections/ansible.network 2026-01-26 00:22:28.568402 | Log URL (when completed): https://ansible.softwarefactory-project.io/logs/f0b/ansible/f0bea0a075b94b22aa346244903578f6/ 2026-01-26 00:22:28.568434 | Event ID: 4b9b1c413fcf4b5b911f7b1cc126ee08 2026-01-26 00:22:28.575486 | 2026-01-26 00:22:28.575649 | LOOP [emit-job-header : Print node information] 2026-01-26 00:22:28.691540 | localhost | ok: 2026-01-26 00:22:28.691935 | localhost | # Node Information 2026-01-26 00:22:28.691999 | localhost | Inventory Hostname: controller 2026-01-26 00:22:28.692046 | localhost | Hostname: np0005595231 2026-01-26 00:22:28.692090 | localhost | Username: zuul 2026-01-26 00:22:28.692136 | localhost | Distro: Fedora 37 2026-01-26 00:22:28.692177 | localhost | Provider: ansible-vexxhost-ams1 2026-01-26 00:22:28.692218 | localhost | Region: ams1 2026-01-26 00:22:28.692256 | localhost | Label: ansible-fedora-37-1vcpu 2026-01-26 00:22:28.692295 | localhost | Product Name: OpenStack Nova 2026-01-26 00:22:28.692333 | localhost | Interface IP: 38.129.16.247 2026-01-26 00:22:28.712344 | 2026-01-26 00:22:28.712558 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-01-26 00:22:29.118255 | localhost -> localhost | changed 2026-01-26 00:22:29.130813 | 2026-01-26 00:22:29.130969 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-01-26 00:22:30.020726 | localhost -> localhost | changed 2026-01-26 00:22:30.048723 | 2026-01-26 00:22:30.048812 | PLAY [all:!appliance*] 2026-01-26 00:22:30.064878 | 2026-01-26 00:22:30.064966 | TASK [include_role : start-zuul-console] 2026-01-26 00:22:30.084133 | controller | ok 2026-01-26 00:22:30.099710 | 2026-01-26 00:22:30.099799 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-01-26 00:22:31.135198 | controller | ok 2026-01-26 00:22:31.159548 | 2026-01-26 00:22:31.159730 | TASK [use-our-mirror : Retrieve the IP address] 2026-01-26 00:22:33.555756 | controller | ok: Status code was 404 and not [200]: HTTP Error 404: Not Found 2026-01-26 00:22:33.569770 | 2026-01-26 00:22:33.569904 | TASK [use-our-mirror : Ensure the registry service is running on the mirror] 2026-01-26 00:22:34.513507 | controller | skipping: Conditional result was False 2026-01-26 00:22:34.519994 | 2026-01-26 00:22:34.520062 | TASK [use-our-mirror : Write the IP in /etc/hosts] 2026-01-26 00:22:34.543868 | controller | skipping: Conditional result was False 2026-01-26 00:22:34.550433 | 2026-01-26 00:22:34.550506 | TASK [use-our-mirror : Create the podman configuration directory] 2026-01-26 00:22:34.573722 | controller | skipping: Conditional result was False 2026-01-26 00:22:34.580383 | 2026-01-26 00:22:34.580457 | TASK [use-our-mirror : Copy the podman configuration] 2026-01-26 00:22:34.603697 | controller | skipping: Conditional result was False 2026-01-26 00:22:34.610222 | 2026-01-26 00:22:34.610295 | TASK [use-our-mirror : Copy the Yum configuration (main)] 2026-01-26 00:22:34.633444 | controller | skipping: Conditional result was False 2026-01-26 00:22:34.639266 | 2026-01-26 00:22:34.639331 | TASK [use-our-mirror : Copy the Yum configuration (update)] 2026-01-26 00:22:34.662534 | controller | skipping: Conditional result was False 2026-01-26 00:22:34.673132 | 2026-01-26 00:22:34.673195 | TASK [Disable Fedora Modular] 2026-01-26 00:22:35.782410 | controller | changed 2026-01-26 00:22:35.797141 | 2026-01-26 00:22:35.797281 | TASK [Enable EPEL] 2026-01-26 00:22:35.815610 | controller | skipping: Conditional result was False 2026-01-26 00:22:35.829164 | 2026-01-26 00:22:35.829307 | TASK [Register the RHEL node] 2026-01-26 00:22:36.241278 | 2026-01-26 00:22:36.241499 | TASK [Show the subscription-manager status] 2026-01-26 00:22:36.621779 | controller | skipping: Conditional result was False 2026-01-26 00:22:36.635749 | 2026-01-26 00:22:36.635883 | TASK [Enable EPEL on RHEL] 2026-01-26 00:22:37.041408 | controller | skipping: Conditional result was False 2026-01-26 00:22:37.054302 | 2026-01-26 00:22:37.054438 | TASK [Install git and tox] 2026-01-26 00:24:42.969799 | controller | changed 2026-01-26 00:24:42.984143 | 2026-01-26 00:24:42.984277 | TASK [include_role : prepare-workspace] 2026-01-26 00:24:43.025571 | controller | ok 2026-01-26 00:24:43.062401 | 2026-01-26 00:24:43.062502 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-01-26 00:24:43.909757 | controller | ok 2026-01-26 00:24:43.924282 | 2026-01-26 00:24:43.924439 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-01-26 00:24:59.863264 | controller | Output suppressed because no_log was given 2026-01-26 00:24:59.911640 | 2026-01-26 00:24:59.911769 | TASK [include_role : prepare-workspace-openshift] 2026-01-26 00:24:59.935828 | controller | skipping: Conditional result was False 2026-01-26 00:24:59.957938 | 2026-01-26 00:24:59.958006 | PLAY [all:!appliance] 2026-01-26 00:24:59.975080 | 2026-01-26 00:24:59.975150 | TASK [Run add-build-sshkey role (RSA)] 2026-01-26 00:25:00.005148 | controller | ok 2026-01-26 00:25:00.020387 | 2026-01-26 00:25:00.020468 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-01-26 00:25:00.281548 | controller -> localhost | ok 2026-01-26 00:25:00.288129 | 2026-01-26 00:25:00.288193 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-01-26 00:25:00.310240 | controller | ok 2026-01-26 00:25:00.324224 | controller | included: /var/lib/zuul/builds/f0bea0a075b94b22aa346244903578f6/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-01-26 00:25:00.330384 | 2026-01-26 00:25:00.330450 | TASK [add-build-sshkey : Create Temp SSH key] 2026-01-26 00:25:00.729023 | controller -> localhost | Generating public/private rsa key pair. 2026-01-26 00:25:00.729273 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/f0bea0a075b94b22aa346244903578f6/work/f0bea0a075b94b22aa346244903578f6_id_rsa. 2026-01-26 00:25:00.729316 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/f0bea0a075b94b22aa346244903578f6/work/f0bea0a075b94b22aa346244903578f6_id_rsa.pub. 2026-01-26 00:25:00.729347 | controller -> localhost | The key fingerprint is: 2026-01-26 00:25:00.729376 | controller -> localhost | SHA256:GaDFwtP8iItdCJFMBPrGzakEfYPmlOUwTV3s2GI7Ifs zuul-build-sshkey 2026-01-26 00:25:00.729404 | controller -> localhost | The key's randomart image is: 2026-01-26 00:25:00.729431 | controller -> localhost | +---[RSA 2048]----+ 2026-01-26 00:25:00.729458 | controller -> localhost | |.=**o=oo. | 2026-01-26 00:25:00.729486 | controller -> localhost | |..+B=o=.. | 2026-01-26 00:25:00.729513 | controller -> localhost | |o *.== *. | 2026-01-26 00:25:00.729539 | controller -> localhost | | B ++oB +o | 2026-01-26 00:25:00.729565 | controller -> localhost | | *o+* +S | 2026-01-26 00:25:00.729602 | controller -> localhost | | o..+ o | 2026-01-26 00:25:00.729634 | controller -> localhost | | . . . | 2026-01-26 00:25:00.729662 | controller -> localhost | | E | 2026-01-26 00:25:00.729718 | controller -> localhost | | | 2026-01-26 00:25:00.729752 | controller -> localhost | +----[SHA256]-----+ 2026-01-26 00:25:00.729821 | controller -> localhost | ok: Runtime: 0:00:00.051939 2026-01-26 00:25:00.740199 | 2026-01-26 00:25:00.740304 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-01-26 00:25:00.763221 | controller | ok 2026-01-26 00:25:00.777450 | controller | included: /var/lib/zuul/builds/f0bea0a075b94b22aa346244903578f6/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-01-26 00:25:00.789917 | 2026-01-26 00:25:00.789984 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-01-26 00:25:00.804178 | controller | skipping: Conditional result was False 2026-01-26 00:25:00.814435 | 2026-01-26 00:25:00.814531 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-01-26 00:25:01.882872 | controller | changed 2026-01-26 00:25:01.896234 | 2026-01-26 00:25:01.896507 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-01-26 00:25:02.578550 | controller | ok 2026-01-26 00:25:02.592347 | 2026-01-26 00:25:02.592551 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-01-26 00:25:05.655128 | controller | changed 2026-01-26 00:25:05.669186 | 2026-01-26 00:25:05.669388 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-01-26 00:25:08.770144 | controller | changed 2026-01-26 00:25:08.784204 | 2026-01-26 00:25:08.784378 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-01-26 00:25:08.824392 | controller | skipping: Conditional result was False 2026-01-26 00:25:08.841431 | 2026-01-26 00:25:08.841615 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-01-26 00:25:09.330071 | controller -> localhost | changed 2026-01-26 00:25:09.371469 | 2026-01-26 00:25:09.371734 | TASK [add-build-sshkey : Add back temp key] 2026-01-26 00:25:09.718129 | controller -> localhost | Identity added: /var/lib/zuul/builds/f0bea0a075b94b22aa346244903578f6/work/f0bea0a075b94b22aa346244903578f6_id_rsa (zuul-build-sshkey) 2026-01-26 00:25:09.718333 | controller -> localhost | ok: Runtime: 0:00:00.015528 2026-01-26 00:25:09.724890 | 2026-01-26 00:25:09.724963 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-01-26 00:25:10.736343 | controller | ok 2026-01-26 00:25:10.748110 | 2026-01-26 00:25:10.748240 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-01-26 00:25:10.774447 | controller | skipping: Conditional result was False 2026-01-26 00:25:10.798926 | 2026-01-26 00:25:10.799060 | TASK [Run add-build-sshkey role (ECDSA)] 2026-01-26 00:25:10.826732 | controller | ok 2026-01-26 00:25:10.852054 | 2026-01-26 00:25:10.852151 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-01-26 00:25:11.152120 | controller -> localhost | ok 2026-01-26 00:25:11.166994 | 2026-01-26 00:25:11.167142 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-01-26 00:25:11.195469 | controller | ok 2026-01-26 00:25:11.219241 | controller | included: /var/lib/zuul/builds/f0bea0a075b94b22aa346244903578f6/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-01-26 00:25:11.229076 | 2026-01-26 00:25:11.229179 | TASK [add-build-sshkey : Create Temp SSH key] 2026-01-26 00:25:11.561761 | controller -> localhost | Generating public/private ecdsa key pair. 2026-01-26 00:25:11.561959 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/f0bea0a075b94b22aa346244903578f6/work/f0bea0a075b94b22aa346244903578f6_id_ecdsa. 2026-01-26 00:25:11.561991 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/f0bea0a075b94b22aa346244903578f6/work/f0bea0a075b94b22aa346244903578f6_id_ecdsa.pub. 2026-01-26 00:25:11.562023 | controller -> localhost | The key fingerprint is: 2026-01-26 00:25:11.562045 | controller -> localhost | SHA256:m0bNviXy14EWUSEY2FcfuRXm8uoIQknhT86CUH9mTO8 zuul-build-sshkey 2026-01-26 00:25:11.562068 | controller -> localhost | The key's randomart image is: 2026-01-26 00:25:11.562095 | controller -> localhost | +---[ECDSA 521]---+ 2026-01-26 00:25:11.562121 | controller -> localhost | | . . +.o.o+=o| 2026-01-26 00:25:11.562148 | controller -> localhost | | . o = + o.ooo| 2026-01-26 00:25:11.562174 | controller -> localhost | | . + * o o .+| 2026-01-26 00:25:11.562200 | controller -> localhost | | . o Xo. . o. | 2026-01-26 00:25:11.562227 | controller -> localhost | | . +S+oE o . | 2026-01-26 00:25:11.562251 | controller -> localhost | | ...+ o o | 2026-01-26 00:25:11.562278 | controller -> localhost | | .=.o..o . | 2026-01-26 00:25:11.562305 | controller -> localhost | | ..o.++ . | 2026-01-26 00:25:11.562330 | controller -> localhost | | oo . | 2026-01-26 00:25:11.562356 | controller -> localhost | +----[SHA256]-----+ 2026-01-26 00:25:11.562418 | controller -> localhost | ok: Runtime: 0:00:00.014591 2026-01-26 00:25:11.571096 | 2026-01-26 00:25:11.571192 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-01-26 00:25:11.602638 | controller | ok 2026-01-26 00:25:11.612271 | controller | included: /var/lib/zuul/builds/f0bea0a075b94b22aa346244903578f6/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-01-26 00:25:11.625736 | 2026-01-26 00:25:11.625819 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-01-26 00:25:11.650305 | controller | skipping: Conditional result was False 2026-01-26 00:25:11.656997 | 2026-01-26 00:25:11.657073 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-01-26 00:25:12.611463 | controller | changed 2026-01-26 00:25:12.626219 | 2026-01-26 00:25:12.626396 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-01-26 00:25:13.288503 | controller | ok 2026-01-26 00:25:13.301365 | 2026-01-26 00:25:13.301502 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-01-26 00:25:16.358332 | controller | changed 2026-01-26 00:25:16.371312 | 2026-01-26 00:25:16.371632 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-01-26 00:25:19.482481 | controller | changed 2026-01-26 00:25:19.496935 | 2026-01-26 00:25:19.497109 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-01-26 00:25:19.523884 | controller | skipping: Conditional result was False 2026-01-26 00:25:19.539028 | 2026-01-26 00:25:19.539206 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-01-26 00:25:19.840523 | controller -> localhost | changed 2026-01-26 00:25:19.852634 | 2026-01-26 00:25:19.852751 | TASK [add-build-sshkey : Add back temp key] 2026-01-26 00:25:20.173078 | controller -> localhost | Identity added: /var/lib/zuul/builds/f0bea0a075b94b22aa346244903578f6/work/f0bea0a075b94b22aa346244903578f6_id_ecdsa (zuul-build-sshkey) 2026-01-26 00:25:20.173505 | controller -> localhost | ok: Runtime: 0:00:00.016445 2026-01-26 00:25:20.192540 | 2026-01-26 00:25:20.192789 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-01-26 00:25:20.940369 | controller | ok 2026-01-26 00:25:20.953421 | 2026-01-26 00:25:20.953738 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-01-26 00:25:20.991153 | controller | skipping: Conditional result was False 2026-01-26 00:25:21.017751 | 2026-01-26 00:25:21.017916 | TASK [include_role : remove-zuul-sshkey] 2026-01-26 00:25:21.044379 | controller | skipping: Conditional result was False 2026-01-26 00:25:21.061081 | 2026-01-26 00:25:21.061251 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-01-26 00:25:21.752631 | controller | ok: "logs" 2026-01-26 00:25:21.753043 | controller | ok: All items complete 2026-01-26 00:25:21.753100 | 2026-01-26 00:25:22.404982 | controller | ok: "artifacts" 2026-01-26 00:25:23.041286 | controller | ok: "docs" 2026-01-26 00:25:23.076855 | 2026-01-26 00:25:23.077082 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-01-26 00:25:23.776823 | controller | changed: "logs" 2026-01-26 00:25:24.400572 | controller | changed: "artifacts" 2026-01-26 00:25:25.067399 | controller | changed: "docs" 2026-01-26 00:25:25.117589 | 2026-01-26 00:25:25.117796 | PLAY RECAP 2026-01-26 00:25:25.117860 | controller | ok: 30 changed: 16 unreachable: 0 failed: 0 skipped: 18 rescued: 0 ignored: 0 2026-01-26 00:25:25.117900 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-01-26 00:25:25.117928 | 2026-01-26 00:25:25.243561 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master] 2026-01-26 00:25:25.245285 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2026-01-26 00:25:25.829084 | 2026-01-26 00:25:25.829205 | PLAY [all] 2026-01-26 00:25:25.851165 | 2026-01-26 00:25:25.851293 | TASK [Install binary dependencies] 2026-01-26 00:25:25.922424 | controller | ok 2026-01-26 00:25:25.942382 | 2026-01-26 00:25:25.942495 | TASK [bindep : Include find tasks] 2026-01-26 00:25:25.971648 | controller | ok 2026-01-26 00:25:25.979139 | controller | included: /var/lib/zuul/builds/f0bea0a075b94b22aa346244903578f6/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find.yaml 2026-01-26 00:25:25.985382 | 2026-01-26 00:25:25.985447 | TASK [bindep : Look for bindep.txt] 2026-01-26 00:25:26.981159 | controller | ok 2026-01-26 00:25:26.995543 | 2026-01-26 00:25:26.995763 | TASK [bindep : Define bindep_file fact] 2026-01-26 00:25:27.022939 | controller | skipping: Conditional result was False 2026-01-26 00:25:27.031370 | 2026-01-26 00:25:27.031479 | TASK [bindep : Look for other-requirements.txt] 2026-01-26 00:25:27.696131 | controller | ok 2026-01-26 00:25:27.709710 | 2026-01-26 00:25:27.709858 | TASK [bindep : Define bindep_file fact] 2026-01-26 00:25:27.748290 | controller | skipping: Conditional result was False 2026-01-26 00:25:27.762097 | 2026-01-26 00:25:27.762238 | TASK [bindep : Look for bindep fallback file] 2026-01-26 00:25:27.809589 | controller | skipping: Conditional result was False 2026-01-26 00:25:27.822727 | 2026-01-26 00:25:27.822870 | TASK [bindep : Define bindep_file fact] 2026-01-26 00:25:27.859777 | controller | skipping: Conditional result was False 2026-01-26 00:25:27.872937 | 2026-01-26 00:25:27.873074 | TASK [bindep : Include bindep tasks] 2026-01-26 00:25:27.910078 | controller | skipping: Conditional result was False 2026-01-26 00:25:27.923164 | 2026-01-26 00:25:27.923299 | TASK [bindep : Include install tasks] 2026-01-26 00:25:27.950150 | controller | skipping: Conditional result was False 2026-01-26 00:25:27.964402 | 2026-01-26 00:25:27.964540 | LOOP [bindep : Include package tasks] 2026-01-26 00:25:28.048403 | 2026-01-26 00:25:28.048799 | TASK [Run test-setup role] 2026-01-26 00:25:28.076925 | controller | ok 2026-01-26 00:25:28.112194 | 2026-01-26 00:25:28.112322 | TASK [test-setup : Check if project's tools/test-setup.sh exists] 2026-01-26 00:25:28.775219 | controller | ok 2026-01-26 00:25:28.783357 | 2026-01-26 00:25:28.783444 | TASK [test-setup : Run tools/test-setup.sh] 2026-01-26 00:25:29.154098 | controller | skipping: Conditional result was False 2026-01-26 00:25:29.198374 | 2026-01-26 00:25:29.198477 | PLAY RECAP 2026-01-26 00:25:29.198536 | controller | ok: 4 changed: 0 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2026-01-26 00:25:29.198571 | 2026-01-26 00:25:29.296549 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2026-01-26 00:25:29.298243 | PRE-RUN START: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master] 2026-01-26 00:25:29.943162 | 2026-01-26 00:25:29.943284 | PLAY [controller] 2026-01-26 00:25:29.963533 | 2026-01-26 00:25:29.963645 | TASK [Create the /root directory] 2026-01-26 00:25:31.116606 | controller | ok 2026-01-26 00:25:31.130636 | 2026-01-26 00:25:31.130832 | TASK [Install glibc-langpack-en] 2026-01-26 00:25:39.357585 | controller | ok: Nothing to do 2026-01-26 00:25:39.370101 | 2026-01-26 00:25:39.370240 | TASK [Ensure controller directory exists] 2026-01-26 00:25:40.183121 | controller | changed 2026-01-26 00:25:40.196578 | 2026-01-26 00:25:40.196791 | TASK [Install container runtime] 2026-01-26 00:25:40.278167 | controller | ok 2026-01-26 00:25:40.336823 | 2026-01-26 00:25:40.336961 | LOOP [ensure-podman : Find distribution installation] 2026-01-26 00:25:40.380547 | controller | ok: "/var/lib/zuul/builds/f0bea0a075b94b22aa346244903578f6/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-podman/tasks/RedHat.yaml" 2026-01-26 00:25:40.392404 | controller | included: /var/lib/zuul/builds/f0bea0a075b94b22aa346244903578f6/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-podman/tasks/RedHat.yaml 2026-01-26 00:25:40.401709 | 2026-01-26 00:25:40.401811 | TASK [ensure-podman : Install podman (RedHat)] 2026-01-26 00:27:43.268341 | controller | changed 2026-01-26 00:27:43.283343 | 2026-01-26 00:27:43.283490 | TASK [ensure-podman : Fetch podman version] 2026-01-26 00:27:44.409228 | controller | Client: Podman Engine 2026-01-26 00:27:44.452645 | controller | Version: 4.6.2 2026-01-26 00:27:44.452738 | controller | API Version: 4.6.2 2026-01-26 00:27:44.452748 | controller | Go Version: go1.19.12 2026-01-26 00:27:44.452775 | controller | Built: Mon Aug 28 19:38:31 2023 2026-01-26 00:27:44.452784 | controller | OS/Arch: linux/amd64 2026-01-26 00:27:44.654054 | controller | ok: Runtime: 0:00:00.284228 2026-01-26 00:27:44.668858 | 2026-01-26 00:27:44.669029 | TASK [ensure-podman : Print podman version installed] 2026-01-26 00:27:44.699039 | Podman version: Client: Podman Engine 2026-01-26 00:27:44.699455 | Version: 4.6.2 2026-01-26 00:27:44.699524 | API Version: 4.6.2 2026-01-26 00:27:44.699570 | Go Version: go1.19.12 2026-01-26 00:27:44.699611 | Built: Mon Aug 28 19:38:31 2023 2026-01-26 00:27:44.699768 | OS/Arch: linux/amd64 2026-01-26 00:27:44.713318 | 2026-01-26 00:27:44.713449 | TASK [ensure-podman : Validate podman engine] 2026-01-26 00:27:45.078011 | controller | skipping: Conditional result was False 2026-01-26 00:27:45.092241 | 2026-01-26 00:27:45.092397 | TASK [ensure-podman : Set up docker compatability socket] 2026-01-26 00:27:45.109187 | controller | skipping: Conditional result was False 2026-01-26 00:27:45.134299 | 2026-01-26 00:27:45.134436 | TASK [Ensure python3.8 is present] 2026-01-26 00:27:45.151343 | controller | skipping: Conditional result was False 2026-01-26 00:27:45.166215 | 2026-01-26 00:27:45.166353 | TASK [Run our-ensure-python role (Fedora only for now)] 2026-01-26 00:27:45.194046 | controller | ok 2026-01-26 00:27:45.227052 | 2026-01-26 00:27:45.227169 | TASK [our-ensure-python : Install the right Python version (rpm)] 2026-01-26 00:27:48.773252 | controller | ok: Nothing to do 2026-01-26 00:27:48.786380 | 2026-01-26 00:27:48.786514 | TASK [our-ensure-python : Also install python3-devel] 2026-01-26 00:28:03.510593 | controller | changed 2026-01-26 00:28:03.534391 | 2026-01-26 00:28:03.534526 | TASK [Run ensure-virtualenv role] 2026-01-26 00:28:03.559089 | controller | ok 2026-01-26 00:28:03.588394 | 2026-01-26 00:28:03.588517 | TASK [ensure-virtualenv : Check if virtualenv is installed] 2026-01-26 00:28:04.383228 | controller | /usr/bin/virtualenv 2026-01-26 00:28:04.949309 | controller | ok: Runtime: 0:00:00.005662 2026-01-26 00:28:04.962791 | 2026-01-26 00:28:04.962983 | LOOP [ensure-virtualenv : Install virtualenv package] 2026-01-26 00:28:04.997970 | controller | skipping: Conditional result was False 2026-01-26 00:28:04.998415 | controller | ok: All items complete 2026-01-26 00:28:04.998474 | 2026-01-26 00:28:05.027601 | 2026-01-26 00:28:05.027843 | TASK [Find the full path of the Python interpreter] 2026-01-26 00:28:05.823220 | controller | /usr/bin/python3.10 2026-01-26 00:28:06.398993 | controller | ok 2026-01-26 00:28:06.412132 | 2026-01-26 00:28:06.412274 | TASK [Create virtualenv for ansible-test with a specific interpreter] 2026-01-26 00:28:08.424497 | controller | created virtual environment CPython3.10.13.final.0-64 in 905ms 2026-01-26 00:28:08.474561 | controller | creator CPython3Posix(dest=/home/zuul/venv, clear=False, no_vcs_ignore=False, global=False) 2026-01-26 00:28:08.474622 | controller | seeder FromAppData(extra_search_dir=/usr/share/python-wheels,download=False, pip=bundle, setuptools=bundle, wheel=bundle, via=copy, app_data_dir=/home/zuul/.local/share/virtualenv) 2026-01-26 00:28:08.474637 | controller | added seed packages: pip==22.2.2, setuptools==62.6.0, wheel==0.37.1 2026-01-26 00:28:08.474660 | controller | activators BashActivator,CShellActivator,FishActivator,NushellActivator,PowerShellActivator,PythonActivator 2026-01-26 00:28:08.813304 | controller | changed 2026-01-26 00:28:08.826859 | 2026-01-26 00:28:08.826996 | TASK [Set selinux package] 2026-01-26 00:28:08.866199 | controller | ok 2026-01-26 00:28:08.877805 | 2026-01-26 00:28:08.877940 | TASK [Set selinux package (Fedora)] 2026-01-26 00:28:08.928054 | controller | ok 2026-01-26 00:28:08.940301 | 2026-01-26 00:28:08.940436 | TASK [Install selinux into virtualenv] 2026-01-26 00:28:11.761941 | controller | Collecting selinux-please-lie-to-me 2026-01-26 00:28:11.819887 | controller | Downloading selinux_please_lie_to_me-1.0.1-py2.py3-none-any.whl (3.8 kB) 2026-01-26 00:28:12.381304 | controller | Collecting setuptools<50.0.0 2026-01-26 00:28:12.386661 | controller | Downloading setuptools-49.6.0-py3-none-any.whl (803 kB) 2026-01-26 00:28:12.430782 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 803.3/803.3 kB 20.5 MB/s eta 0:00:00 2026-01-26 00:28:12.627626 | controller | Installing collected packages: setuptools, selinux-please-lie-to-me 2026-01-26 00:28:12.627848 | controller | Attempting uninstall: setuptools 2026-01-26 00:28:12.628983 | controller | Found existing installation: setuptools 62.6.0 2026-01-26 00:28:12.756178 | controller | Uninstalling setuptools-62.6.0: 2026-01-26 00:28:12.771209 | controller | Successfully uninstalled setuptools-62.6.0 2026-01-26 00:28:13.527741 | controller | Successfully installed selinux-please-lie-to-me-1.0.1 setuptools-49.6.0 2026-01-26 00:28:13.773919 | controller | 2026-01-26 00:28:14.033482 | controller | [notice] A new release of pip available: 22.2.2 -> 25.3 2026-01-26 00:28:14.033533 | controller | [notice] To update, run: /home/zuul/venv/bin/python -m pip install --upgrade pip 2026-01-26 00:28:14.330756 | controller | ok: Runtime: 0:00:04.287310 2026-01-26 00:28:14.343388 | 2026-01-26 00:28:14.343525 | TASK [Install pytest-forked into virtualenv] 2026-01-26 00:28:15.821302 | controller | Collecting pytest-forked 2026-01-26 00:28:15.886275 | controller | Downloading pytest_forked-1.6.0-py3-none-any.whl (4.9 kB) 2026-01-26 00:28:15.981756 | controller | Collecting py 2026-01-26 00:28:15.986264 | controller | Downloading py-1.11.0-py2.py3-none-any.whl (98 kB) 2026-01-26 00:28:16.029805 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 98.7/98.7 kB 2.2 MB/s eta 0:00:00 2026-01-26 00:28:16.262165 | controller | Collecting pytest>=3.10 2026-01-26 00:28:16.266880 | controller | Downloading pytest-9.0.2-py3-none-any.whl (374 kB) 2026-01-26 00:28:16.293051 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 374.8/374.8 kB 16.7 MB/s eta 0:00:00 2026-01-26 00:28:16.408905 | controller | Collecting packaging>=22 2026-01-26 00:28:16.413996 | controller | Downloading packaging-26.0-py3-none-any.whl (74 kB) 2026-01-26 00:28:16.437722 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 74.4/74.4 kB 3.7 MB/s eta 0:00:00 2026-01-26 00:28:16.546042 | controller | Collecting tomli>=1 2026-01-26 00:28:16.551401 | controller | Downloading tomli-2.4.0-py3-none-any.whl (14 kB) 2026-01-26 00:28:16.602767 | controller | Collecting iniconfig>=1.0.1 2026-01-26 00:28:16.612936 | controller | Downloading iniconfig-2.3.0-py3-none-any.whl (7.5 kB) 2026-01-26 00:28:16.676955 | controller | Collecting pluggy<2,>=1.5 2026-01-26 00:28:16.681420 | controller | Downloading pluggy-1.6.0-py3-none-any.whl (20 kB) 2026-01-26 00:28:16.734576 | controller | Collecting exceptiongroup>=1 2026-01-26 00:28:16.743168 | controller | Downloading exceptiongroup-1.3.1-py3-none-any.whl (16 kB) 2026-01-26 00:28:16.832905 | controller | Collecting pygments>=2.7.2 2026-01-26 00:28:16.840309 | controller | Downloading pygments-2.19.2-py3-none-any.whl (1.2 MB) 2026-01-26 00:28:16.880800 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 1.2/1.2 MB 33.0 MB/s eta 0:00:00 2026-01-26 00:28:16.991640 | controller | Collecting typing-extensions>=4.6.0 2026-01-26 00:28:17.000298 | controller | Downloading typing_extensions-4.15.0-py3-none-any.whl (44 kB) 2026-01-26 00:28:17.015797 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 44.6/44.6 kB 4.8 MB/s eta 0:00:00 2026-01-26 00:28:17.150803 | controller | Installing collected packages: typing-extensions, tomli, pygments, py, pluggy, packaging, iniconfig, exceptiongroup, pytest, pytest-forked 2026-01-26 00:28:19.549787 | controller | Successfully installed exceptiongroup-1.3.1 iniconfig-2.3.0 packaging-26.0 pluggy-1.6.0 py-1.11.0 pygments-2.19.2 pytest-9.0.2 pytest-forked-1.6.0 tomli-2.4.0 typing-extensions-4.15.0 2026-01-26 00:28:19.554661 | controller | 2026-01-26 00:28:19.802726 | controller | [notice] A new release of pip available: 22.2.2 -> 25.3 2026-01-26 00:28:19.802785 | controller | [notice] To update, run: /home/zuul/venv/bin/python -m pip install --upgrade pip 2026-01-26 00:28:20.243438 | controller | ok: Runtime: 0:00:04.681864 2026-01-26 00:28:20.251847 | 2026-01-26 00:28:20.251954 | TASK [Update pip] 2026-01-26 00:28:21.663218 | controller | Requirement already satisfied: pip in ./venv/lib/python3.10/site-packages (22.2.2) 2026-01-26 00:28:21.914181 | controller | Collecting pip 2026-01-26 00:28:21.976731 | controller | Downloading pip-25.3-py3-none-any.whl (1.8 MB) 2026-01-26 00:28:22.048056 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 1.8/1.8 MB 27.3 MB/s eta 0:00:00 2026-01-26 00:28:22.160864 | controller | Installing collected packages: pip 2026-01-26 00:28:22.161233 | controller | Attempting uninstall: pip 2026-01-26 00:28:22.162612 | controller | Found existing installation: pip 22.2.2 2026-01-26 00:28:22.448589 | controller | Uninstalling pip-22.2.2: 2026-01-26 00:28:22.479540 | controller | Successfully uninstalled pip-22.2.2 2026-01-26 00:28:24.117147 | controller | Successfully installed pip-25.3 2026-01-26 00:28:24.619144 | controller | ok: Runtime: 0:00:03.276789 2026-01-26 00:28:24.632442 | 2026-01-26 00:28:24.632578 | TASK [Ensure pip temp directory exists in /var/tmp] 2026-01-26 00:28:25.489624 | controller | changed 2026-01-26 00:28:25.502211 | 2026-01-26 00:28:25.502347 | TASK [Install ansible into virtualenv] 2026-01-26 00:28:26.737421 | controller | Processing ./src/github.com/ansible/ansible 2026-01-26 00:28:26.742493 | controller | Installing build dependencies: started 2026-01-26 00:28:28.699073 | controller | Installing build dependencies: finished with status 'done' 2026-01-26 00:28:28.699414 | controller | Getting requirements to build wheel: started 2026-01-26 00:28:29.981794 | controller | Getting requirements to build wheel: finished with status 'done' 2026-01-26 00:28:29.983225 | controller | Preparing metadata (pyproject.toml): started 2026-01-26 00:28:30.902181 | controller | Preparing metadata (pyproject.toml): finished with status 'done' 2026-01-26 00:28:31.063300 | controller | Collecting jinja2 (from ansible-core==2.12.10.post0) 2026-01-26 00:28:31.170309 | controller | Downloading jinja2-3.1.6-py3-none-any.whl.metadata (2.9 kB) 2026-01-26 00:28:31.296503 | controller | Collecting PyYAML (from ansible-core==2.12.10.post0) 2026-01-26 00:28:31.302480 | controller | Downloading pyyaml-6.0.3-cp310-cp310-manylinux2014_x86_64.manylinux_2_17_x86_64.manylinux_2_28_x86_64.whl.metadata (2.4 kB) 2026-01-26 00:28:31.806212 | controller | Collecting cryptography (from ansible-core==2.12.10.post0) 2026-01-26 00:28:31.811363 | controller | Downloading cryptography-46.0.3-cp38-abi3-manylinux_2_34_x86_64.whl.metadata (5.7 kB) 2026-01-26 00:28:31.837467 | controller | Requirement already satisfied: packaging in ./venv/lib/python3.10/site-packages (from ansible-core==2.12.10.post0) (26.0) 2026-01-26 00:28:31.866866 | controller | Collecting resolvelib<0.6.0,>=0.5.3 (from ansible-core==2.12.10.post0) 2026-01-26 00:28:31.873833 | controller | Downloading resolvelib-0.5.4-py2.py3-none-any.whl.metadata (3.7 kB) 2026-01-26 00:28:32.155800 | controller | Collecting cffi>=2.0.0 (from cryptography->ansible-core==2.12.10.post0) 2026-01-26 00:28:32.162090 | controller | Downloading cffi-2.0.0-cp310-cp310-manylinux2014_x86_64.manylinux_2_17_x86_64.whl.metadata (2.6 kB) 2026-01-26 00:28:32.178470 | controller | Requirement already satisfied: typing-extensions>=4.13.2 in ./venv/lib/python3.10/site-packages (from cryptography->ansible-core==2.12.10.post0) (4.15.0) 2026-01-26 00:28:32.202416 | controller | Collecting pycparser (from cffi>=2.0.0->cryptography->ansible-core==2.12.10.post0) 2026-01-26 00:28:32.208388 | controller | Downloading pycparser-3.0-py3-none-any.whl.metadata (8.2 kB) 2026-01-26 00:28:32.353428 | controller | Collecting MarkupSafe>=2.0 (from jinja2->ansible-core==2.12.10.post0) 2026-01-26 00:28:32.359282 | controller | Downloading markupsafe-3.0.3-cp310-cp310-manylinux2014_x86_64.manylinux_2_17_x86_64.manylinux_2_28_x86_64.whl.metadata (2.7 kB) 2026-01-26 00:28:32.377895 | controller | Downloading resolvelib-0.5.4-py2.py3-none-any.whl (12 kB) 2026-01-26 00:28:32.408587 | controller | Downloading cryptography-46.0.3-cp38-abi3-manylinux_2_34_x86_64.whl (4.5 MB) 2026-01-26 00:28:32.488662 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 4.5/4.5 MB 80.5 MB/s 0:00:00 2026-01-26 00:28:32.493980 | controller | Downloading cffi-2.0.0-cp310-cp310-manylinux2014_x86_64.manylinux_2_17_x86_64.whl (216 kB) 2026-01-26 00:28:32.510422 | controller | Downloading jinja2-3.1.6-py3-none-any.whl (134 kB) 2026-01-26 00:28:32.527692 | controller | Downloading markupsafe-3.0.3-cp310-cp310-manylinux2014_x86_64.manylinux_2_17_x86_64.manylinux_2_28_x86_64.whl (20 kB) 2026-01-26 00:28:32.544457 | controller | Downloading pycparser-3.0-py3-none-any.whl (48 kB) 2026-01-26 00:28:32.561210 | controller | Downloading pyyaml-6.0.3-cp310-cp310-manylinux2014_x86_64.manylinux_2_17_x86_64.manylinux_2_28_x86_64.whl (770 kB) 2026-01-26 00:28:32.585347 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 770.3/770.3 kB 31.6 MB/s 0:00:00 2026-01-26 00:28:32.603847 | controller | Building wheels for collected packages: ansible-core 2026-01-26 00:28:32.606052 | controller | Building wheel for ansible-core (pyproject.toml): started 2026-01-26 00:28:35.527697 | controller | Building wheel for ansible-core (pyproject.toml): finished with status 'done' 2026-01-26 00:28:35.531086 | controller | Created wheel for ansible-core: filename=ansible_core-2.12.10.post0-py3-none-any.whl size=2117520 sha256=68f490681c43868a74e17fbe69573d3c74f7dcee4fd3d48f1e4c0398d01f83d0 2026-01-26 00:28:35.531431 | controller | Stored in directory: /var/tmp/ansible-test-pip/pip-ephem-wheel-cache-u2xsb4a9/wheels/68/27/b0/9b068d12b42b734c64dc438319298ad4c1c5027b13ccb3a660 2026-01-26 00:28:35.544023 | controller | Successfully built ansible-core 2026-01-26 00:28:35.604403 | controller | Installing collected packages: resolvelib, PyYAML, pycparser, MarkupSafe, jinja2, cffi, cryptography, ansible-core 2026-01-26 00:28:38.160413 | controller | 2026-01-26 00:28:38.165787 | controller | Successfully installed MarkupSafe-3.0.3 PyYAML-6.0.3 ansible-core-2.12.10.post0 cffi-2.0.0 cryptography-46.0.3 jinja2-3.1.6 pycparser-3.0 resolvelib-0.5.4 2026-01-26 00:28:38.922464 | controller | ok: Runtime: 0:00:12.286118 2026-01-26 00:28:38.936865 | 2026-01-26 00:28:38.937010 | TASK [Setup download-artifact-fork role] 2026-01-26 00:28:38.965424 | controller | ok 2026-01-26 00:28:39.000120 | 2026-01-26 00:28:39.000247 | TASK [download-artifact-fork : Parse inventory response] 2026-01-26 00:28:39.042375 | controller | ok 2026-01-26 00:28:39.050573 | 2026-01-26 00:28:39.050659 | TASK [download-artifact-fork : Fetch info from Zuul API] 2026-01-26 00:28:39.075520 | controller | skipping: Conditional result was False 2026-01-26 00:28:39.089885 | 2026-01-26 00:28:39.090066 | TASK [download-artifact-fork : Parse build response] 2026-01-26 00:28:39.118615 | controller | skipping: Conditional result was False 2026-01-26 00:28:39.127555 | 2026-01-26 00:28:39.127650 | TASK [download-artifact-fork : Ensure artifacts directory exists] 2026-01-26 00:28:39.972596 | controller | changed 2026-01-26 00:28:39.978504 | 2026-01-26 00:28:39.978568 | LOOP [download-artifact-fork : Download archive by type] 2026-01-26 00:28:40.013002 | controller | skipping: Conditional result was False 2026-01-26 00:28:40.013586 | 2026-01-26 00:28:41.945651 | controller | changed: OK (20209 bytes) 2026-01-26 00:28:41.980653 | 2026-01-26 00:28:41.980828 | TASK [deploy-artifacts : define __collections] 2026-01-26 00:28:42.004547 | controller | ok 2026-01-26 00:28:42.016486 | 2026-01-26 00:28:42.016711 | LOOP [deploy-artifacts : Create list of collections] 2026-01-26 00:28:42.043818 | controller | skipping: Conditional result was False 2026-01-26 00:28:42.056755 | controller | ok: 2026-01-26 00:28:42.056839 | controller | { 2026-01-26 00:28:42.056874 | controller | "branch": "main", 2026-01-26 00:28:42.056902 | controller | "job": "build-ansible-collection", 2026-01-26 00:28:42.056927 | controller | "metadata": { 2026-01-26 00:28:42.056952 | controller | "type": "ansible_collection", 2026-01-26 00:28:42.056978 | controller | "version": "5.0.0" 2026-01-26 00:28:42.057002 | controller | }, 2026-01-26 00:28:42.057028 | controller | "name": "ansible.network", 2026-01-26 00:28:42.057052 | controller | "project": "ansible-collections/ansible.network", 2026-01-26 00:28:42.057077 | controller | "url": "https://108c8d23af627e4d1393-4faa70ad57055de6f9b2bdd48f139317.ssl.cf2.rackcdn.com/ansible/90602f8673bc4ffaa5e8bce2f09adbe0/artifacts/ansible-network-5.0.0.tar.gz" 2026-01-26 00:28:42.057110 | controller | } 2026-01-26 00:28:42.075399 | 2026-01-26 00:28:42.075567 | TASK [deploy-artifacts : Install require-project collection using ansible-galaxy] 2026-01-26 00:28:43.498020 | controller | Starting galaxy collection install process 2026-01-26 00:28:43.498198 | controller | Process install dependency map 2026-01-26 00:28:55.211474 | controller | ERROR! Error when getting collection version metadata for ansible.utils:6.0.1 from default (https://galaxy.ansible.com/api/) (HTTP Code: 500, Message: Internal Server Error Code: Unknown) 2026-01-26 00:28:55.954782 | controller | ERROR 2026-01-26 00:28:55.955295 | controller | { 2026-01-26 00:28:55.955375 | controller | "delta": "0:00:12.755518", 2026-01-26 00:28:55.955428 | controller | "end": "2026-01-26 00:28:55.498314", 2026-01-26 00:28:55.955476 | controller | "msg": "non-zero return code", 2026-01-26 00:28:55.955515 | controller | "rc": 1, 2026-01-26 00:28:55.955554 | controller | "start": "2026-01-26 00:28:42.742796" 2026-01-26 00:28:55.955628 | controller | } failure 2026-01-26 00:28:55.959401 | 2026-01-26 00:28:55.959516 | PLAY RECAP 2026-01-26 00:28:55.959612 | controller | ok: 24 changed: 13 unreachable: 0 failed: 1 skipped: 6 rescued: 0 ignored: 0 2026-01-26 00:28:55.959659 | 2026-01-26 00:28:56.169489 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master] 2026-01-26 00:28:56.171658 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2026-01-26 00:28:56.781152 | 2026-01-26 00:28:56.781265 | PLAY [all] 2026-01-26 00:28:56.802494 | 2026-01-26 00:28:56.802582 | TASK [fetch-subunit-output : Find stestr or testr executable] 2026-01-26 00:28:58.726441 | controller | changed: non-zero return code 2026-01-26 00:28:58.740075 | 2026-01-26 00:28:58.740249 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2026-01-26 00:28:58.769242 | controller | skipping: Conditional result was False 2026-01-26 00:28:58.778535 | 2026-01-26 00:28:58.778644 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2026-01-26 00:28:58.813854 | 2026-01-26 00:28:58.814034 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2026-01-26 00:28:58.853229 | 2026-01-26 00:28:58.853489 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2026-01-26 00:28:58.878716 | controller | skipping: Conditional result was False 2026-01-26 00:28:58.887082 | 2026-01-26 00:28:58.887183 | LOOP [fetch-subunit-output : Generate subunit file] 2026-01-26 00:28:58.921966 | 2026-01-26 00:28:58.922187 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2026-01-26 00:28:58.937297 | controller | skipping: Conditional result was False 2026-01-26 00:28:58.946621 | 2026-01-26 00:28:58.946772 | TASK [fetch-subunit-output : Remove the temporary file] 2026-01-26 00:28:58.961787 | controller | skipping: Conditional result was False 2026-01-26 00:28:58.971237 | 2026-01-26 00:28:58.971340 | TASK [fetch-subunit-output : Process and fetch subunit results] 2026-01-26 00:28:58.986194 | controller | skipping: Conditional result was False 2026-01-26 00:28:59.024149 | 2026-01-26 00:28:59.024285 | PLAY RECAP 2026-01-26 00:28:59.024332 | controller | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2026-01-26 00:28:59.024353 | 2026-01-26 00:28:59.127003 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2026-01-26 00:28:59.127837 | POST-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/post.yaml@master] 2026-01-26 00:28:59.697278 | 2026-01-26 00:28:59.697401 | PLAY [all:!appliance*] 2026-01-26 00:28:59.721010 | 2026-01-26 00:28:59.721098 | TASK [unregister the node] 2026-01-26 00:29:00.071650 | controller | skipping: Conditional result was False 2026-01-26 00:29:00.085307 | 2026-01-26 00:29:00.085561 | TASK [include_role : fetch-output] 2026-01-26 00:29:00.136510 | controller | ok 2026-01-26 00:29:00.176486 | 2026-01-26 00:29:00.176598 | TASK [fetch-output : Set log path for multiple nodes] 2026-01-26 00:29:00.233267 | controller | skipping: Conditional result was False 2026-01-26 00:29:00.243628 | 2026-01-26 00:29:00.243779 | TASK [fetch-output : Set log path for single node] 2026-01-26 00:29:00.286937 | controller | ok 2026-01-26 00:29:00.294453 | 2026-01-26 00:29:00.294538 | LOOP [fetch-output : Ensure local output dirs] 2026-01-26 00:29:00.687391 | controller -> localhost | ok: "/var/lib/zuul/builds/f0bea0a075b94b22aa346244903578f6/work/logs" 2026-01-26 00:29:00.919546 | controller -> localhost | changed: "/var/lib/zuul/builds/f0bea0a075b94b22aa346244903578f6/work/artifacts" 2026-01-26 00:29:01.152154 | controller -> localhost | changed: "/var/lib/zuul/builds/f0bea0a075b94b22aa346244903578f6/work/docs" 2026-01-26 00:29:01.162333 | 2026-01-26 00:29:01.162474 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-01-26 00:29:03.341570 | controller | changed: 2026-01-26 00:29:03.341892 | controller | .d..t...... ./ 2026-01-26 00:29:03.341927 | controller | cd+++++++++ controller/ 2026-01-26 00:29:03.341961 | controller | changed: All items complete 2026-01-26 00:29:03.341983 | 2026-01-26 00:29:05.436063 | controller | changed: .d..t...... ./ 2026-01-26 00:29:07.542615 | controller | changed: .d..t...... ./ 2026-01-26 00:29:07.571961 | 2026-01-26 00:29:07.572243 | TASK [include_role : fetch-output-openshift] 2026-01-26 00:29:07.599242 | controller | skipping: Conditional result was False 2026-01-26 00:29:07.614086 | 2026-01-26 00:29:07.614264 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-01-26 00:29:07.657410 | controller | skipping: Conditional result was False 2026-01-26 00:29:07.667399 | controller | skipping: Conditional result was False 2026-01-26 00:29:07.729383 | 2026-01-26 00:29:07.729501 | PLAY [localhost] 2026-01-26 00:29:07.748196 | 2026-01-26 00:29:07.748298 | TASK [Run Zuul manifest role] 2026-01-26 00:29:07.767628 | localhost | ok 2026-01-26 00:29:07.785591 | 2026-01-26 00:29:07.785697 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-01-26 00:29:08.174237 | localhost | changed 2026-01-26 00:29:08.184348 | 2026-01-26 00:29:08.184477 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-01-26 00:29:08.219533 | localhost | ok 2026-01-26 00:29:08.237296 | 2026-01-26 00:29:08.237416 | TASK [Set zuul-log-path fact] 2026-01-26 00:29:08.263515 | localhost | ok 2026-01-26 00:29:08.313812 | 2026-01-26 00:29:08.313948 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-01-26 00:29:08.342131 | localhost | ok 2026-01-26 00:29:08.349873 | 2026-01-26 00:29:08.349927 | LOOP [Run upload-logs-swift role] 2026-01-26 00:29:08.385785 | localhost | Output suppressed because no_log was given 2026-01-26 00:29:08.409894 | 2026-01-26 00:29:08.409980 | TASK [Set zuul-log-path fact] 2026-01-26 00:29:08.433552 | localhost | skipping: Conditional result was False 2026-01-26 00:29:08.439777 | 2026-01-26 00:29:08.439842 | TASK [upload-logs-swift : Ensure logs are readable before uploading] 2026-01-26 00:29:08.809430 | localhost -> localhost | ok: Runtime: 0:00:00.005225 2026-01-26 00:29:08.815068 | 2026-01-26 00:29:08.815138 | TASK [upload-logs-swift : Upload logs to swift]