← Back to team overview

yahoo-eng-team team mailing list archive

[Bug 1335031] [NEW] Cisco Nexus plugin issue delete_vlan netconf call 2 times in batch instance delete

 

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'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': 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.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

** 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': 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.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


Follow ups

References