2024-03-29 02:59:09.458483 | Job console starting... 2024-03-29 02:59:09.459577 | Updating repositories 2024-03-29 02:59:10.534992 | Preparing job workspace 2024-03-29 02:59:38.792722 | Running Ansible setup... 2024-03-29 03:00:27.863933 | PRE-RUN START: [trusted : services.openstack.netapp.com/netapp-config/playbooks/base/pre.yaml@master] 2024-03-29 03:00:30.821780 | 2024-03-29 03:00:30.822008 | PLAY [localhost] 2024-03-29 03:00:31.754263 | 2024-03-29 03:00:31.754581 | TASK [Gathering Facts] 2024-03-29 03:00:33.014955 | localhost | ok 2024-03-29 03:00:33.199358 | 2024-03-29 03:00:33.199555 | TASK [Setup log path fact] 2024-03-29 03:00:33.493613 | localhost | ok 2024-03-29 03:00:33.613268 | 2024-03-29 03:00:33.613485 | TASK [set-zuul-log-path-fact : Set log path for a change] 2024-03-29 03:00:34.410932 | localhost | ok 2024-03-29 03:00:34.604443 | 2024-03-29 03:00:34.604743 | TASK [set-zuul-log-path-fact : Set log path for a ref update] 2024-03-29 03:00:35.470556 | localhost | skipping: Conditional result was False 2024-03-29 03:00:35.603633 | 2024-03-29 03:00:35.603912 | TASK [set-zuul-log-path-fact : Set log path for a periodic job] 2024-03-29 03:00:36.313869 | localhost | skipping: Conditional result was False 2024-03-29 03:00:47.981888 | 2024-03-29 03:00:47.982156 | TASK [set-zuul-log-path-fact : Set log path for a change] 2024-03-29 03:00:54.971923 | localhost | skipping: Conditional result was False 2024-03-29 03:00:55.077559 | 2024-03-29 03:00:55.077787 | TASK [set-zuul-log-path-fact : Set log path for a ref update] 2024-03-29 03:00:55.324933 | localhost | skipping: Conditional result was False 2024-03-29 03:00:55.437508 | 2024-03-29 03:00:55.437798 | TASK [set-zuul-log-path-fact : Set log path for a periodic job] 2024-03-29 03:00:55.734915 | localhost | skipping: Conditional result was False 2024-03-29 03:00:55.863521 | 2024-03-29 03:00:55.863749 | TASK [emit-job-header : Print job information] 2024-03-29 03:00:56.439283 | # Job Information 2024-03-29 03:00:56.439601 | Ansible Version: 2.9.27 2024-03-29 03:00:56.439679 | Job: manila-tempest-plugin-ontap-no-dhss-rest 2024-03-29 03:00:56.439749 | Pipeline: upstream-check 2024-03-29 03:00:56.439816 | Executor: managesf.services.openstack.netapp.com 2024-03-29 03:00:56.439882 | Triggered by: https://review.opendev.org/c/openstack/manila/+/912059 2024-03-29 03:00:56.439948 | Event ID: 388c99753da2422aa7ee19304c5bc4d6 2024-03-29 03:00:56.520092 | 2024-03-29 03:00:56.520317 | LOOP [emit-job-header : Print node information] 2024-03-29 03:00:57.212979 | localhost | ok: 2024-03-29 03:00:57.213313 | localhost | # Node Information 2024-03-29 03:00:57.213387 | localhost | Inventory Hostname: controller 2024-03-29 03:00:57.213454 | localhost | Hostname: ubuntu 2024-03-29 03:00:57.213517 | localhost | Username: zuul 2024-03-29 03:00:57.213579 | localhost | Distro: Ubuntu 22.04 2024-03-29 03:00:57.213642 | localhost | Provider: devstack-22 2024-03-29 03:00:57.213704 | localhost | Label: ubuntu-jammy-functional 2024-03-29 03:00:57.213765 | localhost | Interface IP: ***.***.***.*** 2024-03-29 03:00:57.310886 | 2024-03-29 03:00:57.311083 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2024-03-29 03:00:58.446001 | localhost -> localhost | changed 2024-03-29 03:00:59.249336 | 2024-03-29 03:00:59.249543 | TASK [log-inventory : Copy ansible inventory to logs dir] 2024-03-29 03:01:01.102779 | localhost -> localhost | changed 2024-03-29 03:01:01.192079 | 2024-03-29 03:01:01.192271 | PLAY [all] 2024-03-29 03:01:01.428137 | 2024-03-29 03:01:01.428332 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2024-03-29 03:01:02.214929 | controller -> localhost | ok 2024-03-29 03:01:02.356872 | 2024-03-29 03:01:02.357077 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2024-03-29 03:01:02.962363 | controller | ok 2024-03-29 03:01:03.106428 | controller | included: /var/lib/zuul/builds/07dadfe9c765420dbaee789dfb65a71e/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2024-03-29 03:01:03.140442 | 2024-03-29 03:01:03.140629 | TASK [add-build-sshkey : Create Temp SSH key] 2024-03-29 03:01:04.810246 | controller -> localhost | Generating public/private rsa key pair. 2024-03-29 03:01:04.810661 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/07dadfe9c765420dbaee789dfb65a71e/work/07dadfe9c765420dbaee789dfb65a71e_id_rsa. 2024-03-29 03:01:04.810766 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/07dadfe9c765420dbaee789dfb65a71e/work/07dadfe9c765420dbaee789dfb65a71e_id_rsa.pub. 2024-03-29 03:01:04.810846 | controller -> localhost | The key fingerprint is: 2024-03-29 03:01:04.810921 | controller -> localhost | SHA256:VP5FWSNdbBuCYFYq4X57aWXpQ10+SJWNKQLuyPn2azQ zuul-build-sshkey 2024-03-29 03:01:04.810994 | controller -> localhost | The key's randomart image is: 2024-03-29 03:01:04.811067 | controller -> localhost | +---[RSA 3072]----+ 2024-03-29 03:01:04.811154 | controller -> localhost | | ..=+...oO*| 2024-03-29 03:01:04.811229 | controller -> localhost | | ..+oo..o*+=| 2024-03-29 03:01:04.811302 | controller -> localhost | | oo... oo.+| 2024-03-29 03:01:04.811373 | controller -> localhost | | ..=. ...+o.| 2024-03-29 03:01:04.811444 | controller -> localhost | | +.S. .* o.| 2024-03-29 03:01:04.811515 | controller -> localhost | | .. E * .| 2024-03-29 03:01:04.811586 | controller -> localhost | | oo = o | 2024-03-29 03:01:04.811656 | controller -> localhost | | . .+ . | 2024-03-29 03:01:04.811727 | controller -> localhost | | .o. | 2024-03-29 03:01:04.811799 | controller -> localhost | +----[SHA256]-----+ 2024-03-29 03:01:04.811906 | controller -> localhost | ok: Runtime: 0:00:00.593948 2024-03-29 03:01:05.719011 | 2024-03-29 03:01:05.719275 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2024-03-29 03:01:06.400423 | controller | ok 2024-03-29 03:01:06.483269 | controller | included: /var/lib/zuul/builds/07dadfe9c765420dbaee789dfb65a71e/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2024-03-29 03:01:06.504808 | 2024-03-29 03:01:06.504973 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2024-03-29 03:01:06.708313 | controller | skipping: Conditional result was False 2024-03-29 03:01:06.801237 | 2024-03-29 03:01:06.801557 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2024-03-29 03:01:08.059351 | controller | changed 2024-03-29 03:01:08.164094 | 2024-03-29 03:01:08.164337 | TASK [add-build-sshkey : Make sure user has a .ssh] 2024-03-29 03:01:08.550463 | controller | ok 2024-03-29 03:01:08.633003 | 2024-03-29 03:01:08.633227 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2024-03-29 03:01:10.012524 | controller | changed 2024-03-29 03:01:13.663737 | 2024-03-29 03:01:13.664022 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2024-03-29 03:01:14.970497 | controller | changed 2024-03-29 03:01:15.114976 | 2024-03-29 03:01:15.115204 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2024-03-29 03:01:15.991944 | controller | skipping: Conditional result was False 2024-03-29 03:01:16.233343 | 2024-03-29 03:01:16.233607 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2024-03-29 03:01:17.087249 | controller -> localhost | changed 2024-03-29 03:01:17.203288 | 2024-03-29 03:01:17.203561 | TASK [add-build-sshkey : Add back temp key] 2024-03-29 03:01:18.238192 | controller -> localhost | Identity added: /var/lib/zuul/builds/07dadfe9c765420dbaee789dfb65a71e/work/07dadfe9c765420dbaee789dfb65a71e_id_rsa (/var/lib/zuul/builds/07dadfe9c765420dbaee789dfb65a71e/work/07dadfe9c765420dbaee789dfb65a71e_id_rsa) 2024-03-29 03:01:18.238668 | controller -> localhost | ok: Runtime: 0:00:00.009673 2024-03-29 03:01:27.513951 | 2024-03-29 03:01:27.514216 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2024-03-29 03:01:28.612533 | controller | ok 2024-03-29 03:01:28.708145 | 2024-03-29 03:01:28.708408 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2024-03-29 03:01:29.401097 | controller | skipping: Conditional result was False 2024-03-29 03:01:29.492858 | 2024-03-29 03:01:29.493069 | TASK [start-zuul-console : Start zuul_console daemon.] 2024-03-29 03:01:30.184360 | controller | ok 2024-03-29 03:01:30.312527 | 2024-03-29 03:01:30.312829 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2024-03-29 03:01:31.821452 | 2024-03-29 03:01:31.821799 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2024-03-29 03:01:33.258243 | 2024-03-29 03:01:33.258524 | TASK [validate-host : Define zuul_info_dir fact] 2024-03-29 03:01:33.999471 | controller | ok 2024-03-29 03:01:34.112399 | 2024-03-29 03:01:34.112664 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2024-03-29 03:01:35.027343 | controller -> localhost | ok 2024-03-29 03:01:35.134087 | 2024-03-29 03:01:35.134379 | TASK [validate-host : Collect information about the host] 2024-03-29 03:01:36.092059 | controller | ok 2024-03-29 03:01:36.335338 | 2024-03-29 03:01:36.335549 | TASK [validate-host : Sanitize hostname] 2024-03-29 03:01:37.023836 | controller | ok 2024-03-29 03:01:37.154789 | 2024-03-29 03:01:37.154992 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2024-03-29 03:01:38.527166 | controller -> localhost | changed 2024-03-29 03:01:38.618637 | 2024-03-29 03:01:38.618841 | TASK [validate-host : Collect information about zuul worker] 2024-03-29 03:01:39.680300 | controller | ok 2024-03-29 03:01:39.777048 | 2024-03-29 03:01:39.777268 | TASK [validate-host : Write out all zuul information for each host] 2024-03-29 03:01:41.066967 | controller -> localhost | changed 2024-03-29 03:01:41.152358 | 2024-03-29 03:01:41.152568 | LOOP [prepare-workspace-git : Filter zuul projects if sync-only-required-projects flag is set] 2024-03-29 03:01:41.913395 | controller | skipping: Conditional result was False 2024-03-29 03:01:41.913853 | controller | changed: All items complete 2024-03-29 03:01:41.914243 | 2024-03-29 03:01:41.915973 | controller | skipping: Conditional result was False 2024-03-29 03:01:41.927805 | controller | skipping: Conditional result was False 2024-03-29 03:01:41.929175 | controller | skipping: Conditional result was False 2024-03-29 03:01:41.930487 | controller | skipping: Conditional result was False 2024-03-29 03:01:41.942018 | controller | skipping: Conditional result was False 2024-03-29 03:01:41.945942 | controller | skipping: Conditional result was False 2024-03-29 03:01:41.948971 | controller | skipping: Conditional result was False 2024-03-29 03:01:41.950392 | controller | skipping: Conditional result was False 2024-03-29 03:01:41.962397 | controller | skipping: Conditional result was False 2024-03-29 03:01:41.963820 | controller | skipping: Conditional result was False 2024-03-29 03:01:41.965143 | controller | skipping: Conditional result was False 2024-03-29 03:01:41.966452 | controller | skipping: Conditional result was False 2024-03-29 03:01:42.090480 | 2024-03-29 03:01:42.090691 | TASK [prepare-workspace-git : Don't filter zuul projects if flag is false] 2024-03-29 03:01:42.849070 | controller | ok 2024-03-29 03:01:42.963578 | 2024-03-29 03:01:42.963772 | LOOP [prepare-workspace-git : Set initial repo states in workspace] 2024-03-29 03:01:43.732771 | controller | ERROR 2024-03-29 03:01:43.733197 | controller | { 2024-03-29 03:01:43.733289 | controller | "msg": "The task includes an option with an undefined variable. The error was: 'ansible.utils.unsafe_proxy.AnsibleUnsafeText object' has no attribute 'src_dir'\n\nThe error appears to be in '/var/lib/zuul/builds/07dadfe9c765420dbaee789dfb65a71e/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/prepare-workspace-git/tasks/main.yaml': line 21, column 3, but may\nbe elsewhere in the file depending on the exact syntax problem.\n\nThe offending line appears to be:\n\n# task startup time we incur.\n- name: Set initial repo states in workspace\n ^ here\n" 2024-03-29 03:01:43.733385 | controller | } 2024-03-29 03:01:43.793227 | 2024-03-29 03:01:43.793400 | PLAY RECAP 2024-03-29 03:01:43.793538 | controller | ok: 22 changed: 9 unreachable: 0 failed: 1 skipped: 4 rescued: 0 ignored: 0 2024-03-29 03:01:43.793642 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0 2024-03-29 03:01:43.793716 | 2024-03-29 03:01:44.367839 | PRE-RUN END RESULT_NORMAL: [trusted : services.openstack.netapp.com/netapp-config/playbooks/base/pre.yaml@master] 2024-03-29 03:01:44.483356 | POST-RUN START: [untrusted : services.openstack.netapp.com/netapp-jobs/playbooks/netapp/manila-tempest-plugin-ontap-no-dhss/post.yaml@master] 2024-03-29 03:01:47.084744 | 2024-03-29 03:01:47.085031 | PLAY [all] 2024-03-29 03:01:47.195377 | 2024-03-29 03:01:47.195556 | TASK [Execute manila teardown to delete the device.] 2024-03-29 03:01:58.288612 | controller | MODULE FAILURE: 2024-03-29 03:01:58.289092 | controller | Traceback (most recent call last): 2024-03-29 03:01:58.289265 | controller | File "", line 102, in 2024-03-29 03:01:58.289397 | controller | File "", line 94, in _ansiballz_main 2024-03-29 03:01:58.289522 | controller | File "", line 40, in invoke_module 2024-03-29 03:01:58.289644 | controller | File "/usr/lib/python3.10/runpy.py", line 224, in run_module 2024-03-29 03:01:58.289776 | controller | return _run_module_code(code, init_globals, run_name, mod_spec) 2024-03-29 03:01:58.289901 | controller | File "/usr/lib/python3.10/runpy.py", line 96, in _run_module_code 2024-03-29 03:01:58.290022 | controller | _run_code(code, mod_globals, init_globals, 2024-03-29 03:01:58.290169 | controller | File "/usr/lib/python3.10/runpy.py", line 86, in _run_code 2024-03-29 03:01:58.290294 | controller | exec(code, run_globals) 2024-03-29 03:01:58.290410 | controller | File "/tmp/ansible_command_payload_clgesytb/ansible_command_payload.zip/ansible/modules/command.py", line 675, in 2024-03-29 03:01:58.290524 | controller | File "/tmp/ansible_command_payload_clgesytb/ansible_command_payload.zip/ansible/modules/command.py", line 620, in main 2024-03-29 03:01:58.290639 | controller | FileNotFoundError: [Errno 2] No such file or directory: '/home/zuul/netapp-jobs/playbooks/netapp/manila-tempest-plugin-ontap-no-dhss/nested' 2024-03-29 03:01:58.468111 | 2024-03-29 03:01:58.468354 | PLAY RECAP 2024-03-29 03:01:58.468546 | controller | ok: 0 changed: 0 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0 2024-03-29 03:01:58.468675 | 2024-03-29 03:01:59.059694 | POST-RUN END RESULT_NORMAL: [untrusted : services.openstack.netapp.com/netapp-jobs/playbooks/netapp/manila-tempest-plugin-ontap-no-dhss/post.yaml@master] 2024-03-29 03:01:59.175400 | POST-RUN START: [untrusted : services.openstack.netapp.com/netapp-jobs/playbooks/opendev/openstack/tempest/post-tempest.yaml@master] 2024-03-29 03:02:02.141493 | 2024-03-29 03:02:02.141706 | PLAY [tempest] 2024-03-29 03:02:02.260580 | 2024-03-29 03:02:02.260782 | TASK [fetch-subunit-output : Find stestr or testr executable] 2024-03-29 03:02:03.465972 | controller | changed: non-zero return code 2024-03-29 03:02:03.671046 | 2024-03-29 03:02:03.671258 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2024-03-29 03:02:04.321682 | controller | skipping: Conditional result was False 2024-03-29 03:02:04.425310 | 2024-03-29 03:02:04.425518 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2024-03-29 03:02:05.187460 | 2024-03-29 03:02:05.187828 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2024-03-29 03:02:05.979486 | 2024-03-29 03:02:05.979865 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2024-03-29 03:02:06.608075 | controller | skipping: Conditional result was False 2024-03-29 03:02:06.751347 | 2024-03-29 03:02:06.751567 | LOOP [fetch-subunit-output : Generate subunit file] 2024-03-29 03:02:07.469909 | 2024-03-29 03:02:07.470218 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2024-03-29 03:02:08.077181 | controller | skipping: Conditional result was False 2024-03-29 03:02:08.189903 | 2024-03-29 03:02:08.190100 | TASK [fetch-subunit-output : Remove the temporary file] 2024-03-29 03:02:08.782390 | controller | skipping: Conditional result was False 2024-03-29 03:02:08.880248 | 2024-03-29 03:02:08.880451 | TASK [fetch-subunit-output : Process and fetch subunit results] 2024-03-29 03:02:09.442687 | controller | skipping: Conditional result was False 2024-03-29 03:02:09.533597 | 2024-03-29 03:02:09.533795 | TASK [process-stackviz : Devstack checks if stackviz archive exists] 2024-03-29 03:02:10.227355 | controller | ok 2024-03-29 03:02:10.323337 | 2024-03-29 03:02:10.323585 | TASK [process-stackviz : debug] 2024-03-29 03:02:10.970257 | controller | skipping: Conditional result was False 2024-03-29 03:02:11.058179 | 2024-03-29 03:02:11.058435 | TASK [process-stackviz : Check if subunit data exists] 2024-03-29 03:02:11.933326 | controller | ok 2024-03-29 03:02:12.029947 | 2024-03-29 03:02:12.030156 | TASK [process-stackviz : debug] 2024-03-29 03:02:12.804182 | Subunit file could not be found at /opt/stack/tempest/testrepository.subunit 2024-03-29 03:02:12.895927 | 2024-03-29 03:02:12.896166 | TASK [include_role : ensure-pip] 2024-03-29 03:02:13.646211 | controller | skipping: Conditional result was False 2024-03-29 03:02:13.735491 | 2024-03-29 03:02:13.735679 | TASK [process-stackviz : pip] 2024-03-29 03:02:14.403018 | controller | skipping: Conditional result was False 2024-03-29 03:02:14.493856 | 2024-03-29 03:02:14.494067 | TASK [process-stackviz : Deploy stackviz static html+js] 2024-03-29 03:02:15.346223 | controller | skipping: Conditional result was False 2024-03-29 03:02:15.457620 | 2024-03-29 03:02:15.457879 | TASK [process-stackviz : Check if dstat data exists] 2024-03-29 03:02:16.178239 | controller | skipping: Conditional result was False 2024-03-29 03:02:16.274276 | 2024-03-29 03:02:16.274818 | TASK [process-stackviz : Run stackviz with dstat] 2024-03-29 03:02:17.025952 | controller | skipping: Conditional result was False 2024-03-29 03:02:17.123550 | 2024-03-29 03:02:17.123765 | TASK [process-stackviz : Run stackviz without dstat] 2024-03-29 03:02:17.907129 | controller | skipping: Conditional result was False 2024-03-29 03:02:17.983926 | 2024-03-29 03:02:17.984199 | PLAY RECAP 2024-03-29 03:02:17.984404 | controller | ok: 4 changed: 1 unreachable: 0 failed: 0 skipped: 15 rescued: 0 ignored: 0 2024-03-29 03:02:17.984526 | 2024-03-29 03:02:18.989802 | POST-RUN END RESULT_NORMAL: [untrusted : services.openstack.netapp.com/netapp-jobs/playbooks/opendev/openstack/tempest/post-tempest.yaml@master] 2024-03-29 03:02:19.111262 | POST-RUN START: [untrusted : services.openstack.netapp.com/netapp-jobs/playbooks/opendev/openstack/devstack/post.yaml@master] 2024-03-29 03:02:22.187349 | 2024-03-29 03:02:22.187539 | PLAY [all] 2024-03-29 03:02:22.793221 | 2024-03-29 03:02:22.793463 | TASK [export-devstack-journal : Ensure /home/zuul/logs exists] 2024-03-29 03:02:23.903832 | controller | changed 2024-03-29 03:02:24.148584 | 2024-03-29 03:02:24.148810 | TASK [export-devstack-journal : Export legacy stack screen log files] 2024-03-29 03:02:25.727235 | controller | ok: Runtime: 0:00:00.701351 2024-03-29 03:02:25.850855 | 2024-03-29 03:02:25.851038 | TASK [export-devstack-journal : Export legacy syslog.txt] 2024-03-29 06:02:26.343983 | controller | cat: /opt/stack/log-start-timestamp.txt: No such file or directory 2024-03-29 06:02:26.353321 | controller | Failed to parse timestamp: 2024-03-29 03:02:26.424681 | controller | ERROR 2024-03-29 03:02:26.425200 | controller | { 2024-03-29 03:02:26.425353 | controller | "delta": "0:00:00.015379", 2024-03-29 03:02:26.425483 | controller | "end": "2024-03-29 06:02:26.354518", 2024-03-29 03:02:26.425609 | controller | "msg": "non-zero return code", 2024-03-29 03:02:26.425736 | controller | "rc": 1, 2024-03-29 03:02:26.425862 | controller | "start": "2024-03-29 06:02:26.339139" 2024-03-29 03:02:26.425973 | controller | } 2024-03-29 03:02:26.502964 | 2024-03-29 03:02:26.503118 | PLAY RECAP 2024-03-29 03:02:26.503246 | controller | ok: 2 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0 2024-03-29 03:02:26.503320 | 2024-03-29 03:02:27.238261 | POST-RUN END RESULT_NORMAL: [untrusted : services.openstack.netapp.com/netapp-jobs/playbooks/opendev/openstack/devstack/post.yaml@master] 2024-03-29 03:02:27.336586 | POST-RUN START: [trusted : services.openstack.netapp.com/netapp-config/playbooks/base/post.yaml@master] 2024-03-29 03:02:30.547333 | 2024-03-29 03:02:30.547600 | PLAY [all] 2024-03-29 03:02:30.750004 | 2024-03-29 03:02:30.750250 | TASK [fetch-output : Set log path for multiple nodes] 2024-03-29 03:02:31.574884 | controller | skipping: Conditional result was False 2024-03-29 03:02:31.804574 | 2024-03-29 03:02:31.804831 | TASK [fetch-output : Set log path for single node] 2024-03-29 03:02:32.604990 | controller | ok 2024-03-29 03:02:33.227799 | 2024-03-29 03:02:33.228040 | LOOP [fetch-output : Ensure local output dirs] 2024-03-29 03:02:34.838848 | 2024-03-29 03:02:34.839098 | LOOP [fetch-output : Collect logs, artifacts and docs] 2024-03-29 03:02:36.071390 | controller | changed: .d..t...... ./ 2024-03-29 03:02:36.071767 | controller | changed: All items complete 2024-03-29 03:02:36.071846 | 2024-03-29 03:02:36.924422 | controller | changed: .d..t...... ./ 2024-03-29 03:02:37.596548 | controller | changed: .d..t...... ./ 2024-03-29 03:02:37.765780 | 2024-03-29 03:02:37.765996 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2024-03-29 03:02:38.876472 | 2024-03-29 03:02:38.876792 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2024-03-29 03:02:39.940744 | controller | changed 2024-03-29 03:02:40.038451 | 2024-03-29 03:02:40.038612 | PLAY [localhost] 2024-03-29 03:02:40.327090 | 2024-03-29 03:02:40.327337 | TASK [add-fileserver : Create SSH private key tempfile] 2024-03-29 03:02:40.922502 | localhost | changed 2024-03-29 03:02:41.193916 | 2024-03-29 03:02:41.194269 | TASK [add-fileserver : Create SSH private key from secret] 2024-03-29 03:02:42.441838 | localhost | changed 2024-03-29 03:02:42.575281 | 2024-03-29 03:02:42.575532 | TASK [add-fileserver : Add fileserver ssh key] 2024-03-29 03:02:43.142963 | localhost | Identity added: /var/lib/zuul/builds/07dadfe9c765420dbaee789dfb65a71e/work/tmp/ansible.0d16q02f (/var/lib/zuul/builds/07dadfe9c765420dbaee789dfb65a71e/work/tmp/ansible.0d16q02f) 2024-03-29 03:02:43.143358 | localhost | ok: Runtime: 0:00:00.015004 2024-03-29 03:02:43.267202 | 2024-03-29 03:02:43.267430 | TASK [add-fileserver : Remove SSH private key from disk] 2024-03-29 03:02:43.837625 | localhost | ok: Runtime: 0:00:00.003379 2024-03-29 03:02:43.987568 | 2024-03-29 03:02:43.988411 | TASK [add-fileserver : Add fileserver to inventory] 2024-03-29 03:02:44.297127 | localhost | changed 2024-03-29 03:02:44.438204 | 2024-03-29 03:02:44.438423 | TASK [add-fileserver : Add fileserver server to known hosts] 2024-03-29 03:02:45.323736 | localhost | changed 2024-03-29 03:02:45.464137 | 2024-03-29 03:02:45.464433 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2024-03-29 03:02:46.307457 | localhost | changed 2024-03-29 03:02:46.426462 | 2024-03-29 03:02:46.426715 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2024-03-29 03:02:46.698375 | localhost | ok 2024-03-29 03:02:46.775448 | 2024-03-29 03:02:46.775635 | PLAY [services.openstack.netapp.com] 2024-03-29 03:02:47.033036 | 2024-03-29 03:02:47.033254 | TASK [Set zuul-log-path fact] 2024-03-29 03:02:47.285494 | services.openstack.netapp.com | ok 2024-03-29 03:02:47.516405 | 2024-03-29 03:02:47.516649 | TASK [set-zuul-log-path-fact : Set log path for a change] 2024-03-29 03:02:47.839775 | services.openstack.netapp.com | ok 2024-03-29 03:02:48.051476 | 2024-03-29 03:02:48.051756 | TASK [set-zuul-log-path-fact : Set log path for a ref update] 2024-03-29 03:02:49.042290 | services.openstack.netapp.com | skipping: Conditional result was False 2024-03-29 03:02:49.292970 | 2024-03-29 03:02:49.293217 | TASK [set-zuul-log-path-fact : Set log path for a periodic job] 2024-03-29 03:02:49.556539 | services.openstack.netapp.com | skipping: Conditional result was False 2024-03-29 03:02:49.653404 | 2024-03-29 03:02:49.653606 | TASK [set-zuul-log-path-fact : Set log path for a change] 2024-03-29 03:02:49.889131 | services.openstack.netapp.com | skipping: Conditional result was False 2024-03-29 03:02:49.990706 | 2024-03-29 03:02:49.990942 | TASK [set-zuul-log-path-fact : Set log path for a ref update] 2024-03-29 03:02:50.185550 | services.openstack.netapp.com | skipping: Conditional result was False 2024-03-29 03:02:50.364531 | 2024-03-29 03:02:50.364729 | TASK [set-zuul-log-path-fact : Set log path for a periodic job] 2024-03-29 03:02:50.685227 | services.openstack.netapp.com | skipping: Conditional result was False 2024-03-29 03:02:50.790898 | 2024-03-29 03:02:50.791148 | TASK [upload-logs : Create log directories] 2024-03-29 03:02:51.702414 | services.openstack.netapp.com | changed 2024-03-29 03:02:51.962133 | 2024-03-29 03:02:51.962349 | TASK [upload-logs : Ensure logs are readable before uploading] 2024-03-29 03:02:52.445922 | services.openstack.netapp.com -> localhost | ok: Runtime: 0:00:00.003125 2024-03-29 03:02:52.943975 | 2024-03-29 03:02:52.944190 | TASK [upload-logs : Upload logs to log server] 2024-03-29 03:02:54.140754 | services.openstack.netapp.com | Output suppressed because no_log was given 2024-03-29 03:02:54.704476 | 2024-03-29 03:02:54.704677 | LOOP [upload-logs : Compress console log and json output] 2024-03-29 03:02:54.999251 | services.openstack.netapp.com | skipping: Conditional result was False 2024-03-29 03:02:54.999824 | services.openstack.netapp.com | changed: All items complete 2024-03-29 03:02:54.999917 | 2024-03-29 03:02:55.011685 | services.openstack.netapp.com | skipping: Conditional result was False 2024-03-29 03:02:55.159846 | 2024-03-29 03:02:55.160065 | LOOP [upload-logs : Upload compressed console log and json output] 2024-03-29 03:02:55.362850 | services.openstack.netapp.com | skipping: Conditional result was False 2024-03-29 03:02:55.363412 | 2024-03-29 03:02:55.375030 | services.openstack.netapp.com | skipping: Conditional result was False 2024-03-29 03:02:55.797393 | 2024-03-29 03:02:55.797612 | LOOP [upload-logs : Upload console log and json output] 2024-03-29 03:02:56.961775 | services.openstack.netapp.com | changed: