2026-02-01 00:22:52.028551 | Job console starting... 2026-02-01 00:22:52.039123 | Updating repositories 2026-02-01 00:22:52.179128 | Preparing job workspace 2026-02-01 00:22:56.363243 | Running Ansible setup... 2026-02-01 00:23:01.765560 | PRE-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master] 2026-02-01 00:23:02.530978 | 2026-02-01 00:23:02.531227 | PLAY [localhost] 2026-02-01 00:23:02.543639 | 2026-02-01 00:23:02.543849 | TASK [Gathering Facts] 2026-02-01 00:23:03.550488 | localhost | ok 2026-02-01 00:23:03.572353 | 2026-02-01 00:23:03.572594 | TASK [Setup log path fact] 2026-02-01 00:23:03.594733 | localhost | ok 2026-02-01 00:23:03.609187 | 2026-02-01 00:23:03.609326 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-02-01 00:23:03.638862 | localhost | ok 2026-02-01 00:23:03.648417 | 2026-02-01 00:23:03.648561 | TASK [emit-job-header : Print job information] 2026-02-01 00:23:03.678478 | # Job Information 2026-02-01 00:23:03.678772 | Ansible Version: 2.15.12 2026-02-01 00:23:03.678818 | Job: ansible-test-sanity-docker-stable-2.11 2026-02-01 00:23:03.678845 | Pipeline: periodic 2026-02-01 00:23:03.678870 | Executor: ze04.softwarefactory-project.io 2026-02-01 00:23:03.678894 | Triggered by: https://github.com/ansible-collections/ansible.network 2026-02-01 00:23:03.678921 | Log URL (when completed): https://ansible.softwarefactory-project.io/logs/944/ansible/944f5555e272436e955f9f2773547b1c/ 2026-02-01 00:23:03.678946 | Event ID: 04be55980abd4211aa5248e6fab4a21f 2026-02-01 00:23:03.683281 | 2026-02-01 00:23:03.683353 | LOOP [emit-job-header : Print node information] 2026-02-01 00:23:03.803887 | localhost | ok: 2026-02-01 00:23:03.804137 | localhost | # Node Information 2026-02-01 00:23:03.804166 | localhost | Inventory Hostname: controller 2026-02-01 00:23:03.804186 | localhost | Hostname: np0005604149 2026-02-01 00:23:03.804205 | localhost | Username: zuul 2026-02-01 00:23:03.804229 | localhost | Distro: Fedora 37 2026-02-01 00:23:03.804248 | localhost | Provider: ansible-vexxhost-ca-ymq-1 2026-02-01 00:23:03.804265 | localhost | Region: ca-ymq-1 2026-02-01 00:23:03.804282 | localhost | Label: ansible-fedora-37-1vcpu 2026-02-01 00:23:03.804299 | localhost | Product Name: OpenStack Nova 2026-02-01 00:23:03.804316 | localhost | Interface IP: 162.253.55.204 2026-02-01 00:23:03.824489 | 2026-02-01 00:23:03.824727 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-02-01 00:23:04.371655 | localhost -> localhost | changed 2026-02-01 00:23:04.378622 | 2026-02-01 00:23:04.378781 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-02-01 00:23:05.502924 | localhost -> localhost | changed 2026-02-01 00:23:05.525332 | 2026-02-01 00:23:05.525461 | PLAY [all:!appliance*] 2026-02-01 00:23:05.544140 | 2026-02-01 00:23:05.544300 | TASK [include_role : start-zuul-console] 2026-02-01 00:23:05.567719 | controller | ok 2026-02-01 00:23:05.586002 | 2026-02-01 00:23:05.586122 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-02-01 00:23:05.977525 | controller | ok 2026-02-01 00:23:06.006102 | 2026-02-01 00:23:06.006295 | TASK [use-our-mirror : Retrieve the IP address] 2026-02-01 00:23:06.970105 | controller | ok: Status code was 404 and not [200]: HTTP Error 404: Not Found 2026-02-01 00:23:06.978506 | 2026-02-01 00:23:06.978699 | TASK [use-our-mirror : Ensure the registry service is running on the mirror] 2026-02-01 00:23:07.516415 | controller | skipping: Conditional result was False 2026-02-01 00:23:07.525828 | 2026-02-01 00:23:07.525958 | TASK [use-our-mirror : Write the IP in /etc/hosts] 2026-02-01 00:23:07.562462 | controller | skipping: Conditional result was False 2026-02-01 00:23:07.570266 | 2026-02-01 00:23:07.570390 | TASK [use-our-mirror : Create the podman configuration directory] 2026-02-01 00:23:07.595352 | controller | skipping: Conditional result was False 2026-02-01 00:23:07.603000 | 2026-02-01 00:23:07.603109 | TASK [use-our-mirror : Copy the podman configuration] 2026-02-01 00:23:07.632747 | controller | skipping: Conditional result was False 2026-02-01 00:23:07.639542 | 2026-02-01 00:23:07.639661 | TASK [use-our-mirror : Copy the Yum configuration (main)] 2026-02-01 00:23:07.671699 | controller | skipping: Conditional result was False 2026-02-01 00:23:07.680372 | 2026-02-01 00:23:07.680483 | TASK [use-our-mirror : Copy the Yum configuration (update)] 2026-02-01 00:23:07.709614 | controller | skipping: Conditional result was False 2026-02-01 00:23:07.723065 | 2026-02-01 00:23:07.723225 | TASK [Disable Fedora Modular] 2026-02-01 00:23:07.971762 | controller | changed 2026-02-01 00:23:07.980145 | 2026-02-01 00:23:07.980314 | TASK [Enable EPEL] 2026-02-01 00:23:08.016238 | controller | skipping: Conditional result was False 2026-02-01 00:23:08.023270 | 2026-02-01 00:23:08.023350 | TASK [Register the RHEL node] 2026-02-01 00:23:08.574321 | 2026-02-01 00:23:08.574648 | TASK [Show the subscription-manager status] 2026-02-01 00:23:09.186491 | controller | skipping: Conditional result was False 2026-02-01 00:23:09.194584 | 2026-02-01 00:23:09.194715 | TASK [Enable EPEL on RHEL] 2026-02-01 00:23:09.732232 | controller | skipping: Conditional result was False 2026-02-01 00:23:09.742845 | 2026-02-01 00:23:09.743017 | TASK [Install git and tox] 2026-02-01 00:24:31.205652 | controller | changed 2026-02-01 00:24:31.215987 | 2026-02-01 00:24:31.216095 | TASK [include_role : prepare-workspace] 2026-02-01 00:24:31.253343 | controller | ok 2026-02-01 00:24:31.283882 | 2026-02-01 00:24:31.284056 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-02-01 00:24:31.546893 | controller | ok 2026-02-01 00:24:31.557955 | 2026-02-01 00:24:31.558089 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-02-01 00:24:46.637163 | controller | Output suppressed because no_log was given 2026-02-01 00:24:46.648755 | 2026-02-01 00:24:46.648891 | TASK [include_role : prepare-workspace-openshift] 2026-02-01 00:24:46.667630 | controller | skipping: Conditional result was False 2026-02-01 00:24:46.696990 | 2026-02-01 00:24:46.697090 | PLAY [all:!appliance] 2026-02-01 00:24:46.717221 | 2026-02-01 00:24:46.717372 | TASK [Run add-build-sshkey role (RSA)] 2026-02-01 00:24:46.750566 | controller | ok 2026-02-01 00:24:46.773040 | 2026-02-01 00:24:46.773201 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-02-01 00:24:47.038012 | controller -> localhost | ok 2026-02-01 00:24:47.049973 | 2026-02-01 00:24:47.050147 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-02-01 00:24:47.087429 | controller | ok 2026-02-01 00:24:47.115661 | controller | included: /var/lib/zuul/builds/944f5555e272436e955f9f2773547b1c/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-02-01 00:24:47.126517 | 2026-02-01 00:24:47.126708 | TASK [add-build-sshkey : Create Temp SSH key] 2026-02-01 00:24:47.645750 | controller -> localhost | Generating public/private rsa key pair. 2026-02-01 00:24:47.646137 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/944f5555e272436e955f9f2773547b1c/work/944f5555e272436e955f9f2773547b1c_id_rsa. 2026-02-01 00:24:47.646186 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/944f5555e272436e955f9f2773547b1c/work/944f5555e272436e955f9f2773547b1c_id_rsa.pub. 2026-02-01 00:24:47.646219 | controller -> localhost | The key fingerprint is: 2026-02-01 00:24:47.646249 | controller -> localhost | SHA256:CdlS1OUM3Ku0U4aTx7QLlxfK4C7nyRpzGwU3oC4+ykM zuul-build-sshkey 2026-02-01 00:24:47.646277 | controller -> localhost | The key's randomart image is: 2026-02-01 00:24:47.646306 | controller -> localhost | +---[RSA 2048]----+ 2026-02-01 00:24:47.646334 | controller -> localhost | | .oooo. | 2026-02-01 00:24:47.646364 | controller -> localhost | | + .+=o . | 2026-02-01 00:24:47.646391 | controller -> localhost | | + o..B== . | 2026-02-01 00:24:47.646419 | controller -> localhost | | + .Bo%.. | 2026-02-01 00:24:47.646446 | controller -> localhost | | . So X.o | 2026-02-01 00:24:47.646486 | controller -> localhost | | E. .. *.. | 2026-02-01 00:24:47.646520 | controller -> localhost | | . o o=oo | 2026-02-01 00:24:47.646550 | controller -> localhost | | ... . ++o | 2026-02-01 00:24:47.646578 | controller -> localhost | | o. ... | 2026-02-01 00:24:47.646608 | controller -> localhost | +----[SHA256]-----+ 2026-02-01 00:24:47.647158 | controller -> localhost | ok: Runtime: 0:00:00.128572 2026-02-01 00:24:47.662311 | 2026-02-01 00:24:47.662475 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-02-01 00:24:47.698744 | controller | ok 2026-02-01 00:24:47.719500 | controller | included: /var/lib/zuul/builds/944f5555e272436e955f9f2773547b1c/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-02-01 00:24:47.737106 | 2026-02-01 00:24:47.737240 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-02-01 00:24:47.763070 | controller | skipping: Conditional result was False 2026-02-01 00:24:47.772779 | 2026-02-01 00:24:47.772926 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-02-01 00:24:48.274557 | controller | changed 2026-02-01 00:24:48.289567 | 2026-02-01 00:24:48.289735 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-02-01 00:24:48.526573 | controller | ok 2026-02-01 00:24:48.536895 | 2026-02-01 00:24:48.537080 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-02-01 00:24:49.221128 | controller | changed 2026-02-01 00:24:49.234969 | 2026-02-01 00:24:49.235177 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-02-01 00:24:49.862138 | controller | changed 2026-02-01 00:24:49.877569 | 2026-02-01 00:24:49.878070 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-02-01 00:24:49.908784 | controller | skipping: Conditional result was False 2026-02-01 00:24:49.917605 | 2026-02-01 00:24:49.917771 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-02-01 00:24:50.372647 | controller -> localhost | changed 2026-02-01 00:24:50.388266 | 2026-02-01 00:24:50.388433 | TASK [add-build-sshkey : Add back temp key] 2026-02-01 00:24:50.681296 | controller -> localhost | Identity added: /var/lib/zuul/builds/944f5555e272436e955f9f2773547b1c/work/944f5555e272436e955f9f2773547b1c_id_rsa (zuul-build-sshkey) 2026-02-01 00:24:50.681648 | controller -> localhost | ok: Runtime: 0:00:00.013265 2026-02-01 00:24:50.694019 | 2026-02-01 00:24:50.694158 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-02-01 00:24:51.074564 | controller | ok 2026-02-01 00:24:51.089170 | 2026-02-01 00:24:51.089398 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-02-01 00:24:51.118721 | controller | skipping: Conditional result was False 2026-02-01 00:24:51.151716 | 2026-02-01 00:24:51.151914 | TASK [Run add-build-sshkey role (ECDSA)] 2026-02-01 00:24:51.189589 | controller | ok 2026-02-01 00:24:51.216290 | 2026-02-01 00:24:51.216627 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-02-01 00:24:51.495595 | controller -> localhost | ok 2026-02-01 00:24:51.515902 | 2026-02-01 00:24:51.516147 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-02-01 00:24:51.557904 | controller | ok 2026-02-01 00:24:51.574387 | controller | included: /var/lib/zuul/builds/944f5555e272436e955f9f2773547b1c/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-02-01 00:24:51.583669 | 2026-02-01 00:24:51.583819 | TASK [add-build-sshkey : Create Temp SSH key] 2026-02-01 00:24:51.929650 | controller -> localhost | Generating public/private ecdsa key pair. 2026-02-01 00:24:51.929977 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/944f5555e272436e955f9f2773547b1c/work/944f5555e272436e955f9f2773547b1c_id_ecdsa. 2026-02-01 00:24:51.930007 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/944f5555e272436e955f9f2773547b1c/work/944f5555e272436e955f9f2773547b1c_id_ecdsa.pub. 2026-02-01 00:24:51.930039 | controller -> localhost | The key fingerprint is: 2026-02-01 00:24:51.930061 | controller -> localhost | SHA256:EtBvVZek2wIHqqBl5OkT/Eg187dJyT2R8tlg/hSbFqY zuul-build-sshkey 2026-02-01 00:24:51.930083 | controller -> localhost | The key's randomart image is: 2026-02-01 00:24:51.930105 | controller -> localhost | +---[ECDSA 521]---+ 2026-02-01 00:24:51.930125 | controller -> localhost | | o.+ ..ooo. | 2026-02-01 00:24:51.930145 | controller -> localhost | | + +.+ oo+++= | 2026-02-01 00:24:51.930164 | controller -> localhost | | X ..o.*==* = | 2026-02-01 00:24:51.930183 | controller -> localhost | | * = ooo =E+* | 2026-02-01 00:24:51.930202 | controller -> localhost | | . + +.S o o+. | 2026-02-01 00:24:51.930220 | controller -> localhost | | . . .. | 2026-02-01 00:24:51.930239 | controller -> localhost | | | 2026-02-01 00:24:51.930257 | controller -> localhost | | | 2026-02-01 00:24:51.930276 | controller -> localhost | | | 2026-02-01 00:24:51.930294 | controller -> localhost | +----[SHA256]-----+ 2026-02-01 00:24:51.930360 | controller -> localhost | ok: Runtime: 0:00:00.016217 2026-02-01 00:24:51.938747 | 2026-02-01 00:24:51.938862 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-02-01 00:24:51.971635 | controller | ok 2026-02-01 00:24:51.981729 | controller | included: /var/lib/zuul/builds/944f5555e272436e955f9f2773547b1c/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-02-01 00:24:51.992535 | 2026-02-01 00:24:51.992667 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-02-01 00:24:52.019784 | controller | skipping: Conditional result was False 2026-02-01 00:24:52.027978 | 2026-02-01 00:24:52.028057 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-02-01 00:29:52.075019 | controller | changed 2026-02-01 00:29:52.081056 | 2026-02-01 00:29:52.081123 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-02-01 00:29:52.308940 | controller | ok 2026-02-01 00:29:52.317135 | 2026-02-01 00:29:52.317307 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-02-01 00:29:52.944322 | controller | changed 2026-02-01 00:29:52.961973 | 2026-02-01 00:29:52.962639 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-02-01 00:29:53.594592 | controller | changed 2026-02-01 00:29:53.604711 | 2026-02-01 00:29:53.604869 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-02-01 00:29:53.643575 | controller | skipping: Conditional result was False 2026-02-01 00:29:53.665587 | 2026-02-01 00:29:53.666078 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-02-01 00:29:53.962141 | controller -> localhost | changed 2026-02-01 00:29:53.996280 | 2026-02-01 00:29:53.996575 | TASK [add-build-sshkey : Add back temp key] 2026-02-01 00:29:54.260230 | controller -> localhost | Identity added: /var/lib/zuul/builds/944f5555e272436e955f9f2773547b1c/work/944f5555e272436e955f9f2773547b1c_id_ecdsa (zuul-build-sshkey) 2026-02-01 00:29:54.260637 | controller -> localhost | ok: Runtime: 0:00:00.012884 2026-02-01 00:29:54.276274 | 2026-02-01 00:29:54.276430 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-02-01 00:29:54.472896 | controller | ok 2026-02-01 00:29:54.478386 | 2026-02-01 00:29:54.478452 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-02-01 00:29:54.517566 | controller | skipping: Conditional result was False 2026-02-01 00:29:54.546425 | 2026-02-01 00:29:54.546595 | TASK [include_role : remove-zuul-sshkey] 2026-02-01 00:29:54.574991 | controller | skipping: Conditional result was False 2026-02-01 00:29:54.590240 | 2026-02-01 00:29:54.590452 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-02-01 00:29:54.848643 | controller | ok: "logs" 2026-02-01 00:29:54.848996 | controller | ok: All items complete 2026-02-01 00:29:54.849037 | 2026-02-01 00:29:55.040323 | controller | ok: "artifacts" 2026-02-01 00:29:55.240052 | controller | ok: "docs" 2026-02-01 00:29:55.253060 | 2026-02-01 00:29:55.253180 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-02-01 00:29:55.504906 | controller | changed: "logs" 2026-02-01 00:29:55.690349 | controller | changed: "artifacts" 2026-02-01 00:29:55.903829 | controller | changed: "docs" 2026-02-01 00:29:55.961470 | 2026-02-01 00:29:55.961611 | PLAY RECAP 2026-02-01 00:29:55.961671 | controller | ok: 30 changed: 16 unreachable: 0 failed: 0 skipped: 18 rescued: 0 ignored: 0 2026-02-01 00:29:55.961732 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-02-01 00:29:55.961756 | 2026-02-01 00:29:56.208345 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master] 2026-02-01 00:29:56.209384 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2026-02-01 00:29:56.853846 | 2026-02-01 00:29:56.853991 | PLAY [all] 2026-02-01 00:29:56.878668 | 2026-02-01 00:29:56.878852 | TASK [Install binary dependencies] 2026-02-01 00:29:56.961109 | controller | ok 2026-02-01 00:29:56.983185 | 2026-02-01 00:29:56.983534 | TASK [bindep : Include find tasks] 2026-02-01 00:29:57.024821 | controller | ok 2026-02-01 00:29:57.033163 | controller | included: /var/lib/zuul/builds/944f5555e272436e955f9f2773547b1c/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find.yaml 2026-02-01 00:29:57.040054 | 2026-02-01 00:29:57.040162 | TASK [bindep : Look for bindep.txt] 2026-02-01 00:29:57.590401 | controller | ok 2026-02-01 00:29:57.601478 | 2026-02-01 00:29:57.601603 | TASK [bindep : Define bindep_file fact] 2026-02-01 00:29:57.629653 | controller | skipping: Conditional result was False 2026-02-01 00:29:57.645944 | 2026-02-01 00:29:57.646075 | TASK [bindep : Look for other-requirements.txt] 2026-02-01 00:29:57.871430 | controller | ok 2026-02-01 00:29:57.882471 | 2026-02-01 00:29:57.882587 | TASK [bindep : Define bindep_file fact] 2026-02-01 00:29:57.907351 | controller | skipping: Conditional result was False 2026-02-01 00:29:57.915824 | 2026-02-01 00:29:57.915908 | TASK [bindep : Look for bindep fallback file] 2026-02-01 00:29:57.940351 | controller | skipping: Conditional result was False 2026-02-01 00:29:57.948079 | 2026-02-01 00:29:57.948174 | TASK [bindep : Define bindep_file fact] 2026-02-01 00:29:57.971994 | controller | skipping: Conditional result was False 2026-02-01 00:29:57.981373 | 2026-02-01 00:29:57.981501 | TASK [bindep : Include bindep tasks] 2026-02-01 00:29:58.017899 | controller | skipping: Conditional result was False 2026-02-01 00:29:58.031576 | 2026-02-01 00:29:58.031759 | TASK [bindep : Include install tasks] 2026-02-01 00:29:58.059770 | controller | skipping: Conditional result was False 2026-02-01 00:29:58.073515 | 2026-02-01 00:29:58.073672 | LOOP [bindep : Include package tasks] 2026-02-01 00:29:58.146167 | 2026-02-01 00:29:58.146479 | TASK [Run test-setup role] 2026-02-01 00:29:58.179197 | controller | ok 2026-02-01 00:29:58.208821 | 2026-02-01 00:29:58.208990 | TASK [test-setup : Check if project's tools/test-setup.sh exists] 2026-02-01 00:29:58.428242 | controller | ok 2026-02-01 00:29:58.438314 | 2026-02-01 00:29:58.438491 | TASK [test-setup : Run tools/test-setup.sh] 2026-02-01 00:29:58.988584 | controller | skipping: Conditional result was False 2026-02-01 00:29:59.048988 | 2026-02-01 00:29:59.049197 | PLAY RECAP 2026-02-01 00:29:59.049288 | controller | ok: 4 changed: 0 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2026-02-01 00:29:59.049321 | 2026-02-01 00:29:59.210838 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2026-02-01 00:29:59.211791 | PRE-RUN START: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master] 2026-02-01 00:29:59.967523 | 2026-02-01 00:29:59.967669 | PLAY [controller] 2026-02-01 00:29:59.993425 | 2026-02-01 00:29:59.993587 | TASK [Create the /root directory] 2026-02-01 00:30:00.653421 | controller | ok 2026-02-01 00:30:00.670364 | 2026-02-01 00:30:00.670619 | TASK [Install glibc-langpack-en] 2026-02-01 00:30:04.617382 | controller | ok: Nothing to do 2026-02-01 00:30:04.632187 | 2026-02-01 00:30:04.632348 | TASK [Ensure controller directory exists] 2026-02-01 00:30:04.859771 | controller | changed 2026-02-01 00:30:04.875058 | 2026-02-01 00:30:04.875278 | TASK [Install container runtime] 2026-02-01 00:30:04.931942 | controller | ok 2026-02-01 00:30:05.000049 | 2026-02-01 00:30:05.000202 | LOOP [ensure-docker : Gather variables for each operating system] 2026-02-01 00:30:05.065664 | controller | ok: "/var/lib/zuul/builds/944f5555e272436e955f9f2773547b1c/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/vars/fedora.yaml" 2026-02-01 00:30:05.085586 | 2026-02-01 00:30:05.085897 | TASK [ensure-docker : Sanity check for distro selection] 2026-02-01 00:30:05.113967 | controller | skipping: Conditional result was False 2026-02-01 00:30:05.127893 | 2026-02-01 00:30:05.128079 | TASK [ensure-docker : Sanity check for distro install] 2026-02-01 00:30:05.165922 | controller | skipping: Conditional result was False 2026-02-01 00:30:05.181059 | 2026-02-01 00:30:05.181304 | TASK [ensure-docker : Sanity check for upstream install] 2026-02-01 00:30:05.219663 | controller | skipping: Conditional result was False 2026-02-01 00:30:05.235409 | 2026-02-01 00:30:05.235719 | TASK [ensure-docker : Sanity check for upstream install mirrors] 2026-02-01 00:30:05.273622 | controller | skipping: Conditional result was False 2026-02-01 00:30:05.291889 | 2026-02-01 00:30:05.292077 | TASK [ensure-docker : Include tasks for docker install] 2026-02-01 00:30:05.333130 | controller | ok 2026-02-01 00:30:05.355047 | controller | included: /var/lib/zuul/builds/944f5555e272436e955f9f2773547b1c/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/tasks/docker-upstream.yaml 2026-02-01 00:30:05.373031 | 2026-02-01 00:30:05.373158 | TASK [ensure-docker : Not Implemented] 2026-02-01 00:30:05.398999 | controller | skipping: Conditional result was False 2026-02-01 00:30:05.408444 | 2026-02-01 00:30:05.408534 | TASK [Set up docker mirrors] 2026-02-01 00:30:05.433771 | controller | ok 2026-02-01 00:30:05.463509 | 2026-02-01 00:30:05.463669 | TASK [use-docker-mirror : Set docker_mirror fact from mirror_info] 2026-02-01 00:30:05.500847 | controller | skipping: Conditional result was False 2026-02-01 00:30:05.508311 | 2026-02-01 00:30:05.508409 | TASK [use-docker-mirror : Set docker_mirror fact from zuul_site_mirror_info] 2026-02-01 00:30:05.542764 | controller | skipping: Conditional result was False 2026-02-01 00:30:05.549710 | 2026-02-01 00:30:05.549787 | TASK [use-docker-mirror : Set docker_mirror fact] 2026-02-01 00:30:05.583972 | controller | skipping: Conditional result was False 2026-02-01 00:30:05.590658 | 2026-02-01 00:30:05.590876 | TASK [use-docker-mirror : Set up docker mirrors] 2026-02-01 00:30:05.614384 | controller | skipping: Conditional result was False 2026-02-01 00:30:05.627430 | 2026-02-01 00:30:05.627540 | TASK [Add all repositories] 2026-02-01 00:30:05.647220 | controller | ok 2026-02-01 00:30:05.671591 | 2026-02-01 00:30:05.671670 | LOOP [ensure-package-repositories : Install repositories] 2026-02-01 00:30:05.704096 | controller | ok: "/var/lib/zuul/builds/944f5555e272436e955f9f2773547b1c/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-package-repositories/tasks/RedHat.yaml" 2026-02-01 00:30:05.704309 | controller | ok: All items complete 2026-02-01 00:30:05.704341 | 2026-02-01 00:30:05.757450 | controller | included: /var/lib/zuul/builds/944f5555e272436e955f9f2773547b1c/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-package-repositories/tasks/RedHat.yaml 2026-02-01 00:30:05.764109 | 2026-02-01 00:30:05.764174 | TASK [ensure-package-repositories : Create GPG key temp directory] 2026-02-01 00:30:06.117246 | controller | changed 2026-02-01 00:30:06.134114 | 2026-02-01 00:30:06.134374 | LOOP [ensure-package-repositories : Upload GPG keys] 2026-02-01 00:30:07.088804 | controller | changed: 2026-02-01 00:30:07.089089 | controller | { 2026-02-01 00:30:07.089145 | controller | "data": "-----BEGIN PGP PUBLIC KEY BLOCK-----\n\nmQINBFit5IEBEADDt86QpYKz5flnCsOyZ/fk3WwBKxfDjwHf/GIflo+4GWAXS7wJ\n1PSzPsvSDATV10J44i5WQzh99q+lZvFCVRFiNhRmlmcXG+rk1QmDh3fsCCj9Q/yP\nw8jn3Hx0zDtz8PIB/18ReftYJzUo34COLiHn8WiY20uGCF2pjdPgfxE+K454c4G7\ngKFqVUFYgPug2CS0quaBB5b0rpFUdzTeI5RCStd27nHCpuSDCvRYAfdv+4Y1yiVh\nKKdoe3Smj+RnXeVMgDxtH9FJibZ3DK7WnMN2yeob6VqXox+FvKYJCCLkbQgQmE50\nuVK0uN71A1mQDcTRKQ2q3fFGlMTqJbbzr3LwnCBE6hV0a36t+DABtZTmz5O69xdJ\nWGdBeePCnWVqtDb/BdEYz7hPKskcZBarygCCe2Xi7sZieoFZuq6ltPoCsdfEdfbO\n+VBVKJnExqNZCcFUTEnbH4CldWROOzMS8BGUlkGpa59Sl1t0QcmWlw1EbkeMQNrN\nspdR8lobcdNS9bpAJQqSHRZh3cAM9mA3Yq/bssUS/P2quRXLjJ9mIv3dky9C3udM\n+q2unvnbNpPtIUly76FJ3s8g8sHeOnmYcKqNGqHq2Q3kMdA2eIbI0MqfOIo2+Xk0\nrNt3ctq3g+cQiorcN3rdHPsTRSAcp+NCz1QF9TwXYtH1XV24A6QMO0+CZwARAQAB\ntCtEb2NrZXIgUmVsZWFzZSAoQ0UgcnBtKSA8ZG9ja2VyQGRvY2tlci5jb20+iQI3\nBBMBCgAhBQJYrep4AhsvBQsJCAcDBRUKCQgLBRYCAwEAAh4BAheAAAoJEMUv62ti\nHp816C0P/iP+1uhSa6Qq3TIc5sIFE5JHxOO6y0R97cUdAmCbEqBiJHUPNQDQaaRG\nVYBm0K013Q1gcJeUJvS32gthmIvhkstw7KTodwOM8Kl11CCqZ07NPFef1b2SaJ7l\nTYpyUsT9+e343ph+O4C1oUQw6flaAJe+8ATCmI/4KxfhIjD2a/Q1voR5tUIxfexC\n/LZTx05gyf2mAgEWlRm/cGTStNfqDN1uoKMlV+WFuB1j2oTUuO1/dr8mL+FgZAM3\nntWFo9gQCllNV9ahYOON2gkoZoNuPUnHsf4Bj6BQJnIXbAhMk9H2sZzwUi9bgObZ\nXO8+OrP4D4B9kCAKqqaQqA+O46LzO2vhN74lm/Fy6PumHuviqDBdN+HgtRPMUuao\nxnuVJSvBu9sPdgT/pR1N9u/KnfAnnLtR6g+fx4mWz+ts/riB/KRHzXd+44jGKZra\nIhTMfniguMJNsyEOO0AN8Tqcl0eRBxcOArcri7xu8HFvvl+e+ILymu4buusbYEVL\nGBkYP5YMmScfKn+jnDVN4mWoN1Bq2yMhMGx6PA3hOvzPNsUoYy2BwDxNZyflzuAi\ng59mgJm2NXtzNbSRJbMamKpQ69mzLWGdFNsRd4aH7PT7uPAURaf7B5BVp3UyjERW\n5alSGnBqsZmvlRnVH5BDUhYsWZMPRQS9rRr4iGW0l+TH+O2VJ8aQ\n=0Zqq\n-----END PGP PUBLIC KEY BLOCK-----\n" 2026-02-01 00:30:07.089193 | controller | } 2026-02-01 00:30:07.105802 | 2026-02-01 00:30:07.106010 | LOOP [ensure-package-repositories : Import GPG keys] 2026-02-01 00:30:07.701328 | controller | changed: 2026-02-01 00:30:07.701709 | controller | { 2026-02-01 00:30:07.701779 | controller | "data": "-----BEGIN PGP PUBLIC KEY BLOCK-----\n\nmQINBFit5IEBEADDt86QpYKz5flnCsOyZ/fk3WwBKxfDjwHf/GIflo+4GWAXS7wJ\n1PSzPsvSDATV10J44i5WQzh99q+lZvFCVRFiNhRmlmcXG+rk1QmDh3fsCCj9Q/yP\nw8jn3Hx0zDtz8PIB/18ReftYJzUo34COLiHn8WiY20uGCF2pjdPgfxE+K454c4G7\ngKFqVUFYgPug2CS0quaBB5b0rpFUdzTeI5RCStd27nHCpuSDCvRYAfdv+4Y1yiVh\nKKdoe3Smj+RnXeVMgDxtH9FJibZ3DK7WnMN2yeob6VqXox+FvKYJCCLkbQgQmE50\nuVK0uN71A1mQDcTRKQ2q3fFGlMTqJbbzr3LwnCBE6hV0a36t+DABtZTmz5O69xdJ\nWGdBeePCnWVqtDb/BdEYz7hPKskcZBarygCCe2Xi7sZieoFZuq6ltPoCsdfEdfbO\n+VBVKJnExqNZCcFUTEnbH4CldWROOzMS8BGUlkGpa59Sl1t0QcmWlw1EbkeMQNrN\nspdR8lobcdNS9bpAJQqSHRZh3cAM9mA3Yq/bssUS/P2quRXLjJ9mIv3dky9C3udM\n+q2unvnbNpPtIUly76FJ3s8g8sHeOnmYcKqNGqHq2Q3kMdA2eIbI0MqfOIo2+Xk0\nrNt3ctq3g+cQiorcN3rdHPsTRSAcp+NCz1QF9TwXYtH1XV24A6QMO0+CZwARAQAB\ntCtEb2NrZXIgUmVsZWFzZSAoQ0UgcnBtKSA8ZG9ja2VyQGRvY2tlci5jb20+iQI3\nBBMBCgAhBQJYrep4AhsvBQsJCAcDBRUKCQgLBRYCAwEAAh4BAheAAAoJEMUv62ti\nHp816C0P/iP+1uhSa6Qq3TIc5sIFE5JHxOO6y0R97cUdAmCbEqBiJHUPNQDQaaRG\nVYBm0K013Q1gcJeUJvS32gthmIvhkstw7KTodwOM8Kl11CCqZ07NPFef1b2SaJ7l\nTYpyUsT9+e343ph+O4C1oUQw6flaAJe+8ATCmI/4KxfhIjD2a/Q1voR5tUIxfexC\n/LZTx05gyf2mAgEWlRm/cGTStNfqDN1uoKMlV+WFuB1j2oTUuO1/dr8mL+FgZAM3\nntWFo9gQCllNV9ahYOON2gkoZoNuPUnHsf4Bj6BQJnIXbAhMk9H2sZzwUi9bgObZ\nXO8+OrP4D4B9kCAKqqaQqA+O46LzO2vhN74lm/Fy6PumHuviqDBdN+HgtRPMUuao\nxnuVJSvBu9sPdgT/pR1N9u/KnfAnnLtR6g+fx4mWz+ts/riB/KRHzXd+44jGKZra\nIhTMfniguMJNsyEOO0AN8Tqcl0eRBxcOArcri7xu8HFvvl+e+ILymu4buusbYEVL\nGBkYP5YMmScfKn+jnDVN4mWoN1Bq2yMhMGx6PA3hOvzPNsUoYy2BwDxNZyflzuAi\ng59mgJm2NXtzNbSRJbMamKpQ69mzLWGdFNsRd4aH7PT7uPAURaf7B5BVp3UyjERW\n5alSGnBqsZmvlRnVH5BDUhYsWZMPRQS9rRr4iGW0l+TH+O2VJ8aQ\n=0Zqq\n-----END PGP PUBLIC KEY BLOCK-----\n" 2026-02-01 00:30:07.701829 | controller | } 2026-02-01 00:30:07.701890 | controller | changed: All items complete 2026-02-01 00:30:07.701931 | 2026-02-01 00:30:07.722259 | 2026-02-01 00:30:07.722511 | TASK [ensure-package-repositories : Remove GPG temp directory] 2026-02-01 00:30:07.943217 | controller | changed 2026-02-01 00:30:07.952706 | 2026-02-01 00:30:07.952833 | LOOP [ensure-package-repositories : Add repositories] 2026-02-01 00:30:08.271412 | controller | changed: 2026-02-01 00:30:08.271665 | controller | { 2026-02-01 00:30:08.271717 | controller | "baseurl": "https://download.docker.com/linux/fedora/$releasever/$basearch/stable", 2026-02-01 00:30:08.271740 | controller | "description": "Docker CE Stable - $basearch", 2026-02-01 00:30:08.271758 | controller | "gpgcheck": true, 2026-02-01 00:30:08.271774 | controller | "name": "docker-ce-stable" 2026-02-01 00:30:08.271791 | controller | } 2026-02-01 00:30:08.287788 | 2026-02-01 00:30:08.288087 | TASK [ensure-docker : Install pre-reqs] 2026-02-01 00:30:10.694077 | controller | ok: Nothing to do 2026-02-01 00:30:10.709161 | 2026-02-01 00:30:10.709316 | TASK [ensure-docker : Remove packages] 2026-02-01 00:30:12.071149 | controller | ok: Nothing to do 2026-02-01 00:30:12.080671 | 2026-02-01 00:30:12.080859 | TASK [ensure-docker : Install upstream docker using package] 2026-02-01 00:30:58.789832 | controller | changed 2026-02-01 00:30:58.802884 | 2026-02-01 00:30:58.803013 | TASK [ensure-docker : Setup docker] 2026-02-01 00:30:58.832104 | controller | ok 2026-02-01 00:30:58.855811 | controller | included: /var/lib/zuul/builds/944f5555e272436e955f9f2773547b1c/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/tasks/docker-setup.yaml 2026-02-01 00:30:58.875827 | 2026-02-01 00:30:58.876001 | TASK [ensure-docker : Ensure "docker" group exists] 2026-02-01 00:30:59.270527 | controller | ok 2026-02-01 00:30:59.283535 | 2026-02-01 00:30:59.283670 | TASK [ensure-docker : Add user to docker group] 2026-02-01 00:30:59.815717 | controller | changed 2026-02-01 00:30:59.827214 | 2026-02-01 00:30:59.827391 | TASK [Update docker daemon proxy configuration] 2026-02-01 00:30:59.863446 | controller | skipping: Conditional result was False 2026-02-01 00:30:59.871051 | 2026-02-01 00:30:59.871146 | TASK [Lower default MTU] 2026-02-01 00:30:59.895946 | controller | skipping: Conditional result was False 2026-02-01 00:30:59.904109 | 2026-02-01 00:30:59.904190 | TASK [ensure-docker : Get the status of the docket.socket unit] 2026-02-01 00:31:00.272337 | controller | inactive 2026-02-01 00:31:00.476709 | controller | ok: Runtime: 0:00:00.010253 2026-02-01 00:31:00.486257 | 2026-02-01 00:31:00.486355 | TASK [ensure-docker : Get the status of the docket unit] 2026-02-01 00:31:00.716451 | controller | inactive 2026-02-01 00:31:01.031328 | controller | ok: Runtime: 0:00:00.007988 2026-02-01 00:31:01.045045 | 2026-02-01 00:31:01.045207 | TASK [ensure-docker : Restart docker] 2026-02-01 00:31:01.094732 | Trigger a Docker restart 2026-02-01 00:31:01.107116 | 2026-02-01 00:31:01.107257 | TASK [ensure-docker : Reset ssh connection to pick up docker group] 2026-02-01 00:31:01.160452 | 2026-02-01 00:31:01.160649 | TASK [ensure-docker : Install docker-compose] 2026-02-01 00:31:01.188196 | controller | skipping: Conditional result was False 2026-02-01 00:31:01.201915 | 2026-02-01 00:31:01.202074 | TASK [ensure-docker : Flush handlers before role exit] 2026-02-01 00:31:01.222428 | 2026-02-01 00:31:01.222525 | TASK [ensure-docker : Stop docker.socket to avoid any conflict] 2026-02-01 00:31:02.545593 | controller | changed 2026-02-01 00:31:02.562477 | 2026-02-01 00:31:02.562937 | TASK [ensure-docker : Assure docker service is running] 2026-02-01 00:31:04.201246 | controller | changed 2026-02-01 00:31:04.209658 | 2026-02-01 00:31:04.209791 | TASK [ensure-docker : Assure docker.socket service is running] 2026-02-01 00:31:05.195521 | controller | ok 2026-02-01 00:31:05.204047 | 2026-02-01 00:31:05.204141 | TASK [ensure-docker : Correct group ownership on docker sock] 2026-02-01 00:31:05.785538 | controller | ok 2026-02-01 00:31:05.797900 | 2026-02-01 00:31:05.798033 | TASK [ensure-docker : Validate ability to talk with docker] 2026-02-01 00:31:06.287950 | controller | CONTAINER ID IMAGE COMMAND CREATED STATUS PORTS NAMES 2026-02-01 00:31:06.349566 | controller | ok: Runtime: 0:00:00.061550 2026-02-01 00:31:06.364085 | 2026-02-01 00:31:06.364237 | TASK [ensure-docker : Get version details] 2026-02-01 00:31:06.903460 | controller | Client: Docker Engine - Community 2026-02-01 00:31:06.903687 | controller | Version: 24.0.7 2026-02-01 00:31:06.903760 | controller | API version: 1.43 2026-02-01 00:31:06.903780 | controller | Go version: go1.20.10 2026-02-01 00:31:06.903794 | controller | Git commit: afdd53b 2026-02-01 00:31:06.903799 | controller | Built: Thu Oct 26 09:09:39 2023 2026-02-01 00:31:06.903804 | controller | OS/Arch: linux/amd64 2026-02-01 00:31:06.903810 | controller | Context: default 2026-02-01 00:31:06.903817 | controller | 2026-02-01 00:31:06.903822 | controller | Server: Docker Engine - Community 2026-02-01 00:31:06.903826 | controller | Engine: 2026-02-01 00:31:06.903831 | controller | Version: 24.0.7 2026-02-01 00:31:06.903835 | controller | API version: 1.43 (minimum version 1.12) 2026-02-01 00:31:06.903840 | controller | Go version: go1.20.10 2026-02-01 00:31:06.903844 | controller | Git commit: 311b9ff 2026-02-01 00:31:06.903849 | controller | Built: Thu Oct 26 09:08:11 2023 2026-02-01 00:31:06.903853 | controller | OS/Arch: linux/amd64 2026-02-01 00:31:06.903858 | controller | Experimental: false 2026-02-01 00:31:06.903862 | controller | containerd: 2026-02-01 00:31:06.903866 | controller | Version: 1.6.25 2026-02-01 00:31:06.903871 | controller | GitCommit: d8f198a4ed8892c764191ef7b3b06d8a2eeb5c7f 2026-02-01 00:31:06.903875 | controller | runc: 2026-02-01 00:31:06.903879 | controller | Version: 1.1.10 2026-02-01 00:31:06.903884 | controller | GitCommit: v1.1.10-0-g18a0cb0 2026-02-01 00:31:06.903890 | controller | docker-init: 2026-02-01 00:31:06.903894 | controller | Version: 0.19.0 2026-02-01 00:31:06.903899 | controller | GitCommit: de40ad0 2026-02-01 00:31:06.931441 | controller | ok: Runtime: 0:00:00.028039 2026-02-01 00:31:06.939030 | 2026-02-01 00:31:06.939176 | TASK [ensure-docker : Dump installed docker details] 2026-02-01 00:31:06.970421 | {'changed': True, 'stdout': 'Client: Docker Engine - Community\n Version: 24.0.7\n API version: 1.43\n Go version: go1.20.10\n Git commit: afdd53b\n Built: Thu Oct 26 09:09:39 2023\n OS/Arch: linux/amd64\n Context: default\n\nServer: Docker Engine - Community\n Engine:\n Version: 24.0.7\n API version: 1.43 (minimum version 1.12)\n Go version: go1.20.10\n Git commit: 311b9ff\n Built: Thu Oct 26 09:08:11 2023\n OS/Arch: linux/amd64\n Experimental: false\n containerd:\n Version: 1.6.25\n GitCommit: d8f198a4ed8892c764191ef7b3b06d8a2eeb5c7f\n runc:\n Version: 1.1.10\n GitCommit: v1.1.10-0-g18a0cb0\n docker-init:\n Version: 0.19.0\n GitCommit: de40ad0', 'stderr': '', 'rc': 0, 'cmd': ['docker', 'version'], 'start': '2026-02-01 00:31:06.876119', 'end': '2026-02-01 00:31:06.904158', 'delta': '0:00:00.028039', 'msg': '', 'zuul_log_id': 'fa163e3b-3c83-834a-0232-000000000061-1-controller', 'stdout_lines': ['Client: Docker Engine - Community', ' Version: 24.0.7', ' API version: 1.43', ' Go version: go1.20.10', ' Git commit: afdd53b', ' Built: Thu Oct 26 09:09:39 2023', ' OS/Arch: linux/amd64', ' Context: default', '', 'Server: Docker Engine - Community', ' Engine:', ' Version: 24.0.7', ' API version: 1.43 (minimum version 1.12)', ' Go version: go1.20.10', ' Git commit: 311b9ff', ' Built: Thu Oct 26 09:08:11 2023', ' OS/Arch: linux/amd64', ' Experimental: false', ' containerd:', ' Version: 1.6.25', ' GitCommit: d8f198a4ed8892c764191ef7b3b06d8a2eeb5c7f', ' runc:', ' Version: 1.1.10', ' GitCommit: v1.1.10-0-g18a0cb0', ' docker-init:', ' Version: 0.19.0', ' GitCommit: de40ad0'], 'stderr_lines': [], 'failed': False} 2026-02-01 00:31:06.977767 | 2026-02-01 00:31:06.977889 | TASK [Use buildset registry] 2026-02-01 00:31:07.002303 | controller | skipping: Conditional result was False 2026-02-01 00:31:07.015226 | 2026-02-01 00:31:07.015368 | TASK [Ensure python3.8 is present] 2026-02-01 00:31:07.030952 | controller | skipping: Conditional result was False 2026-02-01 00:31:07.038003 | 2026-02-01 00:31:07.038090 | TASK [Run our-ensure-python role (Fedora only for now)] 2026-02-01 00:31:07.062144 | controller | ok 2026-02-01 00:31:07.082124 | 2026-02-01 00:31:07.082231 | TASK [our-ensure-python : Install the right Python version (rpm)] 2026-02-01 00:31:09.146135 | controller | ok: Nothing to do 2026-02-01 00:31:09.155525 | 2026-02-01 00:31:09.155640 | TASK [our-ensure-python : Also install python3-devel] 2026-02-01 00:31:21.730172 | controller | changed 2026-02-01 00:31:21.780367 | 2026-02-01 00:31:21.780608 | TASK [Run ensure-virtualenv role] 2026-02-01 00:31:21.816742 | controller | ok 2026-02-01 00:31:21.842143 | 2026-02-01 00:31:21.842258 | TASK [ensure-virtualenv : Check if virtualenv is installed] 2026-02-01 00:31:22.077125 | controller | /usr/bin/virtualenv 2026-02-01 00:31:22.380415 | controller | ok: Runtime: 0:00:00.003365 2026-02-01 00:31:22.389443 | 2026-02-01 00:31:22.389547 | LOOP [ensure-virtualenv : Install virtualenv package] 2026-02-01 00:31:22.428259 | controller | skipping: Conditional result was False 2026-02-01 00:31:22.428531 | controller | changed: All items complete 2026-02-01 00:31:22.428559 | 2026-02-01 00:31:22.449602 | 2026-02-01 00:31:22.449724 | TASK [Find the full path of the Python interpreter] 2026-02-01 00:31:22.680053 | controller | /usr/bin/python3.9 2026-02-01 00:31:22.988099 | controller | ok 2026-02-01 00:31:22.999200 | 2026-02-01 00:31:22.999445 | TASK [Create virtualenv for ansible-test with a specific interpreter] 2026-02-01 00:31:24.593521 | controller | created virtual environment CPython3.9.18.final.0-64 in 943ms 2026-02-01 00:31:24.621189 | controller | creator CPython3Posix(dest=/home/zuul/venv, clear=False, no_vcs_ignore=False, global=False) 2026-02-01 00:31:24.621207 | controller | seeder FromAppData(extra_search_dir=/usr/share/python-wheels,download=False, pip=bundle, setuptools=bundle, wheel=bundle, via=copy, app_data_dir=/home/zuul/.local/share/virtualenv) 2026-02-01 00:31:24.621224 | controller | added seed packages: pip==22.2.2, setuptools==62.6.0, wheel==0.37.1 2026-02-01 00:31:24.621237 | controller | activators BashActivator,CShellActivator,FishActivator,NushellActivator,PowerShellActivator,PythonActivator 2026-02-01 00:31:25.047558 | controller | changed 2026-02-01 00:31:25.057293 | 2026-02-01 00:31:25.057476 | TASK [Set selinux package] 2026-02-01 00:31:25.083703 | controller | ok 2026-02-01 00:31:25.091705 | 2026-02-01 00:31:25.091861 | TASK [Set selinux package (Fedora)] 2026-02-01 00:31:25.130556 | controller | ok 2026-02-01 00:31:25.142920 | 2026-02-01 00:31:25.143133 | TASK [Install selinux into virtualenv] 2026-02-01 00:31:28.200225 | controller | Collecting selinux-please-lie-to-me 2026-02-01 00:31:28.280943 | controller | Downloading selinux_please_lie_to_me-1.0.1-py2.py3-none-any.whl (3.8 kB) 2026-02-01 00:31:29.166582 | controller | Collecting setuptools<50.0.0 2026-02-01 00:31:29.183479 | controller | Downloading setuptools-49.6.0-py3-none-any.whl (803 kB) 2026-02-01 00:31:29.234865 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 803.3/803.3 kB 17.3 MB/s eta 0:00:00 2026-02-01 00:31:29.453253 | controller | Installing collected packages: setuptools, selinux-please-lie-to-me 2026-02-01 00:31:29.455887 | controller | Attempting uninstall: setuptools 2026-02-01 00:31:29.461016 | controller | Found existing installation: setuptools 62.6.0 2026-02-01 00:31:29.653563 | controller | Uninstalling setuptools-62.6.0: 2026-02-01 00:31:29.681908 | controller | Successfully uninstalled setuptools-62.6.0 2026-02-01 00:31:30.410500 | controller | Successfully installed selinux-please-lie-to-me-1.0.1 setuptools-49.6.0 2026-02-01 00:31:30.673615 | controller | 2026-02-01 00:31:30.882470 | controller | [notice] A new release of pip available: 22.2.2 -> 26.0 2026-02-01 00:31:30.882505 | controller | [notice] To update, run: /home/zuul/venv/bin/python -m pip install --upgrade pip 2026-02-01 00:31:31.226272 | controller | ok: Runtime: 0:00:05.165386 2026-02-01 00:31:31.241460 | 2026-02-01 00:31:31.241628 | TASK [Install pytest-forked into virtualenv] 2026-02-01 00:31:32.182408 | controller | Collecting pytest-forked 2026-02-01 00:31:32.238656 | controller | Downloading pytest_forked-1.6.0-py3-none-any.whl (4.9 kB) 2026-02-01 00:31:32.312086 | controller | Collecting py 2026-02-01 00:31:32.317521 | controller | Downloading py-1.11.0-py2.py3-none-any.whl (98 kB) 2026-02-01 00:31:32.333784 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 98.7/98.7 kB 11.4 MB/s eta 0:00:00 2026-02-01 00:31:32.481927 | controller | Collecting pytest>=3.10 2026-02-01 00:31:32.485242 | controller | Downloading pytest-8.4.2-py3-none-any.whl (365 kB) 2026-02-01 00:31:32.501397 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 365.8/365.8 kB 30.3 MB/s eta 0:00:00 2026-02-01 00:31:32.615599 | controller | Collecting pluggy<2,>=1.5 2026-02-01 00:31:32.622987 | controller | Downloading pluggy-1.6.0-py3-none-any.whl (20 kB) 2026-02-01 00:31:32.684444 | controller | Collecting exceptiongroup>=1 2026-02-01 00:31:32.694417 | controller | Downloading exceptiongroup-1.3.1-py3-none-any.whl (16 kB) 2026-02-01 00:31:32.796408 | controller | Collecting pygments>=2.7.2 2026-02-01 00:31:32.807322 | controller | Downloading pygments-2.19.2-py3-none-any.whl (1.2 MB) 2026-02-01 00:31:32.836270 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 1.2/1.2 MB 46.4 MB/s eta 0:00:00 2026-02-01 00:31:32.981708 | controller | Collecting tomli>=1 2026-02-01 00:31:32.988694 | controller | Downloading tomli-2.4.0-py3-none-any.whl (14 kB) 2026-02-01 00:31:33.108360 | controller | Collecting packaging>=20 2026-02-01 00:31:33.119025 | controller | Downloading packaging-26.0-py3-none-any.whl (74 kB) 2026-02-01 00:31:33.130868 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 74.4/74.4 kB 9.8 MB/s eta 0:00:00 2026-02-01 00:31:33.172291 | controller | Collecting iniconfig>=1 2026-02-01 00:31:33.182661 | controller | Downloading iniconfig-2.1.0-py3-none-any.whl (6.0 kB) 2026-02-01 00:31:33.296172 | controller | Collecting typing-extensions>=4.6.0 2026-02-01 00:31:33.300657 | controller | Downloading typing_extensions-4.15.0-py3-none-any.whl (44 kB) 2026-02-01 00:31:33.309433 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 44.6/44.6 kB 6.8 MB/s eta 0:00:00 2026-02-01 00:31:33.510087 | controller | Installing collected packages: typing-extensions, tomli, pygments, py, pluggy, packaging, iniconfig, exceptiongroup, pytest, pytest-forked 2026-02-01 00:31:35.593836 | controller | Successfully installed exceptiongroup-1.3.1 iniconfig-2.1.0 packaging-26.0 pluggy-1.6.0 py-1.11.0 pygments-2.19.2 pytest-8.4.2 pytest-forked-1.6.0 tomli-2.4.0 typing-extensions-4.15.0 2026-02-01 00:31:35.597970 | controller | 2026-02-01 00:31:35.665591 | controller | [notice] A new release of pip available: 22.2.2 -> 26.0 2026-02-01 00:31:35.665635 | controller | [notice] To update, run: /home/zuul/venv/bin/python -m pip install --upgrade pip 2026-02-01 00:31:35.845411 | controller | ok: Runtime: 0:00:03.985415 2026-02-01 00:31:35.860172 | 2026-02-01 00:31:35.860447 | TASK [Update pip] 2026-02-01 00:31:37.324378 | controller | Requirement already satisfied: pip in ./venv/lib/python3.9/site-packages (22.2.2) 2026-02-01 00:31:37.683289 | controller | Collecting pip 2026-02-01 00:31:37.760058 | controller | Downloading pip-26.0-py3-none-any.whl (1.8 MB) 2026-02-01 00:31:37.841917 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 1.8/1.8 MB 23.1 MB/s eta 0:00:00 2026-02-01 00:31:37.899068 | controller | Installing collected packages: pip 2026-02-01 00:31:37.899243 | controller | Attempting uninstall: pip 2026-02-01 00:31:37.899842 | controller | Found existing installation: pip 22.2.2 2026-02-01 00:31:38.382734 | controller | Uninstalling pip-22.2.2: 2026-02-01 00:31:38.433213 | controller | Successfully uninstalled pip-22.2.2 2026-02-01 00:31:40.418651 | controller | Successfully installed pip-26.0 2026-02-01 00:31:40.919090 | controller | ok: Runtime: 0:00:04.209847 2026-02-01 00:31:40.934875 | 2026-02-01 00:31:40.935081 | TASK [Ensure pip temp directory exists in /var/tmp] 2026-02-01 00:31:41.296907 | controller | changed 2026-02-01 00:31:41.311396 | 2026-02-01 00:31:41.311541 | TASK [Install ansible into virtualenv] 2026-02-01 00:31:42.676113 | controller | Processing ./src/github.com/ansible/ansible 2026-02-01 00:31:42.689768 | controller | Installing build dependencies: started 2026-02-01 00:31:44.664702 | controller | Installing build dependencies: finished with status 'done' 2026-02-01 00:31:44.665626 | controller | Getting requirements to build wheel: started 2026-02-01 00:31:46.071144 | controller | Getting requirements to build wheel: finished with status 'done' 2026-02-01 00:31:46.072371 | controller | Preparing metadata (pyproject.toml): started 2026-02-01 00:31:46.537298 | controller | Preparing metadata (pyproject.toml): finished with status 'done' 2026-02-01 00:31:46.621197 | controller | Collecting jinja2 (from ansible-core==2.11.12.post0) 2026-02-01 00:31:46.652992 | controller | Downloading jinja2-3.1.6-py3-none-any.whl.metadata (2.9 kB) 2026-02-01 00:31:46.738062 | controller | Collecting PyYAML (from ansible-core==2.11.12.post0) 2026-02-01 00:31:46.742470 | controller | Downloading pyyaml-6.0.3-cp39-cp39-manylinux2014_x86_64.manylinux_2_17_x86_64.manylinux_2_28_x86_64.whl.metadata (2.4 kB) 2026-02-01 00:31:47.036523 | controller | Collecting cryptography (from ansible-core==2.11.12.post0) 2026-02-01 00:31:47.042343 | controller | Downloading cryptography-46.0.4-cp38-abi3-manylinux_2_34_x86_64.whl.metadata (5.7 kB) 2026-02-01 00:31:47.059342 | controller | Requirement already satisfied: packaging in ./venv/lib/python3.9/site-packages (from ansible-core==2.11.12.post0) (26.0) 2026-02-01 00:31:47.074840 | controller | Collecting resolvelib<0.6.0,>=0.5.3 (from ansible-core==2.11.12.post0) 2026-02-01 00:31:47.080011 | controller | Downloading resolvelib-0.5.4-py2.py3-none-any.whl.metadata (3.7 kB) 2026-02-01 00:31:47.237515 | controller | Collecting cffi>=2.0.0 (from cryptography->ansible-core==2.11.12.post0) 2026-02-01 00:31:47.242934 | controller | Downloading cffi-2.0.0-cp39-cp39-manylinux2014_x86_64.manylinux_2_17_x86_64.whl.metadata (2.6 kB) 2026-02-01 00:31:47.249452 | controller | Requirement already satisfied: typing-extensions>=4.13.2 in ./venv/lib/python3.9/site-packages (from cryptography->ansible-core==2.11.12.post0) (4.15.0) 2026-02-01 00:31:47.265736 | controller | Collecting pycparser (from cffi>=2.0.0->cryptography->ansible-core==2.11.12.post0) 2026-02-01 00:31:47.271805 | controller | Downloading pycparser-2.23-py3-none-any.whl.metadata (993 bytes) 2026-02-01 00:31:47.353500 | controller | Collecting MarkupSafe>=2.0 (from jinja2->ansible-core==2.11.12.post0) 2026-02-01 00:31:47.358325 | controller | Downloading markupsafe-3.0.3-cp39-cp39-manylinux2014_x86_64.manylinux_2_17_x86_64.manylinux_2_28_x86_64.whl.metadata (2.7 kB) 2026-02-01 00:31:47.370668 | controller | Downloading resolvelib-0.5.4-py2.py3-none-any.whl (12 kB) 2026-02-01 00:31:47.383147 | controller | Downloading cryptography-46.0.4-cp38-abi3-manylinux_2_34_x86_64.whl (4.5 MB) 2026-02-01 00:31:47.473134 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 4.5/4.5 MB 56.4 MB/s 0:00:00 2026-02-01 00:31:47.477994 | controller | Downloading cffi-2.0.0-cp39-cp39-manylinux2014_x86_64.manylinux_2_17_x86_64.whl (216 kB) 2026-02-01 00:31:47.490444 | controller | Downloading jinja2-3.1.6-py3-none-any.whl (134 kB) 2026-02-01 00:31:47.508122 | controller | Downloading markupsafe-3.0.3-cp39-cp39-manylinux2014_x86_64.manylinux_2_17_x86_64.manylinux_2_28_x86_64.whl (20 kB) 2026-02-01 00:31:47.520881 | controller | Downloading pycparser-2.23-py3-none-any.whl (118 kB) 2026-02-01 00:31:47.534458 | controller | Downloading pyyaml-6.0.3-cp39-cp39-manylinux2014_x86_64.manylinux_2_17_x86_64.manylinux_2_28_x86_64.whl (750 kB) 2026-02-01 00:31:47.552961 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 750.8/750.8 kB 32.2 MB/s 0:00:00 2026-02-01 00:31:47.568474 | controller | Building wheels for collected packages: ansible-core 2026-02-01 00:31:47.569281 | controller | Building wheel for ansible-core (pyproject.toml): started 2026-02-01 00:31:49.160456 | controller | Building wheel for ansible-core (pyproject.toml): finished with status 'done' 2026-02-01 00:31:49.161913 | controller | Created wheel for ansible-core: filename=ansible_core-2.11.12.post0-py3-none-any.whl size=1961248 sha256=cc6af66a5640189856ed240545d15b92cb126861863237c18642c80c6ca206a2 2026-02-01 00:31:49.162156 | controller | Stored in directory: /var/tmp/ansible-test-pip/pip-ephem-wheel-cache-n45c_5m6/wheels/0f/a0/93/ab85b739e1eb1e0467171316f13571c14efdf87e0d5d44a33e 2026-02-01 00:31:49.170851 | controller | Successfully built ansible-core 2026-02-01 00:31:49.201782 | controller | Installing collected packages: resolvelib, PyYAML, pycparser, MarkupSafe, jinja2, cffi, cryptography, ansible-core 2026-02-01 00:31:50.848404 | controller | 2026-02-01 00:31:50.851270 | controller | Successfully installed MarkupSafe-3.0.3 PyYAML-6.0.3 ansible-core-2.11.12.post0 cffi-2.0.0 cryptography-46.0.4 jinja2-3.1.6 pycparser-2.23 resolvelib-0.5.4 2026-02-01 00:31:51.384416 | controller | ok: Runtime: 0:00:09.115682 2026-02-01 00:31:51.397953 | 2026-02-01 00:31:51.398113 | TASK [Setup download-artifact-fork role] 2026-02-01 00:31:51.427650 | controller | ok 2026-02-01 00:31:51.461340 | 2026-02-01 00:31:51.461451 | TASK [download-artifact-fork : Parse inventory response] 2026-02-01 00:31:51.516151 | controller | ok 2026-02-01 00:31:51.524903 | 2026-02-01 00:31:51.525000 | TASK [download-artifact-fork : Fetch info from Zuul API] 2026-02-01 00:31:51.560904 | controller | skipping: Conditional result was False 2026-02-01 00:31:51.570986 | 2026-02-01 00:31:51.571122 | TASK [download-artifact-fork : Parse build response] 2026-02-01 00:31:51.598070 | controller | skipping: Conditional result was False 2026-02-01 00:31:51.609566 | 2026-02-01 00:31:51.609776 | TASK [download-artifact-fork : Ensure artifacts directory exists] 2026-02-01 00:31:51.821176 | controller | changed 2026-02-01 00:31:51.830160 | 2026-02-01 00:31:51.830271 | LOOP [download-artifact-fork : Download archive by type] 2026-02-01 00:31:51.858080 | controller | skipping: Conditional result was False 2026-02-01 00:31:51.858472 | 2026-02-01 00:31:52.736329 | controller | changed: OK (20223 bytes) 2026-02-01 00:31:52.766536 | 2026-02-01 00:31:52.766690 | TASK [deploy-artifacts : define __collections] 2026-02-01 00:31:52.786838 | controller | ok 2026-02-01 00:31:52.794792 | 2026-02-01 00:31:52.794923 | LOOP [deploy-artifacts : Create list of collections] 2026-02-01 00:31:52.826178 | controller | skipping: Conditional result was False 2026-02-01 00:31:52.844397 | controller | ok: 2026-02-01 00:31:52.844608 | controller | { 2026-02-01 00:31:52.844738 | controller | "branch": "main", 2026-02-01 00:31:52.844964 | controller | "job": "build-ansible-collection", 2026-02-01 00:31:52.845039 | controller | "metadata": { 2026-02-01 00:31:52.845098 | controller | "type": "ansible_collection", 2026-02-01 00:31:52.845142 | controller | "version": "5.0.0" 2026-02-01 00:31:52.845181 | controller | }, 2026-02-01 00:31:52.845218 | controller | "name": "ansible.network", 2026-02-01 00:31:52.845256 | controller | "project": "ansible-collections/ansible.network", 2026-02-01 00:31:52.845294 | controller | "url": "https://105751c3d9106eaa16a8-a5f069c4cb65bcf9eedbc5bdcc3a37f1.ssl.cf2.rackcdn.com/ansible/2133dcfed4794b28827ce03a24b53b5c/artifacts/ansible-network-5.0.0.tar.gz" 2026-02-01 00:31:52.845347 | controller | } 2026-02-01 00:31:52.864526 | 2026-02-01 00:31:52.864711 | TASK [deploy-artifacts : Install require-project collection using ansible-galaxy] 2026-02-01 00:31:53.484813 | controller | Starting galaxy collection install process 2026-02-01 00:31:53.485002 | controller | Process install dependency map 2026-02-01 00:32:44.507595 | controller | [WARNING]: Skipping Galaxy server https://galaxy.ansible.com/api/. Got an 2026-02-01 00:32:44.507822 | controller | unexpected error when getting available versions of collection 2026-02-01 00:32:44.507868 | controller | ansible.netcommon: '/api/v3/plugin/ansible/content/published/collections/index/ 2026-02-01 00:32:44.507881 | controller | ansible/netcommon/versions/' 2026-02-01 00:32:44.507898 | controller | ERROR! Unexpected Exception, this is probably a bug: '/api/v3/plugin/ansible/content/published/collections/index/ansible/netcommon/versions/' 2026-02-01 00:32:44.545961 | controller | to see the full traceback, use -vvv 2026-02-01 00:32:44.973816 | controller | ERROR 2026-02-01 00:32:44.974102 | controller | { 2026-02-01 00:32:44.974145 | controller | "delta": "0:00:51.435975", 2026-02-01 00:32:44.974175 | controller | "end": "2026-02-01 00:32:44.546586", 2026-02-01 00:32:44.974202 | controller | "msg": "non-zero return code", 2026-02-01 00:32:44.974226 | controller | "rc": 250, 2026-02-01 00:32:44.974251 | controller | "start": "2026-02-01 00:31:53.110611" 2026-02-01 00:32:44.974275 | controller | } failure 2026-02-01 00:32:44.976572 | 2026-02-01 00:32:44.976644 | PLAY RECAP 2026-02-01 00:32:44.976747 | controller | ok: 44 changed: 24 unreachable: 0 failed: 1 skipped: 17 rescued: 0 ignored: 0 2026-02-01 00:32:44.976784 | 2026-02-01 00:32:45.118875 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master] 2026-02-01 00:32:45.119989 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2026-02-01 00:32:45.750259 | 2026-02-01 00:32:45.750408 | PLAY [all] 2026-02-01 00:32:45.771457 | 2026-02-01 00:32:45.771555 | TASK [fetch-subunit-output : Find stestr or testr executable] 2026-02-01 00:32:46.225956 | controller | changed: non-zero return code 2026-02-01 00:32:46.232322 | 2026-02-01 00:32:46.232429 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2026-02-01 00:32:46.257181 | controller | skipping: Conditional result was False 2026-02-01 00:32:46.264428 | 2026-02-01 00:32:46.264527 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2026-02-01 00:32:46.297450 | 2026-02-01 00:32:46.297657 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2026-02-01 00:32:46.331025 | 2026-02-01 00:32:46.331241 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2026-02-01 00:32:46.346777 | controller | skipping: Conditional result was False 2026-02-01 00:32:46.354511 | 2026-02-01 00:32:46.354611 | LOOP [fetch-subunit-output : Generate subunit file] 2026-02-01 00:32:46.376772 | 2026-02-01 00:32:46.376970 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2026-02-01 00:32:46.392272 | controller | skipping: Conditional result was False 2026-02-01 00:32:46.401508 | 2026-02-01 00:32:46.401674 | TASK [fetch-subunit-output : Remove the temporary file] 2026-02-01 00:32:46.417569 | controller | skipping: Conditional result was False 2026-02-01 00:32:46.426254 | 2026-02-01 00:32:46.426411 | TASK [fetch-subunit-output : Process and fetch subunit results] 2026-02-01 00:32:46.442596 | controller | skipping: Conditional result was False 2026-02-01 00:32:46.477125 | 2026-02-01 00:32:46.477226 | PLAY RECAP 2026-02-01 00:32:46.477268 | controller | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2026-02-01 00:32:46.477289 | 2026-02-01 00:32:46.589458 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2026-02-01 00:32:46.590319 | POST-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/post.yaml@master] 2026-02-01 00:32:47.212143 | 2026-02-01 00:32:47.212280 | PLAY [all:!appliance*] 2026-02-01 00:32:47.240949 | 2026-02-01 00:32:47.241107 | TASK [unregister the node] 2026-02-01 00:32:47.781598 | controller | skipping: Conditional result was False 2026-02-01 00:32:47.801007 | 2026-02-01 00:32:47.801321 | TASK [include_role : fetch-output] 2026-02-01 00:32:47.854827 | controller | ok 2026-02-01 00:32:47.893740 | 2026-02-01 00:32:47.893923 | TASK [fetch-output : Set log path for multiple nodes] 2026-02-01 00:32:47.941737 | controller | skipping: Conditional result was False 2026-02-01 00:32:47.951244 | 2026-02-01 00:32:47.951357 | TASK [fetch-output : Set log path for single node] 2026-02-01 00:32:47.984197 | controller | ok 2026-02-01 00:32:48.006405 | 2026-02-01 00:32:48.006558 | LOOP [fetch-output : Ensure local output dirs] 2026-02-01 00:32:48.419089 | controller -> localhost | ok: "/var/lib/zuul/builds/944f5555e272436e955f9f2773547b1c/work/logs" 2026-02-01 00:32:48.690752 | controller -> localhost | changed: "/var/lib/zuul/builds/944f5555e272436e955f9f2773547b1c/work/artifacts" 2026-02-01 00:32:48.934327 | controller -> localhost | changed: "/var/lib/zuul/builds/944f5555e272436e955f9f2773547b1c/work/docs" 2026-02-01 00:32:48.952882 | 2026-02-01 00:32:48.952980 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-02-01 00:32:49.691954 | controller | changed: 2026-02-01 00:32:49.692319 | controller | .d..t...... ./ 2026-02-01 00:32:49.692387 | controller | cd+++++++++ controller/ 2026-02-01 00:32:49.692459 | controller | changed: All items complete 2026-02-01 00:32:49.692524 | 2026-02-01 00:32:50.301014 | controller | changed: .d..t...... ./ 2026-02-01 00:32:50.809871 | controller | changed: .d..t...... ./ 2026-02-01 00:32:50.845016 | 2026-02-01 00:32:50.845362 | TASK [include_role : fetch-output-openshift] 2026-02-01 00:32:50.862743 | controller | skipping: Conditional result was False 2026-02-01 00:32:50.877966 | 2026-02-01 00:32:50.878148 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-02-01 00:32:50.915011 | controller | skipping: Conditional result was False 2026-02-01 00:32:50.930971 | controller | skipping: Conditional result was False 2026-02-01 00:32:50.973731 | 2026-02-01 00:32:50.973849 | PLAY [localhost] 2026-02-01 00:32:50.987266 | 2026-02-01 00:32:50.987376 | TASK [Run Zuul manifest role] 2026-02-01 00:32:51.008121 | localhost | ok 2026-02-01 00:32:51.024828 | 2026-02-01 00:32:51.024941 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-02-01 00:32:51.412284 | localhost | changed 2026-02-01 00:32:51.419556 | 2026-02-01 00:32:51.419641 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-02-01 00:32:51.453127 | localhost | ok 2026-02-01 00:32:51.467256 | 2026-02-01 00:32:51.467490 | TASK [Set zuul-log-path fact] 2026-02-01 00:32:51.490038 | localhost | ok 2026-02-01 00:32:51.513896 | 2026-02-01 00:32:51.514028 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-02-01 00:32:51.543629 | localhost | ok 2026-02-01 00:32:51.552179 | 2026-02-01 00:32:51.552246 | LOOP [Run upload-logs-swift role] 2026-02-01 00:32:51.576763 | localhost | Output suppressed because no_log was given 2026-02-01 00:32:51.603001 | 2026-02-01 00:32:51.603106 | TASK [Set zuul-log-path fact] 2026-02-01 00:32:51.638516 | localhost | skipping: Conditional result was False 2026-02-01 00:32:51.643672 | 2026-02-01 00:32:51.643778 | TASK [upload-logs-swift : Ensure logs are readable before uploading] 2026-02-01 00:32:52.089142 | localhost -> localhost | ok: Runtime: 0:00:00.008621 2026-02-01 00:32:52.124419 | 2026-02-01 00:32:52.124548 | TASK [upload-logs-swift : Upload logs to swift]