2026-03-06 00:21:36.971827 | Job console starting... 2026-03-06 00:21:36.983975 | Updating repositories 2026-03-06 00:21:37.169131 | Preparing job workspace 2026-03-06 00:21:41.022083 | Running Ansible setup... 2026-03-06 00:21:45.787595 | PRE-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master] 2026-03-06 00:21:46.454798 | 2026-03-06 00:21:46.454947 | PLAY [localhost] 2026-03-06 00:21:46.464014 | 2026-03-06 00:21:46.464110 | TASK [Gathering Facts] 2026-03-06 00:21:47.524561 | localhost | ok 2026-03-06 00:21:47.538704 | 2026-03-06 00:21:47.538853 | TASK [Setup log path fact] 2026-03-06 00:21:47.558779 | localhost | ok 2026-03-06 00:21:47.572827 | 2026-03-06 00:21:47.572977 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-03-06 00:21:47.615931 | localhost | ok 2026-03-06 00:21:47.627584 | 2026-03-06 00:21:47.627759 | TASK [emit-job-header : Print job information] 2026-03-06 00:21:47.680373 | # Job Information 2026-03-06 00:21:47.681106 | Ansible Version: 2.15.12 2026-03-06 00:21:47.681143 | Job: ansible-test-sanity-docker-stable-2.11 2026-03-06 00:21:47.681165 | Pipeline: periodic 2026-03-06 00:21:47.681184 | Executor: ze02.softwarefactory-project.io 2026-03-06 00:21:47.681202 | Triggered by: https://github.com/ansible-collections/ansible.network 2026-03-06 00:21:47.681223 | Log URL (when completed): https://ansible.softwarefactory-project.io/logs/e62/ansible/e62bdfec3a3e4c5e96167eb783553060/ 2026-03-06 00:21:47.681242 | Event ID: cd32135b903048a8b5523f70ed18b23e 2026-03-06 00:21:47.685537 | 2026-03-06 00:21:47.685631 | LOOP [emit-job-header : Print node information] 2026-03-06 00:21:47.802948 | localhost | ok: 2026-03-06 00:21:47.803275 | localhost | # Node Information 2026-03-06 00:21:47.803305 | localhost | Inventory Hostname: controller 2026-03-06 00:21:47.803327 | localhost | Hostname: np0005639343 2026-03-06 00:21:47.803347 | localhost | Username: zuul 2026-03-06 00:21:47.803371 | localhost | Distro: Fedora 37 2026-03-06 00:21:47.803391 | localhost | Provider: ansible-vexxhost-ca-ymq-1 2026-03-06 00:21:47.803409 | localhost | Region: ca-ymq-1 2026-03-06 00:21:47.803426 | localhost | Label: ansible-fedora-37-1vcpu 2026-03-06 00:21:47.803443 | localhost | Product Name: OpenStack Nova 2026-03-06 00:21:47.803460 | localhost | Interface IP: 162.253.55.70 2026-03-06 00:21:47.817081 | 2026-03-06 00:21:47.817288 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-03-06 00:21:48.250072 | localhost -> localhost | changed 2026-03-06 00:21:48.256891 | 2026-03-06 00:21:48.257028 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-03-06 00:21:49.153712 | localhost -> localhost | changed 2026-03-06 00:21:49.195842 | 2026-03-06 00:21:49.196141 | PLAY [all:!appliance*] 2026-03-06 00:21:49.219206 | 2026-03-06 00:21:49.219309 | TASK [include_role : start-zuul-console] 2026-03-06 00:21:49.241659 | controller | ok 2026-03-06 00:21:49.260877 | 2026-03-06 00:21:49.260978 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-03-06 00:21:49.655994 | controller | ok 2026-03-06 00:21:49.679944 | 2026-03-06 00:21:49.680138 | TASK [use-our-mirror : Retrieve the IP address] 2026-03-06 00:21:50.628491 | controller | ok: Status code was 404 and not [200]: HTTP Error 404: Not Found 2026-03-06 00:21:50.642239 | 2026-03-06 00:21:50.642383 | TASK [use-our-mirror : Ensure the registry service is running on the mirror] 2026-03-06 00:21:51.191388 | controller | skipping: Conditional result was False 2026-03-06 00:21:51.205160 | 2026-03-06 00:21:51.205344 | TASK [use-our-mirror : Write the IP in /etc/hosts] 2026-03-06 00:21:51.233061 | controller | skipping: Conditional result was False 2026-03-06 00:21:51.247686 | 2026-03-06 00:21:51.247935 | TASK [use-our-mirror : Create the podman configuration directory] 2026-03-06 00:21:51.274863 | controller | skipping: Conditional result was False 2026-03-06 00:21:51.289360 | 2026-03-06 00:21:51.289568 | TASK [use-our-mirror : Copy the podman configuration] 2026-03-06 00:21:51.316200 | controller | skipping: Conditional result was False 2026-03-06 00:21:51.330764 | 2026-03-06 00:21:51.331001 | TASK [use-our-mirror : Copy the Yum configuration (main)] 2026-03-06 00:21:51.357845 | controller | skipping: Conditional result was False 2026-03-06 00:21:51.372008 | 2026-03-06 00:21:51.372178 | TASK [use-our-mirror : Copy the Yum configuration (update)] 2026-03-06 00:21:51.398478 | controller | skipping: Conditional result was False 2026-03-06 00:21:51.427102 | 2026-03-06 00:21:51.427297 | TASK [Disable Fedora Modular] 2026-03-06 00:21:51.712381 | controller | changed 2026-03-06 00:21:51.725227 | 2026-03-06 00:21:51.725394 | TASK [Enable EPEL] 2026-03-06 00:21:51.752820 | controller | skipping: Conditional result was False 2026-03-06 00:21:51.765583 | 2026-03-06 00:21:51.765815 | TASK [Register the RHEL node] 2026-03-06 00:21:52.337584 | 2026-03-06 00:21:52.337960 | TASK [Show the subscription-manager status] 2026-03-06 00:21:52.933410 | controller | skipping: Conditional result was False 2026-03-06 00:21:52.949547 | 2026-03-06 00:21:52.949849 | TASK [Enable EPEL on RHEL] 2026-03-06 00:21:53.500779 | controller | skipping: Conditional result was False 2026-03-06 00:21:53.507495 | 2026-03-06 00:21:53.507645 | TASK [Install git and tox] 2026-03-06 00:23:12.472596 | controller | changed 2026-03-06 00:23:12.488357 | 2026-03-06 00:23:12.488533 | TASK [include_role : prepare-workspace] 2026-03-06 00:23:12.523312 | controller | ok 2026-03-06 00:23:12.557279 | 2026-03-06 00:23:12.557422 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-03-06 00:23:12.804117 | controller | ok 2026-03-06 00:23:12.810691 | 2026-03-06 00:23:12.810773 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-03-06 00:23:25.444087 | controller | Output suppressed because no_log was given 2026-03-06 00:23:25.463123 | 2026-03-06 00:23:25.463267 | TASK [include_role : prepare-workspace-openshift] 2026-03-06 00:23:25.479432 | controller | skipping: Conditional result was False 2026-03-06 00:23:25.536298 | 2026-03-06 00:23:25.536384 | PLAY [all:!appliance] 2026-03-06 00:23:25.552078 | 2026-03-06 00:23:25.552179 | TASK [Run add-build-sshkey role (RSA)] 2026-03-06 00:23:25.573159 | controller | ok 2026-03-06 00:23:25.587883 | 2026-03-06 00:23:25.587993 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-03-06 00:23:25.818173 | controller -> localhost | ok 2026-03-06 00:23:25.831957 | 2026-03-06 00:23:25.832203 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-03-06 00:23:25.869008 | controller | ok 2026-03-06 00:23:25.891497 | controller | included: /var/lib/zuul/builds/e62bdfec3a3e4c5e96167eb783553060/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-03-06 00:23:25.901287 | 2026-03-06 00:23:25.901449 | TASK [add-build-sshkey : Create Temp SSH key] 2026-03-06 00:23:26.367839 | controller -> localhost | Generating public/private rsa key pair. 2026-03-06 00:23:26.368020 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/e62bdfec3a3e4c5e96167eb783553060/work/e62bdfec3a3e4c5e96167eb783553060_id_rsa. 2026-03-06 00:23:26.368050 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/e62bdfec3a3e4c5e96167eb783553060/work/e62bdfec3a3e4c5e96167eb783553060_id_rsa.pub. 2026-03-06 00:23:26.368071 | controller -> localhost | The key fingerprint is: 2026-03-06 00:23:26.368091 | controller -> localhost | SHA256:MkMQOFMyBoP4dygvOODL2GWFlbehQjvWCv27kQ0wCfk zuul-build-sshkey 2026-03-06 00:23:26.368111 | controller -> localhost | The key's randomart image is: 2026-03-06 00:23:26.368130 | controller -> localhost | +---[RSA 2048]----+ 2026-03-06 00:23:26.368149 | controller -> localhost | |=.=++. . | 2026-03-06 00:23:26.368169 | controller -> localhost | |oo=+.oo o | 2026-03-06 00:23:26.368188 | controller -> localhost | | . *+*.o o | 2026-03-06 00:23:26.368206 | controller -> localhost | |. + E+= . | 2026-03-06 00:23:26.368224 | controller -> localhost | |o. * B= S | 2026-03-06 00:23:26.368275 | controller -> localhost | |o.. = .B | 2026-03-06 00:23:26.368304 | controller -> localhost | |oo.+ o.. | 2026-03-06 00:23:26.368324 | controller -> localhost | |.o. .. | 2026-03-06 00:23:26.368343 | controller -> localhost | | .. | 2026-03-06 00:23:26.368364 | controller -> localhost | +----[SHA256]-----+ 2026-03-06 00:23:26.368410 | controller -> localhost | ok: Runtime: 0:00:00.061892 2026-03-06 00:23:26.375374 | 2026-03-06 00:23:26.375444 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-03-06 00:23:26.407583 | controller | ok 2026-03-06 00:23:26.417957 | controller | included: /var/lib/zuul/builds/e62bdfec3a3e4c5e96167eb783553060/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-03-06 00:23:26.428579 | 2026-03-06 00:23:26.428737 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-03-06 00:23:26.453815 | controller | skipping: Conditional result was False 2026-03-06 00:23:26.460578 | 2026-03-06 00:23:26.460706 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-03-06 00:23:26.947927 | controller | changed 2026-03-06 00:23:26.958439 | 2026-03-06 00:23:26.958547 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-03-06 00:23:27.199595 | controller | ok 2026-03-06 00:23:27.210862 | 2026-03-06 00:23:27.210972 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-03-06 00:23:27.850767 | controller | changed 2026-03-06 00:23:27.857816 | 2026-03-06 00:23:27.857959 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-03-06 00:23:28.508119 | controller | changed 2026-03-06 00:23:28.515597 | 2026-03-06 00:23:28.515759 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-03-06 00:23:28.530828 | controller | skipping: Conditional result was False 2026-03-06 00:23:28.539043 | 2026-03-06 00:23:28.539218 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-03-06 00:23:28.946011 | controller -> localhost | changed 2026-03-06 00:23:28.958598 | 2026-03-06 00:23:28.958810 | TASK [add-build-sshkey : Add back temp key] 2026-03-06 00:23:29.238610 | controller -> localhost | Identity added: /var/lib/zuul/builds/e62bdfec3a3e4c5e96167eb783553060/work/e62bdfec3a3e4c5e96167eb783553060_id_rsa (zuul-build-sshkey) 2026-03-06 00:23:29.238861 | controller -> localhost | ok: Runtime: 0:00:00.011062 2026-03-06 00:23:29.245469 | 2026-03-06 00:23:29.245535 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-03-06 00:23:29.622028 | controller | ok 2026-03-06 00:23:29.630781 | 2026-03-06 00:23:29.630901 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-03-06 00:23:29.668411 | controller | skipping: Conditional result was False 2026-03-06 00:23:29.685545 | 2026-03-06 00:23:29.685725 | TASK [Run add-build-sshkey role (ECDSA)] 2026-03-06 00:23:29.718047 | controller | ok 2026-03-06 00:23:29.742396 | 2026-03-06 00:23:29.742553 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-03-06 00:23:30.004384 | controller -> localhost | ok 2026-03-06 00:23:30.020890 | 2026-03-06 00:23:30.021125 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-03-06 00:23:30.048160 | controller | ok 2026-03-06 00:23:30.064808 | controller | included: /var/lib/zuul/builds/e62bdfec3a3e4c5e96167eb783553060/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-03-06 00:23:30.076150 | 2026-03-06 00:23:30.076269 | TASK [add-build-sshkey : Create Temp SSH key] 2026-03-06 00:23:30.417563 | controller -> localhost | Generating public/private ecdsa key pair. 2026-03-06 00:23:30.417910 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/e62bdfec3a3e4c5e96167eb783553060/work/e62bdfec3a3e4c5e96167eb783553060_id_ecdsa. 2026-03-06 00:23:30.417973 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/e62bdfec3a3e4c5e96167eb783553060/work/e62bdfec3a3e4c5e96167eb783553060_id_ecdsa.pub. 2026-03-06 00:23:30.418034 | controller -> localhost | The key fingerprint is: 2026-03-06 00:23:30.418076 | controller -> localhost | SHA256:Nqay0g6LJLztV0xZadFqEeeevEqUOxzM2QuFYx/VTZY zuul-build-sshkey 2026-03-06 00:23:30.418116 | controller -> localhost | The key's randomart image is: 2026-03-06 00:23:30.418155 | controller -> localhost | +---[ECDSA 521]---+ 2026-03-06 00:23:30.418194 | controller -> localhost | | o=. ...=| 2026-03-06 00:23:30.418231 | controller -> localhost | | =+.. E.| 2026-03-06 00:23:30.418268 | controller -> localhost | | ++o+ | 2026-03-06 00:23:30.418306 | controller -> localhost | | o+oX o | 2026-03-06 00:23:30.418343 | controller -> localhost | | o SO * | 2026-03-06 00:23:30.418380 | controller -> localhost | |. *o.+ o | 2026-03-06 00:23:30.418416 | controller -> localhost | |.o... o = o | 2026-03-06 00:23:30.418453 | controller -> localhost | |o.+o.+ . o | 2026-03-06 00:23:30.418490 | controller -> localhost | |..o=+ . | 2026-03-06 00:23:30.418527 | controller -> localhost | +----[SHA256]-----+ 2026-03-06 00:23:30.418617 | controller -> localhost | ok: Runtime: 0:00:00.011247 2026-03-06 00:23:30.435025 | 2026-03-06 00:23:30.435165 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-03-06 00:23:30.473821 | controller | ok 2026-03-06 00:23:30.492044 | controller | included: /var/lib/zuul/builds/e62bdfec3a3e4c5e96167eb783553060/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-03-06 00:23:30.507162 | 2026-03-06 00:23:30.507257 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-03-06 00:23:30.542594 | controller | skipping: Conditional result was False 2026-03-06 00:23:30.551831 | 2026-03-06 00:23:30.551930 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-03-06 00:23:30.831187 | controller | changed 2026-03-06 00:23:30.839475 | 2026-03-06 00:23:30.839573 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-03-06 00:23:31.072463 | controller | ok 2026-03-06 00:23:31.087133 | 2026-03-06 00:23:31.087404 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-03-06 00:23:31.734623 | controller | changed 2026-03-06 00:23:31.750510 | 2026-03-06 00:23:31.750807 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-03-06 00:23:32.393704 | controller | changed 2026-03-06 00:23:32.408159 | 2026-03-06 00:23:32.408344 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-03-06 00:23:32.436589 | controller | skipping: Conditional result was False 2026-03-06 00:23:32.451278 | 2026-03-06 00:23:32.451436 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-03-06 00:23:32.767209 | controller -> localhost | changed 2026-03-06 00:23:32.840322 | 2026-03-06 00:23:32.840475 | TASK [add-build-sshkey : Add back temp key] 2026-03-06 00:23:33.151128 | controller -> localhost | Identity added: /var/lib/zuul/builds/e62bdfec3a3e4c5e96167eb783553060/work/e62bdfec3a3e4c5e96167eb783553060_id_ecdsa (zuul-build-sshkey) 2026-03-06 00:23:33.151485 | controller -> localhost | ok: Runtime: 0:00:00.013964 2026-03-06 00:23:33.167087 | 2026-03-06 00:23:33.167237 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-03-06 00:23:33.392637 | controller | ok 2026-03-06 00:23:33.405853 | 2026-03-06 00:23:33.406025 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-03-06 00:23:33.444700 | controller | skipping: Conditional result was False 2026-03-06 00:23:33.461772 | 2026-03-06 00:23:33.461947 | TASK [include_role : remove-zuul-sshkey] 2026-03-06 00:23:33.489344 | controller | skipping: Conditional result was False 2026-03-06 00:23:33.496926 | 2026-03-06 00:23:33.497058 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-03-06 00:23:33.754736 | controller | ok: "logs" 2026-03-06 00:23:33.755236 | controller | ok: All items complete 2026-03-06 00:23:33.755303 | 2026-03-06 00:23:33.951954 | controller | ok: "artifacts" 2026-03-06 00:23:34.143328 | controller | ok: "docs" 2026-03-06 00:23:34.158375 | 2026-03-06 00:23:34.158533 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-03-06 00:23:34.402393 | controller | changed: "logs" 2026-03-06 00:23:34.604455 | controller | changed: "artifacts" 2026-03-06 00:23:34.810605 | controller | changed: "docs" 2026-03-06 00:23:34.881187 | 2026-03-06 00:23:34.881339 | PLAY RECAP 2026-03-06 00:23:34.881401 | controller | ok: 30 changed: 16 unreachable: 0 failed: 0 skipped: 18 rescued: 0 ignored: 0 2026-03-06 00:23:34.881436 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-03-06 00:23:34.881460 | 2026-03-06 00:23:35.015780 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master] 2026-03-06 00:23:35.016846 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2026-03-06 00:23:35.615168 | 2026-03-06 00:23:35.615315 | PLAY [all] 2026-03-06 00:23:35.639981 | 2026-03-06 00:23:35.640145 | TASK [Install binary dependencies] 2026-03-06 00:23:35.713122 | controller | ok 2026-03-06 00:23:35.733733 | 2026-03-06 00:23:35.733911 | TASK [bindep : Include find tasks] 2026-03-06 00:23:35.774111 | controller | ok 2026-03-06 00:23:35.781871 | controller | included: /var/lib/zuul/builds/e62bdfec3a3e4c5e96167eb783553060/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find.yaml 2026-03-06 00:23:35.788354 | 2026-03-06 00:23:35.788429 | TASK [bindep : Look for bindep.txt] 2026-03-06 00:23:36.137825 | controller | ok 2026-03-06 00:23:36.145218 | 2026-03-06 00:23:36.145311 | TASK [bindep : Define bindep_file fact] 2026-03-06 00:23:36.166400 | controller | skipping: Conditional result was False 2026-03-06 00:23:36.174076 | 2026-03-06 00:23:36.174159 | TASK [bindep : Look for other-requirements.txt] 2026-03-06 00:23:36.385023 | controller | ok 2026-03-06 00:23:36.400932 | 2026-03-06 00:23:36.401217 | TASK [bindep : Define bindep_file fact] 2026-03-06 00:23:36.440491 | controller | skipping: Conditional result was False 2026-03-06 00:23:36.458219 | 2026-03-06 00:23:36.458456 | TASK [bindep : Look for bindep fallback file] 2026-03-06 00:23:36.490361 | controller | skipping: Conditional result was False 2026-03-06 00:23:36.509268 | 2026-03-06 00:23:36.509515 | TASK [bindep : Define bindep_file fact] 2026-03-06 00:23:36.538244 | controller | skipping: Conditional result was False 2026-03-06 00:23:36.555104 | 2026-03-06 00:23:36.555260 | TASK [bindep : Include bindep tasks] 2026-03-06 00:23:36.584194 | controller | skipping: Conditional result was False 2026-03-06 00:23:36.598748 | 2026-03-06 00:23:36.598914 | TASK [bindep : Include install tasks] 2026-03-06 00:23:36.627346 | controller | skipping: Conditional result was False 2026-03-06 00:23:36.639328 | 2026-03-06 00:23:36.639428 | LOOP [bindep : Include package tasks] 2026-03-06 00:23:36.710549 | 2026-03-06 00:23:36.710814 | TASK [Run test-setup role] 2026-03-06 00:23:36.733989 | controller | ok 2026-03-06 00:23:36.758451 | 2026-03-06 00:23:36.758568 | TASK [test-setup : Check if project's tools/test-setup.sh exists] 2026-03-06 00:23:36.973819 | controller | ok 2026-03-06 00:23:36.980883 | 2026-03-06 00:23:36.980952 | TASK [test-setup : Run tools/test-setup.sh] 2026-03-06 00:23:37.514582 | controller | skipping: Conditional result was False 2026-03-06 00:23:37.544759 | 2026-03-06 00:23:37.544979 | PLAY RECAP 2026-03-06 00:23:37.545035 | controller | ok: 4 changed: 0 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2026-03-06 00:23:37.545056 | 2026-03-06 00:23:37.674076 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2026-03-06 00:23:37.675116 | PRE-RUN START: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master] 2026-03-06 00:23:38.342604 | 2026-03-06 00:23:38.342784 | PLAY [controller] 2026-03-06 00:23:38.363164 | 2026-03-06 00:23:38.363309 | TASK [Create the /root directory] 2026-03-06 00:25:50.687264 | controller | ERROR 2026-03-06 00:25:50.687634 | controller | { 2026-03-06 00:25:50.687815 | controller | "msg": "Timeout (32s) waiting for privilege escalation prompt: " 2026-03-06 00:25:50.687881 | controller | } failure 2026-03-06 00:25:50.690147 | 2026-03-06 00:25:50.690251 | PLAY RECAP 2026-03-06 00:25:50.690334 | controller | ok: 0 changed: 0 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0 2026-03-06 00:25:50.690382 | 2026-03-06 00:25:50.814096 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master] 2026-03-06 00:25:50.815171 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2026-03-06 00:25:51.440814 | 2026-03-06 00:25:51.440949 | PLAY [all] 2026-03-06 00:25:51.463878 | 2026-03-06 00:25:51.463990 | TASK [fetch-subunit-output : Find stestr or testr executable] 2026-03-06 00:28:38.936443 | controller | changed: non-zero return code 2026-03-06 00:28:38.943854 | 2026-03-06 00:28:38.943979 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2026-03-06 00:28:38.968078 | controller | skipping: Conditional result was False 2026-03-06 00:28:38.974145 | 2026-03-06 00:28:38.974230 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2026-03-06 00:28:38.994339 | 2026-03-06 00:28:38.994499 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2026-03-06 00:28:39.015690 | 2026-03-06 00:28:39.015885 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2026-03-06 00:28:39.031163 | controller | skipping: Conditional result was False 2026-03-06 00:28:39.038406 | 2026-03-06 00:28:39.038482 | LOOP [fetch-subunit-output : Generate subunit file] 2026-03-06 00:28:39.071257 | 2026-03-06 00:28:39.071439 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2026-03-06 00:28:39.095988 | controller | skipping: Conditional result was False 2026-03-06 00:28:39.103216 | 2026-03-06 00:28:39.103317 | TASK [fetch-subunit-output : Remove the temporary file] 2026-03-06 00:28:39.127491 | controller | skipping: Conditional result was False 2026-03-06 00:28:39.134177 | 2026-03-06 00:28:39.134271 | TASK [fetch-subunit-output : Process and fetch subunit results] 2026-03-06 00:28:39.158745 | controller | skipping: Conditional result was False 2026-03-06 00:28:39.187529 | 2026-03-06 00:28:39.187637 | PLAY RECAP 2026-03-06 00:28:39.187703 | controller | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2026-03-06 00:28:39.187733 | 2026-03-06 00:28:39.307423 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2026-03-06 00:28:39.308400 | POST-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/post.yaml@master] 2026-03-06 00:28:39.914258 | 2026-03-06 00:28:39.914418 | PLAY [all:!appliance*] 2026-03-06 00:28:39.937990 | 2026-03-06 00:28:39.938122 | TASK [unregister the node] 2026-03-06 00:28:40.469592 | controller | skipping: Conditional result was False 2026-03-06 00:28:40.478683 | 2026-03-06 00:28:40.478864 | TASK [include_role : fetch-output] 2026-03-06 00:28:40.529743 | controller | ok 2026-03-06 00:28:40.570610 | 2026-03-06 00:28:40.570807 | TASK [fetch-output : Set log path for multiple nodes] 2026-03-06 00:28:40.647746 | controller | skipping: Conditional result was False 2026-03-06 00:28:40.657018 | 2026-03-06 00:28:40.657132 | TASK [fetch-output : Set log path for single node] 2026-03-06 00:28:40.701960 | controller | ok 2026-03-06 00:28:40.709726 | 2026-03-06 00:28:40.709826 | LOOP [fetch-output : Ensure local output dirs] 2026-03-06 00:28:41.146880 | controller -> localhost | ok: "/var/lib/zuul/builds/e62bdfec3a3e4c5e96167eb783553060/work/logs" 2026-03-06 00:28:41.431437 | controller -> localhost | changed: "/var/lib/zuul/builds/e62bdfec3a3e4c5e96167eb783553060/work/artifacts" 2026-03-06 00:28:41.736614 | controller -> localhost | changed: "/var/lib/zuul/builds/e62bdfec3a3e4c5e96167eb783553060/work/docs" 2026-03-06 00:28:41.755460 | 2026-03-06 00:28:41.755593 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-03-06 00:28:42.503328 | controller | ok 2026-03-06 00:28:42.503593 | controller | changed: All items complete 2026-03-06 00:28:42.503631 | 2026-03-06 00:28:43.033170 | controller | changed: .d..t...... ./ 2026-03-06 00:28:43.573899 | controller | changed: .d..t...... ./ 2026-03-06 00:28:43.600428 | 2026-03-06 00:28:43.600613 | TASK [include_role : fetch-output-openshift] 2026-03-06 00:28:43.626223 | controller | skipping: Conditional result was False 2026-03-06 00:28:43.636592 | 2026-03-06 00:28:43.636784 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-03-06 00:28:43.688975 | controller | skipping: Conditional result was False 2026-03-06 00:28:43.700981 | controller | skipping: Conditional result was False 2026-03-06 00:28:43.743847 | 2026-03-06 00:28:43.743978 | PLAY [localhost] 2026-03-06 00:28:43.760076 | 2026-03-06 00:28:43.760192 | TASK [Run Zuul manifest role] 2026-03-06 00:28:43.779678 | localhost | ok 2026-03-06 00:28:43.793965 | 2026-03-06 00:28:43.794044 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-03-06 00:28:44.205595 | localhost | changed 2026-03-06 00:28:44.215860 | 2026-03-06 00:28:44.216035 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-03-06 00:28:44.263404 | localhost | ok 2026-03-06 00:28:44.283264 | 2026-03-06 00:28:44.283418 | TASK [Set zuul-log-path fact] 2026-03-06 00:28:44.306708 | localhost | ok 2026-03-06 00:28:44.325836 | 2026-03-06 00:28:44.325950 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-03-06 00:28:44.367935 | localhost | ok 2026-03-06 00:28:44.379592 | 2026-03-06 00:28:44.379717 | LOOP [Set zuul_log_targets (Swift with random Rackspace region)] 2026-03-06 00:28:44.429105 | localhost | ok: 2026-03-06 00:28:44.429474 | localhost | { 2026-03-06 00:28:44.429533 | localhost | "auth": { 2026-03-06 00:28:44.429575 | localhost | "password": "IonWCwcuOFib&&<}wp^:[2G/", 2026-03-06 00:28:44.429612 | localhost | "username": "9LF91O0RK7JU" 2026-03-06 00:28:44.429660 | localhost | }, 2026-03-06 00:28:44.429739 | localhost | "profile": "rackspace", 2026-03-06 00:28:44.429778 | localhost | "project_name": "1251317", 2026-03-06 00:28:44.429814 | localhost | "region_name": "DFW" 2026-03-06 00:28:44.429850 | localhost | } 2026-03-06 00:28:44.429898 | 2026-03-06 00:28:44.444174 | 2026-03-06 00:28:44.444328 | TASK [Run upload-logs-failover role (S3 then Swift)] 2026-03-06 00:28:44.466545 | localhost | Output suppressed because no_log was given 2026-03-06 00:28:44.484114 | 2026-03-06 00:28:44.484243 | TASK [upload-logs-failover : Initialize control vars] 2026-03-06 00:28:44.516518 | localhost | ok 2026-03-06 00:28:44.522451 | 2026-03-06 00:28:44.522541 | LOOP [upload-logs-failover : Perform log upload] 2026-03-06 00:28:44.618073 | localhost | Output suppressed because no_log was given 2026-03-06 00:28:44.634831 | localhost | included: /var/lib/zuul/builds/e62bdfec3a3e4c5e96167eb783553060/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/upload-logs-failover/tasks/upload.yaml 2026-03-06 00:28:44.653249 | 2026-03-06 00:28:44.653434 | LOOP [upload-logs-failover : Unpack vars for swift upload] 2026-03-06 00:28:44.754774 | localhost | Output suppressed because no_log was given 2026-03-06 00:28:44.763062 | 2026-03-06 00:28:44.763201 | TASK [Upload logs to swift] 2026-03-06 00:28:44.797569 | localhost | ok 2026-03-06 00:28:44.849429 | 2026-03-06 00:28:44.849783 | TASK [Set zuul-log-path fact] 2026-03-06 00:28:44.874808 | localhost | skipping: Conditional result was False 2026-03-06 00:28:44.881438 | 2026-03-06 00:28:44.881567 | TASK [upload-logs-swift : Ensure logs are readable before uploading] 2026-03-06 00:28:45.282324 | localhost -> localhost | ok: Runtime: 0:00:00.006808 2026-03-06 00:28:45.290797 | 2026-03-06 00:28:45.290900 | TASK [upload-logs-swift : Upload logs to swift]