2025-11-18 00:14:17.565876 | Job console starting... 2025-11-18 00:14:17.577457 | Updating repositories 2025-11-18 00:14:17.710916 | Preparing job workspace 2025-11-18 00:14:21.412269 | Running Ansible setup... 2025-11-18 00:14:25.973841 | PRE-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master] 2025-11-18 00:14:26.625507 | 2025-11-18 00:14:26.625671 | PLAY [localhost] 2025-11-18 00:14:26.636411 | 2025-11-18 00:14:26.636543 | TASK [Gathering Facts] 2025-11-18 00:14:27.653111 | localhost | ok 2025-11-18 00:14:27.681132 | 2025-11-18 00:14:27.681300 | TASK [Setup log path fact] 2025-11-18 00:14:27.704864 | localhost | ok 2025-11-18 00:14:27.724117 | 2025-11-18 00:14:27.724268 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-11-18 00:14:27.755963 | localhost | ok 2025-11-18 00:14:27.767628 | 2025-11-18 00:14:27.767801 | TASK [emit-job-header : Print job information] 2025-11-18 00:14:27.806808 | # Job Information 2025-11-18 00:14:27.806964 | Ansible Version: 2.15.12 2025-11-18 00:14:27.806994 | Job: ansible-test-sanity-docker-stable-2.10 2025-11-18 00:14:27.807015 | Pipeline: periodic 2025-11-18 00:14:27.807033 | Executor: ze04.softwarefactory-project.io 2025-11-18 00:14:27.807050 | Triggered by: https://github.com/ansible-collections/ansible.network 2025-11-18 00:14:27.807069 | Log URL (when completed): https://ansible.softwarefactory-project.io/logs/330/ansible/330dd27a202545feb45504e5eaa6e1c4/ 2025-11-18 00:14:27.807087 | Event ID: ce1d8018739b49068127d11881773b47 2025-11-18 00:14:27.810774 | 2025-11-18 00:14:27.810835 | LOOP [emit-job-header : Print node information] 2025-11-18 00:14:27.924657 | localhost | ok: 2025-11-18 00:14:27.924831 | localhost | # Node Information 2025-11-18 00:14:27.924859 | localhost | Inventory Hostname: controller 2025-11-18 00:14:27.924879 | localhost | Hostname: np0005525956 2025-11-18 00:14:27.924899 | localhost | Username: zuul 2025-11-18 00:14:27.924922 | localhost | Distro: Fedora 37 2025-11-18 00:14:27.924942 | localhost | Provider: ansible-vexxhost-ca-ymq-1 2025-11-18 00:14:27.924960 | localhost | Region: ca-ymq-1 2025-11-18 00:14:27.924978 | localhost | Label: ansible-fedora-37-1vcpu 2025-11-18 00:14:27.924995 | localhost | Product Name: OpenStack Nova 2025-11-18 00:14:27.925012 | localhost | Interface IP: 162.253.55.62 2025-11-18 00:14:27.948337 | 2025-11-18 00:14:27.948534 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-11-18 00:14:28.362878 | localhost -> localhost | changed 2025-11-18 00:14:28.368663 | 2025-11-18 00:14:28.368789 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-11-18 00:14:29.324792 | localhost -> localhost | changed 2025-11-18 00:14:29.353281 | 2025-11-18 00:14:29.353503 | PLAY [all:!appliance*] 2025-11-18 00:14:29.373589 | 2025-11-18 00:14:29.373824 | TASK [include_role : start-zuul-console] 2025-11-18 00:14:29.395152 | controller | ok 2025-11-18 00:14:29.409940 | 2025-11-18 00:14:29.410048 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-11-18 00:14:29.900641 | controller | ok 2025-11-18 00:14:29.925491 | 2025-11-18 00:14:29.925634 | TASK [use-our-mirror : Retrieve the IP address] 2025-11-18 00:14:30.902199 | controller | ok: Status code was 404 and not [200]: HTTP Error 404: Not Found 2025-11-18 00:14:30.911300 | 2025-11-18 00:14:30.911426 | TASK [use-our-mirror : Ensure the registry service is running on the mirror] 2025-11-18 00:14:31.451057 | controller | skipping: Conditional result was False 2025-11-18 00:14:31.467184 | 2025-11-18 00:14:31.467379 | TASK [use-our-mirror : Write the IP in /etc/hosts] 2025-11-18 00:14:31.494842 | controller | skipping: Conditional result was False 2025-11-18 00:14:31.505007 | 2025-11-18 00:14:31.505143 | TASK [use-our-mirror : Create the podman configuration directory] 2025-11-18 00:14:31.530458 | controller | skipping: Conditional result was False 2025-11-18 00:14:31.540486 | 2025-11-18 00:14:31.540608 | TASK [use-our-mirror : Copy the podman configuration] 2025-11-18 00:14:31.565823 | controller | skipping: Conditional result was False 2025-11-18 00:14:31.575173 | 2025-11-18 00:14:31.575284 | TASK [use-our-mirror : Copy the Yum configuration (main)] 2025-11-18 00:14:31.600039 | controller | skipping: Conditional result was False 2025-11-18 00:14:31.613193 | 2025-11-18 00:14:31.613515 | TASK [use-our-mirror : Copy the Yum configuration (update)] 2025-11-18 00:14:31.639954 | controller | skipping: Conditional result was False 2025-11-18 00:14:31.663994 | 2025-11-18 00:14:31.664164 | TASK [Disable Fedora Modular] 2025-11-18 00:14:31.912773 | controller | changed 2025-11-18 00:14:31.922040 | 2025-11-18 00:14:31.922165 | TASK [Enable EPEL] 2025-11-18 00:14:31.947955 | controller | skipping: Conditional result was False 2025-11-18 00:14:31.955917 | 2025-11-18 00:14:31.956012 | TASK [Register the RHEL node] 2025-11-18 00:14:32.505064 | 2025-11-18 00:14:32.505304 | TASK [Show the subscription-manager status] 2025-11-18 00:14:33.088690 | controller | skipping: Conditional result was False 2025-11-18 00:14:33.096833 | 2025-11-18 00:14:33.096922 | TASK [Enable EPEL on RHEL] 2025-11-18 00:14:33.632060 | controller | skipping: Conditional result was False 2025-11-18 00:14:33.657304 | 2025-11-18 00:14:33.657637 | TASK [Install git and tox] 2025-11-18 00:15:53.833186 | controller | changed 2025-11-18 00:15:53.848446 | 2025-11-18 00:15:53.848622 | TASK [include_role : prepare-workspace] 2025-11-18 00:15:53.878818 | controller | ok 2025-11-18 00:15:53.915184 | 2025-11-18 00:15:53.915317 | TASK [prepare-workspace : Start zuul_console daemon.] 2025-11-18 00:15:54.158851 | controller | ok 2025-11-18 00:15:54.177244 | 2025-11-18 00:15:54.177501 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2025-11-18 00:16:06.421729 | controller | Output suppressed because no_log was given 2025-11-18 00:16:06.480336 | 2025-11-18 00:16:06.480458 | TASK [include_role : prepare-workspace-openshift] 2025-11-18 00:16:06.506211 | controller | skipping: Conditional result was False 2025-11-18 00:16:06.534209 | 2025-11-18 00:16:06.534320 | PLAY [all:!appliance] 2025-11-18 00:16:06.553859 | 2025-11-18 00:16:06.554006 | TASK [Run add-build-sshkey role (RSA)] 2025-11-18 00:16:06.574985 | controller | ok 2025-11-18 00:16:06.592249 | 2025-11-18 00:16:06.592354 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-11-18 00:16:06.820980 | controller -> localhost | ok 2025-11-18 00:16:06.828274 | 2025-11-18 00:16:06.828390 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-11-18 00:16:06.849553 | controller | ok 2025-11-18 00:16:06.864779 | controller | included: /var/lib/zuul/builds/330dd27a202545feb45504e5eaa6e1c4/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-11-18 00:16:06.871292 | 2025-11-18 00:16:06.871366 | TASK [add-build-sshkey : Create Temp SSH key] 2025-11-18 00:16:07.490895 | controller -> localhost | Generating public/private rsa key pair. 2025-11-18 00:16:07.491313 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/330dd27a202545feb45504e5eaa6e1c4/work/330dd27a202545feb45504e5eaa6e1c4_id_rsa. 2025-11-18 00:16:07.491392 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/330dd27a202545feb45504e5eaa6e1c4/work/330dd27a202545feb45504e5eaa6e1c4_id_rsa.pub. 2025-11-18 00:16:07.491451 | controller -> localhost | The key fingerprint is: 2025-11-18 00:16:07.491513 | controller -> localhost | SHA256:vv7U+Gb86OBNF0rnf6TFXpH5LEDpNyU8eid/fO0qiBU zuul-build-sshkey 2025-11-18 00:16:07.491574 | controller -> localhost | The key's randomart image is: 2025-11-18 00:16:07.491630 | controller -> localhost | +---[RSA 2048]----+ 2025-11-18 00:16:07.491736 | controller -> localhost | | o | 2025-11-18 00:16:07.491803 | controller -> localhost | | o + .| 2025-11-18 00:16:07.491846 | controller -> localhost | | o . +o| 2025-11-18 00:16:07.491888 | controller -> localhost | | E + =+.| 2025-11-18 00:16:07.491929 | controller -> localhost | | S ..+oO+| 2025-11-18 00:16:07.491988 | controller -> localhost | | . .+ +..&| 2025-11-18 00:16:07.492039 | controller -> localhost | | .o+o+ oB+| 2025-11-18 00:16:07.492084 | controller -> localhost | | .+.==o..+| 2025-11-18 00:16:07.492126 | controller -> localhost | | .o.o+=oo.o| 2025-11-18 00:16:07.492176 | controller -> localhost | +----[SHA256]-----+ 2025-11-18 00:16:07.492267 | controller -> localhost | ok: Runtime: 0:00:00.181365 2025-11-18 00:16:07.507357 | 2025-11-18 00:16:07.507428 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-11-18 00:16:07.538309 | controller | ok 2025-11-18 00:16:07.548604 | controller | included: /var/lib/zuul/builds/330dd27a202545feb45504e5eaa6e1c4/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-11-18 00:16:07.557949 | 2025-11-18 00:16:07.558015 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-11-18 00:16:07.582453 | controller | skipping: Conditional result was False 2025-11-18 00:16:07.591429 | 2025-11-18 00:16:07.591524 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-11-18 00:16:08.038546 | controller | changed 2025-11-18 00:16:08.056008 | 2025-11-18 00:16:08.056226 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-11-18 00:16:08.286977 | controller | ok 2025-11-18 00:16:08.303246 | 2025-11-18 00:16:08.303404 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-11-18 00:16:08.932327 | controller | changed 2025-11-18 00:16:08.940596 | 2025-11-18 00:16:08.940751 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-11-18 00:16:09.597987 | controller | changed 2025-11-18 00:16:09.612061 | 2025-11-18 00:16:09.612220 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-11-18 00:16:09.640661 | controller | skipping: Conditional result was False 2025-11-18 00:16:09.656998 | 2025-11-18 00:16:09.657246 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-11-18 00:16:10.189818 | controller -> localhost | changed 2025-11-18 00:16:10.204963 | 2025-11-18 00:16:10.205089 | TASK [add-build-sshkey : Add back temp key] 2025-11-18 00:16:10.475623 | controller -> localhost | Identity added: /var/lib/zuul/builds/330dd27a202545feb45504e5eaa6e1c4/work/330dd27a202545feb45504e5eaa6e1c4_id_rsa (zuul-build-sshkey) 2025-11-18 00:16:10.475864 | controller -> localhost | ok: Runtime: 0:00:00.008578 2025-11-18 00:16:10.482179 | 2025-11-18 00:16:10.482274 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-11-18 00:16:10.852120 | controller | ok 2025-11-18 00:16:10.872510 | 2025-11-18 00:16:10.872736 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-11-18 00:16:10.897385 | controller | skipping: Conditional result was False 2025-11-18 00:16:10.913603 | 2025-11-18 00:16:10.913753 | TASK [Run add-build-sshkey role (ECDSA)] 2025-11-18 00:16:10.945597 | controller | ok 2025-11-18 00:16:10.962054 | 2025-11-18 00:16:10.962150 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-11-18 00:16:11.242504 | controller -> localhost | ok 2025-11-18 00:16:11.252967 | 2025-11-18 00:16:11.253089 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-11-18 00:16:11.277523 | controller | ok 2025-11-18 00:16:11.294194 | controller | included: /var/lib/zuul/builds/330dd27a202545feb45504e5eaa6e1c4/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-11-18 00:16:11.305471 | 2025-11-18 00:16:11.305628 | TASK [add-build-sshkey : Create Temp SSH key] 2025-11-18 00:16:11.617858 | controller -> localhost | Generating public/private ecdsa key pair. 2025-11-18 00:16:11.618018 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/330dd27a202545feb45504e5eaa6e1c4/work/330dd27a202545feb45504e5eaa6e1c4_id_ecdsa. 2025-11-18 00:16:11.618046 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/330dd27a202545feb45504e5eaa6e1c4/work/330dd27a202545feb45504e5eaa6e1c4_id_ecdsa.pub. 2025-11-18 00:16:11.618076 | controller -> localhost | The key fingerprint is: 2025-11-18 00:16:11.618097 | controller -> localhost | SHA256:HSdUVdxZqTjRJgs/QFYo/fHP1nl/e1KykM/on6jGZUk zuul-build-sshkey 2025-11-18 00:16:11.618118 | controller -> localhost | The key's randomart image is: 2025-11-18 00:16:11.618138 | controller -> localhost | +---[ECDSA 521]---+ 2025-11-18 00:16:11.618157 | controller -> localhost | | oo+oo..oB| 2025-11-18 00:16:11.618175 | controller -> localhost | | ..* + o oo| 2025-11-18 00:16:11.618192 | controller -> localhost | | . B X . | 2025-11-18 00:16:11.618210 | controller -> localhost | | . @Eo | 2025-11-18 00:16:11.618227 | controller -> localhost | | S ..ooo o| 2025-11-18 00:16:11.618243 | controller -> localhost | | * .=+| 2025-11-18 00:16:11.618260 | controller -> localhost | | . o =.+o| 2025-11-18 00:16:11.618277 | controller -> localhost | | o ..=.+| 2025-11-18 00:16:11.618293 | controller -> localhost | | ..oo.oo+| 2025-11-18 00:16:11.618310 | controller -> localhost | +----[SHA256]-----+ 2025-11-18 00:16:11.618353 | controller -> localhost | ok: Runtime: 0:00:00.009619 2025-11-18 00:16:11.624808 | 2025-11-18 00:16:11.624903 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-11-18 00:16:11.654118 | controller | ok 2025-11-18 00:16:11.661719 | controller | included: /var/lib/zuul/builds/330dd27a202545feb45504e5eaa6e1c4/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-11-18 00:16:11.671143 | 2025-11-18 00:16:11.671239 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-11-18 00:16:11.695107 | controller | skipping: Conditional result was False 2025-11-18 00:16:11.702622 | 2025-11-18 00:16:11.702729 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-11-18 00:16:11.971742 | controller | changed 2025-11-18 00:16:11.984553 | 2025-11-18 00:16:11.984732 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-11-18 00:16:12.225618 | controller | ok 2025-11-18 00:16:12.235539 | 2025-11-18 00:16:12.235657 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-11-18 00:16:12.903445 | controller | changed 2025-11-18 00:16:12.911545 | 2025-11-18 00:16:12.911663 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-11-18 00:16:13.525446 | controller | changed 2025-11-18 00:16:13.532155 | 2025-11-18 00:16:13.532264 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-11-18 00:16:13.556903 | controller | skipping: Conditional result was False 2025-11-18 00:16:13.564113 | 2025-11-18 00:16:13.564259 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-11-18 00:16:13.844299 | controller -> localhost | changed 2025-11-18 00:16:13.893893 | 2025-11-18 00:16:13.894014 | TASK [add-build-sshkey : Add back temp key] 2025-11-18 00:16:14.173398 | controller -> localhost | Identity added: /var/lib/zuul/builds/330dd27a202545feb45504e5eaa6e1c4/work/330dd27a202545feb45504e5eaa6e1c4_id_ecdsa (zuul-build-sshkey) 2025-11-18 00:16:14.173653 | controller -> localhost | ok: Runtime: 0:00:00.015251 2025-11-18 00:16:14.182653 | 2025-11-18 00:16:14.182797 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-11-18 00:16:14.387827 | controller | ok 2025-11-18 00:16:14.393710 | 2025-11-18 00:16:14.393782 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-11-18 00:16:14.428510 | controller | skipping: Conditional result was False 2025-11-18 00:16:14.449396 | 2025-11-18 00:16:14.449513 | TASK [include_role : remove-zuul-sshkey] 2025-11-18 00:16:14.474338 | controller | skipping: Conditional result was False 2025-11-18 00:16:14.484023 | 2025-11-18 00:16:14.484121 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2025-11-18 00:16:14.718145 | controller | ok: "logs" 2025-11-18 00:16:14.718400 | controller | ok: All items complete 2025-11-18 00:16:14.718432 | 2025-11-18 00:16:14.907490 | controller | ok: "artifacts" 2025-11-18 00:16:15.094523 | controller | ok: "docs" 2025-11-18 00:16:15.103273 | 2025-11-18 00:16:15.103406 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2025-11-18 00:16:15.340052 | controller | changed: "logs" 2025-11-18 00:16:15.518412 | controller | changed: "artifacts" 2025-11-18 00:16:15.710719 | controller | changed: "docs" 2025-11-18 00:16:15.759410 | 2025-11-18 00:16:15.759537 | PLAY RECAP 2025-11-18 00:16:15.759596 | controller | ok: 30 changed: 16 unreachable: 0 failed: 0 skipped: 18 rescued: 0 ignored: 0 2025-11-18 00:16:15.759635 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-11-18 00:16:15.759663 | 2025-11-18 00:16:15.887034 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master] 2025-11-18 00:16:15.888026 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2025-11-18 00:16:16.484035 | 2025-11-18 00:16:16.484166 | PLAY [all] 2025-11-18 00:16:16.508905 | 2025-11-18 00:16:16.509043 | TASK [Install binary dependencies] 2025-11-18 00:16:16.562711 | controller | ok 2025-11-18 00:16:16.594237 | 2025-11-18 00:16:16.594437 | TASK [bindep : Include find tasks] 2025-11-18 00:16:16.627352 | controller | ok 2025-11-18 00:16:16.636804 | controller | included: /var/lib/zuul/builds/330dd27a202545feb45504e5eaa6e1c4/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find.yaml 2025-11-18 00:16:16.644867 | 2025-11-18 00:16:16.645051 | TASK [bindep : Look for bindep.txt] 2025-11-18 00:16:16.992261 | controller | ok 2025-11-18 00:16:16.998871 | 2025-11-18 00:16:16.998955 | TASK [bindep : Define bindep_file fact] 2025-11-18 00:16:17.023798 | controller | skipping: Conditional result was False 2025-11-18 00:16:17.032373 | 2025-11-18 00:16:17.032493 | TASK [bindep : Look for other-requirements.txt] 2025-11-18 00:16:17.258604 | controller | ok 2025-11-18 00:16:17.267346 | 2025-11-18 00:16:17.267487 | TASK [bindep : Define bindep_file fact] 2025-11-18 00:16:17.303915 | controller | skipping: Conditional result was False 2025-11-18 00:16:17.314006 | 2025-11-18 00:16:17.314162 | TASK [bindep : Look for bindep fallback file] 2025-11-18 00:16:17.350172 | controller | skipping: Conditional result was False 2025-11-18 00:16:17.360870 | 2025-11-18 00:16:17.361025 | TASK [bindep : Define bindep_file fact] 2025-11-18 00:16:17.397774 | controller | skipping: Conditional result was False 2025-11-18 00:16:17.414090 | 2025-11-18 00:16:17.414266 | TASK [bindep : Include bindep tasks] 2025-11-18 00:16:17.444466 | controller | skipping: Conditional result was False 2025-11-18 00:16:17.455501 | 2025-11-18 00:16:17.455729 | TASK [bindep : Include install tasks] 2025-11-18 00:16:17.494090 | controller | skipping: Conditional result was False 2025-11-18 00:16:17.506135 | 2025-11-18 00:16:17.506483 | LOOP [bindep : Include package tasks] 2025-11-18 00:16:17.587034 | 2025-11-18 00:16:17.587389 | TASK [Run test-setup role] 2025-11-18 00:16:17.623598 | controller | ok 2025-11-18 00:16:17.662476 | 2025-11-18 00:16:17.662879 | TASK [test-setup : Check if project's tools/test-setup.sh exists] 2025-11-18 00:16:17.874780 | controller | ok 2025-11-18 00:16:17.883425 | 2025-11-18 00:16:17.883608 | TASK [test-setup : Run tools/test-setup.sh] 2025-11-18 00:16:18.426328 | controller | skipping: Conditional result was False 2025-11-18 00:16:18.483569 | 2025-11-18 00:16:18.483705 | PLAY RECAP 2025-11-18 00:16:18.483752 | controller | ok: 4 changed: 0 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2025-11-18 00:16:18.483774 | 2025-11-18 00:16:18.610966 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2025-11-18 00:16:18.612809 | PRE-RUN START: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master] 2025-11-18 00:16:19.252038 | 2025-11-18 00:16:19.252222 | PLAY [controller] 2025-11-18 00:16:19.275405 | 2025-11-18 00:16:19.275552 | TASK [Create the /root directory] 2025-11-18 00:18:31.641273 | controller | ERROR 2025-11-18 00:18:31.641638 | controller | { 2025-11-18 00:18:31.641800 | controller | "msg": "Timeout (32s) waiting for privilege escalation prompt: " 2025-11-18 00:18:31.641883 | controller | } failure 2025-11-18 00:18:31.644436 | 2025-11-18 00:18:31.644540 | PLAY RECAP 2025-11-18 00:18:31.644620 | controller | ok: 0 changed: 0 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0 2025-11-18 00:18:31.644668 | 2025-11-18 00:18:31.754014 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master] 2025-11-18 00:18:31.754979 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2025-11-18 00:18:32.476968 | 2025-11-18 00:18:32.477192 | PLAY [all] 2025-11-18 00:18:32.507403 | 2025-11-18 00:18:32.507630 | TASK [fetch-subunit-output : Find stestr or testr executable] 2025-11-18 00:21:42.598721 | controller | changed: non-zero return code 2025-11-18 00:21:42.605053 | 2025-11-18 00:21:42.605148 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2025-11-18 00:21:42.629557 | controller | skipping: Conditional result was False 2025-11-18 00:21:42.636057 | 2025-11-18 00:21:42.636149 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2025-11-18 00:21:42.677224 | 2025-11-18 00:21:42.677383 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2025-11-18 00:21:42.707904 | 2025-11-18 00:21:42.708060 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2025-11-18 00:21:42.722442 | controller | skipping: Conditional result was False 2025-11-18 00:21:42.729955 | 2025-11-18 00:21:42.730055 | LOOP [fetch-subunit-output : Generate subunit file] 2025-11-18 00:21:42.763764 | 2025-11-18 00:21:42.763978 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2025-11-18 00:21:42.780630 | controller | skipping: Conditional result was False 2025-11-18 00:21:42.793333 | 2025-11-18 00:21:42.793475 | TASK [fetch-subunit-output : Remove the temporary file] 2025-11-18 00:21:42.819588 | controller | skipping: Conditional result was False 2025-11-18 00:21:42.829390 | 2025-11-18 00:21:42.829505 | TASK [fetch-subunit-output : Process and fetch subunit results] 2025-11-18 00:21:42.854448 | controller | skipping: Conditional result was False 2025-11-18 00:21:42.899777 | 2025-11-18 00:21:42.899889 | PLAY RECAP 2025-11-18 00:21:42.899953 | controller | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2025-11-18 00:21:42.899983 | 2025-11-18 00:21:43.004230 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2025-11-18 00:21:43.006110 | POST-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/post.yaml@master] 2025-11-18 00:21:43.712994 | 2025-11-18 00:21:43.713132 | PLAY [all:!appliance*] 2025-11-18 00:21:43.736706 | 2025-11-18 00:21:43.736829 | TASK [unregister the node] 2025-11-18 00:21:44.272602 | controller | skipping: Conditional result was False 2025-11-18 00:21:44.285941 | 2025-11-18 00:21:44.286135 | TASK [include_role : fetch-output] 2025-11-18 00:21:44.346305 | controller | ok 2025-11-18 00:21:44.377029 | 2025-11-18 00:21:44.377131 | TASK [fetch-output : Set log path for multiple nodes] 2025-11-18 00:21:44.443526 | controller | skipping: Conditional result was False 2025-11-18 00:21:44.450334 | 2025-11-18 00:21:44.450411 | TASK [fetch-output : Set log path for single node] 2025-11-18 00:21:44.493645 | controller | ok 2025-11-18 00:21:44.501825 | 2025-11-18 00:21:44.501947 | LOOP [fetch-output : Ensure local output dirs] 2025-11-18 00:21:44.963328 | controller -> localhost | ok: "/var/lib/zuul/builds/330dd27a202545feb45504e5eaa6e1c4/work/logs" 2025-11-18 00:21:45.222790 | controller -> localhost | changed: "/var/lib/zuul/builds/330dd27a202545feb45504e5eaa6e1c4/work/artifacts" 2025-11-18 00:21:45.449973 | controller -> localhost | changed: "/var/lib/zuul/builds/330dd27a202545feb45504e5eaa6e1c4/work/docs" 2025-11-18 00:21:45.464165 | 2025-11-18 00:21:45.464256 | LOOP [fetch-output : Collect logs, artifacts and docs] 2025-11-18 00:21:46.112040 | controller | ok 2025-11-18 00:21:46.112598 | controller | changed: All items complete 2025-11-18 00:21:46.112654 | 2025-11-18 00:21:46.645342 | controller | changed: .d..t...... ./ 2025-11-18 00:21:47.174484 | controller | changed: .d..t...... ./ 2025-11-18 00:21:47.194955 | 2025-11-18 00:21:47.195052 | TASK [include_role : fetch-output-openshift] 2025-11-18 00:21:47.219583 | controller | skipping: Conditional result was False 2025-11-18 00:21:47.230174 | 2025-11-18 00:21:47.230289 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2025-11-18 00:21:47.266355 | controller | skipping: Conditional result was False 2025-11-18 00:21:47.278574 | controller | skipping: Conditional result was False 2025-11-18 00:21:47.317707 | 2025-11-18 00:21:47.317823 | PLAY [localhost] 2025-11-18 00:21:47.331347 | 2025-11-18 00:21:47.331425 | TASK [Run Zuul manifest role] 2025-11-18 00:21:47.349828 | localhost | ok 2025-11-18 00:21:47.364883 | 2025-11-18 00:21:47.364987 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-11-18 00:21:47.735696 | localhost | changed 2025-11-18 00:21:47.744140 | 2025-11-18 00:21:47.744294 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-11-18 00:21:47.777510 | localhost | ok 2025-11-18 00:21:47.790328 | 2025-11-18 00:21:47.790468 | TASK [Set zuul-log-path fact] 2025-11-18 00:21:47.813127 | localhost | ok 2025-11-18 00:21:47.837352 | 2025-11-18 00:21:47.837521 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-11-18 00:21:47.870568 | localhost | ok 2025-11-18 00:21:47.883993 | 2025-11-18 00:21:47.884154 | LOOP [Run upload-logs-swift role] 2025-11-18 00:21:47.910983 | localhost | Output suppressed because no_log was given 2025-11-18 00:21:47.950283 | 2025-11-18 00:21:47.950414 | TASK [Set zuul-log-path fact] 2025-11-18 00:21:47.975123 | localhost | skipping: Conditional result was False 2025-11-18 00:21:47.982954 | 2025-11-18 00:21:47.983056 | TASK [upload-logs-swift : Ensure logs are readable before uploading] 2025-11-18 00:21:48.389121 | localhost -> localhost | ok: Runtime: 0:00:00.009406 2025-11-18 00:21:48.422401 | 2025-11-18 00:21:48.422521 | TASK [upload-logs-swift : Upload logs to swift]