2025-11-09 00:07:10.629844 | Job console starting...
2025-11-09 00:07:10.641097 | Updating repositories
2025-11-09 00:07:10.808681 | Preparing job workspace
2025-11-09 00:07:16.805693 | Running Ansible setup...
2025-11-09 00:07:24.811953 | PRE-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master]
2025-11-09 00:07:25.349455 | 
2025-11-09 00:07:25.349562 | PLAY [localhost]
2025-11-09 00:07:25.366418 | 
2025-11-09 00:07:25.366503 | TASK [Gathering Facts]
2025-11-09 00:07:26.359408 | localhost | ok
2025-11-09 00:07:26.389385 | 
2025-11-09 00:07:26.389627 | TASK [Setup log path fact]
2025-11-09 00:07:26.409825 | localhost | ok
2025-11-09 00:07:26.426940 | 
2025-11-09 00:07:26.427073 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-11-09 00:07:26.465474 | localhost | ok
2025-11-09 00:07:26.473191 | 
2025-11-09 00:07:26.473260 | TASK [emit-job-header : Print job information]
2025-11-09 00:07:26.511766 | # Job Information
2025-11-09 00:07:26.511929 | Ansible Version: 2.15.12
2025-11-09 00:07:26.511961 | Job: ansible-test-sanity-docker-stable-2.9
2025-11-09 00:07:26.511984 | Pipeline: periodic
2025-11-09 00:07:26.512004 | Executor: ze03.softwarefactory-project.io
2025-11-09 00:07:26.512024 | Triggered by: https://github.com/ansible-collections/ansible.network
2025-11-09 00:07:26.512047 | Log URL (when completed): https://ansible.softwarefactory-project.io/logs/3b2/ansible/3b265a43d66b495a9e2ed1155bec8177/
2025-11-09 00:07:26.512068 | Event ID: 280be395c61a4a10afa9abd9d444e89a
2025-11-09 00:07:26.515836 | 
2025-11-09 00:07:26.515900 | LOOP [emit-job-header : Print node information]
2025-11-09 00:07:26.622847 | localhost | ok:
2025-11-09 00:07:26.623056 | localhost | # Node Information
2025-11-09 00:07:26.623083 | localhost | Inventory Hostname: controller
2025-11-09 00:07:26.623103 | localhost | Hostname: np0005515818
2025-11-09 00:07:26.623121 | localhost | Username: zuul
2025-11-09 00:07:26.623144 | localhost | Distro: Fedora 37
2025-11-09 00:07:26.623163 | localhost | Provider: ansible-vexxhost-ams1
2025-11-09 00:07:26.623181 | localhost | Region: ams1
2025-11-09 00:07:26.623198 | localhost | Label: ansible-fedora-37-1vcpu
2025-11-09 00:07:26.623214 | localhost | Product Name: OpenStack Nova
2025-11-09 00:07:26.623231 | localhost | Interface IP: 38.129.16.82
2025-11-09 00:07:26.641307 | 
2025-11-09 00:07:26.641477 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2025-11-09 00:07:27.070571 | localhost -> localhost | changed
2025-11-09 00:07:27.076335 | 
2025-11-09 00:07:27.076402 | TASK [log-inventory : Copy ansible inventory to logs dir]
2025-11-09 00:07:27.977899 | localhost -> localhost | changed
2025-11-09 00:07:27.999925 | 
2025-11-09 00:07:28.000019 | PLAY [all:!appliance*]
2025-11-09 00:07:28.017386 | 
2025-11-09 00:07:28.017566 | TASK [include_role : start-zuul-console]
2025-11-09 00:07:28.046502 | controller | ok
2025-11-09 00:07:28.060136 | 
2025-11-09 00:07:28.060199 | TASK [start-zuul-console : Start zuul_console daemon.]
2025-11-09 00:07:29.150021 | controller | ok
2025-11-09 00:07:29.168342 | 
2025-11-09 00:07:29.168481 | TASK [use-our-mirror : Retrieve the IP address]
2025-11-09 00:07:32.556424 | controller | ok: Status code was 404 and not [200]: HTTP Error 404: Not Found
2025-11-09 00:07:32.562652 | 
2025-11-09 00:07:32.562712 | TASK [use-our-mirror : Ensure the registry service is running on the mirror]
2025-11-09 00:07:32.922173 | controller | skipping: Conditional result was False
2025-11-09 00:07:32.928865 | 
2025-11-09 00:07:32.928933 | TASK [use-our-mirror : Write the IP in /etc/hosts]
2025-11-09 00:07:32.953235 | controller | skipping: Conditional result was False
2025-11-09 00:07:32.960005 | 
2025-11-09 00:07:32.960069 | TASK [use-our-mirror : Create the podman configuration directory]
2025-11-09 00:07:32.984100 | controller | skipping: Conditional result was False
2025-11-09 00:07:32.991037 | 
2025-11-09 00:07:32.991105 | TASK [use-our-mirror : Copy the podman configuration]
2025-11-09 00:07:33.015157 | controller | skipping: Conditional result was False
2025-11-09 00:07:33.021116 | 
2025-11-09 00:07:33.021179 | TASK [use-our-mirror : Copy the Yum configuration (main)]
2025-11-09 00:07:33.045245 | controller | skipping: Conditional result was False
2025-11-09 00:07:33.051284 | 
2025-11-09 00:07:33.051347 | TASK [use-our-mirror : Copy the Yum configuration (update)]
2025-11-09 00:07:33.075412 | controller | skipping: Conditional result was False
2025-11-09 00:07:33.088515 | 
2025-11-09 00:07:33.088585 | TASK [Disable Fedora Modular]
2025-11-09 00:07:34.330584 | controller | changed
2025-11-09 00:07:34.345637 | 
2025-11-09 00:07:34.345825 | TASK [Enable EPEL]
2025-11-09 00:07:34.383817 | controller | skipping: Conditional result was False
2025-11-09 00:07:34.398337 | 
2025-11-09 00:07:34.398491 | TASK [Register the RHEL node]
2025-11-09 00:07:34.811674 | 
2025-11-09 00:07:34.811840 | TASK [Show the subscription-manager status]
2025-11-09 00:07:35.212546 | controller | skipping: Conditional result was False
2025-11-09 00:07:35.226080 | 
2025-11-09 00:07:35.226211 | TASK [Enable EPEL on RHEL]
2025-11-09 00:07:35.603437 | controller | skipping: Conditional result was False
2025-11-09 00:07:35.622559 | 
2025-11-09 00:07:35.622796 | TASK [Install git and tox]
2025-11-09 00:09:44.112313 | controller | changed
2025-11-09 00:09:44.127202 | 
2025-11-09 00:09:44.127488 | TASK [include_role : prepare-workspace]
2025-11-09 00:09:44.172287 | controller | ok
2025-11-09 00:09:44.219711 | 
2025-11-09 00:09:44.219903 | TASK [prepare-workspace : Start zuul_console daemon.]
2025-11-09 00:09:45.080910 | controller | ok
2025-11-09 00:09:45.090989 | 
2025-11-09 00:09:45.091156 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2025-11-09 00:10:05.872374 | controller | Output suppressed because no_log was given
2025-11-09 00:10:05.886342 | 
2025-11-09 00:10:05.886438 | TASK [include_role : prepare-workspace-openshift]
2025-11-09 00:10:05.922187 | controller | skipping: Conditional result was False
2025-11-09 00:10:05.964223 | 
2025-11-09 00:10:05.964355 | PLAY [all:!appliance]
2025-11-09 00:10:05.992130 | 
2025-11-09 00:10:05.992262 | TASK [Run add-build-sshkey role (RSA)]
2025-11-09 00:10:06.022181 | controller | ok
2025-11-09 00:10:06.038899 | 
2025-11-09 00:10:06.039012 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2025-11-09 00:10:06.246124 | controller -> localhost | ok
2025-11-09 00:10:06.252968 | 
2025-11-09 00:10:06.253035 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2025-11-09 00:10:06.273821 | controller | ok
2025-11-09 00:10:06.291712 | controller | included: /var/lib/zuul/builds/3b265a43d66b495a9e2ed1155bec8177/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2025-11-09 00:10:06.297666 | 
2025-11-09 00:10:06.297747 | TASK [add-build-sshkey : Create Temp SSH key]
2025-11-09 00:10:06.820981 | controller -> localhost | Generating public/private rsa key pair.
2025-11-09 00:10:06.821168 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/3b265a43d66b495a9e2ed1155bec8177/work/3b265a43d66b495a9e2ed1155bec8177_id_rsa.
2025-11-09 00:10:06.821197 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/3b265a43d66b495a9e2ed1155bec8177/work/3b265a43d66b495a9e2ed1155bec8177_id_rsa.pub.
2025-11-09 00:10:06.821218 | controller -> localhost | The key fingerprint is:
2025-11-09 00:10:06.821237 | controller -> localhost | SHA256:u3NK77frIBx6Lxge+YeubfXyu3E67vpwb3qirUx1jqU zuul-build-sshkey
2025-11-09 00:10:06.821256 | controller -> localhost | The key's randomart image is:
2025-11-09 00:10:06.821275 | controller -> localhost | +---[RSA 2048]----+
2025-11-09 00:10:06.821293 | controller -> localhost | |                 |
2025-11-09 00:10:06.821312 | controller -> localhost | |                 |
2025-11-09 00:10:06.821330 | controller -> localhost | |                 |
2025-11-09 00:10:06.821348 | controller -> localhost | |                 |
2025-11-09 00:10:06.821365 | controller -> localhost | |       .S   . o  |
2025-11-09 00:10:06.821391 | controller -> localhost | |      +o o.. *   |
2025-11-09 00:10:06.821413 | controller -> localhost | |     ..=*o+.E o  |
2025-11-09 00:10:06.821432 | controller -> localhost | |      o+*B+===o  |
2025-11-09 00:10:06.821450 | controller -> localhost | |      .+=BO%#@.  |
2025-11-09 00:10:06.821471 | controller -> localhost | +----[SHA256]-----+
2025-11-09 00:10:06.821514 | controller -> localhost | ok: Runtime: 0:00:00.105656
2025-11-09 00:10:06.830858 | 
2025-11-09 00:10:06.830927 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2025-11-09 00:10:06.859764 | controller | ok
2025-11-09 00:10:06.869564 | controller | included: /var/lib/zuul/builds/3b265a43d66b495a9e2ed1155bec8177/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2025-11-09 00:10:06.878828 | 
2025-11-09 00:10:06.878895 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2025-11-09 00:10:06.902639 | controller | skipping: Conditional result was False
2025-11-09 00:10:06.909281 | 
2025-11-09 00:10:06.909346 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2025-11-09 00:10:08.012214 | controller | changed
2025-11-09 00:10:08.027669 | 
2025-11-09 00:10:08.027845 | TASK [add-build-sshkey : Make sure user has a .ssh]
2025-11-09 00:10:08.670756 | controller | ok
2025-11-09 00:10:08.678195 | 
2025-11-09 00:10:08.678321 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2025-11-09 00:10:11.664863 | controller | changed
2025-11-09 00:10:11.678611 | 
2025-11-09 00:10:11.678803 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2025-11-09 00:10:14.692860 | controller | changed
2025-11-09 00:10:14.742974 | 
2025-11-09 00:10:14.743119 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2025-11-09 00:10:14.769551 | controller | skipping: Conditional result was False
2025-11-09 00:10:14.779823 | 
2025-11-09 00:10:14.779960 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2025-11-09 00:10:15.137694 | controller -> localhost | changed
2025-11-09 00:10:15.153155 | 
2025-11-09 00:10:15.153312 | TASK [add-build-sshkey : Add back temp key]
2025-11-09 00:10:15.413659 | controller -> localhost | Identity added: /var/lib/zuul/builds/3b265a43d66b495a9e2ed1155bec8177/work/3b265a43d66b495a9e2ed1155bec8177_id_rsa (zuul-build-sshkey)
2025-11-09 00:10:15.414170 | controller -> localhost | ok: Runtime: 0:00:00.013411
2025-11-09 00:10:15.433875 | 
2025-11-09 00:10:15.434052 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2025-11-09 00:10:16.323615 | controller | ok
2025-11-09 00:10:16.329681 | 
2025-11-09 00:10:16.329789 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2025-11-09 00:10:16.364750 | controller | skipping: Conditional result was False
2025-11-09 00:10:16.375989 | 
2025-11-09 00:10:16.376062 | TASK [Run add-build-sshkey role (ECDSA)]
2025-11-09 00:10:16.407837 | controller | ok
2025-11-09 00:10:16.425179 | 
2025-11-09 00:10:16.425278 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2025-11-09 00:10:16.715092 | controller -> localhost | ok
2025-11-09 00:10:16.727938 | 
2025-11-09 00:10:16.728143 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2025-11-09 00:10:16.763939 | controller | ok
2025-11-09 00:10:16.779437 | controller | included: /var/lib/zuul/builds/3b265a43d66b495a9e2ed1155bec8177/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2025-11-09 00:10:16.788530 | 
2025-11-09 00:10:16.788662 | TASK [add-build-sshkey : Create Temp SSH key]
2025-11-09 00:10:17.093480 | controller -> localhost | Generating public/private ecdsa key pair.
2025-11-09 00:10:17.093785 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/3b265a43d66b495a9e2ed1155bec8177/work/3b265a43d66b495a9e2ed1155bec8177_id_ecdsa.
2025-11-09 00:10:17.093854 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/3b265a43d66b495a9e2ed1155bec8177/work/3b265a43d66b495a9e2ed1155bec8177_id_ecdsa.pub.
2025-11-09 00:10:17.093915 | controller -> localhost | The key fingerprint is:
2025-11-09 00:10:17.093959 | controller -> localhost | SHA256:mbWgrnZnxYBdAA4wQXHW+xYCQOKlueQAJjPekkiOeY4 zuul-build-sshkey
2025-11-09 00:10:17.094002 | controller -> localhost | The key's randomart image is:
2025-11-09 00:10:17.094042 | controller -> localhost | +---[ECDSA 521]---+
2025-11-09 00:10:17.094082 | controller -> localhost | |*=O*+.....       |
2025-11-09 00:10:17.094121 | controller -> localhost | |@=*+.o.   .      |
2025-11-09 00:10:17.094160 | controller -> localhost | |=X.. ..+...      |
2025-11-09 00:10:17.094198 | controller -> localhost | |++o   +.+= .     |
2025-11-09 00:10:17.094236 | controller -> localhost | |Eo.   .oS+.      |
2025-11-09 00:10:17.094275 | controller -> localhost | |     .  o o      |
2025-11-09 00:10:17.094313 | controller -> localhost | |      .. .       |
2025-11-09 00:10:17.094351 | controller -> localhost | |    ... o        |
2025-11-09 00:10:17.094389 | controller -> localhost | |   ... o         |
2025-11-09 00:10:17.094428 | controller -> localhost | +----[SHA256]-----+
2025-11-09 00:10:17.094511 | controller -> localhost | ok: Runtime: 0:00:00.010404
2025-11-09 00:10:17.108529 | 
2025-11-09 00:10:17.108666 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2025-11-09 00:10:17.133157 | controller | ok
2025-11-09 00:10:17.144354 | controller | included: /var/lib/zuul/builds/3b265a43d66b495a9e2ed1155bec8177/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2025-11-09 00:10:17.157704 | 
2025-11-09 00:10:17.157846 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2025-11-09 00:10:17.182875 | controller | skipping: Conditional result was False
2025-11-09 00:10:17.192447 | 
2025-11-09 00:10:17.192713 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2025-11-09 00:10:18.140968 | controller | changed
2025-11-09 00:10:18.159053 | 
2025-11-09 00:10:18.159211 | TASK [add-build-sshkey : Make sure user has a .ssh]
2025-11-09 00:10:18.800995 | controller | ok
2025-11-09 00:10:18.809238 | 
2025-11-09 00:10:18.809332 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2025-11-09 00:10:21.817550 | controller | changed
2025-11-09 00:10:21.826660 | 
2025-11-09 00:10:21.826812 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2025-11-09 00:10:24.831853 | controller | changed
2025-11-09 00:10:24.847882 | 
2025-11-09 00:10:24.848020 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2025-11-09 00:10:24.873619 | controller | skipping: Conditional result was False
2025-11-09 00:10:24.885040 | 
2025-11-09 00:10:24.885173 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2025-11-09 00:10:25.118384 | controller -> localhost | changed
2025-11-09 00:10:25.134797 | 
2025-11-09 00:10:25.134915 | TASK [add-build-sshkey : Add back temp key]
2025-11-09 00:10:25.393974 | controller -> localhost | Identity added: /var/lib/zuul/builds/3b265a43d66b495a9e2ed1155bec8177/work/3b265a43d66b495a9e2ed1155bec8177_id_ecdsa (zuul-build-sshkey)
2025-11-09 00:10:25.394224 | controller -> localhost | ok: Runtime: 0:00:00.008953
2025-11-09 00:10:25.409887 | 
2025-11-09 00:10:25.409978 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2025-11-09 00:10:26.102113 | controller | ok
2025-11-09 00:10:26.111669 | 
2025-11-09 00:10:26.111815 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2025-11-09 00:10:26.148354 | controller | skipping: Conditional result was False
2025-11-09 00:10:26.166380 | 
2025-11-09 00:10:26.166490 | TASK [include_role : remove-zuul-sshkey]
2025-11-09 00:10:26.192001 | controller | skipping: Conditional result was False
2025-11-09 00:10:26.201569 | 
2025-11-09 00:10:26.201661 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2025-11-09 00:10:26.898539 | controller | ok: "logs"
2025-11-09 00:10:26.899011 | controller | ok: All items complete
2025-11-09 00:10:26.899084 | 
2025-11-09 00:10:27.517866 | controller | ok: "artifacts"
2025-11-09 00:10:28.127542 | controller | ok: "docs"
2025-11-09 00:10:28.145025 | 
2025-11-09 00:10:28.145144 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2025-11-09 00:10:28.856105 | controller | changed: "logs"
2025-11-09 00:10:29.484468 | controller | changed: "artifacts"
2025-11-09 00:10:30.106563 | controller | changed: "docs"
2025-11-09 00:10:30.185016 | 
2025-11-09 00:10:30.185110 | PLAY RECAP
2025-11-09 00:10:30.185150 | controller | ok: 30 changed: 16 unreachable: 0 failed: 0 skipped: 18 rescued: 0 ignored: 0
2025-11-09 00:10:30.185175 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-11-09 00:10:30.185193 | 
2025-11-09 00:10:30.294551 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master]
2025-11-09 00:10:30.295674 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master]
2025-11-09 00:10:30.935579 | 
2025-11-09 00:10:30.935696 | PLAY [all]
2025-11-09 00:10:30.958874 | 
2025-11-09 00:10:30.958996 | TASK [Install binary dependencies]
2025-11-09 00:10:31.010154 | controller | ok
2025-11-09 00:10:31.037501 | 
2025-11-09 00:10:31.037641 | TASK [bindep : Include find tasks]
2025-11-09 00:10:31.068654 | controller | ok
2025-11-09 00:10:31.077062 | controller | included: /var/lib/zuul/builds/3b265a43d66b495a9e2ed1155bec8177/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find.yaml
2025-11-09 00:10:31.083581 | 
2025-11-09 00:10:31.083659 | TASK [bindep : Look for bindep.txt]
2025-11-09 00:10:32.017928 | controller | ok
2025-11-09 00:10:32.028894 | 
2025-11-09 00:10:32.029043 | TASK [bindep : Define bindep_file fact]
2025-11-09 00:10:32.054636 | controller | skipping: Conditional result was False
2025-11-09 00:10:32.064865 | 
2025-11-09 00:10:32.065000 | TASK [bindep : Look for other-requirements.txt]
2025-11-09 00:10:32.716711 | controller | ok
2025-11-09 00:10:32.726995 | 
2025-11-09 00:10:32.727249 | TASK [bindep : Define bindep_file fact]
2025-11-09 00:10:32.753704 | controller | skipping: Conditional result was False
2025-11-09 00:10:32.764707 | 
2025-11-09 00:10:32.764864 | TASK [bindep : Look for bindep fallback file]
2025-11-09 00:10:32.793458 | controller | skipping: Conditional result was False
2025-11-09 00:10:32.807743 | 
2025-11-09 00:10:32.807945 | TASK [bindep : Define bindep_file fact]
2025-11-09 00:10:32.832483 | controller | skipping: Conditional result was False
2025-11-09 00:10:32.839243 | 
2025-11-09 00:10:32.839312 | TASK [bindep : Include bindep tasks]
2025-11-09 00:10:32.862869 | controller | skipping: Conditional result was False
2025-11-09 00:10:32.869593 | 
2025-11-09 00:10:32.869663 | TASK [bindep : Include install tasks]
2025-11-09 00:10:32.893066 | controller | skipping: Conditional result was False
2025-11-09 00:10:32.899903 | 
2025-11-09 00:10:32.899974 | LOOP [bindep : Include package tasks]
2025-11-09 00:10:32.956483 | 
2025-11-09 00:10:32.956651 | TASK [Run test-setup role]
2025-11-09 00:10:32.976911 | controller | ok
2025-11-09 00:10:32.995275 | 
2025-11-09 00:10:32.995369 | TASK [test-setup : Check if project's tools/test-setup.sh exists]
2025-11-09 00:10:33.610216 | controller | ok
2025-11-09 00:10:33.619248 | 
2025-11-09 00:10:33.619383 | TASK [test-setup : Run tools/test-setup.sh]
2025-11-09 00:10:33.982573 | controller | skipping: Conditional result was False
2025-11-09 00:10:34.042077 | 
2025-11-09 00:10:34.042200 | PLAY RECAP
2025-11-09 00:10:34.042258 | controller | ok: 4 changed: 0 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0
2025-11-09 00:10:34.042286 | 
2025-11-09 00:10:34.163388 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master]
2025-11-09 00:10:34.164544 | PRE-RUN START: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master]
2025-11-09 00:10:34.864993 | 
2025-11-09 00:10:34.865147 | PLAY [controller]
2025-11-09 00:10:34.887241 | 
2025-11-09 00:10:34.887375 | TASK [Create the /root directory]
2025-11-09 00:10:35.932566 | controller | ok
2025-11-09 00:10:35.941478 | 
2025-11-09 00:10:35.941600 | TASK [Install glibc-langpack-en]
2025-11-09 00:10:44.184489 | controller | ok: Nothing to do
2025-11-09 00:10:44.199079 | 
2025-11-09 00:10:44.199250 | TASK [Ensure controller directory exists]
2025-11-09 00:10:45.013716 | controller | changed
2025-11-09 00:10:45.026059 | 
2025-11-09 00:10:45.026185 | TASK [Install container runtime]
2025-11-09 00:10:45.086252 | controller | ok
2025-11-09 00:10:45.156588 | 
2025-11-09 00:10:45.156704 | LOOP [ensure-docker : Gather variables for each operating system]
2025-11-09 00:10:45.214655 | controller | ok: "/var/lib/zuul/builds/3b265a43d66b495a9e2ed1155bec8177/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/vars/fedora.yaml"
2025-11-09 00:10:45.232441 | 
2025-11-09 00:10:45.232741 | TASK [ensure-docker : Sanity check for distro selection]
2025-11-09 00:10:45.258718 | controller | skipping: Conditional result was False
2025-11-09 00:10:45.269932 | 
2025-11-09 00:10:45.270090 | TASK [ensure-docker : Sanity check for distro install]
2025-11-09 00:10:45.296180 | controller | skipping: Conditional result was False
2025-11-09 00:10:45.305153 | 
2025-11-09 00:10:45.305259 | TASK [ensure-docker : Sanity check for upstream install]
2025-11-09 00:10:45.330233 | controller | skipping: Conditional result was False
2025-11-09 00:10:45.339402 | 
2025-11-09 00:10:45.339509 | TASK [ensure-docker : Sanity check for upstream install mirrors]
2025-11-09 00:10:45.364790 | controller | skipping: Conditional result was False
2025-11-09 00:10:45.374054 | 
2025-11-09 00:10:45.374156 | TASK [ensure-docker : Include tasks for docker install]
2025-11-09 00:10:45.407621 | controller | ok
2025-11-09 00:10:45.422160 | controller | included: /var/lib/zuul/builds/3b265a43d66b495a9e2ed1155bec8177/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/tasks/docker-upstream.yaml
2025-11-09 00:10:45.436560 | 
2025-11-09 00:10:45.436688 | TASK [ensure-docker : Not Implemented]
2025-11-09 00:10:45.461615 | controller | skipping: Conditional result was False
2025-11-09 00:10:45.471473 | 
2025-11-09 00:10:45.471620 | TASK [Set up docker mirrors]
2025-11-09 00:10:45.496322 | controller | ok
2025-11-09 00:10:45.527045 | 
2025-11-09 00:10:45.527183 | TASK [use-docker-mirror : Set docker_mirror fact from mirror_info]
2025-11-09 00:10:45.571979 | controller | skipping: Conditional result was False
2025-11-09 00:10:45.581556 | 
2025-11-09 00:10:45.581694 | TASK [use-docker-mirror : Set docker_mirror fact from zuul_site_mirror_info]
2025-11-09 00:10:45.617133 | controller | skipping: Conditional result was False
2025-11-09 00:10:45.664207 | 
2025-11-09 00:10:45.664370 | TASK [use-docker-mirror : Set docker_mirror fact]
2025-11-09 00:10:45.699437 | controller | skipping: Conditional result was False
2025-11-09 00:10:45.709157 | 
2025-11-09 00:10:45.709288 | TASK [use-docker-mirror : Set up docker mirrors]
2025-11-09 00:10:45.734313 | controller | skipping: Conditional result was False
2025-11-09 00:10:45.755288 | 
2025-11-09 00:10:45.755431 | TASK [Add all repositories]
2025-11-09 00:10:45.777776 | controller | ok
2025-11-09 00:10:45.812525 | 
2025-11-09 00:10:45.812665 | LOOP [ensure-package-repositories : Install repositories]
2025-11-09 00:10:45.837339 | controller | ok: "/var/lib/zuul/builds/3b265a43d66b495a9e2ed1155bec8177/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-package-repositories/tasks/RedHat.yaml"
2025-11-09 00:10:45.837614 | controller | ok: All items complete
2025-11-09 00:10:45.837655 | 
2025-11-09 00:10:45.849980 | controller | included: /var/lib/zuul/builds/3b265a43d66b495a9e2ed1155bec8177/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-package-repositories/tasks/RedHat.yaml
2025-11-09 00:10:45.858857 | 
2025-11-09 00:10:45.858962 | TASK [ensure-package-repositories : Create GPG key temp directory]
2025-11-09 00:10:46.779219 | controller | changed
2025-11-09 00:10:46.798479 | 
2025-11-09 00:10:46.798654 | LOOP [ensure-package-repositories : Upload GPG keys]
2025-11-09 00:10:49.995225 | controller | changed:
2025-11-09 00:10:49.995402 | controller | {
2025-11-09 00:10:49.995428 | 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-11-09 00:10:49.995450 | controller | }
2025-11-09 00:10:50.009740 | 
2025-11-09 00:10:50.009854 | LOOP [ensure-package-repositories : Import GPG keys]
2025-11-09 00:10:51.298320 | controller | changed:
2025-11-09 00:10:51.298933 | controller | {
2025-11-09 00:10:51.298996 | 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-11-09 00:10:51.299034 | controller | }
2025-11-09 00:10:51.299081 | controller | changed: All items complete
2025-11-09 00:10:51.299110 | 
2025-11-09 00:10:51.319500 | 
2025-11-09 00:10:51.319647 | TASK [ensure-package-repositories : Remove GPG temp directory]
2025-11-09 00:10:52.031849 | controller | changed
2025-11-09 00:10:52.045645 | 
2025-11-09 00:10:52.045867 | LOOP [ensure-package-repositories : Add repositories]
2025-11-09 00:10:52.992068 | controller | changed:
2025-11-09 00:10:52.992454 | controller | {
2025-11-09 00:10:52.992532 | controller |   "baseurl": "https://download.docker.com/linux/fedora/$releasever/$basearch/stable",
2025-11-09 00:10:52.992588 | controller |   "description": "Docker CE Stable - $basearch",
2025-11-09 00:10:52.992642 | controller |   "gpgcheck": true,
2025-11-09 00:10:52.992693 | controller |   "name": "docker-ce-stable"
2025-11-09 00:10:52.992804 | controller | }
2025-11-09 00:10:53.019941 | 
2025-11-09 00:10:53.020101 | TASK [ensure-docker : Install pre-reqs]
2025-11-09 00:10:58.060574 | controller | ok: Nothing to do
2025-11-09 00:10:58.069039 | 
2025-11-09 00:10:58.069167 | TASK [ensure-docker : Remove packages]
2025-11-09 00:11:01.200562 | controller | ok: Nothing to do
2025-11-09 00:11:01.216294 | 
2025-11-09 00:11:01.216461 | TASK [ensure-docker : Install upstream docker using package]
2025-11-09 00:12:26.505762 | controller | changed
2025-11-09 00:12:26.520563 | 
2025-11-09 00:12:26.520771 | TASK [ensure-docker : Setup docker]
2025-11-09 00:12:26.544613 | controller | ok
2025-11-09 00:12:26.597324 | controller | included: /var/lib/zuul/builds/3b265a43d66b495a9e2ed1155bec8177/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/tasks/docker-setup.yaml
2025-11-09 00:12:26.613219 | 
2025-11-09 00:12:26.613355 | TASK [ensure-docker : Ensure "docker" group exists]
2025-11-09 00:12:27.591692 | controller | ok
2025-11-09 00:12:27.605402 | 
2025-11-09 00:12:27.605582 | TASK [ensure-docker : Add user to docker group]
2025-11-09 00:12:28.586538 | controller | changed
2025-11-09 00:12:28.597395 | 
2025-11-09 00:12:28.597495 | TASK [Update docker daemon proxy configuration]
2025-11-09 00:12:28.634020 | controller | skipping: Conditional result was False
2025-11-09 00:12:28.641246 | 
2025-11-09 00:12:28.641324 | TASK [Lower default MTU]
2025-11-09 00:12:28.654920 | controller | skipping: Conditional result was False
2025-11-09 00:12:28.662983 | 
2025-11-09 00:12:28.663083 | TASK [ensure-docker : Get the status of the docket.socket unit]
2025-11-09 00:12:29.404865 | controller | inactive
2025-11-09 00:12:30.022248 | controller | ok: Runtime: 0:00:00.018086
2025-11-09 00:12:30.028805 | 
2025-11-09 00:12:30.028868 | TASK [ensure-docker : Get the status of the docket unit]
2025-11-09 00:12:30.812137 | controller | inactive
2025-11-09 00:12:31.405658 | controller | ok: Runtime: 0:00:00.021514
2025-11-09 00:12:31.414806 | 
2025-11-09 00:12:31.414901 | TASK [ensure-docker : Restart docker]
2025-11-09 00:12:31.469592 | Trigger a Docker restart
2025-11-09 00:12:31.477568 | 
2025-11-09 00:12:31.477663 | TASK [ensure-docker : Reset ssh connection to pick up docker group]
2025-11-09 00:12:31.528424 | 
2025-11-09 00:12:31.528624 | TASK [ensure-docker : Install docker-compose]
2025-11-09 00:12:31.556951 | controller | skipping: Conditional result was False
2025-11-09 00:12:31.567053 | 
2025-11-09 00:12:31.567211 | TASK [ensure-docker : Flush handlers before role exit]
2025-11-09 00:12:31.582213 | 
2025-11-09 00:12:31.582348 | TASK [ensure-docker : Stop docker.socket to avoid any conflict]
2025-11-09 00:12:35.059682 | controller | changed
2025-11-09 00:12:35.068291 | 
2025-11-09 00:12:35.068403 | TASK [ensure-docker : Assure docker service is running]
2025-11-09 00:12:38.818062 | controller | changed
2025-11-09 00:12:38.832201 | 
2025-11-09 00:12:38.832399 | TASK [ensure-docker : Assure docker.socket service is running]
2025-11-09 00:12:40.527348 | controller | ok
2025-11-09 00:12:40.546763 | 
2025-11-09 00:12:40.546927 | TASK [ensure-docker : Correct group ownership on docker sock]
2025-11-09 00:12:41.356966 | controller | ok
2025-11-09 00:12:41.362676 | 
2025-11-09 00:12:41.362767 | TASK [ensure-docker : Validate ability to talk with docker]
2025-11-09 00:12:41.978682 | controller | CONTAINER ID   IMAGE     COMMAND   CREATED   STATUS    PORTS     NAMES
2025-11-09 00:12:42.215988 | controller | ok: Runtime: 0:00:00.036292
2025-11-09 00:12:42.224362 | 
2025-11-09 00:12:42.224473 | TASK [ensure-docker : Get version details]
2025-11-09 00:12:42.937024 | controller | Client: Docker Engine - Community
2025-11-09 00:12:42.937101 | controller |  Version:           24.0.7
2025-11-09 00:12:42.937112 | controller |  API version:       1.43
2025-11-09 00:12:42.937137 | controller |  Go version:        go1.20.10
2025-11-09 00:12:42.937156 | controller |  Git commit:        afdd53b
2025-11-09 00:12:42.937164 | controller |  Built:             Thu Oct 26 09:09:39 2023
2025-11-09 00:12:42.937171 | controller |  OS/Arch:           linux/amd64
2025-11-09 00:12:42.937181 | controller |  Context:           default
2025-11-09 00:12:42.937191 | controller |
2025-11-09 00:12:42.937198 | controller | Server: Docker Engine - Community
2025-11-09 00:12:42.937204 | controller |  Engine:
2025-11-09 00:12:42.937213 | controller |   Version:          24.0.7
2025-11-09 00:12:42.937245 | controller |   API version:      1.43 (minimum version 1.12)
2025-11-09 00:12:42.937266 | controller |   Go version:       go1.20.10
2025-11-09 00:12:42.937272 | controller |   Git commit:       311b9ff
2025-11-09 00:12:42.937278 | controller |   Built:            Thu Oct 26 09:08:11 2023
2025-11-09 00:12:42.937285 | controller |   OS/Arch:          linux/amd64
2025-11-09 00:12:42.937291 | controller |   Experimental:     false
2025-11-09 00:12:42.937306 | controller |  containerd:
2025-11-09 00:12:42.937313 | controller |   Version:          1.6.25
2025-11-09 00:12:42.937318 | controller |   GitCommit:        d8f198a4ed8892c764191ef7b3b06d8a2eeb5c7f
2025-11-09 00:12:42.937324 | controller |  runc:
2025-11-09 00:12:42.937330 | controller |   Version:          1.1.10
2025-11-09 00:12:42.937336 | controller |   GitCommit:        v1.1.10-0-g18a0cb0
2025-11-09 00:12:42.937344 | controller |  docker-init:
2025-11-09 00:12:42.937353 | controller |   Version:          0.19.0
2025-11-09 00:12:42.938913 | controller |   GitCommit:        de40ad0
2025-11-09 00:12:43.124190 | controller | ok: Runtime: 0:00:00.052341
2025-11-09 00:12:43.132311 | 
2025-11-09 00:12:43.132413 | TASK [ensure-docker : Dump installed docker details]
2025-11-09 00:12:43.175112 | {'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-11-09 00:12:42.890175', 'end': '2025-11-09 00:12:42.942516', 'delta': '0:00:00.052341', 'msg': '', 'zuul_log_id': 'fa163ef9-e89a-f4cc-00f5-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-11-09 00:12:43.182854 | 
2025-11-09 00:12:43.182938 | TASK [Use buildset registry]
2025-11-09 00:12:43.207954 | controller | skipping: Conditional result was False
2025-11-09 00:12:43.223252 | 
2025-11-09 00:12:43.223380 | TASK [Ensure python3.8 is present]
2025-11-09 00:12:43.248963 | controller | skipping: Conditional result was False
2025-11-09 00:12:43.257050 | 
2025-11-09 00:12:43.257158 | TASK [Run our-ensure-python role (Fedora only for now)]
2025-11-09 00:12:43.280088 | controller | ok
2025-11-09 00:12:43.307091 | 
2025-11-09 00:12:43.307229 | TASK [our-ensure-python : Install the right Python version (rpm)]
2025-11-09 00:12:50.778332 | controller | ok: Nothing to do
2025-11-09 00:12:50.784191 | 
2025-11-09 00:12:50.784262 | TASK [our-ensure-python : Also install python3-devel]
2025-11-09 00:13:18.498775 | controller | changed
2025-11-09 00:13:18.518377 | 
2025-11-09 00:13:18.518491 | TASK [Run ensure-virtualenv role]
2025-11-09 00:13:18.572149 | controller | ok
2025-11-09 00:13:18.602896 | 
2025-11-09 00:13:18.603015 | TASK [ensure-virtualenv : Check if virtualenv is installed]
2025-11-09 00:13:19.359263 | controller | /usr/bin/virtualenv
2025-11-09 00:13:19.965747 | controller | ok: Runtime: 0:00:00.004786
2025-11-09 00:13:19.980208 | 
2025-11-09 00:13:19.980381 | LOOP [ensure-virtualenv : Install virtualenv package]
2025-11-09 00:13:20.013703 | controller | skipping: Conditional result was False
2025-11-09 00:13:20.013982 | controller | changed: All items complete
2025-11-09 00:13:20.014013 | 
2025-11-09 00:13:20.048093 | 
2025-11-09 00:13:20.048242 | TASK [Find the full path of the Python interpreter]
2025-11-09 00:13:20.847558 | controller | /usr/bin/python3.8
2025-11-09 00:13:21.411196 | controller | ok
2025-11-09 00:13:21.419842 | 
2025-11-09 00:13:21.419951 | TASK [Create virtualenv for ansible-test with a specific interpreter]
2025-11-09 00:13:24.303857 | controller | created virtual environment CPython3.8.18.final.0-64 in 1324ms
2025-11-09 00:13:24.450658 | controller |   creator CPython3Posix(dest=/home/zuul/venv, clear=False, no_vcs_ignore=False, global=False)
2025-11-09 00:13:24.450722 | controller |   seeder FromAppData(extra_search_dir=/usr/share/python-wheels,download=False, pip=bundle, setuptools=bundle, wheel=bundle, via=copy, app_data_dir=/home/zuul/.local/share/virtualenv)
2025-11-09 00:13:24.450738 | controller |     added seed packages: pip==22.2.2, setuptools==62.6.0, wheel==0.37.1
2025-11-09 00:13:24.450770 | controller |   activators BashActivator,CShellActivator,FishActivator,NushellActivator,PowerShellActivator,PythonActivator
2025-11-09 00:13:24.876104 | controller | changed
2025-11-09 00:13:24.882029 | 
2025-11-09 00:13:24.882118 | TASK [Set selinux package]
2025-11-09 00:13:24.901773 | controller | ok
2025-11-09 00:13:24.907249 | 
2025-11-09 00:13:24.907315 | TASK [Set selinux package (Fedora)]
2025-11-09 00:13:24.968178 | controller | ok
2025-11-09 00:13:24.976569 | 
2025-11-09 00:13:24.976704 | TASK [Install selinux into virtualenv]
2025-11-09 00:13:30.939260 | controller | Collecting selinux-please-lie-to-me
2025-11-09 00:13:31.119854 | controller |   Downloading selinux_please_lie_to_me-1.0.1-py2.py3-none-any.whl (3.8 kB)
2025-11-09 00:13:31.977517 | controller | Collecting setuptools<50.0.0
2025-11-09 00:13:31.986707 | controller |   Downloading setuptools-49.6.0-py3-none-any.whl (803 kB)
2025-11-09 00:13:32.118634 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 803.3/803.3 kB 6.4 MB/s eta 0:00:00
2025-11-09 00:13:32.255495 | controller | Installing collected packages: setuptools, selinux-please-lie-to-me
2025-11-09 00:13:32.255853 | controller |   Attempting uninstall: setuptools
2025-11-09 00:13:32.257027 | controller |     Found existing installation: setuptools 62.6.0
2025-11-09 00:13:32.394238 | controller |     Uninstalling setuptools-62.6.0:
2025-11-09 00:13:32.411547 | controller |       Successfully uninstalled setuptools-62.6.0
2025-11-09 00:13:33.203445 | controller | Successfully installed selinux-please-lie-to-me-1.0.1 setuptools-49.6.0
2025-11-09 00:13:33.439313 | controller |
2025-11-09 00:13:33.719960 | controller | [notice] A new release of pip available: 22.2.2 -> 25.0.1
2025-11-09 00:13:33.720021 | controller | [notice] To update, run: /home/zuul/venv/bin/python -m pip install --upgrade pip
2025-11-09 00:13:33.855477 | controller | ok: Runtime: 0:00:07.544931
2025-11-09 00:13:33.861284 | 
2025-11-09 00:13:33.861346 | TASK [Install pytest-forked into virtualenv]
2025-11-09 00:13:35.516170 | controller | Collecting pytest-forked
2025-11-09 00:13:35.577184 | controller |   Downloading pytest_forked-1.6.0-py3-none-any.whl (4.9 kB)
2025-11-09 00:13:35.809059 | controller | Collecting pytest>=3.10
2025-11-09 00:13:35.815825 | controller |   Downloading pytest-8.3.5-py3-none-any.whl (343 kB)
2025-11-09 00:13:35.859356 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 343.6/343.6 kB 9.1 MB/s eta 0:00:00
2025-11-09 00:13:35.916196 | controller | Collecting py
2025-11-09 00:13:35.921260 | controller |   Downloading py-1.11.0-py2.py3-none-any.whl (98 kB)
2025-11-09 00:13:35.937205 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 98.7/98.7 kB 8.6 MB/s eta 0:00:00
2025-11-09 00:13:36.017006 | controller | Collecting pluggy<2,>=1.5
2025-11-09 00:13:36.024526 | controller |   Downloading pluggy-1.5.0-py3-none-any.whl (20 kB)
2025-11-09 00:13:36.070964 | controller | Collecting iniconfig
2025-11-09 00:13:36.075945 | controller |   Downloading iniconfig-2.1.0-py3-none-any.whl (6.0 kB)
2025-11-09 00:13:36.161160 | controller | Collecting tomli>=1
2025-11-09 00:13:36.167609 | controller |   Downloading tomli-2.3.0-py3-none-any.whl (14 kB)
2025-11-09 00:13:36.238369 | controller | Collecting exceptiongroup>=1.0.0rc8
2025-11-09 00:13:36.243500 | controller |   Downloading exceptiongroup-1.3.0-py3-none-any.whl (16 kB)
2025-11-09 00:13:36.330242 | controller | Collecting packaging
2025-11-09 00:13:36.336188 | controller |   Downloading packaging-25.0-py3-none-any.whl (66 kB)
2025-11-09 00:13:36.351409 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 66.5/66.5 kB 5.4 MB/s eta 0:00:00
2025-11-09 00:13:36.431328 | controller | Collecting typing-extensions>=4.6.0
2025-11-09 00:13:36.440376 | controller |   Downloading typing_extensions-4.13.2-py3-none-any.whl (45 kB)
2025-11-09 00:13:36.451157 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 45.8/45.8 kB 4.9 MB/s eta 0:00:00
2025-11-09 00:13:36.567427 | controller | Installing collected packages: typing-extensions, tomli, py, pluggy, packaging, iniconfig, exceptiongroup, pytest, pytest-forked
2025-11-09 00:13:37.585242 | controller | Successfully installed exceptiongroup-1.3.0 iniconfig-2.1.0 packaging-25.0 pluggy-1.5.0 py-1.11.0 pytest-8.3.5 pytest-forked-1.6.0 tomli-2.3.0 typing-extensions-4.13.2
2025-11-09 00:13:37.594192 | controller |
2025-11-09 00:13:37.841570 | controller | [notice] A new release of pip available: 22.2.2 -> 25.0.1
2025-11-09 00:13:37.841630 | controller | [notice] To update, run: /home/zuul/venv/bin/python -m pip install --upgrade pip
2025-11-09 00:13:38.229635 | controller | ok: Runtime: 0:00:03.057129
2025-11-09 00:13:38.242341 | 
2025-11-09 00:13:38.242475 | TASK [Update pip]
2025-11-09 00:13:41.035633 | controller | Requirement already satisfied: pip in ./venv/lib/python3.8/site-packages (22.2.2)
2025-11-09 00:13:41.507729 | controller | Collecting pip
2025-11-09 00:13:41.642477 | controller |   Downloading pip-25.0.1-py3-none-any.whl (1.8 MB)
2025-11-09 00:13:41.754592 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 1.8/1.8 MB 18.4 MB/s eta 0:00:00
2025-11-09 00:13:42.020165 | controller | Installing collected packages: pip
2025-11-09 00:13:42.020367 | controller |   Attempting uninstall: pip
2025-11-09 00:13:42.029355 | controller |     Found existing installation: pip 22.2.2
2025-11-09 00:13:42.748358 | controller |     Uninstalling pip-22.2.2:
2025-11-09 00:13:42.786063 | controller |       Successfully uninstalled pip-22.2.2
2025-11-09 00:13:45.128646 | controller | Successfully installed pip-25.0.1
2025-11-09 00:13:45.631854 | controller | ok: Runtime: 0:00:05.939661
2025-11-09 00:13:45.646346 | 
2025-11-09 00:13:45.646516 | TASK [Ensure pip temp directory exists in /var/tmp]
2025-11-09 00:13:46.870513 | controller | changed
2025-11-09 00:13:46.888923 | 
2025-11-09 00:13:46.889104 | TASK [Install ansible into virtualenv]
2025-11-09 00:13:48.600494 | controller | Processing ./src/github.com/ansible/ansible
2025-11-09 00:13:50.559006 | controller |   Preparing metadata (setup.py): started
2025-11-09 00:13:50.559073 | controller |   Preparing metadata (setup.py): finished with status 'done'
2025-11-09 00:13:50.645562 | controller | Collecting jinja2 (from ansible==2.9.27.post0)
2025-11-09 00:13:50.663717 | controller |   Downloading jinja2-3.1.6-py3-none-any.whl.metadata (2.9 kB)
2025-11-09 00:13:50.766920 | controller | Collecting PyYAML (from ansible==2.9.27.post0)
2025-11-09 00:13:50.773282 | controller |   Downloading PyYAML-6.0.3-cp38-cp38-manylinux2014_x86_64.manylinux_2_17_x86_64.manylinux_2_28_x86_64.whl.metadata (2.1 kB)
2025-11-09 00:13:51.260486 | controller | Collecting cryptography (from ansible==2.9.27.post0)
2025-11-09 00:13:51.267707 | controller |   Downloading cryptography-46.0.3-cp38-abi3-manylinux_2_34_x86_64.whl.metadata (5.7 kB)
2025-11-09 00:13:51.548682 | controller | Collecting cffi>=1.14 (from cryptography->ansible==2.9.27.post0)
2025-11-09 00:13:51.554114 | controller |   Downloading cffi-1.17.1-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl.metadata (1.5 kB)
2025-11-09 00:13:51.583272 | controller | Requirement already satisfied: typing-extensions>=4.13.2 in ./venv/lib/python3.8/site-packages (from cryptography->ansible==2.9.27.post0) (4.13.2)
2025-11-09 00:13:51.717585 | controller | Collecting MarkupSafe>=2.0 (from jinja2->ansible==2.9.27.post0)
2025-11-09 00:13:51.728125 | controller |   Downloading MarkupSafe-2.1.5-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl.metadata (3.0 kB)
2025-11-09 00:13:51.776164 | controller | Collecting pycparser (from cffi>=1.14->cryptography->ansible==2.9.27.post0)
2025-11-09 00:13:51.784583 | controller |   Downloading pycparser-2.23-py3-none-any.whl.metadata (993 bytes)
2025-11-09 00:13:51.808388 | controller | Downloading cryptography-46.0.3-cp38-abi3-manylinux_2_34_x86_64.whl (4.5 MB)
2025-11-09 00:13:51.885897 | controller |    ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 4.5/4.5 MB 66.8 MB/s eta 0:00:00
2025-11-09 00:13:51.891158 | controller | Downloading jinja2-3.1.6-py3-none-any.whl (134 kB)
2025-11-09 00:13:51.914410 | controller | Downloading PyYAML-6.0.3-cp38-cp38-manylinux2014_x86_64.manylinux_2_17_x86_64.manylinux_2_28_x86_64.whl (806 kB)
2025-11-09 00:13:51.936859 | controller |    ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 806.0/806.0 kB 39.4 MB/s eta 0:00:00
2025-11-09 00:13:51.943390 | controller | Downloading cffi-1.17.1-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl (446 kB)
2025-11-09 00:13:51.965522 | controller | Downloading MarkupSafe-2.1.5-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl (26 kB)
2025-11-09 00:13:51.982328 | controller | Downloading pycparser-2.23-py3-none-any.whl (118 kB)
2025-11-09 00:13:52.018667 | controller | Building wheels for collected packages: ansible
2025-11-09 00:13:52.019919 | controller |   Building wheel for ansible (setup.py): started
2025-11-09 00:14:02.753323 | controller |   Building wheel for ansible (setup.py): finished with status 'done'
2025-11-09 00:14:02.777090 | controller |   Created wheel for ansible: filename=ansible-2.9.27.post0-py3-none-any.whl size=16207514 sha256=eac0ae32f129f0294530c07aeddc3f82de93059fceca038eea7a5f26668d05c6
2025-11-09 00:14:02.777490 | controller |   Stored in directory: /var/tmp/ansible-test-pip/pip-ephem-wheel-cache-6xlcyq50/wheels/a5/4b/fa/b6deaae105422b0b3b92831e0369d44bf7fca5bb3413d79be7
2025-11-09 00:14:02.857287 | controller | Successfully built ansible
2025-11-09 00:14:03.056664 | controller | Installing collected packages: PyYAML, pycparser, MarkupSafe, jinja2, cffi, cryptography, ansible
2025-11-09 00:14:17.626146 | controller | Successfully installed MarkupSafe-2.1.5 PyYAML-6.0.3 ansible-2.9.27.post0 cffi-1.17.1 cryptography-46.0.3 jinja2-3.1.6 pycparser-2.23
2025-11-09 00:14:18.289164 | controller | ok: Runtime: 0:00:29.887613
2025-11-09 00:14:18.297660 | 
2025-11-09 00:14:18.297809 | TASK [Setup download-artifact-fork role]
2025-11-09 00:14:18.323679 | controller | ok
2025-11-09 00:14:18.348565 | 
2025-11-09 00:14:18.348707 | TASK [download-artifact-fork : Parse inventory response]
2025-11-09 00:14:18.404055 | controller | ok
2025-11-09 00:14:18.412223 | 
2025-11-09 00:14:18.412343 | TASK [download-artifact-fork : Fetch info from Zuul API]
2025-11-09 00:14:18.448524 | controller | skipping: Conditional result was False
2025-11-09 00:14:18.457201 | 
2025-11-09 00:14:18.457316 | TASK [download-artifact-fork : Parse build response]
2025-11-09 00:14:18.483339 | controller | skipping: Conditional result was False
2025-11-09 00:14:18.525893 | 
2025-11-09 00:14:18.526012 | TASK [download-artifact-fork : Ensure artifacts directory exists]
2025-11-09 00:14:19.361704 | controller | changed
2025-11-09 00:14:19.378231 | 
2025-11-09 00:14:19.378428 | LOOP [download-artifact-fork : Download archive by type]
2025-11-09 00:14:19.406680 | controller | skipping: Conditional result was False
2025-11-09 00:14:19.406989 | 
2025-11-09 00:14:21.263461 | controller | changed: OK (20185 bytes)
2025-11-09 00:14:21.288535 | 
2025-11-09 00:14:21.288668 | TASK [deploy-artifacts : define __collections]
2025-11-09 00:14:21.321416 | controller | ok
2025-11-09 00:14:21.329449 | 
2025-11-09 00:14:21.329550 | LOOP [deploy-artifacts : Create list of collections]
2025-11-09 00:14:21.355629 | controller | skipping: Conditional result was False
2025-11-09 00:14:21.369782 | controller | ok:
2025-11-09 00:14:21.370303 | controller | {
2025-11-09 00:14:21.370352 | controller |   "branch": "main",
2025-11-09 00:14:21.370382 | controller |   "job": "build-ansible-collection",
2025-11-09 00:14:21.370408 | controller |   "metadata": {
2025-11-09 00:14:21.370433 | controller |     "type": "ansible_collection",
2025-11-09 00:14:21.370457 | controller |     "version": "5.0.0"
2025-11-09 00:14:21.370481 | controller |   },
2025-11-09 00:14:21.370506 | controller |   "name": "ansible.network",
2025-11-09 00:14:21.370530 | controller |   "project": "ansible-collections/ansible.network",
2025-11-09 00:14:21.370554 | controller |   "url": "https://fa21944ca58e915dfb0e-2ed5cf1d0adaac8ad75a43940c07b553.ssl.cf5.rackcdn.com/ansible/bcb699ed2cf54500b5db42c838ed6212/artifacts/ansible-network-5.0.0.tar.gz"
2025-11-09 00:14:21.370586 | controller | }
2025-11-09 00:14:21.389099 | 
2025-11-09 00:14:21.389229 | TASK [deploy-artifacts : Install require-project collection using ansible-galaxy]
2025-11-09 00:14:22.418089 | controller | Process install dependency map
2025-11-09 00:15:08.344916 | controller | |/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/Starting collection install process
2025-11-09 00:15:08.445809 | controller | |Installing 'ansible.network:5.0.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/network'
2025-11-09 00:15:08.445875 | controller | Installing 'ansible.netcommon:8.2.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/netcommon'
2025-11-09 00:15:10.153923 | controller | /-\|/-\|/-\|/-\|/Installing 'ansible.utils:6.0.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/utils'
2025-11-09 00:15:12.484952 | controller | -\|/-\|/-\|/-\|/-\|/-\|Installing 'arista.eos:12.0.0' to '/home/zuul/.ansible/collections/ansible_collections/arista/eos'
2025-11-09 00:15:15.200381 | controller | /-\|/-\|/-\|/-\|/-\|/-\|/-\Installing 'cisco.ios:11.1.1' to '/home/zuul/.ansible/collections/ansible_collections/cisco/ios'
2025-11-09 00:15:18.222817 | controller | |/-\|/-\|/-\|/-\|/-\|/-\|/-\|/Installing 'cisco.iosxr:12.1.0' to '/home/zuul/.ansible/collections/ansible_collections/cisco/iosxr'
2025-11-09 00:15:21.197327 | controller | -\|/-\|/-\|/-\|/-\|/-\|/-\|/-Installing 'cisco.nxos:11.1.0' to '/home/zuul/.ansible/collections/ansible_collections/cisco/nxos'
2025-11-09 00:15:25.955491 | controller | \|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/Installing 'junipernetworks.junos:11.0.0' to '/home/zuul/.ansible/collections/ansible_collections/junipernetworks/junos'
2025-11-09 00:15:29.298231 | controller | ok: Runtime: 0:01:06.769805
2025-11-09 00:15:29.315107 | 
2025-11-09 00:15:29.315226 | TASK [podman may need iptables kernel modules]
2025-11-09 00:15:30.690273 | controller | ok: Runtime: 0:00:00.010234
2025-11-09 00:15:30.738933 | 
2025-11-09 00:15:30.739037 | PLAY RECAP
2025-11-09 00:15:30.739093 | controller | ok: 46 changed: 26 unreachable: 0 failed: 0 skipped: 17 rescued: 0 ignored: 0
2025-11-09 00:15:30.739121 | 
2025-11-09 00:15:30.865269 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master]
2025-11-09 00:15:30.866466 | RUN START: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/run.yaml@master]
2025-11-09 00:15:31.535954 | 
2025-11-09 00:15:31.536103 | PLAY [controller]
2025-11-09 00:15:31.564401 | 
2025-11-09 00:15:31.564571 | TASK [Copy the galaxy.yml on the controller]
2025-11-09 00:15:35.290550 | controller | changed
2025-11-09 00:15:35.298223 | 
2025-11-09 00:15:35.298368 | TASK [Load information from galaxy.yml]
2025-11-09 00:15:35.344012 | controller | ok
2025-11-09 00:15:35.353098 | 
2025-11-09 00:15:35.353216 | LOOP [Enable FIPS mode]
2025-11-09 00:15:35.382352 | 
2025-11-09 00:15:35.382566 | LOOP [ansible-test : Include branch specific variables.]
2025-11-09 00:15:35.431768 | controller | ok: "/var/lib/zuul/builds/3b265a43d66b495a9e2ed1155bec8177/untrusted/project_2/github.com/ansible/ansible-zuul-jobs/roles/ansible-test/vars/default.yaml"
2025-11-09 00:15:35.432028 | 
2025-11-09 00:15:35.451035 | 
2025-11-09 00:15:35.451183 | TASK [ansible-test : Setup --skip-tags for test_options]
2025-11-09 00:15:35.484027 | controller | ok
2025-11-09 00:15:35.492962 | 
2025-11-09 00:15:35.493097 | TASK [ansible-test : Enable --inventory for network-integration]
2025-11-09 00:15:35.509009 | controller | skipping: Conditional result was False
2025-11-09 00:15:35.519863 | 
2025-11-09 00:15:35.520014 | TASK [ansible-test : Enable --retry-on-error]
2025-11-09 00:15:35.534688 | controller | skipping: Conditional result was False
2025-11-09 00:15:35.544536 | 
2025-11-09 00:15:35.544768 | TASK [ansible-test : Enable --continue-on-error]
2025-11-09 00:15:35.577498 | controller | ok
2025-11-09 00:15:35.584070 | 
2025-11-09 00:15:35.584168 | TASK [ansible-test : Enable --requirements]
2025-11-09 00:15:35.597673 | controller | skipping: Conditional result was False
2025-11-09 00:15:35.604308 | 
2025-11-09 00:15:35.604425 | TASK [ansible-test : Adjust options for unit tests]
2025-11-09 00:15:35.618050 | controller | skipping: Conditional result was False
2025-11-09 00:15:35.624613 | 
2025-11-09 00:15:35.624719 | TASK [ansible-test : Install requirements with unit tests]
2025-11-09 00:15:35.680370 | controller | ok
2025-11-09 00:15:35.686140 | 
2025-11-09 00:15:35.686237 | TASK [ansible-test : Set --skip-test]
2025-11-09 00:15:35.731021 | controller | skipping: Conditional result was False
2025-11-09 00:15:35.740331 | 
2025-11-09 00:15:35.740478 | TASK [ansible-test : Reduce verbosity]
2025-11-09 00:15:35.771845 | controller | ok
2025-11-09 00:15:35.777544 | 
2025-11-09 00:15:35.777648 | TASK [ansible-test : Setup --python option]
2025-11-09 00:15:35.791419 | controller | skipping: Conditional result was False
2025-11-09 00:15:35.798264 | 
2025-11-09 00:15:35.798396 | TASK [ansible-test : Setup --docker option]
2025-11-09 00:15:35.827462 | controller | ok
2025-11-09 00:15:35.834277 | 
2025-11-09 00:15:35.834353 | TASK [ansible-test : check if MANIFEST.json exists]
2025-11-09 00:15:36.911879 | controller | ok
2025-11-09 00:15:36.924483 | 
2025-11-09 00:15:36.924613 | TASK [ansible-test : Copy the MANIFEST.json on the controller]
2025-11-09 00:15:36.940799 | controller | skipping: Conditional result was False
2025-11-09 00:15:36.947617 | 
2025-11-09 00:15:36.947701 | TASK [ansible-test : Load information from MANIFEST.json]
2025-11-09 00:15:36.961208 | controller | skipping: Conditional result was False
2025-11-09 00:15:36.975536 | 
2025-11-09 00:15:36.975648 | TASK [ansible-test : Setup location of project for integration tests]
2025-11-09 00:15:36.989944 | controller | skipping: Conditional result was False
2025-11-09 00:15:36.999518 | 
2025-11-09 00:15:36.999657 | TASK [ansible-test : Copy the galaxy.yml on the controller]
2025-11-09 00:15:38.386599 | controller | ok
2025-11-09 00:15:38.396563 | 
2025-11-09 00:15:38.396760 | TASK [ansible-test : Load information from galaxy.yml]
2025-11-09 00:15:38.433071 | controller | ok
2025-11-09 00:15:38.439868 | 
2025-11-09 00:15:38.439956 | TASK [ansible-test : Setup location of project for integration tests]
2025-11-09 00:15:38.469959 | controller | ok
2025-11-09 00:15:38.476170 | 
2025-11-09 00:15:38.476254 | TASK [ansible-test : Setup minimum test requirements]
2025-11-09 00:15:38.506323 | controller | ok
2025-11-09 00:15:38.512795 | 
2025-11-09 00:15:38.512868 | TASK [ansible-test : Ensure pip temp directory exists in /var/tmp]
2025-11-09 00:15:39.560076 | controller | ok
2025-11-09 00:15:39.569654 | 
2025-11-09 00:15:39.569785 | TASK [ansible-test : Install python requirements]
2025-11-09 00:15:41.160392 | controller | Collecting yamllint (from -r /home/zuul/.ansible/collections/ansible_collections/ansible/network/test-requirements.txt (line 1))
2025-11-09 00:15:41.177411 | controller |   Downloading yamllint-1.35.1-py3-none-any.whl.metadata (4.2 kB)
2025-11-09 00:15:41.222692 | controller | Collecting pathspec>=0.5.3 (from yamllint->-r /home/zuul/.ansible/collections/ansible_collections/ansible/network/test-requirements.txt (line 1))
2025-11-09 00:15:41.227889 | controller |   Downloading pathspec-0.12.1-py3-none-any.whl.metadata (21 kB)
2025-11-09 00:15:41.240434 | controller | Requirement already satisfied: pyyaml in ./venv/lib64/python3.8/site-packages (from yamllint->-r /home/zuul/.ansible/collections/ansible_collections/ansible/network/test-requirements.txt (line 1)) (6.0.3)
2025-11-09 00:15:41.250282 | controller | Downloading yamllint-1.35.1-py3-none-any.whl (66 kB)
2025-11-09 00:15:41.287244 | controller | Downloading pathspec-0.12.1-py3-none-any.whl (31 kB)
2025-11-09 00:15:41.371328 | controller | Installing collected packages: pathspec, yamllint
2025-11-09 00:15:41.465363 | controller | Successfully installed pathspec-0.12.1 yamllint-1.35.1
2025-11-09 00:15:41.932423 | controller | ok: Runtime: 0:00:01.144779
2025-11-09 00:15:41.941564 | 
2025-11-09 00:15:41.941681 | TASK [ansible-test : Copy the integration_config.yml file from the controller]
2025-11-09 00:15:42.953580 | controller | cp: cannot stat '/tmp/integration_config.yml': No such file or directory
2025-11-09 00:15:43.306509 | controller | ERROR
2025-11-09 00:15:43.306714 | controller | {
2025-11-09 00:15:43.306792 | controller |   "delta": "0:00:00.017938",
2025-11-09 00:15:43.306823 | controller |   "end": "2025-11-09 00:15:42.953877",
2025-11-09 00:15:43.306848 | controller |   "msg": "non-zero return code",
2025-11-09 00:15:43.306871 | controller |   "rc": 1,
2025-11-09 00:15:43.306895 | controller |   "start": "2025-11-09 00:15:42.935939"
2025-11-09 00:15:43.306918 | controller | }
2025-11-09 00:15:43.306948 | controller | ERROR: Ignoring Errors
2025-11-09 00:15:43.315664 | 
2025-11-09 00:15:43.315811 | TASK [ansible-test : Copy potential cloud provider configuration for ansible-test in the collection]
2025-11-09 00:15:43.686206 | controller | skipping: Conditional result was False
2025-11-09 00:15:43.700119 | 
2025-11-09 00:15:43.700261 | TASK [ansible-test : Enable persistent connection logging]
2025-11-09 00:15:45.086611 | controller | changed: section and option added
2025-11-09 00:15:45.101053 | 
2025-11-09 00:15:45.101256 | TASK [ansible-test : Set persistent ssh_type value]
2025-11-09 00:15:46.310082 | controller | changed: option added
2025-11-09 00:15:46.324164 | 
2025-11-09 00:15:46.324313 | TASK [ansible-test : Set ansible log path]
2025-11-09 00:15:47.688494 | controller | changed: section and option added
2025-11-09 00:15:47.702223 | 
2025-11-09 00:15:47.702376 | TASK [ansible-test : Identify the changed targets]
2025-11-09 00:15:48.082671 | controller | skipping: Conditional result was False
2025-11-09 00:15:48.096302 | 
2025-11-09 00:15:48.096444 | TASK [ansible-test : set_fact]
2025-11-09 00:15:48.205369 | controller | skipping: Conditional result was False
2025-11-09 00:15:48.219022 | 
2025-11-09 00:15:48.219175 | TASK [ansible-test : Set the targets]
2025-11-09 00:15:48.293386 | controller | ok
2025-11-09 00:15:48.301126 | 
2025-11-09 00:15:48.301218 | TASK [ansible-test : set_fact]
2025-11-09 00:15:48.326933 | controller | skipping: Conditional result was False
2025-11-09 00:15:48.336833 | 
2025-11-09 00:15:48.336983 | TASK [ansible-test : List the targets]
2025-11-09 00:15:48.708205 | controller | skipping: Conditional result was False
2025-11-09 00:15:48.719388 | 
2025-11-09 00:15:48.719563 | TASK [ansible-test : set_fact]
2025-11-09 00:15:48.745825 | controller | skipping: Conditional result was False
2025-11-09 00:15:48.756651 | 
2025-11-09 00:15:48.756858 | TASK [ansible-test : set_fact]
2025-11-09 00:15:48.783621 | controller | skipping: Conditional result was False
2025-11-09 00:15:48.794895 | 
2025-11-09 00:15:48.795036 | LOOP [ansible-test : set_fact]
2025-11-09 00:15:48.871616 | 
2025-11-09 00:15:48.871884 | TASK [ansible-test : set_fact]
2025-11-09 00:15:48.899718 | controller | skipping: Conditional result was False
2025-11-09 00:15:48.908115 | 
2025-11-09 00:15:48.908243 | TASK [ansible-test : debug]
2025-11-09 00:15:48.933993 | controller | skipping: Conditional result was False
2025-11-09 00:15:48.946244 | 
2025-11-09 00:15:48.946413 | TASK [ansible-test : debug]
2025-11-09 00:15:48.992478 | About to run: ansible-test sanity --requirements --docker -v
2025-11-09 00:15:48.998755 | 
2025-11-09 00:15:48.998845 | TASK [ansible-test : Run the test suite]
2025-11-09 00:15:50.337998 | controller | Run command: docker images quay.io/ansible/default-test-container:1.10.1 --format '{{json .}}'
2025-11-09 00:15:50.410848 | controller | Run command: docker pull quay.io/ansible/default-test-container:1.10.1
2025-11-09 00:15:52.040304 | controller | 1.10.1: Pulling from ansible/default-test-container
2025-11-09 00:15:52.040357 | controller | [DEPRECATION NOTICE] Docker Image Format v1, and Docker Image manifest version 2, schema 1 support will be removed in an upcoming release. Suggest the author of quay.io/ansible/default-test-container:1.10.1 to upgrade the image to the OCI Format, or Docker Image manifest v2, schema 2. More information at https://docs.docker.com/go/deprecated-image-specs/
2025-11-09 00:15:52.040379 | controller | 5667fdb72017: Pulling fs layer
2025-11-09 00:15:52.040387 | controller | d83811f270d5: Pulling fs layer
2025-11-09 00:15:52.040394 | controller | ee671aafb583: Pulling fs layer
2025-11-09 00:15:52.040404 | controller | 7fc152dfb3a6: Pulling fs layer
2025-11-09 00:15:52.040413 | controller | 3fb967a6fd03: Pulling fs layer
2025-11-09 00:15:52.040419 | controller | b3e63023ca5f: Pulling fs layer
2025-11-09 00:15:52.040426 | controller | 653cb3e65dbf: Pulling fs layer
2025-11-09 00:15:52.040432 | controller | f4712b586375: Pulling fs layer
2025-11-09 00:15:52.040438 | controller | d696ed7812f8: Pulling fs layer
2025-11-09 00:15:52.040444 | controller | 29bf28764f14: Pulling fs layer
2025-11-09 00:15:52.040450 | controller | 47d7edb3c2b7: Pulling fs layer
2025-11-09 00:15:52.040456 | controller | 07550ad4f6e4: Pulling fs layer
2025-11-09 00:15:52.040462 | controller | 7e79526e64c5: Pulling fs layer
2025-11-09 00:15:52.040468 | controller | 4fffb32882b7: Pulling fs layer
2025-11-09 00:15:52.040474 | controller | fc7a38b06f4e: Pulling fs layer
2025-11-09 00:15:52.040480 | controller | 6d9d912e060e: Pulling fs layer
2025-11-09 00:15:52.040488 | controller | 5dd542dd28d9: Pulling fs layer
2025-11-09 00:15:52.040494 | controller | cef8beb99552: Pulling fs layer
2025-11-09 00:15:52.040501 | controller | bd5557e101ad: Pulling fs layer
2025-11-09 00:15:52.040507 | controller | e057c20fabdd: Pulling fs layer
2025-11-09 00:15:52.040515 | controller | d16059cdae2e: Pulling fs layer
2025-11-09 00:15:52.040521 | controller | 431e42d906a3: Pulling fs layer
2025-11-09 00:15:52.040543 | controller | 7a0fd3b1d7c3: Pulling fs layer
2025-11-09 00:15:52.040550 | controller | aacdb6db0c8c: Pulling fs layer
2025-11-09 00:15:52.040556 | controller | 2e7f04ae8c8b: Pulling fs layer
2025-11-09 00:15:52.040562 | controller | 4cd343027a44: Pulling fs layer
2025-11-09 00:15:52.040571 | controller | 0b4379883f90: Pulling fs layer
2025-11-09 00:15:52.832877 | controller | 3d9d6d9dfc91: Pulling fs layer
2025-11-09 00:15:52.832927 | controller | 57d6314bd293: Pulling fs layer
2025-11-09 00:15:52.832935 | controller | 774036afeb7f: Pulling fs layer
2025-11-09 00:15:52.832967 | controller | 54b10f0e1645: Pulling fs layer
2025-11-09 00:15:52.832975 | controller | 78cf439d9fdb: Pulling fs layer
2025-11-09 00:15:52.832982 | controller | 36e5e20969c7: Pulling fs layer
2025-11-09 00:15:52.832989 | controller | 4b19ac3f17a8: Pulling fs layer
2025-11-09 00:15:52.832995 | controller | 7e79526e64c5: Waiting
2025-11-09 00:15:52.833002 | controller | 4fffb32882b7: Waiting
2025-11-09 00:15:52.833012 | controller | fc7a38b06f4e: Waiting
2025-11-09 00:15:52.833019 | controller | 6d9d912e060e: Waiting
2025-11-09 00:15:52.833025 | controller | 5dd542dd28d9: Waiting
2025-11-09 00:15:52.833032 | controller | cef8beb99552: Waiting
2025-11-09 00:15:52.833038 | controller | bd5557e101ad: Waiting
2025-11-09 00:15:52.833044 | controller | e057c20fabdd: Waiting
2025-11-09 00:15:52.833050 | controller | d16059cdae2e: Waiting
2025-11-09 00:15:52.833056 | controller | 431e42d906a3: Waiting
2025-11-09 00:15:52.833062 | controller | 7a0fd3b1d7c3: Waiting
2025-11-09 00:15:52.833068 | controller | aacdb6db0c8c: Waiting
2025-11-09 00:15:52.833074 | controller | 2e7f04ae8c8b: Waiting
2025-11-09 00:15:52.833080 | controller | 4cd343027a44: Waiting
2025-11-09 00:15:52.833089 | controller | 0b4379883f90: Waiting
2025-11-09 00:15:52.833095 | controller | 3d9d6d9dfc91: Waiting
2025-11-09 00:15:52.833101 | controller | 57d6314bd293: Waiting
2025-11-09 00:15:52.833107 | controller | 774036afeb7f: Waiting
2025-11-09 00:15:52.833113 | controller | 54b10f0e1645: Waiting
2025-11-09 00:15:52.833119 | controller | 78cf439d9fdb: Waiting
2025-11-09 00:15:52.833125 | controller | 36e5e20969c7: Waiting
2025-11-09 00:15:52.833131 | controller | 4b19ac3f17a8: Waiting
2025-11-09 00:15:52.833137 | controller | 653cb3e65dbf: Waiting
2025-11-09 00:15:52.833144 | controller | f4712b586375: Waiting
2025-11-09 00:15:52.833150 | controller | d696ed7812f8: Waiting
2025-11-09 00:15:52.833156 | controller | 29bf28764f14: Waiting
2025-11-09 00:15:52.833164 | controller | 47d7edb3c2b7: Waiting
2025-11-09 00:15:52.833182 | controller | 07550ad4f6e4: Waiting
2025-11-09 00:15:52.833189 | controller | 7fc152dfb3a6: Waiting
2025-11-09 00:15:52.833195 | controller | 3fb967a6fd03: Waiting
2025-11-09 00:15:52.833201 | controller | b3e63023ca5f: Waiting
2025-11-09 00:15:52.833215 | controller | d83811f270d5: Verifying Checksum
2025-11-09 00:15:52.873129 | controller | d83811f270d5: Download complete
2025-11-09 00:15:52.873178 | controller | ee671aafb583: Verifying Checksum
2025-11-09 00:15:53.630025 | controller | ee671aafb583: Download complete
2025-11-09 00:15:53.630076 | controller | 7fc152dfb3a6: Verifying Checksum
2025-11-09 00:15:54.596651 | controller | 7fc152dfb3a6: Download complete
2025-11-09 00:15:54.596708 | controller | b3e63023ca5f: Verifying Checksum
2025-11-09 00:15:55.562901 | controller | b3e63023ca5f: Download complete
2025-11-09 00:15:55.562978 | controller | 653cb3e65dbf: Verifying Checksum
2025-11-09 00:15:55.563032 | controller | 653cb3e65dbf: Download complete
2025-11-09 00:15:57.444537 | controller | 5667fdb72017: Verifying Checksum
2025-11-09 00:15:58.559112 | controller | 5667fdb72017: Download complete
2025-11-09 00:15:58.559170 | controller | d696ed7812f8: Verifying Checksum
2025-11-09 00:15:58.559244 | controller | d696ed7812f8: Download complete
2025-11-09 00:15:59.619110 | controller | 29bf28764f14: Verifying Checksum
2025-11-09 00:16:00.492888 | controller | 29bf28764f14: Download complete
2025-11-09 00:16:00.492994 | controller | 5667fdb72017: Pull complete
2025-11-09 00:16:00.537061 | controller | d83811f270d5: Pull complete
2025-11-09 00:16:00.561160 | controller | ee671aafb583: Pull complete
2025-11-09 00:16:00.596729 | controller | 7fc152dfb3a6: Pull complete
2025-11-09 00:16:01.262088 | controller | 47d7edb3c2b7: Verifying Checksum
2025-11-09 00:16:01.262168 | controller | 47d7edb3c2b7: Download complete
2025-11-09 00:16:02.317814 | controller | 07550ad4f6e4: Verifying Checksum
2025-11-09 00:16:02.317897 | controller | 07550ad4f6e4: Download complete
2025-11-09 00:16:03.307251 | controller | 7e79526e64c5: Verifying Checksum
2025-11-09 00:16:03.307332 | controller | 7e79526e64c5: Download complete
2025-11-09 00:16:04.247291 | controller | 4fffb32882b7: Verifying Checksum
2025-11-09 00:16:04.247369 | controller | 4fffb32882b7: Download complete
2025-11-09 00:16:04.490276 | controller | 3fb967a6fd03: Verifying Checksum
2025-11-09 00:16:04.490346 | controller | 3fb967a6fd03: Download complete
2025-11-09 00:16:05.416915 | controller | 6d9d912e060e: Verifying Checksum
2025-11-09 00:16:05.417134 | controller | 6d9d912e060e: Download complete
2025-11-09 00:16:05.617928 | controller | fc7a38b06f4e: Verifying Checksum
2025-11-09 00:16:05.618000 | controller | fc7a38b06f4e: Download complete
2025-11-09 00:16:05.687748 | controller | 3fb967a6fd03: Pull complete
2025-11-09 00:16:05.732654 | controller | b3e63023ca5f: Pull complete
2025-11-09 00:16:05.771918 | controller | 653cb3e65dbf: Pull complete
2025-11-09 00:16:06.433545 | controller | 5dd542dd28d9: Verifying Checksum
2025-11-09 00:16:06.433655 | controller | 5dd542dd28d9: Download complete
2025-11-09 00:16:07.293442 | controller | bd5557e101ad: Download complete
2025-11-09 00:16:08.279006 | controller | e057c20fabdd: Verifying Checksum
2025-11-09 00:16:08.279168 | controller | e057c20fabdd: Download complete
2025-11-09 00:16:09.126402 | controller | d16059cdae2e: Verifying Checksum
2025-11-09 00:16:09.126551 | controller | d16059cdae2e: Download complete
2025-11-09 00:16:11.197135 | controller | 431e42d906a3: Verifying Checksum
2025-11-09 00:16:11.197279 | controller | 431e42d906a3: Download complete
2025-11-09 00:16:12.433780 | controller | 7a0fd3b1d7c3: Verifying Checksum
2025-11-09 00:16:12.434144 | controller | 7a0fd3b1d7c3: Download complete
2025-11-09 00:16:13.495662 | controller | aacdb6db0c8c: Verifying Checksum
2025-11-09 00:16:13.495886 | controller | aacdb6db0c8c: Download complete
2025-11-09 00:16:14.373952 | controller | 2e7f04ae8c8b: Verifying Checksum
2025-11-09 00:16:14.375351 | controller | 2e7f04ae8c8b: Download complete
2025-11-09 00:16:15.519845 | controller | 4cd343027a44: Verifying Checksum
2025-11-09 00:16:15.519965 | controller | 4cd343027a44: Download complete
2025-11-09 00:16:16.501620 | controller | 0b4379883f90: Verifying Checksum
2025-11-09 00:16:17.491794 | controller | 0b4379883f90: Download complete
2025-11-09 00:16:17.493592 | controller | 3d9d6d9dfc91: Verifying Checksum
2025-11-09 00:16:18.490315 | controller | 3d9d6d9dfc91: Download complete
2025-11-09 00:16:18.490416 | controller | f4712b586375: Verifying Checksum
2025-11-09 00:16:18.490514 | controller | f4712b586375: Download complete
2025-11-09 00:16:26.896138 | controller | 57d6314bd293: Verifying Checksum
2025-11-09 00:16:26.896234 | controller | 57d6314bd293: Download complete
2025-11-09 00:16:31.092611 | controller | 774036afeb7f: Verifying Checksum
2025-11-09 00:16:31.093303 | controller | 774036afeb7f: Download complete
2025-11-09 00:16:33.684666 | controller | cef8beb99552: Verifying Checksum
2025-11-09 00:16:33.684888 | controller | cef8beb99552: Download complete
2025-11-09 00:16:37.278455 | controller | 54b10f0e1645: Verifying Checksum
2025-11-09 00:16:37.280055 | controller | 54b10f0e1645: Download complete
2025-11-09 00:16:43.987704 | controller | 36e5e20969c7: Verifying Checksum
2025-11-09 00:16:44.754331 | controller | 36e5e20969c7: Download complete
2025-11-09 00:16:44.754389 | controller | f4712b586375: Pull complete
2025-11-09 00:16:44.801848 | controller | d696ed7812f8: Pull complete
2025-11-09 00:16:44.832822 | controller | 29bf28764f14: Pull complete
2025-11-09 00:16:44.911379 | controller | 47d7edb3c2b7: Pull complete
2025-11-09 00:16:44.959493 | controller | 07550ad4f6e4: Pull complete
2025-11-09 00:16:44.985979 | controller | 7e79526e64c5: Pull complete
2025-11-09 00:16:45.048051 | controller | 4fffb32882b7: Pull complete
2025-11-09 00:16:45.108572 | controller | fc7a38b06f4e: Pull complete
2025-11-09 00:16:45.169443 | controller | 6d9d912e060e: Pull complete
2025-11-09 00:16:45.196233 | controller | 5dd542dd28d9: Pull complete
2025-11-09 00:16:45.268472 | controller | 4b19ac3f17a8: Verifying Checksum
2025-11-09 00:16:45.822750 | controller | 4b19ac3f17a8: Download complete
2025-11-09 00:16:45.822833 | controller | 78cf439d9fdb: Verifying Checksum
2025-11-09 00:17:08.833126 | controller | 78cf439d9fdb: Download complete
2025-11-09 00:17:08.833213 | controller | cef8beb99552: Pull complete
2025-11-09 00:17:08.859196 | controller | bd5557e101ad: Pull complete
2025-11-09 00:17:08.881276 | controller | e057c20fabdd: Pull complete
2025-11-09 00:17:08.917171 | controller | d16059cdae2e: Pull complete
2025-11-09 00:17:10.844661 | controller | 431e42d906a3: Pull complete
2025-11-09 00:17:10.919382 | controller | 7a0fd3b1d7c3: Pull complete
2025-11-09 00:17:10.995368 | controller | aacdb6db0c8c: Pull complete
2025-11-09 00:17:11.043963 | controller | 2e7f04ae8c8b: Pull complete
2025-11-09 00:17:11.091809 | controller | 4cd343027a44: Pull complete
2025-11-09 00:17:11.156381 | controller | 0b4379883f90: Pull complete
2025-11-09 00:17:11.184633 | controller | 3d9d6d9dfc91: Pull complete
2025-11-09 00:17:16.872566 | controller | 57d6314bd293: Pull complete
2025-11-09 00:17:40.209987 | controller | 774036afeb7f: Pull complete
2025-11-09 00:18:07.197586 | controller | 54b10f0e1645: Pull complete
2025-11-09 00:18:35.477437 | controller | 78cf439d9fdb: Pull complete
2025-11-09 00:19:04.638701 | controller | 36e5e20969c7: Pull complete
2025-11-09 00:19:32.943321 | controller | 4b19ac3f17a8: Pull complete
2025-11-09 00:19:32.960040 | controller | Digest: sha256:954c4edffb0905df64e23c8611b1c4a62db9af13062f51cad5b658e727238d41
2025-11-09 00:19:32.963893 | controller | Status: Downloaded newer image for quay.io/ansible/default-test-container:1.10.1
2025-11-09 00:19:32.974314 | controller | quay.io/ansible/default-test-container:1.10.1
2025-11-09 00:19:32.977806 | controller | Run command: docker images quay.io/ansible/pypi-test-container:1.0.0 --format '{{json .}}'
2025-11-09 00:19:33.014183 | controller | Run command: docker pull quay.io/ansible/pypi-test-container:1.0.0
2025-11-09 00:19:34.396950 | controller | 1.0.0: Pulling from ansible/pypi-test-container
2025-11-09 00:19:34.397871 | controller | 04a5f4cda3ee: Pulling fs layer
2025-11-09 00:19:34.397962 | controller | ff496a88c8ed: Pulling fs layer
2025-11-09 00:19:34.398046 | controller | 0ce83f459fe7: Pulling fs layer
2025-11-09 00:19:34.398123 | controller | 2e5170e1f099: Pulling fs layer
2025-11-09 00:19:34.398202 | controller | 7641eb41b08c: Pulling fs layer
2025-11-09 00:19:34.398276 | controller | ad15fa9da398: Pulling fs layer
2025-11-09 00:19:34.398365 | controller | 087d91352424: Pulling fs layer
2025-11-09 00:19:34.398442 | controller | 8b92efd6a100: Pulling fs layer
2025-11-09 00:19:34.398722 | controller | 7641eb41b08c: Waiting
2025-11-09 00:19:34.398847 | controller | ad15fa9da398: Waiting
2025-11-09 00:19:34.398933 | controller | 087d91352424: Waiting
2025-11-09 00:19:34.399026 | controller | 8b92efd6a100: Waiting
2025-11-09 00:19:34.399432 | controller | 2e5170e1f099: Waiting
2025-11-09 00:19:34.846383 | controller | ff496a88c8ed: Verifying Checksum
2025-11-09 00:19:34.846526 | controller | ff496a88c8ed: Download complete
2025-11-09 00:19:34.851842 | controller | 0ce83f459fe7: Verifying Checksum
2025-11-09 00:19:34.851975 | controller | 0ce83f459fe7: Download complete
2025-11-09 00:19:35.116349 | controller | 04a5f4cda3ee: Verifying Checksum
2025-11-09 00:19:35.216977 | controller | 04a5f4cda3ee: Download complete
2025-11-09 00:19:35.217048 | controller | 7641eb41b08c: Verifying Checksum
2025-11-09 00:19:35.474424 | controller | 7641eb41b08c: Download complete
2025-11-09 00:19:35.474484 | controller | 2e5170e1f099: Verifying Checksum
2025-11-09 00:19:35.518020 | controller | 2e5170e1f099: Download complete
2025-11-09 00:19:35.518161 | controller | ad15fa9da398: Verifying Checksum
2025-11-09 00:19:35.518256 | controller | ad15fa9da398: Download complete
2025-11-09 00:19:35.723959 | controller | 087d91352424: Verifying Checksum
2025-11-09 00:19:35.724128 | controller | 087d91352424: Download complete
2025-11-09 00:19:35.844222 | controller | 8b92efd6a100: Verifying Checksum
2025-11-09 00:19:35.844346 | controller | 8b92efd6a100: Download complete
2025-11-09 00:19:37.243090 | controller | 04a5f4cda3ee: Pull complete
2025-11-09 00:19:37.277151 | controller | ff496a88c8ed: Pull complete
2025-11-09 00:19:37.305809 | controller | 0ce83f459fe7: Pull complete
2025-11-09 00:19:38.881597 | controller | 2e5170e1f099: Pull complete
2025-11-09 00:19:38.906461 | controller | 7641eb41b08c: Pull complete
2025-11-09 00:19:38.940891 | controller | ad15fa9da398: Pull complete
2025-11-09 00:19:40.190234 | controller | 087d91352424: Pull complete
2025-11-09 00:19:40.212451 | controller | 8b92efd6a100: Pull complete
2025-11-09 00:19:40.233132 | controller | Digest: sha256:71042ab0a14971b5608fe75706de54f367fc31db573e3b3955182037f73cadb6
2025-11-09 00:19:40.237414 | controller | Status: Downloaded newer image for quay.io/ansible/pypi-test-container:1.0.0
2025-11-09 00:19:40.264156 | controller | quay.io/ansible/pypi-test-container:1.0.0
2025-11-09 00:19:40.268832 | controller | Run command: docker run --detach quay.io/ansible/pypi-test-container:1.0.0
2025-11-09 00:19:49.584646 | controller | Run command: docker inspect 082d7b8b462c4075110bd55a49e01cade7cd9b753d43254442517f83276157df
2025-11-09 00:19:49.898543 | controller | Scanning collection root: /home/zuul/.ansible/collections/ansible_collections
2025-11-09 00:19:49.916427 | controller | Including collection: ansible.netcommon (292 files)
2025-11-09 00:19:49.916514 | controller | Including collection: ansible.network (23 files)
2025-11-09 00:19:49.929422 | controller | Including collection: ansible.utils (442 files)
2025-11-09 00:19:49.977344 | controller | Including collection: arista.eos (1022 files)
2025-11-09 00:19:50.028342 | controller | Including collection: cisco.ios (1172 files)
2025-11-09 00:19:50.075960 | controller | Including collection: cisco.iosxr (1043 files)
2025-11-09 00:19:50.164664 | controller | Including collection: cisco.nxos (1684 files)
2025-11-09 00:19:50.237028 | controller | Including collection: junipernetworks.junos (963 files)
2025-11-09 00:19:50.297955 | controller | Creating a payload archive containing 8378 files...
2025-11-09 00:19:52.868181 | controller | Created a 6890019 byte payload archive containing 8378 files in 2 seconds.
2025-11-09 00:19:52.872880 | controller | Assuming Docker is available on localhost.
2025-11-09 00:19:52.873308 | controller | Run command: docker run --detach --volume /sys/fs/cgroup:/sys/fs/cgroup:ro --privileged=false --security-opt seccomp=unconfined --volume /var/run/docker.sock:/var/run/docker.sock quay.io/ansible/default-test-container:1.10.1
2025-11-09 00:19:53.524993 | controller | Run command: docker exec -i acfaefccff12b7297d72344e4ee89f1e39fcdba240e9be2a32d91417951f7ef7 dd of=/root/docker.sh bs=65536
2025-11-09 00:19:53.643888 | controller | Run command: docker exec acfaefccff12b7297d72344e4ee89f1e39fcdba240e9be2a32d91417951f7ef7 /bin/bash /root/docker.sh
2025-11-09 00:19:53.753838 | controller | Run command: docker exec -i acfaefccff12b7297d72344e4ee89f1e39fcdba240e9be2a32d91417951f7ef7 dd of=/root/ansible.tgz bs=65536
2025-11-09 00:19:53.867987 | controller | Run command: docker exec acfaefccff12b7297d72344e4ee89f1e39fcdba240e9be2a32d91417951f7ef7 mkdir /root/ansible
2025-11-09 00:19:53.942830 | controller | Run command: docker exec acfaefccff12b7297d72344e4ee89f1e39fcdba240e9be2a32d91417951f7ef7 tar oxzf /root/ansible.tgz -C /root/ansible
2025-11-09 00:19:55.200144 | controller | Run command: docker exec acfaefccff12b7297d72344e4ee89f1e39fcdba240e9be2a32d91417951f7ef7 /usr/bin/env ANSIBLE_TEST_CONTENT_ROOT=/root/ansible/ansible_collections/ansible/network LC_ALL=en_US.UTF-8 /usr/bin/python3.6 /root/ansible/bin/ansible-test sanity -v --metadata metadata-5uoxib_b.json --truncate 0 --redact --color no --requirements --pypi-endpoint http://172.17.0.2:3141/root/pypi/+simple/
2025-11-09 00:19:56.089108 | controller | Injecting custom PyPI hosts entries: /etc/hosts
2025-11-09 00:19:56.089498 | controller | Injecting custom PyPI config: /root/.pip/pip.conf
2025-11-09 00:19:56.089908 | controller | Injecting custom PyPI config: /root/.pydistutils.cfg
2025-11-09 00:19:56.091844 | controller | Run command: /usr/bin/python3.6 /root/ansible/test/lib/ansible_test/_data/yamlcheck.py
2025-11-09 00:19:56.199899 | controller | Running sanity test 'action-plugin-docs' with Python 3.6
2025-11-09 00:19:56.204083 | controller | Read 0 sanity test ignore line(s) for Ansible 2.9 from: tests/sanity/ignore-2.9.txt
2025-11-09 00:19:56.226515 | controller | No tests applicable.
2025-11-09 00:19:56.226746 | controller | Running sanity test 'ansible-doc' with Python 3.6
2025-11-09 00:19:56.226788 | controller | No tests applicable.
2025-11-09 00:19:56.227060 | controller | Run command: /usr/bin/python2.6 /root/ansible/test/lib/ansible_test/_data/yamlcheck.py
2025-11-09 00:19:56.388138 | controller | Running sanity test 'compile' with Python 2.6
2025-11-09 00:19:56.388490 | controller | No tests applicable.
2025-11-09 00:19:56.390958 | controller | Run command: /usr/bin/python2.7 /root/ansible/test/lib/ansible_test/_data/yamlcheck.py
2025-11-09 00:19:56.764162 | controller | Running sanity test 'compile' with Python 2.7
2025-11-09 00:19:56.934472 | controller | No tests applicable.
2025-11-09 00:19:56.934526 | controller | Run command: /usr/bin/python3.5 /root/ansible/test/lib/ansible_test/_data/yamlcheck.py
2025-11-09 00:19:56.934541 | controller | Running sanity test 'compile' with Python 3.5
2025-11-09 00:19:56.934707 | controller | No tests applicable.
2025-11-09 00:19:56.934733 | controller | Running sanity test 'compile' with Python 3.6
2025-11-09 00:19:56.934937 | controller | No tests applicable.
2025-11-09 00:19:56.935333 | controller | Run command: /usr/bin/python3.7 /root/ansible/test/lib/ansible_test/_data/yamlcheck.py
2025-11-09 00:19:57.056335 | controller | Running sanity test 'compile' with Python 3.7
2025-11-09 00:19:57.059022 | controller | No tests applicable.
2025-11-09 00:19:57.171938 | controller | Run command: /usr/bin/python3.8 /root/ansible/test/lib/ansible_test/_data/yamlcheck.py
2025-11-09 00:19:57.171997 | controller | Running sanity test 'compile' with Python 3.8
2025-11-09 00:19:57.172505 | controller | No tests applicable.
2025-11-09 00:19:57.172744 | controller | Running sanity test 'empty-init' with Python 3.6
2025-11-09 00:19:57.173103 | controller | No tests applicable.
2025-11-09 00:19:57.173293 | controller | Running sanity test 'future-import-boilerplate' with Python 3.6
2025-11-09 00:19:57.173593 | controller | No tests applicable.
2025-11-09 00:19:57.173791 | controller | Running sanity test 'ignores'
2025-11-09 00:19:57.180701 | controller | Run command: /usr/bin/python3.6 -m pip.__main__ install --disable-pip-version-check -r /root/ansible/test/lib/ansible_test/_data/requirements/sanity.txt -c /root/ansible/test/lib/ansible_test/_data/requirements/constraints.txt
2025-11-09 00:19:57.968339 | controller | Ignoring packaging: markers 'python_version < "3.6"' don't match your environment
2025-11-09 00:19:57.971873 | controller | Ignoring coverage: markers 'python_version > "3.7"' don't match your environment
2025-11-09 00:19:57.973406 | controller | Ignoring cryptography: markers 'python_version < "2.7"' don't match your environment
2025-11-09 00:19:57.976191 | controller | Ignoring deepdiff: markers 'python_version < "3"' don't match your environment
2025-11-09 00:19:57.977786 | controller | Ignoring jinja2: markers 'python_version < "2.7"' don't match your environment
2025-11-09 00:19:57.979244 | controller | Ignoring urllib3: markers 'python_version < "2.7"' don't match your environment
2025-11-09 00:19:57.982469 | controller | Ignoring sphinx: markers 'python_version < "2.7"' don't match your environment
2025-11-09 00:19:57.987101 | controller | Ignoring wheel: markers 'python_version < "2.7"' don't match your environment
2025-11-09 00:19:57.988550 | controller | Ignoring yamllint: markers 'python_version < "2.7"' don't match your environment
2025-11-09 00:19:57.993166 | controller | Ignoring paramiko: markers 'python_version < "2.7"' don't match your environment
2025-11-09 00:19:57.995731 | controller | Ignoring pytest: markers 'python_version < "2.7"' don't match your environment
2025-11-09 00:19:57.997350 | controller | Ignoring pytest: markers 'python_version == "2.7"' don't match your environment
2025-11-09 00:19:57.998747 | controller | Ignoring pytest-forked: markers 'python_version < "2.7"' don't match your environment
2025-11-09 00:19:58.003626 | controller | Ignoring requests: markers 'python_version < "2.7"' don't match your environment
2025-11-09 00:19:58.010502 | controller | Ignoring virtualenv: markers 'python_version < "2.7"' don't match your environment
2025-11-09 00:19:58.012077 | controller | Ignoring pathspec: markers 'python_version < "2.7"' don't match your environment
2025-11-09 00:19:58.013424 | controller | Ignoring pyopenssl: markers 'python_version < "2.7"' don't match your environment
2025-11-09 00:19:58.017808 | controller | Ignoring pyyaml: markers 'python_version < "2.7"' don't match your environment
2025-11-09 00:19:58.019169 | controller | Ignoring pycparser: markers 'python_version < "2.7"' don't match your environment
2025-11-09 00:19:58.022490 | controller | Ignoring xmltodict: markers 'python_version < "2.7"' don't match your environment
2025-11-09 00:19:58.023887 | controller | Ignoring lxml: markers 'python_version < "2.7"' don't match your environment
2025-11-09 00:19:58.025317 | controller | Ignoring pyvmomi: markers 'python_version < "2.7"' don't match your environment
2025-11-09 00:19:58.027492 | controller | Ignoring MarkupSafe: markers 'python_version < "3.6"' don't match your environment
2025-11-09 00:19:58.035318 | controller | Ignoring setuptools: markers 'python_version <= "2.7"' don't match your environment
2025-11-09 00:19:58.039330 | controller | Ignoring websocket-client: markers 'python_version < "3"' don't match your environment
2025-11-09 00:19:58.040689 | controller | Ignoring certifi: markers 'python_version < "3"' don't match your environment
2025-11-09 00:19:58.057716 | controller | Looking in indexes: http://172.17.0.2:3141/root/pypi/+simple/
2025-11-09 00:19:58.058717 | controller | Requirement already satisfied: cryptography<3.4 in /usr/local/lib/python3.6/dist-packages (from -c /root/ansible/test/lib/ansible_test/_data/requirements/constraints.txt (line 5)) (2.7)
2025-11-09 00:19:58.086529 | controller | Requirement already satisfied: rstcheck==3.3.1 in /usr/local/lib/python3.6/dist-packages (from -c /root/ansible/test/lib/ansible_test/_data/requirements/constraints.txt (line 12)) (3.3.1)
2025-11-09 00:19:58.091230 | controller | Requirement already satisfied: voluptuous>=0.11.0 in /usr/local/lib/python3.6/dist-packages (from -c /root/ansible/test/lib/ansible_test/_data/requirements/constraints.txt (line 30)) (0.11.7)
2025-11-09 00:19:58.094773 | controller | Requirement already satisfied: cffi!=1.14.4 in /usr/local/lib/python3.6/dist-packages (from -c /root/ansible/test/lib/ansible_test/_data/requirements/constraints.txt (line 49)) (1.13.0)
2025-11-09 00:19:58.097553 | controller | Requirement already satisfied: pylint==2.3.1 in /usr/local/lib/python3.6/dist-packages (from -c /root/ansible/test/lib/ansible_test/_data/requirements/constraints.txt (line 58)) (2.3.1)
2025-11-09 00:19:58.103949 | controller | Requirement already satisfied: jinja2 in /usr/local/lib/python3.6/dist-packages (from -r /root/ansible/test/lib/ansible_test/_data/requirements/sanity.txt (line 2)) (2.10.3)
2025-11-09 00:19:58.107503 | controller | Requirement already satisfied: pycodestyle in /usr/local/lib/python3.6/dist-packages (from -r /root/ansible/test/lib/ansible_test/_data/requirements/sanity.txt (line 3)) (2.5.0)
2025-11-09 00:19:58.113250 | controller | Requirement already satisfied: pyyaml in /usr/local/lib/python3.6/dist-packages (from -r /root/ansible/test/lib/ansible_test/_data/requirements/sanity.txt (line 5)) (5.1.2)
2025-11-09 00:19:58.115305 | controller | Requirement already satisfied: virtualenv in /usr/local/lib/python3.6/dist-packages (from -r /root/ansible/test/lib/ansible_test/_data/requirements/sanity.txt (line 7)) (16.7.5)
2025-11-09 00:19:58.134090 | controller | Requirement already satisfied: yamllint in /usr/local/lib/python3.6/dist-packages (from -r /root/ansible/test/lib/ansible_test/_data/requirements/sanity.txt (line 9)) (1.18.0)
2025-11-09 00:19:58.139971 | controller | Requirement already satisfied: six>=1.4.1 in /usr/local/lib/python3.6/dist-packages (from cryptography<3.4->-c /root/ansible/test/lib/ansible_test/_data/requirements/constraints.txt (line 5)) (1.12.0)
2025-11-09 00:19:58.142170 | controller | Requirement already satisfied: asn1crypto>=0.21.0 in /usr/local/lib/python3.6/dist-packages (from cryptography<3.4->-c /root/ansible/test/lib/ansible_test/_data/requirements/constraints.txt (line 5)) (1.1.0)
2025-11-09 00:19:58.146346 | controller | Requirement already satisfied: docutils>=0.7 in /usr/local/lib/python3.6/dist-packages (from rstcheck==3.3.1->-c /root/ansible/test/lib/ansible_test/_data/requirements/constraints.txt (line 12)) (0.15.2)
2025-11-09 00:19:58.148576 | controller | Requirement already satisfied: pycparser in /usr/local/lib/python3.6/dist-packages (from cffi!=1.14.4->-c /root/ansible/test/lib/ansible_test/_data/requirements/constraints.txt (line 49)) (2.19)
2025-11-09 00:19:58.150511 | controller | Requirement already satisfied: mccabe==0.6.1 in /usr/local/lib/python3.6/dist-packages (from -c /root/ansible/test/lib/ansible_test/_data/requirements/constraints.txt (line 57)) (0.6.1)
2025-11-09 00:19:58.153180 | controller | Collecting astroid==2.3.3 (from -c /root/ansible/test/lib/ansible_test/_data/requirements/constraints.txt (line 54))
2025-11-09 00:20:13.174426 | controller |   WARNING: Retrying (Retry(total=4, connect=None, read=None, redirect=None, status=None)) after connection broken by 'ReadTimeoutError("HTTPConnectionPool(host='172.17.0.2', port=3141): Read timed out. (read timeout=15)",)': /root/pypi/+simple/astroid/
2025-11-09 00:20:28.695418 | controller |   WARNING: Retrying (Retry(total=3, connect=None, read=None, redirect=None, status=None)) after connection broken by 'ReadTimeoutError("HTTPConnectionPool(host='172.17.0.2', port=3141): Read timed out. (read timeout=15)",)': /root/pypi/+simple/astroid/
2025-11-09 00:20:44.710934 | controller |   WARNING: Retrying (Retry(total=2, connect=None, read=None, redirect=None, status=None)) after connection broken by 'ReadTimeoutError("HTTPConnectionPool(host='172.17.0.2', port=3141): Read timed out. (read timeout=15)",)': /root/pypi/+simple/astroid/
2025-11-09 00:21:01.728792 | controller |   WARNING: Retrying (Retry(total=1, connect=None, read=None, redirect=None, status=None)) after connection broken by 'ReadTimeoutError("HTTPConnectionPool(host='172.17.0.2', port=3141): Read timed out. (read timeout=15)",)': /root/pypi/+simple/astroid/
2025-11-09 00:21:20.749652 | controller |   WARNING: Retrying (Retry(total=0, connect=None, read=None, redirect=None, status=None)) after connection broken by 'ReadTimeoutError("HTTPConnectionPool(host='172.17.0.2', port=3141): Read timed out. (read timeout=15)",)': /root/pypi/+simple/astroid/
2025-11-09 00:21:35.767698 | controller |   ERROR: Could not find a version that satisfies the requirement astroid==2.3.3 (from -c /root/ansible/test/lib/ansible_test/_data/requirements/constraints.txt (line 54)) (from versions: none)
2025-11-09 00:21:35.769287 | controller | ERROR: No matching distribution found for astroid==2.3.3 (from -c /root/ansible/test/lib/ansible_test/_data/requirements/constraints.txt (line 54))
2025-11-09 00:21:35.901197 | controller | ERROR: Command "/usr/bin/python3.6 -m pip.__main__ install --disable-pip-version-check -r /root/ansible/test/lib/ansible_test/_data/requirements/sanity.txt -c /root/ansible/test/lib/ansible_test/_data/requirements/constraints.txt" returned exit status 1.
2025-11-09 00:21:35.901437 | controller | Removing custom PyPI config: /root/.pydistutils.cfg
2025-11-09 00:21:35.901632 | controller | Removing custom PyPI config: /root/.pip/pip.conf
2025-11-09 00:21:35.901718 | controller | Removing custom PyPI hosts entries: /etc/hosts
2025-11-09 00:21:35.951212 | controller | Run command: docker exec acfaefccff12b7297d72344e4ee89f1e39fcdba240e9be2a32d91417951f7ef7 tar czf /root/output.tgz --exclude .tmp -C /root/ansible/ansible_collections/ansible/network/tests output
2025-11-09 00:21:36.030721 | controller | Run command: docker exec -i acfaefccff12b7297d72344e4ee89f1e39fcdba240e9be2a32d91417951f7ef7 dd if=/root/output.tgz bs=65536
2025-11-09 00:21:36.105846 | controller | Run command: tar oxzf /tmp/ansible-result-2d0ou5ew.tgz -C /home/zuul/.ansible/collections/ansible_collections/ansible/network/tests
2025-11-09 00:21:36.120844 | controller | Run command: docker rm -f 082d7b8b462c4075110bd55a49e01cade7cd9b753d43254442517f83276157df
2025-11-09 00:21:36.397453 | controller | Run command: docker rm -f acfaefccff12b7297d72344e4ee89f1e39fcdba240e9be2a32d91417951f7ef7
2025-11-09 00:21:37.262357 | controller | ERROR: Command "docker exec acfaefccff12b7297d72344e4ee89f1e39fcdba240e9be2a32d91417951f7ef7 /usr/bin/env ANSIBLE_TEST_CONTENT_ROOT=/root/ansible/ansible_collections/ansible/network LC_ALL=en_US.UTF-8 /usr/bin/python3.6 /root/ansible/bin/ansible-test sanity -v --metadata metadata-5uoxib_b.json --truncate 0 --redact --color no --requirements --pypi-endpoint http://172.17.0.2:3141/root/pypi/+simple/" returned exit status 1.
2025-11-09 00:21:37.603494 | controller | ERROR
2025-11-09 00:21:37.603876 | controller | {
2025-11-09 00:21:37.604020 | controller |   "delta": "0:05:47.225908",
2025-11-09 00:21:37.604103 | controller |   "end": "2025-11-09 00:21:37.325476",
2025-11-09 00:21:37.604146 | controller |   "msg": "non-zero return code",
2025-11-09 00:21:37.604184 | controller |   "rc": 1,
2025-11-09 00:21:37.604221 | controller |   "start": "2025-11-09 00:15:50.099568"
2025-11-09 00:21:37.604257 | controller | }
failure
2025-11-09 00:21:37.615504 | 
2025-11-09 00:21:37.615617 | PLAY RECAP
2025-11-09 00:21:37.615701 | controller | ok: 21 changed: 6 unreachable: 0 failed: 1 skipped: 20 rescued: 0 ignored: 1
2025-11-09 00:21:37.615790 | 
2025-11-09 00:21:37.768605 | RUN END RESULT_NORMAL: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/run.yaml@master]
2025-11-09 00:21:37.770012 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master]
2025-11-09 00:21:38.427157 | 
2025-11-09 00:21:38.427315 | PLAY [all]
2025-11-09 00:21:38.449133 | 
2025-11-09 00:21:38.449281 | TASK [fetch-subunit-output : Find stestr or testr executable]
2025-11-09 00:21:41.470351 | controller | changed: non-zero return code
2025-11-09 00:21:41.478664 | 
2025-11-09 00:21:41.478834 | TASK [fetch-subunit-output : Get the list of directories with subunit files]
2025-11-09 00:21:41.505060 | controller | skipping: Conditional result was False
2025-11-09 00:21:41.514194 | 
2025-11-09 00:21:41.514339 | LOOP [fetch-subunit-output : Find any inflight partial subunit files]
2025-11-09 00:21:41.548557 | 
2025-11-09 00:21:41.548720 | LOOP [fetch-subunit-output : Copy any inflight subunit files]
2025-11-09 00:21:41.571019 | 
2025-11-09 00:21:41.571208 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream]
2025-11-09 00:21:41.585155 | controller | skipping: Conditional result was False
2025-11-09 00:21:41.591819 | 
2025-11-09 00:21:41.591887 | LOOP [fetch-subunit-output : Generate subunit file]
2025-11-09 00:21:41.611647 | 
2025-11-09 00:21:41.611793 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory]
2025-11-09 00:21:41.626233 | controller | skipping: Conditional result was False
2025-11-09 00:21:41.633969 | 
2025-11-09 00:21:41.634036 | TASK [fetch-subunit-output : Remove the temporary file]
2025-11-09 00:21:41.647660 | controller | skipping: Conditional result was False
2025-11-09 00:21:41.653994 | 
2025-11-09 00:21:41.654061 | TASK [fetch-subunit-output : Process and fetch subunit results]
2025-11-09 00:21:41.667837 | controller | skipping: Conditional result was False
2025-11-09 00:21:41.695090 | 
2025-11-09 00:21:41.695171 | PLAY RECAP
2025-11-09 00:21:41.695212 | controller | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0
2025-11-09 00:21:41.695232 | 
2025-11-09 00:21:41.805063 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master]
2025-11-09 00:21:41.807054 | POST-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/post.yaml@master]
2025-11-09 00:21:42.429371 | 
2025-11-09 00:21:42.429509 | PLAY [all:!appliance*]
2025-11-09 00:21:42.452701 | 
2025-11-09 00:21:42.452853 | TASK [unregister the node]
2025-11-09 00:21:42.808555 | controller | skipping: Conditional result was False
2025-11-09 00:21:42.821383 | 
2025-11-09 00:21:42.821542 | TASK [include_role : fetch-output]
2025-11-09 00:21:42.876316 | controller | ok
2025-11-09 00:21:42.899368 | 
2025-11-09 00:21:42.899473 | TASK [fetch-output : Set log path for multiple nodes]
2025-11-09 00:21:42.966092 | controller | skipping: Conditional result was False
2025-11-09 00:21:42.972895 | 
2025-11-09 00:21:42.972967 | TASK [fetch-output : Set log path for single node]
2025-11-09 00:21:43.017946 | controller | ok
2025-11-09 00:21:43.026867 | 
2025-11-09 00:21:43.026980 | LOOP [fetch-output : Ensure local output dirs]
2025-11-09 00:21:43.476258 | controller -> localhost | ok: "/var/lib/zuul/builds/3b265a43d66b495a9e2ed1155bec8177/work/logs"
2025-11-09 00:21:43.715040 | controller -> localhost | changed: "/var/lib/zuul/builds/3b265a43d66b495a9e2ed1155bec8177/work/artifacts"
2025-11-09 00:21:43.955070 | controller -> localhost | changed: "/var/lib/zuul/builds/3b265a43d66b495a9e2ed1155bec8177/work/docs"
2025-11-09 00:21:43.980307 | 
2025-11-09 00:21:43.980527 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-11-09 00:21:46.350394 | controller | changed:
2025-11-09 00:21:46.350800 | controller | .d..t...... ./
2025-11-09 00:21:46.350871 | controller | cd+++++++++ controller/
2025-11-09 00:21:46.350939 | controller | changed: All items complete
2025-11-09 00:21:46.350983 | 
2025-11-09 00:21:48.449579 | controller | changed: .d..t...... ./
2025-11-09 00:21:50.509853 | controller | changed: .d..t...... ./
2025-11-09 00:21:50.549641 | 
2025-11-09 00:21:50.550186 | TASK [include_role : fetch-output-openshift]
2025-11-09 00:21:50.577865 | controller | skipping: Conditional result was False
2025-11-09 00:21:50.591806 | 
2025-11-09 00:21:50.591947 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2025-11-09 00:21:50.641833 | controller | skipping: Conditional result was False
2025-11-09 00:21:50.654245 | controller | skipping: Conditional result was False
2025-11-09 00:21:50.707135 | 
2025-11-09 00:21:50.707321 | PLAY [localhost]
2025-11-09 00:21:50.733929 | 
2025-11-09 00:21:50.734122 | TASK [Run Zuul manifest role]
2025-11-09 00:21:50.756690 | localhost | ok
2025-11-09 00:21:50.774097 | 
2025-11-09 00:21:50.774241 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2025-11-09 00:21:51.241348 | localhost | changed
2025-11-09 00:21:51.246439 | 
2025-11-09 00:21:51.246513 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2025-11-09 00:21:51.275263 | localhost | ok
2025-11-09 00:21:51.284664 | 
2025-11-09 00:21:51.284785 | TASK [Set zuul-log-path fact]
2025-11-09 00:21:51.338684 | localhost | ok
2025-11-09 00:21:51.351782 | 
2025-11-09 00:21:51.351868 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-11-09 00:21:51.390153 | localhost | ok
2025-11-09 00:21:51.398031 | 
2025-11-09 00:21:51.398094 | LOOP [Run upload-logs-swift role]
2025-11-09 00:21:51.432983 | localhost | Output suppressed because no_log was given
2025-11-09 00:21:51.474045 | 
2025-11-09 00:21:51.474166 | TASK [Set zuul-log-path fact]
2025-11-09 00:21:51.498467 | localhost | skipping: Conditional result was False
2025-11-09 00:21:51.503613 | 
2025-11-09 00:21:51.503681 | TASK [upload-logs-swift : Ensure logs are readable before uploading]
2025-11-09 00:21:51.938164 | localhost -> localhost | ok: Runtime: 0:00:00.009014
2025-11-09 00:21:51.943950 | 
2025-11-09 00:21:51.944046 | TASK [upload-logs-swift : Upload logs to swift]