2025-09-27 00:06:34.452458 | Job console starting... 2025-09-27 00:06:34.463196 | Updating repositories 2025-09-27 00:06:34.633466 | Preparing job workspace 2025-09-27 00:06:38.620895 | Running Ansible setup... 2025-09-27 00:06:44.618322 | PRE-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master] 2025-09-27 00:06:45.193976 | 2025-09-27 00:06:45.194096 | PLAY [localhost] 2025-09-27 00:06:45.202515 | 2025-09-27 00:06:45.202587 | TASK [Gathering Facts] 2025-09-27 00:06:46.193060 | localhost | ok 2025-09-27 00:06:46.217949 | 2025-09-27 00:06:46.218055 | TASK [Setup log path fact] 2025-09-27 00:06:46.237609 | localhost | ok 2025-09-27 00:06:46.255030 | 2025-09-27 00:06:46.255125 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-09-27 00:06:46.297765 | localhost | ok 2025-09-27 00:06:46.310390 | 2025-09-27 00:06:46.310480 | TASK [emit-job-header : Print job information] 2025-09-27 00:06:46.355792 | # Job Information 2025-09-27 00:06:46.356031 | Ansible Version: 2.15.12 2025-09-27 00:06:46.356102 | Job: ansible-test-sanity-docker-stable-2.12 2025-09-27 00:06:46.356155 | Pipeline: periodic 2025-09-27 00:06:46.356200 | Executor: ze02.softwarefactory-project.io 2025-09-27 00:06:46.356243 | Triggered by: https://github.com/ansible-collections/ansible.network 2025-09-27 00:06:46.356292 | Log URL (when completed): https://ansible.softwarefactory-project.io/logs/795/ansible/795598aaabfb407bad79a247db7f4564/ 2025-09-27 00:06:46.356337 | Event ID: 6059628ede9d40b08c9c7e960ce1822b 2025-09-27 00:06:46.365291 | 2025-09-27 00:06:46.365562 | LOOP [emit-job-header : Print node information] 2025-09-27 00:06:46.482998 | localhost | ok: 2025-09-27 00:06:46.483734 | localhost | # Node Information 2025-09-27 00:06:46.483780 | localhost | Inventory Hostname: controller 2025-09-27 00:06:46.483802 | localhost | Hostname: ip-172-16-199-82 2025-09-27 00:06:46.483822 | localhost | Username: zuul-worker 2025-09-27 00:06:46.483843 | localhost | Distro: Fedora 37 2025-09-27 00:06:46.483861 | localhost | Provider: ansible-us-east-2 2025-09-27 00:06:46.483879 | localhost | Region: us-east-2 2025-09-27 00:06:46.483896 | localhost | Label: ansible-fedora-37-1vcpu 2025-09-27 00:06:46.483912 | localhost | Product Name: t3.small 2025-09-27 00:06:46.483928 | localhost | Interface IP: 3.16.21.71 2025-09-27 00:06:46.501097 | 2025-09-27 00:06:46.501198 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-09-27 00:06:46.913251 | localhost -> localhost | changed 2025-09-27 00:06:46.920151 | 2025-09-27 00:06:46.920231 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-09-27 00:06:47.692092 | localhost -> localhost | changed 2025-09-27 00:06:47.715348 | 2025-09-27 00:06:47.715438 | PLAY [all:!appliance*] 2025-09-27 00:06:47.730423 | 2025-09-27 00:06:47.730486 | TASK [include_role : start-zuul-console] 2025-09-27 00:06:47.748833 | controller | ok 2025-09-27 00:06:47.761944 | 2025-09-27 00:06:47.762008 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-09-27 00:06:48.439917 | controller | ok 2025-09-27 00:06:48.465276 | 2025-09-27 00:06:48.465369 | TASK [use-our-mirror : Retrieve the IP address] 2025-09-27 00:06:53.145284 | controller | ok: Status code was 404 and not [200]: HTTP Error 404: Not Found 2025-09-27 00:06:53.154091 | 2025-09-27 00:06:53.154180 | TASK [use-our-mirror : Ensure the registry service is running on the mirror] 2025-09-27 00:06:53.290426 | controller | skipping: Conditional result was False 2025-09-27 00:06:53.299857 | 2025-09-27 00:06:53.299940 | TASK [use-our-mirror : Write the IP in /etc/hosts] 2025-09-27 00:06:53.335212 | controller | skipping: Conditional result was False 2025-09-27 00:06:53.341900 | 2025-09-27 00:06:53.341969 | TASK [use-our-mirror : Create the podman configuration directory] 2025-09-27 00:06:53.366464 | controller | skipping: Conditional result was False 2025-09-27 00:06:53.379763 | 2025-09-27 00:06:53.379908 | TASK [use-our-mirror : Copy the podman configuration] 2025-09-27 00:06:53.407208 | controller | skipping: Conditional result was False 2025-09-27 00:06:53.415968 | 2025-09-27 00:06:53.416061 | TASK [use-our-mirror : Copy the Yum configuration (main)] 2025-09-27 00:06:53.450648 | controller | skipping: Conditional result was False 2025-09-27 00:06:53.460087 | 2025-09-27 00:06:53.460184 | TASK [use-our-mirror : Copy the Yum configuration (update)] 2025-09-27 00:06:53.484855 | controller | skipping: Conditional result was False 2025-09-27 00:06:53.506815 | 2025-09-27 00:06:53.506900 | TASK [Disable Fedora Modular] 2025-09-27 00:06:54.241292 | controller | changed 2025-09-27 00:06:54.253933 | 2025-09-27 00:06:54.254067 | TASK [Enable EPEL] 2025-09-27 00:06:54.280722 | controller | skipping: Conditional result was False 2025-09-27 00:06:54.294919 | 2025-09-27 00:06:54.295059 | TASK [Register the RHEL node] 2025-09-27 00:06:54.493037 | 2025-09-27 00:06:54.493204 | TASK [Show the subscription-manager status] 2025-09-27 00:06:54.635021 | controller | skipping: Conditional result was False 2025-09-27 00:06:54.648597 | 2025-09-27 00:06:54.648761 | TASK [Enable EPEL on RHEL] 2025-09-27 00:06:54.792502 | controller | skipping: Conditional result was False 2025-09-27 00:06:54.798497 | 2025-09-27 00:06:54.798572 | TASK [Install git and tox] 2025-09-27 00:08:20.556878 | controller | changed 2025-09-27 00:08:20.562595 | 2025-09-27 00:08:20.562653 | TASK [include_role : prepare-workspace] 2025-09-27 00:08:20.596917 | controller | ok 2025-09-27 00:08:20.624702 | 2025-09-27 00:08:20.624836 | TASK [prepare-workspace : Start zuul_console daemon.] 2025-09-27 00:08:21.171281 | controller | ok 2025-09-27 00:08:21.185441 | 2025-09-27 00:08:21.185613 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2025-09-27 00:08:34.556644 | controller | Output suppressed because no_log was given 2025-09-27 00:08:34.568050 | 2025-09-27 00:08:34.568114 | TASK [include_role : prepare-workspace-openshift] 2025-09-27 00:08:34.592437 | controller | skipping: Conditional result was False 2025-09-27 00:08:34.613770 | 2025-09-27 00:08:34.613837 | PLAY [all:!appliance] 2025-09-27 00:08:34.629959 | 2025-09-27 00:08:34.630033 | TASK [Run add-build-sshkey role (RSA)] 2025-09-27 00:08:34.659908 | controller | ok 2025-09-27 00:08:34.677116 | 2025-09-27 00:08:34.677203 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-09-27 00:08:34.924220 | controller -> localhost | ok 2025-09-27 00:08:34.931168 | 2025-09-27 00:08:34.931239 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-09-27 00:08:34.962607 | controller | ok 2025-09-27 00:08:34.976752 | controller | included: /var/lib/zuul/builds/795598aaabfb407bad79a247db7f4564/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-09-27 00:08:34.982736 | 2025-09-27 00:08:34.982798 | TASK [add-build-sshkey : Create Temp SSH key] 2025-09-27 00:08:35.541162 | controller -> localhost | Generating public/private rsa key pair. 2025-09-27 00:08:35.543045 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/795598aaabfb407bad79a247db7f4564/work/795598aaabfb407bad79a247db7f4564_id_rsa. 2025-09-27 00:08:35.543147 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/795598aaabfb407bad79a247db7f4564/work/795598aaabfb407bad79a247db7f4564_id_rsa.pub. 2025-09-27 00:08:35.543213 | controller -> localhost | The key fingerprint is: 2025-09-27 00:08:35.543275 | controller -> localhost | SHA256:JhsRZ4U0gW+wOJ+frpQOfxowO6VDxZyCZboXTXG7w1A zuul-build-sshkey 2025-09-27 00:08:35.543335 | controller -> localhost | The key's randomart image is: 2025-09-27 00:08:35.543393 | controller -> localhost | +---[RSA 2048]----+ 2025-09-27 00:08:35.543453 | controller -> localhost | | o ++E+. | 2025-09-27 00:08:35.543514 | controller -> localhost | | = =oB.o | 2025-09-27 00:08:35.543577 | controller -> localhost | | o o.O+. | 2025-09-27 00:08:35.543634 | controller -> localhost | | .o+.+o. | 2025-09-27 00:08:35.543750 | controller -> localhost | | . =o+oS | 2025-09-27 00:08:35.543836 | controller -> localhost | | o *o* . | 2025-09-27 00:08:35.543906 | controller -> localhost | | * =. . | 2025-09-27 00:08:35.543971 | controller -> localhost | | B .+ | 2025-09-27 00:08:35.544031 | controller -> localhost | | ==. | 2025-09-27 00:08:35.544095 | controller -> localhost | +----[SHA256]-----+ 2025-09-27 00:08:35.544212 | controller -> localhost | ok: Runtime: 0:00:00.118696 2025-09-27 00:08:35.562264 | 2025-09-27 00:08:35.562772 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-09-27 00:08:35.599926 | controller | ok 2025-09-27 00:08:35.615412 | controller | included: /var/lib/zuul/builds/795598aaabfb407bad79a247db7f4564/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-09-27 00:08:35.628165 | 2025-09-27 00:08:35.628266 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-09-27 00:08:35.665486 | controller | skipping: Conditional result was False 2025-09-27 00:08:35.676996 | 2025-09-27 00:08:35.677140 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-09-27 00:08:36.320301 | controller | changed 2025-09-27 00:08:36.328458 | 2025-09-27 00:08:36.328532 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-09-27 00:08:36.683049 | controller | ok 2025-09-27 00:08:36.700432 | 2025-09-27 00:08:36.700594 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-09-27 00:08:38.397097 | controller | changed 2025-09-27 00:08:38.409061 | 2025-09-27 00:08:38.409186 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-09-27 00:08:39.671358 | controller | changed 2025-09-27 00:08:39.687271 | 2025-09-27 00:08:39.687419 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-09-27 00:08:39.714765 | controller | skipping: Conditional result was False 2025-09-27 00:08:39.729477 | 2025-09-27 00:08:39.729624 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-09-27 00:08:40.155510 | controller -> localhost | changed 2025-09-27 00:08:40.179977 | 2025-09-27 00:08:40.180212 | TASK [add-build-sshkey : Add back temp key] 2025-09-27 00:08:40.483926 | controller -> localhost | Identity added: /var/lib/zuul/builds/795598aaabfb407bad79a247db7f4564/work/795598aaabfb407bad79a247db7f4564_id_rsa (zuul-build-sshkey) 2025-09-27 00:08:40.484394 | controller -> localhost | ok: Runtime: 0:00:00.015307 2025-09-27 00:08:40.499901 | 2025-09-27 00:08:40.500067 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-09-27 00:08:41.039956 | controller | ok 2025-09-27 00:08:41.052993 | 2025-09-27 00:08:41.053198 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-09-27 00:08:41.091857 | controller | skipping: Conditional result was False 2025-09-27 00:08:41.122118 | 2025-09-27 00:08:41.122304 | TASK [Run add-build-sshkey role (ECDSA)] 2025-09-27 00:08:41.163809 | controller | ok 2025-09-27 00:08:41.195012 | 2025-09-27 00:08:41.195135 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-09-27 00:08:41.529344 | controller -> localhost | ok 2025-09-27 00:08:41.537966 | 2025-09-27 00:08:41.538051 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-09-27 00:08:41.570861 | controller | ok 2025-09-27 00:08:41.584265 | controller | included: /var/lib/zuul/builds/795598aaabfb407bad79a247db7f4564/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-09-27 00:08:41.590448 | 2025-09-27 00:08:41.590514 | TASK [add-build-sshkey : Create Temp SSH key] 2025-09-27 00:08:41.933981 | controller -> localhost | Generating public/private ecdsa key pair. 2025-09-27 00:08:41.934421 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/795598aaabfb407bad79a247db7f4564/work/795598aaabfb407bad79a247db7f4564_id_ecdsa. 2025-09-27 00:08:41.934475 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/795598aaabfb407bad79a247db7f4564/work/795598aaabfb407bad79a247db7f4564_id_ecdsa.pub. 2025-09-27 00:08:41.934521 | controller -> localhost | The key fingerprint is: 2025-09-27 00:08:41.934551 | controller -> localhost | SHA256:e/qHokdMgSLJqImBqbUlhJrOrpUNyEil9wGH11aJtKA zuul-build-sshkey 2025-09-27 00:08:41.934581 | controller -> localhost | The key's randomart image is: 2025-09-27 00:08:41.934619 | controller -> localhost | +---[ECDSA 521]---+ 2025-09-27 00:08:41.934657 | controller -> localhost | |.*.+.oooo.. | 2025-09-27 00:08:41.934720 | controller -> localhost | |* O.*.o+o. | 2025-09-27 00:08:41.934749 | controller -> localhost | |=B E.o.. . | 2025-09-27 00:08:41.934774 | controller -> localhost | |@.o . . . | 2025-09-27 00:08:41.934799 | controller -> localhost | |=.. . oS | 2025-09-27 00:08:41.934825 | controller -> localhost | | o + o. | 2025-09-27 00:08:41.934859 | controller -> localhost | |. o . .. .. | 2025-09-27 00:08:41.934896 | controller -> localhost | | o oo. . | 2025-09-27 00:08:41.934924 | controller -> localhost | |o .o.o.. | 2025-09-27 00:08:41.934949 | controller -> localhost | +----[SHA256]-----+ 2025-09-27 00:08:41.935022 | controller -> localhost | ok: Runtime: 0:00:00.016360 2025-09-27 00:08:41.947080 | 2025-09-27 00:08:41.947197 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-09-27 00:08:41.992570 | controller | ok 2025-09-27 00:08:42.000890 | controller | included: /var/lib/zuul/builds/795598aaabfb407bad79a247db7f4564/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-09-27 00:08:42.010009 | 2025-09-27 00:08:42.010073 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-09-27 00:08:42.036685 | controller | skipping: Conditional result was False 2025-09-27 00:08:42.044978 | 2025-09-27 00:08:42.045129 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-09-27 00:08:42.493840 | controller | changed 2025-09-27 00:08:42.499825 | 2025-09-27 00:08:42.499929 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-09-27 00:08:42.826058 | controller | ok 2025-09-27 00:08:42.832101 | 2025-09-27 00:08:42.832198 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-09-27 00:08:44.115864 | controller | changed 2025-09-27 00:08:44.123513 | 2025-09-27 00:08:44.123612 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-09-27 00:08:45.516280 | controller | changed 2025-09-27 00:08:45.531565 | 2025-09-27 00:08:45.531978 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-09-27 00:08:45.602108 | controller | skipping: Conditional result was False 2025-09-27 00:08:45.664681 | 2025-09-27 00:08:45.664823 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-09-27 00:08:45.918207 | controller -> localhost | changed 2025-09-27 00:08:45.930106 | 2025-09-27 00:08:45.930177 | TASK [add-build-sshkey : Add back temp key] 2025-09-27 00:08:46.195552 | controller -> localhost | Identity added: /var/lib/zuul/builds/795598aaabfb407bad79a247db7f4564/work/795598aaabfb407bad79a247db7f4564_id_ecdsa (zuul-build-sshkey) 2025-09-27 00:08:46.195758 | controller -> localhost | ok: Runtime: 0:00:00.012445 2025-09-27 00:08:46.202470 | 2025-09-27 00:08:46.202621 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-09-27 00:08:46.570179 | controller | ok 2025-09-27 00:08:46.577061 | 2025-09-27 00:08:46.577142 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-09-27 00:08:46.643026 | controller | skipping: Conditional result was False 2025-09-27 00:08:46.655274 | 2025-09-27 00:08:46.655385 | TASK [include_role : remove-zuul-sshkey] 2025-09-27 00:08:46.679313 | controller | skipping: Conditional result was False 2025-09-27 00:08:46.685792 | 2025-09-27 00:08:46.685863 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2025-09-27 00:08:46.996518 | controller | ok: "logs" 2025-09-27 00:08:46.996816 | controller | ok: All items complete 2025-09-27 00:08:46.996848 | 2025-09-27 00:08:47.293445 | controller | ok: "artifacts" 2025-09-27 00:08:47.581157 | controller | ok: "docs" 2025-09-27 00:08:47.602094 | 2025-09-27 00:08:47.602272 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2025-09-27 00:08:47.951196 | controller | changed: "logs" 2025-09-27 00:08:48.216906 | controller | changed: "artifacts" 2025-09-27 00:08:48.505327 | controller | changed: "docs" 2025-09-27 00:08:48.541056 | 2025-09-27 00:08:48.541151 | PLAY RECAP 2025-09-27 00:08:48.541191 | controller | ok: 30 changed: 16 unreachable: 0 failed: 0 skipped: 18 rescued: 0 ignored: 0 2025-09-27 00:08:48.541217 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-09-27 00:08:48.541234 | 2025-09-27 00:08:48.638607 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master] 2025-09-27 00:08:48.639460 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2025-09-27 00:08:49.238407 | 2025-09-27 00:08:49.238530 | PLAY [all] 2025-09-27 00:08:49.259160 | 2025-09-27 00:08:49.259257 | TASK [Install binary dependencies] 2025-09-27 00:08:49.339232 | controller | ok 2025-09-27 00:08:49.365067 | 2025-09-27 00:08:49.365181 | TASK [bindep : Include find tasks] 2025-09-27 00:08:49.397617 | controller | ok 2025-09-27 00:08:49.407827 | controller | included: /var/lib/zuul/builds/795598aaabfb407bad79a247db7f4564/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find.yaml 2025-09-27 00:08:49.416045 | 2025-09-27 00:08:49.416130 | TASK [bindep : Look for bindep.txt] 2025-09-27 00:08:49.956852 | controller | ok 2025-09-27 00:08:49.963791 | 2025-09-27 00:08:49.963865 | TASK [bindep : Define bindep_file fact] 2025-09-27 00:08:49.977783 | controller | skipping: Conditional result was False 2025-09-27 00:08:49.984038 | 2025-09-27 00:08:49.984108 | TASK [bindep : Look for other-requirements.txt] 2025-09-27 00:08:50.265927 | controller | ok 2025-09-27 00:08:50.271799 | 2025-09-27 00:08:50.271887 | TASK [bindep : Define bindep_file fact] 2025-09-27 00:08:50.305835 | controller | skipping: Conditional result was False 2025-09-27 00:08:50.312707 | 2025-09-27 00:08:50.312819 | TASK [bindep : Look for bindep fallback file] 2025-09-27 00:08:50.357069 | controller | skipping: Conditional result was False 2025-09-27 00:08:50.363276 | 2025-09-27 00:08:50.363351 | TASK [bindep : Define bindep_file fact] 2025-09-27 00:08:50.387280 | controller | skipping: Conditional result was False 2025-09-27 00:08:50.394856 | 2025-09-27 00:08:50.394999 | TASK [bindep : Include bindep tasks] 2025-09-27 00:08:50.428821 | controller | skipping: Conditional result was False 2025-09-27 00:08:50.436511 | 2025-09-27 00:08:50.436596 | TASK [bindep : Include install tasks] 2025-09-27 00:08:50.470900 | controller | skipping: Conditional result was False 2025-09-27 00:08:50.479739 | 2025-09-27 00:08:50.479856 | LOOP [bindep : Include package tasks] 2025-09-27 00:08:50.577715 | 2025-09-27 00:08:50.577989 | TASK [Run test-setup role] 2025-09-27 00:08:50.604826 | controller | ok 2025-09-27 00:08:50.634774 | 2025-09-27 00:08:50.634907 | TASK [test-setup : Check if project's tools/test-setup.sh exists] 2025-09-27 00:08:50.965870 | controller | ok 2025-09-27 00:08:50.978194 | 2025-09-27 00:08:50.978331 | TASK [test-setup : Run tools/test-setup.sh] 2025-09-27 00:08:51.129406 | controller | skipping: Conditional result was False 2025-09-27 00:08:51.178855 | 2025-09-27 00:08:51.178958 | PLAY RECAP 2025-09-27 00:08:51.179012 | controller | ok: 4 changed: 0 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2025-09-27 00:08:51.179039 | 2025-09-27 00:08:51.273634 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2025-09-27 00:08:51.274438 | PRE-RUN START: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master] 2025-09-27 00:08:51.876319 | 2025-09-27 00:08:51.876440 | PLAY [controller] 2025-09-27 00:08:51.897186 | 2025-09-27 00:08:51.897287 | TASK [Create the /root directory] 2025-09-27 00:08:52.604466 | controller | ok 2025-09-27 00:08:52.619829 | 2025-09-27 00:08:52.620033 | TASK [Install glibc-langpack-en] 2025-09-27 00:09:00.804752 | controller | ok: Nothing to do 2025-09-27 00:09:00.818289 | 2025-09-27 00:09:00.818412 | TASK [Ensure controller directory exists] 2025-09-27 00:09:01.335596 | controller | changed 2025-09-27 00:09:01.348975 | 2025-09-27 00:09:01.349152 | TASK [Install container runtime] 2025-09-27 00:09:01.430182 | controller | ok 2025-09-27 00:09:01.494584 | 2025-09-27 00:09:01.494727 | LOOP [ensure-podman : Find distribution installation] 2025-09-27 00:09:01.533731 | controller | ok: "/var/lib/zuul/builds/795598aaabfb407bad79a247db7f4564/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-podman/tasks/RedHat.yaml" 2025-09-27 00:09:01.547648 | controller | included: /var/lib/zuul/builds/795598aaabfb407bad79a247db7f4564/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-podman/tasks/RedHat.yaml 2025-09-27 00:09:01.573768 | 2025-09-27 00:09:01.573976 | TASK [ensure-podman : Install podman (RedHat)] 2025-09-27 00:10:17.692208 | controller | changed 2025-09-27 00:10:17.700170 | 2025-09-27 00:10:17.700260 | TASK [ensure-podman : Fetch podman version] 2025-09-27 00:10:18.519728 | controller | Client: Podman Engine 2025-09-27 00:10:18.544864 | controller | Version: 4.6.2 2025-09-27 00:10:18.544915 | controller | API Version: 4.6.2 2025-09-27 00:10:18.544925 | controller | Go Version: go1.19.12 2025-09-27 00:10:18.544947 | controller | Built: Mon Aug 28 19:38:31 2023 2025-09-27 00:10:18.544959 | controller | OS/Arch: linux/amd64 2025-09-27 00:10:18.845222 | controller | ok: Runtime: 0:00:00.235839 2025-09-27 00:10:18.851765 | 2025-09-27 00:10:18.851884 | TASK [ensure-podman : Print podman version installed] 2025-09-27 00:10:18.917196 | Podman version: Client: Podman Engine 2025-09-27 00:10:18.917345 | Version: 4.6.2 2025-09-27 00:10:18.917373 | API Version: 4.6.2 2025-09-27 00:10:18.917393 | Go Version: go1.19.12 2025-09-27 00:10:18.917411 | Built: Mon Aug 28 19:38:31 2023 2025-09-27 00:10:18.917431 | OS/Arch: linux/amd64 2025-09-27 00:10:18.927826 | 2025-09-27 00:10:18.927944 | TASK [ensure-podman : Validate podman engine] 2025-09-27 00:10:19.097654 | controller | skipping: Conditional result was False 2025-09-27 00:10:19.112413 | 2025-09-27 00:10:19.112549 | TASK [ensure-podman : Set up docker compatability socket] 2025-09-27 00:10:19.133917 | controller | skipping: Conditional result was False 2025-09-27 00:10:19.170160 | 2025-09-27 00:10:19.170255 | TASK [Ensure python3.8 is present] 2025-09-27 00:10:19.204891 | controller | skipping: Conditional result was False 2025-09-27 00:10:19.212148 | 2025-09-27 00:10:19.212303 | TASK [Run our-ensure-python role (Fedora only for now)] 2025-09-27 00:10:19.253183 | controller | ok 2025-09-27 00:10:19.289267 | 2025-09-27 00:10:19.289404 | TASK [our-ensure-python : Install the right Python version (rpm)] 2025-09-27 00:10:23.148996 | controller | ok: Nothing to do 2025-09-27 00:10:23.158988 | 2025-09-27 00:10:23.159103 | TASK [our-ensure-python : Also install python3-devel] 2025-09-27 00:10:36.719798 | controller | changed 2025-09-27 00:10:36.733351 | 2025-09-27 00:10:36.733498 | TASK [Run ensure-virtualenv role] 2025-09-27 00:10:36.776363 | controller | ok 2025-09-27 00:10:36.802909 | 2025-09-27 00:10:36.803026 | TASK [ensure-virtualenv : Check if virtualenv is installed] 2025-09-27 00:10:37.247679 | controller | /usr/bin/virtualenv 2025-09-27 00:10:37.485335 | controller | ok: Runtime: 0:00:00.004256 2025-09-27 00:10:37.491229 | 2025-09-27 00:10:37.491329 | LOOP [ensure-virtualenv : Install virtualenv package] 2025-09-27 00:10:37.533502 | controller | skipping: Conditional result was False 2025-09-27 00:10:37.533808 | controller | ok: All items complete 2025-09-27 00:10:37.533845 | 2025-09-27 00:10:37.560548 | 2025-09-27 00:10:37.560735 | TASK [Find the full path of the Python interpreter] 2025-09-27 00:10:38.021942 | controller | /usr/bin/python3.10 2025-09-27 00:10:38.212932 | controller | ok 2025-09-27 00:10:38.222408 | 2025-09-27 00:10:38.222564 | TASK [Create virtualenv for ansible-test with a specific interpreter] 2025-09-27 00:10:39.890478 | controller | created virtual environment CPython3.10.13.final.0-64 in 880ms 2025-09-27 00:10:39.930478 | controller | creator CPython3Posix(dest=/home/zuul-worker/venv, clear=False, no_vcs_ignore=False, global=False) 2025-09-27 00:10:39.930534 | controller | seeder FromAppData(extra_search_dir=/usr/share/python-wheels,download=False, pip=bundle, setuptools=bundle, wheel=bundle, via=copy, app_data_dir=/home/zuul-worker/.local/share/virtualenv) 2025-09-27 00:10:39.930549 | controller | added seed packages: pip==22.2.2, setuptools==62.6.0, wheel==0.37.1 2025-09-27 00:10:39.930574 | controller | activators BashActivator,CShellActivator,FishActivator,NushellActivator,PowerShellActivator,PythonActivator 2025-09-27 00:10:40.375021 | controller | changed 2025-09-27 00:10:40.385286 | 2025-09-27 00:10:40.385391 | TASK [Set selinux package] 2025-09-27 00:10:40.437923 | controller | ok 2025-09-27 00:10:40.457014 | 2025-09-27 00:10:40.457150 | TASK [Set selinux package (Fedora)] 2025-09-27 00:10:40.499999 | controller | ok 2025-09-27 00:10:40.507572 | 2025-09-27 00:10:40.507733 | TASK [Install selinux into virtualenv] 2025-09-27 00:10:43.178452 | controller | Collecting selinux-please-lie-to-me 2025-09-27 00:10:43.266790 | controller | Downloading selinux_please_lie_to_me-1.0.1-py2.py3-none-any.whl (3.8 kB) 2025-09-27 00:10:43.868082 | controller | Collecting setuptools<50.0.0 2025-09-27 00:10:43.881836 | controller | Downloading setuptools-49.6.0-py3-none-any.whl (803 kB) 2025-09-27 00:10:44.010162 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 803.3/803.3 kB 6.6 MB/s eta 0:00:00 2025-09-27 00:10:44.152288 | controller | Installing collected packages: setuptools, selinux-please-lie-to-me 2025-09-27 00:10:44.152620 | controller | Attempting uninstall: setuptools 2025-09-27 00:10:44.154078 | controller | Found existing installation: setuptools 62.6.0 2025-09-27 00:10:44.283399 | controller | Uninstalling setuptools-62.6.0: 2025-09-27 00:10:44.300887 | controller | Successfully uninstalled setuptools-62.6.0 2025-09-27 00:10:44.998142 | controller | Successfully installed selinux-please-lie-to-me-1.0.1 setuptools-49.6.0 2025-09-27 00:10:45.214899 | controller | 2025-09-27 00:10:45.423239 | controller | [notice] A new release of pip available: 22.2.2 -> 25.2 2025-09-27 00:10:45.423292 | controller | [notice] To update, run: /home/zuul-worker/venv/bin/python -m pip install --upgrade pip 2025-09-27 00:10:45.684795 | controller | ok: Runtime: 0:00:04.418579 2025-09-27 00:10:45.690774 | 2025-09-27 00:10:45.691018 | TASK [Install pytest-forked into virtualenv] 2025-09-27 00:10:46.965502 | controller | Collecting pytest-forked 2025-09-27 00:10:47.053561 | controller | Downloading pytest_forked-1.6.0-py3-none-any.whl (4.9 kB) 2025-09-27 00:10:47.115813 | controller | Collecting py 2025-09-27 00:10:47.129227 | controller | Downloading py-1.11.0-py2.py3-none-any.whl (98 kB) 2025-09-27 00:10:47.174661 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 98.7/98.7 kB 2.3 MB/s eta 0:00:00 2025-09-27 00:10:47.367045 | controller | Collecting pytest>=3.10 2025-09-27 00:10:47.380754 | controller | Downloading pytest-8.4.2-py3-none-any.whl (365 kB) 2025-09-27 00:10:47.466854 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 365.8/365.8 kB 4.4 MB/s eta 0:00:00 2025-09-27 00:10:47.573575 | controller | Collecting pygments>=2.7.2 2025-09-27 00:10:47.587579 | controller | Downloading pygments-2.19.2-py3-none-any.whl (1.2 MB) 2025-09-27 00:10:47.813519 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 1.2/1.2 MB 5.5 MB/s eta 0:00:00 2025-09-27 00:10:47.883721 | controller | Collecting tomli>=1 2025-09-27 00:10:47.897395 | controller | Downloading tomli-2.2.1-py3-none-any.whl (14 kB) 2025-09-27 00:10:47.934291 | controller | Collecting iniconfig>=1 2025-09-27 00:10:47.947415 | controller | Downloading iniconfig-2.1.0-py3-none-any.whl (6.0 kB) 2025-09-27 00:10:48.021872 | controller | Collecting packaging>=20 2025-09-27 00:10:48.035826 | controller | Downloading packaging-25.0-py3-none-any.whl (66 kB) 2025-09-27 00:10:48.054399 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 66.5/66.5 kB 3.9 MB/s eta 0:00:00 2025-09-27 00:10:48.103813 | controller | Collecting exceptiongroup>=1 2025-09-27 00:10:48.117327 | controller | Downloading exceptiongroup-1.3.0-py3-none-any.whl (16 kB) 2025-09-27 00:10:48.170480 | controller | Collecting pluggy<2,>=1.5 2025-09-27 00:10:48.184102 | controller | Downloading pluggy-1.6.0-py3-none-any.whl (20 kB) 2025-09-27 00:10:48.267106 | controller | Collecting typing-extensions>=4.6.0 2025-09-27 00:10:48.280988 | controller | Downloading typing_extensions-4.15.0-py3-none-any.whl (44 kB) 2025-09-27 00:10:48.292098 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 44.6/44.6 kB 5.1 MB/s eta 0:00:00 2025-09-27 00:10:48.431983 | controller | Installing collected packages: typing-extensions, tomli, pygments, py, pluggy, packaging, iniconfig, exceptiongroup, pytest, pytest-forked 2025-09-27 00:10:50.690440 | controller | Successfully installed exceptiongroup-1.3.0 iniconfig-2.1.0 packaging-25.0 pluggy-1.6.0 py-1.11.0 pygments-2.19.2 pytest-8.4.2 pytest-forked-1.6.0 tomli-2.2.1 typing-extensions-4.15.0 2025-09-27 00:10:50.697253 | controller | 2025-09-27 00:10:50.875631 | controller | [notice] A new release of pip available: 22.2.2 -> 25.2 2025-09-27 00:10:50.875675 | controller | [notice] To update, run: /home/zuul-worker/venv/bin/python -m pip install --upgrade pip 2025-09-27 00:10:51.379005 | controller | ok: Runtime: 0:00:04.647732 2025-09-27 00:10:51.387632 | 2025-09-27 00:10:51.387796 | TASK [Update pip] 2025-09-27 00:10:52.559023 | controller | Requirement already satisfied: pip in ./venv/lib/python3.10/site-packages (22.2.2) 2025-09-27 00:10:52.790166 | controller | Collecting pip 2025-09-27 00:10:52.879131 | controller | Downloading pip-25.2-py3-none-any.whl (1.8 MB) 2025-09-27 00:10:53.049647 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 1.8/1.8 MB 10.5 MB/s eta 0:00:00 2025-09-27 00:10:53.185638 | controller | Installing collected packages: pip 2025-09-27 00:10:53.186099 | controller | Attempting uninstall: pip 2025-09-27 00:10:53.187505 | controller | Found existing installation: pip 22.2.2 2025-09-27 00:10:53.488567 | controller | Uninstalling pip-22.2.2: 2025-09-27 00:10:53.522608 | controller | Successfully uninstalled pip-22.2.2 2025-09-27 00:10:55.298599 | controller | Successfully installed pip-25.2 2025-09-27 00:10:55.551903 | controller | ok: Runtime: 0:00:03.541671 2025-09-27 00:10:55.560019 | 2025-09-27 00:10:55.560599 | TASK [Ensure pip temp directory exists in /var/tmp] 2025-09-27 00:10:56.073955 | controller | changed 2025-09-27 00:10:56.081465 | 2025-09-27 00:10:56.081589 | TASK [Install ansible into virtualenv] 2025-09-27 00:10:57.349528 | controller | Processing ./src/github.com/ansible/ansible 2025-09-27 00:10:57.351487 | controller | Preparing metadata (setup.py): started 2025-09-27 00:10:58.430038 | controller | Preparing metadata (setup.py): finished with status 'done' 2025-09-27 00:10:58.572526 | controller | Collecting jinja2 (from ansible-core==2.12.10.post0) 2025-09-27 00:10:58.676181 | controller | Downloading jinja2-3.1.6-py3-none-any.whl.metadata (2.9 kB) 2025-09-27 00:10:58.797377 | controller | Collecting PyYAML (from ansible-core==2.12.10.post0) 2025-09-27 00:10:58.810263 | controller | Downloading pyyaml-6.0.3-cp310-cp310-manylinux2014_x86_64.manylinux_2_17_x86_64.manylinux_2_28_x86_64.whl.metadata (2.4 kB) 2025-09-27 00:10:59.379183 | controller | Collecting cryptography (from ansible-core==2.12.10.post0) 2025-09-27 00:10:59.392456 | controller | Downloading cryptography-46.0.1-cp38-abi3-manylinux_2_34_x86_64.whl.metadata (5.7 kB) 2025-09-27 00:10:59.419960 | controller | Requirement already satisfied: packaging in ./venv/lib/python3.10/site-packages (from ansible-core==2.12.10.post0) (25.0) 2025-09-27 00:10:59.448339 | controller | Collecting resolvelib<0.6.0,>=0.5.3 (from ansible-core==2.12.10.post0) 2025-09-27 00:10:59.463430 | controller | Downloading resolvelib-0.5.4-py2.py3-none-any.whl.metadata (3.7 kB) 2025-09-27 00:10:59.755720 | controller | Collecting cffi>=2.0.0 (from cryptography->ansible-core==2.12.10.post0) 2025-09-27 00:10:59.769311 | controller | Downloading cffi-2.0.0-cp310-cp310-manylinux2014_x86_64.manylinux_2_17_x86_64.whl.metadata (2.6 kB) 2025-09-27 00:10:59.779395 | controller | Requirement already satisfied: typing-extensions>=4.13.2 in ./venv/lib/python3.10/site-packages (from cryptography->ansible-core==2.12.10.post0) (4.15.0) 2025-09-27 00:10:59.801322 | controller | Collecting pycparser (from cffi>=2.0.0->cryptography->ansible-core==2.12.10.post0) 2025-09-27 00:10:59.814603 | controller | Downloading pycparser-2.23-py3-none-any.whl.metadata (993 bytes) 2025-09-27 00:10:59.975136 | controller | Collecting MarkupSafe>=2.0 (from jinja2->ansible-core==2.12.10.post0) 2025-09-27 00:10:59.988137 | controller | Downloading MarkupSafe-3.0.2-cp310-cp310-manylinux_2_17_x86_64.manylinux2014_x86_64.whl.metadata (4.0 kB) 2025-09-27 00:11:00.015038 | controller | Downloading resolvelib-0.5.4-py2.py3-none-any.whl (12 kB) 2025-09-27 00:11:00.037667 | controller | Downloading cryptography-46.0.1-cp38-abi3-manylinux_2_34_x86_64.whl (4.6 MB) 2025-09-27 00:11:00.137298 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 4.6/4.6 MB 69.7 MB/s 0:00:00 2025-09-27 00:11:00.151556 | controller | Downloading cffi-2.0.0-cp310-cp310-manylinux2014_x86_64.manylinux_2_17_x86_64.whl (216 kB) 2025-09-27 00:11:00.174437 | controller | Downloading jinja2-3.1.6-py3-none-any.whl (134 kB) 2025-09-27 00:11:00.196978 | controller | Downloading MarkupSafe-3.0.2-cp310-cp310-manylinux_2_17_x86_64.manylinux2014_x86_64.whl (20 kB) 2025-09-27 00:11:00.217780 | controller | Downloading pycparser-2.23-py3-none-any.whl (118 kB) 2025-09-27 00:11:00.240338 | controller | Downloading pyyaml-6.0.3-cp310-cp310-manylinux2014_x86_64.manylinux_2_17_x86_64.manylinux_2_28_x86_64.whl (770 kB) 2025-09-27 00:11:00.257951 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 770.3/770.3 kB 39.2 MB/s 0:00:00 2025-09-27 00:11:00.314476 | controller | Building wheels for collected packages: ansible-core 2025-09-27 00:11:00.316511 | controller | DEPRECATION: Building 'ansible-core' using the legacy setup.py bdist_wheel mechanism, which will be removed in a future version. pip 25.3 will enforce this behaviour change. A possible replacement is to use the standardized build interface by setting the `--use-pep517` option, (possibly combined with `--no-build-isolation`), or adding a `pyproject.toml` file to the source tree of 'ansible-core'. Discussion can be found at https://github.com/pypa/pip/issues/6334 2025-09-27 00:11:00.317372 | controller | Building wheel for ansible-core (setup.py): started 2025-09-27 00:11:02.861038 | controller | Building wheel for ansible-core (setup.py): finished with status 'done' 2025-09-27 00:11:02.869104 | controller | Created wheel for ansible-core: filename=ansible_core-2.12.10.post0-py3-none-any.whl size=2117426 sha256=315ca110c429b1a1ace9e6873e05b5b6cf9a326eb19b81c9876271ab4fef78e3 2025-09-27 00:11:02.869558 | controller | Stored in directory: /var/tmp/ansible-test-pip/pip-ephem-wheel-cache-lmz0eu4v/wheels/16/41/d9/87e90d8b7da5963c4aded34a9a739f0b6a61cf129309c38be9 2025-09-27 00:11:02.885711 | controller | Successfully built ansible-core 2025-09-27 00:11:02.954366 | controller | Installing collected packages: resolvelib, PyYAML, pycparser, MarkupSafe, jinja2, cffi, cryptography, ansible-core 2025-09-27 00:11:05.987739 | controller | 2025-09-27 00:11:05.990743 | controller | Successfully installed MarkupSafe-3.0.2 PyYAML-6.0.3 ansible-core-2.12.10.post0 cffi-2.0.0 cryptography-46.0.1 jinja2-3.1.6 pycparser-2.23 resolvelib-0.5.4 2025-09-27 00:11:06.290354 | controller | ok: Runtime: 0:00:09.541293 2025-09-27 00:11:06.299711 | 2025-09-27 00:11:06.300085 | TASK [Setup download-artifact-fork role] 2025-09-27 00:11:06.322725 | controller | ok 2025-09-27 00:11:06.348338 | 2025-09-27 00:11:06.348462 | TASK [download-artifact-fork : Parse inventory response] 2025-09-27 00:11:06.391377 | controller | ok 2025-09-27 00:11:06.398350 | 2025-09-27 00:11:06.398445 | TASK [download-artifact-fork : Fetch info from Zuul API] 2025-09-27 00:11:06.425269 | controller | skipping: Conditional result was False 2025-09-27 00:11:06.435200 | 2025-09-27 00:11:06.435338 | TASK [download-artifact-fork : Parse build response] 2025-09-27 00:11:06.471824 | controller | skipping: Conditional result was False 2025-09-27 00:11:06.486172 | 2025-09-27 00:11:06.486344 | TASK [download-artifact-fork : Ensure artifacts directory exists] 2025-09-27 00:11:07.003411 | controller | changed 2025-09-27 00:11:07.010369 | 2025-09-27 00:11:07.010483 | LOOP [download-artifact-fork : Download archive by type] 2025-09-27 00:11:07.042249 | controller | skipping: Conditional result was False 2025-09-27 00:11:07.042563 | 2025-09-27 00:11:08.665695 | controller | changed: OK (20194 bytes) 2025-09-27 00:11:08.690680 | 2025-09-27 00:11:08.690816 | TASK [deploy-artifacts : define __collections] 2025-09-27 00:11:08.715816 | controller | ok 2025-09-27 00:11:08.722573 | 2025-09-27 00:11:08.722681 | LOOP [deploy-artifacts : Create list of collections] 2025-09-27 00:11:08.746406 | controller | skipping: Conditional result was False 2025-09-27 00:11:08.771874 | controller | ok: 2025-09-27 00:11:08.771996 | controller | { 2025-09-27 00:11:08.772027 | controller | "branch": "main", 2025-09-27 00:11:08.772263 | controller | "job": "build-ansible-collection", 2025-09-27 00:11:08.772291 | controller | "metadata": { 2025-09-27 00:11:08.772308 | controller | "type": "ansible_collection", 2025-09-27 00:11:08.772324 | controller | "version": "5.0.0" 2025-09-27 00:11:08.772341 | controller | }, 2025-09-27 00:11:08.772362 | controller | "name": "ansible.network", 2025-09-27 00:11:08.772386 | controller | "project": "ansible-collections/ansible.network", 2025-09-27 00:11:08.772411 | controller | "url": "https://35f1dd69ff4e3e8ce914-84974173997ce646ebc73a22f24b90ec.ssl.cf1.rackcdn.com/ansible/4c74acd5de0c47c1acec7c71aa0b74bd/artifacts/ansible-network-5.0.0.tar.gz" 2025-09-27 00:11:08.772444 | controller | } 2025-09-27 00:11:08.791793 | 2025-09-27 00:11:08.791966 | TASK [deploy-artifacts : Install require-project collection using ansible-galaxy] 2025-09-27 00:11:10.062715 | controller | Starting galaxy collection install process 2025-09-27 00:11:10.063181 | controller | Process install dependency map 2025-09-27 00:11:28.530389 | controller | Starting collection install process 2025-09-27 00:11:28.530551 | controller | Installing 'ansible.network:5.0.0' to '/home/zuul-worker/.ansible/collections/ansible_collections/ansible/network' 2025-09-27 00:11:28.543444 | controller | ansible.network:5.0.0 was installed successfully 2025-09-27 00:11:28.543511 | controller | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/ansible-utils-6.0.0.tar.gz to /home/zuul-worker/.ansible/tmp/ansible-local-34283ct38qv3/tmpq1nqixx8/ansible-utils-6.0.0-mfohr_5s 2025-09-27 00:11:29.102643 | controller | Installing 'ansible.utils:6.0.0' to '/home/zuul-worker/.ansible/collections/ansible_collections/ansible/utils' 2025-09-27 00:11:29.332680 | controller | ansible.utils:6.0.0 was installed successfully 2025-09-27 00:11:29.332805 | controller | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/arista-eos-12.0.0.tar.gz to /home/zuul-worker/.ansible/tmp/ansible-local-34283ct38qv3/tmpq1nqixx8/arista-eos-12.0.0-5hqwsy9z 2025-09-27 00:11:30.595933 | controller | Installing 'arista.eos:12.0.0' to '/home/zuul-worker/.ansible/collections/ansible_collections/arista/eos' 2025-09-27 00:11:31.191109 | controller | arista.eos:12.0.0 was installed successfully 2025-09-27 00:11:31.191207 | controller | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/ansible-netcommon-8.1.0.tar.gz to /home/zuul-worker/.ansible/tmp/ansible-local-34283ct38qv3/tmpq1nqixx8/ansible-netcommon-8.1.0-uf6z6ah8 2025-09-27 00:11:31.920697 | controller | Installing 'ansible.netcommon:8.1.0' to '/home/zuul-worker/.ansible/collections/ansible_collections/ansible/netcommon' 2025-09-27 00:11:32.067305 | controller | ansible.netcommon:8.1.0 was installed successfully 2025-09-27 00:11:32.067431 | controller | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/junipernetworks-junos-11.0.0.tar.gz to /home/zuul-worker/.ansible/tmp/ansible-local-34283ct38qv3/tmpq1nqixx8/junipernetworks-junos-11.0.0-kw69q0z9 2025-09-27 00:11:34.468747 | controller | Installing 'junipernetworks.junos:11.0.0' to '/home/zuul-worker/.ansible/collections/ansible_collections/junipernetworks/junos' 2025-09-27 00:11:35.073463 | controller | junipernetworks.junos:11.0.0 was installed successfully 2025-09-27 00:11:35.073552 | controller | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/cisco-iosxr-12.0.0.tar.gz to /home/zuul-worker/.ansible/tmp/ansible-local-34283ct38qv3/tmpq1nqixx8/cisco-iosxr-12.0.0-6ghf1htb 2025-09-27 00:11:35.717154 | controller | Installing 'cisco.iosxr:12.0.0' to '/home/zuul-worker/.ansible/collections/ansible_collections/cisco/iosxr' 2025-09-27 00:11:36.365180 | controller | cisco.iosxr:12.0.0 was installed successfully 2025-09-27 00:11:36.365288 | controller | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/cisco-nxos-11.0.0.tar.gz to /home/zuul-worker/.ansible/tmp/ansible-local-34283ct38qv3/tmpq1nqixx8/cisco-nxos-11.0.0-coggz1o2 2025-09-27 00:11:37.047870 | controller | Installing 'cisco.nxos:11.0.0' to '/home/zuul-worker/.ansible/collections/ansible_collections/cisco/nxos' 2025-09-27 00:11:38.231924 | controller | cisco.nxos:11.0.0 was installed successfully 2025-09-27 00:11:38.232019 | controller | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/cisco-ios-11.0.0.tar.gz to /home/zuul-worker/.ansible/tmp/ansible-local-34283ct38qv3/tmpq1nqixx8/cisco-ios-11.0.0-f6wnhr9n 2025-09-27 00:11:38.635343 | controller | ERROR! Failed to download collection tar from 'default': 2025-09-27 00:11:38.950280 | controller | ERROR 2025-09-27 00:11:38.950460 | controller | { 2025-09-27 00:11:38.950491 | controller | "delta": "0:00:29.442530", 2025-09-27 00:11:38.950512 | controller | "end": "2025-09-27 00:11:38.718056", 2025-09-27 00:11:38.950532 | controller | "msg": "non-zero return code", 2025-09-27 00:11:38.950550 | controller | "rc": 1, 2025-09-27 00:11:38.950571 | controller | "start": "2025-09-27 00:11:09.275526" 2025-09-27 00:11:38.950594 | controller | } failure 2025-09-27 00:11:38.963519 | 2025-09-27 00:11:38.963620 | PLAY RECAP 2025-09-27 00:11:38.963693 | controller | ok: 24 changed: 13 unreachable: 0 failed: 1 skipped: 6 rescued: 0 ignored: 0 2025-09-27 00:11:38.963721 | 2025-09-27 00:11:39.150685 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master] 2025-09-27 00:11:39.151710 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2025-09-27 00:11:39.852376 | 2025-09-27 00:11:39.852518 | PLAY [all] 2025-09-27 00:11:39.876474 | 2025-09-27 00:11:39.876607 | TASK [fetch-subunit-output : Find stestr or testr executable] 2025-09-27 00:11:40.687479 | controller | changed: non-zero return code 2025-09-27 00:11:40.695109 | 2025-09-27 00:11:40.695242 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2025-09-27 00:11:40.743628 | controller | skipping: Conditional result was False 2025-09-27 00:11:40.750987 | 2025-09-27 00:11:40.751118 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2025-09-27 00:11:40.785832 | 2025-09-27 00:11:40.786017 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2025-09-27 00:11:40.820021 | 2025-09-27 00:11:40.820660 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2025-09-27 00:11:40.848262 | controller | skipping: Conditional result was False 2025-09-27 00:11:40.854833 | 2025-09-27 00:11:40.855137 | LOOP [fetch-subunit-output : Generate subunit file] 2025-09-27 00:11:40.886895 | 2025-09-27 00:11:40.887056 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2025-09-27 00:11:40.911039 | controller | skipping: Conditional result was False 2025-09-27 00:11:40.918902 | 2025-09-27 00:11:40.919009 | TASK [fetch-subunit-output : Remove the temporary file] 2025-09-27 00:11:40.953336 | controller | skipping: Conditional result was False 2025-09-27 00:11:40.961043 | 2025-09-27 00:11:40.961159 | TASK [fetch-subunit-output : Process and fetch subunit results] 2025-09-27 00:11:40.986191 | controller | skipping: Conditional result was False 2025-09-27 00:11:41.049809 | 2025-09-27 00:11:41.049900 | PLAY RECAP 2025-09-27 00:11:41.049946 | controller | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2025-09-27 00:11:41.049967 | 2025-09-27 00:11:41.159527 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2025-09-27 00:11:41.160428 | POST-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/post.yaml@master] 2025-09-27 00:11:41.903682 | 2025-09-27 00:11:41.903817 | PLAY [all:!appliance*] 2025-09-27 00:11:41.927539 | 2025-09-27 00:11:41.927721 | TASK [unregister the node] 2025-09-27 00:11:42.065271 | controller | skipping: Conditional result was False 2025-09-27 00:11:42.071417 | 2025-09-27 00:11:42.071548 | TASK [include_role : fetch-output] 2025-09-27 00:11:42.101621 | controller | ok 2025-09-27 00:11:42.133191 | 2025-09-27 00:11:42.133321 | TASK [fetch-output : Set log path for multiple nodes] 2025-09-27 00:11:42.189368 | controller | skipping: Conditional result was False 2025-09-27 00:11:42.197353 | 2025-09-27 00:11:42.197486 | TASK [fetch-output : Set log path for single node] 2025-09-27 00:11:42.240814 | controller | ok 2025-09-27 00:11:42.247188 | 2025-09-27 00:11:42.247294 | LOOP [fetch-output : Ensure local output dirs] 2025-09-27 00:11:42.688211 | controller -> localhost | ok: "/var/lib/zuul/builds/795598aaabfb407bad79a247db7f4564/work/logs" 2025-09-27 00:11:42.919802 | controller -> localhost | changed: "/var/lib/zuul/builds/795598aaabfb407bad79a247db7f4564/work/artifacts" 2025-09-27 00:11:43.165474 | controller -> localhost | changed: "/var/lib/zuul/builds/795598aaabfb407bad79a247db7f4564/work/docs" 2025-09-27 00:11:43.173750 | 2025-09-27 00:11:43.173848 | LOOP [fetch-output : Collect logs, artifacts and docs] 2025-09-27 00:11:44.408264 | controller | changed: 2025-09-27 00:11:44.408480 | controller | .d..t...... ./ 2025-09-27 00:11:44.408509 | controller | cd+++++++++ controller/ 2025-09-27 00:11:44.408540 | controller | changed: All items complete 2025-09-27 00:11:44.408559 | 2025-09-27 00:11:45.465727 | controller | changed: .d..t...... ./ 2025-09-27 00:11:46.465825 | controller | changed: .d..t...... ./ 2025-09-27 00:11:46.487560 | 2025-09-27 00:11:46.487825 | TASK [include_role : fetch-output-openshift] 2025-09-27 00:11:46.513175 | controller | skipping: Conditional result was False 2025-09-27 00:11:46.522171 | 2025-09-27 00:11:46.522298 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2025-09-27 00:11:46.572261 | controller | skipping: Conditional result was False 2025-09-27 00:11:46.581062 | controller | skipping: Conditional result was False 2025-09-27 00:11:46.642424 | 2025-09-27 00:11:46.642567 | PLAY [localhost] 2025-09-27 00:11:46.655570 | 2025-09-27 00:11:46.655715 | TASK [Run Zuul manifest role] 2025-09-27 00:11:46.673748 | localhost | ok 2025-09-27 00:11:46.688031 | 2025-09-27 00:11:46.688137 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-09-27 00:11:47.078036 | localhost | changed 2025-09-27 00:11:47.089248 | 2025-09-27 00:11:47.089396 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-09-27 00:11:47.122845 | localhost | ok 2025-09-27 00:11:47.137832 | 2025-09-27 00:11:47.137968 | TASK [Set zuul-log-path fact] 2025-09-27 00:11:47.161636 | localhost | ok 2025-09-27 00:11:47.184098 | 2025-09-27 00:11:47.184356 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-09-27 00:11:47.217270 | localhost | ok 2025-09-27 00:11:47.231746 | 2025-09-27 00:11:47.231864 | LOOP [Run upload-logs-swift role] 2025-09-27 00:11:47.257392 | localhost | Output suppressed because no_log was given 2025-09-27 00:11:47.284813 | 2025-09-27 00:11:47.284927 | TASK [Set zuul-log-path fact] 2025-09-27 00:11:47.308992 | localhost | skipping: Conditional result was False 2025-09-27 00:11:47.314361 | 2025-09-27 00:11:47.314435 | TASK [upload-logs-swift : Ensure logs are readable before uploading] 2025-09-27 00:11:47.787045 | localhost -> localhost | ok: Runtime: 0:00:00.008384 2025-09-27 00:11:47.800376 | 2025-09-27 00:11:47.800527 | TASK [upload-logs-swift : Upload logs to swift]