2025-11-10 00:06:54.447190 | Job console starting... 2025-11-10 00:06:54.457873 | Updating repositories 2025-11-10 00:06:54.568556 | Preparing job workspace 2025-11-10 00:06:58.389638 | Running Ansible setup... 2025-11-10 00:07:03.144769 | PRE-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master] 2025-11-10 00:07:03.729466 | 2025-11-10 00:07:03.729625 | PLAY [localhost] 2025-11-10 00:07:03.737973 | 2025-11-10 00:07:03.738061 | TASK [Gathering Facts] 2025-11-10 00:07:04.694341 | localhost | ok 2025-11-10 00:07:04.723536 | 2025-11-10 00:07:04.723689 | TASK [Setup log path fact] 2025-11-10 00:07:04.744507 | localhost | ok 2025-11-10 00:07:04.762543 | 2025-11-10 00:07:04.762636 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-11-10 00:07:04.782226 | localhost | ok 2025-11-10 00:07:04.794963 | 2025-11-10 00:07:04.795077 | TASK [emit-job-header : Print job information] 2025-11-10 00:07:04.824535 | # Job Information 2025-11-10 00:07:04.825054 | Ansible Version: 2.15.12 2025-11-10 00:07:04.825107 | Job: ansible-test-sanity-docker-milestone 2025-11-10 00:07:04.825141 | Pipeline: periodic 2025-11-10 00:07:04.825170 | Executor: ze01.softwarefactory-project.io 2025-11-10 00:07:04.825200 | Triggered by: https://github.com/ansible-collections/ansible.network 2025-11-10 00:07:04.825236 | Log URL (when completed): https://ansible.softwarefactory-project.io/logs/afc/ansible/afcc68cfd8574a1db59ba1ad8e8f8123/ 2025-11-10 00:07:04.825268 | Event ID: e7ed9acfd5d246fc9eb02e45c4dd8a00 2025-11-10 00:07:04.830724 | 2025-11-10 00:07:04.830814 | LOOP [emit-job-header : Print node information] 2025-11-10 00:07:04.965074 | localhost | ok: 2025-11-10 00:07:04.965327 | localhost | # Node Information 2025-11-10 00:07:04.965366 | localhost | Inventory Hostname: controller 2025-11-10 00:07:04.965393 | localhost | Hostname: np0005516253 2025-11-10 00:07:04.965419 | localhost | Username: zuul 2025-11-10 00:07:04.965447 | localhost | Distro: Fedora 37 2025-11-10 00:07:04.965473 | localhost | Provider: ansible-vexxhost-ca-ymq-1 2025-11-10 00:07:04.965497 | localhost | Region: ca-ymq-1 2025-11-10 00:07:04.965520 | localhost | Label: ansible-fedora-37-1vcpu 2025-11-10 00:07:04.965543 | localhost | Product Name: OpenStack Nova 2025-11-10 00:07:04.965566 | localhost | Interface IP: 162.253.55.84 2025-11-10 00:07:04.978345 | 2025-11-10 00:07:04.978441 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-11-10 00:07:05.421382 | localhost -> localhost | changed 2025-11-10 00:07:05.432681 | 2025-11-10 00:07:05.432774 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-11-10 00:07:06.417683 | localhost -> localhost | changed 2025-11-10 00:07:06.462220 | 2025-11-10 00:07:06.462395 | PLAY [all:!appliance*] 2025-11-10 00:07:06.484874 | 2025-11-10 00:07:06.484965 | TASK [include_role : start-zuul-console] 2025-11-10 00:07:06.506702 | controller | ok 2025-11-10 00:07:06.525411 | 2025-11-10 00:07:06.525499 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-11-10 00:07:06.929542 | controller | ok 2025-11-10 00:07:06.953458 | 2025-11-10 00:07:06.953597 | TASK [use-our-mirror : Retrieve the IP address] 2025-11-10 00:07:07.704051 | controller | ok: Status code was 404 and not [200]: HTTP Error 404: Not Found 2025-11-10 00:07:07.714052 | 2025-11-10 00:07:07.714146 | TASK [use-our-mirror : Ensure the registry service is running on the mirror] 2025-11-10 00:07:08.251064 | controller | skipping: Conditional result was False 2025-11-10 00:07:08.267108 | 2025-11-10 00:07:08.267341 | TASK [use-our-mirror : Write the IP in /etc/hosts] 2025-11-10 00:07:08.304709 | controller | skipping: Conditional result was False 2025-11-10 00:07:08.325143 | 2025-11-10 00:07:08.325336 | TASK [use-our-mirror : Create the podman configuration directory] 2025-11-10 00:07:08.354097 | controller | skipping: Conditional result was False 2025-11-10 00:07:08.369592 | 2025-11-10 00:07:08.369769 | TASK [use-our-mirror : Copy the podman configuration] 2025-11-10 00:07:08.397335 | controller | skipping: Conditional result was False 2025-11-10 00:07:08.407937 | 2025-11-10 00:07:08.408145 | TASK [use-our-mirror : Copy the Yum configuration (main)] 2025-11-10 00:07:08.435558 | controller | skipping: Conditional result was False 2025-11-10 00:07:08.448261 | 2025-11-10 00:07:08.448452 | TASK [use-our-mirror : Copy the Yum configuration (update)] 2025-11-10 00:07:08.475240 | controller | skipping: Conditional result was False 2025-11-10 00:07:08.494069 | 2025-11-10 00:07:08.494210 | TASK [Disable Fedora Modular] 2025-11-10 00:07:08.761749 | controller | changed 2025-11-10 00:07:08.778590 | 2025-11-10 00:07:08.778829 | TASK [Enable EPEL] 2025-11-10 00:07:08.817217 | controller | skipping: Conditional result was False 2025-11-10 00:07:08.825941 | 2025-11-10 00:07:08.826071 | TASK [Register the RHEL node] 2025-11-10 00:07:09.382844 | 2025-11-10 00:07:09.383145 | TASK [Show the subscription-manager status] 2025-11-10 00:07:09.971527 | controller | skipping: Conditional result was False 2025-11-10 00:07:09.985590 | 2025-11-10 00:07:09.985770 | TASK [Enable EPEL on RHEL] 2025-11-10 00:07:10.542848 | controller | skipping: Conditional result was False 2025-11-10 00:07:10.550421 | 2025-11-10 00:07:10.550510 | TASK [Install git and tox] 2025-11-10 00:08:44.687918 | controller | changed 2025-11-10 00:08:44.694395 | 2025-11-10 00:08:44.694458 | TASK [include_role : prepare-workspace] 2025-11-10 00:08:44.724835 | controller | ok 2025-11-10 00:08:44.745939 | 2025-11-10 00:08:44.746056 | TASK [prepare-workspace : Start zuul_console daemon.] 2025-11-10 00:08:44.992229 | controller | ok 2025-11-10 00:08:45.002605 | 2025-11-10 00:08:45.002715 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2025-11-10 00:14:16.728936 | controller | Output suppressed because no_log was given 2025-11-10 00:14:16.741070 | 2025-11-10 00:14:16.741140 | TASK [include_role : prepare-workspace-openshift] 2025-11-10 00:14:16.767814 | controller | skipping: Conditional result was False 2025-11-10 00:14:16.794800 | 2025-11-10 00:14:16.794886 | PLAY [all:!appliance] 2025-11-10 00:14:16.823969 | 2025-11-10 00:14:16.824083 | TASK [Run add-build-sshkey role (RSA)] 2025-11-10 00:14:16.843344 | controller | ok 2025-11-10 00:14:16.860707 | 2025-11-10 00:14:16.860791 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-11-10 00:14:17.066992 | controller -> localhost | ok 2025-11-10 00:14:17.074597 | 2025-11-10 00:14:17.074680 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-11-10 00:14:17.106986 | controller | ok 2025-11-10 00:14:17.123633 | controller | included: /var/lib/zuul/builds/afcc68cfd8574a1db59ba1ad8e8f8123/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-11-10 00:14:17.130436 | 2025-11-10 00:14:17.130502 | TASK [add-build-sshkey : Create Temp SSH key] 2025-11-10 00:14:17.719415 | controller -> localhost | Generating public/private rsa key pair. 2025-11-10 00:14:17.719584 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/afcc68cfd8574a1db59ba1ad8e8f8123/work/afcc68cfd8574a1db59ba1ad8e8f8123_id_rsa. 2025-11-10 00:14:17.719612 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/afcc68cfd8574a1db59ba1ad8e8f8123/work/afcc68cfd8574a1db59ba1ad8e8f8123_id_rsa.pub. 2025-11-10 00:14:17.719632 | controller -> localhost | The key fingerprint is: 2025-11-10 00:14:17.719651 | controller -> localhost | SHA256:iXaGN066am7113DqYBTTTxhmh8P3itRJdf9G7rgx0EQ zuul-build-sshkey 2025-11-10 00:14:17.719670 | controller -> localhost | The key's randomart image is: 2025-11-10 00:14:17.719688 | controller -> localhost | +---[RSA 2048]----+ 2025-11-10 00:14:17.719706 | controller -> localhost | | .+...E .| 2025-11-10 00:14:17.719724 | controller -> localhost | | +++o. ..| 2025-11-10 00:14:17.719742 | controller -> localhost | | o o=.o. o| 2025-11-10 00:14:17.719759 | controller -> localhost | | o +.ooo.o.| 2025-11-10 00:14:17.719777 | controller -> localhost | | + S. .o.. +| 2025-11-10 00:14:17.719801 | controller -> localhost | | ..O .o o. + | 2025-11-10 00:14:17.719823 | controller -> localhost | | ...+ = + .| 2025-11-10 00:14:17.719845 | controller -> localhost | | o o..o . + | 2025-11-10 00:14:17.719865 | controller -> localhost | | +o.. o. . | 2025-11-10 00:14:17.719886 | controller -> localhost | +----[SHA256]-----+ 2025-11-10 00:14:17.719937 | controller -> localhost | ok: Runtime: 0:00:00.104867 2025-11-10 00:14:17.726482 | 2025-11-10 00:14:17.726545 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-11-10 00:14:17.747933 | controller | ok 2025-11-10 00:14:17.758502 | controller | included: /var/lib/zuul/builds/afcc68cfd8574a1db59ba1ad8e8f8123/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-11-10 00:14:17.779409 | 2025-11-10 00:14:17.779507 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-11-10 00:14:17.805493 | controller | skipping: Conditional result was False 2025-11-10 00:14:17.813501 | 2025-11-10 00:14:17.813600 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-11-10 00:14:18.515642 | controller | changed 2025-11-10 00:14:18.545798 | 2025-11-10 00:14:18.545901 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-11-10 00:14:18.801855 | controller | ok 2025-11-10 00:14:18.809008 | 2025-11-10 00:14:18.809129 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-11-10 00:14:19.489116 | controller | changed 2025-11-10 00:14:19.496868 | 2025-11-10 00:14:19.497514 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-11-10 00:14:20.142776 | controller | changed 2025-11-10 00:14:20.148737 | 2025-11-10 00:14:20.148803 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-11-10 00:14:20.173642 | controller | skipping: Conditional result was False 2025-11-10 00:14:20.179939 | 2025-11-10 00:14:20.180010 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-11-10 00:14:20.829289 | controller -> localhost | changed 2025-11-10 00:14:20.848681 | 2025-11-10 00:14:20.848811 | TASK [add-build-sshkey : Add back temp key] 2025-11-10 00:14:21.171645 | controller -> localhost | Identity added: /var/lib/zuul/builds/afcc68cfd8574a1db59ba1ad8e8f8123/work/afcc68cfd8574a1db59ba1ad8e8f8123_id_rsa (zuul-build-sshkey) 2025-11-10 00:14:21.171977 | controller -> localhost | ok: Runtime: 0:00:00.020863 2025-11-10 00:14:21.179551 | 2025-11-10 00:14:21.179647 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-11-10 00:14:21.531438 | controller | ok 2025-11-10 00:14:21.541704 | 2025-11-10 00:14:21.542259 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-11-10 00:14:21.570769 | controller | skipping: Conditional result was False 2025-11-10 00:14:21.593225 | 2025-11-10 00:14:21.593419 | TASK [Run add-build-sshkey role (ECDSA)] 2025-11-10 00:14:21.668684 | controller | ok 2025-11-10 00:14:21.776751 | 2025-11-10 00:14:21.776900 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-11-10 00:14:22.100278 | controller -> localhost | ok 2025-11-10 00:14:22.108612 | 2025-11-10 00:14:22.108742 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-11-10 00:14:22.139837 | controller | ok 2025-11-10 00:14:22.166027 | controller | included: /var/lib/zuul/builds/afcc68cfd8574a1db59ba1ad8e8f8123/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-11-10 00:14:22.177661 | 2025-11-10 00:14:22.177731 | TASK [add-build-sshkey : Create Temp SSH key] 2025-11-10 00:14:22.583673 | controller -> localhost | Generating public/private ecdsa key pair. 2025-11-10 00:14:22.583855 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/afcc68cfd8574a1db59ba1ad8e8f8123/work/afcc68cfd8574a1db59ba1ad8e8f8123_id_ecdsa. 2025-11-10 00:14:22.583883 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/afcc68cfd8574a1db59ba1ad8e8f8123/work/afcc68cfd8574a1db59ba1ad8e8f8123_id_ecdsa.pub. 2025-11-10 00:14:22.583911 | controller -> localhost | The key fingerprint is: 2025-11-10 00:14:22.583931 | controller -> localhost | SHA256:tjsDNye8N0krso2Qg4Xq9el4DdSSUFvuYaaSo6OtAVQ zuul-build-sshkey 2025-11-10 00:14:22.583949 | controller -> localhost | The key's randomart image is: 2025-11-10 00:14:22.583967 | controller -> localhost | +---[ECDSA 521]---+ 2025-11-10 00:14:22.583985 | controller -> localhost | | E.. . | 2025-11-10 00:14:22.584005 | controller -> localhost | | .. + | 2025-11-10 00:14:22.584043 | controller -> localhost | | . ..o= | 2025-11-10 00:14:22.584067 | controller -> localhost | |. ..+=.. | 2025-11-10 00:14:22.584085 | controller -> localhost | |. .+o..oS | 2025-11-10 00:14:22.584102 | controller -> localhost | |...ooo..=.o | 2025-11-10 00:14:22.584130 | controller -> localhost | |oo..+ oo.* o | 2025-11-10 00:14:22.584147 | controller -> localhost | |ooo o+o+=.= | 2025-11-10 00:14:22.584164 | controller -> localhost | |oo..o+ooo= . | 2025-11-10 00:14:22.584181 | controller -> localhost | +----[SHA256]-----+ 2025-11-10 00:14:22.584227 | controller -> localhost | ok: Runtime: 0:00:00.011765 2025-11-10 00:14:22.593827 | 2025-11-10 00:14:22.593898 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-11-10 00:14:22.628998 | controller | ok 2025-11-10 00:14:22.643518 | controller | included: /var/lib/zuul/builds/afcc68cfd8574a1db59ba1ad8e8f8123/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-11-10 00:14:22.653739 | 2025-11-10 00:14:22.653811 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-11-10 00:14:22.677467 | controller | skipping: Conditional result was False 2025-11-10 00:14:22.684546 | 2025-11-10 00:14:22.684617 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-11-10 00:14:22.961915 | controller | changed 2025-11-10 00:14:22.967753 | 2025-11-10 00:14:22.967818 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-11-10 00:14:23.198946 | controller | ok 2025-11-10 00:14:23.215982 | 2025-11-10 00:14:23.216108 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-11-10 00:14:23.856344 | controller | changed 2025-11-10 00:14:23.862183 | 2025-11-10 00:14:23.862248 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-11-10 00:14:24.527292 | controller | changed 2025-11-10 00:14:24.701565 | 2025-11-10 00:14:24.701664 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-11-10 00:14:24.756102 | controller | skipping: Conditional result was False 2025-11-10 00:14:24.763849 | 2025-11-10 00:14:24.763929 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-11-10 00:14:25.133189 | controller -> localhost | changed 2025-11-10 00:14:25.145946 | 2025-11-10 00:14:25.146064 | TASK [add-build-sshkey : Add back temp key] 2025-11-10 00:14:25.820917 | controller -> localhost | Identity added: /var/lib/zuul/builds/afcc68cfd8574a1db59ba1ad8e8f8123/work/afcc68cfd8574a1db59ba1ad8e8f8123_id_ecdsa (zuul-build-sshkey) 2025-11-10 00:14:25.821144 | controller -> localhost | ok: Runtime: 0:00:00.023792 2025-11-10 00:14:25.836109 | 2025-11-10 00:14:25.836191 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-11-10 00:14:26.088722 | controller | ok 2025-11-10 00:14:26.106516 | 2025-11-10 00:14:26.106678 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-11-10 00:14:26.175864 | controller | skipping: Conditional result was False 2025-11-10 00:14:26.191614 | 2025-11-10 00:14:26.191746 | TASK [include_role : remove-zuul-sshkey] 2025-11-10 00:14:26.227236 | controller | skipping: Conditional result was False 2025-11-10 00:14:26.233846 | 2025-11-10 00:14:26.233925 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2025-11-10 00:14:26.501551 | controller | ok: "logs" 2025-11-10 00:14:26.502209 | controller | ok: All items complete 2025-11-10 00:14:26.502243 | 2025-11-10 00:14:26.704044 | controller | ok: "artifacts" 2025-11-10 00:14:26.903972 | controller | ok: "docs" 2025-11-10 00:14:26.920086 | 2025-11-10 00:14:26.920215 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2025-11-10 00:14:27.206538 | controller | changed: "logs" 2025-11-10 00:14:27.411441 | controller | changed: "artifacts" 2025-11-10 00:14:27.619257 | controller | changed: "docs" 2025-11-10 00:14:27.693455 | 2025-11-10 00:14:27.693531 | PLAY RECAP 2025-11-10 00:14:27.693576 | controller | ok: 30 changed: 16 unreachable: 0 failed: 0 skipped: 18 rescued: 0 ignored: 0 2025-11-10 00:14:27.693603 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-11-10 00:14:27.693622 | 2025-11-10 00:14:27.908118 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master] 2025-11-10 00:14:27.909051 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2025-11-10 00:14:28.717648 | 2025-11-10 00:14:28.717765 | PLAY [all] 2025-11-10 00:14:28.767959 | 2025-11-10 00:14:28.768336 | TASK [Install binary dependencies] 2025-11-10 00:14:28.876469 | controller | ok 2025-11-10 00:14:28.931497 | 2025-11-10 00:14:28.931676 | TASK [bindep : Include find tasks] 2025-11-10 00:14:28.983478 | controller | ok 2025-11-10 00:14:28.999741 | controller | included: /var/lib/zuul/builds/afcc68cfd8574a1db59ba1ad8e8f8123/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find.yaml 2025-11-10 00:14:29.011656 | 2025-11-10 00:14:29.011793 | TASK [bindep : Look for bindep.txt] 2025-11-10 00:14:29.576048 | controller | ok 2025-11-10 00:14:29.583488 | 2025-11-10 00:14:29.583560 | TASK [bindep : Define bindep_file fact] 2025-11-10 00:14:29.662451 | controller | skipping: Conditional result was False 2025-11-10 00:14:29.681409 | 2025-11-10 00:14:29.681524 | TASK [bindep : Look for other-requirements.txt] 2025-11-10 00:14:29.916776 | controller | ok 2025-11-10 00:14:29.937101 | 2025-11-10 00:14:29.937225 | TASK [bindep : Define bindep_file fact] 2025-11-10 00:14:30.035333 | controller | skipping: Conditional result was False 2025-11-10 00:14:30.042236 | 2025-11-10 00:14:30.042320 | TASK [bindep : Look for bindep fallback file] 2025-11-10 00:14:30.079276 | controller | skipping: Conditional result was False 2025-11-10 00:14:30.087418 | 2025-11-10 00:14:30.087499 | TASK [bindep : Define bindep_file fact] 2025-11-10 00:14:30.125293 | controller | skipping: Conditional result was False 2025-11-10 00:14:30.133246 | 2025-11-10 00:14:30.133327 | TASK [bindep : Include bindep tasks] 2025-11-10 00:14:30.159215 | controller | skipping: Conditional result was False 2025-11-10 00:14:30.166001 | 2025-11-10 00:14:30.166100 | TASK [bindep : Include install tasks] 2025-11-10 00:14:30.189837 | controller | skipping: Conditional result was False 2025-11-10 00:14:30.196395 | 2025-11-10 00:14:30.196466 | LOOP [bindep : Include package tasks] 2025-11-10 00:14:30.263000 | 2025-11-10 00:14:30.263218 | TASK [Run test-setup role] 2025-11-10 00:14:30.293033 | controller | ok 2025-11-10 00:14:30.320227 | 2025-11-10 00:14:30.320334 | TASK [test-setup : Check if project's tools/test-setup.sh exists] 2025-11-10 00:14:30.522352 | controller | ok 2025-11-10 00:14:30.537373 | 2025-11-10 00:14:30.537502 | TASK [test-setup : Run tools/test-setup.sh] 2025-11-10 00:14:31.071132 | controller | skipping: Conditional result was False 2025-11-10 00:14:31.107593 | 2025-11-10 00:14:31.107676 | PLAY RECAP 2025-11-10 00:14:31.107716 | controller | ok: 4 changed: 0 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2025-11-10 00:14:31.107738 | 2025-11-10 00:14:31.327875 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2025-11-10 00:14:31.328714 | PRE-RUN START: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master] 2025-11-10 00:14:32.248165 | 2025-11-10 00:14:32.248304 | PLAY [controller] 2025-11-10 00:14:32.276517 | 2025-11-10 00:14:32.276639 | TASK [Create the /root directory] 2025-11-10 00:14:32.835595 | controller | ok 2025-11-10 00:14:32.841461 | 2025-11-10 00:14:32.841547 | TASK [Install glibc-langpack-en] 2025-11-10 00:14:36.699660 | controller | ok: Nothing to do 2025-11-10 00:14:36.705670 | 2025-11-10 00:14:36.705742 | TASK [Ensure controller directory exists] 2025-11-10 00:14:36.924950 | controller | changed 2025-11-10 00:14:36.939064 | 2025-11-10 00:14:36.939240 | TASK [Install container runtime] 2025-11-10 00:14:37.018512 | controller | ok 2025-11-10 00:14:37.083173 | 2025-11-10 00:14:37.083325 | LOOP [ensure-podman : Find distribution installation] 2025-11-10 00:14:37.131176 | controller | ok: "/var/lib/zuul/builds/afcc68cfd8574a1db59ba1ad8e8f8123/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-podman/tasks/RedHat.yaml" 2025-11-10 00:14:37.148965 | controller | included: /var/lib/zuul/builds/afcc68cfd8574a1db59ba1ad8e8f8123/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-podman/tasks/RedHat.yaml 2025-11-10 00:14:37.155834 | 2025-11-10 00:14:37.155903 | TASK [ensure-podman : Install podman (RedHat)] 2025-11-10 00:15:42.241425 | controller | changed 2025-11-10 00:15:42.257505 | 2025-11-10 00:15:42.257661 | TASK [ensure-podman : Fetch podman version] 2025-11-10 00:15:42.809096 | controller | Client: Podman Engine 2025-11-10 00:15:42.844298 | controller | Version: 4.6.2 2025-11-10 00:15:42.844331 | controller | API Version: 4.6.2 2025-11-10 00:15:42.844340 | controller | Go Version: go1.19.12 2025-11-10 00:15:42.844357 | controller | Built: Mon Aug 28 19:38:31 2023 2025-11-10 00:15:42.844365 | controller | OS/Arch: linux/amd64 2025-11-10 00:15:43.309703 | controller | ok: Runtime: 0:00:00.209925 2025-11-10 00:15:43.320117 | 2025-11-10 00:15:43.320230 | TASK [ensure-podman : Print podman version installed] 2025-11-10 00:15:43.355372 | Podman version: Client: Podman Engine 2025-11-10 00:15:43.355546 | Version: 4.6.2 2025-11-10 00:15:43.355589 | API Version: 4.6.2 2025-11-10 00:15:43.355622 | Go Version: go1.19.12 2025-11-10 00:15:43.355653 | Built: Mon Aug 28 19:38:31 2023 2025-11-10 00:15:43.355684 | OS/Arch: linux/amd64 2025-11-10 00:15:43.364927 | 2025-11-10 00:15:43.365057 | TASK [ensure-podman : Validate podman engine] 2025-11-10 00:15:43.913490 | controller | skipping: Conditional result was False 2025-11-10 00:15:43.920941 | 2025-11-10 00:15:43.921065 | TASK [ensure-podman : Set up docker compatability socket] 2025-11-10 00:15:43.934997 | controller | skipping: Conditional result was False 2025-11-10 00:15:43.946887 | 2025-11-10 00:15:43.946999 | TASK [Ensure python3.8 is present] 2025-11-10 00:15:43.961391 | controller | skipping: Conditional result was False 2025-11-10 00:15:43.967667 | 2025-11-10 00:15:43.967758 | TASK [Run our-ensure-python role (Fedora only for now)] 2025-11-10 00:15:43.986854 | controller | ok 2025-11-10 00:15:44.007646 | 2025-11-10 00:15:44.007760 | TASK [our-ensure-python : Install the right Python version (rpm)] 2025-11-10 00:15:45.349084 | controller | ok: Nothing to do 2025-11-10 00:15:45.355256 | 2025-11-10 00:15:45.355340 | TASK [our-ensure-python : Also install python3-devel] 2025-11-10 00:15:54.912844 | controller | changed 2025-11-10 00:15:54.923450 | 2025-11-10 00:15:54.923529 | TASK [Run ensure-virtualenv role] 2025-11-10 00:15:54.953650 | controller | ok 2025-11-10 00:15:54.973395 | 2025-11-10 00:15:54.973507 | TASK [ensure-virtualenv : Check if virtualenv is installed] 2025-11-10 00:15:55.217903 | controller | /usr/bin/virtualenv 2025-11-10 00:15:55.531558 | controller | ok: Runtime: 0:00:00.005298 2025-11-10 00:15:55.539886 | 2025-11-10 00:15:55.539976 | LOOP [ensure-virtualenv : Install virtualenv package] 2025-11-10 00:15:55.577258 | controller | skipping: Conditional result was False 2025-11-10 00:15:55.577758 | controller | ok: All items complete 2025-11-10 00:15:55.577818 | 2025-11-10 00:15:55.601122 | 2025-11-10 00:15:55.601284 | TASK [Find the full path of the Python interpreter] 2025-11-10 00:15:55.834538 | controller | /usr/bin/python3 2025-11-10 00:15:56.142145 | controller | ok 2025-11-10 00:15:56.154545 | 2025-11-10 00:15:56.154883 | TASK [Create virtualenv for ansible-test with a specific interpreter] 2025-11-10 00:15:56.973757 | controller | created virtual environment CPython3.11.0.final.0-64 in 471ms 2025-11-10 00:15:56.990405 | controller | creator CPython3Posix(dest=/home/zuul/venv, clear=False, no_vcs_ignore=False, global=False) 2025-11-10 00:15:56.990442 | controller | seeder FromAppData(extra_search_dir=/usr/share/python-wheels,download=False, pip=bundle, setuptools=bundle, wheel=bundle, via=copy, app_data_dir=/home/zuul/.local/share/virtualenv) 2025-11-10 00:15:56.990452 | controller | added seed packages: pip==22.2.2, setuptools==62.6.0, wheel==0.37.1 2025-11-10 00:15:56.990467 | controller | activators BashActivator,CShellActivator,FishActivator,NushellActivator,PowerShellActivator,PythonActivator 2025-11-10 00:15:57.215453 | controller | changed 2025-11-10 00:15:57.229218 | 2025-11-10 00:15:57.229339 | TASK [Set selinux package] 2025-11-10 00:15:57.264434 | controller | ok 2025-11-10 00:15:57.271589 | 2025-11-10 00:15:57.271683 | TASK [Set selinux package (Fedora)] 2025-11-10 00:15:57.329121 | controller | ok 2025-11-10 00:15:57.340886 | 2025-11-10 00:15:57.341070 | TASK [Install selinux into virtualenv] 2025-11-10 00:15:58.630365 | controller | Collecting selinux-please-lie-to-me 2025-11-10 00:15:58.668727 | controller | Downloading selinux_please_lie_to_me-1.0.1-py2.py3-none-any.whl (3.8 kB) 2025-11-10 00:15:58.970675 | controller | Collecting setuptools<50.0.0 2025-11-10 00:15:58.977306 | controller | Downloading setuptools-49.6.0-py3-none-any.whl (803 kB) 2025-11-10 00:15:59.027523 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 803.3/803.3 kB 17.1 MB/s eta 0:00:00 2025-11-10 00:15:59.106861 | controller | Installing collected packages: setuptools, selinux-please-lie-to-me 2025-11-10 00:15:59.107077 | controller | Attempting uninstall: setuptools 2025-11-10 00:15:59.109436 | controller | Found existing installation: setuptools 62.6.0 2025-11-10 00:15:59.170451 | controller | Uninstalling setuptools-62.6.0: 2025-11-10 00:15:59.178112 | controller | Successfully uninstalled setuptools-62.6.0 2025-11-10 00:15:59.554004 | controller | Successfully installed selinux-please-lie-to-me-1.0.1 setuptools-49.6.0 2025-11-10 00:15:59.673431 | controller | 2025-11-10 00:15:59.750837 | controller | [notice] A new release of pip available: 22.2.2 -> 25.3 2025-11-10 00:15:59.750880 | controller | [notice] To update, run: python -m pip install --upgrade pip 2025-11-10 00:15:59.916910 | controller | ok: Runtime: 0:00:02.151659 2025-11-10 00:15:59.926051 | 2025-11-10 00:15:59.926174 | TASK [Install pytest-forked into virtualenv] 2025-11-10 00:16:00.520787 | controller | Collecting pytest-forked 2025-11-10 00:16:00.557679 | controller | Downloading pytest_forked-1.6.0-py3-none-any.whl (4.9 kB) 2025-11-10 00:16:00.595979 | controller | Collecting py 2025-11-10 00:16:00.601022 | controller | Downloading py-1.11.0-py2.py3-none-any.whl (98 kB) 2025-11-10 00:16:00.620971 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 98.7/98.7 kB 5.7 MB/s eta 0:00:00 2025-11-10 00:16:00.726380 | controller | Collecting pytest>=3.10 2025-11-10 00:16:00.729938 | controller | Downloading pytest-9.0.0-py3-none-any.whl (373 kB) 2025-11-10 00:16:00.745247 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 373.4/373.4 kB 29.2 MB/s eta 0:00:00 2025-11-10 00:16:00.786104 | controller | Collecting iniconfig>=1.0.1 2025-11-10 00:16:00.789865 | controller | Downloading iniconfig-2.3.0-py3-none-any.whl (7.5 kB) 2025-11-10 00:16:00.831703 | controller | Collecting packaging>=22 2025-11-10 00:16:00.836787 | controller | Downloading packaging-25.0-py3-none-any.whl (66 kB) 2025-11-10 00:16:00.844231 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 66.5/66.5 kB 11.1 MB/s eta 0:00:00 2025-11-10 00:16:00.875729 | controller | Collecting pluggy<2,>=1.5 2025-11-10 00:16:00.879802 | controller | Downloading pluggy-1.6.0-py3-none-any.whl (20 kB) 2025-11-10 00:16:00.929373 | controller | Collecting pygments>=2.7.2 2025-11-10 00:16:00.938176 | controller | Downloading pygments-2.19.2-py3-none-any.whl (1.2 MB) 2025-11-10 00:16:00.961390 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 1.2/1.2 MB 60.2 MB/s eta 0:00:00 2025-11-10 00:20:38.573245 | [Zuul] Log Stream did not terminate 2025-11-10 00:20:38.573590 | controller | ERROR 2025-11-10 00:20:38.573743 | controller | { 2025-11-10 00:20:38.573798 | controller | "msg": "Data could not be sent to remote host \"162.253.55.84\". Make sure this host can be reached over ssh: ssh: connect to host 162.253.55.84 port 22: No route to host\r\n", 2025-11-10 00:20:38.573840 | controller | "unreachable": true 2025-11-10 00:20:38.573882 | controller | } unreachable 2025-11-10 00:20:38.576158 | 2025-11-10 00:20:38.576265 | PLAY RECAP 2025-11-10 00:20:38.576347 | controller | ok: 15 changed: 7 unreachable: 1 failed: 0 skipped: 4 rescued: 0 ignored: 0 2025-11-10 00:20:38.576391 | 2025-11-10 00:20:38.716309 | PRE-RUN END RESULT_UNREACHABLE: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master] 2025-11-10 00:20:38.717336 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2025-11-10 00:20:39.317074 | 2025-11-10 00:20:39.317182 | PLAY [all] 2025-11-10 00:20:39.338475 | 2025-11-10 00:20:39.338606 | TASK [fetch-subunit-output : Find stestr or testr executable] 2025-11-10 00:20:51.009198 | controller | ERROR 2025-11-10 00:20:51.009588 | controller | { 2025-11-10 00:20:51.009671 | controller | "msg": "Failed to connect to the host via ssh: ssh: connect to host 162.253.55.84 port 22: No route to host", 2025-11-10 00:20:51.009739 | controller | "unreachable": true 2025-11-10 00:20:51.009840 | controller | } unreachable 2025-11-10 00:20:51.011986 | 2025-11-10 00:20:51.012118 | PLAY RECAP 2025-11-10 00:20:51.012200 | controller | ok: 0 changed: 0 unreachable: 1 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-11-10 00:20:51.012246 | 2025-11-10 00:20:51.172084 | POST-RUN END RESULT_UNREACHABLE: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2025-11-10 00:20:51.173773 | POST-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/post.yaml@master] 2025-11-10 00:20:51.789831 | 2025-11-10 00:20:51.789965 | PLAY [all:!appliance*] 2025-11-10 00:20:51.811872 | 2025-11-10 00:20:51.811984 | TASK [unregister the node] 2025-11-10 00:20:54.057889 | [controller] Waiting on logger 2025-11-10 00:21:01.710275 | controller | skipping: Conditional result was False 2025-11-10 00:21:01.718351 | 2025-11-10 00:21:01.718455 | TASK [include_role : fetch-output] 2025-11-10 00:21:01.761675 | controller | ok 2025-11-10 00:21:01.799489 | 2025-11-10 00:21:01.799649 | TASK [fetch-output : Set log path for multiple nodes] 2025-11-10 00:21:01.875769 | controller | skipping: Conditional result was False 2025-11-10 00:21:01.886890 | 2025-11-10 00:21:01.887054 | TASK [fetch-output : Set log path for single node] 2025-11-10 00:21:01.921266 | controller | ok 2025-11-10 00:21:01.928412 | 2025-11-10 00:21:01.928492 | LOOP [fetch-output : Ensure local output dirs] 2025-11-10 00:21:02.348268 | controller -> localhost | ok: "/var/lib/zuul/builds/afcc68cfd8574a1db59ba1ad8e8f8123/work/logs" 2025-11-10 00:21:02.639584 | controller -> localhost | changed: "/var/lib/zuul/builds/afcc68cfd8574a1db59ba1ad8e8f8123/work/artifacts" 2025-11-10 00:21:02.885263 | controller -> localhost | changed: "/var/lib/zuul/builds/afcc68cfd8574a1db59ba1ad8e8f8123/work/docs" 2025-11-10 00:21:02.900934 | 2025-11-10 00:21:02.901085 | LOOP [fetch-output : Collect logs, artifacts and docs] 2025-11-10 00:21:03.543430 | controller | changed: 2025-11-10 00:21:03.543622 | controller | .d..t...... ./ 2025-11-10 00:21:03.543650 | controller | cd+++++++++ controller/ 2025-11-10 00:21:03.543681 | controller | changed: All items complete 2025-11-10 00:21:03.543701 | 2025-11-10 00:21:04.031584 | controller | changed: .d..t...... ./ 2025-11-10 00:21:04.507571 | controller | changed: .d..t...... ./ 2025-11-10 00:21:04.525044 | 2025-11-10 00:21:04.525198 | TASK [include_role : fetch-output-openshift] 2025-11-10 00:21:04.550431 | controller | skipping: Conditional result was False 2025-11-10 00:21:04.560388 | 2025-11-10 00:21:04.560508 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2025-11-10 00:21:04.603623 | controller | skipping: Conditional result was False 2025-11-10 00:21:04.614165 | controller | skipping: Conditional result was False 2025-11-10 00:21:04.657552 | 2025-11-10 00:21:04.657677 | PLAY [localhost] 2025-11-10 00:21:04.675585 | 2025-11-10 00:21:04.675671 | TASK [Run Zuul manifest role] 2025-11-10 00:21:04.697737 | localhost | ok 2025-11-10 00:21:04.717328 | 2025-11-10 00:21:04.717449 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-11-10 00:21:05.142277 | localhost | changed 2025-11-10 00:21:05.147366 | 2025-11-10 00:21:05.147451 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-11-10 00:21:05.181363 | localhost | ok 2025-11-10 00:21:05.189557 | 2025-11-10 00:21:05.189636 | TASK [Set zuul-log-path fact] 2025-11-10 00:21:05.207321 | localhost | ok 2025-11-10 00:21:05.220848 | 2025-11-10 00:21:05.220944 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-11-10 00:21:05.266560 | localhost | ok 2025-11-10 00:21:05.275754 | 2025-11-10 00:21:05.275823 | LOOP [Run upload-logs-swift role] 2025-11-10 00:21:05.310941 | localhost | Output suppressed because no_log was given 2025-11-10 00:21:05.343516 | 2025-11-10 00:21:05.343621 | TASK [Set zuul-log-path fact] 2025-11-10 00:21:05.368158 | localhost | skipping: Conditional result was False 2025-11-10 00:21:05.374476 | 2025-11-10 00:21:05.374581 | TASK [upload-logs-swift : Ensure logs are readable before uploading] 2025-11-10 00:21:05.828792 | localhost -> localhost | ok: Runtime: 0:00:00.006099 2025-11-10 00:21:05.835414 | 2025-11-10 00:21:05.835519 | TASK [upload-logs-swift : Upload logs to swift]