[ https://issues.apache.org/jira/browse/CLOUDSTACK-7327?page=com.atlassian.jira.plugin.system.issuetabpanels:comment-tabpanel&focusedCommentId=14105484#comment-14105484 ]
Loic Lambiel commented on CLOUDSTACK-7327: ------------------------------------------ Hi, the zone has been setup using the wizard (CS 4.0.0). We never used or set vlan tagging. We added several IP ranges using the wizard without issue on CS 4.0.0. As far as I remember the network config hasn't changed since the initial setup. We upgraded to 4.3 and faced this issue on new IP pool add. > Failed to deploy instance when using an IP from freshly added IP pool > --------------------------------------------------------------------- > > Key: CLOUDSTACK-7327 > URL: https://issues.apache.org/jira/browse/CLOUDSTACK-7327 > Project: CloudStack > Issue Type: Bug > Security Level: Public(Anyone can view this level - this is the > default.) > Components: KVM > Affects Versions: 4.3.0 > Environment: Cloudstack 4.3, Basic networking, KVM on ubuntu 12.04 > Reporter: Loic Lambiel > Assignee: Kishan Kavala > > Hi, > We have a case where an instance failed to deploy when it takes an IP from a > freshly added IP pool range. > Our setup: Cloudstack 4.3, Basic networking, KVM on Ubuntu 12.04. Our setup > is running since 4.0.0. > The workaround is to change the vlan_id field of the ip pool in the > cloud.vlan table from "EC2://untagged" to "untagged" > Below the logs: > *Management server:* > {quote} > ERROR [cloud.vm.VirtualMachineManagerImpl] (Job-Executor-13:ctx-3e34bc70 > ctx-b69247df) Failed to start instance > VM[User|VM-fe3812d7-9d3d-49d8-9692-d9a8c976 > 6c6f] > com.cloud.utils.exception.CloudRuntimeException: Unable to get answer that is > of class com.cloud.agent.api.StartAnswer > at com.cloud.agent.manager.Commands.getAnswer(Commands.java:80) > at > com.cloud.vm.VirtualMachineManagerImpl.orchestrateStart(VirtualMachineManagerImpl.java:992) > at > com.cloud.vm.VirtualMachineManagerImpl.advanceStart(VirtualMachineManagerImpl.java:761) > at > com.cloud.vm.VirtualMachineManagerImpl.start(VirtualMachineManagerImpl.java:601) > at > org.apache.cloudstack.engine.cloud.entity.api.VMEntityManagerImpl.deployVirtualMachine(VMEntityManagerImpl.java:228) > at > org.apache.cloudstack.engine.cloud.entity.api.VirtualMachineEntityImpl.deploy(VirtualMachineEntityImpl.java:207) > at > com.cloud.vm.UserVmManagerImpl.startVirtualMachine(UserVmManagerImpl.java:3581) > at > com.cloud.vm.UserVmManagerImpl.startVirtualMachine(UserVmManagerImpl.java:3161) > at > com.cloud.vm.UserVmManagerImpl.startVirtualMachine(UserVmManagerImpl.java:3147) > 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:606) > 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 > 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.$Proxy169.startVirtualMachine(Unknown Source) > at > org.apache.cloudstack.api.command.user.vm.DeployVMCmd.execute(DeployVMCmd.java:443) > 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:509) > 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.run(FutureTask.java:262) > at > java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145) > at > java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615) > at java.lang.Thread.run(Thread.java:744) > {quote} > *Agent:* > {quote} > [DEBUG] [2014-08-12T00:06:25.516+02:00] - Agent.java:450 - Request:Seq > 48-518389783: { Cmd , MgmtId: 90520742695270, via: 48, Ver: v1, Flags: > 100011, > [{"com.cloud.agent.api.StartCommand":{"vm":{"id":11397,"name":"i-161-11397-VM","type":"User","cpus":1,"minSpeed":732,"maxSpeed":2198,"minRam":536870912,"maxRam":536870912,"arch":"x86_64","os":"Other > PV > (64-bit)","bootArgs":"","rebootOnCrash":false,"enableHA":false,"limitCpuUse":false,"enableDynamicallyScaleVm":false,"vncPassword":"4dd550e02a904de5","params":{"memoryOvercommitRatio":"1","SSH.PublicKey":"ssh-rsa > > blabla","cpuOvercommitRatio":"3","Encrypted.Password":"blabla"},"uuid":"519d6ff0-f32d-4d44-8a0e-61116d7cf2bb","disks":[{"data":{"org.apache.cloudstack.storage.to.VolumeObjectTO":{"uuid":"1c6d5378-30da-4172-8cb0-e790a9175745","volumeType":"ROOT","dataStore":{"org.apache.cloudstack.storage.to.PrimaryDataStoreTO":{"uuid":"a9585123-829c-4972-a449-8b7f911c88ec","id":290,"poolType":"Filesystem","host":"10.16.36.49","path":"/var/lib/libvirt/images","port":0,"url":"Filesystem://10.16.36.49//var/lib/libvirt/images/?ROLE=Primary&STOREUUID=a9585123-829c-4972-a449-8b7f911c88ec"}},"name":"ROOT-11397","size":10737418240,"path":"1c6d5378-30da-4172-8cb0-e790a9175745","volumeId":11369,"vmName":"i-161-11397-VM","accountId":161,"format":"QCOW2","id":11369,"deviceId":0,"hypervisorType":"KVM"}},"diskSeq":0,"path":"1c6d5378-30da-4172-8cb0-e790a9175745","type":"ROOT","_details":{"managed":"false","storagePort":"0","storageHost":"10.16.36.49","volumeSize":"10737418240"}},{"data":{"org.apache.cloudstack.storage.to.TemplateObjectTO":{"id":0,"format":"ISO","accountId":0,"hvm":false}},"diskSeq":3,"type":"ISO"}],"nics":[{"deviceId":0,"networkRateMbps":-1,"defaultNic":true,"uuid":"a08d866b-732c-4a3f-8ddf-b6cb57fcb086","ip":"185.19.30.250","netmask":"255.255.252.0","gateway":"185.19.28.1","mac":"06:d4:6a:00:00:52","dns1":"80.245.17.229","dns2":"80.245.17.230","broadcastType":"Vlan","type":"Guest","isSecurityGroupEnabled":true,"name":"public"}]},"hostIp":"10.16.36.49","executeInSequence":false,"wait":0}}] > } > [DEBUG] [2014-08-12T00:06:25.516+02:00] - Agent.java:454 - Processing > command: com.cloud.agent.api.StartCommand > [DEBUG] [2014-08-12T00:06:25.551+02:00] - BridgeVifDriver.java:91 - > nic=[Nic:Guest-185.19.30.250-null] > [DEBUG] [2014-08-12T00:06:25.552+02:00] - KVMStoragePoolManager.java:174 - > Disconnecting disk 1c6d5378-30da-4172-8cb0-e790a9175745 > [WARN] [2014-08-12T00:06:25.562+02:00] - Agent.java:508 - Caught: > [DEBUG] [2014-08-12T00:06:25.568+02:00] - Agent.java:527 - Seq 48-518389783: > { Ans: , MgmtId: 90520742695270, via: 48, Ver: v1, Flags: 10, > [{"com.cloud.agent.api.Answer":{"result":false,"details":"java.lang.NullPointerException\n\tat > > com.cloud.network.Networks$BroadcastDomainType.getSchemeValue(Networks.java:173)\n\tat > > com.cloud.network.Networks$BroadcastDomainType.getValue(Networks.java:228)\n\tat > > com.cloud.hypervisor.kvm.resource.BridgeVifDriver.plug(BridgeVifDriver.java:99)\n\tat > > com.cloud.hypervisor.kvm.resource.LibvirtComputingResource.createVif(LibvirtComputingResource.java:3882)\n\tat > > com.cloud.hypervisor.kvm.resource.LibvirtComputingResource.createVifs(LibvirtComputingResource.java:3633)\n\tat > > com.cloud.hypervisor.kvm.resource.LibvirtComputingResource.execute(LibvirtComputingResource.java:3660)\n\tat > > com.cloud.hypervisor.kvm.resource.LibvirtComputingResource.executeRequest(LibvirtComputingResource.java:1307)\n\tat > com.cloud.agent.Agent.processRequest(Agent.java:498)\n\tat > com.cloud.agent.Agent$AgentRequestHandler.doTask(Agent.java:806)\n\tat > com.cloud.utils.nio.Task.run(Task.java:83)\n\tat > java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145)\n\tat > > java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)\n\tat > java.lang.Thread.run(Thread.java:744)\n","wait":0}}] } > [DEBUG] [2014-08-12T00:06:25.590+02:00] - Agent.java:450 - Request:Seq > 48-518389784: { Cmd , MgmtId: 90520742695270, via: 48, Ver: v1, Flags: > 100011, > [{"com.cloud.agent.api.StopCommand":{"isProxy":false,"executeInSequence":false,"vmName":"i-161-11397-VM","wait":0}}] > } > [DEBUG] [2014-08-12T00:06:25.590+02:00] - Agent.java:454 - Processing > command: com.cloud.agent.api.StopCommand > [DEBUG] [2014-08-12T00:06:25.597+02:00] - LibvirtConnection.java:69 - can't > find connection: KVM, for vm: i-161-11397-VM, continue > [DEBUG] [2014-08-12T00:06:25.601+02:00] - LibvirtConnection.java:69 - can't > find connection: LXC, for vm: i-161-11397-VM, continue > [DEBUG] [2014-08-12T00:06:25.601+02:00] - LibvirtConnection.java:73 - can't > find which hypervisor the vm used , then use the default hypervisor > [DEBUG] [2014-08-12T00:06:25.603+02:00] - LibvirtComputingResource.java:4938 > - Failed to get dom xml: org.libvirt.LibvirtException: Domain not found: no > domain with matching name 'i-161-11397-VM' > [DEBUG] [2014-08-12T00:06:25.605+02:00] - LibvirtComputingResource.java:4916 > - Failed to get dom xml: org.libvirt.LibvirtException: Domain not found: no > domain with matching name 'i-161-11397-VM' > [DEBUG] [2014-08-12T00:06:25.606+02:00] - LibvirtComputingResource.java:4916 > - Failed to get dom xml: org.libvirt.LibvirtException: Domain not found: no > domain with matching name 'i-161-11397-VM' > [DEBUG] [2014-08-12T00:06:25.607+02:00] - Script.java:168 - Executing: > /usr/share/cloudstack-common/scripts/vm/network/security_group.py > destroy_network_rules_for_vm --vmname i-161-11397-VM > DEBUG] [2014-08-12T00:06:29.308+02:00] - Script.java:200 - Execution is > successful. > [DEBUG] [2014-08-12T00:06:29.309+02:00] - LibvirtComputingResource.java:4666 > - Try to stop the vm at first > [DEBUG] [2014-08-12T00:06:29.311+02:00] - LibvirtComputingResource.java:4757 > - VM i-161-11397-VM doesn't exist, no need to stop it > [DEBUG] [2014-08-12T00:06:29.313+02:00] - Agent.java:527 - Seq 48-518389784: > { Ans: , MgmtId: 90520742695270, via: 48, Ver: v1, Flags: 10, > [{"com.cloud.agent.api.StopAnswer":{"result":true,"wait":0}}] } > [DEBUG] [2014-08-12T00:07:04.219+02:00] - Script.java:168 - Executing: > /usr/share/cloudstack-common/scripts/vm/network/security_group.py > get_rule_logs_for_vms > [DEBUG] [2014-08-12T00:07:04.536+02:00] - Agent.java:454 - Processing > command: com.cloud.agent.api.GetHostStatsCommand > [DEBUG] [2014-08-12T00:07:04.536+02:00] - Script.java:168 - Executing: > /bin/bash -c idle=$(top -b -n 1|grep Cpu\(s\):|cut -d% -f4|cut -d, -f2);echo > $idle > [DEBUG] [2014-08-12T00:07:05.076+02:00] - Script.java:200 - Execution is > successful. > [DEBUG] [2014-08-12T00:07:05.077+02:00] - Script.java:168 - Executing: > /bin/bash -c freeMem=$(free|grep cache:|awk '{print $4}');echo $freeMem > [DEBUG] [2014-08-12T00:07:05.084+02:00] - Script.java:200 - Execution is > successful. > [DEBUG] [2014-08-12T00:07:05.084+02:00] - Script.java:168 - Executing: > /bin/bash -c free|grep Mem:|awk '{print $2}' > [DEBUG] [2014-08-12T00:07:05.090+02:00] - Script.java:200 - Execution is > successful. > [DEBUG] [2014-08-12T00:07:07.825+02:00] - Script.java:200 - Execution is > successful. > {quote} -- This message was sent by Atlassian JIRA (v6.2#6252)