2025-09-23 00:06:39.909120 | Job console starting... 2025-09-23 00:06:39.922771 | Updating repositories 2025-09-23 00:06:40.751863 | Preparing job workspace 2025-09-23 00:06:44.951458 | Running Ansible setup... 2025-09-23 00:06:51.505470 | PRE-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master] 2025-09-23 00:06:52.185830 | 2025-09-23 00:06:52.185975 | PLAY [localhost] 2025-09-23 00:06:52.203982 | 2025-09-23 00:06:52.204087 | TASK [Gathering Facts] 2025-09-23 00:06:53.349220 | localhost | ok 2025-09-23 00:06:53.368506 | 2025-09-23 00:06:53.368694 | TASK [Setup log path fact] 2025-09-23 00:06:53.402706 | localhost | ok 2025-09-23 00:06:53.421949 | 2025-09-23 00:06:53.422122 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-09-23 00:06:53.465749 | localhost | ok 2025-09-23 00:06:53.477830 | 2025-09-23 00:06:53.477984 | TASK [emit-job-header : Print job information] 2025-09-23 00:06:53.529727 | # Job Information 2025-09-23 00:06:53.529952 | Ansible Version: 2.15.12 2025-09-23 00:06:53.529986 | Job: ansible-test-sanity-docker-stable-2.11 2025-09-23 00:06:53.530007 | Pipeline: periodic 2025-09-23 00:06:53.530026 | Executor: ze02.softwarefactory-project.io 2025-09-23 00:06:53.530045 | Triggered by: https://github.com/ansible-collections/ansible.network 2025-09-23 00:06:53.530066 | Log URL (when completed): https://ansible.softwarefactory-project.io/logs/7fd/ansible/7fd40f5e12da4ae48617f6fa8be4ffb7/ 2025-09-23 00:06:53.530085 | Event ID: ce90a15208884c3e9ebd24fbd880974e 2025-09-23 00:06:53.534414 | 2025-09-23 00:06:53.534493 | LOOP [emit-job-header : Print node information] 2025-09-23 00:06:53.655235 | localhost | ok: 2025-09-23 00:06:53.655639 | localhost | # Node Information 2025-09-23 00:06:53.655736 | localhost | Inventory Hostname: controller 2025-09-23 00:06:53.655783 | localhost | Hostname: ip-172-16-36-125 2025-09-23 00:06:53.655826 | localhost | Username: zuul-worker 2025-09-23 00:06:53.655873 | localhost | Distro: Fedora 37 2025-09-23 00:06:53.655913 | localhost | Provider: ansible-us-east-2 2025-09-23 00:06:53.655952 | localhost | Region: us-east-2 2025-09-23 00:06:53.655989 | localhost | Label: ansible-fedora-37-1vcpu 2025-09-23 00:06:53.656027 | localhost | Product Name: t3.small 2025-09-23 00:06:53.656065 | localhost | Interface IP: 3.23.96.7 2025-09-23 00:06:53.678527 | 2025-09-23 00:06:53.678836 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-09-23 00:06:54.076227 | localhost -> localhost | changed 2025-09-23 00:06:54.084019 | 2025-09-23 00:06:54.084119 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-09-23 00:06:54.991609 | localhost -> localhost | changed 2025-09-23 00:06:55.023159 | 2025-09-23 00:06:55.023258 | PLAY [all:!appliance*] 2025-09-23 00:06:55.046841 | 2025-09-23 00:06:55.046917 | TASK [include_role : start-zuul-console] 2025-09-23 00:06:55.071592 | controller | ok 2025-09-23 00:06:55.089870 | 2025-09-23 00:06:55.089939 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-09-23 00:06:55.811284 | controller | ok 2025-09-23 00:06:55.840162 | 2025-09-23 00:06:55.840283 | TASK [use-our-mirror : Retrieve the IP address] 2025-09-23 00:06:57.214481 | controller | ok: Status code was 404 and not [200]: HTTP Error 404: Not Found 2025-09-23 00:06:57.223132 | 2025-09-23 00:06:57.223214 | TASK [use-our-mirror : Ensure the registry service is running on the mirror] 2025-09-23 00:06:57.356131 | controller | skipping: Conditional result was False 2025-09-23 00:06:57.364878 | 2025-09-23 00:06:57.364966 | TASK [use-our-mirror : Write the IP in /etc/hosts] 2025-09-23 00:06:57.391251 | controller | skipping: Conditional result was False 2025-09-23 00:06:57.399512 | 2025-09-23 00:06:57.399594 | TASK [use-our-mirror : Create the podman configuration directory] 2025-09-23 00:06:57.415911 | controller | skipping: Conditional result was False 2025-09-23 00:06:57.424606 | 2025-09-23 00:06:57.424718 | TASK [use-our-mirror : Copy the podman configuration] 2025-09-23 00:06:57.450712 | controller | skipping: Conditional result was False 2025-09-23 00:06:57.459197 | 2025-09-23 00:06:57.459291 | TASK [use-our-mirror : Copy the Yum configuration (main)] 2025-09-23 00:06:57.484316 | controller | skipping: Conditional result was False 2025-09-23 00:06:57.491885 | 2025-09-23 00:06:57.491993 | TASK [use-our-mirror : Copy the Yum configuration (update)] 2025-09-23 00:06:57.516433 | controller | skipping: Conditional result was False 2025-09-23 00:06:57.528903 | 2025-09-23 00:06:57.528990 | TASK [Disable Fedora Modular] 2025-09-23 00:06:58.252867 | controller | changed 2025-09-23 00:06:58.264886 | 2025-09-23 00:06:58.265048 | TASK [Enable EPEL] 2025-09-23 00:06:58.291803 | controller | skipping: Conditional result was False 2025-09-23 00:06:58.301353 | 2025-09-23 00:06:58.301463 | TASK [Register the RHEL node] 2025-09-23 00:06:58.462996 | 2025-09-23 00:06:58.463245 | TASK [Show the subscription-manager status] 2025-09-23 00:06:58.609586 | controller | skipping: Conditional result was False 2025-09-23 00:06:58.646938 | 2025-09-23 00:06:58.647070 | TASK [Enable EPEL on RHEL] 2025-09-23 00:06:58.779083 | controller | skipping: Conditional result was False 2025-09-23 00:06:58.792151 | 2025-09-23 00:06:58.792284 | TASK [Install git and tox] 2025-09-23 00:08:30.379563 | controller | changed 2025-09-23 00:08:30.393813 | 2025-09-23 00:08:30.393946 | TASK [include_role : prepare-workspace] 2025-09-23 00:08:30.435982 | controller | ok 2025-09-23 00:08:30.485016 | 2025-09-23 00:08:30.485231 | TASK [prepare-workspace : Start zuul_console daemon.] 2025-09-23 00:08:31.006607 | controller | ok 2025-09-23 00:08:31.024916 | 2025-09-23 00:08:31.025159 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2025-09-23 00:08:44.871764 | controller | Output suppressed because no_log was given 2025-09-23 00:08:44.888080 | 2025-09-23 00:08:44.888217 | TASK [include_role : prepare-workspace-openshift] 2025-09-23 00:08:44.925137 | controller | skipping: Conditional result was False 2025-09-23 00:08:44.962934 | 2025-09-23 00:08:44.963053 | PLAY [all:!appliance] 2025-09-23 00:08:44.981401 | 2025-09-23 00:08:44.981518 | TASK [Run add-build-sshkey role (RSA)] 2025-09-23 00:08:45.002608 | controller | ok 2025-09-23 00:08:45.017419 | 2025-09-23 00:08:45.017539 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-09-23 00:08:45.287747 | controller -> localhost | ok 2025-09-23 00:08:45.301950 | 2025-09-23 00:08:45.302096 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-09-23 00:08:45.332377 | controller | ok 2025-09-23 00:08:45.355778 | controller | included: /var/lib/zuul/builds/7fd40f5e12da4ae48617f6fa8be4ffb7/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-09-23 00:08:45.396761 | 2025-09-23 00:08:45.396924 | TASK [add-build-sshkey : Create Temp SSH key] 2025-09-23 00:08:45.971008 | controller -> localhost | Generating public/private rsa key pair. 2025-09-23 00:08:45.971303 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/7fd40f5e12da4ae48617f6fa8be4ffb7/work/7fd40f5e12da4ae48617f6fa8be4ffb7_id_rsa. 2025-09-23 00:08:45.971348 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/7fd40f5e12da4ae48617f6fa8be4ffb7/work/7fd40f5e12da4ae48617f6fa8be4ffb7_id_rsa.pub. 2025-09-23 00:08:45.971381 | controller -> localhost | The key fingerprint is: 2025-09-23 00:08:45.971412 | controller -> localhost | SHA256:TklgC2mGjsZra+atalwj67S/yfz/J46pA64fUQ/a91o zuul-build-sshkey 2025-09-23 00:08:45.971441 | controller -> localhost | The key's randomart image is: 2025-09-23 00:08:45.971471 | controller -> localhost | +---[RSA 2048]----+ 2025-09-23 00:08:45.971539 | controller -> localhost | | ...o | 2025-09-23 00:08:45.971570 | controller -> localhost | | . +o o | 2025-09-23 00:08:45.971598 | controller -> localhost | |.o o o. . | 2025-09-23 00:08:45.971626 | controller -> localhost | |.o. + o. . | 2025-09-23 00:08:45.971653 | controller -> localhost | |. .o . oS | 2025-09-23 00:08:45.971765 | controller -> localhost | | + +. .o. | 2025-09-23 00:08:45.971803 | controller -> localhost | |o.*.o .E | 2025-09-23 00:08:45.971835 | controller -> localhost | |.B=.o. =. . | 2025-09-23 00:08:45.972032 | controller -> localhost | |O*=Ooo+=ooo | 2025-09-23 00:08:45.972063 | controller -> localhost | +----[SHA256]-----+ 2025-09-23 00:08:45.972139 | controller -> localhost | ok: Runtime: 0:00:00.073122 2025-09-23 00:08:45.982412 | 2025-09-23 00:08:45.982511 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-09-23 00:08:46.031457 | controller | ok 2025-09-23 00:08:46.053299 | controller | included: /var/lib/zuul/builds/7fd40f5e12da4ae48617f6fa8be4ffb7/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-09-23 00:08:46.066275 | 2025-09-23 00:08:46.066393 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-09-23 00:08:46.093331 | controller | skipping: Conditional result was False 2025-09-23 00:08:46.103383 | 2025-09-23 00:08:46.103486 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-09-23 00:08:46.777272 | controller | changed 2025-09-23 00:08:46.791913 | 2025-09-23 00:08:46.792103 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-09-23 00:08:47.151421 | controller | ok 2025-09-23 00:08:47.165733 | 2025-09-23 00:08:47.165906 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-09-23 00:08:48.468538 | controller | changed 2025-09-23 00:08:48.481813 | 2025-09-23 00:08:48.482005 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-09-23 00:08:49.747610 | controller | changed 2025-09-23 00:08:49.762279 | 2025-09-23 00:08:49.762513 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-09-23 00:08:49.792583 | controller | skipping: Conditional result was False 2025-09-23 00:08:49.800540 | 2025-09-23 00:08:49.800683 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-09-23 00:08:50.225403 | controller -> localhost | changed 2025-09-23 00:08:50.250829 | 2025-09-23 00:08:50.251002 | TASK [add-build-sshkey : Add back temp key] 2025-09-23 00:08:50.638041 | controller -> localhost | Identity added: /var/lib/zuul/builds/7fd40f5e12da4ae48617f6fa8be4ffb7/work/7fd40f5e12da4ae48617f6fa8be4ffb7_id_rsa (zuul-build-sshkey) 2025-09-23 00:08:50.638658 | controller -> localhost | ok: Runtime: 0:00:00.011783 2025-09-23 00:08:50.656733 | 2025-09-23 00:08:50.656912 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-09-23 00:08:51.152168 | controller | ok 2025-09-23 00:08:51.167264 | 2025-09-23 00:08:51.167369 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-09-23 00:08:51.215166 | controller | skipping: Conditional result was False 2025-09-23 00:08:51.243133 | 2025-09-23 00:08:51.243357 | TASK [Run add-build-sshkey role (ECDSA)] 2025-09-23 00:08:51.287757 | controller | ok 2025-09-23 00:08:51.321047 | 2025-09-23 00:08:51.321158 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-09-23 00:08:51.602338 | controller -> localhost | ok 2025-09-23 00:08:51.608837 | 2025-09-23 00:08:51.608904 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-09-23 00:08:51.639085 | controller | ok 2025-09-23 00:08:51.650999 | controller | included: /var/lib/zuul/builds/7fd40f5e12da4ae48617f6fa8be4ffb7/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-09-23 00:08:51.657042 | 2025-09-23 00:08:51.657102 | TASK [add-build-sshkey : Create Temp SSH key] 2025-09-23 00:08:52.007307 | controller -> localhost | Generating public/private ecdsa key pair. 2025-09-23 00:08:52.007730 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/7fd40f5e12da4ae48617f6fa8be4ffb7/work/7fd40f5e12da4ae48617f6fa8be4ffb7_id_ecdsa. 2025-09-23 00:08:52.007813 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/7fd40f5e12da4ae48617f6fa8be4ffb7/work/7fd40f5e12da4ae48617f6fa8be4ffb7_id_ecdsa.pub. 2025-09-23 00:08:52.007887 | controller -> localhost | The key fingerprint is: 2025-09-23 00:08:52.007939 | controller -> localhost | SHA256:Idqov2sAm470AXyXFdqCn1Msww/wOoykFKh/lKSUIEQ zuul-build-sshkey 2025-09-23 00:08:52.007989 | controller -> localhost | The key's randomart image is: 2025-09-23 00:08:52.008039 | controller -> localhost | +---[ECDSA 521]---+ 2025-09-23 00:08:52.008086 | controller -> localhost | |*E o .. | 2025-09-23 00:08:52.008133 | controller -> localhost | |o + * +. | 2025-09-23 00:08:52.008181 | controller -> localhost | |o+ + X++. | 2025-09-23 00:08:52.008227 | controller -> localhost | |*o+.=*O. . | 2025-09-23 00:08:52.008273 | controller -> localhost | |.*o=++..S | 2025-09-23 00:08:52.008319 | controller -> localhost | |o.ooo . | 2025-09-23 00:08:52.008365 | controller -> localhost | |+ oo. | 2025-09-23 00:08:52.008411 | controller -> localhost | |.. o. | 2025-09-23 00:08:52.008457 | controller -> localhost | | .+o | 2025-09-23 00:08:52.008503 | controller -> localhost | +----[SHA256]-----+ 2025-09-23 00:08:52.008610 | controller -> localhost | ok: Runtime: 0:00:00.018926 2025-09-23 00:08:52.025117 | 2025-09-23 00:08:52.025264 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-09-23 00:08:52.066987 | controller | ok 2025-09-23 00:08:52.083903 | controller | included: /var/lib/zuul/builds/7fd40f5e12da4ae48617f6fa8be4ffb7/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-09-23 00:08:52.103570 | 2025-09-23 00:08:52.103654 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-09-23 00:08:52.129645 | controller | skipping: Conditional result was False 2025-09-23 00:08:52.139618 | 2025-09-23 00:08:52.139745 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-09-23 00:08:52.603610 | controller | changed 2025-09-23 00:08:52.615581 | 2025-09-23 00:08:52.615746 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-09-23 00:08:52.983685 | controller | ok 2025-09-23 00:08:52.996628 | 2025-09-23 00:08:52.996856 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-09-23 00:08:54.303467 | controller | changed 2025-09-23 00:08:54.311960 | 2025-09-23 00:08:54.312045 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-09-23 00:08:55.627047 | controller | changed 2025-09-23 00:08:55.635235 | 2025-09-23 00:08:55.635343 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-09-23 00:08:55.660320 | controller | skipping: Conditional result was False 2025-09-23 00:08:55.669350 | 2025-09-23 00:08:55.669456 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-09-23 00:08:55.939257 | controller -> localhost | changed 2025-09-23 00:08:55.956716 | 2025-09-23 00:08:55.956794 | TASK [add-build-sshkey : Add back temp key] 2025-09-23 00:08:56.250943 | controller -> localhost | Identity added: /var/lib/zuul/builds/7fd40f5e12da4ae48617f6fa8be4ffb7/work/7fd40f5e12da4ae48617f6fa8be4ffb7_id_ecdsa (zuul-build-sshkey) 2025-09-23 00:08:56.251428 | controller -> localhost | ok: Runtime: 0:00:00.014414 2025-09-23 00:08:56.267626 | 2025-09-23 00:08:56.267830 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-09-23 00:08:56.618216 | controller | ok 2025-09-23 00:08:56.623707 | 2025-09-23 00:08:56.623784 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-09-23 00:08:56.659659 | controller | skipping: Conditional result was False 2025-09-23 00:08:56.671349 | 2025-09-23 00:08:56.671426 | TASK [include_role : remove-zuul-sshkey] 2025-09-23 00:08:56.696703 | controller | skipping: Conditional result was False 2025-09-23 00:08:56.702856 | 2025-09-23 00:08:56.702918 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2025-09-23 00:08:57.022108 | controller | ok: "logs" 2025-09-23 00:08:57.022503 | controller | ok: All items complete 2025-09-23 00:08:57.022543 | 2025-09-23 00:08:57.324299 | controller | ok: "artifacts" 2025-09-23 00:08:57.598223 | controller | ok: "docs" 2025-09-23 00:08:57.618781 | 2025-09-23 00:08:57.618969 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2025-09-23 00:08:57.967623 | controller | changed: "logs" 2025-09-23 00:08:58.371136 | controller | changed: "artifacts" 2025-09-23 00:08:58.593125 | controller | changed: "docs" 2025-09-23 00:08:58.654553 | 2025-09-23 00:08:58.655140 | PLAY RECAP 2025-09-23 00:08:58.655207 | controller | ok: 30 changed: 16 unreachable: 0 failed: 0 skipped: 18 rescued: 0 ignored: 0 2025-09-23 00:08:58.655246 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-09-23 00:08:58.655272 | 2025-09-23 00:08:58.783006 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master] 2025-09-23 00:08:58.784837 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2025-09-23 00:08:59.375881 | 2025-09-23 00:08:59.376017 | PLAY [all] 2025-09-23 00:08:59.397694 | 2025-09-23 00:08:59.397808 | TASK [Install binary dependencies] 2025-09-23 00:08:59.458911 | controller | ok 2025-09-23 00:08:59.478539 | 2025-09-23 00:08:59.478632 | TASK [bindep : Include find tasks] 2025-09-23 00:08:59.508333 | controller | ok 2025-09-23 00:08:59.516718 | controller | included: /var/lib/zuul/builds/7fd40f5e12da4ae48617f6fa8be4ffb7/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find.yaml 2025-09-23 00:08:59.523688 | 2025-09-23 00:08:59.523753 | TASK [bindep : Look for bindep.txt] 2025-09-23 00:09:00.081520 | controller | ok 2025-09-23 00:09:00.096099 | 2025-09-23 00:09:00.096395 | TASK [bindep : Define bindep_file fact] 2025-09-23 00:09:00.124900 | controller | skipping: Conditional result was False 2025-09-23 00:09:00.140630 | 2025-09-23 00:09:00.140924 | TASK [bindep : Look for other-requirements.txt] 2025-09-23 00:09:00.476630 | controller | ok 2025-09-23 00:09:00.490178 | 2025-09-23 00:09:00.490410 | TASK [bindep : Define bindep_file fact] 2025-09-23 00:09:00.528348 | controller | skipping: Conditional result was False 2025-09-23 00:09:00.541481 | 2025-09-23 00:09:00.541626 | TASK [bindep : Look for bindep fallback file] 2025-09-23 00:09:00.580863 | controller | skipping: Conditional result was False 2025-09-23 00:09:00.597235 | 2025-09-23 00:09:00.597478 | TASK [bindep : Define bindep_file fact] 2025-09-23 00:09:00.635774 | controller | skipping: Conditional result was False 2025-09-23 00:09:00.648778 | 2025-09-23 00:09:00.648921 | TASK [bindep : Include bindep tasks] 2025-09-23 00:09:00.686470 | controller | skipping: Conditional result was False 2025-09-23 00:09:00.701933 | 2025-09-23 00:09:00.702069 | TASK [bindep : Include install tasks] 2025-09-23 00:09:00.729230 | controller | skipping: Conditional result was False 2025-09-23 00:09:00.742441 | 2025-09-23 00:09:00.742583 | LOOP [bindep : Include package tasks] 2025-09-23 00:09:00.833565 | 2025-09-23 00:09:00.833900 | TASK [Run test-setup role] 2025-09-23 00:09:00.859590 | controller | ok 2025-09-23 00:09:00.885990 | 2025-09-23 00:09:00.886149 | TASK [test-setup : Check if project's tools/test-setup.sh exists] 2025-09-23 00:09:01.305058 | controller | ok 2025-09-23 00:09:01.321174 | 2025-09-23 00:09:01.321351 | TASK [test-setup : Run tools/test-setup.sh] 2025-09-23 00:09:01.473717 | controller | skipping: Conditional result was False 2025-09-23 00:09:01.523691 | 2025-09-23 00:09:01.524031 | PLAY RECAP 2025-09-23 00:09:01.524109 | controller | ok: 4 changed: 0 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2025-09-23 00:09:01.524141 | 2025-09-23 00:09:01.671593 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2025-09-23 00:09:01.673623 | PRE-RUN START: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master] 2025-09-23 00:09:02.310041 | 2025-09-23 00:09:02.310183 | PLAY [controller] 2025-09-23 00:09:02.329574 | 2025-09-23 00:09:02.329649 | TASK [Create the /root directory] 2025-09-23 00:09:03.683784 | controller | ok 2025-09-23 00:09:03.696505 | 2025-09-23 00:09:03.696710 | TASK [Install glibc-langpack-en] 2025-09-23 00:09:11.942454 | controller | ok: Nothing to do 2025-09-23 00:09:11.955462 | 2025-09-23 00:09:11.955620 | TASK [Ensure controller directory exists] 2025-09-23 00:09:12.371496 | controller | changed 2025-09-23 00:09:12.381724 | 2025-09-23 00:09:12.381866 | TASK [Install container runtime] 2025-09-23 00:09:12.435113 | controller | ok 2025-09-23 00:09:12.477101 | 2025-09-23 00:09:12.477272 | LOOP [ensure-docker : Gather variables for each operating system] 2025-09-23 00:09:12.540868 | controller | ok: "/var/lib/zuul/builds/7fd40f5e12da4ae48617f6fa8be4ffb7/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/vars/fedora.yaml" 2025-09-23 00:09:12.551529 | 2025-09-23 00:09:12.551640 | TASK [ensure-docker : Sanity check for distro selection] 2025-09-23 00:09:12.577016 | controller | skipping: Conditional result was False 2025-09-23 00:09:12.586346 | 2025-09-23 00:09:12.586456 | TASK [ensure-docker : Sanity check for distro install] 2025-09-23 00:09:12.623196 | controller | skipping: Conditional result was False 2025-09-23 00:09:12.633121 | 2025-09-23 00:09:12.633384 | TASK [ensure-docker : Sanity check for upstream install] 2025-09-23 00:09:12.661319 | controller | skipping: Conditional result was False 2025-09-23 00:09:12.672274 | 2025-09-23 00:09:12.672371 | TASK [ensure-docker : Sanity check for upstream install mirrors] 2025-09-23 00:09:12.709404 | controller | skipping: Conditional result was False 2025-09-23 00:09:12.719233 | 2025-09-23 00:09:12.719350 | TASK [ensure-docker : Include tasks for docker install] 2025-09-23 00:09:12.758451 | controller | ok 2025-09-23 00:09:12.775458 | controller | included: /var/lib/zuul/builds/7fd40f5e12da4ae48617f6fa8be4ffb7/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/tasks/docker-upstream.yaml 2025-09-23 00:09:12.789112 | 2025-09-23 00:09:12.789220 | TASK [ensure-docker : Not Implemented] 2025-09-23 00:09:12.816654 | controller | skipping: Conditional result was False 2025-09-23 00:09:12.824347 | 2025-09-23 00:09:12.824423 | TASK [Set up docker mirrors] 2025-09-23 00:09:12.858364 | controller | ok 2025-09-23 00:09:12.889051 | 2025-09-23 00:09:12.889247 | TASK [use-docker-mirror : Set docker_mirror fact from mirror_info] 2025-09-23 00:09:12.935208 | controller | skipping: Conditional result was False 2025-09-23 00:09:12.942076 | 2025-09-23 00:09:12.942146 | TASK [use-docker-mirror : Set docker_mirror fact from zuul_site_mirror_info] 2025-09-23 00:09:12.977782 | controller | skipping: Conditional result was False 2025-09-23 00:09:12.984952 | 2025-09-23 00:09:12.985026 | TASK [use-docker-mirror : Set docker_mirror fact] 2025-09-23 00:09:13.021348 | controller | skipping: Conditional result was False 2025-09-23 00:09:13.028559 | 2025-09-23 00:09:13.028635 | TASK [use-docker-mirror : Set up docker mirrors] 2025-09-23 00:09:13.064350 | controller | skipping: Conditional result was False 2025-09-23 00:09:13.078495 | 2025-09-23 00:09:13.078895 | TASK [Add all repositories] 2025-09-23 00:09:13.158875 | controller | ok 2025-09-23 00:09:13.184048 | 2025-09-23 00:09:13.184199 | LOOP [ensure-package-repositories : Install repositories] 2025-09-23 00:09:13.219838 | controller | ok: "/var/lib/zuul/builds/7fd40f5e12da4ae48617f6fa8be4ffb7/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-package-repositories/tasks/RedHat.yaml" 2025-09-23 00:09:13.220182 | controller | ok: All items complete 2025-09-23 00:09:13.220212 | 2025-09-23 00:09:13.239023 | controller | included: /var/lib/zuul/builds/7fd40f5e12da4ae48617f6fa8be4ffb7/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-package-repositories/tasks/RedHat.yaml 2025-09-23 00:09:13.248205 | 2025-09-23 00:09:13.248308 | TASK [ensure-package-repositories : Create GPG key temp directory] 2025-09-23 00:09:13.784053 | controller | changed 2025-09-23 00:09:13.799063 | 2025-09-23 00:09:13.799229 | LOOP [ensure-package-repositories : Upload GPG keys] 2025-09-23 00:09:15.476868 | controller | changed: 2025-09-23 00:09:15.477278 | controller | { 2025-09-23 00:09:15.477367 | controller | "data": "-----BEGIN PGP PUBLIC KEY BLOCK-----\n\nmQINBFit5IEBEADDt86QpYKz5flnCsOyZ/fk3WwBKxfDjwHf/GIflo+4GWAXS7wJ\n1PSzPsvSDATV10J44i5WQzh99q+lZvFCVRFiNhRmlmcXG+rk1QmDh3fsCCj9Q/yP\nw8jn3Hx0zDtz8PIB/18ReftYJzUo34COLiHn8WiY20uGCF2pjdPgfxE+K454c4G7\ngKFqVUFYgPug2CS0quaBB5b0rpFUdzTeI5RCStd27nHCpuSDCvRYAfdv+4Y1yiVh\nKKdoe3Smj+RnXeVMgDxtH9FJibZ3DK7WnMN2yeob6VqXox+FvKYJCCLkbQgQmE50\nuVK0uN71A1mQDcTRKQ2q3fFGlMTqJbbzr3LwnCBE6hV0a36t+DABtZTmz5O69xdJ\nWGdBeePCnWVqtDb/BdEYz7hPKskcZBarygCCe2Xi7sZieoFZuq6ltPoCsdfEdfbO\n+VBVKJnExqNZCcFUTEnbH4CldWROOzMS8BGUlkGpa59Sl1t0QcmWlw1EbkeMQNrN\nspdR8lobcdNS9bpAJQqSHRZh3cAM9mA3Yq/bssUS/P2quRXLjJ9mIv3dky9C3udM\n+q2unvnbNpPtIUly76FJ3s8g8sHeOnmYcKqNGqHq2Q3kMdA2eIbI0MqfOIo2+Xk0\nrNt3ctq3g+cQiorcN3rdHPsTRSAcp+NCz1QF9TwXYtH1XV24A6QMO0+CZwARAQAB\ntCtEb2NrZXIgUmVsZWFzZSAoQ0UgcnBtKSA8ZG9ja2VyQGRvY2tlci5jb20+iQI3\nBBMBCgAhBQJYrep4AhsvBQsJCAcDBRUKCQgLBRYCAwEAAh4BAheAAAoJEMUv62ti\nHp816C0P/iP+1uhSa6Qq3TIc5sIFE5JHxOO6y0R97cUdAmCbEqBiJHUPNQDQaaRG\nVYBm0K013Q1gcJeUJvS32gthmIvhkstw7KTodwOM8Kl11CCqZ07NPFef1b2SaJ7l\nTYpyUsT9+e343ph+O4C1oUQw6flaAJe+8ATCmI/4KxfhIjD2a/Q1voR5tUIxfexC\n/LZTx05gyf2mAgEWlRm/cGTStNfqDN1uoKMlV+WFuB1j2oTUuO1/dr8mL+FgZAM3\nntWFo9gQCllNV9ahYOON2gkoZoNuPUnHsf4Bj6BQJnIXbAhMk9H2sZzwUi9bgObZ\nXO8+OrP4D4B9kCAKqqaQqA+O46LzO2vhN74lm/Fy6PumHuviqDBdN+HgtRPMUuao\nxnuVJSvBu9sPdgT/pR1N9u/KnfAnnLtR6g+fx4mWz+ts/riB/KRHzXd+44jGKZra\nIhTMfniguMJNsyEOO0AN8Tqcl0eRBxcOArcri7xu8HFvvl+e+ILymu4buusbYEVL\nGBkYP5YMmScfKn+jnDVN4mWoN1Bq2yMhMGx6PA3hOvzPNsUoYy2BwDxNZyflzuAi\ng59mgJm2NXtzNbSRJbMamKpQ69mzLWGdFNsRd4aH7PT7uPAURaf7B5BVp3UyjERW\n5alSGnBqsZmvlRnVH5BDUhYsWZMPRQS9rRr4iGW0l+TH+O2VJ8aQ\n=0Zqq\n-----END PGP PUBLIC KEY BLOCK-----\n" 2025-09-23 00:09:15.477440 | controller | } 2025-09-23 00:09:15.497176 | 2025-09-23 00:09:15.497441 | LOOP [ensure-package-repositories : Import GPG keys] 2025-09-23 00:09:16.330916 | controller | changed: 2025-09-23 00:09:16.331317 | controller | { 2025-09-23 00:09:16.331348 | controller | "data": "-----BEGIN PGP PUBLIC KEY BLOCK-----\n\nmQINBFit5IEBEADDt86QpYKz5flnCsOyZ/fk3WwBKxfDjwHf/GIflo+4GWAXS7wJ\n1PSzPsvSDATV10J44i5WQzh99q+lZvFCVRFiNhRmlmcXG+rk1QmDh3fsCCj9Q/yP\nw8jn3Hx0zDtz8PIB/18ReftYJzUo34COLiHn8WiY20uGCF2pjdPgfxE+K454c4G7\ngKFqVUFYgPug2CS0quaBB5b0rpFUdzTeI5RCStd27nHCpuSDCvRYAfdv+4Y1yiVh\nKKdoe3Smj+RnXeVMgDxtH9FJibZ3DK7WnMN2yeob6VqXox+FvKYJCCLkbQgQmE50\nuVK0uN71A1mQDcTRKQ2q3fFGlMTqJbbzr3LwnCBE6hV0a36t+DABtZTmz5O69xdJ\nWGdBeePCnWVqtDb/BdEYz7hPKskcZBarygCCe2Xi7sZieoFZuq6ltPoCsdfEdfbO\n+VBVKJnExqNZCcFUTEnbH4CldWROOzMS8BGUlkGpa59Sl1t0QcmWlw1EbkeMQNrN\nspdR8lobcdNS9bpAJQqSHRZh3cAM9mA3Yq/bssUS/P2quRXLjJ9mIv3dky9C3udM\n+q2unvnbNpPtIUly76FJ3s8g8sHeOnmYcKqNGqHq2Q3kMdA2eIbI0MqfOIo2+Xk0\nrNt3ctq3g+cQiorcN3rdHPsTRSAcp+NCz1QF9TwXYtH1XV24A6QMO0+CZwARAQAB\ntCtEb2NrZXIgUmVsZWFzZSAoQ0UgcnBtKSA8ZG9ja2VyQGRvY2tlci5jb20+iQI3\nBBMBCgAhBQJYrep4AhsvBQsJCAcDBRUKCQgLBRYCAwEAAh4BAheAAAoJEMUv62ti\nHp816C0P/iP+1uhSa6Qq3TIc5sIFE5JHxOO6y0R97cUdAmCbEqBiJHUPNQDQaaRG\nVYBm0K013Q1gcJeUJvS32gthmIvhkstw7KTodwOM8Kl11CCqZ07NPFef1b2SaJ7l\nTYpyUsT9+e343ph+O4C1oUQw6flaAJe+8ATCmI/4KxfhIjD2a/Q1voR5tUIxfexC\n/LZTx05gyf2mAgEWlRm/cGTStNfqDN1uoKMlV+WFuB1j2oTUuO1/dr8mL+FgZAM3\nntWFo9gQCllNV9ahYOON2gkoZoNuPUnHsf4Bj6BQJnIXbAhMk9H2sZzwUi9bgObZ\nXO8+OrP4D4B9kCAKqqaQqA+O46LzO2vhN74lm/Fy6PumHuviqDBdN+HgtRPMUuao\nxnuVJSvBu9sPdgT/pR1N9u/KnfAnnLtR6g+fx4mWz+ts/riB/KRHzXd+44jGKZra\nIhTMfniguMJNsyEOO0AN8Tqcl0eRBxcOArcri7xu8HFvvl+e+ILymu4buusbYEVL\nGBkYP5YMmScfKn+jnDVN4mWoN1Bq2yMhMGx6PA3hOvzPNsUoYy2BwDxNZyflzuAi\ng59mgJm2NXtzNbSRJbMamKpQ69mzLWGdFNsRd4aH7PT7uPAURaf7B5BVp3UyjERW\n5alSGnBqsZmvlRnVH5BDUhYsWZMPRQS9rRr4iGW0l+TH+O2VJ8aQ\n=0Zqq\n-----END PGP PUBLIC KEY BLOCK-----\n" 2025-09-23 00:09:16.331370 | controller | } 2025-09-23 00:09:16.331406 | controller | changed: All items complete 2025-09-23 00:09:16.331426 | 2025-09-23 00:09:16.353558 | 2025-09-23 00:09:16.353835 | TASK [ensure-package-repositories : Remove GPG temp directory] 2025-09-23 00:09:16.762029 | controller | changed 2025-09-23 00:09:16.773056 | 2025-09-23 00:09:16.773168 | LOOP [ensure-package-repositories : Add repositories] 2025-09-23 00:09:17.277634 | controller | changed: 2025-09-23 00:09:17.277897 | controller | { 2025-09-23 00:09:17.277936 | controller | "baseurl": "https://download.docker.com/linux/fedora/$releasever/$basearch/stable", 2025-09-23 00:09:17.277974 | controller | "description": "Docker CE Stable - $basearch", 2025-09-23 00:09:17.278004 | controller | "gpgcheck": true, 2025-09-23 00:09:17.278030 | controller | "name": "docker-ce-stable" 2025-09-23 00:09:17.278054 | controller | } 2025-09-23 00:09:17.312344 | 2025-09-23 00:09:17.312539 | TASK [ensure-docker : Install pre-reqs] 2025-09-23 00:09:20.915508 | controller | ok: Nothing to do 2025-09-23 00:09:20.923909 | 2025-09-23 00:09:20.924073 | TASK [ensure-docker : Remove packages] 2025-09-23 00:09:23.157057 | controller | ok: Nothing to do 2025-09-23 00:09:23.170323 | 2025-09-23 00:09:23.170552 | TASK [ensure-docker : Install upstream docker using package] 2025-09-23 00:10:19.600831 | controller | changed 2025-09-23 00:10:19.608862 | 2025-09-23 00:10:19.608934 | TASK [ensure-docker : Setup docker] 2025-09-23 00:10:19.630577 | controller | ok 2025-09-23 00:10:19.648650 | controller | included: /var/lib/zuul/builds/7fd40f5e12da4ae48617f6fa8be4ffb7/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/tasks/docker-setup.yaml 2025-09-23 00:10:19.665466 | 2025-09-23 00:10:19.665567 | TASK [ensure-docker : Ensure "docker" group exists] 2025-09-23 00:10:20.226573 | controller | ok 2025-09-23 00:10:20.242412 | 2025-09-23 00:10:20.242622 | TASK [ensure-docker : Add user to docker group] 2025-09-23 00:10:20.912002 | controller | changed 2025-09-23 00:10:20.925339 | 2025-09-23 00:10:20.925694 | TASK [Update docker daemon proxy configuration] 2025-09-23 00:10:20.964391 | controller | skipping: Conditional result was False 2025-09-23 00:10:20.982913 | 2025-09-23 00:10:20.983115 | TASK [Lower default MTU] 2025-09-23 00:10:21.009492 | controller | skipping: Conditional result was False 2025-09-23 00:10:21.027070 | 2025-09-23 00:10:21.027265 | TASK [ensure-docker : Get the status of the docket.socket unit] 2025-09-23 00:10:21.576501 | controller | inactive 2025-09-23 00:10:21.680449 | controller | ok: Runtime: 0:00:00.011652 2025-09-23 00:10:21.693593 | 2025-09-23 00:10:21.693762 | TASK [ensure-docker : Get the status of the docket unit] 2025-09-23 00:10:22.178788 | controller | inactive 2025-09-23 00:10:22.343001 | controller | ok: Runtime: 0:00:00.018309 2025-09-23 00:10:22.359309 | 2025-09-23 00:10:22.359520 | TASK [ensure-docker : Restart docker] 2025-09-23 00:10:22.422754 | Trigger a Docker restart 2025-09-23 00:10:22.436123 | 2025-09-23 00:10:22.436300 | TASK [ensure-docker : Reset ssh connection to pick up docker group] 2025-09-23 00:10:22.481318 | 2025-09-23 00:10:22.481475 | TASK [ensure-docker : Install docker-compose] 2025-09-23 00:10:22.507927 | controller | skipping: Conditional result was False 2025-09-23 00:10:22.523497 | 2025-09-23 00:10:22.523739 | TASK [ensure-docker : Flush handlers before role exit] 2025-09-23 00:10:22.540488 | 2025-09-23 00:10:22.540625 | TASK [ensure-docker : Stop docker.socket to avoid any conflict] 2025-09-23 00:10:24.797841 | controller | changed 2025-09-23 00:10:24.813954 | 2025-09-23 00:10:24.814097 | TASK [ensure-docker : Assure docker service is running] 2025-09-23 00:10:27.752117 | controller | changed 2025-09-23 00:10:27.778451 | 2025-09-23 00:10:27.778757 | TASK [ensure-docker : Assure docker.socket service is running] 2025-09-23 00:10:29.242032 | controller | ok 2025-09-23 00:10:29.259979 | 2025-09-23 00:10:29.260152 | TASK [ensure-docker : Correct group ownership on docker sock] 2025-09-23 00:10:30.173788 | controller | ok 2025-09-23 00:10:30.179370 | 2025-09-23 00:10:30.179457 | TASK [ensure-docker : Validate ability to talk with docker] 2025-09-23 00:10:30.795925 | controller | CONTAINER ID IMAGE COMMAND CREATED STATUS PORTS NAMES 2025-09-23 00:10:31.317441 | controller | ok: Runtime: 0:00:00.054727 2025-09-23 00:10:31.332942 | 2025-09-23 00:10:31.333118 | TASK [ensure-docker : Get version details] 2025-09-23 00:10:31.990760 | controller | Client: Docker Engine - Community 2025-09-23 00:10:31.990836 | controller | Version: 24.0.7 2025-09-23 00:10:31.991124 | controller | API version: 1.43 2025-09-23 00:10:31.991141 | controller | Go version: go1.20.10 2025-09-23 00:10:31.991161 | controller | Git commit: afdd53b 2025-09-23 00:10:31.991169 | controller | Built: Thu Oct 26 09:09:39 2023 2025-09-23 00:10:31.991178 | controller | OS/Arch: linux/amd64 2025-09-23 00:10:31.991189 | controller | Context: default 2025-09-23 00:10:31.991198 | controller | 2025-09-23 00:10:31.991206 | controller | Server: Docker Engine - Community 2025-09-23 00:10:31.991213 | controller | Engine: 2025-09-23 00:10:31.991224 | controller | Version: 24.0.7 2025-09-23 00:10:31.993124 | controller | API version: 1.43 (minimum version 1.12) 2025-09-23 00:10:31.993152 | controller | Go version: go1.20.10 2025-09-23 00:10:31.993161 | controller | Git commit: 311b9ff 2025-09-23 00:10:31.993171 | controller | Built: Thu Oct 26 09:08:11 2023 2025-09-23 00:10:31.993181 | controller | OS/Arch: linux/amd64 2025-09-23 00:10:31.993190 | controller | Experimental: false 2025-09-23 00:10:31.993200 | controller | containerd: 2025-09-23 00:10:31.993209 | controller | Version: 1.6.25 2025-09-23 00:10:31.993218 | controller | GitCommit: d8f198a4ed8892c764191ef7b3b06d8a2eeb5c7f 2025-09-23 00:10:31.993227 | controller | runc: 2025-09-23 00:10:31.993235 | controller | Version: 1.1.10 2025-09-23 00:10:31.993244 | controller | GitCommit: v1.1.10-0-g18a0cb0 2025-09-23 00:10:31.993258 | controller | docker-init: 2025-09-23 00:10:31.993267 | controller | Version: 0.19.0 2025-09-23 00:10:31.993276 | controller | GitCommit: de40ad0 2025-09-23 00:10:32.506712 | controller | ok: Runtime: 0:00:00.059484 2025-09-23 00:10:32.521539 | 2025-09-23 00:10:32.521714 | TASK [ensure-docker : Dump installed docker details] 2025-09-23 00:10:32.573759 | {'changed': True, 'stdout': 'Client: Docker Engine - Community\n Version: 24.0.7\n API version: 1.43\n Go version: go1.20.10\n Git commit: afdd53b\n Built: Thu Oct 26 09:09:39 2023\n OS/Arch: linux/amd64\n Context: default\n\nServer: Docker Engine - Community\n Engine:\n Version: 24.0.7\n API version: 1.43 (minimum version 1.12)\n Go version: go1.20.10\n Git commit: 311b9ff\n Built: Thu Oct 26 09:08:11 2023\n OS/Arch: linux/amd64\n Experimental: false\n containerd:\n Version: 1.6.25\n GitCommit: d8f198a4ed8892c764191ef7b3b06d8a2eeb5c7f\n runc:\n Version: 1.1.10\n GitCommit: v1.1.10-0-g18a0cb0\n docker-init:\n Version: 0.19.0\n GitCommit: de40ad0', 'stderr': '', 'rc': 0, 'cmd': ['docker', 'version'], 'start': '2025-09-23 00:10:31.934117', 'end': '2025-09-23 00:10:31.993601', 'delta': '0:00:00.059484', 'msg': '', 'zuul_log_id': 'fa163efc-24cc-7c59-3c17-000000000061-1-controller', 'stdout_lines': ['Client: Docker Engine - Community', ' Version: 24.0.7', ' API version: 1.43', ' Go version: go1.20.10', ' Git commit: afdd53b', ' Built: Thu Oct 26 09:09:39 2023', ' OS/Arch: linux/amd64', ' Context: default', '', 'Server: Docker Engine - Community', ' Engine:', ' Version: 24.0.7', ' API version: 1.43 (minimum version 1.12)', ' Go version: go1.20.10', ' Git commit: 311b9ff', ' Built: Thu Oct 26 09:08:11 2023', ' OS/Arch: linux/amd64', ' Experimental: false', ' containerd:', ' Version: 1.6.25', ' GitCommit: d8f198a4ed8892c764191ef7b3b06d8a2eeb5c7f', ' runc:', ' Version: 1.1.10', ' GitCommit: v1.1.10-0-g18a0cb0', ' docker-init:', ' Version: 0.19.0', ' GitCommit: de40ad0'], 'stderr_lines': [], 'failed': False} 2025-09-23 00:10:32.587574 | 2025-09-23 00:10:32.587784 | TASK [Use buildset registry] 2025-09-23 00:10:32.620244 | controller | skipping: Conditional result was False 2025-09-23 00:10:32.636779 | 2025-09-23 00:10:32.636927 | TASK [Ensure python3.8 is present] 2025-09-23 00:10:32.663122 | controller | skipping: Conditional result was False 2025-09-23 00:10:32.672486 | 2025-09-23 00:10:32.672576 | TASK [Run our-ensure-python role (Fedora only for now)] 2025-09-23 00:10:32.698117 | controller | ok 2025-09-23 00:10:32.727195 | 2025-09-23 00:10:32.727324 | TASK [our-ensure-python : Install the right Python version (rpm)] 2025-09-23 00:10:39.532289 | controller | ok: Nothing to do 2025-09-23 00:10:39.537832 | 2025-09-23 00:10:39.537905 | TASK [our-ensure-python : Also install python3-devel] 2025-09-23 00:10:54.677541 | controller | changed 2025-09-23 00:10:54.700786 | 2025-09-23 00:10:54.700928 | TASK [Run ensure-virtualenv role] 2025-09-23 00:10:54.730024 | controller | ok 2025-09-23 00:10:54.765652 | 2025-09-23 00:10:54.765791 | TASK [ensure-virtualenv : Check if virtualenv is installed] 2025-09-23 00:10:55.339584 | controller | /usr/bin/virtualenv 2025-09-23 00:10:55.403193 | controller | ok: Runtime: 0:00:00.004771 2025-09-23 00:10:55.417157 | 2025-09-23 00:10:55.417328 | LOOP [ensure-virtualenv : Install virtualenv package] 2025-09-23 00:10:55.454987 | controller | skipping: Conditional result was False 2025-09-23 00:10:55.455375 | controller | changed: All items complete 2025-09-23 00:10:55.455408 | 2025-09-23 00:10:55.474753 | 2025-09-23 00:10:55.474899 | TASK [Find the full path of the Python interpreter] 2025-09-23 00:10:55.905758 | controller | /usr/bin/python3.9 2025-09-23 00:10:56.104186 | controller | ok 2025-09-23 00:10:56.115926 | 2025-09-23 00:10:56.116105 | TASK [Create virtualenv for ansible-test with a specific interpreter] 2025-09-23 00:10:58.406708 | controller | created virtual environment CPython3.9.18.final.0-64 in 1153ms 2025-09-23 00:10:58.450774 | controller | creator CPython3Posix(dest=/home/zuul-worker/venv, clear=False, no_vcs_ignore=False, global=False) 2025-09-23 00:10:58.450831 | 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-worker/.local/share/virtualenv) 2025-09-23 00:10:58.450849 | controller | added seed packages: pip==22.2.2, setuptools==62.6.0, wheel==0.37.1 2025-09-23 00:10:58.450872 | controller | activators BashActivator,CShellActivator,FishActivator,NushellActivator,PowerShellActivator,PythonActivator 2025-09-23 00:10:58.767785 | controller | changed 2025-09-23 00:10:58.781255 | 2025-09-23 00:10:58.781400 | TASK [Set selinux package] 2025-09-23 00:10:58.811191 | controller | ok 2025-09-23 00:10:58.822294 | 2025-09-23 00:10:58.822427 | TASK [Set selinux package (Fedora)] 2025-09-23 00:10:58.871440 | controller | ok 2025-09-23 00:10:58.882601 | 2025-09-23 00:10:58.882765 | TASK [Install selinux into virtualenv] 2025-09-23 00:11:03.418090 | controller | Collecting selinux-please-lie-to-me 2025-09-23 00:11:04.064475 | controller | Downloading selinux_please_lie_to_me-1.0.1-py2.py3-none-any.whl (3.8 kB) 2025-09-23 00:11:05.271473 | controller | Collecting setuptools<50.0.0 2025-09-23 00:11:05.289268 | controller | Downloading setuptools-49.6.0-py3-none-any.whl (803 kB) 2025-09-23 00:11:05.469487 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 803.3/803.3 kB 5.0 MB/s eta 0:00:00 2025-09-23 00:11:05.763641 | controller | Installing collected packages: setuptools, selinux-please-lie-to-me 2025-09-23 00:11:05.763984 | controller | Attempting uninstall: setuptools 2025-09-23 00:11:05.767615 | controller | Found existing installation: setuptools 62.6.0 2025-09-23 00:11:06.014822 | controller | Uninstalling setuptools-62.6.0: 2025-09-23 00:11:06.049064 | controller | Successfully uninstalled setuptools-62.6.0 2025-09-23 00:11:06.782354 | controller | Successfully installed selinux-please-lie-to-me-1.0.1 setuptools-49.6.0 2025-09-23 00:11:07.005706 | controller | 2025-09-23 00:11:07.209596 | controller | [notice] A new release of pip available: 22.2.2 -> 25.2 2025-09-23 00:11:07.209646 | controller | [notice] To update, run: /home/zuul-worker/venv/bin/python -m pip install --upgrade pip 2025-09-23 00:11:07.541150 | controller | ok: Runtime: 0:00:07.695828 2025-09-23 00:11:07.548107 | 2025-09-23 00:11:07.548219 | TASK [Install pytest-forked into virtualenv] 2025-09-23 00:11:09.192423 | controller | Collecting pytest-forked 2025-09-23 00:11:09.274651 | controller | Downloading pytest_forked-1.6.0-py3-none-any.whl (4.9 kB) 2025-09-23 00:11:09.338517 | controller | Collecting py 2025-09-23 00:11:09.351659 | controller | Downloading py-1.11.0-py2.py3-none-any.whl (98 kB) 2025-09-23 00:11:09.385590 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 98.7/98.7 kB 3.2 MB/s eta 0:00:00 2025-09-23 00:11:09.578603 | controller | Collecting pytest>=3.10 2025-09-23 00:11:09.591345 | controller | Downloading pytest-8.4.2-py3-none-any.whl (365 kB) 2025-09-23 00:11:09.633429 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 365.8/365.8 kB 9.5 MB/s eta 0:00:00 2025-09-23 00:11:09.712042 | controller | Collecting exceptiongroup>=1 2025-09-23 00:11:09.725095 | controller | Downloading exceptiongroup-1.3.0-py3-none-any.whl (16 kB) 2025-09-23 00:11:09.770674 | controller | Collecting iniconfig>=1 2025-09-23 00:11:09.784369 | controller | Downloading iniconfig-2.1.0-py3-none-any.whl (6.0 kB) 2025-09-23 00:11:09.850482 | controller | Collecting tomli>=1 2025-09-23 00:11:09.887407 | controller | Downloading tomli-2.2.1-py3-none-any.whl (14 kB) 2025-09-23 00:11:09.972164 | controller | Collecting pygments>=2.7.2 2025-09-23 00:11:09.988521 | controller | Downloading pygments-2.19.2-py3-none-any.whl (1.2 MB) 2025-09-23 00:11:10.018432 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 1.2/1.2 MB 49.1 MB/s eta 0:00:00 2025-09-23 00:11:10.102867 | controller | Collecting packaging>=20 2025-09-23 00:11:10.123722 | controller | Downloading packaging-25.0-py3-none-any.whl (66 kB) 2025-09-23 00:11:10.134759 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 66.5/66.5 kB 8.5 MB/s eta 0:00:00 2025-09-23 00:11:10.214383 | controller | Collecting pluggy<2,>=1.5 2025-09-23 00:11:10.228415 | controller | Downloading pluggy-1.6.0-py3-none-any.whl (20 kB) 2025-09-23 00:11:10.317198 | controller | Collecting typing-extensions>=4.6.0 2025-09-23 00:11:10.329853 | controller | Downloading typing_extensions-4.15.0-py3-none-any.whl (44 kB) 2025-09-23 00:11:10.339787 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 44.6/44.6 kB 5.9 MB/s eta 0:00:00 2025-09-23 00:11:10.469690 | controller | Installing collected packages: typing-extensions, tomli, pygments, py, pluggy, packaging, iniconfig, exceptiongroup, pytest, pytest-forked 2025-09-23 00:11:12.692957 | controller | Successfully installed exceptiongroup-1.3.0 iniconfig-2.1.0 packaging-25.0 pluggy-1.6.0 py-1.11.0 pygments-2.19.2 pytest-8.4.2 pytest-forked-1.6.0 tomli-2.2.1 typing-extensions-4.15.0 2025-09-23 00:11:12.699878 | controller | 2025-09-23 00:11:12.886742 | controller | [notice] A new release of pip available: 22.2.2 -> 25.2 2025-09-23 00:11:12.886787 | controller | [notice] To update, run: /home/zuul-worker/venv/bin/python -m pip install --upgrade pip 2025-09-23 00:11:13.212323 | controller | ok: Runtime: 0:00:04.678112 2025-09-23 00:11:13.226618 | 2025-09-23 00:11:13.226833 | TASK [Update pip] 2025-09-23 00:11:15.324607 | controller | Requirement already satisfied: pip in ./venv/lib/python3.9/site-packages (22.2.2) 2025-09-23 00:11:15.745292 | controller | Collecting pip 2025-09-23 00:11:15.875824 | controller | Downloading pip-25.2-py3-none-any.whl (1.8 MB) 2025-09-23 00:11:16.071924 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 1.8/1.8 MB 9.1 MB/s eta 0:00:00 2025-09-23 00:11:16.313339 | controller | Installing collected packages: pip 2025-09-23 00:11:16.315052 | controller | Attempting uninstall: pip 2025-09-23 00:11:16.316486 | controller | Found existing installation: pip 22.2.2 2025-09-23 00:11:16.922336 | controller | Uninstalling pip-22.2.2: 2025-09-23 00:11:16.986116 | controller | Successfully uninstalled pip-22.2.2 2025-09-23 00:11:18.722121 | controller | Successfully installed pip-25.2 2025-09-23 00:11:18.978696 | controller | ok: Runtime: 0:00:04.863627 2025-09-23 00:11:18.997150 | 2025-09-23 00:11:18.997377 | TASK [Ensure pip temp directory exists in /var/tmp] 2025-09-23 00:11:19.817244 | controller | changed 2025-09-23 00:11:19.829739 | 2025-09-23 00:11:19.829886 | TASK [Install ansible into virtualenv] 2025-09-23 00:11:21.543124 | controller | Processing ./src/github.com/ansible/ansible 2025-09-23 00:11:21.547180 | controller | Preparing metadata (setup.py): started 2025-09-23 00:11:22.802384 | controller | Preparing metadata (setup.py): finished with status 'done' 2025-09-23 00:11:22.924938 | controller | Collecting jinja2 (from ansible-core==2.11.12.post0) 2025-09-23 00:11:22.970042 | controller | Downloading jinja2-3.1.6-py3-none-any.whl.metadata (2.9 kB) 2025-09-23 00:11:23.132949 | controller | Collecting PyYAML (from ansible-core==2.11.12.post0) 2025-09-23 00:11:23.147097 | controller | Downloading PyYAML-6.0.2-cp39-cp39-manylinux_2_17_x86_64.manylinux2014_x86_64.whl.metadata (2.1 kB) 2025-09-23 00:11:23.675616 | controller | Collecting cryptography (from ansible-core==2.11.12.post0) 2025-09-23 00:11:23.688483 | controller | Downloading cryptography-46.0.1-cp38-abi3-manylinux_2_34_x86_64.whl.metadata (5.7 kB) 2025-09-23 00:11:23.714419 | controller | Requirement already satisfied: packaging in ./venv/lib/python3.9/site-packages (from ansible-core==2.11.12.post0) (25.0) 2025-09-23 00:11:23.740500 | controller | Collecting resolvelib<0.6.0,>=0.5.3 (from ansible-core==2.11.12.post0) 2025-09-23 00:11:23.759058 | controller | Downloading resolvelib-0.5.4-py2.py3-none-any.whl.metadata (3.7 kB) 2025-09-23 00:11:24.044793 | controller | Collecting cffi>=2.0.0 (from cryptography->ansible-core==2.11.12.post0) 2025-09-23 00:11:24.057746 | controller | Downloading cffi-2.0.0-cp39-cp39-manylinux2014_x86_64.manylinux_2_17_x86_64.whl.metadata (2.6 kB) 2025-09-23 00:11:24.068855 | controller | Requirement already satisfied: typing-extensions>=4.13.2 in ./venv/lib/python3.9/site-packages (from cryptography->ansible-core==2.11.12.post0) (4.15.0) 2025-09-23 00:11:24.091077 | controller | Collecting pycparser (from cffi>=2.0.0->cryptography->ansible-core==2.11.12.post0) 2025-09-23 00:11:24.104731 | controller | Downloading pycparser-2.23-py3-none-any.whl.metadata (993 bytes) 2025-09-23 00:11:24.239102 | controller | Collecting MarkupSafe>=2.0 (from jinja2->ansible-core==2.11.12.post0) 2025-09-23 00:11:24.252608 | controller | Downloading MarkupSafe-3.0.2-cp39-cp39-manylinux_2_17_x86_64.manylinux2014_x86_64.whl.metadata (4.0 kB) 2025-09-23 00:11:24.283638 | controller | Downloading resolvelib-0.5.4-py2.py3-none-any.whl (12 kB) 2025-09-23 00:11:24.304996 | controller | Downloading cryptography-46.0.1-cp38-abi3-manylinux_2_34_x86_64.whl (4.6 MB) 2025-09-23 00:11:24.415267 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 4.6/4.6 MB 58.4 MB/s 0:00:00 2025-09-23 00:11:24.428603 | controller | Downloading cffi-2.0.0-cp39-cp39-manylinux2014_x86_64.manylinux_2_17_x86_64.whl (216 kB) 2025-09-23 00:11:24.451852 | controller | Downloading jinja2-3.1.6-py3-none-any.whl (134 kB) 2025-09-23 00:11:24.471716 | controller | Downloading MarkupSafe-3.0.2-cp39-cp39-manylinux_2_17_x86_64.manylinux2014_x86_64.whl (20 kB) 2025-09-23 00:11:24.491026 | controller | Downloading pycparser-2.23-py3-none-any.whl (118 kB) 2025-09-23 00:11:24.513796 | controller | Downloading PyYAML-6.0.2-cp39-cp39-manylinux_2_17_x86_64.manylinux2014_x86_64.whl (737 kB) 2025-09-23 00:11:24.532122 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 737.4/737.4 kB 36.6 MB/s 0:00:00 2025-09-23 00:11:24.577804 | controller | Building wheels for collected packages: ansible-core 2025-09-23 00:11:24.579331 | controller | DEPRECATION: Building 'ansible-core' using the legacy setup.py bdist_wheel mechanism, which will be removed in a future version. pip 25.3 will enforce this behaviour change. A possible replacement is to use the standardized build interface by setting the `--use-pep517` option, (possibly combined with `--no-build-isolation`), or adding a `pyproject.toml` file to the source tree of 'ansible-core'. Discussion can be found at https://github.com/pypa/pip/issues/6334 2025-09-23 00:11:24.579799 | controller | Building wheel for ansible-core (setup.py): started 2025-09-23 00:11:27.123203 | controller | Building wheel for ansible-core (setup.py): finished with status 'done' 2025-09-23 00:11:27.136156 | controller | Created wheel for ansible-core: filename=ansible_core-2.11.12.post0-py3-none-any.whl size=1961168 sha256=ea5eec6679dfaa9dc2213af9f63ee12102f1de024a2650ecd34c167f0e5412c3 2025-09-23 00:11:27.136680 | controller | Stored in directory: /var/tmp/ansible-test-pip/pip-ephem-wheel-cache-5w0493ts/wheels/e8/fd/e9/6250c469bad44a7c6855190836e9d22c0bb11cb641caf57fbe 2025-09-23 00:11:27.153114 | controller | Successfully built ansible-core 2025-09-23 00:11:27.223514 | controller | Installing collected packages: resolvelib, PyYAML, pycparser, MarkupSafe, jinja2, cffi, cryptography, ansible-core 2025-09-23 00:11:29.748895 | controller | 2025-09-23 00:11:29.752764 | controller | Successfully installed MarkupSafe-3.0.2 PyYAML-6.0.2 ansible-core-2.11.12.post0 cffi-2.0.0 cryptography-46.0.1 jinja2-3.1.6 pycparser-2.23 resolvelib-0.5.4 2025-09-23 00:11:30.003984 | controller | ok: Runtime: 0:00:09.416335 2025-09-23 00:11:30.011039 | 2025-09-23 00:11:30.011115 | TASK [Setup download-artifact-fork role] 2025-09-23 00:11:30.038756 | controller | ok 2025-09-23 00:11:30.074658 | 2025-09-23 00:11:30.074849 | TASK [download-artifact-fork : Parse inventory response] 2025-09-23 00:11:30.116207 | controller | ok 2025-09-23 00:11:30.125112 | 2025-09-23 00:11:30.125257 | TASK [download-artifact-fork : Fetch info from Zuul API] 2025-09-23 00:11:30.150562 | controller | skipping: Conditional result was False 2025-09-23 00:11:30.157497 | 2025-09-23 00:11:30.157626 | TASK [download-artifact-fork : Parse build response] 2025-09-23 00:11:30.182927 | controller | skipping: Conditional result was False 2025-09-23 00:11:30.189923 | 2025-09-23 00:11:30.190012 | TASK [download-artifact-fork : Ensure artifacts directory exists] 2025-09-23 00:11:30.698002 | controller | changed 2025-09-23 00:11:30.713156 | 2025-09-23 00:11:30.713323 | LOOP [download-artifact-fork : Download archive by type] 2025-09-23 00:11:30.740261 | controller | skipping: Conditional result was False 2025-09-23 00:11:30.740587 | 2025-09-23 00:11:32.341337 | controller | changed: OK (20210 bytes) 2025-09-23 00:11:32.361195 | 2025-09-23 00:11:32.361306 | TASK [deploy-artifacts : define __collections] 2025-09-23 00:11:32.385052 | controller | ok 2025-09-23 00:11:32.391490 | 2025-09-23 00:11:32.391564 | LOOP [deploy-artifacts : Create list of collections] 2025-09-23 00:11:32.422640 | controller | skipping: Conditional result was False 2025-09-23 00:11:32.435423 | controller | ok: 2025-09-23 00:11:32.435504 | controller | { 2025-09-23 00:11:32.435528 | controller | "branch": "main", 2025-09-23 00:11:32.435547 | controller | "job": "build-ansible-collection", 2025-09-23 00:11:32.435570 | controller | "metadata": { 2025-09-23 00:11:32.435595 | controller | "type": "ansible_collection", 2025-09-23 00:11:32.435612 | controller | "version": "5.0.0" 2025-09-23 00:11:32.435627 | controller | }, 2025-09-23 00:11:32.435645 | controller | "name": "ansible.network", 2025-09-23 00:11:32.435705 | controller | "project": "ansible-collections/ansible.network", 2025-09-23 00:11:32.435726 | controller | "url": "https://af01054f635eeb4a9fae-32a02f76c70759d21352b186e3463762.ssl.cf2.rackcdn.com/ansible/d1c4c3323f274a1db5ba0cd2ba3b8841/artifacts/ansible-network-5.0.0.tar.gz" 2025-09-23 00:11:32.435749 | controller | } 2025-09-23 00:11:32.443249 | 2025-09-23 00:11:32.443321 | TASK [deploy-artifacts : Install require-project collection using ansible-galaxy] 2025-09-23 00:11:33.679878 | controller | Starting galaxy collection install process 2025-09-23 00:11:33.680165 | controller | Process install dependency map 2025-09-23 00:11:36.265143 | controller | [WARNING]: Skipping Galaxy server https://galaxy.ansible.com/api/. Got an 2025-09-23 00:11:36.265424 | controller | unexpected error when getting available versions of collection 2025-09-23 00:11:36.265465 | controller | ansible.netcommon: '/api/v3/plugin/ansible/content/published/collections/index/ 2025-09-23 00:11:36.265476 | controller | ansible/netcommon/versions/' 2025-09-23 00:11:36.265489 | controller | ERROR! Unexpected Exception, this is probably a bug: '/api/v3/plugin/ansible/content/published/collections/index/ansible/netcommon/versions/' 2025-09-23 00:11:36.349398 | controller | to see the full traceback, use -vvv 2025-09-23 00:11:36.582466 | controller | ERROR 2025-09-23 00:11:36.582851 | controller | { 2025-09-23 00:11:36.582922 | controller | "delta": "0:00:03.471824", 2025-09-23 00:11:36.582967 | controller | "end": "2025-09-23 00:11:36.349834", 2025-09-23 00:11:36.583005 | controller | "msg": "non-zero return code", 2025-09-23 00:11:36.583041 | controller | "rc": 250, 2025-09-23 00:11:36.583076 | controller | "start": "2025-09-23 00:11:32.878010" 2025-09-23 00:11:36.583111 | controller | } failure 2025-09-23 00:11:36.586718 | 2025-09-23 00:11:36.586822 | PLAY RECAP 2025-09-23 00:11:36.586910 | controller | ok: 44 changed: 24 unreachable: 0 failed: 1 skipped: 17 rescued: 0 ignored: 0 2025-09-23 00:11:36.586954 | 2025-09-23 00:11:36.808580 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master] 2025-09-23 00:11:36.809720 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2025-09-23 00:11:37.485945 | 2025-09-23 00:11:37.486102 | PLAY [all] 2025-09-23 00:11:37.508948 | 2025-09-23 00:11:37.509138 | TASK [fetch-subunit-output : Find stestr or testr executable] 2025-09-23 00:11:39.362740 | controller | changed: non-zero return code 2025-09-23 00:11:39.372242 | 2025-09-23 00:11:39.372389 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2025-09-23 00:11:39.400050 | controller | skipping: Conditional result was False 2025-09-23 00:11:39.411419 | 2025-09-23 00:11:39.411571 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2025-09-23 00:11:39.450453 | 2025-09-23 00:11:39.450655 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2025-09-23 00:11:39.490728 | 2025-09-23 00:11:39.490959 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2025-09-23 00:11:39.519944 | controller | skipping: Conditional result was False 2025-09-23 00:11:39.535465 | 2025-09-23 00:11:39.535692 | LOOP [fetch-subunit-output : Generate subunit file] 2025-09-23 00:11:39.572060 | 2025-09-23 00:11:39.572280 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2025-09-23 00:11:39.602848 | controller | skipping: Conditional result was False 2025-09-23 00:11:39.613932 | 2025-09-23 00:11:39.614100 | TASK [fetch-subunit-output : Remove the temporary file] 2025-09-23 00:11:39.656283 | controller | skipping: Conditional result was False 2025-09-23 00:11:39.664878 | 2025-09-23 00:11:39.664984 | TASK [fetch-subunit-output : Process and fetch subunit results] 2025-09-23 00:11:39.690572 | controller | skipping: Conditional result was False 2025-09-23 00:11:39.726863 | 2025-09-23 00:11:39.727003 | PLAY RECAP 2025-09-23 00:11:39.727046 | controller | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2025-09-23 00:11:39.727067 | 2025-09-23 00:11:39.905874 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2025-09-23 00:11:39.907164 | POST-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/post.yaml@master] 2025-09-23 00:11:40.665937 | 2025-09-23 00:11:40.666118 | PLAY [all:!appliance*] 2025-09-23 00:11:40.715375 | 2025-09-23 00:11:40.715787 | TASK [unregister the node] 2025-09-23 00:11:40.845965 | controller | skipping: Conditional result was False 2025-09-23 00:11:40.853397 | 2025-09-23 00:11:40.853492 | TASK [include_role : fetch-output] 2025-09-23 00:11:40.906135 | controller | ok 2025-09-23 00:11:40.944822 | 2025-09-23 00:11:40.945158 | TASK [fetch-output : Set log path for multiple nodes] 2025-09-23 00:11:41.022367 | controller | skipping: Conditional result was False 2025-09-23 00:11:41.033399 | 2025-09-23 00:11:41.033568 | TASK [fetch-output : Set log path for single node] 2025-09-23 00:11:41.079071 | controller | ok 2025-09-23 00:11:41.087761 | 2025-09-23 00:11:41.087903 | LOOP [fetch-output : Ensure local output dirs] 2025-09-23 00:11:41.589929 | controller -> localhost | ok: "/var/lib/zuul/builds/7fd40f5e12da4ae48617f6fa8be4ffb7/work/logs" 2025-09-23 00:11:41.852687 | controller -> localhost | changed: "/var/lib/zuul/builds/7fd40f5e12da4ae48617f6fa8be4ffb7/work/artifacts" 2025-09-23 00:11:42.141192 | controller -> localhost | changed: "/var/lib/zuul/builds/7fd40f5e12da4ae48617f6fa8be4ffb7/work/docs" 2025-09-23 00:11:42.153963 | 2025-09-23 00:11:42.154137 | LOOP [fetch-output : Collect logs, artifacts and docs] 2025-09-23 00:11:43.473257 | controller | changed: 2025-09-23 00:11:43.473646 | controller | .d..t...... ./ 2025-09-23 00:11:43.473752 | controller | cd+++++++++ controller/ 2025-09-23 00:11:43.473828 | controller | changed: All items complete 2025-09-23 00:11:43.473873 | 2025-09-23 00:11:44.617642 | controller | changed: .d..t...... ./ 2025-09-23 00:11:45.710481 | controller | changed: .d..t...... ./ 2025-09-23 00:11:45.738710 | 2025-09-23 00:11:45.739114 | TASK [include_role : fetch-output-openshift] 2025-09-23 00:11:45.765754 | controller | skipping: Conditional result was False 2025-09-23 00:11:45.776187 | 2025-09-23 00:11:45.776289 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2025-09-23 00:11:45.826800 | controller | skipping: Conditional result was False 2025-09-23 00:11:45.837256 | controller | skipping: Conditional result was False 2025-09-23 00:11:45.909253 | 2025-09-23 00:11:45.909540 | PLAY [localhost] 2025-09-23 00:11:45.943960 | 2025-09-23 00:11:45.944140 | TASK [Run Zuul manifest role] 2025-09-23 00:11:45.981154 | localhost | ok 2025-09-23 00:11:46.003136 | 2025-09-23 00:11:46.003243 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-09-23 00:11:46.424413 | localhost | changed 2025-09-23 00:11:46.429633 | 2025-09-23 00:11:46.429727 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-09-23 00:11:46.464920 | localhost | ok 2025-09-23 00:11:46.473277 | 2025-09-23 00:11:46.473351 | TASK [Set zuul-log-path fact] 2025-09-23 00:11:46.503467 | localhost | ok 2025-09-23 00:11:46.520146 | 2025-09-23 00:11:46.520296 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-09-23 00:11:46.572320 | localhost | ok 2025-09-23 00:11:46.591634 | 2025-09-23 00:11:46.591801 | LOOP [Run upload-logs-swift role] 2025-09-23 00:11:46.633282 | localhost | Output suppressed because no_log was given 2025-09-23 00:11:46.665444 | 2025-09-23 00:11:46.665615 | TASK [Set zuul-log-path fact] 2025-09-23 00:11:46.690707 | localhost | skipping: Conditional result was False 2025-09-23 00:11:46.695923 | 2025-09-23 00:11:46.695990 | TASK [upload-logs-swift : Ensure logs are readable before uploading] 2025-09-23 00:11:47.160809 | localhost -> localhost | ok: Runtime: 0:00:00.009009 2025-09-23 00:11:47.175062 | 2025-09-23 00:11:47.175152 | TASK [upload-logs-swift : Upload logs to swift]