cloudstack-issues mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From "Abhinav Roy (JIRA)" <j...@apache.org>
Subject [jira] [Updated] (CLOUDSTACK-6865) [Hyper-V]attach of an uploaded volume is failing as it is looking for a .vhdx volume even though the volume present is .vhd
Date Mon, 09 Jun 2014 08:09:01 GMT

     [ https://issues.apache.org/jira/browse/CLOUDSTACK-6865?page=com.atlassian.jira.plugin.system.issuetabpanels:all-tabpanel ]

Abhinav Roy updated CLOUDSTACK-6865:
------------------------------------

    Description: 
Steps :
==================
1. Deploy an advanced zone Hyper-V setup.
2. Create a VM v1
3. Upload a volume upload1
4. Attach upload1 to v1


Expected Behavior :
==================
Attach should be successful.

Observed behavior :
==================
Attach fails with the following error :

2014-06-09 11:32:47,684 DEBUG [c.c.a.ApiServlet] (catalina-exec-8:ctx-02af10fb) ===START===  10.144.7.13 -- GET  command=attachVolume&id=ddcaf328-0e71-462b-9ce6-e520dc8f15cc&virtualMachineId=ea78d7ec-6fed-4c3e-bff1-29219c70bddd&response=json&sessionkey=4MQxLlvkRgcp%2FMBl6tf6TodmICI%3D&_=1402293458440
2014-06-09 11:32:47,726 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl] (catalina-exec-8:ctx-02af10fb ctx-dce1c925) submit async job-66, details: AsyncJobVO {id:66, userId: 2, accountId: 2, instanceType: Volume, instanceId: 7, cmd: org.apache.cloudstack.api.command.admin.volume.AttachVolumeCmdByAdmin, cmdInfo: {"response":"json","id":"ddcaf328-0e71-462b-9ce6-e520dc8f15cc","sessionkey":"4MQxLlvkRgcp/MBl6tf6TodmICI\u003d","ctxDetails":"{\"com.cloud.storage.Volume\":7,\"Volume\":\"ddcaf328-0e71-462b-9ce6-e520dc8f15cc\",\"com.cloud.vm.VirtualMachine\":5}","cmdEventType":"VOLUME.ATTACH","ctxUserId":"2","virtualMachineId":"ea78d7ec-6fed-4c3e-bff1-29219c70bddd","httpmethod":"GET","_":"1402293458440","uuid":"ddcaf328-0e71-462b-9ce6-e520dc8f15cc","ctxAccountId":"2","ctxStartEventId":"112"}, cmdVersion: 0, status: IN_PROGRESS, processStatus: 0, resultCode: 0, result: null, initMsid: 213737702773493, completeMsid: null, lastUpdated: null, lastPolled: null, created: null}
2014-06-09 11:32:47,727 DEBUG [c.c.a.ApiServlet] (catalina-exec-8:ctx-02af10fb ctx-dce1c925) ===END===  10.144.7.13 -- GET  command=attachVolume&id=ddcaf328-0e71-462b-9ce6-e520dc8f15cc&virtualMachineId=ea78d7ec-6fed-4c3e-bff1-29219c70bddd&response=json&sessionkey=4MQxLlvkRgcp%2FMBl6tf6TodmICI%3D&_=1402293458440
2014-06-09 11:32:47,729 INFO  [o.a.c.f.j.i.AsyncJobMonitor] (API-Job-Executor-24:ctx-976a0ed5 job-66) Add job-66 into job monitoring
2014-06-09 11:32:47,734 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl] (API-Job-Executor-24:ctx-976a0ed5 job-66) Executing AsyncJobVO {id:66, userId: 2, accountId: 2, instanceType: Volume, instanceId: 7, cmd: org.apache.cloudstack.api.command.admin.volume.AttachVolumeCmdByAdmin, cmdInfo: {"response":"json","id":"ddcaf328-0e71-462b-9ce6-e520dc8f15cc","sessionkey":"4MQxLlvkRgcp/MBl6tf6TodmICI\u003d","ctxDetails":"{\"com.cloud.storage.Volume\":7,\"Volume\":\"ddcaf328-0e71-462b-9ce6-e520dc8f15cc\",\"com.cloud.vm.VirtualMachine\":5}","cmdEventType":"VOLUME.ATTACH","ctxUserId":"2","virtualMachineId":"ea78d7ec-6fed-4c3e-bff1-29219c70bddd","httpmethod":"GET","_":"1402293458440","uuid":"ddcaf328-0e71-462b-9ce6-e520dc8f15cc","ctxAccountId":"2","ctxStartEventId":"112"}, cmdVersion: 0, status: IN_PROGRESS, processStatus: 0, resultCode: 0, result: null, initMsid: 213737702773493, completeMsid: null, lastUpdated: null, lastPolled: null, created: null}
2014-06-09 11:32:47,744 WARN  [c.c.a.d.ParamGenericValidationWorker] (API-Job-Executor-24:ctx-976a0ed5 job-66 ctx-1d0f4aec) Received unknown parameters for command attachVolume. Unknown parameters : ctxdetails
2014-06-09 11:32:47,769 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl] (API-Job-Executor-24:ctx-976a0ed5 job-66 ctx-1d0f4aec) Sync job-67 execution on object VmWorkJobQueue.5
2014-06-09 11:32:47,772 DEBUG [c.c.s.VolumeApiServiceImpl] (API-Job-Executor-24:ctx-976a0ed5 job-66 ctx-1d0f4aec) New job 67, result field: null
2014-06-09 11:32:47,773 WARN  [c.c.u.d.Merovingian2] (API-Job-Executor-24:ctx-976a0ed5 job-66 ctx-1d0f4aec) Was unable to find lock for the key vm_instance5 and thread id 1700504230
2014-06-09 11:32:49,461 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl] (AsyncJobMgr-Heartbeat-1:ctx-04e089b1) Execute sync-queue item: SyncQueueItemVO {id:24, queueId: 4, contentType: AsyncJob, contentId: 67, lastProcessMsid: null, lastprocessNumber: null, lastProcessTime: null, created: Mon Jun 09 11:32:47 IST 2014}
2014-06-09 11:32:49,463 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl] (AsyncJobMgr-Heartbeat-1:ctx-04e089b1) Schedule queued job-67
2014-06-09 11:32:49,480 INFO  [o.a.c.f.j.i.AsyncJobMonitor] (Work-Job-Executor-22:ctx-594553d6 job-66/job-67) Add job-67 into job monitoring
2014-06-09 11:32:49,481 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl] (Work-Job-Executor-22:ctx-594553d6 job-66/job-67) Executing AsyncJobVO {id:67, userId: 2, accountId: 2, instanceType: null, instanceId: null, cmd: com.cloud.storage.VmWorkAttachVolume, cmdInfo: rO0ABXNyACRjb20uY2xvdWQuc3RvcmFnZS5WbVdvcmtBdHRhY2hWb2x1bWUHra_5YYfiHAIAAkwACGRldmljZUlkdAAQTGphdmEvbGFuZy9Mb25nO0wACHZvbHVtZUlkcQB-AAF4cgATY29tLmNsb3VkLnZtLlZtV29ya5-ZtlbwJWdrAgAESgAJYWNjb3VudElkSgAGdXNlcklkSgAEdm1JZEwAC2hhbmRsZXJOYW1ldAASTGphdmEvbGFuZy9TdHJpbmc7eHAAAAAAAAAAAgAAAAAAAAACAAAAAAAAAAV0ABRWb2x1bWVBcGlTZXJ2aWNlSW1wbHBzcgAOamF2YS5sYW5nLkxvbmc7i-SQzI8j3wIAAUoABXZhbHVleHIAEGphdmEubGFuZy5OdW1iZXKGrJUdC5TgiwIAAHhwAAAAAAAAAAc, cmdVersion: 0, status: IN_PROGRESS, processStatus: 0, resultCode: 0, result: null, initMsid: 213737702773493, completeMsid: null, lastUpdated: null, lastPolled: null, created: Mon Jun 09 11:32:47 IST 2014}
2014-06-09 11:32:49,482 DEBUG [c.c.v.VmWorkJobDispatcher] (Work-Job-Executor-22:ctx-594553d6 job-66/job-67) Run VM work job: com.cloud.storage.VmWorkAttachVolume for VM 5, job origin: 66
2014-06-09 11:32:49,486 DEBUG [c.c.v.VmWorkJobHandlerProxy] (Work-Job-Executor-22:ctx-594553d6 job-66/job-67 ctx-136180d5) Execute VM work job: com.cloud.storage.VmWorkAttachVolume{"volumeId":7,"userId":2,"accountId":2,"vmId":5,"handlerName":"VolumeApiServiceImpl"}
2014-06-09 11:32:49,511 DEBUG [o.a.c.s.a.LocalStoragePoolAllocator] (Work-Job-Executor-22:ctx-594553d6 job-66/job-67 ctx-136180d5) LocalStoragePoolAllocator trying to find storage pool to fit the vm
2014-06-09 11:32:49,511 DEBUG [o.a.c.s.a.ClusterScopeStoragePoolAllocator] (Work-Job-Executor-22:ctx-594553d6 job-66/job-67 ctx-136180d5) ClusterScopeStoragePoolAllocator looking for storage pool
2014-06-09 11:32:49,511 DEBUG [o.a.c.s.a.ClusterScopeStoragePoolAllocator] (Work-Job-Executor-22:ctx-594553d6 job-66/job-67 ctx-136180d5) Looking for pools in dc: 1  pod:1  cluster:2
2014-06-09 11:32:49,512 DEBUG [o.a.c.s.a.ClusterScopeStoragePoolAllocator] (Work-Job-Executor-22:ctx-594553d6 job-66/job-67 ctx-136180d5) Found pools matching tags: [Pool[5|SMB], Pool[9|SMB]]
2014-06-09 11:32:49,514 DEBUG [o.a.c.s.a.ClusterScopeStoragePoolAllocator] (Work-Job-Executor-22:ctx-594553d6 job-66/job-67 ctx-136180d5) Removing pool Pool[5|SMB] from avoid set, must have been inserted when searching for another disk's tag
2014-06-09 11:32:49,514 DEBUG [o.a.c.s.a.ClusterScopeStoragePoolAllocator] (Work-Job-Executor-22:ctx-594553d6 job-66/job-67 ctx-136180d5) Removing pool Pool[9|SMB] from avoid set, must have been inserted when searching for another disk's tag
2014-06-09 11:32:49,515 DEBUG [o.a.c.s.a.AbstractStoragePoolAllocator] (Work-Job-Executor-22:ctx-594553d6 job-66/job-67 ctx-136180d5) Checking if storage pool is suitable, name: null ,poolId: 5
2014-06-09 11:32:49,519 DEBUG [c.c.s.StorageManagerImpl] (Work-Job-Executor-22:ctx-594553d6 job-66/job-67 ctx-136180d5) Checking pool 5 for storage, totalSize: 998772830208, usedBytes: 94130245632, usedPct: 0.09424590135516286, disable threshold: 0.85
2014-06-09 11:32:49,521 DEBUG [c.c.s.StorageManagerImpl] (Work-Job-Executor-22:ctx-594553d6 job-66/job-67 ctx-136180d5) Found storage pool ps2 of type SMB
2014-06-09 11:32:49,521 DEBUG [c.c.s.StorageManagerImpl] (Work-Job-Executor-22:ctx-594553d6 job-66/job-67 ctx-136180d5) Total capacity of the pool ps2 id: 5 is 998772830208
2014-06-09 11:32:49,522 DEBUG [c.c.s.StorageManagerImpl] (Work-Job-Executor-22:ctx-594553d6 job-66/job-67 ctx-136180d5) Checking pool: 5 for volume allocation [Vol[7|vm=null|DATADISK]], maxSize : 998772830208, totalAllocatedSize : 16106127360, askingSize : 0, allocated disable threshold: 0.85
2014-06-09 11:32:49,522 DEBUG [o.a.c.s.a.ClusterScopeStoragePoolAllocator] (Work-Job-Executor-22:ctx-594553d6 job-66/job-67 ctx-136180d5) ClusterScopeStoragePoolAllocator returning 1 suitable storage pools
2014-06-09 11:32:49,562 DEBUG [o.a.c.s.m.AncientDataMotionStrategy] (Work-Job-Executor-22:ctx-594553d6 job-66/job-67 ctx-136180d5) copyAsync inspecting src type VOLUME copyAsync inspecting dest type VOLUME
2014-06-09 11:32:49,565 DEBUG [o.a.c.s.c.a.StorageCacheRandomAllocator] (Work-Job-Executor-22:ctx-594553d6 job-66/job-67 ctx-136180d5) Can't find staging storage in zone: 1
2014-06-09 11:32:49,595 DEBUG [c.c.a.t.Request] (Work-Job-Executor-22:ctx-594553d6 job-66/job-67 ctx-136180d5) Seq 4-7045600142044462129: Sending  { Cmd , MgmtId: 213737702773493, via: 4(10.102.244.21), Ver: v1, Flags: 100011, [{"org.apache.cloudstack.storage.command.CopyCommand":{"srcTO":{"org.apache.cloudstack.storage.to.VolumeObjectTO":{"uuid":"ddcaf328-0e71-462b-9ce6-e520dc8f15cc","volumeType":"DATADISK","dataStore":{"org.apache.cloudstack.storage.to.PrimaryDataStoreTO":{"uuid":"641da331-d6d6-3224-b53a-0dad93667d30","id":2,"poolType":"SMB","host":"SMB19","path":"/hyperv-share/abhinav-ps1?user=abhinavroy&domain=BLR","port":445,"url":"SMB://SMB19/hyperv-share/abhinav-ps1?user=abhinavroy&domain=BLR/?ROLE=Primary&STOREUUID=641da331-d6d6-3224-b53a-0dad93667d30"}},"name":"upload1","size":5368709120,"volumeId":7,"accountId":2,"format":"VHDX","id":7,"hypervisorType":"Hyperv"}},"destTO":{"org.apache.cloudstack.storage.to.VolumeObjectTO":{"uuid":"ddcaf328-0e71-462b-9ce6-e520dc8f15cc","volumeType":"DATADISK","dataStore":{"com.cloud.agent.api.to.NfsTO":{"_url":"cifs://10.102.192.19/hyperv-share/abhinav-ss1?user=abhinavroy&domain=BLR","_role":"Image"}},"name":"upload1","size":5368709120,"path":"volumes/2/7","volumeId":7,"accountId":2,"format":"VHDX","id":7,"hypervisorType":"Hyperv"}},"executeInSequence":false,"options":{},"wait":10800}}] }
2014-06-09 11:32:49,596 DEBUG [c.c.a.t.Request] (Work-Job-Executor-22:ctx-594553d6 job-66/job-67 ctx-136180d5) Seq 4-7045600142044462129: Executing:  { Cmd , MgmtId: 213737702773493, via: 4(10.102.244.21), Ver: v1, Flags: 100011, [{"org.apache.cloudstack.storage.command.CopyCommand":{"srcTO":{"org.apache.cloudstack.storage.to.VolumeObjectTO":{"uuid":"ddcaf328-0e71-462b-9ce6-e520dc8f15cc","volumeType":"DATADISK","dataStore":{"org.apache.cloudstack.storage.to.PrimaryDataStoreTO":{"uuid":"641da331-d6d6-3224-b53a-0dad93667d30","id":2,"poolType":"SMB","host":"SMB19","path":"/hyperv-share/abhinav-ps1?user=abhinavroy&domain=BLR","port":445,"url":"SMB://SMB19/hyperv-share/abhinav-ps1?user=abhinavroy&domain=BLR/?ROLE=Primary&STOREUUID=641da331-d6d6-3224-b53a-0dad93667d30"}},"name":"upload1","size":5368709120,"volumeId":7,"accountId":2,"format":"VHDX","id":7,"hypervisorType":"Hyperv"}},"destTO":{"org.apache.cloudstack.storage.to.VolumeObjectTO":{"uuid":"ddcaf328-0e71-462b-9ce6-e520dc8f15cc","volumeType":"DATADISK","dataStore":{"com.cloud.agent.api.to.NfsTO":{"_url":"cifs://10.102.192.19/hyperv-share/abhinav-ss1?user=abhinavroy&domain=BLR","_role":"Image"}},"name":"upload1","size":5368709120,"path":"volumes/2/7","volumeId":7,"accountId":2,"format":"VHDX","id":7,"hypervisorType":"Hyperv"}},"executeInSequence":false,"options":{},"wait":10800}}] }
2014-06-09 11:32:49,596 DEBUG [c.c.a.m.DirectAgentAttache] (DirectAgent-9:ctx-1007ce0e) Seq 4-7045600142044462129: Executing request
2014-06-09 11:32:49,597 DEBUG [c.c.h.h.r.HypervDirectConnectResource] (DirectAgent-9:ctx-1007ce0e) POST request to https://10.102.244.21:8250/api/HypervResource/org.apache.cloudstack.storage.command.CopyCommand with contents {"srcTO":{"org.apache.cloudstack.storage.to.VolumeObjectTO":{"uuid":"ddcaf328-0e71-462b-9ce6-e520dc8f15cc","volumeType":"DATADISK","dataStore":{"org.apache.cloudstack.storage.to.PrimaryDataStoreTO":{"uuid":"641da331-d6d6-3224-b53a-0dad93667d30","id":2,"poolType":"SMB","host":"SMB19","path":"/hyperv-share/abhinav-ps1?user=abhinavroy&domain=BLR","port":445,"url":"SMB://SMB19/hyperv-share/abhinav-ps1?user=abhinavroy&domain=BLR/?ROLE=Primary&STOREUUID=641da331-d6d6-3224-b53a-0dad93667d30"}},"name":"upload1","size":5368709120,"volumeId":7,"accountId":2,"format":"VHDX","id":7,"hypervisorType":"Hyperv"}},"destTO":{"org.apache.cloudstack.storage.to.VolumeObjectTO":{"uuid":"ddcaf328-0e71-462b-9ce6-e520dc8f15cc","volumeType":"DATADISK","dataStore":{"com.cloud.agent.api.to.NfsTO":{"_url":"cifs://10.102.192.19/hyperv-share/abhinav-ss1?user=abhinavroy&domain=BLR","_role":"Image"}},"name":"upload1","size":5368709120,"path":"volumes/2/7","volumeId":7,"accountId":2,"format":"VHDX","id":7,"hypervisorType":"Hyperv"}},"executeInSequence":false,"options":{},"contextMap":{},"wait":10800}
2014-06-09 11:32:49,601 DEBUG [c.c.h.h.r.HypervDirectConnectResource] (DirectAgent-9:ctx-1007ce0e) Sending cmd to https://10.102.244.21:8250/api/HypervResource/org.apache.cloudstack.storage.command.CopyCommand cmd data:{"srcTO":{"org.apache.cloudstack.storage.to.VolumeObjectTO":{"uuid":"ddcaf328-0e71-462b-9ce6-e520dc8f15cc","volumeType":"DATADISK","dataStore":{"org.apache.cloudstack.storage.to.PrimaryDataStoreTO":{"uuid":"641da331-d6d6-3224-b53a-0dad93667d30","id":2,"poolType":"SMB","host":"SMB19","path":"/hyperv-share/abhinav-ps1?user=abhinavroy&domain=BLR","port":445,"url":"SMB://SMB19/hyperv-share/abhinav-ps1?user=abhinavroy&domain=BLR/?ROLE=Primary&STOREUUID=641da331-d6d6-3224-b53a-0dad93667d30"}},"name":"upload1","size":5368709120,"volumeId":7,"accountId":2,"format":"VHDX","id":7,"hypervisorType":"Hyperv"}},"destTO":{"org.apache.cloudstack.storage.to.VolumeObjectTO":{"uuid":"ddcaf328-0e71-462b-9ce6-e520dc8f15cc","volumeType":"DATADISK","dataStore":{"com.cloud.agent.api.to.NfsTO":{"_url":"cifs://10.102.192.19/hyperv-share/abhinav-ss1?user=abhinavroy&domain=BLR","_role":"Image"}},"name":"upload1","size":5368709120,"path":"volumes/2/7","volumeId":7,"accountId":2,"format":"VHDX","id":7,"hypervisorType":"Hyperv"}},"executeInSequence":false,"options":{},"contextMap":{},"wait":10800}
2014-06-09 11:32:49,667 DEBUG [c.c.s.StatsCollector] (StatsCollector-3:ctx-4c35f7eb) StorageCollector is running...
2014-06-09 11:32:49,680 DEBUG [c.c.h.h.r.HypervDirectConnectResource] (DirectAgent-9:ctx-1007ce0e) POST response is [{"org.apache.cloudstack.storage.command.CopyCmdAnswer":{"result":false,"details":"Local template file missing from \\smb19\hyperv-share\abhinav-ps1\ddcaf328-0e71-462b-9ce6-e520dc8f15cc.vhdx","newData":null,"contextMap":{}}}]
2014-06-09 11:32:49,681 DEBUG [c.c.h.h.r.HypervDirectConnectResource] (DirectAgent-9:ctx-1007ce0e) executeRequest received response [Lcom.cloud.agent.api.Answer;@43233812
2014-06-09 11:32:49,681 DEBUG [c.c.a.m.DirectAgentAttache] (DirectAgent-9:ctx-1007ce0e) Seq 4-7045600142044462129: Response Received:
2014-06-09 11:32:49,681 DEBUG [c.c.a.t.Request] (DirectAgent-9:ctx-1007ce0e) Seq 4-7045600142044462129: Processing:  { Ans: , MgmtId: 213737702773493, via: 4, Ver: v1, Flags: 10, [{"org.apache.cloudstack.storage.command.CopyCmdAnswer":{"result":false,"details":"Local template file missing from \\\\smb19\\hyperv-share\\abhinav-ps1\\ddcaf328-0e71-462b-9ce6-e520dc8f15cc.vhdx","wait":0}}] }
2014-06-09 11:32:49,681 DEBUG [c.c.a.t.Request] (Work-Job-Executor-22:ctx-594553d6 job-66/job-67 ctx-136180d5) Seq 4-7045600142044462129: Received:  { Ans: , MgmtId: 213737702773493, via: 4, Ver: v1, Flags: 10, { CopyCmdAnswer } }
2014-06-09 11:32:49,681 DEBUG [o.a.c.s.m.AncientDataMotionStrategy] (Work-Job-Executor-22:ctx-594553d6 job-66/job-67 ctx-136180d5) copy to image store failed: Local template file missing from \\smb19\hyperv-share\abhinav-ps1\ddcaf328-0e71-462b-9ce6-e520dc8f15cc.vhdx
2014-06-09 11:32:49,727 DEBUG [c.c.a.t.Request] (StatsCollector-3:ctx-4c35f7eb) Seq 3-8975955532326116099: Received:  { Ans: , MgmtId: 213737702773493, via: 3, Ver: v1, Flags: 10, { GetStorageStatsAnswer } }
2014-06-09 11:32:49,731 DEBUG [c.c.a.m.DirectAgentAttache] (DirectAgent-66:ctx-2742f335) Seq 1-3677470570724749799: Executing request
2014-06-09 11:32:49,732 DEBUG [c.c.h.h.r.HypervDirectConnectResource] (DirectAgent-66:ctx-2742f335) POST request to https://10.102.244.20:8250/api/HypervResource/com.cloud.agent.api.GetStorageStatsCommand with contents {"id":"c5034950-d1a2-3368-8c8f-77a2cb0d49d2-HypervResource","localPath":"C:\Users\Public\Documents\Hyper-V\Virtual Hard Disks","pooltype":"Filesystem","contextMap":{},"wait":0}
2014-06-09 11:32:49,738 DEBUG [c.c.h.h.r.HypervDirectConnectResource] (DirectAgent-66:ctx-2742f335) Sending cmd to https://10.102.244.20:8250/api/HypervResource/com.cloud.agent.api.GetStorageStatsCommand cmd data:{"id":"c5034950-d1a2-3368-8c8f-77a2cb0d49d2-HypervResource","localPath":"C:\Users\Public\Documents\Hyper-V\Virtual Hard Disks","pooltype":"Filesystem","contextMap":{},"wait":0}
2014-06-09 11:32:49,750 DEBUG [c.c.a.t.Request] (Work-Job-Executor-22:ctx-594553d6 job-66/job-67 ctx-136180d5) Seq 3-8975955532326116100: Sending  { Cmd , MgmtId: 213737702773493, via: 3(s-2-VM), Ver: v1, Flags: 100011, [{"org.apache.cloudstack.storage.command.DeleteCommand":{"data":{"org.apache.cloudstack.storage.to.VolumeObjectTO":{"uuid":"ddcaf328-0e71-462b-9ce6-e520dc8f15cc","volumeType":"DATADISK","dataStore":{"com.cloud.agent.api.to.NfsTO":{"_url":"cifs://10.102.192.19/hyperv-share/abhinav-ss1?user=abhinavroy&domain=BLR","_role":"Image"}},"name":"upload1","size":5368709120,"volumeId":7,"accountId":2,"format":"VHDX","id":7,"hypervisorType":"Hyperv"}},"wait":0}}] }
2014-06-09 11:32:49,800 DEBUG [c.c.h.h.r.HypervDirectConnectResource] (DirectAgent-66:ctx-2742f335) POST response is [{"com.cloud.agent.api.GetStorageStatsAnswer":{"result":true,"details":null,"capacity":497917358080,"used":14639181824,"contextMap":{}}}]
2014-06-09 11:32:49,801 DEBUG [c.c.h.h.r.HypervDirectConnectResource] (DirectAgent-66:ctx-2742f335) executeRequest received response [Lcom.cloud.agent.api.Answer;@5d8acfbf
2014-06-09 11:32:49,801 DEBUG [c.c.a.m.DirectAgentAttache] (DirectAgent-66:ctx-2742f335) Seq 1-3677470570724749799: Response Received:
2014-06-09 11:32:49,801 DEBUG [c.c.a.t.Request] (StatsCollector-3:ctx-4c35f7eb) Seq 1-3677470570724749799: Received:  { Ans: , MgmtId: 213737702773493, via: 1, Ver: v1, Flags: 10, { GetStorageStatsAnswer } }
2014-06-09 11:32:49,805 DEBUG [c.c.a.m.DirectAgentAttache] (DirectAgent-66:ctx-c6a55fb8) Seq 4-7045600142044462130: Executing request
2014-06-09 11:32:49,806 DEBUG [c.c.h.h.r.HypervDirectConnectResource] (DirectAgent-66:ctx-c6a55fb8) POST request to https://10.102.244.21:8250/api/HypervResource/com.cloud.agent.api.GetStorageStatsCommand with contents {"id":"641da331-d6d6-3224-b53a-0dad93667d30","localPath":"/hyperv-share/abhinav-ps1?user=abhinavroy&domain=BLR","pooltype":"SMB","contextMap":{},"wait":0}
2014-06-09 11:32:49,809 DEBUG [c.c.h.h.r.HypervDirectConnectResource] (DirectAgent-66:ctx-c6a55fb8) Sending cmd to https://10.102.244.21:8250/api/HypervResource/com.cloud.agent.api.GetStorageStatsCommand cmd data:{"id":"641da331-d6d6-3224-b53a-0dad93667d30","localPath":"/hyperv-share/abhinav-ps1?user=abhinavroy&domain=BLR","pooltype":"SMB","contextMap":{},"wait":0}
2014-06-09 11:32:49,835 DEBUG [c.c.a.t.Request] (AgentManager-Handler-1:null) Seq 3-8975955532326116100: Processing:  { Ans: , MgmtId: 213737702773493, via: 3, Ver: v1, Flags: 10, [{"com.cloud.agent.api.Answer":{"result":true,"details":"Object with null install path does not exist on image store , no need to delete","wait":0}}] }
2014-06-09 11:32:49,835 DEBUG [c.c.a.t.Request] (Work-Job-Executor-22:ctx-594553d6 job-66/job-67 ctx-136180d5) Seq 3-8975955532326116100: Received:  { Ans: , MgmtId: 213737702773493, via: 3, Ver: v1, Flags: 10, { Answer } }
2014-06-09 11:32:49,836 WARN  [o.a.c.s.d.ObjectInDataStoreManagerImpl] (Work-Job-Executor-22:ctx-594553d6 job-66/job-67 ctx-136180d5) Volume 7 is not found on image store 1, so no need to delete
2014-06-09 11:32:49,840 WARN  [o.a.c.s.d.ObjectInDataStoreManagerImpl] (Work-Job-Executor-22:ctx-594553d6 job-66/job-67 ctx-136180d5) Unsupported data object (VOLUME, org.apache.cloudstack.storage.datastore.PrimaryDataStoreImpl@4c73c07e), no need to delete from object in store ref table
2014-06-09 11:32:49,866 WARN  [o.a.c.s.d.ObjectInDataStoreManagerImpl] (Work-Job-Executor-22:ctx-594553d6 job-66/job-67 ctx-136180d5) Unsupported data object (VOLUME, org.apache.cloudstack.storage.datastore.PrimaryDataStoreImpl@15046282), no need to delete from object in store ref table
2014-06-09 11:32:49,867 DEBUG [c.c.h.h.r.HypervDirectConnectResource] (DirectAgent-66:ctx-c6a55fb8) POST response is [{"com.cloud.agent.api.GetStorageStatsAnswer":{"result":true,"details":null,"capacity":998772830208,"used":94130245632,"contextMap":{}}}]
2014-06-09 11:32:49,868 DEBUG [c.c.h.h.r.HypervDirectConnectResource] (DirectAgent-66:ctx-c6a55fb8) executeRequest received response [Lcom.cloud.agent.api.Answer;@4ae3f829
2014-06-09 11:32:49,868 DEBUG [c.c.a.m.DirectAgentAttache] (DirectAgent-66:ctx-c6a55fb8) Seq 4-7045600142044462130: Response Received:
2014-06-09 11:32:49,868 DEBUG [c.c.a.t.Request] (StatsCollector-3:ctx-4c35f7eb) Seq 4-7045600142044462130: Received:  { Ans: , MgmtId: 213737702773493, via: 4, Ver: v1, Flags: 10, { GetStorageStatsAnswer } }
2014-06-09 11:32:49,871 DEBUG [c.c.a.m.DirectAgentAttache] (DirectAgent-131:ctx-3fbe123c) Seq 4-7045600142044462131: Executing request
2014-06-09 11:32:49,871 DEBUG [c.c.h.h.r.HypervDirectConnectResource] (DirectAgent-131:ctx-3fbe123c) POST request to https://10.102.244.21:8250/api/HypervResource/com.cloud.agent.api.GetStorageStatsCommand with contents {"id":"c03aa877-a1af-315c-a2c2-2b10f128b1a3-HypervResource","localPath":"C:\Users\Public\Documents\Hyper-V\Virtual Hard Disks","pooltype":"Filesystem","contextMap":{},"wait":0}
2014-06-09 11:32:49,875 DEBUG [c.c.h.h.r.HypervDirectConnectResource] (DirectAgent-131:ctx-3fbe123c) Sending cmd to https://10.102.244.21:8250/api/HypervResource/com.cloud.agent.api.GetStorageStatsCommand cmd data:{"id":"c03aa877-a1af-315c-a2c2-2b10f128b1a3-HypervResource","localPath":"C:\Users\Public\Documents\Hyper-V\Virtual Hard Disks","pooltype":"Filesystem","contextMap":{},"wait":0}
2014-06-09 11:32:49,904 DEBUG [o.a.c.s.v.VolumeServiceImpl] (Work-Job-Executor-22:ctx-594553d6 job-66/job-67 ctx-136180d5) Marking volume that was never created as destroyed: Vol[14|vm=null|DATADISK]
2014-06-09 11:32:49,908 ERROR [o.a.c.e.o.VolumeOrchestrator] (Work-Job-Executor-22:ctx-594553d6 job-66/job-67 ctx-136180d5) migrate volume failed:Local template file missing from \\smb19\hyperv-share\abhinav-ps1\ddcaf328-0e71-462b-9ce6-e520dc8f15cc.vhdx
2014-06-09 11:32:49,908 DEBUG [c.c.s.VolumeApiServiceImpl] (Work-Job-Executor-22:ctx-594553d6 job-66/job-67 ctx-136180d5) move volume failed
com.cloud.exception.StorageUnavailableException: Resource [StoragePool:5] is unreachable: migrate volume failed: Local template file missing from \\smb19\hyperv-share\abhinav-ps1\ddcaf328-0e71-462b-9ce6-e520dc8f15cc.vhdx
        at org.apache.cloudstack.engine.orchestration.VolumeOrchestrator.migrateVolume(VolumeOrchestrator.java:882)
        at org.apache.cloudstack.engine.orchestration.VolumeOrchestrator.moveVolume(VolumeOrchestrator.java:202)
        at com.cloud.storage.VolumeApiServiceImpl.attachVolumeToVM(VolumeApiServiceImpl.java:1207)
        at com.cloud.storage.VolumeApiServiceImpl.orchestrateAttachVolumeToVM(VolumeApiServiceImpl.java:1055)
        at com.cloud.storage.VolumeApiServiceImpl.orchestrateAttachVolumeToVM(VolumeApiServiceImpl.java:2477)
        at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
        at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
        at java.lang.reflect.Method.invoke(Method.java:601)
        at com.cloud.vm.VmWorkJobHandlerProxy.handleVmWorkJob(VmWorkJobHandlerProxy.java:107)
        at com.cloud.storage.VolumeApiServiceImpl.handleVmWorkJob(VolumeApiServiceImpl.java:2512)
        at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
        at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
        at java.lang.reflect.Method.invoke(Method.java:601)
        at org.springframework.aop.support.AopUtils.invokeJoinpointUsingReflection(AopUtils.java:317)
        at org.springframework.aop.framework.ReflectiveMethodInvocation.invokeJoinpoint(ReflectiveMethodInvocation.java:183)
        at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:150)
        at org.springframework.aop.interceptor.ExposeInvocationInterceptor.invoke(ExposeInvocationInterceptor.java:91)
        at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172)
        at org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:204)
        at $Proxy181.handleVmWorkJob(Unknown Source)
        at com.cloud.vm.VmWorkJobDispatcher.runJob(VmWorkJobDispatcher.java:102)
        at org.apache.cloudstack.framework.jobs.impl.AsyncJobManagerImpl$5.runInContext(AsyncJobManagerImpl.java:503)
        at org.apache.cloudstack.managed.context.ManagedContextRunnable$1.run(ManagedContextRunnable.java:49)
        at org.apache.cloudstack.managed.context.impl.DefaultManagedContext$1.call(DefaultManagedContext.java:56)
        at org.apache.cloudstack.managed.context.impl.DefaultManagedContext.callWithContext(DefaultManagedContext.java:103)
        at org.apache.cloudstack.managed.context.impl.DefaultManagedContext.runWithContext(DefaultManagedContext.java:53)
        at org.apache.cloudstack.managed.context.ManagedContextRunnable.run(ManagedContextRunnable.java:46)
        at org.apache.cloudstack.framework.jobs.impl.AsyncJobManagerImpl$5.run(AsyncJobManagerImpl.java:460)
        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:722)
