2026-02-22 00:14:26.001052 | Job console starting... 2026-02-22 00:14:26.012360 | Updating repositories 2026-02-22 00:14:26.163670 | Preparing job workspace 2026-02-22 00:14:30.025481 | Running Ansible setup... 2026-02-22 00:14:38.238064 | PRE-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master] 2026-02-22 00:14:38.901521 | 2026-02-22 00:14:38.901649 | PLAY [localhost] 2026-02-22 00:14:38.910448 | 2026-02-22 00:14:38.910522 | TASK [Gathering Facts] 2026-02-22 00:14:39.926787 | localhost | ok 2026-02-22 00:14:39.941327 | 2026-02-22 00:14:39.941431 | TASK [Setup log path fact] 2026-02-22 00:14:39.958984 | localhost | ok 2026-02-22 00:14:39.973591 | 2026-02-22 00:14:39.973824 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-02-22 00:14:40.002027 | localhost | ok 2026-02-22 00:14:40.011059 | 2026-02-22 00:14:40.011167 | TASK [emit-job-header : Print job information] 2026-02-22 00:14:40.040892 | # Job Information 2026-02-22 00:14:40.041079 | Ansible Version: 2.15.12 2026-02-22 00:14:40.041125 | Job: ansible-test-sanity-docker-stable-2.11 2026-02-22 00:14:40.041157 | Pipeline: periodic 2026-02-22 00:14:40.041184 | Executor: ze03.softwarefactory-project.io 2026-02-22 00:14:40.041211 | Triggered by: https://github.com/ansible-collections/ansible.network 2026-02-22 00:14:40.041244 | Log URL (when completed): https://ansible.softwarefactory-project.io/logs/0e3/ansible/0e3516dbf5ff4fd1bbb7711af5858b1d/ 2026-02-22 00:14:40.041272 | Event ID: a7fc77fcb26d4cceb2ed771a560774dd 2026-02-22 00:14:40.047180 | 2026-02-22 00:14:40.047270 | LOOP [emit-job-header : Print node information] 2026-02-22 00:14:40.150928 | localhost | ok: 2026-02-22 00:14:40.151126 | localhost | # Node Information 2026-02-22 00:14:40.151171 | localhost | Inventory Hostname: controller 2026-02-22 00:14:40.151216 | localhost | Hostname: np0005625937 2026-02-22 00:14:40.151245 | localhost | Username: zuul 2026-02-22 00:14:40.151278 | localhost | Distro: Fedora 37 2026-02-22 00:14:40.151299 | localhost | Provider: ansible-vexxhost-ams1 2026-02-22 00:14:40.151318 | localhost | Region: ams1 2026-02-22 00:14:40.151335 | localhost | Label: ansible-fedora-37-1vcpu 2026-02-22 00:14:40.151352 | localhost | Product Name: OpenStack Nova 2026-02-22 00:14:40.151368 | localhost | Interface IP: 38.129.16.188 2026-02-22 00:14:40.160613 | 2026-02-22 00:14:40.160691 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-02-22 00:14:40.573567 | localhost -> localhost | changed 2026-02-22 00:14:40.581294 | 2026-02-22 00:14:40.581401 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-02-22 00:14:41.463700 | localhost -> localhost | changed 2026-02-22 00:14:41.484553 | 2026-02-22 00:14:41.484660 | PLAY [all:!appliance*] 2026-02-22 00:14:41.502331 | 2026-02-22 00:14:41.502461 | TASK [include_role : start-zuul-console] 2026-02-22 00:14:41.522143 | controller | ok 2026-02-22 00:14:41.538713 | 2026-02-22 00:14:41.538845 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-02-22 00:14:42.584258 | controller | ok 2026-02-22 00:14:42.616432 | 2026-02-22 00:14:42.616766 | TASK [use-our-mirror : Retrieve the IP address] 2026-02-22 00:14:46.110362 | controller | ok: Status code was 404 and not [200]: HTTP Error 404: Not Found 2026-02-22 00:14:46.119207 | 2026-02-22 00:14:46.119293 | TASK [use-our-mirror : Ensure the registry service is running on the mirror] 2026-02-22 00:14:46.491867 | controller | skipping: Conditional result was False 2026-02-22 00:14:46.501075 | 2026-02-22 00:14:46.501189 | TASK [use-our-mirror : Write the IP in /etc/hosts] 2026-02-22 00:14:46.538854 | controller | skipping: Conditional result was False 2026-02-22 00:14:46.547973 | 2026-02-22 00:14:46.548135 | TASK [use-our-mirror : Create the podman configuration directory] 2026-02-22 00:14:46.574299 | controller | skipping: Conditional result was False 2026-02-22 00:14:46.583512 | 2026-02-22 00:14:46.583654 | TASK [use-our-mirror : Copy the podman configuration] 2026-02-22 00:14:46.610066 | controller | skipping: Conditional result was False 2026-02-22 00:14:46.618172 | 2026-02-22 00:14:46.618321 | TASK [use-our-mirror : Copy the Yum configuration (main)] 2026-02-22 00:14:46.644150 | controller | skipping: Conditional result was False 2026-02-22 00:14:46.650785 | 2026-02-22 00:14:46.650862 | TASK [use-our-mirror : Copy the Yum configuration (update)] 2026-02-22 00:14:46.687675 | controller | skipping: Conditional result was False 2026-02-22 00:14:46.700436 | 2026-02-22 00:14:46.700542 | TASK [Disable Fedora Modular] 2026-02-22 00:14:47.947121 | controller | changed 2026-02-22 00:14:47.961783 | 2026-02-22 00:14:47.961972 | TASK [Enable EPEL] 2026-02-22 00:14:48.014998 | controller | skipping: Conditional result was False 2026-02-22 00:14:48.026245 | 2026-02-22 00:14:48.026459 | TASK [Register the RHEL node] 2026-02-22 00:14:48.463880 | 2026-02-22 00:14:48.464090 | TASK [Show the subscription-manager status] 2026-02-22 00:14:48.849881 | controller | skipping: Conditional result was False 2026-02-22 00:14:48.860691 | 2026-02-22 00:14:48.860836 | TASK [Enable EPEL on RHEL] 2026-02-22 00:14:49.261232 | controller | skipping: Conditional result was False 2026-02-22 00:14:49.268909 | 2026-02-22 00:14:49.269013 | TASK [Install git and tox] 2026-02-22 00:17:11.862769 | controller | changed 2026-02-22 00:17:11.873522 | 2026-02-22 00:17:11.873625 | TASK [include_role : prepare-workspace] 2026-02-22 00:17:11.895249 | controller | ok 2026-02-22 00:17:11.919068 | 2026-02-22 00:17:11.919186 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-02-22 00:17:12.756387 | controller | ok 2026-02-22 00:17:12.764037 | 2026-02-22 00:17:12.764126 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-02-22 00:17:28.477952 | controller | Output suppressed because no_log was given 2026-02-22 00:17:28.491272 | 2026-02-22 00:17:28.491388 | TASK [include_role : prepare-workspace-openshift] 2026-02-22 00:17:28.516373 | controller | skipping: Conditional result was False 2026-02-22 00:17:28.552235 | 2026-02-22 00:17:28.552344 | PLAY [all:!appliance] 2026-02-22 00:17:28.577461 | 2026-02-22 00:17:28.577592 | TASK [Run add-build-sshkey role (RSA)] 2026-02-22 00:17:28.599286 | controller | ok 2026-02-22 00:17:28.615514 | 2026-02-22 00:17:28.615627 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-02-22 00:17:28.844002 | controller -> localhost | ok 2026-02-22 00:17:28.851544 | 2026-02-22 00:17:28.851630 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-02-22 00:17:28.882005 | controller | ok 2026-02-22 00:17:28.897216 | controller | included: /var/lib/zuul/builds/0e3516dbf5ff4fd1bbb7711af5858b1d/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-02-22 00:17:28.904705 | 2026-02-22 00:17:28.904806 | TASK [add-build-sshkey : Create Temp SSH key] 2026-02-22 00:17:29.441875 | controller -> localhost | Generating public/private rsa key pair. 2026-02-22 00:17:29.442096 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/0e3516dbf5ff4fd1bbb7711af5858b1d/work/0e3516dbf5ff4fd1bbb7711af5858b1d_id_rsa. 2026-02-22 00:17:29.442137 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/0e3516dbf5ff4fd1bbb7711af5858b1d/work/0e3516dbf5ff4fd1bbb7711af5858b1d_id_rsa.pub. 2026-02-22 00:17:29.442168 | controller -> localhost | The key fingerprint is: 2026-02-22 00:17:29.442199 | controller -> localhost | SHA256:YUkUUQ6lTdWHlX0z2SNXOEHdWKo4+PmiHhpMRaL4DWU zuul-build-sshkey 2026-02-22 00:17:29.442229 | controller -> localhost | The key's randomart image is: 2026-02-22 00:17:29.442265 | controller -> localhost | +---[RSA 2048]----+ 2026-02-22 00:17:29.442293 | controller -> localhost | | E.B=+..ooO@| 2026-02-22 00:17:29.442321 | controller -> localhost | | . + + B .*OB| 2026-02-22 00:17:29.442347 | controller -> localhost | | . o * o +o=| 2026-02-22 00:17:29.442374 | controller -> localhost | | . o o o . . | 2026-02-22 00:17:29.442399 | controller -> localhost | | . o S o . | 2026-02-22 00:17:29.442438 | controller -> localhost | | o . o | 2026-02-22 00:17:29.442472 | controller -> localhost | | o . o | 2026-02-22 00:17:29.442501 | controller -> localhost | | o ... | 2026-02-22 00:17:29.442528 | controller -> localhost | | ..o. .. | 2026-02-22 00:17:29.442573 | controller -> localhost | +----[SHA256]-----+ 2026-02-22 00:17:29.442638 | controller -> localhost | ok: Runtime: 0:00:00.130400 2026-02-22 00:17:29.452347 | 2026-02-22 00:17:29.452451 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-02-22 00:17:29.476485 | controller | ok 2026-02-22 00:17:29.490287 | controller | included: /var/lib/zuul/builds/0e3516dbf5ff4fd1bbb7711af5858b1d/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-02-22 00:17:29.503212 | 2026-02-22 00:17:29.503318 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-02-22 00:17:29.532715 | controller | skipping: Conditional result was False 2026-02-22 00:17:29.542764 | 2026-02-22 00:17:29.542880 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-02-22 00:17:30.637702 | controller | changed 2026-02-22 00:17:30.644802 | 2026-02-22 00:17:30.644905 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-02-22 00:17:31.281707 | controller | ok 2026-02-22 00:17:31.289196 | 2026-02-22 00:17:31.289334 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-02-22 00:17:34.318558 | controller | changed 2026-02-22 00:17:34.326165 | 2026-02-22 00:17:34.326262 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-02-22 00:17:37.415407 | controller | changed 2026-02-22 00:17:37.426173 | 2026-02-22 00:17:37.426308 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-02-22 00:17:37.455427 | controller | skipping: Conditional result was False 2026-02-22 00:17:37.464372 | 2026-02-22 00:17:37.464523 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-02-22 00:17:37.845884 | controller -> localhost | changed 2026-02-22 00:17:37.858782 | 2026-02-22 00:17:37.858874 | TASK [add-build-sshkey : Add back temp key] 2026-02-22 00:17:38.199504 | controller -> localhost | Identity added: /var/lib/zuul/builds/0e3516dbf5ff4fd1bbb7711af5858b1d/work/0e3516dbf5ff4fd1bbb7711af5858b1d_id_rsa (zuul-build-sshkey) 2026-02-22 00:17:38.199787 | controller -> localhost | ok: Runtime: 0:00:00.007580 2026-02-22 00:17:38.210180 | 2026-02-22 00:17:38.210276 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-02-22 00:17:39.170008 | controller | ok 2026-02-22 00:17:39.187225 | 2026-02-22 00:17:39.187425 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-02-22 00:17:39.225994 | controller | skipping: Conditional result was False 2026-02-22 00:17:39.252917 | 2026-02-22 00:17:39.253067 | TASK [Run add-build-sshkey role (ECDSA)] 2026-02-22 00:17:39.287541 | controller | ok 2026-02-22 00:17:39.310875 | 2026-02-22 00:17:39.310971 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-02-22 00:17:39.572586 | controller -> localhost | ok 2026-02-22 00:17:39.579584 | 2026-02-22 00:17:39.579661 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-02-22 00:17:39.619331 | controller | ok 2026-02-22 00:17:39.632051 | controller | included: /var/lib/zuul/builds/0e3516dbf5ff4fd1bbb7711af5858b1d/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-02-22 00:17:39.638352 | 2026-02-22 00:17:39.638440 | TASK [add-build-sshkey : Create Temp SSH key] 2026-02-22 00:17:39.958676 | controller -> localhost | Generating public/private ecdsa key pair. 2026-02-22 00:17:39.959118 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/0e3516dbf5ff4fd1bbb7711af5858b1d/work/0e3516dbf5ff4fd1bbb7711af5858b1d_id_ecdsa. 2026-02-22 00:17:39.959185 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/0e3516dbf5ff4fd1bbb7711af5858b1d/work/0e3516dbf5ff4fd1bbb7711af5858b1d_id_ecdsa.pub. 2026-02-22 00:17:39.959247 | controller -> localhost | The key fingerprint is: 2026-02-22 00:17:39.959291 | controller -> localhost | SHA256:KOnIOcsQIqWDCeJX/5XPnYrox2GMdHvF05t0KBtmcbU zuul-build-sshkey 2026-02-22 00:17:39.959332 | controller -> localhost | The key's randomart image is: 2026-02-22 00:17:39.959559 | controller -> localhost | +---[ECDSA 521]---+ 2026-02-22 00:17:39.959598 | controller -> localhost | | ..| 2026-02-22 00:17:39.959636 | controller -> localhost | | . . .| 2026-02-22 00:17:39.959674 | controller -> localhost | |o . . o.E.| 2026-02-22 00:17:39.959711 | controller -> localhost | |=+ .... . .* .=o| 2026-02-22 00:17:39.959796 | controller -> localhost | |O. .o ..S +=.+o =| 2026-02-22 00:17:39.959836 | controller -> localhost | |o+.+ . ...=+..o.| 2026-02-22 00:17:39.959874 | controller -> localhost | |. = . .o oo o | 2026-02-22 00:17:39.959924 | controller -> localhost | | o o .o. . | 2026-02-22 00:17:39.959980 | controller -> localhost | | o .o.. . | 2026-02-22 00:17:39.960026 | controller -> localhost | +----[SHA256]-----+ 2026-02-22 00:17:39.960124 | controller -> localhost | ok: Runtime: 0:00:00.018094 2026-02-22 00:17:39.982094 | 2026-02-22 00:17:39.982273 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-02-22 00:17:40.017825 | controller | ok 2026-02-22 00:17:40.030779 | controller | included: /var/lib/zuul/builds/0e3516dbf5ff4fd1bbb7711af5858b1d/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-02-22 00:17:40.045181 | 2026-02-22 00:17:40.045353 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-02-22 00:17:40.081925 | controller | skipping: Conditional result was False 2026-02-22 00:17:40.136634 | 2026-02-22 00:17:40.136784 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-02-22 00:17:41.072397 | controller | changed 2026-02-22 00:17:41.088000 | 2026-02-22 00:17:41.088204 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-02-22 00:17:41.753588 | controller | ok 2026-02-22 00:17:41.772578 | 2026-02-22 00:17:41.772803 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-02-22 00:17:44.810761 | controller | changed 2026-02-22 00:17:44.823840 | 2026-02-22 00:17:44.823981 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-02-22 00:17:47.875662 | controller | changed 2026-02-22 00:17:47.885354 | 2026-02-22 00:17:47.885466 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-02-22 00:17:47.911321 | controller | skipping: Conditional result was False 2026-02-22 00:17:47.922077 | 2026-02-22 00:17:47.922227 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-02-22 00:17:48.171614 | controller -> localhost | changed 2026-02-22 00:17:48.184432 | 2026-02-22 00:17:48.184540 | TASK [add-build-sshkey : Add back temp key] 2026-02-22 00:17:48.484776 | controller -> localhost | Identity added: /var/lib/zuul/builds/0e3516dbf5ff4fd1bbb7711af5858b1d/work/0e3516dbf5ff4fd1bbb7711af5858b1d_id_ecdsa (zuul-build-sshkey) 2026-02-22 00:17:48.484969 | controller -> localhost | ok: Runtime: 0:00:00.009650 2026-02-22 00:17:48.492655 | 2026-02-22 00:17:48.492746 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-02-22 00:17:49.215607 | controller | ok 2026-02-22 00:17:49.221580 | 2026-02-22 00:17:49.221660 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-02-22 00:17:49.255571 | controller | skipping: Conditional result was False 2026-02-22 00:17:49.268102 | 2026-02-22 00:17:49.268213 | TASK [include_role : remove-zuul-sshkey] 2026-02-22 00:17:49.292008 | controller | skipping: Conditional result was False 2026-02-22 00:17:49.300615 | 2026-02-22 00:17:49.300745 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-02-22 00:17:49.969454 | controller | ok: "logs" 2026-02-22 00:17:49.969744 | controller | ok: All items complete 2026-02-22 00:17:49.969796 | 2026-02-22 00:17:50.596445 | controller | ok: "artifacts" 2026-02-22 00:17:51.220107 | controller | ok: "docs" 2026-02-22 00:17:51.231749 | 2026-02-22 00:17:51.231859 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-02-22 00:17:51.914454 | controller | changed: "logs" 2026-02-22 00:17:52.528961 | controller | changed: "artifacts" 2026-02-22 00:17:53.155928 | controller | changed: "docs" 2026-02-22 00:17:53.186048 | 2026-02-22 00:17:53.186154 | PLAY RECAP 2026-02-22 00:17:53.186197 | controller | ok: 30 changed: 16 unreachable: 0 failed: 0 skipped: 18 rescued: 0 ignored: 0 2026-02-22 00:17:53.186224 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-02-22 00:17:53.186242 | 2026-02-22 00:17:53.310371 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master] 2026-02-22 00:17:53.312113 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2026-02-22 00:17:53.917015 | 2026-02-22 00:17:53.917138 | PLAY [all] 2026-02-22 00:17:53.941577 | 2026-02-22 00:17:53.941771 | TASK [Install binary dependencies] 2026-02-22 00:17:54.026065 | controller | ok 2026-02-22 00:17:54.057295 | 2026-02-22 00:17:54.057510 | TASK [bindep : Include find tasks] 2026-02-22 00:17:54.091007 | controller | ok 2026-02-22 00:17:54.101848 | controller | included: /var/lib/zuul/builds/0e3516dbf5ff4fd1bbb7711af5858b1d/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find.yaml 2026-02-22 00:17:54.111127 | 2026-02-22 00:17:54.111291 | TASK [bindep : Look for bindep.txt] 2026-02-22 00:17:55.036358 | controller | ok 2026-02-22 00:17:55.050033 | 2026-02-22 00:17:55.050215 | TASK [bindep : Define bindep_file fact] 2026-02-22 00:17:55.077461 | controller | skipping: Conditional result was False 2026-02-22 00:17:55.083698 | 2026-02-22 00:17:55.083793 | TASK [bindep : Look for other-requirements.txt] 2026-02-22 00:17:55.716621 | controller | ok 2026-02-22 00:17:55.725042 | 2026-02-22 00:17:55.725152 | TASK [bindep : Define bindep_file fact] 2026-02-22 00:17:55.751721 | controller | skipping: Conditional result was False 2026-02-22 00:17:55.759974 | 2026-02-22 00:17:55.760070 | TASK [bindep : Look for bindep fallback file] 2026-02-22 00:17:55.794766 | controller | skipping: Conditional result was False 2026-02-22 00:17:55.802916 | 2026-02-22 00:17:55.803062 | TASK [bindep : Define bindep_file fact] 2026-02-22 00:17:55.827428 | controller | skipping: Conditional result was False 2026-02-22 00:17:55.834710 | 2026-02-22 00:17:55.834857 | TASK [bindep : Include bindep tasks] 2026-02-22 00:17:55.859006 | controller | skipping: Conditional result was False 2026-02-22 00:17:55.865426 | 2026-02-22 00:17:55.865522 | TASK [bindep : Include install tasks] 2026-02-22 00:17:55.889684 | controller | skipping: Conditional result was False 2026-02-22 00:17:55.898670 | 2026-02-22 00:17:55.898802 | LOOP [bindep : Include package tasks] 2026-02-22 00:17:55.967794 | 2026-02-22 00:17:55.968074 | TASK [Run test-setup role] 2026-02-22 00:17:55.989624 | controller | ok 2026-02-22 00:17:56.007775 | 2026-02-22 00:17:56.007907 | TASK [test-setup : Check if project's tools/test-setup.sh exists] 2026-02-22 00:17:56.661795 | controller | ok 2026-02-22 00:17:56.675210 | 2026-02-22 00:17:56.675355 | TASK [test-setup : Run tools/test-setup.sh] 2026-02-22 00:17:57.047584 | controller | skipping: Conditional result was False 2026-02-22 00:17:57.090628 | 2026-02-22 00:17:57.090782 | PLAY RECAP 2026-02-22 00:17:57.090886 | controller | ok: 4 changed: 0 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2026-02-22 00:17:57.091106 | 2026-02-22 00:17:57.204743 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2026-02-22 00:17:57.205960 | PRE-RUN START: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master] 2026-02-22 00:17:57.837487 | 2026-02-22 00:17:57.837623 | PLAY [controller] 2026-02-22 00:17:57.858738 | 2026-02-22 00:17:57.858865 | TASK [Create the /root directory] 2026-02-22 00:17:58.931512 | controller | ok 2026-02-22 00:17:58.946312 | 2026-02-22 00:17:58.946571 | TASK [Install glibc-langpack-en] 2026-02-22 00:18:07.336365 | controller | ok: Nothing to do 2026-02-22 00:18:07.353250 | 2026-02-22 00:18:07.353399 | TASK [Ensure controller directory exists] 2026-02-22 00:18:08.160551 | controller | changed 2026-02-22 00:18:08.174117 | 2026-02-22 00:18:08.174288 | TASK [Install container runtime] 2026-02-22 00:18:08.245891 | controller | ok 2026-02-22 00:18:08.310354 | 2026-02-22 00:18:08.310466 | LOOP [ensure-docker : Gather variables for each operating system] 2026-02-22 00:18:08.352438 | controller | ok: "/var/lib/zuul/builds/0e3516dbf5ff4fd1bbb7711af5858b1d/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/vars/fedora.yaml" 2026-02-22 00:18:08.360452 | 2026-02-22 00:18:08.360520 | TASK [ensure-docker : Sanity check for distro selection] 2026-02-22 00:18:08.384367 | controller | skipping: Conditional result was False 2026-02-22 00:18:08.391530 | 2026-02-22 00:18:08.391619 | TASK [ensure-docker : Sanity check for distro install] 2026-02-22 00:18:08.415406 | controller | skipping: Conditional result was False 2026-02-22 00:18:08.422055 | 2026-02-22 00:18:08.422141 | TASK [ensure-docker : Sanity check for upstream install] 2026-02-22 00:18:08.445900 | controller | skipping: Conditional result was False 2026-02-22 00:18:08.452110 | 2026-02-22 00:18:08.452182 | TASK [ensure-docker : Sanity check for upstream install mirrors] 2026-02-22 00:18:08.477778 | controller | skipping: Conditional result was False 2026-02-22 00:18:08.486356 | 2026-02-22 00:18:08.486652 | TASK [ensure-docker : Include tasks for docker install] 2026-02-22 00:18:08.509505 | controller | ok 2026-02-22 00:18:08.518712 | controller | included: /var/lib/zuul/builds/0e3516dbf5ff4fd1bbb7711af5858b1d/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/tasks/docker-upstream.yaml 2026-02-22 00:18:08.528381 | 2026-02-22 00:18:08.528454 | TASK [ensure-docker : Not Implemented] 2026-02-22 00:18:08.553851 | controller | skipping: Conditional result was False 2026-02-22 00:18:08.564482 | 2026-02-22 00:18:08.564612 | TASK [Set up docker mirrors] 2026-02-22 00:18:08.590038 | controller | ok 2026-02-22 00:18:08.616031 | 2026-02-22 00:18:08.616138 | TASK [use-docker-mirror : Set docker_mirror fact from mirror_info] 2026-02-22 00:18:08.652272 | controller | skipping: Conditional result was False 2026-02-22 00:18:08.664428 | 2026-02-22 00:18:08.664534 | TASK [use-docker-mirror : Set docker_mirror fact from zuul_site_mirror_info] 2026-02-22 00:18:08.762962 | controller | skipping: Conditional result was False 2026-02-22 00:18:08.816212 | 2026-02-22 00:18:08.816554 | TASK [use-docker-mirror : Set docker_mirror fact] 2026-02-22 00:18:08.852164 | controller | skipping: Conditional result was False 2026-02-22 00:18:08.859079 | 2026-02-22 00:18:08.859237 | TASK [use-docker-mirror : Set up docker mirrors] 2026-02-22 00:18:08.883274 | controller | skipping: Conditional result was False 2026-02-22 00:18:08.895932 | 2026-02-22 00:18:08.896076 | TASK [Add all repositories] 2026-02-22 00:18:08.916387 | controller | ok 2026-02-22 00:18:08.944330 | 2026-02-22 00:18:08.944465 | LOOP [ensure-package-repositories : Install repositories] 2026-02-22 00:18:08.966686 | controller | ok: "/var/lib/zuul/builds/0e3516dbf5ff4fd1bbb7711af5858b1d/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-package-repositories/tasks/RedHat.yaml" 2026-02-22 00:18:08.966897 | controller | ok: All items complete 2026-02-22 00:18:08.966935 | 2026-02-22 00:18:08.978446 | controller | included: /var/lib/zuul/builds/0e3516dbf5ff4fd1bbb7711af5858b1d/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-package-repositories/tasks/RedHat.yaml 2026-02-22 00:18:08.985629 | 2026-02-22 00:18:08.985749 | TASK [ensure-package-repositories : Create GPG key temp directory] 2026-02-22 00:18:09.921192 | controller | changed 2026-02-22 00:18:09.935519 | 2026-02-22 00:18:09.935790 | LOOP [ensure-package-repositories : Upload GPG keys] 2026-02-22 00:18:13.199696 | controller | changed: 2026-02-22 00:18:13.199920 | controller | { 2026-02-22 00:18:13.199956 | controller | "data": "-----BEGIN PGP PUBLIC KEY BLOCK-----\n\nmQINBFit5IEBEADDt86QpYKz5flnCsOyZ/fk3WwBKxfDjwHf/GIflo+4GWAXS7wJ\n1PSzPsvSDATV10J44i5WQzh99q+lZvFCVRFiNhRmlmcXG+rk1QmDh3fsCCj9Q/yP\nw8jn3Hx0zDtz8PIB/18ReftYJzUo34COLiHn8WiY20uGCF2pjdPgfxE+K454c4G7\ngKFqVUFYgPug2CS0quaBB5b0rpFUdzTeI5RCStd27nHCpuSDCvRYAfdv+4Y1yiVh\nKKdoe3Smj+RnXeVMgDxtH9FJibZ3DK7WnMN2yeob6VqXox+FvKYJCCLkbQgQmE50\nuVK0uN71A1mQDcTRKQ2q3fFGlMTqJbbzr3LwnCBE6hV0a36t+DABtZTmz5O69xdJ\nWGdBeePCnWVqtDb/BdEYz7hPKskcZBarygCCe2Xi7sZieoFZuq6ltPoCsdfEdfbO\n+VBVKJnExqNZCcFUTEnbH4CldWROOzMS8BGUlkGpa59Sl1t0QcmWlw1EbkeMQNrN\nspdR8lobcdNS9bpAJQqSHRZh3cAM9mA3Yq/bssUS/P2quRXLjJ9mIv3dky9C3udM\n+q2unvnbNpPtIUly76FJ3s8g8sHeOnmYcKqNGqHq2Q3kMdA2eIbI0MqfOIo2+Xk0\nrNt3ctq3g+cQiorcN3rdHPsTRSAcp+NCz1QF9TwXYtH1XV24A6QMO0+CZwARAQAB\ntCtEb2NrZXIgUmVsZWFzZSAoQ0UgcnBtKSA8ZG9ja2VyQGRvY2tlci5jb20+iQI3\nBBMBCgAhBQJYrep4AhsvBQsJCAcDBRUKCQgLBRYCAwEAAh4BAheAAAoJEMUv62ti\nHp816C0P/iP+1uhSa6Qq3TIc5sIFE5JHxOO6y0R97cUdAmCbEqBiJHUPNQDQaaRG\nVYBm0K013Q1gcJeUJvS32gthmIvhkstw7KTodwOM8Kl11CCqZ07NPFef1b2SaJ7l\nTYpyUsT9+e343ph+O4C1oUQw6flaAJe+8ATCmI/4KxfhIjD2a/Q1voR5tUIxfexC\n/LZTx05gyf2mAgEWlRm/cGTStNfqDN1uoKMlV+WFuB1j2oTUuO1/dr8mL+FgZAM3\nntWFo9gQCllNV9ahYOON2gkoZoNuPUnHsf4Bj6BQJnIXbAhMk9H2sZzwUi9bgObZ\nXO8+OrP4D4B9kCAKqqaQqA+O46LzO2vhN74lm/Fy6PumHuviqDBdN+HgtRPMUuao\nxnuVJSvBu9sPdgT/pR1N9u/KnfAnnLtR6g+fx4mWz+ts/riB/KRHzXd+44jGKZra\nIhTMfniguMJNsyEOO0AN8Tqcl0eRBxcOArcri7xu8HFvvl+e+ILymu4buusbYEVL\nGBkYP5YMmScfKn+jnDVN4mWoN1Bq2yMhMGx6PA3hOvzPNsUoYy2BwDxNZyflzuAi\ng59mgJm2NXtzNbSRJbMamKpQ69mzLWGdFNsRd4aH7PT7uPAURaf7B5BVp3UyjERW\n5alSGnBqsZmvlRnVH5BDUhYsWZMPRQS9rRr4iGW0l+TH+O2VJ8aQ\n=0Zqq\n-----END PGP PUBLIC KEY BLOCK-----\n" 2026-02-22 00:18:13.199984 | controller | } 2026-02-22 00:18:13.215605 | 2026-02-22 00:18:13.215794 | LOOP [ensure-package-repositories : Import GPG keys] 2026-02-22 00:18:14.460353 | controller | changed: 2026-02-22 00:18:14.460535 | controller | { 2026-02-22 00:18:14.460571 | controller | "data": "-----BEGIN PGP PUBLIC KEY BLOCK-----\n\nmQINBFit5IEBEADDt86QpYKz5flnCsOyZ/fk3WwBKxfDjwHf/GIflo+4GWAXS7wJ\n1PSzPsvSDATV10J44i5WQzh99q+lZvFCVRFiNhRmlmcXG+rk1QmDh3fsCCj9Q/yP\nw8jn3Hx0zDtz8PIB/18ReftYJzUo34COLiHn8WiY20uGCF2pjdPgfxE+K454c4G7\ngKFqVUFYgPug2CS0quaBB5b0rpFUdzTeI5RCStd27nHCpuSDCvRYAfdv+4Y1yiVh\nKKdoe3Smj+RnXeVMgDxtH9FJibZ3DK7WnMN2yeob6VqXox+FvKYJCCLkbQgQmE50\nuVK0uN71A1mQDcTRKQ2q3fFGlMTqJbbzr3LwnCBE6hV0a36t+DABtZTmz5O69xdJ\nWGdBeePCnWVqtDb/BdEYz7hPKskcZBarygCCe2Xi7sZieoFZuq6ltPoCsdfEdfbO\n+VBVKJnExqNZCcFUTEnbH4CldWROOzMS8BGUlkGpa59Sl1t0QcmWlw1EbkeMQNrN\nspdR8lobcdNS9bpAJQqSHRZh3cAM9mA3Yq/bssUS/P2quRXLjJ9mIv3dky9C3udM\n+q2unvnbNpPtIUly76FJ3s8g8sHeOnmYcKqNGqHq2Q3kMdA2eIbI0MqfOIo2+Xk0\nrNt3ctq3g+cQiorcN3rdHPsTRSAcp+NCz1QF9TwXYtH1XV24A6QMO0+CZwARAQAB\ntCtEb2NrZXIgUmVsZWFzZSAoQ0UgcnBtKSA8ZG9ja2VyQGRvY2tlci5jb20+iQI3\nBBMBCgAhBQJYrep4AhsvBQsJCAcDBRUKCQgLBRYCAwEAAh4BAheAAAoJEMUv62ti\nHp816C0P/iP+1uhSa6Qq3TIc5sIFE5JHxOO6y0R97cUdAmCbEqBiJHUPNQDQaaRG\nVYBm0K013Q1gcJeUJvS32gthmIvhkstw7KTodwOM8Kl11CCqZ07NPFef1b2SaJ7l\nTYpyUsT9+e343ph+O4C1oUQw6flaAJe+8ATCmI/4KxfhIjD2a/Q1voR5tUIxfexC\n/LZTx05gyf2mAgEWlRm/cGTStNfqDN1uoKMlV+WFuB1j2oTUuO1/dr8mL+FgZAM3\nntWFo9gQCllNV9ahYOON2gkoZoNuPUnHsf4Bj6BQJnIXbAhMk9H2sZzwUi9bgObZ\nXO8+OrP4D4B9kCAKqqaQqA+O46LzO2vhN74lm/Fy6PumHuviqDBdN+HgtRPMUuao\nxnuVJSvBu9sPdgT/pR1N9u/KnfAnnLtR6g+fx4mWz+ts/riB/KRHzXd+44jGKZra\nIhTMfniguMJNsyEOO0AN8Tqcl0eRBxcOArcri7xu8HFvvl+e+ILymu4buusbYEVL\nGBkYP5YMmScfKn+jnDVN4mWoN1Bq2yMhMGx6PA3hOvzPNsUoYy2BwDxNZyflzuAi\ng59mgJm2NXtzNbSRJbMamKpQ69mzLWGdFNsRd4aH7PT7uPAURaf7B5BVp3UyjERW\n5alSGnBqsZmvlRnVH5BDUhYsWZMPRQS9rRr4iGW0l+TH+O2VJ8aQ\n=0Zqq\n-----END PGP PUBLIC KEY BLOCK-----\n" 2026-02-22 00:18:14.460599 | controller | } 2026-02-22 00:18:14.460636 | controller | changed: All items complete 2026-02-22 00:18:14.460658 | 2026-02-22 00:18:14.477111 | 2026-02-22 00:18:14.477261 | TASK [ensure-package-repositories : Remove GPG temp directory] 2026-02-22 00:18:15.159123 | controller | changed 2026-02-22 00:18:15.166452 | 2026-02-22 00:18:15.166536 | LOOP [ensure-package-repositories : Add repositories] 2026-02-22 00:18:16.019459 | controller | changed: 2026-02-22 00:18:16.019943 | controller | { 2026-02-22 00:18:16.020028 | controller | "baseurl": "https://download.docker.com/linux/fedora/$releasever/$basearch/stable", 2026-02-22 00:18:16.020085 | controller | "description": "Docker CE Stable - $basearch", 2026-02-22 00:18:16.020142 | controller | "gpgcheck": true, 2026-02-22 00:18:16.020184 | controller | "name": "docker-ce-stable" 2026-02-22 00:18:16.020221 | controller | } 2026-02-22 00:18:16.060626 | 2026-02-22 00:18:16.060893 | TASK [ensure-docker : Install pre-reqs] 2026-02-22 00:18:21.220870 | controller | ok: Nothing to do 2026-02-22 00:18:21.234441 | 2026-02-22 00:18:21.234617 | TASK [ensure-docker : Remove packages] 2026-02-22 00:18:24.542151 | controller | ok: Nothing to do 2026-02-22 00:18:24.556352 | 2026-02-22 00:18:24.557066 | TASK [ensure-docker : Install upstream docker using package] 2026-02-22 00:19:54.672543 | controller | changed 2026-02-22 00:19:54.691852 | 2026-02-22 00:19:54.692025 | TASK [ensure-docker : Setup docker] 2026-02-22 00:19:54.725926 | controller | ok 2026-02-22 00:19:54.742312 | controller | included: /var/lib/zuul/builds/0e3516dbf5ff4fd1bbb7711af5858b1d/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/tasks/docker-setup.yaml 2026-02-22 00:19:54.759908 | 2026-02-22 00:19:54.760037 | TASK [ensure-docker : Ensure "docker" group exists] 2026-02-22 00:19:55.813089 | controller | ok 2026-02-22 00:19:55.858190 | 2026-02-22 00:19:55.858313 | TASK [ensure-docker : Add user to docker group] 2026-02-22 00:19:56.930890 | controller | changed 2026-02-22 00:19:56.950775 | 2026-02-22 00:19:56.950926 | TASK [Update docker daemon proxy configuration] 2026-02-22 00:19:56.975994 | controller | skipping: Conditional result was False 2026-02-22 00:19:56.983148 | 2026-02-22 00:19:56.983258 | TASK [Lower default MTU] 2026-02-22 00:19:56.997163 | controller | skipping: Conditional result was False 2026-02-22 00:19:57.008946 | 2026-02-22 00:19:57.009094 | TASK [ensure-docker : Get the status of the docket.socket unit] 2026-02-22 00:19:57.748149 | controller | inactive 2026-02-22 00:19:57.877855 | controller | ok: Runtime: 0:00:00.017811 2026-02-22 00:19:57.890146 | 2026-02-22 00:19:57.890255 | TASK [ensure-docker : Get the status of the docket unit] 2026-02-22 00:19:58.577095 | controller | inactive 2026-02-22 00:19:58.745003 | controller | ok: Runtime: 0:00:00.025210 2026-02-22 00:19:58.760330 | 2026-02-22 00:19:58.760494 | TASK [ensure-docker : Restart docker] 2026-02-22 00:19:58.806511 | Trigger a Docker restart 2026-02-22 00:19:58.815091 | 2026-02-22 00:19:58.815299 | TASK [ensure-docker : Reset ssh connection to pick up docker group] 2026-02-22 00:19:58.847340 | 2026-02-22 00:19:58.847463 | TASK [ensure-docker : Install docker-compose] 2026-02-22 00:19:58.862158 | controller | skipping: Conditional result was False 2026-02-22 00:19:58.869168 | 2026-02-22 00:19:58.869291 | TASK [ensure-docker : Flush handlers before role exit] 2026-02-22 00:19:58.883572 | 2026-02-22 00:19:58.883695 | TASK [ensure-docker : Stop docker.socket to avoid any conflict] 2026-02-22 00:20:02.353473 | controller | changed 2026-02-22 00:20:02.362298 | 2026-02-22 00:20:02.362405 | TASK [ensure-docker : Assure docker service is running] 2026-02-22 00:20:06.166290 | controller | changed 2026-02-22 00:20:06.177828 | 2026-02-22 00:20:06.177947 | TASK [ensure-docker : Assure docker.socket service is running] 2026-02-22 00:20:07.996919 | controller | ok 2026-02-22 00:20:08.016258 | 2026-02-22 00:20:08.016567 | TASK [ensure-docker : Correct group ownership on docker sock] 2026-02-22 00:20:08.924460 | controller | ok 2026-02-22 00:20:08.934332 | 2026-02-22 00:20:08.934550 | TASK [ensure-docker : Validate ability to talk with docker] 2026-02-22 00:20:09.568842 | controller | CONTAINER ID IMAGE COMMAND CREATED STATUS PORTS NAMES 2026-02-22 00:20:09.811596 | controller | ok: Runtime: 0:00:00.043728 2026-02-22 00:20:09.821015 | 2026-02-22 00:20:09.821111 | TASK [ensure-docker : Get version details] 2026-02-22 00:20:10.517360 | controller | Client: Docker Engine - Community 2026-02-22 00:20:10.518769 | controller | Version: 24.0.7 2026-02-22 00:20:10.518788 | controller | API version: 1.43 2026-02-22 00:20:10.518795 | controller | Go version: go1.20.10 2026-02-22 00:20:10.518813 | controller | Git commit: afdd53b 2026-02-22 00:20:10.518821 | controller | Built: Thu Oct 26 09:09:39 2023 2026-02-22 00:20:10.518828 | controller | OS/Arch: linux/amd64 2026-02-22 00:20:10.518837 | controller | Context: default 2026-02-22 00:20:10.518846 | controller | 2026-02-22 00:20:10.518858 | controller | Server: Docker Engine - Community 2026-02-22 00:20:10.518866 | controller | Engine: 2026-02-22 00:20:10.518872 | controller | Version: 24.0.7 2026-02-22 00:20:10.518881 | controller | API version: 1.43 (minimum version 1.12) 2026-02-22 00:20:10.518946 | controller | Go version: go1.20.10 2026-02-22 00:20:10.518954 | controller | Git commit: 311b9ff 2026-02-22 00:20:10.518961 | controller | Built: Thu Oct 26 09:08:11 2023 2026-02-22 00:20:10.518980 | controller | OS/Arch: linux/amd64 2026-02-22 00:20:10.518987 | controller | Experimental: false 2026-02-22 00:20:10.518994 | controller | containerd: 2026-02-22 00:20:10.519004 | controller | Version: 1.6.25 2026-02-22 00:20:10.520721 | controller | GitCommit: d8f198a4ed8892c764191ef7b3b06d8a2eeb5c7f 2026-02-22 00:20:10.520744 | controller | runc: 2026-02-22 00:20:10.520751 | controller | Version: 1.1.10 2026-02-22 00:20:10.520757 | controller | GitCommit: v1.1.10-0-g18a0cb0 2026-02-22 00:20:10.520773 | controller | docker-init: 2026-02-22 00:20:10.520784 | controller | Version: 0.19.0 2026-02-22 00:20:10.520795 | controller | GitCommit: de40ad0 2026-02-22 00:20:10.715531 | controller | ok: Runtime: 0:00:00.049052 2026-02-22 00:20:10.726900 | 2026-02-22 00:20:10.727029 | TASK [ensure-docker : Dump installed docker details] 2026-02-22 00:20:10.774364 | {'changed': True, 'stdout': 'Client: Docker Engine - Community\n Version: 24.0.7\n API version: 1.43\n Go version: go1.20.10\n Git commit: afdd53b\n Built: Thu Oct 26 09:09:39 2023\n OS/Arch: linux/amd64\n Context: default\n\nServer: Docker Engine - Community\n Engine:\n Version: 24.0.7\n API version: 1.43 (minimum version 1.12)\n Go version: go1.20.10\n Git commit: 311b9ff\n Built: Thu Oct 26 09:08:11 2023\n OS/Arch: linux/amd64\n Experimental: false\n containerd:\n Version: 1.6.25\n GitCommit: d8f198a4ed8892c764191ef7b3b06d8a2eeb5c7f\n runc:\n Version: 1.1.10\n GitCommit: v1.1.10-0-g18a0cb0\n docker-init:\n Version: 0.19.0\n GitCommit: de40ad0', 'stderr': '', 'rc': 0, 'cmd': ['docker', 'version'], 'start': '2026-02-22 00:20:10.472158', 'end': '2026-02-22 00:20:10.521210', 'delta': '0:00:00.049052', 'msg': '', 'zuul_log_id': 'fa163ef9-e89a-1573-49a8-000000000061-1-controller', 'stdout_lines': ['Client: Docker Engine - Community', ' Version: 24.0.7', ' API version: 1.43', ' Go version: go1.20.10', ' Git commit: afdd53b', ' Built: Thu Oct 26 09:09:39 2023', ' OS/Arch: linux/amd64', ' Context: default', '', 'Server: Docker Engine - Community', ' Engine:', ' Version: 24.0.7', ' API version: 1.43 (minimum version 1.12)', ' Go version: go1.20.10', ' Git commit: 311b9ff', ' Built: Thu Oct 26 09:08:11 2023', ' OS/Arch: linux/amd64', ' Experimental: false', ' containerd:', ' Version: 1.6.25', ' GitCommit: d8f198a4ed8892c764191ef7b3b06d8a2eeb5c7f', ' runc:', ' Version: 1.1.10', ' GitCommit: v1.1.10-0-g18a0cb0', ' docker-init:', ' Version: 0.19.0', ' GitCommit: de40ad0'], 'stderr_lines': [], 'failed': False} 2026-02-22 00:20:10.782900 | 2026-02-22 00:20:10.783097 | TASK [Use buildset registry] 2026-02-22 00:20:10.811308 | controller | skipping: Conditional result was False 2026-02-22 00:20:10.825141 | 2026-02-22 00:20:10.825302 | TASK [Ensure python3.8 is present] 2026-02-22 00:20:10.850933 | controller | skipping: Conditional result was False 2026-02-22 00:20:10.860901 | 2026-02-22 00:20:10.861079 | TASK [Run our-ensure-python role (Fedora only for now)] 2026-02-22 00:20:10.884787 | controller | ok 2026-02-22 00:20:10.914183 | 2026-02-22 00:20:10.914589 | TASK [our-ensure-python : Install the right Python version (rpm)] 2026-02-22 00:20:16.960925 | controller | ok: Nothing to do 2026-02-22 00:20:16.975898 | 2026-02-22 00:20:16.976086 | TASK [our-ensure-python : Also install python3-devel] 2026-02-22 00:20:33.407049 | controller | changed 2026-02-22 00:20:33.418007 | 2026-02-22 00:20:33.418073 | TASK [Run ensure-virtualenv role] 2026-02-22 00:20:33.438376 | controller | ok 2026-02-22 00:20:33.461952 | 2026-02-22 00:20:33.462070 | TASK [ensure-virtualenv : Check if virtualenv is installed] 2026-02-22 00:20:34.203517 | controller | /usr/bin/virtualenv 2026-02-22 00:20:34.818368 | controller | ok: Runtime: 0:00:00.004453 2026-02-22 00:20:34.828523 | 2026-02-22 00:20:34.828657 | LOOP [ensure-virtualenv : Install virtualenv package] 2026-02-22 00:20:34.855677 | controller | skipping: Conditional result was False 2026-02-22 00:20:34.856104 | controller | changed: All items complete 2026-02-22 00:20:34.856163 | 2026-02-22 00:20:34.885755 | 2026-02-22 00:20:34.885907 | TASK [Find the full path of the Python interpreter] 2026-02-22 00:20:35.669323 | controller | /usr/bin/python3.9 2026-02-22 00:20:36.255373 | controller | ok 2026-02-22 00:20:36.263512 | 2026-02-22 00:20:36.263611 | TASK [Create virtualenv for ansible-test with a specific interpreter] 2026-02-22 00:20:38.726516 | controller | created virtual environment CPython3.9.18.final.0-64 in 1256ms 2026-02-22 00:20:38.844819 | controller | creator CPython3Posix(dest=/home/zuul/venv, clear=False, no_vcs_ignore=False, global=False) 2026-02-22 00:20:38.844947 | controller | seeder FromAppData(extra_search_dir=/usr/share/python-wheels,download=False, pip=bundle, setuptools=bundle, wheel=bundle, via=copy, app_data_dir=/home/zuul/.local/share/virtualenv) 2026-02-22 00:20:38.844964 | controller | added seed packages: pip==22.2.2, setuptools==62.6.0, wheel==0.37.1 2026-02-22 00:20:38.844982 | controller | activators BashActivator,CShellActivator,FishActivator,NushellActivator,PowerShellActivator,PythonActivator 2026-02-22 00:20:39.154395 | controller | changed 2026-02-22 00:20:39.160745 | 2026-02-22 00:20:39.160842 | TASK [Set selinux package] 2026-02-22 00:20:39.181656 | controller | ok 2026-02-22 00:20:39.187339 | 2026-02-22 00:20:39.187433 | TASK [Set selinux package (Fedora)] 2026-02-22 00:20:39.217802 | controller | ok 2026-02-22 00:20:39.223603 | 2026-02-22 00:20:39.223706 | TASK [Install selinux into virtualenv] 2026-02-22 00:20:44.630424 | controller | Collecting selinux-please-lie-to-me 2026-02-22 00:20:44.778838 | controller | Downloading selinux_please_lie_to_me-1.0.1-py2.py3-none-any.whl (3.8 kB) 2026-02-22 00:20:46.077671 | controller | Collecting setuptools<50.0.0 2026-02-22 00:20:46.089820 | controller | Downloading setuptools-49.6.0-py3-none-any.whl (803 kB) 2026-02-22 00:20:46.188667 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 803.3/803.3 kB 9.3 MB/s eta 0:00:00 2026-02-22 00:20:46.407210 | controller | Installing collected packages: setuptools, selinux-please-lie-to-me 2026-02-22 00:20:46.407591 | controller | Attempting uninstall: setuptools 2026-02-22 00:20:46.408757 | controller | Found existing installation: setuptools 62.6.0 2026-02-22 00:20:46.561367 | controller | Uninstalling setuptools-62.6.0: 2026-02-22 00:20:46.579501 | controller | Successfully uninstalled setuptools-62.6.0 2026-02-22 00:20:47.452987 | controller | Successfully installed selinux-please-lie-to-me-1.0.1 setuptools-49.6.0 2026-02-22 00:20:47.688813 | controller | 2026-02-22 00:20:47.996077 | controller | [notice] A new release of pip available: 22.2.2 -> 26.0.1 2026-02-22 00:20:47.996130 | controller | [notice] To update, run: /home/zuul/venv/bin/python -m pip install --upgrade pip 2026-02-22 00:20:48.159343 | controller | ok: Runtime: 0:00:07.739523 2026-02-22 00:20:48.167343 | 2026-02-22 00:20:48.167443 | TASK [Install pytest-forked into virtualenv] 2026-02-22 00:20:49.978508 | controller | Collecting pytest-forked 2026-02-22 00:20:50.047178 | controller | Downloading pytest_forked-1.6.0-py3-none-any.whl (4.9 kB) 2026-02-22 00:20:50.271870 | controller | Collecting pytest>=3.10 2026-02-22 00:20:50.277689 | controller | Downloading pytest-8.4.2-py3-none-any.whl (365 kB) 2026-02-22 00:20:50.334346 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 365.8/365.8 kB 6.8 MB/s eta 0:00:00 2026-02-22 00:20:50.392017 | controller | Collecting py 2026-02-22 00:20:50.397011 | controller | Downloading py-1.11.0-py2.py3-none-any.whl (98 kB) 2026-02-22 00:20:50.410254 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 98.7/98.7 kB 9.1 MB/s eta 0:00:00 2026-02-22 00:20:50.518941 | controller | Collecting packaging>=20 2026-02-22 00:20:50.524575 | controller | Downloading packaging-26.0-py3-none-any.whl (74 kB) 2026-02-22 00:20:50.536877 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 74.4/74.4 kB 7.3 MB/s eta 0:00:00 2026-02-22 00:20:50.567692 | controller | Collecting iniconfig>=1 2026-02-22 00:20:50.575456 | controller | Downloading iniconfig-2.1.0-py3-none-any.whl (6.0 kB) 2026-02-22 00:20:50.642457 | controller | Collecting exceptiongroup>=1 2026-02-22 00:20:50.649582 | controller | Downloading exceptiongroup-1.3.1-py3-none-any.whl (16 kB) 2026-02-22 00:20:50.761130 | controller | Collecting pygments>=2.7.2 2026-02-22 00:20:50.765846 | controller | Downloading pygments-2.19.2-py3-none-any.whl (1.2 MB) 2026-02-22 00:20:50.793978 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 1.2/1.2 MB 49.5 MB/s eta 0:00:00 2026-02-22 00:20:50.888804 | controller | Collecting tomli>=1 2026-02-22 00:20:50.893430 | controller | Downloading tomli-2.4.0-py3-none-any.whl (14 kB) 2026-02-22 00:20:50.952721 | controller | Collecting pluggy<2,>=1.5 2026-02-22 00:20:50.958152 | controller | Downloading pluggy-1.6.0-py3-none-any.whl (20 kB) 2026-02-22 00:20:51.046207 | controller | Collecting typing-extensions>=4.6.0 2026-02-22 00:20:51.053116 | controller | Downloading typing_extensions-4.15.0-py3-none-any.whl (44 kB) 2026-02-22 00:20:51.065188 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 44.6/44.6 kB 4.5 MB/s eta 0:00:00 2026-02-22 00:20:51.191908 | controller | Installing collected packages: typing-extensions, tomli, pygments, py, pluggy, packaging, iniconfig, exceptiongroup, pytest, pytest-forked 2026-02-22 00:20:53.584502 | controller | Successfully installed exceptiongroup-1.3.1 iniconfig-2.1.0 packaging-26.0 pluggy-1.6.0 py-1.11.0 pygments-2.19.2 pytest-8.4.2 pytest-forked-1.6.0 tomli-2.4.0 typing-extensions-4.15.0 2026-02-22 00:20:53.594208 | controller | 2026-02-22 00:20:53.823335 | controller | [notice] A new release of pip available: 22.2.2 -> 26.0.1 2026-02-22 00:20:53.823393 | controller | [notice] To update, run: /home/zuul/venv/bin/python -m pip install --upgrade pip 2026-02-22 00:20:54.033719 | controller | ok: Runtime: 0:00:04.653895 2026-02-22 00:20:54.047182 | 2026-02-22 00:20:54.047336 | TASK [Update pip] 2026-02-22 00:20:56.240303 | controller | Requirement already satisfied: pip in ./venv/lib/python3.9/site-packages (22.2.2) 2026-02-22 00:20:56.597834 | controller | Collecting pip 2026-02-22 00:20:56.740478 | controller | Downloading pip-26.0.1-py3-none-any.whl (1.8 MB) 2026-02-22 00:20:56.878179 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 1.8/1.8 MB 14.7 MB/s eta 0:00:00 2026-02-22 00:20:57.044753 | controller | Installing collected packages: pip 2026-02-22 00:20:57.046155 | controller | Attempting uninstall: pip 2026-02-22 00:20:57.046175 | controller | Found existing installation: pip 22.2.2 2026-02-22 00:20:57.569802 | controller | Uninstalling pip-22.2.2: 2026-02-22 00:20:57.644844 | controller | Successfully uninstalled pip-22.2.2 2026-02-22 00:20:59.649398 | controller | Successfully installed pip-26.0.1 2026-02-22 00:21:00.429645 | controller | ok: Runtime: 0:00:04.786837 2026-02-22 00:21:00.444338 | 2026-02-22 00:21:00.444496 | TASK [Ensure pip temp directory exists in /var/tmp] 2026-02-22 00:21:01.589927 | controller | changed 2026-02-22 00:21:01.595762 | 2026-02-22 00:21:01.595867 | TASK [Install ansible into virtualenv] 2026-02-22 00:21:03.603572 | controller | Processing ./src/github.com/ansible/ansible 2026-02-22 00:21:03.620302 | controller | Installing build dependencies: started 2026-02-22 00:21:05.448953 | controller | Installing build dependencies: finished with status 'done' 2026-02-22 00:21:06.801634 | controller | Getting requirements to build wheel: started 2026-02-22 00:21:06.801716 | controller | Getting requirements to build wheel: finished with status 'done' 2026-02-22 00:21:06.804300 | controller | Preparing metadata (pyproject.toml): started 2026-02-22 00:21:07.729312 | controller | Preparing metadata (pyproject.toml): finished with status 'done' 2026-02-22 00:21:07.845441 | controller | Collecting jinja2 (from ansible-core==2.11.12.post0) 2026-02-22 00:21:07.906519 | controller | Downloading jinja2-3.1.6-py3-none-any.whl.metadata (2.9 kB) 2026-02-22 00:21:08.024872 | controller | Collecting PyYAML (from ansible-core==2.11.12.post0) 2026-02-22 00:21:08.035306 | controller | Downloading pyyaml-6.0.3-cp39-cp39-manylinux2014_x86_64.manylinux_2_17_x86_64.manylinux_2_28_x86_64.whl.metadata (2.4 kB) 2026-02-22 00:21:08.577323 | controller | Collecting cryptography (from ansible-core==2.11.12.post0) 2026-02-22 00:21:08.583627 | controller | Downloading cryptography-46.0.5-cp38-abi3-manylinux_2_34_x86_64.whl.metadata (5.7 kB) 2026-02-22 00:21:08.602583 | controller | Requirement already satisfied: packaging in ./venv/lib/python3.9/site-packages (from ansible-core==2.11.12.post0) (26.0) 2026-02-22 00:21:08.626205 | controller | Collecting resolvelib<0.6.0,>=0.5.3 (from ansible-core==2.11.12.post0) 2026-02-22 00:21:08.633947 | controller | Downloading resolvelib-0.5.4-py2.py3-none-any.whl.metadata (3.7 kB) 2026-02-22 00:21:08.948433 | controller | Collecting cffi>=2.0.0 (from cryptography->ansible-core==2.11.12.post0) 2026-02-22 00:21:08.956341 | controller | Downloading cffi-2.0.0-cp39-cp39-manylinux2014_x86_64.manylinux_2_17_x86_64.whl.metadata (2.6 kB) 2026-02-22 00:21:08.970772 | controller | Requirement already satisfied: typing-extensions>=4.13.2 in ./venv/lib/python3.9/site-packages (from cryptography->ansible-core==2.11.12.post0) (4.15.0) 2026-02-22 00:21:09.001804 | controller | Collecting pycparser (from cffi>=2.0.0->cryptography->ansible-core==2.11.12.post0) 2026-02-22 00:21:09.007509 | controller | Downloading pycparser-2.23-py3-none-any.whl.metadata (993 bytes) 2026-02-22 00:21:09.166899 | controller | Collecting MarkupSafe>=2.0 (from jinja2->ansible-core==2.11.12.post0) 2026-02-22 00:21:09.175220 | controller | Downloading markupsafe-3.0.3-cp39-cp39-manylinux2014_x86_64.manylinux_2_17_x86_64.manylinux_2_28_x86_64.whl.metadata (2.7 kB) 2026-02-22 00:21:09.194266 | controller | Downloading resolvelib-0.5.4-py2.py3-none-any.whl (12 kB) 2026-02-22 00:21:09.211661 | controller | Downloading cryptography-46.0.5-cp38-abi3-manylinux_2_34_x86_64.whl (4.5 MB) 2026-02-22 00:21:09.283115 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 4.5/4.5 MB 78.6 MB/s 0:00:00 2026-02-22 00:21:09.294643 | controller | Downloading cffi-2.0.0-cp39-cp39-manylinux2014_x86_64.manylinux_2_17_x86_64.whl (216 kB) 2026-02-22 00:21:09.314172 | controller | Downloading jinja2-3.1.6-py3-none-any.whl (134 kB) 2026-02-22 00:21:09.336029 | controller | Downloading markupsafe-3.0.3-cp39-cp39-manylinux2014_x86_64.manylinux_2_17_x86_64.manylinux_2_28_x86_64.whl (20 kB) 2026-02-22 00:21:09.347876 | controller | Downloading pycparser-2.23-py3-none-any.whl (118 kB) 2026-02-22 00:21:09.362634 | controller | Downloading pyyaml-6.0.3-cp39-cp39-manylinux2014_x86_64.manylinux_2_17_x86_64.manylinux_2_28_x86_64.whl (750 kB) 2026-02-22 00:21:09.387657 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 750.8/750.8 kB 24.0 MB/s 0:00:00 2026-02-22 00:21:09.407605 | controller | Building wheels for collected packages: ansible-core 2026-02-22 00:21:09.408934 | controller | Building wheel for ansible-core (pyproject.toml): started 2026-02-22 00:21:12.126308 | controller | Building wheel for ansible-core (pyproject.toml): finished with status 'done' 2026-02-22 00:21:12.131406 | controller | Created wheel for ansible-core: filename=ansible_core-2.11.12.post0-py3-none-any.whl size=1961250 sha256=e3ce32eaf698128f4c438c8cd320d1bebe8fe8271bc149faa8419c6d62bdbc70 2026-02-22 00:21:12.132606 | controller | Stored in directory: /var/tmp/ansible-test-pip/pip-ephem-wheel-cache-qb7xappl/wheels/0f/a0/93/ab85b739e1eb1e0467171316f13571c14efdf87e0d5d44a33e 2026-02-22 00:21:12.149176 | controller | Successfully built ansible-core 2026-02-22 00:21:12.199515 | controller | Installing collected packages: resolvelib, PyYAML, pycparser, MarkupSafe, jinja2, cffi, cryptography, ansible-core 2026-02-22 00:21:14.884655 | controller | 2026-02-22 00:21:14.888985 | controller | Successfully installed MarkupSafe-3.0.3 PyYAML-6.0.3 ansible-core-2.11.12.post0 cffi-2.0.0 cryptography-46.0.5 jinja2-3.1.6 pycparser-2.23 resolvelib-0.5.4 2026-02-22 00:21:15.475484 | controller | ok: Runtime: 0:00:12.630080 2026-02-22 00:21:15.491363 | 2026-02-22 00:21:15.491534 | TASK [Setup download-artifact-fork role] 2026-02-22 00:21:15.517296 | controller | ok 2026-02-22 00:21:15.558971 | 2026-02-22 00:21:15.559125 | TASK [download-artifact-fork : Parse inventory response] 2026-02-22 00:21:15.595107 | controller | ok 2026-02-22 00:21:15.604840 | 2026-02-22 00:21:15.604954 | TASK [download-artifact-fork : Fetch info from Zuul API] 2026-02-22 00:21:15.641189 | controller | skipping: Conditional result was False 2026-02-22 00:21:15.652227 | 2026-02-22 00:21:15.652859 | TASK [download-artifact-fork : Parse build response] 2026-02-22 00:21:15.679187 | controller | skipping: Conditional result was False 2026-02-22 00:21:15.688170 | 2026-02-22 00:21:15.688281 | TASK [download-artifact-fork : Ensure artifacts directory exists] 2026-02-22 00:21:16.545485 | controller | changed 2026-02-22 00:21:16.558232 | 2026-02-22 00:21:16.558380 | LOOP [download-artifact-fork : Download archive by type] 2026-02-22 00:21:16.581343 | controller | skipping: Conditional result was False 2026-02-22 00:21:16.581721 | 2026-02-22 00:21:18.983381 | controller | changed: OK (20208 bytes) 2026-02-22 00:21:19.018855 | 2026-02-22 00:21:19.019015 | TASK [deploy-artifacts : define __collections] 2026-02-22 00:21:19.052844 | controller | ok 2026-02-22 00:21:19.060694 | 2026-02-22 00:21:19.060826 | LOOP [deploy-artifacts : Create list of collections] 2026-02-22 00:21:19.101083 | controller | skipping: Conditional result was False 2026-02-22 00:21:19.119273 | controller | ok: 2026-02-22 00:21:19.119524 | controller | { 2026-02-22 00:21:19.119596 | controller | "branch": "main", 2026-02-22 00:21:19.119649 | controller | "job": "build-ansible-collection", 2026-02-22 00:21:19.119697 | controller | "metadata": { 2026-02-22 00:21:19.119926 | controller | "type": "ansible_collection", 2026-02-22 00:21:19.119980 | controller | "version": "5.0.0" 2026-02-22 00:21:19.120019 | controller | }, 2026-02-22 00:21:19.120056 | controller | "name": "ansible.network", 2026-02-22 00:21:19.120093 | controller | "project": "ansible-collections/ansible.network", 2026-02-22 00:21:19.120130 | controller | "url": "https://5e28ed2b492a80c5e0c8-d66ebbecf8e3babb6214bb83db5d634d.ssl.cf5.rackcdn.com/ansible/8eb4ea3b8c1b41dabde0210903611d8d/artifacts/ansible-network-5.0.0.tar.gz" 2026-02-22 00:21:19.120180 | controller | } 2026-02-22 00:21:19.148299 | 2026-02-22 00:21:19.148531 | TASK [deploy-artifacts : Install require-project collection using ansible-galaxy] 2026-02-22 00:21:20.790713 | controller | Starting galaxy collection install process 2026-02-22 00:21:20.791063 | controller | Process install dependency map 2026-02-22 00:21:22.372383 | controller | [WARNING]: Skipping Galaxy server https://galaxy.ansible.com/api/. Got an 2026-02-22 00:21:22.505167 | controller | unexpected error when getting available versions of collection 2026-02-22 00:21:22.505238 | controller | ansible.netcommon: '/api/v3/plugin/ansible/content/published/collections/index/ 2026-02-22 00:21:22.505261 | controller | ansible/netcommon/versions/' 2026-02-22 00:21:22.505270 | controller | ERROR! Unexpected Exception, this is probably a bug: '/api/v3/plugin/ansible/content/published/collections/index/ansible/netcommon/versions/' 2026-02-22 00:21:22.505280 | controller | to see the full traceback, use -vvv 2026-02-22 00:21:23.054041 | controller | ERROR 2026-02-22 00:21:23.054274 | controller | { 2026-02-22 00:21:23.054322 | controller | "delta": "0:00:02.530644", 2026-02-22 00:21:23.054346 | controller | "end": "2026-02-22 00:21:22.505927", 2026-02-22 00:21:23.054367 | controller | "msg": "non-zero return code", 2026-02-22 00:21:23.054386 | controller | "rc": 250, 2026-02-22 00:21:23.054406 | controller | "start": "2026-02-22 00:21:19.975283" 2026-02-22 00:21:23.054425 | controller | } failure 2026-02-22 00:21:23.056865 | 2026-02-22 00:21:23.056927 | PLAY RECAP 2026-02-22 00:21:23.056971 | controller | ok: 44 changed: 24 unreachable: 0 failed: 1 skipped: 17 rescued: 0 ignored: 0 2026-02-22 00:21:23.056992 | 2026-02-22 00:21:23.208490 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master] 2026-02-22 00:21:23.209491 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2026-02-22 00:21:23.810994 | 2026-02-22 00:21:23.811120 | PLAY [all] 2026-02-22 00:21:23.835372 | 2026-02-22 00:21:23.835522 | TASK [fetch-subunit-output : Find stestr or testr executable] 2026-02-22 00:21:26.986506 | controller | changed: non-zero return code 2026-02-22 00:21:26.994433 | 2026-02-22 00:21:26.994537 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2026-02-22 00:21:27.019090 | controller | skipping: Conditional result was False 2026-02-22 00:21:27.027145 | 2026-02-22 00:21:27.027242 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2026-02-22 00:21:27.050886 | 2026-02-22 00:21:27.051099 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2026-02-22 00:21:27.073117 | 2026-02-22 00:21:27.073314 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2026-02-22 00:21:27.088392 | controller | skipping: Conditional result was False 2026-02-22 00:21:27.098163 | 2026-02-22 00:21:27.098265 | LOOP [fetch-subunit-output : Generate subunit file] 2026-02-22 00:21:27.122048 | 2026-02-22 00:21:27.122210 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2026-02-22 00:21:27.136515 | controller | skipping: Conditional result was False 2026-02-22 00:21:27.146960 | 2026-02-22 00:21:27.147074 | TASK [fetch-subunit-output : Remove the temporary file] 2026-02-22 00:21:27.172512 | controller | skipping: Conditional result was False 2026-02-22 00:21:27.181842 | 2026-02-22 00:21:27.181960 | TASK [fetch-subunit-output : Process and fetch subunit results] 2026-02-22 00:21:27.197159 | controller | skipping: Conditional result was False 2026-02-22 00:21:27.228132 | 2026-02-22 00:21:27.228403 | PLAY RECAP 2026-02-22 00:21:27.228461 | controller | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2026-02-22 00:21:27.228483 | 2026-02-22 00:21:27.365407 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2026-02-22 00:21:27.366824 | POST-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/post.yaml@master] 2026-02-22 00:21:27.975203 | 2026-02-22 00:21:27.975329 | PLAY [all:!appliance*] 2026-02-22 00:21:28.003281 | 2026-02-22 00:21:28.003435 | TASK [unregister the node] 2026-02-22 00:21:28.362053 | controller | skipping: Conditional result was False 2026-02-22 00:21:28.370609 | 2026-02-22 00:21:28.370708 | TASK [include_role : fetch-output] 2026-02-22 00:21:28.405244 | controller | ok 2026-02-22 00:21:28.436106 | 2026-02-22 00:21:28.436254 | TASK [fetch-output : Set log path for multiple nodes] 2026-02-22 00:21:28.502254 | controller | skipping: Conditional result was False 2026-02-22 00:21:28.510832 | 2026-02-22 00:21:28.510945 | TASK [fetch-output : Set log path for single node] 2026-02-22 00:21:28.554097 | controller | ok 2026-02-22 00:21:28.561656 | 2026-02-22 00:21:28.561784 | LOOP [fetch-output : Ensure local output dirs] 2026-02-22 00:21:29.011024 | controller -> localhost | ok: "/var/lib/zuul/builds/0e3516dbf5ff4fd1bbb7711af5858b1d/work/logs" 2026-02-22 00:21:29.260952 | controller -> localhost | changed: "/var/lib/zuul/builds/0e3516dbf5ff4fd1bbb7711af5858b1d/work/artifacts" 2026-02-22 00:21:29.529573 | controller -> localhost | changed: "/var/lib/zuul/builds/0e3516dbf5ff4fd1bbb7711af5858b1d/work/docs" 2026-02-22 00:21:29.548271 | 2026-02-22 00:21:29.548396 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-02-22 00:21:31.837436 | controller | changed: 2026-02-22 00:21:31.837989 | controller | .d..t...... ./ 2026-02-22 00:21:31.838649 | controller | cd+++++++++ controller/ 2026-02-22 00:21:31.838762 | controller | changed: All items complete 2026-02-22 00:21:31.838951 | 2026-02-22 00:21:33.963163 | controller | changed: .d..t...... ./ 2026-02-22 00:21:36.047552 | controller | changed: .d..t...... ./ 2026-02-22 00:21:36.067825 | 2026-02-22 00:21:36.067963 | TASK [include_role : fetch-output-openshift] 2026-02-22 00:21:36.083170 | controller | skipping: Conditional result was False 2026-02-22 00:21:36.090217 | 2026-02-22 00:21:36.090309 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-02-22 00:21:36.115027 | controller | skipping: Conditional result was False 2026-02-22 00:21:36.124182 | controller | skipping: Conditional result was False 2026-02-22 00:21:36.164135 | 2026-02-22 00:21:36.164233 | PLAY [localhost] 2026-02-22 00:21:36.178757 | 2026-02-22 00:21:36.178868 | TASK [Run Zuul manifest role] 2026-02-22 00:21:36.196796 | localhost | ok 2026-02-22 00:21:36.213883 | 2026-02-22 00:21:36.214000 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-02-22 00:21:36.617597 | localhost | changed 2026-02-22 00:21:36.629540 | 2026-02-22 00:21:36.629789 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-02-22 00:21:36.668541 | localhost | ok 2026-02-22 00:21:36.689100 | 2026-02-22 00:21:36.689215 | TASK [Set zuul-log-path fact] 2026-02-22 00:21:36.741466 | localhost | ok 2026-02-22 00:21:36.758740 | 2026-02-22 00:21:36.758846 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-02-22 00:21:36.786812 | localhost | ok 2026-02-22 00:21:36.795078 | 2026-02-22 00:21:36.795191 | LOOP [Set zuul_log_targets (Swift with random Rackspace region)] 2026-02-22 00:21:36.821223 | localhost | ok: 2026-02-22 00:21:36.821488 | localhost | { 2026-02-22 00:21:36.821529 | localhost | "auth": { 2026-02-22 00:21:36.821562 | localhost | "password": "IonWCwcuOFib&&<}wp^:[2G/", 2026-02-22 00:21:36.821590 | localhost | "username": "9LF91O0RK7JU" 2026-02-22 00:21:36.821616 | localhost | }, 2026-02-22 00:21:36.821638 | localhost | "profile": "rackspace", 2026-02-22 00:21:36.821660 | localhost | "project_name": "1251317", 2026-02-22 00:21:36.821684 | localhost | "region_name": "DFW" 2026-02-22 00:21:36.821707 | localhost | } 2026-02-22 00:21:36.821788 | 2026-02-22 00:21:36.835799 | 2026-02-22 00:21:36.835933 | TASK [Run upload-logs-failover role (S3 then Swift)] 2026-02-22 00:21:36.854743 | localhost | Output suppressed because no_log was given 2026-02-22 00:21:36.870465 | 2026-02-22 00:21:36.870557 | TASK [upload-logs-failover : Initialize control vars] 2026-02-22 00:21:36.898463 | localhost | ok 2026-02-22 00:21:36.902889 | 2026-02-22 00:21:36.902964 | LOOP [upload-logs-failover : Perform log upload] 2026-02-22 00:21:36.927309 | localhost | Output suppressed because no_log was given 2026-02-22 00:21:36.937605 | localhost | included: /var/lib/zuul/builds/0e3516dbf5ff4fd1bbb7711af5858b1d/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/upload-logs-failover/tasks/upload.yaml 2026-02-22 00:21:36.947441 | 2026-02-22 00:21:36.947542 | LOOP [upload-logs-failover : Unpack vars for swift upload] 2026-02-22 00:21:37.024318 | localhost | Output suppressed because no_log was given 2026-02-22 00:21:37.029495 | 2026-02-22 00:21:37.029565 | TASK [Upload logs to swift] 2026-02-22 00:21:37.049368 | localhost | ok 2026-02-22 00:21:37.078918 | 2026-02-22 00:21:37.079050 | TASK [Set zuul-log-path fact] 2026-02-22 00:21:37.103304 | localhost | skipping: Conditional result was False 2026-02-22 00:21:37.111155 | 2026-02-22 00:21:37.111334 | TASK [upload-logs-swift : Ensure logs are readable before uploading] 2026-02-22 00:21:37.510765 | localhost -> localhost | ok: Runtime: 0:00:00.006652 2026-02-22 00:21:37.516349 | 2026-02-22 00:21:37.516553 | TASK [upload-logs-swift : Upload logs to swift]