2026-02-19 00:31:45.317906 | Job console starting...
2026-02-19 00:31:45.334592 | Updating repositories
2026-02-19 00:31:45.465239 | Preparing job workspace
2026-02-19 00:31:49.304921 | Running Ansible setup...
2026-02-19 00:31:54.810853 | PRE-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master]
2026-02-19 00:31:55.515540 | 
2026-02-19 00:31:55.515766 | PLAY [localhost]
2026-02-19 00:31:55.527044 | 
2026-02-19 00:31:55.527156 | TASK [Gathering Facts]
2026-02-19 00:31:56.695671 | localhost | ok
2026-02-19 00:31:56.713661 | 
2026-02-19 00:31:56.713888 | TASK [Setup log path fact]
2026-02-19 00:31:56.737294 | localhost | ok
2026-02-19 00:31:56.755002 | 
2026-02-19 00:31:56.755173 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-02-19 00:31:56.788249 | localhost | ok
2026-02-19 00:31:56.799024 | 
2026-02-19 00:31:56.799223 | TASK [emit-job-header : Print job information]
2026-02-19 00:31:56.843019 | # Job Information
2026-02-19 00:31:56.843288 | Ansible Version: 2.15.12
2026-02-19 00:31:56.843339 | Job: ansible-test-sanity-docker-devel
2026-02-19 00:31:56.843373 | Pipeline: periodic
2026-02-19 00:31:56.843403 | Executor: ze03.softwarefactory-project.io
2026-02-19 00:31:56.843436 | Triggered by: https://github.com/ansible-collections/ansible.network
2026-02-19 00:31:56.843474 | Log URL (when completed): https://ansible.softwarefactory-project.io/logs/4f8/ansible/4f87683ae3b041599a6b61355d56f31d/
2026-02-19 00:31:56.843509 | Event ID: d77a250cf7fd40868fb03f0c570040ac
2026-02-19 00:31:56.850400 | 
2026-02-19 00:31:56.850533 | LOOP [emit-job-header : Print node information]
2026-02-19 00:31:56.962954 | localhost | ok:
2026-02-19 00:31:56.963168 | localhost | # Node Information
2026-02-19 00:31:56.963198 | localhost | Inventory Hostname: controller
2026-02-19 00:31:56.963219 | localhost | Hostname: np0005623874
2026-02-19 00:31:56.963238 | localhost | Username: zuul
2026-02-19 00:31:56.963260 | localhost | Distro: Fedora 37
2026-02-19 00:31:56.963459 | localhost | Provider: ansible-vexxhost-ca-ymq-1
2026-02-19 00:31:56.963481 | localhost | Region: ca-ymq-1
2026-02-19 00:31:56.963500 | localhost | Label: ansible-fedora-37-1vcpu
2026-02-19 00:31:56.963524 | localhost | Product Name: OpenStack Nova
2026-02-19 00:31:56.963542 | localhost | Interface IP: 162.253.55.62
2026-02-19 00:31:56.973965 | 
2026-02-19 00:31:56.974109 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-02-19 00:31:57.461520 | localhost -> localhost | changed
2026-02-19 00:31:57.480834 | 
2026-02-19 00:31:57.481030 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-02-19 00:31:58.441815 | localhost -> localhost | changed
2026-02-19 00:31:58.471013 | 
2026-02-19 00:31:58.471149 | PLAY [all:!appliance*]
2026-02-19 00:31:58.502601 | 
2026-02-19 00:31:58.502817 | TASK [include_role : start-zuul-console]
2026-02-19 00:31:58.526763 | controller | ok
2026-02-19 00:31:58.541745 | 
2026-02-19 00:31:58.541899 | TASK [start-zuul-console : Start zuul_console daemon.]
2026-02-19 00:31:58.945118 | controller | ok
2026-02-19 00:31:58.964071 | 
2026-02-19 00:31:58.964249 | TASK [use-our-mirror : Retrieve the IP address]
2026-02-19 00:32:00.361441 | controller | ok: Status code was 404 and not [200]: HTTP Error 404: Not Found
2026-02-19 00:32:00.372958 | 
2026-02-19 00:32:00.373162 | TASK [use-our-mirror : Ensure the registry service is running on the mirror]
2026-02-19 00:32:00.925060 | controller | skipping: Conditional result was False
2026-02-19 00:32:00.933475 | 
2026-02-19 00:32:00.933677 | TASK [use-our-mirror : Write the IP in /etc/hosts]
2026-02-19 00:32:00.959566 | controller | skipping: Conditional result was False
2026-02-19 00:32:00.970072 | 
2026-02-19 00:32:00.970292 | TASK [use-our-mirror : Create the podman configuration directory]
2026-02-19 00:32:00.998200 | controller | skipping: Conditional result was False
2026-02-19 00:32:01.005657 | 
2026-02-19 00:32:01.005814 | TASK [use-our-mirror : Copy the podman configuration]
2026-02-19 00:32:01.031494 | controller | skipping: Conditional result was False
2026-02-19 00:32:01.038643 | 
2026-02-19 00:32:01.038835 | TASK [use-our-mirror : Copy the Yum configuration (main)]
2026-02-19 00:32:01.063592 | controller | skipping: Conditional result was False
2026-02-19 00:32:01.072533 | 
2026-02-19 00:32:01.072709 | TASK [use-our-mirror : Copy the Yum configuration (update)]
2026-02-19 00:32:01.097844 | controller | skipping: Conditional result was False
2026-02-19 00:32:01.111752 | 
2026-02-19 00:32:01.111876 | TASK [Disable Fedora Modular]
2026-02-19 00:32:01.361661 | controller | changed
2026-02-19 00:32:01.370088 | 
2026-02-19 00:32:01.370370 | TASK [Enable EPEL]
2026-02-19 00:32:01.398035 | controller | skipping: Conditional result was False
2026-02-19 00:32:01.407977 | 
2026-02-19 00:32:01.408121 | TASK [Register the RHEL node]
2026-02-19 00:32:01.948758 | 
2026-02-19 00:32:01.948972 | TASK [Show the subscription-manager status]
2026-02-19 00:32:02.556516 | controller | skipping: Conditional result was False
2026-02-19 00:32:02.573406 | 
2026-02-19 00:32:02.573661 | TASK [Enable EPEL on RHEL]
2026-02-19 00:32:03.133352 | controller | skipping: Conditional result was False
2026-02-19 00:32:03.142857 | 
2026-02-19 00:32:03.143030 | TASK [Install git and tox]
2026-02-19 00:33:23.275589 | controller | changed
2026-02-19 00:33:23.284993 | 
2026-02-19 00:33:23.285131 | TASK [include_role : prepare-workspace]
2026-02-19 00:33:23.322662 | controller | ok
2026-02-19 00:33:23.355594 | 
2026-02-19 00:33:23.355801 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-02-19 00:33:23.585341 | controller | ok
2026-02-19 00:33:23.595974 | 
2026-02-19 00:33:23.596139 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-02-19 00:33:36.312495 | controller | Output suppressed because no_log was given
2026-02-19 00:33:36.325636 | 
2026-02-19 00:33:36.325822 | TASK [include_role : prepare-workspace-openshift]
2026-02-19 00:33:36.343209 | controller | skipping: Conditional result was False
2026-02-19 00:33:36.368394 | 
2026-02-19 00:33:36.368499 | PLAY [all:!appliance]
2026-02-19 00:33:36.389049 | 
2026-02-19 00:33:36.389183 | TASK [Run add-build-sshkey role (RSA)]
2026-02-19 00:33:36.419621 | controller | ok
2026-02-19 00:33:36.436751 | 
2026-02-19 00:33:36.436916 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-02-19 00:33:36.659768 | controller -> localhost | ok
2026-02-19 00:33:36.670232 | 
2026-02-19 00:33:36.670594 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-02-19 00:33:36.714438 | controller | ok
2026-02-19 00:33:36.729094 | controller | included: /var/lib/zuul/builds/4f87683ae3b041599a6b61355d56f31d/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-02-19 00:33:36.735425 | 
2026-02-19 00:33:36.735492 | TASK [add-build-sshkey : Create Temp SSH key]
2026-02-19 00:33:37.321337 | controller -> localhost | Generating public/private rsa key pair.
2026-02-19 00:33:37.321603 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/4f87683ae3b041599a6b61355d56f31d/work/4f87683ae3b041599a6b61355d56f31d_id_rsa.
2026-02-19 00:33:37.321648 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/4f87683ae3b041599a6b61355d56f31d/work/4f87683ae3b041599a6b61355d56f31d_id_rsa.pub.
2026-02-19 00:33:37.321681 | controller -> localhost | The key fingerprint is:
2026-02-19 00:33:37.321709 | controller -> localhost | SHA256:dwWPDmHJbHe1Ivp4IcuTVs/yxcEq3k1SbEgJ6k2Vl1Y zuul-build-sshkey
2026-02-19 00:33:37.321759 | controller -> localhost | The key's randomart image is:
2026-02-19 00:33:37.321847 | controller -> localhost | +---[RSA 2048]----+
2026-02-19 00:33:37.321873 | controller -> localhost | |         ooo....E|
2026-02-19 00:33:37.321894 | controller -> localhost | |         .*.+=o+.|
2026-02-19 00:33:37.321913 | controller -> localhost | |         o.+.==. |
2026-02-19 00:33:37.321932 | controller -> localhost | |        . +oo.=  |
2026-02-19 00:33:37.321951 | controller -> localhost | |        S+.+o. * |
2026-02-19 00:33:37.321988 | controller -> localhost | |        ..B.+ = .|
2026-02-19 00:33:37.322013 | controller -> localhost | |         B = = + |
2026-02-19 00:33:37.322033 | controller -> localhost | |        . + = =  |
2026-02-19 00:33:37.322053 | controller -> localhost | |           . o . |
2026-02-19 00:33:37.322074 | controller -> localhost | +----[SHA256]-----+
2026-02-19 00:33:37.322129 | controller -> localhost | ok: Runtime: 0:00:00.170818
2026-02-19 00:33:37.331042 | 
2026-02-19 00:33:37.331137 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-02-19 00:33:37.364832 | controller | ok
2026-02-19 00:33:37.381487 | controller | included: /var/lib/zuul/builds/4f87683ae3b041599a6b61355d56f31d/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-02-19 00:33:37.393779 | 
2026-02-19 00:33:37.393892 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-02-19 00:33:37.418719 | controller | skipping: Conditional result was False
2026-02-19 00:33:37.425561 | 
2026-02-19 00:33:37.425673 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-02-19 00:33:37.897344 | controller | changed
2026-02-19 00:33:37.903833 | 
2026-02-19 00:33:37.903913 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-02-19 00:33:38.152102 | controller | ok
2026-02-19 00:33:38.158495 | 
2026-02-19 00:33:38.158602 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-02-19 00:33:38.793375 | controller | changed
2026-02-19 00:33:38.801535 | 
2026-02-19 00:33:38.801690 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-02-19 00:33:39.439198 | controller | changed
2026-02-19 00:33:39.446762 | 
2026-02-19 00:33:39.446958 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-02-19 00:33:39.474426 | controller | skipping: Conditional result was False
2026-02-19 00:33:39.482833 | 
2026-02-19 00:33:39.482987 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-02-19 00:33:39.959236 | controller -> localhost | changed
2026-02-19 00:33:39.975694 | 
2026-02-19 00:33:39.975898 | TASK [add-build-sshkey : Add back temp key]
2026-02-19 00:33:40.281491 | controller -> localhost | Identity added: /var/lib/zuul/builds/4f87683ae3b041599a6b61355d56f31d/work/4f87683ae3b041599a6b61355d56f31d_id_rsa (zuul-build-sshkey)
2026-02-19 00:33:40.281822 | controller -> localhost | ok: Runtime: 0:00:00.015356
2026-02-19 00:33:40.290119 | 
2026-02-19 00:33:40.290291 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-02-19 00:33:40.664191 | controller | ok
2026-02-19 00:33:40.685062 | 
2026-02-19 00:33:40.685230 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-02-19 00:33:40.720262 | controller | skipping: Conditional result was False
2026-02-19 00:33:40.738877 | 
2026-02-19 00:33:40.739460 | TASK [Run add-build-sshkey role (ECDSA)]
2026-02-19 00:33:40.771508 | controller | ok
2026-02-19 00:33:40.790111 | 
2026-02-19 00:33:40.790290 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-02-19 00:33:41.032871 | controller -> localhost | ok
2026-02-19 00:33:41.039818 | 
2026-02-19 00:33:41.039928 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-02-19 00:33:41.063673 | controller | ok
2026-02-19 00:33:41.077334 | controller | included: /var/lib/zuul/builds/4f87683ae3b041599a6b61355d56f31d/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-02-19 00:33:41.084891 | 
2026-02-19 00:33:41.085009 | TASK [add-build-sshkey : Create Temp SSH key]
2026-02-19 00:33:41.365738 | controller -> localhost | Generating public/private ecdsa key pair.
2026-02-19 00:33:41.365951 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/4f87683ae3b041599a6b61355d56f31d/work/4f87683ae3b041599a6b61355d56f31d_id_ecdsa.
2026-02-19 00:33:41.365982 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/4f87683ae3b041599a6b61355d56f31d/work/4f87683ae3b041599a6b61355d56f31d_id_ecdsa.pub.
2026-02-19 00:33:41.366016 | controller -> localhost | The key fingerprint is:
2026-02-19 00:33:41.366038 | controller -> localhost | SHA256:Wy62u79j65Xxt6MqCMcHDhy+QHLmtxWHJnfItiIfSn0 zuul-build-sshkey
2026-02-19 00:33:41.366058 | controller -> localhost | The key's randomart image is:
2026-02-19 00:33:41.366077 | controller -> localhost | +---[ECDSA 521]---+
2026-02-19 00:33:41.366095 | controller -> localhost | |      . o        |
2026-02-19 00:33:41.366114 | controller -> localhost | | . + o O o       |
2026-02-19 00:33:41.366132 | controller -> localhost | |  * + * =        |
2026-02-19 00:33:41.366150 | controller -> localhost | |   = O E         |
2026-02-19 00:33:41.366167 | controller -> localhost | |  . * @ S . .    |
2026-02-19 00:33:41.366185 | controller -> localhost | |   . = + =   +   |
2026-02-19 00:33:41.366202 | controller -> localhost | |      o * . o . .|
2026-02-19 00:33:41.366220 | controller -> localhost | |       o +o.   o.|
2026-02-19 00:33:41.366237 | controller -> localhost | |        +**=.....|
2026-02-19 00:33:41.366255 | controller -> localhost | +----[SHA256]-----+
2026-02-19 00:33:41.366303 | controller -> localhost | ok: Runtime: 0:00:00.007918
2026-02-19 00:33:41.374199 | 
2026-02-19 00:33:41.374327 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-02-19 00:33:41.405852 | controller | ok
2026-02-19 00:33:41.414089 | controller | included: /var/lib/zuul/builds/4f87683ae3b041599a6b61355d56f31d/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-02-19 00:33:41.425861 | 
2026-02-19 00:33:41.426016 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-02-19 00:33:41.451400 | controller | skipping: Conditional result was False
2026-02-19 00:33:41.458361 | 
2026-02-19 00:33:41.458460 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-02-19 00:33:41.727975 | controller | changed
2026-02-19 00:33:41.736928 | 
2026-02-19 00:33:41.737031 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-02-19 00:33:41.966428 | controller | ok
2026-02-19 00:33:41.977131 | 
2026-02-19 00:33:41.977334 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-02-19 00:33:42.600695 | controller | changed
2026-02-19 00:33:42.613252 | 
2026-02-19 00:33:42.613439 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-02-19 00:33:43.223019 | controller | changed
2026-02-19 00:33:43.231329 | 
2026-02-19 00:33:43.231485 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-02-19 00:33:43.255807 | controller | skipping: Conditional result was False
2026-02-19 00:33:43.326687 | 
2026-02-19 00:33:43.326906 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-02-19 00:33:43.583514 | controller -> localhost | changed
2026-02-19 00:33:43.606838 | 
2026-02-19 00:33:43.607033 | TASK [add-build-sshkey : Add back temp key]
2026-02-19 00:33:43.951577 | controller -> localhost | Identity added: /var/lib/zuul/builds/4f87683ae3b041599a6b61355d56f31d/work/4f87683ae3b041599a6b61355d56f31d_id_ecdsa (zuul-build-sshkey)
2026-02-19 00:33:43.952018 | controller -> localhost | ok: Runtime: 0:00:00.012501
2026-02-19 00:33:43.963855 | 
2026-02-19 00:33:43.964019 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-02-19 00:33:44.164109 | controller | ok
2026-02-19 00:33:44.175699 | 
2026-02-19 00:33:44.175898 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-02-19 00:33:44.202715 | controller | skipping: Conditional result was False
2026-02-19 00:33:44.217755 | 
2026-02-19 00:33:44.217889 | TASK [include_role : remove-zuul-sshkey]
2026-02-19 00:33:44.233192 | controller | skipping: Conditional result was False
2026-02-19 00:33:44.241342 | 
2026-02-19 00:33:44.241449 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-02-19 00:33:44.474410 | controller | ok: "logs"
2026-02-19 00:33:44.474791 | controller | ok: All items complete
2026-02-19 00:33:44.474842 | 
2026-02-19 00:33:44.656659 | controller | ok: "artifacts"
2026-02-19 00:33:44.863858 | controller | ok: "docs"
2026-02-19 00:33:44.892647 | 
2026-02-19 00:33:44.892915 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-02-19 00:33:45.136041 | controller | changed: "logs"
2026-02-19 00:33:45.329524 | controller | changed: "artifacts"
2026-02-19 00:33:45.544030 | controller | changed: "docs"
2026-02-19 00:33:45.595968 | 
2026-02-19 00:33:45.596121 | PLAY RECAP
2026-02-19 00:33:45.596186 | controller | ok: 30 changed: 16 unreachable: 0 failed: 0 skipped: 18 rescued: 0 ignored: 0
2026-02-19 00:33:45.596226 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-02-19 00:33:45.596254 | 
2026-02-19 00:33:45.753363 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master]
2026-02-19 00:33:45.754381 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master]
2026-02-19 00:33:46.434901 | 
2026-02-19 00:33:46.435044 | PLAY [all]
2026-02-19 00:33:46.460510 | 
2026-02-19 00:33:46.460693 | TASK [Install binary dependencies]
2026-02-19 00:33:46.521698 | controller | ok
2026-02-19 00:33:46.542111 | 
2026-02-19 00:33:46.542276 | TASK [bindep : Include find tasks]
2026-02-19 00:33:46.572946 | controller | ok
2026-02-19 00:33:46.584415 | controller | included: /var/lib/zuul/builds/4f87683ae3b041599a6b61355d56f31d/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find.yaml
2026-02-19 00:33:46.591462 | 
2026-02-19 00:33:46.591568 | TASK [bindep : Look for bindep.txt]
2026-02-19 00:33:46.972976 | controller | ok
2026-02-19 00:33:46.982943 | 
2026-02-19 00:33:46.983065 | TASK [bindep : Define bindep_file fact]
2026-02-19 00:33:47.009965 | controller | skipping: Conditional result was False
2026-02-19 00:33:47.016857 | 
2026-02-19 00:33:47.016947 | TASK [bindep : Look for other-requirements.txt]
2026-02-19 00:33:47.240041 | controller | ok
2026-02-19 00:33:47.254422 | 
2026-02-19 00:33:47.254620 | TASK [bindep : Define bindep_file fact]
2026-02-19 00:33:47.293367 | controller | skipping: Conditional result was False
2026-02-19 00:33:47.300271 | 
2026-02-19 00:33:47.300410 | TASK [bindep : Look for bindep fallback file]
2026-02-19 00:33:47.335551 | controller | skipping: Conditional result was False
2026-02-19 00:33:47.346487 | 
2026-02-19 00:33:47.346671 | TASK [bindep : Define bindep_file fact]
2026-02-19 00:33:47.383332 | controller | skipping: Conditional result was False
2026-02-19 00:33:47.392567 | 
2026-02-19 00:33:47.392793 | TASK [bindep : Include bindep tasks]
2026-02-19 00:33:47.418924 | controller | skipping: Conditional result was False
2026-02-19 00:33:47.428316 | 
2026-02-19 00:33:47.428499 | TASK [bindep : Include install tasks]
2026-02-19 00:33:47.480519 | controller | skipping: Conditional result was False
2026-02-19 00:33:47.490055 | 
2026-02-19 00:33:47.490186 | LOOP [bindep : Include package tasks]
2026-02-19 00:33:47.547333 | 
2026-02-19 00:33:47.547524 | TASK [Run test-setup role]
2026-02-19 00:33:47.568383 | controller | ok
2026-02-19 00:33:47.585132 | 
2026-02-19 00:33:47.585257 | TASK [test-setup : Check if project's tools/test-setup.sh exists]
2026-02-19 00:33:47.802038 | controller | ok
2026-02-19 00:33:47.813266 | 
2026-02-19 00:33:47.813457 | TASK [test-setup : Run tools/test-setup.sh]
2026-02-19 00:33:48.353659 | controller | skipping: Conditional result was False
2026-02-19 00:33:48.384078 | 
2026-02-19 00:33:48.384250 | PLAY RECAP
2026-02-19 00:33:48.384301 | controller | ok: 4 changed: 0 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0
2026-02-19 00:33:48.384323 | 
2026-02-19 00:33:48.533467 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master]
2026-02-19 00:33:48.534705 | PRE-RUN START: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master]
2026-02-19 00:33:49.206952 | 
2026-02-19 00:33:49.207117 | PLAY [controller]
2026-02-19 00:33:49.229644 | 
2026-02-19 00:33:49.229840 | TASK [Create the /root directory]
2026-02-19 00:33:49.652944 | controller | ok
2026-02-19 00:33:49.659614 | 
2026-02-19 00:33:49.659828 | TASK [Install glibc-langpack-en]
2026-02-19 00:33:53.700017 | controller | ok: Nothing to do
2026-02-19 00:33:53.707180 | 
2026-02-19 00:33:53.707336 | TASK [Ensure controller directory exists]
2026-02-19 00:33:53.922568 | controller | changed
2026-02-19 00:33:53.928478 | 
2026-02-19 00:33:53.928558 | TASK [Install container runtime]
2026-02-19 00:33:53.979686 | controller | ok
2026-02-19 00:33:54.018596 | 
2026-02-19 00:33:54.018761 | LOOP [ensure-podman : Find distribution installation]
2026-02-19 00:33:54.046122 | controller | ok: "/var/lib/zuul/builds/4f87683ae3b041599a6b61355d56f31d/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-podman/tasks/RedHat.yaml"
2026-02-19 00:33:54.066874 | controller | included: /var/lib/zuul/builds/4f87683ae3b041599a6b61355d56f31d/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-podman/tasks/RedHat.yaml
2026-02-19 00:33:54.074453 | 
2026-02-19 00:33:54.074573 | TASK [ensure-podman : Install podman (RedHat)]
2026-02-19 00:34:58.373715 | controller | changed
2026-02-19 00:34:58.390150 | 
2026-02-19 00:34:58.390347 | TASK [ensure-podman : Fetch podman version]
2026-02-19 00:34:58.967205 | controller | Client:       Podman Engine
2026-02-19 00:34:58.998388 | controller | Version:      4.6.2
2026-02-19 00:34:58.998419 | controller | API Version:  4.6.2
2026-02-19 00:34:58.998427 | controller | Go Version:   go1.19.12
2026-02-19 00:34:58.998444 | controller | Built:        Mon Aug 28 19:38:31 2023
2026-02-19 00:34:58.998452 | controller | OS/Arch:      linux/amd64
2026-02-19 00:34:59.438390 | controller | ok: Runtime: 0:00:00.200254
2026-02-19 00:34:59.446505 | 
2026-02-19 00:34:59.446678 | TASK [ensure-podman : Print podman version installed]
2026-02-19 00:34:59.479928 | Podman version: Client:       Podman Engine
2026-02-19 00:34:59.480109 | Version:      4.6.2
2026-02-19 00:34:59.480138 | API Version:  4.6.2
2026-02-19 00:34:59.480158 | Go Version:   go1.19.12
2026-02-19 00:34:59.480177 | Built:        Mon Aug 28 19:38:31 2023
2026-02-19 00:34:59.480195 | OS/Arch:      linux/amd64
2026-02-19 00:34:59.486636 | 
2026-02-19 00:34:59.486803 | TASK [ensure-podman : Validate podman engine]
2026-02-19 00:35:00.019284 | controller | skipping: Conditional result was False
2026-02-19 00:35:00.028591 | 
2026-02-19 00:35:00.028758 | TASK [ensure-podman : Set up docker compatability socket]
2026-02-19 00:35:00.054635 | controller | skipping: Conditional result was False
2026-02-19 00:35:00.086452 | 
2026-02-19 00:35:00.086789 | TASK [Ensure python3.8 is present]
2026-02-19 00:35:00.113985 | controller | skipping: Conditional result was False
2026-02-19 00:35:00.122868 | 
2026-02-19 00:35:00.122971 | TASK [Run our-ensure-python role (Fedora only for now)]
2026-02-19 00:35:00.147553 | controller | ok
2026-02-19 00:35:00.175957 | 
2026-02-19 00:35:00.176158 | TASK [our-ensure-python : Install the right Python version (rpm)]
2026-02-19 00:35:01.664497 | controller | ok: Nothing to do
2026-02-19 00:35:01.677561 | 
2026-02-19 00:35:01.677883 | TASK [our-ensure-python : Also install python3-devel]
2026-02-19 00:35:10.432167 | controller | changed
2026-02-19 00:35:10.457596 | 
2026-02-19 00:35:10.457795 | TASK [Run ensure-virtualenv role]
2026-02-19 00:35:10.481523 | controller | ok
2026-02-19 00:35:10.513676 | 
2026-02-19 00:35:10.513888 | TASK [ensure-virtualenv : Check if virtualenv is installed]
2026-02-19 00:35:10.750860 | controller | /usr/bin/virtualenv
2026-02-19 00:35:11.049391 | controller | ok: Runtime: 0:00:00.005304
2026-02-19 00:35:11.064368 | 
2026-02-19 00:35:11.064595 | LOOP [ensure-virtualenv : Install virtualenv package]
2026-02-19 00:35:11.088263 | controller | skipping: Conditional result was False
2026-02-19 00:35:11.088836 | controller | ok: All items complete
2026-02-19 00:35:11.088914 | 
2026-02-19 00:35:11.117938 | 
2026-02-19 00:35:11.118184 | TASK [Find the full path of the Python interpreter]
2026-02-19 00:35:11.399874 | controller | /usr/bin/python3
2026-02-19 00:35:11.714483 | controller | ok
2026-02-19 00:35:11.722686 | 
2026-02-19 00:35:11.722851 | TASK [Create virtualenv for ansible-test with a specific interpreter]
2026-02-19 00:35:12.580878 | controller | created virtual environment CPython3.11.0.final.0-64 in 508ms
2026-02-19 00:35:12.608942 | controller |   creator CPython3Posix(dest=/home/zuul/venv, clear=False, no_vcs_ignore=False, global=False)
2026-02-19 00:35:12.609383 | 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-02-19 00:35:12.609608 | controller |     added seed packages: pip==22.2.2, setuptools==62.6.0, wheel==0.37.1
2026-02-19 00:35:12.609630 | controller |   activators BashActivator,CShellActivator,FishActivator,NushellActivator,PowerShellActivator,PythonActivator
2026-02-19 00:35:12.764647 | controller | changed
2026-02-19 00:35:12.773884 | 
2026-02-19 00:35:12.774033 | TASK [Set selinux package]
2026-02-19 00:35:12.807031 | controller | ok
2026-02-19 00:35:12.813749 | 
2026-02-19 00:35:12.813880 | TASK [Set selinux package (Fedora)]
2026-02-19 00:35:12.855064 | controller | ok
2026-02-19 00:35:12.861368 | 
2026-02-19 00:35:12.861534 | TASK [Install selinux into virtualenv]
2026-02-19 00:35:50.271101 | controller | Collecting selinux-please-lie-to-me
2026-02-19 00:36:26.474067 | controller |   Downloading selinux_please_lie_to_me-1.0.1-py2.py3-none-any.whl (3.8 kB)
2026-02-19 00:36:26.779992 | controller | Collecting setuptools<50.0.0
2026-02-19 00:36:26.787991 | controller |   Downloading setuptools-49.6.0-py3-none-any.whl (803 kB)
2026-02-19 00:36:26.843108 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 803.3/803.3 kB 15.8 MB/s eta 0:00:00
2026-02-19 00:36:26.922827 | controller | Installing collected packages: setuptools, selinux-please-lie-to-me
2026-02-19 00:36:26.923043 | controller |   Attempting uninstall: setuptools
2026-02-19 00:36:26.925505 | controller |     Found existing installation: setuptools 62.6.0
2026-02-19 00:36:26.985795 | controller |     Uninstalling setuptools-62.6.0:
2026-02-19 00:36:26.994187 | controller |       Successfully uninstalled setuptools-62.6.0
2026-02-19 00:36:27.334549 | controller | Successfully installed selinux-please-lie-to-me-1.0.1 setuptools-49.6.0
2026-02-19 00:36:38.847380 | controller |
2026-02-19 00:36:38.933679 | controller | [notice] A new release of pip available: 22.2.2 -> 26.0.1
2026-02-19 00:36:38.933740 | controller | [notice] To update, run: python -m pip install --upgrade pip
2026-02-19 00:36:38.967858 | controller | ok: Runtime: 0:01:25.858591
2026-02-19 00:36:38.978126 | 
2026-02-19 00:36:38.978317 | TASK [Install pytest-forked into virtualenv]
2026-02-19 00:36:51.080930 | controller | Collecting pytest-forked
2026-02-19 00:37:15.363766 | controller |   Downloading pytest_forked-1.6.0-py3-none-any.whl (4.9 kB)
2026-02-19 00:37:15.406912 | controller | Collecting py
2026-02-19 00:37:15.410702 | controller |   Downloading py-1.11.0-py2.py3-none-any.whl (98 kB)
2026-02-19 00:37:15.432720 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 98.7/98.7 kB 5.2 MB/s eta 0:00:00
2026-02-19 00:37:15.546607 | controller | Collecting pytest>=3.10
2026-02-19 00:37:15.550079 | controller |   Downloading pytest-9.0.2-py3-none-any.whl (374 kB)
2026-02-19 00:37:15.565953 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 374.8/374.8 kB 30.5 MB/s eta 0:00:00
2026-02-19 00:37:15.603988 | controller | Collecting iniconfig>=1.0.1
2026-02-19 00:37:15.607415 | controller |   Downloading iniconfig-2.3.0-py3-none-any.whl (7.5 kB)
2026-02-19 00:37:15.655799 | controller | Collecting packaging>=22
2026-02-19 00:37:15.662991 | controller |   Downloading packaging-26.0-py3-none-any.whl (74 kB)
2026-02-19 00:37:15.669208 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 74.4/74.4 kB 19.4 MB/s eta 0:00:00
2026-02-19 00:37:15.703155 | controller | Collecting pluggy<2,>=1.5
2026-02-19 00:37:15.706554 | controller |   Downloading pluggy-1.6.0-py3-none-any.whl (20 kB)
2026-02-19 00:37:15.753269 | controller | Collecting pygments>=2.7.2
2026-02-19 00:37:15.756830 | controller |   Downloading pygments-2.19.2-py3-none-any.whl (1.2 MB)
2026-02-19 00:37:15.772087 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 1.2/1.2 MB 95.7 MB/s eta 0:00:00
2026-02-19 00:37:15.838895 | controller | Installing collected packages: pygments, py, pluggy, packaging, iniconfig, pytest, pytest-forked
2026-02-19 00:37:16.957490 | controller | Successfully installed iniconfig-2.3.0 packaging-26.0 pluggy-1.6.0 py-1.11.0 pygments-2.19.2 pytest-9.0.2 pytest-forked-1.6.0
2026-02-19 00:37:16.966433 | controller |
2026-02-19 00:37:17.039581 | controller | [notice] A new release of pip available: 22.2.2 -> 26.0.1
2026-02-19 00:37:17.039652 | controller | [notice] To update, run: python -m pip install --upgrade pip
2026-02-19 00:37:17.085845 | controller | ok: Runtime: 0:00:37.843842
2026-02-19 00:37:17.091676 | 
2026-02-19 00:37:17.091784 | TASK [Update pip]
2026-02-19 00:37:17.659341 | controller | Requirement already satisfied: pip in ./venv/lib/python3.11/site-packages (22.2.2)
2026-02-19 00:37:27.753314 | controller | Collecting pip
2026-02-19 00:37:39.940670 | controller |   Downloading pip-26.0.1-py3-none-any.whl (1.8 MB)
2026-02-19 00:37:40.019272 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 1.8/1.8 MB 24.5 MB/s eta 0:00:00
2026-02-19 00:37:40.082793 | controller | Installing collected packages: pip
2026-02-19 00:37:40.083016 | controller |   Attempting uninstall: pip
2026-02-19 00:37:40.085194 | controller |     Found existing installation: pip 22.2.2
2026-02-19 00:37:40.224710 | controller |     Uninstalling pip-22.2.2:
2026-02-19 00:37:40.240039 | controller |       Successfully uninstalled pip-22.2.2
2026-02-19 00:37:41.043070 | controller | Successfully installed pip-26.0.1
2026-02-19 00:37:41.186988 | controller | ok: Runtime: 0:00:23.770471
2026-02-19 00:37:41.195886 | 
2026-02-19 00:37:41.196001 | TASK [Ensure pip temp directory exists in /var/tmp]
2026-02-19 00:37:41.428704 | controller | changed
2026-02-19 00:37:41.444182 | 
2026-02-19 00:37:41.444336 | TASK [Install ansible into virtualenv]
2026-02-19 00:37:41.976486 | controller | Processing ./src/github.com/ansible/ansible
2026-02-19 00:37:41.978959 | controller |   Installing build dependencies: started
2026-02-19 00:38:02.992724 | controller |   Installing build dependencies: finished with status 'done'
2026-02-19 00:38:02.993335 | controller |   Getting requirements to build wheel: started
2026-02-19 00:38:03.764146 | controller |   Getting requirements to build wheel: finished with status 'done'
2026-02-19 00:38:03.765565 | controller |   Preparing metadata (pyproject.toml): started
2026-02-19 00:38:04.231000 | controller |   Preparing metadata (pyproject.toml): finished with status 'done'
2026-02-19 00:38:04.235599 | 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-02-19 00:38:04.239095 | controller | ERROR: Package 'ansible-core' requires a different Python: 3.11.0 not in '>=3.12'
2026-02-19 00:38:04.523535 | controller | ERROR
2026-02-19 00:38:04.523799 | controller | {
2026-02-19 00:38:04.523847 | controller |   "delta": "0:00:22.615616",
2026-02-19 00:38:04.523877 | controller |   "end": "2026-02-19 00:38:04.302870",
2026-02-19 00:38:04.523904 | controller |   "msg": "non-zero return code",
2026-02-19 00:38:04.523943 | controller |   "rc": 1,
2026-02-19 00:38:04.523969 | controller |   "start": "2026-02-19 00:37:41.687254"
2026-02-19 00:38:04.523992 | controller | }
failure
2026-02-19 00:38:04.525941 | 
2026-02-19 00:38:04.525999 | PLAY RECAP
2026-02-19 00:38:04.526048 | controller | ok: 18 changed: 10 unreachable: 0 failed: 1 skipped: 4 rescued: 0 ignored: 0
2026-02-19 00:38:04.526076 | 
2026-02-19 00:38:04.691941 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master]
2026-02-19 00:38:04.693275 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master]
2026-02-19 00:38:05.277491 | 
2026-02-19 00:38:05.277632 | PLAY [all]
2026-02-19 00:38:05.300431 | 
2026-02-19 00:38:05.300572 | TASK [fetch-subunit-output : Find stestr or testr executable]
2026-02-19 00:38:05.536203 | controller | changed: non-zero return code
2026-02-19 00:38:05.543381 | 
2026-02-19 00:38:05.543481 | TASK [fetch-subunit-output : Get the list of directories with subunit files]
2026-02-19 00:38:05.575593 | controller | skipping: Conditional result was False
2026-02-19 00:38:05.593259 | 
2026-02-19 00:38:05.593484 | LOOP [fetch-subunit-output : Find any inflight partial subunit files]
2026-02-19 00:38:05.632661 | 
2026-02-19 00:38:05.632981 | LOOP [fetch-subunit-output : Copy any inflight subunit files]
2026-02-19 00:38:05.660622 | 
2026-02-19 00:38:05.660934 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream]
2026-02-19 00:38:05.677226 | controller | skipping: Conditional result was False
2026-02-19 00:38:05.688985 | 
2026-02-19 00:38:05.689158 | LOOP [fetch-subunit-output : Generate subunit file]
2026-02-19 00:38:05.724012 | 
2026-02-19 00:38:05.724258 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory]
2026-02-19 00:38:05.740013 | controller | skipping: Conditional result was False
2026-02-19 00:38:05.748919 | 
2026-02-19 00:38:05.749071 | TASK [fetch-subunit-output : Remove the temporary file]
2026-02-19 00:38:05.764816 | controller | skipping: Conditional result was False
2026-02-19 00:38:05.774997 | 
2026-02-19 00:38:05.775138 | TASK [fetch-subunit-output : Process and fetch subunit results]
2026-02-19 00:38:05.801379 | controller | skipping: Conditional result was False
2026-02-19 00:38:05.830955 | 
2026-02-19 00:38:05.831101 | PLAY RECAP
2026-02-19 00:38:05.831147 | controller | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0
2026-02-19 00:38:05.831170 | 
2026-02-19 00:38:05.960878 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master]
2026-02-19 00:38:05.962145 | POST-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/post.yaml@master]
2026-02-19 00:38:06.677541 | 
2026-02-19 00:38:06.677833 | PLAY [all:!appliance*]
2026-02-19 00:38:06.714410 | 
2026-02-19 00:38:06.714594 | TASK [unregister the node]
2026-02-19 00:38:07.259009 | controller | skipping: Conditional result was False
2026-02-19 00:38:07.268497 | 
2026-02-19 00:38:07.268697 | TASK [include_role : fetch-output]
2026-02-19 00:38:07.303232 | controller | ok
2026-02-19 00:38:07.328682 | 
2026-02-19 00:38:07.328828 | TASK [fetch-output : Set log path for multiple nodes]
2026-02-19 00:38:07.393990 | controller | skipping: Conditional result was False
2026-02-19 00:38:07.403504 | 
2026-02-19 00:38:07.403675 | TASK [fetch-output : Set log path for single node]
2026-02-19 00:38:07.437871 | controller | ok
2026-02-19 00:38:07.447270 | 
2026-02-19 00:38:07.447407 | LOOP [fetch-output : Ensure local output dirs]
2026-02-19 00:38:07.887847 | controller -> localhost | ok: "/var/lib/zuul/builds/4f87683ae3b041599a6b61355d56f31d/work/logs"
2026-02-19 00:38:08.121076 | controller -> localhost | changed: "/var/lib/zuul/builds/4f87683ae3b041599a6b61355d56f31d/work/artifacts"
2026-02-19 00:38:08.365777 | controller -> localhost | changed: "/var/lib/zuul/builds/4f87683ae3b041599a6b61355d56f31d/work/docs"
2026-02-19 00:38:08.384073 | 
2026-02-19 00:38:08.384190 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-02-19 00:38:09.030388 | controller | changed:
2026-02-19 00:38:09.030617 | controller | .d..t...... ./
2026-02-19 00:38:09.030651 | controller | cd+++++++++ controller/
2026-02-19 00:38:09.030748 | controller | changed: All items complete
2026-02-19 00:38:09.030785 | 
2026-02-19 00:38:09.493544 | controller | changed: .d..t...... ./
2026-02-19 00:38:09.933079 | controller | changed: .d..t...... ./
2026-02-19 00:38:09.952893 | 
2026-02-19 00:38:09.953029 | TASK [include_role : fetch-output-openshift]
2026-02-19 00:38:09.969026 | controller | skipping: Conditional result was False
2026-02-19 00:38:09.979795 | 
2026-02-19 00:38:09.979934 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-02-19 00:38:10.006026 | controller | skipping: Conditional result was False
2026-02-19 00:38:10.014625 | controller | skipping: Conditional result was False
2026-02-19 00:38:10.047001 | 
2026-02-19 00:38:10.047144 | PLAY [localhost]
2026-02-19 00:38:10.060288 | 
2026-02-19 00:38:10.060397 | TASK [Run Zuul manifest role]
2026-02-19 00:38:10.079364 | localhost | ok
2026-02-19 00:38:10.104170 | 
2026-02-19 00:38:10.104296 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-02-19 00:38:10.531464 | localhost | changed
2026-02-19 00:38:10.537131 | 
2026-02-19 00:38:10.537260 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-02-19 00:38:10.578961 | localhost | ok
2026-02-19 00:38:10.590965 | 
2026-02-19 00:38:10.591105 | TASK [Set zuul-log-path fact]
2026-02-19 00:38:10.611557 | localhost | ok
2026-02-19 00:38:10.625930 | 
2026-02-19 00:38:10.626066 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-02-19 00:38:10.656226 | localhost | ok
2026-02-19 00:38:10.664875 | 
2026-02-19 00:38:10.664991 | LOOP [Run upload-logs-swift role]
2026-02-19 00:38:10.699453 | localhost | Output suppressed because no_log was given
2026-02-19 00:38:10.739304 | 
2026-02-19 00:38:10.739460 | TASK [Set zuul-log-path fact]
2026-02-19 00:38:10.765307 | localhost | skipping: Conditional result was False
2026-02-19 00:38:10.770915 | 
2026-02-19 00:38:10.771028 | TASK [upload-logs-swift : Ensure logs are readable before uploading]
2026-02-19 00:38:11.193313 | localhost -> localhost | ok: Runtime: 0:00:00.005337
2026-02-19 00:38:11.199844 | 
2026-02-19 00:38:11.199948 | TASK [upload-logs-swift : Upload logs to swift]