Start a Conversation

Unsolved

This post is more than 5 years old

12879

October 5th, 2016 07:00

Error Code 10055 fault InvalidLogin does not have reason

I'm at my wits end here. This has been going on for months. I have worked with EMC support and VMware support. EMC and VMware support have also worked together. We generally have about 25-75 backup jobs fail every night. On the surface, it's a simple authentication issue. Here are the details.


1. I'm using Avamar 7.2.0-401 performing image backups on VMware 6.0

2. There are no vcenter servers being backed up at the time of the failures.

3. This only happens during peak loads

4. I can re-run each job successfully

5. It will fail at any point during the backup process when it is trying to attach to the first or next disk.

6. I have used an AD account and local account in VMware to connect without any difference.

7. VMware says they don't see the authentication errors that Avamar is reporting

8. This happens throughout the backup window

9. This happens across VMware clusters

10. This is not limited to a single proxy.

11. Proxies only see hosts in the same cluster. There is no cross traffic.

12. This has happened on 2 different virtual centers.


Please tell me someone else has seen this and has an answer.


2016-10-05 01:02:06 avvcbimage Info <16041>: VDDK:VixDiskLibVim: VixDiskLibVimGetFaultReason: fault InvalidLogin does not have reason.

2016-10-05 01:02:06 avvcbimage Info <16041>: VDDK:VixDiskLibVim: Error 3014 (listener error VmodlVimFaultInvalidLogin).

2016-10-05 01:02:06 avvcbimage Warning <16041>: VDDK:VixDiskLibVim: Login failure. Callback error 3014 at 2434.

2016-10-05 01:02:06 avvcbimage Info <16041>: VDDK:VixDiskLibVim: Failed to find the VM. Error 3014 at 2506.

2016-10-05 01:02:06 avvcbimage Info <16041>: VDDK:VixDiskLibVim: Logout from server.

2016-10-05 01:02:06 avvcbimage Info <16041>: VDDK:VixDiskLibVim: Clean up callback data.

2016-10-05 01:02:06 avvcbimage Info <16041>: VDDK:VixDiskLibVim: Clean up after logging out from server.

2016-10-05 01:02:06 avvcbimage Info <16041>: VDDK:VixDiskLibVim: Logout callback is done.

2016-10-05 01:02:06 avvcbimage Info <16041>: VDDK:VixDiskLibVim: Logout from server is done.

2016-10-05 01:02:06 avvcbimage Info <16041>: VDDK:VixDiskLibVim: Login callback is done.

2016-10-05 01:02:06 avvcbimage Info <16041>: VDDK:VixDiskLibVim: Clean up callback data.

2016-10-05 01:02:06 avvcbimage Info <16041>: VDDK:VixDiskLibVim: VixDiskLibVim_FreeNfcTicket: Free NFC ticket.

2016-10-05 01:02:06 avvcbimage Info <16041>: VDDK:VixDiskLibVim: Get NFC ticket completed.

2016-10-05 01:02:06 avvcbimage Info <16041>: VDDK:VixDiskLib: Error occurred when obtaining NFC ticket for: [VNX5300_R5_06] ARPJVXPSC02/ARPJVXPSC02.vmdk. Error 3014 (Insufficient permissions in the host operating system) (fault InvalidLogin, type VmodlVimFaultInvalidLogin, reason: (none given), translated to 3014) at 4515.

2016-10-05 01:02:06 avvcbimage Info <16041>: VDDK:VixDiskLib: VixDiskLib_OpenEx: Cannot open disk [VNX5300_R5_06] ARPJVXPSC02/ARPJVXPSC02.vmdk. Error 3014 (Insufficient permissions in the host operating system) (fault InvalidLogin, type VmodlVimFaultInvalidLogin, reason: (none given), translated to 3014) at 4669.

2016-10-05 01:02:06 avvcbimage Info <16041>: VDDK:VixDiskLib: VixDiskLib_Open: Cannot open disk [VNX5300_R5_06] ARPJVXPSC02/ARPJVXPSC02.vmdk. Error 3014 (Insufficient permissions in the host operating system) at 4707.

2016-10-05 01:02:06 avvcbimage Error <0000>: [IMG0008] Failed to connect to virtual disk [VNX5300_R5_06] ARPJVXPSC02/ARPJVXPSC02.vmdk (3014) (3014) Insufficient permissions in the host operating system
2016-10-05 01:02:06 avvcbimage Info <19644>: Connecting virtual disk [VNX5300_R5_06] ARPJVXPSC02/ARPJVXPSC02.vmdk
2016-10-05 01:02:06 avvcbimage Error <0000>: [IMG0008] VixDiskLib_Open([VNX5300_R5_06] ARPJVXPSC02/ARPJVXPSC02.vmdk) returned (3014) Insufficient permissions in the host operating system
2016-10-05 01:02:06 avvcbimage Info <9772>: Starting graceful (staged) termination, VixDiskLib_Open attempt to connect to virtual disk failed (wrap-up stage)

6 Posts

October 19th, 2016 13:00

Ok. After a couple of WebEx’s with EMC here is the latest.

When looking at the VMs log for the failed backup(s) there are the usual errors and exceptions in Red and this is what we are generally drawn to. However right above one of these entries is something one of the EMC guys found:

