2025-11-01 00:18:27.473239 | Job console starting... 2025-11-01 00:18:27.485631 | Updating repositories 2025-11-01 00:18:27.643417 | Preparing job workspace 2025-11-01 00:18:31.701866 | Running Ansible setup... 2025-11-01 00:18:38.135457 | PRE-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master] 2025-11-01 00:18:38.754930 | 2025-11-01 00:18:38.755063 | PLAY [localhost] 2025-11-01 00:18:38.764040 | 2025-11-01 00:18:38.764138 | TASK [Gathering Facts] 2025-11-01 00:18:39.756634 | localhost | ok 2025-11-01 00:18:39.771712 | 2025-11-01 00:18:39.771828 | TASK [Setup log path fact] 2025-11-01 00:18:39.802641 | localhost | ok 2025-11-01 00:18:39.816825 | 2025-11-01 00:18:39.816971 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-11-01 00:18:39.856212 | localhost | ok 2025-11-01 00:18:39.864496 | 2025-11-01 00:18:39.864574 | TASK [emit-job-header : Print job information] 2025-11-01 00:18:39.919324 | # Job Information 2025-11-01 00:18:39.919642 | Ansible Version: 2.15.12 2025-11-01 00:18:39.919783 | Job: ansible-test-sanity-docker-stable-2.11 2025-11-01 00:18:39.919840 | Pipeline: periodic 2025-11-01 00:18:39.919888 | Executor: ze02.softwarefactory-project.io 2025-11-01 00:18:39.919947 | Triggered by: https://github.com/ansible-collections/ansible.network 2025-11-01 00:18:39.920001 | Log URL (when completed): https://ansible.softwarefactory-project.io/logs/8a6/ansible/8a63bc67a69c48aebff7972d1cf34888/ 2025-11-01 00:18:39.920051 | Event ID: a664f74adefa4145bbf2cab5da247121 2025-11-01 00:18:39.930615 | 2025-11-01 00:18:39.930961 | LOOP [emit-job-header : Print node information] 2025-11-01 00:18:40.059840 | localhost | ok: 2025-11-01 00:18:40.060186 | localhost | # Node Information 2025-11-01 00:18:40.060216 | localhost | Inventory Hostname: controller 2025-11-01 00:18:40.060237 | localhost | Hostname: ip-172-16-245-19 2025-11-01 00:18:40.060255 | localhost | Username: zuul-worker 2025-11-01 00:18:40.060275 | localhost | Distro: Fedora 37 2025-11-01 00:18:40.060294 | localhost | Provider: ansible-us-east-2 2025-11-01 00:18:40.060311 | localhost | Region: us-east-2 2025-11-01 00:18:40.060328 | localhost | Label: ansible-fedora-37-1vcpu 2025-11-01 00:18:40.060345 | localhost | Product Name: t3.small 2025-11-01 00:18:40.060362 | localhost | Interface IP: 18.220.140.255 2025-11-01 00:18:40.083074 | 2025-11-01 00:18:40.083319 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-11-01 00:18:40.572405 | localhost -> localhost | changed 2025-11-01 00:18:40.579017 | 2025-11-01 00:18:40.579137 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-11-01 00:18:41.451496 | localhost -> localhost | changed 2025-11-01 00:18:41.491228 | 2025-11-01 00:18:41.491313 | PLAY [all:!appliance*] 2025-11-01 00:18:41.512811 | 2025-11-01 00:18:41.512907 | TASK [include_role : start-zuul-console] 2025-11-01 00:18:41.535613 | controller | ok 2025-11-01 00:18:41.555618 | 2025-11-01 00:18:41.555738 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-11-01 00:18:42.247101 | controller | ok 2025-11-01 00:18:42.271879 | 2025-11-01 00:18:42.272152 | TASK [use-our-mirror : Retrieve the IP address] 2025-11-01 00:18:44.027791 | controller | ok: Status code was 404 and not [200]: HTTP Error 404: Not Found 2025-11-01 00:18:44.042568 | 2025-11-01 00:18:44.042778 | TASK [use-our-mirror : Ensure the registry service is running on the mirror] 2025-11-01 00:18:44.206385 | controller | skipping: Conditional result was False 2025-11-01 00:18:44.216832 | 2025-11-01 00:18:44.216959 | TASK [use-our-mirror : Write the IP in /etc/hosts] 2025-11-01 00:18:44.242756 | controller | skipping: Conditional result was False 2025-11-01 00:18:44.253308 | 2025-11-01 00:18:44.253416 | TASK [use-our-mirror : Create the podman configuration directory] 2025-11-01 00:18:44.289425 | controller | skipping: Conditional result was False 2025-11-01 00:18:44.301335 | 2025-11-01 00:18:44.301503 | TASK [use-our-mirror : Copy the podman configuration] 2025-11-01 00:18:44.327324 | controller | skipping: Conditional result was False 2025-11-01 00:18:44.337346 | 2025-11-01 00:18:44.337491 | TASK [use-our-mirror : Copy the Yum configuration (main)] 2025-11-01 00:18:44.365510 | controller | skipping: Conditional result was False 2025-11-01 00:18:44.375784 | 2025-11-01 00:18:44.375916 | TASK [use-our-mirror : Copy the Yum configuration (update)] 2025-11-01 00:18:44.401657 | controller | skipping: Conditional result was False 2025-11-01 00:18:44.419574 | 2025-11-01 00:18:44.419764 | TASK [Disable Fedora Modular] 2025-11-01 00:18:45.212931 | controller | changed 2025-11-01 00:18:45.227547 | 2025-11-01 00:18:45.227763 | TASK [Enable EPEL] 2025-11-01 00:18:45.256929 | controller | skipping: Conditional result was False 2025-11-01 00:18:45.272057 | 2025-11-01 00:18:45.272202 | TASK [Register the RHEL node] 2025-11-01 00:18:45.445841 | 2025-11-01 00:18:45.446144 | TASK [Show the subscription-manager status] 2025-11-01 00:18:45.654650 | controller | skipping: Conditional result was False 2025-11-01 00:18:45.664310 | 2025-11-01 00:18:45.664412 | TASK [Enable EPEL on RHEL] 2025-11-01 00:18:45.826417 | controller | skipping: Conditional result was False 2025-11-01 00:18:45.841052 | 2025-11-01 00:18:45.841223 | TASK [Install git and tox] 2025-11-01 00:20:21.124423 | controller | changed 2025-11-01 00:20:21.139717 | 2025-11-01 00:20:21.139949 | TASK [include_role : prepare-workspace] 2025-11-01 00:20:21.179875 | controller | ok 2025-11-01 00:20:21.220908 | 2025-11-01 00:20:21.221063 | TASK [prepare-workspace : Start zuul_console daemon.] 2025-11-01 00:20:21.726953 | controller | ok 2025-11-01 00:20:21.736150 | 2025-11-01 00:20:21.736249 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2025-11-01 00:20:34.937395 | controller | Output suppressed because no_log was given 2025-11-01 00:20:34.951255 | 2025-11-01 00:20:34.951358 | TASK [include_role : prepare-workspace-openshift] 2025-11-01 00:20:35.017886 | controller | skipping: Conditional result was False 2025-11-01 00:20:35.068793 | 2025-11-01 00:20:35.068890 | PLAY [all:!appliance] 2025-11-01 00:20:35.084093 | 2025-11-01 00:20:35.084152 | TASK [Run add-build-sshkey role (RSA)] 2025-11-01 00:20:35.114696 | controller | ok 2025-11-01 00:20:35.129321 | 2025-11-01 00:20:35.129386 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-11-01 00:20:35.418186 | controller -> localhost | ok 2025-11-01 00:20:35.425858 | 2025-11-01 00:20:35.425954 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-11-01 00:20:35.455513 | controller | ok 2025-11-01 00:20:35.468985 | controller | included: /var/lib/zuul/builds/8a63bc67a69c48aebff7972d1cf34888/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-11-01 00:20:35.475854 | 2025-11-01 00:20:35.475944 | TASK [add-build-sshkey : Create Temp SSH key] 2025-11-01 00:20:35.897635 | controller -> localhost | Generating public/private rsa key pair. 2025-11-01 00:20:35.897836 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/8a63bc67a69c48aebff7972d1cf34888/work/8a63bc67a69c48aebff7972d1cf34888_id_rsa. 2025-11-01 00:20:35.897866 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/8a63bc67a69c48aebff7972d1cf34888/work/8a63bc67a69c48aebff7972d1cf34888_id_rsa.pub. 2025-11-01 00:20:35.897888 | controller -> localhost | The key fingerprint is: 2025-11-01 00:20:35.897908 | controller -> localhost | SHA256:nqGA4kQX3fcpP2p0svOShPiyQSkT3gqHN4hqED0YjiE zuul-build-sshkey 2025-11-01 00:20:35.897927 | controller -> localhost | The key's randomart image is: 2025-11-01 00:20:35.897948 | controller -> localhost | +---[RSA 2048]----+ 2025-11-01 00:20:35.897966 | controller -> localhost | |E .. . | 2025-11-01 00:20:35.897986 | controller -> localhost | |+= .. . . | 2025-11-01 00:20:35.898004 | controller -> localhost | |+ooo . . . | 2025-11-01 00:20:35.898026 | controller -> localhost | |o.=oo . . o | 2025-11-01 00:20:35.898053 | controller -> localhost | |+=.B.+. S o | 2025-11-01 00:20:35.898087 | controller -> localhost | |=.+ *o + * + | 2025-11-01 00:20:35.898113 | controller -> localhost | |.o . .o = * . | 2025-11-01 00:20:35.898133 | controller -> localhost | |. ... B | 2025-11-01 00:20:35.898151 | controller -> localhost | | .o . +. | 2025-11-01 00:20:35.898173 | controller -> localhost | +----[SHA256]-----+ 2025-11-01 00:20:35.898221 | controller -> localhost | ok: Runtime: 0:00:00.055860 2025-11-01 00:20:35.905016 | 2025-11-01 00:20:35.905089 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-11-01 00:20:35.926921 | controller | ok 2025-11-01 00:20:35.937096 | controller | included: /var/lib/zuul/builds/8a63bc67a69c48aebff7972d1cf34888/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-11-01 00:20:35.947492 | 2025-11-01 00:20:35.947594 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-11-01 00:20:35.962067 | controller | skipping: Conditional result was False 2025-11-01 00:20:35.970105 | 2025-11-01 00:20:35.970184 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-11-01 00:20:36.654422 | controller | changed 2025-11-01 00:20:36.668094 | 2025-11-01 00:20:36.668246 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-11-01 00:20:37.050358 | controller | ok 2025-11-01 00:20:37.057050 | 2025-11-01 00:20:37.057164 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-11-01 00:20:38.454234 | controller | changed 2025-11-01 00:20:38.466030 | 2025-11-01 00:20:38.466174 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-11-01 00:20:39.888224 | controller | changed 2025-11-01 00:20:39.896012 | 2025-11-01 00:20:39.896104 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-11-01 00:20:39.920882 | controller | skipping: Conditional result was False 2025-11-01 00:20:39.929206 | 2025-11-01 00:20:39.929302 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-11-01 00:20:40.341277 | controller -> localhost | changed 2025-11-01 00:20:40.374218 | 2025-11-01 00:20:40.374468 | TASK [add-build-sshkey : Add back temp key] 2025-11-01 00:20:40.687621 | controller -> localhost | Identity added: /var/lib/zuul/builds/8a63bc67a69c48aebff7972d1cf34888/work/8a63bc67a69c48aebff7972d1cf34888_id_rsa (zuul-build-sshkey) 2025-11-01 00:20:40.688019 | controller -> localhost | ok: Runtime: 0:00:00.014825 2025-11-01 00:20:40.702365 | 2025-11-01 00:20:40.702547 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-11-01 00:20:41.257189 | controller | ok 2025-11-01 00:20:41.270356 | 2025-11-01 00:20:41.270533 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-11-01 00:20:41.310090 | controller | skipping: Conditional result was False 2025-11-01 00:20:41.325879 | 2025-11-01 00:20:41.326106 | TASK [Run add-build-sshkey role (ECDSA)] 2025-11-01 00:20:41.359099 | controller | ok 2025-11-01 00:20:41.384035 | 2025-11-01 00:20:41.384175 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-11-01 00:20:41.634570 | controller -> localhost | ok 2025-11-01 00:20:41.649092 | 2025-11-01 00:20:41.649230 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-11-01 00:20:41.690226 | controller | ok 2025-11-01 00:20:41.713111 | controller | included: /var/lib/zuul/builds/8a63bc67a69c48aebff7972d1cf34888/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-11-01 00:20:41.721747 | 2025-11-01 00:20:41.721833 | TASK [add-build-sshkey : Create Temp SSH key] 2025-11-01 00:20:42.033886 | controller -> localhost | Generating public/private ecdsa key pair. 2025-11-01 00:20:42.034292 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/8a63bc67a69c48aebff7972d1cf34888/work/8a63bc67a69c48aebff7972d1cf34888_id_ecdsa. 2025-11-01 00:20:42.034357 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/8a63bc67a69c48aebff7972d1cf34888/work/8a63bc67a69c48aebff7972d1cf34888_id_ecdsa.pub. 2025-11-01 00:20:42.034421 | controller -> localhost | The key fingerprint is: 2025-11-01 00:20:42.034468 | controller -> localhost | SHA256:m0CAGN6Hblkh5i9j9nQZn6zgCQBeJNbWF6SYj+IxR5k zuul-build-sshkey 2025-11-01 00:20:42.034512 | controller -> localhost | The key's randomart image is: 2025-11-01 00:20:42.034554 | controller -> localhost | +---[ECDSA 521]---+ 2025-11-01 00:20:42.034596 | controller -> localhost | |o=o*o..o. | 2025-11-01 00:20:42.034637 | controller -> localhost | |* BoBoo. | 2025-11-01 00:20:42.034721 | controller -> localhost | | +.E +o. | 2025-11-01 00:20:42.034768 | controller -> localhost | | + B. = . | 2025-11-01 00:20:42.034809 | controller -> localhost | | + % =.oS+ | 2025-11-01 00:20:42.034849 | controller -> localhost | |. O B +..o | 2025-11-01 00:20:42.034890 | controller -> localhost | | . + .o | 2025-11-01 00:20:42.034930 | controller -> localhost | | | 2025-11-01 00:20:42.034970 | controller -> localhost | | | 2025-11-01 00:20:42.035011 | controller -> localhost | +----[SHA256]-----+ 2025-11-01 00:20:42.035119 | controller -> localhost | ok: Runtime: 0:00:00.013999 2025-11-01 00:20:42.061727 | 2025-11-01 00:20:42.061922 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-11-01 00:20:42.103657 | controller | ok 2025-11-01 00:20:42.118306 | controller | included: /var/lib/zuul/builds/8a63bc67a69c48aebff7972d1cf34888/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-11-01 00:20:42.131089 | 2025-11-01 00:20:42.131183 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-11-01 00:20:42.157340 | controller | skipping: Conditional result was False 2025-11-01 00:20:42.167687 | 2025-11-01 00:20:42.167813 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-11-01 00:20:42.667174 | controller | changed 2025-11-01 00:20:42.683200 | 2025-11-01 00:20:42.683346 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-11-01 00:20:43.083484 | controller | ok 2025-11-01 00:20:43.097306 | 2025-11-01 00:20:43.097519 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-11-01 00:20:44.535790 | controller | changed 2025-11-01 00:20:44.549578 | 2025-11-01 00:20:44.549754 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-11-01 00:20:45.954618 | controller | changed 2025-11-01 00:20:45.972874 | 2025-11-01 00:20:45.973031 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-11-01 00:20:46.001707 | controller | skipping: Conditional result was False 2025-11-01 00:20:46.012071 | 2025-11-01 00:20:46.012207 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-11-01 00:20:46.273968 | controller -> localhost | changed 2025-11-01 00:20:46.340373 | 2025-11-01 00:20:46.340516 | TASK [add-build-sshkey : Add back temp key] 2025-11-01 00:20:46.681087 | controller -> localhost | Identity added: /var/lib/zuul/builds/8a63bc67a69c48aebff7972d1cf34888/work/8a63bc67a69c48aebff7972d1cf34888_id_ecdsa (zuul-build-sshkey) 2025-11-01 00:20:46.681353 | controller -> localhost | ok: Runtime: 0:00:00.007700 2025-11-01 00:20:46.688006 | 2025-11-01 00:20:46.688123 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-11-01 00:20:47.051498 | controller | ok 2025-11-01 00:20:47.063461 | 2025-11-01 00:20:47.063598 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-11-01 00:20:47.101175 | controller | skipping: Conditional result was False 2025-11-01 00:20:47.127147 | 2025-11-01 00:20:47.127323 | TASK [include_role : remove-zuul-sshkey] 2025-11-01 00:20:47.154224 | controller | skipping: Conditional result was False 2025-11-01 00:20:47.166890 | 2025-11-01 00:20:47.167041 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2025-11-01 00:20:47.512372 | controller | ok: "logs" 2025-11-01 00:20:47.512650 | controller | ok: All items complete 2025-11-01 00:20:47.512711 | 2025-11-01 00:20:47.865168 | controller | ok: "artifacts" 2025-11-01 00:20:48.165058 | controller | ok: "docs" 2025-11-01 00:20:48.177982 | 2025-11-01 00:20:48.178243 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2025-11-01 00:20:48.539306 | controller | changed: "logs" 2025-11-01 00:20:48.827016 | controller | changed: "artifacts" 2025-11-01 00:20:49.130386 | controller | changed: "docs" 2025-11-01 00:20:49.198136 | 2025-11-01 00:20:49.198248 | PLAY RECAP 2025-11-01 00:20:49.198296 | controller | ok: 30 changed: 16 unreachable: 0 failed: 0 skipped: 18 rescued: 0 ignored: 0 2025-11-01 00:20:49.198322 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-11-01 00:20:49.198340 | 2025-11-01 00:20:49.315051 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master] 2025-11-01 00:20:49.316002 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2025-11-01 00:20:49.976198 | 2025-11-01 00:20:49.976325 | PLAY [all] 2025-11-01 00:20:49.997992 | 2025-11-01 00:20:49.998108 | TASK [Install binary dependencies] 2025-11-01 00:20:50.074934 | controller | ok 2025-11-01 00:20:50.115735 | 2025-11-01 00:20:50.115919 | TASK [bindep : Include find tasks] 2025-11-01 00:20:50.149245 | controller | ok 2025-11-01 00:20:50.165769 | controller | included: /var/lib/zuul/builds/8a63bc67a69c48aebff7972d1cf34888/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find.yaml 2025-11-01 00:20:50.175108 | 2025-11-01 00:20:50.175213 | TASK [bindep : Look for bindep.txt] 2025-11-01 00:20:50.708423 | controller | ok 2025-11-01 00:20:50.717525 | 2025-11-01 00:20:50.717637 | TASK [bindep : Define bindep_file fact] 2025-11-01 00:20:50.743022 | controller | skipping: Conditional result was False 2025-11-01 00:20:50.751514 | 2025-11-01 00:20:50.751631 | TASK [bindep : Look for other-requirements.txt] 2025-11-01 00:20:51.070729 | controller | ok 2025-11-01 00:20:51.078313 | 2025-11-01 00:20:51.078442 | TASK [bindep : Define bindep_file fact] 2025-11-01 00:20:51.138525 | controller | skipping: Conditional result was False 2025-11-01 00:20:51.147531 | 2025-11-01 00:20:51.147682 | TASK [bindep : Look for bindep fallback file] 2025-11-01 00:20:51.183804 | controller | skipping: Conditional result was False 2025-11-01 00:20:51.193755 | 2025-11-01 00:20:51.193901 | TASK [bindep : Define bindep_file fact] 2025-11-01 00:20:51.219975 | controller | skipping: Conditional result was False 2025-11-01 00:20:51.231809 | 2025-11-01 00:20:51.231986 | TASK [bindep : Include bindep tasks] 2025-11-01 00:20:51.258226 | controller | skipping: Conditional result was False 2025-11-01 00:20:51.269069 | 2025-11-01 00:20:51.269198 | TASK [bindep : Include install tasks] 2025-11-01 00:20:51.294881 | controller | skipping: Conditional result was False 2025-11-01 00:20:51.304198 | 2025-11-01 00:20:51.304315 | LOOP [bindep : Include package tasks] 2025-11-01 00:20:51.376053 | 2025-11-01 00:20:51.376258 | TASK [Run test-setup role] 2025-11-01 00:20:51.398826 | controller | ok 2025-11-01 00:20:51.425464 | 2025-11-01 00:20:51.425657 | TASK [test-setup : Check if project's tools/test-setup.sh exists] 2025-11-01 00:20:51.732879 | controller | ok 2025-11-01 00:20:51.738584 | 2025-11-01 00:20:51.738651 | TASK [test-setup : Run tools/test-setup.sh] 2025-11-01 00:20:51.895180 | controller | skipping: Conditional result was False 2025-11-01 00:20:51.927566 | 2025-11-01 00:20:51.927682 | PLAY RECAP 2025-11-01 00:20:51.927736 | controller | ok: 4 changed: 0 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2025-11-01 00:20:51.927764 | 2025-11-01 00:20:52.027905 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2025-11-01 00:20:52.028757 | PRE-RUN START: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master] 2025-11-01 00:20:52.608086 | 2025-11-01 00:20:52.608244 | PLAY [controller] 2025-11-01 00:20:52.628961 | 2025-11-01 00:20:52.629090 | TASK [Create the /root directory] 2025-11-01 00:20:53.421038 | controller | ok 2025-11-01 00:20:53.430645 | 2025-11-01 00:20:53.430827 | TASK [Install glibc-langpack-en] 2025-11-01 00:21:01.571830 | controller | ok: Nothing to do 2025-11-01 00:21:01.585176 | 2025-11-01 00:21:01.585326 | TASK [Ensure controller directory exists] 2025-11-01 00:21:02.818263 | controller | changed 2025-11-01 00:21:02.833295 | 2025-11-01 00:21:02.833441 | TASK [Install container runtime] 2025-11-01 00:21:02.898092 | controller | ok 2025-11-01 00:21:02.943088 | 2025-11-01 00:21:02.943219 | LOOP [ensure-docker : Gather variables for each operating system] 2025-11-01 00:21:02.981261 | controller | ok: "/var/lib/zuul/builds/8a63bc67a69c48aebff7972d1cf34888/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/vars/fedora.yaml" 2025-11-01 00:21:02.996449 | 2025-11-01 00:21:02.996566 | TASK [ensure-docker : Sanity check for distro selection] 2025-11-01 00:21:03.013233 | controller | skipping: Conditional result was False 2025-11-01 00:21:03.020376 | 2025-11-01 00:21:03.020511 | TASK [ensure-docker : Sanity check for distro install] 2025-11-01 00:21:03.046943 | controller | skipping: Conditional result was False 2025-11-01 00:21:03.053891 | 2025-11-01 00:21:03.054038 | TASK [ensure-docker : Sanity check for upstream install] 2025-11-01 00:21:03.085230 | controller | skipping: Conditional result was False 2025-11-01 00:21:03.092700 | 2025-11-01 00:21:03.092829 | TASK [ensure-docker : Sanity check for upstream install mirrors] 2025-11-01 00:21:03.116722 | controller | skipping: Conditional result was False 2025-11-01 00:21:03.123332 | 2025-11-01 00:21:03.123442 | TASK [ensure-docker : Include tasks for docker install] 2025-11-01 00:21:03.142785 | controller | ok 2025-11-01 00:21:03.150936 | controller | included: /var/lib/zuul/builds/8a63bc67a69c48aebff7972d1cf34888/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/tasks/docker-upstream.yaml 2025-11-01 00:21:03.159209 | 2025-11-01 00:21:03.159276 | TASK [ensure-docker : Not Implemented] 2025-11-01 00:21:03.173632 | controller | skipping: Conditional result was False 2025-11-01 00:21:03.179944 | 2025-11-01 00:21:03.180032 | TASK [Set up docker mirrors] 2025-11-01 00:21:03.201050 | controller | ok 2025-11-01 00:21:03.223606 | 2025-11-01 00:21:03.223787 | TASK [use-docker-mirror : Set docker_mirror fact from mirror_info] 2025-11-01 00:21:03.258142 | controller | skipping: Conditional result was False 2025-11-01 00:21:03.264961 | 2025-11-01 00:21:03.265043 | TASK [use-docker-mirror : Set docker_mirror fact from zuul_site_mirror_info] 2025-11-01 00:21:03.288773 | controller | skipping: Conditional result was False 2025-11-01 00:21:03.295227 | 2025-11-01 00:21:03.295308 | TASK [use-docker-mirror : Set docker_mirror fact] 2025-11-01 00:21:03.319029 | controller | skipping: Conditional result was False 2025-11-01 00:21:03.325830 | 2025-11-01 00:21:03.325931 | TASK [use-docker-mirror : Set up docker mirrors] 2025-11-01 00:21:03.350501 | controller | skipping: Conditional result was False 2025-11-01 00:21:03.363493 | 2025-11-01 00:21:03.363584 | TASK [Add all repositories] 2025-11-01 00:21:03.419315 | controller | ok 2025-11-01 00:21:03.445537 | 2025-11-01 00:21:03.445707 | LOOP [ensure-package-repositories : Install repositories] 2025-11-01 00:21:03.469243 | controller | ok: "/var/lib/zuul/builds/8a63bc67a69c48aebff7972d1cf34888/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-package-repositories/tasks/RedHat.yaml" 2025-11-01 00:21:03.469462 | controller | ok: All items complete 2025-11-01 00:21:03.469494 | 2025-11-01 00:21:03.479167 | controller | included: /var/lib/zuul/builds/8a63bc67a69c48aebff7972d1cf34888/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-package-repositories/tasks/RedHat.yaml 2025-11-01 00:21:03.485684 | 2025-11-01 00:21:03.485779 | TASK [ensure-package-repositories : Create GPG key temp directory] 2025-11-01 00:21:04.045201 | controller | changed 2025-11-01 00:21:04.063359 | 2025-11-01 00:21:04.063550 | LOOP [ensure-package-repositories : Upload GPG keys] 2025-11-01 00:21:05.746616 | controller | changed: 2025-11-01 00:21:05.746860 | controller | { 2025-11-01 00:21:05.746899 | 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-01 00:21:05.746931 | controller | } 2025-11-01 00:21:05.765064 | 2025-11-01 00:21:05.765389 | LOOP [ensure-package-repositories : Import GPG keys] 2025-11-01 00:21:06.517403 | controller | changed: 2025-11-01 00:21:06.517681 | controller | { 2025-11-01 00:21:06.517721 | 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-01 00:21:06.517750 | controller | } 2025-11-01 00:21:06.517790 | controller | changed: All items complete 2025-11-01 00:21:06.517816 | 2025-11-01 00:21:06.529026 | 2025-11-01 00:21:06.529180 | TASK [ensure-package-repositories : Remove GPG temp directory] 2025-11-01 00:21:06.920074 | controller | changed 2025-11-01 00:21:06.928086 | 2025-11-01 00:21:06.928181 | LOOP [ensure-package-repositories : Add repositories] 2025-11-01 00:21:07.437142 | controller | changed: 2025-11-01 00:21:07.437425 | controller | { 2025-11-01 00:21:07.437483 | controller | "baseurl": "https://download.docker.com/linux/fedora/$releasever/$basearch/stable", 2025-11-01 00:21:07.437526 | controller | "description": "Docker CE Stable - $basearch", 2025-11-01 00:21:07.437563 | controller | "gpgcheck": true, 2025-11-01 00:21:07.437610 | controller | "name": "docker-ce-stable" 2025-11-01 00:21:07.437694 | controller | } 2025-11-01 00:21:07.481852 | 2025-11-01 00:21:07.482083 | TASK [ensure-docker : Install pre-reqs] 2025-11-01 00:21:10.982407 | controller | ok: Nothing to do 2025-11-01 00:21:10.995232 | 2025-11-01 00:21:10.995426 | TASK [ensure-docker : Remove packages] 2025-11-01 00:21:13.229779 | controller | ok: Nothing to do 2025-11-01 00:21:13.242154 | 2025-11-01 00:21:13.242323 | TASK [ensure-docker : Install upstream docker using package] 2025-11-01 00:22:08.486810 | controller | changed 2025-11-01 00:22:08.492373 | 2025-11-01 00:22:08.492434 | TASK [ensure-docker : Setup docker] 2025-11-01 00:22:08.527065 | controller | ok 2025-11-01 00:22:08.552298 | controller | included: /var/lib/zuul/builds/8a63bc67a69c48aebff7972d1cf34888/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/tasks/docker-setup.yaml 2025-11-01 00:22:08.570274 | 2025-11-01 00:22:08.570419 | TASK [ensure-docker : Ensure "docker" group exists] 2025-11-01 00:22:09.239012 | controller | ok 2025-11-01 00:22:09.254322 | 2025-11-01 00:22:09.254494 | TASK [ensure-docker : Add user to docker group] 2025-11-01 00:22:09.980621 | controller | changed 2025-11-01 00:22:10.001103 | 2025-11-01 00:22:10.001296 | TASK [Update docker daemon proxy configuration] 2025-11-01 00:22:10.049059 | controller | skipping: Conditional result was False 2025-11-01 00:22:10.062890 | 2025-11-01 00:22:10.063036 | TASK [Lower default MTU] 2025-11-01 00:22:10.090138 | controller | skipping: Conditional result was False 2025-11-01 00:22:10.108727 | 2025-11-01 00:22:10.108925 | TASK [ensure-docker : Get the status of the docket.socket unit] 2025-11-01 00:22:10.635082 | controller | inactive 2025-11-01 00:22:10.757896 | controller | ok: Runtime: 0:00:00.011618 2025-11-01 00:22:10.770751 | 2025-11-01 00:22:10.770903 | TASK [ensure-docker : Get the status of the docket unit] 2025-11-01 00:22:11.255253 | controller | inactive 2025-11-01 00:22:11.430432 | controller | ok: Runtime: 0:00:00.017924 2025-11-01 00:22:11.503620 | 2025-11-01 00:22:11.503836 | TASK [ensure-docker : Restart docker] 2025-11-01 00:22:11.558853 | Trigger a Docker restart 2025-11-01 00:22:11.568356 | 2025-11-01 00:22:11.568470 | TASK [ensure-docker : Reset ssh connection to pick up docker group] 2025-11-01 00:22:11.609510 | 2025-11-01 00:22:11.609656 | TASK [ensure-docker : Install docker-compose] 2025-11-01 00:22:11.637090 | controller | skipping: Conditional result was False 2025-11-01 00:22:11.646496 | 2025-11-01 00:22:11.646614 | TASK [ensure-docker : Flush handlers before role exit] 2025-11-01 00:22:11.664316 | 2025-11-01 00:22:11.664586 | TASK [ensure-docker : Stop docker.socket to avoid any conflict] 2025-11-01 00:22:14.033525 | controller | changed 2025-11-01 00:22:14.051098 | 2025-11-01 00:22:14.051274 | TASK [ensure-docker : Assure docker service is running] 2025-11-01 00:22:17.046875 | controller | changed 2025-11-01 00:22:17.065245 | 2025-11-01 00:22:17.065421 | TASK [ensure-docker : Assure docker.socket service is running] 2025-11-01 00:22:18.373235 | controller | ok 2025-11-01 00:22:18.391154 | 2025-11-01 00:22:18.391347 | TASK [ensure-docker : Correct group ownership on docker sock] 2025-11-01 00:22:19.241518 | controller | ok 2025-11-01 00:22:19.255653 | 2025-11-01 00:22:19.255987 | TASK [ensure-docker : Validate ability to talk with docker] 2025-11-01 00:22:20.000073 | controller | CONTAINER ID IMAGE COMMAND CREATED STATUS PORTS NAMES 2025-11-01 00:22:20.423891 | controller | ok: Runtime: 0:00:00.062692 2025-11-01 00:22:20.436814 | 2025-11-01 00:22:20.437259 | TASK [ensure-docker : Get version details] 2025-11-01 00:22:21.105658 | controller | Client: Docker Engine - Community 2025-11-01 00:22:21.107781 | controller | Version: 24.0.7 2025-11-01 00:22:21.107819 | controller | API version: 1.43 2025-11-01 00:22:21.107831 | controller | Go version: go1.20.10 2025-11-01 00:22:21.107856 | controller | Git commit: afdd53b 2025-11-01 00:22:21.107867 | controller | Built: Thu Oct 26 09:09:39 2023 2025-11-01 00:22:21.107877 | controller | OS/Arch: linux/amd64 2025-11-01 00:22:21.107891 | controller | Context: default 2025-11-01 00:22:21.107904 | controller | 2025-11-01 00:22:21.107914 | controller | Server: Docker Engine - Community 2025-11-01 00:22:21.107923 | controller | Engine: 2025-11-01 00:22:21.107932 | controller | Version: 24.0.7 2025-11-01 00:22:21.107941 | controller | API version: 1.43 (minimum version 1.12) 2025-11-01 00:22:21.107950 | controller | Go version: go1.20.10 2025-11-01 00:22:21.107959 | controller | Git commit: 311b9ff 2025-11-01 00:22:21.107968 | controller | Built: Thu Oct 26 09:08:11 2023 2025-11-01 00:22:21.107978 | controller | OS/Arch: linux/amd64 2025-11-01 00:22:21.107987 | controller | Experimental: false 2025-11-01 00:22:21.107996 | controller | containerd: 2025-11-01 00:22:21.108005 | controller | Version: 1.6.25 2025-11-01 00:22:21.108014 | controller | GitCommit: d8f198a4ed8892c764191ef7b3b06d8a2eeb5c7f 2025-11-01 00:22:21.108023 | controller | runc: 2025-11-01 00:22:21.108032 | controller | Version: 1.1.10 2025-11-01 00:22:21.108041 | controller | GitCommit: v1.1.10-0-g18a0cb0 2025-11-01 00:22:21.108053 | controller | docker-init: 2025-11-01 00:22:21.108062 | controller | Version: 0.19.0 2025-11-01 00:22:21.108073 | controller | GitCommit: de40ad0 2025-11-01 00:22:21.594345 | controller | ok: Runtime: 0:00:00.042861 2025-11-01 00:22:21.607026 | 2025-11-01 00:22:21.607167 | TASK [ensure-docker : Dump installed docker details] 2025-11-01 00:22:21.652557 | {'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-01 00:22:21.065675', 'end': '2025-11-01 00:22:21.108536', 'delta': '0:00:00.042861', 'msg': '', 'zuul_log_id': 'fa163efc-24cc-43d8-7bcf-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-01 00:22:21.666991 | 2025-11-01 00:22:21.667150 | TASK [Use buildset registry] 2025-11-01 00:22:21.695955 | controller | skipping: Conditional result was False 2025-11-01 00:22:21.720070 | 2025-11-01 00:22:21.720215 | TASK [Ensure python3.8 is present] 2025-11-01 00:22:21.747134 | controller | skipping: Conditional result was False 2025-11-01 00:22:21.761400 | 2025-11-01 00:22:21.761541 | TASK [Run our-ensure-python role (Fedora only for now)] 2025-11-01 00:22:21.791237 | controller | ok 2025-11-01 00:22:21.818403 | 2025-11-01 00:22:21.818529 | TASK [our-ensure-python : Install the right Python version (rpm)] 2025-11-01 00:22:27.831858 | controller | ok: Nothing to do 2025-11-01 00:22:27.845186 | 2025-11-01 00:22:27.845343 | TASK [our-ensure-python : Also install python3-devel] 2025-11-01 00:22:41.762643 | controller | changed 2025-11-01 00:22:41.791936 | 2025-11-01 00:22:41.792123 | TASK [Run ensure-virtualenv role] 2025-11-01 00:22:41.817902 | controller | ok 2025-11-01 00:22:41.847574 | 2025-11-01 00:22:41.847743 | TASK [ensure-virtualenv : Check if virtualenv is installed] 2025-11-01 00:22:42.216322 | controller | /usr/bin/virtualenv 2025-11-01 00:22:42.488949 | controller | ok: Runtime: 0:00:00.004799 2025-11-01 00:22:42.503161 | 2025-11-01 00:22:42.503317 | LOOP [ensure-virtualenv : Install virtualenv package] 2025-11-01 00:22:42.545641 | controller | skipping: Conditional result was False 2025-11-01 00:22:42.546086 | controller | changed: All items complete 2025-11-01 00:22:42.546117 | 2025-11-01 00:22:42.583964 | 2025-11-01 00:22:42.584153 | TASK [Find the full path of the Python interpreter] 2025-11-01 00:22:43.027692 | controller | /usr/bin/python3.9 2025-11-01 00:22:43.231308 | controller | ok 2025-11-01 00:22:43.246258 | 2025-11-01 00:22:43.246454 | TASK [Create virtualenv for ansible-test with a specific interpreter] 2025-11-01 00:22:45.454315 | controller | created virtual environment CPython3.9.18.final.0-64 in 1106ms 2025-11-01 00:22:45.529778 | controller | creator CPython3Posix(dest=/home/zuul-worker/venv, clear=False, no_vcs_ignore=False, global=False) 2025-11-01 00:22:45.529881 | 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-worker/.local/share/virtualenv) 2025-11-01 00:22:45.529900 | controller | added seed packages: pip==22.2.2, setuptools==62.6.0, wheel==0.37.1 2025-11-01 00:22:45.529922 | controller | activators BashActivator,CShellActivator,FishActivator,NushellActivator,PowerShellActivator,PythonActivator 2025-11-01 00:22:45.911090 | controller | changed 2025-11-01 00:22:45.923266 | 2025-11-01 00:22:45.923406 | TASK [Set selinux package] 2025-11-01 00:22:45.962757 | controller | ok 2025-11-01 00:22:45.973801 | 2025-11-01 00:22:45.973935 | TASK [Set selinux package (Fedora)] 2025-11-01 00:22:46.022135 | controller | ok 2025-11-01 00:22:46.032974 | 2025-11-01 00:22:46.033129 | TASK [Install selinux into virtualenv] 2025-11-01 00:22:49.435335 | controller | Collecting selinux-please-lie-to-me 2025-11-01 00:22:49.568659 | controller | Downloading selinux_please_lie_to_me-1.0.1-py2.py3-none-any.whl (3.8 kB) 2025-11-01 00:22:50.475954 | controller | Collecting setuptools<50.0.0 2025-11-01 00:22:50.490863 | controller | Downloading setuptools-49.6.0-py3-none-any.whl (803 kB) 2025-11-01 00:22:50.614186 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 803.3/803.3 kB 7.2 MB/s eta 0:00:00 2025-11-01 00:22:50.794966 | controller | Installing collected packages: setuptools, selinux-please-lie-to-me 2025-11-01 00:22:50.795512 | controller | Attempting uninstall: setuptools 2025-11-01 00:22:50.797091 | controller | Found existing installation: setuptools 62.6.0 2025-11-01 00:22:50.964059 | controller | Uninstalling setuptools-62.6.0: 2025-11-01 00:22:50.988888 | controller | Successfully uninstalled setuptools-62.6.0 2025-11-01 00:22:51.871609 | controller | Successfully installed selinux-please-lie-to-me-1.0.1 setuptools-49.6.0 2025-11-01 00:22:52.196893 | controller | 2025-11-01 00:22:52.522076 | controller | [notice] A new release of pip available: 22.2.2 -> 25.3 2025-11-01 00:22:52.522127 | controller | [notice] To update, run: /home/zuul-worker/venv/bin/python -m pip install --upgrade pip 2025-11-01 00:22:52.689306 | controller | ok: Runtime: 0:00:05.839590 2025-11-01 00:22:52.702566 | 2025-11-01 00:22:52.702758 | TASK [Install pytest-forked into virtualenv] 2025-11-01 00:22:54.297044 | controller | Collecting pytest-forked 2025-11-01 00:22:54.422218 | controller | Downloading pytest_forked-1.6.0-py3-none-any.whl (4.9 kB) 2025-11-01 00:22:54.522291 | controller | Collecting py 2025-11-01 00:22:54.534920 | controller | Downloading py-1.11.0-py2.py3-none-any.whl (98 kB) 2025-11-01 00:22:54.576254 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 98.7/98.7 kB 3.0 MB/s eta 0:00:00 2025-11-01 00:22:54.820065 | controller | Collecting pytest>=3.10 2025-11-01 00:22:54.833930 | controller | Downloading pytest-8.4.2-py3-none-any.whl (365 kB) 2025-11-01 00:22:54.891769 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 365.8/365.8 kB 6.8 MB/s eta 0:00:00 2025-11-01 00:22:55.078771 | controller | Collecting tomli>=1 2025-11-01 00:22:55.095650 | controller | Downloading tomli-2.3.0-py3-none-any.whl (14 kB) 2025-11-01 00:22:55.152315 | controller | Collecting iniconfig>=1 2025-11-01 00:22:55.169055 | controller | Downloading iniconfig-2.1.0-py3-none-any.whl (6.0 kB) 2025-11-01 00:22:55.258789 | controller | Collecting pluggy<2,>=1.5 2025-11-01 00:22:55.278634 | controller | Downloading pluggy-1.6.0-py3-none-any.whl (20 kB) 2025-11-01 00:22:55.338537 | controller | Collecting exceptiongroup>=1 2025-11-01 00:22:55.351560 | controller | Downloading exceptiongroup-1.3.0-py3-none-any.whl (16 kB) 2025-11-01 00:22:55.488010 | controller | Collecting packaging>=20 2025-11-01 00:22:55.501292 | controller | Downloading packaging-25.0-py3-none-any.whl (66 kB) 2025-11-01 00:22:55.513203 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 66.5/66.5 kB 8.4 MB/s eta 0:00:00 2025-11-01 00:22:55.655101 | controller | Collecting pygments>=2.7.2 2025-11-01 00:22:55.670470 | controller | Downloading pygments-2.19.2-py3-none-any.whl (1.2 MB) 2025-11-01 00:22:55.784248 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 1.2/1.2 MB 11.1 MB/s eta 0:00:00 2025-11-01 00:22:55.941109 | controller | Collecting typing-extensions>=4.6.0 2025-11-01 00:22:55.953943 | controller | Downloading typing_extensions-4.15.0-py3-none-any.whl (44 kB) 2025-11-01 00:22:55.964509 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 44.6/44.6 kB 5.8 MB/s eta 0:00:00 2025-11-01 00:22:56.093029 | controller | Installing collected packages: typing-extensions, tomli, pygments, py, pluggy, packaging, iniconfig, exceptiongroup, pytest, pytest-forked 2025-11-01 00:22:58.322459 | controller | Successfully installed exceptiongroup-1.3.0 iniconfig-2.1.0 packaging-25.0 pluggy-1.6.0 py-1.11.0 pygments-2.19.2 pytest-8.4.2 pytest-forked-1.6.0 tomli-2.3.0 typing-extensions-4.15.0 2025-11-01 00:22:58.329598 | controller | 2025-11-01 00:22:58.518614 | controller | [notice] A new release of pip available: 22.2.2 -> 25.3 2025-11-01 00:22:58.518667 | controller | [notice] To update, run: /home/zuul-worker/venv/bin/python -m pip install --upgrade pip 2025-11-01 00:22:58.860482 | controller | ok: Runtime: 0:00:05.273625 2025-11-01 00:22:58.868348 | 2025-11-01 00:22:58.868435 | TASK [Update pip] 2025-11-01 00:23:00.721594 | controller | Requirement already satisfied: pip in ./venv/lib/python3.9/site-packages (22.2.2) 2025-11-01 00:23:01.105303 | controller | Collecting pip 2025-11-01 00:23:01.195963 | controller | Downloading pip-25.3-py3-none-any.whl (1.8 MB) 2025-11-01 00:23:01.377387 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 1.8/1.8 MB 10.2 MB/s eta 0:00:00 2025-11-01 00:23:01.613102 | controller | Installing collected packages: pip 2025-11-01 00:23:01.613442 | controller | Attempting uninstall: pip 2025-11-01 00:23:01.617064 | controller | Found existing installation: pip 22.2.2 2025-11-01 00:23:02.364961 | controller | Uninstalling pip-22.2.2: 2025-11-01 00:23:02.445798 | controller | Successfully uninstalled pip-22.2.2 2025-11-01 00:23:04.832052 | controller | Successfully installed pip-25.3 2025-11-01 00:23:05.518297 | controller | ok: Runtime: 0:00:05.426180 2025-11-01 00:23:05.532169 | 2025-11-01 00:23:05.532365 | TASK [Ensure pip temp directory exists in /var/tmp] 2025-11-01 00:23:06.339106 | controller | changed 2025-11-01 00:23:06.351383 | 2025-11-01 00:23:06.351553 | TASK [Install ansible into virtualenv] 2025-11-01 00:23:07.902197 | controller | Processing ./src/github.com/ansible/ansible 2025-11-01 00:23:07.911033 | controller | Installing build dependencies: started 2025-11-01 00:23:10.085143 | controller | Installing build dependencies: finished with status 'done' 2025-11-01 00:23:10.087035 | controller | Getting requirements to build wheel: started 2025-11-01 00:23:11.309459 | controller | Getting requirements to build wheel: finished with status 'done' 2025-11-01 00:23:11.311783 | controller | Preparing metadata (pyproject.toml): started 2025-11-01 00:23:12.216812 | controller | Preparing metadata (pyproject.toml): finished with status 'done' 2025-11-01 00:23:12.338395 | controller | Collecting jinja2 (from ansible-core==2.11.12.post0) 2025-11-01 00:23:12.447244 | controller | Downloading jinja2-3.1.6-py3-none-any.whl.metadata (2.9 kB) 2025-11-01 00:23:12.567443 | controller | Collecting PyYAML (from ansible-core==2.11.12.post0) 2025-11-01 00:23:12.580455 | controller | Downloading pyyaml-6.0.3-cp39-cp39-manylinux2014_x86_64.manylinux_2_17_x86_64.manylinux_2_28_x86_64.whl.metadata (2.4 kB) 2025-11-01 00:23:13.088638 | controller | Collecting cryptography (from ansible-core==2.11.12.post0) 2025-11-01 00:23:13.101653 | controller | Downloading cryptography-46.0.3-cp38-abi3-manylinux_2_34_x86_64.whl.metadata (5.7 kB) 2025-11-01 00:23:13.126605 | controller | Requirement already satisfied: packaging in ./venv/lib/python3.9/site-packages (from ansible-core==2.11.12.post0) (25.0) 2025-11-01 00:23:13.152075 | controller | Collecting resolvelib<0.6.0,>=0.5.3 (from ansible-core==2.11.12.post0) 2025-11-01 00:23:13.165963 | controller | Downloading resolvelib-0.5.4-py2.py3-none-any.whl.metadata (3.7 kB) 2025-11-01 00:23:13.445380 | controller | Collecting cffi>=2.0.0 (from cryptography->ansible-core==2.11.12.post0) 2025-11-01 00:23:13.458664 | controller | Downloading cffi-2.0.0-cp39-cp39-manylinux2014_x86_64.manylinux_2_17_x86_64.whl.metadata (2.6 kB) 2025-11-01 00:23:13.469574 | controller | Requirement already satisfied: typing-extensions>=4.13.2 in ./venv/lib/python3.9/site-packages (from cryptography->ansible-core==2.11.12.post0) (4.15.0) 2025-11-01 00:23:13.491750 | controller | Collecting pycparser (from cffi>=2.0.0->cryptography->ansible-core==2.11.12.post0) 2025-11-01 00:23:13.504472 | controller | Downloading pycparser-2.23-py3-none-any.whl.metadata (993 bytes) 2025-11-01 00:23:13.646718 | controller | Collecting MarkupSafe>=2.0 (from jinja2->ansible-core==2.11.12.post0) 2025-11-01 00:23:13.659627 | controller | Downloading markupsafe-3.0.3-cp39-cp39-manylinux2014_x86_64.manylinux_2_17_x86_64.manylinux_2_28_x86_64.whl.metadata (2.7 kB) 2025-11-01 00:23:13.692365 | controller | Downloading resolvelib-0.5.4-py2.py3-none-any.whl (12 kB) 2025-11-01 00:23:13.713826 | controller | Downloading cryptography-46.0.3-cp38-abi3-manylinux_2_34_x86_64.whl (4.5 MB) 2025-11-01 00:23:13.798878 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 4.5/4.5 MB 77.2 MB/s 0:00:00 2025-11-01 00:23:13.811676 | controller | Downloading cffi-2.0.0-cp39-cp39-manylinux2014_x86_64.manylinux_2_17_x86_64.whl (216 kB) 2025-11-01 00:23:13.836941 | controller | Downloading jinja2-3.1.6-py3-none-any.whl (134 kB) 2025-11-01 00:23:13.860276 | controller | Downloading markupsafe-3.0.3-cp39-cp39-manylinux2014_x86_64.manylinux_2_17_x86_64.manylinux_2_28_x86_64.whl (20 kB) 2025-11-01 00:23:13.881208 | controller | Downloading pycparser-2.23-py3-none-any.whl (118 kB) 2025-11-01 00:23:13.903226 | controller | Downloading pyyaml-6.0.3-cp39-cp39-manylinux2014_x86_64.manylinux_2_17_x86_64.manylinux_2_28_x86_64.whl (750 kB) 2025-11-01 00:23:13.922358 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 750.8/750.8 kB 35.8 MB/s 0:00:00 2025-11-01 00:23:13.967216 | controller | Building wheels for collected packages: ansible-core 2025-11-01 00:23:13.969086 | controller | Building wheel for ansible-core (pyproject.toml): started 2025-11-01 00:23:16.342179 | controller | Building wheel for ansible-core (pyproject.toml): finished with status 'done' 2025-11-01 00:23:16.348880 | controller | Created wheel for ansible-core: filename=ansible_core-2.11.12.post0-py3-none-any.whl size=1961250 sha256=2f328a9c06eb804a5b17c37edd6178e54d5333f63496c8267043868c422dbe5f 2025-11-01 00:23:16.349169 | controller | Stored in directory: /var/tmp/ansible-test-pip/pip-ephem-wheel-cache-hhnmld5a/wheels/e8/fd/e9/6250c469bad44a7c6855190836e9d22c0bb11cb641caf57fbe 2025-11-01 00:23:16.358577 | controller | Successfully built ansible-core 2025-11-01 00:23:16.400269 | controller | Installing collected packages: resolvelib, PyYAML, pycparser, MarkupSafe, jinja2, cffi, cryptography, ansible-core 2025-11-01 00:23:18.356415 | controller | 2025-11-01 00:23:18.360062 | controller | Successfully installed MarkupSafe-3.0.3 PyYAML-6.0.3 ansible-core-2.11.12.post0 cffi-2.0.0 cryptography-46.0.3 jinja2-3.1.6 pycparser-2.23 resolvelib-0.5.4 2025-11-01 00:23:19.056073 | controller | ok: Runtime: 0:00:11.521026 2025-11-01 00:23:19.069870 | 2025-11-01 00:23:19.070013 | TASK [Setup download-artifact-fork role] 2025-11-01 00:23:19.100640 | controller | ok 2025-11-01 00:23:19.130919 | 2025-11-01 00:23:19.131059 | TASK [download-artifact-fork : Parse inventory response] 2025-11-01 00:23:19.174452 | controller | ok 2025-11-01 00:23:19.183204 | 2025-11-01 00:23:19.183305 | TASK [download-artifact-fork : Fetch info from Zuul API] 2025-11-01 00:23:19.208974 | controller | skipping: Conditional result was False 2025-11-01 00:23:19.219924 | 2025-11-01 00:23:19.220029 | TASK [download-artifact-fork : Parse build response] 2025-11-01 00:23:19.246442 | controller | skipping: Conditional result was False 2025-11-01 00:23:19.257536 | 2025-11-01 00:23:19.257636 | TASK [download-artifact-fork : Ensure artifacts directory exists] 2025-11-01 00:23:19.760587 | controller | changed 2025-11-01 00:23:19.773697 | 2025-11-01 00:23:19.773853 | LOOP [download-artifact-fork : Download archive by type] 2025-11-01 00:23:19.802907 | controller | skipping: Conditional result was False 2025-11-01 00:23:19.803232 | 2025-11-01 00:23:21.249863 | controller | changed: OK (20195 bytes) 2025-11-01 00:23:21.278278 | 2025-11-01 00:23:21.278472 | TASK [deploy-artifacts : define __collections] 2025-11-01 00:23:21.316808 | controller | ok 2025-11-01 00:23:21.329102 | 2025-11-01 00:23:21.329274 | LOOP [deploy-artifacts : Create list of collections] 2025-11-01 00:23:21.366819 | controller | skipping: Conditional result was False 2025-11-01 00:23:21.387880 | controller | ok: 2025-11-01 00:23:21.388028 | controller | { 2025-11-01 00:23:21.388080 | controller | "branch": "main", 2025-11-01 00:23:21.388129 | controller | "job": "build-ansible-collection", 2025-11-01 00:23:21.388167 | controller | "metadata": { 2025-11-01 00:23:21.388203 | controller | "type": "ansible_collection", 2025-11-01 00:23:21.388238 | controller | "version": "5.0.0" 2025-11-01 00:23:21.388273 | controller | }, 2025-11-01 00:23:21.388307 | controller | "name": "ansible.network", 2025-11-01 00:23:21.388340 | controller | "project": "ansible-collections/ansible.network", 2025-11-01 00:23:21.388374 | controller | "url": "https://9394b742839ff234e4eb-ef798df73aa4a2c34d0c454baba00769.ssl.cf1.rackcdn.com/ansible/13e68b360c224f4b8df7e0540a7bfa0e/artifacts/ansible-network-5.0.0.tar.gz" 2025-11-01 00:23:21.388429 | controller | } 2025-11-01 00:23:21.402536 | 2025-11-01 00:23:21.402707 | TASK [deploy-artifacts : Install require-project collection using ansible-galaxy] 2025-11-01 00:23:22.689229 | controller | Starting galaxy collection install process 2025-11-01 00:23:22.689524 | controller | Process install dependency map 2025-11-01 00:23:24.563214 | controller | [WARNING]: Skipping Galaxy server https://galaxy.ansible.com/api/. Got an 2025-11-01 00:23:24.563492 | controller | unexpected error when getting available versions of collection 2025-11-01 00:23:24.563543 | controller | ansible.netcommon: '/api/v3/plugin/ansible/content/published/collections/index/ 2025-11-01 00:23:24.563554 | controller | ansible/netcommon/versions/' 2025-11-01 00:23:24.563569 | controller | ERROR! Unexpected Exception, this is probably a bug: '/api/v3/plugin/ansible/content/published/collections/index/ansible/netcommon/versions/' 2025-11-01 00:23:24.647632 | controller | to see the full traceback, use -vvv 2025-11-01 00:23:25.065364 | controller | ERROR 2025-11-01 00:23:25.065703 | controller | { 2025-11-01 00:23:25.065794 | controller | "delta": "0:00:02.755665", 2025-11-01 00:23:25.065843 | controller | "end": "2025-11-01 00:23:24.648137", 2025-11-01 00:23:25.065883 | controller | "msg": "non-zero return code", 2025-11-01 00:23:25.065922 | controller | "rc": 250, 2025-11-01 00:23:25.065959 | controller | "start": "2025-11-01 00:23:21.892472" 2025-11-01 00:23:25.066059 | controller | } failure 2025-11-01 00:23:25.069629 | 2025-11-01 00:23:25.070290 | PLAY RECAP 2025-11-01 00:23:25.070408 | controller | ok: 44 changed: 24 unreachable: 0 failed: 1 skipped: 17 rescued: 0 ignored: 0 2025-11-01 00:23:25.070468 | 2025-11-01 00:23:25.231226 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master] 2025-11-01 00:23:25.232257 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2025-11-01 00:23:25.828487 | 2025-11-01 00:23:25.828609 | PLAY [all] 2025-11-01 00:23:25.849896 | 2025-11-01 00:23:25.850027 | TASK [fetch-subunit-output : Find stestr or testr executable] 2025-11-01 00:23:27.291070 | controller | changed: non-zero return code 2025-11-01 00:23:27.303333 | 2025-11-01 00:23:27.303498 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2025-11-01 00:23:27.331204 | controller | skipping: Conditional result was False 2025-11-01 00:23:27.344206 | 2025-11-01 00:23:27.344374 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2025-11-01 00:23:27.386917 | 2025-11-01 00:23:27.387215 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2025-11-01 00:23:27.427354 | 2025-11-01 00:23:27.427617 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2025-11-01 00:23:27.454987 | controller | skipping: Conditional result was False 2025-11-01 00:23:27.469486 | 2025-11-01 00:23:27.469639 | LOOP [fetch-subunit-output : Generate subunit file] 2025-11-01 00:23:27.509456 | 2025-11-01 00:23:27.509777 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2025-11-01 00:23:27.537406 | controller | skipping: Conditional result was False 2025-11-01 00:23:27.550041 | 2025-11-01 00:23:27.550193 | TASK [fetch-subunit-output : Remove the temporary file] 2025-11-01 00:23:27.577956 | controller | skipping: Conditional result was False 2025-11-01 00:23:27.592186 | 2025-11-01 00:23:27.592347 | TASK [fetch-subunit-output : Process and fetch subunit results] 2025-11-01 00:23:27.621161 | controller | skipping: Conditional result was False 2025-11-01 00:23:27.668828 | 2025-11-01 00:23:27.668923 | PLAY RECAP 2025-11-01 00:23:27.668980 | controller | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2025-11-01 00:23:27.669009 | 2025-11-01 00:23:27.768342 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2025-11-01 00:23:27.769248 | POST-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/post.yaml@master] 2025-11-01 00:23:28.376992 | 2025-11-01 00:23:28.377101 | PLAY [all:!appliance*] 2025-11-01 00:23:28.398224 | 2025-11-01 00:23:28.398339 | TASK [unregister the node] 2025-11-01 00:23:28.542163 | controller | skipping: Conditional result was False 2025-11-01 00:23:28.554758 | 2025-11-01 00:23:28.554933 | TASK [include_role : fetch-output] 2025-11-01 00:23:28.604307 | controller | ok 2025-11-01 00:23:28.639833 | 2025-11-01 00:23:28.639951 | TASK [fetch-output : Set log path for multiple nodes] 2025-11-01 00:23:28.716137 | controller | skipping: Conditional result was False 2025-11-01 00:23:28.724238 | 2025-11-01 00:23:28.724333 | TASK [fetch-output : Set log path for single node] 2025-11-01 00:23:28.768799 | controller | ok 2025-11-01 00:23:28.776229 | 2025-11-01 00:23:28.776317 | LOOP [fetch-output : Ensure local output dirs] 2025-11-01 00:23:29.227113 | controller -> localhost | ok: "/var/lib/zuul/builds/8a63bc67a69c48aebff7972d1cf34888/work/logs" 2025-11-01 00:23:29.509002 | controller -> localhost | changed: "/var/lib/zuul/builds/8a63bc67a69c48aebff7972d1cf34888/work/artifacts" 2025-11-01 00:23:29.795037 | controller -> localhost | changed: "/var/lib/zuul/builds/8a63bc67a69c48aebff7972d1cf34888/work/docs" 2025-11-01 00:23:29.819243 | 2025-11-01 00:23:29.819460 | LOOP [fetch-output : Collect logs, artifacts and docs] 2025-11-01 00:23:31.099006 | controller | changed: 2025-11-01 00:23:31.099469 | controller | .d..t...... ./ 2025-11-01 00:23:31.099592 | controller | cd+++++++++ controller/ 2025-11-01 00:23:31.100039 | controller | changed: All items complete 2025-11-01 00:23:31.100146 | 2025-11-01 00:23:32.167190 | controller | changed: .d..t...... ./ 2025-11-01 00:23:33.269825 | controller | changed: .d..t...... ./ 2025-11-01 00:23:33.305795 | 2025-11-01 00:23:33.305996 | TASK [include_role : fetch-output-openshift] 2025-11-01 00:23:33.334936 | controller | skipping: Conditional result was False 2025-11-01 00:23:33.350921 | 2025-11-01 00:23:33.351124 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2025-11-01 00:23:33.399135 | controller | skipping: Conditional result was False 2025-11-01 00:23:33.413482 | controller | skipping: Conditional result was False 2025-11-01 00:23:33.480570 | 2025-11-01 00:23:33.480735 | PLAY [localhost] 2025-11-01 00:23:33.500899 | 2025-11-01 00:23:33.501035 | TASK [Run Zuul manifest role] 2025-11-01 00:23:33.523938 | localhost | ok 2025-11-01 00:23:33.546616 | 2025-11-01 00:23:33.546774 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-11-01 00:23:33.986356 | localhost | changed 2025-11-01 00:23:33.991161 | 2025-11-01 00:23:33.991230 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-11-01 00:23:34.040414 | localhost | ok 2025-11-01 00:23:34.049188 | 2025-11-01 00:23:34.049273 | TASK [Set zuul-log-path fact] 2025-11-01 00:23:34.068988 | localhost | ok 2025-11-01 00:23:34.084105 | 2025-11-01 00:23:34.084189 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-11-01 00:23:34.114151 | localhost | ok 2025-11-01 00:23:34.123809 | 2025-11-01 00:23:34.123884 | LOOP [Run upload-logs-swift role] 2025-11-01 00:23:34.158194 | localhost | Output suppressed because no_log was given 2025-11-01 00:23:34.183385 | 2025-11-01 00:23:34.183465 | TASK [Set zuul-log-path fact] 2025-11-01 00:23:34.208040 | localhost | skipping: Conditional result was False 2025-11-01 00:23:34.215088 | 2025-11-01 00:23:34.215183 | TASK [upload-logs-swift : Ensure logs are readable before uploading] 2025-11-01 00:23:34.660286 | localhost -> localhost | ok: Runtime: 0:00:00.011790 2025-11-01 00:23:34.675455 | 2025-11-01 00:23:34.675607 | TASK [upload-logs-swift : Upload logs to swift]