VMware_thaw: Unable to unlock virtual machine:IP

[Vinayak_Chennur]

Hi Friends,

Vm backup are getting partially successful status 1.

as per TN:  http://www.symantec.com/business/support/index?page=content&id=TECH214208

https://www.veritas.com/support/en_US/article.TECH130493

I checked Roles of Vspehere account used for VM backups, it already has this permission "Register".

Still issue is there..

Any suggestions ?

11/21/2016 05:44:52 - estimated 0 kbytes needed
11/21/2016 05:44:52 - begin Parent Job
11/21/2016 05:44:52 - begin VMware: Start Notify Script
11/21/2016 05:44:52 - Info RUNCMD (pid=49126) started
11/21/2016 05:44:52 - Info RUNCMD (pid=49126) exiting with status: 0
Operation Status: 0
11/21/2016 05:44:52 - end VMware: Start Notify Script; elapsed time 0:00:00
11/21/2016 05:44:52 - begin VMware: Step By Condition
Operation Status: 0
11/21/2016 05:44:52 - end VMware: Step By Condition; elapsed time 0:00:00
11/21/2016 05:44:52 - begin VMware: Read File List
Operation Status: 0
11/21/2016 05:44:52 - end VMware: Read File List; elapsed time 0:00:00
11/21/2016 05:44:52 - begin VMware: Create Snapshot
11/21/2016 05:44:53 - Info bpbrm (pid=106043) IP is the host to backup data from
11/21/2016 05:44:53 - Info bpbrm (pid=106043) reading file list for client
11/21/2016 05:44:53 - Info bpbrm (pid=106043) start bpfis on client
11/21/2016 05:44:53 - Info bpbrm (pid=106043) Starting create snapshot processing
11/21/2016 05:44:53 - Info bpfis (pid=106052) Backup started
11/21/2016 05:44:53 - snapshot backup of client IP using method VMware_v2
11/21/2016 05:44:53 - started process bpbrm (pid=106043)
11/21/2016 05:55:30 - end VMware: Create Snapshot; elapsed time 0:10:38
11/21/2016 05:55:30 - Info bpfis (pid=106052) done. status: 0
11/21/2016 05:55:30 - Info bpfis (pid=106052) done. status: 0: the requested operation was successfully completed
11/21/2016 05:55:30 - end writing
Operation Status: 0
11/21/2016 05:55:30 - end Parent Job; elapsed time 0:10:38
11/21/2016 05:55:30 - Info nbjm (pid=13845) snapshotid=IP_1479687292
11/21/2016 05:55:30 - begin VMware: Policy Execution Manager Preprocessed
Operation Status: 0
11/21/2016 05:59:21 - end VMware: Policy Execution Manager Preprocessed; elapsed time 0:03:51
11/21/2016 05:59:21 - begin VMware: Validate Image
Operation Status: 0
11/21/2016 05:59:21 - end VMware: Validate Image; elapsed time 0:00:00
11/21/2016 05:59:21 - begin VMware: Delete Snapshot
11/21/2016 05:59:21 - Info bpbrm (pid=107142) Starting delete snapshot processing
11/21/2016 05:59:21 - started process bpbrm (pid=107142)
11/21/2016 05:59:22 - Info bpfis (pid=107146) Backup started
11/21/2016 05:59:31 - Info bpbrm (pid=107142) INF - vmwareLogger: PostExtendedEventAPI: SYM_VMC_ERROR: SOAP_ERROR
11/21/2016 05:59:31 - Info bpbrm (pid=107142) INF - vmwareLogger: SOAP 1.1 fault: "":ServerFaultCode [no subcode]
11/21/2016 05:59:31 - Info bpbrm (pid=107142) INF - vmwareLogger: GetTagTypeFromXml: SYM_VMC_ERROR: INVALID_VM_XML_FILE
11/21/2016 06:10:21 - Critical bpbrm (pid=107142) from client IP: VMware_thaw: Unable to unlock virtual machine:IP
11/21/2016 06:10:22 - Critical bpbrm (pid=107142) from client IP: vfm_thaw: method: VMware_v2, type: FIM, function: VMware_v2_thaw
11/21/2016 06:10:22 - Critical bpbrm (pid=107142) from client IP: snapshot delete returned status 1

11/21/2016 06:10:22 - Info bpfis (pid=107146) done. status: 1

11/21/2016 06:10:22 - end VMware: Delete Snapshot; elapsed time 0:11:01
11/21/2016 06:10:22 - Info bpfis (pid=107146) done. status: 1: the requested operation was partially successful
11/21/2016 06:10:22 - end writing
Operation Status: 1

11/21/2016 06:10:22 - begin VMware: End Notify Script
11/21/2016 06:10:22 - Info RUNCMD (pid=6513) started
11/21/2016 06:10:22 - Info RUNCMD (pid=6513) exiting with status: 0
Operation Status: 0
11/21/2016 06:10:22 - end VMware: End Notify Script; elapsed time 0:00:00
Operation Status: 1

The requested operation was partially successful (1)

[PatS729]

Hi Vinayak,

Your problem could be this "Critical bpbrm (pid=107142) from client IP: snapshot delete returned status 1"

is this new setup ? If so, then verify following permissions

Snapshot management(State for vSphere 5.0)    
        Create snapshot
        Remove Snapshot
        Revert to snapshot

[Vinayak_Chennur]

Hi Pats729,

is this new setup ?  YES

If so, then verify following permissions : We have all the permission as requested by Veritas Technote.

https://www.veritas.com/support/en_US/article.TECH130493

Also below mention permission are there.

Snapshot management(State for vSphere 5.0)    
        Create snapshot
        Remove Snapshot
        Revert to snapshot

[Michal_Mikulik1]

Hello,

investigate on VMware side. Test if you can create/delete snapshot directly in vSphere console and if not, what is the VMware message.

Probably something like Consolidate Disks action is required.

Michal

[Vinayak_Chennur]

Hi Michel

We are able to create/delete snapshot directly in vSphere console.

regards,

vinayak chennuri

[InVino]

Hi Vinayak,

I think this is caused by permission issues anyway.

As per https://www.veritas.com/support/en_US/article.TECH130493, the permissions listed are the minimum needed for Basic vSphere Environment:

"These are the minimum required permissions that have been found to be sufficient in the tests performed by VERITAS for a basic vSphere environment.  The permissions are best propagated downwards from the root of the vSphere level.  Additional privileges might be required if advanced features are in use"

If that is an advanced vSphere, you'll pretty much need admin rights.

Hope this helps

Regards

A.