Extremely slow startup for first VM loaded

shawn_ · ‎02-20-2007

I'm running VMWare Player 1.0.3 on Vista with a fast computer (3.2GHz Core 2 Du with 2GB of RAM). After I boot my Vista machine and then load my vm it takes close to 3 minutes to even get to the "restoring VM state" screen. After which the VM loads quickly and is very resposive. If I shutdown the vm completely and then reload it it loads in under 5 seconds. Here is my log file for the slow start up. You can see the first slowdown is during the WSSCAN.

Feb 20 01:41:26: vmx| Log for VMware Player pid=5456 version=1.0.3 build=build-34682 option=Release

Feb 20 01:41:26: vmx| Command line: "C:\Program Files\VMware\VMware Player\bin\vmware-vmx.exe" "-@" "pipe=
.\pipe\vmxe427432e126fbd5e; vm=e427432e126fbd5e" "C:\Virtual Machines\Windows2003Base\winnetenterprise.vmx"

Feb 20 01:41:26: vmx| UI Connecting to pipe '
.\pipe\vmxe427432e126fbd5e' with user '(null)'

Feb 20 01:41:27: vmx| VMMon: failed memory configuration

Feb 20 01:41:27: vmx| CPU #0 TSC = 413998151960

Feb 20 01:41:27: vmx| CPU #1 TSC = 413998151925

Feb 20 01:41:27: vmx| TSC delta 35

Feb 20 01:41:27: vmx| VMMon_GetkHzEstimate: Calculated 3219999 kHz

Feb 20 01:41:27: vmx| Measured CPU as 3219999 kHz, but OS says 700000 kHz; using 3219999

Feb 20 01:41:27: vmx| Msg_Hint: msg.hostWin32.cpuSpeedMismatch (shown)

Feb 20 01:41:27: vmx| cpuids[0].id81.ecx = 0x1

Feb 20 01:41:27: vmx| cpuids[1].id81.ecx = 0x1

Feb 20 01:41:27: vmx| pcpu #0 CPUID numEntries=10 GenuntelineI

Feb 20 01:41:27: vmx| pcpu #0 CPUID version=0x6f6 id1.edx=0xbfebfbff id1.ecx=0xe3bd id1.ebx=0x20800

Feb 20 01:41:27: vmx| pcpu #0 CPUID id80.eax=80000008 id81.edx=0x20100000 id81.ecx=0x1

Feb 20 01:41:27: vmx| pcpu #1 CPUID numEntries=10 GenuntelineI

Feb 20 01:41:27: vmx| pcpu #1 CPUID version=0x6f6 id1.edx=0xbfebfbff id1.ecx=0xe3bd id1.ebx=0x1020800

Feb 20 01:41:27: vmx| pcpu #1 CPUID id80.eax=80000008 id81.edx=0x20100000 id81.ecx=0x1

Feb 20 01:41:27: vmx| CPUID id1.edx: 0xbfebfbff id1.ecx: 0xe3bd id81.edx: 0x20100000 id81.ecx: 0x1

Feb 20 01:41:27: vmx| CPUID id88.ecx: 0 id88.edx: 0

Feb 20 01:41:27: vmx| ACL_InitCapabilities: here 1 (bug 63252)

Feb 20 01:41:27: vmx| changing directory to C:\Virtual Machines\Windows2003Base\.

Feb 20 01:41:27: vmx| Config file: C:\Virtual Machines\Windows2003Base\winnetenterprise.vmx

Feb 20 01:41:27: vmx| VMXVmdbCbVmVmxExecState: Exec state change requested to state poweredOn without reset

Feb 20 01:41:27: vmx| TOOLS delaying state change request to state 4

Feb 20 01:41:27: vmx| PowerOn

Feb 20 01:41:27: vmx| Host: WIN32 highest NUMA node 0

Feb 20 01:41:27: vmx| Host: WIN32 NUMA node 0, CPU mask 0x0000000000000003

Feb 20 01:41:27: vmx| Resuming virtual machine from C:\Virtual Machines\Windows2003Base\winnetenterprise.vmss

Feb 20 01:41:27: vmx| DUMPER: restoring checkpoint version 8

Feb 20 01:41:27: vmx| HOST Windows version 6.0, build 6000, platform 2, "", SMP

Feb 20 01:41:27: vmx| DICT --- USER PREFERENCES

Feb 20 01:41:27: vmx| DICT pref.autoFitFullScreen = fitHostToGuest

Feb 20 01:41:27: vmx| DICT pref.view.navBar.type = favorites

Feb 20 01:41:27: vmx| DICT pref.view.toolbars.power.items.count = 7

Feb 20 01:41:27: vmx| DICT pref.view.toolbars.power.item0.id = POWER_OFF

Feb 20 01:41:27: vmx| DICT pref.view.toolbars.power.item1.id = SUSPEND

Feb 20 01:41:27: vmx| DICT pref.view.toolbars.power.item2.id = POWER_ON

Feb 20 01:41:27: vmx| DICT pref.view.toolbars.power.item3.id = RESET

Feb 20 01:41:27: vmx| DICT pref.view.toolbars.power.item4.id = \_SEP_

Feb 20 01:41:27: vmx| DICT pref.view.toolbars.power.item5.id = PREVIEW_PLAYER

Feb 20 01:41:27: vmx| DICT pref.view.toolbars.power.item6.id = PREVIEW_WS

Feb 20 01:41:27: vmx| DICT pref.mruDest0.present = FALSE

Feb 20 01:41:27: vmx| DICT pref.mruDest0.destString =

Feb 20 01:41:27: vmx| DICT pref.mruDest0.user =

Feb 20 01:41:27: vmx| DICT pref.mruDest1.present = FALSE

Feb 20 01:41:27: vmx| DICT pref.mruDest1.destString =

Feb 20 01:41:27: vmx| DICT pref.mruDest1.user =

Feb 20 01:41:27: vmx| DICT pref.mruDest2.present = FALSE

Feb 20 01:41:27: vmx| DICT pref.mruDest2.destString =

Feb 20 01:41:27: vmx| DICT pref.mruDest2.user =

Feb 20 01:41:27: vmx| DICT pref.mruDest3.present = FALSE

Feb 20 01:41:27: vmx| DICT pref.mruDest3.destString =

Feb 20 01:41:27: vmx| DICT pref.mruDest3.user =

Feb 20 01:41:27: vmx| DICT pref.mruDest4.present = FALSE

Feb 20 01:41:27: vmx| DICT pref.mruDest4.destString =

Feb 20 01:41:27: vmx| DICT pref.mruDest4.user =

Feb 20 01:41:27: vmx| DICT pref.mruDest5.present = FALSE

Feb 20 01:41:27: vmx| DICT pref.mruDest5.destString =

Feb 20 01:41:27: vmx| DICT pref.mruDest5.user =

Feb 20 01:41:27: vmx| DICT pref.mruDest6.present = FALSE

Feb 20 01:41:27: vmx| DICT pref.mruDest6.destString =

Feb 20 01:41:27: vmx| DICT pref.mruDest6.user =

Feb 20 01:41:27: vmx| DICT pref.mruDest7.present = FALSE

Feb 20 01:41:27: vmx| DICT pref.mruDest7.destString =

Feb 20 01:41:27: vmx| DICT pref.mruDest7.user =

Feb 20 01:41:27: vmx| DICT webUpdate.checkPeriod = weekly

Feb 20 01:41:27: vmx| DICT pref.view.toolbars.proj.items.count = 4

Feb 20 01:41:27: vmx| DICT pref.view.toolbars.proj.item0.id = ADD_VM

Feb 20 01:41:27: vmx| DICT pref.view.toolbars.proj.item1.id = POLICIES

Feb 20 01:41:27: vmx| DICT pref.view.toolbars.proj.item2.id = PKG_NEW

Feb 20 01:41:27: vmx| DICT pref.view.toolbars.proj.item3.id = EDIT_SETTINGS

Feb 20 01:41:27: vmx| DICT pref.view.toolbars.snapshot.items.count = 3

Feb 20 01:41:27: vmx| DICT pref.view.toolbars.snapshot.item0.id = SNAPSHOT

Feb 20 01:41:27: vmx| DICT pref.view.toolbars.snapshot.item1.id = REVERT

Feb 20 01:41:27: vmx| DICT pref.view.toolbars.snapshot.item2.id = SNAPSHOT_MANAGER

Feb 20 01:41:27: vmx| DICT pref.view.toolbars.view.items.count = 6

Feb 20 01:41:27: vmx| DICT pref.view.toolbars.view.item0.id = FAVORITES

Feb 20 01:41:27: vmx| DICT pref.view.toolbars.view.item1.id = FULL_SCREEN

Feb 20 01:41:27: vmx| DICT pref.view.toolbars.view.item2.id = QUICK_SWITCH

Feb 20 01:41:27: vmx| DICT pref.view.toolbars.view.item3.id = \_SEP_

Feb 20 01:41:27: vmx| DICT pref.view.toolbars.view.item4.id = SUMMARY

Feb 20 01:41:27: vmx| DICT pref.view.toolbars.view.item5.id = CONSOLE

Feb 20 01:41:27: vmx| DICT webUpdate.checkLast = 1171963974

Feb 20 01:41:27: vmx| DICT pref.view.toolbars.menu.position.ix = 0

Feb 20 01:41:27: vmx| DICT pref.view.toolbars.menu.position.newLine = TRUE

Feb 20 01:41:27: vmx| DICT pref.view.toolbars.power.position.ix = 1

Feb 20 01:41:27: vmx| DICT pref.view.toolbars.power.position.newLine = TRUE

Feb 20 01:41:27: vmx| DICT pref.view.toolbars.power.position.length = 118

Feb 20 01:41:27: vmx| DICT pref.view.toolbars.snapshot.position.ix = 2

Feb 20 01:41:27: vmx| DICT pref.view.toolbars.snapshot.position.length = 87

Feb 20 01:41:27: vmx| DICT pref.view.toolbars.view.position.ix = 3

