2025-11-30 00:14:06.094972 | Job console starting... 2025-11-30 00:14:06.107194 | Updating repositories 2025-11-30 00:14:06.277385 | Preparing job workspace 2025-11-30 00:14:09.871170 | Running Ansible setup... 2025-11-30 00:14:14.548469 | PRE-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master] 2025-11-30 00:14:15.214946 | 2025-11-30 00:14:15.215089 | PLAY [localhost] 2025-11-30 00:14:15.224302 | 2025-11-30 00:14:15.224398 | TASK [Gathering Facts] 2025-11-30 00:14:16.216022 | localhost | ok 2025-11-30 00:14:16.235161 | 2025-11-30 00:14:16.235300 | TASK [Setup log path fact] 2025-11-30 00:14:16.261282 | localhost | ok 2025-11-30 00:14:16.284264 | 2025-11-30 00:14:16.284363 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-11-30 00:14:16.328604 | localhost | ok 2025-11-30 00:14:16.345014 | 2025-11-30 00:14:16.345110 | TASK [emit-job-header : Print job information] 2025-11-30 00:14:16.396447 | # Job Information 2025-11-30 00:14:16.396619 | Ansible Version: 2.15.12 2025-11-30 00:14:16.396661 | Job: ansible-test-sanity-docker-stable-2.10 2025-11-30 00:14:16.396689 | Pipeline: periodic 2025-11-30 00:14:16.396714 | Executor: ze03.softwarefactory-project.io 2025-11-30 00:14:16.396767 | Triggered by: https://github.com/ansible-collections/ansible.network 2025-11-30 00:14:16.396799 | Log URL (when completed): https://ansible.softwarefactory-project.io/logs/aea/ansible/aea547922fbd4892b6888cf5276d96e7/ 2025-11-30 00:14:16.396825 | Event ID: 7a84db3cbb014f2ca6b2aa61dbabec79 2025-11-30 00:14:16.401924 | 2025-11-30 00:14:16.402010 | LOOP [emit-job-header : Print node information] 2025-11-30 00:14:16.512860 | localhost | ok: 2025-11-30 00:14:16.513053 | localhost | # Node Information 2025-11-30 00:14:16.513090 | localhost | Inventory Hostname: controller 2025-11-30 00:14:16.513118 | localhost | Hostname: np0005540041 2025-11-30 00:14:16.513144 | localhost | Username: zuul 2025-11-30 00:14:16.513173 | localhost | Distro: Fedora 37 2025-11-30 00:14:16.513198 | localhost | Provider: ansible-vexxhost-ca-ymq-1 2025-11-30 00:14:16.513222 | localhost | Region: ca-ymq-1 2025-11-30 00:14:16.513244 | localhost | Label: ansible-fedora-37-1vcpu 2025-11-30 00:14:16.513266 | localhost | Product Name: OpenStack Nova 2025-11-30 00:14:16.513288 | localhost | Interface IP: 162.253.55.186 2025-11-30 00:14:16.529045 | 2025-11-30 00:14:16.529194 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-11-30 00:14:16.943985 | localhost -> localhost | changed 2025-11-30 00:14:16.950156 | 2025-11-30 00:14:16.950237 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-11-30 00:14:17.894014 | localhost -> localhost | changed 2025-11-30 00:14:17.913901 | 2025-11-30 00:14:17.913976 | PLAY [all:!appliance*] 2025-11-30 00:14:17.930440 | 2025-11-30 00:14:17.930518 | TASK [include_role : start-zuul-console] 2025-11-30 00:14:17.949797 | controller | ok 2025-11-30 00:14:17.963920 | 2025-11-30 00:14:17.964004 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-11-30 00:14:18.324608 | controller | ok 2025-11-30 00:14:18.348496 | 2025-11-30 00:14:18.348650 | TASK [use-our-mirror : Retrieve the IP address] 2025-11-30 00:14:19.303103 | controller | ok: Status code was 404 and not [200]: HTTP Error 404: Not Found 2025-11-30 00:14:19.312568 | 2025-11-30 00:14:19.312662 | TASK [use-our-mirror : Ensure the registry service is running on the mirror] 2025-11-30 00:14:19.856174 | controller | skipping: Conditional result was False 2025-11-30 00:14:19.867471 | 2025-11-30 00:14:19.867597 | TASK [use-our-mirror : Write the IP in /etc/hosts] 2025-11-30 00:14:19.893226 | controller | skipping: Conditional result was False 2025-11-30 00:14:19.903236 | 2025-11-30 00:14:19.903376 | TASK [use-our-mirror : Create the podman configuration directory] 2025-11-30 00:14:19.928594 | controller | skipping: Conditional result was False 2025-11-30 00:14:19.938174 | 2025-11-30 00:14:19.938322 | TASK [use-our-mirror : Copy the podman configuration] 2025-11-30 00:14:19.963424 | controller | skipping: Conditional result was False 2025-11-30 00:14:19.972510 | 2025-11-30 00:14:19.972613 | TASK [use-our-mirror : Copy the Yum configuration (main)] 2025-11-30 00:14:19.996370 | controller | skipping: Conditional result was False 2025-11-30 00:14:20.002437 | 2025-11-30 00:14:20.002512 | TASK [use-our-mirror : Copy the Yum configuration (update)] 2025-11-30 00:14:20.026525 | controller | skipping: Conditional result was False 2025-11-30 00:14:20.038894 | 2025-11-30 00:14:20.038995 | TASK [Disable Fedora Modular] 2025-11-30 00:14:20.274086 | controller | changed 2025-11-30 00:14:20.282061 | 2025-11-30 00:14:20.282138 | TASK [Enable EPEL] 2025-11-30 00:14:20.307824 | controller | skipping: Conditional result was False 2025-11-30 00:14:20.316047 | 2025-11-30 00:14:20.316141 | TASK [Register the RHEL node] 2025-11-30 00:14:20.874314 | 2025-11-30 00:14:20.874706 | TASK [Show the subscription-manager status] 2025-11-30 00:14:21.493924 | controller | skipping: Conditional result was False 2025-11-30 00:14:21.509272 | 2025-11-30 00:14:21.509502 | TASK [Enable EPEL on RHEL] 2025-11-30 00:14:22.074073 | controller | skipping: Conditional result was False 2025-11-30 00:14:22.088518 | 2025-11-30 00:14:22.088676 | TASK [Install git and tox] 2025-11-30 00:15:42.035962 | controller | changed 2025-11-30 00:15:42.042674 | 2025-11-30 00:15:42.046806 | TASK [include_role : prepare-workspace] 2025-11-30 00:15:42.076963 | controller | ok 2025-11-30 00:15:42.100518 | 2025-11-30 00:15:42.100670 | TASK [prepare-workspace : Start zuul_console daemon.] 2025-11-30 00:15:42.342751 | controller | ok 2025-11-30 00:15:42.349275 | 2025-11-30 00:15:42.349335 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2025-11-30 00:15:54.417984 | controller | Output suppressed because no_log was given 2025-11-30 00:15:54.432243 | 2025-11-30 00:15:54.432327 | TASK [include_role : prepare-workspace-openshift] 2025-11-30 00:15:54.457203 | controller | skipping: Conditional result was False 2025-11-30 00:15:54.487142 | 2025-11-30 00:15:54.487265 | PLAY [all:!appliance] 2025-11-30 00:15:54.508800 | 2025-11-30 00:15:54.508883 | TASK [Run add-build-sshkey role (RSA)] 2025-11-30 00:15:54.567855 | controller | ok 2025-11-30 00:15:54.581794 | 2025-11-30 00:15:54.581860 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-11-30 00:15:54.809039 | controller -> localhost | ok 2025-11-30 00:15:54.816048 | 2025-11-30 00:15:54.816112 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-11-30 00:15:54.853184 | controller | ok 2025-11-30 00:15:54.867344 | controller | included: /var/lib/zuul/builds/aea547922fbd4892b6888cf5276d96e7/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-11-30 00:15:54.873085 | 2025-11-30 00:15:54.873150 | TASK [add-build-sshkey : Create Temp SSH key] 2025-11-30 00:15:55.263501 | controller -> localhost | Generating public/private rsa key pair. 2025-11-30 00:15:55.263852 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/aea547922fbd4892b6888cf5276d96e7/work/aea547922fbd4892b6888cf5276d96e7_id_rsa. 2025-11-30 00:15:55.263918 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/aea547922fbd4892b6888cf5276d96e7/work/aea547922fbd4892b6888cf5276d96e7_id_rsa.pub. 2025-11-30 00:15:55.263965 | controller -> localhost | The key fingerprint is: 2025-11-30 00:15:55.264009 | controller -> localhost | SHA256:8wnLmOv1OhWcxu4X/mUkxxAHhHnwGg/UjP9yv1Hi2lo zuul-build-sshkey 2025-11-30 00:15:55.264050 | controller -> localhost | The key's randomart image is: 2025-11-30 00:15:55.264091 | controller -> localhost | +---[RSA 2048]----+ 2025-11-30 00:15:55.264131 | controller -> localhost | | oO+..| 2025-11-30 00:15:55.264172 | controller -> localhost | | .+.+o | 2025-11-30 00:15:55.264211 | controller -> localhost | | o .ooo | 2025-11-30 00:15:55.264250 | controller -> localhost | | * =.o | 2025-11-30 00:15:55.264288 | controller -> localhost | | So .. +.=| 2025-11-30 00:15:55.264341 | controller -> localhost | | + =o....=+| 2025-11-30 00:15:55.264388 | controller -> localhost | | o +oo. .E++| 2025-11-30 00:15:55.264430 | controller -> localhost | | o... o+ oo| 2025-11-30 00:15:55.264471 | controller -> localhost | | .o .o..ooo..| 2025-11-30 00:15:55.264515 | controller -> localhost | +----[SHA256]-----+ 2025-11-30 00:15:55.264605 | controller -> localhost | ok: Runtime: 0:00:00.022485 2025-11-30 00:15:55.281424 | 2025-11-30 00:15:55.281597 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-11-30 00:15:55.305584 | controller | ok 2025-11-30 00:15:55.321592 | controller | included: /var/lib/zuul/builds/aea547922fbd4892b6888cf5276d96e7/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-11-30 00:15:55.337045 | 2025-11-30 00:15:55.337207 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-11-30 00:15:55.364199 | controller | skipping: Conditional result was False 2025-11-30 00:15:55.373955 | 2025-11-30 00:15:55.374082 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-11-30 00:15:55.754900 | controller | changed 2025-11-30 00:15:55.763469 | 2025-11-30 00:15:55.763599 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-11-30 00:15:55.985109 | controller | ok 2025-11-30 00:15:55.998719 | 2025-11-30 00:15:55.998929 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-11-30 00:15:56.673278 | controller | changed 2025-11-30 00:15:56.683270 | 2025-11-30 00:15:56.683350 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-11-30 00:15:57.344002 | controller | changed 2025-11-30 00:15:57.357719 | 2025-11-30 00:15:57.357887 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-11-30 00:15:57.385371 | controller | skipping: Conditional result was False 2025-11-30 00:15:57.395405 | 2025-11-30 00:15:57.395489 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-11-30 00:15:57.819870 | controller -> localhost | changed 2025-11-30 00:15:57.831625 | 2025-11-30 00:15:57.831759 | TASK [add-build-sshkey : Add back temp key] 2025-11-30 00:15:58.090354 | controller -> localhost | Identity added: /var/lib/zuul/builds/aea547922fbd4892b6888cf5276d96e7/work/aea547922fbd4892b6888cf5276d96e7_id_rsa (zuul-build-sshkey) 2025-11-30 00:15:58.090782 | controller -> localhost | ok: Runtime: 0:00:00.008048 2025-11-30 00:15:58.106494 | 2025-11-30 00:15:58.106653 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-11-30 00:15:58.455025 | controller | ok 2025-11-30 00:15:58.468434 | 2025-11-30 00:15:58.468622 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-11-30 00:15:58.507085 | controller | skipping: Conditional result was False 2025-11-30 00:15:58.534044 | 2025-11-30 00:15:58.534196 | TASK [Run add-build-sshkey role (ECDSA)] 2025-11-30 00:15:58.576846 | controller | ok 2025-11-30 00:15:58.606893 | 2025-11-30 00:15:58.607026 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-11-30 00:15:58.861856 | controller -> localhost | ok 2025-11-30 00:15:58.879055 | 2025-11-30 00:15:58.879266 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-11-30 00:15:58.922031 | controller | ok 2025-11-30 00:15:58.947145 | controller | included: /var/lib/zuul/builds/aea547922fbd4892b6888cf5276d96e7/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-11-30 00:15:58.957498 | 2025-11-30 00:15:58.957711 | TASK [add-build-sshkey : Create Temp SSH key] 2025-11-30 00:15:59.299379 | controller -> localhost | Generating public/private ecdsa key pair. 2025-11-30 00:15:59.299631 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/aea547922fbd4892b6888cf5276d96e7/work/aea547922fbd4892b6888cf5276d96e7_id_ecdsa. 2025-11-30 00:15:59.299671 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/aea547922fbd4892b6888cf5276d96e7/work/aea547922fbd4892b6888cf5276d96e7_id_ecdsa.pub. 2025-11-30 00:15:59.299713 | controller -> localhost | The key fingerprint is: 2025-11-30 00:15:59.299780 | controller -> localhost | SHA256:b6L570QQh6mc6j5IINxw469XILg0q48rcwIHGudOGco zuul-build-sshkey 2025-11-30 00:15:59.299811 | controller -> localhost | The key's randomart image is: 2025-11-30 00:15:59.299839 | controller -> localhost | +---[ECDSA 521]---+ 2025-11-30 00:15:59.299866 | controller -> localhost | | .o. | 2025-11-30 00:15:59.299888 | controller -> localhost | | . o oo | 2025-11-30 00:15:59.299910 | controller -> localhost | |. * .. o. | 2025-11-30 00:15:59.299934 | controller -> localhost | |==o+ .+ . | 2025-11-30 00:15:59.299960 | controller -> localhost | |=B+oo.. S . | 2025-11-30 00:15:59.299984 | controller -> localhost | |+E* .. . o | 2025-11-30 00:15:59.300006 | controller -> localhost | |o= o. . . + | 2025-11-30 00:15:59.300030 | controller -> localhost | |=.+.o. o + | 2025-11-30 00:15:59.300055 | controller -> localhost | |+*..o.o..oo | 2025-11-30 00:15:59.300078 | controller -> localhost | +----[SHA256]-----+ 2025-11-30 00:15:59.300141 | controller -> localhost | ok: Runtime: 0:00:00.008422 2025-11-30 00:15:59.310919 | 2025-11-30 00:15:59.311013 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-11-30 00:15:59.345472 | controller | ok 2025-11-30 00:15:59.356484 | controller | included: /var/lib/zuul/builds/aea547922fbd4892b6888cf5276d96e7/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-11-30 00:15:59.367417 | 2025-11-30 00:15:59.367483 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-11-30 00:15:59.401712 | controller | skipping: Conditional result was False 2025-11-30 00:15:59.411372 | 2025-11-30 00:15:59.411471 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-11-30 00:15:59.695460 | controller | changed 2025-11-30 00:15:59.715964 | 2025-11-30 00:15:59.716192 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-11-30 00:15:59.941481 | controller | ok 2025-11-30 00:15:59.958610 | 2025-11-30 00:15:59.958812 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-11-30 00:16:00.619134 | controller | changed 2025-11-30 00:16:00.631467 | 2025-11-30 00:16:00.631600 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-11-30 00:16:01.295664 | controller | changed 2025-11-30 00:16:01.313442 | 2025-11-30 00:16:01.313634 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-11-30 00:16:01.341357 | controller | skipping: Conditional result was False 2025-11-30 00:16:01.357332 | 2025-11-30 00:16:01.357511 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-11-30 00:16:01.659323 | controller -> localhost | changed 2025-11-30 00:16:01.673473 | 2025-11-30 00:16:01.673559 | TASK [add-build-sshkey : Add back temp key] 2025-11-30 00:16:01.964184 | controller -> localhost | Identity added: /var/lib/zuul/builds/aea547922fbd4892b6888cf5276d96e7/work/aea547922fbd4892b6888cf5276d96e7_id_ecdsa (zuul-build-sshkey) 2025-11-30 00:16:01.964583 | controller -> localhost | ok: Runtime: 0:00:00.008463 2025-11-30 00:16:01.975109 | 2025-11-30 00:16:01.975223 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-11-30 00:16:02.200100 | controller | ok 2025-11-30 00:16:02.212551 | 2025-11-30 00:16:02.212760 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-11-30 00:16:02.261628 | controller | skipping: Conditional result was False 2025-11-30 00:16:02.286222 | 2025-11-30 00:16:02.286364 | TASK [include_role : remove-zuul-sshkey] 2025-11-30 00:16:02.315024 | controller | skipping: Conditional result was False 2025-11-30 00:16:02.363769 | 2025-11-30 00:16:02.363917 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2025-11-30 00:16:02.627618 | controller | ok: "logs" 2025-11-30 00:16:02.628263 | controller | ok: All items complete 2025-11-30 00:16:02.628334 | 2025-11-30 00:16:02.840385 | controller | ok: "artifacts" 2025-11-30 00:16:03.045835 | controller | ok: "docs" 2025-11-30 00:16:03.061490 | 2025-11-30 00:16:03.061720 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2025-11-30 00:16:03.330634 | controller | changed: "logs" 2025-11-30 00:16:03.538505 | controller | changed: "artifacts" 2025-11-30 00:16:03.742033 | controller | changed: "docs" 2025-11-30 00:16:03.804633 | 2025-11-30 00:16:03.804797 | PLAY RECAP 2025-11-30 00:16:03.804858 | controller | ok: 30 changed: 16 unreachable: 0 failed: 0 skipped: 18 rescued: 0 ignored: 0 2025-11-30 00:16:03.804893 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-11-30 00:16:03.804917 | 2025-11-30 00:16:03.932268 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master] 2025-11-30 00:16:03.934164 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2025-11-30 00:16:04.499875 | 2025-11-30 00:16:04.499987 | PLAY [all] 2025-11-30 00:16:04.521687 | 2025-11-30 00:16:04.521784 | TASK [Install binary dependencies] 2025-11-30 00:16:04.593477 | controller | ok 2025-11-30 00:16:04.619694 | 2025-11-30 00:16:04.619819 | TASK [bindep : Include find tasks] 2025-11-30 00:16:04.650165 | controller | ok 2025-11-30 00:16:04.657807 | controller | included: /var/lib/zuul/builds/aea547922fbd4892b6888cf5276d96e7/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find.yaml 2025-11-30 00:16:04.663916 | 2025-11-30 00:16:04.663977 | TASK [bindep : Look for bindep.txt] 2025-11-30 00:16:05.006099 | controller | ok 2025-11-30 00:16:05.018792 | 2025-11-30 00:16:05.018930 | TASK [bindep : Define bindep_file fact] 2025-11-30 00:16:05.035838 | controller | skipping: Conditional result was False 2025-11-30 00:16:05.048360 | 2025-11-30 00:16:05.048495 | TASK [bindep : Look for other-requirements.txt] 2025-11-30 00:16:05.259561 | controller | ok 2025-11-30 00:16:05.271542 | 2025-11-30 00:16:05.271681 | TASK [bindep : Define bindep_file fact] 2025-11-30 00:16:05.309080 | controller | skipping: Conditional result was False 2025-11-30 00:16:05.322696 | 2025-11-30 00:16:05.322895 | TASK [bindep : Look for bindep fallback file] 2025-11-30 00:16:05.360649 | controller | skipping: Conditional result was False 2025-11-30 00:16:05.376340 | 2025-11-30 00:16:05.376485 | TASK [bindep : Define bindep_file fact] 2025-11-30 00:16:05.403447 | controller | skipping: Conditional result was False 2025-11-30 00:16:05.416446 | 2025-11-30 00:16:05.416584 | TASK [bindep : Include bindep tasks] 2025-11-30 00:16:05.443264 | controller | skipping: Conditional result was False 2025-11-30 00:16:05.456082 | 2025-11-30 00:16:05.456217 | TASK [bindep : Include install tasks] 2025-11-30 00:16:05.482889 | controller | skipping: Conditional result was False 2025-11-30 00:16:05.495505 | 2025-11-30 00:16:05.495642 | LOOP [bindep : Include package tasks] 2025-11-30 00:16:05.566653 | 2025-11-30 00:16:05.566966 | TASK [Run test-setup role] 2025-11-30 00:16:05.597049 | controller | ok 2025-11-30 00:16:05.624714 | 2025-11-30 00:16:05.624834 | TASK [test-setup : Check if project's tools/test-setup.sh exists] 2025-11-30 00:16:05.834884 | controller | ok 2025-11-30 00:16:05.849507 | 2025-11-30 00:16:05.849658 | TASK [test-setup : Run tools/test-setup.sh] 2025-11-30 00:16:06.407984 | controller | skipping: Conditional result was False 2025-11-30 00:16:06.463419 | 2025-11-30 00:16:06.463504 | PLAY RECAP 2025-11-30 00:16:06.463561 | controller | ok: 4 changed: 0 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2025-11-30 00:16:06.463589 | 2025-11-30 00:16:06.565684 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2025-11-30 00:16:06.566956 | PRE-RUN START: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master] 2025-11-30 00:16:07.147438 | 2025-11-30 00:16:07.147664 | PLAY [controller] 2025-11-30 00:16:07.167903 | 2025-11-30 00:16:07.168004 | TASK [Create the /root directory] 2025-11-30 00:18:19.500273 | controller | ERROR 2025-11-30 00:18:19.500571 | controller | { 2025-11-30 00:18:19.500648 | controller | "msg": "Timeout (32s) waiting for privilege escalation prompt: " 2025-11-30 00:18:19.500708 | controller | } failure 2025-11-30 00:18:19.502593 | 2025-11-30 00:18:19.502685 | PLAY RECAP 2025-11-30 00:18:19.502802 | controller | ok: 0 changed: 0 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0 2025-11-30 00:18:19.502853 | 2025-11-30 00:18:19.607103 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master] 2025-11-30 00:18:19.608092 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2025-11-30 00:18:20.178496 | 2025-11-30 00:18:20.178626 | PLAY [all] 2025-11-30 00:18:20.202633 | 2025-11-30 00:18:20.202941 | TASK [fetch-subunit-output : Find stestr or testr executable] 2025-11-30 00:21:40.154057 | controller | changed: non-zero return code 2025-11-30 00:21:40.171615 | 2025-11-30 00:21:40.171878 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2025-11-30 00:21:40.197664 | controller | skipping: Conditional result was False 2025-11-30 00:21:40.206787 | 2025-11-30 00:21:40.206921 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2025-11-30 00:21:40.244900 | 2025-11-30 00:21:40.245136 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2025-11-30 00:21:40.280992 | 2025-11-30 00:21:40.281231 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2025-11-30 00:21:40.298553 | controller | skipping: Conditional result was False 2025-11-30 00:21:40.312036 | 2025-11-30 00:21:40.312204 | LOOP [fetch-subunit-output : Generate subunit file] 2025-11-30 00:21:40.348748 | 2025-11-30 00:21:40.348991 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2025-11-30 00:21:40.367863 | controller | skipping: Conditional result was False 2025-11-30 00:21:40.376930 | 2025-11-30 00:21:40.377053 | TASK [fetch-subunit-output : Remove the temporary file] 2025-11-30 00:21:40.404281 | controller | skipping: Conditional result was False 2025-11-30 00:21:40.412407 | 2025-11-30 00:21:40.412494 | TASK [fetch-subunit-output : Process and fetch subunit results] 2025-11-30 00:21:40.439711 | controller | skipping: Conditional result was False 2025-11-30 00:21:40.468374 | 2025-11-30 00:21:40.468469 | PLAY RECAP 2025-11-30 00:21:40.468514 | controller | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2025-11-30 00:21:40.468534 | 2025-11-30 00:21:40.587617 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2025-11-30 00:21:40.591988 | POST-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/post.yaml@master] 2025-11-30 00:21:41.171582 | 2025-11-30 00:21:41.171707 | PLAY [all:!appliance*] 2025-11-30 00:21:41.193704 | 2025-11-30 00:21:41.193855 | TASK [unregister the node] 2025-11-30 00:21:41.731191 | controller | skipping: Conditional result was False 2025-11-30 00:21:41.745516 | 2025-11-30 00:21:41.745691 | TASK [include_role : fetch-output] 2025-11-30 00:21:41.801092 | controller | ok 2025-11-30 00:21:41.852119 | 2025-11-30 00:21:41.852623 | TASK [fetch-output : Set log path for multiple nodes] 2025-11-30 00:21:41.903097 | controller | skipping: Conditional result was False 2025-11-30 00:21:41.917924 | 2025-11-30 00:21:41.918108 | TASK [fetch-output : Set log path for single node] 2025-11-30 00:21:41.955176 | controller | ok 2025-11-30 00:21:41.963761 | 2025-11-30 00:21:41.963874 | LOOP [fetch-output : Ensure local output dirs] 2025-11-30 00:21:42.434448 | controller -> localhost | ok: "/var/lib/zuul/builds/aea547922fbd4892b6888cf5276d96e7/work/logs" 2025-11-30 00:21:42.737895 | controller -> localhost | changed: "/var/lib/zuul/builds/aea547922fbd4892b6888cf5276d96e7/work/artifacts" 2025-11-30 00:21:43.034946 | controller -> localhost | changed: "/var/lib/zuul/builds/aea547922fbd4892b6888cf5276d96e7/work/docs" 2025-11-30 00:21:43.062936 | 2025-11-30 00:21:43.063131 | LOOP [fetch-output : Collect logs, artifacts and docs] 2025-11-30 00:21:43.802672 | controller | ok 2025-11-30 00:21:43.803216 | controller | changed: All items complete 2025-11-30 00:21:43.803282 | 2025-11-30 00:21:44.366367 | controller | changed: .d..t...... ./ 2025-11-30 00:21:44.957843 | controller | changed: .d..t...... ./ 2025-11-30 00:21:44.993837 | 2025-11-30 00:21:44.994098 | TASK [include_role : fetch-output-openshift] 2025-11-30 00:21:45.023719 | controller | skipping: Conditional result was False 2025-11-30 00:21:45.039392 | 2025-11-30 00:21:45.039627 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2025-11-30 00:21:45.079527 | controller | skipping: Conditional result was False 2025-11-30 00:21:45.088948 | controller | skipping: Conditional result was False 2025-11-30 00:21:45.149384 | 2025-11-30 00:21:45.149549 | PLAY [localhost] 2025-11-30 00:21:45.169276 | 2025-11-30 00:21:45.169392 | TASK [Run Zuul manifest role] 2025-11-30 00:21:45.194927 | localhost | ok 2025-11-30 00:21:45.214599 | 2025-11-30 00:21:45.214697 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-11-30 00:21:46.099801 | localhost | changed 2025-11-30 00:21:46.110021 | 2025-11-30 00:21:46.110157 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-11-30 00:21:46.157787 | localhost | ok 2025-11-30 00:21:46.176175 | 2025-11-30 00:21:46.176306 | TASK [Set zuul-log-path fact] 2025-11-30 00:21:46.204495 | localhost | ok 2025-11-30 00:21:46.227956 | 2025-11-30 00:21:46.228045 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-11-30 00:21:46.273169 | localhost | ok 2025-11-30 00:21:46.290717 | 2025-11-30 00:21:46.290881 | LOOP [Run upload-logs-swift role] 2025-11-30 00:21:46.492106 | localhost | Output suppressed because no_log was given 2025-11-30 00:21:46.545521 | 2025-11-30 00:21:46.545680 | TASK [Set zuul-log-path fact] 2025-11-30 00:21:46.653512 | localhost | skipping: Conditional result was False 2025-11-30 00:21:46.703834 | 2025-11-30 00:21:46.704197 | TASK [upload-logs-swift : Ensure logs are readable before uploading] 2025-11-30 00:21:47.128967 | localhost -> localhost | ok: Runtime: 0:00:00.010386 2025-11-30 00:21:47.134425 | 2025-11-30 00:21:47.134493 | TASK [upload-logs-swift : Upload logs to swift]