2026-03-09 00:07:03.703887 | Job console starting... 2026-03-09 00:07:03.716266 | Updating repositories 2026-03-09 00:07:03.865864 | Preparing job workspace 2026-03-09 00:07:12.489967 | Running Ansible setup... 2026-03-09 00:07:17.347875 | PRE-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master] 2026-03-09 00:07:18.023208 | 2026-03-09 00:07:18.023361 | PLAY [localhost] 2026-03-09 00:07:18.034059 | 2026-03-09 00:07:18.034187 | TASK [Gathering Facts] 2026-03-09 00:07:19.020888 | localhost | ok 2026-03-09 00:07:19.054417 | 2026-03-09 00:07:19.054716 | TASK [Setup log path fact] 2026-03-09 00:07:19.077133 | localhost | ok 2026-03-09 00:07:19.096550 | 2026-03-09 00:07:19.096693 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-03-09 00:07:19.127709 | localhost | ok 2026-03-09 00:07:19.139010 | 2026-03-09 00:07:19.139133 | TASK [emit-job-header : Print job information] 2026-03-09 00:07:19.194764 | # Job Information 2026-03-09 00:07:19.195066 | Ansible Version: 2.15.12 2026-03-09 00:07:19.195152 | Job: ansible-test-sanity-docker-devel 2026-03-09 00:07:19.195204 | Pipeline: periodic 2026-03-09 00:07:19.195249 | Executor: ze02.softwarefactory-project.io 2026-03-09 00:07:19.195292 | Triggered by: https://github.com/ansible-collections/ansible.network 2026-03-09 00:07:19.195341 | Log URL (when completed): https://ansible.softwarefactory-project.io/logs/6cd/ansible/6cd4544696c4446e9f3885c91ec3d969/ 2026-03-09 00:07:19.195386 | Event ID: 6ed282d1e81147dcab9935779dfad26a 2026-03-09 00:07:19.205425 | 2026-03-09 00:07:19.205627 | LOOP [emit-job-header : Print node information] 2026-03-09 00:07:19.309537 | localhost | ok: 2026-03-09 00:07:19.309773 | localhost | # Node Information 2026-03-09 00:07:19.309802 | localhost | Inventory Hostname: controller 2026-03-09 00:07:19.309822 | localhost | Hostname: np0005641915 2026-03-09 00:07:19.309841 | localhost | Username: zuul 2026-03-09 00:07:19.309866 | localhost | Distro: Fedora 37 2026-03-09 00:07:19.309885 | localhost | Provider: ansible-vexxhost-ca-ymq-1 2026-03-09 00:07:19.309902 | localhost | Region: ca-ymq-1 2026-03-09 00:07:19.309919 | localhost | Label: ansible-fedora-37-1vcpu 2026-03-09 00:07:19.309936 | localhost | Product Name: OpenStack Nova 2026-03-09 00:07:19.309952 | localhost | Interface IP: 199.204.45.246 2026-03-09 00:07:19.325578 | 2026-03-09 00:07:19.325786 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-03-09 00:07:19.782481 | localhost -> localhost | changed 2026-03-09 00:07:19.790643 | 2026-03-09 00:07:19.790780 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-03-09 00:07:20.742757 | localhost -> localhost | changed 2026-03-09 00:07:20.763265 | 2026-03-09 00:07:20.763360 | PLAY [all:!appliance*] 2026-03-09 00:07:20.779618 | 2026-03-09 00:07:20.779817 | TASK [include_role : start-zuul-console] 2026-03-09 00:07:20.800543 | controller | ok 2026-03-09 00:07:20.817774 | 2026-03-09 00:07:20.817916 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-03-09 00:07:21.185445 | controller | ok 2026-03-09 00:07:21.199915 | 2026-03-09 00:07:21.200017 | TASK [use-our-mirror : Retrieve the IP address] 2026-03-09 00:07:22.125105 | controller | ok: Status code was 404 and not [200]: HTTP Error 404: Not Found 2026-03-09 00:07:22.141814 | 2026-03-09 00:07:22.141994 | TASK [use-our-mirror : Ensure the registry service is running on the mirror] 2026-03-09 00:07:22.693455 | controller | skipping: Conditional result was False 2026-03-09 00:07:22.709082 | 2026-03-09 00:07:22.709242 | TASK [use-our-mirror : Write the IP in /etc/hosts] 2026-03-09 00:07:22.736942 | controller | skipping: Conditional result was False 2026-03-09 00:07:22.751506 | 2026-03-09 00:07:22.751661 | TASK [use-our-mirror : Create the podman configuration directory] 2026-03-09 00:07:22.779910 | controller | skipping: Conditional result was False 2026-03-09 00:07:22.796332 | 2026-03-09 00:07:22.796561 | TASK [use-our-mirror : Copy the podman configuration] 2026-03-09 00:07:22.825069 | controller | skipping: Conditional result was False 2026-03-09 00:07:22.835620 | 2026-03-09 00:07:22.835752 | TASK [use-our-mirror : Copy the Yum configuration (main)] 2026-03-09 00:07:22.861580 | controller | skipping: Conditional result was False 2026-03-09 00:07:22.871433 | 2026-03-09 00:07:22.871538 | TASK [use-our-mirror : Copy the Yum configuration (update)] 2026-03-09 00:07:22.897054 | controller | skipping: Conditional result was False 2026-03-09 00:07:22.919505 | 2026-03-09 00:07:22.919620 | TASK [Disable Fedora Modular] 2026-03-09 00:07:23.171825 | controller | changed 2026-03-09 00:07:23.185364 | 2026-03-09 00:07:23.185507 | TASK [Enable EPEL] 2026-03-09 00:07:23.213421 | controller | skipping: Conditional result was False 2026-03-09 00:07:23.227304 | 2026-03-09 00:07:23.227455 | TASK [Register the RHEL node] 2026-03-09 00:07:23.793199 | 2026-03-09 00:07:23.793534 | TASK [Show the subscription-manager status] 2026-03-09 00:07:24.389505 | controller | skipping: Conditional result was False 2026-03-09 00:07:24.404608 | 2026-03-09 00:07:24.404794 | TASK [Enable EPEL on RHEL] 2026-03-09 00:07:24.959327 | controller | skipping: Conditional result was False 2026-03-09 00:07:24.972990 | 2026-03-09 00:07:24.973130 | TASK [Install git and tox] 2026-03-09 00:08:57.710288 | controller | changed 2026-03-09 00:08:57.727157 | 2026-03-09 00:08:57.727489 | TASK [include_role : prepare-workspace] 2026-03-09 00:08:57.769607 | controller | ok 2026-03-09 00:08:57.808285 | 2026-03-09 00:08:57.808411 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-03-09 00:08:58.047515 | controller | ok 2026-03-09 00:08:58.060048 | 2026-03-09 00:08:58.060154 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-03-09 00:14:30.959118 | controller | Output suppressed because no_log was given 2026-03-09 00:14:30.977942 | 2026-03-09 00:14:30.978093 | TASK [include_role : prepare-workspace-openshift] 2026-03-09 00:14:31.004693 | controller | skipping: Conditional result was False 2026-03-09 00:14:31.068837 | 2026-03-09 00:14:31.068932 | PLAY [all:!appliance] 2026-03-09 00:14:31.085254 | 2026-03-09 00:14:31.085366 | TASK [Run add-build-sshkey role (RSA)] 2026-03-09 00:14:31.105050 | controller | ok 2026-03-09 00:14:31.120216 | 2026-03-09 00:14:31.120315 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-03-09 00:14:31.357991 | controller -> localhost | ok 2026-03-09 00:14:31.364224 | 2026-03-09 00:14:31.364290 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-03-09 00:14:31.384587 | controller | ok 2026-03-09 00:14:31.399943 | controller | included: /var/lib/zuul/builds/6cd4544696c4446e9f3885c91ec3d969/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-03-09 00:14:31.405829 | 2026-03-09 00:14:31.405891 | TASK [add-build-sshkey : Create Temp SSH key] 2026-03-09 00:14:31.824522 | controller -> localhost | Generating public/private rsa key pair. 2026-03-09 00:14:31.824711 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/6cd4544696c4446e9f3885c91ec3d969/work/6cd4544696c4446e9f3885c91ec3d969_id_rsa. 2026-03-09 00:14:31.824741 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/6cd4544696c4446e9f3885c91ec3d969/work/6cd4544696c4446e9f3885c91ec3d969_id_rsa.pub. 2026-03-09 00:14:31.824762 | controller -> localhost | The key fingerprint is: 2026-03-09 00:14:31.824782 | controller -> localhost | SHA256:CGfd/J61x53AWXIrf9+zDHsintE+jLYpZSl3CTCaM/o zuul-build-sshkey 2026-03-09 00:14:31.824801 | controller -> localhost | The key's randomart image is: 2026-03-09 00:14:31.824819 | controller -> localhost | +---[RSA 2048]----+ 2026-03-09 00:14:31.824836 | controller -> localhost | | | 2026-03-09 00:14:31.824855 | controller -> localhost | | . = | 2026-03-09 00:14:31.824873 | controller -> localhost | | . o + = . o | 2026-03-09 00:14:31.824890 | controller -> localhost | | + * o. = .| 2026-03-09 00:14:31.824908 | controller -> localhost | | o S +=o. | 2026-03-09 00:14:31.824938 | controller -> localhost | | . . *.=+oo| 2026-03-09 00:14:31.824962 | controller -> localhost | | . =.*o.o=| 2026-03-09 00:14:31.824982 | controller -> localhost | | E . +=++++| 2026-03-09 00:14:31.825000 | controller -> localhost | | +=oo+o=| 2026-03-09 00:14:31.825021 | controller -> localhost | +----[SHA256]-----+ 2026-03-09 00:14:31.825068 | controller -> localhost | ok: Runtime: 0:00:00.070069 2026-03-09 00:14:31.831509 | 2026-03-09 00:14:31.831573 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-03-09 00:14:31.852555 | controller | ok 2026-03-09 00:14:31.862426 | controller | included: /var/lib/zuul/builds/6cd4544696c4446e9f3885c91ec3d969/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-03-09 00:14:31.871948 | 2026-03-09 00:14:31.872013 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-03-09 00:14:31.896223 | controller | skipping: Conditional result was False 2026-03-09 00:14:31.903267 | 2026-03-09 00:14:31.903365 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-03-09 00:14:32.523161 | controller | changed 2026-03-09 00:14:32.533179 | 2026-03-09 00:14:32.533342 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-03-09 00:14:32.754213 | controller | ok 2026-03-09 00:14:32.769329 | 2026-03-09 00:14:32.769484 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-03-09 00:14:33.387815 | controller | changed 2026-03-09 00:14:33.393528 | 2026-03-09 00:14:33.393591 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-03-09 00:14:33.998044 | controller | changed 2026-03-09 00:14:34.015771 | 2026-03-09 00:14:34.016071 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-03-09 00:14:34.042699 | controller | skipping: Conditional result was False 2026-03-09 00:14:34.052212 | 2026-03-09 00:14:34.052309 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-03-09 00:14:34.422553 | controller -> localhost | changed 2026-03-09 00:14:34.434212 | 2026-03-09 00:14:34.434292 | TASK [add-build-sshkey : Add back temp key] 2026-03-09 00:14:34.765118 | controller -> localhost | Identity added: /var/lib/zuul/builds/6cd4544696c4446e9f3885c91ec3d969/work/6cd4544696c4446e9f3885c91ec3d969_id_rsa (zuul-build-sshkey) 2026-03-09 00:14:34.765551 | controller -> localhost | ok: Runtime: 0:00:00.014101 2026-03-09 00:14:34.780032 | 2026-03-09 00:14:34.780177 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-03-09 00:14:35.188763 | controller | ok 2026-03-09 00:14:35.200983 | 2026-03-09 00:14:35.201125 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-03-09 00:14:35.238178 | controller | skipping: Conditional result was False 2026-03-09 00:14:35.262606 | 2026-03-09 00:14:35.262800 | TASK [Run add-build-sshkey role (ECDSA)] 2026-03-09 00:14:35.301445 | controller | ok 2026-03-09 00:14:35.330480 | 2026-03-09 00:14:35.330589 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-03-09 00:14:35.630522 | controller -> localhost | ok 2026-03-09 00:14:35.639495 | 2026-03-09 00:14:35.639597 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-03-09 00:14:35.676819 | controller | ok 2026-03-09 00:14:35.698343 | controller | included: /var/lib/zuul/builds/6cd4544696c4446e9f3885c91ec3d969/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-03-09 00:14:35.707060 | 2026-03-09 00:14:35.707148 | TASK [add-build-sshkey : Create Temp SSH key] 2026-03-09 00:14:35.997526 | controller -> localhost | Generating public/private ecdsa key pair. 2026-03-09 00:14:35.997787 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/6cd4544696c4446e9f3885c91ec3d969/work/6cd4544696c4446e9f3885c91ec3d969_id_ecdsa. 2026-03-09 00:14:35.997828 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/6cd4544696c4446e9f3885c91ec3d969/work/6cd4544696c4446e9f3885c91ec3d969_id_ecdsa.pub. 2026-03-09 00:14:35.997868 | controller -> localhost | The key fingerprint is: 2026-03-09 00:14:35.997899 | controller -> localhost | SHA256:mAtQw5GnCk5sFKkbI4HHGpIxTRKCmUZXQWMjMWDS88I zuul-build-sshkey 2026-03-09 00:14:35.997924 | controller -> localhost | The key's randomart image is: 2026-03-09 00:14:35.997949 | controller -> localhost | +---[ECDSA 521]---+ 2026-03-09 00:14:35.997976 | controller -> localhost | |@/=*BX. | 2026-03-09 00:14:35.998000 | controller -> localhost | |&*B.*.+ | 2026-03-09 00:14:35.998023 | controller -> localhost | |**.o o | 2026-03-09 00:14:35.998045 | controller -> localhost | |B+E.o o | 2026-03-09 00:14:35.998072 | controller -> localhost | |== o. o S | 2026-03-09 00:14:35.998096 | controller -> localhost | |... . . | 2026-03-09 00:14:35.998121 | controller -> localhost | | . | 2026-03-09 00:14:35.998147 | controller -> localhost | | | 2026-03-09 00:14:35.998172 | controller -> localhost | | | 2026-03-09 00:14:35.998197 | controller -> localhost | +----[SHA256]-----+ 2026-03-09 00:14:35.998262 | controller -> localhost | ok: Runtime: 0:00:00.014286 2026-03-09 00:14:36.009159 | 2026-03-09 00:14:36.009245 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-03-09 00:14:36.047733 | controller | ok 2026-03-09 00:14:36.061097 | controller | included: /var/lib/zuul/builds/6cd4544696c4446e9f3885c91ec3d969/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-03-09 00:14:36.075081 | 2026-03-09 00:14:36.075174 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-03-09 00:14:36.100729 | controller | skipping: Conditional result was False 2026-03-09 00:14:36.109877 | 2026-03-09 00:14:36.109974 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-03-09 00:14:36.384041 | controller | changed 2026-03-09 00:14:36.397046 | 2026-03-09 00:14:36.397191 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-03-09 00:14:36.633044 | controller | ok 2026-03-09 00:14:36.647335 | 2026-03-09 00:14:36.647483 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-03-09 00:14:37.316305 | controller | changed 2026-03-09 00:14:37.325528 | 2026-03-09 00:14:37.325621 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-03-09 00:14:37.918228 | controller | changed 2026-03-09 00:14:37.932843 | 2026-03-09 00:14:37.932997 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-03-09 00:14:37.960850 | controller | skipping: Conditional result was False 2026-03-09 00:14:37.968271 | 2026-03-09 00:14:37.968346 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-03-09 00:14:38.217488 | controller -> localhost | changed 2026-03-09 00:14:38.245706 | 2026-03-09 00:14:38.245888 | TASK [add-build-sshkey : Add back temp key] 2026-03-09 00:14:38.626046 | controller -> localhost | Identity added: /var/lib/zuul/builds/6cd4544696c4446e9f3885c91ec3d969/work/6cd4544696c4446e9f3885c91ec3d969_id_ecdsa (zuul-build-sshkey) 2026-03-09 00:14:38.626269 | controller -> localhost | ok: Runtime: 0:00:00.028668 2026-03-09 00:14:38.634336 | 2026-03-09 00:14:38.634422 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-03-09 00:14:38.857971 | controller | ok 2026-03-09 00:14:38.872832 | 2026-03-09 00:14:38.872993 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-03-09 00:14:38.911766 | controller | skipping: Conditional result was False 2026-03-09 00:14:38.938633 | 2026-03-09 00:14:38.938842 | TASK [include_role : remove-zuul-sshkey] 2026-03-09 00:14:38.977138 | controller | skipping: Conditional result was False 2026-03-09 00:14:38.988368 | 2026-03-09 00:14:38.988460 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-03-09 00:14:39.224615 | controller | ok: "logs" 2026-03-09 00:14:39.224937 | controller | ok: All items complete 2026-03-09 00:14:39.224966 | 2026-03-09 00:14:39.427422 | controller | ok: "artifacts" 2026-03-09 00:14:39.632407 | controller | ok: "docs" 2026-03-09 00:14:39.648955 | 2026-03-09 00:14:39.649140 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-03-09 00:14:39.887300 | controller | changed: "logs" 2026-03-09 00:14:40.085134 | controller | changed: "artifacts" 2026-03-09 00:14:40.285592 | controller | changed: "docs" 2026-03-09 00:14:40.329860 | 2026-03-09 00:14:40.329996 | PLAY RECAP 2026-03-09 00:14:40.330060 | controller | ok: 30 changed: 16 unreachable: 0 failed: 0 skipped: 18 rescued: 0 ignored: 0 2026-03-09 00:14:40.330097 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-03-09 00:14:40.330123 | 2026-03-09 00:14:40.467367 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master] 2026-03-09 00:14:40.469099 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2026-03-09 00:14:41.069018 | 2026-03-09 00:14:41.069128 | PLAY [all] 2026-03-09 00:14:41.091103 | 2026-03-09 00:14:41.091208 | TASK [Install binary dependencies] 2026-03-09 00:14:41.161441 | controller | ok 2026-03-09 00:14:41.181939 | 2026-03-09 00:14:41.182028 | TASK [bindep : Include find tasks] 2026-03-09 00:14:41.226138 | controller | ok 2026-03-09 00:14:41.237802 | controller | included: /var/lib/zuul/builds/6cd4544696c4446e9f3885c91ec3d969/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find.yaml 2026-03-09 00:14:41.247483 | 2026-03-09 00:14:41.247612 | TASK [bindep : Look for bindep.txt] 2026-03-09 00:14:41.872389 | controller | ok 2026-03-09 00:14:41.888078 | 2026-03-09 00:14:41.888215 | TASK [bindep : Define bindep_file fact] 2026-03-09 00:14:41.904012 | controller | skipping: Conditional result was False 2026-03-09 00:14:41.914832 | 2026-03-09 00:14:41.914946 | TASK [bindep : Look for other-requirements.txt] 2026-03-09 00:14:42.118112 | controller | ok 2026-03-09 00:14:42.135331 | 2026-03-09 00:14:42.135438 | TASK [bindep : Define bindep_file fact] 2026-03-09 00:14:42.163477 | controller | skipping: Conditional result was False 2026-03-09 00:14:42.184309 | 2026-03-09 00:14:42.184472 | TASK [bindep : Look for bindep fallback file] 2026-03-09 00:14:42.224213 | controller | skipping: Conditional result was False 2026-03-09 00:14:42.239687 | 2026-03-09 00:14:42.239825 | TASK [bindep : Define bindep_file fact] 2026-03-09 00:14:42.268403 | controller | skipping: Conditional result was False 2026-03-09 00:14:42.282365 | 2026-03-09 00:14:42.282524 | TASK [bindep : Include bindep tasks] 2026-03-09 00:14:42.308716 | controller | skipping: Conditional result was False 2026-03-09 00:14:42.317122 | 2026-03-09 00:14:42.317215 | TASK [bindep : Include install tasks] 2026-03-09 00:14:42.341604 | controller | skipping: Conditional result was False 2026-03-09 00:14:42.349493 | 2026-03-09 00:14:42.349581 | LOOP [bindep : Include package tasks] 2026-03-09 00:14:42.419173 | 2026-03-09 00:14:42.419489 | TASK [Run test-setup role] 2026-03-09 00:14:42.449040 | controller | ok 2026-03-09 00:14:42.478729 | 2026-03-09 00:14:42.478858 | TASK [test-setup : Check if project's tools/test-setup.sh exists] 2026-03-09 00:14:42.686452 | controller | ok 2026-03-09 00:14:42.698260 | 2026-03-09 00:14:42.698374 | TASK [test-setup : Run tools/test-setup.sh] 2026-03-09 00:14:43.242832 | controller | skipping: Conditional result was False 2026-03-09 00:14:43.301541 | 2026-03-09 00:14:43.301686 | PLAY RECAP 2026-03-09 00:14:43.301749 | controller | ok: 4 changed: 0 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2026-03-09 00:14:43.301781 | 2026-03-09 00:14:43.415495 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2026-03-09 00:14:43.416976 | PRE-RUN START: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master] 2026-03-09 00:14:44.028462 | 2026-03-09 00:14:44.028577 | PLAY [controller] 2026-03-09 00:14:44.050129 | 2026-03-09 00:14:44.050229 | TASK [Create the /root directory] 2026-03-09 00:14:44.683344 | controller | ok 2026-03-09 00:14:44.698329 | 2026-03-09 00:14:44.698497 | TASK [Install glibc-langpack-en] 2026-03-09 00:14:48.706348 | controller | ok: Nothing to do 2026-03-09 00:14:48.715472 | 2026-03-09 00:14:48.715574 | TASK [Ensure controller directory exists] 2026-03-09 00:14:48.947319 | controller | changed 2026-03-09 00:14:48.954212 | 2026-03-09 00:14:48.954325 | TASK [Install container runtime] 2026-03-09 00:14:49.020356 | controller | ok 2026-03-09 00:14:49.064153 | 2026-03-09 00:14:49.064295 | LOOP [ensure-podman : Find distribution installation] 2026-03-09 00:14:49.090164 | controller | ok: "/var/lib/zuul/builds/6cd4544696c4446e9f3885c91ec3d969/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-podman/tasks/RedHat.yaml" 2026-03-09 00:14:49.106094 | controller | included: /var/lib/zuul/builds/6cd4544696c4446e9f3885c91ec3d969/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-podman/tasks/RedHat.yaml 2026-03-09 00:14:49.113271 | 2026-03-09 00:14:49.113361 | TASK [ensure-podman : Install podman (RedHat)] 2026-03-09 00:15:54.237594 | controller | changed 2026-03-09 00:15:54.250545 | 2026-03-09 00:15:54.250802 | TASK [ensure-podman : Fetch podman version] 2026-03-09 00:15:54.808810 | controller | Client: Podman Engine 2026-03-09 00:15:54.830897 | controller | Version: 4.6.2 2026-03-09 00:15:54.830935 | controller | API Version: 4.6.2 2026-03-09 00:15:54.830943 | controller | Go Version: go1.19.12 2026-03-09 00:15:54.830963 | controller | Built: Mon Aug 28 19:38:31 2023 2026-03-09 00:15:54.830971 | controller | OS/Arch: linux/amd64 2026-03-09 00:15:55.296754 | controller | ok: Runtime: 0:00:00.196938 2026-03-09 00:15:55.311522 | 2026-03-09 00:15:55.311804 | TASK [ensure-podman : Print podman version installed] 2026-03-09 00:15:55.353860 | Podman version: Client: Podman Engine 2026-03-09 00:15:55.354127 | Version: 4.6.2 2026-03-09 00:15:55.354191 | API Version: 4.6.2 2026-03-09 00:15:55.354236 | Go Version: go1.19.12 2026-03-09 00:15:55.354275 | Built: Mon Aug 28 19:38:31 2023 2026-03-09 00:15:55.354315 | OS/Arch: linux/amd64 2026-03-09 00:15:55.366900 | 2026-03-09 00:15:55.367031 | TASK [ensure-podman : Validate podman engine] 2026-03-09 00:15:55.910953 | controller | skipping: Conditional result was False 2026-03-09 00:15:55.917658 | 2026-03-09 00:15:55.917770 | TASK [ensure-podman : Set up docker compatability socket] 2026-03-09 00:15:55.931747 | controller | skipping: Conditional result was False 2026-03-09 00:15:55.947408 | 2026-03-09 00:15:55.947506 | TASK [Ensure python3.8 is present] 2026-03-09 00:15:55.985841 | controller | skipping: Conditional result was False 2026-03-09 00:15:55.991917 | 2026-03-09 00:15:55.992007 | TASK [Run our-ensure-python role (Fedora only for now)] 2026-03-09 00:15:56.012453 | controller | ok 2026-03-09 00:15:56.031505 | 2026-03-09 00:15:56.031607 | TASK [our-ensure-python : Install the right Python version (rpm)] 2026-03-09 00:15:57.444234 | controller | ok: Nothing to do 2026-03-09 00:15:57.468799 | 2026-03-09 00:15:57.469002 | TASK [our-ensure-python : Also install python3-devel] 2026-03-09 00:16:06.269517 | controller | changed 2026-03-09 00:16:06.295103 | 2026-03-09 00:16:06.295278 | TASK [Run ensure-virtualenv role] 2026-03-09 00:16:06.319986 | controller | ok 2026-03-09 00:16:06.351301 | 2026-03-09 00:16:06.351569 | TASK [ensure-virtualenv : Check if virtualenv is installed] 2026-03-09 00:16:06.612581 | controller | /usr/bin/virtualenv 2026-03-09 00:16:06.915634 | controller | ok: Runtime: 0:00:00.004639 2026-03-09 00:16:06.930825 | 2026-03-09 00:16:06.930972 | LOOP [ensure-virtualenv : Install virtualenv package] 2026-03-09 00:16:06.968611 | controller | skipping: Conditional result was False 2026-03-09 00:16:06.969082 | controller | ok: All items complete 2026-03-09 00:16:06.969144 | 2026-03-09 00:16:07.006407 | 2026-03-09 00:16:07.006608 | TASK [Find the full path of the Python interpreter] 2026-03-09 00:16:07.249317 | controller | /usr/bin/python3 2026-03-09 00:16:07.555454 | controller | ok 2026-03-09 00:16:07.561783 | 2026-03-09 00:16:07.561848 | TASK [Create virtualenv for ansible-test with a specific interpreter] 2026-03-09 00:16:08.375047 | controller | created virtual environment CPython3.11.0.final.0-64 in 430ms 2026-03-09 00:16:08.398017 | controller | creator CPython3Posix(dest=/home/zuul/venv, clear=False, no_vcs_ignore=False, global=False) 2026-03-09 00:16:08.398371 | controller | seeder FromAppData(extra_search_dir=/usr/share/python-wheels,download=False, pip=bundle, setuptools=bundle, wheel=bundle, via=copy, app_data_dir=/home/zuul/.local/share/virtualenv) 2026-03-09 00:16:08.398592 | controller | added seed packages: pip==22.2.2, setuptools==62.6.0, wheel==0.37.1 2026-03-09 00:16:08.398803 | controller | activators BashActivator,CShellActivator,FishActivator,NushellActivator,PowerShellActivator,PythonActivator 2026-03-09 00:16:08.619543 | controller | changed 2026-03-09 00:16:08.636165 | 2026-03-09 00:16:08.636513 | TASK [Set selinux package] 2026-03-09 00:16:08.680035 | controller | ok 2026-03-09 00:16:08.692882 | 2026-03-09 00:16:08.693024 | TASK [Set selinux package (Fedora)] 2026-03-09 00:16:08.737028 | controller | ok 2026-03-09 00:16:08.744133 | 2026-03-09 00:16:08.744193 | TASK [Install selinux into virtualenv] 2026-03-09 00:16:10.057178 | controller | Collecting selinux-please-lie-to-me 2026-03-09 00:16:10.097736 | controller | Downloading selinux_please_lie_to_me-1.0.1-py2.py3-none-any.whl (3.8 kB) 2026-03-09 00:16:10.405097 | controller | Collecting setuptools<50.0.0 2026-03-09 00:16:10.420836 | controller | Downloading setuptools-49.6.0-py3-none-any.whl (803 kB) 2026-03-09 00:16:10.463162 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 803.3/803.3 kB 22.1 MB/s eta 0:00:00 2026-03-09 00:16:10.544425 | controller | Installing collected packages: setuptools, selinux-please-lie-to-me 2026-03-09 00:16:10.544593 | controller | Attempting uninstall: setuptools 2026-03-09 00:16:10.546994 | controller | Found existing installation: setuptools 62.6.0 2026-03-09 00:16:10.608088 | controller | Uninstalling setuptools-62.6.0: 2026-03-09 00:16:10.615964 | controller | Successfully uninstalled setuptools-62.6.0 2026-03-09 00:16:10.961191 | controller | Successfully installed selinux-please-lie-to-me-1.0.1 setuptools-49.6.0 2026-03-09 00:16:11.082824 | controller | 2026-03-09 00:16:11.163603 | controller | [notice] A new release of pip available: 22.2.2 -> 26.0.1 2026-03-09 00:16:11.163619 | controller | [notice] To update, run: python -m pip install --upgrade pip 2026-03-09 00:16:11.281446 | controller | ok: Runtime: 0:00:02.161813 2026-03-09 00:16:11.290527 | 2026-03-09 00:16:11.290618 | TASK [Install pytest-forked into virtualenv] 2026-03-09 00:21:11.028337 | [Zuul] Log Stream did not terminate 2026-03-09 00:21:11.028719 | controller | ERROR 2026-03-09 00:21:11.028885 | controller | { 2026-03-09 00:21:11.028942 | controller | "msg": "Data could not be sent to remote host \"199.204.45.246\". Make sure this host can be reached over ssh: ssh: connect to host 199.204.45.246 port 22: No route to host\r\n", 2026-03-09 00:21:11.028995 | controller | "unreachable": true 2026-03-09 00:21:11.029034 | controller | } unreachable 2026-03-09 00:21:11.030842 | 2026-03-09 00:21:11.030941 | PLAY RECAP 2026-03-09 00:21:11.031021 | controller | ok: 15 changed: 7 unreachable: 1 failed: 0 skipped: 4 rescued: 0 ignored: 0 2026-03-09 00:21:11.031097 | 2026-03-09 00:21:11.148828 | PRE-RUN END RESULT_UNREACHABLE: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master] 2026-03-09 00:21:11.150892 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2026-03-09 00:21:11.780980 | 2026-03-09 00:21:11.781092 | PLAY [all] 2026-03-09 00:21:11.802907 | 2026-03-09 00:21:11.803035 | TASK [fetch-subunit-output : Find stestr or testr executable] 2026-03-09 00:21:13.066733 | controller | changed: non-zero return code 2026-03-09 00:21:13.081289 | 2026-03-09 00:21:13.081451 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2026-03-09 00:21:13.110204 | controller | skipping: Conditional result was False 2026-03-09 00:21:13.125187 | 2026-03-09 00:21:13.125336 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2026-03-09 00:21:13.170825 | 2026-03-09 00:21:13.171085 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2026-03-09 00:21:13.206491 | 2026-03-09 00:21:13.206700 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2026-03-09 00:21:13.230733 | controller | skipping: Conditional result was False 2026-03-09 00:21:13.241630 | 2026-03-09 00:21:13.241846 | LOOP [fetch-subunit-output : Generate subunit file] 2026-03-09 00:21:13.282064 | 2026-03-09 00:21:13.282316 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2026-03-09 00:21:13.309641 | controller | skipping: Conditional result was False 2026-03-09 00:21:13.336056 | 2026-03-09 00:21:13.336203 | TASK [fetch-subunit-output : Remove the temporary file] 2026-03-09 00:21:13.363888 | controller | skipping: Conditional result was False 2026-03-09 00:21:13.375850 | 2026-03-09 00:21:13.375950 | TASK [fetch-subunit-output : Process and fetch subunit results] 2026-03-09 00:21:13.401015 | controller | skipping: Conditional result was False 2026-03-09 00:21:13.436203 | 2026-03-09 00:21:13.436294 | PLAY RECAP 2026-03-09 00:21:13.436344 | controller | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2026-03-09 00:21:13.436370 | 2026-03-09 00:21:13.527432 | POST-RUN END RESULT_UNREACHABLE: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2026-03-09 00:21:13.529248 | POST-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/post.yaml@master] 2026-03-09 00:21:14.125993 | 2026-03-09 00:21:14.126101 | PLAY [all:!appliance*] 2026-03-09 00:21:14.147514 | 2026-03-09 00:21:14.147637 | TASK [unregister the node] 2026-03-09 00:21:14.679951 | controller | skipping: Conditional result was False 2026-03-09 00:21:14.693747 | 2026-03-09 00:21:14.693920 | TASK [include_role : fetch-output] 2026-03-09 00:21:14.755316 | controller | ok 2026-03-09 00:21:14.795735 | 2026-03-09 00:21:14.795843 | TASK [fetch-output : Set log path for multiple nodes] 2026-03-09 00:21:14.872731 | controller | skipping: Conditional result was False 2026-03-09 00:21:14.881066 | 2026-03-09 00:21:14.881175 | TASK [fetch-output : Set log path for single node] 2026-03-09 00:21:14.924460 | controller | ok 2026-03-09 00:21:14.932302 | 2026-03-09 00:21:14.932401 | LOOP [fetch-output : Ensure local output dirs] 2026-03-09 00:21:15.366486 | controller -> localhost | ok: "/var/lib/zuul/builds/6cd4544696c4446e9f3885c91ec3d969/work/logs" 2026-03-09 00:21:15.596132 | controller -> localhost | changed: "/var/lib/zuul/builds/6cd4544696c4446e9f3885c91ec3d969/work/artifacts" 2026-03-09 00:21:15.846713 | controller -> localhost | changed: "/var/lib/zuul/builds/6cd4544696c4446e9f3885c91ec3d969/work/docs" 2026-03-09 00:21:15.855924 | 2026-03-09 00:21:15.856042 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-03-09 00:21:16.483401 | controller | changed: 2026-03-09 00:21:16.483590 | controller | .d..t...... ./ 2026-03-09 00:21:16.483617 | controller | cd+++++++++ controller/ 2026-03-09 00:21:16.483651 | controller | changed: All items complete 2026-03-09 00:21:16.483690 | 2026-03-09 00:21:17.012224 | controller | changed: .d..t...... ./ 2026-03-09 00:21:17.473570 | controller | changed: .d..t...... ./ 2026-03-09 00:21:17.501784 | 2026-03-09 00:21:17.501948 | TASK [include_role : fetch-output-openshift] 2026-03-09 00:21:17.527585 | controller | skipping: Conditional result was False 2026-03-09 00:21:17.536974 | 2026-03-09 00:21:17.537071 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-03-09 00:21:17.581370 | controller | skipping: Conditional result was False 2026-03-09 00:21:17.593858 | controller | skipping: Conditional result was False 2026-03-09 00:21:17.656701 | 2026-03-09 00:21:17.656828 | PLAY [localhost] 2026-03-09 00:21:17.676560 | 2026-03-09 00:21:17.676668 | TASK [Run Zuul manifest role] 2026-03-09 00:21:17.697644 | localhost | ok 2026-03-09 00:21:17.719546 | 2026-03-09 00:21:17.719728 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-03-09 00:21:18.107986 | localhost | changed 2026-03-09 00:21:18.114433 | 2026-03-09 00:21:18.114518 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-03-09 00:21:18.145767 | localhost | ok 2026-03-09 00:21:18.157518 | 2026-03-09 00:21:18.157604 | TASK [Set zuul-log-path fact] 2026-03-09 00:21:18.177981 | localhost | ok 2026-03-09 00:21:18.197999 | 2026-03-09 00:21:18.198114 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-03-09 00:21:18.229345 | localhost | ok 2026-03-09 00:21:18.241252 | 2026-03-09 00:21:18.241348 | LOOP [Set zuul_log_targets (Swift with random Rackspace region)] 2026-03-09 00:21:18.274213 | localhost | ok: 2026-03-09 00:21:18.274587 | localhost | { 2026-03-09 00:21:18.274626 | localhost | "auth": { 2026-03-09 00:21:18.274655 | localhost | "password": "IonWCwcuOFib&&<}wp^:[2G/", 2026-03-09 00:21:18.274724 | localhost | "username": "9LF91O0RK7JU" 2026-03-09 00:21:18.274749 | localhost | }, 2026-03-09 00:21:18.274772 | localhost | "profile": "rackspace", 2026-03-09 00:21:18.274793 | localhost | "region_name": "ORD" 2026-03-09 00:21:18.274814 | localhost | } 2026-03-09 00:21:18.274851 | 2026-03-09 00:21:18.283486 | 2026-03-09 00:21:18.283616 | TASK [Run upload-logs-failover role (S3 then Swift)] 2026-03-09 00:21:18.304713 | localhost | Output suppressed because no_log was given 2026-03-09 00:21:18.322436 | 2026-03-09 00:21:18.322534 | TASK [upload-logs-failover : Initialize control vars] 2026-03-09 00:21:18.353870 | localhost | ok 2026-03-09 00:21:18.360328 | 2026-03-09 00:21:18.360412 | LOOP [upload-logs-failover : Perform log upload] 2026-03-09 00:21:18.397534 | localhost | Output suppressed because no_log was given 2026-03-09 00:21:18.438842 | localhost | included: /var/lib/zuul/builds/6cd4544696c4446e9f3885c91ec3d969/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/upload-logs-failover/tasks/upload.yaml 2026-03-09 00:21:18.447875 | 2026-03-09 00:21:18.447953 | LOOP [upload-logs-failover : Unpack vars for swift upload] 2026-03-09 00:21:18.544226 | localhost | Output suppressed because no_log was given 2026-03-09 00:21:18.550162 | 2026-03-09 00:21:18.550244 | TASK [Upload logs to swift] 2026-03-09 00:21:18.568947 | localhost | ok 2026-03-09 00:21:18.596824 | 2026-03-09 00:21:18.596937 | TASK [Set zuul-log-path fact] 2026-03-09 00:21:18.621603 | localhost | skipping: Conditional result was False 2026-03-09 00:21:18.627056 | 2026-03-09 00:21:18.627119 | TASK [upload-logs-swift : Ensure logs are readable before uploading] 2026-03-09 00:21:18.997090 | localhost -> localhost | ok: Runtime: 0:00:00.005268 2026-03-09 00:21:19.002228 | 2026-03-09 00:21:19.002294 | TASK [upload-logs-swift : Upload logs to swift]