Feb 20 01:41:27: vmx| DICT pref.view.toolbars.view.position.length = 153

Feb 20 01:41:27: vmx| DICT pref.console.openedObj0.present = TRUE

Feb 20 01:41:27: vmx| DICT pref.console.openedObj0.name = Home

Feb 20 01:41:27: vmx| DICT pref.console.openedObj.maxNum = 2

Feb 20 01:41:27: vmx| DICT pref.placement.left = 108

Feb 20 01:41:27: vmx| DICT pref.placement.top = 52

Feb 20 01:41:27: vmx| DICT pref.placement.right = 1295

Feb 20 01:41:27: vmx| DICT pref.placement.bottom = 816

Feb 20 01:41:27: vmx| DICT pref.view.homepage = TRUE

Feb 20 01:41:27: vmx| DICT pref.console.currentObj.path = /vm/#35d221d814556c30/

Feb 20 01:41:27: vmx| DICT pref.console.currentObj.type = vm

Feb 20 01:41:27: vmx| DICT pref.console.openedObj1.present = TRUE

Feb 20 01:41:27: vmx| DICT pref.console.openedObj1.type = vm

Feb 20 01:41:27: vmx| DICT pref.console.openedObj1.path = /vm/#35d221d814556c30/

Feb 20 01:41:27: vmx| DICT pref.console.openedObj1.file = C:\VMs\Windows2003Base\winnetenterprise.vmx

Feb 20 01:41:27: vmx| DICT pref.console.openedObj1.name = VM-AST-DEV99

Feb 20 01:41:27: vmx| DICT pref.console.openedObj0.type = home

Feb 20 01:41:27: vmx| DICT pref.console.openedObj0.path = /vm/#35d221d814556c30/

Feb 20 01:41:27: vmx| DICT pref.console.openedObj0.file = C:\VMs\Windows2003Base\winnetenterprise.vmx

Feb 20 01:41:27: vmx| DICT pref.console.openedObj2.present = TRUE

Feb 20 01:41:27: vmx| DICT pref.console.openedObj2.type = home

Feb 20 01:41:27: vmx| DICT pref.console.openedObj2.path =

Feb 20 01:41:27: vmx| DICT pref.console.openedObj2.file =

Feb 20 01:41:27: vmx| DICT pref.console.openedObj2.name = Home

Feb 20 01:41:27: vmx| DICT pref.view.navBar = FALSE

Feb 20 01:41:27: vmx| DICT pref.console.openedObj3.present = TRUE

Feb 20 01:41:27: vmx| DICT pref.console.openedObj3.type = vm

Feb 20 01:41:27: vmx| DICT pref.console.openedObj3.path = /vm/#35d221d814556c30/

Feb 20 01:41:27: vmx| DICT pref.console.openedObj3.file = C:\VMs\Windows2003Base\winnetenterprise.vmx

Feb 20 01:41:27: vmx| DICT pref.console.openedObj3.name = VM-AST-DEV99

Feb 20 01:41:27: vmx| DICT pref.autoFit = FALSE

Feb 20 01:41:27: vmx| DICT pref.autoFitGuestToWindow = FALSE

Feb 20 01:41:27: vmx| DICT pref.eula.0.appName = VMware Player

Feb 20 01:41:27: vmx| DICT pref.eula.0.buildNumber = 34682

Feb 20 01:41:27: vmx| DICT pref.mruATS0.present = FALSE

Feb 20 01:41:27: vmx| DICT pref.mruATS0.atsString =

Feb 20 01:41:27: vmx| DICT pref.mruATS0.domain =

Feb 20 01:41:27: vmx| DICT pref.mruATS0.user =

Feb 20 01:41:27: vmx| DICT pref.mruATS0.secure = FALSE

Feb 20 01:41:27: vmx| DICT pref.mruATS0.port = 0

Feb 20 01:41:27: vmx| DICT pref.mruATS1.present = FALSE

Feb 20 01:41:27: vmx| DICT pref.mruATS1.atsString =

Feb 20 01:41:27: vmx| DICT pref.mruATS1.domain =

Feb 20 01:41:27: vmx| DICT pref.mruATS1.user =

Feb 20 01:41:27: vmx| DICT pref.mruATS1.secure = FALSE

Feb 20 01:41:27: vmx| DICT pref.mruATS1.port = 0

Feb 20 01:41:27: vmx| DICT pref.mruATS2.present = FALSE

Feb 20 01:41:27: vmx| DICT pref.mruATS2.atsString =

Feb 20 01:41:27: vmx| DICT pref.mruATS2.domain =

Feb 20 01:41:27: vmx| DICT pref.mruATS2.user =

Feb 20 01:41:27: vmx| DICT pref.mruATS2.secure = FALSE

Feb 20 01:41:27: vmx| DICT pref.mruATS2.port = 0

Feb 20 01:41:27: vmx| DICT pref.mruATS3.present = FALSE

Feb 20 01:41:27: vmx| DICT pref.mruATS3.atsString =

Feb 20 01:41:27: vmx| DICT pref.mruATS3.domain =

Feb 20 01:41:27: vmx| DICT pref.mruATS3.user =

Feb 20 01:41:27: vmx| DICT pref.mruATS3.secure = FALSE

Feb 20 01:41:27: vmx| DICT pref.mruATS3.port = 0

Feb 20 01:41:27: vmx| DICT pref.mruATS4.present = FALSE

Feb 20 01:41:27: vmx| DICT pref.mruATS4.atsString =

Feb 20 01:41:27: vmx| DICT pref.mruATS4.domain =

Feb 20 01:41:27: vmx| DICT pref.mruATS4.user =

Feb 20 01:41:27: vmx| DICT pref.mruATS4.secure = FALSE

Feb 20 01:41:27: vmx| DICT pref.mruATS4.port = 0

Feb 20 01:41:27: vmx| DICT pref.mruATS5.present = FALSE

Feb 20 01:41:27: vmx| DICT pref.mruATS5.atsString =

Feb 20 01:41:27: vmx| DICT pref.mruATS5.domain =

Feb 20 01:41:27: vmx| DICT pref.mruATS5.user =

Feb 20 01:41:27: vmx| DICT pref.mruATS5.secure = FALSE

Feb 20 01:41:27: vmx| DICT pref.mruATS5.port = 0

Feb 20 01:41:27: vmx| DICT pref.mruATS6.present = FALSE

Feb 20 01:41:27: vmx| DICT pref.mruATS6.atsString =

Feb 20 01:41:27: vmx| DICT pref.mruATS6.domain =

Feb 20 01:41:27: vmx| DICT pref.mruATS6.user =

Feb 20 01:41:27: vmx| DICT pref.mruATS6.secure = FALSE

Feb 20 01:41:27: vmx| DICT pref.mruATS6.port = 0

Feb 20 01:41:27: vmx| DICT pref.mruATS7.present = FALSE

Feb 20 01:41:27: vmx| DICT pref.mruATS7.atsString =

Feb 20 01:41:27: vmx| DICT pref.mruATS7.domain =

Feb 20 01:41:27: vmx| DICT pref.mruATS7.user =

Feb 20 01:41:27: vmx| DICT pref.mruATS7.secure = FALSE

Feb 20 01:41:27: vmx| DICT pref.mruATS7.port = 0

Feb 20 01:41:27: vmx| DICT pref.view.toolbars.list = \_SEP_,POWER_OFF,SUSPEND,POWER_ON,RESET,SNAPSHOT,REVERT,SNAPSHOT_MANAGER,FAVORITES,

QUICK_SWITCH,SUMMARY,APPLIANCE,CONSOLE,QUICK_MINIMIZE,FILE_EXIT

Feb 20 01:41:27: vmx| DICT pref.ws.openedObj0.present = TRUE

Feb 20 01:41:27: vmx| DICT pref.ws.openedObj0.name = Home

Feb 20 01:41:27: vmx| DICT pref.ws.openedObj.maxNum = 2

Feb 20 01:41:27: vmx| DICT pref.ws.openedObj1.present = TRUE

Feb 20 01:41:27: vmx| DICT pref.ws.openedObj1.name = VM-AST-DEV99

Feb 20 01:41:27: vmx| DICT prefvmx.defaultVMPath = C:\Virtual Machines

Feb 20 01:41:27: vmx| DICT pref.ws.currentObj.path = /vm/#e427432e126fbd5e/

Feb 20 01:41:27: vmx| DICT pref.ws.currentObj.type = vm

Feb 20 01:41:27: vmx| DICT pref.ws.openedObj2.present = TRUE

Feb 20 01:41:27: vmx| DICT pref.ws.openedObj2.type = vm

Feb 20 01:41:27: vmx| DICT pref.ws.openedObj2.path = /vm/#e427432e126fbd5e/

Feb 20 01:41:27: vmx| DICT pref.ws.openedObj2.file = C:\Virtual Machines\Windows2003Base\winnetenterprise.vmx

Feb 20 01:41:27: vmx| DICT pref.ws.openedObj2.name = VM-AST-DEV99

Feb 20 01:41:27: vmx| DICT pref.vmplayer.fullscreen.autohide = TRUE

Feb 20 01:41:27: vmx| DICT pref.eula.size = 1

Feb 20 01:41:27: vmx| DICT pref.ws.openedObj1.type = vm

Feb 20 01:41:27: vmx| DICT pref.ws.openedObj1.path = /vm/#e427432e126fbd5e/

Feb 20 01:41:27: vmx| DICT pref.ws.openedObj1.file = C:\Virtual Machines\Windows2003Base\winnetenterprise.vmx

Feb 20 01:41:27: vmx| DICT pref.vmplayer.webUpdateOnStartup = TRUE

Feb 20 01:41:27: vmx| DICT --- USER DEFAULTS

Feb 20 01:41:27: vmx| DICT --- HOST DEFAULTS

Feb 20 01:41:27: vmx| DICT vmplayer.searchbar = FALSE

Feb 20 01:41:27: vmx| DICT --- SITE DEFAULTS

Feb 20 01:41:27: vmx| DICT vmplayer.searchbar = FALSE

Feb 20 01:41:27: vmx| DICT --- COMMAND LINE

Feb 20 01:41:27: vmx| DICT gui.available = TRUE

Feb 20 01:41:27: vmx| DICT --- CONFIGURATION

Feb 20 01:41:27: vmx| DICT config.version = 8

Feb 20 01:41:27: vmx| DICT virtualHW.version = 4

Feb 20 01:41:27: vmx| DICT scsi0.present = TRUE

Feb 20 01:41:27: vmx| DICT scsi0.virtualDev = lsilogic

Feb 20 01:41:27: vmx| DICT memsize = 384

Feb 20 01:41:27: vmx| DICT scsi0:0.present = FALSE

Feb 20 01:41:27: vmx| DICT scsi0:0.fileName = Windows Server 2003 Enterprise Edition (2).vmdk

Feb 20 01:41:27: vmx| DICT ide1:0.present = TRUE

Feb 20 01:41:27: vmx| DICT ide1:0.fileName = auto detect

Feb 20 01:41:27: vmx| DICT ide1:0.deviceType = cdrom-raw

Feb 20 01:41:27: vmx| DICT floppy0.fileName = B:

Feb 20 01:41:27: vmx| DICT ethernet0.present = TRUE

Feb 20 01:41:27: vmx| DICT usb.present = TRUE

Feb 20 01:41:27: vmx| DICT sound.present = TRUE

Feb 20 01:41:27: vmx| DICT sound.virtualDev = es1371

Feb 20 01:41:27: vmx| DICT displayName = VM-AST-DEV99

Feb 20 01:41:27: vmx| DICT guestOS = winnetenterprise

Feb 20 01:41:27: vmx| DICT nvram = winnetenterprise.nvram

Feb 20 01:41:27: vmx| DICT ide0:0.present = TRUE

Feb 20 01:41:27: vmx| DICT ide0:0.fileName = Windows Server 2003 Enterprise Edition.vmdk

Feb 20 01:41:27: vmx| DICT ide0:0.redo =

Feb 20 01:41:27: vmx| DICT ethernet0.addressType = generated

Feb 20 01:41:27: vmx| DICT uuid.location = 56 4d 3d 8f 0b c8 e6 46-1b 61 e3 f6 ce c3 f7 11

Feb 20 01:41:27: vmx| DICT uuid.bios = 56 4d 41 a3 d7 c1 0e 1c-5a e7 3a af 7b 37 c7 1c

Feb 20 01:41:27: vmx| DICT ethernet0.generatedAddress = 00:0c:29:37:c7:1c

Feb 20 01:41:27: vmx| DICT ethernet0.generatedAddressOffset = 0

Feb 20 01:41:27: vmx| DICT tools.syncTime = TRUE

Feb 20 01:41:27: vmx| DICT checkpoint.vmState.readOnly = FALSE

Feb 20 01:41:27: vmx| DICT checkpoint.vmState = winnetenterprise.vmss

Feb 20 01:41:27: vmx| DICT workingDir =

Feb 20 01:41:27: vmx| DICT ide1:0.startConnected = TRUE

Feb 20 01:41:27: vmx| DICT sound.fileName = -1

Feb 20 01:41:27: vmx| DICT sound.autodetect = TRUE

Feb 20 01:41:27: vmx| DICT ide1:0.autodetect = TRUE

Feb 20 01:41:27: vmx| DICT ethernet0.connectionType = bridged

Feb 20 01:41:27: vmx| DICT ethernet0.vnet = VMnet2

Feb 20 01:41:27: vmx| DICT floppy0.startConnected = FALSE

Feb 20 01:41:27: vmx| DICT floppy0.autodetect = FALSE

Feb 20 01:41:27: vmx| DICT priority.grabbed = normal

Feb 20 01:41:27: vmx| DICT priority.ungrabbed = normal

Feb 20 01:41:27: vmx| DICT virtualHW.productCompatibility = hosted

Feb 20 01:41:27: vmx| DICT tools.upgrade.policy = upgradeAtPowerCycle

Feb 20 01:41:27: vmx| DICT uuid.action = keep

Feb 20 01:41:27: vmx| DICT usb.autoConnect.device0 = path:1/2/2 autoclean:1

Feb 20 01:41:27: vmx| DICT usb.autoConnect.device1 = path:1/4/1 autoclean:1

Feb 20 01:41:27: vmx| DICT mainMem.partialLazyRestore = FALSE

Feb 20 01:41:27: vmx| DICT --- USER DEFAULTS

Feb 20 01:41:27: vmx| DICT --- HOST DEFAULTS

Feb 20 01:41:27: vmx| DICT vmplayer.searchbar = FALSE

Feb 20 01:41:27: vmx| DICT --- SITE DEFAULTS

Feb 20 01:41:27: vmx| DICT vmplayer.searchbar = FALSE

Feb 20 01:41:27: vmx| DICT --- GLOBAL SETTINGS

Feb 20 01:41:27: vmx| Msg_Hint: msg.hostw32.wrongNTversion (shown)

Feb 20 01:41:27: vmx| WSSCAN: reserved mem (in MB) min=32 max=1916 recommended=1916

Feb 20 01:41:27: vmx| hostMem=2044 maxAllowedAll=4096 maxAllowedVM=3600

Feb 20 01:41:27: vmx| totOverhead=16

Feb 20 01:41:27: vmx| WSSCAN: used rec mem (in MB) 1916

Feb 20 01:41:27: vmx| WSSCAN: Overhead 104689 paged 5791 nonpaged 4096 maxFBSize

Feb 20 01:43:23: vmx| WSSCAN 1 2 451402 451402 490496 -1 50 0

Feb 20 01:43:42: vmx| LICENSE: Running in restricted mode

Feb 20 01:43:43: vmx| STATDECLGROUP stats Root "" null

Feb 20 01:43:46: vmx| Host CPUID features: version 0x6f8 id1.edx 0xbfebfbff id1.ecx 0xe3bd id81.edx 0x20100000 id81.ecx 0x1

Feb 20 01:43:46: vmx| CPU.cpuFeatures = 0xb971ff37

Feb 20 01:43:46: vmx| CPUID after masking: version 0x6f8 id1.edx 0xfebbbff id1.ecx 0xa215 id81.edx 0x20100800 id81.ecx 0x1 id88.ecx 0x0

Feb 20 01:43:46: vmx| CPU.cpuFeatures = 0x9871ff33

Feb 20 01:43:46: vmx| APIC: Local APIC at 0xfee00000

Feb 20 01:43:50: vmx| KHZEstimate 3219999

Feb 20 01:43:50: vmx| MHZEstimate 3220

Feb 20 01:43:50: vmx| NumVCPUs 1

Feb 20 01:44:04: vmx| UUID: location-UUID is 56 4d 8c 4f 75 5e f8 e1-fb fd 65 04 94 72 eb 51

Feb 20 01:44:53: vmx| WMI_CreateWMIObjectInstance: failed to enum an instance of class: 0x00040004 0 'Win32_ComputerSystemProduct'

Feb 20 01:44:53: vmx| Failed to get system information

Feb 20 01:44:53: vmx| UUID: Failed to obtain host UUID using WMI

Feb 20 01:44:53: vmx| UUID: canonical path is c:\virtual machines\windows2003base\winnetenterprise.vmx

Feb 20 01:44:53: vmx| UUID: location-UUID is 56 4d 0c 46 d9 1d 09 0d-6e 73 f8 32 50 76 1a 4e

Feb 20 01:44:53: vmx| UUID: Writing uuid.location 56 4d 0c 46 d9 1d 09 0d-6e 73 f8 32 50 76 1a 4e

Feb 20 01:44:53: vmx| MM: Using partialmap, 98304 pages AC 0 CE 1 TM 0 DOHU 0

Feb 20 01:44:53: vmx| MStat: Creating Stat vm.uptime

Feb 20 01:44:55: vmx| DISK: OPEN ide0:0 'C:\Virtual Machines\Windows2003Base\Windows Server 2003 Enterprise Edition.vmdk' persistent R\[(null)]

Feb 20 01:44:57: vmx| DISKLIB-DSCPTR: Opened : "Windows Server 2003 Enterprise Edition-s001.vmdk" (0xa)

Feb 20 01:44:57: vmx| DISKLIB-DSCPTR: Opened : "Windows Server 2003 Enterprise Edition-s002.vmdk" (0xa)

Feb 20 01:44:58: vmx| DISKLIB-DSCPTR: Opened : "Windows Server 2003 Enterprise Edition-s003.vmdk" (0xa)

Feb 20 01:44:59: vmx| DISKLIB-DSCPTR: Opened : "Windows Server 2003 Enterprise Edition-s004.vmdk" (0xa)

Feb 20 01:45:00: vmx| DISKLIB-DSCPTR: Opened : "Windows Server 2003 Enterprise Edition-s005.vmdk" (0xa)

Feb 20 01:45:01: vmx| DISKLIB-DSCPTR: Opened : "Windows Server 2003 Enterprise Edition-s006.vmdk" (0xa)

Feb 20 01:45:02: vmx| DISKLIB-DSCPTR: Opened : "Windows Server 2003 Enterprise Edition-s007.vmdk" (0xa)

Feb 20 01:45:02: vmx| DISKLIB-DSCPTR: Opened : "Windows Server 2003 Enterprise Edition-s008.vmdk" (0xa)

Feb 20 01:45:03: vmx| DISKLIB-DSCPTR: Opened : "Windows Server 2003 Enterprise Edition-s009.vmdk" (0xa)

Feb 20 01:45:04: vmx| DISKLIB-DSCPTR: Opened : "Windows Server 2003 Enterprise Edition-s010.vmdk" (0xa)

Feb 20 01:45:04: vmx| DISKLIB-DSCPTR: Opened \[10]: "Windows Server 2003 Enterprise Edition-s011.vmdk" (0xa)

