cloudstack-users-cn mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From 不坏阿峰 <onlydeb...@gmail.com>
Subject CS4.2.1 SVM can not start
Date Wed, 29 Jan 2014 16:41:01 GMT
i have try clean DB and re initial CS4.2.1.
in Xen server pool,  nfs primary storage have attached., it is ok.

i search other person meet same problem, but no solution.
http://permalink.gmane.org/gmane.comp.apache.cloudstack.user/8728

hope some one can give me some support. thanks

----------------------------------------------------------------------------------------->
2014-01-29 23:37:07,747 DEBUG
[cloud.deploy.DeploymentPlanningManagerImpl] (secstorage-1:null)
Deploy avoids pods: null, clusters: null, hosts: [1]
2014-01-29 23:37:07,749 DEBUG
[cloud.deploy.DeploymentPlanningManagerImpl] (secstorage-1:null)
DeploymentPlanner allocation algorithm:
com.cloud.deploy.FirstFitPlanner_EnhancerByCloudStack_1de5a3b1@9317ccb
2014-01-29 23:37:07,749 DEBUG
[cloud.deploy.DeploymentPlanningManagerImpl] (secstorage-1:null)
Trying to allocate a host and storage pools from dc:1,
pod:1,cluster:null, requested cpu: 500, requested ram: 268435456
2014-01-29 23:37:07,749 DEBUG
[cloud.deploy.DeploymentPlanningManagerImpl] (secstorage-1:null) Is
ROOT volume READY (pool already allocated)?: No
2014-01-29 23:37:07,749 DEBUG [cloud.deploy.FirstFitPlanner]
(secstorage-1:null) Searching resources only under specified Pod: 1
2014-01-29 23:37:07,749 DEBUG [cloud.deploy.FirstFitPlanner]
(secstorage-1:null) Listing clusters in order of aggregate capacity,
that have (atleast one host with) enough CPU and RAM capacity under
this Pod: 1
2014-01-29 23:37:07,759 DEBUG
[cloud.deploy.DeploymentPlanningManagerImpl] (secstorage-1:null)
Checking resources in Cluster: 1 under Pod: 1
2014-01-29 23:37:07,759 DEBUG [allocator.impl.FirstFitAllocator]
(secstorage-1:FirstFitRoutingAllocator) Looking for hosts in dc: 1
pod:1  cluster:1
2014-01-29 23:37:07,763 DEBUG [allocator.impl.FirstFitAllocator]
(secstorage-1:FirstFitRoutingAllocator) FirstFitAllocator has 2 hosts
to check for allocation: [Host[-2-Routing], Host[-1-Routing]]
2014-01-29 23:37:07,767 DEBUG [allocator.impl.FirstFitAllocator]
(secstorage-1:FirstFitRoutingAllocator) Found 2 hosts for allocation
after prioritization: [Host[-2-Routing], Host[-1-Routing]]
2014-01-29 23:37:07,767 DEBUG [allocator.impl.FirstFitAllocator]
(secstorage-1:FirstFitRoutingAllocator) Looking for speed=500Mhz,
Ram=256
2014-01-29 23:37:07,772 DEBUG [cloud.capacity.CapacityManagerImpl]
(secstorage-1:FirstFitRoutingAllocator) Checking if host: 2 has enough
capacity for requested CPU: 500 and requested RAM: 268435456 ,
cpuOverprovisioningFactor: 1.0
2014-01-29 23:37:07,775 DEBUG [cloud.capacity.CapacityManagerImpl]
(secstorage-1:FirstFitRoutingAllocator) Hosts's actual total CPU:
13590 and CPU after applying overprovisioning: 13590
2014-01-29 23:37:07,775 DEBUG [cloud.capacity.CapacityManagerImpl]
(secstorage-1:FirstFitRoutingAllocator) Free CPU: 13590 , Requested
CPU: 500
2014-01-29 23:37:07,776 DEBUG [cloud.capacity.CapacityManagerImpl]
(secstorage-1:FirstFitRoutingAllocator) Free RAM: 9669118976 ,
Requested RAM: 268435456
2014-01-29 23:37:07,776 DEBUG [cloud.capacity.CapacityManagerImpl]
(secstorage-1:FirstFitRoutingAllocator) Host has enough CPU and RAM
available
2014-01-29 23:37:07,776 DEBUG [cloud.capacity.CapacityManagerImpl]
(secstorage-1:FirstFitRoutingAllocator) STATS: Can alloc CPU from
host: 2, used: 0, reserved: 0, actual total: 13590, total with
overprovisioning: 13590; requested cpu:500,alloc_from_last_host?:false
,considerReservedCapacity?: true
2014-01-29 23:37:07,776 DEBUG [cloud.capacity.CapacityManagerImpl]
(secstorage-1:FirstFitRoutingAllocator) STATS: Can alloc MEM from
host: 2, used: 0, reserved: 0, total: 9669118976; requested mem:
268435456,alloc_from_last_host?:false ,considerReservedCapacity?: true
2014-01-29 23:37:07,776 DEBUG [allocator.impl.FirstFitAllocator]
(secstorage-1:FirstFitRoutingAllocator) Found a suitable host, adding
to list: 2
2014-01-29 23:37:07,776 DEBUG [allocator.impl.FirstFitAllocator]
(secstorage-1:FirstFitRoutingAllocator) Host name: xen01, hostId: 1 is
in avoid set, skipping this and trying other available hosts
2014-01-29 23:37:07,776 DEBUG [allocator.impl.FirstFitAllocator]
(secstorage-1:FirstFitRoutingAllocator) Host Allocator returning 1
suitable hosts
2014-01-29 23:37:07,777 DEBUG
[cloud.deploy.DeploymentPlanningManagerImpl] (secstorage-1:null)
Checking suitable pools for volume (Id, Type): (19,ROOT)
2014-01-29 23:37:07,778 DEBUG
[cloud.deploy.DeploymentPlanningManagerImpl] (secstorage-1:null) We
need to allocate new storagepool for this volume
2014-01-29 23:37:07,778 DEBUG
[cloud.deploy.DeploymentPlanningManagerImpl] (secstorage-1:null)
Calling StoragePoolAllocators to find suitable pools
2014-01-29 23:37:07,780 DEBUG
[storage.allocator.LocalStoragePoolAllocator] (secstorage-1:null)
LocalStoragePoolAllocator trying to find storage pool to fit the vm
2014-01-29 23:37:07,780 DEBUG
[storage.allocator.ClusterScopeStoragePoolAllocator]
(secstorage-1:null) ClusterScopeStoragePoolAllocator looking for
storage pool
2014-01-29 23:37:07,780 DEBUG
[storage.allocator.ClusterScopeStoragePoolAllocator]
(secstorage-1:null) Looking for pools in dc: 1  pod:1  cluster:1
2014-01-29 23:37:07,784 DEBUG
[storage.allocator.AbstractStoragePoolAllocator] (secstorage-1:null)
Checking if storage pool is suitable, name: null ,poolId: 1
2014-01-29 23:37:07,784 DEBUG
[storage.allocator.AbstractStoragePoolAllocator] (secstorage-1:null)
StoragePool is in avoid set, skipping this pool
2014-01-29 23:37:07,784 DEBUG
[storage.allocator.ClusterScopeStoragePoolAllocator]
(secstorage-1:null) ClusterScopeStoragePoolAllocator returning 0
suitable storage pools
2014-01-29 23:37:07,784 DEBUG
[storage.allocator.ZoneWideStoragePoolAllocator] (secstorage-1:null)
ZoneWideStoragePoolAllocator to find storage pool
2014-01-29 23:37:07,788 DEBUG
[cloud.deploy.DeploymentPlanningManagerImpl] (secstorage-1:null) No
suitable pools found for volume: Vol[19|vm=19|ROOT] under cluster: 1
2014-01-29 23:37:07,788 DEBUG
[cloud.deploy.DeploymentPlanningManagerImpl] (secstorage-1:null) No
suitable pools found
2014-01-29 23:37:07,788 DEBUG
[cloud.deploy.DeploymentPlanningManagerImpl] (secstorage-1:null) No
suitable storagePools found under this Cluster: 1
2014-01-29 23:37:07,792 DEBUG
[cloud.deploy.DeploymentPlanningManagerImpl] (secstorage-1:null) Could
not find suitable Deployment Destination for this VM under any
clusters, returning.
2014-01-29 23:37:07,793 DEBUG [cloud.deploy.FirstFitPlanner]
(secstorage-1:null) Searching resources only under specified Pod: 1
2014-01-29 23:37:07,793 DEBUG [cloud.deploy.FirstFitPlanner]
(secstorage-1:null) Listing clusters in order of aggregate capacity,
that have (atleast one host with) enough CPU and RAM capacity under
this Pod: 1
2014-01-29 23:37:07,796 DEBUG [cloud.deploy.FirstFitPlanner]
(secstorage-1:null) Removing from the clusterId list these clusters
from avoid set: [1]
2014-01-29 23:37:07,796 DEBUG [cloud.deploy.FirstFitPlanner]
(secstorage-1:null) No clusters found after removing disabled clusters
and clusters in avoid list, returning.
2014-01-29 23:37:07,802 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-01-29 23:37:07,810 DEBUG [cloud.capacity.CapacityManagerImpl]
(secstorage-1:null) Hosts's actual total CPU: 13590 and CPU after
applying overprovisioning: 13590
2014-01-29 23:37:07,810 DEBUG [cloud.capacity.CapacityManagerImpl]
(secstorage-1:null) Hosts's actual total RAM: 9669119040 and RAM after
applying overprovisioning: 9669118976
2014-01-29 23:37:07,810 DEBUG [cloud.capacity.CapacityManagerImpl]
(secstorage-1:null) release cpu from host: 1, old used: 1000,reserved:
0, actual total: 13590, total with overprovisioning: 13590; new used:
500,reserved:0; movedfromreserved: false,moveToReserveredfalse
2014-01-29 23:37:07,810 DEBUG [cloud.capacity.CapacityManagerImpl]
(secstorage-1:null) release mem from host: 1, old used:
1342177280,reserved: 0, total: 9669118976; new used:
1073741824,reserved:0; movedfromreserved: false,moveToReserveredfalse
2014-01-29 23:37:07,813 WARN
[storage.secondary.SecondaryStorageManagerImpl] (secstorage-1:null)
Exception while trying to start secondary storage vm
com.cloud.exception.InsufficientServerCapacityException: Unable to
create a deployment for VM[SecondaryStorageVm|s-19-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.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$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)
2014-01-29 23:37:07,814 INFO
[storage.secondary.SecondaryStorageManagerImpl] (secstorage-1:null)
Unable to start secondary storage vm for standby capacity,
secStorageVm vm Id : 19, will recycle it and start a new one
2014-01-29 23:37:07,817 DEBUG [cloud.vm.VirtualMachineManagerImpl]
(secstorage-1:null) VM is already stopped:
VM[SecondaryStorageVm|s-19-VM]
2014-01-29 23:37:07,821 DEBUG [cloud.capacity.CapacityManagerImpl]
(secstorage-1:null) VM state transitted from :Stopped to Expunging
with event: ExpungeOperationvm's original host id: null new host id:
null host id before state transition: null
2014-01-29 23:37:07,824 DEBUG [cloud.vm.VirtualMachineManagerImpl]
(secstorage-1:null) Destroying vm VM[SecondaryStorageVm|s-19-VM]
2014-01-29 23:37:07,824 DEBUG [cloud.vm.VirtualMachineManagerImpl]
(secstorage-1:null) Cleaning up NICS
2014-01-29 23:37:07,824 DEBUG [cloud.network.NetworkManagerImpl]
(secstorage-1:null) Cleaning network for vm: 19
2014-01-29 23:37:07,836 DEBUG [network.guru.PublicNetworkGuru]
(secstorage-1:null) public network deallocate network: networkId: 200,
ip: 192.168.240.50
2014-01-29 23:37:07,842 DEBUG [network.guru.PublicNetworkGuru]
(secstorage-1:null) Deallocated nic: NicProfile[72-19-null-null-null
2014-01-29 23:37:07,844 DEBUG [cloud.network.NetworkManagerImpl]
(secstorage-1:null) Removed nic id=72
2014-01-29 23:37:07,845 DEBUG [cloud.network.NetworkManagerImpl]
(secstorage-1:null) Revoving nic secondary ip entry ...
2014-01-29 23:37:07,851 DEBUG [cloud.network.NetworkManagerImpl]
(secstorage-1:null) Removed nic id=73
2014-01-29 23:37:07,852 DEBUG [cloud.network.NetworkManagerImpl]
(secstorage-1:null) Revoving nic secondary ip entry ...
2014-01-29 23:37:07,864 DEBUG [cloud.network.NetworkManagerImpl]
(secstorage-1:null) Removed nic id=74
2014-01-29 23:37:07,865 DEBUG [cloud.network.NetworkManagerImpl]
(secstorage-1:null) Revoving nic secondary ip entry ...
2014-01-29 23:37:07,884 DEBUG [cloud.network.NetworkManagerImpl]
(secstorage-1:null) Removed nic id=75
2014-01-29 23:37:07,886 DEBUG [cloud.network.NetworkManagerImpl]
(secstorage-1:null) Revoving nic secondary ip entry ...
2014-01-29 23:37:07,888 DEBUG [cloud.storage.VolumeManagerImpl]
(secstorage-1:null) Cleaning storage for vm: 19
2014-01-29 23:37:07,903 INFO  [storage.volume.VolumeServiceImpl]
(secstorage-1:null) Expunge volume with no data store specified
2014-01-29 23:37:07,906 INFO  [storage.volume.VolumeServiceImpl]
(secstorage-1:null) Volume 19 is not referred anywhere, remove it from
volumes table
2014-01-29 23:37:07,913 DEBUG [cloud.vm.VirtualMachineManagerImpl]
(secstorage-1:null) Expunged VM[SecondaryStorageVm|s-19-VM]
2014-01-29 23:37:07,919 INFO
[cloud.secstorage.PremiumSecondaryStorageManagerImpl]
(secstorage-1:null) Primary secondary storage is not even started,
wait until next turn
2014-01-29 23:37:10,313 INFO  [storage.volume.VolumeServiceImpl]
(consoleproxy-1:null) Unable to acquire lock on VMTemplateStoragePool
37
2014-01-29 23:37:10,315 ERROR [cloud.vm.VirtualMachineManagerImpl]
(consoleproxy-1:null) Failed to start instance VM[ConsoleProxy|v-2-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.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$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)
2014-01-29 23:37:10,318 DEBUG [cloud.vm.VirtualMachineManagerImpl]
(consoleproxy-1:null) Cleaning up resources for the vm
VM[ConsoleProxy|v-2-VM] in Starting state
2014-01-29 23:37:10,320 DEBUG [agent.transport.Request]
(consoleproxy-1:null) Seq 1-1493762102: Sending  { Cmd , MgmtId:
345050143793, via: 1, Ver: v1, Flags: 100111,
[{"com.cloud.agent.api.StopCommand":{"isProxy":false,"executeInSequence":true,"vmName":"v-2-VM","wait":0}}]
}
2014-01-29 23:37:10,321 DEBUG [agent.transport.Request]
(consoleproxy-1:null) Seq 1-1493762102: Executing:  { Cmd , MgmtId:
345050143793, via: 1, Ver: v1, Flags: 100111,
[{"com.cloud.agent.api.StopCommand":{"isProxy":false,"executeInSequence":true,"vmName":"v-2-VM","wait":0}}]
}
2014-01-29 23:37:10,321 DEBUG [agent.manager.DirectAgentAttache]
(DirectAgent-151:null) Seq 1-1493762102: Executing request
2014-01-29 23:37:10,444 INFO  [xen.resource.CitrixResourceBase]
(DirectAgent-151:null) VM does not exist on
XenServerce2325a7-2953-4a88-af4e-d828e5624ffc
2014-01-29 23:37:10,444 DEBUG [agent.manager.DirectAgentAttache]
(DirectAgent-151:null) Seq 1-1493762102: Response Received:
2014-01-29 23:37:10,444 DEBUG [agent.transport.Request]
(DirectAgent-151:null) Seq 1-1493762102: Processing:  { Ans: , MgmtId:
345050143793, via: 1, Ver: v1, Flags: 110,
[{"com.cloud.agent.api.StopAnswer":{"result":true,"details":"VM does
not exist","wait":0}}] }
2014-01-29 23:37:10,445 DEBUG [agent.manager.AgentAttache]
(DirectAgent-151:null) Seq 1-1493762102: No more commands found
2014-01-29 23:37:10,445 DEBUG [agent.transport.Request]
(consoleproxy-1:null) Seq 1-1493762102: Received:  { Ans: , MgmtId:
345050143793, via: 1, Ver: v1, Flags: 110, { StopAnswer } }
2014-01-29 23:37:10,456 DEBUG [network.guru.ControlNetworkGuru]
(consoleproxy-1:null) Released nic: NicProfile[6-2-null-null-null
2014-01-29 23:37:10,464 DEBUG [dc.dao.DataCenterIpAddressDaoImpl]
(consoleproxy-1:null) Releasing ip address for
reservationId=8326d153-52df-4c46-ac11-a10c433c3fbe, instance=7
2014-01-29 23:37:10,465 DEBUG [network.guru.PodBasedNetworkGuru]
(consoleproxy-1:null) Released nic: NicProfile[7-2-null-null-null
2014-01-29 23:37:10,468 DEBUG [cloud.vm.VirtualMachineManagerImpl]
(consoleproxy-1:null) Successfully released network resources for the
vm VM[ConsoleProxy|v-2-VM]
2014-01-29 23:37:10,468 DEBUG [cloud.vm.VirtualMachineManagerImpl]
(consoleproxy-1:null) Successfully cleanued up resources for the vm
VM[ConsoleProxy|v-2-VM] in Starting state
2014-01-29 23:37:10,473 DEBUG [cloud.capacity.CapacityManagerImpl]
(consoleproxy-1:null) VM state transitted from :Starting to Stopped
with event: OperationFailedvm's original host id: 2 new host id: null
host id before state transition: 1
2014-01-29 23:37:10,480 DEBUG [cloud.capacity.CapacityManagerImpl]
(consoleproxy-1:null) Hosts's actual total CPU: 13590 and CPU after
applying overprovisioning: 13590
2014-01-29 23:37:10,480 DEBUG [cloud.capacity.CapacityManagerImpl]
(consoleproxy-1:null) Hosts's actual total RAM: 9669119040 and RAM
after applying overprovisioning: 9669118976
2014-01-29 23:37:10,481 DEBUG [cloud.capacity.CapacityManagerImpl]
(consoleproxy-1:null) release cpu from host: 1, old used:
500,reserved: 0, actual total: 13590, total with overprovisioning:
13590; new used: 0,reserved:0; movedfromreserved:
false,moveToReserveredfalse
2014-01-29 23:37:10,481 DEBUG [cloud.capacity.CapacityManagerImpl]
(consoleproxy-1:null) release mem from host: 1, old used:
1073741824,reserved: 0, total: 9669118976; new used: 0,reserved:0;
movedfromreserved: false,moveToReserveredfalse
2014-01-29 23:37:10,484 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 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.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$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: 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-01-29 23:37:14,276 DEBUG [agent.manager.DirectAgentAttache]
(DirectAgent-84:null) Ping from 1
2014-01-29 23:37:15,955 DEBUG [agent.manager.DirectAgentAttache]
(DirectAgent-121:null) Ping from 2

Mime
View raw message