[ https://issues.apache.org/jira/browse/CLOUDSTACK-3530?page=com.atlassian.jira.plugin.system.issuetabpanels:all-tabpanel ]
Sailaja Mada updated CLOUDSTACK-3530: ------------------------------------- Attachment: management-server.log apilog.log > Runtime exception while creating Snapshot from volume (ROOT/DATA) > ----------------------------------------------------------------- > > Key: CLOUDSTACK-3530 > URL: https://issues.apache.org/jira/browse/CLOUDSTACK-3530 > Project: CloudStack > Issue Type: Bug > Security Level: Public(Anyone can view this level - this is the > default.) > Components: Snapshot, Storage Controller, VMware > Affects Versions: 4.2.0 > Reporter: Sailaja Mada > Priority: Blocker > Attachments: apilog.log, management-server.log > > > Steps: > 1. Configure Advanced Zone with VMWARE (Standard vSwitch) > 2. Register new template (Cent OS 6.2) > 3. Create new account and deployed instance with this user > 4. Add new volume and attached to the instance. > 5. Tried to create snapshot from this DATA volume . > Observation : > 2013-07-15 16:42:09,475 DEBUG [agent.transport.Request] (DirectAgent-48:null) > Seq 1-1176305828: Processing: { Ans: , MgmtId: 187767034175903, via: 1, Ver: > v1, Flags: 10, > [{"org.apache.cloudstack.storage.command.CreateObjectAnswer":{"data":{"org.apache.cloudstack.storage.to.SnapshotObjectTO":{"path":"474f05b4-0621-4f5b-9d8e-f841392d5d35","id":0}},"result":true,"wait":0}}] > } > 2013-07-15 16:42:09,478 DEBUG [agent.transport.Request] > (Job-Executor-28:job-25 = [ 0e251591-8270-46bd-a52d-1e7cbf39f62b ]) Seq > 1-1176305828: Received: { Ans: , MgmtId: 187767034175903, via: 1, Ver: v1, > Flags: 10, { CreateObjectAnswer } } > 2013-07-15 16:42:09,553 DEBUG [storage.motion.AncientDataMotionStrategy] > (Job-Executor-28:job-25 = [ 0e251591-8270-46bd-a52d-1e7cbf39f62b ]) copyAsync > inspecting src type SNAPSHOT copyAsync inspecting dest type SNAPSHOT > 2013-07-15 16:42:09,669 DEBUG [agent.transport.Request] > (Job-Executor-28:job-25 = [ 0e251591-8270-46bd-a52d-1e7cbf39f62b ]) Seq > 3-84803757: Sending { Cmd , MgmtId: 187767034175903, via: 3, Ver: v1, Flags: > 100011, > [{"org.apache.cloudstack.storage.command.CopyCommand":{"srcTO":{"org.apache.cloudstack.storage.to.SnapshotObjectTO":{"path":"474f05b4-0621-4f5b-9d8e-f841392d5d35","volume":{"uuid":"53ae541c-5dfa-4352-924c-8a7e727e3129","volumeType":"DATADISK","dataStore":{"org.apache.cloudstack.storage.to.PrimaryDataStoreTO":{"uuid":"55e6d095-7e22-3f4a-b533-874a9fede8df","id":1,"poolType":"NetworkFilesystem","host":"10.102.192.100","path":"/cpg_vol/sailaja/vmwareps1","port":2049}},"name":"vol2","size":5120,"path":"03b2f1a817524977b37a5693b711186d","volumeId":6,"accountId":3,"format":"OVA","id":6},"dataStore":{"org.apache.cloudstack.storage.to.PrimaryDataStoreTO":{"uuid":"55e6d095-7e22-3f4a-b533-874a9fede8df","id":1,"poolType":"NetworkFilesystem","host":"10.102.192.100","path":"/cpg_vol/sailaja/vmwareps1","port":2049}},"name":"detached_vol2_20130715111209","hypervisorType":"VMware","id":3}},"destTO":{"org.apache.cloudstack.storage.to.SnapshotObjectTO":{"path":"snapshots/3/6","volume":{"uuid":"53ae541c-5dfa-4352-924c-8a7e727e3129","volumeType":"DATADISK","dataStore":{"org.apache.cloudstack.storage.to.PrimaryDataStoreTO":{"uuid":"55e6d095-7e22-3f4a-b533-874a9fede8df","id":1,"poolType":"NetworkFilesystem","host":"10.102.192.100","path":"/cpg_vol/sailaja/vmwareps1","port":2049}},"name":"vol2","size":5120,"path":"03b2f1a817524977b37a5693b711186d","volumeId":6,"accountId":3,"format":"OVA","id":6},"dataStore":{"com.cloud.agent.api.to.NfsTO":{"_url":"nfs://10.102.192.100/cpg_vol/sailaja/vmwaress1","_role":"Image"}},"name":"detached_vol2_20130715111209","hypervisorType":"VMware","id":3}},"executeInSequence":false,"wait":21600}}] > } > 2013-07-15 16:42:10,094 DEBUG [agent.transport.Request] > (AgentManager-Handler-15:null) Seq 3-84803757: Processing: { Ans: , MgmtId: > 187767034175903, via: 3, Ver: v1, Flags: 10, > [{"org.apache.cloudstack.storage.command.CopyCmdAnswer":{"result":false,"details":"BackupSnapshotCommand > exception: java.lang.RuntimeException: A specified parameter was not > correct. > \nconfig.name\ncom.cloud.hypervisor.vmware.util.VmwareClient.waitForTask(VmwareClient.java:290)\ncom.cloud.hypervisor.vmware.mo.HostMO.createVm(HostMO.java:525)\ncom.cloud.hypervisor.vmware.mo.HypervisorHostHelper.createBlankVm(HypervisorHostHelper.java:1149)\ncom.cloud.hypervisor.vmware.mo.HostMO.createBlankVm(HostMO.java:700)\ncom.cloud.storage.resource.VmwareStorageProcessor.backupSnapshot(VmwareStorageProcessor.java:703)\ncom.cloud.storage.resource.StorageSubsystemCommandHandlerBase.execute(StorageSubsystemCommandHandlerBase.java:84)\ncom.cloud.storage.resource.StorageSubsystemCommandHandlerBase.handleStorageCommands(StorageSubsystemCommandHandlerBase.java:49)\ncom.cloud.storage.resource.VmwareSecondaryStorageResourceHandler.executeRequest(VmwareSecondaryStorageResourceHandler.java:98)\ncom.cloud.storage.resource.PremiumSecondaryStorageResource.executeRequest(PremiumSecondaryStorageResource.java:56)\ncom.cloud.agent.Agent.processRequest(Agent.java:525)\ncom.cloud.agent.Agent$AgentRequestHandler.doTask(Agent.java:852)\ncom.cloud.utils.nio.Task.run(Task.java:83)\njava.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1146)\njava.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)\njava.lang.Thread.run(Thread.java:679)\n","wait":0}}] > } > 2013-07-15 16:42:10,096 DEBUG [agent.transport.Request] > (Job-Executor-28:job-25 = [ 0e251591-8270-46bd-a52d-1e7cbf39f62b ]) Seq > 3-84803757: Received: { Ans: , MgmtId: 187767034175903, via: 3, Ver: v1, > Flags: 10, { CopyCmdAnswer } } > 2013-07-15 16:42:10,131 DEBUG [storage.snapshot.SnapshotManagerImpl] > (Job-Executor-28:job-25 = [ 0e251591-8270-46bd-a52d-1e7cbf39f62b ]) Failed to > create snapshot > com.cloud.utils.exception.CloudRuntimeException: BackupSnapshotCommand > exception: java.lang.RuntimeException: A specified parameter was not correct. > config.name > com.cloud.hypervisor.vmware.util.VmwareClient.waitForTask(VmwareClient.java:290) > com.cloud.hypervisor.vmware.mo.HostMO.createVm(HostMO.java:525) > com.cloud.hypervisor.vmware.mo.HypervisorHostHelper.createBlankVm(HypervisorHostHelper.java:1149) > com.cloud.hypervisor.vmware.mo.HostMO.createBlankVm(HostMO.java:700) > com.cloud.storage.resource.VmwareStorageProcessor.backupSnapshot(VmwareStorageProcessor.java:703) > com.cloud.storage.resource.StorageSubsystemCommandHandlerBase.execute(StorageSubsystemCommandHandlerBase.java:84) > com.cloud.storage.resource.StorageSubsystemCommandHandlerBase.handleStorageCommands(StorageSubsystemCommandHandlerBase.java:49) > com.cloud.storage.resource.VmwareSecondaryStorageResourceHandler.executeRequest(VmwareSecondaryStorageResourceHandler.java:98) > com.cloud.storage.resource.PremiumSecondaryStorageResource.executeRequest(PremiumSecondaryStorageResource.java:56) > com.cloud.agent.Agent.processRequest(Agent.java:525) > com.cloud.agent.Agent$AgentRequestHandler.doTask(Agent.java:852) > com.cloud.utils.nio.Task.run(Task.java:83) > java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1146) > java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615) > java.lang.Thread.run(Thread.java:679) > at > org.apache.cloudstack.storage.snapshot.SnapshotServiceImpl.backupSnapshot(SnapshotServiceImpl.java:282) > at > org.apache.cloudstack.storage.snapshot.XenserverSnapshotStrategy.backupSnapshot(XenserverSnapshotStrategy.java:127) > at > org.apache.cloudstack.storage.snapshot.XenserverSnapshotStrategy.takeSnapshot(XenserverSnapshotStrategy.java:197) > at > com.cloud.storage.snapshot.SnapshotManagerImpl.takeSnapshot(SnapshotManagerImpl.java:978) > at > org.apache.cloudstack.storage.volume.VolumeServiceImpl.takeSnapshot(VolumeServiceImpl.java:1171) > at > com.cloud.storage.VolumeManagerImpl.takeSnapshot(VolumeManagerImpl.java:2664) > at > org.apache.cloudstack.api.command.user.snapshot.CreateSnapshotCmd.execute(CreateSnapshotCmd.java:170) > 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-07-15 16:42:10,151 DEBUG [storage.volume.VolumeServiceImpl] > (Job-Executor-28:job-25 = [ 0e251591-8270-46bd-a52d-1e7cbf39f62b ]) Take > snapshot: 6 failed: com.cloud.utils.exception.CloudRuntimeException: Failed > to create snapshot > 2013-07-15 16:42:10,170 DEBUG [cloud.async.AsyncJobManagerImpl] > (Job-Executor-28:job-25 = [ 0e251591-8270-46bd-a52d-1e7cbf39f62b ]) Complete > async job-25 = [ 0e251591-8270-46bd-a52d-1e7cbf39f62b ], jobStatus: 2, > resultCode: 530, result: Error Code: 530 Error text: Failed to create > snapshot due to an internal error creating snapshot for volume 6 > 2013-07-15 16:42:10,461 DEBUG [agent.manager.AgentManagerImpl] > (AgentManager-Handler-13:null) Ping from 2 > 2013-07-15 16:42:11,844 DEBUG [agent.manager.AgentManagerImpl] > (AgentManager-Handler-14:null) SeqA 2-333: Processing Seq 2-333: { Cmd , > MgmtId: -1, via: 2, Ver: v1, Flags: 11, > [{"com.cloud.agent.api.ConsoleProxyLoadReportCommand":{"_proxyVmId":2,"_loadInfo":"{\n > \"connections\": [\n {\n \"id\": 2,\n \"clientInfo\": \"\",\n > \"host\": \"10.102.192.18\",\n \"port\": 5900,\n \"tag\": > \"67f51c10-d65d-41fa-9ae4-21abc9373b9f\",\n \"createTime\": > 1373884796912,\n \"lastUsedTime\": 1373884829233\n }\n > ]\n}","wait":0}}] } > 2013-07-15 16:42:11,850 DEBUG [agent.manager.AgentManagerImpl] > (AgentManager-Handler-14:null) SeqA 2-333: Sending Seq 2-333: { Ans: , > MgmtId: 187767034175903, via: 2, Ver: v1, Flags: 100010, > [{"com.cloud.agent.api.AgentControlAnswer":{"result":true,"wait":0}}] } > 2013-07-15 16:42:12,321 DEBUG [agent.transport.Request] (Timer-10:null) Seq > 3-84803758: Sending { Cmd , MgmtId: 187767034175903, via: 3, Ver: v1, Flags: > 100011, > [{"org.apache.cloudstack.storage.command.DownloadProgressCommand":{"jobId":"9a856fd6-a1b6-452e-8b0b-8f10d10e36c3","request":"GET_STATUS","hvm":false,"description":"CentOS > 5.3(64-bit) no GUI > (vSphere)","checksum":"f6f881b7f2292948d8494db837fe0f47","maxDownloadSizeInBytes":53687091200,"id":7,"resourceType":"TEMPLATE","installPath":"template/tmpl/1/7","_store":{"com.cloud.agent.api.to.NfsTO":{"_url":"nfs://10.102.192.100/cpg_vol/sailaja/vmwaress1","_role":"Image"}},"url":"http://download.cloud.com/releases/2.2.0/CentOS5.3-x86_64.ova","format":"OVA","accountId":1,"name":"centos53-x64","secUrl":"nfs://10.102.192.100/cpg_vol/sailaja/vmwaress1","wait":0}}] > } > 2013-07-15 16:42:12,329 DEBUG [agent.transport.Request] > (AgentManager-Handler-1:null) Seq 3-84803758: Processing: { Ans: , MgmtId: > 187767034175903, via: 3, Ver: v1, Flags: 10, > [{"com.cloud.agent.api.storage.DownloadAnswer":{"jobId":"9a856fd6-a1b6-452e-8b0b-8f10d10e36c3","downloadPct":11,"errorString":" > > ","downloadStatus":"DOWNLOAD_IN_PROGRESS","downloadPath":"/mnt/SecStorage/756c37ec-129a-3615-a376-c8f1f77fa7aa/template/tmpl/1/7/dnld5325549045825883254tmp_","installPath":"template/tmpl/1/7","templateSize":0,"templatePhySicalSize":0,"checkSum":"f6f881b7f2292948d8494db837fe0f47","result":true,"details":" > ","wait":0}}] } > 2013-07-15 16:42:12,383 DEBUG [cloud.api.ApiServlet] (catalina-exec-15:null) > ===START=== 10.144.6.19 -- GET > command=queryAsyncJobResult&jobId=0e251591-8270-46bd-a52d-1e7cbf39f62b&response=json&sessionkey=x3Ny9sOz91aeGO5APAxX1WEd%2BWg%3D&_=1373886943581 -- 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