[
https://issues.apache.org/jira/browse/CLOUDSTACK-4521?page=com.atlassian.jira.plugin.system.issuetabpanels:comment-tabpanel&focusedCommentId=13752233#comment-13752233
]
Abhinav Roy commented on CLOUDSTACK-4521:
-----------------------------------------
Hi Min,
yes, last time the uploaded volume might have been corrupt ,sorry for that. But
today again i tried the same scenario with a legitimate volume and this time
again i saw this NPE in copy volume,
chineId=bfa9a44b-76dc-496d-8a69-1ea9c9f1eea4&response=json&sessionkey=YLVEsmGR39Coa5VtDBemh9vAf%2BQ%3D&_=1377679939715
2013-08-28 14:18:55,269 DEBUG [cloud.async.AsyncJobManagerImpl]
(catalina-exec-9:null) submit async job-100 = [
e328cdae-0db7-40d3-9bb2-6fcaacccb013 ], details: AsyncJobVO {id:100, userId: 2,
accountId: 2, sessionKey: null, instanceType: Volume, instanceId: 26, cmd:
org.apache.cloudstack.api.command.user.volume.AttachVolumeCmd, cmdOriginator:
null, cmdInfo:
{"response":"json","id":"2be0cac1-cb4a-4f38-842d-538f094f041c","sessionkey":"YLVEsmGR39Coa5VtDBemh9vAf+Q\u003d","cmdEventType":"VOLUME.ATTACH","ctxUserId":"2","virtualMachineId":"bfa9a44b-76dc-496d-8a69-1ea9c9f1eea4","httpmethod":"GET","_":"1377679939715","ctxAccountId":"2","ctxStartEventId":"309"},
cmdVersion: 0, callbackType: 0, callbackAddress: null, status: 0,
processStatus: 0, resultCode: 0, result: null, initMsid: 280320865129348,
completeMsid: null, lastUpdated: null, lastPolled: null, created: null}
2013-08-28 14:18:55,271 DEBUG [cloud.api.ApiServlet] (catalina-exec-9:null)
===END=== 10.144.6.17 -- GET
command=attachVolume&id=2be0cac1-cb4a-4f38-842d-538f094f041c&virtualMachineId=bfa9a44b-76dc-496d-8a69-1ea9c9f1eea4&response=json&sessionkey=YLVEsmGR39Coa5VtDBemh9vAf%2BQ%3D&_=1377679939715
2013-08-28 14:18:55,273 DEBUG [cloud.async.AsyncJobManagerImpl]
(Job-Executor-86:job-100 = [ e328cdae-0db7-40d3-9bb2-6fcaacccb013 ]) Executing
org.apache.cloudstack.api.command.user.volume.AttachVolumeCmd for job-100 = [
e328cdae-0db7-40d3-9bb2-6fcaacccb013 ]
2013-08-28 14:18:55,305 DEBUG [storage.allocator.LocalStoragePoolAllocator]
(Job-Executor-86:job-100 = [ e328cdae-0db7-40d3-9bb2-6fcaacccb013 ])
LocalStoragePoolAllocator trying to find storage pool to fit the vm
2013-08-28 14:18:55,306 DEBUG
[storage.allocator.ClusterScopeStoragePoolAllocator] (Job-Executor-86:job-100 =
[ e328cdae-0db7-40d3-9bb2-6fcaacccb013 ]) ClusterScopeStoragePoolAllocator
looking for storage pool
2013-08-28 14:18:55,306 DEBUG
[storage.allocator.ClusterScopeStoragePoolAllocator] (Job-Executor-86:job-100 =
[ e328cdae-0db7-40d3-9bb2-6fcaacccb013 ]) Looking for pools in dc: 1 pod:1
cluster:1
2013-08-28 14:18:55,309 DEBUG [storage.allocator.AbstractStoragePoolAllocator]
(Job-Executor-86:job-100 = [ e328cdae-0db7-40d3-9bb2-6fcaacccb013 ]) Checking
if storage pool is suitable, name: null ,poolId: 201
2013-08-28 14:18:55,313 DEBUG [cloud.storage.StorageManagerImpl]
(Job-Executor-86:job-100 = [ e328cdae-0db7-40d3-9bb2-6fcaacccb013 ]) Checking
pool 201 for storage, totalSize: 107105746944, usedBytes: 12980322304, usedPct:
0.12119165100250627, disable threshold: 0.85
2013-08-28 14:18:55,320 DEBUG [cloud.storage.StorageManagerImpl]
(Job-Executor-86:job-100 = [ e328cdae-0db7-40d3-9bb2-6fcaacccb013 ]) Checking
pool: 201 for volume allocation [Vol[26|vm=null|DATADISK]], maxSize :
107105746944, totalAllocatedSize : 14831058944, askingSize : 2097152000,
allocated disable threshold: 0.85
2013-08-28 14:18:55,320 DEBUG
[storage.allocator.ClusterScopeStoragePoolAllocator] (Job-Executor-86:job-100 =
[ e328cdae-0db7-40d3-9bb2-6fcaacccb013 ]) FirstFitStoragePoolAllocator
returning 1 suitable storage pools
2013-08-28 14:18:55,346 DEBUG [storage.motion.AncientDataMotionStrategy]
(Job-Executor-86:job-100 = [ e328cdae-0db7-40d3-9bb2-6fcaacccb013 ]) copyAsync
inspecting src type VOLUME copyAsync inspecting dest type VOLUME
2013-08-28 14:18:55,370 DEBUG [agent.transport.Request]
(Job-Executor-86:job-100 = [ e328cdae-0db7-40d3-9bb2-6fcaacccb013 ]) Seq
6-1711539443: Sending { Cmd , MgmtId: 280320865129348, via: 6, Ver: v1, Flags:
100111,
[{"org.apache.cloudstack.storage.command.CopyCommand":{"srcTO":{"org.apache.cloudstack.storage.to.VolumeObjectTO":{"uuid":"2be0cac1-cb4a-4f38-842d-538f094f041c","volumeType":"DATADISK","dataStore":{"com.cloud.agent.api.to.NfsTO":{"_url":"nfs://10.102.192.100/cpg_vol/abhinav/esx-sec-burbank","_role":"Image"}},"name":"uloadvol3","size":2097152000,"path":"volumes/2/26/6bd783eb-368a-38b8-97f6-14ba92df2093.ova","volumeId":26,"accountId":2,"format":"OVA","id":26,"hypervisorType":"VMware"}},"destTO":{"org.apache.cloudstack.storage.to.VolumeObjectTO":{"uuid":"2be0cac1-cb4a-4f38-842d-538f094f041c","volumeType":"DATADISK","dataStore":{"org.apache.cloudstack.storage.to.PrimaryDataStoreTO":{"uuid":"07580f3c-3c3d-3ac3-aec6-b36270b8fbc2","id":201,"poolType":"VMFS","host":"VMFS
datastore:
/roydc/primary-iscsi1","path":"/roydc/primary-iscsi1","port":0}},"name":"uloadvol3","size":2097152000,"volumeId":26,"accountId":2,"format":"OVA","id":26,"hypervisorType":"VMware"}},"executeInSequence":true,"wait":10800}}]
}
2013-08-28 14:18:55,545 DEBUG [agent.manager.AgentManagerImpl]
(AgentManager-Handler-9:null) SeqA 5-8880: Processing Seq 5-8880: { Cmd ,
MgmtId: -1, via: 5, Ver: v1, Flags: 11,
[{"com.cloud.agent.api.ConsoleProxyLoadReportCommand":{"_proxyVmId":9,"_loadInfo":"{\n
\"connections\": []\n}","wait":0}}] }
2013-08-28 14:18:55,548 DEBUG [agent.manager.AgentManagerImpl]
(AgentManager-Handler-9:null) SeqA 5-8880: Sending Seq 5-8880: { Ans: ,
MgmtId: 280320865129348, via: 5, Ver: v1, Flags: 100010,
[{"com.cloud.agent.api.AgentControlAnswer":{"result":true,"wait":0}}] }
2013-08-28 14:18:55,809 DEBUG [agent.manager.DirectAgentAttache]
(DirectAgent-18:null) Ping from 1
2013-08-28 14:18:56,468 DEBUG [host.dao.HostDaoImpl] (ClusteredAgentManager
Timer:null) Resetting hosts suitable for reconnect
2013-08-28 14:18:56,469 DEBUG [host.dao.HostDaoImpl] (ClusteredAgentManager
Timer:null) Completed resetting hosts suitable for reconnect
2013-08-28 14:18:56,469 DEBUG [host.dao.HostDaoImpl] (ClusteredAgentManager
Timer:null) Acquiring hosts for clusters already owned by this management server
2013-08-28 14:18:56,471 DEBUG [host.dao.HostDaoImpl] (ClusteredAgentManager
Timer:null) Completed acquiring hosts for clusters already owned by this
management server
2013-08-28 14:18:56,471 DEBUG [host.dao.HostDaoImpl] (ClusteredAgentManager
Timer:null) Acquiring hosts for clusters not owned by any management server
2013-08-28 14:18:56,471 DEBUG [host.dao.HostDaoImpl] (ClusteredAgentManager
Timer:null) Completed acquiring hosts for clusters not owned by any management
server
2013-08-28 14:18:56,957 DEBUG [agent.transport.Request]
(AgentManager-Handler-5:null) Seq 6-1711539443: Processing: { Ans: , MgmtId:
280320865129348, via: 6, Ver: v1, Flags: 110,
[{"org.apache.cloudstack.storage.command.CopyCmdAnswer":{"result":false,"details":"copy
volume secondary to primary failed due to exception: Exception:
javax.xml.ws.soap.SOAPFaultException\nMessage: \nRequired parameter spec is
missing\n\nwhile parsing call information for method ImportVApp\nat line 1,
column 110\n\nwhile parsing SOAP body\nat line 1, column 102\n\nwhile parsing
SOAP envelope\nat line 1, column 38\n\nwhile parsing HTTP request for method
importVApp\non object of type vim.ResourcePool\nat line 1, column
0\n","wait":0}}] }
2013-08-28 14:18:56,957 DEBUG [agent.transport.Request]
(Job-Executor-86:job-100 = [ e328cdae-0db7-40d3-9bb2-6fcaacccb013 ]) Seq
6-1711539443: Received: { Ans: , MgmtId: 280320865129348, via: 6, Ver: v1,
Flags: 110, { CopyCmdAnswer } }
2013-08-28 14:18:56,960 DEBUG [agent.manager.AgentAttache]
(AgentManager-Handler-5:null) Seq 6-1711539443: No more commands found
2013-08-28 14:18:56,969 DEBUG [cloud.storage.VolumeManagerImpl]
(Job-Executor-86:job-100 = [ e328cdae-0db7-40d3-9bb2-6fcaacccb013 ]) copy
volume failed: java.lang.NullPointerException
2013-08-28 14:18:56,980 ERROR [cloud.async.AsyncJobManagerImpl]
(Job-Executor-86:job-100 = [ e328cdae-0db7-40d3-9bb2-6fcaacccb013 ]) Unexpected
exception while executing
org.apache.cloudstack.api.command.user.volume.AttachVolumeCmd
com.cloud.utils.exception.CloudRuntimeException: copy volume failed:
java.lang.NullPointerException
at
com.cloud.storage.VolumeManagerImpl.copyVolumeFromSecToPrimary(VolumeManagerImpl.java:634)
at
com.cloud.utils.component.ComponentInstantiationPostProcessor$InterceptorDispatcher.intercept(ComponentInstantiationPostProcessor.java:125)
at
com.cloud.storage.VolumeManagerImpl.copyVolume(VolumeManagerImpl.java:1512)
at
com.cloud.storage.VolumeManagerImpl.createVolumeOnPrimaryStorage(VolumeManagerImpl.java:1543)
at
com.cloud.storage.VolumeManagerImpl.attachVolumeToVM(VolumeManagerImpl.java:1862)
at
com.cloud.utils.component.ComponentInstantiationPostProcessor$InterceptorDispatcher.intercept(ComponentInstantiationPostProcessor.java:125)
at
org.apache.cloudstack.api.command.user.volume.AttachVolumeCmd.execute(AttachVolumeCmd.java:122)
at com.cloud.api.ApiDispatcher.dispatch(ApiDispatcher.java:158)
at
com.cloud.async.AsyncJobManagerImpl$1.run(AsyncJobManagerImpl.java:531)
at
java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:471)
at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:334)
at java.util.concurrent.FutureTask.run(FutureTask.java:166)
at
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1110)
at
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:603)
at java.lang.Thread.run(Thread.java:679)
2013-08-28 14:18:56,983 DEBUG [cloud.async.AsyncJobManagerImpl]
(Job-Executor-86:job-100 = [ e328cdae-0db7-40d3-9bb2-6fcaacccb013 ]) Complete
async job-100 = [ e328cdae-0db7-40d3-9bb2-6fcaacccb013 ], jobStatus: 2,
resultCode: 530, result: Error Code: 530 Error text: copy volume failed:
java.lang.NullPointerException
2013-08-28 14:18:58,364 DEBUG [cloud.api.ApiServlet] (catalina-exec-15:null)
===START=== 10.144.6.17 -- GET
command=queryAsyncJobResult&jobId=e328cdae-0db7-40d3-9bb2-6fcaacccb013&response=json&sessionkey=YLVEsmGR39Coa5VtDBemh9vAf%2BQ%3D&_=1377679942852
2013-08-28 14:18:58,372 DEBUG [cloud.async.AsyncJobManagerImpl]
(catalina-exec-15:null) Async job-100 = [ e328cdae-0db7-40d3-9bb2-6fcaacccb013
] completed
And the uploaded volume is then stuck in the copying state :
mysql> select * from volumes where id=26\G;
*************************** 1. row ***************************
id: 26
account_id: 2
domain_id: 1
pool_id: 201
last_pool_id: NULL
instance_id: NULL
device_id: NULL
name: uloadvol3
uuid: 2be0cac1-cb4a-4f38-842d-538f094f041c
size: 2097152000
folder: NULL
path: NULL
pod_id: NULL
data_center_id: 1
iscsi_name: NULL
host_ip: NULL
volume_type: DATADISK
pool_type: NULL
disk_offering_id: 6
template_id: NULL
iso_id: 0
first_snapshot_backup_uuid: NULL
recreatable: 0
created: 2013-08-28 08:42:48
attached: NULL
updated: 2013-08-28 08:48:55
removed: NULL
state: Copying
chain_info: NULL
update_count: 3
disk_type: NULL
vm_snapshot_chain_size: NULL
display_volume: 0
format: OVA
min_iops: NULL
max_iops: NULL
1 row in set (0.00 sec)
> [upgrade][vmware]Attaching an uploaded volume to a VM throws NPE
> ----------------------------------------------------------------
>
> Key: CLOUDSTACK-4521
> URL: https://issues.apache.org/jira/browse/CLOUDSTACK-4521
> Project: CloudStack
> Issue Type: Bug
> Security Level: Public(Anyone can view this level - this is the
> default.)
> Components: Storage Controller, Upgrade, VMware
> Affects Versions: 4.2.0
> Environment: Host : ESX 5.0
> upgrade from 3.0.6 to 4.2
> Primary storage : iscsi
> secondary storage : nfs
> Reporter: Abhinav Roy
> Assignee: Min Chen
> Priority: Critical
> Fix For: 4.2.1
>
> Attachments: CS-4521.zip, ssvm logs.zip
>
>
> Steps :
> ======================
> 1. Desploy CS 3.0.6 advanced zone setup with esx 5.0 host, iscsi primary
> storage and nfs secondary storage
> 2. Create vms, snapshots, templates etc.
> 3. Upgrade to 4.2
> 4. Create a VM , ex- V-11
> 5. Upload a volume, ex- UV-11
> 6. Attach UV-11 to V-11
> Expected behaviour:
> ======================
> Attach volume should succeed
> Observed behaviour:
> ======================
> Attaching of an uploaded volume fails with :
> 2013-08-27 20:54:03,613 DEBUG [cloud.api.ApiServlet] (catalina-exec-17:null)
> ===START=== 10.144.6.17 -- GET
> command=attachVolume&id=ba0da87e-01f4-4a9d-84a4-4186e161021d&virtualMachineId=6924cda3-88b0-46c0-b826-1af3fb17fa32&response=json&sessionkey=VTc5%2FD4MTl6bTX%2B7GHiy1wakRF4%3D&_=1377617247397
> 2013-08-27 20:54:03,644 DEBUG [cloud.async.AsyncJobManagerImpl]
> (catalina-exec-17:null) submit async job-81 = [
> 9e4fa921-e187-4b06-859f-d9a03b4b6145 ], details: AsyncJobVO {id:81, userId:
> 2, accountId: 2, sessionKey: null, instanceType: Volume, instanceId: 21, cmd:
> org.apache.cloudstack.api.command.user.volume.AttachVolumeCmd, cmdOriginator:
> null, cmdInfo:
> {"response":"json","id":"ba0da87e-01f4-4a9d-84a4-4186e161021d","sessionkey":"VTc5/D4MTl6bTX+7GHiy1wakRF4\u003d","cmdEventType":"VOLUME.ATTACH","ctxUserId":"2","virtualMachineId":"6924cda3-88b0-46c0-b826-1af3fb17fa32","httpmethod":"GET","_":"1377617247397","ctxAccountId":"2","ctxStartEventId":"243"},
> cmdVersion: 0, callbackType: 0, callbackAddress: null, status: 0,
> processStatus: 0, resultCode: 0, result: null, initMsid: 280320865129348,
> completeMsid: null, lastUpdated: null, lastPolled: null, created: null}
> 2013-08-27 20:54:03,646 DEBUG [cloud.api.ApiServlet] (catalina-exec-17:null)
> ===END=== 10.144.6.17 -- GET
> command=attachVolume&id=ba0da87e-01f4-4a9d-84a4-4186e161021d&virtualMachineId=6924cda3-88b0-46c0-b826-1af3fb17fa32&response=json&sessionkey=VTc5%2FD4MTl6bTX%2B7GHiy1wakRF4%3D&_=1377617247397
> 2013-08-27 20:54:03,648 DEBUG [cloud.async.AsyncJobManagerImpl]
> (Job-Executor-67:job-81 = [ 9e4fa921-e187-4b06-859f-d9a03b4b6145 ]) Executing
> org.apache.cloudstack.api.command.user.volume.AttachVolumeCmd for job-81 = [
> 9e4fa921-e187-4b06-859f-d9a03b4b6145 ]
> 2013-08-27 20:54:03,680 DEBUG [storage.allocator.LocalStoragePoolAllocator]
> (Job-Executor-67:job-81 = [ 9e4fa921-e187-4b06-859f-d9a03b4b6145 ])
> LocalStoragePoolAllocator trying to find storage pool to fit the vm
> 2013-08-27 20:54:03,681 DEBUG
> [storage.allocator.ClusterScopeStoragePoolAllocator] (Job-Executor-67:job-81
> = [ 9e4fa921-e187-4b06-859f-d9a03b4b6145 ]) ClusterScopeStoragePoolAllocator
> looking for storage pool
> 2013-08-27 20:54:03,681 DEBUG
> [storage.allocator.ClusterScopeStoragePoolAllocator] (Job-Executor-67:job-81
> = [ 9e4fa921-e187-4b06-859f-d9a03b4b6145 ]) Looking for pools in dc: 1 pod:1
> cluster:1
> 2013-08-27 20:54:03,685 DEBUG
> [storage.allocator.AbstractStoragePoolAllocator] (Job-Executor-67:job-81 = [
> 9e4fa921-e187-4b06-859f-d9a03b4b6145 ]) Checking if storage pool is suitable,
> name: null ,poolId: 201
> 2013-08-27 20:54:03,688 DEBUG [cloud.storage.StorageManagerImpl]
> (Job-Executor-67:job-81 = [ 9e4fa921-e187-4b06-859f-d9a03b4b6145 ]) Checking
> pool 201 for storage, totalSize: 107105746944, usedBytes: 12640583680,
> usedPct: 0.11801965852130326, disable threshold: 0.85
> 2013-08-27 20:54:03,695 DEBUG [cloud.storage.StorageManagerImpl]
> (Job-Executor-67:job-81 = [ 9e4fa921-e187-4b06-859f-d9a03b4b6145 ]) Checking
> pool: 201 for volume allocation [Vol[21|vm=null|DATADISK]], maxSize :
> 107105746944, totalAllocatedSize : 14831058944, askingSize : 0, allocated
> disable threshold: 0.85
> 2013-08-27 20:54:03,696 DEBUG
> [storage.allocator.ClusterScopeStoragePoolAllocator] (Job-Executor-67:job-81
> = [ 9e4fa921-e187-4b06-859f-d9a03b4b6145 ]) FirstFitStoragePoolAllocator
> returning 1 suitable storage pools
> 2013-08-27 20:54:03,733 DEBUG [storage.motion.AncientDataMotionStrategy]
> (Job-Executor-67:job-81 = [ 9e4fa921-e187-4b06-859f-d9a03b4b6145 ]) copyAsync
> inspecting src type VOLUME copyAsync inspecting dest type VOLUME
> 2013-08-27 20:54:03,757 DEBUG [agent.transport.Request]
> (Job-Executor-67:job-81 = [ 9e4fa921-e187-4b06-859f-d9a03b4b6145 ]) Seq
> 6-1711538341: Sending { Cmd , MgmtId: 280320865129348, via: 6, Ver: v1,
> Flags: 100111,
> [{"org.apache.cloudstack.storage.command.CopyCommand":{"srcTO":{"org.apache.cloudstack.storage.to.VolumeObjectTO":{"uuid":"ba0da87e-01f4-4a9d-84a4-4186e161021d","volumeType":"DATADISK","dataStore":{"com.cloud.agent.api.to.NfsTO":{"_url":"nfs://10.102.192.100/cpg_vol/abhinav/esx-sec-burbank","_role":"Image"}},"name":"uploadvolume","size":0,"path":"volumes/2/21/17605744-8b1e-361a-8f2c-fe61b88a962f.ova","volumeId":21,"accountId":2,"format":"OVA","id":21,"hypervisorType":"VMware"}},"destTO":{"org.apache.cloudstack.storage.to.VolumeObjectTO":{"uuid":"ba0da87e-01f4-4a9d-84a4-4186e161021d","volumeType":"DATADISK","dataStore":{"org.apache.cloudstack.storage.to.PrimaryDataStoreTO":{"uuid":"07580f3c-3c3d-3ac3-aec6-b36270b8fbc2","id":201,"poolType":"VMFS","host":"VMFS
> datastore:
> /roydc/primary-iscsi1","path":"/roydc/primary-iscsi1","port":0}},"name":"uploadvolume","size":0,"volumeId":21,"accountId":2,"format":"OVA","id":21,"hypervisorType":"VMware"}},"executeInSequence":true,"wait":10800}}]
> }
> 2013-08-27 20:54:04,006 DEBUG [agent.manager.AgentManagerImpl]
> (AgentManager-Handler-7:null) SeqA 4-50833: Processing Seq 4-50833: { Cmd ,
> MgmtId: -1, via: 4, Ver: v1, Flags: 11,
> [{"com.cloud.agent.api.ConsoleProxyLoadReportCommand":{"_proxyVmId":2,"_loadInfo":"{\n
> \"connections\": []\n}","wait":0}}] }
> 2013-08-27 20:54:04,009 DEBUG [agent.manager.AgentManagerImpl]
> (AgentManager-Handler-7:null) SeqA 4-50833: Sending Seq 4-50833: { Ans: ,
> MgmtId: 280320865129348, via: 4, Ver: v1, Flags: 100010,
> [{"com.cloud.agent.api.AgentControlAnswer":{"result":true,"wait":0}}] }
> 2013-08-27 20:54:04,023 DEBUG [agent.transport.Request]
> (AgentManager-Handler-5:null) Seq 6-1711538341: Processing: { Ans: , MgmtId:
> 280320865129348, via: 6, Ver: v1, Flags: 110,
> [{"org.apache.cloudstack.storage.command.CopyCmdAnswer":{"result":false,"details":"copy
> volume secondary to primary failed due to exception: Exception:
> java.lang.Exception\nMessage: Unable to unpack snapshot OVA file at:
> /mnt/SecStorage/17fd8ff0-6b93-3aa5-9e15-b90da1a52e44/volumes/2/21/17605744-8b1e-361a-8f2c-fe61b88a962f.ova\n","wait":0}}]
> }
> 2013-08-27 20:54:04,024 DEBUG [agent.transport.Request]
> (Job-Executor-67:job-81 = [ 9e4fa921-e187-4b06-859f-d9a03b4b6145 ]) Seq
> 6-1711538341: Received: { Ans: , MgmtId: 280320865129348, via: 6, Ver: v1,
> Flags: 110, { CopyCmdAnswer } }
> 2013-08-27 20:54:04,025 DEBUG [agent.manager.AgentAttache]
> (AgentManager-Handler-5:null) Seq 6-1711538341: No more commands found
> 2013-08-27 20:54:04,030 DEBUG [cloud.storage.VolumeManagerImpl]
> (Job-Executor-67:job-81 = [ 9e4fa921-e187-4b06-859f-d9a03b4b6145 ]) copy
> volume failed: java.lang.NullPointerException
> 2013-08-27 20:54:04,034 ERROR [cloud.async.AsyncJobManagerImpl]
> (Job-Executor-67:job-81 = [ 9e4fa921-e187-4b06-859f-d9a03b4b6145 ])
> Unexpected exception while executing
> org.apache.cloudstack.api.command.user.volume.AttachVolumeCmd
> com.cloud.utils.exception.CloudRuntimeException: copy volume failed:
> java.lang.NullPointerException
> at
> com.cloud.storage.VolumeManagerImpl.copyVolumeFromSecToPrimary(VolumeManagerImpl.java:634)
> at
> com.cloud.utils.component.ComponentInstantiationPostProcessor$InterceptorDispatcher.intercept(ComponentInstantiationPostProcessor.java:125)
> at
> com.cloud.storage.VolumeManagerImpl.copyVolume(VolumeManagerImpl.java:1512)
> at
> com.cloud.storage.VolumeManagerImpl.createVolumeOnPrimaryStorage(VolumeManagerImpl.java:1543)
> at
> com.cloud.storage.VolumeManagerImpl.attachVolumeToVM(VolumeManagerImpl.java:1862)
> at
> com.cloud.utils.component.ComponentInstantiationPostProcessor$InterceptorDispatcher.intercept(ComponentInstantiationPostProcessor.java:125)
> at
> org.apache.cloudstack.api.command.user.volume.AttachVolumeCmd.execute(AttachVolumeCmd.java:122)
> at com.cloud.api.ApiDispatcher.dispatch(ApiDispatcher.java:158)
> at
> com.cloud.async.AsyncJobManagerImpl$1.run(AsyncJobManagerImpl.java:531)
> at
> java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:471)
> at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:334)
> at java.util.concurrent.FutureTask.run(FutureTask.java:166)
> at
> java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1110)
> at
> java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:603)
> at java.lang.Thread.run(Thread.java:679)
> 2013-08-27 20:54:04,038 DEBUG [cloud.async.AsyncJobManagerImpl]
> (Job-Executor-67:job-81 = [ 9e4fa921-e187-4b06-859f-d9a03b4b6145 ]) Complete
> async job-81 = [ 9e4fa921-e187-4b06-859f-d9a03b4b6145 ], jobStatus: 2,
> resultCode: 530, result: Error Code: 530 Error text: copy volume failed:
> java.lang.NullPointerException
> This behaviour is specific to uploaded volumes.
--
This message is automatically generated by JIRA.
If you think it was sent incorrectly, please contact your JIRA administrators
For more information on JIRA, see: http://www.atlassian.com/software/jira
