VMware Communities > VMTN > Desktop Products > VMware Fusion > Discussions

This Question is Possibly Answered

1 "correct" answer available (10 pts) 2 "helpful" answers available (6 pts)
1 2 3 4 Previous Next
Reply Re: vmwareuser.exe cpu 100% Sep 24, 2008 12:51 PM
in response to: R1ch
Click to view reesd27's profile Novice reesd27 20 posts since
Sep 23, 2008
I have been seeing this same problem. Unfortunately remove/install of tools didn't seem to fix it.

It happens around drag and drop. I also have seen it when launching an URL from inside the guest to Firefox in the host. I also have seen it when I am just working in Firefox in the host, so no direct guest usage that I know of.

I am also a Parallels Desktop import, so perhaps there are is some common importer problem we are seeing.

It is usually accompanied with a lot of disk access and with the following lines over and over again in the log:
Sep 24 14:26:50.256: vcpu-0| VMXVmdbGuestBinaryHandlersCB: failed: ret = VMDB failure
Sep 24 14:26:50.257: vcpu-0| VMXVmdbGuestBinaryHandlersCB: failed: ret = VMDB failure
Sep 24 14:26:50.256: vcpu-0| TOOLS call to unity.get.binary.handlers failed.

I also am seeing this line a fair amount:
Sep 24 15:15:48.816: vmx| VMXVmdbCbUnityCmd: failed: ret = Schema path not found


I just tried removing and reinstalling vmware tools. Unfortunately it didn't seem to solve the problem. When I start the guest I see hundreds of the "VMXVmdbGuestBinaryHandlersCB failed" lines in the log. Also, after an hour or so of use I ran into this issue again starting with these log entries
Sep 24 15:40:26.818: vmx| GuestRpcSendTimedOut: message to toolbox-dnd timed out.
Sep 24 15:40:27.502: vmx| GuestRpcSendTimedOut: message to toolbox-dnd timed out.
Sep 24 15:40:38.996: vmx| GuestRpcSendTimedOut: message to toolbox-dnd timed out.
Sep 24 15:40:39.097: vmx| GuestRpcSendTimedOut: message to toolbox-dnd timed out.
Sep 24 15:40:39.098: vmx| VMXVmdbCbUnityCmd: failed: ret = Schema path not found
Sep 24 15:40:39.161: vmx| VMXVmdbCbUnityCmd: failed: ret = Schema path not found
Sep 24 15:40:39.225: vmx| VMXVmdbCbUnityCmd: failed: ret = Schema path not found
Sep 24 15:40:47.144: vcpu-0| VMXVmdbGuestBinaryInfoCB: Could not get name


As a final note, initially (after initial import and before my remove/install of vmware tools) my menu choice was "Install VMware Tools" rather than "Update VMWare Tools", which makes me think the importer did miss something. After remove/install my menu option is now "update vmware tools".

My logs are attached.

Thanks,
d
Attachments:
Reply Re: vmwareuser.exe cpu 100% Sep 24, 2008 10:17 PM
in response to: Matt Stevens
Click to view Matt Stevens's profile Novice Matt Stevens 9 posts since
Sep 3, 2008
Ok, it just happened again here and I attached WinDbg to the process to look up the call stack for the thread eating up the CPU.

0:009> !runaway
User Mode Time
Thread Time
8:ce4 0 days 0:05:25.908
0:114 0 days 0:00:04.726
4:a18 0 days 0:00:00.010
9:c58 0 days 0:00:00.000
7:c00 0 days 0:00:00.000
6:4ac 0 days 0:00:00.000
5:79c 0 days 0:00:00.000
3:394 0 days 0:00:00.000
2:168 0 days 0:00:00.000
1:164 0 days 0:00:00.000

