My Oracle Support Banner

Exalogic Virtual: Import VM Template Fails In EMOC Due To A Proxy Controller Receiving NullPointerException From OVMM (Doc ID 2042063.1)

Last updated on OCTOBER 12, 2018

Applies to:

Oracle Exalogic Elastic Cloud Software - Version 2.0.6.0.0 and later
Linux x86-64

Symptoms

You have an Exalogic Virtual system and are uploading a vServer template via the Enterprise Manager OpsCenter Control (EMOC) browser based user interface (BUI) when the vDC-Upload-Server-Template-Job fails in the "Import Images from URL task" with the following error:

Job Id: ec-ovm.2352
  Created By: cloudadmin
  Type: vDC-Upload-Server-Template-Job
  Name:
  Status: FAILED
  Creation Date: Sun Jun 21 03:52:18 CEST 2015
  End Date: Sun Jun 21 04:01:57 CEST 2015
  Run Id: 1
    Task Id: 37832
    Task Name: vDC-Upload-Server-Template-Task
      :
    Task Id: 37837
    Task Name: Import Images from URL
      Target: ecu-pc-IPoIB-vserver-shared-storage-primary-74ea7c9f/com.sun.hss.domain:name="sr:///0004fb0000030000f8d9bef44e1586b8",type=StorageRepository
        Status: FAILED
        start time: Sun Jun 21 03:52:41 CEST 2015
        end time: Sun Jun 21 04:01:56 CEST 2015
        Cause Code: None
        Message: Import VM Template Failed. Exception occurred: [http://192.168.xx.x:8003/tmpResourceFile.1434851539785.tgz]. (63080)
          INFO : ec-ovm.2352.1.36240 acquired the local lock on the target - ecu-pc-IPoIB-vserver-shared-storage-primary-74ea7c9f/com.sun.hss.domain:name="sr:///0004fb0000030000f8d9bef44e1586b8",type=StorageRepository for the duration of Task
          INFO : Task is posted to the Proxy Controller (ecu-pc-IPoIB-vserver-shared-storage-primary-74ea7c9f), from the Enterprise Controller. (15011)
          INFO : Task is received by the Proxy Controller. (15012)
          INFO : Importing the VirtualMachineTemplate in OVM
          INFO : About To Launch OVM Job: Import VM Template. (63065)
          INFO : ec-ovm.2352.1.36240 released the local lock on the target - ecu-pc-IPoIB-vserver-shared-storage-primary-74ea7c9f/com.sun.hss.domain:name="sr:///0004fb0000030000f8d9bef44e1586b8",type=StorageRepository for the duration of Task

 

Within the emoc.log file (/var/opt/sun/xvm/logs/emoc.log) on the ExalogicControl vServer you see the following corresponding error:

Jun 21, 2015 4:01:56 AM com.sun.scn.jobmanager.satellite.impl.db.JobDBImpl updateTaskStatus
FINE: thr#355:"JM-HandleMessagesThread" updating task - 37837, target - ecu-pc-IPoIB-vserver-shared-storage-primary-74ea7c9f/com.sun.hss.domain:name="sr:///0004fb0000030000f8d9bef44e1586b8",type=StorageRepository, status - FAILED
Jun 21, 2015 4:01:56 AM com.sun.scn.jobmanager.satellite.impl.db.JobDBImpl updateJobStatus
FINE: thr#355:"JM-HandleMessagesThread" updating job:2352 runID:1 from status:RUNNING to status:RUNNING
Jun 21, 2015 4:01:56 AM com.sun.scn.jobmanager.api.JobEvent <init>
FINE: thr#355:"JM-HandleMessagesThread"  Job / Task Notification Event - Task.Import Images from URL.JobManager.Task.Progress.Fail, Fired For Job - ec-ovm.2352
Jun 21, 2015 4:01:56 AM com.sun.scn.jobmanager.satellite.impl.JobTaskNotifListener$TaskNotificationListener handleNotification
FINE: thr#355:"JM-HandleMessagesThread" Received Task Complete Notification
Jun 21, 2015 4:01:56 AM com.sun.scn.jobmanager.satellite.impl.JobTaskNotifListener$TaskNotificationListener handleNotification
FINE: thr#355:"JM-HandleMessagesThread"
Task Completed -
 JobID - ec-ovm.2352
 JobEnvlpID - ec-ovm.2352.1.3
 TaskID - 37837
 Target - ecu-pc-IPoIB-vserver-shared-storage-primary-74ea7c9f/com.sun.hss.domain:name="sr:///0004fb0000030000f8d9bef44e1575a7",type=StorageRepository
 Status - FAILED
 Message - Import VM Template Failed. Exception occurred: [http://192.168.xx.x:8003/tmpResourceFile.1434851539785.tgz]. (63080)
 CasueCode - null

 
On the ProxyController targeted to perform the task (in this case the "target - ecu-pc-IPoIB-vserver-shared-storage-primary" in the above message indicates PC1 was used) you see the following error in the cacao.N log file (/var/opt/sun/cacao2/instances/oem-ec/logs/cacao.N)

Jun 21, 2015 4:01:52 AM com.oracle.ovm.mgr.api.job.JobImpl internalJobCommit
SEVERE: thr#102794:"JM-ec-ovm.2352.1.37837.36240_ecu-pc-IPoIB-vserver-shared-storage-primary-74ea7c9f/com.sun.hss.domain:name="sr:///0004fb0000030000f8d9bef44e1575a7",type=StorageRepository" Job[Import VM Template-1434851557075] Internal (operation) Error due to : OVMAPI_6000E Internal Error: java.lang.NullPointerException
  at com.oracle.ovm.mgr.op.virtual.RepositoryElementFilesRefresh.action(RepositoryElementFilesRefresh.java:124)
  at com.oracle.ovm.mgr.api.collectable.ManagedObjectDbImpl.executeCurrentJobOperationAction(ManagedObjectDbImpl.java:1156)
  at com.oracle.odof.core.AbstractVessel.invokeMethod(AbstractVessel.java:356)
  at com.oracle.odof.core.AbstractVessel.invokeMethod(AbstractVessel.java:333)
  at com.oracle.odof.core.storage.Transaction.invokeMethod(Transaction.java:869)
  at com.oracle.odof.core.Exchange.invokeMethod(Exchange.java:244)
      :
  at java.lang.Thread.run(Thread.java:662)

   :
 [Sun Jun 21 04:01:51 CEST 2015]
  at com.oracle.ovm.mgr.api.job.InternalJobDbImpl.objectCommitter(InternalJobDbImpl.java:1437)
  at sun.reflect.GeneratedMethodAccessor421.invoke(Unknown Source)
  at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
  at java.lang.reflect.Method.invoke(Method.java:597)
  at com.oracle.odof.core.AbstractVessel.invokeMethod(AbstractVessel.java:356)
  at com.oracle.odof.core.AbstractVessel.invokeMethod(AbstractVessel.java:333)
  at com.oracle.odof.core.BasicWork.invokeMethod(BasicWork.java:106)
  at com.oracle.odof.command.InvokeMethodCommand.process(InvokeMethodCommand.java:92)
  at com.oracle.odof.core.BasicWork.processCommand(BasicWork.java:81)
  at com.oracle.odof.core.TransactionManager.processCommand(TransactionManager.java:752)
  at com.oracle.odof.core.WorkflowManager.processCommand(WorkflowManager.java:467)
  at com.oracle.odof.core.WorkflowManager.processWork(WorkflowManager.java:525)
  at com.oracle.odof.io.AbstractClient.run(AbstractClient.java:42)
  at java.lang.Thread.run(Thread.java:662)
Jun 21, 2015 4:01:52 AM com.sun.scn.jobmanager.common.impl.TaskExecutionThread finishTask
INFO: thr#102794:"JM-ec-ovm.2352.1.37837.36240_ecu-pc-IPoIB-vserver-shared-storage-primary-74ea7c9f/com.sun.hss.domain:name="sr:///0004fb0000030000f8d9bef44e1575a7",type=StorageRepository" ************* End Of Task - Import Images from URL
 TaskID - 37837
 TaskRunID - 36240
 Target - ecu-pc-IPoIB-vserver-shared-storage-primary-74ea7c9f/com.sun.hss.domain:name="sr:///0004fb0000030000f8d9bef44e1575a7",type=StorageRepository
 JobIDrunID - ec-ovm.2352.1
 Status - FAILED
 Result - Import VM Template Failed. Exception occurred: [http://192.168.xx.x:8003/tmpResourceFile.1434851539785.tgz]. (63080)
 CauseCode - null


Also, the same "java.lang.NullPointerException" from the cacao.N log for the ProxyController can be found in the "Import VM Template" within the Oracle Virtual Machine Manager (OVMM) web interface or AdminServer.log file (/u01/app/oracle/ovm-manager-3/machine1/base_adf_domain/servers/AdminServer/logs/AdminServer.log), as shown below:

2015-06-21 04:01:52: Import VM Template-1434820158517
OpsCenter: Import VM Template
Job Construction Phase
----------------------
Job ID: 1434820158518

begin()
Appended operation 'Virtual Machine Cfg File Configure' to object 'cfgFile_0004fb00001400006d2689fee68c60b5<480794>'.
Appended operation 'Import Virtual Machine' to object 'cfgFile_0004fb00001400006d2689fee68c60b5<480794>'.
commit()
Completed Step: COMMIT

Objects and Operations
----------------------
Object (IN_USE): [Server] ff:20:00:08:ff:ff:ff:ff:ff:ff:00:10:e0:65:5a:e5<325779> (elrack01cn03.mydomain.com)
Object (CREATED): [VirtualMachineCfgFile] cfgFile_0004fb00001400006d2689fee68c60b5<480794>
    Operation: Virtual Machine Cfg File Configure
    Operation: Import Virtual Machine
Object (CREATED): [VirtualMachineTemplate] 0004fb00001400006d2689fee68c60b5<480789> (tmpResourceFile.1434820145298.tgz)
Object (IN_USE): [Repository] 0004fb0000030000f8d9bef44e1575a7<1260> (/)

Job Running Phase at 2015-06-21 03:52:18,518
----------------------------------------------
Job Participants: [e0:30:d6:16:00:10:ff:ff:ff:ff:ff:ff:ff:20:00:07<1468> (elrack01cn02.mydomain.com), ff:20:00:08:ff:ff:ff:ff:ff:ff:00:10:e0:65:5a:e5<325779> (elrack01cn03.mydomain.com)]


Actioner
--------
03:52:18,859: Starting operation 'Virtual Machine Cfg File Configure' on object 'cfgFile_0004fb00001400006d2689fee68c60b5<480794>'
03:52:18,859: Completed operation 'Virtual Machine Cfg File Configure' with direction ==> LATER
03:52:18,862: Starting operation 'Import Virtual Machine' on object 'cfgFile_0004fb00001400006d2689fee68c60b5<480794>'
Appended operation 'Repository Element files refresh' to object 'cfgFile_0004fb00001400006d2689fee68c60b5<480794>'.
Appended operation 'Virtual Machine Cfg File Refresh' to object 'cfgFile_0004fb00001400006d2689fee68c60b5<480794>'.
Appended operation 'Virtual Machine Import Post' to object 'cfgFile_0004fb00001400006d2689fee68c60b5<480794>'.
04:01:52,204: Completed operation 'Import Virtual Machine' with direction ==> DONE
04:01:52,213: Starting operation 'Virtual Machine Cfg File Configure' on object 'cfgFile_0004fb00001400006d2689fee68c60b5<480794>'
04:01:52,213: Completed operation 'Virtual Machine Cfg File Configure' with direction ==> LATER
04:01:52,218: Starting operation 'Repository Element files refresh' on object 'cfgFile_0004fb00001400006d2689fee68c60b5<480794>'
04:01:52,218: Completed operation 'Repository Element files refresh' with direction ==> LATER
04:01:52,221: Starting operation 'Virtual Machine Cfg File Refresh' on object 'cfgFile_0004fb00001400006d2689fee68c60b5<480794>'
Setting Context to model only in job with id=1434820158518
Setting Context to model only in job with id=1434820158518
Setting Context to model only in job with id=1434820158518
Setting Context to default in job with id=1434820158518
04:01:52,310: Completed operation 'Virtual Machine Cfg File Refresh' with direction ==> DONE
04:01:52,313: Starting operation 'Virtual Machine Import Post' on object 'cfgFile_0004fb00001400006d2689fee68c60b5<480794>'
04:01:52,313: Completed operation 'Virtual Machine Import Post' with direction ==> LATER
04:01:52,316: Starting operation 'Virtual Machine Cfg File Configure' on object 'cfgFile_0004fb00001400006d2689fee68c60b5<480794>'
04:01:52,399: Completed operation 'Virtual Machine Cfg File Configure' with direction ==> DONE
04:01:52,402: Starting operation 'Repository Element files refresh' on object 'cfgFile_0004fb00001400006d2689fee68c60b5<480794>'
Job Internal Error (Indeterminate)java.lang.NullPointerException
    at com.oracle.ovm.mgr.op.virtual.RepositoryElementFilesRefresh.action(RepositoryElementFilesRefresh.java:124)
    at com.oracle.ovm.mgr.api.collectable.ManagedObjectDbImpl.executeCurrentJobOperationAction(ManagedObjectDbImpl.java:1156)
    at com.oracle.odof.core.AbstractVessel.invokeMethod(AbstractVessel.java:356)
    at com.oracle.odof.core.AbstractVessel.invokeMethod(AbstractVessel.java:333)
    at com.oracle.odof.core.storage.Transaction.invokeMethod(Transaction.java:869)
    at com.oracle.odof.core.Exchange.invokeMethod(Exchange.java:244)
        :
    at java.lang.Thread.run(Thread.java:662)

 

Cause

To view full details, sign in with your My Oracle Support account.

Don't have a My Oracle Support account? Click to get started!


In this Document
Symptoms
Cause
Solution
References


My Oracle Support provides customers with access to over a million knowledge articles and a vibrant support community of peers and Oracle experts.