2026-01-17 00:28:03.004224 | Job console starting...
2026-01-17 00:28:03.020170 | Updating repositories
2026-01-17 00:28:03.147821 | Preparing job workspace
2026-01-17 00:28:06.773690 | Running Ansible setup...
2026-01-17 00:28:14.244228 | PRE-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master]
2026-01-17 00:28:14.829963 | 
2026-01-17 00:28:14.830093 | PLAY [localhost]
2026-01-17 00:28:14.838653 | 
2026-01-17 00:28:14.838748 | TASK [Gathering Facts]
2026-01-17 00:28:15.843646 | localhost | ok
2026-01-17 00:28:15.875915 | 
2026-01-17 00:28:15.876096 | TASK [Setup log path fact]
2026-01-17 00:28:15.897408 | localhost | ok
2026-01-17 00:28:15.909825 | 
2026-01-17 00:28:15.909891 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-01-17 00:28:15.947689 | localhost | ok
2026-01-17 00:28:15.956004 | 
2026-01-17 00:28:15.956079 | TASK [emit-job-header : Print job information]
2026-01-17 00:28:16.020314 | # Job Information
2026-01-17 00:28:16.020558 | Ansible Version: 2.15.12
2026-01-17 00:28:16.020625 | Job: ansible-test-sanity-docker-devel
2026-01-17 00:28:16.020707 | Pipeline: periodic
2026-01-17 00:28:16.020755 | Executor: ze02.softwarefactory-project.io
2026-01-17 00:28:16.020795 | Triggered by: https://github.com/ansible-collections/ansible.network
2026-01-17 00:28:16.020841 | Log URL (when completed): https://ansible.softwarefactory-project.io/logs/dca/ansible/dcadfd6ce80a4a48843e5d9d714c9c90/
2026-01-17 00:28:16.020883 | Event ID: 1d1f0d08e7134262830bd5487e0cab0e
2026-01-17 00:28:16.028835 | 
2026-01-17 00:28:16.028962 | LOOP [emit-job-header : Print node information]
2026-01-17 00:28:16.144059 | localhost | ok:
2026-01-17 00:28:16.144227 | localhost | # Node Information
2026-01-17 00:28:16.144253 | localhost | Inventory Hostname: controller
2026-01-17 00:28:16.144273 | localhost | Hostname: np0005586331
2026-01-17 00:28:16.144292 | localhost | Username: zuul
2026-01-17 00:28:16.144314 | localhost | Distro: Fedora 37
2026-01-17 00:28:16.144331 | localhost | Provider: ansible-vexxhost-ams1
2026-01-17 00:28:16.144349 | localhost | Region: ams1
2026-01-17 00:28:16.144366 | localhost | Label: ansible-fedora-37-1vcpu
2026-01-17 00:28:16.144382 | localhost | Product Name: OpenStack Nova
2026-01-17 00:28:16.144400 | localhost | Interface IP: 38.129.16.92
2026-01-17 00:28:16.167370 | 
2026-01-17 00:28:16.167580 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-01-17 00:28:16.553360 | localhost -> localhost | changed
2026-01-17 00:28:16.567733 | 
2026-01-17 00:28:16.567895 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-01-17 00:28:17.555319 | localhost -> localhost | changed
2026-01-17 00:28:17.581775 | 
2026-01-17 00:28:17.582047 | PLAY [all:!appliance*]
2026-01-17 00:28:17.620999 | 
2026-01-17 00:28:17.621177 | TASK [include_role : start-zuul-console]
2026-01-17 00:28:17.654532 | controller | ok
2026-01-17 00:28:17.678101 | 
2026-01-17 00:28:17.678305 | TASK [start-zuul-console : Start zuul_console daemon.]
2026-01-17 00:28:18.767408 | controller | ok
2026-01-17 00:28:18.793289 | 
2026-01-17 00:28:18.793474 | TASK [use-our-mirror : Retrieve the IP address]
2026-01-17 00:28:22.087436 | controller | ok: Status code was 404 and not [200]: HTTP Error 404: Not Found
2026-01-17 00:28:22.097749 | 
2026-01-17 00:28:22.097841 | TASK [use-our-mirror : Ensure the registry service is running on the mirror]
2026-01-17 00:28:22.472211 | controller | skipping: Conditional result was False
2026-01-17 00:28:22.487834 | 
2026-01-17 00:28:22.488008 | TASK [use-our-mirror : Write the IP in /etc/hosts]
2026-01-17 00:28:22.517004 | controller | skipping: Conditional result was False
2026-01-17 00:28:22.531866 | 
2026-01-17 00:28:22.532038 | TASK [use-our-mirror : Create the podman configuration directory]
2026-01-17 00:28:22.559637 | controller | skipping: Conditional result was False
2026-01-17 00:28:22.573438 | 
2026-01-17 00:28:22.573606 | TASK [use-our-mirror : Copy the podman configuration]
2026-01-17 00:28:22.611006 | controller | skipping: Conditional result was False
2026-01-17 00:28:22.624106 | 
2026-01-17 00:28:22.624271 | TASK [use-our-mirror : Copy the Yum configuration (main)]
2026-01-17 00:28:22.651080 | controller | skipping: Conditional result was False
2026-01-17 00:28:22.664796 | 
2026-01-17 00:28:22.664969 | TASK [use-our-mirror : Copy the Yum configuration (update)]
2026-01-17 00:28:22.692212 | controller | skipping: Conditional result was False
2026-01-17 00:28:22.718854 | 
2026-01-17 00:28:22.719031 | TASK [Disable Fedora Modular]
2026-01-17 00:28:23.862386 | controller | changed
2026-01-17 00:28:23.875062 | 
2026-01-17 00:28:23.875203 | TASK [Enable EPEL]
2026-01-17 00:28:23.902410 | controller | skipping: Conditional result was False
2026-01-17 00:28:23.917243 | 
2026-01-17 00:28:23.917394 | TASK [Register the RHEL node]
2026-01-17 00:28:24.333785 | 
2026-01-17 00:28:24.334051 | TASK [Show the subscription-manager status]
2026-01-17 00:28:24.717741 | controller | skipping: Conditional result was False
2026-01-17 00:28:24.728386 | 
2026-01-17 00:28:24.728520 | TASK [Enable EPEL on RHEL]
2026-01-17 00:28:25.115252 | controller | skipping: Conditional result was False
2026-01-17 00:28:25.129283 | 
2026-01-17 00:28:25.129466 | TASK [Install git and tox]
2026-01-17 00:30:30.655176 | controller | changed
2026-01-17 00:30:30.661440 | 
2026-01-17 00:30:30.661507 | TASK [include_role : prepare-workspace]
2026-01-17 00:30:30.693228 | controller | ok
2026-01-17 00:30:30.715023 | 
2026-01-17 00:30:30.715119 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-01-17 00:30:31.546055 | controller | ok
2026-01-17 00:30:31.557162 | 
2026-01-17 00:30:31.557255 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-01-17 00:30:47.798266 | controller | Output suppressed because no_log was given
2026-01-17 00:30:47.808457 | 
2026-01-17 00:30:47.808519 | TASK [include_role : prepare-workspace-openshift]
2026-01-17 00:30:47.824857 | controller | skipping: Conditional result was False
2026-01-17 00:30:47.850544 | 
2026-01-17 00:30:47.850607 | PLAY [all:!appliance]
2026-01-17 00:30:47.865384 | 
2026-01-17 00:30:47.865445 | TASK [Run add-build-sshkey role (RSA)]
2026-01-17 00:30:47.895247 | controller | ok
2026-01-17 00:30:47.911137 | 
2026-01-17 00:30:47.911220 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-01-17 00:30:48.169038 | controller -> localhost | ok
2026-01-17 00:30:48.176232 | 
2026-01-17 00:30:48.176299 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-01-17 00:30:48.207144 | controller | ok
2026-01-17 00:30:48.221797 | controller | included: /var/lib/zuul/builds/dcadfd6ce80a4a48843e5d9d714c9c90/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-01-17 00:30:48.228733 | 
2026-01-17 00:30:48.228796 | TASK [add-build-sshkey : Create Temp SSH key]
2026-01-17 00:30:48.695595 | controller -> localhost | Generating public/private rsa key pair.
2026-01-17 00:30:48.696330 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/dcadfd6ce80a4a48843e5d9d714c9c90/work/dcadfd6ce80a4a48843e5d9d714c9c90_id_rsa.
2026-01-17 00:30:48.696402 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/dcadfd6ce80a4a48843e5d9d714c9c90/work/dcadfd6ce80a4a48843e5d9d714c9c90_id_rsa.pub.
2026-01-17 00:30:48.696447 | controller -> localhost | The key fingerprint is:
2026-01-17 00:30:48.696489 | controller -> localhost | SHA256:pTPK77rKc1BsD969Ys79IeTPX6WvPqQftHxB4yKzimM zuul-build-sshkey
2026-01-17 00:30:48.696529 | controller -> localhost | The key's randomart image is:
2026-01-17 00:30:48.696566 | controller -> localhost | +---[RSA 2048]----+
2026-01-17 00:30:48.696602 | controller -> localhost | |                 |
2026-01-17 00:30:48.696640 | controller -> localhost | |                 |
2026-01-17 00:30:48.696716 | controller -> localhost | |     .    .    o |
2026-01-17 00:30:48.696759 | controller -> localhost | |      =  o    o .|
2026-01-17 00:30:48.696804 | controller -> localhost | |     + +S..o ..o.|
2026-01-17 00:30:48.696883 | controller -> localhost | |    ....o=. +oooo|
2026-01-17 00:30:48.696953 | controller -> localhost | |     .o   oo.o= o|
2026-01-17 00:30:48.697000 | controller -> localhost | |   .. .E+.o+...= |
2026-01-17 00:30:48.697039 | controller -> localhost | |    o++B*o..++=o.|
2026-01-17 00:30:48.697083 | controller -> localhost | +----[SHA256]-----+
2026-01-17 00:30:48.697185 | controller -> localhost | ok: Runtime: 0:00:00.068798
2026-01-17 00:30:48.713201 | 
2026-01-17 00:30:48.713367 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-01-17 00:30:48.747211 | controller | ok
2026-01-17 00:30:48.765452 | controller | included: /var/lib/zuul/builds/dcadfd6ce80a4a48843e5d9d714c9c90/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-01-17 00:30:48.779966 | 
2026-01-17 00:30:48.780056 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-01-17 00:30:48.796434 | controller | skipping: Conditional result was False
2026-01-17 00:30:48.808155 | 
2026-01-17 00:30:48.808246 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-01-17 00:30:50.011649 | controller | changed
2026-01-17 00:30:50.024429 | 
2026-01-17 00:30:50.024596 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-01-17 00:30:50.708219 | controller | ok
2026-01-17 00:30:50.718956 | 
2026-01-17 00:30:50.719112 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-01-17 00:30:53.751151 | controller | changed
2026-01-17 00:30:53.757525 | 
2026-01-17 00:30:53.757600 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-01-17 00:30:56.817266 | controller | changed
2026-01-17 00:30:56.829733 | 
2026-01-17 00:30:56.829854 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-01-17 00:30:56.854323 | controller | skipping: Conditional result was False
2026-01-17 00:30:56.862024 | 
2026-01-17 00:30:56.862131 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-01-17 00:30:57.255950 | controller -> localhost | changed
2026-01-17 00:30:57.273773 | 
2026-01-17 00:30:57.273905 | TASK [add-build-sshkey : Add back temp key]
2026-01-17 00:30:57.553564 | controller -> localhost | Identity added: /var/lib/zuul/builds/dcadfd6ce80a4a48843e5d9d714c9c90/work/dcadfd6ce80a4a48843e5d9d714c9c90_id_rsa (zuul-build-sshkey)
2026-01-17 00:30:57.553846 | controller -> localhost | ok: Runtime: 0:00:00.016140
2026-01-17 00:30:57.563614 | 
2026-01-17 00:30:57.563758 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-01-17 00:30:58.545582 | controller | ok
2026-01-17 00:30:58.558634 | 
2026-01-17 00:30:58.558848 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-01-17 00:30:58.585204 | controller | skipping: Conditional result was False
2026-01-17 00:30:58.603175 | 
2026-01-17 00:30:58.603315 | TASK [Run add-build-sshkey role (ECDSA)]
2026-01-17 00:30:58.639964 | controller | ok
2026-01-17 00:30:58.667437 | 
2026-01-17 00:30:58.667539 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-01-17 00:30:58.926206 | controller -> localhost | ok
2026-01-17 00:30:58.933250 | 
2026-01-17 00:30:58.933318 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-01-17 00:30:58.965335 | controller | ok
2026-01-17 00:30:58.982899 | controller | included: /var/lib/zuul/builds/dcadfd6ce80a4a48843e5d9d714c9c90/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-01-17 00:30:58.988989 | 
2026-01-17 00:30:58.989053 | TASK [add-build-sshkey : Create Temp SSH key]
2026-01-17 00:30:59.287882 | controller -> localhost | Generating public/private ecdsa key pair.
2026-01-17 00:30:59.288109 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/dcadfd6ce80a4a48843e5d9d714c9c90/work/dcadfd6ce80a4a48843e5d9d714c9c90_id_ecdsa.
2026-01-17 00:30:59.288150 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/dcadfd6ce80a4a48843e5d9d714c9c90/work/dcadfd6ce80a4a48843e5d9d714c9c90_id_ecdsa.pub.
2026-01-17 00:30:59.288190 | controller -> localhost | The key fingerprint is:
2026-01-17 00:30:59.288220 | controller -> localhost | SHA256:V2Ox+Y3oxVPc5mpCND1ufonSbvQ4KnCCyv3ZIOmsC8I zuul-build-sshkey
2026-01-17 00:30:59.288247 | controller -> localhost | The key's randomart image is:
2026-01-17 00:30:59.288273 | controller -> localhost | +---[ECDSA 521]---+
2026-01-17 00:30:59.288299 | controller -> localhost | |            .    |
2026-01-17 00:30:59.288324 | controller -> localhost | |             = ..|
2026-01-17 00:30:59.288350 | controller -> localhost | |            O o =|
2026-01-17 00:30:59.288374 | controller -> localhost | |           + B B |
2026-01-17 00:30:59.288399 | controller -> localhost | |      . S . o O o|
2026-01-17 00:30:59.288423 | controller -> localhost | |.    ..o o o.=.o.|
2026-01-17 00:30:59.288448 | controller -> localhost | |.E. oo .+  .+o*..|
2026-01-17 00:30:59.288473 | controller -> localhost | |. .oo.. +.  o* o |
2026-01-17 00:30:59.288497 | controller -> localhost | |   ooo.o ...o..  |
2026-01-17 00:30:59.288521 | controller -> localhost | +----[SHA256]-----+
2026-01-17 00:30:59.288586 | controller -> localhost | ok: Runtime: 0:00:00.010849
2026-01-17 00:30:59.298292 | 
2026-01-17 00:30:59.298381 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-01-17 00:30:59.331022 | controller | ok
2026-01-17 00:30:59.341988 | controller | included: /var/lib/zuul/builds/dcadfd6ce80a4a48843e5d9d714c9c90/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-01-17 00:30:59.354574 | 
2026-01-17 00:30:59.354686 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-01-17 00:30:59.380277 | controller | skipping: Conditional result was False
2026-01-17 00:30:59.442310 | 
2026-01-17 00:30:59.442584 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-01-17 00:31:00.399081 | controller | changed
2026-01-17 00:31:00.411795 | 
2026-01-17 00:31:00.411937 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-01-17 00:31:01.092761 | controller | ok
2026-01-17 00:31:01.110141 | 
2026-01-17 00:31:01.110305 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-01-17 00:31:04.186212 | controller | changed
2026-01-17 00:31:04.199744 | 
2026-01-17 00:31:04.199905 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-01-17 00:31:07.254776 | controller | changed
2026-01-17 00:31:07.268412 | 
2026-01-17 00:31:07.268555 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-01-17 00:31:07.286537 | controller | skipping: Conditional result was False
2026-01-17 00:31:07.297022 | 
2026-01-17 00:31:07.297117 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-01-17 00:31:07.525288 | controller -> localhost | changed
2026-01-17 00:31:07.537553 | 
2026-01-17 00:31:07.537629 | TASK [add-build-sshkey : Add back temp key]
2026-01-17 00:31:07.816096 | controller -> localhost | Identity added: /var/lib/zuul/builds/dcadfd6ce80a4a48843e5d9d714c9c90/work/dcadfd6ce80a4a48843e5d9d714c9c90_id_ecdsa (zuul-build-sshkey)
2026-01-17 00:31:07.816375 | controller -> localhost | ok: Runtime: 0:00:00.014860
2026-01-17 00:31:07.826562 | 
2026-01-17 00:31:07.826632 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-01-17 00:31:08.525017 | controller | ok
2026-01-17 00:31:08.539145 | 
2026-01-17 00:31:08.539368 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-01-17 00:31:08.586835 | controller | skipping: Conditional result was False
2026-01-17 00:31:08.612176 | 
2026-01-17 00:31:08.612331 | TASK [include_role : remove-zuul-sshkey]
2026-01-17 00:31:08.639288 | controller | skipping: Conditional result was False
2026-01-17 00:31:08.654908 | 
2026-01-17 00:31:08.655075 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-01-17 00:31:09.333478 | controller | ok: "logs"
2026-01-17 00:31:09.334003 | controller | ok: All items complete
2026-01-17 00:31:09.334084 | 
2026-01-17 00:31:09.963882 | controller | ok: "artifacts"
2026-01-17 00:31:10.608236 | controller | ok: "docs"
2026-01-17 00:31:10.629156 | 
2026-01-17 00:31:10.629307 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-01-17 00:31:11.324251 | controller | changed: "logs"
2026-01-17 00:31:11.956422 | controller | changed: "artifacts"
2026-01-17 00:31:12.608177 | controller | changed: "docs"
2026-01-17 00:31:12.670920 | 
2026-01-17 00:31:12.671047 | PLAY RECAP
2026-01-17 00:31:12.671104 | controller | ok: 30 changed: 16 unreachable: 0 failed: 0 skipped: 18 rescued: 0 ignored: 0
2026-01-17 00:31:12.671140 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-01-17 00:31:12.671164 | 
2026-01-17 00:31:12.796311 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master]
2026-01-17 00:31:12.797191 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master]
2026-01-17 00:31:13.420167 | 
2026-01-17 00:31:13.420287 | PLAY [all]
2026-01-17 00:31:13.442819 | 
2026-01-17 00:31:13.442923 | TASK [Install binary dependencies]
2026-01-17 00:31:13.483700 | controller | ok
2026-01-17 00:31:13.505326 | 
2026-01-17 00:31:13.505474 | TASK [bindep : Include find tasks]
2026-01-17 00:31:13.535579 | controller | ok
2026-01-17 00:31:13.543562 | controller | included: /var/lib/zuul/builds/dcadfd6ce80a4a48843e5d9d714c9c90/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find.yaml
2026-01-17 00:31:13.550136 | 
2026-01-17 00:31:13.550225 | TASK [bindep : Look for bindep.txt]
2026-01-17 00:31:14.481999 | controller | ok
2026-01-17 00:31:14.489659 | 
2026-01-17 00:31:14.489770 | TASK [bindep : Define bindep_file fact]
2026-01-17 00:31:14.514776 | controller | skipping: Conditional result was False
2026-01-17 00:31:14.521375 | 
2026-01-17 00:31:14.521448 | TASK [bindep : Look for other-requirements.txt]
2026-01-17 00:31:15.161970 | controller | ok
2026-01-17 00:31:15.180050 | 
2026-01-17 00:31:15.180242 | TASK [bindep : Define bindep_file fact]
2026-01-17 00:31:15.215589 | controller | skipping: Conditional result was False
2026-01-17 00:31:15.222788 | 
2026-01-17 00:31:15.222889 | TASK [bindep : Look for bindep fallback file]
2026-01-17 00:31:15.247325 | controller | skipping: Conditional result was False
2026-01-17 00:31:15.254554 | 
2026-01-17 00:31:15.254681 | TASK [bindep : Define bindep_file fact]
2026-01-17 00:31:15.279804 | controller | skipping: Conditional result was False
2026-01-17 00:31:15.287436 | 
2026-01-17 00:31:15.287522 | TASK [bindep : Include bindep tasks]
2026-01-17 00:31:15.311149 | controller | skipping: Conditional result was False
2026-01-17 00:31:15.317480 | 
2026-01-17 00:31:15.317544 | TASK [bindep : Include install tasks]
2026-01-17 00:31:15.341337 | controller | skipping: Conditional result was False
2026-01-17 00:31:15.347571 | 
2026-01-17 00:31:15.347635 | LOOP [bindep : Include package tasks]
2026-01-17 00:31:15.403955 | 
2026-01-17 00:31:15.404144 | TASK [Run test-setup role]
2026-01-17 00:31:15.436115 | controller | ok
2026-01-17 00:31:15.453019 | 
2026-01-17 00:31:15.453093 | TASK [test-setup : Check if project's tools/test-setup.sh exists]
2026-01-17 00:31:16.079939 | controller | ok
2026-01-17 00:31:16.086134 | 
2026-01-17 00:31:16.086198 | TASK [test-setup : Run tools/test-setup.sh]
2026-01-17 00:31:16.438903 | controller | skipping: Conditional result was False
2026-01-17 00:31:16.505580 | 
2026-01-17 00:31:16.505648 | PLAY RECAP
2026-01-17 00:31:16.505724 | controller | ok: 4 changed: 0 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0
2026-01-17 00:31:16.505747 | 
2026-01-17 00:31:16.623907 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master]
2026-01-17 00:31:16.624798 | PRE-RUN START: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master]
2026-01-17 00:31:17.386738 | 
2026-01-17 00:31:17.386872 | PLAY [controller]
2026-01-17 00:31:17.406495 | 
2026-01-17 00:31:17.406570 | TASK [Create the /root directory]
2026-01-17 00:31:18.530594 | controller | ok
2026-01-17 00:31:18.538598 | 
2026-01-17 00:31:18.538720 | TASK [Install glibc-langpack-en]
2026-01-17 00:31:26.509958 | controller | ok: Nothing to do
2026-01-17 00:31:26.515887 | 
2026-01-17 00:31:26.515955 | TASK [Ensure controller directory exists]
2026-01-17 00:31:27.309622 | controller | changed
2026-01-17 00:31:27.315012 | 
2026-01-17 00:31:27.315072 | TASK [Install container runtime]
2026-01-17 00:31:27.385172 | controller | ok
2026-01-17 00:31:27.420956 | 
2026-01-17 00:31:27.421085 | LOOP [ensure-podman : Find distribution installation]
2026-01-17 00:31:27.455499 | controller | ok: "/var/lib/zuul/builds/dcadfd6ce80a4a48843e5d9d714c9c90/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-podman/tasks/RedHat.yaml"
2026-01-17 00:31:27.475928 | controller | included: /var/lib/zuul/builds/dcadfd6ce80a4a48843e5d9d714c9c90/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-podman/tasks/RedHat.yaml
2026-01-17 00:31:27.487134 | 
2026-01-17 00:31:27.487241 | TASK [ensure-podman : Install podman (RedHat)]
2026-01-17 00:33:35.087873 | controller | changed
2026-01-17 00:33:35.101811 | 
2026-01-17 00:33:35.101980 | TASK [ensure-podman : Fetch podman version]
2026-01-17 00:33:36.291695 | controller | Client:       Podman Engine
2026-01-17 00:33:36.291807 | controller | Version:      4.6.2
2026-01-17 00:33:36.291854 | controller | API Version:  4.6.2
2026-01-17 00:33:36.291894 | controller | Go Version:   go1.19.12
2026-01-17 00:33:36.291959 | controller | Built:        Mon Aug 28 19:38:31 2023
2026-01-17 00:33:36.291999 | controller | OS/Arch:      linux/amd64
2026-01-17 00:33:36.478825 | controller | ok: Runtime: 0:00:00.313031
2026-01-17 00:33:36.493215 | 
2026-01-17 00:33:36.493357 | TASK [ensure-podman : Print podman version installed]
2026-01-17 00:33:36.534081 | Podman version: Client:       Podman Engine
2026-01-17 00:33:36.534321 | Version:      4.6.2
2026-01-17 00:33:36.534384 | API Version:  4.6.2
2026-01-17 00:33:36.534429 | Go Version:   go1.19.12
2026-01-17 00:33:36.534470 | Built:        Mon Aug 28 19:38:31 2023
2026-01-17 00:33:36.534512 | OS/Arch:      linux/amd64
2026-01-17 00:33:36.546989 | 
2026-01-17 00:33:36.547121 | TASK [ensure-podman : Validate podman engine]
2026-01-17 00:33:36.917124 | controller | skipping: Conditional result was False
2026-01-17 00:33:36.933539 | 
2026-01-17 00:33:36.933742 | TASK [ensure-podman : Set up docker compatability socket]
2026-01-17 00:33:36.961209 | controller | skipping: Conditional result was False
2026-01-17 00:33:36.986037 | 
2026-01-17 00:33:36.986237 | TASK [Ensure python3.8 is present]
2026-01-17 00:33:37.013290 | controller | skipping: Conditional result was False
2026-01-17 00:33:37.027559 | 
2026-01-17 00:33:37.027781 | TASK [Run our-ensure-python role (Fedora only for now)]
2026-01-17 00:33:37.066736 | controller | ok
2026-01-17 00:33:37.102874 | 
2026-01-17 00:33:37.103036 | TASK [our-ensure-python : Install the right Python version (rpm)]
2026-01-17 00:33:41.284550 | controller | ok: Nothing to do
2026-01-17 00:33:41.290320 | 
2026-01-17 00:33:41.290383 | TASK [our-ensure-python : Also install python3-devel]
2026-01-17 00:33:57.024630 | controller | changed
2026-01-17 00:33:57.044218 | 
2026-01-17 00:33:57.044310 | TASK [Run ensure-virtualenv role]
2026-01-17 00:33:57.064756 | controller | ok
2026-01-17 00:33:57.086600 | 
2026-01-17 00:33:57.086683 | TASK [ensure-virtualenv : Check if virtualenv is installed]
2026-01-17 00:33:57.900391 | controller | /usr/bin/virtualenv
2026-01-17 00:33:58.473106 | controller | ok: Runtime: 0:00:00.006467
2026-01-17 00:33:58.487359 | 
2026-01-17 00:33:58.487542 | LOOP [ensure-virtualenv : Install virtualenv package]
2026-01-17 00:33:58.523419 | controller | skipping: Conditional result was False
2026-01-17 00:33:58.523925 | controller | ok: All items complete
2026-01-17 00:33:58.523992 | 
2026-01-17 00:33:58.552240 | 
2026-01-17 00:33:58.552471 | TASK [Find the full path of the Python interpreter]
2026-01-17 00:33:59.356792 | controller | /usr/bin/python3
2026-01-17 00:33:59.924314 | controller | ok
2026-01-17 00:33:59.938930 | 
2026-01-17 00:33:59.939233 | TASK [Create virtualenv for ansible-test with a specific interpreter]
2026-01-17 00:34:01.723910 | controller | created virtual environment CPython3.11.0.final.0-64 in 846ms
2026-01-17 00:34:01.776066 | controller |   creator CPython3Posix(dest=/home/zuul/venv, clear=False, no_vcs_ignore=False, global=False)
2026-01-17 00:34:01.776130 | 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)
2026-01-17 00:34:01.776147 | controller |     added seed packages: pip==22.2.2, setuptools==62.6.0, wheel==0.37.1
2026-01-17 00:34:01.776167 | controller |   activators BashActivator,CShellActivator,FishActivator,NushellActivator,PowerShellActivator,PythonActivator
2026-01-17 00:34:02.344017 | controller | changed
2026-01-17 00:34:02.351631 | 
2026-01-17 00:34:02.351738 | TASK [Set selinux package]
2026-01-17 00:34:02.395832 | controller | ok
2026-01-17 00:34:02.401249 | 
2026-01-17 00:34:02.401314 | TASK [Set selinux package (Fedora)]
2026-01-17 00:34:02.433991 | controller | ok
2026-01-17 00:34:02.439597 | 
2026-01-17 00:34:02.439660 | TASK [Install selinux into virtualenv]
2026-01-17 00:34:05.216425 | controller | Collecting selinux-please-lie-to-me
2026-01-17 00:34:05.284091 | controller |   Downloading selinux_please_lie_to_me-1.0.1-py2.py3-none-any.whl (3.8 kB)
2026-01-17 00:34:05.794552 | controller | Collecting setuptools<50.0.0
2026-01-17 00:34:05.800494 | controller |   Downloading setuptools-49.6.0-py3-none-any.whl (803 kB)
2026-01-17 00:34:05.852605 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 803.3/803.3 kB 16.7 MB/s eta 0:00:00
2026-01-17 00:34:06.036073 | controller | Installing collected packages: setuptools, selinux-please-lie-to-me
2026-01-17 00:34:06.036412 | controller |   Attempting uninstall: setuptools
2026-01-17 00:34:06.040205 | controller |     Found existing installation: setuptools 62.6.0
2026-01-17 00:34:06.141908 | controller |     Uninstalling setuptools-62.6.0:
2026-01-17 00:34:06.160526 | controller |       Successfully uninstalled setuptools-62.6.0
2026-01-17 00:34:06.960152 | controller | Successfully installed selinux-please-lie-to-me-1.0.1 setuptools-49.6.0
2026-01-17 00:34:07.193878 | controller |
2026-01-17 00:34:07.545103 | controller | [notice] A new release of pip available: 22.2.2 -> 25.3
2026-01-17 00:34:07.545180 | controller | [notice] To update, run: python -m pip install --upgrade pip
2026-01-17 00:34:07.834321 | controller | ok: Runtime: 0:00:04.311046
2026-01-17 00:34:07.847489 | 
2026-01-17 00:34:07.847624 | TASK [Install pytest-forked into virtualenv]
2026-01-17 00:34:09.319298 | controller | Collecting pytest-forked
2026-01-17 00:34:09.379894 | controller |   Downloading pytest_forked-1.6.0-py3-none-any.whl (4.9 kB)
2026-01-17 00:34:09.462585 | controller | Collecting py
2026-01-17 00:34:09.469966 | controller |   Downloading py-1.11.0-py2.py3-none-any.whl (98 kB)
2026-01-17 00:34:09.503629 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 98.7/98.7 kB 3.0 MB/s eta 0:00:00
2026-01-17 00:34:09.677166 | controller | Collecting pytest>=3.10
2026-01-17 00:34:09.682491 | controller |   Downloading pytest-9.0.2-py3-none-any.whl (374 kB)
2026-01-17 00:34:09.703003 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 374.8/374.8 kB 24.1 MB/s eta 0:00:00
2026-01-17 00:34:09.760404 | controller | Collecting iniconfig>=1.0.1
2026-01-17 00:34:09.765187 | controller |   Downloading iniconfig-2.3.0-py3-none-any.whl (7.5 kB)
2026-01-17 00:34:09.836331 | controller | Collecting packaging>=22
2026-01-17 00:34:09.842231 | controller |   Downloading packaging-25.0-py3-none-any.whl (66 kB)
2026-01-17 00:34:09.854508 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 66.5/66.5 kB 7.3 MB/s eta 0:00:00
2026-01-17 00:34:09.905762 | controller | Collecting pluggy<2,>=1.5
2026-01-17 00:34:09.910316 | controller |   Downloading pluggy-1.6.0-py3-none-any.whl (20 kB)
2026-01-17 00:34:09.983679 | controller | Collecting pygments>=2.7.2
2026-01-17 00:34:09.990525 | controller |   Downloading pygments-2.19.2-py3-none-any.whl (1.2 MB)
2026-01-17 00:34:10.032365 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 1.2/1.2 MB 32.0 MB/s eta 0:00:00
2026-01-17 00:34:10.160430 | controller | Installing collected packages: pygments, py, pluggy, packaging, iniconfig, pytest, pytest-forked
2026-01-17 00:34:12.482639 | controller | Successfully installed iniconfig-2.3.0 packaging-25.0 pluggy-1.6.0 py-1.11.0 pygments-2.19.2 pytest-9.0.2 pytest-forked-1.6.0
2026-01-17 00:34:12.496415 | controller |
2026-01-17 00:34:12.719822 | controller | [notice] A new release of pip available: 22.2.2 -> 25.3
2026-01-17 00:34:12.719877 | controller | [notice] To update, run: python -m pip install --upgrade pip
2026-01-17 00:34:13.244238 | controller | ok: Runtime: 0:00:04.097066
2026-01-17 00:34:13.250078 | 
2026-01-17 00:34:13.250157 | TASK [Update pip]
2026-01-17 00:34:14.585170 | controller | Requirement already satisfied: pip in ./venv/lib/python3.11/site-packages (22.2.2)
2026-01-17 00:34:14.859599 | controller | Collecting pip
2026-01-17 00:34:14.933950 | controller |   Downloading pip-25.3-py3-none-any.whl (1.8 MB)
2026-01-17 00:34:15.014453 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 1.8/1.8 MB 23.6 MB/s eta 0:00:00
2026-01-17 00:34:15.136833 | controller | Installing collected packages: pip
2026-01-17 00:34:15.137148 | controller |   Attempting uninstall: pip
2026-01-17 00:34:15.141581 | controller |     Found existing installation: pip 22.2.2
2026-01-17 00:34:15.377610 | controller |     Uninstalling pip-22.2.2:
2026-01-17 00:34:15.405144 | controller |       Successfully uninstalled pip-22.2.2
2026-01-17 00:34:17.351541 | controller | Successfully installed pip-25.3
2026-01-17 00:34:18.128226 | controller | ok: Runtime: 0:00:03.530188
2026-01-17 00:34:18.134965 | 
2026-01-17 00:34:18.135090 | TASK [Ensure pip temp directory exists in /var/tmp]
2026-01-17 00:34:18.998399 | controller | changed
2026-01-17 00:34:19.004412 | 
2026-01-17 00:34:19.004476 | TASK [Install ansible into virtualenv]
2026-01-17 00:34:20.105888 | controller | Processing ./src/github.com/ansible/ansible
2026-01-17 00:34:20.112983 | controller |   Installing build dependencies: started
2026-01-17 00:34:21.864410 | controller |   Installing build dependencies: finished with status 'done'
2026-01-17 00:34:21.864464 | controller |   Getting requirements to build wheel: started
2026-01-17 00:34:23.314575 | controller |   Getting requirements to build wheel: finished with status 'done'
2026-01-17 00:34:23.319602 | controller |   Preparing metadata (pyproject.toml): started
2026-01-17 00:34:24.154361 | controller |   Preparing metadata (pyproject.toml): finished with status 'done'
2026-01-17 00:34:24.158416 | 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.
2026-01-17 00:34:24.164100 | controller | ERROR: Package 'ansible-core' requires a different Python: 3.11.0 not in '>=3.12'
2026-01-17 00:34:24.872736 | controller | ERROR
2026-01-17 00:34:24.873079 | controller | {
2026-01-17 00:34:24.873144 | controller |   "delta": "0:00:04.731924",
2026-01-17 00:34:24.873231 | controller |   "end": "2026-01-17 00:34:24.322632",
2026-01-17 00:34:24.873272 | controller |   "msg": "non-zero return code",
2026-01-17 00:34:24.873327 | controller |   "rc": 1,
2026-01-17 00:34:24.873365 | controller |   "start": "2026-01-17 00:34:19.590708"
2026-01-17 00:34:24.873399 | controller | }
failure
2026-01-17 00:34:24.876418 | 
2026-01-17 00:34:24.876527 | PLAY RECAP
2026-01-17 00:34:24.876613 | controller | ok: 18 changed: 10 unreachable: 0 failed: 1 skipped: 4 rescued: 0 ignored: 0
2026-01-17 00:34:24.876657 | 
2026-01-17 00:34:25.010173 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master]
2026-01-17 00:34:25.012355 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master]
2026-01-17 00:34:25.657627 | 
2026-01-17 00:34:25.657764 | PLAY [all]
2026-01-17 00:34:25.679306 | 
2026-01-17 00:34:25.679390 | TASK [fetch-subunit-output : Find stestr or testr executable]
2026-01-17 00:34:27.567783 | controller | changed: non-zero return code
2026-01-17 00:34:27.576509 | 
2026-01-17 00:34:27.576612 | TASK [fetch-subunit-output : Get the list of directories with subunit files]
2026-01-17 00:34:27.602334 | controller | skipping: Conditional result was False
2026-01-17 00:34:27.614569 | 
2026-01-17 00:34:27.614760 | LOOP [fetch-subunit-output : Find any inflight partial subunit files]
2026-01-17 00:34:27.648855 | 
2026-01-17 00:34:27.649134 | LOOP [fetch-subunit-output : Copy any inflight subunit files]
2026-01-17 00:34:27.685130 | 
2026-01-17 00:34:27.685310 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream]
2026-01-17 00:34:27.711407 | controller | skipping: Conditional result was False
2026-01-17 00:34:27.721315 | 
2026-01-17 00:34:27.721408 | LOOP [fetch-subunit-output : Generate subunit file]
2026-01-17 00:34:27.756997 | 
2026-01-17 00:34:27.757185 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory]
2026-01-17 00:34:27.783135 | controller | skipping: Conditional result was False
2026-01-17 00:34:27.791775 | 
2026-01-17 00:34:27.791871 | TASK [fetch-subunit-output : Remove the temporary file]
2026-01-17 00:34:27.817632 | controller | skipping: Conditional result was False
2026-01-17 00:34:27.826035 | 
2026-01-17 00:34:27.826119 | TASK [fetch-subunit-output : Process and fetch subunit results]
2026-01-17 00:34:27.851721 | controller | skipping: Conditional result was False
2026-01-17 00:34:27.889395 | 
2026-01-17 00:34:27.889472 | PLAY RECAP
2026-01-17 00:34:27.889532 | controller | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0
2026-01-17 00:34:27.889560 | 
2026-01-17 00:34:28.011148 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master]
2026-01-17 00:34:28.013266 | POST-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/post.yaml@master]
2026-01-17 00:34:28.643307 | 
2026-01-17 00:34:28.643475 | PLAY [all:!appliance*]
2026-01-17 00:34:28.667147 | 
2026-01-17 00:34:28.667330 | TASK [unregister the node]
2026-01-17 00:34:29.022614 | controller | skipping: Conditional result was False
2026-01-17 00:34:29.035885 | 
2026-01-17 00:34:29.036056 | TASK [include_role : fetch-output]
2026-01-17 00:34:29.097030 | controller | ok
2026-01-17 00:34:29.136981 | 
2026-01-17 00:34:29.137164 | TASK [fetch-output : Set log path for multiple nodes]
2026-01-17 00:34:29.215922 | controller | skipping: Conditional result was False
2026-01-17 00:34:29.231020 | 
2026-01-17 00:34:29.231189 | TASK [fetch-output : Set log path for single node]
2026-01-17 00:34:29.274541 | controller | ok
2026-01-17 00:34:29.283428 | 
2026-01-17 00:34:29.283578 | LOOP [fetch-output : Ensure local output dirs]
2026-01-17 00:34:29.776351 | controller -> localhost | ok: "/var/lib/zuul/builds/dcadfd6ce80a4a48843e5d9d714c9c90/work/logs"
2026-01-17 00:34:30.053046 | controller -> localhost | changed: "/var/lib/zuul/builds/dcadfd6ce80a4a48843e5d9d714c9c90/work/artifacts"
2026-01-17 00:34:30.303036 | controller -> localhost | changed: "/var/lib/zuul/builds/dcadfd6ce80a4a48843e5d9d714c9c90/work/docs"
2026-01-17 00:34:30.319744 | 
2026-01-17 00:34:30.319929 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-01-17 00:34:32.615482 | controller | changed:
2026-01-17 00:34:32.616072 | controller | .d..t...... ./
2026-01-17 00:34:32.616139 | controller | cd+++++++++ controller/
2026-01-17 00:34:32.616210 | controller | changed: All items complete
2026-01-17 00:34:32.616257 | 
2026-01-17 00:34:34.630649 | controller | changed: .d..t...... ./
2026-01-17 00:34:36.739552 | controller | changed: .d..t...... ./
2026-01-17 00:34:36.773458 | 
2026-01-17 00:34:36.773651 | TASK [include_role : fetch-output-openshift]
2026-01-17 00:34:36.790780 | controller | skipping: Conditional result was False
2026-01-17 00:34:36.798937 | 
2026-01-17 00:34:36.799017 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-01-17 00:34:36.830376 | controller | skipping: Conditional result was False
2026-01-17 00:34:36.837997 | controller | skipping: Conditional result was False
2026-01-17 00:34:36.893094 | 
2026-01-17 00:34:36.893274 | PLAY [localhost]
2026-01-17 00:34:36.911745 | 
2026-01-17 00:34:36.911839 | TASK [Run Zuul manifest role]
2026-01-17 00:34:36.933335 | localhost | ok
2026-01-17 00:34:36.951142 | 
2026-01-17 00:34:36.951226 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-01-17 00:34:37.436303 | localhost | changed
2026-01-17 00:34:37.447733 | 
2026-01-17 00:34:37.447898 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-01-17 00:34:37.498220 | localhost | ok
2026-01-17 00:34:37.520355 | 
2026-01-17 00:34:37.520507 | TASK [Set zuul-log-path fact]
2026-01-17 00:34:37.548274 | localhost | ok
2026-01-17 00:34:37.615424 | 
2026-01-17 00:34:37.615603 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-01-17 00:34:37.658241 | localhost | ok
2026-01-17 00:34:37.667501 | 
2026-01-17 00:34:37.667573 | LOOP [Run upload-logs-swift role]
2026-01-17 00:34:37.717556 | localhost | Output suppressed because no_log was given
2026-01-17 00:34:37.748271 | 
2026-01-17 00:34:37.748515 | TASK [Set zuul-log-path fact]
2026-01-17 00:34:37.773603 | localhost | skipping: Conditional result was False
2026-01-17 00:34:37.781556 | 
2026-01-17 00:34:37.781629 | TASK [upload-logs-swift : Ensure logs are readable before uploading]
2026-01-17 00:34:38.184075 | localhost -> localhost | ok: Runtime: 0:00:00.015416
2026-01-17 00:34:38.197455 | 
2026-01-17 00:34:38.197608 | TASK [upload-logs-swift : Upload logs to swift]