2025-10-01 00:12:09.804656 | Job console starting... 2025-10-01 00:12:09.813898 | Updating repositories 2025-10-01 00:12:09.960134 | Preparing job workspace 2025-10-01 00:12:13.863234 | Running Ansible setup... 2025-10-01 00:12:20.420123 | PRE-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master] 2025-10-01 00:12:21.164590 | 2025-10-01 00:12:21.164861 | PLAY [localhost] 2025-10-01 00:12:21.176856 | 2025-10-01 00:12:21.177007 | TASK [Gathering Facts] 2025-10-01 00:12:22.355881 | localhost | ok 2025-10-01 00:12:22.376359 | 2025-10-01 00:12:22.376565 | TASK [Setup log path fact] 2025-10-01 00:12:22.399425 | localhost | ok 2025-10-01 00:12:22.419581 | 2025-10-01 00:12:22.420026 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-10-01 00:12:22.462619 | localhost | ok 2025-10-01 00:12:22.476026 | 2025-10-01 00:12:22.476348 | TASK [emit-job-header : Print job information] 2025-10-01 00:12:22.517667 | # Job Information 2025-10-01 00:12:22.517901 | Ansible Version: 2.15.12 2025-10-01 00:12:22.517947 | Job: ansible-test-sanity-docker-stable-2.11 2025-10-01 00:12:22.517978 | Pipeline: periodic 2025-10-01 00:12:22.518006 | Executor: ze03.softwarefactory-project.io 2025-10-01 00:12:22.518036 | Triggered by: https://github.com/ansible-collections/ansible.network 2025-10-01 00:12:22.518069 | Log URL (when completed): https://ansible.softwarefactory-project.io/logs/9f7/ansible/9f7f345a5f5045b892b83a2a68283f69/ 2025-10-01 00:12:22.518102 | Event ID: 2d247e7dd365484888695175254b0a86 2025-10-01 00:12:22.523969 | 2025-10-01 00:12:22.524090 | LOOP [emit-job-header : Print node information] 2025-10-01 00:12:22.655408 | localhost | ok: 2025-10-01 00:12:22.655766 | localhost | # Node Information 2025-10-01 00:12:22.655800 | localhost | Inventory Hostname: controller 2025-10-01 00:12:22.655825 | localhost | Hostname: ip-172-16-153-128 2025-10-01 00:12:22.655845 | localhost | Username: zuul-worker 2025-10-01 00:12:22.655866 | localhost | Distro: Fedora 37 2025-10-01 00:12:22.655884 | localhost | Provider: ansible-us-east-2 2025-10-01 00:12:22.655901 | localhost | Region: us-east-2 2025-10-01 00:12:22.655918 | localhost | Label: ansible-fedora-37-1vcpu 2025-10-01 00:12:22.655934 | localhost | Product Name: t3.small 2025-10-01 00:12:22.655951 | localhost | Interface IP: 18.217.217.19 2025-10-01 00:12:22.674557 | 2025-10-01 00:12:22.674815 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-10-01 00:12:23.207640 | localhost -> localhost | changed 2025-10-01 00:12:23.215402 | 2025-10-01 00:12:23.215560 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-10-01 00:12:24.228681 | localhost -> localhost | changed 2025-10-01 00:12:24.260009 | 2025-10-01 00:12:24.260201 | PLAY [all:!appliance*] 2025-10-01 00:12:24.286107 | 2025-10-01 00:12:24.286257 | TASK [include_role : start-zuul-console] 2025-10-01 00:12:24.309506 | controller | ok 2025-10-01 00:12:24.330979 | 2025-10-01 00:12:24.331127 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-10-01 00:12:25.023089 | controller | ok 2025-10-01 00:12:25.050943 | 2025-10-01 00:12:25.051139 | TASK [use-our-mirror : Retrieve the IP address] 2025-10-01 00:12:26.608395 | controller | ok: Status code was 404 and not [200]: HTTP Error 404: Not Found 2025-10-01 00:12:26.615279 | 2025-10-01 00:12:26.615386 | TASK [use-our-mirror : Ensure the registry service is running on the mirror] 2025-10-01 00:12:26.747139 | controller | skipping: Conditional result was False 2025-10-01 00:12:26.753526 | 2025-10-01 00:12:26.753634 | TASK [use-our-mirror : Write the IP in /etc/hosts] 2025-10-01 00:12:26.777777 | controller | skipping: Conditional result was False 2025-10-01 00:12:26.790435 | 2025-10-01 00:12:26.790588 | TASK [use-our-mirror : Create the podman configuration directory] 2025-10-01 00:12:26.827530 | controller | skipping: Conditional result was False 2025-10-01 00:12:26.834426 | 2025-10-01 00:12:26.834564 | TASK [use-our-mirror : Copy the podman configuration] 2025-10-01 00:12:26.868765 | controller | skipping: Conditional result was False 2025-10-01 00:12:26.875252 | 2025-10-01 00:12:26.875361 | TASK [use-our-mirror : Copy the Yum configuration (main)] 2025-10-01 00:12:26.899541 | controller | skipping: Conditional result was False 2025-10-01 00:12:26.906082 | 2025-10-01 00:12:26.906200 | TASK [use-our-mirror : Copy the Yum configuration (update)] 2025-10-01 00:12:26.930626 | controller | skipping: Conditional result was False 2025-10-01 00:12:26.942974 | 2025-10-01 00:12:26.943095 | TASK [Disable Fedora Modular] 2025-10-01 00:12:27.681558 | controller | changed 2025-10-01 00:12:27.688170 | 2025-10-01 00:12:27.688265 | TASK [Enable EPEL] 2025-10-01 00:12:27.712842 | controller | skipping: Conditional result was False 2025-10-01 00:12:27.719579 | 2025-10-01 00:12:27.719713 | TASK [Register the RHEL node] 2025-10-01 00:12:27.860666 | 2025-10-01 00:12:27.860906 | TASK [Show the subscription-manager status] 2025-10-01 00:12:28.071426 | controller | skipping: Conditional result was False 2025-10-01 00:12:28.081158 | 2025-10-01 00:12:28.081307 | TASK [Enable EPEL on RHEL] 2025-10-01 00:12:28.243542 | controller | skipping: Conditional result was False 2025-10-01 00:12:28.252385 | 2025-10-01 00:12:28.252531 | TASK [Install git and tox] 2025-10-01 00:13:55.222879 | controller | changed 2025-10-01 00:13:55.239564 | 2025-10-01 00:13:55.239796 | TASK [include_role : prepare-workspace] 2025-10-01 00:13:55.286834 | controller | ok 2025-10-01 00:13:55.321675 | 2025-10-01 00:13:55.321854 | TASK [prepare-workspace : Start zuul_console daemon.] 2025-10-01 00:13:55.772529 | controller | ok 2025-10-01 00:13:55.780492 | 2025-10-01 00:13:55.780578 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2025-10-01 00:14:08.888935 | controller | Output suppressed because no_log was given 2025-10-01 00:14:08.913391 | 2025-10-01 00:14:08.913586 | TASK [include_role : prepare-workspace-openshift] 2025-10-01 00:14:08.939939 | controller | skipping: Conditional result was False 2025-10-01 00:14:08.984149 | 2025-10-01 00:14:08.984279 | PLAY [all:!appliance] 2025-10-01 00:14:09.008026 | 2025-10-01 00:14:09.008186 | TASK [Run add-build-sshkey role (RSA)] 2025-10-01 00:14:09.042212 | controller | ok 2025-10-01 00:14:09.068556 | 2025-10-01 00:14:09.068707 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-10-01 00:14:09.429201 | controller -> localhost | ok 2025-10-01 00:14:09.435096 | 2025-10-01 00:14:09.435203 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-10-01 00:14:09.468937 | controller | ok 2025-10-01 00:14:09.492785 | controller | included: /var/lib/zuul/builds/9f7f345a5f5045b892b83a2a68283f69/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-10-01 00:14:09.503369 | 2025-10-01 00:14:09.503524 | TASK [add-build-sshkey : Create Temp SSH key] 2025-10-01 00:14:09.994368 | controller -> localhost | Generating public/private rsa key pair. 2025-10-01 00:14:09.994694 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/9f7f345a5f5045b892b83a2a68283f69/work/9f7f345a5f5045b892b83a2a68283f69_id_rsa. 2025-10-01 00:14:09.994784 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/9f7f345a5f5045b892b83a2a68283f69/work/9f7f345a5f5045b892b83a2a68283f69_id_rsa.pub. 2025-10-01 00:14:09.994829 | controller -> localhost | The key fingerprint is: 2025-10-01 00:14:09.994871 | controller -> localhost | SHA256:Es69ecJx48watT2ptn4QYxpmZQSejLwczvVt33WaifQ zuul-build-sshkey 2025-10-01 00:14:09.994910 | controller -> localhost | The key's randomart image is: 2025-10-01 00:14:09.994946 | controller -> localhost | +---[RSA 2048]----+ 2025-10-01 00:14:09.994985 | controller -> localhost | | .o. | 2025-10-01 00:14:09.995022 | controller -> localhost | | . + .o | 2025-10-01 00:14:09.995062 | controller -> localhost | | = =o | 2025-10-01 00:14:09.995099 | controller -> localhost | | * *+.+. | 2025-10-01 00:14:09.995135 | controller -> localhost | | OoS+=o+ o| 2025-10-01 00:14:09.995189 | controller -> localhost | | o.X.* = =o| 2025-10-01 00:14:09.995238 | controller -> localhost | | * *.= E .| 2025-10-01 00:14:09.995279 | controller -> localhost | | =.... | 2025-10-01 00:14:09.995314 | controller -> localhost | | .o+o | 2025-10-01 00:14:09.995357 | controller -> localhost | +----[SHA256]-----+ 2025-10-01 00:14:09.995441 | controller -> localhost | ok: Runtime: 0:00:00.027133 2025-10-01 00:14:10.011139 | 2025-10-01 00:14:10.011265 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-10-01 00:14:10.032777 | controller | ok 2025-10-01 00:14:10.042673 | controller | included: /var/lib/zuul/builds/9f7f345a5f5045b892b83a2a68283f69/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-10-01 00:14:10.052215 | 2025-10-01 00:14:10.052337 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-10-01 00:14:10.076536 | controller | skipping: Conditional result was False 2025-10-01 00:14:10.083302 | 2025-10-01 00:14:10.083440 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-10-01 00:14:10.664257 | controller | changed 2025-10-01 00:14:10.679000 | 2025-10-01 00:14:10.679173 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-10-01 00:14:11.024667 | controller | ok 2025-10-01 00:14:11.031912 | 2025-10-01 00:14:11.031991 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-10-01 00:14:12.350655 | controller | changed 2025-10-01 00:14:12.366504 | 2025-10-01 00:14:12.366697 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-10-01 00:14:13.670641 | controller | changed 2025-10-01 00:14:13.679151 | 2025-10-01 00:14:13.679253 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-10-01 00:14:13.714401 | controller | skipping: Conditional result was False 2025-10-01 00:14:13.723410 | 2025-10-01 00:14:13.723539 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-10-01 00:14:14.106273 | controller -> localhost | changed 2025-10-01 00:14:14.118805 | 2025-10-01 00:14:14.118929 | TASK [add-build-sshkey : Add back temp key] 2025-10-01 00:14:14.428256 | controller -> localhost | Identity added: /var/lib/zuul/builds/9f7f345a5f5045b892b83a2a68283f69/work/9f7f345a5f5045b892b83a2a68283f69_id_rsa (zuul-build-sshkey) 2025-10-01 00:14:14.428575 | controller -> localhost | ok: Runtime: 0:00:00.011240 2025-10-01 00:14:14.438690 | 2025-10-01 00:14:14.438854 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-10-01 00:14:14.878970 | controller | ok 2025-10-01 00:14:14.884605 | 2025-10-01 00:14:14.884719 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-10-01 00:14:14.922002 | controller | skipping: Conditional result was False 2025-10-01 00:14:14.936027 | 2025-10-01 00:14:14.936148 | TASK [Run add-build-sshkey role (ECDSA)] 2025-10-01 00:14:14.955715 | controller | ok 2025-10-01 00:14:14.970957 | 2025-10-01 00:14:14.971049 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-10-01 00:14:15.218849 | controller -> localhost | ok 2025-10-01 00:14:15.227270 | 2025-10-01 00:14:15.227371 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-10-01 00:14:15.249594 | controller | ok 2025-10-01 00:14:15.267882 | controller | included: /var/lib/zuul/builds/9f7f345a5f5045b892b83a2a68283f69/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-10-01 00:14:15.276820 | 2025-10-01 00:14:15.276910 | TASK [add-build-sshkey : Create Temp SSH key] 2025-10-01 00:14:15.560079 | controller -> localhost | Generating public/private ecdsa key pair. 2025-10-01 00:14:15.560312 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/9f7f345a5f5045b892b83a2a68283f69/work/9f7f345a5f5045b892b83a2a68283f69_id_ecdsa. 2025-10-01 00:14:15.560343 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/9f7f345a5f5045b892b83a2a68283f69/work/9f7f345a5f5045b892b83a2a68283f69_id_ecdsa.pub. 2025-10-01 00:14:15.560370 | controller -> localhost | The key fingerprint is: 2025-10-01 00:14:15.560391 | controller -> localhost | SHA256:GjDt917YnmYFU3wKkas2eMynIUxcb6XInEJBOF1lbko zuul-build-sshkey 2025-10-01 00:14:15.560411 | controller -> localhost | The key's randomart image is: 2025-10-01 00:14:15.560429 | controller -> localhost | +---[ECDSA 521]---+ 2025-10-01 00:14:15.560447 | controller -> localhost | | +oo..++ | 2025-10-01 00:14:15.560464 | controller -> localhost | | .o o .oo + .| 2025-10-01 00:14:15.560482 | controller -> localhost | | o .+ +E+o* o | 2025-10-01 00:14:15.560500 | controller -> localhost | | + +.=oB . | 2025-10-01 00:14:15.560517 | controller -> localhost | | ooS=.o o | 2025-10-01 00:14:15.560535 | controller -> localhost | | ++.Oo. . | 2025-10-01 00:14:15.560553 | controller -> localhost | | . +o=o. | 2025-10-01 00:14:15.560612 | controller -> localhost | | ..oo. | 2025-10-01 00:14:15.560638 | controller -> localhost | | .oo | 2025-10-01 00:14:15.560656 | controller -> localhost | +----[SHA256]-----+ 2025-10-01 00:14:15.560713 | controller -> localhost | ok: Runtime: 0:00:00.008254 2025-10-01 00:14:15.567421 | 2025-10-01 00:14:15.567488 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-10-01 00:14:15.587971 | controller | ok 2025-10-01 00:14:15.595386 | controller | included: /var/lib/zuul/builds/9f7f345a5f5045b892b83a2a68283f69/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-10-01 00:14:15.603893 | 2025-10-01 00:14:15.603956 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-10-01 00:14:15.628523 | controller | skipping: Conditional result was False 2025-10-01 00:14:15.641344 | 2025-10-01 00:14:15.641506 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-10-01 00:14:16.106030 | controller | changed 2025-10-01 00:14:16.120102 | 2025-10-01 00:14:16.120311 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-10-01 00:14:16.468999 | controller | ok 2025-10-01 00:14:16.484853 | 2025-10-01 00:14:16.485065 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-10-01 00:14:17.764854 | controller | changed 2025-10-01 00:14:17.780825 | 2025-10-01 00:14:17.781014 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-10-01 00:14:19.095831 | controller | changed 2025-10-01 00:14:19.102461 | 2025-10-01 00:14:19.102587 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-10-01 00:14:19.117597 | controller | skipping: Conditional result was False 2025-10-01 00:14:19.126905 | 2025-10-01 00:14:19.127048 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-10-01 00:14:19.385518 | controller -> localhost | changed 2025-10-01 00:14:19.410824 | 2025-10-01 00:14:19.410967 | TASK [add-build-sshkey : Add back temp key] 2025-10-01 00:14:19.723832 | controller -> localhost | Identity added: /var/lib/zuul/builds/9f7f345a5f5045b892b83a2a68283f69/work/9f7f345a5f5045b892b83a2a68283f69_id_ecdsa (zuul-build-sshkey) 2025-10-01 00:14:19.724141 | controller -> localhost | ok: Runtime: 0:00:00.016752 2025-10-01 00:14:19.735612 | 2025-10-01 00:14:19.735806 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-10-01 00:14:20.054783 | controller | ok 2025-10-01 00:14:20.061580 | 2025-10-01 00:14:20.061678 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-10-01 00:14:20.087315 | controller | skipping: Conditional result was False 2025-10-01 00:14:20.101874 | 2025-10-01 00:14:20.102014 | TASK [include_role : remove-zuul-sshkey] 2025-10-01 00:14:20.122532 | controller | skipping: Conditional result was False 2025-10-01 00:14:20.129983 | 2025-10-01 00:14:20.130101 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2025-10-01 00:14:20.442370 | controller | ok: "logs" 2025-10-01 00:14:20.443076 | controller | ok: All items complete 2025-10-01 00:14:20.443169 | 2025-10-01 00:14:20.711970 | controller | ok: "artifacts" 2025-10-01 00:14:20.990861 | controller | ok: "docs" 2025-10-01 00:14:21.032422 | 2025-10-01 00:14:21.032549 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2025-10-01 00:14:21.384823 | controller | changed: "logs" 2025-10-01 00:14:21.682138 | controller | changed: "artifacts" 2025-10-01 00:14:21.965101 | controller | changed: "docs" 2025-10-01 00:14:22.026856 | 2025-10-01 00:14:22.026982 | PLAY RECAP 2025-10-01 00:14:22.027045 | controller | ok: 30 changed: 16 unreachable: 0 failed: 0 skipped: 18 rescued: 0 ignored: 0 2025-10-01 00:14:22.027081 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-10-01 00:14:22.027106 | 2025-10-01 00:14:22.152507 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master] 2025-10-01 00:14:22.153426 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2025-10-01 00:14:22.726569 | 2025-10-01 00:14:22.726699 | PLAY [all] 2025-10-01 00:14:22.750697 | 2025-10-01 00:14:22.750900 | TASK [Install binary dependencies] 2025-10-01 00:14:22.810231 | controller | ok 2025-10-01 00:14:22.829200 | 2025-10-01 00:14:22.829323 | TASK [bindep : Include find tasks] 2025-10-01 00:14:22.860294 | controller | ok 2025-10-01 00:14:22.870959 | controller | included: /var/lib/zuul/builds/9f7f345a5f5045b892b83a2a68283f69/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find.yaml 2025-10-01 00:14:22.879783 | 2025-10-01 00:14:22.879910 | TASK [bindep : Look for bindep.txt] 2025-10-01 00:14:23.416495 | controller | ok 2025-10-01 00:14:23.430689 | 2025-10-01 00:14:23.430899 | TASK [bindep : Define bindep_file fact] 2025-10-01 00:14:23.448386 | controller | skipping: Conditional result was False 2025-10-01 00:14:23.455601 | 2025-10-01 00:14:23.455703 | TASK [bindep : Look for other-requirements.txt] 2025-10-01 00:14:23.740135 | controller | ok 2025-10-01 00:14:23.746209 | 2025-10-01 00:14:23.746301 | TASK [bindep : Define bindep_file fact] 2025-10-01 00:14:23.781033 | controller | skipping: Conditional result was False 2025-10-01 00:14:23.787187 | 2025-10-01 00:14:23.787273 | TASK [bindep : Look for bindep fallback file] 2025-10-01 00:14:23.811298 | controller | skipping: Conditional result was False 2025-10-01 00:14:23.818022 | 2025-10-01 00:14:23.818102 | TASK [bindep : Define bindep_file fact] 2025-10-01 00:14:23.842375 | controller | skipping: Conditional result was False 2025-10-01 00:14:23.848310 | 2025-10-01 00:14:23.848381 | TASK [bindep : Include bindep tasks] 2025-10-01 00:14:23.872286 | controller | skipping: Conditional result was False 2025-10-01 00:14:23.883533 | 2025-10-01 00:14:23.883664 | TASK [bindep : Include install tasks] 2025-10-01 00:14:23.910471 | controller | skipping: Conditional result was False 2025-10-01 00:14:23.920320 | 2025-10-01 00:14:23.920468 | LOOP [bindep : Include package tasks] 2025-10-01 00:14:23.982848 | 2025-10-01 00:14:23.983068 | TASK [Run test-setup role] 2025-10-01 00:14:24.010491 | controller | ok 2025-10-01 00:14:24.037855 | 2025-10-01 00:14:24.037969 | TASK [test-setup : Check if project's tools/test-setup.sh exists] 2025-10-01 00:14:24.324768 | controller | ok 2025-10-01 00:14:24.333746 | 2025-10-01 00:14:24.333861 | TASK [test-setup : Run tools/test-setup.sh] 2025-10-01 00:14:24.481830 | controller | skipping: Conditional result was False 2025-10-01 00:14:24.536108 | 2025-10-01 00:14:24.536223 | PLAY RECAP 2025-10-01 00:14:24.536279 | controller | ok: 4 changed: 0 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2025-10-01 00:14:24.536306 | 2025-10-01 00:14:24.666574 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2025-10-01 00:14:24.667469 | PRE-RUN START: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master] 2025-10-01 00:14:25.241468 | 2025-10-01 00:14:25.241638 | PLAY [controller] 2025-10-01 00:14:25.262308 | 2025-10-01 00:14:25.262438 | TASK [Create the /root directory] 2025-10-01 00:14:26.169971 | controller | ok 2025-10-01 00:14:26.175814 | 2025-10-01 00:14:26.175923 | TASK [Install glibc-langpack-en] 2025-10-01 00:14:34.319521 | controller | ok: Nothing to do 2025-10-01 00:14:34.341511 | 2025-10-01 00:14:34.341643 | TASK [Ensure controller directory exists] 2025-10-01 00:14:34.748145 | controller | changed 2025-10-01 00:14:34.763640 | 2025-10-01 00:14:34.763894 | TASK [Install container runtime] 2025-10-01 00:14:34.825340 | controller | ok 2025-10-01 00:14:34.886932 | 2025-10-01 00:14:34.887072 | LOOP [ensure-docker : Gather variables for each operating system] 2025-10-01 00:14:34.921587 | controller | ok: "/var/lib/zuul/builds/9f7f345a5f5045b892b83a2a68283f69/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/vars/fedora.yaml" 2025-10-01 00:14:34.931703 | 2025-10-01 00:14:34.931879 | TASK [ensure-docker : Sanity check for distro selection] 2025-10-01 00:14:34.960581 | controller | skipping: Conditional result was False 2025-10-01 00:14:34.968916 | 2025-10-01 00:14:34.969002 | TASK [ensure-docker : Sanity check for distro install] 2025-10-01 00:14:35.008940 | controller | skipping: Conditional result was False 2025-10-01 00:14:35.015294 | 2025-10-01 00:14:35.015377 | TASK [ensure-docker : Sanity check for upstream install] 2025-10-01 00:14:35.039619 | controller | skipping: Conditional result was False 2025-10-01 00:14:35.046966 | 2025-10-01 00:14:35.047035 | TASK [ensure-docker : Sanity check for upstream install mirrors] 2025-10-01 00:14:35.072504 | controller | skipping: Conditional result was False 2025-10-01 00:14:35.083341 | 2025-10-01 00:14:35.083437 | TASK [ensure-docker : Include tasks for docker install] 2025-10-01 00:14:35.126117 | controller | ok 2025-10-01 00:14:35.144355 | controller | included: /var/lib/zuul/builds/9f7f345a5f5045b892b83a2a68283f69/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/tasks/docker-upstream.yaml 2025-10-01 00:14:35.154276 | 2025-10-01 00:14:35.154351 | TASK [ensure-docker : Not Implemented] 2025-10-01 00:14:35.168960 | controller | skipping: Conditional result was False 2025-10-01 00:14:35.175375 | 2025-10-01 00:14:35.175439 | TASK [Set up docker mirrors] 2025-10-01 00:14:35.194621 | controller | ok 2025-10-01 00:14:35.215646 | 2025-10-01 00:14:35.215780 | TASK [use-docker-mirror : Set docker_mirror fact from mirror_info] 2025-10-01 00:14:35.240977 | controller | skipping: Conditional result was False 2025-10-01 00:14:35.247141 | 2025-10-01 00:14:35.247217 | TASK [use-docker-mirror : Set docker_mirror fact from zuul_site_mirror_info] 2025-10-01 00:14:35.271158 | controller | skipping: Conditional result was False 2025-10-01 00:14:35.277106 | 2025-10-01 00:14:35.277176 | TASK [use-docker-mirror : Set docker_mirror fact] 2025-10-01 00:14:35.301171 | controller | skipping: Conditional result was False 2025-10-01 00:14:35.307479 | 2025-10-01 00:14:35.307553 | TASK [use-docker-mirror : Set up docker mirrors] 2025-10-01 00:14:35.332218 | controller | skipping: Conditional result was False 2025-10-01 00:14:35.345574 | 2025-10-01 00:14:35.345765 | TASK [Add all repositories] 2025-10-01 00:14:35.368397 | controller | ok 2025-10-01 00:14:35.423406 | 2025-10-01 00:14:35.423538 | LOOP [ensure-package-repositories : Install repositories] 2025-10-01 00:14:35.449781 | controller | ok: "/var/lib/zuul/builds/9f7f345a5f5045b892b83a2a68283f69/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-package-repositories/tasks/RedHat.yaml" 2025-10-01 00:14:35.449957 | controller | ok: All items complete 2025-10-01 00:14:35.449984 | 2025-10-01 00:14:35.459211 | controller | included: /var/lib/zuul/builds/9f7f345a5f5045b892b83a2a68283f69/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-package-repositories/tasks/RedHat.yaml 2025-10-01 00:14:35.465392 | 2025-10-01 00:14:35.465455 | TASK [ensure-package-repositories : Create GPG key temp directory] 2025-10-01 00:14:35.966077 | controller | changed 2025-10-01 00:14:35.974566 | 2025-10-01 00:14:35.974694 | LOOP [ensure-package-repositories : Upload GPG keys] 2025-10-01 00:14:37.566702 | controller | changed: 2025-10-01 00:14:37.566999 | controller | { 2025-10-01 00:14:37.567051 | 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-10-01 00:14:37.567092 | controller | } 2025-10-01 00:14:37.578710 | 2025-10-01 00:14:37.579251 | LOOP [ensure-package-repositories : Import GPG keys] 2025-10-01 00:14:38.333614 | controller | changed: 2025-10-01 00:14:38.333884 | controller | { 2025-10-01 00:14:38.333916 | 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-10-01 00:14:38.333945 | controller | } 2025-10-01 00:14:38.333991 | controller | changed: All items complete 2025-10-01 00:14:38.334014 | 2025-10-01 00:14:38.349928 | 2025-10-01 00:14:38.350211 | TASK [ensure-package-repositories : Remove GPG temp directory] 2025-10-01 00:14:38.718870 | controller | changed 2025-10-01 00:14:38.734963 | 2025-10-01 00:14:38.735158 | LOOP [ensure-package-repositories : Add repositories] 2025-10-01 00:14:39.218619 | controller | changed: 2025-10-01 00:14:39.218839 | controller | { 2025-10-01 00:14:39.218866 | controller | "baseurl": "https://download.docker.com/linux/fedora/$releasever/$basearch/stable", 2025-10-01 00:14:39.218886 | controller | "description": "Docker CE Stable - $basearch", 2025-10-01 00:14:39.218903 | controller | "gpgcheck": true, 2025-10-01 00:14:39.218919 | controller | "name": "docker-ce-stable" 2025-10-01 00:14:39.218935 | controller | } 2025-10-01 00:14:39.254623 | 2025-10-01 00:14:39.254886 | TASK [ensure-docker : Install pre-reqs] 2025-10-01 00:14:42.612917 | controller | ok: Nothing to do 2025-10-01 00:14:42.625897 | 2025-10-01 00:14:42.626056 | TASK [ensure-docker : Remove packages] 2025-10-01 00:14:44.786339 | controller | ok: Nothing to do 2025-10-01 00:14:44.794774 | 2025-10-01 00:14:44.794877 | TASK [ensure-docker : Install upstream docker using package] 2025-10-01 00:15:39.635549 | controller | changed 2025-10-01 00:15:39.644437 | 2025-10-01 00:15:39.644521 | TASK [ensure-docker : Setup docker] 2025-10-01 00:15:39.666599 | controller | ok 2025-10-01 00:15:39.683535 | controller | included: /var/lib/zuul/builds/9f7f345a5f5045b892b83a2a68283f69/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/tasks/docker-setup.yaml 2025-10-01 00:15:39.705238 | 2025-10-01 00:15:39.705454 | TASK [ensure-docker : Ensure "docker" group exists] 2025-10-01 00:15:40.331072 | controller | ok 2025-10-01 00:15:40.346683 | 2025-10-01 00:15:40.346924 | TASK [ensure-docker : Add user to docker group] 2025-10-01 00:15:41.047572 | controller | changed 2025-10-01 00:15:41.054399 | 2025-10-01 00:15:41.054493 | TASK [Update docker daemon proxy configuration] 2025-10-01 00:15:41.090303 | controller | skipping: Conditional result was False 2025-10-01 00:15:41.100784 | 2025-10-01 00:15:41.100938 | TASK [Lower default MTU] 2025-10-01 00:15:41.127788 | controller | skipping: Conditional result was False 2025-10-01 00:15:41.138523 | 2025-10-01 00:15:41.138658 | TASK [ensure-docker : Get the status of the docket.socket unit] 2025-10-01 00:15:41.659579 | controller | inactive 2025-10-01 00:15:41.791504 | controller | ok: Runtime: 0:00:00.011217 2025-10-01 00:15:41.801472 | 2025-10-01 00:15:41.801625 | TASK [ensure-docker : Get the status of the docket unit] 2025-10-01 00:15:42.334824 | controller | inactive 2025-10-01 00:15:42.488281 | controller | ok: Runtime: 0:00:00.017921 2025-10-01 00:15:42.509493 | 2025-10-01 00:15:42.509699 | TASK [ensure-docker : Restart docker] 2025-10-01 00:15:42.568585 | Trigger a Docker restart 2025-10-01 00:15:42.579626 | 2025-10-01 00:15:42.579760 | TASK [ensure-docker : Reset ssh connection to pick up docker group] 2025-10-01 00:15:42.618718 | 2025-10-01 00:15:42.618890 | TASK [ensure-docker : Install docker-compose] 2025-10-01 00:15:42.636100 | controller | skipping: Conditional result was False 2025-10-01 00:15:42.646227 | 2025-10-01 00:15:42.646378 | TASK [ensure-docker : Flush handlers before role exit] 2025-10-01 00:15:42.660188 | 2025-10-01 00:15:42.660272 | TASK [ensure-docker : Stop docker.socket to avoid any conflict] 2025-10-01 00:15:45.063556 | controller | changed 2025-10-01 00:15:45.075841 | 2025-10-01 00:15:45.076165 | TASK [ensure-docker : Assure docker service is running] 2025-10-01 00:15:48.007850 | controller | changed 2025-10-01 00:15:48.020767 | 2025-10-01 00:15:48.020915 | TASK [ensure-docker : Assure docker.socket service is running] 2025-10-01 00:15:49.137539 | controller | ok 2025-10-01 00:15:49.156639 | 2025-10-01 00:15:49.156862 | TASK [ensure-docker : Correct group ownership on docker sock] 2025-10-01 00:15:50.017157 | controller | ok 2025-10-01 00:15:50.031096 | 2025-10-01 00:15:50.031272 | TASK [ensure-docker : Validate ability to talk with docker] 2025-10-01 00:15:50.607161 | controller | CONTAINER ID IMAGE COMMAND CREATED STATUS PORTS NAMES 2025-10-01 00:15:50.692838 | controller | ok: Runtime: 0:00:00.054889 2025-10-01 00:15:50.701834 | 2025-10-01 00:15:50.701988 | TASK [ensure-docker : Get version details] 2025-10-01 00:15:51.275276 | controller | Client: Docker Engine - Community 2025-10-01 00:15:51.275885 | controller | Version: 24.0.7 2025-10-01 00:15:51.280891 | controller | API version: 1.43 2025-10-01 00:15:51.280957 | controller | Go version: go1.20.10 2025-10-01 00:15:51.281004 | controller | Git commit: afdd53b 2025-10-01 00:15:51.281016 | controller | Built: Thu Oct 26 09:09:39 2023 2025-10-01 00:15:51.281027 | controller | OS/Arch: linux/amd64 2025-10-01 00:15:51.281040 | controller | Context: default 2025-10-01 00:15:51.281054 | controller | 2025-10-01 00:15:51.281064 | controller | Server: Docker Engine - Community 2025-10-01 00:15:51.281073 | controller | Engine: 2025-10-01 00:15:51.281082 | controller | Version: 24.0.7 2025-10-01 00:15:51.281091 | controller | API version: 1.43 (minimum version 1.12) 2025-10-01 00:15:51.281100 | controller | Go version: go1.20.10 2025-10-01 00:15:51.281109 | controller | Git commit: 311b9ff 2025-10-01 00:15:51.281118 | controller | Built: Thu Oct 26 09:08:11 2023 2025-10-01 00:15:51.281127 | controller | OS/Arch: linux/amd64 2025-10-01 00:15:51.281136 | controller | Experimental: false 2025-10-01 00:15:51.281146 | controller | containerd: 2025-10-01 00:15:51.281155 | controller | Version: 1.6.25 2025-10-01 00:15:51.281163 | controller | GitCommit: d8f198a4ed8892c764191ef7b3b06d8a2eeb5c7f 2025-10-01 00:15:51.281172 | controller | runc: 2025-10-01 00:15:51.281181 | controller | Version: 1.1.10 2025-10-01 00:15:51.281190 | controller | GitCommit: v1.1.10-0-g18a0cb0 2025-10-01 00:15:51.281202 | controller | docker-init: 2025-10-01 00:15:51.281211 | controller | Version: 0.19.0 2025-10-01 00:15:51.281219 | controller | GitCommit: de40ad0 2025-10-01 00:15:51.366989 | controller | ok: Runtime: 0:00:00.068627 2025-10-01 00:15:51.376224 | 2025-10-01 00:15:51.376383 | TASK [ensure-docker : Dump installed docker details] 2025-10-01 00:15:51.420560 | {'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-10-01 00:15:51.212903', 'end': '2025-10-01 00:15:51.281530', 'delta': '0:00:00.068627', 'msg': '', 'zuul_log_id': 'fa163ef9-e89a-87ac-f888-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-10-01 00:15:51.435638 | 2025-10-01 00:15:51.435772 | TASK [Use buildset registry] 2025-10-01 00:15:51.462235 | controller | skipping: Conditional result was False 2025-10-01 00:15:51.484257 | 2025-10-01 00:15:51.484432 | TASK [Ensure python3.8 is present] 2025-10-01 00:15:51.511993 | controller | skipping: Conditional result was False 2025-10-01 00:15:51.524824 | 2025-10-01 00:15:51.524975 | TASK [Run our-ensure-python role (Fedora only for now)] 2025-10-01 00:15:51.548785 | controller | ok 2025-10-01 00:15:51.571209 | 2025-10-01 00:15:51.571349 | TASK [our-ensure-python : Install the right Python version (rpm)] 2025-10-01 00:15:58.030620 | controller | ok: Nothing to do 2025-10-01 00:15:58.044396 | 2025-10-01 00:15:58.044540 | TASK [our-ensure-python : Also install python3-devel] 2025-10-01 00:16:11.778123 | controller | changed 2025-10-01 00:16:11.789779 | 2025-10-01 00:16:11.789873 | TASK [Run ensure-virtualenv role] 2025-10-01 00:16:11.809389 | controller | ok 2025-10-01 00:16:11.865667 | 2025-10-01 00:16:11.865850 | TASK [ensure-virtualenv : Check if virtualenv is installed] 2025-10-01 00:16:12.241305 | controller | /usr/bin/virtualenv 2025-10-01 00:16:12.503002 | controller | ok: Runtime: 0:00:00.004090 2025-10-01 00:16:12.511591 | 2025-10-01 00:16:12.511835 | LOOP [ensure-virtualenv : Install virtualenv package] 2025-10-01 00:16:12.552934 | controller | skipping: Conditional result was False 2025-10-01 00:16:12.553531 | controller | changed: All items complete 2025-10-01 00:16:12.553643 | 2025-10-01 00:16:12.573658 | 2025-10-01 00:16:12.573897 | TASK [Find the full path of the Python interpreter] 2025-10-01 00:16:12.912386 | controller | /usr/bin/python3.9 2025-10-01 00:16:13.219579 | controller | ok 2025-10-01 00:16:13.225915 | 2025-10-01 00:16:13.226006 | TASK [Create virtualenv for ansible-test with a specific interpreter] 2025-10-01 00:16:15.438881 | controller | created virtual environment CPython3.9.18.final.0-64 in 1172ms 2025-10-01 00:16:15.479903 | controller | creator CPython3Posix(dest=/home/zuul-worker/venv, clear=False, no_vcs_ignore=False, global=False) 2025-10-01 00:16:15.479972 | 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-10-01 00:16:15.479992 | controller | added seed packages: pip==22.2.2, setuptools==62.6.0, wheel==0.37.1 2025-10-01 00:16:15.480015 | controller | activators BashActivator,CShellActivator,FishActivator,NushellActivator,PowerShellActivator,PythonActivator 2025-10-01 00:16:15.867863 | controller | changed 2025-10-01 00:16:15.877405 | 2025-10-01 00:16:15.877624 | TASK [Set selinux package] 2025-10-01 00:16:15.915107 | controller | ok 2025-10-01 00:16:15.924030 | 2025-10-01 00:16:15.924256 | TASK [Set selinux package (Fedora)] 2025-10-01 00:16:15.981479 | controller | ok 2025-10-01 00:16:15.989860 | 2025-10-01 00:16:15.990067 | TASK [Install selinux into virtualenv] 2025-10-01 00:16:19.300191 | controller | Collecting selinux-please-lie-to-me 2025-10-01 00:16:19.417698 | controller | Downloading selinux_please_lie_to_me-1.0.1-py2.py3-none-any.whl (3.8 kB) 2025-10-01 00:16:20.433757 | controller | Collecting setuptools<50.0.0 2025-10-01 00:16:20.449024 | controller | Downloading setuptools-49.6.0-py3-none-any.whl (803 kB) 2025-10-01 00:16:20.577551 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 803.3/803.3 kB 6.7 MB/s eta 0:00:00 2025-10-01 00:16:20.802517 | controller | Installing collected packages: setuptools, selinux-please-lie-to-me 2025-10-01 00:16:20.802945 | controller | Attempting uninstall: setuptools 2025-10-01 00:16:20.804520 | controller | Found existing installation: setuptools 62.6.0 2025-10-01 00:16:21.008920 | controller | Uninstalling setuptools-62.6.0: 2025-10-01 00:16:21.044486 | controller | Successfully uninstalled setuptools-62.6.0 2025-10-01 00:16:21.924533 | controller | Successfully installed selinux-please-lie-to-me-1.0.1 setuptools-49.6.0 2025-10-01 00:16:22.249648 | controller | 2025-10-01 00:16:22.645644 | controller | [notice] A new release of pip available: 22.2.2 -> 25.2 2025-10-01 00:16:22.645691 | controller | [notice] To update, run: /home/zuul-worker/venv/bin/python -m pip install --upgrade pip 2025-10-01 00:16:23.142464 | controller | ok: Runtime: 0:00:06.051076 2025-10-01 00:16:23.157007 | 2025-10-01 00:16:23.157179 | TASK [Install pytest-forked into virtualenv] 2025-10-01 00:16:25.135607 | controller | Collecting pytest-forked 2025-10-01 00:16:25.230469 | controller | Downloading pytest_forked-1.6.0-py3-none-any.whl (4.9 kB) 2025-10-01 00:16:25.300919 | controller | Collecting py 2025-10-01 00:16:25.315279 | controller | Downloading py-1.11.0-py2.py3-none-any.whl (98 kB) 2025-10-01 00:16:25.350547 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 98.7/98.7 kB 2.9 MB/s eta 0:00:00 2025-10-01 00:16:25.563544 | controller | Collecting pytest>=3.10 2025-10-01 00:16:25.576372 | controller | Downloading pytest-8.4.2-py3-none-any.whl (365 kB) 2025-10-01 00:16:25.637400 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 365.8/365.8 kB 6.4 MB/s eta 0:00:00 2025-10-01 00:16:25.844815 | controller | Collecting pygments>=2.7.2 2025-10-01 00:16:25.916480 | controller | Downloading pygments-2.19.2-py3-none-any.whl (1.2 MB) 2025-10-01 00:16:26.040760 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 1.2/1.2 MB 10.1 MB/s eta 0:00:00 2025-10-01 00:16:26.105118 | controller | Collecting pluggy<2,>=1.5 2025-10-01 00:16:26.118376 | controller | Downloading pluggy-1.6.0-py3-none-any.whl (20 kB) 2025-10-01 00:16:26.172562 | controller | Collecting exceptiongroup>=1 2025-10-01 00:16:26.185823 | controller | Downloading exceptiongroup-1.3.0-py3-none-any.whl (16 kB) 2025-10-01 00:16:26.264022 | controller | Collecting packaging>=20 2025-10-01 00:16:26.277355 | controller | Downloading packaging-25.0-py3-none-any.whl (66 kB) 2025-10-01 00:16:26.287658 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 66.5/66.5 kB 9.2 MB/s eta 0:00:00 2025-10-01 00:16:26.350009 | controller | Collecting tomli>=1 2025-10-01 00:16:26.362653 | controller | Downloading tomli-2.2.1-py3-none-any.whl (14 kB) 2025-10-01 00:16:26.399280 | controller | Collecting iniconfig>=1 2025-10-01 00:16:26.412013 | controller | Downloading iniconfig-2.1.0-py3-none-any.whl (6.0 kB) 2025-10-01 00:16:26.498530 | controller | Collecting typing-extensions>=4.6.0 2025-10-01 00:16:26.511094 | controller | Downloading typing_extensions-4.15.0-py3-none-any.whl (44 kB) 2025-10-01 00:16:26.520732 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 44.6/44.6 kB 6.3 MB/s eta 0:00:00 2025-10-01 00:16:26.649429 | controller | Installing collected packages: typing-extensions, tomli, pygments, py, pluggy, packaging, iniconfig, exceptiongroup, pytest, pytest-forked 2025-10-01 00:16:28.936001 | 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-10-01 00:16:28.942868 | controller | 2025-10-01 00:16:29.114614 | controller | [notice] A new release of pip available: 22.2.2 -> 25.2 2025-10-01 00:16:29.114657 | controller | [notice] To update, run: /home/zuul-worker/venv/bin/python -m pip install --upgrade pip 2025-10-01 00:16:29.330768 | controller | ok: Runtime: 0:00:05.205353 2025-10-01 00:16:29.339020 | 2025-10-01 00:16:29.339138 | TASK [Update pip] 2025-10-01 00:16:31.222625 | controller | Requirement already satisfied: pip in ./venv/lib/python3.9/site-packages (22.2.2) 2025-10-01 00:16:31.661841 | controller | Collecting pip 2025-10-01 00:16:31.796290 | controller | Downloading pip-25.2-py3-none-any.whl (1.8 MB) 2025-10-01 00:16:31.997735 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 1.8/1.8 MB 9.0 MB/s eta 0:00:00 2025-10-01 00:16:32.226944 | controller | Installing collected packages: pip 2025-10-01 00:16:32.227289 | controller | Attempting uninstall: pip 2025-10-01 00:16:32.234170 | controller | Found existing installation: pip 22.2.2 2025-10-01 00:16:32.832639 | controller | Uninstalling pip-22.2.2: 2025-10-01 00:16:32.899331 | controller | Successfully uninstalled pip-22.2.2 2025-10-01 00:16:34.682290 | controller | Successfully installed pip-25.2 2025-10-01 00:16:34.981938 | controller | ok: Runtime: 0:00:04.780357 2025-10-01 00:16:34.991000 | 2025-10-01 00:16:34.991169 | TASK [Ensure pip temp directory exists in /var/tmp] 2025-10-01 00:16:35.681431 | controller | changed 2025-10-01 00:16:35.688127 | 2025-10-01 00:16:35.688243 | TASK [Install ansible into virtualenv] 2025-10-01 00:16:37.337641 | controller | Processing ./src/github.com/ansible/ansible 2025-10-01 00:16:37.341834 | controller | Preparing metadata (setup.py): started 2025-10-01 00:16:38.779030 | controller | Preparing metadata (setup.py): finished with status 'done' 2025-10-01 00:16:38.868015 | controller | Collecting jinja2 (from ansible-core==2.11.12.post0) 2025-10-01 00:16:38.909836 | controller | Downloading jinja2-3.1.6-py3-none-any.whl.metadata (2.9 kB) 2025-10-01 00:16:39.099628 | controller | Collecting PyYAML (from ansible-core==2.11.12.post0) 2025-10-01 00:16:39.120604 | controller | Downloading pyyaml-6.0.3-cp39-cp39-manylinux2014_x86_64.manylinux_2_17_x86_64.manylinux_2_28_x86_64.whl.metadata (2.4 kB) 2025-10-01 00:16:39.714372 | controller | Collecting cryptography (from ansible-core==2.11.12.post0) 2025-10-01 00:16:39.726996 | controller | Downloading cryptography-46.0.1-cp38-abi3-manylinux_2_34_x86_64.whl.metadata (5.7 kB) 2025-10-01 00:16:39.752726 | controller | Requirement already satisfied: packaging in ./venv/lib/python3.9/site-packages (from ansible-core==2.11.12.post0) (25.0) 2025-10-01 00:16:39.779930 | controller | Collecting resolvelib<0.6.0,>=0.5.3 (from ansible-core==2.11.12.post0) 2025-10-01 00:16:39.799583 | controller | Downloading resolvelib-0.5.4-py2.py3-none-any.whl.metadata (3.7 kB) 2025-10-01 00:16:40.087201 | controller | Collecting cffi>=2.0.0 (from cryptography->ansible-core==2.11.12.post0) 2025-10-01 00:16:40.100235 | controller | Downloading cffi-2.0.0-cp39-cp39-manylinux2014_x86_64.manylinux_2_17_x86_64.whl.metadata (2.6 kB) 2025-10-01 00:16:40.111234 | 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-10-01 00:16:40.139487 | controller | Collecting pycparser (from cffi>=2.0.0->cryptography->ansible-core==2.11.12.post0) 2025-10-01 00:16:40.152509 | controller | Downloading pycparser-2.23-py3-none-any.whl.metadata (993 bytes) 2025-10-01 00:16:40.339556 | controller | Collecting MarkupSafe>=2.0 (from jinja2->ansible-core==2.11.12.post0) 2025-10-01 00:16:40.352761 | controller | Downloading markupsafe-3.0.3-cp39-cp39-manylinux2014_x86_64.manylinux_2_17_x86_64.manylinux_2_28_x86_64.whl.metadata (2.7 kB) 2025-10-01 00:16:40.382363 | controller | Downloading resolvelib-0.5.4-py2.py3-none-any.whl (12 kB) 2025-10-01 00:16:40.403809 | controller | Downloading cryptography-46.0.1-cp38-abi3-manylinux_2_34_x86_64.whl (4.6 MB) 2025-10-01 00:16:40.513155 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 4.6/4.6 MB 69.1 MB/s 0:00:00 2025-10-01 00:16:40.526098 | controller | Downloading cffi-2.0.0-cp39-cp39-manylinux2014_x86_64.manylinux_2_17_x86_64.whl (216 kB) 2025-10-01 00:16:40.549703 | controller | Downloading jinja2-3.1.6-py3-none-any.whl (134 kB) 2025-10-01 00:16:40.572366 | controller | Downloading markupsafe-3.0.3-cp39-cp39-manylinux2014_x86_64.manylinux_2_17_x86_64.manylinux_2_28_x86_64.whl (20 kB) 2025-10-01 00:16:40.593374 | controller | Downloading pycparser-2.23-py3-none-any.whl (118 kB) 2025-10-01 00:16:40.617766 | controller | Downloading pyyaml-6.0.3-cp39-cp39-manylinux2014_x86_64.manylinux_2_17_x86_64.manylinux_2_28_x86_64.whl (750 kB) 2025-10-01 00:16:40.636447 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 750.8/750.8 kB 36.5 MB/s 0:00:00 2025-10-01 00:16:40.682625 | controller | Building wheels for collected packages: ansible-core 2025-10-01 00:16:40.684178 | 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-10-01 00:16:40.684632 | controller | Building wheel for ansible-core (setup.py): started 2025-10-01 00:16:43.022392 | controller | Building wheel for ansible-core (setup.py): finished with status 'done' 2025-10-01 00:16:43.035365 | controller | Created wheel for ansible-core: filename=ansible_core-2.11.12.post0-py3-none-any.whl size=1961168 sha256=8f70ace3e6f7212add8133d68d921cbf0e12df25277169ee8d93768154602a2d 2025-10-01 00:16:43.035694 | controller | Stored in directory: /var/tmp/ansible-test-pip/pip-ephem-wheel-cache-c29t5u8h/wheels/e8/fd/e9/6250c469bad44a7c6855190836e9d22c0bb11cb641caf57fbe 2025-10-01 00:16:43.052533 | controller | Successfully built ansible-core 2025-10-01 00:16:43.122855 | controller | Installing collected packages: resolvelib, PyYAML, pycparser, MarkupSafe, jinja2, cffi, cryptography, ansible-core 2025-10-01 00:16:45.711606 | controller | 2025-10-01 00:16:45.715383 | controller | Successfully installed MarkupSafe-3.0.3 PyYAML-6.0.3 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-10-01 00:16:46.333283 | controller | ok: Runtime: 0:00:09.658539 2025-10-01 00:16:46.343907 | 2025-10-01 00:16:46.344068 | TASK [Setup download-artifact-fork role] 2025-10-01 00:16:46.369515 | controller | ok 2025-10-01 00:16:46.400292 | 2025-10-01 00:16:46.400818 | TASK [download-artifact-fork : Parse inventory response] 2025-10-01 00:16:46.445989 | controller | ok 2025-10-01 00:16:46.455630 | 2025-10-01 00:16:46.455809 | TASK [download-artifact-fork : Fetch info from Zuul API] 2025-10-01 00:16:46.482370 | controller | skipping: Conditional result was False 2025-10-01 00:16:46.492204 | 2025-10-01 00:16:46.492517 | TASK [download-artifact-fork : Parse build response] 2025-10-01 00:16:46.519206 | controller | skipping: Conditional result was False 2025-10-01 00:16:46.529878 | 2025-10-01 00:16:46.530061 | TASK [download-artifact-fork : Ensure artifacts directory exists] 2025-10-01 00:16:47.089036 | controller | changed 2025-10-01 00:16:47.097075 | 2025-10-01 00:16:47.097172 | LOOP [download-artifact-fork : Download archive by type] 2025-10-01 00:16:47.122521 | controller | skipping: Conditional result was False 2025-10-01 00:16:47.123366 | 2025-10-01 00:16:48.539291 | controller | changed: OK (20194 bytes) 2025-10-01 00:16:48.579805 | 2025-10-01 00:16:48.580022 | TASK [deploy-artifacts : define __collections] 2025-10-01 00:16:48.620704 | controller | ok 2025-10-01 00:16:48.633868 | 2025-10-01 00:16:48.634037 | LOOP [deploy-artifacts : Create list of collections] 2025-10-01 00:16:48.672033 | controller | skipping: Conditional result was False 2025-10-01 00:16:48.692148 | controller | ok: 2025-10-01 00:16:48.692284 | controller | { 2025-10-01 00:16:48.692318 | controller | "branch": "main", 2025-10-01 00:16:48.692344 | controller | "job": "build-ansible-collection", 2025-10-01 00:16:48.692368 | controller | "metadata": { 2025-10-01 00:16:48.692391 | controller | "type": "ansible_collection", 2025-10-01 00:16:48.692415 | controller | "version": "5.0.0" 2025-10-01 00:16:48.692437 | controller | }, 2025-10-01 00:16:48.692459 | controller | "name": "ansible.network", 2025-10-01 00:16:48.692481 | controller | "project": "ansible-collections/ansible.network", 2025-10-01 00:16:48.692504 | controller | "url": "https://ae2cf7d4ec3a6e236bcc-12f681861349c51e0d5488836e797fe8.ssl.cf1.rackcdn.com/ansible/5155f30f807644fbb6afb7d71e44743a/artifacts/ansible-network-5.0.0.tar.gz" 2025-10-01 00:16:48.692536 | controller | } 2025-10-01 00:16:48.709354 | 2025-10-01 00:16:48.709606 | TASK [deploy-artifacts : Install require-project collection using ansible-galaxy] 2025-10-01 00:16:50.008615 | controller | Starting galaxy collection install process 2025-10-01 00:16:50.008933 | controller | Process install dependency map 2025-10-01 00:16:52.187033 | controller | [WARNING]: Skipping Galaxy server https://galaxy.ansible.com/api/. Got an 2025-10-01 00:16:52.234621 | controller | unexpected error when getting available versions of collection 2025-10-01 00:16:52.234675 | controller | ansible.netcommon: '/api/v3/plugin/ansible/content/published/collections/index/ 2025-10-01 00:16:52.234683 | controller | ansible/netcommon/versions/' 2025-10-01 00:16:52.234689 | controller | ERROR! Unexpected Exception, this is probably a bug: '/api/v3/plugin/ansible/content/published/collections/index/ansible/netcommon/versions/' 2025-10-01 00:16:52.234699 | controller | to see the full traceback, use -vvv 2025-10-01 00:16:52.369255 | controller | ERROR 2025-10-01 00:16:52.369558 | controller | { 2025-10-01 00:16:52.369620 | controller | "delta": "0:00:03.026797", 2025-10-01 00:16:52.369662 | controller | "end": "2025-10-01 00:16:52.235151", 2025-10-01 00:16:52.369699 | controller | "msg": "non-zero return code", 2025-10-01 00:16:52.369773 | controller | "rc": 250, 2025-10-01 00:16:52.369812 | controller | "start": "2025-10-01 00:16:49.208354" 2025-10-01 00:16:52.369848 | controller | } failure 2025-10-01 00:16:52.373139 | 2025-10-01 00:16:52.373243 | PLAY RECAP 2025-10-01 00:16:52.373328 | controller | ok: 44 changed: 24 unreachable: 0 failed: 1 skipped: 17 rescued: 0 ignored: 0 2025-10-01 00:16:52.373373 | 2025-10-01 00:16:52.559828 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master] 2025-10-01 00:16:52.561207 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2025-10-01 00:16:53.239975 | 2025-10-01 00:16:53.240133 | PLAY [all] 2025-10-01 00:16:53.263659 | 2025-10-01 00:16:53.263871 | TASK [fetch-subunit-output : Find stestr or testr executable] 2025-10-01 00:16:54.817006 | controller | changed: non-zero return code 2025-10-01 00:16:54.832997 | 2025-10-01 00:16:54.833249 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2025-10-01 00:16:54.861422 | controller | skipping: Conditional result was False 2025-10-01 00:16:54.874908 | 2025-10-01 00:16:54.875116 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2025-10-01 00:16:54.913043 | 2025-10-01 00:16:54.913262 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2025-10-01 00:16:54.949266 | 2025-10-01 00:16:54.949527 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2025-10-01 00:16:54.975693 | controller | skipping: Conditional result was False 2025-10-01 00:16:54.987361 | 2025-10-01 00:16:54.987532 | LOOP [fetch-subunit-output : Generate subunit file] 2025-10-01 00:16:55.023700 | 2025-10-01 00:16:55.024037 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2025-10-01 00:16:55.049474 | controller | skipping: Conditional result was False 2025-10-01 00:16:55.057943 | 2025-10-01 00:16:55.058107 | TASK [fetch-subunit-output : Remove the temporary file] 2025-10-01 00:16:55.084308 | controller | skipping: Conditional result was False 2025-10-01 00:16:55.094035 | 2025-10-01 00:16:55.094199 | TASK [fetch-subunit-output : Process and fetch subunit results] 2025-10-01 00:16:55.119915 | controller | skipping: Conditional result was False 2025-10-01 00:16:55.155107 | 2025-10-01 00:16:55.155220 | PLAY RECAP 2025-10-01 00:16:55.155270 | controller | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2025-10-01 00:16:55.155300 | 2025-10-01 00:16:55.259317 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2025-10-01 00:16:55.260192 | POST-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/post.yaml@master] 2025-10-01 00:16:55.878259 | 2025-10-01 00:16:55.878398 | PLAY [all:!appliance*] 2025-10-01 00:16:55.905818 | 2025-10-01 00:16:55.905965 | TASK [unregister the node] 2025-10-01 00:16:56.043794 | controller | skipping: Conditional result was False 2025-10-01 00:16:56.053787 | 2025-10-01 00:16:56.053957 | TASK [include_role : fetch-output] 2025-10-01 00:16:56.089455 | controller | ok 2025-10-01 00:16:56.111960 | 2025-10-01 00:16:56.112078 | TASK [fetch-output : Set log path for multiple nodes] 2025-10-01 00:16:56.167006 | controller | skipping: Conditional result was False 2025-10-01 00:16:56.175308 | 2025-10-01 00:16:56.175472 | TASK [fetch-output : Set log path for single node] 2025-10-01 00:16:56.218560 | controller | ok 2025-10-01 00:16:56.226666 | 2025-10-01 00:16:56.226829 | LOOP [fetch-output : Ensure local output dirs] 2025-10-01 00:16:56.674292 | controller -> localhost | ok: "/var/lib/zuul/builds/9f7f345a5f5045b892b83a2a68283f69/work/logs" 2025-10-01 00:16:56.925098 | controller -> localhost | changed: "/var/lib/zuul/builds/9f7f345a5f5045b892b83a2a68283f69/work/artifacts" 2025-10-01 00:16:57.144852 | controller -> localhost | changed: "/var/lib/zuul/builds/9f7f345a5f5045b892b83a2a68283f69/work/docs" 2025-10-01 00:16:57.159219 | 2025-10-01 00:16:57.159340 | LOOP [fetch-output : Collect logs, artifacts and docs] 2025-10-01 00:16:58.435333 | controller | changed: 2025-10-01 00:16:58.438821 | controller | .d..t...... ./ 2025-10-01 00:16:58.438943 | controller | cd+++++++++ controller/ 2025-10-01 00:16:58.439013 | controller | changed: All items complete 2025-10-01 00:16:58.439048 | 2025-10-01 00:16:59.523877 | controller | changed: .d..t...... ./ 2025-10-01 00:17:00.655288 | controller | changed: .d..t...... ./ 2025-10-01 00:17:00.676682 | 2025-10-01 00:17:00.676849 | TASK [include_role : fetch-output-openshift] 2025-10-01 00:17:00.691252 | controller | skipping: Conditional result was False 2025-10-01 00:17:00.698352 | 2025-10-01 00:17:00.698425 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2025-10-01 00:17:00.727217 | controller | skipping: Conditional result was False 2025-10-01 00:17:00.738406 | controller | skipping: Conditional result was False 2025-10-01 00:17:00.796966 | 2025-10-01 00:17:00.797220 | PLAY [localhost] 2025-10-01 00:17:00.820766 | 2025-10-01 00:17:00.820957 | TASK [Run Zuul manifest role] 2025-10-01 00:17:00.839756 | localhost | ok 2025-10-01 00:17:00.859477 | 2025-10-01 00:17:00.859577 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-10-01 00:17:01.241352 | localhost | changed 2025-10-01 00:17:01.252797 | 2025-10-01 00:17:01.253061 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-10-01 00:17:01.284157 | localhost | ok 2025-10-01 00:17:01.295657 | 2025-10-01 00:17:01.295805 | TASK [Set zuul-log-path fact] 2025-10-01 00:17:01.319057 | localhost | ok 2025-10-01 00:17:01.340082 | 2025-10-01 00:17:01.340233 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-10-01 00:17:01.380866 | localhost | ok 2025-10-01 00:17:01.391535 | 2025-10-01 00:17:01.391672 | LOOP [Run upload-logs-swift role] 2025-10-01 00:17:01.439073 | localhost | Output suppressed because no_log was given 2025-10-01 00:17:01.470632 | 2025-10-01 00:17:01.470744 | TASK [Set zuul-log-path fact] 2025-10-01 00:17:01.504948 | localhost | skipping: Conditional result was False 2025-10-01 00:17:01.511373 | 2025-10-01 00:17:01.511454 | TASK [upload-logs-swift : Ensure logs are readable before uploading] 2025-10-01 00:17:01.991221 | localhost -> localhost | ok: Runtime: 0:00:00.004662 2025-10-01 00:17:01.996771 | 2025-10-01 00:17:01.996865 | TASK [upload-logs-swift : Upload logs to swift]