Hi,
I set GPO group policy on Active Directory OU in which I got my VDI desktops and user group. It works well when I use RDP. But when I use PCoIP it just not respect my GPO. Please what exactly is different between RDP resp. PCoIP process of authentification and applying of GPO?
Thanks.
Which GPO was set and what exactly is it doing? If you run gpupdate on the machine after logging in does it pull down the proper settings?
-Mike
I would also be curious to see what GPO policies you have changed that are PCoIP specific. There are roughly 23 RDP specific GPO settings and then there are general settings.
I see you have several posts here perhaps my Blog could assist you. http://paulslager.com/?p=572
What GPO are you specifically trying to do? Chances are we have a similar PCoIP-specific GPO. If you want to disable single sign-on, use VMware's "AllowSingleSignOn" GPO.
Hi,
Did you ever figure out this thing? We're having the same problem here.
Lots of GPOs are active and I'm sure one or more of them is causing this issue.
Lars
Hey Lars, what exactly is happening in your environment?
When I try to log in I either get a black screen or I get to the logon screen. From the logon scren I can press change user and logon corectly, but that's not how it's supposed to work.
Here are the logs from a client:
First try (getting disconnected):
Then second try (black screen hanging forever):
Sep 28 10:32:57.344: vmware-view| Log for VMware View Client pid=3023 version=4.0.1 build-235010 build=build-235010 option=Release
Sep 28 10:32:57.344: vmware-view| Host codepage=UTF-8 encoding=UTF-8
Sep 28 10:32:57.344: vmware-view| Using locale directory /usr/share/locale
Sep 28 10:32:57.349: vmware-view| Command line: vmware-view
Sep 28 10:32:57.665: vmware-view| Initialzing connection to broker https://10.aa.bb.251:443
Sep 28 10:32:57.665: vmware-view| Busy: Setting client locale...
Sep 28 10:32:57.680: vmware-view| Busy: Getting server configuration...
Sep 28 10:32:57.972: vmware-view| Auth Info: Name: windows-password, result: ok
Sep 28 10:33:02.120: vmware-view| Busy: Logging in...
Sep 28 10:33:03.055: vmware-view| Auth Info: Name: , result: ok
Sep 28 10:33:03.055: vmware-view| Executing secure HTTP tunnel: /usr/bin/vmware-view-tunnel
Sep 28 10:33:03.055: vmware-view| Starting child: /usr/bin/vmware-view-tunnel 'https://vcs01.my.domain.com:443' '[omitted]'
Sep 28 10:33:03.058: vmware-view| Number of monitors on this screen is 1.
Sep 28 10:33:03.058: vmware-view| Current window manager supports _NET_WM_FULLSCREEN_MONITORS message.
Sep 28 10:33:03.199: vmware-view| vmware-view-tunnel(3025): Connecting to tunnel server 'vcs01.my.domain.com:443' over HTTPS.
Sep 28 10:33:03.199: vmware-view| vmware-view-tunnel(3025): SOCKET creating new socket, connecting to 10.aa.bb.251:443 (vcs01.my.domain.com)
Sep 28 10:33:03.386: vmware-view| vmware-view-tunnel(3025): TUNNEL READY
Sep 28 10:33:04.291: vmware-view| Transitioning: Connecting to the desktop...
Sep 28 10:33:06.318: vmware-view| vmware-view-tunnel(3025): SOCKET creating new listening socket on port 0
Sep 28 10:33:06.318: vmware-view| vmware-view-tunnel(3025): Creating new listener "Port1" on 127.0.0.1:48123 to server 10.cc.bb.29:32111.
Sep 28 10:33:06.327: vmware-view| Desktop connect successful. Starting desktop using PCOIP...
Sep 28 10:33:06.327: vmware-view| Transitioning: Connecting to the desktop...
Sep 28 10:33:06.335: vmware-view| Connecting to desktop with total geometry 1260x787.
Sep 28 10:33:06.335: vmware-view| Starting usb redirection to '127.0.0.1:48123' with ticket '6a3e82f2-2806-49ce-ab00-3022b9d3596c'.
Sep 28 10:33:06.335: vmware-view| Starting child: /usr/bin/vmware-view-usb '-a' '127.0.0.1' '-p' '48123' '-u' '[omitted]'
Sep 28 10:33:06.337: vmware-view| Connecting to desktop CN=Win7,OU=Applications,DC=vdi,DC=vmware,DC=int: PCOIP://user@10.cc.bb.29:4172
Sep 28 10:33:06.337: vmware-view| Starting child: vmware-remotemks-container 'pcoip_client' 'mksvchanclient' '[omitted]' '1260x787' '92275634'
Sep 28 10:33:06.359: vmware-view| vmware-view-tunnel(3025): Creating new channel "1" to listener "Port1".
Sep 28 10:33:06.359: vmware-view| vmware-view-usb(3026): vmware-view-usb[3026]: Sep 28 10:33:06.10: vmware-view-usb| Starting...
Sep 28 10:33:06.438: vmware-view| vmware-remotemks-container(3027): Creating VMware Remote MKS interface
Sep 28 10:33:06.470: vmware-view| vmware-view-usb(3026): vmware-view-usb[3026]: Sep 28 10:33:06.393: vmware-view-usb| Remote USB status reported, state: available
Sep 28 10:33:06.471: vmware-view| vmware-view-usb(3026): vmware-view-usb[3026]: Sep 28 10:33:06.803: vmware-view-usb| Device attributes: Name: ChipsBnk Video Device, Id: vid6127,pid18439, Family: , Path: bus1,port7
Sep 28 10:33:06.471: vmware-view| vmware-view-usb(3026): vmware-view-usb[3026]: Sep 28 10:33:06.909: vmware-view-usb| 'ChipsBnk Video Device' is allowed.
Sep 28 10:33:06.487: vmware-view| vmware-view-usb(3026): vmware-view-usb[3026]: Sep 28 10:33:06.658: vmware-view-usb| ChipsBnk Video Device is being redirected
Sep 28 10:33:06.573: vmware-view| vmware-remotemks-container(3027): CreateMKSInterface: forcing mount, Remote MKS already present
Sep 28 10:33:06.573: vmware-view| vmware-remotemks-container(3027): Entering GTK main loop
Sep 28 10:33:16.486: vmware-view| vmware-view-usb(3026): vmware-view-usb[3026]: Sep 28 10:33:16.1: vmware-view-usb| Error while connecting Device: 'ChipsBnk Video Device'.
Sep 28 10:33:16.486: vmware-view| vmware-view-usb(3026): vmware-view-usb[3026]: Sep 28 10:33:16.125: vmware-view-usb| Device attributes: Name: AuthenTec Fingerprint Sensor, Id: vid2303,pid10256, Family: vendor, Path: bus4,port2
Sep 28 10:33:16.486: vmware-view| vmware-view-usb(3026): vmware-view-usb[3026]: Sep 28 10:33:16.154: vmware-view-usb| 'AuthenTec Fingerprint Sensor' is allowed.
Sep 28 10:33:16.494: vmware-view| vmware-view-usb(3026): vmware-view-usb[3026]: Sep 28 10:33:16.443: vmware-view-usb| AuthenTec Fingerprint Sensor is being redirected
Sep 28 10:33:28.576: vmware-view| Caught signal 20 -- tid 3023 (eip 0x009f2416)
Sep 28 10:33:29.251: vmware-view| vmware-remotemks-container(3027): Caught signal 20 -- pid 3027 (eip 0x006e9416)
Sep 28 12:33:02.271: vmware-view| vmware-remotemks-container(3027): vmdbPipe_Streams Couldn't read: OVL_STATUS_EOF
Sep 28 12:33:02.271: vmware-view| vmware-remotemks-container(3027): Remote MKS network connection status changed: DISCONNECTED
Sep 28 12:33:02.276: vmware-view| vmware-remotemks-container(3027): GTK main loop terminated
Sep 28 12:33:02.277: vmware-view| vmware-remotemks-container(3027) died.
Sep 28 12:33:02.277: vmware-view| vmware-remotemks-container(3027) exited normally.
Sep 28 12:33:02.279: vmware-view| Cancelling 0 broker XML requests.
Sep 28 12:33:02.280: vmware-view| vmware-view-usb(3026) exited normally.
Sep 28 12:33:02.280: vmware-view| vmware-view-tunnel(3025) exited due to signal 15.
Sep 28 12:33:02.280: vmware-view| Cancelling 0 broker XML requests.
Getting to the logon screen, prompted for password:
Sep 28 13:42:36.431: vmware-view| Log for VMware View Client pid=4337 version=4.0.1 build-235010 build=build-235010 option=Release
Sep 28 13:42:36.431: vmware-view| Host codepage=UTF-8 encoding=UTF-8
Sep 28 13:42:36.431: vmware-view| Using locale directory /usr/share/locale
Sep 28 13:42:36.434: vmware-view| Command line: vmware-view
Sep 28 13:42:36.809: vmware-view| Initialzing connection to broker https://10.aa.bb.251:443
Sep 28 13:42:36.809: vmware-view| Busy: Setting client locale...
Sep 28 13:42:36.816: vmware-view| Busy: Getting server configuration...
Sep 28 13:42:37.130: vmware-view| Auth Info: Name: windows-password, result: ok
Sep 28 13:42:41.162: vmware-view| Busy: Logging in...
Sep 28 13:42:42.164: vmware-view| Auth Info: Name: , result: ok
Sep 28 13:42:42.164: vmware-view| Executing secure HTTP tunnel: /usr/bin/vmware-view-tunnel
Sep 28 13:42:42.164: vmware-view| Starting child: /usr/bin/vmware-view-tunnel 'https://vcs01.my.domain.com:443' '[omitted]'
Sep 28 13:42:42.165: vmware-view| Number of monitors on this screen is 1.
Sep 28 13:42:42.165: vmware-view| Current window manager supports _NET_WM_FULLSCREEN_MONITORS message.
Sep 28 13:42:42.257: vmware-view| vmware-view-tunnel(4339): Connecting to tunnel server 'vcs01.my.domain.com:443' over HTTPS.
Sep 28 13:42:42.257: vmware-view| vmware-view-tunnel(4339): SOCKET creating new socket, connecting to 10.aa.bb.251:443 (vcs01.my.domain.com)
Sep 28 13:42:42.506: vmware-view| vmware-view-tunnel(4339): TUNNEL READY
Sep 28 13:42:43.177: vmware-view| Transitioning: Connecting to the desktop...
Sep 28 13:42:44.640: vmware-view| vmware-view-tunnel(4339): SOCKET creating new listening socket on port 0
Sep 28 13:42:44.640: vmware-view| vmware-view-tunnel(4339): Creating new listener "Port1" on 127.0.0.1:58054 to server 10.cc.bb8.28:32111.
Sep 28 13:42:44.648: vmware-view| Desktop connect successful. Starting desktop using PCOIP...
Sep 28 13:42:44.648: vmware-view| Transitioning: Connecting to the desktop...
Sep 28 13:42:44.656: vmware-view| Connecting to desktop with total geometry 1260x787.
Sep 28 13:42:44.656: vmware-view| Starting usb redirection to '127.0.0.1:58054' with ticket 'cca04037-adb0-478d-b5f5-73d4a8a6c6a9'.
Sep 28 13:42:44.656: vmware-view| Starting child: /usr/bin/vmware-view-usb '-a' '127.0.0.1' '-p' '58054' '-u' '[omitted]'
Sep 28 13:42:44.658: vmware-view| Connecting to desktop CN=Win7,OU=Applications,DC=vdi,DC=vmware,DC=int: PCOIP://user@10.cc.bb8.28:4172
Sep 28 13:42:44.658: vmware-view| Starting child: vmware-remotemks-container 'pcoip_client' 'mksvchanclient' '[omitted]' '1260x787' '94372768'
Sep 28 13:42:44.679: vmware-view| vmware-view-tunnel(4339): Creating new channel "1" to listener "Port1".
Sep 28 13:42:44.679: vmware-view| vmware-view-usb(4340): vmware-view-usb[4340]: Sep 28 13:42:44.45: vmware-view-usb| Starting...
Sep 28 13:42:44.719: vmware-view| vmware-remotemks-container(4341): Creating VMware Remote MKS interface
Sep 28 13:42:44.793: vmware-view| vmware-view-usb(4340): vmware-view-usb[4340]: Sep 28 13:42:44.626: vmware-view-usb| Remote USB status reported, state: available
Sep 28 13:42:44.793: vmware-view| vmware-view-usb(4340): vmware-view-usb[4340]: Sep 28 13:42:44.785: vmware-view-usb| Device attributes: Name: ChipsBnk Video Device, Id: vid6127,pid18439, Family: , Path: bus1,port7
Sep 28 13:42:44.793: vmware-view| vmware-view-usb(4340): vmware-view-usb[4340]: Sep 28 13:42:44.818: vmware-view-usb| 'ChipsBnk Video Device' is allowed.
Sep 28 13:42:44.799: vmware-view| vmware-view-usb(4340): vmware-view-usb[4340]: Sep 28 13:42:44.414: vmware-view-usb| ChipsBnk Video Device is being redirected
Sep 28 13:42:44.832: vmware-view| vmware-remotemks-container(4341): CreateMKSInterface: forcing mount, Remote MKS already present
Sep 28 13:42:44.833: vmware-view| vmware-remotemks-container(4341): Entering GTK main loop
Sep 28 13:42:54.799: vmware-view| vmware-view-usb(4340): vmware-view-usb[4340]: Sep 28 13:42:54.562: vmware-view-usb| Error while connecting Device: 'ChipsBnk Video Device'.
Sep 28 13:42:54.799: vmware-view| vmware-view-usb(4340): vmware-view-usb[4340]: Sep 28 13:42:54.696: vmware-view-usb| Device attributes: Name: AuthenTec Fingerprint Sensor, Id: vid2303,pid10256, Family: vendor, Path: bus4,port2
Sep 28 13:42:54.799: vmware-view| vmware-view-usb(4340): vmware-view-usb[4340]: Sep 28 13:42:54.747: vmware-view-usb| 'AuthenTec Fingerprint Sensor' is allowed.
Sep 28 13:42:54.805: vmware-view| vmware-view-usb(4340): vmware-view-usb[4340]: Sep 28 13:42:54.112: vmware-view-usb| AuthenTec Fingerprint Sensor is being redirected
Sep 28 13:42:55.024: vmware-view| Caught signal 20 -- tid 4337 (eip 0x00a7f416)
Sep 28 13:42:55.829: vmware-view| vmware-remotemks-container(4341): Caught signal 20 -- pid 4341 (eip 0x0060b416)
Sep 28 13:43:10.962: vmware-view| vmware-remotemks-container(4341) exited normally.
Sep 28 13:43:10.962: vmware-view| Cancelling 0 broker XML requests.
Sep 28 13:43:10.969: vmware-view| vmware-view-usb(4340) exited normally.
Sep 28 13:43:10.969: vmware-view| vmware-view-tunnel(4339) exited due to signal 15.
Sep 28 13:43:10.969: vmware-view| Cancelling 0 broker XML requests.
Here is the log from the virtual desktop (first two logon attempts up here where we go disconnected and black screen):
2011-09-28 10:32:11,194 DEBUG <MessageFrameWorkDispatch> [ws_vmx] Rpci: Sending request='machine.id.get '
2011-09-28 10:32:11,194 DEBUG <MessageFrameWorkDispatch> [ws_vmx] Rpci: Sent request='machine.id.get ', reply='vdi.broker.useSvi=1;vdi.broker.brokerPublicKey=MIHxMIGoBgcqhkjOOAQBMIGcAkEA/KaCzo4Syrom78z3EQ5SbbB4sF7ey80etKII864WF64B81uRpH5t9jQTxeEu0ImbzRMqzVDZkVG9xD7nN1kuFwIVAJYu3cw2nLqOuyYO5rahJtk0bjjFAkBnhHGyepz0TukaScUUfbGpqvJE8FpDTWSGkx0tFCcbnjUDC3H9c9oXkGmzLik1Yw4cIGI1TQ2iCmxBblC+eUykA0QAAkEAnfprpbzURfAm0NQVv171IIKWUKWkZfZrhTq5EVoZ/2pfhPo+eB7dH88gg/KDiuJDLP+1lGn9aft20F6YwPCW+Q==;vdi.broker.brokers=vcs01.my.domain.com ;vdi.broker.agentIdentity=agent/4bf136e4-59ae-4a26-9a9d-483fcf13d05c;vdi.broker.agentPrivateKeyHash=r78jwga/k50Wee7YI9K6WA==;vdi.broker.itemDn=cn=4bf136e4-59ae-4a26-9a9d-483fcf13d05c,ou=servers,dc=vdi,dc=vmware,dc=int;vdi.broker.asyncSessionSeconds=150;vdi.broker.brokerPublicKeyHash=kTI3JoIz8FO2vCyXjOk7XQ==;vdi.broker.singleuse2=1;vdi.broker.msMode=OFF;vdi.broker.agentPrivateKey=MIHGAgEAMIGoBgcqhkjOOAQBMIGcAkEA/KaCzo4Syrom78z3EQ5SbbB4sF7ey80etKII864WF64B81uRpH5t9jQTxeEu0ImbzRMqzVDZkVG9xD7nN1kuFwIVAJYu3cw2nLqOuyYO5rahJtk0bjjFAkBnhHGyepz0TukaScUUfbGpqvJE8FpDTWSGkx0tFCcbnjUDC3H9c9oXkGmzLik1Yw4cIGI1TQ2iCmxBblC+eUykBBYCFB2DvXwmYGUt8uo4kaeWbv6T9hEc;vdi.broker.disconnecttimeout=120;vdi.broker.singleuse=0;vdi.broker.useSysprep=0;vdi.broker.poolDn=cn=win7,ou=server groups,dc=vdi,dc=vmware,dc=int', len=1222, status=1
2011-09-28 10:32:19,694 DEBUG <Thread-73> [ComponentResponse] Reponse directed to:ID:/127.0.0.1/5876504814872078132/80530/0
2011-09-28 10:32:19,694 DEBUG <Thread-73> [ComponentResponse] Message is <?xml version="1.0"?>
<TERMINALRESPONSE><ID>30833744:13274111049:-1e58</ID><SERVERDN>cn=4bf136e4-59ae-4a26-9a9d-483fcf13d05c,ou=servers,dc=vdi,dc=vmware,dc=int</SERVERDN><SERVERPOOLDN>cn=win7,ou=server groups,dc=vdi,dc=vmware,dc=int</SERVERPOOLDN><SERVERDNSNAME>V02-00003-A.domain.com</SERVERDNSNAME><DYNAMICIPADDRESS>10.aa.bb.29</DYNAMICIPADDRESS><__JMS_ENCRYPTED__>oYHmMIHjoAMKAQGhDAYKKwYBBAGCNwICCqKBzQSByk5UTE1TU1AAAgAAAA4ADgA4AAAANYKJ4v+5CLcz7bA8AAAAAAAAAACEAIQARgAAAAYBsR0AAAAPSABFAEwAUwBFAE0ATgACAA4ASABFAEwAUwBFAE0ATgABABYAVgAwADIALQAwADAAMAAwADMALQBBAAQAFABoAGUAbABzAGUAbQBuAC4AbgBvAAMALABWADAAMgAtADAAMAAwADAAMwAtAEEALgBoAGUAbABzAGUAbQBuAC4AbgBvAAcACAD5WwYjuX3MAQAAAAA=</__JMS_ENCRYPTED__><complete></complete></TERMINALRESPONSE>
2011-09-28 10:32:19,694 DEBUG <theTopicMessageResponder> [TopicMessageResponder] Reponse message ready.
2011-09-28 10:32:19,694 DEBUG <theTopicMessageResponder> [TopicMessageResponder] Response sent.
2011-09-28 10:32:19,694 DEBUG <theTopicMessageResponder> [TopicMessageResponder] Waiting for response message.
2011-09-28 10:32:19,709 DEBUG <Thread-74> [ComponentResponse] Reponse directed to:ID:/127.0.0.1/5876504814872078132/80532/0
2011-09-28 10:32:19,709 DEBUG <Thread-74> [ComponentResponse] Message is <?xml version="1.0"?>
<TERMINALRESPONSE><ID>30833744:13274111049:-1e57</ID><SERVERDN>cn=4bf136e4-59ae-4a26-9a9d-483fcf13d05c,ou=servers,dc=vdi,dc=vmware,dc=int</SERVERDN><SERVERPOOLDN>cn=win7,ou=server groups,dc=vdi,dc=vmware,dc=int</SERVERPOOLDN><SERVERDNSNAME>V02-00003-A.domain.com</SERVERDNSNAME><DYNAMICIPADDRESS>10.aa.bb.29</DYNAMICIPADDRESS><__JMS_ENCRYPTED__>oRswGaADCgEAoxIEEAEAAADL0a68nAgrqQAAAAA=</__JMS_ENCRYPTED__><complete>true</complete></TERMINALRESPONSE>
2011-09-28 10:32:19,709 DEBUG <theTopicMessageResponder> [TopicMessageResponder] Reponse message ready.
2011-09-28 10:32:19,709 DEBUG <theTopicMessageResponder> [TopicMessageResponder] Response sent.
2011-09-28 10:32:19,709 DEBUG <theTopicMessageResponder> [TopicMessageResponder] Waiting for response message.
2011-09-28 10:32:19,725 DEBUG <MessageFrameWorkDispatch> [wsnm_desktop] DesktopManager got a StartSession message {SESSION:A3DF527B0A430929BFE2EF31EA62AF97;306A472644930C0774785376EDE558BD}
2011-09-28 10:32:19,725 DEBUG <MessageFrameWorkDispatch> [wsnm_desktop] StartSession CLIENTINFO =
MAC_Address = "00:1C:25:99:36:0A"
Machine_Name = "view-client"
Type = "Linux"
Broker_Tunnel_URL = "https://vcs01.my.domain.com:443"
Broker_Remote_IP_Address = "10.dd.bb.27"
Machine_Domain = "(none)"
TZID = "Europe/Oslo"
IP_Address = "10.dd.bb.27"
Broker_DNS_Name = "vcs01.my.domain.com"
Windows_Timezone = "Central European Standard Time"
Language = "en_US.utf8"
LoggedOn_Username = "lars"
Broker_Tunneled = "true"
Broker_URL = "https://10.cc.bb.251:443" {SESSION:A3DF527B0A430929BFE2EF31EA62AF97;306A472644930C0774785376EDE558BD}
2011-09-28 10:32:19,725 DEBUG <MessageFrameWorkDispatch> [wsnm_desktop] New session policies:
overrideProfilePath = "false"
allowMMR = "true"
remoteProfilePath = ""
flashThrottling = "0"
allowUSB = "true"
flashQuality = "0"
hostCardAcceleration = "3"
enableVirtualProfiles = "false" {SESSION:A3DF527B0A430929BFE2EF31EA62AF97;306A472644930C0774785376EDE558BD}
2011-09-28 10:32:19,725 DEBUG <MessageFrameWorkDispatch> [wsnm_desktop] commandhandler::startSession: Received windows credentials for SSO {SESSION:A3DF527B0A430929BFE2EF31EA62AF97;306A472644930C0774785376EDE558BD}
2011-09-28 10:32:19,725 DEBUG <MessageFrameWorkDispatch> [wsnm_desktop] Starting protocol PCOIP... {SESSION:A3DF527B0A430929BFE2EF31EA62AF97;306A472644930C0774785376EDE558BD}
2011-09-28 10:32:19,741 DEBUG <MessageFrameWorkDispatch> [wsnm_desktop] Launch PCoIP server. {SESSION:A3DF527B0A430929BFE2EF31EA62AF97;306A472644930C0774785376EDE558BD}
2011-09-28 10:32:20,053 INFO <3024> [pcoip_server_win32] Program 'pcoip_server_win32 - PCoIP Server' started, version=3,8,0,9606:soft_pcoip_rc_3_8, pid=4016, buildtype=release, usethread=0, closeafterwrite=0
2011-09-28 10:32:20,053 DEBUG <3024> [pcoip_server_win32] pcoip_vchan_plugin_server_init(): loading
2011-09-28 10:32:20,053 DEBUG <3024> [pcoip_server_win32] pcoip_vchan_plugin_server_init(): Using PCoIP virtual channel API version 1
2011-09-28 10:32:20,053 DEBUG <3024> [pcoip_server_win32] pcoip_vchan_plugin_server_init(): starting message framework
2011-09-28 10:32:20,053 DEBUG <3024> [pcoip_server_win32] pcoip_server_win32 MessageQueueShared started with numThreads=2, queueTreshold=250
2011-09-28 10:32:20,053 DEBUG <3024> [pcoip_server_win32] pcoip_server_win32 MessageQueue started with numThreads=2, queueTreshold=1000
2011-09-28 10:32:20,053 DEBUG <3024> [pcoip_server_win32] MessageFrameWork Worker Added, Name=System, Description=The Builtin MessageFrameWork Administration Queue, Channel=00000000
2011-09-28 10:32:20,069 DEBUG <NodeManagerWatcher> [pcoip_server_win32] Outgoing SharedMemory channel from PCoIP Virtual Channels to machine V02-00003-A.domain.com wsnm connected as : domain\V02-00003-A$, Authenticated through SSPI, package = NTLM, encrypting = true
2011-09-28 10:32:20,069 DEBUG <NodeManagerWatcher> [pcoip_server_win32] Connection to Node Manager established.
2011-09-28 10:32:20,069 DEBUG <NodeManagerWatcher> [pcoip_server_win32] ChannelCallbackEx: remoteName:"wsnm" incoming:false local:true opened:true
2011-09-28 10:32:20,069 DEBUG <SharedMemAuthenticateThread> [MessageFrameWork] Incoming SharedMemory channel from machine V02-00003-A.domain.com PCoIP Virtual Channels to wsnm connected as : domain\V02-00003-A$, Authenticated through SSPI, package = NTLM, encrypting = true, canImpersonate = true
2011-09-28 10:32:20,069 DEBUG <SharedMemAuthenticateThread> [wssm_usb] USB channel callback: remoteName: PCoIP Virtual Channels incoming: true local: true opened: true
2011-09-28 10:32:20,069 DEBUG <MessageFrameWorkDispatch> [MessageFrameWork] creating thread for privileged message
2011-09-28 10:32:20,163 DEBUG <3024> [pcoip_server_win32] MessageFrameWork Worker Added, Name=PCoIPVChan-SERVER, Description=PCoIPVChan, Channel=00000000
2011-09-28 10:32:20,163 DEBUG <3024> [pcoip_server_win32] pcoip_vchan_plugin_server_init(): sending initial message
2011-09-28 10:32:20,163 DEBUG <3024> [pcoip_server_win32] pcoip_vchan_plugin_server_init(): ready
2011-09-28 10:32:20,163 DEBUG <MessageFrameWorkDispatch> [MessageFrameWork] MessageFrameWork Worker Added, Name=PCoIPVChan-SERVER, Description=PCoIPVChan, Channel=00000000009F6870
2011-09-28 10:32:20,975 DEBUG <MessageFrameWorkDispatch> [wsnm_desktop] PCoIPCnx::OnConnectionResponse {SESSION:A3DF527B0A430929BFE2EF31EA62AF97;306A472644930C0774785376EDE558BD}
2011-09-28 10:32:20,975 DEBUG <MessageFrameWorkDispatch> [wsnm_desktop] PCoIP connection response: OK {SESSION:A3DF527B0A430929BFE2EF31EA62AF97;306A472644930C0774785376EDE558BD}
2011-09-28 10:32:20,975 DEBUG <MessageFrameWorkDispatch> [wsnm_desktop] PCoIP connect to handle: hndl 0x2 {SESSION:A3DF527B0A430929BFE2EF31EA62AF97;306A472644930C0774785376EDE558BD}
2011-09-28 10:32:20,975 DEBUG <MessageFrameWorkDispatch> [wsnm_desktop] PCoIP connection request succeeded! (handle=0x2) {SESSION:A3DF527B0A430929BFE2EF31EA62AF97;306A472644930C0774785376EDE558BD}
2011-09-28 10:32:20,975 DEBUG <MessageFrameWorkDispatch> [wsnm_desktop] Protocol PCOIP is listening on 10.aa.bb.29:4172 {SESSION:A3DF527B0A430929BFE2EF31EA62AF97;306A472644930C0774785376EDE558BD}
2011-09-28 10:32:20,975 DEBUG <MessageFrameWorkDispatch> [wsnm_desktop] DesktopManager got a SendEvent message {SESSION:A3DF527B0A430929BFE2EF31EA62AF97;306A472644930C0774785376EDE558BD}
2011-09-28 10:32:20,975 DEBUG <MessageFrameWorkDispatch> [wsnm_desktop] startSession added portal logon for user trusteddomain\user, timeout=900 secs, pending count=1 {SESSION:A3DF527B0A430929BFE2EF31EA62AF97;306A472644930C0774785376EDE558BD}
2011-09-28 10:32:20,975 DEBUG <Thread-77> [ComponentResponse] Message is PENDING
2011-09-28 10:32:20,975 DEBUG <theTopicPublishingManager> [TopicPublishingManager] Message to publish ready.
2011-09-28 10:32:20,975 DEBUG <theTopicPublishingManager> [TopicPublishingManager] Message published.
2011-09-28 10:32:20,975 DEBUG <theTopicPublishingManager> [TopicPublishingManager] Waiting for message to publish.
2011-09-28 10:32:20,975 DEBUG <Thread-78> [EventLoggerService] Info_Event:[AGENT_PENDING] "The agent running on machine V02-00003-A has accepted an allocated session for user trusteddomain\user": MachineName=V02-00003-A, Module=Agent, UserDisplayName=trusteddomain\user, Source=com.vmware.vdi.ob.lib.b, Severity=INFO, Time=Wed Sep 28 10:32:20 CEST 2011, Node=V02-00003-A.domain.com, PoolId=win7, UserSID=S-1-5-21-1659004503-2000478354-839522115-12212, MachineId=4bf136e4-59ae-4a26-9a9d-483fcf13d05c, Acknowledged=true {SESSION:A3DF527B0A430929BFE2EF31EA62AF97;306A472644930C0774785376EDE558BD}
2011-09-28 10:32:20,991 DEBUG <Thread-79> [ComponentResponse] Reponse directed to:ID:/127.0.0.1/5876504814872078132/80534/0 {SESSION:A3DF527B0A430929BFE2EF31EA62AF97;306A472644930C0774785376EDE558BD}
2011-09-28 10:32:20,991 DEBUG <Thread-79> [ComponentResponse] Message is <?xml version="1.0"?>
<TERMINALRESPONSE><ID>30833744:13274111049:-1e56</ID><SERVERDN>cn=4bf136e4-59ae-4a26-9a9d-483fcf13d05c,ou=servers,dc=vdi,dc=vmware,dc=int</SERVERDN><SERVERPOOLDN>cn=win7,ou=server groups,dc=vdi,dc=vmware,dc=int</SERVERPOOLDN><SERVERDNSNAME>V02-00003-A.domain.com</SERVERDNSNAME><DYNAMICIPADDRESS>10.aa.bb.29</DYNAMICIPADDRESS><ID>30833744:13274111049:-1e55</ID><COMMAND>StartSession</COMMAND><encryptId>30833744:13274111049:-1e59</encryptId><encryptState>data</encryptState><__JMS_ENCRYPTED__>AQAAANZIhs9JF6qsAQAAAFmrqd3+djuHVaSXPq+yrbMjlvwuTF1crJr2bfFWIL499IcQfwYQ88QJ/wTkj6LPv8tSSSWLclbnuC+Wt6JTOFddzqIlloy9qf2zESbSS9iuMmLunOY8DprrlESvcZpAK9bCP1X1NKdZDetGWXK/ol5Vj2YYKTsXDnBRMHDi4tUX+hxIDMJyp93VGB8cDlofwch9ovGVOnLasmYThYFRkk/geH7Ff3IdqDUOW5EwPiP9WaPYZxUVsVHR4U1vv/yg0Vs1VIgB8881jxS34mIoWAQFOQQTo2XwqTqRZP1wDlvcK1/qANZmpx3SUCdUcmYd8MkrwHqSNcO7dOzwSfORKFwQGPvERotEogbRh7NAUH7kDtbTSvw5uX9JkF4ugdATf8bi+yEg5K2rgRmYMPKl5HgowcSRsUJX3LXBt1TVbzxNyuBUOAeUiX6ULV52ESZMY0AXdae4jyoxA2EYTKz1Ben7s6+NpjEo2MowU1BtyjLajldOSjcgflaIXjpP/HF6SsuHaAgDWpUPDJkDsA1+jKc2Ettrs6R57ZMgZsepTHGJAUVeR5bn7RnO3vrSWS+8leFiCnao90iYeyGHYBEW2hxtKMLKWJe3fAlVApSGueDbGevIkVEjv4NjmK0kTejLS/8uI35sIUCEZ5YAAI1zkAB5I2NE96hJRO80itsMNVAbaU6IAIGTG8wOM8uZ3hOcK3lLS49lRTpFLx51G5JlBjkZAczLwRj5g9tNTJdovWwWSAQmt+AprZVx40NfPwKzrSJVqABbHFZ+kSvgSxIWOX4Txrf7sU4VtbSO0IkkTSbxW70W/G9JeO2KnXIcexaWWIKbVJ03cKwJIcZhsIWqDI+yrZZDJIc52T1IJkG4H8Q+ISQ3nQUWNtCkSlezSp/GMIzDPnc+5c5kdmigKdzDwR+kZEGemckYsdsd0hxYZ67ZhA==</__JMS_ENCRYPTED__></TERMINALRESPONSE> {SESSION:A3DF527B0A430929BFE2EF31EA62AF97;306A472644930C0774785376EDE558BD}
2011-09-28 10:32:20,991 DEBUG <theTopicMessageResponder> [TopicMessageResponder] Reponse message ready.
2011-09-28 10:32:20,991 DEBUG <theTopicMessageResponder> [TopicMessageResponder] Response sent.
2011-09-28 10:32:20,991 DEBUG <theTopicMessageResponder> [TopicMessageResponder] Waiting for response message.
2011-09-28 10:32:20,991 DEBUG <theEventPublishingManager> [EventPublishingManager] Waiting for message to publish.
2011-09-28 10:32:21,272 DEBUG <SocketAuthenticateThread> [MessageFrameWork] Incoming Socket channel from machine view-client.(none) wswc to wsnm connected as : \, Authenticated through TICKET, encrypting = false, canImpersonate = false
2011-09-28 10:32:21,272 DEBUG <SocketAuthenticateThread> [wssm_usb] USB channel callback: remoteName: wswc incoming: true local: false opened: true
2011-09-28 10:32:21,272 DEBUG <SocketAuthenticateThread> [wssm_usb] Attempting to initialise Virtual USB subsystems.
2011-09-28 10:32:21,272 DEBUG <SocketAuthenticateThread> [wssm_usb] Opened Virtual USB interface, hub version: 0x00010000
2011-09-28 10:32:21,272 DEBUG <SocketAuthenticateThread> [wssm_usb] Starting USB forwarding thread
2011-09-28 10:32:21,272 DEBUG <UsbForwarderThread> [wssm_usb] Starting Hub thread
2011-09-28 10:32:21,272 DEBUG <MessageFrameWorkDispatch> [MessageFrameWork] System::CreateRemoteSessionChannel
2011-09-28 10:32:21,288 DEBUG <MessageFrameWorkDispatch> [wsnm_desktop] DesktopManager got a FindVirtualChannelTicket message
2011-09-28 10:32:21,288 DEBUG <MessageFrameWorkDispatch> [wsnm_desktop] findChannelTicketSession, session is pending
2011-09-28 10:32:21,288 INFO <MessageFrameWorkDispatch> [MessageFrameWork] Client/agent channel pending wssm to start
2011-09-28 10:32:21,288 DEBUG <MessageFrameWorkShare> [wssm_usb] UsbRemoteManager got a IsUsbAvailable message
2011-09-28 10:32:21,288 DEBUG <MessageFrameWorkShare> [wsnm_desktop] DesktopManager got a GetSessionInfoForChannelTicket message
2011-09-28 10:32:21,288 DEBUG <MessageFrameWorkDispatch> [MessageFrameWork] System::CreateRemoteSessionChannel
2011-09-28 10:32:21,288 DEBUG <MessageFrameWorkDispatch> [wsnm_desktop] DesktopManager got a FindVirtualChannelTicket message
2011-09-28 10:32:21,803 DEBUG <3904> [wsnm_desktop] PCoIP connection complete: hndl 0x2 code 0
2011-09-28 10:32:21,803 DEBUG <3904> [wsnm_desktop] PCoIPCnx::OnConnectionComplete Begin
2011-09-28 10:32:21,803 DEBUG <3904> [wsnm_desktop] disabling Display Settings in Control Panel
2011-09-28 10:32:21,819 DEBUG <3904> [wsnm_desktop] PCoIPCnx::NotifySmartCard Inform smartcard redirection that a PCoIP session is being set up.
2011-09-28 10:32:21,819 DEBUG <MessageFrameWorkDispatch> [wsnm_desktop] DesktopManager got a GetPCoIPClientProperties message
2011-09-28 10:32:21,819 DEBUG <1264> [wsnm_scredir] PCoIPServerMonitor: clientName='view-client'
2011-09-28 10:32:21,819 DEBUG <1264> [wsnm_scredir] PCoIPServerMonitor: Driver Notified of the connection
2011-09-28 10:32:21,819 DEBUG <3904> [wsnm_desktop] PCoIPCnx::NotifySmartCard PCoIP smartcard redirection confirmed that it's ready.
2011-09-28 10:32:21,819 DEBUG <3904> [wsnm_desktop] SessionSwitchToConsole is not performed : Not allowed in Vista Family.
2011-09-28 10:32:21,819 DEBUG <3904> [wsnm_desktop] PCoIPCnx::OnConnectionComplete End
2011-09-28 10:32:21,819 DEBUG <SimpleEventPoll> [wsnm_desktop] Simulating Ctrl+Alt+Del for SSO.
2011-09-28 10:32:21,819 DEBUG <3000> [pcoip_server_win32] VChanServerHost::SignalConnectionEvent: Signaling event 'Global\PCoIP_VChan-Reconnect'
2011-09-28 10:32:21,819 DEBUG <3000> [pcoip_server_win32] VChanServerHost::SignalConnectionEvent: The 'Name' key doesn't exist for addin 'Dynamic VC'.
2011-09-28 10:32:21,819 DEBUG <VChanConnectionMonitor> [wsnm_scredir] VChanMgr::HandleConnectCallback: Received connection notification.
2011-09-28 10:32:21,819 DEBUG <VChanConnectionMonitor> [wsnm_scredir] VChanMgr::OpenChannelHelper: VChan open succeeded. Channel handle is 256.
2011-09-28 10:32:21,881 DEBUG <1180> [LogonUI] Outgoing SharedMemory channel from LogonUI to machine V02-00003-A.domain.com wsnm connected as : domain\V02-00003-A$, Authenticated through SSPI, package = NTLM, encrypting = true
2011-09-28 10:32:21,881 DEBUG <1180> [LogonUI] LogonUI MessageQueueShared started with numThreads=1, queueTreshold=250
2011-09-28 10:32:21,881 DEBUG <SharedMemAuthenticateThread> [MessageFrameWork] Incoming SharedMemory channel from machine V02-00003-A.domain.com LogonUI to wsnm connected as : domain\V02-00003-A$, Authenticated through SSPI, package = NTLM, encrypting = true, canImpersonate = true
2011-09-28 10:32:21,881 DEBUG <SharedMemAuthenticateThread> [wssm_usb] USB channel callback: remoteName: LogonUI incoming: true local: true opened: true
2011-09-28 10:32:21,881 DEBUG <MessageFrameWorkDispatch> [wsnm_desktop] DesktopManager got a GetCredentialsForTicket message
2011-09-28 10:32:21,881 DEBUG <MessageFrameWorkDispatch> [wsnm_desktop] ticket::GetCredentialsForTicket Ticket found from portal, session = 1, user = LOCAL\console
2011-09-28 10:32:21,881 DEBUG <MessageChannel ReceiveThread> [LogonUI] Closed outgoing SharedMemory channel to machine V02-00003-A.domain.com, user domain\V02-00003-A$
2011-09-28 10:32:21,881 DEBUG <MessageChannel ReceiveThread> [wssm_usb] USB channel callback: remoteName: LogonUI incoming: true local: true opened: false
2011-09-28 10:32:21,881 DEBUG <MessageFrameWorkShare> [MessageFrameWork] Closed incoming SharedMemory channel from machine V02-00003-A.domain.com, user domain\V02-00003-A$
2011-09-28 10:32:21,913 DEBUG <1180> [LogonUI] credprovider::GetCredentialCount: Allowing autologon: console protocol credentials available
2011-09-28 10:32:21,928 DEBUG <1180> [LogonUI] cred::GetSerialization: Using PCoIP password credentials for SSO.
2011-09-28 10:32:24,366 DEBUG <SharedMemAuthenticateThread> [MessageFrameWork] Incoming SharedMemory channel from machine V02-00003-A.domain.com lsass to wsnm connected as : domain\V02-00003-A$, Authenticated through SSPI, package = NTLM, encrypting = true, canImpersonate = true
2011-09-28 10:32:24,366 DEBUG <SharedMemAuthenticateThread> [wssm_usb] USB channel callback: remoteName: lsass incoming: true local: true opened: true
2011-09-28 10:32:24,366 DEBUG <2708> [lsass] Outgoing SharedMemory channel from lsass to machine V02-00003-A.domain.com wsnm connected as : domain\V02-00003-A$, Authenticated through SSPI, package = NTLM, encrypting = true
2011-09-28 10:32:24,366 DEBUG <2708> [lsass] lsass MessageQueueShared started with numThreads=1, queueTreshold=250
2011-09-28 10:32:24,366 DEBUG <MessageFrameWorkDispatch> [wsnm_desktop] DesktopManager got a CreatePartialLogon message
2011-09-28 10:32:24,366 DEBUG <MessageFrameWorkDispatch> [wsnm_desktop] Adding partial logon for session 1
2011-09-28 10:32:24,366 DEBUG <MessageChannel ReceiveThread> [wssm_usb] USB channel callback: remoteName: lsass incoming: true local: true opened: false
2011-09-28 10:32:24,366 DEBUG <MessageChannel ReceiveThread> [MessageFrameWork] Closed incoming SharedMemory channel from machine V02-00003-A.domain.com, user domain\V02-00003-A$
2011-09-28 10:32:24,366 DEBUG <2708> [lsass] Closed outgoing SharedMemory channel to machine V02-00003-A.domain.com, user domain\V02-00003-A$
2011-09-28 10:32:27,819 DEBUG <Main Thread> [wsnm_desktop] WTS_SESSION_LOGON, session=1
2011-09-28 10:32:27,819 DEBUG <Main Thread> [wsnm_desktop] Removing partial logon for session 1
2011-09-28 10:32:27,819 INFO <Main Thread> [wsnm_desktop] Session created in PENDING state: id=1, winStation=Console
2011-09-28 10:32:28,022 INFO <2884> [MessageFrameWork] Program 'wssm - VMware View Framework Session Manager' started, version=5.0.0 build-481677, pid=3188, buildtype=release, usethread=1, closeafterwrite=0
2011-09-28 10:32:28,037 DEBUG <Main Thread> [wssm] Session Manager starting
2011-09-28 10:32:28,037 DEBUG <Main Thread> [MessageFrameWork] wssm MessageQueueShared started with numThreads=10, queueTreshold=250
2011-09-28 10:32:28,037 DEBUG <Main Thread> [MessageFrameWork] wssm MessageQueue started with numThreads=10, queueTreshold=1000
2011-09-28 10:32:28,037 DEBUG <Main Thread> [MessageFrameWork] MessageFrameWork Worker Added, Name=System, Description=The Builtin MessageFrameWork Administration Queue, Channel=0000000000000000
2011-09-28 10:32:28,037 DEBUG <Main Thread> [MessageFrameWork] MessageFrameWork Worker Added, Name=SessionManager, Description=The SessionManager instance, Channel=0000000000000000
2011-09-28 10:32:28,194 DEBUG <MessageFrameWorkDispatch> [MessageFrameWork] System::LoadPlugin
2011-09-28 10:32:28,194 INFO <logloaded> [MessageFrameWork] Plugin 'wssm_desktop - VMware View Framework Desktop Instance Manager' loaded, version=5.0.0 build-481677, buildtype=release
2011-09-28 10:32:28,241 DEBUG <MessageFrameWorkDispatch> [MessageFrameWork] MessageFrameWork Worker Added, Name=WinAuth, Description=Support for windows authentication tasks, Channel=0000000000000000
2011-09-28 10:32:28,241 DEBUG <MessageFrameWorkDispatch> [ws_winauth] WindowsAuthentication queue server installed
2011-09-28 10:32:28,241 DEBUG <Main Thread> [MessageFrameWork] MessageFrameWork Worker Added, Name=DesktopInstance, Description=The remote desktop session instance, Channel=0000000000000000
2011-09-28 10:32:28,241 DEBUG <Main Thread> [wssm_desktop] MainWnd::StartLoop
2011-09-28 10:32:28,256 INFO <logloaded> [MessageFrameWork] Plugin 'ws_winauth - VMware View Framework Windows Authentication Support' loaded, version=5.0.0 build-481677, buildtype=release
2011-09-28 10:32:28,303 DEBUG <PluginInitThread> [MessageFrameWork] MessageFrameWork Worker Added, Name=ApplicationManagement, Description=Support for VMware ThinApp, Channel=0000000000000000
2011-09-28 10:32:28,303 INFO <Main Thread> [wssm] Session Manager started for session 1
2011-09-28 10:32:28,303 INFO <logloaded> [MessageFrameWork] Plugin 'ws_thinappmgmt - VMware View Framework Application Management Support' loaded, version=5.0.0 build-481677, buildtype=release
2011-09-28 10:32:28,303 DEBUG <MessageFrameWorkDispatch> [wssm_desktop] DesktopInstance1 got a getPortalInfo message
2011-09-28 10:32:28,303 INFO <logloaded> [MessageFrameWork] Plugin 'wssm_ui - VMware View Framework User Interface' loaded, version=5.0.0 build-481677, buildtype=release
2011-09-28 10:32:28,303 INFO <logloaded> [MessageFrameWork] Plugin 'wssm_uimanager - VMware View Framework UI Host' loaded, version=5.0.0 build-481677, buildtype=release
2011-09-28 10:32:28,319 DEBUG <PluginInitThread> [MessageFrameWork] MessageFrameWork Worker Added, Name=TransfersUI, Description=UI for replication and other CVP transfers, Channel=0000000000000000
2011-09-28 10:32:28,319 DEBUG <SharedMemAuthenticateThread> [MessageFrameWork] Incoming SharedMemory channel from machine V02-00003-A.domain.com wssm to wsnm connected as : trusteddomain\user, Authenticated through SSPI, package = NTLM, encrypting = true, canImpersonate = true
2011-09-28 10:32:28,319 DEBUG <SharedMemAuthenticateThread> [wssm_usb] USB channel callback: remoteName: wssm incoming: true local: true opened: true
2011-09-28 10:32:28,319 DEBUG <NodeManagerWatcher> [MessageFrameWork] Outgoing SharedMemory channel from wssm to machine V02-00003-A.domain.com wsnm connected as : trusteddomain\user, Authenticated through SSPI, package = NTLM, encrypting = true
2011-09-28 10:32:28,319 DEBUG <NodeManagerWatcher> [MessageFrameWork] Connection to Node Manager established.
2011-09-28 10:32:28,319 INFO <MessageChannel ReceiveThread> [MessageFrameWork] Session Manager logged in, sessionId=1, userDomainName=trusteddomain\user
2011-09-28 10:32:28,334 DEBUG <MessageFrameWorkDispatch> [MessageFrameWork] MessageFrameWork Worker Added, Name=DesktopInstance1, Description=The remote desktop session instance, Channel=00000000009F6A00
2011-09-28 10:32:28,334 DEBUG <MessageFrameWorkDispatch> [MessageFrameWork] MessageFrameWork Worker Added, Name=SessionManager1, Description=The SessionManager instance, Channel=00000000009F6A00
2011-09-28 10:32:28,334 DEBUG <MessageFrameWorkDispatch> [MessageFrameWork] MessageFrameWork Worker Added, Name=TransfersUI, Description=UI for replication and other CVP transfers, Channel=00000000009F6A00
2011-09-28 10:32:28,334 DEBUG <MessageFrameWorkShare> [wssm_usb] UsbRemoteManager got a SessionStartup message
2011-09-28 10:32:28,334 DEBUG <MessageFrameWorkShare> [MessageFrameWork] MessageFrameWork Worker Added, Name=UsbRemoteManager1, Description=USB manager for nodes virtualising devices remotely, Channel=0000000000000000
2011-09-28 10:32:28,334 DEBUG <MessageFrameWorkShare> [wssm_usb] Added worker UsbRemoteManager1
2011-09-28 10:32:28,334 DEBUG <MessageFrameWorkDispatch> [MessageFrameWork] System::IsWorkerInstalled
2011-09-28 10:32:28,350 DEBUG <InitUIManager> [MessageFrameWork] MessageFrameWork Worker Added, Name=UIManager, Description=Provides User Interface services for a session, Channel=0000000000000000
2011-09-28 10:32:28,350 DEBUG <MessageFrameWorkDispatch> [MessageFrameWork] MessageFrameWork Worker Added, Name=UIManager1, Description=Provides User Interface services for a session, Channel=00000000009F6A00
2011-09-28 10:32:28,350 DEBUG <MessageFrameWorkDispatch> [MessageFrameWork] System::IsWorkerInstalled
2011-09-28 10:32:28,350 DEBUG <MessageFrameWorkDispatch> [wsnm_desktop] DesktopManager got a SessionConnect message
2011-09-28 10:32:28,350 DEBUG <MessageFrameWorkDispatch> [wsnm_desktop] connectToPortalInfo CONNECT, winStation Console, user trusteddomain\user, portalcount 1
2011-09-28 10:32:28,350 DEBUG <MessageFrameWorkDispatch> [wsnm_desktop] connectToPortalInfo portal winStation Console, user trusteddomain\user
2011-09-28 10:32:28,350 DEBUG <MessageFrameWorkDispatch> [wsnm_desktop] Removed pending portal logon by SSO ticket, user trusteddomain\user, pending count left=0
2011-09-28 10:32:28,350 DEBUG <MessageFrameWorkDispatch> [wsnm_desktop] sessionDisconnectTimer: Setting timer for 7187 seconds
2011-09-28 10:32:28,350 DEBUG <MessageFrameWorkDispatch> [wsnm_desktop] Cannot find registry information for MMR filter, no policy enforcement will take place.
2011-09-28 10:32:28,350 DEBUG <MessageFrameWorkDispatch> [wsnm_desktop] findChannelTicketSession OK
2011-09-28 10:32:28,350 INFO <MessageFrameWorkDispatch> [MessageFrameWork] Client/agent channel connected for session = 1
2011-09-28 10:32:28,350 INFO <sessionConnectedThread> [wsnm_desktop] View Composer agent has completed customization
2011-09-28 10:32:28,350 INFO <sessionConnectedThread> [wsnm_desktop] View Composer user login initialization state 4,0 (in 0 seconds)
2011-09-28 10:32:28,350 DEBUG <MessageFrameWorkDispatch> [wsnm_desktop] DesktopManager got a SendEvent message
2011-09-28 10:32:28,350 INFO <sessionConnectedThread> [wsnm_desktop] Session CONNECTED: id=1, user trusteddomain\user, client=(null), connectionId=306A472644930C0774785376EDE558BD, userDn=cn=s-1-5-21-1659004503-2000478354-839522115-12212,cn=foreignsecurityprincipals,dc=vdi,dc=vmware,dc=int
2011-09-28 10:32:28,350 DEBUG <NodeManagerWatcher> [wssm_desktop] SessionConnect sent ok, connectionId = 306A472644930C0774785376EDE558BD
2011-09-28 10:32:28,350 DEBUG <MessageFrameWorkDispatch> [wssm_desktop] DesktopInstance1 got a ChangedPortalInfo message
2011-09-28 10:32:28,350 DEBUG <Thread-86> [EventLoggerService] Info_Event:[AGENT_CONNECTED] "User trusteddomain\user has logged in to a new session on machine V02-00003-A": MachineName=V02-00003-A, Module=Agent, UserDisplayName=trusteddomain\user, Source=com.vmware.vdi.ob.lib.b, Severity=INFO, Time=Wed Sep 28 10:32:28 CEST 2011, Node=V02-00003-A.domain.com, PoolId=win7, UserSID=S-1-5-21-1659004503-2000478354-839522115-12212, MachineId=4bf136e4-59ae-4a26-9a9d-483fcf13d05c, Acknowledged=true
2011-09-28 10:32:28,350 DEBUG <MessageFrameWorkDispatch> [wssm] gotClientInfo ClientInfo =
MAC_Address = "00:1C:25:99:36:0A"
Machine_Name = "view-client"
Type = "Linux"
Broker_Tunnel_URL = "https://vcs01.my.domain.com:443"
Broker_Remote_IP_Address = "10.dd.bb.27"
Machine_Domain = "(none)"
TZID = "Europe/Oslo"
IP_Address = "10.dd.bb.27"
Broker_DNS_Name = "vcs01.my.domain.com"
Windows_Timezone = "Central European Standard Time"
Language = "en_US.utf8"
LoggedOn_Username = "lars"
Broker_Tunneled = "true"
Broker_URL = "https://10.cc.bb.251:443"
Protocol = "PCOIP"
2011-09-28 10:32:28,350 DEBUG <MessageFrameWorkDispatch> [wssm] ZwQueryInformationProcess of processInfo return status = 3221225476
2011-09-28 10:32:28,350 DEBUG <theEventPublishingManager> [EventPublishingManager] Waiting for message to publish.
2011-09-28 10:32:28,350 DEBUG <MessageFrameWorkDispatch> [wssm] gotClientInfo: CommandsToRunOnConnect has 0 scripts
2011-09-28 10:32:28,350 DEBUG <MessageFrameWorkDispatch> [MessageFrameWork] System::IsWorkerInstalled
2011-09-28 10:32:28,366 DEBUG <MessageFrameWorkDispatch> [wsnm_desktop] DesktopManager got a SetTimeZone message
2011-09-28 10:32:28,366 DEBUG <MessageFrameWorkDispatch> [wsnm_desktop] current timezone set
2011-09-28 10:32:28,366 DEBUG <Thread-88> [ComponentResponse] Message is CONNECTED
2011-09-28 10:32:28,366 DEBUG <theTopicPublishingManager> [TopicPublishingManager] Message to publish ready.
2011-09-28 10:32:28,366 DEBUG <theTopicPublishingManager> [TopicPublishingManager] Message published.
2011-09-28 10:32:28,366 DEBUG <theTopicPublishingManager> [TopicPublishingManager] Waiting for message to publish.
2011-09-28 10:32:30,944 INFO <1424> [TPAutoConnect] Program 'TPAutoConnect - TPAutoConnect User Agent' started, version=7,17,526,3, pid=3812, buildtype=release, usethread=0, closeafterwrite=0
2011-09-28 10:32:31,100 DEBUG <1424> [TPAutoConnect] TPAutoConnect MessageQueueShared started with numThreads=2, queueTreshold=250
2011-09-28 10:32:31,100 DEBUG <1424> [TPAutoConnect] TPAutoConnect MessageQueue started with numThreads=2, queueTreshold=1000
2011-09-28 10:32:31,100 DEBUG <1424> [TPAutoConnect] MessageFrameWork Worker Added, Name=System, Description=The Builtin MessageFrameWork Administration Queue, Channel=0000000000000000
2011-09-28 10:32:31,131 DEBUG <SharedMemAuthenticateThread> [MessageFrameWork] Incoming SharedMemory channel from machine V02-00003-A.domain.com PCoIP Virtual Channels to wsnm connected as : trusteddomain\user, Authenticated through SSPI, package = NTLM, encrypting = true, canImpersonate = true
2011-09-28 10:32:31,131 DEBUG <SharedMemAuthenticateThread> [wssm_usb] USB channel callback: remoteName: PCoIP Virtual Channels incoming: true local: true opened: true
2011-09-28 10:32:31,131 DEBUG <NodeManagerWatcher> [TPAutoConnect] Outgoing SharedMemory channel from PCoIP Virtual Channels to machine V02-00003-A.domain.com wsnm connected as : trusteddomain\user, Authenticated through SSPI, package = NTLM, encrypting = true
2011-09-28 10:32:31,131 DEBUG <NodeManagerWatcher> [TPAutoConnect] Connection to Node Manager established.
2011-09-28 10:32:31,131 DEBUG <NodeManagerWatcher> [TPAutoConnect] ChannelCallbackEx: remoteName:"wsnm" incoming:false local:true opened:true
2011-09-28 10:32:31,209 DEBUG <MessageFrameWorkDispatch> [MessageFrameWork] MessageFrameWork Worker Added, Name=PCoIPVChan-CLIENT(3812), Description=PCoIPVChan, Channel=00000000009F6D20
2011-09-28 10:32:31,209 DEBUG <1424> [TPAutoConnect] MessageFrameWork Worker Added, Name=PCoIPVChan-CLIENT(3812), Description=PCoIPVChan, Channel=0000000000000000
2011-09-28 10:32:31,209 DEBUG <1424> [TPAutoConnect] pcoip_vchan_plugin_app_init(): ready
2011-09-28 10:32:36,225 DEBUG <3000> [pcoip_server_win32] VChanServerHost::SignalConnectionEvent: Signaling event 'Global\PCoIP_VChan-Disconnect'
2011-09-28 10:32:36,225 DEBUG <3000> [pcoip_server_win32] VChanServerHost::SignalConnectionEvent: The 'Name' key doesn't exist for addin 'Dynamic VC'.
2011-09-28 10:32:36,225 DEBUG <VChanConnectionMonitor> [wsnm_scredir] VChanMgr::HandleConnectCallback: Received disconnection notification.
2011-09-28 10:32:36,225 DEBUG <VChanConnectionMonitor> [wsnm] VChanAPI::ProcessConnectionEvent(): removing all pcoip callback info due to disconnect
2011-09-28 10:32:36,225 DEBUG <MessageFrameWorkDispatch> [wsnm] pcoip_vchan_connect_callback(): ignoring event because it will be delivered by the connection monitor
2011-09-28 10:32:36,225 DEBUG <VChanConnectionMonitor> [TPAutoConnect] VChanAPI::ProcessConnectionEvent(): removing all pcoip callback info due to disconnect
2011-09-28 10:32:36,225 DEBUG <MessageFrameWorkDispatch> [TPAutoConnect] pcoip_vchan_connect_callback(): ignoring event because it will be delivered by the connection monitor
2011-09-28 10:32:36,225 DEBUG <3024> [pcoip_server_win32] MessageFrameWork Worker Shutdown, Name=PCoIPVChan-SERVER, Channel=00000000
2011-09-28 10:32:36,225 DEBUG <NodeManagerWatcher> [pcoip_server_win32] ChannelCallbackEx: remoteName:"wsnm" incoming:false local:true opened:false
2011-09-28 10:32:36,225 DEBUG <NodeManagerWatcher> [pcoip_server_win32] Closed outgoing SharedMemory channel to machine V02-00003-A.domain.com, user domain\V02-00003-A$
2011-09-28 10:32:36,225 DEBUG <MessageChannel ReceiveThread> [MessageFrameWork] MessageFrameWork Worker Shutdown OnChannelDelete, Name=PCoIPVChan-SERVER
2011-09-28 10:32:36,225 DEBUG <MessageChannel ReceiveThread> [wssm_usb] USB channel callback: remoteName: PCoIP Virtual Channels incoming: true local: true opened: false
2011-09-28 10:32:36,225 DEBUG <MessageChannel ReceiveThread> [MessageFrameWork] Closed incoming SharedMemory channel from machine V02-00003-A.domain.com, user domain\V02-00003-A$
2011-09-28 10:32:36,272 DEBUG <3024> [pcoip_server_win32] pcoip_vchan_plugin_server_exit(): unloaded
2011-09-28 10:32:36,287 DEBUG <MessageChannel ReceiveThread> [wssm_usb] USB channel callback: remoteName: wswc incoming: true local: false opened: false
2011-09-28 10:32:36,287 DEBUG <MessageChannel ReceiveThread> [wssm_usb] Stopping Virtual USB subsystems.
2011-09-28 10:32:36,287 DEBUG <MessageChannel ReceiveThread> [wssm_usb] Stopping USB forwarding thread
2011-09-28 10:32:36,287 DEBUG <UsbForwarderThread> [wssm_usb] Failed to retrieve data from hub
2011-09-28 10:32:36,287 DEBUG <UsbForwarderThread> [wssm_usb] Exiting Hub thread
2011-09-28 10:32:36,287 DEBUG <MessageChannel ReceiveThread> [wssm_usb] Stopped USB forwarding thread
2011-09-28 10:32:36,350 DEBUG <3904> [wsnm_desktop] PCoIP connection closed: hndl 0x2 code 2
2011-09-28 10:32:36,350 DEBUG <3904> [wsnm_desktop] PCoIPCnx::OnConnectionClosed
2011-09-28 10:32:36,350 DEBUG <3904> [wsnm_desktop] Console session needs to be disconnected
2011-09-28 10:32:36,412 DEBUG <Main Thread> [wsnm_desktop] WTS_CONSOLE_DISCONNECT, session=1
2011-09-28 10:32:36,412 DEBUG <Main Thread> [wsnm_desktop] Original dynamic timezone restored
2011-09-28 10:32:36,412 DEBUG <Main Thread> [wsnm_desktop] Stopping protocol PCOIP...
2011-09-28 10:32:36,412 DEBUG <Main Thread> [wsnm_desktop] Closing client/agent TCP channel on session disconnect
2011-09-28 10:32:36,412 INFO <Main Thread> [wsnm_desktop] Session DISCONNECTED: id=1, user trusteddomain\user, client=(null), connectionId=306A472644930C0774785376EDE558BD, userDn=cn=s-1-5-21-1659004503-2000478354-839522115-12212,cn=foreignsecurityprincipals,dc=vdi,dc=vmware,dc=int
2011-09-28 10:32:36,412 DEBUG <3904> [wsnm_desktop] session::disconnect session disconnect done: 1
2011-09-28 10:32:36,412 DEBUG <3904> [wsnm_desktop] enabling Display Settings in Control Panel
2011-09-28 10:32:36,412 DEBUG <MessageFrameWorkDispatch> [wsnm_desktop] DesktopManager got a SendEvent message
2011-09-28 10:32:36,412 DEBUG <Thread-92> [EventLoggerService] Info_Event:[AGENT_DISCONNECTED] "User trusteddomain\user has disconnected from machine V02-00003-A": MachineName=V02-00003-A, Module=Agent, UserDisplayName=trusteddomain\user, Source=com.vmware.vdi.ob.lib.b, Severity=INFO, Time=Wed Sep 28 10:32:36 CEST 2011, Node=V02-00003-A.domain.com, PoolId=win7, UserSID=S-1-5-21-1659004503-2000478354-839522115-12212, MachineId=4bf136e4-59ae-4a26-9a9d-483fcf13d05c, Acknowledged=true
2011-09-28 10:32:36,412 DEBUG <MessageFrameWorkDispatch> [wssm] SessionManager1 got a Disconnected message
2011-09-28 10:32:36,412 DEBUG <theEventPublishingManager> [EventPublishingManager] Waiting for message to publish.
2011-09-28 10:32:36,412 DEBUG <Thread-94> [ComponentResponse] Message is DISCONNECTED
2011-09-28 10:32:36,412 DEBUG <theTopicPublishingManager> [TopicPublishingManager] Message to publish ready.
2011-09-28 10:32:36,428 DEBUG <theTopicPublishingManager> [TopicPublishingManager] Message published.
2011-09-28 10:32:36,428 DEBUG <theTopicPublishingManager> [TopicPublishingManager] Waiting for message to publish.
2011-09-28 10:32:36,787 INFO <3964> [LogonUI] Program 'LogonUI - Windows Logon User Interface Host' started, version=6.1.7601.17514 (win7sp1_rtm.101119-1850), pid=2380, buildtype=release, usethread=0, closeafterwrite=0
2011-09-28 10:32:36,787 DEBUG <3964> [LogonUI] Rpci: Sending request='machine.id.get '
2011-09-28 10:32:36,787 DEBUG <3964> [LogonUI] Rpci: Sent request='machine.id.get ', reply='vdi.broker.useSvi=1;vdi.broker.brokerPublicKey=MIHxMIGoBgcqhkjOOAQBMIGcAkEA/KaCzo4Syrom78z3EQ5SbbB4sF7ey80etKII864WF64B81uRpH5t9jQTxeEu0ImbzRMqzVDZkVG9xD7nN1kuFwIVAJYu3cw2nLqOuyYO5rahJtk0bjjFAkBnhHGyepz0TukaScUUfbGpqvJE8FpDTWSGkx0tFCcbnjUDC3H9c9oXkGmzLik1Yw4cIGI1TQ2iCmxBblC+eUykA0QAAkEAnfprpbzURfAm0NQVv171IIKWUKWkZfZrhTq5EVoZ/2pfhPo+eB7dH88gg/KDiuJDLP+1lGn9aft20F6YwPCW+Q==;vdi.broker.brokers=vcs01.my.domain.com ;vdi.broker.agentIdentity=agent/4bf136e4-59ae-4a26-9a9d-483fcf13d05c;vdi.broker.agentPrivateKeyHash=r78jwga/k50Wee7YI9K6WA==;vdi.broker.itemDn=cn=4bf136e4-59ae-4a26-9a9d-483fcf13d05c,ou=servers,dc=vdi,dc=vmware,dc=int;vdi.broker.asyncSessionSeconds=150;vdi.broker.brokerPublicKeyHash=kTI3JoIz8FO2vCyXjOk7XQ==;vdi.broker.singleuse2=1;vdi.broker.msMode=OFF;vdi.broker.agentPrivateKey=MIHGAgEAMIGoBgcqhkjOOAQBMIGcAkEA/KaCzo4Syrom78z3EQ5SbbB4sF7ey80etKII864WF64B81uRpH5t9jQTxeEu0ImbzRMqzVDZkVG9xD7nN1kuFwIVAJYu3cw2nLqOuyYO5rahJtk0bjjFAkBnhHGyepz0TukaScUUfbGpqvJE8FpDTWSGkx0tFCcbnjUDC3H9c9oXkGmzLik1Yw4cIGI1TQ2iCmxBblC+eUykBBYCFB2DvXwmYGUt8uo4kaeWbv6T9hEc;vdi.broker.disconnecttimeout=120;vdi.broker.singleuse=0;vdi.broker.useSysprep=0;vdi.broker.poolDn=cn=win7,ou=server groups,dc=vdi,dc=vmware,dc=int', len=1222, status=1
2011-09-28 10:32:36,819 DEBUG <Main Thread> [wsnm_desktop] WTS_CONSOLE_CONNECT, session=2
2011-09-28 10:32:41,194 DEBUG <MessageFrameWorkDispatch> [ws_vmx] Rpci: Sending request='machine.id.get '
2011-09-28 10:32:41,194 DEBUG <MessageFrameWorkDispatch> [ws_vmx] Rpci: Sent request='machine.id.get ', reply='vdi.broker.useSvi=1;vdi.broker.brokerPublicKey=MIHxMIGoBgcqhkjOOAQBMIGcAkEA/KaCzo4Syrom78z3EQ5SbbB4sF7ey80etKII864WF64B81uRpH5t9jQTxeEu0ImbzRMqzVDZkVG9xD7nN1kuFwIVAJYu3cw2nLqOuyYO5rahJtk0bjjFAkBnhHGyepz0TukaScUUfbGpqvJE8FpDTWSGkx0tFCcbnjUDC3H9c9oXkGmzLik1Yw4cIGI1TQ2iCmxBblC+eUykA0QAAkEAnfprpbzURfAm0NQVv171IIKWUKWkZfZrhTq5EVoZ/2pfhPo+eB7dH88gg/KDiuJDLP+1lGn9aft20F6YwPCW+Q==;vdi.broker.brokers=vcs01.my.domain.com ;vdi.broker.agentIdentity=agent/4bf136e4-59ae-4a26-9a9d-483fcf13d05c;vdi.broker.agentPrivateKeyHash=r78jwga/k50Wee7YI9K6WA==;vdi.broker.itemDn=cn=4bf136e4-59ae-4a26-9a9d-483fcf13d05c,ou=servers,dc=vdi,dc=vmware,dc=int;vdi.broker.asyncSessionSeconds=150;vdi.broker.brokerPublicKeyHash=kTI3JoIz8FO2vCyXjOk7XQ==;vdi.broker.singleuse2=1;vdi.broker.msMode=OFF;vdi.broker.agentPrivateKey=MIHGAgEAMIGoBgcqhkjOOAQBMIGcAkEA/KaCzo4Syrom78z3EQ5SbbB4sF7ey80etKII864WF64B81uRpH5t9jQTxeEu0ImbzRMqzVDZkVG9xD7nN1kuFwIVAJYu3cw2nLqOuyYO5rahJtk0bjjFAkBnhHGyepz0TukaScUUfbGpqvJE8FpDTWSGkx0tFCcbnjUDC3H9c9oXkGmzLik1Yw4cIGI1TQ2iCmxBblC+eUykBBYCFB2DvXwmYGUt8uo4kaeWbv6T9hEc;vdi.broker.disconnecttimeout=120;vdi.broker.singleuse=0;vdi.broker.useSysprep=0;vdi.broker.poolDn=cn=win7,ou=server groups,dc=vdi,dc=vmware,dc=int', len=1222, status=1
2011-09-28 10:32:50,428 DEBUG <MessageChannel ReceiveThread> [MessageFrameWork] MessageFrameWork Worker Shutdown OnChannelDelete, Name=PCoIPVChan-CLIENT(3812)
2011-09-28 10:32:50,428 DEBUG <MessageChannel ReceiveThread> [wssm_usb] USB channel callback: remoteName: PCoIP Virtual Channels incoming: true local: true opened: false
2011-09-28 10:32:50,428 DEBUG <MessageChannel ReceiveThread> [MessageFrameWork] Closed incoming SharedMemory channel from machine V02-00003-A.domain.com, user trusteddomain\user
2011-09-28 10:33:01,245 INFO <1856> [VMwareViewClipboard] Program 'VMwareViewClipboard - VMware View PCoIP Clipboard Server' started, version=e.x.p build-481055, pid=4068, buildtype=release, usethread=0, closeafterwrite=0
2011-09-28 10:33:01,245 DEBUG <1856> [VMwareViewClipboard] VMwareViewClipboard MessageQueueShared started with numThreads=2, queueTreshold=250
2011-09-28 10:33:01,245 DEBUG <1856> [VMwareViewClipboard] VMwareViewClipboard MessageQueue started with numThreads=2, queueTreshold=1000
2011-09-28 10:33:01,245 DEBUG <1856> [VMwareViewClipboard] MessageFrameWork Worker Added, Name=System, Description=The Builtin MessageFrameWork Administration Queue, Channel=00000000
2011-09-28 10:33:01,245 DEBUG <SharedMemAuthenticateThread> [MessageFrameWork] Incoming SharedMemory channel from machine V02-00003-A.domain.com PCoIP Virtual Channels to wsnm connected as : trusteddomain\user, Authenticated through SSPI, package = NTLM, encrypting = true, canImpersonate = true
2011-09-28 10:33:01,245 DEBUG <SharedMemAuthenticateThread> [wssm_usb] USB channel callback: remoteName: PCoIP Virtual Channels incoming: true local: true opened: true
2011-09-28 10:33:01,245 DEBUG <NodeManagerWatcher> [VMwareViewClipboard] Outgoing SharedMemory channel from PCoIP Virtual Channels to machine V02-00003-A.domain.com wsnm connected as : trusteddomain\user, Authenticated through SSPI, package = NTLM, encrypting = true
2011-09-28 10:33:01,245 DEBUG <NodeManagerWatcher> [VMwareViewClipboard] Connection to Node Manager established.
2011-09-28 10:33:01,245 DEBUG <NodeManagerWatcher> [VMwareViewClipboard] ChannelCallbackEx: remoteName:"wsnm" incoming:false local:true opened:true
2011-09-28 10:33:01,385 DEBUG <MessageFrameWorkDispatch> [MessageFrameWork] MessageFrameWork Worker Added, Name=PCoIPVChan-CLIENT(4068), Description=PCoIPVChan, Channel=00000000009F6D20
2011-09-28 10:33:01,385 DEBUG <1856> [VMwareViewClipboard] MessageFrameWork Worker Added, Name=PCoIPVChan-CLIENT(4068), Description=PCoIPVChan, Channel=00000000
2011-09-28 10:33:01,385 DEBUG <1856> [VMwareViewClipboard] pcoip_vchan_plugin_app_init(): ready
2011-09-28 10:33:01,385 DEBUG <MessageChannel ReceiveThread> [MessageFrameWork] User 'trusteddomain\user' not authorized (not local admin), queue=PCoIPVChan-SERVER, hint=MESSAGE_IPC
2011-09-28 10:33:01,385 DEBUG <MessageChannel ReceiveThread> [MessageFrameWork] User 'trusteddomain\user' not authorized (not local admin), queue=PCoIPVChan-SERVER, hint=MESSAGE_IPC
2011-09-28 10:33:01,417 DEBUG <MessageChannel ReceiveThread> [MessageFrameWork] User 'trusteddomain\user' not authorized (not local admin), queue=PCoIPVChan-SERVER, hint=MESSAGE_IPC
2011-09-28 10:33:01,417 DEBUG <VChanConnectionMonitor> [VMwareViewClipboard] VChanConnectionMonitor(): Failed to find PCoIP process
2011-09-28 10:33:07,588 DEBUG <Thread-97> [ComponentResponse] Reponse directed to:ID:/127.0.0.1/5876504814872078132/80543/0
2011-09-28 10:33:07,588 DEBUG <Thread-97> [ComponentResponse] Message is <?xml version="1.0"?>
<TERMINALRESPONSE><ID>30833744:13274111049:-1e52</ID><SERVERDN>cn=4bf136e4-59ae-4a26-9a9d-483fcf13d05c,ou=servers,dc=vdi,dc=vmware,dc=int</SERVERDN><SERVERPOOLDN>cn=win7,ou=server groups,dc=vdi,dc=vmware,dc=int</SERVERPOOLDN><SERVERDNSNAME>V02-00003-A.domain.com</SERVERDNSNAME><DYNAMICIPADDRESS>10.aa.bb.29</DYNAMICIPADDRESS><__JMS_ENCRYPTED__>oYH+MIH7oAMKAQGhDAYKKwYBBAGCNwICCqKB5QSB4k5UTE1TU1AAAgAAAA4ADgA4AAAANYKJ4vqWVa+EoIwFAAAAAAAAAACcAJwARgAAAAYBsR0AAAAPSABFAEwAUwBFAE0ATgACAA4ASABFAEwAUwBFAE0ATgABABYAVgAwADIALQAwADAAMAAwADMALQBBAAQAFABoAGUAbABzAGUAbQBuAC4AbgBvAAMALABWADAAMgAtADAAMAAwADAAMwAtAEEALgBoAGUAbABzAGUAbQBuAC4AbgBvAAUAFABoAGUAbABzAGUAbQBuAC4AbgBvAAcACADceJI/uX3MAQAAAAA=</__JMS_ENCRYPTED__><complete></complete></TERMINALRESPONSE>
2011-09-28 10:33:07,588 DEBUG <theTopicMessageResponder> [TopicMessageResponder] Reponse message ready.
2011-09-28 10:33:07,588 DEBUG <theTopicMessageResponder> [TopicMessageResponder] Response sent.
2011-09-28 10:33:07,588 DEBUG <theTopicMessageResponder> [TopicMessageResponder] Waiting for response message.
2011-09-28 10:33:07,604 DEBUG <Thread-98> [ComponentResponse] Reponse directed to:ID:/127.0.0.1/5876504814872078132/80545/0
2011-09-28 10:33:07,604 DEBUG <Thread-98> [ComponentResponse] Message is <?xml version="1.0"?>
<TERMINALRESPONSE><ID>30833744:13274111049:-1e51</ID><SERVERDN>cn=4bf136e4-59ae-4a26-9a9d-483fcf13d05c,ou=servers,dc=vdi,dc=vmware,dc=int</SERVERDN><SERVERPOOLDN>cn=win7,ou=server groups,dc=vdi,dc=vmware,dc=int</SERVERPOOLDN><SERVERDNSNAME>V02-00003-A.domain.com</SERVERDNSNAME><DYNAMICIPADDRESS>10.aa.bb.29</DYNAMICIPADDRESS><__JMS_ENCRYPTED__>oRswGaADCgEAoxIEEAEAAACH6XCey9wB3QAAAAA=</__JMS_ENCRYPTED__><complete>true</complete></TERMINALRESPONSE>
2011-09-28 10:33:07,604 DEBUG <theTopicMessageResponder> [TopicMessageResponder] Reponse message ready.
2011-09-28 10:33:07,604 DEBUG <theTopicMessageResponder> [TopicMessageResponder] Response sent.
2011-09-28 10:33:07,604 DEBUG <theTopicMessageResponder> [TopicMessageResponder] Waiting for response message.
2011-09-28 10:33:07,635 DEBUG <MessageFrameWorkDispatch> [wsnm_desktop] DesktopManager got a StartSession message {SESSION:62BB75822896D9224E3B41973BCDE878;3A516F94F539F7C340354987BF5E8ECD}
2011-09-28 10:33:07,635 DEBUG <MessageFrameWorkDispatch> [wsnm_desktop] StartSession CLIENTINFO =
MAC_Address = "00:1C:25:99:36:0A"
Machine_Name = "view-client"
Type = "Linux"
Broker_Tunnel_URL = "https://vcs01.my.domain.com:443"
Broker_Remote_IP_Address = "10.dd.bb.27"
Machine_Domain = "(none)"
TZID = "Europe/Oslo"
IP_Address = "10.dd.bb.27"
Broker_DNS_Name = "vcs01.my.domain.com"
Windows_Timezone = "Central European Standard Time"
Language = "en_US.utf8"
LoggedOn_Username = "lars"
Broker_Tunneled = "true"
Broker_URL = "https://10.cc.bb.251:443" {SESSION:62BB75822896D9224E3B41973BCDE878;3A516F94F539F7C340354987BF5E8ECD}
2011-09-28 10:33:07,635 DEBUG <MessageFrameWorkDispatch> [wsnm_desktop] New session policies:
overrideProfilePath = "false"
allowMMR = "true"
remoteProfilePath = ""
flashThrottling = "0"
allowUSB = "true"
flashQuality = "0"
hostCardAcceleration = "3"
enableVirtualProfiles = "false" {SESSION:62BB75822896D9224E3B41973BCDE878;3A516F94F539F7C340354987BF5E8ECD}
2011-09-28 10:33:07,635 DEBUG <MessageFrameWorkDispatch> [wsnm_desktop] commandhandler::startSession: Received windows credentials for SSO {SESSION:62BB75822896D9224E3B41973BCDE878;3A516F94F539F7C340354987BF5E8ECD}
2011-09-28 10:33:07,635 DEBUG <MessageFrameWorkDispatch> [wsnm_desktop] Starting protocol PCOIP... {SESSION:62BB75822896D9224E3B41973BCDE878;3A516F94F539F7C340354987BF5E8ECD}
2011-09-28 10:33:07,651 DEBUG <MessageFrameWorkDispatch> [wsnm_desktop] Launch PCoIP server. {SESSION:62BB75822896D9224E3B41973BCDE878;3A516F94F539F7C340354987BF5E8ECD}
2011-09-28 10:33:07,807 INFO <1680> [pcoip_server_win32] Program 'pcoip_server_win32 - PCoIP Server' started, version=3,8,0,9606:soft_pcoip_rc_3_8, pid=3140, buildtype=release, usethread=0, closeafterwrite=0
2011-09-28 10:33:07,807 DEBUG <1680> [pcoip_server_win32] pcoip_vchan_plugin_server_init(): loading
2011-09-28 10:33:07,807 DEBUG <1680> [pcoip_server_win32] pcoip_vchan_plugin_server_init(): Using PCoIP virtual channel API version 1
2011-09-28 10:33:07,807 DEBUG <1680> [pcoip_server_win32] pcoip_vchan_plugin_server_init(): starting message framework
2011-09-28 10:33:07,807 DEBUG <1680> [pcoip_server_win32] pcoip_server_win32 MessageQueueShared started with numThreads=2, queueTreshold=250
2011-09-28 10:33:07,807 DEBUG <1680> [pcoip_server_win32] pcoip_server_win32 MessageQueue started with numThreads=2, queueTreshold=1000
2011-09-28 10:33:07,807 DEBUG <1680> [pcoip_server_win32] MessageFrameWork Worker Added, Name=System, Description=The Builtin MessageFrameWork Administration Queue, Channel=00000000
2011-09-28 10:33:07,807 DEBUG <NodeManagerWatcher> [pcoip_server_win32] Outgoing SharedMemory channel from PCoIP Virtual Channels to machine V02-00003-A.domain.com wsnm connected as : domain\V02-00003-A$, Authenticated through SSPI, package = NTLM, encrypting = true
2011-09-28 10:33:07,807 DEBUG <NodeManagerWatcher> [pcoip_server_win32] Connection to Node Manager established.
2011-09-28 10:33:07,807 DEBUG <NodeManagerWatcher> [pcoip_server_win32] ChannelCallbackEx: remoteName:"wsnm" incoming:false local:true opened:true
2011-09-28 10:33:07,807 DEBUG <SharedMemAuthenticateThread> [MessageFrameWork] Incoming SharedMemory channel from machine V02-00003-A.domain.com PCoIP Virtual Channels to wsnm connected as : domain\V02-00003-A$, Authenticated through SSPI, package = NTLM, encrypting = true, canImpersonate = true
2011-09-28 10:33:07,807 DEBUG <SharedMemAuthenticateThread> [wssm_usb] USB channel callback: remoteName: PCoIP Virtual Channels incoming: true local: true opened: true
2011-09-28 10:33:07,916 DEBUG <1680> [pcoip_server_win32] MessageFrameWork Worker Added, Name=PCoIPVChan-SERVER, Description=PCoIPVChan, Channel=00000000
2011-09-28 10:33:07,916 DEBUG <1680> [pcoip_server_win32] pcoip_vchan_plugin_server_init(): sending initial message
2011-09-28 10:33:07,916 DEBUG <1680> [pcoip_server_win32] pcoip_vchan_plugin_server_init(): ready
2011-09-28 10:33:07,916 DEBUG <MessageFrameWorkDispatch> [MessageFrameWork] MessageFrameWork Worker Added, Name=PCoIPVChan-SERVER, Description=PCoIPVChan, Channel=00000000009F6870
2011-09-28 10:33:08,776 DEBUG <MessageFrameWorkDispatch> [wsnm_desktop] PCoIPCnx::OnConnectionResponse {SESSION:62BB75822896D9224E3B41973BCDE878;3A516F94F539F7C340354987BF5E8ECD}
2011-09-28 10:33:08,776 DEBUG <MessageFrameWorkDispatch> [wsnm_desktop] PCoIP connection response: OK {SESSION:62BB75822896D9224E3B41973BCDE878;3A516F94F539F7C340354987BF5E8ECD}
2011-09-28 10:33:08,776 DEBUG <MessageFrameWorkDispatch> [wsnm_desktop] PCoIP connect to handle: hndl 0x3 {SESSION:62BB75822896D9224E3B41973BCDE878;3A516F94F539F7C340354987BF5E8ECD}
2011-09-28 10:33:08,776 DEBUG <MessageFrameWorkDispatch> [wsnm_desktop] PCoIP connection request succeeded! (handle=0x3) {SESSION:62BB75822896D9224E3B41973BCDE878;3A516F94F539F7C340354987BF5E8ECD}
2011-09-28 10:33:08,776 DEBUG <MessageFrameWorkDispatch> [wsnm_desktop] Protocol PCOIP is listening on 10.aa.bb.29:4172 {SESSION:62BB75822896D9224E3B41973BCDE878;3A516F94F539F7C340354987BF5E8ECD}
2011-09-28 10:33:08,776 DEBUG <MessageFrameWorkDispatch> [wsnm_desktop] DesktopManager got a SendEvent message {SESSION:62BB75822896D9224E3B41973BCDE878;3A516F94F539F7C340354987BF5E8ECD}
2011-09-28 10:33:08,776 DEBUG <MessageFrameWorkDispatch> [wsnm_desktop] startSession added portal logon for user trusteddomain\user, timeout=900 secs, pending count=1 {SESSION:62BB75822896D9224E3B41973BCDE878;3A516F94F539F7C340354987BF5E8ECD}
2011-09-28 10:33:08,776 DEBUG <Thread-101> [ComponentResponse] Message is PENDING
2011-09-28 10:33:08,776 DEBUG <theTopicPublishingManager> [TopicPublishingManager] Message to publish ready.
2011-09-28 10:33:08,776 DEBUG <Thread-102> [EventLoggerService] Info_Event:[AGENT_PENDING] "The agent running on machine V02-00003-A has accepted an allocated session for user trusteddomain\user": MachineName=V02-00003-A, Module=Agent, UserDisplayName=trusteddomain\user, Source=com.vmware.vdi.ob.lib.b, Severity=INFO, Time=Wed Sep 28 10:33:08 CEST 2011, Node=V02-00003-A.domain.com, PoolId=win7, UserSID=S-1-5-21-1659004503-2000478354-839522115-12212, MachineId=4bf136e4-59ae-4a26-9a9d-483fcf13d05c, Acknowledged=true {SESSION:62BB75822896D9224E3B41973BCDE878;3A516F94F539F7C340354987BF5E8ECD}
2011-09-28 10:33:08,776 DEBUG <Thread-103> [ComponentResponse] Reponse directed to:ID:/127.0.0.1/5876504814872078132/80547/0 {SESSION:62BB75822896D9224E3B41973BCDE878;3A516F94F539F7C340354987BF5E8ECD}
2011-09-28 10:33:08,776 DEBUG <Thread-103> [ComponentResponse] Message is <?xml version="1.0"?>
<TERMINALRESPONSE><ID>30833744:13274111049:-1e50</ID><SERVERDN>cn=4bf136e4-59ae-4a26-9a9d-483fcf13d05c,ou=servers,dc=vdi,dc=vmware,dc=int</SERVERDN><SERVERPOOLDN>cn=win7,ou=server groups,dc=vdi,dc=vmware,dc=int</SERVERPOOLDN><SERVERDNSNAME>V02-00003-A.domain.com</SERVERDNSNAME><DYNAMICIPADDRESS>10.aa.bb.29</DYNAMICIPADDRESS><ID>30833744:13274111049:-1e4f</ID><COMMAND>StartSession</COMMAND><encryptId>30833744:13274111049:-1e53</encryptId><encryptState>data</encryptState><__JMS_ENCRYPTED__>AQAAAIzNBb0I7yasAQAAAKRGY7XyNX8zLrnR5+M4pploehjREhZxVnmrH3xaA/FrihsUKXxQc4T/PAXgEfOiHe1+aNwd9vt+rxZSxFajR67dPopItURX8bcqlIY6EtKAJ62NcEBQEzNvb1J0Kwv8oRiXqVR7g2ANn6dHBux9jx4abZL6ZnuHwIGel0xZ6x8N40DK/Yxq9C6lUaUG9qMTnb01LRbU8M0mL6BXKQvTn5NQzO6jVAeGAtGZbv74AlcRCcBfMRv6WPDjgDIZaC7htIkUVki6BbLeX3DcLSJErS93etjFuJbCcBhSWN2jMcAPvDoaLCD8DPpXBYsbZrY0BSY4KK8TjEb/3XIrtXx8rgHKDjI5wuEgkLP6BsDF3iFTxUcbTliB5bzFRcdJEXjedjJ2c/swdd2LUXO70tnNe0fig8iY/kdm9W00vwXMY5M8fEikrl/jb+B4ognxmVJ9JnBTU/Mc91l0DcYKz6ygH66RFfHHMzACO1kjTnV2SxJ9q8xyRF+ZzlsRZNOxD+Uc1Fl3/QXV4ROCPtGlYO4idOOdlojcOjTLPdxhvXaQLTX/rGg5hN2ivQUDWVleplLjYk5pr54d7qPZ3pIqi0Lh3pS7Ul+xgQY6+cwNTj/kZE+x01i2Vf9tqRsChAuJvM36SUPUlZCAx8+prL50mZq14kM0N8PUSvnnevvzvS/z3/nllUXj7YdwK1A0q9dJM1O36B5i5K/2NktjavsSNoKELM/yGY+bgR9LHWCbvIlHfYfRdna8TK27ahpFf6enuvdpp/3v3iAU6XqKzMIZ82jSUQ1EhqC40mm99PIFjrFUv+o6GgtoPgz+2yaqLACE4fhm+0WOfPchuP3/cUpx8qI2eYOakBZAmzOkph99xzoTNMfTHtbuLzIToA2szJrDI8nsnImgyWvX8S6uS08rtfkkQ+rR06X3kgxAs3N3ZtAQuQC8XA==</__JMS_ENCRYPTED__></TERMINALRESPONSE> {SESSION:62BB75822896D9224E3B41973BCDE878;3A516F94F539F7C340354987BF5E8ECD}
2011-09-28 10:33:08,776 DEBUG <theTopicMessageResponder> [TopicMessageResponder] Reponse message ready.
2011-09-28 10:33:08,776 DEBUG <theTopicPublishingManager> [TopicPublishingManager] Message published.
2011-09-28 10:33:08,776 DEBUG <theTopicPublishingManager> [TopicPublishingManager] Waiting for message to publish.
2011-09-28 10:33:08,776 DEBUG <theTopicMessageResponder> [TopicMessageResponder] Response sent.
2011-09-28 10:33:08,776 DEBUG <theTopicMessageResponder> [TopicMessageResponder] Waiting for response message.
2011-09-28 10:33:08,776 DEBUG <theEventPublishingManager> [EventPublishingManager] Waiting for message to publish.
2011-09-28 10:33:08,932 DEBUG <SocketAuthenticateThread> [MessageFrameWork] Incoming Socket channel from machine view-client.(none) wswc to wsnm connected as : \, Authenticated through TICKET, encrypting = false, canImpersonate = false
2011-09-28 10:33:08,932 DEBUG <SocketAuthenticateThread> [wssm_usb] USB channel callback: remoteName: wswc incoming: true local: false opened: true
2011-09-28 10:33:08,932 DEBUG <SocketAuthenticateThread> [wssm_usb] Attempting to initialise Virtual USB subsystems.
2011-09-28 10:33:08,932 DEBUG <SocketAuthenticateThread> [wssm_usb] Opened Virtual USB interface, hub version: 0x00010000
2011-09-28 10:33:08,932 DEBUG <SocketAuthenticateThread> [wssm_usb] Starting USB forwarding thread
2011-09-28 10:33:08,932 DEBUG <UsbForwarderThread> [wssm_usb] Starting Hub thread
2011-09-28 10:33:08,948 DEBUG <MessageFrameWorkDispatch> [MessageFrameWork] System::CreateRemoteSessionChannel
2011-09-28 10:33:08,948 DEBUG <MessageFrameWorkDispatch> [wsnm_desktop] DesktopManager got a FindVirtualChannelTicket message
2011-09-28 10:33:08,948 DEBUG <MessageFrameWorkDispatch> [wsnm_desktop] findChannelTicketSession, session is pending
2011-09-28 10:33:08,948 INFO <MessageFrameWorkDispatch> [MessageFrameWork] Client/agent channel pending wssm to start
2011-09-28 10:33:08,948 DEBUG <MessageFrameWorkShare> [wssm_usb] UsbRemoteManager got a IsUsbAvailable message
2011-09-28 10:33:08,948 DEBUG <MessageFrameWorkShare> [wsnm_desktop] DesktopManager got a GetSessionInfoForChannelTicket message
2011-09-28 10:33:08,948 DEBUG <MessageFrameWorkDispatch> [MessageFrameWork] System::CreateRemoteSessionChannel
2011-09-28 10:33:08,948 DEBUG <MessageFrameWorkDispatch> [wsnm_desktop] DesktopManager got a FindVirtualChannelTicket message
2011-09-28 10:33:09,198 DEBUG <1064> [wsnm_desktop] PCoIP connection complete: hndl 0x3 code 0
2011-09-28 10:33:09,198 DEBUG <1064> [wsnm_desktop] PCoIPCnx::OnConnectionComplete Begin
2011-09-28 10:33:09,198 DEBUG <1064> [wsnm_desktop] disabling Display Settings in Control Panel
2011-09-28 10:33:09,198 DEBUG <1064> [wsnm_desktop] PCoIPCnx::NotifySmartCard Inform smartcard redirection that a PCoIP session is being set up.
2011-09-28 10:33:09,198 DEBUG <MessageFrameWorkDispatch> [wsnm_desktop] DesktopManager got a GetPCoIPClientProperties message
2011-09-28 10:33:09,198 DEBUG <1264> [wsnm_scredir] PCoIPServerMonitor: clientName='view-client'
2011-09-28 10:33:09,198 DEBUG <1264> [wsnm_scredir] PCoIPServerMonitor: Driver Notified of the connection
2011-09-28 10:33:09,198 DEBUG <1064> [wsnm_desktop] PCoIPCnx::NotifySmartCard PCoIP smartcard redirection confirmed that it's ready.
2011-09-28 10:33:09,198 DEBUG <1064> [wsnm_desktop] PCoIPCnx::OnConnectionComplete start session switch.
2011-09-28 10:33:09,573 DEBUG <1064> [wsnm_desktop] PCoIPCnx::OnConnectionComplete start lock workstation.
2011-09-28 10:33:09,573 DEBUG <MessageFrameWorkDispatch> [wssm_desktop] DesktopInstance1 got a LockWindowStation message
2011-09-28 10:33:09,776 DEBUG <Main Thread> [wsnm_desktop] WTS_CONSOLE_DISCONNECT, session=2
2011-09-28 10:33:09,791 DEBUG <Main Thread> [wsnm_desktop] WTS_CONSOLE_CONNECT, session=1
2011-09-28 10:33:09,791 DEBUG <Main Thread> [wsnm_desktop] connectToPortalInfo RECONNECT, winStation Console, user trusteddomain\user, portalcount 1
2011-09-28 10:33:09,791 DEBUG <Main Thread> [wsnm_desktop] connectToPortalInfo portal winStation (null), user trusteddomain\user
2011-09-28 10:33:09,791 DEBUG <Main Thread> [wsnm_desktop] connectToPortalInfo, check domain/user match
2011-09-28 10:33:09,791 DEBUG <Main Thread> [wsnm_desktop] Stopping protocol PCOIP...
2011-09-28 10:33:09,791 DEBUG <Main Thread> [wsnm_desktop] connectToPortalInfo, check for NON SSO
2011-09-28 10:33:09,791 DEBUG <Main Thread> [wsnm_desktop] connectToPortalInfo, check for changed credentials or PCoIP reconnect
2011-09-28 10:33:09,791 DEBUG <Main Thread> [wsnm_desktop] Removed pending portal logon with changed credentials, user trusteddomain\user, pending count left=0
2011-09-28 10:33:09,791 INFO <Main Thread> [wsnm_desktop] Session RECONNECTED (PCOIP) with CHANGED CREDENTIALS: expected trusteddomain\user, got trusteddomain\user
2011-09-28 10:33:09,791 DEBUG <MessageFrameWorkDispatch> [wssm_desktop] DesktopInstance1 got a ChangedPortalInfo message
2011-09-28 10:33:09,791 DEBUG <Main Thread> [wsnm_desktop] sessionDisconnectTimer: Setting timer for 7195 seconds
2011-09-28 10:33:09,791 INFO <Main Thread> [wsnm_desktop] Session RECONNECTED: id=1, user trusteddomain\user, client=(null), connectionId=3A516F94F539F7C340354987BF5E8ECD, userDn=cn=s-1-5-21-1659004503-2000478354-839522115-12212,cn=foreignsecurityprincipals,dc=vdi,dc=vmware,dc=int
2011-09-28 10:33:09,791 DEBUG <Main Thread> [wsnm_desktop] Cannot find registry information for MMR filter, no policy enforcement will take place.
2011-09-28 10:33:09,791 DEBUG <MessageFrameWorkDispatch> [wsnm_desktop] DesktopManager got a SendEvent message
2011-09-28 10:33:09,791 DEBUG <MessageFrameWorkDispatch> [wsnm_desktop] findChannelTicketSession OK
2011-09-28 10:33:09,791 INFO <MessageFrameWorkDispatch> [MessageFrameWork] Client/agent channel connected for session = 1
2011-09-28 10:33:09,791 DEBUG <Thread-105> [EventLoggerService] Info_Event:[AGENT_RECONNECTED] "User trusteddomain\user has reconnected to machine V02-00003-A": MachineName=V02-00003-A, Module=Agent, UserDisplayName=trusteddomain\user, Source=com.vmware.vdi.ob.lib.b, Severity=INFO, Time=Wed Sep 28 10:33:09 CEST 2011, Node=V02-00003-A.domain.com, PoolId=win7, UserSID=S-1-5-21-1659004503-2000478354-839522115-12212, MachineId=4bf136e4-59ae-4a26-9a9d-483fcf13d05c, Acknowledged=true
2011-09-28 10:33:09,791 DEBUG <theEventPublishingManager> [EventPublishingManager] Waiting for message to publish.
2011-09-28 10:33:09,791 DEBUG <Thread-107> [ComponentResponse] Message is RECONNECTED
2011-09-28 10:33:09,791 DEBUG <theTopicPublishingManager> [TopicPublishingManager] Message to publish ready.
2011-09-28 10:33:09,791 DEBUG <theTopicPublishingManager> [TopicPublishingManager] Message published.
2011-09-28 10:33:09,791 DEBUG <theTopicPublishingManager> [TopicPublishingManager] Waiting for message to publish.
2011-09-28 10:33:09,791 DEBUG <MessageFrameWorkDispatch> [wssm] SessionManager1 got a Reconnected message
2011-09-28 10:33:09,791 DEBUG <MessageFrameWorkDispatch> [wssm_desktop] DesktopInstance1 got a ChangedPortalInfo message
2011-09-28 10:33:09,791 DEBUG <MessageFrameWorkDispatch> [wssm_desktop] DesktopInstance1 got a getPortalInfo message
2011-09-28 10:33:09,791 DEBUG <MessageFrameWorkDispatch> [wssm] gotClientInfo ClientInfo =
MAC_Address = "00:1C:25:99:36:0A"
Machine_Name = "view-client"
Type = "Linux"
Broker_Tunnel_URL = "https://vcs01.my.domain.com:443"
Broker_Remote_IP_Address = "10.dd.bb.27"
Machine_Domain = "(none)"
TZID = "Europe/Oslo"
IP_Address = "10.dd.bb.27"
Broker_DNS_Name = "vcs01.my.domain.com"
Windows_Timezone = "Central European Standard Time"
Language = "en_US.utf8"
LoggedOn_Username = "lars"
Broker_Tunneled = "true"
Broker_URL = "https://10.cc.bb.251:443"
Protocol = "PCOIP"
2011-09-28 10:33:09,791 DEBUG <MessageFrameWorkDispatch> [wssm] gotClientInfo: CommandsToRunOnReconnect has 0 scripts
2011-09-28 10:33:09,791 DEBUG <MessageFrameWorkDispatch> [wsnm_desktop] DesktopManager got a SetTimeZone message
2011-09-28 10:33:09,807 DEBUG <MessageFrameWorkDispatch> [wsnm_desktop] current timezone set
2011-09-28 10:33:10,963 INFO <328> [LogonUI] Program 'LogonUI - Windows Logon User Interface Host' started, version=6.1.7601.17514 (win7sp1_rtm.101119-1850), pid=1568, buildtype=release, usethread=0, closeafterwrite=0
2011-09-28 10:33:10,963 DEBUG <328> [LogonUI] Rpci: Sending request='machine.id.get '
2011-09-28 10:33:10,963 DEBUG <328> [LogonUI] Rpci: Sent request='machine.id.get ', reply='vdi.broker.useSvi=1;vdi.broker.brokerPublicKey=MIHxMIGoBgcqhkjOOAQBMIGcAkEA/KaCzo4Syrom78z3EQ5SbbB4sF7ey80etKII864WF64B81uRpH5t9jQTxeEu0ImbzRMqzVDZkVG9xD7nN1kuFwIVAJYu3cw2nLqOuyYO5rahJtk0bjjFAkBnhHGyepz0TukaScUUfbGpqvJE8FpDTWSGkx0tFCcbnjUDC3H9c9oXkGmzLik1Yw4cIGI1TQ2iCmxBblC+eUykA0QAAkEAnfprpbzURfAm0NQVv171IIKWUKWkZfZrhTq5EVoZ/2pfhPo+eB7dH88gg/KDiuJDLP+1lGn9aft20F6YwPCW+Q==;vdi.broker.brokers=vcs01.my.domain.com ;vdi.broker.agentIdentity=agent/4bf136e4-59ae-4a26-9a9d-483fcf13d05c;vdi.broker.agentPrivateKeyHash=r78jwga/k50Wee7YI9K6WA==;vdi.broker.itemDn=cn=4bf136e4-59ae-4a26-9a9d-483fcf13d05c,ou=servers,dc=vdi,dc=vmware,dc=int;vdi.broker.asyncSessionSeconds=150;vdi.broker.brokerPublicKeyHash=kTI3JoIz8FO2vCyXjOk7XQ==;vdi.broker.singleuse2=1;vdi.broker.msMode=OFF;vdi.broker.agentPrivateKey=MIHGAgEAMIGoBgcqhkjOOAQBMIGcAkEA/KaCzo4Syrom78z3EQ5SbbB4sF7ey80etKII864WF64B81uRpH5t9jQTxeEu0ImbzRMqzVDZkVG9xD7nN1kuFwIVAJYu3cw2nLqOuyYO5rahJtk0bjjFAkBnhHGyepz0TukaScUUfbGpqvJE8FpDTWSGkx0tFCcbnjUDC3H9c9oXkGmzLik1Yw4cIGI1TQ2iCmxBblC+eUykBBYCFB2DvXwmYGUt8uo4kaeWbv6T9hEc;vdi.broker.disconnecttimeout=120;vdi.broker.singleuse=0;vdi.broker.useSysprep=0;vdi.broker.poolDn=cn=win7,ou=server groups,dc=vdi,dc=vmware,dc=int', len=1222, status=1
2011-09-28 10:33:11,042 DEBUG <Main Thread> [wsnm_desktop] WTS_SESSION_LOCK, session=1
2011-09-28 10:33:11,043 DEBUG <1064> [wsnm_desktop] PCoIPCnx::OnConnectionComplete End
2011-09-28 10:33:11,043 DEBUG <SimpleEventPoll> [wsnm_desktop] Simulating Ctrl+Alt+Del for SSO.
2011-09-28 10:33:11,044 DEBUG <280> [pcoip_server_win32] VChanServerHost::SignalConnectionEvent: Signaling event 'Global\PCoIP_VChan-Reconnect'
2011-09-28 10:33:11,044 DEBUG <280> [pcoip_server_win32] VChanServerHost::SignalConnectionEvent: The 'Name' key doesn't exist for addin 'Dynamic VC'.
2011-09-28 10:33:11,046 DEBUG <VChanConnectionMonitor> [wsnm_scredir] VChanMgr::HandleConnectCallback: Received connection notification.
2011-09-28 10:33:11,047 DEBUG <VChanConnectionMonitor> [wsnm_scredir] VChanMgr::OpenChannelHelper: VChan open succeeded. Channel handle is 256.
2011-09-28 10:33:11,076 DEBUG <328> [LogonUI] Outgoing SharedMemory channel from LogonUI to machine V02-00003-A.domain.com wsnm connected as : domain\V02-00003-A$, Authenticated through SSPI, package = NTLM, encrypting = true
2011-09-28 10:33:11,077 DEBUG <328> [LogonUI] LogonUI MessageQueueShared started with numThreads=1, queueTreshold=250
2011-09-28 10:33:11,077 DEBUG <SharedMemAuthenticateThread> [MessageFrameWork] Incoming SharedMemory channel from machine V02-00003-A.domain.com LogonUI to wsnm connected as : domain\V02-00003-A$, Authenticated through SSPI, package = NTLM, encrypting = true, canImpersonate = true
2011-09-28 10:33:11,077 DEBUG <SharedMemAuthenticateThread> [wssm_usb] USB channel callback: remoteName: LogonUI incoming: true local: true opened: true
2011-09-28 10:33:11,078 DEBUG <MessageFrameWorkDispatch> [wsnm_desktop] DesktopManager got a GetCredentialsForTicket message
2011-09-28 10:33:11,078 DEBUG <MessageFrameWorkDispatch> [wsnm_desktop] ticket::GetCredentialsForTicket Ticket found from session, session = 1, user = LOCAL\console
2011-09-28 10:33:11,089 DEBUG <328> [LogonUI] credprovider::GetCredentialCount: Allowing autologon: console protocol credentials available
2011-09-28 10:33:11,089 DEBUG <MessageChannel ReceiveThread> [LogonUI] Closed outgoing SharedMemory channel to machine V02-00003-A.domain.com, user domain\V02-00003-A$
2011-09-28 10:33:11,092 DEBUG <328> [LogonUI] cred::GetSerialization: Using PCoIP password credentials for SSO.
2011-09-28 10:33:11,092 DEBUG <MessageChannel ReceiveThread> [wssm_usb] USB channel callback: remoteName: LogonUI incoming: true local: true opened: false
2011-09-28 10:33:11,092 DEBUG <MessageFrameWorkShare> [MessageFrameWork] Closed incoming SharedMemory channel from machine V02-00003-A.domain.com, user domain\V02-00003-A$
2011-09-28 10:33:11,173 DEBUG <MessageFrameWorkDispatch> [ws_vmx] Rpci: Sending request='machine.id.get '
2011-09-28 10:33:11,173 DEBUG <MessageFrameWorkDispatch> [ws_vmx] Rpci: Sent request='machine.id.get ', reply='vdi.broker.useSvi=1;vdi.broker.brokerPublicKey=MIHxMIGoBgcqhkjOOAQBMIGcAkEA/KaCzo4Syrom78z3EQ5SbbB4sF7ey80etKII864WF64B81uRpH5t9jQTxeEu0ImbzRMqzVDZkVG9xD7nN1kuFwIVAJYu3cw2nLqOuyYO5rahJtk0bjjFAkBnhHGyepz0TukaScUUfbGpqvJE8FpDTWSGkx0tFCcbnjUDC3H9c9oXkGmzLik1Yw4cIGI1TQ2iCmxBblC+eUykA0QAAkEAnfprpbzURfAm0NQVv171IIKWUKWkZfZrhTq5EVoZ/2pfhPo+eB7dH88gg/KDiuJDLP+1lGn9aft20F6YwPCW+Q==;vdi.broker.brokers=vcs01.my.domain.com ;vdi.broker.agentIdentity=agent/4bf136e4-59ae-4a26-9a9d-483fcf13d05c;vdi.broker.agentPrivateKeyHash=r78jwga/k50Wee7YI9K6WA==;vdi.broker.itemDn=cn=4bf136e4-59ae-4a26-9a9d-483fcf13d05c,ou=servers,dc=vdi,dc=vmware,dc=int;vdi.broker.asyncSessionSeconds=150;vdi.broker.brokerPublicKeyHash=kTI3JoIz8FO2vCyXjOk7XQ==;vdi.broker.singleuse2=1;vdi.broker.msMode=OFF;vdi.broker.agentPrivateKey=MIHGAgEAMIGoBgcqhkjOOAQBMIGcAkEA/KaCzo4Syrom78z3EQ5SbbB4sF7ey80etKII864WF64B81uRpH5t9jQTxeEu0ImbzRMqzVDZkVG9xD7nN1kuFwIVAJYu3cw2nLqOuyYO5rahJtk0bjjFAkBnhHGyepz0TukaScUUfbGpqvJE8FpDTWSGkx0tFCcbnjUDC3H9c9oXkGmzLik1Yw4cIGI1TQ2iCmxBblC+eUykBBYCFB2DvXwmYGUt8uo4kaeWbv6T9hEc;vdi.broker.disconnecttimeout=120;vdi.broker.singleuse=0;vdi.broker.useSysprep=0;vdi.broker.poolDn=cn=win7,ou=server groups,dc=vdi,dc=vmware,dc=int', len=1222, status=1
2011-09-28 10:33:11,197 DEBUG <Main Thread> [wsnm_desktop] WTS_SESSION_UNLOCK, session=1
2011-09-28 10:33:11,899 INFO <760> [TPAutoConnect] Program 'TPAutoConnect - TPAutoConnect User Agent' started, version=7,17,526,3, pid=764, buildtype=release, usethread=0, closeafterwrite=0
2011-09-28 10:33:11,901 DEBUG <760> [TPAutoConnect] TPAutoConnect MessageQueueShared started with numThreads=2, queueTreshold=250
2011-09-28 10:33:11,901 DEBUG <760> [TPAutoConnect] TPAutoConnect MessageQueue started with numThreads=2, queueTreshold=1000
2011-09-28 10:33:11,901 DEBUG <760> [TPAutoConnect] MessageFrameWork Worker Added, Name=System, Description=The Builtin MessageFrameWork Administration Queue, Channel=0000000000000000
2011-09-28 10:33:11,904 DEBUG <SharedMemAuthenticateThread> [MessageFrameWork] Incoming SharedMemory channel from machine V02-00003-A.domain.com PCoIP Virtual Channels to wsnm connected as : trusteddomain\user, Authenticated through SSPI, package = NTLM, encrypting = true, canImpersonate = true
2011-09-28 10:33:11,904 DEBUG <SharedMemAuthenticateThread> [wssm_usb] USB channel callback: remoteName: PCoIP Virtual Channels incoming: true local: true opened: true
2011-09-28 10:33:11,905 DEBUG <NodeManagerWatcher> [TPAutoConnect] Outgoing SharedMemory channel from PCoIP Virtual Channels to machine V02-00003-A.domain.com wsnm connected as : trusteddomain\user, Authenticated through SSPI, package = NTLM, encrypting = true
2011-09-28 10:33:11,906 DEBUG <NodeManagerWatcher> [TPAutoConnect] Connection to Node Manager established.
2011-09-28 10:33:11,906 DEBUG <NodeManagerWatcher> [TPAutoConnect] ChannelCallbackEx: remoteName:"wsnm" incoming:false local:true opened:true
2011-09-28 10:33:12,032 DEBUG <MessageFrameWorkDispatch> [MessageFrameWork] MessageFrameWork Worker Added, Name=PCoIPVChan-CLIENT(764), Description=PCoIPVChan, Channel=00000000009F71D0
2011-09-28 10:33:12,032 DEBUG <760> [TPAutoConnect] MessageFrameWork Worker Added, Name=PCoIPVChan-CLIENT(764), Description=PCoIPVChan, Channel=0000000000000000
2011-09-28 10:33:12,032 DEBUG <760> [TPAutoConnect] pcoip_vchan_plugin_app_init(): ready
2011-09-28 10:33:41,187 DEBUG <MessageFrameWorkDispatch> [ws_vmx] Rpci: Sending request='machine.id.get '
2011-09-28 10:33:41,187 DEBUG <MessageFrameWorkDispatch> [ws_vmx] Rpci: Sent request='machine.id.get ', reply='vdi.broker.useSvi=1;vdi.broker.brokerPublicKey=MIHxMIGoBgcqhkjOOAQBMIGcAkEA/KaCzo4Syrom78z3EQ5SbbB4sF7ey80etKII864WF64B81uRpH5t9jQTxeEu0ImbzRMqzVDZkVG9xD7nN1kuFwIVAJYu3cw2nLqOuyYO5rahJtk0bjjFAkBnhHGyepz0TukaScUUfbGpqvJE8FpDTWSGkx0tFCcbnjUDC3H9c9oXkGmzLik1Yw4cIGI1TQ2iCmxBblC+eUykA0QAAkEAnfprpbzURfAm0NQVv171IIKWUKWkZfZrhTq5EVoZ/2pfhPo+eB7dH88gg/KDiuJDLP+1lGn9aft20F6YwPCW+Q==;vdi.broker.brokers=vcs01.my.domain.com ;vdi.broker.agentIdentity=agent/4bf136e4-59ae-4a26-9a9d-483fcf13d05c;vdi.broker.agentPrivateKeyHash=r78jwga/k50Wee7YI9K6WA==;vdi.broker.itemDn=cn=4bf136e4-59ae-4a26-9a9d-483fcf13d05c,ou=servers,dc=vdi,dc=vmware,dc=int;vdi.broker.asyncSessionSeconds=150;vdi.broker.brokerPublicKeyHash=kTI3JoIz8FO2vCyXjOk7XQ==;vdi.broker.singleuse2=1;vdi.broker.msMode=OFF;vdi.broker.agentPrivateKey=MIHGAgEAMIGoBgcqhkjOOAQBMIGcAkEA/KaCzo4Syrom78z3EQ5SbbB4sF7ey80etKII864WF64B81uRpH5t9jQTxeEu0ImbzRMqzVDZkVG9xD7nN1kuFwIVAJYu3cw2nLqOuyYO5rahJtk0bjjFAkBnhHGyepz0TukaScUUfbGpqvJE8FpDTWSGkx0tFCcbnjUDC3H9c9oXkGmzLik1Yw4cIGI1TQ2iCmxBblC+eUykBBYCFB2DvXwmYGUt8uo4kaeWbv6T9hEc;vdi.broker.disconnecttimeout=120;vdi.broker.singleuse=0;vdi.broker.useSysprep=0;vdi.broker.poolDn=cn=win7,ou=server groups,dc=vdi,dc=vmware,dc=int', len=1222, status=1
2011-09-28 10:34:08,952 DEBUG <MessageFrameWorkDispatch> [wsnm_desktop] DesktopManager got a SendSessionReport message
2011-09-28 10:34:08,967 DEBUG <Thread-111> [ComponentResponse] Message is <?xml version="1.0"?>
<TERMINALRESPONSE><ID></ID><SERVERDN>cn=4bf136e4-59ae-4a26-9a9d-483fcf13d05c,ou=servers,dc=vdi,dc=vmware,dc=int</SERVERDN><SERVERPOOLDN>cn=win7,ou=server groups,dc=vdi,dc=vmware,dc=int</SERVERPOOLDN><SERVERDNSNAME>V02-00003-A.domain.com</SERVERDNSNAME><DYNAMICIPADDRESS>10.aa.bb.29</DYNAMICIPADDRESS><PLATFORM>WINDOWS</PLATFORM><OSVERMAJOR>6</OSVERMAJOR><OSVERMINOR>1</OSVERMINOR><AGENTVERSION>5.0.0</AGENTVERSION><AGENTBUILDNUM>481677</AGENTBUILDNUM><PROTOCOL><NAME>RDP</NAME><PORT>3389</PORT><STATUS>ready</STATUS></PROTOCOL><PROTOCOL><NAME>PCOIP</NAME><PORT>0</PORT><STATUS>negotiate</STATUS></PROTOCOL><AGENTREADY>true</AGENTREADY><ACCEPTINGCONNECTIONS>true</ACCEPTINGCONNECTIONS><ERROR-CODE>AGENT_ERR_OK</ERROR-CODE><STARTTIME>1317190541</STARTTIME><MAXSESSIONS>1</MAXSESSIONS><SESSIONCOUNT>1</SESSIONCOUNT><PENDINGSESSIONCOUNT>0</PENDINGSESSIONCOUNT><CONNECTEDSESSIONCOUNT>1</CONNECTEDSESSIONCOUNT><DISCONNECTEDSESSIONCOUNT>0</DISCONNECTEDSESSIONCOUNT><TERMINALSERVICESENABLED>false</TERMINALSERVICESENABLED><LISTENERS><LISTENER>FRAMEWORKCHANNEL:32111</LISTENER></LISTENERS><DOMAIN><NAME>domain</NAME></DOMAIN><DOMAIN><NAME>trusteddomain</NAME></DOMAIN><DOMAIN><NAME>HNT</NAME></DOMAIN><DOMAIN><NAME>HSM</NAME></DOMAIN><DOMAIN><NAME>MNH</NAME></DOMAIN><DOMAIN><NAME>MOLDE</NAME></DOMAIN><DOMAIN><NAME>STOH</NAME></DOMAIN><DOMAIN><NAME>STOLAV</NAME></DOMAIN><DOMAIN><NAME>USER</NAME></DOMAIN><DOMAIN><NAME>V02-00003-A</NAME></DOMAIN><SESSION><SESSIONGUID>49c9097d-23ee-43db-9c80-8b74f37012dd</SESSIONGUID><USERDN>cn=s-1-5-21-1659004503-2000478354-839522115-12212,cn=foreignsecurityprincipals,dc=vdi,dc=vmware,dc=int</USERDN><BROKERUSERSID>S-1-5-21-1659004503-2000478354-839522115-12212</BROKERUSERSID><CONNECTIONID>3A516F94F539F7C340354987BF5E8ECD</CONNECTIONID><LOCATIONID>3d603dee-6f34-425c-a8c5-1d9529b6d031</LOCATIONID><USERNAME>user</USERNAME><DOMAINNAME>trusteddomain</DOMAINNAME><SESSIONID>1</SESSIONID><STATE>Connected</STATE><CLIENTNAME></CLIENTNAME><CLIENTADDRESS></CLIENTADDRESS><PROTOCOL>PCOIP</PROTOCOL><USERSID>S-1-5-21-1659004503-2000478354-839522115-12212</USERSID><LOGGEDINASUSER>user</LOGGEDINASUSER><LOGGEDINASDOMAIN>trusteddomain</LOGGEDINASDOMAIN><STARTTIME>1317198747</STARTTIME><STARTTICK>8232343</STARTTICK><FIRSTCONNECTTICK>8232875</FIRSTCONNECTTICK><LASTCONNECTTICK>8274328</LASTCONNECTTICK><LASTDISCONNECTTICK>8240937</LASTDISCONNECTTICK><LOGOFFTICK>0</LOGOFFTICK><CHECKEDOUT>false</CHECKEDOUT><GROUPSIDS><groupSID>S-1-5-21-1659004503-2000478354-839522115-513</groupSID><groupSID>S-1-1-0</groupSID><groupSID>S-1-5-32-545</groupSID><groupSID>S-1-5-32-555</groupSID><groupSID>S-1-5-4</groupSID><groupSID>S-1-2-1</groupSID><groupSID>S-1-5-11</groupSID><groupSID>S-1-5-15</groupSID><groupSID>S-1-5-5-0-1140379</groupSID><groupSID>S-1-2-0</groupSID><groupSID>S-1-5-21-1659004503-2000478354-839522115-9032</groupSID><groupSID>S-1-5-21-1659004503-2000478354-839522115-512</groupSID><groupSID>S-1-5-21-1659004503-2000478354-839522115-2036</groupSID><groupSID>S-1-5-21-1659004503-2000478354-839522115-5754</groupSID><groupSID>S-1-5-21-1659004503-2000478354-8395221
Here's the log from the connection server last logon attempt where we got to the password prompt:
13:40:57,460 WARN <TP-Processor1> [AbstractProcessor] (SESSION:D0962CE23C7AC0B76D26586477869774) Invalid encoding: utf8, specified in locale: en_US.utf8
13:41:02,541 INFO <TP-Processor3> [ProperoAuthFilter] (SESSION:D0962CE23C7AC0B76D26586477869774) User trusteddomain\user has successfully authenticated to VDM
13:41:02,559 INFO <TP-Processor3> [Audit] (SESSION:D0962CE23C7AC0B76D26586477869774) BROKER_LOGON:USER:trusteddomain\user;USERSID:S-1-5-21-1659004503-2000478354-839522115-12212;USERDN:CN=S-1-5-21-1659004503-2000478354-839522115-12212,CN=ForeignSecurityPrincipals,DC=vdi,DC=vmware,DC=int;
13:41:02,895 INFO <Thread-20> [ak] (Request36) User user connected to the Secure Gateway Server - session ID: A9B25EBDEB2971B48E1F3D8A190D60DD
13:41:02,897 WARN <Tunnel#36> [aj] (A9B25EBDEB2971B48E1F3D8A190D60DD) Old client detected: ChunkVersion = 0, latest = 1
13:41:05,900 INFO <DesktopControlJMS> [Audit] PENDING:Server:cn=4bf136e4-59ae-4a26-9a9d-483fcf13d05c,ou=servers,dc=vdi,dc=vmware,dc=int;Pool:cn=win7,ou=server groups,dc=vdi,dc=vmware,dc=int;DNS:V02-00003-A.domain.com;IP:10.aa.bb8.29;USER:trusteddomain\user;USERDN:cn=s-1-5-21-1659004503-2000478354-839522115-12212,cn=foreignsecurityprincipals,dc=vdi,dc=vmware,dc=int;BROKERUSERSID:S-1-5-21-1659004503-2000478354-839522115-12212;
13:41:07,004 INFO <DesktopControlJMS> [Audit] RECONNECTED:Server:cn=4bf136e4-59ae-4a26-9a9d-483fcf13d05c,ou=servers,dc=vdi,dc=vmware,dc=int;Pool:cn=win7,ou=server groups,dc=vdi,dc=vmware,dc=int;DNS:V02-00003-A.domain.com;IP:10.aa.bb8.29;USER:trusteddomain\user;USERDN:cn=s-1-5-21-1659004503-2000478354-839522115-12212,cn=foreignsecurityprincipals,dc=vdi,dc=vmware,dc=int;BROKERUSERSID:S-1-5-21-1659004503-2000478354-839522115-12212;
13:41:07,005 INFO <DesktopControlJMS> [DesktopTracker] User trusteddomain\user reconnected to machine V02-00003-A for desktop win7 - session allocated at September 28, 2011 10:32:27 AM CEST, disconnected for 68 mins 3 secs
13:42:12,399 INFO <TP-Processor1> [VCSessionBean] Logoff Session...
13:42:14,565 INFO <DesktopControlJMS> [Audit] ENDED:Server:cn=4bf136e4-59ae-4a26-9a9d-483fcf13d05c,ou=servers,dc=vdi,dc=vmware,dc=int;Pool:cn=win7,ou=server groups,dc=vdi,dc=vmware,dc=int;DNS:V02-00003-A.domain.com;IP:10.aa.bb8.29;USER:trusteddomain\user;USERDN:cn=s-1-5-21-1659004503-2000478354-839522115-12212,cn=foreignsecurityprincipals,dc=vdi,dc=vmware,dc=int;BROKERUSERSID:S-1-5-21-1659004503-2000478354-839522115-12212;
13:42:14,565 INFO <DesktopControlJMS> [DesktopTracker] User trusteddomain\user logged off from machine V02-00003-A for desktop win7 - session allocated at September 28, 2011 10:32:27 AM CEST, connected for 1 mins 7 secs
13:42:24,614 INFO <propagate-d42e591e-c6c4-4748-8ea0-7535cf5795a3> [PendingOperation] Pool control for desktop win7 is refitting VM V02-00003-A
13:42:27,755 INFO <DesktopControlJMS> [Audit] SHUTDOWN:Server:cn=4bf136e4-59ae-4a26-9a9d-483fcf13d05c,ou=servers,dc=vdi,dc=vmware,dc=int;Pool:cn=win7,ou=server groups,dc=vdi,dc=vmware,dc=int;DNS:V02-00003-A.domain.com;IP:10.aa.bb8.29;
13:42:38,726 WARN <TP-Processor2> [AbstractProcessor] (SESSION:9628040BD22F080DACFC4006C4B76689) Invalid encoding: utf8, specified in locale: en_US.utf8
13:42:43,734 INFO <TP-Processor1> [ProperoAuthFilter] (SESSION:9628040BD22F080DACFC4006C4B76689) User trusteddomain\user has successfully authenticated to VDM
13:42:43,751 INFO <TP-Processor1> [Audit] (SESSION:9628040BD22F080DACFC4006C4B76689) BROKER_LOGON:USER:trusteddomain\user;USERSID:S-1-5-21-1659004503-2000478354-839522115-12212;USERDN:CN=S-1-5-21-1659004503-2000478354-839522115-12212,CN=ForeignSecurityPrincipals,DC=vdi,DC=vmware,DC=int;
13:42:44,095 INFO <Thread-29> [ak] (Request37) User user connected to the Secure Gateway Server - session ID: C9F651EE1F19628790CB5F79B5AC9398
13:42:44,102 WARN <Tunnel#37> [aj] (C9F651EE1F19628790CB5F79B5AC9398) Old client detected: ChunkVersion = 0, latest = 1
13:42:46,221 INFO <DesktopControlJMS> [Audit] PENDING:Server:cn=bba90553-4615-4a71-adc8-20270acf56ac,ou=servers,dc=vdi,dc=vmware,dc=int;Pool:cn=win7,ou=server groups,dc=vdi,dc=vmware,dc=int;DNS:V02-00002-A.domain.com;IP:10.aa.bb8.28;USER:trusteddomain\user;USERDN:cn=s-1-5-21-1659004503-2000478354-839522115-12212,cn=foreignsecurityprincipals,dc=vdi,dc=vmware,dc=int;BROKERUSERSID:S-1-5-21-1659004503-2000478354-839522115-12212;
13:42:47,155 INFO <PendingOperation-vm-226> [Audit] VC_OUTAGE:Url:https://vcenterlab01:443/sdk
13:42:47,155 WARN <PendingOperation-vm-226> [ServiceConnection25] VirtualCenter https://vcenterlab01:443/sdk is currently unavailable - attempting to reconnect
13:43:27,760 INFO <PendingOperation-vm-226> [PendingOperation] Pool cn=d42e591e-c6c4-4748-8ea0-7535cf5795a3,ou=virtualcenter,ou=properties,dc=vdi,dc=vmware,dc=int::Reverted Linked Clone VM /DC-Lab/vm/Win7/V02-00003-A (Clone Id: 1ee7d60a-ff0b-469e-8981-cd289d86917e) successfully
13:43:37,784 INFO <propagate-d42e591e-c6c4-4748-8ea0-7535cf5795a3> [PendingOperation] Pool control for desktop win7 is starting VM V02-00003-A
13:44:17,599 INFO <DesktopControlJMS> [Audit] STARTUP:Server:cn=4bf136e4-59ae-4a26-9a9d-483fcf13d05c,ou=servers,dc=vdi,dc=vmware,dc=int;Pool:cn=win7,ou=server groups,dc=vdi,dc=vmware,dc=int;DNS:V02-00003-A.domain.com;IP:10.aa.bb8.29;
13:47:14,438 INFO <2504> [ws_ldap] 0 collision entry references resolved.
13:47:14,439 INFO <2504> [ws_ldap] 2 deleted entry references removed.
13:47:14,439 INFO <2504> [ws_ldap] 0 collision entries removed.
Lars
There is an AllowSingoleSignon GPO for the View agent and if that was disabled you would have to authenticate to View and then authenticate again to Windows. it is enabled by default so someone would have had to set it on the virtual desktops. It also wouldn't explain your initial disconnect.
Hi,
We checket out this one:
http://blogs.vmware.com/view/2010/12/troubleshooting-single-sign-on.html
We have verified that HKLM\SOFTWARE\Microsoft\Windows\CurrentVersion\Policies\System\SoftwareSASGeneration is set to 1.
Lars