2025-11-22 00:06:58.877295 | Job console starting... 2025-11-22 00:06:58.888996 | Updating repositories 2025-11-22 00:06:59.008986 | Preparing job workspace 2025-11-22 00:07:04.489974 | Running Ansible setup... 2025-11-22 00:07:10.082420 | PRE-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master] 2025-11-22 00:07:10.767707 | 2025-11-22 00:07:10.767847 | PLAY [localhost] 2025-11-22 00:07:10.777073 | 2025-11-22 00:07:10.777176 | TASK [Gathering Facts] 2025-11-22 00:07:11.729504 | localhost | ok 2025-11-22 00:07:11.762320 | 2025-11-22 00:07:11.762544 | TASK [Setup log path fact] 2025-11-22 00:07:11.784552 | localhost | ok 2025-11-22 00:07:11.802324 | 2025-11-22 00:07:11.802434 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-11-22 00:07:11.842399 | localhost | ok 2025-11-22 00:07:11.853498 | 2025-11-22 00:07:11.853592 | TASK [emit-job-header : Print job information] 2025-11-22 00:07:11.914463 | # Job Information 2025-11-22 00:07:11.914648 | Ansible Version: 2.15.12 2025-11-22 00:07:11.914725 | Job: ansible-test-sanity-docker-stable-2.9 2025-11-22 00:07:11.914760 | Pipeline: periodic 2025-11-22 00:07:11.914788 | Executor: ze04.softwarefactory-project.io 2025-11-22 00:07:11.914816 | Triggered by: https://github.com/ansible-collections/ansible.network 2025-11-22 00:07:11.914847 | Log URL (when completed): https://ansible.softwarefactory-project.io/logs/71c/ansible/71cd02fe6c904f9cb647f098e6300d59/ 2025-11-22 00:07:11.914876 | Event ID: c15ec521bc464be4b3ce046f0e5676a7 2025-11-22 00:07:11.920583 | 2025-11-22 00:07:11.920695 | LOOP [emit-job-header : Print node information] 2025-11-22 00:07:12.034460 | localhost | ok: 2025-11-22 00:07:12.034610 | localhost | # Node Information 2025-11-22 00:07:12.034636 | localhost | Inventory Hostname: controller 2025-11-22 00:07:12.034656 | localhost | Hostname: np0005531610 2025-11-22 00:07:12.034690 | localhost | Username: zuul 2025-11-22 00:07:12.034718 | localhost | Distro: Fedora 37 2025-11-22 00:07:12.034738 | localhost | Provider: ansible-vexxhost-ca-ymq-1 2025-11-22 00:07:12.034755 | localhost | Region: ca-ymq-1 2025-11-22 00:07:12.034773 | localhost | Label: ansible-fedora-37-1vcpu 2025-11-22 00:07:12.034790 | localhost | Product Name: OpenStack Nova 2025-11-22 00:07:12.034807 | localhost | Interface IP: 162.253.55.84 2025-11-22 00:07:12.058191 | 2025-11-22 00:07:12.058402 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-11-22 00:07:12.486185 | localhost -> localhost | changed 2025-11-22 00:07:12.494117 | 2025-11-22 00:07:12.494216 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-11-22 00:07:13.326632 | localhost -> localhost | changed 2025-11-22 00:07:13.346366 | 2025-11-22 00:07:13.346477 | PLAY [all:!appliance*] 2025-11-22 00:07:13.363123 | 2025-11-22 00:07:13.363199 | TASK [include_role : start-zuul-console] 2025-11-22 00:07:13.382598 | controller | ok 2025-11-22 00:07:13.396631 | 2025-11-22 00:07:13.396716 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-11-22 00:07:13.809698 | controller | ok 2025-11-22 00:07:13.828709 | 2025-11-22 00:07:13.829019 | TASK [use-our-mirror : Retrieve the IP address] 2025-11-22 00:07:14.671017 | controller | ok: Status code was 404 and not [200]: HTTP Error 404: Not Found 2025-11-22 00:07:14.678886 | 2025-11-22 00:07:14.678955 | TASK [use-our-mirror : Ensure the registry service is running on the mirror] 2025-11-22 00:07:15.216480 | controller | skipping: Conditional result was False 2025-11-22 00:07:15.232617 | 2025-11-22 00:07:15.233059 | TASK [use-our-mirror : Write the IP in /etc/hosts] 2025-11-22 00:07:15.261842 | controller | skipping: Conditional result was False 2025-11-22 00:07:15.277470 | 2025-11-22 00:07:15.277630 | TASK [use-our-mirror : Create the podman configuration directory] 2025-11-22 00:07:15.305840 | controller | skipping: Conditional result was False 2025-11-22 00:07:15.320982 | 2025-11-22 00:07:15.321143 | TASK [use-our-mirror : Copy the podman configuration] 2025-11-22 00:07:15.348662 | controller | skipping: Conditional result was False 2025-11-22 00:07:15.367067 | 2025-11-22 00:07:15.367422 | TASK [use-our-mirror : Copy the Yum configuration (main)] 2025-11-22 00:07:15.394453 | controller | skipping: Conditional result was False 2025-11-22 00:07:15.412115 | 2025-11-22 00:07:15.412285 | TASK [use-our-mirror : Copy the Yum configuration (update)] 2025-11-22 00:07:15.439812 | controller | skipping: Conditional result was False 2025-11-22 00:07:15.458533 | 2025-11-22 00:07:15.458652 | TASK [Disable Fedora Modular] 2025-11-22 00:07:15.751758 | controller | changed 2025-11-22 00:07:15.768363 | 2025-11-22 00:07:15.768564 | TASK [Enable EPEL] 2025-11-22 00:07:15.797298 | controller | skipping: Conditional result was False 2025-11-22 00:07:15.812754 | 2025-11-22 00:07:15.812925 | TASK [Register the RHEL node] 2025-11-22 00:07:16.905401 | 2025-11-22 00:07:16.905551 | TASK [Show the subscription-manager status] 2025-11-22 00:07:17.496511 | controller | skipping: Conditional result was False 2025-11-22 00:07:17.511577 | 2025-11-22 00:07:17.511805 | TASK [Enable EPEL on RHEL] 2025-11-22 00:07:18.067331 | controller | skipping: Conditional result was False 2025-11-22 00:07:18.080266 | 2025-11-22 00:07:18.080447 | TASK [Install git and tox] 2025-11-22 00:08:39.116902 | controller | changed 2025-11-22 00:08:39.128643 | 2025-11-22 00:08:39.128822 | TASK [include_role : prepare-workspace] 2025-11-22 00:08:39.164240 | controller | ok 2025-11-22 00:08:39.196875 | 2025-11-22 00:08:39.197008 | TASK [prepare-workspace : Start zuul_console daemon.] 2025-11-22 00:08:39.421090 | controller | ok 2025-11-22 00:08:39.429147 | 2025-11-22 00:08:39.429245 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2025-11-22 00:14:11.783460 | controller | Output suppressed because no_log was given 2025-11-22 00:14:11.803652 | 2025-11-22 00:14:11.803842 | TASK [include_role : prepare-workspace-openshift] 2025-11-22 00:14:11.841820 | controller | skipping: Conditional result was False 2025-11-22 00:14:11.878319 | 2025-11-22 00:14:11.878438 | PLAY [all:!appliance] 2025-11-22 00:14:11.902709 | 2025-11-22 00:14:11.903039 | TASK [Run add-build-sshkey role (RSA)] 2025-11-22 00:14:11.943432 | controller | ok 2025-11-22 00:14:11.975930 | 2025-11-22 00:14:11.976074 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-11-22 00:14:12.318757 | controller -> localhost | ok 2025-11-22 00:14:12.333198 | 2025-11-22 00:14:12.333341 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-11-22 00:14:12.375876 | controller | ok 2025-11-22 00:14:12.409309 | controller | included: /var/lib/zuul/builds/71cd02fe6c904f9cb647f098e6300d59/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-11-22 00:14:12.418639 | 2025-11-22 00:14:12.418746 | TASK [add-build-sshkey : Create Temp SSH key] 2025-11-22 00:14:12.943386 | controller -> localhost | Generating public/private rsa key pair. 2025-11-22 00:14:12.943636 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/71cd02fe6c904f9cb647f098e6300d59/work/71cd02fe6c904f9cb647f098e6300d59_id_rsa. 2025-11-22 00:14:12.943722 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/71cd02fe6c904f9cb647f098e6300d59/work/71cd02fe6c904f9cb647f098e6300d59_id_rsa.pub. 2025-11-22 00:14:12.943760 | controller -> localhost | The key fingerprint is: 2025-11-22 00:14:12.943788 | controller -> localhost | SHA256:H5QqZeDlChW/DHTmIU6/CL85XMOxKSeT1aSmvtzD6EU zuul-build-sshkey 2025-11-22 00:14:12.943815 | controller -> localhost | The key's randomart image is: 2025-11-22 00:14:12.943841 | controller -> localhost | +---[RSA 2048]----+ 2025-11-22 00:14:12.943865 | controller -> localhost | | B.= . | 2025-11-22 00:14:12.943891 | controller -> localhost | | * @ = . | 2025-11-22 00:14:12.943915 | controller -> localhost | | o + % + | 2025-11-22 00:14:12.943939 | controller -> localhost | | + & O | 2025-11-22 00:14:12.943963 | controller -> localhost | | @ E . | 2025-11-22 00:14:12.943997 | controller -> localhost | | o @ o . | 2025-11-22 00:14:12.944027 | controller -> localhost | | *o. . | 2025-11-22 00:14:12.944053 | controller -> localhost | | ..=o | 2025-11-22 00:14:12.944078 | controller -> localhost | | .+ .. | 2025-11-22 00:14:12.944111 | controller -> localhost | +----[SHA256]-----+ 2025-11-22 00:14:12.944173 | controller -> localhost | ok: Runtime: 0:00:00.046532 2025-11-22 00:14:12.953131 | 2025-11-22 00:14:12.953219 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-11-22 00:14:12.976527 | controller | ok 2025-11-22 00:14:12.989967 | controller | included: /var/lib/zuul/builds/71cd02fe6c904f9cb647f098e6300d59/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-11-22 00:14:13.003139 | 2025-11-22 00:14:13.003222 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-11-22 00:14:13.028053 | controller | skipping: Conditional result was False 2025-11-22 00:14:13.034126 | 2025-11-22 00:14:13.034187 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-11-22 00:14:13.665415 | controller | changed 2025-11-22 00:14:13.679499 | 2025-11-22 00:14:13.679665 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-11-22 00:14:13.903919 | controller | ok 2025-11-22 00:14:13.918518 | 2025-11-22 00:14:13.918663 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-11-22 00:14:14.543760 | controller | changed 2025-11-22 00:14:14.559349 | 2025-11-22 00:14:14.559577 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-11-22 00:14:15.250402 | controller | changed 2025-11-22 00:14:15.265136 | 2025-11-22 00:14:15.265284 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-11-22 00:14:15.305833 | controller | skipping: Conditional result was False 2025-11-22 00:14:15.325213 | 2025-11-22 00:14:15.325406 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-11-22 00:14:15.753821 | controller -> localhost | changed 2025-11-22 00:14:15.766261 | 2025-11-22 00:14:15.766366 | TASK [add-build-sshkey : Add back temp key] 2025-11-22 00:14:16.055477 | controller -> localhost | Identity added: /var/lib/zuul/builds/71cd02fe6c904f9cb647f098e6300d59/work/71cd02fe6c904f9cb647f098e6300d59_id_rsa (zuul-build-sshkey) 2025-11-22 00:14:16.055799 | controller -> localhost | ok: Runtime: 0:00:00.013101 2025-11-22 00:14:16.063459 | 2025-11-22 00:14:16.063586 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-11-22 00:14:16.402201 | controller | ok 2025-11-22 00:14:16.458596 | 2025-11-22 00:14:16.458869 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-11-22 00:14:16.503938 | controller | skipping: Conditional result was False 2025-11-22 00:14:16.517178 | 2025-11-22 00:14:16.517298 | TASK [Run add-build-sshkey role (ECDSA)] 2025-11-22 00:14:16.538726 | controller | ok 2025-11-22 00:14:16.556518 | 2025-11-22 00:14:16.556638 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-11-22 00:14:16.851767 | controller -> localhost | ok 2025-11-22 00:14:16.868610 | 2025-11-22 00:14:16.868826 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-11-22 00:14:16.916765 | controller | ok 2025-11-22 00:14:16.938117 | controller | included: /var/lib/zuul/builds/71cd02fe6c904f9cb647f098e6300d59/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-11-22 00:14:16.950026 | 2025-11-22 00:14:16.950173 | TASK [add-build-sshkey : Create Temp SSH key] 2025-11-22 00:14:17.276088 | controller -> localhost | Generating public/private ecdsa key pair. 2025-11-22 00:14:17.276479 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/71cd02fe6c904f9cb647f098e6300d59/work/71cd02fe6c904f9cb647f098e6300d59_id_ecdsa. 2025-11-22 00:14:17.276560 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/71cd02fe6c904f9cb647f098e6300d59/work/71cd02fe6c904f9cb647f098e6300d59_id_ecdsa.pub. 2025-11-22 00:14:17.276641 | controller -> localhost | The key fingerprint is: 2025-11-22 00:14:17.276737 | controller -> localhost | SHA256:ntP0U53cP4ZmjK/wzU1ZEQ4v9+Uc48G8X8Rpkmn7hG4 zuul-build-sshkey 2025-11-22 00:14:17.276788 | controller -> localhost | The key's randomart image is: 2025-11-22 00:14:17.276832 | controller -> localhost | +---[ECDSA 521]---+ 2025-11-22 00:14:17.276874 | controller -> localhost | | . . | 2025-11-22 00:14:17.276945 | controller -> localhost | | O.o| 2025-11-22 00:14:17.276991 | controller -> localhost | | * &+| 2025-11-22 00:14:17.277067 | controller -> localhost | | . X=@| 2025-11-22 00:14:17.277122 | controller -> localhost | | S . o.*O| 2025-11-22 00:14:17.277171 | controller -> localhost | | . + .+.+ *| 2025-11-22 00:14:17.277231 | controller -> localhost | | +...oE *o| 2025-11-22 00:14:17.277292 | controller -> localhost | | .o B.+ .| 2025-11-22 00:14:17.277341 | controller -> localhost | | o.+ . | 2025-11-22 00:14:17.277385 | controller -> localhost | +----[SHA256]-----+ 2025-11-22 00:14:17.277500 | controller -> localhost | ok: Runtime: 0:00:00.011146 2025-11-22 00:14:17.298853 | 2025-11-22 00:14:17.299007 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-11-22 00:14:17.345102 | controller | ok 2025-11-22 00:14:17.362016 | controller | included: /var/lib/zuul/builds/71cd02fe6c904f9cb647f098e6300d59/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-11-22 00:14:17.375032 | 2025-11-22 00:14:17.375133 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-11-22 00:14:17.411668 | controller | skipping: Conditional result was False 2025-11-22 00:14:17.421749 | 2025-11-22 00:14:17.421898 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-11-22 00:14:17.700938 | controller | changed 2025-11-22 00:14:17.711386 | 2025-11-22 00:14:17.711526 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-11-22 00:14:17.956551 | controller | ok 2025-11-22 00:14:17.972465 | 2025-11-22 00:14:17.972643 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-11-22 00:14:18.612865 | controller | changed 2025-11-22 00:14:18.627468 | 2025-11-22 00:14:18.627577 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-11-22 00:14:19.284547 | controller | changed 2025-11-22 00:14:19.292067 | 2025-11-22 00:14:19.292150 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-11-22 00:14:19.327935 | controller | skipping: Conditional result was False 2025-11-22 00:14:19.342631 | 2025-11-22 00:14:19.342817 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-11-22 00:14:19.634629 | controller -> localhost | changed 2025-11-22 00:14:19.646928 | 2025-11-22 00:14:19.647035 | TASK [add-build-sshkey : Add back temp key] 2025-11-22 00:14:19.957562 | controller -> localhost | Identity added: /var/lib/zuul/builds/71cd02fe6c904f9cb647f098e6300d59/work/71cd02fe6c904f9cb647f098e6300d59_id_ecdsa (zuul-build-sshkey) 2025-11-22 00:14:19.957871 | controller -> localhost | ok: Runtime: 0:00:00.013676 2025-11-22 00:14:19.967625 | 2025-11-22 00:14:19.967761 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-11-22 00:14:20.183497 | controller | ok 2025-11-22 00:14:20.189194 | 2025-11-22 00:14:20.189258 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-11-22 00:14:20.224122 | controller | skipping: Conditional result was False 2025-11-22 00:14:20.239908 | 2025-11-22 00:14:20.239996 | TASK [include_role : remove-zuul-sshkey] 2025-11-22 00:14:20.255243 | controller | skipping: Conditional result was False 2025-11-22 00:14:20.265915 | 2025-11-22 00:14:20.266005 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2025-11-22 00:14:20.496606 | controller | ok: "logs" 2025-11-22 00:14:20.497285 | controller | ok: All items complete 2025-11-22 00:14:20.497360 | 2025-11-22 00:14:20.673056 | controller | ok: "artifacts" 2025-11-22 00:14:20.874703 | controller | ok: "docs" 2025-11-22 00:14:20.887277 | 2025-11-22 00:14:20.887391 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2025-11-22 00:14:21.107820 | controller | changed: "logs" 2025-11-22 00:14:21.314843 | controller | changed: "artifacts" 2025-11-22 00:14:21.510607 | controller | changed: "docs" 2025-11-22 00:14:21.548159 | 2025-11-22 00:14:21.548272 | PLAY RECAP 2025-11-22 00:14:21.548316 | controller | ok: 30 changed: 16 unreachable: 0 failed: 0 skipped: 18 rescued: 0 ignored: 0 2025-11-22 00:14:21.548341 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-11-22 00:14:21.548367 | 2025-11-22 00:14:21.671401 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master] 2025-11-22 00:14:21.672219 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2025-11-22 00:14:22.256427 | 2025-11-22 00:14:22.256572 | PLAY [all] 2025-11-22 00:14:22.279247 | 2025-11-22 00:14:22.279400 | TASK [Install binary dependencies] 2025-11-22 00:14:22.321848 | controller | ok 2025-11-22 00:14:22.343230 | 2025-11-22 00:14:22.343367 | TASK [bindep : Include find tasks] 2025-11-22 00:14:22.385704 | controller | ok 2025-11-22 00:14:22.397275 | controller | included: /var/lib/zuul/builds/71cd02fe6c904f9cb647f098e6300d59/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find.yaml 2025-11-22 00:14:22.406733 | 2025-11-22 00:14:22.406903 | TASK [bindep : Look for bindep.txt] 2025-11-22 00:14:22.942904 | controller | ok 2025-11-22 00:14:22.958879 | 2025-11-22 00:14:22.959043 | TASK [bindep : Define bindep_file fact] 2025-11-22 00:14:22.984890 | controller | skipping: Conditional result was False 2025-11-22 00:14:22.993418 | 2025-11-22 00:14:22.993635 | TASK [bindep : Look for other-requirements.txt] 2025-11-22 00:14:23.187519 | controller | ok 2025-11-22 00:14:23.193138 | 2025-11-22 00:14:23.193215 | TASK [bindep : Define bindep_file fact] 2025-11-22 00:14:23.217395 | controller | skipping: Conditional result was False 2025-11-22 00:14:23.223778 | 2025-11-22 00:14:23.223863 | TASK [bindep : Look for bindep fallback file] 2025-11-22 00:14:23.247591 | controller | skipping: Conditional result was False 2025-11-22 00:14:23.254601 | 2025-11-22 00:14:23.254727 | TASK [bindep : Define bindep_file fact] 2025-11-22 00:14:23.278507 | controller | skipping: Conditional result was False 2025-11-22 00:14:23.284808 | 2025-11-22 00:14:23.284883 | TASK [bindep : Include bindep tasks] 2025-11-22 00:14:23.308531 | controller | skipping: Conditional result was False 2025-11-22 00:14:23.315210 | 2025-11-22 00:14:23.315342 | TASK [bindep : Include install tasks] 2025-11-22 00:14:23.339301 | controller | skipping: Conditional result was False 2025-11-22 00:14:23.345749 | 2025-11-22 00:14:23.345862 | LOOP [bindep : Include package tasks] 2025-11-22 00:14:23.400773 | 2025-11-22 00:14:23.400925 | TASK [Run test-setup role] 2025-11-22 00:14:23.420866 | controller | ok 2025-11-22 00:14:23.440163 | 2025-11-22 00:14:23.440232 | TASK [test-setup : Check if project's tools/test-setup.sh exists] 2025-11-22 00:14:23.650759 | controller | ok 2025-11-22 00:14:23.664514 | 2025-11-22 00:14:23.664648 | TASK [test-setup : Run tools/test-setup.sh] 2025-11-22 00:14:24.214741 | controller | skipping: Conditional result was False 2025-11-22 00:14:24.241140 | 2025-11-22 00:14:24.241205 | PLAY RECAP 2025-11-22 00:14:24.241245 | controller | ok: 4 changed: 0 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2025-11-22 00:14:24.241266 | 2025-11-22 00:14:24.344843 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2025-11-22 00:14:24.345759 | PRE-RUN START: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master] 2025-11-22 00:14:24.989155 | 2025-11-22 00:14:24.989280 | PLAY [controller] 2025-11-22 00:14:25.019442 | 2025-11-22 00:14:25.019618 | TASK [Create the /root directory] 2025-11-22 00:14:25.614795 | controller | ok 2025-11-22 00:14:25.630133 | 2025-11-22 00:14:25.630282 | TASK [Install glibc-langpack-en] 2025-11-22 00:14:29.518267 | controller | ok: Nothing to do 2025-11-22 00:14:29.527784 | 2025-11-22 00:14:29.528048 | TASK [Ensure controller directory exists] 2025-11-22 00:14:29.734194 | controller | changed 2025-11-22 00:14:29.741846 | 2025-11-22 00:14:29.741924 | TASK [Install container runtime] 2025-11-22 00:14:29.822968 | controller | ok 2025-11-22 00:14:29.881846 | 2025-11-22 00:14:29.881979 | LOOP [ensure-docker : Gather variables for each operating system] 2025-11-22 00:14:29.924726 | controller | ok: "/var/lib/zuul/builds/71cd02fe6c904f9cb647f098e6300d59/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/vars/fedora.yaml" 2025-11-22 00:14:29.933383 | 2025-11-22 00:14:29.933473 | TASK [ensure-docker : Sanity check for distro selection] 2025-11-22 00:14:29.957857 | controller | skipping: Conditional result was False 2025-11-22 00:14:29.965991 | 2025-11-22 00:14:29.966062 | TASK [ensure-docker : Sanity check for distro install] 2025-11-22 00:14:29.990753 | controller | skipping: Conditional result was False 2025-11-22 00:14:29.999399 | 2025-11-22 00:14:29.999483 | TASK [ensure-docker : Sanity check for upstream install] 2025-11-22 00:14:30.024976 | controller | skipping: Conditional result was False 2025-11-22 00:14:30.032016 | 2025-11-22 00:14:30.032082 | TASK [ensure-docker : Sanity check for upstream install mirrors] 2025-11-22 00:14:30.058286 | controller | skipping: Conditional result was False 2025-11-22 00:14:30.066787 | 2025-11-22 00:14:30.066873 | TASK [ensure-docker : Include tasks for docker install] 2025-11-22 00:14:30.087511 | controller | ok 2025-11-22 00:14:30.097641 | controller | included: /var/lib/zuul/builds/71cd02fe6c904f9cb647f098e6300d59/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/tasks/docker-upstream.yaml 2025-11-22 00:14:30.108227 | 2025-11-22 00:14:30.108320 | TASK [ensure-docker : Not Implemented] 2025-11-22 00:14:30.132768 | controller | skipping: Conditional result was False 2025-11-22 00:14:30.140007 | 2025-11-22 00:14:30.140086 | TASK [Set up docker mirrors] 2025-11-22 00:14:30.165469 | controller | ok 2025-11-22 00:14:30.201517 | 2025-11-22 00:14:30.201735 | TASK [use-docker-mirror : Set docker_mirror fact from mirror_info] 2025-11-22 00:14:30.237201 | controller | skipping: Conditional result was False 2025-11-22 00:14:30.244076 | 2025-11-22 00:14:30.244143 | TASK [use-docker-mirror : Set docker_mirror fact from zuul_site_mirror_info] 2025-11-22 00:14:30.268243 | controller | skipping: Conditional result was False 2025-11-22 00:14:30.274655 | 2025-11-22 00:14:30.274739 | TASK [use-docker-mirror : Set docker_mirror fact] 2025-11-22 00:14:30.309066 | controller | skipping: Conditional result was False 2025-11-22 00:14:30.317167 | 2025-11-22 00:14:30.317245 | TASK [use-docker-mirror : Set up docker mirrors] 2025-11-22 00:14:30.342360 | controller | skipping: Conditional result was False 2025-11-22 00:14:30.369059 | 2025-11-22 00:14:30.369201 | TASK [Add all repositories] 2025-11-22 00:14:30.390918 | controller | ok 2025-11-22 00:14:30.414766 | 2025-11-22 00:14:30.414855 | LOOP [ensure-package-repositories : Install repositories] 2025-11-22 00:14:30.455079 | controller | ok: "/var/lib/zuul/builds/71cd02fe6c904f9cb647f098e6300d59/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-package-repositories/tasks/RedHat.yaml" 2025-11-22 00:14:30.455260 | controller | ok: All items complete 2025-11-22 00:14:30.455288 | 2025-11-22 00:14:30.502792 | controller | included: /var/lib/zuul/builds/71cd02fe6c904f9cb647f098e6300d59/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-package-repositories/tasks/RedHat.yaml 2025-11-22 00:14:30.509956 | 2025-11-22 00:14:30.510015 | TASK [ensure-package-repositories : Create GPG key temp directory] 2025-11-22 00:14:30.841925 | controller | changed 2025-11-22 00:14:30.856797 | 2025-11-22 00:14:30.856908 | LOOP [ensure-package-repositories : Upload GPG keys] 2025-11-22 00:14:31.713234 | controller | changed: 2025-11-22 00:14:31.713407 | controller | { 2025-11-22 00:14:31.713434 | controller | "data": "-----BEGIN PGP PUBLIC KEY BLOCK-----\n\nmQINBFit5IEBEADDt86QpYKz5flnCsOyZ/fk3WwBKxfDjwHf/GIflo+4GWAXS7wJ\n1PSzPsvSDATV10J44i5WQzh99q+lZvFCVRFiNhRmlmcXG+rk1QmDh3fsCCj9Q/yP\nw8jn3Hx0zDtz8PIB/18ReftYJzUo34COLiHn8WiY20uGCF2pjdPgfxE+K454c4G7\ngKFqVUFYgPug2CS0quaBB5b0rpFUdzTeI5RCStd27nHCpuSDCvRYAfdv+4Y1yiVh\nKKdoe3Smj+RnXeVMgDxtH9FJibZ3DK7WnMN2yeob6VqXox+FvKYJCCLkbQgQmE50\nuVK0uN71A1mQDcTRKQ2q3fFGlMTqJbbzr3LwnCBE6hV0a36t+DABtZTmz5O69xdJ\nWGdBeePCnWVqtDb/BdEYz7hPKskcZBarygCCe2Xi7sZieoFZuq6ltPoCsdfEdfbO\n+VBVKJnExqNZCcFUTEnbH4CldWROOzMS8BGUlkGpa59Sl1t0QcmWlw1EbkeMQNrN\nspdR8lobcdNS9bpAJQqSHRZh3cAM9mA3Yq/bssUS/P2quRXLjJ9mIv3dky9C3udM\n+q2unvnbNpPtIUly76FJ3s8g8sHeOnmYcKqNGqHq2Q3kMdA2eIbI0MqfOIo2+Xk0\nrNt3ctq3g+cQiorcN3rdHPsTRSAcp+NCz1QF9TwXYtH1XV24A6QMO0+CZwARAQAB\ntCtEb2NrZXIgUmVsZWFzZSAoQ0UgcnBtKSA8ZG9ja2VyQGRvY2tlci5jb20+iQI3\nBBMBCgAhBQJYrep4AhsvBQsJCAcDBRUKCQgLBRYCAwEAAh4BAheAAAoJEMUv62ti\nHp816C0P/iP+1uhSa6Qq3TIc5sIFE5JHxOO6y0R97cUdAmCbEqBiJHUPNQDQaaRG\nVYBm0K013Q1gcJeUJvS32gthmIvhkstw7KTodwOM8Kl11CCqZ07NPFef1b2SaJ7l\nTYpyUsT9+e343ph+O4C1oUQw6flaAJe+8ATCmI/4KxfhIjD2a/Q1voR5tUIxfexC\n/LZTx05gyf2mAgEWlRm/cGTStNfqDN1uoKMlV+WFuB1j2oTUuO1/dr8mL+FgZAM3\nntWFo9gQCllNV9ahYOON2gkoZoNuPUnHsf4Bj6BQJnIXbAhMk9H2sZzwUi9bgObZ\nXO8+OrP4D4B9kCAKqqaQqA+O46LzO2vhN74lm/Fy6PumHuviqDBdN+HgtRPMUuao\nxnuVJSvBu9sPdgT/pR1N9u/KnfAnnLtR6g+fx4mWz+ts/riB/KRHzXd+44jGKZra\nIhTMfniguMJNsyEOO0AN8Tqcl0eRBxcOArcri7xu8HFvvl+e+ILymu4buusbYEVL\nGBkYP5YMmScfKn+jnDVN4mWoN1Bq2yMhMGx6PA3hOvzPNsUoYy2BwDxNZyflzuAi\ng59mgJm2NXtzNbSRJbMamKpQ69mzLWGdFNsRd4aH7PT7uPAURaf7B5BVp3UyjERW\n5alSGnBqsZmvlRnVH5BDUhYsWZMPRQS9rRr4iGW0l+TH+O2VJ8aQ\n=0Zqq\n-----END PGP PUBLIC KEY BLOCK-----\n" 2025-11-22 00:14:31.713713 | controller | } 2025-11-22 00:14:31.734481 | 2025-11-22 00:14:31.734612 | LOOP [ensure-package-repositories : Import GPG keys] 2025-11-22 00:14:32.355607 | controller | changed: 2025-11-22 00:14:32.355916 | controller | { 2025-11-22 00:14:32.355973 | controller | "data": "-----BEGIN PGP PUBLIC KEY BLOCK-----\n\nmQINBFit5IEBEADDt86QpYKz5flnCsOyZ/fk3WwBKxfDjwHf/GIflo+4GWAXS7wJ\n1PSzPsvSDATV10J44i5WQzh99q+lZvFCVRFiNhRmlmcXG+rk1QmDh3fsCCj9Q/yP\nw8jn3Hx0zDtz8PIB/18ReftYJzUo34COLiHn8WiY20uGCF2pjdPgfxE+K454c4G7\ngKFqVUFYgPug2CS0quaBB5b0rpFUdzTeI5RCStd27nHCpuSDCvRYAfdv+4Y1yiVh\nKKdoe3Smj+RnXeVMgDxtH9FJibZ3DK7WnMN2yeob6VqXox+FvKYJCCLkbQgQmE50\nuVK0uN71A1mQDcTRKQ2q3fFGlMTqJbbzr3LwnCBE6hV0a36t+DABtZTmz5O69xdJ\nWGdBeePCnWVqtDb/BdEYz7hPKskcZBarygCCe2Xi7sZieoFZuq6ltPoCsdfEdfbO\n+VBVKJnExqNZCcFUTEnbH4CldWROOzMS8BGUlkGpa59Sl1t0QcmWlw1EbkeMQNrN\nspdR8lobcdNS9bpAJQqSHRZh3cAM9mA3Yq/bssUS/P2quRXLjJ9mIv3dky9C3udM\n+q2unvnbNpPtIUly76FJ3s8g8sHeOnmYcKqNGqHq2Q3kMdA2eIbI0MqfOIo2+Xk0\nrNt3ctq3g+cQiorcN3rdHPsTRSAcp+NCz1QF9TwXYtH1XV24A6QMO0+CZwARAQAB\ntCtEb2NrZXIgUmVsZWFzZSAoQ0UgcnBtKSA8ZG9ja2VyQGRvY2tlci5jb20+iQI3\nBBMBCgAhBQJYrep4AhsvBQsJCAcDBRUKCQgLBRYCAwEAAh4BAheAAAoJEMUv62ti\nHp816C0P/iP+1uhSa6Qq3TIc5sIFE5JHxOO6y0R97cUdAmCbEqBiJHUPNQDQaaRG\nVYBm0K013Q1gcJeUJvS32gthmIvhkstw7KTodwOM8Kl11CCqZ07NPFef1b2SaJ7l\nTYpyUsT9+e343ph+O4C1oUQw6flaAJe+8ATCmI/4KxfhIjD2a/Q1voR5tUIxfexC\n/LZTx05gyf2mAgEWlRm/cGTStNfqDN1uoKMlV+WFuB1j2oTUuO1/dr8mL+FgZAM3\nntWFo9gQCllNV9ahYOON2gkoZoNuPUnHsf4Bj6BQJnIXbAhMk9H2sZzwUi9bgObZ\nXO8+OrP4D4B9kCAKqqaQqA+O46LzO2vhN74lm/Fy6PumHuviqDBdN+HgtRPMUuao\nxnuVJSvBu9sPdgT/pR1N9u/KnfAnnLtR6g+fx4mWz+ts/riB/KRHzXd+44jGKZra\nIhTMfniguMJNsyEOO0AN8Tqcl0eRBxcOArcri7xu8HFvvl+e+ILymu4buusbYEVL\nGBkYP5YMmScfKn+jnDVN4mWoN1Bq2yMhMGx6PA3hOvzPNsUoYy2BwDxNZyflzuAi\ng59mgJm2NXtzNbSRJbMamKpQ69mzLWGdFNsRd4aH7PT7uPAURaf7B5BVp3UyjERW\n5alSGnBqsZmvlRnVH5BDUhYsWZMPRQS9rRr4iGW0l+TH+O2VJ8aQ\n=0Zqq\n-----END PGP PUBLIC KEY BLOCK-----\n" 2025-11-22 00:14:32.356032 | controller | } 2025-11-22 00:14:32.356099 | controller | changed: All items complete 2025-11-22 00:14:32.356148 | 2025-11-22 00:14:32.372346 | 2025-11-22 00:14:32.372447 | TASK [ensure-package-repositories : Remove GPG temp directory] 2025-11-22 00:14:32.618856 | controller | changed 2025-11-22 00:14:32.628992 | 2025-11-22 00:14:32.629144 | LOOP [ensure-package-repositories : Add repositories] 2025-11-22 00:14:33.015395 | controller | changed: 2025-11-22 00:14:33.016519 | controller | { 2025-11-22 00:14:33.016784 | controller | "baseurl": "https://download.docker.com/linux/fedora/$releasever/$basearch/stable", 2025-11-22 00:14:33.016845 | controller | "description": "Docker CE Stable - $basearch", 2025-11-22 00:14:33.017582 | controller | "gpgcheck": true, 2025-11-22 00:14:33.017654 | controller | "name": "docker-ce-stable" 2025-11-22 00:14:33.017747 | controller | } 2025-11-22 00:14:33.043068 | 2025-11-22 00:14:33.043222 | TASK [ensure-docker : Install pre-reqs] 2025-11-22 00:14:35.581045 | controller | ok: Nothing to do 2025-11-22 00:14:35.591831 | 2025-11-22 00:14:35.591963 | TASK [ensure-docker : Remove packages] 2025-11-22 00:14:36.949262 | controller | ok: Nothing to do 2025-11-22 00:14:36.963592 | 2025-11-22 00:14:36.963782 | TASK [ensure-docker : Install upstream docker using package] 2025-11-22 00:15:23.852817 | controller | changed 2025-11-22 00:15:23.865838 | 2025-11-22 00:15:23.865973 | TASK [ensure-docker : Setup docker] 2025-11-22 00:15:23.896280 | controller | ok 2025-11-22 00:15:23.917887 | controller | included: /var/lib/zuul/builds/71cd02fe6c904f9cb647f098e6300d59/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/tasks/docker-setup.yaml 2025-11-22 00:15:23.942320 | 2025-11-22 00:15:23.942554 | TASK [ensure-docker : Ensure "docker" group exists] 2025-11-22 00:15:24.354101 | controller | ok 2025-11-22 00:15:24.360175 | 2025-11-22 00:15:24.360242 | TASK [ensure-docker : Add user to docker group] 2025-11-22 00:15:24.878068 | controller | changed 2025-11-22 00:15:24.883948 | 2025-11-22 00:15:24.884008 | TASK [Update docker daemon proxy configuration] 2025-11-22 00:15:24.932011 | controller | skipping: Conditional result was False 2025-11-22 00:15:24.938827 | 2025-11-22 00:15:24.938903 | TASK [Lower default MTU] 2025-11-22 00:15:24.963267 | controller | skipping: Conditional result was False 2025-11-22 00:15:24.979194 | 2025-11-22 00:15:24.980189 | TASK [ensure-docker : Get the status of the docket.socket unit] 2025-11-22 00:15:25.374646 | controller | inactive 2025-11-22 00:15:25.556853 | controller | ok: Runtime: 0:00:00.014531 2025-11-22 00:15:25.570479 | 2025-11-22 00:15:25.570648 | TASK [ensure-docker : Get the status of the docket unit] 2025-11-22 00:15:25.835492 | controller | inactive 2025-11-22 00:15:26.128585 | controller | ok: Runtime: 0:00:00.009784 2025-11-22 00:15:26.150402 | 2025-11-22 00:15:26.150617 | TASK [ensure-docker : Restart docker] 2025-11-22 00:15:26.204222 | Trigger a Docker restart 2025-11-22 00:15:26.212970 | 2025-11-22 00:15:26.213946 | TASK [ensure-docker : Reset ssh connection to pick up docker group] 2025-11-22 00:15:26.246615 | 2025-11-22 00:15:26.246773 | TASK [ensure-docker : Install docker-compose] 2025-11-22 00:15:26.261109 | controller | skipping: Conditional result was False 2025-11-22 00:15:26.285818 | 2025-11-22 00:15:26.285958 | TASK [ensure-docker : Flush handlers before role exit] 2025-11-22 00:15:26.297032 | 2025-11-22 00:15:26.297219 | TASK [ensure-docker : Stop docker.socket to avoid any conflict] 2025-11-22 00:15:27.614337 | controller | changed 2025-11-22 00:15:27.629635 | 2025-11-22 00:15:27.629801 | TASK [ensure-docker : Assure docker service is running] 2025-11-22 00:15:29.724070 | controller | changed 2025-11-22 00:15:29.735869 | 2025-11-22 00:15:29.735998 | TASK [ensure-docker : Assure docker.socket service is running] 2025-11-22 00:15:30.420634 | controller | ok 2025-11-22 00:15:30.439988 | 2025-11-22 00:15:30.440196 | TASK [ensure-docker : Correct group ownership on docker sock] 2025-11-22 00:15:31.065529 | controller | ok 2025-11-22 00:15:31.076620 | 2025-11-22 00:15:31.076830 | TASK [ensure-docker : Validate ability to talk with docker] 2025-11-22 00:15:31.590013 | controller | CONTAINER ID IMAGE COMMAND CREATED STATUS PORTS NAMES 2025-11-22 00:15:31.633031 | controller | ok: Runtime: 0:00:00.054664 2025-11-22 00:15:31.639854 | 2025-11-22 00:15:31.639970 | TASK [ensure-docker : Get version details] 2025-11-22 00:15:32.262915 | controller | Client: Docker Engine - Community 2025-11-22 00:15:32.262958 | controller | Version: 24.0.7 2025-11-22 00:15:32.262965 | controller | API version: 1.43 2025-11-22 00:15:32.262973 | controller | Go version: go1.20.10 2025-11-22 00:15:32.262984 | controller | Git commit: afdd53b 2025-11-22 00:15:32.262990 | controller | Built: Thu Oct 26 09:09:39 2023 2025-11-22 00:15:32.262995 | controller | OS/Arch: linux/amd64 2025-11-22 00:15:32.263001 | controller | Context: default 2025-11-22 00:15:32.263006 | controller | 2025-11-22 00:15:32.263011 | controller | Server: Docker Engine - Community 2025-11-22 00:15:32.263016 | controller | Engine: 2025-11-22 00:15:32.263022 | controller | Version: 24.0.7 2025-11-22 00:15:32.263053 | controller | API version: 1.43 (minimum version 1.12) 2025-11-22 00:15:32.263060 | controller | Go version: go1.20.10 2025-11-22 00:15:32.263064 | controller | Git commit: 311b9ff 2025-11-22 00:15:32.263079 | controller | Built: Thu Oct 26 09:08:11 2023 2025-11-22 00:15:32.263084 | controller | OS/Arch: linux/amd64 2025-11-22 00:15:32.263088 | controller | Experimental: false 2025-11-22 00:15:32.263092 | controller | containerd: 2025-11-22 00:15:32.263096 | controller | Version: 1.6.25 2025-11-22 00:15:32.263107 | controller | GitCommit: d8f198a4ed8892c764191ef7b3b06d8a2eeb5c7f 2025-11-22 00:15:32.263111 | controller | runc: 2025-11-22 00:15:32.263118 | controller | Version: 1.1.10 2025-11-22 00:15:32.264505 | controller | GitCommit: v1.1.10-0-g18a0cb0 2025-11-22 00:15:32.264516 | controller | docker-init: 2025-11-22 00:15:32.264520 | controller | Version: 0.19.0 2025-11-22 00:15:32.264525 | controller | GitCommit: de40ad0 2025-11-22 00:15:32.676238 | controller | ok: Runtime: 0:00:00.088474 2025-11-22 00:15:32.686644 | 2025-11-22 00:15:32.686795 | TASK [ensure-docker : Dump installed docker details] 2025-11-22 00:15:32.730182 | {'changed': True, 'stdout': 'Client: Docker Engine - Community\n Version: 24.0.7\n API version: 1.43\n Go version: go1.20.10\n Git commit: afdd53b\n Built: Thu Oct 26 09:09:39 2023\n OS/Arch: linux/amd64\n Context: default\n\nServer: Docker Engine - Community\n Engine:\n Version: 24.0.7\n API version: 1.43 (minimum version 1.12)\n Go version: go1.20.10\n Git commit: 311b9ff\n Built: Thu Oct 26 09:08:11 2023\n OS/Arch: linux/amd64\n Experimental: false\n containerd:\n Version: 1.6.25\n GitCommit: d8f198a4ed8892c764191ef7b3b06d8a2eeb5c7f\n runc:\n Version: 1.1.10\n GitCommit: v1.1.10-0-g18a0cb0\n docker-init:\n Version: 0.19.0\n GitCommit: de40ad0', 'stderr': '', 'rc': 0, 'cmd': ['docker', 'version'], 'start': '2025-11-22 00:15:32.176253', 'end': '2025-11-22 00:15:32.264727', 'delta': '0:00:00.088474', 'msg': '', 'zuul_log_id': 'fa163e3b-3c83-72da-3aa4-000000000061-1-controller', 'stdout_lines': ['Client: Docker Engine - Community', ' Version: 24.0.7', ' API version: 1.43', ' Go version: go1.20.10', ' Git commit: afdd53b', ' Built: Thu Oct 26 09:09:39 2023', ' OS/Arch: linux/amd64', ' Context: default', '', 'Server: Docker Engine - Community', ' Engine:', ' Version: 24.0.7', ' API version: 1.43 (minimum version 1.12)', ' Go version: go1.20.10', ' Git commit: 311b9ff', ' Built: Thu Oct 26 09:08:11 2023', ' OS/Arch: linux/amd64', ' Experimental: false', ' containerd:', ' Version: 1.6.25', ' GitCommit: d8f198a4ed8892c764191ef7b3b06d8a2eeb5c7f', ' runc:', ' Version: 1.1.10', ' GitCommit: v1.1.10-0-g18a0cb0', ' docker-init:', ' Version: 0.19.0', ' GitCommit: de40ad0'], 'stderr_lines': [], 'failed': False} 2025-11-22 00:15:32.737666 | 2025-11-22 00:15:32.737797 | TASK [Use buildset registry] 2025-11-22 00:15:32.752792 | controller | skipping: Conditional result was False 2025-11-22 00:15:32.770272 | 2025-11-22 00:15:32.770383 | TASK [Ensure python3.8 is present] 2025-11-22 00:15:32.784014 | controller | skipping: Conditional result was False 2025-11-22 00:15:32.791269 | 2025-11-22 00:15:32.791385 | TASK [Run our-ensure-python role (Fedora only for now)] 2025-11-22 00:15:32.815561 | controller | ok 2025-11-22 00:15:32.836612 | 2025-11-22 00:15:32.836721 | TASK [our-ensure-python : Install the right Python version (rpm)] 2025-11-22 00:15:34.943260 | controller | ok: Nothing to do 2025-11-22 00:15:34.958858 | 2025-11-22 00:15:34.959042 | TASK [our-ensure-python : Also install python3-devel] 2025-11-22 00:15:45.886814 | controller | changed 2025-11-22 00:15:45.913373 | 2025-11-22 00:15:45.913481 | TASK [Run ensure-virtualenv role] 2025-11-22 00:15:45.936973 | controller | ok 2025-11-22 00:15:45.969150 | 2025-11-22 00:15:45.969271 | TASK [ensure-virtualenv : Check if virtualenv is installed] 2025-11-22 00:15:46.200852 | controller | /usr/bin/virtualenv 2025-11-22 00:15:46.504447 | controller | ok: Runtime: 0:00:00.004714 2025-11-22 00:15:46.525380 | 2025-11-22 00:15:46.525551 | LOOP [ensure-virtualenv : Install virtualenv package] 2025-11-22 00:15:46.557845 | controller | skipping: Conditional result was False 2025-11-22 00:15:46.558126 | controller | changed: All items complete 2025-11-22 00:15:46.558162 | 2025-11-22 00:15:46.582807 | 2025-11-22 00:15:46.582900 | TASK [Find the full path of the Python interpreter] 2025-11-22 00:15:46.803463 | controller | /usr/bin/python3.8 2025-11-22 00:15:47.124557 | controller | ok 2025-11-22 00:15:47.131407 | 2025-11-22 00:15:47.131472 | TASK [Create virtualenv for ansible-test with a specific interpreter] 2025-11-22 00:15:48.752187 | controller | created virtual environment CPython3.8.18.final.0-64 in 825ms 2025-11-22 00:15:48.792109 | controller | creator CPython3Posix(dest=/home/zuul/venv, clear=False, no_vcs_ignore=False, global=False) 2025-11-22 00:15:48.792144 | 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-22 00:15:48.792155 | controller | added seed packages: pip==22.2.2, setuptools==62.6.0, wheel==0.37.1 2025-11-22 00:15:48.792167 | controller | activators BashActivator,CShellActivator,FishActivator,NushellActivator,PowerShellActivator,PythonActivator 2025-11-22 00:15:49.174935 | controller | changed 2025-11-22 00:15:49.184912 | 2025-11-22 00:15:49.185032 | TASK [Set selinux package] 2025-11-22 00:15:49.209608 | controller | ok 2025-11-22 00:15:49.217823 | 2025-11-22 00:15:49.217941 | TASK [Set selinux package (Fedora)] 2025-11-22 00:15:49.254428 | controller | ok 2025-11-22 00:15:49.269289 | 2025-11-22 00:15:49.269452 | TASK [Install selinux into virtualenv] 2025-11-22 00:15:52.105341 | controller | Collecting selinux-please-lie-to-me 2025-11-22 00:15:52.186146 | controller | Downloading selinux_please_lie_to_me-1.0.1-py2.py3-none-any.whl (3.8 kB) 2025-11-22 00:15:53.198475 | controller | Collecting setuptools<50.0.0 2025-11-22 00:15:53.209430 | controller | Downloading setuptools-49.6.0-py3-none-any.whl (803 kB) 2025-11-22 00:15:53.265784 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 803.3/803.3 kB 16.0 MB/s eta 0:00:00 2025-11-22 00:15:53.349522 | controller | Installing collected packages: setuptools, selinux-please-lie-to-me 2025-11-22 00:15:53.349712 | controller | Attempting uninstall: setuptools 2025-11-22 00:15:53.350396 | controller | Found existing installation: setuptools 62.6.0 2025-11-22 00:15:53.426196 | controller | Uninstalling setuptools-62.6.0: 2025-11-22 00:15:53.435075 | controller | Successfully uninstalled setuptools-62.6.0 2025-11-22 00:15:54.236869 | controller | Successfully installed selinux-please-lie-to-me-1.0.1 setuptools-49.6.0 2025-11-22 00:15:54.373159 | controller | 2025-11-22 00:15:54.534588 | controller | [notice] A new release of pip available: 22.2.2 -> 25.0.1 2025-11-22 00:15:54.534601 | controller | [notice] To update, run: /home/zuul/venv/bin/python -m pip install --upgrade pip 2025-11-22 00:15:54.827745 | controller | ok: Runtime: 0:00:04.721294 2025-11-22 00:15:54.840859 | 2025-11-22 00:15:54.841017 | TASK [Install pytest-forked into virtualenv] 2025-11-22 00:20:37.836901 | [Zuul] Log Stream did not terminate 2025-11-22 00:20:37.838020 | controller | ERROR 2025-11-22 00:20:37.838229 | controller | { 2025-11-22 00:20:37.838291 | 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-22 00:20:37.838347 | controller | "unreachable": true 2025-11-22 00:20:37.838388 | controller | } unreachable 2025-11-22 00:20:37.841108 | 2025-11-22 00:20:37.841217 | PLAY RECAP 2025-11-22 00:20:37.841307 | controller | ok: 35 changed: 18 unreachable: 1 failed: 0 skipped: 15 rescued: 0 ignored: 0 2025-11-22 00:20:37.841359 | 2025-11-22 00:20:38.018607 | PRE-RUN END RESULT_UNREACHABLE: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master] 2025-11-22 00:20:38.020578 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2025-11-22 00:20:38.641424 | 2025-11-22 00:20:38.641548 | PLAY [all] 2025-11-22 00:20:38.663364 | 2025-11-22 00:20:38.663472 | TASK [fetch-subunit-output : Find stestr or testr executable] 2025-11-22 00:20:57.142105 | controller | changed: non-zero return code 2025-11-22 00:20:57.155282 | 2025-11-22 00:20:57.155440 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2025-11-22 00:20:57.183624 | controller | skipping: Conditional result was False 2025-11-22 00:20:57.197158 | 2025-11-22 00:20:57.197312 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2025-11-22 00:20:57.240423 | 2025-11-22 00:20:57.240756 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2025-11-22 00:20:57.280581 | 2025-11-22 00:20:57.280909 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2025-11-22 00:20:57.308344 | controller | skipping: Conditional result was False 2025-11-22 00:20:57.323569 | 2025-11-22 00:20:57.323801 | LOOP [fetch-subunit-output : Generate subunit file] 2025-11-22 00:20:57.364115 | 2025-11-22 00:20:57.364403 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2025-11-22 00:20:57.390772 | controller | skipping: Conditional result was False 2025-11-22 00:20:57.406934 | 2025-11-22 00:20:57.407128 | TASK [fetch-subunit-output : Remove the temporary file] 2025-11-22 00:20:57.433873 | controller | skipping: Conditional result was False 2025-11-22 00:20:57.450008 | 2025-11-22 00:20:57.450214 | TASK [fetch-subunit-output : Process and fetch subunit results] 2025-11-22 00:20:57.477123 | controller | skipping: Conditional result was False 2025-11-22 00:20:57.531025 | 2025-11-22 00:20:57.531151 | PLAY RECAP 2025-11-22 00:20:57.531207 | controller | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2025-11-22 00:20:57.531235 | 2025-11-22 00:20:57.659516 | POST-RUN END RESULT_UNREACHABLE: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2025-11-22 00:20:57.660672 | POST-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/post.yaml@master] 2025-11-22 00:20:58.278937 | 2025-11-22 00:20:58.279060 | PLAY [all:!appliance*] 2025-11-22 00:20:58.299982 | 2025-11-22 00:20:58.300097 | TASK [unregister the node] 2025-11-22 00:20:58.831999 | controller | skipping: Conditional result was False 2025-11-22 00:20:58.845023 | 2025-11-22 00:20:58.845239 | TASK [include_role : fetch-output] 2025-11-22 00:20:58.895502 | controller | ok 2025-11-22 00:20:58.941848 | 2025-11-22 00:20:58.941998 | TASK [fetch-output : Set log path for multiple nodes] 2025-11-22 00:20:59.018288 | controller | skipping: Conditional result was False 2025-11-22 00:20:59.031649 | 2025-11-22 00:20:59.031858 | TASK [fetch-output : Set log path for single node] 2025-11-22 00:20:59.080419 | controller | ok 2025-11-22 00:20:59.092201 | 2025-11-22 00:20:59.092340 | LOOP [fetch-output : Ensure local output dirs] 2025-11-22 00:20:59.546238 | controller -> localhost | ok: "/var/lib/zuul/builds/71cd02fe6c904f9cb647f098e6300d59/work/logs" 2025-11-22 00:20:59.811193 | controller -> localhost | changed: "/var/lib/zuul/builds/71cd02fe6c904f9cb647f098e6300d59/work/artifacts" 2025-11-22 00:21:00.088997 | controller -> localhost | changed: "/var/lib/zuul/builds/71cd02fe6c904f9cb647f098e6300d59/work/docs" 2025-11-22 00:21:00.109080 | 2025-11-22 00:21:00.109279 | LOOP [fetch-output : Collect logs, artifacts and docs] 2025-11-22 00:21:00.800951 | controller | changed: 2025-11-22 00:21:00.801281 | controller | .d..t...... ./ 2025-11-22 00:21:00.801325 | controller | cd+++++++++ controller/ 2025-11-22 00:21:00.801373 | controller | changed: All items complete 2025-11-22 00:21:00.801403 | 2025-11-22 00:21:01.317556 | controller | changed: .d..t...... ./ 2025-11-22 00:21:01.913304 | controller | changed: .d..t...... ./ 2025-11-22 00:21:01.949253 | 2025-11-22 00:21:01.949439 | TASK [include_role : fetch-output-openshift] 2025-11-22 00:21:01.966524 | controller | skipping: Conditional result was False 2025-11-22 00:21:01.980478 | 2025-11-22 00:21:01.980655 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2025-11-22 00:21:02.015666 | controller | skipping: Conditional result was False 2025-11-22 00:21:02.028844 | controller | skipping: Conditional result was False 2025-11-22 00:21:02.089699 | 2025-11-22 00:21:02.089827 | PLAY [localhost] 2025-11-22 00:21:02.107203 | 2025-11-22 00:21:02.107374 | TASK [Run Zuul manifest role] 2025-11-22 00:21:02.127769 | localhost | ok 2025-11-22 00:21:02.148427 | 2025-11-22 00:21:02.148569 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-11-22 00:21:02.504664 | localhost | changed 2025-11-22 00:21:02.511224 | 2025-11-22 00:21:02.511313 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-11-22 00:21:02.556196 | localhost | ok 2025-11-22 00:21:02.568568 | 2025-11-22 00:21:02.568728 | TASK [Set zuul-log-path fact] 2025-11-22 00:21:02.589895 | localhost | ok 2025-11-22 00:21:02.611257 | 2025-11-22 00:21:02.611368 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-11-22 00:21:02.652096 | localhost | ok 2025-11-22 00:21:02.662932 | 2025-11-22 00:21:02.663012 | LOOP [Run upload-logs-swift role] 2025-11-22 00:21:02.698460 | localhost | Output suppressed because no_log was given 2025-11-22 00:21:02.747799 | 2025-11-22 00:21:02.747941 | TASK [Set zuul-log-path fact] 2025-11-22 00:21:02.783275 | localhost | skipping: Conditional result was False 2025-11-22 00:21:02.790760 | 2025-11-22 00:21:02.790851 | TASK [upload-logs-swift : Ensure logs are readable before uploading] 2025-11-22 00:21:03.234566 | localhost -> localhost | ok: Runtime: 0:00:00.009491 2025-11-22 00:21:03.263172 | 2025-11-22 00:21:03.263309 | TASK [upload-logs-swift : Upload logs to swift]