2025-10-20 00:06:36.994418 | Job console starting...
2025-10-20 00:06:37.004275 | Updating repositories
2025-10-20 00:06:37.261499 | Preparing job workspace
2025-10-20 00:06:43.907959 | Running Ansible setup...
2025-10-20 00:06:49.948590 | PRE-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master]
2025-10-20 00:06:50.604384 | 
2025-10-20 00:06:50.604507 | PLAY [localhost]
2025-10-20 00:06:50.613643 | 
2025-10-20 00:06:50.613745 | TASK [Gathering Facts]
2025-10-20 00:06:51.713155 | localhost | ok
2025-10-20 00:06:51.739837 | 
2025-10-20 00:06:51.739943 | TASK [Setup log path fact]
2025-10-20 00:06:51.763654 | localhost | ok
2025-10-20 00:06:51.780569 | 
2025-10-20 00:06:51.780699 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-10-20 00:06:51.810411 | localhost | ok
2025-10-20 00:06:51.821543 | 
2025-10-20 00:06:51.821642 | TASK [emit-job-header : Print job information]
2025-10-20 00:06:51.861679 | # Job Information
2025-10-20 00:06:51.861865 | Ansible Version: 2.15.12
2025-10-20 00:06:51.861909 | Job: ansible-test-sanity-docker-devel
2025-10-20 00:06:51.861939 | Pipeline: periodic
2025-10-20 00:06:51.861964 | Executor: ze01.softwarefactory-project.io
2025-10-20 00:06:51.861989 | Triggered by: https://github.com/ansible-collections/ansible.network
2025-10-20 00:06:51.862036 | Log URL (when completed): https://ansible.softwarefactory-project.io/logs/708/ansible/70896dbb58e24e56b0e53d4fc2fe83e9/
2025-10-20 00:06:51.862069 | Event ID: 328e9597672940b996d379e8913dc261
2025-10-20 00:06:51.867070 | 
2025-10-20 00:06:51.867156 | LOOP [emit-job-header : Print node information]
2025-10-20 00:06:51.985372 | localhost | ok:
2025-10-20 00:06:51.985969 | localhost | # Node Information
2025-10-20 00:06:51.986074 | localhost | Inventory Hostname: controller
2025-10-20 00:06:51.986122 | localhost | Hostname: ip-172-16-153-9
2025-10-20 00:06:51.986166 | localhost | Username: zuul-worker
2025-10-20 00:06:51.986256 | localhost | Distro: Fedora 37
2025-10-20 00:06:51.986305 | localhost | Provider: ansible-us-east-2
2025-10-20 00:06:51.986384 | localhost | Region: us-east-2
2025-10-20 00:06:51.986427 | localhost | Label: ansible-fedora-37-1vcpu
2025-10-20 00:06:51.986463 | localhost | Product Name: t3.small
2025-10-20 00:06:51.986499 | localhost | Interface IP: 3.16.138.97
2025-10-20 00:06:52.022101 | 
2025-10-20 00:06:52.022289 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2025-10-20 00:06:52.490934 | localhost -> localhost | changed
2025-10-20 00:06:52.498388 | 
2025-10-20 00:06:52.498508 | TASK [log-inventory : Copy ansible inventory to logs dir]
2025-10-20 00:06:53.649484 | localhost -> localhost | changed
2025-10-20 00:06:53.670060 | 
2025-10-20 00:06:53.670130 | PLAY [all:!appliance*]
2025-10-20 00:06:53.686047 | 
2025-10-20 00:06:53.686150 | TASK [include_role : start-zuul-console]
2025-10-20 00:06:53.705820 | controller | ok
2025-10-20 00:06:53.721945 | 
2025-10-20 00:06:53.722087 | TASK [start-zuul-console : Start zuul_console daemon.]
2025-10-20 00:06:54.441134 | controller | ok
2025-10-20 00:06:54.463378 | 
2025-10-20 00:06:54.463496 | TASK [use-our-mirror : Retrieve the IP address]
2025-10-20 00:06:56.683726 | controller | ok: Status code was 404 and not [200]: HTTP Error 404: Not Found
2025-10-20 00:06:56.698315 | 
2025-10-20 00:06:56.698976 | TASK [use-our-mirror : Ensure the registry service is running on the mirror]
2025-10-20 00:06:56.844284 | controller | skipping: Conditional result was False
2025-10-20 00:06:56.852927 | 
2025-10-20 00:06:56.853115 | TASK [use-our-mirror : Write the IP in /etc/hosts]
2025-10-20 00:06:56.881192 | controller | skipping: Conditional result was False
2025-10-20 00:06:56.890583 | 
2025-10-20 00:06:56.890695 | TASK [use-our-mirror : Create the podman configuration directory]
2025-10-20 00:06:56.917097 | controller | skipping: Conditional result was False
2025-10-20 00:06:56.926044 | 
2025-10-20 00:06:56.926141 | TASK [use-our-mirror : Copy the podman configuration]
2025-10-20 00:06:56.950488 | controller | skipping: Conditional result was False
2025-10-20 00:06:56.957365 | 
2025-10-20 00:06:56.957464 | TASK [use-our-mirror : Copy the Yum configuration (main)]
2025-10-20 00:06:56.981214 | controller | skipping: Conditional result was False
2025-10-20 00:06:56.987489 | 
2025-10-20 00:06:56.987565 | TASK [use-our-mirror : Copy the Yum configuration (update)]
2025-10-20 00:06:57.001534 | controller | skipping: Conditional result was False
2025-10-20 00:06:57.014998 | 
2025-10-20 00:06:57.015123 | TASK [Disable Fedora Modular]
2025-10-20 00:06:57.795441 | controller | changed
2025-10-20 00:06:57.801844 | 
2025-10-20 00:06:57.801940 | TASK [Enable EPEL]
2025-10-20 00:06:57.827204 | controller | skipping: Conditional result was False
2025-10-20 00:06:57.834889 | 
2025-10-20 00:06:57.834977 | TASK [Register the RHEL node]
2025-10-20 00:06:57.990180 | 
2025-10-20 00:06:57.990368 | TASK [Show the subscription-manager status]
2025-10-20 00:06:58.181211 | controller | skipping: Conditional result was False
2025-10-20 00:06:58.190632 | 
2025-10-20 00:06:58.190777 | TASK [Enable EPEL on RHEL]
2025-10-20 00:06:58.363185 | controller | skipping: Conditional result was False
2025-10-20 00:06:58.373805 | 
2025-10-20 00:06:58.373964 | TASK [Install git and tox]
2025-10-20 00:08:27.771596 | controller | changed
2025-10-20 00:08:27.784822 | 
2025-10-20 00:08:27.784984 | TASK [include_role : prepare-workspace]
2025-10-20 00:08:27.843611 | controller | ok
2025-10-20 00:08:27.876107 | 
2025-10-20 00:08:27.876240 | TASK [prepare-workspace : Start zuul_console daemon.]
2025-10-20 00:08:28.448882 | controller | ok
2025-10-20 00:08:28.466299 | 
2025-10-20 00:08:28.466481 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2025-10-20 00:08:49.349302 | controller | Output suppressed because no_log was given
2025-10-20 00:08:49.383822 | 
2025-10-20 00:08:49.384011 | TASK [include_role : prepare-workspace-openshift]
2025-10-20 00:08:49.410758 | controller | skipping: Conditional result was False
2025-10-20 00:08:49.452487 | 
2025-10-20 00:08:49.452616 | PLAY [all:!appliance]
2025-10-20 00:08:49.503522 | 
2025-10-20 00:08:49.503637 | TASK [Run add-build-sshkey role (RSA)]
2025-10-20 00:08:49.554942 | controller | ok
2025-10-20 00:08:49.576271 | 
2025-10-20 00:08:49.576379 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2025-10-20 00:08:49.875507 | controller -> localhost | ok
2025-10-20 00:08:49.899341 | 
2025-10-20 00:08:49.899602 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2025-10-20 00:08:49.942770 | controller | ok
2025-10-20 00:08:49.993198 | controller | included: /var/lib/zuul/builds/70896dbb58e24e56b0e53d4fc2fe83e9/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2025-10-20 00:08:50.002346 | 
2025-10-20 00:08:50.002449 | TASK [add-build-sshkey : Create Temp SSH key]
2025-10-20 00:08:50.599377 | controller -> localhost | Generating public/private rsa key pair.
2025-10-20 00:08:50.599744 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/70896dbb58e24e56b0e53d4fc2fe83e9/work/70896dbb58e24e56b0e53d4fc2fe83e9_id_rsa.
2025-10-20 00:08:50.599810 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/70896dbb58e24e56b0e53d4fc2fe83e9/work/70896dbb58e24e56b0e53d4fc2fe83e9_id_rsa.pub.
2025-10-20 00:08:50.599858 | controller -> localhost | The key fingerprint is:
2025-10-20 00:08:50.599906 | controller -> localhost | SHA256:xkwuT3jobJy1MXbNNjElhF7uWcCExD6juWfseX0iHjM zuul-build-sshkey
2025-10-20 00:08:50.599949 | controller -> localhost | The key's randomart image is:
2025-10-20 00:08:50.599991 | controller -> localhost | +---[RSA 2048]----+
2025-10-20 00:08:50.601416 | controller -> localhost | |         o.*+ .  |
2025-10-20 00:08:50.601567 | controller -> localhost | |          + +o   |
2025-10-20 00:08:50.601640 | controller -> localhost | |        .o oo.   |
2025-10-20 00:08:50.601700 | controller -> localhost | |       B  =o.o.  |
2025-10-20 00:08:50.601758 | controller -> localhost | |      + So.+=o   |
2025-10-20 00:08:50.601839 | controller -> localhost | |     + Xo= .o.   |
2025-10-20 00:08:50.601911 | controller -> localhost | |      * oo  E.   |
2025-10-20 00:08:50.601972 | controller -> localhost | |     .  . +.o+o .|
2025-10-20 00:08:50.602075 | controller -> localhost | |         +oo.. o |
2025-10-20 00:08:50.602148 | controller -> localhost | +----[SHA256]-----+
2025-10-20 00:08:50.602273 | controller -> localhost | ok: Runtime: 0:00:00.115402
2025-10-20 00:08:50.620532 | 
2025-10-20 00:08:50.620703 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2025-10-20 00:08:50.673515 | controller | ok
2025-10-20 00:08:50.713829 | controller | included: /var/lib/zuul/builds/70896dbb58e24e56b0e53d4fc2fe83e9/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2025-10-20 00:08:50.730574 | 
2025-10-20 00:08:50.730906 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2025-10-20 00:08:50.756914 | controller | skipping: Conditional result was False
2025-10-20 00:08:50.768262 | 
2025-10-20 00:08:50.768429 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2025-10-20 00:08:51.504460 | controller | changed
2025-10-20 00:08:51.517807 | 
2025-10-20 00:08:51.517978 | TASK [add-build-sshkey : Make sure user has a .ssh]
2025-10-20 00:08:51.894084 | controller | ok
2025-10-20 00:08:51.909189 | 
2025-10-20 00:08:51.909540 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2025-10-20 00:08:53.527120 | controller | changed
2025-10-20 00:08:53.541445 | 
2025-10-20 00:08:53.541604 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2025-10-20 00:08:55.001469 | controller | changed
2025-10-20 00:08:55.014364 | 
2025-10-20 00:08:55.014503 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2025-10-20 00:08:55.042318 | controller | skipping: Conditional result was False
2025-10-20 00:08:55.057437 | 
2025-10-20 00:08:55.057627 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2025-10-20 00:08:55.585580 | controller -> localhost | changed
2025-10-20 00:08:55.610750 | 
2025-10-20 00:08:55.610928 | TASK [add-build-sshkey : Add back temp key]
2025-10-20 00:08:55.934535 | controller -> localhost | Identity added: /var/lib/zuul/builds/70896dbb58e24e56b0e53d4fc2fe83e9/work/70896dbb58e24e56b0e53d4fc2fe83e9_id_rsa (zuul-build-sshkey)
2025-10-20 00:08:55.934815 | controller -> localhost | ok: Runtime: 0:00:00.017745
2025-10-20 00:08:55.943529 | 
2025-10-20 00:08:55.943668 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2025-10-20 00:08:56.465343 | controller | ok
2025-10-20 00:08:56.479622 | 
2025-10-20 00:08:56.479790 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2025-10-20 00:08:56.533428 | controller | skipping: Conditional result was False
2025-10-20 00:08:56.560152 | 
2025-10-20 00:08:56.560488 | TASK [Run add-build-sshkey role (ECDSA)]
2025-10-20 00:08:56.615354 | controller | ok
2025-10-20 00:08:56.644867 | 
2025-10-20 00:08:56.644971 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2025-10-20 00:08:56.914216 | controller -> localhost | ok
2025-10-20 00:08:56.921167 | 
2025-10-20 00:08:56.921247 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2025-10-20 00:08:56.950323 | controller | ok
2025-10-20 00:08:56.961152 | controller | included: /var/lib/zuul/builds/70896dbb58e24e56b0e53d4fc2fe83e9/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2025-10-20 00:08:56.967543 | 
2025-10-20 00:08:56.967608 | TASK [add-build-sshkey : Create Temp SSH key]
2025-10-20 00:08:57.318287 | controller -> localhost | Generating public/private ecdsa key pair.
2025-10-20 00:08:57.318651 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/70896dbb58e24e56b0e53d4fc2fe83e9/work/70896dbb58e24e56b0e53d4fc2fe83e9_id_ecdsa.
2025-10-20 00:08:57.318710 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/70896dbb58e24e56b0e53d4fc2fe83e9/work/70896dbb58e24e56b0e53d4fc2fe83e9_id_ecdsa.pub.
2025-10-20 00:08:57.318767 | controller -> localhost | The key fingerprint is:
2025-10-20 00:08:57.318812 | controller -> localhost | SHA256:5ufgrAeTRPrblstVBEwtfEx/t8WCO1ZRsZ5vF8uqArY zuul-build-sshkey
2025-10-20 00:08:57.318855 | controller -> localhost | The key's randomart image is:
2025-10-20 00:08:57.318896 | controller -> localhost | +---[ECDSA 521]---+
2025-10-20 00:08:57.318935 | controller -> localhost | |         +o+. .oo|
2025-10-20 00:08:57.318975 | controller -> localhost | |      .   +.+o o.|
2025-10-20 00:08:57.319042 | controller -> localhost | |     o     oo +.=|
2025-10-20 00:08:57.319092 | controller -> localhost | |    . .    . o.o=|
2025-10-20 00:08:57.319131 | controller -> localhost | |     o .S   =  = |
2025-10-20 00:08:57.319168 | controller -> localhost | |      ==   o .. +|
2025-10-20 00:08:57.319204 | controller -> localhost | |      .==.o    o+|
2025-10-20 00:08:57.319243 | controller -> localhost | |      .E=*    ...|
2025-10-20 00:08:57.319281 | controller -> localhost | |      .+=.o...   |
2025-10-20 00:08:57.319318 | controller -> localhost | +----[SHA256]-----+
2025-10-20 00:08:57.319424 | controller -> localhost | ok: Runtime: 0:00:00.014230
2025-10-20 00:08:57.333889 | 
2025-10-20 00:08:57.334088 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2025-10-20 00:08:57.379899 | controller | ok
2025-10-20 00:08:57.387548 | controller | included: /var/lib/zuul/builds/70896dbb58e24e56b0e53d4fc2fe83e9/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2025-10-20 00:08:57.396670 | 
2025-10-20 00:08:57.396745 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2025-10-20 00:08:57.411441 | controller | skipping: Conditional result was False
2025-10-20 00:08:57.419776 | 
2025-10-20 00:08:57.419876 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2025-10-20 00:08:57.900490 | controller | changed
2025-10-20 00:08:57.914276 | 
2025-10-20 00:08:57.914468 | TASK [add-build-sshkey : Make sure user has a .ssh]
2025-10-20 00:08:58.298107 | controller | ok
2025-10-20 00:08:58.306701 | 
2025-10-20 00:08:58.306817 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2025-10-20 00:09:00.026428 | controller | changed
2025-10-20 00:09:00.039205 | 
2025-10-20 00:09:00.039343 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2025-10-20 00:09:02.120379 | controller | changed
2025-10-20 00:09:02.133457 | 
2025-10-20 00:09:02.133596 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2025-10-20 00:09:02.160842 | controller | skipping: Conditional result was False
2025-10-20 00:09:02.175726 | 
2025-10-20 00:09:02.175895 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2025-10-20 00:09:02.510338 | controller -> localhost | changed
2025-10-20 00:09:02.527266 | 
2025-10-20 00:09:02.527385 | TASK [add-build-sshkey : Add back temp key]
2025-10-20 00:09:02.807181 | controller -> localhost | Identity added: /var/lib/zuul/builds/70896dbb58e24e56b0e53d4fc2fe83e9/work/70896dbb58e24e56b0e53d4fc2fe83e9_id_ecdsa (zuul-build-sshkey)
2025-10-20 00:09:02.807507 | controller -> localhost | ok: Runtime: 0:00:00.006644
2025-10-20 00:09:02.820961 | 
2025-10-20 00:09:02.821156 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2025-10-20 00:09:03.415580 | controller | ok
2025-10-20 00:09:03.422967 | 
2025-10-20 00:09:03.423075 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2025-10-20 00:09:03.485232 | controller | skipping: Conditional result was False
2025-10-20 00:09:03.499691 | 
2025-10-20 00:09:03.499817 | TASK [include_role : remove-zuul-sshkey]
2025-10-20 00:09:03.526650 | controller | skipping: Conditional result was False
2025-10-20 00:09:03.542519 | 
2025-10-20 00:09:03.542681 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2025-10-20 00:09:04.035765 | controller | ok: "logs"
2025-10-20 00:09:04.036229 | controller | ok: All items complete
2025-10-20 00:09:04.036292 | 
2025-10-20 00:09:04.387535 | controller | ok: "artifacts"
2025-10-20 00:09:04.802650 | controller | ok: "docs"
2025-10-20 00:09:04.820604 | 
2025-10-20 00:09:04.820773 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2025-10-20 00:09:05.509991 | controller | changed: "logs"
2025-10-20 00:09:05.894379 | controller | changed: "artifacts"
2025-10-20 00:09:06.364389 | controller | changed: "docs"
2025-10-20 00:09:06.406099 | 
2025-10-20 00:09:06.406204 | PLAY RECAP
2025-10-20 00:09:06.406251 | controller | ok: 30 changed: 16 unreachable: 0 failed: 0 skipped: 18 rescued: 0 ignored: 0
2025-10-20 00:09:06.406277 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-10-20 00:09:06.406294 | 
2025-10-20 00:09:06.518373 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master]
2025-10-20 00:09:06.519541 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master]
2025-10-20 00:09:07.130950 | 
2025-10-20 00:09:07.131081 | PLAY [all]
2025-10-20 00:09:07.153792 | 
2025-10-20 00:09:07.153915 | TASK [Install binary dependencies]
2025-10-20 00:09:07.215258 | controller | ok
2025-10-20 00:09:07.234840 | 
2025-10-20 00:09:07.234961 | TASK [bindep : Include find tasks]
2025-10-20 00:09:07.274297 | controller | ok
2025-10-20 00:09:07.282072 | controller | included: /var/lib/zuul/builds/70896dbb58e24e56b0e53d4fc2fe83e9/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find.yaml
2025-10-20 00:09:07.287963 | 
2025-10-20 00:09:07.288045 | TASK [bindep : Look for bindep.txt]
2025-10-20 00:09:07.844693 | controller | ok
2025-10-20 00:09:07.859480 | 
2025-10-20 00:09:07.859646 | TASK [bindep : Define bindep_file fact]
2025-10-20 00:09:07.888968 | controller | skipping: Conditional result was False
2025-10-20 00:09:07.902351 | 
2025-10-20 00:09:07.902506 | TASK [bindep : Look for other-requirements.txt]
2025-10-20 00:09:08.280207 | controller | ok
2025-10-20 00:09:08.293510 | 
2025-10-20 00:09:08.293749 | TASK [bindep : Define bindep_file fact]
2025-10-20 00:09:08.332383 | controller | skipping: Conditional result was False
2025-10-20 00:09:08.345143 | 
2025-10-20 00:09:08.345350 | TASK [bindep : Look for bindep fallback file]
2025-10-20 00:09:08.382817 | controller | skipping: Conditional result was False
2025-10-20 00:09:08.395478 | 
2025-10-20 00:09:08.395836 | TASK [bindep : Define bindep_file fact]
2025-10-20 00:09:08.423047 | controller | skipping: Conditional result was False
2025-10-20 00:09:08.440064 | 
2025-10-20 00:09:08.440264 | TASK [bindep : Include bindep tasks]
2025-10-20 00:09:08.468281 | controller | skipping: Conditional result was False
2025-10-20 00:09:08.481391 | 
2025-10-20 00:09:08.481585 | TASK [bindep : Include install tasks]
2025-10-20 00:09:08.508205 | controller | skipping: Conditional result was False
2025-10-20 00:09:08.521794 | 
2025-10-20 00:09:08.521988 | LOOP [bindep : Include package tasks]
2025-10-20 00:09:08.602005 | 
2025-10-20 00:09:08.602352 | TASK [Run test-setup role]
2025-10-20 00:09:08.629766 | controller | ok
2025-10-20 00:09:08.661225 | 
2025-10-20 00:09:08.661381 | TASK [test-setup : Check if project's tools/test-setup.sh exists]
2025-10-20 00:09:09.016910 | controller | ok
2025-10-20 00:09:09.029627 | 
2025-10-20 00:09:09.029772 | TASK [test-setup : Run tools/test-setup.sh]
2025-10-20 00:09:09.196329 | controller | skipping: Conditional result was False
2025-10-20 00:09:09.248616 | 
2025-10-20 00:09:09.248857 | PLAY RECAP
2025-10-20 00:09:09.248927 | controller | ok: 4 changed: 0 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0
2025-10-20 00:09:09.248958 | 
2025-10-20 00:09:09.352454 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master]
2025-10-20 00:09:09.353273 | PRE-RUN START: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master]
2025-10-20 00:09:10.011831 | 
2025-10-20 00:09:10.011947 | PLAY [controller]
2025-10-20 00:09:10.031865 | 
2025-10-20 00:09:10.031954 | TASK [Create the /root directory]
2025-10-20 00:09:10.797178 | controller | ok
2025-10-20 00:09:10.803183 | 
2025-10-20 00:09:10.803258 | TASK [Install glibc-langpack-en]
2025-10-20 00:09:18.964525 | controller | ok: Nothing to do
2025-10-20 00:09:18.976839 | 
2025-10-20 00:09:18.976987 | TASK [Ensure controller directory exists]
2025-10-20 00:09:19.443282 | controller | changed
2025-10-20 00:09:19.456493 | 
2025-10-20 00:09:19.456704 | TASK [Install container runtime]
2025-10-20 00:09:19.533901 | controller | ok
2025-10-20 00:09:19.591800 | 
2025-10-20 00:09:19.591944 | LOOP [ensure-podman : Find distribution installation]
2025-10-20 00:09:19.642746 | controller | ok: "/var/lib/zuul/builds/70896dbb58e24e56b0e53d4fc2fe83e9/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-podman/tasks/RedHat.yaml"
2025-10-20 00:09:19.665560 | controller | included: /var/lib/zuul/builds/70896dbb58e24e56b0e53d4fc2fe83e9/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-podman/tasks/RedHat.yaml
2025-10-20 00:09:19.679811 | 
2025-10-20 00:09:19.679966 | TASK [ensure-podman : Install podman (RedHat)]
2025-10-20 00:10:39.737682 | controller | changed
2025-10-20 00:10:39.752045 | 
2025-10-20 00:10:39.752230 | TASK [ensure-podman : Fetch podman version]
2025-10-20 00:10:40.559994 | controller | Client:       Podman Engine
2025-10-20 00:10:40.560098 | controller | Version:      4.6.2
2025-10-20 00:10:40.560130 | controller | API Version:  4.6.2
2025-10-20 00:10:40.560156 | controller | Go Version:   go1.19.12
2025-10-20 00:10:40.560193 | controller | Built:        Mon Aug 28 19:38:31 2023
2025-10-20 00:10:40.560221 | controller | OS/Arch:      linux/amd64
2025-10-20 00:10:40.922182 | controller | ok: Runtime: 0:00:00.229323
2025-10-20 00:10:40.928897 | 
2025-10-20 00:10:40.928999 | TASK [ensure-podman : Print podman version installed]
2025-10-20 00:10:40.959249 | Podman version: Client:       Podman Engine
2025-10-20 00:10:40.959412 | Version:      4.6.2
2025-10-20 00:10:40.959441 | API Version:  4.6.2
2025-10-20 00:10:40.959462 | Go Version:   go1.19.12
2025-10-20 00:10:40.959481 | Built:        Mon Aug 28 19:38:31 2023
2025-10-20 00:10:40.959499 | OS/Arch:      linux/amd64
2025-10-20 00:10:40.965169 | 
2025-10-20 00:10:40.965243 | TASK [ensure-podman : Validate podman engine]
2025-10-20 00:10:41.109648 | controller | skipping: Conditional result was False
2025-10-20 00:10:41.126683 | 
2025-10-20 00:10:41.126920 | TASK [ensure-podman : Set up docker compatability socket]
2025-10-20 00:10:41.145419 | controller | skipping: Conditional result was False
2025-10-20 00:10:41.174929 | 
2025-10-20 00:10:41.175166 | TASK [Ensure python3.8 is present]
2025-10-20 00:10:41.190579 | controller | skipping: Conditional result was False
2025-10-20 00:10:41.199064 | 
2025-10-20 00:10:41.199166 | TASK [Run our-ensure-python role (Fedora only for now)]
2025-10-20 00:10:41.221197 | controller | ok
2025-10-20 00:10:41.248375 | 
2025-10-20 00:10:41.248509 | TASK [our-ensure-python : Install the right Python version (rpm)]
2025-10-20 00:10:45.108623 | controller | ok: Nothing to do
2025-10-20 00:10:45.116337 | 
2025-10-20 00:10:45.116452 | TASK [our-ensure-python : Also install python3-devel]
2025-10-20 00:10:58.717946 | controller | changed
2025-10-20 00:10:58.733501 | 
2025-10-20 00:10:58.733642 | TASK [Run ensure-virtualenv role]
2025-10-20 00:10:58.764871 | controller | ok
2025-10-20 00:10:58.811950 | 
2025-10-20 00:10:58.812174 | TASK [ensure-virtualenv : Check if virtualenv is installed]
2025-10-20 00:10:59.248430 | controller | /usr/bin/virtualenv
2025-10-20 00:10:59.504715 | controller | ok: Runtime: 0:00:00.004745
2025-10-20 00:10:59.513898 | 
2025-10-20 00:10:59.514040 | LOOP [ensure-virtualenv : Install virtualenv package]
2025-10-20 00:10:59.544902 | controller | skipping: Conditional result was False
2025-10-20 00:10:59.545384 | controller | ok: All items complete
2025-10-20 00:10:59.545443 | 
2025-10-20 00:10:59.565770 | 
2025-10-20 00:10:59.565920 | TASK [Find the full path of the Python interpreter]
2025-10-20 00:11:00.017618 | controller | /usr/bin/python3
2025-10-20 00:11:00.222935 | controller | ok
2025-10-20 00:11:00.235324 | 
2025-10-20 00:11:00.235463 | TASK [Create virtualenv for ansible-test with a specific interpreter]
2025-10-20 00:11:01.797982 | controller | created virtual environment CPython3.11.0.final.0-64 in 868ms
2025-10-20 00:11:01.956856 | controller |   creator CPython3Posix(dest=/home/zuul-worker/venv, clear=False, no_vcs_ignore=False, global=False)
2025-10-20 00:11:01.956916 | 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-20 00:11:01.956938 | controller |     added seed packages: pip==22.2.2, setuptools==62.6.0, wheel==0.37.1
2025-10-20 00:11:01.956961 | controller |   activators BashActivator,CShellActivator,FishActivator,NushellActivator,PowerShellActivator,PythonActivator
2025-10-20 00:11:02.431829 | controller | changed
2025-10-20 00:11:02.445963 | 
2025-10-20 00:11:02.446161 | TASK [Set selinux package]
2025-10-20 00:11:02.471656 | controller | ok
2025-10-20 00:11:02.479270 | 
2025-10-20 00:11:02.479362 | TASK [Set selinux package (Fedora)]
2025-10-20 00:11:02.512505 | controller | ok
2025-10-20 00:11:02.521150 | 
2025-10-20 00:11:02.521261 | TASK [Install selinux into virtualenv]
2025-10-20 00:11:05.696757 | controller | Collecting selinux-please-lie-to-me
2025-10-20 00:11:05.786414 | controller |   Downloading selinux_please_lie_to_me-1.0.1-py2.py3-none-any.whl (3.8 kB)
2025-10-20 00:11:06.319170 | controller | Collecting setuptools<50.0.0
2025-10-20 00:11:06.333093 | controller |   Downloading setuptools-49.6.0-py3-none-any.whl (803 kB)
2025-10-20 00:11:06.465882 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 803.3/803.3 kB 6.4 MB/s eta 0:00:00
2025-10-20 00:11:06.623361 | controller | Installing collected packages: setuptools, selinux-please-lie-to-me
2025-10-20 00:11:06.623653 | controller |   Attempting uninstall: setuptools
2025-10-20 00:11:06.628532 | controller |     Found existing installation: setuptools 62.6.0
2025-10-20 00:11:06.739737 | controller |     Uninstalling setuptools-62.6.0:
2025-10-20 00:11:06.754463 | controller |       Successfully uninstalled setuptools-62.6.0
2025-10-20 00:11:07.497639 | controller | Successfully installed selinux-please-lie-to-me-1.0.1 setuptools-49.6.0
2025-10-20 00:11:07.719092 | controller |
2025-10-20 00:11:07.928534 | controller | [notice] A new release of pip available: 22.2.2 -> 25.2
2025-10-20 00:11:07.928580 | controller | [notice] To update, run: python -m pip install --upgrade pip
2025-10-20 00:11:08.254809 | controller | ok: Runtime: 0:00:04.595885
2025-10-20 00:11:08.260732 | 
2025-10-20 00:11:08.260813 | TASK [Install pytest-forked into virtualenv]
2025-10-20 00:11:09.460188 | controller | Collecting pytest-forked
2025-10-20 00:11:09.549139 | controller |   Downloading pytest_forked-1.6.0-py3-none-any.whl (4.9 kB)
2025-10-20 00:11:09.612497 | controller | Collecting py
2025-10-20 00:11:09.627492 | controller |   Downloading py-1.11.0-py2.py3-none-any.whl (98 kB)
2025-10-20 00:11:09.660861 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 98.7/98.7 kB 3.2 MB/s eta 0:00:00
2025-10-20 00:11:09.831342 | controller | Collecting pytest>=3.10
2025-10-20 00:11:09.844601 | controller |   Downloading pytest-8.4.2-py3-none-any.whl (365 kB)
2025-10-20 00:11:09.895663 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 365.8/365.8 kB 7.6 MB/s eta 0:00:00
2025-10-20 00:11:09.952069 | controller | Collecting iniconfig>=1
2025-10-20 00:11:09.965531 | controller |   Downloading iniconfig-2.3.0-py3-none-any.whl (7.5 kB)
2025-10-20 00:11:10.029271 | controller | Collecting packaging>=20
2025-10-20 00:11:10.042261 | controller |   Downloading packaging-25.0-py3-none-any.whl (66 kB)
2025-10-20 00:11:10.052367 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 66.5/66.5 kB 8.8 MB/s eta 0:00:00
2025-10-20 00:11:10.097617 | controller | Collecting pluggy<2,>=1.5
2025-10-20 00:11:10.110555 | controller |   Downloading pluggy-1.6.0-py3-none-any.whl (20 kB)
2025-10-20 00:11:10.182348 | controller | Collecting pygments>=2.7.2
2025-10-20 00:11:10.195507 | controller |   Downloading pygments-2.19.2-py3-none-any.whl (1.2 MB)
2025-10-20 00:11:10.282743 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 1.2/1.2 MB 14.6 MB/s eta 0:00:00
2025-10-20 00:11:10.412387 | controller | Installing collected packages: pygments, py, pluggy, packaging, iniconfig, pytest, pytest-forked
2025-10-20 00:11:12.649851 | controller | Successfully installed iniconfig-2.3.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
2025-10-20 00:11:12.665726 | controller |
2025-10-20 00:11:12.849442 | controller | [notice] A new release of pip available: 22.2.2 -> 25.2
2025-10-20 00:11:12.849489 | controller | [notice] To update, run: python -m pip install --upgrade pip
2025-10-20 00:11:12.912595 | controller | ok: Runtime: 0:00:04.114125
2025-10-20 00:11:12.918191 | 
2025-10-20 00:11:12.918266 | TASK [Update pip]
2025-10-20 00:11:14.046916 | controller | Requirement already satisfied: pip in ./venv/lib/python3.11/site-packages (22.2.2)
2025-10-20 00:11:14.295352 | controller | Collecting pip
2025-10-20 00:11:14.394513 | controller |   Downloading pip-25.2-py3-none-any.whl (1.8 MB)
2025-10-20 00:11:14.612276 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 1.8/1.8 MB 8.3 MB/s eta 0:00:00
2025-10-20 00:11:14.735059 | controller | Installing collected packages: pip
2025-10-20 00:11:14.735482 | controller |   Attempting uninstall: pip
2025-10-20 00:11:14.739991 | controller |     Found existing installation: pip 22.2.2
2025-10-20 00:11:14.994284 | controller |     Uninstalling pip-22.2.2:
2025-10-20 00:11:15.024379 | controller |       Successfully uninstalled pip-22.2.2
2025-10-20 00:11:16.771453 | controller | Successfully installed pip-25.2
2025-10-20 00:11:17.074512 | controller | ok: Runtime: 0:00:03.547800
2025-10-20 00:11:17.086707 | 
2025-10-20 00:11:17.086854 | TASK [Ensure pip temp directory exists in /var/tmp]
2025-10-20 00:11:17.628199 | controller | changed
2025-10-20 00:11:17.639627 | 
2025-10-20 00:11:17.639772 | TASK [Install ansible into virtualenv]
2025-10-20 00:11:18.734620 | controller | Processing ./src/github.com/ansible/ansible
2025-10-20 00:11:18.741059 | controller |   Installing build dependencies: started
2025-10-20 00:11:20.632354 | controller |   Installing build dependencies: finished with status 'done'
2025-10-20 00:11:22.143070 | controller |   Getting requirements to build wheel: started
2025-10-20 00:11:22.143137 | controller |   Getting requirements to build wheel: finished with status 'done'
2025-10-20 00:11:22.144289 | controller |   Preparing metadata (pyproject.toml): started
2025-10-20 00:11:23.064090 | controller |   Preparing metadata (pyproject.toml): finished with status 'done'
2025-10-20 00:11:23.067514 | controller | INFO: pip is looking at multiple versions of ansible-core to determine which version is compatible with other requirements. This could take a while.
2025-10-20 00:11:23.072354 | controller | ERROR: Package 'ansible-core' requires a different Python: 3.11.0 not in '>=3.12'
2025-10-20 00:11:23.343654 | controller | ERROR
2025-10-20 00:11:23.343859 | controller | {
2025-10-20 00:11:23.343895 | controller |   "delta": "0:00:05.106515",
2025-10-20 00:11:23.343922 | controller |   "end": "2025-10-20 00:11:23.221611",
2025-10-20 00:11:23.343946 | controller |   "msg": "non-zero return code",
2025-10-20 00:11:23.343982 | controller |   "rc": 1,
2025-10-20 00:11:23.344007 | controller |   "start": "2025-10-20 00:11:18.115096"
2025-10-20 00:11:23.344061 | controller | }
failure
2025-10-20 00:11:23.346774 | 
2025-10-20 00:11:23.346861 | PLAY RECAP
2025-10-20 00:11:23.346921 | controller | ok: 18 changed: 10 unreachable: 0 failed: 1 skipped: 4 rescued: 0 ignored: 0
2025-10-20 00:11:23.346951 | 
2025-10-20 00:11:23.493941 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master]
2025-10-20 00:11:23.494983 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master]
2025-10-20 00:11:24.167206 | 
2025-10-20 00:11:24.167321 | PLAY [all]
2025-10-20 00:11:24.191160 | 
2025-10-20 00:11:24.191298 | TASK [fetch-subunit-output : Find stestr or testr executable]
2025-10-20 00:11:25.077231 | controller | changed: non-zero return code
2025-10-20 00:11:25.085835 | 
2025-10-20 00:11:25.085960 | TASK [fetch-subunit-output : Get the list of directories with subunit files]
2025-10-20 00:11:25.111773 | controller | skipping: Conditional result was False
2025-10-20 00:11:25.120665 | 
2025-10-20 00:11:25.120785 | LOOP [fetch-subunit-output : Find any inflight partial subunit files]
2025-10-20 00:11:25.155310 | 
2025-10-20 00:11:25.155507 | LOOP [fetch-subunit-output : Copy any inflight subunit files]
2025-10-20 00:11:25.189382 | 
2025-10-20 00:11:25.189601 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream]
2025-10-20 00:11:25.215357 | controller | skipping: Conditional result was False
2025-10-20 00:11:25.224413 | 
2025-10-20 00:11:25.224526 | LOOP [fetch-subunit-output : Generate subunit file]
2025-10-20 00:11:25.259466 | 
2025-10-20 00:11:25.259671 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory]
2025-10-20 00:11:25.275626 | controller | skipping: Conditional result was False
2025-10-20 00:11:25.285444 | 
2025-10-20 00:11:25.285578 | TASK [fetch-subunit-output : Remove the temporary file]
2025-10-20 00:11:25.302009 | controller | skipping: Conditional result was False
2025-10-20 00:11:25.311106 | 
2025-10-20 00:11:25.311239 | TASK [fetch-subunit-output : Process and fetch subunit results]
2025-10-20 00:11:25.338900 | controller | skipping: Conditional result was False
2025-10-20 00:11:25.373335 | 
2025-10-20 00:11:25.373670 | PLAY RECAP
2025-10-20 00:11:25.373746 | controller | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0
2025-10-20 00:11:25.373786 | 
2025-10-20 00:11:25.538597 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master]
2025-10-20 00:11:25.539445 | POST-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/post.yaml@master]
2025-10-20 00:11:26.156786 | 
2025-10-20 00:11:26.156934 | PLAY [all:!appliance*]
2025-10-20 00:11:26.182052 | 
2025-10-20 00:11:26.182199 | TASK [unregister the node]
2025-10-20 00:11:26.320996 | controller | skipping: Conditional result was False
2025-10-20 00:11:26.334240 | 
2025-10-20 00:11:26.334435 | TASK [include_role : fetch-output]
2025-10-20 00:11:26.385119 | controller | ok
2025-10-20 00:11:26.421002 | 
2025-10-20 00:11:26.421205 | TASK [fetch-output : Set log path for multiple nodes]
2025-10-20 00:11:26.497930 | controller | skipping: Conditional result was False
2025-10-20 00:11:26.507519 | 
2025-10-20 00:11:26.507663 | TASK [fetch-output : Set log path for single node]
2025-10-20 00:11:26.546381 | controller | ok
2025-10-20 00:11:26.559977 | 
2025-10-20 00:11:26.560180 | LOOP [fetch-output : Ensure local output dirs]
2025-10-20 00:11:26.986777 | controller -> localhost | ok: "/var/lib/zuul/builds/70896dbb58e24e56b0e53d4fc2fe83e9/work/logs"
2025-10-20 00:11:27.221210 | controller -> localhost | changed: "/var/lib/zuul/builds/70896dbb58e24e56b0e53d4fc2fe83e9/work/artifacts"
2025-10-20 00:11:27.491165 | controller -> localhost | changed: "/var/lib/zuul/builds/70896dbb58e24e56b0e53d4fc2fe83e9/work/docs"
2025-10-20 00:11:27.508061 | 
2025-10-20 00:11:27.508178 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-10-20 00:11:28.751012 | controller | changed:
2025-10-20 00:11:28.751368 | controller | .d..t...... ./
2025-10-20 00:11:28.751405 | controller | cd+++++++++ controller/
2025-10-20 00:11:28.751444 | controller | changed: All items complete
2025-10-20 00:11:28.751466 | 
2025-10-20 00:11:29.791337 | controller | changed: .d..t...... ./
2025-10-20 00:11:30.888976 | controller | changed: .d..t...... ./
2025-10-20 00:11:30.909408 | 
2025-10-20 00:11:30.909547 | TASK [include_role : fetch-output-openshift]
2025-10-20 00:11:30.936402 | controller | skipping: Conditional result was False
2025-10-20 00:11:30.944677 | 
2025-10-20 00:11:30.944811 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2025-10-20 00:11:30.991330 | controller | skipping: Conditional result was False
2025-10-20 00:11:31.006223 | controller | skipping: Conditional result was False
2025-10-20 00:11:31.054379 | 
2025-10-20 00:11:31.054506 | PLAY [localhost]
2025-10-20 00:11:31.067834 | 
2025-10-20 00:11:31.067937 | TASK [Run Zuul manifest role]
2025-10-20 00:11:31.087103 | localhost | ok
2025-10-20 00:11:31.102030 | 
2025-10-20 00:11:31.102125 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2025-10-20 00:11:31.478098 | localhost | changed
2025-10-20 00:11:31.483422 | 
2025-10-20 00:11:31.483516 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2025-10-20 00:11:31.511978 | localhost | ok
2025-10-20 00:11:31.520779 | 
2025-10-20 00:11:31.520883 | TASK [Set zuul-log-path fact]
2025-10-20 00:11:31.540132 | localhost | ok
2025-10-20 00:11:31.554958 | 
2025-10-20 00:11:31.555094 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-10-20 00:11:31.594097 | localhost | ok
2025-10-20 00:11:31.603450 | 
2025-10-20 00:11:31.603540 | LOOP [Run upload-logs-swift role]
2025-10-20 00:11:31.651195 | localhost | Output suppressed because no_log was given
2025-10-20 00:11:31.679813 | 
2025-10-20 00:11:31.679928 | TASK [Set zuul-log-path fact]
2025-10-20 00:11:31.703885 | localhost | skipping: Conditional result was False
2025-10-20 00:11:31.709824 | 
2025-10-20 00:11:31.709925 | TASK [upload-logs-swift : Ensure logs are readable before uploading]
2025-10-20 00:11:32.141809 | localhost -> localhost | ok: Runtime: 0:00:00.005046
2025-10-20 00:11:32.148082 | 
2025-10-20 00:11:32.148170 | TASK [upload-logs-swift : Upload logs to swift]