Re: Receiving 'Stale Data' error upon restart of V...

jamal182 · ‎06-24-2007

I have just updated my VMWare Workstation to 6.0 and when i ran my rebuild and restart script i received an error saying 'Stale data' upon restart of one of the VMs.

The rebuild/restart script basically stops all my VMs rolls them back to a baseline snapshot and starts them up again.

The VM causing the problem is a windows server 2003 machine with nothing else on it just a fresh install which is updated and patched.

Any suggestions why this error is coming up for this VM? and any solutions would be handy thanks.

admin · ‎06-24-2007

Host OS? What's the script? What are the contents of the UI and VMX logs?

jamal182 · ‎06-24-2007

windows XP, script is just a batch file that runs from windows scheduler stops, reverts an d starts VMs. here's the vmx log

Jun 25 11:15:19.147: vmx| Log for VMware Workstation pid=2644 version=6.0.0 build=build-45731 option=Release

Jun 25 11:15:19.147: vmx| Hostname=fileserver.kwinana.ksc.net.au

Jun 25 11:15:19.147: vmx| Command line: "C:\Program Files\VMware\VMware Workstation\bin\vmware-vmx.exe" "-#" "name=VMware Workstation;version=6.0.0;licensename=VMware Workstation for Win32;licenseversion=6.0 build-45731;" "-@" "pipe=
.\pipe\vmxd812bc6118c9d2ae;" "E:\Virtual Machines\WebStage\Windows Server 2003 Standard Edition.vmx"

Jun 25 11:15:19.178: vmx| UI Connecting to pipe '
.\pipe\vmxd812bc6118c9d2ae' with user '(null)'

Jun 25 11:15:19.256: vmx| HD: host version is 5.2.3790

Jun 25 11:15:19.256: vmx| HD: addr 8083f526

Jun 25 11:15:19.272: vmx| HD: 808ab8e8, 8089c400

Jun 25 11:15:19.272: vmx| CPU #0 TSC = 17307095080716

Jun 25 11:15:19.272: vmx| CPU #1 TSC = 17307095079778

Jun 25 11:15:19.272: vmx| TSC delta 938

Jun 25 11:15:19.272: vmx| VMMon_GetkHzEstimate: Calculated 2793030 kHz

Jun 25 11:15:19.272: vmx| CPUID[0] vendor: GenuntelineI

Jun 25 11:15:19.272: vmx| CPUID[0] level 00000000, 0: 0x00000003 0x756e6547 0x6c65746e 0x49656e69

Jun 25 11:15:19.272: vmx| CPUID[0] level 00000001, 0: 0x00000f41 0x00020800 0x0000441d 0xbfebfbff

Jun 25 11:15:19.272: vmx| CPUID[0] level 00000002, 0: 0x605b5001 0x00000000 0x00000000 0x007c7040

Jun 25 11:15:19.272: vmx| CPUID[0] level 00000003, 0: 0x00000000 0x00000000 0x00000000 0x00000000

Jun 25 11:15:19.272: vmx| CPUID[0] level 80000000, 0: 0x80000008 0x00000000 0x00000000 0x00000000

Jun 25 11:15:19.272: vmx| CPUID[0] level 80000001, 0: 0x00000000 0x00000000 0x00000000 0x00100000

Jun 25 11:15:19.272: vmx| CPUID[0] level 80000002, 0: 0x20202020 0x20202020 0x20202020 0x6e492020

Jun 25 11:15:19.272: vmx| CPUID[0] level 80000003, 0: 0x286c6574 0x50202952 0x69746e65 0x52286d75

Jun 25 11:15:19.272: vmx| CPUID[0] level 80000004, 0: 0x20342029 0x20555043 0x30382e32 0x007a4847

Jun 25 11:15:19.272: vmx| CPUID[0] level 80000005, 0: 0x00000000 0x00000000 0x00000000 0x00000000

Jun 25 11:15:19.272: vmx| CPUID[0] level 80000006, 0: 0x00000000 0x00000000 0x04006040 0x00000000

Jun 25 11:15:19.272: vmx| CPUID[0] level 80000007, 0: 0x00000000 0x00000000 0x00000000 0x00000000

Jun 25 11:15:19.272: vmx| CPUID[0] level 80000008, 0: 0x00002024 0x00000000 0x00000000 0x00000000

Jun 25 11:15:19.272: vmx| CPUID[1] vendor: GenuntelineI

Jun 25 11:15:19.272: vmx| CPUID[1] level 00000000, 0: 0x00000003 0x756e6547 0x6c65746e 0x49656e69

Jun 25 11:15:19.272: vmx| CPUID[1] level 00000001, 0: 0x00000f41 0x01020800 0x0000441d 0xbfebfbff

Jun 25 11:15:19.272: vmx| CPUID[1] level 00000002, 0: 0x605b5001 0x00000000 0x00000000 0x007c7040

Jun 25 11:15:19.272: vmx| CPUID[1] level 00000003, 0: 0x00000000 0x00000000 0x00000000 0x00000000

Jun 25 11:15:19.272: vmx| CPUID[1] level 80000000, 0: 0x80000008 0x00000000 0x00000000 0x00000000

Jun 25 11:15:19.272: vmx| CPUID[1] level 80000001, 0: 0x00000000 0x00000000 0x00000000 0x00100000

Jun 25 11:15:19.272: vmx| CPUID[1] level 80000002, 0: 0x20202020 0x20202020 0x20202020 0x6e492020

Jun 25 11:15:19.272: vmx| CPUID[1] level 80000003, 0: 0x286c6574 0x50202952 0x69746e65 0x52286d75

Jun 25 11:15:19.272: vmx| CPUID[1] level 80000004, 0: 0x20342029 0x20555043 0x30382e32 0x007a4847

Jun 25 11:15:19.272: vmx| CPUID[1] level 80000005, 0: 0x00000000 0x00000000 0x00000000 0x00000000

Jun 25 11:15:19.272: vmx| CPUID[1] level 80000006, 0: 0x00000000 0x00000000 0x04006040 0x00000000

Jun 25 11:15:19.272: vmx| CPUID[1] level 80000007, 0: 0x00000000 0x00000000 0x00000000 0x00000000

Jun 25 11:15:19.272: vmx| CPUID[1] level 80000008, 0: 0x00002024 0x00000000 0x00000000 0x00000000

Jun 25 11:15:19.272: vmx| hostCPUID vendor: GenuntelineI

Jun 25 11:15:19.272: vmx| hostCPUID level 00000000, 0: 0x00000003 0x756e6547 0x6c65746e 0x49656e69

Jun 25 11:15:19.272: vmx| hostCPUID level 00000001, 0: 0x00000f41 0x00020800 0x0000441d 0xbfebfbff

Jun 25 11:15:19.272: vmx| hostCPUID level 00000002, 0: 0x605b5001 0x00000000 0x00000000 0x007c7040

Jun 25 11:15:19.272: vmx| hostCPUID level 00000003, 0: 0x00000000 0x00000000 0x00000000 0x00000000

Jun 25 11:15:19.272: vmx| hostCPUID level 80000000, 0: 0x80000008 0x00000000 0x00000000 0x00000000

Jun 25 11:15:19.272: vmx| hostCPUID level 80000001, 0: 0x00000000 0x00000000 0x00000000 0x00100000

Jun 25 11:15:19.272: vmx| hostCPUID level 80000002, 0: 0x20202020 0x20202020 0x20202020 0x6e492020

Jun 25 11:15:19.272: vmx| hostCPUID level 80000003, 0: 0x286c6574 0x50202952 0x69746e65 0x52286d75

Jun 25 11:15:19.272: vmx| hostCPUID level 80000004, 0: 0x20342029 0x20555043 0x30382e32 0x007a4847

Jun 25 11:15:19.272: vmx| hostCPUID level 80000005, 0: 0x00000000 0x00000000 0x00000000 0x00000000

Jun 25 11:15:19.272: vmx| hostCPUID level 80000006, 0: 0x00000000 0x00000000 0x04006040 0x00000000

Jun 25 11:15:19.272: vmx| hostCPUID level 80000007, 0: 0x00000000 0x00000000 0x00000000 0x00000000

Jun 25 11:15:19.272: vmx| hostCPUID level 80000008, 0: 0x00002024 0x00000000 0x00000000 0x00000000

Jun 25 11:15:19.272: vmx| CPUID Maximum Physical Address Bits supported across all CPUs : 36

Jun 25 11:15:19.319: vmx| Host ACPI: can't find SRAT

Jun 25 11:15:19.319: vmx| Host: SRAT tables not found in memory

Jun 25 11:15:19.490: vmx| ACL_InitCapabilities: current IPC thread

