You are viewing a plain text version of this content. The canonical link for it is here.
Posted to issues@cloudstack.apache.org by "Parth Jagirdar (JIRA)" <ji...@apache.org> on 2014/02/14 00:36:19 UTC

[jira] [Created] (CLOUDSTACK-6101) Contrail:MS: Disable NAT on acquired IP results in exception

Parth Jagirdar created CLOUDSTACK-6101:
------------------------------------------

             Summary: Contrail:MS: Disable NAT on acquired IP results in exception
                 Key: CLOUDSTACK-6101
                 URL: https://issues.apache.org/jira/browse/CLOUDSTACK-6101
             Project: CloudStack
          Issue Type: Bug
      Security Level: Public (Anyone can view this level - this is the default.)
          Components: Contrail, Management Server
    Affects Versions: 4.3.0
         Environment: Contrail
            Reporter: Parth Jagirdar
             Fix For: 4.3.0


Disable NAT, rule gets removed but exception is thrown.

2014-02-13 13:53:08,389 DEBUG [c.c.n.r.RulesManagerImpl] (Job-Executor-77:ctx-6e8167ac ctx-152aebc9) Revoking all Firewallrules as a part of disabling static nat for public IP id=3
2014-02-13 13:53:08,400 DEBUG [c.c.n.f.FirewallManagerImpl] (Job-Executor-77:ctx-6e8167ac ctx-152aebc9) Releasing 0 firewall rules for ip id=3
2014-02-13 13:53:08,401 DEBUG [c.c.n.f.FirewallManagerImpl] (Job-Executor-77:ctx-6e8167ac ctx-152aebc9) There are no firewall rules to apply
2014-02-13 13:53:08,402 DEBUG [c.c.n.f.FirewallManagerImpl] (Job-Executor-77:ctx-6e8167ac ctx-152aebc9) Successfully released firewall rules for ip id=3 and # of rules now = 0
2014-02-13 13:53:08,408 DEBUG [c.c.n.r.RulesManagerImpl] (Job-Executor-77:ctx-6e8167ac ctx-152aebc9) Releasing 0 port forwarding rules for ip id=3
2014-02-13 13:53:08,410 DEBUG [c.c.n.r.RulesManagerImpl] (Job-Executor-77:ctx-6e8167ac ctx-152aebc9) Releasing 0 static nat rules for ip id=3
2014-02-13 13:53:08,411 DEBUG [c.c.n.r.RulesManagerImpl] (Job-Executor-77:ctx-6e8167ac ctx-152aebc9) There are no port forwarding rules to apply for ip id=3
2014-02-13 13:53:08,412 DEBUG [c.c.n.r.RulesManagerImpl] (Job-Executor-77:ctx-6e8167ac ctx-152aebc9) There are no static nat rules to apply for ip id=3
2014-02-13 13:53:08,423 INFO  [o.a.c.e.o.NetworkOrchestrator] (Job-Executor-77:ctx-6e8167ac ctx-152aebc9) Let ContrailElement handle StaticNat in network 206
2014-02-13 13:53:08,441 INFO  [n.j.c.a.ApiConnector] (Job-Executor-77:ctx-6e8167ac ctx-152aebc9) >> Request: GET, /virtual-network/e6c067bc-bc63-4613-a7af-84d0182ff6d2
2014-02-13 13:53:08,451 INFO  [n.j.c.a.ApiConnector] (Job-Executor-77:ctx-6e8167ac ctx-152aebc9) << Response Status: HTTP/1.1 200 OK
2014-02-13 13:53:08,455 INFO  [n.j.c.a.ApiConnector] (Job-Executor-77:ctx-6e8167ac ctx-152aebc9) >> Request: PUT, /virtual-network/e6c067bc-bc63-4613-a7af-84d0182ff6d2, {"virtual-network":{"virtual_network_properties":{"extend_to_external_routers":false,"network_id":4},"route_target_list":{"route_target":["target:64512:1002","target:64512:1002","target:64512:1002","target:64512:1002","target:64512:1002","target:64512:1002","target:64512:1002"]},"network_ipam_refs":[{"to":["default-domain","default-project","default-network-ipam"],"attr":{"ipam_subnets":[{"subnet":{"ip_prefix":"10.223.138.64","ip_prefix_len":26},"default_gateway":"10.223.138.65"}]},"href":null,"uuid":null}],"floating_ip_pools":[{"to":["default-domain","default-project","__default_Public__","PublicIpPool"],"attr":null,"href":"http://10.223.58.3:8082/floating-ip-pool/8cf92e6e-b81a-44d6-9f6d-1472aaec7264","uuid":"8cf92e6e-b81a-44d6-9f6d-1472aaec7264"}],"routing_instances":[{"to":["default-domain","default-project","__default_Public__","__default_Public__"],"attr":null,"href":"http://10.223.58.3:8082/routing-instance/74769566-dfce-4b6d-bbb5-db772bccb2f9","uuid":"74769566-dfce-4b6d-bbb5-db772bccb2f9"}],"name":"__default_Public__","uuid":"e6c067bc-bc63-4613-a7af-84d0182ff6d2","fq_name":["default-domain","default-project","__default_Public__"],"parent_type":"project","parent_uuid":"fafebf0e-5d9c-4c99-928d-25ab65bd7ebc"}}
2014-02-13 13:53:08,492 INFO  [n.j.c.a.ApiConnector] (Job-Executor-77:ctx-6e8167ac ctx-152aebc9) << Response Status: HTTP/1.1 200 OK
2014-02-13 13:53:08,492 INFO  [n.j.c.a.ApiConnector] (Job-Executor-77:ctx-6e8167ac ctx-152aebc9) >> Request: GET, /virtual-machine-interface/07c6bdac-7be4-4d88-83ef-6f5ffe2f8b19
2014-02-13 13:53:08,496 INFO  [n.j.c.a.ApiConnector] (Job-Executor-77:ctx-6e8167ac ctx-152aebc9) << Response Status: HTTP/1.1 404 Not Found
2014-02-13 13:53:08,496 INFO  [n.j.c.a.ApiConnector] (Job-Executor-77:ctx-6e8167ac ctx-152aebc9) >> Request: POST, /virtual-machine-interfaces, {"virtual-machine-interface":{"virtual_machine_interface_mac_addresses":{"mac_address":["06:e4:2a:00:00:35"]},"virtual_network_refs":[{"to":["default-domain","default-project","__default_Public__"],"attr":null,"href":null,"uuid":null}],"name":"s-11-VM-2","uuid":"07c6bdac-7be4-4d88-83ef-6f5ffe2f8b19","fq_name":["s-11-VM","s-11-VM-2"],"parent_type":"virtual-machine"}}
2014-02-13 13:53:08,505 INFO  [n.j.c.a.ApiConnector] (Job-Executor-77:ctx-6e8167ac ctx-152aebc9) << Response Status: HTTP/1.1 400 Bad Request
2014-02-13 13:53:08,505 ERROR [n.j.c.a.ApiConnector] (Job-Executor-77:ctx-6e8167ac ctx-152aebc9) create api request failed: Bad Request
2014-02-13 13:53:08,508 ERROR [n.j.c.a.ApiConnector] (Job-Executor-77:ctx-6e8167ac ctx-152aebc9) Failure message:
    <!DOCTYPE HTML PUBLIC "-//IETF//DTD HTML 2.0//EN">
    <html>
        <head>
            <title>Error: 400 Bad Request</title>
            <style type="text/css">
              html {background-color: #eee; font-family: sans;}
              body {background-color: #fff; border: 1px solid #ddd;
                    padding: 15px; margin: 15px;}
              pre {background-color: #eee; border: 1px solid #ddd; padding: 5px;}
            </style>
        </head>
        <body>
            <h1>Error: 400 Bad Request</h1>
            <p>Sorry, the requested URL <tt>&#039;http://10.223.58.3:8082/virtual-machine-interfaces&#039;</tt>
               caused an error:</p>
            <pre>Parent [u&#039;s-11-VM&#039;] type virtual-machine does not exist</pre>
        </body>
    </html>

2014-02-13 13:53:08,509 WARN  [o.a.c.n.c.m.ContrailManager] (Job-Executor-77:ctx-6e8167ac ctx-152aebc9) virtual-network update:
com.cloud.exception.InternalErrorException: Unable to create virtual-machine-interface 07c6bdac-7be4-4d88-83ef-6f5ffe2f8b19
        at org.apache.cloudstack.network.contrail.model.VMInterfaceModel.update(VMInterfaceModel.java:229)
        at org.apache.cloudstack.network.contrail.model.VirtualNetworkModel.update(VirtualNetworkModel.java:305)
        at org.apache.cloudstack.network.contrail.management.ContrailManagerImpl.lookupPublicNetworkModel(ContrailManagerImpl.java:781)
        at org.apache.cloudstack.network.contrail.management.ContrailManagerImpl.deleteFloatingIp(ContrailManagerImpl.java:826)
        at org.apache.cloudstack.network.contrail.management.ContrailElementImpl.applyIps(ContrailElementImpl.java:349)
        at com.cloud.network.IpAddressManagerImpl.applyIpAssociations(IpAddressManagerImpl.java:976)
        at com.cloud.network.IpAddressManagerImpl.applyStaticNats(IpAddressManagerImpl.java:1790)
        at com.cloud.network.rules.RulesManagerImpl.applyStaticNatForIp(RulesManagerImpl.java:1324)
        at com.cloud.network.rules.RulesManagerImpl.revokeAllPFAndStaticNatRulesForIp(RulesManagerImpl.java:1102)
        at com.cloud.network.rules.RulesManagerImpl.disableStaticNat(RulesManagerImpl.java:1267)
        at com.cloud.network.rules.RulesManagerImpl.disableStaticNat(RulesManagerImpl.java:1238)
        at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
        at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
        at java.lang.reflect.Method.invoke(Method.java:622)
        at org.springframework.aop.support.AopUtils.invokeJoinpointUsingReflection(AopUtils.java:317)
        at org.springframework.aop.framework.ReflectiveMethodInvocation.invokeJoinpoint(ReflectiveMethodInvocation.java:183)
        at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:150)
        at org.apache.cloudstack.network.contrail.management.EventUtils$EventInterceptor.invoke(EventUtils.java:109)
        at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:161)
        at com.cloud.event.ActionEventInterceptor.invoke(ActionEventInterceptor.java:50)
        at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:161)
        at org.springframework.aop.interceptor.ExposeInvocationInterceptor.invoke(ExposeInvocationInterceptor.java:91)
        at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172)
        at org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:204)
        at com.sun.proxy.$Proxy88.disableStaticNat(Unknown Source)
        at org.apache.cloudstack.api.command.user.nat.DisableStaticNatCmd.execute(DisableStaticNatCmd.java:83)
        at com.cloud.api.ApiDispatcher.dispatch(ApiDispatcher.java:161)
        at com.cloud.api.ApiAsyncJobDispatcher.runJobInContext(ApiAsyncJobDispatcher.java:109)
        at com.cloud.api.ApiAsyncJobDispatcher$1.run(ApiAsyncJobDispatcher.java:66)
        at org.apache.cloudstack.managed.context.impl.DefaultManagedContext$1.call(DefaultManagedContext.java:56)
        at org.apache.cloudstack.managed.context.impl.DefaultManagedContext.callWithContext(DefaultManagedContext.java:103)
        at org.apache.cloudstack.managed.context.impl.DefaultManagedContext.runWithContext(DefaultManagedContext.java:53)
        at com.cloud.api.ApiAsyncJobDispatcher.runJob(ApiAsyncJobDispatcher.java:63)
        at org.apache.cloudstack.framework.jobs.impl.AsyncJobManagerImpl$5.runInContext(AsyncJobManagerImpl.java:526)
        at org.apache.cloudstack.managed.context.ManagedContextRunnable$1.run(ManagedContextRunnable.java:49)
        at org.apache.cloudstack.managed.context.impl.DefaultManagedContext$1.call(DefaultManagedContext.java:56)
        at org.apache.cloudstack.managed.context.impl.DefaultManagedContext.callWithContext(DefaultManagedContext.java:103)
        at org.apache.cloudstack.managed.context.impl.DefaultManagedContext.runWithContext(DefaultManagedContext.java:53)
        at org.apache.cloudstack.managed.context.ManagedContextRunnable.run(ManagedContextRunnable.java:46)
        at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:471)
        at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:334)
        at java.util.concurrent.FutureTask.run(FutureTask.java:166)
        at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1146)
        at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
        at java.lang.Thread.run(Thread.java:701)
