2025-09-18 00:17:29.495984 | Job console starting... 2025-09-18 00:17:29.508156 | Updating repositories 2025-09-18 00:17:29.647427 | Preparing job workspace 2025-09-18 00:17:33.271416 | Running Ansible setup... 2025-09-18 00:17:41.090885 | PRE-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master] 2025-09-18 00:17:41.731386 | 2025-09-18 00:17:41.731527 | PLAY [localhost] 2025-09-18 00:17:41.740895 | 2025-09-18 00:17:41.741012 | TASK [Gathering Facts] 2025-09-18 00:17:42.737871 | localhost | ok 2025-09-18 00:17:42.757979 | 2025-09-18 00:17:42.758105 | TASK [Setup log path fact] 2025-09-18 00:17:42.778887 | localhost | ok 2025-09-18 00:17:42.798824 | 2025-09-18 00:17:42.798969 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-09-18 00:17:42.830674 | localhost | ok 2025-09-18 00:17:42.843596 | 2025-09-18 00:17:42.843773 | TASK [emit-job-header : Print job information] 2025-09-18 00:17:42.886569 | # Job Information 2025-09-18 00:17:42.886833 | Ansible Version: 2.15.12 2025-09-18 00:17:42.886900 | Job: ansible-test-sanity-docker-stable-2.11 2025-09-18 00:17:42.886938 | Pipeline: periodic 2025-09-18 00:17:42.886967 | Executor: ze03.softwarefactory-project.io 2025-09-18 00:17:42.886994 | Triggered by: https://github.com/ansible-collections/ansible.network 2025-09-18 00:17:42.887025 | Log URL (when completed): https://ansible.softwarefactory-project.io/logs/67e/ansible/67e7470b5317453d81d57e3a3069db89/ 2025-09-18 00:17:42.887055 | Event ID: 570725ce3f614176acf25441c589d88f 2025-09-18 00:17:42.893491 | 2025-09-18 00:17:42.893602 | LOOP [emit-job-header : Print node information] 2025-09-18 00:17:43.027575 | localhost | ok: 2025-09-18 00:17:43.027856 | localhost | # Node Information 2025-09-18 00:17:43.027899 | localhost | Inventory Hostname: controller 2025-09-18 00:17:43.027930 | localhost | Hostname: np0005438473 2025-09-18 00:17:43.027960 | localhost | Username: zuul 2025-09-18 00:17:43.027997 | localhost | Distro: Fedora 37 2025-09-18 00:17:43.028028 | localhost | Provider: ansible-vexxhost-ams1 2025-09-18 00:17:43.028057 | localhost | Region: ams1 2025-09-18 00:17:43.028084 | localhost | Label: ansible-fedora-37-1vcpu 2025-09-18 00:17:43.028111 | localhost | Product Name: OpenStack Nova 2025-09-18 00:17:43.028138 | localhost | Interface IP: 38.129.16.97 2025-09-18 00:17:43.043963 | 2025-09-18 00:17:43.044128 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-09-18 00:17:43.554935 | localhost -> localhost | changed 2025-09-18 00:17:43.560705 | 2025-09-18 00:17:43.560846 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-09-18 00:17:44.494020 | localhost -> localhost | changed 2025-09-18 00:17:44.516277 | 2025-09-18 00:17:44.516400 | PLAY [all:!appliance*] 2025-09-18 00:17:44.533901 | 2025-09-18 00:17:44.534019 | TASK [include_role : start-zuul-console] 2025-09-18 00:17:44.552889 | controller | ok 2025-09-18 00:17:44.568535 | 2025-09-18 00:17:44.568649 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-09-18 00:17:45.539403 | controller | ok 2025-09-18 00:17:45.565858 | 2025-09-18 00:17:45.566048 | TASK [use-our-mirror : Retrieve the IP address] 2025-09-18 00:17:47.635380 | controller | ok: Status code was 404 and not [200]: HTTP Error 404: Not Found 2025-09-18 00:17:47.651808 | 2025-09-18 00:17:47.652078 | TASK [use-our-mirror : Ensure the registry service is running on the mirror] 2025-09-18 00:17:48.027116 | controller | skipping: Conditional result was False 2025-09-18 00:17:48.034062 | 2025-09-18 00:17:48.034150 | TASK [use-our-mirror : Write the IP in /etc/hosts] 2025-09-18 00:17:48.058308 | controller | skipping: Conditional result was False 2025-09-18 00:17:48.072546 | 2025-09-18 00:17:48.072771 | TASK [use-our-mirror : Create the podman configuration directory] 2025-09-18 00:17:48.099461 | controller | skipping: Conditional result was False 2025-09-18 00:17:48.112413 | 2025-09-18 00:17:48.112591 | TASK [use-our-mirror : Copy the podman configuration] 2025-09-18 00:17:48.129604 | controller | skipping: Conditional result was False 2025-09-18 00:17:48.145866 | 2025-09-18 00:17:48.146070 | TASK [use-our-mirror : Copy the Yum configuration (main)] 2025-09-18 00:17:48.164142 | controller | skipping: Conditional result was False 2025-09-18 00:17:48.177248 | 2025-09-18 00:17:48.177420 | TASK [use-our-mirror : Copy the Yum configuration (update)] 2025-09-18 00:17:48.193819 | controller | skipping: Conditional result was False 2025-09-18 00:17:48.207564 | 2025-09-18 00:17:48.207694 | TASK [Disable Fedora Modular] 2025-09-18 00:17:49.066400 | controller | changed 2025-09-18 00:17:49.075631 | 2025-09-18 00:17:49.075814 | TASK [Enable EPEL] 2025-09-18 00:17:49.101130 | controller | skipping: Conditional result was False 2025-09-18 00:17:49.108418 | 2025-09-18 00:17:49.108523 | TASK [Register the RHEL node] 2025-09-18 00:17:49.513613 | 2025-09-18 00:17:49.513802 | TASK [Show the subscription-manager status] 2025-09-18 00:17:49.882003 | controller | skipping: Conditional result was False 2025-09-18 00:17:49.896046 | 2025-09-18 00:17:49.896204 | TASK [Enable EPEL on RHEL] 2025-09-18 00:17:50.272536 | controller | skipping: Conditional result was False 2025-09-18 00:17:50.280003 | 2025-09-18 00:17:50.280119 | TASK [Install git and tox] 2025-09-18 00:20:23.281893 | controller | changed 2025-09-18 00:20:23.288988 | 2025-09-18 00:20:23.289059 | TASK [include_role : prepare-workspace] 2025-09-18 00:20:23.309214 | controller | ok 2025-09-18 00:20:23.329843 | 2025-09-18 00:20:23.329961 | TASK [prepare-workspace : Start zuul_console daemon.] 2025-09-18 00:20:24.133363 | controller | ok 2025-09-18 00:20:24.142230 | 2025-09-18 00:20:24.142362 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2025-09-18 00:20:39.607448 | controller | Output suppressed because no_log was given 2025-09-18 00:20:39.618760 | 2025-09-18 00:20:39.618873 | TASK [include_role : prepare-workspace-openshift] 2025-09-18 00:20:39.635766 | controller | skipping: Conditional result was False 2025-09-18 00:20:39.660360 | 2025-09-18 00:20:39.660458 | PLAY [all:!appliance] 2025-09-18 00:20:39.678120 | 2025-09-18 00:20:39.678241 | TASK [Run add-build-sshkey role (RSA)] 2025-09-18 00:20:39.698576 | controller | ok 2025-09-18 00:20:39.715083 | 2025-09-18 00:20:39.715201 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-09-18 00:20:39.953957 | controller -> localhost | ok 2025-09-18 00:20:39.967147 | 2025-09-18 00:20:39.967302 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-09-18 00:20:39.992799 | controller | ok 2025-09-18 00:20:40.011032 | controller | included: /var/lib/zuul/builds/67e7470b5317453d81d57e3a3069db89/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-09-18 00:20:40.019349 | 2025-09-18 00:20:40.019438 | TASK [add-build-sshkey : Create Temp SSH key] 2025-09-18 00:20:40.504564 | controller -> localhost | Generating public/private rsa key pair. 2025-09-18 00:20:40.504773 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/67e7470b5317453d81d57e3a3069db89/work/67e7470b5317453d81d57e3a3069db89_id_rsa. 2025-09-18 00:20:40.504809 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/67e7470b5317453d81d57e3a3069db89/work/67e7470b5317453d81d57e3a3069db89_id_rsa.pub. 2025-09-18 00:20:40.504832 | controller -> localhost | The key fingerprint is: 2025-09-18 00:20:40.504852 | controller -> localhost | SHA256:q+z7nYwqCkZzasgf4GWaPY1v1EYXyFk4edaIH180cgQ zuul-build-sshkey 2025-09-18 00:20:40.504870 | controller -> localhost | The key's randomart image is: 2025-09-18 00:20:40.504888 | controller -> localhost | +---[RSA 2048]----+ 2025-09-18 00:20:40.504906 | controller -> localhost | | . B.oE+= | 2025-09-18 00:20:40.504925 | controller -> localhost | | O * .o.. | 2025-09-18 00:20:40.504942 | controller -> localhost | | = + . | 2025-09-18 00:20:40.504960 | controller -> localhost | | . o . | 2025-09-18 00:20:40.504977 | controller -> localhost | | + + o S | 2025-09-18 00:20:40.505007 | controller -> localhost | |= @ o. o . | 2025-09-18 00:20:40.505030 | controller -> localhost | |oO =... . | 2025-09-18 00:20:40.505049 | controller -> localhost | |o.. =o . + . | 2025-09-18 00:20:40.505068 | controller -> localhost | | .o.+*+o + | 2025-09-18 00:20:40.505089 | controller -> localhost | +----[SHA256]-----+ 2025-09-18 00:20:40.505132 | controller -> localhost | ok: Runtime: 0:00:00.102094 2025-09-18 00:20:40.512642 | 2025-09-18 00:20:40.512718 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-09-18 00:20:40.538684 | controller | ok 2025-09-18 00:20:40.553313 | controller | included: /var/lib/zuul/builds/67e7470b5317453d81d57e3a3069db89/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-09-18 00:20:40.565394 | 2025-09-18 00:20:40.565499 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-09-18 00:20:40.590691 | controller | skipping: Conditional result was False 2025-09-18 00:20:40.597615 | 2025-09-18 00:20:40.597756 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-09-18 00:20:41.664380 | controller | changed 2025-09-18 00:20:41.672685 | 2025-09-18 00:20:41.672856 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-09-18 00:20:42.330111 | controller | ok 2025-09-18 00:20:42.336343 | 2025-09-18 00:20:42.336456 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-09-18 00:20:45.311301 | controller | changed 2025-09-18 00:20:45.317423 | 2025-09-18 00:20:45.317520 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-09-18 00:20:48.344361 | controller | changed 2025-09-18 00:20:48.350395 | 2025-09-18 00:20:48.350486 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-09-18 00:20:48.364856 | controller | skipping: Conditional result was False 2025-09-18 00:20:48.373062 | 2025-09-18 00:20:48.373200 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-09-18 00:20:48.755306 | controller -> localhost | changed 2025-09-18 00:20:48.767062 | 2025-09-18 00:20:48.767165 | TASK [add-build-sshkey : Add back temp key] 2025-09-18 00:20:49.058834 | controller -> localhost | Identity added: /var/lib/zuul/builds/67e7470b5317453d81d57e3a3069db89/work/67e7470b5317453d81d57e3a3069db89_id_rsa (zuul-build-sshkey) 2025-09-18 00:20:49.059040 | controller -> localhost | ok: Runtime: 0:00:00.010832 2025-09-18 00:20:49.065409 | 2025-09-18 00:20:49.065487 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-09-18 00:20:49.984196 | controller | ok 2025-09-18 00:20:49.992238 | 2025-09-18 00:20:49.992359 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-09-18 00:20:50.028956 | controller | skipping: Conditional result was False 2025-09-18 00:20:50.050563 | 2025-09-18 00:20:50.050708 | TASK [Run add-build-sshkey role (ECDSA)] 2025-09-18 00:20:50.084194 | controller | ok 2025-09-18 00:20:50.111564 | 2025-09-18 00:20:50.111694 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-09-18 00:20:50.350853 | controller -> localhost | ok 2025-09-18 00:20:50.358702 | 2025-09-18 00:20:50.358851 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-09-18 00:20:50.398681 | controller | ok 2025-09-18 00:20:50.421126 | controller | included: /var/lib/zuul/builds/67e7470b5317453d81d57e3a3069db89/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-09-18 00:20:50.430505 | 2025-09-18 00:20:50.430589 | TASK [add-build-sshkey : Create Temp SSH key] 2025-09-18 00:20:50.755368 | controller -> localhost | Generating public/private ecdsa key pair. 2025-09-18 00:20:50.755615 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/67e7470b5317453d81d57e3a3069db89/work/67e7470b5317453d81d57e3a3069db89_id_ecdsa. 2025-09-18 00:20:50.755648 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/67e7470b5317453d81d57e3a3069db89/work/67e7470b5317453d81d57e3a3069db89_id_ecdsa.pub. 2025-09-18 00:20:50.755680 | controller -> localhost | The key fingerprint is: 2025-09-18 00:20:50.755703 | controller -> localhost | SHA256:UK8E8P1Pje7WOTkc01XtqyNZk7JVtiodfG5O7Ebf+3c zuul-build-sshkey 2025-09-18 00:20:50.755729 | controller -> localhost | The key's randomart image is: 2025-09-18 00:20:50.755783 | controller -> localhost | +---[ECDSA 521]---+ 2025-09-18 00:20:50.755808 | controller -> localhost | | ... . .| 2025-09-18 00:20:50.755830 | controller -> localhost | | . + . o| 2025-09-18 00:20:50.755851 | controller -> localhost | | o o . ..| 2025-09-18 00:20:50.755871 | controller -> localhost | | o o o oo| 2025-09-18 00:20:50.755891 | controller -> localhost | | S . + =.+| 2025-09-18 00:20:50.755912 | controller -> localhost | | = B+=.| 2025-09-18 00:20:50.755933 | controller -> localhost | | XoOBo| 2025-09-18 00:20:50.755953 | controller -> localhost | | *.=X+E| 2025-09-18 00:20:50.755974 | controller -> localhost | | .+ =**| 2025-09-18 00:20:50.755993 | controller -> localhost | +----[SHA256]-----+ 2025-09-18 00:20:50.756050 | controller -> localhost | ok: Runtime: 0:00:00.013737 2025-09-18 00:20:50.763633 | 2025-09-18 00:20:50.763768 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-09-18 00:20:50.786590 | controller | ok 2025-09-18 00:20:50.797928 | controller | included: /var/lib/zuul/builds/67e7470b5317453d81d57e3a3069db89/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-09-18 00:20:50.812227 | 2025-09-18 00:20:50.812357 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-09-18 00:20:50.826685 | controller | skipping: Conditional result was False 2025-09-18 00:20:50.834125 | 2025-09-18 00:20:50.834232 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-09-18 00:20:51.774999 | controller | changed 2025-09-18 00:20:51.782232 | 2025-09-18 00:20:51.782364 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-09-18 00:20:52.452168 | controller | ok 2025-09-18 00:20:52.459730 | 2025-09-18 00:20:52.459987 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-09-18 00:20:55.511851 | controller | changed 2025-09-18 00:20:55.521102 | 2025-09-18 00:20:55.521274 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-09-18 00:20:58.453198 | controller | changed 2025-09-18 00:20:58.459572 | 2025-09-18 00:20:58.459652 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-09-18 00:20:58.473789 | controller | skipping: Conditional result was False 2025-09-18 00:20:58.490280 | 2025-09-18 00:20:58.490630 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-09-18 00:20:58.751656 | controller -> localhost | changed 2025-09-18 00:20:58.763978 | 2025-09-18 00:20:58.764096 | TASK [add-build-sshkey : Add back temp key] 2025-09-18 00:20:59.033112 | controller -> localhost | Identity added: /var/lib/zuul/builds/67e7470b5317453d81d57e3a3069db89/work/67e7470b5317453d81d57e3a3069db89_id_ecdsa (zuul-build-sshkey) 2025-09-18 00:20:59.033308 | controller -> localhost | ok: Runtime: 0:00:00.012576 2025-09-18 00:20:59.040233 | 2025-09-18 00:20:59.040300 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-09-18 00:20:59.716617 | controller | ok 2025-09-18 00:20:59.728156 | 2025-09-18 00:20:59.728351 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-09-18 00:20:59.754787 | controller | skipping: Conditional result was False 2025-09-18 00:20:59.772661 | 2025-09-18 00:20:59.772833 | TASK [include_role : remove-zuul-sshkey] 2025-09-18 00:20:59.788245 | controller | skipping: Conditional result was False 2025-09-18 00:20:59.798558 | 2025-09-18 00:20:59.798778 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2025-09-18 00:21:00.429217 | controller | ok: "logs" 2025-09-18 00:21:00.429526 | controller | ok: All items complete 2025-09-18 00:21:00.429567 | 2025-09-18 00:21:01.032763 | controller | ok: "artifacts" 2025-09-18 00:21:01.631763 | controller | ok: "docs" 2025-09-18 00:21:01.651681 | 2025-09-18 00:21:01.651930 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2025-09-18 00:21:02.283163 | controller | changed: "logs" 2025-09-18 00:21:02.880100 | controller | changed: "artifacts" 2025-09-18 00:21:04.119920 | controller | changed: "docs" 2025-09-18 00:21:04.170808 | 2025-09-18 00:21:04.170943 | PLAY RECAP 2025-09-18 00:21:04.171011 | controller | ok: 30 changed: 16 unreachable: 0 failed: 0 skipped: 18 rescued: 0 ignored: 0 2025-09-18 00:21:04.171052 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-09-18 00:21:04.171080 | 2025-09-18 00:21:04.302258 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master] 2025-09-18 00:21:04.303150 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2025-09-18 00:21:04.926393 | 2025-09-18 00:21:04.926529 | PLAY [all] 2025-09-18 00:21:04.955012 | 2025-09-18 00:21:04.955163 | TASK [Install binary dependencies] 2025-09-18 00:21:05.037721 | controller | ok 2025-09-18 00:21:05.058666 | 2025-09-18 00:21:05.058834 | TASK [bindep : Include find tasks] 2025-09-18 00:21:05.089374 | controller | ok 2025-09-18 00:21:05.097389 | controller | included: /var/lib/zuul/builds/67e7470b5317453d81d57e3a3069db89/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find.yaml 2025-09-18 00:21:05.103583 | 2025-09-18 00:21:05.103663 | TASK [bindep : Look for bindep.txt] 2025-09-18 00:21:05.894668 | controller | ok 2025-09-18 00:21:05.902935 | 2025-09-18 00:21:05.903081 | TASK [bindep : Define bindep_file fact] 2025-09-18 00:21:05.929238 | controller | skipping: Conditional result was False 2025-09-18 00:21:05.938853 | 2025-09-18 00:21:05.938991 | TASK [bindep : Look for other-requirements.txt] 2025-09-18 00:21:06.489381 | controller | ok 2025-09-18 00:21:06.495960 | 2025-09-18 00:21:06.496088 | TASK [bindep : Define bindep_file fact] 2025-09-18 00:21:06.520633 | controller | skipping: Conditional result was False 2025-09-18 00:21:06.532172 | 2025-09-18 00:21:06.532332 | TASK [bindep : Look for bindep fallback file] 2025-09-18 00:21:06.557113 | controller | skipping: Conditional result was False 2025-09-18 00:21:06.564305 | 2025-09-18 00:21:06.564415 | TASK [bindep : Define bindep_file fact] 2025-09-18 00:21:06.588880 | controller | skipping: Conditional result was False 2025-09-18 00:21:06.595675 | 2025-09-18 00:21:06.595783 | TASK [bindep : Include bindep tasks] 2025-09-18 00:21:06.620525 | controller | skipping: Conditional result was False 2025-09-18 00:21:06.627780 | 2025-09-18 00:21:06.627892 | TASK [bindep : Include install tasks] 2025-09-18 00:21:06.652228 | controller | skipping: Conditional result was False 2025-09-18 00:21:06.658416 | 2025-09-18 00:21:06.658534 | LOOP [bindep : Include package tasks] 2025-09-18 00:21:06.715414 | 2025-09-18 00:21:06.715580 | TASK [Run test-setup role] 2025-09-18 00:21:06.736909 | controller | ok 2025-09-18 00:21:06.761688 | 2025-09-18 00:21:06.761860 | TASK [test-setup : Check if project's tools/test-setup.sh exists] 2025-09-18 00:21:07.377958 | controller | ok 2025-09-18 00:21:07.385772 | 2025-09-18 00:21:07.385904 | TASK [test-setup : Run tools/test-setup.sh] 2025-09-18 00:21:07.744220 | controller | skipping: Conditional result was False 2025-09-18 00:21:07.771673 | 2025-09-18 00:21:07.771795 | PLAY RECAP 2025-09-18 00:21:07.771837 | controller | ok: 4 changed: 0 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2025-09-18 00:21:07.771857 | 2025-09-18 00:21:07.888509 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2025-09-18 00:21:07.889376 | PRE-RUN START: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master] 2025-09-18 00:21:08.526907 | 2025-09-18 00:21:08.527055 | PLAY [controller] 2025-09-18 00:21:08.549789 | 2025-09-18 00:21:08.549930 | TASK [Create the /root directory] 2025-09-18 00:21:09.595089 | controller | ok 2025-09-18 00:21:09.601769 | 2025-09-18 00:21:09.601867 | TASK [Install glibc-langpack-en] 2025-09-18 00:21:16.961251 | controller | ok: Nothing to do 2025-09-18 00:21:16.969577 | 2025-09-18 00:21:16.969686 | TASK [Ensure controller directory exists] 2025-09-18 00:21:17.778030 | controller | changed 2025-09-18 00:21:17.786187 | 2025-09-18 00:21:17.786289 | TASK [Install container runtime] 2025-09-18 00:21:17.830766 | controller | ok 2025-09-18 00:21:17.884265 | 2025-09-18 00:21:17.884411 | LOOP [ensure-docker : Gather variables for each operating system] 2025-09-18 00:21:17.928449 | controller | ok: "/var/lib/zuul/builds/67e7470b5317453d81d57e3a3069db89/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/vars/fedora.yaml" 2025-09-18 00:21:17.940647 | 2025-09-18 00:21:17.940831 | TASK [ensure-docker : Sanity check for distro selection] 2025-09-18 00:21:17.958833 | controller | skipping: Conditional result was False 2025-09-18 00:21:17.968078 | 2025-09-18 00:21:17.968270 | TASK [ensure-docker : Sanity check for distro install] 2025-09-18 00:21:17.993367 | controller | skipping: Conditional result was False 2025-09-18 00:21:17.999880 | 2025-09-18 00:21:18.000003 | TASK [ensure-docker : Sanity check for upstream install] 2025-09-18 00:21:18.024616 | controller | skipping: Conditional result was False 2025-09-18 00:21:18.035003 | 2025-09-18 00:21:18.035164 | TASK [ensure-docker : Sanity check for upstream install mirrors] 2025-09-18 00:21:18.061690 | controller | skipping: Conditional result was False 2025-09-18 00:21:18.068070 | 2025-09-18 00:21:18.068151 | TASK [ensure-docker : Include tasks for docker install] 2025-09-18 00:21:18.091041 | controller | ok 2025-09-18 00:21:18.104479 | controller | included: /var/lib/zuul/builds/67e7470b5317453d81d57e3a3069db89/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/tasks/docker-upstream.yaml 2025-09-18 00:21:18.115543 | 2025-09-18 00:21:18.115640 | TASK [ensure-docker : Not Implemented] 2025-09-18 00:21:18.130023 | controller | skipping: Conditional result was False 2025-09-18 00:21:18.137196 | 2025-09-18 00:21:18.137304 | TASK [Set up docker mirrors] 2025-09-18 00:21:18.158260 | controller | ok 2025-09-18 00:21:18.178988 | 2025-09-18 00:21:18.179101 | TASK [use-docker-mirror : Set docker_mirror fact from mirror_info] 2025-09-18 00:21:18.203271 | controller | skipping: Conditional result was False 2025-09-18 00:21:18.210489 | 2025-09-18 00:21:18.210568 | TASK [use-docker-mirror : Set docker_mirror fact from zuul_site_mirror_info] 2025-09-18 00:21:18.235203 | controller | skipping: Conditional result was False 2025-09-18 00:21:18.241802 | 2025-09-18 00:21:18.241887 | TASK [use-docker-mirror : Set docker_mirror fact] 2025-09-18 00:21:18.266536 | controller | skipping: Conditional result was False 2025-09-18 00:21:18.274398 | 2025-09-18 00:21:18.274507 | TASK [use-docker-mirror : Set up docker mirrors] 2025-09-18 00:21:18.339452 | controller | skipping: Conditional result was False 2025-09-18 00:21:18.382277 | 2025-09-18 00:21:18.382408 | TASK [Add all repositories] 2025-09-18 00:21:18.405186 | controller | ok 2025-09-18 00:21:18.432120 | 2025-09-18 00:21:18.432260 | LOOP [ensure-package-repositories : Install repositories] 2025-09-18 00:21:18.454417 | controller | ok: "/var/lib/zuul/builds/67e7470b5317453d81d57e3a3069db89/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-package-repositories/tasks/RedHat.yaml" 2025-09-18 00:21:18.454716 | controller | ok: All items complete 2025-09-18 00:21:18.454784 | 2025-09-18 00:21:18.467833 | controller | included: /var/lib/zuul/builds/67e7470b5317453d81d57e3a3069db89/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-package-repositories/tasks/RedHat.yaml 2025-09-18 00:21:18.475084 | 2025-09-18 00:21:18.475202 | TASK [ensure-package-repositories : Create GPG key temp directory] 2025-09-18 00:21:19.419218 | controller | changed 2025-09-18 00:21:19.429267 | 2025-09-18 00:21:19.429426 | LOOP [ensure-package-repositories : Upload GPG keys] 2025-09-18 00:21:22.625480 | controller | changed: 2025-09-18 00:21:22.625804 | controller | { 2025-09-18 00:21:22.625873 | 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-18 00:21:22.625926 | controller | } 2025-09-18 00:21:22.642383 | 2025-09-18 00:21:22.642521 | LOOP [ensure-package-repositories : Import GPG keys] 2025-09-18 00:21:23.897511 | controller | changed: 2025-09-18 00:21:23.897688 | controller | { 2025-09-18 00:21:23.897716 | 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-18 00:21:23.897781 | controller | } 2025-09-18 00:21:23.897814 | controller | changed: All items complete 2025-09-18 00:21:23.897835 | 2025-09-18 00:21:23.914959 | 2025-09-18 00:21:23.915171 | TASK [ensure-package-repositories : Remove GPG temp directory] 2025-09-18 00:21:24.653588 | controller | changed 2025-09-18 00:21:24.662688 | 2025-09-18 00:21:24.662838 | LOOP [ensure-package-repositories : Add repositories] 2025-09-18 00:21:25.499851 | controller | changed: 2025-09-18 00:21:25.500051 | controller | { 2025-09-18 00:21:25.500077 | controller | "baseurl": "https://download.docker.com/linux/fedora/$releasever/$basearch/stable", 2025-09-18 00:21:25.500096 | controller | "description": "Docker CE Stable - $basearch", 2025-09-18 00:21:25.500113 | controller | "gpgcheck": true, 2025-09-18 00:21:25.500129 | controller | "name": "docker-ce-stable" 2025-09-18 00:21:25.500144 | controller | } 2025-09-18 00:21:25.531166 | 2025-09-18 00:21:25.531367 | TASK [ensure-docker : Install pre-reqs] 2025-09-18 00:21:30.562921 | controller | ok: Nothing to do 2025-09-18 00:21:30.568746 | 2025-09-18 00:21:30.568819 | TASK [ensure-docker : Remove packages] 2025-09-18 00:21:33.500553 | controller | ok: Nothing to do 2025-09-18 00:21:33.514165 | 2025-09-18 00:21:33.514348 | TASK [ensure-docker : Install upstream docker using package] 2025-09-18 00:22:53.464964 | controller | changed 2025-09-18 00:22:53.475265 | 2025-09-18 00:22:53.475400 | TASK [ensure-docker : Setup docker] 2025-09-18 00:22:53.499767 | controller | ok 2025-09-18 00:22:53.515990 | controller | included: /var/lib/zuul/builds/67e7470b5317453d81d57e3a3069db89/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/tasks/docker-setup.yaml 2025-09-18 00:22:53.532875 | 2025-09-18 00:22:53.532993 | TASK [ensure-docker : Ensure "docker" group exists] 2025-09-18 00:22:54.586027 | controller | ok 2025-09-18 00:22:54.592438 | 2025-09-18 00:22:54.592529 | TASK [ensure-docker : Add user to docker group] 2025-09-18 00:22:55.643371 | controller | changed 2025-09-18 00:22:55.651050 | 2025-09-18 00:22:55.651133 | TASK [Update docker daemon proxy configuration] 2025-09-18 00:22:55.683067 | controller | skipping: Conditional result was False 2025-09-18 00:22:55.692827 | 2025-09-18 00:22:55.692945 | TASK [Lower default MTU] 2025-09-18 00:22:55.708592 | controller | skipping: Conditional result was False 2025-09-18 00:22:55.717192 | 2025-09-18 00:22:55.717319 | TASK [ensure-docker : Get the status of the docket.socket unit] 2025-09-18 00:22:56.475364 | controller | inactive 2025-09-18 00:22:57.077317 | controller | ok: Runtime: 0:00:00.019760 2025-09-18 00:22:57.083385 | 2025-09-18 00:22:57.083480 | TASK [ensure-docker : Get the status of the docket unit] 2025-09-18 00:22:57.757996 | controller | inactive 2025-09-18 00:22:57.944562 | controller | ok: Runtime: 0:00:00.024663 2025-09-18 00:22:57.953040 | 2025-09-18 00:22:57.953145 | TASK [ensure-docker : Restart docker] 2025-09-18 00:22:57.996981 | Trigger a Docker restart 2025-09-18 00:22:58.005416 | 2025-09-18 00:22:58.005549 | TASK [ensure-docker : Reset ssh connection to pick up docker group] 2025-09-18 00:22:58.040865 | 2025-09-18 00:22:58.040987 | TASK [ensure-docker : Install docker-compose] 2025-09-18 00:22:58.065957 | controller | skipping: Conditional result was False 2025-09-18 00:22:58.075614 | 2025-09-18 00:22:58.075749 | TASK [ensure-docker : Flush handlers before role exit] 2025-09-18 00:22:58.089375 | 2025-09-18 00:22:58.089489 | TASK [ensure-docker : Stop docker.socket to avoid any conflict] 2025-09-18 00:23:01.613357 | controller | changed 2025-09-18 00:23:01.622361 | 2025-09-18 00:23:01.622476 | TASK [ensure-docker : Assure docker service is running] 2025-09-18 00:23:05.360400 | controller | changed 2025-09-18 00:23:05.373122 | 2025-09-18 00:23:05.373272 | TASK [ensure-docker : Assure docker.socket service is running] 2025-09-18 00:23:07.130140 | controller | ok 2025-09-18 00:23:07.142811 | 2025-09-18 00:23:07.142922 | TASK [ensure-docker : Correct group ownership on docker sock] 2025-09-18 00:23:07.923784 | controller | ok 2025-09-18 00:23:07.932972 | 2025-09-18 00:23:07.933097 | TASK [ensure-docker : Validate ability to talk with docker] 2025-09-18 00:23:08.604499 | controller | CONTAINER ID IMAGE COMMAND CREATED STATUS PORTS NAMES 2025-09-18 00:23:08.823324 | controller | ok: Runtime: 0:00:00.051882 2025-09-18 00:23:08.840463 | 2025-09-18 00:23:08.840661 | TASK [ensure-docker : Get version details] 2025-09-18 00:23:09.530078 | controller | Client: Docker Engine - Community 2025-09-18 00:23:09.530203 | controller | Version: 24.0.7 2025-09-18 00:23:09.530216 | controller | API version: 1.43 2025-09-18 00:23:09.530223 | controller | Go version: go1.20.10 2025-09-18 00:23:09.530244 | controller | Git commit: afdd53b 2025-09-18 00:23:09.530257 | controller | Built: Thu Oct 26 09:09:39 2023 2025-09-18 00:23:09.530265 | controller | OS/Arch: linux/amd64 2025-09-18 00:23:09.530291 | controller | Context: default 2025-09-18 00:23:09.530310 | controller | 2025-09-18 00:23:09.530317 | controller | Server: Docker Engine - Community 2025-09-18 00:23:09.530324 | controller | Engine: 2025-09-18 00:23:09.530330 | controller | Version: 24.0.7 2025-09-18 00:23:09.530336 | controller | API version: 1.43 (minimum version 1.12) 2025-09-18 00:23:09.530345 | controller | Go version: go1.20.10 2025-09-18 00:23:09.530352 | controller | Git commit: 311b9ff 2025-09-18 00:23:09.530374 | controller | Built: Thu Oct 26 09:08:11 2023 2025-09-18 00:23:09.530380 | controller | OS/Arch: linux/amd64 2025-09-18 00:23:09.530386 | controller | Experimental: false 2025-09-18 00:23:09.530392 | controller | containerd: 2025-09-18 00:23:09.530398 | controller | Version: 1.6.25 2025-09-18 00:23:09.530404 | controller | GitCommit: d8f198a4ed8892c764191ef7b3b06d8a2eeb5c7f 2025-09-18 00:23:09.530415 | controller | runc: 2025-09-18 00:23:09.532342 | controller | Version: 1.1.10 2025-09-18 00:23:09.532363 | controller | GitCommit: v1.1.10-0-g18a0cb0 2025-09-18 00:23:09.532374 | controller | docker-init: 2025-09-18 00:23:09.532381 | controller | Version: 0.19.0 2025-09-18 00:23:09.532387 | controller | GitCommit: de40ad0 2025-09-18 00:23:09.725720 | controller | ok: Runtime: 0:00:00.048496 2025-09-18 00:23:09.742081 | 2025-09-18 00:23:09.742307 | TASK [ensure-docker : Dump installed docker details] 2025-09-18 00:23:09.816694 | {'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-18 00:23:09.484584', 'end': '2025-09-18 00:23:09.533080', 'delta': '0:00:00.048496', 'msg': '', 'zuul_log_id': 'fa163ef9-e89a-b377-3734-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-18 00:23:09.825082 | 2025-09-18 00:23:09.825181 | TASK [Use buildset registry] 2025-09-18 00:23:09.842002 | controller | skipping: Conditional result was False 2025-09-18 00:23:09.853924 | 2025-09-18 00:23:09.854030 | TASK [Ensure python3.8 is present] 2025-09-18 00:23:09.868429 | controller | skipping: Conditional result was False 2025-09-18 00:23:09.875421 | 2025-09-18 00:23:09.875504 | TASK [Run our-ensure-python role (Fedora only for now)] 2025-09-18 00:23:09.894925 | controller | ok 2025-09-18 00:23:09.916034 | 2025-09-18 00:23:09.916149 | TASK [our-ensure-python : Install the right Python version (rpm)] 2025-09-18 00:23:16.694133 | controller | ok: Nothing to do 2025-09-18 00:23:16.707578 | 2025-09-18 00:23:16.707770 | TASK [our-ensure-python : Also install python3-devel] 2025-09-18 00:23:34.662959 | controller | changed 2025-09-18 00:23:34.679579 | 2025-09-18 00:23:34.679704 | TASK [Run ensure-virtualenv role] 2025-09-18 00:23:34.699463 | controller | ok 2025-09-18 00:23:34.733518 | 2025-09-18 00:23:34.733651 | TASK [ensure-virtualenv : Check if virtualenv is installed] 2025-09-18 00:23:35.525833 | controller | /usr/bin/virtualenv 2025-09-18 00:23:36.097049 | controller | ok: Runtime: 0:00:00.007050 2025-09-18 00:23:36.112071 | 2025-09-18 00:23:36.112265 | LOOP [ensure-virtualenv : Install virtualenv package] 2025-09-18 00:23:36.150311 | controller | skipping: Conditional result was False 2025-09-18 00:23:36.151249 | controller | changed: All items complete 2025-09-18 00:23:36.151353 | 2025-09-18 00:23:36.176820 | 2025-09-18 00:23:36.176965 | TASK [Find the full path of the Python interpreter] 2025-09-18 00:23:36.938105 | controller | /usr/bin/python3.9 2025-09-18 00:23:37.546490 | controller | ok 2025-09-18 00:23:37.552899 | 2025-09-18 00:23:37.552995 | TASK [Create virtualenv for ansible-test with a specific interpreter] 2025-09-18 00:23:39.886933 | controller | created virtual environment CPython3.9.18.final.0-64 in 1189ms 2025-09-18 00:23:39.941495 | controller | creator CPython3Posix(dest=/home/zuul/venv, clear=False, no_vcs_ignore=False, global=False) 2025-09-18 00:23:39.941559 | 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-18 00:23:39.941575 | controller | added seed packages: pip==22.2.2, setuptools==62.6.0, wheel==0.37.1 2025-09-18 00:23:39.941597 | controller | activators BashActivator,CShellActivator,FishActivator,NushellActivator,PowerShellActivator,PythonActivator 2025-09-18 00:23:40.420276 | controller | changed 2025-09-18 00:23:40.428943 | 2025-09-18 00:23:40.429212 | TASK [Set selinux package] 2025-09-18 00:23:40.452571 | controller | ok 2025-09-18 00:23:40.460405 | 2025-09-18 00:23:40.460542 | TASK [Set selinux package (Fedora)] 2025-09-18 00:23:40.494499 | controller | ok 2025-09-18 00:23:40.536665 | 2025-09-18 00:23:40.536813 | TASK [Install selinux into virtualenv] 2025-09-18 00:23:45.972200 | controller | Collecting selinux-please-lie-to-me 2025-09-18 00:23:46.092370 | controller | Downloading selinux_please_lie_to_me-1.0.1-py2.py3-none-any.whl (3.8 kB) 2025-09-18 00:23:47.480532 | controller | Collecting setuptools<50.0.0 2025-09-18 00:23:47.495918 | controller | Downloading setuptools-49.6.0-py3-none-any.whl (803 kB) 2025-09-18 00:23:47.587482 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 803.3/803.3 kB 10.1 MB/s eta 0:00:00 2025-09-18 00:23:47.711580 | controller | Installing collected packages: setuptools, selinux-please-lie-to-me 2025-09-18 00:23:47.711963 | controller | Attempting uninstall: setuptools 2025-09-18 00:23:47.713239 | controller | Found existing installation: setuptools 62.6.0 2025-09-18 00:23:47.828143 | controller | Uninstalling setuptools-62.6.0: 2025-09-18 00:23:47.842797 | controller | Successfully uninstalled setuptools-62.6.0 2025-09-18 00:23:48.487948 | controller | Successfully installed selinux-please-lie-to-me-1.0.1 setuptools-49.6.0 2025-09-18 00:23:48.677325 | controller | 2025-09-18 00:23:49.115398 | controller | [notice] A new release of pip available: 22.2.2 -> 25.2 2025-09-18 00:23:49.115483 | controller | [notice] To update, run: /home/zuul/venv/bin/python -m pip install --upgrade pip 2025-09-18 00:23:49.405179 | controller | ok: Runtime: 0:00:07.592721 2025-09-18 00:23:49.411079 | 2025-09-18 00:23:49.411151 | TASK [Install pytest-forked into virtualenv] 2025-09-18 00:23:51.154241 | controller | Collecting pytest-forked 2025-09-18 00:23:51.217964 | controller | Downloading pytest_forked-1.6.0-py3-none-any.whl (4.9 kB) 2025-09-18 00:23:51.275976 | controller | Collecting py 2025-09-18 00:23:51.280425 | controller | Downloading py-1.11.0-py2.py3-none-any.whl (98 kB) 2025-09-18 00:23:51.310007 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 98.7/98.7 kB 3.5 MB/s eta 0:00:00 2025-09-18 00:23:51.478386 | controller | Collecting pytest>=3.10 2025-09-18 00:23:51.482098 | controller | Downloading pytest-8.4.2-py3-none-any.whl (365 kB) 2025-09-18 00:23:51.500672 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 365.8/365.8 kB 24.4 MB/s eta 0:00:00 2025-09-18 00:23:51.584294 | controller | Collecting packaging>=20 2025-09-18 00:23:51.590145 | controller | Downloading packaging-25.0-py3-none-any.whl (66 kB) 2025-09-18 00:23:51.601339 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 66.5/66.5 kB 6.4 MB/s eta 0:00:00 2025-09-18 00:23:51.647815 | controller | Collecting pluggy<2,>=1.5 2025-09-18 00:23:51.655797 | controller | Downloading pluggy-1.6.0-py3-none-any.whl (20 kB) 2025-09-18 00:23:51.711338 | controller | Collecting exceptiongroup>=1 2025-09-18 00:23:51.717622 | controller | Downloading exceptiongroup-1.3.0-py3-none-any.whl (16 kB) 2025-09-18 00:23:51.798938 | controller | Collecting pygments>=2.7.2 2025-09-18 00:23:51.805517 | controller | Downloading pygments-2.19.2-py3-none-any.whl (1.2 MB) 2025-09-18 00:23:51.831635 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 1.2/1.2 MB 56.8 MB/s eta 0:00:00 2025-09-18 00:23:51.866469 | controller | Collecting iniconfig>=1 2025-09-18 00:23:51.872458 | controller | Downloading iniconfig-2.1.0-py3-none-any.whl (6.0 kB) 2025-09-18 00:23:51.929913 | controller | Collecting tomli>=1 2025-09-18 00:23:51.935814 | controller | Downloading tomli-2.2.1-py3-none-any.whl (14 kB) 2025-09-18 00:23:52.009112 | controller | Collecting typing-extensions>=4.6.0 2025-09-18 00:23:52.015456 | controller | Downloading typing_extensions-4.15.0-py3-none-any.whl (44 kB) 2025-09-18 00:23:52.028949 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 44.6/44.6 kB 3.6 MB/s eta 0:00:00 2025-09-18 00:23:52.137470 | controller | Installing collected packages: typing-extensions, tomli, pygments, py, pluggy, packaging, iniconfig, exceptiongroup, pytest, pytest-forked 2025-09-18 00:23:54.635170 | 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-18 00:23:54.650316 | controller | 2025-09-18 00:23:54.981336 | controller | [notice] A new release of pip available: 22.2.2 -> 25.2 2025-09-18 00:23:54.981400 | controller | [notice] To update, run: /home/zuul/venv/bin/python -m pip install --upgrade pip 2025-09-18 00:23:55.271698 | controller | ok: Runtime: 0:00:04.483526 2025-09-18 00:23:55.278469 | 2025-09-18 00:23:55.278591 | TASK [Update pip] 2025-09-18 00:23:57.739821 | controller | Requirement already satisfied: pip in ./venv/lib/python3.9/site-packages (22.2.2) 2025-09-18 00:23:58.211097 | controller | Collecting pip 2025-09-18 00:23:58.343674 | controller | Downloading pip-25.2-py3-none-any.whl (1.8 MB) 2025-09-18 00:23:58.456138 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 1.8/1.8 MB 17.9 MB/s eta 0:00:00 2025-09-18 00:23:58.690668 | controller | Installing collected packages: pip 2025-09-18 00:23:58.691125 | controller | Attempting uninstall: pip 2025-09-18 00:23:58.692422 | controller | Found existing installation: pip 22.2.2 2025-09-18 00:23:59.282022 | controller | Uninstalling pip-22.2.2: 2025-09-18 00:23:59.373568 | controller | Successfully uninstalled pip-22.2.2 2025-09-18 00:24:00.943207 | controller | Successfully installed pip-25.2 2025-09-18 00:24:01.643106 | controller | ok: Runtime: 0:00:04.811684 2025-09-18 00:24:01.652038 | 2025-09-18 00:24:01.652177 | TASK [Ensure pip temp directory exists in /var/tmp] 2025-09-18 00:24:02.900256 | controller | changed 2025-09-18 00:24:02.907313 | 2025-09-18 00:24:02.907417 | TASK [Install ansible into virtualenv] 2025-09-18 00:24:05.150699 | controller | Processing ./src/github.com/ansible/ansible 2025-09-18 00:24:05.157881 | controller | Preparing metadata (setup.py): started 2025-09-18 00:24:06.075679 | controller | Preparing metadata (setup.py): finished with status 'done' 2025-09-18 00:24:06.142332 | controller | Collecting jinja2 (from ansible-core==2.11.12.post0) 2025-09-18 00:24:06.157196 | controller | Downloading jinja2-3.1.6-py3-none-any.whl.metadata (2.9 kB) 2025-09-18 00:24:06.242055 | controller | Collecting PyYAML (from ansible-core==2.11.12.post0) 2025-09-18 00:24:06.249675 | controller | Downloading PyYAML-6.0.2-cp39-cp39-manylinux_2_17_x86_64.manylinux2014_x86_64.whl.metadata (2.1 kB) 2025-09-18 00:24:06.749926 | controller | Collecting cryptography (from ansible-core==2.11.12.post0) 2025-09-18 00:24:06.755631 | controller | Downloading cryptography-46.0.1-cp38-abi3-manylinux_2_34_x86_64.whl.metadata (5.7 kB) 2025-09-18 00:24:06.778446 | controller | Requirement already satisfied: packaging in ./venv/lib/python3.9/site-packages (from ansible-core==2.11.12.post0) (25.0) 2025-09-18 00:24:06.805907 | controller | Collecting resolvelib<0.6.0,>=0.5.3 (from ansible-core==2.11.12.post0) 2025-09-18 00:24:06.820163 | controller | Downloading resolvelib-0.5.4-py2.py3-none-any.whl.metadata (3.7 kB) 2025-09-18 00:24:07.091617 | controller | Collecting cffi>=2.0.0 (from cryptography->ansible-core==2.11.12.post0) 2025-09-18 00:24:07.097249 | controller | Downloading cffi-2.0.0-cp39-cp39-manylinux2014_x86_64.manylinux_2_17_x86_64.whl.metadata (2.6 kB) 2025-09-18 00:24:07.108680 | 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-18 00:24:07.128036 | controller | Collecting pycparser (from cffi>=2.0.0->cryptography->ansible-core==2.11.12.post0) 2025-09-18 00:24:07.132836 | controller | Downloading pycparser-2.23-py3-none-any.whl.metadata (993 bytes) 2025-09-18 00:24:07.255751 | controller | Collecting MarkupSafe>=2.0 (from jinja2->ansible-core==2.11.12.post0) 2025-09-18 00:24:07.261719 | controller | Downloading MarkupSafe-3.0.2-cp39-cp39-manylinux_2_17_x86_64.manylinux2014_x86_64.whl.metadata (4.0 kB) 2025-09-18 00:24:07.282113 | controller | Downloading resolvelib-0.5.4-py2.py3-none-any.whl (12 kB) 2025-09-18 00:24:07.296763 | controller | Downloading cryptography-46.0.1-cp38-abi3-manylinux_2_34_x86_64.whl (4.6 MB) 2025-09-18 00:24:07.350971 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 4.6/4.6 MB 103.0 MB/s 0:00:00 2025-09-18 00:24:07.356690 | controller | Downloading cffi-2.0.0-cp39-cp39-manylinux2014_x86_64.manylinux_2_17_x86_64.whl (216 kB) 2025-09-18 00:24:07.375091 | controller | Downloading jinja2-3.1.6-py3-none-any.whl (134 kB) 2025-09-18 00:24:07.390885 | controller | Downloading MarkupSafe-3.0.2-cp39-cp39-manylinux_2_17_x86_64.manylinux2014_x86_64.whl (20 kB) 2025-09-18 00:24:07.403836 | controller | Downloading pycparser-2.23-py3-none-any.whl (118 kB) 2025-09-18 00:24:07.419091 | controller | Downloading PyYAML-6.0.2-cp39-cp39-manylinux_2_17_x86_64.manylinux2014_x86_64.whl (737 kB) 2025-09-18 00:24:07.435165 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 737.4/737.4 kB 43.7 MB/s 0:00:00 2025-09-18 00:24:07.455787 | controller | Building wheels for collected packages: ansible-core 2025-09-18 00:24:07.457322 | 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-18 00:24:07.457670 | controller | Building wheel for ansible-core (setup.py): started 2025-09-18 00:24:09.502985 | controller | Building wheel for ansible-core (setup.py): finished with status 'done' 2025-09-18 00:24:09.506711 | controller | Created wheel for ansible-core: filename=ansible_core-2.11.12.post0-py3-none-any.whl size=1961168 sha256=18d3629cc04b7f029207639b73fadb0eee02a0b39ee03c923c7d6805c822f228 2025-09-18 00:24:09.506953 | controller | Stored in directory: /var/tmp/ansible-test-pip/pip-ephem-wheel-cache-hp1fgeu9/wheels/0f/a0/93/ab85b739e1eb1e0467171316f13571c14efdf87e0d5d44a33e 2025-09-18 00:24:09.523099 | controller | Successfully built ansible-core 2025-09-18 00:24:09.580333 | controller | Installing collected packages: resolvelib, PyYAML, pycparser, MarkupSafe, jinja2, cffi, cryptography, ansible-core 2025-09-18 00:24:12.223405 | controller | 2025-09-18 00:24:12.228306 | controller | Successfully installed MarkupSafe-3.0.2 PyYAML-6.0.2 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-18 00:24:12.778602 | controller | ok: Runtime: 0:00:08.598139 2025-09-18 00:24:12.789244 | 2025-09-18 00:24:12.789399 | TASK [Setup download-artifact-fork role] 2025-09-18 00:24:12.811347 | controller | ok 2025-09-18 00:24:12.831128 | 2025-09-18 00:24:12.831254 | TASK [download-artifact-fork : Parse inventory response] 2025-09-18 00:24:12.874577 | controller | ok 2025-09-18 00:24:12.884827 | 2025-09-18 00:24:12.884990 | TASK [download-artifact-fork : Fetch info from Zuul API] 2025-09-18 00:24:12.922581 | controller | skipping: Conditional result was False 2025-09-18 00:24:12.933643 | 2025-09-18 00:24:12.933864 | TASK [download-artifact-fork : Parse build response] 2025-09-18 00:24:12.960165 | controller | skipping: Conditional result was False 2025-09-18 00:24:12.970687 | 2025-09-18 00:24:12.970846 | TASK [download-artifact-fork : Ensure artifacts directory exists] 2025-09-18 00:24:13.805673 | controller | changed 2025-09-18 00:24:13.812578 | 2025-09-18 00:24:13.812693 | LOOP [download-artifact-fork : Download archive by type] 2025-09-18 00:24:13.834586 | controller | skipping: Conditional result was False 2025-09-18 00:24:13.834903 | 2025-09-18 00:24:16.190281 | controller | changed: OK (20186 bytes) 2025-09-18 00:24:16.227005 | 2025-09-18 00:24:16.227211 | TASK [deploy-artifacts : define __collections] 2025-09-18 00:24:16.253296 | controller | ok 2025-09-18 00:24:16.262417 | 2025-09-18 00:24:16.262554 | LOOP [deploy-artifacts : Create list of collections] 2025-09-18 00:24:16.289514 | controller | skipping: Conditional result was False 2025-09-18 00:24:16.341123 | controller | ok: 2025-09-18 00:24:16.341264 | controller | { 2025-09-18 00:24:16.341296 | controller | "branch": "main", 2025-09-18 00:24:16.341324 | controller | "job": "build-ansible-collection", 2025-09-18 00:24:16.341352 | controller | "metadata": { 2025-09-18 00:24:16.341375 | controller | "type": "ansible_collection", 2025-09-18 00:24:16.341396 | controller | "version": "5.0.0" 2025-09-18 00:24:16.341417 | controller | }, 2025-09-18 00:24:16.341437 | controller | "name": "ansible.network", 2025-09-18 00:24:16.341457 | controller | "project": "ansible-collections/ansible.network", 2025-09-18 00:24:16.341477 | controller | "url": "https://621ed2f938ec07472166-da4351aaa874cd347a68e4f498cacad8.ssl.cf2.rackcdn.com/ansible/9698a1da48e94780a6e4ea9266c293e9/artifacts/ansible-network-5.0.0.tar.gz" 2025-09-18 00:24:16.341506 | controller | } 2025-09-18 00:24:16.358160 | 2025-09-18 00:24:16.358304 | TASK [deploy-artifacts : Install require-project collection using ansible-galaxy] 2025-09-18 00:24:17.916750 | controller | Starting galaxy collection install process 2025-09-18 00:24:17.918234 | controller | Process install dependency map 2025-09-18 00:24:20.399735 | controller | [WARNING]: Skipping Galaxy server https://galaxy.ansible.com/api/. Got an 2025-09-18 00:24:20.400182 | controller | unexpected error when getting available versions of collection 2025-09-18 00:24:20.400212 | controller | ansible.netcommon: '/api/v3/plugin/ansible/content/published/collections/index/ 2025-09-18 00:24:20.400220 | controller | ansible/netcommon/versions/' 2025-09-18 00:24:20.400231 | controller | ERROR! Unexpected Exception, this is probably a bug: '/api/v3/plugin/ansible/content/published/collections/index/ansible/netcommon/versions/' 2025-09-18 00:24:20.400312 | controller | to see the full traceback, use -vvv 2025-09-18 00:24:20.729354 | controller | ERROR 2025-09-18 00:24:20.729607 | controller | { 2025-09-18 00:24:20.729648 | controller | "delta": "0:00:03.416121", 2025-09-18 00:24:20.729708 | controller | "end": "2025-09-18 00:24:20.530056", 2025-09-18 00:24:20.729767 | controller | "msg": "non-zero return code", 2025-09-18 00:24:20.729796 | controller | "rc": 250, 2025-09-18 00:24:20.729819 | controller | "start": "2025-09-18 00:24:17.113935" 2025-09-18 00:24:20.729840 | controller | } failure 2025-09-18 00:24:20.732164 | 2025-09-18 00:24:20.732229 | PLAY RECAP 2025-09-18 00:24:20.732281 | controller | ok: 44 changed: 24 unreachable: 0 failed: 1 skipped: 17 rescued: 0 ignored: 0 2025-09-18 00:24:20.732309 | 2025-09-18 00:24:20.902478 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master] 2025-09-18 00:24:20.904466 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2025-09-18 00:24:21.526653 | 2025-09-18 00:24:21.526896 | PLAY [all] 2025-09-18 00:24:21.552936 | 2025-09-18 00:24:21.553098 | TASK [fetch-subunit-output : Find stestr or testr executable] 2025-09-18 00:24:24.553691 | controller | changed: non-zero return code 2025-09-18 00:24:24.559786 | 2025-09-18 00:24:24.559919 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2025-09-18 00:24:24.574100 | controller | skipping: Conditional result was False 2025-09-18 00:24:24.580337 | 2025-09-18 00:24:24.580445 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2025-09-18 00:24:24.614853 | 2025-09-18 00:24:24.615064 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2025-09-18 00:24:24.640581 | 2025-09-18 00:24:24.640813 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2025-09-18 00:24:24.656099 | controller | skipping: Conditional result was False 2025-09-18 00:24:24.666131 | 2025-09-18 00:24:24.666285 | LOOP [fetch-subunit-output : Generate subunit file] 2025-09-18 00:24:24.690911 | 2025-09-18 00:24:24.691140 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2025-09-18 00:24:24.707181 | controller | skipping: Conditional result was False 2025-09-18 00:24:24.718596 | 2025-09-18 00:24:24.718770 | TASK [fetch-subunit-output : Remove the temporary file] 2025-09-18 00:24:24.733873 | controller | skipping: Conditional result was False 2025-09-18 00:24:24.741263 | 2025-09-18 00:24:24.741425 | TASK [fetch-subunit-output : Process and fetch subunit results] 2025-09-18 00:24:24.765577 | controller | skipping: Conditional result was False 2025-09-18 00:24:24.803126 | 2025-09-18 00:24:24.803234 | PLAY RECAP 2025-09-18 00:24:24.803289 | controller | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2025-09-18 00:24:24.803317 | 2025-09-18 00:24:24.929869 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2025-09-18 00:24:24.931061 | POST-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/post.yaml@master] 2025-09-18 00:24:25.577711 | 2025-09-18 00:24:25.577853 | PLAY [all:!appliance*] 2025-09-18 00:24:25.600630 | 2025-09-18 00:24:25.600760 | TASK [unregister the node] 2025-09-18 00:24:25.955617 | controller | skipping: Conditional result was False 2025-09-18 00:24:25.961951 | 2025-09-18 00:24:25.962047 | TASK [include_role : fetch-output] 2025-09-18 00:24:25.992696 | controller | ok 2025-09-18 00:24:26.013609 | 2025-09-18 00:24:26.013748 | TASK [fetch-output : Set log path for multiple nodes] 2025-09-18 00:24:26.078636 | controller | skipping: Conditional result was False 2025-09-18 00:24:26.087692 | 2025-09-18 00:24:26.087868 | TASK [fetch-output : Set log path for single node] 2025-09-18 00:24:26.131740 | controller | ok 2025-09-18 00:24:26.139991 | 2025-09-18 00:24:26.140131 | LOOP [fetch-output : Ensure local output dirs] 2025-09-18 00:24:26.545424 | controller -> localhost | ok: "/var/lib/zuul/builds/67e7470b5317453d81d57e3a3069db89/work/logs" 2025-09-18 00:24:26.788279 | controller -> localhost | changed: "/var/lib/zuul/builds/67e7470b5317453d81d57e3a3069db89/work/artifacts" 2025-09-18 00:24:27.012159 | controller -> localhost | changed: "/var/lib/zuul/builds/67e7470b5317453d81d57e3a3069db89/work/docs" 2025-09-18 00:24:27.022850 | 2025-09-18 00:24:27.022975 | LOOP [fetch-output : Collect logs, artifacts and docs] 2025-09-18 00:24:29.282141 | controller | changed: 2025-09-18 00:24:29.282546 | controller | .d..t...... ./ 2025-09-18 00:24:29.282612 | controller | cd+++++++++ controller/ 2025-09-18 00:24:29.282685 | controller | changed: All items complete 2025-09-18 00:24:29.282776 | 2025-09-18 00:24:31.332895 | controller | changed: .d..t...... ./ 2025-09-18 00:24:33.398325 | controller | changed: .d..t...... ./ 2025-09-18 00:24:33.412460 | 2025-09-18 00:24:33.412580 | TASK [include_role : fetch-output-openshift] 2025-09-18 00:24:33.426942 | controller | skipping: Conditional result was False 2025-09-18 00:24:33.437210 | 2025-09-18 00:24:33.437314 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2025-09-18 00:24:33.462532 | controller | skipping: Conditional result was False 2025-09-18 00:24:33.469941 | controller | skipping: Conditional result was False 2025-09-18 00:24:33.512755 | 2025-09-18 00:24:33.512923 | PLAY [localhost] 2025-09-18 00:24:33.531503 | 2025-09-18 00:24:33.531836 | TASK [Run Zuul manifest role] 2025-09-18 00:24:33.550809 | localhost | ok 2025-09-18 00:24:33.564156 | 2025-09-18 00:24:33.564257 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-09-18 00:24:33.920607 | localhost | changed 2025-09-18 00:24:33.927259 | 2025-09-18 00:24:33.927402 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-09-18 00:24:33.958873 | localhost | ok 2025-09-18 00:24:33.971307 | 2025-09-18 00:24:33.971444 | TASK [Set zuul-log-path fact] 2025-09-18 00:24:33.991855 | localhost | ok 2025-09-18 00:24:34.034430 | 2025-09-18 00:24:34.034555 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-09-18 00:24:34.064991 | localhost | ok 2025-09-18 00:24:34.074439 | 2025-09-18 00:24:34.074530 | LOOP [Run upload-logs-swift role] 2025-09-18 00:24:34.098564 | localhost | Output suppressed because no_log was given 2025-09-18 00:24:34.126557 | 2025-09-18 00:24:34.126696 | TASK [Set zuul-log-path fact] 2025-09-18 00:24:34.151146 | localhost | skipping: Conditional result was False 2025-09-18 00:24:34.157977 | 2025-09-18 00:24:34.158102 | TASK [upload-logs-swift : Ensure logs are readable before uploading] 2025-09-18 00:24:34.518919 | localhost -> localhost | ok: Runtime: 0:00:00.005966 2025-09-18 00:24:34.530951 | 2025-09-18 00:24:34.531118 | TASK [upload-logs-swift : Upload logs to swift]