Jun 25 11:15:19.490: vmx| ACL_InitCapabilities: done

Jun 25 11:15:19.490: vmx| changing directory to E:\Virtual Machines\WebStage\.

Jun 25 11:15:19.490: vmx| Config file: E:\Virtual Machines\WebStage\Windows Server 2003 Standard Edition.vmx

Jun 25 11:15:19.537: vmx| VMXVmdb_LoadRawConfig: Loading raw config

Jun 25 11:15:19.615: vmx| VMXVmdbCbVmVmxExecState: Exec state change requested to state poweredOn without reset

Jun 25 11:15:19.615: vmx| PowerOn

Jun 25 11:15:19.615: vmx| Host: WIN32 highest NUMA node 0

Jun 25 11:15:19.615: vmx| Host: WIN32 NUMA node 0, CPU mask 0x0000000000000003

Jun 25 11:15:19.631: vmx| VMXVmdb_LoadRawConfig: Loading raw config

Jun 25 11:15:19.647: vmx| HOST Windows version 5.2, build 3790, platform 2, "Service Pack 1", SMP

Jun 25 11:15:19.647: vmx| DICT --- USER PREFERENCES

Jun 25 11:15:19.647: vmx| DICT pref.eula.0.appName = VMware Workstation

Jun 25 11:15:19.647: vmx| DICT pref.eula.0.buildNumber = 29772

Jun 25 11:15:19.647: vmx| DICT pref.eula.size = 2

Jun 25 11:15:19.647: vmx| DICT pref.autoFitFullScreen = fitHostToGuest

Jun 25 11:15:19.647: vmx| DICT pref.view.navBar.type = favorites

Jun 25 11:15:19.647: vmx| DICT pref.mruDest0.present = FALSE

Jun 25 11:15:19.647: vmx| DICT pref.mruDest0.destString =

Jun 25 11:15:19.647: vmx| DICT pref.mruDest0.user =

Jun 25 11:15:19.647: vmx| DICT pref.mruDest1.present = FALSE

Jun 25 11:15:19.647: vmx| DICT pref.mruDest1.destString =

Jun 25 11:15:19.647: vmx| DICT pref.mruDest1.user =

Jun 25 11:15:19.647: vmx| DICT pref.mruDest2.present = FALSE

Jun 25 11:15:19.647: vmx| DICT pref.mruDest2.destString =

Jun 25 11:15:19.647: vmx| DICT pref.mruDest2.user =

Jun 25 11:15:19.647: vmx| DICT pref.mruDest3.present = FALSE

Jun 25 11:15:19.647: vmx| DICT pref.mruDest3.destString =

Jun 25 11:15:19.647: vmx| DICT pref.mruDest3.user =

Jun 25 11:15:19.647: vmx| DICT pref.mruDest4.present = FALSE

Jun 25 11:15:19.647: vmx| DICT pref.mruDest4.destString =

Jun 25 11:15:19.647: vmx| DICT pref.mruDest4.user =

Jun 25 11:15:19.647: vmx| DICT pref.mruDest5.present = FALSE

Jun 25 11:15:19.647: vmx| DICT pref.mruDest5.destString =

Jun 25 11:15:19.647: vmx| DICT pref.mruDest5.user =

Jun 25 11:15:19.647: vmx| DICT pref.mruDest6.present = FALSE

Jun 25 11:15:19.647: vmx| DICT pref.mruDest6.destString =

Jun 25 11:15:19.647: vmx| DICT pref.mruDest6.user =

Jun 25 11:15:19.647: vmx| DICT pref.mruDest7.present = FALSE

Jun 25 11:15:19.647: vmx| DICT pref.mruDest7.destString =

Jun 25 11:15:19.647: vmx| DICT pref.mruDest7.user =

Jun 25 11:15:19.647: vmx| DICT pref.view.toolbars.power.items.count = 7

Jun 25 11:15:19.647: vmx| DICT pref.view.toolbars.power.item0.id = POWER_OFF

Jun 25 11:15:19.647: vmx| DICT pref.view.toolbars.power.item1.id = SUSPEND

Jun 25 11:15:19.647: vmx| DICT pref.view.toolbars.power.item2.id = POWER_ON

Jun 25 11:15:19.647: vmx| DICT pref.view.toolbars.power.item3.id = RESET

Jun 25 11:15:19.647: vmx| DICT pref.view.toolbars.power.item4.id = \_SEP_

Jun 25 11:15:19.647: vmx| DICT pref.view.toolbars.power.item5.id = PREVIEW_PLAYER

Jun 25 11:15:19.647: vmx| DICT pref.view.toolbars.power.item6.id = PREVIEW_WS

Jun 25 11:15:19.647: vmx| DICT pref.view.toolbars.proj.items.count = 4

Jun 25 11:15:19.647: vmx| DICT pref.view.toolbars.proj.item0.id = ADD_VM

Jun 25 11:15:19.647: vmx| DICT pref.view.toolbars.proj.item1.id = POLICIES

Jun 25 11:15:19.647: vmx| DICT pref.view.toolbars.proj.item2.id = PKG_NEW

Jun 25 11:15:19.647: vmx| DICT pref.view.toolbars.proj.item3.id = EDIT_SETTINGS

Jun 25 11:15:19.647: vmx| DICT pref.view.toolbars.view.items.count = 6

Jun 25 11:15:19.647: vmx| DICT pref.view.toolbars.view.item0.id = FAVORITES

Jun 25 11:15:19.647: vmx| DICT pref.view.toolbars.view.item1.id = FULL_SCREEN

Jun 25 11:15:19.647: vmx| DICT pref.view.toolbars.view.item2.id = QUICK_SWITCH

Jun 25 11:15:19.647: vmx| DICT pref.view.toolbars.view.item3.id = \_SEP_

Jun 25 11:15:19.647: vmx| DICT pref.view.toolbars.view.item4.id = SUMMARY

Jun 25 11:15:19.647: vmx| DICT pref.view.toolbars.view.item5.id = CONSOLE

Jun 25 11:15:19.647: vmx| DICT pref.view.toolbars.snapshot.items.count = 3

Jun 25 11:15:19.647: vmx| DICT pref.view.toolbars.snapshot.item0.id = SNAPSHOT

Jun 25 11:15:19.647: vmx| DICT pref.view.toolbars.snapshot.item1.id = REVERT

Jun 25 11:15:19.647: vmx| DICT pref.view.toolbars.snapshot.item2.id = SNAPSHOT_MANAGER

Jun 25 11:15:19.647: vmx| DICT hint.guestos.xp = FALSE

Jun 25 11:15:19.647: vmx| DICT hint.mainmem.lazySnapshotHint = FALSE

Jun 25 11:15:19.647: vmx| DICT hint.keyboardHook.hookTimeout = FALSE

Jun 25 11:15:19.647: vmx| DICT webUpdate.checkLast = 1182699222

Jun 25 11:15:19.647: vmx| DICT hint.mks.hostAlphaCursor = FALSE

Jun 25 11:15:19.647: vmx| DICT pref.view.toolbars.menu.position.ix = 0

Jun 25 11:15:19.647: vmx| DICT pref.view.toolbars.menu.position.newLine = TRUE

Jun 25 11:15:19.647: vmx| DICT pref.view.toolbars.power.position.ix = 1

Jun 25 11:15:19.647: vmx| DICT pref.view.toolbars.power.position.newLine = TRUE

Jun 25 11:15:19.647: vmx| DICT pref.view.toolbars.power.position.length = 118

Jun 25 11:15:19.647: vmx| DICT pref.view.toolbars.snapshot.position.ix = 2

Jun 25 11:15:19.647: vmx| DICT pref.view.toolbars.snapshot.position.length = 87

Jun 25 11:15:19.647: vmx| DICT pref.view.toolbars.view.position.ix = 3

Jun 25 11:15:19.647: vmx| DICT pref.view.toolbars.view.position.length = 153

Jun 25 11:15:19.647: vmx| DICT pref.placement.left = 197

Jun 25 11:15:19.647: vmx| DICT pref.placement.top = 20

Jun 25 11:15:19.647: vmx| DICT pref.placement.right = 1361

Jun 25 11:15:19.647: vmx| DICT pref.placement.bottom = 1014

Jun 25 11:15:19.647: vmx| DICT pref.tip.startup = FALSE

Jun 25 11:15:19.647: vmx| DICT pref.tip.index = 1

Jun 25 11:15:19.647: vmx| DICT pref.ws.currentObj.path = /vm/#c456e2ea72eb6bd8/

Jun 25 11:15:19.647: vmx| DICT pref.ws.currentObj.type = home

