路由虚拟机无法正常迁移

[王耀锋 <wi...@hotmail.com>]

在cloudstack4.2.1中虚拟化程序为kvm，主存储是rbd，在高级区域创建的隔离网络能正常使用；但是手动迁移路由虚拟机的时候就就一直处于迁移状态， 但是可以在迁移主机上进入路由虚拟机，网络不能正常使用。管理节点后台日志并没有报错：2014-03-13 08:54:11,435 DEBUG [cloud.async.AsyncJobManagerImpl] (Job-Executor-12:job-4418 = [ 487826de-87d9-47fb-972f-4df8de301228 ]) Executing org.apache.cloudstack.api.command.admin.systemvm.MigrateSystemVMCmd for job-4418 = [ 487826de-87d9-47fb-972f-4df8de301228 ]
2014-03-13 08:54:11,433 DEBUG [cloud.async.AsyncJobManagerImpl] (catalina-exec-23:null) submit async job-4418 = [ 487826de-87d9-47fb-972f-4df8de301228 ], details: AsyncJobVO {id:4418, userId: 13, accountId: 2, sessionKey: null, instanceType: None, instanceId: null, cmd: org.apache.cloudstack.api.command.admin.systemvm.MigrateSystemVMCmd, cmdOriginator: null, cmdInfo: {"response":"json","sessionkey":"WMSV2QVpu/EAwUvFqWY1h5yjCII\u003d","virtualmachineid":"b4850d74-fb0b-45d1-b95c-3b1466f9cced","cmdEventType":"VM.MIGRATE","hostid":"d208ea1f-bf77-4fb6-a9e3-5dfe1a4c755a","ctxUserId":"13","httpmethod":"GET","_":"1394672041368","ctxAccountId":"2","ctxStartEventId":"17086"}, cmdVersion: 0, callbackType: 0, callbackAddress: null, status: 0, processStatus: 0, resultCode: 0, result: null, initMsid: 124705458927, completeMsid: null, lastUpdated: null, lastPolled: null, created: null}
2014-03-13 08:54:11,955 INFO  [cloud.vm.VirtualMachineManagerImpl] (Job-Executor-12:job-4418 = [ 487826de-87d9-47fb-972f-4df8de301228 ]) Migrating VM[DomainRouter|r-562-VM] to Dest[Zone(Id)-Pod(Id)-Cluster(Id)-Host(Id)-Storage(Volume(Id|Type-->Pool(Id))] : Dest[Zone(1)-Pod(1)-Cluster(2)-Host(12)-Storage()]
2014-03-13 08:54:11,981 DEBUG [cloud.network.NetworkModelImpl] (Job-Executor-12:job-4418 = [ 487826de-87d9-47fb-972f-4df8de301228 ]) Service SecurityGroup is not supported in the network id=280
2014-03-13 08:54:12,000 DEBUG [cloud.network.NetworkModelImpl] (Job-Executor-12:job-4418 = [ 487826de-87d9-47fb-972f-4df8de301228 ]) Service SecurityGroup is not supported in the network id=280
2014-03-13 08:54:12,088 DEBUG [cloud.storage.VolumeManagerImpl] (Job-Executor-12:job-4418 = [ 487826de-87d9-47fb-972f-4df8de301228 ]) Preparing 1 volumes for VM[DomainRouter|r-562-VM]
2014-03-13 08:54:12,221 DEBUG [agent.transport.Request] (Job-Executor-12:job-4418 = [ 487826de-87d9-47fb-972f-4df8de301228 ]) Seq 12-1805465199: Sending  { Cmd , MgmtId: 124705458927, via: 12, Ver: v1, Flags: 100111, [{"com.cloud.agent.api.PrepareForMigrationCommand":{"vm":{"id":562,"name":"r-562-VM","type":"DomainRouter","cpus":1,"minSpeed":500,"maxSpeed":500,"minRam":134217728,"maxRam":134217728,"arch":"x86_64","os":"Debian GNU/Linux 5.0 (32-bit)","bootArgs":"","rebootOnCrash":false,"enableHA":true,"limitCpuUse":false,"enableDynamicallyScaleVm":false,"vncPassword":"fc46a4e5fa37b288","params":{},"uuid":"b4850d74-fb0b-45d1-b95c-3b1466f9cced","disks":[{"data":{"org.apache.cloudstack.storage.to.VolumeObjectTO":{"uuid":"57a930f6-7885-4e36-a075-6c2528f6b9dc","volumeType":"ROOT","dataStore":{"org.apache.cloudstack.storage.to.PrimaryDataStoreTO":{"uuid":"980ffe35-14dd-3e41-a4bc-2f2d6df1be6c","id":223,"poolType":"RBD","host":"monitor.cluster2.jovaunn.com","path":"rbd","port":6789}},"name":"ROOT-562","size":276162048,"path":"0fc6bae1-b6ca-4aa4-81c6-9a4f192bd3b1","volumeId":708,"vmName":"r-562-VM","accountId":2,"format":"RAW","id":708,"hypervisorType":"KVM"}},"diskSeq":0,"type":"ROOT"}],"nics":[{"deviceId":0,"networkRateMbps":200,"defaultNic":false,"uuid":"6f543c4b-e2c1-4358-b205-f4853828ff46","ip":"10.1.1.1","netmask":"255.255.255.0","mac":"02:00:3e:7d:00:02","dns1":"202.101.172.35","dns2":"8.8.8.8","broadcastType":"Vlan","type":"Guest","broadcastUri":"vlan://2245","isolationUri":"vlan://2245","isSecurityGroupEnabled":false,"name":"publicnet"},{"deviceId":1,"networkRateMbps":-1,"defaultNic":false,"uuid":"2ce1dea9-cf22-4342-81b7-b1a9a840d819","ip":"169.254.1.44","netmask":"255.255.0.0","gateway":"169.254.0.1","mac":"0e:00:a9:fe:01:2c","broadcastType":"LinkLocal","type":"Control","isSecurityGroupEnabled":false},{"deviceId":2,"networkRateMbps":200,"defaultNic":true,"uuid":"c20991da-fc68-4d8a-8880-d0e6b7035fd0","ip":"192.168.0.249","netmask":"255.255.255.0","gateway":"192.168.0.1","mac":"06:cf:e4:00:00:29","dns1":"202.101.172.35","dns2":"8.8.8.8","broadcastType":"Vlan","type":"Public","broadcastUri":"vlan://untagged","isolationUri":"vlan://untagged","isSecurityGroupEnabled":false,"name":"publicnet"}]},"wait":0}}] }
2014-03-13 08:54:12,660 DEBUG [agent.transport.Request] (Job-Executor-12:job-4418 = [ 487826de-87d9-47fb-972f-4df8de301228 ]) Seq 12-1805465199: Received:  { Ans: , MgmtId: 124705458927, via: 12, Ver: v1, Flags: 110, { PrepareForMigrationAnswer } }
2014-03-13 08:54:12,778 DEBUG [cloud.capacity.CapacityManagerImpl] (Job-Executor-12:job-4418 = [ 487826de-87d9-47fb-972f-4df8de301228 ]) VM state transitted from :Running to Migrating with event: MigrationRequestedvm's original host id: 14 new host id: 12 host id before state transition: 14
2014-03-13 08:54:12,783 DEBUG [cloud.capacity.CapacityManagerImpl] (Job-Executor-12:job-4418 = [ 487826de-87d9-47fb-972f-4df8de301228 ]) Hosts's actual total CPU: 18616 and CPU after applying overprovisioning: 18616
2014-03-13 08:54:12,783 DEBUG [cloud.capacity.CapacityManagerImpl] (Job-Executor-12:job-4418 = [ 487826de-87d9-47fb-972f-4df8de301228 ]) We are allocating VM, increasing the used capacity of this host:12
2014-03-13 08:54:12,783 DEBUG [cloud.capacity.CapacityManagerImpl] (Job-Executor-12:job-4418 = [ 487826de-87d9-47fb-972f-4df8de301228 ]) Current Used CPU: 7448 , Free CPU:11168 ,Requested CPU: 500
2014-03-13 08:54:12,783 DEBUG [cloud.capacity.CapacityManagerImpl] (Job-Executor-12:job-4418 = [ 487826de-87d9-47fb-972f-4df8de301228 ]) Current Used RAM: 5100273664 , Free RAM:3273220096 ,Requested RAM: 134217728
2014-03-13 08:54:12,783 DEBUG [cloud.capacity.CapacityManagerImpl] (Job-Executor-12:job-4418 = [ 487826de-87d9-47fb-972f-4df8de301228 ]) CPU STATS after allocation: for host: 12, old used: 7448, old reserved: 0, actual total: 18616, total with overprovisioning: 18616; new used:7948, reserved:0; requested cpu:500,alloc_from_last:false
2014-03-13 08:54:12,783 DEBUG [cloud.capacity.CapacityManagerImpl] (Job-Executor-12:job-4418 = [ 487826de-87d9-47fb-972f-4df8de301228 ]) RAM STATS after allocation: for host: 12, old used: 5100273664, old reserved: 0, total: 8373493760; new used: 5234491392, reserved: 0; requested mem: 134217728,alloc_from_last:false
2014-03-13 08:54:12,841 DEBUG [agent.transport.Request] (Job-Executor-12:job-4418 = [ 487826de-87d9-47fb-972f-4df8de301228 ]) Seq 14-835465237: Sending  { Cmd , MgmtId: 124705458927, via: 14, Ver: v1, Flags: 100111, [{"com.cloud.agent.api.MigrateCommand":{"vmName":"r-562-VM","destIp":"192.168.2.15","hostGuid":"0b656c77-e099-3422-b1d8-12ec34e05508-LibvirtComputingResource","isWindows":false,"wait":0}}] }
2014-03-13 08:54:19,803 DEBUG [agent.transport.Request] (Job-Executor-12:job-4418 = [ 487826de-87d9-47fb-972f-4df8de301228 ]) Seq 14-835465237: Received:  { Ans: , MgmtId: 124705458927, via: 14, Ver: v1, Flags: 110, { MigrateAnswer } }
2014-03-13 08:54:19,920 DEBUG [cloud.network.NetworkModelImpl] (Job-Executor-12:job-4418 = [ 487826de-87d9-47fb-972f-4df8de301228 ]) Service SecurityGroup is not supported in the network id=280
2014-03-13 08:54:19,933 DEBUG [cloud.network.NetworkModelImpl] (Job-Executor-12:job-4418 = [ 487826de-87d9-47fb-972f-4df8de301228 ]) Service SecurityGroup is not supported in the network id=280
2014-03-13 08:54:19,934 DEBUG [cloud.capacity.CapacityManagerImpl] (Job-Executor-12:job-4418 = [ 487826de-87d9-47fb-972f-4df8de301228 ]) VM state transitted from :Migrating to Running with event: OperationSucceededvm's original host id: 14 new host id: 12 host id before state transition: 12
2014-03-13 08:54:19,938 DEBUG [cloud.capacity.CapacityManagerImpl] (Job-Executor-12:job-4418 = [ 487826de-87d9-47fb-972f-4df8de301228 ]) Hosts's actual total CPU: 18616 and CPU after applying overprovisioning: 18616
2014-03-13 08:54:19,938 DEBUG [cloud.capacity.CapacityManagerImpl] (Job-Executor-12:job-4418 = [ 487826de-87d9-47fb-972f-4df8de301228 ]) Hosts's actual total RAM: 8368939008 and RAM after applying overprovisioning: 8368939008
2014-03-13 08:54:19,938 DEBUG [cloud.capacity.CapacityManagerImpl] (Job-Executor-12:job-4418 = [ 487826de-87d9-47fb-972f-4df8de301228 ]) release cpu from host: 14, old used: 5400,reserved: 0, actual total: 18616, total with overprovisioning: 18616; new used: 4900,reserved:0; movedfromreserved: false,moveToReserveredfalse
2014-03-13 08:54:19,938 DEBUG [cloud.capacity.CapacityManagerImpl] (Job-Executor-12:job-4418 = [ 487826de-87d9-47fb-972f-4df8de301228 ]) release mem from host: 14, old used: 3355443200,reserved: 0, total: 8368939008; new used: 3221225472,reserved:0; movedfromreserved: false,moveToReserveredfalse
2014-03-13 08:54:19,986 DEBUG [agent.transport.Request] (Job-Executor-12:job-4418 = [ 487826de-87d9-47fb-972f-4df8de301228 ]) Seq 12-1805465201: Sending  { Cmd , MgmtId: 124705458927, via: 12, Ver: v1, Flags: 100011, [{"com.cloud.agent.api.CheckVirtualMachineCommand":{"vmName":"r-562-VM","wait":20}}] }
2014-03-13 08:54:20,035 DEBUG [agent.transport.Request] (Job-Executor-12:job-4418 = [ 487826de-87d9-47fb-972f-4df8de301228 ]) Seq 12-1805465201: Received:  { Ans: , MgmtId: 124705458927, via: 12, Ver: v1, Flags: 10, { CheckVirtualMachineAnswer } }
2014-03-13 08:54:20,131 DEBUG [cloud.async.AsyncJobManagerImpl] (Job-Executor-12:job-4418 = [ 487826de-87d9-47fb-972f-4df8de301228 ]) Complete async job-4418 = [ 487826de-87d9-47fb-972f-4df8de301228 ], jobStatus: 1, resultCode: 0, result: org.apache.cloudstack.api.response.SystemVmResponse@1d53d9f4
2014-03-13 08:54:20,172 DEBUG [cloud.async.AsyncJobManagerImpl] (Job-Executor-12:job-4418 = [ 487826de-87d9-47fb-972f-4df8de301228 ]) Done executing org.apache.cloudstack.api.command.admin.systemvm.MigrateSystemVMCmd for job-4418 = [ 487826de-87d9-47fb-972f-4df8de301228 ]
2014-03-13 08:54:20,670 DEBUG [cloud.async.AsyncJobManagerImpl] (catalina-exec-3:null) Async job-4418 = [ 487826de-87d9-47fb-972f-4df8de301228 ] completed
2014-03-13 08:54:23,684 DEBUG [cloud.async.AsyncJobManagerImpl] (catalina-exec-22:null) Async job-4418 = [ 487826de-87d9-47fb-972f-4df8de301228 ] completed
2014-03-13 08:54:26,674 DEBUG [cloud.async.AsyncJobManagerImpl] (catalina-exec-17:null) Async job-4418 = [ 487826de-87d9-47fb-972f-4df8de301228 ] completed
2014-03-13 08:54:29,642 DEBUG [cloud.async.AsyncJobManagerImpl] (catalina-exec-25:null) Async job-4418 = [ 487826de-87d9-47fb-972f-4df8de301228 ] completed
2014-03-13 08:54:32,675 DEBUG [cloud.async.AsyncJobManagerImpl] (catalina-exec-11:null) Async job-4418 = [ 487826de-87d9-47fb-972f-4df8de301228 ] completed
2014-03-13 08:54:35,642 DEBUG [cloud.async.AsyncJobManagerImpl] (catalina-exec-16:null) Async job-4418 = [ 487826de-87d9-47fb-972f-4df8de301228 ] completed
2014-03-13 08:54:39,916 DEBUG [cloud.async.AsyncJobManagerImpl] (catalina-exec-18:null) Async job-4418 = [ 487826de-87d9-47fb-972f-4df8de301228 ] completed
2014-03-13 08:54:41,642 DEBUG [cloud.async.AsyncJobManagerImpl] (catalina-exec-6:null) Async job-4418 = [ 487826de-87d9-47fb-972f-4df8de301228 ] completed
2014-03-13 08:54:44,644 DEBUG [cloud.async.AsyncJobManagerImpl] (catalina-exec-15:null) Async job-4418 = [ 487826de-87d9-47fb-972f-4df8de301228 ] completed
2014-03-13 08:54:47,643 DEBUG [cloud.async.AsyncJobManagerImpl] (catalina-exec-13:null) Async job-4418 = [ 487826de-87d9-47fb-972f-4df8de301228 ] completed
2014-03-13 08:54:50,644 DEBUG [cloud.async.AsyncJobManagerImpl] (catalina-exec-4:null) Async job-4418 = [ 487826de-87d9-47fb-972f-4df8de301228 ] completed
2014-03-13 08:54:53,643 DEBUG [cloud.async.AsyncJobManagerImpl] (catalina-exec-8:null) Async job-4418 = [ 487826de-87d9-47fb-972f-4df8de301228 ] completed
2014-03-13 08:54:56,644 DEBUG [cloud.async.AsyncJobManagerImpl] (catalina-exec-7:null) Async job-4418 = [ 487826de-87d9-47fb-972f-4df8de301228 ] completed
2014-03-13 08:54:59,647 DEBUG [cloud.async.AsyncJobManagerImpl] (catalina-exec-14:null) Async job-4418 = [ 487826de-87d9-47fb-972f-4df8de301228 ] completed
2014-03-13 08:55:02,684 DEBUG [cloud.async.AsyncJobManagerImpl] (catalina-exec-17:null) Async job-4418 = [ 487826de-87d9-47fb-972f-4df8de301228 ] completed
2014-03-13 08:55:05,672 DEBUG [cloud.async.AsyncJobManagerImpl] (catalina-exec-11:null) Async job-4418 = [ 487826de-87d9-47fb-972f-4df8de301228 ] completed
2014-03-13 08:55:08,645 DEBUG [cloud.async.AsyncJobManagerImpl] (catalina-exec-16:null) Async job-4418 = [ 487826de-87d9-47fb-972f-4df8de301228 ] completed
2014-03-13 08:55:11,674 DEBUG [cloud.async.AsyncJobManagerImpl] (catalina-exec-18:null) Async job-4418 = [ 487826de-87d9-47fb-972f-4df8de301228 ] completed
2014-03-13 08:55:14,645 DEBUG [cloud.async.AsyncJobManagerImpl] (catalina-exec-15:null) Async job-4418 = [ 487826de-87d9-47fb-972f-4df8de301228 ] completed
2014-03-13 08:55:17,671 DEBUG [cloud.async.AsyncJobManagerImpl] (catalina-exec-6:null) Async job-4418 = [ 487826de-87d9-47fb-972f-4df8de301228 ] completed
2014-03-13 08:55:20,703 DEBUG [cloud.async.AsyncJobManagerImpl] (catalina-exec-13:null) Async job-4418 = [ 487826de-87d9-47fb-972f-4df8de301228 ] completed
2014-03-13 08:55:23,646 DEBUG [cloud.async.AsyncJobManagerImpl] (catalina-exec-4:null) Async job-4418 = [ 487826de-87d9-47fb-972f-4df8de301228 ] completed
2014-03-13 08:55:26,675 DEBUG [cloud.async.AsyncJobManagerImpl] (catalina-exec-8:null) Async job-4418 = [ 487826de-87d9-47fb-972f-4df8de301228 ] completed
2014-03-13 08:55:29,675 DEBUG [cloud.async.AsyncJobManagerImpl] (catalina-exec-7:null) Async job-4418 = [ 487826de-87d9-47fb-972f-4df8de301228 ] completed
2014-03-13 08:55:32,648 DEBUG [cloud.async.AsyncJobManagerImpl] (catalina-exec-14:null) Async job-4418 = [ 487826de-87d9-47fb-972f-4df8de301228 ] completed
2014-03-13 08:55:35,700 DEBUG [cloud.async.AsyncJobManagerImpl] (catalina-exec-5:null) Async job-4418 = [ 487826de-87d9-47fb-972f-4df8de301228 ] completed
2014-03-13 08:55:38,686 DEBUG [cloud.async.AsyncJobManagerImpl] (catalina-exec-10:null) Async job-4418 = [ 487826de-87d9-47fb-972f-4df8de301228 ] completed
2014-03-13 08:55:41,653 DEBUG [cloud.async.AsyncJobManagerImpl] (catalina-exec-2:null) Async job-4418 = [ 487826de-87d9-47fb-972f-4df8de301228 ] completed
2014-03-13 08:55:44,687 DEBUG [cloud.async.AsyncJobManagerImpl] (catalina-exec-21:null) Async job-4418 = [ 487826de-87d9-47fb-972f-4df8de301228 ] completed
2014-03-13 08:55:47,694 DEBUG [cloud.async.AsyncJobManagerImpl] (catalina-exec-24:null) Async job-4418 = [ 487826de-87d9-47fb-972f-4df8de301228 ] completed
2014-03-13 08:55:50,653 DEBUG [cloud.async.AsyncJobManagerImpl] (catalina-exec-20:null) Async job-4418 = [ 487826de-87d9-47fb-972f-4df8de301228 ] completed
2014-03-13 08:55:53,653 DEBUG [cloud.async.AsyncJobManagerImpl] (catalina-exec-9:null) Async job-4418 = [ 487826de-87d9-47fb-972f-4df8de301228 ] completed
2014-03-13 08:55:56,653 DEBUG [cloud.async.AsyncJobManagerImpl] (catalina-exec-19:null) Async job-4418 = [ 487826de-87d9-47fb-972f-4df8de301228 ] completed
2014-03-13 08:55:59,672 DEBUG [cloud.async.AsyncJobManagerImpl] (catalina-exec-1:null) Async job-4418 = [ 487826de-87d9-47fb-972f-4df8de301228 ] completed这是什么原因，请大家分析下？