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 00B5C10A45 for ; Fri, 3 Jan 2014 16:27:59 +0000 (UTC) Received: (qmail 65243 invoked by uid 500); 3 Jan 2014 16:27:58 -0000 Delivered-To: apmail-cloudstack-issues-archive@cloudstack.apache.org Received: (qmail 65044 invoked by uid 500); 3 Jan 2014 16:27:53 -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 64872 invoked by uid 500); 3 Jan 2014 16:27:50 -0000 Delivered-To: apmail-incubator-cloudstack-issues@incubator.apache.org Received: (qmail 64561 invoked by uid 99); 3 Jan 2014 16:27:50 -0000 Received: from arcas.apache.org (HELO arcas.apache.org) (140.211.11.28) by apache.org (qpsmtpd/0.29) with ESMTP; Fri, 03 Jan 2014 16:27:50 +0000 Date: Fri, 3 Jan 2014 16:27:50 +0000 (UTC) From: "Rayees Namathponnan (JIRA)" To: cloudstack-issues@incubator.apache.org Message-ID: In-Reply-To: References: Subject: [jira] [Resolved] (CLOUDSTACK-5766) [Automation] NPE while migration virtual machine 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-5766?page=3Dcom.atl= assian.jira.plugin.system.issuetabpanels:all-tabpanel ] Rayees Namathponnan resolved CLOUDSTACK-5766. --------------------------------------------- Resolution: Duplicate > [Automation] NPE while migration virtual machine > ------------------------------------------------ > > Key: CLOUDSTACK-5766 > URL: https://issues.apache.org/jira/browse/CLOUDSTACK-576= 6 > Project: CloudStack > Issue Type: Bug > Security Level: Public(Anyone can view this level - this is the defa= ult.)=20 > Components: Management Server > Affects Versions: 4.3.0 > Environment: xenserver 6.2 > advanced zone > Reporter: Srikanteswararao Talluri > Priority: Blocker > Fix For: 4.3.0 > > > steps to reproduce: > 1. migrate a virtual machine to another available host in xenserver pool > =3D=3D=3DSTART=3D=3D=3D 10.252.192.57 -- GET command=3DmigrateVirtualMa= chine&hostid=3Dca564580-b195-4094-8a60-bcbdf28059ed&virtualmachineid=3D4561= 434a-7f4e-4e1b-95d9-c820fd566cf2&response=3Djson&sessionkey=3D7razDgyA%2Ft1= Sgdr1XRCXVV1n2RE%3D&_=3D1388758756482 > 2014-01-04 01:09:20,327 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl] (catalina= -exec-24:ctx-045cf2c3 ctx-01e7c6a6) submit async job-593, details: AsyncJob= VO {id:593, userId: 2, accountId: 2, instanceType: None, instanceId: null, = cmd: org.apache.cloudstack.api.command.admin.vm.MigrateVMCmd, cmdInfo: {"re= sponse":"json","sessionkey":"7razDgyA/t1Sgdr1XRCXVV1n2RE\u003d","virtualmac= hineid":"4561434a-7f4e-4e1b-95d9-c820fd566cf2","cmdEventType":"VM.MIGRATE",= "hostid":"ca564580-b195-4094-8a60-bcbdf28059ed","ctxUserId":"2","httpmethod= ":"GET","_":"1388758756482","ctxAccountId":"2","ctxStartEventId":"2073"}, c= mdVersion: 0, status: IN_PROGRESS, processStatus: 0, resultCode: 0, result:= null, initMsid: 6631563722783, completeMsid: null, lastUpdated: null, last= Polled: null, created: null} > 2014-01-04 01:09:20,329 DEBUG [c.c.a.ApiServlet] (catalina-exec-24:ctx-04= 5cf2c3 ctx-01e7c6a6) =3D=3D=3DEND=3D=3D=3D 10.252.192.57 -- GET command= =3DmigrateVirtualMachine&hostid=3Dca564580-b195-4094-8a60-bcbdf28059ed&virt= ualmachineid=3D4561434a-7f4e-4e1b-95d9-c820fd566cf2&response=3Djson&session= key=3D7razDgyA%2Ft1Sgdr1XRCXVV1n2RE%3D&_=3D1388758756482 > 2014-01-04 01:09:20,336 INFO [o.a.c.f.j.i.AsyncJobMonitor] (Job-Executor= -30:ctx-64b33f9f) Add job-593 into job monitoring > 2014-01-04 01:09:20,336 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl] (Job-Exec= utor-30:ctx-64b33f9f) Executing AsyncJobVO {id:593, userId: 2, accountId: 2= , instanceType: None, instanceId: null, cmd: org.apache.cloudstack.api.comm= and.admin.vm.MigrateVMCmd, cmdInfo: {"response":"json","sessionkey":"7razDg= yA/t1Sgdr1XRCXVV1n2RE\u003d","virtualmachineid":"4561434a-7f4e-4e1b-95d9-c8= 20fd566cf2","cmdEventType":"VM.MIGRATE","hostid":"ca564580-b195-4094-8a60-b= cbdf28059ed","ctxUserId":"2","httpmethod":"GET","_":"1388758756482","ctxAcc= ountId":"2","ctxStartEventId":"2073"}, cmdVersion: 0, status: IN_PROGRESS, = processStatus: 0, resultCode: 0, result: null, initMsid: 6631563722783, com= pleteMsid: null, lastUpdated: null, lastPolled: null, created: null} > 2014-01-04 01:09:20,420 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl] (Job-Exec= utor-30:ctx-64b33f9f ctx-01e7c6a6) Sync job-594 execution on object VmWorkJ= obQueue.90 > 2014-01-04 01:09:21,201 DEBUG [c.c.a.ApiServlet] (catalina-exec-13:ctx-84= 21a3e0) =3D=3D=3DSTART=3D=3D=3D 10.147.38.149 -- GET signature=3DwqgfbCPG= PfzFD18638tlhCOrNgY%3D&apiKey=3DCmB8bjoITlrdKIDjDmEH2AfUefYO-vv5GnylOL4jUfd= b3tfNRuRCeQWytcnwxyen1OdeNfa1EPoBOjIuAhV7JA&command=3DqueryAsyncJobResult&r= esponse=3Djson&jobid=3D1a74c10e-6ff3-42cb-9884-edef05dd5e3b > 2014-01-04 01:09:21,244 DEBUG [c.c.c.ConsoleProxyManagerImpl] (consolepro= xy-1:ctx-fa7a9fd4) Zone 1 is ready to launch console proxy > 2014-01-04 01:09:21,250 DEBUG [c.c.a.ApiServlet] (catalina-exec-13:ctx-84= 21a3e0 ctx-0ff139b2 ctx-7481681b) =3D=3D=3DEND=3D=3D=3D 10.147.38.149 -- G= ET signature=3DwqgfbCPGPfzFD18638tlhCOrNgY%3D&apiKey=3DCmB8bjoITlrdKIDjDmE= H2AfUefYO-vv5GnylOL4jUfdb3tfNRuRCeQWytcnwxyen1OdeNfa1EPoBOjIuAhV7JA&command= =3DqueryAsyncJobResult&response=3Djson&jobid=3D1a74c10e-6ff3-42cb-9884-edef= 05dd5e3b > 2014-01-04 01:09:21,270 DEBUG [c.c.c.ConsoleProxyManagerImpl] (consolepro= xy-1:ctx-fa7a9fd4) Zone 2 is ready to launch console proxy > 2014-01-04 01:09:21,482 DEBUG [c.c.s.s.SecondaryStorageManagerImpl] (secs= torage-1:ctx-06667971) Zone 1 is ready to launch secondary storage VM > 2014-01-04 01:09:21,520 DEBUG [c.c.s.s.SecondaryStorageManagerImpl] (secs= torage-1:ctx-06667971) Zone 2 is ready to launch secondary storage VM > 2014-01-04 01:09:22,037 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl] (AsyncJob= Mgr-Heartbeat-1:ctx-3e6ab8a9) Execute sync-queue item: SyncQueueItemVO {id:= 167, queueId: 146, contentType: AsyncJob, contentId: 594, lastProcessMsid: = null, lastprocessNumber: null, lastProcessTime: null, created: Sat Jan 04 0= 1:09:20 IST 2014} > 2014-01-04 01:09:22,040 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl] (AsyncJob= Mgr-Heartbeat-1:ctx-3e6ab8a9) Schedule queued job-594 > 2014-01-04 01:09:22,048 INFO [o.a.c.f.j.i.AsyncJobMonitor] (Job-Executor= -51:ctx-9a705049) Add job-594 into job monitoring > 2014-01-04 01:09:22,049 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl] (Job-Exec= utor-51:ctx-9a705049) Executing AsyncJobVO {id:594, userId: 2, accountId: 2= , instanceType: null, instanceId: null, cmd: com.cloud.vm.VmWorkMigrate, cm= dInfo: rO0ABXNyABpjb20uY2xvdWQudm0uVm1Xb3JrTWlncmF0ZRdxQXtPtzYqAgAGSgAJc3Jj= SG9zdElkTAAJY2x1c3RlcklkdAAQTGphdmEvbGFuZy9Mb25nO0wABmhvc3RJZHEAfgABTAAFcG9= kSWRxAH4AAUwAB3N0b3JhZ2V0AA9MamF2YS91dGlsL01hcDtMAAZ6b25lSWRxAH4AAXhyABNjb2= 0uY2xvdWQudm0uVm1Xb3Jrn5m2VvAlZ2sCAARKAAlhY2NvdW50SWRKAAZ1c2VySWRKAAR2bUlkT= AALaGFuZGxlck5hbWV0ABJMamF2YS9sYW5nL1N0cmluZzt4cAAAAAAAAAACAAAAAAAAAAIAAAAA= AAAAWnQAGVZpcnR1YWxNYWNoaW5lTWFuYWdlckltcGwAAAAAAAAAAXNyAA5qYXZhLmxhbmcuTG9= uZzuL5JDMjyPfAgABSgAFdmFsdWV4cgAQamF2YS5sYW5nLk51bWJlcoaslR0LlOCLAgAAeHAAAA= AAAAAAAXNxAH4ABwAAAAAAAAACcQB-AAlwcQB-AAk, cmdVersion: 0, status: IN_PROGRE= SS, processStatus: 0, resultCode: 0, result: null, initMsid: 6631563722783,= completeMsid: null, lastUpdated: null, lastPolled: null, created: Sat Jan = 04 01:09:20 IST 2014} > 2014-01-04 01:09:22,049 DEBUG [c.c.v.VmWorkJobDispatcher] (Job-Executor-5= 1:ctx-9a705049) Run VM work job: com.cloud.vm.VmWorkMigrate > 2014-01-04 01:09:22,052 DEBUG [c.c.v.VmWorkJobHandlerProxy] (Job-Executor= -51:ctx-9a705049 ctx-01e7c6a6) Execute VM work job: com.cloud.vm.VmWorkMigr= ate{"zoneId":1,"podId":1,"clusterId":1,"hostId":2,"srcHostId":1,"userId":2,= "accountId":2,"vmId":90,"handlerName":"VirtualMachineManagerImpl"} > 2014-01-04 01:09:22,059 ERROR [c.c.v.VmWorkJobHandlerProxy] (Job-Executor= -51:ctx-9a705049 ctx-01e7c6a6) Invocation exception, caused by: java.lang.N= ullPointerException > 2014-01-04 01:09:22,059 ERROR [c.c.v.VmWorkJobDispatcher] (Job-Executor-5= 1:ctx-9a705049 ctx-01e7c6a6) Unable to complete AsyncJobVO {id:594, userId:= 2, accountId: 2, instanceType: null, instanceId: null, cmd: com.cloud.vm.V= mWorkMigrate, cmdInfo: rO0ABXNyABpjb20uY2xvdWQudm0uVm1Xb3JrTWlncmF0ZRdxQXtP= tzYqAgAGSgAJc3JjSG9zdElkTAAJY2x1c3RlcklkdAAQTGphdmEvbGFuZy9Mb25nO0wABmhvc3R= JZHEAfgABTAAFcG9kSWRxAH4AAUwAB3N0b3JhZ2V0AA9MamF2YS91dGlsL01hcDtMAAZ6b25lSW= RxAH4AAXhyABNjb20uY2xvdWQudm0uVm1Xb3Jrn5m2VvAlZ2sCAARKAAlhY2NvdW50SWRKAAZ1c= 2VySWRKAAR2bUlkTAALaGFuZGxlck5hbWV0ABJMamF2YS9sYW5nL1N0cmluZzt4cAAAAAAAAAAC= AAAAAAAAAAIAAAAAAAAAWnQAGVZpcnR1YWxNYWNoaW5lTWFuYWdlckltcGwAAAAAAAAAAXNyAA5= qYXZhLmxhbmcuTG9uZzuL5JDMjyPfAgABSgAFdmFsdWV4cgAQamF2YS5sYW5nLk51bWJlcoaslR= 0LlOCLAgAAeHAAAAAAAAAAAXNxAH4ABwAAAAAAAAACcQB-AAlwcQB-AAk, cmdVersion: 0, s= tatus: IN_PROGRESS, processStatus: 0, resultCode: 0, result: null, initMsid= : 6631563722783, completeMsid: null, lastUpdated: null, lastPolled: null, c= reated: Sat Jan 04 01:09:20 IST 2014} > java.lang.NullPointerException > =09at com.cloud.vm.VmWorkMigrate.getDeployDestination(VmWorkMigrate.java:= 60) > =09at com.cloud.vm.VirtualMachineManagerImpl.orchestrateMigrate(VirtualMa= chineManagerImpl.java:4758) > =09at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) > =09at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImp= l.java:57) > =09at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAcc= essorImpl.java:43) > =09at java.lang.reflect.Method.invoke(Method.java:616) > =09at com.cloud.vm.VmWorkJobHandlerProxy.handleVmWorkJob(VmWorkJobHandler= Proxy.java:107) > =09at com.cloud.vm.VirtualMachineManagerImpl.handleVmWorkJob(VirtualMachi= neManagerImpl.java:4855) > =09at com.cloud.vm.VmWorkJobDispatcher.runJob(VmWorkJobDispatcher.java:99= ) > =09at org.apache.cloudstack.framework.jobs.impl.AsyncJobManagerImpl$5.run= InContext(AsyncJobManagerImpl.java:524) > =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.ThreadPoolExecutor.runWorker(ThreadPoolExecuto= r.java:1110) > =09at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecut= or.java:603) > =09at java.lang.Thread.run(Thread.java:679) > 2014-01-04 01:09:22,082 ERROR [o.a.c.f.j.i.AsyncJobManagerImpl] (Job-Exec= utor-51:ctx-9a705049) Unexpected exception > java.lang.NullPointerException > =09at com.google.gson.JsonPrimitive.isPrimitiveOrString(JsonPrimitive.jav= a:316) > =09at com.google.gson.JsonPrimitive.setValue(JsonPrimitive.java:97) > =09at com.google.gson.JsonPrimitive.(JsonPrimitive.java:67) > =09at org.apache.cloudstack.framework.jobs.impl.JobSerializerHelper$Throw= ableTypeAdapter.serialize(JobSerializerHelper.java:195) > =09at org.apache.cloudstack.framework.jobs.impl.JobSerializerHelper$Throw= ableTypeAdapter.serialize(JobSerializerHelper.java:158) > =09at com.google.gson.JsonSerializationVisitor.findAndInvokeCustomSeriali= zer(JsonSerializationVisitor.java:184) > =09at com.google.gson.JsonSerializationVisitor.visitFieldUsingCustomHandl= er(JsonSerializationVisitor.java:204) > =09at com.google.gson.ReflectingFieldNavigator.visitFieldsReflectively(Re= flectingFieldNavigator.java:63) > =09at com.google.gson.ObjectNavigator.accept(ObjectNavigator.java:120) > =09at com.google.gson.JsonSerializationContextDefault.serialize(JsonSeria= lizationContextDefault.java:62) > =09at com.google.gson.JsonSerializationContextDefault.serialize(JsonSeria= lizationContextDefault.java:53) > =09at com.google.gson.Gson.toJsonTree(Gson.java:220) > =09at com.google.gson.Gson.toJson(Gson.java:260) > =09at com.google.gson.Gson.toJson(Gson.java:240) > =09at org.apache.cloudstack.framework.jobs.impl.JobSerializerHelper.toSer= ializedString(JobSerializerHelper.java:65) > =09at com.cloud.vm.VmWorkJobDispatcher.runJob(VmWorkJobDispatcher.java:10= 5) > =09at org.apache.cloudstack.framework.jobs.impl.AsyncJobManagerImpl$5.run= InContext(AsyncJobManagerImpl.java:524) > =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.ThreadPoolExecutor.runWorker(ThreadPoolExecuto= r.java:1110) > =09at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecut= or.java:603) > =09at java.lang.Thread.run(Thread.java:679) > 2014-01-04 01:09:22,086 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl] (Job-Exec= utor-51:ctx-9a705049) Complete async job-594, jobStatus: FAILED, resultCode= : 530, result: null > 2014-01-04 01:09:22,101 INFO [o.a.c.f.j.i.AsyncJobMonitor] (Job-Executor= -60:ctx-2a961988) Add job-593 into job monitoring > 2014-01-04 01:09:22,102 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl] (Job-Exec= utor-60:ctx-2a961988) Executing AsyncJobVO {id:593, userId: 2, accountId: 2= , instanceType: None, instanceId: null, cmd: org.apache.cloudstack.api.comm= and.admin.vm.MigrateVMCmd, cmdInfo: {"response":"json","sessionkey":"7razDg= yA/t1Sgdr1XRCXVV1n2RE\u003d","virtualmachineid":"4561434a-7f4e-4e1b-95d9-c8= 20fd566cf2","cmdEventType":"VM.MIGRATE","hostid":"ca564580-b195-4094-8a60-b= cbdf28059ed","ctxUserId":"2","httpmethod":"GET","_":"1388758756482","ctxAcc= ountId":"2","ctxStartEventId":"2073"}, cmdVersion: 0, status: IN_PROGRESS, = processStatus: 0, resultCode: 0, result: null, initMsid: 6631563722783, com= pleteMsid: null, lastUpdated: null, lastPolled: null, created: Sat Jan 04 0= 1:09:20 IST 2014} > 2014-01-04 01:09:22,127 ERROR [o.a.c.f.j.i.AsyncJobManagerImpl] (Job-Exec= utor-60:ctx-2a961988) Unable to find a wakeup dispatcher from the joined jo= b: AsyncJobVO {id:593, userId: 2, accountId: 2, instanceType: None, instanc= eId: null, cmd: org.apache.cloudstack.api.command.admin.vm.MigrateVMCmd, cm= dInfo: {"response":"json","sessionkey":"7razDgyA/t1Sgdr1XRCXVV1n2RE\u003d",= "virtualmachineid":"4561434a-7f4e-4e1b-95d9-c820fd566cf2","cmdEventType":"V= M.MIGRATE","hostid":"ca564580-b195-4094-8a60-bcbdf28059ed","ctxUserId":"2",= "httpmethod":"GET","_":"1388758756482","ctxAccountId":"2","ctxStartEventId"= :"2073"}, cmdVersion: 0, status: IN_PROGRESS, processStatus: 0, resultCode:= 0, result: null, initMsid: 6631563722783, completeMsid: null, lastUpdated:= null, lastPolled: null, created: Sat Jan 04 01:09:20 IST 2014} > 2014-01-04 01:09:22,154 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl] (Job-Exec= utor-60:ctx-2a961988) Done executing org.apache.cloudstack.api.command.admi= n.vm.MigrateVMCmd for job-593 > 2014-01-04 01:09:22,162 INFO [o.a.c.f.j.i.AsyncJobMonitor] (Job-Executor= -60:ctx-2a961988) Remove job-593 from job monitoring > 2014-01-04 01:09:22,168 DEBUG [o.a.c.f.j.i.SyncQueueManagerImpl] (Job-Exe= cutor-51:ctx-9a705049) Sync queue (146) is currently empty > 2014-01-04 01:09:22,169 INFO [o.a.c.f.j.i.AsyncJobMonitor] (Job-Executor= -51:ctx-9a705049) Remove job-594 from job monitoring > 2014-01-04 01:09:22,191 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl] (Job-Exec= utor-30:ctx-64b33f9f ctx-01e7c6a6) Complete async job-593, jobStatus: SUCCE= EDED, resultCode: 0, result: org.apache.cloudstack.api.response.UserVmRespo= nse/virtualmachine/{"id":"4561434a-7f4e-4e1b-95d9-c820fd566cf2","name":"t",= "displayname":"t","account":"admin","domainid":"4508cc0e-7497-11e3-b905-060= 80800001f","domain":"ROOT"," -- This message was sent by Atlassian JIRA (v6.1.5#6160)