2026-02-27 20:54:59.827136 | Job console starting... 2026-02-27 20:54:59.837686 | Updating repositories 2026-02-27 20:54:59.985237 | Preparing job workspace 2026-02-27 20:55:04.696204 | Running Ansible setup... 2026-02-27 20:55:09.740143 | PRE-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master] 2026-02-27 20:55:10.397283 | 2026-02-27 20:55:10.397409 | PLAY [localhost] 2026-02-27 20:55:10.406215 | 2026-02-27 20:55:10.406321 | TASK [Gathering Facts] 2026-02-27 20:55:11.406363 | localhost | ok 2026-02-27 20:55:11.421643 | 2026-02-27 20:55:11.421825 | TASK [Setup log path fact] 2026-02-27 20:55:11.446328 | localhost | ok 2026-02-27 20:55:11.476470 | 2026-02-27 20:55:11.476661 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-02-27 20:55:11.516263 | localhost | ok 2026-02-27 20:55:11.524512 | 2026-02-27 20:55:11.524598 | TASK [emit-job-header : Print job information] 2026-02-27 20:55:11.605997 | # Job Information 2026-02-27 20:55:11.606176 | Ansible Version: 2.15.12 2026-02-27 20:55:11.606208 | Job: ansible-test-sanity-docker-stable-2.16 2026-02-27 20:55:11.606231 | Pipeline: third-party-check 2026-02-27 20:55:11.606253 | Executor: ze03.softwarefactory-project.io 2026-02-27 20:55:11.606271 | Triggered by: https://github.com/openshift/community.okd/pull/273 2026-02-27 20:55:11.606292 | Log URL (when completed): https://ansible.softwarefactory-project.io/logs/f6f/ansible/f6f3dd578a794f7abf2503654e4e4e59/ 2026-02-27 20:55:11.606311 | Event ID: 9f760df0-141c-11f1-9656-853b724fa688 2026-02-27 20:55:11.610492 | 2026-02-27 20:55:11.610562 | LOOP [emit-job-header : Print node information] 2026-02-27 20:55:11.754314 | localhost | ok: 2026-02-27 20:55:11.754495 | localhost | # Node Information 2026-02-27 20:55:11.754521 | localhost | Inventory Hostname: controller 2026-02-27 20:55:11.754541 | localhost | Hostname: np0005633555 2026-02-27 20:55:11.754559 | localhost | Username: zuul 2026-02-27 20:55:11.754582 | localhost | Distro: Fedora 37 2026-02-27 20:55:11.754601 | localhost | Provider: ansible-vexxhost-ca-ymq-1 2026-02-27 20:55:11.754619 | localhost | Region: ca-ymq-1 2026-02-27 20:55:11.754635 | localhost | Label: ansible-fedora-37-1vcpu 2026-02-27 20:55:11.754652 | localhost | Product Name: OpenStack Nova 2026-02-27 20:55:11.754669 | localhost | Interface IP: 162.253.55.62 2026-02-27 20:55:11.767364 | 2026-02-27 20:55:11.767520 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-02-27 20:55:12.379023 | localhost -> localhost | changed 2026-02-27 20:55:12.385204 | 2026-02-27 20:55:12.385274 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-02-27 20:55:13.401686 | localhost -> localhost | changed 2026-02-27 20:55:13.432369 | 2026-02-27 20:55:13.432455 | PLAY [all:!appliance*] 2026-02-27 20:55:13.462624 | 2026-02-27 20:55:13.462719 | TASK [include_role : start-zuul-console] 2026-02-27 20:55:13.507860 | controller | ok 2026-02-27 20:55:13.543679 | 2026-02-27 20:55:13.543766 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-02-27 20:55:14.030986 | controller | ok 2026-02-27 20:55:14.043601 | 2026-02-27 20:55:14.043685 | TASK [use-our-mirror : Retrieve the IP address] 2026-02-27 20:55:15.247677 | controller | ok: Status code was 404 and not [200]: HTTP Error 404: Not Found 2026-02-27 20:55:15.262125 | 2026-02-27 20:55:15.262204 | TASK [use-our-mirror : Ensure the registry service is running on the mirror] 2026-02-27 20:55:15.813986 | controller | skipping: Conditional result was False 2026-02-27 20:55:15.820748 | 2026-02-27 20:55:15.820848 | TASK [use-our-mirror : Write the IP in /etc/hosts] 2026-02-27 20:55:15.845171 | controller | skipping: Conditional result was False 2026-02-27 20:55:15.851465 | 2026-02-27 20:55:15.851527 | TASK [use-our-mirror : Create the podman configuration directory] 2026-02-27 20:55:15.886714 | controller | skipping: Conditional result was False 2026-02-27 20:55:15.895370 | 2026-02-27 20:55:15.895520 | TASK [use-our-mirror : Copy the podman configuration] 2026-02-27 20:55:15.922405 | controller | skipping: Conditional result was False 2026-02-27 20:55:15.931686 | 2026-02-27 20:55:15.931856 | TASK [use-our-mirror : Copy the Yum configuration (main)] 2026-02-27 20:55:15.968631 | controller | skipping: Conditional result was False 2026-02-27 20:55:15.977696 | 2026-02-27 20:55:15.977792 | TASK [use-our-mirror : Copy the Yum configuration (update)] 2026-02-27 20:55:16.002950 | controller | skipping: Conditional result was False 2026-02-27 20:55:16.014824 | 2026-02-27 20:55:16.014909 | TASK [Disable Fedora Modular] 2026-02-27 20:55:16.270235 | controller | changed 2026-02-27 20:55:16.276352 | 2026-02-27 20:55:16.276474 | TASK [Enable EPEL] 2026-02-27 20:55:16.311598 | controller | skipping: Conditional result was False 2026-02-27 20:55:16.320186 | 2026-02-27 20:55:16.320310 | TASK [Register the RHEL node] 2026-02-27 20:55:16.867493 | 2026-02-27 20:55:16.867750 | TASK [Show the subscription-manager status] 2026-02-27 20:55:17.428369 | controller | skipping: Conditional result was False 2026-02-27 20:55:17.441920 | 2026-02-27 20:55:17.442051 | TASK [Enable EPEL on RHEL] 2026-02-27 20:55:17.994361 | controller | skipping: Conditional result was False 2026-02-27 20:55:18.007171 | 2026-02-27 20:55:18.007305 | TASK [Install git and tox] 2026-02-27 20:56:50.177136 | controller | changed 2026-02-27 20:56:50.184844 | 2026-02-27 20:56:50.184922 | TASK [include_role : prepare-workspace] 2026-02-27 20:56:50.215995 | controller | ok 2026-02-27 20:56:50.248993 | 2026-02-27 20:56:50.249245 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-02-27 20:56:50.491440 | controller | ok 2026-02-27 20:56:50.504980 | 2026-02-27 20:56:50.505112 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-02-27 21:02:22.428040 | controller | Output suppressed because no_log was given 2026-02-27 21:02:22.438069 | 2026-02-27 21:02:22.438168 | TASK [include_role : prepare-workspace-openshift] 2026-02-27 21:02:22.453697 | controller | skipping: Conditional result was False 2026-02-27 21:02:22.475238 | 2026-02-27 21:02:22.475300 | PLAY [all:!appliance] 2026-02-27 21:02:22.490519 | 2026-02-27 21:02:22.490585 | TASK [Run add-build-sshkey role (RSA)] 2026-02-27 21:02:22.520584 | controller | ok 2026-02-27 21:02:22.535148 | 2026-02-27 21:02:22.535236 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-02-27 21:02:22.772846 | controller -> localhost | ok 2026-02-27 21:02:22.779847 | 2026-02-27 21:02:22.779915 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-02-27 21:02:22.810337 | controller | ok 2026-02-27 21:02:22.825868 | controller | included: /var/lib/zuul/builds/f6f3dd578a794f7abf2503654e4e4e59/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-02-27 21:02:22.832104 | 2026-02-27 21:02:22.832169 | TASK [add-build-sshkey : Create Temp SSH key] 2026-02-27 21:02:23.337381 | controller -> localhost | Generating public/private rsa key pair. 2026-02-27 21:02:23.337619 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/f6f3dd578a794f7abf2503654e4e4e59/work/f6f3dd578a794f7abf2503654e4e4e59_id_rsa. 2026-02-27 21:02:23.337649 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/f6f3dd578a794f7abf2503654e4e4e59/work/f6f3dd578a794f7abf2503654e4e4e59_id_rsa.pub. 2026-02-27 21:02:23.337671 | controller -> localhost | The key fingerprint is: 2026-02-27 21:02:23.337691 | controller -> localhost | SHA256:lNz4zj6THUErpbTeuxPMp/SRx3mk5zihteCy7Xerxls zuul-build-sshkey 2026-02-27 21:02:23.337709 | controller -> localhost | The key's randomart image is: 2026-02-27 21:02:23.337747 | controller -> localhost | +---[RSA 2048]----+ 2026-02-27 21:02:23.337770 | controller -> localhost | | | 2026-02-27 21:02:23.337791 | controller -> localhost | | . +. o | 2026-02-27 21:02:23.337809 | controller -> localhost | | =..= . | 2026-02-27 21:02:23.337827 | controller -> localhost | | . .+ o .| 2026-02-27 21:02:23.337844 | controller -> localhost | | S..= . =.| 2026-02-27 21:02:23.337877 | controller -> localhost | | o. O B.=| 2026-02-27 21:02:23.337917 | controller -> localhost | | o=.@ E.| 2026-02-27 21:02:23.337947 | controller -> localhost | | .=.BoB o| 2026-02-27 21:02:23.337968 | controller -> localhost | | o*=*o+.| 2026-02-27 21:02:23.337989 | controller -> localhost | +----[SHA256]-----+ 2026-02-27 21:02:23.338041 | controller -> localhost | ok: Runtime: 0:00:00.106534 2026-02-27 21:02:23.347623 | 2026-02-27 21:02:23.347709 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-02-27 21:02:23.371717 | controller | ok 2026-02-27 21:02:23.384111 | controller | included: /var/lib/zuul/builds/f6f3dd578a794f7abf2503654e4e4e59/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-02-27 21:02:23.394040 | 2026-02-27 21:02:23.394129 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-02-27 21:02:23.418243 | controller | skipping: Conditional result was False 2026-02-27 21:02:23.425563 | 2026-02-27 21:02:23.425625 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-02-27 21:02:24.071967 | controller | changed 2026-02-27 21:02:24.085825 | 2026-02-27 21:02:24.085999 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-02-27 21:02:24.332523 | controller | ok 2026-02-27 21:02:24.346006 | 2026-02-27 21:02:24.346141 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-02-27 21:02:24.999521 | controller | changed 2026-02-27 21:02:25.012416 | 2026-02-27 21:02:25.012551 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-02-27 21:02:25.673663 | controller | changed 2026-02-27 21:02:25.687211 | 2026-02-27 21:02:25.687372 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-02-27 21:02:25.714919 | controller | skipping: Conditional result was False 2026-02-27 21:02:25.730961 | 2026-02-27 21:02:25.731097 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-02-27 21:02:26.189052 | controller -> localhost | changed 2026-02-27 21:02:26.218899 | 2026-02-27 21:02:26.219110 | TASK [add-build-sshkey : Add back temp key] 2026-02-27 21:02:26.521287 | controller -> localhost | Identity added: /var/lib/zuul/builds/f6f3dd578a794f7abf2503654e4e4e59/work/f6f3dd578a794f7abf2503654e4e4e59_id_rsa (zuul-build-sshkey) 2026-02-27 21:02:26.521581 | controller -> localhost | ok: Runtime: 0:00:00.015570 2026-02-27 21:02:26.529062 | 2026-02-27 21:02:26.529129 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-02-27 21:02:26.925503 | controller | ok 2026-02-27 21:02:26.938394 | 2026-02-27 21:02:26.938536 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-02-27 21:02:26.990228 | controller | skipping: Conditional result was False 2026-02-27 21:02:27.017086 | 2026-02-27 21:02:27.017260 | TASK [Run add-build-sshkey role (ECDSA)] 2026-02-27 21:02:27.058392 | controller | ok 2026-02-27 21:02:27.085172 | 2026-02-27 21:02:27.085264 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-02-27 21:02:27.357268 | controller -> localhost | ok 2026-02-27 21:02:27.363923 | 2026-02-27 21:02:27.363992 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-02-27 21:02:27.404742 | controller | ok 2026-02-27 21:02:27.415432 | controller | included: /var/lib/zuul/builds/f6f3dd578a794f7abf2503654e4e4e59/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-02-27 21:02:27.421439 | 2026-02-27 21:02:27.421498 | TASK [add-build-sshkey : Create Temp SSH key] 2026-02-27 21:02:27.773431 | controller -> localhost | Generating public/private ecdsa key pair. 2026-02-27 21:02:27.773685 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/f6f3dd578a794f7abf2503654e4e4e59/work/f6f3dd578a794f7abf2503654e4e4e59_id_ecdsa. 2026-02-27 21:02:27.773714 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/f6f3dd578a794f7abf2503654e4e4e59/work/f6f3dd578a794f7abf2503654e4e4e59_id_ecdsa.pub. 2026-02-27 21:02:27.773774 | controller -> localhost | The key fingerprint is: 2026-02-27 21:02:27.773797 | controller -> localhost | SHA256:4CDoDoTu5UsdJvsqeQyRgRXICu8J6kJCOAFkXIn9T68 zuul-build-sshkey 2026-02-27 21:02:27.773831 | controller -> localhost | The key's randomart image is: 2026-02-27 21:02:27.773852 | controller -> localhost | +---[ECDSA 521]---+ 2026-02-27 21:02:27.773870 | controller -> localhost | |B=*o. | 2026-02-27 21:02:27.773887 | controller -> localhost | |B* o | 2026-02-27 21:02:27.773904 | controller -> localhost | |*++ o . | 2026-02-27 21:02:27.773922 | controller -> localhost | |O+.. + o | 2026-02-27 21:02:27.773938 | controller -> localhost | |+*.+ o+ S | 2026-02-27 21:02:27.773955 | controller -> localhost | |*o= = .. . | 2026-02-27 21:02:27.773972 | controller -> localhost | |+o++ . . | 2026-02-27 21:02:27.773988 | controller -> localhost | |.+.oo E | 2026-02-27 21:02:27.774005 | controller -> localhost | |. oo.. | 2026-02-27 21:02:27.774022 | controller -> localhost | +----[SHA256]-----+ 2026-02-27 21:02:27.774090 | controller -> localhost | ok: Runtime: 0:00:00.016771 2026-02-27 21:02:27.781517 | 2026-02-27 21:02:27.781581 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-02-27 21:02:27.837086 | controller | ok 2026-02-27 21:02:27.844984 | controller | included: /var/lib/zuul/builds/f6f3dd578a794f7abf2503654e4e4e59/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-02-27 21:02:27.853745 | 2026-02-27 21:02:27.853815 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-02-27 21:02:27.879051 | controller | skipping: Conditional result was False 2026-02-27 21:02:27.886338 | 2026-02-27 21:02:27.886407 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-02-27 21:02:28.154451 | controller | changed 2026-02-27 21:02:28.161704 | 2026-02-27 21:02:28.161807 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-02-27 21:02:28.384845 | controller | ok 2026-02-27 21:02:28.390493 | 2026-02-27 21:02:28.390556 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-02-27 21:02:29.005863 | controller | changed 2026-02-27 21:02:29.021596 | 2026-02-27 21:02:29.021784 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-02-27 21:02:29.700103 | controller | changed 2026-02-27 21:02:29.705849 | 2026-02-27 21:02:29.705915 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-02-27 21:02:29.745918 | controller | skipping: Conditional result was False 2026-02-27 21:02:29.756426 | 2026-02-27 21:02:29.756536 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-02-27 21:02:30.046865 | controller -> localhost | changed 2026-02-27 21:02:30.064802 | 2026-02-27 21:02:30.065012 | TASK [add-build-sshkey : Add back temp key] 2026-02-27 21:02:30.358271 | controller -> localhost | Identity added: /var/lib/zuul/builds/f6f3dd578a794f7abf2503654e4e4e59/work/f6f3dd578a794f7abf2503654e4e4e59_id_ecdsa (zuul-build-sshkey) 2026-02-27 21:02:30.358458 | controller -> localhost | ok: Runtime: 0:00:00.011721 2026-02-27 21:02:30.366244 | 2026-02-27 21:02:30.366308 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-02-27 21:02:30.578842 | controller | ok 2026-02-27 21:02:30.584386 | 2026-02-27 21:02:30.584448 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-02-27 21:02:30.609240 | controller | skipping: Conditional result was False 2026-02-27 21:02:30.625152 | 2026-02-27 21:02:30.625236 | TASK [include_role : remove-zuul-sshkey] 2026-02-27 21:02:30.638651 | controller | skipping: Conditional result was False 2026-02-27 21:02:30.646114 | 2026-02-27 21:02:30.646197 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-02-27 21:02:30.893058 | controller | ok: "logs" 2026-02-27 21:02:30.893355 | controller | ok: All items complete 2026-02-27 21:02:30.893383 | 2026-02-27 21:02:31.106802 | controller | ok: "artifacts" 2026-02-27 21:02:31.307328 | controller | ok: "docs" 2026-02-27 21:02:31.331539 | 2026-02-27 21:02:31.331793 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-02-27 21:02:31.602883 | controller | changed: "logs" 2026-02-27 21:02:31.801918 | controller | changed: "artifacts" 2026-02-27 21:02:32.001046 | controller | changed: "docs" 2026-02-27 21:02:32.037220 | 2026-02-27 21:02:32.037319 | PLAY RECAP 2026-02-27 21:02:32.037372 | controller | ok: 30 changed: 16 unreachable: 0 failed: 0 skipped: 18 rescued: 0 ignored: 0 2026-02-27 21:02:32.037401 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-02-27 21:02:32.037420 | 2026-02-27 21:02:32.152621 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master] 2026-02-27 21:02:32.153580 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2026-02-27 21:02:32.738245 | 2026-02-27 21:02:32.738352 | PLAY [all] 2026-02-27 21:02:32.760920 | 2026-02-27 21:02:32.761022 | TASK [Install binary dependencies] 2026-02-27 21:02:32.832169 | controller | ok 2026-02-27 21:02:32.853653 | 2026-02-27 21:02:32.853760 | TASK [bindep : Include find tasks] 2026-02-27 21:02:32.895060 | controller | ok 2026-02-27 21:02:32.903467 | controller | included: /var/lib/zuul/builds/f6f3dd578a794f7abf2503654e4e4e59/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find.yaml 2026-02-27 21:02:32.909789 | 2026-02-27 21:02:32.909856 | TASK [bindep : Look for bindep.txt] 2026-02-27 21:02:33.445348 | controller | ok 2026-02-27 21:02:33.453877 | 2026-02-27 21:02:33.453986 | TASK [bindep : Define bindep_file fact] 2026-02-27 21:02:33.480158 | controller | skipping: Conditional result was False 2026-02-27 21:02:33.487199 | 2026-02-27 21:02:33.487273 | TASK [bindep : Look for other-requirements.txt] 2026-02-27 21:02:33.700479 | controller | ok 2026-02-27 21:02:33.706326 | 2026-02-27 21:02:33.706399 | TASK [bindep : Define bindep_file fact] 2026-02-27 21:02:33.730997 | controller | skipping: Conditional result was False 2026-02-27 21:02:33.738585 | 2026-02-27 21:02:33.738660 | TASK [bindep : Look for bindep fallback file] 2026-02-27 21:02:33.763060 | controller | skipping: Conditional result was False 2026-02-27 21:02:33.770119 | 2026-02-27 21:02:33.770213 | TASK [bindep : Define bindep_file fact] 2026-02-27 21:02:33.794910 | controller | skipping: Conditional result was False 2026-02-27 21:02:33.802334 | 2026-02-27 21:02:33.802439 | TASK [bindep : Include bindep tasks] 2026-02-27 21:02:33.827577 | controller | skipping: Conditional result was False 2026-02-27 21:02:33.834472 | 2026-02-27 21:02:33.834544 | TASK [bindep : Include install tasks] 2026-02-27 21:02:33.868534 | controller | skipping: Conditional result was False 2026-02-27 21:02:33.875202 | 2026-02-27 21:02:33.875271 | LOOP [bindep : Include package tasks] 2026-02-27 21:02:33.936154 | 2026-02-27 21:02:33.936357 | TASK [Run test-setup role] 2026-02-27 21:02:33.967666 | controller | ok 2026-02-27 21:02:33.985455 | 2026-02-27 21:02:33.985524 | TASK [test-setup : Check if project's tools/test-setup.sh exists] 2026-02-27 21:02:34.227303 | controller | ok 2026-02-27 21:02:34.243592 | 2026-02-27 21:02:34.243822 | TASK [test-setup : Run tools/test-setup.sh] 2026-02-27 21:02:34.817655 | controller | skipping: Conditional result was False 2026-02-27 21:02:34.845291 | 2026-02-27 21:02:34.845416 | PLAY RECAP 2026-02-27 21:02:34.845470 | controller | ok: 4 changed: 0 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2026-02-27 21:02:34.845496 | 2026-02-27 21:02:34.950124 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2026-02-27 21:02:34.951232 | PRE-RUN START: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master] 2026-02-27 21:02:35.552684 | 2026-02-27 21:02:35.552917 | PLAY [controller] 2026-02-27 21:02:35.573713 | 2026-02-27 21:02:35.573814 | TASK [Create the /root directory] 2026-02-27 21:02:36.141890 | controller | ok 2026-02-27 21:02:36.155344 | 2026-02-27 21:02:36.155519 | TASK [Install glibc-langpack-en] 2026-02-27 21:02:40.224027 | controller | ok: Nothing to do 2026-02-27 21:02:40.238980 | 2026-02-27 21:02:40.239087 | TASK [Ensure controller directory exists] 2026-02-27 21:02:40.461517 | controller | changed 2026-02-27 21:02:40.484653 | 2026-02-27 21:02:40.484821 | TASK [Install container runtime] 2026-02-27 21:02:40.564523 | controller | ok 2026-02-27 21:02:40.625407 | 2026-02-27 21:02:40.625561 | LOOP [ensure-podman : Find distribution installation] 2026-02-27 21:02:40.665321 | controller | ok: "/var/lib/zuul/builds/f6f3dd578a794f7abf2503654e4e4e59/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-podman/tasks/RedHat.yaml" 2026-02-27 21:02:40.678774 | controller | included: /var/lib/zuul/builds/f6f3dd578a794f7abf2503654e4e4e59/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-podman/tasks/RedHat.yaml 2026-02-27 21:02:40.685229 | 2026-02-27 21:02:40.685301 | TASK [ensure-podman : Install podman (RedHat)] 2026-02-27 21:03:47.264280 | controller | changed 2026-02-27 21:03:47.279330 | 2026-02-27 21:03:47.279494 | TASK [ensure-podman : Fetch podman version] 2026-02-27 21:03:47.827153 | controller | Client: Podman Engine 2026-02-27 21:03:47.862061 | controller | Version: 4.6.2 2026-02-27 21:03:47.862114 | controller | API Version: 4.6.2 2026-02-27 21:03:47.862126 | controller | Go Version: go1.19.12 2026-02-27 21:03:47.862153 | controller | Built: Mon Aug 28 19:38:31 2023 2026-02-27 21:03:47.862165 | controller | OS/Arch: linux/amd64 2026-02-27 21:03:48.329951 | controller | ok: Runtime: 0:00:00.208723 2026-02-27 21:03:48.343246 | 2026-02-27 21:03:48.343398 | TASK [ensure-podman : Print podman version installed] 2026-02-27 21:03:48.383472 | Podman version: Client: Podman Engine 2026-02-27 21:03:48.383777 | Version: 4.6.2 2026-02-27 21:03:48.383878 | API Version: 4.6.2 2026-02-27 21:03:48.383941 | Go Version: go1.19.12 2026-02-27 21:03:48.383984 | Built: Mon Aug 28 19:38:31 2023 2026-02-27 21:03:48.384026 | OS/Arch: linux/amd64 2026-02-27 21:03:48.396419 | 2026-02-27 21:03:48.396549 | TASK [ensure-podman : Validate podman engine] 2026-02-27 21:03:48.939710 | controller | skipping: Conditional result was False 2026-02-27 21:03:48.953862 | 2026-02-27 21:03:48.954014 | TASK [ensure-podman : Set up docker compatability socket] 2026-02-27 21:03:48.981238 | controller | skipping: Conditional result was False 2026-02-27 21:03:49.005928 | 2026-02-27 21:03:49.006204 | TASK [Ensure python3.8 is present] 2026-02-27 21:03:49.033906 | controller | skipping: Conditional result was False 2026-02-27 21:03:49.047391 | 2026-02-27 21:03:49.047528 | TASK [Run our-ensure-python role (Fedora only for now)] 2026-02-27 21:03:49.078053 | controller | ok 2026-02-27 21:03:49.108708 | 2026-02-27 21:03:49.108858 | TASK [our-ensure-python : Install the right Python version (rpm)] 2026-02-27 21:03:50.557247 | controller | ok: Nothing to do 2026-02-27 21:03:50.567776 | 2026-02-27 21:03:50.567909 | TASK [our-ensure-python : Also install python3-devel] 2026-02-27 21:04:00.106208 | controller | changed 2026-02-27 21:04:00.120817 | 2026-02-27 21:04:00.120929 | TASK [Run ensure-virtualenv role] 2026-02-27 21:04:00.153841 | controller | ok 2026-02-27 21:04:00.182329 | 2026-02-27 21:04:00.182475 | TASK [ensure-virtualenv : Check if virtualenv is installed] 2026-02-27 21:04:00.428352 | controller | /usr/bin/virtualenv 2026-02-27 21:04:00.741333 | controller | ok: Runtime: 0:00:00.005154 2026-02-27 21:04:00.793658 | 2026-02-27 21:04:00.793831 | LOOP [ensure-virtualenv : Install virtualenv package] 2026-02-27 21:04:00.826986 | controller | skipping: Conditional result was False 2026-02-27 21:04:00.827304 | controller | ok: All items complete 2026-02-27 21:04:00.827340 | 2026-02-27 21:04:00.849702 | 2026-02-27 21:04:00.849852 | TASK [Find the full path of the Python interpreter] 2026-02-27 21:04:01.073300 | controller | /usr/bin/python3.11 2026-02-27 21:04:01.394337 | controller | ok 2026-02-27 21:04:01.406437 | 2026-02-27 21:04:01.406571 | TASK [Create virtualenv for ansible-test with a specific interpreter] 2026-02-27 21:04:02.530317 | controller | created virtual environment CPython3.11.0.final.0-64 in 667ms 2026-02-27 21:04:02.549381 | controller | creator CPython3Posix(dest=/home/zuul/venv, clear=False, no_vcs_ignore=False, global=False) 2026-02-27 21:04:02.550034 | 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) 2026-02-27 21:04:02.550420 | controller | added seed packages: pip==22.2.2, setuptools==62.6.0, wheel==0.37.1 2026-02-27 21:04:02.550819 | controller | activators BashActivator,CShellActivator,FishActivator,NushellActivator,PowerShellActivator,PythonActivator 2026-02-27 21:04:02.963485 | controller | changed 2026-02-27 21:04:02.976980 | 2026-02-27 21:04:02.977127 | TASK [Set selinux package] 2026-02-27 21:04:03.017134 | controller | ok 2026-02-27 21:04:03.030292 | 2026-02-27 21:04:03.030474 | TASK [Set selinux package (Fedora)] 2026-02-27 21:04:03.081540 | controller | ok 2026-02-27 21:04:03.093075 | 2026-02-27 21:04:03.093204 | TASK [Install selinux into virtualenv] 2026-02-27 21:04:04.553125 | controller | Collecting selinux-please-lie-to-me 2026-02-27 21:04:04.623233 | controller | Downloading selinux_please_lie_to_me-1.0.1-py2.py3-none-any.whl (3.8 kB) 2026-02-27 21:04:04.930807 | controller | Collecting setuptools<50.0.0 2026-02-27 21:04:04.944197 | controller | Downloading setuptools-49.6.0-py3-none-any.whl (803 kB) 2026-02-27 21:04:05.054112 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 803.3/803.3 kB 7.5 MB/s eta 0:00:00 2026-02-27 21:04:05.142331 | controller | Installing collected packages: setuptools, selinux-please-lie-to-me 2026-02-27 21:04:05.142549 | controller | Attempting uninstall: setuptools 2026-02-27 21:04:05.145124 | controller | Found existing installation: setuptools 62.6.0 2026-02-27 21:04:05.206374 | controller | Uninstalling setuptools-62.6.0: 2026-02-27 21:04:05.214619 | controller | Successfully uninstalled setuptools-62.6.0 2026-02-27 21:04:05.560361 | controller | Successfully installed selinux-please-lie-to-me-1.0.1 setuptools-49.6.0 2026-02-27 21:04:05.683441 | controller | 2026-02-27 21:04:05.770826 | controller | [notice] A new release of pip available: 22.2.2 -> 26.0.1 2026-02-27 21:04:05.770869 | controller | [notice] To update, run: python -m pip install --upgrade pip 2026-02-27 21:04:06.157899 | controller | ok: Runtime: 0:00:02.313771 2026-02-27 21:04:06.172753 | 2026-02-27 21:04:06.172935 | TASK [Install pytest-forked into virtualenv] 2026-02-27 21:04:06.795718 | controller | Collecting pytest-forked 2026-02-27 21:04:06.858715 | controller | Downloading pytest_forked-1.6.0-py3-none-any.whl (4.9 kB) 2026-02-27 21:04:06.897897 | controller | Collecting py 2026-02-27 21:04:06.910263 | controller | Downloading py-1.11.0-py2.py3-none-any.whl (98 kB) 2026-02-27 21:04:06.935922 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 98.7/98.7 kB 4.1 MB/s eta 0:00:00 2026-02-27 21:04:07.037307 | controller | Collecting pytest>=3.10 2026-02-27 21:04:07.049369 | controller | Downloading pytest-9.0.2-py3-none-any.whl (374 kB) 2026-02-27 21:04:07.072129 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 374.8/374.8 kB 18.9 MB/s eta 0:00:00 2026-02-27 21:04:07.105270 | controller | Collecting iniconfig>=1.0.1 2026-02-27 21:04:07.117568 | controller | Downloading iniconfig-2.3.0-py3-none-any.whl (7.5 kB) 2026-02-27 21:04:07.159358 | controller | Collecting packaging>=22 2026-02-27 21:04:07.196543 | controller | Downloading packaging-26.0-py3-none-any.whl (74 kB) 2026-02-27 21:04:07.205100 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 74.4/74.4 kB 10.0 MB/s eta 0:00:00 2026-02-27 21:09:01.814433 | [Zuul] Log Stream did not terminate 2026-02-27 21:09:01.814700 | controller | ERROR 2026-02-27 21:09:01.814875 | controller | { 2026-02-27 21:09:01.814928 | controller | "msg": "Data could not be sent to remote host \"162.253.55.62\". Make sure this host can be reached over ssh: ssh: connect to host 162.253.55.62 port 22: No route to host\r\n", 2026-02-27 21:09:01.814964 | controller | "unreachable": true 2026-02-27 21:09:01.815003 | controller | } unreachable 2026-02-27 21:09:01.817100 | 2026-02-27 21:09:01.817153 | PLAY RECAP 2026-02-27 21:09:01.817197 | controller | ok: 15 changed: 7 unreachable: 1 failed: 0 skipped: 4 rescued: 0 ignored: 0 2026-02-27 21:09:01.817248 | 2026-02-27 21:09:01.956677 | PRE-RUN END RESULT_UNREACHABLE: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master] 2026-02-27 21:09:01.958606 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2026-02-27 21:09:02.549048 | 2026-02-27 21:09:02.549157 | PLAY [all] 2026-02-27 21:09:02.570501 | 2026-02-27 21:09:02.570593 | TASK [fetch-subunit-output : Find stestr or testr executable] 2026-02-27 21:09:08.098448 | controller | changed: non-zero return code 2026-02-27 21:09:08.111822 | 2026-02-27 21:09:08.112023 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2026-02-27 21:09:08.139177 | controller | skipping: Conditional result was False 2026-02-27 21:09:08.153201 | 2026-02-27 21:09:08.153349 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2026-02-27 21:09:08.194945 | 2026-02-27 21:09:08.195233 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2026-02-27 21:09:08.236020 | 2026-02-27 21:09:08.236312 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2026-02-27 21:09:08.263407 | controller | skipping: Conditional result was False 2026-02-27 21:09:08.278379 | 2026-02-27 21:09:08.278660 | LOOP [fetch-subunit-output : Generate subunit file] 2026-02-27 21:09:08.320755 | 2026-02-27 21:09:08.321310 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2026-02-27 21:09:08.338262 | controller | skipping: Conditional result was False 2026-02-27 21:09:08.353375 | 2026-02-27 21:09:08.353534 | TASK [fetch-subunit-output : Remove the temporary file] 2026-02-27 21:09:08.383910 | controller | skipping: Conditional result was False 2026-02-27 21:09:08.397297 | 2026-02-27 21:09:08.397481 | TASK [fetch-subunit-output : Process and fetch subunit results] 2026-02-27 21:09:08.414455 | controller | skipping: Conditional result was False 2026-02-27 21:09:08.458889 | 2026-02-27 21:09:08.459007 | PLAY RECAP 2026-02-27 21:09:08.459060 | controller | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2026-02-27 21:09:08.459260 | 2026-02-27 21:09:08.569686 | POST-RUN END RESULT_UNREACHABLE: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2026-02-27 21:09:08.571614 | POST-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/post.yaml@master] 2026-02-27 21:09:09.156576 | 2026-02-27 21:09:09.156689 | PLAY [all:!appliance*] 2026-02-27 21:09:09.178294 | 2026-02-27 21:09:09.178391 | TASK [unregister the node] 2026-02-27 21:09:09.710082 | controller | skipping: Conditional result was False 2026-02-27 21:09:09.723897 | 2026-02-27 21:09:09.724072 | TASK [include_role : fetch-output] 2026-02-27 21:09:09.775032 | controller | ok 2026-02-27 21:09:09.816388 | 2026-02-27 21:09:09.816534 | TASK [fetch-output : Set log path for multiple nodes] 2026-02-27 21:09:09.892988 | controller | skipping: Conditional result was False 2026-02-27 21:09:09.902049 | 2026-02-27 21:09:09.902158 | TASK [fetch-output : Set log path for single node] 2026-02-27 21:09:09.943854 | controller | ok 2026-02-27 21:09:09.950041 | 2026-02-27 21:09:09.950134 | LOOP [fetch-output : Ensure local output dirs] 2026-02-27 21:09:10.384000 | controller -> localhost | ok: "/var/lib/zuul/builds/f6f3dd578a794f7abf2503654e4e4e59/work/logs" 2026-02-27 21:09:10.658400 | controller -> localhost | changed: "/var/lib/zuul/builds/f6f3dd578a794f7abf2503654e4e4e59/work/artifacts" 2026-02-27 21:09:10.967290 | controller -> localhost | changed: "/var/lib/zuul/builds/f6f3dd578a794f7abf2503654e4e4e59/work/docs" 2026-02-27 21:09:10.993580 | 2026-02-27 21:09:10.993795 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-02-27 21:09:11.714923 | controller | changed: 2026-02-27 21:09:11.715265 | controller | .d..t...... ./ 2026-02-27 21:09:11.715329 | controller | cd+++++++++ controller/ 2026-02-27 21:09:11.715400 | controller | changed: All items complete 2026-02-27 21:09:11.715446 | 2026-02-27 21:09:12.217202 | controller | changed: .d..t...... ./ 2026-02-27 21:09:12.676054 | controller | changed: .d..t...... ./ 2026-02-27 21:09:12.704804 | 2026-02-27 21:09:12.705006 | TASK [include_role : fetch-output-openshift] 2026-02-27 21:09:12.732151 | controller | skipping: Conditional result was False 2026-02-27 21:09:12.746602 | 2026-02-27 21:09:12.746813 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-02-27 21:09:13.279475 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.013618 2026-02-27 21:09:13.586791 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.012024 2026-02-27 21:09:13.616677 | 2026-02-27 21:09:13.616800 | PLAY [localhost] 2026-02-27 21:09:13.629788 | 2026-02-27 21:09:13.629875 | TASK [Run Zuul manifest role] 2026-02-27 21:09:13.650542 | localhost | ok 2026-02-27 21:09:13.663670 | 2026-02-27 21:09:13.663765 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-02-27 21:09:14.013185 | localhost | changed 2026-02-27 21:09:14.025895 | 2026-02-27 21:09:14.025985 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-02-27 21:09:14.056894 | localhost | ok 2026-02-27 21:09:14.069634 | 2026-02-27 21:09:14.069792 | TASK [Set zuul-log-path fact] 2026-02-27 21:09:14.090442 | localhost | ok 2026-02-27 21:09:14.109645 | 2026-02-27 21:09:14.109770 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-02-27 21:09:14.140929 | localhost | ok 2026-02-27 21:09:14.151995 | 2026-02-27 21:09:14.152078 | LOOP [Set zuul_log_targets (Swift with random Rackspace region)] 2026-02-27 21:09:14.189626 | localhost | ok: 2026-02-27 21:09:14.189973 | localhost | { 2026-02-27 21:09:14.190010 | localhost | "auth": { 2026-02-27 21:09:14.190045 | localhost | "password": "IonWCwcuOFib&&<}wp^:[2G/", 2026-02-27 21:09:14.190070 | localhost | "username": "9LF91O0RK7JU" 2026-02-27 21:09:14.190094 | localhost | }, 2026-02-27 21:09:14.190116 | localhost | "profile": "rackspace", 2026-02-27 21:09:14.190138 | localhost | "region_name": "ORD" 2026-02-27 21:09:14.190160 | localhost | } 2026-02-27 21:09:14.190205 | localhost | changed: All items complete 2026-02-27 21:09:14.190233 | 2026-02-27 21:09:14.205366 | 2026-02-27 21:09:14.205508 | TASK [Run upload-logs-failover role (S3 then Swift)] 2026-02-27 21:09:14.226021 | localhost | Output suppressed because no_log was given 2026-02-27 21:09:14.244623 | 2026-02-27 21:09:14.244713 | TASK [upload-logs-failover : Initialize control vars] 2026-02-27 21:09:14.312320 | localhost | ok 2026-02-27 21:09:14.317709 | 2026-02-27 21:09:14.317794 | LOOP [upload-logs-failover : Perform log upload] 2026-02-27 21:09:14.342429 | localhost | Output suppressed because no_log was given 2026-02-27 21:09:14.352564 | localhost | included: /var/lib/zuul/builds/f6f3dd578a794f7abf2503654e4e4e59/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/upload-logs-failover/tasks/upload.yaml 2026-02-27 21:09:14.362687 | 2026-02-27 21:09:14.362776 | LOOP [upload-logs-failover : Unpack vars for swift upload] 2026-02-27 21:09:14.448677 | localhost | Output suppressed because no_log was given 2026-02-27 21:09:14.454250 | 2026-02-27 21:09:14.454333 | TASK [Upload logs to swift] 2026-02-27 21:09:14.473781 | localhost | ok 2026-02-27 21:09:14.500751 | 2026-02-27 21:09:14.500832 | TASK [Set zuul-log-path fact] 2026-02-27 21:09:14.525186 | localhost | skipping: Conditional result was False 2026-02-27 21:09:14.530280 | 2026-02-27 21:09:14.530356 | TASK [upload-logs-swift : Ensure logs are readable before uploading] 2026-02-27 21:09:14.836960 | localhost -> localhost | ok: Runtime: 0:00:00.008867 2026-02-27 21:09:14.844688 | 2026-02-27 21:09:14.844895 | TASK [upload-logs-swift : Upload logs to swift]