Jun 25 11:15:19.647: vmx| DICT pref.ws.openedObj0.present = TRUE

Jun 25 11:15:19.647: vmx| DICT pref.ws.openedObj0.name = Home

Jun 25 11:15:19.647: vmx| DICT pref.ws.openedObj1.present = TRUE

Jun 25 11:15:19.647: vmx| DICT pref.ws.openedObj1.type = vm

Jun 25 11:15:19.647: vmx| DICT pref.ws.openedObj1.path = /vm/#c456e2ea72eb6bd8/

Jun 25 11:15:19.647: vmx| DICT pref.ws.openedObj1.file = E:\Virtual Machines\Win2000Server\Windows 2000 Server.vmx

Jun 25 11:15:19.647: vmx| DICT pref.ws.openedObj1.name = Windows 2000 Server

Jun 25 11:15:19.647: vmx| DICT pref.ws.openedObj.maxNum = 0

Jun 25 11:15:19.647: vmx| DICT pref.ws.openedObj2.present = TRUE

Jun 25 11:15:19.647: vmx| DICT pref.ws.openedObj2.name = Oracle 10g Staging

Jun 25 11:15:19.647: vmx| DICT pref.ws.openedObj3.present = TRUE

Jun 25 11:15:19.647: vmx| DICT pref.ws.openedObj3.type = vm

Jun 25 11:15:19.647: vmx| DICT pref.ws.openedObj3.path = /vm/#85be1f5aef7a28ae/

Jun 25 11:15:19.647: vmx| DICT pref.ws.openedObj3.file = C:\Virtual Machines\WebStage\Windows Server 2003 Standard Edition.vmx

Jun 25 11:15:19.647: vmx| DICT pref.ws.openedObj3.name = WebStage

Jun 25 11:15:19.647: vmx| DICT hint.mainmem.lazyResumeHint = FALSE

Jun 25 11:15:19.647: vmx| DICT pref.ws.openedObj2.type = vm

Jun 25 11:15:19.647: vmx| DICT pref.ws.openedObj2.path = /vm/#0e0890b9653170dc/

Jun 25 11:15:19.647: vmx| DICT pref.ws.openedObj2.file = C:\Virtual Machines\Windows Server 2003 Standard Edition\Windows Server 2003 Standard Edition.vmx

Jun 25 11:15:19.647: vmx| DICT pref.view.navBar = FALSE

Jun 25 11:15:19.647: vmx| DICT pref.snapshotManager.left = 343

Jun 25 11:15:19.647: vmx| DICT pref.snapshotManager.top = 189

Jun 25 11:15:19.647: vmx| DICT pref.snapshotManager.right = 893

Jun 25 11:15:19.647: vmx| DICT pref.snapshotManager.bottom = 669

Jun 25 11:15:19.647: vmx| DICT webUpdate.lastCheck.status = done_updates

Jun 25 11:15:19.647: vmx| DICT pref.eula.1.appName = VMware Workstation

Jun 25 11:15:19.647: vmx| DICT pref.eula.1.buildNumber = 34685

Jun 25 11:15:19.647: vmx| DICT hint.disk.fragmented.low = FALSE

Jun 25 11:15:19.647: vmx| DICT pref.ws.openedObj0.type = home

Jun 25 11:15:19.647: vmx| DICT pref.ws.openedObj0.path =

Jun 25 11:15:19.647: vmx| DICT pref.ws.openedObj0.file =

Jun 25 11:15:19.647: vmx| DICT pref.eula.2.appName = VMware Workstation

Jun 25 11:15:19.647: vmx| DICT pref.eula.2.buildNumber = 45731

Jun 25 11:15:19.647: vmx| DICT pref.mruATS0.present = FALSE

Jun 25 11:15:19.647: vmx| DICT pref.mruATS0.atsString =

Jun 25 11:15:19.647: vmx| DICT pref.mruATS0.domain =

Jun 25 11:15:19.647: vmx| DICT pref.mruATS0.user =

Jun 25 11:15:19.647: vmx| DICT pref.mruATS0.secure = FALSE

Jun 25 11:15:19.647: vmx| DICT pref.mruATS0.port = 0

Jun 25 11:15:19.647: vmx| DICT pref.mruATS1.present = FALSE

Jun 25 11:15:19.647: vmx| DICT pref.mruATS1.atsString =

Jun 25 11:15:19.647: vmx| DICT pref.mruATS1.domain =

Jun 25 11:15:19.647: vmx| DICT pref.mruATS1.user =

Jun 25 11:15:19.647: vmx| DICT pref.mruATS1.secure = FALSE

Jun 25 11:15:19.647: vmx| DICT pref.mruATS1.port = 0

Jun 25 11:15:19.647: vmx| DICT pref.mruATS2.present = FALSE

Jun 25 11:15:19.647: vmx| DICT pref.mruATS2.atsString =

Jun 25 11:15:19.647: vmx| DICT pref.mruATS2.domain =

Jun 25 11:15:19.647: vmx| DICT pref.mruATS2.user =

Jun 25 11:15:19.647: vmx| DICT pref.mruATS2.secure = FALSE

Jun 25 11:15:19.647: vmx| DICT pref.mruATS2.port = 0

Jun 25 11:15:19.647: vmx| DICT pref.mruATS3.present = FALSE

Jun 25 11:15:19.647: vmx| DICT pref.mruATS3.atsString =

Jun 25 11:15:19.647: vmx| DICT pref.mruATS3.domain =

Jun 25 11:15:19.647: vmx| DICT pref.mruATS3.user =

Jun 25 11:15:19.647: vmx| DICT pref.mruATS3.secure = FALSE

Jun 25 11:15:19.647: vmx| DICT pref.mruATS3.port = 0

Jun 25 11:15:19.647: vmx| DICT pref.mruATS4.present = FALSE

Jun 25 11:15:19.647: vmx| DICT pref.mruATS4.atsString =

Jun 25 11:15:19.647: vmx| DICT pref.mruATS4.domain =

Jun 25 11:15:19.647: vmx| DICT pref.mruATS4.user =

Jun 25 11:15:19.647: vmx| DICT pref.mruATS4.secure = FALSE

Jun 25 11:15:19.647: vmx| DICT pref.mruATS4.port = 0

Jun 25 11:15:19.647: vmx| DICT pref.mruATS5.present = FALSE

Jun 25 11:15:19.647: vmx| DICT pref.mruATS5.atsString =

Jun 25 11:15:19.647: vmx| DICT pref.mruATS5.domain =

Jun 25 11:15:19.647: vmx| DICT pref.mruATS5.user =

Jun 25 11:15:19.647: vmx| DICT pref.mruATS5.secure = FALSE

Jun 25 11:15:19.647: vmx| DICT pref.mruATS5.port = 0

Jun 25 11:15:19.647: vmx| DICT pref.mruATS6.present = FALSE

Jun 25 11:15:19.647: vmx| DICT pref.mruATS6.atsString =

Jun 25 11:15:19.647: vmx| DICT pref.mruATS6.domain =

Jun 25 11:15:19.647: vmx| DICT pref.mruATS6.user =

Jun 25 11:15:19.647: vmx| DICT pref.mruATS6.secure = FALSE

Jun 25 11:15:19.647: vmx| DICT pref.mruATS6.port = 0

Jun 25 11:15:19.647: vmx| DICT pref.mruATS7.present = FALSE

Jun 25 11:15:19.647: vmx| DICT pref.mruATS7.atsString =

Jun 25 11:15:19.647: vmx| DICT pref.mruATS7.domain =

Jun 25 11:15:19.647: vmx| DICT pref.mruATS7.user =

Jun 25 11:15:19.647: vmx| DICT pref.mruATS7.secure = FALSE

Jun 25 11:15:19.647: vmx| DICT pref.mruATS7.port = 0

Jun 25 11:15:19.647: vmx| DICT pref.view.toolbars.list = \_SEP_,POWER_OFF,SUSPEND,POWER_ON,RESET,SNAPSHOT,REVERT,SNAPSHOT_MANAGER,FAVORITES,QUICK_SWITCH,FULL_SCREEN,SUMMARY,APPLIANCE,CONSOLE,QUICK_MINIMIZE,FILE_EXIT,VPLAY_PLAY,VPLAY_RECORD,VPLAY_STOP

Jun 25 11:15:19.647: vmx| DICT --- USER DEFAULTS

Jun 25 11:15:19.647: vmx| DICT --- HOST DEFAULTS

Jun 25 11:15:19.647: vmx| DICT prefvmx.useRecommendedLockedMemSize = TRUE

Jun 25 11:15:19.647: vmx| DICT --- SITE DEFAULTS

