2025-11-18 00:06:47.215817 | Job console starting... 2025-11-18 00:06:47.229467 | Updating repositories 2025-11-18 00:06:47.360775 | Preparing job workspace 2025-11-18 00:06:51.882742 | Running Ansible setup... 2025-11-18 00:06:56.455203 | PRE-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master] 2025-11-18 00:06:57.057299 | 2025-11-18 00:06:57.057425 | PLAY [localhost] 2025-11-18 00:06:57.065875 | 2025-11-18 00:06:57.065952 | TASK [Gathering Facts] 2025-11-18 00:06:58.108082 | localhost | ok 2025-11-18 00:06:58.133170 | 2025-11-18 00:06:58.133354 | TASK [Setup log path fact] 2025-11-18 00:06:58.156303 | localhost | ok 2025-11-18 00:06:58.185916 | 2025-11-18 00:06:58.186251 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-11-18 00:06:58.218541 | localhost | ok 2025-11-18 00:06:58.226950 | 2025-11-18 00:06:58.227052 | TASK [emit-job-header : Print job information] 2025-11-18 00:06:58.270109 | # Job Information 2025-11-18 00:06:58.270301 | Ansible Version: 2.15.12 2025-11-18 00:06:58.270337 | Job: ansible-test-sanity-docker-milestone 2025-11-18 00:06:58.270359 | Pipeline: periodic 2025-11-18 00:06:58.270377 | Executor: ze01.softwarefactory-project.io 2025-11-18 00:06:58.270395 | Triggered by: https://github.com/ansible-collections/ansible.network 2025-11-18 00:06:58.270416 | Log URL (when completed): https://ansible.softwarefactory-project.io/logs/99b/ansible/99bec17591bf4bb89e1ea926a8c3f011/ 2025-11-18 00:06:58.270436 | Event ID: ce1d8018739b49068127d11881773b47 2025-11-18 00:06:58.274843 | 2025-11-18 00:06:58.274913 | LOOP [emit-job-header : Print node information] 2025-11-18 00:06:58.394853 | localhost | ok: 2025-11-18 00:06:58.395159 | localhost | # Node Information 2025-11-18 00:06:58.395205 | localhost | Inventory Hostname: controller 2025-11-18 00:06:58.395240 | localhost | Hostname: np0005525952 2025-11-18 00:06:58.395272 | localhost | Username: zuul 2025-11-18 00:06:58.395312 | localhost | Distro: Fedora 37 2025-11-18 00:06:58.395346 | localhost | Provider: ansible-vexxhost-ca-ymq-1 2025-11-18 00:06:58.395376 | localhost | Region: ca-ymq-1 2025-11-18 00:06:58.395405 | localhost | Label: ansible-fedora-37-1vcpu 2025-11-18 00:06:58.395434 | localhost | Product Name: OpenStack Nova 2025-11-18 00:06:58.395463 | localhost | Interface IP: 162.253.55.145 2025-11-18 00:06:58.413304 | 2025-11-18 00:06:58.413413 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-11-18 00:06:58.898775 | localhost -> localhost | changed 2025-11-18 00:06:58.905777 | 2025-11-18 00:06:58.905912 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-11-18 00:06:59.832898 | localhost -> localhost | changed 2025-11-18 00:06:59.867461 | 2025-11-18 00:06:59.867569 | PLAY [all:!appliance*] 2025-11-18 00:06:59.888189 | 2025-11-18 00:06:59.888441 | TASK [include_role : start-zuul-console] 2025-11-18 00:06:59.910185 | controller | ok 2025-11-18 00:06:59.923882 | 2025-11-18 00:06:59.923982 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-11-18 00:07:00.324259 | controller | ok 2025-11-18 00:07:00.340390 | 2025-11-18 00:07:00.340515 | TASK [use-our-mirror : Retrieve the IP address] 2025-11-18 00:07:01.124984 | controller | ok: Status code was 404 and not [200]: HTTP Error 404: Not Found 2025-11-18 00:07:01.131657 | 2025-11-18 00:07:01.131724 | TASK [use-our-mirror : Ensure the registry service is running on the mirror] 2025-11-18 00:07:01.665329 | controller | skipping: Conditional result was False 2025-11-18 00:07:01.679159 | 2025-11-18 00:07:01.679335 | TASK [use-our-mirror : Write the IP in /etc/hosts] 2025-11-18 00:07:01.706537 | controller | skipping: Conditional result was False 2025-11-18 00:07:01.720847 | 2025-11-18 00:07:01.721006 | TASK [use-our-mirror : Create the podman configuration directory] 2025-11-18 00:07:01.748355 | controller | skipping: Conditional result was False 2025-11-18 00:07:01.761785 | 2025-11-18 00:07:01.761930 | TASK [use-our-mirror : Copy the podman configuration] 2025-11-18 00:07:01.789877 | controller | skipping: Conditional result was False 2025-11-18 00:07:01.800910 | 2025-11-18 00:07:01.801071 | TASK [use-our-mirror : Copy the Yum configuration (main)] 2025-11-18 00:07:01.826388 | controller | skipping: Conditional result was False 2025-11-18 00:07:01.835253 | 2025-11-18 00:07:01.835353 | TASK [use-our-mirror : Copy the Yum configuration (update)] 2025-11-18 00:07:01.860890 | controller | skipping: Conditional result was False 2025-11-18 00:07:01.878175 | 2025-11-18 00:07:01.878278 | TASK [Disable Fedora Modular] 2025-11-18 00:07:02.127727 | controller | changed 2025-11-18 00:07:02.138104 | 2025-11-18 00:07:02.138236 | TASK [Enable EPEL] 2025-11-18 00:07:02.163624 | controller | skipping: Conditional result was False 2025-11-18 00:07:02.171739 | 2025-11-18 00:07:02.171829 | TASK [Register the RHEL node] 2025-11-18 00:07:02.732822 | 2025-11-18 00:07:02.733155 | TASK [Show the subscription-manager status] 2025-11-18 00:07:03.322843 | controller | skipping: Conditional result was False 2025-11-18 00:07:03.337473 | 2025-11-18 00:07:03.337624 | TASK [Enable EPEL on RHEL] 2025-11-18 00:07:03.893809 | controller | skipping: Conditional result was False 2025-11-18 00:07:03.900120 | 2025-11-18 00:07:03.900186 | TASK [Install git and tox] 2025-11-18 00:08:23.649765 | controller | changed 2025-11-18 00:08:23.667095 | 2025-11-18 00:08:23.667286 | TASK [include_role : prepare-workspace] 2025-11-18 00:08:23.708960 | controller | ok 2025-11-18 00:08:23.746560 | 2025-11-18 00:08:23.746714 | TASK [prepare-workspace : Start zuul_console daemon.] 2025-11-18 00:08:23.987216 | controller | ok 2025-11-18 00:08:24.001002 | 2025-11-18 00:08:24.001213 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2025-11-18 00:08:36.114224 | controller | Output suppressed because no_log was given 2025-11-18 00:08:36.127900 | 2025-11-18 00:08:36.128058 | TASK [include_role : prepare-workspace-openshift] 2025-11-18 00:08:36.148410 | controller | skipping: Conditional result was False 2025-11-18 00:08:36.187324 | 2025-11-18 00:08:36.187449 | PLAY [all:!appliance] 2025-11-18 00:08:36.258314 | 2025-11-18 00:08:36.258502 | TASK [Run add-build-sshkey role (RSA)] 2025-11-18 00:08:36.293134 | controller | ok 2025-11-18 00:08:36.314861 | 2025-11-18 00:08:36.315002 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-11-18 00:08:36.618747 | controller -> localhost | ok 2025-11-18 00:08:36.625810 | 2025-11-18 00:08:36.625877 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-11-18 00:08:36.657678 | controller | ok 2025-11-18 00:08:36.675677 | controller | included: /var/lib/zuul/builds/99bec17591bf4bb89e1ea926a8c3f011/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-11-18 00:08:36.682495 | 2025-11-18 00:08:36.682566 | TASK [add-build-sshkey : Create Temp SSH key] 2025-11-18 00:08:37.228853 | controller -> localhost | Generating public/private rsa key pair. 2025-11-18 00:08:37.229120 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/99bec17591bf4bb89e1ea926a8c3f011/work/99bec17591bf4bb89e1ea926a8c3f011_id_rsa. 2025-11-18 00:08:37.229159 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/99bec17591bf4bb89e1ea926a8c3f011/work/99bec17591bf4bb89e1ea926a8c3f011_id_rsa.pub. 2025-11-18 00:08:37.229186 | controller -> localhost | The key fingerprint is: 2025-11-18 00:08:37.229211 | controller -> localhost | SHA256:ISmSu9FoeFPTjML0CLwDHgqYz8fWZxGlal9t2Gk0YOY zuul-build-sshkey 2025-11-18 00:08:37.229236 | controller -> localhost | The key's randomart image is: 2025-11-18 00:08:37.229261 | controller -> localhost | +---[RSA 2048]----+ 2025-11-18 00:08:37.229285 | controller -> localhost | |+.. .o= | 2025-11-18 00:08:37.229310 | controller -> localhost | |=* + + ..= . | 2025-11-18 00:08:37.229332 | controller -> localhost | |=oO.*.= o.E o | 2025-11-18 00:08:37.229355 | controller -> localhost | |.=oB+o.oo. = o | 2025-11-18 00:08:37.229377 | controller -> localhost | |. Oo. ooS o * | 2025-11-18 00:08:37.229415 | controller -> localhost | | o + . . . o | 2025-11-18 00:08:37.229443 | controller -> localhost | | . . | 2025-11-18 00:08:37.229467 | controller -> localhost | | | 2025-11-18 00:08:37.229490 | controller -> localhost | | | 2025-11-18 00:08:37.229516 | controller -> localhost | +----[SHA256]-----+ 2025-11-18 00:08:37.229574 | controller -> localhost | ok: Runtime: 0:00:00.091744 2025-11-18 00:08:37.240597 | 2025-11-18 00:08:37.240767 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-11-18 00:08:37.275905 | controller | ok 2025-11-18 00:08:37.292972 | controller | included: /var/lib/zuul/builds/99bec17591bf4bb89e1ea926a8c3f011/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-11-18 00:08:37.309218 | 2025-11-18 00:08:37.309379 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-11-18 00:08:37.336378 | controller | skipping: Conditional result was False 2025-11-18 00:08:37.346625 | 2025-11-18 00:08:37.346790 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-11-18 00:08:37.817915 | controller | changed 2025-11-18 00:08:37.823704 | 2025-11-18 00:08:37.823794 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-11-18 00:08:38.047124 | controller | ok 2025-11-18 00:08:38.058437 | 2025-11-18 00:08:38.058594 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-11-18 00:08:38.700852 | controller | changed 2025-11-18 00:08:38.713208 | 2025-11-18 00:08:38.713374 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-11-18 00:08:39.378720 | controller | changed 2025-11-18 00:08:39.390933 | 2025-11-18 00:08:39.391097 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-11-18 00:08:39.427211 | controller | skipping: Conditional result was False 2025-11-18 00:08:39.438592 | 2025-11-18 00:08:39.438750 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-11-18 00:08:39.926375 | controller -> localhost | changed 2025-11-18 00:08:39.946309 | 2025-11-18 00:08:39.946472 | TASK [add-build-sshkey : Add back temp key] 2025-11-18 00:08:40.249566 | controller -> localhost | Identity added: /var/lib/zuul/builds/99bec17591bf4bb89e1ea926a8c3f011/work/99bec17591bf4bb89e1ea926a8c3f011_id_rsa (zuul-build-sshkey) 2025-11-18 00:08:40.249793 | controller -> localhost | ok: Runtime: 0:00:00.009502 2025-11-18 00:08:40.260157 | 2025-11-18 00:08:40.260272 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-11-18 00:08:40.647135 | controller | ok 2025-11-18 00:08:40.655183 | 2025-11-18 00:08:40.655301 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-11-18 00:08:40.681959 | controller | skipping: Conditional result was False 2025-11-18 00:08:40.699504 | 2025-11-18 00:08:40.699631 | TASK [Run add-build-sshkey role (ECDSA)] 2025-11-18 00:08:40.733327 | controller | ok 2025-11-18 00:08:40.758628 | 2025-11-18 00:08:40.758929 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-11-18 00:08:41.051136 | controller -> localhost | ok 2025-11-18 00:08:41.059208 | 2025-11-18 00:08:41.059336 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-11-18 00:08:41.090659 | controller | ok 2025-11-18 00:08:41.103078 | controller | included: /var/lib/zuul/builds/99bec17591bf4bb89e1ea926a8c3f011/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-11-18 00:08:41.110763 | 2025-11-18 00:08:41.110877 | TASK [add-build-sshkey : Create Temp SSH key] 2025-11-18 00:08:41.428341 | controller -> localhost | Generating public/private ecdsa key pair. 2025-11-18 00:08:41.428559 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/99bec17591bf4bb89e1ea926a8c3f011/work/99bec17591bf4bb89e1ea926a8c3f011_id_ecdsa. 2025-11-18 00:08:41.428587 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/99bec17591bf4bb89e1ea926a8c3f011/work/99bec17591bf4bb89e1ea926a8c3f011_id_ecdsa.pub. 2025-11-18 00:08:41.428618 | controller -> localhost | The key fingerprint is: 2025-11-18 00:08:41.428639 | controller -> localhost | SHA256:AxZDj569ymxeUKtwpSJWF2+v/6Cjul9/Baied9C/gzM zuul-build-sshkey 2025-11-18 00:08:41.428660 | controller -> localhost | The key's randomart image is: 2025-11-18 00:08:41.428679 | controller -> localhost | +---[ECDSA 521]---+ 2025-11-18 00:08:41.428697 | controller -> localhost | | .= | 2025-11-18 00:08:41.428716 | controller -> localhost | | B | 2025-11-18 00:08:41.428734 | controller -> localhost | | . = * . | 2025-11-18 00:08:41.428752 | controller -> localhost | | . + O o. . | 2025-11-18 00:08:41.428769 | controller -> localhost | | o o * S... . | 2025-11-18 00:08:41.428787 | controller -> localhost | | . . + o.+. . . | 2025-11-18 00:08:41.428804 | controller -> localhost | | ..=... + | 2025-11-18 00:08:41.428822 | controller -> localhost | | o.+++..E o | 2025-11-18 00:08:41.428840 | controller -> localhost | | o*B..oo+.o.o | 2025-11-18 00:08:41.428858 | controller -> localhost | +----[SHA256]-----+ 2025-11-18 00:08:41.428902 | controller -> localhost | ok: Runtime: 0:00:00.013439 2025-11-18 00:08:41.435821 | 2025-11-18 00:08:41.435931 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-11-18 00:08:41.467189 | controller | ok 2025-11-18 00:08:41.475226 | controller | included: /var/lib/zuul/builds/99bec17591bf4bb89e1ea926a8c3f011/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-11-18 00:08:41.485111 | 2025-11-18 00:08:41.485228 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-11-18 00:08:41.510412 | controller | skipping: Conditional result was False 2025-11-18 00:08:41.518394 | 2025-11-18 00:08:41.518533 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-11-18 00:08:41.817139 | controller | changed 2025-11-18 00:08:41.824515 | 2025-11-18 00:08:41.824614 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-11-18 00:08:42.041326 | controller | ok 2025-11-18 00:08:42.048581 | 2025-11-18 00:08:42.048712 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-11-18 00:08:42.705900 | controller | changed 2025-11-18 00:08:42.715998 | 2025-11-18 00:08:42.716166 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-11-18 00:08:43.366707 | controller | changed 2025-11-18 00:08:43.375957 | 2025-11-18 00:08:43.376123 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-11-18 00:08:43.402515 | controller | skipping: Conditional result was False 2025-11-18 00:08:43.414296 | 2025-11-18 00:08:43.414448 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-11-18 00:08:43.717882 | controller -> localhost | changed 2025-11-18 00:08:43.734768 | 2025-11-18 00:08:43.734904 | TASK [add-build-sshkey : Add back temp key] 2025-11-18 00:08:44.109221 | controller -> localhost | Identity added: /var/lib/zuul/builds/99bec17591bf4bb89e1ea926a8c3f011/work/99bec17591bf4bb89e1ea926a8c3f011_id_ecdsa (zuul-build-sshkey) 2025-11-18 00:08:44.109487 | controller -> localhost | ok: Runtime: 0:00:00.011799 2025-11-18 00:08:44.119179 | 2025-11-18 00:08:44.119322 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-11-18 00:08:44.328439 | controller | ok 2025-11-18 00:08:44.337006 | 2025-11-18 00:08:44.337358 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-11-18 00:08:44.363832 | controller | skipping: Conditional result was False 2025-11-18 00:08:44.430400 | 2025-11-18 00:08:44.430563 | TASK [include_role : remove-zuul-sshkey] 2025-11-18 00:08:44.457199 | controller | skipping: Conditional result was False 2025-11-18 00:08:44.466819 | 2025-11-18 00:08:44.466987 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2025-11-18 00:08:44.723597 | controller | ok: "logs" 2025-11-18 00:08:44.723938 | controller | ok: All items complete 2025-11-18 00:08:44.723985 | 2025-11-18 00:08:44.942001 | controller | ok: "artifacts" 2025-11-18 00:08:45.134763 | controller | ok: "docs" 2025-11-18 00:08:45.156933 | 2025-11-18 00:08:45.157103 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2025-11-18 00:08:45.418628 | controller | changed: "logs" 2025-11-18 00:08:45.610269 | controller | changed: "artifacts" 2025-11-18 00:08:45.794934 | controller | changed: "docs" 2025-11-18 00:08:45.848264 | 2025-11-18 00:08:45.848412 | PLAY RECAP 2025-11-18 00:08:45.848473 | controller | ok: 30 changed: 16 unreachable: 0 failed: 0 skipped: 18 rescued: 0 ignored: 0 2025-11-18 00:08:45.848506 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-11-18 00:08:45.848528 | 2025-11-18 00:08:45.981854 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master] 2025-11-18 00:08:45.983931 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2025-11-18 00:08:46.605964 | 2025-11-18 00:08:46.606126 | PLAY [all] 2025-11-18 00:08:46.630816 | 2025-11-18 00:08:46.630957 | TASK [Install binary dependencies] 2025-11-18 00:08:46.718185 | controller | ok 2025-11-18 00:08:46.752836 | 2025-11-18 00:08:46.753011 | TASK [bindep : Include find tasks] 2025-11-18 00:08:46.819150 | controller | ok 2025-11-18 00:08:46.827789 | controller | included: /var/lib/zuul/builds/99bec17591bf4bb89e1ea926a8c3f011/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find.yaml 2025-11-18 00:08:46.835241 | 2025-11-18 00:08:46.835369 | TASK [bindep : Look for bindep.txt] 2025-11-18 00:08:47.221865 | controller | ok 2025-11-18 00:08:47.235284 | 2025-11-18 00:08:47.235497 | TASK [bindep : Define bindep_file fact] 2025-11-18 00:08:47.264914 | controller | skipping: Conditional result was False 2025-11-18 00:08:47.279516 | 2025-11-18 00:08:47.279713 | TASK [bindep : Look for other-requirements.txt] 2025-11-18 00:08:47.500186 | controller | ok 2025-11-18 00:08:47.508727 | 2025-11-18 00:08:47.508880 | TASK [bindep : Define bindep_file fact] 2025-11-18 00:08:47.534864 | controller | skipping: Conditional result was False 2025-11-18 00:08:47.544351 | 2025-11-18 00:08:47.544493 | TASK [bindep : Look for bindep fallback file] 2025-11-18 00:08:47.570848 | controller | skipping: Conditional result was False 2025-11-18 00:08:47.583193 | 2025-11-18 00:08:47.583357 | TASK [bindep : Define bindep_file fact] 2025-11-18 00:08:47.609479 | controller | skipping: Conditional result was False 2025-11-18 00:08:47.619277 | 2025-11-18 00:08:47.619427 | TASK [bindep : Include bindep tasks] 2025-11-18 00:08:47.645050 | controller | skipping: Conditional result was False 2025-11-18 00:08:47.654492 | 2025-11-18 00:08:47.654636 | TASK [bindep : Include install tasks] 2025-11-18 00:08:47.680696 | controller | skipping: Conditional result was False 2025-11-18 00:08:47.688142 | 2025-11-18 00:08:47.688266 | LOOP [bindep : Include package tasks] 2025-11-18 00:08:47.752553 | 2025-11-18 00:08:47.752784 | TASK [Run test-setup role] 2025-11-18 00:08:47.780419 | controller | ok 2025-11-18 00:08:47.806606 | 2025-11-18 00:08:47.806738 | TASK [test-setup : Check if project's tools/test-setup.sh exists] 2025-11-18 00:08:48.057167 | controller | ok 2025-11-18 00:08:48.068799 | 2025-11-18 00:08:48.069094 | TASK [test-setup : Run tools/test-setup.sh] 2025-11-18 00:08:48.615566 | controller | skipping: Conditional result was False 2025-11-18 00:08:48.656801 | 2025-11-18 00:08:48.656963 | PLAY RECAP 2025-11-18 00:08:48.657105 | controller | ok: 4 changed: 0 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2025-11-18 00:08:48.657143 | 2025-11-18 00:08:48.779822 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2025-11-18 00:08:48.780756 | PRE-RUN START: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master] 2025-11-18 00:08:49.366464 | 2025-11-18 00:08:49.366608 | PLAY [controller] 2025-11-18 00:08:49.388052 | 2025-11-18 00:08:49.388197 | TASK [Create the /root directory] 2025-11-18 00:11:01.753633 | controller | ERROR 2025-11-18 00:11:01.753979 | controller | { 2025-11-18 00:11:01.755300 | controller | "msg": "Timeout (32s) waiting for privilege escalation prompt: " 2025-11-18 00:11:01.755459 | controller | } failure 2025-11-18 00:11:01.758383 | 2025-11-18 00:11:01.758520 | PLAY RECAP 2025-11-18 00:11:01.758615 | controller | ok: 0 changed: 0 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0 2025-11-18 00:11:01.758669 | 2025-11-18 00:11:01.875481 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master] 2025-11-18 00:11:01.876431 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2025-11-18 00:11:02.496563 | 2025-11-18 00:11:02.496679 | PLAY [all] 2025-11-18 00:11:02.520525 | 2025-11-18 00:11:02.520655 | TASK [fetch-subunit-output : Find stestr or testr executable] 2025-11-18 00:14:22.215921 | controller | changed: non-zero return code 2025-11-18 00:14:22.231394 | 2025-11-18 00:14:22.231606 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2025-11-18 00:14:22.247497 | controller | skipping: Conditional result was False 2025-11-18 00:14:22.256882 | 2025-11-18 00:14:22.257003 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2025-11-18 00:14:22.281480 | 2025-11-18 00:14:22.281664 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2025-11-18 00:14:22.305856 | 2025-11-18 00:14:22.306060 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2025-11-18 00:14:22.320925 | controller | skipping: Conditional result was False 2025-11-18 00:14:22.327368 | 2025-11-18 00:14:22.327454 | LOOP [fetch-subunit-output : Generate subunit file] 2025-11-18 00:14:22.347337 | 2025-11-18 00:14:22.347493 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2025-11-18 00:14:22.361654 | controller | skipping: Conditional result was False 2025-11-18 00:14:22.368677 | 2025-11-18 00:14:22.368763 | TASK [fetch-subunit-output : Remove the temporary file] 2025-11-18 00:14:22.383361 | controller | skipping: Conditional result was False 2025-11-18 00:14:22.391429 | 2025-11-18 00:14:22.391529 | TASK [fetch-subunit-output : Process and fetch subunit results] 2025-11-18 00:14:22.405275 | controller | skipping: Conditional result was False 2025-11-18 00:14:22.435127 | 2025-11-18 00:14:22.435226 | PLAY RECAP 2025-11-18 00:14:22.435267 | controller | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2025-11-18 00:14:22.435287 | 2025-11-18 00:14:22.537919 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2025-11-18 00:14:22.539065 | POST-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/post.yaml@master] 2025-11-18 00:14:23.106636 | 2025-11-18 00:14:23.106755 | PLAY [all:!appliance*] 2025-11-18 00:14:23.130251 | 2025-11-18 00:14:23.130377 | TASK [unregister the node] 2025-11-18 00:14:23.664339 | controller | skipping: Conditional result was False 2025-11-18 00:14:23.673283 | 2025-11-18 00:14:23.673422 | TASK [include_role : fetch-output] 2025-11-18 00:14:23.716266 | controller | ok 2025-11-18 00:14:23.742957 | 2025-11-18 00:14:23.743114 | TASK [fetch-output : Set log path for multiple nodes] 2025-11-18 00:14:23.809576 | controller | skipping: Conditional result was False 2025-11-18 00:14:23.818849 | 2025-11-18 00:14:23.818986 | TASK [fetch-output : Set log path for single node] 2025-11-18 00:14:23.867728 | controller | ok 2025-11-18 00:14:23.879595 | 2025-11-18 00:14:23.879752 | LOOP [fetch-output : Ensure local output dirs] 2025-11-18 00:14:24.347251 | controller -> localhost | ok: "/var/lib/zuul/builds/99bec17591bf4bb89e1ea926a8c3f011/work/logs" 2025-11-18 00:14:24.576766 | controller -> localhost | changed: "/var/lib/zuul/builds/99bec17591bf4bb89e1ea926a8c3f011/work/artifacts" 2025-11-18 00:14:24.796019 | controller -> localhost | changed: "/var/lib/zuul/builds/99bec17591bf4bb89e1ea926a8c3f011/work/docs" 2025-11-18 00:14:24.815277 | 2025-11-18 00:14:24.815413 | LOOP [fetch-output : Collect logs, artifacts and docs] 2025-11-18 00:14:25.523673 | controller | ok 2025-11-18 00:14:25.524188 | controller | changed: All items complete 2025-11-18 00:14:25.524265 | 2025-11-18 00:14:26.034238 | controller | changed: .d..t...... ./ 2025-11-18 00:14:26.539652 | controller | changed: .d..t...... ./ 2025-11-18 00:14:26.580993 | 2025-11-18 00:14:26.581287 | TASK [include_role : fetch-output-openshift] 2025-11-18 00:14:26.608784 | controller | skipping: Conditional result was False 2025-11-18 00:14:26.619870 | 2025-11-18 00:14:26.620010 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2025-11-18 00:14:26.648623 | controller | skipping: Conditional result was False 2025-11-18 00:14:26.660250 | controller | skipping: Conditional result was False 2025-11-18 00:14:26.698289 | 2025-11-18 00:14:26.698424 | PLAY [localhost] 2025-11-18 00:14:26.712686 | 2025-11-18 00:14:26.712932 | TASK [Run Zuul manifest role] 2025-11-18 00:14:26.733715 | localhost | ok 2025-11-18 00:14:26.749588 | 2025-11-18 00:14:26.749676 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-11-18 00:14:27.125708 | localhost | changed 2025-11-18 00:14:27.130789 | 2025-11-18 00:14:27.130879 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-11-18 00:14:27.159924 | localhost | ok 2025-11-18 00:14:27.168441 | 2025-11-18 00:14:27.168541 | TASK [Set zuul-log-path fact] 2025-11-18 00:14:27.186497 | localhost | ok 2025-11-18 00:14:27.204885 | 2025-11-18 00:14:27.205056 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-11-18 00:14:27.235514 | localhost | ok 2025-11-18 00:14:27.244515 | 2025-11-18 00:14:27.244611 | LOOP [Run upload-logs-swift role] 2025-11-18 00:14:27.279844 | localhost | Output suppressed because no_log was given 2025-11-18 00:14:27.321797 | 2025-11-18 00:14:27.321962 | TASK [Set zuul-log-path fact] 2025-11-18 00:14:27.347195 | localhost | skipping: Conditional result was False 2025-11-18 00:14:27.355339 | 2025-11-18 00:14:27.355439 | TASK [upload-logs-swift : Ensure logs are readable before uploading] 2025-11-18 00:14:27.836191 | localhost -> localhost | ok: Runtime: 0:00:00.008044 2025-11-18 00:14:27.874148 | 2025-11-18 00:14:27.874289 | TASK [upload-logs-swift : Upload logs to swift]