Feb 20 01:45:05: vmx| DISKLIB-DSCPTR: Opened \[11]: "Windows Server 2003 Enterprise Edition-s012.vmdk" (0xa)

Feb 20 01:45:06: vmx| DISKLIB-DSCPTR: Opened \[12]: "Windows Server 2003 Enterprise Edition-s013.vmdk" (0xa)

Feb 20 01:45:06: vmx| DISKLIB-DSCPTR: Opened \[13]: "Windows Server 2003 Enterprise Edition-s014.vmdk" (0xa)

Feb 20 01:45:07: vmx| DISKLIB-DSCPTR: Opened \[14]: "Windows Server 2003 Enterprise Edition-s015.vmdk" (0xa)

Feb 20 01:45:08: vmx| DISKLIB-DSCPTR: Opened \[15]: "Windows Server 2003 Enterprise Edition-s016.vmdk" (0xa)

Feb 20 01:45:09: vmx| DISKLIB-DSCPTR: Opened \[16]: "Windows Server 2003 Enterprise Edition-s017.vmdk" (0xa)

Feb 20 01:45:10: vmx| DISKLIB-DSCPTR: Opened \[17]: "Windows Server 2003 Enterprise Edition-s018.vmdk" (0xa)

Feb 20 01:45:11: vmx| DISKLIB-DSCPTR: Opened \[18]: "Windows Server 2003 Enterprise Edition-s019.vmdk" (0xa)

Feb 20 01:45:11: vmx| DISKLIB-DSCPTR: Opened \[19]: "Windows Server 2003 Enterprise Edition-s020.vmdk" (0xa)

Feb 20 01:45:12: vmx| DISKLIB-DSCPTR: Opened \[20]: "Windows Server 2003 Enterprise Edition-s021.vmdk" (0xa)

Feb 20 01:45:13: vmx| DISKLIB-DSCPTR: Opened \[21]: "Windows Server 2003 Enterprise Edition-s022.vmdk" (0xa)

Feb 20 01:45:13: vmx| DISKLIB-LINK : Opened 'C:\Virtual Machines\Windows2003Base\Windows Server 2003 Enterprise Edition.vmdk' (0xa): twoGbMaxExtentSparse, 83886080 sectors / 40960 Mb.

Feb 20 01:45:13: vmx| DISKLIB-LIB : Opened "C:\Virtual Machines\Windows2003Base\Windows Server 2003 Enterprise Edition.vmdk" (flags 0xa).

Feb 20 01:45:13: vmx| DISK: OPEN 'C:\Virtual Machines\Windows2003Base\Windows Server 2003 Enterprise Edition.vmdk' Geo (16383/16/63) BIOS Geo (5221/255/63) freeSpace=44080Mb

Feb 20 01:45:13: vmx| TimeTracker host to guest rate conversion 1142616637961 @ 3219999000Hz -> 1142616637961 @ 3219999000Hz

Feb 20 01:45:13: vmx| TimeTracker host to guest rate conversion ((x * 2147483648) >> 31) + 0

Feb 20 01:45:14: vmx| SCSI0: UNTAGGED commands will be converted to ORDER tags.

Feb 20 01:45:14: vmx| MStat: Creating Stat vm.heartbeat

Feb 20 01:45:14: vmx| DISKUTIL: ide0:0 : toolsVersion = 7234

Feb 20 01:45:14: vmx| TOOLS INSTALL initializing state to IDLE on power on.

Feb 20 01:45:23: vmx| USB: Initializing UHCI host controller

Feb 20 01:45:23: vmx| USB: Initializing USB Generic backend

Feb 20 01:45:23: vmx| USBGW: Connected to HCMON version 1.2

Feb 20 01:45:23: vmx| USB: Search for USB devices to connect \[path:1/2/2 autoclean:1]

Feb 20 01:45:23: vmx| USB: Search for USB devices to connect \[path:1/4/1 autoclean:1]

Feb 20 01:45:23: vmx| VLANCE: send cluster threshold is 80, size = 2 recalcInterval is 2 ticks

Feb 20 01:45:23: vmx| Ethernet0 MAC Address: 00:0c:29:37:c7:1c

Feb 20 01:45:23: vmx| VMXNET: send cluster threshold is 80, size = 2 recalcInterval is 2 ticks, dontClusterSize is 128

Feb 20 01:45:23: vmx| E1000: checksum cycles/kB: C=1251 asm=906

Feb 20 01:45:23: vmx| VirtualProtect MapProtectMem(0xe20000,0x1000) returns 0x1e7

Feb 20 01:45:23: vmx| Can't read-only monitor pages from 0x00E20000 to 0x00E21000

Feb 20 01:45:23: vmx| VirtualProtect MapProtectMem(0xe22000,0x0) returns 0x57

Feb 20 01:45:23: vmx| Can't read-only monitor pages from 0x00E22000 to 0x00E22000

Feb 20 01:45:23: vmx| CPT: Restoring checkpoint C:\Virtual Machines\Windows2003Base\winnetenterprise.vmss

Feb 20 01:45:23: vmx| DUMPER: restoring checkpoint version 8

Feb 20 01:45:23: vmx| checkpointCPUID: cpt vendor GenuntelineI host vendor GenuntelineI

Feb 20 01:45:23: vmx| checkpointCPUID: cpt family 06 model 0f6 host family 06 model 0f6

Feb 20 01:45:23: vmx| checkpointCPUID: cpt id1.ecx a215 id1.edx febbbff id81.ecx 1 id81.edx 20100000

Feb 20 01:45:23: vmx| checkpointCPUID: host id1.ecx a215 id1.edx febbbff id81.ecx 1 id81.edx 20100000

Feb 20 01:45:23: vmx| DUMPER: item eip \[0,-1] in group cpu not used

Feb 20 01:45:23: vmx| restoring Snapshot

Feb 20 01:45:24: vmx| DUMPER: item cfgFile \[-1,-1] in group Snapshot not used

Feb 20 01:45:24: vmx| DUMPER: item nvramFile \[-1,-1] in group Snapshot not used

Feb 20 01:45:24: vmx| restoring memory

Feb 20 01:45:24: vmx| Mapped mainmem as pageable

Feb 20 01:45:24: vmx| Msg_Reset:

Feb 20 01:45:24: vmx| \----

Feb 20 01:45:24: vmx| Opened paging file C:\Virtual Machines\Windows2003Base\winnetenterprise.vmem

Feb 20 01:45:24: vmx| Lazy Restore with prefetch of 384 MB

Feb 20 01:45:24: MMPageWalker| Entering MM LazySave Loop

Feb 20 01:45:37: vmx| restoring MStats

Feb 20 01:45:37: vmx| restoring pic

Feb 20 01:45:37: vmx| restoring TimeTracker

Feb 20 01:45:37: vmx| TimeTracker host to guest rate conversion 1142616637961 @ 3219999000Hz -> 272131579314811 @ 3219999000Hz

Feb 20 01:45:37: vmx| TimeTracker host to guest rate conversion ((x * 2147483648) >> 31) + 270988962676850

Feb 20 01:45:37: vmx| restoring Floppy

Feb 20 01:45:37: vmx| restoring GuestMsg

Feb 20 01:45:37: vmx| restoring GuestRpc

Feb 20 01:45:37: vmx| restoring PCI

Feb 20 01:45:37: vmx| restoring Cs440bx

Feb 20 01:45:37: vmx| DUMPER: item drb \[1,-1] in group Cs440bx not used

Feb 20 01:45:37: vmx| DUMPER: item drb \[0,-1] in group Cs440bx not used

Feb 20 01:45:37: vmx| restoring ide1:0

Feb 20 01:45:37: vmx| restoring ide0:0

Feb 20 01:45:37: vmx| restoring vide

Feb 20 01:45:37: vmx| DUMPER: BlockItem monbuf \[0, -1] not found

Feb 20 01:45:37: vmx| DUMPER: BlockItem monbuf \[1, -1] not found

Feb 20 01:45:37: vmx| DUMPER: item setMultiple \[0,-1] in group vide not used

Feb 20 01:45:37: vmx| restoring SCSI0

Feb 20 01:45:37: vmx| restoring Timer

Feb 20 01:45:37: vmx| restoring Tools

Feb 20 01:45:37: vmx| restoring Tools Install

Feb 20 01:45:37: vmx| TOOLS INSTALL setting state to 0 on restore.

Feb 20 01:45:37: vmx| restoring MKSVMX

Feb 20 01:45:37: vmx| DUMPER: item imageData \[-1,-1] in group MKSVMX not used

Feb 20 01:45:37: vmx| restoring VGA

Feb 20 01:45:38: vmx| restoring DMA

Feb 20 01:45:38: vmx| restoring BackdoorAPM

Feb 20 01:45:38: vmx| restoring CMOS

Feb 20 01:45:38: vmx| restoring FlashRam

Feb 20 01:45:38: vmx| restoring A20

Feb 20 01:45:38: vmx| restoring Keyboard

Feb 20 01:45:38: vmx| restoring backdoorAbsMouse

Feb 20 01:45:38: vmx| restoring SIO

Feb 20 01:45:38: vmx| restoring serial0

Feb 20 01:45:38: vmx| restoring serial1

Feb 20 01:45:38: vmx| restoring serial2

Feb 20 01:45:38: vmx| restoring serial3

Feb 20 01:45:38: vmx| restoring parallel0

Feb 20 01:45:38: vmx| restoring parallel1

Feb 20 01:45:38: vmx| restoring parallel2

Feb 20 01:45:38: vmx| restoring SVGA

Feb 20 01:45:38: vmx| SVGA phys sizes: 2360x1770x32 offset 0 fb 0x01000000

Feb 20 01:45:38: vmx| SVGA compatible geometry on checkpoint restore

Feb 20 01:45:38: vmx| SVGA old geometry 2360x1770, max size = 16777216

Feb 20 01:45:38: vmx| SVGA new geometry 2360x1770, max size = 16777216

Feb 20 01:45:38: vmx| restoring USB