Jun 25 11:15:19.647: vmx| DICT prefvmx.useRecommendedLockedMemSize = TRUE

Jun 25 11:15:19.647: vmx| DICT --- COMMAND LINE

Jun 25 11:15:19.647: vmx| DICT gui.available = TRUE

Jun 25 11:15:19.647: vmx| DICT --- CONFIGURATION

Jun 25 11:15:19.647: vmx| DICT config.version = 8

Jun 25 11:15:19.647: vmx| DICT virtualHW.version = 4

Jun 25 11:15:19.647: vmx| DICT scsi0.present = TRUE

Jun 25 11:15:19.647: vmx| DICT scsi0.virtualDev = lsilogic

Jun 25 11:15:19.647: vmx| DICT memsize = 384

Jun 25 11:15:19.647: vmx| DICT MemAllowAutoScaleDown = FALSE

Jun 25 11:15:19.647: vmx| DICT scsi0:0.present = TRUE

Jun 25 11:15:19.647: vmx| DICT scsi0:0.fileName = Windows Server 2003 Standard Edition-000007.vmdk

Jun 25 11:15:19.647: vmx| DICT ide1:0.present = TRUE

Jun 25 11:15:19.647: vmx| DICT ide1:0.fileName = auto detect

Jun 25 11:15:19.647: vmx| DICT ide1:0.deviceType = cdrom-raw

Jun 25 11:15:19.647: vmx| DICT floppy0.fileName = A:

Jun 25 11:15:19.647: vmx| DICT ethernet0.present = TRUE

Jun 25 11:15:19.647: vmx| DICT usb.present = TRUE

Jun 25 11:15:19.647: vmx| DICT sound.present = TRUE

Jun 25 11:15:19.647: vmx| DICT sound.virtualDev = es1371

Jun 25 11:15:19.647: vmx| DICT sound.fileName = -1

Jun 25 11:15:19.647: vmx| DICT sound.autodetect = TRUE

Jun 25 11:15:19.647: vmx| DICT displayName = WebStage

Jun 25 11:15:19.647: vmx| DICT guestOS = winnetstandard

Jun 25 11:15:19.647: vmx| DICT nvram = Windows Server 2003 Standard Edition.nvram

Jun 25 11:15:19.647: vmx| DICT scsi0:0.redo =

Jun 25 11:15:19.647: vmx| DICT ide1:0.startConnected = TRUE

Jun 25 11:15:19.647: vmx| DICT ethernet0.addressType = generated

Jun 25 11:15:19.647: vmx| DICT uuid.location = 56 4d ca bd 63 3d 10 21-3c 6d 62 73 42 9a 28 7c

Jun 25 11:15:19.647: vmx| DICT uuid.bios = 56 4d ca bd 63 3d 10 21-3c 6d 62 73 42 9a 28 7c

Jun 25 11:15:19.647: vmx| DICT ide1:0.autodetect = TRUE

Jun 25 11:15:19.647: vmx| DICT ethernet0.generatedAddress = 00:0c:29:9a:28:7c

Jun 25 11:15:19.647: vmx| DICT ethernet0.generatedAddressOffset = 0

Jun 25 11:15:19.647: vmx| DICT tools.syncTime = FALSE

Jun 25 11:15:19.647: vmx| DICT workingDir =

Jun 25 11:15:19.647: vmx| DICT checkpoint.vmState.readOnly = FALSE

Jun 25 11:15:19.647: vmx| DICT checkpoint.vmState =

Jun 25 11:15:19.647: vmx| DICT extendedConfigFile = Windows Server 2003 Standard Edition.vmxf

Jun 25 11:15:19.647: vmx| DICT ethernet0.txbw.limit = 10000

Jun 25 11:15:19.647: vmx| DICT ethernet0.rxbw.limit = 10000

Jun 25 11:15:19.647: vmx| DICT ethernet0.connectionType = bridged

Jun 25 11:15:19.647: vmx| DICT gui.powerOnAtStartup = TRUE

Jun 25 11:15:19.647: vmx| DICT fileSearchPath = .;E:\Virtual Machines\WebStage

Jun 25 11:15:19.647: vmx| DICT sound.startConnected = FALSE

Jun 25 11:15:19.647: vmx| DICT gui.exitAtPowerOff = TRUE

Jun 25 11:15:19.647: vmx| DICT isolation.tools.hgfs.disable = TRUE

Jun 25 11:15:19.647: vmx| DICT virtualHW.productCompatibility = hosted

Jun 25 11:15:19.647: vmx| DICT tools.upgrade.policy = manual

Jun 25 11:15:19.647: vmx| DICT --- USER DEFAULTS

Jun 25 11:15:19.647: vmx| DICT --- HOST DEFAULTS

Jun 25 11:15:19.647: vmx| DICT prefvmx.useRecommendedLockedMemSize = TRUE

Jun 25 11:15:19.647: vmx| DICT --- SITE DEFAULTS

Jun 25 11:15:19.647: vmx| DICT prefvmx.useRecommendedLockedMemSize = TRUE

Jun 25 11:15:19.647: vmx| DICT --- GLOBAL SETTINGS

Jun 25 11:15:19.678: vmx| hostCpuFeatures = 0x800001f0

Jun 25 11:15:19.678: vmx| hostNumPerfCounters = 26

Jun 25 11:15:19.694: vmx| WSSCAN: reserved mem (in MB) min=32 max=2936 recommended=2936

Jun 25 11:15:19.694: vmx| hostMem=3064 maxAllowedAll=-1 maxAllowedVM=8192

Jun 25 11:15:19.694: vmx| totOverhead=16

Jun 25 11:15:19.694: vmx| WSSCAN: used rec mem (in MB) 2936

Jun 25 11:15:19.694: vmx| WSSCAN: Overhead 106301 paged 6263 nonpaged 4096 maxFBSize

Jun 25 11:15:19.725: vmx| WSSCAN 4 74760 700163 700163 751616 733149 50 197398

Jun 25 11:15:19.725: vmx| LICENSE using: 'HKEY_LOCAL_MACHINE\SOFTWARE\VMware, Inc.\VMware Workstation\License.ws.6.0.200610'

Jun 25 11:15:19.756: vmx| LOG failed to remove stats32-2 failed: The system cannot find the file specified

Jun 25 11:15:19.756: vmx| LOG failed to remove stats64-2 failed: The system cannot find the file specified

Jun 25 11:15:19.756: vmx| guestCpuFeatures = 0x800001f0

Jun 25 11:15:19.756: vmx| APIC: Host Local APIC at 0xfee00000

Jun 25 11:15:19.772: vmx| vmm32-modules: \[vmm.vmm32 .data:0x2b000 .sdata:0x2c000 .statvars:0x2d000 .peer:0x2e000 .shared:0x54000 .bss:0x65000 .rodata:0x6c000 .text:0x77000 .kstatvars:0x3000 ,mmu-pae.vmm32 .rodata:0x75d6c .data:0x2bdb0 .peer:0x52928 .shared:0x639f0 .bss:0x6abc0 .text:0xc996c .comment:0x40000cf0 .statvars:0x2000 .kstatvars:0x2000 .scb:0x400035a0 .shared_meta:0x400034b0 .peer_meta:0x40000ba0 ,sharedmmu-none.vmm32 .text:0xd3834 .comment:0x40000dec ,pv-none.vmm32 .data:0x2bdcc .peer:0x1000 .shared:0x64300 .bss:0x6b120 .text:0xd3850 .comment:0x40000dfe .statvars:0x1000 .kstatvars:0x1000 .shared_meta:0x40003720 ,hv-none.vmm32 .data:0x2bdd0 .peer:0x1000 .shared:0x1000 .bss:0x6b19c .text:0xd3950 .comment:0x40000e46 .statvars:0x1000 .kstatvars:0x1000 , .rodata:0x75d7c ]

Jun 25 11:15:19.787: vmx| KHZEstimate 2793030

Jun 25 11:15:19.787: vmx| MHZEstimate 2793

Jun 25 11:15:19.787: vmx| NumVCPUs 1

Jun 25 11:15:19.787: vmx| guestCPUID vendor: GenuntelineI

Jun 25 11:15:19.787: vmx| guestCPUID level 00000000, 0: 0x00000003 0x756e6547 0x6c65746e 0x49656e69

Jun 25 11:15:19.787: vmx| guestCPUID level 00000001, 0: 0x00000f48 0x00010800 0x00000011 0x0febbbff

Jun 25 11:15:19.787: vmx| guestCPUID level 00000002, 0: 0x605b5001 0x00000000 0x00000000 0x007c7040

Jun 25 11:15:19.787: vmx| guestCPUID level 00000003, 0: 0x00000000 0x00000000 0x00000000 0x00000000

