2025-10-05 00:06:33.864665 | Job console starting...
2025-10-05 00:06:33.877263 | Updating repositories
2025-10-05 00:06:34.093888 | Preparing job workspace
2025-10-05 00:06:38.151110 | Running Ansible setup...
2025-10-05 00:06:44.019744 | PRE-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master]
2025-10-05 00:06:44.734438 | 
2025-10-05 00:06:44.734592 | PLAY [localhost]
2025-10-05 00:06:44.744759 | 
2025-10-05 00:06:44.744883 | TASK [Gathering Facts]
2025-10-05 00:06:45.712793 | localhost | ok
2025-10-05 00:06:45.733255 | 
2025-10-05 00:06:45.733407 | TASK [Setup log path fact]
2025-10-05 00:06:45.753613 | localhost | ok
2025-10-05 00:06:45.770999 | 
2025-10-05 00:06:45.771124 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-10-05 00:06:45.801260 | localhost | ok
2025-10-05 00:06:45.812614 | 
2025-10-05 00:06:45.812711 | TASK [emit-job-header : Print job information]
2025-10-05 00:06:45.853373 | # Job Information
2025-10-05 00:06:45.853572 | Ansible Version: 2.15.12
2025-10-05 00:06:45.853616 | Job: ansible-test-sanity-docker-stable-2.12
2025-10-05 00:06:45.853645 | Pipeline: periodic
2025-10-05 00:06:45.853670 | Executor: ze01.softwarefactory-project.io
2025-10-05 00:06:45.853695 | Triggered by: https://github.com/ansible-collections/ansible.network
2025-10-05 00:06:45.853724 | Log URL (when completed): https://ansible.softwarefactory-project.io/logs/3f5/ansible/3f53970449d7456abfbca85bf1f7e4e6/
2025-10-05 00:06:45.853750 | Event ID: 82198ffb092345eeb6b1fdd59cdb0848
2025-10-05 00:06:45.859234 | 
2025-10-05 00:06:45.859329 | LOOP [emit-job-header : Print node information]
2025-10-05 00:06:45.964183 | localhost | ok:
2025-10-05 00:06:45.964390 | localhost | # Node Information
2025-10-05 00:06:45.964418 | localhost | Inventory Hostname: controller
2025-10-05 00:06:45.964438 | localhost | Hostname: ip-172-16-72-118
2025-10-05 00:06:45.964456 | localhost | Username: zuul-worker
2025-10-05 00:06:45.964480 | localhost | Distro: Fedora 37
2025-10-05 00:06:45.964500 | localhost | Provider: ansible-us-east-2
2025-10-05 00:06:45.964517 | localhost | Region: us-east-2
2025-10-05 00:06:45.964534 | localhost | Label: ansible-fedora-37-1vcpu
2025-10-05 00:06:45.964550 | localhost | Product Name: t3.small
2025-10-05 00:06:45.964567 | localhost | Interface IP: 3.139.91.85
2025-10-05 00:06:45.973337 | 
2025-10-05 00:06:45.973469 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2025-10-05 00:06:46.349427 | localhost -> localhost | changed
2025-10-05 00:06:46.364810 | 
2025-10-05 00:06:46.364928 | TASK [log-inventory : Copy ansible inventory to logs dir]
2025-10-05 00:06:47.290873 | localhost -> localhost | changed
2025-10-05 00:06:47.312083 | 
2025-10-05 00:06:47.312175 | PLAY [all:!appliance*]
2025-10-05 00:06:47.327975 | 
2025-10-05 00:06:47.328115 | TASK [include_role : start-zuul-console]
2025-10-05 00:06:47.347112 | controller | ok
2025-10-05 00:06:47.360619 | 
2025-10-05 00:06:47.360700 | TASK [start-zuul-console : Start zuul_console daemon.]
2025-10-05 00:06:48.009950 | controller | ok
2025-10-05 00:06:48.032998 | 
2025-10-05 00:06:48.033218 | TASK [use-our-mirror : Retrieve the IP address]
2025-10-05 00:06:49.432069 | controller | ok: Status code was 404 and not [200]: HTTP Error 404: Not Found
2025-10-05 00:06:49.442653 | 
2025-10-05 00:06:49.442797 | TASK [use-our-mirror : Ensure the registry service is running on the mirror]
2025-10-05 00:06:49.583831 | controller | skipping: Conditional result was False
2025-10-05 00:06:49.592914 | 
2025-10-05 00:06:49.593059 | TASK [use-our-mirror : Write the IP in /etc/hosts]
2025-10-05 00:06:49.617138 | controller | skipping: Conditional result was False
2025-10-05 00:06:49.629246 | 
2025-10-05 00:06:49.629376 | TASK [use-our-mirror : Create the podman configuration directory]
2025-10-05 00:06:49.654448 | controller | skipping: Conditional result was False
2025-10-05 00:06:49.660199 | 
2025-10-05 00:06:49.660285 | TASK [use-our-mirror : Copy the podman configuration]
2025-10-05 00:06:49.684273 | controller | skipping: Conditional result was False
2025-10-05 00:06:49.689985 | 
2025-10-05 00:06:49.690084 | TASK [use-our-mirror : Copy the Yum configuration (main)]
2025-10-05 00:06:49.714632 | controller | skipping: Conditional result was False
2025-10-05 00:06:49.720899 | 
2025-10-05 00:06:49.720985 | TASK [use-our-mirror : Copy the Yum configuration (update)]
2025-10-05 00:06:49.734877 | controller | skipping: Conditional result was False
2025-10-05 00:06:49.746120 | 
2025-10-05 00:06:49.746219 | TASK [Disable Fedora Modular]
2025-10-05 00:06:50.502660 | controller | changed
2025-10-05 00:06:50.511605 | 
2025-10-05 00:06:50.511737 | TASK [Enable EPEL]
2025-10-05 00:06:50.536857 | controller | skipping: Conditional result was False
2025-10-05 00:06:50.546299 | 
2025-10-05 00:06:50.546438 | TASK [Register the RHEL node]
2025-10-05 00:06:50.700733 | 
2025-10-05 00:06:50.700919 | TASK [Show the subscription-manager status]
2025-10-05 00:06:50.849340 | controller | skipping: Conditional result was False
2025-10-05 00:06:50.886861 | 
2025-10-05 00:06:50.886977 | TASK [Enable EPEL on RHEL]
2025-10-05 00:06:51.044035 | controller | skipping: Conditional result was False
2025-10-05 00:06:51.057290 | 
2025-10-05 00:06:51.057463 | TASK [Install git and tox]
2025-10-05 00:08:20.949249 | controller | changed
2025-10-05 00:08:20.958929 | 
2025-10-05 00:08:20.959204 | TASK [include_role : prepare-workspace]
2025-10-05 00:08:20.989520 | controller | ok
2025-10-05 00:08:21.010477 | 
2025-10-05 00:08:21.010600 | TASK [prepare-workspace : Start zuul_console daemon.]
2025-10-05 00:08:21.533117 | controller | ok
2025-10-05 00:08:21.542306 | 
2025-10-05 00:08:21.542398 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2025-10-05 00:08:34.835270 | controller | Output suppressed because no_log was given
2025-10-05 00:08:34.860530 | 
2025-10-05 00:08:34.860684 | TASK [include_role : prepare-workspace-openshift]
2025-10-05 00:08:34.893804 | controller | skipping: Conditional result was False
2025-10-05 00:08:34.925689 | 
2025-10-05 00:08:34.925804 | PLAY [all:!appliance]
2025-10-05 00:08:34.953482 | 
2025-10-05 00:08:34.953609 | TASK [Run add-build-sshkey role (RSA)]
2025-10-05 00:08:35.001291 | controller | ok
2025-10-05 00:08:35.027961 | 
2025-10-05 00:08:35.028113 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2025-10-05 00:08:35.454214 | controller -> localhost | ok
2025-10-05 00:08:35.465461 | 
2025-10-05 00:08:35.465624 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2025-10-05 00:08:35.501814 | controller | ok
2025-10-05 00:08:35.525533 | controller | included: /var/lib/zuul/builds/3f53970449d7456abfbca85bf1f7e4e6/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2025-10-05 00:08:35.535891 | 
2025-10-05 00:08:35.536072 | TASK [add-build-sshkey : Create Temp SSH key]
2025-10-05 00:08:36.084706 | controller -> localhost | Generating public/private rsa key pair.
2025-10-05 00:08:36.085150 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/3f53970449d7456abfbca85bf1f7e4e6/work/3f53970449d7456abfbca85bf1f7e4e6_id_rsa.
2025-10-05 00:08:36.085279 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/3f53970449d7456abfbca85bf1f7e4e6/work/3f53970449d7456abfbca85bf1f7e4e6_id_rsa.pub.
2025-10-05 00:08:36.085410 | controller -> localhost | The key fingerprint is:
2025-10-05 00:08:36.085465 | controller -> localhost | SHA256:Am2/Lk8i4s3mCTnsuDRjyEHP5zSUDZ9RVCTe0WnA/KU zuul-build-sshkey
2025-10-05 00:08:36.085511 | controller -> localhost | The key's randomart image is:
2025-10-05 00:08:36.085554 | controller -> localhost | +---[RSA 2048]----+
2025-10-05 00:08:36.085595 | controller -> localhost | |     . .o+==o .  |
2025-10-05 00:08:36.085638 | controller -> localhost | |     .= + oo.+ . |
2025-10-05 00:08:36.085680 | controller -> localhost | | .  .oo+ . .o o  |
2025-10-05 00:08:36.085721 | controller -> localhost | |. o .o .     E   |
2025-10-05 00:08:36.085797 | controller -> localhost | | . o +. S        |
2025-10-05 00:08:36.085866 | controller -> localhost | |o...+ .. .       |
2025-10-05 00:08:36.085924 | controller -> localhost | |.** ... o        |
2025-10-05 00:08:36.085972 | controller -> localhost | |o+o*.o.+         |
2025-10-05 00:08:36.086045 | controller -> localhost | |o.oo=  oo        |
2025-10-05 00:08:36.086107 | controller -> localhost | +----[SHA256]-----+
2025-10-05 00:08:36.086244 | controller -> localhost | ok: Runtime: 0:00:00.046758
2025-10-05 00:08:36.105217 | 
2025-10-05 00:08:36.105423 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2025-10-05 00:08:36.140107 | controller | ok
2025-10-05 00:08:36.181964 | controller | included: /var/lib/zuul/builds/3f53970449d7456abfbca85bf1f7e4e6/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2025-10-05 00:08:36.191321 | 
2025-10-05 00:08:36.191389 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2025-10-05 00:08:36.215547 | controller | skipping: Conditional result was False
2025-10-05 00:08:36.221639 | 
2025-10-05 00:08:36.221703 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2025-10-05 00:08:36.861685 | controller | changed
2025-10-05 00:08:36.871634 | 
2025-10-05 00:08:36.871862 | TASK [add-build-sshkey : Make sure user has a .ssh]
2025-10-05 00:08:37.216700 | controller | ok
2025-10-05 00:08:37.230858 | 
2025-10-05 00:08:37.231046 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2025-10-05 00:08:38.508177 | controller | changed
2025-10-05 00:08:38.516212 | 
2025-10-05 00:08:38.516327 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2025-10-05 00:08:39.771637 | controller | changed
2025-10-05 00:08:39.782968 | 
2025-10-05 00:08:39.783124 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2025-10-05 00:08:39.809451 | controller | skipping: Conditional result was False
2025-10-05 00:08:39.817546 | 
2025-10-05 00:08:39.817664 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2025-10-05 00:08:40.220373 | controller -> localhost | changed
2025-10-05 00:08:40.240423 | 
2025-10-05 00:08:40.240580 | TASK [add-build-sshkey : Add back temp key]
2025-10-05 00:08:40.564415 | controller -> localhost | Identity added: /var/lib/zuul/builds/3f53970449d7456abfbca85bf1f7e4e6/work/3f53970449d7456abfbca85bf1f7e4e6_id_rsa (zuul-build-sshkey)
2025-10-05 00:08:40.564667 | controller -> localhost | ok: Runtime: 0:00:00.015600
2025-10-05 00:08:40.571159 | 
2025-10-05 00:08:40.571238 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2025-10-05 00:08:41.065780 | controller | ok
2025-10-05 00:08:41.078797 | 
2025-10-05 00:08:41.078947 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2025-10-05 00:08:41.118343 | controller | skipping: Conditional result was False
2025-10-05 00:08:41.145075 | 
2025-10-05 00:08:41.145269 | TASK [Run add-build-sshkey role (ECDSA)]
2025-10-05 00:08:41.190982 | controller | ok
2025-10-05 00:08:41.219520 | 
2025-10-05 00:08:41.219647 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2025-10-05 00:08:41.524082 | controller -> localhost | ok
2025-10-05 00:08:41.532776 | 
2025-10-05 00:08:41.532861 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2025-10-05 00:08:41.565350 | controller | ok
2025-10-05 00:08:41.580661 | controller | included: /var/lib/zuul/builds/3f53970449d7456abfbca85bf1f7e4e6/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2025-10-05 00:08:41.589999 | 
2025-10-05 00:08:41.590119 | TASK [add-build-sshkey : Create Temp SSH key]
2025-10-05 00:08:41.937630 | controller -> localhost | Generating public/private ecdsa key pair.
2025-10-05 00:08:41.938072 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/3f53970449d7456abfbca85bf1f7e4e6/work/3f53970449d7456abfbca85bf1f7e4e6_id_ecdsa.
2025-10-05 00:08:41.938189 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/3f53970449d7456abfbca85bf1f7e4e6/work/3f53970449d7456abfbca85bf1f7e4e6_id_ecdsa.pub.
2025-10-05 00:08:41.938294 | controller -> localhost | The key fingerprint is:
2025-10-05 00:08:41.938375 | controller -> localhost | SHA256:/XfnukLiUZtNOuiGKSun8RmAqbsXhV5twNAxP+XcDFs zuul-build-sshkey
2025-10-05 00:08:41.938448 | controller -> localhost | The key's randomart image is:
2025-10-05 00:08:41.938521 | controller -> localhost | +---[ECDSA 521]---+
2025-10-05 00:08:41.938591 | controller -> localhost | |  .+o.  o E      |
2025-10-05 00:08:41.938660 | controller -> localhost | |    +o + *       |
2025-10-05 00:08:41.938731 | controller -> localhost | |   . oo + o      |
2025-10-05 00:08:41.938802 | controller -> localhost | |  .oo o. .  . .  |
2025-10-05 00:08:41.938875 | controller -> localhost | | .oo..  S .o *   |
2025-10-05 00:08:41.938945 | controller -> localhost | | .o  .    +.* .  |
2025-10-05 00:08:41.939023 | controller -> localhost | |.  .. .  = +... o|
2025-10-05 00:08:41.939149 | controller -> localhost | | .. .oooo + .. o.|
2025-10-05 00:08:41.939222 | controller -> localhost | |oo  .++o .   .oo.|
2025-10-05 00:08:41.939295 | controller -> localhost | +----[SHA256]-----+
2025-10-05 00:08:41.939435 | controller -> localhost | ok: Runtime: 0:00:00.009065
2025-10-05 00:08:41.949917 | 
2025-10-05 00:08:41.950073 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2025-10-05 00:08:41.979922 | controller | ok
2025-10-05 00:08:41.987598 | controller | included: /var/lib/zuul/builds/3f53970449d7456abfbca85bf1f7e4e6/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2025-10-05 00:08:41.996607 | 
2025-10-05 00:08:41.996715 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2025-10-05 00:08:42.021408 | controller | skipping: Conditional result was False
2025-10-05 00:08:42.028124 | 
2025-10-05 00:08:42.028236 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2025-10-05 00:08:42.502446 | controller | changed
2025-10-05 00:08:42.516139 | 
2025-10-05 00:08:42.516337 | TASK [add-build-sshkey : Make sure user has a .ssh]
2025-10-05 00:08:42.845200 | controller | ok
2025-10-05 00:08:42.857417 | 
2025-10-05 00:08:42.857560 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2025-10-05 00:08:44.097235 | controller | changed
2025-10-05 00:08:44.106492 | 
2025-10-05 00:08:44.106632 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2025-10-05 00:08:45.339898 | controller | changed
2025-10-05 00:08:45.353589 | 
2025-10-05 00:08:45.353736 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2025-10-05 00:08:45.382817 | controller | skipping: Conditional result was False
2025-10-05 00:08:45.400984 | 
2025-10-05 00:08:45.401177 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2025-10-05 00:08:45.735672 | controller -> localhost | changed
2025-10-05 00:08:45.747365 | 
2025-10-05 00:08:45.747482 | TASK [add-build-sshkey : Add back temp key]
2025-10-05 00:08:46.038544 | controller -> localhost | Identity added: /var/lib/zuul/builds/3f53970449d7456abfbca85bf1f7e4e6/work/3f53970449d7456abfbca85bf1f7e4e6_id_ecdsa (zuul-build-sshkey)
2025-10-05 00:08:46.038788 | controller -> localhost | ok: Runtime: 0:00:00.019003
2025-10-05 00:08:46.049350 | 
2025-10-05 00:08:46.049466 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2025-10-05 00:08:46.377312 | controller | ok
2025-10-05 00:08:46.385617 | 
2025-10-05 00:08:46.385745 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2025-10-05 00:08:46.422566 | controller | skipping: Conditional result was False
2025-10-05 00:08:46.450657 | 
2025-10-05 00:08:46.450837 | TASK [include_role : remove-zuul-sshkey]
2025-10-05 00:08:46.478868 | controller | skipping: Conditional result was False
2025-10-05 00:08:46.494094 | 
2025-10-05 00:08:46.494259 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2025-10-05 00:08:46.828703 | controller | ok: "logs"
2025-10-05 00:08:46.829228 | controller | ok: All items complete
2025-10-05 00:08:46.829297 | 
2025-10-05 00:08:47.099676 | controller | ok: "artifacts"
2025-10-05 00:08:47.368926 | controller | ok: "docs"
2025-10-05 00:08:47.376901 | 
2025-10-05 00:08:47.377022 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2025-10-05 00:08:47.701749 | controller | changed: "logs"
2025-10-05 00:08:47.989142 | controller | changed: "artifacts"
2025-10-05 00:08:48.275432 | controller | changed: "docs"
2025-10-05 00:08:48.375236 | 
2025-10-05 00:08:48.375345 | PLAY RECAP
2025-10-05 00:08:48.375386 | controller | ok: 30 changed: 16 unreachable: 0 failed: 0 skipped: 18 rescued: 0 ignored: 0
2025-10-05 00:08:48.375412 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-10-05 00:08:48.375429 | 
2025-10-05 00:08:48.505385 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master]
2025-10-05 00:08:48.506238 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master]
2025-10-05 00:08:49.157212 | 
2025-10-05 00:08:49.157362 | PLAY [all]
2025-10-05 00:08:49.179274 | 
2025-10-05 00:08:49.179385 | TASK [Install binary dependencies]
2025-10-05 00:08:49.229974 | controller | ok
2025-10-05 00:08:49.251461 | 
2025-10-05 00:08:49.251614 | TASK [bindep : Include find tasks]
2025-10-05 00:08:49.294679 | controller | ok
2025-10-05 00:08:49.306194 | controller | included: /var/lib/zuul/builds/3f53970449d7456abfbca85bf1f7e4e6/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find.yaml
2025-10-05 00:08:49.315667 | 
2025-10-05 00:08:49.315768 | TASK [bindep : Look for bindep.txt]
2025-10-05 00:08:49.851160 | controller | ok
2025-10-05 00:08:49.857888 | 
2025-10-05 00:08:49.857964 | TASK [bindep : Define bindep_file fact]
2025-10-05 00:08:49.882691 | controller | skipping: Conditional result was False
2025-10-05 00:08:49.891335 | 
2025-10-05 00:08:49.891533 | TASK [bindep : Look for other-requirements.txt]
2025-10-05 00:08:50.483193 | controller | ok
2025-10-05 00:08:50.498253 | 
2025-10-05 00:08:50.498440 | TASK [bindep : Define bindep_file fact]
2025-10-05 00:08:50.526685 | controller | skipping: Conditional result was False
2025-10-05 00:08:50.540094 | 
2025-10-05 00:08:50.540245 | TASK [bindep : Look for bindep fallback file]
2025-10-05 00:08:50.568395 | controller | skipping: Conditional result was False
2025-10-05 00:08:50.582452 | 
2025-10-05 00:08:50.582602 | TASK [bindep : Define bindep_file fact]
2025-10-05 00:08:50.610176 | controller | skipping: Conditional result was False
2025-10-05 00:08:50.619930 | 
2025-10-05 00:08:50.620045 | TASK [bindep : Include bindep tasks]
2025-10-05 00:08:50.645231 | controller | skipping: Conditional result was False
2025-10-05 00:08:50.654328 | 
2025-10-05 00:08:50.654422 | TASK [bindep : Include install tasks]
2025-10-05 00:08:50.679875 | controller | skipping: Conditional result was False
2025-10-05 00:08:50.688283 | 
2025-10-05 00:08:50.688375 | LOOP [bindep : Include package tasks]
2025-10-05 00:08:50.750014 | 
2025-10-05 00:08:50.750263 | TASK [Run test-setup role]
2025-10-05 00:08:50.773357 | controller | ok
2025-10-05 00:08:50.801265 | 
2025-10-05 00:08:50.801520 | TASK [test-setup : Check if project's tools/test-setup.sh exists]
2025-10-05 00:08:51.109902 | controller | ok
2025-10-05 00:08:51.119186 | 
2025-10-05 00:08:51.119303 | TASK [test-setup : Run tools/test-setup.sh]
2025-10-05 00:08:51.263201 | controller | skipping: Conditional result was False
2025-10-05 00:08:51.296332 | 
2025-10-05 00:08:51.296431 | PLAY RECAP
2025-10-05 00:08:51.296472 | controller | ok: 4 changed: 0 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0
2025-10-05 00:08:51.296494 | 
2025-10-05 00:08:51.418061 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master]
2025-10-05 00:08:51.419152 | PRE-RUN START: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master]
2025-10-05 00:08:52.017527 | 
2025-10-05 00:08:52.017687 | PLAY [controller]
2025-10-05 00:08:52.038439 | 
2025-10-05 00:08:52.038556 | TASK [Create the /root directory]
2025-10-05 00:08:52.805306 | controller | ok
2025-10-05 00:08:52.824712 | 
2025-10-05 00:08:52.824970 | TASK [Install glibc-langpack-en]
2025-10-05 00:09:00.881073 | controller | ok: Nothing to do
2025-10-05 00:09:00.888854 | 
2025-10-05 00:09:00.888950 | TASK [Ensure controller directory exists]
2025-10-05 00:09:01.384270 | controller | changed
2025-10-05 00:09:01.396697 | 
2025-10-05 00:09:01.396851 | TASK [Install container runtime]
2025-10-05 00:09:01.440827 | controller | ok
2025-10-05 00:09:01.475789 | 
2025-10-05 00:09:01.475906 | LOOP [ensure-podman : Find distribution installation]
2025-10-05 00:09:01.504219 | controller | ok: "/var/lib/zuul/builds/3f53970449d7456abfbca85bf1f7e4e6/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-podman/tasks/RedHat.yaml"
2025-10-05 00:09:01.519042 | controller | included: /var/lib/zuul/builds/3f53970449d7456abfbca85bf1f7e4e6/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-podman/tasks/RedHat.yaml
2025-10-05 00:09:01.525823 | 
2025-10-05 00:09:01.525901 | TASK [ensure-podman : Install podman (RedHat)]
2025-10-05 00:10:30.572403 | controller | changed
2025-10-05 00:10:30.579557 | 
2025-10-05 00:10:30.579647 | TASK [ensure-podman : Fetch podman version]
2025-10-05 00:10:31.317974 | controller | Client:       Podman Engine
2025-10-05 00:10:31.344897 | controller | Version:      4.6.2
2025-10-05 00:10:31.344963 | controller | API Version:  4.6.2
2025-10-05 00:10:31.344974 | controller | Go Version:   go1.19.12
2025-10-05 00:10:31.344994 | controller | Built:        Mon Aug 28 19:38:31 2023
2025-10-05 00:10:31.345002 | controller | OS/Arch:      linux/amd64
2025-10-05 00:10:31.718389 | controller | ok: Runtime: 0:00:00.216829
2025-10-05 00:10:31.727796 | 
2025-10-05 00:10:31.727946 | TASK [ensure-podman : Print podman version installed]
2025-10-05 00:10:31.759692 | Podman version: Client:       Podman Engine
2025-10-05 00:10:31.759850 | Version:      4.6.2
2025-10-05 00:10:31.759880 | API Version:  4.6.2
2025-10-05 00:10:31.759901 | Go Version:   go1.19.12
2025-10-05 00:10:31.759919 | Built:        Mon Aug 28 19:38:31 2023
2025-10-05 00:10:31.759939 | OS/Arch:      linux/amd64
2025-10-05 00:10:31.765238 | 
2025-10-05 00:10:31.765298 | TASK [ensure-podman : Validate podman engine]
2025-10-05 00:10:31.895010 | controller | skipping: Conditional result was False
2025-10-05 00:10:31.909718 | 
2025-10-05 00:10:31.909932 | TASK [ensure-podman : Set up docker compatability socket]
2025-10-05 00:10:31.937874 | controller | skipping: Conditional result was False
2025-10-05 00:10:31.963517 | 
2025-10-05 00:10:31.963715 | TASK [Ensure python3.8 is present]
2025-10-05 00:10:31.991905 | controller | skipping: Conditional result was False
2025-10-05 00:10:32.006278 | 
2025-10-05 00:10:32.006493 | TASK [Run our-ensure-python role (Fedora only for now)]
2025-10-05 00:10:32.036592 | controller | ok
2025-10-05 00:10:32.059409 | 
2025-10-05 00:10:32.059543 | TASK [our-ensure-python : Install the right Python version (rpm)]
2025-10-05 00:10:35.861913 | controller | ok: Nothing to do
2025-10-05 00:10:35.877213 | 
2025-10-05 00:10:35.877383 | TASK [our-ensure-python : Also install python3-devel]
2025-10-05 00:10:48.915193 | controller | changed
2025-10-05 00:10:48.942698 | 
2025-10-05 00:10:48.942879 | TASK [Run ensure-virtualenv role]
2025-10-05 00:10:48.977000 | controller | ok
2025-10-05 00:10:49.006088 | 
2025-10-05 00:10:49.006234 | TASK [ensure-virtualenv : Check if virtualenv is installed]
2025-10-05 00:10:49.382017 | controller | /usr/bin/virtualenv
2025-10-05 00:10:49.641002 | controller | ok: Runtime: 0:00:00.004391
2025-10-05 00:10:49.654800 | 
2025-10-05 00:10:49.654973 | LOOP [ensure-virtualenv : Install virtualenv package]
2025-10-05 00:10:49.674447 | controller | skipping: Conditional result was False
2025-10-05 00:10:49.674709 | controller | ok: All items complete
2025-10-05 00:10:49.674738 | 
2025-10-05 00:10:49.692771 | 
2025-10-05 00:10:49.692891 | TASK [Find the full path of the Python interpreter]
2025-10-05 00:10:50.131064 | controller | /usr/bin/python3.10
2025-10-05 00:10:50.361532 | controller | ok
2025-10-05 00:10:50.367357 | 
2025-10-05 00:10:50.367426 | TASK [Create virtualenv for ansible-test with a specific interpreter]
2025-10-05 00:10:51.890999 | controller | created virtual environment CPython3.10.13.final.0-64 in 919ms
2025-10-05 00:10:51.937660 | controller |   creator CPython3Posix(dest=/home/zuul-worker/venv, clear=False, no_vcs_ignore=False, global=False)
2025-10-05 00:10:51.937719 | controller |   seeder FromAppData(extra_search_dir=/usr/share/python-wheels,download=False, pip=bundle, setuptools=bundle, wheel=bundle, via=copy, app_data_dir=/home/zuul-worker/.local/share/virtualenv)
2025-10-05 00:10:51.937737 | controller |     added seed packages: pip==22.2.2, setuptools==62.6.0, wheel==0.37.1
2025-10-05 00:10:51.937761 | controller |   activators BashActivator,CShellActivator,FishActivator,NushellActivator,PowerShellActivator,PythonActivator
2025-10-05 00:10:52.005562 | controller | changed
2025-10-05 00:10:52.014176 | 
2025-10-05 00:10:52.014288 | TASK [Set selinux package]
2025-10-05 00:10:52.053092 | controller | ok
2025-10-05 00:10:52.066241 | 
2025-10-05 00:10:52.066422 | TASK [Set selinux package (Fedora)]
2025-10-05 00:10:52.110473 | controller | ok
2025-10-05 00:10:52.117791 | 
2025-10-05 00:10:52.117882 | TASK [Install selinux into virtualenv]
2025-10-05 00:10:54.772353 | controller | Collecting selinux-please-lie-to-me
2025-10-05 00:10:54.869284 | controller |   Downloading selinux_please_lie_to_me-1.0.1-py2.py3-none-any.whl (3.8 kB)
2025-10-05 00:10:55.471615 | controller | Collecting setuptools<50.0.0
2025-10-05 00:10:55.488306 | controller |   Downloading setuptools-49.6.0-py3-none-any.whl (803 kB)
2025-10-05 00:10:55.589293 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 803.3/803.3 kB 8.4 MB/s eta 0:00:00
2025-10-05 00:10:55.731540 | controller | Installing collected packages: setuptools, selinux-please-lie-to-me
2025-10-05 00:10:55.731887 | controller |   Attempting uninstall: setuptools
2025-10-05 00:10:55.733355 | controller |     Found existing installation: setuptools 62.6.0
2025-10-05 00:10:55.861869 | controller |     Uninstalling setuptools-62.6.0:
2025-10-05 00:10:55.878966 | controller |       Successfully uninstalled setuptools-62.6.0
2025-10-05 00:10:56.578405 | controller | Successfully installed selinux-please-lie-to-me-1.0.1 setuptools-49.6.0
2025-10-05 00:10:56.800825 | controller |
2025-10-05 00:10:57.002227 | controller | [notice] A new release of pip available: 22.2.2 -> 25.2
2025-10-05 00:10:57.002272 | controller | [notice] To update, run: /home/zuul-worker/venv/bin/python -m pip install --upgrade pip
2025-10-05 00:10:57.276440 | controller | ok: Runtime: 0:00:04.401703
2025-10-05 00:10:57.289607 | 
2025-10-05 00:10:57.289831 | TASK [Install pytest-forked into virtualenv]
2025-10-05 00:10:58.617747 | controller | Collecting pytest-forked
2025-10-05 00:10:58.702221 | controller |   Downloading pytest_forked-1.6.0-py3-none-any.whl (4.9 kB)
2025-10-05 00:10:58.911563 | controller | Collecting pytest>=3.10
2025-10-05 00:10:58.924064 | controller |   Downloading pytest-8.4.2-py3-none-any.whl (365 kB)
2025-10-05 00:10:58.987582 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 365.8/365.8 kB 6.5 MB/s eta 0:00:00
2025-10-05 00:10:59.045958 | controller | Collecting py
2025-10-05 00:10:59.058834 | controller |   Downloading py-1.11.0-py2.py3-none-any.whl (98 kB)
2025-10-05 00:10:59.073786 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 98.7/98.7 kB 8.4 MB/s eta 0:00:00
2025-10-05 00:10:59.172293 | controller | Collecting packaging>=20
2025-10-05 00:10:59.185159 | controller |   Downloading packaging-25.0-py3-none-any.whl (66 kB)
2025-10-05 00:10:59.195811 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 66.5/66.5 kB 8.9 MB/s eta 0:00:00
2025-10-05 00:10:59.274077 | controller | Collecting pygments>=2.7.2
2025-10-05 00:10:59.295496 | controller |   Downloading pygments-2.19.2-py3-none-any.whl (1.2 MB)
2025-10-05 00:10:59.382151 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 1.2/1.2 MB 14.8 MB/s eta 0:00:00
2025-10-05 00:10:59.425427 | controller | Collecting iniconfig>=1
2025-10-05 00:10:59.437865 | controller |   Downloading iniconfig-2.1.0-py3-none-any.whl (6.0 kB)
2025-10-05 00:10:59.492238 | controller | Collecting exceptiongroup>=1
2025-10-05 00:10:59.505942 | controller |   Downloading exceptiongroup-1.3.0-py3-none-any.whl (16 kB)
2025-10-05 00:10:59.571012 | controller | Collecting tomli>=1
2025-10-05 00:10:59.583454 | controller |   Downloading tomli-2.2.1-py3-none-any.whl (14 kB)
2025-10-05 00:10:59.639149 | controller | Collecting pluggy<2,>=1.5
2025-10-05 00:10:59.651978 | controller |   Downloading pluggy-1.6.0-py3-none-any.whl (20 kB)
2025-10-05 00:10:59.736492 | controller | Collecting typing-extensions>=4.6.0
2025-10-05 00:10:59.748884 | controller |   Downloading typing_extensions-4.15.0-py3-none-any.whl (44 kB)
2025-10-05 00:10:59.758887 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 44.6/44.6 kB 6.1 MB/s eta 0:00:00
2025-10-05 00:10:59.886100 | controller | Installing collected packages: typing-extensions, tomli, pygments, py, pluggy, packaging, iniconfig, exceptiongroup, pytest, pytest-forked
2025-10-05 00:11:02.218654 | controller | Successfully installed exceptiongroup-1.3.0 iniconfig-2.1.0 packaging-25.0 pluggy-1.6.0 py-1.11.0 pygments-2.19.2 pytest-8.4.2 pytest-forked-1.6.0 tomli-2.2.1 typing-extensions-4.15.0
2025-10-05 00:11:02.228552 | controller |
2025-10-05 00:11:02.516029 | controller | [notice] A new release of pip available: 22.2.2 -> 25.2
2025-10-05 00:11:02.516075 | controller | [notice] To update, run: /home/zuul-worker/venv/bin/python -m pip install --upgrade pip
2025-10-05 00:11:02.975819 | controller | ok: Runtime: 0:00:04.732782
2025-10-05 00:11:02.989903 | 
2025-10-05 00:11:02.990160 | TASK [Update pip]
2025-10-05 00:11:04.235830 | controller | Requirement already satisfied: pip in ./venv/lib/python3.10/site-packages (22.2.2)
2025-10-05 00:11:04.514633 | controller | Collecting pip
2025-10-05 00:11:04.612329 | controller |   Downloading pip-25.2-py3-none-any.whl (1.8 MB)
2025-10-05 00:11:04.773166 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 1.8/1.8 MB 11.2 MB/s eta 0:00:00
2025-10-05 00:11:04.915785 | controller | Installing collected packages: pip
2025-10-05 00:11:04.916255 | controller |   Attempting uninstall: pip
2025-10-05 00:11:04.917660 | controller |     Found existing installation: pip 22.2.2
2025-10-05 00:11:05.220043 | controller |     Uninstalling pip-22.2.2:
2025-10-05 00:11:05.254598 | controller |       Successfully uninstalled pip-22.2.2
2025-10-05 00:11:06.977228 | controller | Successfully installed pip-25.2
2025-10-05 00:11:07.655054 | controller | ok: Runtime: 0:00:03.549837
2025-10-05 00:11:07.661726 | 
2025-10-05 00:11:07.661831 | TASK [Ensure pip temp directory exists in /var/tmp]
2025-10-05 00:11:08.212574 | controller | changed
2025-10-05 00:11:08.220488 | 
2025-10-05 00:11:08.220623 | TASK [Install ansible into virtualenv]
2025-10-05 00:11:09.434557 | controller | Processing ./src/github.com/ansible/ansible
2025-10-05 00:11:09.436575 | controller |   Preparing metadata (setup.py): started
2025-10-05 00:11:10.432006 | controller |   Preparing metadata (setup.py): finished with status 'done'
2025-10-05 00:11:10.577131 | controller | Collecting jinja2 (from ansible-core==2.12.10.post0)
2025-10-05 00:11:10.679211 | controller |   Downloading jinja2-3.1.6-py3-none-any.whl.metadata (2.9 kB)
2025-10-05 00:11:10.825555 | controller | Collecting PyYAML (from ansible-core==2.12.10.post0)
2025-10-05 00:11:10.838095 | controller |   Downloading pyyaml-6.0.3-cp310-cp310-manylinux2014_x86_64.manylinux_2_17_x86_64.manylinux_2_28_x86_64.whl.metadata (2.4 kB)
2025-10-05 00:11:11.361848 | controller | Collecting cryptography (from ansible-core==2.12.10.post0)
2025-10-05 00:11:11.374728 | controller |   Downloading cryptography-46.0.2-cp38-abi3-manylinux_2_34_x86_64.whl.metadata (5.7 kB)
2025-10-05 00:11:11.403784 | controller | Requirement already satisfied: packaging in ./venv/lib/python3.10/site-packages (from ansible-core==2.12.10.post0) (25.0)
2025-10-05 00:11:11.433303 | controller | Collecting resolvelib<0.6.0,>=0.5.3 (from ansible-core==2.12.10.post0)
2025-10-05 00:11:11.448469 | controller |   Downloading resolvelib-0.5.4-py2.py3-none-any.whl.metadata (3.7 kB)
2025-10-05 00:11:11.764330 | controller | Collecting cffi>=2.0.0 (from cryptography->ansible-core==2.12.10.post0)
2025-10-05 00:11:11.777062 | controller |   Downloading cffi-2.0.0-cp310-cp310-manylinux2014_x86_64.manylinux_2_17_x86_64.whl.metadata (2.6 kB)
2025-10-05 00:11:11.787825 | controller | Requirement already satisfied: typing-extensions>=4.13.2 in ./venv/lib/python3.10/site-packages (from cryptography->ansible-core==2.12.10.post0) (4.15.0)
2025-10-05 00:11:11.809929 | controller | Collecting pycparser (from cffi>=2.0.0->cryptography->ansible-core==2.12.10.post0)
2025-10-05 00:11:11.822464 | controller |   Downloading pycparser-2.23-py3-none-any.whl.metadata (993 bytes)
2025-10-05 00:11:11.980417 | controller | Collecting MarkupSafe>=2.0 (from jinja2->ansible-core==2.12.10.post0)
2025-10-05 00:11:11.993723 | controller |   Downloading markupsafe-3.0.3-cp310-cp310-manylinux2014_x86_64.manylinux_2_17_x86_64.manylinux_2_28_x86_64.whl.metadata (2.7 kB)
2025-10-05 00:11:12.023439 | controller | Downloading resolvelib-0.5.4-py2.py3-none-any.whl (12 kB)
2025-10-05 00:11:12.044563 | controller | Downloading cryptography-46.0.2-cp38-abi3-manylinux_2_34_x86_64.whl (4.5 MB)
2025-10-05 00:11:12.171902 | controller |    ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 4.5/4.5 MB 44.7 MB/s  0:00:00
2025-10-05 00:11:12.186032 | controller | Downloading cffi-2.0.0-cp310-cp310-manylinux2014_x86_64.manylinux_2_17_x86_64.whl (216 kB)
2025-10-05 00:11:12.208384 | controller | Downloading jinja2-3.1.6-py3-none-any.whl (134 kB)
2025-10-05 00:11:12.235230 | controller | Downloading markupsafe-3.0.3-cp310-cp310-manylinux2014_x86_64.manylinux_2_17_x86_64.manylinux_2_28_x86_64.whl (20 kB)
2025-10-05 00:11:12.255638 | controller | Downloading pycparser-2.23-py3-none-any.whl (118 kB)
2025-10-05 00:11:12.278003 | controller | Downloading pyyaml-6.0.3-cp310-cp310-manylinux2014_x86_64.manylinux_2_17_x86_64.manylinux_2_28_x86_64.whl (770 kB)
2025-10-05 00:11:12.295637 | controller |    ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 770.3/770.3 kB 40.6 MB/s  0:00:00
2025-10-05 00:11:12.361233 | controller | Building wheels for collected packages: ansible-core
2025-10-05 00:11:12.364069 | controller |   DEPRECATION: Building 'ansible-core' using the legacy setup.py bdist_wheel mechanism, which will be removed in a future version. pip 25.3 will enforce this behaviour change. A possible replacement is to use the standardized build interface by setting the `--use-pep517` option, (possibly combined with `--no-build-isolation`), or adding a `pyproject.toml` file to the source tree of 'ansible-core'. Discussion can be found at https://github.com/pypa/pip/issues/6334
2025-10-05 00:11:12.364854 | controller |   Building wheel for ansible-core (setup.py): started
2025-10-05 00:11:14.963002 | controller |   Building wheel for ansible-core (setup.py): finished with status 'done'
2025-10-05 00:11:14.974832 | controller |   Created wheel for ansible-core: filename=ansible_core-2.12.10.post0-py3-none-any.whl size=2117426 sha256=c91572ae6235fc26eb7debde1619930bec11cef6f2d7a0cda0fbd147339458f2
2025-10-05 00:11:14.975335 | controller |   Stored in directory: /var/tmp/ansible-test-pip/pip-ephem-wheel-cache-g6xdvo7c/wheels/16/41/d9/87e90d8b7da5963c4aded34a9a739f0b6a61cf129309c38be9
2025-10-05 00:11:14.991717 | controller | Successfully built ansible-core
2025-10-05 00:11:15.061065 | controller | Installing collected packages: resolvelib, PyYAML, pycparser, MarkupSafe, jinja2, cffi, cryptography, ansible-core
2025-10-05 00:11:18.117670 | controller |
2025-10-05 00:11:18.120827 | controller | Successfully installed MarkupSafe-3.0.3 PyYAML-6.0.3 ansible-core-2.12.10.post0 cffi-2.0.0 cryptography-46.0.2 jinja2-3.1.6 pycparser-2.23 resolvelib-0.5.4
2025-10-05 00:11:18.380698 | controller | ok: Runtime: 0:00:09.634466
2025-10-05 00:11:18.398490 | 
2025-10-05 00:11:18.398686 | TASK [Setup download-artifact-fork role]
2025-10-05 00:11:18.432512 | controller | ok
2025-10-05 00:11:18.466377 | 
2025-10-05 00:11:18.466618 | TASK [download-artifact-fork : Parse inventory response]
2025-10-05 00:11:18.518277 | controller | ok
2025-10-05 00:11:18.525330 | 
2025-10-05 00:11:18.525436 | TASK [download-artifact-fork : Fetch info from Zuul API]
2025-10-05 00:11:18.550286 | controller | skipping: Conditional result was False
2025-10-05 00:11:18.558603 | 
2025-10-05 00:11:18.558710 | TASK [download-artifact-fork : Parse build response]
2025-10-05 00:11:18.603820 | controller | skipping: Conditional result was False
2025-10-05 00:11:18.611137 | 
2025-10-05 00:11:18.611271 | TASK [download-artifact-fork : Ensure artifacts directory exists]
2025-10-05 00:11:19.133068 | controller | changed
2025-10-05 00:11:19.139231 | 
2025-10-05 00:11:19.139324 | LOOP [download-artifact-fork : Download archive by type]
2025-10-05 00:11:19.175766 | controller | skipping: Conditional result was False
2025-10-05 00:11:19.176052 | 
2025-10-05 00:11:20.771392 | controller | changed: OK (20212 bytes)
2025-10-05 00:11:20.794134 | 
2025-10-05 00:11:20.794295 | TASK [deploy-artifacts : define __collections]
2025-10-05 00:11:20.821437 | controller | ok
2025-10-05 00:11:20.832875 | 
2025-10-05 00:11:20.833011 | LOOP [deploy-artifacts : Create list of collections]
2025-10-05 00:11:20.859115 | controller | skipping: Conditional result was False
2025-10-05 00:11:20.868980 | controller | ok:
2025-10-05 00:11:20.869068 | controller | {
2025-10-05 00:11:20.869092 | controller |   "branch": "main",
2025-10-05 00:11:20.869111 | controller |   "job": "build-ansible-collection",
2025-10-05 00:11:20.869128 | controller |   "metadata": {
2025-10-05 00:11:20.869144 | controller |     "type": "ansible_collection",
2025-10-05 00:11:20.869161 | controller |     "version": "5.0.0"
2025-10-05 00:11:20.869176 | controller |   },
2025-10-05 00:11:20.869191 | controller |   "name": "ansible.network",
2025-10-05 00:11:20.869207 | controller |   "project": "ansible-collections/ansible.network",
2025-10-05 00:11:20.869223 | controller |   "url": "https://0b954735594a780b35b6-ec09ba963dce14fb0f4736f1166b4a51.ssl.cf5.rackcdn.com/ansible/320090d05aeb4e7fbadb8fbe910ab4ec/artifacts/ansible-network-5.0.0.tar.gz"
2025-10-05 00:11:20.869244 | controller | }
2025-10-05 00:11:20.883049 | 
2025-10-05 00:11:20.883193 | TASK [deploy-artifacts : Install require-project collection using ansible-galaxy]
2025-10-05 00:11:22.118758 | controller | Starting galaxy collection install process
2025-10-05 00:11:22.119232 | controller | Process install dependency map
2025-10-05 00:11:43.223240 | controller | ERROR! Error when getting the collection info for ansible.netcommon from default (https://galaxy.ansible.com/api/) (HTTP Code: 502, Message: Bad Gateway Code: Unknown)
2025-10-05 00:11:43.522990 | controller | ERROR
2025-10-05 00:11:43.523309 | controller | {
2025-10-05 00:11:43.523355 | controller |   "delta": "0:00:21.970321",
2025-10-05 00:11:43.523393 | controller |   "end": "2025-10-05 00:11:43.303274",
2025-10-05 00:11:43.523422 | controller |   "msg": "non-zero return code",
2025-10-05 00:11:43.523468 | controller |   "rc": 1,
2025-10-05 00:11:43.523497 | controller |   "start": "2025-10-05 00:11:21.332953"
2025-10-05 00:11:43.523531 | controller | }
failure
2025-10-05 00:11:43.527549 | 
2025-10-05 00:11:43.527630 | PLAY RECAP
2025-10-05 00:11:43.527712 | controller | ok: 24 changed: 13 unreachable: 0 failed: 1 skipped: 6 rescued: 0 ignored: 0
2025-10-05 00:11:43.527746 | 
2025-10-05 00:11:43.652728 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master]
2025-10-05 00:11:43.653724 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master]
2025-10-05 00:11:44.274041 | 
2025-10-05 00:11:44.274187 | PLAY [all]
2025-10-05 00:11:44.296446 | 
2025-10-05 00:11:44.296590 | TASK [fetch-subunit-output : Find stestr or testr executable]
2025-10-05 00:11:45.064188 | controller | changed: non-zero return code
2025-10-05 00:11:45.077980 | 
2025-10-05 00:11:45.078238 | TASK [fetch-subunit-output : Get the list of directories with subunit files]
2025-10-05 00:11:45.105924 | controller | skipping: Conditional result was False
2025-10-05 00:11:45.121891 | 
2025-10-05 00:11:45.122146 | LOOP [fetch-subunit-output : Find any inflight partial subunit files]
2025-10-05 00:11:45.159488 | 
2025-10-05 00:11:45.159705 | LOOP [fetch-subunit-output : Copy any inflight subunit files]
2025-10-05 00:11:45.193184 | 
2025-10-05 00:11:45.193359 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream]
2025-10-05 00:11:45.217620 | controller | skipping: Conditional result was False
2025-10-05 00:11:45.227514 | 
2025-10-05 00:11:45.227682 | LOOP [fetch-subunit-output : Generate subunit file]
2025-10-05 00:11:45.251539 | 
2025-10-05 00:11:45.251716 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory]
2025-10-05 00:11:45.265960 | controller | skipping: Conditional result was False
2025-10-05 00:11:45.272608 | 
2025-10-05 00:11:45.272731 | TASK [fetch-subunit-output : Remove the temporary file]
2025-10-05 00:11:45.286951 | controller | skipping: Conditional result was False
2025-10-05 00:11:45.296297 | 
2025-10-05 00:11:45.296447 | TASK [fetch-subunit-output : Process and fetch subunit results]
2025-10-05 00:11:45.321855 | controller | skipping: Conditional result was False
2025-10-05 00:11:45.361727 | 
2025-10-05 00:11:45.361854 | PLAY RECAP
2025-10-05 00:11:45.361911 | controller | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0
2025-10-05 00:11:45.361989 | 
2025-10-05 00:11:45.494785 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master]
2025-10-05 00:11:45.495854 | POST-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/post.yaml@master]
2025-10-05 00:11:46.106542 | 
2025-10-05 00:11:46.106726 | PLAY [all:!appliance*]
2025-10-05 00:11:46.127937 | 
2025-10-05 00:11:46.128070 | TASK [unregister the node]
2025-10-05 00:11:46.257374 | controller | skipping: Conditional result was False
2025-10-05 00:11:46.270648 | 
2025-10-05 00:11:46.270858 | TASK [include_role : fetch-output]
2025-10-05 00:11:46.309863 | controller | ok
2025-10-05 00:11:46.348627 | 
2025-10-05 00:11:46.348775 | TASK [fetch-output : Set log path for multiple nodes]
2025-10-05 00:11:46.395197 | controller | skipping: Conditional result was False
2025-10-05 00:11:46.403451 | 
2025-10-05 00:11:46.403545 | TASK [fetch-output : Set log path for single node]
2025-10-05 00:11:46.451622 | controller | ok
2025-10-05 00:11:46.464263 | 
2025-10-05 00:11:46.464410 | LOOP [fetch-output : Ensure local output dirs]
2025-10-05 00:11:46.902379 | controller -> localhost | ok: "/var/lib/zuul/builds/3f53970449d7456abfbca85bf1f7e4e6/work/logs"
2025-10-05 00:11:47.116711 | controller -> localhost | changed: "/var/lib/zuul/builds/3f53970449d7456abfbca85bf1f7e4e6/work/artifacts"
2025-10-05 00:11:47.373490 | controller -> localhost | changed: "/var/lib/zuul/builds/3f53970449d7456abfbca85bf1f7e4e6/work/docs"
2025-10-05 00:11:47.396069 | 
2025-10-05 00:11:47.398478 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-10-05 00:11:48.672865 | controller | changed:
2025-10-05 00:11:48.673209 | controller | .d..t...... ./
2025-10-05 00:11:48.673250 | controller | cd+++++++++ controller/
2025-10-05 00:11:48.673303 | controller | changed: All items complete
2025-10-05 00:11:48.673334 | 
2025-10-05 00:11:49.825395 | controller | changed: .d..t...... ./
2025-10-05 00:11:50.889575 | controller | changed: .d..t...... ./
2025-10-05 00:11:50.909731 | 
2025-10-05 00:11:50.909859 | TASK [include_role : fetch-output-openshift]
2025-10-05 00:11:50.924302 | controller | skipping: Conditional result was False
2025-10-05 00:11:50.934388 | 
2025-10-05 00:11:50.934491 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2025-10-05 00:11:50.972970 | controller | skipping: Conditional result was False
2025-10-05 00:11:50.985466 | controller | skipping: Conditional result was False
2025-10-05 00:11:51.030770 | 
2025-10-05 00:11:51.030915 | PLAY [localhost]
2025-10-05 00:11:51.049135 | 
2025-10-05 00:11:51.049260 | TASK [Run Zuul manifest role]
2025-10-05 00:11:51.069651 | localhost | ok
2025-10-05 00:11:51.082644 | 
2025-10-05 00:11:51.082709 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2025-10-05 00:11:51.501099 | localhost | changed
2025-10-05 00:11:51.512484 | 
2025-10-05 00:11:51.512651 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2025-10-05 00:11:51.551214 | localhost | ok
2025-10-05 00:11:51.570931 | 
2025-10-05 00:11:51.571191 | TASK [Set zuul-log-path fact]
2025-10-05 00:11:51.592639 | localhost | ok
2025-10-05 00:11:51.611578 | 
2025-10-05 00:11:51.611693 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-10-05 00:11:51.643072 | localhost | ok
2025-10-05 00:11:51.658932 | 
2025-10-05 00:11:51.659126 | LOOP [Run upload-logs-swift role]
2025-10-05 00:11:51.696379 | localhost | Output suppressed because no_log was given
2025-10-05 00:11:51.726296 | 
2025-10-05 00:11:51.726425 | TASK [Set zuul-log-path fact]
2025-10-05 00:11:51.751166 | localhost | skipping: Conditional result was False
2025-10-05 00:11:51.756847 | 
2025-10-05 00:11:51.756926 | TASK [upload-logs-swift : Ensure logs are readable before uploading]
2025-10-05 00:11:52.189755 | localhost -> localhost | ok: Runtime: 0:00:00.005740
2025-10-05 00:11:52.198311 | 
2025-10-05 00:11:52.198417 | TASK [upload-logs-swift : Upload logs to swift]