2026-01-05 00:06:50.726108 | Job console starting... 2026-01-05 00:06:50.740058 | Updating repositories 2026-01-05 00:06:50.864478 | Preparing job workspace 2026-01-05 00:06:54.745852 | Running Ansible setup... 2026-01-05 00:06:59.862762 | PRE-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master] 2026-01-05 00:07:00.492968 | 2026-01-05 00:07:00.493093 | PLAY [localhost] 2026-01-05 00:07:00.501512 | 2026-01-05 00:07:00.501585 | TASK [Gathering Facts] 2026-01-05 00:07:01.610801 | localhost | ok 2026-01-05 00:07:01.630972 | 2026-01-05 00:07:01.631148 | TASK [Setup log path fact] 2026-01-05 00:07:01.654362 | localhost | ok 2026-01-05 00:07:01.672837 | 2026-01-05 00:07:01.672983 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-01-05 00:07:01.714553 | localhost | ok 2026-01-05 00:07:01.725844 | 2026-01-05 00:07:01.725972 | TASK [emit-job-header : Print job information] 2026-01-05 00:07:01.767383 | # Job Information 2026-01-05 00:07:01.767642 | Ansible Version: 2.15.12 2026-01-05 00:07:01.767717 | Job: ansible-test-sanity-docker-stable-2.11 2026-01-05 00:07:01.767750 | Pipeline: periodic 2026-01-05 00:07:01.767778 | Executor: ze04.softwarefactory-project.io 2026-01-05 00:07:01.767805 | Triggered by: https://github.com/ansible-collections/ansible.network 2026-01-05 00:07:01.767835 | Log URL (when completed): https://ansible.softwarefactory-project.io/logs/73f/ansible/73f2cf9b4f5e483fa64028576d644257/ 2026-01-05 00:07:01.767862 | Event ID: 19345726fd1140bdae6ad9d74d038fac 2026-01-05 00:07:01.773261 | 2026-01-05 00:07:01.773352 | LOOP [emit-job-header : Print node information] 2026-01-05 00:07:01.891987 | localhost | ok: 2026-01-05 00:07:01.892150 | localhost | # Node Information 2026-01-05 00:07:01.892184 | localhost | Inventory Hostname: controller 2026-01-05 00:07:01.892210 | localhost | Hostname: np0005574132 2026-01-05 00:07:01.892234 | localhost | Username: zuul 2026-01-05 00:07:01.892261 | localhost | Distro: Fedora 37 2026-01-05 00:07:01.892285 | localhost | Provider: ansible-vexxhost-ca-ymq-1 2026-01-05 00:07:01.892307 | localhost | Region: ca-ymq-1 2026-01-05 00:07:01.892328 | localhost | Label: ansible-fedora-37-1vcpu 2026-01-05 00:07:01.892349 | localhost | Product Name: OpenStack Nova 2026-01-05 00:07:01.892370 | localhost | Interface IP: 162.253.55.62 2026-01-05 00:07:01.912152 | 2026-01-05 00:07:01.912337 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-01-05 00:07:02.317732 | localhost -> localhost | changed 2026-01-05 00:07:02.326493 | 2026-01-05 00:07:02.326624 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-01-05 00:07:03.141431 | localhost -> localhost | changed 2026-01-05 00:07:03.163591 | 2026-01-05 00:07:03.163714 | PLAY [all:!appliance*] 2026-01-05 00:07:03.183636 | 2026-01-05 00:07:03.183843 | TASK [include_role : start-zuul-console] 2026-01-05 00:07:03.205122 | controller | ok 2026-01-05 00:07:03.223371 | 2026-01-05 00:07:03.223496 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-01-05 00:07:03.616357 | controller | ok 2026-01-05 00:07:03.643947 | 2026-01-05 00:07:03.644125 | TASK [use-our-mirror : Retrieve the IP address] 2026-01-05 00:07:04.421263 | controller | ok: Status code was 404 and not [200]: HTTP Error 404: Not Found 2026-01-05 00:07:04.428545 | 2026-01-05 00:07:04.428625 | TASK [use-our-mirror : Ensure the registry service is running on the mirror] 2026-01-05 00:07:04.963401 | controller | skipping: Conditional result was False 2026-01-05 00:07:04.972930 | 2026-01-05 00:07:04.973037 | TASK [use-our-mirror : Write the IP in /etc/hosts] 2026-01-05 00:07:04.998002 | controller | skipping: Conditional result was False 2026-01-05 00:07:05.018613 | 2026-01-05 00:07:05.018749 | TASK [use-our-mirror : Create the podman configuration directory] 2026-01-05 00:07:05.034404 | controller | skipping: Conditional result was False 2026-01-05 00:07:05.045178 | 2026-01-05 00:07:05.045328 | TASK [use-our-mirror : Copy the podman configuration] 2026-01-05 00:07:05.064051 | controller | skipping: Conditional result was False 2026-01-05 00:07:05.071359 | 2026-01-05 00:07:05.071478 | TASK [use-our-mirror : Copy the Yum configuration (main)] 2026-01-05 00:07:05.087784 | controller | skipping: Conditional result was False 2026-01-05 00:07:05.097188 | 2026-01-05 00:07:05.097312 | TASK [use-our-mirror : Copy the Yum configuration (update)] 2026-01-05 00:07:05.111715 | controller | skipping: Conditional result was False 2026-01-05 00:07:05.128040 | 2026-01-05 00:07:05.128168 | TASK [Disable Fedora Modular] 2026-01-05 00:07:05.374576 | controller | changed 2026-01-05 00:07:05.388312 | 2026-01-05 00:07:05.388477 | TASK [Enable EPEL] 2026-01-05 00:07:05.414647 | controller | skipping: Conditional result was False 2026-01-05 00:07:05.421459 | 2026-01-05 00:07:05.421548 | TASK [Register the RHEL node] 2026-01-05 00:07:05.965566 | 2026-01-05 00:07:05.965759 | TASK [Show the subscription-manager status] 2026-01-05 00:07:06.524456 | controller | skipping: Conditional result was False 2026-01-05 00:07:06.531428 | 2026-01-05 00:07:06.531518 | TASK [Enable EPEL on RHEL] 2026-01-05 00:07:07.063009 | controller | skipping: Conditional result was False 2026-01-05 00:07:07.069255 | 2026-01-05 00:07:07.069330 | TASK [Install git and tox] 2026-01-05 00:08:52.251217 | controller | changed 2026-01-05 00:08:52.268088 | 2026-01-05 00:08:52.268294 | TASK [include_role : prepare-workspace] 2026-01-05 00:08:52.297362 | controller | ok 2026-01-05 00:08:52.336743 | 2026-01-05 00:08:52.336857 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-01-05 00:08:52.567443 | controller | ok 2026-01-05 00:08:52.582832 | 2026-01-05 00:08:52.582971 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-01-05 00:09:05.886382 | controller | Output suppressed because no_log was given 2026-01-05 00:09:05.906887 | 2026-01-05 00:09:05.907069 | TASK [include_role : prepare-workspace-openshift] 2026-01-05 00:09:05.938342 | controller | skipping: Conditional result was False 2026-01-05 00:09:06.024442 | 2026-01-05 00:09:06.024531 | PLAY [all:!appliance] 2026-01-05 00:09:06.040322 | 2026-01-05 00:09:06.040415 | TASK [Run add-build-sshkey role (RSA)] 2026-01-05 00:09:06.062657 | controller | ok 2026-01-05 00:09:06.079309 | 2026-01-05 00:09:06.079403 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-01-05 00:09:06.347625 | controller -> localhost | ok 2026-01-05 00:09:06.354756 | 2026-01-05 00:09:06.354833 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-01-05 00:09:06.386439 | controller | ok 2026-01-05 00:09:06.400773 | controller | included: /var/lib/zuul/builds/73f2cf9b4f5e483fa64028576d644257/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-01-05 00:09:06.408330 | 2026-01-05 00:09:06.408426 | TASK [add-build-sshkey : Create Temp SSH key] 2026-01-05 00:09:06.982860 | controller -> localhost | Generating public/private rsa key pair. 2026-01-05 00:09:06.983140 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/73f2cf9b4f5e483fa64028576d644257/work/73f2cf9b4f5e483fa64028576d644257_id_rsa. 2026-01-05 00:09:06.983172 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/73f2cf9b4f5e483fa64028576d644257/work/73f2cf9b4f5e483fa64028576d644257_id_rsa.pub. 2026-01-05 00:09:06.983194 | controller -> localhost | The key fingerprint is: 2026-01-05 00:09:06.983214 | controller -> localhost | SHA256:LR5F4rnBeDegWrAT+SHsATornjtTrJwF9WFEuyrDeCI zuul-build-sshkey 2026-01-05 00:09:06.983234 | controller -> localhost | The key's randomart image is: 2026-01-05 00:09:06.983253 | controller -> localhost | +---[RSA 2048]----+ 2026-01-05 00:09:06.983271 | controller -> localhost | | .o=+ o . | 2026-01-05 00:09:06.983291 | controller -> localhost | | . .=*o= = | 2026-01-05 00:09:06.983311 | controller -> localhost | |o ..===.* + | 2026-01-05 00:09:06.983330 | controller -> localhost | | + .=o. * . | 2026-01-05 00:09:06.983348 | controller -> localhost | |o o .. S . | 2026-01-05 00:09:06.983375 | controller -> localhost | |= .+. . o | 2026-01-05 00:09:06.983397 | controller -> localhost | |E**. . | 2026-01-05 00:09:06.983417 | controller -> localhost | |.O+ | 2026-01-05 00:09:06.983437 | controller -> localhost | | .o | 2026-01-05 00:09:06.983459 | controller -> localhost | +----[SHA256]-----+ 2026-01-05 00:09:06.983508 | controller -> localhost | ok: Runtime: 0:00:00.166400 2026-01-05 00:09:06.990758 | 2026-01-05 00:09:06.990854 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-01-05 00:09:07.020342 | controller | ok 2026-01-05 00:09:07.038613 | controller | included: /var/lib/zuul/builds/73f2cf9b4f5e483fa64028576d644257/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-01-05 00:09:07.053356 | 2026-01-05 00:09:07.053476 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-01-05 00:09:07.078475 | controller | skipping: Conditional result was False 2026-01-05 00:09:07.089354 | 2026-01-05 00:09:07.089469 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-01-05 00:09:07.535380 | controller | changed 2026-01-05 00:09:07.548894 | 2026-01-05 00:09:07.549199 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-01-05 00:09:07.776976 | controller | ok 2026-01-05 00:09:07.782953 | 2026-01-05 00:09:07.783021 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-01-05 00:09:08.407704 | controller | changed 2026-01-05 00:09:08.413462 | 2026-01-05 00:09:08.413534 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-01-05 00:09:09.001552 | controller | changed 2026-01-05 00:09:09.015992 | 2026-01-05 00:09:09.016239 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-01-05 00:09:09.046347 | controller | skipping: Conditional result was False 2026-01-05 00:09:09.064504 | 2026-01-05 00:09:09.064658 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-01-05 00:09:09.520674 | controller -> localhost | changed 2026-01-05 00:09:09.533289 | 2026-01-05 00:09:09.533370 | TASK [add-build-sshkey : Add back temp key] 2026-01-05 00:09:09.783246 | controller -> localhost | Identity added: /var/lib/zuul/builds/73f2cf9b4f5e483fa64028576d644257/work/73f2cf9b4f5e483fa64028576d644257_id_rsa (zuul-build-sshkey) 2026-01-05 00:09:09.783477 | controller -> localhost | ok: Runtime: 0:00:00.009429 2026-01-05 00:09:09.789858 | 2026-01-05 00:09:09.789922 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-01-05 00:09:10.127383 | controller | ok 2026-01-05 00:09:10.137018 | 2026-01-05 00:09:10.137156 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-01-05 00:09:10.164079 | controller | skipping: Conditional result was False 2026-01-05 00:09:10.176819 | 2026-01-05 00:09:10.176919 | TASK [Run add-build-sshkey role (ECDSA)] 2026-01-05 00:09:10.213666 | controller | ok 2026-01-05 00:09:10.245761 | 2026-01-05 00:09:10.245912 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-01-05 00:09:10.497808 | controller -> localhost | ok 2026-01-05 00:09:10.504659 | 2026-01-05 00:09:10.504743 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-01-05 00:09:10.534917 | controller | ok 2026-01-05 00:09:10.546616 | controller | included: /var/lib/zuul/builds/73f2cf9b4f5e483fa64028576d644257/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-01-05 00:09:10.552791 | 2026-01-05 00:09:10.552855 | TASK [add-build-sshkey : Create Temp SSH key] 2026-01-05 00:09:10.891891 | controller -> localhost | Generating public/private ecdsa key pair. 2026-01-05 00:09:10.892127 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/73f2cf9b4f5e483fa64028576d644257/work/73f2cf9b4f5e483fa64028576d644257_id_ecdsa. 2026-01-05 00:09:10.892161 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/73f2cf9b4f5e483fa64028576d644257/work/73f2cf9b4f5e483fa64028576d644257_id_ecdsa.pub. 2026-01-05 00:09:10.892192 | controller -> localhost | The key fingerprint is: 2026-01-05 00:09:10.892213 | controller -> localhost | SHA256:KJA4cOfhr7iANA0r7rBkUi8xoiI7S5RHiASChOgP23E zuul-build-sshkey 2026-01-05 00:09:10.892232 | controller -> localhost | The key's randomart image is: 2026-01-05 00:09:10.892250 | controller -> localhost | +---[ECDSA 521]---+ 2026-01-05 00:09:10.892268 | controller -> localhost | |Xo. o | 2026-01-05 00:09:10.892285 | controller -> localhost | |Oo.= . | 2026-01-05 00:09:10.892302 | controller -> localhost | |=oo.o | 2026-01-05 00:09:10.892319 | controller -> localhost | | +*o E . | 2026-01-05 00:09:10.892336 | controller -> localhost | |o*Oo+ o S | 2026-01-05 00:09:10.892353 | controller -> localhost | |B+oB o | 2026-01-05 00:09:10.892387 | controller -> localhost | |O=o o | 2026-01-05 00:09:10.892407 | controller -> localhost | |@+ o | 2026-01-05 00:09:10.892424 | controller -> localhost | |++. | 2026-01-05 00:09:10.892441 | controller -> localhost | +----[SHA256]-----+ 2026-01-05 00:09:10.892493 | controller -> localhost | ok: Runtime: 0:00:00.013525 2026-01-05 00:09:10.900527 | 2026-01-05 00:09:10.900599 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-01-05 00:09:10.931552 | controller | ok 2026-01-05 00:09:10.939621 | controller | included: /var/lib/zuul/builds/73f2cf9b4f5e483fa64028576d644257/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-01-05 00:09:10.949907 | 2026-01-05 00:09:10.949987 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-01-05 00:09:10.974640 | controller | skipping: Conditional result was False 2026-01-05 00:09:10.981671 | 2026-01-05 00:09:10.981762 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-01-05 00:09:11.293783 | controller | changed 2026-01-05 00:09:11.308770 | 2026-01-05 00:09:11.308946 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-01-05 00:09:11.536087 | controller | ok 2026-01-05 00:09:11.543282 | 2026-01-05 00:09:11.543392 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-01-05 00:09:12.146717 | controller | changed 2026-01-05 00:09:12.156272 | 2026-01-05 00:09:12.156431 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-01-05 00:09:12.797786 | controller | changed 2026-01-05 00:09:12.806501 | 2026-01-05 00:09:12.806593 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-01-05 00:09:12.833149 | controller | skipping: Conditional result was False 2026-01-05 00:09:12.844548 | 2026-01-05 00:09:12.844658 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-01-05 00:09:13.116640 | controller -> localhost | changed 2026-01-05 00:09:13.134201 | 2026-01-05 00:09:13.134462 | TASK [add-build-sshkey : Add back temp key] 2026-01-05 00:09:13.470988 | controller -> localhost | Identity added: /var/lib/zuul/builds/73f2cf9b4f5e483fa64028576d644257/work/73f2cf9b4f5e483fa64028576d644257_id_ecdsa (zuul-build-sshkey) 2026-01-05 00:09:13.471233 | controller -> localhost | ok: Runtime: 0:00:00.010026 2026-01-05 00:09:13.513162 | 2026-01-05 00:09:13.513292 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-01-05 00:09:13.763939 | controller | ok 2026-01-05 00:09:13.776734 | 2026-01-05 00:09:13.776896 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-01-05 00:09:13.804881 | controller | skipping: Conditional result was False 2026-01-05 00:09:13.832668 | 2026-01-05 00:09:13.832933 | TASK [include_role : remove-zuul-sshkey] 2026-01-05 00:09:13.860391 | controller | skipping: Conditional result was False 2026-01-05 00:09:13.869804 | 2026-01-05 00:09:13.869926 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-01-05 00:09:14.148585 | controller | ok: "logs" 2026-01-05 00:09:14.148877 | controller | ok: All items complete 2026-01-05 00:09:14.148912 | 2026-01-05 00:09:14.331001 | controller | ok: "artifacts" 2026-01-05 00:09:14.528180 | controller | ok: "docs" 2026-01-05 00:09:14.543744 | 2026-01-05 00:09:14.543875 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-01-05 00:09:14.793532 | controller | changed: "logs" 2026-01-05 00:09:14.996796 | controller | changed: "artifacts" 2026-01-05 00:09:15.238998 | controller | changed: "docs" 2026-01-05 00:09:15.302980 | 2026-01-05 00:09:15.303095 | PLAY RECAP 2026-01-05 00:09:15.303141 | controller | ok: 30 changed: 16 unreachable: 0 failed: 0 skipped: 18 rescued: 0 ignored: 0 2026-01-05 00:09:15.303167 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-01-05 00:09:15.303187 | 2026-01-05 00:09:15.462111 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master] 2026-01-05 00:09:15.463399 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2026-01-05 00:09:16.155330 | 2026-01-05 00:09:16.156003 | PLAY [all] 2026-01-05 00:09:16.199436 | 2026-01-05 00:09:16.199580 | TASK [Install binary dependencies] 2026-01-05 00:09:16.250486 | controller | ok 2026-01-05 00:09:16.287809 | 2026-01-05 00:09:16.287959 | TASK [bindep : Include find tasks] 2026-01-05 00:09:16.328849 | controller | ok 2026-01-05 00:09:16.337637 | controller | included: /var/lib/zuul/builds/73f2cf9b4f5e483fa64028576d644257/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find.yaml 2026-01-05 00:09:16.343965 | 2026-01-05 00:09:16.344029 | TASK [bindep : Look for bindep.txt] 2026-01-05 00:09:16.731881 | controller | ok 2026-01-05 00:09:16.739262 | 2026-01-05 00:09:16.739358 | TASK [bindep : Define bindep_file fact] 2026-01-05 00:09:16.763908 | controller | skipping: Conditional result was False 2026-01-05 00:09:16.771062 | 2026-01-05 00:09:16.771150 | TASK [bindep : Look for other-requirements.txt] 2026-01-05 00:09:16.996629 | controller | ok 2026-01-05 00:09:17.004615 | 2026-01-05 00:09:17.004756 | TASK [bindep : Define bindep_file fact] 2026-01-05 00:09:17.030510 | controller | skipping: Conditional result was False 2026-01-05 00:09:17.040308 | 2026-01-05 00:09:17.040432 | TASK [bindep : Look for bindep fallback file] 2026-01-05 00:09:17.066841 | controller | skipping: Conditional result was False 2026-01-05 00:09:17.074398 | 2026-01-05 00:09:17.074479 | TASK [bindep : Define bindep_file fact] 2026-01-05 00:09:17.098244 | controller | skipping: Conditional result was False 2026-01-05 00:09:17.105100 | 2026-01-05 00:09:17.105174 | TASK [bindep : Include bindep tasks] 2026-01-05 00:09:17.129094 | controller | skipping: Conditional result was False 2026-01-05 00:09:17.135726 | 2026-01-05 00:09:17.135799 | TASK [bindep : Include install tasks] 2026-01-05 00:09:17.159798 | controller | skipping: Conditional result was False 2026-01-05 00:09:17.166779 | 2026-01-05 00:09:17.166864 | LOOP [bindep : Include package tasks] 2026-01-05 00:09:17.221936 | 2026-01-05 00:09:17.222092 | TASK [Run test-setup role] 2026-01-05 00:09:17.242286 | controller | ok 2026-01-05 00:09:17.260512 | 2026-01-05 00:09:17.260588 | TASK [test-setup : Check if project's tools/test-setup.sh exists] 2026-01-05 00:09:17.491386 | controller | ok 2026-01-05 00:09:17.507089 | 2026-01-05 00:09:17.507403 | TASK [test-setup : Run tools/test-setup.sh] 2026-01-05 00:09:17.807522 | controller | skipping: Conditional result was False 2026-01-05 00:09:17.866917 | 2026-01-05 00:09:17.867083 | PLAY RECAP 2026-01-05 00:09:17.867162 | controller | ok: 4 changed: 0 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2026-01-05 00:09:17.867204 | 2026-01-05 00:09:17.986114 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2026-01-05 00:09:17.987004 | PRE-RUN START: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master] 2026-01-05 00:09:18.627867 | 2026-01-05 00:09:18.628033 | PLAY [controller] 2026-01-05 00:09:18.649311 | 2026-01-05 00:09:18.649428 | TASK [Create the /root directory] 2026-01-05 00:09:19.025949 | controller | ok 2026-01-05 00:09:19.031770 | 2026-01-05 00:09:19.031846 | TASK [Install glibc-langpack-en] 2026-01-05 00:09:23.126715 | controller | ok: Nothing to do 2026-01-05 00:09:23.132624 | 2026-01-05 00:09:23.132720 | TASK [Ensure controller directory exists] 2026-01-05 00:09:23.345325 | controller | changed 2026-01-05 00:09:23.359255 | 2026-01-05 00:09:23.359408 | TASK [Install container runtime] 2026-01-05 00:09:23.411412 | controller | ok 2026-01-05 00:09:23.481322 | 2026-01-05 00:09:23.481475 | LOOP [ensure-docker : Gather variables for each operating system] 2026-01-05 00:09:23.520194 | controller | ok: "/var/lib/zuul/builds/73f2cf9b4f5e483fa64028576d644257/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/vars/fedora.yaml" 2026-01-05 00:09:23.531058 | 2026-01-05 00:09:23.531184 | TASK [ensure-docker : Sanity check for distro selection] 2026-01-05 00:09:23.546493 | controller | skipping: Conditional result was False 2026-01-05 00:09:23.555833 | 2026-01-05 00:09:23.555934 | TASK [ensure-docker : Sanity check for distro install] 2026-01-05 00:09:23.581353 | controller | skipping: Conditional result was False 2026-01-05 00:09:23.590498 | 2026-01-05 00:09:23.590614 | TASK [ensure-docker : Sanity check for upstream install] 2026-01-05 00:09:23.626062 | controller | skipping: Conditional result was False 2026-01-05 00:09:23.636393 | 2026-01-05 00:09:23.636498 | TASK [ensure-docker : Sanity check for upstream install mirrors] 2026-01-05 00:09:23.662045 | controller | skipping: Conditional result was False 2026-01-05 00:09:23.670503 | 2026-01-05 00:09:23.670596 | TASK [ensure-docker : Include tasks for docker install] 2026-01-05 00:09:23.690450 | controller | ok 2026-01-05 00:09:23.699794 | controller | included: /var/lib/zuul/builds/73f2cf9b4f5e483fa64028576d644257/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/tasks/docker-upstream.yaml 2026-01-05 00:09:23.708259 | 2026-01-05 00:09:23.708331 | TASK [ensure-docker : Not Implemented] 2026-01-05 00:09:23.732453 | controller | skipping: Conditional result was False 2026-01-05 00:09:23.738809 | 2026-01-05 00:09:23.738875 | TASK [Set up docker mirrors] 2026-01-05 00:09:23.758024 | controller | ok 2026-01-05 00:09:23.779726 | 2026-01-05 00:09:23.779868 | TASK [use-docker-mirror : Set docker_mirror fact from mirror_info] 2026-01-05 00:09:23.804064 | controller | skipping: Conditional result was False 2026-01-05 00:09:23.810169 | 2026-01-05 00:09:23.810234 | TASK [use-docker-mirror : Set docker_mirror fact from zuul_site_mirror_info] 2026-01-05 00:09:23.834099 | controller | skipping: Conditional result was False 2026-01-05 00:09:23.840084 | 2026-01-05 00:09:23.840158 | TASK [use-docker-mirror : Set docker_mirror fact] 2026-01-05 00:09:23.864048 | controller | skipping: Conditional result was False 2026-01-05 00:09:23.870487 | 2026-01-05 00:09:23.870549 | TASK [use-docker-mirror : Set up docker mirrors] 2026-01-05 00:09:23.894723 | controller | skipping: Conditional result was False 2026-01-05 00:09:23.907715 | 2026-01-05 00:09:23.907825 | TASK [Add all repositories] 2026-01-05 00:09:23.927585 | controller | ok 2026-01-05 00:09:23.953968 | 2026-01-05 00:09:23.954061 | LOOP [ensure-package-repositories : Install repositories] 2026-01-05 00:09:23.977182 | controller | ok: "/var/lib/zuul/builds/73f2cf9b4f5e483fa64028576d644257/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-package-repositories/tasks/RedHat.yaml" 2026-01-05 00:09:23.977553 | controller | ok: All items complete 2026-01-05 00:09:23.977587 | 2026-01-05 00:09:24.027124 | controller | included: /var/lib/zuul/builds/73f2cf9b4f5e483fa64028576d644257/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-package-repositories/tasks/RedHat.yaml 2026-01-05 00:09:24.034706 | 2026-01-05 00:09:24.034773 | TASK [ensure-package-repositories : Create GPG key temp directory] 2026-01-05 00:09:24.332567 | controller | changed 2026-01-05 00:09:24.340771 | 2026-01-05 00:09:24.340846 | LOOP [ensure-package-repositories : Upload GPG keys] 2026-01-05 00:09:25.318008 | controller | changed: 2026-01-05 00:09:25.318244 | controller | { 2026-01-05 00:09:25.318282 | 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-05 00:09:25.318321 | controller | } 2026-01-05 00:09:25.335696 | 2026-01-05 00:09:25.335835 | LOOP [ensure-package-repositories : Import GPG keys] 2026-01-05 00:09:26.136422 | controller | changed: 2026-01-05 00:09:26.136636 | controller | { 2026-01-05 00:09:26.136699 | 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-05 00:09:26.136741 | controller | } 2026-01-05 00:09:26.136782 | controller | changed: All items complete 2026-01-05 00:09:26.136812 | 2026-01-05 00:09:26.147630 | 2026-01-05 00:09:26.147773 | TASK [ensure-package-repositories : Remove GPG temp directory] 2026-01-05 00:09:26.362521 | controller | changed 2026-01-05 00:09:26.369245 | 2026-01-05 00:09:26.369318 | LOOP [ensure-package-repositories : Add repositories] 2026-01-05 00:09:26.743276 | controller | changed: 2026-01-05 00:09:26.743453 | controller | { 2026-01-05 00:09:26.743479 | controller | "baseurl": "https://download.docker.com/linux/fedora/$releasever/$basearch/stable", 2026-01-05 00:09:26.743499 | controller | "description": "Docker CE Stable - $basearch", 2026-01-05 00:09:26.743517 | controller | "gpgcheck": true, 2026-01-05 00:09:26.743535 | controller | "name": "docker-ce-stable" 2026-01-05 00:09:26.743552 | controller | } 2026-01-05 00:09:26.771622 | 2026-01-05 00:09:26.771764 | TASK [ensure-docker : Install pre-reqs] 2026-01-05 00:09:29.727248 | controller | ok: Nothing to do 2026-01-05 00:09:29.734530 | 2026-01-05 00:09:29.734598 | TASK [ensure-docker : Remove packages] 2026-01-05 00:09:31.068538 | controller | ok: Nothing to do 2026-01-05 00:09:31.074797 | 2026-01-05 00:09:31.074889 | TASK [ensure-docker : Install upstream docker using package] 2026-01-05 00:10:18.088026 | controller | changed 2026-01-05 00:10:18.105003 | 2026-01-05 00:10:18.105202 | TASK [ensure-docker : Setup docker] 2026-01-05 00:10:18.137935 | controller | ok 2026-01-05 00:10:18.161195 | controller | included: /var/lib/zuul/builds/73f2cf9b4f5e483fa64028576d644257/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/tasks/docker-setup.yaml 2026-01-05 00:10:18.179105 | 2026-01-05 00:10:18.179224 | TASK [ensure-docker : Ensure "docker" group exists] 2026-01-05 00:10:18.566056 | controller | ok 2026-01-05 00:10:18.572472 | 2026-01-05 00:10:18.572536 | TASK [ensure-docker : Add user to docker group] 2026-01-05 00:10:19.098476 | controller | changed 2026-01-05 00:10:19.115243 | 2026-01-05 00:10:19.115541 | TASK [Update docker daemon proxy configuration] 2026-01-05 00:10:19.165631 | controller | skipping: Conditional result was False 2026-01-05 00:10:19.174476 | 2026-01-05 00:10:19.174564 | TASK [Lower default MTU] 2026-01-05 00:10:19.201051 | controller | skipping: Conditional result was False 2026-01-05 00:10:19.218940 | 2026-01-05 00:10:19.219054 | TASK [ensure-docker : Get the status of the docket.socket unit] 2026-01-05 00:10:19.600422 | controller | inactive 2026-01-05 00:10:19.794162 | controller | ok: Runtime: 0:00:00.013480 2026-01-05 00:10:19.802533 | 2026-01-05 00:10:19.802613 | TASK [ensure-docker : Get the status of the docket unit] 2026-01-05 00:10:20.063230 | controller | inactive 2026-01-05 00:10:20.366016 | controller | ok: Runtime: 0:00:00.007231 2026-01-05 00:10:20.372883 | 2026-01-05 00:10:20.372944 | TASK [ensure-docker : Restart docker] 2026-01-05 00:10:20.430709 | Trigger a Docker restart 2026-01-05 00:10:20.442456 | 2026-01-05 00:10:20.442580 | TASK [ensure-docker : Reset ssh connection to pick up docker group] 2026-01-05 00:10:20.488786 | 2026-01-05 00:10:20.488920 | TASK [ensure-docker : Install docker-compose] 2026-01-05 00:10:20.514116 | controller | skipping: Conditional result was False 2026-01-05 00:10:20.523407 | 2026-01-05 00:10:20.523502 | TASK [ensure-docker : Flush handlers before role exit] 2026-01-05 00:10:20.539139 | 2026-01-05 00:10:20.539230 | TASK [ensure-docker : Stop docker.socket to avoid any conflict] 2026-01-05 00:10:21.846396 | controller | changed 2026-01-05 00:10:21.863835 | 2026-01-05 00:10:21.864007 | TASK [ensure-docker : Assure docker service is running] 2026-01-05 00:10:23.913482 | controller | changed 2026-01-05 00:10:23.921179 | 2026-01-05 00:10:23.921243 | TASK [ensure-docker : Assure docker.socket service is running] 2026-01-05 00:10:24.830527 | controller | ok 2026-01-05 00:10:24.841984 | 2026-01-05 00:10:24.842252 | TASK [ensure-docker : Correct group ownership on docker sock] 2026-01-05 00:10:25.417919 | controller | ok 2026-01-05 00:10:25.425978 | 2026-01-05 00:10:25.426081 | TASK [ensure-docker : Validate ability to talk with docker] 2026-01-05 00:10:25.971555 | controller | CONTAINER ID IMAGE COMMAND CREATED STATUS PORTS NAMES 2026-01-05 00:10:26.461023 | controller | ok: Runtime: 0:00:00.049011 2026-01-05 00:10:26.477542 | 2026-01-05 00:10:26.477709 | TASK [ensure-docker : Get version details] 2026-01-05 00:10:27.123086 | controller | Client: Docker Engine - Community 2026-01-05 00:10:27.124252 | controller | Version: 24.0.7 2026-01-05 00:10:27.124269 | controller | API version: 1.43 2026-01-05 00:10:27.124275 | controller | Go version: go1.20.10 2026-01-05 00:10:27.124287 | controller | Git commit: afdd53b 2026-01-05 00:10:27.124292 | controller | Built: Thu Oct 26 09:09:39 2023 2026-01-05 00:10:27.124297 | controller | OS/Arch: linux/amd64 2026-01-05 00:10:27.124302 | controller | Context: default 2026-01-05 00:10:27.124308 | controller | 2026-01-05 00:10:27.124313 | controller | Server: Docker Engine - Community 2026-01-05 00:10:27.124317 | controller | Engine: 2026-01-05 00:10:27.124322 | controller | Version: 24.0.7 2026-01-05 00:10:27.124326 | controller | API version: 1.43 (minimum version 1.12) 2026-01-05 00:10:27.124330 | controller | Go version: go1.20.10 2026-01-05 00:10:27.124334 | controller | Git commit: 311b9ff 2026-01-05 00:10:27.124338 | controller | Built: Thu Oct 26 09:08:11 2023 2026-01-05 00:10:27.124342 | controller | OS/Arch: linux/amd64 2026-01-05 00:10:27.124347 | controller | Experimental: false 2026-01-05 00:10:27.124351 | controller | containerd: 2026-01-05 00:10:27.124355 | controller | Version: 1.6.25 2026-01-05 00:10:27.124359 | controller | GitCommit: d8f198a4ed8892c764191ef7b3b06d8a2eeb5c7f 2026-01-05 00:10:27.124363 | controller | runc: 2026-01-05 00:10:27.124367 | controller | Version: 1.1.10 2026-01-05 00:10:27.124372 | controller | GitCommit: v1.1.10-0-g18a0cb0 2026-01-05 00:10:27.124378 | controller | docker-init: 2026-01-05 00:10:27.124382 | controller | Version: 0.19.0 2026-01-05 00:10:27.124386 | controller | GitCommit: de40ad0 2026-01-05 00:10:27.528625 | controller | ok: Runtime: 0:00:00.088908 2026-01-05 00:10:27.546965 | 2026-01-05 00:10:27.547230 | TASK [ensure-docker : Dump installed docker details] 2026-01-05 00:10:27.600020 | {'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-05 00:10:27.035683', 'end': '2026-01-05 00:10:27.124591', 'delta': '0:00:00.088908', 'msg': '', 'zuul_log_id': 'fa163e3b-3c83-7449-7de0-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-05 00:10:27.617637 | 2026-01-05 00:10:27.617800 | TASK [Use buildset registry] 2026-01-05 00:10:27.644328 | controller | skipping: Conditional result was False 2026-01-05 00:10:27.673076 | 2026-01-05 00:10:27.673295 | TASK [Ensure python3.8 is present] 2026-01-05 00:10:27.704386 | controller | skipping: Conditional result was False 2026-01-05 00:10:27.717881 | 2026-01-05 00:10:27.718059 | TASK [Run our-ensure-python role (Fedora only for now)] 2026-01-05 00:10:27.743750 | controller | ok 2026-01-05 00:10:27.772850 | 2026-01-05 00:10:27.773006 | TASK [our-ensure-python : Install the right Python version (rpm)] 2026-01-05 00:10:29.929802 | controller | ok: Nothing to do 2026-01-05 00:10:29.946638 | 2026-01-05 00:10:29.946861 | TASK [our-ensure-python : Also install python3-devel] 2026-01-05 00:10:44.486582 | controller | changed 2026-01-05 00:10:44.514095 | 2026-01-05 00:10:44.514243 | TASK [Run ensure-virtualenv role] 2026-01-05 00:10:44.543935 | controller | ok 2026-01-05 00:10:44.581080 | 2026-01-05 00:10:44.581196 | TASK [ensure-virtualenv : Check if virtualenv is installed] 2026-01-05 00:10:44.816151 | controller | /usr/bin/virtualenv 2026-01-05 00:10:45.121794 | controller | ok: Runtime: 0:00:00.003454 2026-01-05 00:10:45.128178 | 2026-01-05 00:10:45.128255 | LOOP [ensure-virtualenv : Install virtualenv package] 2026-01-05 00:10:45.147149 | controller | skipping: Conditional result was False 2026-01-05 00:10:45.147578 | controller | changed: All items complete 2026-01-05 00:10:45.147643 | 2026-01-05 00:10:45.176670 | 2026-01-05 00:10:45.176863 | TASK [Find the full path of the Python interpreter] 2026-01-05 00:10:45.416369 | controller | /usr/bin/python3.9 2026-01-05 00:10:45.724405 | controller | ok 2026-01-05 00:10:45.732613 | 2026-01-05 00:10:45.732759 | TASK [Create virtualenv for ansible-test with a specific interpreter] 2026-01-05 00:10:47.454115 | controller | created virtual environment CPython3.9.18.final.0-64 in 874ms 2026-01-05 00:10:47.517619 | controller | creator CPython3Posix(dest=/home/zuul/venv, clear=False, no_vcs_ignore=False, global=False) 2026-01-05 00:10:47.517658 | 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-05 00:10:47.517671 | controller | added seed packages: pip==22.2.2, setuptools==62.6.0, wheel==0.37.1 2026-01-05 00:10:47.517684 | controller | activators BashActivator,CShellActivator,FishActivator,NushellActivator,PowerShellActivator,PythonActivator 2026-01-05 00:10:47.788829 | controller | changed 2026-01-05 00:10:47.801792 | 2026-01-05 00:10:47.801963 | TASK [Set selinux package] 2026-01-05 00:10:47.841127 | controller | ok 2026-01-05 00:10:47.852181 | 2026-01-05 00:10:47.852309 | TASK [Set selinux package (Fedora)] 2026-01-05 00:10:47.904175 | controller | ok 2026-01-05 00:10:47.917576 | 2026-01-05 00:10:47.917764 | TASK [Install selinux into virtualenv] 2026-01-05 00:11:15.652419 | controller | Collecting selinux-please-lie-to-me 2026-01-05 00:11:27.871946 | controller | Downloading selinux_please_lie_to_me-1.0.1-py2.py3-none-any.whl (3.8 kB) 2026-01-05 00:11:28.223160 | controller | Collecting setuptools<50.0.0 2026-01-05 00:11:28.229642 | controller | Downloading setuptools-49.6.0-py3-none-any.whl (803 kB) 2026-01-05 00:11:28.300840 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 803.3/803.3 kB 13.5 MB/s eta 0:00:00 2026-01-05 00:11:28.379082 | controller | Installing collected packages: setuptools, selinux-please-lie-to-me 2026-01-05 00:11:28.379252 | controller | Attempting uninstall: setuptools 2026-01-05 00:11:28.379872 | controller | Found existing installation: setuptools 62.6.0 2026-01-05 00:11:28.452631 | controller | Uninstalling setuptools-62.6.0: 2026-01-05 00:11:28.461679 | controller | Successfully uninstalled setuptools-62.6.0 2026-01-05 00:11:28.793307 | controller | Successfully installed selinux-please-lie-to-me-1.0.1 setuptools-49.6.0 2026-01-05 00:11:40.242236 | controller | 2026-01-05 00:11:40.323464 | controller | [notice] A new release of pip available: 22.2.2 -> 25.3 2026-01-05 00:11:40.323510 | controller | [notice] To update, run: /home/zuul/venv/bin/python -m pip install --upgrade pip 2026-01-05 00:11:40.488816 | controller | ok: Runtime: 0:00:51.863429 2026-01-05 00:11:40.502228 | 2026-01-05 00:11:40.502367 | TASK [Install pytest-forked into virtualenv] 2026-01-05 00:12:02.384507 | controller | Collecting pytest-forked 2026-01-05 00:12:14.713851 | controller | Downloading pytest_forked-1.6.0-py3-none-any.whl (4.9 kB) 2026-01-05 00:12:14.767935 | controller | Collecting py 2026-01-05 00:12:14.773452 | controller | Downloading py-1.11.0-py2.py3-none-any.whl (98 kB) 2026-01-05 00:12:14.794695 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 98.7/98.7 kB 5.3 MB/s eta 0:00:00 2026-01-05 00:12:14.920570 | controller | Collecting pytest>=3.10 2026-01-05 00:12:14.936504 | controller | Downloading pytest-8.4.2-py3-none-any.whl (365 kB) 2026-01-05 00:12:14.967749 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 365.8/365.8 kB 12.6 MB/s eta 0:00:00 2026-01-05 00:12:15.006688 | controller | Collecting iniconfig>=1 2026-01-05 00:12:15.009935 | controller | Downloading iniconfig-2.1.0-py3-none-any.whl (6.0 kB) 2026-01-05 00:12:15.058462 | controller | Collecting packaging>=20 2026-01-05 00:12:15.062433 | controller | Downloading packaging-25.0-py3-none-any.whl (66 kB) 2026-01-05 00:12:15.069389 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 66.5/66.5 kB 14.4 MB/s eta 0:00:00 2026-01-05 00:12:15.112376 | controller | Collecting tomli>=1 2026-01-05 00:12:15.126443 | controller | Downloading tomli-2.3.0-py3-none-any.whl (14 kB) 2026-01-05 00:12:15.180750 | controller | Collecting pygments>=2.7.2 2026-01-05 00:12:15.184717 | controller | Downloading pygments-2.19.2-py3-none-any.whl (1.2 MB) 2026-01-05 00:12:15.231444 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 1.2/1.2 MB 28.5 MB/s eta 0:00:00 2026-01-05 00:12:15.266759 | controller | Collecting pluggy<2,>=1.5 2026-01-05 00:12:15.270330 | controller | Downloading pluggy-1.6.0-py3-none-any.whl (20 kB) 2026-01-05 00:12:15.300363 | controller | Collecting exceptiongroup>=1 2026-01-05 00:12:15.303588 | controller | Downloading exceptiongroup-1.3.1-py3-none-any.whl (16 kB) 2026-01-05 00:12:15.351025 | controller | Collecting typing-extensions>=4.6.0 2026-01-05 00:12:15.354500 | controller | Downloading typing_extensions-4.15.0-py3-none-any.whl (44 kB) 2026-01-05 00:12:15.361493 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 44.6/44.6 kB 7.4 MB/s eta 0:00:00 2026-01-05 00:12:15.427507 | controller | Installing collected packages: typing-extensions, tomli, pygments, py, pluggy, packaging, iniconfig, exceptiongroup, pytest, pytest-forked 2026-01-05 00:12:16.505093 | controller | Successfully installed exceptiongroup-1.3.1 iniconfig-2.1.0 packaging-25.0 pluggy-1.6.0 py-1.11.0 pygments-2.19.2 pytest-8.4.2 pytest-forked-1.6.0 tomli-2.3.0 typing-extensions-4.15.0 2026-01-05 00:12:16.510097 | controller | 2026-01-05 00:12:16.622931 | controller | [notice] A new release of pip available: 22.2.2 -> 25.3 2026-01-05 00:12:16.622962 | controller | [notice] To update, run: /home/zuul/venv/bin/python -m pip install --upgrade pip 2026-01-05 00:12:17.118969 | controller | ok: Runtime: 0:00:35.858320 2026-01-05 00:12:17.125085 | 2026-01-05 00:12:17.125158 | TASK [Update pip] 2026-01-05 00:12:17.647400 | controller | Requirement already satisfied: pip in ./venv/lib/python3.9/site-packages (22.2.2) 2026-01-05 00:12:27.090460 | controller | Collecting pip 2026-01-05 00:13:03.162916 | controller | Downloading pip-25.3-py3-none-any.whl (1.8 MB) 2026-01-05 00:13:03.241030 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 1.8/1.8 MB 24.4 MB/s eta 0:00:00 2026-01-05 00:13:03.300247 | controller | Installing collected packages: pip 2026-01-05 00:13:03.300345 | controller | Attempting uninstall: pip 2026-01-05 00:13:03.300365 | controller | Found existing installation: pip 22.2.2 2026-01-05 00:13:03.466953 | controller | Uninstalling pip-22.2.2: 2026-01-05 00:13:03.485003 | controller | Successfully uninstalled pip-22.2.2 2026-01-05 00:13:04.267289 | controller | Successfully installed pip-25.3 2026-01-05 00:13:04.683628 | controller | ok: Runtime: 0:00:47.008312 2026-01-05 00:13:04.690752 | 2026-01-05 00:13:04.690888 | TASK [Ensure pip temp directory exists in /var/tmp] 2026-01-05 00:13:04.925083 | controller | changed 2026-01-05 00:13:04.938311 | 2026-01-05 00:13:04.938478 | TASK [Install ansible into virtualenv] 2026-01-05 00:13:05.458919 | controller | Processing ./src/github.com/ansible/ansible 2026-01-05 00:13:05.463120 | controller | Installing build dependencies: started 2026-01-05 00:13:28.329747 | controller | Installing build dependencies: finished with status 'done' 2026-01-05 00:13:28.330781 | controller | Getting requirements to build wheel: started 2026-01-05 00:13:28.947277 | controller | Getting requirements to build wheel: finished with status 'done' 2026-01-05 00:13:28.951645 | controller | Preparing metadata (pyproject.toml): started 2026-01-05 00:13:29.435743 | controller | Preparing metadata (pyproject.toml): finished with status 'done' 2026-01-05 00:13:40.312736 | controller | Collecting jinja2 (from ansible-core==2.11.12.post0) 2026-01-05 00:14:04.533909 | controller | Downloading jinja2-3.1.6-py3-none-any.whl.metadata (2.9 kB) 2026-01-05 00:14:04.615016 | controller | Collecting PyYAML (from ansible-core==2.11.12.post0) 2026-01-05 00:14:04.621612 | 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-05 00:14:04.950903 | controller | Collecting cryptography (from ansible-core==2.11.12.post0) 2026-01-05 00:14:04.956517 | controller | Downloading cryptography-46.0.3-cp38-abi3-manylinux_2_34_x86_64.whl.metadata (5.7 kB) 2026-01-05 00:14:04.974764 | controller | Requirement already satisfied: packaging in ./venv/lib/python3.9/site-packages (from ansible-core==2.11.12.post0) (25.0) 2026-01-05 00:14:04.994428 | controller | Collecting resolvelib<0.6.0,>=0.5.3 (from ansible-core==2.11.12.post0) 2026-01-05 00:14:05.012644 | controller | Downloading resolvelib-0.5.4-py2.py3-none-any.whl.metadata (3.7 kB) 2026-01-05 00:14:05.254030 | controller | Collecting cffi>=2.0.0 (from cryptography->ansible-core==2.11.12.post0) 2026-01-05 00:14:05.260296 | controller | Downloading cffi-2.0.0-cp39-cp39-manylinux2014_x86_64.manylinux_2_17_x86_64.whl.metadata (2.6 kB) 2026-01-05 00:14:05.268012 | 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-05 00:14:05.290265 | controller | Collecting pycparser (from cffi>=2.0.0->cryptography->ansible-core==2.11.12.post0) 2026-01-05 00:14:05.294600 | controller | Downloading pycparser-2.23-py3-none-any.whl.metadata (993 bytes) 2026-01-05 00:14:05.385493 | controller | Collecting MarkupSafe>=2.0 (from jinja2->ansible-core==2.11.12.post0) 2026-01-05 00:14:05.390464 | 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-05 00:14:05.407403 | controller | Downloading resolvelib-0.5.4-py2.py3-none-any.whl (12 kB) 2026-01-05 00:14:05.418836 | controller | Downloading cryptography-46.0.3-cp38-abi3-manylinux_2_34_x86_64.whl (4.5 MB) 2026-01-05 00:14:05.493706 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 4.5/4.5 MB 65.7 MB/s 0:00:00 2026-01-05 00:14:05.499304 | controller | Downloading cffi-2.0.0-cp39-cp39-manylinux2014_x86_64.manylinux_2_17_x86_64.whl (216 kB) 2026-01-05 00:14:05.512784 | controller | Downloading jinja2-3.1.6-py3-none-any.whl (134 kB) 2026-01-05 00:14:05.527918 | 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-05 00:14:05.539076 | controller | Downloading pycparser-2.23-py3-none-any.whl (118 kB) 2026-01-05 00:14:05.552077 | 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-05 00:14:05.591689 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 750.8/750.8 kB 14.9 MB/s 0:00:00 2026-01-05 00:14:05.605340 | controller | Building wheels for collected packages: ansible-core 2026-01-05 00:14:05.606467 | controller | Building wheel for ansible-core (pyproject.toml): started 2026-01-05 00:14:07.073998 | controller | Building wheel for ansible-core (pyproject.toml): finished with status 'done' 2026-01-05 00:14:07.079446 | controller | Created wheel for ansible-core: filename=ansible_core-2.11.12.post0-py3-none-any.whl size=1961250 sha256=a5550b2163aef8fa7287596d49f9f99c295a677059de0900678a661c23720269 2026-01-05 00:14:07.079760 | controller | Stored in directory: /var/tmp/ansible-test-pip/pip-ephem-wheel-cache-qrior2a6/wheels/0f/a0/93/ab85b739e1eb1e0467171316f13571c14efdf87e0d5d44a33e 2026-01-05 00:14:07.091048 | controller | Successfully built ansible-core 2026-01-05 00:14:07.128519 | controller | Installing collected packages: resolvelib, PyYAML, pycparser, MarkupSafe, jinja2, cffi, cryptography, ansible-core 2026-01-05 00:14:08.597269 | controller | 2026-01-05 00:14:08.600494 | 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-05 00:14:09.043617 | controller | ok: Runtime: 0:01:03.575040 2026-01-05 00:14:09.052495 | 2026-01-05 00:14:09.052995 | TASK [Setup download-artifact-fork role] 2026-01-05 00:14:09.085559 | controller | ok 2026-01-05 00:14:09.122578 | 2026-01-05 00:14:09.122754 | TASK [download-artifact-fork : Parse inventory response] 2026-01-05 00:14:09.178990 | controller | ok 2026-01-05 00:14:09.188914 | 2026-01-05 00:14:09.189082 | TASK [download-artifact-fork : Fetch info from Zuul API] 2026-01-05 00:14:09.217632 | controller | skipping: Conditional result was False 2026-01-05 00:14:09.227766 | 2026-01-05 00:14:09.227911 | TASK [download-artifact-fork : Parse build response] 2026-01-05 00:14:09.254047 | controller | skipping: Conditional result was False 2026-01-05 00:14:09.264496 | 2026-01-05 00:14:09.264624 | TASK [download-artifact-fork : Ensure artifacts directory exists] 2026-01-05 00:14:09.487557 | controller | changed 2026-01-05 00:14:09.495815 | 2026-01-05 00:14:09.495917 | LOOP [download-artifact-fork : Download archive by type] 2026-01-05 00:14:09.529934 | controller | skipping: Conditional result was False 2026-01-05 00:14:09.530204 | 2026-01-05 00:14:10.262859 | controller | changed: OK (20203 bytes) 2026-01-05 00:14:10.305708 | 2026-01-05 00:14:10.305896 | TASK [deploy-artifacts : define __collections] 2026-01-05 00:14:10.336611 | controller | ok 2026-01-05 00:14:10.342134 | 2026-01-05 00:14:10.342194 | LOOP [deploy-artifacts : Create list of collections] 2026-01-05 00:14:10.370737 | controller | skipping: Conditional result was False 2026-01-05 00:14:10.384383 | controller | ok: 2026-01-05 00:14:10.384474 | controller | { 2026-01-05 00:14:10.384504 | controller | "branch": "main", 2026-01-05 00:14:10.384528 | controller | "job": "build-ansible-collection", 2026-01-05 00:14:10.384550 | controller | "metadata": { 2026-01-05 00:14:10.384572 | controller | "type": "ansible_collection", 2026-01-05 00:14:10.384592 | controller | "version": "5.0.0" 2026-01-05 00:14:10.384613 | controller | }, 2026-01-05 00:14:10.384634 | controller | "name": "ansible.network", 2026-01-05 00:14:10.384655 | controller | "project": "ansible-collections/ansible.network", 2026-01-05 00:14:10.384680 | controller | "url": "https://007c5ad67eaf5241f8d6-94663adb5aa29887733a90f90427e567.ssl.cf1.rackcdn.com/ansible/60663e1f75de4f189ee1df5ded4075d0/artifacts/ansible-network-5.0.0.tar.gz" 2026-01-05 00:14:10.384734 | controller | } 2026-01-05 00:14:10.401201 | 2026-01-05 00:14:10.401374 | TASK [deploy-artifacts : Install require-project collection using ansible-galaxy] 2026-01-05 00:14:11.094763 | controller | Starting galaxy collection install process 2026-01-05 00:14:11.095044 | controller | Process install dependency map 2026-01-05 00:15:54.224261 | controller | [WARNING]: Skipping Galaxy server https://galaxy.ansible.com/api/. Got an 2026-01-05 00:15:54.224863 | controller | unexpected error when getting available versions of collection 2026-01-05 00:15:54.224910 | controller | ansible.netcommon: '/api/v3/plugin/ansible/content/published/collections/index/ 2026-01-05 00:15:54.224923 | controller | ansible/netcommon/versions/' 2026-01-05 00:15:54.224939 | controller | ERROR! Unexpected Exception, this is probably a bug: '/api/v3/plugin/ansible/content/published/collections/index/ansible/netcommon/versions/' 2026-01-05 00:15:54.225028 | controller | to see the full traceback, use -vvv 2026-01-05 00:15:54.580000 | controller | ERROR 2026-01-05 00:15:54.580134 | controller | { 2026-01-05 00:15:54.580161 | controller | "delta": "0:01:43.604884", 2026-01-05 00:15:54.580180 | controller | "end": "2026-01-05 00:15:54.275769", 2026-01-05 00:15:54.580196 | controller | "msg": "non-zero return code", 2026-01-05 00:15:54.580212 | controller | "rc": 250, 2026-01-05 00:15:54.580228 | controller | "start": "2026-01-05 00:14:10.670885" 2026-01-05 00:15:54.580243 | controller | } failure 2026-01-05 00:15:54.582243 | 2026-01-05 00:15:54.582291 | PLAY RECAP 2026-01-05 00:15:54.582332 | controller | ok: 44 changed: 24 unreachable: 0 failed: 1 skipped: 17 rescued: 0 ignored: 0 2026-01-05 00:15:54.582352 | 2026-01-05 00:15:54.798056 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master] 2026-01-05 00:15:54.799268 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2026-01-05 00:15:55.461648 | 2026-01-05 00:15:55.461835 | PLAY [all] 2026-01-05 00:15:55.486853 | 2026-01-05 00:15:55.486983 | TASK [fetch-subunit-output : Find stestr or testr executable] 2026-01-05 00:15:55.936626 | controller | changed: non-zero return code 2026-01-05 00:15:55.942873 | 2026-01-05 00:15:55.942950 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2026-01-05 00:15:55.967507 | controller | skipping: Conditional result was False 2026-01-05 00:15:55.973789 | 2026-01-05 00:15:55.973860 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2026-01-05 00:15:56.007460 | 2026-01-05 00:15:56.007632 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2026-01-05 00:15:56.042142 | 2026-01-05 00:15:56.042312 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2026-01-05 00:15:56.067982 | controller | skipping: Conditional result was False 2026-01-05 00:15:56.078099 | 2026-01-05 00:15:56.078190 | LOOP [fetch-subunit-output : Generate subunit file] 2026-01-05 00:15:56.112302 | 2026-01-05 00:15:56.112485 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2026-01-05 00:15:56.138875 | controller | skipping: Conditional result was False 2026-01-05 00:15:56.147504 | 2026-01-05 00:15:56.147597 | TASK [fetch-subunit-output : Remove the temporary file] 2026-01-05 00:15:56.172468 | controller | skipping: Conditional result was False 2026-01-05 00:15:56.180970 | 2026-01-05 00:15:56.181062 | TASK [fetch-subunit-output : Process and fetch subunit results] 2026-01-05 00:15:56.206958 | controller | skipping: Conditional result was False 2026-01-05 00:15:56.243172 | 2026-01-05 00:15:56.243257 | PLAY RECAP 2026-01-05 00:15:56.243329 | controller | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2026-01-05 00:15:56.243366 | 2026-01-05 00:15:56.377178 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2026-01-05 00:15:56.378237 | POST-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/post.yaml@master] 2026-01-05 00:15:57.058241 | 2026-01-05 00:15:57.058517 | PLAY [all:!appliance*] 2026-01-05 00:15:57.092229 | 2026-01-05 00:15:57.092320 | TASK [unregister the node] 2026-01-05 00:15:57.633146 | controller | skipping: Conditional result was False 2026-01-05 00:15:57.639989 | 2026-01-05 00:15:57.640074 | TASK [include_role : fetch-output] 2026-01-05 00:15:57.670283 | controller | ok 2026-01-05 00:15:57.691182 | 2026-01-05 00:15:57.691300 | TASK [fetch-output : Set log path for multiple nodes] 2026-01-05 00:15:57.736346 | controller | skipping: Conditional result was False 2026-01-05 00:15:57.742868 | 2026-01-05 00:15:57.742944 | TASK [fetch-output : Set log path for single node] 2026-01-05 00:15:57.783729 | controller | ok 2026-01-05 00:15:57.789139 | 2026-01-05 00:15:57.789205 | LOOP [fetch-output : Ensure local output dirs] 2026-01-05 00:15:58.276431 | controller -> localhost | ok: "/var/lib/zuul/builds/73f2cf9b4f5e483fa64028576d644257/work/logs" 2026-01-05 00:15:58.585645 | controller -> localhost | changed: "/var/lib/zuul/builds/73f2cf9b4f5e483fa64028576d644257/work/artifacts" 2026-01-05 00:15:58.952565 | controller -> localhost | changed: "/var/lib/zuul/builds/73f2cf9b4f5e483fa64028576d644257/work/docs" 2026-01-05 00:15:58.963000 | 2026-01-05 00:15:58.963174 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-01-05 00:15:59.752179 | controller | changed: 2026-01-05 00:15:59.759724 | controller | .d..t...... ./ 2026-01-05 00:15:59.759782 | controller | cd+++++++++ controller/ 2026-01-05 00:15:59.759823 | controller | changed: All items complete 2026-01-05 00:15:59.759844 | 2026-01-05 00:16:00.293721 | controller | changed: .d..t...... ./ 2026-01-05 00:16:00.800882 | controller | changed: .d..t...... ./ 2026-01-05 00:16:00.854621 | 2026-01-05 00:16:00.854774 | TASK [include_role : fetch-output-openshift] 2026-01-05 00:16:00.879975 | controller | skipping: Conditional result was False 2026-01-05 00:16:00.888395 | 2026-01-05 00:16:00.888493 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-01-05 00:16:00.969422 | controller | skipping: Conditional result was False 2026-01-05 00:16:00.980453 | controller | skipping: Conditional result was False 2026-01-05 00:16:01.051480 | 2026-01-05 00:16:01.051657 | PLAY [localhost] 2026-01-05 00:16:01.077879 | 2026-01-05 00:16:01.078045 | TASK [Run Zuul manifest role] 2026-01-05 00:16:01.101581 | localhost | ok 2026-01-05 00:16:01.136229 | 2026-01-05 00:16:01.136379 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-01-05 00:16:01.578847 | localhost | changed 2026-01-05 00:16:01.605437 | 2026-01-05 00:16:01.605736 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-01-05 00:16:01.656705 | localhost | ok 2026-01-05 00:16:01.667357 | 2026-01-05 00:16:01.667420 | TASK [Set zuul-log-path fact] 2026-01-05 00:16:01.689759 | localhost | ok 2026-01-05 00:16:01.711782 | 2026-01-05 00:16:01.712000 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-01-05 00:16:01.755260 | localhost | ok 2026-01-05 00:16:01.768379 | 2026-01-05 00:16:01.768470 | LOOP [Run upload-logs-swift role] 2026-01-05 00:16:01.805044 | localhost | Output suppressed because no_log was given 2026-01-05 00:16:01.847022 | 2026-01-05 00:16:01.847196 | TASK [Set zuul-log-path fact] 2026-01-05 00:16:01.874216 | localhost | skipping: Conditional result was False 2026-01-05 00:16:01.880072 | 2026-01-05 00:16:01.880192 | TASK [upload-logs-swift : Ensure logs are readable before uploading] 2026-01-05 00:16:02.324946 | localhost -> localhost | ok: Runtime: 0:00:00.017440 2026-01-05 00:16:02.366794 | 2026-01-05 00:16:02.366934 | TASK [upload-logs-swift : Upload logs to swift]