Parth Jagirdar created CLOUDSTACK-6070: ------------------------------------------
Summary: Contrail:Static NAT: Unable to create virtual-machine-interface Key: CLOUDSTACK-6070 URL: https://issues.apache.org/jira/browse/CLOUDSTACK-6070 Project: CloudStack Issue Type: Bug Security Level: Public (Anyone can view this level - this is the default.) Components: API, Contrail Affects Versions: 4.2.1 Environment: Contrail, Xen Reporter: Parth Jagirdar Priority: Critical Fix For: 4.2.1 Acquire IP, Enable Static NAT, Choose a VM and following exception is raised. Although NAt does get enabled. 2014-02-10 10:20:21,975 INFO [n.j.c.a.ApiConnector] (catalina-exec-11:ctx-8730afff ctx-f70574dc) >> 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-10 10:20:21,997 INFO [n.j.c.a.ApiConnector] (catalina-exec-11:ctx-8730afff ctx-f70574dc) << Response Status: HTTP/1.1 400 Bad Request 2014-02-10 10:20:21,997 ERROR [n.j.c.a.ApiConnector] (catalina-exec-11:ctx-8730afff ctx-f70574dc) create api request failed: Bad Request 2014-02-10 10:20:21,998 ERROR [n.j.c.a.ApiConnector] (catalina-exec-11:ctx-8730afff ctx-f70574dc) 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>'http://10.223.58.3:8082/virtual-machine-interfaces'</tt> caused an error:</p> <pre>Parent [u's-11-VM'] type virtual-machine does not exist</pre> </body> </html> 2014-02-10 10:20:21,998 WARN [o.a.c.n.c.m.ContrailManager] (catalina-exec-11:ctx-8730afff ctx-f70574dc) 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.createFloatingIp(ContrailManagerImpl.java:791) at org.apache.cloudstack.network.contrail.management.ContrailElementImpl.applyIps(ContrailElementImpl.java:345) at com.cloud.network.IpAddressManagerImpl.applyIpAssociations(IpAddressManagerImpl.java:976) at com.cloud.network.IpAddressManagerImpl.applyStaticNats(IpAddressManagerImpl.java:1756) at com.cloud.network.rules.RulesManagerImpl.applyStaticNatForIp(RulesManagerImpl.java:1324) at com.cloud.network.rules.RulesManagerImpl.enableStaticNat(RulesManagerImpl.java:602) at com.cloud.network.rules.RulesManagerImpl.enableStaticNat(RulesManagerImpl.java:446) 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.enableStaticNat(Unknown Source) at org.apache.cloudstack.api.command.user.nat.EnableStaticNatCmd.execute(EnableStaticNatCmd.java:129) at com.cloud.api.ApiDispatcher.dispatch(ApiDispatcher.java:161) at com.cloud.api.ApiServer.queueCommand(ApiServer.java:530) at com.cloud.api.ApiServer.handleRequest(ApiServer.java:373) at com.cloud.api.ApiServlet.processRequestInContext(ApiServlet.java:322) at com.cloud.api.ApiServlet.access$000(ApiServlet.java:52) at com.cloud.api.ApiServlet$1.run(ApiServlet.java:114) 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.ApiServlet.processRequest(ApiServlet.java:111) at com.cloud.api.ApiServlet.doGet(ApiServlet.java:73) at javax.servlet.http.HttpServlet.service(HttpServlet.java:617) at javax.servlet.http.HttpServlet.service(HttpServlet.java:717) at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:290) at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:206) at org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:233) at org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:191) at org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:127) at org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:102) at org.apache.catalina.valves.AccessLogValve.invoke(AccessLogValve.java:555) at org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:109) at org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:298) at org.apache.coyote.http11.Http11NioProcessor.process(Http11NioProcessor.java:889) at org.apache.coyote.http11.Http11NioProtocol$Http11ConnectionHandler.process(Http11NioProtocol.java:721) at org.apache.tomcat.util.net.NioEndpoint$SocketProcessor.run(NioEndpoint.java:2274) 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-10 10:20:22,007 INFO [n.j.c.a.ApiConnector] (catalina-exec-11:ctx-8730afff ctx-f70574dc) >> Request: POST, /fqname-to-id, {"type":"floating-ip-pool","fq_name":["default-domain","default-project","__default_Public__","PublicIpPool"]} 2014-02-10 10:20:22,012 INFO [n.j.c.a.ApiConnector] (catalina-exec-11:ctx-8730afff ctx-f70574dc) << Response Status: HTTP/1.1 404 Not Found 2014-02-10 10:20:22,015 INFO [n.j.c.a.ApiConnector] (catalina-exec-11:ctx-8730afff ctx-f70574dc) >> Request: POST, /floating-ip-pools, {"floating-ip-pool":{"name":"PublicIpPool","fq_name":["default-domain","default-project","__default_Public__","PublicIpPool"],"parent_type":"virtual-network"}} 2014-02-10 10:20:22,035 INFO [n.j.c.a.ApiConnector] (catalina-exec-11:ctx-8730afff ctx-f70574dc) << Response Status: HTTP/1.1 200 OK 2014-02-10 10:20:22,049 INFO [n.j.c.a.ApiConnector] (catalina-exec-11:ctx-8730afff ctx-f70574dc) >> Request: GET, /floating-ip/6a6fa311-e5a0-4dfb-b289-cbad2122a756 2014-02-10 10:20:22,054 INFO [n.j.c.a.ApiConnector] (catalina-exec-11:ctx-8730afff ctx-f70574dc) << Response Status: HTTP/1.1 404 Not Found 2014-02-10 10:20:22,062 INFO [n.j.c.a.ApiConnector] (catalina-exec-11:ctx-8730afff ctx-f70574dc) >> Request: POST, /floating-ips, {"floating-ip":{"floating_ip_address":"10.223.138.78","floating_ip_is_virtual_ip":false,"virtual_machine_interface_refs":[{"to":["i-2-37-VM","i-2-37-VM-0"],"attr":null,"href":null,"uuid":null}],"name":"4","uuid":"6a6fa311-e5a0-4dfb-b289-cbad2122a756","fq_name":["default-domain","default-project","__default_Public__","PublicIpPool","4"],"parent_type":"floating-ip-pool"}} 2014-02-10 10:20:22,077 INFO [n.j.c.a.ApiConnector] (catalina-exec-11:ctx-8730afff ctx-f70574dc) << Response Status: HTTP/1.1 200 OK 2014-02-10 10:20:22,078 DEBUG [o.a.c.n.c.m.ContrailElement] (catalina-exec-11:ctx-8730afff ctx-f70574dc) Successfully created floating ip: 10.223.138.78 2014-02-10 10:20:22,081 INFO [o.a.c.e.o.NetworkOrchestrator] (catalina-exec-11:ctx-8730afff ctx-f70574dc) Let ContrailElement handle StaticNat in network 205 2014-02-10 10:20:22,091 DEBUG [c.c.a.ApiServlet] (catalina-exec-11:ctx-8730afff ctx-f70574dc) ===END=== 10.215.2.19 -- GET command=enableStaticNat&response=json&sessionkey=sHulzWsJF6AFX%2BK6zhMU8MzvWis%3D&ipaddressid=6a6fa311-e5a0-4dfb-b289-cbad2122a756&virtualmachineid=e7561a28-7070-4926-8ac7-73946db98cbd&_=1392061126290 2014-02-10 10:20:26,565 DEBUG [c.c.a.m.AgentManagerImpl] (AgentManager-Handler-6:null) SeqA 3-43386: Processing Seq 3-43386: { Cmd , MgmtId: -1, via: 3, Ver: v1, Flags: 11, [{"com.cloud.agent.api.ConsoleProxyLoadReportCommand":{"_proxyVmId":1,"_loadInfo":"{\n \"connections\": [\n {\n \"id\": 4,\n \"clientInfo\": \"\",\n \"host\": \"10.223.58.66\",\n \"port\": -1,\n \"tag\": \"e7561a28-7070-4926-8ac7-73946db98cbd\",\n \"createTime\": 1392060836067,\n \"lastUsedTime\": 1392060845195\n }\n ]\n}","wait":0}}] } 2014-02-10 10:20:26,569 DEBUG [c.c.a.m.AgentManagerImpl] (AgentManager-Handler-6:null) SeqA 3-43386: Sending Seq 3-43386: { Ans: , MgmtId: 214151488957798, via: 3, Ver: v1, Flags: 100010, [{"com.cloud.agent.api.AgentControlAnswer":{"result":true,"wait":0}}] } -- This message was sent by Atlassian JIRA (v6.1.5#6160)