2026-01-19 00:06:36.442321 | Job console starting... 2026-01-19 00:06:36.451605 | Updating repositories 2026-01-19 00:06:36.588126 | Preparing job workspace 2026-01-19 00:06:40.513669 | Running Ansible setup... 2026-01-19 00:06:45.475388 | PRE-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master] 2026-01-19 00:06:46.123915 | 2026-01-19 00:06:46.124084 | PLAY [localhost] 2026-01-19 00:06:46.132579 | 2026-01-19 00:06:46.132652 | TASK [Gathering Facts] 2026-01-19 00:06:47.088706 | localhost | ok 2026-01-19 00:06:47.106236 | 2026-01-19 00:06:47.106370 | TASK [Setup log path fact] 2026-01-19 00:06:47.124628 | localhost | ok 2026-01-19 00:06:47.138081 | 2026-01-19 00:06:47.138175 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-01-19 00:06:47.166502 | localhost | ok 2026-01-19 00:06:47.176489 | 2026-01-19 00:06:47.176614 | TASK [emit-job-header : Print job information] 2026-01-19 00:06:47.226923 | # Job Information 2026-01-19 00:06:47.227084 | Ansible Version: 2.15.12 2026-01-19 00:06:47.227205 | Job: ansible-test-sanity-docker-stable-2.11 2026-01-19 00:06:47.227232 | Pipeline: periodic 2026-01-19 00:06:47.227252 | Executor: ze04.softwarefactory-project.io 2026-01-19 00:06:47.227270 | Triggered by: https://github.com/ansible-collections/ansible.network 2026-01-19 00:06:47.227293 | Log URL (when completed): https://ansible.softwarefactory-project.io/logs/27e/ansible/27efd94b50e048c1ab6069c282faac5b/ 2026-01-19 00:06:47.227313 | Event ID: 20e0e583b2d44c99aa392c32d5cd13c2 2026-01-19 00:06:47.231611 | 2026-01-19 00:06:47.231695 | LOOP [emit-job-header : Print node information] 2026-01-19 00:06:47.367462 | localhost | ok: 2026-01-19 00:06:47.367719 | localhost | # Node Information 2026-01-19 00:06:47.367754 | localhost | Inventory Hostname: controller 2026-01-19 00:06:47.367775 | localhost | Hostname: np0005587266 2026-01-19 00:06:47.367796 | localhost | Username: zuul 2026-01-19 00:06:47.367822 | localhost | Distro: Fedora 37 2026-01-19 00:06:47.367842 | localhost | Provider: ansible-vexxhost-ca-ymq-1 2026-01-19 00:06:47.367861 | localhost | Region: ca-ymq-1 2026-01-19 00:06:47.367880 | localhost | Label: ansible-fedora-37-1vcpu 2026-01-19 00:06:47.367897 | localhost | Product Name: OpenStack Nova 2026-01-19 00:06:47.368006 | localhost | Interface IP: 162.253.55.49 2026-01-19 00:06:47.386138 | 2026-01-19 00:06:47.386230 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-01-19 00:06:47.777898 | localhost -> localhost | changed 2026-01-19 00:06:47.783928 | 2026-01-19 00:06:47.784009 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-01-19 00:06:48.767582 | localhost -> localhost | changed 2026-01-19 00:06:48.807213 | 2026-01-19 00:06:48.807302 | PLAY [all:!appliance*] 2026-01-19 00:06:48.831024 | 2026-01-19 00:06:48.831133 | TASK [include_role : start-zuul-console] 2026-01-19 00:06:48.853531 | controller | ok 2026-01-19 00:06:48.872472 | 2026-01-19 00:06:48.872568 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-01-19 00:06:49.286229 | controller | ok 2026-01-19 00:06:49.297362 | 2026-01-19 00:06:49.297449 | TASK [use-our-mirror : Retrieve the IP address] 2026-01-19 00:07:11.860815 | controller | ok: Status code was 404 and not [200]: HTTP Error 404: Not Found 2026-01-19 00:07:11.875800 | 2026-01-19 00:07:11.875951 | TASK [use-our-mirror : Ensure the registry service is running on the mirror] 2026-01-19 00:07:12.428041 | controller | skipping: Conditional result was False 2026-01-19 00:07:12.443616 | 2026-01-19 00:07:12.443818 | TASK [use-our-mirror : Write the IP in /etc/hosts] 2026-01-19 00:07:12.470916 | controller | skipping: Conditional result was False 2026-01-19 00:07:12.487462 | 2026-01-19 00:07:12.487631 | TASK [use-our-mirror : Create the podman configuration directory] 2026-01-19 00:07:12.525017 | controller | skipping: Conditional result was False 2026-01-19 00:07:12.540551 | 2026-01-19 00:07:12.540766 | TASK [use-our-mirror : Copy the podman configuration] 2026-01-19 00:07:12.558727 | controller | skipping: Conditional result was False 2026-01-19 00:07:12.573623 | 2026-01-19 00:07:12.573800 | TASK [use-our-mirror : Copy the Yum configuration (main)] 2026-01-19 00:07:12.590848 | controller | skipping: Conditional result was False 2026-01-19 00:07:12.599816 | 2026-01-19 00:07:12.599907 | TASK [use-our-mirror : Copy the Yum configuration (update)] 2026-01-19 00:07:12.625182 | controller | skipping: Conditional result was False 2026-01-19 00:07:12.641093 | 2026-01-19 00:07:12.641193 | TASK [Disable Fedora Modular] 2026-01-19 00:07:12.883103 | controller | changed 2026-01-19 00:07:12.898952 | 2026-01-19 00:07:12.899097 | TASK [Enable EPEL] 2026-01-19 00:07:12.926001 | controller | skipping: Conditional result was False 2026-01-19 00:07:12.939838 | 2026-01-19 00:07:12.939991 | TASK [Register the RHEL node] 2026-01-19 00:07:13.501017 | 2026-01-19 00:07:13.501224 | TASK [Show the subscription-manager status] 2026-01-19 00:07:14.046627 | controller | skipping: Conditional result was False 2026-01-19 00:07:14.061031 | 2026-01-19 00:07:14.061169 | TASK [Enable EPEL on RHEL] 2026-01-19 00:07:14.627873 | controller | skipping: Conditional result was False 2026-01-19 00:07:14.642218 | 2026-01-19 00:07:14.642426 | TASK [Install git and tox] 2026-01-19 00:08:14.364506 | controller | changed 2026-01-19 00:08:14.378798 | 2026-01-19 00:08:14.378949 | TASK [include_role : prepare-workspace] 2026-01-19 00:08:14.410855 | controller | ok 2026-01-19 00:08:14.448887 | 2026-01-19 00:08:14.449033 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-01-19 00:08:14.681229 | controller | ok 2026-01-19 00:08:14.697014 | 2026-01-19 00:08:14.697202 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-01-19 00:08:29.489848 | controller | Output suppressed because no_log was given 2026-01-19 00:08:29.503558 | 2026-01-19 00:08:29.503703 | TASK [include_role : prepare-workspace-openshift] 2026-01-19 00:08:29.529510 | controller | skipping: Conditional result was False 2026-01-19 00:08:29.556234 | 2026-01-19 00:08:29.556334 | PLAY [all:!appliance] 2026-01-19 00:08:29.574284 | 2026-01-19 00:08:29.574396 | TASK [Run add-build-sshkey role (RSA)] 2026-01-19 00:08:29.605669 | controller | ok 2026-01-19 00:08:29.621523 | 2026-01-19 00:08:29.621636 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-01-19 00:08:29.866895 | controller -> localhost | ok 2026-01-19 00:08:29.892480 | 2026-01-19 00:08:29.892572 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-01-19 00:08:29.928633 | controller | ok 2026-01-19 00:08:29.951419 | controller | included: /var/lib/zuul/builds/27efd94b50e048c1ab6069c282faac5b/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-01-19 00:08:29.957427 | 2026-01-19 00:08:29.957490 | TASK [add-build-sshkey : Create Temp SSH key] 2026-01-19 00:08:30.658043 | controller -> localhost | Generating public/private rsa key pair. 2026-01-19 00:08:30.658430 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/27efd94b50e048c1ab6069c282faac5b/work/27efd94b50e048c1ab6069c282faac5b_id_rsa. 2026-01-19 00:08:30.658518 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/27efd94b50e048c1ab6069c282faac5b/work/27efd94b50e048c1ab6069c282faac5b_id_rsa.pub. 2026-01-19 00:08:30.658569 | controller -> localhost | The key fingerprint is: 2026-01-19 00:08:30.658613 | controller -> localhost | SHA256:8uaxcva+LvkuihCiNlqVeYq74V38fsh9XpqsRXP+BjI zuul-build-sshkey 2026-01-19 00:08:30.658655 | controller -> localhost | The key's randomart image is: 2026-01-19 00:08:30.658730 | controller -> localhost | +---[RSA 2048]----+ 2026-01-19 00:08:30.658774 | controller -> localhost | | | 2026-01-19 00:08:30.658815 | controller -> localhost | | | 2026-01-19 00:08:30.658855 | controller -> localhost | | | 2026-01-19 00:08:30.658894 | controller -> localhost | | o | 2026-01-19 00:08:30.658932 | controller -> localhost | |. . + o S o . | 2026-01-19 00:08:30.658985 | controller -> localhost | |.. + + o .E+. | 2026-01-19 00:08:30.659032 | controller -> localhost | |.o= . o.++ .oo. | 2026-01-19 00:08:30.659073 | controller -> localhost | |oo.= o.+Oooo.+ ..| 2026-01-19 00:08:30.659113 | controller -> localhost | |. +.o .*=XO== ..| 2026-01-19 00:08:30.659158 | controller -> localhost | +----[SHA256]-----+ 2026-01-19 00:08:30.659251 | controller -> localhost | ok: Runtime: 0:00:00.181930 2026-01-19 00:08:30.674517 | 2026-01-19 00:08:30.674650 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-01-19 00:08:30.709896 | controller | ok 2026-01-19 00:08:30.737892 | controller | included: /var/lib/zuul/builds/27efd94b50e048c1ab6069c282faac5b/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-01-19 00:08:30.750152 | 2026-01-19 00:08:30.750240 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-01-19 00:08:30.785053 | controller | skipping: Conditional result was False 2026-01-19 00:08:30.793911 | 2026-01-19 00:08:30.794000 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-01-19 00:08:31.269247 | controller | changed 2026-01-19 00:08:31.283221 | 2026-01-19 00:08:31.283358 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-01-19 00:08:31.520305 | controller | ok 2026-01-19 00:08:31.533535 | 2026-01-19 00:08:31.533672 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-01-19 00:08:32.202309 | controller | changed 2026-01-19 00:08:32.215630 | 2026-01-19 00:08:32.215819 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-01-19 00:08:32.853828 | controller | changed 2026-01-19 00:08:32.862246 | 2026-01-19 00:08:32.862477 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-01-19 00:08:32.887436 | controller | skipping: Conditional result was False 2026-01-19 00:08:32.898105 | 2026-01-19 00:08:32.898254 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-01-19 00:08:33.303978 | controller -> localhost | changed 2026-01-19 00:08:33.336859 | 2026-01-19 00:08:33.337029 | TASK [add-build-sshkey : Add back temp key] 2026-01-19 00:08:33.663635 | controller -> localhost | Identity added: /var/lib/zuul/builds/27efd94b50e048c1ab6069c282faac5b/work/27efd94b50e048c1ab6069c282faac5b_id_rsa (zuul-build-sshkey) 2026-01-19 00:08:33.663934 | controller -> localhost | ok: Runtime: 0:00:00.009745 2026-01-19 00:08:33.675201 | 2026-01-19 00:08:33.675301 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-01-19 00:08:34.015184 | controller | ok 2026-01-19 00:08:34.021821 | 2026-01-19 00:08:34.021889 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-01-19 00:08:34.046585 | controller | skipping: Conditional result was False 2026-01-19 00:08:34.059859 | 2026-01-19 00:08:34.059925 | TASK [Run add-build-sshkey role (ECDSA)] 2026-01-19 00:08:34.090209 | controller | ok 2026-01-19 00:08:34.107104 | 2026-01-19 00:08:34.107165 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-01-19 00:08:34.368960 | controller -> localhost | ok 2026-01-19 00:08:34.375894 | 2026-01-19 00:08:34.375961 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-01-19 00:08:34.395744 | controller | ok 2026-01-19 00:08:34.406698 | controller | included: /var/lib/zuul/builds/27efd94b50e048c1ab6069c282faac5b/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-01-19 00:08:34.412767 | 2026-01-19 00:08:34.412830 | TASK [add-build-sshkey : Create Temp SSH key] 2026-01-19 00:08:34.686748 | controller -> localhost | Generating public/private ecdsa key pair. 2026-01-19 00:08:34.687037 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/27efd94b50e048c1ab6069c282faac5b/work/27efd94b50e048c1ab6069c282faac5b_id_ecdsa. 2026-01-19 00:08:34.687096 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/27efd94b50e048c1ab6069c282faac5b/work/27efd94b50e048c1ab6069c282faac5b_id_ecdsa.pub. 2026-01-19 00:08:34.687156 | controller -> localhost | The key fingerprint is: 2026-01-19 00:08:34.687201 | controller -> localhost | SHA256:dWBP881Cudo+Nmupw+Zmq2SfKBfH5LmpHBFREJPobNU zuul-build-sshkey 2026-01-19 00:08:34.687242 | controller -> localhost | The key's randomart image is: 2026-01-19 00:08:34.687282 | controller -> localhost | +---[ECDSA 521]---+ 2026-01-19 00:08:34.687322 | controller -> localhost | | .B*+ .. | 2026-01-19 00:08:34.687362 | controller -> localhost | | ..o*E+.o | 2026-01-19 00:08:34.687402 | controller -> localhost | | o .o o o.o| 2026-01-19 00:08:34.687441 | controller -> localhost | | +. o. .. | 2026-01-19 00:08:34.687480 | controller -> localhost | | .S .+ + | 2026-01-19 00:08:34.687518 | controller -> localhost | | ..* . | 2026-01-19 00:08:34.687556 | controller -> localhost | | ++ + . | 2026-01-19 00:08:34.687595 | controller -> localhost | | .+.+O.B | 2026-01-19 00:08:34.687633 | controller -> localhost | | o=B**.+ | 2026-01-19 00:08:34.687671 | controller -> localhost | +----[SHA256]-----+ 2026-01-19 00:08:34.687806 | controller -> localhost | ok: Runtime: 0:00:00.007757 2026-01-19 00:08:34.702480 | 2026-01-19 00:08:34.702608 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-01-19 00:08:34.733781 | controller | ok 2026-01-19 00:08:34.752131 | controller | included: /var/lib/zuul/builds/27efd94b50e048c1ab6069c282faac5b/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-01-19 00:08:34.764970 | 2026-01-19 00:08:34.765089 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-01-19 00:08:34.790723 | controller | skipping: Conditional result was False 2026-01-19 00:08:34.800874 | 2026-01-19 00:08:34.800963 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-01-19 00:08:35.101948 | controller | changed 2026-01-19 00:08:35.108367 | 2026-01-19 00:08:35.108444 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-01-19 00:08:35.331186 | controller | ok 2026-01-19 00:08:35.337469 | 2026-01-19 00:08:35.337539 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-01-19 00:08:36.015960 | controller | changed 2026-01-19 00:08:36.031254 | 2026-01-19 00:08:36.031411 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-01-19 00:08:36.723013 | controller | changed 2026-01-19 00:08:36.738002 | 2026-01-19 00:08:36.738172 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-01-19 00:08:36.766470 | controller | skipping: Conditional result was False 2026-01-19 00:08:36.778586 | 2026-01-19 00:08:36.779088 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-01-19 00:08:37.021915 | controller -> localhost | changed 2026-01-19 00:08:37.058124 | 2026-01-19 00:08:37.058357 | TASK [add-build-sshkey : Add back temp key] 2026-01-19 00:08:37.373772 | controller -> localhost | Identity added: /var/lib/zuul/builds/27efd94b50e048c1ab6069c282faac5b/work/27efd94b50e048c1ab6069c282faac5b_id_ecdsa (zuul-build-sshkey) 2026-01-19 00:08:37.374152 | controller -> localhost | ok: Runtime: 0:00:00.016361 2026-01-19 00:08:37.391952 | 2026-01-19 00:08:37.392122 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-01-19 00:08:37.617899 | controller | ok 2026-01-19 00:08:37.631057 | 2026-01-19 00:08:37.631194 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-01-19 00:08:37.670979 | controller | skipping: Conditional result was False 2026-01-19 00:08:37.698961 | 2026-01-19 00:08:37.699135 | TASK [include_role : remove-zuul-sshkey] 2026-01-19 00:08:37.738638 | controller | skipping: Conditional result was False 2026-01-19 00:08:37.759881 | 2026-01-19 00:08:37.760050 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-01-19 00:08:38.057478 | controller | ok: "logs" 2026-01-19 00:08:38.058081 | controller | ok: All items complete 2026-01-19 00:08:38.058152 | 2026-01-19 00:08:38.276278 | controller | ok: "artifacts" 2026-01-19 00:08:38.468384 | controller | ok: "docs" 2026-01-19 00:08:38.485508 | 2026-01-19 00:08:38.485616 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-01-19 00:08:38.754971 | controller | changed: "logs" 2026-01-19 00:08:38.961885 | controller | changed: "artifacts" 2026-01-19 00:08:39.167544 | controller | changed: "docs" 2026-01-19 00:08:39.201910 | 2026-01-19 00:08:39.201977 | PLAY RECAP 2026-01-19 00:08:39.202018 | controller | ok: 30 changed: 16 unreachable: 0 failed: 0 skipped: 18 rescued: 0 ignored: 0 2026-01-19 00:08:39.202044 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-01-19 00:08:39.202062 | 2026-01-19 00:08:39.306594 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master] 2026-01-19 00:08:39.307450 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2026-01-19 00:08:39.871469 | 2026-01-19 00:08:39.871580 | PLAY [all] 2026-01-19 00:08:39.895948 | 2026-01-19 00:08:39.896080 | TASK [Install binary dependencies] 2026-01-19 00:08:39.977561 | controller | ok 2026-01-19 00:08:40.005556 | 2026-01-19 00:08:40.005818 | TASK [bindep : Include find tasks] 2026-01-19 00:08:40.037671 | controller | ok 2026-01-19 00:08:40.046298 | controller | included: /var/lib/zuul/builds/27efd94b50e048c1ab6069c282faac5b/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find.yaml 2026-01-19 00:08:40.053342 | 2026-01-19 00:08:40.053417 | TASK [bindep : Look for bindep.txt] 2026-01-19 00:08:40.395496 | controller | ok 2026-01-19 00:08:40.418121 | 2026-01-19 00:08:40.418294 | TASK [bindep : Define bindep_file fact] 2026-01-19 00:08:40.443286 | controller | skipping: Conditional result was False 2026-01-19 00:08:40.450372 | 2026-01-19 00:08:40.450459 | TASK [bindep : Look for other-requirements.txt] 2026-01-19 00:08:40.685106 | controller | ok 2026-01-19 00:08:40.698515 | 2026-01-19 00:08:40.698742 | TASK [bindep : Define bindep_file fact] 2026-01-19 00:08:40.736558 | controller | skipping: Conditional result was False 2026-01-19 00:08:40.749587 | 2026-01-19 00:08:40.749771 | TASK [bindep : Look for bindep fallback file] 2026-01-19 00:08:40.796778 | controller | skipping: Conditional result was False 2026-01-19 00:08:40.815344 | 2026-01-19 00:08:40.815571 | TASK [bindep : Define bindep_file fact] 2026-01-19 00:08:40.853456 | controller | skipping: Conditional result was False 2026-01-19 00:08:40.873282 | 2026-01-19 00:08:40.873445 | TASK [bindep : Include bindep tasks] 2026-01-19 00:08:40.904851 | controller | skipping: Conditional result was False 2026-01-19 00:08:40.918973 | 2026-01-19 00:08:40.919116 | TASK [bindep : Include install tasks] 2026-01-19 00:08:40.945940 | controller | skipping: Conditional result was False 2026-01-19 00:08:40.959527 | 2026-01-19 00:08:40.959664 | LOOP [bindep : Include package tasks] 2026-01-19 00:08:41.040996 | 2026-01-19 00:08:41.041241 | TASK [Run test-setup role] 2026-01-19 00:08:41.072143 | controller | ok 2026-01-19 00:08:41.099652 | 2026-01-19 00:08:41.099763 | TASK [test-setup : Check if project's tools/test-setup.sh exists] 2026-01-19 00:08:41.309935 | controller | ok 2026-01-19 00:08:41.323858 | 2026-01-19 00:08:41.323992 | TASK [test-setup : Run tools/test-setup.sh] 2026-01-19 00:08:41.873463 | controller | skipping: Conditional result was False 2026-01-19 00:08:41.930360 | 2026-01-19 00:08:41.930500 | PLAY RECAP 2026-01-19 00:08:41.930558 | controller | ok: 4 changed: 0 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2026-01-19 00:08:41.930587 | 2026-01-19 00:08:42.068470 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2026-01-19 00:08:42.069661 | PRE-RUN START: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master] 2026-01-19 00:08:42.712048 | 2026-01-19 00:08:42.712191 | PLAY [controller] 2026-01-19 00:08:42.735755 | 2026-01-19 00:08:42.735888 | TASK [Create the /root directory] 2026-01-19 00:08:43.120639 | controller | ok 2026-01-19 00:08:43.130012 | 2026-01-19 00:08:43.130129 | TASK [Install glibc-langpack-en] 2026-01-19 00:08:47.060565 | controller | ok: Nothing to do 2026-01-19 00:08:47.072617 | 2026-01-19 00:08:47.072818 | TASK [Ensure controller directory exists] 2026-01-19 00:08:47.296359 | controller | changed 2026-01-19 00:08:47.311260 | 2026-01-19 00:08:47.311409 | TASK [Install container runtime] 2026-01-19 00:08:47.391819 | controller | ok 2026-01-19 00:08:47.494127 | 2026-01-19 00:08:47.494331 | LOOP [ensure-docker : Gather variables for each operating system] 2026-01-19 00:08:47.557218 | controller | ok: "/var/lib/zuul/builds/27efd94b50e048c1ab6069c282faac5b/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/vars/fedora.yaml" 2026-01-19 00:08:47.578327 | 2026-01-19 00:08:47.578530 | TASK [ensure-docker : Sanity check for distro selection] 2026-01-19 00:08:47.608503 | controller | skipping: Conditional result was False 2026-01-19 00:08:47.616992 | 2026-01-19 00:08:47.617091 | TASK [ensure-docker : Sanity check for distro install] 2026-01-19 00:08:47.653372 | controller | skipping: Conditional result was False 2026-01-19 00:08:47.662503 | 2026-01-19 00:08:47.662599 | TASK [ensure-docker : Sanity check for upstream install] 2026-01-19 00:08:47.698360 | controller | skipping: Conditional result was False 2026-01-19 00:08:47.707899 | 2026-01-19 00:08:47.707998 | TASK [ensure-docker : Sanity check for upstream install mirrors] 2026-01-19 00:08:47.744079 | controller | skipping: Conditional result was False 2026-01-19 00:08:47.760035 | 2026-01-19 00:08:47.760192 | TASK [ensure-docker : Include tasks for docker install] 2026-01-19 00:08:47.799943 | controller | ok 2026-01-19 00:08:47.818535 | controller | included: /var/lib/zuul/builds/27efd94b50e048c1ab6069c282faac5b/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/tasks/docker-upstream.yaml 2026-01-19 00:08:47.829947 | 2026-01-19 00:08:47.830037 | TASK [ensure-docker : Not Implemented] 2026-01-19 00:08:47.855593 | controller | skipping: Conditional result was False 2026-01-19 00:08:47.864462 | 2026-01-19 00:08:47.864547 | TASK [Set up docker mirrors] 2026-01-19 00:08:47.897451 | controller | ok 2026-01-19 00:08:47.925886 | 2026-01-19 00:08:47.925981 | TASK [use-docker-mirror : Set docker_mirror fact from mirror_info] 2026-01-19 00:08:47.972227 | controller | skipping: Conditional result was False 2026-01-19 00:08:47.981170 | 2026-01-19 00:08:47.981263 | TASK [use-docker-mirror : Set docker_mirror fact from zuul_site_mirror_info] 2026-01-19 00:08:48.027787 | controller | skipping: Conditional result was False 2026-01-19 00:08:48.036534 | 2026-01-19 00:08:48.036629 | TASK [use-docker-mirror : Set docker_mirror fact] 2026-01-19 00:08:48.082156 | controller | skipping: Conditional result was False 2026-01-19 00:08:48.091949 | 2026-01-19 00:08:48.092075 | TASK [use-docker-mirror : Set up docker mirrors] 2026-01-19 00:08:48.128341 | controller | skipping: Conditional result was False 2026-01-19 00:08:48.145613 | 2026-01-19 00:08:48.145729 | TASK [Add all repositories] 2026-01-19 00:08:48.170667 | controller | ok 2026-01-19 00:08:48.203884 | 2026-01-19 00:08:48.203992 | LOOP [ensure-package-repositories : Install repositories] 2026-01-19 00:08:48.245124 | controller | ok: "/var/lib/zuul/builds/27efd94b50e048c1ab6069c282faac5b/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-package-repositories/tasks/RedHat.yaml" 2026-01-19 00:08:48.245426 | controller | ok: All items complete 2026-01-19 00:08:48.245485 | 2026-01-19 00:08:48.265135 | controller | included: /var/lib/zuul/builds/27efd94b50e048c1ab6069c282faac5b/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-package-repositories/tasks/RedHat.yaml 2026-01-19 00:08:48.313626 | 2026-01-19 00:08:48.313795 | TASK [ensure-package-repositories : Create GPG key temp directory] 2026-01-19 00:08:48.638418 | controller | changed 2026-01-19 00:08:48.652751 | 2026-01-19 00:08:48.652914 | LOOP [ensure-package-repositories : Upload GPG keys] 2026-01-19 00:08:49.463028 | controller | changed: 2026-01-19 00:08:49.463259 | controller | { 2026-01-19 00:08:49.463299 | controller | "data": "-----BEGIN PGP PUBLIC KEY BLOCK-----\n\nmQINBFit5IEBEADDt86QpYKz5flnCsOyZ/fk3WwBKxfDjwHf/GIflo+4GWAXS7wJ\n1PSzPsvSDATV10J44i5WQzh99q+lZvFCVRFiNhRmlmcXG+rk1QmDh3fsCCj9Q/yP\nw8jn3Hx0zDtz8PIB/18ReftYJzUo34COLiHn8WiY20uGCF2pjdPgfxE+K454c4G7\ngKFqVUFYgPug2CS0quaBB5b0rpFUdzTeI5RCStd27nHCpuSDCvRYAfdv+4Y1yiVh\nKKdoe3Smj+RnXeVMgDxtH9FJibZ3DK7WnMN2yeob6VqXox+FvKYJCCLkbQgQmE50\nuVK0uN71A1mQDcTRKQ2q3fFGlMTqJbbzr3LwnCBE6hV0a36t+DABtZTmz5O69xdJ\nWGdBeePCnWVqtDb/BdEYz7hPKskcZBarygCCe2Xi7sZieoFZuq6ltPoCsdfEdfbO\n+VBVKJnExqNZCcFUTEnbH4CldWROOzMS8BGUlkGpa59Sl1t0QcmWlw1EbkeMQNrN\nspdR8lobcdNS9bpAJQqSHRZh3cAM9mA3Yq/bssUS/P2quRXLjJ9mIv3dky9C3udM\n+q2unvnbNpPtIUly76FJ3s8g8sHeOnmYcKqNGqHq2Q3kMdA2eIbI0MqfOIo2+Xk0\nrNt3ctq3g+cQiorcN3rdHPsTRSAcp+NCz1QF9TwXYtH1XV24A6QMO0+CZwARAQAB\ntCtEb2NrZXIgUmVsZWFzZSAoQ0UgcnBtKSA8ZG9ja2VyQGRvY2tlci5jb20+iQI3\nBBMBCgAhBQJYrep4AhsvBQsJCAcDBRUKCQgLBRYCAwEAAh4BAheAAAoJEMUv62ti\nHp816C0P/iP+1uhSa6Qq3TIc5sIFE5JHxOO6y0R97cUdAmCbEqBiJHUPNQDQaaRG\nVYBm0K013Q1gcJeUJvS32gthmIvhkstw7KTodwOM8Kl11CCqZ07NPFef1b2SaJ7l\nTYpyUsT9+e343ph+O4C1oUQw6flaAJe+8ATCmI/4KxfhIjD2a/Q1voR5tUIxfexC\n/LZTx05gyf2mAgEWlRm/cGTStNfqDN1uoKMlV+WFuB1j2oTUuO1/dr8mL+FgZAM3\nntWFo9gQCllNV9ahYOON2gkoZoNuPUnHsf4Bj6BQJnIXbAhMk9H2sZzwUi9bgObZ\nXO8+OrP4D4B9kCAKqqaQqA+O46LzO2vhN74lm/Fy6PumHuviqDBdN+HgtRPMUuao\nxnuVJSvBu9sPdgT/pR1N9u/KnfAnnLtR6g+fx4mWz+ts/riB/KRHzXd+44jGKZra\nIhTMfniguMJNsyEOO0AN8Tqcl0eRBxcOArcri7xu8HFvvl+e+ILymu4buusbYEVL\nGBkYP5YMmScfKn+jnDVN4mWoN1Bq2yMhMGx6PA3hOvzPNsUoYy2BwDxNZyflzuAi\ng59mgJm2NXtzNbSRJbMamKpQ69mzLWGdFNsRd4aH7PT7uPAURaf7B5BVp3UyjERW\n5alSGnBqsZmvlRnVH5BDUhYsWZMPRQS9rRr4iGW0l+TH+O2VJ8aQ\n=0Zqq\n-----END PGP PUBLIC KEY BLOCK-----\n" 2026-01-19 00:08:49.463331 | controller | } 2026-01-19 00:08:49.478631 | 2026-01-19 00:08:49.478796 | LOOP [ensure-package-repositories : Import GPG keys] 2026-01-19 00:08:50.114403 | controller | changed: 2026-01-19 00:08:50.114839 | controller | { 2026-01-19 00:08:50.114913 | controller | "data": "-----BEGIN PGP PUBLIC KEY BLOCK-----\n\nmQINBFit5IEBEADDt86QpYKz5flnCsOyZ/fk3WwBKxfDjwHf/GIflo+4GWAXS7wJ\n1PSzPsvSDATV10J44i5WQzh99q+lZvFCVRFiNhRmlmcXG+rk1QmDh3fsCCj9Q/yP\nw8jn3Hx0zDtz8PIB/18ReftYJzUo34COLiHn8WiY20uGCF2pjdPgfxE+K454c4G7\ngKFqVUFYgPug2CS0quaBB5b0rpFUdzTeI5RCStd27nHCpuSDCvRYAfdv+4Y1yiVh\nKKdoe3Smj+RnXeVMgDxtH9FJibZ3DK7WnMN2yeob6VqXox+FvKYJCCLkbQgQmE50\nuVK0uN71A1mQDcTRKQ2q3fFGlMTqJbbzr3LwnCBE6hV0a36t+DABtZTmz5O69xdJ\nWGdBeePCnWVqtDb/BdEYz7hPKskcZBarygCCe2Xi7sZieoFZuq6ltPoCsdfEdfbO\n+VBVKJnExqNZCcFUTEnbH4CldWROOzMS8BGUlkGpa59Sl1t0QcmWlw1EbkeMQNrN\nspdR8lobcdNS9bpAJQqSHRZh3cAM9mA3Yq/bssUS/P2quRXLjJ9mIv3dky9C3udM\n+q2unvnbNpPtIUly76FJ3s8g8sHeOnmYcKqNGqHq2Q3kMdA2eIbI0MqfOIo2+Xk0\nrNt3ctq3g+cQiorcN3rdHPsTRSAcp+NCz1QF9TwXYtH1XV24A6QMO0+CZwARAQAB\ntCtEb2NrZXIgUmVsZWFzZSAoQ0UgcnBtKSA8ZG9ja2VyQGRvY2tlci5jb20+iQI3\nBBMBCgAhBQJYrep4AhsvBQsJCAcDBRUKCQgLBRYCAwEAAh4BAheAAAoJEMUv62ti\nHp816C0P/iP+1uhSa6Qq3TIc5sIFE5JHxOO6y0R97cUdAmCbEqBiJHUPNQDQaaRG\nVYBm0K013Q1gcJeUJvS32gthmIvhkstw7KTodwOM8Kl11CCqZ07NPFef1b2SaJ7l\nTYpyUsT9+e343ph+O4C1oUQw6flaAJe+8ATCmI/4KxfhIjD2a/Q1voR5tUIxfexC\n/LZTx05gyf2mAgEWlRm/cGTStNfqDN1uoKMlV+WFuB1j2oTUuO1/dr8mL+FgZAM3\nntWFo9gQCllNV9ahYOON2gkoZoNuPUnHsf4Bj6BQJnIXbAhMk9H2sZzwUi9bgObZ\nXO8+OrP4D4B9kCAKqqaQqA+O46LzO2vhN74lm/Fy6PumHuviqDBdN+HgtRPMUuao\nxnuVJSvBu9sPdgT/pR1N9u/KnfAnnLtR6g+fx4mWz+ts/riB/KRHzXd+44jGKZra\nIhTMfniguMJNsyEOO0AN8Tqcl0eRBxcOArcri7xu8HFvvl+e+ILymu4buusbYEVL\nGBkYP5YMmScfKn+jnDVN4mWoN1Bq2yMhMGx6PA3hOvzPNsUoYy2BwDxNZyflzuAi\ng59mgJm2NXtzNbSRJbMamKpQ69mzLWGdFNsRd4aH7PT7uPAURaf7B5BVp3UyjERW\n5alSGnBqsZmvlRnVH5BDUhYsWZMPRQS9rRr4iGW0l+TH+O2VJ8aQ\n=0Zqq\n-----END PGP PUBLIC KEY BLOCK-----\n" 2026-01-19 00:08:50.114973 | controller | } 2026-01-19 00:08:50.115185 | controller | changed: All items complete 2026-01-19 00:08:50.115242 | 2026-01-19 00:08:50.139159 | 2026-01-19 00:08:50.139315 | TASK [ensure-package-repositories : Remove GPG temp directory] 2026-01-19 00:08:50.384226 | controller | changed 2026-01-19 00:08:50.397829 | 2026-01-19 00:08:50.398038 | LOOP [ensure-package-repositories : Add repositories] 2026-01-19 00:08:50.748247 | controller | changed: 2026-01-19 00:08:50.748926 | controller | { 2026-01-19 00:08:50.748984 | controller | "baseurl": "https://download.docker.com/linux/fedora/$releasever/$basearch/stable", 2026-01-19 00:08:50.749013 | controller | "description": "Docker CE Stable - $basearch", 2026-01-19 00:08:50.749038 | controller | "gpgcheck": true, 2026-01-19 00:08:50.749061 | controller | "name": "docker-ce-stable" 2026-01-19 00:08:50.749083 | controller | } 2026-01-19 00:08:50.775612 | 2026-01-19 00:08:50.775785 | TASK [ensure-docker : Install pre-reqs] 2026-01-19 00:08:53.558330 | controller | ok: Nothing to do 2026-01-19 00:08:53.570342 | 2026-01-19 00:08:53.570467 | TASK [ensure-docker : Remove packages] 2026-01-19 00:08:54.891505 | controller | ok: Nothing to do 2026-01-19 00:08:54.904734 | 2026-01-19 00:08:54.904871 | TASK [ensure-docker : Install upstream docker using package] 2026-01-19 00:09:40.833852 | controller | changed 2026-01-19 00:09:40.848037 | 2026-01-19 00:09:40.848236 | TASK [ensure-docker : Setup docker] 2026-01-19 00:09:40.878973 | controller | ok 2026-01-19 00:09:40.902186 | controller | included: /var/lib/zuul/builds/27efd94b50e048c1ab6069c282faac5b/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/tasks/docker-setup.yaml 2026-01-19 00:09:40.921557 | 2026-01-19 00:09:40.921714 | TASK [ensure-docker : Ensure "docker" group exists] 2026-01-19 00:09:41.289398 | controller | ok 2026-01-19 00:09:41.295955 | 2026-01-19 00:09:41.296049 | TASK [ensure-docker : Add user to docker group] 2026-01-19 00:09:41.801877 | controller | changed 2026-01-19 00:09:41.817300 | 2026-01-19 00:09:41.817435 | TASK [Update docker daemon proxy configuration] 2026-01-19 00:09:41.855316 | controller | skipping: Conditional result was False 2026-01-19 00:09:41.863955 | 2026-01-19 00:09:41.864823 | TASK [Lower default MTU] 2026-01-19 00:09:41.879273 | controller | skipping: Conditional result was False 2026-01-19 00:09:41.889423 | 2026-01-19 00:09:41.889527 | TASK [ensure-docker : Get the status of the docket.socket unit] 2026-01-19 00:09:42.282686 | controller | inactive 2026-01-19 00:09:42.456914 | controller | ok: Runtime: 0:00:00.011559 2026-01-19 00:09:42.470767 | 2026-01-19 00:09:42.470916 | TASK [ensure-docker : Get the status of the docket unit] 2026-01-19 00:09:42.732177 | controller | inactive 2026-01-19 00:09:43.026628 | controller | ok: Runtime: 0:00:00.014587 2026-01-19 00:09:43.041940 | 2026-01-19 00:09:43.042120 | TASK [ensure-docker : Restart docker] 2026-01-19 00:09:43.104008 | Trigger a Docker restart 2026-01-19 00:09:43.117651 | 2026-01-19 00:09:43.117864 | TASK [ensure-docker : Reset ssh connection to pick up docker group] 2026-01-19 00:09:43.167057 | 2026-01-19 00:09:43.167176 | TASK [ensure-docker : Install docker-compose] 2026-01-19 00:09:43.192728 | controller | skipping: Conditional result was False 2026-01-19 00:09:43.202890 | 2026-01-19 00:09:43.203001 | TASK [ensure-docker : Flush handlers before role exit] 2026-01-19 00:09:43.217914 | 2026-01-19 00:09:43.218012 | TASK [ensure-docker : Stop docker.socket to avoid any conflict] 2026-01-19 00:09:44.388527 | controller | changed 2026-01-19 00:09:44.406294 | 2026-01-19 00:09:44.406464 | TASK [ensure-docker : Assure docker service is running] 2026-01-19 00:09:47.471599 | controller | changed 2026-01-19 00:09:47.492819 | 2026-01-19 00:09:47.493018 | TASK [ensure-docker : Assure docker.socket service is running] 2026-01-19 00:09:48.086980 | controller | ok 2026-01-19 00:09:48.105289 | 2026-01-19 00:09:48.105437 | TASK [ensure-docker : Correct group ownership on docker sock] 2026-01-19 00:09:48.696829 | controller | ok 2026-01-19 00:09:48.711365 | 2026-01-19 00:09:48.711538 | TASK [ensure-docker : Validate ability to talk with docker] 2026-01-19 00:09:49.275880 | controller | CONTAINER ID IMAGE COMMAND CREATED STATUS PORTS NAMES 2026-01-19 00:09:49.767903 | controller | ok: Runtime: 0:00:00.047008 2026-01-19 00:09:49.776842 | 2026-01-19 00:09:49.776957 | TASK [ensure-docker : Get version details] 2026-01-19 00:09:50.099065 | controller | Client: Docker Engine - Community 2026-01-19 00:09:50.099112 | controller | Version: 24.0.7 2026-01-19 00:09:50.099162 | controller | API version: 1.43 2026-01-19 00:09:50.099168 | controller | Go version: go1.20.10 2026-01-19 00:09:50.099179 | controller | Git commit: afdd53b 2026-01-19 00:09:50.099184 | controller | Built: Thu Oct 26 09:09:39 2023 2026-01-19 00:09:50.099189 | controller | OS/Arch: linux/amd64 2026-01-19 00:09:50.099195 | controller | Context: default 2026-01-19 00:09:50.099201 | controller | 2026-01-19 00:09:50.099206 | controller | Server: Docker Engine - Community 2026-01-19 00:09:50.099210 | controller | Engine: 2026-01-19 00:09:50.099218 | controller | Version: 24.0.7 2026-01-19 00:09:50.099222 | controller | API version: 1.43 (minimum version 1.12) 2026-01-19 00:09:50.099226 | controller | Go version: go1.20.10 2026-01-19 00:09:50.099231 | controller | Git commit: 311b9ff 2026-01-19 00:09:50.099235 | controller | Built: Thu Oct 26 09:08:11 2023 2026-01-19 00:09:50.099240 | controller | OS/Arch: linux/amd64 2026-01-19 00:09:50.099252 | controller | Experimental: false 2026-01-19 00:09:50.099256 | controller | containerd: 2026-01-19 00:09:50.099261 | controller | Version: 1.6.25 2026-01-19 00:09:50.099265 | controller | GitCommit: d8f198a4ed8892c764191ef7b3b06d8a2eeb5c7f 2026-01-19 00:09:50.099269 | controller | runc: 2026-01-19 00:09:50.099275 | controller | Version: 1.1.10 2026-01-19 00:09:50.100530 | controller | GitCommit: v1.1.10-0-g18a0cb0 2026-01-19 00:09:50.100557 | controller | docker-init: 2026-01-19 00:09:50.100562 | controller | Version: 0.19.0 2026-01-19 00:09:50.100566 | controller | GitCommit: de40ad0 2026-01-19 00:09:50.321886 | controller | ok: Runtime: 0:00:00.045701 2026-01-19 00:09:50.331133 | 2026-01-19 00:09:50.331241 | TASK [ensure-docker : Dump installed docker details] 2026-01-19 00:09:50.368953 | {'changed': True, 'stdout': 'Client: Docker Engine - Community\n Version: 24.0.7\n API version: 1.43\n Go version: go1.20.10\n Git commit: afdd53b\n Built: Thu Oct 26 09:09:39 2023\n OS/Arch: linux/amd64\n Context: default\n\nServer: Docker Engine - Community\n Engine:\n Version: 24.0.7\n API version: 1.43 (minimum version 1.12)\n Go version: go1.20.10\n Git commit: 311b9ff\n Built: Thu Oct 26 09:08:11 2023\n OS/Arch: linux/amd64\n Experimental: false\n containerd:\n Version: 1.6.25\n GitCommit: d8f198a4ed8892c764191ef7b3b06d8a2eeb5c7f\n runc:\n Version: 1.1.10\n GitCommit: v1.1.10-0-g18a0cb0\n docker-init:\n Version: 0.19.0\n GitCommit: de40ad0', 'stderr': '', 'rc': 0, 'cmd': ['docker', 'version'], 'start': '2026-01-19 00:09:50.055311', 'end': '2026-01-19 00:09:50.101012', 'delta': '0:00:00.045701', 'msg': '', 'zuul_log_id': 'fa163e3b-3c83-dc0f-ad27-000000000061-1-controller', 'stdout_lines': ['Client: Docker Engine - Community', ' Version: 24.0.7', ' API version: 1.43', ' Go version: go1.20.10', ' Git commit: afdd53b', ' Built: Thu Oct 26 09:09:39 2023', ' OS/Arch: linux/amd64', ' Context: default', '', 'Server: Docker Engine - Community', ' Engine:', ' Version: 24.0.7', ' API version: 1.43 (minimum version 1.12)', ' Go version: go1.20.10', ' Git commit: 311b9ff', ' Built: Thu Oct 26 09:08:11 2023', ' OS/Arch: linux/amd64', ' Experimental: false', ' containerd:', ' Version: 1.6.25', ' GitCommit: d8f198a4ed8892c764191ef7b3b06d8a2eeb5c7f', ' runc:', ' Version: 1.1.10', ' GitCommit: v1.1.10-0-g18a0cb0', ' docker-init:', ' Version: 0.19.0', ' GitCommit: de40ad0'], 'stderr_lines': [], 'failed': False} 2026-01-19 00:09:50.381369 | 2026-01-19 00:09:50.381510 | TASK [Use buildset registry] 2026-01-19 00:09:50.400811 | controller | skipping: Conditional result was False 2026-01-19 00:09:50.416578 | 2026-01-19 00:09:50.416708 | TASK [Ensure python3.8 is present] 2026-01-19 00:09:50.431587 | controller | skipping: Conditional result was False 2026-01-19 00:09:50.440372 | 2026-01-19 00:09:50.440469 | TASK [Run our-ensure-python role (Fedora only for now)] 2026-01-19 00:09:50.464478 | controller | ok 2026-01-19 00:09:50.491449 | 2026-01-19 00:09:50.491594 | TASK [our-ensure-python : Install the right Python version (rpm)] 2026-01-19 00:09:52.331151 | controller | ok: Nothing to do 2026-01-19 00:09:52.344757 | 2026-01-19 00:09:52.344951 | TASK [our-ensure-python : Also install python3-devel] 2026-01-19 00:10:03.947362 | controller | changed 2026-01-19 00:10:03.973128 | 2026-01-19 00:10:03.973527 | TASK [Run ensure-virtualenv role] 2026-01-19 00:10:03.993131 | controller | ok 2026-01-19 00:10:04.015061 | 2026-01-19 00:10:04.015156 | TASK [ensure-virtualenv : Check if virtualenv is installed] 2026-01-19 00:10:04.252911 | controller | /usr/bin/virtualenv 2026-01-19 00:10:04.546328 | controller | ok: Runtime: 0:00:00.005430 2026-01-19 00:10:04.552497 | 2026-01-19 00:10:04.552568 | LOOP [ensure-virtualenv : Install virtualenv package] 2026-01-19 00:10:04.587415 | controller | skipping: Conditional result was False 2026-01-19 00:10:04.587727 | controller | changed: All items complete 2026-01-19 00:10:04.587761 | 2026-01-19 00:10:04.612121 | 2026-01-19 00:10:04.612253 | TASK [Find the full path of the Python interpreter] 2026-01-19 00:10:05.220189 | controller | /usr/bin/python3.9 2026-01-19 00:10:05.646668 | controller | ok 2026-01-19 00:10:05.659036 | 2026-01-19 00:10:05.659175 | TASK [Create virtualenv for ansible-test with a specific interpreter] 2026-01-19 00:10:07.295866 | controller | created virtual environment CPython3.9.18.final.0-64 in 837ms 2026-01-19 00:10:07.310781 | controller | creator CPython3Posix(dest=/home/zuul/venv, clear=False, no_vcs_ignore=False, global=False) 2026-01-19 00:10:07.310798 | controller | seeder FromAppData(extra_search_dir=/usr/share/python-wheels,download=False, pip=bundle, setuptools=bundle, wheel=bundle, via=copy, app_data_dir=/home/zuul/.local/share/virtualenv) 2026-01-19 00:10:07.310813 | controller | added seed packages: pip==22.2.2, setuptools==62.6.0, wheel==0.37.1 2026-01-19 00:10:07.310826 | controller | activators BashActivator,CShellActivator,FishActivator,NushellActivator,PowerShellActivator,PythonActivator 2026-01-19 00:10:07.716624 | controller | changed 2026-01-19 00:10:07.728291 | 2026-01-19 00:10:07.728414 | TASK [Set selinux package] 2026-01-19 00:10:07.753088 | controller | ok 2026-01-19 00:10:07.760742 | 2026-01-19 00:10:07.760837 | TASK [Set selinux package (Fedora)] 2026-01-19 00:10:07.794929 | controller | ok 2026-01-19 00:10:07.801731 | 2026-01-19 00:10:07.801818 | TASK [Install selinux into virtualenv] 2026-01-19 00:10:20.935195 | controller | Collecting selinux-please-lie-to-me 2026-01-19 00:10:33.277360 | controller | Downloading selinux_please_lie_to_me-1.0.1-py2.py3-none-any.whl (3.8 kB) 2026-01-19 00:10:33.669784 | controller | Collecting setuptools<50.0.0 2026-01-19 00:10:33.678906 | controller | Downloading setuptools-49.6.0-py3-none-any.whl (803 kB) 2026-01-19 00:10:33.725403 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 803.3/803.3 kB 18.9 MB/s eta 0:00:00 2026-01-19 00:10:33.804926 | controller | Installing collected packages: setuptools, selinux-please-lie-to-me 2026-01-19 00:10:33.805153 | controller | Attempting uninstall: setuptools 2026-01-19 00:10:33.805973 | controller | Found existing installation: setuptools 62.6.0 2026-01-19 00:10:33.878539 | controller | Uninstalling setuptools-62.6.0: 2026-01-19 00:10:33.887382 | controller | Successfully uninstalled setuptools-62.6.0 2026-01-19 00:10:34.228401 | controller | Successfully installed selinux-please-lie-to-me-1.0.1 setuptools-49.6.0 2026-01-19 00:10:45.576840 | controller | 2026-01-19 00:10:45.667428 | controller | [notice] A new release of pip available: 22.2.2 -> 25.3 2026-01-19 00:10:45.667501 | controller | [notice] To update, run: /home/zuul/venv/bin/python -m pip install --upgrade pip 2026-01-19 00:10:45.888770 | controller | ok: Runtime: 0:00:37.504362 2026-01-19 00:10:45.900868 | 2026-01-19 00:10:45.901012 | TASK [Install pytest-forked into virtualenv] 2026-01-19 00:10:57.811553 | controller | Collecting pytest-forked 2026-01-19 00:11:08.015063 | controller | Downloading pytest_forked-1.6.0-py3-none-any.whl (4.9 kB) 2026-01-19 00:11:08.143931 | controller | Collecting pytest>=3.10 2026-01-19 00:11:08.148875 | controller | Downloading pytest-8.4.2-py3-none-any.whl (365 kB) 2026-01-19 00:11:08.195016 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 365.8/365.8 kB 9.1 MB/s eta 0:00:00 2026-01-19 00:11:08.242492 | controller | Collecting py 2026-01-19 00:11:08.246957 | controller | Downloading py-1.11.0-py2.py3-none-any.whl (98 kB) 2026-01-19 00:11:08.256786 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 98.7/98.7 kB 14.2 MB/s eta 0:00:00 2026-01-19 00:11:08.307347 | controller | Collecting exceptiongroup>=1 2026-01-19 00:11:08.313285 | controller | Downloading exceptiongroup-1.3.1-py3-none-any.whl (16 kB) 2026-01-19 00:11:08.344150 | controller | Collecting iniconfig>=1 2026-01-19 00:11:08.368528 | controller | Downloading iniconfig-2.1.0-py3-none-any.whl (6.0 kB) 2026-01-19 00:11:08.416201 | controller | Collecting pygments>=2.7.2 2026-01-19 00:11:08.422894 | controller | Downloading pygments-2.19.2-py3-none-any.whl (1.2 MB) 2026-01-19 00:11:08.454049 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 1.2/1.2 MB 43.5 MB/s eta 0:00:00 2026-01-19 00:11:08.512669 | controller | Collecting tomli>=1 2026-01-19 00:11:08.519427 | controller | Downloading tomli-2.4.0-py3-none-any.whl (14 kB) 2026-01-19 00:11:08.563600 | controller | Collecting pluggy<2,>=1.5 2026-01-19 00:11:08.567556 | controller | Downloading pluggy-1.6.0-py3-none-any.whl (20 kB) 2026-01-19 00:11:08.615273 | controller | Collecting packaging>=20 2026-01-19 00:11:08.619175 | controller | Downloading packaging-25.0-py3-none-any.whl (66 kB) 2026-01-19 00:11:08.627296 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 66.5/66.5 kB 11.9 MB/s eta 0:00:00 2026-01-19 00:11:08.684094 | controller | Collecting typing-extensions>=4.6.0 2026-01-19 00:11:08.688918 | controller | Downloading typing_extensions-4.15.0-py3-none-any.whl (44 kB) 2026-01-19 00:11:08.699028 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 44.6/44.6 kB 6.5 MB/s eta 0:00:00 2026-01-19 00:11:08.770466 | controller | Installing collected packages: typing-extensions, tomli, pygments, py, pluggy, packaging, iniconfig, exceptiongroup, pytest, pytest-forked 2026-01-19 00:11:09.840821 | controller | Successfully installed exceptiongroup-1.3.1 iniconfig-2.1.0 packaging-25.0 pluggy-1.6.0 py-1.11.0 pygments-2.19.2 pytest-8.4.2 pytest-forked-1.6.0 tomli-2.4.0 typing-extensions-4.15.0 2026-01-19 00:11:09.924718 | controller | 2026-01-19 00:11:09.924735 | controller | [notice] A new release of pip available: 22.2.2 -> 25.3 2026-01-19 00:11:09.924740 | controller | [notice] To update, run: /home/zuul/venv/bin/python -m pip install --upgrade pip 2026-01-19 00:11:10.009460 | controller | ok: Runtime: 0:00:23.759788 2026-01-19 00:11:10.022922 | 2026-01-19 00:11:10.023097 | TASK [Update pip] 2026-01-19 00:11:10.559873 | controller | Requirement already satisfied: pip in ./venv/lib/python3.9/site-packages (22.2.2) 2026-01-19 00:11:20.406150 | controller | Collecting pip 2026-01-19 00:11:32.584569 | controller | Downloading pip-25.3-py3-none-any.whl (1.8 MB) 2026-01-19 00:11:32.629971 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 1.8/1.8 MB 42.3 MB/s eta 0:00:00 2026-01-19 00:11:32.689272 | controller | Installing collected packages: pip 2026-01-19 00:11:32.689362 | controller | Attempting uninstall: pip 2026-01-19 00:11:32.690034 | controller | Found existing installation: pip 22.2.2 2026-01-19 00:11:32.856498 | controller | Uninstalling pip-22.2.2: 2026-01-19 00:11:32.873818 | controller | Successfully uninstalled pip-22.2.2 2026-01-19 00:11:33.649956 | controller | Successfully installed pip-25.3 2026-01-19 00:11:34.090864 | controller | ok: Runtime: 0:00:23.472255 2026-01-19 00:11:34.104471 | 2026-01-19 00:11:34.104631 | TASK [Ensure pip temp directory exists in /var/tmp] 2026-01-19 00:11:34.342047 | controller | changed 2026-01-19 00:11:34.357389 | 2026-01-19 00:11:34.357549 | TASK [Install ansible into virtualenv] 2026-01-19 00:11:34.864514 | controller | Processing ./src/github.com/ansible/ansible 2026-01-19 00:11:34.868365 | controller | Installing build dependencies: started 2026-01-19 00:11:57.469070 | controller | Installing build dependencies: finished with status 'done' 2026-01-19 00:11:57.470868 | controller | Getting requirements to build wheel: started 2026-01-19 00:11:58.071657 | controller | Getting requirements to build wheel: finished with status 'done' 2026-01-19 00:11:58.074082 | controller | Preparing metadata (pyproject.toml): started 2026-01-19 00:11:58.523438 | controller | Preparing metadata (pyproject.toml): finished with status 'done' 2026-01-19 00:12:09.484819 | controller | Collecting jinja2 (from ansible-core==2.11.12.post0) 2026-01-19 00:12:21.743038 | controller | Downloading jinja2-3.1.6-py3-none-any.whl.metadata (2.9 kB) 2026-01-19 00:12:21.828957 | controller | Collecting PyYAML (from ansible-core==2.11.12.post0) 2026-01-19 00:12:21.835448 | controller | Downloading pyyaml-6.0.3-cp39-cp39-manylinux2014_x86_64.manylinux_2_17_x86_64.manylinux_2_28_x86_64.whl.metadata (2.4 kB) 2026-01-19 00:12:22.126882 | controller | Collecting cryptography (from ansible-core==2.11.12.post0) 2026-01-19 00:12:22.133274 | controller | Downloading cryptography-46.0.3-cp38-abi3-manylinux_2_34_x86_64.whl.metadata (5.7 kB) 2026-01-19 00:12:22.150254 | controller | Requirement already satisfied: packaging in ./venv/lib/python3.9/site-packages (from ansible-core==2.11.12.post0) (25.0) 2026-01-19 00:12:22.169153 | controller | Collecting resolvelib<0.6.0,>=0.5.3 (from ansible-core==2.11.12.post0) 2026-01-19 00:12:22.179232 | controller | Downloading resolvelib-0.5.4-py2.py3-none-any.whl.metadata (3.7 kB) 2026-01-19 00:12:22.345723 | controller | Collecting cffi>=2.0.0 (from cryptography->ansible-core==2.11.12.post0) 2026-01-19 00:12:22.350617 | controller | Downloading cffi-2.0.0-cp39-cp39-manylinux2014_x86_64.manylinux_2_17_x86_64.whl.metadata (2.6 kB) 2026-01-19 00:12:22.357863 | controller | Requirement already satisfied: typing-extensions>=4.13.2 in ./venv/lib/python3.9/site-packages (from cryptography->ansible-core==2.11.12.post0) (4.15.0) 2026-01-19 00:12:22.372638 | controller | Collecting pycparser (from cffi>=2.0.0->cryptography->ansible-core==2.11.12.post0) 2026-01-19 00:12:22.376687 | controller | Downloading pycparser-2.23-py3-none-any.whl.metadata (993 bytes) 2026-01-19 00:12:22.464236 | controller | Collecting MarkupSafe>=2.0 (from jinja2->ansible-core==2.11.12.post0) 2026-01-19 00:12:22.469432 | controller | Downloading markupsafe-3.0.3-cp39-cp39-manylinux2014_x86_64.manylinux_2_17_x86_64.manylinux_2_28_x86_64.whl.metadata (2.7 kB) 2026-01-19 00:12:22.485630 | controller | Downloading resolvelib-0.5.4-py2.py3-none-any.whl (12 kB) 2026-01-19 00:12:22.496527 | controller | Downloading cryptography-46.0.3-cp38-abi3-manylinux_2_34_x86_64.whl (4.5 MB) 2026-01-19 00:12:22.571984 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 4.5/4.5 MB 67.6 MB/s 0:00:00 2026-01-19 00:12:22.579961 | controller | Downloading cffi-2.0.0-cp39-cp39-manylinux2014_x86_64.manylinux_2_17_x86_64.whl (216 kB) 2026-01-19 00:12:22.592856 | controller | Downloading jinja2-3.1.6-py3-none-any.whl (134 kB) 2026-01-19 00:12:22.606025 | controller | Downloading markupsafe-3.0.3-cp39-cp39-manylinux2014_x86_64.manylinux_2_17_x86_64.manylinux_2_28_x86_64.whl (20 kB) 2026-01-19 00:12:22.618561 | controller | Downloading pycparser-2.23-py3-none-any.whl (118 kB) 2026-01-19 00:12:22.631885 | controller | Downloading pyyaml-6.0.3-cp39-cp39-manylinux2014_x86_64.manylinux_2_17_x86_64.manylinux_2_28_x86_64.whl (750 kB) 2026-01-19 00:12:22.647665 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 750.8/750.8 kB 41.6 MB/s 0:00:00 2026-01-19 00:12:22.661527 | controller | Building wheels for collected packages: ansible-core 2026-01-19 00:12:22.662410 | controller | Building wheel for ansible-core (pyproject.toml): started 2026-01-19 00:12:24.073467 | controller | Building wheel for ansible-core (pyproject.toml): finished with status 'done' 2026-01-19 00:12:24.078956 | controller | Created wheel for ansible-core: filename=ansible_core-2.11.12.post0-py3-none-any.whl size=1961250 sha256=1c45d267574676680b2ccce8911c8c1f0909f813a04a8f7963823f51819bbb05 2026-01-19 00:12:24.079412 | controller | Stored in directory: /var/tmp/ansible-test-pip/pip-ephem-wheel-cache-6s5ey5km/wheels/0f/a0/93/ab85b739e1eb1e0467171316f13571c14efdf87e0d5d44a33e 2026-01-19 00:12:24.088562 | controller | Successfully built ansible-core 2026-01-19 00:12:24.125494 | controller | Installing collected packages: resolvelib, PyYAML, pycparser, MarkupSafe, jinja2, cffi, cryptography, ansible-core 2026-01-19 00:12:25.607621 | controller | 2026-01-19 00:12:25.611068 | controller | Successfully installed MarkupSafe-3.0.3 PyYAML-6.0.3 ansible-core-2.11.12.post0 cffi-2.0.0 cryptography-46.0.3 jinja2-3.1.6 pycparser-2.23 resolvelib-0.5.4 2026-01-19 00:12:25.942467 | controller | ok: Runtime: 0:00:51.142976 2026-01-19 00:12:25.955667 | 2026-01-19 00:12:25.955852 | TASK [Setup download-artifact-fork role] 2026-01-19 00:12:26.022246 | controller | ok 2026-01-19 00:12:26.064306 | 2026-01-19 00:12:26.064434 | TASK [download-artifact-fork : Parse inventory response] 2026-01-19 00:12:26.094643 | controller | ok 2026-01-19 00:12:26.101435 | 2026-01-19 00:12:26.101527 | TASK [download-artifact-fork : Fetch info from Zuul API] 2026-01-19 00:12:26.136997 | controller | skipping: Conditional result was False 2026-01-19 00:12:26.144241 | 2026-01-19 00:12:26.144335 | TASK [download-artifact-fork : Parse build response] 2026-01-19 00:12:26.168251 | controller | skipping: Conditional result was False 2026-01-19 00:12:26.174443 | 2026-01-19 00:12:26.174516 | TASK [download-artifact-fork : Ensure artifacts directory exists] 2026-01-19 00:12:26.404156 | controller | changed 2026-01-19 00:12:26.416925 | 2026-01-19 00:12:26.417084 | LOOP [download-artifact-fork : Download archive by type] 2026-01-19 00:12:26.455977 | controller | skipping: Conditional result was False 2026-01-19 00:12:26.456403 | 2026-01-19 00:12:27.268345 | controller | changed: OK (20185 bytes) 2026-01-19 00:12:27.294601 | 2026-01-19 00:12:27.294841 | TASK [deploy-artifacts : define __collections] 2026-01-19 00:12:27.332464 | controller | ok 2026-01-19 00:12:27.344284 | 2026-01-19 00:12:27.344454 | LOOP [deploy-artifacts : Create list of collections] 2026-01-19 00:12:27.384138 | controller | skipping: Conditional result was False 2026-01-19 00:12:27.399818 | controller | ok: 2026-01-19 00:12:27.399998 | controller | { 2026-01-19 00:12:27.400033 | controller | "branch": "main", 2026-01-19 00:12:27.400059 | controller | "job": "build-ansible-collection", 2026-01-19 00:12:27.400083 | controller | "metadata": { 2026-01-19 00:12:27.400106 | controller | "type": "ansible_collection", 2026-01-19 00:12:27.400129 | controller | "version": "5.0.0" 2026-01-19 00:12:27.400151 | controller | }, 2026-01-19 00:12:27.400173 | controller | "name": "ansible.network", 2026-01-19 00:12:27.400195 | controller | "project": "ansible-collections/ansible.network", 2026-01-19 00:12:27.400218 | controller | "url": "https://9796e7590646178d8df3-f4f2a03c4c4cfc8aff10bd8bce9cc399.ssl.cf2.rackcdn.com/ansible/ca26ca8d1d7e45ba97836ed1fd7bddaf/artifacts/ansible-network-5.0.0.tar.gz" 2026-01-19 00:12:27.400250 | controller | } 2026-01-19 00:12:27.411971 | 2026-01-19 00:12:27.412114 | TASK [deploy-artifacts : Install require-project collection using ansible-galaxy] 2026-01-19 00:12:28.026245 | controller | Starting galaxy collection install process 2026-01-19 00:12:28.026471 | controller | Process install dependency map 2026-01-19 00:13:20.893459 | controller | [WARNING]: Skipping Galaxy server https://galaxy.ansible.com/api/. Got an 2026-01-19 00:13:20.894114 | controller | unexpected error when getting available versions of collection 2026-01-19 00:13:20.894161 | controller | ansible.netcommon: '/api/v3/plugin/ansible/content/published/collections/index/ 2026-01-19 00:13:20.894174 | controller | ansible/netcommon/versions/' 2026-01-19 00:13:20.894191 | controller | ERROR! Unexpected Exception, this is probably a bug: '/api/v3/plugin/ansible/content/published/collections/index/ansible/netcommon/versions/' 2026-01-19 00:13:20.894283 | controller | to see the full traceback, use -vvv 2026-01-19 00:13:20.981820 | controller | ERROR 2026-01-19 00:13:20.981932 | controller | { 2026-01-19 00:13:20.981959 | controller | "delta": "0:00:53.305663", 2026-01-19 00:13:20.981978 | controller | "end": "2026-01-19 00:13:20.940344", 2026-01-19 00:13:20.981995 | controller | "msg": "non-zero return code", 2026-01-19 00:13:20.982012 | controller | "rc": 250, 2026-01-19 00:13:20.982029 | controller | "start": "2026-01-19 00:12:27.634681" 2026-01-19 00:13:20.982045 | controller | } failure 2026-01-19 00:13:20.984155 | 2026-01-19 00:13:20.984204 | PLAY RECAP 2026-01-19 00:13:20.984246 | controller | ok: 44 changed: 24 unreachable: 0 failed: 1 skipped: 17 rescued: 0 ignored: 0 2026-01-19 00:13:20.984266 | 2026-01-19 00:13:21.120794 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master] 2026-01-19 00:13:21.121980 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2026-01-19 00:13:21.733615 | 2026-01-19 00:13:21.733744 | PLAY [all] 2026-01-19 00:13:21.757439 | 2026-01-19 00:13:21.757543 | TASK [fetch-subunit-output : Find stestr or testr executable] 2026-01-19 00:13:22.187643 | controller | changed: non-zero return code 2026-01-19 00:13:22.195434 | 2026-01-19 00:13:22.195543 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2026-01-19 00:13:22.220879 | controller | skipping: Conditional result was False 2026-01-19 00:13:22.229236 | 2026-01-19 00:13:22.229331 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2026-01-19 00:13:22.262634 | 2026-01-19 00:13:22.262827 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2026-01-19 00:13:22.296040 | 2026-01-19 00:13:22.296235 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2026-01-19 00:13:22.322906 | controller | skipping: Conditional result was False 2026-01-19 00:13:22.332818 | 2026-01-19 00:13:22.332920 | LOOP [fetch-subunit-output : Generate subunit file] 2026-01-19 00:13:22.363363 | 2026-01-19 00:13:22.363497 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2026-01-19 00:13:22.378253 | controller | skipping: Conditional result was False 2026-01-19 00:13:22.384950 | 2026-01-19 00:13:22.385023 | TASK [fetch-subunit-output : Remove the temporary file] 2026-01-19 00:13:22.405026 | controller | skipping: Conditional result was False 2026-01-19 00:13:22.411954 | 2026-01-19 00:13:22.412025 | TASK [fetch-subunit-output : Process and fetch subunit results] 2026-01-19 00:13:22.431995 | controller | skipping: Conditional result was False 2026-01-19 00:13:22.459106 | 2026-01-19 00:13:22.459197 | PLAY RECAP 2026-01-19 00:13:22.459236 | controller | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2026-01-19 00:13:22.459255 | 2026-01-19 00:13:22.552490 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2026-01-19 00:13:22.553424 | POST-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/post.yaml@master] 2026-01-19 00:13:23.179768 | 2026-01-19 00:13:23.179945 | PLAY [all:!appliance*] 2026-01-19 00:13:23.203294 | 2026-01-19 00:13:23.203395 | TASK [unregister the node] 2026-01-19 00:13:23.738003 | controller | skipping: Conditional result was False 2026-01-19 00:13:23.751156 | 2026-01-19 00:13:23.751333 | TASK [include_role : fetch-output] 2026-01-19 00:13:23.791484 | controller | ok 2026-01-19 00:13:23.827553 | 2026-01-19 00:13:23.827671 | TASK [fetch-output : Set log path for multiple nodes] 2026-01-19 00:13:23.894907 | controller | skipping: Conditional result was False 2026-01-19 00:13:23.901270 | 2026-01-19 00:13:23.901340 | TASK [fetch-output : Set log path for single node] 2026-01-19 00:13:23.950998 | controller | ok 2026-01-19 00:13:23.973495 | 2026-01-19 00:13:23.973715 | LOOP [fetch-output : Ensure local output dirs] 2026-01-19 00:13:24.386819 | controller -> localhost | ok: "/var/lib/zuul/builds/27efd94b50e048c1ab6069c282faac5b/work/logs" 2026-01-19 00:13:24.590490 | controller -> localhost | changed: "/var/lib/zuul/builds/27efd94b50e048c1ab6069c282faac5b/work/artifacts" 2026-01-19 00:13:24.816258 | controller -> localhost | changed: "/var/lib/zuul/builds/27efd94b50e048c1ab6069c282faac5b/work/docs" 2026-01-19 00:13:24.825857 | 2026-01-19 00:13:24.825967 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-01-19 00:13:25.614539 | controller | changed: 2026-01-19 00:13:25.614756 | controller | .d..t...... ./ 2026-01-19 00:13:25.614785 | controller | cd+++++++++ controller/ 2026-01-19 00:13:25.614816 | controller | changed: All items complete 2026-01-19 00:13:25.614835 | 2026-01-19 00:13:26.157820 | controller | changed: .d..t...... ./ 2026-01-19 00:13:26.724364 | controller | changed: .d..t...... ./ 2026-01-19 00:13:26.748368 | 2026-01-19 00:13:26.748493 | TASK [include_role : fetch-output-openshift] 2026-01-19 00:13:26.762849 | controller | skipping: Conditional result was False 2026-01-19 00:13:26.769922 | 2026-01-19 00:13:26.770006 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-01-19 00:13:26.807016 | controller | skipping: Conditional result was False 2026-01-19 00:13:26.814221 | controller | skipping: Conditional result was False 2026-01-19 00:13:26.852987 | 2026-01-19 00:13:26.853194 | PLAY [localhost] 2026-01-19 00:13:26.875193 | 2026-01-19 00:13:26.875283 | TASK [Run Zuul manifest role] 2026-01-19 00:13:26.898374 | localhost | ok 2026-01-19 00:13:26.913653 | 2026-01-19 00:13:26.913752 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-01-19 00:13:27.310382 | localhost | changed 2026-01-19 00:13:27.329338 | 2026-01-19 00:13:27.329544 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-01-19 00:13:27.366725 | localhost | ok 2026-01-19 00:13:27.383128 | 2026-01-19 00:13:27.383251 | TASK [Set zuul-log-path fact] 2026-01-19 00:13:27.418238 | localhost | ok 2026-01-19 00:13:27.438855 | 2026-01-19 00:13:27.438992 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-01-19 00:13:27.468300 | localhost | ok 2026-01-19 00:13:27.477542 | 2026-01-19 00:13:27.477660 | LOOP [Run upload-logs-swift role] 2026-01-19 00:13:27.501994 | localhost | Output suppressed because no_log was given 2026-01-19 00:13:27.534078 | 2026-01-19 00:13:27.534203 | TASK [Set zuul-log-path fact] 2026-01-19 00:13:27.558089 | localhost | skipping: Conditional result was False 2026-01-19 00:13:27.563055 | 2026-01-19 00:13:27.563177 | TASK [upload-logs-swift : Ensure logs are readable before uploading] 2026-01-19 00:13:28.013249 | localhost -> localhost | ok: Runtime: 0:00:00.021111 2026-01-19 00:13:28.055102 | 2026-01-19 00:13:28.055237 | TASK [upload-logs-swift : Upload logs to swift]