Jun 25 11:15:19.787: vmx| guestCPUID level 80000000, 0: 0x80000008 0x00000000 0x00000000 0x00000000

Jun 25 11:15:19.787: vmx| guestCPUID level 80000001, 0: 0x00000000 0x00000000 0x00000000 0x00100800

Jun 25 11:15:19.787: vmx| guestCPUID level 80000002, 0: 0x20202020 0x20202020 0x20202020 0x6e492020

Jun 25 11:15:19.787: vmx| guestCPUID level 80000003, 0: 0x286c6574 0x50202952 0x69746e65 0x52286d75

Jun 25 11:15:19.787: vmx| guestCPUID level 80000004, 0: 0x20342029 0x20555043 0x30382e32 0x007a4847

Jun 25 11:15:19.787: vmx| guestCPUID level 80000005, 0: 0x00000000 0x00000000 0x00000000 0x00000000

Jun 25 11:15:19.787: vmx| guestCPUID level 80000006, 0: 0x00000000 0x00000000 0x04006040 0x00000000

Jun 25 11:15:19.787: vmx| guestCPUID level 80000007, 0: 0x00000000 0x00000000 0x00000000 0x00000000

Jun 25 11:15:19.787: vmx| guestCPUID level 80000008, 0: 0x00002024 0x00000000 0x00000000 0x00000000

Jun 25 11:15:19.787: vmx| PShare: enabled 1, scanRate 32, checkRate 16

Jun 25 11:15:19.787: vmx| UUID: location-UUID is 56 4d c7 95 13 09 9a c3-a4 42 18 4b 71 25 9f f5

Jun 25 11:15:19.787: vmx| UUID: canonical path is e:\virtual machines\webstage\windows server 2003 standard edition.vmx

Jun 25 11:15:19.787: vmx| UUID: location-UUID is 56 4d ca bd 63 3d 10 21-3c 6d 62 73 42 9a 28 7c

Jun 25 11:15:19.787: vmx| MM: Using partialmap, 98304 pages AC 0 CE 1 TM 0 DOHU 0

Jun 25 11:15:19.787: vmx| UUID: canonical path is e:\virtual machines\webstage\windows server 2003 standard edition.vmx

Jun 25 11:15:19.787: vmx| UUID: location-UUID is 56 4d ca bd 63 3d 10 21-3c 6d 62 73 42 9a 28 7c

Jun 25 11:15:19.818: vmx| MM: using 'E:\Virtual Machines\WebStage\564dcabd-633d-1021-3c6d-6273429a287c.vmem' as a paging file

Jun 25 11:15:19.818: vmx| Msg_Reset:

Jun 25 11:15:19.818: vmx| \----

Jun 25 11:15:19.818: vmx| Opened paging file E:\Virtual Machines\WebStage\564dcabd-633d-1021-3c6d-6273429a287c.vmem

Jun 25 11:15:19.818: vmx| Mapped mainmem as pageable

Jun 25 11:15:19.818: vmx| MStat: Creating Stat vm.uptime

Jun 25 11:15:19.818: vmx| MStat: Creating Stat vm.suspendTime

Jun 25 11:15:19.818: vmx| MStat: Creating Stat vm.powerOnTimeStamp

Jun 25 11:15:19.834: vmx| VMXVmdb_LoadRawConfig: Loading raw config

Jun 25 11:15:19.850: vmx| DISK: OPEN scsi0:0 'E:\Virtual Machines\WebStage\Windows Server 2003 Standard Edition-000007.vmdk' persistent R\[(null)]

Jun 25 11:15:20.022: vmx| DISKLIB-DSCPTR: Opened : "Windows Server 2003 Standard Edition-000007.vmdk" (0xa)

Jun 25 11:15:20.022: vmx| DISKLIB-LINK : Opened 'E:\Virtual Machines\WebStage\Windows Server 2003 Standard Edition-000007.vmdk' (0xa): monolithicSparse, 41943040 sectors / 20480 Mb.

Jun 25 11:15:20.037: vmx| DISKLIB-DSCPTR: Opened : "Windows Server 2003 Standard Edition-000009.vmdk" (0xe)

Jun 25 11:15:20.037: vmx| DISKLIB-LINK : Opened 'E:\Virtual Machines\WebStage\Windows Server 2003 Standard Edition-000009.vmdk' (0xe): monolithicSparse, 41943040 sectors / 20480 Mb.

Jun 25 11:15:20.068: vmx| DISKLIB-DSCPTR: Opened : "Windows Server 2003 Standard Edition-000008.vmdk" (0xe)

Jun 25 11:15:20.068: vmx| DISKLIB-LINK : Opened 'E:\Virtual Machines\WebStage\Windows Server 2003 Standard Edition-000008.vmdk' (0xe): monolithicSparse, 41943040 sectors / 20480 Mb.

Jun 25 11:15:20.115: vmx| DISKLIB-DSCPTR: Opened : "Windows Server 2003 Standard Edition-000005.vmdk" (0xe)

Jun 25 11:15:20.115: vmx| DISKLIB-LINK : Opened 'E:\Virtual Machines\WebStage\Windows Server 2003 Standard Edition-000005.vmdk' (0xe): monolithicSparse, 41943040 sectors / 20480 Mb.

Jun 25 11:15:20.162: vmx| DISKLIB-DSCPTR: Opened : "Windows Server 2003 Standard Edition-000006.vmdk" (0xe)

Jun 25 11:15:20.162: vmx| DISKLIB-LINK : Opened 'E:\Virtual Machines\WebStage\Windows Server 2003 Standard Edition-000006.vmdk' (0xe): monolithicSparse, 41943040 sectors / 20480 Mb.

Jun 25 11:15:20.178: vmx| DISKLIB-DSCPTR: Opened : "Windows Server 2003 Standard Edition-000004.vmdk" (0xe)

Jun 25 11:15:20.178: vmx| DISKLIB-LINK : Opened 'E:\Virtual Machines\WebStage\Windows Server 2003 Standard Edition-000004.vmdk' (0xe): monolithicSparse, 41943040 sectors / 20480 Mb.

Jun 25 11:15:20.193: vmx| DISKLIB-DSCPTR: Opened : "Windows Server 2003 Standard Edition-000001.vmdk" (0xe)

Jun 25 11:15:20.193: vmx| DISKLIB-LINK : Opened 'E:\Virtual Machines\WebStage\Windows Server 2003 Standard Edition-000001.vmdk' (0xe): monolithicSparse, 41943040 sectors / 20480 Mb.

Jun 25 11:15:20.225: vmx| DISKLIB-DSCPTR: Opened : "Windows Server 2003 Standard Edition.vmdk" (0xe)

Jun 25 11:15:20.225: vmx| DISKLIB-LINK : Opened 'E:\Virtual Machines\WebStage\Windows Server 2003 Standard Edition.vmdk' (0xe): monolithicSparse, 41943040 sectors / 20480 Mb.

Jun 25 11:15:20.225: vmx| DISKLIB-LIB : Opened "E:\Virtual Machines\WebStage\Windows Server 2003 Standard Edition-000007.vmdk" (flags 0xa). 17D1014

Jun 25 11:15:20.225: vmx| DiskGetGeometry: Reading of disk partition table

Jun 25 11:15:20.256: vmx| DISK: OPEN 'E:\Virtual Machines\WebStage\Windows Server 2003 Standard Edition-000007.vmdk' Geo (2610/255/63) BIOS Geo (2610/255/63) freeSpace=104046Mb, lsilogic

Jun 25 11:15:20.256: vmx| Msg_Reset:

Jun 25 11:15:20.256: vmx| \----

Jun 25 11:15:20.256: vmx| TimeTracker host to guest rate conversion 17309849019505 @ 2793030000Hz -> 17309849019505 @ 2793030000Hz

Jun 25 11:15:20.256: vmx| TimeTracker host to guest rate conversion ((x * 2147483648) >> 31) + 0

Jun 25 11:15:20.256: vmx| USB: Initializing 'Generic' backend

Jun 25 11:15:20.256: vmx| USBGW: Connected to HCMON version 2.3

Jun 25 11:15:20.256: vmx| USB: Initializing 'Virtual Hub' backend

Jun 25 11:15:20.256: vmx| USB: Initializing 'Virtual Mouse' backend

Jun 25 11:15:20.256: vmx| MKS REMOTE Loading VNC Configuration from VM config file

Jun 25 11:15:20.256: vmx| DISKUTIL: scsi0:0 : capacity=41943040

Jun 25 11:15:20.256: vmx| DISKUTIL: scsi0:0 : geometry=2610/255/63