2014-02-13 13:53:08,512 INFO  [n.j.c.a.ApiConnector] (Job-Executor-77:ctx-6e8167ac ctx-152aebc9) >> Request: GET, /floating-ip/fe78aa9f-7f82-4416-b214-bce21e4aca48
2014-02-13 13:53:08,518 INFO  [n.j.c.a.ApiConnector] (Job-Executor-77:ctx-6e8167ac ctx-152aebc9) << Response Status: HTTP/1.1 200 OK
2014-02-13 13:53:08,519 INFO  [n.j.c.a.ApiConnector] (Job-Executor-77:ctx-6e8167ac ctx-152aebc9) >> Request: DELETE, /floating-ip/fe78aa9f-7f82-4416-b214-bce21e4aca48
2014-02-13 13:53:08,551 INFO  [n.j.c.a.ApiConnector] (Job-Executor-77:ctx-6e8167ac ctx-152aebc9) << Response Status: HTTP/1.1 200 OK
2014-02-13 13:53:08,551 INFO  [n.j.c.a.ApiConnector] (Job-Executor-77:ctx-6e8167ac ctx-152aebc9) >> Request: GET, /floating-ip-pool/8cf92e6e-b81a-44d6-9f6d-1472aaec7264
2014-02-13 13:53:08,558 INFO  [n.j.c.a.ApiConnector] (Job-Executor-77:ctx-6e8167ac ctx-152aebc9) << Response Status: HTTP/1.1 200 OK
2014-02-13 13:53:08,560 INFO  [n.j.c.a.ApiConnector] (Job-Executor-77:ctx-6e8167ac ctx-152aebc9) >> Request: DELETE, /floating-ip-pool/8cf92e6e-b81a-44d6-9f6d-1472aaec7264
2014-02-13 13:53:08,572 INFO  [n.j.c.a.ApiConnector] (Job-Executor-77:ctx-6e8167ac ctx-152aebc9) << Response Status: HTTP/1.1 200 OK
2014-02-13 13:53:08,572 DEBUG [o.a.c.n.c.m.ContrailElement] (Job-Executor-77:ctx-6e8167ac ctx-152aebc9) Successfully deleted floating ip: 10.223.138.77
2014-02-13 13:53:08,576 DEBUG [c.c.n.r.RulesManagerImpl] (Job-Executor-77:ctx-6e8167ac ctx-152aebc9) Successfully released rules for ip id=3 and # of rules now = 0
2014-02-13 13:53:08,607 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl] (Job-Executor-77:ctx-6e8167ac ctx-152aebc9) Complete async job-115, jobStatus: SUCCEEDED, resultCode: 0, result: org.apache.cloudstack.api.response.SuccessResponse/null/{"success":true}
2014-02-13 13:53:08,622 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl] (Job-Executor-77:ctx-6e8167ac) Done executing org.apache.cloudstack.api.command.user.nat.DisableStaticNatCmd for job-115
2014-02-13 13:53:08,631 INFO  [o.a.c.f.j.i.AsyncJobMonitor] (Job-Executor-77:ctx-6e8167ac) Remove job-115 from job monitoring
2014-02-13 13:53:11,461 DEBUG [c.c.a.ApiServlet] (catalina-exec-19:ctx-0fe8a59e) ===START===  10.215.2.19 -- GET  command=queryAsyncJobResult&jobId=056b6fc2-992b-4f56-ab95-49ef4c11c23e&response=json&sessionkey=gmTvf42GzX8UhbQt9iTNSIQN6yE%3D&_=1392333100748
2014-02-13 13:53:11,480 DEBUG [c.c.a.ApiServlet] (catalina-exec-19:ctx-0fe8a59e ctx-2d4834d3) ===END===  10.215.2.19 -- GET  command=queryAsyncJobResult&jobId=056b6fc2-992b-4f56-ab95-49ef4c11c23e&response=json&sessionkey=gmTvf42GzX8UhbQt9iTNSIQN6yE%3D&_=1392333100748
2014-02-13 13:53:11,644 DEBUG [c.c.a.ApiServlet] (catalina-exec-16:ctx-f4c67cd6) ===START===  10.215.2.19 -- GET  command=listPublicIpAddresses&response=json&sessionkey=gmTvf42GzX8UhbQt9iTNSIQN6yE%3D&id=fe78aa9f-7f82-4416-b214-bce21e4aca48&_=1392333100833
2014-02-13 13:53:11,664 DEBUG [c.c.a.ApiServlet] (catalina-exec-16:ctx-f4c67cd6 ctx-4ae28adb) ===END===  10.215.2.19 -- GET  command=listPublicIpAddresses&response=json&sessionkey=gmTvf42GzX8UhbQt9iTNSIQN6yE%3D&id=fe78aa9f-7f82-4416-b214-bce21e4aca48&_=1392333100833
2014-02-13 13:53:11,673 DEBUG [c.c.a.ApiServlet] (catalina-exec-25:ctx-e47be587) ===START===  10.215.2.19 -- GET  command=listZones&id=cce32d06-91d9-47f0-90b5-766416d6add2&response=json&sessionkey=gmTvf42GzX8UhbQt9iTNSIQN6yE%3D&_=1392333100970
2014-02-13 13:53:11,685 DEBUG [c.c.a.ApiServlet] (catalina-exec-25:ctx-e47be587 ctx-4ac5f0e0) ===END===  10.215.2.19 -- GET  command=listZones&id=cce32d06-91d9-47f0-90b5-766416d6add2&response=json&sessionkey=gmTvf42GzX8UhbQt9iTNSIQN6yE%3D&_=1392333100970
2014-02-13 13:53:11,740 DEBUG [c.c.a.ApiServlet] (catalina-exec-20:ctx-a240485a) ===START===  10.215.2.19 -- GET  command=listPortForwardingRules&response=json&sessionkey=gmTvf42GzX8UhbQt9iTNSIQN6yE%3D&ipaddressid=fe78aa9f-7f82-4416-b214-bce21e4aca48&listAll=true&_=1392333101033
2014-02-13 13:53:11,751 DEBUG [c.c.a.ApiServlet] (catalina-exec-20:ctx-a240485a ctx-6ca0a0a4) ===END===  10.215.2.19 -- GET  command=listPortForwardingRules&response=json&sessionkey=gmTvf42GzX8UhbQt9iTNSIQN6yE%3D&ipaddressid=fe78aa9f-7f82-4416-b214-bce21e4aca48&listAll=true&_=1392333101033
2014-02-13 13:53:11,769 DEBUG [c.c.a.ApiServlet] (catalina-exec-10:ctx-d89ab182) ===START===  10.215.2.19 -- GET  command=listLoadBalancerRules&response=json&sessionkey=gmTvf42GzX8UhbQt9iTNSIQN6yE%3D&publicipid=fe78aa9f-7f82-4416-b214-bce21e4aca48&listAll=true&_=1392333101057
2014-02-13 13:53:11,779 DEBUG [c.c.a.ApiServlet] (catalina-exec-10:ctx-d89ab182 ctx-daf4f706) ===END===  10.215.2.19 -- GET  command=listLoadBalancerRules&response=json&sessionkey=gmTvf42GzX8



--
This message was sent by Atlassian JIRA
(v6.1.5#6160)