2025-10-29 00:12:22.723312 | Job console starting... 2025-10-29 00:12:22.735082 | Updating repositories 2025-10-29 00:12:22.847999 | Preparing job workspace 2025-10-29 00:12:26.375141 | Running Ansible setup... 2025-10-29 00:12:34.437656 | PRE-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master] 2025-10-29 00:12:35.096258 | 2025-10-29 00:12:35.096455 | PLAY [localhost] 2025-10-29 00:12:35.109592 | 2025-10-29 00:12:35.109761 | TASK [Gathering Facts] 2025-10-29 00:12:36.198833 | localhost | ok 2025-10-29 00:12:36.213097 | 2025-10-29 00:12:36.213184 | TASK [Setup log path fact] 2025-10-29 00:12:36.232874 | localhost | ok 2025-10-29 00:12:36.246154 | 2025-10-29 00:12:36.246229 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-10-29 00:12:36.286858 | localhost | ok 2025-10-29 00:12:36.299727 | 2025-10-29 00:12:36.299824 | TASK [emit-job-header : Print job information] 2025-10-29 00:12:36.351439 | # Job Information 2025-10-29 00:12:36.351608 | Ansible Version: 2.15.12 2025-10-29 00:12:36.351653 | Job: ansible-test-sanity-docker-stable-2.11 2025-10-29 00:12:36.351684 | Pipeline: periodic 2025-10-29 00:12:36.351710 | Executor: ze01.softwarefactory-project.io 2025-10-29 00:12:36.351736 | Triggered by: https://github.com/ansible-collections/ansible.network 2025-10-29 00:12:36.351767 | Log URL (when completed): https://ansible.softwarefactory-project.io/logs/1b6/ansible/1b6fd6ae234d4ad986b4503ea7ea4126/ 2025-10-29 00:12:36.351795 | Event ID: a4ea198b67664ad8b8b738cb49a7abfb 2025-10-29 00:12:36.357838 | 2025-10-29 00:12:36.357931 | LOOP [emit-job-header : Print node information] 2025-10-29 00:12:36.476504 | localhost | ok: 2025-10-29 00:12:36.476816 | localhost | # Node Information 2025-10-29 00:12:36.476923 | localhost | Inventory Hostname: controller 2025-10-29 00:12:36.477047 | localhost | Hostname: np0005505281 2025-10-29 00:12:36.477121 | localhost | Username: zuul 2025-10-29 00:12:36.477184 | localhost | Distro: Fedora 37 2025-10-29 00:12:36.477234 | localhost | Provider: ansible-vexxhost-ams1 2025-10-29 00:12:36.477280 | localhost | Region: ams1 2025-10-29 00:12:36.477324 | localhost | Label: ansible-fedora-37-1vcpu 2025-10-29 00:12:36.477368 | localhost | Product Name: OpenStack Nova 2025-10-29 00:12:36.477412 | localhost | Interface IP: 38.129.16.88 2025-10-29 00:12:36.496084 | 2025-10-29 00:12:36.496230 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-10-29 00:12:36.922454 | localhost -> localhost | changed 2025-10-29 00:12:36.930296 | 2025-10-29 00:12:36.930413 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-10-29 00:12:37.948570 | localhost -> localhost | changed 2025-10-29 00:12:37.990297 | 2025-10-29 00:12:37.990490 | PLAY [all:!appliance*] 2025-10-29 00:12:38.023972 | 2025-10-29 00:12:38.024176 | TASK [include_role : start-zuul-console] 2025-10-29 00:12:38.047344 | controller | ok 2025-10-29 00:12:38.066601 | 2025-10-29 00:12:38.066723 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-10-29 00:12:39.008445 | controller | ok 2025-10-29 00:12:39.027516 | 2025-10-29 00:12:39.027655 | TASK [use-our-mirror : Retrieve the IP address] 2025-10-29 00:12:42.230330 | controller | ok: Status code was 404 and not [200]: HTTP Error 404: Not Found 2025-10-29 00:12:42.240175 | 2025-10-29 00:12:42.240324 | TASK [use-our-mirror : Ensure the registry service is running on the mirror] 2025-10-29 00:12:42.613255 | controller | skipping: Conditional result was False 2025-10-29 00:12:42.628824 | 2025-10-29 00:12:42.629042 | TASK [use-our-mirror : Write the IP in /etc/hosts] 2025-10-29 00:12:42.666136 | controller | skipping: Conditional result was False 2025-10-29 00:12:42.675585 | 2025-10-29 00:12:42.675732 | TASK [use-our-mirror : Create the podman configuration directory] 2025-10-29 00:12:42.700724 | controller | skipping: Conditional result was False 2025-10-29 00:12:42.709960 | 2025-10-29 00:12:42.710130 | TASK [use-our-mirror : Copy the podman configuration] 2025-10-29 00:12:42.736372 | controller | skipping: Conditional result was False 2025-10-29 00:12:42.745201 | 2025-10-29 00:12:42.745357 | TASK [use-our-mirror : Copy the Yum configuration (main)] 2025-10-29 00:12:42.771272 | controller | skipping: Conditional result was False 2025-10-29 00:12:42.778891 | 2025-10-29 00:12:42.779004 | TASK [use-our-mirror : Copy the Yum configuration (update)] 2025-10-29 00:12:42.803481 | controller | skipping: Conditional result was False 2025-10-29 00:12:42.819909 | 2025-10-29 00:12:42.820039 | TASK [Disable Fedora Modular] 2025-10-29 00:12:43.650582 | controller | changed 2025-10-29 00:12:43.659333 | 2025-10-29 00:12:43.659429 | TASK [Enable EPEL] 2025-10-29 00:12:43.685570 | controller | skipping: Conditional result was False 2025-10-29 00:12:43.694987 | 2025-10-29 00:12:43.695126 | TASK [Register the RHEL node] 2025-10-29 00:12:44.106115 | 2025-10-29 00:12:44.106393 | TASK [Show the subscription-manager status] 2025-10-29 00:12:44.488346 | controller | skipping: Conditional result was False 2025-10-29 00:12:44.503946 | 2025-10-29 00:12:44.504262 | TASK [Enable EPEL on RHEL] 2025-10-29 00:12:44.915423 | controller | skipping: Conditional result was False 2025-10-29 00:12:44.924233 | 2025-10-29 00:12:44.924353 | TASK [Install git and tox] 2025-10-29 00:14:30.452176 | controller | changed 2025-10-29 00:14:30.463712 | 2025-10-29 00:14:30.463821 | TASK [include_role : prepare-workspace] 2025-10-29 00:14:30.502441 | controller | ok 2025-10-29 00:14:30.547604 | 2025-10-29 00:14:30.548326 | TASK [prepare-workspace : Start zuul_console daemon.] 2025-10-29 00:14:31.352336 | controller | ok 2025-10-29 00:14:31.361839 | 2025-10-29 00:14:31.361972 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2025-10-29 00:14:50.030327 | controller | Output suppressed because no_log was given 2025-10-29 00:14:50.042469 | 2025-10-29 00:14:50.042588 | TASK [include_role : prepare-workspace-openshift] 2025-10-29 00:14:50.060484 | controller | skipping: Conditional result was False 2025-10-29 00:14:50.090103 | 2025-10-29 00:14:50.090321 | PLAY [all:!appliance] 2025-10-29 00:14:50.111540 | 2025-10-29 00:14:50.111675 | TASK [Run add-build-sshkey role (RSA)] 2025-10-29 00:14:50.133829 | controller | ok 2025-10-29 00:14:50.153971 | 2025-10-29 00:14:50.154149 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-10-29 00:14:50.415697 | controller -> localhost | ok 2025-10-29 00:14:50.424285 | 2025-10-29 00:14:50.424403 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-10-29 00:14:50.454967 | controller | ok 2025-10-29 00:14:50.471215 | controller | included: /var/lib/zuul/builds/1b6fd6ae234d4ad986b4503ea7ea4126/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-10-29 00:14:50.478944 | 2025-10-29 00:14:50.479075 | TASK [add-build-sshkey : Create Temp SSH key] 2025-10-29 00:14:51.038280 | controller -> localhost | Generating public/private rsa key pair. 2025-10-29 00:14:51.038501 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/1b6fd6ae234d4ad986b4503ea7ea4126/work/1b6fd6ae234d4ad986b4503ea7ea4126_id_rsa. 2025-10-29 00:14:51.038530 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/1b6fd6ae234d4ad986b4503ea7ea4126/work/1b6fd6ae234d4ad986b4503ea7ea4126_id_rsa.pub. 2025-10-29 00:14:51.038551 | controller -> localhost | The key fingerprint is: 2025-10-29 00:14:51.038571 | controller -> localhost | SHA256:eOfYhG1dPbG3GtEn18tdV+EYsOYo/t3QgvYk8EArrL4 zuul-build-sshkey 2025-10-29 00:14:51.038589 | controller -> localhost | The key's randomart image is: 2025-10-29 00:14:51.038606 | controller -> localhost | +---[RSA 2048]----+ 2025-10-29 00:14:51.038624 | controller -> localhost | | ... oo| 2025-10-29 00:14:51.038642 | controller -> localhost | | . =.=| 2025-10-29 00:14:51.038659 | controller -> localhost | | . o oo=O| 2025-10-29 00:14:51.038676 | controller -> localhost | | . ...o+. .ooX| 2025-10-29 00:14:51.038692 | controller -> localhost | | o.=S.=... +.| 2025-10-29 00:14:51.038719 | controller -> localhost | | . o.=B. . o | 2025-10-29 00:14:51.038741 | controller -> localhost | | . ..=o+ o | 2025-10-29 00:14:51.038759 | controller -> localhost | | . o = + | 2025-10-29 00:14:51.038777 | controller -> localhost | | E. . o . | 2025-10-29 00:14:51.038797 | controller -> localhost | +----[SHA256]-----+ 2025-10-29 00:14:51.038842 | controller -> localhost | ok: Runtime: 0:00:00.090555 2025-10-29 00:14:51.046434 | 2025-10-29 00:14:51.046568 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-10-29 00:14:51.078593 | controller | ok 2025-10-29 00:14:51.090735 | controller | included: /var/lib/zuul/builds/1b6fd6ae234d4ad986b4503ea7ea4126/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-10-29 00:14:51.102430 | 2025-10-29 00:14:51.102556 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-10-29 00:14:51.145619 | controller | skipping: Conditional result was False 2025-10-29 00:14:51.156738 | 2025-10-29 00:14:51.156901 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-10-29 00:14:52.199044 | controller | changed 2025-10-29 00:14:52.211966 | 2025-10-29 00:14:52.212178 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-10-29 00:14:52.838698 | controller | ok 2025-10-29 00:14:52.858733 | 2025-10-29 00:14:52.858854 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-10-29 00:14:55.750741 | controller | changed 2025-10-29 00:14:55.764519 | 2025-10-29 00:14:55.764669 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-10-29 00:14:58.696004 | controller | changed 2025-10-29 00:14:58.705286 | 2025-10-29 00:14:58.705448 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-10-29 00:14:58.729428 | controller | skipping: Conditional result was False 2025-10-29 00:14:58.744617 | 2025-10-29 00:14:58.744772 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-10-29 00:14:59.243560 | controller -> localhost | changed 2025-10-29 00:14:59.257282 | 2025-10-29 00:14:59.257438 | TASK [add-build-sshkey : Add back temp key] 2025-10-29 00:14:59.573733 | controller -> localhost | Identity added: /var/lib/zuul/builds/1b6fd6ae234d4ad986b4503ea7ea4126/work/1b6fd6ae234d4ad986b4503ea7ea4126_id_rsa (zuul-build-sshkey) 2025-10-29 00:14:59.573989 | controller -> localhost | ok: Runtime: 0:00:00.038895 2025-10-29 00:14:59.592800 | 2025-10-29 00:14:59.592938 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-10-29 00:15:00.764759 | controller | ok 2025-10-29 00:15:00.771434 | 2025-10-29 00:15:00.771571 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-10-29 00:15:00.796956 | controller | skipping: Conditional result was False 2025-10-29 00:15:00.811578 | 2025-10-29 00:15:00.811713 | TASK [Run add-build-sshkey role (ECDSA)] 2025-10-29 00:15:00.849145 | controller | ok 2025-10-29 00:15:00.891141 | 2025-10-29 00:15:00.891297 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-10-29 00:15:01.437331 | controller -> localhost | ok 2025-10-29 00:15:01.446265 | 2025-10-29 00:15:01.446409 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-10-29 00:15:01.481035 | controller | ok 2025-10-29 00:15:01.499879 | controller | included: /var/lib/zuul/builds/1b6fd6ae234d4ad986b4503ea7ea4126/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-10-29 00:15:01.510116 | 2025-10-29 00:15:01.510232 | TASK [add-build-sshkey : Create Temp SSH key] 2025-10-29 00:15:01.809635 | controller -> localhost | Generating public/private ecdsa key pair. 2025-10-29 00:15:01.809837 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/1b6fd6ae234d4ad986b4503ea7ea4126/work/1b6fd6ae234d4ad986b4503ea7ea4126_id_ecdsa. 2025-10-29 00:15:01.809878 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/1b6fd6ae234d4ad986b4503ea7ea4126/work/1b6fd6ae234d4ad986b4503ea7ea4126_id_ecdsa.pub. 2025-10-29 00:15:01.809917 | controller -> localhost | The key fingerprint is: 2025-10-29 00:15:01.809946 | controller -> localhost | SHA256:Cxbj3QzLQT4XrvIc9WBg/S8JGj4L4XfXkcXmne/wPJk zuul-build-sshkey 2025-10-29 00:15:01.809976 | controller -> localhost | The key's randomart image is: 2025-10-29 00:15:01.810003 | controller -> localhost | +---[ECDSA 521]---+ 2025-10-29 00:15:01.810056 | controller -> localhost | | +.. . | 2025-10-29 00:15:01.810086 | controller -> localhost | | + o.. +| 2025-10-29 00:15:01.810115 | controller -> localhost | | o = *. =o| 2025-10-29 00:15:01.810142 | controller -> localhost | | ..=.%.o. o.o| 2025-10-29 00:15:01.810165 | controller -> localhost | | .=oSoo..+ ..| 2025-10-29 00:15:01.810215 | controller -> localhost | | .o==o. + + .| 2025-10-29 00:15:01.810295 | controller -> localhost | | o++ . . =o| 2025-10-29 00:15:01.810329 | controller -> localhost | | . E+| 2025-10-29 00:15:01.810354 | controller -> localhost | | .| 2025-10-29 00:15:01.810393 | controller -> localhost | +----[SHA256]-----+ 2025-10-29 00:15:01.810456 | controller -> localhost | ok: Runtime: 0:00:00.010432 2025-10-29 00:15:01.822279 | 2025-10-29 00:15:01.822408 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-10-29 00:15:01.893136 | controller | ok 2025-10-29 00:15:01.914238 | controller | included: /var/lib/zuul/builds/1b6fd6ae234d4ad986b4503ea7ea4126/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-10-29 00:15:01.948579 | 2025-10-29 00:15:01.948713 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-10-29 00:15:02.005260 | controller | skipping: Conditional result was False 2025-10-29 00:15:02.062621 | 2025-10-29 00:15:02.062770 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-10-29 00:15:02.959883 | controller | changed 2025-10-29 00:15:02.976110 | 2025-10-29 00:15:02.976253 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-10-29 00:15:03.653467 | controller | ok 2025-10-29 00:15:03.690140 | 2025-10-29 00:15:03.690287 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-10-29 00:15:06.738135 | controller | changed 2025-10-29 00:15:06.773553 | 2025-10-29 00:15:06.773728 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-10-29 00:15:09.906351 | controller | changed 2025-10-29 00:15:09.935740 | 2025-10-29 00:15:09.939759 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-10-29 00:15:10.012634 | controller | skipping: Conditional result was False 2025-10-29 00:15:10.024145 | 2025-10-29 00:15:10.024279 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-10-29 00:15:10.700787 | controller -> localhost | changed 2025-10-29 00:15:10.743040 | 2025-10-29 00:15:10.743347 | TASK [add-build-sshkey : Add back temp key] 2025-10-29 00:15:11.208195 | controller -> localhost | Identity added: /var/lib/zuul/builds/1b6fd6ae234d4ad986b4503ea7ea4126/work/1b6fd6ae234d4ad986b4503ea7ea4126_id_ecdsa (zuul-build-sshkey) 2025-10-29 00:15:11.208385 | controller -> localhost | ok: Runtime: 0:00:00.011323 2025-10-29 00:15:11.219129 | 2025-10-29 00:15:11.219281 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-10-29 00:15:12.027833 | controller | ok 2025-10-29 00:15:12.036418 | 2025-10-29 00:15:12.036560 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-10-29 00:15:12.145764 | controller | skipping: Conditional result was False 2025-10-29 00:15:12.179043 | 2025-10-29 00:15:12.179171 | TASK [include_role : remove-zuul-sshkey] 2025-10-29 00:15:12.218051 | controller | skipping: Conditional result was False 2025-10-29 00:15:12.228633 | 2025-10-29 00:15:12.229321 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2025-10-29 00:15:12.856549 | controller | ok: "logs" 2025-10-29 00:15:12.856797 | controller | ok: All items complete 2025-10-29 00:15:12.856825 | 2025-10-29 00:15:13.443475 | controller | ok: "artifacts" 2025-10-29 00:15:14.023840 | controller | ok: "docs" 2025-10-29 00:15:14.034444 | 2025-10-29 00:15:14.034577 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2025-10-29 00:15:14.652328 | controller | changed: "logs" 2025-10-29 00:15:15.232919 | controller | changed: "artifacts" 2025-10-29 00:15:15.817674 | controller | changed: "docs" 2025-10-29 00:15:15.867202 | 2025-10-29 00:15:15.867303 | PLAY RECAP 2025-10-29 00:15:15.867346 | controller | ok: 30 changed: 16 unreachable: 0 failed: 0 skipped: 18 rescued: 0 ignored: 0 2025-10-29 00:15:15.867375 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-10-29 00:15:15.867394 | 2025-10-29 00:15:16.018957 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master] 2025-10-29 00:15:16.019977 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2025-10-29 00:15:16.663599 | 2025-10-29 00:15:16.663745 | PLAY [all] 2025-10-29 00:15:16.697143 | 2025-10-29 00:15:16.697286 | TASK [Install binary dependencies] 2025-10-29 00:15:16.762064 | controller | ok 2025-10-29 00:15:16.797997 | 2025-10-29 00:15:16.798207 | TASK [bindep : Include find tasks] 2025-10-29 00:15:16.844161 | controller | ok 2025-10-29 00:15:16.856372 | controller | included: /var/lib/zuul/builds/1b6fd6ae234d4ad986b4503ea7ea4126/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find.yaml 2025-10-29 00:15:16.866555 | 2025-10-29 00:15:16.866714 | TASK [bindep : Look for bindep.txt] 2025-10-29 00:15:17.790118 | controller | ok 2025-10-29 00:15:17.797362 | 2025-10-29 00:15:17.797494 | TASK [bindep : Define bindep_file fact] 2025-10-29 00:15:17.812076 | controller | skipping: Conditional result was False 2025-10-29 00:15:17.820111 | 2025-10-29 00:15:17.820270 | TASK [bindep : Look for other-requirements.txt] 2025-10-29 00:15:18.408390 | controller | ok 2025-10-29 00:15:18.416792 | 2025-10-29 00:15:18.416892 | TASK [bindep : Define bindep_file fact] 2025-10-29 00:15:18.442303 | controller | skipping: Conditional result was False 2025-10-29 00:15:18.455263 | 2025-10-29 00:15:18.455404 | TASK [bindep : Look for bindep fallback file] 2025-10-29 00:15:18.491009 | controller | skipping: Conditional result was False 2025-10-29 00:15:18.497989 | 2025-10-29 00:15:18.498126 | TASK [bindep : Define bindep_file fact] 2025-10-29 00:15:18.532654 | controller | skipping: Conditional result was False 2025-10-29 00:15:18.542352 | 2025-10-29 00:15:18.542523 | TASK [bindep : Include bindep tasks] 2025-10-29 00:15:18.568758 | controller | skipping: Conditional result was False 2025-10-29 00:15:18.577673 | 2025-10-29 00:15:18.577836 | TASK [bindep : Include install tasks] 2025-10-29 00:15:18.613906 | controller | skipping: Conditional result was False 2025-10-29 00:15:18.625473 | 2025-10-29 00:15:18.625627 | LOOP [bindep : Include package tasks] 2025-10-29 00:15:18.684822 | 2025-10-29 00:15:18.685005 | TASK [Run test-setup role] 2025-10-29 00:15:18.704850 | controller | ok 2025-10-29 00:15:18.725272 | 2025-10-29 00:15:18.725413 | TASK [test-setup : Check if project's tools/test-setup.sh exists] 2025-10-29 00:15:19.393885 | controller | ok 2025-10-29 00:15:19.402317 | 2025-10-29 00:15:19.402429 | TASK [test-setup : Run tools/test-setup.sh] 2025-10-29 00:15:19.762488 | controller | skipping: Conditional result was False 2025-10-29 00:15:19.789088 | 2025-10-29 00:15:19.789183 | PLAY RECAP 2025-10-29 00:15:19.789225 | controller | ok: 4 changed: 0 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2025-10-29 00:15:19.789247 | 2025-10-29 00:15:19.937674 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2025-10-29 00:15:19.938937 | PRE-RUN START: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master] 2025-10-29 00:15:20.621787 | 2025-10-29 00:15:20.621903 | PLAY [controller] 2025-10-29 00:15:20.654900 | 2025-10-29 00:15:20.655089 | TASK [Create the /root directory] 2025-10-29 00:15:21.672384 | controller | ok 2025-10-29 00:15:21.686944 | 2025-10-29 00:15:21.687288 | TASK [Install glibc-langpack-en] 2025-10-29 00:15:28.488731 | controller | ok: Nothing to do 2025-10-29 00:15:28.494258 | 2025-10-29 00:15:28.494325 | TASK [Ensure controller directory exists] 2025-10-29 00:15:29.260557 | controller | changed 2025-10-29 00:15:29.275625 | 2025-10-29 00:15:29.275754 | TASK [Install container runtime] 2025-10-29 00:15:29.362657 | controller | ok 2025-10-29 00:15:29.455261 | 2025-10-29 00:15:29.455428 | LOOP [ensure-docker : Gather variables for each operating system] 2025-10-29 00:15:29.504895 | controller | ok: "/var/lib/zuul/builds/1b6fd6ae234d4ad986b4503ea7ea4126/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/vars/fedora.yaml" 2025-10-29 00:15:29.522609 | 2025-10-29 00:15:29.522748 | TASK [ensure-docker : Sanity check for distro selection] 2025-10-29 00:15:29.556360 | controller | skipping: Conditional result was False 2025-10-29 00:15:29.566760 | 2025-10-29 00:15:29.566915 | TASK [ensure-docker : Sanity check for distro install] 2025-10-29 00:15:29.593723 | controller | skipping: Conditional result was False 2025-10-29 00:15:29.616101 | 2025-10-29 00:15:29.616834 | TASK [ensure-docker : Sanity check for upstream install] 2025-10-29 00:15:29.678912 | controller | skipping: Conditional result was False 2025-10-29 00:15:29.691023 | 2025-10-29 00:15:29.691155 | TASK [ensure-docker : Sanity check for upstream install mirrors] 2025-10-29 00:15:29.755984 | controller | skipping: Conditional result was False 2025-10-29 00:15:29.788881 | 2025-10-29 00:15:29.789073 | TASK [ensure-docker : Include tasks for docker install] 2025-10-29 00:15:29.840685 | controller | ok 2025-10-29 00:15:29.868474 | controller | included: /var/lib/zuul/builds/1b6fd6ae234d4ad986b4503ea7ea4126/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/tasks/docker-upstream.yaml 2025-10-29 00:15:29.897178 | 2025-10-29 00:15:29.897327 | TASK [ensure-docker : Not Implemented] 2025-10-29 00:15:29.947085 | controller | skipping: Conditional result was False 2025-10-29 00:15:29.953567 | 2025-10-29 00:15:29.953680 | TASK [Set up docker mirrors] 2025-10-29 00:15:29.986764 | controller | ok 2025-10-29 00:15:30.031520 | 2025-10-29 00:15:30.031681 | TASK [use-docker-mirror : Set docker_mirror fact from mirror_info] 2025-10-29 00:15:30.092043 | controller | skipping: Conditional result was False 2025-10-29 00:15:30.098464 | 2025-10-29 00:15:30.098545 | TASK [use-docker-mirror : Set docker_mirror fact from zuul_site_mirror_info] 2025-10-29 00:15:30.145314 | controller | skipping: Conditional result was False 2025-10-29 00:15:30.154266 | 2025-10-29 00:15:30.154429 | TASK [use-docker-mirror : Set docker_mirror fact] 2025-10-29 00:15:30.190765 | controller | skipping: Conditional result was False 2025-10-29 00:15:30.200041 | 2025-10-29 00:15:30.200234 | TASK [use-docker-mirror : Set up docker mirrors] 2025-10-29 00:15:30.229403 | controller | skipping: Conditional result was False 2025-10-29 00:15:30.358900 | 2025-10-29 00:15:30.359040 | TASK [Add all repositories] 2025-10-29 00:15:30.398836 | controller | ok 2025-10-29 00:15:30.458910 | 2025-10-29 00:15:30.459186 | LOOP [ensure-package-repositories : Install repositories] 2025-10-29 00:15:30.525018 | controller | ok: "/var/lib/zuul/builds/1b6fd6ae234d4ad986b4503ea7ea4126/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-package-repositories/tasks/RedHat.yaml" 2025-10-29 00:15:30.525273 | controller | ok: All items complete 2025-10-29 00:15:30.525318 | 2025-10-29 00:15:30.540581 | controller | included: /var/lib/zuul/builds/1b6fd6ae234d4ad986b4503ea7ea4126/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-package-repositories/tasks/RedHat.yaml 2025-10-29 00:15:30.567109 | 2025-10-29 00:15:30.567249 | TASK [ensure-package-repositories : Create GPG key temp directory] 2025-10-29 00:15:31.524642 | controller | changed 2025-10-29 00:15:31.538270 | 2025-10-29 00:15:31.538413 | LOOP [ensure-package-repositories : Upload GPG keys] 2025-10-29 00:15:34.801987 | controller | changed: 2025-10-29 00:15:34.802187 | controller | { 2025-10-29 00:15:34.802221 | 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:15:34.802245 | controller | } 2025-10-29 00:15:34.821157 | 2025-10-29 00:15:34.821397 | LOOP [ensure-package-repositories : Import GPG keys] 2025-10-29 00:15:36.039325 | controller | changed: 2025-10-29 00:15:36.039820 | controller | { 2025-10-29 00:15:36.039855 | 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:15:36.039878 | controller | } 2025-10-29 00:15:36.039913 | controller | changed: All items complete 2025-10-29 00:15:36.039950 | 2025-10-29 00:15:36.082154 | 2025-10-29 00:15:36.082304 | TASK [ensure-package-repositories : Remove GPG temp directory] 2025-10-29 00:15:36.766618 | controller | changed 2025-10-29 00:15:36.774103 | 2025-10-29 00:15:36.774209 | LOOP [ensure-package-repositories : Add repositories] 2025-10-29 00:15:37.651733 | controller | changed: 2025-10-29 00:15:37.651998 | controller | { 2025-10-29 00:15:37.652067 | controller | "baseurl": "https://download.docker.com/linux/fedora/$releasever/$basearch/stable", 2025-10-29 00:15:37.652089 | controller | "description": "Docker CE Stable - $basearch", 2025-10-29 00:15:37.652107 | controller | "gpgcheck": true, 2025-10-29 00:15:37.652123 | controller | "name": "docker-ce-stable" 2025-10-29 00:15:37.652139 | controller | } 2025-10-29 00:15:37.718213 | 2025-10-29 00:15:37.721913 | TASK [ensure-docker : Install pre-reqs] 2025-10-29 00:15:42.139202 | controller | ok: Nothing to do 2025-10-29 00:15:42.152836 | 2025-10-29 00:15:42.153062 | TASK [ensure-docker : Remove packages] 2025-10-29 00:15:44.931933 | controller | ok: Nothing to do 2025-10-29 00:15:44.945404 | 2025-10-29 00:15:44.945577 | TASK [ensure-docker : Install upstream docker using package] 2025-10-29 00:16:58.462007 | controller | changed 2025-10-29 00:16:58.469803 | 2025-10-29 00:16:58.469928 | TASK [ensure-docker : Setup docker] 2025-10-29 00:16:58.503732 | controller | ok 2025-10-29 00:16:58.600934 | controller | included: /var/lib/zuul/builds/1b6fd6ae234d4ad986b4503ea7ea4126/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/tasks/docker-setup.yaml 2025-10-29 00:16:58.616226 | 2025-10-29 00:16:58.616357 | TASK [ensure-docker : Ensure "docker" group exists] 2025-10-29 00:16:59.518434 | controller | ok 2025-10-29 00:16:59.525303 | 2025-10-29 00:16:59.525426 | TASK [ensure-docker : Add user to docker group] 2025-10-29 00:17:00.533760 | controller | changed 2025-10-29 00:17:00.541533 | 2025-10-29 00:17:00.541644 | TASK [Update docker daemon proxy configuration] 2025-10-29 00:17:00.566316 | controller | skipping: Conditional result was False 2025-10-29 00:17:00.574248 | 2025-10-29 00:17:00.574341 | TASK [Lower default MTU] 2025-10-29 00:17:00.588853 | controller | skipping: Conditional result was False 2025-10-29 00:17:00.598680 | 2025-10-29 00:17:00.598787 | TASK [ensure-docker : Get the status of the docket.socket unit] 2025-10-29 00:17:01.335900 | controller | inactive 2025-10-29 00:17:01.958211 | controller | ok: Runtime: 0:00:00.016780 2025-10-29 00:17:01.966492 | 2025-10-29 00:17:01.966832 | TASK [ensure-docker : Get the status of the docket unit] 2025-10-29 00:17:02.657572 | controller | inactive 2025-10-29 00:17:02.834783 | controller | ok: Runtime: 0:00:00.012885 2025-10-29 00:17:02.842112 | 2025-10-29 00:17:02.842211 | TASK [ensure-docker : Restart docker] 2025-10-29 00:17:02.873406 | Trigger a Docker restart 2025-10-29 00:17:02.881455 | 2025-10-29 00:17:02.881586 | TASK [ensure-docker : Reset ssh connection to pick up docker group] 2025-10-29 00:17:02.911269 | 2025-10-29 00:17:02.911383 | TASK [ensure-docker : Install docker-compose] 2025-10-29 00:17:02.928591 | controller | skipping: Conditional result was False 2025-10-29 00:17:02.934653 | 2025-10-29 00:17:02.934773 | TASK [ensure-docker : Flush handlers before role exit] 2025-10-29 00:17:02.945847 | 2025-10-29 00:17:02.945969 | TASK [ensure-docker : Stop docker.socket to avoid any conflict] 2025-10-29 00:17:06.281166 | controller | changed 2025-10-29 00:17:06.291231 | 2025-10-29 00:17:06.291364 | TASK [ensure-docker : Assure docker service is running] 2025-10-29 00:17:09.641536 | controller | changed 2025-10-29 00:17:09.649422 | 2025-10-29 00:17:09.649491 | TASK [ensure-docker : Assure docker.socket service is running] 2025-10-29 00:17:11.474911 | controller | ok 2025-10-29 00:17:11.493361 | 2025-10-29 00:17:11.493508 | TASK [ensure-docker : Correct group ownership on docker sock] 2025-10-29 00:17:12.395824 | controller | ok 2025-10-29 00:17:12.402431 | 2025-10-29 00:17:12.402545 | TASK [ensure-docker : Validate ability to talk with docker] 2025-10-29 00:17:12.971802 | controller | CONTAINER ID IMAGE COMMAND CREATED STATUS PORTS NAMES 2025-10-29 00:17:13.261574 | controller | ok: Runtime: 0:00:00.030112 2025-10-29 00:17:13.268398 | 2025-10-29 00:17:13.268517 | TASK [ensure-docker : Get version details] 2025-10-29 00:17:13.886095 | controller | Client: Docker Engine - Community 2025-10-29 00:17:13.886171 | controller | Version: 24.0.7 2025-10-29 00:17:13.886181 | controller | API version: 1.43 2025-10-29 00:17:13.886189 | controller | Go version: go1.20.10 2025-10-29 00:17:13.886207 | controller | Git commit: afdd53b 2025-10-29 00:17:13.886215 | controller | Built: Thu Oct 26 09:09:39 2023 2025-10-29 00:17:13.886222 | controller | OS/Arch: linux/amd64 2025-10-29 00:17:13.886230 | controller | Context: default 2025-10-29 00:17:13.886244 | controller | 2025-10-29 00:17:13.886252 | controller | Server: Docker Engine - Community 2025-10-29 00:17:13.886258 | controller | Engine: 2025-10-29 00:17:13.886265 | controller | Version: 24.0.7 2025-10-29 00:17:13.886271 | controller | API version: 1.43 (minimum version 1.12) 2025-10-29 00:17:13.886279 | controller | Go version: go1.20.10 2025-10-29 00:17:13.886286 | controller | Git commit: 311b9ff 2025-10-29 00:17:13.886292 | controller | Built: Thu Oct 26 09:08:11 2023 2025-10-29 00:17:13.886300 | controller | OS/Arch: linux/amd64 2025-10-29 00:17:13.886308 | controller | Experimental: false 2025-10-29 00:17:13.886326 | controller | containerd: 2025-10-29 00:17:13.886338 | controller | Version: 1.6.25 2025-10-29 00:17:13.888092 | controller | GitCommit: d8f198a4ed8892c764191ef7b3b06d8a2eeb5c7f 2025-10-29 00:17:13.888117 | controller | runc: 2025-10-29 00:17:13.888124 | controller | Version: 1.1.10 2025-10-29 00:17:13.888130 | controller | GitCommit: v1.1.10-0-g18a0cb0 2025-10-29 00:17:13.888139 | controller | docker-init: 2025-10-29 00:17:13.888145 | controller | Version: 0.19.0 2025-10-29 00:17:13.888151 | controller | GitCommit: de40ad0 2025-10-29 00:17:14.171005 | controller | ok: Runtime: 0:00:00.036032 2025-10-29 00:17:14.177061 | 2025-10-29 00:17:14.177148 | TASK [ensure-docker : Dump installed docker details] 2025-10-29 00:17:14.210594 | {'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:17:13.852646', 'end': '2025-10-29 00:17:13.888678', 'delta': '0:00:00.036032', 'msg': '', 'zuul_log_id': 'fa163ec2-ffbe-c478-2ac4-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:17:14.216557 | 2025-10-29 00:17:14.216644 | TASK [Use buildset registry] 2025-10-29 00:17:14.230580 | controller | skipping: Conditional result was False 2025-10-29 00:17:14.248071 | 2025-10-29 00:17:14.248206 | TASK [Ensure python3.8 is present] 2025-10-29 00:17:14.273655 | controller | skipping: Conditional result was False 2025-10-29 00:17:14.283066 | 2025-10-29 00:17:14.283177 | TASK [Run our-ensure-python role (Fedora only for now)] 2025-10-29 00:17:14.307807 | controller | ok 2025-10-29 00:17:14.340579 | 2025-10-29 00:17:14.340736 | TASK [our-ensure-python : Install the right Python version (rpm)] 2025-10-29 00:17:21.525512 | controller | ok: Nothing to do 2025-10-29 00:17:21.534181 | 2025-10-29 00:17:21.534330 | TASK [our-ensure-python : Also install python3-devel] 2025-10-29 00:17:35.295643 | controller | changed 2025-10-29 00:17:35.316808 | 2025-10-29 00:17:35.316951 | TASK [Run ensure-virtualenv role] 2025-10-29 00:17:35.342332 | controller | ok 2025-10-29 00:17:35.380560 | 2025-10-29 00:17:35.380709 | TASK [ensure-virtualenv : Check if virtualenv is installed] 2025-10-29 00:17:36.090626 | controller | /usr/bin/virtualenv 2025-10-29 00:17:36.238442 | controller | ok: Runtime: 0:00:00.006613 2025-10-29 00:17:36.245960 | 2025-10-29 00:17:36.246096 | LOOP [ensure-virtualenv : Install virtualenv package] 2025-10-29 00:17:36.270391 | controller | skipping: Conditional result was False 2025-10-29 00:17:36.270696 | controller | changed: All items complete 2025-10-29 00:17:36.270739 | 2025-10-29 00:17:36.295986 | 2025-10-29 00:17:36.296134 | TASK [Find the full path of the Python interpreter] 2025-10-29 00:17:36.852870 | controller | /usr/bin/python3.9 2025-10-29 00:17:37.151868 | controller | ok 2025-10-29 00:17:37.160258 | 2025-10-29 00:17:37.160375 | TASK [Create virtualenv for ansible-test with a specific interpreter] 2025-10-29 00:17:39.777131 | controller | created virtual environment CPython3.9.18.final.0-64 in 1276ms 2025-10-29 00:17:39.885235 | controller | creator CPython3Posix(dest=/home/zuul/venv, clear=False, no_vcs_ignore=False, global=False) 2025-10-29 00:17:39.885423 | 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:17:39.885448 | controller | added seed packages: pip==22.2.2, setuptools==62.6.0, wheel==0.37.1 2025-10-29 00:17:39.885470 | controller | activators BashActivator,CShellActivator,FishActivator,NushellActivator,PowerShellActivator,PythonActivator 2025-10-29 00:17:40.022635 | controller | changed 2025-10-29 00:17:40.078057 | 2025-10-29 00:17:40.078185 | TASK [Set selinux package] 2025-10-29 00:17:40.103079 | controller | ok 2025-10-29 00:17:40.108626 | 2025-10-29 00:17:40.108727 | TASK [Set selinux package (Fedora)] 2025-10-29 00:17:40.159407 | controller | ok 2025-10-29 00:17:40.165491 | 2025-10-29 00:17:40.165589 | TASK [Install selinux into virtualenv] 2025-10-29 00:17:45.464727 | controller | Collecting selinux-please-lie-to-me 2025-10-29 00:17:45.594201 | controller | Downloading selinux_please_lie_to_me-1.0.1-py2.py3-none-any.whl (3.8 kB) 2025-10-29 00:17:46.614606 | controller | Collecting setuptools<50.0.0 2025-10-29 00:17:46.630836 | controller | Downloading setuptools-49.6.0-py3-none-any.whl (803 kB) 2025-10-29 00:17:46.710859 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 803.3/803.3 kB 11.3 MB/s eta 0:00:00 2025-10-29 00:17:47.034242 | controller | Installing collected packages: setuptools, selinux-please-lie-to-me 2025-10-29 00:17:47.034455 | controller | Attempting uninstall: setuptools 2025-10-29 00:17:47.046134 | controller | Found existing installation: setuptools 62.6.0 2025-10-29 00:17:47.251499 | controller | Uninstalling setuptools-62.6.0: 2025-10-29 00:17:47.267330 | controller | Successfully uninstalled setuptools-62.6.0 2025-10-29 00:17:47.843923 | controller | Successfully installed selinux-please-lie-to-me-1.0.1 setuptools-49.6.0 2025-10-29 00:17:48.013635 | controller | 2025-10-29 00:17:48.241562 | controller | [notice] A new release of pip available: 22.2.2 -> 25.3 2025-10-29 00:17:48.241614 | controller | [notice] To update, run: /home/zuul/venv/bin/python -m pip install --upgrade pip 2025-10-29 00:17:48.530348 | controller | ok: Runtime: 0:00:06.812213 2025-10-29 00:17:48.536585 | 2025-10-29 00:17:48.536714 | TASK [Install pytest-forked into virtualenv] 2025-10-29 00:17:50.506326 | controller | Collecting pytest-forked 2025-10-29 00:17:50.568437 | controller | Downloading pytest_forked-1.6.0-py3-none-any.whl (4.9 kB) 2025-10-29 00:17:50.747011 | controller | Collecting pytest>=3.10 2025-10-29 00:17:50.752500 | controller | Downloading pytest-8.4.2-py3-none-any.whl (365 kB) 2025-10-29 00:17:50.797544 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 365.8/365.8 kB 8.8 MB/s eta 0:00:00 2025-10-29 00:17:50.843137 | controller | Collecting py 2025-10-29 00:17:50.847566 | controller | Downloading py-1.11.0-py2.py3-none-any.whl (98 kB) 2025-10-29 00:17:50.858264 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 98.7/98.7 kB 12.3 MB/s eta 0:00:00 2025-10-29 00:17:50.921796 | controller | Collecting pluggy<2,>=1.5 2025-10-29 00:17:50.926342 | controller | Downloading pluggy-1.6.0-py3-none-any.whl (20 kB) 2025-10-29 00:17:50.995917 | controller | Collecting pygments>=2.7.2 2025-10-29 00:17:51.000630 | controller | Downloading pygments-2.19.2-py3-none-any.whl (1.2 MB) 2025-10-29 00:17:51.028202 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 1.2/1.2 MB 55.6 MB/s eta 0:00:00 2025-10-29 00:17:51.060641 | controller | Collecting iniconfig>=1 2025-10-29 00:17:51.065758 | controller | Downloading iniconfig-2.1.0-py3-none-any.whl (6.0 kB) 2025-10-29 00:17:51.125395 | controller | Collecting packaging>=20 2025-10-29 00:17:51.131705 | controller | Downloading packaging-25.0-py3-none-any.whl (66 kB) 2025-10-29 00:17:51.140027 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 66.5/66.5 kB 11.4 MB/s eta 0:00:00 2025-10-29 00:17:51.180358 | controller | Collecting exceptiongroup>=1 2025-10-29 00:17:51.185370 | controller | Downloading exceptiongroup-1.3.0-py3-none-any.whl (16 kB) 2025-10-29 00:17:51.254866 | controller | Collecting tomli>=1 2025-10-29 00:17:51.259398 | controller | Downloading tomli-2.3.0-py3-none-any.whl (14 kB) 2025-10-29 00:17:51.332209 | controller | Collecting typing-extensions>=4.6.0 2025-10-29 00:17:51.336921 | controller | Downloading typing_extensions-4.15.0-py3-none-any.whl (44 kB) 2025-10-29 00:17:51.346346 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 44.6/44.6 kB 5.8 MB/s eta 0:00:00 2025-10-29 00:17:51.445367 | controller | Installing collected packages: typing-extensions, tomli, pygments, py, pluggy, packaging, iniconfig, exceptiongroup, pytest, pytest-forked 2025-10-29 00:17:53.304495 | 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:17:53.309229 | controller | 2025-10-29 00:17:53.508442 | controller | [notice] A new release of pip available: 22.2.2 -> 25.3 2025-10-29 00:17:53.508496 | controller | [notice] To update, run: /home/zuul/venv/bin/python -m pip install --upgrade pip 2025-10-29 00:17:53.898462 | controller | ok: Runtime: 0:00:03.840043 2025-10-29 00:17:53.905531 | 2025-10-29 00:17:53.905650 | TASK [Update pip] 2025-10-29 00:17:56.508253 | controller | Requirement already satisfied: pip in ./venv/lib/python3.9/site-packages (22.2.2) 2025-10-29 00:17:57.009893 | controller | Collecting pip 2025-10-29 00:17:57.132434 | controller | Downloading pip-25.3-py3-none-any.whl (1.8 MB) 2025-10-29 00:17:57.235243 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 1.8/1.8 MB 18.8 MB/s eta 0:00:00 2025-10-29 00:17:57.475584 | controller | Installing collected packages: pip 2025-10-29 00:17:57.475831 | controller | Attempting uninstall: pip 2025-10-29 00:17:57.476792 | controller | Found existing installation: pip 22.2.2 2025-10-29 00:17:58.169796 | controller | Uninstalling pip-22.2.2: 2025-10-29 00:17:58.244416 | controller | Successfully uninstalled pip-22.2.2 2025-10-29 00:17:59.871277 | controller | Successfully installed pip-25.3 2025-10-29 00:18:00.270612 | controller | ok: Runtime: 0:00:04.971334 2025-10-29 00:18:00.285743 | 2025-10-29 00:18:00.285924 | TASK [Ensure pip temp directory exists in /var/tmp] 2025-10-29 00:18:01.460921 | controller | changed 2025-10-29 00:18:01.466582 | 2025-10-29 00:18:01.466691 | TASK [Install ansible into virtualenv] 2025-10-29 00:18:03.733432 | controller | Processing ./src/github.com/ansible/ansible 2025-10-29 00:18:03.751957 | controller | Installing build dependencies: started 2025-10-29 00:18:05.227895 | controller | Installing build dependencies: finished with status 'done' 2025-10-29 00:18:05.228958 | controller | Getting requirements to build wheel: started 2025-10-29 00:18:06.231917 | controller | Getting requirements to build wheel: finished with status 'done' 2025-10-29 00:18:06.232664 | controller | Preparing metadata (pyproject.toml): started 2025-10-29 00:18:06.993917 | controller | Preparing metadata (pyproject.toml): finished with status 'done' 2025-10-29 00:18:07.090661 | controller | Collecting jinja2 (from ansible-core==2.11.12.post0) 2025-10-29 00:18:07.147871 | controller | Downloading jinja2-3.1.6-py3-none-any.whl.metadata (2.9 kB) 2025-10-29 00:18:07.242239 | controller | Collecting PyYAML (from ansible-core==2.11.12.post0) 2025-10-29 00:18:07.247879 | 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:18:07.691307 | controller | Collecting cryptography (from ansible-core==2.11.12.post0) 2025-10-29 00:18:07.696101 | controller | Downloading cryptography-46.0.3-cp38-abi3-manylinux_2_34_x86_64.whl.metadata (5.7 kB) 2025-10-29 00:18:07.715961 | 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:18:07.734157 | controller | Collecting resolvelib<0.6.0,>=0.5.3 (from ansible-core==2.11.12.post0) 2025-10-29 00:18:07.754186 | controller | Downloading resolvelib-0.5.4-py2.py3-none-any.whl.metadata (3.7 kB) 2025-10-29 00:18:08.001878 | controller | Collecting cffi>=2.0.0 (from cryptography->ansible-core==2.11.12.post0) 2025-10-29 00:18:08.008026 | 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:18:08.016183 | 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:18:08.034239 | controller | Collecting pycparser (from cffi>=2.0.0->cryptography->ansible-core==2.11.12.post0) 2025-10-29 00:18:08.038911 | controller | Downloading pycparser-2.23-py3-none-any.whl.metadata (993 bytes) 2025-10-29 00:18:08.156708 | controller | Collecting MarkupSafe>=2.0 (from jinja2->ansible-core==2.11.12.post0) 2025-10-29 00:18:08.163316 | 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:18:08.178372 | controller | Downloading resolvelib-0.5.4-py2.py3-none-any.whl (12 kB) 2025-10-29 00:18:08.187327 | controller | Downloading cryptography-46.0.3-cp38-abi3-manylinux_2_34_x86_64.whl (4.5 MB) 2025-10-29 00:18:08.259938 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 4.5/4.5 MB 71.8 MB/s 0:00:00 2025-10-29 00:18:08.265900 | controller | Downloading cffi-2.0.0-cp39-cp39-manylinux2014_x86_64.manylinux_2_17_x86_64.whl (216 kB) 2025-10-29 00:18:08.278244 | controller | Downloading jinja2-3.1.6-py3-none-any.whl (134 kB) 2025-10-29 00:18:08.294725 | 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:18:08.303722 | controller | Downloading pycparser-2.23-py3-none-any.whl (118 kB) 2025-10-29 00:18:08.314363 | 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:18:08.332864 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 750.8/750.8 kB 38.1 MB/s 0:00:00 2025-10-29 00:18:08.350007 | controller | Building wheels for collected packages: ansible-core 2025-10-29 00:18:08.351290 | controller | Building wheel for ansible-core (pyproject.toml): started 2025-10-29 00:18:10.622925 | controller | Building wheel for ansible-core (pyproject.toml): finished with status 'done' 2025-10-29 00:18:10.627696 | controller | Created wheel for ansible-core: filename=ansible_core-2.11.12.post0-py3-none-any.whl size=1961250 sha256=bba0fad3728abfde34cf8c1545e0638e28a60e34ac80740ba81461a5dd38ed8c 2025-10-29 00:18:10.627957 | controller | Stored in directory: /var/tmp/ansible-test-pip/pip-ephem-wheel-cache-nuo9lp7t/wheels/0f/a0/93/ab85b739e1eb1e0467171316f13571c14efdf87e0d5d44a33e 2025-10-29 00:18:10.640329 | controller | Successfully built ansible-core 2025-10-29 00:18:10.694906 | controller | Installing collected packages: resolvelib, PyYAML, pycparser, MarkupSafe, jinja2, cffi, cryptography, ansible-core 2025-10-29 00:18:13.085349 | controller | 2025-10-29 00:18:13.089974 | 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:18:13.834350 | controller | ok: Runtime: 0:00:10.877786 2025-10-29 00:18:13.845065 | 2025-10-29 00:18:13.845214 | TASK [Setup download-artifact-fork role] 2025-10-29 00:18:13.871746 | controller | ok 2025-10-29 00:18:13.900010 | 2025-10-29 00:18:13.900799 | TASK [download-artifact-fork : Parse inventory response] 2025-10-29 00:18:13.944449 | controller | ok 2025-10-29 00:18:13.952811 | 2025-10-29 00:18:13.952939 | TASK [download-artifact-fork : Fetch info from Zuul API] 2025-10-29 00:18:13.977861 | controller | skipping: Conditional result was False 2025-10-29 00:18:13.986246 | 2025-10-29 00:18:13.986377 | TASK [download-artifact-fork : Parse build response] 2025-10-29 00:18:14.011215 | controller | skipping: Conditional result was False 2025-10-29 00:18:14.022372 | 2025-10-29 00:18:14.022557 | TASK [download-artifact-fork : Ensure artifacts directory exists] 2025-10-29 00:18:14.828248 | controller | changed 2025-10-29 00:18:14.839463 | 2025-10-29 00:18:14.839622 | LOOP [download-artifact-fork : Download archive by type] 2025-10-29 00:18:14.868099 | controller | skipping: Conditional result was False 2025-10-29 00:18:14.868548 | 2025-10-29 00:18:17.073122 | controller | changed: OK (20199 bytes) 2025-10-29 00:18:17.123740 | 2025-10-29 00:18:17.123863 | TASK [deploy-artifacts : define __collections] 2025-10-29 00:18:17.154474 | controller | ok 2025-10-29 00:18:17.160620 | 2025-10-29 00:18:17.160734 | LOOP [deploy-artifacts : Create list of collections] 2025-10-29 00:18:17.188731 | controller | skipping: Conditional result was False 2025-10-29 00:18:17.205454 | controller | ok: 2025-10-29 00:18:17.205591 | controller | { 2025-10-29 00:18:17.205639 | controller | "branch": "main", 2025-10-29 00:18:17.205668 | controller | "job": "build-ansible-collection", 2025-10-29 00:18:17.205692 | controller | "metadata": { 2025-10-29 00:18:17.205716 | controller | "type": "ansible_collection", 2025-10-29 00:18:17.205739 | controller | "version": "5.0.0" 2025-10-29 00:18:17.205762 | controller | }, 2025-10-29 00:18:17.205785 | controller | "name": "ansible.network", 2025-10-29 00:18:17.205809 | controller | "project": "ansible-collections/ansible.network", 2025-10-29 00:18:17.205840 | controller | "url": "https://5b811762c597a4e61588-f1df475a3bd31dc5211674e175785e2a.ssl.cf2.rackcdn.com/ansible/e4d0b785852b4fe89b2826d19f1f109d/artifacts/ansible-network-5.0.0.tar.gz" 2025-10-29 00:18:17.205881 | controller | } 2025-10-29 00:18:17.213840 | 2025-10-29 00:18:17.213966 | TASK [deploy-artifacts : Install require-project collection using ansible-galaxy] 2025-10-29 00:18:18.589919 | controller | Starting galaxy collection install process 2025-10-29 00:18:18.590255 | controller | Process install dependency map 2025-10-29 00:18:21.501305 | controller | [WARNING]: Skipping Galaxy server https://galaxy.ansible.com/api/. Got an 2025-10-29 00:18:21.501681 | controller | unexpected error when getting available versions of collection 2025-10-29 00:18:21.501708 | controller | ansible.netcommon: '/api/v3/plugin/ansible/content/published/collections/index/ 2025-10-29 00:18:21.501717 | controller | ansible/netcommon/versions/' 2025-10-29 00:18:21.501728 | controller | ERROR! Unexpected Exception, this is probably a bug: '/api/v3/plugin/ansible/content/published/collections/index/ansible/netcommon/versions/' 2025-10-29 00:18:21.501785 | controller | to see the full traceback, use -vvv 2025-10-29 00:18:22.071634 | controller | ERROR 2025-10-29 00:18:22.071839 | controller | { 2025-10-29 00:18:22.071870 | controller | "delta": "0:00:03.649599", 2025-10-29 00:18:22.071889 | controller | "end": "2025-10-29 00:18:21.595437", 2025-10-29 00:18:22.071906 | controller | "msg": "non-zero return code", 2025-10-29 00:18:22.071922 | controller | "rc": 250, 2025-10-29 00:18:22.071939 | controller | "start": "2025-10-29 00:18:17.945838" 2025-10-29 00:18:22.071954 | controller | } failure 2025-10-29 00:18:22.074037 | 2025-10-29 00:18:22.074107 | PLAY RECAP 2025-10-29 00:18:22.074153 | controller | ok: 44 changed: 24 unreachable: 0 failed: 1 skipped: 17 rescued: 0 ignored: 0 2025-10-29 00:18:22.074175 | 2025-10-29 00:18:22.235756 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master] 2025-10-29 00:18:22.237246 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2025-10-29 00:18:22.901278 | 2025-10-29 00:18:22.901420 | PLAY [all] 2025-10-29 00:18:22.930209 | 2025-10-29 00:18:22.930349 | TASK [fetch-subunit-output : Find stestr or testr executable] 2025-10-29 00:18:25.868466 | controller | changed: non-zero return code 2025-10-29 00:18:25.877161 | 2025-10-29 00:18:25.877299 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2025-10-29 00:18:25.903288 | controller | skipping: Conditional result was False 2025-10-29 00:18:25.913704 | 2025-10-29 00:18:25.913874 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2025-10-29 00:18:25.948818 | 2025-10-29 00:18:25.949085 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2025-10-29 00:18:25.984697 | 2025-10-29 00:18:25.984939 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2025-10-29 00:18:26.010277 | controller | skipping: Conditional result was False 2025-10-29 00:18:26.020192 | 2025-10-29 00:18:26.020336 | LOOP [fetch-subunit-output : Generate subunit file] 2025-10-29 00:18:26.051807 | 2025-10-29 00:18:26.051961 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2025-10-29 00:18:26.066362 | controller | skipping: Conditional result was False 2025-10-29 00:18:26.074445 | 2025-10-29 00:18:26.074550 | TASK [fetch-subunit-output : Remove the temporary file] 2025-10-29 00:18:26.098592 | controller | skipping: Conditional result was False 2025-10-29 00:18:26.104665 | 2025-10-29 00:18:26.104737 | TASK [fetch-subunit-output : Process and fetch subunit results] 2025-10-29 00:18:26.128794 | controller | skipping: Conditional result was False 2025-10-29 00:18:26.156195 | 2025-10-29 00:18:26.156307 | PLAY RECAP 2025-10-29 00:18:26.156351 | controller | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2025-10-29 00:18:26.156376 | 2025-10-29 00:18:26.262155 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2025-10-29 00:18:26.263178 | POST-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/post.yaml@master] 2025-10-29 00:18:26.902913 | 2025-10-29 00:18:26.903055 | PLAY [all:!appliance*] 2025-10-29 00:18:26.927726 | 2025-10-29 00:18:26.927873 | TASK [unregister the node] 2025-10-29 00:18:27.288262 | controller | skipping: Conditional result was False 2025-10-29 00:18:27.303987 | 2025-10-29 00:18:27.304256 | TASK [include_role : fetch-output] 2025-10-29 00:18:27.347960 | controller | ok 2025-10-29 00:18:27.396863 | 2025-10-29 00:18:27.397009 | TASK [fetch-output : Set log path for multiple nodes] 2025-10-29 00:18:27.452841 | controller | skipping: Conditional result was False 2025-10-29 00:18:27.462533 | 2025-10-29 00:18:27.462651 | TASK [fetch-output : Set log path for single node] 2025-10-29 00:18:27.506075 | controller | ok 2025-10-29 00:18:27.514683 | 2025-10-29 00:18:27.514802 | LOOP [fetch-output : Ensure local output dirs] 2025-10-29 00:18:27.970188 | controller -> localhost | ok: "/var/lib/zuul/builds/1b6fd6ae234d4ad986b4503ea7ea4126/work/logs" 2025-10-29 00:18:28.206082 | controller -> localhost | changed: "/var/lib/zuul/builds/1b6fd6ae234d4ad986b4503ea7ea4126/work/artifacts" 2025-10-29 00:18:28.461687 | controller -> localhost | changed: "/var/lib/zuul/builds/1b6fd6ae234d4ad986b4503ea7ea4126/work/docs" 2025-10-29 00:18:28.488775 | 2025-10-29 00:18:28.488963 | LOOP [fetch-output : Collect logs, artifacts and docs] 2025-10-29 00:18:30.723796 | controller | changed: 2025-10-29 00:18:30.724210 | controller | .d..t...... ./ 2025-10-29 00:18:30.724258 | controller | cd+++++++++ controller/ 2025-10-29 00:18:30.724297 | controller | changed: All items complete 2025-10-29 00:18:30.724319 | 2025-10-29 00:18:32.734076 | controller | changed: .d..t...... ./ 2025-10-29 00:18:34.786410 | controller | changed: .d..t...... ./ 2025-10-29 00:18:34.814170 | 2025-10-29 00:18:34.814320 | TASK [include_role : fetch-output-openshift] 2025-10-29 00:18:34.840887 | controller | skipping: Conditional result was False 2025-10-29 00:18:34.850318 | 2025-10-29 00:18:34.850449 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2025-10-29 00:18:34.896209 | controller | skipping: Conditional result was False 2025-10-29 00:18:34.910482 | controller | skipping: Conditional result was False 2025-10-29 00:18:34.954697 | 2025-10-29 00:18:34.955146 | PLAY [localhost] 2025-10-29 00:18:34.970740 | 2025-10-29 00:18:34.970852 | TASK [Run Zuul manifest role] 2025-10-29 00:18:34.989645 | localhost | ok 2025-10-29 00:18:35.009368 | 2025-10-29 00:18:35.009510 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-10-29 00:18:35.423670 | localhost | changed 2025-10-29 00:18:35.431737 | 2025-10-29 00:18:35.431858 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-10-29 00:18:35.464825 | localhost | ok 2025-10-29 00:18:35.484792 | 2025-10-29 00:18:35.484965 | TASK [Set zuul-log-path fact] 2025-10-29 00:18:35.508604 | localhost | ok 2025-10-29 00:18:35.569236 | 2025-10-29 00:18:35.569364 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-10-29 00:18:35.599132 | localhost | ok 2025-10-29 00:18:35.614805 | 2025-10-29 00:18:35.614947 | LOOP [Run upload-logs-swift role] 2025-10-29 00:18:35.639379 | localhost | Output suppressed because no_log was given 2025-10-29 00:18:35.686508 | 2025-10-29 00:18:35.686633 | TASK [Set zuul-log-path fact] 2025-10-29 00:18:35.711424 | localhost | skipping: Conditional result was False 2025-10-29 00:18:35.719335 | 2025-10-29 00:18:35.719449 | TASK [upload-logs-swift : Ensure logs are readable before uploading] 2025-10-29 00:18:36.108080 | localhost -> localhost | ok: Runtime: 0:00:00.007722 2025-10-29 00:18:36.117588 | 2025-10-29 00:18:36.117734 | TASK [upload-logs-swift : Upload logs to swift]