Jun 25 11:15:20.272: vmx| SCSI0: UNTAGGED commands will be converted to ORDER tags.

Jun 25 11:15:20.272: vmx| VLANCE: send cluster threshold is 80, size = 2 recalcInterval is 2 ticks

Jun 25 11:15:20.272: vmx| Ethernet0 MAC Address: 00:0c:29:9a:28:7c

Jun 25 11:15:20.272: vmx| MACBW: Setting TX bandwidth to 10000 Kbps

Jun 25 11:15:20.272: vmx| MACBW: Setting RX bandwidth to 10000 Kbps

Jun 25 11:15:20.272: vmx| VMXNET: send cluster threshold is 80, size = 2 recalcInterval is 2 ticks, dontClusterSize is 128

Jun 25 11:15:20.272: vmx| E1000: checksum cycles/kB: C=1183 asm=2901

Jun 25 11:15:20.272: vmx| USB: Initializing 'UHCI' host controller

Jun 25 11:15:20.272: vmx| MStat: Creating Stat vm.heartbeat

Jun 25 11:15:20.272: vmx| DISKUTIL: scsi0:0 : toolsVersion = 7238

Jun 25 11:15:20.272: vmx| TOOLS INSTALL initializing state to IDLE on power on.

Jun 25 11:15:20.272: vmx| PTSC to VMI Wallclock (nsec) 17309898914217 @ 2793030000Hz -> 1182741320000000000 @ 1000000000Hz

Jun 25 11:15:20.272: vmx| PTSC to VMI Wallclock (nsec) ((x * 3075489555) >> 33) + 1182735122465811747

Jun 25 11:15:20.272: vmx| VirtualProtect MapProtectMem(0x1cc0000,0x1000) returns 0x1e7

Jun 25 11:15:20.272: vmx| VirtualProtect MapProtectMem(0x1cc2000,0x0) returns 0x57

Jun 25 11:15:20.303: vmx| TOOLS received request in VMX to set option 'enableDnD' -> '1'

Jun 25 11:15:20.303: vmx| TOOLS received request in VMX to set option 'copypaste' -> '1'

Jun 25 11:15:20.350: vmx| VMX_PowerOn: ModuleTable_PowerOn = 1

Jun 25 11:15:20.350: vmx| VMX setting maximum IPC write buffers to 0 packets, 0 bytes

Jun 25 11:15:20.350: mks| Async MKS thread is alive

Jun 25 11:15:20.365: vmx| DnD rpc already set to 1

Jun 25 11:15:20.365: vmx| DnD CopyPasteRegisterRpc already set to 1

Jun 25 11:15:20.365: vcpu-0| APIC: version = 0x14, max LVT = 5

Jun 25 11:15:20.365: vcpu-0| APIC: LDR = 0x2000000, DFR = 0xffffffff

Jun 25 11:15:20.397: vcpu-0| guestCpuFeatures = 0x800001f0

Jun 25 11:15:20.397: vcpu-0| Init modules.

Jun 25 11:15:20.412: vcpu-0| CPU reset: hard

Jun 25 11:15:20.412: vmx| VNET: Notification enabled for Ethernet0

Jun 25 11:15:21.428: vmx| FLOPPYLIB-LIB : Sync: syserror Incorrect function.

Jun 25 11:15:21.428: vmx| CDROM: Using autodetect backend 😧 for ide1:0.

Jun 25 11:15:21.428: vmx| CDROM: Connecting ide1:0 to 'D:'. img=0 raw=1 excl=0 remote=0

Jun 25 11:15:21.522: mks| Connecting to window system.

Jun 25 11:15:21.522: mks| KHBKL: Unable to parse keystring at: ''

Jun 25 11:15:21.522: mks| Ignoring update request in VGA_Expose (mode change pending).

Jun 25 11:15:21.568: vcpu-0| sz=3055584

Jun 25 11:15:21.584: vcpu-0| vmm32 initialized: Releasebuild-45731. cflags: 0x20000002.18000000.54000600.00000002

Jun 25 11:15:21.584: vcpu-0| MonitorInitNumaUnmapVMM32

Jun 25 11:15:22.037: vcpu-0| SVGA: Registering MemSpace at 0xf0000000(0x0) and 0xe8000000(0x0)

Jun 25 11:15:22.053: vcpu-0| SVGA: Unregistering MemSpace at 0xf0000000(0xf0000000) and 0xe8000000(0xe8000000)

Jun 25 11:15:22.084: vcpu-0| SVGA: Registering MemSpace at 0xf0000000(0xf0000000) and 0xe8000000(0xe8000000)

Jun 25 11:15:22.100: vcpu-0| SVGA: Unregistering MemSpace at 0xf0000000(0xf0000000) and 0xe8000000(0xe8000000)

Jun 25 11:15:22.100: vcpu-0| SVGA: Registering IOSpace at 0x14c0

Jun 25 11:15:22.100: vcpu-0| SVGA: Registering MemSpace at 0xf0000000(0xf0000000) and 0xe8000000(0xe8000000)

Jun 25 11:15:22.240: mks| Ignoring update request in VGA_Expose (mode change pending).

Jun 25 11:15:22.271: mks| Ignoring update request in VGA_Expose (mode change pending).

Jun 25 11:15:22.287: vmx| USB: Found device \[name:Hewlett-Packard\ Printer vid:03f0 pid:4f11 path:1/3/1 speed:full family:vendor,printer]

Jun 25 11:15:22.287: vmx| USB: Found device \[name:Cypress\ Removable\ Disk vid:04b4 pid:6830 path:1/4/6 speed:high family:storage]

Jun 25 11:15:22.287: vmx| VMXVmdbLoadUsbDevices: New set of 2 USB devices

Jun 25 11:15:22.975: vcpu-0| CDROM-WIN: 'D:' Length 24 MaxTransferLength 131072 MaxPhysPages 32

Jun 25 11:15:22.975: vcpu-0| CDROM-WIN: AlignMask 0x1 !AsyncEvent !TaggedQ ScanUp PIO

Jun 25 11:15:23.693: vcpu-0| DISKUTIL: scsi0:0 : geometry=2610/255/63

Jun 25 11:15:25.162: vcpu-0| BIOS-UUID is 56 4d ca bd 63 3d 10 21-3c 6d 62 73 42 9a 28 7c

Jun 25 11:15:25.615: vcpu-0| DISKUTIL: scsi0:0 : toolsVersion = 7238

Jun 25 11:15:25.631: mks| Ignoring update request in VGA_Expose (mode change pending).

Jun 25 11:15:26.693: vcpu-0| FLOPPYLIB-LIB : Win32Ioctl: syserror The device is not ready.

Jun 25 11:15:26.771: vcpu-0| FLOPPYLIB-LIB : Win32Ioctl: syserror The device is not ready.

Jun 25 11:15:26.787: vcpu-0| FLOPPYLIB-LIB : Win32Ioctl: syserror The device is not ready.

Jun 25 11:15:26.802: vcpu-0| FLOPPYLIB-LIB : Win32Ioctl: syserror The device is not ready.

Jun 25 11:15:26.865: vcpu-0| FLOPPYLIB-LIB : Win32Ioctl: syserror The device is not ready.

Jun 25 11:15:26.881: vcpu-0| FLOPPYLIB-LIB : Win32Ioctl: syserror The device is not ready.

Jun 25 11:15:26.896: vcpu-0| FLOPPYLIB-LIB : Win32Ioctl: syserror The device is not ready.

Jun 25 11:15:26.959: vcpu-0| FLOPPYLIB-LIB : Win32Ioctl: syserror The device is not ready.

Jun 25 11:15:26.974: vcpu-0| FLOPPYLIB-LIB : Win32Ioctl: syserror The device is not ready.

Jun 25 11:15:26.990: vcpu-0| FLOPPYLIB-LIB : Win32Ioctl: syserror The device is not ready.

Jun 25 11:15:27.052: vcpu-0| FLOPPYLIB-LIB : Win32Ioctl: syserror The device is not ready.

Jun 25 11:15:27.427: vcpu-0| Unknown int 10h func 0x2000

Jun 25 11:15:34.692: mks| Ignoring update request in VGA_Expose (mode change pending).

Jun 25 11:15:34.724: mks| Ignoring update request in VGA_Expose (mode change pending).

Jun 25 11:15:35.786: vcpu-0| UHCI: Global Reset

Jun 25 11:15:36.130: vcpu-0| SVGA: Unregistering IOSpace at 0x14c0

Jun 25 11:15:36.130: vcpu-0| SVGA: Unregistering MemSpace at 0xf0000000(0xf0000000) and 0xe8000000(0xe8000000)

