2026-01-13 00:23:13.563182 | Job console starting... 2026-01-13 00:23:13.576139 | Updating repositories 2026-01-13 00:23:13.707597 | Preparing job workspace 2026-01-13 00:23:17.586154 | Running Ansible setup... 2026-01-13 00:23:24.979093 | PRE-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master] 2026-01-13 00:23:25.605274 | 2026-01-13 00:23:25.605414 | PLAY [localhost] 2026-01-13 00:23:25.614371 | 2026-01-13 00:23:25.614446 | TASK [Gathering Facts] 2026-01-13 00:23:26.663407 | localhost | ok 2026-01-13 00:23:26.691905 | 2026-01-13 00:23:26.692116 | TASK [Setup log path fact] 2026-01-13 00:23:26.718784 | localhost | ok 2026-01-13 00:23:26.742093 | 2026-01-13 00:23:26.742198 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-01-13 00:23:26.773856 | localhost | ok 2026-01-13 00:23:26.783910 | 2026-01-13 00:23:26.783980 | TASK [emit-job-header : Print job information] 2026-01-13 00:23:26.823031 | # Job Information 2026-01-13 00:23:26.823163 | Ansible Version: 2.15.12 2026-01-13 00:23:26.823196 | Job: ansible-test-sanity-docker-stable-2.11 2026-01-13 00:23:26.823216 | Pipeline: periodic 2026-01-13 00:23:26.823234 | Executor: ze02.softwarefactory-project.io 2026-01-13 00:23:26.823251 | Triggered by: https://github.com/ansible-collections/ansible.network 2026-01-13 00:23:26.823271 | Log URL (when completed): https://ansible.softwarefactory-project.io/logs/15b/ansible/15b2bf71563d439e8d57adca22ad30b4/ 2026-01-13 00:23:26.823290 | Event ID: c4f09769d5af45b29afe2e93570d6015 2026-01-13 00:23:26.826939 | 2026-01-13 00:23:26.827001 | LOOP [emit-job-header : Print node information] 2026-01-13 00:23:26.952924 | localhost | ok: 2026-01-13 00:23:26.953238 | localhost | # Node Information 2026-01-13 00:23:26.953267 | localhost | Inventory Hostname: controller 2026-01-13 00:23:26.953287 | localhost | Hostname: np0005582390 2026-01-13 00:23:26.953305 | localhost | Username: zuul 2026-01-13 00:23:26.953325 | localhost | Distro: Fedora 37 2026-01-13 00:23:26.953342 | localhost | Provider: ansible-vexxhost-ams1 2026-01-13 00:23:26.953359 | localhost | Region: ams1 2026-01-13 00:23:26.953375 | localhost | Label: ansible-fedora-37-1vcpu 2026-01-13 00:23:26.953391 | localhost | Product Name: OpenStack Nova 2026-01-13 00:23:26.953407 | localhost | Interface IP: 38.129.16.99 2026-01-13 00:23:26.971158 | 2026-01-13 00:23:26.971232 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-01-13 00:23:27.435137 | localhost -> localhost | changed 2026-01-13 00:23:27.441151 | 2026-01-13 00:23:27.441221 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-01-13 00:23:28.446957 | localhost -> localhost | changed 2026-01-13 00:23:28.467561 | 2026-01-13 00:23:28.467651 | PLAY [all:!appliance*] 2026-01-13 00:23:28.483790 | 2026-01-13 00:23:28.483865 | TASK [include_role : start-zuul-console] 2026-01-13 00:23:28.513245 | controller | ok 2026-01-13 00:23:28.527694 | 2026-01-13 00:23:28.527776 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-01-13 00:23:29.584322 | controller | ok 2026-01-13 00:23:29.608984 | 2026-01-13 00:23:29.609133 | TASK [use-our-mirror : Retrieve the IP address] 2026-01-13 00:23:32.054729 | controller | ok: Status code was 404 and not [200]: HTTP Error 404: Not Found 2026-01-13 00:23:32.070361 | 2026-01-13 00:23:32.070516 | TASK [use-our-mirror : Ensure the registry service is running on the mirror] 2026-01-13 00:23:32.445129 | controller | skipping: Conditional result was False 2026-01-13 00:23:32.461091 | 2026-01-13 00:23:32.461254 | TASK [use-our-mirror : Write the IP in /etc/hosts] 2026-01-13 00:23:32.490028 | controller | skipping: Conditional result was False 2026-01-13 00:23:32.504239 | 2026-01-13 00:23:32.504376 | TASK [use-our-mirror : Create the podman configuration directory] 2026-01-13 00:23:32.531180 | controller | skipping: Conditional result was False 2026-01-13 00:23:32.544808 | 2026-01-13 00:23:32.544964 | TASK [use-our-mirror : Copy the podman configuration] 2026-01-13 00:23:32.572327 | controller | skipping: Conditional result was False 2026-01-13 00:23:32.588001 | 2026-01-13 00:23:32.588165 | TASK [use-our-mirror : Copy the Yum configuration (main)] 2026-01-13 00:23:32.615382 | controller | skipping: Conditional result was False 2026-01-13 00:23:32.630958 | 2026-01-13 00:23:32.631129 | TASK [use-our-mirror : Copy the Yum configuration (update)] 2026-01-13 00:23:32.658294 | controller | skipping: Conditional result was False 2026-01-13 00:23:32.684592 | 2026-01-13 00:23:32.684778 | TASK [Disable Fedora Modular] 2026-01-13 00:23:33.990577 | controller | changed 2026-01-13 00:23:34.003263 | 2026-01-13 00:23:34.003462 | TASK [Enable EPEL] 2026-01-13 00:23:34.030868 | controller | skipping: Conditional result was False 2026-01-13 00:23:34.044414 | 2026-01-13 00:23:34.044574 | TASK [Register the RHEL node] 2026-01-13 00:23:34.466770 | 2026-01-13 00:23:34.467073 | TASK [Show the subscription-manager status] 2026-01-13 00:23:34.844929 | controller | skipping: Conditional result was False 2026-01-13 00:23:34.860457 | 2026-01-13 00:23:34.860642 | TASK [Enable EPEL on RHEL] 2026-01-13 00:23:35.225878 | controller | skipping: Conditional result was False 2026-01-13 00:23:35.240512 | 2026-01-13 00:23:35.240718 | TASK [Install git and tox] 2026-01-13 00:25:46.748519 | controller | changed 2026-01-13 00:25:46.767482 | 2026-01-13 00:25:46.767710 | TASK [include_role : prepare-workspace] 2026-01-13 00:25:46.805760 | controller | ok 2026-01-13 00:25:46.843750 | 2026-01-13 00:25:46.844077 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-01-13 00:25:47.690047 | controller | ok 2026-01-13 00:25:47.696221 | 2026-01-13 00:25:47.696279 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-01-13 00:26:03.455935 | controller | Output suppressed because no_log was given 2026-01-13 00:26:03.480373 | 2026-01-13 00:26:03.480556 | TASK [include_role : prepare-workspace-openshift] 2026-01-13 00:26:03.507584 | controller | skipping: Conditional result was False 2026-01-13 00:26:03.580274 | 2026-01-13 00:26:03.580390 | PLAY [all:!appliance] 2026-01-13 00:26:03.597419 | 2026-01-13 00:26:03.597513 | TASK [Run add-build-sshkey role (RSA)] 2026-01-13 00:26:03.627984 | controller | ok 2026-01-13 00:26:03.646412 | 2026-01-13 00:26:03.646534 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-01-13 00:26:03.875793 | controller -> localhost | ok 2026-01-13 00:26:03.882539 | 2026-01-13 00:26:03.882603 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-01-13 00:26:03.902733 | controller | ok 2026-01-13 00:26:03.916237 | controller | included: /var/lib/zuul/builds/15b2bf71563d439e8d57adca22ad30b4/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-01-13 00:26:03.922726 | 2026-01-13 00:26:03.922786 | TASK [add-build-sshkey : Create Temp SSH key] 2026-01-13 00:26:04.415538 | controller -> localhost | Generating public/private rsa key pair. 2026-01-13 00:26:04.415900 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/15b2bf71563d439e8d57adca22ad30b4/work/15b2bf71563d439e8d57adca22ad30b4_id_rsa. 2026-01-13 00:26:04.415944 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/15b2bf71563d439e8d57adca22ad30b4/work/15b2bf71563d439e8d57adca22ad30b4_id_rsa.pub. 2026-01-13 00:26:04.415976 | controller -> localhost | The key fingerprint is: 2026-01-13 00:26:04.416004 | controller -> localhost | SHA256:XenULvYhYiLOn3AQUHtIlHJZZz924MKetLtjtZrD+co zuul-build-sshkey 2026-01-13 00:26:04.416031 | controller -> localhost | The key's randomart image is: 2026-01-13 00:26:04.416057 | controller -> localhost | +---[RSA 2048]----+ 2026-01-13 00:26:04.416082 | controller -> localhost | | .o++. o . | 2026-01-13 00:26:04.416109 | controller -> localhost | | .o+o + o + | 2026-01-13 00:26:04.416135 | controller -> localhost | | o+ . + O o | 2026-01-13 00:26:04.416160 | controller -> localhost | | o + O + | 2026-01-13 00:26:04.416185 | controller -> localhost | | o S B = o | 2026-01-13 00:26:04.416225 | controller -> localhost | | o o o +.+ . | 2026-01-13 00:26:04.416260 | controller -> localhost | | + ...o .. | 2026-01-13 00:26:04.416287 | controller -> localhost | | + o*o. | 2026-01-13 00:26:04.416313 | controller -> localhost | | o.E*. | 2026-01-13 00:26:04.416343 | controller -> localhost | +----[SHA256]-----+ 2026-01-13 00:26:04.416414 | controller -> localhost | ok: Runtime: 0:00:00.093414 2026-01-13 00:26:04.425909 | 2026-01-13 00:26:04.426001 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-01-13 00:26:04.448823 | controller | ok 2026-01-13 00:26:04.463474 | controller | included: /var/lib/zuul/builds/15b2bf71563d439e8d57adca22ad30b4/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-01-13 00:26:04.476237 | 2026-01-13 00:26:04.476363 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-01-13 00:26:04.501508 | controller | skipping: Conditional result was False 2026-01-13 00:26:04.509523 | 2026-01-13 00:26:04.509598 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-01-13 00:26:05.595280 | controller | changed 2026-01-13 00:26:05.607436 | 2026-01-13 00:26:05.607591 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-01-13 00:26:06.264627 | controller | ok 2026-01-13 00:26:06.273212 | 2026-01-13 00:26:06.273315 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-01-13 00:26:09.259702 | controller | changed 2026-01-13 00:26:09.274636 | 2026-01-13 00:26:09.274899 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-01-13 00:26:12.286696 | controller | changed 2026-01-13 00:26:12.295462 | 2026-01-13 00:26:12.295556 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-01-13 00:26:12.311010 | controller | skipping: Conditional result was False 2026-01-13 00:26:12.321890 | 2026-01-13 00:26:12.322043 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-01-13 00:26:12.724435 | controller -> localhost | changed 2026-01-13 00:26:12.736166 | 2026-01-13 00:26:12.736331 | TASK [add-build-sshkey : Add back temp key] 2026-01-13 00:26:13.047031 | controller -> localhost | Identity added: /var/lib/zuul/builds/15b2bf71563d439e8d57adca22ad30b4/work/15b2bf71563d439e8d57adca22ad30b4_id_rsa (zuul-build-sshkey) 2026-01-13 00:26:13.047330 | controller -> localhost | ok: Runtime: 0:00:00.013222 2026-01-13 00:26:13.056332 | 2026-01-13 00:26:13.056418 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-01-13 00:26:13.969034 | controller | ok 2026-01-13 00:26:13.976804 | 2026-01-13 00:26:13.977015 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-01-13 00:26:14.004530 | controller | skipping: Conditional result was False 2026-01-13 00:26:14.030251 | 2026-01-13 00:26:14.030384 | TASK [Run add-build-sshkey role (ECDSA)] 2026-01-13 00:26:14.064780 | controller | ok 2026-01-13 00:26:14.087895 | 2026-01-13 00:26:14.087987 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-01-13 00:26:14.350500 | controller -> localhost | ok 2026-01-13 00:26:14.365396 | 2026-01-13 00:26:14.365532 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-01-13 00:26:14.395569 | controller | ok 2026-01-13 00:26:14.414786 | controller | included: /var/lib/zuul/builds/15b2bf71563d439e8d57adca22ad30b4/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-01-13 00:26:14.423715 | 2026-01-13 00:26:14.423798 | TASK [add-build-sshkey : Create Temp SSH key] 2026-01-13 00:26:14.693298 | controller -> localhost | Generating public/private ecdsa key pair. 2026-01-13 00:26:14.693622 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/15b2bf71563d439e8d57adca22ad30b4/work/15b2bf71563d439e8d57adca22ad30b4_id_ecdsa. 2026-01-13 00:26:14.693689 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/15b2bf71563d439e8d57adca22ad30b4/work/15b2bf71563d439e8d57adca22ad30b4_id_ecdsa.pub. 2026-01-13 00:26:14.693737 | controller -> localhost | The key fingerprint is: 2026-01-13 00:26:14.693765 | controller -> localhost | SHA256:TcJxZ6rTI6+N0tllrdlv9AGaHPe2jIv2DJ9dHbF7sxw zuul-build-sshkey 2026-01-13 00:26:14.693793 | controller -> localhost | The key's randomart image is: 2026-01-13 00:26:14.693819 | controller -> localhost | +---[ECDSA 521]---+ 2026-01-13 00:26:14.693843 | controller -> localhost | | . . o | 2026-01-13 00:26:14.693867 | controller -> localhost | | . o + | 2026-01-13 00:26:14.693891 | controller -> localhost | | o o . | 2026-01-13 00:26:14.693915 | controller -> localhost | | * . o o| 2026-01-13 00:26:14.693939 | controller -> localhost | | S = =.oo | 2026-01-13 00:26:14.693975 | controller -> localhost | | + =o .+=| 2026-01-13 00:26:14.694001 | controller -> localhost | | . o.+ ++EB| 2026-01-13 00:26:14.694024 | controller -> localhost | | . o+..B.=oO| 2026-01-13 00:26:14.694048 | controller -> localhost | | .o o..*.*.| 2026-01-13 00:26:14.694082 | controller -> localhost | +----[SHA256]-----+ 2026-01-13 00:26:14.694168 | controller -> localhost | ok: Runtime: 0:00:00.008301 2026-01-13 00:26:14.705634 | 2026-01-13 00:26:14.705803 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-01-13 00:26:14.739477 | controller | ok 2026-01-13 00:26:14.747215 | controller | included: /var/lib/zuul/builds/15b2bf71563d439e8d57adca22ad30b4/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-01-13 00:26:14.757211 | 2026-01-13 00:26:14.757274 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-01-13 00:26:14.782383 | controller | skipping: Conditional result was False 2026-01-13 00:26:14.789516 | 2026-01-13 00:26:14.789593 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-01-13 00:26:15.684146 | controller | changed 2026-01-13 00:26:15.697123 | 2026-01-13 00:26:15.697263 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-01-13 00:26:16.414364 | controller | ok 2026-01-13 00:26:16.424559 | 2026-01-13 00:26:16.424682 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-01-13 00:26:19.431120 | controller | changed 2026-01-13 00:26:19.445107 | 2026-01-13 00:26:19.445289 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-01-13 00:26:22.442605 | controller | changed 2026-01-13 00:26:22.456257 | 2026-01-13 00:26:22.456396 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-01-13 00:26:22.484625 | controller | skipping: Conditional result was False 2026-01-13 00:26:22.494306 | 2026-01-13 00:26:22.494417 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-01-13 00:26:22.734362 | controller -> localhost | changed 2026-01-13 00:26:22.746194 | 2026-01-13 00:26:22.746282 | TASK [add-build-sshkey : Add back temp key] 2026-01-13 00:26:23.106994 | controller -> localhost | Identity added: /var/lib/zuul/builds/15b2bf71563d439e8d57adca22ad30b4/work/15b2bf71563d439e8d57adca22ad30b4_id_ecdsa (zuul-build-sshkey) 2026-01-13 00:26:23.107288 | controller -> localhost | ok: Runtime: 0:00:00.015861 2026-01-13 00:26:23.118107 | 2026-01-13 00:26:23.118178 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-01-13 00:26:23.844217 | controller | ok 2026-01-13 00:26:23.856378 | 2026-01-13 00:26:23.856517 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-01-13 00:26:23.896285 | controller | skipping: Conditional result was False 2026-01-13 00:26:23.925462 | 2026-01-13 00:26:23.925957 | TASK [include_role : remove-zuul-sshkey] 2026-01-13 00:26:23.954631 | controller | skipping: Conditional result was False 2026-01-13 00:26:23.971537 | 2026-01-13 00:26:23.971701 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-01-13 00:26:24.611717 | controller | ok: "logs" 2026-01-13 00:26:24.612588 | controller | ok: All items complete 2026-01-13 00:26:24.612654 | 2026-01-13 00:26:25.230007 | controller | ok: "artifacts" 2026-01-13 00:26:25.841784 | controller | ok: "docs" 2026-01-13 00:26:25.858782 | 2026-01-13 00:26:25.858996 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-01-13 00:26:26.529507 | controller | changed: "logs" 2026-01-13 00:26:27.193515 | controller | changed: "artifacts" 2026-01-13 00:26:27.815768 | controller | changed: "docs" 2026-01-13 00:26:27.858945 | 2026-01-13 00:26:27.859080 | PLAY RECAP 2026-01-13 00:26:27.859128 | controller | ok: 30 changed: 16 unreachable: 0 failed: 0 skipped: 18 rescued: 0 ignored: 0 2026-01-13 00:26:27.859154 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-01-13 00:26:27.859172 | 2026-01-13 00:26:27.980744 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master] 2026-01-13 00:26:27.981638 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2026-01-13 00:26:28.593998 | 2026-01-13 00:26:28.594134 | PLAY [all] 2026-01-13 00:26:28.615678 | 2026-01-13 00:26:28.615777 | TASK [Install binary dependencies] 2026-01-13 00:26:28.707245 | controller | ok 2026-01-13 00:26:28.746379 | 2026-01-13 00:26:28.746510 | TASK [bindep : Include find tasks] 2026-01-13 00:26:28.780193 | controller | ok 2026-01-13 00:26:28.788252 | controller | included: /var/lib/zuul/builds/15b2bf71563d439e8d57adca22ad30b4/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find.yaml 2026-01-13 00:26:28.794618 | 2026-01-13 00:26:28.794703 | TASK [bindep : Look for bindep.txt] 2026-01-13 00:26:29.717522 | controller | ok 2026-01-13 00:26:29.729947 | 2026-01-13 00:26:29.730108 | TASK [bindep : Define bindep_file fact] 2026-01-13 00:26:29.757220 | controller | skipping: Conditional result was False 2026-01-13 00:26:29.770840 | 2026-01-13 00:26:29.770993 | TASK [bindep : Look for other-requirements.txt] 2026-01-13 00:26:30.413487 | controller | ok 2026-01-13 00:26:30.426834 | 2026-01-13 00:26:30.427063 | TASK [bindep : Define bindep_file fact] 2026-01-13 00:26:30.465175 | controller | skipping: Conditional result was False 2026-01-13 00:26:30.474746 | 2026-01-13 00:26:30.474890 | TASK [bindep : Look for bindep fallback file] 2026-01-13 00:26:30.501633 | controller | skipping: Conditional result was False 2026-01-13 00:26:30.511178 | 2026-01-13 00:26:30.511342 | TASK [bindep : Define bindep_file fact] 2026-01-13 00:26:30.538018 | controller | skipping: Conditional result was False 2026-01-13 00:26:30.547283 | 2026-01-13 00:26:30.547383 | TASK [bindep : Include bindep tasks] 2026-01-13 00:26:30.573623 | controller | skipping: Conditional result was False 2026-01-13 00:26:30.582318 | 2026-01-13 00:26:30.582404 | TASK [bindep : Include install tasks] 2026-01-13 00:26:30.607291 | controller | skipping: Conditional result was False 2026-01-13 00:26:30.616635 | 2026-01-13 00:26:30.616820 | LOOP [bindep : Include package tasks] 2026-01-13 00:26:30.700046 | 2026-01-13 00:26:30.700346 | TASK [Run test-setup role] 2026-01-13 00:26:30.727963 | controller | ok 2026-01-13 00:26:30.758612 | 2026-01-13 00:26:30.758817 | TASK [test-setup : Check if project's tools/test-setup.sh exists] 2026-01-13 00:26:31.451628 | controller | ok 2026-01-13 00:26:31.458071 | 2026-01-13 00:26:31.458162 | TASK [test-setup : Run tools/test-setup.sh] 2026-01-13 00:26:31.816131 | controller | skipping: Conditional result was False 2026-01-13 00:26:31.871920 | 2026-01-13 00:26:31.872033 | PLAY RECAP 2026-01-13 00:26:31.872090 | controller | ok: 4 changed: 0 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2026-01-13 00:26:31.872118 | 2026-01-13 00:26:32.011313 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2026-01-13 00:26:32.012471 | PRE-RUN START: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master] 2026-01-13 00:26:32.604540 | 2026-01-13 00:26:32.604705 | PLAY [controller] 2026-01-13 00:26:32.625187 | 2026-01-13 00:26:32.625275 | TASK [Create the /root directory] 2026-01-13 00:26:33.806059 | controller | ok 2026-01-13 00:26:33.820497 | 2026-01-13 00:26:33.820800 | TASK [Install glibc-langpack-en] 2026-01-13 00:26:41.958942 | controller | ok: Nothing to do 2026-01-13 00:26:41.971339 | 2026-01-13 00:26:41.971480 | TASK [Ensure controller directory exists] 2026-01-13 00:26:42.787032 | controller | changed 2026-01-13 00:26:42.799250 | 2026-01-13 00:26:42.799381 | TASK [Install container runtime] 2026-01-13 00:26:42.879227 | controller | ok 2026-01-13 00:26:42.945511 | 2026-01-13 00:26:42.945644 | LOOP [ensure-docker : Gather variables for each operating system] 2026-01-13 00:26:43.008038 | controller | ok: "/var/lib/zuul/builds/15b2bf71563d439e8d57adca22ad30b4/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/vars/fedora.yaml" 2026-01-13 00:26:43.029311 | 2026-01-13 00:26:43.029545 | TASK [ensure-docker : Sanity check for distro selection] 2026-01-13 00:26:43.056713 | controller | skipping: Conditional result was False 2026-01-13 00:26:43.072383 | 2026-01-13 00:26:43.072556 | TASK [ensure-docker : Sanity check for distro install] 2026-01-13 00:26:43.109334 | controller | skipping: Conditional result was False 2026-01-13 00:26:43.122844 | 2026-01-13 00:26:43.122996 | TASK [ensure-docker : Sanity check for upstream install] 2026-01-13 00:26:43.150844 | controller | skipping: Conditional result was False 2026-01-13 00:26:43.165070 | 2026-01-13 00:26:43.165242 | TASK [ensure-docker : Sanity check for upstream install mirrors] 2026-01-13 00:26:43.201935 | controller | skipping: Conditional result was False 2026-01-13 00:26:43.216321 | 2026-01-13 00:26:43.216474 | TASK [ensure-docker : Include tasks for docker install] 2026-01-13 00:26:43.245706 | controller | ok 2026-01-13 00:26:43.265246 | controller | included: /var/lib/zuul/builds/15b2bf71563d439e8d57adca22ad30b4/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/tasks/docker-upstream.yaml 2026-01-13 00:26:43.277862 | 2026-01-13 00:26:43.277952 | TASK [ensure-docker : Not Implemented] 2026-01-13 00:26:43.302193 | controller | skipping: Conditional result was False 2026-01-13 00:26:43.310858 | 2026-01-13 00:26:43.310946 | TASK [Set up docker mirrors] 2026-01-13 00:26:43.342534 | controller | ok 2026-01-13 00:26:43.368757 | 2026-01-13 00:26:43.368856 | TASK [use-docker-mirror : Set docker_mirror fact from mirror_info] 2026-01-13 00:26:43.413797 | controller | skipping: Conditional result was False 2026-01-13 00:26:43.429124 | 2026-01-13 00:26:43.429273 | TASK [use-docker-mirror : Set docker_mirror fact from zuul_site_mirror_info] 2026-01-13 00:26:43.507596 | controller | skipping: Conditional result was False 2026-01-13 00:26:43.559380 | 2026-01-13 00:26:43.559544 | TASK [use-docker-mirror : Set docker_mirror fact] 2026-01-13 00:26:43.604914 | controller | skipping: Conditional result was False 2026-01-13 00:26:43.619223 | 2026-01-13 00:26:43.619378 | TASK [use-docker-mirror : Set up docker mirrors] 2026-01-13 00:26:43.655806 | controller | skipping: Conditional result was False 2026-01-13 00:26:43.681519 | 2026-01-13 00:26:43.681728 | TASK [Add all repositories] 2026-01-13 00:26:43.710137 | controller | ok 2026-01-13 00:26:43.749128 | 2026-01-13 00:26:43.749229 | LOOP [ensure-package-repositories : Install repositories] 2026-01-13 00:26:43.793812 | controller | ok: "/var/lib/zuul/builds/15b2bf71563d439e8d57adca22ad30b4/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-package-repositories/tasks/RedHat.yaml" 2026-01-13 00:26:43.794359 | controller | ok: All items complete 2026-01-13 00:26:43.794431 | 2026-01-13 00:26:43.816096 | controller | included: /var/lib/zuul/builds/15b2bf71563d439e8d57adca22ad30b4/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-package-repositories/tasks/RedHat.yaml 2026-01-13 00:26:43.824797 | 2026-01-13 00:26:43.824883 | TASK [ensure-package-repositories : Create GPG key temp directory] 2026-01-13 00:26:44.754103 | controller | changed 2026-01-13 00:26:44.768055 | 2026-01-13 00:26:44.768200 | LOOP [ensure-package-repositories : Upload GPG keys] 2026-01-13 00:26:48.050430 | controller | changed: 2026-01-13 00:26:48.050713 | controller | { 2026-01-13 00:26:48.050768 | 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-13 00:26:48.050808 | controller | } 2026-01-13 00:26:48.068353 | 2026-01-13 00:26:48.068574 | LOOP [ensure-package-repositories : Import GPG keys] 2026-01-13 00:26:49.310910 | controller | changed: 2026-01-13 00:26:49.311285 | controller | { 2026-01-13 00:26:49.311351 | 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-13 00:26:49.311400 | controller | } 2026-01-13 00:26:49.311463 | controller | changed: All items complete 2026-01-13 00:26:49.311507 | 2026-01-13 00:26:49.328076 | 2026-01-13 00:26:49.328181 | TASK [ensure-package-repositories : Remove GPG temp directory] 2026-01-13 00:26:50.007708 | controller | changed 2026-01-13 00:26:50.022260 | 2026-01-13 00:26:50.022481 | LOOP [ensure-package-repositories : Add repositories] 2026-01-13 00:26:50.863458 | controller | changed: 2026-01-13 00:26:50.863837 | controller | { 2026-01-13 00:26:50.863931 | controller | "baseurl": "https://download.docker.com/linux/fedora/$releasever/$basearch/stable", 2026-01-13 00:26:50.864010 | controller | "description": "Docker CE Stable - $basearch", 2026-01-13 00:26:50.864082 | controller | "gpgcheck": true, 2026-01-13 00:26:50.864152 | controller | "name": "docker-ce-stable" 2026-01-13 00:26:50.864218 | controller | } 2026-01-13 00:26:50.903389 | 2026-01-13 00:26:50.903606 | TASK [ensure-docker : Install pre-reqs] 2026-01-13 00:26:55.738952 | controller | ok: Nothing to do 2026-01-13 00:26:55.751549 | 2026-01-13 00:26:55.751749 | TASK [ensure-docker : Remove packages] 2026-01-13 00:26:58.863754 | controller | ok: Nothing to do 2026-01-13 00:26:58.878432 | 2026-01-13 00:26:58.878597 | TASK [ensure-docker : Install upstream docker using package] 2026-01-13 00:28:26.256987 | controller | changed 2026-01-13 00:28:26.270534 | 2026-01-13 00:28:26.270719 | TASK [ensure-docker : Setup docker] 2026-01-13 00:28:26.303529 | controller | ok 2026-01-13 00:28:26.325247 | controller | included: /var/lib/zuul/builds/15b2bf71563d439e8d57adca22ad30b4/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/tasks/docker-setup.yaml 2026-01-13 00:28:26.341526 | 2026-01-13 00:28:26.341612 | TASK [ensure-docker : Ensure "docker" group exists] 2026-01-13 00:28:27.408824 | controller | ok 2026-01-13 00:28:27.424119 | 2026-01-13 00:28:27.424266 | TASK [ensure-docker : Add user to docker group] 2026-01-13 00:28:28.465237 | controller | changed 2026-01-13 00:28:28.524265 | 2026-01-13 00:28:28.524432 | TASK [Update docker daemon proxy configuration] 2026-01-13 00:28:28.562219 | controller | skipping: Conditional result was False 2026-01-13 00:28:28.573082 | 2026-01-13 00:28:28.573172 | TASK [Lower default MTU] 2026-01-13 00:28:28.600030 | controller | skipping: Conditional result was False 2026-01-13 00:28:28.610654 | 2026-01-13 00:28:28.610815 | TASK [ensure-docker : Get the status of the docket.socket unit] 2026-01-13 00:28:29.378557 | controller | inactive 2026-01-13 00:28:29.981137 | controller | ok: Runtime: 0:00:00.016069 2026-01-13 00:28:29.990379 | 2026-01-13 00:28:29.990475 | TASK [ensure-docker : Get the status of the docket unit] 2026-01-13 00:28:30.757329 | controller | inactive 2026-01-13 00:28:31.353789 | controller | ok: Runtime: 0:00:00.016801 2026-01-13 00:28:31.371297 | 2026-01-13 00:28:31.371615 | TASK [ensure-docker : Restart docker] 2026-01-13 00:28:31.439734 | Trigger a Docker restart 2026-01-13 00:28:31.457864 | 2026-01-13 00:28:31.458015 | TASK [ensure-docker : Reset ssh connection to pick up docker group] 2026-01-13 00:28:31.504107 | 2026-01-13 00:28:31.504219 | TASK [ensure-docker : Install docker-compose] 2026-01-13 00:28:31.531797 | controller | skipping: Conditional result was False 2026-01-13 00:28:31.541713 | 2026-01-13 00:28:31.541895 | TASK [ensure-docker : Flush handlers before role exit] 2026-01-13 00:28:31.558968 | 2026-01-13 00:28:31.559090 | TASK [ensure-docker : Stop docker.socket to avoid any conflict] 2026-01-13 00:28:35.124032 | controller | changed 2026-01-13 00:28:35.140361 | 2026-01-13 00:28:35.140500 | TASK [ensure-docker : Assure docker service is running] 2026-01-13 00:28:38.928387 | controller | changed 2026-01-13 00:28:38.946504 | 2026-01-13 00:28:38.946726 | TASK [ensure-docker : Assure docker.socket service is running] 2026-01-13 00:28:40.551776 | controller | ok 2026-01-13 00:28:40.559216 | 2026-01-13 00:28:40.559278 | TASK [ensure-docker : Correct group ownership on docker sock] 2026-01-13 00:28:41.337175 | controller | ok 2026-01-13 00:28:41.352018 | 2026-01-13 00:28:41.352176 | TASK [ensure-docker : Validate ability to talk with docker] 2026-01-13 00:28:41.993523 | controller | CONTAINER ID IMAGE COMMAND CREATED STATUS PORTS NAMES 2026-01-13 00:28:42.222013 | controller | ok: Runtime: 0:00:00.036630 2026-01-13 00:28:42.235104 | 2026-01-13 00:28:42.235293 | TASK [ensure-docker : Get version details] 2026-01-13 00:28:42.858082 | controller | Client: Docker Engine - Community 2026-01-13 00:28:42.860767 | controller | Version: 24.0.7 2026-01-13 00:28:42.860885 | controller | API version: 1.43 2026-01-13 00:28:42.860896 | controller | Go version: go1.20.10 2026-01-13 00:28:42.860913 | controller | Git commit: afdd53b 2026-01-13 00:28:42.860930 | controller | Built: Thu Oct 26 09:09:39 2023 2026-01-13 00:28:42.860937 | controller | OS/Arch: linux/amd64 2026-01-13 00:28:42.860945 | controller | Context: default 2026-01-13 00:28:42.860954 | controller | 2026-01-13 00:28:42.860960 | controller | Server: Docker Engine - Community 2026-01-13 00:28:42.860966 | controller | Engine: 2026-01-13 00:28:42.860972 | controller | Version: 24.0.7 2026-01-13 00:28:42.860978 | controller | API version: 1.43 (minimum version 1.12) 2026-01-13 00:28:42.860984 | controller | Go version: go1.20.10 2026-01-13 00:28:42.860990 | controller | Git commit: 311b9ff 2026-01-13 00:28:42.860996 | controller | Built: Thu Oct 26 09:08:11 2023 2026-01-13 00:28:42.861002 | controller | OS/Arch: linux/amd64 2026-01-13 00:28:42.861008 | controller | Experimental: false 2026-01-13 00:28:42.861014 | controller | containerd: 2026-01-13 00:28:42.861020 | controller | Version: 1.6.25 2026-01-13 00:28:42.861026 | controller | GitCommit: d8f198a4ed8892c764191ef7b3b06d8a2eeb5c7f 2026-01-13 00:28:42.861032 | controller | runc: 2026-01-13 00:28:42.861038 | controller | Version: 1.1.10 2026-01-13 00:28:42.861044 | controller | GitCommit: v1.1.10-0-g18a0cb0 2026-01-13 00:28:42.861053 | controller | docker-init: 2026-01-13 00:28:42.861059 | controller | Version: 0.19.0 2026-01-13 00:28:42.861065 | controller | GitCommit: de40ad0 2026-01-13 00:28:43.113709 | controller | ok: Runtime: 0:00:00.036477 2026-01-13 00:28:43.130030 | 2026-01-13 00:28:43.130176 | TASK [ensure-docker : Dump installed docker details] 2026-01-13 00:28:43.180473 | {'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-13 00:28:42.824891', 'end': '2026-01-13 00:28:42.861368', 'delta': '0:00:00.036477', 'msg': '', 'zuul_log_id': 'fa163efc-24cc-01ba-a2c2-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-13 00:28:43.193737 | 2026-01-13 00:28:43.193871 | TASK [Use buildset registry] 2026-01-13 00:28:43.221912 | controller | skipping: Conditional result was False 2026-01-13 00:28:43.291854 | 2026-01-13 00:28:43.291993 | TASK [Ensure python3.8 is present] 2026-01-13 00:28:43.317838 | controller | skipping: Conditional result was False 2026-01-13 00:28:43.325240 | 2026-01-13 00:28:43.325360 | TASK [Run our-ensure-python role (Fedora only for now)] 2026-01-13 00:28:43.346092 | controller | ok 2026-01-13 00:28:43.365013 | 2026-01-13 00:28:43.365098 | TASK [our-ensure-python : Install the right Python version (rpm)] 2026-01-13 00:28:50.410574 | controller | ok: Nothing to do 2026-01-13 00:28:50.420845 | 2026-01-13 00:28:50.421052 | TASK [our-ensure-python : Also install python3-devel] 2026-01-13 00:29:09.003564 | controller | changed 2026-01-13 00:29:09.031076 | 2026-01-13 00:29:09.031330 | TASK [Run ensure-virtualenv role] 2026-01-13 00:29:09.057815 | controller | ok 2026-01-13 00:29:09.087685 | 2026-01-13 00:29:09.087812 | TASK [ensure-virtualenv : Check if virtualenv is installed] 2026-01-13 00:29:09.840037 | controller | /usr/bin/virtualenv 2026-01-13 00:29:10.448016 | controller | ok: Runtime: 0:00:00.006144 2026-01-13 00:29:10.455037 | 2026-01-13 00:29:10.455271 | LOOP [ensure-virtualenv : Install virtualenv package] 2026-01-13 00:29:10.494100 | controller | skipping: Conditional result was False 2026-01-13 00:29:10.494873 | controller | changed: All items complete 2026-01-13 00:29:10.494954 | 2026-01-13 00:29:10.530958 | 2026-01-13 00:29:10.531219 | TASK [Find the full path of the Python interpreter] 2026-01-13 00:29:11.321171 | controller | /usr/bin/python3.9 2026-01-13 00:29:11.895865 | controller | ok 2026-01-13 00:29:11.908838 | 2026-01-13 00:29:11.909002 | TASK [Create virtualenv for ansible-test with a specific interpreter] 2026-01-13 00:29:14.837865 | controller | created virtual environment CPython3.9.18.final.0-64 in 1384ms 2026-01-13 00:29:14.964912 | controller | creator CPython3Posix(dest=/home/zuul/venv, clear=False, no_vcs_ignore=False, global=False) 2026-01-13 00:29:14.964967 | 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-13 00:29:14.964982 | controller | added seed packages: pip==22.2.2, setuptools==62.6.0, wheel==0.37.1 2026-01-13 00:29:14.965002 | controller | activators BashActivator,CShellActivator,FishActivator,NushellActivator,PowerShellActivator,PythonActivator 2026-01-13 00:29:15.296364 | controller | changed 2026-01-13 00:29:15.309406 | 2026-01-13 00:29:15.309539 | TASK [Set selinux package] 2026-01-13 00:29:15.349042 | controller | ok 2026-01-13 00:29:15.360286 | 2026-01-13 00:29:15.360415 | TASK [Set selinux package (Fedora)] 2026-01-13 00:29:15.410119 | controller | ok 2026-01-13 00:29:15.422652 | 2026-01-13 00:29:15.422846 | TASK [Install selinux into virtualenv] 2026-01-13 00:29:20.774855 | controller | Collecting selinux-please-lie-to-me 2026-01-13 00:29:20.906071 | controller | Downloading selinux_please_lie_to_me-1.0.1-py2.py3-none-any.whl (3.8 kB) 2026-01-13 00:29:22.091076 | controller | Collecting setuptools<50.0.0 2026-01-13 00:29:22.096941 | controller | Downloading setuptools-49.6.0-py3-none-any.whl (803 kB) 2026-01-13 00:29:22.185166 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 803.3/803.3 kB 9.7 MB/s eta 0:00:00 2026-01-13 00:29:22.326939 | controller | Installing collected packages: setuptools, selinux-please-lie-to-me 2026-01-13 00:29:22.327182 | controller | Attempting uninstall: setuptools 2026-01-13 00:29:22.328452 | controller | Found existing installation: setuptools 62.6.0 2026-01-13 00:29:22.454649 | controller | Uninstalling setuptools-62.6.0: 2026-01-13 00:29:22.472251 | controller | Successfully uninstalled setuptools-62.6.0 2026-01-13 00:29:23.212821 | controller | Successfully installed selinux-please-lie-to-me-1.0.1 setuptools-49.6.0 2026-01-13 00:29:23.405903 | controller | 2026-01-13 00:29:23.674237 | controller | [notice] A new release of pip available: 22.2.2 -> 25.3 2026-01-13 00:29:23.674294 | controller | [notice] To update, run: /home/zuul/venv/bin/python -m pip install --upgrade pip 2026-01-13 00:29:23.810323 | controller | ok: Runtime: 0:00:06.994569 2026-01-13 00:29:23.824622 | 2026-01-13 00:29:23.824866 | TASK [Install pytest-forked into virtualenv] 2026-01-13 00:29:25.886174 | controller | Collecting pytest-forked 2026-01-13 00:29:25.948467 | controller | Downloading pytest_forked-1.6.0-py3-none-any.whl (4.9 kB) 2026-01-13 00:29:26.147284 | controller | Collecting pytest>=3.10 2026-01-13 00:29:26.155302 | controller | Downloading pytest-8.4.2-py3-none-any.whl (365 kB) 2026-01-13 00:29:26.201801 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 365.8/365.8 kB 8.5 MB/s eta 0:00:00 2026-01-13 00:29:26.266373 | controller | Collecting py 2026-01-13 00:29:26.273291 | controller | Downloading py-1.11.0-py2.py3-none-any.whl (98 kB) 2026-01-13 00:29:26.287532 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 98.7/98.7 kB 12.9 MB/s eta 0:00:00 2026-01-13 00:29:26.386503 | controller | Collecting pygments>=2.7.2 2026-01-13 00:29:26.394597 | controller | Downloading pygments-2.19.2-py3-none-any.whl (1.2 MB) 2026-01-13 00:29:26.420103 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 1.2/1.2 MB 57.5 MB/s eta 0:00:00 2026-01-13 00:29:26.516633 | controller | Collecting tomli>=1 2026-01-13 00:29:26.522936 | controller | Downloading tomli-2.4.0-py3-none-any.whl (14 kB) 2026-01-13 00:29:26.579086 | controller | Collecting pluggy<2,>=1.5 2026-01-13 00:29:26.584133 | controller | Downloading pluggy-1.6.0-py3-none-any.whl (20 kB) 2026-01-13 00:29:26.664549 | controller | Collecting packaging>=20 2026-01-13 00:29:26.669559 | controller | Downloading packaging-25.0-py3-none-any.whl (66 kB) 2026-01-13 00:29:26.683420 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 66.5/66.5 kB 5.4 MB/s eta 0:00:00 2026-01-13 00:29:26.730881 | controller | Collecting exceptiongroup>=1 2026-01-13 00:29:26.736361 | controller | Downloading exceptiongroup-1.3.1-py3-none-any.whl (16 kB) 2026-01-13 00:29:26.777355 | controller | Collecting iniconfig>=1 2026-01-13 00:29:26.785111 | controller | Downloading iniconfig-2.1.0-py3-none-any.whl (6.0 kB) 2026-01-13 00:29:26.869181 | controller | Collecting typing-extensions>=4.6.0 2026-01-13 00:29:26.874203 | controller | Downloading typing_extensions-4.15.0-py3-none-any.whl (44 kB) 2026-01-13 00:29:26.883192 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 44.6/44.6 kB 6.8 MB/s eta 0:00:00 2026-01-13 00:29:26.995833 | controller | Installing collected packages: typing-extensions, tomli, pygments, py, pluggy, packaging, iniconfig, exceptiongroup, pytest, pytest-forked 2026-01-13 00:29:30.010624 | 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-13 00:29:30.017498 | controller | 2026-01-13 00:29:30.271383 | controller | [notice] A new release of pip available: 22.2.2 -> 25.3 2026-01-13 00:29:30.271428 | controller | [notice] To update, run: /home/zuul/venv/bin/python -m pip install --upgrade pip 2026-01-13 00:29:30.717877 | controller | ok: Runtime: 0:00:05.330430 2026-01-13 00:29:30.731029 | 2026-01-13 00:29:30.731181 | TASK [Update pip] 2026-01-13 00:29:33.432080 | controller | Requirement already satisfied: pip in ./venv/lib/python3.9/site-packages (22.2.2) 2026-01-13 00:29:33.885295 | controller | Collecting pip 2026-01-13 00:29:33.938238 | controller | Downloading pip-25.3-py3-none-any.whl (1.8 MB) 2026-01-13 00:29:33.997957 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 1.8/1.8 MB 31.8 MB/s eta 0:00:00 2026-01-13 00:29:34.089474 | controller | Installing collected packages: pip 2026-01-13 00:29:34.089540 | controller | Attempting uninstall: pip 2026-01-13 00:29:34.089554 | controller | Found existing installation: pip 22.2.2 2026-01-13 00:29:34.365084 | controller | Uninstalling pip-22.2.2: 2026-01-13 00:29:34.396901 | controller | Successfully uninstalled pip-22.2.2 2026-01-13 00:29:35.902754 | controller | Successfully installed pip-25.3 2026-01-13 00:29:36.631642 | controller | ok: Runtime: 0:00:04.134000 2026-01-13 00:29:36.640087 | 2026-01-13 00:29:36.640173 | TASK [Ensure pip temp directory exists in /var/tmp] 2026-01-13 00:29:37.885284 | controller | changed 2026-01-13 00:29:37.897758 | 2026-01-13 00:29:37.897897 | TASK [Install ansible into virtualenv] 2026-01-13 00:29:39.967601 | controller | Processing ./src/github.com/ansible/ansible 2026-01-13 00:29:39.977293 | controller | Installing build dependencies: started 2026-01-13 00:29:42.101955 | controller | Installing build dependencies: finished with status 'done' 2026-01-13 00:29:42.103283 | controller | Getting requirements to build wheel: started 2026-01-13 00:29:43.296366 | controller | Getting requirements to build wheel: finished with status 'done' 2026-01-13 00:29:43.298183 | controller | Preparing metadata (pyproject.toml): started 2026-01-13 00:29:44.217434 | controller | Preparing metadata (pyproject.toml): finished with status 'done' 2026-01-13 00:29:44.333098 | controller | Collecting jinja2 (from ansible-core==2.11.12.post0) 2026-01-13 00:29:44.391998 | controller | Downloading jinja2-3.1.6-py3-none-any.whl.metadata (2.9 kB) 2026-01-13 00:29:44.514147 | controller | Collecting PyYAML (from ansible-core==2.11.12.post0) 2026-01-13 00:29:44.520720 | 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-13 00:29:45.070780 | controller | Collecting cryptography (from ansible-core==2.11.12.post0) 2026-01-13 00:29:45.077462 | controller | Downloading cryptography-46.0.3-cp38-abi3-manylinux_2_34_x86_64.whl.metadata (5.7 kB) 2026-01-13 00:29:45.105211 | controller | Requirement already satisfied: packaging in ./venv/lib/python3.9/site-packages (from ansible-core==2.11.12.post0) (25.0) 2026-01-13 00:29:45.134741 | controller | Collecting resolvelib<0.6.0,>=0.5.3 (from ansible-core==2.11.12.post0) 2026-01-13 00:29:45.142693 | controller | Downloading resolvelib-0.5.4-py2.py3-none-any.whl.metadata (3.7 kB) 2026-01-13 00:29:45.437662 | controller | Collecting cffi>=2.0.0 (from cryptography->ansible-core==2.11.12.post0) 2026-01-13 00:29:45.445941 | controller | Downloading cffi-2.0.0-cp39-cp39-manylinux2014_x86_64.manylinux_2_17_x86_64.whl.metadata (2.6 kB) 2026-01-13 00:29:45.459443 | 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-13 00:29:45.490530 | controller | Collecting pycparser (from cffi>=2.0.0->cryptography->ansible-core==2.11.12.post0) 2026-01-13 00:29:45.496700 | controller | Downloading pycparser-2.23-py3-none-any.whl.metadata (993 bytes) 2026-01-13 00:29:45.655209 | controller | Collecting MarkupSafe>=2.0 (from jinja2->ansible-core==2.11.12.post0) 2026-01-13 00:29:45.664141 | 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-13 00:29:45.690192 | controller | Downloading resolvelib-0.5.4-py2.py3-none-any.whl (12 kB) 2026-01-13 00:29:45.704863 | controller | Downloading cryptography-46.0.3-cp38-abi3-manylinux_2_34_x86_64.whl (4.5 MB) 2026-01-13 00:29:45.774823 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 4.5/4.5 MB 82.0 MB/s 0:00:00 2026-01-13 00:29:45.782212 | controller | Downloading cffi-2.0.0-cp39-cp39-manylinux2014_x86_64.manylinux_2_17_x86_64.whl (216 kB) 2026-01-13 00:29:45.800767 | controller | Downloading jinja2-3.1.6-py3-none-any.whl (134 kB) 2026-01-13 00:29:45.819217 | 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-13 00:29:45.834636 | controller | Downloading pycparser-2.23-py3-none-any.whl (118 kB) 2026-01-13 00:29:45.852680 | 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-13 00:29:45.883051 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 750.8/750.8 kB 22.6 MB/s 0:00:00 2026-01-13 00:29:45.907893 | controller | Building wheels for collected packages: ansible-core 2026-01-13 00:29:45.909660 | controller | Building wheel for ansible-core (pyproject.toml): started 2026-01-13 00:29:48.769037 | controller | Building wheel for ansible-core (pyproject.toml): finished with status 'done' 2026-01-13 00:29:48.776480 | controller | Created wheel for ansible-core: filename=ansible_core-2.11.12.post0-py3-none-any.whl size=1961250 sha256=43e83e2d90c38616819e8df0d77d451c056e6c5cab05a4df04b7515ec7764951 2026-01-13 00:29:48.776725 | controller | Stored in directory: /var/tmp/ansible-test-pip/pip-ephem-wheel-cache-c0zjuk80/wheels/0f/a0/93/ab85b739e1eb1e0467171316f13571c14efdf87e0d5d44a33e 2026-01-13 00:29:48.793786 | controller | Successfully built ansible-core 2026-01-13 00:29:48.861873 | controller | Installing collected packages: resolvelib, PyYAML, pycparser, MarkupSafe, jinja2, cffi, cryptography, ansible-core 2026-01-13 00:29:51.687996 | controller | 2026-01-13 00:29:51.693529 | 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-13 00:29:52.289954 | controller | ok: Runtime: 0:00:13.131022 2026-01-13 00:29:52.298603 | 2026-01-13 00:29:52.298731 | TASK [Setup download-artifact-fork role] 2026-01-13 00:29:52.327535 | controller | ok 2026-01-13 00:29:52.362305 | 2026-01-13 00:29:52.362441 | TASK [download-artifact-fork : Parse inventory response] 2026-01-13 00:29:52.397004 | controller | ok 2026-01-13 00:29:52.405232 | 2026-01-13 00:29:52.405317 | TASK [download-artifact-fork : Fetch info from Zuul API] 2026-01-13 00:29:52.431056 | controller | skipping: Conditional result was False 2026-01-13 00:29:52.440360 | 2026-01-13 00:29:52.440502 | TASK [download-artifact-fork : Parse build response] 2026-01-13 00:29:52.466725 | controller | skipping: Conditional result was False 2026-01-13 00:29:52.473437 | 2026-01-13 00:29:52.473504 | TASK [download-artifact-fork : Ensure artifacts directory exists] 2026-01-13 00:29:53.279072 | controller | changed 2026-01-13 00:29:53.292166 | 2026-01-13 00:29:53.292316 | LOOP [download-artifact-fork : Download archive by type] 2026-01-13 00:29:53.332445 | controller | skipping: Conditional result was False 2026-01-13 00:29:53.333028 | 2026-01-13 00:29:55.178515 | controller | changed: OK (20201 bytes) 2026-01-13 00:29:55.205546 | 2026-01-13 00:29:55.205739 | TASK [deploy-artifacts : define __collections] 2026-01-13 00:29:55.244251 | controller | ok 2026-01-13 00:29:55.256544 | 2026-01-13 00:29:55.256714 | LOOP [deploy-artifacts : Create list of collections] 2026-01-13 00:29:55.293322 | controller | skipping: Conditional result was False 2026-01-13 00:29:55.312498 | controller | ok: 2026-01-13 00:29:55.312615 | controller | { 2026-01-13 00:29:55.312711 | controller | "branch": "main", 2026-01-13 00:29:55.312761 | controller | "job": "build-ansible-collection", 2026-01-13 00:29:55.312799 | controller | "metadata": { 2026-01-13 00:29:55.312834 | controller | "type": "ansible_collection", 2026-01-13 00:29:55.312868 | controller | "version": "5.0.0" 2026-01-13 00:29:55.312901 | controller | }, 2026-01-13 00:29:55.312933 | controller | "name": "ansible.network", 2026-01-13 00:29:55.312965 | controller | "project": "ansible-collections/ansible.network", 2026-01-13 00:29:55.312997 | controller | "url": "https://31a4c2cdbf09f6ea3daf-9c3e30aeca6aa8032f977aa968e7fa1b.ssl.cf5.rackcdn.com/ansible/86e8ecaac39c4c6389c366621ae3e18c/artifacts/ansible-network-5.0.0.tar.gz" 2026-01-13 00:29:55.313043 | controller | } 2026-01-13 00:29:55.329823 | 2026-01-13 00:29:55.329968 | TASK [deploy-artifacts : Install require-project collection using ansible-galaxy] 2026-01-13 00:29:56.746864 | controller | Starting galaxy collection install process 2026-01-13 00:29:56.747229 | controller | Process install dependency map 2026-01-13 00:29:58.383380 | controller | [WARNING]: Skipping Galaxy server https://galaxy.ansible.com/api/. Got an 2026-01-13 00:29:58.383774 | controller | unexpected error when getting available versions of collection 2026-01-13 00:29:58.383804 | controller | ansible.netcommon: '/api/v3/plugin/ansible/content/published/collections/index/ 2026-01-13 00:29:58.383812 | controller | ansible/netcommon/versions/' 2026-01-13 00:29:58.383824 | controller | ERROR! Unexpected Exception, this is probably a bug: '/api/v3/plugin/ansible/content/published/collections/index/ansible/netcommon/versions/' 2026-01-13 00:29:58.383882 | controller | to see the full traceback, use -vvv 2026-01-13 00:29:58.703150 | controller | ERROR 2026-01-13 00:29:58.703395 | controller | { 2026-01-13 00:29:58.703425 | controller | "delta": "0:00:02.536913", 2026-01-13 00:29:58.703445 | controller | "end": "2026-01-13 00:29:58.493981", 2026-01-13 00:29:58.703462 | controller | "msg": "non-zero return code", 2026-01-13 00:29:58.703478 | controller | "rc": 250, 2026-01-13 00:29:58.703494 | controller | "start": "2026-01-13 00:29:55.957068" 2026-01-13 00:29:58.703509 | controller | } failure 2026-01-13 00:29:58.705864 | 2026-01-13 00:29:58.705914 | PLAY RECAP 2026-01-13 00:29:58.705961 | controller | ok: 44 changed: 24 unreachable: 0 failed: 1 skipped: 17 rescued: 0 ignored: 0 2026-01-13 00:29:58.705981 | 2026-01-13 00:29:58.838721 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master] 2026-01-13 00:29:58.840752 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2026-01-13 00:29:59.423695 | 2026-01-13 00:29:59.423814 | PLAY [all] 2026-01-13 00:29:59.444948 | 2026-01-13 00:29:59.445033 | TASK [fetch-subunit-output : Find stestr or testr executable] 2026-01-13 00:30:02.667233 | controller | changed: non-zero return code 2026-01-13 00:30:02.680829 | 2026-01-13 00:30:02.680996 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2026-01-13 00:30:02.709581 | controller | skipping: Conditional result was False 2026-01-13 00:30:02.722974 | 2026-01-13 00:30:02.723141 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2026-01-13 00:30:02.756652 | 2026-01-13 00:30:02.756855 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2026-01-13 00:30:02.788310 | 2026-01-13 00:30:02.788512 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2026-01-13 00:30:02.814755 | controller | skipping: Conditional result was False 2026-01-13 00:30:02.821161 | 2026-01-13 00:30:02.821231 | LOOP [fetch-subunit-output : Generate subunit file] 2026-01-13 00:30:02.852017 | 2026-01-13 00:30:02.852146 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2026-01-13 00:30:02.877193 | controller | skipping: Conditional result was False 2026-01-13 00:30:02.893869 | 2026-01-13 00:30:02.893981 | TASK [fetch-subunit-output : Remove the temporary file] 2026-01-13 00:30:02.920271 | controller | skipping: Conditional result was False 2026-01-13 00:30:02.934208 | 2026-01-13 00:30:02.934361 | TASK [fetch-subunit-output : Process and fetch subunit results] 2026-01-13 00:30:02.962274 | controller | skipping: Conditional result was False 2026-01-13 00:30:03.001376 | 2026-01-13 00:30:03.001509 | PLAY RECAP 2026-01-13 00:30:03.001575 | controller | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2026-01-13 00:30:03.001604 | 2026-01-13 00:30:03.113297 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2026-01-13 00:30:03.115070 | POST-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/post.yaml@master] 2026-01-13 00:30:03.708883 | 2026-01-13 00:30:03.709018 | PLAY [all:!appliance*] 2026-01-13 00:30:03.731355 | 2026-01-13 00:30:03.731476 | TASK [unregister the node] 2026-01-13 00:30:04.084327 | controller | skipping: Conditional result was False 2026-01-13 00:30:04.097820 | 2026-01-13 00:30:04.098001 | TASK [include_role : fetch-output] 2026-01-13 00:30:04.147907 | controller | ok 2026-01-13 00:30:04.186006 | 2026-01-13 00:30:04.186135 | TASK [fetch-output : Set log path for multiple nodes] 2026-01-13 00:30:04.233078 | controller | skipping: Conditional result was False 2026-01-13 00:30:04.247360 | 2026-01-13 00:30:04.247510 | TASK [fetch-output : Set log path for single node] 2026-01-13 00:30:04.287570 | controller | ok 2026-01-13 00:30:04.302307 | 2026-01-13 00:30:04.302497 | LOOP [fetch-output : Ensure local output dirs] 2026-01-13 00:30:04.763237 | controller -> localhost | ok: "/var/lib/zuul/builds/15b2bf71563d439e8d57adca22ad30b4/work/logs" 2026-01-13 00:30:05.052268 | controller -> localhost | changed: "/var/lib/zuul/builds/15b2bf71563d439e8d57adca22ad30b4/work/artifacts" 2026-01-13 00:30:05.304678 | controller -> localhost | changed: "/var/lib/zuul/builds/15b2bf71563d439e8d57adca22ad30b4/work/docs" 2026-01-13 00:30:05.319035 | 2026-01-13 00:30:05.319106 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-01-13 00:30:07.592945 | controller | changed: 2026-01-13 00:30:07.593738 | controller | .d..t...... ./ 2026-01-13 00:30:07.593830 | controller | cd+++++++++ controller/ 2026-01-13 00:30:07.593910 | controller | changed: All items complete 2026-01-13 00:30:07.593959 | 2026-01-13 00:30:09.661806 | controller | changed: .d..t...... ./ 2026-01-13 00:30:11.746887 | controller | changed: .d..t...... ./ 2026-01-13 00:30:11.782546 | 2026-01-13 00:30:11.782794 | TASK [include_role : fetch-output-openshift] 2026-01-13 00:30:11.816820 | controller | skipping: Conditional result was False 2026-01-13 00:30:11.831849 | 2026-01-13 00:30:11.831999 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-01-13 00:30:11.888154 | controller | skipping: Conditional result was False 2026-01-13 00:30:11.897831 | controller | skipping: Conditional result was False 2026-01-13 00:30:11.946604 | 2026-01-13 00:30:11.946702 | PLAY [localhost] 2026-01-13 00:30:11.962246 | 2026-01-13 00:30:11.962335 | TASK [Run Zuul manifest role] 2026-01-13 00:30:11.994299 | localhost | ok 2026-01-13 00:30:12.013042 | 2026-01-13 00:30:12.013140 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-01-13 00:30:12.487079 | localhost | changed 2026-01-13 00:30:12.497973 | 2026-01-13 00:30:12.498111 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-01-13 00:30:12.548254 | localhost | ok 2026-01-13 00:30:12.567035 | 2026-01-13 00:30:12.567165 | TASK [Set zuul-log-path fact] 2026-01-13 00:30:12.593230 | localhost | ok 2026-01-13 00:30:12.649399 | 2026-01-13 00:30:12.649556 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-01-13 00:30:12.691599 | localhost | ok 2026-01-13 00:30:12.703065 | 2026-01-13 00:30:12.703147 | LOOP [Run upload-logs-swift role] 2026-01-13 00:30:12.763819 | localhost | Output suppressed because no_log was given 2026-01-13 00:30:12.811650 | 2026-01-13 00:30:12.811806 | TASK [Set zuul-log-path fact] 2026-01-13 00:30:12.859488 | localhost | skipping: Conditional result was False 2026-01-13 00:30:12.875527 | 2026-01-13 00:30:12.875711 | TASK [upload-logs-swift : Ensure logs are readable before uploading] 2026-01-13 00:30:13.339806 | localhost -> localhost | ok: Runtime: 0:00:00.011643 2026-01-13 00:30:13.353134 | 2026-01-13 00:30:13.353228 | TASK [upload-logs-swift : Upload logs to swift]