Deploy VNF error

Asked by Shuqiang Zhang

Hi,

I am new to tacker and trying out it. I successfully install it on devstack using the provided sample local.conf, and I can create a VNFD using "tacker vnfd-create --name sample-vnfd --vnfd-file sample-vnfd.yaml"

But when I tried to deploy the sample vnf using the UI or" tacker vnf-create --name sample --vnfd-id ba48**********", I always got ERROR status. Below are the logs of the traceback. Can someone help me identify what's the problem. Thanks a lot!

{"explanation": "The resource could not be found.", "code": 404, "error": {"message": "The Stack (None) could not be found.", "traceback": "Traceback (most recent call last):\n\n File \"/opt/stack/heat/heat/common/context.py\", line 296, in wrapped\n return func(self, ctx, *args, **kwargs)\n\n File \"/opt/stack/heat/heat/engine/service.py\", line 430, in identify_stack\n raise exception.StackNotFound(stack_name=stack_name)\n\nStackNotFound: The Stack (None) could not be found.\n", "type": "StackNotFound"}, "title": "Not Found"}
^[[00m ^[[00;33mfrom (pid=23114) log_http_response /usr/local/lib/python2.7/dist-packages/heatclient/common/http.py:142^[[00m
2015-11-05 22:59:13.792 ^[[01;31mERROR tacker.vm.plugin [^[[00;36m-^[[01;31m] ^[[01;35m^[[01;31m_delete_device_wait^[[00m
^[[01;31m2015-11-05 22:59:13.792 TRACE tacker.vm.plugin ^[[01;35m^[[00mTraceback (most recent call last):
^[[01;31m2015-11-05 22:59:13.792 TRACE tacker.vm.plugin ^[[01;35m^[[00m File "/opt/stack/tacker/tacker/vm/plugin.py", line 377, in _delete_device_wait
^[[01;31m2015-11-05 22:59:13.792 TRACE tacker.vm.plugin ^[[01;35m^[[00m context=context, device_id=instance_id)
^[[01;31m2015-11-05 22:59:13.792 TRACE tacker.vm.plugin ^[[01;35m^[[00m File "/opt/stack/tacker/tacker/common/driver_manager.py", line 75, in invoke
^[[01;31m2015-11-05 22:59:13.792 TRACE tacker.vm.plugin ^[[01;35m^[[00m return getattr(driver, method_name)(**kwargs)
^[[01;31m2015-11-05 22:59:13.792 TRACE tacker.vm.plugin ^[[01;35m^[[00m File "/opt/stack/tacker/tacker/common/log.py", line 34, in wrapper
^[[01;31m2015-11-05 22:59:13.792 TRACE tacker.vm.plugin ^[[01;35m^[[00m return method(*args, **kwargs)
^[[01;31m2015-11-05 22:59:13.792 TRACE tacker.vm.plugin ^[[01;35m^[[00m File "/opt/stack/tacker/tacker/vm/drivers/heat/heat.py", line 416, in delete_wait
^[[01;31m2015-11-05 22:59:13.792 TRACE tacker.vm.plugin ^[[01;35m^[[00m stack = heatclient_.get(device_id)
^[[01;31m2015-11-05 22:59:13.792 TRACE tacker.vm.plugin ^[[01;35m^[[00m File "/opt/stack/tacker/tacker/vm/drivers/heat/heat.py", line 499, in get
^[[01;31m2015-11-05 22:59:13.792 TRACE tacker.vm.plugin ^[[01;35m^[[00m return self.stacks.get(stack_id)
^[[01;31m2015-11-05 22:59:13.792 TRACE tacker.vm.plugin ^[[01;35m^[[00m File "/usr/local/lib/python2.7/dist-packages/heatclient/v1/stacks.py", line 214, in get
^[[01;31m2015-11-05 22:59:13.792 TRACE tacker.vm.plugin ^[[01;35m^[[00m resp = self.client.get('/stacks/%s' % stack_id)
^[[01;31m2015-11-05 22:59:13.792 TRACE tacker.vm.plugin ^[[01;35m^[[00m File "/usr/local/lib/python2.7/dist-packages/heatclient/common/http.py", line 284, in get
^[[01;31m2015-11-05 22:59:13.792 TRACE tacker.vm.plugin ^[[01;35m^[[00m return self.client_request("GET", url, **kwargs)
^[[01;31m2015-11-05 22:59:13.792 TRACE tacker.vm.plugin ^[[01;35m^[[00m File "/usr/local/lib/python2.7/dist-packages/heatclient/common/http.py", line 277, in client_request
^[[01;31m2015-11-05 22:59:13.792 TRACE tacker.vm.plugin ^[[01;35m^[[00m resp, body = self.json_request(method, url, **kwargs)
^[[01;31m2015-11-05 22:59:13.792 TRACE tacker.vm.plugin ^[[01;35m^[[00m File "/usr/local/lib/python2.7/dist-packages/heatclient/common/http.py", line 266, in json_request
^[[01;31m2015-11-05 22:59:13.792 TRACE tacker.vm.plugin ^[[01;35m^[[00m resp = self._http_request(url, method, **kwargs)
^[[01;31m2015-11-05 22:59:13.792 TRACE tacker.vm.plugin ^[[01;35m^[[00m File "/usr/local/lib/python2.7/dist-packages/heatclient/common/http.py", line 221, in _http_request
^[[01;31m2015-11-05 22:59:13.792 TRACE tacker.vm.plugin ^[[01;35m^[[00m raise exc.from_response(resp)
^[[01;31m2015-11-05 22:59:13.792 TRACE tacker.vm.plugin ^[[01;35m^[[00mHTTPNotFound: ERROR: The Stack (None) could not be found.
^[[01;31m2015-11-05 22:59:13.792 TRACE tacker.vm.plugin ^[[01;35m^[[00m
2015-11-05 23:00:28.592 ^[[00;36mINFO tacker.wsgi [^[[00;36m-^[[00;36m] ^[[01;35m^[[00;36m(23114) accepted ('192.168.111.59', 37363)
^[[00m
2015-11-05 23:00:28.593 ^[[00;32mDEBUG keystonemiddleware.auth_token [^[[00;36m-^[[00;32m] ^[[01;35m^[[00;32mAuthenticating user token^[[00m ^[[00;33mfrom (pid=23114) process_request /usr/local/lib/python2.7/dist-packages/

Question information

Language:
English Edit question
Status:
Open
For:
tacker Edit question
Assignee:
No assignee Edit question
Last query:
Last reply:
Revision history for this message
Sridhar Ramaswamy (srics-r) said :
#1

Log snippet show this happening on vnf-delete. Can you share the VNFD template used when this happen ?

Revision history for this message
Shuqiang Zhang (shuqiangzhang) said :
#2

I was using the provided sample template (sample-vnfd.yaml) without change like below. I tried several time today, very weirdly, the deployment succeeded for only a few times (became "ACTIVE" instead of "ERROR", and I can see the instance). but still, most other trails failed with the traceback provided below.
----------------------------------
template_name: sample-vnfd
description: demo-example

service_properties:
  Id: sample-vnfd
  vendor: tacker
  version: 1

vdus:
  vdu1:
    id: vdu1
    vm_image: cirros-0.3.4-x86_64-uec
    instance_type: m1.tiny

    network_interfaces:
      management:
        network: net_mgmt
        management: true
      pkt_in:
        network: net0
      pkt_out:
        network: net1

    placement_policy:
      availability_zone: nova

    auto-scaling: noop

    config:
      param0: key0
      param1: key1
--------------------------

maybe I provided the wrong traceback yestoday, this is another traceback happened at vnf-create (when I deploy the VNF through UI) time:

2015-11-06 18:33:08.274 ^[[00;32mDEBUG tacker.db.vm.vm_db [^[[00;36m-^[[00;32m] ^[[01;35m^[[00;32mdevice_dict {'status': 'ERROR', 'device_template': {'service_types': [{'service_type': u'vnfd', 'id': u'62c251d7-3694-4d34-9ae9-2fea9e5d3329'}], 'description': u'demo-example', 'tenant_id': u'7cd68db60975461fa690946ea279a015', 'mgmt_driver': u'noop', 'infra_driver': u'heat', 'attributes': {u'vnfd': u'template_name: sample-vnfd\ndescription: demo-example\n\nservice_properties:\n Id: sample-vnfd\n vendor: tacker\n version: 1\n\nvdus:\n vdu1:\n id: vdu1\n vm_image: cirros-0.3.4-x86_64-uec\n instance_type: m1.tiny\n\n network_interfaces:\n management:\n network: net_mgmt\n management: true\n pkt_in:\n network: net0\n pkt_out:\n network: net1\n\n placement_policy:\n availability_zone: nova\n\n auto-scaling: noop\n\n config:\n param0: key0\n param1: key1\n'}, 'id': u'ba48eaa6-ffd5-43b3-b420-871934e0bd1c', 'name': u'sample-vnfd'}, 'name': u'demostack', 'tenant_id': u'7cd68db60975461fa690946ea279a015', 'id': 'f8ddb362-dae2-48a4-a865-ed0aa3e92355', 'instance_id': u'9c1116d8-9e01-4fce-be33-3e5200ec7d3b', 'mgmt_url': None, 'service_context': [], 'services': [], 'attributes': {u'param_values': None, 'monitoring_policy': '{"vdus": {}}', u'config': None, 'heat_template': 'description: demo-example\nheat_template_version: 2013-05-23\noutputs:\n mgmt_ip-vdu1:\n description: management ip address\n value:\n get_attr: [vdu1-net_mgmt-port, fixed_ips, 0, ip_address]\nresources:\n vdu1:\n properties:\n availability_zone: nova\n config_drive: true\n flavor: m1.tiny\n image: cirros-0.3.4-x86_64-uec\n metadata: {param0: key0, param1: key1}\n networks:\n - port: {get_resource: vdu1-net_mgmt-port}\n - {network: net0}\n - {network: net1}\n type: OS::Nova::Server\n vdu1-net_mgmt-port:\n properties:\n fixed_ips: []\n network: net_mgmt\n value_specs: {port_security_enabled: false}\n type: OS::Neutron::Port\n'}, 'template_id': u'ba48eaa6-ffd5-43b3-b420-871934e0bd1c', 'description': u'demo-example'}^[[00m ^[[00;33mfrom (pid=23114) _create_device_post /opt/stack/tacker/tacker/db/vm/vm_db.py:558^[[00m
Traceback (most recent call last):
  File "/usr/local/lib/python2.7/dist-packages/eventlet/greenpool.py", line 82, in _spawn_n_impl
    func(*args, **kwargs)
  File "/opt/stack/tacker/tacker/vm/plugin.py", line 316, in create_device_wait
    self.add_device_to_monitor(device_dict)
  File "/opt/stack/tacker/tacker/vm/plugin.py", line 228, in add_device_to_monitor
    device_dict, action_cb)
  File "/opt/stack/tacker/tacker/vm/monitor.py", line 98, in to_hosting_vnf
    device_dict['mgmt_url']),
  File "/opt/stack/tacker/tacker/openstack/common/jsonutils.py", line 172, in loads
    return json.loads(strutils.safe_decode(s, encoding))
  File "/opt/stack/tacker/tacker/openstack/common/strutils.py", line 112, in safe_decode
    raise TypeError("%s can't be decoded" % type(text))
TypeError: <type 'NoneType'> can't be decoded

Revision history for this message
Nguyen Dinh Hai (nguyendinhhai11) said :
#3

Dear all,

I also experienced the same issue as above. As today I have re-installed and cloned latest master branch of Tacker.
System comes up with no issue but it is failed to creat vnf even when using sample-vnfd.yaml template
the error log

{"stack": {"disable_rollback": true, "description": "http-ping-cirros-example", "parent": null, "tags": null, "stack_name": "tacker.vm.drivers.heat.heat_DeviceHeat-dabf953e-75fe-4c39-b985-2d6705aea33d", "stack_user_project_id": "932ceedf0eb945ca8a72120deb2647a1", "stack_status_reason": "Resource CREATE failed: Conflict: resources.vdu1: Port eb822bda-0f6a-4c21-a0d9-9d5e7b4ef203 is still in use. (HTTP 409) (Request-ID: req-442f3957-5eb0-457a-957e-bcbaa7568cfa)", "creation_time": "2015-11-07T09:25:12", "links": [{"href": "http://223.194.33.91:8004/v1/691f644cb70b46b485da6461dca7c608/stacks/tacker.vm.drivers.heat.heat_DeviceHeat-dabf953e-75fe-4c39-b985-2d6705aea33d/8d2b0835-72b9-48ed-a013-be052fbaf96d", "rel": "self"}], "capabilities": [], "notification_topics": [], "updated_time": null, "timeout_mins": 10, "stack_status": "CREATE_FAILED", "stack_owner": "tacker", "parameters": {"OS::project_id": "691f644cb70b46b485da6461dca7c608", "OS::stack_id": "8d2b0835-72b9-48ed-a013-be052fbaf96d", "OS::stack_name": "tacker.vm.drivers.heat.heat_DeviceHeat-dabf953e-75fe-4c39-b985-2d6705aea33d"}, "id": "8d2b0835-72b9-48ed-a013-be052fbaf96d", "outputs": [{"output_value": "192.168.120.3", "description": "management ip address", "output_key": "mgmt_ip-vdu1"}], "template_description": "http-ping-cirros-example"}}
 from (pid=24574) log_http_response /usr/local/lib/python2.7/dist-packages/heatclient/common/http.py:142
2015-11-07 18:25:57.183 DEBUG tacker.vm.drivers.heat.heat [-] status: CREATE_FAILED from (pid=24574) create_wait /opt/stack/tacker/tacker/vm/drivers/heat/heat.py:340
2015-11-07 18:25:57.185 DEBUG tacker.vm.drivers.heat.heat [-] stack status: <Stack {u'disable_rollback': True, u'description': u'http-ping-cirros-example', u'parent': None, u'tags': None, u'stack_name': u'tacker.vm.drivers.heat.heat_DeviceHeat-dabf953e-75fe-4c39-b985-2d6705aea33d', u'stack_user_project_id': u'932ceedf0eb945ca8a72120deb2647a1', u'stack_status_reason': u'Resource CREATE failed: Conflict: resources.vdu1: Port eb822bda-0f6a-4c21-a0d9-9d5e7b4ef203 is still in use. (HTTP 409) (Request-ID: req-442f3957-5eb0-457a-957e-bcbaa7568cfa)', u'creation_time': u'2015-11-07T09:25:12', u'links': [{u'href': u'http://223.194.33.91:8004/v1/691f644cb70b46b485da6461dca7c608/stacks/tacker.vm.drivers.heat.heat_DeviceHeat-dabf953e-75fe-4c39-b985-2d6705aea33d/8d2b0835-72b9-48ed-a013-be052fbaf96d', u'rel': u'self'}], u'capabilities': [], u'notification_topics': [], u'parameters': {u'OS::project_id': u'691f644cb70b46b485da6461dca7c608', u'OS::stack_name': u'tacker.vm.drivers.heat.heat_DeviceHeat-dabf953e-75fe-4c39-b985-2d6705aea33d', u'OS::stack_id': u'8d2b0835-72b9-48ed-a013-be052fbaf96d'}, u'timeout_mins': 10, u'stack_status': u'CREATE_FAILED', u'stack_owner': u'tacker', u'updated_time': None, u'id': u'8d2b0835-72b9-48ed-a013-be052fbaf96d', u'outputs': [{u'output_value': u'192.168.120.3', u'description': u'management ip address', u'output_key': u'mgmt_ip-vdu1'}], u'template_description': u'http-ping-cirros-example'}> CREATE_FAILED from (pid=24574) create_wait /opt/stack/tacker/tacker/vm/drivers/heat/heat.py:344
2015-11-07 18:25:57.186 DEBUG tacker.db.vm.vm_db [-] device_dict {'status': 'ERROR', 'device_template': {'service_types': [{'service_type': u'vnfd', 'id': u'7df697b8-fe72-4a86-87fd-8370f6c9a3ea'}], 'description': u'http-ping-cirros-example', 'tenant_id': u'43607678eaed4bc6ac7e6bf8aa3211b1', 'mgmt_driver': u'noop', 'infra_driver': u'heat', 'attributes': {u'vnfd': u'template_name: http-ping\r\ndescription: http-ping-cirros-example\r\n\r\nservice_properties:\r\n Id: cirros-vnfd-http-moniotring\r\n vendor: canonical\r\n version: 1\r\n\r\nvdus:\r\n vdu1:\r\n id: vdu1\r\n vm_image: cirros-0.3.4-x86_64-uec\r\n instance_type: m1.small\r\n user_data_format: RAW\r\n user_data: |\r\n #!/bin/sh\r\n cd /tmp\r\n rm -f httpsrv.sh\r\n cat > httpsrv.sh << EOL\r\n eth0_ip=$(ifconfig eth0|grep \'inet addr\'|awk -F: \'{print $2}\'| awk \'{print $1}\')\r\n while true; do echo -e "HTTP/1.0 200 OK\\r\\n\\r\\nWelcome to $eth0_ip" | sudo nc -l -p 80 ; done&\r\n EOL\r\n sudo chmod +x httpsrv.sh\r\n sudo ./httpsrv.sh &\r\n sleep 180\r\n cat > kill << EOL\r\n cat <<\'EOF\' > kill\r\n pid = `ps -ef | grep httpsrv | grep exe | grep -v grep | awk \'{print $1}\'`\r\n echo "killing $pid"\r\n kill -9 $pid\r\n EOF\r\n sudo chmod +x kill\r\n\r\n network_interfaces:\r\n management:\r\n network: net_mgmt\r\n management: true\r\n\r\n placement_policy:\r\n availability_zone: nova\r\n\r\n monitoring_policy:\r\n http_ping:\r\n monitoring_params:\r\n monitoring_delay: 60\r\n retry: 5\r\n timeout: 10\r\n port: 80\r\n actions:\r\n failure: respawn\r\n\r\n config:\r\n param0: key0\r\n param1: key1'}, 'id': u'1af56555-12bc-426d-a4db-4049104ce25e', 'name': u'http-ping'}, 'name': '', 'tenant_id': u'43607678eaed4bc6ac7e6bf8aa3211b1', 'id': 'dabf953e-75fe-4c39-b985-2d6705aea33d', 'instance_id': u'8d2b0835-72b9-48ed-a013-be052fbaf96d', 'mgmt_url': None, 'service_context': [], 'services': [], 'attributes': {'monitoring_policy': '{"vdus": {"vdu1": {"http_ping": {"monitoring_params": {"monitoring_delay": 60, "retry": 5, "timeout": 10, "port": 80}, "actions": {"failure": "respawn"}}}}}', 'heat_template': 'description: http-ping-cirros-example\nheat_template_version: 2013-05-23\noutputs:\n mgmt_ip-vdu1:\n description: management ip address\n value:\n get_attr: [vdu1-net_mgmt-port, fixed_ips, 0, ip_address]\nresources:\n vdu1:\n properties:\n availability_zone: nova\n config_drive: true\n flavor: m1.small\n image: cirros-0.3.4-x86_64-uec\n metadata: {param0: key0, param1: key1}\n networks:\n - port: {get_resource: vdu1-net_mgmt-port}\n user_data: \'#!/bin/sh\n\n cd /tmp\n\n rm -f httpsrv.sh\n\n cat > httpsrv.sh << EOL\n\n eth0_ip=$(ifconfig eth0|grep \'\'inet addr\'\'|awk -F: \'\'{print $2}\'\'| awk \'\'{print\n $1}\'\')\n\n while true; do echo -e "HTTP/1.0 200 OK\\r\\n\\r\\nWelcome to $eth0_ip" | sudo\n nc -l -p 80 ; done&\n\n EOL\n\n sudo chmod +x httpsrv.sh\n\n sudo ./httpsrv.sh &\n\n sleep 180\n\n cat > kill << EOL\n\n cat <<\'\'EOF\'\' > kill\n\n pid = `ps -ef | grep httpsrv | grep exe | grep -v grep | awk \'\'{print $1}\'\'`\n\n echo "killing $pid"\n\n kill -9 $pid\n\n EOF\n\n sudo chmod +x kill\n\n \'\n user_data_format: RAW\n type: OS::Nova::Server\n vdu1-net_mgmt-port:\n properties:\n fixed_ips: []\n network: net_mgmt\n value_specs: {port_security_enabled: false}\n type: OS::Neutron::Port\n'}, 'template_id': u'1af56555-12bc-426d-a4db-4049104ce25e', 'description': u'http-ping-cirros-example'} from (pid=24574) _create_device_post /opt/stack/tacker/tacker/db/vm/vm_db.py:558
Traceback (most recent call last):
  File "/usr/local/lib/python2.7/dist-packages/eventlet/greenpool.py", line 82, in _spawn_n_impl
    func(*args, **kwargs)
  File "/opt/stack/tacker/tacker/vm/plugin.py", line 316, in create_device_wait
    self.add_device_to_monitor(device_dict)
  File "/opt/stack/tacker/tacker/vm/plugin.py", line 228, in add_device_to_monitor
    device_dict, action_cb)
  File "/opt/stack/tacker/tacker/vm/monitor.py", line 98, in to_hosting_vnf
    device_dict['mgmt_url']),
  File "/opt/stack/tacker/tacker/openstack/common/jsonutils.py", line 172, in loads
    return json.loads(strutils.safe_decode(s, encoding))
  File "/opt/stack/tacker/tacker/openstack/common/strutils.py", line 112, in safe_decode
    raise TypeError("%s can't be decoded" % type(text))
TypeError: <type 'NoneType'> can't be decoded
2015-11-07 18:26:19.497 INFO tacker.wsgi [-] (24574) accepted ('223.194.33.91', 55594)

2015-11-07 18:26:19.498 DEBUG keystonemiddleware.auth_token [-] Authenticating user token from (pid=24574) process_request /usr/local/lib/python2.7/dist-packages/keystonemiddleware/auth_token/__init__.py:478
2015-11-07 18:26:19.500 DEBUG keystoneclient.session [-] REQ: curl -g -i --cacert "/opt/stack/data/ca-bundle.pem" -X GET http://223.194.33.91:35357/v3/auth/tokens -H "X-Subject-Token: {SHA1}15c26d09b6892b8a3c99b5f057afc8796f1f6668" -H "User-Agent: python-keystoneclient" -H "Accept: application/json" -H "X-Auth-Token: {SHA1}4a65c7267240bfed0d21eff0b9b25fe41d964e6d" from (pid=24574) _http_log_request /usr/local/lib/python2.7/dist-packages/keystoneclient/session.py:198
2015-11-07 18:26:19.502 INFO requests.packages.urllib3.connectionpool [-] Resetting dropped connection: 223.194.33.91
2015-11-07 18:26:19.806 DEBUG requests.packages.urllib3.connectionpool [-] "GET /v3/auth/tokens HTTP/1.1" 200 7195 from (pid=24574) _make_request /usr/local/lib/python2.7/dist-packages/requests/packages/urllib3/connectionpool.py:387
2015-11-07 18:26:19.813 DEBUG keystoneclient.session [-] RESP: [200] Content-Length: 7195 X-Subject-Token: {SHA1}15c26d09b6892b8a3c99b5f057afc8796f1f6668 Vary: X-Auth-Token Keep-Alive: timeout=5, max=100 Server: Apache/2.4.7 (Ubuntu) Connection: Keep-Alive Date: Sat, 07 Nov 2015 09:26:19 GMT Content-Type: application/json x-openstack-request-id: req-0a88e3a0-2758-4660-946d-364b94f88a64
RESP BODY: {"token": {"methods": ["password", "token"], "roles": [{"id": "0c8c516b5fa147cbb692714dba37f07f", "name": "admin"}], "expires_at": "2015-11-07T10:26:19.000000Z", "project": {"domain": {"id": "default", "name": "Default"}, "id": "43607678eaed4bc6ac7e6bf8aa3211b1", "name": "admin"}, "catalog": "<removed>", "extras": {}, "user": {"domain": {"id": "default", "name": "Default"}, "id": "c428de748ff84c3f988bf5e026229b73", "name": "admin"}, "audit_ids": ["Kir8JKPVQ8O8r5YUre2Lgg"], "issued_at": "2015-11-07T09:26:19.447597"}}
 from (pid=24574) _http_log_response /usr/local/lib/python2.7/dist-packages/keystoneclient/session.py:216
2015-11-07 18:26:19.816 DEBUG keystonemiddleware.auth_token [-] Storing token in cache from (pid=24574) store /usr/local/lib/python2.7/dist-packages/keystonemiddleware/auth_token/_cache.py:140
2015-11-07 18:26:19.821 DEBUG keystonemiddleware.auth_token [-] Received request from user: user_id c428de748ff84c3f988bf5e026229b73, project_id 43607678eaed4bc6ac7e6bf8aa3211b1, roles admin from (pid=24574) process_request /usr/local/lib/python2.7/dist-packages/keystonemiddleware/auth_token/__init__.py:758
2015-11-07 18:26:20.019 DEBUG routes.middleware [-] Matched GET /vnfs.json from (pid=24574) __call__ /usr/local/lib/python2.7/dist-packages/routes/middleware.py:105
2015-11-07 18:26:20.023 DEBUG routes.middleware [-] Route path: '/vnfs.:(format)', defaults: {'action': u'index', 'controller': <wsgify at 140256361654864 wrapping <function resource at 0x7f8ffa9c0c08>>} from (pid=24574) __call__ /usr/local/lib/python2.7/dist-packages/routes/middleware.py:107
2015-11-07 18:26:20.024 DEBUG routes.middleware [-] Match dict: {'action': u'index', 'controller': <wsgify at 140256361654864 wrapping <function resource at 0x7f8ffa9c0c08>>, 'format': u'json'} from (pid=24574) __call__ /usr/local/lib/python2.7/dist-packages/routes/middleware.py:108
2015-11-07 18:26:20.062 DEBUG tacker.db.vm.vm_db [req-a0ac011f-bb7e-4c49-87a8-bbe5921498ae admin 43607678eaed4bc6ac7e6bf8aa3211b1] device_db <tacker.db.vm.vm_db.Device[object at 7f8ffa4aaa10] {tenant_id=u'43607678eaed4bc6ac7e6bf8aa3211b1', id=u'dabf953e-75fe-4c39-b985-2d6705aea33d', template_id=u'1af56555-12bc-426d-a4db-4049104ce25e', name=u'', description=u'http-ping-cirros-example', instance_id=u'8d2b0835-72b9-48ed-a013-be052fbaf96d', mgmt_url=None, status=u'ERROR'}> from (pid=24574) _make_device_dict /opt/stack/tacker/tacker/db/vm/vm_db.py:329
2015-11-07 18:26:20.079 DEBUG tacker.db.vm.vm_db [req-a0ac011f-bb7e-4c49-87a8-bbe5921498ae admin 43607678eaed4bc6ac7e6bf8aa3211b1] device_db attributes ([<tacker.db.vm.vm_db.DeviceAttribute[object at 7f8ffa4aab90] {id=u'33b73e06-9a59-4527-8dfd-d0af0e3bcfda', device_id=u'dabf953e-75fe-4c39-b985-2d6705aea33d', key=u'heat_template', value=u'description: http-ping-cirros-example\nheat_template_version: 2013-05-23\noutputs:\n mgmt_ip-vdu1:\n description: management ip address\n value:\n get_attr: [vdu1-net_mgmt-port, fixed_ips, 0, ip_address]\nresources:\n vdu1:\n properties:\n availability_zone: nova\n config_drive: true\n flavor: m1.small\n image: cirros-0.3.4-x86_64-uec\n metadata: {param0: key0, param1: key1}\n networks:\n - port: {get_resource: vdu1-net_mgmt-port}\n user_data: \'#!/bin/sh\n\n cd /tmp\n\n rm -f httpsrv.sh\n\n cat > httpsrv.sh << EOL\n\n eth0_ip=$(ifconfig eth0|grep \'\'inet addr\'\'|awk -F: \'\'{print $2}\'\'| awk \'\'{print\n $1}\'\')\n\n while true; do echo -e "HTTP/1.0 200 OK\\r\\n\\r\\nWelcome to $eth0_ip" | sudo\n nc -l -p 80 ; done&\n\n EOL\n\n sudo chmod +x httpsrv.sh\n\n sudo ./httpsrv.sh &\n\n sleep 180\n\n cat > kill << EOL\n\n cat <<\'\'EOF\'\' > kill\n\n pid = `ps -ef | grep httpsrv | grep exe | grep -v grep | awk \'\'{print $1}\'\'`\n\n echo "killing $pid"\n\n kill -9 $pid\n\n EOF\n\n sudo chmod +x kill\n\n \'\n user_data_format: RAW\n type: OS::Nova::Server\n vdu1-net_mgmt-port:\n properties:\n fixed_ips: []\n network: net_mgmt\n value_specs: {port_security_enabled: false}\n type: OS::Neutron::Port\n'}>, <tacker.db.vm.vm_db.DeviceAttribute[object at 7f8ffa4aadd0] {id=u'b0d69b98-fccf-4cfe-89f8-495ceecc9389', device_id=u'dabf953e-75fe-4c39-b985-2d6705aea33d', key=u'monitoring_policy', value=u'{"vdus": {"vdu1": {"http_ping": {"monitoring_params": {"monitoring_delay": 60, "retry": 5, "timeout": 10, "port": 80}, "actions": {"failure": "respawn"}}}}}'}>],) from (pid=24574) _make_device_dict /opt/stack/tacker/tacker/db/vm/vm_db.py:330
2015-11-07 18:26:20.139 INFO tacker.wsgi [req-a0ac011f-bb7e-4c49-87a8-bbe5921498ae admin 43607678eaed4bc6ac7e6bf8aa3211b1] 223.194.33.91 - - [07/Nov/2015 18:26:20] "GET /v1.0/vnfs.json HTTP/1.1" 200 2125 0.639895

2015-11-07 18:26:27.220 INFO tacker.wsgi [-] (24574) accepted ('223.194.33.91', 55597)

2015-11-07 18:26:27.221 DEBUG keystonemiddleware.auth_token [-] Authenticating user token from (pid=24574) process_request /usr/local/lib/python2.7/dist-packages/keystonemiddleware/auth_token/__init__.py:478
2015-11-07 18:26:27.223 DEBUG keystoneclient.session [-] REQ: curl -g -i --cacert "/opt/stack/data/ca-bundle.pem" -X GET http://223.194.33.91:35357/v3/auth/tokens -H "X-Subject-Token: {SHA1}09e1448cdecc15c78f338d89822e33251515b09f" -H "User-Agent: python-keystoneclient" -H "Accept: application/json" -H "X-Auth-Token: {SHA1}4a65c7267240bfed0d21eff0b9b25fe41d964e6d" from (pid=24574) _http_log_request /usr/local/lib/python2.7/dist-packages/keystoneclient/session.py:198
2015-11-07 18:26:27.225 INFO requests.packages.urllib3.connectionpool [-] Resetting dropped connection: 223.194.33.91
2015-11-07 18:26:27.406 DEBUG requests.packages.urllib3.connectionpool [-] "GET /v3/auth/tokens HTTP/1.1" 200 7195 from (pid=24574) _make_request /usr/local/lib/python2.7/dist-packages/requests/packages/urllib3/connectionpool.py:387
2015-11-07 18:26:27.409 DEBUG keystoneclient.session [-] RESP: [200] Content-Length: 7195 X-Subject-Token: {SHA1}09e1448cdecc15c78f338d89822e33251515b09f Vary: X-Auth-Token Keep-Alive: timeout=5, max=100 Server: Apache/2.4.7 (Ubuntu) Connection: Keep-Alive Date: Sat, 07 Nov 2015 09:26:27 GMT Content-Type: application/json x-openstack-request-id: req-825202e5-29c9-4c5d-a1c4-2ff77ef6e676
RESP BODY: {"token": {"methods": ["password", "token"], "roles": [{"id": "0c8c516b5fa147cbb692714dba37f07f", "name": "admin"}], "expires_at": "2015-11-07T10:26:27.000000Z", "project": {"domain": {"id": "default", "name": "Default"}, "id": "43607678eaed4bc6ac7e6bf8aa3211b1", "name": "admin"}, "catalog": "<removed>", "extras": {}, "user": {"domain": {"id": "default", "name": "Default"}, "id": "c428de748ff84c3f988bf5e026229b73", "name": "admin"}, "audit_ids": ["c4ieFja8Q2ekdL1Ju6-SdA"], "issued_at": "2015-11-07T09:26:27.198583"}}
 from (pid=24574) _http_log_response /usr/local/lib/python2.7/dist-packages/keystoneclient/session.py:216
2015-11-07 18:26:27.411 DEBUG keystonemiddleware.auth_token [-] Storing token in cache from (pid=24574) store /usr/local/lib/python2.7/dist-packages/keystonemiddleware/auth_token/_cache.py:140
2015-11-07 18:26:27.413 DEBUG keystonemiddleware.auth_token [-] Received request from user: user_id c428de748ff84c3f988bf5e026229b73, project_id 43607678eaed4bc6ac7e6bf8aa3211b1, roles admin from (pid=24574) process_request /usr/local/lib/python2.7/dist-packages/keystonemiddleware/auth_token/__init__.py:758
2015-11-07 18:26:27.414 DEBUG routes.middleware [-] Matched GET /vnfs.json from (pid=24574) __call__ /usr/local/lib/python2.7/dist-packages/routes/middleware.py:105
2015-11-07 18:26:27.415 DEBUG routes.middleware [-] Route path: '/vnfs.:(format)', defaults: {'action': u'index', 'controller': <wsgify at 140256361654864 wrapping <function resource at 0x7f8ffa9c0c08>>} from (pid=24574) __call__ /usr/local/lib/python2.7/dist-packages/routes/middleware.py:107
2015-11-07 18:26:27.415 DEBUG routes.middleware [-] Match dict: {'action': u'index', 'controller': <wsgify at 140256361654864 wrapping <function resource at 0x7f8ffa9c0c08>>, 'format': u'json'} from (pid=24574) __call__ /usr/local/lib/python2.7/dist-packages/routes/middleware.py:108
2015-11-07 18:26:27.428 DEBUG tacker.db.vm.vm_db [req-67c368fb-86ff-450a-96fa-b6d38c54a9e2 admin 43607678eaed4bc6ac7e6bf8aa3211b1] device_db <tacker.db.vm.vm_db.Device[object at 7f8ffa4b1250] {tenant_id=u'43607678eaed4bc6ac7e6bf8aa3211b1', id=u'dabf953e-75fe-4c39-b985-2d6705aea33d', template_id=u'1af56555-12bc-426d-a4db-4049104ce25e', name=u'', description=u'http-ping-cirros-example', instance_id=u'8d2b0835-72b9-48ed-a013-be052fbaf96d', mgmt_url=None, status=u'ERROR'}> from (pid=24574) _make_device_dict /opt/stack/tacker/tacker/db/vm/vm_db.py:329
2015-11-07 18:26:27.443 DEBUG tacker.db.vm.vm_db [req-67c368fb-86ff-450a-96fa-b6d38c54a9e2 admin 43607678eaed4bc6ac7e6bf8aa3211b1] device_db attributes ([<tacker.db.vm.vm_db.DeviceAttribute[object at 7f8ffa4b1450] {id=u'33b73e06-9a59-4527-8dfd-d0af0e3bcfda', device_id=u'dabf953e-75fe-4c39-b985-2d6705aea33d', key=u'heat_template', value=u'description: http-ping-cirros-example\nheat_template_version: 2013-05-23\noutputs:\n mgmt_ip-vdu1:\n description: management ip address\n value:\n get_attr: [vdu1-net_mgmt-port, fixed_ips, 0, ip_address]\nresources:\n vdu1:\n properties:\n availability_zone: nova\n config_drive: true\n flavor: m1.small\n image: cirros-0.3.4-x86_64-uec\n metadata: {param0: key0, param1: key1}\n networks:\n - port: {get_resource: vdu1-net_mgmt-port}\n user_data: \'#!/bin/sh\n\n cd /tmp\n\n rm -f httpsrv.sh\n\n cat > httpsrv.sh << EOL\n\n eth0_ip=$(ifconfig eth0|grep \'\'inet addr\'\'|awk -F: \'\'{print $2}\'\'| awk \'\'{print\n $1}\'\')\n\n while true; do echo -e "HTTP/1.0 200 OK\\r\\n\\r\\nWelcome to $eth0_ip" | sudo\n nc -l -p 80 ; done&\n\n EOL\n\n sudo chmod +x httpsrv.sh\n\n sudo ./httpsrv.sh &\n\n sleep 180\n\n cat > kill << EOL\n\n cat <<\'\'EOF\'\' > kill\n\n pid = `ps -ef | grep httpsrv | grep exe | grep -v grep | awk \'\'{print $1}\'\'`\n\n echo "killing $pid"\n\n kill -9 $pid\n\n EOF\n\n sudo chmod +x kill\n\n \'\n user_data_format: RAW\n type: OS::Nova::Server\n vdu1-net_mgmt-port:\n properties:\n fixed_ips: []\n network: net_mgmt\n value_specs: {port_security_enabled: false}\n type: OS::Neutron::Port\n'}>, <tacker.db.vm.vm_db.DeviceAttribute[object at 7f8ffa4b1710] {id=u'b0d69b98-fccf-4cfe-89f8-495ceecc9389', device_id=u'dabf953e-75fe-4c39-b985-2d6705aea33d', key=u'monitoring_policy', value=u'{"vdus": {"vdu1": {"http_ping": {"monitoring_params": {"monitoring_delay": 60, "retry": 5, "timeout": 10, "port": 80}, "actions": {"failure": "respawn"}}}}}'}>],) from (pid=24574) _make_device_dict /opt/stack/tacker/tacker/db/vm/vm_db.py:330
2015-11-07 18:26:27.479 INFO tacker.wsgi [req-67c368fb-86ff-450a-96fa-b6d38c54a9e2 admin 43607678eaed4bc6ac7e6bf8aa3211b1] 223.194.33.91 - - [07/Nov/2015 18:26:27] "GET /v1.0/vnfs.json HTTP/1.1" 200 2125 0.257500


Revision history for this message
Nguyen Dinh Hai (nguyendinhhai11) said :
#4

Dear Tacker Team,

Anyone can help us on this issue ?
this issue happen with 100% reproducible percentage on my system with error like below even when I am using default sample-vnfd from /devstack/samples folder. Thanks you so much. Appreciate if someone could take a look on this issue and let us know if any

u'stack_status_reason': u'Resource CREATE failed: Conflict: resources.vdu1: Port eb822bda-0f6a-4c21-a0d9-9d5e7b4ef203 is still in use. (HTTP 409) (Request-ID: req-442f3957-5eb0-457a-957e-bcbaa7568cfa)', u'creation_time': u'2015-11-07T09:25:12', u'links': [{u'href': u'http://223.194.33.91:8004/v1/691f644cb70b46b485da6461dca7c608/stacks/tacker.vm.drivers.heat.heat_DeviceHeat-dabf953e-75fe-4c39-b985-2d6705aea33d/8d2b0835-72b9-48ed-a013-be052fbaf96d', u'rel': u'self'}],

Thanks
Hai

Revision history for this message
Sripriya (sseetha) said :
#5

Hello,

This bug is related to neutron and nova projects. I have created a bug in the project https://bugs.launchpad.net/nova/+bug/1515768 and will be following up with neutron and nova teams. Will post an update here once the bug is resolved from the neutron/nova teams.

Cheers,
Sripriya

Revision history for this message
Rick Chen (rick-chen) said :
#6

Hello,
I got same issue at openstack CI environment. Does any one can provide solution?

Can you help with this problem?

Provide an answer of your own, or ask Shuqiang Zhang for more information if necessary.

To post a message you must log in.