You are viewing a plain text version of this content. The canonical link for it is here.
Posted to users-cn@cloudstack.apache.org by 风南 <wi...@gmail.com> on 2014/03/15 13:15:38 UTC
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
Re: XenServer 问题 AgentUnavailableException: Resource [Host:1] is unreachable: Host 1: Unable to start instance due to null
Posted by Bo Wang <br...@gmail.com>.
Ӧ����û�гɹ��Ӷ����洢�Ͽ���ϵͳ�����ģ�嵽���洢�ϣ���������Ϊ���粻ͨ��Ҳ����������״̬���ԣ������Ƕ����洢�����⣬�������п��ܣ�������ԵĻ�����ȥ�����ϵ���һ�¡�copy_vhd_from_secondarystorage.sh���ű�����Ȼ����Ϊ�����һ��ִ��ʧ�ܡ�
��Ȼ�����ɾ��template_spool_ref����template_idΪ1��������¼����ϵͳ���¿���һ�Σ������ɹ����
2014-03-16 15:00 GMT+08:00 �W�ſƼ� �S�ȭt / Carl Huang <ca...@edyna.com>:
> please check primary storage with secondary storage.
>
>
> 2014-03-16 13:05 GMT+08:00 evanitsharp <ev...@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_huang@edyna.com
> ̨������˾��̨���ЃȺ��^���· 513 �� 28 ̖ 7 ��
>
> �팚�u www.laibaodao.com
> oBuyȫ�I�W www.obuy.tw
> �ɰ��W www.joinbao.com
> CityTalk����ͨ www.citytalk.tw
> ============================================
>
Re: XenServer 问题 AgentUnavailableException: Resource [Host:1] is unreachable: Host 1: Unable to start instance due to null
Posted by 網勁科技 黃奕璽 / Carl Huang <ca...@edyna.com>.
please check primary storage with secondary storage.
2014-03-16 13:05 GMT+08:00 evanitsharp <ev...@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_huang@edyna.com
̨������˾��̨���ЃȺ��^���· 513 �� 28 ̖ 7 ��
�팚�u www.laibaodao.com
oBuyȫ�I�W www.obuy.tw
�ɰ��W www.joinbao.com
CityTalk����ͨ www.citytalk.tw
============================================
Re: XenServer 问题 AgentUnavailableException: Resource [Host:1] is unreachable: Host 1: Unable to start instance due to null
Posted by evanitsharp <ev...@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