2014-06-09 11:32:49,909 ERROR [c.c.v.VmWorkJobHandlerProxy] (Work-Job-Executor-22:ctx-594553d6 job-66/job-67 ctx-136180d5) Invocation exception, caused by: com.cloud.utils.exception.CloudRuntimeException: move volume failed
2014-06-09 11:32:49,910 INFO  [c.c.v.VmWorkJobHandlerProxy] (Work-Job-Executor-22:ctx-594553d6 job-66/job-67 ctx-136180d5) Rethrow exception com.cloud.utils.exception.CloudRuntimeException: move volume failed
2014-06-09 11:32:49,910 DEBUG [c.c.v.VmWorkJobDispatcher] (Work-Job-Executor-22:ctx-594553d6 job-66/job-67) Done with run of VM work job: com.cloud.storage.VmWorkAttachVolume for VM 5, job origin: 66
2014-06-09 11:32:49,910 ERROR [c.c.v.VmWorkJobDispatcher] (Work-Job-Executor-22:ctx-594553d6 job-66/job-67) Unable to complete AsyncJobVO {id:67, userId: 2, accountId: 2, instanceType: null, instanceId: null, cmd: com.cloud.storage.VmWorkAttachVolume, cmdInfo: rO0ABXNyACRjb20uY2xvdWQuc3RvcmFnZS5WbVdvcmtBdHRhY2hWb2x1bWUHra_5YYfiHAIAAkwACGRldmljZUlkdAAQTGphdmEvbGFuZy9Mb25nO0wACHZvbHVtZUlkcQB-AAF4cgATY29tLmNsb3VkLnZtLlZtV29ya5-ZtlbwJWdrAgAESgAJYWNjb3VudElkSgAGdXNlcklkSgAEdm1JZEwAC2hhbmRsZXJOYW1ldAASTGphdmEvbGFuZy9TdHJpbmc7eHAAAAAAAAAAAgAAAAAAAAACAAAAAAAAAAV0ABRWb2x1bWVBcGlTZXJ2aWNlSW1wbHBzcgAOamF2YS5sYW5nLkxvbmc7i-SQzI8j3wIAAUoABXZhbHVleHIAEGphdmEubGFuZy5OdW1iZXKGrJUdC5TgiwIAAHhwAAAAAAAAAAc, cmdVersion: 0, status: IN_PROGRESS, processStatus: 0, resultCode: 0, result: null, initMsid: 213737702773493, completeMsid: null, lastUpdated: null, lastPolled: null, created: Mon Jun 09 11:32:47 IST 2014}, job origin:66
com.cloud.utils.exception.CloudRuntimeException: move volume failed
        at com.cloud.storage.VolumeApiServiceImpl.attachVolumeToVM(VolumeApiServiceImpl.java:1214)
        at com.cloud.storage.VolumeApiServiceImpl.orchestrateAttachVolumeToVM(VolumeApiServiceImpl.java:1055)
        at com.cloud.storage.VolumeApiServiceImpl.orchestrateAttachVolumeToVM(VolumeApiServiceImpl.java:2477)
        at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
        at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
        at java.lang.reflect.Method.invoke(Method.java:601)
        at com.cloud.vm.VmWorkJobHandlerProxy.handleVmWorkJob(VmWorkJobHandlerProxy.java:107)
        at com.cloud.storage.VolumeApiServiceImpl.handleVmWorkJob(VolumeApiServiceImpl.java:2512)
        at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
        at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
        at java.lang.reflect.Method.invoke(Method.java:601)
        at org.springframework.aop.support.AopUtils.invokeJoinpointUsingReflection(AopUtils.java:317)
        at org.springframework.aop.framework.ReflectiveMethodInvocation.invokeJoinpoint(ReflectiveMethodInvocation.java:183)
        at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:150)
        at org.springframework.aop.interceptor.ExposeInvocationInterceptor.invoke(ExposeInvocationInterceptor.java:91)
        at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172)
        at org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:204)
        at $Proxy181.handleVmWorkJob(Unknown Source)
        at com.cloud.vm.VmWorkJobDispatcher.runJob(VmWorkJobDispatcher.java:102)
        at org.apache.cloudstack.framework.jobs.impl.AsyncJobManagerImpl$5.runInContext(AsyncJobManagerImpl.java:503)
        at org.apache.cloudstack.managed.context.ManagedContextRunnable$1.run(ManagedContextRunnable.java:49)
        at org.apache.cloudstack.managed.context.impl.DefaultManagedContext$1.call(DefaultManagedContext.java:56)
        at org.apache.cloudstack.managed.context.impl.DefaultManagedContext.callWithContext(DefaultManagedContext.java:103)
        at org.apache.cloudstack.managed.context.impl.DefaultManagedContext.runWithContext(DefaultManagedContext.java:53)
        at org.apache.cloudstack.managed.context.ManagedContextRunnable.run(ManagedContextRunnable.java:46)
        at org.apache.cloudstack.framework.jobs.impl.AsyncJobManagerImpl$5.run(AsyncJobManagerImpl.java:460)
        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:722)
