2025-11-26 00:06:42.727527 | Job console starting... 2025-11-26 00:06:42.741637 | Updating repositories 2025-11-26 00:06:42.904037 | Preparing job workspace 2025-11-26 00:06:46.860725 | Running Ansible setup... 2025-11-26 00:06:52.054099 | PRE-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master] 2025-11-26 00:06:52.799103 | 2025-11-26 00:06:52.799242 | PLAY [localhost] 2025-11-26 00:06:52.807881 | 2025-11-26 00:06:52.807984 | TASK [Gathering Facts] 2025-11-26 00:06:54.247839 | localhost | ok 2025-11-26 00:06:54.265577 | 2025-11-26 00:06:54.265757 | TASK [Setup log path fact] 2025-11-26 00:06:54.294697 | localhost | ok 2025-11-26 00:06:54.316193 | 2025-11-26 00:06:54.316561 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-11-26 00:06:54.368911 | localhost | ok 2025-11-26 00:06:54.380552 | 2025-11-26 00:06:54.380692 | TASK [emit-job-header : Print job information] 2025-11-26 00:06:54.429911 | # Job Information 2025-11-26 00:06:54.430059 | Ansible Version: 2.15.12 2025-11-26 00:06:54.430090 | Job: ansible-test-sanity-docker-stable-2.11 2025-11-26 00:06:54.430115 | Pipeline: periodic 2025-11-26 00:06:54.430135 | Executor: ze04.softwarefactory-project.io 2025-11-26 00:06:54.430154 | Triggered by: https://github.com/ansible-collections/ansible.network 2025-11-26 00:06:54.430177 | Log URL (when completed): https://ansible.softwarefactory-project.io/logs/7c7/ansible/7c7f8a610fa14fc7988212631b740d0b/ 2025-11-26 00:06:54.430197 | Event ID: b4d3d99b0f344991b9ba6c3c8351198a 2025-11-26 00:06:54.434392 | 2025-11-26 00:06:54.434473 | LOOP [emit-job-header : Print node information] 2025-11-26 00:06:54.590440 | localhost | ok: 2025-11-26 00:06:54.590791 | localhost | # Node Information 2025-11-26 00:06:54.590833 | localhost | Inventory Hostname: controller 2025-11-26 00:06:54.590862 | localhost | Hostname: np0005535950 2025-11-26 00:06:54.591077 | localhost | Username: zuul 2025-11-26 00:06:54.591126 | localhost | Distro: Fedora 37 2025-11-26 00:06:54.591151 | localhost | Provider: ansible-vexxhost-ca-ymq-1 2025-11-26 00:06:54.591177 | localhost | Region: ca-ymq-1 2025-11-26 00:06:54.591203 | localhost | Label: ansible-fedora-37-1vcpu 2025-11-26 00:06:54.591226 | localhost | Product Name: OpenStack Nova 2025-11-26 00:06:54.591250 | localhost | Interface IP: 162.253.55.227 2025-11-26 00:06:54.607098 | 2025-11-26 00:06:54.608186 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-11-26 00:06:55.153631 | localhost -> localhost | changed 2025-11-26 00:06:55.161886 | 2025-11-26 00:06:55.161993 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-11-26 00:06:56.244411 | localhost -> localhost | changed 2025-11-26 00:06:56.264273 | 2025-11-26 00:06:56.264365 | PLAY [all:!appliance*] 2025-11-26 00:06:56.280659 | 2025-11-26 00:06:56.280750 | TASK [include_role : start-zuul-console] 2025-11-26 00:06:56.299900 | controller | ok 2025-11-26 00:06:56.313321 | 2025-11-26 00:06:56.313391 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-11-26 00:06:56.707549 | controller | ok 2025-11-26 00:06:56.724103 | 2025-11-26 00:06:56.724208 | TASK [use-our-mirror : Retrieve the IP address] 2025-11-26 00:06:57.466359 | controller | ok: Status code was 404 and not [200]: HTTP Error 404: Not Found 2025-11-26 00:06:57.482186 | 2025-11-26 00:06:57.482336 | TASK [use-our-mirror : Ensure the registry service is running on the mirror] 2025-11-26 00:06:58.036941 | controller | skipping: Conditional result was False 2025-11-26 00:06:58.044076 | 2025-11-26 00:06:58.044171 | TASK [use-our-mirror : Write the IP in /etc/hosts] 2025-11-26 00:06:58.076167 | controller | skipping: Conditional result was False 2025-11-26 00:06:58.088411 | 2025-11-26 00:06:58.088571 | TASK [use-our-mirror : Create the podman configuration directory] 2025-11-26 00:06:58.116337 | controller | skipping: Conditional result was False 2025-11-26 00:06:58.129030 | 2025-11-26 00:06:58.129177 | TASK [use-our-mirror : Copy the podman configuration] 2025-11-26 00:06:58.155162 | controller | skipping: Conditional result was False 2025-11-26 00:06:58.164711 | 2025-11-26 00:06:58.164883 | TASK [use-our-mirror : Copy the Yum configuration (main)] 2025-11-26 00:06:58.193128 | controller | skipping: Conditional result was False 2025-11-26 00:06:58.205173 | 2025-11-26 00:06:58.205317 | TASK [use-our-mirror : Copy the Yum configuration (update)] 2025-11-26 00:06:58.234910 | controller | skipping: Conditional result was False 2025-11-26 00:06:58.259411 | 2025-11-26 00:06:58.259559 | TASK [Disable Fedora Modular] 2025-11-26 00:06:58.495728 | controller | changed 2025-11-26 00:06:58.502292 | 2025-11-26 00:06:58.502386 | TASK [Enable EPEL] 2025-11-26 00:06:58.516139 | controller | skipping: Conditional result was False 2025-11-26 00:06:58.524234 | 2025-11-26 00:06:58.524349 | TASK [Register the RHEL node] 2025-11-26 00:06:59.065741 | 2025-11-26 00:06:59.065909 | TASK [Show the subscription-manager status] 2025-11-26 00:06:59.652562 | controller | skipping: Conditional result was False 2025-11-26 00:06:59.668248 | 2025-11-26 00:06:59.668399 | TASK [Enable EPEL on RHEL] 2025-11-26 00:07:00.225187 | controller | skipping: Conditional result was False 2025-11-26 00:07:00.238551 | 2025-11-26 00:07:00.238735 | TASK [Install git and tox] 2025-11-26 00:08:24.644989 | controller | changed 2025-11-26 00:08:24.651452 | 2025-11-26 00:08:24.651520 | TASK [include_role : prepare-workspace] 2025-11-26 00:08:24.681935 | controller | ok 2025-11-26 00:08:24.723244 | 2025-11-26 00:08:24.723349 | TASK [prepare-workspace : Start zuul_console daemon.] 2025-11-26 00:08:24.968956 | controller | ok 2025-11-26 00:08:24.983551 | 2025-11-26 00:08:24.983657 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2025-11-26 00:08:40.821743 | controller | Output suppressed because no_log was given 2025-11-26 00:08:40.891546 | 2025-11-26 00:08:40.891668 | TASK [include_role : prepare-workspace-openshift] 2025-11-26 00:08:40.929465 | controller | skipping: Conditional result was False 2025-11-26 00:08:40.960531 | 2025-11-26 00:08:40.960612 | PLAY [all:!appliance] 2025-11-26 00:08:40.977696 | 2025-11-26 00:08:40.977820 | TASK [Run add-build-sshkey role (RSA)] 2025-11-26 00:08:40.998125 | controller | ok 2025-11-26 00:08:41.014769 | 2025-11-26 00:08:41.014896 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-11-26 00:08:41.245064 | controller -> localhost | ok 2025-11-26 00:08:41.251565 | 2025-11-26 00:08:41.251634 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-11-26 00:08:41.272396 | controller | ok 2025-11-26 00:08:41.287172 | controller | included: /var/lib/zuul/builds/7c7f8a610fa14fc7988212631b740d0b/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-11-26 00:08:41.293406 | 2025-11-26 00:08:41.293473 | TASK [add-build-sshkey : Create Temp SSH key] 2025-11-26 00:08:41.817987 | controller -> localhost | Generating public/private rsa key pair. 2025-11-26 00:08:41.818217 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/7c7f8a610fa14fc7988212631b740d0b/work/7c7f8a610fa14fc7988212631b740d0b_id_rsa. 2025-11-26 00:08:41.818247 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/7c7f8a610fa14fc7988212631b740d0b/work/7c7f8a610fa14fc7988212631b740d0b_id_rsa.pub. 2025-11-26 00:08:41.818269 | controller -> localhost | The key fingerprint is: 2025-11-26 00:08:41.818291 | controller -> localhost | SHA256:WWCnEZV3NGQ7rzHdHdRkLJppEe9DwET/tTD1zWTN5fQ zuul-build-sshkey 2025-11-26 00:08:41.818311 | controller -> localhost | The key's randomart image is: 2025-11-26 00:08:41.818330 | controller -> localhost | +---[RSA 2048]----+ 2025-11-26 00:08:41.818348 | controller -> localhost | | =oo==+=*@| 2025-11-26 00:08:41.818367 | controller -> localhost | | . =. +==XO| 2025-11-26 00:08:41.818386 | controller -> localhost | | . .. O*oE| 2025-11-26 00:08:41.818405 | controller -> localhost | | o =oo=*| 2025-11-26 00:08:41.818424 | controller -> localhost | | S . =.*| 2025-11-26 00:08:41.818450 | controller -> localhost | | = | 2025-11-26 00:08:41.818474 | controller -> localhost | | . | 2025-11-26 00:08:41.818496 | controller -> localhost | | | 2025-11-26 00:08:41.818514 | controller -> localhost | | | 2025-11-26 00:08:41.818535 | controller -> localhost | +----[SHA256]-----+ 2025-11-26 00:08:41.818583 | controller -> localhost | ok: Runtime: 0:00:00.128568 2025-11-26 00:08:41.826137 | 2025-11-26 00:08:41.826214 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-11-26 00:08:41.847393 | controller | ok 2025-11-26 00:08:41.858559 | controller | included: /var/lib/zuul/builds/7c7f8a610fa14fc7988212631b740d0b/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-11-26 00:08:41.869244 | 2025-11-26 00:08:41.869407 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-11-26 00:08:41.894466 | controller | skipping: Conditional result was False 2025-11-26 00:08:41.904763 | 2025-11-26 00:08:41.904863 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-11-26 00:08:42.370720 | controller | changed 2025-11-26 00:08:42.378045 | 2025-11-26 00:08:42.378282 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-11-26 00:08:42.618321 | controller | ok 2025-11-26 00:08:42.627372 | 2025-11-26 00:08:42.627450 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-11-26 00:08:43.252581 | controller | changed 2025-11-26 00:08:43.263318 | 2025-11-26 00:08:43.263504 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-11-26 00:08:43.892694 | controller | changed 2025-11-26 00:08:43.900821 | 2025-11-26 00:08:43.900938 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-11-26 00:08:43.940010 | controller | skipping: Conditional result was False 2025-11-26 00:08:43.946492 | 2025-11-26 00:08:43.946596 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-11-26 00:08:44.347654 | controller -> localhost | changed 2025-11-26 00:08:44.363925 | 2025-11-26 00:08:44.364058 | TASK [add-build-sshkey : Add back temp key] 2025-11-26 00:08:44.693837 | controller -> localhost | Identity added: /var/lib/zuul/builds/7c7f8a610fa14fc7988212631b740d0b/work/7c7f8a610fa14fc7988212631b740d0b_id_rsa (zuul-build-sshkey) 2025-11-26 00:08:44.694090 | controller -> localhost | ok: Runtime: 0:00:00.012005 2025-11-26 00:08:44.703951 | 2025-11-26 00:08:44.704031 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-11-26 00:08:45.054720 | controller | ok 2025-11-26 00:08:45.060984 | 2025-11-26 00:08:45.061266 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-11-26 00:08:45.119550 | controller | skipping: Conditional result was False 2025-11-26 00:08:45.140003 | 2025-11-26 00:08:45.140093 | TASK [Run add-build-sshkey role (ECDSA)] 2025-11-26 00:08:45.175171 | controller | ok 2025-11-26 00:08:45.204861 | 2025-11-26 00:08:45.205156 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-11-26 00:08:45.536613 | controller -> localhost | ok 2025-11-26 00:08:45.545457 | 2025-11-26 00:08:45.545544 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-11-26 00:08:45.578611 | controller | ok 2025-11-26 00:08:45.594344 | controller | included: /var/lib/zuul/builds/7c7f8a610fa14fc7988212631b740d0b/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-11-26 00:08:45.604325 | 2025-11-26 00:08:45.604402 | TASK [add-build-sshkey : Create Temp SSH key] 2025-11-26 00:08:46.048316 | controller -> localhost | Generating public/private ecdsa key pair. 2025-11-26 00:08:46.048489 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/7c7f8a610fa14fc7988212631b740d0b/work/7c7f8a610fa14fc7988212631b740d0b_id_ecdsa. 2025-11-26 00:08:46.048532 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/7c7f8a610fa14fc7988212631b740d0b/work/7c7f8a610fa14fc7988212631b740d0b_id_ecdsa.pub. 2025-11-26 00:08:46.048562 | controller -> localhost | The key fingerprint is: 2025-11-26 00:08:46.048582 | controller -> localhost | SHA256:nGbr7aQVkLGwQUmA4K4zCxmd+n9SnC/bjA4G+oLkf2A zuul-build-sshkey 2025-11-26 00:08:46.048601 | controller -> localhost | The key's randomart image is: 2025-11-26 00:08:46.048620 | controller -> localhost | +---[ECDSA 521]---+ 2025-11-26 00:08:46.048638 | controller -> localhost | |.. ..+=.. | 2025-11-26 00:08:46.048656 | controller -> localhost | |. . .+ + | 2025-11-26 00:08:46.048674 | controller -> localhost | | . . + | 2025-11-26 00:08:46.048714 | controller -> localhost | |.. . . o | 2025-11-26 00:08:46.048732 | controller -> localhost | |..+ . .S . | 2025-11-26 00:08:46.048750 | controller -> localhost | |.* E +o . . | 2025-11-26 00:08:46.048778 | controller -> localhost | |% . +. .. o | 2025-11-26 00:08:46.048797 | controller -> localhost | |+B ..oo=.= | 2025-11-26 00:08:46.048814 | controller -> localhost | |..+oo++o=.o | 2025-11-26 00:08:46.048831 | controller -> localhost | +----[SHA256]-----+ 2025-11-26 00:08:46.048880 | controller -> localhost | ok: Runtime: 0:00:00.013751 2025-11-26 00:08:46.055728 | 2025-11-26 00:08:46.055791 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-11-26 00:08:46.107502 | controller | ok 2025-11-26 00:08:46.124170 | controller | included: /var/lib/zuul/builds/7c7f8a610fa14fc7988212631b740d0b/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-11-26 00:08:46.139822 | 2025-11-26 00:08:46.139934 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-11-26 00:08:46.181126 | controller | skipping: Conditional result was False 2025-11-26 00:08:46.188766 | 2025-11-26 00:08:46.188861 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-11-26 00:08:46.510268 | controller | changed 2025-11-26 00:08:46.519614 | 2025-11-26 00:08:46.519720 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-11-26 00:08:46.754617 | controller | ok 2025-11-26 00:08:46.765196 | 2025-11-26 00:08:46.765470 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-11-26 00:08:47.406450 | controller | changed 2025-11-26 00:08:47.412670 | 2025-11-26 00:08:47.412749 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-11-26 00:08:48.044482 | controller | changed 2025-11-26 00:08:48.085731 | 2025-11-26 00:08:48.085864 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-11-26 00:08:48.120506 | controller | skipping: Conditional result was False 2025-11-26 00:08:48.127215 | 2025-11-26 00:08:48.127285 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-11-26 00:08:48.416945 | controller -> localhost | changed 2025-11-26 00:08:48.444482 | 2025-11-26 00:08:48.444640 | TASK [add-build-sshkey : Add back temp key] 2025-11-26 00:08:48.745566 | controller -> localhost | Identity added: /var/lib/zuul/builds/7c7f8a610fa14fc7988212631b740d0b/work/7c7f8a610fa14fc7988212631b740d0b_id_ecdsa (zuul-build-sshkey) 2025-11-26 00:08:48.745840 | controller -> localhost | ok: Runtime: 0:00:00.013939 2025-11-26 00:08:48.752208 | 2025-11-26 00:08:48.752270 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-11-26 00:08:48.950017 | controller | ok 2025-11-26 00:08:48.955558 | 2025-11-26 00:08:48.955623 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-11-26 00:08:48.979647 | controller | skipping: Conditional result was False 2025-11-26 00:08:48.991159 | 2025-11-26 00:08:48.991226 | TASK [include_role : remove-zuul-sshkey] 2025-11-26 00:08:49.005162 | controller | skipping: Conditional result was False 2025-11-26 00:08:49.013145 | 2025-11-26 00:08:49.013267 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2025-11-26 00:08:49.235637 | controller | ok: "logs" 2025-11-26 00:08:49.236776 | controller | ok: All items complete 2025-11-26 00:08:49.236883 | 2025-11-26 00:08:49.425307 | controller | ok: "artifacts" 2025-11-26 00:08:49.620987 | controller | ok: "docs" 2025-11-26 00:08:49.638113 | 2025-11-26 00:08:49.638281 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2025-11-26 00:08:49.875353 | controller | changed: "logs" 2025-11-26 00:08:50.045705 | controller | changed: "artifacts" 2025-11-26 00:08:50.241908 | controller | changed: "docs" 2025-11-26 00:08:50.322509 | 2025-11-26 00:08:50.322614 | PLAY RECAP 2025-11-26 00:08:50.322673 | controller | ok: 30 changed: 16 unreachable: 0 failed: 0 skipped: 18 rescued: 0 ignored: 0 2025-11-26 00:08:50.322742 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-11-26 00:08:50.322790 | 2025-11-26 00:08:50.458876 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master] 2025-11-26 00:08:50.459736 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2025-11-26 00:08:51.846318 | 2025-11-26 00:08:51.846431 | PLAY [all] 2025-11-26 00:08:51.871497 | 2025-11-26 00:08:51.871604 | TASK [Install binary dependencies] 2025-11-26 00:08:51.942917 | controller | ok 2025-11-26 00:08:51.964206 | 2025-11-26 00:08:51.964308 | TASK [bindep : Include find tasks] 2025-11-26 00:08:51.993665 | controller | ok 2025-11-26 00:08:52.002103 | controller | included: /var/lib/zuul/builds/7c7f8a610fa14fc7988212631b740d0b/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find.yaml 2025-11-26 00:08:52.008600 | 2025-11-26 00:08:52.008663 | TASK [bindep : Look for bindep.txt] 2025-11-26 00:08:52.334237 | controller | ok 2025-11-26 00:08:52.341398 | 2025-11-26 00:08:52.341543 | TASK [bindep : Define bindep_file fact] 2025-11-26 00:08:52.359878 | controller | skipping: Conditional result was False 2025-11-26 00:08:52.375282 | 2025-11-26 00:08:52.375380 | TASK [bindep : Look for other-requirements.txt] 2025-11-26 00:08:52.578044 | controller | ok 2025-11-26 00:08:52.583624 | 2025-11-26 00:08:52.583708 | TASK [bindep : Define bindep_file fact] 2025-11-26 00:08:52.618245 | controller | skipping: Conditional result was False 2025-11-26 00:08:52.625023 | 2025-11-26 00:08:52.625174 | TASK [bindep : Look for bindep fallback file] 2025-11-26 00:08:52.659981 | controller | skipping: Conditional result was False 2025-11-26 00:08:52.670462 | 2025-11-26 00:08:52.670563 | TASK [bindep : Define bindep_file fact] 2025-11-26 00:08:52.695774 | controller | skipping: Conditional result was False 2025-11-26 00:08:52.704563 | 2025-11-26 00:08:52.704669 | TASK [bindep : Include bindep tasks] 2025-11-26 00:08:52.729712 | controller | skipping: Conditional result was False 2025-11-26 00:08:52.738445 | 2025-11-26 00:08:52.738531 | TASK [bindep : Include install tasks] 2025-11-26 00:08:52.763546 | controller | skipping: Conditional result was False 2025-11-26 00:08:52.772757 | 2025-11-26 00:08:52.772851 | LOOP [bindep : Include package tasks] 2025-11-26 00:08:52.844743 | 2025-11-26 00:08:52.844952 | TASK [Run test-setup role] 2025-11-26 00:08:52.869825 | controller | ok 2025-11-26 00:08:52.904611 | 2025-11-26 00:08:52.904784 | TASK [test-setup : Check if project's tools/test-setup.sh exists] 2025-11-26 00:08:53.108067 | controller | ok 2025-11-26 00:08:53.113950 | 2025-11-26 00:08:53.114015 | TASK [test-setup : Run tools/test-setup.sh] 2025-11-26 00:08:53.645513 | controller | skipping: Conditional result was False 2025-11-26 00:08:53.672759 | 2025-11-26 00:08:53.672868 | PLAY RECAP 2025-11-26 00:08:53.672915 | controller | ok: 4 changed: 0 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2025-11-26 00:08:53.672943 | 2025-11-26 00:08:53.795070 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2025-11-26 00:08:53.795977 | PRE-RUN START: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master] 2025-11-26 00:08:54.376194 | 2025-11-26 00:08:54.376309 | PLAY [controller] 2025-11-26 00:08:54.395750 | 2025-11-26 00:08:54.395824 | TASK [Create the /root directory] 2025-11-26 00:08:54.770139 | controller | ok 2025-11-26 00:08:54.790410 | 2025-11-26 00:08:54.790593 | TASK [Install glibc-langpack-en] 2025-11-26 00:08:59.088023 | controller | ok: Nothing to do 2025-11-26 00:08:59.100944 | 2025-11-26 00:08:59.101097 | TASK [Ensure controller directory exists] 2025-11-26 00:08:59.345748 | controller | changed 2025-11-26 00:08:59.358657 | 2025-11-26 00:08:59.358937 | TASK [Install container runtime] 2025-11-26 00:08:59.430978 | controller | ok 2025-11-26 00:08:59.498339 | 2025-11-26 00:08:59.498483 | LOOP [ensure-docker : Gather variables for each operating system] 2025-11-26 00:08:59.563006 | controller | ok: "/var/lib/zuul/builds/7c7f8a610fa14fc7988212631b740d0b/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/vars/fedora.yaml" 2025-11-26 00:08:59.584217 | 2025-11-26 00:08:59.584403 | TASK [ensure-docker : Sanity check for distro selection] 2025-11-26 00:08:59.611050 | controller | skipping: Conditional result was False 2025-11-26 00:08:59.624346 | 2025-11-26 00:08:59.624513 | TASK [ensure-docker : Sanity check for distro install] 2025-11-26 00:08:59.661435 | controller | skipping: Conditional result was False 2025-11-26 00:08:59.675214 | 2025-11-26 00:08:59.675363 | TASK [ensure-docker : Sanity check for upstream install] 2025-11-26 00:08:59.702095 | controller | skipping: Conditional result was False 2025-11-26 00:08:59.718773 | 2025-11-26 00:08:59.718916 | TASK [ensure-docker : Sanity check for upstream install mirrors] 2025-11-26 00:08:59.745022 | controller | skipping: Conditional result was False 2025-11-26 00:08:59.754950 | 2025-11-26 00:08:59.755047 | TASK [ensure-docker : Include tasks for docker install] 2025-11-26 00:08:59.787592 | controller | ok 2025-11-26 00:08:59.799671 | controller | included: /var/lib/zuul/builds/7c7f8a610fa14fc7988212631b740d0b/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/tasks/docker-upstream.yaml 2025-11-26 00:08:59.810794 | 2025-11-26 00:08:59.810881 | TASK [ensure-docker : Not Implemented] 2025-11-26 00:08:59.845338 | controller | skipping: Conditional result was False 2025-11-26 00:08:59.854510 | 2025-11-26 00:08:59.854600 | TASK [Set up docker mirrors] 2025-11-26 00:08:59.877715 | controller | ok 2025-11-26 00:08:59.906334 | 2025-11-26 00:08:59.906441 | TASK [use-docker-mirror : Set docker_mirror fact from mirror_info] 2025-11-26 00:08:59.941661 | controller | skipping: Conditional result was False 2025-11-26 00:08:59.950362 | 2025-11-26 00:08:59.950453 | TASK [use-docker-mirror : Set docker_mirror fact from zuul_site_mirror_info] 2025-11-26 00:08:59.975609 | controller | skipping: Conditional result was False 2025-11-26 00:08:59.984297 | 2025-11-26 00:08:59.984388 | TASK [use-docker-mirror : Set docker_mirror fact] 2025-11-26 00:09:00.018896 | controller | skipping: Conditional result was False 2025-11-26 00:09:00.027411 | 2025-11-26 00:09:00.027498 | TASK [use-docker-mirror : Set up docker mirrors] 2025-11-26 00:09:00.052139 | controller | skipping: Conditional result was False 2025-11-26 00:09:00.069181 | 2025-11-26 00:09:00.069267 | TASK [Add all repositories] 2025-11-26 00:09:00.091968 | controller | ok 2025-11-26 00:09:00.125829 | 2025-11-26 00:09:00.125957 | LOOP [ensure-package-repositories : Install repositories] 2025-11-26 00:09:00.158166 | controller | ok: "/var/lib/zuul/builds/7c7f8a610fa14fc7988212631b740d0b/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-package-repositories/tasks/RedHat.yaml" 2025-11-26 00:09:00.158337 | controller | ok: All items complete 2025-11-26 00:09:00.158366 | 2025-11-26 00:09:00.203396 | controller | included: /var/lib/zuul/builds/7c7f8a610fa14fc7988212631b740d0b/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-package-repositories/tasks/RedHat.yaml 2025-11-26 00:09:00.217135 | 2025-11-26 00:09:00.217322 | TASK [ensure-package-repositories : Create GPG key temp directory] 2025-11-26 00:09:00.527806 | controller | changed 2025-11-26 00:09:00.536674 | 2025-11-26 00:09:00.536787 | LOOP [ensure-package-repositories : Upload GPG keys] 2025-11-26 00:09:01.465554 | controller | changed: 2025-11-26 00:09:01.465758 | controller | { 2025-11-26 00:09:01.465786 | 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-11-26 00:09:01.465808 | controller | } 2025-11-26 00:09:01.483160 | 2025-11-26 00:09:01.483297 | LOOP [ensure-package-repositories : Import GPG keys] 2025-11-26 00:09:02.260533 | controller | changed: 2025-11-26 00:09:02.260828 | controller | { 2025-11-26 00:09:02.260887 | 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-11-26 00:09:02.260935 | controller | } 2025-11-26 00:09:02.260995 | controller | changed: All items complete 2025-11-26 00:09:02.261041 | 2025-11-26 00:09:02.282223 | 2025-11-26 00:09:02.282378 | TASK [ensure-package-repositories : Remove GPG temp directory] 2025-11-26 00:09:02.534434 | controller | changed 2025-11-26 00:09:02.544403 | 2025-11-26 00:09:02.544498 | LOOP [ensure-package-repositories : Add repositories] 2025-11-26 00:09:02.900100 | controller | changed: 2025-11-26 00:09:02.900329 | controller | { 2025-11-26 00:09:02.900372 | controller | "baseurl": "https://download.docker.com/linux/fedora/$releasever/$basearch/stable", 2025-11-26 00:09:02.900406 | controller | "description": "Docker CE Stable - $basearch", 2025-11-26 00:09:02.900443 | controller | "gpgcheck": true, 2025-11-26 00:09:02.900478 | controller | "name": "docker-ce-stable" 2025-11-26 00:09:02.900512 | controller | } 2025-11-26 00:09:02.923470 | 2025-11-26 00:09:02.923692 | TASK [ensure-docker : Install pre-reqs] 2025-11-26 00:09:06.124890 | controller | ok: Nothing to do 2025-11-26 00:09:06.130978 | 2025-11-26 00:09:06.131064 | TASK [ensure-docker : Remove packages] 2025-11-26 00:09:07.517829 | controller | ok: Nothing to do 2025-11-26 00:09:07.527513 | 2025-11-26 00:09:07.527635 | TASK [ensure-docker : Install upstream docker using package] 2025-11-26 00:09:56.756549 | controller | changed 2025-11-26 00:09:56.783118 | 2025-11-26 00:09:56.783258 | TASK [ensure-docker : Setup docker] 2025-11-26 00:09:56.821981 | controller | ok 2025-11-26 00:09:56.838668 | controller | included: /var/lib/zuul/builds/7c7f8a610fa14fc7988212631b740d0b/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/tasks/docker-setup.yaml 2025-11-26 00:09:56.855995 | 2025-11-26 00:09:56.856086 | TASK [ensure-docker : Ensure "docker" group exists] 2025-11-26 00:09:57.259759 | controller | ok 2025-11-26 00:09:57.283050 | 2025-11-26 00:09:57.283240 | TASK [ensure-docker : Add user to docker group] 2025-11-26 00:09:57.832527 | controller | changed 2025-11-26 00:09:57.838148 | 2025-11-26 00:09:57.838205 | TASK [Update docker daemon proxy configuration] 2025-11-26 00:09:57.873531 | controller | skipping: Conditional result was False 2025-11-26 00:09:57.880241 | 2025-11-26 00:09:57.880303 | TASK [Lower default MTU] 2025-11-26 00:09:57.904877 | controller | skipping: Conditional result was False 2025-11-26 00:09:57.913251 | 2025-11-26 00:09:57.913340 | TASK [ensure-docker : Get the status of the docket.socket unit] 2025-11-26 00:09:58.312086 | controller | inactive 2025-11-26 00:09:58.449912 | controller | ok: Runtime: 0:00:00.013743 2025-11-26 00:09:58.463842 | 2025-11-26 00:09:58.463984 | TASK [ensure-docker : Get the status of the docket unit] 2025-11-26 00:09:58.756220 | controller | inactive 2025-11-26 00:09:59.047926 | controller | ok: Runtime: 0:00:00.008701 2025-11-26 00:09:59.062402 | 2025-11-26 00:09:59.062537 | TASK [ensure-docker : Restart docker] 2025-11-26 00:09:59.122913 | Trigger a Docker restart 2025-11-26 00:09:59.136283 | 2025-11-26 00:09:59.136436 | TASK [ensure-docker : Reset ssh connection to pick up docker group] 2025-11-26 00:09:59.182017 | 2025-11-26 00:09:59.182128 | TASK [ensure-docker : Install docker-compose] 2025-11-26 00:09:59.207871 | controller | skipping: Conditional result was False 2025-11-26 00:09:59.216524 | 2025-11-26 00:09:59.216613 | TASK [ensure-docker : Flush handlers before role exit] 2025-11-26 00:09:59.239999 | 2025-11-26 00:09:59.240066 | TASK [ensure-docker : Stop docker.socket to avoid any conflict] 2025-11-26 00:10:00.370379 | controller | changed 2025-11-26 00:10:00.381216 | 2025-11-26 00:10:00.381304 | TASK [ensure-docker : Assure docker service is running] 2025-11-26 00:10:02.624222 | controller | changed 2025-11-26 00:10:02.631725 | 2025-11-26 00:10:02.631792 | TASK [ensure-docker : Assure docker.socket service is running] 2025-11-26 00:10:03.680768 | controller | ok 2025-11-26 00:10:03.701317 | 2025-11-26 00:10:03.701513 | TASK [ensure-docker : Correct group ownership on docker sock] 2025-11-26 00:10:04.294870 | controller | ok 2025-11-26 00:10:04.309161 | 2025-11-26 00:10:04.309329 | TASK [ensure-docker : Validate ability to talk with docker] 2025-11-26 00:10:04.882324 | controller | CONTAINER ID IMAGE COMMAND CREATED STATUS PORTS NAMES 2025-11-26 00:10:05.362032 | controller | ok: Runtime: 0:00:00.040881 2025-11-26 00:10:05.376605 | 2025-11-26 00:10:05.377162 | TASK [ensure-docker : Get version details] 2025-11-26 00:10:05.935399 | controller | Client: Docker Engine - Community 2025-11-26 00:10:05.935535 | controller | Version: 24.0.7 2025-11-26 00:10:05.935570 | controller | API version: 1.43 2025-11-26 00:10:05.935613 | controller | Go version: go1.20.10 2025-11-26 00:10:05.935652 | controller | Git commit: afdd53b 2025-11-26 00:10:05.935699 | controller | Built: Thu Oct 26 09:09:39 2023 2025-11-26 00:10:05.935736 | controller | OS/Arch: linux/amd64 2025-11-26 00:10:05.935783 | controller | Context: default 2025-11-26 00:10:05.935799 | controller | 2025-11-26 00:10:05.935840 | controller | Server: Docker Engine - Community 2025-11-26 00:10:05.935877 | controller | Engine: 2025-11-26 00:10:05.935991 | controller | Version: 24.0.7 2025-11-26 00:10:05.936039 | controller | API version: 1.43 (minimum version 1.12) 2025-11-26 00:10:05.936074 | controller | Go version: go1.20.10 2025-11-26 00:10:05.936104 | controller | Git commit: 311b9ff 2025-11-26 00:10:05.936150 | controller | Built: Thu Oct 26 09:08:11 2023 2025-11-26 00:10:05.936186 | controller | OS/Arch: linux/amd64 2025-11-26 00:10:05.936227 | controller | Experimental: false 2025-11-26 00:10:05.936246 | controller | containerd: 2025-11-26 00:10:05.936327 | controller | Version: 1.6.25 2025-11-26 00:10:05.936359 | controller | GitCommit: d8f198a4ed8892c764191ef7b3b06d8a2eeb5c7f 2025-11-26 00:10:05.936388 | controller | runc: 2025-11-26 00:10:05.936447 | controller | Version: 1.1.10 2025-11-26 00:10:05.936490 | controller | GitCommit: v1.1.10-0-g18a0cb0 2025-11-26 00:10:05.936511 | controller | docker-init: 2025-11-26 00:10:05.936573 | controller | Version: 0.19.0 2025-11-26 00:10:05.936604 | controller | GitCommit: de40ad0 2025-11-26 00:10:05.981496 | controller | ok: Runtime: 0:00:00.044964 2025-11-26 00:10:05.995897 | 2025-11-26 00:10:05.996047 | TASK [ensure-docker : Dump installed docker details] 2025-11-26 00:10:06.038025 | {'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-11-26 00:10:05.893257', 'end': '2025-11-26 00:10:05.938221', 'delta': '0:00:00.044964', 'msg': '', 'zuul_log_id': 'fa163e3b-3c83-eae5-2967-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-11-26 00:10:06.049744 | 2025-11-26 00:10:06.049896 | TASK [Use buildset registry] 2025-11-26 00:10:06.065993 | controller | skipping: Conditional result was False 2025-11-26 00:10:06.082441 | 2025-11-26 00:10:06.082547 | TASK [Ensure python3.8 is present] 2025-11-26 00:10:06.097519 | controller | skipping: Conditional result was False 2025-11-26 00:10:06.107504 | 2025-11-26 00:10:06.107630 | TASK [Run our-ensure-python role (Fedora only for now)] 2025-11-26 00:10:06.129159 | controller | ok 2025-11-26 00:10:06.159405 | 2025-11-26 00:10:06.159542 | TASK [our-ensure-python : Install the right Python version (rpm)] 2025-11-26 00:10:08.268605 | controller | ok: Nothing to do 2025-11-26 00:10:08.275904 | 2025-11-26 00:10:08.276056 | TASK [our-ensure-python : Also install python3-devel] 2025-11-26 00:10:33.830896 | controller | changed 2025-11-26 00:10:33.846576 | 2025-11-26 00:10:33.846700 | TASK [Run ensure-virtualenv role] 2025-11-26 00:10:33.869205 | controller | ok 2025-11-26 00:10:33.899734 | 2025-11-26 00:10:33.899917 | TASK [ensure-virtualenv : Check if virtualenv is installed] 2025-11-26 00:10:34.126139 | controller | /usr/bin/virtualenv 2025-11-26 00:10:34.434042 | controller | ok: Runtime: 0:00:00.005499 2025-11-26 00:10:34.449767 | 2025-11-26 00:10:34.449927 | LOOP [ensure-virtualenv : Install virtualenv package] 2025-11-26 00:10:34.486667 | controller | skipping: Conditional result was False 2025-11-26 00:10:34.487199 | controller | changed: All items complete 2025-11-26 00:10:34.487267 | 2025-11-26 00:10:34.516710 | 2025-11-26 00:10:34.516903 | TASK [Find the full path of the Python interpreter] 2025-11-26 00:10:34.745728 | controller | /usr/bin/python3.9 2025-11-26 00:10:35.060078 | controller | ok 2025-11-26 00:10:35.072428 | 2025-11-26 00:10:35.072568 | TASK [Create virtualenv for ansible-test with a specific interpreter] 2025-11-26 00:10:36.764862 | controller | created virtual environment CPython3.9.18.final.0-64 in 853ms 2025-11-26 00:10:36.797822 | controller | creator CPython3Posix(dest=/home/zuul/venv, clear=False, no_vcs_ignore=False, global=False) 2025-11-26 00:10:36.797840 | 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-11-26 00:10:36.797850 | controller | added seed packages: pip==22.2.2, setuptools==62.6.0, wheel==0.37.1 2025-11-26 00:10:36.797869 | controller | activators BashActivator,CShellActivator,FishActivator,NushellActivator,PowerShellActivator,PythonActivator 2025-11-26 00:10:37.128959 | controller | changed 2025-11-26 00:10:37.141841 | 2025-11-26 00:10:37.141977 | TASK [Set selinux package] 2025-11-26 00:10:37.180388 | controller | ok 2025-11-26 00:10:37.192723 | 2025-11-26 00:10:37.192854 | TASK [Set selinux package (Fedora)] 2025-11-26 00:10:37.241673 | controller | ok 2025-11-26 00:10:37.252661 | 2025-11-26 00:10:37.252936 | TASK [Install selinux into virtualenv] 2025-11-26 00:10:52.910003 | controller | Collecting selinux-please-lie-to-me 2025-11-26 00:11:05.175530 | controller | Downloading selinux_please_lie_to_me-1.0.1-py2.py3-none-any.whl (3.8 kB) 2025-11-26 00:11:05.520655 | controller | Collecting setuptools<50.0.0 2025-11-26 00:11:05.525992 | controller | Downloading setuptools-49.6.0-py3-none-any.whl (803 kB) 2025-11-26 00:11:05.581135 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 803.3/803.3 kB 15.9 MB/s eta 0:00:00 2025-11-26 00:11:05.656496 | controller | Installing collected packages: setuptools, selinux-please-lie-to-me 2025-11-26 00:11:05.656709 | controller | Attempting uninstall: setuptools 2025-11-26 00:11:05.657400 | controller | Found existing installation: setuptools 62.6.0 2025-11-26 00:11:05.728125 | controller | Uninstalling setuptools-62.6.0: 2025-11-26 00:11:05.736578 | controller | Successfully uninstalled setuptools-62.6.0 2025-11-26 00:11:06.066066 | controller | Successfully installed selinux-please-lie-to-me-1.0.1 setuptools-49.6.0 2025-11-26 00:11:17.558332 | controller | 2025-11-26 00:11:17.645445 | controller | [notice] A new release of pip available: 22.2.2 -> 25.3 2025-11-26 00:11:17.645476 | controller | [notice] To update, run: /home/zuul/venv/bin/python -m pip install --upgrade pip 2025-11-26 00:11:17.833594 | controller | ok: Runtime: 0:00:39.857315 2025-11-26 00:11:17.846194 | 2025-11-26 00:11:17.846330 | TASK [Install pytest-forked into virtualenv] 2025-11-26 00:11:28.698942 | controller | Collecting pytest-forked 2025-11-26 00:11:41.016102 | controller | Downloading pytest_forked-1.6.0-py3-none-any.whl (4.9 kB) 2025-11-26 00:11:41.158484 | controller | Collecting pytest>=3.10 2025-11-26 00:11:41.163950 | controller | Downloading pytest-8.4.2-py3-none-any.whl (365 kB) 2025-11-26 00:11:41.199460 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 365.8/365.8 kB 11.2 MB/s eta 0:00:00 2025-11-26 00:11:41.237883 | controller | Collecting py 2025-11-26 00:11:41.243092 | controller | Downloading py-1.11.0-py2.py3-none-any.whl (98 kB) 2025-11-26 00:11:41.252766 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 98.7/98.7 kB 12.6 MB/s eta 0:00:00 2025-11-26 00:11:41.305895 | controller | Collecting pluggy<2,>=1.5 2025-11-26 00:11:41.309240 | controller | Downloading pluggy-1.6.0-py3-none-any.whl (20 kB) 2025-11-26 00:11:41.356605 | controller | Collecting packaging>=20 2025-11-26 00:11:41.359906 | controller | Downloading packaging-25.0-py3-none-any.whl (66 kB) 2025-11-26 00:11:41.369240 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 66.5/66.5 kB 8.5 MB/s eta 0:00:00 2025-11-26 00:11:41.396323 | controller | Collecting iniconfig>=1 2025-11-26 00:11:41.399870 | controller | Downloading iniconfig-2.1.0-py3-none-any.whl (6.0 kB) 2025-11-26 00:11:41.450843 | controller | Collecting pygments>=2.7.2 2025-11-26 00:11:41.454317 | controller | Downloading pygments-2.19.2-py3-none-any.whl (1.2 MB) 2025-11-26 00:11:41.481188 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 1.2/1.2 MB 51.9 MB/s eta 0:00:00 2025-11-26 00:11:41.534384 | controller | Collecting tomli>=1 2025-11-26 00:11:41.537622 | controller | Downloading tomli-2.3.0-py3-none-any.whl (14 kB) 2025-11-26 00:11:41.584757 | controller | Collecting exceptiongroup>=1 2025-11-26 00:11:41.589199 | controller | Downloading exceptiongroup-1.3.1-py3-none-any.whl (16 kB) 2025-11-26 00:11:41.648977 | controller | Collecting typing-extensions>=4.6.0 2025-11-26 00:11:41.652650 | controller | Downloading typing_extensions-4.15.0-py3-none-any.whl (44 kB) 2025-11-26 00:11:41.659651 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 44.6/44.6 kB 7.5 MB/s eta 0:00:00 2025-11-26 00:11:41.729946 | controller | Installing collected packages: typing-extensions, tomli, pygments, py, pluggy, packaging, iniconfig, exceptiongroup, pytest, pytest-forked 2025-11-26 00:11:42.774416 | 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 2025-11-26 00:11:42.777645 | controller | 2025-11-26 00:11:42.865042 | controller | [notice] A new release of pip available: 22.2.2 -> 25.3 2025-11-26 00:11:42.865095 | controller | [notice] To update, run: /home/zuul/venv/bin/python -m pip install --upgrade pip 2025-11-26 00:11:42.916954 | controller | ok: Runtime: 0:00:24.792158 2025-11-26 00:11:42.928038 | 2025-11-26 00:11:42.928166 | TASK [Update pip] 2025-11-26 00:11:43.532345 | controller | Requirement already satisfied: pip in ./venv/lib/python3.9/site-packages (22.2.2) 2025-11-26 00:11:53.394699 | controller | Collecting pip 2025-11-26 00:12:53.499441 | controller | Downloading pip-25.3-py3-none-any.whl (1.8 MB) 2025-11-26 00:12:53.594072 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 1.8/1.8 MB 19.6 MB/s eta 0:00:00 2025-11-26 00:12:53.652407 | controller | Installing collected packages: pip 2025-11-26 00:12:53.652498 | controller | Attempting uninstall: pip 2025-11-26 00:12:53.653136 | controller | Found existing installation: pip 22.2.2 2025-11-26 00:12:53.818552 | controller | Uninstalling pip-22.2.2: 2025-11-26 00:12:53.835334 | controller | Successfully uninstalled pip-22.2.2 2025-11-26 00:12:54.613122 | controller | Successfully installed pip-25.3 2025-11-26 00:12:55.090801 | controller | ok: Runtime: 0:01:11.460702 2025-11-26 00:12:55.097175 | 2025-11-26 00:12:55.097252 | TASK [Ensure pip temp directory exists in /var/tmp] 2025-11-26 00:12:55.384999 | controller | changed 2025-11-26 00:12:55.390893 | 2025-11-26 00:12:55.390981 | TASK [Install ansible into virtualenv] 2025-11-26 00:12:55.984456 | controller | Processing ./src/github.com/ansible/ansible 2025-11-26 00:12:55.988851 | controller | Installing build dependencies: started 2025-11-26 00:13:21.283517 | controller | Installing build dependencies: finished with status 'done' 2025-11-26 00:13:21.284510 | controller | Getting requirements to build wheel: started 2025-11-26 00:13:21.922587 | controller | Getting requirements to build wheel: finished with status 'done' 2025-11-26 00:13:21.925223 | controller | Preparing metadata (pyproject.toml): started 2025-11-26 00:13:22.421485 | controller | Preparing metadata (pyproject.toml): finished with status 'done' 2025-11-26 00:13:33.243279 | controller | Collecting jinja2 (from ansible-core==2.11.12.post0) 2025-11-26 00:13:45.502631 | controller | Downloading jinja2-3.1.6-py3-none-any.whl.metadata (2.9 kB) 2025-11-26 00:13:45.578970 | controller | Collecting PyYAML (from ansible-core==2.11.12.post0) 2025-11-26 00:13:45.584716 | 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-11-26 00:13:45.880258 | controller | Collecting cryptography (from ansible-core==2.11.12.post0) 2025-11-26 00:13:45.885389 | controller | Downloading cryptography-46.0.3-cp38-abi3-manylinux_2_34_x86_64.whl.metadata (5.7 kB) 2025-11-26 00:13:45.901962 | controller | Requirement already satisfied: packaging in ./venv/lib/python3.9/site-packages (from ansible-core==2.11.12.post0) (25.0) 2025-11-26 00:13:45.918615 | controller | Collecting resolvelib<0.6.0,>=0.5.3 (from ansible-core==2.11.12.post0) 2025-11-26 00:13:45.923896 | controller | Downloading resolvelib-0.5.4-py2.py3-none-any.whl.metadata (3.7 kB) 2025-11-26 00:13:46.089756 | controller | Collecting cffi>=2.0.0 (from cryptography->ansible-core==2.11.12.post0) 2025-11-26 00:13:46.094819 | controller | Downloading cffi-2.0.0-cp39-cp39-manylinux2014_x86_64.manylinux_2_17_x86_64.whl.metadata (2.6 kB) 2025-11-26 00:13:46.101712 | 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-11-26 00:13:46.117496 | controller | Collecting pycparser (from cffi>=2.0.0->cryptography->ansible-core==2.11.12.post0) 2025-11-26 00:13:46.121575 | controller | Downloading pycparser-2.23-py3-none-any.whl.metadata (993 bytes) 2025-11-26 00:13:46.206498 | controller | Collecting MarkupSafe>=2.0 (from jinja2->ansible-core==2.11.12.post0) 2025-11-26 00:13:46.211926 | 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-11-26 00:13:46.226494 | controller | Downloading resolvelib-0.5.4-py2.py3-none-any.whl (12 kB) 2025-11-26 00:13:46.243865 | controller | Downloading cryptography-46.0.3-cp38-abi3-manylinux_2_34_x86_64.whl (4.5 MB) 2025-11-26 00:13:46.321668 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 4.5/4.5 MB 65.8 MB/s 0:00:00 2025-11-26 00:13:46.328941 | controller | Downloading cffi-2.0.0-cp39-cp39-manylinux2014_x86_64.manylinux_2_17_x86_64.whl (216 kB) 2025-11-26 00:13:46.340642 | controller | Downloading jinja2-3.1.6-py3-none-any.whl (134 kB) 2025-11-26 00:13:46.352836 | 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-11-26 00:13:46.361363 | controller | Downloading pycparser-2.23-py3-none-any.whl (118 kB) 2025-11-26 00:13:46.373473 | 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-11-26 00:13:46.415245 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 750.8/750.8 kB 13.4 MB/s 0:00:00 2025-11-26 00:13:46.431448 | controller | Building wheels for collected packages: ansible-core 2025-11-26 00:13:46.432303 | controller | Building wheel for ansible-core (pyproject.toml): started 2025-11-26 00:13:47.948172 | controller | Building wheel for ansible-core (pyproject.toml): finished with status 'done' 2025-11-26 00:13:47.953452 | controller | Created wheel for ansible-core: filename=ansible_core-2.11.12.post0-py3-none-any.whl size=1961250 sha256=0d20da015c11aa9f1a0758bf76567b5f40bca0a963a634b506321cf41aa95945 2025-11-26 00:13:47.953746 | controller | Stored in directory: /var/tmp/ansible-test-pip/pip-ephem-wheel-cache-qcjqedy1/wheels/0f/a0/93/ab85b739e1eb1e0467171316f13571c14efdf87e0d5d44a33e 2025-11-26 00:13:47.965006 | controller | Successfully built ansible-core 2025-11-26 00:13:48.002056 | controller | Installing collected packages: resolvelib, PyYAML, pycparser, MarkupSafe, jinja2, cffi, cryptography, ansible-core 2025-11-26 00:13:49.492749 | controller | 2025-11-26 00:13:49.496988 | 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 2025-11-26 00:13:49.980849 | controller | ok: Runtime: 0:00:53.935994 2025-11-26 00:13:49.987243 | 2025-11-26 00:13:49.987316 | TASK [Setup download-artifact-fork role] 2025-11-26 00:13:50.009123 | controller | ok 2025-11-26 00:13:50.028061 | 2025-11-26 00:13:50.028145 | TASK [download-artifact-fork : Parse inventory response] 2025-11-26 00:13:50.057666 | controller | ok 2025-11-26 00:13:50.064569 | 2025-11-26 00:13:50.064639 | TASK [download-artifact-fork : Fetch info from Zuul API] 2025-11-26 00:13:50.088641 | controller | skipping: Conditional result was False 2025-11-26 00:13:50.096326 | 2025-11-26 00:13:50.096425 | TASK [download-artifact-fork : Parse build response] 2025-11-26 00:13:50.120599 | controller | skipping: Conditional result was False 2025-11-26 00:13:50.127879 | 2025-11-26 00:13:50.127954 | TASK [download-artifact-fork : Ensure artifacts directory exists] 2025-11-26 00:13:50.333930 | controller | changed 2025-11-26 00:13:50.341578 | 2025-11-26 00:13:50.341644 | LOOP [download-artifact-fork : Download archive by type] 2025-11-26 00:13:50.382147 | controller | skipping: Conditional result was False 2025-11-26 00:13:50.382396 | 2025-11-26 00:13:51.142308 | controller | changed: OK (20213 bytes) 2025-11-26 00:13:51.161701 | 2025-11-26 00:13:51.161815 | TASK [deploy-artifacts : define __collections] 2025-11-26 00:13:51.181573 | controller | ok 2025-11-26 00:13:51.186811 | 2025-11-26 00:13:51.186873 | LOOP [deploy-artifacts : Create list of collections] 2025-11-26 00:13:51.210795 | controller | skipping: Conditional result was False 2025-11-26 00:13:51.219922 | controller | ok: 2025-11-26 00:13:51.219991 | controller | { 2025-11-26 00:13:51.220013 | controller | "branch": "main", 2025-11-26 00:13:51.220031 | controller | "job": "build-ansible-collection", 2025-11-26 00:13:51.220047 | controller | "metadata": { 2025-11-26 00:13:51.220062 | controller | "type": "ansible_collection", 2025-11-26 00:13:51.220078 | controller | "version": "5.0.0" 2025-11-26 00:13:51.220093 | controller | }, 2025-11-26 00:13:51.220108 | controller | "name": "ansible.network", 2025-11-26 00:13:51.220122 | controller | "project": "ansible-collections/ansible.network", 2025-11-26 00:13:51.220138 | controller | "url": "https://74fbf45fa98e7d8acc44-5223fe1329cffb807c0143d82714e45a.ssl.cf5.rackcdn.com/ansible/d8839acc6cfe4d2a9b9f2a8499215075/artifacts/ansible-network-5.0.0.tar.gz" 2025-11-26 00:13:51.220159 | controller | } 2025-11-26 00:13:51.229671 | 2025-11-26 00:13:51.229847 | TASK [deploy-artifacts : Install require-project collection using ansible-galaxy] 2025-11-26 00:13:51.846799 | controller | Starting galaxy collection install process 2025-11-26 00:13:51.847017 | controller | Process install dependency map 2025-11-26 00:14:44.254775 | controller | [WARNING]: Skipping Galaxy server https://galaxy.ansible.com/api/. Got an 2025-11-26 00:14:44.256062 | controller | unexpected error when getting available versions of collection 2025-11-26 00:14:44.256116 | controller | ansible.netcommon: '/api/v3/plugin/ansible/content/published/collections/index/ 2025-11-26 00:14:44.256129 | controller | ansible/netcommon/versions/' 2025-11-26 00:14:44.256147 | controller | ERROR! Unexpected Exception, this is probably a bug: '/api/v3/plugin/ansible/content/published/collections/index/ansible/netcommon/versions/' 2025-11-26 00:14:44.256242 | controller | to see the full traceback, use -vvv 2025-11-26 00:14:44.790626 | controller | ERROR 2025-11-26 00:14:44.791021 | controller | { 2025-11-26 00:14:44.791086 | controller | "delta": "0:00:52.886876", 2025-11-26 00:14:44.791126 | controller | "end": "2025-11-26 00:14:44.322912", 2025-11-26 00:14:44.791162 | controller | "msg": "non-zero return code", 2025-11-26 00:14:44.791196 | controller | "rc": 250, 2025-11-26 00:14:44.791230 | controller | "start": "2025-11-26 00:13:51.436036" 2025-11-26 00:14:44.791262 | controller | } failure 2025-11-26 00:14:44.797365 | 2025-11-26 00:14:44.797463 | PLAY RECAP 2025-11-26 00:14:44.797544 | controller | ok: 44 changed: 24 unreachable: 0 failed: 1 skipped: 17 rescued: 0 ignored: 0 2025-11-26 00:14:44.797588 | 2025-11-26 00:14:44.950080 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master] 2025-11-26 00:14:44.951102 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2025-11-26 00:14:45.536849 | 2025-11-26 00:14:45.536971 | PLAY [all] 2025-11-26 00:14:45.559130 | 2025-11-26 00:14:45.559215 | TASK [fetch-subunit-output : Find stestr or testr executable] 2025-11-26 00:14:46.052010 | controller | changed: non-zero return code 2025-11-26 00:14:46.058143 | 2025-11-26 00:14:46.058235 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2025-11-26 00:14:46.082233 | controller | skipping: Conditional result was False 2025-11-26 00:14:46.088535 | 2025-11-26 00:14:46.088610 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2025-11-26 00:14:46.119797 | 2025-11-26 00:14:46.119939 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2025-11-26 00:14:46.150835 | 2025-11-26 00:14:46.151001 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2025-11-26 00:14:46.175421 | controller | skipping: Conditional result was False 2025-11-26 00:14:46.183265 | 2025-11-26 00:14:46.183341 | LOOP [fetch-subunit-output : Generate subunit file] 2025-11-26 00:14:46.225095 | 2025-11-26 00:14:46.225264 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2025-11-26 00:14:46.239543 | controller | skipping: Conditional result was False 2025-11-26 00:14:46.246813 | 2025-11-26 00:14:46.246898 | TASK [fetch-subunit-output : Remove the temporary file] 2025-11-26 00:14:46.271192 | controller | skipping: Conditional result was False 2025-11-26 00:14:46.278312 | 2025-11-26 00:14:46.278387 | TASK [fetch-subunit-output : Process and fetch subunit results] 2025-11-26 00:14:46.304265 | controller | skipping: Conditional result was False 2025-11-26 00:14:46.339624 | 2025-11-26 00:14:46.339778 | PLAY RECAP 2025-11-26 00:14:46.339843 | controller | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2025-11-26 00:14:46.339872 | 2025-11-26 00:14:46.477590 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2025-11-26 00:14:46.478797 | POST-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/post.yaml@master] 2025-11-26 00:14:47.075353 | 2025-11-26 00:14:47.075637 | PLAY [all:!appliance*] 2025-11-26 00:14:47.109667 | 2025-11-26 00:14:47.119899 | TASK [unregister the node] 2025-11-26 00:14:47.653629 | controller | skipping: Conditional result was False 2025-11-26 00:14:47.660504 | 2025-11-26 00:14:47.660590 | TASK [include_role : fetch-output] 2025-11-26 00:14:47.691582 | controller | ok 2025-11-26 00:14:47.712450 | 2025-11-26 00:14:47.712535 | TASK [fetch-output : Set log path for multiple nodes] 2025-11-26 00:14:47.758708 | controller | skipping: Conditional result was False 2025-11-26 00:14:47.766379 | 2025-11-26 00:14:47.766470 | TASK [fetch-output : Set log path for single node] 2025-11-26 00:14:47.810455 | controller | ok 2025-11-26 00:14:47.816039 | 2025-11-26 00:14:47.816106 | LOOP [fetch-output : Ensure local output dirs] 2025-11-26 00:14:48.226196 | controller -> localhost | ok: "/var/lib/zuul/builds/7c7f8a610fa14fc7988212631b740d0b/work/logs" 2025-11-26 00:14:48.440319 | controller -> localhost | changed: "/var/lib/zuul/builds/7c7f8a610fa14fc7988212631b740d0b/work/artifacts" 2025-11-26 00:14:48.661535 | controller -> localhost | changed: "/var/lib/zuul/builds/7c7f8a610fa14fc7988212631b740d0b/work/docs" 2025-11-26 00:14:48.672615 | 2025-11-26 00:14:48.672764 | LOOP [fetch-output : Collect logs, artifacts and docs] 2025-11-26 00:14:49.382850 | controller | changed: 2025-11-26 00:14:49.383225 | controller | .d..t...... ./ 2025-11-26 00:14:49.383287 | controller | cd+++++++++ controller/ 2025-11-26 00:14:49.383370 | controller | changed: All items complete 2025-11-26 00:14:49.383416 | 2025-11-26 00:14:49.981849 | controller | changed: .d..t...... ./ 2025-11-26 00:14:50.557352 | controller | changed: .d..t...... ./ 2025-11-26 00:14:50.575176 | 2025-11-26 00:14:50.575270 | TASK [include_role : fetch-output-openshift] 2025-11-26 00:14:50.604434 | controller | skipping: Conditional result was False 2025-11-26 00:14:50.612921 | 2025-11-26 00:14:50.612997 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2025-11-26 00:14:50.662445 | controller | skipping: Conditional result was False 2025-11-26 00:14:50.675971 | controller | skipping: Conditional result was False 2025-11-26 00:14:50.732716 | 2025-11-26 00:14:50.732897 | PLAY [localhost] 2025-11-26 00:14:50.750302 | 2025-11-26 00:14:50.750391 | TASK [Run Zuul manifest role] 2025-11-26 00:14:50.774025 | localhost | ok 2025-11-26 00:14:50.795219 | 2025-11-26 00:14:50.795364 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-11-26 00:14:51.242024 | localhost | changed 2025-11-26 00:14:51.254704 | 2025-11-26 00:14:51.254890 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-11-26 00:14:51.290275 | localhost | ok 2025-11-26 00:14:51.309207 | 2025-11-26 00:14:51.309448 | TASK [Set zuul-log-path fact] 2025-11-26 00:14:51.332897 | localhost | ok 2025-11-26 00:14:51.356261 | 2025-11-26 00:14:51.356462 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-11-26 00:14:51.389042 | localhost | ok 2025-11-26 00:14:51.401168 | 2025-11-26 00:14:51.401250 | LOOP [Run upload-logs-swift role] 2025-11-26 00:14:51.447931 | localhost | Output suppressed because no_log was given 2025-11-26 00:14:51.483370 | 2025-11-26 00:14:51.483460 | TASK [Set zuul-log-path fact] 2025-11-26 00:14:51.519960 | localhost | skipping: Conditional result was False 2025-11-26 00:14:51.526851 | 2025-11-26 00:14:51.526939 | TASK [upload-logs-swift : Ensure logs are readable before uploading] 2025-11-26 00:14:52.077474 | localhost -> localhost | ok: Runtime: 0:00:00.009901 2025-11-26 00:14:52.133959 | 2025-11-26 00:14:52.134140 | TASK [upload-logs-swift : Upload logs to swift]