2025-10-29 00:19:57.800742 | Job console starting... 2025-10-29 00:19:57.810861 | Updating repositories 2025-10-29 00:19:57.908332 | Preparing job workspace 2025-10-29 00:20:04.128337 | Running Ansible setup... 2025-10-29 00:20:09.246614 | PRE-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master] 2025-10-29 00:20:09.903976 | 2025-10-29 00:20:09.904127 | PLAY [localhost] 2025-10-29 00:20:09.913299 | 2025-10-29 00:20:09.913375 | TASK [Gathering Facts] 2025-10-29 00:20:10.943425 | localhost | ok 2025-10-29 00:20:10.981077 | 2025-10-29 00:20:10.981227 | TASK [Setup log path fact] 2025-10-29 00:20:11.003995 | localhost | ok 2025-10-29 00:20:11.024518 | 2025-10-29 00:20:11.024652 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-10-29 00:20:11.056454 | localhost | ok 2025-10-29 00:20:11.071947 | 2025-10-29 00:20:11.072242 | TASK [emit-job-header : Print job information] 2025-10-29 00:20:11.124959 | # Job Information 2025-10-29 00:20:11.125187 | Ansible Version: 2.15.12 2025-10-29 00:20:11.125254 | Job: ansible-test-sanity-docker-stable-2.11 2025-10-29 00:20:11.125362 | Pipeline: periodic 2025-10-29 00:20:11.125407 | Executor: ze04.softwarefactory-project.io 2025-10-29 00:20:11.125435 | Triggered by: https://github.com/ansible-collections/ansible.network 2025-10-29 00:20:11.125465 | Log URL (when completed): https://ansible.softwarefactory-project.io/logs/47c/ansible/47c5873cb64347458f921bd1c7c6cb49/ 2025-10-29 00:20:11.125492 | Event ID: a4ea198b67664ad8b8b738cb49a7abfb 2025-10-29 00:20:11.132799 | 2025-10-29 00:20:11.132910 | LOOP [emit-job-header : Print node information] 2025-10-29 00:20:11.248648 | localhost | ok: 2025-10-29 00:20:11.249054 | localhost | # Node Information 2025-10-29 00:20:11.249117 | localhost | Inventory Hostname: controller 2025-10-29 00:20:11.249161 | localhost | Hostname: np0005505327 2025-10-29 00:20:11.249204 | localhost | Username: zuul 2025-10-29 00:20:11.249258 | localhost | Distro: Fedora 37 2025-10-29 00:20:11.249300 | localhost | Provider: ansible-vexxhost-ca-ymq-1 2025-10-29 00:20:11.249340 | localhost | Region: ca-ymq-1 2025-10-29 00:20:11.249380 | localhost | Label: ansible-fedora-37-1vcpu 2025-10-29 00:20:11.249418 | localhost | Product Name: OpenStack Nova 2025-10-29 00:20:11.249455 | localhost | Interface IP: 162.253.55.62 2025-10-29 00:20:11.264089 | 2025-10-29 00:20:11.264245 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-10-29 00:20:11.720142 | localhost -> localhost | changed 2025-10-29 00:20:11.730386 | 2025-10-29 00:20:11.730516 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-10-29 00:20:12.761036 | localhost -> localhost | changed 2025-10-29 00:20:12.810717 | 2025-10-29 00:20:12.811069 | PLAY [all:!appliance*] 2025-10-29 00:20:12.836347 | 2025-10-29 00:20:12.836468 | TASK [include_role : start-zuul-console] 2025-10-29 00:20:12.861557 | controller | ok 2025-10-29 00:20:12.889700 | 2025-10-29 00:20:12.889852 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-10-29 00:20:13.306968 | controller | ok 2025-10-29 00:20:13.333529 | 2025-10-29 00:20:13.333807 | TASK [use-our-mirror : Retrieve the IP address] 2025-10-29 00:20:17.927347 | controller | ok: Status code was 404 and not [200]: HTTP Error 404: Not Found 2025-10-29 00:20:17.942329 | 2025-10-29 00:20:17.942548 | TASK [use-our-mirror : Ensure the registry service is running on the mirror] 2025-10-29 00:20:18.487451 | controller | skipping: Conditional result was False 2025-10-29 00:20:18.505350 | 2025-10-29 00:20:18.505600 | TASK [use-our-mirror : Write the IP in /etc/hosts] 2025-10-29 00:20:18.544611 | controller | skipping: Conditional result was False 2025-10-29 00:20:18.554362 | 2025-10-29 00:20:18.554487 | TASK [use-our-mirror : Create the podman configuration directory] 2025-10-29 00:20:18.581385 | controller | skipping: Conditional result was False 2025-10-29 00:20:18.592003 | 2025-10-29 00:20:18.592156 | TASK [use-our-mirror : Copy the podman configuration] 2025-10-29 00:20:18.627995 | controller | skipping: Conditional result was False 2025-10-29 00:20:18.638062 | 2025-10-29 00:20:18.638189 | TASK [use-our-mirror : Copy the Yum configuration (main)] 2025-10-29 00:20:18.674257 | controller | skipping: Conditional result was False 2025-10-29 00:20:18.683539 | 2025-10-29 00:20:18.683674 | TASK [use-our-mirror : Copy the Yum configuration (update)] 2025-10-29 00:20:18.719328 | controller | skipping: Conditional result was False 2025-10-29 00:20:18.736516 | 2025-10-29 00:20:18.736671 | TASK [Disable Fedora Modular] 2025-10-29 00:20:19.015341 | controller | changed 2025-10-29 00:20:19.030589 | 2025-10-29 00:20:19.030787 | TASK [Enable EPEL] 2025-10-29 00:20:19.069057 | controller | skipping: Conditional result was False 2025-10-29 00:20:19.089708 | 2025-10-29 00:20:19.089937 | TASK [Register the RHEL node] 2025-10-29 00:20:19.683634 | 2025-10-29 00:20:19.684133 | TASK [Show the subscription-manager status] 2025-10-29 00:20:20.253466 | controller | skipping: Conditional result was False 2025-10-29 00:20:20.271923 | 2025-10-29 00:20:20.272120 | TASK [Enable EPEL on RHEL] 2025-10-29 00:20:20.836464 | controller | skipping: Conditional result was False 2025-10-29 00:20:20.851491 | 2025-10-29 00:20:20.851660 | TASK [Install git and tox] 2025-10-29 00:21:49.486982 | controller | changed 2025-10-29 00:21:49.504287 | 2025-10-29 00:21:49.504468 | TASK [include_role : prepare-workspace] 2025-10-29 00:21:49.539735 | controller | ok 2025-10-29 00:21:49.560432 | 2025-10-29 00:21:49.560508 | TASK [prepare-workspace : Start zuul_console daemon.] 2025-10-29 00:21:49.803834 | controller | ok 2025-10-29 00:21:49.818302 | 2025-10-29 00:21:49.818443 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2025-10-29 00:22:02.060371 | controller | Output suppressed because no_log was given 2025-10-29 00:22:02.100417 | 2025-10-29 00:22:02.100534 | TASK [include_role : prepare-workspace-openshift] 2025-10-29 00:22:02.124995 | controller | skipping: Conditional result was False 2025-10-29 00:22:02.148432 | 2025-10-29 00:22:02.148508 | PLAY [all:!appliance] 2025-10-29 00:22:02.165550 | 2025-10-29 00:22:02.165647 | TASK [Run add-build-sshkey role (RSA)] 2025-10-29 00:22:02.195374 | controller | ok 2025-10-29 00:22:02.210305 | 2025-10-29 00:22:02.210402 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-10-29 00:22:02.459976 | controller -> localhost | ok 2025-10-29 00:22:02.467712 | 2025-10-29 00:22:02.467938 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-10-29 00:22:02.497952 | controller | ok 2025-10-29 00:22:02.512530 | controller | included: /var/lib/zuul/builds/47c5873cb64347458f921bd1c7c6cb49/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-10-29 00:22:02.518911 | 2025-10-29 00:22:02.519005 | TASK [add-build-sshkey : Create Temp SSH key] 2025-10-29 00:22:02.981491 | controller -> localhost | Generating public/private rsa key pair. 2025-10-29 00:22:02.981896 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/47c5873cb64347458f921bd1c7c6cb49/work/47c5873cb64347458f921bd1c7c6cb49_id_rsa. 2025-10-29 00:22:02.982026 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/47c5873cb64347458f921bd1c7c6cb49/work/47c5873cb64347458f921bd1c7c6cb49_id_rsa.pub. 2025-10-29 00:22:02.982084 | controller -> localhost | The key fingerprint is: 2025-10-29 00:22:02.982128 | controller -> localhost | SHA256:Rio5gznCz6aJYgSGWBBsfek4DstBn8HZ69RGg5TPaaA zuul-build-sshkey 2025-10-29 00:22:02.982171 | controller -> localhost | The key's randomart image is: 2025-10-29 00:22:02.982214 | controller -> localhost | +---[RSA 2048]----+ 2025-10-29 00:22:02.982255 | controller -> localhost | |+oo +.+ | 2025-10-29 00:22:02.982299 | controller -> localhost | | +.= B o | 2025-10-29 00:22:02.982340 | controller -> localhost | |=.. B B + | 2025-10-29 00:22:02.982381 | controller -> localhost | |=+ E = X | 2025-10-29 00:22:02.982421 | controller -> localhost | |=.O O + S | 2025-10-29 00:22:02.982477 | controller -> localhost | | =oo = . | 2025-10-29 00:22:02.982525 | controller -> localhost | |. + | 2025-10-29 00:22:02.982567 | controller -> localhost | |o.+ | 2025-10-29 00:22:02.982608 | controller -> localhost | |+o | 2025-10-29 00:22:02.982655 | controller -> localhost | +----[SHA256]-----+ 2025-10-29 00:22:02.982997 | controller -> localhost | ok: Runtime: 0:00:00.099154 2025-10-29 00:22:03.001293 | 2025-10-29 00:22:03.001491 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-10-29 00:22:03.046930 | controller | ok 2025-10-29 00:22:03.070598 | controller | included: /var/lib/zuul/builds/47c5873cb64347458f921bd1c7c6cb49/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-10-29 00:22:03.085986 | 2025-10-29 00:22:03.086122 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-10-29 00:22:03.112539 | controller | skipping: Conditional result was False 2025-10-29 00:22:03.124376 | 2025-10-29 00:22:03.124553 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-10-29 00:22:03.564410 | controller | changed 2025-10-29 00:22:03.577618 | 2025-10-29 00:22:03.577807 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-10-29 00:22:03.807973 | controller | ok 2025-10-29 00:22:03.814425 | 2025-10-29 00:22:03.814506 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-10-29 00:22:04.446837 | controller | changed 2025-10-29 00:22:04.462873 | 2025-10-29 00:22:04.463053 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-10-29 00:22:05.116801 | controller | changed 2025-10-29 00:22:05.125576 | 2025-10-29 00:22:05.125721 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-10-29 00:22:05.152669 | controller | skipping: Conditional result was False 2025-10-29 00:22:05.162940 | 2025-10-29 00:22:05.163065 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-10-29 00:22:05.582582 | controller -> localhost | changed 2025-10-29 00:22:05.618532 | 2025-10-29 00:22:05.618784 | TASK [add-build-sshkey : Add back temp key] 2025-10-29 00:22:05.922296 | controller -> localhost | Identity added: /var/lib/zuul/builds/47c5873cb64347458f921bd1c7c6cb49/work/47c5873cb64347458f921bd1c7c6cb49_id_rsa (zuul-build-sshkey) 2025-10-29 00:22:05.922797 | controller -> localhost | ok: Runtime: 0:00:00.007401 2025-10-29 00:22:05.942048 | 2025-10-29 00:22:05.942202 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-10-29 00:22:06.297942 | controller | ok 2025-10-29 00:22:06.306187 | 2025-10-29 00:22:06.306279 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-10-29 00:22:06.344216 | controller | skipping: Conditional result was False 2025-10-29 00:22:06.370328 | 2025-10-29 00:22:06.370464 | TASK [Run add-build-sshkey role (ECDSA)] 2025-10-29 00:22:06.406276 | controller | ok 2025-10-29 00:22:06.433724 | 2025-10-29 00:22:06.433865 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-10-29 00:22:06.738070 | controller -> localhost | ok 2025-10-29 00:22:06.746207 | 2025-10-29 00:22:06.746306 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-10-29 00:22:06.779275 | controller | ok 2025-10-29 00:22:06.793039 | controller | included: /var/lib/zuul/builds/47c5873cb64347458f921bd1c7c6cb49/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-10-29 00:22:06.800199 | 2025-10-29 00:22:06.800318 | TASK [add-build-sshkey : Create Temp SSH key] 2025-10-29 00:22:07.090526 | controller -> localhost | Generating public/private ecdsa key pair. 2025-10-29 00:22:07.090757 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/47c5873cb64347458f921bd1c7c6cb49/work/47c5873cb64347458f921bd1c7c6cb49_id_ecdsa. 2025-10-29 00:22:07.090795 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/47c5873cb64347458f921bd1c7c6cb49/work/47c5873cb64347458f921bd1c7c6cb49_id_ecdsa.pub. 2025-10-29 00:22:07.090835 | controller -> localhost | The key fingerprint is: 2025-10-29 00:22:07.090864 | controller -> localhost | SHA256:iQ9v7JrFinoDFaon9bupsj3xc3bkFqzKF7ftWAykxnI zuul-build-sshkey 2025-10-29 00:22:07.090888 | controller -> localhost | The key's randomart image is: 2025-10-29 00:22:07.090914 | controller -> localhost | +---[ECDSA 521]---+ 2025-10-29 00:22:07.090939 | controller -> localhost | | | 2025-10-29 00:22:07.090962 | controller -> localhost | | . | 2025-10-29 00:22:07.090986 | controller -> localhost | | . . . | 2025-10-29 00:22:07.091012 | controller -> localhost | | o .. + . | 2025-10-29 00:22:07.091036 | controller -> localhost | | o o. E.S | 2025-10-29 00:22:07.091058 | controller -> localhost | |o + .+.*=o | 2025-10-29 00:22:07.091084 | controller -> localhost | | o + . *B+o | 2025-10-29 00:22:07.091110 | controller -> localhost | |....*o=*=o. | 2025-10-29 00:22:07.091134 | controller -> localhost | |.o+=*O++o.. | 2025-10-29 00:22:07.091159 | controller -> localhost | +----[SHA256]-----+ 2025-10-29 00:22:07.091221 | controller -> localhost | ok: Runtime: 0:00:00.010856 2025-10-29 00:22:07.100665 | 2025-10-29 00:22:07.100772 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-10-29 00:22:07.124698 | controller | ok 2025-10-29 00:22:07.135420 | controller | included: /var/lib/zuul/builds/47c5873cb64347458f921bd1c7c6cb49/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-10-29 00:22:07.151251 | 2025-10-29 00:22:07.151362 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-10-29 00:22:07.166271 | controller | skipping: Conditional result was False 2025-10-29 00:22:07.173734 | 2025-10-29 00:22:07.173824 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-10-29 00:22:07.441762 | controller | changed 2025-10-29 00:22:07.449546 | 2025-10-29 00:22:07.449619 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-10-29 00:22:07.686185 | controller | ok 2025-10-29 00:22:07.692346 | 2025-10-29 00:22:07.692457 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-10-29 00:22:08.280361 | controller | changed 2025-10-29 00:22:08.290721 | 2025-10-29 00:22:08.290844 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-10-29 00:22:08.909343 | controller | changed 2025-10-29 00:22:08.917508 | 2025-10-29 00:22:08.917592 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-10-29 00:22:08.943170 | controller | skipping: Conditional result was False 2025-10-29 00:22:08.988753 | 2025-10-29 00:22:08.988874 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-10-29 00:22:09.287650 | controller -> localhost | changed 2025-10-29 00:22:09.304620 | 2025-10-29 00:22:09.304753 | TASK [add-build-sshkey : Add back temp key] 2025-10-29 00:22:09.615439 | controller -> localhost | Identity added: /var/lib/zuul/builds/47c5873cb64347458f921bd1c7c6cb49/work/47c5873cb64347458f921bd1c7c6cb49_id_ecdsa (zuul-build-sshkey) 2025-10-29 00:22:09.615734 | controller -> localhost | ok: Runtime: 0:00:00.012975 2025-10-29 00:22:09.622885 | 2025-10-29 00:22:09.622949 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-10-29 00:22:09.844073 | controller | ok 2025-10-29 00:22:09.861059 | 2025-10-29 00:22:09.861218 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-10-29 00:22:09.911901 | controller | skipping: Conditional result was False 2025-10-29 00:22:09.927221 | 2025-10-29 00:22:09.927320 | TASK [include_role : remove-zuul-sshkey] 2025-10-29 00:22:09.945424 | controller | skipping: Conditional result was False 2025-10-29 00:22:09.958527 | 2025-10-29 00:22:09.958697 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2025-10-29 00:22:10.200714 | controller | ok: "logs" 2025-10-29 00:22:10.200977 | controller | ok: All items complete 2025-10-29 00:22:10.201012 | 2025-10-29 00:22:10.412186 | controller | ok: "artifacts" 2025-10-29 00:22:10.598819 | controller | ok: "docs" 2025-10-29 00:22:10.616037 | 2025-10-29 00:22:10.616171 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2025-10-29 00:22:10.844142 | controller | changed: "logs" 2025-10-29 00:22:11.041287 | controller | changed: "artifacts" 2025-10-29 00:22:11.235183 | controller | changed: "docs" 2025-10-29 00:22:11.303863 | 2025-10-29 00:22:11.304068 | PLAY RECAP 2025-10-29 00:22:11.304599 | controller | ok: 30 changed: 16 unreachable: 0 failed: 0 skipped: 18 rescued: 0 ignored: 0 2025-10-29 00:22:11.304647 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-10-29 00:22:11.304674 | 2025-10-29 00:22:11.455741 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master] 2025-10-29 00:22:11.457558 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2025-10-29 00:22:12.059149 | 2025-10-29 00:22:12.059277 | PLAY [all] 2025-10-29 00:22:12.081645 | 2025-10-29 00:22:12.081811 | TASK [Install binary dependencies] 2025-10-29 00:22:12.142243 | controller | ok 2025-10-29 00:22:12.165506 | 2025-10-29 00:22:12.165644 | TASK [bindep : Include find tasks] 2025-10-29 00:22:12.205590 | controller | ok 2025-10-29 00:22:12.214431 | controller | included: /var/lib/zuul/builds/47c5873cb64347458f921bd1c7c6cb49/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find.yaml 2025-10-29 00:22:12.220842 | 2025-10-29 00:22:12.220918 | TASK [bindep : Look for bindep.txt] 2025-10-29 00:22:12.597920 | controller | ok 2025-10-29 00:22:12.604868 | 2025-10-29 00:22:12.604946 | TASK [bindep : Define bindep_file fact] 2025-10-29 00:22:12.618988 | controller | skipping: Conditional result was False 2025-10-29 00:22:12.625374 | 2025-10-29 00:22:12.625444 | TASK [bindep : Look for other-requirements.txt] 2025-10-29 00:22:12.827103 | controller | ok 2025-10-29 00:22:12.832556 | 2025-10-29 00:22:12.832626 | TASK [bindep : Define bindep_file fact] 2025-10-29 00:22:12.882761 | controller | skipping: Conditional result was False 2025-10-29 00:22:12.891902 | 2025-10-29 00:22:12.892000 | TASK [bindep : Look for bindep fallback file] 2025-10-29 00:22:12.918234 | controller | skipping: Conditional result was False 2025-10-29 00:22:12.929740 | 2025-10-29 00:22:12.929871 | TASK [bindep : Define bindep_file fact] 2025-10-29 00:22:12.958457 | controller | skipping: Conditional result was False 2025-10-29 00:22:12.974296 | 2025-10-29 00:22:12.974425 | TASK [bindep : Include bindep tasks] 2025-10-29 00:22:13.000091 | controller | skipping: Conditional result was False 2025-10-29 00:22:13.009237 | 2025-10-29 00:22:13.009327 | TASK [bindep : Include install tasks] 2025-10-29 00:22:13.033428 | controller | skipping: Conditional result was False 2025-10-29 00:22:13.043355 | 2025-10-29 00:22:13.043452 | LOOP [bindep : Include package tasks] 2025-10-29 00:22:13.125615 | 2025-10-29 00:22:13.125939 | TASK [Run test-setup role] 2025-10-29 00:22:13.150921 | controller | ok 2025-10-29 00:22:13.178896 | 2025-10-29 00:22:13.179033 | TASK [test-setup : Check if project's tools/test-setup.sh exists] 2025-10-29 00:22:13.392850 | controller | ok 2025-10-29 00:22:13.399135 | 2025-10-29 00:22:13.399198 | TASK [test-setup : Run tools/test-setup.sh] 2025-10-29 00:22:13.931321 | controller | skipping: Conditional result was False 2025-10-29 00:22:13.993108 | 2025-10-29 00:22:13.993229 | PLAY RECAP 2025-10-29 00:22:13.993282 | controller | ok: 4 changed: 0 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2025-10-29 00:22:13.993309 | 2025-10-29 00:22:14.115652 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2025-10-29 00:22:14.116525 | PRE-RUN START: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master] 2025-10-29 00:22:14.693385 | 2025-10-29 00:22:14.693529 | PLAY [controller] 2025-10-29 00:22:14.715268 | 2025-10-29 00:22:14.715394 | TASK [Create the /root directory] 2025-10-29 00:22:15.155550 | controller | ok 2025-10-29 00:22:15.164644 | 2025-10-29 00:22:15.164768 | TASK [Install glibc-langpack-en] 2025-10-29 00:22:19.365259 | controller | ok: Nothing to do 2025-10-29 00:22:19.378604 | 2025-10-29 00:22:19.378815 | TASK [Ensure controller directory exists] 2025-10-29 00:22:19.631078 | controller | changed 2025-10-29 00:22:19.642039 | 2025-10-29 00:22:19.642451 | TASK [Install container runtime] 2025-10-29 00:22:19.718004 | controller | ok 2025-10-29 00:22:19.773151 | 2025-10-29 00:22:19.773297 | LOOP [ensure-docker : Gather variables for each operating system] 2025-10-29 00:22:19.832617 | controller | ok: "/var/lib/zuul/builds/47c5873cb64347458f921bd1c7c6cb49/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/vars/fedora.yaml" 2025-10-29 00:22:19.845277 | 2025-10-29 00:22:19.845414 | TASK [ensure-docker : Sanity check for distro selection] 2025-10-29 00:22:19.870977 | controller | skipping: Conditional result was False 2025-10-29 00:22:19.879315 | 2025-10-29 00:22:19.879408 | TASK [ensure-docker : Sanity check for distro install] 2025-10-29 00:22:19.905777 | controller | skipping: Conditional result was False 2025-10-29 00:22:19.913726 | 2025-10-29 00:22:19.913831 | TASK [ensure-docker : Sanity check for upstream install] 2025-10-29 00:22:19.949092 | controller | skipping: Conditional result was False 2025-10-29 00:22:19.957785 | 2025-10-29 00:22:19.957864 | TASK [ensure-docker : Sanity check for upstream install mirrors] 2025-10-29 00:22:19.992807 | controller | skipping: Conditional result was False 2025-10-29 00:22:20.000408 | 2025-10-29 00:22:20.000482 | TASK [ensure-docker : Include tasks for docker install] 2025-10-29 00:22:20.043157 | controller | ok 2025-10-29 00:22:20.057423 | controller | included: /var/lib/zuul/builds/47c5873cb64347458f921bd1c7c6cb49/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/tasks/docker-upstream.yaml 2025-10-29 00:22:20.069481 | 2025-10-29 00:22:20.069573 | TASK [ensure-docker : Not Implemented] 2025-10-29 00:22:20.095525 | controller | skipping: Conditional result was False 2025-10-29 00:22:20.104612 | 2025-10-29 00:22:20.104737 | TASK [Set up docker mirrors] 2025-10-29 00:22:20.128968 | controller | ok 2025-10-29 00:22:20.155084 | 2025-10-29 00:22:20.155178 | TASK [use-docker-mirror : Set docker_mirror fact from mirror_info] 2025-10-29 00:22:20.200001 | controller | skipping: Conditional result was False 2025-10-29 00:22:20.213766 | 2025-10-29 00:22:20.213914 | TASK [use-docker-mirror : Set docker_mirror fact from zuul_site_mirror_info] 2025-10-29 00:22:20.261232 | controller | skipping: Conditional result was False 2025-10-29 00:22:20.274759 | 2025-10-29 00:22:20.274907 | TASK [use-docker-mirror : Set docker_mirror fact] 2025-10-29 00:22:20.313269 | controller | skipping: Conditional result was False 2025-10-29 00:22:20.334040 | 2025-10-29 00:22:20.334240 | TASK [use-docker-mirror : Set up docker mirrors] 2025-10-29 00:22:20.372355 | controller | skipping: Conditional result was False 2025-10-29 00:22:20.390600 | 2025-10-29 00:22:20.390744 | TASK [Add all repositories] 2025-10-29 00:22:20.413479 | controller | ok 2025-10-29 00:22:20.445573 | 2025-10-29 00:22:20.445732 | LOOP [ensure-package-repositories : Install repositories] 2025-10-29 00:22:20.479650 | controller | ok: "/var/lib/zuul/builds/47c5873cb64347458f921bd1c7c6cb49/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-package-repositories/tasks/RedHat.yaml" 2025-10-29 00:22:20.480054 | controller | ok: All items complete 2025-10-29 00:22:20.480117 | 2025-10-29 00:22:20.536322 | controller | included: /var/lib/zuul/builds/47c5873cb64347458f921bd1c7c6cb49/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-package-repositories/tasks/RedHat.yaml 2025-10-29 00:22:20.544303 | 2025-10-29 00:22:20.544445 | TASK [ensure-package-repositories : Create GPG key temp directory] 2025-10-29 00:22:20.879649 | controller | changed 2025-10-29 00:22:20.895035 | 2025-10-29 00:22:20.895217 | LOOP [ensure-package-repositories : Upload GPG keys] 2025-10-29 00:22:21.841986 | controller | changed: 2025-10-29 00:22:21.842433 | controller | { 2025-10-29 00:22:21.842503 | 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-10-29 00:22:21.842553 | controller | } 2025-10-29 00:22:21.867051 | 2025-10-29 00:22:21.867240 | LOOP [ensure-package-repositories : Import GPG keys] 2025-10-29 00:22:22.516608 | controller | changed: 2025-10-29 00:22:22.516788 | controller | { 2025-10-29 00:22:22.516819 | 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-10-29 00:22:22.516841 | controller | } 2025-10-29 00:22:22.516870 | controller | changed: All items complete 2025-10-29 00:22:22.516888 | 2025-10-29 00:22:22.531918 | 2025-10-29 00:22:22.532058 | TASK [ensure-package-repositories : Remove GPG temp directory] 2025-10-29 00:22:22.764095 | controller | changed 2025-10-29 00:22:22.779367 | 2025-10-29 00:22:22.779580 | LOOP [ensure-package-repositories : Add repositories] 2025-10-29 00:22:23.153070 | controller | changed: 2025-10-29 00:22:23.153492 | controller | { 2025-10-29 00:22:23.153554 | controller | "baseurl": "https://download.docker.com/linux/fedora/$releasever/$basearch/stable", 2025-10-29 00:22:23.153598 | controller | "description": "Docker CE Stable - $basearch", 2025-10-29 00:22:23.153636 | controller | "gpgcheck": true, 2025-10-29 00:22:23.153672 | controller | "name": "docker-ce-stable" 2025-10-29 00:22:23.153764 | controller | } 2025-10-29 00:22:23.183814 | 2025-10-29 00:22:23.184009 | TASK [ensure-docker : Install pre-reqs] 2025-10-29 00:22:27.512330 | controller | ok: Nothing to do 2025-10-29 00:22:27.522452 | 2025-10-29 00:22:27.522554 | TASK [ensure-docker : Remove packages] 2025-10-29 00:22:33.650307 | controller | ok: Nothing to do 2025-10-29 00:22:33.666052 | 2025-10-29 00:22:33.666238 | TASK [ensure-docker : Install upstream docker using package] 2025-10-29 00:23:30.046391 | controller | changed 2025-10-29 00:23:30.053768 | 2025-10-29 00:23:30.053988 | TASK [ensure-docker : Setup docker] 2025-10-29 00:23:30.079200 | controller | ok 2025-10-29 00:23:30.100365 | controller | included: /var/lib/zuul/builds/47c5873cb64347458f921bd1c7c6cb49/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/tasks/docker-setup.yaml 2025-10-29 00:23:30.118885 | 2025-10-29 00:23:30.119013 | TASK [ensure-docker : Ensure "docker" group exists] 2025-10-29 00:23:30.508880 | controller | ok 2025-10-29 00:23:30.525661 | 2025-10-29 00:23:30.525874 | TASK [ensure-docker : Add user to docker group] 2025-10-29 00:23:31.433950 | controller | changed 2025-10-29 00:23:31.443507 | 2025-10-29 00:23:31.443610 | TASK [Update docker daemon proxy configuration] 2025-10-29 00:23:31.469513 | controller | skipping: Conditional result was False 2025-10-29 00:23:31.478064 | 2025-10-29 00:23:31.478175 | TASK [Lower default MTU] 2025-10-29 00:23:31.493519 | controller | skipping: Conditional result was False 2025-10-29 00:23:31.504646 | 2025-10-29 00:23:31.504889 | TASK [ensure-docker : Get the status of the docket.socket unit] 2025-10-29 00:23:31.887842 | controller | inactive 2025-10-29 00:23:32.043872 | controller | ok: Runtime: 0:00:00.017467 2025-10-29 00:23:32.053618 | 2025-10-29 00:23:32.053770 | TASK [ensure-docker : Get the status of the docket unit] 2025-10-29 00:23:32.292481 | controller | inactive 2025-10-29 00:23:32.592357 | controller | ok: Runtime: 0:00:00.009653 2025-10-29 00:23:32.607806 | 2025-10-29 00:23:32.607965 | TASK [ensure-docker : Restart docker] 2025-10-29 00:23:32.648969 | Trigger a Docker restart 2025-10-29 00:23:32.664301 | 2025-10-29 00:23:32.664484 | TASK [ensure-docker : Reset ssh connection to pick up docker group] 2025-10-29 00:23:32.708329 | 2025-10-29 00:23:32.708466 | TASK [ensure-docker : Install docker-compose] 2025-10-29 00:23:32.725460 | controller | skipping: Conditional result was False 2025-10-29 00:23:32.741358 | 2025-10-29 00:23:32.741518 | TASK [ensure-docker : Flush handlers before role exit] 2025-10-29 00:23:32.759039 | 2025-10-29 00:23:32.759178 | TASK [ensure-docker : Stop docker.socket to avoid any conflict] 2025-10-29 00:23:34.088719 | controller | changed 2025-10-29 00:23:34.109394 | 2025-10-29 00:23:34.109610 | TASK [ensure-docker : Assure docker service is running] 2025-10-29 00:23:37.157151 | controller | changed 2025-10-29 00:23:37.172146 | 2025-10-29 00:23:37.172302 | TASK [ensure-docker : Assure docker.socket service is running] 2025-10-29 00:23:37.903441 | controller | ok 2025-10-29 00:23:37.912476 | 2025-10-29 00:23:37.912576 | TASK [ensure-docker : Correct group ownership on docker sock] 2025-10-29 00:23:38.328261 | controller | ok 2025-10-29 00:23:38.339166 | 2025-10-29 00:23:38.339456 | TASK [ensure-docker : Validate ability to talk with docker] 2025-10-29 00:23:38.898017 | controller | CONTAINER ID IMAGE COMMAND CREATED STATUS PORTS NAMES 2025-10-29 00:23:39.383832 | controller | ok: Runtime: 0:00:00.054208 2025-10-29 00:23:39.397729 | 2025-10-29 00:23:39.398582 | TASK [ensure-docker : Get version details] 2025-10-29 00:23:40.009847 | controller | Client: Docker Engine - Community 2025-10-29 00:23:40.010160 | controller | Version: 24.0.7 2025-10-29 00:23:40.010285 | controller | API version: 1.43 2025-10-29 00:23:40.010475 | controller | Go version: go1.20.10 2025-10-29 00:23:40.010687 | controller | Git commit: afdd53b 2025-10-29 00:23:40.010944 | controller | Built: Thu Oct 26 09:09:39 2023 2025-10-29 00:23:40.011122 | controller | OS/Arch: linux/amd64 2025-10-29 00:23:40.011392 | controller | Context: default 2025-10-29 00:23:40.011500 | controller | 2025-10-29 00:23:40.011647 | controller | Server: Docker Engine - Community 2025-10-29 00:23:40.011768 | controller | Engine: 2025-10-29 00:23:40.012010 | controller | Version: 24.0.7 2025-10-29 00:23:40.012125 | controller | API version: 1.43 (minimum version 1.12) 2025-10-29 00:23:40.012191 | controller | Go version: go1.20.10 2025-10-29 00:23:40.012295 | controller | Git commit: 311b9ff 2025-10-29 00:23:40.012462 | controller | Built: Thu Oct 26 09:08:11 2023 2025-10-29 00:23:40.012586 | controller | OS/Arch: linux/amd64 2025-10-29 00:23:40.012647 | controller | Experimental: false 2025-10-29 00:23:40.012697 | controller | containerd: 2025-10-29 00:23:40.013133 | controller | Version: 1.6.25 2025-10-29 00:23:40.013303 | controller | GitCommit: d8f198a4ed8892c764191ef7b3b06d8a2eeb5c7f 2025-10-29 00:23:40.013372 | controller | runc: 2025-10-29 00:23:40.013541 | controller | Version: 1.1.10 2025-10-29 00:23:40.013711 | controller | GitCommit: v1.1.10-0-g18a0cb0 2025-10-29 00:23:40.013811 | controller | docker-init: 2025-10-29 00:23:40.013998 | controller | Version: 0.19.0 2025-10-29 00:23:40.014123 | controller | GitCommit: de40ad0 2025-10-29 00:23:40.459356 | controller | ok: Runtime: 0:00:00.045299 2025-10-29 00:23:40.468751 | 2025-10-29 00:23:40.468867 | TASK [ensure-docker : Dump installed docker details] 2025-10-29 00:23:40.492417 | {'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-10-29 00:23:39.972214', 'end': '2025-10-29 00:23:40.017513', 'delta': '0:00:00.045299', 'msg': '', 'zuul_log_id': 'fa163e3b-3c83-72be-8830-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-10-29 00:23:40.501601 | 2025-10-29 00:23:40.501735 | TASK [Use buildset registry] 2025-10-29 00:23:40.517845 | controller | skipping: Conditional result was False 2025-10-29 00:23:40.534341 | 2025-10-29 00:23:40.534465 | TASK [Ensure python3.8 is present] 2025-10-29 00:23:40.549790 | controller | skipping: Conditional result was False 2025-10-29 00:23:40.558720 | 2025-10-29 00:23:40.558851 | TASK [Run our-ensure-python role (Fedora only for now)] 2025-10-29 00:23:40.591943 | controller | ok 2025-10-29 00:23:40.621339 | 2025-10-29 00:23:40.621462 | TASK [our-ensure-python : Install the right Python version (rpm)] 2025-10-29 00:23:42.879662 | controller | ok: Nothing to do 2025-10-29 00:23:42.889162 | 2025-10-29 00:23:42.889271 | TASK [our-ensure-python : Also install python3-devel] 2025-10-29 00:23:54.918611 | controller | changed 2025-10-29 00:23:54.944657 | 2025-10-29 00:23:54.944922 | TASK [Run ensure-virtualenv role] 2025-10-29 00:23:54.967597 | controller | ok 2025-10-29 00:23:55.001993 | 2025-10-29 00:23:55.002223 | TASK [ensure-virtualenv : Check if virtualenv is installed] 2025-10-29 00:23:55.237444 | controller | /usr/bin/virtualenv 2025-10-29 00:23:55.557420 | controller | ok: Runtime: 0:00:00.005428 2025-10-29 00:23:55.571229 | 2025-10-29 00:23:55.571387 | LOOP [ensure-virtualenv : Install virtualenv package] 2025-10-29 00:23:55.599684 | controller | skipping: Conditional result was False 2025-10-29 00:23:55.600126 | controller | changed: All items complete 2025-10-29 00:23:55.600181 | 2025-10-29 00:23:55.628442 | 2025-10-29 00:23:55.628639 | TASK [Find the full path of the Python interpreter] 2025-10-29 00:23:55.874594 | controller | /usr/bin/python3.9 2025-10-29 00:23:56.176487 | controller | ok 2025-10-29 00:23:56.191738 | 2025-10-29 00:23:56.191954 | TASK [Create virtualenv for ansible-test with a specific interpreter] 2025-10-29 00:23:57.879882 | controller | created virtual environment CPython3.9.18.final.0-64 in 873ms 2025-10-29 00:23:57.896261 | controller | creator CPython3Posix(dest=/home/zuul/venv, clear=False, no_vcs_ignore=False, global=False) 2025-10-29 00:23:57.896300 | controller | seeder FromAppData(extra_search_dir=/usr/share/python-wheels,download=False, pip=bundle, setuptools=bundle, wheel=bundle, via=copy, app_data_dir=/home/zuul/.local/share/virtualenv) 2025-10-29 00:23:57.896320 | controller | added seed packages: pip==22.2.2, setuptools==62.6.0, wheel==0.37.1 2025-10-29 00:23:57.896334 | controller | activators BashActivator,CShellActivator,FishActivator,NushellActivator,PowerShellActivator,PythonActivator 2025-10-29 00:23:58.248576 | controller | changed 2025-10-29 00:23:58.265586 | 2025-10-29 00:23:58.265829 | TASK [Set selinux package] 2025-10-29 00:23:58.301636 | controller | ok 2025-10-29 00:23:58.315644 | 2025-10-29 00:23:58.315836 | TASK [Set selinux package (Fedora)] 2025-10-29 00:23:58.358538 | controller | ok 2025-10-29 00:23:58.364186 | 2025-10-29 00:23:58.364269 | TASK [Install selinux into virtualenv] 2025-10-29 00:24:13.635745 | controller | Collecting selinux-please-lie-to-me 2025-10-29 00:24:25.880575 | controller | Downloading selinux_please_lie_to_me-1.0.1-py2.py3-none-any.whl (3.8 kB) 2025-10-29 00:24:26.311251 | controller | Collecting setuptools<50.0.0 2025-10-29 00:24:26.334615 | controller | Downloading setuptools-49.6.0-py3-none-any.whl (803 kB) 2025-10-29 00:24:26.465233 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 803.3/803.3 kB 6.4 MB/s eta 0:00:00 2025-10-29 00:24:26.557451 | controller | Installing collected packages: setuptools, selinux-please-lie-to-me 2025-10-29 00:24:26.557709 | controller | Attempting uninstall: setuptools 2025-10-29 00:24:26.558508 | controller | Found existing installation: setuptools 62.6.0 2025-10-29 00:24:26.649994 | controller | Uninstalling setuptools-62.6.0: 2025-10-29 00:24:26.659574 | controller | Successfully uninstalled setuptools-62.6.0 2025-10-29 00:24:27.346941 | controller | Successfully installed selinux-please-lie-to-me-1.0.1 setuptools-49.6.0 2025-10-29 00:24:38.254318 | controller | 2025-10-29 00:24:38.333136 | controller | [notice] A new release of pip available: 22.2.2 -> 25.3 2025-10-29 00:24:38.333177 | controller | [notice] To update, run: /home/zuul/venv/bin/python -m pip install --upgrade pip 2025-10-29 00:24:38.428389 | controller | ok: Runtime: 0:00:39.430244 2025-10-29 00:24:38.443836 | 2025-10-29 00:24:38.444013 | TASK [Install pytest-forked into virtualenv] 2025-10-29 00:24:50.489164 | controller | Collecting pytest-forked 2025-10-29 00:25:00.760511 | controller | Downloading pytest_forked-1.6.0-py3-none-any.whl (4.9 kB) 2025-10-29 00:25:00.894441 | controller | Collecting pytest>=3.10 2025-10-29 00:25:00.902139 | controller | Downloading pytest-8.4.2-py3-none-any.whl (365 kB) 2025-10-29 00:25:00.955693 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 365.8/365.8 kB 7.5 MB/s eta 0:00:00 2025-10-29 00:25:00.994776 | controller | Collecting py 2025-10-29 00:25:00.998821 | controller | Downloading py-1.11.0-py2.py3-none-any.whl (98 kB) 2025-10-29 00:25:01.010364 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 98.7/98.7 kB 13.5 MB/s eta 0:00:00 2025-10-29 00:25:01.077061 | controller | Collecting packaging>=20 2025-10-29 00:25:01.081173 | controller | Downloading packaging-25.0-py3-none-any.whl (66 kB) 2025-10-29 00:25:01.091368 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 66.5/66.5 kB 9.8 MB/s eta 0:00:00 2025-10-29 00:25:01.117132 | controller | Collecting iniconfig>=1 2025-10-29 00:25:01.122353 | controller | Downloading iniconfig-2.1.0-py3-none-any.whl (6.0 kB) 2025-10-29 00:25:01.177090 | controller | Collecting pygments>=2.7.2 2025-10-29 00:25:01.181048 | controller | Downloading pygments-2.19.2-py3-none-any.whl (1.2 MB) 2025-10-29 00:25:01.206854 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 1.2/1.2 MB 56.7 MB/s eta 0:00:00 2025-10-29 00:25:01.264674 | controller | Collecting tomli>=1 2025-10-29 00:25:01.268192 | controller | Downloading tomli-2.3.0-py3-none-any.whl (14 kB) 2025-10-29 00:25:01.306104 | controller | Collecting exceptiongroup>=1 2025-10-29 00:25:01.310692 | controller | Downloading exceptiongroup-1.3.0-py3-none-any.whl (16 kB) 2025-10-29 00:25:01.349448 | controller | Collecting pluggy<2,>=1.5 2025-10-29 00:25:01.353639 | controller | Downloading pluggy-1.6.0-py3-none-any.whl (20 kB) 2025-10-29 00:25:01.411372 | controller | Collecting typing-extensions>=4.6.0 2025-10-29 00:25:01.416052 | controller | Downloading typing_extensions-4.15.0-py3-none-any.whl (44 kB) 2025-10-29 00:25:01.422791 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 44.6/44.6 kB 9.5 MB/s eta 0:00:00 2025-10-29 00:25:01.496481 | controller | Installing collected packages: typing-extensions, tomli, pygments, py, pluggy, packaging, iniconfig, exceptiongroup, pytest, pytest-forked 2025-10-29 00:25:02.566168 | 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-10-29 00:25:02.570265 | controller | 2025-10-29 00:25:02.656694 | controller | [notice] A new release of pip available: 22.2.2 -> 25.3 2025-10-29 00:25:02.656738 | controller | [notice] To update, run: /home/zuul/venv/bin/python -m pip install --upgrade pip 2025-10-29 00:25:03.035424 | controller | ok: Runtime: 0:00:23.933895 2025-10-29 00:25:03.045100 | 2025-10-29 00:25:03.045256 | TASK [Update pip] 2025-10-29 00:25:03.689068 | controller | Requirement already satisfied: pip in ./venv/lib/python3.9/site-packages (22.2.2) 2025-10-29 00:25:13.150710 | controller | Collecting pip 2025-10-29 00:25:25.331023 | controller | Downloading pip-25.3-py3-none-any.whl (1.8 MB) 2025-10-29 00:25:25.404048 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 1.8/1.8 MB 26.1 MB/s eta 0:00:00 2025-10-29 00:25:25.464862 | controller | Installing collected packages: pip 2025-10-29 00:25:25.465095 | controller | Attempting uninstall: pip 2025-10-29 00:25:25.465767 | controller | Found existing installation: pip 22.2.2 2025-10-29 00:25:25.632893 | controller | Uninstalling pip-22.2.2: 2025-10-29 00:25:25.654249 | controller | Successfully uninstalled pip-22.2.2 2025-10-29 00:25:26.497495 | controller | Successfully installed pip-25.3 2025-10-29 00:25:26.663880 | controller | ok: Runtime: 0:00:23.203516 2025-10-29 00:25:26.677346 | 2025-10-29 00:25:26.677516 | TASK [Ensure pip temp directory exists in /var/tmp] 2025-10-29 00:25:26.925265 | controller | changed 2025-10-29 00:25:26.938843 | 2025-10-29 00:25:26.939045 | TASK [Install ansible into virtualenv] 2025-10-29 00:25:27.462938 | controller | Processing ./src/github.com/ansible/ansible 2025-10-29 00:25:27.467624 | controller | Installing build dependencies: started 2025-10-29 00:25:50.267885 | controller | Installing build dependencies: finished with status 'done' 2025-10-29 00:25:50.269580 | controller | Getting requirements to build wheel: started 2025-10-29 00:25:50.889225 | controller | Getting requirements to build wheel: finished with status 'done' 2025-10-29 00:25:50.891675 | controller | Preparing metadata (pyproject.toml): started 2025-10-29 00:25:51.356602 | controller | Preparing metadata (pyproject.toml): finished with status 'done' 2025-10-29 00:26:01.773263 | controller | Collecting jinja2 (from ansible-core==2.11.12.post0) 2025-10-29 00:26:13.977644 | controller | Downloading jinja2-3.1.6-py3-none-any.whl.metadata (2.9 kB) 2025-10-29 00:26:14.276880 | controller | Collecting PyYAML (from ansible-core==2.11.12.post0) 2025-10-29 00:26:14.281853 | 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-10-29 00:26:14.847654 | controller | Collecting cryptography (from ansible-core==2.11.12.post0) 2025-10-29 00:26:14.851668 | controller | Downloading cryptography-46.0.3-cp38-abi3-manylinux_2_34_x86_64.whl.metadata (5.7 kB) 2025-10-29 00:26:14.996193 | controller | Requirement already satisfied: packaging in ./venv/lib/python3.9/site-packages (from ansible-core==2.11.12.post0) (25.0) 2025-10-29 00:26:15.225693 | controller | Collecting resolvelib<0.6.0,>=0.5.3 (from ansible-core==2.11.12.post0) 2025-10-29 00:26:15.231153 | controller | Downloading resolvelib-0.5.4-py2.py3-none-any.whl.metadata (3.7 kB) 2025-10-29 00:26:15.761639 | controller | Collecting cffi>=2.0.0 (from cryptography->ansible-core==2.11.12.post0) 2025-10-29 00:26:15.765768 | controller | Downloading cffi-2.0.0-cp39-cp39-manylinux2014_x86_64.manylinux_2_17_x86_64.whl.metadata (2.6 kB) 2025-10-29 00:26:15.896635 | 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-10-29 00:26:16.022655 | controller | Collecting pycparser (from cffi>=2.0.0->cryptography->ansible-core==2.11.12.post0) 2025-10-29 00:26:16.027946 | controller | Downloading pycparser-2.23-py3-none-any.whl.metadata (993 bytes) 2025-10-29 00:26:16.195847 | controller | Collecting MarkupSafe>=2.0 (from jinja2->ansible-core==2.11.12.post0) 2025-10-29 00:26:16.199943 | 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-10-29 00:26:16.223240 | controller | Downloading resolvelib-0.5.4-py2.py3-none-any.whl (12 kB) 2025-10-29 00:26:16.239820 | controller | Downloading cryptography-46.0.3-cp38-abi3-manylinux_2_34_x86_64.whl (4.5 MB) 2025-10-29 00:26:16.321384 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 4.5/4.5 MB 60.6 MB/s 0:00:00 2025-10-29 00:26:16.327961 | controller | Downloading cffi-2.0.0-cp39-cp39-manylinux2014_x86_64.manylinux_2_17_x86_64.whl (216 kB) 2025-10-29 00:26:16.341800 | controller | Downloading jinja2-3.1.6-py3-none-any.whl (134 kB) 2025-10-29 00:26:16.358155 | 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-10-29 00:26:16.371382 | controller | Downloading pycparser-2.23-py3-none-any.whl (118 kB) 2025-10-29 00:26:16.387172 | 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-10-29 00:26:16.427753 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 750.8/750.8 kB 13.9 MB/s 0:00:00 2025-10-29 00:26:16.445070 | controller | Building wheels for collected packages: ansible-core 2025-10-29 00:26:16.445807 | controller | Building wheel for ansible-core (pyproject.toml): started 2025-10-29 00:26:17.921436 | controller | Building wheel for ansible-core (pyproject.toml): finished with status 'done' 2025-10-29 00:26:17.923184 | controller | Created wheel for ansible-core: filename=ansible_core-2.11.12.post0-py3-none-any.whl size=1961250 sha256=fcc4f5249914d8a4f2970d8ec0e03b73526f8042e525295d41aeb8df21ac3692 2025-10-29 00:26:17.923391 | controller | Stored in directory: /var/tmp/ansible-test-pip/pip-ephem-wheel-cache-cxjod_3d/wheels/0f/a0/93/ab85b739e1eb1e0467171316f13571c14efdf87e0d5d44a33e 2025-10-29 00:26:17.932709 | controller | Successfully built ansible-core 2025-10-29 00:26:17.970706 | controller | Installing collected packages: resolvelib, PyYAML, pycparser, MarkupSafe, jinja2, cffi, cryptography, ansible-core 2025-10-29 00:26:19.456634 | controller | 2025-10-29 00:26:19.460906 | 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-10-29 00:26:20.016467 | controller | ok: Runtime: 0:00:52.391442 2025-10-29 00:26:20.028203 | 2025-10-29 00:26:20.028441 | TASK [Setup download-artifact-fork role] 2025-10-29 00:26:20.052726 | controller | ok 2025-10-29 00:26:20.071642 | 2025-10-29 00:26:20.071748 | TASK [download-artifact-fork : Parse inventory response] 2025-10-29 00:26:20.102423 | controller | ok 2025-10-29 00:26:20.109700 | 2025-10-29 00:26:20.109792 | TASK [download-artifact-fork : Fetch info from Zuul API] 2025-10-29 00:26:20.135501 | controller | skipping: Conditional result was False 2025-10-29 00:26:20.144600 | 2025-10-29 00:26:20.144737 | TASK [download-artifact-fork : Parse build response] 2025-10-29 00:26:20.171496 | controller | skipping: Conditional result was False 2025-10-29 00:26:20.180446 | 2025-10-29 00:26:20.180569 | TASK [download-artifact-fork : Ensure artifacts directory exists] 2025-10-29 00:26:20.400473 | controller | changed 2025-10-29 00:26:20.409294 | 2025-10-29 00:26:20.409413 | LOOP [download-artifact-fork : Download archive by type] 2025-10-29 00:26:20.434744 | controller | skipping: Conditional result was False 2025-10-29 00:26:20.435203 | 2025-10-29 00:26:21.723929 | controller | changed: OK (20199 bytes) 2025-10-29 00:26:21.754417 | 2025-10-29 00:26:21.754633 | TASK [deploy-artifacts : define __collections] 2025-10-29 00:26:21.798773 | controller | ok 2025-10-29 00:26:21.810409 | 2025-10-29 00:26:21.810776 | LOOP [deploy-artifacts : Create list of collections] 2025-10-29 00:26:21.839925 | controller | skipping: Conditional result was False 2025-10-29 00:26:21.850728 | controller | ok: 2025-10-29 00:26:21.850828 | controller | { 2025-10-29 00:26:21.850852 | controller | "branch": "main", 2025-10-29 00:26:21.850870 | controller | "job": "build-ansible-collection", 2025-10-29 00:26:21.850886 | controller | "metadata": { 2025-10-29 00:26:21.850902 | controller | "type": "ansible_collection", 2025-10-29 00:26:21.850917 | controller | "version": "5.0.0" 2025-10-29 00:26:21.850932 | controller | }, 2025-10-29 00:26:21.850947 | controller | "name": "ansible.network", 2025-10-29 00:26:21.850962 | controller | "project": "ansible-collections/ansible.network", 2025-10-29 00:26:21.850978 | controller | "url": "https://5b811762c597a4e61588-f1df475a3bd31dc5211674e175785e2a.ssl.cf2.rackcdn.com/ansible/e4d0b785852b4fe89b2826d19f1f109d/artifacts/ansible-network-5.0.0.tar.gz" 2025-10-29 00:26:21.851001 | controller | } 2025-10-29 00:26:21.867257 | 2025-10-29 00:26:21.867431 | TASK [deploy-artifacts : Install require-project collection using ansible-galaxy] 2025-10-29 00:26:22.479909 | controller | Starting galaxy collection install process 2025-10-29 00:26:22.480146 | controller | Process install dependency map 2025-10-29 00:27:15.984548 | controller | [WARNING]: Skipping Galaxy server https://galaxy.ansible.com/api/. Got an 2025-10-29 00:27:15.985089 | controller | unexpected error when getting available versions of collection 2025-10-29 00:27:15.985123 | controller | ansible.netcommon: '/api/v3/plugin/ansible/content/published/collections/index/ 2025-10-29 00:27:15.985136 | controller | ansible/netcommon/versions/' 2025-10-29 00:27:15.985154 | controller | ERROR! Unexpected Exception, this is probably a bug: '/api/v3/plugin/ansible/content/published/collections/index/ansible/netcommon/versions/' 2025-10-29 00:27:16.047007 | controller | to see the full traceback, use -vvv 2025-10-29 00:27:16.469513 | controller | ERROR 2025-10-29 00:27:16.469897 | controller | { 2025-10-29 00:27:16.469981 | controller | "delta": "0:00:53.960703", 2025-10-29 00:27:16.470014 | controller | "end": "2025-10-29 00:27:16.047862", 2025-10-29 00:27:16.470039 | controller | "msg": "non-zero return code", 2025-10-29 00:27:16.470063 | controller | "rc": 250, 2025-10-29 00:27:16.470086 | controller | "start": "2025-10-29 00:26:22.087159" 2025-10-29 00:27:16.470108 | controller | } failure 2025-10-29 00:27:16.472512 | 2025-10-29 00:27:16.472580 | PLAY RECAP 2025-10-29 00:27:16.472637 | controller | ok: 44 changed: 24 unreachable: 0 failed: 1 skipped: 17 rescued: 0 ignored: 0 2025-10-29 00:27:16.472665 | 2025-10-29 00:27:16.642821 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master] 2025-10-29 00:27:16.643793 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2025-10-29 00:27:17.242987 | 2025-10-29 00:27:17.243116 | PLAY [all] 2025-10-29 00:27:17.265178 | 2025-10-29 00:27:17.265312 | TASK [fetch-subunit-output : Find stestr or testr executable] 2025-10-29 00:27:17.695226 | controller | changed: non-zero return code 2025-10-29 00:27:17.703323 | 2025-10-29 00:27:17.703426 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2025-10-29 00:27:17.719198 | controller | skipping: Conditional result was False 2025-10-29 00:27:17.729544 | 2025-10-29 00:27:17.729709 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2025-10-29 00:27:17.764194 | 2025-10-29 00:27:17.764398 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2025-10-29 00:27:17.798439 | 2025-10-29 00:27:17.798639 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2025-10-29 00:27:17.814328 | controller | skipping: Conditional result was False 2025-10-29 00:27:17.828513 | 2025-10-29 00:27:17.828644 | LOOP [fetch-subunit-output : Generate subunit file] 2025-10-29 00:27:17.869069 | 2025-10-29 00:27:17.869272 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2025-10-29 00:27:17.895753 | controller | skipping: Conditional result was False 2025-10-29 00:27:17.906942 | 2025-10-29 00:27:17.907086 | TASK [fetch-subunit-output : Remove the temporary file] 2025-10-29 00:27:17.924448 | controller | skipping: Conditional result was False 2025-10-29 00:27:17.935143 | 2025-10-29 00:27:17.935290 | TASK [fetch-subunit-output : Process and fetch subunit results] 2025-10-29 00:27:17.950964 | controller | skipping: Conditional result was False 2025-10-29 00:27:18.000341 | 2025-10-29 00:27:18.000476 | PLAY RECAP 2025-10-29 00:27:18.000532 | controller | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2025-10-29 00:27:18.000562 | 2025-10-29 00:27:18.130079 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2025-10-29 00:27:18.130985 | POST-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/post.yaml@master] 2025-10-29 00:27:18.800998 | 2025-10-29 00:27:18.801251 | PLAY [all:!appliance*] 2025-10-29 00:27:18.826755 | 2025-10-29 00:27:18.826890 | TASK [unregister the node] 2025-10-29 00:27:19.362045 | controller | skipping: Conditional result was False 2025-10-29 00:27:19.378817 | 2025-10-29 00:27:19.379036 | TASK [include_role : fetch-output] 2025-10-29 00:27:19.451785 | controller | ok 2025-10-29 00:27:19.488737 | 2025-10-29 00:27:19.488888 | TASK [fetch-output : Set log path for multiple nodes] 2025-10-29 00:27:19.554009 | controller | skipping: Conditional result was False 2025-10-29 00:27:19.560476 | 2025-10-29 00:27:19.560560 | TASK [fetch-output : Set log path for single node] 2025-10-29 00:27:19.590598 | controller | ok 2025-10-29 00:27:19.596115 | 2025-10-29 00:27:19.596350 | LOOP [fetch-output : Ensure local output dirs] 2025-10-29 00:27:20.051638 | controller -> localhost | ok: "/var/lib/zuul/builds/47c5873cb64347458f921bd1c7c6cb49/work/logs" 2025-10-29 00:27:20.317724 | controller -> localhost | changed: "/var/lib/zuul/builds/47c5873cb64347458f921bd1c7c6cb49/work/artifacts" 2025-10-29 00:27:20.576375 | controller -> localhost | changed: "/var/lib/zuul/builds/47c5873cb64347458f921bd1c7c6cb49/work/docs" 2025-10-29 00:27:20.594930 | 2025-10-29 00:27:20.595048 | LOOP [fetch-output : Collect logs, artifacts and docs] 2025-10-29 00:27:21.317416 | controller | changed: 2025-10-29 00:27:21.317744 | controller | .d..t...... ./ 2025-10-29 00:27:21.317809 | controller | cd+++++++++ controller/ 2025-10-29 00:27:21.317862 | controller | changed: All items complete 2025-10-29 00:27:21.317893 | 2025-10-29 00:27:21.895062 | controller | changed: .d..t...... ./ 2025-10-29 00:27:22.473854 | controller | changed: .d..t...... ./ 2025-10-29 00:27:22.509127 | 2025-10-29 00:27:22.509291 | TASK [include_role : fetch-output-openshift] 2025-10-29 00:27:22.546744 | controller | skipping: Conditional result was False 2025-10-29 00:27:22.553603 | 2025-10-29 00:27:22.553751 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2025-10-29 00:27:22.596071 | controller | skipping: Conditional result was False 2025-10-29 00:27:22.609236 | controller | skipping: Conditional result was False 2025-10-29 00:27:22.656126 | 2025-10-29 00:27:22.656350 | PLAY [localhost] 2025-10-29 00:27:22.677399 | 2025-10-29 00:27:22.677718 | TASK [Run Zuul manifest role] 2025-10-29 00:27:22.698451 | localhost | ok 2025-10-29 00:27:22.720558 | 2025-10-29 00:27:22.720828 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-10-29 00:27:23.189833 | localhost | changed 2025-10-29 00:27:23.194604 | 2025-10-29 00:27:23.194670 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-10-29 00:27:23.243377 | localhost | ok 2025-10-29 00:27:23.251789 | 2025-10-29 00:27:23.251864 | TASK [Set zuul-log-path fact] 2025-10-29 00:27:23.269423 | localhost | ok 2025-10-29 00:27:23.284275 | 2025-10-29 00:27:23.284365 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-10-29 00:27:23.323575 | localhost | ok 2025-10-29 00:27:23.331937 | 2025-10-29 00:27:23.332000 | LOOP [Run upload-logs-swift role] 2025-10-29 00:27:23.366753 | localhost | Output suppressed because no_log was given 2025-10-29 00:27:23.402876 | 2025-10-29 00:27:23.402992 | TASK [Set zuul-log-path fact] 2025-10-29 00:27:23.430480 | localhost | skipping: Conditional result was False 2025-10-29 00:27:23.439262 | 2025-10-29 00:27:23.439402 | TASK [upload-logs-swift : Ensure logs are readable before uploading] 2025-10-29 00:27:23.847577 | localhost -> localhost | ok: Runtime: 0:00:00.010282 2025-10-29 00:27:23.905642 | 2025-10-29 00:27:23.905798 | TASK [upload-logs-swift : Upload logs to swift]