Rayees Namathponnan created CLOUDSTACK-5022:
-----------------------------------------------
Summary: [Automation] Failed to create volume from snapshot in KVM
Key: CLOUDSTACK-5022
URL: https://issues.apache.org/jira/browse/CLOUDSTACK-5022
Project: CloudStack
Issue Type: Bug
Security Level: Public (Anyone can view this level - this is the default.)
Components: Volumes
Affects Versions: 4.2.1
Environment: KVM (RHEL 6.3)
Build : 4.2
Reporter: Rayees Namathponnan
Priority: Blocker
Fix For: 4.2.1
Steps to reproduce
Step 1 : Create snapshot of ROOT volume
Step 2 : Create volume from snapshot
Failed to create volume with below null pointer exception
2013-11-01 08:19:18,229 DEBUG [agent.transport.Request]
(Job-Executor-147:job-6486 = [ 14b465b9-0c03-4783-866f-1296b20c358b ]) Seq
1-894971116: Sending { Cmd , MgmtId: 29066118877352, via: 1, Ver: v1, Flags:
100001,
[{"com.cloud.agent.api.routing.IpAssocCommand":{"ipAddresses":[{"accountId":911,"publicIp":"10.223.122.92","sourceNat":true,"add":true,"oneToOneNat":false,"firstIP":true,"vlanId":"1221","vlanGateway":"10.223.122.65","vlanNetmask":"255.255.255.192","vifMacAddress":"06:39:a6:00:00:55","networkRate":200,"trafficType":"Public"},{"accountId":911,"publicIp":"10.223.122.113","sourceNat":false,"add":true,"oneToOneNat":false,"firstIP":false,"vlanId":"1221","vlanGateway":"10.223.122.65","vlanNetmask":"255.255.255.192","vifMacAddress":"06:52:bb:00:00:55","networkRate":200,"trafficType":"Public"},{"accountId":911,"publicIp":"10.223.122.79","sourceNat":false,"add":true,"oneToOneNat":false,"firstIP":false,"vlanId":"1221","vlanGateway":"10.223.122.65","vlanNetmask":"255.255.255.192","vifMacAddress":"06:52:bb:00:00:55","networkRate":200,"trafficType":"Public"}],"accessDetails":{"router.guest.ip":"10.1.1.1","zone.network.type":"Advanced","router.ip":"169.254.0.81","router.name":"r-1662-QA"},"wait":0}}]
}
2013-11-01 08:19:18,345 DEBUG [agent.transport.Request]
(AgentManager-Handler-6:null) Seq 2-877402380: Processing: { Ans: , MgmtId:
29066118877352, via: 2, Ver: v1, Flags: 110,
[{"com.cloud.agent.api.Answer":{"result":false,"details":"java.lang.NullPointerException\n\tat
com.cloud.hypervisor.kvm.storage.KVMStorageProcessor.createVolumeFromSnapshot(KVMStorageProcessor.java:1178)\n\tat
com.cloud.storage.resource.StorageSubsystemCommandHandlerBase.execute(StorageSubsystemCommandHandlerBase.java:86)\n\tat
com.cloud.storage.resource.StorageSubsystemCommandHandlerBase.handleStorageCommands(StorageSubsystemCommandHandlerBase.java:49)\n\tat
com.cloud.hypervisor.kvm.resource.LibvirtComputingResource.executeRequest(LibvirtComputingResource.java:1286)\n\tat
com.cloud.agent.Agent.processRequest(Agent.java:525)\n\tat
com.cloud.agent.Agent$AgentRequestHandler.doTask(Agent.java:852)\n\tat
com.cloud.utils.nio.Task.run(Task.java:83)\n\tat
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1110)\n\tat
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:603)\n\tat
java.lang.Thread.run(Thread.java:679)\n","wait":0}}] }
2013-11-01 08:19:18,345 DEBUG [agent.manager.AgentAttache]
(AgentManager-Handler-6:null) Seq 2-877402380: No more commands found
2013-11-01 08:19:18,345 DEBUG [agent.transport.Request]
(Job-Executor-120:job-6487 = [ f6fbb88e-1fe1-4507-9406-b8e62d9e1f70 ]) Seq
2-877402380: Received: { Ans: , MgmtId: 29066118877352, via: 2, Ver: v1,
Flags: 110, { Answer } }
2013-11-01 08:19:18,350 WARN [storage.datastore.ObjectInDataStoreManagerImpl]
(Job-Executor-120:job-6487 = [ f6fbb88e-1fe1-4507-9406-b8e62d9e1f70 ])
Unsupported data object (VOLUME,
org.apache.cloudstack.storage.datastore.PrimaryDataStoreImpl@3918ddd), no need
to delete from object in store ref table
2013-11-01 08:19:18,372 WARN [storage.datastore.ObjectInDataStoreManagerImpl]
(Job-Executor-120:job-6487 = [ f6fbb88e-1fe1-4507-9406-b8e62d9e1f70 ]) Snapshot
38 is not found on image store 1, so no need to delete
2013-11-01 08:19:18,372 DEBUG [cloud.storage.VolumeManagerImpl]
(Job-Executor-120:job-6487 = [ f6fbb88e-1fe1-4507-9406-b8e62d9e1f70 ]) Failed
to create volume from snapshot:java.lang.NullPointerException
at
com.cloud.hypervisor.kvm.storage.KVMStorageProcessor.createVolumeFromSnapshot(KVMStorageProcessor.java:1178)
at
com.cloud.storage.resource.StorageSubsystemCommandHandlerBase.execute(StorageSubsystemCommandHandlerBase.java:86)
at
com.cloud.storage.resource.StorageSubsystemCommandHandlerBase.handleStorageCommands(StorageSubsystemCommandHandlerBase.java:49)
at
com.cloud.hypervisor.kvm.resource.LibvirtComputingResource.executeRequest(LibvirtComputingResource.java:1286)
at com.cloud.agent.Agent.processRequest(Agent.java:525)
at com.cloud.agent.Agent$AgentRequestHandler.doTask(Agent.java:852)
at com.cloud.utils.nio.Task.run(Task.java:83)
at
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1110)
at
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:603)
at java.lang.Thread.run(Thread.java:679)
2013-11-01 08:19:18,372 DEBUG [cloud.storage.VolumeManagerImpl]
(Job-Executor-120:job-6487 = [ f6fbb88e-1fe1-4507-9406-b8e62d9e1f70 ]) Failed
to create volume: 1784
com.cloud.utils.exception.CloudRuntimeException: Failed to create volume from
snapshot:java.lang.NullPointerException
at
com.cloud.hypervisor.kvm.storage.KVMStorageProcessor.createVolumeFromSnapshot(KVMStorageProcessor.java:1178)
at
com.cloud.storage.resource.StorageSubsystemCommandHandlerBase.execute(StorageSubsystemCommandHandlerBase.java:86)
at
com.cloud.storage.resource.StorageSubsystemCommandHandlerBase.handleStorageCommands(StorageSubsystemCommandHandlerBase.java:49)
at
com.cloud.hypervisor.kvm.resource.LibvirtComputingResource.executeRequest(LibvirtComputingResource.java:1286)
at com.cloud.agent.Agent.processRequest(Agent.java:525)
at com.cloud.agent.Agent$AgentRequestHandler.doTask(Agent.java:852)
at com.cloud.utils.nio.Task.run(Task.java:83)
at
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1110)
at
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:603)
at java.lang.Thread.run(Thread.java:679)
at
com.cloud.storage.VolumeManagerImpl.createVolumeFromSnapshot(VolumeManagerImpl.java:559)
at
com.cloud.utils.component.ComponentInstantiationPostProcessor$InterceptorDispatcher.intercept(ComponentInstantiationPostProcessor.java:125)
at
com.cloud.storage.VolumeManagerImpl.createVolumeFromSnapshot(VolumeManagerImpl.java:604)
at
com.cloud.storage.VolumeManagerImpl.createVolume(VolumeManagerImpl.java:1057)
at
com.cloud.utils.component.ComponentInstantiationPostProcessor$InterceptorDispatcher.intercept(ComponentInstantiationPostProcessor.java:125)
at
com.cloud.storage.VolumeManagerImpl.createVolume(VolumeManagerImpl.java:183)
at
org.apache.cloudstack.api.command.user.volume.CreateVolumeCmd.execute(CreateVolumeCmd.java:189)
at com.cloud.api.ApiDispatcher.dispatch(ApiDispatcher.java:158)
at
com.cloud.async.AsyncJobManagerImpl$1.run(AsyncJobManagerImpl.java:531)
at
java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:471)
at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:334)
at java.util.concurrent.FutureTask.run(FutureTask.java:166)
at
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1110)
at
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:603)
at java.lang.Thread.run(Thread.java:679)
2013-11-01 08:19:18,398 DEBUG [cloud.async.AsyncJobManagerImpl]
(Job-Executor-120:job-6487 = [ f6fbb88e-1fe1-4507-9406-b8e62d9e1f70 ]) Complete
async job-6487 = [ f6fbb88e-1fe1-4507-9406-b8e62d9e1f70 ], jobStatus: 2,
resultCode: 530, result: Error Code: 530 Error text: Failed to create a volume
2013-11-01 08:19:18,492 DEBUG [agent.transport.Request] (StatsCollector-2:null)
Seq 1-894971115: Received: { Ans: , MgmtId: 29066118877352, via: 1, Ver: v1,
Flags: 10, { GetVmStatsAnswer } }
--
This message was sent by Atlassian JIRA
(v6.1#6144)
