2026-02-23 00:14:19.818476 | Job console starting... 2026-02-23 00:14:19.830989 | Updating repositories 2026-02-23 00:14:19.983801 | Preparing job workspace 2026-02-23 00:14:23.885157 | Running Ansible setup... 2026-02-23 00:14:28.866657 | PRE-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master] 2026-02-23 00:14:29.534584 | 2026-02-23 00:14:29.534765 | PLAY [localhost] 2026-02-23 00:14:29.546241 | 2026-02-23 00:14:29.546412 | TASK [Gathering Facts] 2026-02-23 00:14:30.616950 | localhost | ok 2026-02-23 00:14:30.631037 | 2026-02-23 00:14:30.631180 | TASK [Setup log path fact] 2026-02-23 00:14:30.651328 | localhost | ok 2026-02-23 00:14:30.670261 | 2026-02-23 00:14:30.670413 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-02-23 00:14:30.692386 | localhost | ok 2026-02-23 00:14:30.703657 | 2026-02-23 00:14:30.703825 | TASK [emit-job-header : Print job information] 2026-02-23 00:14:30.745228 | # Job Information 2026-02-23 00:14:30.745407 | Ansible Version: 2.15.12 2026-02-23 00:14:30.745453 | Job: ansible-test-sanity-docker-stable-2.12 2026-02-23 00:14:30.745484 | Pipeline: periodic 2026-02-23 00:14:30.745512 | Executor: ze04.softwarefactory-project.io 2026-02-23 00:14:30.745539 | Triggered by: https://github.com/ansible-collections/ansible.network 2026-02-23 00:14:30.745570 | Log URL (when completed): https://ansible.softwarefactory-project.io/logs/695/ansible/695a8570a0bf460083665ce9ffd45014/ 2026-02-23 00:14:30.745598 | Event ID: 6a8a9386ce23468fa82fb2babfc7fd44 2026-02-23 00:14:30.750915 | 2026-02-23 00:14:30.751005 | LOOP [emit-job-header : Print node information] 2026-02-23 00:14:30.863372 | localhost | ok: 2026-02-23 00:14:30.863528 | localhost | # Node Information 2026-02-23 00:14:30.863583 | localhost | Inventory Hostname: controller 2026-02-23 00:14:30.863606 | localhost | Hostname: np0005626334 2026-02-23 00:14:30.863626 | localhost | Username: zuul 2026-02-23 00:14:30.863647 | localhost | Distro: Fedora 37 2026-02-23 00:14:30.863666 | localhost | Provider: ansible-vexxhost-ca-ymq-1 2026-02-23 00:14:30.863704 | localhost | Region: ca-ymq-1 2026-02-23 00:14:30.863722 | localhost | Label: ansible-fedora-37-1vcpu 2026-02-23 00:14:30.863739 | localhost | Product Name: OpenStack Nova 2026-02-23 00:14:30.863756 | localhost | Interface IP: 162.253.55.195 2026-02-23 00:14:30.877164 | 2026-02-23 00:14:30.877433 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-02-23 00:14:31.289635 | localhost -> localhost | changed 2026-02-23 00:14:31.306224 | 2026-02-23 00:14:31.306464 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-02-23 00:14:32.223365 | localhost -> localhost | changed 2026-02-23 00:14:32.260888 | 2026-02-23 00:14:32.261009 | PLAY [all:!appliance*] 2026-02-23 00:14:32.285831 | 2026-02-23 00:14:32.285950 | TASK [include_role : start-zuul-console] 2026-02-23 00:14:32.305613 | controller | ok 2026-02-23 00:14:32.319138 | 2026-02-23 00:14:32.319207 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-02-23 00:14:32.688303 | controller | ok 2026-02-23 00:14:32.704905 | 2026-02-23 00:14:32.705066 | TASK [use-our-mirror : Retrieve the IP address] 2026-02-23 00:14:33.763920 | controller | ok: Status code was 404 and not [200]: HTTP Error 404: Not Found 2026-02-23 00:14:33.778470 | 2026-02-23 00:14:33.778605 | TASK [use-our-mirror : Ensure the registry service is running on the mirror] 2026-02-23 00:14:34.327078 | controller | skipping: Conditional result was False 2026-02-23 00:14:34.342101 | 2026-02-23 00:14:34.342285 | TASK [use-our-mirror : Write the IP in /etc/hosts] 2026-02-23 00:14:34.370420 | controller | skipping: Conditional result was False 2026-02-23 00:14:34.385150 | 2026-02-23 00:14:34.385587 | TASK [use-our-mirror : Create the podman configuration directory] 2026-02-23 00:14:34.418589 | controller | skipping: Conditional result was False 2026-02-23 00:14:34.429201 | 2026-02-23 00:14:34.429433 | TASK [use-our-mirror : Copy the podman configuration] 2026-02-23 00:14:34.456058 | controller | skipping: Conditional result was False 2026-02-23 00:14:34.465311 | 2026-02-23 00:14:34.465430 | TASK [use-our-mirror : Copy the Yum configuration (main)] 2026-02-23 00:14:34.490944 | controller | skipping: Conditional result was False 2026-02-23 00:14:34.500302 | 2026-02-23 00:14:34.500437 | TASK [use-our-mirror : Copy the Yum configuration (update)] 2026-02-23 00:14:34.526747 | controller | skipping: Conditional result was False 2026-02-23 00:14:34.545583 | 2026-02-23 00:14:34.545737 | TASK [Disable Fedora Modular] 2026-02-23 00:14:34.801600 | controller | changed 2026-02-23 00:14:34.816852 | 2026-02-23 00:14:34.817035 | TASK [Enable EPEL] 2026-02-23 00:14:34.846751 | controller | skipping: Conditional result was False 2026-02-23 00:14:34.864121 | 2026-02-23 00:14:34.864441 | TASK [Register the RHEL node] 2026-02-23 00:14:35.426903 | 2026-02-23 00:14:35.427097 | TASK [Show the subscription-manager status] 2026-02-23 00:14:36.008794 | controller | skipping: Conditional result was False 2026-02-23 00:14:36.024292 | 2026-02-23 00:14:36.024476 | TASK [Enable EPEL on RHEL] 2026-02-23 00:14:36.584538 | controller | skipping: Conditional result was False 2026-02-23 00:14:36.593489 | 2026-02-23 00:14:36.593621 | TASK [Install git and tox] 2026-02-23 00:15:54.878611 | controller | changed 2026-02-23 00:15:54.887189 | 2026-02-23 00:15:54.887263 | TASK [include_role : prepare-workspace] 2026-02-23 00:15:54.907784 | controller | ok 2026-02-23 00:15:54.929165 | 2026-02-23 00:15:54.929250 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-02-23 00:15:55.441633 | controller | ok 2026-02-23 00:15:55.448886 | 2026-02-23 00:15:55.448996 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-02-23 00:16:08.317944 | controller | Output suppressed because no_log was given 2026-02-23 00:16:08.365538 | 2026-02-23 00:16:08.365654 | TASK [include_role : prepare-workspace-openshift] 2026-02-23 00:16:08.382925 | controller | skipping: Conditional result was False 2026-02-23 00:16:08.406570 | 2026-02-23 00:16:08.406664 | PLAY [all:!appliance] 2026-02-23 00:16:08.428085 | 2026-02-23 00:16:08.428225 | TASK [Run add-build-sshkey role (RSA)] 2026-02-23 00:16:08.450555 | controller | ok 2026-02-23 00:16:08.467163 | 2026-02-23 00:16:08.467297 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-02-23 00:16:08.711410 | controller -> localhost | ok 2026-02-23 00:16:08.718083 | 2026-02-23 00:16:08.718172 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-02-23 00:16:08.739275 | controller | ok 2026-02-23 00:16:08.754134 | controller | included: /var/lib/zuul/builds/695a8570a0bf460083665ce9ffd45014/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-02-23 00:16:08.760664 | 2026-02-23 00:16:08.760774 | TASK [add-build-sshkey : Create Temp SSH key] 2026-02-23 00:16:09.284177 | controller -> localhost | Generating public/private rsa key pair. 2026-02-23 00:16:09.284349 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/695a8570a0bf460083665ce9ffd45014/work/695a8570a0bf460083665ce9ffd45014_id_rsa. 2026-02-23 00:16:09.284379 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/695a8570a0bf460083665ce9ffd45014/work/695a8570a0bf460083665ce9ffd45014_id_rsa.pub. 2026-02-23 00:16:09.284401 | controller -> localhost | The key fingerprint is: 2026-02-23 00:16:09.284422 | controller -> localhost | SHA256:aLK/u3ZjCvwaQsyBJgS0WOJ0RS66HLucL15sRnkiKUg zuul-build-sshkey 2026-02-23 00:16:09.284442 | controller -> localhost | The key's randomart image is: 2026-02-23 00:16:09.284461 | controller -> localhost | +---[RSA 2048]----+ 2026-02-23 00:16:09.284479 | controller -> localhost | |=+..oo | 2026-02-23 00:16:09.284511 | controller -> localhost | |=+o . | 2026-02-23 00:16:09.284532 | controller -> localhost | |+E.. . | 2026-02-23 00:16:09.284550 | controller -> localhost | |=oo.o . | 2026-02-23 00:16:09.284569 | controller -> localhost | |o=++..o S | 2026-02-23 00:16:09.284597 | controller -> localhost | |o.B.o+ | 2026-02-23 00:16:09.284619 | controller -> localhost | | +.== | 2026-02-23 00:16:09.284639 | controller -> localhost | |..*. =. + | 2026-02-23 00:16:09.284658 | controller -> localhost | |.=o..oBB . | 2026-02-23 00:16:09.284697 | controller -> localhost | +----[SHA256]-----+ 2026-02-23 00:16:09.284749 | controller -> localhost | ok: Runtime: 0:00:00.147643 2026-02-23 00:16:09.291993 | 2026-02-23 00:16:09.292141 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-02-23 00:16:09.322138 | controller | ok 2026-02-23 00:16:09.333671 | controller | included: /var/lib/zuul/builds/695a8570a0bf460083665ce9ffd45014/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-02-23 00:16:09.343502 | 2026-02-23 00:16:09.343611 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-02-23 00:16:09.367595 | controller | skipping: Conditional result was False 2026-02-23 00:16:09.374265 | 2026-02-23 00:16:09.374376 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-02-23 00:16:09.838674 | controller | changed 2026-02-23 00:16:09.851039 | 2026-02-23 00:16:09.851197 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-02-23 00:16:10.077080 | controller | ok 2026-02-23 00:16:10.083130 | 2026-02-23 00:16:10.083212 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-02-23 00:16:10.681756 | controller | changed 2026-02-23 00:16:10.688641 | 2026-02-23 00:16:10.688760 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-02-23 00:16:11.320446 | controller | changed 2026-02-23 00:16:11.326926 | 2026-02-23 00:16:11.327029 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-02-23 00:16:11.351418 | controller | skipping: Conditional result was False 2026-02-23 00:16:11.358562 | 2026-02-23 00:16:11.358660 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-02-23 00:16:11.740430 | controller -> localhost | changed 2026-02-23 00:16:11.761012 | 2026-02-23 00:16:11.761151 | TASK [add-build-sshkey : Add back temp key] 2026-02-23 00:16:12.053494 | controller -> localhost | Identity added: /var/lib/zuul/builds/695a8570a0bf460083665ce9ffd45014/work/695a8570a0bf460083665ce9ffd45014_id_rsa (zuul-build-sshkey) 2026-02-23 00:16:12.053864 | controller -> localhost | ok: Runtime: 0:00:00.010487 2026-02-23 00:16:12.070168 | 2026-02-23 00:16:12.070383 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-02-23 00:16:12.439332 | controller | ok 2026-02-23 00:16:12.453257 | 2026-02-23 00:16:12.453401 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-02-23 00:16:12.491584 | controller | skipping: Conditional result was False 2026-02-23 00:16:12.511836 | 2026-02-23 00:16:12.511948 | TASK [Run add-build-sshkey role (ECDSA)] 2026-02-23 00:16:12.534157 | controller | ok 2026-02-23 00:16:12.551531 | 2026-02-23 00:16:12.551744 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-02-23 00:16:12.800129 | controller -> localhost | ok 2026-02-23 00:16:12.807139 | 2026-02-23 00:16:12.807214 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-02-23 00:16:12.828967 | controller | ok 2026-02-23 00:16:12.840068 | controller | included: /var/lib/zuul/builds/695a8570a0bf460083665ce9ffd45014/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-02-23 00:16:12.846456 | 2026-02-23 00:16:12.846521 | TASK [add-build-sshkey : Create Temp SSH key] 2026-02-23 00:16:13.117545 | controller -> localhost | Generating public/private ecdsa key pair. 2026-02-23 00:16:13.117717 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/695a8570a0bf460083665ce9ffd45014/work/695a8570a0bf460083665ce9ffd45014_id_ecdsa. 2026-02-23 00:16:13.117746 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/695a8570a0bf460083665ce9ffd45014/work/695a8570a0bf460083665ce9ffd45014_id_ecdsa.pub. 2026-02-23 00:16:13.117780 | controller -> localhost | The key fingerprint is: 2026-02-23 00:16:13.117801 | controller -> localhost | SHA256:gzeu3y3fv+14XrkwWEKo5HWnZ2xVm5Hw2p+hPZI3kfg zuul-build-sshkey 2026-02-23 00:16:13.117820 | controller -> localhost | The key's randomart image is: 2026-02-23 00:16:13.117839 | controller -> localhost | +---[ECDSA 521]---+ 2026-02-23 00:16:13.117858 | controller -> localhost | | ...o| 2026-02-23 00:16:13.117877 | controller -> localhost | | . ..=| 2026-02-23 00:16:13.117895 | controller -> localhost | | . o o . = | 2026-02-23 00:16:13.117912 | controller -> localhost | | o.o o + = .| 2026-02-23 00:16:13.117929 | controller -> localhost | | .oS o O = | 2026-02-23 00:16:13.117946 | controller -> localhost | | o o B = *| 2026-02-23 00:16:13.117964 | controller -> localhost | | . . * Eo| 2026-02-23 00:16:13.117981 | controller -> localhost | | . ... .=.B| 2026-02-23 00:16:13.117998 | controller -> localhost | | ... .oo oB*| 2026-02-23 00:16:13.118015 | controller -> localhost | +----[SHA256]-----+ 2026-02-23 00:16:13.118056 | controller -> localhost | ok: Runtime: 0:00:00.015477 2026-02-23 00:16:13.124560 | 2026-02-23 00:16:13.124621 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-02-23 00:16:13.154731 | controller | ok 2026-02-23 00:16:13.162179 | controller | included: /var/lib/zuul/builds/695a8570a0bf460083665ce9ffd45014/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-02-23 00:16:13.171567 | 2026-02-23 00:16:13.171641 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-02-23 00:16:13.195587 | controller | skipping: Conditional result was False 2026-02-23 00:16:13.203842 | 2026-02-23 00:16:13.203973 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-02-23 00:16:13.467309 | controller | changed 2026-02-23 00:16:13.475097 | 2026-02-23 00:16:13.475268 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-02-23 00:16:13.709405 | controller | ok 2026-02-23 00:16:13.716294 | 2026-02-23 00:16:13.716379 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-02-23 00:16:14.358789 | controller | changed 2026-02-23 00:16:14.364821 | 2026-02-23 00:16:14.364912 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-02-23 00:16:15.024001 | controller | changed 2026-02-23 00:16:15.038001 | 2026-02-23 00:16:15.038162 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-02-23 00:16:15.064751 | controller | skipping: Conditional result was False 2026-02-23 00:16:15.114557 | 2026-02-23 00:16:15.114752 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-02-23 00:16:15.437281 | controller -> localhost | changed 2026-02-23 00:16:15.454755 | 2026-02-23 00:16:15.454915 | TASK [add-build-sshkey : Add back temp key] 2026-02-23 00:16:15.750375 | controller -> localhost | Identity added: /var/lib/zuul/builds/695a8570a0bf460083665ce9ffd45014/work/695a8570a0bf460083665ce9ffd45014_id_ecdsa (zuul-build-sshkey) 2026-02-23 00:16:15.750668 | controller -> localhost | ok: Runtime: 0:00:00.007217 2026-02-23 00:16:15.759596 | 2026-02-23 00:16:15.759755 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-02-23 00:16:15.984305 | controller | ok 2026-02-23 00:16:15.990997 | 2026-02-23 00:16:15.991110 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-02-23 00:16:16.035799 | controller | skipping: Conditional result was False 2026-02-23 00:16:16.058126 | 2026-02-23 00:16:16.058411 | TASK [include_role : remove-zuul-sshkey] 2026-02-23 00:16:16.077517 | controller | skipping: Conditional result was False 2026-02-23 00:16:16.091824 | 2026-02-23 00:16:16.092038 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-02-23 00:16:16.510210 | controller | ok: "logs" 2026-02-23 00:16:16.510640 | controller | ok: All items complete 2026-02-23 00:16:16.510741 | 2026-02-23 00:16:16.725658 | controller | ok: "artifacts" 2026-02-23 00:16:16.923034 | controller | ok: "docs" 2026-02-23 00:16:16.937462 | 2026-02-23 00:16:16.937589 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-02-23 00:16:17.168164 | controller | changed: "logs" 2026-02-23 00:16:17.364132 | controller | changed: "artifacts" 2026-02-23 00:16:17.560532 | controller | changed: "docs" 2026-02-23 00:16:17.602737 | 2026-02-23 00:16:17.602856 | PLAY RECAP 2026-02-23 00:16:17.602919 | controller | ok: 30 changed: 16 unreachable: 0 failed: 0 skipped: 18 rescued: 0 ignored: 0 2026-02-23 00:16:17.602959 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-02-23 00:16:17.602989 | 2026-02-23 00:16:17.754418 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master] 2026-02-23 00:16:17.755311 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2026-02-23 00:16:18.424534 | 2026-02-23 00:16:18.424697 | PLAY [all] 2026-02-23 00:16:18.451208 | 2026-02-23 00:16:18.451345 | TASK [Install binary dependencies] 2026-02-23 00:16:18.502633 | controller | ok 2026-02-23 00:16:18.524761 | 2026-02-23 00:16:18.524901 | TASK [bindep : Include find tasks] 2026-02-23 00:16:18.554666 | controller | ok 2026-02-23 00:16:18.562980 | controller | included: /var/lib/zuul/builds/695a8570a0bf460083665ce9ffd45014/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find.yaml 2026-02-23 00:16:18.569546 | 2026-02-23 00:16:18.569641 | TASK [bindep : Look for bindep.txt] 2026-02-23 00:16:18.915993 | controller | ok 2026-02-23 00:16:18.937000 | 2026-02-23 00:16:18.937246 | TASK [bindep : Define bindep_file fact] 2026-02-23 00:16:18.965974 | controller | skipping: Conditional result was False 2026-02-23 00:16:18.976615 | 2026-02-23 00:16:18.976805 | TASK [bindep : Look for other-requirements.txt] 2026-02-23 00:16:19.195939 | controller | ok 2026-02-23 00:16:19.212604 | 2026-02-23 00:16:19.212854 | TASK [bindep : Define bindep_file fact] 2026-02-23 00:16:19.238643 | controller | skipping: Conditional result was False 2026-02-23 00:16:19.250076 | 2026-02-23 00:16:19.250291 | TASK [bindep : Look for bindep fallback file] 2026-02-23 00:16:19.289353 | controller | skipping: Conditional result was False 2026-02-23 00:16:19.299914 | 2026-02-23 00:16:19.300080 | TASK [bindep : Define bindep_file fact] 2026-02-23 00:16:19.325290 | controller | skipping: Conditional result was False 2026-02-23 00:16:19.334412 | 2026-02-23 00:16:19.334523 | TASK [bindep : Include bindep tasks] 2026-02-23 00:16:19.359289 | controller | skipping: Conditional result was False 2026-02-23 00:16:19.368365 | 2026-02-23 00:16:19.368476 | TASK [bindep : Include install tasks] 2026-02-23 00:16:19.393575 | controller | skipping: Conditional result was False 2026-02-23 00:16:19.402537 | 2026-02-23 00:16:19.402651 | LOOP [bindep : Include package tasks] 2026-02-23 00:16:19.464288 | 2026-02-23 00:16:19.464488 | TASK [Run test-setup role] 2026-02-23 00:16:19.489063 | controller | ok 2026-02-23 00:16:19.514998 | 2026-02-23 00:16:19.515137 | TASK [test-setup : Check if project's tools/test-setup.sh exists] 2026-02-23 00:16:19.728231 | controller | ok 2026-02-23 00:16:19.743433 | 2026-02-23 00:16:19.743612 | TASK [test-setup : Run tools/test-setup.sh] 2026-02-23 00:16:20.293074 | controller | skipping: Conditional result was False 2026-02-23 00:16:20.339458 | 2026-02-23 00:16:20.339555 | PLAY RECAP 2026-02-23 00:16:20.339599 | controller | ok: 4 changed: 0 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2026-02-23 00:16:20.339620 | 2026-02-23 00:16:20.470837 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2026-02-23 00:16:20.471741 | PRE-RUN START: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master] 2026-02-23 00:16:21.089891 | 2026-02-23 00:16:21.090026 | PLAY [controller] 2026-02-23 00:16:21.109297 | 2026-02-23 00:16:21.109384 | TASK [Create the /root directory] 2026-02-23 00:18:33.415845 | controller | ERROR 2026-02-23 00:18:33.416207 | controller | { 2026-02-23 00:18:33.416289 | controller | "msg": "Timeout (32s) waiting for privilege escalation prompt: " 2026-02-23 00:18:33.416350 | controller | } failure 2026-02-23 00:18:33.417747 | 2026-02-23 00:18:33.417968 | PLAY RECAP 2026-02-23 00:18:33.418066 | controller | ok: 0 changed: 0 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0 2026-02-23 00:18:33.418115 | 2026-02-23 00:18:33.523357 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master] 2026-02-23 00:18:33.524320 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2026-02-23 00:18:34.094559 | 2026-02-23 00:18:34.094710 | PLAY [all] 2026-02-23 00:18:34.116392 | 2026-02-23 00:18:34.116488 | TASK [fetch-subunit-output : Find stestr or testr executable] 2026-02-23 00:21:59.973112 | controller | changed: non-zero return code 2026-02-23 00:21:59.981714 | 2026-02-23 00:21:59.981848 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2026-02-23 00:22:00.009135 | controller | skipping: Conditional result was False 2026-02-23 00:22:00.024930 | 2026-02-23 00:22:00.025147 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2026-02-23 00:22:00.068302 | 2026-02-23 00:22:00.068596 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2026-02-23 00:22:00.104786 | 2026-02-23 00:22:00.105003 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2026-02-23 00:22:00.130129 | controller | skipping: Conditional result was False 2026-02-23 00:22:00.139646 | 2026-02-23 00:22:00.139835 | LOOP [fetch-subunit-output : Generate subunit file] 2026-02-23 00:22:00.165110 | 2026-02-23 00:22:00.165339 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2026-02-23 00:22:00.190316 | controller | skipping: Conditional result was False 2026-02-23 00:22:00.199429 | 2026-02-23 00:22:00.199542 | TASK [fetch-subunit-output : Remove the temporary file] 2026-02-23 00:22:00.224613 | controller | skipping: Conditional result was False 2026-02-23 00:22:00.234428 | 2026-02-23 00:22:00.234630 | TASK [fetch-subunit-output : Process and fetch subunit results] 2026-02-23 00:22:00.259985 | controller | skipping: Conditional result was False 2026-02-23 00:22:00.299284 | 2026-02-23 00:22:00.299412 | PLAY RECAP 2026-02-23 00:22:00.299467 | controller | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2026-02-23 00:22:00.299494 | 2026-02-23 00:22:00.406352 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2026-02-23 00:22:00.407536 | POST-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/post.yaml@master] 2026-02-23 00:22:01.038858 | 2026-02-23 00:22:01.039179 | PLAY [all:!appliance*] 2026-02-23 00:22:01.092551 | 2026-02-23 00:22:01.092779 | TASK [unregister the node] 2026-02-23 00:22:01.636570 | controller | skipping: Conditional result was False 2026-02-23 00:22:01.643743 | 2026-02-23 00:22:01.643890 | TASK [include_role : fetch-output] 2026-02-23 00:22:01.674491 | controller | ok 2026-02-23 00:22:01.694458 | 2026-02-23 00:22:01.694574 | TASK [fetch-output : Set log path for multiple nodes] 2026-02-23 00:22:01.740133 | controller | skipping: Conditional result was False 2026-02-23 00:22:01.756631 | 2026-02-23 00:22:01.756843 | TASK [fetch-output : Set log path for single node] 2026-02-23 00:22:01.815351 | controller | ok 2026-02-23 00:22:01.824004 | 2026-02-23 00:22:01.824101 | LOOP [fetch-output : Ensure local output dirs] 2026-02-23 00:22:02.343525 | controller -> localhost | ok: "/var/lib/zuul/builds/695a8570a0bf460083665ce9ffd45014/work/logs" 2026-02-23 00:22:02.631324 | controller -> localhost | changed: "/var/lib/zuul/builds/695a8570a0bf460083665ce9ffd45014/work/artifacts" 2026-02-23 00:22:02.890057 | controller -> localhost | changed: "/var/lib/zuul/builds/695a8570a0bf460083665ce9ffd45014/work/docs" 2026-02-23 00:22:02.903749 | 2026-02-23 00:22:02.903958 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-02-23 00:22:03.731257 | controller | ok 2026-02-23 00:22:03.731475 | controller | changed: All items complete 2026-02-23 00:22:03.731503 | 2026-02-23 00:22:04.214254 | controller | changed: .d..t...... ./ 2026-02-23 00:22:04.809833 | controller | changed: .d..t...... ./ 2026-02-23 00:22:04.837358 | 2026-02-23 00:22:04.837548 | TASK [include_role : fetch-output-openshift] 2026-02-23 00:22:04.867915 | controller | skipping: Conditional result was False 2026-02-23 00:22:04.883779 | 2026-02-23 00:22:04.883965 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-02-23 00:22:04.934052 | controller | skipping: Conditional result was False 2026-02-23 00:22:04.947563 | controller | skipping: Conditional result was False 2026-02-23 00:22:05.008059 | 2026-02-23 00:22:05.008253 | PLAY [localhost] 2026-02-23 00:22:05.028441 | 2026-02-23 00:22:05.028640 | TASK [Run Zuul manifest role] 2026-02-23 00:22:05.051341 | localhost | ok 2026-02-23 00:22:05.066955 | 2026-02-23 00:22:05.067082 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-02-23 00:22:05.543297 | localhost | changed 2026-02-23 00:22:05.553369 | 2026-02-23 00:22:05.553482 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-02-23 00:22:05.586614 | localhost | ok 2026-02-23 00:22:05.599224 | 2026-02-23 00:22:05.599328 | TASK [Set zuul-log-path fact] 2026-02-23 00:22:05.620909 | localhost | ok 2026-02-23 00:22:05.642142 | 2026-02-23 00:22:05.642365 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-02-23 00:22:05.672173 | localhost | ok 2026-02-23 00:22:05.680736 | 2026-02-23 00:22:05.680821 | LOOP [Set zuul_log_targets (Swift with random Rackspace region)] 2026-02-23 00:22:05.705277 | localhost | ok: 2026-02-23 00:22:05.705622 | localhost | { 2026-02-23 00:22:05.705661 | localhost | "auth": { 2026-02-23 00:22:05.705698 | localhost | "password": "IonWCwcuOFib&&<}wp^:[2G/", 2026-02-23 00:22:05.705717 | localhost | "username": "9LF91O0RK7JU" 2026-02-23 00:22:05.705738 | localhost | }, 2026-02-23 00:22:05.705755 | localhost | "profile": "rackspace", 2026-02-23 00:22:05.705771 | localhost | "region_name": "ORD" 2026-02-23 00:22:05.705787 | localhost | } 2026-02-23 00:22:05.705820 | 2026-02-23 00:22:05.720495 | 2026-02-23 00:22:05.720599 | TASK [Run upload-logs-failover role (S3 then Swift)] 2026-02-23 00:22:05.738330 | localhost | Output suppressed because no_log was given 2026-02-23 00:22:05.750700 | 2026-02-23 00:22:05.750771 | TASK [upload-logs-failover : Initialize control vars] 2026-02-23 00:22:05.769542 | localhost | ok 2026-02-23 00:22:05.774189 | 2026-02-23 00:22:05.774278 | LOOP [upload-logs-failover : Perform log upload] 2026-02-23 00:22:05.864474 | localhost | Output suppressed because no_log was given 2026-02-23 00:22:05.871274 | localhost | included: /var/lib/zuul/builds/695a8570a0bf460083665ce9ffd45014/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/upload-logs-failover/tasks/upload.yaml 2026-02-23 00:22:05.877870 | 2026-02-23 00:22:05.877946 | LOOP [upload-logs-failover : Unpack vars for swift upload] 2026-02-23 00:22:05.953486 | localhost | Output suppressed because no_log was given 2026-02-23 00:22:05.967189 | 2026-02-23 00:22:05.967403 | TASK [Upload logs to swift] 2026-02-23 00:22:06.006283 | localhost | ok 2026-02-23 00:22:06.056308 | 2026-02-23 00:22:06.056453 | TASK [Set zuul-log-path fact] 2026-02-23 00:22:06.082297 | localhost | skipping: Conditional result was False 2026-02-23 00:22:06.090673 | 2026-02-23 00:22:06.090799 | TASK [upload-logs-swift : Ensure logs are readable before uploading] 2026-02-23 00:22:06.486871 | localhost -> localhost | ok: Runtime: 0:00:00.008986 2026-02-23 00:22:06.498573 | 2026-02-23 00:22:06.498771 | TASK [upload-logs-swift : Upload logs to swift]