2025-12-31 00:16:04.312380 | Job console starting... 2025-12-31 00:16:04.324037 | Updating repositories 2025-12-31 00:16:04.487775 | Preparing job workspace 2025-12-31 00:16:10.298296 | Running Ansible setup... 2025-12-31 00:16:15.454880 | PRE-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master] 2025-12-31 00:16:16.111595 | 2025-12-31 00:16:16.111790 | PLAY [localhost] 2025-12-31 00:16:16.121255 | 2025-12-31 00:16:16.121366 | TASK [Gathering Facts] 2025-12-31 00:16:17.105889 | localhost | ok 2025-12-31 00:16:17.130157 | 2025-12-31 00:16:17.130355 | TASK [Setup log path fact] 2025-12-31 00:16:17.152120 | localhost | ok 2025-12-31 00:16:17.170338 | 2025-12-31 00:16:17.170480 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-12-31 00:16:17.192758 | localhost | ok 2025-12-31 00:16:17.207620 | 2025-12-31 00:16:17.207797 | TASK [emit-job-header : Print job information] 2025-12-31 00:16:17.237408 | # Job Information 2025-12-31 00:16:17.237568 | Ansible Version: 2.15.12 2025-12-31 00:16:17.237599 | Job: ansible-test-sanity-docker-stable-2.11 2025-12-31 00:16:17.237620 | Pipeline: periodic 2025-12-31 00:16:17.237641 | Executor: ze03.softwarefactory-project.io 2025-12-31 00:16:17.237662 | Triggered by: https://github.com/ansible-collections/ansible.network 2025-12-31 00:16:17.237683 | Log URL (when completed): https://ansible.softwarefactory-project.io/logs/936/ansible/9360cf52aa984e1ea60a79e9e83e52b8/ 2025-12-31 00:16:17.237702 | Event ID: 214f7501b1d74dd5aa83a4ef761a9fe7 2025-12-31 00:16:17.242482 | 2025-12-31 00:16:17.242583 | LOOP [emit-job-header : Print node information] 2025-12-31 00:16:17.339854 | localhost | ok: 2025-12-31 00:16:17.340040 | localhost | # Node Information 2025-12-31 00:16:17.340078 | localhost | Inventory Hostname: controller 2025-12-31 00:16:17.340104 | localhost | Hostname: np0005572779 2025-12-31 00:16:17.340128 | localhost | Username: zuul 2025-12-31 00:16:17.340155 | localhost | Distro: Fedora 37 2025-12-31 00:16:17.340178 | localhost | Provider: ansible-vexxhost-ca-ymq-1 2025-12-31 00:16:17.340201 | localhost | Region: ca-ymq-1 2025-12-31 00:16:17.340222 | localhost | Label: ansible-fedora-37-1vcpu 2025-12-31 00:16:17.340243 | localhost | Product Name: OpenStack Nova 2025-12-31 00:16:17.340263 | localhost | Interface IP: 162.253.55.218 2025-12-31 00:16:17.349279 | 2025-12-31 00:16:17.349410 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-12-31 00:16:17.772844 | localhost -> localhost | changed 2025-12-31 00:16:17.786323 | 2025-12-31 00:16:17.786519 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-12-31 00:16:19.012032 | localhost -> localhost | changed 2025-12-31 00:16:19.036027 | 2025-12-31 00:16:19.036127 | PLAY [all:!appliance*] 2025-12-31 00:16:19.054527 | 2025-12-31 00:16:19.054645 | TASK [include_role : start-zuul-console] 2025-12-31 00:16:19.074983 | controller | ok 2025-12-31 00:16:19.088942 | 2025-12-31 00:16:19.089030 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-12-31 00:16:19.469780 | controller | ok 2025-12-31 00:16:19.494683 | 2025-12-31 00:16:19.494856 | TASK [use-our-mirror : Retrieve the IP address] 2025-12-31 00:16:20.426531 | controller | ok: Status code was 404 and not [200]: HTTP Error 404: Not Found 2025-12-31 00:16:20.445093 | 2025-12-31 00:16:20.445246 | TASK [use-our-mirror : Ensure the registry service is running on the mirror] 2025-12-31 00:16:20.996276 | controller | skipping: Conditional result was False 2025-12-31 00:16:21.004357 | 2025-12-31 00:16:21.004512 | TASK [use-our-mirror : Write the IP in /etc/hosts] 2025-12-31 00:16:21.040898 | controller | skipping: Conditional result was False 2025-12-31 00:16:21.048485 | 2025-12-31 00:16:21.048603 | TASK [use-our-mirror : Create the podman configuration directory] 2025-12-31 00:16:21.072849 | controller | skipping: Conditional result was False 2025-12-31 00:16:21.080812 | 2025-12-31 00:16:21.080920 | TASK [use-our-mirror : Copy the podman configuration] 2025-12-31 00:16:21.105916 | controller | skipping: Conditional result was False 2025-12-31 00:16:21.116270 | 2025-12-31 00:16:21.116430 | TASK [use-our-mirror : Copy the Yum configuration (main)] 2025-12-31 00:16:21.152273 | controller | skipping: Conditional result was False 2025-12-31 00:16:21.160811 | 2025-12-31 00:16:21.160941 | TASK [use-our-mirror : Copy the Yum configuration (update)] 2025-12-31 00:16:21.184957 | controller | skipping: Conditional result was False 2025-12-31 00:16:21.200608 | 2025-12-31 00:16:21.200755 | TASK [Disable Fedora Modular] 2025-12-31 00:16:21.434639 | controller | changed 2025-12-31 00:16:21.441314 | 2025-12-31 00:16:21.441419 | TASK [Enable EPEL] 2025-12-31 00:16:21.457044 | controller | skipping: Conditional result was False 2025-12-31 00:16:21.466518 | 2025-12-31 00:16:21.466659 | TASK [Register the RHEL node] 2025-12-31 00:16:22.022888 | 2025-12-31 00:16:22.023093 | TASK [Show the subscription-manager status] 2025-12-31 00:16:22.601698 | controller | skipping: Conditional result was False 2025-12-31 00:16:22.608952 | 2025-12-31 00:16:22.609034 | TASK [Enable EPEL on RHEL] 2025-12-31 00:16:23.140610 | controller | skipping: Conditional result was False 2025-12-31 00:16:23.148318 | 2025-12-31 00:16:23.148443 | TASK [Install git and tox] 2025-12-31 00:17:43.130817 | controller | changed 2025-12-31 00:17:43.147854 | 2025-12-31 00:17:43.148045 | TASK [include_role : prepare-workspace] 2025-12-31 00:17:43.190919 | controller | ok 2025-12-31 00:17:43.241309 | 2025-12-31 00:17:43.241485 | TASK [prepare-workspace : Start zuul_console daemon.] 2025-12-31 00:17:43.500373 | controller | ok 2025-12-31 00:17:43.509987 | 2025-12-31 00:17:43.510178 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2025-12-31 00:17:56.308806 | controller | Output suppressed because no_log was given 2025-12-31 00:17:56.357190 | 2025-12-31 00:17:56.357301 | TASK [include_role : prepare-workspace-openshift] 2025-12-31 00:17:56.381286 | controller | skipping: Conditional result was False 2025-12-31 00:17:56.404665 | 2025-12-31 00:17:56.404785 | PLAY [all:!appliance] 2025-12-31 00:17:56.423229 | 2025-12-31 00:17:56.423352 | TASK [Run add-build-sshkey role (RSA)] 2025-12-31 00:17:56.453458 | controller | ok 2025-12-31 00:17:56.469577 | 2025-12-31 00:17:56.469677 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-12-31 00:17:56.739572 | controller -> localhost | ok 2025-12-31 00:17:56.747888 | 2025-12-31 00:17:56.748006 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-12-31 00:17:56.780468 | controller | ok 2025-12-31 00:17:56.802356 | controller | included: /var/lib/zuul/builds/9360cf52aa984e1ea60a79e9e83e52b8/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-12-31 00:17:56.812217 | 2025-12-31 00:17:56.812313 | TASK [add-build-sshkey : Create Temp SSH key] 2025-12-31 00:17:57.353183 | controller -> localhost | Generating public/private rsa key pair. 2025-12-31 00:17:57.353636 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/9360cf52aa984e1ea60a79e9e83e52b8/work/9360cf52aa984e1ea60a79e9e83e52b8_id_rsa. 2025-12-31 00:17:57.353789 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/9360cf52aa984e1ea60a79e9e83e52b8/work/9360cf52aa984e1ea60a79e9e83e52b8_id_rsa.pub. 2025-12-31 00:17:57.353916 | controller -> localhost | The key fingerprint is: 2025-12-31 00:17:57.353992 | controller -> localhost | SHA256:9/h8QLUemLv53e2rT8hLQx+eNM6rEKTguRS2GpWd8UQ zuul-build-sshkey 2025-12-31 00:17:57.354042 | controller -> localhost | The key's randomart image is: 2025-12-31 00:17:57.354087 | controller -> localhost | +---[RSA 2048]----+ 2025-12-31 00:17:57.354130 | controller -> localhost | | ..E | 2025-12-31 00:17:57.354173 | controller -> localhost | | o = . | 2025-12-31 00:17:57.354215 | controller -> localhost | | * o o + . | 2025-12-31 00:17:57.354256 | controller -> localhost | | + = o + o | 2025-12-31 00:17:57.354297 | controller -> localhost | | . =S..o + = | 2025-12-31 00:17:57.354355 | controller -> localhost | | + .. o* O +| 2025-12-31 00:17:57.354409 | controller -> localhost | | . . ...O B | 2025-12-31 00:17:57.354456 | controller -> localhost | | o= =.+| 2025-12-31 00:17:57.354501 | controller -> localhost | | o*==*| 2025-12-31 00:17:57.354552 | controller -> localhost | +----[SHA256]-----+ 2025-12-31 00:17:57.354927 | controller -> localhost | ok: Runtime: 0:00:00.106985 2025-12-31 00:17:57.371872 | 2025-12-31 00:17:57.372202 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-12-31 00:17:57.418622 | controller | ok 2025-12-31 00:17:57.439219 | controller | included: /var/lib/zuul/builds/9360cf52aa984e1ea60a79e9e83e52b8/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-12-31 00:17:57.474072 | 2025-12-31 00:17:57.474250 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-12-31 00:17:57.501789 | controller | skipping: Conditional result was False 2025-12-31 00:17:57.512032 | 2025-12-31 00:17:57.512181 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-12-31 00:17:57.968435 | controller | changed 2025-12-31 00:17:57.984493 | 2025-12-31 00:17:57.984680 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-12-31 00:17:58.201507 | controller | ok 2025-12-31 00:17:58.216471 | 2025-12-31 00:17:58.216655 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-12-31 00:17:58.892193 | controller | changed 2025-12-31 00:17:58.906941 | 2025-12-31 00:17:58.907113 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-12-31 00:17:59.525960 | controller | changed 2025-12-31 00:17:59.534915 | 2025-12-31 00:17:59.535051 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-12-31 00:17:59.551595 | controller | skipping: Conditional result was False 2025-12-31 00:17:59.560966 | 2025-12-31 00:17:59.561082 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-12-31 00:17:59.985623 | controller -> localhost | changed 2025-12-31 00:18:00.007832 | 2025-12-31 00:18:00.008012 | TASK [add-build-sshkey : Add back temp key] 2025-12-31 00:18:00.312429 | controller -> localhost | Identity added: /var/lib/zuul/builds/9360cf52aa984e1ea60a79e9e83e52b8/work/9360cf52aa984e1ea60a79e9e83e52b8_id_rsa (zuul-build-sshkey) 2025-12-31 00:18:00.312746 | controller -> localhost | ok: Runtime: 0:00:00.009601 2025-12-31 00:18:00.322766 | 2025-12-31 00:18:00.322897 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-12-31 00:18:00.711951 | controller | ok 2025-12-31 00:18:00.725936 | 2025-12-31 00:18:00.726102 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-12-31 00:18:00.753130 | controller | skipping: Conditional result was False 2025-12-31 00:18:00.764820 | 2025-12-31 00:18:00.764931 | TASK [Run add-build-sshkey role (ECDSA)] 2025-12-31 00:18:00.799363 | controller | ok 2025-12-31 00:18:00.826704 | 2025-12-31 00:18:00.826891 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-12-31 00:18:01.096923 | controller -> localhost | ok 2025-12-31 00:18:01.111597 | 2025-12-31 00:18:01.111772 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-12-31 00:18:01.133719 | controller | ok 2025-12-31 00:18:01.146856 | controller | included: /var/lib/zuul/builds/9360cf52aa984e1ea60a79e9e83e52b8/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-12-31 00:18:01.153050 | 2025-12-31 00:18:01.153114 | TASK [add-build-sshkey : Create Temp SSH key] 2025-12-31 00:18:01.442981 | controller -> localhost | Generating public/private ecdsa key pair. 2025-12-31 00:18:01.443296 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/9360cf52aa984e1ea60a79e9e83e52b8/work/9360cf52aa984e1ea60a79e9e83e52b8_id_ecdsa. 2025-12-31 00:18:01.443404 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/9360cf52aa984e1ea60a79e9e83e52b8/work/9360cf52aa984e1ea60a79e9e83e52b8_id_ecdsa.pub. 2025-12-31 00:18:01.443541 | controller -> localhost | The key fingerprint is: 2025-12-31 00:18:01.443597 | controller -> localhost | SHA256:V6DA1rTAL/qmPJP9D8CTe8GOM4EgIq8afq2mwe7Z/1A zuul-build-sshkey 2025-12-31 00:18:01.443638 | controller -> localhost | The key's randomart image is: 2025-12-31 00:18:01.443677 | controller -> localhost | +---[ECDSA 521]---+ 2025-12-31 00:18:01.443715 | controller -> localhost | | ooo. . | 2025-12-31 00:18:01.443806 | controller -> localhost | | +o.o . | 2025-12-31 00:18:01.443848 | controller -> localhost | |+ . . .o . | 2025-12-31 00:18:01.443891 | controller -> localhost | |oo . o.o. . | 2025-12-31 00:18:01.443928 | controller -> localhost | | . ..*ES . | 2025-12-31 00:18:01.443966 | controller -> localhost | |.. . .B o | 2025-12-31 00:18:01.444003 | controller -> localhost | |oo .== + | 2025-12-31 00:18:01.444043 | controller -> localhost | |+.+++.=+ . | 2025-12-31 00:18:01.444081 | controller -> localhost | |o*+o=*.o... | 2025-12-31 00:18:01.444118 | controller -> localhost | +----[SHA256]-----+ 2025-12-31 00:18:01.444204 | controller -> localhost | ok: Runtime: 0:00:00.015204 2025-12-31 00:18:01.456321 | 2025-12-31 00:18:01.456467 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-12-31 00:18:01.491026 | controller | ok 2025-12-31 00:18:01.502493 | controller | included: /var/lib/zuul/builds/9360cf52aa984e1ea60a79e9e83e52b8/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-12-31 00:18:01.516516 | 2025-12-31 00:18:01.516695 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-12-31 00:18:01.542918 | controller | skipping: Conditional result was False 2025-12-31 00:18:01.553484 | 2025-12-31 00:18:01.553654 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-12-31 00:18:01.875989 | controller | changed 2025-12-31 00:18:01.885206 | 2025-12-31 00:18:01.885316 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-12-31 00:18:02.140222 | controller | ok 2025-12-31 00:18:02.156837 | 2025-12-31 00:18:02.157246 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-12-31 00:18:02.811770 | controller | changed 2025-12-31 00:18:02.823117 | 2025-12-31 00:18:02.823209 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-12-31 00:18:03.501917 | controller | changed 2025-12-31 00:18:03.510969 | 2025-12-31 00:18:03.511102 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-12-31 00:18:03.547150 | controller | skipping: Conditional result was False 2025-12-31 00:18:03.565839 | 2025-12-31 00:18:03.566059 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-12-31 00:18:03.897468 | controller -> localhost | changed 2025-12-31 00:18:03.909052 | 2025-12-31 00:18:03.909129 | TASK [add-build-sshkey : Add back temp key] 2025-12-31 00:18:04.232821 | controller -> localhost | Identity added: /var/lib/zuul/builds/9360cf52aa984e1ea60a79e9e83e52b8/work/9360cf52aa984e1ea60a79e9e83e52b8_id_ecdsa (zuul-build-sshkey) 2025-12-31 00:18:04.233051 | controller -> localhost | ok: Runtime: 0:00:00.009669 2025-12-31 00:18:04.240382 | 2025-12-31 00:18:04.240507 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-12-31 00:18:04.435539 | controller | ok 2025-12-31 00:18:04.441614 | 2025-12-31 00:18:04.441696 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-12-31 00:18:04.466944 | controller | skipping: Conditional result was False 2025-12-31 00:18:04.480453 | 2025-12-31 00:18:04.480567 | TASK [include_role : remove-zuul-sshkey] 2025-12-31 00:18:04.506657 | controller | skipping: Conditional result was False 2025-12-31 00:18:04.513493 | 2025-12-31 00:18:04.513564 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2025-12-31 00:18:04.746972 | controller | ok: "logs" 2025-12-31 00:18:04.747534 | controller | ok: All items complete 2025-12-31 00:18:04.747608 | 2025-12-31 00:18:04.948341 | controller | ok: "artifacts" 2025-12-31 00:18:05.138128 | controller | ok: "docs" 2025-12-31 00:18:05.149909 | 2025-12-31 00:18:05.150024 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2025-12-31 00:18:05.386814 | controller | changed: "logs" 2025-12-31 00:18:05.579061 | controller | changed: "artifacts" 2025-12-31 00:18:05.768036 | controller | changed: "docs" 2025-12-31 00:18:05.817045 | 2025-12-31 00:18:05.817176 | PLAY RECAP 2025-12-31 00:18:05.817238 | controller | ok: 30 changed: 16 unreachable: 0 failed: 0 skipped: 18 rescued: 0 ignored: 0 2025-12-31 00:18:05.817276 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-12-31 00:18:05.817302 | 2025-12-31 00:18:05.961936 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master] 2025-12-31 00:18:05.962973 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2025-12-31 00:18:06.617365 | 2025-12-31 00:18:06.617485 | PLAY [all] 2025-12-31 00:18:06.642877 | 2025-12-31 00:18:06.643011 | TASK [Install binary dependencies] 2025-12-31 00:18:06.717142 | controller | ok 2025-12-31 00:18:06.746507 | 2025-12-31 00:18:06.746668 | TASK [bindep : Include find tasks] 2025-12-31 00:18:06.776709 | controller | ok 2025-12-31 00:18:06.784619 | controller | included: /var/lib/zuul/builds/9360cf52aa984e1ea60a79e9e83e52b8/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find.yaml 2025-12-31 00:18:06.790712 | 2025-12-31 00:18:06.790793 | TASK [bindep : Look for bindep.txt] 2025-12-31 00:18:07.164302 | controller | ok 2025-12-31 00:18:07.175277 | 2025-12-31 00:18:07.175448 | TASK [bindep : Define bindep_file fact] 2025-12-31 00:18:07.201699 | controller | skipping: Conditional result was False 2025-12-31 00:18:07.213552 | 2025-12-31 00:18:07.213814 | TASK [bindep : Look for other-requirements.txt] 2025-12-31 00:18:07.438397 | controller | ok 2025-12-31 00:18:07.452575 | 2025-12-31 00:18:07.452782 | TASK [bindep : Define bindep_file fact] 2025-12-31 00:18:07.489349 | controller | skipping: Conditional result was False 2025-12-31 00:18:07.504901 | 2025-12-31 00:18:07.505078 | TASK [bindep : Look for bindep fallback file] 2025-12-31 00:18:07.544233 | controller | skipping: Conditional result was False 2025-12-31 00:18:07.554608 | 2025-12-31 00:18:07.554743 | TASK [bindep : Define bindep_file fact] 2025-12-31 00:18:07.579952 | controller | skipping: Conditional result was False 2025-12-31 00:18:07.595441 | 2025-12-31 00:18:07.595692 | TASK [bindep : Include bindep tasks] 2025-12-31 00:18:07.623400 | controller | skipping: Conditional result was False 2025-12-31 00:18:07.633583 | 2025-12-31 00:18:07.634364 | TASK [bindep : Include install tasks] 2025-12-31 00:18:07.659520 | controller | skipping: Conditional result was False 2025-12-31 00:18:07.669316 | 2025-12-31 00:18:07.669474 | LOOP [bindep : Include package tasks] 2025-12-31 00:18:07.733940 | 2025-12-31 00:18:07.734175 | TASK [Run test-setup role] 2025-12-31 00:18:07.761693 | controller | ok 2025-12-31 00:18:07.789386 | 2025-12-31 00:18:07.789540 | TASK [test-setup : Check if project's tools/test-setup.sh exists] 2025-12-31 00:18:08.013280 | controller | ok 2025-12-31 00:18:08.028188 | 2025-12-31 00:18:08.028353 | TASK [test-setup : Run tools/test-setup.sh] 2025-12-31 00:18:08.577439 | controller | skipping: Conditional result was False 2025-12-31 00:18:08.627878 | 2025-12-31 00:18:08.627993 | PLAY RECAP 2025-12-31 00:18:08.628049 | controller | ok: 4 changed: 0 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2025-12-31 00:18:08.628078 | 2025-12-31 00:18:08.735263 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2025-12-31 00:18:08.736127 | PRE-RUN START: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master] 2025-12-31 00:18:09.356377 | 2025-12-31 00:18:09.356500 | PLAY [controller] 2025-12-31 00:18:09.378431 | 2025-12-31 00:18:09.378530 | TASK [Create the /root directory] 2025-12-31 00:18:09.795251 | controller | ok 2025-12-31 00:18:09.809438 | 2025-12-31 00:18:09.809689 | TASK [Install glibc-langpack-en] 2025-12-31 00:18:13.751668 | controller | ok: Nothing to do 2025-12-31 00:18:13.760101 | 2025-12-31 00:18:13.760204 | TASK [Ensure controller directory exists] 2025-12-31 00:18:13.974529 | controller | changed 2025-12-31 00:18:13.980609 | 2025-12-31 00:18:13.980684 | TASK [Install container runtime] 2025-12-31 00:18:14.044247 | controller | ok 2025-12-31 00:18:14.096675 | 2025-12-31 00:18:14.096816 | LOOP [ensure-docker : Gather variables for each operating system] 2025-12-31 00:18:14.145353 | controller | ok: "/var/lib/zuul/builds/9360cf52aa984e1ea60a79e9e83e52b8/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/vars/fedora.yaml" 2025-12-31 00:18:14.164266 | 2025-12-31 00:18:14.164400 | TASK [ensure-docker : Sanity check for distro selection] 2025-12-31 00:18:14.189970 | controller | skipping: Conditional result was False 2025-12-31 00:18:14.200043 | 2025-12-31 00:18:14.200176 | TASK [ensure-docker : Sanity check for distro install] 2025-12-31 00:18:14.225173 | controller | skipping: Conditional result was False 2025-12-31 00:18:14.241357 | 2025-12-31 00:18:14.241599 | TASK [ensure-docker : Sanity check for upstream install] 2025-12-31 00:18:14.268824 | controller | skipping: Conditional result was False 2025-12-31 00:18:14.282463 | 2025-12-31 00:18:14.282647 | TASK [ensure-docker : Sanity check for upstream install mirrors] 2025-12-31 00:18:14.309941 | controller | skipping: Conditional result was False 2025-12-31 00:18:14.326984 | 2025-12-31 00:18:14.327200 | TASK [ensure-docker : Include tasks for docker install] 2025-12-31 00:18:14.353260 | controller | ok 2025-12-31 00:18:14.366025 | controller | included: /var/lib/zuul/builds/9360cf52aa984e1ea60a79e9e83e52b8/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/tasks/docker-upstream.yaml 2025-12-31 00:18:14.378991 | 2025-12-31 00:18:14.379132 | TASK [ensure-docker : Not Implemented] 2025-12-31 00:18:14.405107 | controller | skipping: Conditional result was False 2025-12-31 00:18:14.414434 | 2025-12-31 00:18:14.414519 | TASK [Set up docker mirrors] 2025-12-31 00:18:14.436008 | controller | ok 2025-12-31 00:18:14.463434 | 2025-12-31 00:18:14.463762 | TASK [use-docker-mirror : Set docker_mirror fact from mirror_info] 2025-12-31 00:18:14.488517 | controller | skipping: Conditional result was False 2025-12-31 00:18:14.495822 | 2025-12-31 00:18:14.495949 | TASK [use-docker-mirror : Set docker_mirror fact from zuul_site_mirror_info] 2025-12-31 00:18:14.520534 | controller | skipping: Conditional result was False 2025-12-31 00:18:14.528151 | 2025-12-31 00:18:14.528281 | TASK [use-docker-mirror : Set docker_mirror fact] 2025-12-31 00:18:14.562761 | controller | skipping: Conditional result was False 2025-12-31 00:18:14.571960 | 2025-12-31 00:18:14.572092 | TASK [use-docker-mirror : Set up docker mirrors] 2025-12-31 00:18:14.596923 | controller | skipping: Conditional result was False 2025-12-31 00:18:14.610866 | 2025-12-31 00:18:14.610992 | TASK [Add all repositories] 2025-12-31 00:18:14.630875 | controller | ok 2025-12-31 00:18:14.655221 | 2025-12-31 00:18:14.655339 | LOOP [ensure-package-repositories : Install repositories] 2025-12-31 00:18:14.679935 | controller | ok: "/var/lib/zuul/builds/9360cf52aa984e1ea60a79e9e83e52b8/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-package-repositories/tasks/RedHat.yaml" 2025-12-31 00:18:14.680370 | controller | ok: All items complete 2025-12-31 00:18:14.680410 | 2025-12-31 00:18:14.731354 | controller | included: /var/lib/zuul/builds/9360cf52aa984e1ea60a79e9e83e52b8/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-package-repositories/tasks/RedHat.yaml 2025-12-31 00:18:14.739084 | 2025-12-31 00:18:14.739191 | TASK [ensure-package-repositories : Create GPG key temp directory] 2025-12-31 00:18:15.048833 | controller | changed 2025-12-31 00:18:15.059359 | 2025-12-31 00:18:15.059528 | LOOP [ensure-package-repositories : Upload GPG keys] 2025-12-31 00:18:15.957975 | controller | changed: 2025-12-31 00:18:15.958299 | controller | { 2025-12-31 00:18:15.958358 | 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-12-31 00:18:15.958410 | controller | } 2025-12-31 00:18:15.985221 | 2025-12-31 00:18:15.985424 | LOOP [ensure-package-repositories : Import GPG keys] 2025-12-31 00:18:16.621788 | controller | changed: 2025-12-31 00:18:16.622025 | controller | { 2025-12-31 00:18:16.622074 | 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-12-31 00:18:16.622111 | controller | } 2025-12-31 00:18:16.622152 | controller | changed: All items complete 2025-12-31 00:18:16.622226 | 2025-12-31 00:18:16.643702 | 2025-12-31 00:18:16.644227 | TASK [ensure-package-repositories : Remove GPG temp directory] 2025-12-31 00:18:16.874856 | controller | changed 2025-12-31 00:18:16.892892 | 2025-12-31 00:18:16.893073 | LOOP [ensure-package-repositories : Add repositories] 2025-12-31 00:18:17.253207 | controller | changed: 2025-12-31 00:18:17.253470 | controller | { 2025-12-31 00:18:17.253507 | controller | "baseurl": "https://download.docker.com/linux/fedora/$releasever/$basearch/stable", 2025-12-31 00:18:17.253535 | controller | "description": "Docker CE Stable - $basearch", 2025-12-31 00:18:17.253560 | controller | "gpgcheck": true, 2025-12-31 00:18:17.253585 | controller | "name": "docker-ce-stable" 2025-12-31 00:18:17.253610 | controller | } 2025-12-31 00:18:17.274717 | 2025-12-31 00:18:17.274861 | TASK [ensure-docker : Install pre-reqs] 2025-12-31 00:18:20.033098 | controller | ok: Nothing to do 2025-12-31 00:18:20.044796 | 2025-12-31 00:18:20.044982 | TASK [ensure-docker : Remove packages] 2025-12-31 00:18:21.341839 | controller | ok: Nothing to do 2025-12-31 00:18:21.359177 | 2025-12-31 00:18:21.359409 | TASK [ensure-docker : Install upstream docker using package] 2025-12-31 00:19:07.126379 | controller | changed 2025-12-31 00:19:07.133159 | 2025-12-31 00:19:07.133270 | TASK [ensure-docker : Setup docker] 2025-12-31 00:19:07.153177 | controller | ok 2025-12-31 00:19:07.164388 | controller | included: /var/lib/zuul/builds/9360cf52aa984e1ea60a79e9e83e52b8/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/tasks/docker-setup.yaml 2025-12-31 00:19:07.177690 | 2025-12-31 00:19:07.177820 | TASK [ensure-docker : Ensure "docker" group exists] 2025-12-31 00:19:07.582098 | controller | ok 2025-12-31 00:19:07.595436 | 2025-12-31 00:19:07.595609 | TASK [ensure-docker : Add user to docker group] 2025-12-31 00:19:08.118907 | controller | changed 2025-12-31 00:19:08.138207 | 2025-12-31 00:19:08.138398 | TASK [Update docker daemon proxy configuration] 2025-12-31 00:19:08.186783 | controller | skipping: Conditional result was False 2025-12-31 00:19:08.196716 | 2025-12-31 00:19:08.196873 | TASK [Lower default MTU] 2025-12-31 00:19:08.222239 | controller | skipping: Conditional result was False 2025-12-31 00:19:08.242660 | 2025-12-31 00:19:08.242870 | TASK [ensure-docker : Get the status of the docket.socket unit] 2025-12-31 00:19:08.671657 | controller | inactive 2025-12-31 00:19:08.786991 | controller | ok: Runtime: 0:00:00.011817 2025-12-31 00:19:08.793319 | 2025-12-31 00:19:08.793431 | TASK [ensure-docker : Get the status of the docket unit] 2025-12-31 00:19:09.016166 | controller | inactive 2025-12-31 00:19:09.325069 | controller | ok: Runtime: 0:00:00.010046 2025-12-31 00:19:09.340241 | 2025-12-31 00:19:09.340401 | TASK [ensure-docker : Restart docker] 2025-12-31 00:19:09.386100 | Trigger a Docker restart 2025-12-31 00:19:09.395094 | 2025-12-31 00:19:09.395219 | TASK [ensure-docker : Reset ssh connection to pick up docker group] 2025-12-31 00:19:09.435586 | 2025-12-31 00:19:09.435771 | TASK [ensure-docker : Install docker-compose] 2025-12-31 00:19:09.451642 | controller | skipping: Conditional result was False 2025-12-31 00:19:09.462289 | 2025-12-31 00:19:09.462435 | TASK [ensure-docker : Flush handlers before role exit] 2025-12-31 00:19:09.479417 | 2025-12-31 00:19:09.479567 | TASK [ensure-docker : Stop docker.socket to avoid any conflict] 2025-12-31 00:19:10.668877 | controller | changed 2025-12-31 00:19:10.681230 | 2025-12-31 00:19:10.681376 | TASK [ensure-docker : Assure docker service is running] 2025-12-31 00:19:12.710378 | controller | changed 2025-12-31 00:19:12.722124 | 2025-12-31 00:19:12.722239 | TASK [ensure-docker : Assure docker.socket service is running] 2025-12-31 00:19:13.725451 | controller | ok 2025-12-31 00:19:13.733753 | 2025-12-31 00:19:13.733859 | TASK [ensure-docker : Correct group ownership on docker sock] 2025-12-31 00:19:14.339041 | controller | ok 2025-12-31 00:19:14.348882 | 2025-12-31 00:19:14.349046 | TASK [ensure-docker : Validate ability to talk with docker] 2025-12-31 00:19:14.948776 | controller | CONTAINER ID IMAGE COMMAND CREATED STATUS PORTS NAMES 2025-12-31 00:19:15.393871 | controller | ok: Runtime: 0:00:00.042335 2025-12-31 00:19:15.400444 | 2025-12-31 00:19:15.400529 | TASK [ensure-docker : Get version details] 2025-12-31 00:19:15.835930 | controller | Client: Docker Engine - Community 2025-12-31 00:19:15.837161 | controller | Version: 24.0.7 2025-12-31 00:19:15.837171 | controller | API version: 1.43 2025-12-31 00:19:15.837176 | controller | Go version: go1.20.10 2025-12-31 00:19:15.837187 | controller | Git commit: afdd53b 2025-12-31 00:19:15.837192 | controller | Built: Thu Oct 26 09:09:39 2023 2025-12-31 00:19:15.837197 | controller | OS/Arch: linux/amd64 2025-12-31 00:19:15.837203 | controller | Context: default 2025-12-31 00:19:15.837209 | controller | 2025-12-31 00:19:15.837214 | controller | Server: Docker Engine - Community 2025-12-31 00:19:15.837218 | controller | Engine: 2025-12-31 00:19:15.837222 | controller | Version: 24.0.7 2025-12-31 00:19:15.837227 | controller | API version: 1.43 (minimum version 1.12) 2025-12-31 00:19:15.837231 | controller | Go version: go1.20.10 2025-12-31 00:19:15.837235 | controller | Git commit: 311b9ff 2025-12-31 00:19:15.837239 | controller | Built: Thu Oct 26 09:08:11 2023 2025-12-31 00:19:15.837244 | controller | OS/Arch: linux/amd64 2025-12-31 00:19:15.837248 | controller | Experimental: false 2025-12-31 00:19:15.837252 | controller | containerd: 2025-12-31 00:19:15.837256 | controller | Version: 1.6.25 2025-12-31 00:19:15.837261 | controller | GitCommit: d8f198a4ed8892c764191ef7b3b06d8a2eeb5c7f 2025-12-31 00:19:15.837265 | controller | runc: 2025-12-31 00:19:15.837269 | controller | Version: 1.1.10 2025-12-31 00:19:15.837273 | controller | GitCommit: v1.1.10-0-g18a0cb0 2025-12-31 00:19:15.837280 | controller | docker-init: 2025-12-31 00:19:15.837284 | controller | Version: 0.19.0 2025-12-31 00:19:15.837288 | controller | GitCommit: de40ad0 2025-12-31 00:19:15.951064 | controller | ok: Runtime: 0:00:00.045192 2025-12-31 00:19:15.964366 | 2025-12-31 00:19:15.964529 | TASK [ensure-docker : Dump installed docker details] 2025-12-31 00:19:16.004824 | {'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-12-31 00:19:15.792465', 'end': '2025-12-31 00:19:15.837657', 'delta': '0:00:00.045192', 'msg': '', 'zuul_log_id': 'fa163ef9-e89a-85a5-2cde-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-12-31 00:19:16.019043 | 2025-12-31 00:19:16.019198 | TASK [Use buildset registry] 2025-12-31 00:19:16.035506 | controller | skipping: Conditional result was False 2025-12-31 00:19:16.052089 | 2025-12-31 00:19:16.052198 | TASK [Ensure python3.8 is present] 2025-12-31 00:19:16.067401 | controller | skipping: Conditional result was False 2025-12-31 00:19:16.078346 | 2025-12-31 00:19:16.078476 | TASK [Run our-ensure-python role (Fedora only for now)] 2025-12-31 00:19:16.102235 | controller | ok 2025-12-31 00:19:16.131029 | 2025-12-31 00:19:16.131114 | TASK [our-ensure-python : Install the right Python version (rpm)] 2025-12-31 00:19:18.216184 | controller | ok: Nothing to do 2025-12-31 00:19:18.223049 | 2025-12-31 00:19:18.223198 | TASK [our-ensure-python : Also install python3-devel] 2025-12-31 00:19:29.303338 | controller | changed 2025-12-31 00:19:29.331675 | 2025-12-31 00:19:29.331894 | TASK [Run ensure-virtualenv role] 2025-12-31 00:19:29.355999 | controller | ok 2025-12-31 00:19:29.387319 | 2025-12-31 00:19:29.387462 | TASK [ensure-virtualenv : Check if virtualenv is installed] 2025-12-31 00:19:29.616456 | controller | /usr/bin/virtualenv 2025-12-31 00:19:29.925904 | controller | ok: Runtime: 0:00:00.004844 2025-12-31 00:19:29.939800 | 2025-12-31 00:19:29.939975 | LOOP [ensure-virtualenv : Install virtualenv package] 2025-12-31 00:19:29.976954 | controller | skipping: Conditional result was False 2025-12-31 00:19:29.977432 | controller | changed: All items complete 2025-12-31 00:19:29.977506 | 2025-12-31 00:19:30.005644 | 2025-12-31 00:19:30.005890 | TASK [Find the full path of the Python interpreter] 2025-12-31 00:19:30.253701 | controller | /usr/bin/python3.9 2025-12-31 00:19:30.560104 | controller | ok 2025-12-31 00:19:30.573608 | 2025-12-31 00:19:30.573853 | TASK [Create virtualenv for ansible-test with a specific interpreter] 2025-12-31 00:19:32.236003 | controller | created virtual environment CPython3.9.18.final.0-64 in 815ms 2025-12-31 00:19:32.267961 | controller | creator CPython3Posix(dest=/home/zuul/venv, clear=False, no_vcs_ignore=False, global=False) 2025-12-31 00:19:32.267995 | 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-12-31 00:19:32.268005 | controller | added seed packages: pip==22.2.2, setuptools==62.6.0, wheel==0.37.1 2025-12-31 00:19:32.268018 | controller | activators BashActivator,CShellActivator,FishActivator,NushellActivator,PowerShellActivator,PythonActivator 2025-12-31 00:19:32.634942 | controller | changed 2025-12-31 00:19:32.651602 | 2025-12-31 00:19:32.651852 | TASK [Set selinux package] 2025-12-31 00:19:32.687840 | controller | ok 2025-12-31 00:19:32.699923 | 2025-12-31 00:19:32.700051 | TASK [Set selinux package (Fedora)] 2025-12-31 00:19:32.735355 | controller | ok 2025-12-31 00:19:32.746356 | 2025-12-31 00:19:32.747070 | TASK [Install selinux into virtualenv] 2025-12-31 00:20:00.002235 | controller | Collecting selinux-please-lie-to-me 2025-12-31 00:20:36.136460 | controller | Downloading selinux_please_lie_to_me-1.0.1-py2.py3-none-any.whl (3.8 kB) 2025-12-31 00:20:36.514068 | controller | Collecting setuptools<50.0.0 2025-12-31 00:20:36.520339 | controller | Downloading setuptools-49.6.0-py3-none-any.whl (803 kB) 2025-12-31 00:20:36.558259 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 803.3/803.3 kB 23.1 MB/s eta 0:00:00 2025-12-31 00:20:36.643168 | controller | Installing collected packages: setuptools, selinux-please-lie-to-me 2025-12-31 00:20:36.643399 | controller | Attempting uninstall: setuptools 2025-12-31 00:20:36.644114 | controller | Found existing installation: setuptools 62.6.0 2025-12-31 00:20:36.720041 | controller | Uninstalling setuptools-62.6.0: 2025-12-31 00:20:36.728836 | controller | Successfully uninstalled setuptools-62.6.0 2025-12-31 00:20:37.077241 | controller | Successfully installed selinux-please-lie-to-me-1.0.1 setuptools-49.6.0 2025-12-31 00:20:50.447704 | controller | 2025-12-31 00:20:50.530222 | controller | [notice] A new release of pip available: 22.2.2 -> 25.3 2025-12-31 00:20:50.530278 | controller | [notice] To update, run: /home/zuul/venv/bin/python -m pip install --upgrade pip 2025-12-31 00:20:50.839536 | controller | ok: Runtime: 0:01:17.272664 2025-12-31 00:20:50.853813 | 2025-12-31 00:20:50.853972 | TASK [Install pytest-forked into virtualenv] 2025-12-31 00:21:16.743048 | controller | Collecting pytest-forked 2025-12-31 00:21:28.997598 | controller | Downloading pytest_forked-1.6.0-py3-none-any.whl (4.9 kB) 2025-12-31 00:21:29.130142 | controller | Collecting pytest>=3.10 2025-12-31 00:21:29.134736 | controller | Downloading pytest-8.4.2-py3-none-any.whl (365 kB) 2025-12-31 00:21:29.164646 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 365.8/365.8 kB 13.5 MB/s eta 0:00:00 2025-12-31 00:21:29.202924 | controller | Collecting py 2025-12-31 00:21:29.214177 | controller | Downloading py-1.11.0-py2.py3-none-any.whl (98 kB) 2025-12-31 00:21:29.223590 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 98.7/98.7 kB 14.2 MB/s eta 0:00:00 2025-12-31 00:21:29.270022 | controller | Collecting exceptiongroup>=1 2025-12-31 00:21:29.274503 | controller | Downloading exceptiongroup-1.3.1-py3-none-any.whl (16 kB) 2025-12-31 00:21:29.316375 | controller | Collecting tomli>=1 2025-12-31 00:21:29.319885 | controller | Downloading tomli-2.3.0-py3-none-any.whl (14 kB) 2025-12-31 00:21:29.365591 | controller | Collecting pygments>=2.7.2 2025-12-31 00:21:29.369723 | controller | Downloading pygments-2.19.2-py3-none-any.whl (1.2 MB) 2025-12-31 00:21:29.400224 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 1.2/1.2 MB 43.2 MB/s eta 0:00:00 2025-12-31 00:21:29.421977 | controller | Collecting iniconfig>=1 2025-12-31 00:21:29.426875 | controller | Downloading iniconfig-2.1.0-py3-none-any.whl (6.0 kB) 2025-12-31 00:21:29.468297 | controller | Collecting packaging>=20 2025-12-31 00:21:29.472710 | controller | Downloading packaging-25.0-py3-none-any.whl (66 kB) 2025-12-31 00:21:29.481349 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 66.5/66.5 kB 8.5 MB/s eta 0:00:00 2025-12-31 00:21:29.510593 | controller | Collecting pluggy<2,>=1.5 2025-12-31 00:21:29.514817 | controller | Downloading pluggy-1.6.0-py3-none-any.whl (20 kB) 2025-12-31 00:21:29.560224 | controller | Collecting typing-extensions>=4.6.0 2025-12-31 00:21:29.563769 | controller | Downloading typing_extensions-4.15.0-py3-none-any.whl (44 kB) 2025-12-31 00:21:29.571029 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 44.6/44.6 kB 6.9 MB/s eta 0:00:00 2025-12-31 00:21:29.639971 | controller | Installing collected packages: typing-extensions, tomli, pygments, py, pluggy, packaging, iniconfig, exceptiongroup, pytest, pytest-forked 2025-12-31 00:21:30.671386 | controller | Successfully installed exceptiongroup-1.3.1 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-12-31 00:21:30.675647 | controller | 2025-12-31 00:21:30.745023 | controller | [notice] A new release of pip available: 22.2.2 -> 25.3 2025-12-31 00:21:30.745070 | controller | [notice] To update, run: /home/zuul/venv/bin/python -m pip install --upgrade pip 2025-12-31 00:21:30.961852 | controller | ok: Runtime: 0:00:39.636002 2025-12-31 00:21:30.972159 | 2025-12-31 00:21:30.972293 | TASK [Update pip] 2025-12-31 00:21:31.494619 | controller | Requirement already satisfied: pip in ./venv/lib/python3.9/site-packages (22.2.2) 2025-12-31 00:21:41.381544 | controller | Collecting pip 2025-12-31 00:21:53.571395 | controller | Downloading pip-25.3-py3-none-any.whl (1.8 MB) 2025-12-31 00:21:53.641827 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 1.8/1.8 MB 26.9 MB/s eta 0:00:00 2025-12-31 00:21:53.702071 | controller | Installing collected packages: pip 2025-12-31 00:21:53.702222 | controller | Attempting uninstall: pip 2025-12-31 00:21:53.702837 | controller | Found existing installation: pip 22.2.2 2025-12-31 00:21:53.870773 | controller | Uninstalling pip-22.2.2: 2025-12-31 00:21:53.888018 | controller | Successfully uninstalled pip-22.2.2 2025-12-31 00:21:54.652302 | controller | Successfully installed pip-25.3 2025-12-31 00:21:55.037242 | controller | ok: Runtime: 0:00:23.538255 2025-12-31 00:21:55.046128 | 2025-12-31 00:21:55.046241 | TASK [Ensure pip temp directory exists in /var/tmp] 2025-12-31 00:21:55.252599 | controller | changed 2025-12-31 00:21:55.259636 | 2025-12-31 00:21:55.259797 | TASK [Install ansible into virtualenv] 2025-12-31 00:21:55.740969 | controller | Processing ./src/github.com/ansible/ansible 2025-12-31 00:21:55.745056 | controller | Installing build dependencies: started 2025-12-31 00:22:42.405804 | controller | Installing build dependencies: finished with status 'done' 2025-12-31 00:22:42.406890 | controller | Getting requirements to build wheel: started 2025-12-31 00:22:42.995185 | controller | Getting requirements to build wheel: finished with status 'done' 2025-12-31 00:22:42.997516 | controller | Preparing metadata (pyproject.toml): started 2025-12-31 00:22:43.451943 | controller | Preparing metadata (pyproject.toml): finished with status 'done' 2025-12-31 00:22:55.821796 | controller | Collecting jinja2 (from ansible-core==2.11.12.post0) 2025-12-31 00:23:08.068557 | controller | Downloading jinja2-3.1.6-py3-none-any.whl.metadata (2.9 kB) 2025-12-31 00:23:08.154933 | controller | Collecting PyYAML (from ansible-core==2.11.12.post0) 2025-12-31 00:23:08.162044 | 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-12-31 00:23:08.463130 | controller | Collecting cryptography (from ansible-core==2.11.12.post0) 2025-12-31 00:23:08.468881 | controller | Downloading cryptography-46.0.3-cp38-abi3-manylinux_2_34_x86_64.whl.metadata (5.7 kB) 2025-12-31 00:23:08.493583 | controller | Requirement already satisfied: packaging in ./venv/lib/python3.9/site-packages (from ansible-core==2.11.12.post0) (25.0) 2025-12-31 00:23:08.520153 | controller | Collecting resolvelib<0.6.0,>=0.5.3 (from ansible-core==2.11.12.post0) 2025-12-31 00:23:08.525900 | controller | Downloading resolvelib-0.5.4-py2.py3-none-any.whl.metadata (3.7 kB) 2025-12-31 00:23:08.708126 | controller | Collecting cffi>=2.0.0 (from cryptography->ansible-core==2.11.12.post0) 2025-12-31 00:23:08.715548 | controller | Downloading cffi-2.0.0-cp39-cp39-manylinux2014_x86_64.manylinux_2_17_x86_64.whl.metadata (2.6 kB) 2025-12-31 00:23:08.725344 | 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-12-31 00:23:08.748888 | controller | Collecting pycparser (from cffi>=2.0.0->cryptography->ansible-core==2.11.12.post0) 2025-12-31 00:23:08.753304 | controller | Downloading pycparser-2.23-py3-none-any.whl.metadata (993 bytes) 2025-12-31 00:23:08.851171 | controller | Collecting MarkupSafe>=2.0 (from jinja2->ansible-core==2.11.12.post0) 2025-12-31 00:23:08.856590 | 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-12-31 00:23:08.880646 | controller | Downloading resolvelib-0.5.4-py2.py3-none-any.whl (12 kB) 2025-12-31 00:23:08.895944 | controller | Downloading cryptography-46.0.3-cp38-abi3-manylinux_2_34_x86_64.whl (4.5 MB) 2025-12-31 00:23:08.977514 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 4.5/4.5 MB 59.9 MB/s 0:00:00 2025-12-31 00:23:08.981816 | controller | Downloading cffi-2.0.0-cp39-cp39-manylinux2014_x86_64.manylinux_2_17_x86_64.whl (216 kB) 2025-12-31 00:23:08.998046 | controller | Downloading jinja2-3.1.6-py3-none-any.whl (134 kB) 2025-12-31 00:23:09.012865 | 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-12-31 00:23:09.025153 | controller | Downloading pycparser-2.23-py3-none-any.whl (118 kB) 2025-12-31 00:23:09.038928 | 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-12-31 00:23:09.057330 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 750.8/750.8 kB 31.5 MB/s 0:00:00 2025-12-31 00:23:09.068587 | controller | Building wheels for collected packages: ansible-core 2025-12-31 00:23:09.069424 | controller | Building wheel for ansible-core (pyproject.toml): started 2025-12-31 00:23:10.564620 | controller | Building wheel for ansible-core (pyproject.toml): finished with status 'done' 2025-12-31 00:23:10.567856 | controller | Created wheel for ansible-core: filename=ansible_core-2.11.12.post0-py3-none-any.whl size=1961250 sha256=f9a5bbb1e9bd2f36b0d75591d8a6e7a5e426889c9fcb49ba74d01ae6c6569200 2025-12-31 00:23:10.568030 | controller | Stored in directory: /var/tmp/ansible-test-pip/pip-ephem-wheel-cache-jxdvxv2q/wheels/0f/a0/93/ab85b739e1eb1e0467171316f13571c14efdf87e0d5d44a33e 2025-12-31 00:23:10.577653 | controller | Successfully built ansible-core 2025-12-31 00:23:10.615066 | controller | Installing collected packages: resolvelib, PyYAML, pycparser, MarkupSafe, jinja2, cffi, cryptography, ansible-core 2025-12-31 00:23:12.105229 | controller | 2025-12-31 00:23:12.111853 | 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-12-31 00:23:12.339049 | controller | ok: Runtime: 0:01:16.809283 2025-12-31 00:23:12.346260 | 2025-12-31 00:23:12.346334 | TASK [Setup download-artifact-fork role] 2025-12-31 00:23:12.369126 | controller | ok 2025-12-31 00:23:12.389315 | 2025-12-31 00:23:12.389442 | TASK [download-artifact-fork : Parse inventory response] 2025-12-31 00:23:12.431903 | controller | ok 2025-12-31 00:23:12.447621 | 2025-12-31 00:23:12.447743 | TASK [download-artifact-fork : Fetch info from Zuul API] 2025-12-31 00:23:12.473522 | controller | skipping: Conditional result was False 2025-12-31 00:23:12.481490 | 2025-12-31 00:23:12.481603 | TASK [download-artifact-fork : Parse build response] 2025-12-31 00:23:12.506372 | controller | skipping: Conditional result was False 2025-12-31 00:23:12.516348 | 2025-12-31 00:23:12.516471 | TASK [download-artifact-fork : Ensure artifacts directory exists] 2025-12-31 00:23:12.741112 | controller | changed 2025-12-31 00:23:12.750321 | 2025-12-31 00:23:12.750472 | LOOP [download-artifact-fork : Download archive by type] 2025-12-31 00:23:12.790188 | controller | skipping: Conditional result was False 2025-12-31 00:23:12.790620 | 2025-12-31 00:23:13.576287 | controller | changed: OK (20187 bytes) 2025-12-31 00:23:13.599846 | 2025-12-31 00:23:13.599976 | TASK [deploy-artifacts : define __collections] 2025-12-31 00:23:13.620612 | controller | ok 2025-12-31 00:23:13.626886 | 2025-12-31 00:23:13.626992 | LOOP [deploy-artifacts : Create list of collections] 2025-12-31 00:23:13.659067 | controller | skipping: Conditional result was False 2025-12-31 00:23:13.668067 | controller | ok: 2025-12-31 00:23:13.668211 | controller | { 2025-12-31 00:23:13.668240 | controller | "branch": "main", 2025-12-31 00:23:13.668258 | controller | "job": "build-ansible-collection", 2025-12-31 00:23:13.668275 | controller | "metadata": { 2025-12-31 00:23:13.668292 | controller | "type": "ansible_collection", 2025-12-31 00:23:13.668308 | controller | "version": "5.0.0" 2025-12-31 00:23:13.668323 | controller | }, 2025-12-31 00:23:13.668339 | controller | "name": "ansible.network", 2025-12-31 00:23:13.668354 | controller | "project": "ansible-collections/ansible.network", 2025-12-31 00:23:13.668370 | controller | "url": "https://4f6f7117bc53e25c601b-aefa49644f68d3938d78db9abd235c58.ssl.cf2.rackcdn.com/ansible/9a52229f5d77455b8428b536a193c64c/artifacts/ansible-network-5.0.0.tar.gz" 2025-12-31 00:23:13.668393 | controller | } 2025-12-31 00:23:13.686111 | 2025-12-31 00:23:13.686192 | TASK [deploy-artifacts : Install require-project collection using ansible-galaxy] 2025-12-31 00:23:14.271116 | controller | Starting galaxy collection install process 2025-12-31 00:23:14.271323 | controller | Process install dependency map 2025-12-31 00:24:25.780768 | controller | [WARNING]: Skipping Galaxy server https://galaxy.ansible.com/api/. Got an 2025-12-31 00:24:25.781126 | controller | unexpected error when getting available versions of collection 2025-12-31 00:24:25.781175 | controller | ansible.netcommon: '/api/v3/plugin/ansible/content/published/collections/index/ 2025-12-31 00:24:25.781189 | controller | ansible/netcommon/versions/' 2025-12-31 00:24:25.781207 | controller | ERROR! Unexpected Exception, this is probably a bug: '/api/v3/plugin/ansible/content/published/collections/index/ansible/netcommon/versions/' 2025-12-31 00:24:25.820044 | controller | to see the full traceback, use -vvv 2025-12-31 00:24:26.270518 | controller | ERROR 2025-12-31 00:24:26.270685 | controller | { 2025-12-31 00:24:26.270749 | controller | "delta": "0:01:11.933690", 2025-12-31 00:24:26.270786 | controller | "end": "2025-12-31 00:24:25.820541", 2025-12-31 00:24:26.270813 | controller | "msg": "non-zero return code", 2025-12-31 00:24:26.270837 | controller | "rc": 250, 2025-12-31 00:24:26.270861 | controller | "start": "2025-12-31 00:23:13.886851" 2025-12-31 00:24:26.270887 | controller | } failure 2025-12-31 00:24:26.273373 | 2025-12-31 00:24:26.273437 | PLAY RECAP 2025-12-31 00:24:26.273492 | controller | ok: 44 changed: 24 unreachable: 0 failed: 1 skipped: 17 rescued: 0 ignored: 0 2025-12-31 00:24:26.273523 | 2025-12-31 00:24:26.424911 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master] 2025-12-31 00:24:26.425918 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2025-12-31 00:24:27.020876 | 2025-12-31 00:24:27.021009 | PLAY [all] 2025-12-31 00:24:27.044964 | 2025-12-31 00:24:27.045098 | TASK [fetch-subunit-output : Find stestr or testr executable] 2025-12-31 00:24:27.454045 | controller | changed: non-zero return code 2025-12-31 00:24:27.460191 | 2025-12-31 00:24:27.460318 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2025-12-31 00:24:27.476240 | controller | skipping: Conditional result was False 2025-12-31 00:24:27.484335 | 2025-12-31 00:24:27.484463 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2025-12-31 00:24:27.517956 | 2025-12-31 00:24:27.518145 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2025-12-31 00:24:27.550990 | 2025-12-31 00:24:27.551194 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2025-12-31 00:24:27.566215 | controller | skipping: Conditional result was False 2025-12-31 00:24:27.576232 | 2025-12-31 00:24:27.576356 | LOOP [fetch-subunit-output : Generate subunit file] 2025-12-31 00:24:27.597830 | 2025-12-31 00:24:27.597993 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2025-12-31 00:24:27.611547 | controller | skipping: Conditional result was False 2025-12-31 00:24:27.618116 | 2025-12-31 00:24:27.618216 | TASK [fetch-subunit-output : Remove the temporary file] 2025-12-31 00:24:27.631823 | controller | skipping: Conditional result was False 2025-12-31 00:24:27.638771 | 2025-12-31 00:24:27.638879 | TASK [fetch-subunit-output : Process and fetch subunit results] 2025-12-31 00:24:27.662322 | controller | skipping: Conditional result was False 2025-12-31 00:24:27.689365 | 2025-12-31 00:24:27.689456 | PLAY RECAP 2025-12-31 00:24:27.689499 | controller | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2025-12-31 00:24:27.689520 | 2025-12-31 00:24:27.786154 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2025-12-31 00:24:27.787153 | POST-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/post.yaml@master] 2025-12-31 00:24:28.437678 | 2025-12-31 00:24:28.437821 | PLAY [all:!appliance*] 2025-12-31 00:24:28.462017 | 2025-12-31 00:24:28.462145 | TASK [unregister the node] 2025-12-31 00:24:28.995218 | controller | skipping: Conditional result was False 2025-12-31 00:24:29.001630 | 2025-12-31 00:24:29.001750 | TASK [include_role : fetch-output] 2025-12-31 00:24:29.051490 | controller | ok 2025-12-31 00:24:29.073095 | 2025-12-31 00:24:29.073220 | TASK [fetch-output : Set log path for multiple nodes] 2025-12-31 00:24:29.128300 | controller | skipping: Conditional result was False 2025-12-31 00:24:29.140267 | 2025-12-31 00:24:29.140428 | TASK [fetch-output : Set log path for single node] 2025-12-31 00:24:29.185863 | controller | ok 2025-12-31 00:24:29.193977 | 2025-12-31 00:24:29.194284 | LOOP [fetch-output : Ensure local output dirs] 2025-12-31 00:24:29.607405 | controller -> localhost | ok: "/var/lib/zuul/builds/9360cf52aa984e1ea60a79e9e83e52b8/work/logs" 2025-12-31 00:24:29.815993 | controller -> localhost | changed: "/var/lib/zuul/builds/9360cf52aa984e1ea60a79e9e83e52b8/work/artifacts" 2025-12-31 00:24:30.048250 | controller -> localhost | changed: "/var/lib/zuul/builds/9360cf52aa984e1ea60a79e9e83e52b8/work/docs" 2025-12-31 00:24:30.070520 | 2025-12-31 00:24:30.070701 | LOOP [fetch-output : Collect logs, artifacts and docs] 2025-12-31 00:24:30.795535 | controller | changed: 2025-12-31 00:24:30.795743 | controller | .d..t...... ./ 2025-12-31 00:24:30.795774 | controller | cd+++++++++ controller/ 2025-12-31 00:24:30.795807 | controller | changed: All items complete 2025-12-31 00:24:30.795827 | 2025-12-31 00:24:31.331397 | controller | changed: .d..t...... ./ 2025-12-31 00:24:31.936426 | controller | changed: .d..t...... ./ 2025-12-31 00:24:31.955576 | 2025-12-31 00:24:31.955774 | TASK [include_role : fetch-output-openshift] 2025-12-31 00:24:31.971890 | controller | skipping: Conditional result was False 2025-12-31 00:24:31.979193 | 2025-12-31 00:24:31.979319 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2025-12-31 00:24:32.008572 | controller | skipping: Conditional result was False 2025-12-31 00:24:32.018578 | controller | skipping: Conditional result was False 2025-12-31 00:24:32.055360 | 2025-12-31 00:24:32.055468 | PLAY [localhost] 2025-12-31 00:24:32.068293 | 2025-12-31 00:24:32.068392 | TASK [Run Zuul manifest role] 2025-12-31 00:24:32.087758 | localhost | ok 2025-12-31 00:24:32.106998 | 2025-12-31 00:24:32.107138 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-12-31 00:24:32.511232 | localhost | changed 2025-12-31 00:24:32.516103 | 2025-12-31 00:24:32.516182 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-12-31 00:24:32.556193 | localhost | ok 2025-12-31 00:24:32.564246 | 2025-12-31 00:24:32.564306 | TASK [Set zuul-log-path fact] 2025-12-31 00:24:32.581607 | localhost | ok 2025-12-31 00:24:32.595932 | 2025-12-31 00:24:32.596013 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-12-31 00:24:32.633991 | localhost | ok 2025-12-31 00:24:32.642044 | 2025-12-31 00:24:32.642114 | LOOP [Run upload-logs-swift role] 2025-12-31 00:24:32.675753 | localhost | Output suppressed because no_log was given 2025-12-31 00:24:32.703938 | 2025-12-31 00:24:32.704074 | TASK [Set zuul-log-path fact] 2025-12-31 00:24:32.738077 | localhost | skipping: Conditional result was False 2025-12-31 00:24:32.743599 | 2025-12-31 00:24:32.743711 | TASK [upload-logs-swift : Ensure logs are readable before uploading] 2025-12-31 00:24:33.197522 | localhost -> localhost | ok: Runtime: 0:00:00.010153 2025-12-31 00:24:33.233631 | 2025-12-31 00:24:33.233782 | TASK [upload-logs-swift : Upload logs to swift]