2025-11-10 00:14:15.553718 | Job console starting... 2025-11-10 00:14:15.563045 | Updating repositories 2025-11-10 00:14:15.716966 | Preparing job workspace 2025-11-10 00:14:19.739870 | Running Ansible setup... 2025-11-10 00:14:24.549958 | PRE-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master] 2025-11-10 00:14:25.369897 | 2025-11-10 00:14:25.370017 | PLAY [localhost] 2025-11-10 00:14:25.378174 | 2025-11-10 00:14:25.378240 | TASK [Gathering Facts] 2025-11-10 00:14:26.447793 | localhost | ok 2025-11-10 00:14:26.468984 | 2025-11-10 00:14:26.469124 | TASK [Setup log path fact] 2025-11-10 00:14:26.510501 | localhost | ok 2025-11-10 00:14:26.526052 | 2025-11-10 00:14:26.526119 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-11-10 00:14:26.575641 | localhost | ok 2025-11-10 00:14:26.584368 | 2025-11-10 00:14:26.584432 | TASK [emit-job-header : Print job information] 2025-11-10 00:14:26.623806 | # Job Information 2025-11-10 00:14:26.623956 | Ansible Version: 2.15.12 2025-11-10 00:14:26.623987 | Job: ansible-test-sanity-docker-devel 2025-11-10 00:14:26.624008 | Pipeline: periodic 2025-11-10 00:14:26.624049 | Executor: ze01.softwarefactory-project.io 2025-11-10 00:14:26.624069 | Triggered by: https://github.com/ansible-collections/ansible.network 2025-11-10 00:14:26.624090 | Log URL (when completed): https://ansible.softwarefactory-project.io/logs/19d/ansible/19dd6e41d869425c973829c54a5b99e8/ 2025-11-10 00:14:26.624108 | Event ID: e7ed9acfd5d246fc9eb02e45c4dd8a00 2025-11-10 00:14:26.627708 | 2025-11-10 00:14:26.627768 | LOOP [emit-job-header : Print node information] 2025-11-10 00:14:26.843103 | localhost | ok: 2025-11-10 00:14:26.843300 | localhost | # Node Information 2025-11-10 00:14:26.843328 | localhost | Inventory Hostname: controller 2025-11-10 00:14:26.843348 | localhost | Hostname: np0005516256 2025-11-10 00:14:26.843367 | localhost | Username: zuul 2025-11-10 00:14:26.843390 | localhost | Distro: Fedora 37 2025-11-10 00:14:26.843408 | localhost | Provider: ansible-vexxhost-ca-ymq-1 2025-11-10 00:14:26.843426 | localhost | Region: ca-ymq-1 2025-11-10 00:14:26.843442 | localhost | Label: ansible-fedora-37-1vcpu 2025-11-10 00:14:26.843459 | localhost | Product Name: OpenStack Nova 2025-11-10 00:14:26.843475 | localhost | Interface IP: 162.253.55.195 2025-11-10 00:14:26.854912 | 2025-11-10 00:14:26.855054 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-11-10 00:14:27.451486 | localhost -> localhost | changed 2025-11-10 00:14:27.461217 | 2025-11-10 00:14:27.461773 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-11-10 00:14:29.089093 | localhost -> localhost | changed 2025-11-10 00:14:29.137477 | 2025-11-10 00:14:29.137574 | PLAY [all:!appliance*] 2025-11-10 00:14:29.173791 | 2025-11-10 00:14:29.173893 | TASK [include_role : start-zuul-console] 2025-11-10 00:14:29.207086 | controller | ok 2025-11-10 00:14:29.234402 | 2025-11-10 00:14:29.234473 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-11-10 00:14:29.745776 | controller | ok 2025-11-10 00:14:29.766941 | 2025-11-10 00:14:29.767227 | TASK [use-our-mirror : Retrieve the IP address] 2025-11-10 00:14:31.272129 | controller | ok: Status code was 404 and not [200]: HTTP Error 404: Not Found 2025-11-10 00:14:31.278833 | 2025-11-10 00:14:31.278904 | TASK [use-our-mirror : Ensure the registry service is running on the mirror] 2025-11-10 00:14:31.874602 | controller | skipping: Conditional result was False 2025-11-10 00:14:31.881376 | 2025-11-10 00:14:31.881464 | TASK [use-our-mirror : Write the IP in /etc/hosts] 2025-11-10 00:14:31.917702 | controller | skipping: Conditional result was False 2025-11-10 00:14:31.924575 | 2025-11-10 00:14:31.924670 | TASK [use-our-mirror : Create the podman configuration directory] 2025-11-10 00:14:31.982393 | controller | skipping: Conditional result was False 2025-11-10 00:14:31.989612 | 2025-11-10 00:14:31.989726 | TASK [use-our-mirror : Copy the podman configuration] 2025-11-10 00:14:32.027222 | controller | skipping: Conditional result was False 2025-11-10 00:14:32.033555 | 2025-11-10 00:14:32.033671 | TASK [use-our-mirror : Copy the Yum configuration (main)] 2025-11-10 00:14:32.100484 | controller | skipping: Conditional result was False 2025-11-10 00:14:32.107100 | 2025-11-10 00:14:32.107193 | TASK [use-our-mirror : Copy the Yum configuration (update)] 2025-11-10 00:14:32.145746 | controller | skipping: Conditional result was False 2025-11-10 00:14:32.162300 | 2025-11-10 00:14:32.162430 | TASK [Disable Fedora Modular] 2025-11-10 00:14:32.432898 | controller | changed 2025-11-10 00:14:32.444297 | 2025-11-10 00:14:32.444391 | TASK [Enable EPEL] 2025-11-10 00:14:32.472680 | controller | skipping: Conditional result was False 2025-11-10 00:14:32.478798 | 2025-11-10 00:14:32.478870 | TASK [Register the RHEL node] 2025-11-10 00:14:33.060047 | 2025-11-10 00:14:33.060213 | TASK [Show the subscription-manager status] 2025-11-10 00:14:33.603375 | controller | skipping: Conditional result was False 2025-11-10 00:14:33.613539 | 2025-11-10 00:14:33.613632 | TASK [Enable EPEL on RHEL] 2025-11-10 00:14:34.148988 | controller | skipping: Conditional result was False 2025-11-10 00:14:34.155001 | 2025-11-10 00:14:34.155088 | TASK [Install git and tox] 2025-11-10 00:15:53.768778 | controller | changed 2025-11-10 00:15:53.784607 | 2025-11-10 00:15:53.784762 | TASK [include_role : prepare-workspace] 2025-11-10 00:15:53.827396 | controller | ok 2025-11-10 00:15:53.866713 | 2025-11-10 00:15:53.866823 | TASK [prepare-workspace : Start zuul_console daemon.] 2025-11-10 00:15:54.120771 | controller | ok 2025-11-10 00:15:54.136166 | 2025-11-10 00:15:54.136318 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2025-11-10 00:16:06.194593 | controller | Output suppressed because no_log was given 2025-11-10 00:16:06.217828 | 2025-11-10 00:16:06.217962 | TASK [include_role : prepare-workspace-openshift] 2025-11-10 00:16:06.248204 | controller | skipping: Conditional result was False 2025-11-10 00:16:06.292010 | 2025-11-10 00:16:06.292133 | PLAY [all:!appliance] 2025-11-10 00:16:06.313801 | 2025-11-10 00:16:06.313885 | TASK [Run add-build-sshkey role (RSA)] 2025-11-10 00:16:06.346893 | controller | ok 2025-11-10 00:16:06.367413 | 2025-11-10 00:16:06.367508 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-11-10 00:16:06.636360 | controller -> localhost | ok 2025-11-10 00:16:06.648078 | 2025-11-10 00:16:06.648187 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-11-10 00:16:06.678601 | controller | ok 2025-11-10 00:16:06.707771 | controller | included: /var/lib/zuul/builds/19dd6e41d869425c973829c54a5b99e8/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-11-10 00:16:06.716715 | 2025-11-10 00:16:06.716857 | TASK [add-build-sshkey : Create Temp SSH key] 2025-11-10 00:16:07.222427 | controller -> localhost | Generating public/private rsa key pair. 2025-11-10 00:16:07.222728 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/19dd6e41d869425c973829c54a5b99e8/work/19dd6e41d869425c973829c54a5b99e8_id_rsa. 2025-11-10 00:16:07.222772 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/19dd6e41d869425c973829c54a5b99e8/work/19dd6e41d869425c973829c54a5b99e8_id_rsa.pub. 2025-11-10 00:16:07.222804 | controller -> localhost | The key fingerprint is: 2025-11-10 00:16:07.222834 | controller -> localhost | SHA256:D1dIui+UWAk6a9jSqVMzb5szG7c6+u35sLojCFinj2A zuul-build-sshkey 2025-11-10 00:16:07.222866 | controller -> localhost | The key's randomart image is: 2025-11-10 00:16:07.222894 | controller -> localhost | +---[RSA 2048]----+ 2025-11-10 00:16:07.222921 | controller -> localhost | | . . | 2025-11-10 00:16:07.222949 | controller -> localhost | | . . + . | 2025-11-10 00:16:07.222975 | controller -> localhost | | o + . . | 2025-11-10 00:16:07.223001 | controller -> localhost | | .+.+ o o . | 2025-11-10 00:16:07.223053 | controller -> localhost | |..ooX . S . | 2025-11-10 00:16:07.223095 | controller -> localhost | |+E.= + . = | 2025-11-10 00:16:07.223136 | controller -> localhost | |.oo+ + + o | 2025-11-10 00:16:07.223167 | controller -> localhost | | o.o.=* * | 2025-11-10 00:16:07.223194 | controller -> localhost | | .+O@*.. | 2025-11-10 00:16:07.223224 | controller -> localhost | +----[SHA256]-----+ 2025-11-10 00:16:07.223298 | controller -> localhost | ok: Runtime: 0:00:00.078237 2025-11-10 00:16:07.234455 | 2025-11-10 00:16:07.234583 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-11-10 00:16:07.268945 | controller | ok 2025-11-10 00:16:07.282487 | controller | included: /var/lib/zuul/builds/19dd6e41d869425c973829c54a5b99e8/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-11-10 00:16:07.292493 | 2025-11-10 00:16:07.292571 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-11-10 00:16:07.318415 | controller | skipping: Conditional result was False 2025-11-10 00:16:07.332728 | 2025-11-10 00:16:07.332912 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-11-10 00:16:07.796073 | controller | changed 2025-11-10 00:16:07.812126 | 2025-11-10 00:16:07.812283 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-11-10 00:16:08.050817 | controller | ok 2025-11-10 00:16:08.064044 | 2025-11-10 00:16:08.064180 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-11-10 00:16:08.716812 | controller | changed 2025-11-10 00:16:08.729877 | 2025-11-10 00:16:08.730059 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-11-10 00:16:09.359534 | controller | changed 2025-11-10 00:16:09.368849 | 2025-11-10 00:16:09.368953 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-11-10 00:16:09.408278 | controller | skipping: Conditional result was False 2025-11-10 00:16:09.419731 | 2025-11-10 00:16:09.419830 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-11-10 00:16:09.852534 | controller -> localhost | changed 2025-11-10 00:16:09.865140 | 2025-11-10 00:16:09.865217 | TASK [add-build-sshkey : Add back temp key] 2025-11-10 00:16:10.153116 | controller -> localhost | Identity added: /var/lib/zuul/builds/19dd6e41d869425c973829c54a5b99e8/work/19dd6e41d869425c973829c54a5b99e8_id_rsa (zuul-build-sshkey) 2025-11-10 00:16:10.153349 | controller -> localhost | ok: Runtime: 0:00:00.012909 2025-11-10 00:16:10.160004 | 2025-11-10 00:16:10.160081 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-11-10 00:16:10.504068 | controller | ok 2025-11-10 00:16:10.509931 | 2025-11-10 00:16:10.510063 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-11-10 00:16:10.544983 | controller | skipping: Conditional result was False 2025-11-10 00:16:10.557542 | 2025-11-10 00:16:10.557609 | TASK [Run add-build-sshkey role (ECDSA)] 2025-11-10 00:16:10.587852 | controller | ok 2025-11-10 00:16:10.604614 | 2025-11-10 00:16:10.604682 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-11-10 00:16:10.853046 | controller -> localhost | ok 2025-11-10 00:16:10.859635 | 2025-11-10 00:16:10.859699 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-11-10 00:16:10.889650 | controller | ok 2025-11-10 00:16:10.901630 | controller | included: /var/lib/zuul/builds/19dd6e41d869425c973829c54a5b99e8/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-11-10 00:16:10.907960 | 2025-11-10 00:16:10.908050 | TASK [add-build-sshkey : Create Temp SSH key] 2025-11-10 00:16:11.208375 | controller -> localhost | Generating public/private ecdsa key pair. 2025-11-10 00:16:11.208775 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/19dd6e41d869425c973829c54a5b99e8/work/19dd6e41d869425c973829c54a5b99e8_id_ecdsa. 2025-11-10 00:16:11.208835 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/19dd6e41d869425c973829c54a5b99e8/work/19dd6e41d869425c973829c54a5b99e8_id_ecdsa.pub. 2025-11-10 00:16:11.208900 | controller -> localhost | The key fingerprint is: 2025-11-10 00:16:11.208944 | controller -> localhost | SHA256:CQQr9PvciAREUWoyNEwYPmoymU18Sg0OnrHqYX0uIro zuul-build-sshkey 2025-11-10 00:16:11.208984 | controller -> localhost | The key's randomart image is: 2025-11-10 00:16:11.209059 | controller -> localhost | +---[ECDSA 521]---+ 2025-11-10 00:16:11.209102 | controller -> localhost | |*B*oo.. | 2025-11-10 00:16:11.209139 | controller -> localhost | |=B== o | 2025-11-10 00:16:11.209176 | controller -> localhost | |o*X = . | 2025-11-10 00:16:11.209212 | controller -> localhost | |oXo= . . . | 2025-11-10 00:16:11.209249 | controller -> localhost | |B+o.o. S | 2025-11-10 00:16:11.209284 | controller -> localhost | |=...o+ o | 2025-11-10 00:16:11.209320 | controller -> localhost | |.o ...+ . | 2025-11-10 00:16:11.209356 | controller -> localhost | |o . . | 2025-11-10 00:16:11.209392 | controller -> localhost | |E. | 2025-11-10 00:16:11.209427 | controller -> localhost | +----[SHA256]-----+ 2025-11-10 00:16:11.209541 | controller -> localhost | ok: Runtime: 0:00:00.013973 2025-11-10 00:16:11.224995 | 2025-11-10 00:16:11.225159 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-11-10 00:16:11.255295 | controller | ok 2025-11-10 00:16:11.272129 | controller | included: /var/lib/zuul/builds/19dd6e41d869425c973829c54a5b99e8/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-11-10 00:16:11.287205 | 2025-11-10 00:16:11.287299 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-11-10 00:16:11.313237 | controller | skipping: Conditional result was False 2025-11-10 00:16:11.323382 | 2025-11-10 00:16:11.323477 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-11-10 00:16:11.596203 | controller | changed 2025-11-10 00:16:11.608939 | 2025-11-10 00:16:11.609103 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-11-10 00:16:11.846791 | controller | ok 2025-11-10 00:16:11.862497 | 2025-11-10 00:16:11.862689 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-11-10 00:16:12.510830 | controller | changed 2025-11-10 00:16:12.525094 | 2025-11-10 00:16:12.525250 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-11-10 00:16:13.168096 | controller | changed 2025-11-10 00:16:13.184355 | 2025-11-10 00:16:13.184566 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-11-10 00:16:13.211329 | controller | skipping: Conditional result was False 2025-11-10 00:16:13.225848 | 2025-11-10 00:16:13.225999 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-11-10 00:16:13.555362 | controller -> localhost | changed 2025-11-10 00:16:13.579146 | 2025-11-10 00:16:13.579302 | TASK [add-build-sshkey : Add back temp key] 2025-11-10 00:16:13.924662 | controller -> localhost | Identity added: /var/lib/zuul/builds/19dd6e41d869425c973829c54a5b99e8/work/19dd6e41d869425c973829c54a5b99e8_id_ecdsa (zuul-build-sshkey) 2025-11-10 00:16:13.925008 | controller -> localhost | ok: Runtime: 0:00:00.014664 2025-11-10 00:16:13.938768 | 2025-11-10 00:16:13.938939 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-11-10 00:16:14.172774 | controller | ok 2025-11-10 00:16:14.185239 | 2025-11-10 00:16:14.185391 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-11-10 00:16:14.233898 | controller | skipping: Conditional result was False 2025-11-10 00:16:14.260984 | 2025-11-10 00:16:14.261297 | TASK [include_role : remove-zuul-sshkey] 2025-11-10 00:16:14.288689 | controller | skipping: Conditional result was False 2025-11-10 00:16:14.305277 | 2025-11-10 00:16:14.305467 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2025-11-10 00:16:14.553804 | controller | ok: "logs" 2025-11-10 00:16:14.554273 | controller | ok: All items complete 2025-11-10 00:16:14.554336 | 2025-11-10 00:16:14.743165 | controller | ok: "artifacts" 2025-11-10 00:16:14.937140 | controller | ok: "docs" 2025-11-10 00:16:14.952622 | 2025-11-10 00:16:14.952928 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2025-11-10 00:16:15.203639 | controller | changed: "logs" 2025-11-10 00:16:15.390811 | controller | changed: "artifacts" 2025-11-10 00:16:15.585674 | controller | changed: "docs" 2025-11-10 00:16:15.641120 | 2025-11-10 00:16:15.641267 | PLAY RECAP 2025-11-10 00:16:15.641350 | controller | ok: 30 changed: 16 unreachable: 0 failed: 0 skipped: 18 rescued: 0 ignored: 0 2025-11-10 00:16:15.641407 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-11-10 00:16:15.641444 | 2025-11-10 00:16:15.773575 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master] 2025-11-10 00:16:15.775455 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2025-11-10 00:16:16.422718 | 2025-11-10 00:16:16.422876 | PLAY [all] 2025-11-10 00:16:16.447011 | 2025-11-10 00:16:16.447154 | TASK [Install binary dependencies] 2025-11-10 00:16:16.520693 | controller | ok 2025-11-10 00:16:16.550890 | 2025-11-10 00:16:16.551082 | TASK [bindep : Include find tasks] 2025-11-10 00:16:16.592071 | controller | ok 2025-11-10 00:16:16.599782 | controller | included: /var/lib/zuul/builds/19dd6e41d869425c973829c54a5b99e8/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find.yaml 2025-11-10 00:16:16.606268 | 2025-11-10 00:16:16.606406 | TASK [bindep : Look for bindep.txt] 2025-11-10 00:16:16.991808 | controller | ok 2025-11-10 00:16:17.005805 | 2025-11-10 00:16:17.005955 | TASK [bindep : Define bindep_file fact] 2025-11-10 00:16:17.034388 | controller | skipping: Conditional result was False 2025-11-10 00:16:17.047989 | 2025-11-10 00:16:17.048167 | TASK [bindep : Look for other-requirements.txt] 2025-11-10 00:16:17.281046 | controller | ok 2025-11-10 00:16:17.293740 | 2025-11-10 00:16:17.293886 | TASK [bindep : Define bindep_file fact] 2025-11-10 00:16:17.332010 | controller | skipping: Conditional result was False 2025-11-10 00:16:17.339440 | 2025-11-10 00:16:17.339529 | TASK [bindep : Look for bindep fallback file] 2025-11-10 00:16:17.378838 | controller | skipping: Conditional result was False 2025-11-10 00:16:17.396284 | 2025-11-10 00:16:17.396498 | TASK [bindep : Define bindep_file fact] 2025-11-10 00:16:17.435232 | controller | skipping: Conditional result was False 2025-11-10 00:16:17.448876 | 2025-11-10 00:16:17.449117 | TASK [bindep : Include bindep tasks] 2025-11-10 00:16:17.477888 | controller | skipping: Conditional result was False 2025-11-10 00:16:17.485676 | 2025-11-10 00:16:17.485787 | TASK [bindep : Include install tasks] 2025-11-10 00:16:17.512672 | controller | skipping: Conditional result was False 2025-11-10 00:16:17.521351 | 2025-11-10 00:16:17.521448 | LOOP [bindep : Include package tasks] 2025-11-10 00:16:17.593456 | 2025-11-10 00:16:17.593674 | TASK [Run test-setup role] 2025-11-10 00:16:17.618202 | controller | ok 2025-11-10 00:16:17.636075 | 2025-11-10 00:16:17.636174 | TASK [test-setup : Check if project's tools/test-setup.sh exists] 2025-11-10 00:16:17.846126 | controller | ok 2025-11-10 00:16:17.859351 | 2025-11-10 00:16:17.859504 | TASK [test-setup : Run tools/test-setup.sh] 2025-11-10 00:16:47.936445 | [Zuul] Log Stream did not terminate 2025-11-10 00:16:47.936863 | controller | skipping: Conditional result was False 2025-11-10 00:16:47.993288 | 2025-11-10 00:16:47.993570 | PLAY RECAP 2025-11-10 00:16:47.993643 | controller | ok: 4 changed: 0 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2025-11-10 00:16:47.993675 | 2025-11-10 00:16:48.109688 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2025-11-10 00:16:48.110550 | PRE-RUN START: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master] 2025-11-10 00:16:48.701540 | 2025-11-10 00:16:48.701659 | PLAY [controller] 2025-11-10 00:16:48.721327 | 2025-11-10 00:16:48.721408 | TASK [Create the /root directory] 2025-11-10 00:19:01.110699 | controller | ERROR 2025-11-10 00:19:01.111137 | controller | { 2025-11-10 00:19:01.111257 | controller | "msg": "Timeout (32s) waiting for privilege escalation prompt: " 2025-11-10 00:19:01.111322 | controller | } failure 2025-11-10 00:19:01.113205 | 2025-11-10 00:19:01.113313 | PLAY RECAP 2025-11-10 00:19:01.113397 | controller | ok: 0 changed: 0 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0 2025-11-10 00:19:01.113452 | 2025-11-10 00:19:01.238072 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master] 2025-11-10 00:19:01.239397 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2025-11-10 00:19:01.866319 | 2025-11-10 00:19:01.866427 | PLAY [all] 2025-11-10 00:19:01.888142 | 2025-11-10 00:19:01.888239 | TASK [fetch-subunit-output : Find stestr or testr executable] 2025-11-10 00:21:18.953469 | controller | changed: non-zero return code 2025-11-10 00:21:18.959507 | 2025-11-10 00:21:18.959583 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2025-11-10 00:21:18.984208 | controller | skipping: Conditional result was False 2025-11-10 00:21:18.990676 | 2025-11-10 00:21:18.990744 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2025-11-10 00:21:19.022086 | 2025-11-10 00:21:19.022223 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2025-11-10 00:21:19.052651 | 2025-11-10 00:21:19.052782 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2025-11-10 00:21:19.077051 | controller | skipping: Conditional result was False 2025-11-10 00:21:19.082994 | 2025-11-10 00:21:19.083082 | LOOP [fetch-subunit-output : Generate subunit file] 2025-11-10 00:21:19.113998 | 2025-11-10 00:21:19.114145 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2025-11-10 00:21:19.138364 | controller | skipping: Conditional result was False 2025-11-10 00:21:19.144511 | 2025-11-10 00:21:19.144576 | TASK [fetch-subunit-output : Remove the temporary file] 2025-11-10 00:21:19.168746 | controller | skipping: Conditional result was False 2025-11-10 00:21:19.182209 | 2025-11-10 00:21:19.182349 | TASK [fetch-subunit-output : Process and fetch subunit results] 2025-11-10 00:21:19.208326 | controller | skipping: Conditional result was False 2025-11-10 00:21:19.240921 | 2025-11-10 00:21:19.241001 | PLAY RECAP 2025-11-10 00:21:19.241081 | controller | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2025-11-10 00:21:19.241114 | 2025-11-10 00:21:19.351705 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2025-11-10 00:21:19.352867 | POST-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/post.yaml@master] 2025-11-10 00:21:19.974361 | 2025-11-10 00:21:19.974535 | PLAY [all:!appliance*] 2025-11-10 00:21:19.997413 | 2025-11-10 00:21:19.997535 | TASK [unregister the node] 2025-11-10 00:21:20.530319 | controller | skipping: Conditional result was False 2025-11-10 00:21:20.540999 | 2025-11-10 00:21:20.541316 | TASK [include_role : fetch-output] 2025-11-10 00:21:20.599669 | controller | ok 2025-11-10 00:21:20.633083 | 2025-11-10 00:21:20.633288 | TASK [fetch-output : Set log path for multiple nodes] 2025-11-10 00:21:20.721898 | controller | skipping: Conditional result was False 2025-11-10 00:21:20.736877 | 2025-11-10 00:21:20.737112 | TASK [fetch-output : Set log path for single node] 2025-11-10 00:21:20.791173 | controller | ok 2025-11-10 00:21:20.804457 | 2025-11-10 00:21:20.804641 | LOOP [fetch-output : Ensure local output dirs] 2025-11-10 00:21:21.302273 | controller -> localhost | ok: "/var/lib/zuul/builds/19dd6e41d869425c973829c54a5b99e8/work/logs" 2025-11-10 00:21:21.591632 | controller -> localhost | changed: "/var/lib/zuul/builds/19dd6e41d869425c973829c54a5b99e8/work/artifacts" 2025-11-10 00:21:21.881450 | controller -> localhost | changed: "/var/lib/zuul/builds/19dd6e41d869425c973829c54a5b99e8/work/docs" 2025-11-10 00:21:21.904828 | 2025-11-10 00:21:21.905005 | LOOP [fetch-output : Collect logs, artifacts and docs] 2025-11-10 00:21:22.709176 | controller | ok 2025-11-10 00:21:22.709708 | controller | changed: All items complete 2025-11-10 00:21:22.709769 | 2025-11-10 00:21:23.252954 | controller | changed: .d..t...... ./ 2025-11-10 00:21:23.748561 | controller | changed: .d..t...... ./ 2025-11-10 00:21:23.781957 | 2025-11-10 00:21:23.782129 | TASK [include_role : fetch-output-openshift] 2025-11-10 00:21:23.796937 | controller | skipping: Conditional result was False 2025-11-10 00:21:23.806303 | 2025-11-10 00:21:23.806399 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2025-11-10 00:21:23.836779 | controller | skipping: Conditional result was False 2025-11-10 00:21:23.841703 | controller | skipping: Conditional result was False 2025-11-10 00:21:23.885274 | 2025-11-10 00:21:23.885402 | PLAY [localhost] 2025-11-10 00:21:23.903676 | 2025-11-10 00:21:23.903787 | TASK [Run Zuul manifest role] 2025-11-10 00:21:23.927278 | localhost | ok 2025-11-10 00:21:23.946725 | 2025-11-10 00:21:23.946851 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-11-10 00:21:24.388979 | localhost | changed 2025-11-10 00:21:24.400088 | 2025-11-10 00:21:24.400300 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-11-10 00:21:24.435974 | localhost | ok 2025-11-10 00:21:24.454081 | 2025-11-10 00:21:24.454224 | TASK [Set zuul-log-path fact] 2025-11-10 00:21:24.479379 | localhost | ok 2025-11-10 00:21:24.506144 | 2025-11-10 00:21:24.506264 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-11-10 00:21:24.550939 | localhost | ok 2025-11-10 00:21:24.569794 | 2025-11-10 00:21:24.569889 | LOOP [Run upload-logs-swift role] 2025-11-10 00:21:24.617330 | localhost | Output suppressed because no_log was given 2025-11-10 00:21:24.668466 | 2025-11-10 00:21:24.668618 | TASK [Set zuul-log-path fact] 2025-11-10 00:21:24.703912 | localhost | skipping: Conditional result was False 2025-11-10 00:21:24.713498 | 2025-11-10 00:21:24.713598 | TASK [upload-logs-swift : Ensure logs are readable before uploading] 2025-11-10 00:21:25.155855 | localhost -> localhost | ok: Runtime: 0:00:00.008730 2025-11-10 00:21:25.165879 | 2025-11-10 00:21:25.166009 | TASK [upload-logs-swift : Upload logs to swift]