Prasanna Santhanam created CLOUDSTACK-3386:
----------------------------------------------
Summary: NPE from VolumeMotionService when creating a template
Key: CLOUDSTACK-3386
URL: https://issues.apache.org/jira/browse/CLOUDSTACK-3386
Project: CloudStack
Issue Type: Bug
Security Level: Public (Anyone can view this level - this is the default.)
Reporter: Prasanna Santhanam
On deploying a VM sometimes the template fails to create on primary storage.
The following NPE is observed at these times. It's not clear why this would
fail sometimes yet. Attaching agent (DEBUG) and management server logs
Jul 5 20:31:01 cloudstack-centos63.fmt.vmops.com local0: 2013-07-06
03:31:01,817 DEBUG [storage.volume.VolumeServiceImpl] (Job-Executor-72:job-185)
failed to create template on storage
Jul 5 20:31:01 java.lang.NullPointerException
Jul 5 20:31:01 cloudstack-centos63.fmt.vmops.com at
org.apache.cloudstack.storage.motion.DataMotionServiceImpl.copyAsync(DataMotionServiceImpl.java:45)
Jul 5 20:31:01 cloudstack-centos63.fmt.vmops.com at
org.apache.cloudstack.storage.volume.VolumeServiceImpl.createBaseImageAsync(VolumeServiceImpl.java:390)
Jul 5 20:31:01 cloudstack-centos63.fmt.vmops.com at
org.apache.cloudstack.storage.volume.VolumeServiceImpl.createVolumeFromTemplateAsync(VolumeServiceImpl.java:490)
Jul 5 20:31:01 cloudstack-centos63.fmt.vmops.com at
com.cloud.storage.VolumeManagerImpl.recreateVolume(VolumeManagerImpl.java:2482)
Jul 5 20:31:01 cloudstack-centos63.fmt.vmops.com at
com.cloud.storage.VolumeManagerImpl.prepare(VolumeManagerImpl.java:2539)
Jul 5 20:31:01 cloudstack-centos63.fmt.vmops.com at
com.cloud.vm.VirtualMachineManagerImpl.advanceStart(VirtualMachineManagerImpl.java:852)
Jul 5 20:31:01 cloudstack-centos63.fmt.vmops.com at
com.cloud.vm.VirtualMachineManagerImpl.start(VirtualMachineManagerImpl.java:557)
Jul 5 20:31:01 cloudstack-centos63.fmt.vmops.com at
org.apache.cloudstack.engine.cloud.entity.api.VMEntityManagerImpl.deployVirtualMachine(VMEntityManagerImpl.java:230)
Jul 5 20:31:01 cloudstack-centos63.fmt.vmops.com at
org.apache.cloudstack.engine.cloud.entity.api.VirtualMachineEntityImpl.deploy(VirtualMachineEntityImpl.java:209)
Jul 5 20:31:01 cloudstack-centos63.fmt.vmops.com at
com.cloud.vm.UserVmManagerImpl.startVirtualMachine(UserVmManagerImpl.java:3317)
Jul 5 20:31:01 cloudstack-centos63.fmt.vmops.com at
com.cloud.vm.UserVmManagerImpl.startVirtualMachine(UserVmManagerImpl.java:2877)
Jul 5 20:31:01 cloudstack-centos63.fmt.vmops.com at
com.cloud.vm.UserVmManagerImpl.startVirtualMachine(UserVmManagerImpl.java:2863)
Jul 5 20:31:01 cloudstack-centos63.fmt.vmops.com at
com.cloud.utils.component.ComponentInstantiationPostProcessor$InterceptorDispatcher.intercept(ComponentInstantiationPostProcessor.java:125)
Jul 5 20:31:01 cloudstack-centos63.fmt.vmops.com at
org.apache.cloudstack.api.command.user.vm.DeployVMCmd.execute(DeployVMCmd.java:420)
Jul 5 20:31:01 cloudstack-centos63.fmt.vmops.com at
com.cloud.api.ApiDispatcher.dispatch(ApiDispatcher.java:155)
Jul 5 20:31:01 cloudstack-centos63.fmt.vmops.com at
com.cloud.async.AsyncJobManagerImpl$1.run(AsyncJobManagerImpl.java:514)
Jul 5 20:31:01 cloudstack-centos63.fmt.vmops.com at
java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:471)
Jul 5 20:31:01 cloudstack-centos63.fmt.vmops.com at
java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:334)
Jul 5 20:31:01 cloudstack-centos63.fmt.vmops.com at
java.util.concurrent.FutureTask.run(FutureTask.java:166)
Jul 5 20:31:01 cloudstack-centos63.fmt.vmops.com at
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1146)
Jul 5 20:31:01 cloudstack-centos63.fmt.vmops.com at
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
Jul 5 20:31:01 cloudstack-centos63.fmt.vmops.com at
java.lang.Thread.run(Thread.java:679)
Jul 5 20:31:01 cloudstack-centos63.fmt.vmops.com local0: 2013-07-06
03:31:01,826 DEBUG [cloud.storage.VolumeManagerImpl] (Job-Executor-72:job-185)
Unable to create Vol[61|vm=58|ROOT]:java.lang.NullPointerException
Jul 5 20:31:01 cloudstack-centos63.fmt.vmops.com local0: 2013-07-06
03:31:01,826 INFO [cloud.vm.VirtualMachineManagerImpl]
(Job-Executor-72:job-185) Unable to contact resource.
Jul 5 20:31:01 cloudstack-centos63.fmt.vmops.com
com.cloud.exception.StorageUnavailableException: Resource [StoragePool:1] is
unreachable: Unable to create Vol[61|vm=58|ROOT]:java.lang.NullPointerException
Jul 5 20:31:01 cloudstack-centos63.fmt.vmops.com at
com.cloud.storage.VolumeManagerImpl.recreateVolume(VolumeManagerImpl.java:2490)
Jul 5 20:31:01 cloudstack-centos63.fmt.vmops.com at
com.cloud.storage.VolumeManagerImpl.prepare(VolumeManagerImpl.java:2539)
Jul 5 20:31:01 cloudstack-centos63.fmt.vmops.com at
com.cloud.vm.VirtualMachineManagerImpl.advanceStart(VirtualMachineManagerImpl.java:852)
Jul 5 20:31:01 cloudstack-centos63.fmt.vmops.com at
com.cloud.vm.VirtualMachineManagerImpl.start(VirtualMachineManagerImpl.java:557)
Jul 5 20:31:01 cloudstack-centos63.fmt.vmops.com at
org.apache.cloudstack.engine.cloud.entity.api.VMEntityManagerImpl.deployVirtualMachine(VMEntityManagerImpl.java:230)
Jul 5 20:31:01 cloudstack-centos63.fmt.vmops.com at
org.apache.cloudstack.engine.cloud.entity.api.VirtualMachineEntityImpl.deploy(VirtualMachineEntityImpl.java:209)
Jul 5 20:31:01 cloudstack-centos63.fmt.vmops.com at
com.cloud.vm.UserVmManagerImpl.startVirtualMachine(UserVmManagerImpl.java:3317)
Jul 5 20:31:01 cloudstack-centos63.fmt.vmops.com at
com.cloud.vm.UserVmManagerImpl.startVirtualMachine(UserVmManagerImpl.java:2877)
Jul 5 20:31:01 cloudstack-centos63.fmt.vmops.com at
com.cloud.vm.UserVmManagerImpl.startVirtualMachine(UserVmManagerImpl.java:2863)
Jul 5 20:31:01 cloudstack-centos63.fmt.vmops.com at
com.cloud.utils.component.ComponentInstantiationPostProcessor$InterceptorDispatcher.intercept(ComponentInstantiationPostProcessor.java:125)
Jul 5 20:31:01 cloudstack-centos63.fmt.vmops.com at
org.apache.cloudstack.api.command.user.vm.DeployVMCmd.execute(DeployVMCmd.java:420)
Jul 5 20:31:01 cloudstack-centos63.fmt.vmops.com at
com.cloud.api.ApiDispatcher.dispatch(ApiDispatcher.java:155)
Jul 5 20:31:01 cloudstack-centos63.fmt.vmops.com at
com.cloud.async.AsyncJobManagerImpl$1.run(AsyncJobManagerImpl.java:514)
Jul 5 20:31:01 cloudstack-centos63.fmt.vmops.com at
java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:471)
Jul 5 20:31:01 cloudstack-centos63.fmt.vmops.com at
java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:334)
Jul 5 20:31:01 cloudstack-centos63.fmt.vmops.com at
java.util.concurrent.FutureTask.run(FutureTask.java:166)
Jul 5 20:31:01 cloudstack-centos63.fmt.vmops.com at
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1146)
Jul 5 20:31:01 cloudstack-centos63.fmt.vmops.com at
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
Jul 5 20:31:01 cloudstack-centos63.fmt.vmops.com at
java.lang.Thread.run(Thread.java:679)
--
This message is automatically generated by JIRA.
If you think it was sent incorrectly, please contact your JIRA administrators
For more information on JIRA, see: http://www.atlassian.com/software/jira
