You are viewing a plain text version of this content. The canonical link for it is here.
Posted to issues@cloudstack.apache.org by "Chandan Purushothama (JIRA)" <ji...@apache.org> on 2013/07/23 23:56:49 UTC

[jira] [Created] (CLOUDSTACK-3755) Object_Store_Refactor - [KVM] Attach Data Volume to VM fails

Chandan Purushothama created CLOUDSTACK-3755:
------------------------------------------------

             Summary: Object_Store_Refactor - [KVM] Attach Data Volume to VM fails
                 Key: CLOUDSTACK-3755
                 URL: https://issues.apache.org/jira/browse/CLOUDSTACK-3755
             Project: CloudStack
          Issue Type: Bug
      Security Level: Public (Anyone can view this level - this is the default.)
          Components: Management Server
    Affects Versions: 4.2.0
            Reporter: Chandan Purushothama
            Priority: Critical
             Fix For: 4.2.0



=================
Steps to Reproduce:
=================

1. Deploy a Linux VM using a Local Storage Service Offering.
2. Create a New Data Disk
3. Try to attach the new Data Disk to the VM deployed in Step 1.

================
Attach Volume Job:
================

2013-07-23 14:25:53,414 DEBUG [cloud.api.ApiServlet] (catalina-exec-3:null) ===START===  10.252.112.197 -- GET  command=attachVolume&id=a65b1c76-f2e1-463a-a5d7-6ef1aa91a145&virtualMachineId=f51470c1-ca1e-4dca-9c8d-ad21e3095613&response=json&sessionkey=Y1grYFDP88FrKV%2BZpAXfM6SCXEk%3D&_=1374615108069
2013-07-23 14:25:53,452 DEBUG [cloud.async.AsyncJobManagerImpl] (catalina-exec-3:null) submit async job-53 = [ 3f28c925-3aba-4d1c-a3fa-a676e5ac7894 ], details: AsyncJobVO {id:53, userId: 3, accountId: 3, sessionKey: null, instanceType: Volume, instanceId: 12, cmd: org.apache.cloudstack.api.command.user.volume.AttachVolumeCmd, cmdOriginator: null, cmdInfo: {"response":"json","id":"a65b1c76-f2e1-463a-a5d7-6ef1aa91a145","sessionkey":"Y1grYFDP88FrKV+ZpAXfM6SCXEk\u003d","cmdEventType":"VOLUME.ATTACH","ctxUserId":"3","virtualMachineId":"f51470c1-ca1e-4dca-9c8d-ad21e3095613","httpmethod":"GET","_":"1374615108069","ctxAccountId":"3","ctxStartEventId":"207"}, cmdVersion: 0, callbackType: 0, callbackAddress: null, status: 0, processStatus: 0, resultCode: 0, result: null, initMsid: 7471666038533, completeMsid: null, lastUpdated: null, lastPolled: null, created: null}
2013-07-23 14:25:53,454 DEBUG [cloud.api.ApiServlet] (catalina-exec-3:null) ===END===  10.252.112.197 -- GET  command=attachVolume&id=a65b1c76-f2e1-463a-a5d7-6ef1aa91a145&virtualMachineId=f51470c1-ca1e-4dca-9c8d-ad21e3095613&response=json&sessionkey=Y1grYFDP88FrKV%2BZpAXfM6SCXEk%3D&_=1374615108069
2013-07-23 14:25:53,455 DEBUG [cloud.async.AsyncJobManagerImpl] (Job-Executor-24:job-53 = [ 3f28c925-3aba-4d1c-a3fa-a676e5ac7894 ]) Executing org.apache.cloudstack.api.command.user.volume.AttachVolumeCmd for job-53 = [ 3f28c925-3aba-4d1c-a3fa-a676e5ac7894 ]
2013-07-23 14:25:53,509 DEBUG [cloud.user.AccountManagerImpl] (Job-Executor-24:job-53 = [ 3f28c925-3aba-4d1c-a3fa-a676e5ac7894 ]) Access to org.apache.cloudstack.storage.volume.VolumeObject@56c6f339 granted to Acct[3-atoms] by DomainChecker_EnhancerByCloudStack_ccb7a71
2013-07-23 14:25:53,510 DEBUG [cloud.user.AccountManagerImpl] (Job-Executor-24:job-53 = [ 3f28c925-3aba-4d1c-a3fa-a676e5ac7894 ]) Access to VM[User|Atoms-VM-2] granted to Acct[3-atoms] by DomainChecker_EnhancerByCloudStack_ccb7a71
2013-07-23 14:25:53,530 DEBUG [storage.allocator.LocalStoragePoolAllocator] (Job-Executor-24:job-53 = [ 3f28c925-3aba-4d1c-a3fa-a676e5ac7894 ]) LocalStoragePoolAllocator trying to find storage pool to fit the vm
2013-07-23 14:25:53,530 DEBUG [storage.allocator.ClusterScopeStoragePoolAllocator] (Job-Executor-24:job-53 = [ 3f28c925-3aba-4d1c-a3fa-a676e5ac7894 ]) ClusterScopeStoragePoolAllocator looking for storage pool
2013-07-23 14:25:53,531 DEBUG [storage.allocator.ClusterScopeStoragePoolAllocator] (Job-Executor-24:job-53 = [ 3f28c925-3aba-4d1c-a3fa-a676e5ac7894 ]) Looking for pools in dc: 1  pod:1  cluster:1
2013-07-23 14:25:53,533 DEBUG [storage.allocator.AbstractStoragePoolAllocator] (Job-Executor-24:job-53 = [ 3f28c925-3aba-4d1c-a3fa-a676e5ac7894 ]) Checking if storage pool is suitable, name: null ,poolId: 1
2013-07-23 14:25:53,536 DEBUG [cloud.storage.StorageManagerImpl] (Job-Executor-24:job-53 = [ 3f28c925-3aba-4d1c-a3fa-a676e5ac7894 ]) Checking pool 1 for storage, totalSize: 11810778316800, usedBytes: 7590987268096, usedPct: 0.6427169374010141, disable threshold: 0.85
2013-07-23 14:25:53,550 DEBUG [cloud.storage.StorageManagerImpl] (Job-Executor-24:job-53 = [ 3f28c925-3aba-4d1c-a3fa-a676e5ac7894 ]) Checking pool: 1 for volume allocation [Vol[12|vm=null|DATADISK]], maxSize : 23621556633600, totalAllocatedSize : 1032192, askingSize : 5368709120, allocated disable threshold: 0.85
2013-07-23 14:25:53,550 DEBUG [storage.allocator.ClusterScopeStoragePoolAllocator] (Job-Executor-24:job-53 = [ 3f28c925-3aba-4d1c-a3fa-a676e5ac7894 ]) FirstFitStoragePoolAllocator returning 1 suitable storage pools
2013-07-23 14:25:53,551 DEBUG [cloud.storage.VolumeManagerImpl] (Job-Executor-24:job-53 = [ 3f28c925-3aba-4d1c-a3fa-a676e5ac7894 ]) Trying to create org.apache.cloudstack.storage.volume.VolumeObject@56c6f339 on org.apache.cloudstack.storage.datastore.PrimaryDataStoreImpl@45253b24
2013-07-23 14:25:53,565 DEBUG [datastore.driver.CloudStackPrimaryDataStoreDriverImpl] (Job-Executor-24:job-53 = [ 3f28c925-3aba-4d1c-a3fa-a676e5ac7894 ]) Creating volume: org.apache.cloudstack.storage.volume.VolumeObject@4d155a55
2013-07-23 14:25:53,568 DEBUG [agent.manager.AgentAttache] (Job-Executor-24:job-53 = [ 3f28c925-3aba-4d1c-a3fa-a676e5ac7894 ]) Request seq: 710935760
2013-07-23 14:25:53,568 DEBUG [agent.manager.AgentAttache] (Job-Executor-24:job-53 = [ 3f28c925-3aba-4d1c-a3fa-a676e5ac7894 ]) waiting to send 710935760
2013-07-23 14:25:53,568 DEBUG [agent.manager.AgentAttache] (Job-Executor-24:job-53 = [ 3f28c925-3aba-4d1c-a3fa-a676e5ac7894 ]) entering synchronize block for sending 710935760
2013-07-23 14:25:53,568 DEBUG [agent.transport.Request] (Job-Executor-24:job-53 = [ 3f28c925-3aba-4d1c-a3fa-a676e5ac7894 ]) Seq 1-710935760: Sending  { Cmd , MgmtId: 7471666038533, via: 1, Ver: v1, Flags: 100011, [{"org.apache.cloudstack.storage.command.CreateObjectCommand":{"data":{"org.apache.cloudstack.storage.to.VolumeObjectTO":{"uuid":"a65b1c76-f2e1-463a-a5d7-6ef1aa91a145","volumeType":"DATADISK","dataStore":{"org.apache.cloudstack.storage.to.PrimaryDataStoreTO":{"uuid":"ef3dea99-0a1b-35b9-87ec-d8cabe7ed9c1","id":1,"poolType":"NetworkFilesystem","host":"10.223.110.232","path":"/export/home/chandan/campocr-195-103/primarykvm","port":2049}},"name":"Data Volume 2","size":5368709120,"volumeId":12,"accountId":3,"id":12}},"wait":0}}] }
2013-07-23 14:25:53,716 DEBUG [agent.transport.Request] (AgentManager-Handler-4:null) Seq 1-710935760: Processing:  { Ans: , MgmtId: 7471666038533, via: 1, Ver: v1, Flags: 10, [{"org.apache.cloudstack.storage.command.CreateObjectAnswer":{"data":{"org.apache.cloudstack.storage.to.VolumeObjectTO":{"size":0,"path":"a1740772-1cce-47f6-9828-ff7e45ebe012","accountId":0,"id":0}},"result":true,"wait":0}}] }
2013-07-23 14:25:53,716 DEBUG [agent.transport.Request] (Job-Executor-24:job-53 = [ 3f28c925-3aba-4d1c-a3fa-a676e5ac7894 ]) Seq 1-710935760: Received:  { Ans: , MgmtId: 7471666038533, via: 1, Ver: v1, Flags: 10, { CreateObjectAnswer } }
2013-07-23 14:25:53,768 ERROR [cloud.async.AsyncJobManagerImpl] (Job-Executor-24:job-53 = [ 3f28c925-3aba-4d1c-a3fa-a676e5ac7894 ]) Unexpected exception while executing org.apache.cloudstack.api.command.user.volume.AttachVolumeCmd
com.cloud.utils.exception.CloudRuntimeException: Can't move volume between scope: CLUSTER and HOST
        at com.cloud.storage.VolumeManagerImpl.needMoveVolume(VolumeManagerImpl.java:1571)
        at com.cloud.storage.VolumeManagerImpl.attachVolumeToVM(VolumeManagerImpl.java:1824)
        at com.cloud.utils.component.ComponentInstantiationPostProcessor$InterceptorDispatcher.intercept(ComponentInstantiationPostProcessor.java:125)
        at org.apache.cloudstack.api.command.user.volume.AttachVolumeCmd.execute(AttachVolumeCmd.java:122)
        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-23 14:25:53,770 DEBUG [cloud.async.AsyncJobManagerImpl] (Job-Executor-24:job-53 = [ 3f28c925-3aba-4d1c-a3fa-a676e5ac7894 ]) Complete async job-53 = [ 3f28c925-3aba-4d1c-a3fa-a676e5ac7894 ], jobStatus: 2, resultCode: 530, result: Error Code: 530 Error text: Can't move volume between scope: CLUSTER and HOST