0:008> kb
ChildEBP RetAddr Args to Child
0212fd30 7e4193e9 7e4193a8 0212fdb0 00000000 ntdll!KiFastSystemCallRet
0212fd5c 7e419402 0212fdb0 00000000 00000200 USER32!NtUserPeekMessage+0xc
0212fd88 775d06f6 0212fdb0 00000000 00000200 USER32!PeekMessageW+0xbc
0212fdcc 775d0be4 00e0b8f0 00d24200 00000003 ole32!CDragOperation::CDragOperation+0xfe
0212fe34 0040c07d 00e0b8f0 00d24200 00000003 ole32!DoDragDrop+0x77
WARNING: Stack unwind information not available. Following frames may be wrong.
0212ff6c 0040c22b 0212ffac 781329bb 00d1f978 VMwareUser+0xc07d
0212ff74 781329bb 00d1f978 f76278a5 00000000 VMwareUser+0xc22b
0212ffac 78132a47 00164098 7c80b713 00de01c0 MSVCR80!endthreadex+0x3b
0212ffec 00000000 781329e1 00de01c0 00000000 MSVCR80!endthreadex+0xc7


Since I've got the process in the problem state I'll leave the debugger attached at a breakpoint for a couple days in case you want me to run this again with symbols or pull any other information.
Reply Re: vmwareuser.exe cpu 100% Sep 25, 2008 7:30 AM
in response to: Matt Stevens
Click to view awk's profile Enthusiast awk 47 posts since
Jul 30, 2008
VMware

Matt,

The stack trace 'appears' (though this can sometimes be misleading) to point to the drag and drop code - had you recently performed a drag-n-drop operation ? Could you also do a '.dump ma <file>' and let us know how big the resultant dump is and then we can work out the most efficient way to get it to us ?

Thanks very much for your help.

Andrew 8-)


Reply Re: vmwareuser.exe cpu 100% Sep 25, 2008 8:57 AM
in response to: awk
Click to view R1ch's profile Lurker R1ch 2 posts since
Sep 24, 2008
Drag and drop was the culprit for my 100% vmwareuser.exe.

Someone else mentioned it - so I should add that a share had be recreated during the upgrade.

I deleted it as well as doing the reinstall.

may not be significant but might help...

R1ch
Reply Re: vmwareuser.exe cpu 100% Sep 25, 2008 9:22 AM
in response to: awk
Click to view Matt Stevens's profile Novice Matt Stevens 9 posts since
Sep 3, 2008
Andrew,

I'm still not exactly sure what triggered the problem, but here are the events leading up to the current state:

1. VM window was not in focus.
2. Dragged & dropped a file from Finder to another Mac application, it passed over a portion of the background VM window in the process, which did start acting on it.
3. Brought the VM window into focus a few minutes later.
4. Connected VPN client and launched Outlook.

This was the point where I noticed the CPU spike. Based on this and R1ch's experience it does seem to indicate a drag & drop issue. Here's the dump file:

http://codeworkshop.net/files/vmwareuser.zip

Matt
Reply Re: vmwareuser.exe cpu 100% Sep 25, 2008 10:01 AM
in response to: Matt Stevens
Click to view awk's profile Enthusiast awk 47 posts since
Jul 30, 2008
VMware

Thanks very much for the dump Matt, I've grabbed it and we'll start looking it over and see what it reveals.

Andrew 8-)


Reply Re: vmwareuser.exe cpu 100% Sep 25, 2008 3:04 PM
in response to: Matt Stevens
Click to view topgunli's profile Enthusiast topgunli 41 posts since
Oct 24, 2005
VMware
Thanks very much for your feedback and dump files. Now I can reproduce the bug. What happened is that when you drag something into guest, we will start a DnD thread in guest, but it may take time. And when you drag it out, we will cancel the DnD. But if you drag out right after drag in, guest DnD thread may be not up yet, so will miss the cancel signal. And unfortunately, DnD thread is very expensive. Actually there is a workaround. If you got into this bad case again, just drag a file into the VM and stay inside for a while, and drag out again. This will reset our DnD status and kill the DnD thread. Let me know if the workaround works or not. This will help me to know if it is the cause of the bad situation.
Reply Re: vmwareuser.exe cpu 100% Sep 25, 2008 4:48 PM
in response to: topgunli
Click to view reesd27's profile Novice reesd27 20 posts since
Sep 23, 2008
I am seeing this problem at drag and drop, but also when the guest starts. I am hoping its part of the same problem so I am looking forward to the fix. I'll add the Mac seems to sometimes treat whole window moves as "drags" so maybe its happening during some window moving/resizing (vs. dragging from inside a window).

Is it possible to just turn drag and drop off for now?

Thanks,

