2025-11-23 00:27:00.644529 | Job console starting... 2025-11-23 00:27:00.660979 | Updating repositories 2025-11-23 00:27:00.821616 | Preparing job workspace 2025-11-23 00:27:04.468893 | Running Ansible setup... 2025-11-23 00:27:09.227680 | PRE-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master] 2025-11-23 00:27:09.881810 | 2025-11-23 00:27:09.881942 | PLAY [localhost] 2025-11-23 00:27:09.890556 | 2025-11-23 00:27:09.890635 | TASK [Gathering Facts] 2025-11-23 00:27:10.896860 | localhost | ok 2025-11-23 00:27:10.914336 | 2025-11-23 00:27:10.914481 | TASK [Setup log path fact] 2025-11-23 00:27:10.933722 | localhost | ok 2025-11-23 00:27:10.946360 | 2025-11-23 00:27:10.946441 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-11-23 00:27:10.975794 | localhost | ok 2025-11-23 00:27:10.983801 | 2025-11-23 00:27:10.983879 | TASK [emit-job-header : Print job information] 2025-11-23 00:27:11.022058 | # Job Information 2025-11-23 00:27:11.022211 | Ansible Version: 2.15.12 2025-11-23 00:27:11.022242 | Job: ansible-test-sanity-docker-stable-2.10 2025-11-23 00:27:11.022262 | Pipeline: periodic 2025-11-23 00:27:11.022280 | Executor: ze02.softwarefactory-project.io 2025-11-23 00:27:11.022298 | Triggered by: https://github.com/ansible-collections/ansible.network 2025-11-23 00:27:11.022318 | Log URL (when completed): https://ansible.softwarefactory-project.io/logs/8c8/ansible/8c88759167864d6e8a04395d2db81061/ 2025-11-23 00:27:11.022336 | Event ID: 957440c764f64053b5278469ab93a4cd 2025-11-23 00:27:11.026084 | 2025-11-23 00:27:11.026149 | LOOP [emit-job-header : Print node information] 2025-11-23 00:27:11.129963 | localhost | ok: 2025-11-23 00:27:11.130294 | localhost | # Node Information 2025-11-23 00:27:11.130355 | localhost | Inventory Hostname: controller 2025-11-23 00:27:11.130400 | localhost | Hostname: np0005532484 2025-11-23 00:27:11.130441 | localhost | Username: zuul 2025-11-23 00:27:11.130488 | localhost | Distro: Fedora 37 2025-11-23 00:27:11.130528 | localhost | Provider: ansible-vexxhost-ca-ymq-1 2025-11-23 00:27:11.130565 | localhost | Region: ca-ymq-1 2025-11-23 00:27:11.130601 | localhost | Label: ansible-fedora-37-1vcpu 2025-11-23 00:27:11.130637 | localhost | Product Name: OpenStack Nova 2025-11-23 00:27:11.130714 | localhost | Interface IP: 162.253.55.231 2025-11-23 00:27:11.140487 | 2025-11-23 00:27:11.140623 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-11-23 00:27:11.543176 | localhost -> localhost | changed 2025-11-23 00:27:11.548921 | 2025-11-23 00:27:11.548995 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-11-23 00:27:12.424364 | localhost -> localhost | changed 2025-11-23 00:27:12.443436 | 2025-11-23 00:27:12.443497 | PLAY [all:!appliance*] 2025-11-23 00:27:12.459985 | 2025-11-23 00:27:12.460114 | TASK [include_role : start-zuul-console] 2025-11-23 00:27:12.478775 | controller | ok 2025-11-23 00:27:12.493131 | 2025-11-23 00:27:12.493255 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-11-23 00:27:12.859125 | controller | ok 2025-11-23 00:27:12.869818 | 2025-11-23 00:27:12.869902 | TASK [use-our-mirror : Retrieve the IP address] 2025-11-23 00:27:13.960863 | controller | ok: Status code was 404 and not [200]: HTTP Error 404: Not Found 2025-11-23 00:27:13.976450 | 2025-11-23 00:27:13.976774 | TASK [use-our-mirror : Ensure the registry service is running on the mirror] 2025-11-23 00:27:14.526277 | controller | skipping: Conditional result was False 2025-11-23 00:27:14.539866 | 2025-11-23 00:27:14.540001 | TASK [use-our-mirror : Write the IP in /etc/hosts] 2025-11-23 00:27:14.566477 | controller | skipping: Conditional result was False 2025-11-23 00:27:14.581416 | 2025-11-23 00:27:14.581566 | TASK [use-our-mirror : Create the podman configuration directory] 2025-11-23 00:27:14.609369 | controller | skipping: Conditional result was False 2025-11-23 00:27:14.624343 | 2025-11-23 00:27:14.624513 | TASK [use-our-mirror : Copy the podman configuration] 2025-11-23 00:27:14.651755 | controller | skipping: Conditional result was False 2025-11-23 00:27:14.666342 | 2025-11-23 00:27:14.666490 | TASK [use-our-mirror : Copy the Yum configuration (main)] 2025-11-23 00:27:14.694256 | controller | skipping: Conditional result was False 2025-11-23 00:27:14.708269 | 2025-11-23 00:27:14.708407 | TASK [use-our-mirror : Copy the Yum configuration (update)] 2025-11-23 00:27:14.747054 | controller | skipping: Conditional result was False 2025-11-23 00:27:14.776009 | 2025-11-23 00:27:14.776160 | TASK [Disable Fedora Modular] 2025-11-23 00:27:15.028273 | controller | changed 2025-11-23 00:27:15.040857 | 2025-11-23 00:27:15.040991 | TASK [Enable EPEL] 2025-11-23 00:27:15.068329 | controller | skipping: Conditional result was False 2025-11-23 00:27:15.082456 | 2025-11-23 00:27:15.082597 | TASK [Register the RHEL node] 2025-11-23 00:27:15.649978 | 2025-11-23 00:27:15.650256 | TASK [Show the subscription-manager status] 2025-11-23 00:27:16.235015 | controller | skipping: Conditional result was False 2025-11-23 00:27:16.248752 | 2025-11-23 00:27:16.248891 | TASK [Enable EPEL on RHEL] 2025-11-23 00:27:16.804872 | controller | skipping: Conditional result was False 2025-11-23 00:27:16.817813 | 2025-11-23 00:27:16.817991 | TASK [Install git and tox] 2025-11-23 00:28:37.566240 | controller | changed 2025-11-23 00:28:37.572817 | 2025-11-23 00:28:37.572912 | TASK [include_role : prepare-workspace] 2025-11-23 00:28:37.603967 | controller | ok 2025-11-23 00:28:37.634466 | 2025-11-23 00:28:37.634583 | TASK [prepare-workspace : Start zuul_console daemon.] 2025-11-23 00:28:37.872280 | controller | ok 2025-11-23 00:28:37.881682 | 2025-11-23 00:28:37.881777 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2025-11-23 00:28:50.019149 | controller | Output suppressed because no_log was given 2025-11-23 00:28:50.083892 | 2025-11-23 00:28:50.084015 | TASK [include_role : prepare-workspace-openshift] 2025-11-23 00:28:50.101704 | controller | skipping: Conditional result was False 2025-11-23 00:28:50.126308 | 2025-11-23 00:28:50.126384 | PLAY [all:!appliance] 2025-11-23 00:28:50.143633 | 2025-11-23 00:28:50.143722 | TASK [Run add-build-sshkey role (RSA)] 2025-11-23 00:28:50.173486 | controller | ok 2025-11-23 00:28:50.188231 | 2025-11-23 00:28:50.188295 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-11-23 00:28:50.447500 | controller -> localhost | ok 2025-11-23 00:28:50.463809 | 2025-11-23 00:28:50.463961 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-11-23 00:28:50.505051 | controller | ok 2025-11-23 00:28:50.532509 | controller | included: /var/lib/zuul/builds/8c88759167864d6e8a04395d2db81061/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-11-23 00:28:50.541451 | 2025-11-23 00:28:50.541539 | TASK [add-build-sshkey : Create Temp SSH key] 2025-11-23 00:28:51.014994 | controller -> localhost | Generating public/private rsa key pair. 2025-11-23 00:28:51.015433 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/8c88759167864d6e8a04395d2db81061/work/8c88759167864d6e8a04395d2db81061_id_rsa. 2025-11-23 00:28:51.015511 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/8c88759167864d6e8a04395d2db81061/work/8c88759167864d6e8a04395d2db81061_id_rsa.pub. 2025-11-23 00:28:51.015571 | controller -> localhost | The key fingerprint is: 2025-11-23 00:28:51.015627 | controller -> localhost | SHA256:nxJ8+S/u5FqfM5whAF9pXyaAw2r/xzaKZngFZ5kGXhA zuul-build-sshkey 2025-11-23 00:28:51.015733 | controller -> localhost | The key's randomart image is: 2025-11-23 00:28:51.015828 | controller -> localhost | +---[RSA 2048]----+ 2025-11-23 00:28:51.015916 | controller -> localhost | | .E+.o | 2025-11-23 00:28:51.015973 | controller -> localhost | | . = = . o| 2025-11-23 00:28:51.016027 | controller -> localhost | | = * + + | 2025-11-23 00:28:51.016079 | controller -> localhost | | .o =.* . | 2025-11-23 00:28:51.016130 | controller -> localhost | | .S.o* | 2025-11-23 00:28:51.016200 | controller -> localhost | | +.oo . | 2025-11-23 00:28:51.016271 | controller -> localhost | | ..oo++ o | 2025-11-23 00:28:51.016328 | controller -> localhost | | ..+=ooX. | 2025-11-23 00:28:51.016414 | controller -> localhost | | +o+==++ | 2025-11-23 00:28:51.016474 | controller -> localhost | +----[SHA256]-----+ 2025-11-23 00:28:51.016593 | controller -> localhost | ok: Runtime: 0:00:00.083405 2025-11-23 00:28:51.033142 | 2025-11-23 00:28:51.033290 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-11-23 00:28:51.079422 | controller | ok 2025-11-23 00:28:51.102696 | controller | included: /var/lib/zuul/builds/8c88759167864d6e8a04395d2db81061/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-11-23 00:28:51.117567 | 2025-11-23 00:28:51.117690 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-11-23 00:28:51.143644 | controller | skipping: Conditional result was False 2025-11-23 00:28:51.153431 | 2025-11-23 00:28:51.153528 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-11-23 00:28:51.618490 | controller | changed 2025-11-23 00:28:51.632033 | 2025-11-23 00:28:51.632196 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-11-23 00:28:51.866282 | controller | ok 2025-11-23 00:28:51.879103 | 2025-11-23 00:28:51.879245 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-11-23 00:28:52.531252 | controller | changed 2025-11-23 00:28:52.546264 | 2025-11-23 00:28:52.546441 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-11-23 00:28:53.226406 | controller | changed 2025-11-23 00:28:53.239636 | 2025-11-23 00:28:53.239808 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-11-23 00:28:53.270366 | controller | skipping: Conditional result was False 2025-11-23 00:28:53.286158 | 2025-11-23 00:28:53.286325 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-11-23 00:28:53.722412 | controller -> localhost | changed 2025-11-23 00:28:53.735431 | 2025-11-23 00:28:53.735551 | TASK [add-build-sshkey : Add back temp key] 2025-11-23 00:28:54.009061 | controller -> localhost | Identity added: /var/lib/zuul/builds/8c88759167864d6e8a04395d2db81061/work/8c88759167864d6e8a04395d2db81061_id_rsa (zuul-build-sshkey) 2025-11-23 00:28:54.009441 | controller -> localhost | ok: Runtime: 0:00:00.013659 2025-11-23 00:28:54.033786 | 2025-11-23 00:28:54.033911 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-11-23 00:28:54.410388 | controller | ok 2025-11-23 00:28:54.423639 | 2025-11-23 00:28:54.423833 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-11-23 00:28:54.465564 | controller | skipping: Conditional result was False 2025-11-23 00:28:54.493510 | 2025-11-23 00:28:54.493712 | TASK [Run add-build-sshkey role (ECDSA)] 2025-11-23 00:28:54.533630 | controller | ok 2025-11-23 00:28:54.557878 | 2025-11-23 00:28:54.557995 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-11-23 00:28:54.810330 | controller -> localhost | ok 2025-11-23 00:28:54.824854 | 2025-11-23 00:28:54.824989 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-11-23 00:28:54.860647 | controller | ok 2025-11-23 00:28:54.876791 | controller | included: /var/lib/zuul/builds/8c88759167864d6e8a04395d2db81061/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-11-23 00:28:54.885882 | 2025-11-23 00:28:54.885970 | TASK [add-build-sshkey : Create Temp SSH key] 2025-11-23 00:28:55.250429 | controller -> localhost | Generating public/private ecdsa key pair. 2025-11-23 00:28:55.250751 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/8c88759167864d6e8a04395d2db81061/work/8c88759167864d6e8a04395d2db81061_id_ecdsa. 2025-11-23 00:28:55.250812 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/8c88759167864d6e8a04395d2db81061/work/8c88759167864d6e8a04395d2db81061_id_ecdsa.pub. 2025-11-23 00:28:55.250868 | controller -> localhost | The key fingerprint is: 2025-11-23 00:28:55.250910 | controller -> localhost | SHA256:TiBU1MwIiWd6/4kZXxTkkMT0i0ZiRwSKuEEu1ApcVHA zuul-build-sshkey 2025-11-23 00:28:55.250949 | controller -> localhost | The key's randomart image is: 2025-11-23 00:28:55.250988 | controller -> localhost | +---[ECDSA 521]---+ 2025-11-23 00:28:55.251026 | controller -> localhost | |.o+==E+@*o. | 2025-11-23 00:28:55.251063 | controller -> localhost | |=o.+=...*+. | 2025-11-23 00:28:55.251100 | controller -> localhost | |=.o+o + o o. | 2025-11-23 00:28:55.251136 | controller -> localhost | |.+. .o = ... | 2025-11-23 00:28:55.251172 | controller -> localhost | |. . . S.. | 2025-11-23 00:28:55.251207 | controller -> localhost | | o+ . | 2025-11-23 00:28:55.251243 | controller -> localhost | | *.o | 2025-11-23 00:28:55.251277 | controller -> localhost | | o + | 2025-11-23 00:28:55.251313 | controller -> localhost | | | 2025-11-23 00:28:55.251348 | controller -> localhost | +----[SHA256]-----+ 2025-11-23 00:28:55.251433 | controller -> localhost | ok: Runtime: 0:00:00.008271 2025-11-23 00:28:55.264870 | 2025-11-23 00:28:55.264993 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-11-23 00:28:55.296387 | controller | ok 2025-11-23 00:28:55.312603 | controller | included: /var/lib/zuul/builds/8c88759167864d6e8a04395d2db81061/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-11-23 00:28:55.326331 | 2025-11-23 00:28:55.326424 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-11-23 00:28:55.341422 | controller | skipping: Conditional result was False 2025-11-23 00:28:55.351060 | 2025-11-23 00:28:55.351158 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-11-23 00:28:55.636965 | controller | changed 2025-11-23 00:28:55.650478 | 2025-11-23 00:28:55.650647 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-11-23 00:28:55.902304 | controller | ok 2025-11-23 00:28:55.917899 | 2025-11-23 00:28:55.918051 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-11-23 00:28:56.602352 | controller | changed 2025-11-23 00:28:56.611285 | 2025-11-23 00:28:56.611387 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-11-23 00:28:57.313604 | controller | changed 2025-11-23 00:28:57.326580 | 2025-11-23 00:28:57.326793 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-11-23 00:28:57.354698 | controller | skipping: Conditional result was False 2025-11-23 00:28:57.422970 | 2025-11-23 00:28:57.423126 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-11-23 00:28:57.662155 | controller -> localhost | changed 2025-11-23 00:28:57.674016 | 2025-11-23 00:28:57.674098 | TASK [add-build-sshkey : Add back temp key] 2025-11-23 00:28:57.941383 | controller -> localhost | Identity added: /var/lib/zuul/builds/8c88759167864d6e8a04395d2db81061/work/8c88759167864d6e8a04395d2db81061_id_ecdsa (zuul-build-sshkey) 2025-11-23 00:28:57.941585 | controller -> localhost | ok: Runtime: 0:00:00.007397 2025-11-23 00:28:57.948245 | 2025-11-23 00:28:57.948310 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-11-23 00:28:58.163999 | controller | ok 2025-11-23 00:28:58.172766 | 2025-11-23 00:28:58.172858 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-11-23 00:28:58.200554 | controller | skipping: Conditional result was False 2025-11-23 00:28:58.218949 | 2025-11-23 00:28:58.219040 | TASK [include_role : remove-zuul-sshkey] 2025-11-23 00:28:58.233556 | controller | skipping: Conditional result was False 2025-11-23 00:28:58.242980 | 2025-11-23 00:28:58.243078 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2025-11-23 00:28:58.478601 | controller | ok: "logs" 2025-11-23 00:28:58.479004 | controller | ok: All items complete 2025-11-23 00:28:58.479045 | 2025-11-23 00:28:58.688905 | controller | ok: "artifacts" 2025-11-23 00:28:58.894794 | controller | ok: "docs" 2025-11-23 00:28:58.916884 | 2025-11-23 00:28:58.917167 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2025-11-23 00:28:59.141793 | controller | changed: "logs" 2025-11-23 00:28:59.370969 | controller | changed: "artifacts" 2025-11-23 00:28:59.565544 | controller | changed: "docs" 2025-11-23 00:28:59.623659 | 2025-11-23 00:28:59.624070 | PLAY RECAP 2025-11-23 00:28:59.624141 | controller | ok: 30 changed: 16 unreachable: 0 failed: 0 skipped: 18 rescued: 0 ignored: 0 2025-11-23 00:28:59.624177 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-11-23 00:28:59.624202 | 2025-11-23 00:28:59.779834 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master] 2025-11-23 00:28:59.780772 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2025-11-23 00:29:00.350223 | 2025-11-23 00:29:00.350337 | PLAY [all] 2025-11-23 00:29:00.372095 | 2025-11-23 00:29:00.372192 | TASK [Install binary dependencies] 2025-11-23 00:29:00.453432 | controller | ok 2025-11-23 00:29:00.473291 | 2025-11-23 00:29:00.473411 | TASK [bindep : Include find tasks] 2025-11-23 00:29:00.502910 | controller | ok 2025-11-23 00:29:00.511223 | controller | included: /var/lib/zuul/builds/8c88759167864d6e8a04395d2db81061/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find.yaml 2025-11-23 00:29:00.517474 | 2025-11-23 00:29:00.517538 | TASK [bindep : Look for bindep.txt] 2025-11-23 00:29:00.891157 | controller | ok 2025-11-23 00:29:00.905651 | 2025-11-23 00:29:00.905900 | TASK [bindep : Define bindep_file fact] 2025-11-23 00:29:00.932827 | controller | skipping: Conditional result was False 2025-11-23 00:29:00.946859 | 2025-11-23 00:29:00.947048 | TASK [bindep : Look for other-requirements.txt] 2025-11-23 00:29:01.172420 | controller | ok 2025-11-23 00:29:01.187642 | 2025-11-23 00:29:01.187883 | TASK [bindep : Define bindep_file fact] 2025-11-23 00:29:01.225050 | controller | skipping: Conditional result was False 2025-11-23 00:29:01.234015 | 2025-11-23 00:29:01.234114 | TASK [bindep : Look for bindep fallback file] 2025-11-23 00:29:01.271422 | controller | skipping: Conditional result was False 2025-11-23 00:29:01.288865 | 2025-11-23 00:29:01.289034 | TASK [bindep : Define bindep_file fact] 2025-11-23 00:29:01.316742 | controller | skipping: Conditional result was False 2025-11-23 00:29:01.329625 | 2025-11-23 00:29:01.329784 | TASK [bindep : Include bindep tasks] 2025-11-23 00:29:01.355967 | controller | skipping: Conditional result was False 2025-11-23 00:29:01.368724 | 2025-11-23 00:29:01.368853 | TASK [bindep : Include install tasks] 2025-11-23 00:29:01.395113 | controller | skipping: Conditional result was False 2025-11-23 00:29:01.407797 | 2025-11-23 00:29:01.407929 | LOOP [bindep : Include package tasks] 2025-11-23 00:29:01.468648 | 2025-11-23 00:29:01.468949 | TASK [Run test-setup role] 2025-11-23 00:29:01.498956 | controller | ok 2025-11-23 00:29:01.528910 | 2025-11-23 00:29:01.529008 | TASK [test-setup : Check if project's tools/test-setup.sh exists] 2025-11-23 00:29:01.742107 | controller | ok 2025-11-23 00:29:01.748050 | 2025-11-23 00:29:01.748117 | TASK [test-setup : Run tools/test-setup.sh] 2025-11-23 00:29:02.282459 | controller | skipping: Conditional result was False 2025-11-23 00:29:02.308633 | 2025-11-23 00:29:02.308744 | PLAY RECAP 2025-11-23 00:29:02.308785 | controller | ok: 4 changed: 0 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2025-11-23 00:29:02.308805 | 2025-11-23 00:29:02.406083 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2025-11-23 00:29:02.407886 | PRE-RUN START: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master] 2025-11-23 00:29:03.037961 | 2025-11-23 00:29:03.038088 | PLAY [controller] 2025-11-23 00:29:03.057371 | 2025-11-23 00:29:03.057445 | TASK [Create the /root directory] 2025-11-23 00:31:15.354307 | controller | ERROR 2025-11-23 00:31:15.354649 | controller | { 2025-11-23 00:31:15.354765 | controller | "msg": "Timeout (32s) waiting for privilege escalation prompt: " 2025-11-23 00:31:15.354829 | controller | } failure 2025-11-23 00:31:15.356609 | 2025-11-23 00:31:15.356758 | PLAY RECAP 2025-11-23 00:31:15.356840 | controller | ok: 0 changed: 0 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0 2025-11-23 00:31:15.356887 | 2025-11-23 00:31:15.461704 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master] 2025-11-23 00:31:15.463728 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2025-11-23 00:31:16.021082 | 2025-11-23 00:31:16.021198 | PLAY [all] 2025-11-23 00:31:16.042490 | 2025-11-23 00:31:16.042567 | TASK [fetch-subunit-output : Find stestr or testr executable] 2025-11-23 00:34:24.591195 | controller | changed: non-zero return code 2025-11-23 00:34:24.605280 | 2025-11-23 00:34:24.605445 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2025-11-23 00:34:24.633514 | controller | skipping: Conditional result was False 2025-11-23 00:34:24.641839 | 2025-11-23 00:34:24.641990 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2025-11-23 00:34:24.673968 | 2025-11-23 00:34:24.674101 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2025-11-23 00:34:24.704678 | 2025-11-23 00:34:24.704810 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2025-11-23 00:34:24.729064 | controller | skipping: Conditional result was False 2025-11-23 00:34:24.737772 | 2025-11-23 00:34:24.737865 | LOOP [fetch-subunit-output : Generate subunit file] 2025-11-23 00:34:24.771250 | 2025-11-23 00:34:24.771416 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2025-11-23 00:34:24.796475 | controller | skipping: Conditional result was False 2025-11-23 00:34:24.808082 | 2025-11-23 00:34:24.808303 | TASK [fetch-subunit-output : Remove the temporary file] 2025-11-23 00:34:24.836897 | controller | skipping: Conditional result was False 2025-11-23 00:34:24.854488 | 2025-11-23 00:34:24.854706 | TASK [fetch-subunit-output : Process and fetch subunit results] 2025-11-23 00:34:24.883761 | controller | skipping: Conditional result was False 2025-11-23 00:34:24.929119 | 2025-11-23 00:34:24.929241 | PLAY RECAP 2025-11-23 00:34:24.929297 | controller | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2025-11-23 00:34:24.929325 | 2025-11-23 00:34:25.036427 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2025-11-23 00:34:25.037349 | POST-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/post.yaml@master] 2025-11-23 00:34:25.606044 | 2025-11-23 00:34:25.606203 | PLAY [all:!appliance*] 2025-11-23 00:34:25.627696 | 2025-11-23 00:34:25.627775 | TASK [unregister the node] 2025-11-23 00:34:26.162130 | controller | skipping: Conditional result was False 2025-11-23 00:34:26.175119 | 2025-11-23 00:34:26.175284 | TASK [include_role : fetch-output] 2025-11-23 00:34:26.225467 | controller | ok 2025-11-23 00:34:26.262202 | 2025-11-23 00:34:26.262339 | TASK [fetch-output : Set log path for multiple nodes] 2025-11-23 00:34:26.338315 | controller | skipping: Conditional result was False 2025-11-23 00:34:26.351337 | 2025-11-23 00:34:26.351488 | TASK [fetch-output : Set log path for single node] 2025-11-23 00:34:26.410120 | controller | ok 2025-11-23 00:34:26.421985 | 2025-11-23 00:34:26.422122 | LOOP [fetch-output : Ensure local output dirs] 2025-11-23 00:34:26.916899 | controller -> localhost | ok: "/var/lib/zuul/builds/8c88759167864d6e8a04395d2db81061/work/logs" 2025-11-23 00:34:27.168819 | controller -> localhost | changed: "/var/lib/zuul/builds/8c88759167864d6e8a04395d2db81061/work/artifacts" 2025-11-23 00:34:27.377190 | controller -> localhost | changed: "/var/lib/zuul/builds/8c88759167864d6e8a04395d2db81061/work/docs" 2025-11-23 00:34:27.386397 | 2025-11-23 00:34:27.386477 | LOOP [fetch-output : Collect logs, artifacts and docs] 2025-11-23 00:34:28.029111 | controller | ok 2025-11-23 00:34:28.029314 | controller | changed: All items complete 2025-11-23 00:34:28.029340 | 2025-11-23 00:34:28.577982 | controller | changed: .d..t...... ./ 2025-11-23 00:34:29.072592 | controller | changed: .d..t...... ./ 2025-11-23 00:34:29.106779 | 2025-11-23 00:34:29.106972 | TASK [include_role : fetch-output-openshift] 2025-11-23 00:34:29.134364 | controller | skipping: Conditional result was False 2025-11-23 00:34:29.149398 | 2025-11-23 00:34:29.149563 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2025-11-23 00:34:29.195025 | controller | skipping: Conditional result was False 2025-11-23 00:34:29.208588 | controller | skipping: Conditional result was False 2025-11-23 00:34:29.270302 | 2025-11-23 00:34:29.270423 | PLAY [localhost] 2025-11-23 00:34:29.287641 | 2025-11-23 00:34:29.287753 | TASK [Run Zuul manifest role] 2025-11-23 00:34:29.308001 | localhost | ok 2025-11-23 00:34:29.326916 | 2025-11-23 00:34:29.327145 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-11-23 00:34:29.756300 | localhost | changed 2025-11-23 00:34:29.766395 | 2025-11-23 00:34:29.766529 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-11-23 00:34:29.811653 | localhost | ok 2025-11-23 00:34:29.828849 | 2025-11-23 00:34:29.828969 | TASK [Set zuul-log-path fact] 2025-11-23 00:34:29.848519 | localhost | ok 2025-11-23 00:34:29.867432 | 2025-11-23 00:34:29.867518 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-11-23 00:34:29.895964 | localhost | ok 2025-11-23 00:34:29.904048 | 2025-11-23 00:34:29.904105 | LOOP [Run upload-logs-swift role] 2025-11-23 00:34:29.937933 | localhost | Output suppressed because no_log was given 2025-11-23 00:34:29.961429 | 2025-11-23 00:34:29.961493 | TASK [Set zuul-log-path fact] 2025-11-23 00:34:29.985372 | localhost | skipping: Conditional result was False 2025-11-23 00:34:29.990076 | 2025-11-23 00:34:29.990138 | TASK [upload-logs-swift : Ensure logs are readable before uploading] 2025-11-23 00:34:30.397641 | localhost -> localhost | ok: Runtime: 0:00:00.017042 2025-11-23 00:34:30.449878 | 2025-11-23 00:34:30.449998 | TASK [upload-logs-swift : Upload logs to swift]