2025-09-20 00:13:25.755207 | Job console starting... 2025-09-20 00:13:25.764888 | Updating repositories 2025-09-20 00:13:25.884289 | Preparing job workspace 2025-09-20 00:13:29.815568 | Running Ansible setup... 2025-09-20 00:13:35.829039 | PRE-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master] 2025-09-20 00:13:36.545522 | 2025-09-20 00:13:36.545713 | PLAY [localhost] 2025-09-20 00:13:36.557611 | 2025-09-20 00:13:36.557728 | TASK [Gathering Facts] 2025-09-20 00:13:37.704805 | localhost | ok 2025-09-20 00:13:37.725467 | 2025-09-20 00:13:37.725730 | TASK [Setup log path fact] 2025-09-20 00:13:37.745289 | localhost | ok 2025-09-20 00:13:37.762228 | 2025-09-20 00:13:37.762410 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-09-20 00:13:37.793410 | localhost | ok 2025-09-20 00:13:37.802795 | 2025-09-20 00:13:37.802899 | TASK [emit-job-header : Print job information] 2025-09-20 00:13:37.843281 | # Job Information 2025-09-20 00:13:37.843492 | Ansible Version: 2.15.12 2025-09-20 00:13:37.843545 | Job: ansible-test-sanity-docker-stable-2.11 2025-09-20 00:13:37.843576 | Pipeline: periodic 2025-09-20 00:13:37.843605 | Executor: ze04.softwarefactory-project.io 2025-09-20 00:13:37.843633 | Triggered by: https://github.com/ansible-collections/ansible.network 2025-09-20 00:13:37.843666 | Log URL (when completed): https://ansible.softwarefactory-project.io/logs/34a/ansible/34a1ad9bb58c42e193db336dbeb84499/ 2025-09-20 00:13:37.843725 | Event ID: ea09755ff46b4297acaaa5c934db2f81 2025-09-20 00:13:37.849900 | 2025-09-20 00:13:37.850000 | LOOP [emit-job-header : Print node information] 2025-09-20 00:13:37.992367 | localhost | ok: 2025-09-20 00:13:37.992596 | localhost | # Node Information 2025-09-20 00:13:37.992635 | localhost | Inventory Hostname: controller 2025-09-20 00:13:37.992664 | localhost | Hostname: ip-172-16-172-65 2025-09-20 00:13:37.992724 | localhost | Username: zuul-worker 2025-09-20 00:13:37.992757 | localhost | Distro: Fedora 37 2025-09-20 00:13:37.992784 | localhost | Provider: ansible-us-east-2 2025-09-20 00:13:37.992810 | localhost | Region: us-east-2 2025-09-20 00:13:37.992835 | localhost | Label: ansible-fedora-37-1vcpu 2025-09-20 00:13:37.992860 | localhost | Product Name: t3.small 2025-09-20 00:13:37.992886 | localhost | Interface IP: 3.145.117.6 2025-09-20 00:13:38.008855 | 2025-09-20 00:13:38.009007 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-09-20 00:13:38.488621 | localhost -> localhost | changed 2025-09-20 00:13:38.495809 | 2025-09-20 00:13:38.495916 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-09-20 00:13:39.454631 | localhost -> localhost | changed 2025-09-20 00:13:39.506735 | 2025-09-20 00:13:39.506874 | PLAY [all:!appliance*] 2025-09-20 00:13:39.533634 | 2025-09-20 00:13:39.533807 | TASK [include_role : start-zuul-console] 2025-09-20 00:13:39.570946 | controller | ok 2025-09-20 00:13:39.592441 | 2025-09-20 00:13:39.592673 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-09-20 00:13:40.310579 | controller | ok 2025-09-20 00:13:40.335274 | 2025-09-20 00:13:40.335446 | TASK [use-our-mirror : Retrieve the IP address] 2025-09-20 00:13:42.172207 | controller | ok: Status code was 404 and not [200]: HTTP Error 404: Not Found 2025-09-20 00:13:42.185886 | 2025-09-20 00:13:42.186078 | TASK [use-our-mirror : Ensure the registry service is running on the mirror] 2025-09-20 00:13:42.338492 | controller | skipping: Conditional result was False 2025-09-20 00:13:42.355388 | 2025-09-20 00:13:42.355615 | TASK [use-our-mirror : Write the IP in /etc/hosts] 2025-09-20 00:13:42.395959 | controller | skipping: Conditional result was False 2025-09-20 00:13:42.413183 | 2025-09-20 00:13:42.413376 | TASK [use-our-mirror : Create the podman configuration directory] 2025-09-20 00:13:42.444350 | controller | skipping: Conditional result was False 2025-09-20 00:13:42.457238 | 2025-09-20 00:13:42.457410 | TASK [use-our-mirror : Copy the podman configuration] 2025-09-20 00:13:42.496072 | controller | skipping: Conditional result was False 2025-09-20 00:13:42.507534 | 2025-09-20 00:13:42.507695 | TASK [use-our-mirror : Copy the Yum configuration (main)] 2025-09-20 00:13:42.536629 | controller | skipping: Conditional result was False 2025-09-20 00:13:42.545807 | 2025-09-20 00:13:42.545905 | TASK [use-our-mirror : Copy the Yum configuration (update)] 2025-09-20 00:13:42.572007 | controller | skipping: Conditional result was False 2025-09-20 00:13:42.586723 | 2025-09-20 00:13:42.587075 | TASK [Disable Fedora Modular] 2025-09-20 00:13:43.351557 | controller | changed 2025-09-20 00:13:43.364175 | 2025-09-20 00:13:43.364304 | TASK [Enable EPEL] 2025-09-20 00:13:43.391436 | controller | skipping: Conditional result was False 2025-09-20 00:13:43.400975 | 2025-09-20 00:13:43.401295 | TASK [Register the RHEL node] 2025-09-20 00:13:43.542529 | 2025-09-20 00:13:43.542726 | TASK [Show the subscription-manager status] 2025-09-20 00:13:43.719317 | controller | skipping: Conditional result was False 2025-09-20 00:13:43.726400 | 2025-09-20 00:13:43.726512 | TASK [Enable EPEL on RHEL] 2025-09-20 00:13:43.873095 | controller | skipping: Conditional result was False 2025-09-20 00:13:43.890424 | 2025-09-20 00:13:43.890752 | TASK [Install git and tox] 2025-09-20 00:15:12.418280 | controller | changed 2025-09-20 00:15:12.431989 | 2025-09-20 00:15:12.432105 | TASK [include_role : prepare-workspace] 2025-09-20 00:15:12.457591 | controller | ok 2025-09-20 00:15:12.491669 | 2025-09-20 00:15:12.491823 | TASK [prepare-workspace : Start zuul_console daemon.] 2025-09-20 00:15:13.011651 | controller | ok 2025-09-20 00:15:13.030121 | 2025-09-20 00:15:13.032033 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2025-09-20 00:15:26.091560 | controller | Output suppressed because no_log was given 2025-09-20 00:15:26.142313 | 2025-09-20 00:15:26.142523 | TASK [include_role : prepare-workspace-openshift] 2025-09-20 00:15:26.168109 | controller | skipping: Conditional result was False 2025-09-20 00:15:26.190065 | 2025-09-20 00:15:26.190157 | PLAY [all:!appliance] 2025-09-20 00:15:26.206460 | 2025-09-20 00:15:26.206590 | TASK [Run add-build-sshkey role (RSA)] 2025-09-20 00:15:26.229098 | controller | ok 2025-09-20 00:15:26.251194 | 2025-09-20 00:15:26.251330 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-09-20 00:15:26.510645 | controller -> localhost | ok 2025-09-20 00:15:26.517542 | 2025-09-20 00:15:26.517633 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-09-20 00:15:26.554606 | controller | ok 2025-09-20 00:15:26.573148 | controller | included: /var/lib/zuul/builds/34a1ad9bb58c42e193db336dbeb84499/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-09-20 00:15:26.580027 | 2025-09-20 00:15:26.580144 | TASK [add-build-sshkey : Create Temp SSH key] 2025-09-20 00:15:27.064651 | controller -> localhost | Generating public/private rsa key pair. 2025-09-20 00:15:27.064956 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/34a1ad9bb58c42e193db336dbeb84499/work/34a1ad9bb58c42e193db336dbeb84499_id_rsa. 2025-09-20 00:15:27.064991 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/34a1ad9bb58c42e193db336dbeb84499/work/34a1ad9bb58c42e193db336dbeb84499_id_rsa.pub. 2025-09-20 00:15:27.065017 | controller -> localhost | The key fingerprint is: 2025-09-20 00:15:27.065041 | controller -> localhost | SHA256:avG/htav9b+G3sxKYHNnNPxKnOvIJBAxbCcEBC0efHs zuul-build-sshkey 2025-09-20 00:15:27.065067 | controller -> localhost | The key's randomart image is: 2025-09-20 00:15:27.065090 | controller -> localhost | +---[RSA 2048]----+ 2025-09-20 00:15:27.065112 | controller -> localhost | | ..+o++ | 2025-09-20 00:15:27.065136 | controller -> localhost | | + o +o. . | 2025-09-20 00:15:27.065158 | controller -> localhost | | . + o.o + | 2025-09-20 00:15:27.065202 | controller -> localhost | | . . E. ...o| 2025-09-20 00:15:27.065226 | controller -> localhost | | ..S + .+o.| 2025-09-20 00:15:27.065261 | controller -> localhost | | + .. +.oo | 2025-09-20 00:15:27.065289 | controller -> localhost | | o .o. o.+ | 2025-09-20 00:15:27.065312 | controller -> localhost | | . o.o=.=+. | 2025-09-20 00:15:27.065333 | controller -> localhost | | . .++=o=*o| 2025-09-20 00:15:27.065358 | controller -> localhost | +----[SHA256]-----+ 2025-09-20 00:15:27.065415 | controller -> localhost | ok: Runtime: 0:00:00.087975 2025-09-20 00:15:27.073709 | 2025-09-20 00:15:27.073837 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-09-20 00:15:27.108489 | controller | ok 2025-09-20 00:15:27.122873 | controller | included: /var/lib/zuul/builds/34a1ad9bb58c42e193db336dbeb84499/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-09-20 00:15:27.138106 | 2025-09-20 00:15:27.138256 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-09-20 00:15:27.152975 | controller | skipping: Conditional result was False 2025-09-20 00:15:27.161703 | 2025-09-20 00:15:27.161816 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-09-20 00:15:27.822510 | controller | changed 2025-09-20 00:15:27.832147 | 2025-09-20 00:15:27.832259 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-09-20 00:15:28.167069 | controller | ok 2025-09-20 00:15:28.173200 | 2025-09-20 00:15:28.173290 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-09-20 00:15:29.449629 | controller | changed 2025-09-20 00:15:29.455448 | 2025-09-20 00:15:29.455524 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-09-20 00:15:30.714557 | controller | changed 2025-09-20 00:15:30.722836 | 2025-09-20 00:15:30.722985 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-09-20 00:15:30.749994 | controller | skipping: Conditional result was False 2025-09-20 00:15:30.758653 | 2025-09-20 00:15:30.758820 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-09-20 00:15:31.188503 | controller -> localhost | changed 2025-09-20 00:15:31.201098 | 2025-09-20 00:15:31.201223 | TASK [add-build-sshkey : Add back temp key] 2025-09-20 00:15:31.487277 | controller -> localhost | Identity added: /var/lib/zuul/builds/34a1ad9bb58c42e193db336dbeb84499/work/34a1ad9bb58c42e193db336dbeb84499_id_rsa (zuul-build-sshkey) 2025-09-20 00:15:31.487525 | controller -> localhost | ok: Runtime: 0:00:00.009514 2025-09-20 00:15:31.494191 | 2025-09-20 00:15:31.494266 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-09-20 00:15:32.001330 | controller | ok 2025-09-20 00:15:32.009970 | 2025-09-20 00:15:32.010170 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-09-20 00:15:32.036634 | controller | skipping: Conditional result was False 2025-09-20 00:15:32.049481 | 2025-09-20 00:15:32.049633 | TASK [Run add-build-sshkey role (ECDSA)] 2025-09-20 00:15:32.081937 | controller | ok 2025-09-20 00:15:32.100438 | 2025-09-20 00:15:32.100566 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-09-20 00:15:32.350821 | controller -> localhost | ok 2025-09-20 00:15:32.359796 | 2025-09-20 00:15:32.359916 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-09-20 00:15:32.390589 | controller | ok 2025-09-20 00:15:32.402132 | controller | included: /var/lib/zuul/builds/34a1ad9bb58c42e193db336dbeb84499/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-09-20 00:15:32.408287 | 2025-09-20 00:15:32.408361 | TASK [add-build-sshkey : Create Temp SSH key] 2025-09-20 00:15:32.762276 | controller -> localhost | Generating public/private ecdsa key pair. 2025-09-20 00:15:32.762485 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/34a1ad9bb58c42e193db336dbeb84499/work/34a1ad9bb58c42e193db336dbeb84499_id_ecdsa. 2025-09-20 00:15:32.762513 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/34a1ad9bb58c42e193db336dbeb84499/work/34a1ad9bb58c42e193db336dbeb84499_id_ecdsa.pub. 2025-09-20 00:15:32.762546 | controller -> localhost | The key fingerprint is: 2025-09-20 00:15:32.762566 | controller -> localhost | SHA256:cXH6N6+cEQKzuTrI6y9xQle34HcJZ/YoYKkhes+1bvQ zuul-build-sshkey 2025-09-20 00:15:32.762584 | controller -> localhost | The key's randomart image is: 2025-09-20 00:15:32.762603 | controller -> localhost | +---[ECDSA 521]---+ 2025-09-20 00:15:32.762620 | controller -> localhost | | ... | 2025-09-20 00:15:32.762637 | controller -> localhost | | . . *+o + | 2025-09-20 00:15:32.762655 | controller -> localhost | | . ..*=+ * + | 2025-09-20 00:15:32.762672 | controller -> localhost | | . o ooo*+ + .| 2025-09-20 00:15:32.762849 | controller -> localhost | | o +S.oooo+ | 2025-09-20 00:15:32.762879 | controller -> localhost | | o + o. o + | 2025-09-20 00:15:32.762898 | controller -> localhost | | . = o.. . .| 2025-09-20 00:15:32.762916 | controller -> localhost | | + ..o E. + | 2025-09-20 00:15:32.762934 | controller -> localhost | | .o+oo + | 2025-09-20 00:15:32.762951 | controller -> localhost | +----[SHA256]-----+ 2025-09-20 00:15:32.762998 | controller -> localhost | ok: Runtime: 0:00:00.015405 2025-09-20 00:15:32.770983 | 2025-09-20 00:15:32.771079 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-09-20 00:15:32.792367 | controller | ok 2025-09-20 00:15:32.800020 | controller | included: /var/lib/zuul/builds/34a1ad9bb58c42e193db336dbeb84499/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-09-20 00:15:32.810066 | 2025-09-20 00:15:32.810195 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-09-20 00:15:32.836162 | controller | skipping: Conditional result was False 2025-09-20 00:15:32.846673 | 2025-09-20 00:15:32.846852 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-09-20 00:15:33.291458 | controller | changed 2025-09-20 00:15:33.299379 | 2025-09-20 00:15:33.299483 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-09-20 00:15:33.637840 | controller | ok 2025-09-20 00:15:33.650521 | 2025-09-20 00:15:33.650766 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-09-20 00:15:34.890883 | controller | changed 2025-09-20 00:15:34.898017 | 2025-09-20 00:15:34.898099 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-09-20 00:15:36.148355 | controller | changed 2025-09-20 00:15:36.156679 | 2025-09-20 00:15:36.156901 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-09-20 00:15:36.183466 | controller | skipping: Conditional result was False 2025-09-20 00:15:36.193574 | 2025-09-20 00:15:36.193674 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-09-20 00:15:36.477928 | controller -> localhost | changed 2025-09-20 00:15:36.584657 | 2025-09-20 00:15:36.584881 | TASK [add-build-sshkey : Add back temp key] 2025-09-20 00:15:36.898031 | controller -> localhost | Identity added: /var/lib/zuul/builds/34a1ad9bb58c42e193db336dbeb84499/work/34a1ad9bb58c42e193db336dbeb84499_id_ecdsa (zuul-build-sshkey) 2025-09-20 00:15:36.898339 | controller -> localhost | ok: Runtime: 0:00:00.016541 2025-09-20 00:15:36.905059 | 2025-09-20 00:15:36.905121 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-09-20 00:15:37.258953 | controller | ok 2025-09-20 00:15:37.264775 | 2025-09-20 00:15:37.264858 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-09-20 00:15:37.300941 | controller | skipping: Conditional result was False 2025-09-20 00:15:37.315811 | 2025-09-20 00:15:37.315921 | TASK [include_role : remove-zuul-sshkey] 2025-09-20 00:15:37.331952 | controller | skipping: Conditional result was False 2025-09-20 00:15:37.345741 | 2025-09-20 00:15:37.345892 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2025-09-20 00:15:37.658782 | controller | ok: "logs" 2025-09-20 00:15:37.665181 | controller | ok: All items complete 2025-09-20 00:15:37.665263 | 2025-09-20 00:15:37.953565 | controller | ok: "artifacts" 2025-09-20 00:15:38.239529 | controller | ok: "docs" 2025-09-20 00:15:38.259750 | 2025-09-20 00:15:38.259959 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2025-09-20 00:15:38.584228 | controller | changed: "logs" 2025-09-20 00:15:38.865640 | controller | changed: "artifacts" 2025-09-20 00:15:39.135783 | controller | changed: "docs" 2025-09-20 00:15:39.196603 | 2025-09-20 00:15:39.196789 | PLAY RECAP 2025-09-20 00:15:39.196866 | controller | ok: 30 changed: 16 unreachable: 0 failed: 0 skipped: 18 rescued: 0 ignored: 0 2025-09-20 00:15:39.196908 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-09-20 00:15:39.196937 | 2025-09-20 00:15:39.350397 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master] 2025-09-20 00:15:39.352367 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2025-09-20 00:15:39.947426 | 2025-09-20 00:15:39.947546 | PLAY [all] 2025-09-20 00:15:39.969601 | 2025-09-20 00:15:39.969723 | TASK [Install binary dependencies] 2025-09-20 00:15:40.020333 | controller | ok 2025-09-20 00:15:40.039880 | 2025-09-20 00:15:40.040011 | TASK [bindep : Include find tasks] 2025-09-20 00:15:40.070179 | controller | ok 2025-09-20 00:15:40.077871 | controller | included: /var/lib/zuul/builds/34a1ad9bb58c42e193db336dbeb84499/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find.yaml 2025-09-20 00:15:40.084020 | 2025-09-20 00:15:40.084086 | TASK [bindep : Look for bindep.txt] 2025-09-20 00:15:40.627705 | controller | ok 2025-09-20 00:15:40.634757 | 2025-09-20 00:15:40.634849 | TASK [bindep : Define bindep_file fact] 2025-09-20 00:15:40.660099 | controller | skipping: Conditional result was False 2025-09-20 00:15:40.666844 | 2025-09-20 00:15:40.666928 | TASK [bindep : Look for other-requirements.txt] 2025-09-20 00:15:40.957179 | controller | ok 2025-09-20 00:15:40.964200 | 2025-09-20 00:15:40.964343 | TASK [bindep : Define bindep_file fact] 2025-09-20 00:15:40.989209 | controller | skipping: Conditional result was False 2025-09-20 00:15:40.995857 | 2025-09-20 00:15:40.996001 | TASK [bindep : Look for bindep fallback file] 2025-09-20 00:15:41.020742 | controller | skipping: Conditional result was False 2025-09-20 00:15:41.027968 | 2025-09-20 00:15:41.028113 | TASK [bindep : Define bindep_file fact] 2025-09-20 00:15:41.053018 | controller | skipping: Conditional result was False 2025-09-20 00:15:41.059397 | 2025-09-20 00:15:41.059545 | TASK [bindep : Include bindep tasks] 2025-09-20 00:15:41.084193 | controller | skipping: Conditional result was False 2025-09-20 00:15:41.091978 | 2025-09-20 00:15:41.092106 | TASK [bindep : Include install tasks] 2025-09-20 00:15:41.117382 | controller | skipping: Conditional result was False 2025-09-20 00:15:41.125154 | 2025-09-20 00:15:41.125277 | LOOP [bindep : Include package tasks] 2025-09-20 00:15:41.183003 | 2025-09-20 00:15:41.183202 | TASK [Run test-setup role] 2025-09-20 00:15:41.202207 | controller | ok 2025-09-20 00:15:41.222050 | 2025-09-20 00:15:41.222166 | TASK [test-setup : Check if project's tools/test-setup.sh exists] 2025-09-20 00:15:41.508507 | controller | ok 2025-09-20 00:15:41.517780 | 2025-09-20 00:15:41.517925 | TASK [test-setup : Run tools/test-setup.sh] 2025-09-20 00:15:41.661510 | controller | skipping: Conditional result was False 2025-09-20 00:15:41.719966 | 2025-09-20 00:15:41.720101 | PLAY RECAP 2025-09-20 00:15:41.720157 | controller | ok: 4 changed: 0 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2025-09-20 00:15:41.720182 | 2025-09-20 00:15:41.849947 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2025-09-20 00:15:41.850931 | PRE-RUN START: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master] 2025-09-20 00:15:42.462088 | 2025-09-20 00:15:42.462251 | PLAY [controller] 2025-09-20 00:15:42.482227 | 2025-09-20 00:15:42.482380 | TASK [Create the /root directory] 2025-09-20 00:15:43.231407 | controller | ok 2025-09-20 00:15:43.239231 | 2025-09-20 00:15:43.239387 | TASK [Install glibc-langpack-en] 2025-09-20 00:15:51.244666 | controller | ok: Nothing to do 2025-09-20 00:15:51.260296 | 2025-09-20 00:15:51.260449 | TASK [Ensure controller directory exists] 2025-09-20 00:15:51.750993 | controller | changed 2025-09-20 00:15:51.756714 | 2025-09-20 00:15:51.756813 | TASK [Install container runtime] 2025-09-20 00:15:51.811871 | controller | ok 2025-09-20 00:15:51.864457 | 2025-09-20 00:15:51.864628 | LOOP [ensure-docker : Gather variables for each operating system] 2025-09-20 00:15:51.921970 | controller | ok: "/var/lib/zuul/builds/34a1ad9bb58c42e193db336dbeb84499/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/vars/fedora.yaml" 2025-09-20 00:15:51.940250 | 2025-09-20 00:15:51.940391 | TASK [ensure-docker : Sanity check for distro selection] 2025-09-20 00:15:51.966107 | controller | skipping: Conditional result was False 2025-09-20 00:15:51.972602 | 2025-09-20 00:15:51.972725 | TASK [ensure-docker : Sanity check for distro install] 2025-09-20 00:15:51.998118 | controller | skipping: Conditional result was False 2025-09-20 00:15:52.006116 | 2025-09-20 00:15:52.006231 | TASK [ensure-docker : Sanity check for upstream install] 2025-09-20 00:15:52.031522 | controller | skipping: Conditional result was False 2025-09-20 00:15:52.039717 | 2025-09-20 00:15:52.039804 | TASK [ensure-docker : Sanity check for upstream install mirrors] 2025-09-20 00:15:52.066060 | controller | skipping: Conditional result was False 2025-09-20 00:15:52.074854 | 2025-09-20 00:15:52.074984 | TASK [ensure-docker : Include tasks for docker install] 2025-09-20 00:15:52.102503 | controller | ok 2025-09-20 00:15:52.111285 | controller | included: /var/lib/zuul/builds/34a1ad9bb58c42e193db336dbeb84499/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/tasks/docker-upstream.yaml 2025-09-20 00:15:52.119641 | 2025-09-20 00:15:52.119741 | TASK [ensure-docker : Not Implemented] 2025-09-20 00:15:52.145076 | controller | skipping: Conditional result was False 2025-09-20 00:15:52.156027 | 2025-09-20 00:15:52.156150 | TASK [Set up docker mirrors] 2025-09-20 00:15:52.180512 | controller | ok 2025-09-20 00:15:52.205497 | 2025-09-20 00:15:52.205658 | TASK [use-docker-mirror : Set docker_mirror fact from mirror_info] 2025-09-20 00:15:52.240556 | controller | skipping: Conditional result was False 2025-09-20 00:15:52.248105 | 2025-09-20 00:15:52.248214 | TASK [use-docker-mirror : Set docker_mirror fact from zuul_site_mirror_info] 2025-09-20 00:15:52.273037 | controller | skipping: Conditional result was False 2025-09-20 00:15:52.284974 | 2025-09-20 00:15:52.285125 | TASK [use-docker-mirror : Set docker_mirror fact] 2025-09-20 00:15:52.335172 | controller | skipping: Conditional result was False 2025-09-20 00:15:52.348630 | 2025-09-20 00:15:52.348790 | TASK [use-docker-mirror : Set up docker mirrors] 2025-09-20 00:15:52.386267 | controller | skipping: Conditional result was False 2025-09-20 00:15:52.404134 | 2025-09-20 00:15:52.404321 | TASK [Add all repositories] 2025-09-20 00:15:52.429896 | controller | ok 2025-09-20 00:15:52.506816 | 2025-09-20 00:15:52.506969 | LOOP [ensure-package-repositories : Install repositories] 2025-09-20 00:15:52.539976 | controller | ok: "/var/lib/zuul/builds/34a1ad9bb58c42e193db336dbeb84499/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-package-repositories/tasks/RedHat.yaml" 2025-09-20 00:15:52.540358 | controller | ok: All items complete 2025-09-20 00:15:52.540401 | 2025-09-20 00:15:52.553231 | controller | included: /var/lib/zuul/builds/34a1ad9bb58c42e193db336dbeb84499/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-package-repositories/tasks/RedHat.yaml 2025-09-20 00:15:52.559869 | 2025-09-20 00:15:52.560120 | TASK [ensure-package-repositories : Create GPG key temp directory] 2025-09-20 00:15:53.114767 | controller | changed 2025-09-20 00:15:53.123719 | 2025-09-20 00:15:53.123810 | LOOP [ensure-package-repositories : Upload GPG keys] 2025-09-20 00:15:54.682118 | controller | changed: 2025-09-20 00:15:54.682800 | controller | { 2025-09-20 00:15:54.682892 | 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-20 00:15:54.682943 | controller | } 2025-09-20 00:15:54.703190 | 2025-09-20 00:15:54.703375 | LOOP [ensure-package-repositories : Import GPG keys] 2025-09-20 00:15:55.512452 | controller | changed: 2025-09-20 00:15:55.512911 | controller | { 2025-09-20 00:15:55.512977 | 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-20 00:15:55.513025 | controller | } 2025-09-20 00:15:55.513089 | controller | changed: All items complete 2025-09-20 00:15:55.513133 | 2025-09-20 00:15:55.528201 | 2025-09-20 00:15:55.528431 | TASK [ensure-package-repositories : Remove GPG temp directory] 2025-09-20 00:15:55.903744 | controller | changed 2025-09-20 00:15:55.917433 | 2025-09-20 00:15:55.917651 | LOOP [ensure-package-repositories : Add repositories] 2025-09-20 00:15:56.399983 | controller | changed: 2025-09-20 00:15:56.400295 | controller | { 2025-09-20 00:15:56.400379 | controller | "baseurl": "https://download.docker.com/linux/fedora/$releasever/$basearch/stable", 2025-09-20 00:15:56.400449 | controller | "description": "Docker CE Stable - $basearch", 2025-09-20 00:15:56.400498 | controller | "gpgcheck": true, 2025-09-20 00:15:56.400537 | controller | "name": "docker-ce-stable" 2025-09-20 00:15:56.400575 | controller | } 2025-09-20 00:15:56.433222 | 2025-09-20 00:15:56.433432 | TASK [ensure-docker : Install pre-reqs] 2025-09-20 00:15:59.819831 | controller | ok: Nothing to do 2025-09-20 00:15:59.832531 | 2025-09-20 00:15:59.832784 | TASK [ensure-docker : Remove packages] 2025-09-20 00:16:02.961729 | controller | ok: Nothing to do 2025-09-20 00:16:02.975393 | 2025-09-20 00:16:02.975561 | TASK [ensure-docker : Install upstream docker using package] 2025-09-20 00:16:56.120067 | controller | changed 2025-09-20 00:16:56.133314 | 2025-09-20 00:16:56.133447 | TASK [ensure-docker : Setup docker] 2025-09-20 00:16:56.163144 | controller | ok 2025-09-20 00:16:56.183519 | controller | included: /var/lib/zuul/builds/34a1ad9bb58c42e193db336dbeb84499/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/tasks/docker-setup.yaml 2025-09-20 00:16:56.199229 | 2025-09-20 00:16:56.199358 | TASK [ensure-docker : Ensure "docker" group exists] 2025-09-20 00:16:56.826271 | controller | ok 2025-09-20 00:16:56.833800 | 2025-09-20 00:16:56.833936 | TASK [ensure-docker : Add user to docker group] 2025-09-20 00:16:57.537121 | controller | changed 2025-09-20 00:16:57.553238 | 2025-09-20 00:16:57.553396 | TASK [Update docker daemon proxy configuration] 2025-09-20 00:16:57.580971 | controller | skipping: Conditional result was False 2025-09-20 00:16:57.590876 | 2025-09-20 00:16:57.590971 | TASK [Lower default MTU] 2025-09-20 00:16:57.606712 | controller | skipping: Conditional result was False 2025-09-20 00:16:57.617427 | 2025-09-20 00:16:57.617535 | TASK [ensure-docker : Get the status of the docket.socket unit] 2025-09-20 00:16:58.156869 | controller | inactive 2025-09-20 00:16:58.305524 | controller | ok: Runtime: 0:00:00.011828 2025-09-20 00:16:58.318859 | 2025-09-20 00:16:58.319011 | TASK [ensure-docker : Get the status of the docket unit] 2025-09-20 00:16:58.805090 | controller | inactive 2025-09-20 00:16:58.980250 | controller | ok: Runtime: 0:00:00.018178 2025-09-20 00:16:58.995102 | 2025-09-20 00:16:58.995260 | TASK [ensure-docker : Restart docker] 2025-09-20 00:16:59.067044 | Trigger a Docker restart 2025-09-20 00:16:59.080278 | 2025-09-20 00:16:59.080467 | TASK [ensure-docker : Reset ssh connection to pick up docker group] 2025-09-20 00:16:59.133429 | 2025-09-20 00:16:59.133746 | TASK [ensure-docker : Install docker-compose] 2025-09-20 00:16:59.162904 | controller | skipping: Conditional result was False 2025-09-20 00:16:59.173291 | 2025-09-20 00:16:59.173458 | TASK [ensure-docker : Flush handlers before role exit] 2025-09-20 00:16:59.188859 | 2025-09-20 00:16:59.188971 | TASK [ensure-docker : Stop docker.socket to avoid any conflict] 2025-09-20 00:17:01.555920 | controller | changed 2025-09-20 00:17:01.564712 | 2025-09-20 00:17:01.564802 | TASK [ensure-docker : Assure docker service is running] 2025-09-20 00:17:04.410253 | controller | changed 2025-09-20 00:17:04.420762 | 2025-09-20 00:17:04.420890 | TASK [ensure-docker : Assure docker.socket service is running] 2025-09-20 00:17:05.760336 | controller | ok 2025-09-20 00:17:05.779908 | 2025-09-20 00:17:05.780114 | TASK [ensure-docker : Correct group ownership on docker sock] 2025-09-20 00:17:06.672536 | controller | ok 2025-09-20 00:17:06.683040 | 2025-09-20 00:17:06.683219 | TASK [ensure-docker : Validate ability to talk with docker] 2025-09-20 00:17:07.436059 | controller | CONTAINER ID IMAGE COMMAND CREATED STATUS PORTS NAMES 2025-09-20 00:17:07.835804 | controller | ok: Runtime: 0:00:00.054823 2025-09-20 00:17:07.850414 | 2025-09-20 00:17:07.850554 | TASK [ensure-docker : Get version details] 2025-09-20 00:17:08.455718 | controller | Client: Docker Engine - Community 2025-09-20 00:17:08.457986 | controller | Version: 24.0.7 2025-09-20 00:17:08.458058 | controller | API version: 1.43 2025-09-20 00:17:08.458081 | controller | Go version: go1.20.10 2025-09-20 00:17:08.458106 | controller | Git commit: afdd53b 2025-09-20 00:17:08.458118 | controller | Built: Thu Oct 26 09:09:39 2023 2025-09-20 00:17:08.458129 | controller | OS/Arch: linux/amd64 2025-09-20 00:17:08.458143 | controller | Context: default 2025-09-20 00:17:08.458157 | controller | 2025-09-20 00:17:08.458167 | controller | Server: Docker Engine - Community 2025-09-20 00:17:08.458176 | controller | Engine: 2025-09-20 00:17:08.458186 | controller | Version: 24.0.7 2025-09-20 00:17:08.458195 | controller | API version: 1.43 (minimum version 1.12) 2025-09-20 00:17:08.458204 | controller | Go version: go1.20.10 2025-09-20 00:17:08.458213 | controller | Git commit: 311b9ff 2025-09-20 00:17:08.458222 | controller | Built: Thu Oct 26 09:08:11 2023 2025-09-20 00:17:08.458232 | controller | OS/Arch: linux/amd64 2025-09-20 00:17:08.458241 | controller | Experimental: false 2025-09-20 00:17:08.458250 | controller | containerd: 2025-09-20 00:17:08.458259 | controller | Version: 1.6.25 2025-09-20 00:17:08.458268 | controller | GitCommit: d8f198a4ed8892c764191ef7b3b06d8a2eeb5c7f 2025-09-20 00:17:08.458277 | controller | runc: 2025-09-20 00:17:08.458286 | controller | Version: 1.1.10 2025-09-20 00:17:08.458295 | controller | GitCommit: v1.1.10-0-g18a0cb0 2025-09-20 00:17:08.458307 | controller | docker-init: 2025-09-20 00:17:08.458316 | controller | Version: 0.19.0 2025-09-20 00:17:08.458325 | controller | GitCommit: de40ad0 2025-09-20 00:17:08.520102 | controller | ok: Runtime: 0:00:00.045162 2025-09-20 00:17:08.527596 | 2025-09-20 00:17:08.527746 | TASK [ensure-docker : Dump installed docker details] 2025-09-20 00:17:08.561058 | {'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-20 00:17:08.413596', 'end': '2025-09-20 00:17:08.458758', 'delta': '0:00:00.045162', 'msg': '', 'zuul_log_id': 'fa163e3b-3c83-baf5-ea05-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-20 00:17:08.568620 | 2025-09-20 00:17:08.568748 | TASK [Use buildset registry] 2025-09-20 00:17:08.593788 | controller | skipping: Conditional result was False 2025-09-20 00:17:08.608672 | 2025-09-20 00:17:08.608848 | TASK [Ensure python3.8 is present] 2025-09-20 00:17:08.645291 | controller | skipping: Conditional result was False 2025-09-20 00:17:08.653514 | 2025-09-20 00:17:08.653597 | TASK [Run our-ensure-python role (Fedora only for now)] 2025-09-20 00:17:08.681872 | controller | ok 2025-09-20 00:17:08.703377 | 2025-09-20 00:17:08.703545 | TASK [our-ensure-python : Install the right Python version (rpm)] 2025-09-20 00:17:14.988203 | controller | ok: Nothing to do 2025-09-20 00:17:14.993414 | 2025-09-20 00:17:14.993477 | TASK [our-ensure-python : Also install python3-devel] 2025-09-20 00:17:28.395154 | controller | changed 2025-09-20 00:17:28.414637 | 2025-09-20 00:17:28.414803 | TASK [Run ensure-virtualenv role] 2025-09-20 00:17:28.435420 | controller | ok 2025-09-20 00:17:28.458589 | 2025-09-20 00:17:28.458729 | TASK [ensure-virtualenv : Check if virtualenv is installed] 2025-09-20 00:17:28.835142 | controller | /usr/bin/virtualenv 2025-09-20 00:17:29.102459 | controller | ok: Runtime: 0:00:00.004539 2025-09-20 00:17:29.112861 | 2025-09-20 00:17:29.113042 | LOOP [ensure-virtualenv : Install virtualenv package] 2025-09-20 00:17:29.156450 | controller | skipping: Conditional result was False 2025-09-20 00:17:29.156869 | controller | changed: All items complete 2025-09-20 00:17:29.156899 | 2025-09-20 00:17:29.179122 | 2025-09-20 00:17:29.179615 | TASK [Find the full path of the Python interpreter] 2025-09-20 00:17:29.616172 | controller | /usr/bin/python3.9 2025-09-20 00:17:29.819600 | controller | ok 2025-09-20 00:17:29.834124 | 2025-09-20 00:17:29.834352 | TASK [Create virtualenv for ansible-test with a specific interpreter] 2025-09-20 00:17:31.976390 | controller | created virtual environment CPython3.9.18.final.0-64 in 1140ms 2025-09-20 00:17:32.060636 | controller | creator CPython3Posix(dest=/home/zuul-worker/venv, clear=False, no_vcs_ignore=False, global=False) 2025-09-20 00:17:32.060692 | 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-20 00:17:32.060713 | controller | added seed packages: pip==22.2.2, setuptools==62.6.0, wheel==0.37.1 2025-09-20 00:17:32.060737 | controller | activators BashActivator,CShellActivator,FishActivator,NushellActivator,PowerShellActivator,PythonActivator 2025-09-20 00:17:32.487292 | controller | changed 2025-09-20 00:17:32.502934 | 2025-09-20 00:17:32.503115 | TASK [Set selinux package] 2025-09-20 00:17:32.555912 | controller | ok 2025-09-20 00:17:32.565638 | 2025-09-20 00:17:32.565922 | TASK [Set selinux package (Fedora)] 2025-09-20 00:17:32.611851 | controller | ok 2025-09-20 00:17:32.619886 | 2025-09-20 00:17:32.620002 | TASK [Install selinux into virtualenv] 2025-09-20 00:17:35.969359 | controller | Collecting selinux-please-lie-to-me 2025-09-20 00:17:36.098981 | controller | Downloading selinux_please_lie_to_me-1.0.1-py2.py3-none-any.whl (3.8 kB) 2025-09-20 00:17:37.007061 | controller | Collecting setuptools<50.0.0 2025-09-20 00:17:37.021031 | controller | Downloading setuptools-49.6.0-py3-none-any.whl (803 kB) 2025-09-20 00:17:37.175421 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 803.3/803.3 kB 5.5 MB/s eta 0:00:00 2025-09-20 00:17:37.428107 | controller | Installing collected packages: setuptools, selinux-please-lie-to-me 2025-09-20 00:17:37.429483 | controller | Attempting uninstall: setuptools 2025-09-20 00:17:37.433052 | controller | Found existing installation: setuptools 62.6.0 2025-09-20 00:17:37.636383 | controller | Uninstalling setuptools-62.6.0: 2025-09-20 00:17:37.653774 | controller | Successfully uninstalled setuptools-62.6.0 2025-09-20 00:17:38.481602 | controller | Successfully installed selinux-please-lie-to-me-1.0.1 setuptools-49.6.0 2025-09-20 00:17:38.841197 | controller | 2025-09-20 00:17:39.130184 | controller | [notice] A new release of pip available: 22.2.2 -> 25.2 2025-09-20 00:17:39.130230 | controller | [notice] To update, run: /home/zuul-worker/venv/bin/python -m pip install --upgrade pip 2025-09-20 00:17:39.266199 | controller | ok: Runtime: 0:00:05.930025 2025-09-20 00:17:39.274832 | 2025-09-20 00:17:39.274921 | TASK [Install pytest-forked into virtualenv] 2025-09-20 00:17:41.005967 | controller | Collecting pytest-forked 2025-09-20 00:17:41.146810 | controller | Downloading pytest_forked-1.6.0-py3-none-any.whl (4.9 kB) 2025-09-20 00:17:41.377733 | controller | Collecting pytest>=3.10 2025-09-20 00:17:41.395567 | controller | Downloading pytest-8.4.2-py3-none-any.whl (365 kB) 2025-09-20 00:17:41.488565 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 365.8/365.8 kB 4.6 MB/s eta 0:00:00 2025-09-20 00:17:41.587360 | controller | Collecting py 2025-09-20 00:17:41.607054 | controller | Downloading py-1.11.0-py2.py3-none-any.whl (98 kB) 2025-09-20 00:17:41.628706 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 98.7/98.7 kB 5.3 MB/s eta 0:00:00 2025-09-20 00:17:41.774978 | controller | Collecting packaging>=20 2025-09-20 00:17:41.791493 | controller | Downloading packaging-25.0-py3-none-any.whl (66 kB) 2025-09-20 00:17:41.804545 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 66.5/66.5 kB 6.4 MB/s eta 0:00:00 2025-09-20 00:17:41.947420 | controller | Collecting pygments>=2.7.2 2025-09-20 00:17:41.961604 | controller | Downloading pygments-2.19.2-py3-none-any.whl (1.2 MB) 2025-09-20 00:17:42.093935 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 1.2/1.2 MB 9.5 MB/s eta 0:00:00 2025-09-20 00:17:42.197885 | controller | Collecting exceptiongroup>=1 2025-09-20 00:17:42.212088 | controller | Downloading exceptiongroup-1.3.0-py3-none-any.whl (16 kB) 2025-09-20 00:17:42.299964 | controller | Collecting tomli>=1 2025-09-20 00:17:42.313908 | controller | Downloading tomli-2.2.1-py3-none-any.whl (14 kB) 2025-09-20 00:17:42.406930 | controller | Collecting pluggy<2,>=1.5 2025-09-20 00:17:42.424111 | controller | Downloading pluggy-1.6.0-py3-none-any.whl (20 kB) 2025-09-20 00:17:42.476847 | controller | Collecting iniconfig>=1 2025-09-20 00:17:42.497559 | controller | Downloading iniconfig-2.1.0-py3-none-any.whl (6.0 kB) 2025-09-20 00:17:42.582532 | controller | Collecting typing-extensions>=4.6.0 2025-09-20 00:17:42.596608 | controller | Downloading typing_extensions-4.15.0-py3-none-any.whl (44 kB) 2025-09-20 00:17:42.606604 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 44.6/44.6 kB 6.1 MB/s eta 0:00:00 2025-09-20 00:17:42.732184 | controller | Installing collected packages: typing-extensions, tomli, pygments, py, pluggy, packaging, iniconfig, exceptiongroup, pytest, pytest-forked 2025-09-20 00:17:44.944472 | 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-20 00:17:44.951129 | controller | 2025-09-20 00:17:45.134353 | controller | [notice] A new release of pip available: 22.2.2 -> 25.2 2025-09-20 00:17:45.134399 | controller | [notice] To update, run: /home/zuul-worker/venv/bin/python -m pip install --upgrade pip 2025-09-20 00:17:45.424364 | controller | ok: Runtime: 0:00:05.276093 2025-09-20 00:17:45.438897 | 2025-09-20 00:17:45.439073 | TASK [Update pip] 2025-09-20 00:17:47.265198 | controller | Requirement already satisfied: pip in ./venv/lib/python3.9/site-packages (22.2.2) 2025-09-20 00:17:47.676194 | controller | Collecting pip 2025-09-20 00:17:47.801224 | controller | Downloading pip-25.2-py3-none-any.whl (1.8 MB) 2025-09-20 00:17:47.988666 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 1.8/1.8 MB 9.7 MB/s eta 0:00:00 2025-09-20 00:17:48.099697 | controller | Installing collected packages: pip 2025-09-20 00:17:48.100180 | controller | Attempting uninstall: pip 2025-09-20 00:17:48.101593 | controller | Found existing installation: pip 22.2.2 2025-09-20 00:17:48.662650 | controller | Uninstalling pip-22.2.2: 2025-09-20 00:17:48.734548 | controller | Successfully uninstalled pip-22.2.2 2025-09-20 00:17:50.494741 | controller | Successfully installed pip-25.2 2025-09-20 00:17:51.090661 | controller | ok: Runtime: 0:00:04.490991 2025-09-20 00:17:51.098456 | 2025-09-20 00:17:51.098590 | TASK [Ensure pip temp directory exists in /var/tmp] 2025-09-20 00:17:51.903391 | controller | changed 2025-09-20 00:17:51.915604 | 2025-09-20 00:17:51.915835 | TASK [Install ansible into virtualenv] 2025-09-20 00:17:53.617723 | controller | Processing ./src/github.com/ansible/ansible 2025-09-20 00:17:53.619811 | controller | Preparing metadata (setup.py): started 2025-09-20 00:17:54.714155 | controller | Preparing metadata (setup.py): finished with status 'done' 2025-09-20 00:17:54.811426 | controller | Collecting jinja2 (from ansible-core==2.11.12.post0) 2025-09-20 00:17:54.860876 | controller | Downloading jinja2-3.1.6-py3-none-any.whl.metadata (2.9 kB) 2025-09-20 00:17:55.018926 | controller | Collecting PyYAML (from ansible-core==2.11.12.post0) 2025-09-20 00:17:55.037576 | controller | Downloading PyYAML-6.0.2-cp39-cp39-manylinux_2_17_x86_64.manylinux2014_x86_64.whl.metadata (2.1 kB) 2025-09-20 00:17:55.699320 | controller | Collecting cryptography (from ansible-core==2.11.12.post0) 2025-09-20 00:17:55.712013 | controller | Downloading cryptography-46.0.1-cp38-abi3-manylinux_2_34_x86_64.whl.metadata (5.7 kB) 2025-09-20 00:17:55.737148 | controller | Requirement already satisfied: packaging in ./venv/lib/python3.9/site-packages (from ansible-core==2.11.12.post0) (25.0) 2025-09-20 00:17:55.767372 | controller | Collecting resolvelib<0.6.0,>=0.5.3 (from ansible-core==2.11.12.post0) 2025-09-20 00:17:55.788194 | controller | Downloading resolvelib-0.5.4-py2.py3-none-any.whl.metadata (3.7 kB) 2025-09-20 00:17:56.068516 | controller | Collecting cffi>=2.0.0 (from cryptography->ansible-core==2.11.12.post0) 2025-09-20 00:17:56.081206 | controller | Downloading cffi-2.0.0-cp39-cp39-manylinux2014_x86_64.manylinux_2_17_x86_64.whl.metadata (2.6 kB) 2025-09-20 00:17:56.091565 | 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-20 00:17:56.113978 | controller | Collecting pycparser (from cffi>=2.0.0->cryptography->ansible-core==2.11.12.post0) 2025-09-20 00:17:56.126566 | controller | Downloading pycparser-2.23-py3-none-any.whl.metadata (993 bytes) 2025-09-20 00:17:56.261193 | controller | Collecting MarkupSafe>=2.0 (from jinja2->ansible-core==2.11.12.post0) 2025-09-20 00:17:56.273816 | controller | Downloading MarkupSafe-3.0.2-cp39-cp39-manylinux_2_17_x86_64.manylinux2014_x86_64.whl.metadata (4.0 kB) 2025-09-20 00:17:56.304898 | controller | Downloading resolvelib-0.5.4-py2.py3-none-any.whl (12 kB) 2025-09-20 00:17:56.325657 | controller | Downloading cryptography-46.0.1-cp38-abi3-manylinux_2_34_x86_64.whl (4.6 MB) 2025-09-20 00:17:56.424244 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 4.6/4.6 MB 70.1 MB/s 0:00:00 2025-09-20 00:17:56.437261 | controller | Downloading cffi-2.0.0-cp39-cp39-manylinux2014_x86_64.manylinux_2_17_x86_64.whl (216 kB) 2025-09-20 00:17:56.459775 | controller | Downloading jinja2-3.1.6-py3-none-any.whl (134 kB) 2025-09-20 00:17:56.482041 | controller | Downloading MarkupSafe-3.0.2-cp39-cp39-manylinux_2_17_x86_64.manylinux2014_x86_64.whl (20 kB) 2025-09-20 00:17:56.502696 | controller | Downloading pycparser-2.23-py3-none-any.whl (118 kB) 2025-09-20 00:17:56.525127 | controller | Downloading PyYAML-6.0.2-cp39-cp39-manylinux_2_17_x86_64.manylinux2014_x86_64.whl (737 kB) 2025-09-20 00:17:56.543575 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 737.4/737.4 kB 36.1 MB/s 0:00:00 2025-09-20 00:17:56.588110 | controller | Building wheels for collected packages: ansible-core 2025-09-20 00:17:56.589446 | 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-20 00:17:56.589770 | controller | Building wheel for ansible-core (setup.py): started 2025-09-20 00:17:59.003223 | controller | Building wheel for ansible-core (setup.py): finished with status 'done' 2025-09-20 00:17:59.010695 | controller | Created wheel for ansible-core: filename=ansible_core-2.11.12.post0-py3-none-any.whl size=1961168 sha256=4e724f5fab2b8fb0628163aca75475c86aae28598007d940dc91cfde5314026b 2025-09-20 00:17:59.011246 | controller | Stored in directory: /var/tmp/ansible-test-pip/pip-ephem-wheel-cache-rfi7gid8/wheels/e8/fd/e9/6250c469bad44a7c6855190836e9d22c0bb11cb641caf57fbe 2025-09-20 00:17:59.028313 | controller | Successfully built ansible-core 2025-09-20 00:17:59.098555 | controller | Installing collected packages: resolvelib, PyYAML, pycparser, MarkupSafe, jinja2, cffi, cryptography, ansible-core 2025-09-20 00:18:03.088050 | controller | 2025-09-20 00:18:03.097854 | 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-20 00:18:03.634722 | controller | ok: Runtime: 0:00:10.870061 2025-09-20 00:18:03.643813 | 2025-09-20 00:18:03.643994 | TASK [Setup download-artifact-fork role] 2025-09-20 00:18:03.666638 | controller | ok 2025-09-20 00:18:03.694224 | 2025-09-20 00:18:03.694405 | TASK [download-artifact-fork : Parse inventory response] 2025-09-20 00:18:03.727263 | controller | ok 2025-09-20 00:18:03.735176 | 2025-09-20 00:18:03.735342 | TASK [download-artifact-fork : Fetch info from Zuul API] 2025-09-20 00:18:03.762341 | controller | skipping: Conditional result was False 2025-09-20 00:18:03.770823 | 2025-09-20 00:18:03.770964 | TASK [download-artifact-fork : Parse build response] 2025-09-20 00:18:03.798063 | controller | skipping: Conditional result was False 2025-09-20 00:18:03.807947 | 2025-09-20 00:18:03.808135 | TASK [download-artifact-fork : Ensure artifacts directory exists] 2025-09-20 00:18:04.329577 | controller | changed 2025-09-20 00:18:04.344082 | 2025-09-20 00:18:04.344258 | LOOP [download-artifact-fork : Download archive by type] 2025-09-20 00:18:04.375401 | controller | skipping: Conditional result was False 2025-09-20 00:18:04.375750 | 2025-09-20 00:18:05.841100 | controller | changed: OK (20219 bytes) 2025-09-20 00:18:05.856300 | 2025-09-20 00:18:05.856412 | TASK [deploy-artifacts : define __collections] 2025-09-20 00:18:05.895566 | controller | ok 2025-09-20 00:18:05.903949 | 2025-09-20 00:18:05.904055 | LOOP [deploy-artifacts : Create list of collections] 2025-09-20 00:18:05.945102 | controller | skipping: Conditional result was False 2025-09-20 00:18:05.957514 | controller | ok: 2025-09-20 00:18:05.957734 | controller | { 2025-09-20 00:18:05.957780 | controller | "branch": "main", 2025-09-20 00:18:05.957808 | controller | "job": "build-ansible-collection", 2025-09-20 00:18:05.957831 | controller | "metadata": { 2025-09-20 00:18:05.957851 | controller | "type": "ansible_collection", 2025-09-20 00:18:05.957872 | controller | "version": "5.0.0" 2025-09-20 00:18:05.957896 | controller | }, 2025-09-20 00:18:05.957919 | controller | "name": "ansible.network", 2025-09-20 00:18:05.957939 | controller | "project": "ansible-collections/ansible.network", 2025-09-20 00:18:05.957960 | controller | "url": "https://54b8309db3309bbb4f03-5041b23b6710ef00620e740382cd7f62.ssl.cf2.rackcdn.com/ansible/026039551698444cb3f96d7ddc16fa47/artifacts/ansible-network-5.0.0.tar.gz" 2025-09-20 00:18:05.957992 | controller | } 2025-09-20 00:18:05.967807 | 2025-09-20 00:18:05.967979 | TASK [deploy-artifacts : Install require-project collection using ansible-galaxy] 2025-09-20 00:18:07.217670 | controller | Starting galaxy collection install process 2025-09-20 00:18:07.218164 | controller | Process install dependency map 2025-09-20 00:18:08.875097 | controller | [WARNING]: Skipping Galaxy server https://galaxy.ansible.com/api/. Got an 2025-09-20 00:18:08.875373 | controller | unexpected error when getting available versions of collection 2025-09-20 00:18:08.875413 | controller | ansible.netcommon: '/api/v3/plugin/ansible/content/published/collections/index/ 2025-09-20 00:18:08.875424 | controller | ansible/netcommon/versions/' 2025-09-20 00:18:08.875437 | controller | ERROR! Unexpected Exception, this is probably a bug: '/api/v3/plugin/ansible/content/published/collections/index/ansible/netcommon/versions/' 2025-09-20 00:18:08.958126 | controller | to see the full traceback, use -vvv 2025-09-20 00:18:09.151331 | controller | ERROR 2025-09-20 00:18:09.151603 | controller | { 2025-09-20 00:18:09.151633 | controller | "delta": "0:00:02.538235", 2025-09-20 00:18:09.151910 | controller | "end": "2025-09-20 00:18:08.958547", 2025-09-20 00:18:09.151936 | controller | "msg": "non-zero return code", 2025-09-20 00:18:09.151954 | controller | "rc": 250, 2025-09-20 00:18:09.151971 | controller | "start": "2025-09-20 00:18:06.420312" 2025-09-20 00:18:09.151987 | controller | } failure 2025-09-20 00:18:09.154604 | 2025-09-20 00:18:09.154660 | PLAY RECAP 2025-09-20 00:18:09.154741 | controller | ok: 44 changed: 24 unreachable: 0 failed: 1 skipped: 17 rescued: 0 ignored: 0 2025-09-20 00:18:09.154766 | 2025-09-20 00:18:09.305901 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master] 2025-09-20 00:18:09.307408 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2025-09-20 00:18:09.957794 | 2025-09-20 00:18:09.957952 | PLAY [all] 2025-09-20 00:18:09.982359 | 2025-09-20 00:18:09.982531 | TASK [fetch-subunit-output : Find stestr or testr executable] 2025-09-20 00:18:11.461814 | controller | changed: non-zero return code 2025-09-20 00:18:11.474757 | 2025-09-20 00:18:11.474918 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2025-09-20 00:18:11.491807 | controller | skipping: Conditional result was False 2025-09-20 00:18:11.505166 | 2025-09-20 00:18:11.505347 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2025-09-20 00:18:11.547509 | 2025-09-20 00:18:11.547857 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2025-09-20 00:18:11.611674 | 2025-09-20 00:18:11.612241 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2025-09-20 00:18:11.644155 | controller | skipping: Conditional result was False 2025-09-20 00:18:11.660223 | 2025-09-20 00:18:11.660447 | LOOP [fetch-subunit-output : Generate subunit file] 2025-09-20 00:18:11.687951 | 2025-09-20 00:18:11.688193 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2025-09-20 00:18:11.713598 | controller | skipping: Conditional result was False 2025-09-20 00:18:11.722381 | 2025-09-20 00:18:11.722521 | TASK [fetch-subunit-output : Remove the temporary file] 2025-09-20 00:18:11.749611 | controller | skipping: Conditional result was False 2025-09-20 00:18:11.766393 | 2025-09-20 00:18:11.766807 | TASK [fetch-subunit-output : Process and fetch subunit results] 2025-09-20 00:18:11.794458 | controller | skipping: Conditional result was False 2025-09-20 00:18:11.832296 | 2025-09-20 00:18:11.832567 | PLAY RECAP 2025-09-20 00:18:11.832635 | controller | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2025-09-20 00:18:11.832693 | 2025-09-20 00:18:11.959799 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2025-09-20 00:18:11.960771 | POST-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/post.yaml@master] 2025-09-20 00:18:12.594163 | 2025-09-20 00:18:12.594307 | PLAY [all:!appliance*] 2025-09-20 00:18:12.617459 | 2025-09-20 00:18:12.617618 | TASK [unregister the node] 2025-09-20 00:18:12.750900 | controller | skipping: Conditional result was False 2025-09-20 00:18:12.757706 | 2025-09-20 00:18:12.757796 | TASK [include_role : fetch-output] 2025-09-20 00:18:12.798072 | controller | ok 2025-09-20 00:18:12.817179 | 2025-09-20 00:18:12.817257 | TASK [fetch-output : Set log path for multiple nodes] 2025-09-20 00:18:12.862937 | controller | skipping: Conditional result was False 2025-09-20 00:18:12.871518 | 2025-09-20 00:18:12.871619 | TASK [fetch-output : Set log path for single node] 2025-09-20 00:18:12.905236 | controller | ok 2025-09-20 00:18:12.912818 | 2025-09-20 00:18:12.912910 | LOOP [fetch-output : Ensure local output dirs] 2025-09-20 00:18:13.357730 | controller -> localhost | ok: "/var/lib/zuul/builds/34a1ad9bb58c42e193db336dbeb84499/work/logs" 2025-09-20 00:18:13.605387 | controller -> localhost | changed: "/var/lib/zuul/builds/34a1ad9bb58c42e193db336dbeb84499/work/artifacts" 2025-09-20 00:18:13.866353 | controller -> localhost | changed: "/var/lib/zuul/builds/34a1ad9bb58c42e193db336dbeb84499/work/docs" 2025-09-20 00:18:13.889967 | 2025-09-20 00:18:13.890141 | LOOP [fetch-output : Collect logs, artifacts and docs] 2025-09-20 00:18:15.120711 | controller | changed: 2025-09-20 00:18:15.120974 | controller | .d..t...... ./ 2025-09-20 00:18:15.121012 | controller | cd+++++++++ controller/ 2025-09-20 00:18:15.121056 | controller | changed: All items complete 2025-09-20 00:18:15.121080 | 2025-09-20 00:18:16.174688 | controller | changed: .d..t...... ./ 2025-09-20 00:18:17.192652 | controller | changed: .d..t...... ./ 2025-09-20 00:18:17.228801 | 2025-09-20 00:18:17.229013 | TASK [include_role : fetch-output-openshift] 2025-09-20 00:18:17.246891 | controller | skipping: Conditional result was False 2025-09-20 00:18:17.260265 | 2025-09-20 00:18:17.260441 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2025-09-20 00:18:17.289417 | controller | skipping: Conditional result was False 2025-09-20 00:18:17.300414 | controller | skipping: Conditional result was False 2025-09-20 00:18:17.366826 | 2025-09-20 00:18:17.366981 | PLAY [localhost] 2025-09-20 00:18:17.393384 | 2025-09-20 00:18:17.393749 | TASK [Run Zuul manifest role] 2025-09-20 00:18:17.414970 | localhost | ok 2025-09-20 00:18:17.432813 | 2025-09-20 00:18:17.432921 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-09-20 00:18:17.839252 | localhost | changed 2025-09-20 00:18:17.845425 | 2025-09-20 00:18:17.845700 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-09-20 00:18:17.883855 | localhost | ok 2025-09-20 00:18:17.892773 | 2025-09-20 00:18:17.892844 | TASK [Set zuul-log-path fact] 2025-09-20 00:18:17.913577 | localhost | ok 2025-09-20 00:18:17.934947 | 2025-09-20 00:18:17.935061 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-09-20 00:18:17.965280 | localhost | ok 2025-09-20 00:18:17.974861 | 2025-09-20 00:18:17.974956 | LOOP [Run upload-logs-swift role] 2025-09-20 00:18:18.020476 | localhost | Output suppressed because no_log was given 2025-09-20 00:18:18.058659 | 2025-09-20 00:18:18.058826 | TASK [Set zuul-log-path fact] 2025-09-20 00:18:18.083473 | localhost | skipping: Conditional result was False 2025-09-20 00:18:18.090990 | 2025-09-20 00:18:18.091088 | TASK [upload-logs-swift : Ensure logs are readable before uploading] 2025-09-20 00:18:18.493189 | localhost -> localhost | ok: Runtime: 0:00:00.006624 2025-09-20 00:18:18.499320 | 2025-09-20 00:18:18.499409 | TASK [upload-logs-swift : Upload logs to swift]