2025-10-30 00:06:45.778945 | Job console starting... 2025-10-30 00:06:45.792088 | Updating repositories 2025-10-30 00:06:46.682004 | Preparing job workspace 2025-10-30 00:06:51.329396 | Running Ansible setup... 2025-10-30 00:06:57.657510 | PRE-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master] 2025-10-30 00:06:58.274075 | 2025-10-30 00:06:58.274232 | PLAY [localhost] 2025-10-30 00:06:58.283282 | 2025-10-30 00:06:58.283376 | TASK [Gathering Facts] 2025-10-30 00:06:59.250059 | localhost | ok 2025-10-30 00:06:59.270583 | 2025-10-30 00:06:59.270770 | TASK [Setup log path fact] 2025-10-30 00:06:59.290053 | localhost | ok 2025-10-30 00:06:59.303510 | 2025-10-30 00:06:59.303632 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-10-30 00:06:59.321292 | localhost | ok 2025-10-30 00:06:59.329472 | 2025-10-30 00:06:59.329565 | TASK [emit-job-header : Print job information] 2025-10-30 00:06:59.378383 | # Job Information 2025-10-30 00:06:59.378528 | Ansible Version: 2.15.12 2025-10-30 00:06:59.378559 | Job: ansible-test-sanity-docker-stable-2.11 2025-10-30 00:06:59.378580 | Pipeline: periodic 2025-10-30 00:06:59.378599 | Executor: ze02.softwarefactory-project.io 2025-10-30 00:06:59.378617 | Triggered by: https://github.com/ansible-collections/ansible.network 2025-10-30 00:06:59.378638 | Log URL (when completed): https://ansible.softwarefactory-project.io/logs/dff/ansible/dffc80b46a5946ec855af16b0d94af65/ 2025-10-30 00:06:59.378657 | Event ID: 3d3b3e11113e4aa4883722e12166235f 2025-10-30 00:06:59.382337 | 2025-10-30 00:06:59.382402 | LOOP [emit-job-header : Print node information] 2025-10-30 00:06:59.490744 | localhost | ok: 2025-10-30 00:06:59.490911 | localhost | # Node Information 2025-10-30 00:06:59.490941 | localhost | Inventory Hostname: controller 2025-10-30 00:06:59.490964 | localhost | Hostname: ip-172-16-145-117 2025-10-30 00:06:59.490986 | localhost | Username: zuul-worker 2025-10-30 00:06:59.491011 | localhost | Distro: Fedora 37 2025-10-30 00:06:59.491033 | localhost | Provider: ansible-us-east-2 2025-10-30 00:06:59.491055 | localhost | Region: us-east-2 2025-10-30 00:06:59.491076 | localhost | Label: ansible-fedora-37-1vcpu 2025-10-30 00:06:59.491096 | localhost | Product Name: t3.small 2025-10-30 00:06:59.491116 | localhost | Interface IP: 3.17.129.182 2025-10-30 00:06:59.504716 | 2025-10-30 00:06:59.504815 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-10-30 00:06:59.896151 | localhost -> localhost | changed 2025-10-30 00:06:59.902106 | 2025-10-30 00:06:59.902197 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-10-30 00:07:00.814433 | localhost -> localhost | changed 2025-10-30 00:07:00.834115 | 2025-10-30 00:07:00.834256 | PLAY [all:!appliance*] 2025-10-30 00:07:00.850483 | 2025-10-30 00:07:00.850602 | TASK [include_role : start-zuul-console] 2025-10-30 00:07:00.869951 | controller | ok 2025-10-30 00:07:00.883922 | 2025-10-30 00:07:00.884021 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-10-30 00:07:01.736205 | controller | ok 2025-10-30 00:07:01.760288 | 2025-10-30 00:07:01.760440 | TASK [use-our-mirror : Retrieve the IP address] 2025-10-30 00:07:04.988918 | controller | ok: Status code was 404 and not [200]: HTTP Error 404: Not Found 2025-10-30 00:07:05.002243 | 2025-10-30 00:07:05.002558 | TASK [use-our-mirror : Ensure the registry service is running on the mirror] 2025-10-30 00:07:05.166425 | controller | skipping: Conditional result was False 2025-10-30 00:07:05.173295 | 2025-10-30 00:07:05.173416 | TASK [use-our-mirror : Write the IP in /etc/hosts] 2025-10-30 00:07:05.197583 | controller | skipping: Conditional result was False 2025-10-30 00:07:05.203594 | 2025-10-30 00:07:05.203687 | TASK [use-our-mirror : Create the podman configuration directory] 2025-10-30 00:07:05.227595 | controller | skipping: Conditional result was False 2025-10-30 00:07:05.233611 | 2025-10-30 00:07:05.233707 | TASK [use-our-mirror : Copy the podman configuration] 2025-10-30 00:07:05.257649 | controller | skipping: Conditional result was False 2025-10-30 00:07:05.264114 | 2025-10-30 00:07:05.264190 | TASK [use-our-mirror : Copy the Yum configuration (main)] 2025-10-30 00:07:05.288057 | controller | skipping: Conditional result was False 2025-10-30 00:07:05.293887 | 2025-10-30 00:07:05.293953 | TASK [use-our-mirror : Copy the Yum configuration (update)] 2025-10-30 00:07:05.318362 | controller | skipping: Conditional result was False 2025-10-30 00:07:05.334369 | 2025-10-30 00:07:05.334482 | TASK [Disable Fedora Modular] 2025-10-30 00:07:06.065440 | controller | changed 2025-10-30 00:07:06.079316 | 2025-10-30 00:07:06.079441 | TASK [Enable EPEL] 2025-10-30 00:07:06.097353 | controller | skipping: Conditional result was False 2025-10-30 00:07:06.105365 | 2025-10-30 00:07:06.105463 | TASK [Register the RHEL node] 2025-10-30 00:07:06.480028 | 2025-10-30 00:07:06.480242 | TASK [Show the subscription-manager status] 2025-10-30 00:07:06.676092 | controller | skipping: Conditional result was False 2025-10-30 00:07:06.688952 | 2025-10-30 00:07:06.689086 | TASK [Enable EPEL on RHEL] 2025-10-30 00:07:06.844458 | controller | skipping: Conditional result was False 2025-10-30 00:07:06.852721 | 2025-10-30 00:07:06.852823 | TASK [Install git and tox] 2025-10-30 00:08:40.336162 | controller | changed 2025-10-30 00:08:40.342002 | 2025-10-30 00:08:40.342063 | TASK [include_role : prepare-workspace] 2025-10-30 00:08:40.362380 | controller | ok 2025-10-30 00:08:40.394488 | 2025-10-30 00:08:40.394750 | TASK [prepare-workspace : Start zuul_console daemon.] 2025-10-30 00:08:40.953468 | controller | ok 2025-10-30 00:08:40.967706 | 2025-10-30 00:08:40.967872 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2025-10-30 00:08:54.478135 | controller | Output suppressed because no_log was given 2025-10-30 00:08:54.492231 | 2025-10-30 00:08:54.492379 | TASK [include_role : prepare-workspace-openshift] 2025-10-30 00:08:54.517109 | controller | skipping: Conditional result was False 2025-10-30 00:08:54.537805 | 2025-10-30 00:08:54.537866 | PLAY [all:!appliance] 2025-10-30 00:08:54.553208 | 2025-10-30 00:08:54.553334 | TASK [Run add-build-sshkey role (RSA)] 2025-10-30 00:08:54.574523 | controller | ok 2025-10-30 00:08:54.622741 | 2025-10-30 00:08:54.622858 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-10-30 00:08:54.860454 | controller -> localhost | ok 2025-10-30 00:08:54.868326 | 2025-10-30 00:08:54.868396 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-10-30 00:08:54.887655 | controller | ok 2025-10-30 00:08:54.904924 | controller | included: /var/lib/zuul/builds/dffc80b46a5946ec855af16b0d94af65/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-10-30 00:08:54.911472 | 2025-10-30 00:08:54.911535 | TASK [add-build-sshkey : Create Temp SSH key] 2025-10-30 00:08:55.352456 | controller -> localhost | Generating public/private rsa key pair. 2025-10-30 00:08:55.352628 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/dffc80b46a5946ec855af16b0d94af65/work/dffc80b46a5946ec855af16b0d94af65_id_rsa. 2025-10-30 00:08:55.352657 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/dffc80b46a5946ec855af16b0d94af65/work/dffc80b46a5946ec855af16b0d94af65_id_rsa.pub. 2025-10-30 00:08:55.352698 | controller -> localhost | The key fingerprint is: 2025-10-30 00:08:55.352719 | controller -> localhost | SHA256:XnpztX6ywINfQqYlnKPdNfOsowYnF5W6owiE3OhrlFI zuul-build-sshkey 2025-10-30 00:08:55.352738 | controller -> localhost | The key's randomart image is: 2025-10-30 00:08:55.352757 | controller -> localhost | +---[RSA 2048]----+ 2025-10-30 00:08:55.352776 | controller -> localhost | | . | 2025-10-30 00:08:55.352794 | controller -> localhost | | o | 2025-10-30 00:08:55.352813 | controller -> localhost | | . + o | 2025-10-30 00:08:55.352831 | controller -> localhost | | E o . .o | 2025-10-30 00:08:55.352848 | controller -> localhost | | o o S * +o= | 2025-10-30 00:08:55.352891 | controller -> localhost | | . + .. =o@=o * | 2025-10-30 00:08:55.352916 | controller -> localhost | | o . .+.**B.o o| 2025-10-30 00:08:55.352935 | controller -> localhost | | o ...+.*o..| 2025-10-30 00:08:55.352952 | controller -> localhost | | . .o.+= | 2025-10-30 00:08:55.352972 | controller -> localhost | +----[SHA256]-----+ 2025-10-30 00:08:55.353014 | controller -> localhost | ok: Runtime: 0:00:00.106317 2025-10-30 00:08:55.359789 | 2025-10-30 00:08:55.359850 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-10-30 00:08:55.380124 | controller | ok 2025-10-30 00:08:55.390399 | controller | included: /var/lib/zuul/builds/dffc80b46a5946ec855af16b0d94af65/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-10-30 00:08:55.399339 | 2025-10-30 00:08:55.399401 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-10-30 00:08:55.415148 | controller | skipping: Conditional result was False 2025-10-30 00:08:55.422609 | 2025-10-30 00:08:55.422738 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-10-30 00:08:56.042480 | controller | changed 2025-10-30 00:08:56.049549 | 2025-10-30 00:08:56.049637 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-10-30 00:08:56.437589 | controller | ok 2025-10-30 00:08:56.446012 | 2025-10-30 00:08:56.446107 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-10-30 00:08:57.780868 | controller | changed 2025-10-30 00:08:57.793022 | 2025-10-30 00:08:57.793158 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-10-30 00:08:59.355482 | controller | changed 2025-10-30 00:08:59.368004 | 2025-10-30 00:08:59.368257 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-10-30 00:08:59.396022 | controller | skipping: Conditional result was False 2025-10-30 00:08:59.410578 | 2025-10-30 00:08:59.410821 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-10-30 00:08:59.762552 | controller -> localhost | changed 2025-10-30 00:08:59.773467 | 2025-10-30 00:08:59.773754 | TASK [add-build-sshkey : Add back temp key] 2025-10-30 00:09:00.104740 | controller -> localhost | Identity added: /var/lib/zuul/builds/dffc80b46a5946ec855af16b0d94af65/work/dffc80b46a5946ec855af16b0d94af65_id_rsa (zuul-build-sshkey) 2025-10-30 00:09:00.105205 | controller -> localhost | ok: Runtime: 0:00:00.009585 2025-10-30 00:09:00.119313 | 2025-10-30 00:09:00.119452 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-10-30 00:09:00.735804 | controller | ok 2025-10-30 00:09:00.747572 | 2025-10-30 00:09:00.747776 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-10-30 00:09:00.785646 | controller | skipping: Conditional result was False 2025-10-30 00:09:00.809498 | 2025-10-30 00:09:00.809643 | TASK [Run add-build-sshkey role (ECDSA)] 2025-10-30 00:09:00.847842 | controller | ok 2025-10-30 00:09:00.876730 | 2025-10-30 00:09:00.876834 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-10-30 00:09:01.171887 | controller -> localhost | ok 2025-10-30 00:09:01.187776 | 2025-10-30 00:09:01.187975 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-10-30 00:09:01.224804 | controller | ok 2025-10-30 00:09:01.240485 | controller | included: /var/lib/zuul/builds/dffc80b46a5946ec855af16b0d94af65/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-10-30 00:09:01.249081 | 2025-10-30 00:09:01.249202 | TASK [add-build-sshkey : Create Temp SSH key] 2025-10-30 00:09:01.580561 | controller -> localhost | Generating public/private ecdsa key pair. 2025-10-30 00:09:01.580806 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/dffc80b46a5946ec855af16b0d94af65/work/dffc80b46a5946ec855af16b0d94af65_id_ecdsa. 2025-10-30 00:09:01.580836 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/dffc80b46a5946ec855af16b0d94af65/work/dffc80b46a5946ec855af16b0d94af65_id_ecdsa.pub. 2025-10-30 00:09:01.580867 | controller -> localhost | The key fingerprint is: 2025-10-30 00:09:01.580888 | controller -> localhost | SHA256:AcHEVwKuqQOnN3Lxl8Xv/xNLZLBRYHx3EQXO+NsCYvU zuul-build-sshkey 2025-10-30 00:09:01.580908 | controller -> localhost | The key's randomart image is: 2025-10-30 00:09:01.580928 | controller -> localhost | +---[ECDSA 521]---+ 2025-10-30 00:09:01.580945 | controller -> localhost | | +=o....ooo+=| 2025-10-30 00:09:01.580963 | controller -> localhost | | .o... .++. o| 2025-10-30 00:09:01.580981 | controller -> localhost | | ... o=o..| 2025-10-30 00:09:01.580999 | controller -> localhost | | o . . ..oo | 2025-10-30 00:09:01.581018 | controller -> localhost | |. o o S o .oE | 2025-10-30 00:09:01.581035 | controller -> localhost | | + + o o . .oo | 2025-10-30 00:09:01.581053 | controller -> localhost | |o * . o . .oo.| 2025-10-30 00:09:01.581070 | controller -> localhost | | + o . . o. | 2025-10-30 00:09:01.581087 | controller -> localhost | | ...... | 2025-10-30 00:09:01.581104 | controller -> localhost | +----[SHA256]-----+ 2025-10-30 00:09:01.581152 | controller -> localhost | ok: Runtime: 0:00:00.011181 2025-10-30 00:09:01.587418 | 2025-10-30 00:09:01.587484 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-10-30 00:09:01.617599 | controller | ok 2025-10-30 00:09:01.624711 | controller | included: /var/lib/zuul/builds/dffc80b46a5946ec855af16b0d94af65/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-10-30 00:09:01.633524 | 2025-10-30 00:09:01.633589 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-10-30 00:09:01.657637 | controller | skipping: Conditional result was False 2025-10-30 00:09:01.663730 | 2025-10-30 00:09:01.663799 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-10-30 00:09:02.762515 | controller | changed 2025-10-30 00:09:02.777616 | 2025-10-30 00:09:02.777794 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-10-30 00:09:03.660108 | controller | ok 2025-10-30 00:09:03.668856 | 2025-10-30 00:09:03.668998 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-10-30 00:09:06.696711 | controller | changed 2025-10-30 00:09:06.708604 | 2025-10-30 00:09:06.708792 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-10-30 00:09:08.460273 | controller | changed 2025-10-30 00:09:08.468492 | 2025-10-30 00:09:08.468586 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-10-30 00:09:08.493790 | controller | skipping: Conditional result was False 2025-10-30 00:09:08.502595 | 2025-10-30 00:09:08.502740 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-10-30 00:09:08.803291 | controller -> localhost | changed 2025-10-30 00:09:08.814857 | 2025-10-30 00:09:08.814928 | TASK [add-build-sshkey : Add back temp key] 2025-10-30 00:09:09.178890 | controller -> localhost | Identity added: /var/lib/zuul/builds/dffc80b46a5946ec855af16b0d94af65/work/dffc80b46a5946ec855af16b0d94af65_id_ecdsa (zuul-build-sshkey) 2025-10-30 00:09:09.179159 | controller -> localhost | ok: Runtime: 0:00:00.014560 2025-10-30 00:09:09.220001 | 2025-10-30 00:09:09.220130 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-10-30 00:09:09.643059 | controller | ok 2025-10-30 00:09:09.655129 | 2025-10-30 00:09:09.655268 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-10-30 00:09:09.703082 | controller | skipping: Conditional result was False 2025-10-30 00:09:09.727514 | 2025-10-30 00:09:09.727653 | TASK [include_role : remove-zuul-sshkey] 2025-10-30 00:09:09.754523 | controller | skipping: Conditional result was False 2025-10-30 00:09:09.767433 | 2025-10-30 00:09:09.767573 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2025-10-30 00:09:10.125094 | controller | ok: "logs" 2025-10-30 00:09:10.125328 | controller | ok: All items complete 2025-10-30 00:09:10.125356 | 2025-10-30 00:09:10.413072 | controller | ok: "artifacts" 2025-10-30 00:09:10.699846 | controller | ok: "docs" 2025-10-30 00:09:10.716164 | 2025-10-30 00:09:10.716369 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2025-10-30 00:09:11.057693 | controller | changed: "logs" 2025-10-30 00:09:11.355604 | controller | changed: "artifacts" 2025-10-30 00:09:11.676634 | controller | changed: "docs" 2025-10-30 00:09:11.736683 | 2025-10-30 00:09:11.736867 | PLAY RECAP 2025-10-30 00:09:11.736929 | controller | ok: 30 changed: 16 unreachable: 0 failed: 0 skipped: 18 rescued: 0 ignored: 0 2025-10-30 00:09:11.736967 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-10-30 00:09:11.736992 | 2025-10-30 00:09:11.857794 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master] 2025-10-30 00:09:11.858685 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2025-10-30 00:09:12.477112 | 2025-10-30 00:09:12.477242 | PLAY [all] 2025-10-30 00:09:12.499910 | 2025-10-30 00:09:12.500036 | TASK [Install binary dependencies] 2025-10-30 00:09:12.572405 | controller | ok 2025-10-30 00:09:12.602938 | 2025-10-30 00:09:12.603159 | TASK [bindep : Include find tasks] 2025-10-30 00:09:12.636433 | controller | ok 2025-10-30 00:09:12.647742 | controller | included: /var/lib/zuul/builds/dffc80b46a5946ec855af16b0d94af65/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find.yaml 2025-10-30 00:09:12.657116 | 2025-10-30 00:09:12.657218 | TASK [bindep : Look for bindep.txt] 2025-10-30 00:09:13.189143 | controller | ok 2025-10-30 00:09:13.197800 | 2025-10-30 00:09:13.197896 | TASK [bindep : Define bindep_file fact] 2025-10-30 00:09:13.222702 | controller | skipping: Conditional result was False 2025-10-30 00:09:13.231018 | 2025-10-30 00:09:13.231113 | TASK [bindep : Look for other-requirements.txt] 2025-10-30 00:09:13.547070 | controller | ok 2025-10-30 00:09:13.554508 | 2025-10-30 00:09:13.554712 | TASK [bindep : Define bindep_file fact] 2025-10-30 00:09:13.579391 | controller | skipping: Conditional result was False 2025-10-30 00:09:13.587465 | 2025-10-30 00:09:13.587556 | TASK [bindep : Look for bindep fallback file] 2025-10-30 00:09:13.611834 | controller | skipping: Conditional result was False 2025-10-30 00:09:13.618520 | 2025-10-30 00:09:13.618607 | TASK [bindep : Define bindep_file fact] 2025-10-30 00:09:13.642167 | controller | skipping: Conditional result was False 2025-10-30 00:09:13.649188 | 2025-10-30 00:09:13.649287 | TASK [bindep : Include bindep tasks] 2025-10-30 00:09:13.683470 | controller | skipping: Conditional result was False 2025-10-30 00:09:13.691136 | 2025-10-30 00:09:13.691209 | TASK [bindep : Include install tasks] 2025-10-30 00:09:13.715293 | controller | skipping: Conditional result was False 2025-10-30 00:09:13.722897 | 2025-10-30 00:09:13.722976 | LOOP [bindep : Include package tasks] 2025-10-30 00:09:13.787696 | 2025-10-30 00:09:13.787855 | TASK [Run test-setup role] 2025-10-30 00:09:13.806560 | controller | ok 2025-10-30 00:09:13.824156 | 2025-10-30 00:09:13.824226 | TASK [test-setup : Check if project's tools/test-setup.sh exists] 2025-10-30 00:09:14.127038 | controller | ok 2025-10-30 00:09:14.133023 | 2025-10-30 00:09:14.133085 | TASK [test-setup : Run tools/test-setup.sh] 2025-10-30 00:09:14.269530 | controller | skipping: Conditional result was False 2025-10-30 00:09:14.298822 | 2025-10-30 00:09:14.298926 | PLAY RECAP 2025-10-30 00:09:14.298972 | controller | ok: 4 changed: 0 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2025-10-30 00:09:14.298993 | 2025-10-30 00:09:14.393723 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2025-10-30 00:09:14.395615 | PRE-RUN START: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master] 2025-10-30 00:09:15.021475 | 2025-10-30 00:09:15.021600 | PLAY [controller] 2025-10-30 00:09:15.046919 | 2025-10-30 00:09:15.047023 | TASK [Create the /root directory] 2025-10-30 00:09:15.766338 | controller | ok 2025-10-30 00:09:15.774005 | 2025-10-30 00:09:15.774115 | TASK [Install glibc-langpack-en] 2025-10-30 00:09:23.931901 | controller | ok: Nothing to do 2025-10-30 00:09:23.939707 | 2025-10-30 00:09:23.939802 | TASK [Ensure controller directory exists] 2025-10-30 00:09:24.399009 | controller | changed 2025-10-30 00:09:24.406411 | 2025-10-30 00:09:24.406498 | TASK [Install container runtime] 2025-10-30 00:09:24.449969 | controller | ok 2025-10-30 00:09:24.496703 | 2025-10-30 00:09:24.496821 | LOOP [ensure-docker : Gather variables for each operating system] 2025-10-30 00:09:24.541534 | controller | ok: "/var/lib/zuul/builds/dffc80b46a5946ec855af16b0d94af65/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/vars/fedora.yaml" 2025-10-30 00:09:24.555911 | 2025-10-30 00:09:24.556055 | TASK [ensure-docker : Sanity check for distro selection] 2025-10-30 00:09:24.571728 | controller | skipping: Conditional result was False 2025-10-30 00:09:24.580704 | 2025-10-30 00:09:24.580815 | TASK [ensure-docker : Sanity check for distro install] 2025-10-30 00:09:24.606129 | controller | skipping: Conditional result was False 2025-10-30 00:09:24.615477 | 2025-10-30 00:09:24.615650 | TASK [ensure-docker : Sanity check for upstream install] 2025-10-30 00:09:24.641184 | controller | skipping: Conditional result was False 2025-10-30 00:09:24.655267 | 2025-10-30 00:09:24.655448 | TASK [ensure-docker : Sanity check for upstream install mirrors] 2025-10-30 00:09:24.681910 | controller | skipping: Conditional result was False 2025-10-30 00:09:24.693246 | 2025-10-30 00:09:24.693365 | TASK [ensure-docker : Include tasks for docker install] 2025-10-30 00:09:24.726961 | controller | ok 2025-10-30 00:09:24.740952 | controller | included: /var/lib/zuul/builds/dffc80b46a5946ec855af16b0d94af65/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/tasks/docker-upstream.yaml 2025-10-30 00:09:24.754926 | 2025-10-30 00:09:24.755090 | TASK [ensure-docker : Not Implemented] 2025-10-30 00:09:24.780600 | controller | skipping: Conditional result was False 2025-10-30 00:09:24.789367 | 2025-10-30 00:09:24.789469 | TASK [Set up docker mirrors] 2025-10-30 00:09:24.812549 | controller | ok 2025-10-30 00:09:24.843968 | 2025-10-30 00:09:24.844133 | TASK [use-docker-mirror : Set docker_mirror fact from mirror_info] 2025-10-30 00:09:24.869114 | controller | skipping: Conditional result was False 2025-10-30 00:09:24.876554 | 2025-10-30 00:09:24.876642 | TASK [use-docker-mirror : Set docker_mirror fact from zuul_site_mirror_info] 2025-10-30 00:09:24.901424 | controller | skipping: Conditional result was False 2025-10-30 00:09:24.908591 | 2025-10-30 00:09:24.908718 | TASK [use-docker-mirror : Set docker_mirror fact] 2025-10-30 00:09:24.935336 | controller | skipping: Conditional result was False 2025-10-30 00:09:24.942814 | 2025-10-30 00:09:24.942905 | TASK [use-docker-mirror : Set up docker mirrors] 2025-10-30 00:09:24.968052 | controller | skipping: Conditional result was False 2025-10-30 00:09:24.983302 | 2025-10-30 00:09:24.983421 | TASK [Add all repositories] 2025-10-30 00:09:25.007914 | controller | ok 2025-10-30 00:09:25.079186 | 2025-10-30 00:09:25.079328 | LOOP [ensure-package-repositories : Install repositories] 2025-10-30 00:09:25.123222 | controller | ok: "/var/lib/zuul/builds/dffc80b46a5946ec855af16b0d94af65/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-package-repositories/tasks/RedHat.yaml" 2025-10-30 00:09:25.123513 | controller | ok: All items complete 2025-10-30 00:09:25.123567 | 2025-10-30 00:09:25.137737 | controller | included: /var/lib/zuul/builds/dffc80b46a5946ec855af16b0d94af65/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-package-repositories/tasks/RedHat.yaml 2025-10-30 00:09:25.146755 | 2025-10-30 00:09:25.146858 | TASK [ensure-package-repositories : Create GPG key temp directory] 2025-10-30 00:09:25.691357 | controller | changed 2025-10-30 00:09:25.700698 | 2025-10-30 00:09:25.700818 | LOOP [ensure-package-repositories : Upload GPG keys] 2025-10-30 00:09:27.302761 | controller | changed: 2025-10-30 00:09:27.302953 | controller | { 2025-10-30 00:09:27.302980 | 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-30 00:09:27.303004 | controller | } 2025-10-30 00:09:27.313133 | 2025-10-30 00:09:27.313266 | LOOP [ensure-package-repositories : Import GPG keys] 2025-10-30 00:09:28.198622 | controller | changed: 2025-10-30 00:09:28.198917 | controller | { 2025-10-30 00:09:28.198974 | 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-30 00:09:28.199019 | controller | } 2025-10-30 00:09:28.199075 | controller | changed: All items complete 2025-10-30 00:09:28.199115 | 2025-10-30 00:09:28.219849 | 2025-10-30 00:09:28.220073 | TASK [ensure-package-repositories : Remove GPG temp directory] 2025-10-30 00:09:28.596782 | controller | changed 2025-10-30 00:09:28.610133 | 2025-10-30 00:09:28.610287 | LOOP [ensure-package-repositories : Add repositories] 2025-10-30 00:09:29.109734 | controller | changed: 2025-10-30 00:09:29.110158 | controller | { 2025-10-30 00:09:29.110225 | controller | "baseurl": "https://download.docker.com/linux/fedora/$releasever/$basearch/stable", 2025-10-30 00:09:29.110284 | controller | "description": "Docker CE Stable - $basearch", 2025-10-30 00:09:29.110337 | controller | "gpgcheck": true, 2025-10-30 00:09:29.110527 | controller | "name": "docker-ce-stable" 2025-10-30 00:09:29.110584 | controller | } 2025-10-30 00:09:29.145174 | 2025-10-30 00:09:29.145378 | TASK [ensure-docker : Install pre-reqs] 2025-10-30 00:09:33.067767 | controller | ok: Nothing to do 2025-10-30 00:09:33.075134 | 2025-10-30 00:09:33.075280 | TASK [ensure-docker : Remove packages] 2025-10-30 00:09:35.323267 | controller | ok: Nothing to do 2025-10-30 00:09:35.329434 | 2025-10-30 00:09:35.329554 | TASK [ensure-docker : Install upstream docker using package] 2025-10-30 00:10:39.471804 | controller | changed 2025-10-30 00:10:39.487719 | 2025-10-30 00:10:39.487852 | TASK [ensure-docker : Setup docker] 2025-10-30 00:10:39.559352 | controller | ok 2025-10-30 00:10:39.602896 | controller | included: /var/lib/zuul/builds/dffc80b46a5946ec855af16b0d94af65/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/tasks/docker-setup.yaml 2025-10-30 00:10:39.655120 | 2025-10-30 00:10:39.655248 | TASK [ensure-docker : Ensure "docker" group exists] 2025-10-30 00:10:40.465774 | controller | ok 2025-10-30 00:10:40.476220 | 2025-10-30 00:10:40.476310 | TASK [ensure-docker : Add user to docker group] 2025-10-30 00:10:41.585627 | controller | changed 2025-10-30 00:10:41.596616 | 2025-10-30 00:10:41.596722 | TASK [Update docker daemon proxy configuration] 2025-10-30 00:10:41.620605 | controller | skipping: Conditional result was False 2025-10-30 00:10:41.628334 | 2025-10-30 00:10:41.628408 | TASK [Lower default MTU] 2025-10-30 00:10:41.710104 | controller | skipping: Conditional result was False 2025-10-30 00:10:41.726333 | 2025-10-30 00:10:41.726458 | TASK [ensure-docker : Get the status of the docket.socket unit] 2025-10-30 00:10:42.572997 | controller | inactive 2025-10-30 00:10:42.949805 | controller | ok: Runtime: 0:00:00.012609 2025-10-30 00:10:42.956204 | 2025-10-30 00:10:42.956274 | TASK [ensure-docker : Get the status of the docket unit] 2025-10-30 00:10:43.525807 | controller | inactive 2025-10-30 00:10:43.635763 | controller | ok: Runtime: 0:00:00.018120 2025-10-30 00:10:43.642352 | 2025-10-30 00:10:43.642419 | TASK [ensure-docker : Restart docker] 2025-10-30 00:10:43.714350 | Trigger a Docker restart 2025-10-30 00:10:43.719611 | 2025-10-30 00:10:43.719713 | TASK [ensure-docker : Reset ssh connection to pick up docker group] 2025-10-30 00:10:43.790749 | 2025-10-30 00:10:43.790859 | TASK [ensure-docker : Install docker-compose] 2025-10-30 00:10:43.825219 | controller | skipping: Conditional result was False 2025-10-30 00:10:43.832690 | 2025-10-30 00:10:43.832771 | TASK [ensure-docker : Flush handlers before role exit] 2025-10-30 00:10:43.845816 | 2025-10-30 00:10:43.845911 | TASK [ensure-docker : Stop docker.socket to avoid any conflict] 2025-10-30 00:10:46.480716 | controller | changed 2025-10-30 00:10:46.500295 | 2025-10-30 00:10:46.500450 | TASK [ensure-docker : Assure docker service is running] 2025-10-30 00:10:49.510007 | controller | changed 2025-10-30 00:10:49.518309 | 2025-10-30 00:10:49.524067 | TASK [ensure-docker : Assure docker.socket service is running] 2025-10-30 00:10:50.767895 | controller | ok 2025-10-30 00:10:50.794612 | 2025-10-30 00:10:50.794740 | TASK [ensure-docker : Correct group ownership on docker sock] 2025-10-30 00:10:51.660385 | controller | ok 2025-10-30 00:10:51.667447 | 2025-10-30 00:10:51.667586 | TASK [ensure-docker : Validate ability to talk with docker] 2025-10-30 00:10:52.245645 | controller | CONTAINER ID IMAGE COMMAND CREATED STATUS PORTS NAMES 2025-10-30 00:10:52.333958 | controller | ok: Runtime: 0:00:00.048455 2025-10-30 00:10:52.342111 | 2025-10-30 00:10:52.342230 | TASK [ensure-docker : Get version details] 2025-10-30 00:10:52.962396 | controller | Client: Docker Engine - Community 2025-10-30 00:10:52.962613 | controller | Version: 24.0.7 2025-10-30 00:10:52.962667 | controller | API version: 1.43 2025-10-30 00:10:52.962712 | controller | Go version: go1.20.10 2025-10-30 00:10:52.962768 | controller | Git commit: afdd53b 2025-10-30 00:10:52.962819 | controller | Built: Thu Oct 26 09:09:39 2023 2025-10-30 00:10:52.962870 | controller | OS/Arch: linux/amd64 2025-10-30 00:10:52.962923 | controller | Context: default 2025-10-30 00:10:52.962946 | controller | 2025-10-30 00:10:52.963001 | controller | Server: Docker Engine - Community 2025-10-30 00:10:52.963052 | controller | Engine: 2025-10-30 00:10:52.963209 | controller | Version: 24.0.7 2025-10-30 00:10:52.963251 | controller | API version: 1.43 (minimum version 1.12) 2025-10-30 00:10:52.963293 | controller | Go version: go1.20.10 2025-10-30 00:10:52.963334 | controller | Git commit: 311b9ff 2025-10-30 00:10:52.963382 | controller | Built: Thu Oct 26 09:08:11 2023 2025-10-30 00:10:52.963432 | controller | OS/Arch: linux/amd64 2025-10-30 00:10:52.963473 | controller | Experimental: false 2025-10-30 00:10:52.963498 | controller | containerd: 2025-10-30 00:10:52.963624 | controller | Version: 1.6.25 2025-10-30 00:10:52.963668 | controller | GitCommit: d8f198a4ed8892c764191ef7b3b06d8a2eeb5c7f 2025-10-30 00:10:52.963694 | controller | runc: 2025-10-30 00:10:52.963797 | controller | Version: 1.1.10 2025-10-30 00:10:52.963841 | controller | GitCommit: v1.1.10-0-g18a0cb0 2025-10-30 00:10:52.963870 | controller | docker-init: 2025-10-30 00:10:52.963943 | controller | Version: 0.19.0 2025-10-30 00:10:52.964001 | controller | GitCommit: de40ad0 2025-10-30 00:10:53.028872 | controller | ok: Runtime: 0:00:00.044609 2025-10-30 00:10:53.035991 | 2025-10-30 00:10:53.036061 | TASK [ensure-docker : Dump installed docker details] 2025-10-30 00:10:53.097739 | {'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-30 00:10:52.921906', 'end': '2025-10-30 00:10:52.966515', 'delta': '0:00:00.044609', 'msg': '', 'zuul_log_id': 'fa163efc-24cc-5230-cae1-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-30 00:10:53.109118 | 2025-10-30 00:10:53.109248 | TASK [Use buildset registry] 2025-10-30 00:10:53.149043 | controller | skipping: Conditional result was False 2025-10-30 00:10:53.169967 | 2025-10-30 00:10:53.170088 | TASK [Ensure python3.8 is present] 2025-10-30 00:10:53.198113 | controller | skipping: Conditional result was False 2025-10-30 00:10:53.216958 | 2025-10-30 00:10:53.217090 | TASK [Run our-ensure-python role (Fedora only for now)] 2025-10-30 00:10:53.254359 | controller | ok 2025-10-30 00:10:53.275779 | 2025-10-30 00:10:53.275908 | TASK [our-ensure-python : Install the right Python version (rpm)] 2025-10-30 00:10:59.173172 | controller | ok: Nothing to do 2025-10-30 00:10:59.178979 | 2025-10-30 00:10:59.179083 | TASK [our-ensure-python : Also install python3-devel] 2025-10-30 00:11:15.399656 | controller | changed 2025-10-30 00:11:15.413748 | 2025-10-30 00:11:15.413858 | TASK [Run ensure-virtualenv role] 2025-10-30 00:11:15.437400 | controller | ok 2025-10-30 00:11:15.463059 | 2025-10-30 00:11:15.463185 | TASK [ensure-virtualenv : Check if virtualenv is installed] 2025-10-30 00:11:15.841072 | controller | /usr/bin/virtualenv 2025-10-30 00:11:16.100292 | controller | ok: Runtime: 0:00:00.004156 2025-10-30 00:11:16.109682 | 2025-10-30 00:11:16.109874 | LOOP [ensure-virtualenv : Install virtualenv package] 2025-10-30 00:11:16.152037 | controller | skipping: Conditional result was False 2025-10-30 00:11:16.152367 | controller | changed: All items complete 2025-10-30 00:11:16.152406 | 2025-10-30 00:11:16.172910 | 2025-10-30 00:11:16.173057 | TASK [Find the full path of the Python interpreter] 2025-10-30 00:11:16.624994 | controller | /usr/bin/python3.9 2025-10-30 00:11:16.822444 | controller | ok 2025-10-30 00:11:16.832856 | 2025-10-30 00:11:16.832984 | TASK [Create virtualenv for ansible-test with a specific interpreter] 2025-10-30 00:11:19.068544 | controller | created virtual environment CPython3.9.18.final.0-64 in 1205ms 2025-10-30 00:11:19.159283 | controller | creator CPython3Posix(dest=/home/zuul-worker/venv, clear=False, no_vcs_ignore=False, global=False) 2025-10-30 00:11:19.159340 | 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-30 00:11:19.159357 | controller | added seed packages: pip==22.2.2, setuptools==62.6.0, wheel==0.37.1 2025-10-30 00:11:19.159379 | controller | activators BashActivator,CShellActivator,FishActivator,NushellActivator,PowerShellActivator,PythonActivator 2025-10-30 00:11:19.481393 | controller | changed 2025-10-30 00:11:19.487583 | 2025-10-30 00:11:19.487661 | TASK [Set selinux package] 2025-10-30 00:11:19.510430 | controller | ok 2025-10-30 00:11:19.517955 | 2025-10-30 00:11:19.518042 | TASK [Set selinux package (Fedora)] 2025-10-30 00:11:19.551261 | controller | ok 2025-10-30 00:11:19.557908 | 2025-10-30 00:11:19.557986 | TASK [Install selinux into virtualenv] 2025-10-30 00:11:23.051947 | controller | Collecting selinux-please-lie-to-me 2025-10-30 00:11:23.192544 | controller | Downloading selinux_please_lie_to_me-1.0.1-py2.py3-none-any.whl (3.8 kB) 2025-10-30 00:11:24.235701 | controller | Collecting setuptools<50.0.0 2025-10-30 00:11:24.254417 | controller | Downloading setuptools-49.6.0-py3-none-any.whl (803 kB) 2025-10-30 00:11:24.390584 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 803.3/803.3 kB 6.2 MB/s eta 0:00:00 2025-10-30 00:11:24.661166 | controller | Installing collected packages: setuptools, selinux-please-lie-to-me 2025-10-30 00:11:24.661990 | controller | Attempting uninstall: setuptools 2025-10-30 00:11:24.666385 | controller | Found existing installation: setuptools 62.6.0 2025-10-30 00:11:24.881458 | controller | Uninstalling setuptools-62.6.0: 2025-10-30 00:11:24.917897 | controller | Successfully uninstalled setuptools-62.6.0 2025-10-30 00:11:25.816775 | controller | Successfully installed selinux-please-lie-to-me-1.0.1 setuptools-49.6.0 2025-10-30 00:11:26.092982 | controller | 2025-10-30 00:11:26.382736 | controller | [notice] A new release of pip available: 22.2.2 -> 25.3 2025-10-30 00:11:26.382780 | controller | [notice] To update, run: /home/zuul-worker/venv/bin/python -m pip install --upgrade pip 2025-10-30 00:11:26.702416 | controller | ok: Runtime: 0:00:06.289738 2025-10-30 00:11:26.716501 | 2025-10-30 00:11:26.716650 | TASK [Install pytest-forked into virtualenv] 2025-10-30 00:11:28.332012 | controller | Collecting pytest-forked 2025-10-30 00:11:28.471228 | controller | Downloading pytest_forked-1.6.0-py3-none-any.whl (4.9 kB) 2025-10-30 00:11:28.833256 | controller | Collecting pytest>=3.10 2025-10-30 00:11:28.849218 | controller | Downloading pytest-8.4.2-py3-none-any.whl (365 kB) 2025-10-30 00:11:28.932552 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 365.8/365.8 kB 4.8 MB/s eta 0:00:00 2025-10-30 00:11:29.026297 | controller | Collecting py 2025-10-30 00:11:29.044105 | controller | Downloading py-1.11.0-py2.py3-none-any.whl (98 kB) 2025-10-30 00:11:29.054816 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 98.7/98.7 kB 14.1 MB/s eta 0:00:00 2025-10-30 00:11:29.203107 | controller | Collecting packaging>=20 2025-10-30 00:11:29.219769 | controller | Downloading packaging-25.0-py3-none-any.whl (66 kB) 2025-10-30 00:11:29.230817 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 66.5/66.5 kB 9.3 MB/s eta 0:00:00 2025-10-30 00:11:29.353629 | controller | Collecting tomli>=1 2025-10-30 00:11:29.373913 | controller | Downloading tomli-2.3.0-py3-none-any.whl (14 kB) 2025-10-30 00:11:29.513150 | controller | Collecting pygments>=2.7.2 2025-10-30 00:11:29.529203 | controller | Downloading pygments-2.19.2-py3-none-any.whl (1.2 MB) 2025-10-30 00:11:29.569121 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 1.2/1.2 MB 33.3 MB/s eta 0:00:00 2025-10-30 00:11:29.623298 | controller | Collecting pluggy<2,>=1.5 2025-10-30 00:11:29.638897 | controller | Downloading pluggy-1.6.0-py3-none-any.whl (20 kB) 2025-10-30 00:11:29.692579 | controller | Collecting exceptiongroup>=1 2025-10-30 00:11:29.708269 | controller | Downloading exceptiongroup-1.3.0-py3-none-any.whl (16 kB) 2025-10-30 00:11:29.748863 | controller | Collecting iniconfig>=1 2025-10-30 00:11:29.764588 | controller | Downloading iniconfig-2.1.0-py3-none-any.whl (6.0 kB) 2025-10-30 00:11:29.853967 | controller | Collecting typing-extensions>=4.6.0 2025-10-30 00:11:29.869567 | controller | Downloading typing_extensions-4.15.0-py3-none-any.whl (44 kB) 2025-10-30 00:11:29.879691 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 44.6/44.6 kB 6.0 MB/s eta 0:00:00 2025-10-30 00:11:30.010358 | controller | Installing collected packages: typing-extensions, tomli, pygments, py, pluggy, packaging, iniconfig, exceptiongroup, pytest, pytest-forked 2025-10-30 00:11:32.256662 | 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.3.0 typing-extensions-4.15.0 2025-10-30 00:11:32.263822 | controller | 2025-10-30 00:11:32.451952 | controller | [notice] A new release of pip available: 22.2.2 -> 25.3 2025-10-30 00:11:32.451999 | controller | [notice] To update, run: /home/zuul-worker/venv/bin/python -m pip install --upgrade pip 2025-10-30 00:11:32.878408 | controller | ok: Runtime: 0:00:05.167706 2025-10-30 00:11:32.884998 | 2025-10-30 00:11:32.885101 | TASK [Update pip] 2025-10-30 00:11:34.526837 | controller | Requirement already satisfied: pip in ./venv/lib/python3.9/site-packages (22.2.2) 2025-10-30 00:11:34.934034 | controller | Collecting pip 2025-10-30 00:11:35.029864 | controller | Downloading pip-25.3-py3-none-any.whl (1.8 MB) 2025-10-30 00:11:35.235857 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 1.8/1.8 MB 8.9 MB/s eta 0:00:00 2025-10-30 00:11:35.467302 | controller | Installing collected packages: pip 2025-10-30 00:11:35.467636 | controller | Attempting uninstall: pip 2025-10-30 00:11:35.471529 | controller | Found existing installation: pip 22.2.2 2025-10-30 00:11:35.938722 | controller | Uninstalling pip-22.2.2: 2025-10-30 00:11:35.998662 | controller | Successfully uninstalled pip-22.2.2 2025-10-30 00:11:37.834391 | controller | Successfully installed pip-25.3 2025-10-30 00:11:38.073576 | controller | ok: Runtime: 0:00:04.581368 2025-10-30 00:11:38.081518 | 2025-10-30 00:11:38.081606 | TASK [Ensure pip temp directory exists in /var/tmp] 2025-10-30 00:11:38.756053 | controller | changed 2025-10-30 00:11:38.762277 | 2025-10-30 00:11:38.762354 | TASK [Install ansible into virtualenv] 2025-10-30 00:11:40.271105 | controller | Processing ./src/github.com/ansible/ansible 2025-10-30 00:11:40.286393 | controller | Installing build dependencies: started 2025-10-30 00:11:42.912118 | controller | Installing build dependencies: finished with status 'done' 2025-10-30 00:11:42.914244 | controller | Getting requirements to build wheel: started 2025-10-30 00:11:44.152673 | controller | Getting requirements to build wheel: finished with status 'done' 2025-10-30 00:11:44.153248 | controller | Preparing metadata (pyproject.toml): started 2025-10-30 00:11:45.073677 | controller | Preparing metadata (pyproject.toml): finished with status 'done' 2025-10-30 00:11:45.201608 | controller | Collecting jinja2 (from ansible-core==2.11.12.post0) 2025-10-30 00:11:45.279047 | controller | Downloading jinja2-3.1.6-py3-none-any.whl.metadata (2.9 kB) 2025-10-30 00:11:45.406272 | controller | Collecting PyYAML (from ansible-core==2.11.12.post0) 2025-10-30 00:11:45.423497 | 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-30 00:11:45.962034 | controller | Collecting cryptography (from ansible-core==2.11.12.post0) 2025-10-30 00:11:45.976979 | controller | Downloading cryptography-46.0.3-cp38-abi3-manylinux_2_34_x86_64.whl.metadata (5.7 kB) 2025-10-30 00:11:46.000926 | controller | Requirement already satisfied: packaging in ./venv/lib/python3.9/site-packages (from ansible-core==2.11.12.post0) (25.0) 2025-10-30 00:11:46.026425 | controller | Collecting resolvelib<0.6.0,>=0.5.3 (from ansible-core==2.11.12.post0) 2025-10-30 00:11:46.041241 | controller | Downloading resolvelib-0.5.4-py2.py3-none-any.whl.metadata (3.7 kB) 2025-10-30 00:11:46.322967 | controller | Collecting cffi>=2.0.0 (from cryptography->ansible-core==2.11.12.post0) 2025-10-30 00:11:46.337884 | controller | Downloading cffi-2.0.0-cp39-cp39-manylinux2014_x86_64.manylinux_2_17_x86_64.whl.metadata (2.6 kB) 2025-10-30 00:11:46.349015 | 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-30 00:11:46.371426 | controller | Collecting pycparser (from cffi>=2.0.0->cryptography->ansible-core==2.11.12.post0) 2025-10-30 00:11:46.386219 | controller | Downloading pycparser-2.23-py3-none-any.whl.metadata (993 bytes) 2025-10-30 00:11:46.531095 | controller | Collecting MarkupSafe>=2.0 (from jinja2->ansible-core==2.11.12.post0) 2025-10-30 00:11:46.546209 | 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-30 00:11:46.576159 | controller | Downloading resolvelib-0.5.4-py2.py3-none-any.whl (12 kB) 2025-10-30 00:11:46.599361 | controller | Downloading cryptography-46.0.3-cp38-abi3-manylinux_2_34_x86_64.whl (4.5 MB) 2025-10-30 00:11:46.700955 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 4.5/4.5 MB 65.7 MB/s 0:00:00 2025-10-30 00:11:46.716095 | controller | Downloading cffi-2.0.0-cp39-cp39-manylinux2014_x86_64.manylinux_2_17_x86_64.whl (216 kB) 2025-10-30 00:11:46.741775 | controller | Downloading jinja2-3.1.6-py3-none-any.whl (134 kB) 2025-10-30 00:11:46.766643 | 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-30 00:11:46.789476 | controller | Downloading pycparser-2.23-py3-none-any.whl (118 kB) 2025-10-30 00:11:46.814941 | 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-30 00:11:46.833969 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 750.8/750.8 kB 35.7 MB/s 0:00:00 2025-10-30 00:11:46.878703 | controller | Building wheels for collected packages: ansible-core 2025-10-30 00:11:46.880344 | controller | Building wheel for ansible-core (pyproject.toml): started 2025-10-30 00:11:49.104980 | controller | Building wheel for ansible-core (pyproject.toml): finished with status 'done' 2025-10-30 00:11:49.111565 | controller | Created wheel for ansible-core: filename=ansible_core-2.11.12.post0-py3-none-any.whl size=1961250 sha256=4a30ad8f30f61cce78a787c3ffe939a3c6d681216a5188a02bd7fbf99628c096 2025-10-30 00:11:49.111850 | controller | Stored in directory: /var/tmp/ansible-test-pip/pip-ephem-wheel-cache-69pnmo2_/wheels/e8/fd/e9/6250c469bad44a7c6855190836e9d22c0bb11cb641caf57fbe 2025-10-30 00:11:49.121647 | controller | Successfully built ansible-core 2025-10-30 00:11:49.161179 | controller | Installing collected packages: resolvelib, PyYAML, pycparser, MarkupSafe, jinja2, cffi, cryptography, ansible-core 2025-10-30 00:11:51.059193 | controller | 2025-10-30 00:11:51.063483 | controller | Successfully installed MarkupSafe-3.0.3 PyYAML-6.0.3 ansible-core-2.11.12.post0 cffi-2.0.0 cryptography-46.0.3 jinja2-3.1.6 pycparser-2.23 resolvelib-0.5.4 2025-10-30 00:11:51.414152 | controller | ok: Runtime: 0:00:11.838246 2025-10-30 00:11:51.423543 | 2025-10-30 00:11:51.423697 | TASK [Setup download-artifact-fork role] 2025-10-30 00:11:51.458371 | controller | ok 2025-10-30 00:11:51.486912 | 2025-10-30 00:11:51.487033 | TASK [download-artifact-fork : Parse inventory response] 2025-10-30 00:11:51.527175 | controller | ok 2025-10-30 00:11:51.538322 | 2025-10-30 00:11:51.538423 | TASK [download-artifact-fork : Fetch info from Zuul API] 2025-10-30 00:11:51.562232 | controller | skipping: Conditional result was False 2025-10-30 00:11:51.568576 | 2025-10-30 00:11:51.568654 | TASK [download-artifact-fork : Parse build response] 2025-10-30 00:11:51.593074 | controller | skipping: Conditional result was False 2025-10-30 00:11:51.600608 | 2025-10-30 00:11:51.600741 | TASK [download-artifact-fork : Ensure artifacts directory exists] 2025-10-30 00:11:52.091404 | controller | changed 2025-10-30 00:11:52.096836 | 2025-10-30 00:11:52.096900 | LOOP [download-artifact-fork : Download archive by type] 2025-10-30 00:11:52.124835 | controller | skipping: Conditional result was False 2025-10-30 00:11:52.125111 | 2025-10-30 00:11:53.647436 | controller | changed: OK (20213 bytes) 2025-10-30 00:11:53.662431 | 2025-10-30 00:11:53.662546 | TASK [deploy-artifacts : define __collections] 2025-10-30 00:11:53.682949 | controller | ok 2025-10-30 00:11:53.688477 | 2025-10-30 00:11:53.688589 | LOOP [deploy-artifacts : Create list of collections] 2025-10-30 00:11:53.714561 | controller | skipping: Conditional result was False 2025-10-30 00:11:53.721346 | controller | ok: 2025-10-30 00:11:53.721425 | controller | { 2025-10-30 00:11:53.721448 | controller | "branch": "main", 2025-10-30 00:11:53.721466 | controller | "job": "build-ansible-collection", 2025-10-30 00:11:53.721482 | controller | "metadata": { 2025-10-30 00:11:53.721498 | controller | "type": "ansible_collection", 2025-10-30 00:11:53.721513 | controller | "version": "5.0.0" 2025-10-30 00:11:53.721528 | controller | }, 2025-10-30 00:11:53.721543 | controller | "name": "ansible.network", 2025-10-30 00:11:53.721558 | controller | "project": "ansible-collections/ansible.network", 2025-10-30 00:11:53.721573 | controller | "url": "https://f484f8460be7883f44c6-8ad00729ef31897d42c8eb3ebaf60c16.ssl.cf1.rackcdn.com/ansible/74027097c2764e31b24dc91c25bcca7b/artifacts/ansible-network-5.0.0.tar.gz" 2025-10-30 00:11:53.721594 | controller | } 2025-10-30 00:11:53.731058 | 2025-10-30 00:11:53.731179 | TASK [deploy-artifacts : Install require-project collection using ansible-galaxy] 2025-10-30 00:11:54.985272 | controller | Starting galaxy collection install process 2025-10-30 00:11:54.985793 | controller | Process install dependency map 2025-10-30 00:11:56.260110 | controller | [WARNING]: Skipping Galaxy server https://galaxy.ansible.com/api/. Got an 2025-10-30 00:11:56.260489 | controller | unexpected error when getting available versions of collection 2025-10-30 00:11:56.260530 | controller | ansible.netcommon: '/api/v3/plugin/ansible/content/published/collections/index/ 2025-10-30 00:11:56.260544 | controller | ansible/netcommon/versions/' 2025-10-30 00:11:56.260562 | controller | ERROR! Unexpected Exception, this is probably a bug: '/api/v3/plugin/ansible/content/published/collections/index/ansible/netcommon/versions/' 2025-10-30 00:11:56.260654 | controller | to see the full traceback, use -vvv 2025-10-30 00:11:56.874599 | controller | ERROR 2025-10-30 00:11:56.874809 | controller | { 2025-10-30 00:11:56.874841 | controller | "delta": "0:00:02.153583", 2025-10-30 00:11:56.874861 | controller | "end": "2025-10-30 00:11:56.341694", 2025-10-30 00:11:56.874880 | controller | "msg": "non-zero return code", 2025-10-30 00:11:56.874897 | controller | "rc": 250, 2025-10-30 00:11:56.874915 | controller | "start": "2025-10-30 00:11:54.188111" 2025-10-30 00:11:56.874932 | controller | } failure 2025-10-30 00:11:56.876386 | 2025-10-30 00:11:56.876434 | PLAY RECAP 2025-10-30 00:11:56.876499 | controller | ok: 44 changed: 24 unreachable: 0 failed: 1 skipped: 17 rescued: 0 ignored: 0 2025-10-30 00:11:56.876521 | 2025-10-30 00:11:57.014255 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master] 2025-10-30 00:11:57.015262 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2025-10-30 00:11:57.672052 | 2025-10-30 00:11:57.672171 | PLAY [all] 2025-10-30 00:11:57.693625 | 2025-10-30 00:11:57.693768 | TASK [fetch-subunit-output : Find stestr or testr executable] 2025-10-30 00:11:59.115651 | controller | changed: non-zero return code 2025-10-30 00:11:59.124342 | 2025-10-30 00:11:59.124556 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2025-10-30 00:11:59.150328 | controller | skipping: Conditional result was False 2025-10-30 00:11:59.160357 | 2025-10-30 00:11:59.160473 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2025-10-30 00:11:59.192769 | 2025-10-30 00:11:59.192936 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2025-10-30 00:11:59.213339 | 2025-10-30 00:11:59.213477 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2025-10-30 00:11:59.227634 | controller | skipping: Conditional result was False 2025-10-30 00:11:59.234759 | 2025-10-30 00:11:59.234969 | LOOP [fetch-subunit-output : Generate subunit file] 2025-10-30 00:11:59.254609 | 2025-10-30 00:11:59.254803 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2025-10-30 00:11:59.271193 | controller | skipping: Conditional result was False 2025-10-30 00:11:59.281144 | 2025-10-30 00:11:59.281252 | TASK [fetch-subunit-output : Remove the temporary file] 2025-10-30 00:11:59.296905 | controller | skipping: Conditional result was False 2025-10-30 00:11:59.306293 | 2025-10-30 00:11:59.306395 | TASK [fetch-subunit-output : Process and fetch subunit results] 2025-10-30 00:11:59.321925 | controller | skipping: Conditional result was False 2025-10-30 00:11:59.359907 | 2025-10-30 00:11:59.359998 | PLAY RECAP 2025-10-30 00:11:59.360041 | controller | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2025-10-30 00:11:59.360061 | 2025-10-30 00:11:59.460464 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2025-10-30 00:11:59.461464 | POST-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/post.yaml@master] 2025-10-30 00:12:00.090082 | 2025-10-30 00:12:00.090278 | PLAY [all:!appliance*] 2025-10-30 00:12:00.111137 | 2025-10-30 00:12:00.111292 | TASK [unregister the node] 2025-10-30 00:12:00.241707 | controller | skipping: Conditional result was False 2025-10-30 00:12:00.259046 | 2025-10-30 00:12:00.259305 | TASK [include_role : fetch-output] 2025-10-30 00:12:00.300578 | controller | ok 2025-10-30 00:12:00.331463 | 2025-10-30 00:12:00.331613 | TASK [fetch-output : Set log path for multiple nodes] 2025-10-30 00:12:00.377265 | controller | skipping: Conditional result was False 2025-10-30 00:12:00.385685 | 2025-10-30 00:12:00.385787 | TASK [fetch-output : Set log path for single node] 2025-10-30 00:12:00.434174 | controller | ok 2025-10-30 00:12:00.446030 | 2025-10-30 00:12:00.446171 | LOOP [fetch-output : Ensure local output dirs] 2025-10-30 00:12:00.913738 | controller -> localhost | ok: "/var/lib/zuul/builds/dffc80b46a5946ec855af16b0d94af65/work/logs" 2025-10-30 00:12:01.228006 | controller -> localhost | changed: "/var/lib/zuul/builds/dffc80b46a5946ec855af16b0d94af65/work/artifacts" 2025-10-30 00:12:01.497980 | controller -> localhost | changed: "/var/lib/zuul/builds/dffc80b46a5946ec855af16b0d94af65/work/docs" 2025-10-30 00:12:01.511826 | 2025-10-30 00:12:01.511966 | LOOP [fetch-output : Collect logs, artifacts and docs] 2025-10-30 00:12:03.572011 | controller | changed: 2025-10-30 00:12:03.572550 | controller | .d..t...... ./ 2025-10-30 00:12:03.572624 | controller | cd+++++++++ controller/ 2025-10-30 00:12:03.572739 | controller | changed: All items complete 2025-10-30 00:12:03.572796 | 2025-10-30 00:12:05.029860 | controller | changed: .d..t...... ./ 2025-10-30 00:12:06.134131 | controller | changed: .d..t...... ./ 2025-10-30 00:12:06.161166 | 2025-10-30 00:12:06.161360 | TASK [include_role : fetch-output-openshift] 2025-10-30 00:12:06.187010 | controller | skipping: Conditional result was False 2025-10-30 00:12:06.203950 | 2025-10-30 00:12:06.204194 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2025-10-30 00:12:06.241563 | controller | skipping: Conditional result was False 2025-10-30 00:12:06.254474 | controller | skipping: Conditional result was False 2025-10-30 00:12:06.322015 | 2025-10-30 00:12:06.322149 | PLAY [localhost] 2025-10-30 00:12:06.339562 | 2025-10-30 00:12:06.339713 | TASK [Run Zuul manifest role] 2025-10-30 00:12:06.363234 | localhost | ok 2025-10-30 00:12:06.382208 | 2025-10-30 00:12:06.382321 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-10-30 00:12:06.823081 | localhost | changed 2025-10-30 00:12:06.830054 | 2025-10-30 00:12:06.830158 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-10-30 00:12:06.886763 | localhost | ok 2025-10-30 00:12:06.901617 | 2025-10-30 00:12:06.901737 | TASK [Set zuul-log-path fact] 2025-10-30 00:12:06.922933 | localhost | ok 2025-10-30 00:12:06.944364 | 2025-10-30 00:12:06.944492 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-10-30 00:12:06.986363 | localhost | ok 2025-10-30 00:12:06.997486 | 2025-10-30 00:12:06.997571 | LOOP [Run upload-logs-swift role] 2025-10-30 00:12:07.043714 | localhost | Output suppressed because no_log was given 2025-10-30 00:12:07.080652 | 2025-10-30 00:12:07.080785 | TASK [Set zuul-log-path fact] 2025-10-30 00:12:07.126083 | localhost | skipping: Conditional result was False 2025-10-30 00:12:07.140165 | 2025-10-30 00:12:07.140329 | TASK [upload-logs-swift : Ensure logs are readable before uploading] 2025-10-30 00:12:07.625535 | localhost -> localhost | ok: Runtime: 0:00:00.005951 2025-10-30 00:12:07.631562 | 2025-10-30 00:12:07.631636 | TASK [upload-logs-swift : Upload logs to swift]