Jun 25 11:15:36.130: vcpu-0| SVGA: Registering IOSpace at 0x14c0

Jun 25 11:15:36.130: vcpu-0| SVGA: Registering MemSpace at 0xf0000000(0xf0000000) and 0xe8000000(0xe8000000)

Jun 25 11:15:37.333: vcpu-0| SCSI-DEV0:0: Unsupported command READ BUFFER issued. --ok

Jun 25 11:15:37.348: vcpu-0| SCSI DEVICE (scsi0:0): INQUIRY request with EVPD set

Jun 25 11:15:37.411: vcpu-0| SCSI DEVICE (scsi0:0): MODE SENSE(6) for unsupported page 0x1c

Jun 25 11:15:45.973: vmx| VIDE: ATAPI DMA 0xad Failed: key 0x2, asc 0x3a, ascq 0x0

Jun 25 11:15:45.988: vcpu-0| VNET: Notification enabled for Ethernet0

Jun 25 11:15:46.004: vcpu-0| Guest: VMXNET: Initialization completed successfully. Version 2.0

Jun 25 11:15:46.223: vcpu-0| SVGA: Unregistering IOSpace at 0x14c0

Jun 25 11:15:46.223: vcpu-0| SVGA: Unregistering MemSpace at 0xf0000000(0xf0000000) and 0xe8000000(0xe8000000)

Jun 25 11:15:46.238: vcpu-0| SVGA: Registering IOSpace at 0x14c0

Jun 25 11:15:46.238: vcpu-0| SVGA: Registering MemSpace at 0xf0000000(0xf0000000) and 0xe8000000(0xe8000000)

Jun 25 11:15:46.410: vcpu-0| Guest OS = 0x5008

Jun 25 11:15:46.457: vcpu-0| UHCI: Global Reset

Jun 25 11:15:47.785: vmx| VIDE: ATAPI DMA 0x25 Failed: key 0x2, asc 0x3a, ascq 0x0

Jun 25 11:15:47.801: vmx| VIDE: ATAPI DMA 0x25 Failed: key 0x2, asc 0x3a, ascq 0x0

Jun 25 11:15:47.816: vmx| VIDE: ATAPI DMA 0x25 Failed: key 0x2, asc 0x3a, ascq 0x0

Jun 25 11:15:49.348: vmx| SCSI0:0: Command READ(10) took 1.342 seconds (ok)

Jun 25 11:15:49.348: vmx| SCSI0:0: Command READ(10) took 1.343 seconds (ok)

Jun 25 11:15:49.348: vmx| SCSI0:0: Command READ(10) took 1.338 seconds (ok)

Jun 25 11:15:49.348: vmx| SCSI0:0: Command READ(10) took 1.339 seconds (ok)

Jun 25 11:15:49.348: vmx| SCSI0:0: Command READ(10) took 1.338 seconds (ok)

Jun 25 11:15:49.363: vmx| SCSI0:0: Command READ(10) took 1.068 seconds (ok)

Jun 25 11:15:49.363: vmx| SCSI0:0: Command READ(10) took 1.065 seconds (ok)

Jun 25 11:15:49.363: vmx| SCSI0:0: Command READ(10) took 1.064 seconds (ok)

Jun 25 11:15:49.363: vmx| SCSI0:0: Command READ(10) took 1.032 seconds (ok)

Jun 25 11:15:52.175: vmx| SCSI0:0: Command READ(10) took 1.312 seconds (ok)

Jun 25 11:15:52.488: vmx| SCSI0:0: Command READ(10) took 1.708 seconds (ok)

Jun 25 11:15:52.488: vmx| SCSI0:0: Command READ(10) took 1.709 seconds (ok)

Jun 25 11:15:52.550: vmx| SCSI0:0: Command READ(10) took 1.766 seconds (ok)

Jun 25 11:15:52.566: vmx| SCSI0:0: Command READ(10) took 1.738 seconds (ok)

Jun 25 11:15:52.613: vmx| SCSI0:0: Command READ(10) took 1.802 seconds (ok)

Jun 25 11:15:52.629: vmx| SCSI0:0: Command READ(10) took 1.822 seconds (ok)

Jun 25 11:15:52.629: vmx| SCSI0:0: Command READ(10) took 1.748 seconds (ok)

Jun 25 11:15:52.644: vmx| SCSI0:0: Command READ(10) took 1.745 seconds (ok)

Jun 25 11:15:52.644: vmx| SCSI0:0: Command READ(10) took 1.746 seconds (ok)

Jun 25 11:15:52.644: vmx| SCSI0:0: Command READ(10) took 1.798 seconds (ok)

Jun 25 11:15:52.644: vmx| SCSI0:0: Command READ(10) took 1.808 seconds (ok)

Jun 25 11:15:53.535: vmx| SCSI0:0: Command READ(10) took 1.263 seconds (ok)

Jun 25 11:15:53.535: vmx| SCSI0:0: Command READ(10) took 1.247 seconds (ok)

Jun 25 11:15:53.628: vmx| SCSI0:0: Command READ(10) took 2.851 seconds (ok)

Jun 25 11:15:53.675: vmx| SCSI0:0: Command READ(10) took 1.721 seconds (ok)

Jun 25 11:15:53.753: vmx| SCSI0:0: Command READ(10) took 1.195 seconds (ok)

Jun 25 11:15:54.050: vmx| SCSI0:0: Command READ(10) took 1.170 seconds (ok)

Jun 25 11:15:54.050: vmx| SCSI0:0: Command READ(10) took 1.169 seconds (ok)

Jun 25 11:15:54.207: vmx| SCSI0:0: Command READ(10) took 1.399 seconds (ok)

Jun 25 11:15:54.207: vmx| SCSI0:0: Command READ(10) took 1.398 seconds (ok)

Jun 25 11:15:54.269: vmx| SCSI0:0: Command READ(10) took 1.624 seconds (ok)

Jun 25 11:15:54.441: vmx| SCSI0:0: Command READ(10) took 2.184 seconds (ok)

Jun 25 11:15:54.441: vmx| SCSI0:0: Command READ(10) took 2.179 seconds (ok)

Jun 25 11:15:54.457: vmx| SCSI0:0: Command READ(10) took 1.997 seconds (ok)

Jun 25 11:15:54.457: vmx| SCSI0:0: Command READ(10) took 1.972 seconds (ok)

Jun 25 11:15:54.457: vmx| SCSI0:0: Command READ(10) took 1.952 seconds (ok)

Jun 25 11:15:54.472: vmx| SCSI0:0: Command READ(10) took 1.878 seconds (ok)

Jun 25 11:15:54.472: vmx| SCSI0:0: Command READ(10) took 1.867 seconds (ok)

Jun 25 11:15:54.472: vmx| SCSI0:0: Command READ(10) took 1.873 seconds (ok)

Jun 25 11:15:54.519: vmx| SCSI0:0: Command READ(10) took 1.047 seconds (ok)

Jun 25 11:15:54.566: vmx| SCSI0:0: Command READ(10) took 1.065 seconds (ok)

Jun 25 11:15:54.597: vmx| SCSI0:0: Command READ(10) took 1.117 seconds (ok)

Jun 25 11:15:54.660: vmx| SCSI0:0: Command READ(10) took 2.006 seconds (ok)

Jun 25 11:15:54.660: vmx| SCSI0:0: Command READ(10) took 2.003 seconds (ok)

Jun 25 11:15:54.660: vmx| SCSI0:0: Command READ(10) took 1.850 seconds (ok)

Jun 25 11:15:54.691: vmx| SCSI0:0: Command READ(10) took 1.416 seconds (ok)

Jun 25 11:15:54.691: vmx| SCSI0:0: Command READ(10) took 1.081 seconds (ok)

Jun 25 11:15:54.691: vmx| SCSI0:0: Command READ(10) took 1.067 seconds (ok)

Jun 25 11:15:57.831: mks| MKS remote display status changed, enabling remote optimizations

Jun 25 11:16:00.034: mks| HostOps hideCursor before defineCursor!

Jun 25 11:16:05.721: vcpu-0| GuestRpc: Channel 0, registration number 1, guest application tools-hgfs.

Jun 25 11:16:05.721: vcpu-0| DISKUTIL: scsi0:0 : toolsVersion = 7238

Jun 25 11:16:17.674: vmx| VIDE: ATAPI DMA 0x25 Failed: key 0x2, asc 0x3a, ascq 0x0

Jun 25 11:16:17.767: vmx| VIDE: ATAPI DMA 0x25 Failed: key 0x2, asc 0x3a, ascq 0x0

Jun 25 11:16:24.126: vcpu-0| GuestRpc: Channel 1, registration number 1, guest application toolbox.

