SSO issue in View 4.6

[arnaudp]

Hi,
I've got an issue with our Virtual Desktops.
The SSO doesn't work just when we first log in.
As  we have to reboot our desktops, every morning, when users want to  connect to their virtual desktop, they have to write their login in the  View Client and then in the Virtual Desktop.
If they logoff and login again, the credentials are correctly passed to the VM, so, in this case, the SSO works.
This  issue appeared since we upgraded our View connection server from View  3.2.1 on Windows 2003 R2 Standard to View 4.6 on Windows 2008 R2.
First of all, I check the five points listed on VMWare KB Article: 1029391 but everything was OK.
Then, I've checked logs on Thinclient, VM and connection server but they don't help me solving the issue.
Do you know where the issue might come from?
Thank you for helping!

[Baddos]

Have you upgraded the view agent on the guest?

[arnaudp]

Yes, I had upgraded View Agent from 3.1 to 4.6 and SSO still did not work at the first login when virtual desktops were rebooted.

[Linjo]

Could you describe the symptoms a bit more?

What exactly happends? Is there an error-message?

Any interesting event in the logs at the same time?

How is time-sync done?

// Linjo

[arnaudp]

Hi
Sorry for the delay.
Concerning the symptoms, when virtual desktop are rebooted, on the first next logon, credentials wrote on the View Client (4.6) are not always passed to the Virtual Desktop.
When this problem occurs, there is no error message pop-up for the user, user just have to write his credentials again in the Virtual Desktop.
But, sometimes, credentials are correcty passed to a just rebooted Virtual Desktop.

Time is syncronized to our Domain Controller.

Here are some logs from T5730 thinclient and a Virtual Machine.
View Agent and View Client are in version 4.6.

From TX:

- From Logs Files

04:01:21,328 INFO  <1248> [wsnm] Log for VMware View Client, version=4.6.0 build-366101
04:01:21,328 INFO  <1248> [wsnm] hostname=HOSTNAME

04:01:21,312 INFO  <Service Main Thread> [MessageFrameWork] Program 'wsnm - VMware View Framework Node Manager' started, version=4.6.0 build-366101, pid=1056, buildtype=release, usethread=1, closeafterwrite=0
04:01:21,421 INFO  <Service Main Thread> [wsnm] The VMware View System Service is starting
04:01:21,671 INFO  <Main Thread> [wsnm_usbctrl] Program 'wsnm_usbctrl - VMware View Framework USB Controller' started, version=4.6.0 build-366101, pid=1280, buildtype=release, usethread=0, closeafterwrite=0

- From Debug Files