Caused by: com.cloud.exception.StorageUnavailableException: Resource [StoragePool:5] is unreachable: migrate volume failed: Local template file missing from \\smb19\hyperv-share\abhinav-ps1\ddcaf328-0e71-462b-9ce6-e520dc8f15cc.vhdx
        at org.apache.cloudstack.engine.orchestration.VolumeOrchestrator.migrateVolume(VolumeOrchestrator.java:882)
        at org.apache.cloudstack.engine.orchestration.VolumeOrchestrator.moveVolume(VolumeOrchestrator.java:202)
        at com.cloud.storage.VolumeApiServiceImpl.attachVolumeToVM(VolumeApiServiceImpl.java:1207)
        ... 33 more
2014-06-09 11:32:49,932 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl] (Work-Job-Executor-22:ctx-594553d6 job-66/job-67) Complete async job-67, jobStatus: FAILED, resultCode: 0, result: rO0ABXNyABpqYXZhLmxhbmcuUnVudGltZUV4Y2VwdGlvbp5fBkcKNIPlAgAAeHIAE2phdmEubGFuZy5FeGNlcHRpb27Q_R8-GjscxAIAAHhyABNqYXZhLmxhbmcuVGhyb3dhYmxl1cY1Jzl3uMsDAARMAAVjYXVzZXQAFUxqYXZhL2xhbmcvVGhyb3dhYmxlO0wADWRldGFpbE1lc3NhZ2V0ABJMamF2YS9sYW5nL1N0cmluZztbAApzdGFja1RyYWNldAAeW0xqYXZhL2xhbmcvU3RhY2tUcmFjZUVsZW1lbnQ7TAAUc3VwcHJlc3NlZEV4Y2VwdGlvbnN0ABBMamF2YS91dGlsL0xpc3Q7eHBxAH4AB3QALkpvYiBmYWlsZWQgZHVlIHRvIGV4Y2VwdGlvbiBtb3ZlIHZvbHVtZSBmYWlsZWR1cgAeW0xqYXZhLmxhbmcuU3RhY2tUcmFjZUVsZW1lbnQ7AkYqPDz9IjkCAAB4cAAAAA5zcgAbamF2YS5sYW5nLlN0YWNrVHJhY2VFbGVtZW50YQnFmiY23YUCAARJAApsaW5lTnVtYmVyTAAOZGVjbGFyaW5nQ2xhc3NxAH4ABEwACGZpbGVOYW1lcQB-AARMAAptZXRob2ROYW1lcQB-AAR4cAAAAHJ0ACBjb20uY2xvdWQudm0uVm1Xb3JrSm9iRGlzcGF0Y2hlcnQAGFZtV29ya0pvYkRpc3BhdGNoZXIuamF2YXQABnJ1bkpvYnNxAH4ACwAAAfd0AD9vcmcuYXBhY2hlLmNsb3Vkc3RhY2suZnJhbWV3b3JrLmpvYnMuaW1wbC5Bc3luY0pvYk1hbmFnZXJJbXBsJDV0ABhBc3luY0pvYk1hbmFnZXJJbXBsLmphdmF0AAxydW5JbkNvbnRleHRzcQB-AAsAAAAxdAA-b3JnLmFwYWNoZS5jbG91ZHN0YWNrLm1hbmFnZWQuY29udGV4dC5NYW5hZ2VkQ29udGV4dFJ1bm5hYmxlJDF0ABtNYW5hZ2VkQ29udGV4dFJ1bm5hYmxlLmphdmF0AANydW5zcQB-AAsAAAA4dABCb3JnLmFwYWNoZS5jbG91ZHN0YWNrLm1hbmFnZWQuY29udGV4dC5pbXBsLkRlZmF1bHRNYW5hZ2VkQ29udGV4dCQxdAAaRGVmYXVsdE1hbmFnZWRDb250ZXh0LmphdmF0AARjYWxsc3EAfgALAAAAZ3QAQG9yZy5hcGFjaGUuY2xvdWRzdGFjay5tYW5hZ2VkLmNvbnRleHQuaW1wbC5EZWZhdWx0TWFuYWdlZENvbnRleHRxAH4AGnQAD2NhbGxXaXRoQ29udGV4dHNxAH4ACwAAADVxAH4AHXEAfgAadAAOcnVuV2l0aENvbnRleHRzcQB-AAsAAAAudAA8b3JnLmFwYWNoZS5jbG91ZHN0YWNrLm1hbmFnZWQuY29udGV4dC5NYW5hZ2VkQ29udGV4dFJ1bm5hYmxlcQB-ABZxAH4AF3NxAH4ACwAAAcxxAH4AEXEAfgAScQB-ABdzcQB-AAsAAAHXdAAuamF2YS51dGlsLmNvbmN1cnJlbnQuRXhlY3V0b3JzJFJ1bm5hYmxlQWRhcHRlcnQADkV4ZWN1dG9ycy5qYXZhcQB-ABtzcQB-AAsAAAFOdAAkamF2YS51dGlsLmNvbmN1cnJlbnQuRnV0dXJlVGFzayRTeW5jdAAPRnV0dXJlVGFzay5qYXZhdAAIaW5uZXJSdW5zcQB-AAsAAACmdAAfamF2YS51dGlsLmNvbmN1cnJlbnQuRnV0dXJlVGFza3EAfgApcQB-ABdzcQB-AAsAAARWdAAnamF2YS51dGlsLmNvbmN1cnJlbnQuVGhyZWFkUG9vbEV4ZWN1dG9ydAAXVGhyZWFkUG9vbEV4ZWN1dG9yLmphdmF0AAlydW5Xb3JrZXJzcQB-AAsAAAJbdAAuamF2YS51dGlsLmNvbmN1cnJlbnQuVGhyZWFkUG9vbEV4ZWN1dG9yJFdvcmtlcnEAfgAvcQB-ABdzcQB-AAsAAALSdAAQamF2YS5sYW5nLlRocmVhZHQAC1RocmVhZC5qYXZhcQB-ABdzcgAmamF2YS51dGlsLkNvbGxlY3Rpb25zJFVubW9kaWZpYWJsZUxpc3T8DyUxteyOEAIAAUwABGxpc3RxAH4ABnhyACxqYXZhLnV0aWwuQ29sbGVjdGlvbnMkVW5tb2RpZmlhYmxlQ29sbGVjdGlvbhlCAIDLXvceAgABTAABY3QAFkxqYXZhL3V0aWwvQ29sbGVjdGlvbjt4cHNyABNqYXZhLnV0aWwuQXJyYXlMaXN0eIHSHZnHYZ0DAAFJAARzaXpleHAAAAAAdwQAAAAAeHEAfgA7eA
2014-06-09 11:32:49,943 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl] (Work-Job-Executor-22:ctx-594553d6 job-66/job-67) Done executing com.cloud.storage.VmWorkAttachVolume for job-67


NOTE :
=================
Download of an uploaded volume also is failing with this error.

  was:
Steps :
==================
1. Deploy an advanced zone Hyper-V setup.
2. Create a VM v1
3. Upload a volume upload1
4. Attach upload1 to v1


Expected Behavior :
==================
Attach should be successful.

Observed behavior :
==================
Attach fails with the following error :

