2026-03-03 00:20:51.064601 | Job console starting... 2026-03-03 00:20:51.073330 | Updating repositories 2026-03-03 00:20:51.163816 | Preparing job workspace 2026-03-03 00:20:54.919219 | Running Ansible setup... 2026-03-03 00:20:59.736948 | PRE-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master] 2026-03-03 00:21:00.387134 | 2026-03-03 00:21:00.387302 | PLAY [localhost] 2026-03-03 00:21:00.397737 | 2026-03-03 00:21:00.397838 | TASK [Gathering Facts] 2026-03-03 00:21:01.383997 | localhost | ok 2026-03-03 00:21:01.411354 | 2026-03-03 00:21:01.411520 | TASK [Setup log path fact] 2026-03-03 00:21:01.432108 | localhost | ok 2026-03-03 00:21:01.450401 | 2026-03-03 00:21:01.450545 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-03-03 00:21:01.479554 | localhost | ok 2026-03-03 00:21:01.488993 | 2026-03-03 00:21:01.489082 | TASK [emit-job-header : Print job information] 2026-03-03 00:21:01.516917 | # Job Information 2026-03-03 00:21:01.517067 | Ansible Version: 2.15.12 2026-03-03 00:21:01.517098 | Job: ansible-test-sanity-docker-devel 2026-03-03 00:21:01.517119 | Pipeline: periodic 2026-03-03 00:21:01.517137 | Executor: ze04.softwarefactory-project.io 2026-03-03 00:21:01.517155 | Triggered by: https://github.com/ansible-collections/ansible.network 2026-03-03 00:21:01.517176 | Log URL (when completed): https://ansible.softwarefactory-project.io/logs/4d0/ansible/4d04ba67b93046c5974c100ad7b6db79/ 2026-03-03 00:21:01.517195 | Event ID: c6f296bc1fe348df9801fd052b877527 2026-03-03 00:21:01.520799 | 2026-03-03 00:21:01.520859 | LOOP [emit-job-header : Print node information] 2026-03-03 00:21:01.631203 | localhost | ok: 2026-03-03 00:21:01.631402 | localhost | # Node Information 2026-03-03 00:21:01.631431 | localhost | Inventory Hostname: controller 2026-03-03 00:21:01.631452 | localhost | Hostname: np0005636099 2026-03-03 00:21:01.631472 | localhost | Username: zuul 2026-03-03 00:21:01.631497 | localhost | Distro: Fedora 37 2026-03-03 00:21:01.631517 | localhost | Provider: ansible-vexxhost-ca-ymq-1 2026-03-03 00:21:01.631536 | localhost | Region: ca-ymq-1 2026-03-03 00:21:01.631554 | localhost | Label: ansible-fedora-37-1vcpu 2026-03-03 00:21:01.631573 | localhost | Product Name: OpenStack Nova 2026-03-03 00:21:01.631591 | localhost | Interface IP: 199.204.45.44 2026-03-03 00:21:01.644094 | 2026-03-03 00:21:01.644470 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-03-03 00:21:02.004870 | localhost -> localhost | changed 2026-03-03 00:21:02.010646 | 2026-03-03 00:21:02.010750 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-03-03 00:21:02.867277 | localhost -> localhost | changed 2026-03-03 00:21:02.906376 | 2026-03-03 00:21:02.906503 | PLAY [all:!appliance*] 2026-03-03 00:21:02.933887 | 2026-03-03 00:21:02.934034 | TASK [include_role : start-zuul-console] 2026-03-03 00:21:02.955098 | controller | ok 2026-03-03 00:21:02.971460 | 2026-03-03 00:21:02.971574 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-03-03 00:21:03.327638 | controller | ok 2026-03-03 00:21:03.340255 | 2026-03-03 00:21:03.340378 | TASK [use-our-mirror : Retrieve the IP address] 2026-03-03 00:21:04.458294 | controller | ok: Status code was 404 and not [200]: HTTP Error 404: Not Found 2026-03-03 00:21:04.465139 | 2026-03-03 00:21:04.465208 | TASK [use-our-mirror : Ensure the registry service is running on the mirror] 2026-03-03 00:21:04.997456 | controller | skipping: Conditional result was False 2026-03-03 00:21:05.006364 | 2026-03-03 00:21:05.006462 | TASK [use-our-mirror : Write the IP in /etc/hosts] 2026-03-03 00:21:05.031102 | controller | skipping: Conditional result was False 2026-03-03 00:21:05.042230 | 2026-03-03 00:21:05.042341 | TASK [use-our-mirror : Create the podman configuration directory] 2026-03-03 00:21:05.080298 | controller | skipping: Conditional result was False 2026-03-03 00:21:05.086934 | 2026-03-03 00:21:05.087014 | TASK [use-our-mirror : Copy the podman configuration] 2026-03-03 00:21:05.111333 | controller | skipping: Conditional result was False 2026-03-03 00:21:05.117932 | 2026-03-03 00:21:05.118008 | TASK [use-our-mirror : Copy the Yum configuration (main)] 2026-03-03 00:21:05.142826 | controller | skipping: Conditional result was False 2026-03-03 00:21:05.150608 | 2026-03-03 00:21:05.150716 | TASK [use-our-mirror : Copy the Yum configuration (update)] 2026-03-03 00:21:05.174926 | controller | skipping: Conditional result was False 2026-03-03 00:21:05.186271 | 2026-03-03 00:21:05.186352 | TASK [Disable Fedora Modular] 2026-03-03 00:21:05.441158 | controller | changed 2026-03-03 00:21:05.448083 | 2026-03-03 00:21:05.448173 | TASK [Enable EPEL] 2026-03-03 00:21:05.473772 | controller | skipping: Conditional result was False 2026-03-03 00:21:05.485350 | 2026-03-03 00:21:05.485519 | TASK [Register the RHEL node] 2026-03-03 00:21:06.052194 | 2026-03-03 00:21:06.052476 | TASK [Show the subscription-manager status] 2026-03-03 00:21:06.642335 | controller | skipping: Conditional result was False 2026-03-03 00:21:06.659305 | 2026-03-03 00:21:06.659461 | TASK [Enable EPEL on RHEL] 2026-03-03 00:21:07.233137 | controller | skipping: Conditional result was False 2026-03-03 00:21:07.240977 | 2026-03-03 00:21:07.241067 | TASK [Install git and tox] 2026-03-03 00:22:26.795613 | controller | changed 2026-03-03 00:22:26.801756 | 2026-03-03 00:22:26.801820 | TASK [include_role : prepare-workspace] 2026-03-03 00:22:26.833504 | controller | ok 2026-03-03 00:22:26.872953 | 2026-03-03 00:22:26.873156 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-03-03 00:22:27.103603 | controller | ok 2026-03-03 00:22:27.119525 | 2026-03-03 00:22:27.119717 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-03-03 00:22:39.388188 | controller | Output suppressed because no_log was given 2026-03-03 00:22:39.434936 | 2026-03-03 00:22:39.435067 | TASK [include_role : prepare-workspace-openshift] 2026-03-03 00:22:39.462456 | controller | skipping: Conditional result was False 2026-03-03 00:22:39.489259 | 2026-03-03 00:22:39.489355 | PLAY [all:!appliance] 2026-03-03 00:22:39.505792 | 2026-03-03 00:22:39.505868 | TASK [Run add-build-sshkey role (RSA)] 2026-03-03 00:22:39.546582 | controller | ok 2026-03-03 00:22:39.562188 | 2026-03-03 00:22:39.562284 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-03-03 00:22:39.811025 | controller -> localhost | ok 2026-03-03 00:22:39.818874 | 2026-03-03 00:22:39.818998 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-03-03 00:22:39.849258 | controller | ok 2026-03-03 00:22:39.864218 | controller | included: /var/lib/zuul/builds/4d04ba67b93046c5974c100ad7b6db79/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-03-03 00:22:39.871289 | 2026-03-03 00:22:39.871389 | TASK [add-build-sshkey : Create Temp SSH key] 2026-03-03 00:22:40.370318 | controller -> localhost | Generating public/private rsa key pair. 2026-03-03 00:22:40.370839 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/4d04ba67b93046c5974c100ad7b6db79/work/4d04ba67b93046c5974c100ad7b6db79_id_rsa. 2026-03-03 00:22:40.370979 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/4d04ba67b93046c5974c100ad7b6db79/work/4d04ba67b93046c5974c100ad7b6db79_id_rsa.pub. 2026-03-03 00:22:40.371109 | controller -> localhost | The key fingerprint is: 2026-03-03 00:22:40.371181 | controller -> localhost | SHA256:0dMRv20HSFJEizYEAvekCgosW3UzikiWk9KAdeexDMg zuul-build-sshkey 2026-03-03 00:22:40.371245 | controller -> localhost | The key's randomart image is: 2026-03-03 00:22:40.371306 | controller -> localhost | +---[RSA 2048]----+ 2026-03-03 00:22:40.371353 | controller -> localhost | |oB+.=.O o.o+B. | 2026-03-03 00:22:40.371394 | controller -> localhost | |*=E+ O X o = = | 2026-03-03 00:22:40.371432 | controller -> localhost | |=.= . = o * + o | 2026-03-03 00:22:40.371469 | controller -> localhost | |o+ . . o o + | 2026-03-03 00:22:40.371541 | controller -> localhost | |o . S . +| 2026-03-03 00:22:40.371602 | controller -> localhost | | ..| 2026-03-03 00:22:40.371669 | controller -> localhost | | | 2026-03-03 00:22:40.372172 | controller -> localhost | | | 2026-03-03 00:22:40.372279 | controller -> localhost | | | 2026-03-03 00:22:40.372421 | controller -> localhost | +----[SHA256]-----+ 2026-03-03 00:22:40.372583 | controller -> localhost | ok: Runtime: 0:00:00.057267 2026-03-03 00:22:40.391668 | 2026-03-03 00:22:40.391823 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-03-03 00:22:40.428116 | controller | ok 2026-03-03 00:22:40.442706 | controller | included: /var/lib/zuul/builds/4d04ba67b93046c5974c100ad7b6db79/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-03-03 00:22:40.451916 | 2026-03-03 00:22:40.451988 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-03-03 00:22:40.487041 | controller | skipping: Conditional result was False 2026-03-03 00:22:40.494127 | 2026-03-03 00:22:40.494242 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-03-03 00:22:40.943350 | controller | changed 2026-03-03 00:22:40.950541 | 2026-03-03 00:22:40.950647 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-03-03 00:22:41.170491 | controller | ok 2026-03-03 00:22:41.177340 | 2026-03-03 00:22:41.177443 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-03-03 00:22:41.802814 | controller | changed 2026-03-03 00:22:41.813388 | 2026-03-03 00:22:41.813510 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-03-03 00:22:42.464844 | controller | changed 2026-03-03 00:22:42.476856 | 2026-03-03 00:22:42.477058 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-03-03 00:22:42.504143 | controller | skipping: Conditional result was False 2026-03-03 00:22:42.515937 | 2026-03-03 00:22:42.516103 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-03-03 00:22:42.965671 | controller -> localhost | changed 2026-03-03 00:22:42.985162 | 2026-03-03 00:22:42.985297 | TASK [add-build-sshkey : Add back temp key] 2026-03-03 00:22:43.339379 | controller -> localhost | Identity added: /var/lib/zuul/builds/4d04ba67b93046c5974c100ad7b6db79/work/4d04ba67b93046c5974c100ad7b6db79_id_rsa (zuul-build-sshkey) 2026-03-03 00:22:43.339583 | controller -> localhost | ok: Runtime: 0:00:00.007982 2026-03-03 00:22:43.346910 | 2026-03-03 00:22:43.346977 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-03-03 00:22:43.712081 | controller | ok 2026-03-03 00:22:43.725501 | 2026-03-03 00:22:43.725715 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-03-03 00:22:43.764351 | controller | skipping: Conditional result was False 2026-03-03 00:22:43.793887 | 2026-03-03 00:22:43.794015 | TASK [Run add-build-sshkey role (ECDSA)] 2026-03-03 00:22:43.820897 | controller | ok 2026-03-03 00:22:43.849213 | 2026-03-03 00:22:43.849369 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-03-03 00:22:44.104748 | controller -> localhost | ok 2026-03-03 00:22:44.121758 | 2026-03-03 00:22:44.121925 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-03-03 00:22:44.165911 | controller | ok 2026-03-03 00:22:44.188387 | controller | included: /var/lib/zuul/builds/4d04ba67b93046c5974c100ad7b6db79/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-03-03 00:22:44.199434 | 2026-03-03 00:22:44.199579 | TASK [add-build-sshkey : Create Temp SSH key] 2026-03-03 00:22:44.481461 | controller -> localhost | Generating public/private ecdsa key pair. 2026-03-03 00:22:44.481692 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/4d04ba67b93046c5974c100ad7b6db79/work/4d04ba67b93046c5974c100ad7b6db79_id_ecdsa. 2026-03-03 00:22:44.481729 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/4d04ba67b93046c5974c100ad7b6db79/work/4d04ba67b93046c5974c100ad7b6db79_id_ecdsa.pub. 2026-03-03 00:22:44.481762 | controller -> localhost | The key fingerprint is: 2026-03-03 00:22:44.481783 | controller -> localhost | SHA256:Ej9ifl2gqBo797yq7CKh8jHdWGY+8+Tr3WfbCrKGNv4 zuul-build-sshkey 2026-03-03 00:22:44.481802 | controller -> localhost | The key's randomart image is: 2026-03-03 00:22:44.481821 | controller -> localhost | +---[ECDSA 521]---+ 2026-03-03 00:22:44.481840 | controller -> localhost | | | 2026-03-03 00:22:44.481858 | controller -> localhost | | | 2026-03-03 00:22:44.481876 | controller -> localhost | | . . | 2026-03-03 00:22:44.481893 | controller -> localhost | | + . . | 2026-03-03 00:22:44.481909 | controller -> localhost | | O S . | 2026-03-03 00:22:44.481926 | controller -> localhost | |. . @ o o . | 2026-03-03 00:22:44.481944 | controller -> localhost | |..+ + * o.o . | 2026-03-03 00:22:44.481961 | controller -> localhost | |=..*.. B+..+ .o. | 2026-03-03 00:22:44.481977 | controller -> localhost | |o+B+.o+=*=E .ooo.| 2026-03-03 00:22:44.481994 | controller -> localhost | +----[SHA256]-----+ 2026-03-03 00:22:44.482042 | controller -> localhost | ok: Runtime: 0:00:00.008206 2026-03-03 00:22:44.489852 | 2026-03-03 00:22:44.489959 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-03-03 00:22:44.512609 | controller | ok 2026-03-03 00:22:44.520656 | controller | included: /var/lib/zuul/builds/4d04ba67b93046c5974c100ad7b6db79/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-03-03 00:22:44.530571 | 2026-03-03 00:22:44.530648 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-03-03 00:22:44.545141 | controller | skipping: Conditional result was False 2026-03-03 00:22:44.554671 | 2026-03-03 00:22:44.554766 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-03-03 00:22:44.819152 | controller | changed 2026-03-03 00:22:44.825430 | 2026-03-03 00:22:44.825503 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-03-03 00:22:45.041537 | controller | ok 2026-03-03 00:22:45.049899 | 2026-03-03 00:22:45.050023 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-03-03 00:22:45.671168 | controller | changed 2026-03-03 00:22:45.685164 | 2026-03-03 00:22:45.685314 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-03-03 00:22:46.264569 | controller | changed 2026-03-03 00:22:46.275879 | 2026-03-03 00:22:46.276002 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-03-03 00:22:46.301472 | controller | skipping: Conditional result was False 2026-03-03 00:22:46.311247 | 2026-03-03 00:22:46.311390 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-03-03 00:22:46.614515 | controller -> localhost | changed 2026-03-03 00:22:46.664988 | 2026-03-03 00:22:46.665118 | TASK [add-build-sshkey : Add back temp key] 2026-03-03 00:22:46.974357 | controller -> localhost | Identity added: /var/lib/zuul/builds/4d04ba67b93046c5974c100ad7b6db79/work/4d04ba67b93046c5974c100ad7b6db79_id_ecdsa (zuul-build-sshkey) 2026-03-03 00:22:46.974609 | controller -> localhost | ok: Runtime: 0:00:00.011482 2026-03-03 00:22:46.985174 | 2026-03-03 00:22:46.985557 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-03-03 00:22:47.205131 | controller | ok 2026-03-03 00:22:47.213532 | 2026-03-03 00:22:47.213651 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-03-03 00:22:47.240481 | controller | skipping: Conditional result was False 2026-03-03 00:22:47.260213 | 2026-03-03 00:22:47.260410 | TASK [include_role : remove-zuul-sshkey] 2026-03-03 00:22:47.276855 | controller | skipping: Conditional result was False 2026-03-03 00:22:47.285191 | 2026-03-03 00:22:47.285295 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-03-03 00:22:47.518485 | controller | ok: "logs" 2026-03-03 00:22:47.518749 | controller | ok: All items complete 2026-03-03 00:22:47.518783 | 2026-03-03 00:22:47.706000 | controller | ok: "artifacts" 2026-03-03 00:22:47.902979 | controller | ok: "docs" 2026-03-03 00:22:47.921954 | 2026-03-03 00:22:47.922081 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-03-03 00:22:48.136086 | controller | changed: "logs" 2026-03-03 00:22:48.328906 | controller | changed: "artifacts" 2026-03-03 00:22:48.526928 | controller | changed: "docs" 2026-03-03 00:22:48.581314 | 2026-03-03 00:22:48.581436 | PLAY RECAP 2026-03-03 00:22:48.581494 | controller | ok: 30 changed: 16 unreachable: 0 failed: 0 skipped: 18 rescued: 0 ignored: 0 2026-03-03 00:22:48.581533 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-03-03 00:22:48.581560 | 2026-03-03 00:22:48.742842 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master] 2026-03-03 00:22:48.743994 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2026-03-03 00:22:49.378244 | 2026-03-03 00:22:49.378352 | PLAY [all] 2026-03-03 00:22:49.399957 | 2026-03-03 00:22:49.400052 | TASK [Install binary dependencies] 2026-03-03 00:22:49.450437 | controller | ok 2026-03-03 00:22:49.472024 | 2026-03-03 00:22:49.472211 | TASK [bindep : Include find tasks] 2026-03-03 00:22:49.501887 | controller | ok 2026-03-03 00:22:49.509775 | controller | included: /var/lib/zuul/builds/4d04ba67b93046c5974c100ad7b6db79/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find.yaml 2026-03-03 00:22:49.520231 | 2026-03-03 00:22:49.520371 | TASK [bindep : Look for bindep.txt] 2026-03-03 00:22:49.881366 | controller | ok 2026-03-03 00:22:49.894638 | 2026-03-03 00:22:49.894859 | TASK [bindep : Define bindep_file fact] 2026-03-03 00:22:49.923208 | controller | skipping: Conditional result was False 2026-03-03 00:22:49.937717 | 2026-03-03 00:22:49.937902 | TASK [bindep : Look for other-requirements.txt] 2026-03-03 00:22:50.155384 | controller | ok 2026-03-03 00:22:50.161470 | 2026-03-03 00:22:50.161552 | TASK [bindep : Define bindep_file fact] 2026-03-03 00:22:50.186776 | controller | skipping: Conditional result was False 2026-03-03 00:22:50.193770 | 2026-03-03 00:22:50.193856 | TASK [bindep : Look for bindep fallback file] 2026-03-03 00:22:50.218610 | controller | skipping: Conditional result was False 2026-03-03 00:22:50.235051 | 2026-03-03 00:22:50.235242 | TASK [bindep : Define bindep_file fact] 2026-03-03 00:22:50.261942 | controller | skipping: Conditional result was False 2026-03-03 00:22:50.280809 | 2026-03-03 00:22:50.284504 | TASK [bindep : Include bindep tasks] 2026-03-03 00:22:50.312119 | controller | skipping: Conditional result was False 2026-03-03 00:22:50.321546 | 2026-03-03 00:22:50.321642 | TASK [bindep : Include install tasks] 2026-03-03 00:22:50.356817 | controller | skipping: Conditional result was False 2026-03-03 00:22:50.370932 | 2026-03-03 00:22:50.371082 | LOOP [bindep : Include package tasks] 2026-03-03 00:22:50.443503 | 2026-03-03 00:22:50.443732 | TASK [Run test-setup role] 2026-03-03 00:22:50.467899 | controller | ok 2026-03-03 00:22:50.489405 | 2026-03-03 00:22:50.489510 | TASK [test-setup : Check if project's tools/test-setup.sh exists] 2026-03-03 00:22:50.720060 | controller | ok 2026-03-03 00:22:50.734587 | 2026-03-03 00:22:50.734772 | TASK [test-setup : Run tools/test-setup.sh] 2026-03-03 00:22:51.285083 | controller | skipping: Conditional result was False 2026-03-03 00:22:51.341266 | 2026-03-03 00:22:51.341369 | PLAY RECAP 2026-03-03 00:22:51.341422 | controller | ok: 4 changed: 0 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2026-03-03 00:22:51.341451 | 2026-03-03 00:22:51.449226 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2026-03-03 00:22:51.450593 | PRE-RUN START: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master] 2026-03-03 00:22:52.011768 | 2026-03-03 00:22:52.011914 | PLAY [controller] 2026-03-03 00:22:52.032553 | 2026-03-03 00:22:52.032701 | TASK [Create the /root directory] 2026-03-03 00:25:04.327123 | controller | ERROR 2026-03-03 00:25:04.327460 | controller | { 2026-03-03 00:25:04.327541 | controller | "msg": "Timeout (32s) waiting for privilege escalation prompt: " 2026-03-03 00:25:04.327602 | controller | } failure 2026-03-03 00:25:04.329716 | 2026-03-03 00:25:04.329813 | PLAY RECAP 2026-03-03 00:25:04.329898 | controller | ok: 0 changed: 0 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0 2026-03-03 00:25:04.329948 | 2026-03-03 00:25:04.452241 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master] 2026-03-03 00:25:04.453460 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2026-03-03 00:25:05.041122 | 2026-03-03 00:25:05.041246 | PLAY [all] 2026-03-03 00:25:05.064202 | 2026-03-03 00:25:05.064333 | TASK [fetch-subunit-output : Find stestr or testr executable] 2026-03-03 00:29:05.778074 | controller | changed: non-zero return code 2026-03-03 00:29:05.794973 | 2026-03-03 00:29:05.795252 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2026-03-03 00:29:05.824279 | controller | skipping: Conditional result was False 2026-03-03 00:29:05.841005 | 2026-03-03 00:29:05.841209 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2026-03-03 00:29:05.888938 | 2026-03-03 00:29:05.889304 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2026-03-03 00:29:05.927069 | 2026-03-03 00:29:05.927325 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2026-03-03 00:29:05.953150 | controller | skipping: Conditional result was False 2026-03-03 00:29:05.969074 | 2026-03-03 00:29:05.969280 | LOOP [fetch-subunit-output : Generate subunit file] 2026-03-03 00:29:06.005950 | 2026-03-03 00:29:06.006201 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2026-03-03 00:29:06.040386 | controller | skipping: Conditional result was False 2026-03-03 00:29:06.055517 | 2026-03-03 00:29:06.055767 | TASK [fetch-subunit-output : Remove the temporary file] 2026-03-03 00:29:06.083266 | controller | skipping: Conditional result was False 2026-03-03 00:29:06.093769 | 2026-03-03 00:29:06.093879 | TASK [fetch-subunit-output : Process and fetch subunit results] 2026-03-03 00:29:06.119058 | controller | skipping: Conditional result was False 2026-03-03 00:29:06.158265 | 2026-03-03 00:29:06.158447 | PLAY RECAP 2026-03-03 00:29:06.158515 | controller | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2026-03-03 00:29:06.158544 | 2026-03-03 00:29:06.319997 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2026-03-03 00:29:06.321672 | POST-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/post.yaml@master] 2026-03-03 00:29:06.916216 | 2026-03-03 00:29:06.916369 | PLAY [all:!appliance*] 2026-03-03 00:29:06.938670 | 2026-03-03 00:29:06.938839 | TASK [unregister the node] 2026-03-03 00:29:07.471377 | controller | skipping: Conditional result was False 2026-03-03 00:29:07.484948 | 2026-03-03 00:29:07.485107 | TASK [include_role : fetch-output] 2026-03-03 00:29:07.525107 | controller | ok 2026-03-03 00:29:07.563123 | 2026-03-03 00:29:07.563252 | TASK [fetch-output : Set log path for multiple nodes] 2026-03-03 00:29:07.639543 | controller | skipping: Conditional result was False 2026-03-03 00:29:07.648419 | 2026-03-03 00:29:07.648515 | TASK [fetch-output : Set log path for single node] 2026-03-03 00:29:07.716131 | controller | ok 2026-03-03 00:29:07.728130 | 2026-03-03 00:29:07.728267 | LOOP [fetch-output : Ensure local output dirs] 2026-03-03 00:29:08.231465 | controller -> localhost | ok: "/var/lib/zuul/builds/4d04ba67b93046c5974c100ad7b6db79/work/logs" 2026-03-03 00:29:08.503288 | controller -> localhost | changed: "/var/lib/zuul/builds/4d04ba67b93046c5974c100ad7b6db79/work/artifacts" 2026-03-03 00:29:08.782224 | controller -> localhost | changed: "/var/lib/zuul/builds/4d04ba67b93046c5974c100ad7b6db79/work/docs" 2026-03-03 00:29:08.792975 | 2026-03-03 00:29:08.793091 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-03-03 00:29:09.590991 | controller | ok 2026-03-03 00:29:09.591401 | controller | changed: All items complete 2026-03-03 00:29:09.591490 | 2026-03-03 00:29:10.164627 | controller | changed: .d..t...... ./ 2026-03-03 00:29:10.748958 | controller | changed: .d..t...... ./ 2026-03-03 00:29:10.788827 | 2026-03-03 00:29:10.789053 | TASK [include_role : fetch-output-openshift] 2026-03-03 00:29:10.817890 | controller | skipping: Conditional result was False 2026-03-03 00:29:10.833621 | 2026-03-03 00:29:10.833888 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-03-03 00:29:10.885146 | controller | skipping: Conditional result was False 2026-03-03 00:29:10.895246 | controller | skipping: Conditional result was False 2026-03-03 00:29:10.943749 | 2026-03-03 00:29:10.943866 | PLAY [localhost] 2026-03-03 00:29:10.960963 | 2026-03-03 00:29:10.961055 | TASK [Run Zuul manifest role] 2026-03-03 00:29:10.983549 | localhost | ok 2026-03-03 00:29:11.000980 | 2026-03-03 00:29:11.001072 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-03-03 00:29:11.452814 | localhost | changed 2026-03-03 00:29:11.463585 | 2026-03-03 00:29:11.463775 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-03-03 00:29:11.500625 | localhost | ok 2026-03-03 00:29:11.520873 | 2026-03-03 00:29:11.521026 | TASK [Set zuul-log-path fact] 2026-03-03 00:29:11.544225 | localhost | ok 2026-03-03 00:29:11.565025 | 2026-03-03 00:29:11.565130 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-03-03 00:29:11.597634 | localhost | ok 2026-03-03 00:29:11.609424 | 2026-03-03 00:29:11.609515 | LOOP [Set zuul_log_targets (Swift with random Rackspace region)] 2026-03-03 00:29:11.632433 | localhost | ok: 2026-03-03 00:29:11.632704 | localhost | { 2026-03-03 00:29:11.632736 | localhost | "auth": { 2026-03-03 00:29:11.632756 | localhost | "password": "IonWCwcuOFib&&<}wp^:[2G/", 2026-03-03 00:29:11.632773 | localhost | "username": "9LF91O0RK7JU" 2026-03-03 00:29:11.632793 | localhost | }, 2026-03-03 00:29:11.632810 | localhost | "profile": "rackspace", 2026-03-03 00:29:11.632827 | localhost | "region_name": "IAD" 2026-03-03 00:29:11.632844 | localhost | } 2026-03-03 00:29:11.632872 | 2026-03-03 00:29:11.640401 | 2026-03-03 00:29:11.640491 | TASK [Run upload-logs-failover role (S3 then Swift)] 2026-03-03 00:29:11.662059 | localhost | Output suppressed because no_log was given 2026-03-03 00:29:11.680282 | 2026-03-03 00:29:11.680374 | TASK [upload-logs-failover : Initialize control vars] 2026-03-03 00:29:11.713214 | localhost | ok 2026-03-03 00:29:11.750266 | 2026-03-03 00:29:11.750399 | LOOP [upload-logs-failover : Perform log upload] 2026-03-03 00:29:11.797925 | localhost | Output suppressed because no_log was given 2026-03-03 00:29:11.805477 | localhost | included: /var/lib/zuul/builds/4d04ba67b93046c5974c100ad7b6db79/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/upload-logs-failover/tasks/upload.yaml 2026-03-03 00:29:11.812614 | 2026-03-03 00:29:11.812710 | LOOP [upload-logs-failover : Unpack vars for swift upload] 2026-03-03 00:29:11.888193 | localhost | Output suppressed because no_log was given 2026-03-03 00:29:11.894835 | 2026-03-03 00:29:11.894926 | TASK [Upload logs to swift] 2026-03-03 00:29:11.915998 | localhost | ok 2026-03-03 00:29:11.946264 | 2026-03-03 00:29:11.946353 | TASK [Set zuul-log-path fact] 2026-03-03 00:29:11.970463 | localhost | skipping: Conditional result was False 2026-03-03 00:29:11.975891 | 2026-03-03 00:29:11.975964 | TASK [upload-logs-swift : Ensure logs are readable before uploading] 2026-03-03 00:29:12.378523 | localhost -> localhost | ok: Runtime: 0:00:00.008856 2026-03-03 00:29:12.392145 | 2026-03-03 00:29:12.392344 | TASK [upload-logs-swift : Upload logs to swift]