2026-01-11 00:21:08.781110 | Job console starting... 2026-01-11 00:21:08.792580 | Updating repositories 2026-01-11 00:21:08.916373 | Preparing job workspace 2026-01-11 00:21:12.593379 | Running Ansible setup... 2026-01-11 00:21:17.703113 | PRE-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master] 2026-01-11 00:21:18.362321 | 2026-01-11 00:21:18.362483 | PLAY [localhost] 2026-01-11 00:21:18.376399 | 2026-01-11 00:21:18.376590 | TASK [Gathering Facts] 2026-01-11 00:21:19.518990 | localhost | ok 2026-01-11 00:21:19.540348 | 2026-01-11 00:21:19.540526 | TASK [Setup log path fact] 2026-01-11 00:21:19.562225 | localhost | ok 2026-01-11 00:21:19.577385 | 2026-01-11 00:21:19.577534 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-01-11 00:21:19.608880 | localhost | ok 2026-01-11 00:21:19.618297 | 2026-01-11 00:21:19.618451 | TASK [emit-job-header : Print job information] 2026-01-11 00:21:19.657533 | # Job Information 2026-01-11 00:21:19.657753 | Ansible Version: 2.15.12 2026-01-11 00:21:19.657789 | Job: ansible-test-sanity-docker-devel 2026-01-11 00:21:19.657811 | Pipeline: periodic 2026-01-11 00:21:19.657829 | Executor: ze04.softwarefactory-project.io 2026-01-11 00:21:19.657849 | Triggered by: https://github.com/ansible-collections/ansible.network 2026-01-11 00:21:19.657875 | Log URL (when completed): https://ansible.softwarefactory-project.io/logs/ef3/ansible/ef3bed98fb324eb69d88fedbfc089b90/ 2026-01-11 00:21:19.657896 | Event ID: 732349a9225a4a0caafb47fd2fabe663 2026-01-11 00:21:19.662295 | 2026-01-11 00:21:19.662368 | LOOP [emit-job-header : Print node information] 2026-01-11 00:21:19.779084 | localhost | ok: 2026-01-11 00:21:19.779337 | localhost | # Node Information 2026-01-11 00:21:19.779393 | localhost | Inventory Hostname: controller 2026-01-11 00:21:19.779438 | localhost | Hostname: np0005581011 2026-01-11 00:21:19.779481 | localhost | Username: zuul 2026-01-11 00:21:19.779527 | localhost | Distro: Fedora 37 2026-01-11 00:21:19.779567 | localhost | Provider: ansible-vexxhost-ca-ymq-1 2026-01-11 00:21:19.779601 | localhost | Region: ca-ymq-1 2026-01-11 00:21:19.779637 | localhost | Label: ansible-fedora-37-1vcpu 2026-01-11 00:21:19.779670 | localhost | Product Name: OpenStack Nova 2026-01-11 00:21:19.779851 | localhost | Interface IP: 162.253.55.186 2026-01-11 00:21:19.798747 | 2026-01-11 00:21:19.798895 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-01-11 00:21:20.296038 | localhost -> localhost | changed 2026-01-11 00:21:20.308843 | 2026-01-11 00:21:20.308996 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-01-11 00:21:21.259627 | localhost -> localhost | changed 2026-01-11 00:21:21.287349 | 2026-01-11 00:21:21.287518 | PLAY [all:!appliance*] 2026-01-11 00:21:21.308457 | 2026-01-11 00:21:21.308618 | TASK [include_role : start-zuul-console] 2026-01-11 00:21:21.330701 | controller | ok 2026-01-11 00:21:21.345922 | 2026-01-11 00:21:21.346031 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-01-11 00:21:21.743259 | controller | ok 2026-01-11 00:21:21.762154 | 2026-01-11 00:21:21.762304 | TASK [use-our-mirror : Retrieve the IP address] 2026-01-11 00:21:22.800978 | controller | ok: Status code was 404 and not [200]: HTTP Error 404: Not Found 2026-01-11 00:21:22.820143 | 2026-01-11 00:21:22.820305 | TASK [use-our-mirror : Ensure the registry service is running on the mirror] 2026-01-11 00:21:23.371703 | controller | skipping: Conditional result was False 2026-01-11 00:21:23.384277 | 2026-01-11 00:21:23.384473 | TASK [use-our-mirror : Write the IP in /etc/hosts] 2026-01-11 00:21:23.411051 | controller | skipping: Conditional result was False 2026-01-11 00:21:23.421237 | 2026-01-11 00:21:23.421410 | TASK [use-our-mirror : Create the podman configuration directory] 2026-01-11 00:21:23.447292 | controller | skipping: Conditional result was False 2026-01-11 00:21:23.456297 | 2026-01-11 00:21:23.456514 | TASK [use-our-mirror : Copy the podman configuration] 2026-01-11 00:21:23.483431 | controller | skipping: Conditional result was False 2026-01-11 00:21:23.495652 | 2026-01-11 00:21:23.496291 | TASK [use-our-mirror : Copy the Yum configuration (main)] 2026-01-11 00:21:23.524280 | controller | skipping: Conditional result was False 2026-01-11 00:21:23.538281 | 2026-01-11 00:21:23.538500 | TASK [use-our-mirror : Copy the Yum configuration (update)] 2026-01-11 00:21:23.568800 | controller | skipping: Conditional result was False 2026-01-11 00:21:23.591373 | 2026-01-11 00:21:23.591558 | TASK [Disable Fedora Modular] 2026-01-11 00:21:23.839428 | controller | changed 2026-01-11 00:21:23.847472 | 2026-01-11 00:21:23.847615 | TASK [Enable EPEL] 2026-01-11 00:21:23.873987 | controller | skipping: Conditional result was False 2026-01-11 00:21:23.883410 | 2026-01-11 00:21:23.883581 | TASK [Register the RHEL node] 2026-01-11 00:21:23.953363 | 2026-01-11 00:21:23.953550 | TASK [Show the subscription-manager status] 2026-01-11 00:21:24.095514 | controller | skipping: Conditional result was False 2026-01-11 00:21:24.103128 | 2026-01-11 00:21:24.103201 | TASK [Enable EPEL on RHEL] 2026-01-11 00:21:24.644035 | controller | skipping: Conditional result was False 2026-01-11 00:21:24.657792 | 2026-01-11 00:21:24.657954 | TASK [Install git and tox] 2026-01-11 00:22:44.828538 | controller | changed 2026-01-11 00:22:44.835388 | 2026-01-11 00:22:44.835485 | TASK [include_role : prepare-workspace] 2026-01-11 00:22:44.859993 | controller | ok 2026-01-11 00:22:44.890754 | 2026-01-11 00:22:44.890908 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-01-11 00:22:45.134844 | controller | ok 2026-01-11 00:22:45.141981 | 2026-01-11 00:22:45.142088 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-01-11 00:22:57.842830 | controller | Output suppressed because no_log was given 2026-01-11 00:22:57.866888 | 2026-01-11 00:22:57.867034 | TASK [include_role : prepare-workspace-openshift] 2026-01-11 00:22:57.895495 | controller | skipping: Conditional result was False 2026-01-11 00:22:57.930355 | 2026-01-11 00:22:57.930490 | PLAY [all:!appliance] 2026-01-11 00:22:57.949176 | 2026-01-11 00:22:57.949305 | TASK [Run add-build-sshkey role (RSA)] 2026-01-11 00:22:57.989951 | controller | ok 2026-01-11 00:22:58.006822 | 2026-01-11 00:22:58.006944 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-01-11 00:22:58.235778 | controller -> localhost | ok 2026-01-11 00:22:58.243646 | 2026-01-11 00:22:58.243793 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-01-11 00:22:58.265442 | controller | ok 2026-01-11 00:22:58.281803 | controller | included: /var/lib/zuul/builds/ef3bed98fb324eb69d88fedbfc089b90/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-01-11 00:22:58.288923 | 2026-01-11 00:22:58.288999 | TASK [add-build-sshkey : Create Temp SSH key] 2026-01-11 00:22:58.844842 | controller -> localhost | Generating public/private rsa key pair. 2026-01-11 00:22:58.845186 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/ef3bed98fb324eb69d88fedbfc089b90/work/ef3bed98fb324eb69d88fedbfc089b90_id_rsa. 2026-01-11 00:22:58.845228 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/ef3bed98fb324eb69d88fedbfc089b90/work/ef3bed98fb324eb69d88fedbfc089b90_id_rsa.pub. 2026-01-11 00:22:58.845258 | controller -> localhost | The key fingerprint is: 2026-01-11 00:22:58.845286 | controller -> localhost | SHA256:9gw+Ts2N1gBa8HAAx5TnboA4QUS043xe7MQPOyG8MR4 zuul-build-sshkey 2026-01-11 00:22:58.845310 | controller -> localhost | The key's randomart image is: 2026-01-11 00:22:58.845333 | controller -> localhost | +---[RSA 2048]----+ 2026-01-11 00:22:58.845398 | controller -> localhost | | == .+*o. | 2026-01-11 00:22:58.845429 | controller -> localhost | | .. .o=. | 2026-01-11 00:22:58.845457 | controller -> localhost | | oo . o+ | 2026-01-11 00:22:58.845480 | controller -> localhost | | ooo.o.o.. | 2026-01-11 00:22:58.845505 | controller -> localhost | | o.E OoS . | 2026-01-11 00:22:58.845549 | controller -> localhost | | + X Bo* = | 2026-01-11 00:22:58.845584 | controller -> localhost | | + +.= B o | 2026-01-11 00:22:58.845619 | controller -> localhost | | + o | 2026-01-11 00:22:58.845646 | controller -> localhost | | . | 2026-01-11 00:22:58.845693 | controller -> localhost | +----[SHA256]-----+ 2026-01-11 00:22:58.845775 | controller -> localhost | ok: Runtime: 0:00:00.142849 2026-01-11 00:22:58.854308 | 2026-01-11 00:22:58.854451 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-01-11 00:22:58.886774 | controller | ok 2026-01-11 00:22:58.900910 | controller | included: /var/lib/zuul/builds/ef3bed98fb324eb69d88fedbfc089b90/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-01-11 00:22:58.913663 | 2026-01-11 00:22:58.913822 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-01-11 00:22:58.940289 | controller | skipping: Conditional result was False 2026-01-11 00:22:58.950100 | 2026-01-11 00:22:58.950206 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-01-11 00:22:59.412884 | controller | changed 2026-01-11 00:22:59.418638 | 2026-01-11 00:22:59.418724 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-01-11 00:22:59.639666 | controller | ok 2026-01-11 00:22:59.653536 | 2026-01-11 00:22:59.653892 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-01-11 00:23:00.240735 | controller | changed 2026-01-11 00:23:00.250951 | 2026-01-11 00:23:00.251067 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-01-11 00:23:00.889023 | controller | changed 2026-01-11 00:23:00.899011 | 2026-01-11 00:23:00.899159 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-01-11 00:23:00.924922 | controller | skipping: Conditional result was False 2026-01-11 00:23:00.931864 | 2026-01-11 00:23:00.931946 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-01-11 00:23:01.323137 | controller -> localhost | changed 2026-01-11 00:23:01.337171 | 2026-01-11 00:23:01.337289 | TASK [add-build-sshkey : Add back temp key] 2026-01-11 00:23:01.676993 | controller -> localhost | Identity added: /var/lib/zuul/builds/ef3bed98fb324eb69d88fedbfc089b90/work/ef3bed98fb324eb69d88fedbfc089b90_id_rsa (zuul-build-sshkey) 2026-01-11 00:23:01.677298 | controller -> localhost | ok: Runtime: 0:00:00.011049 2026-01-11 00:23:01.685971 | 2026-01-11 00:23:01.686103 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-01-11 00:23:02.076537 | controller | ok 2026-01-11 00:23:02.082212 | 2026-01-11 00:23:02.082291 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-01-11 00:23:02.107011 | controller | skipping: Conditional result was False 2026-01-11 00:23:02.123883 | 2026-01-11 00:23:02.124068 | TASK [Run add-build-sshkey role (ECDSA)] 2026-01-11 00:23:02.146843 | controller | ok 2026-01-11 00:23:02.164036 | 2026-01-11 00:23:02.164163 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-01-11 00:23:02.392641 | controller -> localhost | ok 2026-01-11 00:23:02.406234 | 2026-01-11 00:23:02.406414 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-01-11 00:23:02.433051 | controller | ok 2026-01-11 00:23:02.453492 | controller | included: /var/lib/zuul/builds/ef3bed98fb324eb69d88fedbfc089b90/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-01-11 00:23:02.463848 | 2026-01-11 00:23:02.464003 | TASK [add-build-sshkey : Create Temp SSH key] 2026-01-11 00:23:02.745866 | controller -> localhost | Generating public/private ecdsa key pair. 2026-01-11 00:23:02.746333 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/ef3bed98fb324eb69d88fedbfc089b90/work/ef3bed98fb324eb69d88fedbfc089b90_id_ecdsa. 2026-01-11 00:23:02.746433 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/ef3bed98fb324eb69d88fedbfc089b90/work/ef3bed98fb324eb69d88fedbfc089b90_id_ecdsa.pub. 2026-01-11 00:23:02.746525 | controller -> localhost | The key fingerprint is: 2026-01-11 00:23:02.748327 | controller -> localhost | SHA256:jyNaMzszvQ0pgsAeS1NJh7oEMCUqKMLykEd68myyMFs zuul-build-sshkey 2026-01-11 00:23:02.748458 | controller -> localhost | The key's randomart image is: 2026-01-11 00:23:02.748523 | controller -> localhost | +---[ECDSA 521]---+ 2026-01-11 00:23:02.748570 | controller -> localhost | |=.o... | 2026-01-11 00:23:02.748612 | controller -> localhost | |=*..o | 2026-01-11 00:23:02.748655 | controller -> localhost | |&.+o | 2026-01-11 00:23:02.748742 | controller -> localhost | |*@. | 2026-01-11 00:23:02.748788 | controller -> localhost | |*=E S | 2026-01-11 00:23:02.748829 | controller -> localhost | |+X+. + | 2026-01-11 00:23:02.748867 | controller -> localhost | |oo. . *.= . | 2026-01-11 00:23:02.748906 | controller -> localhost | | ++*.+ | 2026-01-11 00:23:02.748945 | controller -> localhost | | . .+... | 2026-01-11 00:23:02.748998 | controller -> localhost | +----[SHA256]-----+ 2026-01-11 00:23:02.749123 | controller -> localhost | ok: Runtime: 0:00:00.008735 2026-01-11 00:23:02.765108 | 2026-01-11 00:23:02.765308 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-01-11 00:23:02.808503 | controller | ok 2026-01-11 00:23:02.827143 | controller | included: /var/lib/zuul/builds/ef3bed98fb324eb69d88fedbfc089b90/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-01-11 00:23:02.843966 | 2026-01-11 00:23:02.844127 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-01-11 00:23:02.870180 | controller | skipping: Conditional result was False 2026-01-11 00:23:02.881808 | 2026-01-11 00:23:02.881980 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-01-11 00:23:03.169048 | controller | changed 2026-01-11 00:23:03.186643 | 2026-01-11 00:23:03.187183 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-01-11 00:23:03.424061 | controller | ok 2026-01-11 00:23:03.436930 | 2026-01-11 00:23:03.437138 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-01-11 00:23:04.075277 | controller | changed 2026-01-11 00:23:04.091318 | 2026-01-11 00:23:04.091536 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-01-11 00:23:04.741539 | controller | changed 2026-01-11 00:23:04.750382 | 2026-01-11 00:23:04.750472 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-01-11 00:23:04.776236 | controller | skipping: Conditional result was False 2026-01-11 00:23:04.787543 | 2026-01-11 00:23:04.787730 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-01-11 00:23:05.122911 | controller -> localhost | changed 2026-01-11 00:23:05.141123 | 2026-01-11 00:23:05.141280 | TASK [add-build-sshkey : Add back temp key] 2026-01-11 00:23:05.454268 | controller -> localhost | Identity added: /var/lib/zuul/builds/ef3bed98fb324eb69d88fedbfc089b90/work/ef3bed98fb324eb69d88fedbfc089b90_id_ecdsa (zuul-build-sshkey) 2026-01-11 00:23:05.454657 | controller -> localhost | ok: Runtime: 0:00:00.014247 2026-01-11 00:23:05.465983 | 2026-01-11 00:23:05.466108 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-01-11 00:23:05.681652 | controller | ok 2026-01-11 00:23:05.688803 | 2026-01-11 00:23:05.688962 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-01-11 00:23:05.724649 | controller | skipping: Conditional result was False 2026-01-11 00:23:05.745123 | 2026-01-11 00:23:05.745286 | TASK [include_role : remove-zuul-sshkey] 2026-01-11 00:23:05.771890 | controller | skipping: Conditional result was False 2026-01-11 00:23:05.782516 | 2026-01-11 00:23:05.782735 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-01-11 00:23:06.021923 | controller | ok: "logs" 2026-01-11 00:23:06.022263 | controller | ok: All items complete 2026-01-11 00:23:06.022304 | 2026-01-11 00:23:06.231831 | controller | ok: "artifacts" 2026-01-11 00:23:06.434197 | controller | ok: "docs" 2026-01-11 00:23:06.455436 | 2026-01-11 00:23:06.455638 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-01-11 00:23:06.728315 | controller | changed: "logs" 2026-01-11 00:23:06.934776 | controller | changed: "artifacts" 2026-01-11 00:23:07.130235 | controller | changed: "docs" 2026-01-11 00:23:07.159627 | 2026-01-11 00:23:07.160005 | PLAY RECAP 2026-01-11 00:23:07.160059 | controller | ok: 30 changed: 16 unreachable: 0 failed: 0 skipped: 18 rescued: 0 ignored: 0 2026-01-11 00:23:07.160085 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-01-11 00:23:07.160103 | 2026-01-11 00:23:07.329658 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master] 2026-01-11 00:23:07.331017 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2026-01-11 00:23:07.989478 | 2026-01-11 00:23:07.989631 | PLAY [all] 2026-01-11 00:23:08.015273 | 2026-01-11 00:23:08.015430 | TASK [Install binary dependencies] 2026-01-11 00:23:08.066983 | controller | ok 2026-01-11 00:23:08.088888 | 2026-01-11 00:23:08.089089 | TASK [bindep : Include find tasks] 2026-01-11 00:23:08.119765 | controller | ok 2026-01-11 00:23:08.127902 | controller | included: /var/lib/zuul/builds/ef3bed98fb324eb69d88fedbfc089b90/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find.yaml 2026-01-11 00:23:08.134338 | 2026-01-11 00:23:08.134408 | TASK [bindep : Look for bindep.txt] 2026-01-11 00:23:08.522649 | controller | ok 2026-01-11 00:23:08.529134 | 2026-01-11 00:23:08.529223 | TASK [bindep : Define bindep_file fact] 2026-01-11 00:23:08.564724 | controller | skipping: Conditional result was False 2026-01-11 00:23:08.582129 | 2026-01-11 00:23:08.582273 | TASK [bindep : Look for other-requirements.txt] 2026-01-11 00:23:08.787522 | controller | ok 2026-01-11 00:23:08.793181 | 2026-01-11 00:23:08.793254 | TASK [bindep : Define bindep_file fact] 2026-01-11 00:23:08.818330 | controller | skipping: Conditional result was False 2026-01-11 00:23:08.825529 | 2026-01-11 00:23:08.825626 | TASK [bindep : Look for bindep fallback file] 2026-01-11 00:23:08.851005 | controller | skipping: Conditional result was False 2026-01-11 00:23:08.859274 | 2026-01-11 00:23:08.859357 | TASK [bindep : Define bindep_file fact] 2026-01-11 00:23:08.885401 | controller | skipping: Conditional result was False 2026-01-11 00:23:08.892251 | 2026-01-11 00:23:08.892334 | TASK [bindep : Include bindep tasks] 2026-01-11 00:23:08.916537 | controller | skipping: Conditional result was False 2026-01-11 00:23:08.926095 | 2026-01-11 00:23:08.926219 | TASK [bindep : Include install tasks] 2026-01-11 00:23:08.972116 | controller | skipping: Conditional result was False 2026-01-11 00:23:08.979195 | 2026-01-11 00:23:08.979271 | LOOP [bindep : Include package tasks] 2026-01-11 00:23:09.035782 | 2026-01-11 00:23:09.035977 | TASK [Run test-setup role] 2026-01-11 00:23:09.059743 | controller | ok 2026-01-11 00:23:09.083674 | 2026-01-11 00:23:09.083833 | TASK [test-setup : Check if project's tools/test-setup.sh exists] 2026-01-11 00:23:09.307195 | controller | ok 2026-01-11 00:23:09.318743 | 2026-01-11 00:23:09.319151 | TASK [test-setup : Run tools/test-setup.sh] 2026-01-11 00:23:39.391283 | [Zuul] Log Stream did not terminate 2026-01-11 00:23:39.391657 | controller | skipping: Conditional result was False 2026-01-11 00:23:39.430349 | 2026-01-11 00:23:39.430480 | PLAY RECAP 2026-01-11 00:23:39.430536 | controller | ok: 4 changed: 0 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2026-01-11 00:23:39.430565 | 2026-01-11 00:23:39.567944 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2026-01-11 00:23:39.568890 | PRE-RUN START: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master] 2026-01-11 00:23:40.186987 | 2026-01-11 00:23:40.187143 | PLAY [controller] 2026-01-11 00:23:40.210855 | 2026-01-11 00:23:40.211023 | TASK [Create the /root directory] 2026-01-11 00:25:52.566120 | controller | ERROR 2026-01-11 00:25:52.566527 | controller | { 2026-01-11 00:25:52.566617 | controller | "msg": "Timeout (32s) waiting for privilege escalation prompt: " 2026-01-11 00:25:52.566713 | controller | } failure 2026-01-11 00:25:52.568840 | 2026-01-11 00:25:52.568944 | PLAY RECAP 2026-01-11 00:25:52.569027 | controller | ok: 0 changed: 0 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0 2026-01-11 00:25:52.569075 | 2026-01-11 00:25:52.697633 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master] 2026-01-11 00:25:52.700040 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2026-01-11 00:25:53.327062 | 2026-01-11 00:25:53.327203 | PLAY [all] 2026-01-11 00:25:53.349498 | 2026-01-11 00:25:53.349619 | TASK [fetch-subunit-output : Find stestr or testr executable] 2026-01-11 00:28:09.948994 | controller | changed: non-zero return code 2026-01-11 00:28:09.958282 | 2026-01-11 00:28:09.958438 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2026-01-11 00:28:09.984734 | controller | skipping: Conditional result was False 2026-01-11 00:28:09.993484 | 2026-01-11 00:28:09.993622 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2026-01-11 00:28:10.030061 | 2026-01-11 00:28:10.030275 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2026-01-11 00:28:10.063869 | 2026-01-11 00:28:10.064073 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2026-01-11 00:28:10.078656 | controller | skipping: Conditional result was False 2026-01-11 00:28:10.085527 | 2026-01-11 00:28:10.085605 | LOOP [fetch-subunit-output : Generate subunit file] 2026-01-11 00:28:10.116443 | 2026-01-11 00:28:10.116608 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2026-01-11 00:28:10.141568 | controller | skipping: Conditional result was False 2026-01-11 00:28:10.152100 | 2026-01-11 00:28:10.152190 | TASK [fetch-subunit-output : Remove the temporary file] 2026-01-11 00:28:10.167921 | controller | skipping: Conditional result was False 2026-01-11 00:28:10.176456 | 2026-01-11 00:28:10.176536 | TASK [fetch-subunit-output : Process and fetch subunit results] 2026-01-11 00:28:10.203123 | controller | skipping: Conditional result was False 2026-01-11 00:28:10.244462 | 2026-01-11 00:28:10.244614 | PLAY RECAP 2026-01-11 00:28:10.244703 | controller | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2026-01-11 00:28:10.244739 | 2026-01-11 00:28:10.370091 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2026-01-11 00:28:10.372009 | POST-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/post.yaml@master] 2026-01-11 00:28:10.942996 | 2026-01-11 00:28:10.943135 | PLAY [all:!appliance*] 2026-01-11 00:28:10.966856 | 2026-01-11 00:28:10.967041 | TASK [unregister the node] 2026-01-11 00:28:11.501758 | controller | skipping: Conditional result was False 2026-01-11 00:28:11.508249 | 2026-01-11 00:28:11.508348 | TASK [include_role : fetch-output] 2026-01-11 00:28:11.549106 | controller | ok 2026-01-11 00:28:11.570623 | 2026-01-11 00:28:11.570773 | TASK [fetch-output : Set log path for multiple nodes] 2026-01-11 00:28:11.626266 | controller | skipping: Conditional result was False 2026-01-11 00:28:11.632991 | 2026-01-11 00:28:11.633100 | TASK [fetch-output : Set log path for single node] 2026-01-11 00:28:11.677998 | controller | ok 2026-01-11 00:28:11.687735 | 2026-01-11 00:28:11.687993 | LOOP [fetch-output : Ensure local output dirs] 2026-01-11 00:28:12.130191 | controller -> localhost | ok: "/var/lib/zuul/builds/ef3bed98fb324eb69d88fedbfc089b90/work/logs" 2026-01-11 00:28:12.483255 | controller -> localhost | changed: "/var/lib/zuul/builds/ef3bed98fb324eb69d88fedbfc089b90/work/artifacts" 2026-01-11 00:28:12.746750 | controller -> localhost | changed: "/var/lib/zuul/builds/ef3bed98fb324eb69d88fedbfc089b90/work/docs" 2026-01-11 00:28:12.774331 | 2026-01-11 00:28:12.774520 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-01-11 00:28:13.458335 | controller | changed: .d..t...... ./ 2026-01-11 00:28:13.458638 | controller | changed: All items complete 2026-01-11 00:28:13.458697 | 2026-01-11 00:28:13.970724 | controller | changed: .d..t...... ./ 2026-01-11 00:28:14.475382 | controller | changed: .d..t...... ./ 2026-01-11 00:28:14.501480 | 2026-01-11 00:28:14.501756 | TASK [include_role : fetch-output-openshift] 2026-01-11 00:28:14.517492 | controller | skipping: Conditional result was False 2026-01-11 00:28:14.527375 | 2026-01-11 00:28:14.527498 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-01-11 00:28:14.552937 | controller | skipping: Conditional result was False 2026-01-11 00:28:14.564252 | controller | skipping: Conditional result was False 2026-01-11 00:28:14.618047 | 2026-01-11 00:28:14.618179 | PLAY [localhost] 2026-01-11 00:28:14.643032 | 2026-01-11 00:28:14.643195 | TASK [Run Zuul manifest role] 2026-01-11 00:28:14.666392 | localhost | ok 2026-01-11 00:28:14.681800 | 2026-01-11 00:28:14.681915 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-01-11 00:28:15.065934 | localhost | changed 2026-01-11 00:28:15.076762 | 2026-01-11 00:28:15.076912 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-01-11 00:28:15.111493 | localhost | ok 2026-01-11 00:28:15.124753 | 2026-01-11 00:28:15.124861 | TASK [Set zuul-log-path fact] 2026-01-11 00:28:15.146302 | localhost | ok 2026-01-11 00:28:15.169518 | 2026-01-11 00:28:15.169639 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-01-11 00:28:15.202553 | localhost | ok 2026-01-11 00:28:15.214661 | 2026-01-11 00:28:15.214791 | LOOP [Run upload-logs-swift role] 2026-01-11 00:28:15.250623 | localhost | Output suppressed because no_log was given 2026-01-11 00:28:15.286727 | 2026-01-11 00:28:15.286881 | TASK [Set zuul-log-path fact] 2026-01-11 00:28:15.314414 | localhost | skipping: Conditional result was False 2026-01-11 00:28:15.321623 | 2026-01-11 00:28:15.321758 | TASK [upload-logs-swift : Ensure logs are readable before uploading] 2026-01-11 00:28:15.798501 | localhost -> localhost | ok: Runtime: 0:00:00.008652 2026-01-11 00:28:15.835530 | 2026-01-11 00:28:15.835660 | TASK [upload-logs-swift : Upload logs to swift]