2014-06-09 11:32:47,684 DEBUG [c.c.a.ApiServlet] (catalina-exec-8:ctx-02af10fb) ===START===  10.144.7.13 -- GET  command=attachVolume&id=ddcaf328-0e71-462b-9ce6-e520dc8f15cc&virtualMachineId=ea78d7ec-6fed-4c3e-bff1-29219c70bddd&response=json&sessionkey=4MQxLlvkRgcp%2FMBl6tf6TodmICI%3D&_=1402293458440
2014-06-09 11:32:47,726 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl] (catalina-exec-8:ctx-02af10fb ctx-dce1c925) submit async job-66, details: AsyncJobVO {id:66, userId: 2, accountId: 2, instanceType: Volume, instanceId: 7, cmd: org.apache.cloudstack.api.command.admin.volume.AttachVolumeCmdByAdmin, cmdInfo: {"response":"json","id":"ddcaf328-0e71-462b-9ce6-e520dc8f15cc","sessionkey":"4MQxLlvkRgcp/MBl6tf6TodmICI\u003d","ctxDetails":"{\"com.cloud.storage.Volume\":7,\"Volume\":\"ddcaf328-0e71-462b-9ce6-e520dc8f15cc\",\"com.cloud.vm.VirtualMachine\":5}","cmdEventType":"VOLUME.ATTACH","ctxUserId":"2","virtualMachineId":"ea78d7ec-6fed-4c3e-bff1-29219c70bddd","httpmethod":"GET","_":"1402293458440","uuid":"ddcaf328-0e71-462b-9ce6-e520dc8f15cc","ctxAccountId":"2","ctxStartEventId":"112"}, cmdVersion: 0, status: IN_PROGRESS, processStatus: 0, resultCode: 0, result: null, initMsid: 213737702773493, completeMsid: null, lastUpdated: null, lastPolled: null, created: null}
2014-06-09 11:32:47,727 DEBUG [c.c.a.ApiServlet] (catalina-exec-8:ctx-02af10fb ctx-dce1c925) ===END===  10.144.7.13 -- GET  command=attachVolume&id=ddcaf328-0e71-462b-9ce6-e520dc8f15cc&virtualMachineId=ea78d7ec-6fed-4c3e-bff1-29219c70bddd&response=json&sessionkey=4MQxLlvkRgcp%2FMBl6tf6TodmICI%3D&_=1402293458440
2014-06-09 11:32:47,729 INFO  [o.a.c.f.j.i.AsyncJobMonitor] (API-Job-Executor-24:ctx-976a0ed5 job-66) Add job-66 into job monitoring
2014-06-09 11:32:47,734 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl] (API-Job-Executor-24:ctx-976a0ed5 job-66) Executing AsyncJobVO {id:66, userId: 2, accountId: 2, instanceType: Volume, instanceId: 7, cmd: org.apache.cloudstack.api.command.admin.volume.AttachVolumeCmdByAdmin, cmdInfo: {"response":"json","id":"ddcaf328-0e71-462b-9ce6-e520dc8f15cc","sessionkey":"4MQxLlvkRgcp/MBl6tf6TodmICI\u003d","ctxDetails":"{\"com.cloud.storage.Volume\":7,\"Volume\":\"ddcaf328-0e71-462b-9ce6-e520dc8f15cc\",\"com.cloud.vm.VirtualMachine\":5}","cmdEventType":"VOLUME.ATTACH","ctxUserId":"2","virtualMachineId":"ea78d7ec-6fed-4c3e-bff1-29219c70bddd","httpmethod":"GET","_":"1402293458440","uuid":"ddcaf328-0e71-462b-9ce6-e520dc8f15cc","ctxAccountId":"2","ctxStartEventId":"112"}, cmdVersion: 0, status: IN_PROGRESS, processStatus: 0, resultCode: 0, result: null, initMsid: 213737702773493, completeMsid: null, lastUpdated: null, lastPolled: null, created: null}
2014-06-09 11:32:47,744 WARN  [c.c.a.d.ParamGenericValidationWorker] (API-Job-Executor-24:ctx-976a0ed5 job-66 ctx-1d0f4aec) Received unknown parameters for command attachVolume. Unknown parameters : ctxdetails
2014-06-09 11:32:47,769 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl] (API-Job-Executor-24:ctx-976a0ed5 job-66 ctx-1d0f4aec) Sync job-67 execution on object VmWorkJobQueue.5
2014-06-09 11:32:47,772 DEBUG [c.c.s.VolumeApiServiceImpl] (API-Job-Executor-24:ctx-976a0ed5 job-66 ctx-1d0f4aec) New job 67, result field: null
2014-06-09 11:32:47,773 WARN  [c.c.u.d.Merovingian2] (API-Job-Executor-24:ctx-976a0ed5 job-66 ctx-1d0f4aec) Was unable to find lock for the key vm_instance5 and thread id 1700504230
2014-06-09 11:32:49,461 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl] (AsyncJobMgr-Heartbeat-1:ctx-04e089b1) Execute sync-queue item: SyncQueueItemVO {id:24, queueId: 4, contentType: AsyncJob, contentId: 67, lastProcessMsid: null, lastprocessNumber: null, lastProcessTime: null, created: Mon Jun 09 11:32:47 IST 2014}
2014-06-09 11:32:49,463 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl] (AsyncJobMgr-Heartbeat-1:ctx-04e089b1) Schedule queued job-67
2014-06-09 11:32:49,480 INFO  [o.a.c.f.j.i.AsyncJobMonitor] (Work-Job-Executor-22:ctx-594553d6 job-66/job-67) Add job-67 into job monitoring
2014-06-09 11:32:49,481 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl] (Work-Job-Executor-22:ctx-594553d6 job-66/job-67) Executing AsyncJobVO {id:67, userId: 2, accountId: 2, instanceType: null, instanceId: null, cmd: com.cloud.storage.VmWorkAttachVolume, cmdInfo: rO0ABXNyACRjb20uY2xvdWQuc3RvcmFnZS5WbVdvcmtBdHRhY2hWb2x1bWUHra_5YYfiHAIAAkwACGRldmljZUlkdAAQTGphdmEvbGFuZy9Mb25nO0wACHZvbHVtZUlkcQB-AAF4cgATY29tLmNsb3VkLnZtLlZtV29ya5-ZtlbwJWdrAgAESgAJYWNjb3VudElkSgAGdXNlcklkSgAEdm1JZEwAC2hhbmRsZXJOYW1ldAASTGphdmEvbGFuZy9TdHJpbmc7eHAAAAAAAAAAAgAAAAAAAAACAAAAAAAAAAV0ABRWb2x1bWVBcGlTZXJ2aWNlSW1wbHBzcgAOamF2YS5sYW5nLkxvbmc7i-SQzI8j3wIAAUoABXZhbHVleHIAEGphdmEubGFuZy5OdW1iZXKGrJUdC5TgiwIAAHhwAAAAAAAAAAc, cmdVersion: 0, status: IN_PROGRESS, processStatus: 0, resultCode: 0, result: null, initMsid: 213737702773493, completeMsid: null, lastUpdated: null, lastPolled: null, created: Mon Jun 09 11:32:47 IST 2014}
2014-06-09 11:32:49,482 DEBUG [c.c.v.VmWorkJobDispatcher] (Work-Job-Executor-22:ctx-594553d6 job-66/job-67) Run VM work job: com.cloud.storage.VmWorkAttachVolume for VM 5, job origin: 66
2014-06-09 11:32:49,486 DEBUG [c.c.v.VmWorkJobHandlerProxy] (Work-Job-Executor-22:ctx-594553d6 job-66/job-67 ctx-136180d5) Execute VM work job: com.cloud.storage.VmWorkAttachVolume{"volumeId":7,"userId":2,"accountId":2,"vmId":5,"handlerName":"VolumeApiServiceImpl"}
2014-06-09 11:32:49,511 DEBUG [o.a.c.s.a.LocalStoragePoolAllocator] (Work-Job-Executor-22:ctx-594553d6 job-66/job-67 ctx-136180d5) LocalStoragePoolAllocator trying to find storage pool to fit the vm
2014-06-09 11:32:49,511 DEBUG [o.a.c.s.a.ClusterScopeStoragePoolAllocator] (Work-Job-Executor-22:ctx-594553d6 job-66/job-67 ctx-136180d5) ClusterScopeStoragePoolAllocator looking for storage pool
2014-06-09 11:32:49,511 DEBUG [o.a.c.s.a.ClusterScopeStoragePoolAllocator] (Work-Job-Executor-22:ctx-594553d6 job-66/job-67 ctx-136180d5) Looking for pools in dc: 1  pod:1  cluster:2
2014-06-09 11:32:49,512 DEBUG [o.a.c.s.a.ClusterScopeStoragePoolAllocator] (Work-Job-Executor-22:ctx-594553d6 job-66/job-67 ctx-136180d5) Found pools matching tags: [Pool[5|SMB], Pool[9|SMB]]
2014-06-09 11:32:49,514 DEBUG [o.a.c.s.a.ClusterScopeStoragePoolAllocator] (Work-Job-Executor-22:ctx-594553d6 job-66/job-67 ctx-136180d5) Removing pool Pool[5|SMB] from avoid set, must have been inserted when searching for another disk's tag
2014-06-09 11:32:49,514 DEBUG [o.a.c.s.a.ClusterScopeStoragePoolAllocator] (Work-Job-Executor-22:ctx-594553d6 job-66/job-67 ctx-136180d5) Removing pool Pool[9|SMB] from avoid set, must have been inserted when searching for another disk's tag
2014-06-09 11:32:49,515 DEBUG [o.a.c.s.a.AbstractStoragePoolAllocator] (Work-Job-Executor-22:ctx-594553d6 job-66/job-67 ctx-136180d5) Checking if storage pool is suitable, name: null ,poolId: 5
2014-06-09 11:32:49,519 DEBUG [c.c.s.StorageManagerImpl] (Work-Job-Executor-22:ctx-594553d6 job-66/job-67 ctx-136180d5) Checking pool 5 for storage, totalSize: 998772830208, usedBytes: 94130245632, usedPct: 0.09424590135516286, disable threshold: 0.85
2014-06-09 11:32:49,521 DEBUG [c.c.s.StorageManagerImpl] (Work-Job-Executor-22:ctx-594553d6 job-66/job-67 ctx-136180d5) Found storage pool ps2 of type SMB
2014-06-09 11:32:49,521 DEBUG [c.c.s.StorageManagerImpl] (Work-Job-Executor-22:ctx-594553d6 job-66/job-67 ctx-136180d5) Total capacity of the pool ps2 id: 5 is 998772830208
2014-06-09 11:32:49,522 DEBUG [c.c.s.StorageManagerImpl] (Work-Job-Executor-22:ctx-594553d6 job-66/job-67 ctx-136180d5) Checking pool: 5 for volume allocation [Vol[7|vm=null|DATADISK]], maxSize : 998772830208, totalAllocatedSize : 16106127360, askingSize : 0, allocated disable threshold: 0.85
2014-06-09 11:32:49,522 DEBUG [o.a.c.s.a.ClusterScopeStoragePoolAllocator] (Work-Job-Executor-22:ctx-594553d6 job-66/job-67 ctx-136180d5) ClusterScopeStoragePoolAllocator returning 1 suitable storage pools
2014-06-09 11:32:49,562 DEBUG [o.a.c.s.m.AncientDataMotionStrategy] (Work-Job-Executor-22:ctx-594553d6 job-66/job-67 ctx-136180d5) copyAsync inspecting src type VOLUME copyAsync inspecting dest type VOLUME
2014-06-09 11:32:49,565 DEBUG [o.a.c.s.c.a.StorageCacheRandomAllocator] (Work-Job-Executor-22:ctx-594553d6 job-66/job-67 ctx-136180d5) Can't find staging storage in zone: 1
2014-06-09 11:32:49,595 DEBUG [c.c.a.t.Request] (Work-Job-Executor-22:ctx-594553d6 job-66/job-67 ctx-136180d5) Seq 4-7045600142044462129: Sending  { Cmd , MgmtId: 213737702773493, via: 4(10.102.244.21), Ver: v1, Flags: 100011, [{"org.apache.cloudstack.storage.command.CopyCommand":{"srcTO":{"org.apache.cloudstack.storage.to.VolumeObjectTO":{"uuid":"ddcaf328-0e71-462b-9ce6-e520dc8f15cc","volumeType":"DATADISK","dataStore":{"org.apache.cloudstack.storage.to.PrimaryDataStoreTO":{"uuid":"641da331-d6d6-3224-b53a-0dad93667d30","id":2,"poolType":"SMB","host":"SMB19","path":"/hyperv-share/abhinav-ps1?user=abhinavroy&domain=BLR","port":445,"url":"SMB://SMB19/hyperv-share/abhinav-ps1?user=abhinavroy&domain=BLR/?ROLE=Primary&STOREUUID=641da331-d6d6-3224-b53a-0dad93667d30"}},"name":"upload1","size":5368709120,"volumeId":7,"accountId":2,"format":"VHDX","id":7,"hypervisorType":"Hyperv"}},"destTO":{"org.apache.cloudstack.storage.to.VolumeObjectTO":{"uuid":"ddcaf328-0e71-462b-9ce6-e520dc8f15cc","volumeType":"DATADISK","dataStore":{"com.cloud.agent.api.to.NfsTO":{"_url":"cifs://10.102.192.19/hyperv-share/abhinav-ss1?user=abhinavroy&domain=BLR","_role":"Image"}},"name":"upload1","size":5368709120,"path":"volumes/2/7","volumeId":7,"accountId":2,"format":"VHDX","id":7,"hypervisorType":"Hyperv"}},"executeInSequence":false,"options":{},"wait":10800}}] }
2014-06-09 11:32:49,596 DEBUG [c.c.a.t.Request] (Work-Job-Executor-22:ctx-594553d6 job-66/job-67 ctx-136180d5) Seq 4-7045600142044462129: Executing:  { Cmd , MgmtId: 213737702773493, via: 4(10.102.244.21), Ver: v1, Flags: 100011, [{"org.apache.cloudstack.storage.command.CopyCommand":{"srcTO":{"org.apache.cloudstack.storage.to.VolumeObjectTO":{"uuid":"ddcaf328-0e71-462b-9ce6-e520dc8f15cc","volumeType":"DATADISK","dataStore":{"org.apache.cloudstack.storage.to.PrimaryDataStoreTO":{"uuid":"641da331-d6d6-3224-b53a-0dad93667d30","id":2,"poolType":"SMB","host":"SMB19","path":"/hyperv-share/abhinav-ps1?user=abhinavroy&domain=BLR","port":445,"url":"SMB://SMB19/hyperv-share/abhinav-ps1?user=abhinavroy&domain=BLR/?ROLE=Primary&STOREUUID=641da331-d6d6-3224-b53a-0dad93667d30"}},"name":"upload1","size":5368709120,"volumeId":7,"accountId":2,"format":"VHDX","id":7,"hypervisorType":"Hyperv"}},"destTO":{"org.apache.cloudstack.storage.to.VolumeObjectTO":{"uuid":"ddcaf328-0e71-462b-9ce6-e520dc8f15cc","volumeType":"DATADISK","dataStore":{"com.cloud.agent.api.to.NfsTO":{"_url":"cifs://10.102.192.19/hyperv-share/abhinav-ss1?user=abhinavroy&domain=BLR","_role":"Image"}},"name":"upload1","size":5368709120,"path":"volumes/2/7","volumeId":7,"accountId":2,"format":"VHDX","id":7,"hypervisorType":"Hyperv"}},"executeInSequence":false,"options":{},"wait":10800}}] }
2014-06-09 11:32:49,596 DEBUG [c.c.a.m.DirectAgentAttache] (DirectAgent-9:ctx-1007ce0e) Seq 4-7045600142044462129: Executing request
2014-06-09 11:32:49,597 DEBUG [c.c.h.h.r.HypervDirectConnectResource] (DirectAgent-9:ctx-1007ce0e) POST request to https://10.102.244.21:8250/api/HypervResource/org.apache.cloudstack.storage.command.CopyCommand with contents {"srcTO":{"org.apache.cloudstack.storage.to.VolumeObjectTO":{"uuid":"ddcaf328-0e71-462b-9ce6-e520dc8f15cc","volumeType":"DATADISK","dataStore":{"org.apache.cloudstack.storage.to.PrimaryDataStoreTO":{"uuid":"641da331-d6d6-3224-b53a-0dad93667d30","id":2,"poolType":"SMB","host":"SMB19","path":"/hyperv-share/abhinav-ps1?user=abhinavroy&domain=BLR","port":445,"url":"SMB://SMB19/hyperv-share/abhinav-ps1?user=abhinavroy&domain=BLR/?ROLE=Primary&STOREUUID=641da331-d6d6-3224-b53a-0dad93667d30"}},"name":"upload1","size":5368709120,"volumeId":7,"accountId":2,"format":"VHDX","id":7,"hypervisorType":"Hyperv"}},"destTO":{"org.apache.cloudstack.storage.to.VolumeObjectTO":{"uuid":"ddcaf328-0e71-462b-9ce6-e520dc8f15cc","volumeType":"DATADISK","dataStore":{"com.cloud.agent.api.to.NfsTO":{"_url":"cifs://10.102.192.19/hyperv-share/abhinav-ss1?user=abhinavroy&domain=BLR","_role":"Image"}},"name":"upload1","size":5368709120,"path":"volumes/2/7","volumeId":7,"accountId":2,"format":"VHDX","id":7,"hypervisorType":"Hyperv"}},"executeInSequence":false,"options":{},"contextMap":{},"wait":10800}
2014-06-09 11:32:49,601 DEBUG [c.c.h.h.r.HypervDirectConnectResource] (DirectAgent-9:ctx-1007ce0e) Sending cmd to https://10.102.244.21:8250/api/HypervResource/org.apache.cloudstack.storage.command.CopyCommand cmd data:{"srcTO":{"org.apache.cloudstack.storage.to.VolumeObjectTO":{"uuid":"ddcaf328-0e71-462b-9ce6-e520dc8f15cc","volumeType":"DATADISK","dataStore":{"org.apache.cloudstack.storage.to.PrimaryDataStoreTO":{"uuid":"641da331-d6d6-3224-b53a-0dad93667d30","id":2,"poolType":"SMB","host":"SMB19","path":"/hyperv-share/abhinav-ps1?user=abhinavroy&domain=BLR","port":445,"url":"SMB://SMB19/hyperv-share/abhinav-ps1?user=abhinavroy&domain=BLR/?ROLE=Primary&STOREUUID=641da331-d6d6-3224-b53a-0dad93667d30"}},"name":"upload1","size":5368709120,"volumeId":7,"accountId":2,"format":"VHDX","id":7,"hypervisorType":"Hyperv"}},"destTO":{"org.apache.cloudstack.storage.to.VolumeObjectTO":{"uuid":"ddcaf328-0e71-462b-9ce6-e520dc8f15cc","volumeType":"DATADISK","dataStore":{"com.cloud.agent.api.to.NfsTO":{"_url":"cifs://10.102.192.19/hyperv-share/abhinav-ss1?user=abhinavroy&domain=BLR","_role":"Image"}},"name":"upload1","size":5368709120,"path":"volumes/2/7","volumeId":7,"accountId":2,"format":"VHDX","id":7,"hypervisorType":"Hyperv"}},"executeInSequence":false,"options":{},"contextMap":{},"wait":10800}
2014-06-09 11:32:49,667 DEBUG [c.c.s.StatsCollector] (StatsCollector-3:ctx-4c35f7eb) StorageCollector is running...
2014-06-09 11:32:49,680 DEBUG [c.c.h.h.r.HypervDirectConnectResource] (DirectAgent-9:ctx-1007ce0e) POST response is [{"org.apache.cloudstack.storage.command.CopyCmdAnswer":{"result":false,"details":"Local template file missing from \\smb19\hyperv-share\abhinav-ps1\ddcaf328-0e71-462b-9ce6-e520dc8f15cc.vhdx","newData":null,"contextMap":{}}}]
2014-06-09 11:32:49,681 DEBUG [c.c.h.h.r.HypervDirectConnectResource] (DirectAgent-9:ctx-1007ce0e) executeRequest received response [Lcom.cloud.agent.api.Answer;@43233812
2014-06-09 11:32:49,681 DEBUG [c.c.a.m.DirectAgentAttache] (DirectAgent-9:ctx-1007ce0e) Seq 4-7045600142044462129: Response Received:
2014-06-09 11:32:49,681 DEBUG [c.c.a.t.Request] (DirectAgent-9:ctx-1007ce0e) Seq 4-7045600142044462129: Processing:  { Ans: , MgmtId: 213737702773493, via: 4, Ver: v1, Flags: 10, [{"org.apache.cloudstack.storage.command.CopyCmdAnswer":{"result":false,"details":"Local template file missing from \\\\smb19\\hyperv-share\\abhinav-ps1\\ddcaf328-0e71-462b-9ce6-e520dc8f15cc.vhdx","wait":0}}] }
2014-06-09 11:32:49,681 DEBUG [c.c.a.t.Request] (Work-Job-Executor-22:ctx-594553d6 job-66/job-67 ctx-136180d5) Seq 4-7045600142044462129: Received:  { Ans: , MgmtId: 213737702773493, via: 4, Ver: v1, Flags: 10, { CopyCmdAnswer } }
2014-06-09 11:32:49,681 DEBUG [o.a.c.s.m.AncientDataMotionStrategy] (Work-Job-Executor-22:ctx-594553d6 job-66/job-67 ctx-136180d5) copy to image store failed: Local template file missing from \\smb19\hyperv-share\abhinav-ps1\ddcaf328-0e71-462b-9ce6-e520dc8f15cc.vhdx
2014-06-09 11:32:49,727 DEBUG [c.c.a.t.Request] (StatsCollector-3:ctx-4c35f7eb) Seq 3-8975955532326116099: Received:  { Ans: , MgmtId: 213737702773493, via: 3, Ver: v1, Flags: 10, { GetStorageStatsAnswer } }
2014-06-09 11:32:49,731 DEBUG [c.c.a.m.DirectAgentAttache] (DirectAgent-66:ctx-2742f335) Seq 1-3677470570724749799: Executing request
2014-06-09 11:32:49,732 DEBUG [c.c.h.h.r.HypervDirectConnectResource] (DirectAgent-66:ctx-2742f335) POST request to https://10.102.244.20:8250/api/HypervResource/com.cloud.agent.api.GetStorageStatsCommand with contents {"id":"c5034950-d1a2-3368-8c8f-77a2cb0d49d2-HypervResource","localPath":"C:\Users\Public\Documents\Hyper-V\Virtual Hard Disks","pooltype":"Filesystem","contextMap":{},"wait":0}
2014-06-09 11:32:49,738 DEBUG [c.c.h.h.r.HypervDirectConnectResource] (DirectAgent-66:ctx-2742f335) Sending cmd to https://10.102.244.20:8250/api/HypervResource/com.cloud.agent.api.GetStorageStatsCommand cmd data:{"id":"c5034950-d1a2-3368-8c8f-77a2cb0d49d2-HypervResource","localPath":"C:\Users\Public\Documents\Hyper-V\Virtual Hard Disks","pooltype":"Filesystem","contextMap":{},"wait":0}
2014-06-09 11:32:49,750 DEBUG [c.c.a.t.Request] (Work-Job-Executor-22:ctx-594553d6 job-66/job-67 ctx-136180d5) Seq 3-8975955532326116100: Sending  { Cmd , MgmtId: 213737702773493, via: 3(s-2-VM), Ver: v1, Flags: 100011, [{"org.apache.cloudstack.storage.command.DeleteCommand":{"data":{"org.apache.cloudstack.storage.to.VolumeObjectTO":{"uuid":"ddcaf328-0e71-462b-9ce6-e520dc8f15cc","volumeType":"DATADISK","dataStore":{"com.cloud.agent.api.to.NfsTO":{"_url":"cifs://10.102.192.19/hyperv-share/abhinav-ss1?user=abhinavroy&domain=BLR","_role":"Image"}},"name":"upload1","size":5368709120,"volumeId":7,"accountId":2,"format":"VHDX","id":7,"hypervisorType":"Hyperv"}},"wait":0}}] }
2014-06-09 11:32:49,800 DEBUG [c.c.h.h.r.HypervDirectConnectResource] (DirectAgent-66:ctx-2742f335) POST response is [{"com.cloud.agent.api.GetStorageStatsAnswer":{"result":true,"details":null,"capacity":497917358080,"used":14639181824,"contextMap":{}}}]
2014-06-09 11:32:49,801 DEBUG [c.c.h.h.r.HypervDirectConnectResource] (DirectAgent-66:ctx-2742f335) executeRequest received response [Lcom.cloud.agent.api.Answer;@5d8acfbf
2014-06-09 11:32:49,801 DEBUG [c.c.a.m.DirectAgentAttache] (DirectAgent-66:ctx-2742f335) Seq 1-3677470570724749799: Response Received:
2014-06-09 11:32:49,801 DEBUG [c.c.a.t.Request] (StatsCollector-3:ctx-4c35f7eb) Seq 1-3677470570724749799: Received:  { Ans: , MgmtId: 213737702773493, via: 1, Ver: v1, Flags: 10, { GetStorageStatsAnswer } }
2014-06-09 11:32:49,805 DEBUG [c.c.a.m.DirectAgentAttache] (DirectAgent-66:ctx-c6a55fb8) Seq 4-7045600142044462130: Executing request
2014-06-09 11:32:49,806 DEBUG [c.c.h.h.r.HypervDirectConnectResource] (DirectAgent-66:ctx-c6a55fb8) POST request to https://10.102.244.21:8250/api/HypervResource/com.cloud.agent.api.GetStorageStatsCommand with contents {"id":"641da331-d6d6-3224-b53a-0dad93667d30","localPath":"/hyperv-share/abhinav-ps1?user=abhinavroy&domain=BLR","pooltype":"SMB","contextMap":{},"wait":0}
2014-06-09 11:32:49,809 DEBUG [c.c.h.h.r.HypervDirectConnectResource] (DirectAgent-66:ctx-c6a55fb8) Sending cmd to https://10.102.244.21:8250/api/HypervResource/com.cloud.agent.api.GetStorageStatsCommand cmd data:{"id":"641da331-d6d6-3224-b53a-0dad93667d30","localPath":"/hyperv-share/abhinav-ps1?user=abhinavroy&domain=BLR","pooltype":"SMB","contextMap":{},"wait":0}
2014-06-09 11:32:49,835 DEBUG [c.c.a.t.Request] (AgentManager-Handler-1:null) Seq 3-8975955532326116100: Processing:  { Ans: , MgmtId: 213737702773493, via: 3, Ver: v1, Flags: 10, [{"com.cloud.agent.api.Answer":{"result":true,"details":"Object with null install path does not exist on image store , no need to delete","wait":0}}] }
2014-06-09 11:32:49,835 DEBUG [c.c.a.t.Request] (Work-Job-Executor-22:ctx-594553d6 job-66/job-67 ctx-136180d5) Seq 3-8975955532326116100: Received:  { Ans: , MgmtId: 213737702773493, via: 3, Ver: v1, Flags: 10, { Answer } }
2014-06-09 11:32:49,836 WARN  [o.a.c.s.d.ObjectInDataStoreManagerImpl] (Work-Job-Executor-22:ctx-594553d6 job-66/job-67 ctx-136180d5) Volume 7 is not found on image store 1, so no need to delete
2014-06-09 11:32:49,840 WARN  [o.a.c.s.d.ObjectInDataStoreManagerImpl] (Work-Job-Executor-22:ctx-594553d6 job-66/job-67 ctx-136180d5) Unsupported data object (VOLUME, org.apache.cloudstack.storage.datastore.PrimaryDataStoreImpl@4c73c07e), no need to delete from object in store ref table
2014-06-09 11:32:49,866 WARN  [o.a.c.s.d.ObjectInDataStoreManagerImpl] (Work-Job-Executor-22:ctx-594553d6 job-66/job-67 ctx-136180d5) Unsupported data object (VOLUME, org.apache.cloudstack.storage.datastore.PrimaryDataStoreImpl@15046282), no need to delete from object in store ref table
2014-06-09 11:32:49,867 DEBUG [c.c.h.h.r.HypervDirectConnectResource] (DirectAgent-66:ctx-c6a55fb8) POST response is [{"com.cloud.agent.api.GetStorageStatsAnswer":{"result":true,"details":null,"capacity":998772830208,"used":94130245632,"contextMap":{}}}]
2014-06-09 11:32:49,868 DEBUG [c.c.h.h.r.HypervDirectConnectResource] (DirectAgent-66:ctx-c6a55fb8) executeRequest received response [Lcom.cloud.agent.api.Answer;@4ae3f829
2014-06-09 11:32:49,868 DEBUG [c.c.a.m.DirectAgentAttache] (DirectAgent-66:ctx-c6a55fb8) Seq 4-7045600142044462130: Response Received:
2014-06-09 11:32:49,868 DEBUG [c.c.a.t.Request] (StatsCollector-3:ctx-4c35f7eb) Seq 4-7045600142044462130: Received:  { Ans: , MgmtId: 213737702773493, via: 4, Ver: v1, Flags: 10, { GetStorageStatsAnswer } }
2014-06-09 11:32:49,871 DEBUG [c.c.a.m.DirectAgentAttache] (DirectAgent-131:ctx-3fbe123c) Seq 4-7045600142044462131: Executing request
2014-06-09 11:32:49,871 DEBUG [c.c.h.h.r.HypervDirectConnectResource] (DirectAgent-131:ctx-3fbe123c) POST request to https://10.102.244.21:8250/api/HypervResource/com.cloud.agent.api.GetStorageStatsCommand with contents {"id":"c03aa877-a1af-315c-a2c2-2b10f128b1a3-HypervResource","localPath":"C:\Users\Public\Documents\Hyper-V\Virtual Hard Disks","pooltype":"Filesystem","contextMap":{},"wait":0}
2014-06-09 11:32:49,875 DEBUG [c.c.h.h.r.HypervDirectConnectResource] (DirectAgent-131:ctx-3fbe123c) Sending cmd to https://10.102.244.21:8250/api/HypervResource/com.cloud.agent.api.GetStorageStatsCommand cmd data:{"id":"c03aa877-a1af-315c-a2c2-2b10f128b1a3-HypervResource","localPath":"C:\Users\Public\Documents\Hyper-V\Virtual Hard Disks","pooltype":"Filesystem","contextMap":{},"wait":0}
2014-06-09 11:32:49,904 DEBUG [o.a.c.s.v.VolumeServiceImpl] (Work-Job-Executor-22:ctx-594553d6 job-66/job-67 ctx-136180d5) Marking volume that was never created as destroyed: Vol[14|vm=null|DATADISK]
2014-06-09 11:32:49,908 ERROR [o.a.c.e.o.VolumeOrchestrator] (Work-Job-Executor-22:ctx-594553d6 job-66/job-67 ctx-136180d5) migrate volume failed:Local template file missing from \\smb19\hyperv-share\abhinav-ps1\ddcaf328-0e71-462b-9ce6-e520dc8f15cc.vhdx
2014-06-09 11:32:49,908 DEBUG [c.c.s.VolumeApiServiceImpl] (Work-Job-Executor-22:ctx-594553d6 job-66/job-67 ctx-136180d5) move volume failed
com.cloud.exception.StorageUnavailableException: Resource [StoragePool:5] is unreachable: migrate volume failed: Local template file missing from \\smb19\hyperv-share\abhinav-ps1\ddcaf328-0e71-462b-9ce6-e520dc8f15cc.vhdx
        at org.apache.cloudstack.engine.orchestration.VolumeOrchestrator.migrateVolume(VolumeOrchestrator.java:882)
        at org.apache.cloudstack.engine.orchestration.VolumeOrchestrator.moveVolume(VolumeOrchestrator.java:202)
        at com.cloud.storage.VolumeApiServiceImpl.attachVolumeToVM(VolumeApiServiceImpl.java:1207)
        at com.cloud.storage.VolumeApiServiceImpl.orchestrateAttachVolumeToVM(VolumeApiServiceImpl.java:1055)
        at com.cloud.storage.VolumeApiServiceImpl.orchestrateAttachVolumeToVM(VolumeApiServiceImpl.java:2477)
        at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
        at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
        at java.lang.reflect.Method.invoke(Method.java:601)
        at com.cloud.vm.VmWorkJobHandlerProxy.handleVmWorkJob(VmWorkJobHandlerProxy.java:107)
        at com.cloud.storage.VolumeApiServiceImpl.handleVmWorkJob(VolumeApiServiceImpl.java:2512)
        at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
        at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
        at java.lang.reflect.Method.invoke(Method.java:601)
        at org.springframework.aop.support.AopUtils.invokeJoinpointUsingReflection(AopUtils.java:317)
        at org.springframework.aop.framework.ReflectiveMethodInvocation.invokeJoinpoint(ReflectiveMethodInvocation.java:183)
        at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:150)
        at org.springframework.aop.interceptor.ExposeInvocationInterceptor.invoke(ExposeInvocationInterceptor.java:91)
        at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172)
        at org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:204)
        at $Proxy181.handleVmWorkJob(Unknown Source)
        at com.cloud.vm.VmWorkJobDispatcher.runJob(VmWorkJobDispatcher.java:102)
        at org.apache.cloudstack.framework.jobs.impl.AsyncJobManagerImpl$5.runInContext(AsyncJobManagerImpl.java:503)
        at org.apache.cloudstack.managed.context.ManagedContextRunnable$1.run(ManagedContextRunnable.java:49)
        at org.apache.cloudstack.managed.context.impl.DefaultManagedContext$1.call(DefaultManagedContext.java:56)
        at org.apache.cloudstack.managed.context.impl.DefaultManagedContext.callWithContext(DefaultManagedContext.java:103)
        at org.apache.cloudstack.managed.context.impl.DefaultManagedContext.runWithContext(DefaultManagedContext.java:53)
        at org.apache.cloudstack.managed.context.ManagedContextRunnable.run(ManagedContextRunnable.java:46)
        at org.apache.cloudstack.framework.jobs.impl.AsyncJobManagerImpl$5.run(AsyncJobManagerImpl.java:460)
        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:722)
