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
============================================
