2026-02-27 00:17:13.203611 | Job console starting... 2026-02-27 00:17:13.216696 | Updating repositories 2026-02-27 00:17:13.351538 | Preparing job workspace 2026-02-27 00:17:16.991477 | Running Ansible setup... 2026-02-27 00:17:21.968166 | PRE-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master] 2026-02-27 00:17:22.585640 | 2026-02-27 00:17:22.585812 | PLAY [localhost] 2026-02-27 00:17:22.594906 | 2026-02-27 00:17:22.595009 | TASK [Gathering Facts] 2026-02-27 00:17:23.647152 | localhost | ok 2026-02-27 00:17:23.663914 | 2026-02-27 00:17:23.664070 | TASK [Setup log path fact] 2026-02-27 00:17:23.694491 | localhost | ok 2026-02-27 00:17:23.713185 | 2026-02-27 00:17:23.713308 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-02-27 00:17:23.734674 | localhost | ok 2026-02-27 00:17:23.747506 | 2026-02-27 00:17:23.747736 | TASK [emit-job-header : Print job information] 2026-02-27 00:17:23.782771 | # Job Information 2026-02-27 00:17:23.782906 | Ansible Version: 2.15.12 2026-02-27 00:17:23.782941 | Job: ansible-test-sanity-docker-milestone 2026-02-27 00:17:23.782965 | Pipeline: periodic 2026-02-27 00:17:23.782987 | Executor: ze04.softwarefactory-project.io 2026-02-27 00:17:23.783007 | Triggered by: https://github.com/ansible-collections/ansible.network 2026-02-27 00:17:23.783030 | Log URL (when completed): https://ansible.softwarefactory-project.io/logs/620/ansible/620a2dcaf65d4993a3c7e19534f38ba1/ 2026-02-27 00:17:23.783051 | Event ID: c66414a510e648d9aec75995eff050a1 2026-02-27 00:17:23.788023 | 2026-02-27 00:17:23.788116 | LOOP [emit-job-header : Print node information] 2026-02-27 00:17:23.942496 | localhost | ok: 2026-02-27 00:17:23.942936 | localhost | # Node Information 2026-02-27 00:17:23.943061 | localhost | Inventory Hostname: controller 2026-02-27 00:17:23.943139 | localhost | Hostname: np0005632239 2026-02-27 00:17:23.943293 | localhost | Username: zuul 2026-02-27 00:17:23.943347 | localhost | Distro: Fedora 37 2026-02-27 00:17:23.943455 | localhost | Provider: ansible-vexxhost-ca-ymq-1 2026-02-27 00:17:23.943534 | localhost | Region: ca-ymq-1 2026-02-27 00:17:23.943556 | localhost | Label: ansible-fedora-37-1vcpu 2026-02-27 00:17:23.943574 | localhost | Product Name: OpenStack Nova 2026-02-27 00:17:23.943708 | localhost | Interface IP: 199.204.45.240 2026-02-27 00:17:23.965116 | 2026-02-27 00:17:23.965388 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-02-27 00:17:24.398575 | localhost -> localhost | changed 2026-02-27 00:17:24.419547 | 2026-02-27 00:17:24.419777 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-02-27 00:17:25.418573 | localhost -> localhost | changed 2026-02-27 00:17:25.450785 | 2026-02-27 00:17:25.450964 | PLAY [all:!appliance*] 2026-02-27 00:17:25.471519 | 2026-02-27 00:17:25.471661 | TASK [include_role : start-zuul-console] 2026-02-27 00:17:25.516752 | controller | ok 2026-02-27 00:17:25.565630 | 2026-02-27 00:17:25.565910 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-02-27 00:17:26.006720 | controller | ok 2026-02-27 00:17:26.017734 | 2026-02-27 00:17:26.017860 | TASK [use-our-mirror : Retrieve the IP address] 2026-02-27 00:17:27.056442 | controller | ok: Status code was 404 and not [200]: HTTP Error 404: Not Found 2026-02-27 00:17:27.071762 | 2026-02-27 00:17:27.071982 | TASK [use-our-mirror : Ensure the registry service is running on the mirror] 2026-02-27 00:17:27.625226 | controller | skipping: Conditional result was False 2026-02-27 00:17:27.635220 | 2026-02-27 00:17:27.635360 | TASK [use-our-mirror : Write the IP in /etc/hosts] 2026-02-27 00:17:27.662140 | controller | skipping: Conditional result was False 2026-02-27 00:17:27.680287 | 2026-02-27 00:17:27.680716 | TASK [use-our-mirror : Create the podman configuration directory] 2026-02-27 00:17:27.709270 | controller | skipping: Conditional result was False 2026-02-27 00:17:27.719791 | 2026-02-27 00:17:27.719951 | TASK [use-our-mirror : Copy the podman configuration] 2026-02-27 00:17:27.745847 | controller | skipping: Conditional result was False 2026-02-27 00:17:27.756344 | 2026-02-27 00:17:27.756560 | TASK [use-our-mirror : Copy the Yum configuration (main)] 2026-02-27 00:17:27.782892 | controller | skipping: Conditional result was False 2026-02-27 00:17:27.792578 | 2026-02-27 00:17:27.792731 | TASK [use-our-mirror : Copy the Yum configuration (update)] 2026-02-27 00:17:27.817994 | controller | skipping: Conditional result was False 2026-02-27 00:17:27.836149 | 2026-02-27 00:17:27.836292 | TASK [Disable Fedora Modular] 2026-02-27 00:17:28.071016 | controller | changed 2026-02-27 00:17:28.077091 | 2026-02-27 00:17:28.077160 | TASK [Enable EPEL] 2026-02-27 00:17:28.092459 | controller | skipping: Conditional result was False 2026-02-27 00:17:28.102272 | 2026-02-27 00:17:28.102600 | TASK [Register the RHEL node] 2026-02-27 00:17:28.664485 | 2026-02-27 00:17:28.664780 | TASK [Show the subscription-manager status] 2026-02-27 00:17:29.238768 | controller | skipping: Conditional result was False 2026-02-27 00:17:29.249850 | 2026-02-27 00:17:29.250004 | TASK [Enable EPEL on RHEL] 2026-02-27 00:17:29.801333 | controller | skipping: Conditional result was False 2026-02-27 00:17:29.810532 | 2026-02-27 00:17:29.810637 | TASK [Install git and tox] 2026-02-27 00:18:47.358200 | controller | changed 2026-02-27 00:18:47.373106 | 2026-02-27 00:18:47.373279 | TASK [include_role : prepare-workspace] 2026-02-27 00:18:47.415710 | controller | ok 2026-02-27 00:18:47.449328 | 2026-02-27 00:18:47.449457 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-02-27 00:18:47.681633 | controller | ok 2026-02-27 00:18:47.700309 | 2026-02-27 00:18:47.700513 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-02-27 00:19:01.791785 | controller | Output suppressed because no_log was given 2026-02-27 00:19:01.807063 | 2026-02-27 00:19:01.807212 | TASK [include_role : prepare-workspace-openshift] 2026-02-27 00:19:01.823973 | controller | skipping: Conditional result was False 2026-02-27 00:19:01.849350 | 2026-02-27 00:19:01.849446 | PLAY [all:!appliance] 2026-02-27 00:19:01.899308 | 2026-02-27 00:19:01.899440 | TASK [Run add-build-sshkey role (RSA)] 2026-02-27 00:19:01.930136 | controller | ok 2026-02-27 00:19:01.946087 | 2026-02-27 00:19:01.946188 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-02-27 00:19:02.214980 | controller -> localhost | ok 2026-02-27 00:19:02.221593 | 2026-02-27 00:19:02.221759 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-02-27 00:19:02.242396 | controller | ok 2026-02-27 00:19:02.257770 | controller | included: /var/lib/zuul/builds/620a2dcaf65d4993a3c7e19534f38ba1/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-02-27 00:19:02.263505 | 2026-02-27 00:19:02.263568 | TASK [add-build-sshkey : Create Temp SSH key] 2026-02-27 00:19:02.789438 | controller -> localhost | Generating public/private rsa key pair. 2026-02-27 00:19:02.789797 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/620a2dcaf65d4993a3c7e19534f38ba1/work/620a2dcaf65d4993a3c7e19534f38ba1_id_rsa. 2026-02-27 00:19:02.789868 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/620a2dcaf65d4993a3c7e19534f38ba1/work/620a2dcaf65d4993a3c7e19534f38ba1_id_rsa.pub. 2026-02-27 00:19:02.789922 | controller -> localhost | The key fingerprint is: 2026-02-27 00:19:02.789972 | controller -> localhost | SHA256:AEYE8YdrUbvs/FiW1buO9JJ6iwcFWBWuWmvDEDEps3k zuul-build-sshkey 2026-02-27 00:19:02.790019 | controller -> localhost | The key's randomart image is: 2026-02-27 00:19:02.790062 | controller -> localhost | +---[RSA 2048]----+ 2026-02-27 00:19:02.790103 | controller -> localhost | | o== ++..o. | 2026-02-27 00:19:02.790146 | controller -> localhost | | oo+o+.. | 2026-02-27 00:19:02.790189 | controller -> localhost | | +== .. | 2026-02-27 00:19:02.790236 | controller -> localhost | | o=E+ ... | 2026-02-27 00:19:02.790284 | controller -> localhost | | o.+ S.. . | 2026-02-27 00:19:02.790348 | controller -> localhost | | . o =.+ . | 2026-02-27 00:19:02.790406 | controller -> localhost | | + O.... | 2026-02-27 00:19:02.790460 | controller -> localhost | | * +=o . | 2026-02-27 00:19:02.790508 | controller -> localhost | | . ++o++ | 2026-02-27 00:19:02.790552 | controller -> localhost | +----[SHA256]-----+ 2026-02-27 00:19:02.790640 | controller -> localhost | ok: Runtime: 0:00:00.125759 2026-02-27 00:19:02.805080 | 2026-02-27 00:19:02.805273 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-02-27 00:19:02.832330 | controller | ok 2026-02-27 00:19:02.849927 | controller | included: /var/lib/zuul/builds/620a2dcaf65d4993a3c7e19534f38ba1/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-02-27 00:19:02.864613 | 2026-02-27 00:19:02.864809 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-02-27 00:19:02.891252 | controller | skipping: Conditional result was False 2026-02-27 00:19:02.901842 | 2026-02-27 00:19:02.901988 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-02-27 00:19:03.345585 | controller | changed 2026-02-27 00:19:03.354932 | 2026-02-27 00:19:03.355030 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-02-27 00:19:03.589450 | controller | ok 2026-02-27 00:19:03.595439 | 2026-02-27 00:19:03.595509 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-02-27 00:19:04.233015 | controller | changed 2026-02-27 00:19:04.248435 | 2026-02-27 00:19:04.248613 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-02-27 00:19:04.895243 | controller | changed 2026-02-27 00:19:04.910289 | 2026-02-27 00:19:04.910474 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-02-27 00:19:04.947770 | controller | skipping: Conditional result was False 2026-02-27 00:19:04.958303 | 2026-02-27 00:19:04.958451 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-02-27 00:19:05.362068 | controller -> localhost | changed 2026-02-27 00:19:05.378435 | 2026-02-27 00:19:05.378569 | TASK [add-build-sshkey : Add back temp key] 2026-02-27 00:19:05.670147 | controller -> localhost | Identity added: /var/lib/zuul/builds/620a2dcaf65d4993a3c7e19534f38ba1/work/620a2dcaf65d4993a3c7e19534f38ba1_id_rsa (zuul-build-sshkey) 2026-02-27 00:19:05.670351 | controller -> localhost | ok: Runtime: 0:00:00.009463 2026-02-27 00:19:05.678777 | 2026-02-27 00:19:05.678894 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-02-27 00:19:06.056599 | controller | ok 2026-02-27 00:19:06.062786 | 2026-02-27 00:19:06.062882 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-02-27 00:19:06.098244 | controller | skipping: Conditional result was False 2026-02-27 00:19:06.111023 | 2026-02-27 00:19:06.111126 | TASK [Run add-build-sshkey role (ECDSA)] 2026-02-27 00:19:06.142071 | controller | ok 2026-02-27 00:19:06.162934 | 2026-02-27 00:19:06.163055 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-02-27 00:19:06.443511 | controller -> localhost | ok 2026-02-27 00:19:06.460191 | 2026-02-27 00:19:06.460553 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-02-27 00:19:06.496227 | controller | ok 2026-02-27 00:19:06.508759 | controller | included: /var/lib/zuul/builds/620a2dcaf65d4993a3c7e19534f38ba1/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-02-27 00:19:06.516038 | 2026-02-27 00:19:06.516141 | TASK [add-build-sshkey : Create Temp SSH key] 2026-02-27 00:19:06.818133 | controller -> localhost | Generating public/private ecdsa key pair. 2026-02-27 00:19:06.818467 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/620a2dcaf65d4993a3c7e19534f38ba1/work/620a2dcaf65d4993a3c7e19534f38ba1_id_ecdsa. 2026-02-27 00:19:06.818536 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/620a2dcaf65d4993a3c7e19534f38ba1/work/620a2dcaf65d4993a3c7e19534f38ba1_id_ecdsa.pub. 2026-02-27 00:19:06.818606 | controller -> localhost | The key fingerprint is: 2026-02-27 00:19:06.818659 | controller -> localhost | SHA256:dqV18sESXOQdGtT85qlSTntOB9tXskya96fPmLJfgRM zuul-build-sshkey 2026-02-27 00:19:06.818786 | controller -> localhost | The key's randomart image is: 2026-02-27 00:19:06.818866 | controller -> localhost | +---[ECDSA 521]---+ 2026-02-27 00:19:06.818915 | controller -> localhost | | .o==. | 2026-02-27 00:19:06.818961 | controller -> localhost | | .+o+.| 2026-02-27 00:19:06.819006 | controller -> localhost | | =E= o| 2026-02-27 00:19:06.819049 | controller -> localhost | | + =o.o| 2026-02-27 00:19:06.819089 | controller -> localhost | | S o o=+o| 2026-02-27 00:19:06.819171 | controller -> localhost | | . . B.B+| 2026-02-27 00:19:06.819216 | controller -> localhost | | * B.=| 2026-02-27 00:19:06.819257 | controller -> localhost | | ..=.B+| 2026-02-27 00:19:06.819324 | controller -> localhost | | o+B+=| 2026-02-27 00:19:06.819370 | controller -> localhost | +----[SHA256]-----+ 2026-02-27 00:19:06.819488 | controller -> localhost | ok: Runtime: 0:00:00.015902 2026-02-27 00:19:06.835519 | 2026-02-27 00:19:06.835673 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-02-27 00:19:06.868490 | controller | ok 2026-02-27 00:19:06.886069 | controller | included: /var/lib/zuul/builds/620a2dcaf65d4993a3c7e19534f38ba1/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-02-27 00:19:06.899862 | 2026-02-27 00:19:06.900002 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-02-27 00:19:06.926191 | controller | skipping: Conditional result was False 2026-02-27 00:19:06.937294 | 2026-02-27 00:19:06.937422 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-02-27 00:19:07.215078 | controller | changed 2026-02-27 00:19:07.229146 | 2026-02-27 00:19:07.229361 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-02-27 00:19:07.474337 | controller | ok 2026-02-27 00:19:07.489128 | 2026-02-27 00:19:07.489299 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-02-27 00:19:08.122853 | controller | changed 2026-02-27 00:19:08.138319 | 2026-02-27 00:19:08.138526 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-02-27 00:19:08.781270 | controller | changed 2026-02-27 00:19:08.794074 | 2026-02-27 00:19:08.794231 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-02-27 00:19:08.822366 | controller | skipping: Conditional result was False 2026-02-27 00:19:08.835212 | 2026-02-27 00:19:08.835394 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-02-27 00:19:09.158528 | controller -> localhost | changed 2026-02-27 00:19:09.187449 | 2026-02-27 00:19:09.187639 | TASK [add-build-sshkey : Add back temp key] 2026-02-27 00:19:09.542408 | controller -> localhost | Identity added: /var/lib/zuul/builds/620a2dcaf65d4993a3c7e19534f38ba1/work/620a2dcaf65d4993a3c7e19534f38ba1_id_ecdsa (zuul-build-sshkey) 2026-02-27 00:19:09.542839 | controller -> localhost | ok: Runtime: 0:00:00.014772 2026-02-27 00:19:09.558800 | 2026-02-27 00:19:09.558993 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-02-27 00:19:09.773516 | controller | ok 2026-02-27 00:19:09.788028 | 2026-02-27 00:19:09.788229 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-02-27 00:19:09.827920 | controller | skipping: Conditional result was False 2026-02-27 00:19:09.906967 | 2026-02-27 00:19:09.907125 | TASK [include_role : remove-zuul-sshkey] 2026-02-27 00:19:09.931657 | controller | skipping: Conditional result was False 2026-02-27 00:19:09.939009 | 2026-02-27 00:19:09.939102 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-02-27 00:19:10.181301 | controller | ok: "logs" 2026-02-27 00:19:10.181675 | controller | ok: All items complete 2026-02-27 00:19:10.181750 | 2026-02-27 00:19:10.380401 | controller | ok: "artifacts" 2026-02-27 00:19:10.568833 | controller | ok: "docs" 2026-02-27 00:19:10.585109 | 2026-02-27 00:19:10.585257 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-02-27 00:19:10.840416 | controller | changed: "logs" 2026-02-27 00:19:11.031573 | controller | changed: "artifacts" 2026-02-27 00:19:11.226285 | controller | changed: "docs" 2026-02-27 00:19:11.274665 | 2026-02-27 00:19:11.274808 | PLAY RECAP 2026-02-27 00:19:11.274858 | controller | ok: 30 changed: 16 unreachable: 0 failed: 0 skipped: 18 rescued: 0 ignored: 0 2026-02-27 00:19:11.274886 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-02-27 00:19:11.274905 | 2026-02-27 00:19:11.421779 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master] 2026-02-27 00:19:11.423654 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2026-02-27 00:19:12.079347 | 2026-02-27 00:19:12.079540 | PLAY [all] 2026-02-27 00:19:12.104220 | 2026-02-27 00:19:12.104377 | TASK [Install binary dependencies] 2026-02-27 00:19:12.167259 | controller | ok 2026-02-27 00:19:12.196593 | 2026-02-27 00:19:12.196823 | TASK [bindep : Include find tasks] 2026-02-27 00:19:12.238802 | controller | ok 2026-02-27 00:19:12.247298 | controller | included: /var/lib/zuul/builds/620a2dcaf65d4993a3c7e19534f38ba1/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find.yaml 2026-02-27 00:19:12.254154 | 2026-02-27 00:19:12.254250 | TASK [bindep : Look for bindep.txt] 2026-02-27 00:19:12.757251 | controller | ok 2026-02-27 00:19:12.765575 | 2026-02-27 00:19:12.765771 | TASK [bindep : Define bindep_file fact] 2026-02-27 00:19:12.791887 | controller | skipping: Conditional result was False 2026-02-27 00:19:12.802003 | 2026-02-27 00:19:12.802129 | TASK [bindep : Look for other-requirements.txt] 2026-02-27 00:19:13.040836 | controller | ok 2026-02-27 00:19:13.053978 | 2026-02-27 00:19:13.054175 | TASK [bindep : Define bindep_file fact] 2026-02-27 00:19:13.091450 | controller | skipping: Conditional result was False 2026-02-27 00:19:13.119595 | 2026-02-27 00:19:13.119836 | TASK [bindep : Look for bindep fallback file] 2026-02-27 00:19:13.174562 | controller | skipping: Conditional result was False 2026-02-27 00:19:13.184473 | 2026-02-27 00:19:13.184638 | TASK [bindep : Define bindep_file fact] 2026-02-27 00:19:13.220548 | controller | skipping: Conditional result was False 2026-02-27 00:19:13.229408 | 2026-02-27 00:19:13.229538 | TASK [bindep : Include bindep tasks] 2026-02-27 00:19:13.254998 | controller | skipping: Conditional result was False 2026-02-27 00:19:13.262593 | 2026-02-27 00:19:13.262754 | TASK [bindep : Include install tasks] 2026-02-27 00:19:13.287483 | controller | skipping: Conditional result was False 2026-02-27 00:19:13.294085 | 2026-02-27 00:19:13.294181 | LOOP [bindep : Include package tasks] 2026-02-27 00:19:13.360777 | 2026-02-27 00:19:13.360947 | TASK [Run test-setup role] 2026-02-27 00:19:13.380956 | controller | ok 2026-02-27 00:19:13.397908 | 2026-02-27 00:19:13.398027 | TASK [test-setup : Check if project's tools/test-setup.sh exists] 2026-02-27 00:19:13.603166 | controller | ok 2026-02-27 00:19:13.611252 | 2026-02-27 00:19:13.611337 | TASK [test-setup : Run tools/test-setup.sh] 2026-02-27 00:19:14.154181 | controller | skipping: Conditional result was False 2026-02-27 00:19:14.194243 | 2026-02-27 00:19:14.194350 | PLAY RECAP 2026-02-27 00:19:14.194402 | controller | ok: 4 changed: 0 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2026-02-27 00:19:14.194431 | 2026-02-27 00:19:14.317868 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2026-02-27 00:19:14.318729 | PRE-RUN START: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master] 2026-02-27 00:19:14.940661 | 2026-02-27 00:19:14.940816 | PLAY [controller] 2026-02-27 00:19:14.974034 | 2026-02-27 00:19:14.974154 | TASK [Create the /root directory] 2026-02-27 00:21:27.289029 | controller | ERROR 2026-02-27 00:21:27.289398 | controller | { 2026-02-27 00:21:27.289539 | controller | "msg": "Timeout (32s) waiting for privilege escalation prompt: " 2026-02-27 00:21:27.289615 | controller | } failure 2026-02-27 00:21:27.304406 | 2026-02-27 00:21:27.304578 | PLAY RECAP 2026-02-27 00:21:27.304712 | controller | ok: 0 changed: 0 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0 2026-02-27 00:21:27.304775 | 2026-02-27 00:21:27.414471 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master] 2026-02-27 00:21:27.416002 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2026-02-27 00:21:28.026145 | 2026-02-27 00:21:28.026265 | PLAY [all] 2026-02-27 00:21:28.048612 | 2026-02-27 00:21:28.048726 | TASK [fetch-subunit-output : Find stestr or testr executable] 2026-02-27 00:24:43.841363 | controller | changed: non-zero return code 2026-02-27 00:24:43.858912 | 2026-02-27 00:24:43.859077 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2026-02-27 00:24:43.884796 | controller | skipping: Conditional result was False 2026-02-27 00:24:43.892648 | 2026-02-27 00:24:43.892761 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2026-02-27 00:24:43.924947 | 2026-02-27 00:24:43.925107 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2026-02-27 00:24:43.959201 | 2026-02-27 00:24:43.959486 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2026-02-27 00:24:43.985435 | controller | skipping: Conditional result was False 2026-02-27 00:24:43.995480 | 2026-02-27 00:24:43.995582 | LOOP [fetch-subunit-output : Generate subunit file] 2026-02-27 00:24:44.029281 | 2026-02-27 00:24:44.029835 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2026-02-27 00:24:44.055022 | controller | skipping: Conditional result was False 2026-02-27 00:24:44.066031 | 2026-02-27 00:24:44.066161 | TASK [fetch-subunit-output : Remove the temporary file] 2026-02-27 00:24:44.102984 | controller | skipping: Conditional result was False 2026-02-27 00:24:44.115857 | 2026-02-27 00:24:44.115994 | TASK [fetch-subunit-output : Process and fetch subunit results] 2026-02-27 00:24:44.161514 | controller | skipping: Conditional result was False 2026-02-27 00:24:44.216735 | 2026-02-27 00:24:44.216944 | PLAY RECAP 2026-02-27 00:24:44.217015 | controller | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2026-02-27 00:24:44.217047 | 2026-02-27 00:24:44.332611 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2026-02-27 00:24:44.333810 | POST-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/post.yaml@master] 2026-02-27 00:24:44.925162 | 2026-02-27 00:24:44.925278 | PLAY [all:!appliance*] 2026-02-27 00:24:44.946415 | 2026-02-27 00:24:44.946532 | TASK [unregister the node] 2026-02-27 00:24:45.478419 | controller | skipping: Conditional result was False 2026-02-27 00:24:45.484374 | 2026-02-27 00:24:45.484454 | TASK [include_role : fetch-output] 2026-02-27 00:24:45.534366 | controller | ok 2026-02-27 00:24:45.581140 | 2026-02-27 00:24:45.581350 | TASK [fetch-output : Set log path for multiple nodes] 2026-02-27 00:24:45.628076 | controller | skipping: Conditional result was False 2026-02-27 00:24:45.636901 | 2026-02-27 00:24:45.637001 | TASK [fetch-output : Set log path for single node] 2026-02-27 00:24:45.680584 | controller | ok 2026-02-27 00:24:45.689925 | 2026-02-27 00:24:45.690045 | LOOP [fetch-output : Ensure local output dirs] 2026-02-27 00:24:46.169052 | controller -> localhost | ok: "/var/lib/zuul/builds/620a2dcaf65d4993a3c7e19534f38ba1/work/logs" 2026-02-27 00:24:46.459576 | controller -> localhost | changed: "/var/lib/zuul/builds/620a2dcaf65d4993a3c7e19534f38ba1/work/artifacts" 2026-02-27 00:24:46.734284 | controller -> localhost | changed: "/var/lib/zuul/builds/620a2dcaf65d4993a3c7e19534f38ba1/work/docs" 2026-02-27 00:24:46.755204 | 2026-02-27 00:24:46.755358 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-02-27 00:24:47.485771 | controller | changed: .d..t...... ./ 2026-02-27 00:24:47.486152 | controller | changed: All items complete 2026-02-27 00:24:47.486223 | 2026-02-27 00:24:48.020983 | controller | changed: .d..t...... ./ 2026-02-27 00:24:48.555766 | controller | changed: .d..t...... ./ 2026-02-27 00:24:48.588438 | 2026-02-27 00:24:48.588595 | TASK [include_role : fetch-output-openshift] 2026-02-27 00:24:48.607337 | controller | skipping: Conditional result was False 2026-02-27 00:24:48.618025 | 2026-02-27 00:24:48.618151 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-02-27 00:24:48.648565 | controller | skipping: Conditional result was False 2026-02-27 00:24:48.660434 | controller | skipping: Conditional result was False 2026-02-27 00:24:48.714147 | 2026-02-27 00:24:48.714256 | PLAY [localhost] 2026-02-27 00:24:48.727183 | 2026-02-27 00:24:48.727347 | TASK [Run Zuul manifest role] 2026-02-27 00:24:48.745596 | localhost | ok 2026-02-27 00:24:48.763502 | 2026-02-27 00:24:48.763585 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-02-27 00:24:49.226045 | localhost | changed 2026-02-27 00:24:49.237310 | 2026-02-27 00:24:49.237463 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-02-27 00:24:49.284787 | localhost | ok 2026-02-27 00:24:49.302802 | 2026-02-27 00:24:49.302939 | TASK [Set zuul-log-path fact] 2026-02-27 00:24:49.326033 | localhost | ok 2026-02-27 00:24:49.348769 | 2026-02-27 00:24:49.348908 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-02-27 00:24:49.391057 | localhost | ok 2026-02-27 00:24:49.402800 | 2026-02-27 00:24:49.402893 | LOOP [Set zuul_log_targets (Swift with random Rackspace region)] 2026-02-27 00:24:49.458669 | localhost | ok: 2026-02-27 00:24:49.459492 | localhost | { 2026-02-27 00:24:49.459600 | localhost | "auth": { 2026-02-27 00:24:49.459941 | localhost | "password": "IonWCwcuOFib&&<}wp^:[2G/", 2026-02-27 00:24:49.460022 | localhost | "username": "9LF91O0RK7JU" 2026-02-27 00:24:49.460167 | localhost | }, 2026-02-27 00:24:49.460297 | localhost | "profile": "rackspace", 2026-02-27 00:24:49.460584 | localhost | "region_name": "IAD" 2026-02-27 00:24:49.460661 | localhost | } 2026-02-27 00:24:49.460777 | 2026-02-27 00:24:49.478370 | 2026-02-27 00:24:49.478493 | TASK [Run upload-logs-failover role (S3 then Swift)] 2026-02-27 00:24:49.501960 | localhost | Output suppressed because no_log was given 2026-02-27 00:24:49.548344 | 2026-02-27 00:24:49.548546 | TASK [upload-logs-failover : Initialize control vars] 2026-02-27 00:24:49.581921 | localhost | ok 2026-02-27 00:24:49.591059 | 2026-02-27 00:24:49.591192 | LOOP [upload-logs-failover : Perform log upload] 2026-02-27 00:24:49.665564 | localhost | Output suppressed because no_log was given 2026-02-27 00:24:49.673215 | localhost | included: /var/lib/zuul/builds/620a2dcaf65d4993a3c7e19534f38ba1/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/upload-logs-failover/tasks/upload.yaml 2026-02-27 00:24:49.680389 | 2026-02-27 00:24:49.680515 | LOOP [upload-logs-failover : Unpack vars for swift upload] 2026-02-27 00:24:49.757897 | localhost | Output suppressed because no_log was given 2026-02-27 00:24:49.764327 | 2026-02-27 00:24:49.764451 | TASK [Upload logs to swift] 2026-02-27 00:24:49.782549 | localhost | ok 2026-02-27 00:24:49.813577 | 2026-02-27 00:24:49.813756 | TASK [Set zuul-log-path fact] 2026-02-27 00:24:49.838484 | localhost | skipping: Conditional result was False 2026-02-27 00:24:49.843755 | 2026-02-27 00:24:49.843870 | TASK [upload-logs-swift : Ensure logs are readable before uploading] 2026-02-27 00:24:50.215942 | localhost -> localhost | ok: Runtime: 0:00:00.005106 2026-02-27 00:24:50.240466 | 2026-02-27 00:24:50.240649 | TASK [upload-logs-swift : Upload logs to swift]