2011-09-26 04:01:21,312 INFO  <Service Main Thread> [MessageFrameWork] Program 'wsnm - VMware View Framework Node Manager' started, version=4.6.0 build-366101, pid=1056, buildtype=release, usethread=1, closeafterwrite=0
2011-09-26 04:01:21,312 DEBUG <Service Main Thread> [MessageFrameWork] VMware HKLM 'Client' policy values at start of wsnm: connectUSBOnInsert=true;connectUSBOnStartup=true;EnableShade=true;InitialPinState=False
2011-09-26 04:01:21,312 DEBUG <Service Main Thread> [MessageFrameWork] wsnm MessageQueueShared started with numThreads=50, queueTreshold=1000
2011-09-26 04:01:21,328 DEBUG <Service Main Thread> [MessageFrameWork] wsnm MessageQueue started with numThreads=50, queueTreshold=5000
2011-09-26 04:01:21,328 DEBUG <Service Main Thread> [MessageFrameWork] MessageFrameWork Worker Added, Name=System, Description=The Builtin MessageFrameWork Administration Queue, Channel=00000000
2011-09-26 04:01:21,343 DEBUG <Service Main Thread> [MessageFrameWork] MessageFrameWork Worker Added, Name=TraceServer, Description=Provides external trace listening support, Channel=00000000
2011-09-26 04:01:21,375 DEBUG <MessageFrameWorkDispatch> [MessageFrameWork] System::IsPluginLoaded
2011-09-26 04:01:21,421 INFO  <Service Main Thread> [wsnm] The VMware View System Service is starting
2011-09-26 04:01:21,671 INFO  <Main Thread> [wsnm_usbctrl] Program 'wsnm_usbctrl - VMware View Framework USB Controller' started, version=4.6.0 build-366101, pid=1280, buildtype=release, usethread=0, closeafterwrite=0
2011-09-26 04:01:21,671 DEBUG <Main Thread> [wsnm_usbctrl] wsnm_usbctrl started
2011-09-26 04:01:21,687 DEBUG <Service Main Thread> [wsnm_usbctrl] VMware HKLM 'Client' policy values at start of wsnm_usbctrl: connectUSBOnInsert=true;connectUSBOnStartup=true;EnableShade=true;InitialPinState=False
2011-09-26 04:01:21,687 DEBUG <Service Main Thread> [wsnm_usbctrl] wsnm_usbctrl MessageQueueShared started with numThreads=10, queueTreshold=250
2011-09-26 04:01:21,687 DEBUG <Service Main Thread> [wsnm_usbctrl] wsnm_usbctrl MessageQueue started with numThreads=10, queueTreshold=1000
2011-09-26 04:01:21,703 DEBUG <Service Main Thread> [wsnm_usbctrl] MessageFrameWork Worker Added, Name=System, Description=The Builtin MessageFrameWork Administration Queue, Channel=00000000
2011-09-26 04:01:21,781 DEBUG <SharedMemAuthenticateThread> [MessageFrameWork] Incoming SharedMemory channel from machine ****** wsnm_usbctrl to wsnm connected as : *****, Authenticated through SSPI, package = NTLM, encrypting = true, canImpersonate = true
2011-09-26 04:01:21,781 DEBUG <NodeManagerWatcher> [wsnm_usbctrl] Outgoing SharedMemory channel from wsnm_usbctrl to machine ***** wsnm connected as : *****, Authenticated through SSPI, package = NTLM, encrypting = true
2011-09-26 04:01:21,796 DEBUG <NodeManagerWatcher> [wsnm_usbctrl] Connection to Node Manager established.
2011-09-26 04:01:22,046 DEBUG <PluginInitThread> [wsnm_usbctrl] MessageFrameWork Worker Added, Name=UsbCtrl, Description=USB Control, Channel=00000000
2011-09-26 04:01:22,046 DEBUG <MessageFrameWorkDispatch> [MessageFrameWork] MessageFrameWork Worker Added, Name=UsbCtrl, Description=USB Control, Channel=00B1EC80
2011-09-26 04:01:27,843 DEBUG <SharedMemAuthenticateThread> [MessageFrameWork] Incoming SharedMemory channel from machine ***** wswc to wsnm connected as : ******, Authenticated through SSPI, package = NTLM, encrypting = true, canImpersonate = true
2011-09-26 04:01:27,843 DEBUG <MessageFrameWorkDispatch> [MessageFrameWork] MessageFrameWork Worker Added, Name=GuestUIManager, Description=The in-guest UI management service, Channel=00B1E420
2011-09-26 04:01:27,843 DEBUG <MessageFrameWorkDispatch> [MessageFrameWork] MessageFrameWork Worker Added, Name=UIManager0, Description=Provides User Interface services for a session, Channel=00B1E420
2011-09-26 04:01:27,843 DEBUG <MessageFrameWorkDispatch> [MessageFrameWork] MessageFrameWork Worker Added, Name=wswc0, Description=The program main service, Channel=00B1E420
2011-09-26 04:01:27,843 DEBUG <MessageFrameWorkDispatch> [MessageFrameWork] MessageFrameWork Worker Added, Name=UsbRemoteNotify0, Description=USB manager session notify queue, Channel=00B1E420
2011-09-26 04:01:27,859 DEBUG <MessageFrameWorkDispatch> [wsnm_usbctrl] UsbCtrl got a Register message, (null)
2011-09-26 04:01:28,812 DEBUG <MessageFrameWorkDispatch> [wsnm_usbctrl] UsbCtrl got a EnumDevices message, (null)
2011-09-26 04:01:47,563 DEBUG <MessageFrameWorkDispatch> [wsnm_usbctrl] UsbCtrl got a EnumDevices message, (null)
2011-09-26 07:17:09,043 DEBUG <MessageFrameWorkDispatch> [wsnm_usbctrl] UsbCtrl got a EnumDevices message, (null)
2011-09-26 07:17:24,122 DEBUG <MessageFrameWorkDispatch> [wsnm_usbctrl] UsbCtrl got a EnumDevices message, (null)
2011-09-26 07:18:35,610 DEBUG <MessageFrameWorkDispatch> [wsnm_usbctrl] UsbCtrl got a GetState message, (null)
2011-09-26 07:33:22,040 DEBUG <MessageFrameWorkDispatch> [wsnm_usbctrl] UsbCtrl got a EnumDevices message, (null)
2011-09-26 08:10:43,241 DEBUG <MessageFrameWorkDispatch> [wsnm_usbctrl] UsbCtrl got a EnumDevices message, (null)
2011-09-26 08:30:51,037 DEBUG <MessageFrameWorkDispatch> [wsnm_usbctrl] UsbCtrl got a EnumDevices message, (null)
2011-09-26 08:32:28,338 DEBUG <MessageFrameWorkDispatch> [wsnm_usbctrl] UsbCtrl got a EnumDevices message, (null)
2011-09-26 08:32:28,791 DEBUG <MessageFrameWorkDispatch> [wsnm_usbctrl] UsbCtrl got a GetState message, (null)