d
Reply Re: vmwareuser.exe cpu 100% Sep 29, 2008 7:09 AM
in response to: reesd27
Click to view reesd27's profile Novice reesd27 20 posts since
Sep 23, 2008
Actually, I am not sure I am seeing the exact same problem, though it may be related. To recap what I am seeing:

Every few hours in Unity (and less often when not in Unity) windows within the guest become non-responsive. This is coupled with a bunch of messages in the log like the following:
Sep 29 08:54:10.854: vcpu-0| TOOLS call to unity.get.binary.handlers failed.
Sep 29 08:54:10.854: vcpu-0| VMXVmdbGuestBinaryHandlersCB: failed: ret = VMDB failure
Sep 29 08:54:10.854: vcpu-0| VMXVmdbGuestBinaryHandlersCB: failed: ret = VMDB failure
Sep 29 08:54:22.697: vmx| VMXVmdbCbUnityCmd: failed: ret = Schema path not found
Sep 29 08:54:55.863: vmx| VMXVmdbCbUnityCmd: failed: ret = Schema path not found
Sep 29 08:54:59.025: vmx| VMXVmdbCbUnityCmd: failed: ret = Schema path not found
Sep 29 08:55:54.430: vmx| VMXVmdbCbUnityCmd: failed: ret = Schema path not found
Sep 29 08:55:57.313: vmx| VMXVmdbCbUnityCmd: failed: ret = Schema path not found
Sep 29 08:56:02.575: vmx| GuestRpcSendTimedOut: message to toolbox-dnd timed out.
Sep 29 08:56:05.451: vmx| GuestRpcSendTimedOut: message to toolbox-dnd timed out.
...
Sep 29 09:00:01.455: vmx| GuestRpcSendTimedOut: message to toolbox-dnd timed out.
Sep 29 09:00:08.993: vmx| GuestRpcSendTimedOut: message to toolbox-dnd timed out.
Sep 29 09:00:13.556: vmx| GuestRpcSendTimedOut: message to toolbox-dnd timed out.
Sep 29 09:00:20.319: vmx| VMXVmdbCbUnityCmd: failed: ret = Schema path not found
Sep 29 09:00:24.120: vmx| VMXVmdbCbUnityCmd: failed: ret = Schema path not found
Sep 29 09:00:28.490: vmx| GuestRpcSendTimedOut: message to toolbox-dnd timed out.
Sep 29 09:00:28.555: vmx| GuestRpcSendTimedOut: message to toolbox-dnd timed out.
Sep 29 09:00:29.043: vmx| GuestRpcSendTimedOut: message to toolbox-dnd timed out.

I am also seeing this when the guest starts up in the vmware-vmfusion.log:

Sep 29 09:28:16.061: vmfusion| Failed to get guest app info: VMDB failure
Sep 29 09:28:37.373: vmfusion| Failed to get guest app info: VMDB failure
Sep 29 09:29:24.317: vmfusion| Failed to get guest app info: VMDB failure


The vmwareuser.exe sometimes spikes, but not always to 100%. Though its hard to say because really the whole guest becomes non-responsive for a bit and then catches up.

I have tried uninstalling and reinstalling tools with no improvement. It may be worth noting that even with tools uninstalled I still get the "vmx| GuestRpcSendTimedOut: message to toolbox-dnd timed out." lines in he vmware.log.
As I mentioned above this is a parallels import, so it may have something to do with the import.

I've attached my latest vmx and logs. Hopefully we can find a fix for this because when this isn't happening I am enjoying vmware's responsiveness and the dual-monitor support, but if we don't get it figured out before my trial expires I will have to go back to parallels since I am not having these lockup problems with it.

Thanks,
d
Attachments:
Reply Re: vmwareuser.exe cpu 100% Sep 29, 2008 6:33 AM
in response to: topgunli
Click to view JeFurry's profile Novice JeFurry 8 posts since
Aug 7, 2008
topgunli wrote:
Actually there is a workaround. If you got into this bad case again, just drag a file into the VM and stay inside for a while, and drag out again. This will reset our DnD status and kill the DnD thread. Let me know if the workaround works or not. This will help me to know if it is the cause of the bad situation.

