2026-03-02 00:06:42.612254 | Job console starting...
2026-03-02 00:06:42.624477 | Updating repositories
2026-03-02 00:06:42.758699 | Preparing job workspace
2026-03-02 00:06:48.600649 | Running Ansible setup...
2026-03-02 00:06:53.207290 | PRE-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master]
2026-03-02 00:06:53.828497 | 
2026-03-02 00:06:53.828620 | PLAY [localhost]
2026-03-02 00:06:53.837335 | 
2026-03-02 00:06:53.837409 | TASK [Gathering Facts]
2026-03-02 00:06:54.857135 | localhost | ok
2026-03-02 00:06:54.871555 | 
2026-03-02 00:06:54.871633 | TASK [Setup log path fact]
2026-03-02 00:06:54.889640 | localhost | ok
2026-03-02 00:06:54.904234 | 
2026-03-02 00:06:54.904334 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-03-02 00:06:54.934354 | localhost | ok
2026-03-02 00:06:54.943658 | 
2026-03-02 00:06:54.943799 | TASK [emit-job-header : Print job information]
2026-03-02 00:06:54.983509 | # Job Information
2026-03-02 00:06:54.983734 | Ansible Version: 2.15.12
2026-03-02 00:06:54.983781 | Job: ansible-test-sanity-docker-milestone
2026-03-02 00:06:54.983810 | Pipeline: periodic
2026-03-02 00:06:54.983846 | Executor: ze02.softwarefactory-project.io
2026-03-02 00:06:54.983885 | Triggered by: https://github.com/ansible-collections/ansible.network
2026-03-02 00:06:54.983924 | Log URL (when completed): https://ansible.softwarefactory-project.io/logs/599/ansible/59983e903d4747cc852e30f86a912552/
2026-03-02 00:06:54.983961 | Event ID: 2eb49f0a79aa4455b1e117a286c05c7e
2026-03-02 00:06:54.989531 | 
2026-03-02 00:06:54.989655 | LOOP [emit-job-header : Print node information]
2026-03-02 00:06:55.092362 | localhost | ok:
2026-03-02 00:06:55.092680 | localhost | # Node Information
2026-03-02 00:06:55.092718 | localhost | Inventory Hostname: controller
2026-03-02 00:06:55.092739 | localhost | Hostname: np0005634884
2026-03-02 00:06:55.092760 | localhost | Username: zuul
2026-03-02 00:06:55.092788 | localhost | Distro: Fedora 37
2026-03-02 00:06:55.092809 | localhost | Provider: ansible-vexxhost-ca-ymq-1
2026-03-02 00:06:55.092850 | localhost | Region: ca-ymq-1
2026-03-02 00:06:55.092885 | localhost | Label: ansible-fedora-37-1vcpu
2026-03-02 00:06:55.092907 | localhost | Product Name: OpenStack Nova
2026-03-02 00:06:55.092925 | localhost | Interface IP: 162.253.55.110
2026-03-02 00:06:55.107349 | 
2026-03-02 00:06:55.107499 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-03-02 00:06:55.527728 | localhost -> localhost | changed
2026-03-02 00:06:55.534294 | 
2026-03-02 00:06:55.534389 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-03-02 00:06:56.510561 | localhost -> localhost | changed
2026-03-02 00:06:56.529780 | 
2026-03-02 00:06:56.529838 | PLAY [all:!appliance*]
2026-03-02 00:06:56.546088 | 
2026-03-02 00:06:56.546168 | TASK [include_role : start-zuul-console]
2026-03-02 00:06:56.564716 | controller | ok
2026-03-02 00:06:56.577932 | 
2026-03-02 00:06:56.577994 | TASK [start-zuul-console : Start zuul_console daemon.]
2026-03-02 00:06:56.949472 | controller | ok
2026-03-02 00:06:56.974900 | 
2026-03-02 00:06:56.975047 | TASK [use-our-mirror : Retrieve the IP address]
2026-03-02 00:06:57.800224 | controller | ok: Status code was 404 and not [200]: HTTP Error 404: Not Found
2026-03-02 00:06:57.810044 | 
2026-03-02 00:06:57.810181 | TASK [use-our-mirror : Ensure the registry service is running on the mirror]
2026-03-02 00:06:58.357421 | controller | skipping: Conditional result was False
2026-03-02 00:06:58.371250 | 
2026-03-02 00:06:58.371344 | TASK [use-our-mirror : Write the IP in /etc/hosts]
2026-03-02 00:06:58.396847 | controller | skipping: Conditional result was False
2026-03-02 00:06:58.406453 | 
2026-03-02 00:06:58.406545 | TASK [use-our-mirror : Create the podman configuration directory]
2026-03-02 00:06:58.431356 | controller | skipping: Conditional result was False
2026-03-02 00:06:58.440950 | 
2026-03-02 00:06:58.441063 | TASK [use-our-mirror : Copy the podman configuration]
2026-03-02 00:06:58.466722 | controller | skipping: Conditional result was False
2026-03-02 00:06:58.475792 | 
2026-03-02 00:06:58.475898 | TASK [use-our-mirror : Copy the Yum configuration (main)]
2026-03-02 00:06:58.501008 | controller | skipping: Conditional result was False
2026-03-02 00:06:58.510110 | 
2026-03-02 00:06:58.510225 | TASK [use-our-mirror : Copy the Yum configuration (update)]
2026-03-02 00:06:58.535025 | controller | skipping: Conditional result was False
2026-03-02 00:06:58.551794 | 
2026-03-02 00:06:58.551911 | TASK [Disable Fedora Modular]
2026-03-02 00:06:58.793769 | controller | changed
2026-03-02 00:06:58.808410 | 
2026-03-02 00:06:58.808601 | TASK [Enable EPEL]
2026-03-02 00:06:58.845803 | controller | skipping: Conditional result was False
2026-03-02 00:06:58.859137 | 
2026-03-02 00:06:58.859282 | TASK [Register the RHEL node]
2026-03-02 00:06:59.427478 | 
2026-03-02 00:06:59.427823 | TASK [Show the subscription-manager status]
2026-03-02 00:07:00.010829 | controller | skipping: Conditional result was False
2026-03-02 00:07:00.024501 | 
2026-03-02 00:07:00.024698 | TASK [Enable EPEL on RHEL]
2026-03-02 00:07:00.577431 | controller | skipping: Conditional result was False
2026-03-02 00:07:00.591749 | 
2026-03-02 00:07:00.591926 | TASK [Install git and tox]
2026-03-02 00:08:36.988787 | controller | changed
2026-03-02 00:08:36.997142 | 
2026-03-02 00:08:36.997333 | TASK [include_role : prepare-workspace]
2026-03-02 00:08:37.030880 | controller | ok
2026-03-02 00:08:37.060726 | 
2026-03-02 00:08:37.060852 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-03-02 00:08:37.338245 | controller | ok
2026-03-02 00:08:37.356465 | 
2026-03-02 00:08:37.356568 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-03-02 00:13:56.057542 | controller | Output suppressed because no_log was given
2026-03-02 00:13:56.069940 | 
2026-03-02 00:13:56.070014 | TASK [include_role : prepare-workspace-openshift]
2026-03-02 00:13:56.094848 | controller | skipping: Conditional result was False
2026-03-02 00:13:56.140313 | 
2026-03-02 00:13:56.140431 | PLAY [all:!appliance]
2026-03-02 00:13:56.164153 | 
2026-03-02 00:13:56.164288 | TASK [Run add-build-sshkey role (RSA)]
2026-03-02 00:13:56.197062 | controller | ok
2026-03-02 00:13:56.221434 | 
2026-03-02 00:13:56.221555 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-03-02 00:13:56.522952 | controller -> localhost | ok
2026-03-02 00:13:56.534370 | 
2026-03-02 00:13:56.534549 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-03-02 00:13:56.561931 | controller | ok
2026-03-02 00:13:56.591898 | controller | included: /var/lib/zuul/builds/59983e903d4747cc852e30f86a912552/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-03-02 00:13:56.601910 | 
2026-03-02 00:13:56.602040 | TASK [add-build-sshkey : Create Temp SSH key]
2026-03-02 00:13:57.006971 | controller -> localhost | Generating public/private rsa key pair.
2026-03-02 00:13:57.007293 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/59983e903d4747cc852e30f86a912552/work/59983e903d4747cc852e30f86a912552_id_rsa.
2026-03-02 00:13:57.007343 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/59983e903d4747cc852e30f86a912552/work/59983e903d4747cc852e30f86a912552_id_rsa.pub.
2026-03-02 00:13:57.007376 | controller -> localhost | The key fingerprint is:
2026-03-02 00:13:57.007405 | controller -> localhost | SHA256:/s8/eFz+P4F72qO6GGRmTHxu9nMjCKJIhu95+lUSeZE zuul-build-sshkey
2026-03-02 00:13:57.007442 | controller -> localhost | The key's randomart image is:
2026-03-02 00:13:57.007483 | controller -> localhost | +---[RSA 2048]----+
2026-03-02 00:13:57.007512 | controller -> localhost | |         ..      |
2026-03-02 00:13:57.007540 | controller -> localhost | |        oE.      |
2026-03-02 00:13:57.007569 | controller -> localhost | |       o + .     |
2026-03-02 00:13:57.007596 | controller -> localhost | |  .     = o      |
2026-03-02 00:13:57.007622 | controller -> localhost | | . o   oSO +  .  |
2026-03-02 00:13:57.007667 | controller -> localhost | |  + . ..O + o. ..|
2026-03-02 00:13:57.007783 | controller -> localhost | |   o . ... . *.=.|
2026-03-02 00:13:57.007818 | controller -> localhost | |  . ...  .o...O+o|
2026-03-02 00:13:57.007846 | controller -> localhost | |   ++.   ..+===oB|
2026-03-02 00:13:57.007878 | controller -> localhost | +----[SHA256]-----+
2026-03-02 00:13:57.007960 | controller -> localhost | ok: Runtime: 0:00:00.034134
2026-03-02 00:13:57.018389 | 
2026-03-02 00:13:57.018506 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-03-02 00:13:57.040244 | controller | ok
2026-03-02 00:13:57.057236 | controller | included: /var/lib/zuul/builds/59983e903d4747cc852e30f86a912552/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-03-02 00:13:57.066917 | 
2026-03-02 00:13:57.067021 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-03-02 00:13:57.080746 | controller | skipping: Conditional result was False
2026-03-02 00:13:57.088279 | 
2026-03-02 00:13:57.088380 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-03-02 00:13:57.703713 | controller | changed
2026-03-02 00:13:57.713467 | 
2026-03-02 00:13:57.713616 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-03-02 00:13:57.934595 | controller | ok
2026-03-02 00:13:57.946231 | 
2026-03-02 00:13:57.946405 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-03-02 00:13:58.623606 | controller | changed
2026-03-02 00:13:58.635782 | 
2026-03-02 00:13:58.635891 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-03-02 00:13:59.351428 | controller | changed
2026-03-02 00:13:59.361991 | 
2026-03-02 00:13:59.362132 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-03-02 00:13:59.399179 | controller | skipping: Conditional result was False
2026-03-02 00:13:59.410804 | 
2026-03-02 00:13:59.410951 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-03-02 00:13:59.858938 | controller -> localhost | changed
2026-03-02 00:13:59.896633 | 
2026-03-02 00:13:59.896862 | TASK [add-build-sshkey : Add back temp key]
2026-03-02 00:14:00.210001 | controller -> localhost | Identity added: /var/lib/zuul/builds/59983e903d4747cc852e30f86a912552/work/59983e903d4747cc852e30f86a912552_id_rsa (zuul-build-sshkey)
2026-03-02 00:14:00.210280 | controller -> localhost | ok: Runtime: 0:00:00.011441
2026-03-02 00:14:00.221193 | 
2026-03-02 00:14:00.221320 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-03-02 00:14:00.592471 | controller | ok
2026-03-02 00:14:00.606203 | 
2026-03-02 00:14:00.606371 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-03-02 00:14:00.655854 | controller | skipping: Conditional result was False
2026-03-02 00:14:00.674288 | 
2026-03-02 00:14:00.674427 | TASK [Run add-build-sshkey role (ECDSA)]
2026-03-02 00:14:00.708336 | controller | ok
2026-03-02 00:14:00.734894 | 
2026-03-02 00:14:00.735027 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-03-02 00:14:01.045884 | controller -> localhost | ok
2026-03-02 00:14:01.056065 | 
2026-03-02 00:14:01.056168 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-03-02 00:14:01.098356 | controller | ok
2026-03-02 00:14:01.113204 | controller | included: /var/lib/zuul/builds/59983e903d4747cc852e30f86a912552/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-03-02 00:14:01.120000 | 
2026-03-02 00:14:01.120196 | TASK [add-build-sshkey : Create Temp SSH key]
2026-03-02 00:14:01.444609 | controller -> localhost | Generating public/private ecdsa key pair.
2026-03-02 00:14:01.444918 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/59983e903d4747cc852e30f86a912552/work/59983e903d4747cc852e30f86a912552_id_ecdsa.
2026-03-02 00:14:01.444970 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/59983e903d4747cc852e30f86a912552/work/59983e903d4747cc852e30f86a912552_id_ecdsa.pub.
2026-03-02 00:14:01.445015 | controller -> localhost | The key fingerprint is:
2026-03-02 00:14:01.445043 | controller -> localhost | SHA256:GEz2xLO3tvbmI1qzePpQ5p/0g+AGGKymfEmvsgKrOvQ zuul-build-sshkey
2026-03-02 00:14:01.445071 | controller -> localhost | The key's randomart image is:
2026-03-02 00:14:01.445096 | controller -> localhost | +---[ECDSA 521]---+
2026-03-02 00:14:01.445121 | controller -> localhost | |      o..        |
2026-03-02 00:14:01.445146 | controller -> localhost | |     + oo        |
2026-03-02 00:14:01.445170 | controller -> localhost | |     .o .o       |
2026-03-02 00:14:01.445194 | controller -> localhost | |      oo. .      |
2026-03-02 00:14:01.445217 | controller -> localhost | |     ..oS.o.     |
2026-03-02 00:14:01.445241 | controller -> localhost | |..  + . .++      |
2026-03-02 00:14:01.445265 | controller -> localhost | |oo.+ o  .++o..   |
2026-03-02 00:14:01.445290 | controller -> localhost | |+ +Eo .  +B=+o.  |
2026-03-02 00:14:01.445314 | controller -> localhost | |=o.+..  +Bo==... |
2026-03-02 00:14:01.445338 | controller -> localhost | +----[SHA256]-----+
2026-03-02 00:14:01.445407 | controller -> localhost | ok: Runtime: 0:00:00.018798
2026-03-02 00:14:01.456191 | 
2026-03-02 00:14:01.456293 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-03-02 00:14:01.490598 | controller | ok
2026-03-02 00:14:01.502961 | controller | included: /var/lib/zuul/builds/59983e903d4747cc852e30f86a912552/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-03-02 00:14:01.517749 | 
2026-03-02 00:14:01.517866 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-03-02 00:14:01.542267 | controller | skipping: Conditional result was False
2026-03-02 00:14:01.548791 | 
2026-03-02 00:14:01.548858 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-03-02 00:14:01.827841 | controller | changed
2026-03-02 00:14:01.836300 | 
2026-03-02 00:14:01.836402 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-03-02 00:14:02.060149 | controller | ok
2026-03-02 00:14:02.076461 | 
2026-03-02 00:14:02.076647 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-03-02 00:14:02.746571 | controller | changed
2026-03-02 00:14:02.841168 | 
2026-03-02 00:14:02.841376 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-03-02 00:14:03.509154 | controller | changed
2026-03-02 00:14:03.527622 | 
2026-03-02 00:14:03.527974 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-03-02 00:14:03.570596 | controller | skipping: Conditional result was False
2026-03-02 00:14:03.581944 | 
2026-03-02 00:14:03.582070 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-03-02 00:14:03.869030 | controller -> localhost | changed
2026-03-02 00:14:03.902182 | 
2026-03-02 00:14:03.902406 | TASK [add-build-sshkey : Add back temp key]
2026-03-02 00:14:04.206185 | controller -> localhost | Identity added: /var/lib/zuul/builds/59983e903d4747cc852e30f86a912552/work/59983e903d4747cc852e30f86a912552_id_ecdsa (zuul-build-sshkey)
2026-03-02 00:14:04.206451 | controller -> localhost | ok: Runtime: 0:00:00.018958
2026-03-02 00:14:04.219663 | 
2026-03-02 00:14:04.219811 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-03-02 00:14:04.434844 | controller | ok
2026-03-02 00:14:04.441405 | 
2026-03-02 00:14:04.441486 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-03-02 00:14:04.475379 | controller | skipping: Conditional result was False
2026-03-02 00:14:04.488364 | 
2026-03-02 00:14:04.488483 | TASK [include_role : remove-zuul-sshkey]
2026-03-02 00:14:04.522822 | controller | skipping: Conditional result was False
2026-03-02 00:14:04.530395 | 
2026-03-02 00:14:04.530497 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-03-02 00:14:04.769067 | controller | ok: "logs"
2026-03-02 00:14:04.769478 | controller | ok: All items complete
2026-03-02 00:14:04.769529 | 
2026-03-02 00:14:04.973713 | controller | ok: "artifacts"
2026-03-02 00:14:05.171185 | controller | ok: "docs"
2026-03-02 00:14:05.190178 | 
2026-03-02 00:14:05.190392 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-03-02 00:14:05.442617 | controller | changed: "logs"
2026-03-02 00:14:05.646640 | controller | changed: "artifacts"
2026-03-02 00:14:05.872621 | controller | changed: "docs"
2026-03-02 00:14:05.919844 | 
2026-03-02 00:14:05.919982 | PLAY RECAP
2026-03-02 00:14:05.920048 | controller | ok: 30 changed: 16 unreachable: 0 failed: 0 skipped: 18 rescued: 0 ignored: 0
2026-03-02 00:14:05.920086 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-03-02 00:14:05.920113 | 
2026-03-02 00:14:06.060051 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master]
2026-03-02 00:14:06.061762 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master]
2026-03-02 00:14:06.815623 | 
2026-03-02 00:14:06.815755 | PLAY [all]
2026-03-02 00:14:06.843570 | 
2026-03-02 00:14:06.843746 | TASK [Install binary dependencies]
2026-03-02 00:14:06.905843 | controller | ok
2026-03-02 00:14:06.928566 | 
2026-03-02 00:14:06.928751 | TASK [bindep : Include find tasks]
2026-03-02 00:14:06.959244 | controller | ok
2026-03-02 00:14:06.989019 | controller | included: /var/lib/zuul/builds/59983e903d4747cc852e30f86a912552/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find.yaml
2026-03-02 00:14:06.998160 | 
2026-03-02 00:14:06.998263 | TASK [bindep : Look for bindep.txt]
2026-03-02 00:14:07.542930 | controller | ok
2026-03-02 00:14:07.552837 | 
2026-03-02 00:14:07.552942 | TASK [bindep : Define bindep_file fact]
2026-03-02 00:14:07.569087 | controller | skipping: Conditional result was False
2026-03-02 00:14:07.575649 | 
2026-03-02 00:14:07.575735 | TASK [bindep : Look for other-requirements.txt]
2026-03-02 00:14:07.802345 | controller | ok
2026-03-02 00:14:07.812876 | 
2026-03-02 00:14:07.813053 | TASK [bindep : Define bindep_file fact]
2026-03-02 00:14:07.848765 | controller | skipping: Conditional result was False
2026-03-02 00:14:07.856548 | 
2026-03-02 00:14:07.856701 | TASK [bindep : Look for bindep fallback file]
2026-03-02 00:14:07.891104 | controller | skipping: Conditional result was False
2026-03-02 00:14:07.898986 | 
2026-03-02 00:14:07.899081 | TASK [bindep : Define bindep_file fact]
2026-03-02 00:14:07.923789 | controller | skipping: Conditional result was False
2026-03-02 00:14:07.935371 | 
2026-03-02 00:14:07.935483 | TASK [bindep : Include bindep tasks]
2026-03-02 00:14:07.960772 | controller | skipping: Conditional result was False
2026-03-02 00:14:07.967995 | 
2026-03-02 00:14:07.968116 | TASK [bindep : Include install tasks]
2026-03-02 00:14:07.992277 | controller | skipping: Conditional result was False
2026-03-02 00:14:07.999626 | 
2026-03-02 00:14:07.999734 | LOOP [bindep : Include package tasks]
2026-03-02 00:14:08.070235 | 
2026-03-02 00:14:08.070420 | TASK [Run test-setup role]
2026-03-02 00:14:08.092602 | controller | ok
2026-03-02 00:14:08.113166 | 
2026-03-02 00:14:08.113286 | TASK [test-setup : Check if project's tools/test-setup.sh exists]
2026-03-02 00:14:08.333617 | controller | ok
2026-03-02 00:14:08.350862 | 
2026-03-02 00:14:08.351107 | TASK [test-setup : Run tools/test-setup.sh]
2026-03-02 00:14:08.905347 | controller | skipping: Conditional result was False
2026-03-02 00:14:08.942070 | 
2026-03-02 00:14:08.942200 | PLAY RECAP
2026-03-02 00:14:08.942300 | controller | ok: 4 changed: 0 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0
2026-03-02 00:14:08.942334 | 
2026-03-02 00:14:09.060316 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master]
2026-03-02 00:14:09.061334 | PRE-RUN START: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master]
2026-03-02 00:14:09.666346 | 
2026-03-02 00:14:09.666484 | PLAY [controller]
2026-03-02 00:14:09.688006 | 
2026-03-02 00:14:09.688126 | TASK [Create the /root directory]
2026-03-02 00:14:10.291696 | controller | ok
2026-03-02 00:14:10.301713 | 
2026-03-02 00:14:10.301849 | TASK [Install glibc-langpack-en]
2026-03-02 00:14:14.279980 | controller | ok: Nothing to do
2026-03-02 00:14:14.286349 | 
2026-03-02 00:14:14.286421 | TASK [Ensure controller directory exists]
2026-03-02 00:14:14.508903 | controller | changed
2026-03-02 00:14:14.518602 | 
2026-03-02 00:14:14.518749 | TASK [Install container runtime]
2026-03-02 00:14:14.599585 | controller | ok
2026-03-02 00:14:14.656567 | 
2026-03-02 00:14:14.656729 | LOOP [ensure-podman : Find distribution installation]
2026-03-02 00:14:14.678402 | controller | ok: "/var/lib/zuul/builds/59983e903d4747cc852e30f86a912552/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-podman/tasks/RedHat.yaml"
2026-03-02 00:14:14.687566 | controller | included: /var/lib/zuul/builds/59983e903d4747cc852e30f86a912552/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-podman/tasks/RedHat.yaml
2026-03-02 00:14:14.694020 | 
2026-03-02 00:14:14.694090 | TASK [ensure-podman : Install podman (RedHat)]
2026-03-02 00:15:21.381986 | controller | changed
2026-03-02 00:15:21.397991 | 
2026-03-02 00:15:21.398184 | TASK [ensure-podman : Fetch podman version]
2026-03-02 00:15:22.061544 | controller | Client:       Podman Engine
2026-03-02 00:15:22.101028 | controller | Version:      4.6.2
2026-03-02 00:15:22.101071 | controller | API Version:  4.6.2
2026-03-02 00:15:22.101083 | controller | Go Version:   go1.19.12
2026-03-02 00:15:22.101112 | controller | Built:        Mon Aug 28 19:38:31 2023
2026-03-02 00:15:22.101124 | controller | OS/Arch:      linux/amd64
2026-03-02 00:15:22.453951 | controller | ok: Runtime: 0:00:00.256636
2026-03-02 00:15:22.467863 | 
2026-03-02 00:15:22.468045 | TASK [ensure-podman : Print podman version installed]
2026-03-02 00:15:22.506820 | Podman version: Client:       Podman Engine
2026-03-02 00:15:22.507086 | Version:      4.6.2
2026-03-02 00:15:22.507147 | API Version:  4.6.2
2026-03-02 00:15:22.507192 | Go Version:   go1.19.12
2026-03-02 00:15:22.507231 | Built:        Mon Aug 28 19:38:31 2023
2026-03-02 00:15:22.507272 | OS/Arch:      linux/amd64
2026-03-02 00:15:22.519974 | 
2026-03-02 00:15:22.520152 | TASK [ensure-podman : Validate podman engine]
2026-03-02 00:15:23.071144 | controller | skipping: Conditional result was False
2026-03-02 00:15:23.085874 | 
2026-03-02 00:15:23.086085 | TASK [ensure-podman : Set up docker compatability socket]
2026-03-02 00:15:23.113963 | controller | skipping: Conditional result was False
2026-03-02 00:15:23.129416 | 
2026-03-02 00:15:23.129523 | TASK [Ensure python3.8 is present]
2026-03-02 00:15:23.154777 | controller | skipping: Conditional result was False
2026-03-02 00:15:23.162980 | 
2026-03-02 00:15:23.163073 | TASK [Run our-ensure-python role (Fedora only for now)]
2026-03-02 00:15:23.185278 | controller | ok
2026-03-02 00:15:23.210700 | 
2026-03-02 00:15:23.210834 | TASK [our-ensure-python : Install the right Python version (rpm)]
2026-03-02 00:15:24.687790 | controller | ok: Nothing to do
2026-03-02 00:15:24.701147 | 
2026-03-02 00:15:24.701347 | TASK [our-ensure-python : Also install python3-devel]
2026-03-02 00:15:34.299817 | controller | changed
2026-03-02 00:15:34.317652 | 
2026-03-02 00:15:34.317857 | TASK [Run ensure-virtualenv role]
2026-03-02 00:15:34.340816 | controller | ok
2026-03-02 00:15:34.374740 | 
2026-03-02 00:15:34.374892 | TASK [ensure-virtualenv : Check if virtualenv is installed]
2026-03-02 00:15:34.634857 | controller | /usr/bin/virtualenv
2026-03-02 00:15:34.939231 | controller | ok: Runtime: 0:00:00.004590
2026-03-02 00:15:34.953997 | 
2026-03-02 00:15:34.954182 | LOOP [ensure-virtualenv : Install virtualenv package]
2026-03-02 00:15:34.982328 | controller | skipping: Conditional result was False
2026-03-02 00:15:34.982717 | controller | ok: All items complete
2026-03-02 00:15:34.982771 | 
2026-03-02 00:15:35.020494 | 
2026-03-02 00:15:35.020739 | TASK [Find the full path of the Python interpreter]
2026-03-02 00:15:35.261336 | controller | /usr/bin/python3
2026-03-02 00:15:35.575153 | controller | ok
2026-03-02 00:15:35.588703 | 
2026-03-02 00:15:35.588878 | TASK [Create virtualenv for ansible-test with a specific interpreter]
2026-03-02 00:15:36.662956 | controller | created virtual environment CPython3.11.0.final.0-64 in 528ms
2026-03-02 00:15:36.686547 | controller |   creator CPython3Posix(dest=/home/zuul/venv, clear=False, no_vcs_ignore=False, global=False)
2026-03-02 00:15:36.686586 | 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-03-02 00:15:36.686602 | controller |     added seed packages: pip==22.2.2, setuptools==62.6.0, wheel==0.37.1
2026-03-02 00:15:36.686619 | controller |   activators BashActivator,CShellActivator,FishActivator,NushellActivator,PowerShellActivator,PythonActivator
2026-03-02 00:15:37.151083 | controller | changed
2026-03-02 00:15:37.175182 | 
2026-03-02 00:15:37.175428 | TASK [Set selinux package]
2026-03-02 00:15:37.213142 | controller | ok
2026-03-02 00:15:37.221081 | 
2026-03-02 00:15:37.221173 | TASK [Set selinux package (Fedora)]
2026-03-02 00:15:37.291741 | controller | ok
2026-03-02 00:15:37.303103 | 
2026-03-02 00:15:37.303191 | TASK [Install selinux into virtualenv]
2026-03-02 00:15:38.602148 | controller | Collecting selinux-please-lie-to-me
2026-03-02 00:15:38.772057 | controller |   Downloading selinux_please_lie_to_me-1.0.1-py2.py3-none-any.whl (3.8 kB)
2026-03-02 00:15:39.084617 | controller | Collecting setuptools<50.0.0
2026-03-02 00:15:39.129635 | controller |   Downloading setuptools-49.6.0-py3-none-any.whl (803 kB)
2026-03-02 00:15:39.343814 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 803.3/803.3 kB 3.9 MB/s eta 0:00:00
2026-03-02 00:15:39.432367 | controller | Installing collected packages: setuptools, selinux-please-lie-to-me
2026-03-02 00:15:39.432554 | controller |   Attempting uninstall: setuptools
2026-03-02 00:15:39.434919 | controller |     Found existing installation: setuptools 62.6.0
2026-03-02 00:15:39.499163 | controller |     Uninstalling setuptools-62.6.0:
2026-03-02 00:15:39.512084 | controller |       Successfully uninstalled setuptools-62.6.0
2026-03-02 00:15:39.855706 | controller | Successfully installed selinux-please-lie-to-me-1.0.1 setuptools-49.6.0
2026-03-02 00:15:39.968468 | controller |
2026-03-02 00:15:40.050368 | controller | [notice] A new release of pip available: 22.2.2 -> 26.0.1
2026-03-02 00:15:40.050414 | controller | [notice] To update, run: python -m pip install --upgrade pip
2026-03-02 00:15:40.364105 | controller | ok: Runtime: 0:00:02.493132
2026-03-02 00:15:40.378923 | 
2026-03-02 00:15:40.379125 | TASK [Install pytest-forked into virtualenv]
2026-03-02 00:15:41.011570 | controller | Collecting pytest-forked
2026-03-02 00:15:41.167189 | controller |   Downloading pytest_forked-1.6.0-py3-none-any.whl (4.9 kB)
2026-03-02 00:15:41.205669 | controller | Collecting py
2026-03-02 00:15:41.251556 | controller |   Downloading py-1.11.0-py2.py3-none-any.whl (98 kB)
2026-03-02 00:15:41.322759 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 98.7/98.7 kB 1.5 MB/s eta 0:00:00
2026-03-02 00:15:41.423201 | controller | Collecting pytest>=3.10
2026-03-02 00:15:41.467615 | controller |   Downloading pytest-9.0.2-py3-none-any.whl (374 kB)
2026-03-02 00:15:41.553030 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 374.8/374.8 kB 4.4 MB/s eta 0:00:00
2026-03-02 00:15:41.639680 | controller | Collecting iniconfig>=1.0.1
2026-03-02 00:15:41.683923 | controller |   Downloading iniconfig-2.3.0-py3-none-any.whl (7.5 kB)
2026-03-02 00:15:41.788346 | controller | Collecting packaging>=22
2026-03-02 00:15:41.832536 | controller |   Downloading packaging-26.0-py3-none-any.whl (74 kB)
2026-03-02 00:15:41.840127 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 74.4/74.4 kB 11.5 MB/s eta 0:00:00
2026-03-02 00:15:41.867680 | controller | Collecting pluggy<2,>=1.5
2026-03-02 00:15:41.914988 | controller |   Downloading pluggy-1.6.0-py3-none-any.whl (20 kB)
2026-03-02 00:15:41.955774 | controller | Collecting pygments>=2.7.2
2026-03-02 00:15:42.000050 | controller |   Downloading pygments-2.19.2-py3-none-any.whl (1.2 MB)
2026-03-02 00:15:42.097877 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 1.2/1.2 MB 12.7 MB/s eta 0:00:00
2026-03-02 00:15:42.164119 | controller | Installing collected packages: pygments, py, pluggy, packaging, iniconfig, pytest, pytest-forked
2026-03-02 00:15:43.193943 | 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-03-02 00:15:43.202400 | controller |
2026-03-02 00:15:43.269480 | controller | [notice] A new release of pip available: 22.2.2 -> 26.0.1
2026-03-02 00:15:43.269522 | controller | [notice] To update, run: python -m pip install --upgrade pip
2026-03-02 00:15:43.442385 | controller | ok: Runtime: 0:00:02.634391
2026-03-02 00:15:43.452306 | 
2026-03-02 00:15:43.452457 | TASK [Update pip]
2026-03-02 00:15:43.962532 | controller | Requirement already satisfied: pip in ./venv/lib/python3.11/site-packages (22.2.2)
2026-03-02 00:15:44.092201 | controller | Collecting pip
2026-03-02 00:15:44.247589 | controller |   Downloading pip-26.0.1-py3-none-any.whl (1.8 MB)
2026-03-02 00:15:44.541638 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 1.8/1.8 MB 6.2 MB/s eta 0:00:00
2026-03-02 00:15:44.607414 | controller | Installing collected packages: pip
2026-03-02 00:15:44.607581 | controller |   Attempting uninstall: pip
2026-03-02 00:15:44.609659 | controller |     Found existing installation: pip 22.2.2
2026-03-02 00:15:44.749974 | controller |     Uninstalling pip-22.2.2:
2026-03-02 00:15:44.766094 | controller |       Successfully uninstalled pip-22.2.2
2026-03-02 00:15:45.686658 | controller | Successfully installed pip-26.0.1
2026-03-02 00:15:46.006605 | controller | ok: Runtime: 0:00:02.099690
2026-03-02 00:15:46.020520 | 
2026-03-02 00:15:46.020746 | TASK [Ensure pip temp directory exists in /var/tmp]
2026-03-02 00:15:46.241487 | controller | changed
2026-03-02 00:15:46.249006 | 
2026-03-02 00:15:46.249107 | TASK [Install ansible into virtualenv]
2026-03-02 00:15:46.745712 | controller | Processing ./src/github.com/ansible/ansible
2026-03-02 00:15:46.748239 | controller |   Installing build dependencies: started
2026-03-02 00:20:48.678283 | controller | ERROR
2026-03-02 00:20:48.678431 | controller | {
2026-03-02 00:20:48.678462 | controller |   "msg": "Data could not be sent to remote host \"162.253.55.110\". Make sure this host can be reached over ssh: ssh: connect to host 162.253.55.110 port 22: Connection timed out\r\n",
2026-03-02 00:20:48.678486 | controller |   "unreachable": true
2026-03-02 00:20:48.678504 | controller | }
unreachable
2026-03-02 00:20:48.680312 | 
2026-03-02 00:20:48.680378 | PLAY RECAP
2026-03-02 00:20:48.680425 | controller | ok: 18 changed: 10 unreachable: 1 failed: 0 skipped: 4 rescued: 0 ignored: 0
2026-03-02 00:20:48.680456 | 
2026-03-02 00:20:48.820740 | PRE-RUN END RESULT_UNREACHABLE: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master]
2026-03-02 00:20:48.822095 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master]
2026-03-02 00:20:49.427071 | 
2026-03-02 00:20:49.427216 | PLAY [all]
2026-03-02 00:20:49.450926 | 
2026-03-02 00:20:49.451065 | TASK [fetch-subunit-output : Find stestr or testr executable]
2026-03-02 00:20:49.972652 | controller | changed: non-zero return code
2026-03-02 00:20:49.979792 | 
2026-03-02 00:20:49.979942 | TASK [fetch-subunit-output : Get the list of directories with subunit files]
2026-03-02 00:20:50.004597 | controller | skipping: Conditional result was False
2026-03-02 00:20:50.012031 | 
2026-03-02 00:20:50.012185 | LOOP [fetch-subunit-output : Find any inflight partial subunit files]
2026-03-02 00:20:50.043343 | 
2026-03-02 00:20:50.043512 | LOOP [fetch-subunit-output : Copy any inflight subunit files]
2026-03-02 00:20:50.074006 | 
2026-03-02 00:20:50.074162 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream]
2026-03-02 00:20:50.098219 | controller | skipping: Conditional result was False
2026-03-02 00:20:50.106226 | 
2026-03-02 00:20:50.106339 | LOOP [fetch-subunit-output : Generate subunit file]
2026-03-02 00:20:50.141448 | 
2026-03-02 00:20:50.141752 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory]
2026-03-02 00:20:50.166902 | controller | skipping: Conditional result was False
2026-03-02 00:20:50.173710 | 
2026-03-02 00:20:50.173832 | TASK [fetch-subunit-output : Remove the temporary file]
2026-03-02 00:20:50.187511 | controller | skipping: Conditional result was False
2026-03-02 00:20:50.194802 | 
2026-03-02 00:20:50.194923 | TASK [fetch-subunit-output : Process and fetch subunit results]
2026-03-02 00:20:50.209054 | controller | skipping: Conditional result was False
2026-03-02 00:20:50.247650 | 
2026-03-02 00:20:50.247838 | PLAY RECAP
2026-03-02 00:20:50.247893 | controller | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0
2026-03-02 00:20:50.247920 | 
2026-03-02 00:20:50.370612 | POST-RUN END RESULT_UNREACHABLE: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master]
2026-03-02 00:20:50.371529 | POST-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/post.yaml@master]
2026-03-02 00:20:51.020430 | 
2026-03-02 00:20:51.020586 | PLAY [all:!appliance*]
2026-03-02 00:20:51.054929 | 
2026-03-02 00:20:51.055086 | TASK [unregister the node]
2026-03-02 00:20:51.586573 | controller | skipping: Conditional result was False
2026-03-02 00:20:51.596884 | 
2026-03-02 00:20:51.597173 | TASK [include_role : fetch-output]
2026-03-02 00:20:51.633411 | controller | ok
2026-03-02 00:20:51.665919 | 
2026-03-02 00:20:51.666103 | TASK [fetch-output : Set log path for multiple nodes]
2026-03-02 00:20:51.712795 | controller | skipping: Conditional result was False
2026-03-02 00:20:51.723221 | 
2026-03-02 00:20:51.723345 | TASK [fetch-output : Set log path for single node]
2026-03-02 00:20:51.764262 | controller | ok
2026-03-02 00:20:51.770252 | 
2026-03-02 00:20:51.770348 | LOOP [fetch-output : Ensure local output dirs]
2026-03-02 00:20:52.263147 | controller -> localhost | ok: "/var/lib/zuul/builds/59983e903d4747cc852e30f86a912552/work/logs"
2026-03-02 00:20:52.594103 | controller -> localhost | changed: "/var/lib/zuul/builds/59983e903d4747cc852e30f86a912552/work/artifacts"
2026-03-02 00:20:52.838563 | controller -> localhost | changed: "/var/lib/zuul/builds/59983e903d4747cc852e30f86a912552/work/docs"
2026-03-02 00:20:52.855410 | 
2026-03-02 00:20:52.855528 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-03-02 00:20:53.521511 | controller | changed:
2026-03-02 00:20:53.521724 | controller | .d..t...... ./
2026-03-02 00:20:53.521754 | controller | cd+++++++++ controller/
2026-03-02 00:20:53.521789 | controller | changed: All items complete
2026-03-02 00:20:53.521819 | 
2026-03-02 00:20:54.062516 | controller | changed: .d..t...... ./
2026-03-02 00:20:54.602852 | controller | changed: .d..t...... ./
2026-03-02 00:20:54.624150 | 
2026-03-02 00:20:54.624289 | TASK [include_role : fetch-output-openshift]
2026-03-02 00:20:54.649878 | controller | skipping: Conditional result was False
2026-03-02 00:20:54.657781 | 
2026-03-02 00:20:54.657884 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-03-02 00:20:54.693453 | controller | skipping: Conditional result was False
2026-03-02 00:20:54.706560 | controller | skipping: Conditional result was False
2026-03-02 00:20:54.772072 | 
2026-03-02 00:20:54.772252 | PLAY [localhost]
2026-03-02 00:20:54.800955 | 
2026-03-02 00:20:54.801138 | TASK [Run Zuul manifest role]
2026-03-02 00:20:54.827030 | localhost | ok
2026-03-02 00:20:54.851491 | 
2026-03-02 00:20:54.851720 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-03-02 00:20:55.283092 | localhost | changed
2026-03-02 00:20:55.294171 | 
2026-03-02 00:20:55.294322 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-03-02 00:20:55.327725 | localhost | ok
2026-03-02 00:20:55.341872 | 
2026-03-02 00:20:55.342002 | TASK [Set zuul-log-path fact]
2026-03-02 00:20:55.363536 | localhost | ok
2026-03-02 00:20:55.381184 | 
2026-03-02 00:20:55.381301 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-03-02 00:20:55.415114 | localhost | ok
2026-03-02 00:20:55.423754 | 
2026-03-02 00:20:55.423835 | LOOP [Set zuul_log_targets (Swift with random Rackspace region)]
2026-03-02 00:20:55.471765 | localhost | ok:
2026-03-02 00:20:55.472074 | localhost | {
2026-03-02 00:20:55.472141 | localhost |   "auth": {
2026-03-02 00:20:55.472189 | localhost |     "password": "IonWCwcuOFib&&<}wp^:[2G/",
2026-03-02 00:20:55.472232 | localhost |     "username": "9LF91O0RK7JU"
2026-03-02 00:20:55.472273 | localhost |   },
2026-03-02 00:20:55.472316 | localhost |   "profile": "rackspace",
2026-03-02 00:20:55.472357 | localhost |   "project_name": "1251317",
2026-03-02 00:20:55.472397 | localhost |   "region_name": "DFW"
2026-03-02 00:20:55.472438 | localhost | }
2026-03-02 00:20:55.472490 | 
2026-03-02 00:20:55.486539 | 
2026-03-02 00:20:55.486716 | TASK [Run upload-logs-failover role (S3 then Swift)]
2026-03-02 00:20:55.506716 | localhost | Output suppressed because no_log was given
2026-03-02 00:20:55.521345 | 
2026-03-02 00:20:55.521464 | TASK [upload-logs-failover : Initialize control vars]
2026-03-02 00:20:55.552378 | localhost | ok
2026-03-02 00:20:55.557559 | 
2026-03-02 00:20:55.557695 | LOOP [upload-logs-failover : Perform log upload]
2026-03-02 00:20:55.634072 | localhost | Output suppressed because no_log was given
2026-03-02 00:20:55.642827 | localhost | included: /var/lib/zuul/builds/59983e903d4747cc852e30f86a912552/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/upload-logs-failover/tasks/upload.yaml
2026-03-02 00:20:55.650110 | 
2026-03-02 00:20:55.650205 | LOOP [upload-logs-failover : Unpack vars for swift upload]
2026-03-02 00:20:55.767002 | localhost | Output suppressed because no_log was given
2026-03-02 00:20:55.775791 | 
2026-03-02 00:20:55.775898 | TASK [Upload logs to swift]
2026-03-02 00:20:55.799524 | localhost | ok
2026-03-02 00:20:55.829904 | 
2026-03-02 00:20:55.829990 | TASK [Set zuul-log-path fact]
2026-03-02 00:20:55.855257 | localhost | skipping: Conditional result was False
2026-03-02 00:20:55.865099 | 
2026-03-02 00:20:55.865205 | TASK [upload-logs-swift : Ensure logs are readable before uploading]
2026-03-02 00:20:56.295299 | localhost -> localhost | ok: Runtime: 0:00:00.008646
2026-03-02 00:20:56.308021 | 
2026-03-02 00:20:56.308175 | TASK [upload-logs-swift : Upload logs to swift]