create ns fails due to missing Mistral default actions

Bug #1892144 reported by Yoshito Ito
8
This bug affects 1 person
Affects Status Importance Assigned to Milestone
tacker
New
Medium
Yasufumi Ogawa

Bug Description

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

Yasufumi Ogawa (yasufum)
Changed in tacker:
importance: Undecided → Medium
Yasufumi Ogawa (yasufum)
Changed in tacker:
assignee: nobody → Yasufumi Ogawa (yasufum)
Revision history for this message
Hiromu Asahina (h-asahina) wrote :

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?

To post a comment you must log in.
This report contains Public information  
Everyone can see this information.

Other bug subscribers