Return-Path: X-Original-To: apmail-cloudstack-issues-archive@www.apache.org Delivered-To: apmail-cloudstack-issues-archive@www.apache.org Received: from mail.apache.org (hermes.apache.org [140.211.11.3]) by minotaur.apache.org (Postfix) with SMTP id E36A8C5A7 for ; Wed, 26 Nov 2014 11:07:13 +0000 (UTC) Received: (qmail 18883 invoked by uid 500); 26 Nov 2014 11:07:13 -0000 Delivered-To: apmail-cloudstack-issues-archive@cloudstack.apache.org Received: (qmail 18856 invoked by uid 500); 26 Nov 2014 11:07:13 -0000 Mailing-List: contact issues-help@cloudstack.apache.org; run by ezmlm Precedence: bulk List-Help: List-Unsubscribe: List-Post: List-Id: Reply-To: dev@cloudstack.apache.org Delivered-To: mailing list issues@cloudstack.apache.org Received: (qmail 18847 invoked by uid 500); 26 Nov 2014 11:07:13 -0000 Delivered-To: apmail-incubator-cloudstack-issues@incubator.apache.org Received: (qmail 18844 invoked by uid 99); 26 Nov 2014 11:07:13 -0000 Received: from arcas.apache.org (HELO arcas.apache.org) (140.211.11.28) by apache.org (qpsmtpd/0.29) with ESMTP; Wed, 26 Nov 2014 11:07:13 +0000 Date: Wed, 26 Nov 2014 11:07:13 +0000 (UTC) From: "ASF subversion and git services (JIRA)" To: cloudstack-issues@incubator.apache.org Message-ID: In-Reply-To: References: Subject: [jira] [Commented] (CLOUDSTACK-7960) [Automation] Creation of Volume from Snapshot fails due to StringIndexOutOfBoundsException MIME-Version: 1.0 Content-Type: text/plain; charset=utf-8 Content-Transfer-Encoding: quoted-printable X-JIRA-FingerPrint: 30527f35849b9dde25b450d4833f0394 [ https://issues.apache.org/jira/browse/CLOUDSTACK-7960?page=3Dcom.atla= ssian.jira.plugin.system.issuetabpanels:comment-tabpanel&focusedCommentId= =3D14226034#comment-14226034 ]=20 ASF subversion and git services commented on CLOUDSTACK-7960: ------------------------------------------------------------- Commit 3fc392abf8a17d823d76805782d3ee01b54b5210 in cloudstack's branch refs= /heads/4.5 from [~koushikd] [ https://git-wip-us.apache.org/repos/asf?p=3Dcloudstack.git;h=3D3fc392a ] CLOUDSTACK-7960: [Automation] Creation of Volume from Snapshot fails due to= StringIndexOutOfBoundsException Fixed the appropriate CopyCommand handler in simulator plugin > [Automation] Creation of Volume from Snapshot fails due to StringIndexOut= OfBoundsException > -------------------------------------------------------------------------= ----------------- > > Key: CLOUDSTACK-7960 > URL: https://issues.apache.org/jira/browse/CLOUDSTACK-796= 0 > Project: CloudStack > Issue Type: Bug > Security Level: Public(Anyone can view this level - this is the defa= ult.)=20 > Components: Automation > Affects Versions: 4.5.0 > Reporter: Chandan Purushothama > Assignee: Koushik Das > Priority: Critical > Fix For: 4.5.0 > > > Use Case: > Validate the following sequence > # Deploy VM with custom disk offering and check the primary stor= age resource count > # Stop the VM and create Snapshot from VM's volume > # Create volume again from this snapshot > StringIndexOutOfBoundsException: > {noformat} > 2014-11-21 01:04:06,153 DEBUG [c.c.a.ApiServlet] (catalina-exec-22:ctx-4e= 287cc2 ctx-e68b28dc ctx-a00dd9d4) =3D=3D=3DEND=3D=3D=3D 10.220.135.118 -- = GET account=3Dtest-a-TestVolumeLimits-test_create_template_snapshot_1_root= _domain_admin-9B6G87&domainid=3D58f506ce-7108-11e4-bca3-7640e6bc0920&name= =3DTest+Volume-DGE7AV&zoneid=3D7934f921-50e4-406b-b0dd-8c474907d8cf&apiKey= =3DrRJabsdLiEc7Wnp7V6yxoQWckT_5EBLxkm5Bz5JFImYh-CqbkPW8fL87x8Qp2QDJXAobZscf= qwwaW9jm3mXUzg&command=3DcreateVolume&signature=3DKb4x7pXEbm2yfoNmKAVZ92bFu= 1Y%3D&snapshotid=3Dcd663163-8045-43d1-9a88-899d0c1e9182&response=3Djson&siz= e=3D2 > 2014-11-21 01:04:06,158 DEBUG [c.c.a.ApiServlet] (catalina-exec-5:ctx-662= f483b) =3D=3D=3DSTART=3D=3D=3D 10.220.135.118 -- GET jobid=3D5b446f9d-205= 6-43f2-9057-2d16b40e926f&apiKey=3DrRJabsdLiEc7Wnp7V6yxoQWckT_5EBLxkm5Bz5JFI= mYh-CqbkPW8fL87x8Qp2QDJXAobZscfqwwaW9jm3mXUzg&command=3DqueryAsyncJobResult= &response=3Djson&signature=3Ddtw5FWz4%2FrigWTMJHLTB7vMCKS0%3D > 2014-11-21 01:04:06,175 DEBUG [c.c.a.ApiServlet] (catalina-exec-5:ctx-662= f483b ctx-ad395747 ctx-b176f4c5) =3D=3D=3DEND=3D=3D=3D 10.220.135.118 -- G= ET jobid=3D5b446f9d-2056-43f2-9057-2d16b40e926f&apiKey=3DrRJabsdLiEc7Wnp7V= 6yxoQWckT_5EBLxkm5Bz5JFImYh-CqbkPW8fL87x8Qp2QDJXAobZscfqwwaW9jm3mXUzg&comma= nd=3DqueryAsyncJobResult&response=3Djson&signature=3Ddtw5FWz4%2FrigWTMJHLTB= 7vMCKS0%3D > 2014-11-21 01:04:06,185 DEBUG [o.a.c.s.a.LocalStoragePoolAllocator] (API-= Job-Executor-101:ctx-c66892e0 job-4276 ctx-db279945) LocalStoragePoolAlloca= tor trying to find storage pool to fit the vm > 2014-11-21 01:04:06,185 DEBUG [o.a.c.s.a.ClusterScopeStoragePoolAllocator= ] (API-Job-Executor-101:ctx-c66892e0 job-4276 ctx-db279945) ClusterScopeSto= ragePoolAllocator looking for storage pool > 2014-11-21 01:04:06,185 DEBUG [o.a.c.s.a.ClusterScopeStoragePoolAllocator= ] (API-Job-Executor-101:ctx-c66892e0 job-4276 ctx-db279945) Looking for poo= ls in dc: 1 pod:1 cluster:null > 2014-11-21 01:04:06,187 DEBUG [o.a.c.s.a.ClusterScopeStoragePoolAllocator= ] (API-Job-Executor-101:ctx-c66892e0 job-4276 ctx-db279945) Found pools mat= ching tags: [Pool[1|NetworkFilesystem], Pool[2|NetworkFilesystem], Pool[3|N= etworkFilesystem]] > 2014-11-21 01:04:06,189 DEBUG [o.a.c.s.a.AbstractStoragePoolAllocator] (A= PI-Job-Executor-101:ctx-c66892e0 job-4276 ctx-db279945) Checking if storage= pool is suitable, name: null ,poolId: 1 > 2014-11-21 01:04:06,191 INFO [c.c.s.StorageManagerImpl] (API-Job-Executo= r-101:ctx-c66892e0 job-4276 ctx-db279945) Storage pool null (1) does not su= pply IOPS capacity, assuming enough capacity > 2014-11-21 01:04:06,193 DEBUG [c.c.s.StorageManagerImpl] (API-Job-Executo= r-101:ctx-c66892e0 job-4276 ctx-db279945) Checking pool 1 for storage, tota= lSize: 1099511627776, usedBytes: 0, usedPct: 0.0, disable threshold: 0.85 > 2014-11-21 01:04:06,197 DEBUG [c.c.s.StorageManagerImpl] (API-Job-Executo= r-101:ctx-c66892e0 job-4276 ctx-db279945) Checking pool: 1 for volume alloc= ation [Vol[764|vm=3Dnull|DATADISK]], maxSize : 2199023255552, totalAllocate= dSize : 200, askingSize : 2147483648, allocated disable threshold: 0.85 > 2014-11-21 01:04:06,197 DEBUG [o.a.c.s.a.ClusterScopeStoragePoolAllocator= ] (API-Job-Executor-101:ctx-c66892e0 job-4276 ctx-db279945) ClusterScopeSto= ragePoolAllocator returning 1 suitable storage pools > 2014-11-21 01:04:06,198 DEBUG [o.a.c.e.o.VolumeOrchestrator] (API-Job-Exe= cutor-101:ctx-c66892e0 job-4276 ctx-db279945) Found a suitable pool for cre= ate volume: 1 > 2014-11-21 01:04:06,214 DEBUG [o.a.c.s.m.AncientDataMotionStrategy] (API-= Job-Executor-101:ctx-c66892e0 job-4276 ctx-db279945) copyAsync inspecting s= rc type SNAPSHOT copyAsync inspecting dest type VOLUME > 2014-11-21 01:04:06,225 DEBUG [c.c.a.t.Request] (API-Job-Executor-101:ctx= -c66892e0 job-4276 ctx-db279945) Seq 2-8004303912720929589: Sending { Cmd = , MgmtId: 130021121067296, via: 2(SimulatedAgent.69573596-c121-47ea-8e5e-53= deda420c30), Ver: v1, Flags: 100111, [{"org.apache.cloudstack.storage.comma= nd.CopyCommand":{"srcTO":{"org.apache.cloudstack.storage.to.SnapshotObjectT= O":{"path":"null/f6a7021b-d987-4797-9aea-ec8e50431aec","volume":{"uuid":"81= 3b2038-1df8-424c-b0d8-e814794fd90b","volumeType":"DATADISK","dataStore":{"o= rg.apache.cloudstack.storage.to.PrimaryDataStoreTO":{"uuid":"0bbd54fe-b348-= 3e3d-9b91-b453a21bc43a","id":3,"poolType":"NetworkFilesystem","host":"10.14= 7.28.6","path":"/export/home/sandbox/primary2","port":2049,"url":"NetworkFi= lesystem://10.147.28.6/export/home/sandbox/primary2/?ROLE=3DPrimary&STOREUU= ID=3D0bbd54fe-b348-3e3d-9b91-b453a21bc43a"}},"name":"DATA-725","size":21474= 83648,"path":"DATA-725","volumeId":762,"vmName":"i-526-725-QA","accountId":= 526,"provisioningType":"THIN","id":762,"deviceId":1,"hypervisorType":"Simul= ator"},"dataStore":{"com.cloud.agent.api.to.NfsTO":{"_url":"nfs://10.147.28= .6:/export/home/sandbox/secondary","_role":"Image"}},"vmName":"i-526-725-QA= ","name":"QA-20c5fed9-7d23-44f0-b5c7-fa52309451e9_DATA-725_20141121010400",= "hypervisorType":"Simulator","id":19,"quiescevm":false,"physicalSize":0}},"= destTO":{"org.apache.cloudstack.storage.to.VolumeObjectTO":{"uuid":"a306c42= a-359d-4343-892d-db019efbad94","volumeType":"DATADISK","dataStore":{"org.ap= ache.cloudstack.storage.to.PrimaryDataStoreTO":{"uuid":"7c07ec9b-a3c6-3466-= ab5a-f5669ead0b22","id":1,"poolType":"NetworkFilesystem","host":"10.147.28.= 6","path":"/export/home/sandbox/primary0","port":2049,"url":"NetworkFilesys= tem://10.147.28.6/export/home/sandbox/primary0/?ROLE=3DPrimary&STOREUUID=3D= 7c07ec9b-a3c6-3466-ab5a-f5669ead0b22"}},"name":"Test Volume-DGE7AV","size":= 2147483648,"volumeId":764,"accountId":526,"provisioningType":"THIN","id":76= 4,"hypervisorType":"Simulator"}},"executeInSequence":true,"options":{},"wai= t":10800}}] } > 2014-11-21 01:04:06,225 DEBUG [c.c.a.t.Request] (API-Job-Executor-101:ctx= -c66892e0 job-4276 ctx-db279945) Seq 2-8004303912720929589: Executing: { C= md , MgmtId: 130021121067296, via: 2(SimulatedAgent.69573596-c121-47ea-8e5e= -53deda420c30), Ver: v1, Flags: 100111, [{"org.apache.cloudstack.storage.co= mmand.CopyCommand":{"srcTO":{"org.apache.cloudstack.storage.to.SnapshotObje= ctTO":{"path":"null/f6a7021b-d987-4797-9aea-ec8e50431aec","volume":{"uuid":= "813b2038-1df8-424c-b0d8-e814794fd90b","volumeType":"DATADISK","dataStore":= {"org.apache.cloudstack.storage.to.PrimaryDataStoreTO":{"uuid":"0bbd54fe-b3= 48-3e3d-9b91-b453a21bc43a","id":3,"poolType":"NetworkFilesystem","host":"10= .147.28.6","path":"/export/home/sandbox/primary2","port":2049,"url":"Networ= kFilesystem://10.147.28.6/export/home/sandbox/primary2/?ROLE=3DPrimary&STOR= EUUID=3D0bbd54fe-b348-3e3d-9b91-b453a21bc43a"}},"name":"DATA-725","size":21= 47483648,"path":"DATA-725","volumeId":762,"vmName":"i-526-725-QA","accountI= d":526,"provisioningType":"THIN","id":762,"deviceId":1,"hypervisorType":"Si= mulator"},"dataStore":{"com.cloud.agent.api.to.NfsTO":{"_url":"nfs://10.147= .28.6:/export/home/sandbox/secondary","_role":"Image"}},"vmName":"i-526-725= -QA","name":"QA-20c5fed9-7d23-44f0-b5c7-fa52309451e9_DATA-725_2014112101040= 0","hypervisorType":"Simulator","id":19,"quiescevm":false,"physicalSize":0}= },"destTO":{"org.apache.cloudstack.storage.to.VolumeObjectTO":{"uuid":"a306= c42a-359d-4343-892d-db019efbad94","volumeType":"DATADISK","dataStore":{"org= .apache.cloudstack.storage.to.PrimaryDataStoreTO":{"uuid":"7c07ec9b-a3c6-34= 66-ab5a-f5669ead0b22","id":1,"poolType":"NetworkFilesystem","host":"10.147.= 28.6","path":"/export/home/sandbox/primary0","port":2049,"url":"NetworkFile= system://10.147.28.6/export/home/sandbox/primary0/?ROLE=3DPrimary&STOREUUID= =3D7c07ec9b-a3c6-3466-ab5a-f5669ead0b22"}},"name":"Test Volume-DGE7AV","siz= e":2147483648,"volumeId":764,"accountId":526,"provisioningType":"THIN","id"= :764,"hypervisorType":"Simulator"}},"executeInSequence":true,"options":{},"= wait":10800}}] } > 2014-11-21 01:04:06,225 DEBUG [c.c.a.m.DirectAgentAttache] (DirectAgent-6= 6:ctx-080426f7) Seq 2-8004303912720929589: Executing request > 2014-11-21 01:04:06,238 ERROR [c.c.a.m.SimulatorManagerImpl] (DirectAgent= -66:ctx-080426f7) Failed execute cmd:=20 > java.lang.StringIndexOutOfBoundsException: String index out of range: -1 > =09at java.lang.String.substring(String.java:1958) > =09at java.lang.String.substring(String.java:1925) > =09at com.cloud.resource.SimulatorStorageProcessor.createVolumeFromSnapsh= ot(SimulatorStorageProcessor.java:213) > =09at com.cloud.storage.resource.StorageSubsystemCommandHandlerBase.execu= te(StorageSubsystemCommandHandlerBase.java:97) > =09at com.cloud.storage.resource.StorageSubsystemCommandHandlerBase.handl= eStorageCommands(StorageSubsystemCommandHandlerBase.java:53) > =09at com.cloud.agent.manager.SimulatorManagerImpl.simulate(SimulatorMana= gerImpl.java:421) > =09at com.cloud.resource.AgentRoutingResource.executeRequestInContext(Age= ntRoutingResource.java:98) > =09at com.cloud.resource.AgentResourceBase$1.runInContext(AgentResourceBa= se.java:254) > =09at org.apache.cloudstack.managed.context.ManagedContextRunnable$1.run(= ManagedContextRunnable.java:49) > =09at org.apache.cloudstack.managed.context.impl.DefaultManagedContext$1.= call(DefaultManagedContext.java:56) > =09at org.apache.cloudstack.managed.context.impl.DefaultManagedContext.ca= llWithContext(DefaultManagedContext.java:103) > =09at org.apache.cloudstack.managed.context.impl.DefaultManagedContext.ru= nWithContext(DefaultManagedContext.java:53) > =09at org.apache.cloudstack.managed.context.ManagedContextRunnable.run(Ma= nagedContextRunnable.java:46) > =09at com.cloud.resource.AgentResourceBase.executeRequest(AgentResourceBa= se.java:251) > =09at com.cloud.agent.manager.DirectAgentAttache$Task.runInContext(Direct= AgentAttache.java:304) > =09at org.apache.cloudstack.managed.context.ManagedContextRunnable$1.run(= ManagedContextRunnable.java:49) > =09at org.apache.cloudstack.managed.context.impl.DefaultManagedContext$1.= call(DefaultManagedContext.java:56) > =09at org.apache.cloudstack.managed.context.impl.DefaultManagedContext.ca= llWithContext(DefaultManagedContext.java:103) > =09at org.apache.cloudstack.managed.context.impl.DefaultManagedContext.ru= nWithContext(DefaultManagedContext.java:53) > =09at org.apache.cloudstack.managed.context.ManagedContextRunnable.run(Ma= nagedContextRunnable.java:46) > =09at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:= 471) > =09at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:334) > =09at java.util.concurrent.FutureTask.run(FutureTask.java:166) > =09at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTas= k.access$201(ScheduledThreadPoolExecutor.java:178) > =09at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTas= k.run(ScheduledThreadPoolExecutor.java:292) > =09at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecuto= r.java:1110) > =09at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecut= or.java:603) > =09at java.lang.Thread.run(Thread.java:722) > 2014-11-21 01:04:06,239 DEBUG [c.c.a.m.DirectAgentAttache] (DirectAgent-6= 6:ctx-080426f7) Seq 2-8004303912720929589: Response Received:=20 > 2014-11-21 01:04:06,239 DEBUG [c.c.a.t.Request] (DirectAgent-66:ctx-08042= 6f7) Seq 2-8004303912720929589: Processing: { Ans: , MgmtId: 1300211210672= 96, via: 2, Ver: v1, Flags: 110, [{"com.cloud.agent.api.Answer":{"result":f= alse,"details":"java.lang.StringIndexOutOfBoundsException: String index out= of range: -1","wait":0}}] } > 2014-11-21 01:04:06,239 DEBUG [c.c.a.m.AgentAttache] (DirectAgent-66:ctx-= 080426f7) Seq 2-8004303912720929589: No more commands found > 2014-11-21 01:04:06,239 DEBUG [c.c.a.t.Request] (API-Job-Executor-101:ctx= -c66892e0 job-4276 ctx-db279945) Seq 2-8004303912720929589: Received: { An= s: , MgmtId: 130021121067296, via: 2, Ver: v1, Flags: 110, { Answer } } > 2014-11-21 01:04:06,244 WARN [o.a.c.s.d.ObjectInDataStoreManagerImpl] (A= PI-Job-Executor-101:ctx-c66892e0 job-4276 ctx-db279945) Unsupported data ob= ject (VOLUME, org.apache.cloudstack.storage.datastore.PrimaryDataStoreImpl@= 1f6c7aa5), no need to delete from object in store ref table > 2014-11-21 01:04:06,248 WARN [o.a.c.s.d.ObjectInDataStoreManagerImpl] (A= PI-Job-Executor-101:ctx-c66892e0 job-4276 ctx-db279945) Snapshot 19 is not = found on image store 1, so no need to delete > 2014-11-21 01:04:06,248 DEBUG [o.a.c.e.o.VolumeOrchestrator] (API-Job-Exe= cutor-101:ctx-c66892e0 job-4276 ctx-db279945) Failed to create volume from = snapshot:java.lang.StringIndexOutOfBoundsException: String index out of ran= ge: -1 > 2014-11-21 01:04:06,262 DEBUG [c.c.u.AccountManagerImpl] (API-Job-Executo= r-101:ctx-c66892e0 job-4276 ctx-db279945) Access granted to Acct[7f78b85e-7= 108-11e4-bca3-7640e6bc0920-admin] to Domain:1/ by AffinityGroupAccessChecke= r > 2014-11-21 01:04:06,273 ERROR [c.c.a.ApiAsyncJobDispatcher] (API-Job-Exec= utor-101:ctx-c66892e0 job-4276) Unexpected exception while executing org.ap= ache.cloudstack.api.command.admin.volume.CreateVolumeCmdByAdmin > com.cloud.utils.exception.CloudRuntimeException: Failed to create volume:= 764 > =09at com.cloud.storage.VolumeApiServiceImpl.createVolume(VolumeApiServic= eImpl.java:671) > =09at com.cloud.storage.VolumeApiServiceImpl.createVolume(VolumeApiServic= eImpl.java:157) > =09at sun.reflect.GeneratedMethodAccessor841.invoke(Unknown Source) > =09at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAcc= essorImpl.java:43) > =09at java.lang.reflect.Method.invoke(Method.java:601) > =09at org.springframework.aop.support.AopUtils.invokeJoinpointUsingReflec= tion(AopUtils.java:317) > =09at org.springframework.aop.framework.ReflectiveMethodInvocation.invoke= Joinpoint(ReflectiveMethodInvocation.java:183) > =09at org.springframework.aop.framework.ReflectiveMethodInvocation.procee= d(ReflectiveMethodInvocation.java:150) > =09at org.apache.cloudstack.network.contrail.management.EventUtils$EventI= nterceptor.invoke(EventUtils.java:106) > =09at org.springframework.aop.framework.ReflectiveMethodInvocation.procee= d(ReflectiveMethodInvocation.java:161) > =09at com.cloud.event.ActionEventInterceptor.invoke(ActionEventIntercepto= r.java:51) > =09at org.springframework.aop.framework.ReflectiveMethodInvocation.procee= d(ReflectiveMethodInvocation.java:161) > =09at org.springframework.aop.interceptor.ExposeInvocationInterceptor.inv= oke(ExposeInvocationInterceptor.java:91) > =09at org.springframework.aop.framework.ReflectiveMethodInvocation.procee= d(ReflectiveMethodInvocation.java:172) > =09at org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDyna= micAopProxy.java:204) > =09at $Proxy185.createVolume(Unknown Source) > =09at org.apache.cloudstack.api.command.admin.volume.CreateVolumeCmdByAdm= in.execute(CreateVolumeCmdByAdmin.java:42) > =09at com.cloud.api.ApiDispatcher.dispatch(ApiDispatcher.java:141) > =09at com.cloud.api.ApiAsyncJobDispatcher.runJob(ApiAsyncJobDispatcher.ja= va:108) > =09at org.apache.cloudstack.framework.jobs.impl.AsyncJobManagerImpl$5.run= InContext(AsyncJobManagerImpl.java:536) > =09at org.apache.cloudstack.managed.context.ManagedContextRunnable$1.run(= ManagedContextRunnable.java:49) > =09at org.apache.cloudstack.managed.context.impl.DefaultManagedContext$1.= call(DefaultManagedContext.java:56) > =09at org.apache.cloudstack.managed.context.impl.DefaultManagedContext.ca= llWithContext(DefaultManagedContext.java:103) > =09at org.apache.cloudstack.managed.context.impl.DefaultManagedContext.ru= nWithContext(DefaultManagedContext.java:53) > =09at org.apache.cloudstack.managed.context.ManagedContextRunnable.run(Ma= nagedContextRunnable.java:46) > =09at org.apache.cloudstack.framework.jobs.impl.AsyncJobManagerImpl$5.run= (AsyncJobManagerImpl.java:493) > =09at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:= 471) > =09at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:334) > =09at java.util.concurrent.FutureTask.run(FutureTask.java:166) > =09at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecuto= r.java:1110) > =09at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecut= or.java:603) > =09at java.lang.Thread.run(Thread.java:722) > Caused by: com.cloud.utils.exception.CloudRuntimeException: Failed to cre= ate volume from snapshot:java.lang.StringIndexOutOfBoundsException: String = index out of range: -1 > =09at org.apache.cloudstack.engine.orchestration.VolumeOrchestrator.creat= eVolumeFromSnapshot(VolumeOrchestrator.java:386) > =09at com.cloud.storage.VolumeApiServiceImpl.createVolumeFromSnapshot(Vol= umeApiServiceImpl.java:693) > =09at com.cloud.storage.VolumeApiServiceImpl.createVolume(VolumeApiServic= eImpl.java:643) > =09... 31 more > 2014-11-21 01:04:06,275 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl] (API-Job-= Executor-101:ctx-c66892e0 job-4276) Complete async job-4276, jobStatus: FAI= LED, resultCode: 530, result: org.apache.cloudstack.api.response.ExceptionR= esponse/null/{"uuidList":[],"errorcode":530,"errortext":"Failed to create v= olume: 764"} > 2014-11-21 01:04:06,276 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl] (API-Job-= Executor-101:ctx-c66892e0 job-4276) Publish async job-4276 complete on mess= age bus > 2014-11-21 01:04:06,276 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl] (API-Job-= Executor-101:ctx-c66892e0 job-4276) Wake up jobs related to job-4276 > 2014-11-21 01:04:06,276 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl] (API-Job-= Executor-101:ctx-c66892e0 job-4276) Update db status for job-4276 > 2014-11-21 01:04:06,277 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl] (API-Job-= Executor-101:ctx-c66892e0 job-4276) Wake up jobs joined with job-4276 and d= isjoin all subjobs created from job- 4276 > 2014-11-21 01:04:06,279 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl] (API-Job-= Executor-101:ctx-c66892e0 job-4276) Done executing org.apache.cloudstack.ap= i.command.admin.volume.CreateVolumeCmdByAdmin for job-4276 > 2014-11-21 01:04:06,279 INFO [o.a.c.f.j.i.AsyncJobMonitor] (API-Job-Exec= utor-101:ctx-c66892e0 job-4276) Remove job-4276 from job monitoring > {noformat} -- This message was sent by Atlassian JIRA (v6.3.4#6332)