2026-02-26 00:21:31.592531 | Job console starting... 2026-02-26 00:21:31.604545 | Updating repositories 2026-02-26 00:21:31.733151 | Preparing job workspace 2026-02-26 00:21:35.696679 | Running Ansible setup... 2026-02-26 00:21:40.861607 | PRE-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master] 2026-02-26 00:21:41.539796 | 2026-02-26 00:21:41.539960 | PLAY [localhost] 2026-02-26 00:21:41.555678 | 2026-02-26 00:21:41.555836 | TASK [Gathering Facts] 2026-02-26 00:21:42.513914 | localhost | ok 2026-02-26 00:21:42.541614 | 2026-02-26 00:21:42.541825 | TASK [Setup log path fact] 2026-02-26 00:21:42.561765 | localhost | ok 2026-02-26 00:21:42.588569 | 2026-02-26 00:21:42.588743 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-02-26 00:21:42.620844 | localhost | ok 2026-02-26 00:21:42.630854 | 2026-02-26 00:21:42.630969 | TASK [emit-job-header : Print job information] 2026-02-26 00:21:42.682284 | # Job Information 2026-02-26 00:21:42.682488 | Ansible Version: 2.15.12 2026-02-26 00:21:42.682534 | Job: ansible-test-sanity-docker-stable-2.12 2026-02-26 00:21:42.682556 | Pipeline: periodic 2026-02-26 00:21:42.682574 | Executor: ze03.softwarefactory-project.io 2026-02-26 00:21:42.682592 | Triggered by: https://github.com/ansible-collections/ansible.network 2026-02-26 00:21:42.682612 | Log URL (when completed): https://ansible.softwarefactory-project.io/logs/e35/ansible/e35e775a973e4f1ba24bc376c5cfcf19/ 2026-02-26 00:21:42.682630 | Event ID: 2adb30751d3546bcab5e20cd6f839642 2026-02-26 00:21:42.687534 | 2026-02-26 00:21:42.687694 | LOOP [emit-job-header : Print node information] 2026-02-26 00:21:42.824405 | localhost | ok: 2026-02-26 00:21:42.824639 | localhost | # Node Information 2026-02-26 00:21:42.824686 | localhost | Inventory Hostname: controller 2026-02-26 00:21:42.824718 | localhost | Hostname: np0005630874 2026-02-26 00:21:42.824775 | localhost | Username: zuul 2026-02-26 00:21:42.824808 | localhost | Distro: Fedora 37 2026-02-26 00:21:42.824845 | localhost | Provider: ansible-vexxhost-ca-ymq-1 2026-02-26 00:21:42.824881 | localhost | Region: ca-ymq-1 2026-02-26 00:21:42.824918 | localhost | Label: ansible-fedora-37-1vcpu 2026-02-26 00:21:42.824947 | localhost | Product Name: OpenStack Nova 2026-02-26 00:21:42.824983 | localhost | Interface IP: 199.204.45.6 2026-02-26 00:21:42.839281 | 2026-02-26 00:21:42.839423 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-02-26 00:21:43.285187 | localhost -> localhost | changed 2026-02-26 00:21:43.294282 | 2026-02-26 00:21:43.294468 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-02-26 00:21:44.303353 | localhost -> localhost | changed 2026-02-26 00:21:44.350828 | 2026-02-26 00:21:44.350924 | PLAY [all:!appliance*] 2026-02-26 00:21:44.395773 | 2026-02-26 00:21:44.396006 | TASK [include_role : start-zuul-console] 2026-02-26 00:21:44.415573 | controller | ok 2026-02-26 00:21:44.432408 | 2026-02-26 00:21:44.432527 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-02-26 00:21:44.868254 | controller | ok 2026-02-26 00:21:44.887610 | 2026-02-26 00:21:44.887765 | TASK [use-our-mirror : Retrieve the IP address] 2026-02-26 00:21:46.451491 | controller | ok: Status code was 404 and not [200]: HTTP Error 404: Not Found 2026-02-26 00:21:46.465389 | 2026-02-26 00:21:46.465586 | TASK [use-our-mirror : Ensure the registry service is running on the mirror] 2026-02-26 00:21:47.014669 | controller | skipping: Conditional result was False 2026-02-26 00:21:47.046202 | 2026-02-26 00:21:47.046356 | TASK [use-our-mirror : Write the IP in /etc/hosts] 2026-02-26 00:21:47.072190 | controller | skipping: Conditional result was False 2026-02-26 00:21:47.081064 | 2026-02-26 00:21:47.081381 | TASK [use-our-mirror : Create the podman configuration directory] 2026-02-26 00:21:47.111788 | controller | skipping: Conditional result was False 2026-02-26 00:21:47.126705 | 2026-02-26 00:21:47.126879 | TASK [use-our-mirror : Copy the podman configuration] 2026-02-26 00:21:47.154300 | controller | skipping: Conditional result was False 2026-02-26 00:21:47.163394 | 2026-02-26 00:21:47.164000 | TASK [use-our-mirror : Copy the Yum configuration (main)] 2026-02-26 00:21:47.191192 | controller | skipping: Conditional result was False 2026-02-26 00:21:47.201452 | 2026-02-26 00:21:47.201607 | TASK [use-our-mirror : Copy the Yum configuration (update)] 2026-02-26 00:21:47.228753 | controller | skipping: Conditional result was False 2026-02-26 00:21:47.247301 | 2026-02-26 00:21:47.247458 | TASK [Disable Fedora Modular] 2026-02-26 00:21:47.497889 | controller | changed 2026-02-26 00:21:47.506068 | 2026-02-26 00:21:47.506189 | TASK [Enable EPEL] 2026-02-26 00:21:47.522898 | controller | skipping: Conditional result was False 2026-02-26 00:21:47.532482 | 2026-02-26 00:21:47.532618 | TASK [Register the RHEL node] 2026-02-26 00:21:48.099108 | 2026-02-26 00:21:48.099267 | TASK [Show the subscription-manager status] 2026-02-26 00:21:48.691568 | controller | skipping: Conditional result was False 2026-02-26 00:21:48.701072 | 2026-02-26 00:21:48.701225 | TASK [Enable EPEL on RHEL] 2026-02-26 00:21:49.242844 | controller | skipping: Conditional result was False 2026-02-26 00:21:49.249236 | 2026-02-26 00:21:49.249325 | TASK [Install git and tox] 2026-02-26 00:23:10.224834 | controller | changed 2026-02-26 00:23:10.240156 | 2026-02-26 00:23:10.240322 | TASK [include_role : prepare-workspace] 2026-02-26 00:23:10.281036 | controller | ok 2026-02-26 00:23:10.320628 | 2026-02-26 00:23:10.320801 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-02-26 00:23:10.557050 | controller | ok 2026-02-26 00:23:10.563891 | 2026-02-26 00:23:10.563962 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-02-26 00:23:24.851797 | controller | Output suppressed because no_log was given 2026-02-26 00:23:24.868455 | 2026-02-26 00:23:24.868613 | TASK [include_role : prepare-workspace-openshift] 2026-02-26 00:23:24.894336 | controller | skipping: Conditional result was False 2026-02-26 00:23:24.921341 | 2026-02-26 00:23:24.921461 | PLAY [all:!appliance] 2026-02-26 00:23:25.001651 | 2026-02-26 00:23:25.001880 | TASK [Run add-build-sshkey role (RSA)] 2026-02-26 00:23:25.036134 | controller | ok 2026-02-26 00:23:25.056101 | 2026-02-26 00:23:25.056309 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-02-26 00:23:25.333630 | controller -> localhost | ok 2026-02-26 00:23:25.341556 | 2026-02-26 00:23:25.341669 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-02-26 00:23:25.375908 | controller | ok 2026-02-26 00:23:25.395704 | controller | included: /var/lib/zuul/builds/e35e775a973e4f1ba24bc376c5cfcf19/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-02-26 00:23:25.405107 | 2026-02-26 00:23:25.405237 | TASK [add-build-sshkey : Create Temp SSH key] 2026-02-26 00:23:25.962919 | controller -> localhost | Generating public/private rsa key pair. 2026-02-26 00:23:25.963355 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/e35e775a973e4f1ba24bc376c5cfcf19/work/e35e775a973e4f1ba24bc376c5cfcf19_id_rsa. 2026-02-26 00:23:25.963397 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/e35e775a973e4f1ba24bc376c5cfcf19/work/e35e775a973e4f1ba24bc376c5cfcf19_id_rsa.pub. 2026-02-26 00:23:25.963428 | controller -> localhost | The key fingerprint is: 2026-02-26 00:23:25.963456 | controller -> localhost | SHA256:jnJuqvNS979VTXYQbnc2dU5harYrE+8iyNvSfrPopJg zuul-build-sshkey 2026-02-26 00:23:25.963482 | controller -> localhost | The key's randomart image is: 2026-02-26 00:23:25.963509 | controller -> localhost | +---[RSA 2048]----+ 2026-02-26 00:23:25.963534 | controller -> localhost | | o+=| 2026-02-26 00:23:25.963560 | controller -> localhost | | .o+o| 2026-02-26 00:23:25.963587 | controller -> localhost | | +o.O| 2026-02-26 00:23:25.963613 | controller -> localhost | | o..*+| 2026-02-26 00:23:25.963637 | controller -> localhost | | S . .. .| 2026-02-26 00:23:25.963674 | controller -> localhost | | . .o o.. | 2026-02-26 00:23:25.963712 | controller -> localhost | | ...+.+. o.o | 2026-02-26 00:23:25.963971 | controller -> localhost | | o +++=o.=+ | 2026-02-26 00:23:25.964020 | controller -> localhost | | .=oE.o=*=o+. | 2026-02-26 00:23:25.964053 | controller -> localhost | +----[SHA256]-----+ 2026-02-26 00:23:25.964115 | controller -> localhost | ok: Runtime: 0:00:00.136054 2026-02-26 00:23:25.978537 | 2026-02-26 00:23:25.978783 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-02-26 00:23:26.012469 | controller | ok 2026-02-26 00:23:26.027766 | controller | included: /var/lib/zuul/builds/e35e775a973e4f1ba24bc376c5cfcf19/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-02-26 00:23:26.042620 | 2026-02-26 00:23:26.042774 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-02-26 00:23:26.068257 | controller | skipping: Conditional result was False 2026-02-26 00:23:26.075607 | 2026-02-26 00:23:26.075689 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-02-26 00:23:26.595973 | controller | changed 2026-02-26 00:23:26.606157 | 2026-02-26 00:23:26.606316 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-02-26 00:23:26.825461 | controller | ok 2026-02-26 00:23:26.833703 | 2026-02-26 00:23:26.833850 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-02-26 00:23:27.428448 | controller | changed 2026-02-26 00:23:27.437032 | 2026-02-26 00:23:27.437146 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-02-26 00:23:28.072767 | controller | changed 2026-02-26 00:23:28.082545 | 2026-02-26 00:23:28.082641 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-02-26 00:23:28.108518 | controller | skipping: Conditional result was False 2026-02-26 00:23:28.116701 | 2026-02-26 00:23:28.116841 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-02-26 00:23:28.493439 | controller -> localhost | changed 2026-02-26 00:23:28.515255 | 2026-02-26 00:23:28.515406 | TASK [add-build-sshkey : Add back temp key] 2026-02-26 00:23:28.818721 | controller -> localhost | Identity added: /var/lib/zuul/builds/e35e775a973e4f1ba24bc376c5cfcf19/work/e35e775a973e4f1ba24bc376c5cfcf19_id_rsa (zuul-build-sshkey) 2026-02-26 00:23:28.818951 | controller -> localhost | ok: Runtime: 0:00:00.010530 2026-02-26 00:23:28.828517 | 2026-02-26 00:23:28.828620 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-02-26 00:23:29.196196 | controller | ok 2026-02-26 00:23:29.209229 | 2026-02-26 00:23:29.209682 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-02-26 00:23:29.238321 | controller | skipping: Conditional result was False 2026-02-26 00:23:29.261501 | 2026-02-26 00:23:29.261651 | TASK [Run add-build-sshkey role (ECDSA)] 2026-02-26 00:23:29.300303 | controller | ok 2026-02-26 00:23:29.336391 | 2026-02-26 00:23:29.336806 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-02-26 00:23:29.631330 | controller -> localhost | ok 2026-02-26 00:23:29.646062 | 2026-02-26 00:23:29.646251 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-02-26 00:23:29.684628 | controller | ok 2026-02-26 00:23:29.700830 | controller | included: /var/lib/zuul/builds/e35e775a973e4f1ba24bc376c5cfcf19/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-02-26 00:23:29.710228 | 2026-02-26 00:23:29.710349 | TASK [add-build-sshkey : Create Temp SSH key] 2026-02-26 00:23:30.044614 | controller -> localhost | Generating public/private ecdsa key pair. 2026-02-26 00:23:30.044899 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/e35e775a973e4f1ba24bc376c5cfcf19/work/e35e775a973e4f1ba24bc376c5cfcf19_id_ecdsa. 2026-02-26 00:23:30.044941 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/e35e775a973e4f1ba24bc376c5cfcf19/work/e35e775a973e4f1ba24bc376c5cfcf19_id_ecdsa.pub. 2026-02-26 00:23:30.044982 | controller -> localhost | The key fingerprint is: 2026-02-26 00:23:30.045013 | controller -> localhost | SHA256:0ykEwC8lhoPmB4Oi1A1G32D4ovaii+LpDHEcF9NN1JY zuul-build-sshkey 2026-02-26 00:23:30.045040 | controller -> localhost | The key's randomart image is: 2026-02-26 00:23:30.045066 | controller -> localhost | +---[ECDSA 521]---+ 2026-02-26 00:23:30.045091 | controller -> localhost | |..o*B=.+o. . | 2026-02-26 00:23:30.045116 | controller -> localhost | |+=+o*+=.. E | 2026-02-26 00:23:30.045141 | controller -> localhost | |* +oo= ... | 2026-02-26 00:23:30.045166 | controller -> localhost | |.o =.... . . | 2026-02-26 00:23:30.045191 | controller -> localhost | |. = .. S o | 2026-02-26 00:23:30.045215 | controller -> localhost | | = o | 2026-02-26 00:23:30.045241 | controller -> localhost | |o . | 2026-02-26 00:23:30.045265 | controller -> localhost | |=... | 2026-02-26 00:23:30.045290 | controller -> localhost | |O*. | 2026-02-26 00:23:30.045315 | controller -> localhost | +----[SHA256]-----+ 2026-02-26 00:23:30.045371 | controller -> localhost | ok: Runtime: 0:00:00.013348 2026-02-26 00:23:30.060107 | 2026-02-26 00:23:30.060245 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-02-26 00:23:30.099031 | controller | ok 2026-02-26 00:23:30.108476 | controller | included: /var/lib/zuul/builds/e35e775a973e4f1ba24bc376c5cfcf19/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-02-26 00:23:30.120628 | 2026-02-26 00:23:30.120721 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-02-26 00:23:30.147336 | controller | skipping: Conditional result was False 2026-02-26 00:23:30.156419 | 2026-02-26 00:23:30.156508 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-02-26 00:23:30.469171 | controller | changed 2026-02-26 00:23:30.486228 | 2026-02-26 00:23:30.486376 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-02-26 00:23:30.718987 | controller | ok 2026-02-26 00:23:30.729790 | 2026-02-26 00:23:30.729902 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-02-26 00:23:31.387058 | controller | changed 2026-02-26 00:23:31.395149 | 2026-02-26 00:23:31.395271 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-02-26 00:23:32.020884 | controller | changed 2026-02-26 00:23:32.029379 | 2026-02-26 00:23:32.029498 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-02-26 00:23:32.055284 | controller | skipping: Conditional result was False 2026-02-26 00:23:32.066690 | 2026-02-26 00:23:32.066861 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-02-26 00:23:32.350741 | controller -> localhost | changed 2026-02-26 00:23:32.375595 | 2026-02-26 00:23:32.375885 | TASK [add-build-sshkey : Add back temp key] 2026-02-26 00:23:32.679967 | controller -> localhost | Identity added: /var/lib/zuul/builds/e35e775a973e4f1ba24bc376c5cfcf19/work/e35e775a973e4f1ba24bc376c5cfcf19_id_ecdsa (zuul-build-sshkey) 2026-02-26 00:23:32.680294 | controller -> localhost | ok: Runtime: 0:00:00.009784 2026-02-26 00:23:32.689620 | 2026-02-26 00:23:32.689748 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-02-26 00:23:32.909269 | controller | ok 2026-02-26 00:23:32.917119 | 2026-02-26 00:23:32.917230 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-02-26 00:23:32.943768 | controller | skipping: Conditional result was False 2026-02-26 00:23:33.003575 | 2026-02-26 00:23:33.003709 | TASK [include_role : remove-zuul-sshkey] 2026-02-26 00:23:33.018419 | controller | skipping: Conditional result was False 2026-02-26 00:23:33.027666 | 2026-02-26 00:23:33.027818 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-02-26 00:28:47.853547 | controller | ok: "logs" 2026-02-26 00:28:47.854024 | controller | ok: All items complete 2026-02-26 00:28:47.854058 | 2026-02-26 00:28:48.063456 | controller | ok: "artifacts" 2026-02-26 00:28:48.265933 | controller | ok: "docs" 2026-02-26 00:28:48.286624 | 2026-02-26 00:28:48.286895 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-02-26 00:28:48.523454 | controller | changed: "logs" 2026-02-26 00:28:48.737036 | controller | changed: "artifacts" 2026-02-26 00:28:48.926930 | controller | changed: "docs" 2026-02-26 00:28:48.973993 | 2026-02-26 00:28:48.974107 | PLAY RECAP 2026-02-26 00:28:48.974182 | controller | ok: 30 changed: 16 unreachable: 0 failed: 0 skipped: 18 rescued: 0 ignored: 0 2026-02-26 00:28:48.974221 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-02-26 00:28:48.974240 | 2026-02-26 00:28:49.109152 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master] 2026-02-26 00:28:49.110181 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2026-02-26 00:28:49.739741 | 2026-02-26 00:28:49.739888 | PLAY [all] 2026-02-26 00:28:49.764826 | 2026-02-26 00:28:49.764975 | TASK [Install binary dependencies] 2026-02-26 00:28:49.818889 | controller | ok 2026-02-26 00:28:49.857741 | 2026-02-26 00:28:49.857960 | TASK [bindep : Include find tasks] 2026-02-26 00:28:49.891278 | controller | ok 2026-02-26 00:28:49.903319 | controller | included: /var/lib/zuul/builds/e35e775a973e4f1ba24bc376c5cfcf19/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find.yaml 2026-02-26 00:28:49.912568 | 2026-02-26 00:28:49.912662 | TASK [bindep : Look for bindep.txt] 2026-02-26 00:28:50.499405 | controller | ok 2026-02-26 00:28:50.513770 | 2026-02-26 00:28:50.513943 | TASK [bindep : Define bindep_file fact] 2026-02-26 00:28:50.534145 | controller | skipping: Conditional result was False 2026-02-26 00:28:50.542045 | 2026-02-26 00:28:50.542123 | TASK [bindep : Look for other-requirements.txt] 2026-02-26 00:28:50.784828 | controller | ok 2026-02-26 00:28:50.796825 | 2026-02-26 00:28:50.796968 | TASK [bindep : Define bindep_file fact] 2026-02-26 00:28:50.824184 | controller | skipping: Conditional result was False 2026-02-26 00:28:50.836793 | 2026-02-26 00:28:50.836926 | TASK [bindep : Look for bindep fallback file] 2026-02-26 00:28:50.863444 | controller | skipping: Conditional result was False 2026-02-26 00:28:50.870983 | 2026-02-26 00:28:50.871066 | TASK [bindep : Define bindep_file fact] 2026-02-26 00:28:50.895321 | controller | skipping: Conditional result was False 2026-02-26 00:28:50.901625 | 2026-02-26 00:28:50.901693 | TASK [bindep : Include bindep tasks] 2026-02-26 00:28:50.936055 | controller | skipping: Conditional result was False 2026-02-26 00:28:50.942440 | 2026-02-26 00:28:50.942504 | TASK [bindep : Include install tasks] 2026-02-26 00:28:50.966596 | controller | skipping: Conditional result was False 2026-02-26 00:28:50.973800 | 2026-02-26 00:28:50.973902 | LOOP [bindep : Include package tasks] 2026-02-26 00:28:51.040514 | 2026-02-26 00:28:51.040718 | TASK [Run test-setup role] 2026-02-26 00:28:51.063382 | controller | ok 2026-02-26 00:28:51.086314 | 2026-02-26 00:28:51.086444 | TASK [test-setup : Check if project's tools/test-setup.sh exists] 2026-02-26 00:28:51.290166 | controller | ok 2026-02-26 00:28:51.296454 | 2026-02-26 00:28:51.296534 | TASK [test-setup : Run tools/test-setup.sh] 2026-02-26 00:28:51.830116 | controller | skipping: Conditional result was False 2026-02-26 00:28:51.864204 | 2026-02-26 00:28:51.864308 | PLAY RECAP 2026-02-26 00:28:51.864359 | controller | ok: 4 changed: 0 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2026-02-26 00:28:51.864386 | 2026-02-26 00:28:51.967066 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2026-02-26 00:28:51.968105 | PRE-RUN START: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master] 2026-02-26 00:28:52.746935 | 2026-02-26 00:28:52.747141 | PLAY [controller] 2026-02-26 00:28:52.769530 | 2026-02-26 00:28:52.769712 | TASK [Create the /root directory] 2026-02-26 00:28:53.379930 | controller | ok 2026-02-26 00:28:53.389500 | 2026-02-26 00:28:53.389636 | TASK [Install glibc-langpack-en] 2026-02-26 00:28:57.313527 | controller | ok: Nothing to do 2026-02-26 00:28:57.320983 | 2026-02-26 00:28:57.321139 | TASK [Ensure controller directory exists] 2026-02-26 00:28:57.537318 | controller | changed 2026-02-26 00:28:57.543553 | 2026-02-26 00:28:57.543644 | TASK [Install container runtime] 2026-02-26 00:28:57.594530 | controller | ok 2026-02-26 00:28:57.631405 | 2026-02-26 00:28:57.631534 | LOOP [ensure-podman : Find distribution installation] 2026-02-26 00:28:57.655903 | controller | ok: "/var/lib/zuul/builds/e35e775a973e4f1ba24bc376c5cfcf19/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-podman/tasks/RedHat.yaml" 2026-02-26 00:28:57.664225 | controller | included: /var/lib/zuul/builds/e35e775a973e4f1ba24bc376c5cfcf19/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-podman/tasks/RedHat.yaml 2026-02-26 00:28:57.671783 | 2026-02-26 00:28:57.671874 | TASK [ensure-podman : Install podman (RedHat)] 2026-02-26 00:30:04.330939 | controller | changed 2026-02-26 00:30:04.338054 | 2026-02-26 00:30:04.338149 | TASK [ensure-podman : Fetch podman version] 2026-02-26 00:30:04.863875 | controller | Client: Podman Engine 2026-02-26 00:30:04.863971 | controller | Version: 4.6.2 2026-02-26 00:30:04.864015 | controller | API Version: 4.6.2 2026-02-26 00:30:04.864055 | controller | Go Version: go1.19.12 2026-02-26 00:30:04.864131 | controller | Built: Mon Aug 28 19:38:31 2023 2026-02-26 00:30:04.864176 | controller | OS/Arch: linux/amd64 2026-02-26 00:30:05.373521 | controller | ok: Runtime: 0:00:00.199483 2026-02-26 00:30:05.388637 | 2026-02-26 00:30:05.388892 | TASK [ensure-podman : Print podman version installed] 2026-02-26 00:30:05.430480 | Podman version: Client: Podman Engine 2026-02-26 00:30:05.430852 | Version: 4.6.2 2026-02-26 00:30:05.430932 | API Version: 4.6.2 2026-02-26 00:30:05.430978 | Go Version: go1.19.12 2026-02-26 00:30:05.431018 | Built: Mon Aug 28 19:38:31 2023 2026-02-26 00:30:05.431066 | OS/Arch: linux/amd64 2026-02-26 00:30:05.443887 | 2026-02-26 00:30:05.444055 | TASK [ensure-podman : Validate podman engine] 2026-02-26 00:30:06.407941 | controller | skipping: Conditional result was False 2026-02-26 00:30:06.416487 | 2026-02-26 00:30:06.416706 | TASK [ensure-podman : Set up docker compatability socket] 2026-02-26 00:30:06.442845 | controller | skipping: Conditional result was False 2026-02-26 00:30:06.459573 | 2026-02-26 00:30:06.459808 | TASK [Ensure python3.8 is present] 2026-02-26 00:30:06.479209 | controller | skipping: Conditional result was False 2026-02-26 00:30:06.489063 | 2026-02-26 00:30:06.489181 | TASK [Run our-ensure-python role (Fedora only for now)] 2026-02-26 00:30:06.510522 | controller | ok 2026-02-26 00:30:06.534183 | 2026-02-26 00:30:06.534485 | TASK [our-ensure-python : Install the right Python version (rpm)] 2026-02-26 00:30:07.978046 | controller | ok: Nothing to do 2026-02-26 00:30:07.984234 | 2026-02-26 00:30:07.984312 | TASK [our-ensure-python : Also install python3-devel] 2026-02-26 00:30:18.108674 | controller | changed 2026-02-26 00:30:18.134223 | 2026-02-26 00:30:18.134343 | TASK [Run ensure-virtualenv role] 2026-02-26 00:30:18.166354 | controller | ok 2026-02-26 00:30:18.201853 | 2026-02-26 00:30:18.202021 | TASK [ensure-virtualenv : Check if virtualenv is installed] 2026-02-26 00:30:18.444860 | controller | /usr/bin/virtualenv 2026-02-26 00:30:18.766944 | controller | ok: Runtime: 0:00:00.004539 2026-02-26 00:30:18.773386 | 2026-02-26 00:30:18.773484 | LOOP [ensure-virtualenv : Install virtualenv package] 2026-02-26 00:30:18.793951 | controller | skipping: Conditional result was False 2026-02-26 00:30:18.794214 | controller | ok: All items complete 2026-02-26 00:30:18.794243 | 2026-02-26 00:30:18.819106 | 2026-02-26 00:30:18.819326 | TASK [Find the full path of the Python interpreter] 2026-02-26 00:30:19.049614 | controller | /usr/bin/python3.10 2026-02-26 00:30:19.384459 | controller | ok 2026-02-26 00:30:19.398233 | 2026-02-26 00:30:19.398475 | TASK [Create virtualenv for ansible-test with a specific interpreter] 2026-02-26 00:30:20.332850 | controller | created virtual environment CPython3.10.13.final.0-64 in 595ms 2026-02-26 00:30:20.357135 | controller | creator CPython3Posix(dest=/home/zuul/venv, clear=False, no_vcs_ignore=False, global=False) 2026-02-26 00:30:20.357640 | 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-26 00:30:20.357882 | controller | added seed packages: pip==22.2.2, setuptools==62.6.0, wheel==0.37.1 2026-02-26 00:30:20.358115 | controller | activators BashActivator,CShellActivator,FishActivator,NushellActivator,PowerShellActivator,PythonActivator 2026-02-26 00:30:20.459234 | controller | changed 2026-02-26 00:30:20.466178 | 2026-02-26 00:30:20.466329 | TASK [Set selinux package] 2026-02-26 00:30:20.487679 | controller | ok 2026-02-26 00:30:20.494159 | 2026-02-26 00:30:20.494304 | TASK [Set selinux package (Fedora)] 2026-02-26 00:30:20.538647 | controller | ok 2026-02-26 00:30:20.549576 | 2026-02-26 00:30:20.549754 | TASK [Install selinux into virtualenv] 2026-02-26 00:30:21.744162 | controller | Collecting selinux-please-lie-to-me 2026-02-26 00:30:21.777865 | controller | Downloading selinux_please_lie_to_me-1.0.1-py2.py3-none-any.whl (3.8 kB) 2026-02-26 00:30:22.110293 | controller | Collecting setuptools<50.0.0 2026-02-26 00:30:22.113963 | controller | Downloading setuptools-49.6.0-py3-none-any.whl (803 kB) 2026-02-26 00:30:22.153679 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 803.3/803.3 kB 22.0 MB/s eta 0:00:00 2026-02-26 00:30:22.227866 | controller | Installing collected packages: setuptools, selinux-please-lie-to-me 2026-02-26 00:30:22.228040 | controller | Attempting uninstall: setuptools 2026-02-26 00:30:22.228710 | controller | Found existing installation: setuptools 62.6.0 2026-02-26 00:30:22.297851 | controller | Uninstalling setuptools-62.6.0: 2026-02-26 00:30:22.306394 | controller | Successfully uninstalled setuptools-62.6.0 2026-02-26 00:30:22.646287 | controller | Successfully installed selinux-please-lie-to-me-1.0.1 setuptools-49.6.0 2026-02-26 00:30:22.770431 | controller | 2026-02-26 00:30:22.846895 | controller | [notice] A new release of pip available: 22.2.2 -> 26.0.1 2026-02-26 00:30:22.846909 | controller | [notice] To update, run: /home/zuul/venv/bin/python -m pip install --upgrade pip 2026-02-26 00:30:23.099116 | controller | ok: Runtime: 0:00:02.083115 2026-02-26 00:30:23.105987 | 2026-02-26 00:30:23.106253 | TASK [Install pytest-forked into virtualenv] 2026-02-26 00:30:23.682634 | controller | Collecting pytest-forked 2026-02-26 00:30:23.717322 | controller | Downloading pytest_forked-1.6.0-py3-none-any.whl (4.9 kB) 2026-02-26 00:30:23.892574 | controller | Collecting pytest>=3.10 2026-02-26 00:30:23.895902 | controller | Downloading pytest-9.0.2-py3-none-any.whl (374 kB) 2026-02-26 00:30:23.923584 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 374.8/374.8 kB 14.7 MB/s eta 0:00:00 2026-02-26 00:30:24.000545 | controller | Collecting py 2026-02-26 00:30:24.005034 | controller | Downloading py-1.11.0-py2.py3-none-any.whl (98 kB) 2026-02-26 00:30:24.011573 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 98.7/98.7 kB 23.8 MB/s eta 0:00:00 2026-02-26 00:30:24.044974 | controller | Collecting iniconfig>=1.0.1 2026-02-26 00:30:24.048214 | controller | Downloading iniconfig-2.3.0-py3-none-any.whl (7.5 kB) 2026-02-26 00:30:24.091554 | controller | Collecting packaging>=22 2026-02-26 00:30:24.094849 | controller | Downloading packaging-26.0-py3-none-any.whl (74 kB) 2026-02-26 00:30:24.100295 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 74.4/74.4 kB 20.7 MB/s eta 0:00:00 2026-02-26 00:30:24.129485 | controller | Collecting exceptiongroup>=1 2026-02-26 00:30:24.133159 | controller | Downloading exceptiongroup-1.3.1-py3-none-any.whl (16 kB) 2026-02-26 00:30:24.183219 | controller | Collecting pygments>=2.7.2 2026-02-26 00:30:24.187029 | controller | Downloading pygments-2.19.2-py3-none-any.whl (1.2 MB) 2026-02-26 00:30:24.212551 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 1.2/1.2 MB 56.9 MB/s eta 0:00:00 2026-02-26 00:30:24.248166 | controller | Collecting pluggy<2,>=1.5 2026-02-26 00:30:24.251338 | controller | Downloading pluggy-1.6.0-py3-none-any.whl (20 kB) 2026-02-26 00:30:24.303947 | controller | Collecting tomli>=1 2026-02-26 00:30:24.307149 | controller | Downloading tomli-2.4.0-py3-none-any.whl (14 kB) 2026-02-26 00:30:24.360509 | controller | Collecting typing-extensions>=4.6.0 2026-02-26 00:30:24.364174 | controller | Downloading typing_extensions-4.15.0-py3-none-any.whl (44 kB) 2026-02-26 00:30:24.369946 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 44.6/44.6 kB 11.4 MB/s eta 0:00:00 2026-02-26 00:30:24.437393 | controller | Installing collected packages: typing-extensions, tomli, pygments, py, pluggy, packaging, iniconfig, exceptiongroup, pytest, pytest-forked 2026-02-26 00:30:25.524450 | controller | Successfully installed exceptiongroup-1.3.1 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 tomli-2.4.0 typing-extensions-4.15.0 2026-02-26 00:30:25.527594 | controller | 2026-02-26 00:30:25.591670 | controller | [notice] A new release of pip available: 22.2.2 -> 26.0.1 2026-02-26 00:30:25.591714 | controller | [notice] To update, run: /home/zuul/venv/bin/python -m pip install --upgrade pip 2026-02-26 00:30:25.654078 | controller | ok: Runtime: 0:00:02.289169 2026-02-26 00:30:25.661194 | 2026-02-26 00:30:25.661322 | TASK [Update pip] 2026-02-26 00:30:26.180478 | controller | Requirement already satisfied: pip in ./venv/lib/python3.10/site-packages (22.2.2) 2026-02-26 00:30:26.307944 | controller | Collecting pip 2026-02-26 00:30:26.340279 | controller | Downloading pip-26.0.1-py3-none-any.whl (1.8 MB) 2026-02-26 00:30:26.382470 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 1.8/1.8 MB 47.2 MB/s eta 0:00:00 2026-02-26 00:30:26.442459 | controller | Installing collected packages: pip 2026-02-26 00:30:26.442677 | controller | Attempting uninstall: pip 2026-02-26 00:30:26.443327 | controller | Found existing installation: pip 22.2.2 2026-02-26 00:30:26.609601 | controller | Uninstalling pip-22.2.2: 2026-02-26 00:30:26.626319 | controller | Successfully uninstalled pip-22.2.2 2026-02-26 00:30:27.460036 | controller | Successfully installed pip-26.0.1 2026-02-26 00:30:27.718421 | controller | ok: Runtime: 0:00:01.646777 2026-02-26 00:30:27.729150 | 2026-02-26 00:30:27.729381 | TASK [Ensure pip temp directory exists in /var/tmp] 2026-02-26 00:30:27.960785 | controller | changed 2026-02-26 00:30:27.969752 | 2026-02-26 00:30:27.969883 | TASK [Install ansible into virtualenv] 2026-02-26 00:30:28.460471 | controller | Processing ./src/github.com/ansible/ansible 2026-02-26 00:30:28.462137 | controller | Installing build dependencies: started 2026-02-26 00:30:29.319098 | controller | Installing build dependencies: finished with status 'done' 2026-02-26 00:30:29.921292 | controller | Getting requirements to build wheel: started 2026-02-26 00:30:29.921346 | controller | Getting requirements to build wheel: finished with status 'done' 2026-02-26 00:30:29.922469 | controller | Preparing metadata (pyproject.toml): started 2026-02-26 00:30:30.381244 | controller | Preparing metadata (pyproject.toml): finished with status 'done' 2026-02-26 00:30:31.472987 | controller | Collecting jinja2 (from ansible-core==2.12.10.post0) 2026-02-26 00:30:31.528437 | controller | Downloading jinja2-3.1.6-py3-none-any.whl.metadata (2.9 kB) 2026-02-26 00:30:31.606156 | controller | Collecting PyYAML (from ansible-core==2.12.10.post0) 2026-02-26 00:30:31.610910 | controller | Downloading pyyaml-6.0.3-cp310-cp310-manylinux2014_x86_64.manylinux_2_17_x86_64.manylinux_2_28_x86_64.whl.metadata (2.4 kB) 2026-02-26 00:30:31.898478 | controller | Collecting cryptography (from ansible-core==2.12.10.post0) 2026-02-26 00:30:31.902351 | controller | Downloading cryptography-46.0.5-cp38-abi3-manylinux_2_34_x86_64.whl.metadata (5.7 kB) 2026-02-26 00:30:31.929091 | controller | Requirement already satisfied: packaging in ./venv/lib/python3.10/site-packages (from ansible-core==2.12.10.post0) (26.0) 2026-02-26 00:30:31.946798 | controller | Collecting resolvelib<0.6.0,>=0.5.3 (from ansible-core==2.12.10.post0) 2026-02-26 00:30:31.951617 | controller | Downloading resolvelib-0.5.4-py2.py3-none-any.whl.metadata (3.7 kB) 2026-02-26 00:30:32.107387 | controller | Collecting cffi>=2.0.0 (from cryptography->ansible-core==2.12.10.post0) 2026-02-26 00:30:32.111970 | controller | Downloading cffi-2.0.0-cp310-cp310-manylinux2014_x86_64.manylinux_2_17_x86_64.whl.metadata (2.6 kB) 2026-02-26 00:30:32.122635 | controller | Requirement already satisfied: typing-extensions>=4.13.2 in ./venv/lib/python3.10/site-packages (from cryptography->ansible-core==2.12.10.post0) (4.15.0) 2026-02-26 00:30:32.139294 | controller | Collecting pycparser (from cffi>=2.0.0->cryptography->ansible-core==2.12.10.post0) 2026-02-26 00:30:32.142436 | controller | Downloading pycparser-3.0-py3-none-any.whl.metadata (8.2 kB) 2026-02-26 00:30:32.223449 | controller | Collecting MarkupSafe>=2.0 (from jinja2->ansible-core==2.12.10.post0) 2026-02-26 00:30:32.227205 | controller | Downloading markupsafe-3.0.3-cp310-cp310-manylinux2014_x86_64.manylinux_2_17_x86_64.manylinux_2_28_x86_64.whl.metadata (2.7 kB) 2026-02-26 00:30:32.242415 | controller | Downloading resolvelib-0.5.4-py2.py3-none-any.whl (12 kB) 2026-02-26 00:30:32.255631 | controller | Downloading cryptography-46.0.5-cp38-abi3-manylinux_2_34_x86_64.whl (4.5 MB) 2026-02-26 00:30:32.328158 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 4.5/4.5 MB 72.2 MB/s 0:00:00 2026-02-26 00:30:32.332283 | controller | Downloading cffi-2.0.0-cp310-cp310-manylinux2014_x86_64.manylinux_2_17_x86_64.whl (216 kB) 2026-02-26 00:30:32.342583 | controller | Downloading jinja2-3.1.6-py3-none-any.whl (134 kB) 2026-02-26 00:30:32.352799 | controller | Downloading markupsafe-3.0.3-cp310-cp310-manylinux2014_x86_64.manylinux_2_17_x86_64.manylinux_2_28_x86_64.whl (20 kB) 2026-02-26 00:30:32.360866 | controller | Downloading pycparser-3.0-py3-none-any.whl (48 kB) 2026-02-26 00:30:32.371473 | controller | Downloading pyyaml-6.0.3-cp310-cp310-manylinux2014_x86_64.manylinux_2_17_x86_64.manylinux_2_28_x86_64.whl (770 kB) 2026-02-26 00:30:32.389340 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 770.3/770.3 kB 37.7 MB/s 0:00:00 2026-02-26 00:30:32.399911 | controller | Building wheels for collected packages: ansible-core 2026-02-26 00:30:32.400665 | controller | Building wheel for ansible-core (pyproject.toml): started 2026-02-26 00:30:34.242528 | controller | Building wheel for ansible-core (pyproject.toml): finished with status 'done' 2026-02-26 00:30:34.246238 | controller | Created wheel for ansible-core: filename=ansible_core-2.12.10.post0-py3-none-any.whl size=2117520 sha256=87edafd98709fce1a00b44a7232cf763f2ba3ff5b16fd32e499a0a04689280f5 2026-02-26 00:30:34.254423 | controller | Stored in directory: /var/tmp/ansible-test-pip/pip-ephem-wheel-cache-ywkhtyqz/wheels/68/27/b0/9b068d12b42b734c64dc438319298ad4c1c5027b13ccb3a660 2026-02-26 00:30:34.254494 | controller | Successfully built ansible-core 2026-02-26 00:30:34.302805 | controller | Installing collected packages: resolvelib, PyYAML, pycparser, MarkupSafe, jinja2, cffi, cryptography, ansible-core 2026-02-26 00:30:36.064799 | controller | 2026-02-26 00:30:36.067175 | controller | Successfully installed MarkupSafe-3.0.3 PyYAML-6.0.3 ansible-core-2.12.10.post0 cffi-2.0.0 cryptography-46.0.5 jinja2-3.1.6 pycparser-3.0 resolvelib-0.5.4 2026-02-26 00:30:36.523811 | controller | ok: Runtime: 0:00:08.007325 2026-02-26 00:30:36.532780 | 2026-02-26 00:30:36.532888 | TASK [Setup download-artifact-fork role] 2026-02-26 00:30:36.556425 | controller | ok 2026-02-26 00:30:36.584706 | 2026-02-26 00:30:36.584856 | TASK [download-artifact-fork : Parse inventory response] 2026-02-26 00:30:36.619109 | controller | ok 2026-02-26 00:30:36.627388 | 2026-02-26 00:30:36.627538 | TASK [download-artifact-fork : Fetch info from Zuul API] 2026-02-26 00:30:36.652327 | controller | skipping: Conditional result was False 2026-02-26 00:30:36.659552 | 2026-02-26 00:30:36.659690 | TASK [download-artifact-fork : Parse build response] 2026-02-26 00:30:36.686002 | controller | skipping: Conditional result was False 2026-02-26 00:30:36.693061 | 2026-02-26 00:30:36.693150 | TASK [download-artifact-fork : Ensure artifacts directory exists] 2026-02-26 00:30:36.978617 | controller | changed 2026-02-26 00:30:36.985850 | 2026-02-26 00:30:36.985933 | LOOP [download-artifact-fork : Download archive by type] 2026-02-26 00:30:37.014422 | controller | skipping: Conditional result was False 2026-02-26 00:30:37.014802 | 2026-02-26 00:30:37.827133 | controller | changed: OK (20185 bytes) 2026-02-26 00:30:37.842175 | 2026-02-26 00:30:37.842309 | TASK [deploy-artifacts : define __collections] 2026-02-26 00:30:37.864361 | controller | ok 2026-02-26 00:30:37.886219 | 2026-02-26 00:30:37.886335 | LOOP [deploy-artifacts : Create list of collections] 2026-02-26 00:30:37.919020 | controller | skipping: Conditional result was False 2026-02-26 00:30:37.930244 | controller | ok: 2026-02-26 00:30:37.930380 | controller | { 2026-02-26 00:30:37.930424 | controller | "branch": "main", 2026-02-26 00:30:37.930456 | controller | "job": "build-ansible-collection", 2026-02-26 00:30:37.930486 | controller | "metadata": { 2026-02-26 00:30:37.930516 | controller | "type": "ansible_collection", 2026-02-26 00:30:37.930545 | controller | "version": "5.0.0" 2026-02-26 00:30:37.930573 | controller | }, 2026-02-26 00:30:37.930597 | controller | "name": "ansible.network", 2026-02-26 00:30:37.930619 | controller | "project": "ansible-collections/ansible.network", 2026-02-26 00:30:37.930642 | controller | "url": "https://118c11da91095f79916d-f61db552e254be53f052d53baea54a2b.ssl.cf1.rackcdn.com/ansible/9741c0f3dfa94d9b857d4614f51c009d/artifacts/ansible-network-5.0.0.tar.gz" 2026-02-26 00:30:37.930715 | controller | } 2026-02-26 00:30:37.947944 | 2026-02-26 00:30:37.948058 | TASK [deploy-artifacts : Install require-project collection using ansible-galaxy] 2026-02-26 00:30:38.533108 | controller | Starting galaxy collection install process 2026-02-26 00:30:38.533313 | controller | Process install dependency map 2026-02-26 00:35:32.624818 | [Zuul] Log Stream did not terminate 2026-02-26 00:35:32.625205 | controller | ERROR 2026-02-26 00:35:32.625352 | controller | { 2026-02-26 00:35:32.625390 | controller | "msg": "Data could not be sent to remote host \"199.204.45.6\". Make sure this host can be reached over ssh: ssh: connect to host 199.204.45.6 port 22: No route to host\r\n", 2026-02-26 00:35:32.625417 | controller | "unreachable": true 2026-02-26 00:35:32.625434 | controller | } unreachable 2026-02-26 00:35:32.627369 | 2026-02-26 00:35:32.627423 | PLAY RECAP 2026-02-26 00:35:32.627472 | controller | ok: 24 changed: 13 unreachable: 1 failed: 0 skipped: 6 rescued: 0 ignored: 0 2026-02-26 00:35:32.627522 | 2026-02-26 00:35:32.796845 | PRE-RUN END RESULT_UNREACHABLE: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master] 2026-02-26 00:35:32.801493 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2026-02-26 00:35:33.423139 | 2026-02-26 00:35:33.423308 | PLAY [all] 2026-02-26 00:35:33.447530 | 2026-02-26 00:35:33.447695 | TASK [fetch-subunit-output : Find stestr or testr executable] 2026-02-26 00:35:41.101898 | controller | changed: non-zero return code 2026-02-26 00:35:41.116051 | 2026-02-26 00:35:41.116282 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2026-02-26 00:35:41.134999 | controller | skipping: Conditional result was False 2026-02-26 00:35:41.146830 | 2026-02-26 00:35:41.147015 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2026-02-26 00:35:41.180678 | 2026-02-26 00:35:41.180922 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2026-02-26 00:35:41.212543 | 2026-02-26 00:35:41.212741 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2026-02-26 00:35:41.227116 | controller | skipping: Conditional result was False 2026-02-26 00:35:41.234793 | 2026-02-26 00:35:41.234898 | LOOP [fetch-subunit-output : Generate subunit file] 2026-02-26 00:35:41.258873 | 2026-02-26 00:35:41.259082 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2026-02-26 00:35:41.288946 | controller | skipping: Conditional result was False 2026-02-26 00:35:41.295927 | 2026-02-26 00:35:41.296011 | TASK [fetch-subunit-output : Remove the temporary file] 2026-02-26 00:35:41.311513 | controller | skipping: Conditional result was False 2026-02-26 00:35:41.319795 | 2026-02-26 00:35:41.319894 | TASK [fetch-subunit-output : Process and fetch subunit results] 2026-02-26 00:35:41.334890 | controller | skipping: Conditional result was False 2026-02-26 00:35:41.367685 | 2026-02-26 00:35:41.367836 | PLAY RECAP 2026-02-26 00:35:41.367884 | controller | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2026-02-26 00:35:41.367907 | 2026-02-26 00:35:41.539288 | POST-RUN END RESULT_UNREACHABLE: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2026-02-26 00:35:41.540199 | POST-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/post.yaml@master] 2026-02-26 00:35:42.184164 | 2026-02-26 00:35:42.184301 | PLAY [all:!appliance*] 2026-02-26 00:35:42.217555 | 2026-02-26 00:35:42.217716 | TASK [unregister the node] 2026-02-26 00:35:42.751760 | controller | skipping: Conditional result was False 2026-02-26 00:35:42.758516 | 2026-02-26 00:35:42.758686 | TASK [include_role : fetch-output] 2026-02-26 00:35:42.790118 | controller | ok 2026-02-26 00:35:42.812455 | 2026-02-26 00:35:42.813007 | TASK [fetch-output : Set log path for multiple nodes] 2026-02-26 00:35:42.859132 | controller | skipping: Conditional result was False 2026-02-26 00:35:42.866519 | 2026-02-26 00:35:42.866670 | TASK [fetch-output : Set log path for single node] 2026-02-26 00:35:42.897842 | controller | ok 2026-02-26 00:35:42.903950 | 2026-02-26 00:35:42.904096 | LOOP [fetch-output : Ensure local output dirs] 2026-02-26 00:35:43.366304 | controller -> localhost | ok: "/var/lib/zuul/builds/e35e775a973e4f1ba24bc376c5cfcf19/work/logs" 2026-02-26 00:35:43.601148 | controller -> localhost | changed: "/var/lib/zuul/builds/e35e775a973e4f1ba24bc376c5cfcf19/work/artifacts" 2026-02-26 00:35:43.835232 | controller -> localhost | changed: "/var/lib/zuul/builds/e35e775a973e4f1ba24bc376c5cfcf19/work/docs" 2026-02-26 00:35:43.854139 | 2026-02-26 00:35:43.854293 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-02-26 00:35:44.544952 | controller | changed: 2026-02-26 00:35:44.545383 | controller | .d..t...... ./ 2026-02-26 00:35:44.545475 | controller | cd+++++++++ controller/ 2026-02-26 00:35:44.545581 | controller | changed: All items complete 2026-02-26 00:35:44.545678 | 2026-02-26 00:35:45.053473 | controller | changed: .d..t...... ./ 2026-02-26 00:35:45.526529 | controller | changed: .d..t...... ./ 2026-02-26 00:35:45.558388 | 2026-02-26 00:35:45.558563 | TASK [include_role : fetch-output-openshift] 2026-02-26 00:35:45.575849 | controller | skipping: Conditional result was False 2026-02-26 00:35:45.583842 | 2026-02-26 00:35:45.584030 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-02-26 00:35:45.613394 | controller | skipping: Conditional result was False 2026-02-26 00:35:45.624773 | controller | skipping: Conditional result was False 2026-02-26 00:35:45.662256 | 2026-02-26 00:35:45.662402 | PLAY [localhost] 2026-02-26 00:35:45.679399 | 2026-02-26 00:35:45.679548 | TASK [Run Zuul manifest role] 2026-02-26 00:35:45.699610 | localhost | ok 2026-02-26 00:35:45.717536 | 2026-02-26 00:35:45.717679 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-02-26 00:35:46.146822 | localhost | changed 2026-02-26 00:35:46.153484 | 2026-02-26 00:35:46.153567 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-02-26 00:35:46.182465 | localhost | ok 2026-02-26 00:35:46.191203 | 2026-02-26 00:35:46.191281 | TASK [Set zuul-log-path fact] 2026-02-26 00:35:46.209841 | localhost | ok 2026-02-26 00:35:46.224022 | 2026-02-26 00:35:46.224130 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-02-26 00:35:46.256278 | localhost | ok 2026-02-26 00:35:46.268027 | 2026-02-26 00:35:46.268155 | LOOP [Set zuul_log_targets (Swift with random Rackspace region)] 2026-02-26 00:35:46.303507 | localhost | ok: 2026-02-26 00:35:46.303893 | localhost | { 2026-02-26 00:35:46.303971 | localhost | "auth": { 2026-02-26 00:35:46.304036 | localhost | "password": "IonWCwcuOFib&&<}wp^:[2G/", 2026-02-26 00:35:46.304086 | localhost | "username": "9LF91O0RK7JU" 2026-02-26 00:35:46.304134 | localhost | }, 2026-02-26 00:35:46.304180 | localhost | "profile": "rackspace", 2026-02-26 00:35:46.304224 | localhost | "region_name": "ORD" 2026-02-26 00:35:46.304267 | localhost | } 2026-02-26 00:35:46.304325 | 2026-02-26 00:35:46.311659 | 2026-02-26 00:35:46.311814 | TASK [Run upload-logs-failover role (S3 then Swift)] 2026-02-26 00:35:46.330538 | localhost | Output suppressed because no_log was given 2026-02-26 00:35:46.343957 | 2026-02-26 00:35:46.344074 | TASK [upload-logs-failover : Initialize control vars] 2026-02-26 00:35:46.378679 | localhost | ok 2026-02-26 00:35:46.384463 | 2026-02-26 00:35:46.384548 | LOOP [upload-logs-failover : Perform log upload] 2026-02-26 00:35:46.423256 | localhost | Output suppressed because no_log was given 2026-02-26 00:35:46.485347 | localhost | included: /var/lib/zuul/builds/e35e775a973e4f1ba24bc376c5cfcf19/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/upload-logs-failover/tasks/upload.yaml 2026-02-26 00:35:46.500844 | 2026-02-26 00:35:46.501018 | LOOP [upload-logs-failover : Unpack vars for swift upload] 2026-02-26 00:35:46.583271 | localhost | Output suppressed because no_log was given 2026-02-26 00:35:46.590856 | 2026-02-26 00:35:46.591004 | TASK [Upload logs to swift] 2026-02-26 00:35:46.612178 | localhost | ok 2026-02-26 00:35:46.646188 | 2026-02-26 00:35:46.646330 | TASK [Set zuul-log-path fact] 2026-02-26 00:35:46.671408 | localhost | skipping: Conditional result was False 2026-02-26 00:35:46.676787 | 2026-02-26 00:35:46.676863 | TASK [upload-logs-swift : Ensure logs are readable before uploading] 2026-02-26 00:35:47.058533 | localhost -> localhost | ok: Runtime: 0:00:00.005357 2026-02-26 00:35:47.066794 | 2026-02-26 00:35:47.066978 | TASK [upload-logs-swift : Upload logs to swift]