2025-09-29 00:06:36.192007 | Job console starting... 2025-09-29 00:06:36.206495 | Updating repositories 2025-09-29 00:06:36.360402 | Preparing job workspace 2025-09-29 00:06:42.298435 | Running Ansible setup... 2025-09-29 00:06:46.993286 | PRE-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master] 2025-09-29 00:06:47.638498 | 2025-09-29 00:06:47.638626 | PLAY [localhost] 2025-09-29 00:06:47.665359 | 2025-09-29 00:06:47.665439 | TASK [Gathering Facts] 2025-09-29 00:06:48.576945 | localhost | ok 2025-09-29 00:06:48.590330 | 2025-09-29 00:06:48.590409 | TASK [Setup log path fact] 2025-09-29 00:06:48.607767 | localhost | ok 2025-09-29 00:06:48.621221 | 2025-09-29 00:06:48.621329 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-09-29 00:06:48.649571 | localhost | ok 2025-09-29 00:06:48.657827 | 2025-09-29 00:06:48.657896 | TASK [emit-job-header : Print job information] 2025-09-29 00:06:48.685408 | # Job Information 2025-09-29 00:06:48.685575 | Ansible Version: 2.15.12 2025-09-29 00:06:48.685617 | Job: ansible-test-sanity-docker-stable-2.11 2025-09-29 00:06:48.685645 | Pipeline: periodic 2025-09-29 00:06:48.685670 | Executor: ze03.softwarefactory-project.io 2025-09-29 00:06:48.685695 | Triggered by: https://github.com/ansible-collections/ansible.network 2025-09-29 00:06:48.685740 | Log URL (when completed): https://ansible.softwarefactory-project.io/logs/9ec/ansible/9ecc968185e94ea28bf73c6fa4b7e9cf/ 2025-09-29 00:06:48.685775 | Event ID: c18e3d24a25249e3bcbdaf54f0d57ff2 2025-09-29 00:06:48.689946 | 2025-09-29 00:06:48.690008 | LOOP [emit-job-header : Print node information] 2025-09-29 00:06:48.793349 | localhost | ok: 2025-09-29 00:06:48.793716 | localhost | # Node Information 2025-09-29 00:06:48.793808 | localhost | Inventory Hostname: controller 2025-09-29 00:06:48.793852 | localhost | Hostname: np0005457967 2025-09-29 00:06:48.793892 | localhost | Username: zuul 2025-09-29 00:06:48.793943 | localhost | Distro: Fedora 37 2025-09-29 00:06:48.793984 | localhost | Provider: ansible-vexxhost-ca-ymq-1 2025-09-29 00:06:48.794021 | localhost | Region: ca-ymq-1 2025-09-29 00:06:48.794057 | localhost | Label: ansible-fedora-37-1vcpu 2025-09-29 00:06:48.794092 | localhost | Product Name: OpenStack Nova 2025-09-29 00:06:48.794129 | localhost | Interface IP: 162.253.55.186 2025-09-29 00:06:48.816614 | 2025-09-29 00:06:48.816788 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-09-29 00:06:49.222833 | localhost -> localhost | changed 2025-09-29 00:06:49.228350 | 2025-09-29 00:06:49.228440 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-09-29 00:06:50.214978 | localhost -> localhost | changed 2025-09-29 00:06:50.240287 | 2025-09-29 00:06:50.240400 | PLAY [all:!appliance*] 2025-09-29 00:06:50.260585 | 2025-09-29 00:06:50.260709 | TASK [include_role : start-zuul-console] 2025-09-29 00:06:50.280134 | controller | ok 2025-09-29 00:06:50.296123 | 2025-09-29 00:06:50.296251 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-09-29 00:06:50.744470 | controller | ok 2025-09-29 00:06:50.766386 | 2025-09-29 00:06:50.766521 | TASK [use-our-mirror : Retrieve the IP address] 2025-09-29 00:06:52.186435 | controller | ok: Status code was 404 and not [200]: HTTP Error 404: Not Found 2025-09-29 00:06:52.199258 | 2025-09-29 00:06:52.199439 | TASK [use-our-mirror : Ensure the registry service is running on the mirror] 2025-09-29 00:06:52.750345 | controller | skipping: Conditional result was False 2025-09-29 00:06:52.757275 | 2025-09-29 00:06:52.757364 | TASK [use-our-mirror : Write the IP in /etc/hosts] 2025-09-29 00:06:52.782455 | controller | skipping: Conditional result was False 2025-09-29 00:06:52.796385 | 2025-09-29 00:06:52.796560 | TASK [use-our-mirror : Create the podman configuration directory] 2025-09-29 00:06:52.823650 | controller | skipping: Conditional result was False 2025-09-29 00:06:52.838349 | 2025-09-29 00:06:52.838522 | TASK [use-our-mirror : Copy the podman configuration] 2025-09-29 00:06:52.876377 | controller | skipping: Conditional result was False 2025-09-29 00:06:52.890855 | 2025-09-29 00:06:52.891057 | TASK [use-our-mirror : Copy the Yum configuration (main)] 2025-09-29 00:06:52.918770 | controller | skipping: Conditional result was False 2025-09-29 00:06:52.937325 | 2025-09-29 00:06:52.937512 | TASK [use-our-mirror : Copy the Yum configuration (update)] 2025-09-29 00:06:52.955684 | controller | skipping: Conditional result was False 2025-09-29 00:06:52.982467 | 2025-09-29 00:06:52.982699 | TASK [Disable Fedora Modular] 2025-09-29 00:06:53.225773 | controller | changed 2025-09-29 00:06:53.238853 | 2025-09-29 00:06:53.239002 | TASK [Enable EPEL] 2025-09-29 00:06:53.272059 | controller | skipping: Conditional result was False 2025-09-29 00:06:53.278443 | 2025-09-29 00:06:53.278538 | TASK [Register the RHEL node] 2025-09-29 00:06:53.839441 | 2025-09-29 00:06:53.839712 | TASK [Show the subscription-manager status] 2025-09-29 00:06:54.419576 | controller | skipping: Conditional result was False 2025-09-29 00:06:54.434532 | 2025-09-29 00:06:54.434670 | TASK [Enable EPEL on RHEL] 2025-09-29 00:06:54.986500 | controller | skipping: Conditional result was False 2025-09-29 00:06:54.995277 | 2025-09-29 00:06:54.995368 | TASK [Install git and tox] 2025-09-29 00:08:14.549367 | controller | changed 2025-09-29 00:08:14.563295 | 2025-09-29 00:08:14.563473 | TASK [include_role : prepare-workspace] 2025-09-29 00:08:14.587524 | controller | ok 2025-09-29 00:08:14.618129 | 2025-09-29 00:08:14.618258 | TASK [prepare-workspace : Start zuul_console daemon.] 2025-09-29 00:08:14.873666 | controller | ok 2025-09-29 00:08:14.880259 | 2025-09-29 00:08:14.880354 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2025-09-29 00:08:27.786396 | controller | Output suppressed because no_log was given 2025-09-29 00:08:27.816930 | 2025-09-29 00:08:27.817094 | TASK [include_role : prepare-workspace-openshift] 2025-09-29 00:08:27.837303 | controller | skipping: Conditional result was False 2025-09-29 00:08:27.899037 | 2025-09-29 00:08:27.899135 | PLAY [all:!appliance] 2025-09-29 00:08:27.916515 | 2025-09-29 00:08:27.916627 | TASK [Run add-build-sshkey role (RSA)] 2025-09-29 00:08:27.937198 | controller | ok 2025-09-29 00:08:27.952197 | 2025-09-29 00:08:27.952300 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-09-29 00:08:28.201669 | controller -> localhost | ok 2025-09-29 00:08:28.211236 | 2025-09-29 00:08:28.211364 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-09-29 00:08:28.256956 | controller | ok 2025-09-29 00:08:28.275930 | controller | included: /var/lib/zuul/builds/9ecc968185e94ea28bf73c6fa4b7e9cf/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-09-29 00:08:28.285194 | 2025-09-29 00:08:28.285320 | TASK [add-build-sshkey : Create Temp SSH key] 2025-09-29 00:08:28.773325 | controller -> localhost | Generating public/private rsa key pair. 2025-09-29 00:08:28.773526 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/9ecc968185e94ea28bf73c6fa4b7e9cf/work/9ecc968185e94ea28bf73c6fa4b7e9cf_id_rsa. 2025-09-29 00:08:28.773557 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/9ecc968185e94ea28bf73c6fa4b7e9cf/work/9ecc968185e94ea28bf73c6fa4b7e9cf_id_rsa.pub. 2025-09-29 00:08:28.773579 | controller -> localhost | The key fingerprint is: 2025-09-29 00:08:28.773599 | controller -> localhost | SHA256:XZFBuVrF6glcNBqQvzcnlMYktIV2NM6yDw/7XfHADq8 zuul-build-sshkey 2025-09-29 00:08:28.773617 | controller -> localhost | The key's randomart image is: 2025-09-29 00:08:28.773635 | controller -> localhost | +---[RSA 2048]----+ 2025-09-29 00:08:28.773652 | controller -> localhost | | .+o=X= | 2025-09-29 00:08:28.773671 | controller -> localhost | | . +B*+o | 2025-09-29 00:08:28.773689 | controller -> localhost | | ++*+= | 2025-09-29 00:08:28.773707 | controller -> localhost | | .++O. | 2025-09-29 00:08:28.773768 | controller -> localhost | | S =O..o. | 2025-09-29 00:08:28.773816 | controller -> localhost | | o**+..o| 2025-09-29 00:08:28.773841 | controller -> localhost | | ..o+o o| 2025-09-29 00:08:28.773861 | controller -> localhost | | . o . | 2025-09-29 00:08:28.773881 | controller -> localhost | | E . | 2025-09-29 00:08:28.773902 | controller -> localhost | +----[SHA256]-----+ 2025-09-29 00:08:28.773959 | controller -> localhost | ok: Runtime: 0:00:00.070051 2025-09-29 00:08:28.782074 | 2025-09-29 00:08:28.782142 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-09-29 00:08:28.814363 | controller | ok 2025-09-29 00:08:28.827436 | controller | included: /var/lib/zuul/builds/9ecc968185e94ea28bf73c6fa4b7e9cf/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-09-29 00:08:28.838981 | 2025-09-29 00:08:28.839121 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-09-29 00:08:28.864446 | controller | skipping: Conditional result was False 2025-09-29 00:08:28.874167 | 2025-09-29 00:08:28.874309 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-09-29 00:08:29.373157 | controller | changed 2025-09-29 00:08:29.387194 | 2025-09-29 00:08:29.387306 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-09-29 00:08:29.624452 | controller | ok 2025-09-29 00:08:29.632336 | 2025-09-29 00:08:29.632420 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-09-29 00:08:30.281800 | controller | changed 2025-09-29 00:08:30.296756 | 2025-09-29 00:08:30.296911 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-09-29 00:08:30.990717 | controller | changed 2025-09-29 00:08:31.003263 | 2025-09-29 00:08:31.003401 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-09-29 00:08:31.028685 | controller | skipping: Conditional result was False 2025-09-29 00:08:31.036302 | 2025-09-29 00:08:31.036625 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-09-29 00:08:31.481209 | controller -> localhost | changed 2025-09-29 00:08:31.498421 | 2025-09-29 00:08:31.498504 | TASK [add-build-sshkey : Add back temp key] 2025-09-29 00:08:31.787316 | controller -> localhost | Identity added: /var/lib/zuul/builds/9ecc968185e94ea28bf73c6fa4b7e9cf/work/9ecc968185e94ea28bf73c6fa4b7e9cf_id_rsa (zuul-build-sshkey) 2025-09-29 00:08:31.787550 | controller -> localhost | ok: Runtime: 0:00:00.010045 2025-09-29 00:08:31.798953 | 2025-09-29 00:08:31.799029 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-09-29 00:08:32.230854 | controller | ok 2025-09-29 00:08:32.243911 | 2025-09-29 00:08:32.244056 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-09-29 00:08:32.292180 | controller | skipping: Conditional result was False 2025-09-29 00:08:32.318916 | 2025-09-29 00:08:32.319014 | TASK [Run add-build-sshkey role (ECDSA)] 2025-09-29 00:08:32.366704 | controller | ok 2025-09-29 00:08:32.389854 | 2025-09-29 00:08:32.389937 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-09-29 00:08:32.661995 | controller -> localhost | ok 2025-09-29 00:08:32.678410 | 2025-09-29 00:08:32.678564 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-09-29 00:08:32.705717 | controller | ok 2025-09-29 00:08:32.724737 | controller | included: /var/lib/zuul/builds/9ecc968185e94ea28bf73c6fa4b7e9cf/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-09-29 00:08:32.737299 | 2025-09-29 00:08:32.737416 | TASK [add-build-sshkey : Create Temp SSH key] 2025-09-29 00:08:33.021128 | controller -> localhost | Generating public/private ecdsa key pair. 2025-09-29 00:08:33.021497 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/9ecc968185e94ea28bf73c6fa4b7e9cf/work/9ecc968185e94ea28bf73c6fa4b7e9cf_id_ecdsa. 2025-09-29 00:08:33.021578 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/9ecc968185e94ea28bf73c6fa4b7e9cf/work/9ecc968185e94ea28bf73c6fa4b7e9cf_id_ecdsa.pub. 2025-09-29 00:08:33.021662 | controller -> localhost | The key fingerprint is: 2025-09-29 00:08:33.021736 | controller -> localhost | SHA256:6Al2g6Su5FdrRANzccjsxVwBx8jlBt7Wpgxbg79C6G4 zuul-build-sshkey 2025-09-29 00:08:33.022035 | controller -> localhost | The key's randomart image is: 2025-09-29 00:08:33.022110 | controller -> localhost | +---[ECDSA 521]---+ 2025-09-29 00:08:33.022171 | controller -> localhost | | o.*+*=. | 2025-09-29 00:08:33.022228 | controller -> localhost | | o =o**.. | 2025-09-29 00:08:33.022286 | controller -> localhost | | * .+ B o | 2025-09-29 00:08:33.022338 | controller -> localhost | | o =..O + | 2025-09-29 00:08:33.022387 | controller -> localhost | | . =.=oS+ | 2025-09-29 00:08:33.022427 | controller -> localhost | | . ..*.o . | 2025-09-29 00:08:33.022466 | controller -> localhost | | .. o.+. . | 2025-09-29 00:08:33.022504 | controller -> localhost | |o. ..E . | 2025-09-29 00:08:33.022543 | controller -> localhost | |... o. | 2025-09-29 00:08:33.022580 | controller -> localhost | +----[SHA256]-----+ 2025-09-29 00:08:33.022676 | controller -> localhost | ok: Runtime: 0:00:00.019515 2025-09-29 00:08:33.040886 | 2025-09-29 00:08:33.041059 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-09-29 00:08:33.073198 | controller | ok 2025-09-29 00:08:33.087835 | controller | included: /var/lib/zuul/builds/9ecc968185e94ea28bf73c6fa4b7e9cf/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-09-29 00:08:33.103150 | 2025-09-29 00:08:33.103299 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-09-29 00:08:33.129755 | controller | skipping: Conditional result was False 2025-09-29 00:08:33.140299 | 2025-09-29 00:08:33.140439 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-09-29 00:08:33.479289 | controller | changed 2025-09-29 00:08:33.498532 | 2025-09-29 00:08:33.498679 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-09-29 00:08:33.749850 | controller | ok 2025-09-29 00:08:33.766326 | 2025-09-29 00:08:33.766468 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-09-29 00:08:34.479988 | controller | changed 2025-09-29 00:08:34.486995 | 2025-09-29 00:08:34.487060 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-09-29 00:08:35.116697 | controller | changed 2025-09-29 00:08:35.136311 | 2025-09-29 00:08:35.136436 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-09-29 00:08:35.160600 | controller | skipping: Conditional result was False 2025-09-29 00:08:35.168340 | 2025-09-29 00:08:35.168407 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-09-29 00:08:35.428574 | controller -> localhost | changed 2025-09-29 00:08:35.490600 | 2025-09-29 00:08:35.490727 | TASK [add-build-sshkey : Add back temp key] 2025-09-29 00:08:35.790849 | controller -> localhost | Identity added: /var/lib/zuul/builds/9ecc968185e94ea28bf73c6fa4b7e9cf/work/9ecc968185e94ea28bf73c6fa4b7e9cf_id_ecdsa (zuul-build-sshkey) 2025-09-29 00:08:35.791029 | controller -> localhost | ok: Runtime: 0:00:00.008345 2025-09-29 00:08:35.798651 | 2025-09-29 00:08:35.798717 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-09-29 00:08:36.002217 | controller | ok 2025-09-29 00:08:36.009814 | 2025-09-29 00:08:36.009877 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-09-29 00:08:36.045444 | controller | skipping: Conditional result was False 2025-09-29 00:08:36.062093 | 2025-09-29 00:08:36.062208 | TASK [include_role : remove-zuul-sshkey] 2025-09-29 00:08:36.087412 | controller | skipping: Conditional result was False 2025-09-29 00:08:36.096742 | 2025-09-29 00:08:36.097034 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2025-09-29 00:08:36.319569 | controller | ok: "logs" 2025-09-29 00:08:36.320054 | controller | ok: All items complete 2025-09-29 00:08:36.320084 | 2025-09-29 00:08:36.492152 | controller | ok: "artifacts" 2025-09-29 00:08:36.692982 | controller | ok: "docs" 2025-09-29 00:08:36.707347 | 2025-09-29 00:08:36.707479 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2025-09-29 00:08:36.941299 | controller | changed: "logs" 2025-09-29 00:08:37.141518 | controller | changed: "artifacts" 2025-09-29 00:08:37.348139 | controller | changed: "docs" 2025-09-29 00:08:37.404926 | 2025-09-29 00:08:37.405105 | PLAY RECAP 2025-09-29 00:08:37.405165 | controller | ok: 30 changed: 16 unreachable: 0 failed: 0 skipped: 18 rescued: 0 ignored: 0 2025-09-29 00:08:37.405209 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-09-29 00:08:37.405234 | 2025-09-29 00:08:37.572856 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master] 2025-09-29 00:08:37.573697 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2025-09-29 00:08:38.244455 | 2025-09-29 00:08:38.244587 | PLAY [all] 2025-09-29 00:08:38.273689 | 2025-09-29 00:08:38.273902 | TASK [Install binary dependencies] 2025-09-29 00:08:38.348894 | controller | ok 2025-09-29 00:08:38.377530 | 2025-09-29 00:08:38.377707 | TASK [bindep : Include find tasks] 2025-09-29 00:08:38.411428 | controller | ok 2025-09-29 00:08:38.425338 | controller | included: /var/lib/zuul/builds/9ecc968185e94ea28bf73c6fa4b7e9cf/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find.yaml 2025-09-29 00:08:38.435693 | 2025-09-29 00:08:38.435892 | TASK [bindep : Look for bindep.txt] 2025-09-29 00:08:38.815733 | controller | ok 2025-09-29 00:08:38.828437 | 2025-09-29 00:08:38.828621 | TASK [bindep : Define bindep_file fact] 2025-09-29 00:08:38.854396 | controller | skipping: Conditional result was False 2025-09-29 00:08:38.861528 | 2025-09-29 00:08:38.861676 | TASK [bindep : Look for other-requirements.txt] 2025-09-29 00:08:39.078957 | controller | ok 2025-09-29 00:08:39.102446 | 2025-09-29 00:08:39.102597 | TASK [bindep : Define bindep_file fact] 2025-09-29 00:08:39.187814 | controller | skipping: Conditional result was False 2025-09-29 00:08:39.197349 | 2025-09-29 00:08:39.197487 | TASK [bindep : Look for bindep fallback file] 2025-09-29 00:08:39.233904 | controller | skipping: Conditional result was False 2025-09-29 00:08:39.243717 | 2025-09-29 00:08:39.245825 | TASK [bindep : Define bindep_file fact] 2025-09-29 00:08:39.296972 | controller | skipping: Conditional result was False 2025-09-29 00:08:39.311537 | 2025-09-29 00:08:39.311685 | TASK [bindep : Include bindep tasks] 2025-09-29 00:08:39.367414 | controller | skipping: Conditional result was False 2025-09-29 00:08:39.376142 | 2025-09-29 00:08:39.376258 | TASK [bindep : Include install tasks] 2025-09-29 00:08:39.401456 | controller | skipping: Conditional result was False 2025-09-29 00:08:39.408635 | 2025-09-29 00:08:39.408770 | LOOP [bindep : Include package tasks] 2025-09-29 00:08:39.485702 | 2025-09-29 00:08:39.485986 | TASK [Run test-setup role] 2025-09-29 00:08:39.511619 | controller | ok 2025-09-29 00:08:39.538153 | 2025-09-29 00:08:39.538299 | TASK [test-setup : Check if project's tools/test-setup.sh exists] 2025-09-29 00:08:39.749327 | controller | ok 2025-09-29 00:08:39.763814 | 2025-09-29 00:08:39.763966 | TASK [test-setup : Run tools/test-setup.sh] 2025-09-29 00:08:40.316531 | controller | skipping: Conditional result was False 2025-09-29 00:08:40.343567 | 2025-09-29 00:08:40.343661 | PLAY RECAP 2025-09-29 00:08:40.343701 | controller | ok: 4 changed: 0 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2025-09-29 00:08:40.343721 | 2025-09-29 00:08:40.449068 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2025-09-29 00:08:40.449979 | PRE-RUN START: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master] 2025-09-29 00:08:41.074795 | 2025-09-29 00:08:41.075090 | PLAY [controller] 2025-09-29 00:08:41.101438 | 2025-09-29 00:08:41.101565 | TASK [Create the /root directory] 2025-09-29 00:08:41.494882 | controller | ok 2025-09-29 00:08:41.501115 | 2025-09-29 00:08:41.501214 | TASK [Install glibc-langpack-en] 2025-09-29 00:08:45.347800 | controller | ok: Nothing to do 2025-09-29 00:08:45.354876 | 2025-09-29 00:08:45.354982 | TASK [Ensure controller directory exists] 2025-09-29 00:08:45.559529 | controller | changed 2025-09-29 00:08:45.565802 | 2025-09-29 00:08:45.565923 | TASK [Install container runtime] 2025-09-29 00:08:45.617033 | controller | ok 2025-09-29 00:08:45.658441 | 2025-09-29 00:08:45.658565 | LOOP [ensure-docker : Gather variables for each operating system] 2025-09-29 00:08:45.714243 | controller | ok: "/var/lib/zuul/builds/9ecc968185e94ea28bf73c6fa4b7e9cf/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/vars/fedora.yaml" 2025-09-29 00:08:45.721573 | 2025-09-29 00:08:45.721658 | TASK [ensure-docker : Sanity check for distro selection] 2025-09-29 00:08:45.745990 | controller | skipping: Conditional result was False 2025-09-29 00:08:45.752453 | 2025-09-29 00:08:45.752532 | TASK [ensure-docker : Sanity check for distro install] 2025-09-29 00:08:45.776604 | controller | skipping: Conditional result was False 2025-09-29 00:08:45.782623 | 2025-09-29 00:08:45.782710 | TASK [ensure-docker : Sanity check for upstream install] 2025-09-29 00:08:45.817304 | controller | skipping: Conditional result was False 2025-09-29 00:08:45.830365 | 2025-09-29 00:08:45.830478 | TASK [ensure-docker : Sanity check for upstream install mirrors] 2025-09-29 00:08:45.865708 | controller | skipping: Conditional result was False 2025-09-29 00:08:45.873361 | 2025-09-29 00:08:45.873483 | TASK [ensure-docker : Include tasks for docker install] 2025-09-29 00:08:45.904822 | controller | ok 2025-09-29 00:08:45.913892 | controller | included: /var/lib/zuul/builds/9ecc968185e94ea28bf73c6fa4b7e9cf/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/tasks/docker-upstream.yaml 2025-09-29 00:08:45.922224 | 2025-09-29 00:08:45.922303 | TASK [ensure-docker : Not Implemented] 2025-09-29 00:08:45.946385 | controller | skipping: Conditional result was False 2025-09-29 00:08:45.952703 | 2025-09-29 00:08:45.952797 | TASK [Set up docker mirrors] 2025-09-29 00:08:45.971982 | controller | ok 2025-09-29 00:08:45.997001 | 2025-09-29 00:08:45.997137 | TASK [use-docker-mirror : Set docker_mirror fact from mirror_info] 2025-09-29 00:08:46.031906 | controller | skipping: Conditional result was False 2025-09-29 00:08:46.040497 | 2025-09-29 00:08:46.040610 | TASK [use-docker-mirror : Set docker_mirror fact from zuul_site_mirror_info] 2025-09-29 00:08:46.064562 | controller | skipping: Conditional result was False 2025-09-29 00:08:46.073404 | 2025-09-29 00:08:46.073539 | TASK [use-docker-mirror : Set docker_mirror fact] 2025-09-29 00:08:46.109644 | controller | skipping: Conditional result was False 2025-09-29 00:08:46.115870 | 2025-09-29 00:08:46.115959 | TASK [use-docker-mirror : Set up docker mirrors] 2025-09-29 00:08:46.139911 | controller | skipping: Conditional result was False 2025-09-29 00:08:46.153261 | 2025-09-29 00:08:46.153362 | TASK [Add all repositories] 2025-09-29 00:08:46.174591 | controller | ok 2025-09-29 00:08:46.199915 | 2025-09-29 00:08:46.200042 | LOOP [ensure-package-repositories : Install repositories] 2025-09-29 00:08:46.225970 | controller | ok: "/var/lib/zuul/builds/9ecc968185e94ea28bf73c6fa4b7e9cf/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-package-repositories/tasks/RedHat.yaml" 2025-09-29 00:08:46.226125 | controller | ok: All items complete 2025-09-29 00:08:46.226152 | 2025-09-29 00:08:46.243753 | controller | included: /var/lib/zuul/builds/9ecc968185e94ea28bf73c6fa4b7e9cf/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-package-repositories/tasks/RedHat.yaml 2025-09-29 00:08:46.295068 | 2025-09-29 00:08:46.295186 | TASK [ensure-package-repositories : Create GPG key temp directory] 2025-09-29 00:08:46.626711 | controller | changed 2025-09-29 00:08:46.641147 | 2025-09-29 00:08:46.641280 | LOOP [ensure-package-repositories : Upload GPG keys] 2025-09-29 00:08:47.556543 | controller | changed: 2025-09-29 00:08:47.556758 | controller | { 2025-09-29 00:08:47.556798 | controller | "data": "-----BEGIN PGP PUBLIC KEY BLOCK-----\n\nmQINBFit5IEBEADDt86QpYKz5flnCsOyZ/fk3WwBKxfDjwHf/GIflo+4GWAXS7wJ\n1PSzPsvSDATV10J44i5WQzh99q+lZvFCVRFiNhRmlmcXG+rk1QmDh3fsCCj9Q/yP\nw8jn3Hx0zDtz8PIB/18ReftYJzUo34COLiHn8WiY20uGCF2pjdPgfxE+K454c4G7\ngKFqVUFYgPug2CS0quaBB5b0rpFUdzTeI5RCStd27nHCpuSDCvRYAfdv+4Y1yiVh\nKKdoe3Smj+RnXeVMgDxtH9FJibZ3DK7WnMN2yeob6VqXox+FvKYJCCLkbQgQmE50\nuVK0uN71A1mQDcTRKQ2q3fFGlMTqJbbzr3LwnCBE6hV0a36t+DABtZTmz5O69xdJ\nWGdBeePCnWVqtDb/BdEYz7hPKskcZBarygCCe2Xi7sZieoFZuq6ltPoCsdfEdfbO\n+VBVKJnExqNZCcFUTEnbH4CldWROOzMS8BGUlkGpa59Sl1t0QcmWlw1EbkeMQNrN\nspdR8lobcdNS9bpAJQqSHRZh3cAM9mA3Yq/bssUS/P2quRXLjJ9mIv3dky9C3udM\n+q2unvnbNpPtIUly76FJ3s8g8sHeOnmYcKqNGqHq2Q3kMdA2eIbI0MqfOIo2+Xk0\nrNt3ctq3g+cQiorcN3rdHPsTRSAcp+NCz1QF9TwXYtH1XV24A6QMO0+CZwARAQAB\ntCtEb2NrZXIgUmVsZWFzZSAoQ0UgcnBtKSA8ZG9ja2VyQGRvY2tlci5jb20+iQI3\nBBMBCgAhBQJYrep4AhsvBQsJCAcDBRUKCQgLBRYCAwEAAh4BAheAAAoJEMUv62ti\nHp816C0P/iP+1uhSa6Qq3TIc5sIFE5JHxOO6y0R97cUdAmCbEqBiJHUPNQDQaaRG\nVYBm0K013Q1gcJeUJvS32gthmIvhkstw7KTodwOM8Kl11CCqZ07NPFef1b2SaJ7l\nTYpyUsT9+e343ph+O4C1oUQw6flaAJe+8ATCmI/4KxfhIjD2a/Q1voR5tUIxfexC\n/LZTx05gyf2mAgEWlRm/cGTStNfqDN1uoKMlV+WFuB1j2oTUuO1/dr8mL+FgZAM3\nntWFo9gQCllNV9ahYOON2gkoZoNuPUnHsf4Bj6BQJnIXbAhMk9H2sZzwUi9bgObZ\nXO8+OrP4D4B9kCAKqqaQqA+O46LzO2vhN74lm/Fy6PumHuviqDBdN+HgtRPMUuao\nxnuVJSvBu9sPdgT/pR1N9u/KnfAnnLtR6g+fx4mWz+ts/riB/KRHzXd+44jGKZra\nIhTMfniguMJNsyEOO0AN8Tqcl0eRBxcOArcri7xu8HFvvl+e+ILymu4buusbYEVL\nGBkYP5YMmScfKn+jnDVN4mWoN1Bq2yMhMGx6PA3hOvzPNsUoYy2BwDxNZyflzuAi\ng59mgJm2NXtzNbSRJbMamKpQ69mzLWGdFNsRd4aH7PT7uPAURaf7B5BVp3UyjERW\n5alSGnBqsZmvlRnVH5BDUhYsWZMPRQS9rRr4iGW0l+TH+O2VJ8aQ\n=0Zqq\n-----END PGP PUBLIC KEY BLOCK-----\n" 2025-09-29 00:08:47.556822 | controller | } 2025-09-29 00:08:47.571328 | 2025-09-29 00:08:47.571523 | LOOP [ensure-package-repositories : Import GPG keys] 2025-09-29 00:08:48.214557 | controller | changed: 2025-09-29 00:08:48.214913 | controller | { 2025-09-29 00:08:48.214972 | controller | "data": "-----BEGIN PGP PUBLIC KEY BLOCK-----\n\nmQINBFit5IEBEADDt86QpYKz5flnCsOyZ/fk3WwBKxfDjwHf/GIflo+4GWAXS7wJ\n1PSzPsvSDATV10J44i5WQzh99q+lZvFCVRFiNhRmlmcXG+rk1QmDh3fsCCj9Q/yP\nw8jn3Hx0zDtz8PIB/18ReftYJzUo34COLiHn8WiY20uGCF2pjdPgfxE+K454c4G7\ngKFqVUFYgPug2CS0quaBB5b0rpFUdzTeI5RCStd27nHCpuSDCvRYAfdv+4Y1yiVh\nKKdoe3Smj+RnXeVMgDxtH9FJibZ3DK7WnMN2yeob6VqXox+FvKYJCCLkbQgQmE50\nuVK0uN71A1mQDcTRKQ2q3fFGlMTqJbbzr3LwnCBE6hV0a36t+DABtZTmz5O69xdJ\nWGdBeePCnWVqtDb/BdEYz7hPKskcZBarygCCe2Xi7sZieoFZuq6ltPoCsdfEdfbO\n+VBVKJnExqNZCcFUTEnbH4CldWROOzMS8BGUlkGpa59Sl1t0QcmWlw1EbkeMQNrN\nspdR8lobcdNS9bpAJQqSHRZh3cAM9mA3Yq/bssUS/P2quRXLjJ9mIv3dky9C3udM\n+q2unvnbNpPtIUly76FJ3s8g8sHeOnmYcKqNGqHq2Q3kMdA2eIbI0MqfOIo2+Xk0\nrNt3ctq3g+cQiorcN3rdHPsTRSAcp+NCz1QF9TwXYtH1XV24A6QMO0+CZwARAQAB\ntCtEb2NrZXIgUmVsZWFzZSAoQ0UgcnBtKSA8ZG9ja2VyQGRvY2tlci5jb20+iQI3\nBBMBCgAhBQJYrep4AhsvBQsJCAcDBRUKCQgLBRYCAwEAAh4BAheAAAoJEMUv62ti\nHp816C0P/iP+1uhSa6Qq3TIc5sIFE5JHxOO6y0R97cUdAmCbEqBiJHUPNQDQaaRG\nVYBm0K013Q1gcJeUJvS32gthmIvhkstw7KTodwOM8Kl11CCqZ07NPFef1b2SaJ7l\nTYpyUsT9+e343ph+O4C1oUQw6flaAJe+8ATCmI/4KxfhIjD2a/Q1voR5tUIxfexC\n/LZTx05gyf2mAgEWlRm/cGTStNfqDN1uoKMlV+WFuB1j2oTUuO1/dr8mL+FgZAM3\nntWFo9gQCllNV9ahYOON2gkoZoNuPUnHsf4Bj6BQJnIXbAhMk9H2sZzwUi9bgObZ\nXO8+OrP4D4B9kCAKqqaQqA+O46LzO2vhN74lm/Fy6PumHuviqDBdN+HgtRPMUuao\nxnuVJSvBu9sPdgT/pR1N9u/KnfAnnLtR6g+fx4mWz+ts/riB/KRHzXd+44jGKZra\nIhTMfniguMJNsyEOO0AN8Tqcl0eRBxcOArcri7xu8HFvvl+e+ILymu4buusbYEVL\nGBkYP5YMmScfKn+jnDVN4mWoN1Bq2yMhMGx6PA3hOvzPNsUoYy2BwDxNZyflzuAi\ng59mgJm2NXtzNbSRJbMamKpQ69mzLWGdFNsRd4aH7PT7uPAURaf7B5BVp3UyjERW\n5alSGnBqsZmvlRnVH5BDUhYsWZMPRQS9rRr4iGW0l+TH+O2VJ8aQ\n=0Zqq\n-----END PGP PUBLIC KEY BLOCK-----\n" 2025-09-29 00:08:48.215029 | controller | } 2025-09-29 00:08:48.215092 | controller | changed: All items complete 2025-09-29 00:08:48.215128 | 2025-09-29 00:08:48.234002 | 2025-09-29 00:08:48.234230 | TASK [ensure-package-repositories : Remove GPG temp directory] 2025-09-29 00:08:48.460423 | controller | changed 2025-09-29 00:08:48.469587 | 2025-09-29 00:08:48.469682 | LOOP [ensure-package-repositories : Add repositories] 2025-09-29 00:08:48.842530 | controller | changed: 2025-09-29 00:08:48.842779 | controller | { 2025-09-29 00:08:48.842812 | controller | "baseurl": "https://download.docker.com/linux/fedora/$releasever/$basearch/stable", 2025-09-29 00:08:48.842834 | controller | "description": "Docker CE Stable - $basearch", 2025-09-29 00:08:48.842852 | controller | "gpgcheck": true, 2025-09-29 00:08:48.842870 | controller | "name": "docker-ce-stable" 2025-09-29 00:08:48.842888 | controller | } 2025-09-29 00:08:48.864717 | 2025-09-29 00:08:48.864874 | TASK [ensure-docker : Install pre-reqs] 2025-09-29 00:08:51.746578 | controller | ok: Nothing to do 2025-09-29 00:08:51.760077 | 2025-09-29 00:08:51.760223 | TASK [ensure-docker : Remove packages] 2025-09-29 00:08:53.072813 | controller | ok: Nothing to do 2025-09-29 00:08:53.085070 | 2025-09-29 00:08:53.085207 | TASK [ensure-docker : Install upstream docker using package] 2025-09-29 00:09:39.789575 | controller | changed 2025-09-29 00:09:39.804928 | 2025-09-29 00:09:39.805105 | TASK [ensure-docker : Setup docker] 2025-09-29 00:09:39.832240 | controller | ok 2025-09-29 00:09:39.848612 | controller | included: /var/lib/zuul/builds/9ecc968185e94ea28bf73c6fa4b7e9cf/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/tasks/docker-setup.yaml 2025-09-29 00:09:39.867177 | 2025-09-29 00:09:39.867569 | TASK [ensure-docker : Ensure "docker" group exists] 2025-09-29 00:09:40.250690 | controller | ok 2025-09-29 00:09:40.258335 | 2025-09-29 00:09:40.258800 | TASK [ensure-docker : Add user to docker group] 2025-09-29 00:09:40.787218 | controller | changed 2025-09-29 00:09:40.796824 | 2025-09-29 00:09:40.796966 | TASK [Update docker daemon proxy configuration] 2025-09-29 00:09:40.823421 | controller | skipping: Conditional result was False 2025-09-29 00:09:40.833593 | 2025-09-29 00:09:40.833727 | TASK [Lower default MTU] 2025-09-29 00:09:40.859785 | controller | skipping: Conditional result was False 2025-09-29 00:09:40.870257 | 2025-09-29 00:09:40.870402 | TASK [ensure-docker : Get the status of the docket.socket unit] 2025-09-29 00:09:41.266615 | controller | inactive 2025-09-29 00:09:41.441712 | controller | ok: Runtime: 0:00:00.016061 2025-09-29 00:09:41.448182 | 2025-09-29 00:09:41.448268 | TASK [ensure-docker : Get the status of the docket unit] 2025-09-29 00:09:41.690817 | controller | inactive 2025-09-29 00:09:41.990241 | controller | ok: Runtime: 0:00:00.008319 2025-09-29 00:09:42.001530 | 2025-09-29 00:09:42.001763 | TASK [ensure-docker : Restart docker] 2025-09-29 00:09:42.055763 | Trigger a Docker restart 2025-09-29 00:09:42.066418 | 2025-09-29 00:09:42.066576 | TASK [ensure-docker : Reset ssh connection to pick up docker group] 2025-09-29 00:09:42.106099 | 2025-09-29 00:09:42.106248 | TASK [ensure-docker : Install docker-compose] 2025-09-29 00:09:42.132367 | controller | skipping: Conditional result was False 2025-09-29 00:09:42.142802 | 2025-09-29 00:09:42.142929 | TASK [ensure-docker : Flush handlers before role exit] 2025-09-29 00:09:42.158878 | 2025-09-29 00:09:42.159011 | TASK [ensure-docker : Stop docker.socket to avoid any conflict] 2025-09-29 00:09:43.529105 | controller | changed 2025-09-29 00:09:43.536664 | 2025-09-29 00:09:43.536792 | TASK [ensure-docker : Assure docker service is running] 2025-09-29 00:09:45.582808 | controller | changed 2025-09-29 00:09:45.599145 | 2025-09-29 00:09:45.599279 | TASK [ensure-docker : Assure docker.socket service is running] 2025-09-29 00:09:46.368624 | controller | ok 2025-09-29 00:09:46.377235 | 2025-09-29 00:09:46.377300 | TASK [ensure-docker : Correct group ownership on docker sock] 2025-09-29 00:09:46.757898 | controller | ok 2025-09-29 00:09:46.779363 | 2025-09-29 00:09:46.779512 | TASK [ensure-docker : Validate ability to talk with docker] 2025-09-29 00:09:47.357516 | controller | CONTAINER ID IMAGE COMMAND CREATED STATUS PORTS NAMES 2025-09-29 00:09:47.828983 | controller | ok: Runtime: 0:00:00.044806 2025-09-29 00:09:47.836691 | 2025-09-29 00:09:47.836817 | TASK [ensure-docker : Get version details] 2025-09-29 00:09:48.244372 | controller | Client: Docker Engine - Community 2025-09-29 00:09:48.245506 | controller | Version: 24.0.7 2025-09-29 00:09:48.245520 | controller | API version: 1.43 2025-09-29 00:09:48.245526 | controller | Go version: go1.20.10 2025-09-29 00:09:48.245538 | controller | Git commit: afdd53b 2025-09-29 00:09:48.245543 | controller | Built: Thu Oct 26 09:09:39 2023 2025-09-29 00:09:48.245548 | controller | OS/Arch: linux/amd64 2025-09-29 00:09:48.245554 | controller | Context: default 2025-09-29 00:09:48.245561 | controller | 2025-09-29 00:09:48.245566 | controller | Server: Docker Engine - Community 2025-09-29 00:09:48.245570 | controller | Engine: 2025-09-29 00:09:48.245575 | controller | Version: 24.0.7 2025-09-29 00:09:48.245579 | controller | API version: 1.43 (minimum version 1.12) 2025-09-29 00:09:48.245583 | controller | Go version: go1.20.10 2025-09-29 00:09:48.245588 | controller | Git commit: 311b9ff 2025-09-29 00:09:48.245592 | controller | Built: Thu Oct 26 09:08:11 2023 2025-09-29 00:09:48.245597 | controller | OS/Arch: linux/amd64 2025-09-29 00:09:48.245601 | controller | Experimental: false 2025-09-29 00:09:48.245605 | controller | containerd: 2025-09-29 00:09:48.245609 | controller | Version: 1.6.25 2025-09-29 00:09:48.245614 | controller | GitCommit: d8f198a4ed8892c764191ef7b3b06d8a2eeb5c7f 2025-09-29 00:09:48.245618 | controller | runc: 2025-09-29 00:09:48.245622 | controller | Version: 1.1.10 2025-09-29 00:09:48.245627 | controller | GitCommit: v1.1.10-0-g18a0cb0 2025-09-29 00:09:48.245632 | controller | docker-init: 2025-09-29 00:09:48.245637 | controller | Version: 0.19.0 2025-09-29 00:09:48.245641 | controller | GitCommit: de40ad0 2025-09-29 00:09:48.386398 | controller | ok: Runtime: 0:00:00.051615 2025-09-29 00:09:48.395294 | 2025-09-29 00:09:48.395438 | TASK [ensure-docker : Dump installed docker details] 2025-09-29 00:09:48.440386 | {'changed': True, 'stdout': 'Client: Docker Engine - Community\n Version: 24.0.7\n API version: 1.43\n Go version: go1.20.10\n Git commit: afdd53b\n Built: Thu Oct 26 09:09:39 2023\n OS/Arch: linux/amd64\n Context: default\n\nServer: Docker Engine - Community\n Engine:\n Version: 24.0.7\n API version: 1.43 (minimum version 1.12)\n Go version: go1.20.10\n Git commit: 311b9ff\n Built: Thu Oct 26 09:08:11 2023\n OS/Arch: linux/amd64\n Experimental: false\n containerd:\n Version: 1.6.25\n GitCommit: d8f198a4ed8892c764191ef7b3b06d8a2eeb5c7f\n runc:\n Version: 1.1.10\n GitCommit: v1.1.10-0-g18a0cb0\n docker-init:\n Version: 0.19.0\n GitCommit: de40ad0', 'stderr': '', 'rc': 0, 'cmd': ['docker', 'version'], 'start': '2025-09-29 00:09:48.194271', 'end': '2025-09-29 00:09:48.245886', 'delta': '0:00:00.051615', 'msg': '', 'zuul_log_id': 'fa163ef9-e89a-3b05-4e7c-000000000061-1-controller', 'stdout_lines': ['Client: Docker Engine - Community', ' Version: 24.0.7', ' API version: 1.43', ' Go version: go1.20.10', ' Git commit: afdd53b', ' Built: Thu Oct 26 09:09:39 2023', ' OS/Arch: linux/amd64', ' Context: default', '', 'Server: Docker Engine - Community', ' Engine:', ' Version: 24.0.7', ' API version: 1.43 (minimum version 1.12)', ' Go version: go1.20.10', ' Git commit: 311b9ff', ' Built: Thu Oct 26 09:08:11 2023', ' OS/Arch: linux/amd64', ' Experimental: false', ' containerd:', ' Version: 1.6.25', ' GitCommit: d8f198a4ed8892c764191ef7b3b06d8a2eeb5c7f', ' runc:', ' Version: 1.1.10', ' GitCommit: v1.1.10-0-g18a0cb0', ' docker-init:', ' Version: 0.19.0', ' GitCommit: de40ad0'], 'stderr_lines': [], 'failed': False} 2025-09-29 00:09:48.449311 | 2025-09-29 00:09:48.449423 | TASK [Use buildset registry] 2025-09-29 00:09:48.475692 | controller | skipping: Conditional result was False 2025-09-29 00:09:48.487803 | 2025-09-29 00:09:48.487903 | TASK [Ensure python3.8 is present] 2025-09-29 00:09:48.502628 | controller | skipping: Conditional result was False 2025-09-29 00:09:48.509537 | 2025-09-29 00:09:48.509638 | TASK [Run our-ensure-python role (Fedora only for now)] 2025-09-29 00:09:48.533694 | controller | ok 2025-09-29 00:09:48.557462 | 2025-09-29 00:09:48.557583 | TASK [our-ensure-python : Install the right Python version (rpm)] 2025-09-29 00:09:50.574908 | controller | ok: Nothing to do 2025-09-29 00:09:50.582702 | 2025-09-29 00:09:50.582810 | TASK [our-ensure-python : Also install python3-devel] 2025-09-29 00:10:02.402406 | controller | changed 2025-09-29 00:10:02.418361 | 2025-09-29 00:10:02.418499 | TASK [Run ensure-virtualenv role] 2025-09-29 00:10:02.444908 | controller | ok 2025-09-29 00:10:02.473480 | 2025-09-29 00:10:02.473600 | TASK [ensure-virtualenv : Check if virtualenv is installed] 2025-09-29 00:10:02.690816 | controller | /usr/bin/virtualenv 2025-09-29 00:10:03.005110 | controller | ok: Runtime: 0:00:00.003170 2025-09-29 00:10:03.011335 | 2025-09-29 00:10:03.011404 | LOOP [ensure-virtualenv : Install virtualenv package] 2025-09-29 00:10:03.032498 | controller | skipping: Conditional result was False 2025-09-29 00:10:03.032793 | controller | changed: All items complete 2025-09-29 00:10:03.032821 | 2025-09-29 00:10:03.059529 | 2025-09-29 00:10:03.059666 | TASK [Find the full path of the Python interpreter] 2025-09-29 00:10:03.292060 | controller | /usr/bin/python3.9 2025-09-29 00:10:03.601968 | controller | ok 2025-09-29 00:10:03.608341 | 2025-09-29 00:10:03.608423 | TASK [Create virtualenv for ansible-test with a specific interpreter] 2025-09-29 00:10:05.269771 | controller | created virtual environment CPython3.9.18.final.0-64 in 842ms 2025-09-29 00:10:05.311965 | controller | creator CPython3Posix(dest=/home/zuul/venv, clear=False, no_vcs_ignore=False, global=False) 2025-09-29 00:10:05.311995 | controller | seeder FromAppData(extra_search_dir=/usr/share/python-wheels,download=False, pip=bundle, setuptools=bundle, wheel=bundle, via=copy, app_data_dir=/home/zuul/.local/share/virtualenv) 2025-09-29 00:10:05.312005 | controller | added seed packages: pip==22.2.2, setuptools==62.6.0, wheel==0.37.1 2025-09-29 00:10:05.312018 | controller | activators BashActivator,CShellActivator,FishActivator,NushellActivator,PowerShellActivator,PythonActivator 2025-09-29 00:10:05.641240 | controller | changed 2025-09-29 00:10:05.655136 | 2025-09-29 00:10:05.655338 | TASK [Set selinux package] 2025-09-29 00:10:05.679622 | controller | ok 2025-09-29 00:10:05.686054 | 2025-09-29 00:10:05.686191 | TASK [Set selinux package (Fedora)] 2025-09-29 00:10:05.718991 | controller | ok 2025-09-29 00:10:05.725576 | 2025-09-29 00:10:05.725711 | TASK [Install selinux into virtualenv] 2025-09-29 00:10:19.378841 | controller | Collecting selinux-please-lie-to-me 2025-09-29 00:10:31.633311 | controller | Downloading selinux_please_lie_to_me-1.0.1-py2.py3-none-any.whl (3.8 kB) 2025-09-29 00:10:31.985751 | controller | Collecting setuptools<50.0.0 2025-09-29 00:10:31.991837 | controller | Downloading setuptools-49.6.0-py3-none-any.whl (803 kB) 2025-09-29 00:10:32.032059 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 803.3/803.3 kB 22.2 MB/s eta 0:00:00 2025-09-29 00:10:32.107725 | controller | Installing collected packages: setuptools, selinux-please-lie-to-me 2025-09-29 00:10:32.108004 | controller | Attempting uninstall: setuptools 2025-09-29 00:10:32.108706 | controller | Found existing installation: setuptools 62.6.0 2025-09-29 00:10:32.179641 | controller | Uninstalling setuptools-62.6.0: 2025-09-29 00:10:32.188214 | controller | Successfully uninstalled setuptools-62.6.0 2025-09-29 00:10:32.522365 | controller | Successfully installed selinux-please-lie-to-me-1.0.1 setuptools-49.6.0 2025-09-29 00:10:44.005959 | controller | 2025-09-29 00:10:44.091462 | controller | [notice] A new release of pip available: 22.2.2 -> 25.2 2025-09-29 00:10:44.091503 | controller | [notice] To update, run: /home/zuul/venv/bin/python -m pip install --upgrade pip 2025-09-29 00:10:44.298638 | controller | ok: Runtime: 0:00:37.834154 2025-09-29 00:10:44.312566 | 2025-09-29 00:10:44.312718 | TASK [Install pytest-forked into virtualenv] 2025-09-29 00:10:56.246568 | controller | Collecting pytest-forked 2025-09-29 00:11:05.422035 | controller | Downloading pytest_forked-1.6.0-py3-none-any.whl (4.9 kB) 2025-09-29 00:11:05.555863 | controller | Collecting pytest>=3.10 2025-09-29 00:11:05.560810 | controller | Downloading pytest-8.4.2-py3-none-any.whl (365 kB) 2025-09-29 00:11:05.595458 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 365.8/365.8 kB 11.6 MB/s eta 0:00:00 2025-09-29 00:11:05.635971 | controller | Collecting py 2025-09-29 00:11:05.640747 | controller | Downloading py-1.11.0-py2.py3-none-any.whl (98 kB) 2025-09-29 00:11:05.650681 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 98.7/98.7 kB 17.1 MB/s eta 0:00:00 2025-09-29 00:11:05.688926 | controller | Collecting iniconfig>=1 2025-09-29 00:11:05.693322 | controller | Downloading iniconfig-2.1.0-py3-none-any.whl (6.0 kB) 2025-09-29 00:11:05.745411 | controller | Collecting pygments>=2.7.2 2025-09-29 00:11:05.752863 | controller | Downloading pygments-2.19.2-py3-none-any.whl (1.2 MB) 2025-09-29 00:11:05.774126 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 1.2/1.2 MB 67.5 MB/s eta 0:00:00 2025-09-29 00:11:05.817317 | controller | Collecting tomli>=1 2025-09-29 00:11:05.822010 | controller | Downloading tomli-2.2.1-py3-none-any.whl (14 kB) 2025-09-29 00:11:05.869426 | controller | Collecting packaging>=20 2025-09-29 00:11:05.872932 | controller | Downloading packaging-25.0-py3-none-any.whl (66 kB) 2025-09-29 00:11:05.880700 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 66.5/66.5 kB 12.1 MB/s eta 0:00:00 2025-09-29 00:11:05.916917 | controller | Collecting pluggy<2,>=1.5 2025-09-29 00:11:05.920450 | controller | Downloading pluggy-1.6.0-py3-none-any.whl (20 kB) 2025-09-29 00:11:05.951442 | controller | Collecting exceptiongroup>=1 2025-09-29 00:11:05.955204 | controller | Downloading exceptiongroup-1.3.0-py3-none-any.whl (16 kB) 2025-09-29 00:11:06.009537 | controller | Collecting typing-extensions>=4.6.0 2025-09-29 00:11:06.013180 | controller | Downloading typing_extensions-4.15.0-py3-none-any.whl (44 kB) 2025-09-29 00:11:06.019727 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 44.6/44.6 kB 8.2 MB/s eta 0:00:00 2025-09-29 00:11:06.088622 | controller | Installing collected packages: typing-extensions, tomli, pygments, py, pluggy, packaging, iniconfig, exceptiongroup, pytest, pytest-forked 2025-09-29 00:11:07.147417 | controller | Successfully installed exceptiongroup-1.3.0 iniconfig-2.1.0 packaging-25.0 pluggy-1.6.0 py-1.11.0 pygments-2.19.2 pytest-8.4.2 pytest-forked-1.6.0 tomli-2.2.1 typing-extensions-4.15.0 2025-09-29 00:11:07.151696 | controller | 2025-09-29 00:11:07.223663 | controller | [notice] A new release of pip available: 22.2.2 -> 25.2 2025-09-29 00:11:07.223764 | controller | [notice] To update, run: /home/zuul/venv/bin/python -m pip install --upgrade pip 2025-09-29 00:11:07.409062 | controller | ok: Runtime: 0:00:22.664311 2025-09-29 00:11:07.417271 | 2025-09-29 00:11:07.417374 | TASK [Update pip] 2025-09-29 00:11:07.969305 | controller | Requirement already satisfied: pip in ./venv/lib/python3.9/site-packages (22.2.2) 2025-09-29 00:11:17.790065 | controller | Collecting pip 2025-09-29 00:11:30.122959 | controller | Downloading pip-25.2-py3-none-any.whl (1.8 MB) 2025-09-29 00:11:30.171277 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 1.8/1.8 MB 39.0 MB/s eta 0:00:00 2025-09-29 00:11:30.231616 | controller | Installing collected packages: pip 2025-09-29 00:11:30.231746 | controller | Attempting uninstall: pip 2025-09-29 00:11:30.232316 | controller | Found existing installation: pip 22.2.2 2025-09-29 00:11:30.397254 | controller | Uninstalling pip-22.2.2: 2025-09-29 00:11:30.413998 | controller | Successfully uninstalled pip-22.2.2 2025-09-29 00:11:31.179297 | controller | Successfully installed pip-25.2 2025-09-29 00:11:31.513167 | controller | ok: Runtime: 0:00:23.572231 2025-09-29 00:11:31.521972 | 2025-09-29 00:11:31.522102 | TASK [Ensure pip temp directory exists in /var/tmp] 2025-09-29 00:11:31.743983 | controller | changed 2025-09-29 00:11:31.758021 | 2025-09-29 00:11:31.758172 | TASK [Install ansible into virtualenv] 2025-09-29 00:11:32.278793 | controller | Processing ./src/github.com/ansible/ansible 2025-09-29 00:11:32.279578 | controller | Preparing metadata (setup.py): started 2025-09-29 00:11:32.716725 | controller | Preparing metadata (setup.py): finished with status 'done' 2025-09-29 00:11:42.426072 | controller | Collecting jinja2 (from ansible-core==2.11.12.post0) 2025-09-29 00:11:54.667631 | controller | Downloading jinja2-3.1.6-py3-none-any.whl.metadata (2.9 kB) 2025-09-29 00:11:54.748536 | controller | Collecting PyYAML (from ansible-core==2.11.12.post0) 2025-09-29 00:11:54.753293 | controller | Downloading pyyaml-6.0.3-cp39-cp39-manylinux2014_x86_64.manylinux_2_17_x86_64.manylinux_2_28_x86_64.whl.metadata (2.4 kB) 2025-09-29 00:11:55.044264 | controller | Collecting cryptography (from ansible-core==2.11.12.post0) 2025-09-29 00:11:55.051128 | controller | Downloading cryptography-46.0.1-cp38-abi3-manylinux_2_34_x86_64.whl.metadata (5.7 kB) 2025-09-29 00:11:55.075744 | controller | Requirement already satisfied: packaging in ./venv/lib/python3.9/site-packages (from ansible-core==2.11.12.post0) (25.0) 2025-09-29 00:11:55.099177 | controller | Collecting resolvelib<0.6.0,>=0.5.3 (from ansible-core==2.11.12.post0) 2025-09-29 00:11:55.104449 | controller | Downloading resolvelib-0.5.4-py2.py3-none-any.whl.metadata (3.7 kB) 2025-09-29 00:11:55.279603 | controller | Collecting cffi>=2.0.0 (from cryptography->ansible-core==2.11.12.post0) 2025-09-29 00:11:55.291009 | controller | Downloading cffi-2.0.0-cp39-cp39-manylinux2014_x86_64.manylinux_2_17_x86_64.whl.metadata (2.6 kB) 2025-09-29 00:11:55.304046 | controller | Requirement already satisfied: typing-extensions>=4.13.2 in ./venv/lib/python3.9/site-packages (from cryptography->ansible-core==2.11.12.post0) (4.15.0) 2025-09-29 00:11:55.322276 | controller | Collecting pycparser (from cffi>=2.0.0->cryptography->ansible-core==2.11.12.post0) 2025-09-29 00:11:55.326939 | controller | Downloading pycparser-2.23-py3-none-any.whl.metadata (993 bytes) 2025-09-29 00:11:55.428346 | controller | Collecting MarkupSafe>=2.0 (from jinja2->ansible-core==2.11.12.post0) 2025-09-29 00:11:55.433361 | controller | Downloading markupsafe-3.0.3-cp39-cp39-manylinux2014_x86_64.manylinux_2_17_x86_64.manylinux_2_28_x86_64.whl.metadata (2.7 kB) 2025-09-29 00:11:55.451759 | controller | Downloading resolvelib-0.5.4-py2.py3-none-any.whl (12 kB) 2025-09-29 00:11:55.466131 | controller | Downloading cryptography-46.0.1-cp38-abi3-manylinux_2_34_x86_64.whl (4.6 MB) 2025-09-29 00:11:55.519683 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 4.6/4.6 MB 103.5 MB/s 0:00:00 2025-09-29 00:11:55.524721 | controller | Downloading cffi-2.0.0-cp39-cp39-manylinux2014_x86_64.manylinux_2_17_x86_64.whl (216 kB) 2025-09-29 00:11:55.540709 | controller | Downloading jinja2-3.1.6-py3-none-any.whl (134 kB) 2025-09-29 00:11:55.557577 | controller | Downloading markupsafe-3.0.3-cp39-cp39-manylinux2014_x86_64.manylinux_2_17_x86_64.manylinux_2_28_x86_64.whl (20 kB) 2025-09-29 00:11:55.571299 | controller | Downloading pycparser-2.23-py3-none-any.whl (118 kB) 2025-09-29 00:11:55.585260 | controller | Downloading pyyaml-6.0.3-cp39-cp39-manylinux2014_x86_64.manylinux_2_17_x86_64.manylinux_2_28_x86_64.whl (750 kB) 2025-09-29 00:11:55.602798 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 750.8/750.8 kB 34.8 MB/s 0:00:00 2025-09-29 00:11:55.616583 | controller | Building wheels for collected packages: ansible-core 2025-09-29 00:11:55.617206 | controller | DEPRECATION: Building 'ansible-core' using the legacy setup.py bdist_wheel mechanism, which will be removed in a future version. pip 25.3 will enforce this behaviour change. A possible replacement is to use the standardized build interface by setting the `--use-pep517` option, (possibly combined with `--no-build-isolation`), or adding a `pyproject.toml` file to the source tree of 'ansible-core'. Discussion can be found at https://github.com/pypa/pip/issues/6334 2025-09-29 00:11:55.617378 | controller | Building wheel for ansible-core (setup.py): started 2025-09-29 00:11:56.859561 | controller | Building wheel for ansible-core (setup.py): finished with status 'done' 2025-09-29 00:11:56.861985 | controller | Created wheel for ansible-core: filename=ansible_core-2.11.12.post0-py3-none-any.whl size=1961168 sha256=1c85b753ed8e8aa505037a32f8f88834d431e16f18f14de8ad445df976a2cac6 2025-09-29 00:11:56.862115 | controller | Stored in directory: /var/tmp/ansible-test-pip/pip-ephem-wheel-cache-qcti2yqy/wheels/0f/a0/93/ab85b739e1eb1e0467171316f13571c14efdf87e0d5d44a33e 2025-09-29 00:11:56.870871 | controller | Successfully built ansible-core 2025-09-29 00:11:56.907587 | controller | Installing collected packages: resolvelib, PyYAML, pycparser, MarkupSafe, jinja2, cffi, cryptography, ansible-core 2025-09-29 00:11:58.396103 | controller | 2025-09-29 00:11:58.400301 | controller | Successfully installed MarkupSafe-3.0.3 PyYAML-6.0.3 ansible-core-2.11.12.post0 cffi-2.0.0 cryptography-46.0.1 jinja2-3.1.6 pycparser-2.23 resolvelib-0.5.4 2025-09-29 00:11:58.819477 | controller | ok: Runtime: 0:00:26.513097 2025-09-29 00:11:58.829790 | 2025-09-29 00:11:58.829935 | TASK [Setup download-artifact-fork role] 2025-09-29 00:11:58.854141 | controller | ok 2025-09-29 00:11:58.873950 | 2025-09-29 00:11:58.874081 | TASK [download-artifact-fork : Parse inventory response] 2025-09-29 00:11:58.904774 | controller | ok 2025-09-29 00:11:58.910898 | 2025-09-29 00:11:58.910979 | TASK [download-artifact-fork : Fetch info from Zuul API] 2025-09-29 00:11:58.935859 | controller | skipping: Conditional result was False 2025-09-29 00:11:58.943464 | 2025-09-29 00:11:58.943577 | TASK [download-artifact-fork : Parse build response] 2025-09-29 00:11:58.969825 | controller | skipping: Conditional result was False 2025-09-29 00:11:58.979134 | 2025-09-29 00:11:58.979302 | TASK [download-artifact-fork : Ensure artifacts directory exists] 2025-09-29 00:11:59.188500 | controller | changed 2025-09-29 00:11:59.196604 | 2025-09-29 00:11:59.196705 | LOOP [download-artifact-fork : Download archive by type] 2025-09-29 00:11:59.221121 | controller | skipping: Conditional result was False 2025-09-29 00:11:59.221406 | 2025-09-29 00:12:00.337654 | controller | changed: OK (20175 bytes) 2025-09-29 00:12:00.364689 | 2025-09-29 00:12:00.364869 | TASK [deploy-artifacts : define __collections] 2025-09-29 00:12:00.388190 | controller | ok 2025-09-29 00:12:00.394380 | 2025-09-29 00:12:00.394489 | LOOP [deploy-artifacts : Create list of collections] 2025-09-29 00:12:00.420595 | controller | skipping: Conditional result was False 2025-09-29 00:12:00.430040 | controller | ok: 2025-09-29 00:12:00.430158 | controller | { 2025-09-29 00:12:00.430181 | controller | "branch": "main", 2025-09-29 00:12:00.430198 | controller | "job": "build-ansible-collection", 2025-09-29 00:12:00.430215 | controller | "metadata": { 2025-09-29 00:12:00.430230 | controller | "type": "ansible_collection", 2025-09-29 00:12:00.430246 | controller | "version": "5.0.0" 2025-09-29 00:12:00.430261 | controller | }, 2025-09-29 00:12:00.430275 | controller | "name": "ansible.network", 2025-09-29 00:12:00.430290 | controller | "project": "ansible-collections/ansible.network", 2025-09-29 00:12:00.430304 | controller | "url": "https://ac880dc04e5776d62a4f-28f4885b7e8ead4881399a91a1d30ca6.ssl.cf5.rackcdn.com/ansible/ef987a3b6fb9461a9dc3b442059646d1/artifacts/ansible-network-5.0.0.tar.gz" 2025-09-29 00:12:00.430325 | controller | } 2025-09-29 00:12:00.449612 | 2025-09-29 00:12:00.449793 | TASK [deploy-artifacts : Install require-project collection using ansible-galaxy] 2025-09-29 00:12:01.053427 | controller | Starting galaxy collection install process 2025-09-29 00:12:01.053651 | controller | Process install dependency map 2025-09-29 00:12:54.661836 | controller | [WARNING]: Skipping Galaxy server https://galaxy.ansible.com/api/. Got an 2025-09-29 00:12:54.662306 | controller | unexpected error when getting available versions of collection 2025-09-29 00:12:54.662351 | controller | ansible.netcommon: '/api/v3/plugin/ansible/content/published/collections/index/ 2025-09-29 00:12:54.662365 | controller | ansible/netcommon/versions/' 2025-09-29 00:12:54.662419 | controller | ERROR! Unexpected Exception, this is probably a bug: '/api/v3/plugin/ansible/content/published/collections/index/ansible/netcommon/versions/' 2025-09-29 00:12:54.662518 | controller | to see the full traceback, use -vvv 2025-09-29 00:12:55.023844 | controller | ERROR 2025-09-29 00:12:55.024311 | controller | { 2025-09-29 00:12:55.024350 | controller | "delta": "0:00:54.034748", 2025-09-29 00:12:55.024370 | controller | "end": "2025-09-29 00:12:54.700983", 2025-09-29 00:12:55.024388 | controller | "msg": "non-zero return code", 2025-09-29 00:12:55.024405 | controller | "rc": 250, 2025-09-29 00:12:55.024421 | controller | "start": "2025-09-29 00:12:00.666235" 2025-09-29 00:12:55.024455 | controller | } failure 2025-09-29 00:12:55.027046 | 2025-09-29 00:12:55.027103 | PLAY RECAP 2025-09-29 00:12:55.027157 | controller | ok: 44 changed: 24 unreachable: 0 failed: 1 skipped: 17 rescued: 0 ignored: 0 2025-09-29 00:12:55.027185 | 2025-09-29 00:12:55.243124 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master] 2025-09-29 00:12:55.244206 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2025-09-29 00:12:55.887386 | 2025-09-29 00:12:55.887609 | PLAY [all] 2025-09-29 00:12:55.912431 | 2025-09-29 00:12:55.912573 | TASK [fetch-subunit-output : Find stestr or testr executable] 2025-09-29 00:12:56.334284 | controller | changed: non-zero return code 2025-09-29 00:12:56.342211 | 2025-09-29 00:12:56.342335 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2025-09-29 00:12:56.367647 | controller | skipping: Conditional result was False 2025-09-29 00:12:56.375946 | 2025-09-29 00:12:56.376053 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2025-09-29 00:12:56.409606 | 2025-09-29 00:12:56.409815 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2025-09-29 00:12:56.443176 | 2025-09-29 00:12:56.443376 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2025-09-29 00:12:56.458464 | controller | skipping: Conditional result was False 2025-09-29 00:12:56.468321 | 2025-09-29 00:12:56.468466 | LOOP [fetch-subunit-output : Generate subunit file] 2025-09-29 00:12:56.491609 | 2025-09-29 00:12:56.491826 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2025-09-29 00:12:56.507333 | controller | skipping: Conditional result was False 2025-09-29 00:12:56.516386 | 2025-09-29 00:12:56.516488 | TASK [fetch-subunit-output : Remove the temporary file] 2025-09-29 00:12:56.533402 | controller | skipping: Conditional result was False 2025-09-29 00:12:56.543103 | 2025-09-29 00:12:56.543268 | TASK [fetch-subunit-output : Process and fetch subunit results] 2025-09-29 00:12:56.560228 | controller | skipping: Conditional result was False 2025-09-29 00:12:56.597912 | 2025-09-29 00:12:56.598016 | PLAY RECAP 2025-09-29 00:12:56.598069 | controller | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2025-09-29 00:12:56.598097 | 2025-09-29 00:12:56.733870 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2025-09-29 00:12:56.735135 | POST-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/post.yaml@master] 2025-09-29 00:12:57.443211 | 2025-09-29 00:12:57.443336 | PLAY [all:!appliance*] 2025-09-29 00:12:57.467805 | 2025-09-29 00:12:57.467938 | TASK [unregister the node] 2025-09-29 00:12:58.002086 | controller | skipping: Conditional result was False 2025-09-29 00:12:58.010401 | 2025-09-29 00:12:58.010565 | TASK [include_role : fetch-output] 2025-09-29 00:12:58.059953 | controller | ok 2025-09-29 00:12:58.097460 | 2025-09-29 00:12:58.097661 | TASK [fetch-output : Set log path for multiple nodes] 2025-09-29 00:12:58.154282 | controller | skipping: Conditional result was False 2025-09-29 00:12:58.164566 | 2025-09-29 00:12:58.164688 | TASK [fetch-output : Set log path for single node] 2025-09-29 00:12:58.205263 | controller | ok 2025-09-29 00:12:58.210817 | 2025-09-29 00:12:58.210887 | LOOP [fetch-output : Ensure local output dirs] 2025-09-29 00:12:58.612612 | controller -> localhost | ok: "/var/lib/zuul/builds/9ecc968185e94ea28bf73c6fa4b7e9cf/work/logs" 2025-09-29 00:12:58.870753 | controller -> localhost | changed: "/var/lib/zuul/builds/9ecc968185e94ea28bf73c6fa4b7e9cf/work/artifacts" 2025-09-29 00:12:59.139667 | controller -> localhost | changed: "/var/lib/zuul/builds/9ecc968185e94ea28bf73c6fa4b7e9cf/work/docs" 2025-09-29 00:12:59.154600 | 2025-09-29 00:12:59.154831 | LOOP [fetch-output : Collect logs, artifacts and docs] 2025-09-29 00:12:59.919542 | controller | changed: 2025-09-29 00:12:59.919769 | controller | .d..t...... ./ 2025-09-29 00:12:59.919801 | controller | cd+++++++++ controller/ 2025-09-29 00:12:59.919836 | controller | changed: All items complete 2025-09-29 00:12:59.919855 | 2025-09-29 00:13:00.495465 | controller | changed: .d..t...... ./ 2025-09-29 00:13:01.026120 | controller | changed: .d..t...... ./ 2025-09-29 00:13:01.052371 | 2025-09-29 00:13:01.052534 | TASK [include_role : fetch-output-openshift] 2025-09-29 00:13:01.069988 | controller | skipping: Conditional result was False 2025-09-29 00:13:01.081044 | 2025-09-29 00:13:01.081195 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2025-09-29 00:13:01.109055 | controller | skipping: Conditional result was False 2025-09-29 00:13:01.119862 | controller | skipping: Conditional result was False 2025-09-29 00:13:01.155742 | 2025-09-29 00:13:01.155846 | PLAY [localhost] 2025-09-29 00:13:01.168857 | 2025-09-29 00:13:01.168940 | TASK [Run Zuul manifest role] 2025-09-29 00:13:01.187008 | localhost | ok 2025-09-29 00:13:01.201102 | 2025-09-29 00:13:01.201189 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-09-29 00:13:01.638879 | localhost | changed 2025-09-29 00:13:01.645616 | 2025-09-29 00:13:01.645763 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-09-29 00:13:01.689043 | localhost | ok 2025-09-29 00:13:01.700702 | 2025-09-29 00:13:01.700906 | TASK [Set zuul-log-path fact] 2025-09-29 00:13:01.723008 | localhost | ok 2025-09-29 00:13:01.743833 | 2025-09-29 00:13:01.743988 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-09-29 00:13:01.777516 | localhost | ok 2025-09-29 00:13:01.792643 | 2025-09-29 00:13:01.792810 | LOOP [Run upload-logs-swift role] 2025-09-29 00:13:01.818151 | localhost | Output suppressed because no_log was given 2025-09-29 00:13:01.846543 | 2025-09-29 00:13:01.846677 | TASK [Set zuul-log-path fact] 2025-09-29 00:13:01.871221 | localhost | skipping: Conditional result was False 2025-09-29 00:13:01.877012 | 2025-09-29 00:13:01.877145 | TASK [upload-logs-swift : Ensure logs are readable before uploading] 2025-09-29 00:13:02.314924 | localhost -> localhost | ok: Runtime: 0:00:00.006348 2025-09-29 00:13:02.360896 | 2025-09-29 00:13:02.361129 | TASK [upload-logs-swift : Upload logs to swift]