2025-09-29 00:22:36.653273 | Job console starting... 2025-09-29 00:22:36.665020 | Updating repositories 2025-09-29 00:22:36.792553 | Preparing job workspace 2025-09-29 00:22:40.368436 | Running Ansible setup... 2025-09-29 00:22:46.290682 | PRE-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master] 2025-09-29 00:22:46.894008 | 2025-09-29 00:22:46.894137 | PLAY [localhost] 2025-09-29 00:22:46.902616 | 2025-09-29 00:22:46.902700 | TASK [Gathering Facts] 2025-09-29 00:22:47.896562 | localhost | ok 2025-09-29 00:22:47.927270 | 2025-09-29 00:22:47.927440 | TASK [Setup log path fact] 2025-09-29 00:22:47.948653 | localhost | ok 2025-09-29 00:22:47.966348 | 2025-09-29 00:22:47.966452 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-09-29 00:22:48.006223 | localhost | ok 2025-09-29 00:22:48.017638 | 2025-09-29 00:22:48.017774 | TASK [emit-job-header : Print job information] 2025-09-29 00:22:48.068370 | # Job Information 2025-09-29 00:22:48.068574 | Ansible Version: 2.15.12 2025-09-29 00:22:48.068619 | Job: ansible-test-sanity-docker-stable-2.11 2025-09-29 00:22:48.068649 | Pipeline: periodic 2025-09-29 00:22:48.068704 | Executor: ze02.softwarefactory-project.io 2025-09-29 00:22:48.068732 | Triggered by: https://github.com/ansible-collections/ansible.network 2025-09-29 00:22:48.068762 | Log URL (when completed): https://ansible.softwarefactory-project.io/logs/cb8/ansible/cb8c1cd3bfd04bbfa43fc1c787c0f44d/ 2025-09-29 00:22:48.068790 | Event ID: c18e3d24a25249e3bcbdaf54f0d57ff2 2025-09-29 00:22:48.074012 | 2025-09-29 00:22:48.074102 | LOOP [emit-job-header : Print node information] 2025-09-29 00:22:48.183537 | localhost | ok: 2025-09-29 00:22:48.183935 | localhost | # Node Information 2025-09-29 00:22:48.183996 | localhost | Inventory Hostname: controller 2025-09-29 00:22:48.184040 | localhost | Hostname: ip-172-16-125-62 2025-09-29 00:22:48.184081 | localhost | Username: zuul-worker 2025-09-29 00:22:48.184124 | localhost | Distro: Fedora 37 2025-09-29 00:22:48.184165 | localhost | Provider: ansible-us-east-2 2025-09-29 00:22:48.184204 | localhost | Region: us-east-2 2025-09-29 00:22:48.184242 | localhost | Label: ansible-fedora-37-1vcpu 2025-09-29 00:22:48.184280 | localhost | Product Name: t3.small 2025-09-29 00:22:48.184317 | localhost | Interface IP: 3.15.232.213 2025-09-29 00:22:48.201315 | 2025-09-29 00:22:48.201425 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-09-29 00:22:48.613835 | localhost -> localhost | changed 2025-09-29 00:22:48.626255 | 2025-09-29 00:22:48.626403 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-09-29 00:22:49.556550 | localhost -> localhost | changed 2025-09-29 00:22:49.599952 | 2025-09-29 00:22:49.600079 | PLAY [all:!appliance*] 2025-09-29 00:22:49.630503 | 2025-09-29 00:22:49.630603 | TASK [include_role : start-zuul-console] 2025-09-29 00:22:49.652958 | controller | ok 2025-09-29 00:22:49.672860 | 2025-09-29 00:22:49.672961 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-09-29 00:22:50.331346 | controller | ok 2025-09-29 00:22:50.354600 | 2025-09-29 00:22:50.355003 | TASK [use-our-mirror : Retrieve the IP address] 2025-09-29 00:22:52.275540 | controller | ok: Status code was 404 and not [200]: HTTP Error 404: Not Found 2025-09-29 00:22:52.289450 | 2025-09-29 00:22:52.289706 | TASK [use-our-mirror : Ensure the registry service is running on the mirror] 2025-09-29 00:22:52.437326 | controller | skipping: Conditional result was False 2025-09-29 00:22:52.451694 | 2025-09-29 00:22:52.451898 | TASK [use-our-mirror : Write the IP in /etc/hosts] 2025-09-29 00:22:52.480696 | controller | skipping: Conditional result was False 2025-09-29 00:22:52.495506 | 2025-09-29 00:22:52.495728 | TASK [use-our-mirror : Create the podman configuration directory] 2025-09-29 00:22:52.523440 | controller | skipping: Conditional result was False 2025-09-29 00:22:52.537346 | 2025-09-29 00:22:52.537493 | TASK [use-our-mirror : Copy the podman configuration] 2025-09-29 00:22:52.566360 | controller | skipping: Conditional result was False 2025-09-29 00:22:52.581267 | 2025-09-29 00:22:52.581418 | TASK [use-our-mirror : Copy the Yum configuration (main)] 2025-09-29 00:22:52.608836 | controller | skipping: Conditional result was False 2025-09-29 00:22:52.622618 | 2025-09-29 00:22:52.622805 | TASK [use-our-mirror : Copy the Yum configuration (update)] 2025-09-29 00:22:52.650174 | controller | skipping: Conditional result was False 2025-09-29 00:22:52.678124 | 2025-09-29 00:22:52.678309 | TASK [Disable Fedora Modular] 2025-09-29 00:22:53.588053 | controller | changed 2025-09-29 00:22:53.603297 | 2025-09-29 00:22:53.603525 | TASK [Enable EPEL] 2025-09-29 00:22:53.631374 | controller | skipping: Conditional result was False 2025-09-29 00:22:53.646611 | 2025-09-29 00:22:53.646840 | TASK [Register the RHEL node] 2025-09-29 00:22:53.817239 | 2025-09-29 00:22:53.817521 | TASK [Show the subscription-manager status] 2025-09-29 00:22:54.016078 | controller | skipping: Conditional result was False 2025-09-29 00:22:54.028919 | 2025-09-29 00:22:54.029050 | TASK [Enable EPEL on RHEL] 2025-09-29 00:22:54.193337 | controller | skipping: Conditional result was False 2025-09-29 00:22:54.207844 | 2025-09-29 00:22:54.207997 | TASK [Install git and tox] 2025-09-29 00:24:26.354326 | controller | changed 2025-09-29 00:24:26.370206 | 2025-09-29 00:24:26.370356 | TASK [include_role : prepare-workspace] 2025-09-29 00:24:26.418060 | controller | ok 2025-09-29 00:24:26.458033 | 2025-09-29 00:24:26.458142 | TASK [prepare-workspace : Start zuul_console daemon.] 2025-09-29 00:24:27.028380 | controller | ok 2025-09-29 00:24:27.034329 | 2025-09-29 00:24:27.034391 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2025-09-29 00:24:39.644094 | controller | Output suppressed because no_log was given 2025-09-29 00:24:39.658842 | 2025-09-29 00:24:39.658949 | TASK [include_role : prepare-workspace-openshift] 2025-09-29 00:24:39.690111 | controller | skipping: Conditional result was False 2025-09-29 00:24:39.718547 | 2025-09-29 00:24:39.718635 | PLAY [all:!appliance] 2025-09-29 00:24:39.738561 | 2025-09-29 00:24:39.738629 | TASK [Run add-build-sshkey role (RSA)] 2025-09-29 00:24:39.808687 | controller | ok 2025-09-29 00:24:39.822386 | 2025-09-29 00:24:39.822453 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-09-29 00:24:40.123449 | controller -> localhost | ok 2025-09-29 00:24:40.136820 | 2025-09-29 00:24:40.136964 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-09-29 00:24:40.176713 | controller | ok 2025-09-29 00:24:40.204473 | controller | included: /var/lib/zuul/builds/cb8c1cd3bfd04bbfa43fc1c787c0f44d/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-09-29 00:24:40.212208 | 2025-09-29 00:24:40.212291 | TASK [add-build-sshkey : Create Temp SSH key] 2025-09-29 00:24:40.737410 | controller -> localhost | Generating public/private rsa key pair. 2025-09-29 00:24:40.737866 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/cb8c1cd3bfd04bbfa43fc1c787c0f44d/work/cb8c1cd3bfd04bbfa43fc1c787c0f44d_id_rsa. 2025-09-29 00:24:40.737935 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/cb8c1cd3bfd04bbfa43fc1c787c0f44d/work/cb8c1cd3bfd04bbfa43fc1c787c0f44d_id_rsa.pub. 2025-09-29 00:24:40.737983 | controller -> localhost | The key fingerprint is: 2025-09-29 00:24:40.738026 | controller -> localhost | SHA256:3CMXrcNUZjjp9Yl5XmNfpBoPiUuitq/y+uHnq8WXdiQ zuul-build-sshkey 2025-09-29 00:24:40.738068 | controller -> localhost | The key's randomart image is: 2025-09-29 00:24:40.738109 | controller -> localhost | +---[RSA 2048]----+ 2025-09-29 00:24:40.738149 | controller -> localhost | | o+ | 2025-09-29 00:24:40.738190 | controller -> localhost | | +=. .| 2025-09-29 00:24:40.738229 | controller -> localhost | | .o+.= + | 2025-09-29 00:24:40.738268 | controller -> localhost | | ..++o* =oo| 2025-09-29 00:24:40.738306 | controller -> localhost | | .SoE..B..+| 2025-09-29 00:24:40.738362 | controller -> localhost | | o. o.*. o .| 2025-09-29 00:24:40.738411 | controller -> localhost | | ...o + . | 2025-09-29 00:24:40.738452 | controller -> localhost | | ...o.o . | 2025-09-29 00:24:40.738491 | controller -> localhost | | .=**+. | 2025-09-29 00:24:40.738536 | controller -> localhost | +----[SHA256]-----+ 2025-09-29 00:24:40.738661 | controller -> localhost | ok: Runtime: 0:00:00.075040 2025-09-29 00:24:40.752850 | 2025-09-29 00:24:40.752993 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-09-29 00:24:40.794109 | controller | ok 2025-09-29 00:24:40.813497 | controller | included: /var/lib/zuul/builds/cb8c1cd3bfd04bbfa43fc1c787c0f44d/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-09-29 00:24:40.825353 | 2025-09-29 00:24:40.825438 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-09-29 00:24:40.852573 | controller | skipping: Conditional result was False 2025-09-29 00:24:40.863470 | 2025-09-29 00:24:40.863563 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-09-29 00:24:41.483595 | controller | changed 2025-09-29 00:24:41.495325 | 2025-09-29 00:24:41.495460 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-09-29 00:24:41.861733 | controller | ok 2025-09-29 00:24:41.873897 | 2025-09-29 00:24:41.874074 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-09-29 00:24:43.202914 | controller | changed 2025-09-29 00:24:43.215315 | 2025-09-29 00:24:43.215464 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-09-29 00:24:44.545503 | controller | changed 2025-09-29 00:24:44.558403 | 2025-09-29 00:24:44.558551 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-09-29 00:24:44.587441 | controller | skipping: Conditional result was False 2025-09-29 00:24:44.600898 | 2025-09-29 00:24:44.601043 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-09-29 00:24:45.017517 | controller -> localhost | changed 2025-09-29 00:24:45.041950 | 2025-09-29 00:24:45.042103 | TASK [add-build-sshkey : Add back temp key] 2025-09-29 00:24:45.394456 | controller -> localhost | Identity added: /var/lib/zuul/builds/cb8c1cd3bfd04bbfa43fc1c787c0f44d/work/cb8c1cd3bfd04bbfa43fc1c787c0f44d_id_rsa (zuul-build-sshkey) 2025-09-29 00:24:45.394742 | controller -> localhost | ok: Runtime: 0:00:00.014373 2025-09-29 00:24:45.401617 | 2025-09-29 00:24:45.401697 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-09-29 00:24:45.951005 | controller | ok 2025-09-29 00:24:45.962755 | 2025-09-29 00:24:45.962896 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-09-29 00:24:46.001069 | controller | skipping: Conditional result was False 2025-09-29 00:24:46.025573 | 2025-09-29 00:24:46.025750 | TASK [Run add-build-sshkey role (ECDSA)] 2025-09-29 00:24:46.065018 | controller | ok 2025-09-29 00:24:46.095604 | 2025-09-29 00:24:46.095714 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-09-29 00:24:46.387007 | controller -> localhost | ok 2025-09-29 00:24:46.401437 | 2025-09-29 00:24:46.401589 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-09-29 00:24:46.441504 | controller | ok 2025-09-29 00:24:46.468052 | controller | included: /var/lib/zuul/builds/cb8c1cd3bfd04bbfa43fc1c787c0f44d/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-09-29 00:24:46.476123 | 2025-09-29 00:24:46.476208 | TASK [add-build-sshkey : Create Temp SSH key] 2025-09-29 00:24:46.819326 | controller -> localhost | Generating public/private ecdsa key pair. 2025-09-29 00:24:46.819854 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/cb8c1cd3bfd04bbfa43fc1c787c0f44d/work/cb8c1cd3bfd04bbfa43fc1c787c0f44d_id_ecdsa. 2025-09-29 00:24:46.819943 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/cb8c1cd3bfd04bbfa43fc1c787c0f44d/work/cb8c1cd3bfd04bbfa43fc1c787c0f44d_id_ecdsa.pub. 2025-09-29 00:24:46.820030 | controller -> localhost | The key fingerprint is: 2025-09-29 00:24:46.820094 | controller -> localhost | SHA256:hGAW2RdxKM+Cmvoet27spuKY8VNRiK7AfTTG2QuU5sc zuul-build-sshkey 2025-09-29 00:24:46.820157 | controller -> localhost | The key's randomart image is: 2025-09-29 00:24:46.820217 | controller -> localhost | +---[ECDSA 521]---+ 2025-09-29 00:24:46.820272 | controller -> localhost | | .B*+ o+. | 2025-09-29 00:24:46.820328 | controller -> localhost | | .oo@+oo. | 2025-09-29 00:24:46.820381 | controller -> localhost | |... =o==o | 2025-09-29 00:24:46.820436 | controller -> localhost | |....oo.Eo | 2025-09-29 00:24:46.820490 | controller -> localhost | |.. o....S | 2025-09-29 00:24:46.820544 | controller -> localhost | |. o . | 2025-09-29 00:24:46.820601 | controller -> localhost | |...o. | 2025-09-29 00:24:46.820656 | controller -> localhost | |++.o+. | 2025-09-29 00:24:46.820765 | controller -> localhost | |+==B+ | 2025-09-29 00:24:46.820826 | controller -> localhost | +----[SHA256]-----+ 2025-09-29 00:24:46.820949 | controller -> localhost | ok: Runtime: 0:00:00.014436 2025-09-29 00:24:46.841420 | 2025-09-29 00:24:46.841575 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-09-29 00:24:46.884173 | controller | ok 2025-09-29 00:24:46.902368 | controller | included: /var/lib/zuul/builds/cb8c1cd3bfd04bbfa43fc1c787c0f44d/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-09-29 00:24:46.914984 | 2025-09-29 00:24:46.915078 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-09-29 00:24:46.950988 | controller | skipping: Conditional result was False 2025-09-29 00:24:46.960925 | 2025-09-29 00:24:46.961026 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-09-29 00:24:47.440869 | controller | changed 2025-09-29 00:24:47.457000 | 2025-09-29 00:24:47.457164 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-09-29 00:24:47.825992 | controller | ok 2025-09-29 00:24:47.838613 | 2025-09-29 00:24:47.838811 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-09-29 00:24:49.158423 | controller | changed 2025-09-29 00:24:49.171800 | 2025-09-29 00:24:49.171956 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-09-29 00:24:50.500417 | controller | changed 2025-09-29 00:24:50.506724 | 2025-09-29 00:24:50.507137 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-09-29 00:24:50.531678 | controller | skipping: Conditional result was False 2025-09-29 00:24:50.538123 | 2025-09-29 00:24:50.538189 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-09-29 00:24:50.776832 | controller -> localhost | changed 2025-09-29 00:24:50.796712 | 2025-09-29 00:24:50.796802 | TASK [add-build-sshkey : Add back temp key] 2025-09-29 00:24:51.066890 | controller -> localhost | Identity added: /var/lib/zuul/builds/cb8c1cd3bfd04bbfa43fc1c787c0f44d/work/cb8c1cd3bfd04bbfa43fc1c787c0f44d_id_ecdsa (zuul-build-sshkey) 2025-09-29 00:24:51.067113 | controller -> localhost | ok: Runtime: 0:00:00.009072 2025-09-29 00:24:51.073992 | 2025-09-29 00:24:51.074057 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-09-29 00:24:51.426581 | controller | ok 2025-09-29 00:24:51.438054 | 2025-09-29 00:24:51.438192 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-09-29 00:24:51.476865 | controller | skipping: Conditional result was False 2025-09-29 00:24:51.501088 | 2025-09-29 00:24:51.501237 | TASK [include_role : remove-zuul-sshkey] 2025-09-29 00:24:51.529110 | controller | skipping: Conditional result was False 2025-09-29 00:24:51.544889 | 2025-09-29 00:24:51.545028 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2025-09-29 00:24:51.921385 | controller | ok: "logs" 2025-09-29 00:24:51.921773 | controller | ok: All items complete 2025-09-29 00:24:51.921817 | 2025-09-29 00:24:52.245890 | controller | ok: "artifacts" 2025-09-29 00:24:52.540361 | controller | ok: "docs" 2025-09-29 00:24:52.556820 | 2025-09-29 00:24:52.557047 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2025-09-29 00:24:52.903147 | controller | changed: "logs" 2025-09-29 00:24:53.197544 | controller | changed: "artifacts" 2025-09-29 00:24:53.501965 | controller | changed: "docs" 2025-09-29 00:24:53.543114 | 2025-09-29 00:24:53.543244 | PLAY RECAP 2025-09-29 00:24:53.543299 | controller | ok: 30 changed: 16 unreachable: 0 failed: 0 skipped: 18 rescued: 0 ignored: 0 2025-09-29 00:24:53.543334 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-09-29 00:24:53.543359 | 2025-09-29 00:24:53.671100 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master] 2025-09-29 00:24:53.672010 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2025-09-29 00:24:54.320110 | 2025-09-29 00:24:54.320261 | PLAY [all] 2025-09-29 00:24:54.342091 | 2025-09-29 00:24:54.342193 | TASK [Install binary dependencies] 2025-09-29 00:24:54.415162 | controller | ok 2025-09-29 00:24:54.441067 | 2025-09-29 00:24:54.441296 | TASK [bindep : Include find tasks] 2025-09-29 00:24:54.482771 | controller | ok 2025-09-29 00:24:54.490744 | controller | included: /var/lib/zuul/builds/cb8c1cd3bfd04bbfa43fc1c787c0f44d/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find.yaml 2025-09-29 00:24:54.496809 | 2025-09-29 00:24:54.496876 | TASK [bindep : Look for bindep.txt] 2025-09-29 00:24:55.075754 | controller | ok 2025-09-29 00:24:55.090417 | 2025-09-29 00:24:55.090612 | TASK [bindep : Define bindep_file fact] 2025-09-29 00:24:55.117820 | controller | skipping: Conditional result was False 2025-09-29 00:24:55.126430 | 2025-09-29 00:24:55.126529 | TASK [bindep : Look for other-requirements.txt] 2025-09-29 00:24:55.555608 | controller | ok 2025-09-29 00:24:55.562552 | 2025-09-29 00:24:55.562720 | TASK [bindep : Define bindep_file fact] 2025-09-29 00:24:55.597763 | controller | skipping: Conditional result was False 2025-09-29 00:24:55.603851 | 2025-09-29 00:24:55.603919 | TASK [bindep : Look for bindep fallback file] 2025-09-29 00:24:55.639028 | controller | skipping: Conditional result was False 2025-09-29 00:24:55.644887 | 2025-09-29 00:24:55.644956 | TASK [bindep : Define bindep_file fact] 2025-09-29 00:24:55.669434 | controller | skipping: Conditional result was False 2025-09-29 00:24:55.676529 | 2025-09-29 00:24:55.676592 | TASK [bindep : Include bindep tasks] 2025-09-29 00:24:55.700824 | controller | skipping: Conditional result was False 2025-09-29 00:24:55.706948 | 2025-09-29 00:24:55.707012 | TASK [bindep : Include install tasks] 2025-09-29 00:24:55.731788 | controller | skipping: Conditional result was False 2025-09-29 00:24:55.739718 | 2025-09-29 00:24:55.739814 | LOOP [bindep : Include package tasks] 2025-09-29 00:24:55.811593 | 2025-09-29 00:24:55.811879 | TASK [Run test-setup role] 2025-09-29 00:24:55.835036 | controller | ok 2025-09-29 00:24:55.859574 | 2025-09-29 00:24:55.859690 | TASK [test-setup : Check if project's tools/test-setup.sh exists] 2025-09-29 00:24:56.197113 | controller | ok 2025-09-29 00:24:56.205265 | 2025-09-29 00:24:56.205352 | TASK [test-setup : Run tools/test-setup.sh] 2025-09-29 00:24:56.348927 | controller | skipping: Conditional result was False 2025-09-29 00:24:56.400982 | 2025-09-29 00:24:56.401083 | PLAY RECAP 2025-09-29 00:24:56.401143 | controller | ok: 4 changed: 0 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2025-09-29 00:24:56.401170 | 2025-09-29 00:24:56.553406 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2025-09-29 00:24:56.555247 | PRE-RUN START: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master] 2025-09-29 00:24:57.183216 | 2025-09-29 00:24:57.183359 | PLAY [controller] 2025-09-29 00:24:57.203677 | 2025-09-29 00:24:57.203788 | TASK [Create the /root directory] 2025-09-29 00:24:57.956074 | controller | ok 2025-09-29 00:24:57.968466 | 2025-09-29 00:24:57.968622 | TASK [Install glibc-langpack-en] 2025-09-29 00:25:06.605251 | controller | ok: Nothing to do 2025-09-29 00:25:06.610682 | 2025-09-29 00:25:06.610749 | TASK [Ensure controller directory exists] 2025-09-29 00:25:07.050246 | controller | changed 2025-09-29 00:25:07.055499 | 2025-09-29 00:25:07.055569 | TASK [Install container runtime] 2025-09-29 00:25:07.106161 | controller | ok 2025-09-29 00:25:07.143976 | 2025-09-29 00:25:07.144092 | LOOP [ensure-docker : Gather variables for each operating system] 2025-09-29 00:25:07.190396 | controller | ok: "/var/lib/zuul/builds/cb8c1cd3bfd04bbfa43fc1c787c0f44d/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/vars/fedora.yaml" 2025-09-29 00:25:07.207875 | 2025-09-29 00:25:07.208009 | TASK [ensure-docker : Sanity check for distro selection] 2025-09-29 00:25:07.222519 | controller | skipping: Conditional result was False 2025-09-29 00:25:07.229225 | 2025-09-29 00:25:07.229311 | TASK [ensure-docker : Sanity check for distro install] 2025-09-29 00:25:07.258064 | controller | skipping: Conditional result was False 2025-09-29 00:25:07.263906 | 2025-09-29 00:25:07.263975 | TASK [ensure-docker : Sanity check for upstream install] 2025-09-29 00:25:07.288292 | controller | skipping: Conditional result was False 2025-09-29 00:25:07.295714 | 2025-09-29 00:25:07.295780 | TASK [ensure-docker : Sanity check for upstream install mirrors] 2025-09-29 00:25:07.330360 | controller | skipping: Conditional result was False 2025-09-29 00:25:07.336593 | 2025-09-29 00:25:07.336683 | TASK [ensure-docker : Include tasks for docker install] 2025-09-29 00:25:07.368313 | controller | ok 2025-09-29 00:25:07.380573 | controller | included: /var/lib/zuul/builds/cb8c1cd3bfd04bbfa43fc1c787c0f44d/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/tasks/docker-upstream.yaml 2025-09-29 00:25:07.391356 | 2025-09-29 00:25:07.391448 | TASK [ensure-docker : Not Implemented] 2025-09-29 00:25:07.406245 | controller | skipping: Conditional result was False 2025-09-29 00:25:07.413490 | 2025-09-29 00:25:07.413570 | TASK [Set up docker mirrors] 2025-09-29 00:25:07.432495 | controller | ok 2025-09-29 00:25:07.454767 | 2025-09-29 00:25:07.454865 | TASK [use-docker-mirror : Set docker_mirror fact from mirror_info] 2025-09-29 00:25:07.479147 | controller | skipping: Conditional result was False 2025-09-29 00:25:07.485298 | 2025-09-29 00:25:07.485362 | TASK [use-docker-mirror : Set docker_mirror fact from zuul_site_mirror_info] 2025-09-29 00:25:07.510232 | controller | skipping: Conditional result was False 2025-09-29 00:25:07.517126 | 2025-09-29 00:25:07.517200 | TASK [use-docker-mirror : Set docker_mirror fact] 2025-09-29 00:25:07.552203 | controller | skipping: Conditional result was False 2025-09-29 00:25:07.558347 | 2025-09-29 00:25:07.558419 | TASK [use-docker-mirror : Set up docker mirrors] 2025-09-29 00:25:07.583245 | controller | skipping: Conditional result was False 2025-09-29 00:25:07.595305 | 2025-09-29 00:25:07.595391 | TASK [Add all repositories] 2025-09-29 00:25:07.646301 | controller | ok 2025-09-29 00:25:07.698112 | 2025-09-29 00:25:07.698258 | LOOP [ensure-package-repositories : Install repositories] 2025-09-29 00:25:07.739066 | controller | ok: "/var/lib/zuul/builds/cb8c1cd3bfd04bbfa43fc1c787c0f44d/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-package-repositories/tasks/RedHat.yaml" 2025-09-29 00:25:07.739375 | controller | ok: All items complete 2025-09-29 00:25:07.739405 | 2025-09-29 00:25:07.757983 | controller | included: /var/lib/zuul/builds/cb8c1cd3bfd04bbfa43fc1c787c0f44d/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-package-repositories/tasks/RedHat.yaml 2025-09-29 00:25:07.764178 | 2025-09-29 00:25:07.764246 | TASK [ensure-package-repositories : Create GPG key temp directory] 2025-09-29 00:25:08.281041 | controller | changed 2025-09-29 00:25:08.287563 | 2025-09-29 00:25:08.287660 | LOOP [ensure-package-repositories : Upload GPG keys] 2025-09-29 00:25:09.916712 | controller | changed: 2025-09-29 00:25:09.916907 | controller | { 2025-09-29 00:25:09.916934 | 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-09-29 00:25:09.916960 | controller | } 2025-09-29 00:25:09.940005 | 2025-09-29 00:25:09.940219 | LOOP [ensure-package-repositories : Import GPG keys] 2025-09-29 00:25:10.711347 | controller | changed: 2025-09-29 00:25:10.711576 | controller | { 2025-09-29 00:25:10.711618 | 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-09-29 00:25:10.711652 | controller | } 2025-09-29 00:25:10.711725 | controller | changed: All items complete 2025-09-29 00:25:10.711766 | 2025-09-29 00:25:10.719139 | 2025-09-29 00:25:10.719219 | TASK [ensure-package-repositories : Remove GPG temp directory] 2025-09-29 00:25:11.079416 | controller | changed 2025-09-29 00:25:11.092257 | 2025-09-29 00:25:11.092443 | LOOP [ensure-package-repositories : Add repositories] 2025-09-29 00:25:11.555020 | controller | changed: 2025-09-29 00:25:11.555501 | controller | { 2025-09-29 00:25:11.555565 | controller | "baseurl": "https://download.docker.com/linux/fedora/$releasever/$basearch/stable", 2025-09-29 00:25:11.555607 | controller | "description": "Docker CE Stable - $basearch", 2025-09-29 00:25:11.555644 | controller | "gpgcheck": true, 2025-09-29 00:25:11.555712 | controller | "name": "docker-ce-stable" 2025-09-29 00:25:11.555751 | controller | } 2025-09-29 00:25:11.584820 | 2025-09-29 00:25:11.584936 | TASK [ensure-docker : Install pre-reqs] 2025-09-29 00:25:14.968346 | controller | ok: Nothing to do 2025-09-29 00:25:14.986526 | 2025-09-29 00:25:14.986816 | TASK [ensure-docker : Remove packages] 2025-09-29 00:25:17.205614 | controller | ok: Nothing to do 2025-09-29 00:25:17.211153 | 2025-09-29 00:25:17.211217 | TASK [ensure-docker : Install upstream docker using package] 2025-09-29 00:26:12.717292 | controller | changed 2025-09-29 00:26:12.730320 | 2025-09-29 00:26:12.730480 | TASK [ensure-docker : Setup docker] 2025-09-29 00:26:12.759738 | controller | ok 2025-09-29 00:26:12.780817 | controller | included: /var/lib/zuul/builds/cb8c1cd3bfd04bbfa43fc1c787c0f44d/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/tasks/docker-setup.yaml 2025-09-29 00:26:12.796089 | 2025-09-29 00:26:12.796174 | TASK [ensure-docker : Ensure "docker" group exists] 2025-09-29 00:26:13.412597 | controller | ok 2025-09-29 00:26:13.422848 | 2025-09-29 00:26:13.422958 | TASK [ensure-docker : Add user to docker group] 2025-09-29 00:26:14.070820 | controller | changed 2025-09-29 00:26:14.083211 | 2025-09-29 00:26:14.083338 | TASK [Update docker daemon proxy configuration] 2025-09-29 00:26:14.120781 | controller | skipping: Conditional result was False 2025-09-29 00:26:14.133301 | 2025-09-29 00:26:14.133427 | TASK [Lower default MTU] 2025-09-29 00:26:14.161031 | controller | skipping: Conditional result was False 2025-09-29 00:26:14.178191 | 2025-09-29 00:26:14.178335 | TASK [ensure-docker : Get the status of the docket.socket unit] 2025-09-29 00:26:14.751522 | controller | inactive 2025-09-29 00:26:14.832967 | controller | ok: Runtime: 0:00:00.011925 2025-09-29 00:26:14.844688 | 2025-09-29 00:26:14.844825 | TASK [ensure-docker : Get the status of the docket unit] 2025-09-29 00:26:15.349694 | controller | inactive 2025-09-29 00:26:15.493024 | controller | ok: Runtime: 0:00:00.018428 2025-09-29 00:26:15.504841 | 2025-09-29 00:26:15.504972 | TASK [ensure-docker : Restart docker] 2025-09-29 00:26:15.562964 | Trigger a Docker restart 2025-09-29 00:26:15.574081 | 2025-09-29 00:26:15.574211 | TASK [ensure-docker : Reset ssh connection to pick up docker group] 2025-09-29 00:26:15.623263 | 2025-09-29 00:26:15.623443 | TASK [ensure-docker : Install docker-compose] 2025-09-29 00:26:15.650476 | controller | skipping: Conditional result was False 2025-09-29 00:26:15.662655 | 2025-09-29 00:26:15.662823 | TASK [ensure-docker : Flush handlers before role exit] 2025-09-29 00:26:15.683843 | 2025-09-29 00:26:15.683927 | TASK [ensure-docker : Stop docker.socket to avoid any conflict] 2025-09-29 00:26:18.004198 | controller | changed 2025-09-29 00:26:18.022522 | 2025-09-29 00:26:18.022743 | TASK [ensure-docker : Assure docker service is running] 2025-09-29 00:26:20.703081 | controller | changed 2025-09-29 00:26:20.718488 | 2025-09-29 00:26:20.718622 | TASK [ensure-docker : Assure docker.socket service is running] 2025-09-29 00:26:21.862231 | controller | ok 2025-09-29 00:26:21.878643 | 2025-09-29 00:26:21.878824 | TASK [ensure-docker : Correct group ownership on docker sock] 2025-09-29 00:26:22.726524 | controller | ok 2025-09-29 00:26:22.738291 | 2025-09-29 00:26:22.738422 | TASK [ensure-docker : Validate ability to talk with docker] 2025-09-29 00:26:23.492647 | controller | CONTAINER ID IMAGE COMMAND CREATED STATUS PORTS NAMES 2025-09-29 00:26:23.894036 | controller | ok: Runtime: 0:00:00.053278 2025-09-29 00:26:23.906391 | 2025-09-29 00:26:23.906524 | TASK [ensure-docker : Get version details] 2025-09-29 00:26:24.464031 | controller | Client: Docker Engine - Community 2025-09-29 00:26:24.464228 | controller | Version: 24.0.7 2025-09-29 00:26:24.464279 | controller | API version: 1.43 2025-09-29 00:26:24.464323 | controller | Go version: go1.20.10 2025-09-29 00:26:24.464379 | controller | Git commit: afdd53b 2025-09-29 00:26:24.464431 | controller | Built: Thu Oct 26 09:09:39 2023 2025-09-29 00:26:24.464483 | controller | OS/Arch: linux/amd64 2025-09-29 00:26:24.464537 | controller | Context: default 2025-09-29 00:26:24.464559 | controller | 2025-09-29 00:26:24.464608 | controller | Server: Docker Engine - Community 2025-09-29 00:26:24.464657 | controller | Engine: 2025-09-29 00:26:24.464850 | controller | Version: 24.0.7 2025-09-29 00:26:24.464895 | controller | API version: 1.43 (minimum version 1.12) 2025-09-29 00:26:24.464937 | controller | Go version: go1.20.10 2025-09-29 00:26:24.464980 | controller | Git commit: 311b9ff 2025-09-29 00:26:24.465029 | controller | Built: Thu Oct 26 09:08:11 2023 2025-09-29 00:26:24.465078 | controller | OS/Arch: linux/amd64 2025-09-29 00:26:24.465119 | controller | Experimental: false 2025-09-29 00:26:24.465146 | controller | containerd: 2025-09-29 00:26:24.465259 | controller | Version: 1.6.25 2025-09-29 00:26:24.465303 | controller | GitCommit: d8f198a4ed8892c764191ef7b3b06d8a2eeb5c7f 2025-09-29 00:26:24.465329 | controller | runc: 2025-09-29 00:26:24.465408 | controller | Version: 1.1.10 2025-09-29 00:26:24.465449 | controller | GitCommit: v1.1.10-0-g18a0cb0 2025-09-29 00:26:24.465479 | controller | docker-init: 2025-09-29 00:26:24.465547 | controller | Version: 0.19.0 2025-09-29 00:26:24.465589 | controller | GitCommit: de40ad0 2025-09-29 00:26:24.565048 | controller | ok: Runtime: 0:00:00.041214 2025-09-29 00:26:24.578194 | 2025-09-29 00:26:24.578339 | TASK [ensure-docker : Dump installed docker details] 2025-09-29 00:26:24.618060 | {'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-09-29 00:26:24.426816', 'end': '2025-09-29 00:26:24.468030', 'delta': '0:00:00.041214', 'msg': '', 'zuul_log_id': 'fa163efc-24cc-a1bb-016a-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-09-29 00:26:24.630451 | 2025-09-29 00:26:24.630602 | TASK [Use buildset registry] 2025-09-29 00:26:24.658227 | controller | skipping: Conditional result was False 2025-09-29 00:26:24.681251 | 2025-09-29 00:26:24.681381 | TASK [Ensure python3.8 is present] 2025-09-29 00:26:24.708261 | controller | skipping: Conditional result was False 2025-09-29 00:26:24.716404 | 2025-09-29 00:26:24.716491 | TASK [Run our-ensure-python role (Fedora only for now)] 2025-09-29 00:26:24.739238 | controller | ok 2025-09-29 00:26:24.763602 | 2025-09-29 00:26:24.763736 | TASK [our-ensure-python : Install the right Python version (rpm)] 2025-09-29 00:26:31.000021 | controller | ok: Nothing to do 2025-09-29 00:26:31.005538 | 2025-09-29 00:26:31.005609 | TASK [our-ensure-python : Also install python3-devel] 2025-09-29 00:26:45.833079 | controller | changed 2025-09-29 00:26:45.856929 | 2025-09-29 00:26:45.857088 | TASK [Run ensure-virtualenv role] 2025-09-29 00:26:45.886407 | controller | ok 2025-09-29 00:26:45.921708 | 2025-09-29 00:26:45.921902 | TASK [ensure-virtualenv : Check if virtualenv is installed] 2025-09-29 00:26:46.309449 | controller | /usr/bin/virtualenv 2025-09-29 00:26:46.561952 | controller | ok: Runtime: 0:00:00.004855 2025-09-29 00:26:46.574318 | 2025-09-29 00:26:46.574464 | LOOP [ensure-virtualenv : Install virtualenv package] 2025-09-29 00:26:46.608843 | controller | skipping: Conditional result was False 2025-09-29 00:26:46.609302 | controller | changed: All items complete 2025-09-29 00:26:46.609363 | 2025-09-29 00:26:46.635176 | 2025-09-29 00:26:46.635363 | TASK [Find the full path of the Python interpreter] 2025-09-29 00:26:47.100639 | controller | /usr/bin/python3.9 2025-09-29 00:26:47.280213 | controller | ok 2025-09-29 00:26:47.292282 | 2025-09-29 00:26:47.292425 | TASK [Create virtualenv for ansible-test with a specific interpreter] 2025-09-29 00:26:49.469571 | controller | created virtual environment CPython3.9.18.final.0-64 in 1147ms 2025-09-29 00:26:49.512080 | controller | creator CPython3Posix(dest=/home/zuul-worker/venv, clear=False, no_vcs_ignore=False, global=False) 2025-09-29 00:26:49.512137 | controller | seeder FromAppData(extra_search_dir=/usr/share/python-wheels,download=False, pip=bundle, setuptools=bundle, wheel=bundle, via=copy, app_data_dir=/home/zuul-worker/.local/share/virtualenv) 2025-09-29 00:26:49.512156 | controller | added seed packages: pip==22.2.2, setuptools==62.6.0, wheel==0.37.1 2025-09-29 00:26:49.512179 | controller | activators BashActivator,CShellActivator,FishActivator,NushellActivator,PowerShellActivator,PythonActivator 2025-09-29 00:26:49.945332 | controller | changed 2025-09-29 00:26:49.957381 | 2025-09-29 00:26:49.957521 | TASK [Set selinux package] 2025-09-29 00:26:49.995768 | controller | ok 2025-09-29 00:26:50.006502 | 2025-09-29 00:26:50.006632 | TASK [Set selinux package (Fedora)] 2025-09-29 00:26:50.056068 | controller | ok 2025-09-29 00:26:50.066505 | 2025-09-29 00:26:50.066635 | TASK [Install selinux into virtualenv] 2025-09-29 00:26:53.238241 | controller | Collecting selinux-please-lie-to-me 2025-09-29 00:26:53.364985 | controller | Downloading selinux_please_lie_to_me-1.0.1-py2.py3-none-any.whl (3.8 kB) 2025-09-29 00:26:54.266789 | controller | Collecting setuptools<50.0.0 2025-09-29 00:26:54.281056 | controller | Downloading setuptools-49.6.0-py3-none-any.whl (803 kB) 2025-09-29 00:26:54.404256 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 803.3/803.3 kB 6.9 MB/s eta 0:00:00 2025-09-29 00:26:54.604196 | controller | Installing collected packages: setuptools, selinux-please-lie-to-me 2025-09-29 00:26:54.605180 | controller | Attempting uninstall: setuptools 2025-09-29 00:26:54.606723 | controller | Found existing installation: setuptools 62.6.0 2025-09-29 00:26:54.743832 | controller | Uninstalling setuptools-62.6.0: 2025-09-29 00:26:54.765010 | controller | Successfully uninstalled setuptools-62.6.0 2025-09-29 00:26:55.534370 | controller | Successfully installed selinux-please-lie-to-me-1.0.1 setuptools-49.6.0 2025-09-29 00:26:55.901528 | controller | 2025-09-29 00:26:56.121307 | controller | [notice] A new release of pip available: 22.2.2 -> 25.2 2025-09-29 00:26:56.121348 | controller | [notice] To update, run: /home/zuul-worker/venv/bin/python -m pip install --upgrade pip 2025-09-29 00:26:56.227558 | controller | ok: Runtime: 0:00:05.507953 2025-09-29 00:26:56.238448 | 2025-09-29 00:26:56.238578 | TASK [Install pytest-forked into virtualenv] 2025-09-29 00:26:57.870320 | controller | Collecting pytest-forked 2025-09-29 00:26:58.006544 | controller | Downloading pytest_forked-1.6.0-py3-none-any.whl (4.9 kB) 2025-09-29 00:26:58.400291 | controller | Collecting pytest>=3.10 2025-09-29 00:26:58.415321 | controller | Downloading pytest-8.4.2-py3-none-any.whl (365 kB) 2025-09-29 00:26:58.500291 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 365.8/365.8 kB 4.8 MB/s eta 0:00:00 2025-09-29 00:26:58.606790 | controller | Collecting py 2025-09-29 00:26:58.624227 | controller | Downloading py-1.11.0-py2.py3-none-any.whl (98 kB) 2025-09-29 00:26:58.644057 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 98.7/98.7 kB 5.7 MB/s eta 0:00:00 2025-09-29 00:26:58.710798 | controller | Collecting iniconfig>=1 2025-09-29 00:26:58.730610 | controller | Downloading iniconfig-2.1.0-py3-none-any.whl (6.0 kB) 2025-09-29 00:26:58.824440 | controller | Collecting pluggy<2,>=1.5 2025-09-29 00:26:58.843906 | controller | Downloading pluggy-1.6.0-py3-none-any.whl (20 kB) 2025-09-29 00:26:58.952002 | controller | Collecting tomli>=1 2025-09-29 00:26:58.969160 | controller | Downloading tomli-2.2.1-py3-none-any.whl (14 kB) 2025-09-29 00:26:59.120565 | controller | Collecting pygments>=2.7.2 2025-09-29 00:26:59.169024 | controller | Downloading pygments-2.19.2-py3-none-any.whl (1.2 MB) 2025-09-29 00:26:59.288363 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 1.2/1.2 MB 10.6 MB/s eta 0:00:00 2025-09-29 00:26:59.377806 | controller | Collecting exceptiongroup>=1 2025-09-29 00:26:59.395434 | controller | Downloading exceptiongroup-1.3.0-py3-none-any.whl (16 kB) 2025-09-29 00:26:59.532788 | controller | Collecting packaging>=20 2025-09-29 00:26:59.549622 | controller | Downloading packaging-25.0-py3-none-any.whl (66 kB) 2025-09-29 00:26:59.561015 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 66.5/66.5 kB 9.3 MB/s eta 0:00:00 2025-09-29 00:26:59.723120 | controller | Collecting typing-extensions>=4.6.0 2025-09-29 00:26:59.737544 | controller | Downloading typing_extensions-4.15.0-py3-none-any.whl (44 kB) 2025-09-29 00:26:59.747904 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 44.6/44.6 kB 6.0 MB/s eta 0:00:00 2025-09-29 00:26:59.875447 | controller | Installing collected packages: typing-extensions, tomli, pygments, py, pluggy, packaging, iniconfig, exceptiongroup, pytest, pytest-forked 2025-09-29 00:27:02.323327 | 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.2.1 typing-extensions-4.15.0 2025-09-29 00:27:02.370491 | controller | 2025-09-29 00:27:02.826573 | controller | [notice] A new release of pip available: 22.2.2 -> 25.2 2025-09-29 00:27:02.826622 | controller | [notice] To update, run: /home/zuul-worker/venv/bin/python -m pip install --upgrade pip 2025-09-29 00:27:02.963344 | controller | ok: Runtime: 0:00:05.994334 2025-09-29 00:27:02.974987 | 2025-09-29 00:27:02.975154 | TASK [Update pip] 2025-09-29 00:27:04.922995 | controller | Requirement already satisfied: pip in ./venv/lib/python3.9/site-packages (22.2.2) 2025-09-29 00:27:05.364951 | controller | Collecting pip 2025-09-29 00:27:05.498431 | controller | Downloading pip-25.2-py3-none-any.whl (1.8 MB) 2025-09-29 00:27:05.705953 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 1.8/1.8 MB 8.7 MB/s eta 0:00:00 2025-09-29 00:27:05.919706 | controller | Installing collected packages: pip 2025-09-29 00:27:05.925536 | controller | Attempting uninstall: pip 2025-09-29 00:27:05.927380 | controller | Found existing installation: pip 22.2.2 2025-09-29 00:27:06.392605 | controller | Uninstalling pip-22.2.2: 2025-09-29 00:27:06.445854 | controller | Successfully uninstalled pip-22.2.2 2025-09-29 00:27:08.119693 | controller | Successfully installed pip-25.2 2025-09-29 00:27:08.627887 | controller | ok: Runtime: 0:00:04.583488 2025-09-29 00:27:08.640185 | 2025-09-29 00:27:08.640327 | TASK [Ensure pip temp directory exists in /var/tmp] 2025-09-29 00:27:09.455022 | controller | changed 2025-09-29 00:27:09.466377 | 2025-09-29 00:27:09.466511 | TASK [Install ansible into virtualenv] 2025-09-29 00:27:11.198543 | controller | Processing ./src/github.com/ansible/ansible 2025-09-29 00:27:11.203048 | controller | Preparing metadata (setup.py): started 2025-09-29 00:27:12.752814 | controller | Preparing metadata (setup.py): finished with status 'done' 2025-09-29 00:27:12.875903 | controller | Collecting jinja2 (from ansible-core==2.11.12.post0) 2025-09-29 00:27:12.924161 | controller | Downloading jinja2-3.1.6-py3-none-any.whl.metadata (2.9 kB) 2025-09-29 00:27:13.028940 | controller | Collecting PyYAML (from ansible-core==2.11.12.post0) 2025-09-29 00:27:13.063250 | 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-09-29 00:27:13.577550 | controller | Collecting cryptography (from ansible-core==2.11.12.post0) 2025-09-29 00:27:13.590403 | controller | Downloading cryptography-46.0.1-cp38-abi3-manylinux_2_34_x86_64.whl.metadata (5.7 kB) 2025-09-29 00:27:13.615543 | controller | Requirement already satisfied: packaging in ./venv/lib/python3.9/site-packages (from ansible-core==2.11.12.post0) (25.0) 2025-09-29 00:27:13.667374 | controller | Collecting resolvelib<0.6.0,>=0.5.3 (from ansible-core==2.11.12.post0) 2025-09-29 00:27:13.683225 | controller | Downloading resolvelib-0.5.4-py2.py3-none-any.whl.metadata (3.7 kB) 2025-09-29 00:27:13.978077 | controller | Collecting cffi>=2.0.0 (from cryptography->ansible-core==2.11.12.post0) 2025-09-29 00:27:13.991879 | controller | Downloading cffi-2.0.0-cp39-cp39-manylinux2014_x86_64.manylinux_2_17_x86_64.whl.metadata (2.6 kB) 2025-09-29 00:27:14.002805 | 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-09-29 00:27:14.111030 | controller | Collecting pycparser (from cffi>=2.0.0->cryptography->ansible-core==2.11.12.post0) 2025-09-29 00:27:14.124360 | controller | Downloading pycparser-2.23-py3-none-any.whl.metadata (993 bytes) 2025-09-29 00:27:14.339076 | controller | Collecting MarkupSafe>=2.0 (from jinja2->ansible-core==2.11.12.post0) 2025-09-29 00:27:14.353592 | 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-09-29 00:27:14.404531 | controller | Downloading resolvelib-0.5.4-py2.py3-none-any.whl (12 kB) 2025-09-29 00:27:14.427609 | controller | Downloading cryptography-46.0.1-cp38-abi3-manylinux_2_34_x86_64.whl (4.6 MB) 2025-09-29 00:27:14.521717 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 4.6/4.6 MB 74.7 MB/s 0:00:00 2025-09-29 00:27:14.534729 | controller | Downloading cffi-2.0.0-cp39-cp39-manylinux2014_x86_64.manylinux_2_17_x86_64.whl (216 kB) 2025-09-29 00:27:14.555159 | controller | Downloading jinja2-3.1.6-py3-none-any.whl (134 kB) 2025-09-29 00:27:14.582008 | 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-09-29 00:27:14.604585 | controller | Downloading pycparser-2.23-py3-none-any.whl (118 kB) 2025-09-29 00:27:14.628594 | 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-09-29 00:27:14.648654 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 750.8/750.8 kB 32.2 MB/s 0:00:00 2025-09-29 00:27:14.689829 | controller | Building wheels for collected packages: ansible-core 2025-09-29 00:27:14.691293 | controller | DEPRECATION: Building 'ansible-core' using the legacy setup.py bdist_wheel mechanism, which will be removed in a future version. pip 25.3 will enforce this behaviour change. A possible replacement is to use the standardized build interface by setting the `--use-pep517` option, (possibly combined with `--no-build-isolation`), or adding a `pyproject.toml` file to the source tree of 'ansible-core'. Discussion can be found at https://github.com/pypa/pip/issues/6334 2025-09-29 00:27:14.691615 | controller | Building wheel for ansible-core (setup.py): started 2025-09-29 00:27:16.951497 | controller | Building wheel for ansible-core (setup.py): finished with status 'done' 2025-09-29 00:27:16.962624 | controller | Created wheel for ansible-core: filename=ansible_core-2.11.12.post0-py3-none-any.whl size=1961168 sha256=6e5156632e518a27539cf8844dc17f0e11d3247abcb4df9bd2f3bc24be93ef13 2025-09-29 00:27:16.962963 | controller | Stored in directory: /var/tmp/ansible-test-pip/pip-ephem-wheel-cache-cvp8ejh6/wheels/e8/fd/e9/6250c469bad44a7c6855190836e9d22c0bb11cb641caf57fbe 2025-09-29 00:27:16.979024 | controller | Successfully built ansible-core 2025-09-29 00:27:17.046335 | controller | Installing collected packages: resolvelib, PyYAML, pycparser, MarkupSafe, jinja2, cffi, cryptography, ansible-core 2025-09-29 00:27:19.416492 | controller | 2025-09-29 00:27:19.420284 | controller | Successfully installed MarkupSafe-3.0.3 PyYAML-6.0.3 ansible-core-2.11.12.post0 cffi-2.0.0 cryptography-46.0.1 jinja2-3.1.6 pycparser-2.23 resolvelib-0.5.4 2025-09-29 00:27:19.651060 | controller | ok: Runtime: 0:00:09.488709 2025-09-29 00:27:19.663948 | 2025-09-29 00:27:19.664124 | TASK [Setup download-artifact-fork role] 2025-09-29 00:27:19.693544 | controller | ok 2025-09-29 00:27:19.725184 | 2025-09-29 00:27:19.725328 | TASK [download-artifact-fork : Parse inventory response] 2025-09-29 00:27:19.778569 | controller | ok 2025-09-29 00:27:19.787705 | 2025-09-29 00:27:19.787804 | TASK [download-artifact-fork : Fetch info from Zuul API] 2025-09-29 00:27:19.823680 | controller | skipping: Conditional result was False 2025-09-29 00:27:19.832429 | 2025-09-29 00:27:19.832531 | TASK [download-artifact-fork : Parse build response] 2025-09-29 00:27:19.868916 | controller | skipping: Conditional result was False 2025-09-29 00:27:19.879947 | 2025-09-29 00:27:19.880172 | TASK [download-artifact-fork : Ensure artifacts directory exists] 2025-09-29 00:27:20.397866 | controller | changed 2025-09-29 00:27:20.409924 | 2025-09-29 00:27:20.410058 | LOOP [download-artifact-fork : Download archive by type] 2025-09-29 00:27:20.447523 | controller | skipping: Conditional result was False 2025-09-29 00:27:20.448189 | 2025-09-29 00:27:21.870971 | controller | changed: OK (20175 bytes) 2025-09-29 00:27:21.897175 | 2025-09-29 00:27:21.897370 | TASK [deploy-artifacts : define __collections] 2025-09-29 00:27:21.936032 | controller | ok 2025-09-29 00:27:21.946945 | 2025-09-29 00:27:21.947074 | LOOP [deploy-artifacts : Create list of collections] 2025-09-29 00:27:21.982170 | controller | skipping: Conditional result was False 2025-09-29 00:27:22.000333 | controller | ok: 2025-09-29 00:27:22.000419 | controller | { 2025-09-29 00:27:22.000458 | controller | "branch": "main", 2025-09-29 00:27:22.000499 | controller | "job": "build-ansible-collection", 2025-09-29 00:27:22.000537 | controller | "metadata": { 2025-09-29 00:27:22.000562 | controller | "type": "ansible_collection", 2025-09-29 00:27:22.000584 | controller | "version": "5.0.0" 2025-09-29 00:27:22.000606 | controller | }, 2025-09-29 00:27:22.000627 | controller | "name": "ansible.network", 2025-09-29 00:27:22.000648 | controller | "project": "ansible-collections/ansible.network", 2025-09-29 00:27:22.000715 | controller | "url": "https://ac880dc04e5776d62a4f-28f4885b7e8ead4881399a91a1d30ca6.ssl.cf5.rackcdn.com/ansible/ef987a3b6fb9461a9dc3b442059646d1/artifacts/ansible-network-5.0.0.tar.gz" 2025-09-29 00:27:22.000750 | controller | } 2025-09-29 00:27:22.019321 | 2025-09-29 00:27:22.019553 | TASK [deploy-artifacts : Install require-project collection using ansible-galaxy] 2025-09-29 00:27:23.308013 | controller | Starting galaxy collection install process 2025-09-29 00:27:23.308309 | controller | Process install dependency map 2025-09-29 00:27:25.391188 | controller | [WARNING]: Skipping Galaxy server https://galaxy.ansible.com/api/. Got an 2025-09-29 00:27:25.391460 | controller | unexpected error when getting available versions of collection 2025-09-29 00:27:25.391492 | controller | ansible.netcommon: '/api/v3/plugin/ansible/content/published/collections/index/ 2025-09-29 00:27:25.391499 | controller | ansible/netcommon/versions/' 2025-09-29 00:27:25.391510 | controller | ERROR! Unexpected Exception, this is probably a bug: '/api/v3/plugin/ansible/content/published/collections/index/ansible/netcommon/versions/' 2025-09-29 00:27:25.391551 | controller | to see the full traceback, use -vvv 2025-09-29 00:27:25.678126 | controller | ERROR 2025-09-29 00:27:25.678454 | controller | { 2025-09-29 00:27:25.678513 | controller | "delta": "0:00:02.936096", 2025-09-29 00:27:25.678555 | controller | "end": "2025-09-29 00:27:25.446893", 2025-09-29 00:27:25.678591 | controller | "msg": "non-zero return code", 2025-09-29 00:27:25.678626 | controller | "rc": 250, 2025-09-29 00:27:25.678661 | controller | "start": "2025-09-29 00:27:22.510797" 2025-09-29 00:27:25.678741 | controller | } failure 2025-09-29 00:27:25.682651 | 2025-09-29 00:27:25.682794 | PLAY RECAP 2025-09-29 00:27:25.682888 | controller | ok: 44 changed: 24 unreachable: 0 failed: 1 skipped: 17 rescued: 0 ignored: 0 2025-09-29 00:27:25.682932 | 2025-09-29 00:27:25.831793 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master] 2025-09-29 00:27:25.832822 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2025-09-29 00:27:26.421641 | 2025-09-29 00:27:26.421826 | PLAY [all] 2025-09-29 00:27:26.442537 | 2025-09-29 00:27:26.442624 | TASK [fetch-subunit-output : Find stestr or testr executable] 2025-09-29 00:27:27.937582 | controller | changed: non-zero return code 2025-09-29 00:27:27.950726 | 2025-09-29 00:27:27.950994 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2025-09-29 00:27:27.980079 | controller | skipping: Conditional result was False 2025-09-29 00:27:27.993866 | 2025-09-29 00:27:27.994034 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2025-09-29 00:27:28.038185 | 2025-09-29 00:27:28.038528 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2025-09-29 00:27:28.089163 | 2025-09-29 00:27:28.089438 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2025-09-29 00:27:28.126822 | controller | skipping: Conditional result was False 2025-09-29 00:27:28.141767 | 2025-09-29 00:27:28.141972 | LOOP [fetch-subunit-output : Generate subunit file] 2025-09-29 00:27:28.182407 | 2025-09-29 00:27:28.182725 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2025-09-29 00:27:28.210465 | controller | skipping: Conditional result was False 2025-09-29 00:27:28.223340 | 2025-09-29 00:27:28.223482 | TASK [fetch-subunit-output : Remove the temporary file] 2025-09-29 00:27:28.250585 | controller | skipping: Conditional result was False 2025-09-29 00:27:28.263541 | 2025-09-29 00:27:28.263717 | TASK [fetch-subunit-output : Process and fetch subunit results] 2025-09-29 00:27:28.290930 | controller | skipping: Conditional result was False 2025-09-29 00:27:28.338439 | 2025-09-29 00:27:28.338541 | PLAY RECAP 2025-09-29 00:27:28.338601 | controller | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2025-09-29 00:27:28.338630 | 2025-09-29 00:27:28.462623 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2025-09-29 00:27:28.463837 | POST-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/post.yaml@master] 2025-09-29 00:27:29.019122 | 2025-09-29 00:27:29.019242 | PLAY [all:!appliance*] 2025-09-29 00:27:29.039785 | 2025-09-29 00:27:29.039887 | TASK [unregister the node] 2025-09-29 00:27:29.164899 | controller | skipping: Conditional result was False 2025-09-29 00:27:29.178233 | 2025-09-29 00:27:29.178406 | TASK [include_role : fetch-output] 2025-09-29 00:27:29.239231 | controller | ok 2025-09-29 00:27:29.275448 | 2025-09-29 00:27:29.275546 | TASK [fetch-output : Set log path for multiple nodes] 2025-09-29 00:27:29.353825 | controller | skipping: Conditional result was False 2025-09-29 00:27:29.366620 | 2025-09-29 00:27:29.366830 | TASK [fetch-output : Set log path for single node] 2025-09-29 00:27:29.415969 | controller | ok 2025-09-29 00:27:29.427437 | 2025-09-29 00:27:29.427581 | LOOP [fetch-output : Ensure local output dirs] 2025-09-29 00:27:29.889749 | controller -> localhost | ok: "/var/lib/zuul/builds/cb8c1cd3bfd04bbfa43fc1c787c0f44d/work/logs" 2025-09-29 00:27:30.146319 | controller -> localhost | changed: "/var/lib/zuul/builds/cb8c1cd3bfd04bbfa43fc1c787c0f44d/work/artifacts" 2025-09-29 00:27:30.434971 | controller -> localhost | changed: "/var/lib/zuul/builds/cb8c1cd3bfd04bbfa43fc1c787c0f44d/work/docs" 2025-09-29 00:27:30.459958 | 2025-09-29 00:27:30.460127 | LOOP [fetch-output : Collect logs, artifacts and docs] 2025-09-29 00:27:31.682345 | controller | changed: 2025-09-29 00:27:31.682629 | controller | .d..t...... ./ 2025-09-29 00:27:31.682656 | controller | cd+++++++++ controller/ 2025-09-29 00:27:31.682732 | controller | changed: All items complete 2025-09-29 00:27:31.682754 | 2025-09-29 00:27:32.740944 | controller | changed: .d..t...... ./ 2025-09-29 00:27:33.882291 | controller | changed: .d..t...... ./ 2025-09-29 00:27:33.920442 | 2025-09-29 00:27:33.920855 | TASK [include_role : fetch-output-openshift] 2025-09-29 00:27:33.949208 | controller | skipping: Conditional result was False 2025-09-29 00:27:33.963783 | 2025-09-29 00:27:33.963939 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2025-09-29 00:27:34.018588 | controller | skipping: Conditional result was False 2025-09-29 00:27:34.031628 | controller | skipping: Conditional result was False 2025-09-29 00:27:34.086994 | 2025-09-29 00:27:34.087184 | PLAY [localhost] 2025-09-29 00:27:34.105361 | 2025-09-29 00:27:34.105457 | TASK [Run Zuul manifest role] 2025-09-29 00:27:34.129031 | localhost | ok 2025-09-29 00:27:34.148571 | 2025-09-29 00:27:34.148694 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-09-29 00:27:34.597436 | localhost | changed 2025-09-29 00:27:34.609790 | 2025-09-29 00:27:34.609949 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-09-29 00:27:34.650191 | localhost | ok 2025-09-29 00:27:34.672699 | 2025-09-29 00:27:34.672848 | TASK [Set zuul-log-path fact] 2025-09-29 00:27:34.694589 | localhost | ok 2025-09-29 00:27:34.712865 | 2025-09-29 00:27:34.712951 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-09-29 00:27:34.745714 | localhost | ok 2025-09-29 00:27:34.758923 | 2025-09-29 00:27:34.759034 | LOOP [Run upload-logs-swift role] 2025-09-29 00:27:34.786984 | localhost | Output suppressed because no_log was given 2025-09-29 00:27:34.822355 | 2025-09-29 00:27:34.822558 | TASK [Set zuul-log-path fact] 2025-09-29 00:27:34.848405 | localhost | skipping: Conditional result was False 2025-09-29 00:27:34.853962 | 2025-09-29 00:27:34.854032 | TASK [upload-logs-swift : Ensure logs are readable before uploading] 2025-09-29 00:27:35.307947 | localhost -> localhost | ok: Runtime: 0:00:00.006780 2025-09-29 00:27:35.314170 | 2025-09-29 00:27:35.314246 | TASK [upload-logs-swift : Upload logs to swift]