Sailaja Mada created CLOUDSTACK-4141:
----------------------------------------
Summary: [VMWARE][ZWPS] Resize operation failures are propagated
as success to UI
Key: CLOUDSTACK-4141
URL: https://issues.apache.org/jira/browse/CLOUDSTACK-4141
Project: CloudStack
Issue Type: Bug
Security Level: Public (Anyone can view this level - this is the default.)
Components: Storage Controller, VMware
Affects Versions: 4.2.0
Reporter: Sailaja Mada
Priority: Critical
Steps:
1. Configure Adv zone with VMWARE cluster with Zone wide primary storage
2. Deploy instance
3. Add new DATA volume with 5 GB disk offering
4. Attach the volume to the instance
5. Resize to 20 GB - It works with no issues
6. Tried to shrink the volume which is not supported
Observation:
1. From UI, Task notification says its completed to Shrink the volume but It is
not supported operation and failed in the logs.
2013-08-07 16:49:17,935 DEBUG [cloud.async.AsyncJobManagerImpl]
(catalina-exec-18:null) submit async job-53 = [
30c61006-91ca-4334-8d73-e1a7b7760252 ], details: AsyncJobVO {id:53, userId: 3,
accountId: 3, sessionKey: null, instanceType: Volume, instanceId: null, cmd:
org.apache.cloudstack.api.command.user.volume.ResizeVolumeCmd, cmdOriginator:
null, cmdInfo:
{"response":"json","id":"98b2a53b-1475-4658-a874-cf5c911e0faf","sessionkey":"nmiUJgTgEEYHRt8hx5StkuJr5tA\u003d","shrinkok":"true","cmdEventType":"VOLUME.RESIZE","ctxUserId":"3","httpmethod":"GET","_":"1375874557154","ctxAccountId":"3","diskofferingid":"34443d4d-f29c-4d3f-8bb6-f6ae76e34b0d","ctxStartEventId":"194"},
cmdVersion: 0, callbackType: 0, callbackAddress: null, status: 0,
processStatus: 0, resultCode: 0, result: null, initMsid: 187767034175903,
completeMsid: null, lastUpdated: null, lastPolled: null, created: null}
2013-08-07 16:49:17,938 DEBUG [cloud.api.ApiServlet] (catalina-exec-18:null)
===END=== 10.144.6.19 -- GET
command=resizeVolume&id=98b2a53b-1475-4658-a874-cf5c911e0faf&shrinkok=true&diskofferingid=34443d4d-f29c-4d3f-8bb6-f6ae76e34b0d&response=json&sessionkey=nmiUJgTgEEYHRt8hx5StkuJr5tA%3D&_=1375874557154
2013-08-07 16:49:17,940 DEBUG [cloud.async.AsyncJobManagerImpl]
(Job-Executor-36:job-53 = [ 30c61006-91ca-4334-8d73-e1a7b7760252 ]) Executing
org.apache.cloudstack.api.command.user.volume.ResizeVolumeCmd for job-53 = [
30c61006-91ca-4334-8d73-e1a7b7760252 ]
2013-08-07 16:49:17,974 DEBUG [cloud.user.AccountManagerImpl]
(Job-Executor-36:job-53 = [ 30c61006-91ca-4334-8d73-e1a7b7760252 ]) Access to
Vol[38|vm=16|DATADISK] granted to Acct[3-cdcuser1] by
DomainChecker_EnhancerByCloudStack_ccb7a71
2013-08-07 16:49:18,010 DEBUG [agent.transport.Request] (Job-Executor-36:job-53
= [ 30c61006-91ca-4334-8d73-e1a7b7760252 ]) Seq 2-1287389779: Sending { Cmd ,
MgmtId: 187767034175903, via: 2, Ver: v1, Flags: 100011,
[{"com.cloud.agent.api.storage.ResizeVolumeCommand":{"path":"6344e3d83b6541ec863aaa6cd19fd66b","pool":{"id":2,"uuid":"004a6f4c-232c-3a09-9013-e47fe47da3fb","host":"10.102.192.100","path":"/cpg_vol/sailaja/finalps2","port":2049,"type":"NetworkFilesystem"},"vmInstance":"i-3-16-VM","newSize":5368709120,"currentSize":21474836480,"shrinkOk":true,"wait":0}}]
}
2013-08-07 16:49:18,011 DEBUG [agent.transport.Request] (Job-Executor-36:job-53
= [ 30c61006-91ca-4334-8d73-e1a7b7760252 ]) Seq 2-1287389779: Executing: { Cmd
, MgmtId: 187767034175903, via: 2, Ver: v1, Flags: 100011,
[{"com.cloud.agent.api.storage.ResizeVolumeCommand":{"path":"6344e3d83b6541ec863aaa6cd19fd66b","pool":{"id":2,"uuid":"004a6f4c-232c-3a09-9013-e47fe47da3fb","host":"10.102.192.100","path":"/cpg_vol/sailaja/finalps2","port":2049,"type":"NetworkFilesystem"},"vmInstance":"i-3-16-VM","newSize":5368709120,"currentSize":21474836480,"shrinkOk":true,"wait":0}}]
}
2013-08-07 16:49:18,036 DEBUG [agent.manager.DirectAgentAttache]
(DirectAgent-326:null) Seq 2-1287389779: Executing request
2013-08-07 16:49:18,076 INFO [vmware.mo.VirtualMachineMO]
(DirectAgent-326:10.102.192.18) Look for disk device info from volume :
6344e3d83b6541ec863aaa6cd19fd66b
2013-08-07 16:49:18,076 INFO [vmware.mo.VirtualMachineMO]
(DirectAgent-326:10.102.192.18) Test against disk device, controller key: 200,
unit number: 1
2013-08-07 16:49:18,077 INFO [vmware.mo.VirtualMachineMO]
(DirectAgent-326:10.102.192.18) Test against disk backing :
[004a6f4c232c3a099013e47fe47da3fb] ROOT-16.vmdk
2013-08-07 16:49:18,077 INFO [vmware.mo.VirtualMachineMO]
(DirectAgent-326:10.102.192.18) Test against disk backing :
[004a6f4c232c3a099013e47fe47da3fb]
9ace158f21963beaae34b3a20ce17094/9ace158f21963beaae34b3a20ce17094.vmdk
2013-08-07 16:49:18,077 INFO [vmware.mo.VirtualMachineMO]
(DirectAgent-326:10.102.192.18) Test against disk device, controller key: 1000,
unit number: 0
2013-08-07 16:49:18,077 INFO [vmware.mo.VirtualMachineMO]
(DirectAgent-326:10.102.192.18) Test against disk backing :
[004a6f4c232c3a099013e47fe47da3fb] e9166262ee514a398028c04bf21d80b7.vmdk
2013-08-07 16:49:18,077 INFO [vmware.mo.VirtualMachineMO]
(DirectAgent-326:10.102.192.18) Test against disk device, controller key: 1000,
unit number: 1
2013-08-07 16:49:18,077 INFO [vmware.mo.VirtualMachineMO]
(DirectAgent-326:10.102.192.18) Test against disk backing :
[004a6f4c232c3a099013e47fe47da3fb] 6344e3d83b6541ec863aaa6cd19fd66b.vmdk
2013-08-07 16:49:18,078 INFO [vmware.mo.VirtualMachineMO]
(DirectAgent-326:10.102.192.18) Disk backing :
[004a6f4c232c3a099013e47fe47da3fb] 6344e3d83b6541ec863aaa6cd19fd66b.vmdk
matches ==> scsi0:1
2013-08-07 16:49:18,078 ERROR [vmware.resource.VmwareResource]
(DirectAgent-326:10.102.192.18) Unable to resize volume
java.lang.Exception: VMware doesn't support shrinking volume from larger size:
20971520 MB to a smaller size: 5242880 MB
at
com.cloud.hypervisor.vmware.resource.VmwareResource.execute(VmwareResource.java:637)
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:1110)
at
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:603)
at java.lang.Thread.run(Thread.java:679)
2013-08-07 16:49:18,079 DEBUG [agent.manager.DirectAgentAttache]
(DirectAgent-326:null) Seq 2-1287389779: Response Received:
2013-08-07 16:49:18,079 DEBUG [agent.manager.DirectAgentAttache]
(DirectAgent-326:null) Seq 2-1287389779: Response Received:
2013-08-07 16:49:18,079 DEBUG [agent.transport.Request] (DirectAgent-326:null)
Seq 2-1287389779: Processing: { Ans: , MgmtId: 187767034175903, via: 2, Ver:
v1, Flags: 10,
[{"com.cloud.agent.api.storage.ResizeVolumeAnswer":{"newSize":0,"result":false,"details":"failed
to resize volume:java.lang.Exception: VMware doesn't support shrinking volume
from larger size: 20971520 MB to a smaller size: 5242880 MB","wait":0}}] }
2013-08-07 16:49:18,079 DEBUG [agent.transport.Request] (Job-Executor-36:job-53
= [ 30c61006-91ca-4334-8d73-e1a7b7760252 ]) Seq 2-1287389779: Received: { Ans:
, MgmtId: 187767034175903, via: 2, Ver: v1, Flags: 10, { ResizeVolumeAnswer } }
2013-08-07 16:49:18,091 WARN [storage.datastore.ObjectInDataStoreManagerImpl]
(Job-Executor-36:job-53 = [ 30c61006-91ca-4334-8d73-e1a7b7760252 ]) Unsupported
data object (VOLUME,
org.apache.cloudstack.storage.datastore.PrimaryDataStoreImpl@6f7e127e), no need
to delete from object in store ref table
2013-08-07 16:49:18,152 DEBUG [cloud.async.AsyncJobManagerImpl]
(Job-Executor-36:job-53 = [ 30c61006-91ca-4334-8d73-e1a7b7760252 ]) Complete
async job-53 = [ 30c61006-91ca-4334-8d73-e1a7b7760252 ], jobStatus: 1,
resultCode: 0, result:
org.apache.cloudstack.api.response.VolumeResponse@6c1e7233
2013-08-07 16:49:18,164 DEBUG [cloud.async.AsyncJobManagerImpl]
(Job-Executor-36:job-53 = [ 30c61006-91ca-4334-8d73-e1a7b7760252 ]) Done
executing org.apache.cloudstack.api.command.user.volume.ResizeVolumeCmd for
job-53 = [ 30c61006-91ca-4334-8d73-e1a7b7760252 ]
2013-08-07 16:49:18,624 DEBUG [cloud.server.StatsCollector]
(StatsCollector-3:null) VmStatsCollector is running...
2013-08-07 16:49:18,655 DEBUG [agent.manager.DirectAgentAttache]
(DirectAgent-216:null) Seq 2-1287389780: Executing request
2013-08-07 16:49:18,745 DEBUG [vmware.mo.HostMO]
(DirectAgent-216:10.102.192.18) find VM i-3-16-VM on host
2013-08-07 16:49:18,745 DEBUG [vmware.mo.HostMO]
(DirectAgent-216:10.102.192.18) load VM cache on host
2013-08-07 16:49:18,762 DEBUG [agent.manager.DirectAgentAttache]
(DirectAgent-216:null) Seq 2-1287389780: Response Received:
2013-08-07 16:49:18,763 DEBUG [agent.transport.Request] (StatsCollector-3:null)
Seq 2-1287389780: Received: { Ans: , MgmtId: 187767034175903, via: 2, Ver: v1,
Flags: 10, { GetVmStatsAnswer } }
2013-08-07 16:49:21,015 DEBUG [cloud.api.ApiServlet] (catalina-exec-24:null)
===START=== 10.144.6.19 -- GET
command=queryAsyncJobResult&jobId=30c61006-91ca-4334-8d73-e1a7b7760252&response=json&sessionkey=nmiUJgTgEEYHRt8hx5StkuJr5tA%3D&_=1375874560002
2013-08-07 16:49:21,028 DEBUG [cloud.async.AsyncJobManagerImpl]
(catalina-exec-24:null) Async job-53 = [ 30c61006-91ca-4334-8d73-e1a7b7760252 ]
completed
2013-08-07 16:49:21,034 DEBUG [cloud.api.ApiServlet] (catalina-exec-24:null)
===END=== 10.144.6.19 -- GET
command=queryAsyncJobResult&jobId=30c61006-91ca-4334-8d73-e1a7b7760252&response=json&sessionkey=nmiUJgTgEEYHRt8hx5StkuJr5tA%3D&_=1375874560002
--
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