Feb 20 01:45:38: vmx| restoring Ethernet0

Feb 20 01:45:38: vmx| restoring sound

Feb 20 01:45:38: vmx| restoring monitorLate

Feb 20 01:45:38: vmx| restoring devices

Feb 20 01:45:38: vmx| DUMPER: Dumper_EndRestore: update hdr magic

Feb 20 01:45:38: vmx| TOOLS received request in VMX to set option 'enableDnD' -> '1'

Feb 20 01:45:38: vmx| USB: Found device \[name:Genesys\ Logic\ USB\ device vid:05e3 pid:070e path:1/2/2]

Feb 20 01:45:38: vmx| USB: Autoconnecting device "Genesys Logic USB device" matching pattern \[path:1/2/2 autoclean:1]

Feb 20 01:45:38: vmx| USB: Connecting device 0xe200000205e3070e

Feb 20 01:45:38: vmx| USB: Found device \[name:Philips\ USB\ device vid:0471 pid:0815 path:1/4/1]

Feb 20 01:45:38: vmx| USB: Autoconnecting device "Philips USB device" matching pattern \[path:1/4/1 autoclean:1]

Feb 20 01:45:38: vmx| USB: Connecting device 0xe400000104710815

Feb 20 01:45:38: vmx| VMXVmdbLoadUsbDevices: New set of 2 USB devices

Feb 20 01:45:38: vmx| VMX_PowerOn: ModuleTable_PowerOn = 1

Feb 20 01:45:38: mks| Async MKS thread is alive

Feb 20 01:45:38: mks| Connecting to window system.

Feb 20 01:45:38: vmx| DnD rpc already set to 1

Feb 20 01:45:38: vcpu-0| APIC: version = 0x14, max LVT = 5

Feb 20 01:45:38: vcpu-0| APIC: LDR = 0x2000000, DFR = 0xffffffff

Feb 20 01:45:38: vcpu-0| PShare: enabled 1, scanRate 32, checkRate 16

Feb 20 01:45:38: vcpu-0| guestCpuFeatures = 0x9871ff33

Feb 20 01:45:38: vcpu-0| Init modules.

Feb 20 01:45:38: vcpu-0| DISKUTIL: ide0:0 : capacity=83886080

Feb 20 01:45:38: vcpu-0| Cpt monModules(3).

Feb 20 01:45:38: vcpu-0| Done Cpt monModules(3).

Feb 20 01:45:38: vmx| VNET: Notification enabled for Ethernet0

Feb 20 01:45:38: vcpu-0| sz=3108832

Feb 20 01:45:38: vcpu-0| vmm32 initialized: Releasebuild-34682. cflags: 0x02000002.01803000.00000054

Feb 20 01:45:38: vcpu-0| Restoring VM

Feb 20 01:45:38: vmx| VMMouse: restored checkpoint state was absolute, notifying MKS

Feb 20 01:45:38: vmx| MKS switching absolute mouse on

Feb 20 01:45:38: vcpu-0| Sync monModules(4).

Feb 20 01:45:38: vcpu-0| Done Sync monModules(4).

Feb 20 01:45:38: vcpu-0| SVGA: Registering IOSpace at 0x14c0 (0x0)

Feb 20 01:45:38: vcpu-0| SVGA: Registering MemSpace at 0xf0000000(0xf0000000) and 0xfe000000(0xfe000000)

Feb 20 01:45:38: vcpu-0| GuestRpc: Channel 1, registration number 8, guest application toolbox.

Feb 20 01:45:38: vcpu-0| TOOLS Reducing idleLoopSpinUS to 500us

Feb 20 01:45:38: vcpu-0| TOOLS sending 'OS_Resume' (4) state change request

Feb 20 01:45:38: vcpu-0| DISKUTIL: ide0:0 : toolsVersion = 7234

Feb 20 01:45:39: vcpu-0| Guest: toolbox: Version: build-36983

Feb 20 01:45:39: vcpu-0| TOOLS setting the tools version to '7234'

Feb 20 01:45:39: vcpu-0| TOOLS unified loop capability requested by 'toolbox'; now sending options via TCLO

Feb 20 01:45:39: vcpu-0| GuestRpc: Channel 0, registration number 8, guest application tools-hgfs.

Feb 20 01:45:39: vcpu-0| DISKUTIL: ide0:0 : toolsVersion = 7234

Feb 20 01:45:39: vcpu-0| MKS Backdoor get pointer: first time, notify tools are running

Feb 20 01:45:39: vcpu-0| TOOLS unified loop capability requested by 'toolbox-dnd'; now sending options via TCLO

Feb 20 01:45:39: vcpu-0| GuestRpc: Channel 2, registration number 8, guest application toolbox-dnd.

Feb 20 01:45:39: vcpu-0| DISKUTIL: ide0:0 : toolsVersion = 7234

Feb 20 01:45:39: vcpu-0| TOOLS received request in VMX to set option 'enableDnD' -> '1'

Feb 20 01:45:39: vmx| DnD rpc already set to 1

Feb 20 01:46:57: MMPageWalker| Exit MM Lazy Loop

Feb 20 01:46:57: MMPageWalker| Finished pending checkpoint op

shawn_ · ‎02-20-2007

I've noticed that the memory usage spikes during the load of the VM to 100% then drops down to like 20% right before the "restoring VM screen". It then climbs back up to about 45% as the VM loads which is normal.

ESPNSTI · ‎03-15-2007

I seem to have the same symptoms.

I'm running Vista on a Core 2 Duo 2.66Ghz 2GB Ram.

VMWare Player 1.0.3 build 34682

It seems that the two most time consuming points are the "WSSCAN" process and the "MMPageWalker" process:

Mar 15 09:04:00: vmx| WSSCAN: reserved mem (in MB) min=32 max=1916 recommended=1916

Mar 15 09:04:00: vmx| hostMem=2044 maxAllowedAll=4096 maxAllowedVM=3600

Mar 15 09:04:00: vmx| totOverhead=16

Mar 15 09:04:00: vmx| WSSCAN: used rec mem (in MB) 1916

Mar 15 09:04:00: vmx| WSSCAN: Overhead 268529 paged 7072 nonpaged 4096 maxFBSize

Mar 15 09:05:47: vmx| WSSCAN 1 2 364156 418923 490496 364156 50 0

Mar 15 09:07:23: MMPageWalker| Entering MM LazySave Loop

Mar 15 09:09:46: vmx| restoring MStats

________________________________________________________

Config file:

config.version = "8"

virtualHW.version = "4"

memsize = "1024"

MemAllowAutoScaleDown = "TRUE"

MemTrimRate = "-1"

displayName = "Erik2"

guestOS = "win2000pro"

uuid.location = "56 4d 45 3b b9 1f 9d f7-8e db 22 79 20 62 bc 40"

uuid.bios = "56 4d 7c 45 e0 70 7e 4d-c4 e2 27 bf a2 f0 05 a9"

tools.remindInstall = "FALSE"

floppy0.present = "TRUE"

floppy0.fileName = "A:"

sound.present = "TRUE"

sound.virtualDev = "sb16"

sound.filename = "-1"

sound.autodetect = "TRUE"

usb.present = "TRUE"

ethernet0.present = "TRUE"

ethernet0.addressType = "generated"

ethernet0.connectionType = "bridged"

scsi0:0.present = "TRUE"

scsi0:0.fileName = "Erik2.vmdk"

ide0:0.present = "TRUE"

ide0:0.autodetect = "TRUE"

ide0:0.filename = "auto detect"

ide0:0.deviceType = "atapi-cdrom"

ide0:1.present = "TRUE"

ide0:1.autodetect = "TRUE"

ide0:1.filename = "auto detect"

ide0:1.deviceType = "atapi-cdrom"

ide1:0.present = "TRUE"

ide1:0.autodetect = "TRUE"

ide1:0.filename = "auto detect"

ide1:0.deviceType = "atapi-cdrom"

scsi0.present = "TRUE"

scsi0.virtualDev = "buslogic"

scsi0:0.redo = ""

ethernet0.generatedAddress = "00:0c:29:f0:05:a9"

sound.deviceNr = "-1"

sound.device = "/dev/dsp"

ethernet0.generatedAddressOffset = "0"

tools.syncTime = "FALSE"

ide1:0.startConnected = "FALSE"

checkpoint.vmState = ""

________________________________________________________

UI Log:

Mar 15 09:03:57: player| Log for VMware Player pid=3432 version=1.0.3 build=build-34682 option=Release

Mar 15 09:03:57: player| VMware Player: VMware virtual machine player

Mar 15 09:03:57: player| Command line: "C:\Utils\VMware\VMware Player\vmplayer.exe" "-G" "C:\Users\Erik.SWEET\Documents\My Virtual Machines\Erik2\Erik2.vmx"

Mar 15 09:03:57: player| Unable to query Project Resources directory

Mar 15 09:03:57: player| Msg_Reset:

Mar 15 09:03:57: player| \[msg.dictionary.load.statFailed] Unable to get information about file "C:\Utils\VMware\VMware Player
messages\en\tip_list.vmsg": The system cannot find the path specified.

Mar 15 09:03:57: player| \----

Mar 15 09:03:57: player| Cannot load message dictionary "C:\Utils\VMware\VMware Player
messages\en\tip_list.vmsg".

Mar 15 09:03:58: player| HOSTINFO: Seeing Intel CPU, numCoresPerCPU 1 numThreadsPerCore 1.

Mar 15 09:03:58: player| HOSTINFO: This machine has 2 physical CPUS, 2 total cores, and 2 logical CPUs.

Mar 15 09:03:59: player| Power request 2

Mar 15 09:03:59: player| Power state now 1

Mar 15 09:03:59: player| VMHSVMCbPower: Setting state of VM /vm/#935596fb47077c45/ to powerOn with option soft

Mar 15 09:07:20: player| OnVmxRemDeviceArrival: New list of 6 removable devices

Mar 15 09:07:23: player| OnVmxRemDeviceArrival: New list of 6 removable devices