From virtual desktop :

- From Logs Files

00:53:29,627 INFO  <656> [wsnm_jms] Log for VMware View Agent, version=4.6.0 build-366101
00:53:29,627 INFO  <656> [wsnm_jms] hostname=MachineName.DomainName
00:53:29,392 INFO  <MessageFrameWorkDispatch> [] java bridge exe stopped by shutdown command.
00:53:32,330 INFO  <Service Main Thread> [wsnm] The VMware View System Service is shutting down
00:53:38,955 INFO  <Service Main Thread> [wsnm_desktop] DesktopManager queue server un-installed

07:17:09,671 INFO  <MessageFrameWorkDispatch> [MessageFrameWork] Client/agent channel pending wssm to start
07:17:11,796 INFO  <2816> [winlogon] Program 'winlogon' started, version=, pid=3308, buildtype=release, usethread=0, closeafterwrite=1
07:17:12,608 INFO  <2816> [winlogon] Authentication Manager successfully installed.
07:17:14,327 INFO  <2816> [winlogon] No SSO user data available
07:17:22,327 INFO  <3616> [winlogon] Program 'winlogon' started, version=, pid=1188, buildtype=release, usethread=0, closeafterwrite=1
07:17:22,796 INFO  <3616> [winlogon] Authentication Manager successfully installed.
07:17:22,858 INFO  <980> [winlogon] No SSO user data available
07:18:24,500 INFO  <Main Thread> [wsnm_desktop] Session created in PENDING state: id=0, winStation=RDP-Tcp#1
07:18:35,266 INFO  <3880> [MessageFrameWork] Program 'wssm - VMware View Framework Session Manager' started, version=4.6.0 build-366101, pid=3996, buildtype=release, usethread=1, closeafterwrite=0
07:18:35,375 INFO  <logloaded> [MessageFrameWork] Plugin 'wssm_desktop - VMware View Framework Desktop Instance Manager' loaded, version=4.6.0 build-366101, buildtype=release
07:18:35,375 INFO  <logloaded> [MessageFrameWork] Plugin 'ws_winauth - VMware View Framework Windows Authentication Support' loaded, version=4.6.0 build-366101, buildtype=release
07:18:35,391 INFO  <logloaded> [MessageFrameWork] Plugin 'ws_thinappmgmt - VMware View Framework Application Management Support' loaded, version=4.6.0 build-366101, buildtype=release
07:18:35,407 INFO  <logloaded> [MessageFrameWork] Plugin 'wssm_ui - VMware View Framework User Interface' loaded, version=4.6.0 build-366101, buildtype=release
07:18:35,422 INFO  <Main Thread> [wssm] Session Manager started for session 0
07:18:35,422 INFO  <logloaded> [MessageFrameWork] Plugin 'wssm_uimanager - VMware View Framework UI Host' loaded, version=4.6.0 build-366101, buildtype=release
07:18:35,469 INFO  <MessageChannel ReceiveThread> [MessageFrameWork] Session Manager logged in, sessionId=0, userDomainName=User1
07:18:35,563 INFO  <sessionConnectedThread> [wsnm_desktop] View Composer customization is not needed
07:18:35,563 INFO  <sessionConnectedThread> [wsnm_desktop] View Composer user login initialization state -65535 (in 0 seconds)
07:18:35,594 INFO  <sessionConnectedThread> [wsnm_desktop] Session CONNECTED: id=0, user User1, client=MachineName, connectionId=516E86D28F7A0E8ED00F823154039786, userDn=cn=s-1-5-21-54731450-1812880465-32586348-2237,cn=foreignsecurityprincipals,dc=vdi,dc=vmware,dc=int
07:18:35,594 INFO  <MessageFrameWorkDispatch> [MessageFrameWork] Client/agent channel connected for session = 0
07:54:40,036 INFO  <980> [winlogon] No SSO user data available
08:31:55,505 INFO  <980> [winlogon] No SSO user data available
09:33:24,208 INFO  <980> [winlogon] No SSO user data available