2025-12-12 00:14:20.079599 | Job console starting... 2025-12-12 00:14:20.089529 | Updating repositories 2025-12-12 00:14:20.231379 | Preparing job workspace 2025-12-12 00:14:24.357131 | Running Ansible setup... 2025-12-12 00:14:28.879325 | PRE-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master] 2025-12-12 00:14:29.481854 | 2025-12-12 00:14:29.481978 | PLAY [localhost] 2025-12-12 00:14:29.490448 | 2025-12-12 00:14:29.490519 | TASK [Gathering Facts] 2025-12-12 00:14:30.550303 | localhost | ok 2025-12-12 00:14:30.568349 | 2025-12-12 00:14:30.568486 | TASK [Setup log path fact] 2025-12-12 00:14:30.586318 | localhost | ok 2025-12-12 00:14:30.599470 | 2025-12-12 00:14:30.599588 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-12-12 00:14:30.627389 | localhost | ok 2025-12-12 00:14:30.635312 | 2025-12-12 00:14:30.635414 | TASK [emit-job-header : Print job information] 2025-12-12 00:14:30.663306 | # Job Information 2025-12-12 00:14:30.663516 | Ansible Version: 2.15.12 2025-12-12 00:14:30.663550 | Job: ansible-test-sanity-docker-milestone 2025-12-12 00:14:30.663571 | Pipeline: periodic 2025-12-12 00:14:30.663589 | Executor: ze01.softwarefactory-project.io 2025-12-12 00:14:30.663606 | Triggered by: https://github.com/ansible-collections/ansible.network 2025-12-12 00:14:30.663626 | Log URL (when completed): https://ansible.softwarefactory-project.io/logs/76b/ansible/76b2d224bfb142e3aea76b607defc4e4/ 2025-12-12 00:14:30.663645 | Event ID: 336ce9c82f5d4f159df4daaad304a8e0 2025-12-12 00:14:30.667506 | 2025-12-12 00:14:30.667576 | LOOP [emit-job-header : Print node information] 2025-12-12 00:14:30.768132 | localhost | ok: 2025-12-12 00:14:30.768340 | localhost | # Node Information 2025-12-12 00:14:30.768368 | localhost | Inventory Hostname: controller 2025-12-12 00:14:30.768387 | localhost | Hostname: np0005556174 2025-12-12 00:14:30.768405 | localhost | Username: zuul 2025-12-12 00:14:30.768425 | localhost | Distro: Fedora 37 2025-12-12 00:14:30.768442 | localhost | Provider: ansible-vexxhost-ca-ymq-1 2025-12-12 00:14:30.768459 | localhost | Region: ca-ymq-1 2025-12-12 00:14:30.768475 | localhost | Label: ansible-fedora-37-1vcpu 2025-12-12 00:14:30.768491 | localhost | Product Name: OpenStack Nova 2025-12-12 00:14:30.768507 | localhost | Interface IP: 162.253.55.12 2025-12-12 00:14:30.786893 | 2025-12-12 00:14:30.787008 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-12-12 00:14:31.198186 | localhost -> localhost | changed 2025-12-12 00:14:31.204095 | 2025-12-12 00:14:31.204173 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-12-12 00:14:32.069011 | localhost -> localhost | changed 2025-12-12 00:14:32.117258 | 2025-12-12 00:14:32.117369 | PLAY [all:!appliance*] 2025-12-12 00:14:32.135040 | 2025-12-12 00:14:32.135139 | TASK [include_role : start-zuul-console] 2025-12-12 00:14:32.154837 | controller | ok 2025-12-12 00:14:32.169872 | 2025-12-12 00:14:32.169976 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-12-12 00:14:32.597834 | controller | ok 2025-12-12 00:14:32.608829 | 2025-12-12 00:14:32.608902 | TASK [use-our-mirror : Retrieve the IP address] 2025-12-12 00:14:33.551442 | controller | ok: Status code was 404 and not [200]: HTTP Error 404: Not Found 2025-12-12 00:14:33.557664 | 2025-12-12 00:14:33.557732 | TASK [use-our-mirror : Ensure the registry service is running on the mirror] 2025-12-12 00:14:34.109629 | controller | skipping: Conditional result was False 2025-12-12 00:14:34.123430 | 2025-12-12 00:14:34.123609 | TASK [use-our-mirror : Write the IP in /etc/hosts] 2025-12-12 00:14:34.152371 | controller | skipping: Conditional result was False 2025-12-12 00:14:34.167342 | 2025-12-12 00:14:34.167527 | TASK [use-our-mirror : Create the podman configuration directory] 2025-12-12 00:14:34.197522 | controller | skipping: Conditional result was False 2025-12-12 00:14:34.211847 | 2025-12-12 00:14:34.212094 | TASK [use-our-mirror : Copy the podman configuration] 2025-12-12 00:14:34.238719 | controller | skipping: Conditional result was False 2025-12-12 00:14:34.251559 | 2025-12-12 00:14:34.251692 | TASK [use-our-mirror : Copy the Yum configuration (main)] 2025-12-12 00:14:34.278217 | controller | skipping: Conditional result was False 2025-12-12 00:14:34.287163 | 2025-12-12 00:14:34.287256 | TASK [use-our-mirror : Copy the Yum configuration (update)] 2025-12-12 00:14:34.311896 | controller | skipping: Conditional result was False 2025-12-12 00:14:34.329596 | 2025-12-12 00:14:34.329716 | TASK [Disable Fedora Modular] 2025-12-12 00:14:34.563543 | controller | changed 2025-12-12 00:14:34.578723 | 2025-12-12 00:14:34.578896 | TASK [Enable EPEL] 2025-12-12 00:14:34.595643 | controller | skipping: Conditional result was False 2025-12-12 00:14:34.605384 | 2025-12-12 00:14:34.605496 | TASK [Register the RHEL node] 2025-12-12 00:14:35.165415 | 2025-12-12 00:14:35.165676 | TASK [Show the subscription-manager status] 2025-12-12 00:14:35.742470 | controller | skipping: Conditional result was False 2025-12-12 00:14:35.757214 | 2025-12-12 00:14:35.757370 | TASK [Enable EPEL on RHEL] 2025-12-12 00:14:36.307286 | controller | skipping: Conditional result was False 2025-12-12 00:14:36.320057 | 2025-12-12 00:14:36.320196 | TASK [Install git and tox] 2025-12-12 00:15:55.155303 | controller | changed 2025-12-12 00:15:55.162134 | 2025-12-12 00:15:55.162423 | TASK [include_role : prepare-workspace] 2025-12-12 00:15:55.193670 | controller | ok 2025-12-12 00:15:55.216410 | 2025-12-12 00:15:55.216531 | TASK [prepare-workspace : Start zuul_console daemon.] 2025-12-12 00:15:55.460039 | controller | ok 2025-12-12 00:15:55.474166 | 2025-12-12 00:15:55.474298 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2025-12-12 00:16:08.103002 | controller | Output suppressed because no_log was given 2025-12-12 00:16:08.122100 | 2025-12-12 00:16:08.122446 | TASK [include_role : prepare-workspace-openshift] 2025-12-12 00:16:08.148467 | controller | skipping: Conditional result was False 2025-12-12 00:16:08.211621 | 2025-12-12 00:16:08.211719 | PLAY [all:!appliance] 2025-12-12 00:16:08.228771 | 2025-12-12 00:16:08.228878 | TASK [Run add-build-sshkey role (RSA)] 2025-12-12 00:16:08.258549 | controller | ok 2025-12-12 00:16:08.273258 | 2025-12-12 00:16:08.273336 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-12-12 00:16:08.562962 | controller -> localhost | ok 2025-12-12 00:16:08.572730 | 2025-12-12 00:16:08.572848 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-12-12 00:16:08.598431 | controller | ok 2025-12-12 00:16:08.616369 | controller | included: /var/lib/zuul/builds/76b2d224bfb142e3aea76b607defc4e4/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-12-12 00:16:08.624952 | 2025-12-12 00:16:08.625061 | TASK [add-build-sshkey : Create Temp SSH key] 2025-12-12 00:16:09.191549 | controller -> localhost | Generating public/private rsa key pair. 2025-12-12 00:16:09.191818 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/76b2d224bfb142e3aea76b607defc4e4/work/76b2d224bfb142e3aea76b607defc4e4_id_rsa. 2025-12-12 00:16:09.191863 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/76b2d224bfb142e3aea76b607defc4e4/work/76b2d224bfb142e3aea76b607defc4e4_id_rsa.pub. 2025-12-12 00:16:09.191895 | controller -> localhost | The key fingerprint is: 2025-12-12 00:16:09.191927 | controller -> localhost | SHA256:fupF1IYWoCoJItYGItqrjSQfXyLG1cqzuYEjZKkoJpU zuul-build-sshkey 2025-12-12 00:16:09.191957 | controller -> localhost | The key's randomart image is: 2025-12-12 00:16:09.191986 | controller -> localhost | +---[RSA 2048]----+ 2025-12-12 00:16:09.192077 | controller -> localhost | |o. ... | 2025-12-12 00:16:09.192115 | controller -> localhost | |+.o . . + | 2025-12-12 00:16:09.192145 | controller -> localhost | |=..o. o + o | 2025-12-12 00:16:09.192172 | controller -> localhost | |+oo* o o . | 2025-12-12 00:16:09.192198 | controller -> localhost | |.=E * . S . | 2025-12-12 00:16:09.192238 | controller -> localhost | |BB * * . . | 2025-12-12 00:16:09.192275 | controller -> localhost | |O.= = . o | 2025-12-12 00:16:09.192306 | controller -> localhost | |+. . o + | 2025-12-12 00:16:09.192334 | controller -> localhost | | . .o | 2025-12-12 00:16:09.192367 | controller -> localhost | +----[SHA256]-----+ 2025-12-12 00:16:09.192443 | controller -> localhost | ok: Runtime: 0:00:00.176803 2025-12-12 00:16:09.213389 | 2025-12-12 00:16:09.213561 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-12-12 00:16:09.244339 | controller | ok 2025-12-12 00:16:09.256368 | controller | included: /var/lib/zuul/builds/76b2d224bfb142e3aea76b607defc4e4/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-12-12 00:16:09.267488 | 2025-12-12 00:16:09.267604 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-12-12 00:16:09.291634 | controller | skipping: Conditional result was False 2025-12-12 00:16:09.299042 | 2025-12-12 00:16:09.299140 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-12-12 00:16:09.778570 | controller | changed 2025-12-12 00:16:09.794456 | 2025-12-12 00:16:09.794613 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-12-12 00:16:10.032918 | controller | ok 2025-12-12 00:16:10.045230 | 2025-12-12 00:16:10.045342 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-12-12 00:16:10.731876 | controller | changed 2025-12-12 00:16:10.742338 | 2025-12-12 00:16:10.742490 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-12-12 00:16:11.377991 | controller | changed 2025-12-12 00:16:11.393418 | 2025-12-12 00:16:11.393562 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-12-12 00:16:11.420768 | controller | skipping: Conditional result was False 2025-12-12 00:16:11.431738 | 2025-12-12 00:16:11.431853 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-12-12 00:16:11.906680 | controller -> localhost | changed 2025-12-12 00:16:11.918272 | 2025-12-12 00:16:11.918349 | TASK [add-build-sshkey : Add back temp key] 2025-12-12 00:16:12.204751 | controller -> localhost | Identity added: /var/lib/zuul/builds/76b2d224bfb142e3aea76b607defc4e4/work/76b2d224bfb142e3aea76b607defc4e4_id_rsa (zuul-build-sshkey) 2025-12-12 00:16:12.205128 | controller -> localhost | ok: Runtime: 0:00:00.014637 2025-12-12 00:16:12.221925 | 2025-12-12 00:16:12.222057 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-12-12 00:16:12.576308 | controller | ok 2025-12-12 00:16:12.600820 | 2025-12-12 00:16:12.601080 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-12-12 00:16:12.636946 | controller | skipping: Conditional result was False 2025-12-12 00:16:12.653769 | 2025-12-12 00:16:12.653890 | TASK [Run add-build-sshkey role (ECDSA)] 2025-12-12 00:16:12.684196 | controller | ok 2025-12-12 00:16:12.705384 | 2025-12-12 00:16:12.705488 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-12-12 00:16:12.974671 | controller -> localhost | ok 2025-12-12 00:16:12.981420 | 2025-12-12 00:16:12.981492 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-12-12 00:16:13.011566 | controller | ok 2025-12-12 00:16:13.023403 | controller | included: /var/lib/zuul/builds/76b2d224bfb142e3aea76b607defc4e4/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-12-12 00:16:13.030757 | 2025-12-12 00:16:13.030864 | TASK [add-build-sshkey : Create Temp SSH key] 2025-12-12 00:16:13.340652 | controller -> localhost | Generating public/private ecdsa key pair. 2025-12-12 00:16:13.340816 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/76b2d224bfb142e3aea76b607defc4e4/work/76b2d224bfb142e3aea76b607defc4e4_id_ecdsa. 2025-12-12 00:16:13.340844 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/76b2d224bfb142e3aea76b607defc4e4/work/76b2d224bfb142e3aea76b607defc4e4_id_ecdsa.pub. 2025-12-12 00:16:13.340873 | controller -> localhost | The key fingerprint is: 2025-12-12 00:16:13.340893 | controller -> localhost | SHA256:wkGQj+rNccxDobRsK3MTHoxr5hruvJh0Q8pFm90rUto zuul-build-sshkey 2025-12-12 00:16:13.340911 | controller -> localhost | The key's randomart image is: 2025-12-12 00:16:13.340929 | controller -> localhost | +---[ECDSA 521]---+ 2025-12-12 00:16:13.340946 | controller -> localhost | | .o. | 2025-12-12 00:16:13.340964 | controller -> localhost | | o.. | 2025-12-12 00:16:13.340981 | controller -> localhost | | * =.. | 2025-12-12 00:16:13.340997 | controller -> localhost | | o &.+. | 2025-12-12 00:16:13.341047 | controller -> localhost | | X Xo.S | 2025-12-12 00:16:13.341073 | controller -> localhost | |. @ X =.. | 2025-12-12 00:16:13.341092 | controller -> localhost | |.O @ E o | 2025-12-12 00:16:13.341109 | controller -> localhost | |=o+ = . | 2025-12-12 00:16:13.341126 | controller -> localhost | |=*. | 2025-12-12 00:16:13.341143 | controller -> localhost | +----[SHA256]-----+ 2025-12-12 00:16:13.341187 | controller -> localhost | ok: Runtime: 0:00:00.014008 2025-12-12 00:16:13.348083 | 2025-12-12 00:16:13.348147 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-12-12 00:16:13.378135 | controller | ok 2025-12-12 00:16:13.386396 | controller | included: /var/lib/zuul/builds/76b2d224bfb142e3aea76b607defc4e4/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-12-12 00:16:13.396682 | 2025-12-12 00:16:13.396784 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-12-12 00:16:13.422291 | controller | skipping: Conditional result was False 2025-12-12 00:16:13.431375 | 2025-12-12 00:16:13.431579 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-12-12 00:16:13.714804 | controller | changed 2025-12-12 00:16:13.723583 | 2025-12-12 00:16:13.723725 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-12-12 00:16:13.958133 | controller | ok 2025-12-12 00:16:13.964506 | 2025-12-12 00:16:13.964590 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-12-12 00:16:14.579170 | controller | changed 2025-12-12 00:16:14.586706 | 2025-12-12 00:16:14.586795 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-12-12 00:16:15.169481 | controller | changed 2025-12-12 00:16:15.177179 | 2025-12-12 00:16:15.177260 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-12-12 00:16:15.213071 | controller | skipping: Conditional result was False 2025-12-12 00:16:15.221556 | 2025-12-12 00:16:15.221651 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-12-12 00:16:15.492701 | controller -> localhost | changed 2025-12-12 00:16:15.533320 | 2025-12-12 00:16:15.533621 | TASK [add-build-sshkey : Add back temp key] 2025-12-12 00:16:15.823539 | controller -> localhost | Identity added: /var/lib/zuul/builds/76b2d224bfb142e3aea76b607defc4e4/work/76b2d224bfb142e3aea76b607defc4e4_id_ecdsa (zuul-build-sshkey) 2025-12-12 00:16:15.823783 | controller -> localhost | ok: Runtime: 0:00:00.013536 2025-12-12 00:16:15.830466 | 2025-12-12 00:16:15.830528 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-12-12 00:16:16.063301 | controller | ok 2025-12-12 00:16:16.068999 | 2025-12-12 00:16:16.069081 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-12-12 00:16:16.104481 | controller | skipping: Conditional result was False 2025-12-12 00:16:16.144146 | 2025-12-12 00:16:16.144602 | TASK [include_role : remove-zuul-sshkey] 2025-12-12 00:16:16.177280 | controller | skipping: Conditional result was False 2025-12-12 00:16:16.195250 | 2025-12-12 00:16:16.195431 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2025-12-12 00:16:16.433764 | controller | ok: "logs" 2025-12-12 00:16:16.434038 | controller | ok: All items complete 2025-12-12 00:16:16.434074 | 2025-12-12 00:16:16.633823 | controller | ok: "artifacts" 2025-12-12 00:16:16.836366 | controller | ok: "docs" 2025-12-12 00:16:16.846826 | 2025-12-12 00:16:16.846952 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2025-12-12 00:16:17.082074 | controller | changed: "logs" 2025-12-12 00:16:17.279687 | controller | changed: "artifacts" 2025-12-12 00:16:17.484641 | controller | changed: "docs" 2025-12-12 00:16:17.515420 | 2025-12-12 00:16:17.515534 | PLAY RECAP 2025-12-12 00:16:17.515580 | controller | ok: 30 changed: 16 unreachable: 0 failed: 0 skipped: 18 rescued: 0 ignored: 0 2025-12-12 00:16:17.515607 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-12-12 00:16:17.515626 | 2025-12-12 00:16:17.631191 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master] 2025-12-12 00:16:17.632411 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2025-12-12 00:16:18.207686 | 2025-12-12 00:16:18.207829 | PLAY [all] 2025-12-12 00:16:18.230412 | 2025-12-12 00:16:18.230523 | TASK [Install binary dependencies] 2025-12-12 00:16:18.280632 | controller | ok 2025-12-12 00:16:18.300091 | 2025-12-12 00:16:18.300183 | TASK [bindep : Include find tasks] 2025-12-12 00:16:18.330437 | controller | ok 2025-12-12 00:16:18.338627 | controller | included: /var/lib/zuul/builds/76b2d224bfb142e3aea76b607defc4e4/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find.yaml 2025-12-12 00:16:18.346381 | 2025-12-12 00:16:18.346522 | TASK [bindep : Look for bindep.txt] 2025-12-12 00:16:18.693150 | controller | ok 2025-12-12 00:16:18.699862 | 2025-12-12 00:16:18.699971 | TASK [bindep : Define bindep_file fact] 2025-12-12 00:16:18.714233 | controller | skipping: Conditional result was False 2025-12-12 00:16:18.722089 | 2025-12-12 00:16:18.722194 | TASK [bindep : Look for other-requirements.txt] 2025-12-12 00:16:18.941232 | controller | ok 2025-12-12 00:16:18.948028 | 2025-12-12 00:16:18.948124 | TASK [bindep : Define bindep_file fact] 2025-12-12 00:16:18.972208 | controller | skipping: Conditional result was False 2025-12-12 00:16:18.978543 | 2025-12-12 00:16:18.978619 | TASK [bindep : Look for bindep fallback file] 2025-12-12 00:16:19.002442 | controller | skipping: Conditional result was False 2025-12-12 00:16:19.010133 | 2025-12-12 00:16:19.010782 | TASK [bindep : Define bindep_file fact] 2025-12-12 00:16:19.055296 | controller | skipping: Conditional result was False 2025-12-12 00:16:19.062593 | 2025-12-12 00:16:19.062715 | TASK [bindep : Include bindep tasks] 2025-12-12 00:16:19.097186 | controller | skipping: Conditional result was False 2025-12-12 00:16:19.106769 | 2025-12-12 00:16:19.106863 | TASK [bindep : Include install tasks] 2025-12-12 00:16:19.131835 | controller | skipping: Conditional result was False 2025-12-12 00:16:19.138216 | 2025-12-12 00:16:19.138286 | LOOP [bindep : Include package tasks] 2025-12-12 00:16:19.198074 | 2025-12-12 00:16:19.198238 | TASK [Run test-setup role] 2025-12-12 00:16:19.219379 | controller | ok 2025-12-12 00:16:19.237304 | 2025-12-12 00:16:19.237404 | TASK [test-setup : Check if project's tools/test-setup.sh exists] 2025-12-12 00:16:19.430289 | controller | ok 2025-12-12 00:16:19.438032 | 2025-12-12 00:16:19.438150 | TASK [test-setup : Run tools/test-setup.sh] 2025-12-12 00:16:19.977210 | controller | skipping: Conditional result was False 2025-12-12 00:16:20.047346 | 2025-12-12 00:16:20.047498 | PLAY RECAP 2025-12-12 00:16:20.047579 | controller | ok: 4 changed: 0 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2025-12-12 00:16:20.047611 | 2025-12-12 00:16:20.158833 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2025-12-12 00:16:20.161239 | PRE-RUN START: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master] 2025-12-12 00:16:20.777603 | 2025-12-12 00:16:20.777729 | PLAY [controller] 2025-12-12 00:16:20.798953 | 2025-12-12 00:16:20.799093 | TASK [Create the /root directory] 2025-12-12 00:16:21.210597 | controller | ok 2025-12-12 00:16:21.222719 | 2025-12-12 00:16:21.222837 | TASK [Install glibc-langpack-en] 2025-12-12 00:18:33.532995 | controller | ERROR 2025-12-12 00:18:33.533359 | controller | { 2025-12-12 00:18:33.533425 | controller | "msg": "Timeout (32s) waiting for privilege escalation prompt: " 2025-12-12 00:18:33.533470 | controller | } failure 2025-12-12 00:18:33.534980 | 2025-12-12 00:18:33.535090 | PLAY RECAP 2025-12-12 00:18:33.535167 | controller | ok: 1 changed: 0 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0 2025-12-12 00:18:33.535220 | 2025-12-12 00:18:33.663975 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master] 2025-12-12 00:18:33.664969 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2025-12-12 00:18:34.278399 | 2025-12-12 00:18:34.278647 | PLAY [all] 2025-12-12 00:18:34.302258 | 2025-12-12 00:18:34.302395 | TASK [fetch-subunit-output : Find stestr or testr executable] 2025-12-12 00:21:56.876096 | controller | changed: non-zero return code 2025-12-12 00:21:56.891414 | 2025-12-12 00:21:56.891601 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2025-12-12 00:21:56.920189 | controller | skipping: Conditional result was False 2025-12-12 00:21:56.935795 | 2025-12-12 00:21:56.935962 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2025-12-12 00:21:56.979424 | 2025-12-12 00:21:56.979822 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2025-12-12 00:21:57.023914 | 2025-12-12 00:21:57.024219 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2025-12-12 00:21:57.052161 | controller | skipping: Conditional result was False 2025-12-12 00:21:57.067155 | 2025-12-12 00:21:57.067316 | LOOP [fetch-subunit-output : Generate subunit file] 2025-12-12 00:21:57.103411 | 2025-12-12 00:21:57.103659 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2025-12-12 00:21:57.128368 | controller | skipping: Conditional result was False 2025-12-12 00:21:57.134573 | 2025-12-12 00:21:57.134642 | TASK [fetch-subunit-output : Remove the temporary file] 2025-12-12 00:21:57.148168 | controller | skipping: Conditional result was False 2025-12-12 00:21:57.154870 | 2025-12-12 00:21:57.154949 | TASK [fetch-subunit-output : Process and fetch subunit results] 2025-12-12 00:21:57.169031 | controller | skipping: Conditional result was False 2025-12-12 00:21:57.196285 | 2025-12-12 00:21:57.196372 | PLAY RECAP 2025-12-12 00:21:57.196414 | controller | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2025-12-12 00:21:57.196434 | 2025-12-12 00:21:57.312043 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2025-12-12 00:21:57.312915 | POST-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/post.yaml@master] 2025-12-12 00:21:57.937108 | 2025-12-12 00:21:57.937226 | PLAY [all:!appliance*] 2025-12-12 00:21:57.958545 | 2025-12-12 00:21:57.958653 | TASK [unregister the node] 2025-12-12 00:21:58.488484 | controller | skipping: Conditional result was False 2025-12-12 00:21:58.500906 | 2025-12-12 00:21:58.501164 | TASK [include_role : fetch-output] 2025-12-12 00:21:58.542763 | controller | ok 2025-12-12 00:21:58.596499 | 2025-12-12 00:21:58.596723 | TASK [fetch-output : Set log path for multiple nodes] 2025-12-12 00:21:58.655127 | controller | skipping: Conditional result was False 2025-12-12 00:21:58.670787 | 2025-12-12 00:21:58.671136 | TASK [fetch-output : Set log path for single node] 2025-12-12 00:21:58.745203 | controller | ok 2025-12-12 00:21:58.759195 | 2025-12-12 00:21:58.759361 | LOOP [fetch-output : Ensure local output dirs] 2025-12-12 00:21:59.167567 | controller -> localhost | ok: "/var/lib/zuul/builds/76b2d224bfb142e3aea76b607defc4e4/work/logs" 2025-12-12 00:21:59.452580 | controller -> localhost | changed: "/var/lib/zuul/builds/76b2d224bfb142e3aea76b607defc4e4/work/artifacts" 2025-12-12 00:21:59.738517 | controller -> localhost | changed: "/var/lib/zuul/builds/76b2d224bfb142e3aea76b607defc4e4/work/docs" 2025-12-12 00:21:59.762858 | 2025-12-12 00:21:59.763104 | LOOP [fetch-output : Collect logs, artifacts and docs] 2025-12-12 00:22:00.508592 | controller | ok 2025-12-12 00:22:00.508960 | controller | changed: All items complete 2025-12-12 00:22:00.509048 | 2025-12-12 00:22:01.038544 | controller | changed: .d..t...... ./ 2025-12-12 00:22:01.558212 | controller | changed: .d..t...... ./ 2025-12-12 00:22:01.585511 | 2025-12-12 00:22:01.585705 | TASK [include_role : fetch-output-openshift] 2025-12-12 00:22:01.602222 | controller | skipping: Conditional result was False 2025-12-12 00:22:01.617503 | 2025-12-12 00:22:01.617639 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2025-12-12 00:22:01.651273 | controller | skipping: Conditional result was False 2025-12-12 00:22:01.662070 | controller | skipping: Conditional result was False 2025-12-12 00:22:01.693008 | 2025-12-12 00:22:01.693157 | PLAY [localhost] 2025-12-12 00:22:01.705519 | 2025-12-12 00:22:01.705641 | TASK [Run Zuul manifest role] 2025-12-12 00:22:01.725959 | localhost | ok 2025-12-12 00:22:01.747258 | 2025-12-12 00:22:01.747426 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-12-12 00:22:02.163447 | localhost | changed 2025-12-12 00:22:02.169130 | 2025-12-12 00:22:02.169212 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-12-12 00:22:02.210674 | localhost | ok 2025-12-12 00:22:02.219991 | 2025-12-12 00:22:02.220086 | TASK [Set zuul-log-path fact] 2025-12-12 00:22:02.240629 | localhost | ok 2025-12-12 00:22:02.259083 | 2025-12-12 00:22:02.259201 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-12-12 00:22:02.287850 | localhost | ok 2025-12-12 00:22:02.296617 | 2025-12-12 00:22:02.296702 | LOOP [Run upload-logs-swift role] 2025-12-12 00:22:02.332810 | localhost | Output suppressed because no_log was given 2025-12-12 00:22:02.376874 | 2025-12-12 00:22:02.377042 | TASK [Set zuul-log-path fact] 2025-12-12 00:22:02.413793 | localhost | skipping: Conditional result was False 2025-12-12 00:22:02.421409 | 2025-12-12 00:22:02.421499 | TASK [upload-logs-swift : Ensure logs are readable before uploading] 2025-12-12 00:22:02.845010 | localhost -> localhost | ok: Runtime: 0:00:00.009194 2025-12-12 00:22:02.879878 | 2025-12-12 00:22:02.880002 | TASK [upload-logs-swift : Upload logs to swift]