Mar 15 09:09:55: player| Ignoring execState SET poweredOn during power op

Mar 15 09:09:55: player| Power state now 2

Mar 15 09:12:30: player| Resolution set job failed 'error'

Mar 15 09:12:30: player| OnMksSetGuestsizeResult

Mar 15 09:12:30: player| Guest resolution change failed or timed out.

Mar 15 09:17:58: player| DnD: Couldn't find disk space for guest staging directory

________________________________________________________

VMX Log:

Mar 15 09:04:00: vmx| Log for VMware Player pid=1276 version=1.0.3 build=build-34682 option=Release

Mar 15 09:04:00: vmx| Command line: "C:\Utils\VMware\VMware Player\bin\vmware-vmx.exe" "-@" "pipe=
.\pipe\vmx935596fb47077c45; vm=935596fb47077c45" "C:\Users\Erik.SWEET\Documents\My Virtual Machines\Erik2\Erik2.vmx"

Mar 15 09:04:00: vmx| UI Connecting to pipe '
.\pipe\vmx935596fb47077c45' with user '(null)'

Mar 15 09:04:00: vmx| VMMon: failed memory configuration

Mar 15 09:04:00: vmx| CPU #0 TSC = 10979058286380

Mar 15 09:04:00: vmx| CPU #1 TSC = 10979058286390

Mar 15 09:04:00: vmx| TSC delta 10

Mar 15 09:04:00: vmx| VMMon_GetkHzEstimate: Calculated 2658064 kHz

Mar 15 09:04:00: vmx| Measured CPU as 2658064 kHz, but OS says 2667000 kHz; using 2667000

Mar 15 09:04:00: vmx| Msg_Hint: msg.hostWin32.cpuSpeedMismatch (shown)

Mar 15 09:04:00: vmx| cpuids[0].id81.ecx = 0x1

Mar 15 09:04:00: vmx| cpuids[1].id81.ecx = 0x1

Mar 15 09:04:00: vmx| pcpu #0 CPUID numEntries=10 GenuntelineI

Mar 15 09:04:00: vmx| pcpu #0 CPUID version=0x6f6 id1.edx=0xbfebfbff id1.ecx=0xe3bd id1.ebx=0x20800

Mar 15 09:04:00: vmx| pcpu #0 CPUID id80.eax=80000008 id81.edx=0x20100000 id81.ecx=0x1

Mar 15 09:04:00: vmx| pcpu #1 CPUID numEntries=10 GenuntelineI

Mar 15 09:04:00: vmx| pcpu #1 CPUID version=0x6f6 id1.edx=0xbfebfbff id1.ecx=0xe3bd id1.ebx=0x1020800

Mar 15 09:04:00: vmx| pcpu #1 CPUID id80.eax=80000008 id81.edx=0x20100000 id81.ecx=0x1

Mar 15 09:04:00: vmx| CPUID id1.edx: 0xbfebfbff id1.ecx: 0xe3bd id81.edx: 0x20100000 id81.ecx: 0x1

Mar 15 09:04:00: vmx| CPUID id88.ecx: 0 id88.edx: 0

Mar 15 09:04:00: vmx| ACL_InitCapabilities: here 1 (bug 63252)

Mar 15 09:04:00: vmx| changing directory to C:\Users\Erik.SWEET\Documents\My Virtual Machines\Erik2\.

Mar 15 09:04:00: vmx| Config file: C:\Users\Erik.SWEET\Documents\My Virtual Machines\Erik2\Erik2.vmx

Mar 15 09:04:00: vmx| VMXVmdbCbVmVmxExecState: Exec state change requested to state poweredOn without reset

Mar 15 09:04:00: vmx| TOOLS delaying state change request to state 4

Mar 15 09:04:00: vmx| PowerOn

Mar 15 09:04:00: vmx| Host: WIN32 highest NUMA node 0

Mar 15 09:04:00: vmx| Host: WIN32 NUMA node 0, CPU mask 0x0000000000000003

Mar 15 09:04:00: vmx| Resuming virtual machine from C:\Users\Erik.SWEET\Documents\My Virtual Machines\Erik2\Erik2.vmss

Mar 15 09:04:00: vmx| DUMPER: restoring checkpoint version 8

Mar 15 09:04:00: vmx| HOST Windows version 6.0, build 6000, platform 2, "", SMP

Mar 15 09:04:00: vmx| DICT --- USER PREFERENCES

Mar 15 09:04:00: vmx| DICT pref.vmplayer.fullscreen.autohide = TRUE

Mar 15 09:04:00: vmx| DICT pref.eula.0.appName = VMware Player

Mar 15 09:04:00: vmx| DICT pref.eula.0.buildNumber = 34682

Mar 15 09:04:00: vmx| DICT pref.eula.size = 1

Mar 15 09:04:00: vmx| DICT pref.autoFitFullScreen = fitHostToGuest

Mar 15 09:04:00: vmx| DICT pref.view.navBar.type = favorites

Mar 15 09:04:00: vmx| DICT pref.mruDest0.present = FALSE

Mar 15 09:04:00: vmx| DICT pref.mruDest0.destString =

Mar 15 09:04:00: vmx| DICT pref.mruDest0.user =

Mar 15 09:04:00: vmx| DICT pref.mruDest1.present = FALSE

Mar 15 09:04:00: vmx| DICT pref.mruDest1.destString =

Mar 15 09:04:00: vmx| DICT pref.mruDest1.user =

Mar 15 09:04:00: vmx| DICT pref.mruDest2.present = FALSE

Mar 15 09:04:00: vmx| DICT pref.mruDest2.destString =

Mar 15 09:04:00: vmx| DICT pref.mruDest2.user =

Mar 15 09:04:00: vmx| DICT pref.mruDest3.present = FALSE

Mar 15 09:04:00: vmx| DICT pref.mruDest3.destString =

Mar 15 09:04:00: vmx| DICT pref.mruDest3.user =

Mar 15 09:04:00: vmx| DICT pref.mruDest4.present = FALSE

Mar 15 09:04:00: vmx| DICT pref.mruDest4.destString =

Mar 15 09:04:00: vmx| DICT pref.mruDest4.user =

Mar 15 09:04:00: vmx| DICT pref.mruDest5.present = FALSE

Mar 15 09:04:00: vmx| DICT pref.mruDest5.destString =

Mar 15 09:04:00: vmx| DICT pref.mruDest5.user =

Mar 15 09:04:00: vmx| DICT pref.mruDest6.present = FALSE

Mar 15 09:04:00: vmx| DICT pref.mruDest6.destString =

Mar 15 09:04:00: vmx| DICT pref.mruDest6.user =

Mar 15 09:04:00: vmx| DICT pref.mruDest7.present = FALSE

Mar 15 09:04:00: vmx| DICT pref.mruDest7.destString =

Mar 15 09:04:00: vmx| DICT pref.mruDest7.user =

Mar 15 09:04:00: vmx| DICT webUpdate.checkLast = 1173906238

Mar 15 09:04:00: vmx| DICT pref.vmplayer.exit.vmAction = suspend

Mar 15 09:04:00: vmx| DICT --- USER DEFAULTS

Mar 15 09:04:00: vmx| DICT --- HOST DEFAULTS

Mar 15 09:04:00: vmx| DICT vmplayer.searchbar = FALSE

Mar 15 09:04:00: vmx| DICT --- SITE DEFAULTS

Mar 15 09:04:00: vmx| DICT vmplayer.searchbar = FALSE

Mar 15 09:04:00: vmx| DICT --- COMMAND LINE

Mar 15 09:04:00: vmx| DICT gui.available = TRUE

Mar 15 09:04:00: vmx| DICT --- CONFIGURATION

Mar 15 09:04:00: vmx| DICT config.version = 8

Mar 15 09:04:00: vmx| DICT virtualHW.version = 4

Mar 15 09:04:00: vmx| DICT memsize = 1024

Mar 15 09:04:00: vmx| DICT MemAllowAutoScaleDown = TRUE

Mar 15 09:04:00: vmx| DICT MemTrimRate = -1

Mar 15 09:04:00: vmx| DICT displayName = Erik2

Mar 15 09:04:00: vmx| DICT guestOS = win2000pro

Mar 15 09:04:00: vmx| DICT uuid.location = 56 4d 45 3b b9 1f 9d f7-8e db 22 79 20 62 bc 40

Mar 15 09:04:00: vmx| DICT uuid.bios = 56 4d 7c 45 e0 70 7e 4d-c4 e2 27 bf a2 f0 05 a9

Mar 15 09:04:00: vmx| DICT tools.remindInstall = FALSE

Mar 15 09:04:00: vmx| DICT floppy0.present = TRUE

Mar 15 09:04:00: vmx| DICT floppy0.fileName = A:

Mar 15 09:04:00: vmx| DICT sound.present = TRUE

Mar 15 09:04:00: vmx| DICT sound.virtualDev = sb16

Mar 15 09:04:00: vmx| DICT sound.filename = -1

Mar 15 09:04:00: vmx| DICT sound.autodetect = TRUE

Mar 15 09:04:00: vmx| DICT usb.present = TRUE

Mar 15 09:04:00: vmx| DICT ethernet0.present = TRUE

Mar 15 09:04:00: vmx| DICT ethernet0.addressType = generated

Mar 15 09:04:00: vmx| DICT ethernet0.connectionType = bridged

Mar 15 09:04:00: vmx| DICT scsi0:0.present = TRUE

Mar 15 09:04:00: vmx| DICT scsi0:0.fileName = Erik2.vmdk

Mar 15 09:04:00: vmx| DICT ide0:0.present = TRUE

Mar 15 09:04:00: vmx| DICT ide0:0.autodetect = TRUE

Mar 15 09:04:00: vmx| DICT ide0:0.filename = auto detect

Mar 15 09:04:00: vmx| DICT ide0:0.deviceType = atapi-cdrom

Mar 15 09:04:00: vmx| DICT ide0:1.present = TRUE

