VMware Communities
JanGils
Contributor
Contributor

Copy-paste not working between guest and host

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

 

Labels (1)
0 Kudos
0 Replies