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 45862107DE for ; Mon, 6 Jan 2014 11:23:02 +0000 (UTC) Received: (qmail 2795 invoked by uid 500); 6 Jan 2014 11:22:58 -0000 Delivered-To: apmail-cloudstack-issues-archive@cloudstack.apache.org Received: (qmail 2636 invoked by uid 500); 6 Jan 2014 11:22:55 -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 2544 invoked by uid 500); 6 Jan 2014 11:22:54 -0000 Delivered-To: apmail-incubator-cloudstack-issues@incubator.apache.org Received: (qmail 2529 invoked by uid 99); 6 Jan 2014 11:22:52 -0000 Received: from arcas.apache.org (HELO arcas.apache.org) (140.211.11.28) by apache.org (qpsmtpd/0.29) with ESMTP; Mon, 06 Jan 2014 11:22:52 +0000 Date: Mon, 6 Jan 2014 11:22:52 +0000 (UTC) From: "Devdeep Singh (JIRA)" To: cloudstack-issues@incubator.apache.org Message-ID: In-Reply-To: References: Subject: [jira] [Resolved] (CLOUDSTACK-5788) [Hyper-V] Attaching an uploaded volume to a VM is failing because copyCommand fails 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-5788?page=3Dcom.atl= assian.jira.plugin.system.issuetabpanels:all-tabpanel ] Devdeep Singh resolved CLOUDSTACK-5788. --------------------------------------- Resolution: Fixed > [Hyper-V] Attaching an uploaded volume to a VM is failing because copyCom= mand fails > -------------------------------------------------------------------------= ---------- > > Key: CLOUDSTACK-5788 > URL: https://issues.apache.org/jira/browse/CLOUDSTACK-578= 8 > Project: CloudStack > Issue Type: Bug > Security Level: Public(Anyone can view this level - this is the defa= ult.)=20 > Components: Hypervisor Controller, Management Server > Affects Versions: 4.3.0 > Environment: hyperv,4.3 > Reporter: Abhinav Roy > Assignee: Devdeep Singh > Priority: Critical > Labels: hyper-V,, hyper-v, hyperv > Fix For: 4.3.0 > > > Steps :=20 > =3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=20 > 1. Deploy an advanced zone setup with hyperv.=20 > 2. Create a VM.=20 > 3. upload a volume.=20 > 4. Attach the volume uploaded in step 3 to the VM created in step 2.=20 > Expected behaviour:=20 > =3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=20 > Attach volume should succeed.=20 > Observed behaviour:=20 > =3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=20 > Attach volume fails with :=20 > MS logs :=20 > ----------------------=20 > 2014-01-06 11:53:37,402 DEBUG [c.c.h.h.r.HypervDirectConnectResource] (Di= rectAgent-380:ctx-674d76cf) POST response is[{"org.apache.cloudstack.storag= e.command.CopyCmdAnswer":{"result":false,"details":"org.apache.cloudstack.s= torage.command.CopyCommand failed on exception, Error getting value from 'F= ullFileName' on 'HypervResource.VolumeObjectTO'.","newData":null,"contextMa= p":{}}}] > 2014-01-06 11:53:37,403 DEBUG [c.c.h.h.r.HypervDirectConnectResource] (Di= rectAgent-380:ctx-674d76cf) executeRequest received response [{"org.apache.= cloudstack.storage.command.CopyCmdAnswer":{"result":false,"details":"org.ap= ache.cloudstack.storage.command.CopyCommand failed on exception, Error gett= ing value from \u0027FullFileName\u0027 on \u0027HypervResource.VolumeObjec= tTO\u0027.","contextMap":{},"wait":0}}] > 2014-01-06 11:53:37,403 DEBUG [c.c.a.m.DirectAgentAttache] (DirectAgent-3= 80:ctx-674d76cf) Seq 1-849871246: Response Received: > 2014-01-06 11:53:37,403 DEBUG [c.c.a.t.Request] (DirectAgent-380:ctx-674d= 76cf) Seq 1-849871246: Processing: { Ans: , MgmtId: 280320865129348, via: = 1, Ver: v1, Flags: 10, [{"org.apache.cloudstack.storage.command.CopyCmdAnsw= er":{"result":false,"details":"org.apache.cloudstack.storage.command.CopyCo= mmand failed on exception, Error getting value from 'FullFileName' on 'Hype= rvResource.VolumeObjectTO'.","wait":0}}] } > 2014-01-06 11:53:37,403 DEBUG [c.c.a.t.Request] (Job-Executor-115:ctx-46b= 175df ctx-9f2ec3a6) Seq 1-849871246: Received: { Ans: , MgmtId: 2803208651= 29348, via: 1, Ver: v1, Flags: 10, { CopyCmdAnswer } } > 2014-01-06 11:53:37,421 DEBUG [o.a.c.e.o.VolumeOrchestrator] (Job-Executo= r-115:ctx-46b175df ctx-9f2ec3a6) copy volume failed: java.lang.NullPointerE= xception > 2014-01-06 11:53:37,421 ERROR [c.c.v.VmWorkJobHandlerProxy] (Job-Executor= -115:ctx-46b175df ctx-9f2ec3a6) Invocation exception, caused by: com.cloud.= utils.exception.CloudRuntimeException: copy volume failed: java.lang.NullPo= interException > 2014-01-06 11:53:37,421 ERROR [c.c.v.VmWorkJobDispatcher] (Job-Executor-1= 15:ctx-46b175df ctx-9f2ec3a6) Unable to complete AsyncJobVO {id:142, userId= : 2, accountId: 2, instanceType: null, instanceId: null, cmd: com.cloud.sto= rage.VmWorkAttachVolume, cmdInfo: rO0ABXNyACRjb20uY2xvdWQuc3RvcmFnZS5WbVdvc= mtBdHRhY2hWb2x1bWUHra_5YYfiHAIAAkwACGRldmljZUlkdAAQTGphdmEvbGFuZy9Mb25nO0wA= CHZvbHVtZUlkcQB-AAF4cgATY29tLmNsb3VkLnZtLlZtV29ya5-ZtlbwJWdrAgAESgAJYWNjb3V= udElkSgAGdXNlcklkSgAEdm1JZEwAC2hhbmRsZXJOYW1ldAASTGphdmEvbGFuZy9TdHJpbmc7eH= AAAAAAAAAAAgAAAAAAAAACAAAAAAAAAAp0ABRWb2x1bWVBcGlTZXJ2aWNlSW1wbHBzcgAOamF2Y= S5sYW5nLkxvbmc7i-SQzI8j3wIAAUoABXZhbHVleHIAEGphdmEubGFuZy5OdW1iZXKGrJUdC5Tg= iwIAAHhwAAAAAAAAABM, cmdVersion: 0, status: IN_PROGRESS, processStatus: 0, = resultCode: 0, result: null, initMsid: 280320865129348, completeMsid: null,= lastUpdated: null, lastPolled: null, created: Mon Jan 06 11:53:27 IST 2014= } > com.cloud.utils.exception.CloudRuntimeException: copy volume failed: java= .lang.NullPointerException > at org.apache.cloudstack.engine.orchestration.VolumeOrchestrator.= copyVolumeFromSecToPrimary(VolumeOrchestrator.java:412) > at org.apache.cloudstack.engine.orchestration.VolumeOrchestrator.= copyVolume(VolumeOrchestrator.java:718) > at org.apache.cloudstack.engine.orchestration.VolumeOrchestrator.= createVolumeOnPrimaryStorage(VolumeOrchestrator.java:747) > at com.cloud.storage.VolumeApiServiceImpl.attachVolumeToVM(Volume= ApiServiceImpl.java:1249) > at com.cloud.storage.VolumeApiServiceImpl.orchestrateAttachVolume= ToVM(VolumeApiServiceImpl.java:1127) > at com.cloud.storage.VolumeApiServiceImpl.orchestrateAttachVolume= ToVM(VolumeApiServiceImpl.java:2406) > at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) > at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccess= orImpl.java:57) > at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMeth= odAccessorImpl.java:43) > at java.lang.reflect.Method.invoke(Method.java:616) > at com.cloud.vm.VmWorkJobHandlerProxy.handleVmWorkJob(VmWorkJobHa= ndlerProxy.java:107) > at com.cloud.storage.VolumeApiServiceImpl.handleVmWorkJob(VolumeA= piServiceImpl.java:2437) > at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) > at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccess= orImpl.java:57) > at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMeth= odAccessorImpl.java:43) > at java.lang.reflect.Method.invoke(Method.java:616) > at org.springframework.aop.support.AopUtils.invokeJoinpointUsingR= eflection(AopUtils.java:317) > at org.springframework.aop.framework.ReflectiveMethodInvocation.i= nvokeJoinpoint(ReflectiveMethodInvocation.java:183) > at org.springframework.aop.framework.ReflectiveMethodInvocation.p= roceed(ReflectiveMethodInvocation.java:150) > at org.springframework.aop.interceptor.ExposeInvocationIntercepto= r.invoke(ExposeInvocationInterceptor.java:91) > at org.springframework.aop.framework.ReflectiveMethodInvocation.p= roceed(ReflectiveMethodInvocation.java:172) > at org.springframework.aop.framework.JdkDynamicAopProxy.invoke(Jd= kDynamicAopProxy.java:204) > at $Proxy195.handleVmWorkJob(Unknown Source) > at com.cloud.vm.VmWorkJobDispatcher.runJob(VmWorkJobDispatcher.ja= va:99) > at org.apache.cloudstack.framework.jobs.impl.AsyncJobManagerImpl$= 5.runInContext(AsyncJobManagerImpl.java:524) > at org.apache.cloudstack.managed.context.ManagedContextRunnable$1= .run(ManagedContextRunnable.java:49) > at org.apache.cloudstack.managed.context.impl.DefaultManagedConte= xt$1.call(DefaultManagedContext.java:56) > at org.apache.cloudstack.managed.context.impl.DefaultManagedConte= xt.callWithContext(DefaultManagedContext.java:103) > at org.apache.cloudstack.managed.context.impl.DefaultManagedConte= xt.runWithContext(DefaultManagedContext.java:53) > at org.apache.cloudstack.managed.context.ManagedContextRunnable.r= un(ManagedContextRunnable.java:46) > 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(ThreadPoolEx= ecutor.java:1110) > at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolE= xecutor.java:603) > at java.lang.Thread.run(Thread.java:679) > 2014-01-06 11:53:37,430 INFO [c.c.v.VmWorkJobDispatcher] (Job-Executor-1= 15:ctx-46b175df ctx-9f2ec3a6) Serialize exception object into json: com.clo= ud.utils.exception.CloudRuntimeException/{"idList":[],"uuidList":[],"csErro= rCode":4250,"detailMessage":"copy volume failed: java.lang.NullPointerExcep= tion","cause":{"class":"com.cloud.utils.exception.CloudRuntimeException","m= sg":"copy volume failed: java.lang.NullPointerException"},"stackTrace":[{"d= eclaringClass":"org.apache.cloudstack.engine.orchestration.VolumeOrchestrat= or","methodName":"copyVolumeFromSecToPrimary","fileName":"VolumeOrchestrato= r.java","lineNumber":412},{"declaringClass":"org.apache.cloudstack.engine.o= rchestration.VolumeOrchestrator","methodName":"copyVolume","fileName":"Volu= meOrchestrator.java","lineNumber":718},{"declaringClass":"org.apache.clouds= tack.engine.orchestration.VolumeOrchestrator","methodName":"createVolumeOnP= rimaryStorage","fileName":"VolumeOrchestrator.java","lineNumber":747},{"dec= laringClass":"com.cloud.storage.VolumeApiServiceImpl","methodName":"attachV= olumeToVM","fileName":"VolumeApiServiceImpl.java","lineNumber":1249},{"decl= aringClass":"com.cloud.storage.VolumeApiServiceImpl","methodName":"orchestr= ateAttachVolumeToVM","fileName":"VolumeApiServiceImpl.java","lineNumber":11= 27},{"declaringClass":"com.cloud.storage.VolumeApiServiceImpl","methodName"= :"orchestrateAttachVolumeToVM","fileName":"VolumeApiServiceImpl.java","line= Number":2406},{"declaringClass":"sun.reflect.NativeMethodAccessorImpl","met= hodName":"invoke0","fileName":"NativeMethodAccessorImpl.java","lineNumber":= -2},{"declaringClass":"sun.reflect.NativeMethodAccessorImpl","methodName":"= invoke","fileName":"NativeMethodAccessorImpl.java","lineNumber":57},{"decla= ringClass":"sun.reflect.DelegatingMethodAccessorImpl","methodName":"invoke"= ,"fileName":"DelegatingMethodAccessorImpl.java","lineNumber":43},{"declarin= gClass":"java.lang.reflect.Method","methodName":"invoke","fileName":"Method= .java","lineNumber":616},{"declaringClass":"com.cloud.vm.VmWorkJobHandlerPr= oxy","methodName":"handleVmWorkJob","fileName":"VmWorkJobHandlerProxy.java"= ,"lineNumber":107},{"declaringClass":"com.cloud.storage.VolumeApiServiceImp= l","methodName":"handleVmWorkJob","fileName":"VolumeApiServiceImpl.java","l= ineNumber":2437},{"declaringClass":"sun.reflect.NativeMethodAccessorImpl","= methodName":"invoke0","fileName":"NativeMethodAccessorImpl.java","lineNumbe= r":-2},{"declaringClass":"sun.reflect.NativeMethodAccessorImpl","methodName= ":"invoke","fileName":"NativeMethodAccessorImpl.java","lineNumber":57},{"de= claringClass":"sun.reflect.DelegatingMethodAccessorImpl","methodName":"invo= ke","fileName":"DelegatingMethodAccessorImpl.java","lineNumber":43},{"decla= ringClass":"java.lang.reflect.Method","methodName":"invoke","fileName":"Met= hod.java","lineNumber":616},{"declaringClass":"org.springframework.aop.supp= ort.AopUtils","methodName":"invokeJoinpointUsingReflection","fileName":"Aop= Utils.java","lineNumber":317},{"declaringClass":"org.springframework.aop.fr= amework.ReflectiveMethodInvocation","methodName":"invokeJoinpoint","fileNam= e":"ReflectiveMethodInvocation.java","lineNumber":183},{"declaringClass":"o= rg.springframework.aop.framework.ReflectiveMethodInvocation","methodName":"= proceed","fileName":"ReflectiveMethodInvocation.java","lineNumber":150},{"d= eclaringClass":"org.springframework.aop.interceptor.ExposeInvocationInterce= ptor","methodName":"invoke","fileName":"ExposeInvocationInterceptor.java","= lineNumber":91},{"declaringClass":"org.springframework.aop.framework.Reflec= tiveMethodInvocation","methodName":"proceed","fileName":"ReflectiveMethodIn= vocation.java","lineNumber":172},{"declaringClass":"org.springframework.aop= .framework.JdkDynamicAopProxy","methodName":"invoke","fileName":"JdkDynamic= AopProxy.java","lineNumber":204},{"declaringClass":"$Proxy195","methodName"= :"handleVmWorkJob","lineNumber":-1},{"declaringClass":"com.cloud.vm.VmWorkJ= obDispatcher","methodName":"runJob","fileName":"VmWorkJobDispatcher.java","= lineNumber":99},{"declaringClass":"org.apache.cloudstack.framework.jobs.imp= l.AsyncJobManagerImpl$5","methodName":"runInContext","fileName":"AsyncJobMa= nagerImpl.java","lineNumber":524},{"declaringClass":"org.apache.cloudstack.= managed.context.ManagedContextRunnable$1","methodName":"run","fileName":"Ma= nagedContextRunnable.java","lineNumber":49},{"declaringClass":"org.apache.c= loudstack.managed.context.impl.DefaultManagedContext$1","methodName":"call"= ,"fileName":"DefaultManagedContext.java","lineNumber":56},{"declaringClass"= :"org.apache.cloudstack.managed.context.impl.DefaultManagedContext","method= Name":"callWithContext","fileName":"DefaultManagedContext.java","lineNumber= ":103},{"declaringClass":"org.apache.cloudstack.managed.context.impl.Defaul= tManagedContext","methodName":"runWithContext","fileName":"DefaultManagedCo= ntext.java","lineNumber":53},{"declaringClass":"org.apache.cloudstack.manag= ed.context.ManagedContextRunnable","methodName":"run","fileName":"ManagedCo= ntextRunnable.java","lineNumber":46},{"declaringClass":"java.util.concurren= t.Executors$RunnableAdapter","methodName":"call","fileName":"Executors.java= ","lineNumber":471},{"declaringClass":"java.util.concurrent.FutureTask$Sync= ","methodName":"innerRun","fileName":"FutureTask.java","lineNumber":334},{"= declaringClass":"java.util.concurrent.FutureTask","methodName":"run","fileN= ame":"FutureTask.java","lineNumber":166},{"declaringClass":"java.util.concu= rrent.ThreadPoolExecutor","methodName":"runWorker","fileName":"ThreadPoolEx= ecutor.java","lineNumber":1110},{"declaringClass":"java.util.concurrent.Thr= eadPoolExecutor$Worker","methodName":"run","fileName":"ThreadPoolExecutor.j= ava","lineNumber":603},{"declaringClass":"java.lang.Thread","methodName":"r= un","fileName":"Thread.java","lineNumber":679}]} > 2014-01-06 11:53:37,430 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl] (Job-Exec= utor-115:ctx-46b175df ctx-9f2ec3a6) Complete async job-142, jobStatus: FAIL= ED, resultCode: 0, result: com.cloud.utils.exception.CloudRuntimeException/= {"idList":[],"uuidList":[],"csErrorCode":4250,"detailMessage":"copy volume = failed: java.lang.NullPointerException","cause":{"class":"com.cloud.utils.e= xception.CloudRuntimeException","msg":"copy volume failed: java.lang.NullPo= interException"},"stackTrace":[{"declaringClass":"org.apache.cloudstack.eng= ine.orchestration.VolumeOrchestrator","methodName":"copyVolumeFromSecToPrim= ary","fileName":"VolumeOrchestrator.java","lineNumber":412},{"declaringClas= s":"org.apache.cloudstack.engine.orchestration.VolumeOrchestrator","methodN= ame":"copyVolume","fileName":"VolumeOrchestrator.java","lineNumber":718},{"= declaringClass":"org.apache.cloudstack.engine.orchestration.VolumeOrchestra= tor","methodName":"createVolumeOnPrimaryStorage","fileName":"VolumeOrchestr= ator.java","lineNumber":747},{"declaringClass":"com.cloud.storage.VolumeApi= ServiceImpl","methodName":"attachVolumeToVM","fileName":"VolumeApiServiceIm= pl.java","lineNumber":1249},{"declaringClass":"com.cloud.storage.VolumeApiS= erviceImpl","methodName":"orchestrateAttachVolumeToVM","fileName":"VolumeAp= iServiceImpl.java","lineNumber":1127},{"declaringClass":"com.cloud.storage.= VolumeApiServiceImpl","methodName":"orchestrateAttachVolumeToVM","fileName"= :"VolumeApiServiceImpl.java","lineNumber":2406},{"declaringClass":"sun.refl= ect.NativeMethodAccessorImpl","methodName":"invoke0","fileName":"NativeMeth= odAccessorImpl.java","lineNumber":-2},{"declaringClass":"sun.reflect.Native= MethodAccessorImpl","methodName":"invoke","fileName":"NativeMethodAccessorI= mpl.java","lineNumber":57},{"declaringClass":"sun.reflect.DelegatingMethodA= ccessorImpl","methodName":"invoke","fileName":"DelegatingMethodAccessorImpl= .java","lineNumber":43},{"declaringClass":"java.lang.reflect.Method","metho= dName":"invoke","fileName":"Method.java","lineNumber":616},{"declaringClass= ":"com.cloud.vm.VmWorkJobHandlerProxy","methodName":"handleVmWorkJob","file= Name":"VmWorkJobHandlerProxy.java","lineNumber":107},{"declaringClass":"com= .cloud.storage.VolumeApiServiceImpl","methodName":"handleVmWorkJob","fileNa= me":"VolumeApiServiceImpl.java","lineNumber":2437},{"declaringClass":"sun.r= eflect.NativeMethodAccessorImpl","methodName":"invoke0","fileName":"NativeM= ethodAccessorImpl.java","lineNumber":-2},{"declaringClass":"sun.reflect.Nat= iveMethodAccessorImpl","methodName":"invoke","fileName":"NativeMethodAccess= orImpl.java","lineNumber":57},{"declaringClass":"sun.reflect.DelegatingMeth= odAccessorImpl","methodName":"invoke","fileName":"DelegatingMethodAccessorI= mpl.java","lineNumber":43},{"declaringClass":"java.lang.reflect.Method","me= thodName":"invoke","fileName":"Method.java","lineNumber":616},{"declaringCl= ass":"org.springframework.aop.support.AopUtils","methodName":"invokeJoinpoi= ntUsingReflection","fileName":"AopUtils.java","lineNumber":317},{"declaring= Class":"org.springframework.aop.framework.ReflectiveMethodInvocation","meth= odName":"invokeJoinpoint","fileName":"ReflectiveMethodInvocation.java","lin= eNumber":183},{"declaringClass":"org.springframework.aop.framework.Reflecti= veMethodInvocation","methodName":"proceed","fileName":"ReflectiveMethodInvo= cation.java","lineNumber":150},{"declaringClass":"org.springframework.aop.i= nterceptor.ExposeInvocationInterceptor","methodName":"invoke","fileName":"E= xposeInvocationInterceptor.java","lineNumber":91},{"declaringClass":"org.sp= ringframework.aop.framework.ReflectiveMethodInvocation","methodName":"proce= ed","fileName":"ReflectiveMethodInvocation.java","lineNumber":172},{"declar= ingClass":"org.springframework.aop.framework.JdkDynamicAopProxy","methodNam= e":"invoke","fileName":"JdkDynamicAopProxy.java","lineNumber":204},{"declar= ingClass":"$Proxy195","methodName":"handleVmWorkJob","lineNumber":-1},{"dec= laringClass":"com.cloud.vm.VmWorkJobDispatcher","methodName":"runJob","file= Name":"VmWorkJobDispatcher.java","lineNumber":99},{"declaringClass":"org.ap= ache.cloudstack.framework.jobs.impl.AsyncJobManagerImpl$5","methodName":"ru= nInContext","fileName":"AsyncJobManagerImpl.java","lineNumber":524},{"decla= ringClass":"org.apache.cloudstack.managed.context.ManagedContextRunnable$1"= ,"methodName":"run","fileName":"ManagedContextRunnable.java","lineNumber":4= 9},{"declaringClass":"org.apache.cloudstack.managed.context.impl.DefaultMan= agedContext$1","methodName":"call","fileName":"DefaultManagedContext.java",= "lineNumber":56},{"declaringClass":"org.apache.cloudstack.managed.context.i= mpl.DefaultManagedContext","methodName":"callWithContext","fileName":"Defau= ltManagedContext.java","lineNumber":103},{"declaringClass":"org.apache.clou= dstack.managed.context.impl.DefaultManagedContext","methodName":"runWithCon= text","fileName":"DefaultManagedContext.java","lineNumber":53},{"declaringC= lass":"org.apache.cloudstack.managed.context.ManagedContextRunnable","metho= dName":"run","fileName":"ManagedContextRunnable.java","lineNumber":46},{"de= claringClass":"java.util.concurrent.Executors$RunnableAdapter","methodName"= :"call","fileName":"Executors.java","lineNumber":471},{"declaringClass":"ja= va.util.concurrent.FutureTask$Sync","methodName":"innerRun","fileName":"Fut= ureTask.java","lineNumber":334},{"declaringClass":"java.util.concurrent.Fut= ureTask","methodName":"run","fileName":"FutureTask.java","lineNumber":166},= {"declaringClass":"java.util.concurrent.ThreadPoolExecutor","methodName":"r= unWorker","fileName":"ThreadPoolExecutor.java","lineNumber":1110},{"declari= ngClass":"java.util.concurrent.ThreadPoolExecutor$Worker","methodName":"run= ","fileName":"ThreadPoolExecutor.java","lineNumber":603},{"declaringClass":= "java.lang.Thread","methodName":"run","fileName":"Thread.java","lineNumber"= :679}]} > 2014-01-06 11:53:37,437 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl] (Job-Exec= utor-115:ctx-46b175df) Done executing com.cloud.storage.VmWorkAttachVolume = for job-142 > 2014-01-06 11:53:37,441 INFO [o.a.c.f.j.i.AsyncJobMonitor] (Job-Executor= -116:ctx-9f6a5f51) Add job-141 into job monitoring > 2014-01-06 11:53:37,441 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl] (Job-Exec= utor-116:ctx-9f6a5f51) Executing AsyncJobVO {id:141, userId: 2, accountId: = 2, instanceType: Volume, instanceId: 19, cmd: org.apache.cloudstack.api.com= mand.user.volume.AttachVolumeCmd, cmdInfo: {"response":"json","id":"4d5e35e= 3-2a67-4d1e-8758-7c6b283866cd","sessionkey":"5ODiLDmKehMbGznMVT9sutQQg7U\u0= 03d","cmdEventType":"VOLUME.ATTACH","ctxUserId":"2","virtualMachineId":"620= 64ead-e49e-4bb7-86e7-7422f33e3f75","httpmethod":"GET","_":"1388989738235","= ctxAccountId":"2","ctxStartEventId":"332"}, cmdVersion: 0, status: IN_PROGR= ESS, processStatus: 0, resultCode: 0, result: null, initMsid: 2803208651293= 48, completeMsid: null, lastUpdated: null, lastPolled: Mon Jan 06 11:53:36 = IST 2014, created: Mon Jan 06 11:53:27 IST 2014} > 2014-01-06 11:53:37,447 DEBUG [o.a.c.f.j.i.SyncQueueManagerImpl] (Job-Exe= cutor-115:ctx-46b175df) Sync queue (21) is currently empty > 2014-01-06 11:53:37,448 INFO [o.a.c.f.j.i.AsyncJobMonitor] (Job-Executor= -115:ctx-46b175df) Remove job-142 from job monitoring > 2014-01-06 11:53:37,447 ERROR [c.c.a.ApiAsyncJobDispatcher] (Job-Executor= -114:ctx-035abfcd) Unexpected exception while executing org.apache.cloudsta= ck.api.command.user.volume.AttachVolumeCmd > com.cloud.utils.exception.CloudRuntimeException: Unable to serialize: com= .cloud.utils.exception.CloudRuntimeException/{"idList":[],"uuidList":[],"cs= ErrorCode":4250,"detailMessage":"copy volume failed: java.lang.NullPointerE= xception","cause":{"class":"com.cloud.utils.exception.CloudRuntimeException= ","msg":"copy volume failed: java.lang.NullPointerException"},"stackTrace":= [{"declaringClass":"org.apache.cloudstack.engine.orchestration.VolumeOrches= trator","methodName":"copyVolumeFromSecToPrimary","fileName":"VolumeOrchest= rator.java","lineNumber":412},{"declaringClass":"org.apache.cloudstack.engi= ne.orchestration.VolumeOrchestrator","methodName":"copyVolume","fileName":"= VolumeOrchestrator.java","lineNumber":718},{"declaringClass":"org.apache.cl= oudstack.engine.orchestration.VolumeOrchestrator","methodName":"createVolum= eOnPrimaryStorage","fileName":"VolumeOrchestrator.java","lineNumber":747},{= "declaringClass":"com.cloud.storage.VolumeApiServiceImpl","methodName":"att= achVolumeToVM","fileName":"VolumeApiServiceImpl.java","lineNumber":1249},{"= declaringClass":"com.cloud.storage.VolumeApiServiceImpl","methodName":"orch= estrateAttachVolumeToVM","fileName":"VolumeApiServiceImpl.java","lineNumber= ":1127},{"declaringClass":"com.cloud.storage.VolumeApiServiceImpl","methodN= ame":"orchestrateAttachVolumeToVM","fileName":"VolumeApiServiceImpl.java","= lineNumber":2406},{"declaringClass":"sun.reflect.NativeMethodAccessorImpl",= "methodName":"invoke0","fileName":"NativeMethodAccessorImpl.java","lineNumb= er":-2},{"declaringClass":"sun.reflect.NativeMethodAccessorImpl","methodNam= e":"invoke","fileName":"NativeMethodAccessorImpl.java","lineNumber":57},{"d= eclaringClass":"sun.reflect.DelegatingMethodAccessorImpl","methodName":"inv= oke","fileName":"DelegatingMethodAccessorImpl.java","lineNumber":43},{"decl= aringClass":"java.lang.reflect.Method","methodName":"invoke","fileName":"Me= thod.java","lineNumber":616},{"declaringClass":"com.cloud.vm.VmWorkJobHandl= erProxy","methodName":"handleVmWorkJob","fileName":"VmWorkJobHandlerProxy.j= ava","lineNumber":107},{"declaringClass":"com.cloud.storage.VolumeApiServic= eImpl","methodName":"handleVmWorkJob","fileName":"VolumeApiServiceImpl.java= ","lineNumber":2437},{"declaringClass":"sun.reflect.NativeMethodAccessorImp= l","methodName":"invoke0","fileName":"NativeMethodAccessorImpl.java","lineN= umber":-2},{"declaringClass":"sun.reflect.NativeMethodAccessorImpl","method= Name":"invoke","fileName":"NativeMethodAccessorImpl.java","lineNumber":57},= {"declaringClass":"sun.reflect.DelegatingMethodAccessorImpl","methodName":"= invoke","fileName":"DelegatingMethodAccessorImpl.java","lineNumber":43},{"d= eclaringClass":"java.lang.reflect.Method","methodName":"invoke","fileName":= "Method.java","lineNumber":616},{"declaringClass":"org.springframework.aop.= support.AopUtils","methodName":"invokeJoinpointUsingReflection","fileName":= "AopUtils.java","lineNumber":317},{"declaringClass":"org.springframework.ao= p.framework.ReflectiveMethodInvocation","methodName":"invokeJoinpoint","fil= eName":"ReflectiveMethodInvocation.java","lineNumber":183},{"declaringClass= ":"org.springframework.aop.framework.ReflectiveMethodInvocation","methodNam= e":"proceed","fileName":"ReflectiveMethodInvocation.java","lineNumber":150}= ,{"declaringClass":"org.springframework.aop.interceptor.ExposeInvocationInt= erceptor","methodName":"invoke","fileName":"ExposeInvocationInterceptor.jav= a","lineNumber":91},{"declaringClass":"org.springframework.aop.framework.Re= flectiveMethodInvocation","methodName":"proceed","fileName":"ReflectiveMeth= odInvocation.java","lineNumber":172},{"declaringClass":"org.springframework= .aop.framework.JdkDynamicAopProxy","methodName":"invoke","fileName":"JdkDyn= amicAopProxy.java","lineNumber":204},{"declaringClass":"$Proxy195","methodN= ame":"handleVmWorkJob","lineNumber":-1},{"declaringClass":"com.cloud.vm.VmW= orkJobDispatcher","methodName":"runJob","fileName":"VmWorkJobDispatcher.jav= a","lineNumber":99},{"declaringClass":"org.apache.cloudstack.framework.jobs= .impl.AsyncJobManagerImpl$5","methodName":"runInContext","fileName":"AsyncJ= obManagerImpl.java","lineNumber":524},{"declaringClass":"org.apache.cloudst= ack.managed.context.ManagedContextRunnable$1","methodName":"run","fileName"= :"ManagedContextRunnable.java","lineNumber":49},{"declaringClass":"org.apac= he.cloudstack.managed.context.impl.DefaultManagedContext$1","methodName":"c= all","fileName":"DefaultManagedContext.java","lineNumber":56},{"declaringCl= ass":"org.apache.cloudstack.managed.context.impl.DefaultManagedContext","me= thodName":"callWithContext","fileName":"DefaultManagedContext.java","lineNu= mber":103},{"declaringClass":"org.apache.cloudstack.managed.context.impl.De= faultManagedContext","methodName":"runWithContext","fileName":"DefaultManag= edContext.java","lineNumber":53},{"declaringClass":"org.apache.cloudstack.m= anaged.context.ManagedContextRunnable","methodName":"run","fileName":"Manag= edContextRunnable.java","lineNumber":46},{"declaringClass":"java.util.concu= rrent.Executors$RunnableAdapter","methodName":"call","fileName":"Executors.= java","lineNumber":471},{"declaringClass":"java.util.concurrent.FutureTask$= Sync","methodName":"innerRun","fileName":"FutureTask.java","lineNumber":334= },{"declaringClass":"java.util.concurrent.FutureTask","methodName":"run","f= ileName":"FutureTask.java","lineNumber":166},{"declaringClass":"java.util.c= oncurrent.ThreadPoolExecutor","methodName":"runWorker","fileName":"ThreadPo= olExecutor.java","lineNumber":1110},{"declaringClass":"java.util.concurrent= .ThreadPoolExecutor$Worker","methodName":"run","fileName":"ThreadPoolExecut= or.java","lineNumber":603},{"declaringClass":"java.lang.Thread","methodName= ":"run","fileName":"Thread.java","lineNumber":679}]} > at org.apache.cloudstack.framework.jobs.impl.JobSerializerHelper.= fromObjectSerializedString(JobSerializerHelper.java:135) > at org.apache.cloudstack.framework.jobs.impl.AsyncJobManagerImpl.= unmarshallResultObject(AsyncJobManagerImpl.java:669) > at com.cloud.storage.VolumeApiServiceImpl.attachVolumeToVM(Volume= ApiServiceImpl.java:1115) > at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) > at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccess= orImpl.java:57) > at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMeth= odAccessorImpl.java:43) > at java.lang.reflect.Method.invoke(Method.java:616) > at org.springframework.aop.support.AopUtils.invokeJoinpointUsingR= eflection(AopUtils.java:317) > at org.springframework.aop.framework.ReflectiveMethodInvocation.i= nvokeJoinpoint(ReflectiveMethodInvocation.java:183) > at org.springframework.aop.framework.ReflectiveMethodInvocation.p= roceed(ReflectiveMethodInvocation.java:150) > at org.springframework.aop.interceptor.ExposeInvocationIntercepto= r.invoke(ExposeInvocationInterceptor.java:91) > at org.springframework.aop.framework.ReflectiveMethodInvocation.p= roceed(ReflectiveMethodInvocation.java:172) > at org.springframework.aop.framework.JdkDynamicAopProxy.invoke(Jd= kDynamicAopProxy.java:204) > at $Proxy195.attachVolumeToVM(Unknown Source) > at org.apache.cloudstack.api.command.user.volume.AttachVolumeCmd.= execute(AttachVolumeCmd.java:123) > at com.cloud.api.ApiDispatcher.dispatch(ApiDispatcher.java:161) > at com.cloud.api.ApiAsyncJobDispatcher.runJobInContext(ApiAsyncJo= bDispatcher.java:109) > at com.cloud.api.ApiAsyncJobDispatcher$1.run(ApiAsyncJobDispatche= r.java:66) > at org.apache.cloudstack.managed.context.impl.DefaultManagedConte= xt$1.call(DefaultManagedContext.java:56) > at org.apache.cloudstack.managed.context.impl.DefaultManagedConte= xt.callWithContext(DefaultManagedContext.java:103) > at org.apache.cloudstack.managed.context.impl.DefaultManagedConte= xt.runWithContext(DefaultManagedContext.java:53) > at com.cloud.api.ApiAsyncJobDispatcher.runJob(ApiAsyncJobDispatch= er.java:63) > at org.apache.cloudstack.framework.jobs.impl.AsyncJobManagerImpl$= 5.runInContext(AsyncJobManagerImpl.java:524) > at org.apache.cloudstack.managed.context.ManagedContextRunnable$1= .run(ManagedContextRunnable.java:49) > at org.apache.cloudstack.managed.context.impl.DefaultManagedConte= xt$1.call(DefaultManagedContext.java:56) > at org.apache.cloudstack.managed.context.impl.DefaultManagedConte= xt.callWithContext(DefaultManagedContext.java:103) > at org.apache.cloudstack.managed.context.impl.DefaultManagedConte= xt.runWithContext(DefaultManagedContext.java:53) > at org.apache.cloudstack.managed.context.ManagedContextRunnable.r= un(ManagedContextRunnable.java:46) > 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(ThreadPoolEx= ecutor.java:1110) > at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolE= xecutor.java:603) > at java.lang.Thread.run(Thread.java:679) > Caused by: java.io.StreamCorruptedException: invalid stream header: 72899= C96 > at java.io.ObjectInputStream.readStreamHeader(ObjectInputStream.j= ava:800) > at java.io.ObjectInputStream.(ObjectInputStream.java:297) > at org.apache.cloudstack.framework.jobs.impl.JobSerializerHelper.= fromObjectSerializedString(JobSerializerHelper.java:129) > ... 33 more > 2014-01-06 11:53:37,457 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl] (Job-Exec= utor-114:ctx-035abfcd) Complete async job-141, jobStatus: FAILED, resultCod= e: 530, result: org.apache.cloudstack.api.response.ExceptionResponse/null/{= "uuidList":[],"errorcode":530,"errortext":"Unable to serialize: com.cloud.u= tils.exception.CloudRuntimeException/{\"idList\":[],\"uuidList\":[],\"csErr= orCode\":4250,\"detailMessage\":\"copy volume failed: java.lang.NullPointer= Exception\",\"cause\":{\"class\":\"com.cloud.utils.exception.CloudRuntimeEx= ception\",\"msg\":\"copy volume failed: java.lang.NullPointerException\"},\= "stackTrace\":[{\"declaringClass\":\"org.apache.cloudstack.engine.orchestra= tion.VolumeOrchestrator\",\"methodName\":\"copyVolumeFromSecToPrimary\",\"f= ileName\":\"VolumeOrchestrator.java\",\"lineNumber\":412},{\"declaringClass= \":\"org.apache.cloudstack.engine.orchestration.VolumeOrchestrator\",\"meth= odName\":\"copyVolume\",\"fileName\":\"VolumeOrchestrator.java\",\"lineNumb= er\":718},{\"declaringClass\":\"org.apache.cloudstack.engine.orchestration.= VolumeOrchestrator\",\"methodName\":\"createVolumeOnPrimaryStorage\",\"file= Name\":\"VolumeOrchestrator.java\",\"lineNumber\":747},{\"declaringClass\":= \"com.cloud.storage.VolumeApiServiceImpl\",\"methodName\":\"attachVolumeToV= M\",\"fileName\":\"VolumeApiServiceImpl.java\",\"lineNumber\":1249},{\"decl= aringClass\":\"com.cloud.storage.VolumeApiServiceImpl\",\"methodName\":\"or= chestrateAttachVolumeToVM\",\"fileName\":\"VolumeApiServiceImpl.java\",\"li= neNumber\":1127},{\"declaringClass\":\"com.cloud.storage.VolumeApiServiceIm= pl\",\"methodName\":\"orchestrateAttachVolumeToVM\",\"fileName\":\"VolumeAp= iServiceImpl.java\",\"lineNumber\":2406},{\"declaringClass\":\"sun.reflect.= NativeMethodAccessorImpl\",\"methodName\":\"invoke0\",\"fileName\":\"Native= MethodAccessorImpl.java\",\"lineNumber\":-2},{\"declaringClass\":\"sun.refl= ect.NativeMethodAccessorImpl\",\"methodName\":\"invoke\",\"fileName\":\"Nat= iveMethodAccessorImpl.java\",\"lineNumber\":57},{\"declaringClass\":\"sun.r= eflect.DelegatingMethodAccessorImpl\",\"methodName\":\"invoke\",\"fileName\= ":\"DelegatingMethodAccessorImpl.java\",\"lineNumber\":43},{\"declaringClas= s\":\"java.lang.reflect.Method\",\"methodName\":\"invoke\",\"fileName\":\"M= ethod.java\",\"lineNumber\":616},{\"declaringClass\":\"com.cloud.vm.VmWorkJ= obHandlerProxy\",\"methodName\":\"handleVmWorkJob\",\"fileName\":\"VmWorkJo= bHandlerProxy.java\",\"lineNumber\":107},{\"declaringClass\":\"com.cloud.st= orage.VolumeApiServiceImpl\",\"methodName\":\"handleVmWorkJob\",\"fileName\= ":\"VolumeApiServiceImpl.java\",\"lineNumber\":2437},{\"declaringClass\":\"= sun.reflect.NativeMethodAccessorImpl\",\"methodName\":\"invoke0\",\"fileNam= e\":\"NativeMethodAccessorImpl.java\",\"lineNumber\":-2},{\"declaringClass\= ":\"sun.reflect.NativeMethodAccessorImpl\",\"methodName\":\"invoke\",\"file= Name\":\"NativeMethodAccessorImpl.java\",\"lineNumber\":57},{\"declaringCla= ss\":\"sun.reflect.DelegatingMethodAccessorImpl\",\"methodName\":\"invoke\"= ,\"fileName\":\"DelegatingMethodAccessorImpl.java\",\"lineNumber\":43},{\"d= eclaringClass\":\"java.lang.reflect.Method\",\"methodName\":\"invoke\",\"fi= leName\":\"Method.java\",\"lineNumber\":616},{\"declaringClass\":\"org.spri= ngframework.aop.support.AopUtils\",\"methodName\":\"invokeJoinpointUsingRef= lection\",\"fileName\":\"AopUtils.java\",\"lineNumber\":317},{\"declaringCl= ass\":\"org.springframework.aop.framework.ReflectiveMethodInvocation\",\"me= thodName\":\"invokeJoinpoint\",\"fileName\":\"ReflectiveMethodInvocation.ja= va\",\"lineNumber\":183},{\"declaringClass\":\"org.springframework.aop.fram= ework.ReflectiveMethodInvocation\",\"methodName\":\"proceed\",\"fileName\":= \"ReflectiveMethodInvocation.java\",\"lineNumber\":150},{\"declaringClass\"= :\"org.springframework.aop.interceptor.ExposeInvocationInterceptor\",\"meth= odName\":\"invoke\",\"fileName\":\"ExposeInvocationInterceptor.java\",\"lin= eNumber\":91},{\"declaringClass\":\"org.springframework.aop.framework.Refle= ctiveMethodInvocation\",\"methodName\":\"proceed\",\"fileName\":\"Reflectiv= eMethodInvocation.java\",\"lineNumber\":172},{\"declaringClass\":\"org.spri= ngframework.aop.framework.JdkDynamicAopProxy\",\"methodName\":\"invoke\",\"= fileName\":\"JdkDynamicAopProxy.java\",\"lineNumber\":204},{\"declaringClas= s\":\"$Proxy195\",\"methodName\":\"handleVmWorkJob\",\"lineNumber\":-1},{\"= declaringClass\":\"com.cloud.vm.VmWorkJobDispatcher\",\"methodName\":\"runJ= ob\",\"fileName\":\"VmWorkJobDispatcher.java\",\"lineNumber\":99},{\"declar= ingClass\":\"org.apache.cloudstack.framework.jobs.impl.AsyncJobManagerImpl$= 5\",\"methodName\":\"runInContext\",\"fileName\":\"AsyncJobManagerImpl.java= \",\"lineNumber\":524},{\"declaringClass\":\"org.apache.cloudstack.managed.= context.ManagedContextRunnable$1\",\"methodName\":\"run\",\"fileName\":\"Ma= nagedContextRunnable.java\",\"lineNumber\":49},{\"declaringClass\":\"org.ap= ache.cloudstack.managed.context.impl.DefaultManagedContext$1\",\"methodName= \":\"call\",\"fileName\":\"DefaultManagedContext.java\",\"lineNumber\":56},= {\"declaringClass\":\"org.apache.cloudstack.managed.context.impl.DefaultMan= agedContext\",\"methodName\":\"callWithContext\",\"fileName\":\"DefaultMana= gedContext.java\",\"lineNumber\":103},{\"declaringClass\":\"org.apache.clou= dstack.managed.context.impl.DefaultManagedContext\",\"methodName\":\"runWit= hContext\",\"fileName\":\"DefaultManagedContext.java\",\"lineNumber\":53},{= \"declaringClass\":\"org.apache.cloudstack.managed.context.ManagedContextRu= nnable\",\"methodName\":\"run\",\"fileName\":\"ManagedContextRunnable.java\= ",\"lineNumber\":46},{\"declaringClass\":\"java.util.concurrent.Executors$R= unnableAdapter\",\"methodName\":\"call\",\"fileName\":\"Executors.java\",\"= lineNumber\":471},{\"declaringClass\":\"java.util.concurrent.FutureTask$Syn= c\",\"methodName\":\"innerRun\",\"fileName\":\"FutureTask.java\",\"lineNumb= er\":334},{\"declaringClass\":\"java.util.concurrent.FutureTask\",\"methodN= ame\":\"run\",\"fileName\":\"FutureTask.java\",\"lineNumber\":166},{\"decla= ringClass\":\"java.util.concurrent.ThreadPoolExecutor\",\"methodName\":\"ru= nWorker\",\"fileName\":\"ThreadPoolExecutor.java\",\"lineNumber\":1110},{\"= declaringClass\":\"java.util.concurrent.ThreadPoolExecutor$Worker\",\"metho= dName\":\"run\",\"fileName\":\"ThreadPoolExecutor.java\",\"lineNumber\":603= },{\"declaringClass\":\"java.lang.Thread\",\"methodName\":\"run\",\"fileNam= e\":\"Thread.java\",\"lineNumber\":679}]}"} > nagedContext$1.call(DefaultManagedContext.java:56) > at org.apache.cloudstack.managed.context.impl.DefaultManagedConte= xt.callWithContext(DefaultManagedContext.java:103) > at org.apache.cloudstack.managed.context.impl.DefaultManagedConte= xt.runWithContext(DefaultManagedContext.java:53) > at org.apache.cloudstack.managed.context.ManagedContextRunnable.r= un(ManagedContextRunnable.java:46) > 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(ThreadPoolEx= ecutor.java:1110) > at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolE= xecutor.java:603) > at java.lang.Thread.run(Thread.java:679) > Caused by: java.io.StreamCorruptedException: invalid stream header: 72899= C96 > at java.io.ObjectInputStream.readStreamHeader(ObjectInputStream.j= ava:800) > at java.io.ObjectInputStream.(ObjectInputStream.java:297) > at org.apache.cloudstack.framework.jobs.impl.JobSerializerHelper.= fromObjectSerializedString(JobSerializerHelper.java:129) > ... 33 more > 2014-01-06 11:53:37,457 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl] (Job-Exec= utor-114:ctx-035abfcd) Complete async job-141, jobStatus: FAILED, resultCod= e: 530, result: org.apache.cloudstack.api.response.ExceptionResponse/null/{= "uuidList":[],"errorcode":530,"errortext":"Unable to serialize: com.cloud.u= tils.exception.CloudRuntimeException/{\"idList\":[],\"uuidList\":[],\"csErr= orCode\":4250,\"detailMessage\":\"copy volume failed: java.lang.NullPointer= Exception\",\"cause\":{\"class\":\"com.cloud.utils.exception.CloudRuntimeEx= ception\",\"msg\":\"copy volume failed: java.lang.NullPointerException\"},\= "stackTrace\":[{\"declaringClass\":\"org.apache.cloudstack.engine.orchestra= tion.VolumeOrchestrator\",\"methodName\":\"copyVolumeFromSecToPrimary\",\"f= ileName\":\"VolumeOrchestrator.java\",\"lineNumber\":412},{\"declaringClass= \":\"org.apache.cloudstack.engine.orchestration.VolumeOrchestrator\",\"meth= odName\":\"copyVolume\",\"fileName\":\"VolumeOrchestrator.java\",\"lineNumb= er\":718},{\"declaringClass\":\"org.apache.cloudstack.engine.orchestration.= VolumeOrchestrator\",\"methodName\":\"createVolumeOnPrimaryStorage\",\"file= Name\":\"VolumeOrchestrator.java\",\"lineNumber\":747},{\"declaringClass\":= \"com.cloud.storage.VolumeApiServiceImpl\",\"methodName\":\"attachVolumeToV= M\",\"fileName\":\"VolumeApiServiceImpl.java\",\"lineNumber\":1249},{\"decl= aringClass\":\"com.cloud.storage.VolumeApiServiceImpl\",\"methodName\":\"or= chestrateAttachVolumeToVM\",\"fileName\":\"VolumeApiServiceImpl.java\",\"li= neNumber\":1127},{\"declaringClass\":\"com.cloud.storage.VolumeApiServiceIm= pl\",\"methodName\":\"orchestrateAttachVolumeToVM\",\"fileName\":\"VolumeAp= iServiceImpl.java\",\"lineNumber\":2406},{\"declaringClass\":\"sun.reflect.= NativeMethodAccessorImpl\",\"methodName\":\"invoke0\",\"fileName\":\"Native= MethodAccessorImpl.java\",\"lineNumber\":-2},{\"declaringClass\":\"sun.refl= ect.NativeMethodAccessorImpl\",\"methodName\":\"invoke\",\"fileName\":\"Nat= iveMethodAccessorImpl.java\",\"lineNumber\":57},{\"declaringClass\":\"sun.r= eflect.DelegatingMethodAccessorImpl\",\"methodName\":\"invoke\",\"fileName\= ":\"DelegatingMethodAccessorImpl.java\",\"lineNumber\":43},{\"declaringClas= s\":\"java.lang.reflect.Method\",\"methodName\":\"invoke\",\"fileName\":\"M= ethod.java\",\"lineNumber\":616},{\"declaringClass\":\"com.cloud.vm.VmWorkJ= obHandlerProxy\",\"methodName\":\"handleVmWorkJob\",\"fileName\":\"VmWorkJo= bHandlerProxy.java\",\"lineNumber\":107},{\"declaringClass\":\"com.cloud.st= orage.VolumeApiServiceImpl\",\"methodName\":\"handleVmWorkJob\",\"fileName\= ":\"VolumeApiServiceImpl.java\",\"lineNumber\":2437},{\"declaringClass\":\"= sun.reflect.NativeMethodAccessorImpl\",\"methodName\":\"invoke0\",\"fileNam= e\":\"NativeMethodAccessorImpl.java\",\"lineNumber\":-2},{\"declaringClass\= ":\"sun.reflect.NativeMethodAccessorImpl\",\"methodName\":\"invoke\",\"file= Name\":\"NativeMethodAccessorImpl.java\",\"lineNumber\":57},{\"declaringCla= ss\":\"sun.reflect.DelegatingMethodAccessorImpl\",\"methodName\":\"invoke\"= ,\"fileName\":\"DelegatingMethodAccessorImpl.java\",\"lineNumber\":43},{\"d= eclaringClass\":\"java.lang.reflect.Method\",\"methodName\":\"invoke\",\"fi= leName\":\"Method.java\",\"lineNumber\":616},{\"declaringClass\":\"org.spri= ngframework.aop.support.AopUtils\",\"methodName\":\"invokeJoinpointUsingRef= lection\",\"fileName\":\"AopUtils.java\",\"lineNumber\":317},{\"declaringCl= ass\":\"org.springframework.aop.framework.ReflectiveMethodInvocation\",\"me= thodName\":\"invokeJoinpoint\",\"fileName\":\"ReflectiveMethodInvocation.ja= va\",\"lineNumber\":183},{\"declaringClass\":\"org.springframework.aop.fram= ework.ReflectiveMethodInvocation\",\"methodName\":\"proceed\",\"fileName\":= \"ReflectiveMethodInvocation.java\",\"lineNumber\":150},{\"declaringClass\"= :\"org.springframework.aop.interceptor.ExposeInvocationInterceptor\",\"meth= odName\":\"invoke\",\"fileName\":\"ExposeInvocationInterceptor.java\",\"lin= eNumber\":91},{\"declaringClass\":\"org.springframework.aop.framework.Refle= ctiveMethodInvocation\",\"methodName\":\"proceed\",\"fileName\":\"Reflectiv= eMethodInvocation.java\",\"lineNumber\":172},{\"declaringClass\":\"org.spri= ngframework.aop.framework.JdkDynamicAopProxy\",\"methodName\":\"invoke\",\"= fileName\":\"JdkDynamicAopProxy.java\",\"lineNumber\":204},{\"declaringClas= s\":\"$Proxy195\",\"methodName\":\"handleVmWorkJob\",\"lineNumber\":-1},{\"= declaringClass\":\"com.cloud.vm.VmWorkJobDispatcher\",\"methodName\":\"runJ= ob\",\"fileName\":\"VmWorkJobDispatcher.java\",\"lineNumber\":99},{\"declar= ingClass\":\"org.apache.cloudstack.framework.jobs.impl.AsyncJobManagerImpl$= 5\",\"methodName\":\"runInContext\",\"fileName\":\"AsyncJobManagerImpl.java= \",\"lineNumber\":524},{\"declaringClass\":\"org.apache.cloudstack.managed.= context.ManagedContextRunnable$1\",\"methodName\":\"run\",\"fileName\":\"Ma= nagedContextRunnable.java\",\"lineNumber\":49},{\"declaringClass\":\"org.ap= ache.cloudstack.managed.context.impl.DefaultManagedContext$1\",\"methodName= \":\"call\",\"fileName\":\"DefaultManagedContext.java\",\"lineNumber\":56},= {\"declaringClass\":\"org.apache.cloudstack.managed.context.impl.DefaultMan= agedContext\",\"methodName\":\"callWithContext\",\"fileName\":\"DefaultMana= gedContext.java\",\"lineNumber\":103},{\"declaringClass\":\"org.apache.clou= dstack.managed.context.impl.DefaultManagedContext\",\"methodName\":\"runWit= hContext\",\"fileName\":\"DefaultManagedContext.java\",\"lineNumber\":53},{= \"declaringClass\":\"org.apache.cloudstack.managed.context.ManagedContextRu= nnable\",\"methodName\":\"run\",\"fileName\":\"ManagedContextRunnable.java\= ",\"lineNumber\":46},{\"declaringClass\":\"java.util.concurrent.Executors$R= unnableAdapter\",\"methodName\":\"call\",\"fileName\":\"Executors.java\",\"= lineNumber\":471},{\"declaringClass\":\"java.util.concurrent.FutureTask$Syn= c\",\"methodName\":\"innerRun\",\"fileName\":\"FutureTask.java\",\"lineNumb= er\":334},{\"declaringClass\":\"java.util.concurrent.FutureTask\",\"methodN= ame\":\"run\",\"fileName\":\"FutureTask.java\",\"lineNumber\":166},{\"decla= ringClass\":\"java.util.concurrent.ThreadPoolExecutor\",\"methodName\":\"ru= nWorker\",\"fileName\":\"ThreadPoolExecutor.java\",\"lineNumber\":1110},{\"= declaringClass\":\"java.util.concurrent.ThreadPoolExecutor$Worker\",\"metho= dName\":\"run\",\"fileName\":\"ThreadPoolExecutor.java\",\"lineNumber\":603= },{\"declaringClass\":\"java.lang.Thread\",\"methodName\":\"run\",\"fileNam= e\":\"Thread.java\",\"lineNumber\":679}]}"} > 2014-01-06 11:53:37,454 WARN [o.a.c.f.j.i.AsyncJobManagerImpl] (Job-Exec= utor-116:ctx-9f6a5f51) job-141 is scheduled for wakeup run, but there is no= joining info anymore > 2014-01-06 11:53:37,461 ERROR [o.a.c.f.j.i.AsyncJobManagerImpl] (Job-Exec= utor-116:ctx-9f6a5f51) Unable to find a wakeup dispatcher from the joined j= ob: AsyncJobVO {id:141, userId: 2, accountId: 2, instanceType: Volume, inst= anceId: 19, cmd: org.apache.cloudstack.api.command.user.volume.AttachVolume= Cmd, cmdInfo: {"response":"json","id":"4d5e35e3-2a67-4d1e-8758-7c6b283866cd= ","sessionkey":"5ODiLDmKehMbGznMVT9sutQQg7U\u003d","cmdEventType":"VOLUME.A= TTACH","ctxUserId":"2","virtualMachineId":"62064ead-e49e-4bb7-86e7-7422f33e= 3f75","httpmethod":"GET","_":"1388989738235","ctxAccountId":"2","ctxStartEv= entId":"332"}, cmdVersion: 0, status: IN_PROGRESS, processStatus: 0, result= Code: 0, result: null, initMsid: 280320865129348, completeMsid: null, lastU= pdated: null, lastPolled: Mon Jan 06 11:53:36 IST 2014, created: Mon Jan 06= 11:53:27 IST 2014} > 2014-01-06 11:53:37,461 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl] (Job-Exec= utor-116:ctx-9f6a5f51) Done executing org.apache.cloudstack.api.command.use= r.volume.AttachVolumeCmd for job-141 > 2014-01-06 11:53:37,465 INFO [o.a.c.f.j.i.AsyncJobMonitor] (Job-Executor= -116:ctx-9f6a5f51) Remove job-141 from job monitoring > 2014-01-06 11:53:37,468 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl] (Job-Exec= utor-114:ctx-035abfcd) Done executing org.apache.cloudstack.api.command.use= r.volume.AttachVolumeCmd for job-141 > Agent logs : > ------------------------------------ > 2014-01-06 07:06:11,293 [24] INFO HypervResource.HypervResourceControlle= r [ee43f27c-cfea-4abc-b715-4114af3bc691] - org.apache.cloudstack.storage.co= mmand.CopyCommand{ > "srcTO": { > "org.apache.cloudstack.storage.to.VolumeObjectTO": { > "uuid": "88a1e01c-105b-4c6a-a443-513ad65d0447", > "volumeType": "DATADISK", > "dataStore": { > "com.cloud.agent.api.to.NfsTO": { > "_url": "cifs://10.102.192.19/hyperv-share/abhinav-hyperv-ss1?u= ser=3Dabhinavroy&password=3Dfreebsd@123&domain=3DBLR", > "_role": "Image" > } > }, > "name": "av-upload2", > "size": 5368709120, > "path": "volumes/2/26/93f17813-1e10-324e-b276-47ec9ed813c0.vhd", > "volumeId": 26, > "accountId": 2, > "format": "VHD", > "id": 26, > "hypervisorType": "Hyperv" > } > }, > "destTO": { > "org.apache.cloudstack.storage.to.VolumeObjectTO": { > "uuid": "88a1e01c-105b-4c6a-a443-513ad65d0447", > "volumeType": "DATADISK", > "dataStore": { > "org.apache.cloudstack.storage.to.PrimaryDataStoreTO": { > "uuid": "088db467-32e3-3d0a-bca0-20e3382fe902", > "id": 1, > "poolType": "NetworkFilesystem", > "host": "SMB19", > "path": "/hyperv-share/abhinav-hyperv-ps1?user=3Dabhinavroy&pas= sword=3Dfreebsd@123&domain=3DBLR", > "port": 445, > "url": "NetworkFilesystem://SMB19//hyperv-share/abhinav-hyperv-= ps1?user=3Dabhinavroy&password=3Dfreebsd@123&domain=3DBLR/?ROLE=3DPrimary&S= TOREUUID=3D088db467-32e3-3d0a-bca0-20e3382fe902" > } > }, > "name": "av-upload2", > "size": 5368709120, > "volumeId": 26, > "accountId": 2, > "format": "VHD", > "id": 26, > "hypervisorType": "Hyperv" > } > }, > "executeInSequence": false, > "options": {}, > "contextMap": {}, > "wait": 10800 > } > 2014-01-06 07:06:31,215 [22] INFO HypervResource.HypervResourceControlle= r [fb446b9b-fe66-41e6-a652-fa149475526e] - com.cloud.agent.api.GetHostStats= Command{ > "hostGuid": "23ca5fe2-a504-3bac-83a5-519315d0bc3d-HypervResource", > "hostName": "10.102.192.14", > "hostId": 5, > "contextMap": {}, > "wait": 0 > } > 2014-01-06 07:06:31,574 [22] INFO HypervResource.HypervResourceControlle= r [fb446b9b-fe66-41e6-a652-fa149475526e] - { > "com.cloud.agent.api.GetHostStatsAnswer": { > "result": true, > "hostStats": { > "hostId": 5, > "entityType": "host", > "cpuUtilization": 5.0, > "networkReadKBs": 0.0, > "networkWriteKBs": 0.0, > "totalMemoryKBs": 16763980.0, > "freeMemoryKBs": 13853540.0 > }, > "details": null, > "contextMap": {} > } > } > 2014-01-06 07:06:33,871 [24] ERROR HypervResource.VolumeObjectTO [ee43f27= c-cfea-4abc-b715-4114af3bc691] - Invalid dataStore in VolumeObjectTO spec > 2014-01-06 07:06:33,871 [24] ERROR HypervResource.HypervResourceControlle= r [ee43f27c-cfea-4abc-b715-4114af3bc691] - org.apache.cloudstack.storage.co= mmand.CopyCommand failed on exception, Error getting value from 'FullFileNa= me' on 'HypervResource.VolumeObjectTO'. > Newtonsoft.Json.JsonSerializationException: Error getting value from 'Ful= lFileName' on 'HypervResource.VolumeObjectTO'. ---> System.IO.InvalidDataEx= ception: Invalid dataStore in VolumeObjectTO spec > at HypervResource.VolumeObjectTO.get_FullFileName() > at GetFullFileName(Object ) > at Newtonsoft.Json.Serialization.DynamicValueProvider.GetValue(Object = target) > --- End of inner exception stack trace --- > at Newtonsoft.Json.Serialization.DynamicValueProvider.GetValue(Object = target) > at Newtonsoft.Json.Serialization.JsonSerializerInternalWriter.Calculat= ePropertyValues(JsonWriter writer, Object value, JsonContainerContract cont= ract, JsonProperty member, JsonProperty property, JsonContract& memberContr= act, Object& memberValue) > at Newtonsoft.Json.Serialization.JsonSerializerInternalWriter.Serializ= eObject(JsonWriter writer, Object value, JsonObjectContract contract, JsonP= roperty member, JsonContainerContract collectionContract, JsonProperty cont= ainerProperty) > at Newtonsoft.Json.Serialization.JsonSerializerInternalWriter.Serializ= eValue(JsonWriter writer, Object value, JsonContract valueContract, JsonPro= perty member, JsonContainerContract containerContract, JsonProperty contain= erProperty) > at Newtonsoft.Json.Serialization.JsonSerializerInternalWriter.Serializ= e(JsonWriter jsonWriter, Object value) > at Newtonsoft.Json.JsonSerializer.SerializeInternal(JsonWriter jsonWri= ter, Object value) > at Newtonsoft.Json.Linq.JToken.FromObjectInternal(Object o, JsonSerial= izer jsonSerializer) > at Newtonsoft.Json.Linq.JToken.FromObject(Object o) > at HypervResource.Utils.CreateCloudStackObject(String objType, Object = objValue) > at HypervResource.HypervResourceController.CopyCommand(Object cmd) > 2014-01-06 07:06:33,871 [24] INFO HypervResource.HypervResourceControlle= r [ee43f27c-cfea-4abc-b715-4114af3bc691] - { > "org.apache.cloudstack.storage.command.CopyCmdAnswer": { > "result": false, > "details": "org.apache.cloudstack.storage.command.CopyCommand failed = on exception, Error getting value from 'FullFileName' on 'HypervResource.Vo= lumeObjectTO'.", > "newData": null, > "contextMap": {} > } > } -- This message was sent by Atlassian JIRA (v6.1.5#6160)