2025-11-19 00:21:21.940533 | Job console starting...
2025-11-19 00:21:21.951909 | Updating repositories
2025-11-19 00:21:22.045543 | Preparing job workspace
2025-11-19 00:21:27.417338 | Running Ansible setup...
2025-11-19 00:21:34.894273 | PRE-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master]
2025-11-19 00:21:35.510536 | 
2025-11-19 00:21:35.510650 | PLAY [localhost]
2025-11-19 00:21:35.519124 | 
2025-11-19 00:21:35.519219 | TASK [Gathering Facts]
2025-11-19 00:21:36.420082 | localhost | ok
2025-11-19 00:21:36.445284 | 
2025-11-19 00:21:36.445494 | TASK [Setup log path fact]
2025-11-19 00:21:36.465736 | localhost | ok
2025-11-19 00:21:36.478847 | 
2025-11-19 00:21:36.478975 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-11-19 00:21:36.507705 | localhost | ok
2025-11-19 00:21:36.516649 | 
2025-11-19 00:21:36.516730 | TASK [emit-job-header : Print job information]
2025-11-19 00:21:36.575461 | # Job Information
2025-11-19 00:21:36.575603 | Ansible Version: 2.15.12
2025-11-19 00:21:36.575635 | Job: ansible-test-sanity-docker-stable-2.9
2025-11-19 00:21:36.575659 | Pipeline: periodic
2025-11-19 00:21:36.575678 | Executor: ze01.softwarefactory-project.io
2025-11-19 00:21:36.575696 | Triggered by: https://github.com/ansible-collections/ansible.network
2025-11-19 00:21:36.575717 | Log URL (when completed): https://ansible.softwarefactory-project.io/logs/632/ansible/63270ec1d8164135a5e6038b05c5664f/
2025-11-19 00:21:36.575736 | Event ID: 500c7b88eb4e448ea8cf6a57d3831197
2025-11-19 00:21:36.579820 | 
2025-11-19 00:21:36.579881 | LOOP [emit-job-header : Print node information]
2025-11-19 00:21:36.688435 | localhost | ok:
2025-11-19 00:21:36.688576 | localhost | # Node Information
2025-11-19 00:21:36.688603 | localhost | Inventory Hostname: controller
2025-11-19 00:21:36.688623 | localhost | Hostname: np0005527414
2025-11-19 00:21:36.688642 | localhost | Username: zuul
2025-11-19 00:21:36.688663 | localhost | Distro: Fedora 37
2025-11-19 00:21:36.688681 | localhost | Provider: ansible-vexxhost-ams1
2025-11-19 00:21:36.688698 | localhost | Region: ams1
2025-11-19 00:21:36.688715 | localhost | Label: ansible-fedora-37-1vcpu
2025-11-19 00:21:36.688732 | localhost | Product Name: OpenStack Nova
2025-11-19 00:21:36.688749 | localhost | Interface IP: 38.129.16.43
2025-11-19 00:21:36.697048 | 
2025-11-19 00:21:36.697117 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2025-11-19 00:21:37.081820 | localhost -> localhost | changed
2025-11-19 00:21:37.093462 | 
2025-11-19 00:21:37.093610 | TASK [log-inventory : Copy ansible inventory to logs dir]
2025-11-19 00:21:37.884622 | localhost -> localhost | changed
2025-11-19 00:21:37.906933 | 
2025-11-19 00:21:37.907083 | PLAY [all:!appliance*]
2025-11-19 00:21:37.922674 | 
2025-11-19 00:21:37.922739 | TASK [include_role : start-zuul-console]
2025-11-19 00:21:37.945608 | controller | ok
2025-11-19 00:21:37.966117 | 
2025-11-19 00:21:37.966286 | TASK [start-zuul-console : Start zuul_console daemon.]
2025-11-19 00:21:38.967522 | controller | ok
2025-11-19 00:21:38.984006 | 
2025-11-19 00:21:38.984164 | TASK [use-our-mirror : Retrieve the IP address]
2025-11-19 00:21:41.108964 | controller | ok: Status code was 404 and not [200]: HTTP Error 404: Not Found
2025-11-19 00:21:41.118820 | 
2025-11-19 00:21:41.118923 | TASK [use-our-mirror : Ensure the registry service is running on the mirror]
2025-11-19 00:21:41.482882 | controller | skipping: Conditional result was False
2025-11-19 00:21:41.491811 | 
2025-11-19 00:21:41.491921 | TASK [use-our-mirror : Write the IP in /etc/hosts]
2025-11-19 00:21:41.517201 | controller | skipping: Conditional result was False
2025-11-19 00:21:41.526486 | 
2025-11-19 00:21:41.526605 | TASK [use-our-mirror : Create the podman configuration directory]
2025-11-19 00:21:41.552132 | controller | skipping: Conditional result was False
2025-11-19 00:21:41.561591 | 
2025-11-19 00:21:41.561682 | TASK [use-our-mirror : Copy the podman configuration]
2025-11-19 00:21:41.576707 | controller | skipping: Conditional result was False
2025-11-19 00:21:41.586931 | 
2025-11-19 00:21:41.587093 | TASK [use-our-mirror : Copy the Yum configuration (main)]
2025-11-19 00:21:41.606866 | controller | skipping: Conditional result was False
2025-11-19 00:21:41.616864 | 
2025-11-19 00:21:41.616964 | TASK [use-our-mirror : Copy the Yum configuration (update)]
2025-11-19 00:21:41.632612 | controller | skipping: Conditional result was False
2025-11-19 00:21:41.649904 | 
2025-11-19 00:21:41.650008 | TASK [Disable Fedora Modular]
2025-11-19 00:21:42.779091 | controller | changed
2025-11-19 00:21:42.792281 | 
2025-11-19 00:21:42.792412 | TASK [Enable EPEL]
2025-11-19 00:21:42.830764 | controller | skipping: Conditional result was False
2025-11-19 00:21:42.845147 | 
2025-11-19 00:21:42.845288 | TASK [Register the RHEL node]
2025-11-19 00:21:43.260783 | 
2025-11-19 00:21:43.261138 | TASK [Show the subscription-manager status]
2025-11-19 00:21:43.640240 | controller | skipping: Conditional result was False
2025-11-19 00:21:43.649442 | 
2025-11-19 00:21:43.649523 | TASK [Enable EPEL on RHEL]
2025-11-19 00:21:44.036571 | controller | skipping: Conditional result was False
2025-11-19 00:21:44.048959 | 
2025-11-19 00:21:44.049130 | TASK [Install git and tox]
2025-11-19 00:23:36.059246 | controller | changed
2025-11-19 00:23:36.065649 | 
2025-11-19 00:23:36.065733 | TASK [include_role : prepare-workspace]
2025-11-19 00:23:36.095742 | controller | ok
2025-11-19 00:23:36.117223 | 
2025-11-19 00:23:36.117339 | TASK [prepare-workspace : Start zuul_console daemon.]
2025-11-19 00:23:36.948322 | controller | ok
2025-11-19 00:23:36.962970 | 
2025-11-19 00:23:36.963315 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2025-11-19 00:23:56.590114 | controller | Output suppressed because no_log was given
2025-11-19 00:23:56.612461 | 
2025-11-19 00:23:56.612620 | TASK [include_role : prepare-workspace-openshift]
2025-11-19 00:23:56.635558 | controller | skipping: Conditional result was False
2025-11-19 00:23:56.679088 | 
2025-11-19 00:23:56.679183 | PLAY [all:!appliance]
2025-11-19 00:23:56.704480 | 
2025-11-19 00:23:56.704630 | TASK [Run add-build-sshkey role (RSA)]
2025-11-19 00:23:56.726624 | controller | ok
2025-11-19 00:23:56.747966 | 
2025-11-19 00:23:56.748099 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2025-11-19 00:23:57.018714 | controller -> localhost | ok
2025-11-19 00:23:57.027152 | 
2025-11-19 00:23:57.027289 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2025-11-19 00:23:57.056122 | controller | ok
2025-11-19 00:23:57.082710 | controller | included: /var/lib/zuul/builds/63270ec1d8164135a5e6038b05c5664f/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2025-11-19 00:23:57.090823 | 
2025-11-19 00:23:57.090909 | TASK [add-build-sshkey : Create Temp SSH key]
2025-11-19 00:23:57.616742 | controller -> localhost | Generating public/private rsa key pair.
2025-11-19 00:23:57.616927 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/63270ec1d8164135a5e6038b05c5664f/work/63270ec1d8164135a5e6038b05c5664f_id_rsa.
2025-11-19 00:23:57.616955 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/63270ec1d8164135a5e6038b05c5664f/work/63270ec1d8164135a5e6038b05c5664f_id_rsa.pub.
2025-11-19 00:23:57.616976 | controller -> localhost | The key fingerprint is:
2025-11-19 00:23:57.616994 | controller -> localhost | SHA256:X9g9bXC6UGDP0HDg5E0BKfTvv6+A7+mCiRakN6M89Nw zuul-build-sshkey
2025-11-19 00:23:57.617013 | controller -> localhost | The key's randomart image is:
2025-11-19 00:23:57.617047 | controller -> localhost | +---[RSA 2048]----+
2025-11-19 00:23:57.617071 | controller -> localhost | |         .. O*+. |
2025-11-19 00:23:57.617090 | controller -> localhost | |          .*.O.  |
2025-11-19 00:23:57.617109 | controller -> localhost | |           .+ * .|
2025-11-19 00:23:57.617126 | controller -> localhost | |      .    o + = |
2025-11-19 00:23:57.617144 | controller -> localhost | |     o  S . + = o|
2025-11-19 00:23:57.617169 | controller -> localhost | |    o =  . o o + |
2025-11-19 00:23:57.617189 | controller -> localhost | |   o = * oo . o  |
2025-11-19 00:23:57.617208 | controller -> localhost | |    + = E .. o . |
2025-11-19 00:23:57.617226 | controller -> localhost | |     o     += .o=|
2025-11-19 00:23:57.617247 | controller -> localhost | +----[SHA256]-----+
2025-11-19 00:23:57.617291 | controller -> localhost | ok: Runtime: 0:00:00.097063
2025-11-19 00:23:57.623288 | 
2025-11-19 00:23:57.623346 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2025-11-19 00:23:57.643499 | controller | ok
2025-11-19 00:23:57.652904 | controller | included: /var/lib/zuul/builds/63270ec1d8164135a5e6038b05c5664f/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2025-11-19 00:23:57.661448 | 
2025-11-19 00:23:57.661507 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2025-11-19 00:23:57.675743 | controller | skipping: Conditional result was False
2025-11-19 00:23:57.682359 | 
2025-11-19 00:23:57.682421 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2025-11-19 00:23:58.746235 | controller | changed
2025-11-19 00:23:58.758954 | 
2025-11-19 00:23:58.759133 | TASK [add-build-sshkey : Make sure user has a .ssh]
2025-11-19 00:23:59.401082 | controller | ok
2025-11-19 00:23:59.413784 | 
2025-11-19 00:23:59.413922 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2025-11-19 00:24:02.390861 | controller | changed
2025-11-19 00:24:02.403145 | 
2025-11-19 00:24:02.403283 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2025-11-19 00:24:05.438512 | controller | changed
2025-11-19 00:24:05.451289 | 
2025-11-19 00:24:05.451491 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2025-11-19 00:24:05.479286 | controller | skipping: Conditional result was False
2025-11-19 00:24:05.493774 | 
2025-11-19 00:24:05.493922 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2025-11-19 00:24:05.950602 | controller -> localhost | changed
2025-11-19 00:24:05.969566 | 
2025-11-19 00:24:05.969680 | TASK [add-build-sshkey : Add back temp key]
2025-11-19 00:24:06.271729 | controller -> localhost | Identity added: /var/lib/zuul/builds/63270ec1d8164135a5e6038b05c5664f/work/63270ec1d8164135a5e6038b05c5664f_id_rsa (zuul-build-sshkey)
2025-11-19 00:24:06.271979 | controller -> localhost | ok: Runtime: 0:00:00.008929
2025-11-19 00:24:06.278408 | 
2025-11-19 00:24:06.278470 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2025-11-19 00:24:07.186321 | controller | ok
2025-11-19 00:24:07.197238 | 
2025-11-19 00:24:07.197362 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2025-11-19 00:24:07.224612 | controller | skipping: Conditional result was False
2025-11-19 00:24:07.248439 | 
2025-11-19 00:24:07.248572 | TASK [Run add-build-sshkey role (ECDSA)]
2025-11-19 00:24:07.272227 | controller | ok
2025-11-19 00:24:07.295661 | 
2025-11-19 00:24:07.295753 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2025-11-19 00:24:07.513782 | controller -> localhost | ok
2025-11-19 00:24:07.520636 | 
2025-11-19 00:24:07.520704 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2025-11-19 00:24:07.540732 | controller | ok
2025-11-19 00:24:07.551636 | controller | included: /var/lib/zuul/builds/63270ec1d8164135a5e6038b05c5664f/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2025-11-19 00:24:07.557926 | 
2025-11-19 00:24:07.557989 | TASK [add-build-sshkey : Create Temp SSH key]
2025-11-19 00:24:07.805934 | controller -> localhost | Generating public/private ecdsa key pair.
2025-11-19 00:24:07.806130 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/63270ec1d8164135a5e6038b05c5664f/work/63270ec1d8164135a5e6038b05c5664f_id_ecdsa.
2025-11-19 00:24:07.806160 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/63270ec1d8164135a5e6038b05c5664f/work/63270ec1d8164135a5e6038b05c5664f_id_ecdsa.pub.
2025-11-19 00:24:07.806191 | controller -> localhost | The key fingerprint is:
2025-11-19 00:24:07.806212 | controller -> localhost | SHA256:2nE5LdkWbyu81Wqz6D5GK8xfwC+L7GfXvxeSLiYylj8 zuul-build-sshkey
2025-11-19 00:24:07.806231 | controller -> localhost | The key's randomart image is:
2025-11-19 00:24:07.806250 | controller -> localhost | +---[ECDSA 521]---+
2025-11-19 00:24:07.806268 | controller -> localhost | |                 |
2025-11-19 00:24:07.806285 | controller -> localhost | |                 |
2025-11-19 00:24:07.806302 | controller -> localhost | |            .    |
2025-11-19 00:24:07.806319 | controller -> localhost | |           * o   |
2025-11-19 00:24:07.806336 | controller -> localhost | |        S * * +  |
2025-11-19 00:24:07.806353 | controller -> localhost | |       o o =.* + |
2025-11-19 00:24:07.806370 | controller -> localhost | |      . oo .=.*.o|
2025-11-19 00:24:07.806387 | controller -> localhost | |       = E++*X+.o|
2025-11-19 00:24:07.806404 | controller -> localhost | |      . +oBOO+o+=|
2025-11-19 00:24:07.806421 | controller -> localhost | +----[SHA256]-----+
2025-11-19 00:24:07.806468 | controller -> localhost | ok: Runtime: 0:00:00.010344
2025-11-19 00:24:07.815374 | 
2025-11-19 00:24:07.815439 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2025-11-19 00:24:07.835754 | controller | ok
2025-11-19 00:24:07.843642 | controller | included: /var/lib/zuul/builds/63270ec1d8164135a5e6038b05c5664f/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2025-11-19 00:24:07.852840 | 
2025-11-19 00:24:07.852903 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2025-11-19 00:24:07.876670 | controller | skipping: Conditional result was False
2025-11-19 00:24:07.882838 | 
2025-11-19 00:24:07.882902 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2025-11-19 00:24:08.754174 | controller | changed
2025-11-19 00:24:08.761337 | 
2025-11-19 00:24:08.761465 | TASK [add-build-sshkey : Make sure user has a .ssh]
2025-11-19 00:24:09.382718 | controller | ok
2025-11-19 00:24:09.397997 | 
2025-11-19 00:24:09.398223 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2025-11-19 00:24:12.385750 | controller | changed
2025-11-19 00:24:12.399377 | 
2025-11-19 00:24:12.399531 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2025-11-19 00:24:15.390924 | controller | changed
2025-11-19 00:24:15.404777 | 
2025-11-19 00:24:15.404917 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2025-11-19 00:24:15.433756 | controller | skipping: Conditional result was False
2025-11-19 00:24:15.451270 | 
2025-11-19 00:24:15.451420 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2025-11-19 00:24:15.775648 | controller -> localhost | changed
2025-11-19 00:24:15.800253 | 
2025-11-19 00:24:15.800395 | TASK [add-build-sshkey : Add back temp key]
2025-11-19 00:24:16.114794 | controller -> localhost | Identity added: /var/lib/zuul/builds/63270ec1d8164135a5e6038b05c5664f/work/63270ec1d8164135a5e6038b05c5664f_id_ecdsa (zuul-build-sshkey)
2025-11-19 00:24:16.115275 | controller -> localhost | ok: Runtime: 0:00:00.012922
2025-11-19 00:24:16.129404 | 
2025-11-19 00:24:16.129543 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2025-11-19 00:24:16.872487 | controller | ok
2025-11-19 00:24:16.884467 | 
2025-11-19 00:24:16.884603 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2025-11-19 00:24:16.921930 | controller | skipping: Conditional result was False
2025-11-19 00:24:16.946533 | 
2025-11-19 00:24:16.946677 | TASK [include_role : remove-zuul-sshkey]
2025-11-19 00:24:16.974490 | controller | skipping: Conditional result was False
2025-11-19 00:24:16.988136 | 
2025-11-19 00:24:16.988274 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2025-11-19 00:24:17.823339 | controller | ok: "logs"
2025-11-19 00:24:17.823663 | controller | ok: All items complete
2025-11-19 00:24:17.823707 | 
2025-11-19 00:24:18.347363 | controller | ok: "artifacts"
2025-11-19 00:24:18.879293 | controller | ok: "docs"
2025-11-19 00:24:18.899911 | 
2025-11-19 00:24:18.900118 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2025-11-19 00:24:19.486122 | controller | changed: "logs"
2025-11-19 00:24:20.089782 | controller | changed: "artifacts"
2025-11-19 00:24:20.623520 | controller | changed: "docs"
2025-11-19 00:24:20.692753 | 
2025-11-19 00:24:20.692928 | PLAY RECAP
2025-11-19 00:24:20.693082 | controller | ok: 30 changed: 16 unreachable: 0 failed: 0 skipped: 18 rescued: 0 ignored: 0
2025-11-19 00:24:20.693196 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-11-19 00:24:20.693276 | 
2025-11-19 00:24:20.834575 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master]
2025-11-19 00:24:20.835689 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master]
2025-11-19 00:24:21.393490 | 
2025-11-19 00:24:21.393601 | PLAY [all]
2025-11-19 00:24:21.416188 | 
2025-11-19 00:24:21.416271 | TASK [Install binary dependencies]
2025-11-19 00:24:21.502818 | controller | ok
2025-11-19 00:24:21.535912 | 
2025-11-19 00:24:21.536067 | TASK [bindep : Include find tasks]
2025-11-19 00:24:21.578503 | controller | ok
2025-11-19 00:24:21.588756 | controller | included: /var/lib/zuul/builds/63270ec1d8164135a5e6038b05c5664f/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find.yaml
2025-11-19 00:24:21.597191 | 
2025-11-19 00:24:21.597275 | TASK [bindep : Look for bindep.txt]
2025-11-19 00:24:22.516455 | controller | ok
2025-11-19 00:24:22.522672 | 
2025-11-19 00:24:22.522740 | TASK [bindep : Define bindep_file fact]
2025-11-19 00:24:22.546915 | controller | skipping: Conditional result was False
2025-11-19 00:24:22.553045 | 
2025-11-19 00:24:22.553111 | TASK [bindep : Look for other-requirements.txt]
2025-11-19 00:24:23.178788 | controller | ok
2025-11-19 00:24:23.193617 | 
2025-11-19 00:24:23.193805 | TASK [bindep : Define bindep_file fact]
2025-11-19 00:24:23.229273 | controller | skipping: Conditional result was False
2025-11-19 00:24:23.243189 | 
2025-11-19 00:24:23.243347 | TASK [bindep : Look for bindep fallback file]
2025-11-19 00:24:23.270088 | controller | skipping: Conditional result was False
2025-11-19 00:24:23.285260 | 
2025-11-19 00:24:23.285477 | TASK [bindep : Define bindep_file fact]
2025-11-19 00:24:23.322621 | controller | skipping: Conditional result was False
2025-11-19 00:24:23.336639 | 
2025-11-19 00:24:23.336771 | TASK [bindep : Include bindep tasks]
2025-11-19 00:24:23.363357 | controller | skipping: Conditional result was False
2025-11-19 00:24:23.376595 | 
2025-11-19 00:24:23.376725 | TASK [bindep : Include install tasks]
2025-11-19 00:24:23.403659 | controller | skipping: Conditional result was False
2025-11-19 00:24:23.417416 | 
2025-11-19 00:24:23.417560 | LOOP [bindep : Include package tasks]
2025-11-19 00:24:23.502457 | 
2025-11-19 00:24:23.502729 | TASK [Run test-setup role]
2025-11-19 00:24:23.531215 | controller | ok
2025-11-19 00:24:23.560134 | 
2025-11-19 00:24:23.560222 | TASK [test-setup : Check if project's tools/test-setup.sh exists]
2025-11-19 00:24:24.201139 | controller | ok
2025-11-19 00:24:24.215148 | 
2025-11-19 00:24:24.215295 | TASK [test-setup : Run tools/test-setup.sh]
2025-11-19 00:24:24.587671 | controller | skipping: Conditional result was False
2025-11-19 00:24:24.623843 | 
2025-11-19 00:24:24.623952 | PLAY RECAP
2025-11-19 00:24:24.624007 | controller | ok: 4 changed: 0 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0
2025-11-19 00:24:24.624088 | 
2025-11-19 00:24:24.731877 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master]
2025-11-19 00:24:24.732944 | PRE-RUN START: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master]
2025-11-19 00:24:25.350544 | 
2025-11-19 00:24:25.350713 | PLAY [controller]
2025-11-19 00:24:25.376284 | 
2025-11-19 00:24:25.376426 | TASK [Create the /root directory]
2025-11-19 00:24:26.459267 | controller | ok
2025-11-19 00:24:26.474092 | 
2025-11-19 00:24:26.474256 | TASK [Install glibc-langpack-en]
2025-11-19 00:24:33.677059 | controller | ok: Nothing to do
2025-11-19 00:24:33.692381 | 
2025-11-19 00:24:33.692522 | TASK [Ensure controller directory exists]
2025-11-19 00:24:34.531645 | controller | changed
2025-11-19 00:24:34.537138 | 
2025-11-19 00:24:34.537199 | TASK [Install container runtime]
2025-11-19 00:24:34.577106 | controller | ok
2025-11-19 00:24:34.615850 | 
2025-11-19 00:24:34.615951 | LOOP [ensure-docker : Gather variables for each operating system]
2025-11-19 00:24:34.648550 | controller | ok: "/var/lib/zuul/builds/63270ec1d8164135a5e6038b05c5664f/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/vars/fedora.yaml"
2025-11-19 00:24:34.656327 | 
2025-11-19 00:24:34.656390 | TASK [ensure-docker : Sanity check for distro selection]
2025-11-19 00:24:34.670654 | controller | skipping: Conditional result was False
2025-11-19 00:24:34.676445 | 
2025-11-19 00:24:34.676512 | TASK [ensure-docker : Sanity check for distro install]
2025-11-19 00:24:34.700723 | controller | skipping: Conditional result was False
2025-11-19 00:24:34.706431 | 
2025-11-19 00:24:34.706498 | TASK [ensure-docker : Sanity check for upstream install]
2025-11-19 00:24:34.730595 | controller | skipping: Conditional result was False
2025-11-19 00:24:34.737328 | 
2025-11-19 00:24:34.737392 | TASK [ensure-docker : Sanity check for upstream install mirrors]
2025-11-19 00:24:34.761790 | controller | skipping: Conditional result was False
2025-11-19 00:24:34.769923 | 
2025-11-19 00:24:34.770183 | TASK [ensure-docker : Include tasks for docker install]
2025-11-19 00:24:34.792536 | controller | ok
2025-11-19 00:24:34.803826 | controller | included: /var/lib/zuul/builds/63270ec1d8164135a5e6038b05c5664f/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/tasks/docker-upstream.yaml
2025-11-19 00:24:34.815737 | 
2025-11-19 00:24:34.815830 | TASK [ensure-docker : Not Implemented]
2025-11-19 00:24:34.830608 | controller | skipping: Conditional result was False
2025-11-19 00:24:34.836796 | 
2025-11-19 00:24:34.836860 | TASK [Set up docker mirrors]
2025-11-19 00:24:34.867863 | controller | ok
2025-11-19 00:24:34.886442 | 
2025-11-19 00:24:34.886510 | TASK [use-docker-mirror : Set docker_mirror fact from mirror_info]
2025-11-19 00:24:34.910681 | controller | skipping: Conditional result was False
2025-11-19 00:24:34.916771 | 
2025-11-19 00:24:34.916834 | TASK [use-docker-mirror : Set docker_mirror fact from zuul_site_mirror_info]
2025-11-19 00:24:34.941745 | controller | skipping: Conditional result was False
2025-11-19 00:24:34.948107 | 
2025-11-19 00:24:34.948169 | TASK [use-docker-mirror : Set docker_mirror fact]
2025-11-19 00:24:34.972624 | controller | skipping: Conditional result was False
2025-11-19 00:24:34.979775 | 
2025-11-19 00:24:34.979839 | TASK [use-docker-mirror : Set up docker mirrors]
2025-11-19 00:24:35.034606 | controller | skipping: Conditional result was False
2025-11-19 00:24:35.069567 | 
2025-11-19 00:24:35.069676 | TASK [Add all repositories]
2025-11-19 00:24:35.089275 | controller | ok
2025-11-19 00:24:35.111971 | 
2025-11-19 00:24:35.112070 | LOOP [ensure-package-repositories : Install repositories]
2025-11-19 00:24:35.135144 | controller | ok: "/var/lib/zuul/builds/63270ec1d8164135a5e6038b05c5664f/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-package-repositories/tasks/RedHat.yaml"
2025-11-19 00:24:35.135334 | controller | ok: All items complete
2025-11-19 00:24:35.135360 | 
2025-11-19 00:24:35.143935 | controller | included: /var/lib/zuul/builds/63270ec1d8164135a5e6038b05c5664f/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-package-repositories/tasks/RedHat.yaml
2025-11-19 00:24:35.149931 | 
2025-11-19 00:24:35.149994 | TASK [ensure-package-repositories : Create GPG key temp directory]
2025-11-19 00:24:36.035606 | controller | changed
2025-11-19 00:24:36.044323 | 
2025-11-19 00:24:36.044432 | LOOP [ensure-package-repositories : Upload GPG keys]
2025-11-19 00:24:39.186475 | controller | changed:
2025-11-19 00:24:39.186654 | controller | {
2025-11-19 00:24:39.186686 | 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-19 00:24:39.186719 | controller | }
2025-11-19 00:24:39.204481 | 
2025-11-19 00:24:39.204623 | LOOP [ensure-package-repositories : Import GPG keys]
2025-11-19 00:24:40.404398 | controller | changed:
2025-11-19 00:24:40.404599 | controller | {
2025-11-19 00:24:40.404636 | 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-19 00:24:40.404667 | controller | }
2025-11-19 00:24:40.404706 | controller | changed: All items complete
2025-11-19 00:24:40.404733 | 
2025-11-19 00:24:40.415142 | 
2025-11-19 00:24:40.415249 | TASK [ensure-package-repositories : Remove GPG temp directory]
2025-11-19 00:24:41.102874 | controller | changed
2025-11-19 00:24:41.137482 | 
2025-11-19 00:24:41.137643 | LOOP [ensure-package-repositories : Add repositories]
2025-11-19 00:24:41.957584 | controller | changed:
2025-11-19 00:24:41.957859 | controller | {
2025-11-19 00:24:41.957913 | controller |   "baseurl": "https://download.docker.com/linux/fedora/$releasever/$basearch/stable",
2025-11-19 00:24:41.957955 | controller |   "description": "Docker CE Stable - $basearch",
2025-11-19 00:24:41.957992 | controller |   "gpgcheck": true,
2025-11-19 00:24:41.958069 | controller |   "name": "docker-ce-stable"
2025-11-19 00:24:41.958109 | controller | }
2025-11-19 00:24:41.980505 | 
2025-11-19 00:24:41.980661 | TASK [ensure-docker : Install pre-reqs]
2025-11-19 00:24:47.355443 | controller | ok: Nothing to do
2025-11-19 00:24:47.367740 | 
2025-11-19 00:24:47.367872 | TASK [ensure-docker : Remove packages]
2025-11-19 00:24:50.638406 | controller | ok: Nothing to do
2025-11-19 00:24:50.650900 | 
2025-11-19 00:24:50.651092 | TASK [ensure-docker : Install upstream docker using package]
2025-11-19 00:26:07.878688 | controller | changed
2025-11-19 00:26:07.892481 | 
2025-11-19 00:26:07.892624 | TASK [ensure-docker : Setup docker]
2025-11-19 00:26:07.925202 | controller | ok
2025-11-19 00:26:07.945576 | controller | included: /var/lib/zuul/builds/63270ec1d8164135a5e6038b05c5664f/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/tasks/docker-setup.yaml
2025-11-19 00:26:07.967562 | 
2025-11-19 00:26:07.967709 | TASK [ensure-docker : Ensure "docker" group exists]
2025-11-19 00:26:08.990639 | controller | ok
2025-11-19 00:26:09.000452 | 
2025-11-19 00:26:09.000568 | TASK [ensure-docker : Add user to docker group]
2025-11-19 00:26:10.057640 | controller | changed
2025-11-19 00:26:10.113216 | 
2025-11-19 00:26:10.113357 | TASK [Update docker daemon proxy configuration]
2025-11-19 00:26:10.149183 | controller | skipping: Conditional result was False
2025-11-19 00:26:10.158408 | 
2025-11-19 00:26:10.158508 | TASK [Lower default MTU]
2025-11-19 00:26:10.189107 | controller | skipping: Conditional result was False
2025-11-19 00:26:10.204873 | 
2025-11-19 00:26:10.205079 | TASK [ensure-docker : Get the status of the docket.socket unit]
2025-11-19 00:26:10.995593 | controller | inactive
2025-11-19 00:26:11.581353 | controller | ok: Runtime: 0:00:00.022969
2025-11-19 00:26:11.596382 | 
2025-11-19 00:26:11.596587 | TASK [ensure-docker : Get the status of the docket unit]
2025-11-19 00:26:12.332553 | controller | inactive
2025-11-19 00:26:12.477649 | controller | ok: Runtime: 0:00:00.026260
2025-11-19 00:26:12.485423 | 
2025-11-19 00:26:12.485530 | TASK [ensure-docker : Restart docker]
2025-11-19 00:26:12.537547 | Trigger a Docker restart
2025-11-19 00:26:12.544064 | 
2025-11-19 00:26:12.544143 | TASK [ensure-docker : Reset ssh connection to pick up docker group]
2025-11-19 00:26:12.581525 | 
2025-11-19 00:26:12.581642 | TASK [ensure-docker : Install docker-compose]
2025-11-19 00:26:12.606953 | controller | skipping: Conditional result was False
2025-11-19 00:26:12.614608 | 
2025-11-19 00:26:12.614715 | TASK [ensure-docker : Flush handlers before role exit]
2025-11-19 00:26:12.630376 | 
2025-11-19 00:26:12.630530 | TASK [ensure-docker : Stop docker.socket to avoid any conflict]
2025-11-19 00:26:15.981339 | controller | changed
2025-11-19 00:26:15.988866 | 
2025-11-19 00:26:15.988973 | TASK [ensure-docker : Assure docker service is running]
2025-11-19 00:26:19.666601 | controller | changed
2025-11-19 00:26:19.682492 | 
2025-11-19 00:26:19.682625 | TASK [ensure-docker : Assure docker.socket service is running]
2025-11-19 00:26:21.753923 | controller | ok
2025-11-19 00:26:21.770942 | 
2025-11-19 00:26:21.771109 | TASK [ensure-docker : Correct group ownership on docker sock]
2025-11-19 00:26:22.663729 | controller | ok
2025-11-19 00:26:22.676550 | 
2025-11-19 00:26:22.676685 | TASK [ensure-docker : Validate ability to talk with docker]
2025-11-19 00:26:23.329078 | controller | CONTAINER ID   IMAGE     COMMAND   CREATED   STATUS    PORTS     NAMES
2025-11-19 00:26:23.548105 | controller | ok: Runtime: 0:00:00.044332
2025-11-19 00:26:23.555955 | 
2025-11-19 00:26:23.556049 | TASK [ensure-docker : Get version details]
2025-11-19 00:26:24.168236 | controller | Client: Docker Engine - Community
2025-11-19 00:26:24.168479 | controller |  Version:           24.0.7
2025-11-19 00:26:24.168559 | controller |  API version:       1.43
2025-11-19 00:26:24.168603 | controller |  Go version:        go1.20.10
2025-11-19 00:26:24.168660 | controller |  Git commit:        afdd53b
2025-11-19 00:26:24.168710 | controller |  Built:             Thu Oct 26 09:09:39 2023
2025-11-19 00:26:24.168759 | controller |  OS/Arch:           linux/amd64
2025-11-19 00:26:24.168828 | controller |  Context:           default
2025-11-19 00:26:24.168852 | controller |
2025-11-19 00:26:24.168906 | controller | Server: Docker Engine - Community
2025-11-19 00:26:24.168959 | controller |  Engine:
2025-11-19 00:26:24.169126 | controller |   Version:          24.0.7
2025-11-19 00:26:24.169169 | controller |   API version:      1.43 (minimum version 1.12)
2025-11-19 00:26:24.169208 | controller |   Go version:       go1.20.10
2025-11-19 00:26:24.169248 | controller |   Git commit:       311b9ff
2025-11-19 00:26:24.169296 | controller |   Built:            Thu Oct 26 09:08:11 2023
2025-11-19 00:26:24.169343 | controller |   OS/Arch:          linux/amd64
2025-11-19 00:26:24.169384 | controller |   Experimental:     false
2025-11-19 00:26:24.169409 | controller |  containerd:
2025-11-19 00:26:24.169559 | controller |   Version:          1.6.25
2025-11-19 00:26:24.169605 | controller |   GitCommit:        d8f198a4ed8892c764191ef7b3b06d8a2eeb5c7f
2025-11-19 00:26:24.169630 | controller |  runc:
2025-11-19 00:26:24.169718 | controller |   Version:          1.1.10
2025-11-19 00:26:24.169760 | controller |   GitCommit:        v1.1.10-0-g18a0cb0
2025-11-19 00:26:24.169789 | controller |  docker-init:
2025-11-19 00:26:24.169887 | controller |   Version:          0.19.0
2025-11-19 00:26:24.169930 | controller |   GitCommit:        de40ad0
2025-11-19 00:26:24.422582 | controller | ok: Runtime: 0:00:00.052752
2025-11-19 00:26:24.436281 | 
2025-11-19 00:26:24.436437 | TASK [ensure-docker : Dump installed docker details]
2025-11-19 00:26:24.477813 | {'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-19 00:26:24.120242', 'end': '2025-11-19 00:26:24.172994', 'delta': '0:00:00.052752', 'msg': '', 'zuul_log_id': 'fa163ec2-ffbe-ced8-689b-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-19 00:26:24.490192 | 
2025-11-19 00:26:24.490316 | TASK [Use buildset registry]
2025-11-19 00:26:24.521481 | controller | skipping: Conditional result was False
2025-11-19 00:26:24.572701 | 
2025-11-19 00:26:24.572843 | TASK [Ensure python3.8 is present]
2025-11-19 00:26:24.597980 | controller | skipping: Conditional result was False
2025-11-19 00:26:24.606576 | 
2025-11-19 00:26:24.606670 | TASK [Run our-ensure-python role (Fedora only for now)]
2025-11-19 00:26:24.629772 | controller | ok
2025-11-19 00:26:24.656680 | 
2025-11-19 00:26:24.656791 | TASK [our-ensure-python : Install the right Python version (rpm)]
2025-11-19 00:26:32.337967 | controller | ok: Nothing to do
2025-11-19 00:26:32.343461 | 
2025-11-19 00:26:32.343527 | TASK [our-ensure-python : Also install python3-devel]
2025-11-19 00:26:47.120081 | controller | changed
2025-11-19 00:26:47.143958 | 
2025-11-19 00:26:47.144118 | TASK [Run ensure-virtualenv role]
2025-11-19 00:26:47.167701 | controller | ok
2025-11-19 00:26:47.200646 | 
2025-11-19 00:26:47.200756 | TASK [ensure-virtualenv : Check if virtualenv is installed]
2025-11-19 00:26:47.956527 | controller | /usr/bin/virtualenv
2025-11-19 00:26:48.561194 | controller | ok: Runtime: 0:00:00.009136
2025-11-19 00:26:48.576213 | 
2025-11-19 00:26:48.576357 | LOOP [ensure-virtualenv : Install virtualenv package]
2025-11-19 00:26:48.619761 | controller | skipping: Conditional result was False
2025-11-19 00:26:48.620383 | controller | changed: All items complete
2025-11-19 00:26:48.620448 | 
2025-11-19 00:26:48.651140 | 
2025-11-19 00:26:48.651341 | TASK [Find the full path of the Python interpreter]
2025-11-19 00:26:49.418019 | controller | /usr/bin/python3.8
2025-11-19 00:26:50.020886 | controller | ok
2025-11-19 00:26:50.029199 | 
2025-11-19 00:26:50.029298 | TASK [Create virtualenv for ansible-test with a specific interpreter]
2025-11-19 00:26:52.888962 | controller | created virtual environment CPython3.8.18.final.0-64 in 1303ms
2025-11-19 00:26:52.948004 | controller |   creator CPython3Posix(dest=/home/zuul/venv, clear=False, no_vcs_ignore=False, global=False)
2025-11-19 00:26:52.948063 | 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-19 00:26:52.948082 | controller |     added seed packages: pip==22.2.2, setuptools==62.6.0, wheel==0.37.1
2025-11-19 00:26:52.948102 | controller |   activators BashActivator,CShellActivator,FishActivator,NushellActivator,PowerShellActivator,PythonActivator
2025-11-19 00:26:53.397794 | controller | changed
2025-11-19 00:26:53.412826 | 
2025-11-19 00:26:53.413004 | TASK [Set selinux package]
2025-11-19 00:26:53.453295 | controller | ok
2025-11-19 00:26:53.465220 | 
2025-11-19 00:26:53.465349 | TASK [Set selinux package (Fedora)]
2025-11-19 00:26:53.516749 | controller | ok
2025-11-19 00:26:53.528640 | 
2025-11-19 00:26:53.528784 | TASK [Install selinux into virtualenv]
2025-11-19 00:26:59.178591 | controller | Collecting selinux-please-lie-to-me
2025-11-19 00:26:59.309076 | controller |   Downloading selinux_please_lie_to_me-1.0.1-py2.py3-none-any.whl (3.8 kB)
2025-11-19 00:27:00.320394 | controller | Collecting setuptools<50.0.0
2025-11-19 00:27:00.347631 | controller |   Downloading setuptools-49.6.0-py3-none-any.whl (803 kB)
2025-11-19 00:27:00.449705 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 803.3/803.3 kB 8.3 MB/s eta 0:00:00
2025-11-19 00:27:00.573030 | controller | Installing collected packages: setuptools, selinux-please-lie-to-me
2025-11-19 00:27:00.573413 | controller |   Attempting uninstall: setuptools
2025-11-19 00:27:00.574737 | controller |     Found existing installation: setuptools 62.6.0
2025-11-19 00:27:00.689764 | controller |     Uninstalling setuptools-62.6.0:
2025-11-19 00:27:00.703951 | controller |       Successfully uninstalled setuptools-62.6.0
2025-11-19 00:27:01.318584 | controller | Successfully installed selinux-please-lie-to-me-1.0.1 setuptools-49.6.0
2025-11-19 00:27:01.501301 | controller |
2025-11-19 00:27:01.779307 | controller | [notice] A new release of pip available: 22.2.2 -> 25.0.1
2025-11-19 00:27:01.779396 | controller | [notice] To update, run: /home/zuul/venv/bin/python -m pip install --upgrade pip
2025-11-19 00:27:01.915547 | controller | ok: Runtime: 0:00:07.090282
2025-11-19 00:27:01.927883 | 
2025-11-19 00:27:01.928074 | TASK [Install pytest-forked into virtualenv]
2025-11-19 00:27:03.959776 | controller | Collecting pytest-forked
2025-11-19 00:27:04.013531 | controller |   Downloading pytest_forked-1.6.0-py3-none-any.whl (4.9 kB)
2025-11-19 00:27:04.073168 | controller | Collecting py
2025-11-19 00:27:04.078491 | controller |   Downloading py-1.11.0-py2.py3-none-any.whl (98 kB)
2025-11-19 00:27:04.105356 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 98.7/98.7 kB 4.0 MB/s eta 0:00:00
2025-11-19 00:27:04.284839 | controller | Collecting pytest>=3.10
2025-11-19 00:27:04.291033 | controller |   Downloading pytest-8.3.5-py3-none-any.whl (343 kB)
2025-11-19 00:27:04.309372 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 343.6/343.6 kB 25.8 MB/s eta 0:00:00
2025-11-19 00:27:04.408237 | controller | Collecting packaging
2025-11-19 00:27:04.416123 | controller |   Downloading packaging-25.0-py3-none-any.whl (66 kB)
2025-11-19 00:27:04.426165 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 66.5/66.5 kB 9.6 MB/s eta 0:00:00
2025-11-19 00:27:04.453399 | controller | Collecting iniconfig
2025-11-19 00:27:04.458417 | controller |   Downloading iniconfig-2.1.0-py3-none-any.whl (6.0 kB)
2025-11-19 00:27:04.530735 | controller | Collecting tomli>=1
2025-11-19 00:27:04.535890 | controller |   Downloading tomli-2.3.0-py3-none-any.whl (14 kB)
2025-11-19 00:27:04.579151 | controller | Collecting exceptiongroup>=1.0.0rc8
2025-11-19 00:27:04.584960 | controller |   Downloading exceptiongroup-1.3.0-py3-none-any.whl (16 kB)
2025-11-19 00:27:04.637383 | controller | Collecting pluggy<2,>=1.5
2025-11-19 00:27:04.642718 | controller |   Downloading pluggy-1.5.0-py3-none-any.whl (20 kB)
2025-11-19 00:27:04.717753 | controller | Collecting typing-extensions>=4.6.0
2025-11-19 00:27:04.723223 | controller |   Downloading typing_extensions-4.13.2-py3-none-any.whl (45 kB)
2025-11-19 00:27:04.731128 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 45.8/45.8 kB 8.7 MB/s eta 0:00:00
2025-11-19 00:27:04.822409 | controller | Installing collected packages: typing-extensions, tomli, py, pluggy, packaging, iniconfig, exceptiongroup, pytest, pytest-forked
2025-11-19 00:27:05.528619 | controller | Successfully installed exceptiongroup-1.3.0 iniconfig-2.1.0 packaging-25.0 pluggy-1.5.0 py-1.11.0 pytest-8.3.5 pytest-forked-1.6.0 tomli-2.3.0 typing-extensions-4.13.2
2025-11-19 00:27:05.533995 | controller |
2025-11-19 00:27:05.761479 | controller | [notice] A new release of pip available: 22.2.2 -> 25.0.1
2025-11-19 00:27:05.761568 | controller | [notice] To update, run: /home/zuul/venv/bin/python -m pip install --upgrade pip
2025-11-19 00:27:06.329505 | controller | ok: Runtime: 0:00:02.697812
2025-11-19 00:27:06.342494 | 
2025-11-19 00:27:06.342628 | TASK [Update pip]
2025-11-19 00:27:09.038579 | controller | Requirement already satisfied: pip in ./venv/lib/python3.8/site-packages (22.2.2)
2025-11-19 00:27:09.536082 | controller | Collecting pip
2025-11-19 00:27:09.658634 | controller |   Downloading pip-25.0.1-py3-none-any.whl (1.8 MB)
2025-11-19 00:27:09.797654 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 1.8/1.8 MB 14.4 MB/s eta 0:00:00
2025-11-19 00:27:10.017239 | controller | Installing collected packages: pip
2025-11-19 00:27:10.017501 | controller |   Attempting uninstall: pip
2025-11-19 00:27:10.018564 | controller |     Found existing installation: pip 22.2.2
2025-11-19 00:27:10.741528 | controller |     Uninstalling pip-22.2.2:
2025-11-19 00:27:10.804742 | controller |       Successfully uninstalled pip-22.2.2
2025-11-19 00:27:13.008960 | controller | Successfully installed pip-25.0.1
2025-11-19 00:27:13.734114 | controller | ok: Runtime: 0:00:05.787033
2025-11-19 00:27:13.747151 | 
2025-11-19 00:27:13.747296 | TASK [Ensure pip temp directory exists in /var/tmp]
2025-11-19 00:27:15.111118 | controller | changed
2025-11-19 00:27:15.123169 | 
2025-11-19 00:27:15.123304 | TASK [Install ansible into virtualenv]
2025-11-19 00:27:16.875016 | controller | Processing ./src/github.com/ansible/ansible
2025-11-19 00:27:16.876609 | controller |   Preparing metadata (setup.py): started
2025-11-19 00:27:18.512581 | controller |   Preparing metadata (setup.py): finished with status 'done'
2025-11-19 00:27:18.573565 | controller | Collecting jinja2 (from ansible==2.9.27.post0)
2025-11-19 00:27:18.587330 | controller |   Downloading jinja2-3.1.6-py3-none-any.whl.metadata (2.9 kB)
2025-11-19 00:27:18.662795 | controller | Collecting PyYAML (from ansible==2.9.27.post0)
2025-11-19 00:27:18.669012 | 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-19 00:27:19.044549 | controller | Collecting cryptography (from ansible==2.9.27.post0)
2025-11-19 00:27:19.049623 | controller |   Downloading cryptography-46.0.3-cp38-abi3-manylinux_2_34_x86_64.whl.metadata (5.7 kB)
2025-11-19 00:27:19.258988 | controller | Collecting cffi>=1.14 (from cryptography->ansible==2.9.27.post0)
2025-11-19 00:27:19.264126 | controller |   Downloading cffi-1.17.1-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl.metadata (1.5 kB)
2025-11-19 00:27:19.273848 | 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-19 00:27:19.379613 | controller | Collecting MarkupSafe>=2.0 (from jinja2->ansible==2.9.27.post0)
2025-11-19 00:27:19.385818 | controller |   Downloading MarkupSafe-2.1.5-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl.metadata (3.0 kB)
2025-11-19 00:27:19.415310 | controller | Collecting pycparser (from cffi>=1.14->cryptography->ansible==2.9.27.post0)
2025-11-19 00:27:19.420922 | controller |   Downloading pycparser-2.23-py3-none-any.whl.metadata (993 bytes)
2025-11-19 00:27:19.437523 | controller | Downloading cryptography-46.0.3-cp38-abi3-manylinux_2_34_x86_64.whl (4.5 MB)
2025-11-19 00:27:19.491353 | controller |    ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 4.5/4.5 MB 105.0 MB/s eta 0:00:00
2025-11-19 00:27:19.497328 | controller | Downloading jinja2-3.1.6-py3-none-any.whl (134 kB)
2025-11-19 00:27:19.513201 | 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-19 00:27:19.530301 | controller |    ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 806.0/806.0 kB 51.2 MB/s eta 0:00:00
2025-11-19 00:27:19.536347 | controller | Downloading cffi-1.17.1-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl (446 kB)
2025-11-19 00:27:19.555240 | controller | Downloading MarkupSafe-2.1.5-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl (26 kB)
2025-11-19 00:27:19.567154 | controller | Downloading pycparser-2.23-py3-none-any.whl (118 kB)
2025-11-19 00:27:19.598466 | controller | Building wheels for collected packages: ansible
2025-11-19 00:27:19.599957 | controller |   Building wheel for ansible (setup.py): started
2025-11-19 00:27:28.995482 | controller |   Building wheel for ansible (setup.py): finished with status 'done'
2025-11-19 00:27:29.015249 | controller |   Created wheel for ansible: filename=ansible-2.9.27.post0-py3-none-any.whl size=16207514 sha256=9b8e2ee861d64e92fcad2f54e2af142a78a4955986a9df49e84b4ac5daf419c4
2025-11-19 00:27:29.015593 | controller |   Stored in directory: /var/tmp/ansible-test-pip/pip-ephem-wheel-cache-vajcxbl5/wheels/a5/4b/fa/b6deaae105422b0b3b92831e0369d44bf7fca5bb3413d79be7
2025-11-19 00:27:29.101451 | controller | Successfully built ansible
2025-11-19 00:27:29.337573 | controller | Installing collected packages: PyYAML, pycparser, MarkupSafe, jinja2, cffi, cryptography, ansible
2025-11-19 00:27:42.208247 | 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-19 00:27:42.546900 | controller | ok: Runtime: 0:00:26.039316
2025-11-19 00:27:42.560335 | 
2025-11-19 00:27:42.560503 | TASK [Setup download-artifact-fork role]
2025-11-19 00:27:42.591121 | controller | ok
2025-11-19 00:27:42.626228 | 
2025-11-19 00:27:42.626382 | TASK [download-artifact-fork : Parse inventory response]
2025-11-19 00:27:42.684651 | controller | ok
2025-11-19 00:27:42.699226 | 
2025-11-19 00:27:42.699413 | TASK [download-artifact-fork : Fetch info from Zuul API]
2025-11-19 00:27:42.736870 | controller | skipping: Conditional result was False
2025-11-19 00:27:42.746544 | 
2025-11-19 00:27:42.746655 | TASK [download-artifact-fork : Parse build response]
2025-11-19 00:27:42.771986 | controller | skipping: Conditional result was False
2025-11-19 00:27:42.785449 | 
2025-11-19 00:27:42.785798 | TASK [download-artifact-fork : Ensure artifacts directory exists]
2025-11-19 00:27:43.614212 | controller | changed
2025-11-19 00:27:43.619872 | 
2025-11-19 00:27:43.619934 | LOOP [download-artifact-fork : Download archive by type]
2025-11-19 00:27:43.663217 | controller | skipping: Conditional result was False
2025-11-19 00:27:43.663648 | 
2025-11-19 00:27:45.555872 | controller | changed: OK (20205 bytes)
2025-11-19 00:27:45.582925 | 
2025-11-19 00:27:45.583107 | TASK [deploy-artifacts : define __collections]
2025-11-19 00:27:45.625317 | controller | ok
2025-11-19 00:27:45.637752 | 
2025-11-19 00:27:45.637895 | LOOP [deploy-artifacts : Create list of collections]
2025-11-19 00:27:45.674086 | controller | skipping: Conditional result was False
2025-11-19 00:27:45.695182 | controller | ok:
2025-11-19 00:27:45.695361 | controller | {
2025-11-19 00:27:45.695434 | controller |   "branch": "main",
2025-11-19 00:27:45.695495 | controller |   "job": "build-ansible-collection",
2025-11-19 00:27:45.695557 | controller |   "metadata": {
2025-11-19 00:27:45.696679 | controller |     "type": "ansible_collection",
2025-11-19 00:27:45.696752 | controller |     "version": "5.0.0"
2025-11-19 00:27:45.696796 | controller |   },
2025-11-19 00:27:45.696835 | controller |   "name": "ansible.network",
2025-11-19 00:27:45.696871 | controller |   "project": "ansible-collections/ansible.network",
2025-11-19 00:27:45.696907 | controller |   "url": "https://216c8e5e1b1fd7821728-b1027f01e6af574e8ca7f4ef203fcc09.ssl.cf5.rackcdn.com/ansible/dd9ba22fc9c64ec2af86e400a192dace/artifacts/ansible-network-5.0.0.tar.gz"
2025-11-19 00:27:45.696957 | controller | }
2025-11-19 00:27:45.722264 | 
2025-11-19 00:27:45.722434 | TASK [deploy-artifacts : Install require-project collection using ansible-galaxy]
2025-11-19 00:27:46.745961 | controller | Process install dependency map
2025-11-19 00:28:33.618799 | controller | |/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/Starting collection install process
2025-11-19 00:28:33.719926 | controller | |Installing 'ansible.network:5.0.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/network'
2025-11-19 00:28:33.720000 | controller | Installing 'ansible.netcommon:8.2.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/netcommon'
2025-11-19 00:28:35.327765 | controller | /-\|/-\|/-\|/-\|Installing 'ansible.utils:6.0.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/utils'
2025-11-19 00:28:36.973644 | controller | /-\|/-\|/-\|/-\|Installing 'arista.eos:12.0.0' to '/home/zuul/.ansible/collections/ansible_collections/arista/eos'
2025-11-19 00:28:39.286390 | controller | /-\|/-\|/-\|/-\|/-\|/-\Installing 'cisco.ios:11.1.1' to '/home/zuul/.ansible/collections/ansible_collections/cisco/ios'
2025-11-19 00:28:42.412694 | controller | |/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-Installing 'cisco.iosxr:12.1.0' to '/home/zuul/.ansible/collections/ansible_collections/cisco/iosxr'
2025-11-19 00:28:44.930291 | controller | \|/-\|/-\|/-\|/-\|/-\|/-\Installing 'cisco.nxos:11.1.0' to '/home/zuul/.ansible/collections/ansible_collections/cisco/nxos'
2025-11-19 00:28:48.703536 | controller | |/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|Installing 'junipernetworks.junos:11.0.0' to '/home/zuul/.ansible/collections/ansible_collections/junipernetworks/junos'
2025-11-19 00:28:51.640514 | controller | ok: Runtime: 0:01:04.776919
2025-11-19 00:28:51.666505 | 
2025-11-19 00:28:51.666848 | TASK [podman may need iptables kernel modules]
2025-11-19 00:28:53.040710 | controller | ok: Runtime: 0:00:00.010855
2025-11-19 00:28:53.087703 | 
2025-11-19 00:28:53.087846 | PLAY RECAP
2025-11-19 00:28:53.087931 | controller | ok: 46 changed: 26 unreachable: 0 failed: 0 skipped: 17 rescued: 0 ignored: 0
2025-11-19 00:28:53.087976 | 
2025-11-19 00:28:53.222997 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master]
2025-11-19 00:28:53.224034 | RUN START: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/run.yaml@master]
2025-11-19 00:28:53.872828 | 
2025-11-19 00:28:53.872951 | PLAY [controller]
2025-11-19 00:28:53.895096 | 
2025-11-19 00:28:53.895202 | TASK [Copy the galaxy.yml on the controller]
2025-11-19 00:28:57.672524 | controller | changed
2025-11-19 00:28:57.685186 | 
2025-11-19 00:28:57.685394 | TASK [Load information from galaxy.yml]
2025-11-19 00:28:57.727526 | controller | ok
2025-11-19 00:28:57.740368 | 
2025-11-19 00:28:57.740501 | LOOP [Enable FIPS mode]
2025-11-19 00:28:57.773181 | 
2025-11-19 00:28:57.773429 | LOOP [ansible-test : Include branch specific variables.]
2025-11-19 00:28:57.826290 | controller | ok: "/var/lib/zuul/builds/63270ec1d8164135a5e6038b05c5664f/untrusted/project_2/github.com/ansible/ansible-zuul-jobs/roles/ansible-test/vars/default.yaml"
2025-11-19 00:28:57.826546 | 
2025-11-19 00:28:57.842641 | 
2025-11-19 00:28:57.842796 | TASK [ansible-test : Setup --skip-tags for test_options]
2025-11-19 00:28:57.900776 | controller | ok
2025-11-19 00:28:57.915053 | 
2025-11-19 00:28:57.915215 | TASK [ansible-test : Enable --inventory for network-integration]
2025-11-19 00:28:57.942650 | controller | skipping: Conditional result was False
2025-11-19 00:28:57.958560 | 
2025-11-19 00:28:57.958708 | TASK [ansible-test : Enable --retry-on-error]
2025-11-19 00:28:57.975851 | controller | skipping: Conditional result was False
2025-11-19 00:28:57.991559 | 
2025-11-19 00:28:57.991751 | TASK [ansible-test : Enable --continue-on-error]
2025-11-19 00:28:58.041719 | controller | ok
2025-11-19 00:28:58.054597 | 
2025-11-19 00:28:58.054733 | TASK [ansible-test : Enable --requirements]
2025-11-19 00:28:58.071078 | controller | skipping: Conditional result was False
2025-11-19 00:28:58.086259 | 
2025-11-19 00:28:58.086415 | TASK [ansible-test : Adjust options for unit tests]
2025-11-19 00:28:58.103295 | controller | skipping: Conditional result was False
2025-11-19 00:28:58.119599 | 
2025-11-19 00:28:58.119770 | TASK [ansible-test : Install requirements with unit tests]
2025-11-19 00:28:58.195337 | controller | ok
2025-11-19 00:28:58.203539 | 
2025-11-19 00:28:58.203625 | TASK [ansible-test : Set --skip-test]
2025-11-19 00:28:58.249949 | controller | skipping: Conditional result was False
2025-11-19 00:28:58.265201 | 
2025-11-19 00:28:58.265492 | TASK [ansible-test : Reduce verbosity]
2025-11-19 00:28:58.304751 | controller | ok
2025-11-19 00:28:58.319408 | 
2025-11-19 00:28:58.319585 | TASK [ansible-test : Setup --python option]
2025-11-19 00:28:58.346285 | controller | skipping: Conditional result was False
2025-11-19 00:28:58.354815 | 
2025-11-19 00:28:58.354908 | TASK [ansible-test : Setup --docker option]
2025-11-19 00:28:58.388099 | controller | ok
2025-11-19 00:28:58.397601 | 
2025-11-19 00:28:58.397686 | TASK [ansible-test : check if MANIFEST.json exists]
2025-11-19 00:28:59.571577 | controller | ok
2025-11-19 00:28:59.579883 | 
2025-11-19 00:28:59.580000 | TASK [ansible-test : Copy the MANIFEST.json on the controller]
2025-11-19 00:28:59.605208 | controller | skipping: Conditional result was False
2025-11-19 00:28:59.614674 | 
2025-11-19 00:28:59.614795 | TASK [ansible-test : Load information from MANIFEST.json]
2025-11-19 00:28:59.639402 | controller | skipping: Conditional result was False
2025-11-19 00:28:59.649958 | 
2025-11-19 00:28:59.650105 | TASK [ansible-test : Setup location of project for integration tests]
2025-11-19 00:28:59.675895 | controller | skipping: Conditional result was False
2025-11-19 00:28:59.682694 | 
2025-11-19 00:28:59.682790 | TASK [ansible-test : Copy the galaxy.yml on the controller]
2025-11-19 00:29:01.188452 | controller | ok
2025-11-19 00:29:01.195518 | 
2025-11-19 00:29:01.195601 | TASK [ansible-test : Load information from galaxy.yml]
2025-11-19 00:29:01.254182 | controller | ok
2025-11-19 00:29:01.268127 | 
2025-11-19 00:29:01.268285 | TASK [ansible-test : Setup location of project for integration tests]
2025-11-19 00:29:01.313264 | controller | ok
2025-11-19 00:29:01.322039 | 
2025-11-19 00:29:01.322136 | TASK [ansible-test : Setup minimum test requirements]
2025-11-19 00:29:01.358208 | controller | ok
2025-11-19 00:29:01.369102 | 
2025-11-19 00:29:01.369221 | TASK [ansible-test : Ensure pip temp directory exists in /var/tmp]
2025-11-19 00:29:02.325314 | controller | ok
2025-11-19 00:29:02.333984 | 
2025-11-19 00:29:02.334090 | TASK [ansible-test : Install python requirements]
2025-11-19 00:29:03.731548 | controller | Collecting yamllint (from -r /home/zuul/.ansible/collections/ansible_collections/ansible/network/test-requirements.txt (line 1))
2025-11-19 00:29:03.753108 | controller |   Downloading yamllint-1.35.1-py3-none-any.whl.metadata (4.2 kB)
2025-11-19 00:29:03.795731 | controller | Collecting pathspec>=0.5.3 (from yamllint->-r /home/zuul/.ansible/collections/ansible_collections/ansible/network/test-requirements.txt (line 1))
2025-11-19 00:29:03.800978 | controller |   Downloading pathspec-0.12.1-py3-none-any.whl.metadata (21 kB)
2025-11-19 00:29:03.813180 | 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-19 00:29:03.820972 | controller | Downloading yamllint-1.35.1-py3-none-any.whl (66 kB)
2025-11-19 00:29:03.836160 | controller | Downloading pathspec-0.12.1-py3-none-any.whl (31 kB)
2025-11-19 00:29:03.900457 | controller | Installing collected packages: pathspec, yamllint
2025-11-19 00:29:04.015450 | controller | Successfully installed pathspec-0.12.1 yamllint-1.35.1
2025-11-19 00:29:04.706771 | controller | ok: Runtime: 0:00:01.088514
2025-11-19 00:29:04.719894 | 
2025-11-19 00:29:04.720068 | TASK [ansible-test : Copy the integration_config.yml file from the controller]
2025-11-19 00:29:05.502352 | controller | cp: cannot stat '/tmp/integration_config.yml': No such file or directory
2025-11-19 00:29:06.096890 | controller | ERROR
2025-11-19 00:29:06.097274 | controller | {
2025-11-19 00:29:06.097340 | controller |   "delta": "0:00:00.008489",
2025-11-19 00:29:06.097384 | controller |   "end": "2025-11-19 00:29:05.502684",
2025-11-19 00:29:06.097421 | controller |   "msg": "non-zero return code",
2025-11-19 00:29:06.097455 | controller |   "rc": 1,
2025-11-19 00:29:06.097490 | controller |   "start": "2025-11-19 00:29:05.494195"
2025-11-19 00:29:06.097523 | controller | }
2025-11-19 00:29:06.097572 | controller | ERROR: Ignoring Errors
2025-11-19 00:29:06.113727 | 
2025-11-19 00:29:06.114215 | TASK [ansible-test : Copy potential cloud provider configuration for ansible-test in the collection]
2025-11-19 00:29:06.493661 | controller | skipping: Conditional result was False
2025-11-19 00:29:06.508212 | 
2025-11-19 00:29:06.508369 | TASK [ansible-test : Enable persistent connection logging]
2025-11-19 00:29:07.922975 | controller | changed: section and option added
2025-11-19 00:29:07.936650 | 
2025-11-19 00:29:07.936787 | TASK [ansible-test : Set persistent ssh_type value]
2025-11-19 00:29:09.152113 | controller | changed: option added
2025-11-19 00:29:09.166369 | 
2025-11-19 00:29:09.166525 | TASK [ansible-test : Set ansible log path]
2025-11-19 00:29:10.379475 | controller | changed: section and option added
2025-11-19 00:29:10.393459 | 
2025-11-19 00:29:10.393622 | TASK [ansible-test : Identify the changed targets]
2025-11-19 00:29:10.765731 | controller | skipping: Conditional result was False
2025-11-19 00:29:10.779963 | 
2025-11-19 00:29:10.780181 | TASK [ansible-test : set_fact]
2025-11-19 00:29:10.879561 | controller | skipping: Conditional result was False
2025-11-19 00:29:10.897499 | 
2025-11-19 00:29:10.897791 | TASK [ansible-test : Set the targets]
2025-11-19 00:29:10.985540 | controller | ok
2025-11-19 00:29:10.994203 | 
2025-11-19 00:29:10.994291 | TASK [ansible-test : set_fact]
2025-11-19 00:29:11.018544 | controller | skipping: Conditional result was False
2025-11-19 00:29:11.026664 | 
2025-11-19 00:29:11.026766 | TASK [ansible-test : List the targets]
2025-11-19 00:29:11.392248 | controller | skipping: Conditional result was False
2025-11-19 00:29:11.406780 | 
2025-11-19 00:29:11.406932 | TASK [ansible-test : set_fact]
2025-11-19 00:29:11.425296 | controller | skipping: Conditional result was False
2025-11-19 00:29:11.441333 | 
2025-11-19 00:29:11.441479 | TASK [ansible-test : set_fact]
2025-11-19 00:29:11.458998 | controller | skipping: Conditional result was False
2025-11-19 00:29:11.465808 | 
2025-11-19 00:29:11.465870 | LOOP [ansible-test : set_fact]
2025-11-19 00:29:11.538689 | 
2025-11-19 00:29:11.538886 | TASK [ansible-test : set_fact]
2025-11-19 00:29:11.564189 | controller | skipping: Conditional result was False
2025-11-19 00:29:11.573191 | 
2025-11-19 00:29:11.573280 | TASK [ansible-test : debug]
2025-11-19 00:29:11.598659 | controller | skipping: Conditional result was False
2025-11-19 00:29:11.611680 | 
2025-11-19 00:29:11.611801 | TASK [ansible-test : debug]
2025-11-19 00:29:11.653461 | About to run: ansible-test sanity --requirements --docker -v
2025-11-19 00:29:11.659544 | 
2025-11-19 00:29:11.659620 | TASK [ansible-test : Run the test suite]
2025-11-19 00:29:12.937697 | controller | Run command: docker images quay.io/ansible/default-test-container:1.10.1 --format '{{json .}}'
2025-11-19 00:29:13.043581 | controller | Run command: docker pull quay.io/ansible/default-test-container:1.10.1
2025-11-19 00:29:14.615504 | controller | 1.10.1: Pulling from ansible/default-test-container
2025-11-19 00:29:14.615564 | 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-19 00:29:14.615588 | controller | 5667fdb72017: Pulling fs layer
2025-11-19 00:29:14.615596 | controller | d83811f270d5: Pulling fs layer
2025-11-19 00:29:14.615603 | controller | ee671aafb583: Pulling fs layer
2025-11-19 00:29:14.615616 | controller | 7fc152dfb3a6: Pulling fs layer
2025-11-19 00:29:14.615626 | controller | 3fb967a6fd03: Pulling fs layer
2025-11-19 00:29:14.615632 | controller | b3e63023ca5f: Pulling fs layer
2025-11-19 00:29:14.615639 | controller | 653cb3e65dbf: Pulling fs layer
2025-11-19 00:29:14.615645 | controller | f4712b586375: Pulling fs layer
2025-11-19 00:29:14.615651 | controller | d696ed7812f8: Pulling fs layer
2025-11-19 00:29:14.615657 | controller | 29bf28764f14: Pulling fs layer
2025-11-19 00:29:14.615664 | controller | 47d7edb3c2b7: Pulling fs layer
2025-11-19 00:29:14.615672 | controller | 07550ad4f6e4: Pulling fs layer
2025-11-19 00:29:14.615678 | controller | 7e79526e64c5: Pulling fs layer
2025-11-19 00:29:14.615694 | controller | 4fffb32882b7: Pulling fs layer
2025-11-19 00:29:14.615700 | controller | 7fc152dfb3a6: Waiting
2025-11-19 00:29:14.615707 | controller | fc7a38b06f4e: Pulling fs layer
2025-11-19 00:29:14.615713 | controller | 3fb967a6fd03: Waiting
2025-11-19 00:29:14.615719 | controller | 6d9d912e060e: Pulling fs layer
2025-11-19 00:29:14.615725 | controller | 5dd542dd28d9: Pulling fs layer
2025-11-19 00:29:14.615731 | controller | cef8beb99552: Pulling fs layer
2025-11-19 00:29:14.615740 | controller | b3e63023ca5f: Waiting
2025-11-19 00:29:14.615746 | controller | bd5557e101ad: Pulling fs layer
2025-11-19 00:29:14.615755 | controller | e057c20fabdd: Pulling fs layer
2025-11-19 00:29:14.615761 | controller | 653cb3e65dbf: Waiting
2025-11-19 00:29:14.615767 | controller | d16059cdae2e: Pulling fs layer
2025-11-19 00:29:14.615773 | controller | 431e42d906a3: Pulling fs layer
2025-11-19 00:29:14.615780 | controller | 7a0fd3b1d7c3: Pulling fs layer
2025-11-19 00:29:14.615786 | controller | f4712b586375: Waiting
2025-11-19 00:29:14.615796 | controller | aacdb6db0c8c: Pulling fs layer
2025-11-19 00:29:14.615803 | controller | 2e7f04ae8c8b: Pulling fs layer
2025-11-19 00:29:14.615809 | controller | d696ed7812f8: Waiting
2025-11-19 00:29:14.615815 | controller | 4cd343027a44: Pulling fs layer
2025-11-19 00:29:14.615822 | controller | 0b4379883f90: Pulling fs layer
2025-11-19 00:29:14.615828 | controller | 29bf28764f14: Waiting
2025-11-19 00:29:14.615835 | controller | 3d9d6d9dfc91: Pulling fs layer
2025-11-19 00:29:14.615843 | controller | 57d6314bd293: Pulling fs layer
2025-11-19 00:29:14.615852 | controller | 774036afeb7f: Pulling fs layer
2025-11-19 00:29:14.615858 | controller | 47d7edb3c2b7: Waiting
2025-11-19 00:29:14.615864 | controller | 54b10f0e1645: Pulling fs layer
2025-11-19 00:29:14.615870 | controller | 78cf439d9fdb: Pulling fs layer
2025-11-19 00:29:14.615877 | controller | 07550ad4f6e4: Waiting
2025-11-19 00:29:14.615883 | controller | 36e5e20969c7: Pulling fs layer
2025-11-19 00:29:14.615889 | controller | 4b19ac3f17a8: Pulling fs layer
2025-11-19 00:29:14.615900 | controller | 5dd542dd28d9: Waiting
2025-11-19 00:29:14.615907 | controller | cef8beb99552: Waiting
2025-11-19 00:29:14.615913 | controller | bd5557e101ad: Waiting
2025-11-19 00:29:14.615918 | controller | e057c20fabdd: Waiting
2025-11-19 00:29:14.615924 | controller | d16059cdae2e: Waiting
2025-11-19 00:29:14.615933 | controller | 431e42d906a3: Waiting
2025-11-19 00:29:14.615939 | controller | 7a0fd3b1d7c3: Waiting
2025-11-19 00:29:14.615945 | controller | aacdb6db0c8c: Waiting
2025-11-19 00:29:14.615951 | controller | 2e7f04ae8c8b: Waiting
2025-11-19 00:29:14.615956 | controller | 4cd343027a44: Waiting
2025-11-19 00:29:14.615962 | controller | 0b4379883f90: Waiting
2025-11-19 00:29:14.615968 | controller | 3d9d6d9dfc91: Waiting
2025-11-19 00:29:14.615974 | controller | 57d6314bd293: Waiting
2025-11-19 00:29:14.615979 | controller | 774036afeb7f: Waiting
2025-11-19 00:29:14.615985 | controller | 54b10f0e1645: Waiting
2025-11-19 00:29:14.615991 | controller | 78cf439d9fdb: Waiting
2025-11-19 00:29:14.615997 | controller | 36e5e20969c7: Waiting
2025-11-19 00:29:14.616005 | controller | 4b19ac3f17a8: Waiting
2025-11-19 00:29:14.616025 | controller | 7e79526e64c5: Waiting
2025-11-19 00:29:14.616034 | controller | 6d9d912e060e: Waiting
2025-11-19 00:29:14.616043 | controller | 4fffb32882b7: Waiting
2025-11-19 00:29:15.434993 | controller | fc7a38b06f4e: Waiting
2025-11-19 00:29:15.435070 | controller | ee671aafb583: Verifying Checksum
2025-11-19 00:29:15.454377 | controller | ee671aafb583: Download complete
2025-11-19 00:29:15.454446 | controller | d83811f270d5: Verifying Checksum
2025-11-19 00:29:15.454517 | controller | d83811f270d5: Download complete
2025-11-19 00:29:16.155473 | controller | 7fc152dfb3a6: Download complete
2025-11-19 00:29:16.840987 | controller | b3e63023ca5f: Verifying Checksum
2025-11-19 00:29:17.314375 | controller | b3e63023ca5f: Download complete
2025-11-19 00:29:17.314465 | controller | 5667fdb72017: Verifying Checksum
2025-11-19 00:29:17.595235 | controller | 5667fdb72017: Download complete
2025-11-19 00:29:17.595313 | controller | 653cb3e65dbf: Verifying Checksum
2025-11-19 00:29:17.595325 | controller | 653cb3e65dbf: Download complete
2025-11-19 00:29:18.389587 | controller | d696ed7812f8: Verifying Checksum
2025-11-19 00:29:19.559458 | controller | d696ed7812f8: Download complete
2025-11-19 00:29:19.559540 | controller | 29bf28764f14: Verifying Checksum
2025-11-19 00:29:19.559800 | controller | 29bf28764f14: Download complete
2025-11-19 00:29:20.651671 | controller | 5667fdb72017: Pull complete
2025-11-19 00:29:20.724274 | controller | d83811f270d5: Pull complete
2025-11-19 00:29:20.763201 | controller | ee671aafb583: Pull complete
2025-11-19 00:29:20.826725 | controller | 7fc152dfb3a6: Pull complete
2025-11-19 00:29:20.901950 | controller | 47d7edb3c2b7: Verifying Checksum
2025-11-19 00:29:21.690455 | controller | 47d7edb3c2b7: Download complete
2025-11-19 00:29:21.690544 | controller | 07550ad4f6e4: Verifying Checksum
2025-11-19 00:29:21.690624 | controller | 07550ad4f6e4: Download complete
2025-11-19 00:29:22.327354 | controller | 3fb967a6fd03: Verifying Checksum
2025-11-19 00:29:22.667358 | controller | 3fb967a6fd03: Download complete
2025-11-19 00:29:22.667440 | controller | 7e79526e64c5: Verifying Checksum
2025-11-19 00:29:22.669531 | controller | 7e79526e64c5: Download complete
2025-11-19 00:29:23.064836 | controller | 4fffb32882b7: Verifying Checksum
2025-11-19 00:29:23.393396 | controller | 4fffb32882b7: Download complete
2025-11-19 00:29:23.393472 | controller | 3fb967a6fd03: Pull complete
2025-11-19 00:29:23.418904 | controller | b3e63023ca5f: Pull complete
2025-11-19 00:29:23.442742 | controller | 653cb3e65dbf: Pull complete
2025-11-19 00:29:23.490059 | controller | fc7a38b06f4e: Verifying Checksum
2025-11-19 00:29:23.995753 | controller | fc7a38b06f4e: Download complete
2025-11-19 00:29:23.995858 | controller | 6d9d912e060e: Verifying Checksum
2025-11-19 00:29:24.300073 | controller | 6d9d912e060e: Download complete
2025-11-19 00:29:24.300210 | controller | 5dd542dd28d9: Verifying Checksum
2025-11-19 00:29:25.117890 | controller | 5dd542dd28d9: Download complete
2025-11-19 00:29:25.118960 | controller | bd5557e101ad: Verifying Checksum
2025-11-19 00:29:25.914980 | controller | bd5557e101ad: Download complete
2025-11-19 00:29:25.915090 | controller | e057c20fabdd: Verifying Checksum
2025-11-19 00:29:26.642098 | controller | e057c20fabdd: Download complete
2025-11-19 00:29:26.642195 | controller | d16059cdae2e: Verifying Checksum
2025-11-19 00:29:28.401743 | controller | d16059cdae2e: Download complete
2025-11-19 00:29:28.402994 | controller | 431e42d906a3: Verifying Checksum
2025-11-19 00:29:28.403230 | controller | 431e42d906a3: Download complete
2025-11-19 00:29:29.182515 | controller | 7a0fd3b1d7c3: Verifying Checksum
2025-11-19 00:29:29.182910 | controller | 7a0fd3b1d7c3: Download complete
2025-11-19 00:29:30.613780 | controller | aacdb6db0c8c: Verifying Checksum
2025-11-19 00:29:30.614086 | controller | aacdb6db0c8c: Download complete
2025-11-19 00:29:31.307962 | controller | f4712b586375: Verifying Checksum
2025-11-19 00:29:31.308114 | controller | f4712b586375: Download complete
2025-11-19 00:29:31.357729 | controller | 2e7f04ae8c8b: Verifying Checksum
2025-11-19 00:29:31.357967 | controller | 2e7f04ae8c8b: Download complete
2025-11-19 00:29:32.147205 | controller | 4cd343027a44: Verifying Checksum
2025-11-19 00:29:32.147328 | controller | 4cd343027a44: Download complete
2025-11-19 00:29:32.200982 | controller | 0b4379883f90: Verifying Checksum
2025-11-19 00:29:32.201072 | controller | 0b4379883f90: Download complete
2025-11-19 00:29:33.010807 | controller | 3d9d6d9dfc91: Verifying Checksum
2025-11-19 00:29:33.011398 | controller | 3d9d6d9dfc91: Download complete
2025-11-19 00:29:38.409902 | controller | 57d6314bd293: Verifying Checksum
2025-11-19 00:29:38.409986 | controller | 57d6314bd293: Download complete
2025-11-19 00:29:41.731151 | controller | 774036afeb7f: Verifying Checksum
2025-11-19 00:29:42.002417 | controller | 774036afeb7f: Download complete
2025-11-19 00:29:42.002520 | controller | cef8beb99552: Verifying Checksum
2025-11-19 00:29:43.435830 | controller | cef8beb99552: Download complete
2025-11-19 00:29:43.435935 | controller | 54b10f0e1645: Verifying Checksum
2025-11-19 00:29:45.371439 | controller | 54b10f0e1645: Download complete
2025-11-19 00:29:45.371561 | controller | 4b19ac3f17a8: Verifying Checksum
2025-11-19 00:29:50.245283 | controller | 4b19ac3f17a8: Download complete
2025-11-19 00:29:50.249583 | controller | 36e5e20969c7: Verifying Checksum
2025-11-19 00:29:52.598913 | controller | 36e5e20969c7: Download complete
2025-11-19 00:29:52.598990 | controller | 78cf439d9fdb: Verifying Checksum
2025-11-19 00:29:54.853463 | controller | 78cf439d9fdb: Download complete
2025-11-19 00:29:54.853586 | controller | f4712b586375: Pull complete
2025-11-19 00:29:54.874574 | controller | d696ed7812f8: Pull complete
2025-11-19 00:29:54.910273 | controller | 29bf28764f14: Pull complete
2025-11-19 00:29:54.962395 | controller | 47d7edb3c2b7: Pull complete
2025-11-19 00:29:54.983844 | controller | 07550ad4f6e4: Pull complete
2025-11-19 00:29:55.007396 | controller | 7e79526e64c5: Pull complete
2025-11-19 00:29:55.028322 | controller | 4fffb32882b7: Pull complete
2025-11-19 00:29:55.100517 | controller | fc7a38b06f4e: Pull complete
2025-11-19 00:29:55.127717 | controller | 6d9d912e060e: Pull complete
2025-11-19 00:29:55.152288 | controller | 5dd542dd28d9: Pull complete
2025-11-19 00:30:15.334637 | controller | cef8beb99552: Pull complete
2025-11-19 00:30:15.360061 | controller | bd5557e101ad: Pull complete
2025-11-19 00:30:15.382795 | controller | e057c20fabdd: Pull complete
2025-11-19 00:30:15.413506 | controller | d16059cdae2e: Pull complete
2025-11-19 00:30:17.559778 | controller | 431e42d906a3: Pull complete
2025-11-19 00:30:17.628232 | controller | 7a0fd3b1d7c3: Pull complete
2025-11-19 00:30:17.694816 | controller | aacdb6db0c8c: Pull complete
2025-11-19 00:30:17.733760 | controller | 2e7f04ae8c8b: Pull complete
2025-11-19 00:30:17.775891 | controller | 4cd343027a44: Pull complete
2025-11-19 00:30:17.825895 | controller | 0b4379883f90: Pull complete
2025-11-19 00:30:17.875711 | controller | 3d9d6d9dfc91: Pull complete
2025-11-19 00:30:23.671661 | controller | 57d6314bd293: Pull complete
2025-11-19 00:30:47.358396 | controller | 774036afeb7f: Pull complete
2025-11-19 00:31:17.197414 | controller | 54b10f0e1645: Pull complete
2025-11-19 00:31:44.380543 | controller | 78cf439d9fdb: Pull complete
2025-11-19 00:32:13.736146 | controller | 36e5e20969c7: Pull complete
2025-11-19 00:32:41.340986 | controller | 4b19ac3f17a8: Pull complete
2025-11-19 00:32:41.360569 | controller | Digest: sha256:954c4edffb0905df64e23c8611b1c4a62db9af13062f51cad5b658e727238d41
2025-11-19 00:32:41.364429 | controller | Status: Downloaded newer image for quay.io/ansible/default-test-container:1.10.1
2025-11-19 00:32:41.375696 | controller | quay.io/ansible/default-test-container:1.10.1
2025-11-19 00:32:41.383197 | controller | Run command: docker images quay.io/ansible/pypi-test-container:1.0.0 --format '{{json .}}'
2025-11-19 00:32:41.426775 | controller | Run command: docker pull quay.io/ansible/pypi-test-container:1.0.0
2025-11-19 00:32:42.974160 | controller | 1.0.0: Pulling from ansible/pypi-test-container
2025-11-19 00:32:42.974989 | controller | 04a5f4cda3ee: Pulling fs layer
2025-11-19 00:32:42.975010 | controller | ff496a88c8ed: Pulling fs layer
2025-11-19 00:32:42.975017 | controller | 0ce83f459fe7: Pulling fs layer
2025-11-19 00:32:42.975023 | controller | 2e5170e1f099: Pulling fs layer
2025-11-19 00:32:42.975029 | controller | 7641eb41b08c: Pulling fs layer
2025-11-19 00:32:42.975035 | controller | ad15fa9da398: Pulling fs layer
2025-11-19 00:32:42.975049 | controller | 087d91352424: Pulling fs layer
2025-11-19 00:32:42.975056 | controller | 8b92efd6a100: Pulling fs layer
2025-11-19 00:32:42.975066 | controller | 2e5170e1f099: Waiting
2025-11-19 00:32:42.975147 | controller | 7641eb41b08c: Waiting
2025-11-19 00:32:42.975161 | controller | ad15fa9da398: Waiting
2025-11-19 00:32:42.975171 | controller | 087d91352424: Waiting
2025-11-19 00:32:42.975243 | controller | 8b92efd6a100: Waiting
2025-11-19 00:32:43.369844 | controller | 0ce83f459fe7: Verifying Checksum
2025-11-19 00:32:43.379244 | controller | 0ce83f459fe7: Download complete
2025-11-19 00:32:43.379372 | controller | ff496a88c8ed: Verifying Checksum
2025-11-19 00:32:43.849658 | controller | ff496a88c8ed: Download complete
2025-11-19 00:32:43.849756 | controller | 7641eb41b08c: Verifying Checksum
2025-11-19 00:32:43.890923 | controller | 7641eb41b08c: Download complete
2025-11-19 00:32:43.891036 | controller | 04a5f4cda3ee: Verifying Checksum
2025-11-19 00:32:44.318105 | controller | 04a5f4cda3ee: Download complete
2025-11-19 00:32:44.318203 | controller | ad15fa9da398: Verifying Checksum
2025-11-19 00:32:44.363483 | controller | ad15fa9da398: Download complete
2025-11-19 00:32:44.363607 | controller | 2e5170e1f099: Verifying Checksum
2025-11-19 00:32:44.498249 | controller | 2e5170e1f099: Download complete
2025-11-19 00:32:44.498350 | controller | 087d91352424: Verifying Checksum
2025-11-19 00:32:44.697153 | controller | 087d91352424: Download complete
2025-11-19 00:32:44.697264 | controller | 8b92efd6a100: Verifying Checksum
2025-11-19 00:32:46.387535 | controller | 8b92efd6a100: Download complete
2025-11-19 00:32:46.387651 | controller | 04a5f4cda3ee: Pull complete
2025-11-19 00:32:46.418858 | controller | ff496a88c8ed: Pull complete
2025-11-19 00:32:46.442203 | controller | 0ce83f459fe7: Pull complete
2025-11-19 00:32:48.056028 | controller | 2e5170e1f099: Pull complete
2025-11-19 00:32:48.082082 | controller | 7641eb41b08c: Pull complete
2025-11-19 00:32:48.109571 | controller | ad15fa9da398: Pull complete
2025-11-19 00:32:49.186306 | controller | 087d91352424: Pull complete
2025-11-19 00:32:49.212408 | controller | 8b92efd6a100: Pull complete
2025-11-19 00:32:49.269507 | controller | Digest: sha256:71042ab0a14971b5608fe75706de54f367fc31db573e3b3955182037f73cadb6
2025-11-19 00:32:49.278792 | controller | Status: Downloaded newer image for quay.io/ansible/pypi-test-container:1.0.0
2025-11-19 00:32:49.288102 | controller | quay.io/ansible/pypi-test-container:1.0.0
2025-11-19 00:32:49.290930 | controller | Run command: docker run --detach quay.io/ansible/pypi-test-container:1.0.0
2025-11-19 00:32:58.683585 | controller | Run command: docker inspect e992ee62552ab9b3fdc6fba331d6c749b8f6a772437602616a12413f76ef6c99
2025-11-19 00:32:58.903200 | controller | Scanning collection root: /home/zuul/.ansible/collections/ansible_collections
2025-11-19 00:32:58.917061 | controller | Including collection: ansible.netcommon (292 files)
2025-11-19 00:32:58.917131 | controller | Including collection: ansible.network (23 files)
2025-11-19 00:32:58.930088 | controller | Including collection: ansible.utils (442 files)
2025-11-19 00:32:58.969659 | controller | Including collection: arista.eos (1022 files)
2025-11-19 00:32:59.019538 | controller | Including collection: cisco.ios (1172 files)
2025-11-19 00:32:59.062237 | controller | Including collection: cisco.iosxr (1043 files)
2025-11-19 00:32:59.184616 | controller | Including collection: cisco.nxos (1684 files)
2025-11-19 00:32:59.238119 | controller | Including collection: junipernetworks.junos (963 files)
2025-11-19 00:32:59.295367 | controller | Creating a payload archive containing 8378 files...
2025-11-19 00:33:01.712382 | controller | Created a 6889672 byte payload archive containing 8378 files in 2 seconds.
2025-11-19 00:33:01.718956 | controller | Assuming Docker is available on localhost.
2025-11-19 00:33:01.720259 | 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-19 00:33:02.429358 | controller | Run command: docker exec -i 16b612d5c738de2a34cb8cd4ea46d47bd05992e97d8a11ccc54fd3c75604aed6 dd of=/root/docker.sh bs=65536
2025-11-19 00:33:02.546554 | controller | Run command: docker exec 16b612d5c738de2a34cb8cd4ea46d47bd05992e97d8a11ccc54fd3c75604aed6 /bin/bash /root/docker.sh
2025-11-19 00:33:02.674294 | controller | Run command: docker exec -i 16b612d5c738de2a34cb8cd4ea46d47bd05992e97d8a11ccc54fd3c75604aed6 dd of=/root/ansible.tgz bs=65536
2025-11-19 00:33:02.811604 | controller | Run command: docker exec 16b612d5c738de2a34cb8cd4ea46d47bd05992e97d8a11ccc54fd3c75604aed6 mkdir /root/ansible
2025-11-19 00:33:02.910641 | controller | Run command: docker exec 16b612d5c738de2a34cb8cd4ea46d47bd05992e97d8a11ccc54fd3c75604aed6 tar oxzf /root/ansible.tgz -C /root/ansible
2025-11-19 00:33:04.247993 | controller | Run command: docker exec 16b612d5c738de2a34cb8cd4ea46d47bd05992e97d8a11ccc54fd3c75604aed6 /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-xeudim8p.json --truncate 0 --redact --color no --requirements --pypi-endpoint http://172.17.0.2:3141/root/pypi/+simple/
2025-11-19 00:33:04.948444 | controller | Injecting custom PyPI hosts entries: /etc/hosts
2025-11-19 00:33:04.948955 | controller | Injecting custom PyPI config: /root/.pip/pip.conf
2025-11-19 00:33:04.949325 | controller | Injecting custom PyPI config: /root/.pydistutils.cfg
2025-11-19 00:33:04.951445 | controller | Run command: /usr/bin/python3.6 /root/ansible/test/lib/ansible_test/_data/yamlcheck.py
2025-11-19 00:33:05.024915 | controller | Running sanity test 'action-plugin-docs' with Python 3.6
2025-11-19 00:33:05.029315 | controller | Read 0 sanity test ignore line(s) for Ansible 2.9 from: tests/sanity/ignore-2.9.txt
2025-11-19 00:33:05.041514 | controller | No tests applicable.
2025-11-19 00:33:05.041809 | controller | Running sanity test 'ansible-doc' with Python 3.6
2025-11-19 00:33:05.042159 | controller | No tests applicable.
2025-11-19 00:33:05.042638 | controller | Run command: /usr/bin/python2.6 /root/ansible/test/lib/ansible_test/_data/yamlcheck.py
2025-11-19 00:33:05.143082 | controller | Running sanity test 'compile' with Python 2.6
2025-11-19 00:33:05.143291 | controller | No tests applicable.
2025-11-19 00:33:05.143854 | controller | Run command: /usr/bin/python2.7 /root/ansible/test/lib/ansible_test/_data/yamlcheck.py
2025-11-19 00:33:05.376986 | controller | Running sanity test 'compile' with Python 2.7
2025-11-19 00:33:05.377425 | controller | No tests applicable.
2025-11-19 00:33:05.377857 | controller | Run command: /usr/bin/python3.5 /root/ansible/test/lib/ansible_test/_data/yamlcheck.py
2025-11-19 00:33:05.514929 | controller | Running sanity test 'compile' with Python 3.5
2025-11-19 00:33:05.515459 | controller | No tests applicable.
2025-11-19 00:33:05.515641 | controller | Running sanity test 'compile' with Python 3.6
2025-11-19 00:33:05.515698 | controller | No tests applicable.
2025-11-19 00:33:05.516089 | controller | Run command: /usr/bin/python3.7 /root/ansible/test/lib/ansible_test/_data/yamlcheck.py
2025-11-19 00:33:05.627371 | controller | Running sanity test 'compile' with Python 3.7
2025-11-19 00:33:05.627876 | controller | No tests applicable.
2025-11-19 00:33:05.628224 | controller | Run command: /usr/bin/python3.8 /root/ansible/test/lib/ansible_test/_data/yamlcheck.py
2025-11-19 00:33:05.744469 | controller | Running sanity test 'compile' with Python 3.8
2025-11-19 00:33:05.744565 | controller | No tests applicable.
2025-11-19 00:33:05.744738 | controller | Running sanity test 'empty-init' with Python 3.6
2025-11-19 00:33:05.745202 | controller | No tests applicable.
2025-11-19 00:33:05.745441 | controller | Running sanity test 'future-import-boilerplate' with Python 3.6
2025-11-19 00:33:05.745471 | controller | No tests applicable.
2025-11-19 00:33:05.745641 | controller | Running sanity test 'ignores'
2025-11-19 00:33:05.747046 | 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-19 00:33:06.503942 | controller | Ignoring packaging: markers 'python_version < "3.6"' don't match your environment
2025-11-19 00:33:06.507199 | controller | Ignoring coverage: markers 'python_version > "3.7"' don't match your environment
2025-11-19 00:33:06.508729 | controller | Ignoring cryptography: markers 'python_version < "2.7"' don't match your environment
2025-11-19 00:33:06.511677 | controller | Ignoring deepdiff: markers 'python_version < "3"' don't match your environment
2025-11-19 00:33:06.513384 | controller | Ignoring jinja2: markers 'python_version < "2.7"' don't match your environment
2025-11-19 00:33:06.514827 | controller | Ignoring urllib3: markers 'python_version < "2.7"' don't match your environment
2025-11-19 00:33:06.518130 | controller | Ignoring sphinx: markers 'python_version < "2.7"' don't match your environment
2025-11-19 00:33:06.522657 | controller | Ignoring wheel: markers 'python_version < "2.7"' don't match your environment
2025-11-19 00:33:06.524212 | controller | Ignoring yamllint: markers 'python_version < "2.7"' don't match your environment
2025-11-19 00:33:06.528805 | controller | Ignoring paramiko: markers 'python_version < "2.7"' don't match your environment
2025-11-19 00:33:06.531428 | controller | Ignoring pytest: markers 'python_version < "2.7"' don't match your environment
2025-11-19 00:33:06.533113 | controller | Ignoring pytest: markers 'python_version == "2.7"' don't match your environment
2025-11-19 00:33:06.534554 | controller | Ignoring pytest-forked: markers 'python_version < "2.7"' don't match your environment
2025-11-19 00:33:06.538440 | controller | Ignoring requests: markers 'python_version < "2.7"' don't match your environment
2025-11-19 00:33:06.545279 | controller | Ignoring virtualenv: markers 'python_version < "2.7"' don't match your environment
2025-11-19 00:33:06.546953 | controller | Ignoring pathspec: markers 'python_version < "2.7"' don't match your environment
2025-11-19 00:33:06.548341 | controller | Ignoring pyopenssl: markers 'python_version < "2.7"' don't match your environment
2025-11-19 00:33:06.550855 | controller | Ignoring pyyaml: markers 'python_version < "2.7"' don't match your environment
2025-11-19 00:33:06.552233 | controller | Ignoring pycparser: markers 'python_version < "2.7"' don't match your environment
2025-11-19 00:33:06.555617 | controller | Ignoring xmltodict: markers 'python_version < "2.7"' don't match your environment
2025-11-19 00:33:06.557078 | controller | Ignoring lxml: markers 'python_version < "2.7"' don't match your environment
2025-11-19 00:33:06.560123 | controller | Ignoring pyvmomi: markers 'python_version < "2.7"' don't match your environment
2025-11-19 00:33:06.564839 | controller | Ignoring MarkupSafe: markers 'python_version < "3.6"' don't match your environment
2025-11-19 00:33:06.569825 | controller | Ignoring setuptools: markers 'python_version <= "2.7"' don't match your environment
2025-11-19 00:33:06.573982 | controller | Ignoring websocket-client: markers 'python_version < "3"' don't match your environment
2025-11-19 00:33:06.575403 | controller | Ignoring certifi: markers 'python_version < "3"' don't match your environment
2025-11-19 00:33:06.591618 | controller | Looking in indexes: http://172.17.0.2:3141/root/pypi/+simple/
2025-11-19 00:33:06.592779 | 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-19 00:33:06.618252 | 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-19 00:33:06.621875 | 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-19 00:33:06.625176 | 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-19 00:33:06.627660 | 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-19 00:33:06.633742 | 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-19 00:33:06.639319 | 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-19 00:33:06.643445 | 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-19 00:33:06.645458 | 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-19 00:33:06.666147 | 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-19 00:33:06.669968 | 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-19 00:33:06.671999 | 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-19 00:33:06.674638 | 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-19 00:33:06.678301 | 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-19 00:33:06.681167 | controller | Collecting astroid==2.3.3 (from -c /root/ansible/test/lib/ansible_test/_data/requirements/constraints.txt (line 54))
2025-11-19 00:33:21.704945 | 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-19 00:33:37.236290 | 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-19 00:33:53.263982 | 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-19 00:34:10.273013 | 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-19 00:34:29.291952 | 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-19 00:34:44.312993 | 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-19 00:34:44.313286 | 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-19 00:34:44.456895 | 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-19 00:34:44.457212 | controller | Removing custom PyPI config: /root/.pydistutils.cfg
2025-11-19 00:34:44.457625 | controller | Removing custom PyPI config: /root/.pip/pip.conf
2025-11-19 00:34:44.457817 | controller | Removing custom PyPI hosts entries: /etc/hosts
2025-11-19 00:34:44.522580 | controller | Run command: docker exec 16b612d5c738de2a34cb8cd4ea46d47bd05992e97d8a11ccc54fd3c75604aed6 tar czf /root/output.tgz --exclude .tmp -C /root/ansible/ansible_collections/ansible/network/tests output
2025-11-19 00:34:44.613682 | controller | Run command: docker exec -i 16b612d5c738de2a34cb8cd4ea46d47bd05992e97d8a11ccc54fd3c75604aed6 dd if=/root/output.tgz bs=65536
2025-11-19 00:34:44.696054 | controller | Run command: tar oxzf /tmp/ansible-result-ffybgqk_.tgz -C /home/zuul/.ansible/collections/ansible_collections/ansible/network/tests
2025-11-19 00:34:44.709619 | controller | Run command: docker rm -f e992ee62552ab9b3fdc6fba331d6c749b8f6a772437602616a12413f76ef6c99
2025-11-19 00:34:45.029020 | controller | Run command: docker rm -f 16b612d5c738de2a34cb8cd4ea46d47bd05992e97d8a11ccc54fd3c75604aed6
2025-11-19 00:34:45.873166 | controller | ERROR: Command "docker exec 16b612d5c738de2a34cb8cd4ea46d47bd05992e97d8a11ccc54fd3c75604aed6 /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-xeudim8p.json --truncate 0 --redact --color no --requirements --pypi-endpoint http://172.17.0.2:3141/root/pypi/+simple/" returned exit status 1.
2025-11-19 00:34:46.330879 | controller | ERROR
2025-11-19 00:34:46.331185 | controller | {
2025-11-19 00:34:46.331370 | controller |   "delta": "0:05:33.311397",
2025-11-19 00:34:46.331417 | controller |   "end": "2025-11-19 00:34:45.943015",
2025-11-19 00:34:46.331456 | controller |   "msg": "non-zero return code",
2025-11-19 00:34:46.331494 | controller |   "rc": 1,
2025-11-19 00:34:46.331531 | controller |   "start": "2025-11-19 00:29:12.631618"
2025-11-19 00:34:46.331580 | controller | }
failure
2025-11-19 00:34:46.341209 | 
2025-11-19 00:34:46.341323 | PLAY RECAP
2025-11-19 00:34:46.342262 | controller | ok: 21 changed: 6 unreachable: 0 failed: 1 skipped: 20 rescued: 0 ignored: 1
2025-11-19 00:34:46.342332 | 
2025-11-19 00:34:46.498306 | RUN END RESULT_NORMAL: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/run.yaml@master]
2025-11-19 00:34:46.500754 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master]
2025-11-19 00:34:47.099940 | 
2025-11-19 00:34:47.100072 | PLAY [all]
2025-11-19 00:34:47.121319 | 
2025-11-19 00:34:47.121438 | TASK [fetch-subunit-output : Find stestr or testr executable]
2025-11-19 00:34:50.147067 | controller | changed: non-zero return code
2025-11-19 00:34:50.162249 | 
2025-11-19 00:34:50.162470 | TASK [fetch-subunit-output : Get the list of directories with subunit files]
2025-11-19 00:34:50.190191 | controller | skipping: Conditional result was False
2025-11-19 00:34:50.204059 | 
2025-11-19 00:34:50.204234 | LOOP [fetch-subunit-output : Find any inflight partial subunit files]
2025-11-19 00:34:50.246944 | 
2025-11-19 00:34:50.247283 | LOOP [fetch-subunit-output : Copy any inflight subunit files]
2025-11-19 00:34:50.288475 | 
2025-11-19 00:34:50.288752 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream]
2025-11-19 00:34:50.316572 | controller | skipping: Conditional result was False
2025-11-19 00:34:50.330390 | 
2025-11-19 00:34:50.330538 | LOOP [fetch-subunit-output : Generate subunit file]
2025-11-19 00:34:50.370327 | 
2025-11-19 00:34:50.370488 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory]
2025-11-19 00:34:50.384835 | controller | skipping: Conditional result was False
2025-11-19 00:34:50.392431 | 
2025-11-19 00:34:50.392522 | TASK [fetch-subunit-output : Remove the temporary file]
2025-11-19 00:34:50.406069 | controller | skipping: Conditional result was False
2025-11-19 00:34:50.412319 | 
2025-11-19 00:34:50.412417 | TASK [fetch-subunit-output : Process and fetch subunit results]
2025-11-19 00:34:50.436503 | controller | skipping: Conditional result was False
2025-11-19 00:34:50.477198 | 
2025-11-19 00:34:50.477318 | PLAY RECAP
2025-11-19 00:34:50.477374 | controller | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0
2025-11-19 00:34:50.477406 | 
2025-11-19 00:34:50.573498 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master]
2025-11-19 00:34:50.574389 | POST-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/post.yaml@master]
2025-11-19 00:34:51.160766 | 
2025-11-19 00:34:51.160878 | PLAY [all:!appliance*]
2025-11-19 00:34:51.182001 | 
2025-11-19 00:34:51.182128 | TASK [unregister the node]
2025-11-19 00:34:51.534164 | controller | skipping: Conditional result was False
2025-11-19 00:34:51.547632 | 
2025-11-19 00:34:51.547944 | TASK [include_role : fetch-output]
2025-11-19 00:34:51.608569 | controller | ok
2025-11-19 00:34:51.646819 | 
2025-11-19 00:34:51.646933 | TASK [fetch-output : Set log path for multiple nodes]
2025-11-19 00:34:51.723762 | controller | skipping: Conditional result was False
2025-11-19 00:34:51.732710 | 
2025-11-19 00:34:51.732806 | TASK [fetch-output : Set log path for single node]
2025-11-19 00:34:51.778779 | controller | ok
2025-11-19 00:34:51.786465 | 
2025-11-19 00:34:51.786559 | LOOP [fetch-output : Ensure local output dirs]
2025-11-19 00:34:52.253367 | controller -> localhost | ok: "/var/lib/zuul/builds/63270ec1d8164135a5e6038b05c5664f/work/logs"
2025-11-19 00:34:52.509884 | controller -> localhost | changed: "/var/lib/zuul/builds/63270ec1d8164135a5e6038b05c5664f/work/artifacts"
2025-11-19 00:34:52.734842 | controller -> localhost | changed: "/var/lib/zuul/builds/63270ec1d8164135a5e6038b05c5664f/work/docs"
2025-11-19 00:34:52.753846 | 
2025-11-19 00:34:52.754070 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-11-19 00:34:55.065692 | controller | changed:
2025-11-19 00:34:55.066138 | controller | .d..t...... ./
2025-11-19 00:34:55.066201 | controller | cd+++++++++ controller/
2025-11-19 00:34:55.066282 | controller | changed: All items complete
2025-11-19 00:34:55.066329 | 
2025-11-19 00:34:57.146612 | controller | changed: .d..t...... ./
2025-11-19 00:34:59.214336 | controller | changed: .d..t...... ./
2025-11-19 00:34:59.233064 | 
2025-11-19 00:34:59.233232 | TASK [include_role : fetch-output-openshift]
2025-11-19 00:34:59.259161 | controller | skipping: Conditional result was False
2025-11-19 00:34:59.267877 | 
2025-11-19 00:34:59.267962 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2025-11-19 00:34:59.315321 | controller | skipping: Conditional result was False
2025-11-19 00:34:59.325970 | controller | skipping: Conditional result was False
2025-11-19 00:34:59.373591 | 
2025-11-19 00:34:59.373700 | PLAY [localhost]
2025-11-19 00:34:59.395142 | 
2025-11-19 00:34:59.395248 | TASK [Run Zuul manifest role]
2025-11-19 00:34:59.415503 | localhost | ok
2025-11-19 00:34:59.444643 | 
2025-11-19 00:34:59.444780 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2025-11-19 00:34:59.838301 | localhost | changed
2025-11-19 00:34:59.848557 | 
2025-11-19 00:34:59.848697 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2025-11-19 00:34:59.898140 | localhost | ok
2025-11-19 00:34:59.918330 | 
2025-11-19 00:34:59.918539 | TASK [Set zuul-log-path fact]
2025-11-19 00:34:59.942141 | localhost | ok
2025-11-19 00:34:59.988064 | 
2025-11-19 00:34:59.988177 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-11-19 00:35:00.017764 | localhost | ok
2025-11-19 00:35:00.029249 | 
2025-11-19 00:35:00.029332 | LOOP [Run upload-logs-swift role]
2025-11-19 00:35:00.075913 | localhost | Output suppressed because no_log was given
2025-11-19 00:35:00.118127 | 
2025-11-19 00:35:00.118226 | TASK [Set zuul-log-path fact]
2025-11-19 00:35:00.142740 | localhost | skipping: Conditional result was False
2025-11-19 00:35:00.151318 | 
2025-11-19 00:35:00.151419 | TASK [upload-logs-swift : Ensure logs are readable before uploading]
2025-11-19 00:35:00.584076 | localhost -> localhost | ok: Runtime: 0:00:00.008225
2025-11-19 00:35:00.589588 | 
2025-11-19 00:35:00.589668 | TASK [upload-logs-swift : Upload logs to swift]