2014-06-09 11:32:49,909 ERROR [c.c.v.VmWorkJobHandlerProxy] (Work-Job-Executor-22:ctx-594553d6 job-66/job-67 ctx-136180d5) Invocation exception, caused by: com.cloud.utils.exception.CloudRuntimeException: move volume failed
2014-06-09 11:32:49,910 INFO  [c.c.v.VmWorkJobHandlerProxy] (Work-Job-Executor-22:ctx-594553d6 job-66/job-67 ctx-136180d5) Rethrow exception com.cloud.utils.exception.CloudRuntimeException: move volume failed
2014-06-09 11:32:49,910 DEBUG [c.c.v.VmWorkJobDispatcher] (Work-Job-Executor-22:ctx-594553d6 job-66/job-67) Done with run of VM work job: com.cloud.storage.VmWorkAttachVolume for VM 5, job origin: 66
2014-06-09 11:32:49,910 ERROR [c.c.v.VmWorkJobDispatcher] (Work-Job-Executor-22:ctx-594553d6 job-66/job-67) Unable to complete AsyncJobVO {id:67, userId: 2, accountId: 2, instanceType: null, instanceId: null, cmd: com.cloud.storage.VmWorkAttachVolume, cmdInfo: rO0ABXNyACRjb20uY2xvdWQuc3RvcmFnZS5WbVdvcmtBdHRhY2hWb2x1bWUHra_5YYfiHAIAAkwACGRldmljZUlkdAAQTGphdmEvbGFuZy9Mb25nO0wACHZvbHVtZUlkcQB-AAF4cgATY29tLmNsb3VkLnZtLlZtV29ya5-ZtlbwJWdrAgAESgAJYWNjb3VudElkSgAGdXNlcklkSgAEdm1JZEwAC2hhbmRsZXJOYW1ldAASTGphdmEvbGFuZy9TdHJpbmc7eHAAAAAAAAAAAgAAAAAAAAACAAAAAAAAAAV0ABRWb2x1bWVBcGlTZXJ2aWNlSW1wbHBzcgAOamF2YS5sYW5nLkxvbmc7i-SQzI8j3wIAAUoABXZhbHVleHIAEGphdmEubGFuZy5OdW1iZXKGrJUdC5TgiwIAAHhwAAAAAAAAAAc, cmdVersion: 0, status: IN_PROGRESS, processStatus: 0, resultCode: 0, result: null, initMsid: 213737702773493, completeMsid: null, lastUpdated: null, lastPolled: null, created: Mon Jun 09 11:32:47 IST 2014}, job origin:66
com.cloud.utils.exception.CloudRuntimeException: move volume failed
        at com.cloud.storage.VolumeApiServiceImpl.attachVolumeToVM(VolumeApiServiceImpl.java:1214)
        at com.cloud.storage.VolumeApiServiceImpl.orchestrateAttachVolumeToVM(VolumeApiServiceImpl.java:1055)
        at com.cloud.storage.VolumeApiServiceImpl.orchestrateAttachVolumeToVM(VolumeApiServiceImpl.java:2477)
        at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
        at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
        at java.lang.reflect.Method.invoke(Method.java:601)
        at com.cloud.vm.VmWorkJobHandlerProxy.handleVmWorkJob(VmWorkJobHandlerProxy.java:107)
        at com.cloud.storage.VolumeApiServiceImpl.handleVmWorkJob(VolumeApiServiceImpl.java:2512)
        at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
        at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
        at java.lang.reflect.Method.invoke(Method.java:601)
        at org.springframework.aop.support.AopUtils.invokeJoinpointUsingReflection(AopUtils.java:317)
        at org.springframework.aop.framework.ReflectiveMethodInvocation.invokeJoinpoint(ReflectiveMethodInvocation.java:183)
        at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:150)
        at org.springframework.aop.interceptor.ExposeInvocationInterceptor.invoke(ExposeInvocationInterceptor.java:91)
        at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172)
        at org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:204)
        at $Proxy181.handleVmWorkJob(Unknown Source)
        at com.cloud.vm.VmWorkJobDispatcher.runJob(VmWorkJobDispatcher.java:102)
        at org.apache.cloudstack.framework.jobs.impl.AsyncJobManagerImpl$5.runInContext(AsyncJobManagerImpl.java:503)
        at org.apache.cloudstack.managed.context.ManagedContextRunnable$1.run(ManagedContextRunnable.java:49)
        at org.apache.cloudstack.managed.context.impl.DefaultManagedContext$1.call(DefaultManagedContext.java:56)
        at org.apache.cloudstack.managed.context.impl.DefaultManagedContext.callWithContext(DefaultManagedContext.java:103)
        at org.apache.cloudstack.managed.context.impl.DefaultManagedContext.runWithContext(DefaultManagedContext.java:53)
        at org.apache.cloudstack.managed.context.ManagedContextRunnable.run(ManagedContextRunnable.java:46)
        at org.apache.cloudstack.framework.jobs.impl.AsyncJobManagerImpl$5.run(AsyncJobManagerImpl.java:460)
        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:722)
Caused by: com.cloud.exception.StorageUnavailableException: Resource [StoragePool:5] is unreachable: migrate volume failed: Local template file missing from \\smb19\hyperv-share\abhinav-ps1\ddcaf328-0e71-462b-9ce6-e520dc8f15cc.vhdx
        at org.apache.cloudstack.engine.orchestration.VolumeOrchestrator.migrateVolume(VolumeOrchestrator.java:882)
        at org.apache.cloudstack.engine.orchestration.VolumeOrchestrator.moveVolume(VolumeOrchestrator.java:202)
        at com.cloud.storage.VolumeApiServiceImpl.attachVolumeToVM(VolumeApiServiceImpl.java:1207)
        ... 33 more
