系统虚拟机无法创建成功
各位： 我的环境是CS4.0.1+kvm，区域可以创建成功，可是系统虚拟机一直无法创建成功，一直创建删除、创建删除。 agent.log的日志为： 2013-11-30 23:01:08,723 WARN [kvm.resource.LibvirtComputingResource] (agentRequest-Handler-1:null) Exception com.cloud.utils.exception.CloudRuntimeException: org.libvirt.LibvirtException: Storage volume not found: no storage vol with matching name 'dd324d10-c822-4c1b-a3d5-bfb0b84e323c' at com.cloud.hypervisor.kvm.storage.LibvirtStorageAdaptor.getVolume(LibvirtStorageAdaptor.java:90) at com.cloud.hypervisor.kvm.storage.LibvirtStorageAdaptor.getPhysicalDisk(LibvirtStorageAdaptor.java:404) at com.cloud.hypervisor.kvm.storage.LibvirtStoragePool.getPhysicalDisk(LibvirtStoragePool.java:113) at com.cloud.hypervisor.kvm.resource.LibvirtComputingResource.createVbd(LibvirtComputingResource.java:2888) at com.cloud.hypervisor.kvm.resource.LibvirtComputingResource.execute(LibvirtComputingResource.java:2808) at com.cloud.hypervisor.kvm.resource.LibvirtComputingResource.executeRequest(LibvirtComputingResource.java:1035) at com.cloud.agent.Agent.processRequest(Agent.java:518) at com.cloud.agent.Agent$AgentRequestHandler.doTask(Agent.java:831) at com.cloud.utils.nio.Task.run(Task.java:83) 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) 2013-11-30 23:01:08,724 WARN [cloud.agent.Agent] (agentRequest-Handler-1:null) Caught: java.lang.NullPointerException at com.cloud.hypervisor.kvm.resource.LibvirtComputingResource.cleanupVMNetworks(LibvirtComputingResource.java:3922) at com.cloud.hypervisor.kvm.resource.LibvirtComputingResource.handleVmStartFailure(LibvirtComputingResource.java:2709) at com.cloud.hypervisor.kvm.resource.LibvirtComputingResource.execute(LibvirtComputingResource.java:2834) at com.cloud.hypervisor.kvm.resource.LibvirtComputingResource.executeRequest(LibvirtComputingResource.java:1035) at com.cloud.agent.Agent.processRequest(Agent.java:518) at com.cloud.agent.Agent$AgentRequestHandler.doTask(Agent.java:831) at com.cloud.utils.nio.Task.run(Task.java:83) 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) libvirtd.log 中的错误日志： 2013-11-30 14:45:28.379+: 22402: error : virFileOpenForceOwnerMode:807 : cannot chown '/mnt/3c812bf5-0e22-3263-b902-4a65532af2e3/s-28-VM-patchdisk' to (0, 0): Invalid argument 2013-11-30 14:45:28.379+: 22402: error : virStorageBackendCreateRaw:412 : cannot create path '/mnt/3c812bf5-0e22-3263-b902-4a65532af2e3/s-28-VM-patchdisk': Invalid argument 2013-11-30 14:45:28.494+: 22400: error : virStorageBackendVolOpenCheckMode:1049 : cannot open volume '/mnt/3c812bf5-0e22-3263-b902-4a65532af2e3/45052b17-61e9-4387-af47-9c4a756755a5': No such file or directory 2013-11-30 14:50:01.984+: 22403: error : virStorageBackendFileSystemRefresh:823 : cannot open path '/mnt/3c812bf5-0e22-3263-b902-4a65532af2e3': No such file or directory 2013-11-30 14:50:48.322+: 22400: error : virCommandWait:2272 : internal error Child process (/bin/mount 192.168.1.165:/primary /mnt/3c812bf5-0e22-3263-b902-4a65532af2e3) unexpected exit status 1: mount.nfs: mount point /mnt/3c812bf5-0e22-3263-b902-4a65532af2e3 does not exist 2013-11-30 14:50:48.322+: 22400: error : virStorageBackendFileSystemRefresh:823 : cannot open path '/mnt/3c812bf5-0e22-3263-b902-4a65532af2e3': No such file or directory 2013-11-30 14:50:48.400+: 22402: error : virCommandWait:2272 : internal error Child process (/bin/mount 192.168.1.165:/primary /mnt/3c812bf5-0e22-3263-b902-4a65532af2e3) unexpected exit status 1: mount.nfs: mount point /mnt/3c812bf5-0e22-3263-b902-4a65532af2e3 does not exist 2013-11-30 14:50:48.400+: 22402: error : virStorageBackendFileSystemRefresh:823 : cannot open path '/mnt/3c812bf5-0e22-3263-b902-4a65532af2e3': No such file or directory houqd2...@163.com
Re: 系统虚拟机无法创建成功
从libvirtd的log来看，有可能是存储有问题，比如权限之类的，建不了目录。 CloudStack的log，你要在exception这个关键字前面再看看有没有报错原因。 刘宇超 Richard Liu 发件人： houqd2...@163.com 发送时间： 2013-11-30 23:04:12 收件人： users-cn 抄送： 主题： 系统虚拟机无法创建成功 各位： 我的环境是CS4.0.1+kvm，区域可以创建成功，可是系统虚拟机一直无法创建成功，一直创建删除、创建删除。 agent.log的日志为： 2013-11-30 23:01:08,723 WARN [kvm.resource.LibvirtComputingResource] (agentRequest-Handler-1:null) Exception com.cloud.utils.exception.CloudRuntimeException: org.libvirt.LibvirtException: Storage volume not found: no storage vol with matching name 'dd324d10-c822-4c1b-a3d5-bfb0b84e323c' at com.cloud.hypervisor.kvm.storage.LibvirtStorageAdaptor.getVolume(LibvirtStorageAdaptor.java:90) at com.cloud.hypervisor.kvm.storage.LibvirtStorageAdaptor.getPhysicalDisk(LibvirtStorageAdaptor.java:404) at com.cloud.hypervisor.kvm.storage.LibvirtStoragePool.getPhysicalDisk(LibvirtStoragePool.java:113) at com.cloud.hypervisor.kvm.resource.LibvirtComputingResource.createVbd(LibvirtComputingResource.java:2888) at com.cloud.hypervisor.kvm.resource.LibvirtComputingResource.execute(LibvirtComputingResource.java:2808) at com.cloud.hypervisor.kvm.resource.LibvirtComputingResource.executeRequest(LibvirtComputingResource.java:1035) at com.cloud.agent.Agent.processRequest(Agent.java:518) at com.cloud.agent.Agent$AgentRequestHandler.doTask(Agent.java:831) at com.cloud.utils.nio.Task.run(Task.java:83) 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) 2013-11-30 23:01:08,724 WARN [cloud.agent.Agent] (agentRequest-Handler-1:null) Caught: java.lang.NullPointerException at com.cloud.hypervisor.kvm.resource.LibvirtComputingResource.cleanupVMNetworks(LibvirtComputingResource.java:3922) at com.cloud.hypervisor.kvm.resource.LibvirtComputingResource.handleVmStartFailure(LibvirtComputingResource.java:2709) at com.cloud.hypervisor.kvm.resource.LibvirtComputingResource.execute(LibvirtComputingResource.java:2834) at com.cloud.hypervisor.kvm.resource.LibvirtComputingResource.executeRequest(LibvirtComputingResource.java:1035) at com.cloud.agent.Agent.processRequest(Agent.java:518) at com.cloud.agent.Agent$AgentRequestHandler.doTask(Agent.java:831) at com.cloud.utils.nio.Task.run(Task.java:83) 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) libvirtd.log 中的错误日志： 2013-11-30 14:45:28.379+: 22402: error : virFileOpenForceOwnerMode:807 : cannot chown '/mnt/3c812bf5-0e22-3263-b902-4a65532af2e3/s-28-VM-patchdisk' to (0, 0): Invalid argument 2013-11-30 14:45:28.379+: 22402: error : virStorageBackendCreateRaw:412 : cannot create path '/mnt/3c812bf5-0e22-3263-b902-4a65532af2e3/s-28-VM-patchdisk': Invalid argument 2013-11-30 14:45:28.494+: 22400: error : virStorageBackendVolOpenCheckMode:1049 : cannot open volume '/mnt/3c812bf5-0e22-3263-b902-4a65532af2e3/45052b17-61e9-4387-af47-9c4a756755a5': No such file or directory 2013-11-30 14:50:01.984+: 22403: error : virStorageBackendFileSystemRefresh:823 : cannot open path '/mnt/3c812bf5-0e22-3263-b902-4a65532af2e3': No such file or directory 2013-11-30 14:50:48.322+: 22400: error : virCommandWait:2272 : internal error Child process (/bin/mount 192.168.1.165:/primary /mnt/3c812bf5-0e22-3263-b902-4a65532af2e3) unexpected exit status 1: mount.nfs: mount point /mnt/3c812bf5-0e22-3263-b902-4a65532af2e3 does not exist 2013-11-30 14:50:48.322+: 22400: error : virStorageBackendFileSystemRefresh:823 : cannot open path '/mnt/3c812bf5-0e22-3263-b902-4a65532af2e3': No such file or directory 2013-11-30 14:50:48.400+: 22402: error : virCommandWait:2272 : internal error Child process (/bin/mount 192.168.1.165:/primary /mnt/3c812bf5-0e22-3263-b902-4a65532af2e3) unexpected exit status 1: mount.nfs: mount point /mnt/3c812bf5-0e22-3263-b902-4a65532af2e3 does not exist 2013-11-30 14:50:48.400+: 22402: error : virStorageBackendFileSystemRefresh:823 : cannot open path '/mnt/3c812bf5-0e22-3263-b902-4a65532af2e3': No such file or directory houqd2...@163.com
回复: Re: 系统虚拟机无法创建成功
ic[7-2-8c7b1a03-842a-46c7-bedc-63f275cdd83a-null] 2013-12-02 11:07:43,690 DEBUG [cloud.vm.VirtualMachineManagerImpl] (consoleproxy-1:null) Successfully released network resources for the vm VM[ConsoleProxy|v-2-VM] 2013-12-02 11:07:43,690 DEBUG [cloud.vm.VirtualMachineManagerImpl] (consoleproxy-1:null) Successfully cleanued up resources for the vm VM[ConsoleProxy|v-2-VM] in Starting state 2013-12-02 11:07:43,699 DEBUG [cloud.capacity.CapacityManagerImpl] (consoleproxy-1:null) 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 2013-12-02 11:07:43,705 DEBUG [cloud.capacity.CapacityManagerImpl] (consoleproxy-1:null) Hosts's actual total CPU: 64000 and CPU after applying overprovisioning: 64000 2013-12-02 11:07:43,705 DEBUG [cloud.capacity.CapacityManagerImpl] (consoleproxy-1:null) release cpu from host: 1, old used: 500,reserved: 0, actual total: 64000, total with overprovisioning: 64000; new used: 0,reserved:0; movedfromreserved: false,moveToReserveredfalse 2013-12-02 11:07:43,705 DEBUG [cloud.capacity.CapacityManagerImpl] (consoleproxy-1:null) release mem from host: 1, old used: 1073741824,reserved: 0, total: 270832836608; new used: 0,reserved:0; movedfromreserved: false,moveToReserveredfalse 2013-12-02 11:07:43,708 WARN [cloud.consoleproxy.ConsoleProxyManagerImpl] (consoleproxy-1:null) Exception while trying to start console proxy com.cloud.exception.AgentUnavailableException: Resource [Host:1] is unreachable: Host 1: Unable to start instance due to Unable to get answer that is of class com.cloud.agent.api.StartAnswer at com.cloud.vm.VirtualMachineManagerImpl.advanceStart(VirtualMachineManagerImpl.java:847) at com.cloud.vm.VirtualMachineManagerImpl.start(VirtualMachineManagerImpl.java:472) at com.cloud.vm.VirtualMachineManagerImpl.start(VirtualMachineManagerImpl.java:465) at com.cloud.consoleproxy.ConsoleProxyManagerImpl.startProxy(ConsoleProxyManagerImpl.java:627) at com.cloud.consoleproxy.ConsoleProxyManagerImpl.allocCapacity(ConsoleProxyManagerImpl.java:1164) at com.cloud.consoleproxy.ConsoleProxyManagerImpl.expandPool(ConsoleProxyManagerImpl.java:1981) at com.cloud.consoleproxy.ConsoleProxyManagerImpl.expandPool(ConsoleProxyManagerImpl.java:173) at com.cloud.vm.SystemVmLoadScanner.loadScan(SystemVmLoadScanner.java:113) at com.cloud.vm.SystemVmLoadScanner.access$100(SystemVmLoadScanner.java:34) at com.cloud.vm.SystemVmLoadScanner$1.reallyRun(SystemVmLoadScanner.java:83) at com.cloud.vm.SystemVmLoadScanner$1.run(SystemVmLoadScanner.java:73) at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:471) at java.util.concurrent.FutureTask$Sync.innerRunAndReset(FutureTask.java:351) at java.util.concurrent.FutureTask.runAndReset(FutureTask.java:178) at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$201(ScheduledThreadPoolExecutor.java:165) at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:267) 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) Caused by: 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.advanceStart(VirtualMachineManagerImpl.java:783) ... 19 more 2013-12-02 11:07:57,947 DEBUG [cloud.server.StatsCollector] (StatsCollector-2:null) VmStatsCollector is running... houqd2...@163.com 发件人： tanthalas 发送时间： 2013-12-01 21:26 收件人： users-cn 主题： Re: 系统虚拟机无法创建成功 从libvirtd的log来看，有可能是存储有问题，比如权限之类的，建不了目录。 CloudStack的log，你要在exception这个关键字前面再看看有没有报错原因。 刘宇超 Richard Liu 发件人： houqd2...@163.com 发送时间： 2013-11-30 23:04:12 收件人： users-cn 抄送： 主题： 系统虚拟机无法创建成功 各位： 我的环境是CS4.0.1+kvm，区域可以创建成功，可是系统虚拟机一直无法创建成功，一直创建删除、创建删除。 agent.log的日志为： 2013-11-30 23:01:08,723 WARN [kvm.resource.LibvirtComputingResource] (agentRequest-Handler-1:null) Exception com.cloud.utils.exception.CloudRuntimeException: org.libvirt.LibvirtException: Storage volume not found: no storage vol with matching name 'dd324d10-c822-4c1b-a3d5-bfb0b84e323c' at com.cloud.hypervisor.kvm.storage.LibvirtStorageAdaptor.getVolume(LibvirtStorageAdaptor.java:90) at com.cloud.hypervisor.kvm.storage.LibvirtStorageAdaptor.getPhysicalDisk(LibvirtStorageAdaptor.java:404) at com.cloud.hypervisor.kvm.storage.LibvirtStoragePool.getPhysicalDisk(LibvirtStoragePool.java:113) at com.cloud.hypervisor.kvm.resource.LibvirtComputingResource.createVbd(LibvirtComputingResource.java:2888) at com.cloud.hypervisor.kvm.resource.LibvirtComputingResource.execute(LibvirtComputingResource.java:2808) at com.cloud.hypervisor.kvm.resource.LibvirtComputingResource.executeRequest(LibvirtComputingResource.jav
Re: 回复: Re: 系统虚拟机无法创建成功
这log，看着太费劲了，没有找到确切的原因，分析大概是你在安装配置过程中还有些错误。 建议把KVM节点重装一下，重新加入吧。 刘宇超 Richard Liu 发件人： houqd2...@163.com 发送时间： 2013-12-02 11:11:09 收件人： users-cn 抄送： 主题： 回复: Re: 系统虚拟机无法创建成功 各位： 之前的确是因为nfs存储共享的权限问题，修改之后在kvm宿主机端可以正常挂载，而且目录权限也是正确的，但是现在系统虚拟机仍然是创建、删除、创建、删除。。。恳请各位帮忙分析替下。 kvm宿主机上/var/log/cloud/agent/agent.log中的日志为： 2013-12-02 11:05:13,313 WARN [kvm.resource.LibvirtComputingResource] (agentRequest-Handler-5:null) Exception com.cloud.utils.exception.CloudRuntimeException: org.libvirt.LibvirtException: Storage volume not found: no storage vol with matching name 'dd324d10-c822-4c1b-a3d5-bfb0b84e323c' at com.cloud.hypervisor.kvm.storage.LibvirtStorageAdaptor.getVolume(LibvirtStorageAdaptor.java:90) at com.cloud.hypervisor.kvm.storage.LibvirtStorageAdaptor.getPhysicalDisk(LibvirtStorageAdaptor.java:404) at com.cloud.hypervisor.kvm.storage.LibvirtStoragePool.getPhysicalDisk(LibvirtStoragePool.java:113) at com.cloud.hypervisor.kvm.resource.LibvirtComputingResource.createVbd(LibvirtComputingResource.java:2888) at com.cloud.hypervisor.kvm.resource.LibvirtComputingResource.execute(LibvirtComputingResource.java:2808) at com.cloud.hypervisor.kvm.resource.LibvirtComputingResource.executeRequest(LibvirtComputingResource.java:1035) at com.cloud.agent.Agent.processRequest(Agent.java:518) at com.cloud.agent.Agent$AgentRequestHandler.doTask(Agent.java:831) at com.cloud.utils.nio.Task.run(Task.java:83) 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) 2013-12-02 11:05:13,314 WARN [cloud.agent.Agent] (agentRequest-Handler-5:null) Caught: java.lang.NullPointerException at com.cloud.hypervisor.kvm.resource.LibvirtComputingResource.cleanupVMNetworks(LibvirtComputingResource.java:3922) at com.cloud.hypervisor.kvm.resource.LibvirtComputingResource.handleVmStartFailure(LibvirtComputingResource.java:2709) at com.cloud.hypervisor.kvm.resource.LibvirtComputingResource.execute(LibvirtComputingResource.java:2834) at com.cloud.hypervisor.kvm.resource.LibvirtComputingResource.executeRequest(LibvirtComputingResource.java:1035) at com.cloud.agent.Agent.processRequest(Agent.java:518) at com.cloud.agent.Agent$AgentRequestHandler.doTask(Agent.java:831) at com.cloud.utils.nio.Task.run(Task.java:83) 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) 一直是这两段交互。 管理端/var/log/cloud/management/management-server.log中的日志为： 2013-12-02 11:07:43,007 DEBUG [cloud.vm.VirtualMachineManagerImpl] (secstorage-1:null) Cleaning up NICS 2013-12-02 11:07:43,008 DEBUG [cloud.network.NetworkManagerImpl] (secstorage-1:null) Cleaning network for vm: 2206 2013-12-02 11:07:43,012 INFO [cloud.consoleproxy.ConsoleProxyManagerImpl] (consoleproxy-1:null) Found a stopped console proxy, bring it up to running pool. proxy vm id : 2 2013-12-02 11:07:43,025 DEBUG [network.guru.DirectNetworkGuru] (secstorage-1:null) Deallocate network: networkId: 204, ip: null 2013-12-02 11:07:43,026 DEBUG [cloud.capacity.CapacityManagerImpl] (consoleproxy-1:null) VM state transitted from :Stopped to Starting with event: StartRequestedvm's original host id: null new host id: null host id before state transition: null 2013-12-02 11:07:43,026 DEBUG [cloud.vm.VirtualMachineManagerImpl] (consoleproxy-1:null) Successfully transitioned to start state for VM[ConsoleProxy|v-2-VM] reservation id = 8c7b1a03-842a-46c7-bedc-63f275cdd83a 2013-12-02 11:07:43,033 DEBUG [cloud.network.NetworkManagerImpl] (secstorage-1:null) Removed nic id=8820 2013-12-02 11:07:43,033 DEBUG [cloud.vm.VirtualMachineManagerImpl] (consoleproxy-1:null) Trying to deploy VM, vm has dcId: 1 and podId: 1 2013-12-02 11:07:43,033 DEBUG [cloud.vm.VirtualMachineManagerImpl] (consoleproxy-1:null) Deploy avoids pods: null, clusters: null, hosts: null 2013-12-02 11:07:43,038 DEBUG [cloud.vm.VirtualMachineManagerImpl] (consoleproxy-1:null) Root volume is ready, need to place VM in volume's cluster 2013-12-02 11:07:43,039 DEBUG [cloud.vm.VirtualMachineManagerImpl] (consoleproxy-1:null) Vol[2|vm=2|ROOT] is READY, changing deployment plan to use this pool's dcId: 1 , podId: 1 , and clusterId: 1 2013-12-02 11:07:43,047 DEBUG [cloud.deploy.FirstFitPlanner] (consoleproxy-1:null) DeploymentPlanner allocation algorithm: random 2013-12-02 11:07:43,048 DEBUG [cloud.deploy.FirstFitPlanner] (consoleproxy-1:null) Trying to allocate a host and storage pools from dc:1, pod:1,cluster:1, requested cpu: 500, requested ram: 1073741824 2013-12-02 11:07:43,048 DEBUG [cloud.deploy.FirstFitPlanner] (consoleproxy-1:null) Is ROOT volume READY (pool already allocated)?: Yes 2013-12-02 11:07:43,048 DEBUG [clo
回复: 回复: 系统虚拟机无法创建成功
很可能是Agent没有正常启动，你试试登录管理端修改Agent的配置，实在不行就重装吧 evanitsharp 发件人： houqd2...@163.com 发送时间： 2013-12-02 11:10 收件人： users-cn 主题： 回复: Re: 系统虚拟机无法创建成功 各位： 之前的确是因为nfs存储共享的权限问题，修改之后在kvm宿主机端可以正常挂载，而且目录权限也是正确的，但是现在系统虚拟机仍然是创建、删除、创建、删除。。。恳请各位帮忙分析替下。 kvm宿主机上/var/log/cloud/agent/agent.log中的日志为： 2013-12-02 11:05:13,313 WARN [kvm.resource.LibvirtComputingResource] (agentRequest-Handler-5:null) Exception com.cloud.utils.exception.CloudRuntimeException: org.libvirt.LibvirtException: Storage volume not found: no storage vol with matching name 'dd324d10-c822-4c1b-a3d5-bfb0b84e323c' at com.cloud.hypervisor.kvm.storage.LibvirtStorageAdaptor.getVolume(LibvirtStorageAdaptor.java:90) at com.cloud.hypervisor.kvm.storage.LibvirtStorageAdaptor.getPhysicalDisk(LibvirtStorageAdaptor.java:404) at com.cloud.hypervisor.kvm.storage.LibvirtStoragePool.getPhysicalDisk(LibvirtStoragePool.java:113) at com.cloud.hypervisor.kvm.resource.LibvirtComputingResource.createVbd(LibvirtComputingResource.java:2888) at com.cloud.hypervisor.kvm.resource.LibvirtComputingResource.execute(LibvirtComputingResource.java:2808) at com.cloud.hypervisor.kvm.resource.LibvirtComputingResource.executeRequest(LibvirtComputingResource.java:1035) at com.cloud.agent.Agent.processRequest(Agent.java:518) at com.cloud.agent.Agent$AgentRequestHandler.doTask(Agent.java:831) at com.cloud.utils.nio.Task.run(Task.java:83) 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) 2013-12-02 11:05:13,314 WARN [cloud.agent.Agent] (agentRequest-Handler-5:null) Caught: java.lang.NullPointerException at com.cloud.hypervisor.kvm.resource.LibvirtComputingResource.cleanupVMNetworks(LibvirtComputingResource.java:3922) at com.cloud.hypervisor.kvm.resource.LibvirtComputingResource.handleVmStartFailure(LibvirtComputingResource.java:2709) at com.cloud.hypervisor.kvm.resource.LibvirtComputingResource.execute(LibvirtComputingResource.java:2834) at com.cloud.hypervisor.kvm.resource.LibvirtComputingResource.executeRequest(LibvirtComputingResource.java:1035) at com.cloud.agent.Agent.processRequest(Agent.java:518) at com.cloud.agent.Agent$AgentRequestHandler.doTask(Agent.java:831) at com.cloud.utils.nio.Task.run(Task.java:83) 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) 一直是这两段交互。 管理端/var/log/cloud/management/management-server.log中的日志为： 2013-12-02 11:07:43,007 DEBUG [cloud.vm.VirtualMachineManagerImpl] (secstorage-1:null) Cleaning up NICS 2013-12-02 11:07:43,008 DEBUG [cloud.network.NetworkManagerImpl] (secstorage-1:null) Cleaning network for vm: 2206 2013-12-02 11:07:43,012 INFO [cloud.consoleproxy.ConsoleProxyManagerImpl] (consoleproxy-1:null) Found a stopped console proxy, bring it up to running pool. proxy vm id : 2 2013-12-02 11:07:43,025 DEBUG [network.guru.DirectNetworkGuru] (secstorage-1:null) Deallocate network: networkId: 204, ip: null 2013-12-02 11:07:43,026 DEBUG [cloud.capacity.CapacityManagerImpl] (consoleproxy-1:null) VM state transitted from :Stopped to Starting with event: StartRequestedvm's original host id: null new host id: null host id before state transition: null 2013-12-02 11:07:43,026 DEBUG [cloud.vm.VirtualMachineManagerImpl] (consoleproxy-1:null) Successfully transitioned to start state for VM[ConsoleProxy|v-2-VM] reservation id = 8c7b1a03-842a-46c7-bedc-63f275cdd83a 2013-12-02 11:07:43,033 DEBUG [cloud.network.NetworkManagerImpl] (secstorage-1:null) Removed nic id=8820 2013-12-02 11:07:43,033 DEBUG [cloud.vm.VirtualMachineManagerImpl] (consoleproxy-1:null) Trying to deploy VM, vm has dcId: 1 and podId: 1 2013-12-02 11:07:43,033 DEBUG [cloud.vm.VirtualMachineManagerImpl] (consoleproxy-1:null) Deploy avoids pods: null, clusters: null, hosts: null 2013-12-02 11:07:43,038 DEBUG [cloud.vm.VirtualMachineManagerImpl] (consoleproxy-1:null) Root volume is ready, need to place VM in volume's cluster 2013-12-02 11:07:43,039 DEBUG [cloud.vm.VirtualMachineManagerImpl] (consoleproxy-1:null) Vol[2|vm=2|ROOT] is READY, changing deployment plan to use this pool's dcId: 1 , podId: 1 , and clusterId: 1 2013-12-02 11:07:43,047 DEBUG [cloud.deploy.FirstFitPlanner] (consoleproxy-1:null) DeploymentPlanner allocation algorithm: random 2013-12-02 11:07:43,048 DEBUG [cloud.deploy.FirstFitPlanner] (consoleproxy-1:null) Trying to allocate a host and storage pools from dc:1, pod:1,cluster:1, requested cpu: 500, requested ram: 1073741824 2013-12-02 11:07:43,048 DEBUG [cloud.deploy.FirstFitPlanner] (consoleproxy-1:null) Is ROOT volume READY (pool already allocated)?: Yes 2013-12-02 11:07:43,048 DEBUG [cloud.deploy.FirstFitPlanner] (consoleproxy-1:null)
