2025-11-27 00:20:39.410407 | Job console starting... 2025-11-27 00:20:39.419546 | Updating repositories 2025-11-27 00:20:39.545381 | Preparing job workspace 2025-11-27 00:20:43.676155 | Running Ansible setup... 2025-11-27 00:20:48.293539 | PRE-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master] 2025-11-27 00:20:48.929492 | 2025-11-27 00:20:48.929712 | PLAY [localhost] 2025-11-27 00:20:48.938406 | 2025-11-27 00:20:48.938476 | TASK [Gathering Facts] 2025-11-27 00:20:50.018293 | localhost | ok 2025-11-27 00:20:50.044979 | 2025-11-27 00:20:50.045149 | TASK [Setup log path fact] 2025-11-27 00:20:50.068744 | localhost | ok 2025-11-27 00:20:50.087902 | 2025-11-27 00:20:50.088058 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-11-27 00:20:50.120543 | localhost | ok 2025-11-27 00:20:50.131951 | 2025-11-27 00:20:50.132055 | TASK [emit-job-header : Print job information] 2025-11-27 00:20:50.197172 | # Job Information 2025-11-27 00:20:50.197442 | Ansible Version: 2.15.12 2025-11-27 00:20:50.197539 | Job: ansible-test-sanity-docker-milestone 2025-11-27 00:20:50.197591 | Pipeline: periodic 2025-11-27 00:20:50.197635 | Executor: ze02.softwarefactory-project.io 2025-11-27 00:20:50.197724 | Triggered by: https://github.com/ansible-collections/ansible.network 2025-11-27 00:20:50.197781 | Log URL (when completed): https://ansible.softwarefactory-project.io/logs/5c3/ansible/5c341c866134415d83fc3f4bffb97b84/ 2025-11-27 00:20:50.197827 | Event ID: 952fca0482a448208996a7ac17a0c8d7 2025-11-27 00:20:50.206829 | 2025-11-27 00:20:50.206975 | LOOP [emit-job-header : Print node information] 2025-11-27 00:20:50.338242 | localhost | ok: 2025-11-27 00:20:50.338647 | localhost | # Node Information 2025-11-27 00:20:50.338752 | localhost | Inventory Hostname: controller 2025-11-27 00:20:50.338812 | localhost | Hostname: np0005537334 2025-11-27 00:20:50.338862 | localhost | Username: zuul 2025-11-27 00:20:50.338939 | localhost | Distro: Fedora 37 2025-11-27 00:20:50.338991 | localhost | Provider: ansible-vexxhost-ca-ymq-1 2025-11-27 00:20:50.339037 | localhost | Region: ca-ymq-1 2025-11-27 00:20:50.339081 | localhost | Label: ansible-fedora-37-1vcpu 2025-11-27 00:20:50.339125 | localhost | Product Name: OpenStack Nova 2025-11-27 00:20:50.339170 | localhost | Interface IP: 162.253.55.221 2025-11-27 00:20:50.367182 | 2025-11-27 00:20:50.367331 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-11-27 00:20:50.867453 | localhost -> localhost | changed 2025-11-27 00:20:50.880275 | 2025-11-27 00:20:50.880492 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-11-27 00:20:51.930501 | localhost -> localhost | changed 2025-11-27 00:20:51.976758 | 2025-11-27 00:20:51.976920 | PLAY [all:!appliance*] 2025-11-27 00:20:52.009693 | 2025-11-27 00:20:52.009792 | TASK [include_role : start-zuul-console] 2025-11-27 00:20:52.033848 | controller | ok 2025-11-27 00:20:52.052332 | 2025-11-27 00:20:52.052422 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-11-27 00:20:52.487559 | controller | ok 2025-11-27 00:20:52.511808 | 2025-11-27 00:20:52.511991 | TASK [use-our-mirror : Retrieve the IP address] 2025-11-27 00:20:53.475099 | controller | ok: Status code was 404 and not [200]: HTTP Error 404: Not Found 2025-11-27 00:20:53.481706 | 2025-11-27 00:20:53.481779 | TASK [use-our-mirror : Ensure the registry service is running on the mirror] 2025-11-27 00:20:54.014634 | controller | skipping: Conditional result was False 2025-11-27 00:20:54.029588 | 2025-11-27 00:20:54.029817 | TASK [use-our-mirror : Write the IP in /etc/hosts] 2025-11-27 00:20:54.057260 | controller | skipping: Conditional result was False 2025-11-27 00:20:54.071497 | 2025-11-27 00:20:54.071760 | TASK [use-our-mirror : Create the podman configuration directory] 2025-11-27 00:20:54.099529 | controller | skipping: Conditional result was False 2025-11-27 00:20:54.114557 | 2025-11-27 00:20:54.114799 | TASK [use-our-mirror : Copy the podman configuration] 2025-11-27 00:20:54.143473 | controller | skipping: Conditional result was False 2025-11-27 00:20:54.165155 | 2025-11-27 00:20:54.165422 | TASK [use-our-mirror : Copy the Yum configuration (main)] 2025-11-27 00:20:54.185715 | controller | skipping: Conditional result was False 2025-11-27 00:20:54.202943 | 2025-11-27 00:20:54.203129 | TASK [use-our-mirror : Copy the Yum configuration (update)] 2025-11-27 00:20:54.219896 | controller | skipping: Conditional result was False 2025-11-27 00:20:54.236068 | 2025-11-27 00:20:54.236175 | TASK [Disable Fedora Modular] 2025-11-27 00:20:54.490406 | controller | changed 2025-11-27 00:20:54.502807 | 2025-11-27 00:20:54.502946 | TASK [Enable EPEL] 2025-11-27 00:20:54.533939 | controller | skipping: Conditional result was False 2025-11-27 00:20:54.550028 | 2025-11-27 00:20:54.550185 | TASK [Register the RHEL node] 2025-11-27 00:20:55.112755 | 2025-11-27 00:20:55.113039 | TASK [Show the subscription-manager status] 2025-11-27 00:20:55.682451 | controller | skipping: Conditional result was False 2025-11-27 00:20:55.696359 | 2025-11-27 00:20:55.696572 | TASK [Enable EPEL on RHEL] 2025-11-27 00:20:56.272346 | controller | skipping: Conditional result was False 2025-11-27 00:20:56.280618 | 2025-11-27 00:20:56.280790 | TASK [Install git and tox] 2025-11-27 00:22:14.879054 | controller | changed 2025-11-27 00:22:14.895390 | 2025-11-27 00:22:14.895637 | TASK [include_role : prepare-workspace] 2025-11-27 00:22:14.934645 | controller | ok 2025-11-27 00:22:14.968893 | 2025-11-27 00:22:14.968986 | TASK [prepare-workspace : Start zuul_console daemon.] 2025-11-27 00:22:15.220495 | controller | ok 2025-11-27 00:22:15.233932 | 2025-11-27 00:22:15.234060 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2025-11-27 00:22:27.183702 | controller | Output suppressed because no_log was given 2025-11-27 00:22:27.199429 | 2025-11-27 00:22:27.199538 | TASK [include_role : prepare-workspace-openshift] 2025-11-27 00:22:27.226150 | controller | skipping: Conditional result was False 2025-11-27 00:22:27.290007 | 2025-11-27 00:22:27.290107 | PLAY [all:!appliance] 2025-11-27 00:22:27.306163 | 2025-11-27 00:22:27.306263 | TASK [Run add-build-sshkey role (RSA)] 2025-11-27 00:22:27.336625 | controller | ok 2025-11-27 00:22:27.352412 | 2025-11-27 00:22:27.352486 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-11-27 00:22:27.651578 | controller -> localhost | ok 2025-11-27 00:22:27.665571 | 2025-11-27 00:22:27.665759 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-11-27 00:22:27.706015 | controller | ok 2025-11-27 00:22:27.734569 | controller | included: /var/lib/zuul/builds/5c341c866134415d83fc3f4bffb97b84/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-11-27 00:22:27.743740 | 2025-11-27 00:22:27.743828 | TASK [add-build-sshkey : Create Temp SSH key] 2025-11-27 00:22:28.281530 | controller -> localhost | Generating public/private rsa key pair. 2025-11-27 00:22:28.281783 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/5c341c866134415d83fc3f4bffb97b84/work/5c341c866134415d83fc3f4bffb97b84_id_rsa. 2025-11-27 00:22:28.281816 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/5c341c866134415d83fc3f4bffb97b84/work/5c341c866134415d83fc3f4bffb97b84_id_rsa.pub. 2025-11-27 00:22:28.281837 | controller -> localhost | The key fingerprint is: 2025-11-27 00:22:28.281856 | controller -> localhost | SHA256:60yCsRpIc4Ip9mvjLpSu+fD2qwicGeIm7Yjw4HYcIXo zuul-build-sshkey 2025-11-27 00:22:28.281875 | controller -> localhost | The key's randomart image is: 2025-11-27 00:22:28.281893 | controller -> localhost | +---[RSA 2048]----+ 2025-11-27 00:22:28.281911 | controller -> localhost | | | 2025-11-27 00:22:28.281929 | controller -> localhost | | | 2025-11-27 00:22:28.281946 | controller -> localhost | | | 2025-11-27 00:22:28.281963 | controller -> localhost | |.o . | 2025-11-27 00:22:28.281981 | controller -> localhost | |**o.o S | 2025-11-27 00:22:28.282008 | controller -> localhost | |O=E. + . | 2025-11-27 00:22:28.282032 | controller -> localhost | |XB+.+ . o | 2025-11-27 00:22:28.282052 | controller -> localhost | |B&.B. = | 2025-11-27 00:22:28.282070 | controller -> localhost | |O+&*+. o | 2025-11-27 00:22:28.282091 | controller -> localhost | +----[SHA256]-----+ 2025-11-27 00:22:28.282141 | controller -> localhost | ok: Runtime: 0:00:00.100060 2025-11-27 00:22:28.289118 | 2025-11-27 00:22:28.289181 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-11-27 00:22:28.318872 | controller | ok 2025-11-27 00:22:28.329112 | controller | included: /var/lib/zuul/builds/5c341c866134415d83fc3f4bffb97b84/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-11-27 00:22:28.337962 | 2025-11-27 00:22:28.338024 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-11-27 00:22:28.362905 | controller | skipping: Conditional result was False 2025-11-27 00:22:28.371122 | 2025-11-27 00:22:28.371194 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-11-27 00:22:28.837197 | controller | changed 2025-11-27 00:22:28.850660 | 2025-11-27 00:22:28.850838 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-11-27 00:22:29.094467 | controller | ok 2025-11-27 00:22:29.108602 | 2025-11-27 00:22:29.108800 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-11-27 00:22:29.830649 | controller | changed 2025-11-27 00:22:29.844955 | 2025-11-27 00:22:29.845137 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-11-27 00:22:30.528937 | controller | changed 2025-11-27 00:22:30.541537 | 2025-11-27 00:22:30.541758 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-11-27 00:22:30.570047 | controller | skipping: Conditional result was False 2025-11-27 00:22:30.585781 | 2025-11-27 00:22:30.585926 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-11-27 00:22:31.073014 | controller -> localhost | changed 2025-11-27 00:22:31.102043 | 2025-11-27 00:22:31.102206 | TASK [add-build-sshkey : Add back temp key] 2025-11-27 00:22:31.424407 | controller -> localhost | Identity added: /var/lib/zuul/builds/5c341c866134415d83fc3f4bffb97b84/work/5c341c866134415d83fc3f4bffb97b84_id_rsa (zuul-build-sshkey) 2025-11-27 00:22:31.424715 | controller -> localhost | ok: Runtime: 0:00:00.014149 2025-11-27 00:22:31.431497 | 2025-11-27 00:22:31.431564 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-11-27 00:22:31.836780 | controller | ok 2025-11-27 00:22:31.848360 | 2025-11-27 00:22:31.848500 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-11-27 00:22:31.886781 | controller | skipping: Conditional result was False 2025-11-27 00:22:31.912753 | 2025-11-27 00:22:31.912905 | TASK [Run add-build-sshkey role (ECDSA)] 2025-11-27 00:22:31.951427 | controller | ok 2025-11-27 00:22:31.979982 | 2025-11-27 00:22:31.980071 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-11-27 00:22:32.272907 | controller -> localhost | ok 2025-11-27 00:22:32.287878 | 2025-11-27 00:22:32.288032 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-11-27 00:22:32.322024 | controller | ok 2025-11-27 00:22:32.349521 | controller | included: /var/lib/zuul/builds/5c341c866134415d83fc3f4bffb97b84/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-11-27 00:22:32.363586 | 2025-11-27 00:22:32.363777 | TASK [add-build-sshkey : Create Temp SSH key] 2025-11-27 00:22:32.788091 | controller -> localhost | Generating public/private ecdsa key pair. 2025-11-27 00:22:32.788411 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/5c341c866134415d83fc3f4bffb97b84/work/5c341c866134415d83fc3f4bffb97b84_id_ecdsa. 2025-11-27 00:22:32.788452 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/5c341c866134415d83fc3f4bffb97b84/work/5c341c866134415d83fc3f4bffb97b84_id_ecdsa.pub. 2025-11-27 00:22:32.788498 | controller -> localhost | The key fingerprint is: 2025-11-27 00:22:32.788526 | controller -> localhost | SHA256:hucbTv1TUg0pPalmrwQ+63DN1Ot1xrwPPlqAj+fHJ58 zuul-build-sshkey 2025-11-27 00:22:32.788553 | controller -> localhost | The key's randomart image is: 2025-11-27 00:22:32.788578 | controller -> localhost | +---[ECDSA 521]---+ 2025-11-27 00:22:32.788603 | controller -> localhost | | . o | 2025-11-27 00:22:32.788628 | controller -> localhost | | . * | 2025-11-27 00:22:32.788653 | controller -> localhost | | o + | 2025-11-27 00:22:32.788708 | controller -> localhost | | . ..+.. .| 2025-11-27 00:22:32.788735 | controller -> localhost | | . S..+oo. | 2025-11-27 00:22:32.788761 | controller -> localhost | | + .o*o.o+ | 2025-11-27 00:22:32.788785 | controller -> localhost | | = ++==+ *| 2025-11-27 00:22:32.788808 | controller -> localhost | | o =.+o+=+=| 2025-11-27 00:22:32.788836 | controller -> localhost | | o.. ++oE+| 2025-11-27 00:22:32.788873 | controller -> localhost | +----[SHA256]-----+ 2025-11-27 00:22:32.788983 | controller -> localhost | ok: Runtime: 0:00:00.014699 2025-11-27 00:22:32.799076 | 2025-11-27 00:22:32.799165 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-11-27 00:22:32.832196 | controller | ok 2025-11-27 00:22:32.842526 | controller | included: /var/lib/zuul/builds/5c341c866134415d83fc3f4bffb97b84/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-11-27 00:22:32.854807 | 2025-11-27 00:22:32.854893 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-11-27 00:22:32.880188 | controller | skipping: Conditional result was False 2025-11-27 00:22:32.888722 | 2025-11-27 00:22:32.888815 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-11-27 00:22:33.163106 | controller | changed 2025-11-27 00:22:33.170171 | 2025-11-27 00:22:33.170240 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-11-27 00:22:33.408020 | controller | ok 2025-11-27 00:22:33.414052 | 2025-11-27 00:22:33.414122 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-11-27 00:22:34.074059 | controller | changed 2025-11-27 00:22:34.080178 | 2025-11-27 00:22:34.080256 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-11-27 00:22:34.753835 | controller | changed 2025-11-27 00:22:34.768880 | 2025-11-27 00:22:34.769043 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-11-27 00:22:34.809748 | controller | skipping: Conditional result was False 2025-11-27 00:22:34.825008 | 2025-11-27 00:22:34.825284 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-11-27 00:22:35.129567 | controller -> localhost | changed 2025-11-27 00:22:35.141833 | 2025-11-27 00:22:35.141906 | TASK [add-build-sshkey : Add back temp key] 2025-11-27 00:22:35.526103 | controller -> localhost | Identity added: /var/lib/zuul/builds/5c341c866134415d83fc3f4bffb97b84/work/5c341c866134415d83fc3f4bffb97b84_id_ecdsa (zuul-build-sshkey) 2025-11-27 00:22:35.526288 | controller -> localhost | ok: Runtime: 0:00:00.013477 2025-11-27 00:22:35.532179 | 2025-11-27 00:22:35.532241 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-11-27 00:22:35.779627 | controller | ok 2025-11-27 00:22:35.787337 | 2025-11-27 00:22:35.787427 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-11-27 00:22:35.823482 | controller | skipping: Conditional result was False 2025-11-27 00:22:35.839756 | 2025-11-27 00:22:35.839850 | TASK [include_role : remove-zuul-sshkey] 2025-11-27 00:22:35.865739 | controller | skipping: Conditional result was False 2025-11-27 00:22:35.882024 | 2025-11-27 00:22:35.882713 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2025-11-27 00:22:36.136717 | controller | ok: "logs" 2025-11-27 00:22:36.137033 | controller | ok: All items complete 2025-11-27 00:22:36.137073 | 2025-11-27 00:22:36.337442 | controller | ok: "artifacts" 2025-11-27 00:22:36.527329 | controller | ok: "docs" 2025-11-27 00:22:36.544969 | 2025-11-27 00:22:36.545116 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2025-11-27 00:22:36.806578 | controller | changed: "logs" 2025-11-27 00:22:37.012518 | controller | changed: "artifacts" 2025-11-27 00:22:37.212742 | controller | changed: "docs" 2025-11-27 00:22:37.250783 | 2025-11-27 00:22:37.250900 | PLAY RECAP 2025-11-27 00:22:37.250945 | controller | ok: 30 changed: 16 unreachable: 0 failed: 0 skipped: 18 rescued: 0 ignored: 0 2025-11-27 00:22:37.250972 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-11-27 00:22:37.250991 | 2025-11-27 00:22:37.371654 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master] 2025-11-27 00:22:37.372644 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2025-11-27 00:22:37.982018 | 2025-11-27 00:22:37.982136 | PLAY [all] 2025-11-27 00:22:38.005043 | 2025-11-27 00:22:38.005145 | TASK [Install binary dependencies] 2025-11-27 00:22:38.078445 | controller | ok 2025-11-27 00:22:38.105770 | 2025-11-27 00:22:38.105898 | TASK [bindep : Include find tasks] 2025-11-27 00:22:38.139775 | controller | ok 2025-11-27 00:22:38.150386 | controller | included: /var/lib/zuul/builds/5c341c866134415d83fc3f4bffb97b84/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find.yaml 2025-11-27 00:22:38.159920 | 2025-11-27 00:22:38.160014 | TASK [bindep : Look for bindep.txt] 2025-11-27 00:22:38.540953 | controller | ok 2025-11-27 00:22:38.549372 | 2025-11-27 00:22:38.549469 | TASK [bindep : Define bindep_file fact] 2025-11-27 00:22:38.565962 | controller | skipping: Conditional result was False 2025-11-27 00:22:38.574849 | 2025-11-27 00:22:38.574945 | TASK [bindep : Look for other-requirements.txt] 2025-11-27 00:22:38.795050 | controller | ok 2025-11-27 00:22:38.807377 | 2025-11-27 00:22:38.807546 | TASK [bindep : Define bindep_file fact] 2025-11-27 00:22:38.845509 | controller | skipping: Conditional result was False 2025-11-27 00:22:38.859067 | 2025-11-27 00:22:38.859214 | TASK [bindep : Look for bindep fallback file] 2025-11-27 00:22:38.897258 | controller | skipping: Conditional result was False 2025-11-27 00:22:38.917442 | 2025-11-27 00:22:38.917700 | TASK [bindep : Define bindep_file fact] 2025-11-27 00:22:38.955593 | controller | skipping: Conditional result was False 2025-11-27 00:22:38.969223 | 2025-11-27 00:22:38.969368 | TASK [bindep : Include bindep tasks] 2025-11-27 00:22:39.007200 | controller | skipping: Conditional result was False 2025-11-27 00:22:39.022931 | 2025-11-27 00:22:39.023098 | TASK [bindep : Include install tasks] 2025-11-27 00:22:39.050185 | controller | skipping: Conditional result was False 2025-11-27 00:22:39.059139 | 2025-11-27 00:22:39.059232 | LOOP [bindep : Include package tasks] 2025-11-27 00:22:39.139463 | 2025-11-27 00:22:39.139802 | TASK [Run test-setup role] 2025-11-27 00:22:39.170916 | controller | ok 2025-11-27 00:22:39.200607 | 2025-11-27 00:22:39.200727 | TASK [test-setup : Check if project's tools/test-setup.sh exists] 2025-11-27 00:22:39.436038 | controller | ok 2025-11-27 00:22:39.450513 | 2025-11-27 00:22:39.450654 | TASK [test-setup : Run tools/test-setup.sh] 2025-11-27 00:22:40.000884 | controller | skipping: Conditional result was False 2025-11-27 00:22:40.057078 | 2025-11-27 00:22:40.057191 | PLAY RECAP 2025-11-27 00:22:40.057255 | controller | ok: 4 changed: 0 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2025-11-27 00:22:40.057283 | 2025-11-27 00:22:40.193341 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2025-11-27 00:22:40.194268 | PRE-RUN START: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master] 2025-11-27 00:22:40.864171 | 2025-11-27 00:22:40.864313 | PLAY [controller] 2025-11-27 00:22:40.884789 | 2025-11-27 00:22:40.884872 | TASK [Create the /root directory] 2025-11-27 00:24:53.201824 | controller | ERROR 2025-11-27 00:24:53.202308 | controller | { 2025-11-27 00:24:53.202394 | controller | "msg": "Timeout (32s) waiting for privilege escalation prompt: " 2025-11-27 00:24:53.202454 | controller | } failure 2025-11-27 00:24:53.205994 | 2025-11-27 00:24:53.206101 | PLAY RECAP 2025-11-27 00:24:53.206188 | controller | ok: 0 changed: 0 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0 2025-11-27 00:24:53.206236 | 2025-11-27 00:24:53.392453 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master] 2025-11-27 00:24:53.394774 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2025-11-27 00:24:54.070621 | 2025-11-27 00:24:54.070844 | PLAY [all] 2025-11-27 00:24:54.096053 | 2025-11-27 00:24:54.096175 | TASK [fetch-subunit-output : Find stestr or testr executable] 2025-11-27 00:27:54.401300 | controller | changed: non-zero return code 2025-11-27 00:27:54.407212 | 2025-11-27 00:27:54.407302 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2025-11-27 00:27:54.435292 | controller | skipping: Conditional result was False 2025-11-27 00:27:54.441752 | 2025-11-27 00:27:54.441836 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2025-11-27 00:27:54.473429 | 2025-11-27 00:27:54.473590 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2025-11-27 00:27:54.504084 | 2025-11-27 00:27:54.504255 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2025-11-27 00:27:54.519280 | controller | skipping: Conditional result was False 2025-11-27 00:27:54.526993 | 2025-11-27 00:27:54.527079 | LOOP [fetch-subunit-output : Generate subunit file] 2025-11-27 00:27:54.559057 | 2025-11-27 00:27:54.559215 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2025-11-27 00:27:54.572625 | controller | skipping: Conditional result was False 2025-11-27 00:27:54.579893 | 2025-11-27 00:27:54.579972 | TASK [fetch-subunit-output : Remove the temporary file] 2025-11-27 00:27:54.594889 | controller | skipping: Conditional result was False 2025-11-27 00:27:54.601427 | 2025-11-27 00:27:54.601502 | TASK [fetch-subunit-output : Process and fetch subunit results] 2025-11-27 00:27:54.615578 | controller | skipping: Conditional result was False 2025-11-27 00:27:54.643363 | 2025-11-27 00:27:54.643434 | PLAY RECAP 2025-11-27 00:27:54.643476 | controller | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2025-11-27 00:27:54.643496 | 2025-11-27 00:27:54.752601 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2025-11-27 00:27:54.753508 | POST-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/post.yaml@master] 2025-11-27 00:27:55.306640 | 2025-11-27 00:27:55.306848 | PLAY [all:!appliance*] 2025-11-27 00:27:55.339358 | 2025-11-27 00:27:55.339474 | TASK [unregister the node] 2025-11-27 00:27:55.870632 | controller | skipping: Conditional result was False 2025-11-27 00:27:55.879134 | 2025-11-27 00:27:55.879247 | TASK [include_role : fetch-output] 2025-11-27 00:27:55.912744 | controller | ok 2025-11-27 00:27:55.938708 | 2025-11-27 00:27:55.938902 | TASK [fetch-output : Set log path for multiple nodes] 2025-11-27 00:27:55.983498 | controller | skipping: Conditional result was False 2025-11-27 00:27:55.990274 | 2025-11-27 00:27:55.990366 | TASK [fetch-output : Set log path for single node] 2025-11-27 00:27:56.024948 | controller | ok 2025-11-27 00:27:56.034944 | 2025-11-27 00:27:56.035022 | LOOP [fetch-output : Ensure local output dirs] 2025-11-27 00:27:56.422008 | controller -> localhost | ok: "/var/lib/zuul/builds/5c341c866134415d83fc3f4bffb97b84/work/logs" 2025-11-27 00:27:56.644626 | controller -> localhost | changed: "/var/lib/zuul/builds/5c341c866134415d83fc3f4bffb97b84/work/artifacts" 2025-11-27 00:27:56.912245 | controller -> localhost | changed: "/var/lib/zuul/builds/5c341c866134415d83fc3f4bffb97b84/work/docs" 2025-11-27 00:27:56.928142 | 2025-11-27 00:27:56.928236 | LOOP [fetch-output : Collect logs, artifacts and docs] 2025-11-27 00:27:57.588571 | controller | changed: .d..t...... ./ 2025-11-27 00:27:57.588830 | controller | changed: All items complete 2025-11-27 00:27:57.588858 | 2025-11-27 00:27:58.101847 | controller | changed: .d..t...... ./ 2025-11-27 00:27:58.610139 | controller | changed: .d..t...... ./ 2025-11-27 00:27:58.628780 | 2025-11-27 00:27:58.628928 | TASK [include_role : fetch-output-openshift] 2025-11-27 00:27:58.644107 | controller | skipping: Conditional result was False 2025-11-27 00:27:58.656179 | 2025-11-27 00:27:58.656298 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2025-11-27 00:27:58.690994 | controller | skipping: Conditional result was False 2025-11-27 00:27:58.704083 | controller | skipping: Conditional result was False 2025-11-27 00:27:58.741559 | 2025-11-27 00:27:58.741711 | PLAY [localhost] 2025-11-27 00:27:58.755829 | 2025-11-27 00:27:58.755941 | TASK [Run Zuul manifest role] 2025-11-27 00:27:58.773794 | localhost | ok 2025-11-27 00:27:58.792081 | 2025-11-27 00:27:58.792191 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-11-27 00:27:59.156278 | localhost | changed 2025-11-27 00:27:59.163125 | 2025-11-27 00:27:59.163219 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-11-27 00:27:59.194633 | localhost | ok 2025-11-27 00:27:59.203374 | 2025-11-27 00:27:59.203441 | TASK [Set zuul-log-path fact] 2025-11-27 00:27:59.222358 | localhost | ok 2025-11-27 00:27:59.237085 | 2025-11-27 00:27:59.237189 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-11-27 00:27:59.265388 | localhost | ok 2025-11-27 00:27:59.273850 | 2025-11-27 00:27:59.273925 | LOOP [Run upload-logs-swift role] 2025-11-27 00:27:59.308534 | localhost | Output suppressed because no_log was given 2025-11-27 00:27:59.333121 | 2025-11-27 00:27:59.333224 | TASK [Set zuul-log-path fact] 2025-11-27 00:27:59.357584 | localhost | skipping: Conditional result was False 2025-11-27 00:27:59.362842 | 2025-11-27 00:27:59.362911 | TASK [upload-logs-swift : Ensure logs are readable before uploading] 2025-11-27 00:27:59.774638 | localhost -> localhost | ok: Runtime: 0:00:00.009346 2025-11-27 00:27:59.808360 | 2025-11-27 00:27:59.808503 | TASK [upload-logs-swift : Upload logs to swift]