应该是没有成功从二级存储上拷贝系统虚拟机模板到主存储上，可能是因为网络不通，也可能是主机状态不对，或者是二级存储的问题，反正都有可能，如果可以的话可以去主机上调试一下“copy_vhd_from_secondarystorage.sh”脚本，必然是因为最后这一步执行失败。
当然你可以删除template_spool_ref表中template_id为1的那条记录，让系统重新拷贝一次，看看成功与否。
2014-03-16 15:00 GMT+08:00 �W�趴萍� �S奕�t / Carl Huang <carl_hu...@edyna.com>:
> please check primary storage with secondary storage.
>
>
> 2014-03-16 13:05 GMT+08:00 evanitsharp <evanitsh...@gmail.com>:
>
> > 看日志，异常原因很可能是：内存资源或硬盘资源不足
> >
> >
> >
> >
> > evanitsharp
> >
> > 发件人： 风南
> > 发送时间： 2014-03-15 20:15
> > 收件人： users-cn
> > 主题： XenServer 问题 AgentUnavailableException: Resource [Host:1] is
> > unreachable: Host 1: Unable to start instance due to null
> > Hi，各位：
> > 今天弄过了一天一直有一个问题搞不定，前来请教各位，环境如下：
> > 2台机器：
> > 管理端v4.2.1：cloudstack-manager 装在vmware里，桥接网络方式 192.168.11.60，
> > 1,2级存储也在这个机器上NFS
> > Host：XenServer 6.1 192.168.11.40
> > 问题出现在使用向导配置执行
> > Creating system VMs (this may take a while)
> > 这一步的时候。
> >
> > XenSever中挂载的nfs没有问题，可以正常读写。
> >
> > 管理端日志异常信息：
> >
> > 2014-03-16 03:54:38,779 DEBUG [cloud.capacity.CapacityManagerImpl]
> > (consoleproxy-1:null) release mem from host: 1, old used:
> > 1342177280,reserved: 0, total: 7224936960; new used:
> 268435456,reserved:0;
> > movedfromreserved: false,moveToReserveredfalse
> > 2014-03-16 03:54:38,789 WARN
> [cloud.consoleproxy.ConsoleProxyManagerImpl]
> > (consoleproxy-1:null) Exception while trying to start console proxy
> > com.cloud.exception.InsufficientServerCapacityException: Unable to
> create a
> > deployment for VM[ConsoleProxy|v-2-VM]Scope=interface
> > com.cloud.dc.DataCenter; id=1
> > at
> >
> >
> com.cloud.vm.VirtualMachineManagerImpl.advanceStart(VirtualMachineManagerImpl.java:841)
> > at
> >
> >
> com.cloud.vm.VirtualMachineManagerImpl.start(VirtualMachineManagerImpl.java:577)
> > at
> >
> >
> com.cloud.vm.VirtualMachineManagerImpl.start(VirtualMachineManagerImpl.java:570)
> > at
> >
> >
> com.cloud.consoleproxy.ConsoleProxyManagerImpl.startProxy(ConsoleProxyManagerImpl.java:556)
> > at
> >
> >
> com.cloud.consoleproxy.ConsoleProxyManagerImpl.allocCapacity(ConsoleProxyManagerImpl.java:928)
> > at
> >
> >
> com.cloud.consoleproxy.ConsoleProxyManagerImpl.expandPool(ConsoleProxyManagerImpl.java:1672)
> > at
> >
> >
> com.cloud.consoleproxy.ConsoleProxyManagerImpl.expandPool(ConsoleProxyManagerImpl.java:157)
> > at
> com.cloud.vm.SystemVmLoadScanner.loadScan(SystemVmLoadScanner.java:111)
> > at
> com.cloud.vm.SystemVmLoadScanner.access$100(SystemVmLoadScanner.java:33)
> > at
> > com.cloud.vm.SystemVmLoadScanner$1.reallyRun(SystemVmLoadScanner.java:81)
> > at com.cloud.vm.SystemVmLoadScanner$1.run(SystemVmLoadScanner.java:72)
> > at
> java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:471)
> > at java.util.concurrent.FutureTask.runAndReset(FutureTask.java:304)
> > at
> >
> >
> java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$301(ScheduledThreadPoolExecutor.java:178)
> > at
> >
> >
> java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:293)
> > 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)
> > 2014-03-16 03:54:41,326 INFO [storage.volume.VolumeServiceImpl]
> > (secstorage-1:null) Unable to acquire lock on VMTemplateStoragePool 1
> > 2014-03-16 03:54:41,330 ERROR [cloud.vm.VirtualMachineManagerImpl]
> > (secstorage-1:null) Failed to start instance
> VM[SecondaryStorageVm|s-1-VM]
> > java.lang.NullPointerException
> > at
> >
> >
> org.apache.cloudstack.storage.volume.VolumeServiceImpl.createBaseImageAsync(VolumeServiceImpl.java:428)
> > at
> >
> >
> org.apache.cloudstack.storage.volume.VolumeServiceImpl.createVolumeFromTemplateAsync(VolumeServiceImpl.java:575)
> > at
> >
> >
> com.cloud.storage.VolumeManagerImpl.recreateVolume(VolumeManagerImpl.java:2577)
> > at
> com.cloud.storage.VolumeManagerImpl.prepare(VolumeManagerImpl.java:2641)
> > at
> >
> >
> com.cloud.vm.VirtualMachineManagerImpl.advanceStart(VirtualMachineManagerImpl.java:888)
> > at
> >
> >
> com.cloud.vm.VirtualMachineManagerImpl.start(VirtualMachineManagerImpl.java:577)
> > at
> >
> >
> com.cloud.vm.VirtualMachineManagerImpl.start(VirtualMachineManagerImpl.java:570)
> > at
> >
> >
> com.cloud.storage.secondary.SecondaryStorageManagerImpl.startSecStorageVm(SecondaryStorageManagerImpl.java:267)
> > at
> >
> >
> com.cloud.storage.secondary.SecondaryStorageManagerImpl.allocCapacity(SecondaryStorageManagerImpl.java:696)
> > at
> >
> >
> com.cloud.storage.secondary.SecondaryStorageManagerImpl.expandPool(SecondaryStorageManagerImpl.java:1300)
> > at
> >
> >
> com.cloud.secstorage.PremiumSecondaryStorageManagerImpl.scanPool(PremiumSecondaryStorageManagerImpl.java:123)
> > at
> >
> >
> com.cloud.secstorage.PremiumSecondaryStorageManagerImpl.scanPool(PremiumSecondaryStorageManagerImpl.java:50)
> > at
> com.cloud.vm.SystemVmLoadScanner.loadScan(SystemVmLoadScanner.java:104)
> > at
> com.cloud.vm.SystemVmLoadScanner.access$100(SystemVmLoadScanner.java:33)
> > at
> > com.cloud.vm.SystemVmLoadScanner$1.reallyRun(SystemVmLoadScanner.java:81)
> > at com.cloud.vm.SystemVmLoadScanner$1.run(SystemVmLoadScanner.java:72)
> > at
> java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:471)
> > at java.util.concurrent.FutureTask.runAndReset(FutureTask.java:304)
> > at
> >
> >
> java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$301(ScheduledThreadPoolExecutor.java:178)
> > at
> >
> >
> java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:293)
> > 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)
> > 2014-03-16 03:54:41,343 DEBUG [cloud.vm.VirtualMachineManagerImpl]
> > (secstorage-1:null) Cleaning up resources for the vm
> > VM[SecondaryStorageVm|s-1-VM] in Starting state
> > 2014-03-16 03:54:41,350 DEBUG [agent.transport.Request]
> (secstorage-1:null)
> > Seq 1-1640628238: Sending { Cmd , MgmtId: 52233116689, via: 1, Ver: v1,
> > Flags: 100111,
> >
> >
> [{"com.cloud.agent.api.StopCommand":{"isProxy":false,"executeInSequence":true,"vmName":"s-1-VM","wait":0}}]
> > }
> > 2014-03-16 03:54:41,351 DEBUG [agent.transport.Request]
> (secstorage-1:null)
> > Seq 1-1640628238: Executing: { Cmd , MgmtId: 52233116689, via: 1, Ver:
> v1,
> > Flags: 100111,
> >
> >
> [{"com.cloud.agent.api.StopCommand":{"isProxy":false,"executeInSequence":true,"vmName":"s-1-VM","wait":0}}]
> > }
> > 2014-03-16 03:54:41,351 DEBUG [agent.manager.DirectAgentAttache]
> > (DirectAgent-4:null) Seq 1-1640628238: Executing request
> > 2014-03-16 03:54:41,422 INFO [xen.resource.CitrixResourceBase]
> > (DirectAgent-4:null) VM does not exist on
> > XenServere77bf36c-7a39-4db4-acab-12beecab3028
> > 2014-03-16 03:54:41,422 DEBUG [agent.manager.DirectAgentAttache]
> > (DirectAgent-4:null) Seq 1-1640628238: Response Received:
> > 2014-03-16 03:54:41,424 DEBUG [agent.transport.Request]
> > (DirectAgent-4:null) Seq 1-1640628238: Processing: { Ans: , MgmtId:
> > 52233116689, via: 1, Ver: v1, Flags: 110,
> > [{"com.cloud.agent.api.StopAnswer":{"result":true,"details":"VM does not
> > exist","wait":0}}] }
> > 2014-03-16 03:54:41,424 DEBUG [agent.transport.Request]
> (secstorage-1:null)
> > Seq 1-1640628238: Received: { Ans: , MgmtId: 52233116689, via: 1, Ver:
> v1,
> > Flags: 110, { StopAnswer } }
> > 2014-03-16 03:54:41,425 DEBUG [agent.manager.AgentAttache]
> > (DirectAgent-4:null) Seq 1-1640628238: No more commands found
> > 2014-03-16 03:54:41,457 DEBUG [cloud.network.NetworkManagerImpl]
> > (secstorage-1:null) Asking VirtualRouter to release
> > Nic[1-1-a4cc532f-00fa-4f06-a794-7fb4c5cc8c96-192.168.11.80]
> > 2014-03-16 03:54:41,461 DEBUG [cloud.network.NetworkManagerImpl]
> > (secstorage-1:null) Asking SecurityGroupProvider to release
> > Nic[1-1-a4cc532f-00fa-4f06-a794-7fb4c5cc8c96-192.168.11.80]
> > 2014-03-16 03:54:41,466 DEBUG [network.guru.ControlNetworkGuru]
> > (secstorage-1:null) Released nic: NicProfile[2-1-null-null-null
> > 2014-03-16 03:54:41,480 DEBUG [dc.dao.DataCenterIpAddressDaoImpl]
> > (secstorage-1:null) Releasing ip address for
> > reservationId=a4cc532f-00fa-4f06-a794-7fb4c5cc8c96, instance=3
> > 2014-03-16 03:54:41,481 DEBUG [network.guru.PodBasedNetworkGuru]
> > (secstorage-1:null) Released nic: NicProfile[3-1-null-null-null
> > 2014-03-16 03:54:41,491 DEBUG [dc.dao.DataCenterIpAddressDaoImpl]
> > (secstorage-1:null) Releasing ip address for
> > reservationId=a4cc532f-00fa-4f06-a794-7fb4c5cc8c96, instance=4
> > 2014-03-16 03:54:41,491 DEBUG [network.guru.PodBasedNetworkGuru]
> > (secstorage-1:null) Released nic: NicProfile[4-1-null-null-null
> > 2014-03-16 03:54:41,509 DEBUG [cloud.vm.VirtualMachineManagerImpl]
> > (secstorage-1:null) Successfully released network resources for the vm
> > VM[SecondaryStorageVm|s-1-VM]
> > 2014-03-16 03:54:41,509 DEBUG [cloud.vm.VirtualMachineManagerImpl]
> > (secstorage-1:null) Successfully cleanued up resources for the vm
> > VM[SecondaryStorageVm|s-1-VM] in Starting state
> > 2014-03-16 03:54:41,516 DEBUG [cloud.capacity.CapacityManagerImpl]
> > (secstorage-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
> > 2014-03-16 03:54:41,524 DEBUG [cloud.capacity.CapacityManagerImpl]
> > (secstorage-1:null) Hosts's actual total CPU: 27744 and CPU after
> applying
> > overprovisioning: 27744
> > 2014-03-16 03:54:41,524 DEBUG [cloud.capacity.CapacityManagerImpl]
> > (secstorage-1:null) Hosts's actual total RAM: 7224936768 and RAM after
> > applying overprovisioning: 7224936960
> > 2014-03-16 03:54:41,524 DEBUG [cloud.capacity.CapacityManagerImpl]
> > (secstorage-1:null) release cpu from host: 1, old used: 500,reserved: 0,
> > actual total: 27744, total with overprovisioning: 27744; new used:
> > 0,reserved:0; movedfromreserved: false,moveToReserveredfalse
> > 2014-03-16 03:54:41,524 DEBUG [cloud.capacity.CapacityManagerImpl]
> > (secstorage-1:null) release mem from host: 1, old used:
> 268435456,reserved:
> > 0, total: 7224936960; new used: 0,reserved:0; movedfromreserved:
> > false,moveToReserveredfalse
> > 2014-03-16 03:54:41,532 WARN
> > [storage.secondary.SecondaryStorageManagerImpl] (secstorage-1:null)
> > Exception while trying to start secondary storage vm
> > com.cloud.exception.AgentUnavailableException: Resource [Host:1] is
> > unreachable: Host 1: Unable to start instance due to null
> > at
> >
> >
> com.cloud.vm.VirtualMachineManagerImpl.advanceStart(VirtualMachineManagerImpl.java:999)
> > at
> >
> >
> com.cloud.vm.VirtualMachineManagerImpl.start(VirtualMachineManagerImpl.java:577)
> > at
> >
> >
> com.cloud.vm.VirtualMachineManagerImpl.start(VirtualMachineManagerImpl.java:570)
> > at
> >
> >
> com.cloud.storage.secondary.SecondaryStorageManagerImpl.startSecStorageVm(SecondaryStorageManagerImpl.java:267)
> > at
> >
> >
> com.cloud.storage.secondary.SecondaryStorageManagerImpl.allocCapacity(SecondaryStorageManagerImpl.java:696)
> > at
> >
> >
> com.cloud.storage.secondary.SecondaryStorageManagerImpl.expandPool(SecondaryStorageManagerImpl.java:1300)
> > at
> >
> >
> com.cloud.secstorage.PremiumSecondaryStorageManagerImpl.scanPool(PremiumSecondaryStorageManagerImpl.java:123)
> > at
> >
> >
> com.cloud.secstorage.PremiumSecondaryStorageManagerImpl.scanPool(PremiumSecondaryStorageManagerImpl.java:50)
> > at
> com.cloud.vm.SystemVmLoadScanner.loadScan(SystemVmLoadScanner.java:104)
> > at
> com.cloud.vm.SystemVmLoadScanner.access$100(SystemVmLoadScanner.java:33)
> > at
> > com.cloud.vm.SystemVmLoadScanner$1.reallyRun(SystemVmLoadScanner.java:81)
> > at com.cloud.vm.SystemVmLoadScanner$1.run(SystemVmLoadScanner.java:72)
> > at
> java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:471)
> > at java.util.concurrent.FutureTask.runAndReset(FutureTask.java:304)
> > at
> >
> >
> java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$301(ScheduledThreadPoolExecutor.java:178)
> > at
> >
> >
> java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:293)
> > 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)
> > Caused by: java.lang.NullPointerException
> > at
> >
> >
> org.apache.cloudstack.storage.volume.VolumeServiceImpl.createBaseImageAsync(VolumeServiceImpl.java:428)
> > at
> >
> >
> org.apache.cloudstack.storage.volume.VolumeServiceImpl.createVolumeFromTemplateAsync(VolumeServiceImpl.java:575)
> > at
> >
> >
> com.cloud.storage.VolumeManagerImpl.recreateVolume(VolumeManagerImpl.java:2577)
> > at
> com.cloud.storage.VolumeManagerImpl.prepare(VolumeManagerImpl.java:2641)
> > at
> >
> >
> com.cloud.vm.VirtualMachineManagerImpl.advanceStart(VirtualMachineManagerImpl.java:888)
> > ... 19 more
> > 2014-03-16 03:54:41,532 INFO
> > [storage.secondary.SecondaryStorageManagerImpl] (secstorage-1:null)
> Unable
> > to start secondary storage vm for standby capacity, secStorageVm vm Id :
> 1,
> > will recycle it and start a new one
> > 2014-03-16 03:54:41,533 INFO
> > [cloud.secstorage.PremiumSecondaryStorageManagerImpl] (secstorage-1:null)
> > Primary secondary storage is not even started, wait until next turn
> >
> >
> >
> > 实在没辙了，各位是否遇到过这个问题？
> > --
> > Best Regards
> >
>
>
>
> --
> ============================================
> eDynamics �W�趴萍�
>
> ��管理��
> 主任
> �S奕�t Carl Huang
> ����：+886-2-8797-6373 分�C：6302
> �髡妫�+886-2-8797-6272
> E-Mail：carl_hu...@edyna.com
> 台北��公司：台北市�群��^瑞光路 513 巷 28 �� 7 ��
>
> �����u www.laibaodao.com
> oBuy全�I�W www.obuy.tw
> 荷包�W www.joinbao.com
> CityTalk城市通 www.citytalk.tw
> ============================================
>
