[ https://issues.apache.org/jira/browse/CLOUDSTACK-3554?page=com.atlassian.jira.plugin.system.issuetabpanels:all-tabpanel ]
Venkata Siva Vijayendra Bhamidipati resolved CLOUDSTACK-3554. ------------------------------------------------------------- Resolution: Fixed > NPE while attaching VMWARE Tools ISO to the instance > ----------------------------------------------------- > > Key: CLOUDSTACK-3554 > URL: https://issues.apache.org/jira/browse/CLOUDSTACK-3554 > Project: CloudStack > Issue Type: Bug > Security Level: Public(Anyone can view this level - this is the > default.) > Components: Storage Controller > Affects Versions: 4.2.0 > Reporter: Sailaja Mada > Assignee: Venkata Siva Vijayendra Bhamidipati > Priority: Blocker > Fix For: 4.2.0 > > Attachments: apilog.log, management-server.log > > > Steps: > 1. Configure Adv Zone with VMWARE > 2. Deploy instance with newly added account > 3. Tried to attach VMWARE tools iso to this instance > Observation: > It failed with NPE: > 2013-07-16 17:22:43,089 DEBUG [cloud.api.ApiServlet] (catalina-exec-18:null) > ===START=== 10.144.6.19 -- GET > command=attachIso&virtualmachineid=0f7b7a84-2325-4100-817a-e63e216c2b75&id=e09209bc-4731-4aac-b65e-00aed3540993&response=json&sessionkey=5vYbKOzqMAqkmN9vVQ%2F695HcVDs%3D&_=1373975773858 > 2013-07-16 17:22:43,146 DEBUG [cloud.async.AsyncJobManagerImpl] > (catalina-exec-18:null) submit async job-77 = [ > 24362d6b-4eb7-48d0-893a-2ac61fa84d5e ], details: AsyncJobVO {id:77, userId: > 8, accountId: 8, sessionKey: null, instanceType: None, instanceId: null, cmd: > org.apache.cloudstack.api.command.user.iso.AttachIsoCmd, cmdOriginator: null, > cmdInfo: > {"id":"e09209bc-4731-4aac-b65e-00aed3540993","response":"json","sessionkey":"5vYbKOzqMAqkmN9vVQ/695HcVDs\u003d","virtualmachineid":"0f7b7a84-2325-4100-817a-e63e216c2b75","cmdEventType":"ISO.ATTACH","ctxUserId":"8","httpmethod":"GET","_":"1373975773858","ctxAccountId":"8","ctxStartEventId":"299"}, > cmdVersion: 0, callbackType: 0, callbackAddress: null, status: 0, > processStatus: 0, resultCode: 0, result: null, initMsid: 187767034175903, > completeMsid: null, lastUpdated: null, lastPolled: null, created: null} > 2013-07-16 17:22:43,149 DEBUG [cloud.api.ApiServlet] (catalina-exec-18:null) > ===END=== 10.144.6.19 -- GET > command=attachIso&virtualmachineid=0f7b7a84-2325-4100-817a-e63e216c2b75&id=e09209bc-4731-4aac-b65e-00aed3540993&response=json&sessionkey=5vYbKOzqMAqkmN9vVQ%2F695HcVDs%3D&_=1373975773858 > 2013-07-16 17:22:43,152 DEBUG [cloud.async.AsyncJobManagerImpl] > (Job-Executor-18:job-77 = [ 24362d6b-4eb7-48d0-893a-2ac61fa84d5e ]) Executing > org.apache.cloudstack.api.command.user.iso.AttachIsoCmd for job-77 = [ > 24362d6b-4eb7-48d0-893a-2ac61fa84d5e ] > 2013-07-16 17:22:43,186 DEBUG [cloud.user.AccountManagerImpl] > (Job-Executor-18:job-77 = [ 24362d6b-4eb7-48d0-893a-2ac61fa84d5e ]) Access to > Tmpl[200-ISO-vmware-tools.iso granted to Acct[8-dc2user1] by > DomainChecker_EnhancerByCloudStack_1028f2c > 2013-07-16 17:22:43,188 DEBUG [cloud.user.AccountManagerImpl] > (Job-Executor-18:job-77 = [ 24362d6b-4eb7-48d0-893a-2ac61fa84d5e ]) Access to > VM[User|dc2user1i1] granted to Acct[8-dc2user1] by > DomainChecker_EnhancerByCloudStack_1028f2c > 2013-07-16 17:22:43,192 DEBUG [cloud.user.AccountManagerImpl] > (Job-Executor-18:job-77 = [ 24362d6b-4eb7-48d0-893a-2ac61fa84d5e ]) Access to > Tmpl[200-ISO-vmware-tools.iso granted to Acct[8-dc2user1] by > DomainChecker_EnhancerByCloudStack_1028f2c > 2013-07-16 17:22:43,194 DEBUG [cloud.user.AccountManagerImpl] > (Job-Executor-18:job-77 = [ 24362d6b-4eb7-48d0-893a-2ac61fa84d5e ]) Access to > VM[User|dc2user1i1] granted to Acct[8-dc2user1] by > DomainChecker_EnhancerByCloudStack_1028f2c > 2013-07-16 17:22:43,209 DEBUG [agent.transport.Request] > (Job-Executor-18:job-77 = [ 24362d6b-4eb7-48d0-893a-2ac61fa84d5e ]) Seq > 4-1669464340: Sending { Cmd , MgmtId: 187767034175903, via: 4, Ver: v1, > Flags: 100011, > [{"org.apache.cloudstack.storage.command.AttachCommand":{"disk":{"data":{"org.apache.cloudstack.storage.to.TemplateObjectTO":{"uuid":"e09209bc-4731-4aac-b65e-00aed3540993","id":200,"format":"ISO","accountId":1,"hvm":true,"displayText":"VMware > Tools Installer > ISO","name":"vmware-tools.iso"}},"type":"ISO"},"vmName":"i-8-13-VM","_storagePort":0,"_managed":false,"wait":0}}] > } > 2013-07-16 17:22:43,210 DEBUG [agent.transport.Request] > (Job-Executor-18:job-77 = [ 24362d6b-4eb7-48d0-893a-2ac61fa84d5e ]) Seq > 4-1669464340: Executing: { Cmd , MgmtId: 187767034175903, via: 4, Ver: v1, > Flags: 100011, > [{"org.apache.cloudstack.storage.command.AttachCommand":{"disk":{"data":{"org.apache.cloudstack.storage.to.TemplateObjectTO":{"uuid":"e09209bc-4731-4aac-b65e-00aed3540993","id":200,"format":"ISO","accountId":1,"hvm":true,"displayText":"VMware > Tools Installer > ISO","name":"vmware-tools.iso"}},"type":"ISO"},"vmName":"i-8-13-VM","_storagePort":0,"_managed":false,"wait":0}}] > } > 2013-07-16 17:22:43,211 DEBUG [agent.manager.DirectAgentAttache] > (DirectAgent-191:null) Seq 4-1669464340: Executing request > 2013-07-16 17:22:43,211 DEBUG [vmware.mo.HostMO] > (DirectAgent-191:10.102.192.23) find VM i-8-13-VM on host > 2013-07-16 17:22:43,211 DEBUG [vmware.mo.HostMO] > (DirectAgent-191:10.102.192.23) load VM cache on host > 2013-07-16 17:22:43,221 ERROR [storage.resource.VmwareStorageProcessor] > (DirectAgent-191:10.102.192.23) AttachIsoCommand(attach) failed due to > Exception: java.lang.NullPointerException > Message: null > java.lang.NullPointerException > at > com.cloud.storage.resource.VmwareStorageProcessor.attachIso(VmwareStorageProcessor.java:872) > at > com.cloud.storage.resource.VmwareStorageProcessor.attachIso(VmwareStorageProcessor.java:768) > at > com.cloud.storage.resource.StorageSubsystemCommandHandlerBase.execute(StorageSubsystemCommandHandlerBase.java:125) > at > com.cloud.storage.resource.StorageSubsystemCommandHandlerBase.handleStorageCommands(StorageSubsystemCommandHandlerBase.java:55) > at > com.cloud.hypervisor.vmware.resource.VmwareResource.executeRequest(VmwareResource.java:565) > at > com.cloud.agent.manager.DirectAgentAttache$Task.run(DirectAgentAttache.java:186) > 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.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$101(ScheduledThreadPoolExecutor.java:165) > at > java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:266) > 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-07-16 17:22:43,222 DEBUG [agent.manager.DirectAgentAttache] > (DirectAgent-191:null) Seq 4-1669464340: Response Received: > 2013-07-16 17:22:43,223 DEBUG [agent.transport.Request] > (DirectAgent-191:null) Seq 4-1669464340: Processing: { Ans: , MgmtId: > 187767034175903, via: 4, Ver: v1, Flags: 10, > [{"org.apache.cloudstack.storage.command.AttachAnswer":{"result":false,"details":"AttachIsoCommand(attach) > failed due to Exception: java.lang.NullPointerException\nMessage: > null\n","wait":0}}] } > 2013-07-16 17:22:43,223 DEBUG [agent.transport.Request] > (Job-Executor-18:job-77 = [ 24362d6b-4eb7-48d0-893a-2ac61fa84d5e ]) Seq > 4-1669464340: Received: { Ans: , MgmtId: 187767034175903, via: 4, Ver: v1, > Flags: 10, { AttachAnswer } } > 2013-07-16 17:22:43,223 DEBUG [agent.manager.AgentManagerImpl] > (Job-Executor-18:job-77 = [ 24362d6b-4eb7-48d0-893a-2ac61fa84d5e ]) Details > from executing class org.apache.cloudstack.storage.command.AttachCommand: > AttachIsoCommand(attach) failed due to Exception: > java.lang.NullPointerException > Message: null > 2013-07-16 17:22:43,232 ERROR [cloud.async.AsyncJobManagerImpl] > (Job-Executor-18:job-77 = [ 24362d6b-4eb7-48d0-893a-2ac61fa84d5e ]) > Unexpected exception while executing > org.apache.cloudstack.api.command.user.iso.AttachIsoCmd > com.cloud.utils.exception.CloudRuntimeException: Failed to attach iso > at > com.cloud.template.TemplateManagerImpl.attachIso(TemplateManagerImpl.java:969) > at > com.cloud.utils.component.ComponentInstantiationPostProcessor$InterceptorDispatcher.intercept(ComponentInstantiationPostProcessor.java:125) > at > org.apache.cloudstack.api.command.user.iso.AttachIsoCmd.execute(AttachIsoCmd.java:99) > 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-07-16 17:22:43,236 DEBUG [cloud.async.AsyncJobManagerImpl] > (Job-Executor-18:job-77 = [ 24362d6b-4eb7-48d0-893a-2ac61fa84d5e ]) Complete > async job-77 = [ 24362d6b-4eb7-48d0-893a-2ac61fa84d5e ], jobStatus: 2, > resultCode: 530, result: Error Code: 530 Error text: Failed to attach iso > 2013-07-16 17:22:43,935 DEBUG [agent.manager.DirectAgentAttache] > (DirectAgent-187:null) Ping from 1 > 2013-07-16 17:22:46,225 DEBUG [cloud.api.ApiServlet] (catalina-exec-12:null) > ===START=== 10.144.6.19 -- GET > command=queryAsyncJobResult&jobId=24362d6b-4eb7-48d0-893a-2ac61fa84d5e&response=json&sessionkey=5vYbKOzqMAqkmN9vVQ%2F695HcVDs%3D&_=1373975776961 > 2013-07-16 17:22:46,238 DEBUG [cloud.async.AsyncJobManagerImpl] > (catalina-exec-12:null) Async job-77 = [ 24362d6b-4eb7-48d0-893a-2ac61fa84d5e > ] completed > 2013-07-16 17:22:46,243 DEBUG [cloud.api.ApiServlet] (catalina-exec-12:null) > ===END=== 10.144.6.19 -- GET > command=queryAsyncJobResult&jobId=24362d6b-4eb7-48d0-893a-2ac61fa84d5e&response=json&sessionkey=5vYbKOzqMAqkmN9vVQ%2F695HcVDs%3D&_=1373975776961 > 2013-07-16 17:22:46,745 DEBUG [cloud.server.StatsCollector] > (StatsCollector-1:null) StorageCollector is running... -- 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