Re: PCoIP authentication and GPO

ferdis · ‎10-07-2010

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.

mikebarnett · ‎10-07-2010

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

Twitter: @MikeBarnett_

PxPxger · ‎10-08-2010

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

grossag · ‎10-11-2010

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.

larstr · ‎09-27-2011

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

mittim12 · ‎09-27-2011

Hey Lars, what exactly is happening in your environment?

larstr · ‎09-28-2011

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):

Sep 28 10:32:07.186: vmware-view| Log for VMware View Client pid=2922 version=4.0.1 build-235010 build=build-235010 option=Release

Sep 28 10:32:07.186: vmware-view| Host codepage=UTF-8 encoding=UTF-8

Sep 28 10:32:07.186: vmware-view| Using locale directory /usr/share/locale

Sep 28 10:32:07.190: vmware-view| Command line: vmware-view

Sep 28 10:32:07.502: vmware-view| Initialzing connection to broker https://10.aa.bb.251:443

Sep 28 10:32:07.502: vmware-view| Busy: Setting client locale.

Sep 28 10:32:07.504: vmware-view| Busy: Getting server configuration.

Sep 28 10:32:07.834: vmware-view| Auth Info: Name: windows-password, result: ok

Sep 28 10:32:13.793: vmware-view| Busy: Logging in.

Sep 28 10:32:14.068: vmware-view| Auth Info: Name: , result: ok

Sep 28 10:32:14.068: vmware-view| Executing secure HTTP tunnel: /usr/bin/vmware-view-tunnel

Sep 28 10:32:14.068: vmware-view| Starting child: /usr/bin/vmware-view-tunnel 'https://vcs01.my.domain.com:443' '[omitted]'

Sep 28 10:32:14.069: vmware-view| Number of monitors on this screen is 1.

Sep 28 10:32:14.069: vmware-view| Current window manager supports _NET_WM_FULLSCREEN_MONITORS message.

Sep 28 10:32:14.217: vmware-view| vmware-view-tunnel(2923): Connecting to tunnel server 'vcs01.my.domain.com:443' over HTTPS.

Sep 28 10:32:14.217: vmware-view| vmware-view-tunnel(2923): SOCKET creating new socket, connecting to 10.aa.bb.251:443 (vcs01.my.domain.com)

Sep 28 10:32:14.385: vmware-view| vmware-view-tunnel(2923): TUNNEL READY

Sep 28 10:32:16.154: vmware-view| Transitioning: Connecting to the desktop.

Sep 28 10:32:18.533: vmware-view| vmware-view-tunnel(2923): SOCKET creating new listening socket on port 0

Sep 28 10:32:18.534: vmware-view| vmware-view-tunnel(2923): Creating new listener "Port1" on 127.0.0.1:41964 to server 10.cc.bb.29:32111.

Sep 28 10:32:18.541: vmware-view| Desktop connect successful. Starting desktop using PCOIP.

Sep 28 10:32:18.541: vmware-view| Transitioning: Connecting to the desktop.

Sep 28 10:32:18.549: vmware-view| Connecting to desktop with total geometry 1260x787.

Sep 28 10:32:18.549: vmware-view| Starting usb redirection to '127.0.0.1:41964' with ticket '4e6df0fa-5c98-469b-a84e-f985c0d02613'.

Sep 28 10:32:18.550: vmware-view| Starting child: /usr/bin/vmware-view-usb '-a' '127.0.0.1' '-p' '41964' '-u' '[omitted]'

Sep 28 10:32:18.551: 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:32:18.551: vmware-view| Starting child: vmware-remotemks-container 'pcoip_client' 'mksvchanclient' '[omitted]' '1260x787' '92275653'

Sep 28 10:32:18.674: vmware-view| vmware-view-usb(2924): vmware-view-usb[2924]: Sep 28 10:32:18.942: vmware-view-usb| Starting.

Sep 28 10:32:18.695: vmware-view| vmware-view-tunnel(2923): Creating new channel "1" to listener "Port1".

Sep 28 10:32:18.765: vmware-view| vmware-remotemks-container(2925): Creating VMware Remote MKS interface

Sep 28 10:32:18.808: vmware-view| vmware-view-usb(2924): vmware-view-usb[2924]: Sep 28 10:32:18.285: vmware-view-usb| Remote USB status reported, state: available

Sep 28 10:32:18.808: vmware-view| vmware-view-usb(2924): vmware-view-usb[2924]: Sep 28 10:32:18.443: vmware-view-usb| Device attributes: Name: ChipsBnk Video Device, Id: vid6127,pid18439, Family: , Path: bus1,port7

Sep 28 10:32:18.808: vmware-view| vmware-view-usb(2924): vmware-view-usb[2924]: Sep 28 10:32:18.475: vmware-view-usb| 'ChipsBnk Video Device' is allowed.

Sep 28 10:32:18.826: vmware-view| vmware-view-usb(2924): vmware-view-usb[2924]: Sep 28 10:32:18.423: vmware-view-usb| Error initializing Hal Context

Sep 28 10:32:18.826: vmware-view| vmware-view-usb(2924): vmware-view-usb[2924]: Sep 28 10:32:18.502: vmware-view-usb| ChipsBnk Video Device is not being redirected as we may not have priviledge or it may be a boot device

Sep 28 10:32:18.826: vmware-view| vmware-view-usb(2924): vmware-view-usb[2924]: Sep 28 10:32:18.549: vmware-view-usb| Device attributes: Name: AuthenTec Fingerprint Sensor, Id: vid2303,pid10256, Family: vendor, Path: bus4,port2

Sep 28 10:32:18.826: vmware-view| vmware-view-usb(2924): vmware-view-usb[2924]: Sep 28 10:32:18.586: vmware-view-usb| 'AuthenTec Fingerprint Sensor' is allowed.

Sep 28 10:32:19.148: vmware-view| vmware-remotemks-container(2925): CreateMKSInterface: forcing mount, Remote MKS already present

Sep 28 10:32:19.149: vmware-view| vmware-remotemks-container(2925): Entering GTK main loop

Sep 28 10:32:19.357: vmware-view| vmware-view-usb(2924): vmware-view-usb[2924]: Sep 28 10:32:19.774: vmware-view-usb| AuthenTec Fingerprint Sensor is being redirected

Sep 28 10:32:33.785: vmware-view| vmware-remotemks-container(2925): vmdbPipe_Streams Couldn't read: OVL_STATUS_EOF

Sep 28 10:32:33.785: vmware-view| vmware-remotemks-container(2925): Remote MKS network connection status changed: DISCONNECTED

Sep 28 10:32:33.791: vmware-view| vmware-remotemks-container(2925): GTK main loop terminated

Sep 28 10:32:33.791: vmware-view| vmware-remotemks-container(2925) died.

Sep 28 10:32:33.792: vmware-view| vmware-remotemks-container(2925) exited normally.

Sep 28 10:32:33.810: vmware-view| Cancelling 0 broker XML requests.

Sep 28 10:32:33.817: vmware-view| vmware-view-usb(2924) exited normally.

Sep 28 10:32:33.817: vmware-view| vmware-view-tunnel(2923) exited due to signal 15.

Sep 28 10:32:33.817: vmware-view| Cancelling 0 broker XML requests.

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,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

mittim12 · ‎09-28-2011

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.

larstr · ‎09-28-2011

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