2026-01-22 00:21:53.313616 | Job console starting... 2026-01-22 00:21:53.330530 | Updating repositories 2026-01-22 00:21:53.473404 | Preparing job workspace 2026-01-22 00:22:00.549018 | Running Ansible setup... 2026-01-22 00:22:08.030358 | PRE-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master] 2026-01-22 00:22:08.740782 | 2026-01-22 00:22:08.740922 | PLAY [localhost] 2026-01-22 00:22:08.749981 | 2026-01-22 00:22:08.750097 | TASK [Gathering Facts] 2026-01-22 00:22:09.930290 | localhost | ok 2026-01-22 00:22:09.948051 | 2026-01-22 00:22:09.948644 | TASK [Setup log path fact] 2026-01-22 00:22:09.970409 | localhost | ok 2026-01-22 00:22:09.986760 | 2026-01-22 00:22:09.987119 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-01-22 00:22:10.019283 | localhost | ok 2026-01-22 00:22:10.034589 | 2026-01-22 00:22:10.034771 | TASK [emit-job-header : Print job information] 2026-01-22 00:22:10.080344 | # Job Information 2026-01-22 00:22:10.080703 | Ansible Version: 2.15.12 2026-01-22 00:22:10.080910 | Job: ansible-test-sanity-docker-stable-2.9 2026-01-22 00:22:10.082225 | Pipeline: periodic 2026-01-22 00:22:10.082264 | Executor: ze03.softwarefactory-project.io 2026-01-22 00:22:10.082293 | Triggered by: https://github.com/ansible-collections/ansible.network 2026-01-22 00:22:10.082323 | Log URL (when completed): https://ansible.softwarefactory-project.io/logs/ce6/ansible/ce6d637e498e4dac80722e4056b55d9f/ 2026-01-22 00:22:10.082352 | Event ID: 332d988a0e394ca48f64b1f395ee0e08 2026-01-22 00:22:10.087781 | 2026-01-22 00:22:10.087873 | LOOP [emit-job-header : Print node information] 2026-01-22 00:22:10.232927 | localhost | ok: 2026-01-22 00:22:10.233165 | localhost | # Node Information 2026-01-22 00:22:10.233201 | localhost | Inventory Hostname: controller 2026-01-22 00:22:10.233229 | localhost | Hostname: np0005591453 2026-01-22 00:22:10.233255 | localhost | Username: zuul 2026-01-22 00:22:10.233283 | localhost | Distro: Fedora 37 2026-01-22 00:22:10.233305 | localhost | Provider: ansible-vexxhost-ca-ymq-1 2026-01-22 00:22:10.233324 | localhost | Region: ca-ymq-1 2026-01-22 00:22:10.233340 | localhost | Label: ansible-fedora-37-1vcpu 2026-01-22 00:22:10.233356 | localhost | Product Name: OpenStack Nova 2026-01-22 00:22:10.233372 | localhost | Interface IP: 162.253.55.43 2026-01-22 00:22:10.250006 | 2026-01-22 00:22:10.250143 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-01-22 00:22:10.822623 | localhost -> localhost | changed 2026-01-22 00:22:10.829899 | 2026-01-22 00:22:10.829984 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-01-22 00:22:11.948169 | localhost -> localhost | changed 2026-01-22 00:22:11.972342 | 2026-01-22 00:22:11.972474 | PLAY [all:!appliance*] 2026-01-22 00:22:11.991122 | 2026-01-22 00:22:11.991295 | TASK [include_role : start-zuul-console] 2026-01-22 00:22:12.028074 | controller | ok 2026-01-22 00:22:12.045948 | 2026-01-22 00:22:12.046094 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-01-22 00:22:12.435371 | controller | ok 2026-01-22 00:22:12.450175 | 2026-01-22 00:22:12.450321 | TASK [use-our-mirror : Retrieve the IP address] 2026-01-22 00:22:13.671549 | controller | ok: Status code was 404 and not [200]: HTTP Error 404: Not Found 2026-01-22 00:22:13.694975 | 2026-01-22 00:22:13.695135 | TASK [use-our-mirror : Ensure the registry service is running on the mirror] 2026-01-22 00:22:14.245947 | controller | skipping: Conditional result was False 2026-01-22 00:22:14.255588 | 2026-01-22 00:22:14.255752 | TASK [use-our-mirror : Write the IP in /etc/hosts] 2026-01-22 00:22:14.326352 | controller | skipping: Conditional result was False 2026-01-22 00:22:14.336561 | 2026-01-22 00:22:14.336760 | TASK [use-our-mirror : Create the podman configuration directory] 2026-01-22 00:22:14.370946 | controller | skipping: Conditional result was False 2026-01-22 00:22:14.387698 | 2026-01-22 00:22:14.387889 | TASK [use-our-mirror : Copy the podman configuration] 2026-01-22 00:22:14.434410 | controller | skipping: Conditional result was False 2026-01-22 00:22:14.443311 | 2026-01-22 00:22:14.443438 | TASK [use-our-mirror : Copy the Yum configuration (main)] 2026-01-22 00:22:14.498847 | controller | skipping: Conditional result was False 2026-01-22 00:22:14.510347 | 2026-01-22 00:22:14.510481 | TASK [use-our-mirror : Copy the Yum configuration (update)] 2026-01-22 00:22:14.565379 | controller | skipping: Conditional result was False 2026-01-22 00:22:14.581775 | 2026-01-22 00:22:14.581883 | TASK [Disable Fedora Modular] 2026-01-22 00:22:14.901911 | controller | changed 2026-01-22 00:22:14.912889 | 2026-01-22 00:22:14.913020 | TASK [Enable EPEL] 2026-01-22 00:22:14.938000 | controller | skipping: Conditional result was False 2026-01-22 00:22:14.948255 | 2026-01-22 00:22:14.948427 | TASK [Register the RHEL node] 2026-01-22 00:22:15.510869 | 2026-01-22 00:22:15.511858 | TASK [Show the subscription-manager status] 2026-01-22 00:22:16.121332 | controller | skipping: Conditional result was False 2026-01-22 00:22:16.128322 | 2026-01-22 00:22:16.128395 | TASK [Enable EPEL on RHEL] 2026-01-22 00:22:16.697314 | controller | skipping: Conditional result was False 2026-01-22 00:22:16.705885 | 2026-01-22 00:22:16.705982 | TASK [Install git and tox] 2026-01-22 00:23:46.119965 | controller | changed 2026-01-22 00:23:46.128987 | 2026-01-22 00:23:46.129164 | TASK [include_role : prepare-workspace] 2026-01-22 00:23:46.163209 | controller | ok 2026-01-22 00:23:46.186953 | 2026-01-22 00:23:46.187109 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-01-22 00:23:46.498106 | controller | ok 2026-01-22 00:23:46.530846 | 2026-01-22 00:23:46.531042 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-01-22 00:29:51.085125 | controller | Output suppressed because no_log was given 2026-01-22 00:29:51.110262 | 2026-01-22 00:29:51.110354 | TASK [include_role : prepare-workspace-openshift] 2026-01-22 00:29:51.136502 | controller | skipping: Conditional result was False 2026-01-22 00:29:51.172647 | 2026-01-22 00:29:51.172819 | PLAY [all:!appliance] 2026-01-22 00:29:51.195750 | 2026-01-22 00:29:51.195861 | TASK [Run add-build-sshkey role (RSA)] 2026-01-22 00:29:51.229058 | controller | ok 2026-01-22 00:29:51.257968 | 2026-01-22 00:29:51.258091 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-01-22 00:29:51.503687 | controller -> localhost | ok 2026-01-22 00:29:51.513605 | 2026-01-22 00:29:51.513767 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-01-22 00:29:51.547422 | controller | ok 2026-01-22 00:29:51.639636 | controller | included: /var/lib/zuul/builds/ce6d637e498e4dac80722e4056b55d9f/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-01-22 00:29:51.654204 | 2026-01-22 00:29:51.654366 | TASK [add-build-sshkey : Create Temp SSH key] 2026-01-22 00:29:52.253860 | controller -> localhost | Generating public/private rsa key pair. 2026-01-22 00:29:52.254094 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/ce6d637e498e4dac80722e4056b55d9f/work/ce6d637e498e4dac80722e4056b55d9f_id_rsa. 2026-01-22 00:29:52.254122 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/ce6d637e498e4dac80722e4056b55d9f/work/ce6d637e498e4dac80722e4056b55d9f_id_rsa.pub. 2026-01-22 00:29:52.254143 | controller -> localhost | The key fingerprint is: 2026-01-22 00:29:52.254162 | controller -> localhost | SHA256:1mH+c8HEXYd189VSFlLWCCb4nxHEcvEx1s9fpN9SAPM zuul-build-sshkey 2026-01-22 00:29:52.254181 | controller -> localhost | The key's randomart image is: 2026-01-22 00:29:52.254200 | controller -> localhost | +---[RSA 2048]----+ 2026-01-22 00:29:52.254218 | controller -> localhost | | ..oB==@%| 2026-01-22 00:29:52.254237 | controller -> localhost | | . .o+*B=%| 2026-01-22 00:29:52.254255 | controller -> localhost | | .oo .E*+| 2026-01-22 00:29:52.254273 | controller -> localhost | | +...o. =| 2026-01-22 00:29:52.254290 | controller -> localhost | | S o. ooo+| 2026-01-22 00:29:52.254321 | controller -> localhost | | . .o ..+| 2026-01-22 00:29:52.254344 | controller -> localhost | | o .. | 2026-01-22 00:29:52.254364 | controller -> localhost | | o | 2026-01-22 00:29:52.254382 | controller -> localhost | | | 2026-01-22 00:29:52.254403 | controller -> localhost | +----[SHA256]-----+ 2026-01-22 00:29:52.254449 | controller -> localhost | ok: Runtime: 0:00:00.096919 2026-01-22 00:29:52.261347 | 2026-01-22 00:29:52.261419 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-01-22 00:29:52.297223 | controller | ok 2026-01-22 00:29:52.311008 | controller | included: /var/lib/zuul/builds/ce6d637e498e4dac80722e4056b55d9f/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-01-22 00:29:52.322749 | 2026-01-22 00:29:52.322856 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-01-22 00:29:52.350697 | controller | skipping: Conditional result was False 2026-01-22 00:29:52.359195 | 2026-01-22 00:29:52.359324 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-01-22 00:29:53.013992 | controller | changed 2026-01-22 00:29:53.030954 | 2026-01-22 00:29:53.031368 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-01-22 00:29:53.278643 | controller | ok 2026-01-22 00:29:53.284536 | 2026-01-22 00:29:53.284609 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-01-22 00:29:53.992391 | controller | changed 2026-01-22 00:29:53.999019 | 2026-01-22 00:29:53.999122 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-01-22 00:29:54.673266 | controller | changed 2026-01-22 00:29:54.691274 | 2026-01-22 00:29:54.691567 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-01-22 00:29:54.719467 | controller | skipping: Conditional result was False 2026-01-22 00:29:54.727125 | 2026-01-22 00:29:54.727231 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-01-22 00:29:55.192776 | controller -> localhost | changed 2026-01-22 00:29:55.206323 | 2026-01-22 00:29:55.206442 | TASK [add-build-sshkey : Add back temp key] 2026-01-22 00:29:55.541431 | controller -> localhost | Identity added: /var/lib/zuul/builds/ce6d637e498e4dac80722e4056b55d9f/work/ce6d637e498e4dac80722e4056b55d9f_id_rsa (zuul-build-sshkey) 2026-01-22 00:29:55.541858 | controller -> localhost | ok: Runtime: 0:00:00.017679 2026-01-22 00:29:55.592864 | 2026-01-22 00:29:55.593003 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-01-22 00:29:55.927176 | controller | ok 2026-01-22 00:29:55.937799 | 2026-01-22 00:29:55.937925 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-01-22 00:29:55.963925 | controller | skipping: Conditional result was False 2026-01-22 00:29:55.982671 | 2026-01-22 00:29:55.982863 | TASK [Run add-build-sshkey role (ECDSA)] 2026-01-22 00:29:56.017019 | controller | ok 2026-01-22 00:29:56.041647 | 2026-01-22 00:29:56.041841 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-01-22 00:29:56.308531 | controller -> localhost | ok 2026-01-22 00:29:56.323434 | 2026-01-22 00:29:56.323575 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-01-22 00:29:56.353524 | controller | ok 2026-01-22 00:29:56.373977 | controller | included: /var/lib/zuul/builds/ce6d637e498e4dac80722e4056b55d9f/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-01-22 00:29:56.383291 | 2026-01-22 00:29:56.383399 | TASK [add-build-sshkey : Create Temp SSH key] 2026-01-22 00:29:56.711195 | controller -> localhost | Generating public/private ecdsa key pair. 2026-01-22 00:29:56.711474 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/ce6d637e498e4dac80722e4056b55d9f/work/ce6d637e498e4dac80722e4056b55d9f_id_ecdsa. 2026-01-22 00:29:56.711519 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/ce6d637e498e4dac80722e4056b55d9f/work/ce6d637e498e4dac80722e4056b55d9f_id_ecdsa.pub. 2026-01-22 00:29:56.711565 | controller -> localhost | The key fingerprint is: 2026-01-22 00:29:56.711596 | controller -> localhost | SHA256:cYrWPg+iiPWBO1wcuA0cqBK8c4FV6IZH413Qk36Ejys zuul-build-sshkey 2026-01-22 00:29:56.711625 | controller -> localhost | The key's randomart image is: 2026-01-22 00:29:56.711679 | controller -> localhost | +---[ECDSA 521]---+ 2026-01-22 00:29:56.711717 | controller -> localhost | |..o.ooo o | 2026-01-22 00:29:56.711781 | controller -> localhost | |oo.= * . | 2026-01-22 00:29:56.711810 | controller -> localhost | |.o*o+ o * . | 2026-01-22 00:29:56.711836 | controller -> localhost | |o++*.. = * | 2026-01-22 00:29:56.711862 | controller -> localhost | |. == .o S | 2026-01-22 00:29:56.711888 | controller -> localhost | | ..+E o | 2026-01-22 00:29:56.711914 | controller -> localhost | | .o.. o + | 2026-01-22 00:29:56.711940 | controller -> localhost | | oo+ o . + | 2026-01-22 00:29:56.711986 | controller -> localhost | |. o.o . | 2026-01-22 00:29:56.712012 | controller -> localhost | +----[SHA256]-----+ 2026-01-22 00:29:56.712077 | controller -> localhost | ok: Runtime: 0:00:00.009318 2026-01-22 00:29:56.723371 | 2026-01-22 00:29:56.723490 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-01-22 00:29:56.758624 | controller | ok 2026-01-22 00:29:56.771333 | controller | included: /var/lib/zuul/builds/ce6d637e498e4dac80722e4056b55d9f/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-01-22 00:29:56.786565 | 2026-01-22 00:29:56.786693 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-01-22 00:29:56.824677 | controller | skipping: Conditional result was False 2026-01-22 00:29:56.841904 | 2026-01-22 00:29:56.842144 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-01-22 00:29:57.136416 | controller | changed 2026-01-22 00:29:57.145051 | 2026-01-22 00:29:57.145225 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-01-22 00:29:57.374829 | controller | ok 2026-01-22 00:29:57.384288 | 2026-01-22 00:29:57.384441 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-01-22 00:29:58.022433 | controller | changed 2026-01-22 00:29:58.028855 | 2026-01-22 00:29:58.028951 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-01-22 00:29:58.662942 | controller | changed 2026-01-22 00:29:58.674592 | 2026-01-22 00:29:58.674768 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-01-22 00:29:58.694141 | controller | skipping: Conditional result was False 2026-01-22 00:29:58.704969 | 2026-01-22 00:29:58.705108 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-01-22 00:29:59.024945 | controller -> localhost | changed 2026-01-22 00:29:59.038448 | 2026-01-22 00:29:59.038576 | TASK [add-build-sshkey : Add back temp key] 2026-01-22 00:29:59.363840 | controller -> localhost | Identity added: /var/lib/zuul/builds/ce6d637e498e4dac80722e4056b55d9f/work/ce6d637e498e4dac80722e4056b55d9f_id_ecdsa (zuul-build-sshkey) 2026-01-22 00:29:59.364123 | controller -> localhost | ok: Runtime: 0:00:00.013981 2026-01-22 00:29:59.371184 | 2026-01-22 00:29:59.371308 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-01-22 00:29:59.579204 | controller | ok 2026-01-22 00:29:59.585981 | 2026-01-22 00:29:59.586300 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-01-22 00:29:59.611050 | controller | skipping: Conditional result was False 2026-01-22 00:29:59.631365 | 2026-01-22 00:29:59.631497 | TASK [include_role : remove-zuul-sshkey] 2026-01-22 00:29:59.656061 | controller | skipping: Conditional result was False 2026-01-22 00:29:59.662504 | 2026-01-22 00:29:59.662574 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-01-22 00:29:59.881681 | controller | ok: "logs" 2026-01-22 00:29:59.882154 | controller | ok: All items complete 2026-01-22 00:29:59.882216 | 2026-01-22 00:30:00.071367 | controller | ok: "artifacts" 2026-01-22 00:30:00.284554 | controller | ok: "docs" 2026-01-22 00:30:00.296843 | 2026-01-22 00:30:00.297010 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-01-22 00:30:00.553917 | controller | changed: "logs" 2026-01-22 00:30:00.741980 | controller | changed: "artifacts" 2026-01-22 00:30:00.928345 | controller | changed: "docs" 2026-01-22 00:30:00.982499 | 2026-01-22 00:30:00.982608 | PLAY RECAP 2026-01-22 00:30:00.982661 | controller | ok: 30 changed: 16 unreachable: 0 failed: 0 skipped: 18 rescued: 0 ignored: 0 2026-01-22 00:30:00.982707 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-01-22 00:30:00.982757 | 2026-01-22 00:30:01.125621 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master] 2026-01-22 00:30:01.126648 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2026-01-22 00:30:01.781286 | 2026-01-22 00:30:01.781433 | PLAY [all] 2026-01-22 00:30:01.810680 | 2026-01-22 00:30:01.810835 | TASK [Install binary dependencies] 2026-01-22 00:30:01.877628 | controller | ok 2026-01-22 00:30:01.902908 | 2026-01-22 00:30:01.903056 | TASK [bindep : Include find tasks] 2026-01-22 00:30:01.947281 | controller | ok 2026-01-22 00:30:01.959127 | controller | included: /var/lib/zuul/builds/ce6d637e498e4dac80722e4056b55d9f/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find.yaml 2026-01-22 00:30:01.966435 | 2026-01-22 00:30:01.966550 | TASK [bindep : Look for bindep.txt] 2026-01-22 00:30:02.540283 | controller | ok 2026-01-22 00:30:02.549556 | 2026-01-22 00:30:02.549677 | TASK [bindep : Define bindep_file fact] 2026-01-22 00:30:02.584817 | controller | skipping: Conditional result was False 2026-01-22 00:30:02.591361 | 2026-01-22 00:30:02.591482 | TASK [bindep : Look for other-requirements.txt] 2026-01-22 00:30:02.797661 | controller | ok 2026-01-22 00:30:02.805883 | 2026-01-22 00:30:02.805967 | TASK [bindep : Define bindep_file fact] 2026-01-22 00:30:02.831599 | controller | skipping: Conditional result was False 2026-01-22 00:30:02.840154 | 2026-01-22 00:30:02.840244 | TASK [bindep : Look for bindep fallback file] 2026-01-22 00:30:02.874210 | controller | skipping: Conditional result was False 2026-01-22 00:30:02.886304 | 2026-01-22 00:30:02.886503 | TASK [bindep : Define bindep_file fact] 2026-01-22 00:30:02.914152 | controller | skipping: Conditional result was False 2026-01-22 00:30:02.924768 | 2026-01-22 00:30:02.924935 | TASK [bindep : Include bindep tasks] 2026-01-22 00:30:02.952333 | controller | skipping: Conditional result was False 2026-01-22 00:30:02.962614 | 2026-01-22 00:30:02.962773 | TASK [bindep : Include install tasks] 2026-01-22 00:30:02.989620 | controller | skipping: Conditional result was False 2026-01-22 00:30:02.998230 | 2026-01-22 00:30:02.998364 | LOOP [bindep : Include package tasks] 2026-01-22 00:30:03.062977 | 2026-01-22 00:30:03.063196 | TASK [Run test-setup role] 2026-01-22 00:30:03.090890 | controller | ok 2026-01-22 00:30:03.111573 | 2026-01-22 00:30:03.111702 | TASK [test-setup : Check if project's tools/test-setup.sh exists] 2026-01-22 00:30:03.309564 | controller | ok 2026-01-22 00:30:03.321951 | 2026-01-22 00:30:03.322102 | TASK [test-setup : Run tools/test-setup.sh] 2026-01-22 00:30:03.872561 | controller | skipping: Conditional result was False 2026-01-22 00:30:03.931922 | 2026-01-22 00:30:03.932059 | PLAY RECAP 2026-01-22 00:30:03.932115 | controller | ok: 4 changed: 0 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2026-01-22 00:30:03.932144 | 2026-01-22 00:30:04.075923 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2026-01-22 00:30:04.077123 | PRE-RUN START: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master] 2026-01-22 00:30:04.764036 | 2026-01-22 00:30:04.764178 | PLAY [controller] 2026-01-22 00:30:04.786577 | 2026-01-22 00:30:04.786868 | TASK [Create the /root directory] 2026-01-22 00:30:05.390761 | controller | ok 2026-01-22 00:30:05.398268 | 2026-01-22 00:30:05.398405 | TASK [Install glibc-langpack-en] 2026-01-22 00:30:09.435143 | controller | ok: Nothing to do 2026-01-22 00:30:09.441184 | 2026-01-22 00:30:09.441268 | TASK [Ensure controller directory exists] 2026-01-22 00:30:09.656639 | controller | changed 2026-01-22 00:30:09.663319 | 2026-01-22 00:30:09.663480 | TASK [Install container runtime] 2026-01-22 00:30:09.716366 | controller | ok 2026-01-22 00:30:09.761913 | 2026-01-22 00:30:09.762081 | LOOP [ensure-docker : Gather variables for each operating system] 2026-01-22 00:30:09.798443 | controller | ok: "/var/lib/zuul/builds/ce6d637e498e4dac80722e4056b55d9f/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/vars/fedora.yaml" 2026-01-22 00:30:09.815663 | 2026-01-22 00:30:09.815804 | TASK [ensure-docker : Sanity check for distro selection] 2026-01-22 00:30:09.831030 | controller | skipping: Conditional result was False 2026-01-22 00:30:09.838389 | 2026-01-22 00:30:09.838500 | TASK [ensure-docker : Sanity check for distro install] 2026-01-22 00:30:09.862981 | controller | skipping: Conditional result was False 2026-01-22 00:30:09.869608 | 2026-01-22 00:30:09.869807 | TASK [ensure-docker : Sanity check for upstream install] 2026-01-22 00:30:09.894213 | controller | skipping: Conditional result was False 2026-01-22 00:30:09.905275 | 2026-01-22 00:30:09.905429 | TASK [ensure-docker : Sanity check for upstream install mirrors] 2026-01-22 00:30:09.931611 | controller | skipping: Conditional result was False 2026-01-22 00:30:09.941067 | 2026-01-22 00:30:09.941186 | TASK [ensure-docker : Include tasks for docker install] 2026-01-22 00:30:09.964870 | controller | ok 2026-01-22 00:30:09.977715 | controller | included: /var/lib/zuul/builds/ce6d637e498e4dac80722e4056b55d9f/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/tasks/docker-upstream.yaml 2026-01-22 00:30:09.990037 | 2026-01-22 00:30:09.990165 | TASK [ensure-docker : Not Implemented] 2026-01-22 00:30:10.006171 | controller | skipping: Conditional result was False 2026-01-22 00:30:10.016477 | 2026-01-22 00:30:10.016665 | TASK [Set up docker mirrors] 2026-01-22 00:30:10.052217 | controller | ok 2026-01-22 00:30:10.083407 | 2026-01-22 00:30:10.083565 | TASK [use-docker-mirror : Set docker_mirror fact from mirror_info] 2026-01-22 00:30:10.109483 | controller | skipping: Conditional result was False 2026-01-22 00:30:10.116889 | 2026-01-22 00:30:10.117010 | TASK [use-docker-mirror : Set docker_mirror fact from zuul_site_mirror_info] 2026-01-22 00:30:10.141980 | controller | skipping: Conditional result was False 2026-01-22 00:30:10.148787 | 2026-01-22 00:30:10.148880 | TASK [use-docker-mirror : Set docker_mirror fact] 2026-01-22 00:30:10.182711 | controller | skipping: Conditional result was False 2026-01-22 00:30:10.198051 | 2026-01-22 00:30:10.198256 | TASK [use-docker-mirror : Set up docker mirrors] 2026-01-22 00:30:10.234570 | controller | skipping: Conditional result was False 2026-01-22 00:30:10.260526 | 2026-01-22 00:30:10.260647 | TASK [Add all repositories] 2026-01-22 00:30:10.281581 | controller | ok 2026-01-22 00:30:10.310537 | 2026-01-22 00:30:10.310684 | LOOP [ensure-package-repositories : Install repositories] 2026-01-22 00:30:10.339339 | controller | ok: "/var/lib/zuul/builds/ce6d637e498e4dac80722e4056b55d9f/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-package-repositories/tasks/RedHat.yaml" 2026-01-22 00:30:10.339587 | controller | ok: All items complete 2026-01-22 00:30:10.339614 | 2026-01-22 00:30:10.387834 | controller | included: /var/lib/zuul/builds/ce6d637e498e4dac80722e4056b55d9f/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-package-repositories/tasks/RedHat.yaml 2026-01-22 00:30:10.394906 | 2026-01-22 00:30:10.395022 | TASK [ensure-package-repositories : Create GPG key temp directory] 2026-01-22 00:30:10.726295 | controller | changed 2026-01-22 00:30:10.736711 | 2026-01-22 00:30:10.736850 | LOOP [ensure-package-repositories : Upload GPG keys] 2026-01-22 00:30:11.704499 | controller | changed: 2026-01-22 00:30:11.704805 | controller | { 2026-01-22 00:30:11.704839 | controller | "data": "-----BEGIN PGP PUBLIC KEY BLOCK-----\n\nmQINBFit5IEBEADDt86QpYKz5flnCsOyZ/fk3WwBKxfDjwHf/GIflo+4GWAXS7wJ\n1PSzPsvSDATV10J44i5WQzh99q+lZvFCVRFiNhRmlmcXG+rk1QmDh3fsCCj9Q/yP\nw8jn3Hx0zDtz8PIB/18ReftYJzUo34COLiHn8WiY20uGCF2pjdPgfxE+K454c4G7\ngKFqVUFYgPug2CS0quaBB5b0rpFUdzTeI5RCStd27nHCpuSDCvRYAfdv+4Y1yiVh\nKKdoe3Smj+RnXeVMgDxtH9FJibZ3DK7WnMN2yeob6VqXox+FvKYJCCLkbQgQmE50\nuVK0uN71A1mQDcTRKQ2q3fFGlMTqJbbzr3LwnCBE6hV0a36t+DABtZTmz5O69xdJ\nWGdBeePCnWVqtDb/BdEYz7hPKskcZBarygCCe2Xi7sZieoFZuq6ltPoCsdfEdfbO\n+VBVKJnExqNZCcFUTEnbH4CldWROOzMS8BGUlkGpa59Sl1t0QcmWlw1EbkeMQNrN\nspdR8lobcdNS9bpAJQqSHRZh3cAM9mA3Yq/bssUS/P2quRXLjJ9mIv3dky9C3udM\n+q2unvnbNpPtIUly76FJ3s8g8sHeOnmYcKqNGqHq2Q3kMdA2eIbI0MqfOIo2+Xk0\nrNt3ctq3g+cQiorcN3rdHPsTRSAcp+NCz1QF9TwXYtH1XV24A6QMO0+CZwARAQAB\ntCtEb2NrZXIgUmVsZWFzZSAoQ0UgcnBtKSA8ZG9ja2VyQGRvY2tlci5jb20+iQI3\nBBMBCgAhBQJYrep4AhsvBQsJCAcDBRUKCQgLBRYCAwEAAh4BAheAAAoJEMUv62ti\nHp816C0P/iP+1uhSa6Qq3TIc5sIFE5JHxOO6y0R97cUdAmCbEqBiJHUPNQDQaaRG\nVYBm0K013Q1gcJeUJvS32gthmIvhkstw7KTodwOM8Kl11CCqZ07NPFef1b2SaJ7l\nTYpyUsT9+e343ph+O4C1oUQw6flaAJe+8ATCmI/4KxfhIjD2a/Q1voR5tUIxfexC\n/LZTx05gyf2mAgEWlRm/cGTStNfqDN1uoKMlV+WFuB1j2oTUuO1/dr8mL+FgZAM3\nntWFo9gQCllNV9ahYOON2gkoZoNuPUnHsf4Bj6BQJnIXbAhMk9H2sZzwUi9bgObZ\nXO8+OrP4D4B9kCAKqqaQqA+O46LzO2vhN74lm/Fy6PumHuviqDBdN+HgtRPMUuao\nxnuVJSvBu9sPdgT/pR1N9u/KnfAnnLtR6g+fx4mWz+ts/riB/KRHzXd+44jGKZra\nIhTMfniguMJNsyEOO0AN8Tqcl0eRBxcOArcri7xu8HFvvl+e+ILymu4buusbYEVL\nGBkYP5YMmScfKn+jnDVN4mWoN1Bq2yMhMGx6PA3hOvzPNsUoYy2BwDxNZyflzuAi\ng59mgJm2NXtzNbSRJbMamKpQ69mzLWGdFNsRd4aH7PT7uPAURaf7B5BVp3UyjERW\n5alSGnBqsZmvlRnVH5BDUhYsWZMPRQS9rRr4iGW0l+TH+O2VJ8aQ\n=0Zqq\n-----END PGP PUBLIC KEY BLOCK-----\n" 2026-01-22 00:30:11.704862 | controller | } 2026-01-22 00:30:11.722436 | 2026-01-22 00:30:11.722559 | LOOP [ensure-package-repositories : Import GPG keys] 2026-01-22 00:30:12.358765 | controller | changed: 2026-01-22 00:30:12.359096 | controller | { 2026-01-22 00:30:12.359163 | controller | "data": "-----BEGIN PGP PUBLIC KEY BLOCK-----\n\nmQINBFit5IEBEADDt86QpYKz5flnCsOyZ/fk3WwBKxfDjwHf/GIflo+4GWAXS7wJ\n1PSzPsvSDATV10J44i5WQzh99q+lZvFCVRFiNhRmlmcXG+rk1QmDh3fsCCj9Q/yP\nw8jn3Hx0zDtz8PIB/18ReftYJzUo34COLiHn8WiY20uGCF2pjdPgfxE+K454c4G7\ngKFqVUFYgPug2CS0quaBB5b0rpFUdzTeI5RCStd27nHCpuSDCvRYAfdv+4Y1yiVh\nKKdoe3Smj+RnXeVMgDxtH9FJibZ3DK7WnMN2yeob6VqXox+FvKYJCCLkbQgQmE50\nuVK0uN71A1mQDcTRKQ2q3fFGlMTqJbbzr3LwnCBE6hV0a36t+DABtZTmz5O69xdJ\nWGdBeePCnWVqtDb/BdEYz7hPKskcZBarygCCe2Xi7sZieoFZuq6ltPoCsdfEdfbO\n+VBVKJnExqNZCcFUTEnbH4CldWROOzMS8BGUlkGpa59Sl1t0QcmWlw1EbkeMQNrN\nspdR8lobcdNS9bpAJQqSHRZh3cAM9mA3Yq/bssUS/P2quRXLjJ9mIv3dky9C3udM\n+q2unvnbNpPtIUly76FJ3s8g8sHeOnmYcKqNGqHq2Q3kMdA2eIbI0MqfOIo2+Xk0\nrNt3ctq3g+cQiorcN3rdHPsTRSAcp+NCz1QF9TwXYtH1XV24A6QMO0+CZwARAQAB\ntCtEb2NrZXIgUmVsZWFzZSAoQ0UgcnBtKSA8ZG9ja2VyQGRvY2tlci5jb20+iQI3\nBBMBCgAhBQJYrep4AhsvBQsJCAcDBRUKCQgLBRYCAwEAAh4BAheAAAoJEMUv62ti\nHp816C0P/iP+1uhSa6Qq3TIc5sIFE5JHxOO6y0R97cUdAmCbEqBiJHUPNQDQaaRG\nVYBm0K013Q1gcJeUJvS32gthmIvhkstw7KTodwOM8Kl11CCqZ07NPFef1b2SaJ7l\nTYpyUsT9+e343ph+O4C1oUQw6flaAJe+8ATCmI/4KxfhIjD2a/Q1voR5tUIxfexC\n/LZTx05gyf2mAgEWlRm/cGTStNfqDN1uoKMlV+WFuB1j2oTUuO1/dr8mL+FgZAM3\nntWFo9gQCllNV9ahYOON2gkoZoNuPUnHsf4Bj6BQJnIXbAhMk9H2sZzwUi9bgObZ\nXO8+OrP4D4B9kCAKqqaQqA+O46LzO2vhN74lm/Fy6PumHuviqDBdN+HgtRPMUuao\nxnuVJSvBu9sPdgT/pR1N9u/KnfAnnLtR6g+fx4mWz+ts/riB/KRHzXd+44jGKZra\nIhTMfniguMJNsyEOO0AN8Tqcl0eRBxcOArcri7xu8HFvvl+e+ILymu4buusbYEVL\nGBkYP5YMmScfKn+jnDVN4mWoN1Bq2yMhMGx6PA3hOvzPNsUoYy2BwDxNZyflzuAi\ng59mgJm2NXtzNbSRJbMamKpQ69mzLWGdFNsRd4aH7PT7uPAURaf7B5BVp3UyjERW\n5alSGnBqsZmvlRnVH5BDUhYsWZMPRQS9rRr4iGW0l+TH+O2VJ8aQ\n=0Zqq\n-----END PGP PUBLIC KEY BLOCK-----\n" 2026-01-22 00:30:12.359213 | controller | } 2026-01-22 00:30:12.359266 | controller | changed: All items complete 2026-01-22 00:30:12.359298 | 2026-01-22 00:30:12.372056 | 2026-01-22 00:30:12.372220 | TASK [ensure-package-repositories : Remove GPG temp directory] 2026-01-22 00:30:12.609744 | controller | changed 2026-01-22 00:30:12.618260 | 2026-01-22 00:30:12.618378 | LOOP [ensure-package-repositories : Add repositories] 2026-01-22 00:30:12.981605 | controller | changed: 2026-01-22 00:30:12.981936 | controller | { 2026-01-22 00:30:12.981970 | controller | "baseurl": "https://download.docker.com/linux/fedora/$releasever/$basearch/stable", 2026-01-22 00:30:12.981991 | controller | "description": "Docker CE Stable - $basearch", 2026-01-22 00:30:12.982009 | controller | "gpgcheck": true, 2026-01-22 00:30:12.982026 | controller | "name": "docker-ce-stable" 2026-01-22 00:30:12.982042 | controller | } 2026-01-22 00:30:13.005219 | 2026-01-22 00:30:13.005345 | TASK [ensure-docker : Install pre-reqs] 2026-01-22 00:30:15.437064 | controller | ok: Nothing to do 2026-01-22 00:30:15.444464 | 2026-01-22 00:30:15.444582 | TASK [ensure-docker : Remove packages] 2026-01-22 00:30:16.751278 | controller | ok: Nothing to do 2026-01-22 00:30:16.757096 | 2026-01-22 00:30:16.757178 | TASK [ensure-docker : Install upstream docker using package] 2026-01-22 00:35:46.845419 | controller | ERROR 2026-01-22 00:35:46.845680 | controller | { 2026-01-22 00:35:46.845743 | controller | "msg": "Data could not be sent to remote host \"162.253.55.43\". Make sure this host can be reached over ssh: ssh: connect to host 162.253.55.43 port 22: No route to host\r\n", 2026-01-22 00:35:46.845783 | controller | "unreachable": true 2026-01-22 00:35:46.845810 | controller | } unreachable 2026-01-22 00:35:46.847655 | 2026-01-22 00:35:46.847708 | PLAY RECAP 2026-01-22 00:35:46.847769 | controller | ok: 13 changed: 6 unreachable: 1 failed: 0 skipped: 9 rescued: 0 ignored: 0 2026-01-22 00:35:46.847793 | 2026-01-22 00:35:46.966030 | PRE-RUN END RESULT_UNREACHABLE: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master] 2026-01-22 00:35:46.967122 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2026-01-22 00:35:47.554617 | 2026-01-22 00:35:47.554765 | PLAY [all] 2026-01-22 00:35:47.577315 | 2026-01-22 00:35:47.577447 | TASK [fetch-subunit-output : Find stestr or testr executable] 2026-01-22 00:36:03.566348 | controller | changed: non-zero return code 2026-01-22 00:36:03.586405 | 2026-01-22 00:36:03.586651 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2026-01-22 00:36:03.613956 | controller | skipping: Conditional result was False 2026-01-22 00:36:03.624079 | 2026-01-22 00:36:03.624250 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2026-01-22 00:36:03.670945 | 2026-01-22 00:36:03.671174 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2026-01-22 00:36:03.718261 | 2026-01-22 00:36:03.718518 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2026-01-22 00:36:03.744879 | controller | skipping: Conditional result was False 2026-01-22 00:36:03.756230 | 2026-01-22 00:36:03.756412 | LOOP [fetch-subunit-output : Generate subunit file] 2026-01-22 00:36:03.793026 | 2026-01-22 00:36:03.793289 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2026-01-22 00:36:03.820211 | controller | skipping: Conditional result was False 2026-01-22 00:36:03.834646 | 2026-01-22 00:36:03.834773 | TASK [fetch-subunit-output : Remove the temporary file] 2026-01-22 00:36:03.859663 | controller | skipping: Conditional result was False 2026-01-22 00:36:03.869122 | 2026-01-22 00:36:03.869234 | TASK [fetch-subunit-output : Process and fetch subunit results] 2026-01-22 00:36:03.894786 | controller | skipping: Conditional result was False 2026-01-22 00:36:03.933943 | 2026-01-22 00:36:03.934071 | PLAY RECAP 2026-01-22 00:36:03.934127 | controller | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2026-01-22 00:36:03.934156 | 2026-01-22 00:36:04.042993 | POST-RUN END RESULT_UNREACHABLE: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2026-01-22 00:36:04.043977 | POST-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/post.yaml@master] 2026-01-22 00:36:04.756818 | 2026-01-22 00:36:04.756949 | PLAY [all:!appliance*] 2026-01-22 00:36:04.781477 | 2026-01-22 00:36:04.781598 | TASK [unregister the node] 2026-01-22 00:36:05.314000 | controller | skipping: Conditional result was False 2026-01-22 00:36:05.320808 | 2026-01-22 00:36:05.320943 | TASK [include_role : fetch-output] 2026-01-22 00:36:05.351389 | controller | ok 2026-01-22 00:36:05.372685 | 2026-01-22 00:36:05.372844 | TASK [fetch-output : Set log path for multiple nodes] 2026-01-22 00:36:05.448856 | controller | skipping: Conditional result was False 2026-01-22 00:36:05.455980 | 2026-01-22 00:36:05.456103 | TASK [fetch-output : Set log path for single node] 2026-01-22 00:36:05.497105 | controller | ok 2026-01-22 00:36:05.503713 | 2026-01-22 00:36:05.503822 | LOOP [fetch-output : Ensure local output dirs] 2026-01-22 00:36:05.915880 | controller -> localhost | ok: "/var/lib/zuul/builds/ce6d637e498e4dac80722e4056b55d9f/work/logs" 2026-01-22 00:36:06.138600 | controller -> localhost | changed: "/var/lib/zuul/builds/ce6d637e498e4dac80722e4056b55d9f/work/artifacts" 2026-01-22 00:36:06.345073 | controller -> localhost | changed: "/var/lib/zuul/builds/ce6d637e498e4dac80722e4056b55d9f/work/docs" 2026-01-22 00:36:06.363153 | 2026-01-22 00:36:06.363275 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-01-22 00:36:07.081742 | controller | changed: 2026-01-22 00:36:07.081947 | controller | .d..t...... ./ 2026-01-22 00:36:07.081977 | controller | cd+++++++++ controller/ 2026-01-22 00:36:07.082011 | controller | changed: All items complete 2026-01-22 00:36:07.082032 | 2026-01-22 00:36:07.558577 | controller | changed: .d..t...... ./ 2026-01-22 00:36:08.018070 | controller | changed: .d..t...... ./ 2026-01-22 00:36:08.042448 | 2026-01-22 00:36:08.042596 | TASK [include_role : fetch-output-openshift] 2026-01-22 00:36:08.058250 | controller | skipping: Conditional result was False 2026-01-22 00:36:08.067553 | 2026-01-22 00:36:08.067718 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-01-22 00:36:08.095919 | controller | skipping: Conditional result was False 2026-01-22 00:36:08.111452 | controller | skipping: Conditional result was False 2026-01-22 00:36:08.149385 | 2026-01-22 00:36:08.149538 | PLAY [localhost] 2026-01-22 00:36:08.163665 | 2026-01-22 00:36:08.163845 | TASK [Run Zuul manifest role] 2026-01-22 00:36:08.182350 | localhost | ok 2026-01-22 00:36:08.200246 | 2026-01-22 00:36:08.200386 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-01-22 00:36:08.628318 | localhost | changed 2026-01-22 00:36:08.636760 | 2026-01-22 00:36:08.636875 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-01-22 00:36:08.670823 | localhost | ok 2026-01-22 00:36:08.679969 | 2026-01-22 00:36:08.680057 | TASK [Set zuul-log-path fact] 2026-01-22 00:36:08.704281 | localhost | ok 2026-01-22 00:36:08.720598 | 2026-01-22 00:36:08.720756 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-01-22 00:36:08.749873 | localhost | ok 2026-01-22 00:36:08.757925 | 2026-01-22 00:36:08.758029 | LOOP [Run upload-logs-swift role] 2026-01-22 00:36:08.782157 | localhost | Output suppressed because no_log was given 2026-01-22 00:36:08.813525 | 2026-01-22 00:36:08.814075 | TASK [Set zuul-log-path fact] 2026-01-22 00:36:08.859022 | localhost | skipping: Conditional result was False 2026-01-22 00:36:08.864486 | 2026-01-22 00:36:08.864625 | TASK [upload-logs-swift : Ensure logs are readable before uploading] 2026-01-22 00:36:09.354511 | localhost -> localhost | ok: Runtime: 0:00:00.007499 2026-01-22 00:36:09.364682 | 2026-01-22 00:36:09.364852 | TASK [upload-logs-swift : Upload logs to swift]