In my case, the DnD procedure above made things much worse. The (supposedly idling) VM was taking 138% of a core on the host's CPU (a 2-CPU VM on an 8-core Mac Pro), so I dragged an icon on to the VM's Explorer window, waited a few seconds, then dragged it out again. I hoped that the CPU usage would drop back to the normal ~7%, but instead it went up to 204%. The dragged icon left a ghost image which didn't clear for several minutes, and a shutdown of the VM took over 15 minutes.

I tried the same procedure, this time waiting longer before dragging out the file (which was a PNG image, ~2.4MB, and I did not actually drop it in the VM, just dragged it over and back out) and the same thing happened.

If this is making it worse, it suggests that although this is a problem, there is another one...

-Jef.
Reply Re: vmwareuser.exe cpu 100% Sep 29, 2008 8:51 AM
in response to: reesd27
Click to view reesd27's profile Novice reesd27 20 posts since
Sep 23, 2008
To help isolate the problem I created a new image from scratch using default settings. It seems to have similar issues making me think the issue is at the host level rather than the guest level. I've attached its logs as well. Generally I continue to see errors like the following in the log:
Sep 29 11:41:31.884: vcpu-0| VMXVmdbGuestBinaryHandlersCB: failed: ret = VMDB failure
Sep 29 11:41:31.885: vcpu-0| VMXVmdbGuestBinaryHandlersCB: failed: ret = VMDB failure
Sep 29 11:41:31.946: vcpu-0| TOOLS call to unity.get.binary.handlers failed.

Thanks,
d
Attachments:
Reply Re: vmwareuser.exe cpu 100% Sep 29, 2008 12:21 PM
in response to: reesd27
Click to view topgunli's profile Enthusiast topgunli 41 posts since
Oct 24, 2005
VMware

Thanks for all the information. I guess maybe VMwareUser inside the guest un-registered all callbacks. Can you follow the instruction to collect guest log?

Copied from arolett's comment:

To do that go to:
C:\Documents and Settings\All Users\Application Data\VMware\VMware Tools, Then open the tools.conf file.

Add two entries:
log = "TRUE"
log.file = "C:\vmtools.log"

Then log out and log back in to your VM Wait for vmwareuser.exe to start using 100% of your cpu and upload the resulting log
files. There should be one at c:\vmtools.log and possibly more than one
at c:\vmtools.log.<process id> where process id is just a number.

Once you are done, make sure to turn off logging by removing the two entries because they can take up a lot of space over time.

Reply Re: vmwareuser.exe cpu 100% Sep 29, 2008 6:18 PM
in response to: topgunli
Click to view reesd27's profile Novice reesd27 20 posts since
Sep 23, 2008
topgunli wrote:

Thanks for all the information. I guess maybe VMwareUser inside the guest un-registered all callbacks. Can you follow the instruction to collect guest log?

Thanks, here are the guest logs zipped with the other logs. I hadn't realized this was a different log (the guest log) or I would have included it sooner.
Thanks,
d
Attachments:
Reply Re: vmwareuser.exe cpu 100% Sep 30, 2008 7:23 AM
in response to: reesd27
Click to view reesd27's profile Novice reesd27 20 posts since
Sep 23, 2008
Another issue I am seeing is that saving a 200K file to a shared folder can take several seconds. During that time the CPU of the process I am saving from (e.g. notepad.exe) spikes to 100%. Its happening across a variety of applications - notepad, wordpad, natura bonsai, so it seems to be file system related.
Thanks,
dave
Reply Re: vmwareuser.exe cpu 100% Sep 30, 2008 12:05 PM
in response to: reesd27
Click to view topgunli's profile Enthusiast topgunli 41 posts since
Oct 24, 2005
VMware
reesd27 wrote:
Another issue I am seeing is that saving a 200K file to a shared folder can take several seconds. During that time the CPU of the process I am saving from (e.g. notepad.exe) spikes to 100%. Its happening across a variety of applications - notepad, wordpad, natura bonsai, so it seems to be file system related.
Thanks,
dave
How long it will take to save the file to virtual disk? Also looks like your setup is not very powerful. Can you provide more information about your setup? Mac Pro or Mac Book? How big is memory? Also what is your guest OS? XP?
1 2 3 4 Previous Next
Actions