Jun 25 11:16:24.126: vcpu-0| TOOLS Reducing idleLoopSpinUS to 500us

Jun 25 11:16:24.126: vcpu-0| DISKUTIL: scsi0:0 : toolsVersion = 7238

Jun 25 11:16:24.126: vmx| SOCKET 1 client closed connection

Jun 25 11:16:24.126: vmx| SOCKET 2 client closed connection

Jun 25 11:16:24.142: vcpu-0| TOOLS autoupgrade protocol version 2

Jun 25 11:16:24.142: vcpu-0| TOOLS ToolsCapabilityGuestTempDirectory received 1 C:\WINDOWS\TEMP

Jun 25 11:16:24.142: vcpu-0| TOOLS setting the tools version to '7238'

Jun 25 11:16:24.236: vcpu-0| VMXVmdbGuest_GetGuestUptime failed. ret = Type mismatch

Jun 25 11:16:24.236: vcpu-0| DISKUTIL: scsi0:0 : toolsVersion = 7238

Jun 25 11:16:24.236: vcpu-0| TOOLS installed version 7238, available version 7238

Jun 25 11:16:24.236: vcpu-0| TOOLS don't need to be upgraded.

Jun 25 11:16:24.282: vcpu-0| Guest: toolbox: Version: build-45731

Jun 25 11:16:24.282: vcpu-0| TOOLS unified loop capability requested by 'toolbox'; now sending options via TCLO

Jun 25 11:17:07.451: vcpu-0| VMMouse: CMD Read ID

Jun 25 11:17:07.451: vcpu-0| MKS switching absolute mouse on

Jun 25 11:17:33.855: vmx| SOCKET 3 client closed connection

Jun 25 11:17:38.558: vmx| SOCKET 4 client closed connection

Jun 25 11:17:44.292: vcpu-0| MKS Backdoor get pointer: first time, notify tools are running

Jun 25 11:17:44.948: vcpu-0| TOOLS unified loop capability requested by 'toolbox-dnd'; now sending options via TCLO

Jun 25 11:17:44.948: vcpu-0| GuestRpc: Channel 2, registration number 1, guest application toolbox-dnd.

Jun 25 11:17:44.948: vcpu-0| DISKUTIL: scsi0:0 : toolsVersion = 7238

Jun 25 11:17:44.979: vmx| DnD rpc already set to 1

Jun 25 11:17:44.979: vmx| DnD CopyPasteRegisterRpc already set to 1

Jun 25 11:17:44.979: vmx| DnD rpc already set to 1

Jun 25 11:17:44.979: vmx| DnD CopyPasteRegisterRpc already set to 1

Jun 25 11:17:45.339: vmx| DnD rpc already set to 1

Jun 25 11:17:45.339: vmx| DnD CopyPasteRegisterRpc already set to 1

Jun 25 11:17:58.026: vmx| SOCKET 6 client closed connection

Jun 25 11:17:58.682: vmx| SOCKET 7 client closed connection

Jun 25 11:18:09.713: vmx| SOCKET 8 client closed connection

Jun 25 11:18:09.900: vmx| SOCKET 9 client closed connection

Jun 25 11:18:20.947: vmx| Stopping VCPU threads...

Jun 25 11:18:20.963: mks| Detaching from window system.

Jun 25 11:18:20.963: mks| Async MKS thread is exiting

Jun 25 11:18:20.963: vmx| TOOLS received request in VMX to set option 'enableDnD' -> '0'

Jun 25 11:18:20.963: vmx| TOOLS received request in VMX to set option 'copypaste' -> '0'

Jun 25 11:18:20.963: vmx| MKS local poweroff

Jun 25 11:18:20.978: vmx| scsi0:0: numIOs = 9738 numMergedIOs = 1005 numSplitIOs = 375 (27.2%)

Jun 25 11:18:20.994: vmx| AIOWIN32: asyncOps=11086 syncOps=72 bufSize=280Kb delayed=2672 fixed=483 sgOp=9158 sgOn=1

Jun 25 11:18:21.072: vmx| WORKER: asyncOps=346 maxActiveOps=1 maxPending=0 maxCompleted=0

Jun 25 11:18:21.541: vmx| vmdbPipe_Streams: Couldn't read

Jun 25 11:18:21.541: vmx| VMX idle exit

Jun 25 11:18:21.541: vmx| Flushing VMX VMDB connections

Jun 25 11:18:21.541: vmx| IPC_exit: disconnecting all threads

Jun 25 11:18:21.541: vmx| VMX exit (0).

Jun 25 11:18:21.541: vmx| AIOMGR-S : stat o=8 r=24 w=0 i=0 br=102400 bw=0

admin · ‎06-24-2007

What the exact contents of your script, and can you post a copy of the UI log (typically located in %TEMP%\vmware-yourUserName\vmware-yourUserName-processID; you'll have to copy it before you quit, because it's normally deleted when Workstation closes).

jamal182 · ‎06-24-2007

Script:

echo %date% %time% >> E:\NightlyBuild\VMRollback.log started

REM change the directory back to where the VM files are located

REM the next three remarks are the same for each VM to be reloaded

REM we need to stop the VM because it won't start from command line otherwise

REM then revert the VM to it's base snapshot, sleep function pauses the batch file for n seconds (10 in this case)

REM then start power on the VM

cd C:\Program Files\VMware\VMware Workstation

echo %date% %time% >> E:\NightlyBuild\VMRollback.log --- Stopping VMs

vmrun stop "E:\Virtual Machines\XP Pro SP2\Windows XP Professional.vmx"

sleep 10

vmrun stop "E:\Virtual Machines\Windows Server 2003 Standard Edition\Windows Server 2003 Standard Edition.vmx"

sleep 10

vmrun stop "E:\Virtual Machines\SQL Server 2005\Windows Server 2003 Standard Edition.vmx"

sleep 10

vmrun stop "E:\Virtual Machines\WebStage\Windows Server 2003 Standard Edition.vmx"

sleep 10

echo %date% %time% >> E:\NightlyBuild\VMRollback.log --- Completed Stopping VMs

sleep 10

echo %date% %time% >> E:\NightlyBuild\VMRollback.log --- Reverting VMs To Baseline

vmrun revertToSnapshot "E:\Virtual Machines\XP Pro SP2\Windows XP Professional.vmx" XPProBaseline

vmrun revertToSnapshot "E:\Virtual Machines\Windows Server 2003 Standard Edition\Windows Server 2003 Standard Edition.vmx" OracleBaseline

vmrun revertToSnapshot "E:\Virtual Machines\SQL Server 2005\Windows Server 2003 Standard Edition.vmx" SQLBaseline

vmrun revertToSnapshot "E:\Virtual Machines\WebStage\Windows Server 2003 Standard Edition.vmx" WebStageBaseline

echo %date% %time% >> E:\NightlyBuild\VMRollback.log --- Completed Reverting

sleep 30

echo %date% %time% >> E:\NightlyBuild\VMRollback.log --- Starting Up VMs

vmrun start "E:\Virtual Machines\XP Pro SP2\Windows XP Professional.vmx"

sleep 30

vmrun start "E:\Virtual Machines\Windows Server 2003 Standard Edition\Windows Server 2003 Standard Edition.vmx"

sleep 30

vmrun start "E:\Virtual Machines\SQL Server 2005\Windows Server 2003 Standard Edition.vmx"

sleep 30

vmrun start "E:\Virtual Machines\WebStage\Windows Server 2003 Standard Edition.vmx"

echo %date% %time% >> E:\NightlyBuild\VMRollback.log --- Completed Starting Up of VMs

sleep 10

REM Change directory to where the log file is located

REM Send the current date and time of completion to the log file

echo %date% %time% >> E:\NightlyBuild\VMRollback.log completed

jamal182 · ‎06-24-2007

can't find the UI log does it hava a particular name i could search?

admin · ‎06-25-2007

You'll have to reproduce the problem and then, as I said, copy the UI log before closing Workstation. It doesn't have a fixed name; it changes every time you run Workstation.

The file should be in a location like:

C:\Documents and Settings\yourUserName\Local Settings\Temp\vmware-yourUserName\vmware-yourUserName-pid.log[/code]

jamal182 · ‎06-25-2007

I replicated the error but no UI log file was created in the path specified. It was empty.

admin · ‎06-25-2007

Do you have the TEMP environment variable set to something else? Have you edited Workstation's config.ini file to tell it to store the UI log somewhere else?

jamal182 · ‎06-25-2007

nope the path u specified looks like the right place it would be. dunno... thanks for tryin to help me... might just to re-create the VM...

All

Receiving 'Stale Data' error upon restart of VMs