Mar 15 09:04:00: vmx| DICT ide0:1.autodetect = TRUE

Mar 15 09:04:00: vmx| DICT ide0:1.filename = auto detect

Mar 15 09:04:00: vmx| DICT ide0:1.deviceType = atapi-cdrom

Mar 15 09:04:00: vmx| DICT ide1:0.present = TRUE

Mar 15 09:04:00: vmx| DICT ide1:0.autodetect = TRUE

Mar 15 09:04:00: vmx| DICT ide1:0.filename = auto detect

Mar 15 09:04:00: vmx| DICT ide1:0.deviceType = atapi-cdrom

Mar 15 09:04:00: vmx| DICT scsi0.present = TRUE

Mar 15 09:04:00: vmx| DICT scsi0.virtualDev = buslogic

Mar 15 09:04:00: vmx| DICT scsi0:0.redo =

Mar 15 09:04:00: vmx| DICT ethernet0.generatedAddress = 00:0c:29:f0:05:a9

Mar 15 09:04:00: vmx| DICT sound.deviceNr = -1

Mar 15 09:04:00: vmx| DICT sound.device = /dev/dsp

Mar 15 09:04:00: vmx| DICT ethernet0.generatedAddressOffset = 0

Mar 15 09:04:00: vmx| DICT tools.syncTime = FALSE

Mar 15 09:04:00: vmx| DICT ide1:0.startConnected = FALSE

Mar 15 09:04:00: vmx| DICT checkpoint.vmState = Erik2.vmss

Mar 15 09:04:00: vmx| DICT --- USER DEFAULTS

Mar 15 09:04:00: vmx| DICT --- HOST DEFAULTS

Mar 15 09:04:00: vmx| DICT vmplayer.searchbar = FALSE

Mar 15 09:04:00: vmx| DICT --- SITE DEFAULTS

Mar 15 09:04:00: vmx| DICT vmplayer.searchbar = FALSE

Mar 15 09:04:00: vmx| DICT --- GLOBAL SETTINGS

Mar 15 09:04:00: vmx| Msg_Hint: msg.hostw32.wrongNTversion (shown)

Mar 15 09:04:00: vmx| WSSCAN: reserved mem (in MB) min=32 max=1916 recommended=1916

Mar 15 09:04:00: vmx| hostMem=2044 maxAllowedAll=4096 maxAllowedVM=3600

Mar 15 09:04:00: vmx| totOverhead=16

Mar 15 09:04:00: vmx| WSSCAN: used rec mem (in MB) 1916

Mar 15 09:04:00: vmx| WSSCAN: Overhead 268529 paged 7072 nonpaged 4096 maxFBSize

Mar 15 09:05:47: vmx| WSSCAN 1 2 364156 418923 490496 364156 50 0

Mar 15 09:06:12: vmx| LICENSE: Running in restricted mode

Mar 15 09:06:13: vmx| STATDECLGROUP stats Root "" null

Mar 15 09:06:18: vmx| Host CPUID features: version 0x6f8 id1.edx 0xbfebfbff id1.ecx 0xe3bd id81.edx 0x20100000 id81.ecx 0x1

Mar 15 09:06:18: vmx| CPU.cpuFeatures = 0xb971ff37

Mar 15 09:06:18: vmx| CPUID after masking: version 0x6f8 id1.edx 0xfebfbff id1.ecx 0xa215 id81.edx 0x20100800 id81.ecx 0x1 id88.ecx 0x0

Mar 15 09:06:18: vmx| CPU.cpuFeatures = 0x9871ff33

Mar 15 09:06:18: vmx| APIC: Local APIC at 0xfee00000

Mar 15 09:06:26: vmx| KHZEstimate 2667000

Mar 15 09:06:26: vmx| MHZEstimate 2667

Mar 15 09:06:26: vmx| NumVCPUs 1

Mar 15 09:06:27: vmx| UUID: location-UUID is 56 4d b0 02 64 04 7d 6e-41 9d c0 3e 79 37 fa d2

Mar 15 09:07:07: vmx| UUID: canonical path is c:\users\erik.sweet\documents\my virtual machines\erik2\erik2.vmx

Mar 15 09:07:07: vmx| UUID: location-UUID is 56 4d 45 3b b9 1f 9d f7-8e db 22 79 20 62 bc 40

Mar 15 09:07:07: vmx| MM: Using partialmap, 262144 pages AC 0 CE 1 TM 0 DOHU 0

Mar 15 09:07:07: vmx| MStat: Creating Stat vm.uptime

Mar 15 09:07:08: vmx| DISK: OPEN scsi0:0 'C:\Users\Erik.SWEET\Documents\My Virtual Machines\Erik2\Erik2.vmdk' persistent R\[(null)]

Mar 15 09:07:15: vmx| DISKLIB-DSCPTR: Opened : "Erik2.vmdk" (0xa)

Mar 15 09:07:15: vmx| DISKLIB-LINK : Opened 'C:\Users\Erik.SWEET\Documents\My Virtual Machines\Erik2\Erik2.vmdk' (0xa): monolithicSparse, 52454274 sectors / 25613 Mb.

Mar 15 09:07:15: vmx| DISKLIB-LIB : Opened "C:\Users\Erik.SWEET\Documents\My Virtual Machines\Erik2\Erik2.vmdk" (flags 0xa).

Mar 15 09:07:15: vmx| DISK: OPEN 'C:\Users\Erik.SWEET\Documents\My Virtual Machines\Erik2\Erik2.vmdk' Geo (3265/255/63) BIOS Geo (3265/255/63) freeSpace=78568Mb

Mar 15 09:07:15: vmx| TimeTracker host to guest rate conversion 11497649977400 @ 2667000000Hz -> 11497649977400 @ 2667000000Hz

Mar 15 09:07:15: vmx| TimeTracker host to guest rate conversion ((x * 2147483648) >> 31) + 0

Mar 15 09:07:15: vmx| SCSI0: UNTAGGED commands will be converted to ORDER tags.

Mar 15 09:07:15: vmx| DISKUTIL: scsi0:0 : capacity=52454274

Mar 15 09:07:15: vmx| DISKUTIL: scsi0:0 : geometry=3265/255/63

Mar 15 09:07:15: vmx| MStat: Creating Stat vm.heartbeat

Mar 15 09:07:15: vmx| DISKUTIL: scsi0:0 : toolsVersion = 6434

Mar 15 09:07:15: vmx| TOOLS INSTALL initializing state to IDLE on power on.

Mar 15 09:07:18: vmx| USB: Initializing UHCI host controller

Mar 15 09:07:18: vmx| USB: Initializing USB Generic backend

Mar 15 09:07:18: vmx| USBGW: Connected to HCMON version 1.2

Mar 15 09:07:18: vmx| VLANCE: send cluster threshold is 80, size = 2 recalcInterval is 2 ticks

Mar 15 09:07:18: vmx| Ethernet0 MAC Address: 00:0c:29:f0:05:a9

Mar 15 09:07:18: vmx| VMXNET: send cluster threshold is 80, size = 2 recalcInterval is 2 ticks, dontClusterSize is 128

Mar 15 09:07:18: vmx| E1000: checksum cycles/kB: C=1087 asm=758

Mar 15 09:07:18: vmx| VirtualProtect MapProtectMem(0x3f0000,0x1000) returns 0x1e7

Mar 15 09:07:18: vmx| Can't read-only monitor pages from 0x003F0000 to 0x003F1000

Mar 15 09:07:18: vmx| VirtualProtect MapProtectMem(0x3f2000,0x0) returns 0x57

Mar 15 09:07:18: vmx| Can't read-only monitor pages from 0x003F2000 to 0x003F2000

Mar 15 09:07:18: vmx| CPT: Restoring checkpoint C:\Users\Erik.SWEET\Documents\My Virtual Machines\Erik2\Erik2.vmss

Mar 15 09:07:18: vmx| DUMPER: restoring checkpoint version 8

Mar 15 09:07:18: vmx| checkpointCPUID: cpt vendor GenuntelineI host vendor GenuntelineI

Mar 15 09:07:18: vmx| checkpointCPUID: cpt family 06 model 0f6 host family 06 model 0f6

Mar 15 09:07:18: vmx| checkpointCPUID: cpt id1.ecx a215 id1.edx febfbff id81.ecx 1 id81.edx 20100000

Mar 15 09:07:18: vmx| checkpointCPUID: host id1.ecx a215 id1.edx febfbff id81.ecx 1 id81.edx 20100000

Mar 15 09:07:19: vmx| DUMPER: item eip \[0,-1] in group cpu not used

Mar 15 09:07:19: vmx| restoring Snapshot

Mar 15 09:07:19: vmx| DUMPER: item cfgFile \[-1,-1] in group Snapshot not used

Mar 15 09:07:19: vmx| DUMPER: item nvramFile \[-1,-1] in group Snapshot not used

Mar 15 09:07:19: vmx| restoring memory

Mar 15 09:07:19: vmx| Mapped mainmem as pageable

Mar 15 09:07:19: vmx| Msg_Reset:

Mar 15 09:07:19: vmx| \----

Mar 15 09:07:19: vmx| Opened paging file C:\Users\Erik.SWEET\Documents\My Virtual Machines\Erik2\Erik2.vmem

Mar 15 09:07:19: vmx| Lazy Restore with prefetch of 204 MB

Mar 15 09:07:19: vmx| Msg_Hint: msg.mainmem.lazyResumeHint (shown)

Mar 15 09:07:23: MMPageWalker| Entering MM LazySave Loop

Mar 15 09:09:46: vmx| restoring MStats

Mar 15 09:09:46: vmx| restoring pic

Mar 15 09:09:46: vmx| restoring TimeTracker

Mar 15 09:09:46: vmx| TimeTracker host to guest rate conversion 11497649977400 @ 2667000000Hz -> 133309851776729 @ 2667000000Hz

Mar 15 09:09:46: vmx| TimeTracker host to guest rate conversion ((x * 2147483648) >> 31) + 121812201799329

