[
https://issues.apache.org/jira/browse/CLOUDSTACK-4194?page=com.atlassian.jira.plugin.system.issuetabpanels:comment-tabpanel&focusedCommentId=13738943#comment-13738943
]
Venkata Siva Vijayendra Bhamidipati commented on CLOUDSTACK-4194:
-----------------------------------------------------------------
I don't see this issue on the latest 4.2 branch - Kelven had checked in changes
for datastore organization and those changes probably fixed this issue. Marking
as could not reproduce. Please reopen if you see it happening on latest builds.
The 4.2 tree I tested this with is on commit #
36cd824ba721ddbbee3f405a15d53702b22a113e
> Failed to detach volume after it got resized
> --------------------------------------------
>
> Key: CLOUDSTACK-4194
> URL: https://issues.apache.org/jira/browse/CLOUDSTACK-4194
> Project: CloudStack
> Issue Type: Bug
> Security Level: Public(Anyone can view this level - this is the
> default.)
> Components: Upgrade, VMware
> Affects Versions: 4.2.0
> Reporter: Sailaja Mada
> Assignee: Venkata Siva Vijayendra Bhamidipati
> Priority: Critical
> Labels: integration-test
> Fix For: 4.2.0
>
> Attachments: apilog.log, db.dmp, management-server.log
>
>
> Setup : Upgraded from 2.2.14 to 4.2
> 1. Configure Adv Zone with VMWARE cluster of 4.1 hosts
> 2. Upgrade from 2.2.14 to 4.2
> 3. Create DATA volume with small disk offering
> 4. Attach the volume to an instance
> 5. Resize the volume from 5 GB to 20 GB
> 6. Tried to detach after it for resized
> Observation:
> Failed to detach volume after it got resized
> 2013-08-08 22:18:16,003 DEBUG [cloud.api.ApiServlet] (catalina-exec-5:null)
> ===START=== 10.104.255.13 -- GET
> command=detachVolume&id=2f7e7b87-30c2-4677-a5db-ed0aafdb49eb&response=json&sessionkey=AHzQuwtysq%2FQRF50wMgI%2F7ukyrU%3D&_=1375980069954
> 2013-08-08 22:18:16,031 DEBUG [cloud.async.AsyncJobManagerImpl]
> (catalina-exec-5:null) submit async job-107 = [
> a29ebaae-b392-4ffc-be29-184d7233d0e4 ], details: AsyncJobVO {id:107, userId:
> 2, accountId: 2, sessionKey: null, instanceType: Volume, instanceId: 1153,
> cmd: org.apache.cloudstack.api.command.user.volume.DetachVolumeCmd,
> cmdOriginator: null, cmdInfo:
> {"response":"json","id":"2f7e7b87-30c2-4677-a5db-ed0aafdb49eb","sessionkey":"AHzQuwtysq/QRF50wMgI/7ukyrU\u003d","cmdEventType":"VOLUME.DETACH","ctxUserId":"2","httpmethod":"GET","_":"1375980069954","ctxAccountId":"2","ctxStartEventId":"391"},
> cmdVersion: 0, callbackType: 0, callbackAddress: null, status: 0,
> processStatus: 0, resultCode: 0, result: null, initMsid: 90310994128556,
> completeMsid: null, lastUpdated: null, lastPolled: null, created: null}
> 2013-08-08 22:18:16,033 DEBUG [cloud.api.ApiServlet] (catalina-exec-5:null)
> ===END=== 10.104.255.13 -- GET
> command=detachVolume&id=2f7e7b87-30c2-4677-a5db-ed0aafdb49eb&response=json&sessionkey=AHzQuwtysq%2FQRF50wMgI%2F7ukyrU%3D&_=1375980069954
> 2013-08-08 22:18:16,035 DEBUG [cloud.async.AsyncJobManagerImpl]
> (Job-Executor-25:job-107 = [ a29ebaae-b392-4ffc-be29-184d7233d0e4 ])
> Executing org.apache.cloudstack.api.command.user.volume.DetachVolumeCmd for
> job-107 = [ a29ebaae-b392-4ffc-be29-184d7233d0e4 ]
> 2013-08-08 22:18:16,071 DEBUG [agent.transport.Request]
> (Job-Executor-25:job-107 = [ a29ebaae-b392-4ffc-be29-184d7233d0e4 ]) Seq
> 1-475268002: Sending { Cmd , MgmtId: 90310994128556, via: 1, Ver: v1, Flags:
> 100011,
> [{"org.apache.cloudstack.storage.command.DettachCommand":{"disk":{"data":{"org.apache.cloudstack.storage.to.VolumeObjectTO":{"uuid":"2f7e7b87-30c2-4677-a5db-ed0aafdb49eb","volumeType":"DATADISK","dataStore":{"org.apache.cloudstack.storage.to.PrimaryDataStoreTO":{"uuid":"3f4c460a-1634-3db0-8b1f-60fe7b17a264","id":202,"poolType":"NetworkFilesystem","host":"10.102.192.100","path":"/cpg_vol/abhinav/esx-pri2","port":2049}},"name":"newsailaja3","size":6442450944,"path":"daa2d8dac9b8467abd5b670b2559ed46","volumeId":1153,"vmName":"i-2-1131-VM","accountId":2,"format":"OVA","id":1153,"hypervisorType":"VMware"}},"diskSeq":7,"type":"DATADISK"},"vmName":"i-2-1131-VM","_managed":false,"_storageHost":"10.102.192.100","_storagePort":2049,"wait":0}}]
> }
> 2013-08-08 22:18:16,071 DEBUG [agent.transport.Request]
> (Job-Executor-25:job-107 = [ a29ebaae-b392-4ffc-be29-184d7233d0e4 ]) Seq
> 1-475268002: Executing: { Cmd , MgmtId: 90310994128556, via: 1, Ver: v1,
> Flags: 100011,
> [{"org.apache.cloudstack.storage.command.DettachCommand":{"disk":{"data":{"org.apache.cloudstack.storage.to.VolumeObjectTO":{"uuid":"2f7e7b87-30c2-4677-a5db-ed0aafdb49eb","volumeType":"DATADISK","dataStore":{"org.apache.cloudstack.storage.to.PrimaryDataStoreTO":{"uuid":"3f4c460a-1634-3db0-8b1f-60fe7b17a264","id":202,"poolType":"NetworkFilesystem","host":"10.102.192.100","path":"/cpg_vol/abhinav/esx-pri2","port":2049}},"name":"newsailaja3","size":6442450944,"path":"daa2d8dac9b8467abd5b670b2559ed46","volumeId":1153,"vmName":"i-2-1131-VM","accountId":2,"format":"OVA","id":1153,"hypervisorType":"VMware"}},"diskSeq":7,"type":"DATADISK"},"vmName":"i-2-1131-VM","_managed":false,"_storageHost":"10.102.192.100","_storagePort":2049,"wait":0}}]
> }
> 2013-08-08 22:18:16,072 DEBUG [agent.manager.DirectAgentAttache]
> (DirectAgent-230:null) Seq 1-475268002: Executing request
> 2013-08-08 22:18:16,072 DEBUG [vmware.mo.HostMO]
> (DirectAgent-230:10.102.192.17) find VM i-2-1131-VM on host
> 2013-08-08 22:18:16,072 INFO [vmware.mo.HostMO]
> (DirectAgent-230:10.102.192.17) VM i-2-1131-VM not found in host cache
> 2013-08-08 22:18:16,072 DEBUG [vmware.mo.HostMO]
> (DirectAgent-230:10.102.192.17) load VM cache on host
> 2013-08-08 22:18:16,330 INFO [vmware.mo.DatastoreMO]
> (DirectAgent-230:10.102.192.17) Search file
> daa2d8dac9b8467abd5b670b2559ed46.vmdk on
> [3f4c460a-1634-3db0-8b1f-60fe7b17a264]
> 2013-08-08 22:18:16,377 INFO [vmware.mo.DatastoreMO]
> (DirectAgent-230:10.102.192.17) File [3f4c460a-1634-3db0-8b1f-60fe7b17a264]
> daa2d8dac9b8467abd5b670b2559ed46.vmdk does not exist on datastore
> 2013-08-08 22:18:16,387 ERROR [storage.resource.VmwareStorageProcessor]
> (DirectAgent-230:10.102.192.17) AttachVolumeCommand failed due to Exception:
> javax.xml.ws.soap.SOAPFaultException
> Message: null
> javax.xml.ws.soap.SOAPFaultException
> at
> com.sun.xml.internal.ws.fault.SOAP11Fault.getProtocolException(SOAP11Fault.java:178)
> at
> com.sun.xml.internal.ws.fault.SOAPFaultBuilder.createException(SOAPFaultBuilder.java:119)
> at
> com.sun.xml.internal.ws.client.sei.SyncMethodHandler.invoke(SyncMethodHandler.java:108)
> at
> com.sun.xml.internal.ws.client.sei.SyncMethodHandler.invoke(SyncMethodHandler.java:78)
> at com.sun.xml.internal.ws.client.sei.SEIStub.invoke(SEIStub.java:107)
> at sun.proxy.$Proxy90.removeSnapshotTask(Unknown Source)
> at
> com.cloud.hypervisor.vmware.mo.VirtualMachineMO.removeAllSnapshots(VirtualMachineMO.java:466)
> at
> com.cloud.storage.resource.VmwareStorageProcessor.attachVolume(VmwareStorageProcessor.java:1183)
> at
> com.cloud.storage.resource.VmwareStorageProcessor.attachVolume(VmwareStorageProcessor.java:1128)
> at
> com.cloud.storage.resource.VmwareStorageProcessor.dettachVolume(VmwareStorageProcessor.java:1309)
> at
> com.cloud.storage.resource.StorageSubsystemCommandHandlerBase.execute(StorageSubsystemCommandHandlerBase.java:138)
> at
> com.cloud.storage.resource.StorageSubsystemCommandHandlerBase.handleStorageCommands(StorageSubsystemCommandHandlerBase.java:57)
> at
> com.cloud.hypervisor.vmware.resource.VmwareResource.executeRequest(VmwareResource.java:560)
> at
> com.cloud.agent.manager.DirectAgentAttache$Task.run(DirectAgentAttache.java:186)
> 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.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$101(ScheduledThreadPoolExecutor.java:165)
> at
> java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:266)
> 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:679)
> 2013-08-08 22:18:16,388 DEBUG [agent.manager.DirectAgentAttache]
> (DirectAgent-230:null) Seq 1-475268002: Response Received:
> 2013-08-08 22:18:16,388 DEBUG [agent.transport.Request]
> (DirectAgent-230:null) Seq 1-475268002: Processing: { Ans: , MgmtId:
> 90310994128556, via: 1, Ver: v1, Flags: 10,
> [{"org.apache.cloudstack.storage.command.AttachAnswer":{"result":false,"details":"AttachVolumeCommand
> failed due to Exception: javax.xml.ws.soap.SOAPFaultException\nMessage:
> null\n","wait":0}}] }
> 2013-08-08 22:18:16,388 DEBUG [agent.transport.Request]
> (Job-Executor-25:job-107 = [ a29ebaae-b392-4ffc-be29-184d7233d0e4 ]) Seq
> 1-475268002: Received: { Ans: , MgmtId: 90310994128556, via: 1, Ver: v1,
> Flags: 10, { AttachAnswer } }
> 2013-08-08 22:18:16,394 ERROR [cloud.async.AsyncJobManagerImpl]
> (Job-Executor-25:job-107 = [ a29ebaae-b392-4ffc-be29-184d7233d0e4 ])
> Unexpected exception while executing
> org.apache.cloudstack.api.command.user.volume.DetachVolumeCmd
> com.cloud.utils.exception.CloudRuntimeException: Failed to detach volume:
> newsailaja3 from VM: v1-new-402; AttachVolumeCommand failed due to Exception:
> javax.xml.ws.soap.SOAPFaultException
> Message: null
> at
> com.cloud.storage.VolumeManagerImpl.detachVolumeFromVM(VolumeManagerImpl.java:2038)
> at
> com.cloud.utils.component.ComponentInstantiationPostProcessor$InterceptorDispatcher.intercept(ComponentInstantiationPostProcessor.java:125)
> at
> org.apache.cloudstack.api.command.user.volume.DetachVolumeCmd.execute(DetachVolumeCmd.java:133)
> 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:1146)
> at
> java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
> at java.lang.Thread.run(Thread.java:679)
> 2013-08-08 22:18:16,396 DEBUG [cloud.async.AsyncJobManagerImpl]
> (Job-Executor-25:job-107 = [ a29ebaae-b392-4ffc-be29-184d7233d0e4 ]) Complete
> async job-107 = [ a29ebaae-b392-4ffc-be29-184d7233d0e4 ], jobStatus: 2,
> resultCode: 530, result: Error Code: 530 Error text: Failed to detach volume:
> newsailaja3 from VM: v1-new-402; AttachVolumeCommand failed due to Exception:
> javax.xml.ws.soap.SOAPFaultException
> Message: null
> 2013-08-08 22:18:19,182 DEBUG [cloud.api.ApiServlet] (catalina-exec-1:null)
> ===START=== 10.104.255.13 -- GET
> command=queryAsyncJobResult&jobId=a29ebaae-b392-4ffc-be29-184d7233d0e4&response=json&sessionkey=AHzQuwtysq%2FQRF50wMgI%2F7ukyrU%3D&_=1375980073170
> 2013-08-08 22:18:19,196 DEBUG [cloud.async.AsyncJobManagerImpl]
> (catalina-exec-1:null) Async job-107 = [ a29ebaae-b392-4ffc-be29-184d7233d0e4
> ] completed
> 2013-08-08 22:18:19,201 DEBUG [cloud.api.ApiServlet] (catalina-exec-1:null)
> ===END=== 10.104.255.13 -- GET
> command=queryAsyncJobResult&jobId=a29ebaae-b392-4ffc-be29-184d7233d0e4&response=json&sessionkey=AHzQuwtysq%2FQRF50wMgI%2F7ukyrU%3D&_=1375980073170
--
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
