Creating a NS according to the Tacker document [1] fails because the NS creation logic depends on the existence of Mistral action named ``tacker.create_vnf`` . The Mistral actions need to be developed and registerd by users and
no example for ``tacker.create_vnf`` is provided in Tacker or Mistral repository.
When executing ``openstack ns create --nsd-template sample-tosca-nsd.yaml NS1`` with the sample NSD provided in
Tacker repository [2], I got the following error log:
[1] https://docs.openstack.org/tacker/latest/user/nsd_usage_guide.html#using-nsd-to-create-vnfs
[2] https://github.com/openstack/tacker/blob/master/samples/tosca-templates/nsd/sample-tosca-nsd.yaml
Aug 16 17:48:11 devstack mistral-server[859]: 2020-08-16 17:48:11.269 INFO mistral.engine.engine_server [req-d6b3827b-09dd-4663-9c8f-8140b9181e38 nfv nfv_u
Aug 16 17:48:11 devstack mistral-server[859]: 2020-08-16 17:48:11.289 INFO workflow_trace [req-d6b3827b-09dd-4663-9c8f-8140b9181e38 nfv nfv_user] Starting
Aug 16 17:48:11 devstack mistral-server[859]: 2020-08-16 17:48:11.688 DEBUG mistral.services.legacy_scheduler [req-bbb24554-72df-48eb-8439-bd56d8f733ef nfv
Aug 16 17:48:11 devstack mistral-server[859]: 2020-08-16 17:48:11.692 DEBUG mistral.services.legacy_scheduler [req-bbb24554-72df-48eb-8439-bd56d8f733ef nfv
Aug 16 17:48:12 devstack mistral-server[859]: 2020-08-16 17:48:12.081 INFO workflow_trace [req-d6b3827b-09dd-4663-9c8f-8140b9181e38 nfv nfv_user] Workflow
Aug 16 17:48:12 devstack mistral-server[859]: 2020-08-16 17:48:12.105 INFO workflow_trace [req-d6b3827b-09dd-4663-9c8f-8140b9181e38 nfv nfv_user] Task 'cre
Aug 16 17:48:12 devstack mistral-server[859]: 2020-08-16 17:48:12.106 DEBUG mistral.engine.tasks [req-d6b3827b-09dd-4663-9c8f-8140b9181e38 nfv nfv_user] St
Aug 16 17:48:12 devstack mistral-server[859]: 2020-08-16 17:48:12.106 DEBUG mistral.expressions.yaql_expression [req-d6b3827b-09dd-4663-9c8f-8140b9181e38 n
Aug 16 17:48:12 devstack mistral-server[859]: 2020-08-16 17:48:12.504 INFO mistral.expressions.yaql_expression [req-d6b3827b-09dd-4663-9c8f-8140b9181e38 nf
Aug 16 17:48:12 devstack mistral-server[859]: {'yaql.limitIterators': -1, 'yaql.memoryQuota': -1, 'yaql.convertTuplesToLists': True, 'yaql.convertSetsToLis
Aug 16 17:48:12 devstack mistral-server[859]: 2020-08-16 17:48:12.694 DEBUG mistral.services.legacy_scheduler [req-bbb24554-72df-48eb-8439-bd56d8f733ef nfv
Aug 16 17:48:12 devstack mistral-server[859]: 2020-08-16 17:48:12.701 DEBUG mistral.services.legacy_scheduler [req-bbb24554-72df-48eb-8439-bd56d8f733ef nfv
Aug 16 17:48:13 devstack mistral-server[859]: 2020-08-16 17:48:13.187 ERROR mistral.engine.task_handler [req-d6b3827b-09dd-4663-9c8f-8140b9181e38 nfv nfv_u
Aug 16 17:48:13 devstack mistral-server[859]: Traceback (most recent call last):
Aug 16 17:48:13 devstack mistral-server[859]: File "/opt/stack/mistral/mistral/engine/task_handler.py", line 68, in run_task
Aug 16 17:48:13 devstack mistral-server[859]: task.run()
Aug 16 17:48:13 devstack mistral-server[859]: File "/usr/local/lib/python3.6/dist-packages/osprofiler/profiler.py", line 160, in wrapper
Aug 16 17:48:13 devstack mistral-server[859]: result = f(*args, **kwargs)
Aug 16 17:48:13 devstack mistral-server[859]: File "/opt/stack/mistral/mistral/engine/tasks.py", line 569, in run
Aug 16 17:48:13 devstack mistral-server[859]: self._run_new()
Aug 16 17:48:13 devstack mistral-server[859]: File "/usr/local/lib/python3.6/dist-packages/osprofiler/profiler.py", line 160, in wrapper
Aug 16 17:48:13 devstack mistral-server[859]: result = f(*args, **kwargs)
Aug 16 17:48:13 devstack mistral-server[859]: File "/opt/stack/mistral/mistral/engine/tasks.py", line 601, in _run_new
Aug 16 17:48:13 devstack mistral-server[859]: self._schedule_actions()
Aug 16 17:48:13 devstack mistral-server[859]: File "/opt/stack/mistral/mistral/engine/tasks.py", line 669, in _schedule_actions
Aug 16 17:48:13 devstack mistral-server[859]: action = self._build_action()
Aug 16 17:48:13 devstack mistral-server[859]: File "/opt/stack/mistral/mistral/engine/tasks.py", line 741, in _build_action
Aug 16 17:48:13 devstack mistral-server[859]: namespace=self.wf_ex.workflow_namespace
Aug 16 17:48:13 devstack mistral-server[859]: File "/opt/stack/mistral/mistral/engine/actions.py", line 694, in resolve_action_definition
Aug 16 17:48:13 devstack mistral-server[859]: (action_spec_name, namespace)
Aug 16 17:48:13 devstack mistral-server[859]: mistral.exceptions.InvalidActionException: Failed to find action [action_name=tacker.create_vnf] in [namespac
Aug 16 17:48:13 devstack mistral-server[859]: : mistral.exceptions.InvalidActionException: Failed to find action [action_name=tacker.create_vnf] in [namesp
Aug 16 17:48:13 devstack mistral-server[859]: 2020-08-16 17:48:13.191 INFO workflow_trace [req-d6b3827b-09dd-4663-9c8f-8140b9181e38 nfv nfv_user] Task 'cre
Aug 16 17:48:13 devstack mistral-server[859]: Traceback (most recent call last):
Aug 16 17:48:13 devstack mistral-server[859]: File "/opt/stack/mistral/mistral/engine/task_handler.py", line 68, in run_task
Aug 16 17:48:13 devstack mistral-server[859]: task.run()
Aug 16 17:48:13 devstack mistral-server[859]: File "/usr/local/lib/python3.6/dist-packages/osprofiler/profiler.py", line 160, in wrapper
Aug 16 17:48:13 devstack mistral-server[859]: result = f(*args, **kwargs)
Aug 16 17:48:13 devstack mistral-server[859]: File "/opt/stack/mistral/mistral/engine/tasks.py", line 569, in run
Aug 16 17:48:13 devstack mistral-server[859]: self._run_new()
Aug 16 17:48:13 devstack mistral-server[859]: File "/usr/local/lib/python3.6/dist-packages/osprofiler/profiler.py", line 160, in wrapper
Aug 16 17:48:13 devstack mistral-server[859]: result = f(*args, **kwargs)
Aug 16 17:48:13 devstack mistral-server[859]: File "/opt/stack/mistral/mistral/engine/tasks.py", line 569, in run
Aug 16 17:48:13 devstack mistral-server[859]: self._run_new()
Aug 16 17:48:13 devstack mistral-server[859]: File "/usr/local/lib/python3.6/dist-packages/osprofiler/profiler.py", line 160, in wrapper
Aug 16 17:48:13 devstack mistral-server[859]: result = f(*args, **kwargs)
Aug 16 17:48:13 devstack mistral-server[859]: File "/opt/stack/mistral/mistral/engine/tasks.py", line 601, in _run_new
Aug 16 17:48:13 devstack mistral-server[859]: self._schedule_actions()
Aug 16 17:48:13 devstack mistral-server[859]: File "/opt/stack/mistral/mistral/engine/tasks.py", line 669, in _schedule_actions
Aug 16 17:48:13 devstack mistral-server[859]: action = self._build_action()
Aug 16 17:48:13 devstack mistral-server[859]: File "/opt/stack/mistral/mistral/engine/tasks.py", line 741, in _build_action
Aug 16 17:48:13 devstack mistral-server[859]: namespace=self.wf_ex.workflow_namespace
Aug 16 17:48:13 devstack mistral-server[859]: File "/opt/stack/mistral/mistral/engine/actions.py", line 694, in resolve_action_definition
Aug 16 17:48:13 devstack mistral-server[859]: (action_spec_name, namespace)
Aug 16 17:48:13 devstack mistral-server[859]: mistral.exceptions.InvalidActionException: Failed to find action [action_name=tacker.create_vnf] in [namespac
Aug 16 17:48:13 devstack mistral-server[859]: ] (execution_id=8379ff8e-6757-4e1d-9a39-68285c052ead)
Aug 16 17:48:13 devstack mistral-server[859]: 2020-08-16 17:48:13.227 INFO workflow_trace [req-d6b3827b-09dd-4663-9c8f-8140b9181e38 nfv nfv_user] Workflow
Aug 16 17:48:13 devstack mistral-server[859]: Traceback (most recent call last):
Aug 16 17:48:13 devstack mistral-server[859]: File "/opt/stack/mistral/mistral/engine/task_handler.py", line 68, in run_task
Aug 16 17:48:13 devstack mistral-server[859]: task.run()
Aug 16 17:48:13 devstack mistral-server[859]: File "/usr/local/lib/python3.6/dist-packages/osprofiler/profiler.py", line 160, in wrapper
Aug 16 17:48:13 devstack mistral-server[859]: result = f(*args, **kwargs)
Aug 16 17:48:13 devstack mistral-server[859]: File "/opt/stack/mistral/mistral/engine/tasks.py", line 569, in run
Aug 16 17:48:13 devstack mistral-server[859]: self._run_new()
Aug 16 17:48:13 devstack mistral-server[859]: File "/usr/local/lib/python3.6/dist-packages/osprofiler/profiler.py", line 160, in wrapper
Aug 16 17:48:13 devstack mistral-server[859]: result = f(*args, **kwargs)
Aug 16 17:48:13 devstack mistral-server[859]: File "/opt/stack/mistral/mistral/engine/tasks.py", line 601, in _run_new
Aug 16 17:48:13 devstack mistral-server[859]: self._schedule_actions()
Aug 16 17:48:13 devstack mistral-server[859]: File "/opt/stack/mistral/mistral/engine/tasks.py", line 669, in _schedule_actions
Aug 16 17:48:13 devstack mistral-server[859]: action = self._build_action()
Aug 16 17:48:13 devstack mistral-server[859]: File "/opt/stack/mistral/mistral/engine/tasks.py", line 741, in _build_action
Aug 16 17:48:13 devstack mistral-server[859]: namespace=self.wf_ex.workflow_namespace
Aug 16 17:48:13 devstack mistral-server[859]: File "/opt/stack/mistral/mistral/engine/actions.py", line 694, in resolve_action_definition
Aug 16 17:48:13 devstack mistral-server[859]: (action_spec_name, namespace)
Aug 16 17:48:13 devstack mistral-server[859]: mistral.exceptions.InvalidActionException: Failed to find action [action_name=tacker.create_vnf] in [namespac
Aug 16 17:48:13 devstack mistral-server[859]: ] (execution_id=8379ff8e-6757-4e1d-9a39-68285c052ead)
Aug 16 17:47:56 devstack tacker-server[920]: 2020-08-16 17:47:56.961 DEBUG tacker.wsgi [-] (920) accepted ('127.0.0.1', 37162) from (pid=920) server /usr/local/lib/python3.6/dist-packages/eventlet/wsgi.py:985
Aug 16 17:47:56 devstack tacker-server[920]: 2020-08-16 17:47:56.962 DEBUG tacker.alarm_receiver [-] Process request: GET /v1.0/nsds.json?name=sample-tosca-nsd&fields=id HTTP/1.0
Aug 16 17:47:56 devstack tacker-server[920]: Accept: application/json
Aug 16 17:47:56 devstack tacker-server[920]: Accept-Encoding: gzip, deflate
Aug 16 17:47:56 devstack tacker-server[920]: Connection: keep-alive
Aug 16 17:47:56 devstack tacker-server[920]: Content-Type: text/plain
Aug 16 17:47:56 devstack tacker-server[920]: Host: 127.0.0.1:9890
Aug 16 17:47:56 devstack tacker-server[920]: User-Agent: python-tackerclient
Aug 16 17:47:56 devstack tacker-server[920]: X-Auth-Token: gAAAAABfOPK8VFEadqYQ2sE-vJm2YChszjR13Y6RbctkX9iVpk5y_EW6jpEFAReQFouSu3NywQRDLqauJf2ZQdPGozbqPta1Q72A7gsmw2s8mODUQ2KN9D1F-BPceEBQi4qpPXZZ6Tkrjz8y3_DgzwSAT30VxgNiiK_qiMhXabPb5ivZ3k05PJw from (pid=920) process_request /opt/stack/tacker/tacker/alarm_receiver.py:48
Aug 16 17:47:57 devstack tacker-server[920]: 2020-08-16 17:47:57.089 INFO tacker.wsgi [req-a756312d-fccf-4f9d-80c6-9c196a53ca5a nfv nfv_user] 127.0.0.1 - - [16/Aug/2020 17:47:57] "GET /v1.0/nsds.json?name=sample-tosca-nsd&fields=id HTTP/1.1" 200 256 0.127375
Aug 16 17:47:57 devstack tacker-server[920]: 2020-08-16 17:47:57.107 DEBUG tacker.alarm_receiver [req-a756312d-fccf-4f9d-80c6-9c196a53ca5a nfv nfv_user] Process request: POST /v1.0/nss.json HTTP/1.0
Aug 16 17:47:57 devstack tacker-server[920]: Accept: application/json
Aug 16 17:47:57 devstack tacker-server[920]: Accept-Encoding: gzip, deflate
Aug 16 17:47:57 devstack tacker-server[920]: Connection: keep-alive
Aug 16 17:47:57 devstack tacker-server[920]: Content-Length: 172
Aug 16 17:47:57 devstack tacker-server[920]: Content-Type: application/json
Aug 16 17:47:57 devstack tacker-server[920]: Host: 127.0.0.1:9890
Aug 16 17:47:57 devstack tacker-server[920]: User-Agent: python-tackerclient
Aug 16 17:47:57 devstack tacker-server[920]: X-Auth-Token: gAAAAABfOPK8VFEadqYQ2sE-vJm2YChszjR13Y6RbctkX9iVpk5y_EW6jpEFAReQFouSu3NywQRDLqauJf2ZQdPGozbqPta1Q72A7gsmw2s8mODUQ2KN9D1F-BPceEBQi4qpPXZZ6Tkrjz8y3_DgzwSAT30VxgNiiK_qiMhXabPb5ivZ3k05PJw
Aug 16 17:47:57 devstack tacker-server[920]: [1B blob data]
Aug 16 17:47:57 devstack tacker-server[920]: {"ns": {"attributes": {"param_values": {"nsd": {"vl1_name": "net_mgmt", "vl2_name": "net0"}}}, "name": "sample-tosca-ns", "nsd_id": "1e99a0a2-0251-4ad3-9eb1-86d925e5c5db"}} from (pid=920) process_request /opt/stack/tacker/tacker/alarm_receiver.py:48
Aug 16 17:47:57 devstack tacker-server[920]: 2020-08-16 17:47:57.113 DEBUG tacker.api.v1.base [req-7d436bb6-746d-496f-8539-0c86192e135c nfv nfv_user] Request body: {'ns': {'attributes': {'param_values': {'nsd': {'vl1_name': 'net_mgmt', 'vl2_name': 'net0'}}}, 'name': 'sample-tosca-ns', 'nsd_id': '1e99a0a2-0251-4ad3-9eb1-86d925e5c5db'}} from (pid=920) prepare_request_body /opt/stack/tacker/tacker/api/v1/base.py:513
Aug 16 17:47:57 devstack tacker-server[920]: 2020-08-16 17:47:57.113 DEBUG tacker.common.log [req-7d436bb6-746d-496f-8539-0c86192e135c nfv nfv_user] tacker.nfvo.nfvo_plugin.NfvoPlugin method create_ns called with arguments (<tacker.context.Context object at 0x7fddf01f7898>,) {'ns': {'ns': {'attributes': {'param_values': {'nsd': {'vl1_name': 'net_mgmt', 'vl2_name': 'net0'}}}, 'name': 'sample-tosca-ns', 'nsd_id': '1e99a0a2-0251-4ad3-9eb1-86d925e5c5db', 'tenant_id': 'e77397d2a02c4af1b7d79cef2a406396', 'description': '', 'vnf_ids': '', 'vnffg_ids': '', 'placement_attr': {}, 'vim_id': '', 'nsd_template': None}}} from (pid=920) wrapper /opt/stack/tacker/tacker/common/log.py:34
Aug 16 17:47:57 devstack tacker-server[920]: 2020-08-16 17:47:57.178 DEBUG tacker.vnfm.vim_client [req-7d436bb6-746d-496f-8539-0c86192e135c nfv nfv_user] VIM id not provided. Attempting to find default VIM information from (pid=920) get_vim /opt/stack/tacker/tacker/vnfm/vim_client.py:45
Aug 16 17:47:57 devstack tacker-server[920]: 2020-08-16 17:47:57.189 DEBUG tacker.vnfm.vim_client [req-7d436bb6-746d-496f-8539-0c86192e135c nfv nfv_user] VIM info found for vim id from (pid=920) get_vim /opt/stack/tacker/tacker/vnfm/vim_client.py:58
Aug 16 17:47:57 devstack tacker-server[920]: 2020-08-16 17:47:57.191 DEBUG tacker.vnfm.vim_client [req-7d436bb6-746d-496f-8539-0c86192e135c nfv nfv_user] VIM id is aacb3c7f-d532-44d9-b8ed-49e2b30114aa from (pid=920) _build_vim_auth /opt/stack/tacker/tacker/vnfm/vim_client.py:74
Aug 16 17:47:57 devstack tacker-server[920]: 2020-08-16 17:47:57.271 DEBUG barbicanclient.client [req-7d436bb6-746d-496f-8539-0c86192e135c nfv nfv_user] Creating Client object from (pid=920) Client /usr/local/lib/python3.6/dist-packages/barbicanclient/client.py:156
Aug 16 17:47:57 devstack tacker-server[920]: 2020-08-16 17:47:57.285 DEBUG barbicanclient.v1.secrets [req-7d436bb6-746d-496f-8539-0c86192e135c nfv nfv_user] Getting secret - Secret href: http://127.0.0.1/key-manager/v1/secrets/78925246-5c31-403b-8197-7754d427c525 from (pid=920) get /usr/local/lib/python3.6/dist-packages/barbicanclient/v1/secrets.py:458
Aug 16 17:47:57 devstack tacker-server[920]: 2020-08-16 17:47:57.286 INFO barbicanclient.base [req-7d436bb6-746d-496f-8539-0c86192e135c nfv nfv_user] Calculated Secrets uuid ref: secrets/78925246-5c31-403b-8197-7754d427c525
Aug 16 17:47:57 devstack tacker-server[920]: 2020-08-16 17:47:57.635 DEBUG barbicanclient.client [req-7d436bb6-746d-496f-8539-0c86192e135c nfv nfv_user] Response status 200 from (pid=920) _check_status_code /usr/local/lib/python3.6/dist-packages/barbicanclient/client.py:87
Aug 16 17:47:57 devstack tacker-server[920]: 2020-08-16 17:47:57.636 INFO barbicanclient.base [req-7d436bb6-746d-496f-8539-0c86192e135c nfv nfv_user] Calculated Secrets uuid ref: secrets/78925246-5c31-403b-8197-7754d427c525
Aug 16 17:47:57 devstack tacker-server[920]: 2020-08-16 17:47:57.677 DEBUG barbicanclient.client [req-7d436bb6-746d-496f-8539-0c86192e135c nfv nfv_user] Response status 200 from (pid=920) _check_status_code /usr/local/lib/python3.6/dist-packages/barbicanclient/client.py:87
Aug 16 17:47:57 devstack tacker-server[920]: 2020-08-16 17:47:57.678 INFO barbicanclient.base [req-7d436bb6-746d-496f-8539-0c86192e135c nfv nfv_user] Calculated Secrets uuid ref: secrets/78925246-5c31-403b-8197-7754d427c525
Aug 16 17:47:57 devstack tacker-server[920]: 2020-08-16 17:47:57.736 DEBUG barbicanclient.client [req-7d436bb6-746d-496f-8539-0c86192e135c nfv nfv_user] Response status 200 from (pid=920) _check_status_code /usr/local/lib/python3.6/dist-packages/barbicanclient/client.py:87
Aug 16 17:47:57 devstack tacker-server[920]: 2020-08-16 17:47:57.737 DEBUG tacker.common.log [req-7d436bb6-746d-496f-8539-0c86192e135c nfv nfv_user] tacker.nfvo.nfvo_plugin.NfvoPlugin method _process_parameterized_input called with arguments ({'param_values': {'nsd': {'vl1_name': 'net_mgmt', 'vl2_name': 'net0'}}}, {'description': 'Import VNFDs(already on-boarded) with input parameters', 'imports': ['sample-tosca-vnfd1', 'sample-tosca-vnfd2'], 'topology_template': {'inputs': {'vl1_name': {'default': 'net_mgmt', 'description': 'name of VL1 virtuallink', 'type': 'string'}, 'vl2_name': {'default': 'net0', 'description': 'name of VL2 virtuallink', 'type': 'string'}}, 'node_templates': {'VL1': {'properties': {'network_name': {'get_input': 'vl1_name'}, 'vendor': 'tacker'}, 'type': 'tosca.nodes.nfv.VL'}, 'VL2': {'properties': {'network_name': {'get_input': 'vl2_name'}, 'vendor': 'tacker'}, 'type': 'tosca.nodes.nfv.VL'}, 'VNF1': {'requirements': [{'virtualLink1': 'VL1'}, {'virtualLink2': 'VL2'}], 'type': 'tosca.nodes.nfv.VNF1'}, 'VNF2': {'type': 'tosca.nodes.nfv.VNF2'}}}, 'tosca_definitions_version': 'tosca_simple_profile_for_nfv_1_0_0'}) {} from (pid=920) wrapper /opt/stack/tacker/tacker/common/log.py:34
Aug 16 17:47:57 devstack tacker-server[920]: 2020-08-16 17:47:57.737 DEBUG tacker.common.log [req-7d436bb6-746d-496f-8539-0c86192e135c nfv nfv_user] tacker.nfvo.nfvo_plugin.NfvoPlugin method _update_params called with arguments ({'properties': {'network_name': {'get_input': 'vl1_name'}, 'vendor': 'tacker'}, 'type': 'tosca.nodes.nfv.VL'}, {'vl1_name': 'net_mgmt', 'vl2_name': 'net0'}) {} from (pid=920) wrapper /opt/stack/tacker/tacker/common/log.py:34
Aug 16 17:47:57 devstack tacker-server[920]: 2020-08-16 17:47:57.738 DEBUG tacker.common.log [req-7d436bb6-746d-496f-8539-0c86192e135c nfv nfv_user] tacker.nfvo.nfvo_plugin.NfvoPlugin method _update_params called with arguments ({'network_name': {'get_input': 'vl1_name'}, 'vendor': 'tacker'}, {'vl1_name': 'net_mgmt', 'vl2_name': 'net0'}) {} from (pid=920) wrapper /opt/stack/tacker/tacker/common/log.py:34
Aug 16 17:47:57 devstack tacker-server[920]: 2020-08-16 17:47:57.738 DEBUG tacker.common.log [req-7d436bb6-746d-496f-8539-0c86192e135c nfv nfv_user] tacker.nfvo.nfvo_plugin.NfvoPlugin method _update_params called with arguments ({'properties': {'network_name': {'get_input': 'vl2_name'}, 'vendor': 'tacker'}, 'type': 'tosca.nodes.nfv.VL'}, {'vl1_name': 'net_mgmt', 'vl2_name': 'net0'}) {} from (pid=920) wrapper /opt/stack/tacker/tacker/common/log.py:34
Aug 16 17:47:57 devstack tacker-server[920]: 2020-08-16 17:47:57.738 DEBUG tacker.common.log [req-7d436bb6-746d-496f-8539-0c86192e135c nfv nfv_user] tacker.nfvo.nfvo_plugin.NfvoPlugin method _update_params called with arguments ({'network_name': {'get_input': 'vl2_name'}, 'vendor': 'tacker'}, {'vl1_name': 'net_mgmt', 'vl2_name': 'net0'}) {} from (pid=920) wrapper /opt/stack/tacker/tacker/common/log.py:34
Aug 16 17:47:57 devstack tacker-server[920]: 2020-08-16 17:47:57.739 DEBUG tacker.common.log [req-7d436bb6-746d-496f-8539-0c86192e135c nfv nfv_user] tacker.nfvo.nfvo_plugin.NfvoPlugin method _get_vnffgds_from_nsd called with arguments ({'description': 'Import VNFDs(already on-boarded) with input parameters', 'imports': ['sample-tosca-vnfd1', 'sample-tosca-vnfd2'], 'topology_template': {'inputs': {'vl1_name': {'default': 'net_mgmt', 'description': 'name of VL1 virtuallink', 'type': 'string'}, 'vl2_name': {'default': 'net0', 'description': 'name of VL2 virtuallink', 'type': 'string'}}, 'node_templates': {'VL1': {'properties': {'network_name': 'net_mgmt', 'vendor': 'tacker'}, 'type': 'tosca.nodes.nfv.VL'}, 'VL2': {'properties': {'network_name': 'net0', 'vendor': 'tacker'}, 'type': 'tosca.nodes.nfv.VL'}, 'VNF1': {'requirements': [{'virtualLink1': 'VL1'}, {'virtualLink2': 'VL2'}], 'type': 'tosca.nodes.nfv.VNF1'}, 'VNF2': {'type': 'tosca.nodes.nfv.VNF2'}}}, 'tosca_definitions_version': 'tosca_simple_profile_for_nfv_1_0_0'},) {} from (pid=920) wrapper /opt/stack/tacker/tacker/common/log.py:34
Aug 16 17:47:57 devstack tacker-server[920]: 2020-08-16 17:47:57.739 DEBUG tacker.nfvo.nfvo_plugin [req-7d436bb6-746d-496f-8539-0c86192e135c nfv nfv_user] vnffgd_templates: {} from (pid=920) create_ns /opt/stack/tacker/tacker/nfvo/nfvo_plugin.py:799
Aug 16 17:47:57 devstack tacker-server[920]: 2020-08-16 17:47:57.740 DEBUG keystoneclient.session [req-7d436bb6-746d-496f-8539-0c86192e135c nfv nfv_user] REQ: curl -g -i --cacert "True" -X GET http://127.0.0.1/identity/v3 -H "Accept: application/json" -H "User-Agent: python-keystoneclient" from (pid=920) _http_log_request /usr/local/lib/python3.6/dist-packages/keystoneclient/session.py:215
Aug 16 17:47:57 devstack tacker-server[920]: 2020-08-16 17:47:57.750 DEBUG keystoneclient.session [req-7d436bb6-746d-496f-8539-0c86192e135c nfv nfv_user] RESP: [200] Date: Sun, 16 Aug 2020 08:47:57 GMT Server: Apache/2.4.29 (Ubuntu) Content-Type: application/json Content-Length: 253 Vary: X-Auth-Token x-openstack-request-id: req-0315a43f-1e6b-404d-b183-e29186e2ced7 Connection: close
Aug 16 17:47:57 devstack tacker-server[920]: RESP BODY: {"version": {"id": "v3.14", "status": "stable", "updated": "2020-04-07T00:00:00Z", "links": [{"rel": "self", "href": "http://127.0.0.1/identity/v3/"}], "media-types": [{"base": "application/json", "type": "application/vnd.openstack.identity-v3+json"}]}}
Aug 16 17:47:57 devstack tacker-server[920]: from (pid=920) _http_log_response /usr/local/lib/python3.6/dist-packages/keystoneclient/session.py:255
Aug 16 17:48:06 devstack tacker-server[920]: 2020-08-16 17:48:06.818 DEBUG mistralclient.api.httpclient [req-7d436bb6-746d-496f-8539-0c86192e135c nfv nfv_user] HTTP POST http://127.0.0.1:8989/v2/workflows?scope=private&namespace= 201 from (pid=920) decorator /usr/local/lib/python3.6/dist-packages/mistralclient/api/httpclient.py:56
Aug 16 17:48:06 devstack tacker-server[920]: 2020-08-16 17:48:06.819 DEBUG keystoneclient.session [req-7d436bb6-746d-496f-8539-0c86192e135c nfv nfv_user] REQ: curl -g -i --cacert "True" -X GET http://127.0.0.1/identity/v3 -H "Accept: application/json" -H "User-Agent: python-keystoneclient" from (pid=920) _http_log_request /usr/local/lib/python3.6/dist-packages/keystoneclient/session.py:215
Aug 16 17:48:06 devstack tacker-server[920]: 2020-08-16 17:48:06.826 DEBUG keystoneclient.session [req-7d436bb6-746d-496f-8539-0c86192e135c nfv nfv_user] RESP: [200] Date: Sun, 16 Aug 2020 08:48:06 GMT Server: Apache/2.4.29 (Ubuntu) Content-Type: application/json Content-Length: 253 Vary: X-Auth-Token x-openstack-request-id: req-27103792-abd2-45e6-ac69-1a8553446701 Connection: close
Aug 16 17:48:06 devstack tacker-server[920]: RESP BODY: {"version": {"id": "v3.14", "status": "stable", "updated": "2020-04-07T00:00:00Z", "links": [{"rel": "self", "href": "http://127.0.0.1/identity/v3/"}], "media-types": [{"base": "application/json", "type": "application/vnd.openstack.identity-v3+json"}]}}
Aug 16 17:48:06 devstack tacker-server[920]: from (pid=920) _http_log_response /usr/local/lib/python3.6/dist-packages/keystoneclient/session.py:255
Aug 16 17:48:13 devstack tacker-server[920]: 2020-08-16 17:48:13.293 DEBUG mistralclient.api.httpclient [req-7d436bb6-746d-496f-8539-0c86192e135c nfv nfv_user] HTTP POST http://127.0.0.1:8989/v2/executions 201 from (pid=920) decorator /usr/local/lib/python3.6/dist-packages/mistralclient/api/httpclient.py:56
Aug 16 17:48:13 devstack tacker-server[920]: 2020-08-16 17:48:13.295 DEBUG tacker.db.nfvo.ns_db [req-7d436bb6-746d-496f-8539-0c86192e135c nfv nfv_user] ns {'ns': {'attributes': {}, 'name': 'sample-tosca-ns', 'nsd_id': '1e99a0a2-0251-4ad3-9eb1-86d925e5c5db', 'tenant_id': 'e77397d2a02c4af1b7d79cef2a406396', 'description': '', 'vnf_ids': '', 'vnffg_ids': '', 'placement_attr': {}, 'vim_id': 'aacb3c7f-d532-44d9-b8ed-49e2b30114aa', 'nsd_template': None, 'ns_id': 'deb19599-8af9-4d93-b926-a731438e08f1'}, 'vnfd_details': {'sample-tosca-vnfd1': {'id': '22e002c5-b484-40a4-85cb-1fabaf593df3', 'instances': ['VNF1']}, 'sample-tosca-vnfd2': {'id': '6881dd08-886d-46d2-8d3d-aa00b393d7f0', 'instances': ['VNF2']}}, 'vnffgd_templates': {}} from (pid=920) create_ns /opt/stack/tacker/tacker/db/nfvo/ns_db.py:252
Aug 16 17:48:13 devstack tacker-server[920]: 2020-08-16 17:48:13.301 DEBUG tacker.common.log [req-7d436bb6-746d-496f-8539-0c86192e135c nfv nfv_user] tacker.db.common_services.common_services_db_plugin.CommonServicesPluginDb method create_event called with arguments (<tacker.context.Context object at 0x7fddf01f7898>,) {'res_id': 'deb19599-8af9-4d93-b926-a731438e08f1', 'res_type': 'ns', 'res_state': 'PENDING_CREATE', 'evt_type': 'CREATE', 'tstamp': datetime.datetime(2020, 8, 16, 8, 48, 13, 297173), 'details': 'NS UUID assigned.'} from (pid=920) wrapper /opt/stack/tacker/tacker/common/log.py:34
Aug 16 17:48:13 devstack tacker-server[920]: 2020-08-16 17:48:13.308 DEBUG tacker.db.nfvo.ns_db [req-7d436bb6-746d-496f-8539-0c86192e135c nfv nfv_user] ns_db <tacker.db.nfvo.ns_db.NS[object at 7fddf01c0550] {tenant_id='e77397d2a02c4af1b7d79cef2a406396', id='deb19599-8af9-4d93-b926-a731438e08f1', created_at=datetime.datetime(2020, 8, 16, 8, 48, 13, 297173), updated_at=None, deleted_at=datetime.datetime(1, 1, 1, 0, 0), nsd_id='1e99a0a2-0251-4ad3-9eb1-86d925e5c5db', name='sample-tosca-ns', description='', vnf_ids=None, vnffg_ids=None, mgmt_ip_addresses=None, status='PENDING_CREATE', vim_id='aacb3c7f-d532-44d9-b8ed-49e2b30114aa', error_reason=None}> from (pid=920) _make_ns_dict /opt/stack/tacker/tacker/db/nfvo/ns_db.py:166
Aug 16 17:48:13 devstack tacker-server[920]: 2020-08-16 17:48:13.325 INFO tacker.wsgi [req-7d436bb6-746d-496f-8539-0c86192e135c nfv nfv_user] 127.0.0.1 - - [16/Aug/2020 17:48:13] "POST /v1.0/nss.json HTTP/1.1" 201 630 16.217539
Aug 16 17:48:19 devstack tacker-server[920]: 2020-08-16 17:48:19.334 DEBUG keystoneclient.session [-] REQ: curl -g -i --cacert "True" -X GET http://127.0.0.1/identity/v3 -H "Accept: application/json" -H "User-Agent: python-keystoneclient" from (pid=920) _http_log_request /usr/local/lib/python3.6/dist-packages/keystoneclient/session.py:215
Aug 16 17:48:19 devstack tacker-server[920]: 2020-08-16 17:48:19.344 DEBUG keystoneclient.session [-] RESP: [200] Date: Sun, 16 Aug 2020 08:48:19 GMT Server: Apache/2.4.29 (Ubuntu) Content-Type: application/json Content-Length: 253 Vary: X-Auth-Token x-openstack-request-id: req-84f1ba89-0832-46f1-be53-88113d61d9e1 Connection: close
Aug 16 17:48:19 devstack tacker-server[920]: RESP BODY: {"version": {"id": "v3.14", "status": "stable", "updated": "2020-04-07T00:00:00Z", "links": [{"rel": "self", "href": "http://127.0.0.1/identity/v3/"}], "media-types": [{"base": "application/json", "type": "application/vnd.openstack.identity-v3+json"}]}}
Aug 16 17:48:19 devstack tacker-server[920]: from (pid=920) _http_log_response /usr/local/lib/python3.6/dist-packages/keystoneclient/session.py:255
Aug 16 17:48:19 devstack tacker-server[920]: 2020-08-16 17:48:19.415 DEBUG mistralclient.api.httpclient [-] HTTP GET http://127.0.0.1:8989/v2/executions/8379ff8e-6757-4e1d-9a39-68285c052ead 200 from (pid=920) decorator /usr/local/lib/python3.6/dist-packages/mistralclient/api/httpclient.py:56
Aug 16 17:48:19 devstack tacker-server[920]: 2020-08-16 17:48:19.415 DEBUG tacker.nfvo.nfvo_plugin [-] status: ERROR from (pid=920) _create_ns_wait /opt/stack/tacker/tacker/nfvo/nfvo_plugin.py:838
Aug 16 17:48:19 devstack tacker-server[920]: 2020-08-16 17:48:19.416 DEBUG keystoneclient.session [-] REQ: curl -g -i --cacert "True" -X GET http://127.0.0.1/identity/v3 -H "Accept: application/json" -H "User-Agent: python-keystoneclient" from (pid=920) _http_log_request /usr/local/lib/python3.6/dist-packages/keystoneclient/session.py:215
Aug 16 17:48:19 devstack tacker-server[920]: 2020-08-16 17:48:19.423 DEBUG keystoneclient.session [-] RESP: [200] Date: Sun, 16 Aug 2020 08:48:19 GMT Server: Apache/2.4.29 (Ubuntu) Content-Type: application/json Content-Length: 253 Vary: X-Auth-Token x-openstack-request-id: req-da39f501-e69b-436a-8b7b-412d9f371daf Connection: close
Aug 16 17:48:19 devstack tacker-server[920]: RESP BODY: {"version": {"id": "v3.14", "status": "stable", "updated": "2020-04-07T00:00:00Z", "links": [{"rel": "self", "href": "http://127.0.0.1/identity/v3/"}], "media-types": [{"base": "application/json", "type": "application/vnd.openstack.identity-v3+json"}]}}
Aug 16 17:48:19 devstack tacker-server[920]: from (pid=920) _http_log_response /usr/local/lib/python3.6/dist-packages/keystoneclient/session.py:255
Aug 16 17:48:19 devstack tacker-server[920]: 2020-08-16 17:48:19.593 DEBUG mistralclient.api.httpclient [-] HTTP GET http://127.0.0.1:8989/v2/executions/8379ff8e-6757-4e1d-9a39-68285c052ead 200 from (pid=920) decorator /usr/local/lib/python3.6/dist-packages/mistralclient/api/httpclient.py:56
Aug 16 17:48:19 devstack tacker-server[920]: 2020-08-16 17:48:19.593 DEBUG keystoneclient.session [-] REQ: curl -g -i --cacert "True" -X GET http://127.0.0.1/identity/v3 -H "Accept: application/json" -H "User-Agent: python-keystoneclient" from (pid=920) _http_log_request /usr/local/lib/python3.6/dist-packages/keystoneclient/session.py:215
Aug 16 17:48:19 devstack tacker-server[920]: 2020-08-16 17:48:19.600 DEBUG keystoneclient.session [-] RESP: [200] Date: Sun, 16 Aug 2020 08:48:19 GMT Server: Apache/2.4.29 (Ubuntu) Content-Type: application/json Content-Length: 253 Vary: X-Auth-Token x-openstack-request-id: req-60e8208c-d806-49c2-8aeb-9fe5a000fe22 Connection: close
Aug 16 17:48:19 devstack tacker-server[920]: RESP BODY: {"version": {"id": "v3.14", "status": "stable", "updated": "2020-04-07T00:00:00Z", "links": [{"rel": "self", "href": "http://127.0.0.1/identity/v3/"}], "media-types": [{"base": "application/json", "type": "application/vnd.openstack.identity-v3+json"}]}}
Aug 16 17:48:19 devstack tacker-server[920]: from (pid=920) _http_log_response /usr/local/lib/python3.6/dist-packages/keystoneclient/session.py:255
Aug 16 17:48:19 devstack tacker-server[920]: 2020-08-16 17:48:19.672 DEBUG mistralclient.api.httpclient [-] HTTP DELETE http://127.0.0.1:8989/v2/executions/8379ff8e-6757-4e1d-9a39-68285c052ead?force=True 204 from (pid=920) decorator /usr/local/lib/python3.6/dist-packages/mistralclient/api/httpclient.py:56
Aug 16 17:48:19 devstack tacker-server[920]: 2020-08-16 17:48:19.673 DEBUG keystoneclient.session [-] REQ: curl -g -i --cacert "True" -X GET http://127.0.0.1/identity/v3 -H "Accept: application/json" -H "User-Agent: python-keystoneclient" from (pid=920) _http_log_request /usr/local/lib/python3.6/dist-packages/keystoneclient/session.py:215
Aug 16 17:48:19 devstack tacker-server[920]: 2020-08-16 17:48:19.679 DEBUG keystoneclient.session [-] RESP: [200] Date: Sun, 16 Aug 2020 08:48:19 GMT Server: Apache/2.4.29 (Ubuntu) Content-Type: application/json Content-Length: 253 Vary: X-Auth-Token x-openstack-request-id: req-c800ffb4-17b1-4ac6-9f89-c6ec620e00e4 Connection: close
Aug 16 17:48:19 devstack tacker-server[920]: RESP BODY: {"version": {"id": "v3.14", "status": "stable", "updated": "2020-04-07T00:00:00Z", "links": [{"rel": "self", "href": "http://127.0.0.1/identity/v3/"}], "media-types": [{"base": "application/json", "type": "application/vnd.openstack.identity-v3+json"}]}}
Aug 16 17:48:19 devstack tacker-server[920]: from (pid=920) _http_log_response /usr/local/lib/python3.6/dist-packages/keystoneclient/session.py:255
Aug 16 17:48:19 devstack tacker-server[920]: 2020-08-16 17:48:19.857 DEBUG mistralclient.api.httpclient [-] HTTP DELETE http://127.0.0.1:8989/v2/workflows/e4c9c08d-933c-4071-baf8-3b348d08d447 204 from (pid=920) decorator /usr/local/lib/python3.6/dist-packages/mistralclient/api/httpclient.py:56
Aug 16 17:48:19 devstack tacker-server[920]: 2020-08-16 17:48:19.857 DEBUG tacker.db.nfvo.ns_db [-] ns ID deb19599-8af9-4d93-b926-a731438e08f1 from (pid=920) create_ns_post /opt/stack/tacker/tacker/db/nfvo/ns_db.py:297
Aug 16 17:48:19 devstack tacker-server[920]: 2020-08-16 17:48:19.862 DEBUG tacker.db.nfvo.ns_db [-] ns_db <tacker.db.nfvo.ns_db.NS[object at 7fddf01b1e80] {tenant_id='e77397d2a02c4af1b7d79cef2a406396', id='deb19599-8af9-4d93-b926-a731438e08f1', created_at=datetime.datetime(2020, 8, 16, 8, 48, 13), updated_at=datetime.datetime(2020, 8, 16, 8, 48, 19, 862674), deleted_at=datetime.datetime(1, 1, 1, 0, 0), nsd_id='1e99a0a2-0251-4ad3-9eb1-86d925e5c5db', name='sample-tosca-ns', description='', vnf_ids='{}', vnffg_ids='{}', mgmt_ip_addresses='{}', status='ERROR', vim_id='aacb3c7f-d532-44d9-b8ed-49e2b30114aa', error_reason=None}> from (pid=920) _make_ns_dict /opt/stack/tacker/tacker/db/nfvo/ns_db.py:166
Aug 16 17:48:19 devstack tacker-server[920]: 2020-08-16 17:48:19.869 DEBUG tacker.common.log [-] tacker.db.common_services.common_services_db_plugin.CommonServicesPluginDb method create_event called with arguments (<tacker.context.Context object at 0x7fddf01f7898>,) {'res_id': 'deb19599-8af9-4d93-b926-a731438e08f1', 'res_type': 'ns', 'res_state': 'Not Applicable', 'evt_type': 'UPDATE', 'tstamp': datetime.datetime(2020, 8, 16, 8, 48, 19, 862674)} from (pid=920) wrapper /opt/stack/tacker/tacker/common/log.py:34
Hi,
As it's been a long time since this bug was reported, I'd like to confirm the current status. Are you still having trouble? or you have solved the problem by simply updating Tacker?