Public bug reported: Cisco Nexus plugin will issue netconf call delete_vlan to switch when the last instance on a tenant network is deleted. In my case I delete 8 instances in batch, the last instance always failed to be deleted. From /var/log/neutron/server.log, Cisco Nexus plugin thrown exception "None of the VLANs exist". After study the same log, I found the delete_vlan netconf call has been issued 2 times. I expect only 1st call is issue, which delete the VLAN on switch. The 2nd call causes "None of the VLANs exist".
2014-06-27 00:32:55.798 20970 INFO ncclient.operations.rpc [-] Requesting 'EditConfig' 2014-06-27 00:32:55.798 20970 DEBUG ncclient.transport.session [-] queueing <?xml version='1.0' encoding='UTF-8'?> <rpc message-id="urn:uuid:86dae2e8-fd4f-11e3-b8a3-080027381b81" xmlns="urn:ietf:params:xml:ns:netconf:base:1.0" xmlns:if="http://www.cisco.com/nxos:1.0:if_manager"; xmlns:nxos="http://www.cisco.com/nxos:1.0";><edit-config><target><running /></target><config> <configure> <__XML__MODE__exec_configure> <no> <vlan> <vlan-id-create-delete> <__XML__PARAM_value>2000</__XML__PARAM_value> </vlan-id-create-delete> </vlan> </no> </__XML__MODE__exec_configure> </configure> </config></edit-config></rpc> send /usr/local/lib/python2.7/dist-packages/ncclient/transport/session.py:137 2014-06-27 00:32:55.799 20970 DEBUG ncclient.operations.rpc [-] Sync request, will wait for timeout=30 _request /usr/local/lib/python2.7/dist-packages/ncclient/operations/rpc.py:284 2014-06-27 00:32:55.799 20970 INFO ncclient.operations.rpc [-] Requesting 'EditConfig' 2014-06-27 00:32:55.799 20970 DEBUG ncclient.transport.session [-] queueing <?xml version='1.0' encoding='UTF-8'?> <rpc message-id="urn:uuid:86db0ff2-fd4f-11e3-b8a3-080027381b81" xmlns="urn:ietf:params:xml:ns:netconf:base:1.0" xmlns:if="http://www.cisco.com/nxos:1.0:if_manager"; xmlns:nxos="http://www.cisco.com/nxos:1.0";><edit-config><target><running /></target><config> <configure> <__XML__MODE__exec_configure> <no> <vlan> <vlan-id-create-delete> <__XML__PARAM_value>2000</__XML__PARAM_value> </vlan-id-create-delete> </vlan> </no> </__XML__MODE__exec_configure> </configure> </config></edit-config></rpc> send /usr/local/lib/python2.7/dist-packages/ncclient/transport/session.py:137 2014-06-27 00:32:55.800 20970 DEBUG ncclient.operations.rpc [-] Sync request, will wait for timeout=30 _request /usr/local/lib/python2.7/dist-packages/ncclient/operations/rpc.py:284 2014-06-27 00:32:55.810 20970 DEBUG neutron.api.v2.base [-] Policy rule:get_router:external_gateway_info not found. Assuming no authZ check is defined for external_gateway_info _is_visible /usr/lib/python2.7/dist-packages/neutron/api/v2/base.py:143 2014-06-27 00:32:55.864 20970 DEBUG amqp [-] Channel open _open_ok /usr/lib/python2.7/dist-packages/amqp/channel.py:420 2014-06-27 00:32:55.914 20970 DEBUG amqp [-] Closed channel #1 _do_close /usr/lib/python2.7/dist-packages/amqp/channel.py:88 2014-06-27 00:32:55.914 20970 DEBUG amqp [-] using channel_id: 1 __init__ /usr/lib/python2.7/dist-packages/amqp/channel.py:70 2014-06-27 00:32:55.914 20970 DEBUG neutron.openstack.common.rpc.amqp [-] received {u'_context_roles': [u'admin'], u'_msg_id': u'aae227ba330c4d6fae548767903c8215', u'_context_read_deleted': u'no', u'_reply_q': u'reply_b822086377ac4271926517c0d3a4ba2f', u'_context_tenant_id': None, u'args': {u'network_id': u'5805ae6f-1d36-4463-b95e-e4f231306114', u'host': u'openstack', u'device_id': u'dhcpacb6d6f0-2d56-5c7a-9905-4c3a3d168f49-5805ae6f-1d36-4463-b95e-e4f231306114'}, u'namespace': None, u'_unique_id': u'765269a423f84fd0819a8d54951c4979', u'_context_is_admin': True, u'version': u'1.1', u'_context_project_id': None, u'_context_timestamp': u'2014-06-24 10:40:44.712663', u'_context_user_id': None, u'method': u'get_dhcp_port'} _safe_log /usr/lib/python2.7/dist-packages/neutron/openstack/common/rpc/common.py:2762014-06-27 00:32:55.915 20970 DEBUG neutron.openstack.common.rpc.amqp [-] unpacked context: {'user_id': None, 'roles': [u'admin'], 'tenant_id': None, 'is_admin': True, 'timestamp': u'20 14-06-24 10:40:44.712663', 'project_id': None, 'read_deleted': u'no'} _safe_log /usr/lib/python2.7/dist-packages/neutron/openstack/common/rpc/common.py:2762014-06-27 00:32:55.996 20970 DEBUG neutron.db.dhcp_rpc_base [-] Port dhcpacb6d6f0-2d56-5c7a-9905-4c3a3d168f49-5805ae6f-1d36-4463-b95e-e4f231306114 for 5805ae6f-1d36-4463-b95e-e4f231306114 requested from openstack get_dhcp_port /usr/lib/python2.7/dist-packages/neutron/db/dhcp_rpc_base.py:1132014-06-27 00:32:56.048 20970 DEBUG amqp [-] Channel open _open_ok /usr/lib/python2.7/dist-packages/amqp/channel.py:420 2014-06-27 00:32:56.049 20970 DEBUG neutron.openstack.common.rpc.amqp [-] UNIQUE_ID is 531557ef76f04a7f8f0b5b66a063c9b2. _add_unique_id /usr/lib/python2.7/dist-packages/neutron/openstack/common/rpc/amqp.py:339 2014-06-27 00:32:56.062 20970 DEBUG ncclient.transport.ssh [-] Sending message run /usr/local/lib/python2.7/dist-packages/ncclient/transport/ssh.py:299 2014-06-27 00:32:56.149 20970 DEBUG amqp [-] Closed channel #1 _do_close /usr/lib/python2.7/dist-packages/amqp/channel.py:882014-06-27 00:32:56.149 20970 DEBUG amqp [-] using channel_id: 1 __init__ /usr/lib/python2.7/dist-packages/amqp/channel.py:70 2014-06-27 00:32:56.247 20970 DEBUG amqp [-] Channel open _open_ok /usr/lib/python2.7/dist-packages/amqp/channel.py:420 2014-06-27 00:32:56.260 20970 DEBUG neutron.plugins.cisco.models.virt_phy_sw_v2 [-] update_port() called update_port /usr/lib/python2.7/dist-packages/neutron/plugins/cisco/models/virt_phy_sw_v2.py:345 2014-06-27 00:32:56.351 20970 DEBUG keystoneclient.middleware.auth_token [-] Authenticating user token __call__ /usr/lib/python2.7/dist-packages/keystoneclient/middleware/auth_token.py:5262014-06-27 00:32:56.351 20970 DEBUG keystoneclient.middleware.auth_token [-] Removing headers from request environment: X-Identity-Status,X-Domain-Id,X-Domain-Name,X-Project-Id,X-Project-Name,X-Project-Domain-Id,X-Project-Domain-Name,X-User-Id,X-User-Name,X-User-Domain-Id,X-User-Domain-Name,X-Roles,X-Service-Catalog,X-User,X-Tenant-Id,X-Tenant-Name,X-Tenant,X-Role _remove_auth_headers /usr/lib/python2.7/dist-packages/keystoneclient/middleware/auth_token.py:5852014-06-27 00:32:56.351 20970 DEBUG keystoneclient.middleware.auth_token [-] Returning cached token cfbfdf0da25a2f4ebbe9eb7372326d8c _cache_get /usr/lib/python2.7/dist-packages/keystoneclient/middleware/auth_token.py:9762014-06-27 00:32:56.352 20970 DEBUG routes.middleware [-] No route matched for GET /networks.json __call__ /usr/lib/python2.7/ dist-packages/routes/middleware.py:97 2014-06-27 00:32:56.352 20970 DEBUG routes.middleware [-] Matched GET /networks.json __call__ /usr/lib/python2.7/dist-packages/routes/middleware.py:1002014-06-27 00:32:56.352 20970 DEBUG routes.middleware [-] Route path: '/networks{.format}', defaults: {'action': u'index', 'controller': <wsgify at 71235920 wrapping <function resource at 0x43edb18>>} __call__ /usr/lib/python2.7/dist-packages/routes/middleware.py:102 2014-06-27 00:32:56.353 20970 DEBUG routes.middleware [-] Match dict: {'action': u'index', 'controller': <wsgify at 71235920 wrapping <function resource at 0x43edb18>>, 'format': u'json'} __call__ /usr/lib/python2.7/dist-packages/routes/middleware.py:1032014-06-27 00:32:56.366 20970 DEBUG neutron.openstack.common.rpc.amqp [-] received {u'_context_roles': [u'admin'], u'_context_read_deleted': u'no', u'_context_tenant_id': None, u'args': {u'agent_state': {u'agent_state': {u'topic': u'l3_agent', u'binary': u'neutron-l3-agent', u'host': u'openstack', u'agent_type': u'L3 agent', u'configurations': {u'router_id': u'', u'gateway_external_network_id': u'', u'handle_internal_only_routers': True, u'use_namespaces': True, u'routers': 1, u'interfaces': 1, u'floating_ips': 0, u'interface_driver': u'neutron.agent.linux.interface.OVSInterfaceDriver', u'ex_gw_ports': 0}}}, u'time': u'2014-06-26T16:32:56.278811'}, u'namespace': None, u'_unique_id': u'7989b8df817747a49a8128d3f0100573', u'_context_is_a dmin': True, u'version': u'1.0', u'_context_project_id': None, u'_context_timestamp': u'2014-06-24 10:40:45.067965', u'_context_user_id': None, u'method': u'report_state'} _safe_log /usr/lib/python2.7/dist-packages/neutron/openstack/common/rpc/common.py:2762014-06-27 00:32:56.367 20970 DEBUG neutron.openstack.common.rpc.amqp [-] unpacked context: {'user_id': None, 'roles': [u'admin'], 'tenant_id': None, 'is_admin': True, 'timestamp': u'2014-06-24 10:40:45.067965', 'project_id': None, 'read_deleted': u'no'} _safe_log /usr/lib/python2.7/dist-packages/neutron/openstack/common/rpc/common.py:276 2014-06-27 00:32:56.377 20970 DEBUG ncclient.transport.ssh [-] Sending message run /usr/local/lib/python2.7/dist-packages/ncclient/transport/ssh.py:299 2014-06-27 00:32:56.578 20970 DEBUG ncclient.transport.ssh [-] parsed new message _parse /usr/local/lib/python2.7/dist-packages/ncclient/transport/ssh.py:97 2014-06-27 00:32:56.578 20970 DEBUG ncclient.transport.session [-] dispatching message to <ncclient.operations.rpc.RPCReplyListener object at 0x493c110>: <?xml version="1.0" encoding="ISO-8859-1"?> <rpc-reply xmlns="urn:ietf:params:xml:ns:netconf:base:1.0" xmlns:if="http://www.cisco.com/nxos:1.0:if_manager"; xmlns:nxos="http://www.cisco.com/nxos:1.0"; message-id="urn:uuid:86dae2e8-fd4f-11e3-b8a3-080027381b81"> <ok/> </rpc-reply> _dispatch_message /usr/local/lib/python2.7/dist-packages/ncclient/transport/session.py:53 2014-06-27 00:32:56.578 20970 DEBUG ncclient.operations.rpc [-] Delivering to <ncclient.operations.edit.EditConfig object at 0x62d01d0> callback /usr/local/lib/python2.7/dist-packages/ncclient/operations/rpc.py:189 2014-06-27 00:32:56.579 20970 DEBUG neutron.api.v2.base [-] Policy rule:get_subnet:shared not found. Assuming no authZ check is defined for shared _is_visible /usr/lib/python2.7/dist-packages/neutron/api/v2/base.py:143 2014-06-27 00:32:56.580 20970 DEBUG neutron.api.v2.base [-] Policy rule:get_subnet:shared not found. Assuming no authZ check is defined for shared _is_visible /usr/lib/python2.7/dist-packages/neutron/api/v2/base.py:143 2014-06-27 00:32:56.580 20970 DEBUG neutron.api.v2.base [-] Policy rule:get_subnet:shared not found. Assuming no authZ check is defined for shared _is_visible /usr/lib/python2.7/dist-packages/neutron/api/v2/base.py:143 2014-06-27 00:32:56.581 20970 DEBUG neutron.api.v2.base [-] Policy rule:get_subnet:shared not found. Assuming no authZ check is defined for shared _is_visible /usr/lib/python2.7/dist-packages/neutron/api/v2/base.py:143 2014-06-27 00:32:56.682 20970 DEBUG keystoneclient.middleware.auth_token [-] Authenticating user token __call__ /usr/lib/python2.7/dist-packages/keystoneclient/middleware/auth_token.py:5262014-06-27 00:32:56.682 20970 DEBUG keystoneclient.middleware.auth_token [-] Removing headers from request environment: X-Identity-Status,X-Domain-Id,X-Domain-Name,X-Project-Id,X-Project-Name,X-Project-Domain-Id,X-Project-Domain-Name,X-User-Id,X-User-Name,X-User-Domain-Id,X-User-Domain-Name,X-Roles,X-Service-Catalog,X-User,X-Tenant-Id,X-Tenant-Name,X-Tenant,X-Role _remove_auth_headers /usr/lib/python2.7/dist-packages/keystoneclient/middleware/auth_token.py:585 2014-06-27 00:32:56.684 20970 DEBUG keystoneclient.middleware.auth_token [-] Returning cached token cfbfdf0da25a2f4ebbe9eb7372326d8c _cache_get /usr/lib/python2.7/dist-packages/keystoneclient/middleware/auth_token.py:976 2014-06-27 00:32:56.685 20970 DEBUG routes.middleware [-] No route matched for GET /subnets.json __call__ /usr/lib/python2.7/dist-packages/routes/middleware.py:97 2014-06-27 00:32:56.685 20970 DEBUG routes.middleware [-] Matched GET /subnets.json __call__ /usr/lib/python2.7/dist-packages/routes/middleware.py:100 2014-06-27 00:32:56.685 20970 DEBUG routes.middleware [-] Route path: '/subnets{.format}', defaults: {'action': u'index', 'controller': <wsgify at 69838800 wrapping <function resource at 0x43eda28>>} __call__ /usr/lib/python2.7/dist-packages/routes/middleware.py:102 2014-06-27 00:32:56.685 20970 DEBUG routes.middleware [-] Match dict: {'action': u'index', 'controller': <wsgify at 69838800 wrapping <function resource at 0x43eda28>>, 'format': u'json'} __call__ /usr/lib/python2.7/dist-packages/routes/middleware.py:103 2014-06-27 00:32:56.733 20970 DEBUG ncclient.transport.ssh [-] parsed new message _parse /usr/local/lib/python2.7/dist-packages/ncclient/transport/ssh.py:97 2014-06-27 00:32:56.733 20970 DEBUG ncclient.transport.session [-] dispatching message to <ncclient.operations.rpc.RPCReplyListener object at 0x493c110>: <?xml version="1.0" encoding="ISO-8859-1"?> <rpc-reply xmlns="urn:ietf:params:xml:ns:netconf:base:1.0" xmlns:if="http://www.cisco.com/nxos:1.0:if_manager"; xmlns:nxos="http://www.cisco.com/nxos:1.0"; message-id="urn:uuid:86db0ff2-fd4f-11e3-b8a3-080027381b81"> <rpc-error> <error-type>application</error-type> <error-tag>invalid-value</error-tag> <error-severity>error</error-severity> <error-message>ERROR: None of the VLANs exist </error-message> </rpc-error> </rpc-reply> _dispatch_message /usr/local/lib/python2.7/dist-packages/ncclient/transport/session.py:53 2014-06-27 00:32:56.734 20970 DEBUG ncclient.operations.rpc [-] Delivering to <ncclient.operations.edit.EditConfig object at 0x62d0810> callback /usr/local/lib/python2.7/dist-packages/ncclient/operations/rpc.py:1892014-06-27 00:32:56.735 20970 DEBUG neutron.openstack.common.rpc.amqp [-] received {u'_context_roles': [u'admin'], u'_context_read_deleted': u'no', u'_context_tenant_id': None, u'args': {u'agent_state': {u'agent_state': {u'topic': u'N/A', u'binary': u'neutron-openvswitch-agent', u'host': u'openstack', u'agent_type': u'Open vSwitch agent', u'configurations': {u'tunnel_types': [], u'tunneling_ip': u'', u'bridge_mappings': {u'mgmtnet': u'br-ex', u'datanet': u'br-eth4'}, u'l2_population': False, u'devices': 7}}}, u'time': u'2014-06-26T16:32:56.640450'}, u'namespace': None, u'_unique_id': u'0faaf4faee8a4528af4bfd95092583a9', u'_context_is_admin': True, u'version': u'1.0', u'_context_project_id': None, u'_context_timestamp': u'2014-06-24 10:40:44.865491', u'_context_user_id': Non e, u'method': u'report_state'} _safe_log /usr/lib/python2.7/dist-packages/neutron/openstack/common/rpc/common.py:2762014-06-27 00:32:56.735 20970 DEBUG neutron.openstack.common.rpc.amqp [-] unpacked context: {'user_id': None, 'roles': [u'admin'], 'tenant_id': None, 'is_admin': True, 'timestamp': u'2014-06-24 10:40:44.865491', 'project_id': None, 'read_deleted': u'no'} _safe_log /usr/lib/python2.7/dist-packages/neutron/openstack/common/rpc/common.py:2762014-06-27 00:32:56.979 20970 DEBUG neutron.db.db_base_plugin_v2 [-] Recycle 192.168.100.7 _recycle_ip /usr/lib/python2.7/dist-packages/neutron/db/db_base_plugin_v2.py:366 2014-06-27 00:32:56.982 20970 DEBUG neutron.db.db_base_plugin_v2 [-] Recycle: first match for 192.168.100.8-192.168.100.254 _recycle_ip /usr/lib/python2.7/dist-packages/neutron/db/db_base_plugin_v2.py:3712014-06-27 00:32:56.987 20970 DEBUG neutron.db.db_base_plugin_v2 [-] Recycle: last match for 192.168.100.6-192.168.100.6 _recycle_ip /usr/lib/python2.7/dist-packages/neutron/db/db_base_plugin_v2.py:378 2014-06-27 00:32:56.987 20970 DEBUG neutron.db.db_base_plugin_v2 [-] Recycle: merged 192.168.100.6-192.168.100.6 and 192.168.100.8-192.168.100.254 _recycle_ip /usr/lib/python2.7/dist-packages/neutron/db/db_base_plugin_v2.py:392 2014-06-27 00:32:56.987 20970 DEBUG neutron.db.db_base_plugin_v2 [-] Delete allocated IP 192.168.100.7 (5805ae6f-1d36-4463-b95e-e4f231306114/44435cdd-b3d5-4dc0-908a-3754d6eaae7c) _delete_ip_allocation /usr/lib/python2.7/dist-packages/neutron/db/db_base_plugin_v2.py:444 2014-06-27 00:32:57.032 20970 DEBUG neutron.openstack.common.rpc.amqp [-] Making asynchronous fanout cast... fanout_cast /usr/lib/python2.7/dist-packages/neutron/openstack/common/rpc/amqp.py:568 2014-06-27 00:32:57.032 20970 DEBUG neutron.openstack.common.rpc.amqp [-] UNIQUE_ID is 5b29f03cd9be4b3fb593bdde20ece1e9. _add_unique_id /usr/lib/python2.7/dist-packages/neutron/openstack/common/rpc/amqp.py:339 2014-06-27 00:32:57.046 20970 DEBUG neutron.plugins.cisco.db.nexus_db_v2 [-] add_nexusport_binding() called add_nexusport_binding /usr/lib/python2.7/dist-packages/neutron/plugins/cisco/db/nexus_db_v2.py:49 2014-06-27 00:32:57.070 20970 DEBUG keystoneclient.middleware.auth_token [-] Authenticating user token __call__ /usr/lib/python2.7/dist-packages/keystoneclient/middleware/auth_token.py:5262014-06-27 00:32:57.071 20970 DEBUG keystoneclient.middleware.auth_token [-] Removing headers from request environment: X-Identity-Status,X-Domain-Id,X-Domain-Name,X-Project-Id,X-Project-Name,X-Project-Domain-Id,X-Project-Domain-Name,X-User-Id,X-User-Name,X-User-Domain-Id,X-User-Domain-Name,X-Roles,X-Service-Catalog,X-User,X-Tenant-Id,X-Tenant-Name,X-Tenant,X-Role _remove_auth_headers /usr/lib/python2.7/dist-packages/keystoneclient/middleware/auth_token.py:585 2014-06-27 00:32:57.071 20970 DEBUG keystoneclient.middleware.auth_token [-] Returning cached token cfbfdf0da25a2f4ebbe9eb7372326d8c _cache_get /usr/lib/python2.7/dist-packages/keystoneclient/middleware/auth_token.py:9762014-06-27 00:32:57.072 20970 DEBUG routes.middleware [-] No route matched for GET /ports.json __call__ /usr/lib/python2.7/dist-packages/routes/middleware.py:97 2014-06-27 00:32:57.072 20970 DEBUG routes.middleware [-] Matched GET /ports.json __call__ /usr/lib/python2.7/dist-packages/routes/middleware.py:100 2014-06-27 00:32:57.072 20970 DEBUG routes.middleware [-] Route path: '/ports{.format}', defaults: {'action': u'index', 'controller': <wsgify at 71237136 wrapping <function resource at 0x43edb90>>} __call__ /usr/lib/python2.7/dist-packages/routes/middleware.py:102 2014-06-27 00:32:57.072 20970 DEBUG routes.middleware [-] Match dict: {'action': u'index', 'controller': <wsgify at 71237136 wrapping <function resource at 0x43edb90>>, 'format': u'json'} __call__ /usr/lib/python2.7/dist-packages/routes/middleware.py:1032014-06-27 00:32:57.173 20970 DEBUG neutron.openstack.common.rpc.amqp [-] received {u'_context_roles': [u'admin'], u'_context_read_deleted': u'no', u'_context_tenant_id': None, u'args': {u'agent_state': {u'agent_state': {u'topic': u'N/A', u'binary': u'neutron-openvswitch-agent', u'host': u'WorkerNode8', u'agent_type': u'Open vSwitch agent', u'configurations': {u'tunnel_types': [], u'tunneling_ip': u'', u'bridge_mappings': {u'mgmtnet': u'br-eth0', u'datanet': u'br-eth4'}, u'l2_population': False, u'devices': 4}}}, u'time': u'2014-06-26T16:32:56.942233'}, u'namespace': None, u'_unique_id': u'd6819e3f92884efcaef9fe00f63467a1', u'_context_is_admin': True, u'version': u'1.0', u'_context_project_id': None, u'_context_timestamp': u'2014-06-2 4 09:01:14.058689', u'_context_user_id': None, u'method': u'report_state'} _safe_log /usr/lib/python2.7/dist-packages/neutron/openstack/common/rpc/common.py:2762014-06-27 00:32:57.174 20970 DEBUG neutron.openstack.common.rpc.amqp [-] unpacked context: {'user_id': None, 'roles': [u'admin'], 'tenant_id': None, 'is_admin': True, 'timestamp': u'2014-06-24 09:01:14.058689', 'project_id': None, 'read_deleted': u'no'} _safe_log /usr/lib/python2.7/dist-packages/neutron/openstack/common/rpc/common.py:276 2014-06-27 00:32:57.176 20970 DEBUG neutron.openstack.common.rpc.amqp [-] Making asynchronous fanout cast... fanout_cast /usr/lib/python2.7/dist-packages/neutron/openstack/common/rpc/amqp.py:5682014-06-27 00:32:57.176 20970 DEBUG neutron.openstack.common.rpc.amqp [-] UNIQUE_ID is 312e0c282df94972b4b4c05c2fc02be6. _add_unique_id /usr/lib/python2.7/dist-packages/neutron/openstack/common/rpc/amqp.py:339 2014-06-27 00:32:57.177 20970 ERROR neutron.api.v2.resource [-] delete failed 2014-06-27 00:32:57.177 20970 TRACE neutron.api.v2.resource Traceback (most recent call last): 2014-06-27 00:32:57.177 20970 TRACE neutron.api.v2.resource File "/usr/lib/python2.7/dist-packages/neutron/api/v2/resource.py", line 84, in resource 2014-06-27 00:32:57.177 20970 TRACE neutron.api.v2.resource result = method(request=request, **args) 2014-06-27 00:32:57.177 20970 TRACE neutron.api.v2.resource File "/usr/lib/python2.7/dist-packages/neutron/api/v2/base.py", line 432, in delete 2014-06-27 00:32:57.177 20970 TRACE neutron.api.v2.resource obj_deleter(request.context, id, **kwargs) 2014-06-27 00:32:57.177 20970 TRACE neutron.api.v2.resource File "/usr/lib/python2.7/dist-packages/neutron/plugins/cisco/models/virt_phy_sw_v2.py", line 399, in delete_port 2014-06-27 00:32:57.177 20970 TRACE neutron.api.v2.resource n_args) 2014-06-27 00:32:57.177 20970 TRACE neutron.api.v2.resource File "/usr/lib/python2.7/dist-packages/neutron/plugins/cisco/models/virt_phy_sw_v2.py", line 145, in _invoke_plugin_per_device 2014-06-27 00:32:57.177 20970 TRACE neutron.api.v2.resource return [self._invoke_plugin(plugin_key, function_name, args, kwargs)] 2014-06-27 00:32:57.177 20970 TRACE neutron.api.v2.resource File "/usr/lib/python2.7/dist-packages/neutron/plugins/cisco/models/virt_phy_sw_v2.py", line 163, in _invoke_plugin 2014-06-27 00:32:57.177 20970 TRACE neutron.api.v2.resource return func(*args, **kwargs) 2014-06-27 00:32:57.177 20970 TRACE neutron.api.v2.resource File "/usr/lib/python2.7/dist-packages/neutron/plugins/cisco/nexus/cisco_nexus_plugin_v2.py", line 329, in delete_port2014-06-27 00:32:57.177 20970 TRACE neutron.api.v2.resource 2014-06-27 00:32:57.177 20970 TRACE neutron.api.v2.resource File "/usr/lib/python2.7/contextlib.py", line 24, in __exit__ 2014-06-27 00:32:57.177 20970 TRACE neutron.api.v2.resource self.gen.next() 2014-06-27 00:32:57.177 20970 TRACE neutron.api.v2.resource File "/usr/lib/python2.7/dist-packages/neutron/plugins/cisco/nexus/cisco_nexus_plugin_v2.py", line 318, in delete_port 2014-06-27 00:32:57.177 20970 TRACE neutron.api.v2.resource self._client.delete_vlan(switch_ip, row.vlan_id) 2014-06-27 00:32:57.177 20970 TRACE neutron.api.v2.resource File "/usr/lib/python2.7/dist-packages/neutron/plugins/cisco/nexus/cisco_nexus_network_driver_v2.py", line 152, in delete_vlan 2014-06-27 00:32:57.177 20970 TRACE neutron.api.v2.resource self._edit_config(nexus_host, target='running', config=confstr) 2014-06-27 00:32:57.177 20970 TRACE neutron.api.v2.resource File "/usr/lib/python2.7/dist-packages/neutron/plugins/cisco/nexus/cisco_nexus_network_driver_v2.py", line 75, in _edit_config 2014-06-27 00:32:57.177 20970 TRACE neutron.api.v2.resource raise cexc.NexusConfigFailed(config=config, exc=e) 2014-06-27 00:32:57.177 20970 TRACE neutron.api.v2.resource NexusConfigFailed: Failed to configure Nexus: 2014-06-27 00:32:57.177 20970 TRACE neutron.api.v2.resource <config xmlns:xc="urn:ietf:params:xml:ns:netconf:base:1.0"> 2014-06-27 00:32:57.177 20970 TRACE neutron.api.v2.resource <configure> 2014-06-27 00:32:57.177 20970 TRACE neutron.api.v2.resource <__XML__MODE__exec_configure> 2014-06-27 00:32:57.177 20970 TRACE neutron.api.v2.resource <no> 2014-06-27 00:32:57.177 20970 TRACE neutron.api.v2.resource <vlan> 2014-06-27 00:32:57.177 20970 TRACE neutron.api.v2.resource <vlan-id-create-delete> 2014-06-27 00:32:57.177 20970 TRACE neutron.api.v2.resource <__XML__PARAM_value>2000</__XML__PARAM_value> 2014-06-27 00:32:57.177 20970 TRACE neutron.api.v2.resource </vlan-id-create-delete> 2014-06-27 00:32:57.177 20970 TRACE neutron.api.v2.resource </vlan>2014-06-27 00:32:57.177 20970 TRACE neutron.api.v2.resource </no>2014-06-27 00:32:57.177 20970 TRACE neutron.api.v2.resource2014-06-27 00:32:57.177 20970 TRACE neutron.api.v2.resource </__XML__MODE__exec_configure> 2014-06-27 00:32:57.177 20970 TRACE neutron.api.v2.resource </configure>2014-06-27 00:32:57.177 20970 TRACE neutron.api.v2.resource </config> 2014-06-27 00:32:57.177 20970 TRACE neutron.api.v2.resource . Reason: ERROR: None of the VLANs exist 2014-06-27 00:32:57.177 20970 TRACE neutron.api.v2.resource . 2014-06-27 00:32:57.177 20970 TRACE neutron.api.v2.resource ** Affects: neutron Importance: Undecided Status: New -- You received this bug notification because you are a member of Yahoo! Engineering Team, which is subscribed to neutron. https://bugs.launchpad.net/bugs/1335031 Title: Cisco Nexus plugin issue delete_vlan netconf call 2 times in batch instance delete Status in OpenStack Neutron (virtual network service): New Bug description: Cisco Nexus plugin will issue netconf call delete_vlan to switch when the last instance on a tenant network is deleted. In my case I delete 8 instances in batch, the last instance always failed to be deleted. From /var/log/neutron/server.log, Cisco Nexus plugin thrown exception "None of the VLANs exist". After study the same log, I found the delete_vlan netconf call has been issued 2 times. I expect only 1st call is issue, which delete the VLAN on switch. The 2nd call causes "None of the VLANs exist". 2014-06-27 00:32:55.798 20970 INFO ncclient.operations.rpc [-] Requesting 'EditConfig' 2014-06-27 00:32:55.798 20970 DEBUG ncclient.transport.session [-] queueing <?xml version='1.0' encoding='UTF-8'?> <rpc message-id="urn:uuid:86dae2e8-fd4f-11e3-b8a3-080027381b81" xmlns="urn:ietf:params:xml:ns:netconf:base:1.0" xmlns:if="http://www.cisco.com/nxos:1.0:if_manager"; xmlns:nxos="http://www.cisco.com/nxos:1.0";><edit-config><target><running /></target><config> <configure> <__XML__MODE__exec_configure> <no> <vlan> <vlan-id-create-delete> <__XML__PARAM_value>2000</__XML__PARAM_value> </vlan-id-create-delete> </vlan> </no> </__XML__MODE__exec_configure> </configure> </config></edit-config></rpc> send /usr/local/lib/python2.7/dist-packages/ncclient/transport/session.py:137 2014-06-27 00:32:55.799 20970 DEBUG ncclient.operations.rpc [-] Sync request, will wait for timeout=30 _request /usr/local/lib/python2.7/dist-packages/ncclient/operations/rpc.py:284 2014-06-27 00:32:55.799 20970 INFO ncclient.operations.rpc [-] Requesting 'EditConfig' 2014-06-27 00:32:55.799 20970 DEBUG ncclient.transport.session [-] queueing <?xml version='1.0' encoding='UTF-8'?> <rpc message-id="urn:uuid:86db0ff2-fd4f-11e3-b8a3-080027381b81" xmlns="urn:ietf:params:xml:ns:netconf:base:1.0" xmlns:if="http://www.cisco.com/nxos:1.0:if_manager"; xmlns:nxos="http://www.cisco.com/nxos:1.0";><edit-config><target><running /></target><config> <configure> <__XML__MODE__exec_configure> <no> <vlan> <vlan-id-create-delete> <__XML__PARAM_value>2000</__XML__PARAM_value> </vlan-id-create-delete> </vlan> </no> </__XML__MODE__exec_configure> </configure> </config></edit-config></rpc> send /usr/local/lib/python2.7/dist-packages/ncclient/transport/session.py:137 2014-06-27 00:32:55.800 20970 DEBUG ncclient.operations.rpc [-] Sync request, will wait for timeout=30 _request /usr/local/lib/python2.7/dist-packages/ncclient/operations/rpc.py:284 2014-06-27 00:32:55.810 20970 DEBUG neutron.api.v2.base [-] Policy rule:get_router:external_gateway_info not found. Assuming no authZ check is defined for external_gateway_info _is_visible /usr/lib/python2.7/dist-packages/neutron/api/v2/base.py:143 2014-06-27 00:32:55.864 20970 DEBUG amqp [-] Channel open _open_ok /usr/lib/python2.7/dist-packages/amqp/channel.py:420 2014-06-27 00:32:55.914 20970 DEBUG amqp [-] Closed channel #1 _do_close /usr/lib/python2.7/dist-packages/amqp/channel.py:88 2014-06-27 00:32:55.914 20970 DEBUG amqp [-] using channel_id: 1 __init__ /usr/lib/python2.7/dist-packages/amqp/channel.py:70 2014-06-27 00:32:55.914 20970 DEBUG neutron.openstack.common.rpc.amqp [-] received {u'_context_roles': [u'admin'], u'_msg_id': u'aae227ba330c4d6fae548767903c8215', u'_context_read_deleted': u'no', u'_reply_q': u'reply_b822086377ac4271926517c0d3a4ba2f', u'_context_tenant_id': None, u'args': {u'network_id': u'5805ae6f-1d36-4463-b95e-e4f231306114', u'host': u'openstack', u'device_id': u'dhcpacb6d6f0-2d56-5c7a-9905-4c3a3d168f49-5805ae6f-1d36-4463-b95e-e4f231306114'}, u'namespace': None, u'_unique_id': u'765269a423f84fd0819a8d54951c4979', u'_context_is_admin': True, u'version': u'1.1', u'_context_project_id': None, u'_context_timestamp': u'2014-06-24 10:40:44.712663', u'_context_user_id': None, u'method': u'get_dhcp_port'} _safe_log /usr/lib/python2.7/dist-packages/neutron/openstack/common/rpc/common.py:2762014-06-27 00:32:55.915 20970 DEBUG neutron.openstack.common.rpc.amqp [-] unpacked context: {'user_id': None, 'roles': [u'admin'], 'tenant_id': None, 'is_admin': True, 'timestamp': u' 2014-06-24 10:40:44.712663', 'project_id': None, 'read_deleted': u'no'} _safe_log /usr/lib/python2.7/dist-packages/neutron/openstack/common/rpc/common.py:2762014-06-27 00:32:55.996 20970 DEBUG neutron.db.dhcp_rpc_base [-] Port dhcpacb6d6f0-2d56-5c7a-9905-4c3a3d168f49-5805ae6f-1d36-4463-b95e-e4f231306114 for 5805ae6f-1d36-4463-b95e-e4f231306114 requested from openstack get_dhcp_port /usr/lib/python2.7/dist-packages/neutron/db/dhcp_rpc_base.py:1132014-06-27 00:32:56.048 20970 DEBUG amqp [-] Channel open _open_ok /usr/lib/python2.7/dist-packages/amqp/channel.py:420 2014-06-27 00:32:56.049 20970 DEBUG neutron.openstack.common.rpc.amqp [-] UNIQUE_ID is 531557ef76f04a7f8f0b5b66a063c9b2. _add_unique_id /usr/lib/python2.7/dist-packages/neutron/openstack/common/rpc/amqp.py:339 2014-06-27 00:32:56.062 20970 DEBUG ncclient.transport.ssh [-] Sending message run /usr/local/lib/python2.7/dist-packages/ncclient/transport/ssh.py:299 2014-06-27 00:32:56.149 20970 DEBUG amqp [-] Closed channel #1 _do_close /usr/lib/python2.7/dist-packages/amqp/channel.py:882014-06-27 00:32:56.149 20970 DEBUG amqp [-] using channel_id: 1 __init__ /usr/lib/python2.7/dist-packages/amqp/channel.py:70 2014-06-27 00:32:56.247 20970 DEBUG amqp [-] Channel open _open_ok /usr/lib/python2.7/dist-packages/amqp/channel.py:420 2014-06-27 00:32:56.260 20970 DEBUG neutron.plugins.cisco.models.virt_phy_sw_v2 [-] update_port() called update_port /usr/lib/python2.7/dist-packages/neutron/plugins/cisco/models/virt_phy_sw_v2.py:345 2014-06-27 00:32:56.351 20970 DEBUG keystoneclient.middleware.auth_token [-] Authenticating user token __call__ /usr/lib/python2.7/dist-packages/keystoneclient/middleware/auth_token.py:5262014-06-27 00:32:56.351 20970 DEBUG keystoneclient.middleware.auth_token [-] Removing headers from request environment: X-Identity-Status,X-Domain-Id,X-Domain-Name,X-Project-Id,X-Project-Name,X-Project-Domain-Id,X-Project-Domain-Name,X-User-Id,X-User-Name,X-User-Domain-Id,X-User-Domain-Name,X-Roles,X-Service-Catalog,X-User,X-Tenant-Id,X-Tenant-Name,X-Tenant,X-Role _remove_auth_headers /usr/lib/python2.7/dist-packages/keystoneclient/middleware/auth_token.py:5852014-06-27 00:32:56.351 20970 DEBUG keystoneclient.middleware.auth_token [-] Returning cached token cfbfdf0da25a2f4ebbe9eb7372326d8c _cache_get /usr/lib/python2.7/dist-packages/keystoneclient/middleware/auth_token.py:9762014-06-27 00:32:56.352 20970 DEBUG routes.middleware [-] No route matched for GET /networks.json __call__ /usr/lib/python2. 7/dist-packages/routes/middleware.py:97 2014-06-27 00:32:56.352 20970 DEBUG routes.middleware [-] Matched GET /networks.json __call__ /usr/lib/python2.7/dist-packages/routes/middleware.py:1002014-06-27 00:32:56.352 20970 DEBUG routes.middleware [-] Route path: '/networks{.format}', defaults: {'action': u'index', 'controller': <wsgify at 71235920 wrapping <function resource at 0x43edb18>>} __call__ /usr/lib/python2.7/dist-packages/routes/middleware.py:102 2014-06-27 00:32:56.353 20970 DEBUG routes.middleware [-] Match dict: {'action': u'index', 'controller': <wsgify at 71235920 wrapping <function resource at 0x43edb18>>, 'format': u'json'} __call__ /usr/lib/python2.7/dist-packages/routes/middleware.py:1032014-06-27 00:32:56.366 20970 DEBUG neutron.openstack.common.rpc.amqp [-] received {u'_context_roles': [u'admin'], u'_context_read_deleted': u'no', u'_context_tenant_id': None, u'args': {u'agent_state': {u'agent_state': {u'topic': u'l3_agent', u'binary': u'neutron-l3-agent', u'host': u'openstack', u'agent_type': u'L3 agent', u'configurations': {u'router_id': u'', u'gateway_external_network_id': u'', u'handle_internal_only_routers': True, u'use_namespaces': True, u'routers': 1, u'interfaces': 1, u'floating_ips': 0, u'interface_driver': u'neutron.agent.linux.interface.OVSInterfaceDriver', u'ex_gw_ports': 0}}}, u'time': u'2014-06-26T16:32:56.278811'}, u'namespace': None, u'_unique_id': u'7989b8df817747a49a8128d3f0100573', u'_context_is _admin': True, u'version': u'1.0', u'_context_project_id': None, u'_context_timestamp': u'2014-06-24 10:40:45.067965', u'_context_user_id': None, u'method': u'report_state'} _safe_log /usr/lib/python2.7/dist-packages/neutron/openstack/common/rpc/common.py:2762014-06-27 00:32:56.367 20970 DEBUG neutron.openstack.common.rpc.amqp [-] unpacked context: {'user_id': None, 'roles': [u'admin'], 'tenant_id': None, 'is_admin': True, 'timestamp': u'2014-06-24 10:40:45.067965', 'project_id': None, 'read_deleted': u'no'} _safe_log /usr/lib/python2.7/dist-packages/neutron/openstack/common/rpc/common.py:276 2014-06-27 00:32:56.377 20970 DEBUG ncclient.transport.ssh [-] Sending message run /usr/local/lib/python2.7/dist-packages/ncclient/transport/ssh.py:299 2014-06-27 00:32:56.578 20970 DEBUG ncclient.transport.ssh [-] parsed new message _parse /usr/local/lib/python2.7/dist-packages/ncclient/transport/ssh.py:97 2014-06-27 00:32:56.578 20970 DEBUG ncclient.transport.session [-] dispatching message to <ncclient.operations.rpc.RPCReplyListener object at 0x493c110>: <?xml version="1.0" encoding="ISO-8859-1"?> <rpc-reply xmlns="urn:ietf:params:xml:ns:netconf:base:1.0" xmlns:if="http://www.cisco.com/nxos:1.0:if_manager"; xmlns:nxos="http://www.cisco.com/nxos:1.0"; message-id="urn:uuid:86dae2e8-fd4f-11e3-b8a3-080027381b81"> <ok/> </rpc-reply> _dispatch_message /usr/local/lib/python2.7/dist-packages/ncclient/transport/session.py:53 2014-06-27 00:32:56.578 20970 DEBUG ncclient.operations.rpc [-] Delivering to <ncclient.operations.edit.EditConfig object at 0x62d01d0> callback /usr/local/lib/python2.7/dist-packages/ncclient/operations/rpc.py:189 2014-06-27 00:32:56.579 20970 DEBUG neutron.api.v2.base [-] Policy rule:get_subnet:shared not found. Assuming no authZ check is defined for shared _is_visible /usr/lib/python2.7/dist-packages/neutron/api/v2/base.py:143 2014-06-27 00:32:56.580 20970 DEBUG neutron.api.v2.base [-] Policy rule:get_subnet:shared not found. Assuming no authZ check is defined for shared _is_visible /usr/lib/python2.7/dist-packages/neutron/api/v2/base.py:143 2014-06-27 00:32:56.580 20970 DEBUG neutron.api.v2.base [-] Policy rule:get_subnet:shared not found. Assuming no authZ check is defined for shared _is_visible /usr/lib/python2.7/dist-packages/neutron/api/v2/base.py:143 2014-06-27 00:32:56.581 20970 DEBUG neutron.api.v2.base [-] Policy rule:get_subnet:shared not found. Assuming no authZ check is defined for shared _is_visible /usr/lib/python2.7/dist-packages/neutron/api/v2/base.py:143 2014-06-27 00:32:56.682 20970 DEBUG keystoneclient.middleware.auth_token [-] Authenticating user token __call__ /usr/lib/python2.7/dist-packages/keystoneclient/middleware/auth_token.py:5262014-06-27 00:32:56.682 20970 DEBUG keystoneclient.middleware.auth_token [-] Removing headers from request environment: X-Identity-Status,X-Domain-Id,X-Domain-Name,X-Project-Id,X-Project-Name,X-Project-Domain-Id,X-Project-Domain-Name,X-User-Id,X-User-Name,X-User-Domain-Id,X-User-Domain-Name,X-Roles,X-Service-Catalog,X-User,X-Tenant-Id,X-Tenant-Name,X-Tenant,X-Role _remove_auth_headers /usr/lib/python2.7/dist-packages/keystoneclient/middleware/auth_token.py:585 2014-06-27 00:32:56.684 20970 DEBUG keystoneclient.middleware.auth_token [-] Returning cached token cfbfdf0da25a2f4ebbe9eb7372326d8c _cache_get /usr/lib/python2.7/dist-packages/keystoneclient/middleware/auth_token.py:976 2014-06-27 00:32:56.685 20970 DEBUG routes.middleware [-] No route matched for GET /subnets.json __call__ /usr/lib/python2.7/dist-packages/routes/middleware.py:97 2014-06-27 00:32:56.685 20970 DEBUG routes.middleware [-] Matched GET /subnets.json __call__ /usr/lib/python2.7/dist-packages/routes/middleware.py:100 2014-06-27 00:32:56.685 20970 DEBUG routes.middleware [-] Route path: '/subnets{.format}', defaults: {'action': u'index', 'controller': <wsgify at 69838800 wrapping <function resource at 0x43eda28>>} __call__ /usr/lib/python2.7/dist-packages/routes/middleware.py:102 2014-06-27 00:32:56.685 20970 DEBUG routes.middleware [-] Match dict: {'action': u'index', 'controller': <wsgify at 69838800 wrapping <function resource at 0x43eda28>>, 'format': u'json'} __call__ /usr/lib/python2.7/dist-packages/routes/middleware.py:103 2014-06-27 00:32:56.733 20970 DEBUG ncclient.transport.ssh [-] parsed new message _parse /usr/local/lib/python2.7/dist-packages/ncclient/transport/ssh.py:97 2014-06-27 00:32:56.733 20970 DEBUG ncclient.transport.session [-] dispatching message to <ncclient.operations.rpc.RPCReplyListener object at 0x493c110>: <?xml version="1.0" encoding="ISO-8859-1"?> <rpc-reply xmlns="urn:ietf:params:xml:ns:netconf:base:1.0" xmlns:if="http://www.cisco.com/nxos:1.0:if_manager"; xmlns:nxos="http://www.cisco.com/nxos:1.0"; message-id="urn:uuid:86db0ff2-fd4f-11e3-b8a3-080027381b81"> <rpc-error> <error-type>application</error-type> <error-tag>invalid-value</error-tag> <error-severity>error</error-severity> <error-message>ERROR: None of the VLANs exist </error-message> </rpc-error> </rpc-reply> _dispatch_message /usr/local/lib/python2.7/dist-packages/ncclient/transport/session.py:53 2014-06-27 00:32:56.734 20970 DEBUG ncclient.operations.rpc [-] Delivering to <ncclient.operations.edit.EditConfig object at 0x62d0810> callback /usr/local/lib/python2.7/dist-packages/ncclient/operations/rpc.py:1892014-06-27 00:32:56.735 20970 DEBUG neutron.openstack.common.rpc.amqp [-] received {u'_context_roles': [u'admin'], u'_context_read_deleted': u'no', u'_context_tenant_id': None, u'args': {u'agent_state': {u'agent_state': {u'topic': u'N/A', u'binary': u'neutron-openvswitch-agent', u'host': u'openstack', u'agent_type': u'Open vSwitch agent', u'configurations': {u'tunnel_types': [], u'tunneling_ip': u'', u'bridge_mappings': {u'mgmtnet': u'br-ex', u'datanet': u'br-eth4'}, u'l2_population': False, u'devices': 7}}}, u'time': u'2014-06-26T16:32:56.640450'}, u'namespace': None, u'_unique_id': u'0faaf4faee8a4528af4bfd95092583a9', u'_context_is_admin': True, u'version': u'1.0', u'_context_project_id': None, u'_context_timestamp': u'2014-06-24 10:40:44.865491', u'_context_user_id': N one, u'method': u'report_state'} _safe_log /usr/lib/python2.7/dist-packages/neutron/openstack/common/rpc/common.py:2762014-06-27 00:32:56.735 20970 DEBUG neutron.openstack.common.rpc.amqp [-] unpacked context: {'user_id': None, 'roles': [u'admin'], 'tenant_id': None, 'is_admin': True, 'timestamp': u'2014-06-24 10:40:44.865491', 'project_id': None, 'read_deleted': u'no'} _safe_log /usr/lib/python2.7/dist-packages/neutron/openstack/common/rpc/common.py:2762014-06-27 00:32:56.979 20970 DEBUG neutron.db.db_base_plugin_v2 [-] Recycle 192.168.100.7 _recycle_ip /usr/lib/python2.7/dist-packages/neutron/db/db_base_plugin_v2.py:366 2014-06-27 00:32:56.982 20970 DEBUG neutron.db.db_base_plugin_v2 [-] Recycle: first match for 192.168.100.8-192.168.100.254 _recycle_ip /usr/lib/python2.7/dist-packages/neutron/db/db_base_plugin_v2.py:3712014-06-27 00:32:56.987 20970 DEBUG neutron.db.db_base_plugin_v2 [-] Recycle: last match for 192.168.100.6-192.168.100.6 _recycle_ip /usr/lib/python2.7/dist-packages/neutron/db/db_base_plugin_v2.py:378 2014-06-27 00:32:56.987 20970 DEBUG neutron.db.db_base_plugin_v2 [-] Recycle: merged 192.168.100.6-192.168.100.6 and 192.168.100.8-192.168.100.254 _recycle_ip /usr/lib/python2.7/dist-packages/neutron/db/db_base_plugin_v2.py:392 2014-06-27 00:32:56.987 20970 DEBUG neutron.db.db_base_plugin_v2 [-] Delete allocated IP 192.168.100.7 (5805ae6f-1d36-4463-b95e-e4f231306114/44435cdd-b3d5-4dc0-908a-3754d6eaae7c) _delete_ip_allocation /usr/lib/python2.7/dist-packages/neutron/db/db_base_plugin_v2.py:444 2014-06-27 00:32:57.032 20970 DEBUG neutron.openstack.common.rpc.amqp [-] Making asynchronous fanout cast... fanout_cast /usr/lib/python2.7/dist-packages/neutron/openstack/common/rpc/amqp.py:568 2014-06-27 00:32:57.032 20970 DEBUG neutron.openstack.common.rpc.amqp [-] UNIQUE_ID is 5b29f03cd9be4b3fb593bdde20ece1e9. _add_unique_id /usr/lib/python2.7/dist-packages/neutron/openstack/common/rpc/amqp.py:339 2014-06-27 00:32:57.046 20970 DEBUG neutron.plugins.cisco.db.nexus_db_v2 [-] add_nexusport_binding() called add_nexusport_binding /usr/lib/python2.7/dist-packages/neutron/plugins/cisco/db/nexus_db_v2.py:49 2014-06-27 00:32:57.070 20970 DEBUG keystoneclient.middleware.auth_token [-] Authenticating user token __call__ /usr/lib/python2.7/dist-packages/keystoneclient/middleware/auth_token.py:5262014-06-27 00:32:57.071 20970 DEBUG keystoneclient.middleware.auth_token [-] Removing headers from request environment: X-Identity-Status,X-Domain-Id,X-Domain-Name,X-Project-Id,X-Project-Name,X-Project-Domain-Id,X-Project-Domain-Name,X-User-Id,X-User-Name,X-User-Domain-Id,X-User-Domain-Name,X-Roles,X-Service-Catalog,X-User,X-Tenant-Id,X-Tenant-Name,X-Tenant,X-Role _remove_auth_headers /usr/lib/python2.7/dist-packages/keystoneclient/middleware/auth_token.py:585 2014-06-27 00:32:57.071 20970 DEBUG keystoneclient.middleware.auth_token [-] Returning cached token cfbfdf0da25a2f4ebbe9eb7372326d8c _cache_get /usr/lib/python2.7/dist-packages/keystoneclient/middleware/auth_token.py:9762014-06-27 00:32:57.072 20970 DEBUG routes.middleware [-] No route matched for GET /ports.json __call__ /usr/lib/python2.7/dist-packages/routes/middleware.py:97 2014-06-27 00:32:57.072 20970 DEBUG routes.middleware [-] Matched GET /ports.json __call__ /usr/lib/python2.7/dist-packages/routes/middleware.py:100 2014-06-27 00:32:57.072 20970 DEBUG routes.middleware [-] Route path: '/ports{.format}', defaults: {'action': u'index', 'controller': <wsgify at 71237136 wrapping <function resource at 0x43edb90>>} __call__ /usr/lib/python2.7/dist-packages/routes/middleware.py:102 2014-06-27 00:32:57.072 20970 DEBUG routes.middleware [-] Match dict: {'action': u'index', 'controller': <wsgify at 71237136 wrapping <function resource at 0x43edb90>>, 'format': u'json'} __call__ /usr/lib/python2.7/dist-packages/routes/middleware.py:1032014-06-27 00:32:57.173 20970 DEBUG neutron.openstack.common.rpc.amqp [-] received {u'_context_roles': [u'admin'], u'_context_read_deleted': u'no', u'_context_tenant_id': None, u'args': {u'agent_state': {u'agent_state': {u'topic': u'N/A', u'binary': u'neutron-openvswitch-agent', u'host': u'WorkerNode8', u'agent_type': u'Open vSwitch agent', u'configurations': {u'tunnel_types': [], u'tunneling_ip': u'', u'bridge_mappings': {u'mgmtnet': u'br-eth0', u'datanet': u'br-eth4'}, u'l2_population': False, u'devices': 4}}}, u'time': u'2014-06-26T16:32:56.942233'}, u'namespace': None, u'_unique_id': u'd6819e3f92884efcaef9fe00f63467a1', u'_context_is_admin': True, u'version': u'1.0', u'_context_project_id': None, u'_context_timestamp': u'2014-06 -24 09:01:14.058689', u'_context_user_id': None, u'method': u'report_state'} _safe_log /usr/lib/python2.7/dist-packages/neutron/openstack/common/rpc/common.py:2762014-06-27 00:32:57.174 20970 DEBUG neutron.openstack.common.rpc.amqp [-] unpacked context: {'user_id': None, 'roles': [u'admin'], 'tenant_id': None, 'is_admin': True, 'timestamp': u'2014-06-24 09:01:14.058689', 'project_id': None, 'read_deleted': u'no'} _safe_log /usr/lib/python2.7/dist-packages/neutron/openstack/common/rpc/common.py:276 2014-06-27 00:32:57.176 20970 DEBUG neutron.openstack.common.rpc.amqp [-] Making asynchronous fanout cast... fanout_cast /usr/lib/python2.7/dist-packages/neutron/openstack/common/rpc/amqp.py:5682014-06-27 00:32:57.176 20970 DEBUG neutron.openstack.common.rpc.amqp [-] UNIQUE_ID is 312e0c282df94972b4b4c05c2fc02be6. _add_unique_id /usr/lib/python2.7/dist-packages/neutron/openstack/common/rpc/amqp.py:339 2014-06-27 00:32:57.177 20970 ERROR neutron.api.v2.resource [-] delete failed 2014-06-27 00:32:57.177 20970 TRACE neutron.api.v2.resource Traceback (most recent call last): 2014-06-27 00:32:57.177 20970 TRACE neutron.api.v2.resource File "/usr/lib/python2.7/dist-packages/neutron/api/v2/resource.py", line 84, in resource 2014-06-27 00:32:57.177 20970 TRACE neutron.api.v2.resource result = method(request=request, **args) 2014-06-27 00:32:57.177 20970 TRACE neutron.api.v2.resource File "/usr/lib/python2.7/dist-packages/neutron/api/v2/base.py", line 432, in delete 2014-06-27 00:32:57.177 20970 TRACE neutron.api.v2.resource obj_deleter(request.context, id, **kwargs) 2014-06-27 00:32:57.177 20970 TRACE neutron.api.v2.resource File "/usr/lib/python2.7/dist-packages/neutron/plugins/cisco/models/virt_phy_sw_v2.py", line 399, in delete_port 2014-06-27 00:32:57.177 20970 TRACE neutron.api.v2.resource n_args) 2014-06-27 00:32:57.177 20970 TRACE neutron.api.v2.resource File "/usr/lib/python2.7/dist-packages/neutron/plugins/cisco/models/virt_phy_sw_v2.py", line 145, in _invoke_plugin_per_device 2014-06-27 00:32:57.177 20970 TRACE neutron.api.v2.resource return [self._invoke_plugin(plugin_key, function_name, args, kwargs)] 2014-06-27 00:32:57.177 20970 TRACE neutron.api.v2.resource File "/usr/lib/python2.7/dist-packages/neutron/plugins/cisco/models/virt_phy_sw_v2.py", line 163, in _invoke_plugin 2014-06-27 00:32:57.177 20970 TRACE neutron.api.v2.resource return func(*args, **kwargs) 2014-06-27 00:32:57.177 20970 TRACE neutron.api.v2.resource File "/usr/lib/python2.7/dist-packages/neutron/plugins/cisco/nexus/cisco_nexus_plugin_v2.py", line 329, in delete_port2014-06-27 00:32:57.177 20970 TRACE neutron.api.v2.resource 2014-06-27 00:32:57.177 20970 TRACE neutron.api.v2.resource File "/usr/lib/python2.7/contextlib.py", line 24, in __exit__ 2014-06-27 00:32:57.177 20970 TRACE neutron.api.v2.resource self.gen.next() 2014-06-27 00:32:57.177 20970 TRACE neutron.api.v2.resource File "/usr/lib/python2.7/dist-packages/neutron/plugins/cisco/nexus/cisco_nexus_plugin_v2.py", line 318, in delete_port 2014-06-27 00:32:57.177 20970 TRACE neutron.api.v2.resource self._client.delete_vlan(switch_ip, row.vlan_id) 2014-06-27 00:32:57.177 20970 TRACE neutron.api.v2.resource File "/usr/lib/python2.7/dist-packages/neutron/plugins/cisco/nexus/cisco_nexus_network_driver_v2.py", line 152, in delete_vlan 2014-06-27 00:32:57.177 20970 TRACE neutron.api.v2.resource self._edit_config(nexus_host, target='running', config=confstr) 2014-06-27 00:32:57.177 20970 TRACE neutron.api.v2.resource File "/usr/lib/python2.7/dist-packages/neutron/plugins/cisco/nexus/cisco_nexus_network_driver_v2.py", line 75, in _edit_config 2014-06-27 00:32:57.177 20970 TRACE neutron.api.v2.resource raise cexc.NexusConfigFailed(config=config, exc=e) 2014-06-27 00:32:57.177 20970 TRACE neutron.api.v2.resource NexusConfigFailed: Failed to configure Nexus: 2014-06-27 00:32:57.177 20970 TRACE neutron.api.v2.resource <config xmlns:xc="urn:ietf:params:xml:ns:netconf:base:1.0"> 2014-06-27 00:32:57.177 20970 TRACE neutron.api.v2.resource <configure> 2014-06-27 00:32:57.177 20970 TRACE neutron.api.v2.resource <__XML__MODE__exec_configure> 2014-06-27 00:32:57.177 20970 TRACE neutron.api.v2.resource <no> 2014-06-27 00:32:57.177 20970 TRACE neutron.api.v2.resource <vlan> 2014-06-27 00:32:57.177 20970 TRACE neutron.api.v2.resource <vlan-id-create-delete> 2014-06-27 00:32:57.177 20970 TRACE neutron.api.v2.resource <__XML__PARAM_value>2000</__XML__PARAM_value> 2014-06-27 00:32:57.177 20970 TRACE neutron.api.v2.resource </vlan-id-create-delete> 2014-06-27 00:32:57.177 20970 TRACE neutron.api.v2.resource </vlan>2014-06-27 00:32:57.177 20970 TRACE neutron.api.v2.resource </no>2014-06-27 00:32:57.177 20970 TRACE neutron.api.v2.resource2014-06-27 00:32:57.177 20970 TRACE neutron.api.v2.resource </__XML__MODE__exec_configure> 2014-06-27 00:32:57.177 20970 TRACE neutron.api.v2.resource </configure>2014-06-27 00:32:57.177 20970 TRACE neutron.api.v2.resource </config> 2014-06-27 00:32:57.177 20970 TRACE neutron.api.v2.resource . Reason: ERROR: None of the VLANs exist 2014-06-27 00:32:57.177 20970 TRACE neutron.api.v2.resource . 2014-06-27 00:32:57.177 20970 TRACE neutron.api.v2.resource To manage notifications about this bug go to: https://bugs.launchpad.net/neutron/+bug/1335031/+subscriptions -- Mailing list: https://launchpad.net/~yahoo-eng-team Post to : yahoo-eng-team@lists.launchpad.net Unsubscribe : https://launchpad.net/~yahoo-eng-team More help : https://help.launchpad.net/ListHelp
