2026-03-01 00:07:00.204239 | Job console starting... 2026-03-01 00:07:00.217336 | Updating repositories 2026-03-01 00:07:03.018153 | Preparing job workspace 2026-03-01 00:07:07.239272 | Running Ansible setup... 2026-03-01 00:07:12.412520 | PRE-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master] 2026-03-01 00:07:13.023751 | 2026-03-01 00:07:13.023897 | PLAY [localhost] 2026-03-01 00:07:13.032465 | 2026-03-01 00:07:13.032560 | TASK [Gathering Facts] 2026-03-01 00:07:14.058510 | localhost | ok 2026-03-01 00:07:14.073837 | 2026-03-01 00:07:14.073968 | TASK [Setup log path fact] 2026-03-01 00:07:14.093304 | localhost | ok 2026-03-01 00:07:14.106655 | 2026-03-01 00:07:14.106795 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-03-01 00:07:14.135175 | localhost | ok 2026-03-01 00:07:14.143271 | 2026-03-01 00:07:14.143347 | TASK [emit-job-header : Print job information] 2026-03-01 00:07:14.172271 | # Job Information 2026-03-01 00:07:14.172460 | Ansible Version: 2.15.12 2026-03-01 00:07:14.172492 | Job: ansible-test-sanity-docker-stable-2.11 2026-03-01 00:07:14.172515 | Pipeline: periodic 2026-03-01 00:07:14.172536 | Executor: ze02.softwarefactory-project.io 2026-03-01 00:07:14.172556 | Triggered by: https://github.com/ansible-collections/ansible.network 2026-03-01 00:07:14.172579 | Log URL (when completed): https://ansible.softwarefactory-project.io/logs/245/ansible/245564b211174d089341eb8c77cad02f/ 2026-03-01 00:07:14.172599 | Event ID: 9a68ce5367a74382a3f0efb74cca8a21 2026-03-01 00:07:14.177266 | 2026-03-01 00:07:14.177334 | LOOP [emit-job-header : Print node information] 2026-03-01 00:07:14.291450 | localhost | ok: 2026-03-01 00:07:14.291758 | localhost | # Node Information 2026-03-01 00:07:14.291801 | localhost | Inventory Hostname: controller 2026-03-01 00:07:14.291831 | localhost | Hostname: np0005634380 2026-03-01 00:07:14.291859 | localhost | Username: zuul 2026-03-01 00:07:14.292078 | localhost | Distro: Fedora 37 2026-03-01 00:07:14.292125 | localhost | Provider: ansible-vexxhost-ca-ymq-1 2026-03-01 00:07:14.292153 | localhost | Region: ca-ymq-1 2026-03-01 00:07:14.292176 | localhost | Label: ansible-fedora-37-1vcpu 2026-03-01 00:07:14.292201 | localhost | Product Name: OpenStack Nova 2026-03-01 00:07:14.292226 | localhost | Interface IP: 162.253.55.70 2026-03-01 00:07:14.301203 | 2026-03-01 00:07:14.301314 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-03-01 00:07:14.754903 | localhost -> localhost | changed 2026-03-01 00:07:14.767109 | 2026-03-01 00:07:14.767271 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-03-01 00:07:15.733338 | localhost -> localhost | changed 2026-03-01 00:07:15.774109 | 2026-03-01 00:07:15.774200 | PLAY [all:!appliance*] 2026-03-01 00:07:15.798083 | 2026-03-01 00:07:15.798182 | TASK [include_role : start-zuul-console] 2026-03-01 00:07:15.821791 | controller | ok 2026-03-01 00:07:15.841764 | 2026-03-01 00:07:15.841830 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-03-01 00:07:16.267839 | controller | ok 2026-03-01 00:07:16.297379 | 2026-03-01 00:07:16.297601 | TASK [use-our-mirror : Retrieve the IP address] 2026-03-01 00:07:17.176586 | controller | ok: Status code was 404 and not [200]: HTTP Error 404: Not Found 2026-03-01 00:07:17.192398 | 2026-03-01 00:07:17.192531 | TASK [use-our-mirror : Ensure the registry service is running on the mirror] 2026-03-01 00:07:17.743970 | controller | skipping: Conditional result was False 2026-03-01 00:07:17.756455 | 2026-03-01 00:07:17.756655 | TASK [use-our-mirror : Write the IP in /etc/hosts] 2026-03-01 00:07:17.781950 | controller | skipping: Conditional result was False 2026-03-01 00:07:17.792929 | 2026-03-01 00:07:17.793040 | TASK [use-our-mirror : Create the podman configuration directory] 2026-03-01 00:07:17.818066 | controller | skipping: Conditional result was False 2026-03-01 00:07:17.828250 | 2026-03-01 00:07:17.828375 | TASK [use-our-mirror : Copy the podman configuration] 2026-03-01 00:07:17.853886 | controller | skipping: Conditional result was False 2026-03-01 00:07:17.862953 | 2026-03-01 00:07:17.863057 | TASK [use-our-mirror : Copy the Yum configuration (main)] 2026-03-01 00:07:17.898824 | controller | skipping: Conditional result was False 2026-03-01 00:07:17.907355 | 2026-03-01 00:07:17.907446 | TASK [use-our-mirror : Copy the Yum configuration (update)] 2026-03-01 00:07:17.943628 | controller | skipping: Conditional result was False 2026-03-01 00:07:17.960649 | 2026-03-01 00:07:17.960757 | TASK [Disable Fedora Modular] 2026-03-01 00:07:18.222609 | controller | changed 2026-03-01 00:07:18.235264 | 2026-03-01 00:07:18.235410 | TASK [Enable EPEL] 2026-03-01 00:07:18.275057 | controller | skipping: Conditional result was False 2026-03-01 00:07:18.288968 | 2026-03-01 00:07:18.289119 | TASK [Register the RHEL node] 2026-03-01 00:07:18.860717 | 2026-03-01 00:07:18.861059 | TASK [Show the subscription-manager status] 2026-03-01 00:07:19.445056 | controller | skipping: Conditional result was False 2026-03-01 00:07:19.451714 | 2026-03-01 00:07:19.451781 | TASK [Enable EPEL on RHEL] 2026-03-01 00:07:19.985724 | controller | skipping: Conditional result was False 2026-03-01 00:07:19.993355 | 2026-03-01 00:07:19.993543 | TASK [Install git and tox] 2026-03-01 00:08:48.070437 | controller | changed 2026-03-01 00:08:48.077743 | 2026-03-01 00:08:48.077810 | TASK [include_role : prepare-workspace] 2026-03-01 00:08:48.113780 | controller | ok 2026-03-01 00:08:48.135185 | 2026-03-01 00:08:48.135317 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-03-01 00:08:48.378532 | controller | ok 2026-03-01 00:08:48.385305 | 2026-03-01 00:08:48.385366 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-03-01 00:14:40.096926 | controller | Output suppressed because no_log was given 2026-03-01 00:14:40.113208 | 2026-03-01 00:14:40.113302 | TASK [include_role : prepare-workspace-openshift] 2026-03-01 00:14:40.150211 | controller | skipping: Conditional result was False 2026-03-01 00:14:40.187553 | 2026-03-01 00:14:40.187709 | PLAY [all:!appliance] 2026-03-01 00:14:40.223109 | 2026-03-01 00:14:40.223298 | TASK [Run add-build-sshkey role (RSA)] 2026-03-01 00:14:40.267954 | controller | ok 2026-03-01 00:14:40.292225 | 2026-03-01 00:14:40.292410 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-03-01 00:14:40.568370 | controller -> localhost | ok 2026-03-01 00:14:40.579173 | 2026-03-01 00:14:40.579277 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-03-01 00:14:40.618431 | controller | ok 2026-03-01 00:14:40.643145 | controller | included: /var/lib/zuul/builds/245564b211174d089341eb8c77cad02f/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-03-01 00:14:40.652375 | 2026-03-01 00:14:40.652526 | TASK [add-build-sshkey : Create Temp SSH key] 2026-03-01 00:14:41.293695 | controller -> localhost | Generating public/private rsa key pair. 2026-03-01 00:14:41.294101 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/245564b211174d089341eb8c77cad02f/work/245564b211174d089341eb8c77cad02f_id_rsa. 2026-03-01 00:14:41.294203 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/245564b211174d089341eb8c77cad02f/work/245564b211174d089341eb8c77cad02f_id_rsa.pub. 2026-03-01 00:14:41.294238 | controller -> localhost | The key fingerprint is: 2026-03-01 00:14:41.294266 | controller -> localhost | SHA256:FsddXMfdylWq1YLnoJ6ej7b9gU5HsKUCZ39igVTbJUo zuul-build-sshkey 2026-03-01 00:14:41.294291 | controller -> localhost | The key's randomart image is: 2026-03-01 00:14:41.294316 | controller -> localhost | +---[RSA 2048]----+ 2026-03-01 00:14:41.294340 | controller -> localhost | | ..E.ooO| 2026-03-01 00:14:41.294365 | controller -> localhost | | o + *.=*| 2026-03-01 00:14:41.294388 | controller -> localhost | | o * O.Bo.| 2026-03-01 00:14:41.294412 | controller -> localhost | | * o %o. | 2026-03-01 00:14:41.294435 | controller -> localhost | | S o B + | 2026-03-01 00:14:41.294473 | controller -> localhost | | . . + = | 2026-03-01 00:14:41.294511 | controller -> localhost | | o o o | 2026-03-01 00:14:41.294537 | controller -> localhost | | ..* . . | 2026-03-01 00:14:41.294562 | controller -> localhost | | .=o+.. | 2026-03-01 00:14:41.294589 | controller -> localhost | +----[SHA256]-----+ 2026-03-01 00:14:41.294655 | controller -> localhost | ok: Runtime: 0:00:00.199727 2026-03-01 00:14:41.304358 | 2026-03-01 00:14:41.304478 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-03-01 00:14:41.338834 | controller | ok 2026-03-01 00:14:41.353779 | controller | included: /var/lib/zuul/builds/245564b211174d089341eb8c77cad02f/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-03-01 00:14:41.366877 | 2026-03-01 00:14:41.366971 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-03-01 00:14:41.403358 | controller | skipping: Conditional result was False 2026-03-01 00:14:41.412795 | 2026-03-01 00:14:41.412897 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-03-01 00:14:42.084191 | controller | changed 2026-03-01 00:14:42.090638 | 2026-03-01 00:14:42.092189 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-03-01 00:14:42.345933 | controller | ok 2026-03-01 00:14:42.354968 | 2026-03-01 00:14:42.355063 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-03-01 00:14:42.983240 | controller | changed 2026-03-01 00:14:42.989201 | 2026-03-01 00:14:42.989267 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-03-01 00:14:43.630797 | controller | changed 2026-03-01 00:14:43.646953 | 2026-03-01 00:14:43.647104 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-03-01 00:14:43.676187 | controller | skipping: Conditional result was False 2026-03-01 00:14:43.688030 | 2026-03-01 00:14:43.688177 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-03-01 00:14:44.171161 | controller -> localhost | changed 2026-03-01 00:14:44.184270 | 2026-03-01 00:14:44.184363 | TASK [add-build-sshkey : Add back temp key] 2026-03-01 00:14:44.539841 | controller -> localhost | Identity added: /var/lib/zuul/builds/245564b211174d089341eb8c77cad02f/work/245564b211174d089341eb8c77cad02f_id_rsa (zuul-build-sshkey) 2026-03-01 00:14:44.540326 | controller -> localhost | ok: Runtime: 0:00:00.017740 2026-03-01 00:14:44.556275 | 2026-03-01 00:14:44.556414 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-03-01 00:14:44.905484 | controller | ok 2026-03-01 00:14:44.918936 | 2026-03-01 00:14:44.919094 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-03-01 00:14:44.957789 | controller | skipping: Conditional result was False 2026-03-01 00:14:44.985819 | 2026-03-01 00:14:44.985967 | TASK [Run add-build-sshkey role (ECDSA)] 2026-03-01 00:14:45.021231 | controller | ok 2026-03-01 00:14:45.044549 | 2026-03-01 00:14:45.044653 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-03-01 00:14:45.335616 | controller -> localhost | ok 2026-03-01 00:14:45.343202 | 2026-03-01 00:14:45.343272 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-03-01 00:14:45.376715 | controller | ok 2026-03-01 00:14:45.388345 | controller | included: /var/lib/zuul/builds/245564b211174d089341eb8c77cad02f/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-03-01 00:14:45.394360 | 2026-03-01 00:14:45.394427 | TASK [add-build-sshkey : Create Temp SSH key] 2026-03-01 00:14:45.677260 | controller -> localhost | Generating public/private ecdsa key pair. 2026-03-01 00:14:45.677550 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/245564b211174d089341eb8c77cad02f/work/245564b211174d089341eb8c77cad02f_id_ecdsa. 2026-03-01 00:14:45.677590 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/245564b211174d089341eb8c77cad02f/work/245564b211174d089341eb8c77cad02f_id_ecdsa.pub. 2026-03-01 00:14:45.677632 | controller -> localhost | The key fingerprint is: 2026-03-01 00:14:45.677661 | controller -> localhost | SHA256:2Js5Hie1MW0KY8NgEOI+kVD8sgeKrEPLWoRmF9gEJeY zuul-build-sshkey 2026-03-01 00:14:45.677717 | controller -> localhost | The key's randomart image is: 2026-03-01 00:14:45.677745 | controller -> localhost | +---[ECDSA 521]---+ 2026-03-01 00:14:45.677770 | controller -> localhost | |.*=oo. | 2026-03-01 00:14:45.677793 | controller -> localhost | |oo*o . | 2026-03-01 00:14:45.677817 | controller -> localhost | | E++ o | 2026-03-01 00:14:45.677840 | controller -> localhost | | oo.o. = . | 2026-03-01 00:14:45.677863 | controller -> localhost | |+++= . S + o | 2026-03-01 00:14:45.677886 | controller -> localhost | |=+o.. . O * | 2026-03-01 00:14:45.677908 | controller -> localhost | |+ o. B + | 2026-03-01 00:14:45.677931 | controller -> localhost | |o+ . = | 2026-03-01 00:14:45.677954 | controller -> localhost | |o. . | 2026-03-01 00:14:45.677977 | controller -> localhost | +----[SHA256]-----+ 2026-03-01 00:14:45.678233 | controller -> localhost | ok: Runtime: 0:00:00.009235 2026-03-01 00:14:45.688265 | 2026-03-01 00:14:45.688358 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-03-01 00:14:45.723288 | controller | ok 2026-03-01 00:14:45.736042 | controller | included: /var/lib/zuul/builds/245564b211174d089341eb8c77cad02f/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-03-01 00:14:45.753622 | 2026-03-01 00:14:45.753724 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-03-01 00:14:45.779430 | controller | skipping: Conditional result was False 2026-03-01 00:14:45.789447 | 2026-03-01 00:14:45.789541 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-03-01 00:14:46.066531 | controller | changed 2026-03-01 00:14:46.080508 | 2026-03-01 00:14:46.080743 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-03-01 00:14:46.323294 | controller | ok 2026-03-01 00:14:46.330849 | 2026-03-01 00:14:46.330926 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-03-01 00:14:46.959544 | controller | changed 2026-03-01 00:14:46.973182 | 2026-03-01 00:14:46.973329 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-03-01 00:14:47.638284 | controller | changed 2026-03-01 00:14:47.644434 | 2026-03-01 00:14:47.644499 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-03-01 00:14:47.690629 | controller | skipping: Conditional result was False 2026-03-01 00:14:47.746034 | 2026-03-01 00:14:47.746219 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-03-01 00:14:48.025548 | controller -> localhost | changed 2026-03-01 00:14:48.037428 | 2026-03-01 00:14:48.037491 | TASK [add-build-sshkey : Add back temp key] 2026-03-01 00:14:48.327093 | controller -> localhost | Identity added: /var/lib/zuul/builds/245564b211174d089341eb8c77cad02f/work/245564b211174d089341eb8c77cad02f_id_ecdsa (zuul-build-sshkey) 2026-03-01 00:14:48.327313 | controller -> localhost | ok: Runtime: 0:00:00.016425 2026-03-01 00:14:48.337233 | 2026-03-01 00:14:48.337307 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-03-01 00:14:48.569744 | controller | ok 2026-03-01 00:14:48.586071 | 2026-03-01 00:14:48.586240 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-03-01 00:14:48.635587 | controller | skipping: Conditional result was False 2026-03-01 00:14:48.653240 | 2026-03-01 00:14:48.653362 | TASK [include_role : remove-zuul-sshkey] 2026-03-01 00:14:48.678955 | controller | skipping: Conditional result was False 2026-03-01 00:14:48.689019 | 2026-03-01 00:14:48.689118 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-03-01 00:14:48.921919 | controller | ok: "logs" 2026-03-01 00:14:48.922332 | controller | ok: All items complete 2026-03-01 00:14:48.922396 | 2026-03-01 00:14:49.123642 | controller | ok: "artifacts" 2026-03-01 00:14:49.317067 | controller | ok: "docs" 2026-03-01 00:14:49.333360 | 2026-03-01 00:14:49.333463 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-03-01 00:14:49.578415 | controller | changed: "logs" 2026-03-01 00:14:49.777400 | controller | changed: "artifacts" 2026-03-01 00:14:49.979807 | controller | changed: "docs" 2026-03-01 00:14:50.039448 | 2026-03-01 00:14:50.039569 | PLAY RECAP 2026-03-01 00:14:50.039631 | controller | ok: 30 changed: 16 unreachable: 0 failed: 0 skipped: 18 rescued: 0 ignored: 0 2026-03-01 00:14:50.039700 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-03-01 00:14:50.039736 | 2026-03-01 00:14:50.226997 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master] 2026-03-01 00:14:50.228087 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2026-03-01 00:14:50.789907 | 2026-03-01 00:14:50.790106 | PLAY [all] 2026-03-01 00:14:50.825469 | 2026-03-01 00:14:50.825577 | TASK [Install binary dependencies] 2026-03-01 00:14:50.886712 | controller | ok 2026-03-01 00:14:50.908278 | 2026-03-01 00:14:50.908389 | TASK [bindep : Include find tasks] 2026-03-01 00:14:50.938900 | controller | ok 2026-03-01 00:14:50.948841 | controller | included: /var/lib/zuul/builds/245564b211174d089341eb8c77cad02f/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find.yaml 2026-03-01 00:14:50.955242 | 2026-03-01 00:14:50.955307 | TASK [bindep : Look for bindep.txt] 2026-03-01 00:14:51.518881 | controller | ok 2026-03-01 00:14:51.525401 | 2026-03-01 00:14:51.525485 | TASK [bindep : Define bindep_file fact] 2026-03-01 00:14:51.558168 | controller | skipping: Conditional result was False 2026-03-01 00:14:51.567201 | 2026-03-01 00:14:51.567279 | TASK [bindep : Look for other-requirements.txt] 2026-03-01 00:14:51.789472 | controller | ok 2026-03-01 00:14:51.806895 | 2026-03-01 00:14:51.807172 | TASK [bindep : Define bindep_file fact] 2026-03-01 00:14:51.846745 | controller | skipping: Conditional result was False 2026-03-01 00:14:51.861660 | 2026-03-01 00:14:51.861857 | TASK [bindep : Look for bindep fallback file] 2026-03-01 00:14:51.912310 | controller | skipping: Conditional result was False 2026-03-01 00:14:51.928594 | 2026-03-01 00:14:51.928788 | TASK [bindep : Define bindep_file fact] 2026-03-01 00:14:51.978294 | controller | skipping: Conditional result was False 2026-03-01 00:14:51.998080 | 2026-03-01 00:14:51.998347 | TASK [bindep : Include bindep tasks] 2026-03-01 00:14:52.036812 | controller | skipping: Conditional result was False 2026-03-01 00:14:52.045124 | 2026-03-01 00:14:52.045300 | TASK [bindep : Include install tasks] 2026-03-01 00:14:52.069304 | controller | skipping: Conditional result was False 2026-03-01 00:14:52.076271 | 2026-03-01 00:14:52.076337 | LOOP [bindep : Include package tasks] 2026-03-01 00:14:52.142162 | 2026-03-01 00:14:52.142301 | TASK [Run test-setup role] 2026-03-01 00:14:52.164689 | controller | ok 2026-03-01 00:14:52.182282 | 2026-03-01 00:14:52.182351 | TASK [test-setup : Check if project's tools/test-setup.sh exists] 2026-03-01 00:14:52.399501 | controller | ok 2026-03-01 00:14:52.407836 | 2026-03-01 00:14:52.407921 | TASK [test-setup : Run tools/test-setup.sh] 2026-03-01 00:14:52.950986 | controller | skipping: Conditional result was False 2026-03-01 00:14:52.976812 | 2026-03-01 00:14:52.976873 | PLAY RECAP 2026-03-01 00:14:52.976918 | controller | ok: 4 changed: 0 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2026-03-01 00:14:52.976938 | 2026-03-01 00:14:53.083541 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2026-03-01 00:14:53.086119 | PRE-RUN START: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master] 2026-03-01 00:14:53.747217 | 2026-03-01 00:14:53.747397 | PLAY [controller] 2026-03-01 00:14:53.769907 | 2026-03-01 00:14:53.770013 | TASK [Create the /root directory] 2026-03-01 00:14:54.364630 | controller | ok 2026-03-01 00:14:54.383508 | 2026-03-01 00:14:54.383777 | TASK [Install glibc-langpack-en] 2026-03-01 00:14:58.415857 | controller | ok: Nothing to do 2026-03-01 00:14:58.424840 | 2026-03-01 00:14:58.425047 | TASK [Ensure controller directory exists] 2026-03-01 00:14:58.633448 | controller | changed 2026-03-01 00:14:58.639414 | 2026-03-01 00:14:58.639487 | TASK [Install container runtime] 2026-03-01 00:14:58.715483 | controller | ok 2026-03-01 00:14:58.775500 | 2026-03-01 00:14:58.775703 | LOOP [ensure-docker : Gather variables for each operating system] 2026-03-01 00:14:58.825503 | controller | ok: "/var/lib/zuul/builds/245564b211174d089341eb8c77cad02f/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/vars/fedora.yaml" 2026-03-01 00:14:58.839395 | 2026-03-01 00:14:58.839572 | TASK [ensure-docker : Sanity check for distro selection] 2026-03-01 00:14:58.855000 | controller | skipping: Conditional result was False 2026-03-01 00:14:58.862191 | 2026-03-01 00:14:58.862257 | TASK [ensure-docker : Sanity check for distro install] 2026-03-01 00:14:58.886471 | controller | skipping: Conditional result was False 2026-03-01 00:14:58.892569 | 2026-03-01 00:14:58.892636 | TASK [ensure-docker : Sanity check for upstream install] 2026-03-01 00:14:58.916830 | controller | skipping: Conditional result was False 2026-03-01 00:14:58.927174 | 2026-03-01 00:14:58.927266 | TASK [ensure-docker : Sanity check for upstream install mirrors] 2026-03-01 00:14:58.947692 | controller | skipping: Conditional result was False 2026-03-01 00:14:58.958245 | 2026-03-01 00:14:58.958361 | TASK [ensure-docker : Include tasks for docker install] 2026-03-01 00:14:58.990252 | controller | ok 2026-03-01 00:14:59.003124 | controller | included: /var/lib/zuul/builds/245564b211174d089341eb8c77cad02f/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/tasks/docker-upstream.yaml 2026-03-01 00:14:59.013874 | 2026-03-01 00:14:59.013937 | TASK [ensure-docker : Not Implemented] 2026-03-01 00:14:59.029577 | controller | skipping: Conditional result was False 2026-03-01 00:14:59.036858 | 2026-03-01 00:14:59.036921 | TASK [Set up docker mirrors] 2026-03-01 00:14:59.058998 | controller | ok 2026-03-01 00:14:59.079242 | 2026-03-01 00:14:59.079326 | TASK [use-docker-mirror : Set docker_mirror fact from mirror_info] 2026-03-01 00:14:59.104292 | controller | skipping: Conditional result was False 2026-03-01 00:14:59.111111 | 2026-03-01 00:14:59.111177 | TASK [use-docker-mirror : Set docker_mirror fact from zuul_site_mirror_info] 2026-03-01 00:14:59.137259 | controller | skipping: Conditional result was False 2026-03-01 00:14:59.144303 | 2026-03-01 00:14:59.144472 | TASK [use-docker-mirror : Set docker_mirror fact] 2026-03-01 00:14:59.169229 | controller | skipping: Conditional result was False 2026-03-01 00:14:59.176306 | 2026-03-01 00:14:59.176376 | TASK [use-docker-mirror : Set up docker mirrors] 2026-03-01 00:14:59.201517 | controller | skipping: Conditional result was False 2026-03-01 00:14:59.214816 | 2026-03-01 00:14:59.214881 | TASK [Add all repositories] 2026-03-01 00:14:59.242594 | controller | ok 2026-03-01 00:14:59.268093 | 2026-03-01 00:14:59.268229 | LOOP [ensure-package-repositories : Install repositories] 2026-03-01 00:14:59.299778 | controller | ok: "/var/lib/zuul/builds/245564b211174d089341eb8c77cad02f/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-package-repositories/tasks/RedHat.yaml" 2026-03-01 00:14:59.299923 | controller | ok: All items complete 2026-03-01 00:14:59.299949 | 2026-03-01 00:14:59.359149 | controller | included: /var/lib/zuul/builds/245564b211174d089341eb8c77cad02f/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-package-repositories/tasks/RedHat.yaml 2026-03-01 00:14:59.365567 | 2026-03-01 00:14:59.365629 | TASK [ensure-package-repositories : Create GPG key temp directory] 2026-03-01 00:14:59.689351 | controller | changed 2026-03-01 00:14:59.703334 | 2026-03-01 00:14:59.703470 | LOOP [ensure-package-repositories : Upload GPG keys] 2026-03-01 00:15:00.628424 | controller | changed: 2026-03-01 00:15:00.628653 | controller | { 2026-03-01 00:15:00.628702 | 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" 2026-03-01 00:15:00.628726 | controller | } 2026-03-01 00:15:00.649260 | 2026-03-01 00:15:00.649531 | LOOP [ensure-package-repositories : Import GPG keys] 2026-03-01 00:15:01.248752 | controller | changed: 2026-03-01 00:15:01.249062 | controller | { 2026-03-01 00:15:01.249094 | 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" 2026-03-01 00:15:01.249123 | controller | } 2026-03-01 00:15:01.249158 | controller | changed: All items complete 2026-03-01 00:15:01.249179 | 2026-03-01 00:15:01.260272 | 2026-03-01 00:15:01.260507 | TASK [ensure-package-repositories : Remove GPG temp directory] 2026-03-01 00:15:01.509275 | controller | changed 2026-03-01 00:15:01.526743 | 2026-03-01 00:15:01.526890 | LOOP [ensure-package-repositories : Add repositories] 2026-03-01 00:15:01.944645 | controller | changed: 2026-03-01 00:15:01.945089 | controller | { 2026-03-01 00:15:01.945149 | controller | "baseurl": "https://download.docker.com/linux/fedora/$releasever/$basearch/stable", 2026-03-01 00:15:01.945192 | controller | "description": "Docker CE Stable - $basearch", 2026-03-01 00:15:01.945229 | controller | "gpgcheck": true, 2026-03-01 00:15:01.945292 | controller | "name": "docker-ce-stable" 2026-03-01 00:15:01.946269 | controller | } 2026-03-01 00:15:01.982212 | 2026-03-01 00:15:01.982356 | TASK [ensure-docker : Install pre-reqs] 2026-03-01 00:15:05.611521 | controller | ok: Nothing to do 2026-03-01 00:15:05.624480 | 2026-03-01 00:15:05.624614 | TASK [ensure-docker : Remove packages] 2026-03-01 00:15:06.957801 | controller | ok: Nothing to do 2026-03-01 00:15:06.974778 | 2026-03-01 00:15:06.975187 | TASK [ensure-docker : Install upstream docker using package] 2026-03-01 00:15:54.986734 | controller | changed 2026-03-01 00:15:55.003743 | 2026-03-01 00:15:55.003952 | TASK [ensure-docker : Setup docker] 2026-03-01 00:15:55.044074 | controller | ok 2026-03-01 00:15:55.061441 | controller | included: /var/lib/zuul/builds/245564b211174d089341eb8c77cad02f/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/tasks/docker-setup.yaml 2026-03-01 00:15:55.081076 | 2026-03-01 00:15:55.081177 | TASK [ensure-docker : Ensure "docker" group exists] 2026-03-01 00:15:55.502171 | controller | ok 2026-03-01 00:15:55.518755 | 2026-03-01 00:15:55.518900 | TASK [ensure-docker : Add user to docker group] 2026-03-01 00:15:56.049194 | controller | changed 2026-03-01 00:15:56.066782 | 2026-03-01 00:15:56.066928 | TASK [Update docker daemon proxy configuration] 2026-03-01 00:15:56.108043 | controller | skipping: Conditional result was False 2026-03-01 00:15:56.118466 | 2026-03-01 00:15:56.118554 | TASK [Lower default MTU] 2026-03-01 00:15:56.145114 | controller | skipping: Conditional result was False 2026-03-01 00:15:56.156846 | 2026-03-01 00:15:56.156941 | TASK [ensure-docker : Get the status of the docket.socket unit] 2026-03-01 00:15:56.614944 | controller | inactive 2026-03-01 00:15:56.703826 | controller | ok: Runtime: 0:00:00.017450 2026-03-01 00:15:56.712145 | 2026-03-01 00:15:56.712251 | TASK [ensure-docker : Get the status of the docket unit] 2026-03-01 00:15:56.970494 | controller | inactive 2026-03-01 00:15:57.254910 | controller | ok: Runtime: 0:00:00.009070 2026-03-01 00:15:57.269094 | 2026-03-01 00:15:57.269225 | TASK [ensure-docker : Restart docker] 2026-03-01 00:15:57.326096 | Trigger a Docker restart 2026-03-01 00:15:57.333795 | 2026-03-01 00:15:57.333882 | TASK [ensure-docker : Reset ssh connection to pick up docker group] 2026-03-01 00:15:57.369264 | 2026-03-01 00:15:57.369372 | TASK [ensure-docker : Install docker-compose] 2026-03-01 00:15:57.396441 | controller | skipping: Conditional result was False 2026-03-01 00:15:57.405410 | 2026-03-01 00:15:57.405513 | TASK [ensure-docker : Flush handlers before role exit] 2026-03-01 00:15:57.422155 | 2026-03-01 00:15:57.422248 | TASK [ensure-docker : Stop docker.socket to avoid any conflict] 2026-03-01 00:15:58.774972 | controller | changed 2026-03-01 00:15:58.804328 | 2026-03-01 00:15:58.804452 | TASK [ensure-docker : Assure docker service is running] 2026-03-01 00:16:00.993175 | controller | changed 2026-03-01 00:16:01.004427 | 2026-03-01 00:16:01.004520 | TASK [ensure-docker : Assure docker.socket service is running] 2026-03-01 00:16:02.027705 | controller | ok 2026-03-01 00:16:02.040255 | 2026-03-01 00:16:02.040363 | TASK [ensure-docker : Correct group ownership on docker sock] 2026-03-01 00:16:02.471457 | controller | ok 2026-03-01 00:16:02.484586 | 2026-03-01 00:16:02.484788 | TASK [ensure-docker : Validate ability to talk with docker] 2026-03-01 00:16:03.055538 | controller | CONTAINER ID IMAGE COMMAND CREATED STATUS PORTS NAMES 2026-03-01 00:16:03.543658 | controller | ok: Runtime: 0:00:00.039864 2026-03-01 00:16:03.558373 | 2026-03-01 00:16:03.558559 | TASK [ensure-docker : Get version details] 2026-03-01 00:16:04.219330 | controller | Client: Docker Engine - Community 2026-03-01 00:16:04.219594 | controller | Version: 24.0.7 2026-03-01 00:16:04.219700 | controller | API version: 1.43 2026-03-01 00:16:04.219824 | controller | Go version: go1.20.10 2026-03-01 00:16:04.219994 | controller | Git commit: afdd53b 2026-03-01 00:16:04.220191 | controller | Built: Thu Oct 26 09:09:39 2023 2026-03-01 00:16:04.220341 | controller | OS/Arch: linux/amd64 2026-03-01 00:16:04.220526 | controller | Context: default 2026-03-01 00:16:04.220584 | controller | 2026-03-01 00:16:04.220698 | controller | Server: Docker Engine - Community 2026-03-01 00:16:04.220786 | controller | Engine: 2026-03-01 00:16:04.221044 | controller | Version: 24.0.7 2026-03-01 00:16:04.221185 | controller | API version: 1.43 (minimum version 1.12) 2026-03-01 00:16:04.221309 | controller | Go version: go1.20.10 2026-03-01 00:16:04.221419 | controller | Git commit: 311b9ff 2026-03-01 00:16:04.221546 | controller | Built: Thu Oct 26 09:08:11 2023 2026-03-01 00:16:04.221698 | controller | OS/Arch: linux/amd64 2026-03-01 00:16:04.221904 | controller | Experimental: false 2026-03-01 00:16:04.221970 | controller | containerd: 2026-03-01 00:16:04.222138 | controller | Version: 1.6.25 2026-03-01 00:16:04.222266 | controller | GitCommit: d8f198a4ed8892c764191ef7b3b06d8a2eeb5c7f 2026-03-01 00:16:04.222334 | controller | runc: 2026-03-01 00:16:04.222511 | controller | Version: 1.1.10 2026-03-01 00:16:04.222659 | controller | GitCommit: v1.1.10-0-g18a0cb0 2026-03-01 00:16:04.222755 | controller | docker-init: 2026-03-01 00:16:04.222962 | controller | Version: 0.19.0 2026-03-01 00:16:04.223132 | controller | GitCommit: de40ad0 2026-03-01 00:16:04.624433 | controller | ok: Runtime: 0:00:00.058070 2026-03-01 00:16:04.631986 | 2026-03-01 00:16:04.632113 | TASK [ensure-docker : Dump installed docker details] 2026-03-01 00:16:04.666408 | {'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': '2026-03-01 00:16:04.166769', 'end': '2026-03-01 00:16:04.224839', 'delta': '0:00:00.058070', 'msg': '', 'zuul_log_id': 'fa163efc-24cc-f383-baef-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} 2026-03-01 00:16:04.673786 | 2026-03-01 00:16:04.673909 | TASK [Use buildset registry] 2026-03-01 00:16:04.702824 | controller | skipping: Conditional result was False 2026-03-01 00:16:04.723094 | 2026-03-01 00:16:04.723266 | TASK [Ensure python3.8 is present] 2026-03-01 00:16:04.738908 | controller | skipping: Conditional result was False 2026-03-01 00:16:04.747314 | 2026-03-01 00:16:04.747441 | TASK [Run our-ensure-python role (Fedora only for now)] 2026-03-01 00:16:04.780896 | controller | ok 2026-03-01 00:16:04.828527 | 2026-03-01 00:16:04.828706 | TASK [our-ensure-python : Install the right Python version (rpm)] 2026-03-01 00:20:54.872648 | controller | ERROR 2026-03-01 00:20:54.873026 | controller | { 2026-03-01 00:20:54.873092 | controller | "msg": "Data could not be sent to remote host \"162.253.55.70\". Make sure this host can be reached over ssh: ssh: connect to host 162.253.55.70 port 22: Connection timed out\r\n", 2026-03-01 00:20:54.873141 | controller | "unreachable": true 2026-03-01 00:20:54.873179 | controller | } unreachable 2026-03-01 00:20:54.877020 | 2026-03-01 00:20:54.877125 | PLAY RECAP 2026-03-01 00:20:54.877204 | controller | ok: 27 changed: 14 unreachable: 1 failed: 0 skipped: 14 rescued: 0 ignored: 0 2026-03-01 00:20:54.877249 | 2026-03-01 00:20:55.020510 | PRE-RUN END RESULT_UNREACHABLE: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master] 2026-03-01 00:20:55.021630 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2026-03-01 00:20:55.628449 | 2026-03-01 00:20:55.628583 | PLAY [all] 2026-03-01 00:20:55.649949 | 2026-03-01 00:20:55.650037 | TASK [fetch-subunit-output : Find stestr or testr executable] 2026-03-01 00:21:08.184412 | controller | changed: non-zero return code 2026-03-01 00:21:08.198509 | 2026-03-01 00:21:08.198714 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2026-03-01 00:21:08.226478 | controller | skipping: Conditional result was False 2026-03-01 00:21:08.235512 | 2026-03-01 00:21:08.235627 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2026-03-01 00:21:08.270575 | 2026-03-01 00:21:08.270899 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2026-03-01 00:21:08.305912 | 2026-03-01 00:21:08.306406 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2026-03-01 00:21:08.333075 | controller | skipping: Conditional result was False 2026-03-01 00:21:08.342229 | 2026-03-01 00:21:08.342340 | LOOP [fetch-subunit-output : Generate subunit file] 2026-03-01 00:21:08.375843 | 2026-03-01 00:21:08.376095 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2026-03-01 00:21:08.401988 | controller | skipping: Conditional result was False 2026-03-01 00:21:08.410745 | 2026-03-01 00:21:08.410849 | TASK [fetch-subunit-output : Remove the temporary file] 2026-03-01 00:21:08.435623 | controller | skipping: Conditional result was False 2026-03-01 00:21:08.444434 | 2026-03-01 00:21:08.444529 | TASK [fetch-subunit-output : Process and fetch subunit results] 2026-03-01 00:21:08.459912 | controller | skipping: Conditional result was False 2026-03-01 00:21:08.500086 | 2026-03-01 00:21:08.500203 | PLAY RECAP 2026-03-01 00:21:08.500244 | controller | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2026-03-01 00:21:08.500264 | 2026-03-01 00:21:08.620375 | POST-RUN END RESULT_UNREACHABLE: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2026-03-01 00:21:08.621812 | POST-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/post.yaml@master] 2026-03-01 00:21:09.219086 | 2026-03-01 00:21:09.219226 | PLAY [all:!appliance*] 2026-03-01 00:21:09.241383 | 2026-03-01 00:21:09.241497 | TASK [unregister the node] 2026-03-01 00:21:09.775438 | controller | skipping: Conditional result was False 2026-03-01 00:21:09.789779 | 2026-03-01 00:21:09.789993 | TASK [include_role : fetch-output] 2026-03-01 00:21:09.841397 | controller | ok 2026-03-01 00:21:09.884395 | 2026-03-01 00:21:09.884519 | TASK [fetch-output : Set log path for multiple nodes] 2026-03-01 00:21:09.960876 | controller | skipping: Conditional result was False 2026-03-01 00:21:09.969314 | 2026-03-01 00:21:09.969413 | TASK [fetch-output : Set log path for single node] 2026-03-01 00:21:10.032390 | controller | ok 2026-03-01 00:21:10.039881 | 2026-03-01 00:21:10.039969 | LOOP [fetch-output : Ensure local output dirs] 2026-03-01 00:21:10.533866 | controller -> localhost | ok: "/var/lib/zuul/builds/245564b211174d089341eb8c77cad02f/work/logs" 2026-03-01 00:21:10.840728 | controller -> localhost | changed: "/var/lib/zuul/builds/245564b211174d089341eb8c77cad02f/work/artifacts" 2026-03-01 00:21:11.086588 | controller -> localhost | changed: "/var/lib/zuul/builds/245564b211174d089341eb8c77cad02f/work/docs" 2026-03-01 00:21:11.098464 | 2026-03-01 00:21:11.098612 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-03-01 00:21:11.833878 | controller | changed: 2026-03-01 00:21:11.834241 | controller | .d..t...... ./ 2026-03-01 00:21:11.834304 | controller | cd+++++++++ controller/ 2026-03-01 00:21:11.834372 | controller | changed: All items complete 2026-03-01 00:21:11.834417 | 2026-03-01 00:21:12.399717 | controller | changed: .d..t...... ./ 2026-03-01 00:21:12.946858 | controller | changed: .d..t...... ./ 2026-03-01 00:21:12.985625 | 2026-03-01 00:21:12.985893 | TASK [include_role : fetch-output-openshift] 2026-03-01 00:21:13.013918 | controller | skipping: Conditional result was False 2026-03-01 00:21:13.022690 | 2026-03-01 00:21:13.022782 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-03-01 00:21:13.069602 | controller | skipping: Conditional result was False 2026-03-01 00:21:13.081287 | controller | skipping: Conditional result was False 2026-03-01 00:21:13.149261 | 2026-03-01 00:21:13.149509 | PLAY [localhost] 2026-03-01 00:21:13.167127 | 2026-03-01 00:21:13.167277 | TASK [Run Zuul manifest role] 2026-03-01 00:21:13.190432 | localhost | ok 2026-03-01 00:21:13.208409 | 2026-03-01 00:21:13.208511 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-03-01 00:21:13.677801 | localhost | changed 2026-03-01 00:21:13.682400 | 2026-03-01 00:21:13.682462 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-03-01 00:21:13.721189 | localhost | ok 2026-03-01 00:21:13.729152 | 2026-03-01 00:21:13.729215 | TASK [Set zuul-log-path fact] 2026-03-01 00:21:13.747552 | localhost | ok 2026-03-01 00:21:13.762228 | 2026-03-01 00:21:13.762299 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-03-01 00:21:13.800692 | localhost | ok 2026-03-01 00:21:13.808518 | 2026-03-01 00:21:13.808579 | LOOP [Set zuul_log_targets (Swift with random Rackspace region)] 2026-03-01 00:21:13.845769 | localhost | ok: 2026-03-01 00:21:13.845898 | localhost | { 2026-03-01 00:21:13.845922 | localhost | "auth": { 2026-03-01 00:21:13.845943 | localhost | "password": "IonWCwcuOFib&&<}wp^:[2G/", 2026-03-01 00:21:13.845960 | localhost | "username": "9LF91O0RK7JU" 2026-03-01 00:21:13.845977 | localhost | }, 2026-03-01 00:21:13.845992 | localhost | "profile": "rackspace", 2026-03-01 00:21:13.846008 | localhost | "region_name": "IAD" 2026-03-01 00:21:13.846023 | localhost | } 2026-03-01 00:21:13.846046 | 2026-03-01 00:21:13.850775 | 2026-03-01 00:21:13.850833 | TASK [Run upload-logs-failover role (S3 then Swift)] 2026-03-01 00:21:13.868826 | localhost | Output suppressed because no_log was given 2026-03-01 00:21:13.882175 | 2026-03-01 00:21:13.882295 | TASK [upload-logs-failover : Initialize control vars] 2026-03-01 00:21:13.911778 | localhost | ok 2026-03-01 00:21:13.946061 | 2026-03-01 00:21:13.946208 | LOOP [upload-logs-failover : Perform log upload] 2026-03-01 00:21:13.980454 | localhost | Output suppressed because no_log was given 2026-03-01 00:21:13.987445 | localhost | included: /var/lib/zuul/builds/245564b211174d089341eb8c77cad02f/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/upload-logs-failover/tasks/upload.yaml 2026-03-01 00:21:13.993981 | 2026-03-01 00:21:13.994051 | LOOP [upload-logs-failover : Unpack vars for swift upload] 2026-03-01 00:21:14.084580 | localhost | Output suppressed because no_log was given 2026-03-01 00:21:14.090048 | 2026-03-01 00:21:14.090127 | TASK [Upload logs to swift] 2026-03-01 00:21:14.109164 | localhost | ok 2026-03-01 00:21:14.136724 | 2026-03-01 00:21:14.136811 | TASK [Set zuul-log-path fact] 2026-03-01 00:21:14.160971 | localhost | skipping: Conditional result was False 2026-03-01 00:21:14.166634 | 2026-03-01 00:21:14.166771 | TASK [upload-logs-swift : Ensure logs are readable before uploading] 2026-03-01 00:21:14.546375 | localhost -> localhost | ok: Runtime: 0:00:00.009347 2026-03-01 00:21:14.568820 | 2026-03-01 00:21:14.569027 | TASK [upload-logs-swift : Upload logs to swift]