2013-07-23 14:25:54,122 DEBUG [cloud.server.StatsCollector] (StatsCollector-3:null) VmStatsCollector is running...
2013-07-23 14:25:54,138 DEBUG [agent.manager.AgentAttache] (StatsCollector-3:null) Request seq: 710935761
2013-07-23 14:25:54,139 DEBUG [agent.manager.AgentAttache] (StatsCollector-3:null) waiting to send 710935761
2013-07-23 14:25:54,139 DEBUG [agent.manager.AgentAttache] (StatsCollector-3:null) entering synchronize block for sending 710935761
2013-07-23 14:25:55,133 DEBUG [agent.transport.Request] (StatsCollector-3:null) Seq 1-710935761: Received:  { Ans: , MgmtId: 7471666038533, via: 1, Ver: v1, Flags: 10, { GetVmStatsAnswer } }
2013-07-23 14:25:56,533 DEBUG [cloud.api.ApiServlet] (catalina-exec-9:null) ===START===  10.252.112.197 -- GET  command=queryAsyncJobResult&jobId=3f28c925-3aba-4d1c-a3fa-a676e5ac7894&response=json&sessionkey=Y1grYFDP88FrKV%2BZpAXfM6SCXEk%3D&_=1374615111307
2013-07-23 14:25:56,541 DEBUG [cloud.async.AsyncJobManagerImpl] (catalina-exec-9:null) Async job-53 = [ 3f28c925-3aba-4d1c-a3fa-a676e5ac7894 ] completed
2013-07-23 14:25:56,544 DEBUG [cloud.api.ApiServlet] (catalina-exec-9:null) ===END===  10.252.112.197 -- GET  command=queryAsyncJobResult&jobId=3f28c925-3aba-4d1c-a3fa-a676e5ac7894&response=json&sessionkey=Y1grYFDP88FrKV%2BZpAXfM6SCXEk%3D&_=1374615111307


--
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