Thanks for your reply. I'm using 4.9.2.0. I'm posting the full log below!
The secondary storage is mounted as NFS. PS: i was able to start SSVM after creating a new zone, however, when I changed the IP range, the error came back again. 2017-07-31 13:23:15,950 DEBUG [c.c.a.t.Request] (Work-Job-Executor-6:ctx-e8c72967 job-131/job-272 ctx-06907041) (logid:7a78f881) Seq 1-6685312171854790673: Sending { Cmd , MgmtId: 119779289367307, via: 1(kvm-tqr-01.lhost.net.br), Ver: v1, Flags: 100011, [{"com.cloud.agent.api.StopCommand":{"isProxy":false,"checkBeforeCleanup":false,"vmName":"v-66-VM","executeInSequence":false,"wait":0}}] } 2017-07-31 13:23:16,153 DEBUG [c.c.a.t.Request] (AgentManager-Handler-7:null) (logid:) Seq 1-6685312171854790673: Processing: { Ans: , MgmtId: 119779289367307, via: 1, Ver: v1, Flags: 10, [{"com.cloud.agent.api.StopAnswer":{"result":true,"wait":0}}] } 2017-07-31 13:23:16,153 DEBUG [c.c.a.t.Request] (Work-Job-Executor-6:ctx-e8c72967 job-131/job-272 ctx-06907041) (logid:7a78f881) Seq 1-6685312171854790673: Received: { Ans: , MgmtId: 119779289367307, via: 1(kvm-tqr-01.lhost.net.br), Ver: v1, Flags: 10, { StopAnswer } } 2017-07-31 13:23:16,166 DEBUG [c.c.n.NetworkModelImpl] (Work-Job-Executor-6:ctx-e8c72967 job-131/job-272 ctx-06907041) (logid:7a78f881) Service SecurityGroup is not supported in the network id=204 2017-07-31 13:23:16,201 DEBUG [o.a.c.e.o.NetworkOrchestrator] (Work-Job-Executor-6:ctx-e8c72967 job-131/job-272 ctx-06907041) (logid:7a78f881) Asking VirtualRouter to release NicProfile[196-66-cee1cba9-7a24-44cf-a5c2-24f2e5dff1ae-null-null 2017-07-31 13:23:16,211 DEBUG [c.c.v.VirtualMachineManagerImpl] (Work-Job-Executor-6:ctx-e8c72967 job-131/job-272 ctx-06907041) (logid:7a78f881) Successfully released network resources for the vm VM[ConsoleProxy|v-66-VM] 2017-07-31 13:23:16,211 DEBUG [c.c.v.VirtualMachineManagerImpl] (Work-Job-Executor-6:ctx-e8c72967 job-131/job-272 ctx-06907041) (logid:7a78f881) Successfully cleanued up resources for the vm VM[ConsoleProxy|v-66-VM] in Starting state 2017-07-31 13:23:16,314 DEBUG [c.c.c.CapacityManagerImpl] (Work-Job-Executor-6:ctx-e8c72967 job-131/job-272 ctx-06907041) (logid:7a78f881) VM state transitted from :Starting to Stopped with event: OperationFailedvm's original host id: null new host id: null host id before state transition: 1 2017-07-31 13:23:16,324 DEBUG [c.c.c.CapacityManagerImpl] (Work-Job-Executor-6:ctx-e8c72967 job-131/job-272 ctx-06907041) (logid:7a78f881) Hosts's actual total CPU: 54432 and CPU after applying overprovisioning: 54432 2017-07-31 13:23:16,324 DEBUG [c.c.c.CapacityManagerImpl] (Work-Job-Executor-6:ctx-e8c72967 job-131/job-272 ctx-06907041) (logid:7a78f881) Hosts's actual total RAM: 7169134592 and RAM after applying overprovisioning: 7169134592 2017-07-31 13:23:16,325 DEBUG [c.c.c.CapacityManagerImpl] (Work-Job-Executor-6:ctx-e8c72967 job-131/job-272 ctx-06907041) (logid:7a78f881) release cpu from host: 1, old used: 500,reserved: 0, actual total: 54432, total with overprovisioning: 54432; new used: 0,reserved:0; movedfromreserved: false,moveToReserveredfalse 2017-07-31 13:23:16,325 DEBUG [c.c.c.CapacityManagerImpl] (Work-Job-Executor-6:ctx-e8c72967 job-131/job-272 ctx-06907041) (logid:7a78f881) release mem from host: 1, old used: 1073741824,reserved: 0, total: 7169134592; new used: 0,reserved:0; movedfromreserved: false,moveToReserveredfalse 2017-07-31 13:23:16,384 ERROR [c.c.v.VmWorkJobHandlerProxy] (Work-Job-Executor-6:ctx-e8c72967 job-131/job-272 ctx-06907041) (logid:7a78f881) Invocation exception, caused by: java.lang.NullPointerException 2017-07-31 13:23:16,384 INFO [c.c.v.VmWorkJobHandlerProxy] (Work-Job-Executor-6:ctx-e8c72967 job-131/job-272 ctx-06907041) (logid:7a78f881) Rethrow exception java.lang.NullPointerException 2017-07-31 13:23:16,384 DEBUG [c.c.v.VmWorkJobDispatcher] (Work-Job-Executor-6:ctx-e8c72967 job-131/job-272) (logid:7a78f881) Done with run of VM work job: com.cloud.vm.VmWorkStart for VM 66, job origin: 131 2017-07-31 13:23:16,384 ERROR [c.c.v.VmWorkJobDispatcher] (Work-Job-Executor-6:ctx-e8c72967 job-131/job-272) (logid:7a78f881) Unable to complete AsyncJobVO {id:272, userId: 1, accountId: 1, instanceType: null, instanceId: null, cmd: com.cloud.vm.VmWorkStart, cmdInfo: rO0ABXNyABhjb20uY2xvdWQudm0uVm1Xb3JrU3RhcnR9cMGsvxz73gIAC0oABGRjSWRMAAZhdm9pZHN0ADBMY29tL2Nsb3VkL2RlcGxveS9EZXBsb3ltZW50UGxhbm5lciRFeGNsdWRlTGlzdDtMAAljbHVzdGVySWR0ABBMamF2YS9sYW5nL0xvbmc7TAAGaG9zdElkcQB-AAJMAAtqb3VybmFsTmFtZXQAEkxqYXZhL2xhbmcvU3RyaW5nO0wAEXBoeXNpY2FsTmV0d29ya0lkcQB-AAJMAAdwbGFubmVycQB-AANMAAVwb2RJZHEAfgACTAAGcG9vbElkcQB-AAJMAAlyYXdQYXJhbXN0AA9MamF2YS91dGlsL01hcDtMAA1yZXNlcnZhdGlvbklkcQB-AAN4cgATY29tLmNsb3VkLnZtLlZtV29ya5-ZtlbwJWdrAgAESgAJYWNjb3VudElkSgAGdXNlcklkSgAEdm1JZEwAC2hhbmRsZXJOYW1lcQB-AAN4cAAAAAAAAAABAAAAAAAAAAEAAAAAAAAAQnQAGVZpcnR1YWxNYWNoaW5lTWFuYWdlckltcGwAAAAAAAAAAHBwcHBwcHBwcHA, cmdVersion: 0, status: IN_PROGRESS, processStatus: 0, resultCode: 0, result: null, initMsid: 119779289367307, completeMsid: null, lastUpdated: null, lastPolled: null, created: Mon Jul 31 13:23:14 BRT 2017}, job origin:131 java.lang.NullPointerException at com.cloud.network.guru.DirectPodBasedNetworkGuru$2.doInTransactionWithoutResult(DirectPodBasedNetworkGuru.java:177) at com.cloud.utils.db.TransactionCallbackWithExceptionNoReturn.doInTransaction(TransactionCallbackWithExceptionNoReturn.java:25) at com.cloud.utils.db.TransactionCallbackWithExceptionNoReturn.doInTransaction(TransactionCallbackWithExceptionNoReturn.java:21) at com.cloud.utils.db.Transaction.execute(Transaction.java:40) at com.cloud.network.guru.DirectPodBasedNetworkGuru.getIp(DirectPodBasedNetworkGuru.java:170) at com.cloud.network.guru.DirectPodBasedNetworkGuru.reserve(DirectPodBasedNetworkGuru.java:157) at org.apache.cloudstack.engine.orchestration.NetworkOrchestrator.prepareNic(NetworkOrchestrator.java:1350) at org.apache.cloudstack.engine.orchestration.NetworkOrchestrator.prepare(NetworkOrchestrator.java:1321) at com.cloud.vm.VirtualMachineManagerImpl.orchestrateStart(VirtualMachineManagerImpl.java:997) at com.cloud.vm.VirtualMachineManagerImpl.orchestrateStart(VirtualMachineManagerImpl.java:4605) 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 com.cloud.vm.VmWorkJobHandlerProxy.handleVmWorkJob(VmWorkJobHandlerProxy.java:107) at com.cloud.vm.VirtualMachineManagerImpl.handleVmWorkJob(VirtualMachineManagerImpl.java:4766) at com.cloud.vm.VmWorkJobDispatcher.runJob(VmWorkJobDispatcher.java:102) at org.apache.cloudstack.framework.jobs.impl.AsyncJobManagerImpl$5.runInContext(AsyncJobManagerImpl.java:554) 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 org.apache.cloudstack.framework.jobs.impl.AsyncJobManagerImpl$5.run(AsyncJobManagerImpl.java:502) at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:473) 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:748) 2017-07-31 13:23:16,388 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl] (Work-Job-Executor-6:ctx-e8c72967 job-131/job-272) (logid:7a78f881) Complete async job-272, jobStatus: FAILED, resultCode: 0, result: rO0ABXNyAB5qYXZhLmxhbmcuTnVsbFBvaW50ZXJFeGNlcHRpb25HpaGO_zHhuAIAAHhyABpqYXZhLmxhbmcuUnVudGltZUV4Y2VwdGlvbp5fBkcKNIPlAgAAeHIAE2phdmEubGFuZy5FeGNlcHRpb27Q_R8-GjscxAIAAHhyABNqYXZhLmxhbmcuVGhyb3dhYmxl1cY1Jzl3uMsDAARMAAVjYXVzZXQAFUxqYXZhL2xhbmcvVGhyb3dhYmxlO0wADWRldGFpbE1lc3NhZ2V0ABJMamF2YS9sYW5nL1N0cmluZztbAApzdGFja1RyYWNldAAeW0xqYXZhL2xhbmcvU3RhY2tUcmFjZUVsZW1lbnQ7TAAUc3VwcHJlc3NlZEV4Y2VwdGlvbnN0ABBMamF2YS91dGlsL0xpc3Q7eHBxAH4ACHB1cgAeW0xqYXZhLmxhbmcuU3RhY2tUcmFjZUVsZW1lbnQ7AkYqPDz9IjkCAAB4cAAAAB1zcgAbamF2YS5sYW5nLlN0YWNrVHJhY2VFbGVtZW50YQnFmiY23YUCAARJAApsaW5lTnVtYmVyTAAOZGVjbGFyaW5nQ2xhc3NxAH4ABUwACGZpbGVOYW1lcQB-AAVMAAptZXRob2ROYW1lcQB-AAV4cAAAALF0ADJjb20uY2xvdWQubmV0d29yay5ndXJ1LkRpcmVjdFBvZEJhc2VkTmV0d29ya0d1cnUkMnQAHkRpcmVjdFBvZEJhc2VkTmV0d29ya0d1cnUuamF2YXQAHGRvSW5UcmFuc2FjdGlvbldpdGhvdXRSZXN1bHRzcQB-AAsAAAAZdAA7Y29tLmNsb3VkLnV0aWxzLmRiLlRyYW5zYWN0aW9uQ2FsbGJhY2tXaXRoRXhjZXB0aW9uTm9SZXR1cm50AC1UcmFuc2FjdGlvbkNhbGxiYWNrV2l0aEV4Y2VwdGlvbk5vUmV0dXJuLmphdmF0AA9kb0luVHJhbnNhY3Rpb25zcQB-AAsAAAAVcQB-ABFxAH4AEnEAfgATc3EAfgALAAAAKHQAHmNvbS5jbG91ZC51dGlscy5kYi5UcmFuc2FjdGlvbnQAEFRyYW5zYWN0aW9uLmphdmF0AAdleGVjdXRlc3EAfgALAAAAqnQAMGNvbS5jbG91ZC5uZXR3b3JrLmd1cnUuRGlyZWN0UG9kQmFzZWROZXR3b3JrR3VydXEAfgAOdAAFZ2V0SXBzcQB-AAsAAACdcQB-ABpxAH4ADnQAB3Jlc2VydmVzcQB-AAsAAAVGdAA-b3JnLmFwYWNoZS5jbG91ZHN0YWNrLmVuZ2luZS5vcmNoZXN0cmF0aW9uLk5ldHdvcmtPcmNoZXN0cmF0b3J0ABhOZXR3b3JrT3JjaGVzdHJhdG9yLmphdmF0AApwcmVwYXJlTmljc3EAfgALAAAFKXEAfgAfcQB-ACB0AAdwcmVwYXJlc3EAfgALAAAD5XQAJmNvbS5jbG91ZC52bS5WaXJ0dWFsTWFjaGluZU1hbmFnZXJJbXBsdAAeVmlydHVhbE1hY2hpbmVNYW5hZ2VySW1wbC5qYXZhdAAQb3JjaGVzdHJhdGVTdGFydHNxAH4ACwAAEf1xAH4AJXEAfgAmcQB-ACdzcQB-AAv____-dAAkc3VuLnJlZmxlY3QuTmF0aXZlTWV0aG9kQWNjZXNzb3JJbXBsdAAdTmF0aXZlTWV0aG9kQWNjZXNzb3JJbXBsLmphdmF0AAdpbnZva2Uwc3EAfgALAAAAOXEAfgAqcQB-ACt0AAZpbnZva2VzcQB-AAsAAAArdAAoc3VuLnJlZmxlY3QuRGVsZWdhdGluZ01ldGhvZEFjY2Vzc29ySW1wbHQAIURlbGVnYXRpbmdNZXRob2RBY2Nlc3NvckltcGwuamF2YXEAfgAuc3EAfgALAAACXnQAGGphdmEubGFuZy5yZWZsZWN0Lk1ldGhvZHQAC01ldGhvZC5qYXZhcQB-AC5zcQB-AAsAAABrdAAiY29tLmNsb3VkLnZtLlZtV29ya0pvYkhhbmRsZXJQcm94eXQAGlZtV29ya0pvYkhhbmRsZXJQcm94eS5qYXZhdAAPaGFuZGxlVm1Xb3JrSm9ic3EAfgALAAASnnEAfgAlcQB-ACZxAH4AOHNxAH4ACwAAAGZ0ACBjb20uY2xvdWQudm0uVm1Xb3JrSm9iRGlzcGF0Y2hlcnQAGFZtV29ya0pvYkRpc3BhdGNoZXIuamF2YXQABnJ1bkpvYnNxAH4ACwAAAip0AD9vcmcuYXBhY2hlLmNsb3Vkc3RhY2suZnJhbWV3b3JrLmpvYnMuaW1wbC5Bc3luY0pvYk1hbmFnZXJJbXBsJDV0ABhBc3luY0pvYk1hbmFnZXJJbXBsLmphdmF0AAxydW5JbkNvbnRleHRzcQB-AAsAAAAxdAA-b3JnLmFwYWNoZS5jbG91ZHN0YWNrLm1hbmFnZWQuY29udGV4dC5NYW5hZ2VkQ29udGV4dFJ1bm5hYmxlJDF0ABtNYW5hZ2VkQ29udGV4dFJ1bm5hYmxlLmphdmF0AANydW5zcQB-AAsAAAA4dABCb3JnLmFwYWNoZS5jbG91ZHN0YWNrLm1hbmFnZWQuY29udGV4dC5pbXBsLkRlZmF1bHRNYW5hZ2VkQ29udGV4dCQxdAAaRGVmYXVsdE1hbmFnZWRDb250ZXh0LmphdmF0AARjYWxsc3EAfgALAAAAZ3QAQG9yZy5hcGFjaGUuY2xvdWRzdGFjay5tYW5hZ2VkLmNvbnRleHQuaW1wbC5EZWZhdWx0TWFuYWdlZENvbnRleHRxAH4ASHQAD2NhbGxXaXRoQ29udGV4dHNxAH4ACwAAADVxAH4AS3EAfgBIdAAOcnVuV2l0aENvbnRleHRzcQB-AAsAAAAudAA8b3JnLmFwYWNoZS5jbG91ZHN0YWNrLm1hbmFnZWQuY29udGV4dC5NYW5hZ2VkQ29udGV4dFJ1bm5hYmxlcQB-AERxAH4ARXNxAH4ACwAAAfZxAH4AP3EAfgBAcQB-AEVzcQB-AAsAAAHZdAAuamF2YS51dGlsLmNvbmN1cnJlbnQuRXhlY3V0b3JzJFJ1bm5hYmxlQWRhcHRlcnQADkV4ZWN1dG9ycy5qYXZhcQB-AElzcQB-AAsAAAEGdAAfamF2YS51dGlsLmNvbmN1cnJlbnQuRnV0dXJlVGFza3QAD0Z1dHVyZVRhc2suamF2YXEAfgBFc3EAfgALAAAEeXQAJ2phdmEudXRpbC5jb25jdXJyZW50LlRocmVhZFBvb2xFeGVjdXRvcnQAF1RocmVhZFBvb2xFeGVjdXRvci5qYXZhdAAJcnVuV29ya2Vyc3EAfgALAAACZ3QALmphdmEudXRpbC5jb25jdXJyZW50LlRocmVhZFBvb2xFeGVjdXRvciRXb3JrZXJxAH4AWnEAfgBFc3EAfgALAAAC7HQAEGphdmEubGFuZy5UaHJlYWR0AAtUaHJlYWQuamF2YXEAfgBFc3IAJmphdmEudXRpbC5Db2xsZWN0aW9ucyRVbm1vZGlmaWFibGVMaXN0_A8lMbXsjhACAAFMAARsaXN0cQB-AAd4cgAsamF2YS51dGlsLkNvbGxlY3Rpb25zJFVubW9kaWZpYWJsZUNvbGxlY3Rpb24ZQgCAy173HgIAAUwAAWN0ABZMamF2YS91dGlsL0NvbGxlY3Rpb247eHBzcgATamF2YS51dGlsLkFycmF5TGlzdHiB0h2Zx2GdAwABSQAEc2l6ZXhwAAAAAHcEAAAAAHhxAH4AZng 2017-07-28 0:15 GMT-03:00 Gabriel Beims Bräscher <gabrasc...@gmail.com>: > Hi Rodrigo, > > Which version of CloudStack are you using? Is there any exception stack in > the log? Are you using advanced or basic network? Is there any connection > problem? > > Are you using Swift as Secondary Storage? If yes, there is an open issue > "CLOUDSTACK-7443: Cannot launch SSVMs when using Swift as Secondary > Storage" (https://issues.apache.org/jira/browse/CLOUDSTACK-7443) > > Can you please review the log? Seems that there are missing parts from the > presented log. One example is the line "2017-07-27 22:30:00,253 DEBUG > [c.c.c.CapacityManagerImpl]". The full log should contain "VM state > transitted from :" + oldState + " to " + newState + " with event: " + event > + "vm's original host id: " + vm.getLastHostId() + " new host id: " + > vm.getHostId() + " host id before state transition: " + oldHostId". > > Cheers, > Gabriel. > > 2017-07-27 22:37 GMT-03:00 Rodrigo Baldasso <rodr...@loophost.com.br>: > > > Hi everyone, > > > > I'm trying to set up my cloudstack environment but despite my best > efforts, > > the system isn't starting the ssvm's.. on my agent log all i can see is: > > > > [kvm.resource.LibvirtConnection] (agentRequest-Handler-5:null) > > (logid:4acd7d3e) Can not find a connection for Instance v-52-VM. Assuming > > the default connection. > > > > On the manager console, this is the error: > > > > 2017-07-27 22:29:59,853 DEBUG [c.c.a.t.Request] > > (Work-Job-Executor-54:ctx-a9fdb60b job-169/job-546 ctx-23fba537) > > (logid:4acd7d3e) Seq 1-2810527642455900231: vm-tqr-01.lhost.net.br), > Ver: > > v1, Flags: 100011, > > [{"com.cloud.agent.api.StopCommand":{"isProxy":false, > > "checkBeforeCleanup":false,"vmName":"v-52-VM","executeI > > 2017-07-27 22:29:59,952 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl] > > (secstorage-1:ctx-48b34715) (logid:526bc485) Sync job-547 execution on > > object VmWorkJobQueue. > > 2017-07-27 22:30:00,042 DEBUG [c.c.a.t.Request] > > (AgentManager-Handler-7:null) (logid:) Seq 1-2810527642455900231: > > Processing: { Ans: , MgmtId: > > 1197792893673.api.StopAnswer":{"result":true,"wait":0}}] } > > 2017-07-27 22:30:00,043 DEBUG [c.c.a.t.Request] > > (Work-Job-Executor-54:ctx-a9fdb60b job-169/job-546 ctx-23fba537) > > (logid:4acd7d3e) Seq 1-2810527642455900231: 1(kvm-tqr-01.lhost.net.br), > > Ver: v1, Flags: 10, { StopAnswer } } > > 2017-07-27 22:30:00,185 DEBUG [o.a.c.e.o.NetworkOrchestrator] > > (Work-Job-Executor-54:ctx-a9fdb60b job-169/job-546 ctx-23fba537) > > (logid:4acd7d3e) Asking Virtua-42a5-a288-2582921a1946-null-null > > 2017-07-27 22:30:00,185 DEBUG [o.a.c.e.o.NetworkOrchestrator] > > (Work-Job-Executor-54:ctx-a9fdb60b job-169/job-546 ctx-23fba537) > > (logid:4acd7d3e) Asking Securi129-658b-42a5-a288-2582921a1946-null-null > > 2017-07-27 22:30:00,189 DEBUG [c.c.v.VirtualMachineManagerImpl] > > (Work-Job-Executor-54:ctx-a9fdb60b job-169/job-546 ctx-23fba537) > > (logid:4acd7d3e) SuccessfullProxy|v-52-VM] > > 2017-07-27 22:30:00,190 DEBUG [c.c.v.VirtualMachineManagerImpl] > > (Work-Job-Executor-54:ctx-a9fdb60b job-169/job-546 ctx-23fba537) > > (logid:4acd7d3e) Successfull|v-52-VM] in Starting state > > 2017-07-27 22:30:00,253 DEBUG [c.c.c.CapacityManagerImpl] > > (Work-Job-Executor-54:ctx-a9fdb60b job-169/job-546 ctx-23fba537) > > (logid:4acd7d3e) VM state transittFailedvm's original host id: null new > > host id: null host id before state transition: 1 > > 2017-07-27 22:30:00,259 DEBUG [c.c.c.CapacityManagerImpl] > > (Work-Job-Executor-54:ctx-a9fdb60b job-169/job-546 ctx-23fba537) > > (logid:4acd7d3e) Hosts's actual toning: 54432 > > >