Copy past stopped working on one virtual machine, but is still working on another. I've followed all the steps I could find on the internet (uninstall vmware tools, remove dirs + regedit, restart, reinstall, restart, disable folder sharing, disable settings, enable the isolation settings,...) but it just won't work. I manually installed VMWare tools v11.3, I've reinstalled the VMWare player on Macos, ...
Any advice on why it won't work with this virtual machine?
When looking in the c:/programdata/vmware folder for some logs, I found this error (?) in the vmrawdsk.log:
--- System started at 06/23/2021 02:28:32
Unable to lock and unlock a test registry key; disabling driver
VmrdReg_TestLock: deleting locked key has unexpected status 0x0
If I open the vmware.log, the VMWare tools seems to load just fine:
2021-06-23T02:48:29.546+02:00| vmx| I005: TOOLS installed legacy version 11360, available legacy version 11334
2021-06-23T02:48:29.546+02:00| vmx| I005: TOOLS manifest update status is 4
2021-06-23T02:48:29.546+02:00| vmx| I005: TOOLS can be autoupgraded.
2021-06-23T02:48:29.548+02:00| vmx| I005: TOOLS autoupgrade protocol version 0
In the log files, I noticed the following line, but I've no idea wether this is relevant:
2021-06-23T02:40:42.287+02:00| vmx| I005: GuestRpcSendTimedOut: message to toolbox-dnd timed out.
2021-06-23T02:40:42.287+02:00| vmx| W003: TOOLS unity.launchmenu.open failed: failure of the transport layer
To get more details, I've set the log level for the service "vmusr" to "debug". Below is a part of the log that refers to dndcp:
Spoiler (Highlight to read) [2021-06-23T10:12:43.094Z] [ debug] [vmusr] [4576] DnDCPTransportGuestRpc::RegisterRpc: can not find valid cmd for 5, cmdStr NULL disableStr NULL [2021-06-23T10:12:43.094Z] [ debug] [dndcp] [4576] CopyPasteDnDWin32::CreateMessageWnd: enter [2021-06-23T10:12:43.094Z] [ debug] [dndcp] [4576] DnDPluginControlProc: got Window Message 0x81 for hwnd 0x10312 [2021-06-23T10:12:43.094Z] [ debug] [dndcp] [4576] DnDPluginControlProc: sending to HandleWindowMessage [2021-06-23T10:12:43.110Z] [ debug] [dndcp] [4576] DnDPluginControlProc: got Window Message 0x83 for hwnd 0x10312 [2021-06-23T10:12:43.110Z] [ debug] [dndcp] [4576] DnDPluginControlProc: sending to HandleWindowMessage [2021-06-23T10:12:43.110Z] [ debug] [dndcp] [4576] DnDPluginControlProc: got Window Message 0x1 for hwnd 0x10312 [2021-06-23T10:12:43.110Z] [ debug] [dndcp] [4576] DnDPluginControlProc: sending to HandleWindowMessage [2021-06-23T10:12:43.110Z] [ debug] [dndcp] [4576] DnDPluginControlProc: got Window Message 0x5 for hwnd 0x10312 [2021-06-23T10:12:43.110Z] [ debug] [dndcp] [4576] DnDPluginControlProc: sending to HandleWindowMessage [2021-06-23T10:12:43.110Z] [ debug] [dndcp] [4576] DnDPluginControlProc: got Window Message 0x3 for hwnd 0x10312 [2021-06-23T10:12:43.110Z] [ debug] [dndcp] [4576] DnDPluginControlProc: sending to HandleWindowMessage [2021-06-23T10:12:43.110Z] [ debug] [dndcp] [4576] CopyPasteDnDWin32::CreateMessageWnd: created window 0x10312 [2021-06-23T10:12:43.110Z] [ debug] [dndcp] [4576] CDragDetWndTempl<class VMDropTargetImpl>::Create: enter [2021-06-23T10:12:43.116Z] [ debug] [dndcp] [4576] DnDPluginControlProc: got Window Message 0x1c for hwnd 0x10312 [2021-06-23T10:12:43.116Z] [ debug] [dndcp] [4576] DnDPluginControlProc: sending to HandleWindowMessage [2021-06-23T10:12:43.148Z] [ debug] [dndcp] [4576] CDragDetWndTempl<class VMDropTargetImpl>::Create: CreateWindowEx: created window 0x10314 [2021-06-23T10:12:43.148Z] [ debug] [dndcp] [4576] CDragDetWndTempl<class VMDropTargetImpl>::Create: Layered window is created. [2021-06-23T10:12:43.163Z] [ debug] [dndcp] [4576] CDragDetWndTempl<class VMDropTargetImpl>::Create: Successfully registered DragDetWnd 0x10314 [2021-06-23T10:12:43.163Z] [ debug] [dndcp] [4576] CDragDetWndTempl<class VMDropTargetImpl>::Create: enter [2021-06-23T10:12:43.163Z] [ debug] [dndcp] [4576] CDragDetWndTempl<class VMDropTargetImpl>::Create: CreateWindowEx: created window 0x1031c [2021-06-23T10:12:43.179Z] [ debug] [dndcp] [4576] CDragDetWndTempl<class VMDropTargetImpl>::Create: Layered window is created. [2021-06-23T10:12:43.179Z] [ debug] [dndcp] [4576] CDragDetWndTempl<class VMDropTargetImpl>::Create: Successfully registered DragDetWnd 0x1031c [2021-06-23T10:12:43.179Z] [ debug] [dndcp] [4576] DnDUIWin32::UpdateDetWnd: Hiding det window 0x10314 [2021-06-23T10:12:43.179Z] [ debug] [dndcp] [4576] DnDPluginControlProc: got Window Message 0x1c for hwnd 0x10312 [2021-06-23T10:12:43.179Z] [ debug] [dndcp] [4576] DnDPluginControlProc: sending to HandleWindowMessage [2021-06-23T10:12:43.179Z] [ debug] [dndcp] [4576] DnDUIWin32::UpdateUnityDetWnd: hiding unity detWnd [2021-06-23T10:12:43.179Z] [ debug] [dndcp] [4576] CopyPasteUIWin32::StartFCPThread: Attempting to start FCP thread [2021-06-23T10:12:43.179Z] [ debug] [dndcp] [4576] Pointer_Init:absoluteMouseState:ABSMOUSE_AVAILABLE [2021-06-23T10:12:43.179Z] [ debug] [dndcp] [4576] PointerUpdatePointerLoop: grabbed [2021-06-23T10:12:43.179Z] [ debug] [vmusr] [4576] Unable to open 'C:\Program Files\VMware\VMware Tools\messages\nl\dndCP.vmsg': No such file or directory [2021-06-23T10:12:43.179Z] [ message] [vmusr] [4576] Cannot load message catalog for domain 'dndCP', language 'nl', catalog dir 'C:\Program Files\VMware\VMware Tools'. [2021-06-23T10:12:43.179Z] [ message] [vmtoolsd] [4576] Plugin 'dndCP' initialized. [2021-06-23T10:12:43.179Z] [ debug] [dndcp] [7600] CopyPasteUIWin32::RunFCPThread: Starting guest->host FCP thread loop [2021-06-23T10:12:54.632Z] [ debug] [dndcp] [4576] PointerUpdatePointerLoop: grabbed [2021-06-23T10:12:54.632Z] [ debug] [dndcp] [4576] DnDCPReset: enter [2021-06-23T10:12:54.632Z] [ debug] [dndcp] [4576] CopyPasteDnDWrapper::OnReset: enter. [2021-06-23T10:12:54.632Z] [ debug] [dndcp] [4576] VMCopyPasteDnDWrapper::AddDnDPluginResetTimer: enter [2021-06-23T10:12:54.694Z] [ debug] [dndcp] [4576] DnDPluginControlProc: got Window Message 0x7e for hwnd 0x10312 [2021-06-23T10:12:54.694Z] [ debug] [dndcp] [4576] DnDPluginControlProc: sending to HandleWindowMessage [2021-06-23T10:12:54.726Z] [ debug] [dndcp] [4576] DnDPluginControlProc: got Window Message 0x46 for hwnd 0x10312 [2021-06-23T10:12:54.726Z] [ debug] [dndcp] [4576] DnDPluginControlProc: sending to HandleWindowMessage [2021-06-23T10:12:54.741Z] [ debug] [dndcp] [4576] DnDCPSetOption: enter option synctime value 1 [2021-06-23T10:12:54.741Z] [ debug] [dndcp] [4576] DnDCPSetOption: enter option copypaste value 1 [2021-06-23T10:12:54.741Z] [ debug] [vmusr] [4576] DnDCPTransportGuestRpc::RegisterRpc: can not find valid cmd for 5, cmdStr NULL disableStr NULL [2021-06-23T10:12:54.741Z] [ debug] [dndcp] [4576] VMCopyPasteDnDWrapper::OnSetOption: setting option 'copypaste' to '1' [2021-06-23T10:12:54.741Z] [ debug] [dndcp] [4576] CopyPasteDnDWrapper::SetCPIsEnabled: enter. [2021-06-23T10:12:54.741Z] [ debug] [dndcp] [4576] CopyPasteDnDWrapper::IsCPRegistered: enter. [2021-06-23T10:12:54.741Z] [ debug] [dndcp] [4576] CopyPasteDnDWrapper::RegisterCP: enter. [2021-06-23T10:12:54.741Z] [ debug] [dndcp] [4576] CopyPasteDnDWrapper::IsCPRegistered: enter. [2021-06-23T10:12:54.741Z] [ debug] [dndcp] [4576] CopyPasteDnDWrapper::SetCPIsRegistered: enter. [2021-06-23T10:12:54.741Z] [ debug] [dndcp] [4576] VMCopyPasteDnDWrapper::GetCPVersion: enter [2021-06-23T10:12:54.741Z] [ debug] [dndcp] [4576] CopyPasteDnDWrapper::IsCPRegistered: enter. [2021-06-23T10:12:54.741Z] [ debug] [dndcp] [4576] VMCopyPasteDnDWrapper::GetCPVersion: got version 4 [2021-06-23T10:12:54.741Z] [ debug] [dndcp] [4576] CopyPaste_SetVersion: enter version 4 [2021-06-23T10:12:54.741Z] [ debug] [dndcp] [4576] CopyPasteUIWin32::VmxCopyPasteVersionChanged: new version is 4 [2021-06-23T10:12:54.741Z] [ debug] [dndcp] [4576] GuestCopyPasteMgr::GuestCopyPasteMgr::VmxCopyPasteVersionChanged: enter version 4 [2021-06-23T10:12:54.741Z] [ debug] [dndcp] [4576] GuestCopyPasteMgr::GuestCopyPasteMgr::VmxCopyPasteVersionChanged: register ping reply changed 4 [2021-06-23T10:12:54.741Z] [ debug] [vmusr] [4576] DnDCPTransportGuestRpc::RegisterRpc: for copypaste.transport [2021-06-23T10:12:54.741Z] [ debug] [dndcp] [4576] CopyPasteDnDWrapper::IsCPRegistered: enter. [2021-06-23T10:12:54.741Z] [ debug] [dndcp] [4576] DnDCPSetOption: enter option autohide value 0 [2021-06-23T10:12:54.741Z] [ debug] [dndcp] [4576] DnDCPSetOption: enter option broadcastIP value 1 [2021-06-23T10:12:54.741Z] [ debug] [dndcp] [4576] DnDCPSetOption: enter option enableDnD value 1 [2021-06-23T10:12:54.741Z] [ debug] [vmusr] [4576] DnDCPTransportGuestRpc::RegisterRpc: can not find valid cmd for 5, cmdStr NULL disableStr NULL [2021-06-23T10:12:54.741Z] [ debug] [dndcp] [4576] VMCopyPasteDnDWrapper::OnSetOption: setting option 'enableDnD' to '1' [2021-06-23T10:12:54.741Z] [ debug] [dndcp] [4576] CopyPasteDnDWrapper::SetDnDIsEnabled: enter. [2021-06-23T10:12:54.741Z] [ debug] [dndcp] [4576] CopyPasteDnDWrapper::RegisterDnD: enter. [2021-06-23T10:12:54.741Z] [ debug] [dndcp] [4576] CopyPasteDnDWin32::RegisterDnD: enter [2021-06-23T10:12:54.741Z] [ debug] [dndcp] [4576] VMCopyPasteDnDWrapper::GetDnDVersion: enter [2021-06-23T10:12:54.741Z] [ debug] [dndcp] [4576] VMCopyPasteDnDWrapper::GetDnDVersion: got version 4 [2021-06-23T10:12:54.741Z] [ debug] [dndcp] [4576] CopyPasteDnDWin32::RegisterDnD: dnd version is 4 [2021-06-23T10:12:54.741Z] [ debug] [dndcp] [4576] GuestDnDMgr::GuestDnDMgr::VmxDnDVersionChanged: enter version 4 [2021-06-23T10:12:54.741Z] [ debug] [vmusr] [4576] DnDCPTransportGuestRpc::RegisterRpc: for dnd.transport [2021-06-23T10:12:54.741Z] [ debug] [dndcp] [4576] GuestDnDMgr::ResetDnD: change to state 1, session id 0 [2021-06-23T10:12:54.741Z] [ debug] [dndcp] [4576] CopyPasteDnDWin32::RegisterDnD: dnd is registered? 1 [2021-06-23T10:12:54.741Z] [ debug] [dndcp] [4576] DnDCPSetOption: enter option synctime.period value 0 [2021-06-23T10:12:54.741Z] [ debug] [dndcp] [4576] DnDCPSetOption: enter option time.synchronize.tools.enable value 1 [2021-06-23T10:12:54.741Z] [ debug] [dndcp] [4576] DnDCPSetOption: enter option time.synchronize.guest.resync value 0 [2021-06-23T10:12:54.741Z] [ debug] [dndcp] [4576] DnDCPSetOption: enter option time.synchronize.guest.resync.timeout value 0 [2021-06-23T10:12:54.741Z] [ debug] [dndcp] [4576] DnDCPSetOption: enter option time.synchronize.tools.startup.backward value 0 [2021-06-23T10:12:54.741Z] [ debug] [dndcp] [4576] DnDCPSetOption: enter option time.synchronize.tools.startup value 1 [2021-06-23T10:12:54.741Z] [ debug] [dndcp] [4576] DnDCPSetOption: enter option toolScripts.afterPowerOn value 1 [2021-06-23T10:12:54.741Z] [ debug] [dndcp] [4576] DnDCPSetOption: enter option toolScripts.beforePowerOff value 1 [2021-06-23T10:12:54.741Z] [ debug] [dndcp] [4576] DnDCPSetOption: enter option toolScripts.afterResume value 1 [2021-06-23T10:12:54.757Z] [ debug] [dndcp] [4576] DnDCPSetOption: enter option toolScripts.beforeSuspend value 1 [2021-06-23T10:12:54.757Z] [ debug] [dndcp] [4576] DnDCPSetOption: enter option time.synchronize.tools.slewCorrection value 1 [2021-06-23T10:12:54.757Z] [ debug] [dndcp] [4576] DnDCPSetOption: enter option time.synchronize.tools.percentCorrection value 0 [2021-06-23T10:12:54.757Z] [ debug] [dndcp] [4576] DnDCPSetOption: enter option mapRootHgfsShare value 1 [2021-06-23T10:12:54.757Z] [ debug] [dndcp] [4576] DnDCPSetOption: enter option linkRootHgfsShare value 1 [2021-06-23T10:12:54.757Z] [ debug] [dndcp] [4576] DnDCPSetOption: enter option enableMessageBusTunnel value 0 [2021-06-23T10:12:54.757Z] [ debug] [dndcp] [4576] DnDCPSetOption: enter option enableAppInfo value 1 [2021-06-23T10:12:54.757Z] [ debug] [dndcp] [4576] DnDCPCapabilities: enter [2021-06-23T10:12:54.757Z] [ debug] [dndcp] [4576] VMCopyPasteDnDWrapper::OnCapReg: enter [2021-06-23T10:12:54.757Z] [ debug] [dndcp] [4576] VMCopyPasteDnDWrapper::GetDnDVersion: enter [2021-06-23T10:12:54.757Z] [ debug] [dndcp] [4576] VMCopyPasteDnDWrapper::GetDnDVersion: got version 4 [2021-06-23T10:12:54.757Z] [ debug] [dndcp] [4576] VMCopyPasteDnDWrapper::OnCapReg: VMX is dnd version 4 [2021-06-23T10:12:54.757Z] [ debug] [dndcp] [4576] VMCopyPasteDnDWrapper::GetCPVersion: enter [2021-06-23T10:12:54.757Z] [ debug] [dndcp] [4576] CopyPasteDnDWrapper::IsCPRegistered: enter. [2021-06-23T10:12:54.757Z] [ debug] [dndcp] [4576] VMCopyPasteDnDWrapper::GetCPVersion: got version 4 [2021-06-23T10:12:54.757Z] [ debug] [dndcp] [4576] VMCopyPasteDnDWrapper::OnCapReg: VMX is copypaste version 4 [2021-06-23T10:12:54.788Z] [ debug] [vmusr] [4576] CopyPasteRpcV4::HandleMsg: Got DNDCP_CMD_PING_REPLY[2], sessionId 0, srcId 10001, binary size 0. [2021-06-23T10:12:54.788Z] [ debug] [dndcp] [4576] GuestCopyPasteMgr::OnPingReply: copypaste ping reply caps are 1555 [2021-06-23T10:12:54.804Z] [ debug] [vmusr] [4576] DnDRpcV4::HandleMsg: Got DNDCP_CMD_PING_REPLY[2], sessionId 0, srcId 10001, binary size 0. [2021-06-23T10:12:54.804Z] [ debug] [dndcp] [4576] GuestDnDMgr::OnPingReply: dnd ping reply caps are aab [2021-06-23T10:12:54.897Z] [ debug] [dndcp] [4576] DnDPluginControlProc: got Window Message 0x7e for hwnd 0x10312 [2021-06-23T10:12:54.897Z] [ debug] [dndcp] [4576] DnDPluginControlProc: sending to HandleWindowMessage [2021-06-23T10:12:54.960Z] [ debug] [dndcp] [4576] DnDPluginControlProc: got Window Message 0x46 for hwnd 0x10312 [2021-06-23T10:12:54.960Z] [ debug] [dndcp] [4576] DnDPluginControlProc: sending to HandleWindowMessage [2021-06-23T10:12:54.960Z] [ debug] [dndcp] [4576] DnDPluginResetSent: enter [2021-06-23T10:12:54.960Z] [ debug] [dndcp] [4576] VMCopyPasteDnDWrapper::OnResetInternal: enter [2021-06-23T10:12:54.960Z] [ debug] [dndcp] [4576] CopyPasteDnDWrapper::UnregisterDnD: enter. [2021-06-23T10:12:54.960Z] [ debug] [dndcp] [4576] CopyPasteDnDWin32::UnregisterDnD: enter [2021-06-23T10:12:54.960Z] [ debug] [dndcp] [4576] DnDUIWin32::Reset: entering [2021-06-23T10:12:54.960Z] [ debug] [dndcp] [4576] GuestDnDMgr::ResetDnD: change to state 1, session id 0 [2021-06-23T10:12:54.960Z] [ debug] [dndcp] [4576] CopyPasteDnDWrapper::IsCPRegistered: enter. [2021-06-23T10:12:54.960Z] [ debug] [dndcp] [4576] CopyPasteDnDWrapper::UnregisterCP: enter. [2021-06-23T10:12:54.960Z] [ debug] [dndcp] [4576] CopyPasteDnDWrapper::IsCPRegistered: enter. [2021-06-23T10:12:54.960Z] [ debug] [dndcp] [4576] VMCopyPasteDnDWrapper::GetCPVersion: enter [2021-06-23T10:12:54.960Z] [ debug] [dndcp] [4576] CopyPasteDnDWrapper::IsCPRegistered: enter. [2021-06-23T10:12:54.960Z] [ debug] [dndcp] [4576] VMCopyPasteDnDWrapper::GetCPVersion: got version 4 [2021-06-23T10:12:54.960Z] [ debug] [dndcp] [4576] CopyPasteUIWin32::Reset: entering [2021-06-23T10:12:54.960Z] [ debug] [dndcp] [4576] CopyPasteDnDWrapper::SetCPIsRegistered: enter. [2021-06-23T10:12:54.960Z] [ debug] [dndcp] [4576] CopyPasteDnDWrapper::IsCPRegistered: enter. [2021-06-23T10:12:54.960Z] [ debug] [dndcp] [4576] CopyPasteDnDWrapper::RegisterCP: enter. [2021-06-23T10:12:54.960Z] [ debug] [dndcp] [4576] CopyPasteDnDWrapper::IsCPRegistered: enter. [2021-06-23T10:12:54.960Z] [ debug] [dndcp] [4576] CopyPasteDnDWrapper::SetCPIsRegistered: enter. [2021-06-23T10:12:54.960Z] [ debug] [dndcp] [4576] VMCopyPasteDnDWrapper::GetCPVersion: enter [2021-06-23T10:12:54.960Z] [ debug] [dndcp] [4576] CopyPasteDnDWrapper::IsCPRegistered: enter. [2021-06-23T10:12:54.960Z] [ debug] [dndcp] [4576] VMCopyPasteDnDWrapper::GetCPVersion: got version 4 [2021-06-23T10:12:54.960Z] [ debug] [dndcp] [4576] CopyPaste_SetVersion: enter version 4 [2021-06-23T10:12:54.960Z] [ debug] [dndcp] [4576] CopyPasteUIWin32::VmxCopyPasteVersionChanged: new version is 4 [2021-06-23T10:12:54.960Z] [ debug] [dndcp] [4576] GuestCopyPasteMgr::GuestCopyPasteMgr::VmxCopyPasteVersionChanged: enter version 4 [2021-06-23T10:12:54.960Z] [ debug] [dndcp] [4576] GuestCopyPasteMgr::GuestCopyPasteMgr::VmxCopyPasteVersionChanged: register ping reply changed 4 [2021-06-23T10:12:54.960Z] [ debug] [vmusr] [4576] DnDCPTransportGuestRpc::RegisterRpc: the type 4 is already registered [2021-06-23T10:12:54.960Z] [ debug] [vmusr] [4576] DnDCPTransportGuestRpc::RegisterRpc: for copypaste.transport [2021-06-23T10:12:54.960Z] [ debug] [dndcp] [4576] CopyPasteDnDWrapper::IsCPRegistered: enter. [2021-06-23T10:12:54.960Z] [ debug] [dndcp] [4576] CopyPasteDnDWrapper::RegisterDnD: enter. [2021-06-23T10:12:54.960Z] [ debug] [dndcp] [4576] CopyPasteDnDWin32::RegisterDnD: enter [2021-06-23T10:12:54.960Z] [ debug] [dndcp] [4576] VMCopyPasteDnDWrapper::GetDnDVersion: enter [2021-06-23T10:12:54.960Z] [ debug] [dndcp] [4576] VMCopyPasteDnDWrapper::GetDnDVersion: got version 4 [2021-06-23T10:12:54.960Z] [ debug] [dndcp] [4576] CopyPasteDnDWin32::RegisterDnD: dnd version is 4 [2021-06-23T10:12:54.960Z] [ debug] [dndcp] [4576] GuestDnDMgr::GuestDnDMgr::VmxDnDVersionChanged: enter version 4 [2021-06-23T10:12:54.960Z] [ debug] [vmusr] [4576] DnDCPTransportGuestRpc::RegisterRpc: the type 3 is already registered [2021-06-23T10:12:54.960Z] [ debug] [vmusr] [4576] DnDCPTransportGuestRpc::RegisterRpc: for dnd.transport [2021-06-23T10:12:54.960Z] [ debug] [dndcp] [4576] GuestDnDMgr::ResetDnD: change to state 1, session id 0 [2021-06-23T10:12:54.960Z] [ debug] [dndcp] [4576] CopyPasteDnDWin32::RegisterDnD: dnd is registered? 1 [2021-06-23T10:12:54.960Z] [ debug] [dndcp] [4576] CopyPasteDnDWrapper::IsCPRegistered: enter. [2021-06-23T10:12:54.960Z] [ debug] [dndcp] [4576] VMCopyPasteDnDWrapper::RemoveDnDPluginResetTimer: enter
[2021-06-23T10:12:43.094Z] [ debug] [vmusr] [4576] DnDCPTransportGuestRpc::RegisterRpc: can not find valid cmd for 5, cmdStr NULL disableStr NULL[2021-06-23T10:12:43.094Z] [ debug] [dndcp] [4576] CopyPasteDnDWin32::CreateMessageWnd: enter[2021-06-23T10:12:43.094Z] [ debug] [dndcp] [4576] DnDPluginControlProc: got Window Message 0x81 for hwnd 0x10312[2021-06-23T10:12:43.094Z] [ debug] [dndcp] [4576] DnDPluginControlProc: sending to HandleWindowMessage[2021-06-23T10:12:43.110Z] [ debug] [dndcp] [4576] DnDPluginControlProc: got Window Message 0x83 for hwnd 0x10312[2021-06-23T10:12:43.110Z] [ debug] [dndcp] [4576] DnDPluginControlProc: sending to HandleWindowMessage[2021-06-23T10:12:43.110Z] [ debug] [dndcp] [4576] DnDPluginControlProc: got Window Message 0x1 for hwnd 0x10312[2021-06-23T10:12:43.110Z] [ debug] [dndcp] [4576] DnDPluginControlProc: sending to HandleWindowMessage[2021-06-23T10:12:43.110Z] [ debug] [dndcp] [4576] DnDPluginControlProc: got Window Message 0x5 for hwnd 0x10312[2021-06-23T10:12:43.110Z] [ debug] [dndcp] [4576] DnDPluginControlProc: sending to HandleWindowMessage[2021-06-23T10:12:43.110Z] [ debug] [dndcp] [4576] DnDPluginControlProc: got Window Message 0x3 for hwnd 0x10312[2021-06-23T10:12:43.110Z] [ debug] [dndcp] [4576] DnDPluginControlProc: sending to HandleWindowMessage[2021-06-23T10:12:43.110Z] [ debug] [dndcp] [4576] CopyPasteDnDWin32::CreateMessageWnd: created window 0x10312[2021-06-23T10:12:43.110Z] [ debug] [dndcp] [4576] CDragDetWndTempl<class VMDropTargetImpl>::Create: enter[2021-06-23T10:12:43.116Z] [ debug] [dndcp] [4576] DnDPluginControlProc: got Window Message 0x1c for hwnd 0x10312[2021-06-23T10:12:43.116Z] [ debug] [dndcp] [4576] DnDPluginControlProc: sending to HandleWindowMessage[2021-06-23T10:12:43.148Z] [ debug] [dndcp] [4576] CDragDetWndTempl<class VMDropTargetImpl>::Create: CreateWindowEx: created window 0x10314[2021-06-23T10:12:43.148Z] [ debug] [dndcp] [4576] CDragDetWndTempl<class VMDropTargetImpl>::Create: Layered window is created.[2021-06-23T10:12:43.163Z] [ debug] [dndcp] [4576] CDragDetWndTempl<class VMDropTargetImpl>::Create: Successfully registered DragDetWnd 0x10314[2021-06-23T10:12:43.163Z] [ debug] [dndcp] [4576] CDragDetWndTempl<class VMDropTargetImpl>::Create: enter[2021-06-23T10:12:43.163Z] [ debug] [dndcp] [4576] CDragDetWndTempl<class VMDropTargetImpl>::Create: CreateWindowEx: created window 0x1031c[2021-06-23T10:12:43.179Z] [ debug] [dndcp] [4576] CDragDetWndTempl<class VMDropTargetImpl>::Create: Layered window is created.[2021-06-23T10:12:43.179Z] [ debug] [dndcp] [4576] CDragDetWndTempl<class VMDropTargetImpl>::Create: Successfully registered DragDetWnd 0x1031c[2021-06-23T10:12:43.179Z] [ debug] [dndcp] [4576] DnDUIWin32::UpdateDetWnd: Hiding det window 0x10314[2021-06-23T10:12:43.179Z] [ debug] [dndcp] [4576] DnDPluginControlProc: got Window Message 0x1c for hwnd 0x10312[2021-06-23T10:12:43.179Z] [ debug] [dndcp] [4576] DnDPluginControlProc: sending to HandleWindowMessage[2021-06-23T10:12:43.179Z] [ debug] [dndcp] [4576] DnDUIWin32::UpdateUnityDetWnd: hiding unity detWnd[2021-06-23T10:12:43.179Z] [ debug] [dndcp] [4576] CopyPasteUIWin32::StartFCPThread: Attempting to start FCP thread[2021-06-23T10:12:43.179Z] [ debug] [dndcp] [4576] Pointer_Init:absoluteMouseState:ABSMOUSE_AVAILABLE[2021-06-23T10:12:43.179Z] [ debug] [dndcp] [4576] PointerUpdatePointerLoop: grabbed[2021-06-23T10:12:43.179Z] [ debug] [vmusr] [4576] Unable to open 'C:\Program Files\VMware\VMware Tools\messages\nl\dndCP.vmsg': No such file or directory[2021-06-23T10:12:43.179Z] [ message] [vmusr] [4576] Cannot load message catalog for domain 'dndCP', language 'nl', catalog dir 'C:\Program Files\VMware\VMware Tools'.[2021-06-23T10:12:43.179Z] [ message] [vmtoolsd] [4576] Plugin 'dndCP' initialized.[2021-06-23T10:12:43.179Z] [ debug] [dndcp] [7600] CopyPasteUIWin32::RunFCPThread: Starting guest->host FCP thread loop[2021-06-23T10:12:54.632Z] [ debug] [dndcp] [4576] PointerUpdatePointerLoop: grabbed[2021-06-23T10:12:54.632Z] [ debug] [dndcp] [4576] DnDCPReset: enter[2021-06-23T10:12:54.632Z] [ debug] [dndcp] [4576] CopyPasteDnDWrapper::OnReset: enter.[2021-06-23T10:12:54.632Z] [ debug] [dndcp] [4576] VMCopyPasteDnDWrapper::AddDnDPluginResetTimer: enter[2021-06-23T10:12:54.694Z] [ debug] [dndcp] [4576] DnDPluginControlProc: got Window Message 0x7e for hwnd 0x10312[2021-06-23T10:12:54.694Z] [ debug] [dndcp] [4576] DnDPluginControlProc: sending to HandleWindowMessage[2021-06-23T10:12:54.726Z] [ debug] [dndcp] [4576] DnDPluginControlProc: got Window Message 0x46 for hwnd 0x10312[2021-06-23T10:12:54.726Z] [ debug] [dndcp] [4576] DnDPluginControlProc: sending to HandleWindowMessage[2021-06-23T10:12:54.741Z] [ debug] [dndcp] [4576] DnDCPSetOption: enter option synctime value 1[2021-06-23T10:12:54.741Z] [ debug] [dndcp] [4576] DnDCPSetOption: enter option copypaste value 1[2021-06-23T10:12:54.741Z] [ debug] [vmusr] [4576] DnDCPTransportGuestRpc::RegisterRpc: can not find valid cmd for 5, cmdStr NULL disableStr NULL[2021-06-23T10:12:54.741Z] [ debug] [dndcp] [4576] VMCopyPasteDnDWrapper::OnSetOption: setting option 'copypaste' to '1'[2021-06-23T10:12:54.741Z] [ debug] [dndcp] [4576] CopyPasteDnDWrapper::SetCPIsEnabled: enter.[2021-06-23T10:12:54.741Z] [ debug] [dndcp] [4576] CopyPasteDnDWrapper::IsCPRegistered: enter.[2021-06-23T10:12:54.741Z] [ debug] [dndcp] [4576] CopyPasteDnDWrapper::RegisterCP: enter.[2021-06-23T10:12:54.741Z] [ debug] [dndcp] [4576] CopyPasteDnDWrapper::IsCPRegistered: enter.[2021-06-23T10:12:54.741Z] [ debug] [dndcp] [4576] CopyPasteDnDWrapper::SetCPIsRegistered: enter.[2021-06-23T10:12:54.741Z] [ debug] [dndcp] [4576] VMCopyPasteDnDWrapper::GetCPVersion: enter[2021-06-23T10:12:54.741Z] [ debug] [dndcp] [4576] CopyPasteDnDWrapper::IsCPRegistered: enter.[2021-06-23T10:12:54.741Z] [ debug] [dndcp] [4576] VMCopyPasteDnDWrapper::GetCPVersion: got version 4[2021-06-23T10:12:54.741Z] [ debug] [dndcp] [4576] CopyPaste_SetVersion: enter version 4[2021-06-23T10:12:54.741Z] [ debug] [dndcp] [4576] CopyPasteUIWin32::VmxCopyPasteVersionChanged: new version is 4[2021-06-23T10:12:54.741Z] [ debug] [dndcp] [4576] GuestCopyPasteMgr::GuestCopyPasteMgr::VmxCopyPasteVersionChanged: enter version 4[2021-06-23T10:12:54.741Z] [ debug] [dndcp] [4576] GuestCopyPasteMgr::GuestCopyPasteMgr::VmxCopyPasteVersionChanged: register ping reply changed 4[2021-06-23T10:12:54.741Z] [ debug] [vmusr] [4576] DnDCPTransportGuestRpc::RegisterRpc: for copypaste.transport[2021-06-23T10:12:54.741Z] [ debug] [dndcp] [4576] CopyPasteDnDWrapper::IsCPRegistered: enter.[2021-06-23T10:12:54.741Z] [ debug] [dndcp] [4576] DnDCPSetOption: enter option autohide value 0[2021-06-23T10:12:54.741Z] [ debug] [dndcp] [4576] DnDCPSetOption: enter option broadcastIP value 1[2021-06-23T10:12:54.741Z] [ debug] [dndcp] [4576] DnDCPSetOption: enter option enableDnD value 1[2021-06-23T10:12:54.741Z] [ debug] [vmusr] [4576] DnDCPTransportGuestRpc::RegisterRpc: can not find valid cmd for 5, cmdStr NULL disableStr NULL[2021-06-23T10:12:54.741Z] [ debug] [dndcp] [4576] VMCopyPasteDnDWrapper::OnSetOption: setting option 'enableDnD' to '1'[2021-06-23T10:12:54.741Z] [ debug] [dndcp] [4576] CopyPasteDnDWrapper::SetDnDIsEnabled: enter.[2021-06-23T10:12:54.741Z] [ debug] [dndcp] [4576] CopyPasteDnDWrapper::RegisterDnD: enter.[2021-06-23T10:12:54.741Z] [ debug] [dndcp] [4576] CopyPasteDnDWin32::RegisterDnD: enter[2021-06-23T10:12:54.741Z] [ debug] [dndcp] [4576] VMCopyPasteDnDWrapper::GetDnDVersion: enter[2021-06-23T10:12:54.741Z] [ debug] [dndcp] [4576] VMCopyPasteDnDWrapper::GetDnDVersion: got version 4[2021-06-23T10:12:54.741Z] [ debug] [dndcp] [4576] CopyPasteDnDWin32::RegisterDnD: dnd version is 4[2021-06-23T10:12:54.741Z] [ debug] [dndcp] [4576] GuestDnDMgr::GuestDnDMgr::VmxDnDVersionChanged: enter version 4[2021-06-23T10:12:54.741Z] [ debug] [vmusr] [4576] DnDCPTransportGuestRpc::RegisterRpc: for dnd.transport[2021-06-23T10:12:54.741Z] [ debug] [dndcp] [4576] GuestDnDMgr::ResetDnD: change to state 1, session id 0[2021-06-23T10:12:54.741Z] [ debug] [dndcp] [4576] CopyPasteDnDWin32::RegisterDnD: dnd is registered? 1[2021-06-23T10:12:54.741Z] [ debug] [dndcp] [4576] DnDCPSetOption: enter option synctime.period value 0[2021-06-23T10:12:54.741Z] [ debug] [dndcp] [4576] DnDCPSetOption: enter option time.synchronize.tools.enable value 1[2021-06-23T10:12:54.741Z] [ debug] [dndcp] [4576] DnDCPSetOption: enter option time.synchronize.guest.resync value 0[2021-06-23T10:12:54.741Z] [ debug] [dndcp] [4576] DnDCPSetOption: enter option time.synchronize.guest.resync.timeout value 0[2021-06-23T10:12:54.741Z] [ debug] [dndcp] [4576] DnDCPSetOption: enter option time.synchronize.tools.startup.backward value 0[2021-06-23T10:12:54.741Z] [ debug] [dndcp] [4576] DnDCPSetOption: enter option time.synchronize.tools.startup value 1[2021-06-23T10:12:54.741Z] [ debug] [dndcp] [4576] DnDCPSetOption: enter option toolScripts.afterPowerOn value 1[2021-06-23T10:12:54.741Z] [ debug] [dndcp] [4576] DnDCPSetOption: enter option toolScripts.beforePowerOff value 1[2021-06-23T10:12:54.741Z] [ debug] [dndcp] [4576] DnDCPSetOption: enter option toolScripts.afterResume value 1[2021-06-23T10:12:54.757Z] [ debug] [dndcp] [4576] DnDCPSetOption: enter option toolScripts.beforeSuspend value 1[2021-06-23T10:12:54.757Z] [ debug] [dndcp] [4576] DnDCPSetOption: enter option time.synchronize.tools.slewCorrection value 1[2021-06-23T10:12:54.757Z] [ debug] [dndcp] [4576] DnDCPSetOption: enter option time.synchronize.tools.percentCorrection value 0[2021-06-23T10:12:54.757Z] [ debug] [dndcp] [4576] DnDCPSetOption: enter option mapRootHgfsShare value 1[2021-06-23T10:12:54.757Z] [ debug] [dndcp] [4576] DnDCPSetOption: enter option linkRootHgfsShare value 1[2021-06-23T10:12:54.757Z] [ debug] [dndcp] [4576] DnDCPSetOption: enter option enableMessageBusTunnel value 0[2021-06-23T10:12:54.757Z] [ debug] [dndcp] [4576] DnDCPSetOption: enter option enableAppInfo value 1[2021-06-23T10:12:54.757Z] [ debug] [dndcp] [4576] DnDCPCapabilities: enter[2021-06-23T10:12:54.757Z] [ debug] [dndcp] [4576] VMCopyPasteDnDWrapper::OnCapReg: enter[2021-06-23T10:12:54.757Z] [ debug] [dndcp] [4576] VMCopyPasteDnDWrapper::GetDnDVersion: enter[2021-06-23T10:12:54.757Z] [ debug] [dndcp] [4576] VMCopyPasteDnDWrapper::GetDnDVersion: got version 4[2021-06-23T10:12:54.757Z] [ debug] [dndcp] [4576] VMCopyPasteDnDWrapper::OnCapReg: VMX is dnd version 4[2021-06-23T10:12:54.757Z] [ debug] [dndcp] [4576] VMCopyPasteDnDWrapper::GetCPVersion: enter[2021-06-23T10:12:54.757Z] [ debug] [dndcp] [4576] CopyPasteDnDWrapper::IsCPRegistered: enter.[2021-06-23T10:12:54.757Z] [ debug] [dndcp] [4576] VMCopyPasteDnDWrapper::GetCPVersion: got version 4[2021-06-23T10:12:54.757Z] [ debug] [dndcp] [4576] VMCopyPasteDnDWrapper::OnCapReg: VMX is copypaste version 4[2021-06-23T10:12:54.788Z] [ debug] [vmusr] [4576] CopyPasteRpcV4::HandleMsg: Got DNDCP_CMD_PING_REPLY[2], sessionId 0, srcId 10001, binary size 0.[2021-06-23T10:12:54.788Z] [ debug] [dndcp] [4576] GuestCopyPasteMgr::OnPingReply: copypaste ping reply caps are 1555[2021-06-23T10:12:54.804Z] [ debug] [vmusr] [4576] DnDRpcV4::HandleMsg: Got DNDCP_CMD_PING_REPLY[2], sessionId 0, srcId 10001, binary size 0.[2021-06-23T10:12:54.804Z] [ debug] [dndcp] [4576] GuestDnDMgr::OnPingReply: dnd ping reply caps are aab[2021-06-23T10:12:54.897Z] [ debug] [dndcp] [4576] DnDPluginControlProc: got Window Message 0x7e for hwnd 0x10312[2021-06-23T10:12:54.897Z] [ debug] [dndcp] [4576] DnDPluginControlProc: sending to HandleWindowMessage[2021-06-23T10:12:54.960Z] [ debug] [dndcp] [4576] DnDPluginControlProc: got Window Message 0x46 for hwnd 0x10312[2021-06-23T10:12:54.960Z] [ debug] [dndcp] [4576] DnDPluginControlProc: sending to HandleWindowMessage[2021-06-23T10:12:54.960Z] [ debug] [dndcp] [4576] DnDPluginResetSent: enter[2021-06-23T10:12:54.960Z] [ debug] [dndcp] [4576] VMCopyPasteDnDWrapper::OnResetInternal: enter[2021-06-23T10:12:54.960Z] [ debug] [dndcp] [4576] CopyPasteDnDWrapper::UnregisterDnD: enter.[2021-06-23T10:12:54.960Z] [ debug] [dndcp] [4576] CopyPasteDnDWin32::UnregisterDnD: enter[2021-06-23T10:12:54.960Z] [ debug] [dndcp] [4576] DnDUIWin32::Reset: entering[2021-06-23T10:12:54.960Z] [ debug] [dndcp] [4576] GuestDnDMgr::ResetDnD: change to state 1, session id 0[2021-06-23T10:12:54.960Z] [ debug] [dndcp] [4576] CopyPasteDnDWrapper::IsCPRegistered: enter.[2021-06-23T10:12:54.960Z] [ debug] [dndcp] [4576] CopyPasteDnDWrapper::UnregisterCP: enter.[2021-06-23T10:12:54.960Z] [ debug] [dndcp] [4576] CopyPasteDnDWrapper::IsCPRegistered: enter.[2021-06-23T10:12:54.960Z] [ debug] [dndcp] [4576] VMCopyPasteDnDWrapper::GetCPVersion: enter[2021-06-23T10:12:54.960Z] [ debug] [dndcp] [4576] CopyPasteDnDWrapper::IsCPRegistered: enter.[2021-06-23T10:12:54.960Z] [ debug] [dndcp] [4576] VMCopyPasteDnDWrapper::GetCPVersion: got version 4[2021-06-23T10:12:54.960Z] [ debug] [dndcp] [4576] CopyPasteUIWin32::Reset: entering[2021-06-23T10:12:54.960Z] [ debug] [dndcp] [4576] CopyPasteDnDWrapper::SetCPIsRegistered: enter.[2021-06-23T10:12:54.960Z] [ debug] [dndcp] [4576] CopyPasteDnDWrapper::IsCPRegistered: enter.[2021-06-23T10:12:54.960Z] [ debug] [dndcp] [4576] CopyPasteDnDWrapper::RegisterCP: enter.[2021-06-23T10:12:54.960Z] [ debug] [dndcp] [4576] CopyPasteDnDWrapper::IsCPRegistered: enter.[2021-06-23T10:12:54.960Z] [ debug] [dndcp] [4576] CopyPasteDnDWrapper::SetCPIsRegistered: enter.[2021-06-23T10:12:54.960Z] [ debug] [dndcp] [4576] VMCopyPasteDnDWrapper::GetCPVersion: enter[2021-06-23T10:12:54.960Z] [ debug] [dndcp] [4576] CopyPasteDnDWrapper::IsCPRegistered: enter.[2021-06-23T10:12:54.960Z] [ debug] [dndcp] [4576] VMCopyPasteDnDWrapper::GetCPVersion: got version 4[2021-06-23T10:12:54.960Z] [ debug] [dndcp] [4576] CopyPaste_SetVersion: enter version 4[2021-06-23T10:12:54.960Z] [ debug] [dndcp] [4576] CopyPasteUIWin32::VmxCopyPasteVersionChanged: new version is 4[2021-06-23T10:12:54.960Z] [ debug] [dndcp] [4576] GuestCopyPasteMgr::GuestCopyPasteMgr::VmxCopyPasteVersionChanged: enter version 4[2021-06-23T10:12:54.960Z] [ debug] [dndcp] [4576] GuestCopyPasteMgr::GuestCopyPasteMgr::VmxCopyPasteVersionChanged: register ping reply changed 4[2021-06-23T10:12:54.960Z] [ debug] [vmusr] [4576] DnDCPTransportGuestRpc::RegisterRpc: the type 4 is already registered[2021-06-23T10:12:54.960Z] [ debug] [vmusr] [4576] DnDCPTransportGuestRpc::RegisterRpc: for copypaste.transport[2021-06-23T10:12:54.960Z] [ debug] [dndcp] [4576] CopyPasteDnDWrapper::IsCPRegistered: enter.[2021-06-23T10:12:54.960Z] [ debug] [dndcp] [4576] CopyPasteDnDWrapper::RegisterDnD: enter.[2021-06-23T10:12:54.960Z] [ debug] [dndcp] [4576] CopyPasteDnDWin32::RegisterDnD: enter[2021-06-23T10:12:54.960Z] [ debug] [dndcp] [4576] VMCopyPasteDnDWrapper::GetDnDVersion: enter[2021-06-23T10:12:54.960Z] [ debug] [dndcp] [4576] VMCopyPasteDnDWrapper::GetDnDVersion: got version 4[2021-06-23T10:12:54.960Z] [ debug] [dndcp] [4576] CopyPasteDnDWin32::RegisterDnD: dnd version is 4[2021-06-23T10:12:54.960Z] [ debug] [dndcp] [4576] GuestDnDMgr::GuestDnDMgr::VmxDnDVersionChanged: enter version 4[2021-06-23T10:12:54.960Z] [ debug] [vmusr] [4576] DnDCPTransportGuestRpc::RegisterRpc: the type 3 is already registered[2021-06-23T10:12:54.960Z] [ debug] [vmusr] [4576] DnDCPTransportGuestRpc::RegisterRpc: for dnd.transport[2021-06-23T10:12:54.960Z] [ debug] [dndcp] [4576] GuestDnDMgr::ResetDnD: change to state 1, session id 0[2021-06-23T10:12:54.960Z] [ debug] [dndcp] [4576] CopyPasteDnDWin32::RegisterDnD: dnd is registered? 1[2021-06-23T10:12:54.960Z] [ debug] [dndcp] [4576] CopyPasteDnDWrapper::IsCPRegistered: enter.[2021-06-23T10:12:54.960Z] [ debug] [dndcp] [4576] VMCopyPasteDnDWrapper::RemoveDnDPluginResetTimer: enter