2016-10-19 10:03:26 avvcbimage Warning <16041>: VDDK:VixDiskLibVim: Login failure. Callback error 3014 at 2439.
2016-10-19 10:03:26 avvcbimage Info <16041>: VDDK:VixDiskLibVim: Failed to find the VM. Error 3014 at 2511.
2016-10-19 10:03:26 avvcbimage Info <16041>: VDDK:VixDiskLibVim: VixDiskLibVim_FreeNfcTicket: Free NFC ticket.
2016-10-19 10:03:26 avvcbimage Info <16041>: VDDK:VixDiskLib: Error occurred when obtaining NFC ticket for: servername/serverdisk.vmdk. Error 3014 (Insufficient permissions in the host operating system) (fault InvalidLogin, type VmodlVimFaultInvalidLogin, reason: (none given), translated to 3014) at 4526.

2016-10-19 10:03:26 avvcbimage Info <16041>: VDDK:VixDiskLib: VixDiskLib_OpenEx: Cannot open disk servername/serverdisk.vmdk. Error 3014 (Insufficient permissions in the host operating system) (fault InvalidLogin, type VmodlVimFaultInvalidLogin, reason: (none given), translated to 3014) at 4680.

2016-10-19 10:03:26 avvcbimage Info <16041>: VDDK:VixDiskLib: VixDiskLib_Open: Cannot open disk servername/serverdisk.vmdk. Error 3014 (Insufficient permissions in the host operating system) at 4718.

2016-10-19 10:03:26 avvcbimage Error <0000>: Failed to connect to virtual disk servername/serverdisk.vmdk (3014) (3014) Insufficient permissions in the host operating system

2016-10-19 10:03:26 avvcbimage Info <19644>: Connecting virtual disk servername/serverdisk.vmdk

Avamar can’t login to vCenter for some reason, the backup fails. This error is present in all of the failed backups.

So we started to dig into the vpxd.log on the vCenter (appliance in our case) and grepped for “failed” and the AD service account we use for Avamar.

We found over 100+ entries like this:

2016-10-18T23:18:50.794-07:00 error vpxd[7FA110D49700] Failed to authenticate user (actual domain and user names removed to make the security guy happy)

Some of these entries had an time stamp that exactly matched the Avamar client logs for the “VDDK:VixDiskLibVim: Login failure”

When this was found the EMC engineer asked if we were backing up our vCenter appliance with the other production VMs? Yes we are. The next question was are you also backing up your domain controllers with the production VMs? Yes.

He stated that due to the Avamar taking quiesced snapshots there may be a few seconds during which that is happening that either the vCenter server or the AD DC’s are not accepting the login. This would explain why in the AM we can rerun any one of the failed backups and they complete without issue. No other snapshots going on. SAN is quite, everything is responding well.

For tonight’s backup window we removed the vCenter appliance and all of the AD DCs from the backups. We’ll see what happens overnight.

I’ll update you all tomorrow.

1 Message

November 15th, 2016 06:00

Did you ever find a solution with VMware? We have had a couple of cases open with VMware and they haven't found a solution yet.

6 Posts

November 15th, 2016 07:00

Yes. It was quit simple. Instead of using a AD User account for Avamar/ vCenter integration I changed the account Avamar was using a vCenter local account. Fixed the issue. Basically our AD DC’s could not keep up with the amount of AD login requests that Avamar was sending during our nightly backup window. Changed it and we haven’t missed a backup.

42 Posts

November 15th, 2016 08:00

Here's what they had to say.

IDM maintains a TenantCache which stores information about the tenant so that IDM can avoid repeated queries to vmdir. The key to this cache is the tenant name, as supplied by the login request. In this case, we were logging into "abcvsphere.local". However, when the customer created this tenant, they provided the name "ABCvsphere". As such, "ABCvsphere" was stored in vmdir as the tenant name. When writing to the cache, the tenant name stored in vmdir is used as the key, in this case "ABCvsphere". I'll walk through an example to illustrate what happened:

- In many cases this will result in a POST to /sts/STSService/odc.local. Notice the tenant name is "abcvsphere.local"

- IDM tries to query the TenantCache with key "abcvsphere.local". The cache is empty, this is expected since it is the first login.

- IDM queries vmdir several times to get the tenant information. Most LDAP queries are case insensitive, so lookups for tenant "abcvsphere.local" will return the data for "ODC.local."

- Now that IDM has the tenant information, it will be cached with key "ABCvsphere.local". The key to the cache is the tenant name as stored in vmdir.

- Subsequent logins will result in IDM querying the cache with key "abcvsphere.local". By default, Java String comparisons are case sensitive, so the cache will appear empty, and IDM will repeat the same process of querying vmdir.

- Since every authentication request results in a cache miss, many extra LDAP connections are created for each request. Eventually, so many LDAP connections are created that the machine exhausts the ephemeral port space. This in turn results in some authentication requests failing since IDM cannot establish a connection to vmdir.

The fix for the port exhaustion is supposed to be available in v6.0u3 out in January.

73 Posts

November 15th, 2016 08:00

Have you tried using administrator@vsphere.local or another vsphere account instead of a AD account for Avamar authentication to vCenter?  One time I ran into an intermittent issue similar to what is described in this post and that was a sufficient resolution for me.

proxycp.jar has an option to test vCenter connection and I used it to troubleshoot the issue.  The issue I ran into when I used an AD account the proxycp.jar vcenter connection consistently failed about half the times I tried it.  vSphere account worked every time.

The 6.5 VDDK release notes mention a new feature for reusing vCenter server sessions.  Possibly this future VMWare feature is designed to resolve the issue mentioned in this post.  VDDK is the API that Avamar uses for VMWare backups.

June 9th, 2017 04:00

We have encountered this same issue (error code = 10055), we rebooted the host at vcenter and it corrected the problem. I hope this helps.

No Events found!

Top