Mar 15 09:09:46: vmx| restoring Floppy

Mar 15 09:09:46: vmx| restoring GuestMsg

Mar 15 09:09:46: vmx| restoring GuestRpc

Mar 15 09:09:46: vmx| restoring PCI

Mar 15 09:09:46: vmx| restoring Cs440bx

Mar 15 09:09:46: vmx| DUMPER: item drb \[1,-1] in group Cs440bx not used

Mar 15 09:09:46: vmx| DUMPER: item drb \[0,-1] in group Cs440bx not used

Mar 15 09:09:46: vmx| restoring scsi0:0

Mar 15 09:09:46: vmx| restoring ide1:0

Mar 15 09:09:46: vmx| restoring ide0:1

Mar 15 09:09:46: vmx| restoring ide0:0

Mar 15 09:09:46: vmx| restoring vide

Mar 15 09:09:46: vmx| DUMPER: Item error \[0, -1] not found

Mar 15 09:09:46: vmx| DUMPER: Item features \[0, -1] not found

Mar 15 09:09:46: vmx| DUMPER: Item sectorCount \[0, -1] not found

Mar 15 09:09:46: vmx| DUMPER: Item sectorNum \[0, -1] not found

Mar 15 09:09:46: vmx| DUMPER: Item cylinder \[0, -1] not found

Mar 15 09:09:46: vmx| DUMPER: Item head \[0, -1] not found

Mar 15 09:09:46: vmx| DUMPER: Item powerMode \[0, -1] not found

Mar 15 09:09:46: vmx| DUMPER: Item useLBA \[0, -1] not found

Mar 15 09:09:46: vmx| DUMPER: Item status \[0, -1] not found

Mar 15 09:09:46: vmx| DUMPER: Item command \[0, -1] not found

Mar 15 09:09:46: vmx| DUMPER: Item control \[0, -1] not found

Mar 15 09:09:46: vmx| DUMPER: Item numTransferred \[0, -1] not found

Mar 15 09:09:46: vmx| DUMPER: BlockItem monbuf \[0, -1] not found

Mar 15 09:09:46: vmx| DUMPER: BlockItem monbuf \[1, -1] not found

Mar 15 09:09:46: vmx| restoring SCSI0

Mar 15 09:09:46: vmx| restoring Timer

Mar 15 09:09:46: vmx| restoring Tools

Mar 15 09:09:46: vmx| restoring Tools Install

Mar 15 09:09:46: vmx| TOOLS INSTALL setting state to 0 on restore.

Mar 15 09:09:46: vmx| restoring MKSVMX

Mar 15 09:09:46: vmx| DUMPER: item imageData \[-1,-1] in group MKSVMX not used

Mar 15 09:09:46: vmx| restoring VGA

Mar 15 09:09:46: vmx| restoring DMA

Mar 15 09:09:46: vmx| restoring BackdoorAPM

Mar 15 09:09:46: vmx| restoring CMOS

Mar 15 09:09:46: vmx| restoring FlashRam

Mar 15 09:09:46: vmx| restoring A20

Mar 15 09:09:46: vmx| restoring Keyboard

Mar 15 09:09:46: vmx| restoring backdoorAbsMouse

Mar 15 09:09:46: vmx| restoring SIO

Mar 15 09:09:46: vmx| restoring serial0

Mar 15 09:09:46: vmx| restoring serial1

Mar 15 09:09:46: vmx| restoring serial2

Mar 15 09:09:46: vmx| restoring serial3

Mar 15 09:09:46: vmx| restoring parallel0

Mar 15 09:09:46: vmx| restoring parallel1

Mar 15 09:09:46: vmx| restoring parallel2

Mar 15 09:09:46: vmx| restoring SVGA

Mar 15 09:09:46: vmx| SVGA phys sizes: 2360x1770x32 offset 0 fb 0x01000000

Mar 15 09:09:46: vmx| SVGA compatible geometry on checkpoint restore

Mar 15 09:09:46: vmx| SVGA old geometry 2360x1770, max size = 16777216

Mar 15 09:09:46: vmx| SVGA new geometry 2360x1770, max size = 16777216

Mar 15 09:09:47: vmx| restoring USB

Mar 15 09:09:47: vmx| restoring Ethernet0

Mar 15 09:09:47: vmx| Checkpointed with active intr:18

Mar 15 09:09:47: vmx| restoring sound

Mar 15 09:09:47: vmx| restoring monitorLate

Mar 15 09:09:47: vmx| restoring devices

Mar 15 09:09:47: vmx| DUMPER: Dumper_EndRestore: update hdr magic

Mar 15 09:09:47: vmx| TOOLS received request in VMX to set option 'enableDnD' -> '1'

Mar 15 09:09:47: vmx| VMX_PowerOn: ModuleTable_PowerOn = 1

Mar 15 09:09:47: mks| Async MKS thread is alive

Mar 15 09:09:47: mks| Connecting to window system.

Mar 15 09:09:49: vcpu-0| APIC: version = 0x14, max LVT = 5

Mar 15 09:09:49: vcpu-0| APIC: LDR = 0x1000000, DFR = 0xffffffff

Mar 15 09:09:49: vmx| DnD rpc already set to 1

Mar 15 09:09:49: vcpu-0| PShare: enabled 1, scanRate 32, checkRate 16

Mar 15 09:09:49: vcpu-0| guestCpuFeatures = 0x9871ff33

Mar 15 09:09:49: vcpu-0| Init modules.

Mar 15 09:09:49: vcpu-0| Cpt monModules(3).

Mar 15 09:09:49: vcpu-0| Done Cpt monModules(3).

Mar 15 09:09:54: vmx| VNET: Notification enabled for Ethernet0

Mar 15 09:09:54: vcpu-0| sz=3104736

Mar 15 09:09:54: vcpu-0| vmm32 initialized: Releasebuild-34682. cflags: 0x00000002.01883000.00000054

Mar 15 09:09:54: vcpu-0| Restoring VM

Mar 15 09:09:54: vmx| VMMouse: restored checkpoint state was absolute, notifying MKS

Mar 15 09:09:54: vmx| MKS switching absolute mouse on

Mar 15 09:09:54: vcpu-0| Sync monModules(4).

Mar 15 09:09:54: vcpu-0| Done Sync monModules(4).

Mar 15 09:09:54: vcpu-0| SVGA: Registering IOSpace at 0x1420 (0x0)

Mar 15 09:09:54: vcpu-0| SVGA: Registering MemSpace at 0xf0000000(0xf0000000) and 0xfe000000(0xfe000000)

Mar 15 09:10:02: vcpu-0| GuestRpc: Channel 1, registration number 6, guest application toolbox.

Mar 15 09:10:02: vcpu-0| TOOLS Reducing idleLoopSpinUS to 500us

Mar 15 09:10:02: vcpu-0| TOOLS sending 'OS_Resume' (4) state change request

Mar 15 09:10:02: vcpu-0| DISKUTIL: scsi0:0 : toolsVersion = 6434

Mar 15 09:10:08: vcpu-0| Guest: toolbox: Version: build-34685

Mar 15 09:10:08: vcpu-0| TOOLS setting the tools version to '6434'

Mar 15 09:10:08: vcpu-0| TOOLS unified loop capability requested by 'toolbox'; now sending options via TCLO

Mar 15 09:12:26: vmx| VMXVmdbCbVmGuestResolutionSetJob: Sending rpcMsg = Resolution_Set 1152 872

Mar 15 09:12:34: vmx| SCSI0:0: Command WRITE(10) took 1.101 seconds (ok)

Mar 15 09:12:41: vmx| SCSI0:0: Command WRITE(10) took 4.375 seconds (ok)

Mar 15 09:12:41: vmx| SCSI0:0: Command WRITE(10) took 4.373 seconds (ok)

Mar 15 09:12:41: vmx| SCSI0:0: Command WRITE(10) took 4.375 seconds (ok)

Mar 15 09:12:41: vmx| SCSI0:0: Command WRITE(10) took 4.373 seconds (ok)

Mar 15 09:12:42: vmx| SCSI0:0: Command WRITE(10) took 4.621 seconds (ok)

Mar 15 09:12:47: vcpu-0| MKS Backdoor get pointer: first time, notify tools are running

Mar 15 09:12:50: vmx| SCSI0:0: Command WRITE(10) took 1.233 seconds (ok)

Mar 15 09:14:06: mks| SVGA: Using extended FIFO: Caps 0x00000007, Flags 0x00000000

Mar 15 09:14:24: vcpu-0| TOOLS unified loop capability requested by 'toolbox-dnd'; now sending options via TCLO

Mar 15 09:14:32: vcpu-0| GuestRpc: Channel 2, registration number 6, guest application toolbox-dnd.

Mar 15 09:14:32: vcpu-0| DISKUTIL: scsi0:0 : toolsVersion = 6434

Mar 15 09:14:40: vcpu-0| TOOLS received request in VMX to set option 'enableDnD' -> '1'

Mar 15 09:14:40: vmx| DnD rpc already set to 1

Mar 15 09:17:58: vmx| POLL device deleted

Mar 15 09:17:59: vmx| POLL device deleted

Mar 15 09:18:00: vmx| POLL device deleted

Mar 15 09:18:01: vmx| POLL device deleted

Mar 15 09:18:02: vmx| POLL device deleted

Mar 15 09:18:03: vmx| POLL device deleted

Mar 15 09:18:04: vmx| POLL device deleted

Mar 15 09:18:05: vmx| POLL device deleted

Mar 15 09:18:06: vmx| POLL device deleted

Mar 15 09:18:07: vmx| POLL device deleted

Mar 15 09:18:08: vmx| POLL device deleted

Mar 15 09:18:29: MMPageWalker| Exit MM Lazy Loop

Mar 15 09:18:29: MMPageWalker| Finished pending checkpoint op

Mar 15 09:19:25: vmx| POLL device deleted

Mar 15 09:19:26: vmx| POLL device deleted