This discussion is archived
2 Replies Latest reply: Aug 7, 2013 12:08 AM by WadhahDAOUEHI RSS

Failed to start Virtual Machine

1006166 Newbie
Currently Being Moderated
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
----------

Legend

  • Correct Answers - 10 points
  • Helpful Answers - 5 points