2014-06-09 11:32:49,932 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl] (Work-Job-Executor-22:ctx-594553d6 job-66/job-67) Complete async job-67, jobStatus: FAILED, resultCode: 0, result: rO0ABXNyABpqYXZhLmxhbmcuUnVudGltZUV4Y2VwdGlvbp5fBkcKNIPlAgAAeHIAE2phdmEubGFuZy5FeGNlcHRpb27Q_R8-GjscxAIAAHhyABNqYXZhLmxhbmcuVGhyb3dhYmxl1cY1Jzl3uMsDAARMAAVjYXVzZXQAFUxqYXZhL2xhbmcvVGhyb3dhYmxlO0wADWRldGFpbE1lc3NhZ2V0ABJMamF2YS9sYW5nL1N0cmluZztbAApzdGFja1RyYWNldAAeW0xqYXZhL2xhbmcvU3RhY2tUcmFjZUVsZW1lbnQ7TAAUc3VwcHJlc3NlZEV4Y2VwdGlvbnN0ABBMamF2YS91dGlsL0xpc3Q7eHBxAH4AB3QALkpvYiBmYWlsZWQgZHVlIHRvIGV4Y2VwdGlvbiBtb3ZlIHZvbHVtZSBmYWlsZWR1cgAeW0xqYXZhLmxhbmcuU3RhY2tUcmFjZUVsZW1lbnQ7AkYqPDz9IjkCAAB4cAAAAA5zcgAbamF2YS5sYW5nLlN0YWNrVHJhY2VFbGVtZW50YQnFmiY23YUCAARJAApsaW5lTnVtYmVyTAAOZGVjbGFyaW5nQ2xhc3NxAH4ABEwACGZpbGVOYW1lcQB-AARMAAptZXRob2ROYW1lcQB-AAR4cAAAAHJ0ACBjb20uY2xvdWQudm0uVm1Xb3JrSm9iRGlzcGF0Y2hlcnQAGFZtV29ya0pvYkRpc3BhdGNoZXIuamF2YXQABnJ1bkpvYnNxAH4ACwAAAfd0AD9vcmcuYXBhY2hlLmNsb3Vkc3RhY2suZnJhbWV3b3JrLmpvYnMuaW1wbC5Bc3luY0pvYk1hbmFnZXJJbXBsJDV0ABhBc3luY0pvYk1hbmFnZXJJbXBsLmphdmF0AAxydW5JbkNvbnRleHRzcQB-AAsAAAAxdAA-b3JnLmFwYWNoZS5jbG91ZHN0YWNrLm1hbmFnZWQuY29udGV4dC5NYW5hZ2VkQ29udGV4dFJ1bm5hYmxlJDF0ABtNYW5hZ2VkQ29udGV4dFJ1bm5hYmxlLmphdmF0AANydW5zcQB-AAsAAAA4dABCb3JnLmFwYWNoZS5jbG91ZHN0YWNrLm1hbmFnZWQuY29udGV4dC5pbXBsLkRlZmF1bHRNYW5hZ2VkQ29udGV4dCQxdAAaRGVmYXVsdE1hbmFnZWRDb250ZXh0LmphdmF0AARjYWxsc3EAfgALAAAAZ3QAQG9yZy5hcGFjaGUuY2xvdWRzdGFjay5tYW5hZ2VkLmNvbnRleHQuaW1wbC5EZWZhdWx0TWFuYWdlZENvbnRleHRxAH4AGnQAD2NhbGxXaXRoQ29udGV4dHNxAH4ACwAAADVxAH4AHXEAfgAadAAOcnVuV2l0aENvbnRleHRzcQB-AAsAAAAudAA8b3JnLmFwYWNoZS5jbG91ZHN0YWNrLm1hbmFnZWQuY29udGV4dC5NYW5hZ2VkQ29udGV4dFJ1bm5hYmxlcQB-ABZxAH4AF3NxAH4ACwAAAcxxAH4AEXEAfgAScQB-ABdzcQB-AAsAAAHXdAAuamF2YS51dGlsLmNvbmN1cnJlbnQuRXhlY3V0b3JzJFJ1bm5hYmxlQWRhcHRlcnQADkV4ZWN1dG9ycy5qYXZhcQB-ABtzcQB-AAsAAAFOdAAkamF2YS51dGlsLmNvbmN1cnJlbnQuRnV0dXJlVGFzayRTeW5jdAAPRnV0dXJlVGFzay5qYXZhdAAIaW5uZXJSdW5zcQB-AAsAAACmdAAfamF2YS51dGlsLmNvbmN1cnJlbnQuRnV0dXJlVGFza3EAfgApcQB-ABdzcQB-AAsAAARWdAAnamF2YS51dGlsLmNvbmN1cnJlbnQuVGhyZWFkUG9vbEV4ZWN1dG9ydAAXVGhyZWFkUG9vbEV4ZWN1dG9yLmphdmF0AAlydW5Xb3JrZXJzcQB-AAsAAAJbdAAuamF2YS51dGlsLmNvbmN1cnJlbnQuVGhyZWFkUG9vbEV4ZWN1dG9yJFdvcmtlcnEAfgAvcQB-ABdzcQB-AAsAAALSdAAQamF2YS5sYW5nLlRocmVhZHQAC1RocmVhZC5qYXZhcQB-ABdzcgAmamF2YS51dGlsLkNvbGxlY3Rpb25zJFVubW9kaWZpYWJsZUxpc3T8DyUxteyOEAIAAUwABGxpc3RxAH4ABnhyACxqYXZhLnV0aWwuQ29sbGVjdGlvbnMkVW5tb2RpZmlhYmxlQ29sbGVjdGlvbhlCAIDLXvceAgABTAABY3QAFkxqYXZhL3V0aWwvQ29sbGVjdGlvbjt4cHNyABNqYXZhLnV0aWwuQXJyYXlMaXN0eIHSHZnHYZ0DAAFJAARzaXpleHAAAAAAdwQAAAAAeHEAfgA7eA
2014-06-09 11:32:49,943 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl] (Work-Job-Executor-22:ctx-594553d6 job-66/job-67) Done executing com.cloud.storage.VmWorkAttachVolume for job-67






