2026-01-18 00:29:34.205356 | Job console starting... 2026-01-18 00:29:34.217303 | Updating repositories 2026-01-18 00:29:34.397329 | Preparing job workspace 2026-01-18 00:29:39.462120 | Running Ansible setup... 2026-01-18 00:29:47.392911 | PRE-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master] 2026-01-18 00:29:48.082246 | 2026-01-18 00:29:48.082420 | PLAY [localhost] 2026-01-18 00:29:48.091505 | 2026-01-18 00:29:48.091598 | TASK [Gathering Facts] 2026-01-18 00:29:49.079469 | localhost | ok 2026-01-18 00:29:49.100089 | 2026-01-18 00:29:49.100399 | TASK [Setup log path fact] 2026-01-18 00:29:49.120893 | localhost | ok 2026-01-18 00:29:49.134720 | 2026-01-18 00:29:49.134808 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-01-18 00:29:49.164265 | localhost | ok 2026-01-18 00:29:49.173881 | 2026-01-18 00:29:49.174102 | TASK [emit-job-header : Print job information] 2026-01-18 00:29:49.214387 | # Job Information 2026-01-18 00:29:49.214580 | Ansible Version: 2.15.12 2026-01-18 00:29:49.214615 | Job: ansible-test-sanity-docker-stable-2.11 2026-01-18 00:29:49.214646 | Pipeline: periodic 2026-01-18 00:29:49.214672 | Executor: ze04.softwarefactory-project.io 2026-01-18 00:29:49.214716 | Triggered by: https://github.com/ansible-collections/ansible.network 2026-01-18 00:29:49.214740 | Log URL (when completed): https://ansible.softwarefactory-project.io/logs/4d3/ansible/4d3fafec3503402aa78707fdc95c149e/ 2026-01-18 00:29:49.214760 | Event ID: 9ecfb4d48ef5425f8ac323d52d7bd141 2026-01-18 00:29:49.218854 | 2026-01-18 00:29:49.218922 | LOOP [emit-job-header : Print node information] 2026-01-18 00:29:49.346122 | localhost | ok: 2026-01-18 00:29:49.347769 | localhost | # Node Information 2026-01-18 00:29:49.347826 | localhost | Inventory Hostname: controller 2026-01-18 00:29:49.347866 | localhost | Hostname: np0005587000 2026-01-18 00:29:49.347918 | localhost | Username: zuul 2026-01-18 00:29:49.347985 | localhost | Distro: Fedora 37 2026-01-18 00:29:49.348034 | localhost | Provider: ansible-vexxhost-ams1 2026-01-18 00:29:49.348071 | localhost | Region: ams1 2026-01-18 00:29:49.348105 | localhost | Label: ansible-fedora-37-1vcpu 2026-01-18 00:29:49.348137 | localhost | Product Name: OpenStack Nova 2026-01-18 00:29:49.348170 | localhost | Interface IP: 38.129.16.47 2026-01-18 00:29:49.368246 | 2026-01-18 00:29:49.368432 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-01-18 00:29:49.833198 | localhost -> localhost | changed 2026-01-18 00:29:49.841013 | 2026-01-18 00:29:49.841127 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-01-18 00:29:50.726826 | localhost -> localhost | changed 2026-01-18 00:29:50.748337 | 2026-01-18 00:29:50.748436 | PLAY [all:!appliance*] 2026-01-18 00:29:50.765618 | 2026-01-18 00:29:50.765772 | TASK [include_role : start-zuul-console] 2026-01-18 00:29:50.787675 | controller | ok 2026-01-18 00:29:50.803974 | 2026-01-18 00:29:50.804206 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-01-18 00:29:51.937655 | controller | ok 2026-01-18 00:29:51.950696 | 2026-01-18 00:29:51.950860 | TASK [use-our-mirror : Retrieve the IP address] 2026-01-18 00:29:54.145287 | controller | ok: Status code was 404 and not [200]: HTTP Error 404: Not Found 2026-01-18 00:29:54.161986 | 2026-01-18 00:29:54.162209 | TASK [use-our-mirror : Ensure the registry service is running on the mirror] 2026-01-18 00:29:54.532237 | controller | skipping: Conditional result was False 2026-01-18 00:29:54.539329 | 2026-01-18 00:29:54.539459 | TASK [use-our-mirror : Write the IP in /etc/hosts] 2026-01-18 00:29:54.564832 | controller | skipping: Conditional result was False 2026-01-18 00:29:54.571232 | 2026-01-18 00:29:54.571320 | TASK [use-our-mirror : Create the podman configuration directory] 2026-01-18 00:29:54.596302 | controller | skipping: Conditional result was False 2026-01-18 00:29:54.606010 | 2026-01-18 00:29:54.606133 | TASK [use-our-mirror : Copy the podman configuration] 2026-01-18 00:29:54.632215 | controller | skipping: Conditional result was False 2026-01-18 00:29:54.648669 | 2026-01-18 00:29:54.648954 | TASK [use-our-mirror : Copy the Yum configuration (main)] 2026-01-18 00:29:54.687216 | controller | skipping: Conditional result was False 2026-01-18 00:29:54.696921 | 2026-01-18 00:29:54.697091 | TASK [use-our-mirror : Copy the Yum configuration (update)] 2026-01-18 00:29:54.725321 | controller | skipping: Conditional result was False 2026-01-18 00:29:54.742278 | 2026-01-18 00:29:54.742440 | TASK [Disable Fedora Modular] 2026-01-18 00:29:55.777668 | controller | changed 2026-01-18 00:29:55.784334 | 2026-01-18 00:29:55.784458 | TASK [Enable EPEL] 2026-01-18 00:29:55.809738 | controller | skipping: Conditional result was False 2026-01-18 00:29:55.816922 | 2026-01-18 00:29:55.817073 | TASK [Register the RHEL node] 2026-01-18 00:29:56.229077 | 2026-01-18 00:29:56.229304 | TASK [Show the subscription-manager status] 2026-01-18 00:29:56.591931 | controller | skipping: Conditional result was False 2026-01-18 00:29:56.604199 | 2026-01-18 00:29:56.604334 | TASK [Enable EPEL on RHEL] 2026-01-18 00:29:57.009433 | controller | skipping: Conditional result was False 2026-01-18 00:29:57.018512 | 2026-01-18 00:29:57.018626 | TASK [Install git and tox] 2026-01-18 00:31:40.178980 | controller | changed 2026-01-18 00:31:40.196199 | 2026-01-18 00:31:40.196395 | TASK [include_role : prepare-workspace] 2026-01-18 00:31:40.223672 | controller | ok 2026-01-18 00:31:40.248034 | 2026-01-18 00:31:40.248187 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-01-18 00:31:40.983444 | controller | ok 2026-01-18 00:31:40.997905 | 2026-01-18 00:31:40.998042 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-01-18 00:31:56.741032 | controller | Output suppressed because no_log was given 2026-01-18 00:31:56.752745 | 2026-01-18 00:31:56.752850 | TASK [include_role : prepare-workspace-openshift] 2026-01-18 00:31:56.778188 | controller | skipping: Conditional result was False 2026-01-18 00:31:56.859069 | 2026-01-18 00:31:56.859161 | PLAY [all:!appliance] 2026-01-18 00:31:56.879562 | 2026-01-18 00:31:56.879747 | TASK [Run add-build-sshkey role (RSA)] 2026-01-18 00:31:56.901477 | controller | ok 2026-01-18 00:31:56.918050 | 2026-01-18 00:31:56.918201 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-01-18 00:31:57.147833 | controller -> localhost | ok 2026-01-18 00:31:57.154927 | 2026-01-18 00:31:57.154995 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-01-18 00:31:57.187001 | controller | ok 2026-01-18 00:31:57.201572 | controller | included: /var/lib/zuul/builds/4d3fafec3503402aa78707fdc95c149e/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-01-18 00:31:57.207837 | 2026-01-18 00:31:57.207906 | TASK [add-build-sshkey : Create Temp SSH key] 2026-01-18 00:31:57.625206 | controller -> localhost | Generating public/private rsa key pair. 2026-01-18 00:31:57.625416 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/4d3fafec3503402aa78707fdc95c149e/work/4d3fafec3503402aa78707fdc95c149e_id_rsa. 2026-01-18 00:31:57.625444 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/4d3fafec3503402aa78707fdc95c149e/work/4d3fafec3503402aa78707fdc95c149e_id_rsa.pub. 2026-01-18 00:31:57.625465 | controller -> localhost | The key fingerprint is: 2026-01-18 00:31:57.625486 | controller -> localhost | SHA256:e46sgzmv+GuLqC0Zunf6F21H+P95YbLNbq77VOJH7K0 zuul-build-sshkey 2026-01-18 00:31:57.625505 | controller -> localhost | The key's randomart image is: 2026-01-18 00:31:57.625524 | controller -> localhost | +---[RSA 2048]----+ 2026-01-18 00:31:57.625543 | controller -> localhost | | | 2026-01-18 00:31:57.625562 | controller -> localhost | | | 2026-01-18 00:31:57.625580 | controller -> localhost | | . | 2026-01-18 00:31:57.625598 | controller -> localhost | | . . . | 2026-01-18 00:31:57.625615 | controller -> localhost | | .So . +| 2026-01-18 00:31:57.625644 | controller -> localhost | |. . o.o o *o| 2026-01-18 00:31:57.625667 | controller -> localhost | |.o o o.... B.=| 2026-01-18 00:31:57.625717 | controller -> localhost | |+o.o* o. + ...B.| 2026-01-18 00:31:57.625740 | controller -> localhost | |=+=**Booo . +E= | 2026-01-18 00:31:57.625761 | controller -> localhost | +----[SHA256]-----+ 2026-01-18 00:31:57.625807 | controller -> localhost | ok: Runtime: 0:00:00.038087 2026-01-18 00:31:57.632707 | 2026-01-18 00:31:57.632771 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-01-18 00:31:57.665228 | controller | ok 2026-01-18 00:31:57.677572 | controller | included: /var/lib/zuul/builds/4d3fafec3503402aa78707fdc95c149e/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-01-18 00:31:57.687453 | 2026-01-18 00:31:57.687526 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-01-18 00:31:57.714236 | controller | skipping: Conditional result was False 2026-01-18 00:31:57.721598 | 2026-01-18 00:31:57.721706 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-01-18 00:31:58.708556 | controller | changed 2026-01-18 00:31:58.719205 | 2026-01-18 00:31:58.719389 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-01-18 00:31:59.273300 | controller | ok 2026-01-18 00:31:59.279611 | 2026-01-18 00:31:59.279732 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-01-18 00:32:01.976906 | controller | changed 2026-01-18 00:32:01.989657 | 2026-01-18 00:32:01.989824 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-01-18 00:32:04.736243 | controller | changed 2026-01-18 00:32:04.751387 | 2026-01-18 00:32:04.751565 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-01-18 00:32:04.790133 | controller | skipping: Conditional result was False 2026-01-18 00:32:04.807253 | 2026-01-18 00:32:04.807496 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-01-18 00:32:05.287385 | controller -> localhost | changed 2026-01-18 00:32:05.319907 | 2026-01-18 00:32:05.320188 | TASK [add-build-sshkey : Add back temp key] 2026-01-18 00:32:05.669468 | controller -> localhost | Identity added: /var/lib/zuul/builds/4d3fafec3503402aa78707fdc95c149e/work/4d3fafec3503402aa78707fdc95c149e_id_rsa (zuul-build-sshkey) 2026-01-18 00:32:05.670000 | controller -> localhost | ok: Runtime: 0:00:00.011547 2026-01-18 00:32:05.692197 | 2026-01-18 00:32:05.692823 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-01-18 00:32:06.594328 | controller | ok 2026-01-18 00:32:06.600841 | 2026-01-18 00:32:06.600931 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-01-18 00:32:06.648193 | controller | skipping: Conditional result was False 2026-01-18 00:32:06.670882 | 2026-01-18 00:32:06.671115 | TASK [Run add-build-sshkey role (ECDSA)] 2026-01-18 00:32:06.711211 | controller | ok 2026-01-18 00:32:06.734345 | 2026-01-18 00:32:06.734496 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-01-18 00:32:06.996835 | controller -> localhost | ok 2026-01-18 00:32:07.004668 | 2026-01-18 00:32:07.004849 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-01-18 00:32:07.031878 | controller | ok 2026-01-18 00:32:07.050527 | controller | included: /var/lib/zuul/builds/4d3fafec3503402aa78707fdc95c149e/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-01-18 00:32:07.060114 | 2026-01-18 00:32:07.060248 | TASK [add-build-sshkey : Create Temp SSH key] 2026-01-18 00:32:07.404246 | controller -> localhost | Generating public/private ecdsa key pair. 2026-01-18 00:32:07.404848 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/4d3fafec3503402aa78707fdc95c149e/work/4d3fafec3503402aa78707fdc95c149e_id_ecdsa. 2026-01-18 00:32:07.404935 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/4d3fafec3503402aa78707fdc95c149e/work/4d3fafec3503402aa78707fdc95c149e_id_ecdsa.pub. 2026-01-18 00:32:07.405002 | controller -> localhost | The key fingerprint is: 2026-01-18 00:32:07.405048 | controller -> localhost | SHA256:tHEn+U+zhvfi7MlYai4LmTvCVjoOKJeet31eNvGrXGs zuul-build-sshkey 2026-01-18 00:32:07.405091 | controller -> localhost | The key's randomart image is: 2026-01-18 00:32:07.405132 | controller -> localhost | +---[ECDSA 521]---+ 2026-01-18 00:32:07.405171 | controller -> localhost | | | 2026-01-18 00:32:07.405211 | controller -> localhost | | . | 2026-01-18 00:32:07.405250 | controller -> localhost | | o + . | 2026-01-18 00:32:07.405289 | controller -> localhost | | . + + | 2026-01-18 00:32:07.405328 | controller -> localhost | | S . . o | 2026-01-18 00:32:07.405371 | controller -> localhost | | o .o o + o | 2026-01-18 00:32:07.405409 | controller -> localhost | |. + .. o+ + + * | 2026-01-18 00:32:07.405447 | controller -> localhost | | + ..o* o*.oE@.o | 2026-01-18 00:32:07.405485 | controller -> localhost | | o..+o=o.+B*o*..| 2026-01-18 00:32:07.405523 | controller -> localhost | +----[SHA256]-----+ 2026-01-18 00:32:07.405632 | controller -> localhost | ok: Runtime: 0:00:00.017074 2026-01-18 00:32:07.421090 | 2026-01-18 00:32:07.421241 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-01-18 00:32:07.472033 | controller | ok 2026-01-18 00:32:07.489367 | controller | included: /var/lib/zuul/builds/4d3fafec3503402aa78707fdc95c149e/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-01-18 00:32:07.502090 | 2026-01-18 00:32:07.502182 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-01-18 00:32:07.538980 | controller | skipping: Conditional result was False 2026-01-18 00:32:07.550579 | 2026-01-18 00:32:07.550766 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-01-18 00:32:08.329111 | controller | changed 2026-01-18 00:32:08.343495 | 2026-01-18 00:32:08.343636 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-01-18 00:32:08.965097 | controller | ok 2026-01-18 00:32:08.973550 | 2026-01-18 00:32:08.973651 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-01-18 00:32:11.620779 | controller | changed 2026-01-18 00:32:11.627010 | 2026-01-18 00:32:11.627092 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-01-18 00:32:14.295863 | controller | changed 2026-01-18 00:32:14.310096 | 2026-01-18 00:32:14.310325 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-01-18 00:32:14.338283 | controller | skipping: Conditional result was False 2026-01-18 00:32:14.353054 | 2026-01-18 00:32:14.353241 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-01-18 00:32:14.669037 | controller -> localhost | changed 2026-01-18 00:32:14.682307 | 2026-01-18 00:32:14.682474 | TASK [add-build-sshkey : Add back temp key] 2026-01-18 00:32:15.038814 | controller -> localhost | Identity added: /var/lib/zuul/builds/4d3fafec3503402aa78707fdc95c149e/work/4d3fafec3503402aa78707fdc95c149e_id_ecdsa (zuul-build-sshkey) 2026-01-18 00:32:15.039072 | controller -> localhost | ok: Runtime: 0:00:00.011869 2026-01-18 00:32:15.047504 | 2026-01-18 00:32:15.047580 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-01-18 00:32:15.584851 | controller | ok 2026-01-18 00:32:15.596924 | 2026-01-18 00:32:15.597181 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-01-18 00:32:15.636560 | controller | skipping: Conditional result was False 2026-01-18 00:32:15.668582 | 2026-01-18 00:32:15.668817 | TASK [include_role : remove-zuul-sshkey] 2026-01-18 00:32:15.686336 | controller | skipping: Conditional result was False 2026-01-18 00:32:15.697270 | 2026-01-18 00:32:15.697419 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-01-18 00:32:16.180507 | controller | ok: "logs" 2026-01-18 00:32:16.181116 | controller | ok: All items complete 2026-01-18 00:32:16.181203 | 2026-01-18 00:32:16.619230 | controller | ok: "artifacts" 2026-01-18 00:32:17.055522 | controller | ok: "docs" 2026-01-18 00:32:17.070578 | 2026-01-18 00:32:17.070779 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-01-18 00:32:17.557518 | controller | changed: "logs" 2026-01-18 00:32:18.021221 | controller | changed: "artifacts" 2026-01-18 00:32:18.459441 | controller | changed: "docs" 2026-01-18 00:32:18.523214 | 2026-01-18 00:32:18.523366 | PLAY RECAP 2026-01-18 00:32:18.523425 | controller | ok: 30 changed: 16 unreachable: 0 failed: 0 skipped: 18 rescued: 0 ignored: 0 2026-01-18 00:32:18.523462 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-01-18 00:32:18.523487 | 2026-01-18 00:32:18.682806 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master] 2026-01-18 00:32:18.684631 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2026-01-18 00:32:19.321421 | 2026-01-18 00:32:19.321643 | PLAY [all] 2026-01-18 00:32:19.347848 | 2026-01-18 00:32:19.348027 | TASK [Install binary dependencies] 2026-01-18 00:32:19.399656 | controller | ok 2026-01-18 00:32:19.435714 | 2026-01-18 00:32:19.435829 | TASK [bindep : Include find tasks] 2026-01-18 00:32:19.466615 | controller | ok 2026-01-18 00:32:19.475392 | controller | included: /var/lib/zuul/builds/4d3fafec3503402aa78707fdc95c149e/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find.yaml 2026-01-18 00:32:19.483962 | 2026-01-18 00:32:19.484029 | TASK [bindep : Look for bindep.txt] 2026-01-18 00:32:20.334827 | controller | ok 2026-01-18 00:32:20.347014 | 2026-01-18 00:32:20.347170 | TASK [bindep : Define bindep_file fact] 2026-01-18 00:32:20.363585 | controller | skipping: Conditional result was False 2026-01-18 00:32:20.374102 | 2026-01-18 00:32:20.374248 | TASK [bindep : Look for other-requirements.txt] 2026-01-18 00:32:20.805587 | controller | ok 2026-01-18 00:32:20.816370 | 2026-01-18 00:32:20.816602 | TASK [bindep : Define bindep_file fact] 2026-01-18 00:32:20.853155 | controller | skipping: Conditional result was False 2026-01-18 00:32:20.864224 | 2026-01-18 00:32:20.864387 | TASK [bindep : Look for bindep fallback file] 2026-01-18 00:32:20.912093 | controller | skipping: Conditional result was False 2026-01-18 00:32:20.922300 | 2026-01-18 00:32:20.922407 | TASK [bindep : Define bindep_file fact] 2026-01-18 00:32:20.958624 | controller | skipping: Conditional result was False 2026-01-18 00:32:20.967814 | 2026-01-18 00:32:20.967920 | TASK [bindep : Include bindep tasks] 2026-01-18 00:32:21.004424 | controller | skipping: Conditional result was False 2026-01-18 00:32:21.020440 | 2026-01-18 00:32:21.020595 | TASK [bindep : Include install tasks] 2026-01-18 00:32:21.057446 | controller | skipping: Conditional result was False 2026-01-18 00:32:21.067354 | 2026-01-18 00:32:21.067467 | LOOP [bindep : Include package tasks] 2026-01-18 00:32:21.132463 | 2026-01-18 00:32:21.132722 | TASK [Run test-setup role] 2026-01-18 00:32:21.156942 | controller | ok 2026-01-18 00:32:21.182281 | 2026-01-18 00:32:21.182379 | TASK [test-setup : Check if project's tools/test-setup.sh exists] 2026-01-18 00:32:21.636661 | controller | ok 2026-01-18 00:32:21.644450 | 2026-01-18 00:32:21.644527 | TASK [test-setup : Run tools/test-setup.sh] 2026-01-18 00:32:22.010312 | controller | skipping: Conditional result was False 2026-01-18 00:32:22.063560 | 2026-01-18 00:32:22.063732 | PLAY RECAP 2026-01-18 00:32:22.063797 | controller | ok: 4 changed: 0 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2026-01-18 00:32:22.063832 | 2026-01-18 00:32:22.244847 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2026-01-18 00:32:22.245876 | PRE-RUN START: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master] 2026-01-18 00:32:22.923736 | 2026-01-18 00:32:22.923931 | PLAY [controller] 2026-01-18 00:32:22.945014 | 2026-01-18 00:32:22.945096 | TASK [Create the /root directory] 2026-01-18 00:32:23.938819 | controller | ok 2026-01-18 00:32:23.959601 | 2026-01-18 00:32:23.959939 | TASK [Install glibc-langpack-en] 2026-01-18 00:32:28.436452 | controller | ok: Nothing to do 2026-01-18 00:32:28.452948 | 2026-01-18 00:32:28.453203 | TASK [Ensure controller directory exists] 2026-01-18 00:32:29.178442 | controller | changed 2026-01-18 00:32:29.189644 | 2026-01-18 00:32:29.189916 | TASK [Install container runtime] 2026-01-18 00:32:29.252836 | controller | ok 2026-01-18 00:32:29.324722 | 2026-01-18 00:32:29.324927 | LOOP [ensure-docker : Gather variables for each operating system] 2026-01-18 00:32:29.382175 | controller | ok: "/var/lib/zuul/builds/4d3fafec3503402aa78707fdc95c149e/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/vars/fedora.yaml" 2026-01-18 00:32:29.399186 | 2026-01-18 00:32:29.399408 | TASK [ensure-docker : Sanity check for distro selection] 2026-01-18 00:32:29.417546 | controller | skipping: Conditional result was False 2026-01-18 00:32:29.429413 | 2026-01-18 00:32:29.429594 | TASK [ensure-docker : Sanity check for distro install] 2026-01-18 00:32:29.457201 | controller | skipping: Conditional result was False 2026-01-18 00:32:29.464658 | 2026-01-18 00:32:29.464810 | TASK [ensure-docker : Sanity check for upstream install] 2026-01-18 00:32:29.490905 | controller | skipping: Conditional result was False 2026-01-18 00:32:29.498260 | 2026-01-18 00:32:29.498399 | TASK [ensure-docker : Sanity check for upstream install mirrors] 2026-01-18 00:32:29.524584 | controller | skipping: Conditional result was False 2026-01-18 00:32:29.532445 | 2026-01-18 00:32:29.532532 | TASK [ensure-docker : Include tasks for docker install] 2026-01-18 00:32:29.554446 | controller | ok 2026-01-18 00:32:29.563640 | controller | included: /var/lib/zuul/builds/4d3fafec3503402aa78707fdc95c149e/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/tasks/docker-upstream.yaml 2026-01-18 00:32:29.573564 | 2026-01-18 00:32:29.573667 | TASK [ensure-docker : Not Implemented] 2026-01-18 00:32:29.589258 | controller | skipping: Conditional result was False 2026-01-18 00:32:29.596495 | 2026-01-18 00:32:29.596574 | TASK [Set up docker mirrors] 2026-01-18 00:32:29.617694 | controller | ok 2026-01-18 00:32:29.638192 | 2026-01-18 00:32:29.638296 | TASK [use-docker-mirror : Set docker_mirror fact from mirror_info] 2026-01-18 00:32:29.663553 | controller | skipping: Conditional result was False 2026-01-18 00:32:29.670050 | 2026-01-18 00:32:29.670123 | TASK [use-docker-mirror : Set docker_mirror fact from zuul_site_mirror_info] 2026-01-18 00:32:29.695107 | controller | skipping: Conditional result was False 2026-01-18 00:32:29.703482 | 2026-01-18 00:32:29.703556 | TASK [use-docker-mirror : Set docker_mirror fact] 2026-01-18 00:32:29.728637 | controller | skipping: Conditional result was False 2026-01-18 00:32:29.735632 | 2026-01-18 00:32:29.735720 | TASK [use-docker-mirror : Set up docker mirrors] 2026-01-18 00:32:29.761185 | controller | skipping: Conditional result was False 2026-01-18 00:32:29.773466 | 2026-01-18 00:32:29.773641 | TASK [Add all repositories] 2026-01-18 00:32:29.795210 | controller | ok 2026-01-18 00:32:29.861826 | 2026-01-18 00:32:29.861997 | LOOP [ensure-package-repositories : Install repositories] 2026-01-18 00:32:29.889158 | controller | ok: "/var/lib/zuul/builds/4d3fafec3503402aa78707fdc95c149e/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-package-repositories/tasks/RedHat.yaml" 2026-01-18 00:32:29.889368 | controller | ok: All items complete 2026-01-18 00:32:29.889394 | 2026-01-18 00:32:29.907972 | controller | included: /var/lib/zuul/builds/4d3fafec3503402aa78707fdc95c149e/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-package-repositories/tasks/RedHat.yaml 2026-01-18 00:32:29.914374 | 2026-01-18 00:32:29.914446 | TASK [ensure-package-repositories : Create GPG key temp directory] 2026-01-18 00:32:30.715574 | controller | changed 2026-01-18 00:32:30.723568 | 2026-01-18 00:32:30.723754 | LOOP [ensure-package-repositories : Upload GPG keys] 2026-01-18 00:32:33.655464 | controller | changed: 2026-01-18 00:32:33.655965 | controller | { 2026-01-18 00:32:33.656032 | 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" 2026-01-18 00:32:33.656085 | controller | } 2026-01-18 00:32:33.680089 | 2026-01-18 00:32:33.680298 | LOOP [ensure-package-repositories : Import GPG keys] 2026-01-18 00:32:34.596340 | controller | changed: 2026-01-18 00:32:34.596923 | controller | { 2026-01-18 00:32:34.596997 | 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" 2026-01-18 00:32:34.597063 | controller | } 2026-01-18 00:32:34.597141 | controller | changed: All items complete 2026-01-18 00:32:34.597187 | 2026-01-18 00:32:34.627434 | 2026-01-18 00:32:34.627829 | TASK [ensure-package-repositories : Remove GPG temp directory] 2026-01-18 00:32:35.137732 | controller | changed 2026-01-18 00:32:35.144450 | 2026-01-18 00:32:35.144528 | LOOP [ensure-package-repositories : Add repositories] 2026-01-18 00:32:35.797932 | controller | changed: 2026-01-18 00:32:35.798174 | controller | { 2026-01-18 00:32:35.798200 | controller | "baseurl": "https://download.docker.com/linux/fedora/$releasever/$basearch/stable", 2026-01-18 00:32:35.798219 | controller | "description": "Docker CE Stable - $basearch", 2026-01-18 00:32:35.798236 | controller | "gpgcheck": true, 2026-01-18 00:32:35.798252 | controller | "name": "docker-ce-stable" 2026-01-18 00:32:35.798268 | controller | } 2026-01-18 00:32:35.821613 | 2026-01-18 00:32:35.821794 | TASK [ensure-docker : Install pre-reqs] 2026-01-18 00:32:38.650115 | controller | ok: Nothing to do 2026-01-18 00:32:38.658290 | 2026-01-18 00:32:38.658439 | TASK [ensure-docker : Remove packages] 2026-01-18 00:32:40.572160 | controller | ok: Nothing to do 2026-01-18 00:32:40.581530 | 2026-01-18 00:32:40.581641 | TASK [ensure-docker : Install upstream docker using package] 2026-01-18 00:33:28.243604 | controller | changed 2026-01-18 00:33:28.259079 | 2026-01-18 00:33:28.259328 | TASK [ensure-docker : Setup docker] 2026-01-18 00:33:28.299412 | controller | ok 2026-01-18 00:33:28.318822 | controller | included: /var/lib/zuul/builds/4d3fafec3503402aa78707fdc95c149e/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/tasks/docker-setup.yaml 2026-01-18 00:33:28.338272 | 2026-01-18 00:33:28.338465 | TASK [ensure-docker : Ensure "docker" group exists] 2026-01-18 00:33:29.273206 | controller | ok 2026-01-18 00:33:29.287016 | 2026-01-18 00:33:29.287279 | TASK [ensure-docker : Add user to docker group] 2026-01-18 00:33:30.168454 | controller | changed 2026-01-18 00:33:30.181509 | 2026-01-18 00:33:30.181688 | TASK [Update docker daemon proxy configuration] 2026-01-18 00:33:30.220103 | controller | skipping: Conditional result was False 2026-01-18 00:33:30.237754 | 2026-01-18 00:33:30.237957 | TASK [Lower default MTU] 2026-01-18 00:33:30.267414 | controller | skipping: Conditional result was False 2026-01-18 00:33:30.284296 | 2026-01-18 00:33:30.284461 | TASK [ensure-docker : Get the status of the docket.socket unit] 2026-01-18 00:33:31.032406 | controller | inactive 2026-01-18 00:33:31.203338 | controller | ok: Runtime: 0:00:00.011497 2026-01-18 00:33:31.211087 | 2026-01-18 00:33:31.211235 | TASK [ensure-docker : Get the status of the docket unit] 2026-01-18 00:33:31.695955 | controller | inactive 2026-01-18 00:33:32.065517 | controller | ok: Runtime: 0:00:00.007867 2026-01-18 00:33:32.080345 | 2026-01-18 00:33:32.080513 | TASK [ensure-docker : Restart docker] 2026-01-18 00:33:32.145751 | Trigger a Docker restart 2026-01-18 00:33:32.159006 | 2026-01-18 00:33:32.159170 | TASK [ensure-docker : Reset ssh connection to pick up docker group] 2026-01-18 00:33:32.208556 | 2026-01-18 00:33:32.208731 | TASK [ensure-docker : Install docker-compose] 2026-01-18 00:33:32.235114 | controller | skipping: Conditional result was False 2026-01-18 00:33:32.246141 | 2026-01-18 00:33:32.246285 | TASK [ensure-docker : Flush handlers before role exit] 2026-01-18 00:33:32.268098 | 2026-01-18 00:33:32.268323 | TASK [ensure-docker : Stop docker.socket to avoid any conflict] 2026-01-18 00:33:35.093234 | controller | changed 2026-01-18 00:33:35.101252 | 2026-01-18 00:33:35.101427 | TASK [ensure-docker : Assure docker service is running] 2026-01-18 00:33:37.265380 | controller | changed 2026-01-18 00:33:37.284169 | 2026-01-18 00:33:37.284389 | TASK [ensure-docker : Assure docker.socket service is running] 2026-01-18 00:33:38.560471 | controller | ok 2026-01-18 00:33:38.569351 | 2026-01-18 00:33:38.569468 | TASK [ensure-docker : Correct group ownership on docker sock] 2026-01-18 00:33:39.195215 | controller | ok 2026-01-18 00:33:39.208433 | 2026-01-18 00:33:39.208617 | TASK [ensure-docker : Validate ability to talk with docker] 2026-01-18 00:33:39.915722 | controller | CONTAINER ID IMAGE COMMAND CREATED STATUS PORTS NAMES 2026-01-18 00:33:40.081340 | controller | ok: Runtime: 0:00:00.043510 2026-01-18 00:33:40.095016 | 2026-01-18 00:33:40.095160 | TASK [ensure-docker : Get version details] 2026-01-18 00:33:40.717374 | controller | Client: Docker Engine - Community 2026-01-18 00:33:40.718625 | controller | Version: 24.0.7 2026-01-18 00:33:40.718637 | controller | API version: 1.43 2026-01-18 00:33:40.718642 | controller | Go version: go1.20.10 2026-01-18 00:33:40.718654 | controller | Git commit: afdd53b 2026-01-18 00:33:40.718658 | controller | Built: Thu Oct 26 09:09:39 2023 2026-01-18 00:33:40.718663 | controller | OS/Arch: linux/amd64 2026-01-18 00:33:40.718669 | controller | Context: default 2026-01-18 00:33:40.718675 | controller | 2026-01-18 00:33:40.718680 | controller | Server: Docker Engine - Community 2026-01-18 00:33:40.718684 | controller | Engine: 2026-01-18 00:33:40.718688 | controller | Version: 24.0.7 2026-01-18 00:33:40.718692 | controller | API version: 1.43 (minimum version 1.12) 2026-01-18 00:33:40.718696 | controller | Go version: go1.20.10 2026-01-18 00:33:40.718701 | controller | Git commit: 311b9ff 2026-01-18 00:33:40.718705 | controller | Built: Thu Oct 26 09:08:11 2023 2026-01-18 00:33:40.718709 | controller | OS/Arch: linux/amd64 2026-01-18 00:33:40.718713 | controller | Experimental: false 2026-01-18 00:33:40.718717 | controller | containerd: 2026-01-18 00:33:40.718721 | controller | Version: 1.6.25 2026-01-18 00:33:40.718725 | controller | GitCommit: d8f198a4ed8892c764191ef7b3b06d8a2eeb5c7f 2026-01-18 00:33:40.718729 | controller | runc: 2026-01-18 00:33:40.718733 | controller | Version: 1.1.10 2026-01-18 00:33:40.718737 | controller | GitCommit: v1.1.10-0-g18a0cb0 2026-01-18 00:33:40.718744 | controller | docker-init: 2026-01-18 00:33:40.718748 | controller | Version: 0.19.0 2026-01-18 00:33:40.718752 | controller | GitCommit: de40ad0 2026-01-18 00:33:40.965918 | controller | ok: Runtime: 0:00:00.086039 2026-01-18 00:33:40.977564 | 2026-01-18 00:33:40.977729 | TASK [ensure-docker : Dump installed docker details] 2026-01-18 00:33:41.012650 | {'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': '2026-01-18 00:33:40.632913', 'end': '2026-01-18 00:33:40.718952', 'delta': '0:00:00.086039', 'msg': '', 'zuul_log_id': 'fa163e3b-3c83-631f-e31b-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} 2026-01-18 00:33:41.018953 | 2026-01-18 00:33:41.019072 | TASK [Use buildset registry] 2026-01-18 00:33:41.033980 | controller | skipping: Conditional result was False 2026-01-18 00:33:41.046068 | 2026-01-18 00:33:41.046224 | TASK [Ensure python3.8 is present] 2026-01-18 00:33:41.061767 | controller | skipping: Conditional result was False 2026-01-18 00:33:41.070281 | 2026-01-18 00:33:41.070427 | TASK [Run our-ensure-python role (Fedora only for now)] 2026-01-18 00:33:41.094793 | controller | ok 2026-01-18 00:33:41.122309 | 2026-01-18 00:33:41.122471 | TASK [our-ensure-python : Install the right Python version (rpm)] 2026-01-18 00:33:45.403661 | controller | ok: Nothing to do 2026-01-18 00:33:45.418590 | 2026-01-18 00:33:45.418778 | TASK [our-ensure-python : Also install python3-devel] 2026-01-18 00:33:58.220181 | controller | changed 2026-01-18 00:33:58.232512 | 2026-01-18 00:33:58.232630 | TASK [Run ensure-virtualenv role] 2026-01-18 00:33:58.254494 | controller | ok 2026-01-18 00:33:58.279504 | 2026-01-18 00:33:58.279660 | TASK [ensure-virtualenv : Check if virtualenv is installed] 2026-01-18 00:33:58.952773 | controller | /usr/bin/virtualenv 2026-01-18 00:33:59.133352 | controller | ok: Runtime: 0:00:00.003452 2026-01-18 00:33:59.144934 | 2026-01-18 00:33:59.145123 | LOOP [ensure-virtualenv : Install virtualenv package] 2026-01-18 00:33:59.170627 | controller | skipping: Conditional result was False 2026-01-18 00:33:59.171045 | controller | changed: All items complete 2026-01-18 00:33:59.171077 | 2026-01-18 00:33:59.186376 | 2026-01-18 00:33:59.186521 | TASK [Find the full path of the Python interpreter] 2026-01-18 00:33:59.675258 | controller | /usr/bin/python3.9 2026-01-18 00:34:00.045058 | controller | ok 2026-01-18 00:34:00.056066 | 2026-01-18 00:34:00.056187 | TASK [Create virtualenv for ansible-test with a specific interpreter] 2026-01-18 00:34:01.739082 | controller | created virtual environment CPython3.9.18.final.0-64 in 775ms 2026-01-18 00:34:01.759820 | controller | creator CPython3Posix(dest=/home/zuul/venv, clear=False, no_vcs_ignore=False, global=False) 2026-01-18 00:34:01.759834 | 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) 2026-01-18 00:34:01.759849 | controller | added seed packages: pip==22.2.2, setuptools==62.6.0, wheel==0.37.1 2026-01-18 00:34:01.759861 | controller | activators BashActivator,CShellActivator,FishActivator,NushellActivator,PowerShellActivator,PythonActivator 2026-01-18 00:34:01.911407 | controller | changed 2026-01-18 00:34:01.930625 | 2026-01-18 00:34:01.930990 | TASK [Set selinux package] 2026-01-18 00:34:01.973421 | controller | ok 2026-01-18 00:34:01.986619 | 2026-01-18 00:34:01.986854 | TASK [Set selinux package (Fedora)] 2026-01-18 00:34:02.036090 | controller | ok 2026-01-18 00:34:02.043781 | 2026-01-18 00:34:02.043885 | TASK [Install selinux into virtualenv] 2026-01-18 00:34:05.843017 | controller | Collecting selinux-please-lie-to-me 2026-01-18 00:34:06.008434 | controller | Downloading selinux_please_lie_to_me-1.0.1-py2.py3-none-any.whl (3.8 kB) 2026-01-18 00:34:06.915048 | controller | Collecting setuptools<50.0.0 2026-01-18 00:34:06.930940 | controller | Downloading setuptools-49.6.0-py3-none-any.whl (803 kB) 2026-01-18 00:34:06.991347 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 803.3/803.3 kB 14.6 MB/s eta 0:00:00 2026-01-18 00:34:07.056000 | controller | Installing collected packages: setuptools, selinux-please-lie-to-me 2026-01-18 00:34:07.056241 | controller | Attempting uninstall: setuptools 2026-01-18 00:34:07.056866 | controller | Found existing installation: setuptools 62.6.0 2026-01-18 00:34:07.203740 | controller | Uninstalling setuptools-62.6.0: 2026-01-18 00:34:07.226352 | controller | Successfully uninstalled setuptools-62.6.0 2026-01-18 00:34:08.058533 | controller | Successfully installed selinux-please-lie-to-me-1.0.1 setuptools-49.6.0 2026-01-18 00:34:08.222324 | controller | 2026-01-18 00:34:08.314638 | controller | [notice] A new release of pip available: 22.2.2 -> 25.3 2026-01-18 00:34:08.314652 | controller | [notice] To update, run: /home/zuul/venv/bin/python -m pip install --upgrade pip 2026-01-18 00:34:08.914154 | controller | ok: Runtime: 0:00:05.425259 2026-01-18 00:34:08.936948 | 2026-01-18 00:34:08.937230 | TASK [Install pytest-forked into virtualenv] 2026-01-18 00:34:10.681780 | controller | Collecting pytest-forked 2026-01-18 00:34:10.767594 | controller | Downloading pytest_forked-1.6.0-py3-none-any.whl (4.9 kB) 2026-01-18 00:34:10.841930 | controller | Collecting py 2026-01-18 00:34:10.853594 | controller | Downloading py-1.11.0-py2.py3-none-any.whl (98 kB) 2026-01-18 00:34:10.896315 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 98.7/98.7 kB 2.4 MB/s eta 0:00:00 2026-01-18 00:34:11.154127 | controller | Collecting pytest>=3.10 2026-01-18 00:34:11.165669 | controller | Downloading pytest-8.4.2-py3-none-any.whl (365 kB) 2026-01-18 00:34:11.187417 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 365.8/365.8 kB 20.8 MB/s eta 0:00:00 2026-01-18 00:34:11.258080 | controller | Collecting iniconfig>=1 2026-01-18 00:34:11.262011 | controller | Downloading iniconfig-2.1.0-py3-none-any.whl (6.0 kB) 2026-01-18 00:34:11.364968 | controller | Collecting pygments>=2.7.2 2026-01-18 00:34:11.375900 | controller | Downloading pygments-2.19.2-py3-none-any.whl (1.2 MB) 2026-01-18 00:34:11.406885 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 1.2/1.2 MB 46.9 MB/s eta 0:00:00 2026-01-18 00:34:11.542116 | controller | Collecting tomli>=1 2026-01-18 00:34:11.554496 | controller | Downloading tomli-2.4.0-py3-none-any.whl (14 kB) 2026-01-18 00:34:11.610932 | controller | Collecting exceptiongroup>=1 2026-01-18 00:34:11.621346 | controller | Downloading exceptiongroup-1.3.1-py3-none-any.whl (16 kB) 2026-01-18 00:34:11.716257 | controller | Collecting packaging>=20 2026-01-18 00:34:11.722113 | controller | Downloading packaging-25.0-py3-none-any.whl (66 kB) 2026-01-18 00:34:11.733128 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 66.5/66.5 kB 9.0 MB/s eta 0:00:00 2026-01-18 00:34:11.784076 | controller | Collecting pluggy<2,>=1.5 2026-01-18 00:34:11.796880 | controller | Downloading pluggy-1.6.0-py3-none-any.whl (20 kB) 2026-01-18 00:34:11.906511 | controller | Collecting typing-extensions>=4.6.0 2026-01-18 00:34:11.915147 | controller | Downloading typing_extensions-4.15.0-py3-none-any.whl (44 kB) 2026-01-18 00:34:11.924816 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 44.6/44.6 kB 7.0 MB/s eta 0:00:00 2026-01-18 00:34:12.004916 | controller | Installing collected packages: typing-extensions, tomli, pygments, py, pluggy, packaging, iniconfig, exceptiongroup, pytest, pytest-forked 2026-01-18 00:34:13.069897 | 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.4.0 typing-extensions-4.15.0 2026-01-18 00:34:13.073178 | controller | 2026-01-18 00:34:13.153290 | controller | [notice] A new release of pip available: 22.2.2 -> 25.3 2026-01-18 00:34:13.153309 | controller | [notice] To update, run: /home/zuul/venv/bin/python -m pip install --upgrade pip 2026-01-18 00:34:13.385375 | controller | ok: Runtime: 0:00:03.343777 2026-01-18 00:34:13.400488 | 2026-01-18 00:34:13.400652 | TASK [Update pip] 2026-01-18 00:34:15.174463 | controller | Requirement already satisfied: pip in ./venv/lib/python3.9/site-packages (22.2.2) 2026-01-18 00:34:15.469920 | controller | Collecting pip 2026-01-18 00:34:15.549584 | controller | Downloading pip-25.3-py3-none-any.whl (1.8 MB) 2026-01-18 00:34:15.631717 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 1.8/1.8 MB 23.6 MB/s eta 0:00:00 2026-01-18 00:34:15.773680 | controller | Installing collected packages: pip 2026-01-18 00:34:15.778392 | controller | Attempting uninstall: pip 2026-01-18 00:34:15.779120 | controller | Found existing installation: pip 22.2.2 2026-01-18 00:34:16.234576 | controller | Uninstalling pip-22.2.2: 2026-01-18 00:34:16.282275 | controller | Successfully uninstalled pip-22.2.2 2026-01-18 00:34:18.455030 | controller | Successfully installed pip-25.3 2026-01-18 00:34:19.291865 | controller | ok: Runtime: 0:00:04.406875 2026-01-18 00:34:19.308458 | 2026-01-18 00:34:19.309107 | TASK [Ensure pip temp directory exists in /var/tmp] 2026-01-18 00:34:20.284625 | controller | changed 2026-01-18 00:34:20.295247 | 2026-01-18 00:34:20.295444 | TASK [Install ansible into virtualenv] 2026-01-18 00:34:21.821893 | controller | Processing ./src/github.com/ansible/ansible 2026-01-18 00:34:21.833615 | controller | Installing build dependencies: started 2026-01-18 00:34:23.747644 | controller | Installing build dependencies: finished with status 'done' 2026-01-18 00:34:23.748567 | controller | Getting requirements to build wheel: started 2026-01-18 00:34:24.320532 | controller | Getting requirements to build wheel: finished with status 'done' 2026-01-18 00:34:24.321817 | controller | Preparing metadata (pyproject.toml): started 2026-01-18 00:34:24.756127 | controller | Preparing metadata (pyproject.toml): finished with status 'done' 2026-01-18 00:34:24.823063 | controller | Collecting jinja2 (from ansible-core==2.11.12.post0) 2026-01-18 00:34:24.854858 | controller | Downloading jinja2-3.1.6-py3-none-any.whl.metadata (2.9 kB) 2026-01-18 00:34:24.923937 | controller | Collecting PyYAML (from ansible-core==2.11.12.post0) 2026-01-18 00:34:24.927813 | 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) 2026-01-18 00:34:25.194134 | controller | Collecting cryptography (from ansible-core==2.11.12.post0) 2026-01-18 00:34:25.197656 | controller | Downloading cryptography-46.0.3-cp38-abi3-manylinux_2_34_x86_64.whl.metadata (5.7 kB) 2026-01-18 00:34:25.213430 | controller | Requirement already satisfied: packaging in ./venv/lib/python3.9/site-packages (from ansible-core==2.11.12.post0) (25.0) 2026-01-18 00:34:25.229619 | controller | Collecting resolvelib<0.6.0,>=0.5.3 (from ansible-core==2.11.12.post0) 2026-01-18 00:34:25.233735 | controller | Downloading resolvelib-0.5.4-py2.py3-none-any.whl.metadata (3.7 kB) 2026-01-18 00:34:25.388046 | controller | Collecting cffi>=2.0.0 (from cryptography->ansible-core==2.11.12.post0) 2026-01-18 00:34:25.392588 | controller | Downloading cffi-2.0.0-cp39-cp39-manylinux2014_x86_64.manylinux_2_17_x86_64.whl.metadata (2.6 kB) 2026-01-18 00:34:25.402945 | 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) 2026-01-18 00:34:25.417914 | controller | Collecting pycparser (from cffi>=2.0.0->cryptography->ansible-core==2.11.12.post0) 2026-01-18 00:34:25.420672 | controller | Downloading pycparser-2.23-py3-none-any.whl.metadata (993 bytes) 2026-01-18 00:34:25.500537 | controller | Collecting MarkupSafe>=2.0 (from jinja2->ansible-core==2.11.12.post0) 2026-01-18 00:34:25.504719 | 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) 2026-01-18 00:34:25.519447 | controller | Downloading resolvelib-0.5.4-py2.py3-none-any.whl (12 kB) 2026-01-18 00:34:25.530150 | controller | Downloading cryptography-46.0.3-cp38-abi3-manylinux_2_34_x86_64.whl (4.5 MB) 2026-01-18 00:34:25.581182 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 4.5/4.5 MB 100.9 MB/s 0:00:00 2026-01-18 00:34:25.585146 | controller | Downloading cffi-2.0.0-cp39-cp39-manylinux2014_x86_64.manylinux_2_17_x86_64.whl (216 kB) 2026-01-18 00:34:25.594519 | controller | Downloading jinja2-3.1.6-py3-none-any.whl (134 kB) 2026-01-18 00:34:25.603826 | controller | Downloading markupsafe-3.0.3-cp39-cp39-manylinux2014_x86_64.manylinux_2_17_x86_64.manylinux_2_28_x86_64.whl (20 kB) 2026-01-18 00:34:25.610107 | controller | Downloading pycparser-2.23-py3-none-any.whl (118 kB) 2026-01-18 00:34:25.619080 | controller | Downloading pyyaml-6.0.3-cp39-cp39-manylinux2014_x86_64.manylinux_2_17_x86_64.manylinux_2_28_x86_64.whl (750 kB) 2026-01-18 00:34:25.632966 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 750.8/750.8 kB 46.4 MB/s 0:00:00 2026-01-18 00:34:25.647147 | controller | Building wheels for collected packages: ansible-core 2026-01-18 00:34:25.647905 | controller | Building wheel for ansible-core (pyproject.toml): started 2026-01-18 00:34:27.132150 | controller | Building wheel for ansible-core (pyproject.toml): finished with status 'done' 2026-01-18 00:34:27.134665 | controller | Created wheel for ansible-core: filename=ansible_core-2.11.12.post0-py3-none-any.whl size=1961250 sha256=b2e43814a42943d343c7ef428e4d71433d3a546d402a8b996e9637b91ccb259f 2026-01-18 00:34:27.134858 | controller | Stored in directory: /var/tmp/ansible-test-pip/pip-ephem-wheel-cache-wxi200uv/wheels/0f/a0/93/ab85b739e1eb1e0467171316f13571c14efdf87e0d5d44a33e 2026-01-18 00:34:27.142490 | controller | Successfully built ansible-core 2026-01-18 00:34:27.175702 | controller | Installing collected packages: resolvelib, PyYAML, pycparser, MarkupSafe, jinja2, cffi, cryptography, ansible-core 2026-01-18 00:34:28.693896 | controller | 2026-01-18 00:34:28.696871 | 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 2026-01-18 00:34:29.170369 | controller | ok: Runtime: 0:00:07.787961 2026-01-18 00:34:29.183079 | 2026-01-18 00:34:29.183241 | TASK [Setup download-artifact-fork role] 2026-01-18 00:34:29.210044 | controller | ok 2026-01-18 00:34:29.244190 | 2026-01-18 00:34:29.244392 | TASK [download-artifact-fork : Parse inventory response] 2026-01-18 00:34:29.291004 | controller | ok 2026-01-18 00:34:29.301157 | 2026-01-18 00:34:29.301304 | TASK [download-artifact-fork : Fetch info from Zuul API] 2026-01-18 00:34:29.329304 | controller | skipping: Conditional result was False 2026-01-18 00:34:29.338075 | 2026-01-18 00:34:29.338482 | TASK [download-artifact-fork : Parse build response] 2026-01-18 00:34:29.375716 | controller | skipping: Conditional result was False 2026-01-18 00:34:29.388904 | 2026-01-18 00:34:29.389146 | TASK [download-artifact-fork : Ensure artifacts directory exists] 2026-01-18 00:34:30.097084 | controller | changed 2026-01-18 00:34:30.106329 | 2026-01-18 00:34:30.106441 | LOOP [download-artifact-fork : Download archive by type] 2026-01-18 00:34:30.145673 | controller | skipping: Conditional result was False 2026-01-18 00:34:30.146235 | 2026-01-18 00:34:32.527756 | controller | changed: OK (20194 bytes) 2026-01-18 00:34:32.558189 | 2026-01-18 00:34:32.558453 | TASK [deploy-artifacts : define __collections] 2026-01-18 00:34:32.595670 | controller | ok 2026-01-18 00:34:32.603365 | 2026-01-18 00:34:32.603532 | LOOP [deploy-artifacts : Create list of collections] 2026-01-18 00:34:32.645340 | controller | skipping: Conditional result was False 2026-01-18 00:34:32.657943 | controller | ok: 2026-01-18 00:34:32.658051 | controller | { 2026-01-18 00:34:32.658084 | controller | "branch": "main", 2026-01-18 00:34:32.658104 | controller | "job": "build-ansible-collection", 2026-01-18 00:34:32.658120 | controller | "metadata": { 2026-01-18 00:34:32.658138 | controller | "type": "ansible_collection", 2026-01-18 00:34:32.658154 | controller | "version": "5.0.0" 2026-01-18 00:34:32.658171 | controller | }, 2026-01-18 00:34:32.658194 | controller | "name": "ansible.network", 2026-01-18 00:34:32.658214 | controller | "project": "ansible-collections/ansible.network", 2026-01-18 00:34:32.658230 | controller | "url": "https://25a504daa72e356d7eac-445e78ce705e41bfad21670532b58dab.ssl.cf5.rackcdn.com/ansible/e72d784a469c4e21a6c812d825cf7266/artifacts/ansible-network-5.0.0.tar.gz" 2026-01-18 00:34:32.658256 | controller | } 2026-01-18 00:34:32.675124 | 2026-01-18 00:34:32.675327 | TASK [deploy-artifacts : Install require-project collection using ansible-galaxy] 2026-01-18 00:34:33.626199 | controller | Starting galaxy collection install process 2026-01-18 00:34:33.626414 | controller | Process install dependency map 2026-01-18 00:34:35.876178 | controller | [WARNING]: Skipping Galaxy server https://galaxy.ansible.com/api/. Got an 2026-01-18 00:34:35.876565 | controller | unexpected error when getting available versions of collection 2026-01-18 00:34:35.876605 | controller | ansible.netcommon: '/api/v3/plugin/ansible/content/published/collections/index/ 2026-01-18 00:34:35.876616 | controller | ansible/netcommon/versions/' 2026-01-18 00:34:35.876661 | controller | ERROR! Unexpected Exception, this is probably a bug: '/api/v3/plugin/ansible/content/published/collections/index/ansible/netcommon/versions/' 2026-01-18 00:34:35.876748 | controller | to see the full traceback, use -vvv 2026-01-18 00:34:36.540524 | controller | ERROR 2026-01-18 00:34:36.540974 | controller | { 2026-01-18 00:34:36.541047 | controller | "delta": "0:00:02.675903", 2026-01-18 00:34:36.541090 | controller | "end": "2026-01-18 00:34:35.938343", 2026-01-18 00:34:36.541128 | controller | "msg": "non-zero return code", 2026-01-18 00:34:36.541162 | controller | "rc": 250, 2026-01-18 00:34:36.541197 | controller | "start": "2026-01-18 00:34:33.262440" 2026-01-18 00:34:36.541231 | controller | } failure 2026-01-18 00:34:36.544893 | 2026-01-18 00:34:36.545012 | PLAY RECAP 2026-01-18 00:34:36.545113 | controller | ok: 44 changed: 24 unreachable: 0 failed: 1 skipped: 17 rescued: 0 ignored: 0 2026-01-18 00:34:36.545172 | 2026-01-18 00:34:36.810823 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master] 2026-01-18 00:34:36.813221 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2026-01-18 00:34:37.574009 | 2026-01-18 00:34:37.574170 | PLAY [all] 2026-01-18 00:34:37.599373 | 2026-01-18 00:34:37.599562 | TASK [fetch-subunit-output : Find stestr or testr executable] 2026-01-18 00:34:40.662348 | controller | changed: non-zero return code 2026-01-18 00:34:40.674195 | 2026-01-18 00:34:40.674540 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2026-01-18 00:34:40.702514 | controller | skipping: Conditional result was False 2026-01-18 00:34:40.713912 | 2026-01-18 00:34:40.714279 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2026-01-18 00:34:40.754764 | 2026-01-18 00:34:40.755083 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2026-01-18 00:34:40.793591 | 2026-01-18 00:34:40.793883 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2026-01-18 00:34:40.810959 | controller | skipping: Conditional result was False 2026-01-18 00:34:40.823139 | 2026-01-18 00:34:40.823327 | LOOP [fetch-subunit-output : Generate subunit file] 2026-01-18 00:34:40.861367 | 2026-01-18 00:34:40.861751 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2026-01-18 00:34:40.879268 | controller | skipping: Conditional result was False 2026-01-18 00:34:40.895325 | 2026-01-18 00:34:40.895481 | TASK [fetch-subunit-output : Remove the temporary file] 2026-01-18 00:34:40.913169 | controller | skipping: Conditional result was False 2026-01-18 00:34:40.926049 | 2026-01-18 00:34:40.926198 | TASK [fetch-subunit-output : Process and fetch subunit results] 2026-01-18 00:34:40.952774 | controller | skipping: Conditional result was False 2026-01-18 00:34:41.003458 | 2026-01-18 00:34:41.003638 | PLAY RECAP 2026-01-18 00:34:41.003716 | controller | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2026-01-18 00:34:41.003748 | 2026-01-18 00:34:41.198940 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2026-01-18 00:34:41.200186 | POST-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/post.yaml@master] 2026-01-18 00:34:41.887541 | 2026-01-18 00:34:41.887725 | PLAY [all:!appliance*] 2026-01-18 00:34:41.912760 | 2026-01-18 00:34:41.912946 | TASK [unregister the node] 2026-01-18 00:34:42.269482 | controller | skipping: Conditional result was False 2026-01-18 00:34:42.289102 | 2026-01-18 00:34:42.289437 | TASK [include_role : fetch-output] 2026-01-18 00:34:42.346773 | controller | ok 2026-01-18 00:34:42.376505 | 2026-01-18 00:34:42.376709 | TASK [fetch-output : Set log path for multiple nodes] 2026-01-18 00:34:42.455771 | controller | skipping: Conditional result was False 2026-01-18 00:34:42.465285 | 2026-01-18 00:34:42.465399 | TASK [fetch-output : Set log path for single node] 2026-01-18 00:34:42.513175 | controller | ok 2026-01-18 00:34:42.525232 | 2026-01-18 00:34:42.525420 | LOOP [fetch-output : Ensure local output dirs] 2026-01-18 00:34:43.030921 | controller -> localhost | ok: "/var/lib/zuul/builds/4d3fafec3503402aa78707fdc95c149e/work/logs" 2026-01-18 00:34:43.309263 | controller -> localhost | changed: "/var/lib/zuul/builds/4d3fafec3503402aa78707fdc95c149e/work/artifacts" 2026-01-18 00:34:43.575208 | controller -> localhost | changed: "/var/lib/zuul/builds/4d3fafec3503402aa78707fdc95c149e/work/docs" 2026-01-18 00:34:43.613546 | 2026-01-18 00:34:43.613828 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-01-18 00:34:45.902388 | controller | changed: 2026-01-18 00:34:45.903063 | controller | .d..t...... ./ 2026-01-18 00:34:45.903139 | controller | cd+++++++++ controller/ 2026-01-18 00:34:45.903326 | controller | changed: All items complete 2026-01-18 00:34:45.903376 | 2026-01-18 00:34:47.974968 | controller | changed: .d..t...... ./ 2026-01-18 00:34:50.057726 | controller | changed: .d..t...... ./ 2026-01-18 00:34:50.087576 | 2026-01-18 00:34:50.087927 | TASK [include_role : fetch-output-openshift] 2026-01-18 00:34:50.114047 | controller | skipping: Conditional result was False 2026-01-18 00:34:50.125500 | 2026-01-18 00:34:50.125751 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-01-18 00:34:50.207509 | controller | skipping: Conditional result was False 2026-01-18 00:34:50.208916 | controller | skipping: Conditional result was False 2026-01-18 00:34:50.250861 | 2026-01-18 00:34:50.250986 | PLAY [localhost] 2026-01-18 00:34:50.267806 | 2026-01-18 00:34:50.267955 | TASK [Run Zuul manifest role] 2026-01-18 00:34:50.288556 | localhost | ok 2026-01-18 00:34:50.309598 | 2026-01-18 00:34:50.309791 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-01-18 00:34:50.733504 | localhost | changed 2026-01-18 00:34:50.739177 | 2026-01-18 00:34:50.739248 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-01-18 00:34:50.776626 | localhost | ok 2026-01-18 00:34:50.788424 | 2026-01-18 00:34:50.788563 | TASK [Set zuul-log-path fact] 2026-01-18 00:34:50.852497 | localhost | ok 2026-01-18 00:34:50.872454 | 2026-01-18 00:34:50.872631 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-01-18 00:34:50.923786 | localhost | ok 2026-01-18 00:34:50.931987 | 2026-01-18 00:34:50.932051 | LOOP [Run upload-logs-swift role] 2026-01-18 00:34:50.968973 | localhost | Output suppressed because no_log was given 2026-01-18 00:34:51.006358 | 2026-01-18 00:34:51.006510 | TASK [Set zuul-log-path fact] 2026-01-18 00:34:51.042440 | localhost | skipping: Conditional result was False 2026-01-18 00:34:51.048964 | 2026-01-18 00:34:51.049064 | TASK [upload-logs-swift : Ensure logs are readable before uploading] 2026-01-18 00:34:51.465269 | localhost -> localhost | ok: Runtime: 0:00:00.006273 2026-01-18 00:34:51.475275 | 2026-01-18 00:34:51.475420 | TASK [upload-logs-swift : Upload logs to swift]