2026-01-26 00:14:19.746462 | Job console starting... 2026-01-26 00:14:19.759148 | Updating repositories 2026-01-26 00:14:19.930471 | Preparing job workspace 2026-01-26 00:14:24.895533 | Running Ansible setup... 2026-01-26 00:14:32.747122 | PRE-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master] 2026-01-26 00:14:33.342761 | 2026-01-26 00:14:33.342956 | PLAY [localhost] 2026-01-26 00:14:33.352449 | 2026-01-26 00:14:33.352526 | TASK [Gathering Facts] 2026-01-26 00:14:34.456308 | localhost | ok 2026-01-26 00:14:34.471450 | 2026-01-26 00:14:34.471655 | TASK [Setup log path fact] 2026-01-26 00:14:34.497151 | localhost | ok 2026-01-26 00:14:34.511307 | 2026-01-26 00:14:34.511421 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-01-26 00:14:34.550657 | localhost | ok 2026-01-26 00:14:34.559427 | 2026-01-26 00:14:34.559574 | TASK [emit-job-header : Print job information] 2026-01-26 00:14:34.592657 | # Job Information 2026-01-26 00:14:34.592907 | Ansible Version: 2.15.12 2026-01-26 00:14:34.592952 | Job: ansible-test-sanity-docker-stable-2.12 2026-01-26 00:14:34.592981 | Pipeline: periodic 2026-01-26 00:14:34.593005 | Executor: ze02.softwarefactory-project.io 2026-01-26 00:14:34.593028 | Triggered by: https://github.com/ansible-collections/ansible.network 2026-01-26 00:14:34.593055 | Log URL (when completed): https://ansible.softwarefactory-project.io/logs/2ca/ansible/2ca4c207236849c1a15f188a1a06b9e0/ 2026-01-26 00:14:34.593079 | Event ID: 4b9b1c413fcf4b5b911f7b1cc126ee08 2026-01-26 00:14:34.598391 | 2026-01-26 00:14:34.598480 | LOOP [emit-job-header : Print node information] 2026-01-26 00:14:34.735223 | localhost | ok: 2026-01-26 00:14:34.735430 | localhost | # Node Information 2026-01-26 00:14:34.735472 | localhost | Inventory Hostname: controller 2026-01-26 00:14:34.735506 | localhost | Hostname: np0005595220 2026-01-26 00:14:34.735539 | localhost | Username: zuul 2026-01-26 00:14:34.735571 | localhost | Distro: Fedora 37 2026-01-26 00:14:34.735599 | localhost | Provider: ansible-vexxhost-ams1 2026-01-26 00:14:34.735627 | localhost | Region: ams1 2026-01-26 00:14:34.735654 | localhost | Label: ansible-fedora-37-1vcpu 2026-01-26 00:14:34.735783 | localhost | Product Name: OpenStack Nova 2026-01-26 00:14:34.735819 | localhost | Interface IP: 38.129.16.96 2026-01-26 00:14:34.756315 | 2026-01-26 00:14:34.756458 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-01-26 00:14:35.199969 | localhost -> localhost | changed 2026-01-26 00:14:35.212494 | 2026-01-26 00:14:35.212613 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-01-26 00:14:36.149106 | localhost -> localhost | changed 2026-01-26 00:14:36.180717 | 2026-01-26 00:14:36.180893 | PLAY [all:!appliance*] 2026-01-26 00:14:36.205451 | 2026-01-26 00:14:36.205585 | TASK [include_role : start-zuul-console] 2026-01-26 00:14:36.237508 | controller | ok 2026-01-26 00:14:36.253613 | 2026-01-26 00:14:36.253742 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-01-26 00:14:37.367523 | controller | ok 2026-01-26 00:14:37.390755 | 2026-01-26 00:14:37.390880 | TASK [use-our-mirror : Retrieve the IP address] 2026-01-26 00:14:39.387610 | controller | ok: Status code was 404 and not [200]: HTTP Error 404: Not Found 2026-01-26 00:14:39.398570 | 2026-01-26 00:14:39.398755 | TASK [use-our-mirror : Ensure the registry service is running on the mirror] 2026-01-26 00:14:39.771762 | controller | skipping: Conditional result was False 2026-01-26 00:14:39.792709 | 2026-01-26 00:14:39.793006 | TASK [use-our-mirror : Write the IP in /etc/hosts] 2026-01-26 00:14:39.833390 | controller | skipping: Conditional result was False 2026-01-26 00:14:39.847081 | 2026-01-26 00:14:39.847234 | TASK [use-our-mirror : Create the podman configuration directory] 2026-01-26 00:14:39.885487 | controller | skipping: Conditional result was False 2026-01-26 00:14:39.902474 | 2026-01-26 00:14:39.902652 | TASK [use-our-mirror : Copy the podman configuration] 2026-01-26 00:14:39.928371 | controller | skipping: Conditional result was False 2026-01-26 00:14:39.936136 | 2026-01-26 00:14:39.936236 | TASK [use-our-mirror : Copy the Yum configuration (main)] 2026-01-26 00:14:39.961720 | controller | skipping: Conditional result was False 2026-01-26 00:14:39.977260 | 2026-01-26 00:14:39.977447 | TASK [use-our-mirror : Copy the Yum configuration (update)] 2026-01-26 00:14:40.003906 | controller | skipping: Conditional result was False 2026-01-26 00:14:40.017485 | 2026-01-26 00:14:40.017617 | TASK [Disable Fedora Modular] 2026-01-26 00:14:41.278898 | controller | changed 2026-01-26 00:14:41.284527 | 2026-01-26 00:14:41.284590 | TASK [Enable EPEL] 2026-01-26 00:14:41.309146 | controller | skipping: Conditional result was False 2026-01-26 00:14:41.316767 | 2026-01-26 00:14:41.316883 | TASK [Register the RHEL node] 2026-01-26 00:14:41.753790 | 2026-01-26 00:14:41.754209 | TASK [Show the subscription-manager status] 2026-01-26 00:14:42.149509 | controller | skipping: Conditional result was False 2026-01-26 00:14:42.157306 | 2026-01-26 00:14:42.157431 | TASK [Enable EPEL on RHEL] 2026-01-26 00:14:42.521606 | controller | skipping: Conditional result was False 2026-01-26 00:14:42.537328 | 2026-01-26 00:14:42.537478 | TASK [Install git and tox] 2026-01-26 00:16:49.842933 | controller | changed 2026-01-26 00:16:49.850289 | 2026-01-26 00:16:49.850382 | TASK [include_role : prepare-workspace] 2026-01-26 00:16:49.882224 | controller | ok 2026-01-26 00:16:49.903903 | 2026-01-26 00:16:49.904019 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-01-26 00:16:50.799217 | controller | ok 2026-01-26 00:16:50.813875 | 2026-01-26 00:16:50.814013 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-01-26 00:17:07.566985 | controller | Output suppressed because no_log was given 2026-01-26 00:17:07.578435 | 2026-01-26 00:17:07.578504 | TASK [include_role : prepare-workspace-openshift] 2026-01-26 00:17:07.608597 | controller | skipping: Conditional result was False 2026-01-26 00:17:07.680435 | 2026-01-26 00:17:07.680538 | PLAY [all:!appliance] 2026-01-26 00:17:07.697266 | 2026-01-26 00:17:07.697348 | TASK [Run add-build-sshkey role (RSA)] 2026-01-26 00:17:07.740912 | controller | ok 2026-01-26 00:17:07.760739 | 2026-01-26 00:17:07.760940 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-01-26 00:17:08.044449 | controller -> localhost | ok 2026-01-26 00:17:08.062771 | 2026-01-26 00:17:08.062957 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-01-26 00:17:08.108785 | controller | ok 2026-01-26 00:17:08.142358 | controller | included: /var/lib/zuul/builds/2ca4c207236849c1a15f188a1a06b9e0/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-01-26 00:17:08.152939 | 2026-01-26 00:17:08.153057 | TASK [add-build-sshkey : Create Temp SSH key] 2026-01-26 00:17:08.804584 | controller -> localhost | Generating public/private rsa key pair. 2026-01-26 00:17:08.805229 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/2ca4c207236849c1a15f188a1a06b9e0/work/2ca4c207236849c1a15f188a1a06b9e0_id_rsa. 2026-01-26 00:17:08.805332 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/2ca4c207236849c1a15f188a1a06b9e0/work/2ca4c207236849c1a15f188a1a06b9e0_id_rsa.pub. 2026-01-26 00:17:08.805407 | controller -> localhost | The key fingerprint is: 2026-01-26 00:17:08.805468 | controller -> localhost | SHA256:Nkwnj6ZFFtMuM3lpvs9LHanP6CLG/MpAMFGhb/+2qJ0 zuul-build-sshkey 2026-01-26 00:17:08.805531 | controller -> localhost | The key's randomart image is: 2026-01-26 00:17:08.805601 | controller -> localhost | +---[RSA 2048]----+ 2026-01-26 00:17:08.805706 | controller -> localhost | | ..o.o. | 2026-01-26 00:17:08.805793 | controller -> localhost | | o o. | 2026-01-26 00:17:08.805858 | controller -> localhost | | + =o.. | 2026-01-26 00:17:08.805922 | controller -> localhost | | + ==== . | 2026-01-26 00:17:08.805986 | controller -> localhost | | + SB. o | 2026-01-26 00:17:08.806069 | controller -> localhost | | o * .. o . | 2026-01-26 00:17:08.806150 | controller -> localhost | | oo. .o . | 2026-01-26 00:17:08.806223 | controller -> localhost | | +=++o + | 2026-01-26 00:17:08.806290 | controller -> localhost | | .oE=+=*.o | 2026-01-26 00:17:08.806361 | controller -> localhost | +----[SHA256]-----+ 2026-01-26 00:17:08.806494 | controller -> localhost | ok: Runtime: 0:00:00.184558 2026-01-26 00:17:08.823529 | 2026-01-26 00:17:08.823699 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-01-26 00:17:08.865833 | controller | ok 2026-01-26 00:17:08.888579 | controller | included: /var/lib/zuul/builds/2ca4c207236849c1a15f188a1a06b9e0/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-01-26 00:17:08.903170 | 2026-01-26 00:17:08.903282 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-01-26 00:17:08.931727 | controller | skipping: Conditional result was False 2026-01-26 00:17:08.946449 | 2026-01-26 00:17:08.946594 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-01-26 00:17:10.070651 | controller | changed 2026-01-26 00:17:10.084834 | 2026-01-26 00:17:10.084993 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-01-26 00:17:10.752951 | controller | ok 2026-01-26 00:17:10.773504 | 2026-01-26 00:17:10.773690 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-01-26 00:17:13.859580 | controller | changed 2026-01-26 00:17:13.874721 | 2026-01-26 00:17:13.874948 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-01-26 00:17:16.936868 | controller | changed 2026-01-26 00:17:16.954125 | 2026-01-26 00:17:16.954274 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-01-26 00:17:16.975353 | controller | skipping: Conditional result was False 2026-01-26 00:17:16.989408 | 2026-01-26 00:17:16.989572 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-01-26 00:17:17.465269 | controller -> localhost | changed 2026-01-26 00:17:17.479597 | 2026-01-26 00:17:17.479823 | TASK [add-build-sshkey : Add back temp key] 2026-01-26 00:17:17.760088 | controller -> localhost | Identity added: /var/lib/zuul/builds/2ca4c207236849c1a15f188a1a06b9e0/work/2ca4c207236849c1a15f188a1a06b9e0_id_rsa (zuul-build-sshkey) 2026-01-26 00:17:17.760373 | controller -> localhost | ok: Runtime: 0:00:00.007344 2026-01-26 00:17:17.767597 | 2026-01-26 00:17:17.767735 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-01-26 00:17:18.715609 | controller | ok 2026-01-26 00:17:18.723211 | 2026-01-26 00:17:18.723384 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-01-26 00:17:18.748998 | controller | skipping: Conditional result was False 2026-01-26 00:17:18.769279 | 2026-01-26 00:17:18.769473 | TASK [Run add-build-sshkey role (ECDSA)] 2026-01-26 00:17:18.791169 | controller | ok 2026-01-26 00:17:18.807783 | 2026-01-26 00:17:18.807939 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-01-26 00:17:19.037609 | controller -> localhost | ok 2026-01-26 00:17:19.046183 | 2026-01-26 00:17:19.046270 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-01-26 00:17:19.083707 | controller | ok 2026-01-26 00:17:19.105277 | controller | included: /var/lib/zuul/builds/2ca4c207236849c1a15f188a1a06b9e0/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-01-26 00:17:19.114821 | 2026-01-26 00:17:19.114927 | TASK [add-build-sshkey : Create Temp SSH key] 2026-01-26 00:17:19.516188 | controller -> localhost | Generating public/private ecdsa key pair. 2026-01-26 00:17:19.516725 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/2ca4c207236849c1a15f188a1a06b9e0/work/2ca4c207236849c1a15f188a1a06b9e0_id_ecdsa. 2026-01-26 00:17:19.516811 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/2ca4c207236849c1a15f188a1a06b9e0/work/2ca4c207236849c1a15f188a1a06b9e0_id_ecdsa.pub. 2026-01-26 00:17:19.516886 | controller -> localhost | The key fingerprint is: 2026-01-26 00:17:19.516941 | controller -> localhost | SHA256:h32VFT4OO1AngY7JtvmbKiw2lWGrHntscTWvhgmIQyA zuul-build-sshkey 2026-01-26 00:17:19.516991 | controller -> localhost | The key's randomart image is: 2026-01-26 00:17:19.517040 | controller -> localhost | +---[ECDSA 521]---+ 2026-01-26 00:17:19.517088 | controller -> localhost | | .+.oo| 2026-01-26 00:17:19.517135 | controller -> localhost | |E. .. +o | 2026-01-26 00:17:19.517180 | controller -> localhost | |. . . +. .oo | 2026-01-26 00:17:19.517226 | controller -> localhost | | . o B +..+ .| 2026-01-26 00:17:19.517271 | controller -> localhost | | . . o S * +o . | 2026-01-26 00:17:19.517317 | controller -> localhost | | o . * = . .. | 2026-01-26 00:17:19.517361 | controller -> localhost | | ..= + + . | 2026-01-26 00:17:19.517406 | controller -> localhost | | =o* o +. | 2026-01-26 00:17:19.517450 | controller -> localhost | | oo= ..oo. | 2026-01-26 00:17:19.517495 | controller -> localhost | +----[SHA256]-----+ 2026-01-26 00:17:19.517614 | controller -> localhost | ok: Runtime: 0:00:00.014811 2026-01-26 00:17:19.535824 | 2026-01-26 00:17:19.536047 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-01-26 00:17:19.583748 | controller | ok 2026-01-26 00:17:19.601700 | controller | included: /var/lib/zuul/builds/2ca4c207236849c1a15f188a1a06b9e0/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-01-26 00:17:19.615291 | 2026-01-26 00:17:19.615480 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-01-26 00:17:19.641212 | controller | skipping: Conditional result was False 2026-01-26 00:17:19.651258 | 2026-01-26 00:17:19.651396 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-01-26 00:17:20.677134 | controller | changed 2026-01-26 00:17:20.742036 | 2026-01-26 00:17:20.742209 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-01-26 00:17:21.406209 | controller | ok 2026-01-26 00:17:21.415463 | 2026-01-26 00:17:21.415549 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-01-26 00:17:24.463691 | controller | changed 2026-01-26 00:17:24.477564 | 2026-01-26 00:17:24.477745 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-01-26 00:17:27.560235 | controller | changed 2026-01-26 00:17:27.569049 | 2026-01-26 00:17:27.569176 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-01-26 00:17:27.598387 | controller | skipping: Conditional result was False 2026-01-26 00:17:27.608101 | 2026-01-26 00:17:27.608204 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-01-26 00:17:27.882172 | controller -> localhost | changed 2026-01-26 00:17:27.906786 | 2026-01-26 00:17:27.906980 | TASK [add-build-sshkey : Add back temp key] 2026-01-26 00:17:28.230074 | controller -> localhost | Identity added: /var/lib/zuul/builds/2ca4c207236849c1a15f188a1a06b9e0/work/2ca4c207236849c1a15f188a1a06b9e0_id_ecdsa (zuul-build-sshkey) 2026-01-26 00:17:28.230357 | controller -> localhost | ok: Runtime: 0:00:00.010516 2026-01-26 00:17:28.237940 | 2026-01-26 00:17:28.238003 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-01-26 00:17:28.953081 | controller | ok 2026-01-26 00:17:28.961801 | 2026-01-26 00:17:28.961902 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-01-26 00:17:28.998255 | controller | skipping: Conditional result was False 2026-01-26 00:17:29.013012 | 2026-01-26 00:17:29.013111 | TASK [include_role : remove-zuul-sshkey] 2026-01-26 00:17:29.039588 | controller | skipping: Conditional result was False 2026-01-26 00:17:29.048027 | 2026-01-26 00:17:29.048118 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-01-26 00:17:29.782887 | controller | ok: "logs" 2026-01-26 00:17:29.783220 | controller | ok: All items complete 2026-01-26 00:17:29.783263 | 2026-01-26 00:17:30.393169 | controller | ok: "artifacts" 2026-01-26 00:17:31.016196 | controller | ok: "docs" 2026-01-26 00:17:31.032341 | 2026-01-26 00:17:31.032514 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-01-26 00:17:31.710554 | controller | changed: "logs" 2026-01-26 00:17:32.364426 | controller | changed: "artifacts" 2026-01-26 00:17:33.043447 | controller | changed: "docs" 2026-01-26 00:17:33.078228 | 2026-01-26 00:17:33.078415 | PLAY RECAP 2026-01-26 00:17:33.078470 | controller | ok: 30 changed: 16 unreachable: 0 failed: 0 skipped: 18 rescued: 0 ignored: 0 2026-01-26 00:17:33.078520 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-01-26 00:17:33.078544 | 2026-01-26 00:17:33.321899 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master] 2026-01-26 00:17:33.322921 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2026-01-26 00:17:33.929565 | 2026-01-26 00:17:33.929736 | PLAY [all] 2026-01-26 00:17:33.954209 | 2026-01-26 00:17:33.954337 | TASK [Install binary dependencies] 2026-01-26 00:17:34.006077 | controller | ok 2026-01-26 00:17:34.026860 | 2026-01-26 00:17:34.026958 | TASK [bindep : Include find tasks] 2026-01-26 00:17:34.058934 | controller | ok 2026-01-26 00:17:34.067262 | controller | included: /var/lib/zuul/builds/2ca4c207236849c1a15f188a1a06b9e0/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find.yaml 2026-01-26 00:17:34.073983 | 2026-01-26 00:17:34.074052 | TASK [bindep : Look for bindep.txt] 2026-01-26 00:17:35.051261 | controller | ok 2026-01-26 00:17:35.065717 | 2026-01-26 00:17:35.065862 | TASK [bindep : Define bindep_file fact] 2026-01-26 00:17:35.094705 | controller | skipping: Conditional result was False 2026-01-26 00:17:35.108660 | 2026-01-26 00:17:35.108980 | TASK [bindep : Look for other-requirements.txt] 2026-01-26 00:17:35.776575 | controller | ok 2026-01-26 00:17:35.783703 | 2026-01-26 00:17:35.783804 | TASK [bindep : Define bindep_file fact] 2026-01-26 00:17:35.811295 | controller | skipping: Conditional result was False 2026-01-26 00:17:35.818605 | 2026-01-26 00:17:35.818733 | TASK [bindep : Look for bindep fallback file] 2026-01-26 00:17:35.844478 | controller | skipping: Conditional result was False 2026-01-26 00:17:35.851896 | 2026-01-26 00:17:35.851972 | TASK [bindep : Define bindep_file fact] 2026-01-26 00:17:35.878998 | controller | skipping: Conditional result was False 2026-01-26 00:17:35.889745 | 2026-01-26 00:17:35.889855 | TASK [bindep : Include bindep tasks] 2026-01-26 00:17:35.928396 | controller | skipping: Conditional result was False 2026-01-26 00:17:35.938328 | 2026-01-26 00:17:35.938508 | TASK [bindep : Include install tasks] 2026-01-26 00:17:35.967390 | controller | skipping: Conditional result was False 2026-01-26 00:17:35.977754 | 2026-01-26 00:17:35.977877 | LOOP [bindep : Include package tasks] 2026-01-26 00:17:36.066882 | 2026-01-26 00:17:36.067378 | TASK [Run test-setup role] 2026-01-26 00:17:36.093462 | controller | ok 2026-01-26 00:17:36.121389 | 2026-01-26 00:17:36.121535 | TASK [test-setup : Check if project's tools/test-setup.sh exists] 2026-01-26 00:17:36.797774 | controller | ok 2026-01-26 00:17:36.812065 | 2026-01-26 00:17:36.812217 | TASK [test-setup : Run tools/test-setup.sh] 2026-01-26 00:17:37.187558 | controller | skipping: Conditional result was False 2026-01-26 00:17:37.232134 | 2026-01-26 00:17:37.232285 | PLAY RECAP 2026-01-26 00:17:37.232333 | controller | ok: 4 changed: 0 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2026-01-26 00:17:37.232353 | 2026-01-26 00:17:37.410018 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2026-01-26 00:17:37.411011 | PRE-RUN START: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master] 2026-01-26 00:17:38.047173 | 2026-01-26 00:17:38.047342 | PLAY [controller] 2026-01-26 00:17:38.068163 | 2026-01-26 00:17:38.068266 | TASK [Create the /root directory] 2026-01-26 00:17:39.173426 | controller | ok 2026-01-26 00:17:39.187024 | 2026-01-26 00:17:39.187202 | TASK [Install glibc-langpack-en] 2026-01-26 00:17:47.378712 | controller | ok: Nothing to do 2026-01-26 00:17:47.392321 | 2026-01-26 00:17:47.392428 | TASK [Ensure controller directory exists] 2026-01-26 00:17:48.223115 | controller | changed 2026-01-26 00:17:48.236611 | 2026-01-26 00:17:48.236824 | TASK [Install container runtime] 2026-01-26 00:17:48.285995 | controller | ok 2026-01-26 00:17:48.342299 | 2026-01-26 00:17:48.342468 | LOOP [ensure-podman : Find distribution installation] 2026-01-26 00:17:48.380051 | controller | ok: "/var/lib/zuul/builds/2ca4c207236849c1a15f188a1a06b9e0/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-podman/tasks/RedHat.yaml" 2026-01-26 00:17:48.395422 | controller | included: /var/lib/zuul/builds/2ca4c207236849c1a15f188a1a06b9e0/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-podman/tasks/RedHat.yaml 2026-01-26 00:17:48.402097 | 2026-01-26 00:17:48.402165 | TASK [ensure-podman : Install podman (RedHat)] 2026-01-26 00:19:53.613876 | controller | changed 2026-01-26 00:19:53.623439 | 2026-01-26 00:19:53.623534 | TASK [ensure-podman : Fetch podman version] 2026-01-26 00:19:54.911907 | controller | Client: Podman Engine 2026-01-26 00:19:54.960147 | controller | Version: 4.6.2 2026-01-26 00:19:54.960237 | controller | API Version: 4.6.2 2026-01-26 00:19:54.960247 | controller | Go Version: go1.19.12 2026-01-26 00:19:54.960274 | controller | Built: Mon Aug 28 19:38:31 2023 2026-01-26 00:19:54.960285 | controller | OS/Arch: linux/amd64 2026-01-26 00:19:55.500661 | controller | ok: Runtime: 0:00:00.371834 2026-01-26 00:19:55.507369 | 2026-01-26 00:19:55.507442 | TASK [ensure-podman : Print podman version installed] 2026-01-26 00:19:55.548067 | Podman version: Client: Podman Engine 2026-01-26 00:19:55.548203 | Version: 4.6.2 2026-01-26 00:19:55.548232 | API Version: 4.6.2 2026-01-26 00:19:55.548253 | Go Version: go1.19.12 2026-01-26 00:19:55.548271 | Built: Mon Aug 28 19:38:31 2023 2026-01-26 00:19:55.548291 | OS/Arch: linux/amd64 2026-01-26 00:19:55.554044 | 2026-01-26 00:19:55.554112 | TASK [ensure-podman : Validate podman engine] 2026-01-26 00:19:55.907228 | controller | skipping: Conditional result was False 2026-01-26 00:19:55.922084 | 2026-01-26 00:19:55.922244 | TASK [ensure-podman : Set up docker compatability socket] 2026-01-26 00:19:55.947976 | controller | skipping: Conditional result was False 2026-01-26 00:19:55.960928 | 2026-01-26 00:19:55.961083 | TASK [Ensure python3.8 is present] 2026-01-26 00:19:55.985977 | controller | skipping: Conditional result was False 2026-01-26 00:19:55.994512 | 2026-01-26 00:19:55.994621 | TASK [Run our-ensure-python role (Fedora only for now)] 2026-01-26 00:19:56.017638 | controller | ok 2026-01-26 00:19:56.047642 | 2026-01-26 00:19:56.047812 | TASK [our-ensure-python : Install the right Python version (rpm)] 2026-01-26 00:20:00.637029 | controller | ok: Nothing to do 2026-01-26 00:20:00.650772 | 2026-01-26 00:20:00.650939 | TASK [our-ensure-python : Also install python3-devel] 2026-01-26 00:20:18.505250 | controller | changed 2026-01-26 00:20:18.531088 | 2026-01-26 00:20:18.531245 | TASK [Run ensure-virtualenv role] 2026-01-26 00:20:18.555786 | controller | ok 2026-01-26 00:20:18.584694 | 2026-01-26 00:20:18.584791 | TASK [ensure-virtualenv : Check if virtualenv is installed] 2026-01-26 00:20:19.370838 | controller | /usr/bin/virtualenv 2026-01-26 00:20:19.979295 | controller | ok: Runtime: 0:00:00.006166 2026-01-26 00:20:19.994200 | 2026-01-26 00:20:19.994356 | LOOP [ensure-virtualenv : Install virtualenv package] 2026-01-26 00:20:20.037303 | controller | skipping: Conditional result was False 2026-01-26 00:20:20.037621 | controller | ok: All items complete 2026-01-26 00:20:20.037649 | 2026-01-26 00:20:20.063294 | 2026-01-26 00:20:20.063486 | TASK [Find the full path of the Python interpreter] 2026-01-26 00:20:20.863280 | controller | /usr/bin/python3.10 2026-01-26 00:20:21.428612 | controller | ok 2026-01-26 00:20:21.435053 | 2026-01-26 00:20:21.435125 | TASK [Create virtualenv for ansible-test with a specific interpreter] 2026-01-26 00:20:23.316589 | controller | created virtual environment CPython3.10.13.final.0-64 in 990ms 2026-01-26 00:20:23.367682 | controller | creator CPython3Posix(dest=/home/zuul/venv, clear=False, no_vcs_ignore=False, global=False) 2026-01-26 00:20:23.367759 | controller | seeder FromAppData(extra_search_dir=/usr/share/python-wheels,download=False, pip=bundle, setuptools=bundle, wheel=bundle, via=copy, app_data_dir=/home/zuul/.local/share/virtualenv) 2026-01-26 00:20:23.367773 | controller | added seed packages: pip==22.2.2, setuptools==62.6.0, wheel==0.37.1 2026-01-26 00:20:23.367795 | controller | activators BashActivator,CShellActivator,FishActivator,NushellActivator,PowerShellActivator,PythonActivator 2026-01-26 00:20:23.793942 | controller | changed 2026-01-26 00:20:23.808883 | 2026-01-26 00:20:23.809027 | TASK [Set selinux package] 2026-01-26 00:20:23.850030 | controller | ok 2026-01-26 00:20:23.862043 | 2026-01-26 00:20:23.862172 | TASK [Set selinux package (Fedora)] 2026-01-26 00:20:23.913495 | controller | ok 2026-01-26 00:20:23.926711 | 2026-01-26 00:20:23.926846 | TASK [Install selinux into virtualenv] 2026-01-26 00:20:26.796104 | controller | Collecting selinux-please-lie-to-me 2026-01-26 00:20:26.883719 | controller | Downloading selinux_please_lie_to_me-1.0.1-py2.py3-none-any.whl (3.8 kB) 2026-01-26 00:20:27.470998 | controller | Collecting setuptools<50.0.0 2026-01-26 00:20:27.476335 | controller | Downloading setuptools-49.6.0-py3-none-any.whl (803 kB) 2026-01-26 00:20:27.530373 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 803.3/803.3 kB 16.7 MB/s eta 0:00:00 2026-01-26 00:20:27.670596 | controller | Installing collected packages: setuptools, selinux-please-lie-to-me 2026-01-26 00:20:27.670950 | controller | Attempting uninstall: setuptools 2026-01-26 00:20:27.672011 | controller | Found existing installation: setuptools 62.6.0 2026-01-26 00:20:27.808437 | controller | Uninstalling setuptools-62.6.0: 2026-01-26 00:20:27.823454 | controller | Successfully uninstalled setuptools-62.6.0 2026-01-26 00:20:28.589927 | controller | Successfully installed selinux-please-lie-to-me-1.0.1 setuptools-49.6.0 2026-01-26 00:20:28.803347 | controller | 2026-01-26 00:20:29.138998 | controller | [notice] A new release of pip available: 22.2.2 -> 25.3 2026-01-26 00:20:29.139052 | controller | [notice] To update, run: /home/zuul/venv/bin/python -m pip install --upgrade pip 2026-01-26 00:20:29.321789 | controller | ok: Runtime: 0:00:04.392702 2026-01-26 00:20:29.330699 | 2026-01-26 00:20:29.330787 | TASK [Install pytest-forked into virtualenv] 2026-01-26 00:20:30.900658 | controller | Collecting pytest-forked 2026-01-26 00:20:30.964145 | controller | Downloading pytest_forked-1.6.0-py3-none-any.whl (4.9 kB) 2026-01-26 00:20:31.033630 | controller | Collecting py 2026-01-26 00:20:31.039738 | controller | Downloading py-1.11.0-py2.py3-none-any.whl (98 kB) 2026-01-26 00:20:31.078320 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 98.7/98.7 kB 2.6 MB/s eta 0:00:00 2026-01-26 00:20:31.293659 | controller | Collecting pytest>=3.10 2026-01-26 00:20:31.298920 | controller | Downloading pytest-9.0.2-py3-none-any.whl (374 kB) 2026-01-26 00:20:31.329799 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 374.8/374.8 kB 14.5 MB/s eta 0:00:00 2026-01-26 00:20:31.437555 | controller | Collecting tomli>=1 2026-01-26 00:20:31.442681 | controller | Downloading tomli-2.4.0-py3-none-any.whl (14 kB) 2026-01-26 00:20:31.504946 | controller | Collecting pluggy<2,>=1.5 2026-01-26 00:20:31.510816 | controller | Downloading pluggy-1.6.0-py3-none-any.whl (20 kB) 2026-01-26 00:20:31.571401 | controller | Collecting exceptiongroup>=1 2026-01-26 00:20:31.578060 | controller | Downloading exceptiongroup-1.3.1-py3-none-any.whl (16 kB) 2026-01-26 00:20:31.667756 | controller | Collecting pygments>=2.7.2 2026-01-26 00:20:31.677652 | controller | Downloading pygments-2.19.2-py3-none-any.whl (1.2 MB) 2026-01-26 00:20:31.712137 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 1.2/1.2 MB 39.8 MB/s eta 0:00:00 2026-01-26 00:20:31.769881 | controller | Collecting iniconfig>=1.0.1 2026-01-26 00:20:31.775893 | controller | Downloading iniconfig-2.3.0-py3-none-any.whl (7.5 kB) 2026-01-26 00:20:31.868650 | controller | Collecting packaging>=22 2026-01-26 00:20:31.877086 | controller | Downloading packaging-26.0-py3-none-any.whl (74 kB) 2026-01-26 00:20:31.893780 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 74.4/74.4 kB 5.0 MB/s eta 0:00:00 2026-01-26 00:20:31.972915 | controller | Collecting typing-extensions>=4.6.0 2026-01-26 00:20:31.983256 | controller | Downloading typing_extensions-4.15.0-py3-none-any.whl (44 kB) 2026-01-26 00:20:31.999262 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 44.6/44.6 kB 2.9 MB/s eta 0:00:00 2026-01-26 00:20:32.142350 | controller | Installing collected packages: typing-extensions, tomli, pygments, py, pluggy, packaging, iniconfig, exceptiongroup, pytest, pytest-forked 2026-01-26 00:20:34.706581 | 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-01-26 00:20:34.711467 | controller | 2026-01-26 00:20:34.980270 | controller | [notice] A new release of pip available: 22.2.2 -> 25.3 2026-01-26 00:20:34.980335 | controller | [notice] To update, run: /home/zuul/venv/bin/python -m pip install --upgrade pip 2026-01-26 00:20:35.223754 | controller | ok: Runtime: 0:00:04.834931 2026-01-26 00:20:35.238492 | 2026-01-26 00:20:35.238641 | TASK [Update pip] 2026-01-26 00:20:36.680012 | controller | Requirement already satisfied: pip in ./venv/lib/python3.10/site-packages (22.2.2) 2026-01-26 00:20:36.903021 | controller | Collecting pip 2026-01-26 00:20:36.987452 | controller | Downloading pip-25.3-py3-none-any.whl (1.8 MB) 2026-01-26 00:20:37.073570 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 1.8/1.8 MB 22.0 MB/s eta 0:00:00 2026-01-26 00:20:37.206559 | controller | Installing collected packages: pip 2026-01-26 00:20:37.207199 | controller | Attempting uninstall: pip 2026-01-26 00:20:37.208499 | controller | Found existing installation: pip 22.2.2 2026-01-26 00:20:37.500571 | controller | Uninstalling pip-22.2.2: 2026-01-26 00:20:37.532977 | controller | Successfully uninstalled pip-22.2.2 2026-01-26 00:20:39.265325 | controller | Successfully installed pip-25.3 2026-01-26 00:20:39.631163 | controller | ok: Runtime: 0:00:03.371847 2026-01-26 00:20:39.639563 | 2026-01-26 00:20:39.639719 | TASK [Ensure pip temp directory exists in /var/tmp] 2026-01-26 00:20:40.705380 | controller | changed 2026-01-26 00:20:40.715538 | 2026-01-26 00:20:40.715634 | TASK [Install ansible into virtualenv] 2026-01-26 00:20:42.450258 | controller | Processing ./src/github.com/ansible/ansible 2026-01-26 00:20:42.458495 | controller | Installing build dependencies: started 2026-01-26 00:20:44.580287 | controller | Installing build dependencies: finished with status 'done' 2026-01-26 00:20:45.847798 | controller | Getting requirements to build wheel: started 2026-01-26 00:20:45.847893 | controller | Getting requirements to build wheel: finished with status 'done' 2026-01-26 00:20:45.850905 | controller | Preparing metadata (pyproject.toml): started 2026-01-26 00:20:46.837437 | controller | Preparing metadata (pyproject.toml): finished with status 'done' 2026-01-26 00:20:47.030753 | controller | Collecting jinja2 (from ansible-core==2.12.10.post0) 2026-01-26 00:20:47.153117 | controller | Downloading jinja2-3.1.6-py3-none-any.whl.metadata (2.9 kB) 2026-01-26 00:20:47.299121 | controller | Collecting PyYAML (from ansible-core==2.12.10.post0) 2026-01-26 00:20:47.304232 | 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-01-26 00:20:47.897793 | controller | Collecting cryptography (from ansible-core==2.12.10.post0) 2026-01-26 00:20:47.906821 | controller | Downloading cryptography-46.0.3-cp38-abi3-manylinux_2_34_x86_64.whl.metadata (5.7 kB) 2026-01-26 00:20:47.949665 | controller | Requirement already satisfied: packaging in ./venv/lib/python3.10/site-packages (from ansible-core==2.12.10.post0) (26.0) 2026-01-26 00:20:47.977175 | controller | Collecting resolvelib<0.6.0,>=0.5.3 (from ansible-core==2.12.10.post0) 2026-01-26 00:20:47.986315 | controller | Downloading resolvelib-0.5.4-py2.py3-none-any.whl.metadata (3.7 kB) 2026-01-26 00:20:48.308810 | controller | Collecting cffi>=2.0.0 (from cryptography->ansible-core==2.12.10.post0) 2026-01-26 00:20:48.317834 | controller | Downloading cffi-2.0.0-cp310-cp310-manylinux2014_x86_64.manylinux_2_17_x86_64.whl.metadata (2.6 kB) 2026-01-26 00:20:48.334855 | 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-01-26 00:20:48.363930 | controller | Collecting pycparser (from cffi>=2.0.0->cryptography->ansible-core==2.12.10.post0) 2026-01-26 00:20:48.370806 | controller | Downloading pycparser-3.0-py3-none-any.whl.metadata (8.2 kB) 2026-01-26 00:20:48.509433 | controller | Collecting MarkupSafe>=2.0 (from jinja2->ansible-core==2.12.10.post0) 2026-01-26 00:20:48.518579 | 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-01-26 00:20:48.533431 | controller | Downloading resolvelib-0.5.4-py2.py3-none-any.whl (12 kB) 2026-01-26 00:20:48.548790 | controller | Downloading cryptography-46.0.3-cp38-abi3-manylinux_2_34_x86_64.whl (4.5 MB) 2026-01-26 00:20:48.638254 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 4.5/4.5 MB 75.8 MB/s 0:00:00 2026-01-26 00:20:48.642855 | controller | Downloading cffi-2.0.0-cp310-cp310-manylinux2014_x86_64.manylinux_2_17_x86_64.whl (216 kB) 2026-01-26 00:20:48.660065 | controller | Downloading jinja2-3.1.6-py3-none-any.whl (134 kB) 2026-01-26 00:20:48.674462 | 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-01-26 00:20:48.690361 | controller | Downloading pycparser-3.0-py3-none-any.whl (48 kB) 2026-01-26 00:20:48.707889 | 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-01-26 00:20:48.731852 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 770.3/770.3 kB 33.4 MB/s 0:00:00 2026-01-26 00:20:48.751107 | controller | Building wheels for collected packages: ansible-core 2026-01-26 00:20:48.754853 | controller | Building wheel for ansible-core (pyproject.toml): started 2026-01-26 00:20:51.658565 | controller | Building wheel for ansible-core (pyproject.toml): finished with status 'done' 2026-01-26 00:20:51.660715 | controller | Created wheel for ansible-core: filename=ansible_core-2.12.10.post0-py3-none-any.whl size=2117520 sha256=c160f1ce887f2dd2db967529cad0707ff3f755ba996c71e5575e88c2f38f89e3 2026-01-26 00:20:51.660943 | controller | Stored in directory: /var/tmp/ansible-test-pip/pip-ephem-wheel-cache-urrcj2ux/wheels/68/27/b0/9b068d12b42b734c64dc438319298ad4c1c5027b13ccb3a660 2026-01-26 00:20:51.676054 | controller | Successfully built ansible-core 2026-01-26 00:20:51.747877 | controller | Installing collected packages: resolvelib, PyYAML, pycparser, MarkupSafe, jinja2, cffi, cryptography, ansible-core 2026-01-26 00:20:54.650494 | controller | 2026-01-26 00:20:54.654636 | controller | Successfully installed MarkupSafe-3.0.3 PyYAML-6.0.3 ansible-core-2.12.10.post0 cffi-2.0.0 cryptography-46.0.3 jinja2-3.1.6 pycparser-3.0 resolvelib-0.5.4 2026-01-26 00:20:55.619271 | controller | ok: Runtime: 0:00:13.413895 2026-01-26 00:20:55.634916 | 2026-01-26 00:20:55.635065 | TASK [Setup download-artifact-fork role] 2026-01-26 00:20:55.712616 | controller | ok 2026-01-26 00:20:55.738130 | 2026-01-26 00:20:55.738217 | TASK [download-artifact-fork : Parse inventory response] 2026-01-26 00:20:55.790946 | controller | ok 2026-01-26 00:20:55.797717 | 2026-01-26 00:20:55.797788 | TASK [download-artifact-fork : Fetch info from Zuul API] 2026-01-26 00:20:55.822356 | controller | skipping: Conditional result was False 2026-01-26 00:20:55.830574 | 2026-01-26 00:20:55.830643 | TASK [download-artifact-fork : Parse build response] 2026-01-26 00:20:55.855385 | controller | skipping: Conditional result was False 2026-01-26 00:20:55.862562 | 2026-01-26 00:20:55.862630 | TASK [download-artifact-fork : Ensure artifacts directory exists] 2026-01-26 00:20:56.751164 | controller | changed 2026-01-26 00:20:56.763782 | 2026-01-26 00:20:56.763921 | LOOP [download-artifact-fork : Download archive by type] 2026-01-26 00:20:56.801550 | controller | skipping: Conditional result was False 2026-01-26 00:20:56.802331 | 2026-01-26 00:20:58.902837 | controller | changed: OK (20209 bytes) 2026-01-26 00:20:58.931524 | 2026-01-26 00:20:58.931637 | TASK [deploy-artifacts : define __collections] 2026-01-26 00:20:58.954938 | controller | ok 2026-01-26 00:20:58.962173 | 2026-01-26 00:20:58.962259 | LOOP [deploy-artifacts : Create list of collections] 2026-01-26 00:20:58.984405 | controller | skipping: Conditional result was False 2026-01-26 00:20:58.994714 | controller | ok: 2026-01-26 00:20:58.994779 | controller | { 2026-01-26 00:20:58.994812 | controller | "branch": "main", 2026-01-26 00:20:58.994837 | controller | "job": "build-ansible-collection", 2026-01-26 00:20:58.994860 | controller | "metadata": { 2026-01-26 00:20:58.994883 | controller | "type": "ansible_collection", 2026-01-26 00:20:58.994905 | controller | "version": "5.0.0" 2026-01-26 00:20:58.994927 | controller | }, 2026-01-26 00:20:58.994949 | controller | "name": "ansible.network", 2026-01-26 00:20:58.994969 | controller | "project": "ansible-collections/ansible.network", 2026-01-26 00:20:58.994991 | controller | "url": "https://108c8d23af627e4d1393-4faa70ad57055de6f9b2bdd48f139317.ssl.cf2.rackcdn.com/ansible/90602f8673bc4ffaa5e8bce2f09adbe0/artifacts/ansible-network-5.0.0.tar.gz" 2026-01-26 00:20:58.995020 | controller | } 2026-01-26 00:20:59.003483 | 2026-01-26 00:20:59.003568 | TASK [deploy-artifacts : Install require-project collection using ansible-galaxy] 2026-01-26 00:21:00.569449 | controller | Starting galaxy collection install process 2026-01-26 00:21:00.569580 | controller | Process install dependency map 2026-01-26 00:21:13.386814 | controller | ERROR! Error when getting collection version metadata for ansible.utils:6.0.1 from default (https://galaxy.ansible.com/api/) (HTTP Code: 500, Message: Internal Server Error Code: Unknown) 2026-01-26 00:21:13.895948 | controller | ERROR 2026-01-26 00:21:13.896226 | controller | { 2026-01-26 00:21:13.896267 | controller | "delta": "0:00:13.912252", 2026-01-26 00:21:13.896296 | controller | "end": "2026-01-26 00:21:13.643718", 2026-01-26 00:21:13.896322 | controller | "msg": "non-zero return code", 2026-01-26 00:21:13.896352 | controller | "rc": 1, 2026-01-26 00:21:13.896374 | controller | "start": "2026-01-26 00:20:59.731466" 2026-01-26 00:21:13.896422 | controller | } failure 2026-01-26 00:21:13.898737 | 2026-01-26 00:21:13.898788 | PLAY RECAP 2026-01-26 00:21:13.898836 | controller | ok: 24 changed: 13 unreachable: 0 failed: 1 skipped: 6 rescued: 0 ignored: 0 2026-01-26 00:21:13.898855 | 2026-01-26 00:21:14.076521 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master] 2026-01-26 00:21:14.079015 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2026-01-26 00:21:14.685606 | 2026-01-26 00:21:14.685796 | PLAY [all] 2026-01-26 00:21:14.710006 | 2026-01-26 00:21:14.710128 | TASK [fetch-subunit-output : Find stestr or testr executable] 2026-01-26 00:21:16.585819 | controller | changed: non-zero return code 2026-01-26 00:21:16.592001 | 2026-01-26 00:21:16.592102 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2026-01-26 00:21:16.617294 | controller | skipping: Conditional result was False 2026-01-26 00:21:16.624052 | 2026-01-26 00:21:16.624125 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2026-01-26 00:21:16.656293 | 2026-01-26 00:21:16.656455 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2026-01-26 00:21:16.690062 | 2026-01-26 00:21:16.690277 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2026-01-26 00:21:16.716197 | controller | skipping: Conditional result was False 2026-01-26 00:21:16.730753 | 2026-01-26 00:21:16.730855 | LOOP [fetch-subunit-output : Generate subunit file] 2026-01-26 00:21:16.770166 | 2026-01-26 00:21:16.770495 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2026-01-26 00:21:16.809114 | controller | skipping: Conditional result was False 2026-01-26 00:21:16.825194 | 2026-01-26 00:21:16.825379 | TASK [fetch-subunit-output : Remove the temporary file] 2026-01-26 00:21:16.852959 | controller | skipping: Conditional result was False 2026-01-26 00:21:16.869495 | 2026-01-26 00:21:16.869783 | TASK [fetch-subunit-output : Process and fetch subunit results] 2026-01-26 00:21:16.896848 | controller | skipping: Conditional result was False 2026-01-26 00:21:16.936248 | 2026-01-26 00:21:16.936386 | PLAY RECAP 2026-01-26 00:21:16.936445 | controller | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2026-01-26 00:21:16.936474 | 2026-01-26 00:21:17.055463 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2026-01-26 00:21:17.056794 | POST-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/post.yaml@master] 2026-01-26 00:21:17.631504 | 2026-01-26 00:21:17.631626 | PLAY [all:!appliance*] 2026-01-26 00:21:17.663706 | 2026-01-26 00:21:17.663853 | TASK [unregister the node] 2026-01-26 00:21:18.017388 | controller | skipping: Conditional result was False 2026-01-26 00:21:18.031903 | 2026-01-26 00:21:18.032075 | TASK [include_role : fetch-output] 2026-01-26 00:21:18.089766 | controller | ok 2026-01-26 00:21:18.128560 | 2026-01-26 00:21:18.128704 | TASK [fetch-output : Set log path for multiple nodes] 2026-01-26 00:21:18.205697 | controller | skipping: Conditional result was False 2026-01-26 00:21:18.219209 | 2026-01-26 00:21:18.219358 | TASK [fetch-output : Set log path for single node] 2026-01-26 00:21:18.278979 | controller | ok 2026-01-26 00:21:18.292789 | 2026-01-26 00:21:18.292964 | LOOP [fetch-output : Ensure local output dirs] 2026-01-26 00:21:18.782470 | controller -> localhost | ok: "/var/lib/zuul/builds/2ca4c207236849c1a15f188a1a06b9e0/work/logs" 2026-01-26 00:21:19.070375 | controller -> localhost | changed: "/var/lib/zuul/builds/2ca4c207236849c1a15f188a1a06b9e0/work/artifacts" 2026-01-26 00:21:19.296280 | controller -> localhost | changed: "/var/lib/zuul/builds/2ca4c207236849c1a15f188a1a06b9e0/work/docs" 2026-01-26 00:21:19.312609 | 2026-01-26 00:21:19.312764 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-01-26 00:21:21.610984 | controller | changed: 2026-01-26 00:21:21.611319 | controller | .d..t...... ./ 2026-01-26 00:21:21.611378 | controller | cd+++++++++ controller/ 2026-01-26 00:21:21.611441 | controller | changed: All items complete 2026-01-26 00:21:21.611484 | 2026-01-26 00:21:23.653205 | controller | changed: .d..t...... ./ 2026-01-26 00:21:25.742381 | controller | changed: .d..t...... ./ 2026-01-26 00:21:25.773363 | 2026-01-26 00:21:25.773566 | TASK [include_role : fetch-output-openshift] 2026-01-26 00:21:25.799745 | controller | skipping: Conditional result was False 2026-01-26 00:21:25.809724 | 2026-01-26 00:21:25.809815 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-01-26 00:21:25.871119 | controller | skipping: Conditional result was False 2026-01-26 00:21:25.883861 | controller | skipping: Conditional result was False 2026-01-26 00:21:25.935768 | 2026-01-26 00:21:25.935909 | PLAY [localhost] 2026-01-26 00:21:25.953961 | 2026-01-26 00:21:25.954096 | TASK [Run Zuul manifest role] 2026-01-26 00:21:25.975253 | localhost | ok 2026-01-26 00:21:25.996203 | 2026-01-26 00:21:25.996318 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-01-26 00:21:26.388782 | localhost | changed 2026-01-26 00:21:26.398864 | 2026-01-26 00:21:26.398993 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-01-26 00:21:26.436775 | localhost | ok 2026-01-26 00:21:26.456099 | 2026-01-26 00:21:26.456226 | TASK [Set zuul-log-path fact] 2026-01-26 00:21:26.512903 | localhost | ok 2026-01-26 00:21:26.528943 | 2026-01-26 00:21:26.529020 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-01-26 00:21:26.570314 | localhost | ok 2026-01-26 00:21:26.581609 | 2026-01-26 00:21:26.581731 | LOOP [Run upload-logs-swift role] 2026-01-26 00:21:26.628990 | localhost | Output suppressed because no_log was given 2026-01-26 00:21:26.673830 | 2026-01-26 00:21:26.673985 | TASK [Set zuul-log-path fact] 2026-01-26 00:21:26.699384 | localhost | skipping: Conditional result was False 2026-01-26 00:21:26.706021 | 2026-01-26 00:21:26.706112 | TASK [upload-logs-swift : Ensure logs are readable before uploading] 2026-01-26 00:21:27.058158 | localhost -> localhost | ok: Runtime: 0:00:00.005590 2026-01-26 00:21:27.065420 | 2026-01-26 00:21:27.065504 | TASK [upload-logs-swift : Upload logs to swift]