> [Hyper-V]attach of an  uploaded volume is failing as it is looking for a .vhdx volume even though the volume present is .vhd
> ----------------------------------------------------------------------------------------------------------------------------
>
>                 Key: CLOUDSTACK-6865
>                 URL: https://issues.apache.org/jira/browse/CLOUDSTACK-6865
>             Project: CloudStack
>          Issue Type: Bug
>      Security Level: Public(Anyone can view this level - this is the default.) 
>          Components: Management Server, Storage Controller
>    Affects Versions: 4.4.0
>         Environment: Hyper-V
>            Reporter: Abhinav Roy
>            Priority: Critical
>              Labels: hyper-V,, hyper-v, hyperv
>             Fix For: 4.4.0
>
>
> Steps :
> ==================
> 1. Deploy an advanced zone Hyper-V setup.
> 2. Create a VM v1
> 3. Upload a volume upload1
> 4. Attach upload1 to v1
> Expected Behavior :
> ==================
> Attach should be successful.
> Observed behavior :
> ==================
> Attach fails with the following error :
> 2014-06-09 11:32:47,684 DEBUG [c.c.a.ApiServlet] (catalina-exec-8:ctx-02af10fb) ===START===  10.144.7.13 -- GET  command=attachVolume&id=ddcaf328-0e71-462b-9ce6-e520dc8f15cc&virtualMachineId=ea78d7ec-6fed-4c3e-bff1-29219c70bddd&response=json&sessionkey=4MQxLlvkRgcp%2FMBl6tf6TodmICI%3D&_=1402293458440
> 2014-06-09 11:32:47,726 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl] (catalina-exec-8:ctx-02af10fb ctx-dce1c925) submit async job-66, details: AsyncJobVO {id:66, userId: 2, accountId: 2, instanceType: Volume, instanceId: 7, cmd: org.apache.cloudstack.api.command.admin.volume.AttachVolumeCmdByAdmin, cmdInfo: {"response":"json","id":"ddcaf328-0e71-462b-9ce6-e520dc8f15cc","sessionkey":"4MQxLlvkRgcp/MBl6tf6TodmICI\u003d","ctxDetails":"{\"com.cloud.storage.Volume\":7,\"Volume\":\"ddcaf328-0e71-462b-9ce6-e520dc8f15cc\",\"com.cloud.vm.VirtualMachine\":5}","cmdEventType":"VOLUME.ATTACH","ctxUserId":"2","virtualMachineId":"ea78d7ec-6fed-4c3e-bff1-29219c70bddd","httpmethod":"GET","_":"1402293458440","uuid":"ddcaf328-0e71-462b-9ce6-e520dc8f15cc","ctxAccountId":"2","ctxStartEventId":"112"}, cmdVersion: 0, status: IN_PROGRESS, processStatus: 0, resultCode: 0, result: null, initMsid: 213737702773493, completeMsid: null, lastUpdated: null, lastPolled: null, created: null}
> 2014-06-09 11:32:47,727 DEBUG [c.c.a.ApiServlet] (catalina-exec-8:ctx-02af10fb ctx-dce1c925) ===END===  10.144.7.13 -- GET  command=attachVolume&id=ddcaf328-0e71-462b-9ce6-e520dc8f15cc&virtualMachineId=ea78d7ec-6fed-4c3e-bff1-29219c70bddd&response=json&sessionkey=4MQxLlvkRgcp%2FMBl6tf6TodmICI%3D&_=1402293458440
> 2014-06-09 11:32:47,729 INFO  [o.a.c.f.j.i.AsyncJobMonitor] (API-Job-Executor-24:ctx-976a0ed5 job-66) Add job-66 into job monitoring
> 2014-06-09 11:32:47,734 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl] (API-Job-Executor-24:ctx-976a0ed5 job-66) Executing AsyncJobVO {id:66, userId: 2, accountId: 2, instanceType: Volume, instanceId: 7, cmd: org.apache.cloudstack.api.command.admin.volume.AttachVolumeCmdByAdmin, cmdInfo: {"response":"json","id":"ddcaf328-0e71-462b-9ce6-e520dc8f15cc","sessionkey":"4MQxLlvkRgcp/MBl6tf6TodmICI\u003d","ctxDetails":"{\"com.cloud.storage.Volume\":7,\"Volume\":\"ddcaf328-0e71-462b-9ce6-e520dc8f15cc\",\"com.cloud.vm.VirtualMachine\":5}","cmdEventType":"VOLUME.ATTACH","ctxUserId":"2","virtualMachineId":"ea78d7ec-6fed-4c3e-bff1-29219c70bddd","httpmethod":"GET","_":"1402293458440","uuid":"ddcaf328-0e71-462b-9ce6-e520dc8f15cc","ctxAccountId":"2","ctxStartEventId":"112"}, cmdVersion: 0, status: IN_PROGRESS, processStatus: 0, resultCode: 0, result: null, initMsid: 213737702773493, completeMsid: null, lastUpdated: null, lastPolled: null, created: null}
> 2014-06-09 11:32:47,744 WARN  [c.c.a.d.ParamGenericValidationWorker] (API-Job-Executor-24:ctx-976a0ed5 job-66 ctx-1d0f4aec) Received unknown parameters for command attachVolume. Unknown parameters : ctxdetails
> 2014-06-09 11:32:47,769 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl] (API-Job-Executor-24:ctx-976a0ed5 job-66 ctx-1d0f4aec) Sync job-67 execution on object VmWorkJobQueue.5
> 2014-06-09 11:32:47,772 DEBUG [c.c.s.VolumeApiServiceImpl] (API-Job-Executor-24:ctx-976a0ed5 job-66 ctx-1d0f4aec) New job 67, result field: null
> 2014-06-09 11:32:47,773 WARN  [c.c.u.d.Merovingian2] (API-Job-Executor-24:ctx-976a0ed5 job-66 ctx-1d0f4aec) Was unable to find lock for the key vm_instance5 and thread id 1700504230
> 2014-06-09 11:32:49,461 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl] (AsyncJobMgr-Heartbeat-1:ctx-04e089b1) Execute sync-queue item: SyncQueueItemVO {id:24, queueId: 4, contentType: AsyncJob, contentId: 67, lastProcessMsid: null, lastprocessNumber: null, lastProcessTime: null, created: Mon Jun 09 11:32:47 IST 2014}
> 2014-06-09 11:32:49,463 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl] (AsyncJobMgr-Heartbeat-1:ctx-04e089b1) Schedule queued job-67
> 2014-06-09 11:32:49,480 INFO  [o.a.c.f.j.i.AsyncJobMonitor] (Work-Job-Executor-22:ctx-594553d6 job-66/job-67) Add job-67 into job monitoring
> 2014-06-09 11:32:49,481 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl] (Work-Job-Executor-22:ctx-594553d6 job-66/job-67) Executing AsyncJobVO {id:67, userId: 2, accountId: 2, instanceType: null, instanceId: null, cmd: com.cloud.storage.VmWorkAttachVolume, cmdInfo: rO0ABXNyACRjb20uY2xvdWQuc3RvcmFnZS5WbVdvcmtBdHRhY2hWb2x1bWUHra_5YYfiHAIAAkwACGRldmljZUlkdAAQTGphdmEvbGFuZy9Mb25nO0wACHZvbHVtZUlkcQB-AAF4cgATY29tLmNsb3VkLnZtLlZtV29ya5-ZtlbwJWdrAgAESgAJYWNjb3VudElkSgAGdXNlcklkSgAEdm1JZEwAC2hhbmRsZXJOYW1ldAASTGphdmEvbGFuZy9TdHJpbmc7eHAAAAAAAAAAAgAAAAAAAAACAAAAAAAAAAV0ABRWb2x1bWVBcGlTZXJ2aWNlSW1wbHBzcgAOamF2YS5sYW5nLkxvbmc7i-SQzI8j3wIAAUoABXZhbHVleHIAEGphdmEubGFuZy5OdW1iZXKGrJUdC5TgiwIAAHhwAAAAAAAAAAc, cmdVersion: 0, status: IN_PROGRESS, processStatus: 0, resultCode: 0, result: null, initMsid: 213737702773493, completeMsid: null, lastUpdated: null, lastPolled: null, created: Mon Jun 09 11:32:47 IST 2014}
> 2014-06-09 11:32:49,482 DEBUG [c.c.v.VmWorkJobDispatcher] (Work-Job-Executor-22:ctx-594553d6 job-66/job-67) Run VM work job: com.cloud.storage.VmWorkAttachVolume for VM 5, job origin: 66
> 2014-06-09 11:32:49,486 DEBUG [c.c.v.VmWorkJobHandlerProxy] (Work-Job-Executor-22:ctx-594553d6 job-66/job-67 ctx-136180d5) Execute VM work job: com.cloud.storage.VmWorkAttachVolume{"volumeId":7,"userId":2,"accountId":2,"vmId":5,"handlerName":"VolumeApiServiceImpl"}
> 2014-06-09 11:32:49,511 DEBUG [o.a.c.s.a.LocalStoragePoolAllocator] (Work-Job-Executor-22:ctx-594553d6 job-66/job-67 ctx-136180d5) LocalStoragePoolAllocator trying to find storage pool to fit the vm
> 2014-06-09 11:32:49,511 DEBUG [o.a.c.s.a.ClusterScopeStoragePoolAllocator] (Work-Job-Executor-22:ctx-594553d6 job-66/job-67 ctx-136180d5) ClusterScopeStoragePoolAllocator looking for storage pool
> 2014-06-09 11:32:49,511 DEBUG [o.a.c.s.a.ClusterScopeStoragePoolAllocator] (Work-Job-Executor-22:ctx-594553d6 job-66/job-67 ctx-136180d5) Looking for pools in dc: 1  pod:1  cluster:2
> 2014-06-09 11:32:49,512 DEBUG [o.a.c.s.a.ClusterScopeStoragePoolAllocator] (Work-Job-Executor-22:ctx-594553d6 job-66/job-67 ctx-136180d5) Found pools matching tags: [Pool[5|SMB], Pool[9|SMB]]
> 2014-06-09 11:32:49,514 DEBUG [o.a.c.s.a.ClusterScopeStoragePoolAllocator] (Work-Job-Executor-22:ctx-594553d6 job-66/job-67 ctx-136180d5) Removing pool Pool[5|SMB] from avoid set, must have been inserted when searching for another disk's tag
> 2014-06-09 11:32:49,514 DEBUG [o.a.c.s.a.ClusterScopeStoragePoolAllocator] (Work-Job-Executor-22:ctx-594553d6 job-66/job-67 ctx-136180d5) Removing pool Pool[9|SMB] from avoid set, must have been inserted when searching for another disk's tag
> 2014-06-09 11:32:49,515 DEBUG [o.a.c.s.a.AbstractStoragePoolAllocator] (Work-Job-Executor-22:ctx-594553d6 job-66/job-67 ctx-136180d5) Checking if storage pool is suitable, name: null ,poolId: 5
> 2014-06-09 11:32:49,519 DEBUG [c.c.s.StorageManagerImpl] (Work-Job-Executor-22:ctx-594553d6 job-66/job-67 ctx-136180d5) Checking pool 5 for storage, totalSize: 998772830208, usedBytes: 94130245632, usedPct: 0.09424590135516286, disable threshold: 0.85
> 2014-06-09 11:32:49,521 DEBUG [c.c.s.StorageManagerImpl] (Work-Job-Executor-22:ctx-594553d6 job-66/job-67 ctx-136180d5) Found storage pool ps2 of type SMB
> 2014-06-09 11:32:49,521 DEBUG [c.c.s.StorageManagerImpl] (Work-Job-Executor-22:ctx-594553d6 job-66/job-67 ctx-136180d5) Total capacity of the pool ps2 id: 5 is 998772830208
> 2014-06-09 11:32:49,522 DEBUG [c.c.s.StorageManagerImpl] (Work-Job-Executor-22:ctx-594553d6 job-66/job-67 ctx-136180d5) Checking pool: 5 for volume allocation [Vol[7|vm=null|DATADISK]], maxSize : 998772830208, totalAllocatedSize : 16106127360, askingSize : 0, allocated disable threshold: 0.85
> 2014-06-09 11:32:49,522 DEBUG [o.a.c.s.a.ClusterScopeStoragePoolAllocator] (Work-Job-Executor-22:ctx-594553d6 job-66/job-67 ctx-136180d5) ClusterScopeStoragePoolAllocator returning 1 suitable storage pools
> 2014-06-09 11:32:49,562 DEBUG [o.a.c.s.m.AncientDataMotionStrategy] (Work-Job-Executor-22:ctx-594553d6 job-66/job-67 ctx-136180d5) copyAsync inspecting src type VOLUME copyAsync inspecting dest type VOLUME
> 2014-06-09 11:32:49,565 DEBUG [o.a.c.s.c.a.StorageCacheRandomAllocator] (Work-Job-Executor-22:ctx-594553d6 job-66/job-67 ctx-136180d5) Can't find staging storage in zone: 1
> 2014-06-09 11:32:49,595 DEBUG [c.c.a.t.Request] (Work-Job-Executor-22:ctx-594553d6 job-66/job-67 ctx-136180d5) Seq 4-7045600142044462129: Sending  { Cmd , MgmtId: 213737702773493, via: 4(10.102.244.21), Ver: v1, Flags: 100011, [{"org.apache.cloudstack.storage.command.CopyCommand":{"srcTO":{"org.apache.cloudstack.storage.to.VolumeObjectTO":{"uuid":"ddcaf328-0e71-462b-9ce6-e520dc8f15cc","volumeType":"DATADISK","dataStore":{"org.apache.cloudstack.storage.to.PrimaryDataStoreTO":{"uuid":"641da331-d6d6-3224-b53a-0dad93667d30","id":2,"poolType":"SMB","host":"SMB19","path":"/hyperv-share/abhinav-ps1?user=abhinavroy&domain=BLR","port":445,"url":"SMB://SMB19/hyperv-share/abhinav-ps1?user=abhinavroy&domain=BLR/?ROLE=Primary&STOREUUID=641da331-d6d6-3224-b53a-0dad93667d30"}},"name":"upload1","size":5368709120,"volumeId":7,"accountId":2,"format":"VHDX","id":7,"hypervisorType":"Hyperv"}},"destTO":{"org.apache.cloudstack.storage.to.VolumeObjectTO":{"uuid":"ddcaf328-0e71-462b-9ce6-e520dc8f15cc","volumeType":"DATADISK","dataStore":{"com.cloud.agent.api.to.NfsTO":{"_url":"cifs://10.102.192.19/hyperv-share/abhinav-ss1?user=abhinavroy&domain=BLR","_role":"Image"}},"name":"upload1","size":5368709120,"path":"volumes/2/7","volumeId":7,"accountId":2,"format":"VHDX","id":7,"hypervisorType":"Hyperv"}},"executeInSequence":false,"options":{},"wait":10800}}] }
> 2014-06-09 11:32:49,596 DEBUG [c.c.a.t.Request] (Work-Job-Executor-22:ctx-594553d6 job-66/job-67 ctx-136180d5) Seq 4-7045600142044462129: Executing:  { Cmd , MgmtId: 213737702773493, via: 4(10.102.244.21), Ver: v1, Flags: 100011, [{"org.apache.cloudstack.storage.command.CopyCommand":{"srcTO":{"org.apache.cloudstack.storage.to.VolumeObjectTO":{"uuid":"ddcaf328-0e71-462b-9ce6-e520dc8f15cc","volumeType":"DATADISK","dataStore":{"org.apache.cloudstack.storage.to.PrimaryDataStoreTO":{"uuid":"641da331-d6d6-3224-b53a-0dad93667d30","id":2,"poolType":"SMB","host":"SMB19","path":"/hyperv-share/abhinav-ps1?user=abhinavroy&domain=BLR","port":445,"url":"SMB://SMB19/hyperv-share/abhinav-ps1?user=abhinavroy&domain=BLR/?ROLE=Primary&STOREUUID=641da331-d6d6-3224-b53a-0dad93667d30"}},"name":"upload1","size":5368709120,"volumeId":7,"accountId":2,"format":"VHDX","id":7,"hypervisorType":"Hyperv"}},"destTO":{"org.apache.cloudstack.storage.to.VolumeObjectTO":{"uuid":"ddcaf328-0e71-462b-9ce6-e520dc8f15cc","volumeType":"DATADISK","dataStore":{"com.cloud.agent.api.to.NfsTO":{"_url":"cifs://10.102.192.19/hyperv-share/abhinav-ss1?user=abhinavroy&domain=BLR","_role":"Image"}},"name":"upload1","size":5368709120,"path":"volumes/2/7","volumeId":7,"accountId":2,"format":"VHDX","id":7,"hypervisorType":"Hyperv"}},"executeInSequence":false,"options":{},"wait":10800}}] }
> 2014-06-09 11:32:49,596 DEBUG [c.c.a.m.DirectAgentAttache] (DirectAgent-9:ctx-1007ce0e) Seq 4-7045600142044462129: Executing request
> 2014-06-09 11:32:49,597 DEBUG [c.c.h.h.r.HypervDirectConnectResource] (DirectAgent-9:ctx-1007ce0e) POST request to https://10.102.244.21:8250/api/HypervResource/org.apache.cloudstack.storage.command.CopyCommand with contents {"srcTO":{"org.apache.cloudstack.storage.to.VolumeObjectTO":{"uuid":"ddcaf328-0e71-462b-9ce6-e520dc8f15cc","volumeType":"DATADISK","dataStore":{"org.apache.cloudstack.storage.to.PrimaryDataStoreTO":{"uuid":"641da331-d6d6-3224-b53a-0dad93667d30","id":2,"poolType":"SMB","host":"SMB19","path":"/hyperv-share/abhinav-ps1?user=abhinavroy&domain=BLR","port":445,"url":"SMB://SMB19/hyperv-share/abhinav-ps1?user=abhinavroy&domain=BLR/?ROLE=Primary&STOREUUID=641da331-d6d6-3224-b53a-0dad93667d30"}},"name":"upload1","size":5368709120,"volumeId":7,"accountId":2,"format":"VHDX","id":7,"hypervisorType":"Hyperv"}},"destTO":{"org.apache.cloudstack.storage.to.VolumeObjectTO":{"uuid":"ddcaf328-0e71-462b-9ce6-e520dc8f15cc","volumeType":"DATADISK","dataStore":{"com.cloud.agent.api.to.NfsTO":{"_url":"cifs://10.102.192.19/hyperv-share/abhinav-ss1?user=abhinavroy&domain=BLR","_role":"Image"}},"name":"upload1","size":5368709120,"path":"volumes/2/7","volumeId":7,"accountId":2,"format":"VHDX","id":7,"hypervisorType":"Hyperv"}},"executeInSequence":false,"options":{},"contextMap":{},"wait":10800}
> 2014-06-09 11:32:49,601 DEBUG [c.c.h.h.r.HypervDirectConnectResource] (DirectAgent-9:ctx-1007ce0e) Sending cmd to https://10.102.244.21:8250/api/HypervResource/org.apache.cloudstack.storage.command.CopyCommand cmd data:{"srcTO":{"org.apache.cloudstack.storage.to.VolumeObjectTO":{"uuid":"ddcaf328-0e71-462b-9ce6-e520dc8f15cc","volumeType":"DATADISK","dataStore":{"org.apache.cloudstack.storage.to.PrimaryDataStoreTO":{"uuid":"641da331-d6d6-3224-b53a-0dad93667d30","id":2,"poolType":"SMB","host":"SMB19","path":"/hyperv-share/abhinav-ps1?user=abhinavroy&domain=BLR","port":445,"url":"SMB://SMB19/hyperv-share/abhinav-ps1?user=abhinavroy&domain=BLR/?ROLE=Primary&STOREUUID=641da331-d6d6-3224-b53a-0dad93667d30"}},"name":"upload1","size":5368709120,"volumeId":7,"accountId":2,"format":"VHDX","id":7,"hypervisorType":"Hyperv"}},"destTO":{"org.apache.cloudstack.storage.to.VolumeObjectTO":{"uuid":"ddcaf328-0e71-462b-9ce6-e520dc8f15cc","volumeType":"DATADISK","dataStore":{"com.cloud.agent.api.to.NfsTO":{"_url":"cifs://10.102.192.19/hyperv-share/abhinav-ss1?user=abhinavroy&domain=BLR","_role":"Image"}},"name":"upload1","size":5368709120,"path":"volumes/2/7","volumeId":7,"accountId":2,"format":"VHDX","id":7,"hypervisorType":"Hyperv"}},"executeInSequence":false,"options":{},"contextMap":{},"wait":10800}
> 2014-06-09 11:32:49,667 DEBUG [c.c.s.StatsCollector] (StatsCollector-3:ctx-4c35f7eb) StorageCollector is running...
> 2014-06-09 11:32:49,680 DEBUG [c.c.h.h.r.HypervDirectConnectResource] (DirectAgent-9:ctx-1007ce0e) POST response is [{"org.apache.cloudstack.storage.command.CopyCmdAnswer":{"result":false,"details":"Local template file missing from \\smb19\hyperv-share\abhinav-ps1\ddcaf328-0e71-462b-9ce6-e520dc8f15cc.vhdx","newData":null,"contextMap":{}}}]
> 2014-06-09 11:32:49,681 DEBUG [c.c.h.h.r.HypervDirectConnectResource] (DirectAgent-9:ctx-1007ce0e) executeRequest received response [Lcom.cloud.agent.api.Answer;@43233812
> 2014-06-09 11:32:49,681 DEBUG [c.c.a.m.DirectAgentAttache] (DirectAgent-9:ctx-1007ce0e) Seq 4-7045600142044462129: Response Received:
> 2014-06-09 11:32:49,681 DEBUG [c.c.a.t.Request] (DirectAgent-9:ctx-1007ce0e) Seq 4-7045600142044462129: Processing:  { Ans: , MgmtId: 213737702773493, via: 4, Ver: v1, Flags: 10, [{"org.apache.cloudstack.storage.command.CopyCmdAnswer":{"result":false,"details":"Local template file missing from \\\\smb19\\hyperv-share\\abhinav-ps1\\ddcaf328-0e71-462b-9ce6-e520dc8f15cc.vhdx","wait":0}}] }
> 2014-06-09 11:32:49,681 DEBUG [c.c.a.t.Request] (Work-Job-Executor-22:ctx-594553d6 job-66/job-67 ctx-136180d5) Seq 4-7045600142044462129: Received:  { Ans: , MgmtId: 213737702773493, via: 4, Ver: v1, Flags: 10, { CopyCmdAnswer } }
> 2014-06-09 11:32:49,681 DEBUG [o.a.c.s.m.AncientDataMotionStrategy] (Work-Job-Executor-22:ctx-594553d6 job-66/job-67 ctx-136180d5) copy to image store failed: Local template file missing from \\smb19\hyperv-share\abhinav-ps1\ddcaf328-0e71-462b-9ce6-e520dc8f15cc.vhdx
> 2014-06-09 11:32:49,727 DEBUG [c.c.a.t.Request] (StatsCollector-3:ctx-4c35f7eb) Seq 3-8975955532326116099: Received:  { Ans: , MgmtId: 213737702773493, via: 3, Ver: v1, Flags: 10, { GetStorageStatsAnswer } }
> 2014-06-09 11:32:49,731 DEBUG [c.c.a.m.DirectAgentAttache] (DirectAgent-66:ctx-2742f335) Seq 1-3677470570724749799: Executing request
> 2014-06-09 11:32:49,732 DEBUG [c.c.h.h.r.HypervDirectConnectResource] (DirectAgent-66:ctx-2742f335) POST request to https://10.102.244.20:8250/api/HypervResource/com.cloud.agent.api.GetStorageStatsCommand with contents {"id":"c5034950-d1a2-3368-8c8f-77a2cb0d49d2-HypervResource","localPath":"C:\Users\Public\Documents\Hyper-V\Virtual Hard Disks","pooltype":"Filesystem","contextMap":{},"wait":0}
> 2014-06-09 11:32:49,738 DEBUG [c.c.h.h.r.HypervDirectConnectResource] (DirectAgent-66:ctx-2742f335) Sending cmd to https://10.102.244.20:8250/api/HypervResource/com.cloud.agent.api.GetStorageStatsCommand cmd data:{"id":"c5034950-d1a2-3368-8c8f-77a2cb0d49d2-HypervResource","localPath":"C:\Users\Public\Documents\Hyper-V\Virtual Hard Disks","pooltype":"Filesystem","contextMap":{},"wait":0}
> 2014-06-09 11:32:49,750 DEBUG [c.c.a.t.Request] (Work-Job-Executor-22:ctx-594553d6 job-66/job-67 ctx-136180d5) Seq 3-8975955532326116100: Sending  { Cmd , MgmtId: 213737702773493, via: 3(s-2-VM), Ver: v1, Flags: 100011, [{"org.apache.cloudstack.storage.command.DeleteCommand":{"data":{"org.apache.cloudstack.storage.to.VolumeObjectTO":{"uuid":"ddcaf328-0e71-462b-9ce6-e520dc8f15cc","volumeType":"DATADISK","dataStore":{"com.cloud.agent.api.to.NfsTO":{"_url":"cifs://10.102.192.19/hyperv-share/abhinav-ss1?user=abhinavroy&domain=BLR","_role":"Image"}},"name":"upload1","size":5368709120,"volumeId":7,"accountId":2,"format":"VHDX","id":7,"hypervisorType":"Hyperv"}},"wait":0}}] }
> 2014-06-09 11:32:49,800 DEBUG [c.c.h.h.r.HypervDirectConnectResource] (DirectAgent-66:ctx-2742f335) POST response is [{"com.cloud.agent.api.GetStorageStatsAnswer":{"result":true,"details":null,"capacity":497917358080,"used":14639181824,"contextMap":{}}}]
> 2014-06-09 11:32:49,801 DEBUG [c.c.h.h.r.HypervDirectConnectResource] (DirectAgent-66:ctx-2742f335) executeRequest received response [Lcom.cloud.agent.api.Answer;@5d8acfbf
> 2014-06-09 11:32:49,801 DEBUG [c.c.a.m.DirectAgentAttache] (DirectAgent-66:ctx-2742f335) Seq 1-3677470570724749799: Response Received:
> 2014-06-09 11:32:49,801 DEBUG [c.c.a.t.Request] (StatsCollector-3:ctx-4c35f7eb) Seq 1-3677470570724749799: Received:  { Ans: , MgmtId: 213737702773493, via: 1, Ver: v1, Flags: 10, { GetStorageStatsAnswer } }
> 2014-06-09 11:32:49,805 DEBUG [c.c.a.m.DirectAgentAttache] (DirectAgent-66:ctx-c6a55fb8) Seq 4-7045600142044462130: Executing request
> 2014-06-09 11:32:49,806 DEBUG [c.c.h.h.r.HypervDirectConnectResource] (DirectAgent-66:ctx-c6a55fb8) POST request to https://10.102.244.21:8250/api/HypervResource/com.cloud.agent.api.GetStorageStatsCommand with contents {"id":"641da331-d6d6-3224-b53a-0dad93667d30","localPath":"/hyperv-share/abhinav-ps1?user=abhinavroy&domain=BLR","pooltype":"SMB","contextMap":{},"wait":0}
> 2014-06-09 11:32:49,809 DEBUG [c.c.h.h.r.HypervDirectConnectResource] (DirectAgent-66:ctx-c6a55fb8) Sending cmd to https://10.102.244.21:8250/api/HypervResource/com.cloud.agent.api.GetStorageStatsCommand cmd data:{"id":"641da331-d6d6-3224-b53a-0dad93667d30","localPath":"/hyperv-share/abhinav-ps1?user=abhinavroy&domain=BLR","pooltype":"SMB","contextMap":{},"wait":0}
> 2014-06-09 11:32:49,835 DEBUG [c.c.a.t.Request] (AgentManager-Handler-1:null) Seq 3-8975955532326116100: Processing:  { Ans: , MgmtId: 213737702773493, via: 3, Ver: v1, Flags: 10, [{"com.cloud.agent.api.Answer":{"result":true,"details":"Object with null install path does not exist on image store , no need to delete","wait":0}}] }
> 2014-06-09 11:32:49,835 DEBUG [c.c.a.t.Request] (Work-Job-Executor-22:ctx-594553d6 job-66/job-67 ctx-136180d5) Seq 3-8975955532326116100: Received:  { Ans: , MgmtId: 213737702773493, via: 3, Ver: v1, Flags: 10, { Answer } }
> 2014-06-09 11:32:49,836 WARN  [o.a.c.s.d.ObjectInDataStoreManagerImpl] (Work-Job-Executor-22:ctx-594553d6 job-66/job-67 ctx-136180d5) Volume 7 is not found on image store 1, so no need to delete
> 2014-06-09 11:32:49,840 WARN  [o.a.c.s.d.ObjectInDataStoreManagerImpl] (Work-Job-Executor-22:ctx-594553d6 job-66/job-67 ctx-136180d5) Unsupported data object (VOLUME, org.apache.cloudstack.storage.datastore.PrimaryDataStoreImpl@4c73c07e), no need to delete from object in store ref table
> 2014-06-09 11:32:49,866 WARN  [o.a.c.s.d.ObjectInDataStoreManagerImpl] (Work-Job-Executor-22:ctx-594553d6 job-66/job-67 ctx-136180d5) Unsupported data object (VOLUME, org.apache.cloudstack.storage.datastore.PrimaryDataStoreImpl@15046282), no need to delete from object in store ref table
> 2014-06-09 11:32:49,867 DEBUG [c.c.h.h.r.HypervDirectConnectResource] (DirectAgent-66:ctx-c6a55fb8) POST response is [{"com.cloud.agent.api.GetStorageStatsAnswer":{"result":true,"details":null,"capacity":998772830208,"used":94130245632,"contextMap":{}}}]
> 2014-06-09 11:32:49,868 DEBUG [c.c.h.h.r.HypervDirectConnectResource] (DirectAgent-66:ctx-c6a55fb8) executeRequest received response [Lcom.cloud.agent.api.Answer;@4ae3f829
> 2014-06-09 11:32:49,868 DEBUG [c.c.a.m.DirectAgentAttache] (DirectAgent-66:ctx-c6a55fb8) Seq 4-7045600142044462130: Response Received:
> 2014-06-09 11:32:49,868 DEBUG [c.c.a.t.Request] (StatsCollector-3:ctx-4c35f7eb) Seq 4-7045600142044462130: Received:  { Ans: , MgmtId: 213737702773493, via: 4, Ver: v1, Flags: 10, { GetStorageStatsAnswer } }
> 2014-06-09 11:32:49,871 DEBUG [c.c.a.m.DirectAgentAttache] (DirectAgent-131:ctx-3fbe123c) Seq 4-7045600142044462131: Executing request
> 2014-06-09 11:32:49,871 DEBUG [c.c.h.h.r.HypervDirectConnectResource] (DirectAgent-131:ctx-3fbe123c) POST request to https://10.102.244.21:8250/api/HypervResource/com.cloud.agent.api.GetStorageStatsCommand with contents {"id":"c03aa877-a1af-315c-a2c2-2b10f128b1a3-HypervResource","localPath":"C:\Users\Public\Documents\Hyper-V\Virtual Hard Disks","pooltype":"Filesystem","contextMap":{},"wait":0}
> 2014-06-09 11:32:49,875 DEBUG [c.c.h.h.r.HypervDirectConnectResource] (DirectAgent-131:ctx-3fbe123c) Sending cmd to https://10.102.244.21:8250/api/HypervResource/com.cloud.agent.api.GetStorageStatsCommand cmd data:{"id":"c03aa877-a1af-315c-a2c2-2b10f128b1a3-HypervResource","localPath":"C:\Users\Public\Documents\Hyper-V\Virtual Hard Disks","pooltype":"Filesystem","contextMap":{},"wait":0}
> 2014-06-09 11:32:49,904 DEBUG [o.a.c.s.v.VolumeServiceImpl] (Work-Job-Executor-22:ctx-594553d6 job-66/job-67 ctx-136180d5) Marking volume that was never created as destroyed: Vol[14|vm=null|DATADISK]
> 2014-06-09 11:32:49,908 ERROR [o.a.c.e.o.VolumeOrchestrator] (Work-Job-Executor-22:ctx-594553d6 job-66/job-67 ctx-136180d5) migrate volume failed:Local template file missing from \\smb19\hyperv-share\abhinav-ps1\ddcaf328-0e71-462b-9ce6-e520dc8f15cc.vhdx
> 2014-06-09 11:32:49,908 DEBUG [c.c.s.VolumeApiServiceImpl] (Work-Job-Executor-22:ctx-594553d6 job-66/job-67 ctx-136180d5) move volume failed
> com.cloud.exception.StorageUnavailableException: Resource [StoragePool:5] is unreachable: migrate volume failed: Local template file missing from \\smb19\hyperv-share\abhinav-ps1\ddcaf328-0e71-462b-9ce6-e520dc8f15cc.vhdx
>         at org.apache.cloudstack.engine.orchestration.VolumeOrchestrator.migrateVolume(VolumeOrchestrator.java:882)
>         at org.apache.cloudstack.engine.orchestration.VolumeOrchestrator.moveVolume(VolumeOrchestrator.java:202)
>         at com.cloud.storage.VolumeApiServiceImpl.attachVolumeToVM(VolumeApiServiceImpl.java:1207)
>         at com.cloud.storage.VolumeApiServiceImpl.orchestrateAttachVolumeToVM(VolumeApiServiceImpl.java:1055)
>         at com.cloud.storage.VolumeApiServiceImpl.orchestrateAttachVolumeToVM(VolumeApiServiceImpl.java:2477)
>         at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
>         at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
>         at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
>         at java.lang.reflect.Method.invoke(Method.java:601)
>         at com.cloud.vm.VmWorkJobHandlerProxy.handleVmWorkJob(VmWorkJobHandlerProxy.java:107)
>         at com.cloud.storage.VolumeApiServiceImpl.handleVmWorkJob(VolumeApiServiceImpl.java:2512)
>         at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
>         at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
>         at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
>         at java.lang.reflect.Method.invoke(Method.java:601)
>         at org.springframework.aop.support.AopUtils.invokeJoinpointUsingReflection(AopUtils.java:317)
>         at org.springframework.aop.framework.ReflectiveMethodInvocation.invokeJoinpoint(ReflectiveMethodInvocation.java:183)
>         at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:150)
>         at org.springframework.aop.interceptor.ExposeInvocationInterceptor.invoke(ExposeInvocationInterceptor.java:91)
>         at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172)
>         at org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:204)
>         at $Proxy181.handleVmWorkJob(Unknown Source)
>         at com.cloud.vm.VmWorkJobDispatcher.runJob(VmWorkJobDispatcher.java:102)
>         at org.apache.cloudstack.framework.jobs.impl.AsyncJobManagerImpl$5.runInContext(AsyncJobManagerImpl.java:503)
>         at org.apache.cloudstack.managed.context.ManagedContextRunnable$1.run(ManagedContextRunnable.java:49)
>         at org.apache.cloudstack.managed.context.impl.DefaultManagedContext$1.call(DefaultManagedContext.java:56)
>         at org.apache.cloudstack.managed.context.impl.DefaultManagedContext.callWithContext(DefaultManagedContext.java:103)
>         at org.apache.cloudstack.managed.context.impl.DefaultManagedContext.runWithContext(DefaultManagedContext.java:53)
>         at org.apache.cloudstack.managed.context.ManagedContextRunnable.run(ManagedContextRunnable.java:46)
>         at org.apache.cloudstack.framework.jobs.impl.AsyncJobManagerImpl$5.run(AsyncJobManagerImpl.java:460)
>         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:722)
> 2014-06-09 11:32:49,909 ERROR [c.c.v.VmWorkJobHandlerProxy] (Work-Job-Executor-22:ctx-594553d6 job-66/job-67 ctx-136180d5) Invocation exception, caused by: com.cloud.utils.exception.CloudRuntimeException: move volume failed
> 2014-06-09 11:32:49,910 INFO  [c.c.v.VmWorkJobHandlerProxy] (Work-Job-Executor-22:ctx-594553d6 job-66/job-67 ctx-136180d5) Rethrow exception com.cloud.utils.exception.CloudRuntimeException: move volume failed
> 2014-06-09 11:32:49,910 DEBUG [c.c.v.VmWorkJobDispatcher] (Work-Job-Executor-22:ctx-594553d6 job-66/job-67) Done with run of VM work job: com.cloud.storage.VmWorkAttachVolume for VM 5, job origin: 66
> 2014-06-09 11:32:49,910 ERROR [c.c.v.VmWorkJobDispatcher] (Work-Job-Executor-22:ctx-594553d6 job-66/job-67) Unable to complete AsyncJobVO {id:67, userId: 2, accountId: 2, instanceType: null, instanceId: null, cmd: com.cloud.storage.VmWorkAttachVolume, cmdInfo: rO0ABXNyACRjb20uY2xvdWQuc3RvcmFnZS5WbVdvcmtBdHRhY2hWb2x1bWUHra_5YYfiHAIAAkwACGRldmljZUlkdAAQTGphdmEvbGFuZy9Mb25nO0wACHZvbHVtZUlkcQB-AAF4cgATY29tLmNsb3VkLnZtLlZtV29ya5-ZtlbwJWdrAgAESgAJYWNjb3VudElkSgAGdXNlcklkSgAEdm1JZEwAC2hhbmRsZXJOYW1ldAASTGphdmEvbGFuZy9TdHJpbmc7eHAAAAAAAAAAAgAAAAAAAAACAAAAAAAAAAV0ABRWb2x1bWVBcGlTZXJ2aWNlSW1wbHBzcgAOamF2YS5sYW5nLkxvbmc7i-SQzI8j3wIAAUoABXZhbHVleHIAEGphdmEubGFuZy5OdW1iZXKGrJUdC5TgiwIAAHhwAAAAAAAAAAc, cmdVersion: 0, status: IN_PROGRESS, processStatus: 0, resultCode: 0, result: null, initMsid: 213737702773493, completeMsid: null, lastUpdated: null, lastPolled: null, created: Mon Jun 09 11:32:47 IST 2014}, job origin:66
> com.cloud.utils.exception.CloudRuntimeException: move volume failed
>         at com.cloud.storage.VolumeApiServiceImpl.attachVolumeToVM(VolumeApiServiceImpl.java:1214)
>         at com.cloud.storage.VolumeApiServiceImpl.orchestrateAttachVolumeToVM(VolumeApiServiceImpl.java:1055)
>         at com.cloud.storage.VolumeApiServiceImpl.orchestrateAttachVolumeToVM(VolumeApiServiceImpl.java:2477)
>         at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
>         at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
>         at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
>         at java.lang.reflect.Method.invoke(Method.java:601)
>         at com.cloud.vm.VmWorkJobHandlerProxy.handleVmWorkJob(VmWorkJobHandlerProxy.java:107)
>         at com.cloud.storage.VolumeApiServiceImpl.handleVmWorkJob(VolumeApiServiceImpl.java:2512)
>         at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
>         at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
>         at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
>         at java.lang.reflect.Method.invoke(Method.java:601)
>         at org.springframework.aop.support.AopUtils.invokeJoinpointUsingReflection(AopUtils.java:317)
>         at org.springframework.aop.framework.ReflectiveMethodInvocation.invokeJoinpoint(ReflectiveMethodInvocation.java:183)
>         at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:150)
>         at org.springframework.aop.interceptor.ExposeInvocationInterceptor.invoke(ExposeInvocationInterceptor.java:91)
>         at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172)
>         at org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:204)
>         at $Proxy181.handleVmWorkJob(Unknown Source)
>         at com.cloud.vm.VmWorkJobDispatcher.runJob(VmWorkJobDispatcher.java:102)
>         at org.apache.cloudstack.framework.jobs.impl.AsyncJobManagerImpl$5.runInContext(AsyncJobManagerImpl.java:503)
>         at org.apache.cloudstack.managed.context.ManagedContextRunnable$1.run(ManagedContextRunnable.java:49)
>         at org.apache.cloudstack.managed.context.impl.DefaultManagedContext$1.call(DefaultManagedContext.java:56)
>         at org.apache.cloudstack.managed.context.impl.DefaultManagedContext.callWithContext(DefaultManagedContext.java:103)
>         at org.apache.cloudstack.managed.context.impl.DefaultManagedContext.runWithContext(DefaultManagedContext.java:53)
>         at org.apache.cloudstack.managed.context.ManagedContextRunnable.run(ManagedContextRunnable.java:46)
>         at org.apache.cloudstack.framework.jobs.impl.AsyncJobManagerImpl$5.run(AsyncJobManagerImpl.java:460)
>         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:722)
> Caused by: com.cloud.exception.StorageUnavailableException: Resource [StoragePool:5] is unreachable: migrate volume failed: Local template file missing from \\smb19\hyperv-share\abhinav-ps1\ddcaf328-0e71-462b-9ce6-e520dc8f15cc.vhdx
>         at org.apache.cloudstack.engine.orchestration.VolumeOrchestrator.migrateVolume(VolumeOrchestrator.java:882)
>         at org.apache.cloudstack.engine.orchestration.VolumeOrchestrator.moveVolume(VolumeOrchestrator.java:202)
>         at com.cloud.storage.VolumeApiServiceImpl.attachVolumeToVM(VolumeApiServiceImpl.java:1207)
>         ... 33 more
> 2014-06-09 11:32:49,932 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl] (Work-Job-Executor-22:ctx-594553d6 job-66/job-67) Complete async job-67, jobStatus: FAILED, resultCode: 0, result: rO0ABXNyABpqYXZhLmxhbmcuUnVudGltZUV4Y2VwdGlvbp5fBkcKNIPlAgAAeHIAE2phdmEubGFuZy5FeGNlcHRpb27Q_R8-GjscxAIAAHhyABNqYXZhLmxhbmcuVGhyb3dhYmxl1cY1Jzl3uMsDAARMAAVjYXVzZXQAFUxqYXZhL2xhbmcvVGhyb3dhYmxlO0wADWRldGFpbE1lc3NhZ2V0ABJMamF2YS9sYW5nL1N0cmluZztbAApzdGFja1RyYWNldAAeW0xqYXZhL2xhbmcvU3RhY2tUcmFjZUVsZW1lbnQ7TAAUc3VwcHJlc3NlZEV4Y2VwdGlvbnN0ABBMamF2YS91dGlsL0xpc3Q7eHBxAH4AB3QALkpvYiBmYWlsZWQgZHVlIHRvIGV4Y2VwdGlvbiBtb3ZlIHZvbHVtZSBmYWlsZWR1cgAeW0xqYXZhLmxhbmcuU3RhY2tUcmFjZUVsZW1lbnQ7AkYqPDz9IjkCAAB4cAAAAA5zcgAbamF2YS5sYW5nLlN0YWNrVHJhY2VFbGVtZW50YQnFmiY23YUCAARJAApsaW5lTnVtYmVyTAAOZGVjbGFyaW5nQ2xhc3NxAH4ABEwACGZpbGVOYW1lcQB-AARMAAptZXRob2ROYW1lcQB-AAR4cAAAAHJ0ACBjb20uY2xvdWQudm0uVm1Xb3JrSm9iRGlzcGF0Y2hlcnQAGFZtV29ya0pvYkRpc3BhdGNoZXIuamF2YXQABnJ1bkpvYnNxAH4ACwAAAfd0AD9vcmcuYXBhY2hlLmNsb3Vkc3RhY2suZnJhbWV3b3JrLmpvYnMuaW1wbC5Bc3luY0pvYk1hbmFnZXJJbXBsJDV0ABhBc3luY0pvYk1hbmFnZXJJbXBsLmphdmF0AAxydW5JbkNvbnRleHRzcQB-AAsAAAAxdAA-b3JnLmFwYWNoZS5jbG91ZHN0YWNrLm1hbmFnZWQuY29udGV4dC5NYW5hZ2VkQ29udGV4dFJ1bm5hYmxlJDF0ABtNYW5hZ2VkQ29udGV4dFJ1bm5hYmxlLmphdmF0AANydW5zcQB-AAsAAAA4dABCb3JnLmFwYWNoZS5jbG91ZHN0YWNrLm1hbmFnZWQuY29udGV4dC5pbXBsLkRlZmF1bHRNYW5hZ2VkQ29udGV4dCQxdAAaRGVmYXVsdE1hbmFnZWRDb250ZXh0LmphdmF0AARjYWxsc3EAfgALAAAAZ3QAQG9yZy5hcGFjaGUuY2xvdWRzdGFjay5tYW5hZ2VkLmNvbnRleHQuaW1wbC5EZWZhdWx0TWFuYWdlZENvbnRleHRxAH4AGnQAD2NhbGxXaXRoQ29udGV4dHNxAH4ACwAAADVxAH4AHXEAfgAadAAOcnVuV2l0aENvbnRleHRzcQB-AAsAAAAudAA8b3JnLmFwYWNoZS5jbG91ZHN0YWNrLm1hbmFnZWQuY29udGV4dC5NYW5hZ2VkQ29udGV4dFJ1bm5hYmxlcQB-ABZxAH4AF3NxAH4ACwAAAcxxAH4AEXEAfgAScQB-ABdzcQB-AAsAAAHXdAAuamF2YS51dGlsLmNvbmN1cnJlbnQuRXhlY3V0b3JzJFJ1bm5hYmxlQWRhcHRlcnQADkV4ZWN1dG9ycy5qYXZhcQB-ABtzcQB-AAsAAAFOdAAkamF2YS51dGlsLmNvbmN1cnJlbnQuRnV0dXJlVGFzayRTeW5jdAAPRnV0dXJlVGFzay5qYXZhdAAIaW5uZXJSdW5zcQB-AAsAAACmdAAfamF2YS51dGlsLmNvbmN1cnJlbnQuRnV0dXJlVGFza3EAfgApcQB-ABdzcQB-AAsAAARWdAAnamF2YS51dGlsLmNvbmN1cnJlbnQuVGhyZWFkUG9vbEV4ZWN1dG9ydAAXVGhyZWFkUG9vbEV4ZWN1dG9yLmphdmF0AAlydW5Xb3JrZXJzcQB-AAsAAAJbdAAuamF2YS51dGlsLmNvbmN1cnJlbnQuVGhyZWFkUG9vbEV4ZWN1dG9yJFdvcmtlcnEAfgAvcQB-ABdzcQB-AAsAAALSdAAQamF2YS5sYW5nLlRocmVhZHQAC1RocmVhZC5qYXZhcQB-ABdzcgAmamF2YS51dGlsLkNvbGxlY3Rpb25zJFVubW9kaWZpYWJsZUxpc3T8DyUxteyOEAIAAUwABGxpc3RxAH4ABnhyACxqYXZhLnV0aWwuQ29sbGVjdGlvbnMkVW5tb2RpZmlhYmxlQ29sbGVjdGlvbhlCAIDLXvceAgABTAABY3QAFkxqYXZhL3V0aWwvQ29sbGVjdGlvbjt4cHNyABNqYXZhLnV0aWwuQXJyYXlMaXN0eIHSHZnHYZ0DAAFJAARzaXpleHAAAAAAdwQAAAAAeHEAfgA7eA
> 2014-06-09 11:32:49,943 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl] (Work-Job-Executor-22:ctx-594553d6 job-66/job-67) Done executing com.cloud.storage.VmWorkAttachVolume for job-67
> NOTE :
> =================
> Download of an uploaded volume also is failing with this error.



--
This message was sent by Atlassian JIRA
(v6.2#6252)

Mime
View raw message