2 Replies Latest reply: Aug 7, 2013 2:08 AM by WadhahDaouehi RSS

    Failed to start Virtual Machine

    1006166
      Hi,

      I failed to start a newly created virtual Machine with domain type HVM and I got the following error.

      Any help would be appreciated.



      Job ID: 1367253894716

      begin()
      Appended operation 'Virtual Machine Start' to object '0004fb0000060000a70fd970925266ac (Solaris11)'.
      Appended operation 'Bridge Configure Operation' to object '0004fb0000200000e049fce740a73828 (bond0 on OVMServer)'.
      commit()
      Completed Step: COMMIT

      Objects and Operations
      ----------------------
      Object (IN_USE): [StorageElement] 0004fb00001800001deaa1bba7576a77 (SATA_VMware_Virtual_01000000000000000001)
      Object (IN_USE): [VirtualCdrom] 0004fb0000150000c7e83c50934637b2.iso (Solaris11.iso)
      Object (IN_USE): [VmDiskMapping] 0004fb00001300007b3be9bbc930a0ad
      Object (IN_USE): [BondPort] 0004fb0000200000e049fce740a73828 (bond0 on OVMServer)
      Operation: Bridge Configure Operation
      Object (IN_USE): [VirtualMachine] 0004fb0000060000a70fd970925266ac (Solaris11)
      Operation: Virtual Machine Start
      Object (IN_USE): [VmDiskMapping] 0004fb00001300003d9a008d1a2ae538
      Object (IN_USE): [Vnic] 0004fb000007000091164d32b483bc45 (00:21:f6:00:00:00)

      Job Running Phase at 2013-04-30 00:44:54,716
      ----------------------------------------------
      Job Participants: [56:4d:5e:da:84:e5:6e:f9:ea:74:41:66:ca:27:41:35 (OVMServer)]


      Actioner
      --------
      00:44:55,097: Starting operation 'Bridge Configure Operation' on object '0004fb0000200000e049fce740a73828 (bond0 on OVMServer)'
      00:44:55,239: Completed operation 'Bridge Configure Operation' with direction ==> DONE
      00:44:55,263: Starting operation 'Virtual Machine Start' on object '0004fb0000060000a70fd970925266ac (Solaris11)'
      Job Internal Error (Operation)com.oracle.ovm.mgr.api.exception.JobFailureException: OVMAPI_5001E Job: 1,367,253,894,716/1367253894716/Start Virtual Machine Solaris11, failed. The job has a Job Failure Event: 1,367,253,901,934/Job Error: Server Async Command Failed/(04/30/2013 12:45:01:952 AM)
      Async operation failed on server: OVMServer.
      Object: Solaris11,
      PID: 5377,
      Server error message: Command: ['xm', 'create', '/OVS/Repositories/0004fb0000030000b3590891aa44ea2b/VirtualMachines/0004fb0000060000a70fd970925266ac/vm.cfg'] failed (1): stderr: Error: Acquire running lock failed: 512
      stdout: Using config file "/OVS/Repositories/0004fb0000030000b3590891aa44ea2b/VirtualMachines/0004fb0000060000a70fd970925266ac/vm.cfg".

      , on server: 56:4d:5e:da:84:e5:6e:f9:ea:74:41:66:ca:27:41:35, associated with object: 0004fb0000060000a70fd970925266ac [Tue Apr 30 00:45:02 MYT 2013]
      at com.oracle.ovm.mgr.api.job.JobEngine.checkJobStatus(JobEngine.java:657)
      at com.oracle.ovm.mgr.api.job.JobEngine.objectAsyncStart(JobEngine.java:626)
      at com.oracle.ovm.mgr.op.virtual.VirtualMachineStart.action(VirtualMachineStart.java:60)
      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:865)
      at com.oracle.odof.core.Exchange.invokeMethod(Exchange.java:244)
      at com.oracle.ovm.mgr.api.virtual.VirtualMachineProxy.executeCurrentJobOperationAction(Unknown Source)
      at com.oracle.ovm.mgr.api.job.JobEngine.operationActioner(JobEngine.java:230)
      at com.oracle.ovm.mgr.api.job.JobEngine.objectActioner(JobEngine.java:322)
      at com.oracle.ovm.mgr.api.job.InternalJobDbImpl.objectCommitter(InternalJobDbImpl.java:1340)
      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)


      FailedOperationCleanup
      ----------
      Starting failed operation 'Virtual Machine Start' cleanup on object 'Solaris11'
      Complete rollback operation 'Virtual Machine Start' cleanup on object 'Solaris11'

      Rollbacker
      ----------
      00:45:02,433: Starting rollbacker...
      Executing rollback operation 'Bridge Configure Operation' on object '0004fb0000200000e049fce740a73828 (bond0 on OVMServer)'
      Complete rollback operation 'Bridge Configure Operation' completed with direction=DONE
      Executing rollback operation 'Virtual Machine Start' on object '0004fb0000060000a70fd970925266ac (Solaris11)'
      Complete rollback operation 'Virtual Machine Start' completed with direction=DONE
      00:45:12,853: Rollbacker completed...

      Objects To Be Committed
      -----------------------
      Object (IN_USE): [StorageElement] 0004fb00001800001deaa1bba7576a77 (SATA_VMware_Virtual_01000000000000000001)
      Object (IN_USE): [VirtualCdrom] 0004fb0000150000c7e83c50934637b2.iso (Solaris11.iso)
      Object (IN_USE): [VmDiskMapping] 0004fb00001300007b3be9bbc930a0ad
      Object (IN_USE): [BondPort] 0004fb0000200000e049fce740a73828 (bond0 on OVMServer)
      Object (IN_USE): [VirtualMachine] 0004fb0000060000a70fd970925266ac (Solaris11)
      Object (IN_USE): [VmDiskMapping] 0004fb00001300003d9a008d1a2ae538
      Object (IN_USE): [Vnic] 0004fb000007000091164d32b483bc45 (00:21:f6:00:00:00)


      Resetter
      --------

      Write Methods Invoked
      -------------------
      00:44:54,796 Class=InternalJobDbImpl vessel_id=4787 method=addTransactionIdentifier accessLevel=6 owningTx=1367253894798
      00:44:54,797 Class=VirtualMachineDbImpl vessel_id=4681 method=start accessLevel=6 owningTx=1367253894798
      00:44:54,882 Class=VnicDbImpl vessel_id=792 method=lock accessLevel=6 owningTx=1367253894798
      00:44:54,883 Class=VirtualCdromDbImpl vessel_id=3319 method=lock accessLevel=6 owningTx=1367253894798
      00:44:54,883 Class=StorageElementDbImpl vessel_id=2625 method=lock accessLevel=6 owningTx=1367253894798
      00:44:54,883 Class=VnicDbImpl vessel_id=792 method=lock accessLevel=6 owningTx=1367253894798
      00:44:54,883 Class=VmDiskMappingDbImpl vessel_id=4699 method=lock accessLevel=6 owningTx=1367253894798
      00:44:54,883 Class=VmDiskMappingDbImpl vessel_id=4705 method=lock accessLevel=6 owningTx=1367253894798
      00:44:54,883 Class=VirtualCdromDbImpl vessel_id=3319 method=lock accessLevel=6 owningTx=1367253894798
      00:44:54,883 Class=StorageElementDbImpl vessel_id=2625 method=lock accessLevel=6 owningTx=1367253894798
      00:44:54,903 Class=VirtualMachineStartingEventDbImpl vessel_id=4796 method=notify accessLevel=6 owningTx=1367253894798
      00:44:54,910 Class=VirtualMachineStartingEventDbImpl vessel_id=4796 method=setDescription accessLevel=6 owningTx=1367253894798
      00:44:54,910 Class=InternalJobDbImpl vessel_id=4787 method=setRollbackOnFailureFlag accessLevel=6 owningTx=1367253894798
      00:44:54,912 Class=BondPortDbImpl vessel_id=2447 method=addJobOperation accessLevel=6 owningTx=1367253894798
      00:44:55,049 Class=InternalJobDbImpl vessel_id=4787 method=setCompletedStep accessLevel=6 owningTx=1367253894798
      00:44:55,051 Class=InternalJobDbImpl vessel_id=4787 method=setAssociatedHandles accessLevel=6 owningTx=1367253894798
      00:44:55,243 Class=BondPortDbImpl vessel_id=2447 method=setCurrentJobOperationComplete accessLevel=6 owningTx=1367253894798
      00:44:55,262 Class=BondPortDbImpl vessel_id=2447 method=nextJobOperation accessLevel=6 owningTx=1367253894798
      00:45:02,360 Class=VirtualMachineDbImpl vessel_id=4681 method=nextJobOperation accessLevel=6 owningTx=1367253894798
      00:45:02,360 Class=InternalJobDbImpl vessel_id=4787 method=setFailedOperation accessLevel=6 owningTx=1367253894798
      00:45:02,434 Class=StorageElementDbImpl vessel_id=2625 method=nextJobOperation accessLevel=6 owningTx=1367253894798
      00:45:02,434 Class=VirtualCdromDbImpl vessel_id=3319 method=nextJobOperation accessLevel=6 owningTx=1367253894798
      00:45:02,434 Class=VmDiskMappingDbImpl vessel_id=4705 method=nextJobOperation accessLevel=6 owningTx=1367253894798
      00:45:02,435 Class=BondPortDbImpl vessel_id=2447 method=nextJobOperation accessLevel=6 owningTx=1367253894798
      00:45:02,435 Class=VirtualMachineDbImpl vessel_id=4681 method=nextJobOperation accessLevel=6 owningTx=1367253894798
      00:45:02,435 Class=VmDiskMappingDbImpl vessel_id=4699 method=nextJobOperation accessLevel=6 owningTx=1367253894798
      00:45:02,435 Class=VnicDbImpl vessel_id=792 method=nextJobOperation accessLevel=6 owningTx=1367253894798
      00:45:02,458 Class=DiscoverManagerDbImpl vessel_id=235 method=createServerDiscoverScanEvent accessLevel=5 owningTx=-1
      00:45:02,512 Class=BondPortDbImpl vessel_id=2447 method=nextJobOperation accessLevel=6 owningTx=1367253894798
      00:45:12,853 Class=VirtualMachineDbImpl vessel_id=4681 method=nextJobOperation accessLevel=6 owningTx=1367253894798
      00:45:12,874 Class=VirtualCdromDbImpl vessel_id=3319 method=jobReset accessLevel=5 owningTx=1367253894798
      00:45:12,875 Class=VirtualCdromDbImpl vessel_id=3319 method=setRollbackLifecycleState accessLevel=6 owningTx=1367253894798
      00:45:12,875 Class=StorageElementDbImpl vessel_id=2625 method=jobReset accessLevel=5 owningTx=1367253894798
      00:45:12,875 Class=StorageElementDbImpl vessel_id=2625 method=setRollbackLifecycleState accessLevel=6 owningTx=1367253894798
      00:45:12,875 Class=VmDiskMappingDbImpl vessel_id=4705 method=jobReset accessLevel=5 owningTx=1367253894798
      00:45:12,875 Class=VmDiskMappingDbImpl vessel_id=4705 method=setRollbackLifecycleState accessLevel=6 owningTx=1367253894798
      00:45:12,875 Class=BondPortDbImpl vessel_id=2447 method=jobReset accessLevel=5 owningTx=1367253894798
      00:45:12,875 Class=BondPortDbImpl vessel_id=2447 method=setRollbackLifecycleState accessLevel=6 owningTx=1367253894798
      00:45:12,875 Class=VirtualMachineDbImpl vessel_id=4681 method=jobReset accessLevel=5 owningTx=1367253894798
      00:45:12,875 Class=VirtualMachineDbImpl vessel_id=4681 method=setRollbackLifecycleState accessLevel=6 owningTx=1367253894798
      00:45:12,875 Class=VmDiskMappingDbImpl vessel_id=4699 method=jobReset accessLevel=5 owningTx=1367253894798
      00:45:12,876 Class=VmDiskMappingDbImpl vessel_id=4699 method=setRollbackLifecycleState accessLevel=6 owningTx=1367253894798
      00:45:12,876 Class=VnicDbImpl vessel_id=792 method=jobReset accessLevel=5 owningTx=1367253894798
      00:45:12,876 Class=VnicDbImpl vessel_id=792 method=setRollbackLifecycleState accessLevel=6 owningTx=1367253894798
      00:45:12,876 Class=InternalJobDbImpl vessel_id=4787 method=setAssets accessLevel=6 owningTx=1367253894798
      00:45:12,921 Class=StorageElementDbImpl vessel_id=2625 method=clearJobOperations accessLevel=6 owningTx=1367253894798
      00:45:12,921 Class=VirtualCdromDbImpl vessel_id=3319 method=clearJobOperations accessLevel=6 owningTx=1367253894798
      00:45:12,921 Class=VmDiskMappingDbImpl vessel_id=4705 method=clearJobOperations accessLevel=6 owningTx=1367253894798
      00:45:12,921 Class=BondPortDbImpl vessel_id=2447 method=clearJobOperations accessLevel=6 owningTx=1367253894798
      00:45:12,921 Class=VirtualMachineDbImpl vessel_id=4681 method=clearJobOperations accessLevel=6 owningTx=1367253894798
      00:45:12,921 Class=VmDiskMappingDbImpl vessel_id=4699 method=clearJobOperations accessLevel=6 owningTx=1367253894798
      00:45:12,922 Class=VnicDbImpl vessel_id=792 method=clearJobOperations accessLevel=6 owningTx=1367253894798
      Job Failed Commit due to OVMAPI_5001E Job: 1,367,253,894,716/1367253894716/Start Virtual Machine Solaris11, failed. The job has a Job Failure Event: 1,367,253,901,934/Job Error: Server Async Command Failed/(04/30/2013 12:45:01:952 AM)
      Async operation failed on server: OVMServer.
      Object: Solaris11,
      PID: 5377,
      Server error message: Command: ['xm', 'create', '/OVS/Repositories/0004fb0000030000b3590891aa44ea2b/VirtualMachines/0004fb0000060000a70fd970925266ac/vm.cfg'] failed (1): stderr: Error: Acquire running lock failed: 512
      stdout: Using config file "/OVS/Repositories/0004fb0000030000b3590891aa44ea2b/VirtualMachines/0004fb0000060000a70fd970925266ac/vm.cfg".

      , on server: 56:4d:5e:da:84:e5:6e:f9:ea:74:41:66:ca:27:41:35, associated with object: 0004fb0000060000a70fd970925266ac [Tue Apr 30 00:45:02 MYT 2013]
      com.oracle.ovm.mgr.api.exception.JobFailureException: OVMAPI_5001E Job: 1,367,253,894,716/1367253894716/Start Virtual Machine Solaris11, failed. The job has a Job Failure Event: 1,367,253,901,934/Job Error: Server Async Command Failed/(04/30/2013 12:45:01:952 AM)
      Async operation failed on server: OVMServer.
      Object: Solaris11,
      PID: 5377,
      Server error message: Command: ['xm', 'create', '/OVS/Repositories/0004fb0000030000b3590891aa44ea2b/VirtualMachines/0004fb0000060000a70fd970925266ac/vm.cfg'] failed (1): stderr: Error: Acquire running lock failed: 512
      stdout: Using config file "/OVS/Repositories/0004fb0000030000b3590891aa44ea2b/VirtualMachines/0004fb0000060000a70fd970925266ac/vm.cfg".

      , on server: 56:4d:5e:da:84:e5:6e:f9:ea:74:41:66:ca:27:41:35, associated with object: 0004fb0000060000a70fd970925266ac [Tue Apr 30 00:45:02 MYT 2013]
      at com.oracle.ovm.mgr.api.job.JobEngine.checkJobStatus(JobEngine.java:657)
      at com.oracle.ovm.mgr.api.job.JobEngine.objectAsyncStart(JobEngine.java:626)
      at com.oracle.ovm.mgr.op.virtual.VirtualMachineStart.action(VirtualMachineStart.java:60)
      at com.oracle.ovm.mgr.api.collectable.ManagedObjectDbImpl.executeCurrentJobOperationAction(ManagedObjectDbImpl.java:1156)
      at sun.reflect.GeneratedMethodAccessor894.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.storage.Transaction.invokeMethod(Transaction.java:865)
      at com.oracle.odof.core.Exchange.invokeMethod(Exchange.java:244)
      at com.oracle.ovm.mgr.api.virtual.VirtualMachineProxy.executeCurrentJobOperationAction(Unknown Source)
      at com.oracle.ovm.mgr.api.job.JobEngine.operationActioner(JobEngine.java:230)
      at com.oracle.ovm.mgr.api.job.JobEngine.objectActioner(JobEngine.java:322)
      at com.oracle.ovm.mgr.api.job.InternalJobDbImpl.objectCommitter(InternalJobDbImpl.java:1340)
      at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
      at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
      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)


      ----------
      End of Job
      ----------