2025-12-09 00:06:45.118763 | Job console starting... 2025-12-09 00:06:45.133297 | Updating repositories 2025-12-09 00:06:45.296704 | Preparing job workspace 2025-12-09 00:06:49.238802 | Running Ansible setup... 2025-12-09 00:06:53.963991 | PRE-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master] 2025-12-09 00:06:54.543869 | 2025-12-09 00:06:54.543985 | PLAY [localhost] 2025-12-09 00:06:54.551960 | 2025-12-09 00:06:54.552028 | TASK [Gathering Facts] 2025-12-09 00:06:55.492190 | localhost | ok 2025-12-09 00:06:55.506237 | 2025-12-09 00:06:55.506312 | TASK [Setup log path fact] 2025-12-09 00:06:55.545488 | localhost | ok 2025-12-09 00:06:55.563355 | 2025-12-09 00:06:55.563449 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-12-09 00:06:55.608457 | localhost | ok 2025-12-09 00:06:55.623248 | 2025-12-09 00:06:55.623356 | TASK [emit-job-header : Print job information] 2025-12-09 00:06:55.672584 | # Job Information 2025-12-09 00:06:55.672754 | Ansible Version: 2.15.12 2025-12-09 00:06:55.672791 | Job: ansible-test-sanity-docker-devel 2025-12-09 00:06:55.672813 | Pipeline: periodic 2025-12-09 00:06:55.672832 | Executor: ze03.softwarefactory-project.io 2025-12-09 00:06:55.672850 | Triggered by: https://github.com/ansible-collections/ansible.network 2025-12-09 00:06:55.672873 | Log URL (when completed): https://ansible.softwarefactory-project.io/logs/345/ansible/3459fe18be4d4206baa44d70dae49b1e/ 2025-12-09 00:06:55.672892 | Event ID: af90092fb8e9455a9ddccb9605b9ecd4 2025-12-09 00:06:55.677051 | 2025-12-09 00:06:55.677116 | LOOP [emit-job-header : Print node information] 2025-12-09 00:06:55.797309 | localhost | ok: 2025-12-09 00:06:55.797492 | localhost | # Node Information 2025-12-09 00:06:55.797523 | localhost | Inventory Hostname: controller 2025-12-09 00:06:55.797543 | localhost | Hostname: np0005551153 2025-12-09 00:06:55.797563 | localhost | Username: zuul 2025-12-09 00:06:55.797587 | localhost | Distro: Fedora 37 2025-12-09 00:06:55.797606 | localhost | Provider: ansible-vexxhost-ca-ymq-1 2025-12-09 00:06:55.797624 | localhost | Region: ca-ymq-1 2025-12-09 00:06:55.797641 | localhost | Label: ansible-fedora-37-1vcpu 2025-12-09 00:06:55.797658 | localhost | Product Name: OpenStack Nova 2025-12-09 00:06:55.797675 | localhost | Interface IP: 162.253.55.186 2025-12-09 00:06:55.811604 | 2025-12-09 00:06:55.811757 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-12-09 00:06:56.204413 | localhost -> localhost | changed 2025-12-09 00:06:56.217781 | 2025-12-09 00:06:56.218071 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-12-09 00:06:57.039431 | localhost -> localhost | changed 2025-12-09 00:06:57.066779 | 2025-12-09 00:06:57.066892 | PLAY [all:!appliance*] 2025-12-09 00:06:57.084208 | 2025-12-09 00:06:57.084291 | TASK [include_role : start-zuul-console] 2025-12-09 00:06:57.103589 | controller | ok 2025-12-09 00:06:57.118152 | 2025-12-09 00:06:57.118242 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-12-09 00:06:57.492615 | controller | ok 2025-12-09 00:06:57.503681 | 2025-12-09 00:06:57.503796 | TASK [use-our-mirror : Retrieve the IP address] 2025-12-09 00:06:58.341686 | controller | ok: Status code was 404 and not [200]: HTTP Error 404: Not Found 2025-12-09 00:06:58.357363 | 2025-12-09 00:06:58.357506 | TASK [use-our-mirror : Ensure the registry service is running on the mirror] 2025-12-09 00:06:58.905881 | controller | skipping: Conditional result was False 2025-12-09 00:06:58.916228 | 2025-12-09 00:06:58.916331 | TASK [use-our-mirror : Write the IP in /etc/hosts] 2025-12-09 00:06:58.951452 | controller | skipping: Conditional result was False 2025-12-09 00:06:58.961091 | 2025-12-09 00:06:58.961225 | TASK [use-our-mirror : Create the podman configuration directory] 2025-12-09 00:06:58.996004 | controller | skipping: Conditional result was False 2025-12-09 00:06:59.005787 | 2025-12-09 00:06:59.005907 | TASK [use-our-mirror : Copy the podman configuration] 2025-12-09 00:06:59.031662 | controller | skipping: Conditional result was False 2025-12-09 00:06:59.043582 | 2025-12-09 00:06:59.043771 | TASK [use-our-mirror : Copy the Yum configuration (main)] 2025-12-09 00:06:59.073323 | controller | skipping: Conditional result was False 2025-12-09 00:06:59.081719 | 2025-12-09 00:06:59.081819 | TASK [use-our-mirror : Copy the Yum configuration (update)] 2025-12-09 00:06:59.106200 | controller | skipping: Conditional result was False 2025-12-09 00:06:59.127431 | 2025-12-09 00:06:59.127562 | TASK [Disable Fedora Modular] 2025-12-09 00:06:59.410573 | controller | changed 2025-12-09 00:06:59.425618 | 2025-12-09 00:06:59.425806 | TASK [Enable EPEL] 2025-12-09 00:06:59.464291 | controller | skipping: Conditional result was False 2025-12-09 00:06:59.484600 | 2025-12-09 00:06:59.484841 | TASK [Register the RHEL node] 2025-12-09 00:07:00.040901 | 2025-12-09 00:07:00.041067 | TASK [Show the subscription-manager status] 2025-12-09 00:07:00.603850 | controller | skipping: Conditional result was False 2025-12-09 00:07:00.622009 | 2025-12-09 00:07:00.622221 | TASK [Enable EPEL on RHEL] 2025-12-09 00:07:01.197656 | controller | skipping: Conditional result was False 2025-12-09 00:07:01.206805 | 2025-12-09 00:07:01.206897 | TASK [Install git and tox] 2025-12-09 00:08:33.316675 | controller | changed 2025-12-09 00:08:33.331301 | 2025-12-09 00:08:33.331405 | TASK [include_role : prepare-workspace] 2025-12-09 00:08:33.378422 | controller | ok 2025-12-09 00:08:33.428476 | 2025-12-09 00:08:33.428607 | TASK [prepare-workspace : Start zuul_console daemon.] 2025-12-09 00:08:33.692485 | controller | ok 2025-12-09 00:08:33.702234 | 2025-12-09 00:08:33.702354 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2025-12-09 00:14:23.879337 | controller | Output suppressed because no_log was given 2025-12-09 00:14:23.889782 | 2025-12-09 00:14:23.889855 | TASK [include_role : prepare-workspace-openshift] 2025-12-09 00:14:23.913491 | controller | skipping: Conditional result was False 2025-12-09 00:14:23.936574 | 2025-12-09 00:14:23.936648 | PLAY [all:!appliance] 2025-12-09 00:14:23.952313 | 2025-12-09 00:14:23.952470 | TASK [Run add-build-sshkey role (RSA)] 2025-12-09 00:14:23.981580 | controller | ok 2025-12-09 00:14:23.997765 | 2025-12-09 00:14:23.997856 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-12-09 00:14:24.238496 | controller -> localhost | ok 2025-12-09 00:14:24.248251 | 2025-12-09 00:14:24.248408 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-12-09 00:14:24.278428 | controller | ok 2025-12-09 00:14:24.302955 | controller | included: /var/lib/zuul/builds/3459fe18be4d4206baa44d70dae49b1e/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-12-09 00:14:24.312376 | 2025-12-09 00:14:24.312487 | TASK [add-build-sshkey : Create Temp SSH key] 2025-12-09 00:14:24.827919 | controller -> localhost | Generating public/private rsa key pair. 2025-12-09 00:14:24.828128 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/3459fe18be4d4206baa44d70dae49b1e/work/3459fe18be4d4206baa44d70dae49b1e_id_rsa. 2025-12-09 00:14:24.828157 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/3459fe18be4d4206baa44d70dae49b1e/work/3459fe18be4d4206baa44d70dae49b1e_id_rsa.pub. 2025-12-09 00:14:24.828179 | controller -> localhost | The key fingerprint is: 2025-12-09 00:14:24.828200 | controller -> localhost | SHA256:RfBzGVPItXn5uUMTI6Yjuftb9XWXpDonmyhvWWpO1As zuul-build-sshkey 2025-12-09 00:14:24.828219 | controller -> localhost | The key's randomart image is: 2025-12-09 00:14:24.828238 | controller -> localhost | +---[RSA 2048]----+ 2025-12-09 00:14:24.828256 | controller -> localhost | | ....o+o | 2025-12-09 00:14:24.828274 | controller -> localhost | | o o+ o.| 2025-12-09 00:14:24.828293 | controller -> localhost | | + ooo=.| 2025-12-09 00:14:24.828312 | controller -> localhost | | .ooo +.*| 2025-12-09 00:14:24.828330 | controller -> localhost | | SE + . *=| 2025-12-09 00:14:24.828359 | controller -> localhost | | . +.+ o B| 2025-12-09 00:14:24.828381 | controller -> localhost | | o+= o o.| 2025-12-09 00:14:24.828402 | controller -> localhost | | ..=o B .| 2025-12-09 00:14:24.828422 | controller -> localhost | | *=.=. | 2025-12-09 00:14:24.828444 | controller -> localhost | +----[SHA256]-----+ 2025-12-09 00:14:24.828514 | controller -> localhost | ok: Runtime: 0:00:00.092936 2025-12-09 00:14:24.836122 | 2025-12-09 00:14:24.836206 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-12-09 00:14:24.871321 | controller | ok 2025-12-09 00:14:24.888011 | controller | included: /var/lib/zuul/builds/3459fe18be4d4206baa44d70dae49b1e/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-12-09 00:14:24.901847 | 2025-12-09 00:14:24.901963 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-12-09 00:14:24.927483 | controller | skipping: Conditional result was False 2025-12-09 00:14:24.938077 | 2025-12-09 00:14:24.938185 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-12-09 00:14:25.580403 | controller | changed 2025-12-09 00:14:25.594374 | 2025-12-09 00:14:25.594533 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-12-09 00:14:25.840288 | controller | ok 2025-12-09 00:14:25.853526 | 2025-12-09 00:14:25.853659 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-12-09 00:14:26.494146 | controller | changed 2025-12-09 00:14:26.508568 | 2025-12-09 00:14:26.508718 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-12-09 00:14:27.166681 | controller | changed 2025-12-09 00:14:27.181312 | 2025-12-09 00:14:27.181457 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-12-09 00:14:27.209318 | controller | skipping: Conditional result was False 2025-12-09 00:14:27.225312 | 2025-12-09 00:14:27.225454 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-12-09 00:14:27.721773 | controller -> localhost | changed 2025-12-09 00:14:27.742907 | 2025-12-09 00:14:27.743009 | TASK [add-build-sshkey : Add back temp key] 2025-12-09 00:14:28.012450 | controller -> localhost | Identity added: /var/lib/zuul/builds/3459fe18be4d4206baa44d70dae49b1e/work/3459fe18be4d4206baa44d70dae49b1e_id_rsa (zuul-build-sshkey) 2025-12-09 00:14:28.012668 | controller -> localhost | ok: Runtime: 0:00:00.008111 2025-12-09 00:14:28.020217 | 2025-12-09 00:14:28.020287 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-12-09 00:14:28.384853 | controller | ok 2025-12-09 00:14:28.390747 | 2025-12-09 00:14:28.390813 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-12-09 00:14:28.435710 | controller | skipping: Conditional result was False 2025-12-09 00:14:28.464016 | 2025-12-09 00:14:28.464173 | TASK [Run add-build-sshkey role (ECDSA)] 2025-12-09 00:14:28.509867 | controller | ok 2025-12-09 00:14:28.534346 | 2025-12-09 00:14:28.534441 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-12-09 00:14:28.836163 | controller -> localhost | ok 2025-12-09 00:14:28.843362 | 2025-12-09 00:14:28.843451 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-12-09 00:14:28.865988 | controller | ok 2025-12-09 00:14:28.878415 | controller | included: /var/lib/zuul/builds/3459fe18be4d4206baa44d70dae49b1e/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-12-09 00:14:28.885639 | 2025-12-09 00:14:28.885706 | TASK [add-build-sshkey : Create Temp SSH key] 2025-12-09 00:14:29.184715 | controller -> localhost | Generating public/private ecdsa key pair. 2025-12-09 00:14:29.185117 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/3459fe18be4d4206baa44d70dae49b1e/work/3459fe18be4d4206baa44d70dae49b1e_id_ecdsa. 2025-12-09 00:14:29.185187 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/3459fe18be4d4206baa44d70dae49b1e/work/3459fe18be4d4206baa44d70dae49b1e_id_ecdsa.pub. 2025-12-09 00:14:29.185263 | controller -> localhost | The key fingerprint is: 2025-12-09 00:14:29.185310 | controller -> localhost | SHA256:H0hjWkeogLzj3Oy+ZSJ4aqxMwSvSuQ0x3IneJIHzpcs zuul-build-sshkey 2025-12-09 00:14:29.185351 | controller -> localhost | The key's randomart image is: 2025-12-09 00:14:29.185391 | controller -> localhost | +---[ECDSA 521]---+ 2025-12-09 00:14:29.185430 | controller -> localhost | | . . .. | 2025-12-09 00:14:29.185468 | controller -> localhost | | .o . .. | 2025-12-09 00:14:29.185505 | controller -> localhost | |o .... .= . | 2025-12-09 00:14:29.185543 | controller -> localhost | |.+o* ..= + | 2025-12-09 00:14:29.185579 | controller -> localhost | | =O++ . S . | 2025-12-09 00:14:29.185617 | controller -> localhost | | ==Oo . . | 2025-12-09 00:14:29.185653 | controller -> localhost | |=oE.o o . | 2025-12-09 00:14:29.185690 | controller -> localhost | |== =.+ | 2025-12-09 00:14:29.185919 | controller -> localhost | |=...+. | 2025-12-09 00:14:29.185997 | controller -> localhost | +----[SHA256]-----+ 2025-12-09 00:14:29.186096 | controller -> localhost | ok: Runtime: 0:00:00.013778 2025-12-09 00:14:29.203876 | 2025-12-09 00:14:29.204021 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-12-09 00:14:29.245197 | controller | ok 2025-12-09 00:14:29.264138 | controller | included: /var/lib/zuul/builds/3459fe18be4d4206baa44d70dae49b1e/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-12-09 00:14:29.277638 | 2025-12-09 00:14:29.277756 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-12-09 00:14:29.302929 | controller | skipping: Conditional result was False 2025-12-09 00:14:29.313348 | 2025-12-09 00:14:29.313450 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-12-09 00:14:29.585537 | controller | changed 2025-12-09 00:14:29.604180 | 2025-12-09 00:14:29.604515 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-12-09 00:14:29.851089 | controller | ok 2025-12-09 00:14:29.868975 | 2025-12-09 00:14:29.869126 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-12-09 00:14:30.510486 | controller | changed 2025-12-09 00:14:30.520096 | 2025-12-09 00:14:30.520208 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-12-09 00:14:31.175068 | controller | changed 2025-12-09 00:14:31.233291 | 2025-12-09 00:14:31.233440 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-12-09 00:14:31.268715 | controller | skipping: Conditional result was False 2025-12-09 00:14:31.278326 | 2025-12-09 00:14:31.278438 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-12-09 00:14:31.578951 | controller -> localhost | changed 2025-12-09 00:14:31.591916 | 2025-12-09 00:14:31.592033 | TASK [add-build-sshkey : Add back temp key] 2025-12-09 00:14:31.880619 | controller -> localhost | Identity added: /var/lib/zuul/builds/3459fe18be4d4206baa44d70dae49b1e/work/3459fe18be4d4206baa44d70dae49b1e_id_ecdsa (zuul-build-sshkey) 2025-12-09 00:14:31.880822 | controller -> localhost | ok: Runtime: 0:00:00.009649 2025-12-09 00:14:31.887499 | 2025-12-09 00:14:31.887561 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-12-09 00:14:32.109914 | controller | ok 2025-12-09 00:14:32.122654 | 2025-12-09 00:14:32.122850 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-12-09 00:14:32.150124 | controller | skipping: Conditional result was False 2025-12-09 00:14:32.175154 | 2025-12-09 00:14:32.175270 | TASK [include_role : remove-zuul-sshkey] 2025-12-09 00:14:32.200548 | controller | skipping: Conditional result was False 2025-12-09 00:14:32.214246 | 2025-12-09 00:14:32.214384 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2025-12-09 00:14:32.459571 | controller | ok: "logs" 2025-12-09 00:14:32.459898 | controller | ok: All items complete 2025-12-09 00:14:32.459938 | 2025-12-09 00:14:32.675890 | controller | ok: "artifacts" 2025-12-09 00:14:32.864910 | controller | ok: "docs" 2025-12-09 00:14:32.872591 | 2025-12-09 00:14:32.872670 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2025-12-09 00:14:33.105355 | controller | changed: "logs" 2025-12-09 00:14:33.307392 | controller | changed: "artifacts" 2025-12-09 00:14:33.510323 | controller | changed: "docs" 2025-12-09 00:14:33.585750 | 2025-12-09 00:14:33.585890 | PLAY RECAP 2025-12-09 00:14:33.585954 | controller | ok: 30 changed: 16 unreachable: 0 failed: 0 skipped: 18 rescued: 0 ignored: 0 2025-12-09 00:14:33.585991 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-12-09 00:14:33.586028 | 2025-12-09 00:14:33.705211 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master] 2025-12-09 00:14:33.706084 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2025-12-09 00:14:34.234875 | 2025-12-09 00:14:34.234984 | PLAY [all] 2025-12-09 00:14:34.259452 | 2025-12-09 00:14:34.259569 | TASK [Install binary dependencies] 2025-12-09 00:14:34.340183 | controller | ok 2025-12-09 00:14:34.361693 | 2025-12-09 00:14:34.361839 | TASK [bindep : Include find tasks] 2025-12-09 00:14:34.394040 | controller | ok 2025-12-09 00:14:34.407117 | controller | included: /var/lib/zuul/builds/3459fe18be4d4206baa44d70dae49b1e/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find.yaml 2025-12-09 00:14:34.416658 | 2025-12-09 00:14:34.416903 | TASK [bindep : Look for bindep.txt] 2025-12-09 00:14:34.953525 | controller | ok 2025-12-09 00:14:34.967904 | 2025-12-09 00:14:34.968097 | TASK [bindep : Define bindep_file fact] 2025-12-09 00:14:34.996053 | controller | skipping: Conditional result was False 2025-12-09 00:14:35.002640 | 2025-12-09 00:14:35.002738 | TASK [bindep : Look for other-requirements.txt] 2025-12-09 00:14:35.222019 | controller | ok 2025-12-09 00:14:35.234458 | 2025-12-09 00:14:35.234599 | TASK [bindep : Define bindep_file fact] 2025-12-09 00:14:35.272137 | controller | skipping: Conditional result was False 2025-12-09 00:14:35.286094 | 2025-12-09 00:14:35.286240 | TASK [bindep : Look for bindep fallback file] 2025-12-09 00:14:35.334189 | controller | skipping: Conditional result was False 2025-12-09 00:14:35.350665 | 2025-12-09 00:14:35.350862 | TASK [bindep : Define bindep_file fact] 2025-12-09 00:14:35.388609 | controller | skipping: Conditional result was False 2025-12-09 00:14:35.405203 | 2025-12-09 00:14:35.405451 | TASK [bindep : Include bindep tasks] 2025-12-09 00:14:35.445060 | controller | skipping: Conditional result was False 2025-12-09 00:14:35.455978 | 2025-12-09 00:14:35.456114 | TASK [bindep : Include install tasks] 2025-12-09 00:14:35.483443 | controller | skipping: Conditional result was False 2025-12-09 00:14:35.495164 | 2025-12-09 00:14:35.495304 | LOOP [bindep : Include package tasks] 2025-12-09 00:14:35.566923 | 2025-12-09 00:14:35.567211 | TASK [Run test-setup role] 2025-12-09 00:14:35.595089 | controller | ok 2025-12-09 00:14:35.623717 | 2025-12-09 00:14:35.623956 | TASK [test-setup : Check if project's tools/test-setup.sh exists] 2025-12-09 00:14:35.843051 | controller | ok 2025-12-09 00:14:35.851606 | 2025-12-09 00:14:35.851691 | TASK [test-setup : Run tools/test-setup.sh] 2025-12-09 00:14:36.395811 | controller | skipping: Conditional result was False 2025-12-09 00:14:36.452912 | 2025-12-09 00:14:36.453030 | PLAY RECAP 2025-12-09 00:14:36.453085 | controller | ok: 4 changed: 0 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2025-12-09 00:14:36.453114 | 2025-12-09 00:14:36.554054 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2025-12-09 00:14:36.554956 | PRE-RUN START: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master] 2025-12-09 00:14:37.125514 | 2025-12-09 00:14:37.125653 | PLAY [controller] 2025-12-09 00:14:37.148503 | 2025-12-09 00:14:37.148603 | TASK [Create the /root directory] 2025-12-09 00:14:37.684559 | controller | ok 2025-12-09 00:14:37.698673 | 2025-12-09 00:14:37.698886 | TASK [Install glibc-langpack-en] 2025-12-09 00:14:41.590123 | controller | ok: Nothing to do 2025-12-09 00:14:41.605508 | 2025-12-09 00:14:41.605693 | TASK [Ensure controller directory exists] 2025-12-09 00:14:41.825799 | controller | changed 2025-12-09 00:14:41.835123 | 2025-12-09 00:14:41.835225 | TASK [Install container runtime] 2025-12-09 00:14:41.880315 | controller | ok 2025-12-09 00:14:41.936232 | 2025-12-09 00:14:41.936401 | LOOP [ensure-podman : Find distribution installation] 2025-12-09 00:14:41.970244 | controller | ok: "/var/lib/zuul/builds/3459fe18be4d4206baa44d70dae49b1e/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-podman/tasks/RedHat.yaml" 2025-12-09 00:14:41.986267 | controller | included: /var/lib/zuul/builds/3459fe18be4d4206baa44d70dae49b1e/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-podman/tasks/RedHat.yaml 2025-12-09 00:14:41.997273 | 2025-12-09 00:14:41.997385 | TASK [ensure-podman : Install podman (RedHat)] 2025-12-09 00:15:50.645232 | controller | changed 2025-12-09 00:15:50.662490 | 2025-12-09 00:15:50.662655 | TASK [ensure-podman : Fetch podman version] 2025-12-09 00:16:21.338172 | [Zuul] Log Stream did not terminate 2025-12-09 00:16:21.338422 | controller | ok: Runtime: 0:00:00.187725 2025-12-09 00:16:21.349411 | 2025-12-09 00:16:21.349494 | TASK [ensure-podman : Print podman version installed] 2025-12-09 00:16:21.384257 | Podman version: Client: Podman Engine 2025-12-09 00:16:21.384395 | Version: 4.6.2 2025-12-09 00:16:21.384421 | API Version: 4.6.2 2025-12-09 00:16:21.384440 | Go Version: go1.19.12 2025-12-09 00:16:21.384457 | Built: Mon Aug 28 19:38:31 2023 2025-12-09 00:16:21.384476 | OS/Arch: linux/amd64 2025-12-09 00:16:21.392693 | 2025-12-09 00:16:21.392772 | TASK [ensure-podman : Validate podman engine] 2025-12-09 00:16:26.437701 | controller | skipping: Conditional result was False 2025-12-09 00:16:26.445181 | 2025-12-09 00:16:26.445269 | TASK [ensure-podman : Set up docker compatability socket] 2025-12-09 00:16:26.468931 | controller | skipping: Conditional result was False 2025-12-09 00:16:26.480846 | 2025-12-09 00:16:26.480940 | TASK [Ensure python3.8 is present] 2025-12-09 00:16:26.494954 | controller | skipping: Conditional result was False 2025-12-09 00:16:26.501537 | 2025-12-09 00:16:26.501605 | TASK [Run our-ensure-python role (Fedora only for now)] 2025-12-09 00:16:26.526099 | controller | ok 2025-12-09 00:16:26.551122 | 2025-12-09 00:16:26.551234 | TASK [our-ensure-python : Install the right Python version (rpm)] 2025-12-09 00:18:38.736102 | controller | ERROR 2025-12-09 00:18:38.736455 | controller | { 2025-12-09 00:18:38.736517 | controller | "msg": "Timeout (32s) waiting for privilege escalation prompt: " 2025-12-09 00:18:38.736560 | controller | } failure 2025-12-09 00:18:38.738665 | 2025-12-09 00:18:38.738830 | PLAY RECAP 2025-12-09 00:18:38.738918 | controller | ok: 7 changed: 3 unreachable: 0 failed: 1 skipped: 3 rescued: 0 ignored: 0 2025-12-09 00:18:38.738963 | 2025-12-09 00:18:38.873230 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master] 2025-12-09 00:18:38.874446 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2025-12-09 00:18:39.472830 | 2025-12-09 00:18:39.472941 | PLAY [all] 2025-12-09 00:18:39.495901 | 2025-12-09 00:18:39.495998 | TASK [fetch-subunit-output : Find stestr or testr executable] 2025-12-09 00:20:15.829035 | controller | ERROR 2025-12-09 00:20:15.830337 | controller | { 2025-12-09 00:20:15.830475 | controller | "msg": "Failed to connect to the host via ssh: ssh: connect to host 162.253.55.186 port 22: Connection timed out", 2025-12-09 00:20:15.830509 | controller | "unreachable": true 2025-12-09 00:20:15.830556 | controller | } unreachable 2025-12-09 00:20:15.833141 | 2025-12-09 00:20:15.833198 | PLAY RECAP 2025-12-09 00:20:15.833289 | controller | ok: 0 changed: 0 unreachable: 1 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-12-09 00:20:15.833314 | 2025-12-09 00:20:15.914536 | POST-RUN END RESULT_UNREACHABLE: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2025-12-09 00:20:15.915950 | POST-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/post.yaml@master] 2025-12-09 00:20:16.506153 | 2025-12-09 00:20:16.506259 | PLAY [all:!appliance*] 2025-12-09 00:20:16.542391 | 2025-12-09 00:20:16.542491 | TASK [unregister the node] 2025-12-09 00:20:21.576072 | controller | skipping: Conditional result was False 2025-12-09 00:20:21.593879 | 2025-12-09 00:20:21.594094 | TASK [include_role : fetch-output] 2025-12-09 00:20:21.633870 | controller | ok 2025-12-09 00:20:21.675678 | 2025-12-09 00:20:21.675841 | TASK [fetch-output : Set log path for multiple nodes] 2025-12-09 00:20:21.741373 | controller | skipping: Conditional result was False 2025-12-09 00:20:21.754818 | 2025-12-09 00:20:21.755011 | TASK [fetch-output : Set log path for single node] 2025-12-09 00:20:21.803812 | controller | ok 2025-12-09 00:20:21.818838 | 2025-12-09 00:20:21.818977 | LOOP [fetch-output : Ensure local output dirs] 2025-12-09 00:20:22.257475 | controller -> localhost | ok: "/var/lib/zuul/builds/3459fe18be4d4206baa44d70dae49b1e/work/logs" 2025-12-09 00:20:22.527547 | controller -> localhost | changed: "/var/lib/zuul/builds/3459fe18be4d4206baa44d70dae49b1e/work/artifacts" 2025-12-09 00:20:22.776656 | controller -> localhost | changed: "/var/lib/zuul/builds/3459fe18be4d4206baa44d70dae49b1e/work/docs" 2025-12-09 00:20:22.795318 | 2025-12-09 00:20:22.795457 | LOOP [fetch-output : Collect logs, artifacts and docs] 2025-12-09 00:20:55.136906 | controller | changed: 2025-12-09 00:20:55.146500 | controller | .d..t...... ./ 2025-12-09 00:20:55.146555 | controller | cd+++++++++ controller/ 2025-12-09 00:20:55.146592 | controller | changed: All items complete 2025-12-09 00:20:55.146614 | 2025-12-09 00:20:55.758703 | controller | changed: .d..t...... ./ 2025-12-09 00:20:56.299593 | controller | changed: .d..t...... ./ 2025-12-09 00:20:56.338828 | 2025-12-09 00:20:56.339032 | TASK [include_role : fetch-output-openshift] 2025-12-09 00:20:56.362806 | controller | skipping: Conditional result was False 2025-12-09 00:20:56.373380 | 2025-12-09 00:20:56.373468 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2025-12-09 00:20:56.408161 | controller | skipping: Conditional result was False 2025-12-09 00:20:56.419299 | controller | skipping: Conditional result was False 2025-12-09 00:20:56.457653 | 2025-12-09 00:20:56.457798 | PLAY [localhost] 2025-12-09 00:20:56.476263 | 2025-12-09 00:20:56.476373 | TASK [Run Zuul manifest role] 2025-12-09 00:20:56.495529 | localhost | ok 2025-12-09 00:20:56.525350 | 2025-12-09 00:20:56.525412 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-12-09 00:20:56.924258 | localhost | changed 2025-12-09 00:20:56.935825 | 2025-12-09 00:20:56.936022 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-12-09 00:20:56.974966 | localhost | ok 2025-12-09 00:20:56.996283 | 2025-12-09 00:20:56.996370 | TASK [Set zuul-log-path fact] 2025-12-09 00:20:57.025650 | localhost | ok 2025-12-09 00:20:57.045499 | 2025-12-09 00:20:57.045644 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-12-09 00:20:57.087312 | localhost | ok 2025-12-09 00:20:57.099048 | 2025-12-09 00:20:57.099166 | LOOP [Run upload-logs-swift role] 2025-12-09 00:20:57.144694 | localhost | Output suppressed because no_log was given 2025-12-09 00:20:57.183577 | 2025-12-09 00:20:57.183690 | TASK [Set zuul-log-path fact] 2025-12-09 00:20:57.220044 | localhost | skipping: Conditional result was False 2025-12-09 00:20:57.227680 | 2025-12-09 00:20:57.227801 | TASK [upload-logs-swift : Ensure logs are readable before uploading] 2025-12-09 00:20:57.659353 | localhost -> localhost | ok: Runtime: 0:00:00.006729 2025-12-09 00:20:57.665087 | 2025-12-09 00:20:57.665153 | TASK [upload-logs-swift : Upload logs to swift]