2025-11-22 00:21:47.517801 | Job console starting...
2025-11-22 00:21:47.527585 | Updating repositories
2025-11-22 00:21:47.685281 | Preparing job workspace
2025-11-22 00:21:53.282975 | Running Ansible setup...
2025-11-22 00:22:00.743425 | PRE-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master]
2025-11-22 00:22:01.353850 | 
2025-11-22 00:22:01.353965 | PLAY [localhost]
2025-11-22 00:22:01.361945 | 
2025-11-22 00:22:01.362017 | TASK [Gathering Facts]
2025-11-22 00:22:02.327504 | localhost | ok
2025-11-22 00:22:02.342796 | 
2025-11-22 00:22:02.342922 | TASK [Setup log path fact]
2025-11-22 00:22:02.371991 | localhost | ok
2025-11-22 00:22:02.384895 | 
2025-11-22 00:22:02.384968 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-11-22 00:22:02.413382 | localhost | ok
2025-11-22 00:22:02.421046 | 
2025-11-22 00:22:02.421113 | TASK [emit-job-header : Print job information]
2025-11-22 00:22:02.458837 | # Job Information
2025-11-22 00:22:02.458975 | Ansible Version: 2.15.12
2025-11-22 00:22:02.459006 | Job: ansible-test-sanity-docker-stable-2.9
2025-11-22 00:22:02.459026 | Pipeline: periodic
2025-11-22 00:22:02.459045 | Executor: ze02.softwarefactory-project.io
2025-11-22 00:22:02.459062 | Triggered by: https://github.com/ansible-collections/ansible.network
2025-11-22 00:22:02.459085 | Log URL (when completed): https://ansible.softwarefactory-project.io/logs/715/ansible/7157cc27dcfa441eb0034291280b332e/
2025-11-22 00:22:02.459104 | Event ID: c15ec521bc464be4b3ce046f0e5676a7
2025-11-22 00:22:02.462863 | 
2025-11-22 00:22:02.462926 | LOOP [emit-job-header : Print node information]
2025-11-22 00:22:02.561550 | localhost | ok:
2025-11-22 00:22:02.561741 | localhost | # Node Information
2025-11-22 00:22:02.561771 | localhost | Inventory Hostname: controller
2025-11-22 00:22:02.561791 | localhost | Hostname: np0005531625
2025-11-22 00:22:02.561811 | localhost | Username: zuul
2025-11-22 00:22:02.561833 | localhost | Distro: Fedora 37
2025-11-22 00:22:02.561851 | localhost | Provider: ansible-vexxhost-ams1
2025-11-22 00:22:02.561868 | localhost | Region: ams1
2025-11-22 00:22:02.561885 | localhost | Label: ansible-fedora-37-1vcpu
2025-11-22 00:22:02.561902 | localhost | Product Name: OpenStack Nova
2025-11-22 00:22:02.561918 | localhost | Interface IP: 38.129.16.223
2025-11-22 00:22:02.576068 | 
2025-11-22 00:22:02.576180 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2025-11-22 00:22:02.987098 | localhost -> localhost | changed
2025-11-22 00:22:02.992745 | 
2025-11-22 00:22:02.992848 | TASK [log-inventory : Copy ansible inventory to logs dir]
2025-11-22 00:22:03.901152 | localhost -> localhost | changed
2025-11-22 00:22:03.946020 | 
2025-11-22 00:22:03.946147 | PLAY [all:!appliance*]
2025-11-22 00:22:03.970864 | 
2025-11-22 00:22:03.971044 | TASK [include_role : start-zuul-console]
2025-11-22 00:22:04.001488 | controller | ok
2025-11-22 00:22:04.027015 | 
2025-11-22 00:22:04.027160 | TASK [start-zuul-console : Start zuul_console daemon.]
2025-11-22 00:22:05.012758 | controller | ok
2025-11-22 00:22:05.028130 | 
2025-11-22 00:22:05.028253 | TASK [use-our-mirror : Retrieve the IP address]
2025-11-22 00:22:06.851391 | controller | ok: Status code was 404 and not [200]: HTTP Error 404: Not Found
2025-11-22 00:22:06.860608 | 
2025-11-22 00:22:06.860757 | TASK [use-our-mirror : Ensure the registry service is running on the mirror]
2025-11-22 00:22:07.226071 | controller | skipping: Conditional result was False
2025-11-22 00:22:07.235059 | 
2025-11-22 00:22:07.235340 | TASK [use-our-mirror : Write the IP in /etc/hosts]
2025-11-22 00:22:07.261208 | controller | skipping: Conditional result was False
2025-11-22 00:22:07.271090 | 
2025-11-22 00:22:07.271229 | TASK [use-our-mirror : Create the podman configuration directory]
2025-11-22 00:22:07.296996 | controller | skipping: Conditional result was False
2025-11-22 00:22:07.305901 | 
2025-11-22 00:22:07.306028 | TASK [use-our-mirror : Copy the podman configuration]
2025-11-22 00:22:07.330728 | controller | skipping: Conditional result was False
2025-11-22 00:22:07.340385 | 
2025-11-22 00:22:07.340541 | TASK [use-our-mirror : Copy the Yum configuration (main)]
2025-11-22 00:22:07.366432 | controller | skipping: Conditional result was False
2025-11-22 00:22:07.376030 | 
2025-11-22 00:22:07.376179 | TASK [use-our-mirror : Copy the Yum configuration (update)]
2025-11-22 00:22:07.412752 | controller | skipping: Conditional result was False
2025-11-22 00:22:07.427477 | 
2025-11-22 00:22:07.427618 | TASK [Disable Fedora Modular]
2025-11-22 00:22:08.476645 | controller | changed
2025-11-22 00:22:08.485899 | 
2025-11-22 00:22:08.486022 | TASK [Enable EPEL]
2025-11-22 00:22:08.511904 | controller | skipping: Conditional result was False
2025-11-22 00:22:08.520867 | 
2025-11-22 00:22:08.521122 | TASK [Register the RHEL node]
2025-11-22 00:22:08.940651 | 
2025-11-22 00:22:08.940874 | TASK [Show the subscription-manager status]
2025-11-22 00:22:09.327934 | controller | skipping: Conditional result was False
2025-11-22 00:22:09.338585 | 
2025-11-22 00:22:09.338798 | TASK [Enable EPEL on RHEL]
2025-11-22 00:22:09.717151 | controller | skipping: Conditional result was False
2025-11-22 00:22:09.724440 | 
2025-11-22 00:22:09.724575 | TASK [Install git and tox]
2025-11-22 00:23:54.918812 | controller | changed
2025-11-22 00:23:54.930380 | 
2025-11-22 00:23:54.930568 | TASK [include_role : prepare-workspace]
2025-11-22 00:23:54.966586 | controller | ok
2025-11-22 00:23:55.000086 | 
2025-11-22 00:23:55.000265 | TASK [prepare-workspace : Start zuul_console daemon.]
2025-11-22 00:23:55.791937 | controller | ok
2025-11-22 00:23:55.802660 | 
2025-11-22 00:23:55.802787 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2025-11-22 00:24:14.993598 | controller | Output suppressed because no_log was given
2025-11-22 00:24:15.003228 | 
2025-11-22 00:24:15.003291 | TASK [include_role : prepare-workspace-openshift]
2025-11-22 00:24:15.032347 | controller | skipping: Conditional result was False
2025-11-22 00:24:15.083655 | 
2025-11-22 00:24:15.083819 | PLAY [all:!appliance]
2025-11-22 00:24:15.117744 | 
2025-11-22 00:24:15.118014 | TASK [Run add-build-sshkey role (RSA)]
2025-11-22 00:24:15.153033 | controller | ok
2025-11-22 00:24:15.177740 | 
2025-11-22 00:24:15.178241 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2025-11-22 00:24:15.451454 | controller -> localhost | ok
2025-11-22 00:24:15.465608 | 
2025-11-22 00:24:15.465799 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2025-11-22 00:24:15.498247 | controller | ok
2025-11-22 00:24:15.512984 | controller | included: /var/lib/zuul/builds/7157cc27dcfa441eb0034291280b332e/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2025-11-22 00:24:15.518860 | 
2025-11-22 00:24:15.518921 | TASK [add-build-sshkey : Create Temp SSH key]
2025-11-22 00:24:16.149233 | controller -> localhost | Generating public/private rsa key pair.
2025-11-22 00:24:16.149557 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/7157cc27dcfa441eb0034291280b332e/work/7157cc27dcfa441eb0034291280b332e_id_rsa.
2025-11-22 00:24:16.149619 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/7157cc27dcfa441eb0034291280b332e/work/7157cc27dcfa441eb0034291280b332e_id_rsa.pub.
2025-11-22 00:24:16.149747 | controller -> localhost | The key fingerprint is:
2025-11-22 00:24:16.149811 | controller -> localhost | SHA256:kwQwsgG0V0wazpfLZG2nHklxEJ2pJZpO6sT0v3S52Bg zuul-build-sshkey
2025-11-22 00:24:16.149856 | controller -> localhost | The key's randomart image is:
2025-11-22 00:24:16.149897 | controller -> localhost | +---[RSA 2048]----+
2025-11-22 00:24:16.149936 | controller -> localhost | |ooo.=+.++.o      |
2025-11-22 00:24:16.149975 | controller -> localhost | |  +++oooo=       |
2025-11-22 00:24:16.150014 | controller -> localhost | | ..= =o+=.       |
2025-11-22 00:24:16.150052 | controller -> localhost | |  ..=++o+.       |
2025-11-22 00:24:16.150109 | controller -> localhost | |  o =o +S        |
2025-11-22 00:24:16.150188 | controller -> localhost | |   + o. ...      |
2025-11-22 00:24:16.150352 | controller -> localhost | |  o   .E o       |
2025-11-22 00:24:16.150413 | controller -> localhost | |   .  ..* .      |
2025-11-22 00:24:16.150457 | controller -> localhost | |       +.o       |
2025-11-22 00:24:16.150503 | controller -> localhost | +----[SHA256]-----+
2025-11-22 00:24:16.150597 | controller -> localhost | ok: Runtime: 0:00:00.123941
2025-11-22 00:24:16.169291 | 
2025-11-22 00:24:16.169470 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2025-11-22 00:24:16.197141 | controller | ok
2025-11-22 00:24:16.214288 | controller | included: /var/lib/zuul/builds/7157cc27dcfa441eb0034291280b332e/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2025-11-22 00:24:16.230060 | 
2025-11-22 00:24:16.230219 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2025-11-22 00:24:16.257092 | controller | skipping: Conditional result was False
2025-11-22 00:24:16.268061 | 
2025-11-22 00:24:16.268208 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2025-11-22 00:24:17.292631 | controller | changed
2025-11-22 00:24:17.307523 | 
2025-11-22 00:24:17.307783 | TASK [add-build-sshkey : Make sure user has a .ssh]
2025-11-22 00:24:17.927341 | controller | ok
2025-11-22 00:24:17.946731 | 
2025-11-22 00:24:17.946965 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2025-11-22 00:24:20.877015 | controller | changed
2025-11-22 00:24:20.885650 | 
2025-11-22 00:24:20.885847 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2025-11-22 00:24:23.800817 | controller | changed
2025-11-22 00:24:23.814106 | 
2025-11-22 00:24:23.814243 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2025-11-22 00:24:23.851803 | controller | skipping: Conditional result was False
2025-11-22 00:24:23.909407 | 
2025-11-22 00:24:23.909547 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2025-11-22 00:24:24.272298 | controller -> localhost | changed
2025-11-22 00:24:24.285591 | 
2025-11-22 00:24:24.285763 | TASK [add-build-sshkey : Add back temp key]
2025-11-22 00:24:24.605226 | controller -> localhost | Identity added: /var/lib/zuul/builds/7157cc27dcfa441eb0034291280b332e/work/7157cc27dcfa441eb0034291280b332e_id_rsa (zuul-build-sshkey)
2025-11-22 00:24:24.605580 | controller -> localhost | ok: Runtime: 0:00:00.015420
2025-11-22 00:24:24.613541 | 
2025-11-22 00:24:24.613610 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2025-11-22 00:24:25.480639 | controller | ok
2025-11-22 00:24:25.486452 | 
2025-11-22 00:24:25.486533 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2025-11-22 00:24:25.513475 | controller | skipping: Conditional result was False
2025-11-22 00:24:25.530553 | 
2025-11-22 00:24:25.530738 | TASK [Run add-build-sshkey role (ECDSA)]
2025-11-22 00:24:25.560899 | controller | ok
2025-11-22 00:24:25.578745 | 
2025-11-22 00:24:25.578844 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2025-11-22 00:24:25.827220 | controller -> localhost | ok
2025-11-22 00:24:25.834363 | 
2025-11-22 00:24:25.834436 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2025-11-22 00:24:25.875327 | controller | ok
2025-11-22 00:24:25.887312 | controller | included: /var/lib/zuul/builds/7157cc27dcfa441eb0034291280b332e/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2025-11-22 00:24:25.893845 | 
2025-11-22 00:24:25.893917 | TASK [add-build-sshkey : Create Temp SSH key]
2025-11-22 00:24:26.299064 | controller -> localhost | Generating public/private ecdsa key pair.
2025-11-22 00:24:26.299250 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/7157cc27dcfa441eb0034291280b332e/work/7157cc27dcfa441eb0034291280b332e_id_ecdsa.
2025-11-22 00:24:26.299288 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/7157cc27dcfa441eb0034291280b332e/work/7157cc27dcfa441eb0034291280b332e_id_ecdsa.pub.
2025-11-22 00:24:26.299330 | controller -> localhost | The key fingerprint is:
2025-11-22 00:24:26.299353 | controller -> localhost | SHA256:PBgE2bwzjaR27aUHqo86PsPruK33G14SP2KerApNrIM zuul-build-sshkey
2025-11-22 00:24:26.299373 | controller -> localhost | The key's randomart image is:
2025-11-22 00:24:26.299391 | controller -> localhost | +---[ECDSA 521]---+
2025-11-22 00:24:26.299409 | controller -> localhost | |    .=.          |
2025-11-22 00:24:26.299426 | controller -> localhost | |    ..+          |
2025-11-22 00:24:26.299443 | controller -> localhost | |     o.=         |
2025-11-22 00:24:26.299460 | controller -> localhost | | .  o *++ .      |
2025-11-22 00:24:26.299476 | controller -> localhost | |  o....=S+       |
2025-11-22 00:24:26.299493 | controller -> localhost | |.+   o. o..      |
2025-11-22 00:24:26.299510 | controller -> localhost | |E.. =.+  .       |
2025-11-22 00:24:26.299526 | controller -> localhost | |.+*=.B .         |
2025-11-22 00:24:26.299543 | controller -> localhost | |=BBOOo.          |
2025-11-22 00:24:26.299560 | controller -> localhost | +----[SHA256]-----+
2025-11-22 00:24:26.299606 | controller -> localhost | ok: Runtime: 0:00:00.015492
2025-11-22 00:24:26.309021 | 
2025-11-22 00:24:26.309103 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2025-11-22 00:24:26.339881 | controller | ok
2025-11-22 00:24:26.347933 | controller | included: /var/lib/zuul/builds/7157cc27dcfa441eb0034291280b332e/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2025-11-22 00:24:26.357677 | 
2025-11-22 00:24:26.357774 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2025-11-22 00:24:26.382658 | controller | skipping: Conditional result was False
2025-11-22 00:24:26.390211 | 
2025-11-22 00:24:26.390281 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2025-11-22 00:24:27.230111 | controller | changed
2025-11-22 00:24:27.236757 | 
2025-11-22 00:24:27.236851 | TASK [add-build-sshkey : Make sure user has a .ssh]
2025-11-22 00:24:27.837182 | controller | ok
2025-11-22 00:24:27.843084 | 
2025-11-22 00:24:27.843153 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2025-11-22 00:24:31.063985 | controller | changed
2025-11-22 00:24:31.078434 | 
2025-11-22 00:24:31.078749 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2025-11-22 00:24:34.048614 | controller | changed
2025-11-22 00:24:34.064428 | 
2025-11-22 00:24:34.064642 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2025-11-22 00:24:34.093575 | controller | skipping: Conditional result was False
2025-11-22 00:24:34.111830 | 
2025-11-22 00:24:34.112039 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2025-11-22 00:24:34.366488 | controller -> localhost | changed
2025-11-22 00:24:34.379670 | 
2025-11-22 00:24:34.379792 | TASK [add-build-sshkey : Add back temp key]
2025-11-22 00:24:34.651191 | controller -> localhost | Identity added: /var/lib/zuul/builds/7157cc27dcfa441eb0034291280b332e/work/7157cc27dcfa441eb0034291280b332e_id_ecdsa (zuul-build-sshkey)
2025-11-22 00:24:34.651443 | controller -> localhost | ok: Runtime: 0:00:00.013304
2025-11-22 00:24:34.658677 | 
2025-11-22 00:24:34.658778 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2025-11-22 00:24:35.323855 | controller | ok
2025-11-22 00:24:35.335777 | 
2025-11-22 00:24:35.335948 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2025-11-22 00:24:35.362523 | controller | skipping: Conditional result was False
2025-11-22 00:24:35.382914 | 
2025-11-22 00:24:35.383084 | TASK [include_role : remove-zuul-sshkey]
2025-11-22 00:24:35.400816 | controller | skipping: Conditional result was False
2025-11-22 00:24:35.409093 | 
2025-11-22 00:24:35.409236 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2025-11-22 00:24:35.925530 | controller | ok: "logs"
2025-11-22 00:24:35.926845 | controller | ok: All items complete
2025-11-22 00:24:35.927017 | 
2025-11-22 00:24:36.419979 | controller | ok: "artifacts"
2025-11-22 00:24:36.929580 | controller | ok: "docs"
2025-11-22 00:24:36.955651 | 
2025-11-22 00:24:36.956007 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2025-11-22 00:24:37.521234 | controller | changed: "logs"
2025-11-22 00:24:38.020543 | controller | changed: "artifacts"
2025-11-22 00:24:38.511937 | controller | changed: "docs"
2025-11-22 00:24:38.594671 | 
2025-11-22 00:24:38.594814 | PLAY RECAP
2025-11-22 00:24:38.594874 | controller | ok: 30 changed: 16 unreachable: 0 failed: 0 skipped: 18 rescued: 0 ignored: 0
2025-11-22 00:24:38.594912 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-11-22 00:24:38.594938 | 
2025-11-22 00:24:38.744646 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master]
2025-11-22 00:24:38.745595 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master]
2025-11-22 00:24:39.397860 | 
2025-11-22 00:24:39.398009 | PLAY [all]
2025-11-22 00:24:39.422177 | 
2025-11-22 00:24:39.422329 | TASK [Install binary dependencies]
2025-11-22 00:24:39.504005 | controller | ok
2025-11-22 00:24:39.524628 | 
2025-11-22 00:24:39.524759 | TASK [bindep : Include find tasks]
2025-11-22 00:24:39.554638 | controller | ok
2025-11-22 00:24:39.563138 | controller | included: /var/lib/zuul/builds/7157cc27dcfa441eb0034291280b332e/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find.yaml
2025-11-22 00:24:39.570861 | 
2025-11-22 00:24:39.570977 | TASK [bindep : Look for bindep.txt]
2025-11-22 00:24:40.499429 | controller | ok
2025-11-22 00:24:40.515217 | 
2025-11-22 00:24:40.515436 | TASK [bindep : Define bindep_file fact]
2025-11-22 00:24:40.543561 | controller | skipping: Conditional result was False
2025-11-22 00:24:40.562285 | 
2025-11-22 00:24:40.563243 | TASK [bindep : Look for other-requirements.txt]
2025-11-22 00:24:41.166964 | controller | ok
2025-11-22 00:24:41.180349 | 
2025-11-22 00:24:41.180539 | TASK [bindep : Define bindep_file fact]
2025-11-22 00:24:41.218113 | controller | skipping: Conditional result was False
2025-11-22 00:24:41.231850 | 
2025-11-22 00:24:41.232022 | TASK [bindep : Look for bindep fallback file]
2025-11-22 00:24:41.269902 | controller | skipping: Conditional result was False
2025-11-22 00:24:41.283998 | 
2025-11-22 00:24:41.284150 | TASK [bindep : Define bindep_file fact]
2025-11-22 00:24:41.321659 | controller | skipping: Conditional result was False
2025-11-22 00:24:41.336736 | 
2025-11-22 00:24:41.336942 | TASK [bindep : Include bindep tasks]
2025-11-22 00:24:41.374585 | controller | skipping: Conditional result was False
2025-11-22 00:24:41.387408 | 
2025-11-22 00:24:41.387542 | TASK [bindep : Include install tasks]
2025-11-22 00:24:41.423468 | controller | skipping: Conditional result was False
2025-11-22 00:24:41.432621 | 
2025-11-22 00:24:41.432742 | LOOP [bindep : Include package tasks]
2025-11-22 00:24:41.517128 | 
2025-11-22 00:24:41.517358 | TASK [Run test-setup role]
2025-11-22 00:24:41.541997 | controller | ok
2025-11-22 00:24:41.572333 | 
2025-11-22 00:24:41.572488 | TASK [test-setup : Check if project's tools/test-setup.sh exists]
2025-11-22 00:24:42.245417 | controller | ok
2025-11-22 00:24:42.253243 | 
2025-11-22 00:24:42.253377 | TASK [test-setup : Run tools/test-setup.sh]
2025-11-22 00:24:42.610094 | controller | skipping: Conditional result was False
2025-11-22 00:24:42.637213 | 
2025-11-22 00:24:42.637316 | PLAY RECAP
2025-11-22 00:24:42.637358 | controller | ok: 4 changed: 0 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0
2025-11-22 00:24:42.637378 | 
2025-11-22 00:24:42.748799 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master]
2025-11-22 00:24:42.749815 | PRE-RUN START: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master]
2025-11-22 00:24:43.359049 | 
2025-11-22 00:24:43.359169 | PLAY [controller]
2025-11-22 00:24:43.378620 | 
2025-11-22 00:24:43.378727 | TASK [Create the /root directory]
2025-11-22 00:24:44.349294 | controller | ok
2025-11-22 00:24:44.355404 | 
2025-11-22 00:24:44.355480 | TASK [Install glibc-langpack-en]
2025-11-22 00:24:51.242686 | controller | ok: Nothing to do
2025-11-22 00:24:51.251969 | 
2025-11-22 00:24:51.252067 | TASK [Ensure controller directory exists]
2025-11-22 00:24:52.041040 | controller | changed
2025-11-22 00:24:52.050391 | 
2025-11-22 00:24:52.050575 | TASK [Install container runtime]
2025-11-22 00:24:52.114503 | controller | ok
2025-11-22 00:24:52.166588 | 
2025-11-22 00:24:52.166769 | LOOP [ensure-docker : Gather variables for each operating system]
2025-11-22 00:24:52.199293 | controller | ok: "/var/lib/zuul/builds/7157cc27dcfa441eb0034291280b332e/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/vars/fedora.yaml"
2025-11-22 00:24:52.207234 | 
2025-11-22 00:24:52.207331 | TASK [ensure-docker : Sanity check for distro selection]
2025-11-22 00:24:52.222410 | controller | skipping: Conditional result was False
2025-11-22 00:24:52.229217 | 
2025-11-22 00:24:52.229318 | TASK [ensure-docker : Sanity check for distro install]
2025-11-22 00:24:52.254230 | controller | skipping: Conditional result was False
2025-11-22 00:24:52.263436 | 
2025-11-22 00:24:52.263553 | TASK [ensure-docker : Sanity check for upstream install]
2025-11-22 00:24:52.288469 | controller | skipping: Conditional result was False
2025-11-22 00:24:52.298493 | 
2025-11-22 00:24:52.298630 | TASK [ensure-docker : Sanity check for upstream install mirrors]
2025-11-22 00:24:52.323997 | controller | skipping: Conditional result was False
2025-11-22 00:24:52.335481 | 
2025-11-22 00:24:52.335583 | TASK [ensure-docker : Include tasks for docker install]
2025-11-22 00:24:52.359788 | controller | ok
2025-11-22 00:24:52.399611 | controller | included: /var/lib/zuul/builds/7157cc27dcfa441eb0034291280b332e/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/tasks/docker-upstream.yaml
2025-11-22 00:24:52.419036 | 
2025-11-22 00:24:52.419213 | TASK [ensure-docker : Not Implemented]
2025-11-22 00:24:52.444386 | controller | skipping: Conditional result was False
2025-11-22 00:24:52.453872 | 
2025-11-22 00:24:52.454001 | TASK [Set up docker mirrors]
2025-11-22 00:24:52.478206 | controller | ok
2025-11-22 00:24:52.512950 | 
2025-11-22 00:24:52.513090 | TASK [use-docker-mirror : Set docker_mirror fact from mirror_info]
2025-11-22 00:24:52.547639 | controller | skipping: Conditional result was False
2025-11-22 00:24:52.555289 | 
2025-11-22 00:24:52.555638 | TASK [use-docker-mirror : Set docker_mirror fact from zuul_site_mirror_info]
2025-11-22 00:24:52.580794 | controller | skipping: Conditional result was False
2025-11-22 00:24:52.588000 | 
2025-11-22 00:24:52.588113 | TASK [use-docker-mirror : Set docker_mirror fact]
2025-11-22 00:24:52.623097 | controller | skipping: Conditional result was False
2025-11-22 00:24:52.630205 | 
2025-11-22 00:24:52.630308 | TASK [use-docker-mirror : Set up docker mirrors]
2025-11-22 00:24:52.655284 | controller | skipping: Conditional result was False
2025-11-22 00:24:52.704027 | 
2025-11-22 00:24:52.704150 | TASK [Add all repositories]
2025-11-22 00:24:52.725922 | controller | ok
2025-11-22 00:24:52.751613 | 
2025-11-22 00:24:52.751786 | LOOP [ensure-package-repositories : Install repositories]
2025-11-22 00:24:52.787207 | controller | ok: "/var/lib/zuul/builds/7157cc27dcfa441eb0034291280b332e/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-package-repositories/tasks/RedHat.yaml"
2025-11-22 00:24:52.787629 | controller | ok: All items complete
2025-11-22 00:24:52.787772 | 
2025-11-22 00:24:52.808376 | controller | included: /var/lib/zuul/builds/7157cc27dcfa441eb0034291280b332e/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-package-repositories/tasks/RedHat.yaml
2025-11-22 00:24:52.816689 | 
2025-11-22 00:24:52.816840 | TASK [ensure-package-repositories : Create GPG key temp directory]
2025-11-22 00:24:53.720617 | controller | changed
2025-11-22 00:24:53.736218 | 
2025-11-22 00:24:53.736992 | LOOP [ensure-package-repositories : Upload GPG keys]
2025-11-22 00:24:56.838000 | controller | changed:
2025-11-22 00:24:56.838189 | controller | {
2025-11-22 00:24:56.838218 | 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-22 00:24:56.838242 | controller | }
2025-11-22 00:24:56.846857 | 
2025-11-22 00:24:56.846993 | LOOP [ensure-package-repositories : Import GPG keys]
2025-11-22 00:24:57.995239 | controller | changed:
2025-11-22 00:24:57.995466 | controller | {
2025-11-22 00:24:57.995519 | 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-22 00:24:57.995562 | controller | }
2025-11-22 00:24:57.995791 | controller | changed: All items complete
2025-11-22 00:24:57.995840 | 
2025-11-22 00:24:58.014652 | 
2025-11-22 00:24:58.014811 | TASK [ensure-package-repositories : Remove GPG temp directory]
2025-11-22 00:24:58.667898 | controller | changed
2025-11-22 00:24:58.677976 | 
2025-11-22 00:24:58.678091 | LOOP [ensure-package-repositories : Add repositories]
2025-11-22 00:24:59.750441 | controller | changed:
2025-11-22 00:24:59.750731 | controller | {
2025-11-22 00:24:59.750789 | controller |   "baseurl": "https://download.docker.com/linux/fedora/$releasever/$basearch/stable",
2025-11-22 00:24:59.750981 | controller |   "description": "Docker CE Stable - $basearch",
2025-11-22 00:24:59.751030 | controller |   "gpgcheck": true,
2025-11-22 00:24:59.751069 | controller |   "name": "docker-ce-stable"
2025-11-22 00:24:59.751106 | controller | }
2025-11-22 00:24:59.787423 | 
2025-11-22 00:24:59.787622 | TASK [ensure-docker : Install pre-reqs]
2025-11-22 00:25:04.274472 | controller | ok: Nothing to do
2025-11-22 00:25:04.281040 | 
2025-11-22 00:25:04.281180 | TASK [ensure-docker : Remove packages]
2025-11-22 00:25:07.141781 | controller | ok: Nothing to do
2025-11-22 00:25:07.147291 | 
2025-11-22 00:25:07.147359 | TASK [ensure-docker : Install upstream docker using package]
2025-11-22 00:26:23.778286 | controller | changed
2025-11-22 00:26:23.788219 | 
2025-11-22 00:26:23.788362 | TASK [ensure-docker : Setup docker]
2025-11-22 00:26:23.825470 | controller | ok
2025-11-22 00:26:23.894009 | controller | included: /var/lib/zuul/builds/7157cc27dcfa441eb0034291280b332e/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/tasks/docker-setup.yaml
2025-11-22 00:26:23.925345 | 
2025-11-22 00:26:23.925575 | TASK [ensure-docker : Ensure "docker" group exists]
2025-11-22 00:26:24.870885 | controller | ok
2025-11-22 00:26:24.883528 | 
2025-11-22 00:26:24.883713 | TASK [ensure-docker : Add user to docker group]
2025-11-22 00:26:25.903166 | controller | changed
2025-11-22 00:26:25.916475 | 
2025-11-22 00:26:25.916629 | TASK [Update docker daemon proxy configuration]
2025-11-22 00:26:25.954166 | controller | skipping: Conditional result was False
2025-11-22 00:26:25.965159 | 
2025-11-22 00:26:25.965297 | TASK [Lower default MTU]
2025-11-22 00:26:25.991177 | controller | skipping: Conditional result was False
2025-11-22 00:26:26.002721 | 
2025-11-22 00:26:26.002873 | TASK [ensure-docker : Get the status of the docket.socket unit]
2025-11-22 00:26:26.907674 | controller | inactive
2025-11-22 00:26:27.371859 | controller | ok: Runtime: 0:00:00.013349
2025-11-22 00:26:27.386246 | 
2025-11-22 00:26:27.386381 | TASK [ensure-docker : Get the status of the docket unit]
2025-11-22 00:26:28.185753 | controller | inactive
2025-11-22 00:26:28.763903 | controller | ok: Runtime: 0:00:00.020346
2025-11-22 00:26:28.779410 | 
2025-11-22 00:26:28.779573 | TASK [ensure-docker : Restart docker]
2025-11-22 00:26:28.831585 | Trigger a Docker restart
2025-11-22 00:26:28.845029 | 
2025-11-22 00:26:28.845175 | TASK [ensure-docker : Reset ssh connection to pick up docker group]
2025-11-22 00:26:28.898945 | 
2025-11-22 00:26:28.899090 | TASK [ensure-docker : Install docker-compose]
2025-11-22 00:26:28.925096 | controller | skipping: Conditional result was False
2025-11-22 00:26:28.932528 | 
2025-11-22 00:26:28.932701 | TASK [ensure-docker : Flush handlers before role exit]
2025-11-22 00:26:28.943838 | 
2025-11-22 00:26:28.943970 | TASK [ensure-docker : Stop docker.socket to avoid any conflict]
2025-11-22 00:26:32.187847 | controller | changed
2025-11-22 00:26:32.206158 | 
2025-11-22 00:26:32.206376 | TASK [ensure-docker : Assure docker service is running]
2025-11-22 00:26:35.747692 | controller | changed
2025-11-22 00:26:35.765112 | 
2025-11-22 00:26:35.765269 | TASK [ensure-docker : Assure docker.socket service is running]
2025-11-22 00:26:37.574598 | controller | ok
2025-11-22 00:26:37.593477 | 
2025-11-22 00:26:37.593694 | TASK [ensure-docker : Correct group ownership on docker sock]
2025-11-22 00:26:38.427603 | controller | ok
2025-11-22 00:26:38.440357 | 
2025-11-22 00:26:38.440546 | TASK [ensure-docker : Validate ability to talk with docker]
2025-11-22 00:26:39.033069 | controller | CONTAINER ID   IMAGE     COMMAND   CREATED   STATUS    PORTS     NAMES
2025-11-22 00:26:39.310967 | controller | ok: Runtime: 0:00:00.030217
2025-11-22 00:26:39.319460 | 
2025-11-22 00:26:39.319552 | TASK [ensure-docker : Get version details]
2025-11-22 00:26:40.039435 | controller | Client: Docker Engine - Community
2025-11-22 00:26:40.039674 | controller |  Version:           24.0.7
2025-11-22 00:26:40.039740 | controller |  API version:       1.43
2025-11-22 00:26:40.039798 | controller |  Go version:        go1.20.10
2025-11-22 00:26:40.039853 | controller |  Git commit:        afdd53b
2025-11-22 00:26:40.039940 | controller |  Built:             Thu Oct 26 09:09:39 2023
2025-11-22 00:26:40.040007 | controller |  OS/Arch:           linux/amd64
2025-11-22 00:26:40.040076 | controller |  Context:           default
2025-11-22 00:26:40.040099 | controller |
2025-11-22 00:26:40.040173 | controller | Server: Docker Engine - Community
2025-11-22 00:26:40.040255 | controller |  Engine:
2025-11-22 00:26:40.040446 | controller |   Version:          24.0.7
2025-11-22 00:26:40.040506 | controller |   API version:      1.43 (minimum version 1.12)
2025-11-22 00:26:40.040548 | controller |   Go version:       go1.20.10
2025-11-22 00:26:40.040627 | controller |   Git commit:       311b9ff
2025-11-22 00:26:40.040693 | controller |   Built:            Thu Oct 26 09:08:11 2023
2025-11-22 00:26:40.040759 | controller |   OS/Arch:          linux/amd64
2025-11-22 00:26:40.040801 | controller |   Experimental:     false
2025-11-22 00:26:40.040843 | controller |  containerd:
2025-11-22 00:26:40.040968 | controller |   Version:          1.6.25
2025-11-22 00:26:40.041030 | controller |   GitCommit:        d8f198a4ed8892c764191ef7b3b06d8a2eeb5c7f
2025-11-22 00:26:40.041057 | controller |  runc:
2025-11-22 00:26:40.041163 | controller |   Version:          1.1.10
2025-11-22 00:26:40.041213 | controller |   GitCommit:        v1.1.10-0-g18a0cb0
2025-11-22 00:26:40.041243 | controller |  docker-init:
2025-11-22 00:26:40.041330 | controller |   Version:          0.19.0
2025-11-22 00:26:40.041391 | controller |   GitCommit:        de40ad0
2025-11-22 00:26:40.217151 | controller | ok: Runtime: 0:00:00.042191
2025-11-22 00:26:40.231851 | 
2025-11-22 00:26:40.232032 | TASK [ensure-docker : Dump installed docker details]
2025-11-22 00:26:40.276930 | {'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-22 00:26:40.001192', 'end': '2025-11-22 00:26:40.043383', 'delta': '0:00:00.042191', 'msg': '', 'zuul_log_id': 'fa163efc-24cc-5a83-4b23-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-22 00:26:40.292723 | 
2025-11-22 00:26:40.292926 | TASK [Use buildset registry]
2025-11-22 00:26:40.315633 | controller | skipping: Conditional result was False
2025-11-22 00:26:40.343372 | 
2025-11-22 00:26:40.343514 | TASK [Ensure python3.8 is present]
2025-11-22 00:26:40.368478 | controller | skipping: Conditional result was False
2025-11-22 00:26:40.378500 | 
2025-11-22 00:26:40.378592 | TASK [Run our-ensure-python role (Fedora only for now)]
2025-11-22 00:26:40.402654 | controller | ok
2025-11-22 00:26:40.432908 | 
2025-11-22 00:26:40.433096 | TASK [our-ensure-python : Install the right Python version (rpm)]
2025-11-22 00:26:47.235810 | controller | ok: Nothing to do
2025-11-22 00:26:47.250798 | 
2025-11-22 00:26:47.250986 | TASK [our-ensure-python : Also install python3-devel]
2025-11-22 00:27:02.179827 | controller | changed
2025-11-22 00:27:02.205217 | 
2025-11-22 00:27:02.205380 | TASK [Run ensure-virtualenv role]
2025-11-22 00:27:02.235999 | controller | ok
2025-11-22 00:27:02.273697 | 
2025-11-22 00:27:02.273824 | TASK [ensure-virtualenv : Check if virtualenv is installed]
2025-11-22 00:27:02.987859 | controller | /usr/bin/virtualenv
2025-11-22 00:27:03.136019 | controller | ok: Runtime: 0:00:00.005165
2025-11-22 00:27:03.151029 | 
2025-11-22 00:27:03.151247 | LOOP [ensure-virtualenv : Install virtualenv package]
2025-11-22 00:27:03.187365 | controller | skipping: Conditional result was False
2025-11-22 00:27:03.188038 | controller | changed: All items complete
2025-11-22 00:27:03.188128 | 
2025-11-22 00:27:03.217075 | 
2025-11-22 00:27:03.217329 | TASK [Find the full path of the Python interpreter]
2025-11-22 00:27:03.810165 | controller | /usr/bin/python3.8
2025-11-22 00:27:04.085318 | controller | ok
2025-11-22 00:27:04.098654 | 
2025-11-22 00:27:04.098890 | TASK [Create virtualenv for ansible-test with a specific interpreter]
2025-11-22 00:27:06.334521 | controller | created virtual environment CPython3.8.18.final.0-64 in 1134ms
2025-11-22 00:27:06.376826 | controller |   creator CPython3Posix(dest=/home/zuul/venv, clear=False, no_vcs_ignore=False, global=False)
2025-11-22 00:27:06.376883 | 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-22 00:27:06.376897 | controller |     added seed packages: pip==22.2.2, setuptools==62.6.0, wheel==0.37.1
2025-11-22 00:27:06.376917 | controller |   activators BashActivator,CShellActivator,FishActivator,NushellActivator,PowerShellActivator,PythonActivator
2025-11-22 00:27:06.509894 | controller | changed
2025-11-22 00:27:06.523495 | 
2025-11-22 00:27:06.523633 | TASK [Set selinux package]
2025-11-22 00:27:06.557407 | controller | ok
2025-11-22 00:27:06.569621 | 
2025-11-22 00:27:06.569772 | TASK [Set selinux package (Fedora)]
2025-11-22 00:27:06.616180 | controller | ok
2025-11-22 00:27:06.624814 | 
2025-11-22 00:27:06.624915 | TASK [Install selinux into virtualenv]
2025-11-22 00:27:10.948030 | controller | Collecting selinux-please-lie-to-me
2025-11-22 00:27:11.043674 | controller |   Downloading selinux_please_lie_to_me-1.0.1-py2.py3-none-any.whl (3.8 kB)
2025-11-22 00:27:12.147456 | controller | Collecting setuptools<50.0.0
2025-11-22 00:27:12.156371 | controller |   Downloading setuptools-49.6.0-py3-none-any.whl (803 kB)
2025-11-22 00:27:12.223696 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 803.3/803.3 kB 14.1 MB/s eta 0:00:00
2025-11-22 00:27:12.571706 | controller | Installing collected packages: setuptools, selinux-please-lie-to-me
2025-11-22 00:27:12.577172 | controller |   Attempting uninstall: setuptools
2025-11-22 00:27:12.581646 | controller |     Found existing installation: setuptools 62.6.0
2025-11-22 00:27:12.873768 | controller |     Uninstalling setuptools-62.6.0:
2025-11-22 00:27:12.888746 | controller |       Successfully uninstalled setuptools-62.6.0
2025-11-22 00:27:14.103414 | controller | Successfully installed selinux-please-lie-to-me-1.0.1 setuptools-49.6.0
2025-11-22 00:27:14.302811 | controller |
2025-11-22 00:27:14.503054 | controller | [notice] A new release of pip available: 22.2.2 -> 25.0.1
2025-11-22 00:27:14.503106 | controller | [notice] To update, run: /home/zuul/venv/bin/python -m pip install --upgrade pip
2025-11-22 00:27:15.001625 | controller | ok: Runtime: 0:00:06.982446
2025-11-22 00:27:15.015201 | 
2025-11-22 00:27:15.015349 | TASK [Install pytest-forked into virtualenv]
2025-11-22 00:27:16.998387 | controller | Collecting pytest-forked
2025-11-22 00:27:17.048418 | controller |   Downloading pytest_forked-1.6.0-py3-none-any.whl (4.9 kB)
2025-11-22 00:27:17.229003 | controller | Collecting pytest>=3.10
2025-11-22 00:27:17.233636 | controller |   Downloading pytest-8.3.5-py3-none-any.whl (343 kB)
2025-11-22 00:27:17.268052 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 343.6/343.6 kB 11.2 MB/s eta 0:00:00
2025-11-22 00:27:17.320501 | controller | Collecting py
2025-11-22 00:27:17.325045 | controller |   Downloading py-1.11.0-py2.py3-none-any.whl (98 kB)
2025-11-22 00:27:17.335002 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 98.7/98.7 kB 13.5 MB/s eta 0:00:00
2025-11-22 00:27:17.406886 | controller | Collecting exceptiongroup>=1.0.0rc8
2025-11-22 00:27:17.413257 | controller |   Downloading exceptiongroup-1.3.1-py3-none-any.whl (16 kB)
2025-11-22 00:27:17.484818 | controller | Collecting packaging
2025-11-22 00:27:17.490828 | controller |   Downloading packaging-25.0-py3-none-any.whl (66 kB)
2025-11-22 00:27:17.500887 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 66.5/66.5 kB 9.7 MB/s eta 0:00:00
2025-11-22 00:27:17.531337 | controller | Collecting iniconfig
2025-11-22 00:27:17.537173 | controller |   Downloading iniconfig-2.1.0-py3-none-any.whl (6.0 kB)
2025-11-22 00:27:17.605768 | controller | Collecting tomli>=1
2025-11-22 00:27:17.611364 | controller |   Downloading tomli-2.3.0-py3-none-any.whl (14 kB)
2025-11-22 00:27:17.662672 | controller | Collecting pluggy<2,>=1.5
2025-11-22 00:27:17.668232 | controller |   Downloading pluggy-1.5.0-py3-none-any.whl (20 kB)
2025-11-22 00:27:17.753961 | controller | Collecting typing-extensions>=4.6.0
2025-11-22 00:27:17.760179 | controller |   Downloading typing_extensions-4.13.2-py3-none-any.whl (45 kB)
2025-11-22 00:27:17.768750 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 45.8/45.8 kB 8.8 MB/s eta 0:00:00
2025-11-22 00:27:17.864988 | controller | Installing collected packages: typing-extensions, tomli, py, pluggy, packaging, iniconfig, exceptiongroup, pytest, pytest-forked
2025-11-22 00:27:18.657305 | controller | Successfully installed exceptiongroup-1.3.1 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-22 00:27:18.663358 | controller |
2025-11-22 00:27:19.019945 | controller | [notice] A new release of pip available: 22.2.2 -> 25.0.1
2025-11-22 00:27:19.019991 | controller | [notice] To update, run: /home/zuul/venv/bin/python -m pip install --upgrade pip
2025-11-22 00:27:19.401093 | controller | ok: Runtime: 0:00:02.944039
2025-11-22 00:27:19.415853 | 
2025-11-22 00:27:19.415998 | TASK [Update pip]
2025-11-22 00:27:21.987965 | controller | Requirement already satisfied: pip in ./venv/lib/python3.8/site-packages (22.2.2)
2025-11-22 00:27:22.475693 | controller | Collecting pip
2025-11-22 00:27:22.597267 | controller |   Downloading pip-25.0.1-py3-none-any.whl (1.8 MB)
2025-11-22 00:27:22.725728 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 1.8/1.8 MB 15.1 MB/s eta 0:00:00
2025-11-22 00:27:22.943663 | controller | Installing collected packages: pip
2025-11-22 00:27:22.946467 | controller |   Attempting uninstall: pip
2025-11-22 00:27:22.954409 | controller |     Found existing installation: pip 22.2.2
2025-11-22 00:27:23.677298 | controller |     Uninstalling pip-22.2.2:
2025-11-22 00:27:23.747492 | controller |       Successfully uninstalled pip-22.2.2
2025-11-22 00:27:25.989264 | controller | Successfully installed pip-25.0.1
2025-11-22 00:27:26.298627 | controller | ok: Runtime: 0:00:05.632032
2025-11-22 00:27:26.324372 | 
2025-11-22 00:27:26.324730 | TASK [Ensure pip temp directory exists in /var/tmp]
2025-11-22 00:27:27.570281 | controller | changed
2025-11-22 00:27:27.580458 | 
2025-11-22 00:27:27.580564 | TASK [Install ansible into virtualenv]
2025-11-22 00:27:29.665477 | controller | Processing ./src/github.com/ansible/ansible
2025-11-22 00:27:29.666881 | controller |   Preparing metadata (setup.py): started
2025-11-22 00:27:31.286568 | controller |   Preparing metadata (setup.py): finished with status 'done'
2025-11-22 00:27:31.368805 | controller | Collecting jinja2 (from ansible==2.9.27.post0)
2025-11-22 00:27:31.382400 | controller |   Downloading jinja2-3.1.6-py3-none-any.whl.metadata (2.9 kB)
2025-11-22 00:27:31.464185 | controller | Collecting PyYAML (from ansible==2.9.27.post0)
2025-11-22 00:27:31.469204 | 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-22 00:27:31.843994 | controller | Collecting cryptography (from ansible==2.9.27.post0)
2025-11-22 00:27:31.848959 | controller |   Downloading cryptography-46.0.3-cp38-abi3-manylinux_2_34_x86_64.whl.metadata (5.7 kB)
2025-11-22 00:27:32.073495 | controller | Collecting cffi>=1.14 (from cryptography->ansible==2.9.27.post0)
2025-11-22 00:27:32.078519 | controller |   Downloading cffi-1.17.1-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl.metadata (1.5 kB)
2025-11-22 00:27:32.086236 | 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-22 00:27:32.189642 | controller | Collecting MarkupSafe>=2.0 (from jinja2->ansible==2.9.27.post0)
2025-11-22 00:27:32.194877 | controller |   Downloading MarkupSafe-2.1.5-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl.metadata (3.0 kB)
2025-11-22 00:27:32.219987 | controller | Collecting pycparser (from cffi>=1.14->cryptography->ansible==2.9.27.post0)
2025-11-22 00:27:32.224668 | controller |   Downloading pycparser-2.23-py3-none-any.whl.metadata (993 bytes)
2025-11-22 00:27:32.239435 | controller | Downloading cryptography-46.0.3-cp38-abi3-manylinux_2_34_x86_64.whl (4.5 MB)
2025-11-22 00:27:32.281095 | controller |    ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 4.5/4.5 MB 126.9 MB/s eta 0:00:00
2025-11-22 00:27:32.285819 | controller | Downloading jinja2-3.1.6-py3-none-any.whl (134 kB)
2025-11-22 00:27:32.299970 | 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-22 00:27:32.315363 | controller |    ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 806.0/806.0 kB 51.5 MB/s eta 0:00:00
2025-11-22 00:27:32.320265 | controller | Downloading cffi-1.17.1-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl (446 kB)
2025-11-22 00:27:32.339006 | controller | Downloading MarkupSafe-2.1.5-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl (26 kB)
2025-11-22 00:27:32.403876 | controller | Downloading pycparser-2.23-py3-none-any.whl (118 kB)
2025-11-22 00:27:32.427220 | controller | Building wheels for collected packages: ansible
2025-11-22 00:27:32.428167 | controller |   Building wheel for ansible (setup.py): started
2025-11-22 00:27:41.797037 | controller |   Building wheel for ansible (setup.py): finished with status 'done'
2025-11-22 00:27:41.814415 | controller |   Created wheel for ansible: filename=ansible-2.9.27.post0-py3-none-any.whl size=16207514 sha256=735ab207c7f14440e59f5582caef6e225995867ccdde0339ad91ad6c1def577a
2025-11-22 00:27:41.814698 | controller |   Stored in directory: /var/tmp/ansible-test-pip/pip-ephem-wheel-cache-uhaetz7o/wheels/a5/4b/fa/b6deaae105422b0b3b92831e0369d44bf7fca5bb3413d79be7
2025-11-22 00:27:41.887438 | controller | Successfully built ansible
2025-11-22 00:27:42.059246 | controller | Installing collected packages: PyYAML, pycparser, MarkupSafe, jinja2, cffi, cryptography, ansible
2025-11-22 00:27:54.458101 | 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-22 00:27:54.951322 | controller | ok: Runtime: 0:00:26.053973
2025-11-22 00:27:54.967154 | 
2025-11-22 00:27:54.967372 | TASK [Setup download-artifact-fork role]
2025-11-22 00:27:54.998190 | controller | ok
2025-11-22 00:27:55.034011 | 
2025-11-22 00:27:55.034147 | TASK [download-artifact-fork : Parse inventory response]
2025-11-22 00:27:55.087208 | controller | ok
2025-11-22 00:27:55.095507 | 
2025-11-22 00:27:55.095611 | TASK [download-artifact-fork : Fetch info from Zuul API]
2025-11-22 00:27:55.131260 | controller | skipping: Conditional result was False
2025-11-22 00:27:55.140326 | 
2025-11-22 00:27:55.140422 | TASK [download-artifact-fork : Parse build response]
2025-11-22 00:27:55.174951 | controller | skipping: Conditional result was False
2025-11-22 00:27:55.185206 | 
2025-11-22 00:27:55.185306 | TASK [download-artifact-fork : Ensure artifacts directory exists]
2025-11-22 00:27:55.985746 | controller | changed
2025-11-22 00:27:55.999443 | 
2025-11-22 00:27:55.999586 | LOOP [download-artifact-fork : Download archive by type]
2025-11-22 00:27:56.036327 | controller | skipping: Conditional result was False
2025-11-22 00:27:56.036886 | 
2025-11-22 00:27:57.823205 | controller | changed: OK (20197 bytes)
2025-11-22 00:27:57.860559 | 
2025-11-22 00:27:57.860805 | TASK [deploy-artifacts : define __collections]
2025-11-22 00:27:57.886121 | controller | ok
2025-11-22 00:27:57.893737 | 
2025-11-22 00:27:57.893851 | LOOP [deploy-artifacts : Create list of collections]
2025-11-22 00:27:57.919278 | controller | skipping: Conditional result was False
2025-11-22 00:27:57.928384 | controller | ok:
2025-11-22 00:27:57.928502 | controller | {
2025-11-22 00:27:57.928532 | controller |   "branch": "main",
2025-11-22 00:27:57.928552 | controller |   "job": "build-ansible-collection",
2025-11-22 00:27:57.928570 | controller |   "metadata": {
2025-11-22 00:27:57.928587 | controller |     "type": "ansible_collection",
2025-11-22 00:27:57.928603 | controller |     "version": "5.0.0"
2025-11-22 00:27:57.928620 | controller |   },
2025-11-22 00:27:57.928636 | controller |   "name": "ansible.network",
2025-11-22 00:27:57.928651 | controller |   "project": "ansible-collections/ansible.network",
2025-11-22 00:27:57.928691 | controller |   "url": "https://6a36b87c297171ddac11-cc5d625c07ba2a812f68e46aad86587c.ssl.cf1.rackcdn.com/ansible/0d5d59751a99435c9951911b42b053de/artifacts/ansible-network-5.0.0.tar.gz"
2025-11-22 00:27:57.928717 | controller | }
2025-11-22 00:27:57.980242 | 
2025-11-22 00:27:57.980368 | TASK [deploy-artifacts : Install require-project collection using ansible-galaxy]
2025-11-22 00:27:58.879222 | controller | Process install dependency map
2025-11-22 00:28:48.173329 | controller | |/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|Starting collection install process
2025-11-22 00:28:48.274181 | controller | |Installing 'ansible.network:5.0.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/network'
2025-11-22 00:28:48.274265 | controller | Installing 'ansible.netcommon:8.2.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/netcommon'
2025-11-22 00:28:49.801823 | controller | /-\|/-\|/-\|/-\Installing 'ansible.utils:6.0.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/utils'
2025-11-22 00:28:51.527633 | controller | |/-\|/-\|/-\|/-\|Installing 'arista.eos:12.0.0' to '/home/zuul/.ansible/collections/ansible_collections/arista/eos'
2025-11-22 00:28:53.937719 | controller | /-\|/-\|/-\|/-\|/-\|/-\|Installing 'cisco.ios:11.1.1' to '/home/zuul/.ansible/collections/ansible_collections/cisco/ios'
2025-11-22 00:28:56.765277 | controller | /-\|/-\|/-\|/-\|/-\|/-\|/-\|Installing 'cisco.iosxr:12.1.0' to '/home/zuul/.ansible/collections/ansible_collections/cisco/iosxr'
2025-11-22 00:28:59.408424 | controller | /-\|/-\|/-\|/-\|/-\|/-\|/-Installing 'cisco.nxos:11.1.0' to '/home/zuul/.ansible/collections/ansible_collections/cisco/nxos'
2025-11-22 00:29:03.073908 | controller | \|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-Installing 'junipernetworks.junos:11.0.0' to '/home/zuul/.ansible/collections/ansible_collections/junipernetworks/junos'
2025-11-22 00:29:05.859279 | controller | ok: Runtime: 0:01:07.119836
2025-11-22 00:29:05.883638 | 
2025-11-22 00:29:05.883803 | TASK [podman may need iptables kernel modules]
2025-11-22 00:29:07.254731 | controller | ok: Runtime: 0:00:00.006045
2025-11-22 00:29:07.298174 | 
2025-11-22 00:29:07.298301 | PLAY RECAP
2025-11-22 00:29:07.298358 | controller | ok: 46 changed: 26 unreachable: 0 failed: 0 skipped: 17 rescued: 0 ignored: 0
2025-11-22 00:29:07.298387 | 
2025-11-22 00:29:07.434321 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master]
2025-11-22 00:29:07.437500 | RUN START: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/run.yaml@master]
2025-11-22 00:29:08.222759 | 
2025-11-22 00:29:08.222950 | PLAY [controller]
2025-11-22 00:29:08.247778 | 
2025-11-22 00:29:08.247936 | TASK [Copy the galaxy.yml on the controller]
2025-11-22 00:29:11.789555 | controller | changed
2025-11-22 00:29:11.801421 | 
2025-11-22 00:29:11.801658 | TASK [Load information from galaxy.yml]
2025-11-22 00:29:11.841300 | controller | ok
2025-11-22 00:29:11.854605 | 
2025-11-22 00:29:11.854798 | LOOP [Enable FIPS mode]
2025-11-22 00:29:11.887578 | 
2025-11-22 00:29:11.887858 | LOOP [ansible-test : Include branch specific variables.]
2025-11-22 00:29:11.941507 | controller | ok: "/var/lib/zuul/builds/7157cc27dcfa441eb0034291280b332e/untrusted/project_2/github.com/ansible/ansible-zuul-jobs/roles/ansible-test/vars/default.yaml"
2025-11-22 00:29:11.941799 | 
2025-11-22 00:29:11.962791 | 
2025-11-22 00:29:11.962998 | TASK [ansible-test : Setup --skip-tags for test_options]
2025-11-22 00:29:12.022301 | controller | ok
2025-11-22 00:29:12.034151 | 
2025-11-22 00:29:12.034295 | TASK [ansible-test : Enable --inventory for network-integration]
2025-11-22 00:29:12.061323 | controller | skipping: Conditional result was False
2025-11-22 00:29:12.077436 | 
2025-11-22 00:29:12.077607 | TASK [ansible-test : Enable --retry-on-error]
2025-11-22 00:29:12.104443 | controller | skipping: Conditional result was False
2025-11-22 00:29:12.117748 | 
2025-11-22 00:29:12.117927 | TASK [ansible-test : Enable --continue-on-error]
2025-11-22 00:29:12.166484 | controller | ok
2025-11-22 00:29:12.178277 | 
2025-11-22 00:29:12.178432 | TASK [ansible-test : Enable --requirements]
2025-11-22 00:29:12.205213 | controller | skipping: Conditional result was False
2025-11-22 00:29:12.218090 | 
2025-11-22 00:29:12.218228 | TASK [ansible-test : Adjust options for unit tests]
2025-11-22 00:29:12.245050 | controller | skipping: Conditional result was False
2025-11-22 00:29:12.258443 | 
2025-11-22 00:29:12.258614 | TASK [ansible-test : Install requirements with unit tests]
2025-11-22 00:29:12.309169 | controller | ok
2025-11-22 00:29:12.351965 | 
2025-11-22 00:29:12.352112 | TASK [ansible-test : Set --skip-test]
2025-11-22 00:29:12.418272 | controller | skipping: Conditional result was False
2025-11-22 00:29:12.431658 | 
2025-11-22 00:29:12.431835 | TASK [ansible-test : Reduce verbosity]
2025-11-22 00:29:12.480205 | controller | ok
2025-11-22 00:29:12.493156 | 
2025-11-22 00:29:12.493324 | TASK [ansible-test : Setup --python option]
2025-11-22 00:29:12.520987 | controller | skipping: Conditional result was False
2025-11-22 00:29:12.534863 | 
2025-11-22 00:29:12.535058 | TASK [ansible-test : Setup --docker option]
2025-11-22 00:29:12.583819 | controller | ok
2025-11-22 00:29:12.597835 | 
2025-11-22 00:29:12.598030 | TASK [ansible-test : check if MANIFEST.json exists]
2025-11-22 00:29:13.760977 | controller | ok
2025-11-22 00:29:13.773305 | 
2025-11-22 00:29:13.773437 | TASK [ansible-test : Copy the MANIFEST.json on the controller]
2025-11-22 00:29:13.791196 | controller | skipping: Conditional result was False
2025-11-22 00:29:13.805260 | 
2025-11-22 00:29:13.805400 | TASK [ansible-test : Load information from MANIFEST.json]
2025-11-22 00:29:13.831996 | controller | skipping: Conditional result was False
2025-11-22 00:29:13.845020 | 
2025-11-22 00:29:13.845158 | TASK [ansible-test : Setup location of project for integration tests]
2025-11-22 00:29:13.863068 | controller | skipping: Conditional result was False
2025-11-22 00:29:13.879424 | 
2025-11-22 00:29:13.879555 | TASK [ansible-test : Copy the galaxy.yml on the controller]
2025-11-22 00:29:15.324081 | controller | ok
2025-11-22 00:29:15.336575 | 
2025-11-22 00:29:15.336741 | TASK [ansible-test : Load information from galaxy.yml]
2025-11-22 00:29:15.387130 | controller | ok
2025-11-22 00:29:15.403747 | 
2025-11-22 00:29:15.403907 | TASK [ansible-test : Setup location of project for integration tests]
2025-11-22 00:29:15.458972 | controller | ok
2025-11-22 00:29:15.466905 | 
2025-11-22 00:29:15.466998 | TASK [ansible-test : Setup minimum test requirements]
2025-11-22 00:29:15.515311 | controller | ok
2025-11-22 00:29:15.528119 | 
2025-11-22 00:29:15.528259 | TASK [ansible-test : Ensure pip temp directory exists in /var/tmp]
2025-11-22 00:29:16.408691 | controller | ok
2025-11-22 00:29:16.422881 | 
2025-11-22 00:29:16.423053 | TASK [ansible-test : Install python requirements]
2025-11-22 00:29:17.703622 | controller | Collecting yamllint (from -r /home/zuul/.ansible/collections/ansible_collections/ansible/network/test-requirements.txt (line 1))
2025-11-22 00:29:17.721071 | controller |   Downloading yamllint-1.35.1-py3-none-any.whl.metadata (4.2 kB)
2025-11-22 00:29:17.748322 | controller | Collecting pathspec>=0.5.3 (from yamllint->-r /home/zuul/.ansible/collections/ansible_collections/ansible/network/test-requirements.txt (line 1))
2025-11-22 00:29:17.753519 | controller |   Downloading pathspec-0.12.1-py3-none-any.whl.metadata (21 kB)
2025-11-22 00:29:17.768133 | 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-22 00:29:17.775785 | controller | Downloading yamllint-1.35.1-py3-none-any.whl (66 kB)
2025-11-22 00:29:17.787653 | controller | Downloading pathspec-0.12.1-py3-none-any.whl (31 kB)
2025-11-22 00:29:17.849530 | controller | Installing collected packages: pathspec, yamllint
2025-11-22 00:29:17.938665 | controller | Successfully installed pathspec-0.12.1 yamllint-1.35.1
2025-11-22 00:29:18.299893 | controller | ok: Runtime: 0:00:00.921839
2025-11-22 00:29:18.308281 | 
2025-11-22 00:29:18.308370 | TASK [ansible-test : Copy the integration_config.yml file from the controller]
2025-11-22 00:29:19.082562 | controller | cp: cannot stat '/tmp/integration_config.yml': No such file or directory
2025-11-22 00:29:19.672293 | controller | ERROR
2025-11-22 00:29:19.672643 | controller | {
2025-11-22 00:29:19.672796 | controller |   "delta": "0:00:00.006911",
2025-11-22 00:29:19.672845 | controller |   "end": "2025-11-22 00:29:19.082803",
2025-11-22 00:29:19.672883 | controller |   "msg": "non-zero return code",
2025-11-22 00:29:19.672918 | controller |   "rc": 1,
2025-11-22 00:29:19.672954 | controller |   "start": "2025-11-22 00:29:19.075892"
2025-11-22 00:29:19.672988 | controller | }
2025-11-22 00:29:19.673034 | controller | ERROR: Ignoring Errors
2025-11-22 00:29:19.686114 | 
2025-11-22 00:29:19.686318 | TASK [ansible-test : Copy potential cloud provider configuration for ansible-test in the collection]
2025-11-22 00:29:20.068239 | controller | skipping: Conditional result was False
2025-11-22 00:29:20.083629 | 
2025-11-22 00:29:20.083888 | TASK [ansible-test : Enable persistent connection logging]
2025-11-22 00:29:21.389103 | controller | changed: section and option added
2025-11-22 00:29:21.399861 | 
2025-11-22 00:29:21.399978 | TASK [ansible-test : Set persistent ssh_type value]
2025-11-22 00:29:22.221864 | controller | changed: option added
2025-11-22 00:29:22.241782 | 
2025-11-22 00:29:22.242023 | TASK [ansible-test : Set ansible log path]
2025-11-22 00:29:23.285191 | controller | changed: section and option added
2025-11-22 00:29:23.298261 | 
2025-11-22 00:29:23.298403 | TASK [ansible-test : Identify the changed targets]
2025-11-22 00:29:23.674742 | controller | skipping: Conditional result was False
2025-11-22 00:29:23.684891 | 
2025-11-22 00:29:23.685002 | TASK [ansible-test : set_fact]
2025-11-22 00:29:23.751448 | controller | skipping: Conditional result was False
2025-11-22 00:29:23.761548 | 
2025-11-22 00:29:23.761651 | TASK [ansible-test : Set the targets]
2025-11-22 00:29:23.795296 | controller | ok
2025-11-22 00:29:23.804023 | 
2025-11-22 00:29:23.804120 | TASK [ansible-test : set_fact]
2025-11-22 00:29:23.829549 | controller | skipping: Conditional result was False
2025-11-22 00:29:23.840385 | 
2025-11-22 00:29:23.840487 | TASK [ansible-test : List the targets]
2025-11-22 00:29:24.236275 | controller | skipping: Conditional result was False
2025-11-22 00:29:24.250206 | 
2025-11-22 00:29:24.250346 | TASK [ansible-test : set_fact]
2025-11-22 00:29:24.276892 | controller | skipping: Conditional result was False
2025-11-22 00:29:24.289659 | 
2025-11-22 00:29:24.289831 | TASK [ansible-test : set_fact]
2025-11-22 00:29:24.306848 | controller | skipping: Conditional result was False
2025-11-22 00:29:24.320991 | 
2025-11-22 00:29:24.321133 | LOOP [ansible-test : set_fact]
2025-11-22 00:29:24.381010 | 
2025-11-22 00:29:24.381255 | TASK [ansible-test : set_fact]
2025-11-22 00:29:24.408323 | controller | skipping: Conditional result was False
2025-11-22 00:29:24.422005 | 
2025-11-22 00:29:24.422146 | TASK [ansible-test : debug]
2025-11-22 00:29:24.449244 | controller | skipping: Conditional result was False
2025-11-22 00:29:24.462230 | 
2025-11-22 00:29:24.462371 | TASK [ansible-test : debug]
2025-11-22 00:29:24.522210 | About to run: ansible-test sanity --requirements --docker -v
2025-11-22 00:29:24.534362 | 
2025-11-22 00:29:24.534490 | TASK [ansible-test : Run the test suite]
2025-11-22 00:29:25.928674 | controller | Run command: docker images quay.io/ansible/default-test-container:1.10.1 --format '{{json .}}'
2025-11-22 00:29:25.979179 | controller | Run command: docker pull quay.io/ansible/default-test-container:1.10.1
2025-11-22 00:29:27.538705 | controller | 1.10.1: Pulling from ansible/default-test-container
2025-11-22 00:29:28.591318 | 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-22 00:29:28.591460 | controller | 5667fdb72017: Pulling fs layer
2025-11-22 00:29:28.591472 | controller | d83811f270d5: Pulling fs layer
2025-11-22 00:29:28.591479 | controller | ee671aafb583: Pulling fs layer
2025-11-22 00:29:28.591513 | controller | 7fc152dfb3a6: Pulling fs layer
2025-11-22 00:29:28.591522 | controller | 3fb967a6fd03: Pulling fs layer
2025-11-22 00:29:28.591528 | controller | b3e63023ca5f: Pulling fs layer
2025-11-22 00:29:28.591534 | controller | 653cb3e65dbf: Pulling fs layer
2025-11-22 00:29:28.591540 | controller | f4712b586375: Pulling fs layer
2025-11-22 00:29:28.591546 | controller | d696ed7812f8: Pulling fs layer
2025-11-22 00:29:28.591630 | controller | 29bf28764f14: Pulling fs layer
2025-11-22 00:29:28.591637 | controller | 47d7edb3c2b7: Pulling fs layer
2025-11-22 00:29:28.591654 | controller | 07550ad4f6e4: Pulling fs layer
2025-11-22 00:29:28.591660 | controller | 7e79526e64c5: Pulling fs layer
2025-11-22 00:29:28.591666 | controller | 4fffb32882b7: Pulling fs layer
2025-11-22 00:29:28.591671 | controller | fc7a38b06f4e: Pulling fs layer
2025-11-22 00:29:28.591677 | controller | 6d9d912e060e: Pulling fs layer
2025-11-22 00:29:28.591724 | controller | 5dd542dd28d9: Pulling fs layer
2025-11-22 00:29:28.591731 | controller | cef8beb99552: Pulling fs layer
2025-11-22 00:29:28.591740 | controller | bd5557e101ad: Pulling fs layer
2025-11-22 00:29:28.591746 | controller | e057c20fabdd: Pulling fs layer
2025-11-22 00:29:28.591755 | controller | d16059cdae2e: Pulling fs layer
2025-11-22 00:29:28.591762 | controller | 431e42d906a3: Pulling fs layer
2025-11-22 00:29:28.591769 | controller | 7a0fd3b1d7c3: Pulling fs layer
2025-11-22 00:29:28.591807 | controller | aacdb6db0c8c: Pulling fs layer
2025-11-22 00:29:28.591814 | controller | 2e7f04ae8c8b: Pulling fs layer
2025-11-22 00:29:28.591820 | controller | 4cd343027a44: Pulling fs layer
2025-11-22 00:29:28.591827 | controller | 0b4379883f90: Pulling fs layer
2025-11-22 00:29:28.591833 | controller | 3d9d6d9dfc91: Pulling fs layer
2025-11-22 00:29:28.591844 | controller | 57d6314bd293: Pulling fs layer
2025-11-22 00:29:28.591850 | controller | 774036afeb7f: Pulling fs layer
2025-11-22 00:29:28.591856 | controller | 54b10f0e1645: Pulling fs layer
2025-11-22 00:29:28.591862 | controller | 78cf439d9fdb: Pulling fs layer
2025-11-22 00:29:28.591868 | controller | 36e5e20969c7: Pulling fs layer
2025-11-22 00:29:28.591874 | controller | 4b19ac3f17a8: Pulling fs layer
2025-11-22 00:29:28.591880 | controller | 7fc152dfb3a6: Waiting
2025-11-22 00:29:28.591886 | controller | 3fb967a6fd03: Waiting
2025-11-22 00:29:28.591895 | controller | b3e63023ca5f: Waiting
2025-11-22 00:29:28.591901 | controller | 653cb3e65dbf: Waiting
2025-11-22 00:29:28.591907 | controller | f4712b586375: Waiting
2025-11-22 00:29:28.591913 | controller | d696ed7812f8: Waiting
2025-11-22 00:29:28.591918 | controller | 29bf28764f14: Waiting
2025-11-22 00:29:28.591924 | controller | 47d7edb3c2b7: Waiting
2025-11-22 00:29:28.591930 | controller | 07550ad4f6e4: Waiting
2025-11-22 00:29:28.591936 | controller | 7e79526e64c5: Waiting
2025-11-22 00:29:28.591942 | controller | 4fffb32882b7: Waiting
2025-11-22 00:29:28.591948 | controller | fc7a38b06f4e: Waiting
2025-11-22 00:29:28.591953 | controller | 6d9d912e060e: Waiting
2025-11-22 00:29:28.591959 | controller | 5dd542dd28d9: Waiting
2025-11-22 00:29:28.591968 | controller | cef8beb99552: Waiting
2025-11-22 00:29:28.591974 | controller | bd5557e101ad: Waiting
2025-11-22 00:29:28.591980 | controller | e057c20fabdd: Waiting
2025-11-22 00:29:28.591986 | controller | d16059cdae2e: Waiting
2025-11-22 00:29:28.591992 | controller | 431e42d906a3: Waiting
2025-11-22 00:29:28.591997 | controller | 7a0fd3b1d7c3: Waiting
2025-11-22 00:29:28.592004 | controller | aacdb6db0c8c: Waiting
2025-11-22 00:29:28.592009 | controller | 2e7f04ae8c8b: Waiting
2025-11-22 00:29:28.592015 | controller | 4cd343027a44: Waiting
2025-11-22 00:29:28.592021 | controller | 0b4379883f90: Waiting
2025-11-22 00:29:28.592027 | controller | 3d9d6d9dfc91: Waiting
2025-11-22 00:29:28.592033 | controller | 57d6314bd293: Waiting
2025-11-22 00:29:28.592041 | controller | 774036afeb7f: Waiting
2025-11-22 00:29:28.592062 | controller | 54b10f0e1645: Waiting
2025-11-22 00:29:28.592070 | controller | 78cf439d9fdb: Waiting
2025-11-22 00:29:28.592075 | controller | 36e5e20969c7: Waiting
2025-11-22 00:29:28.592082 | controller | 4b19ac3f17a8: Waiting
2025-11-22 00:29:28.592096 | controller | ee671aafb583: Verifying Checksum
2025-11-22 00:29:28.628251 | controller | ee671aafb583: Download complete
2025-11-22 00:29:28.628308 | controller | d83811f270d5: Verifying Checksum
2025-11-22 00:29:30.730839 | controller | d83811f270d5: Download complete
2025-11-22 00:29:30.730904 | controller | b3e63023ca5f: Verifying Checksum
2025-11-22 00:29:31.755889 | controller | b3e63023ca5f: Download complete
2025-11-22 00:29:31.755971 | controller | 653cb3e65dbf: Verifying Checksum
2025-11-22 00:29:34.893448 | controller | 653cb3e65dbf: Download complete
2025-11-22 00:29:34.893510 | controller | 5667fdb72017: Verifying Checksum
2025-11-22 00:29:35.900085 | controller | 5667fdb72017: Download complete
2025-11-22 00:29:35.900154 | controller | d696ed7812f8: Verifying Checksum
2025-11-22 00:29:36.834689 | controller | d696ed7812f8: Download complete
2025-11-22 00:29:36.834781 | controller | 29bf28764f14: Verifying Checksum
2025-11-22 00:29:37.041096 | controller | 29bf28764f14: Download complete
2025-11-22 00:29:37.041154 | controller | 3fb967a6fd03: Verifying Checksum
2025-11-22 00:29:37.042958 | controller | 3fb967a6fd03: Download complete
2025-11-22 00:29:37.042988 | controller | 5667fdb72017: Pull complete
2025-11-22 00:29:37.087615 | controller | d83811f270d5: Pull complete
2025-11-22 00:29:37.115874 | controller | ee671aafb583: Pull complete
2025-11-22 00:29:37.139503 | controller | 7fc152dfb3a6: Pull complete
2025-11-22 00:29:37.868992 | controller | 47d7edb3c2b7: Verifying Checksum
2025-11-22 00:29:37.869131 | controller | 47d7edb3c2b7: Download complete
2025-11-22 00:29:37.934882 | controller | 3fb967a6fd03: Pull complete
2025-11-22 00:29:37.955473 | controller | b3e63023ca5f: Pull complete
2025-11-22 00:29:37.978643 | controller | 653cb3e65dbf: Pull complete
2025-11-22 00:29:38.138192 | controller | 07550ad4f6e4: Verifying Checksum
2025-11-22 00:29:38.138285 | controller | 07550ad4f6e4: Download complete
2025-11-22 00:29:38.830925 | controller | 7e79526e64c5: Verifying Checksum
2025-11-22 00:29:38.831132 | controller | 7e79526e64c5: Download complete
2025-11-22 00:29:39.162865 | controller | 4fffb32882b7: Verifying Checksum
2025-11-22 00:29:39.163052 | controller | 4fffb32882b7: Download complete
2025-11-22 00:29:40.002320 | controller | fc7a38b06f4e: Download complete
2025-11-22 00:29:40.160186 | controller | 6d9d912e060e: Verifying Checksum
2025-11-22 00:29:40.160234 | controller | 6d9d912e060e: Download complete
2025-11-22 00:29:41.056726 | controller | 5dd542dd28d9: Verifying Checksum
2025-11-22 00:29:41.056880 | controller | 5dd542dd28d9: Download complete
2025-11-22 00:29:41.970958 | controller | bd5557e101ad: Verifying Checksum
2025-11-22 00:29:41.971049 | controller | bd5557e101ad: Download complete
2025-11-22 00:29:43.055647 | controller | e057c20fabdd: Verifying Checksum
2025-11-22 00:29:43.055808 | controller | e057c20fabdd: Download complete
2025-11-22 00:29:43.962854 | controller | d16059cdae2e: Verifying Checksum
2025-11-22 00:29:43.963009 | controller | d16059cdae2e: Download complete
2025-11-22 00:29:45.313568 | controller | 431e42d906a3: Verifying Checksum
2025-11-22 00:29:45.313672 | controller | 431e42d906a3: Download complete
2025-11-22 00:29:47.157708 | controller | 7a0fd3b1d7c3: Verifying Checksum
2025-11-22 00:29:48.277913 | controller | 7a0fd3b1d7c3: Download complete
2025-11-22 00:29:48.277990 | controller | aacdb6db0c8c: Verifying Checksum
2025-11-22 00:29:48.278097 | controller | aacdb6db0c8c: Download complete
2025-11-22 00:29:49.224715 | controller | 2e7f04ae8c8b: Verifying Checksum
2025-11-22 00:29:49.224808 | controller | 2e7f04ae8c8b: Download complete
2025-11-22 00:29:50.248302 | controller | 4cd343027a44: Verifying Checksum
2025-11-22 00:29:50.248349 | controller | 4cd343027a44: Download complete
2025-11-22 00:29:51.197045 | controller | 0b4379883f90: Download complete
2025-11-22 00:29:52.334539 | controller | 3d9d6d9dfc91: Verifying Checksum
2025-11-22 00:29:52.334811 | controller | 3d9d6d9dfc91: Download complete
2025-11-22 00:29:55.392310 | controller | f4712b586375: Verifying Checksum
2025-11-22 00:29:55.392402 | controller | f4712b586375: Download complete
2025-11-22 00:30:07.297056 | controller | 57d6314bd293: Verifying Checksum
2025-11-22 00:30:07.297204 | controller | 57d6314bd293: Download complete
2025-11-22 00:30:10.561312 | controller | 774036afeb7f: Verifying Checksum
2025-11-22 00:30:12.703168 | controller | 774036afeb7f: Download complete
2025-11-22 00:30:12.703228 | controller | cef8beb99552: Verifying Checksum
2025-11-22 00:30:15.415919 | controller | cef8beb99552: Download complete
2025-11-22 00:30:15.415979 | controller | f4712b586375: Pull complete
2025-11-22 00:30:15.439924 | controller | d696ed7812f8: Pull complete
2025-11-22 00:30:15.468883 | controller | 29bf28764f14: Pull complete
2025-11-22 00:30:15.517031 | controller | 47d7edb3c2b7: Pull complete
2025-11-22 00:30:15.541894 | controller | 07550ad4f6e4: Pull complete
2025-11-22 00:30:15.564227 | controller | 7e79526e64c5: Pull complete
2025-11-22 00:30:15.587288 | controller | 4fffb32882b7: Pull complete
2025-11-22 00:30:15.637816 | controller | fc7a38b06f4e: Pull complete
2025-11-22 00:30:15.679159 | controller | 6d9d912e060e: Pull complete
2025-11-22 00:30:15.704069 | controller | 5dd542dd28d9: Pull complete
2025-11-22 00:30:23.264893 | controller | 78cf439d9fdb: Verifying Checksum
2025-11-22 00:30:23.882854 | controller | 78cf439d9fdb: Download complete
2025-11-22 00:30:23.882913 | controller | 54b10f0e1645: Verifying Checksum
2025-11-22 00:30:27.187712 | controller | 54b10f0e1645: Download complete
2025-11-22 00:30:27.187799 | controller | 36e5e20969c7: Verifying Checksum
2025-11-22 00:30:27.187840 | controller | 36e5e20969c7: Download complete
2025-11-22 00:30:30.664289 | controller | 4b19ac3f17a8: Verifying Checksum
2025-11-22 00:30:30.664536 | controller | 4b19ac3f17a8: Download complete
2025-11-22 00:30:37.183554 | controller | cef8beb99552: Pull complete
2025-11-22 00:30:37.244390 | controller | bd5557e101ad: Pull complete
2025-11-22 00:30:37.299628 | controller | e057c20fabdd: Pull complete
2025-11-22 00:30:37.374297 | controller | d16059cdae2e: Pull complete
2025-11-22 00:30:39.736772 | controller | 431e42d906a3: Pull complete
2025-11-22 00:30:39.821776 | controller | 7a0fd3b1d7c3: Pull complete
2025-11-22 00:30:39.893112 | controller | aacdb6db0c8c: Pull complete
2025-11-22 00:30:39.936864 | controller | 2e7f04ae8c8b: Pull complete
2025-11-22 00:30:39.977824 | controller | 4cd343027a44: Pull complete
2025-11-22 00:30:40.021110 | controller | 0b4379883f90: Pull complete
2025-11-22 00:30:40.077124 | controller | 3d9d6d9dfc91: Pull complete
2025-11-22 00:30:45.944406 | controller | 57d6314bd293: Pull complete
2025-11-22 00:31:10.201579 | controller | 774036afeb7f: Pull complete
2025-11-22 00:31:37.735030 | controller | 54b10f0e1645: Pull complete
2025-11-22 00:32:06.316183 | controller | 78cf439d9fdb: Pull complete
2025-11-22 00:32:36.106649 | controller | 36e5e20969c7: Pull complete
2025-11-22 00:33:00.779161 | controller | 4b19ac3f17a8: Pull complete
2025-11-22 00:33:00.795717 | controller | Digest: sha256:954c4edffb0905df64e23c8611b1c4a62db9af13062f51cad5b658e727238d41
2025-11-22 00:33:00.799297 | controller | Status: Downloaded newer image for quay.io/ansible/default-test-container:1.10.1
2025-11-22 00:33:00.805976 | controller | quay.io/ansible/default-test-container:1.10.1
2025-11-22 00:33:00.808284 | controller | Run command: docker images quay.io/ansible/pypi-test-container:1.0.0 --format '{{json .}}'
2025-11-22 00:33:00.842546 | controller | Run command: docker pull quay.io/ansible/pypi-test-container:1.0.0
2025-11-22 00:33:08.266327 | controller | 1.0.0: Pulling from ansible/pypi-test-container
2025-11-22 00:33:08.266427 | controller | 04a5f4cda3ee: Pulling fs layer
2025-11-22 00:33:08.266485 | controller | ff496a88c8ed: Pulling fs layer
2025-11-22 00:33:08.266521 | controller | 0ce83f459fe7: Pulling fs layer
2025-11-22 00:33:08.266568 | controller | 2e5170e1f099: Pulling fs layer
2025-11-22 00:33:08.266623 | controller | 7641eb41b08c: Pulling fs layer
2025-11-22 00:33:08.266663 | controller | ad15fa9da398: Pulling fs layer
2025-11-22 00:33:08.266721 | controller | 087d91352424: Pulling fs layer
2025-11-22 00:33:08.266754 | controller | 8b92efd6a100: Pulling fs layer
2025-11-22 00:33:08.266806 | controller | 2e5170e1f099: Waiting
2025-11-22 00:33:08.266838 | controller | 7641eb41b08c: Waiting
2025-11-22 00:33:08.266886 | controller | ad15fa9da398: Waiting
2025-11-22 00:33:08.266917 | controller | 087d91352424: Waiting
2025-11-22 00:33:08.266983 | controller | 8b92efd6a100: Waiting
2025-11-22 00:33:08.632699 | controller | ff496a88c8ed: Verifying Checksum
2025-11-22 00:33:08.667514 | controller | ff496a88c8ed: Download complete
2025-11-22 00:33:08.667567 | controller | 0ce83f459fe7: Verifying Checksum
2025-11-22 00:33:09.027891 | controller | 0ce83f459fe7: Download complete
2025-11-22 00:33:09.028007 | controller | 7641eb41b08c: Verifying Checksum
2025-11-22 00:33:09.145387 | controller | 7641eb41b08c: Download complete
2025-11-22 00:33:09.145446 | controller | 2e5170e1f099: Verifying Checksum
2025-11-22 00:33:09.407092 | controller | 2e5170e1f099: Download complete
2025-11-22 00:33:09.407149 | controller | ad15fa9da398: Verifying Checksum
2025-11-22 00:33:09.567977 | controller | ad15fa9da398: Download complete
2025-11-22 00:33:09.568037 | controller | 087d91352424: Verifying Checksum
2025-11-22 00:33:09.837157 | controller | 087d91352424: Download complete
2025-11-22 00:33:09.837229 | controller | 8b92efd6a100: Verifying Checksum
2025-11-22 00:33:09.837445 | controller | 8b92efd6a100: Download complete
2025-11-22 00:33:09.898092 | controller | 04a5f4cda3ee: Verifying Checksum
2025-11-22 00:33:11.789039 | controller | 04a5f4cda3ee: Download complete
2025-11-22 00:33:11.789114 | controller | 04a5f4cda3ee: Pull complete
2025-11-22 00:33:11.813669 | controller | ff496a88c8ed: Pull complete
2025-11-22 00:33:11.846136 | controller | 0ce83f459fe7: Pull complete
2025-11-22 00:33:13.245754 | controller | 2e5170e1f099: Pull complete
2025-11-22 00:33:13.315562 | controller | 7641eb41b08c: Pull complete
2025-11-22 00:33:13.353493 | controller | ad15fa9da398: Pull complete
2025-11-22 00:33:14.746661 | controller | 087d91352424: Pull complete
2025-11-22 00:33:14.778462 | controller | 8b92efd6a100: Pull complete
2025-11-22 00:33:14.795955 | controller | Digest: sha256:71042ab0a14971b5608fe75706de54f367fc31db573e3b3955182037f73cadb6
2025-11-22 00:33:14.799802 | controller | Status: Downloaded newer image for quay.io/ansible/pypi-test-container:1.0.0
2025-11-22 00:33:14.807113 | controller | quay.io/ansible/pypi-test-container:1.0.0
2025-11-22 00:33:14.809748 | controller | Run command: docker run --detach quay.io/ansible/pypi-test-container:1.0.0
2025-11-22 00:33:22.422272 | controller | Run command: docker inspect 320c99751dd5d05fbf7b01302aefcde55c751601361d15123bb417d2f0e7ffef
2025-11-22 00:33:22.639205 | controller | Scanning collection root: /home/zuul/.ansible/collections/ansible_collections
2025-11-22 00:33:22.653473 | controller | Including collection: ansible.netcommon (292 files)
2025-11-22 00:33:22.653540 | controller | Including collection: ansible.network (23 files)
2025-11-22 00:33:22.664633 | controller | Including collection: ansible.utils (442 files)
2025-11-22 00:33:22.708171 | controller | Including collection: arista.eos (1022 files)
2025-11-22 00:33:22.753359 | controller | Including collection: cisco.ios (1172 files)
2025-11-22 00:33:22.798160 | controller | Including collection: cisco.iosxr (1043 files)
2025-11-22 00:33:22.877025 | controller | Including collection: cisco.nxos (1684 files)
2025-11-22 00:33:22.922438 | controller | Including collection: junipernetworks.junos (963 files)
2025-11-22 00:33:22.965108 | controller | Creating a payload archive containing 8378 files...
2025-11-22 00:33:25.567498 | controller | Created a 6889185 byte payload archive containing 8378 files in 2 seconds.
2025-11-22 00:33:25.590447 | controller | Assuming Docker is available on localhost.
2025-11-22 00:33:25.594011 | 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-22 00:33:26.219700 | controller | Run command: docker exec -i 8343d7da96480aaa6a99c91cd1faf9a106333e42148c96fb6402035ad85635a2 dd of=/root/docker.sh bs=65536
2025-11-22 00:33:26.310029 | controller | Run command: docker exec 8343d7da96480aaa6a99c91cd1faf9a106333e42148c96fb6402035ad85635a2 /bin/bash /root/docker.sh
2025-11-22 00:33:26.397934 | controller | Run command: docker exec -i 8343d7da96480aaa6a99c91cd1faf9a106333e42148c96fb6402035ad85635a2 dd of=/root/ansible.tgz bs=65536
2025-11-22 00:33:26.519452 | controller | Run command: docker exec 8343d7da96480aaa6a99c91cd1faf9a106333e42148c96fb6402035ad85635a2 mkdir /root/ansible
2025-11-22 00:33:26.586514 | controller | Run command: docker exec 8343d7da96480aaa6a99c91cd1faf9a106333e42148c96fb6402035ad85635a2 tar oxzf /root/ansible.tgz -C /root/ansible
2025-11-22 00:33:27.769563 | controller | Run command: docker exec 8343d7da96480aaa6a99c91cd1faf9a106333e42148c96fb6402035ad85635a2 /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-ngxgeszp.json --truncate 0 --redact --color no --requirements --pypi-endpoint http://172.17.0.2:3141/root/pypi/+simple/
2025-11-22 00:33:28.252862 | controller | Injecting custom PyPI hosts entries: /etc/hosts
2025-11-22 00:33:28.253133 | controller | Injecting custom PyPI config: /root/.pip/pip.conf
2025-11-22 00:33:28.253545 | controller | Injecting custom PyPI config: /root/.pydistutils.cfg
2025-11-22 00:33:28.255398 | controller | Run command: /usr/bin/python3.6 /root/ansible/test/lib/ansible_test/_data/yamlcheck.py
2025-11-22 00:33:28.323712 | controller | Running sanity test 'action-plugin-docs' with Python 3.6
2025-11-22 00:33:28.327025 | controller | Read 0 sanity test ignore line(s) for Ansible 2.9 from: tests/sanity/ignore-2.9.txt
2025-11-22 00:33:28.334159 | controller | No tests applicable.
2025-11-22 00:33:28.334310 | controller | Running sanity test 'ansible-doc' with Python 3.6
2025-11-22 00:33:28.334573 | controller | No tests applicable.
2025-11-22 00:33:28.334925 | controller | Run command: /usr/bin/python2.6 /root/ansible/test/lib/ansible_test/_data/yamlcheck.py
2025-11-22 00:33:28.408837 | controller | Running sanity test 'compile' with Python 2.6
2025-11-22 00:33:28.409235 | controller | No tests applicable.
2025-11-22 00:33:28.409655 | controller | Run command: /usr/bin/python2.7 /root/ansible/test/lib/ansible_test/_data/yamlcheck.py
2025-11-22 00:33:28.602887 | controller | Running sanity test 'compile' with Python 2.7
2025-11-22 00:33:28.603013 | controller | No tests applicable.
2025-11-22 00:33:28.605270 | controller | Run command: /usr/bin/python3.5 /root/ansible/test/lib/ansible_test/_data/yamlcheck.py
2025-11-22 00:33:28.723019 | controller | Running sanity test 'compile' with Python 3.5
2025-11-22 00:33:28.723374 | controller | No tests applicable.
2025-11-22 00:33:28.723685 | controller | Running sanity test 'compile' with Python 3.6
2025-11-22 00:33:28.723704 | controller | No tests applicable.
2025-11-22 00:33:28.724124 | controller | Run command: /usr/bin/python3.7 /root/ansible/test/lib/ansible_test/_data/yamlcheck.py
2025-11-22 00:33:28.852438 | controller | Running sanity test 'compile' with Python 3.7
2025-11-22 00:33:28.853082 | controller | No tests applicable.
2025-11-22 00:33:28.854362 | controller | Run command: /usr/bin/python3.8 /root/ansible/test/lib/ansible_test/_data/yamlcheck.py
2025-11-22 00:33:28.981481 | controller | Running sanity test 'compile' with Python 3.8
2025-11-22 00:33:28.982033 | controller | No tests applicable.
2025-11-22 00:33:28.982254 | controller | Running sanity test 'empty-init' with Python 3.6
2025-11-22 00:33:28.982561 | controller | No tests applicable.
2025-11-22 00:33:28.982768 | controller | Running sanity test 'future-import-boilerplate' with Python 3.6
2025-11-22 00:33:28.983046 | controller | No tests applicable.
2025-11-22 00:33:28.983220 | controller | Running sanity test 'ignores'
2025-11-22 00:33:28.984841 | 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-22 00:33:29.695006 | controller | Ignoring packaging: markers 'python_version < "3.6"' don't match your environment
2025-11-22 00:33:29.698311 | controller | Ignoring coverage: markers 'python_version > "3.7"' don't match your environment
2025-11-22 00:33:29.699852 | controller | Ignoring cryptography: markers 'python_version < "2.7"' don't match your environment
2025-11-22 00:33:29.702658 | controller | Ignoring deepdiff: markers 'python_version < "3"' don't match your environment
2025-11-22 00:33:29.704379 | controller | Ignoring jinja2: markers 'python_version < "2.7"' don't match your environment
2025-11-22 00:33:29.705939 | controller | Ignoring urllib3: markers 'python_version < "2.7"' don't match your environment
2025-11-22 00:33:29.709270 | controller | Ignoring sphinx: markers 'python_version < "2.7"' don't match your environment
2025-11-22 00:33:29.716902 | controller | Ignoring wheel: markers 'python_version < "2.7"' don't match your environment
2025-11-22 00:33:29.718390 | controller | Ignoring yamllint: markers 'python_version < "2.7"' don't match your environment
2025-11-22 00:33:29.724824 | controller | Ignoring paramiko: markers 'python_version < "2.7"' don't match your environment
2025-11-22 00:33:29.729125 | controller | Ignoring pytest: markers 'python_version < "2.7"' don't match your environment
2025-11-22 00:33:29.731050 | controller | Ignoring pytest: markers 'python_version == "2.7"' don't match your environment
2025-11-22 00:33:29.732559 | controller | Ignoring pytest-forked: markers 'python_version < "2.7"' don't match your environment
2025-11-22 00:33:29.736504 | controller | Ignoring requests: markers 'python_version < "2.7"' don't match your environment
2025-11-22 00:33:29.743460 | controller | Ignoring virtualenv: markers 'python_version < "2.7"' don't match your environment
2025-11-22 00:33:29.745191 | controller | Ignoring pathspec: markers 'python_version < "2.7"' don't match your environment
2025-11-22 00:33:29.746912 | controller | Ignoring pyopenssl: markers 'python_version < "2.7"' don't match your environment
2025-11-22 00:33:29.749530 | controller | Ignoring pyyaml: markers 'python_version < "2.7"' don't match your environment
2025-11-22 00:33:29.751002 | controller | Ignoring pycparser: markers 'python_version < "2.7"' don't match your environment
2025-11-22 00:33:29.754446 | controller | Ignoring xmltodict: markers 'python_version < "2.7"' don't match your environment
2025-11-22 00:33:29.755984 | controller | Ignoring lxml: markers 'python_version < "2.7"' don't match your environment
2025-11-22 00:33:29.757694 | controller | Ignoring pyvmomi: markers 'python_version < "2.7"' don't match your environment
2025-11-22 00:33:29.760060 | controller | Ignoring MarkupSafe: markers 'python_version < "3.6"' don't match your environment
2025-11-22 00:33:29.764671 | controller | Ignoring setuptools: markers 'python_version <= "2.7"' don't match your environment
2025-11-22 00:33:29.768540 | controller | Ignoring websocket-client: markers 'python_version < "3"' don't match your environment
2025-11-22 00:33:29.770103 | controller | Ignoring certifi: markers 'python_version < "3"' don't match your environment
2025-11-22 00:33:29.787306 | controller | Looking in indexes: http://172.17.0.2:3141/root/pypi/+simple/
2025-11-22 00:33:29.788574 | 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-22 00:33:29.817098 | 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-22 00:33:29.823317 | 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-22 00:33:29.830106 | 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-22 00:33:29.834964 | 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-22 00:33:29.841914 | 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-22 00:33:29.845754 | 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-22 00:33:29.853461 | 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-22 00:33:29.855962 | 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-22 00:33:29.875572 | 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-22 00:33:29.881741 | 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-22 00:33:29.885525 | 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-22 00:33:29.888083 | 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-22 00:33:29.891546 | 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-22 00:33:29.894465 | controller | Collecting astroid==2.3.3 (from -c /root/ansible/test/lib/ansible_test/_data/requirements/constraints.txt (line 54))
2025-11-22 00:33:44.918034 | 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-22 00:34:00.426447 | 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-22 00:34:16.432032 | 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-22 00:34:33.443986 | 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-22 00:34:52.465066 | 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-22 00:35:07.468921 | 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-22 00:35:07.469972 | 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-22 00:35:07.599430 | 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-22 00:35:07.599615 | controller | Removing custom PyPI config: /root/.pydistutils.cfg
2025-11-22 00:35:07.599921 | controller | Removing custom PyPI config: /root/.pip/pip.conf
2025-11-22 00:35:07.600023 | controller | Removing custom PyPI hosts entries: /etc/hosts
2025-11-22 00:35:07.637664 | controller | Run command: docker exec 8343d7da96480aaa6a99c91cd1faf9a106333e42148c96fb6402035ad85635a2 tar czf /root/output.tgz --exclude .tmp -C /root/ansible/ansible_collections/ansible/network/tests output
2025-11-22 00:35:07.708554 | controller | Run command: docker exec -i 8343d7da96480aaa6a99c91cd1faf9a106333e42148c96fb6402035ad85635a2 dd if=/root/output.tgz bs=65536
2025-11-22 00:35:07.770872 | controller | Run command: tar oxzf /tmp/ansible-result-c8obzi08.tgz -C /home/zuul/.ansible/collections/ansible_collections/ansible/network/tests
2025-11-22 00:35:07.781842 | controller | Run command: docker rm -f 320c99751dd5d05fbf7b01302aefcde55c751601361d15123bb417d2f0e7ffef
2025-11-22 00:35:08.020654 | controller | Run command: docker rm -f 8343d7da96480aaa6a99c91cd1faf9a106333e42148c96fb6402035ad85635a2
2025-11-22 00:35:08.710513 | controller | ERROR: Command "docker exec 8343d7da96480aaa6a99c91cd1faf9a106333e42148c96fb6402035ad85635a2 /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-ngxgeszp.json --truncate 0 --redact --color no --requirements --pypi-endpoint http://172.17.0.2:3141/root/pypi/+simple/" returned exit status 1.
2025-11-22 00:35:09.186138 | controller | ERROR
2025-11-22 00:35:09.186429 | controller | {
2025-11-22 00:35:09.186479 | controller |   "delta": "0:05:43.250481",
2025-11-22 00:35:09.186515 | controller |   "end": "2025-11-22 00:35:08.779858",
2025-11-22 00:35:09.186549 | controller |   "msg": "non-zero return code",
2025-11-22 00:35:09.186583 | controller |   "rc": 1,
2025-11-22 00:35:09.186620 | controller |   "start": "2025-11-22 00:29:25.529377"
2025-11-22 00:35:09.186654 | controller | }
failure
2025-11-22 00:35:09.193118 | 
2025-11-22 00:35:09.193195 | PLAY RECAP
2025-11-22 00:35:09.193272 | controller | ok: 21 changed: 6 unreachable: 0 failed: 1 skipped: 20 rescued: 0 ignored: 1
2025-11-22 00:35:09.193324 | 
2025-11-22 00:35:09.316841 | RUN END RESULT_NORMAL: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/run.yaml@master]
2025-11-22 00:35:09.318624 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master]
2025-11-22 00:35:09.927282 | 
2025-11-22 00:35:09.927394 | PLAY [all]
2025-11-22 00:35:09.948706 | 
2025-11-22 00:35:09.948792 | TASK [fetch-subunit-output : Find stestr or testr executable]
2025-11-22 00:35:12.935138 | controller | changed: non-zero return code
2025-11-22 00:35:12.941552 | 
2025-11-22 00:35:12.941631 | TASK [fetch-subunit-output : Get the list of directories with subunit files]
2025-11-22 00:35:12.966315 | controller | skipping: Conditional result was False
2025-11-22 00:35:12.972847 | 
2025-11-22 00:35:12.972920 | LOOP [fetch-subunit-output : Find any inflight partial subunit files]
2025-11-22 00:35:13.004046 | 
2025-11-22 00:35:13.004173 | LOOP [fetch-subunit-output : Copy any inflight subunit files]
2025-11-22 00:35:13.034972 | 
2025-11-22 00:35:13.035117 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream]
2025-11-22 00:35:13.059607 | controller | skipping: Conditional result was False
2025-11-22 00:35:13.066820 | 
2025-11-22 00:35:13.066895 | LOOP [fetch-subunit-output : Generate subunit file]
2025-11-22 00:35:13.097777 | 
2025-11-22 00:35:13.097914 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory]
2025-11-22 00:35:13.122078 | controller | skipping: Conditional result was False
2025-11-22 00:35:13.129890 | 
2025-11-22 00:35:13.129964 | TASK [fetch-subunit-output : Remove the temporary file]
2025-11-22 00:35:13.154229 | controller | skipping: Conditional result was False
2025-11-22 00:35:13.160732 | 
2025-11-22 00:35:13.160814 | TASK [fetch-subunit-output : Process and fetch subunit results]
2025-11-22 00:35:13.186334 | controller | skipping: Conditional result was False
2025-11-22 00:35:13.221068 | 
2025-11-22 00:35:13.221181 | PLAY RECAP
2025-11-22 00:35:13.221230 | controller | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0
2025-11-22 00:35:13.221251 | 
2025-11-22 00:35:13.360318 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master]
2025-11-22 00:35:13.361297 | POST-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/post.yaml@master]
2025-11-22 00:35:13.986778 | 
2025-11-22 00:35:13.986942 | PLAY [all:!appliance*]
2025-11-22 00:35:14.009823 | 
2025-11-22 00:35:14.009945 | TASK [unregister the node]
2025-11-22 00:35:14.365400 | controller | skipping: Conditional result was False
2025-11-22 00:35:14.378283 | 
2025-11-22 00:35:14.378979 | TASK [include_role : fetch-output]
2025-11-22 00:35:14.439518 | controller | ok
2025-11-22 00:35:14.479741 | 
2025-11-22 00:35:14.479882 | TASK [fetch-output : Set log path for multiple nodes]
2025-11-22 00:35:14.557408 | controller | skipping: Conditional result was False
2025-11-22 00:35:14.565805 | 
2025-11-22 00:35:14.565894 | TASK [fetch-output : Set log path for single node]
2025-11-22 00:35:14.608973 | controller | ok
2025-11-22 00:35:14.616275 | 
2025-11-22 00:35:14.616360 | LOOP [fetch-output : Ensure local output dirs]
2025-11-22 00:35:15.079883 | controller -> localhost | ok: "/var/lib/zuul/builds/7157cc27dcfa441eb0034291280b332e/work/logs"
2025-11-22 00:35:15.373599 | controller -> localhost | changed: "/var/lib/zuul/builds/7157cc27dcfa441eb0034291280b332e/work/artifacts"
2025-11-22 00:35:15.600718 | controller -> localhost | changed: "/var/lib/zuul/builds/7157cc27dcfa441eb0034291280b332e/work/docs"
2025-11-22 00:35:15.619451 | 
2025-11-22 00:35:15.619651 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-11-22 00:35:17.963246 | controller | changed:
2025-11-22 00:35:17.963638 | controller | .d..t...... ./
2025-11-22 00:35:17.963738 | controller | cd+++++++++ controller/
2025-11-22 00:35:17.963823 | controller | changed: All items complete
2025-11-22 00:35:17.963868 | 
2025-11-22 00:35:20.039072 | controller | changed: .d..t...... ./
2025-11-22 00:35:22.114867 | controller | changed: .d..t...... ./
2025-11-22 00:35:22.141942 | 
2025-11-22 00:35:22.142107 | TASK [include_role : fetch-output-openshift]
2025-11-22 00:35:22.169991 | controller | skipping: Conditional result was False
2025-11-22 00:35:22.187276 | 
2025-11-22 00:35:22.187529 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2025-11-22 00:35:22.235415 | controller | skipping: Conditional result was False
2025-11-22 00:35:22.247206 | controller | skipping: Conditional result was False
2025-11-22 00:35:22.300008 | 
2025-11-22 00:35:22.300185 | PLAY [localhost]
2025-11-22 00:35:22.317866 | 
2025-11-22 00:35:22.317947 | TASK [Run Zuul manifest role]
2025-11-22 00:35:22.340818 | localhost | ok
2025-11-22 00:35:22.360534 | 
2025-11-22 00:35:22.360720 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2025-11-22 00:35:22.834880 | localhost | changed
2025-11-22 00:35:22.840271 | 
2025-11-22 00:35:22.840343 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2025-11-22 00:35:22.881668 | localhost | ok
2025-11-22 00:35:22.891427 | 
2025-11-22 00:35:22.891550 | TASK [Set zuul-log-path fact]
2025-11-22 00:35:22.944827 | localhost | ok
2025-11-22 00:35:22.957964 | 
2025-11-22 00:35:22.958078 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-11-22 00:35:22.998651 | localhost | ok
2025-11-22 00:35:23.007365 | 
2025-11-22 00:35:23.007466 | LOOP [Run upload-logs-swift role]
2025-11-22 00:35:23.056431 | localhost | Output suppressed because no_log was given
2025-11-22 00:35:23.087630 | 
2025-11-22 00:35:23.087791 | TASK [Set zuul-log-path fact]
2025-11-22 00:35:23.124590 | localhost | skipping: Conditional result was False
2025-11-22 00:35:23.130822 | 
2025-11-22 00:35:23.130941 | TASK [upload-logs-swift : Ensure logs are readable before uploading]
2025-11-22 00:35:23.574057 | localhost -> localhost | ok: Runtime: 0:00:00.007191
2025-11-22 00:35:23.585853 | 
2025-11-22 00:35:23.586007 | TASK [upload-logs-swift : Upload logs to swift]