2025-11-25 00:15:48.187176 | Job console starting... 2025-11-25 00:15:48.206297 | Updating repositories 2025-11-25 00:15:48.345531 | Preparing job workspace 2025-11-25 00:15:52.298447 | Running Ansible setup... 2025-11-25 00:15:56.739858 | PRE-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master] 2025-11-25 00:15:57.393438 | 2025-11-25 00:15:57.393584 | PLAY [localhost] 2025-11-25 00:15:57.403233 | 2025-11-25 00:15:57.403362 | TASK [Gathering Facts] 2025-11-25 00:15:58.430990 | localhost | ok 2025-11-25 00:15:58.466599 | 2025-11-25 00:15:58.466780 | TASK [Setup log path fact] 2025-11-25 00:15:58.489651 | localhost | ok 2025-11-25 00:15:58.509260 | 2025-11-25 00:15:58.509450 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-11-25 00:15:58.551764 | localhost | ok 2025-11-25 00:15:58.566297 | 2025-11-25 00:15:58.566466 | TASK [emit-job-header : Print job information] 2025-11-25 00:15:58.597468 | # Job Information 2025-11-25 00:15:58.597744 | Ansible Version: 2.15.12 2025-11-25 00:15:58.597795 | Job: ansible-test-sanity-docker-stable-2.11 2025-11-25 00:15:58.597825 | Pipeline: periodic 2025-11-25 00:15:58.597846 | Executor: ze03.softwarefactory-project.io 2025-11-25 00:15:58.597865 | Triggered by: https://github.com/ansible-collections/ansible.network 2025-11-25 00:15:58.597888 | Log URL (when completed): https://ansible.softwarefactory-project.io/logs/980/ansible/9805a2b608f44ebf83e8d5755646d569/ 2025-11-25 00:15:58.597908 | Event ID: 79272cc69eda4d18bcd9786106633695 2025-11-25 00:15:58.603035 | 2025-11-25 00:15:58.603341 | LOOP [emit-job-header : Print node information] 2025-11-25 00:15:58.743682 | localhost | ok: 2025-11-25 00:15:58.743865 | localhost | # Node Information 2025-11-25 00:15:58.743894 | localhost | Inventory Hostname: controller 2025-11-25 00:15:58.743914 | localhost | Hostname: np0005534283 2025-11-25 00:15:58.743934 | localhost | Username: zuul 2025-11-25 00:15:58.743957 | localhost | Distro: Fedora 37 2025-11-25 00:15:58.743977 | localhost | Provider: ansible-vexxhost-ca-ymq-1 2025-11-25 00:15:58.743995 | localhost | Region: ca-ymq-1 2025-11-25 00:15:58.744013 | localhost | Label: ansible-fedora-37-1vcpu 2025-11-25 00:15:58.744031 | localhost | Product Name: OpenStack Nova 2025-11-25 00:15:58.744048 | localhost | Interface IP: 162.253.55.58 2025-11-25 00:15:58.762925 | 2025-11-25 00:15:58.763074 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-11-25 00:15:59.176307 | localhost -> localhost | changed 2025-11-25 00:15:59.182327 | 2025-11-25 00:15:59.182424 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-11-25 00:16:00.058553 | localhost -> localhost | changed 2025-11-25 00:16:00.104144 | 2025-11-25 00:16:00.104254 | PLAY [all:!appliance*] 2025-11-25 00:16:00.123960 | 2025-11-25 00:16:00.124093 | TASK [include_role : start-zuul-console] 2025-11-25 00:16:00.143637 | controller | ok 2025-11-25 00:16:00.158685 | 2025-11-25 00:16:00.158836 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-11-25 00:16:00.509807 | controller | ok 2025-11-25 00:16:00.520699 | 2025-11-25 00:16:00.520804 | TASK [use-our-mirror : Retrieve the IP address] 2025-11-25 00:16:01.495052 | controller | ok: Status code was 404 and not [200]: HTTP Error 404: Not Found 2025-11-25 00:16:01.502774 | 2025-11-25 00:16:01.502893 | TASK [use-our-mirror : Ensure the registry service is running on the mirror] 2025-11-25 00:16:02.038574 | controller | skipping: Conditional result was False 2025-11-25 00:16:02.053287 | 2025-11-25 00:16:02.053467 | TASK [use-our-mirror : Write the IP in /etc/hosts] 2025-11-25 00:16:02.093371 | controller | skipping: Conditional result was False 2025-11-25 00:16:02.112915 | 2025-11-25 00:16:02.113178 | TASK [use-our-mirror : Create the podman configuration directory] 2025-11-25 00:16:02.143244 | controller | skipping: Conditional result was False 2025-11-25 00:16:02.162173 | 2025-11-25 00:16:02.162445 | TASK [use-our-mirror : Copy the podman configuration] 2025-11-25 00:16:02.190472 | controller | skipping: Conditional result was False 2025-11-25 00:16:02.203103 | 2025-11-25 00:16:02.203287 | TASK [use-our-mirror : Copy the Yum configuration (main)] 2025-11-25 00:16:02.228081 | controller | skipping: Conditional result was False 2025-11-25 00:16:02.242542 | 2025-11-25 00:16:02.242719 | TASK [use-our-mirror : Copy the Yum configuration (update)] 2025-11-25 00:16:02.258428 | controller | skipping: Conditional result was False 2025-11-25 00:16:02.281677 | 2025-11-25 00:16:02.281912 | TASK [Disable Fedora Modular] 2025-11-25 00:16:02.519133 | controller | changed 2025-11-25 00:16:02.533637 | 2025-11-25 00:16:02.533879 | TASK [Enable EPEL] 2025-11-25 00:16:02.563664 | controller | skipping: Conditional result was False 2025-11-25 00:16:02.580124 | 2025-11-25 00:16:02.580316 | TASK [Register the RHEL node] 2025-11-25 00:16:03.148647 | 2025-11-25 00:16:03.148962 | TASK [Show the subscription-manager status] 2025-11-25 00:16:03.738456 | controller | skipping: Conditional result was False 2025-11-25 00:16:03.745531 | 2025-11-25 00:16:03.745631 | TASK [Enable EPEL on RHEL] 2025-11-25 00:16:04.285630 | controller | skipping: Conditional result was False 2025-11-25 00:16:04.300650 | 2025-11-25 00:16:04.300854 | TASK [Install git and tox] 2025-11-25 00:17:23.205442 | controller | changed 2025-11-25 00:17:23.211608 | 2025-11-25 00:17:23.211673 | TASK [include_role : prepare-workspace] 2025-11-25 00:17:23.241679 | controller | ok 2025-11-25 00:17:23.262864 | 2025-11-25 00:17:23.262960 | TASK [prepare-workspace : Start zuul_console daemon.] 2025-11-25 00:17:23.508877 | controller | ok 2025-11-25 00:17:23.526127 | 2025-11-25 00:17:23.526306 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2025-11-25 00:17:36.174036 | controller | Output suppressed because no_log was given 2025-11-25 00:17:36.200687 | 2025-11-25 00:17:36.200878 | TASK [include_role : prepare-workspace-openshift] 2025-11-25 00:17:36.242163 | controller | skipping: Conditional result was False 2025-11-25 00:17:36.277136 | 2025-11-25 00:17:36.277363 | PLAY [all:!appliance] 2025-11-25 00:17:36.335627 | 2025-11-25 00:17:36.335805 | TASK [Run add-build-sshkey role (RSA)] 2025-11-25 00:17:36.357945 | controller | ok 2025-11-25 00:17:36.377408 | 2025-11-25 00:17:36.377515 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-11-25 00:17:36.658437 | controller -> localhost | ok 2025-11-25 00:17:36.672899 | 2025-11-25 00:17:36.673111 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-11-25 00:17:36.704072 | controller | ok 2025-11-25 00:17:36.739883 | controller | included: /var/lib/zuul/builds/9805a2b608f44ebf83e8d5755646d569/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-11-25 00:17:36.752847 | 2025-11-25 00:17:36.752932 | TASK [add-build-sshkey : Create Temp SSH key] 2025-11-25 00:17:37.256409 | controller -> localhost | Generating public/private rsa key pair. 2025-11-25 00:17:37.256832 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/9805a2b608f44ebf83e8d5755646d569/work/9805a2b608f44ebf83e8d5755646d569_id_rsa. 2025-11-25 00:17:37.256907 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/9805a2b608f44ebf83e8d5755646d569/work/9805a2b608f44ebf83e8d5755646d569_id_rsa.pub. 2025-11-25 00:17:37.256960 | controller -> localhost | The key fingerprint is: 2025-11-25 00:17:37.257025 | controller -> localhost | SHA256:pBZop9jQyvKy/fwupv5ei0PsRu6jPZHpcM6NlgwpC3E zuul-build-sshkey 2025-11-25 00:17:37.257093 | controller -> localhost | The key's randomart image is: 2025-11-25 00:17:37.257142 | controller -> localhost | +---[RSA 2048]----+ 2025-11-25 00:17:37.257199 | controller -> localhost | | | 2025-11-25 00:17:37.257263 | controller -> localhost | | . . | 2025-11-25 00:17:37.257312 | controller -> localhost | | . + o . | 2025-11-25 00:17:37.257357 | controller -> localhost | |..E* o + | 2025-11-25 00:17:37.257398 | controller -> localhost | |.o+oooo S | 2025-11-25 00:17:37.257457 | controller -> localhost | |oo+ O. | 2025-11-25 00:17:37.257543 | controller -> localhost | |oo.& =. | 2025-11-25 00:17:37.257608 | controller -> localhost | |.+ o^o.. | 2025-11-25 00:17:37.257660 | controller -> localhost | |..=@OB+ | 2025-11-25 00:17:37.257706 | controller -> localhost | +----[SHA256]-----+ 2025-11-25 00:17:37.257860 | controller -> localhost | ok: Runtime: 0:00:00.100194 2025-11-25 00:17:37.273443 | 2025-11-25 00:17:37.273677 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-11-25 00:17:37.307094 | controller | ok 2025-11-25 00:17:37.329326 | controller | included: /var/lib/zuul/builds/9805a2b608f44ebf83e8d5755646d569/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-11-25 00:17:37.342709 | 2025-11-25 00:17:37.342867 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-11-25 00:17:37.368518 | controller | skipping: Conditional result was False 2025-11-25 00:17:37.381054 | 2025-11-25 00:17:37.381252 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-11-25 00:17:37.840441 | controller | changed 2025-11-25 00:17:37.855040 | 2025-11-25 00:17:37.855236 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-11-25 00:17:38.091323 | controller | ok 2025-11-25 00:17:38.104918 | 2025-11-25 00:17:38.105098 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-11-25 00:17:38.723296 | controller | changed 2025-11-25 00:17:38.732103 | 2025-11-25 00:17:38.732209 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-11-25 00:17:39.383685 | controller | changed 2025-11-25 00:17:39.398228 | 2025-11-25 00:17:39.398379 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-11-25 00:17:39.436828 | controller | skipping: Conditional result was False 2025-11-25 00:17:39.452003 | 2025-11-25 00:17:39.452151 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-11-25 00:17:39.858409 | controller -> localhost | changed 2025-11-25 00:17:39.894320 | 2025-11-25 00:17:39.894558 | TASK [add-build-sshkey : Add back temp key] 2025-11-25 00:17:40.238073 | controller -> localhost | Identity added: /var/lib/zuul/builds/9805a2b608f44ebf83e8d5755646d569/work/9805a2b608f44ebf83e8d5755646d569_id_rsa (zuul-build-sshkey) 2025-11-25 00:17:40.238498 | controller -> localhost | ok: Runtime: 0:00:00.016493 2025-11-25 00:17:40.256470 | 2025-11-25 00:17:40.256652 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-11-25 00:17:40.615282 | controller | ok 2025-11-25 00:17:40.628697 | 2025-11-25 00:17:40.628917 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-11-25 00:17:40.667023 | controller | skipping: Conditional result was False 2025-11-25 00:17:40.684266 | 2025-11-25 00:17:40.684408 | TASK [Run add-build-sshkey role (ECDSA)] 2025-11-25 00:17:40.719035 | controller | ok 2025-11-25 00:17:40.745415 | 2025-11-25 00:17:40.745555 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-11-25 00:17:41.037384 | controller -> localhost | ok 2025-11-25 00:17:41.054244 | 2025-11-25 00:17:41.054425 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-11-25 00:17:41.085634 | controller | ok 2025-11-25 00:17:41.109748 | controller | included: /var/lib/zuul/builds/9805a2b608f44ebf83e8d5755646d569/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-11-25 00:17:41.124136 | 2025-11-25 00:17:41.124324 | TASK [add-build-sshkey : Create Temp SSH key] 2025-11-25 00:17:41.421627 | controller -> localhost | Generating public/private ecdsa key pair. 2025-11-25 00:17:41.422114 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/9805a2b608f44ebf83e8d5755646d569/work/9805a2b608f44ebf83e8d5755646d569_id_ecdsa. 2025-11-25 00:17:41.422165 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/9805a2b608f44ebf83e8d5755646d569/work/9805a2b608f44ebf83e8d5755646d569_id_ecdsa.pub. 2025-11-25 00:17:41.422207 | controller -> localhost | The key fingerprint is: 2025-11-25 00:17:41.422699 | controller -> localhost | SHA256:sDsacbN628gICkEhbLNUUV4I0op+VeKcjDsPj5QE6Lc zuul-build-sshkey 2025-11-25 00:17:41.422816 | controller -> localhost | The key's randomart image is: 2025-11-25 00:17:41.422851 | controller -> localhost | +---[ECDSA 521]---+ 2025-11-25 00:17:41.422880 | controller -> localhost | |+oo++... | 2025-11-25 00:17:41.422907 | controller -> localhost | |o*o..o.. | 2025-11-25 00:17:41.422934 | controller -> localhost | |=.= =.= | 2025-11-25 00:17:41.422961 | controller -> localhost | |o+ + * o | 2025-11-25 00:17:41.422987 | controller -> localhost | |o o * + S | 2025-11-25 00:17:41.423012 | controller -> localhost | | o E o + | 2025-11-25 00:17:41.423038 | controller -> localhost | |. + B + | 2025-11-25 00:17:41.423063 | controller -> localhost | |.. o O.+ | 2025-11-25 00:17:41.423088 | controller -> localhost | |. +.+.. | 2025-11-25 00:17:41.423114 | controller -> localhost | +----[SHA256]-----+ 2025-11-25 00:17:41.423193 | controller -> localhost | ok: Runtime: 0:00:00.010483 2025-11-25 00:17:41.433157 | 2025-11-25 00:17:41.433282 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-11-25 00:17:41.469119 | controller | ok 2025-11-25 00:17:41.480759 | controller | included: /var/lib/zuul/builds/9805a2b608f44ebf83e8d5755646d569/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-11-25 00:17:41.495036 | 2025-11-25 00:17:41.495174 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-11-25 00:17:41.510350 | controller | skipping: Conditional result was False 2025-11-25 00:17:41.520589 | 2025-11-25 00:17:41.520774 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-11-25 00:17:41.793088 | controller | changed 2025-11-25 00:17:41.806471 | 2025-11-25 00:17:41.806646 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-11-25 00:17:42.033771 | controller | ok 2025-11-25 00:17:42.040478 | 2025-11-25 00:17:42.040583 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-11-25 00:17:42.682657 | controller | changed 2025-11-25 00:17:42.697882 | 2025-11-25 00:17:42.698079 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-11-25 00:17:43.337376 | controller | changed 2025-11-25 00:17:43.345977 | 2025-11-25 00:17:43.346070 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-11-25 00:17:43.374142 | controller | skipping: Conditional result was False 2025-11-25 00:17:43.383168 | 2025-11-25 00:17:43.383270 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-11-25 00:17:43.648863 | controller -> localhost | changed 2025-11-25 00:17:43.661522 | 2025-11-25 00:17:43.661615 | TASK [add-build-sshkey : Add back temp key] 2025-11-25 00:17:43.961265 | controller -> localhost | Identity added: /var/lib/zuul/builds/9805a2b608f44ebf83e8d5755646d569/work/9805a2b608f44ebf83e8d5755646d569_id_ecdsa (zuul-build-sshkey) 2025-11-25 00:17:43.961602 | controller -> localhost | ok: Runtime: 0:00:00.009316 2025-11-25 00:17:43.975911 | 2025-11-25 00:17:43.976040 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-11-25 00:17:44.201277 | controller | ok 2025-11-25 00:17:44.216815 | 2025-11-25 00:17:44.216957 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-11-25 00:17:44.264611 | controller | skipping: Conditional result was False 2025-11-25 00:17:44.285872 | 2025-11-25 00:17:44.286015 | TASK [include_role : remove-zuul-sshkey] 2025-11-25 00:17:44.312075 | controller | skipping: Conditional result was False 2025-11-25 00:17:44.358658 | 2025-11-25 00:17:44.358808 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2025-11-25 00:17:44.613440 | controller | ok: "logs" 2025-11-25 00:17:44.613988 | controller | ok: All items complete 2025-11-25 00:17:44.614051 | 2025-11-25 00:17:44.833751 | controller | ok: "artifacts" 2025-11-25 00:17:45.040578 | controller | ok: "docs" 2025-11-25 00:17:45.058596 | 2025-11-25 00:17:45.058821 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2025-11-25 00:17:45.326894 | controller | changed: "logs" 2025-11-25 00:17:45.530421 | controller | changed: "artifacts" 2025-11-25 00:17:45.726379 | controller | changed: "docs" 2025-11-25 00:17:45.782345 | 2025-11-25 00:17:45.782462 | PLAY RECAP 2025-11-25 00:17:45.782616 | controller | ok: 30 changed: 16 unreachable: 0 failed: 0 skipped: 18 rescued: 0 ignored: 0 2025-11-25 00:17:45.782668 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-11-25 00:17:45.782696 | 2025-11-25 00:17:45.906933 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master] 2025-11-25 00:17:45.907937 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2025-11-25 00:17:46.496050 | 2025-11-25 00:17:46.496168 | PLAY [all] 2025-11-25 00:17:46.523127 | 2025-11-25 00:17:46.523254 | TASK [Install binary dependencies] 2025-11-25 00:17:46.603862 | controller | ok 2025-11-25 00:17:46.623350 | 2025-11-25 00:17:46.623437 | TASK [bindep : Include find tasks] 2025-11-25 00:17:46.673224 | controller | ok 2025-11-25 00:17:46.681129 | controller | included: /var/lib/zuul/builds/9805a2b608f44ebf83e8d5755646d569/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find.yaml 2025-11-25 00:17:46.687350 | 2025-11-25 00:17:46.687419 | TASK [bindep : Look for bindep.txt] 2025-11-25 00:17:47.111625 | controller | ok 2025-11-25 00:17:47.123991 | 2025-11-25 00:17:47.124160 | TASK [bindep : Define bindep_file fact] 2025-11-25 00:17:47.151895 | controller | skipping: Conditional result was False 2025-11-25 00:17:47.166942 | 2025-11-25 00:17:47.167117 | TASK [bindep : Look for other-requirements.txt] 2025-11-25 00:17:47.394308 | controller | ok 2025-11-25 00:17:47.399860 | 2025-11-25 00:17:47.399936 | TASK [bindep : Define bindep_file fact] 2025-11-25 00:17:47.434787 | controller | skipping: Conditional result was False 2025-11-25 00:17:47.448896 | 2025-11-25 00:17:47.449091 | TASK [bindep : Look for bindep fallback file] 2025-11-25 00:17:47.476673 | controller | skipping: Conditional result was False 2025-11-25 00:17:47.487010 | 2025-11-25 00:17:47.487139 | TASK [bindep : Define bindep_file fact] 2025-11-25 00:17:47.523409 | controller | skipping: Conditional result was False 2025-11-25 00:17:47.532631 | 2025-11-25 00:17:47.532790 | TASK [bindep : Include bindep tasks] 2025-11-25 00:17:47.558216 | controller | skipping: Conditional result was False 2025-11-25 00:17:47.568095 | 2025-11-25 00:17:47.568215 | TASK [bindep : Include install tasks] 2025-11-25 00:17:47.593965 | controller | skipping: Conditional result was False 2025-11-25 00:17:47.604115 | 2025-11-25 00:17:47.604244 | LOOP [bindep : Include package tasks] 2025-11-25 00:17:47.666502 | 2025-11-25 00:17:47.666758 | TASK [Run test-setup role] 2025-11-25 00:17:47.691410 | controller | ok 2025-11-25 00:17:47.723106 | 2025-11-25 00:17:47.723273 | TASK [test-setup : Check if project's tools/test-setup.sh exists] 2025-11-25 00:17:47.942535 | controller | ok 2025-11-25 00:17:47.952131 | 2025-11-25 00:17:47.952250 | TASK [test-setup : Run tools/test-setup.sh] 2025-11-25 00:18:18.007529 | [Zuul] Log Stream did not terminate 2025-11-25 00:18:18.007838 | controller | skipping: Conditional result was False 2025-11-25 00:18:18.043429 | 2025-11-25 00:18:18.043528 | PLAY RECAP 2025-11-25 00:18:18.043571 | controller | ok: 4 changed: 0 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2025-11-25 00:18:18.043592 | 2025-11-25 00:18:18.138968 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2025-11-25 00:18:18.140075 | PRE-RUN START: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master] 2025-11-25 00:18:18.748912 | 2025-11-25 00:18:18.749049 | PLAY [controller] 2025-11-25 00:18:18.769243 | 2025-11-25 00:18:18.769359 | TASK [Create the /root directory] 2025-11-25 00:20:31.072375 | controller | ERROR 2025-11-25 00:20:31.072763 | controller | { 2025-11-25 00:20:31.072856 | controller | "msg": "Timeout (32s) waiting for privilege escalation prompt: " 2025-11-25 00:20:31.072923 | controller | } failure 2025-11-25 00:20:31.074619 | 2025-11-25 00:20:31.074720 | PLAY RECAP 2025-11-25 00:20:31.074845 | controller | ok: 0 changed: 0 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0 2025-11-25 00:20:31.074899 | 2025-11-25 00:20:31.191980 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master] 2025-11-25 00:20:31.194195 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2025-11-25 00:20:31.843260 | 2025-11-25 00:20:31.843386 | PLAY [all] 2025-11-25 00:20:31.868617 | 2025-11-25 00:20:31.868772 | TASK [fetch-subunit-output : Find stestr or testr executable] 2025-11-25 00:22:52.858494 | controller | changed: non-zero return code 2025-11-25 00:22:52.871719 | 2025-11-25 00:22:52.871912 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2025-11-25 00:22:52.889811 | controller | skipping: Conditional result was False 2025-11-25 00:22:52.904118 | 2025-11-25 00:22:52.904276 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2025-11-25 00:22:52.944959 | 2025-11-25 00:22:52.945257 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2025-11-25 00:22:52.984782 | 2025-11-25 00:22:52.985055 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2025-11-25 00:22:53.011952 | controller | skipping: Conditional result was False 2025-11-25 00:22:53.027243 | 2025-11-25 00:22:53.027415 | LOOP [fetch-subunit-output : Generate subunit file] 2025-11-25 00:22:53.082062 | 2025-11-25 00:22:53.082392 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2025-11-25 00:22:53.111175 | controller | skipping: Conditional result was False 2025-11-25 00:22:53.120278 | 2025-11-25 00:22:53.120424 | TASK [fetch-subunit-output : Remove the temporary file] 2025-11-25 00:22:53.145429 | controller | skipping: Conditional result was False 2025-11-25 00:22:53.154442 | 2025-11-25 00:22:53.154550 | TASK [fetch-subunit-output : Process and fetch subunit results] 2025-11-25 00:22:53.179507 | controller | skipping: Conditional result was False 2025-11-25 00:22:53.220492 | 2025-11-25 00:22:53.220606 | PLAY RECAP 2025-11-25 00:22:53.220666 | controller | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2025-11-25 00:22:53.220697 | 2025-11-25 00:22:53.335283 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2025-11-25 00:22:53.336125 | POST-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/post.yaml@master] 2025-11-25 00:22:53.948501 | 2025-11-25 00:22:53.948619 | PLAY [all:!appliance*] 2025-11-25 00:22:53.970801 | 2025-11-25 00:22:53.970909 | TASK [unregister the node] 2025-11-25 00:22:54.503514 | controller | skipping: Conditional result was False 2025-11-25 00:22:54.516672 | 2025-11-25 00:22:54.516946 | TASK [include_role : fetch-output] 2025-11-25 00:22:54.569651 | controller | ok 2025-11-25 00:22:54.603401 | 2025-11-25 00:22:54.603539 | TASK [fetch-output : Set log path for multiple nodes] 2025-11-25 00:22:54.659170 | controller | skipping: Conditional result was False 2025-11-25 00:22:54.669573 | 2025-11-25 00:22:54.669709 | TASK [fetch-output : Set log path for single node] 2025-11-25 00:22:54.714301 | controller | ok 2025-11-25 00:22:54.722577 | 2025-11-25 00:22:54.722909 | LOOP [fetch-output : Ensure local output dirs] 2025-11-25 00:22:55.179027 | controller -> localhost | ok: "/var/lib/zuul/builds/9805a2b608f44ebf83e8d5755646d569/work/logs" 2025-11-25 00:22:55.435216 | controller -> localhost | changed: "/var/lib/zuul/builds/9805a2b608f44ebf83e8d5755646d569/work/artifacts" 2025-11-25 00:22:55.721562 | controller -> localhost | changed: "/var/lib/zuul/builds/9805a2b608f44ebf83e8d5755646d569/work/docs" 2025-11-25 00:22:55.743880 | 2025-11-25 00:22:55.744218 | LOOP [fetch-output : Collect logs, artifacts and docs] 2025-11-25 00:22:56.437053 | controller | ok 2025-11-25 00:22:56.437662 | controller | changed: All items complete 2025-11-25 00:22:56.437722 | 2025-11-25 00:22:57.028431 | controller | changed: .d..t...... ./ 2025-11-25 00:22:57.612583 | controller | changed: .d..t...... ./ 2025-11-25 00:22:57.634883 | 2025-11-25 00:22:57.635049 | TASK [include_role : fetch-output-openshift] 2025-11-25 00:22:57.650222 | controller | skipping: Conditional result was False 2025-11-25 00:22:57.657503 | 2025-11-25 00:22:57.657577 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2025-11-25 00:22:57.688890 | controller | skipping: Conditional result was False 2025-11-25 00:22:57.701907 | controller | skipping: Conditional result was False 2025-11-25 00:22:57.741487 | 2025-11-25 00:22:57.741611 | PLAY [localhost] 2025-11-25 00:22:57.756243 | 2025-11-25 00:22:57.756366 | TASK [Run Zuul manifest role] 2025-11-25 00:22:57.775773 | localhost | ok 2025-11-25 00:22:57.790021 | 2025-11-25 00:22:57.790121 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-11-25 00:22:58.176115 | localhost | changed 2025-11-25 00:22:58.181170 | 2025-11-25 00:22:58.181238 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-11-25 00:22:58.209510 | localhost | ok 2025-11-25 00:22:58.217941 | 2025-11-25 00:22:58.218014 | TASK [Set zuul-log-path fact] 2025-11-25 00:22:58.235828 | localhost | ok 2025-11-25 00:22:58.250581 | 2025-11-25 00:22:58.250751 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-11-25 00:22:58.279111 | localhost | ok 2025-11-25 00:22:58.287644 | 2025-11-25 00:22:58.287768 | LOOP [Run upload-logs-swift role] 2025-11-25 00:22:58.312031 | localhost | Output suppressed because no_log was given 2025-11-25 00:22:58.337633 | 2025-11-25 00:22:58.337881 | TASK [Set zuul-log-path fact] 2025-11-25 00:22:58.361603 | localhost | skipping: Conditional result was False 2025-11-25 00:22:58.368510 | 2025-11-25 00:22:58.368652 | TASK [upload-logs-swift : Ensure logs are readable before uploading] 2025-11-25 00:22:58.791328 | localhost -> localhost | ok: Runtime: 0:00:00.007275 2025-11-25 00:22:58.824555 | 2025-11-25 00:22:58.824707 | TASK [upload-logs-swift : Upload logs to swift]