Jan 31 12:59:32: vmx| Log for VMware Server pid=2328 version=1.0.1 build=build-29996 option=Release
Jan 31 12:59:32: vmx| Command line: "C:\Programme\VMware\VMware Server\bin\vmware-vmx.exe" "-T" "querytoken" "d:\vmware\desnadc02\windows 2000 server.vmx"
Jan 31 12:59:32: vmx| vmxvmdb: Index name being generated from config file
Jan 31 12:59:32: vmx| VMXVmdbConnectServerd - Trying to discover serverd
Jan 31 12:59:32: vmx| MStat: Creating Stat system.cpuusage
Jan 31 12:59:32: vmx| MStat: Creating Stat system.ram
Jan 31 12:59:32: vmx| MStat: Creating Stat system.uptime
Jan 31 12:59:32: vmx| MStat: Creating Stat system.load
Jan 31 12:59:32: vmx| CPU #0 TSC = 161384841283108
Jan 31 12:59:32: vmx| CPU #1 TSC = 161384841282512
Jan 31 12:59:32: vmx| TSC delta 596
Jan 31 12:59:32: vmx| VMMon_GetkHzEstimate: Calculated 1993530 kHz
Jan 31 12:59:32: vmx| cpuids[0].id81.ecx = 0x0
Jan 31 12:59:32: vmx| cpuids[1].id81.ecx = 0x0
Jan 31 12:59:32: vmx| pcpu #0 CPUID numEntries=2 GenuntelineI
Jan 31 12:59:32: vmx| pcpu #0 CPUID version=0xf27 id1.edx=0xbfebfbff id1.ecx=0x400 id1.ebx=0x2080b
Jan 31 12:59:32: vmx| pcpu #0 CPUID id80.eax=80000004 id81.edx=0x0 id81.ecx=0x0
Jan 31 12:59:32: vmx| pcpu #1 CPUID numEntries=2 GenuntelineI
Jan 31 12:59:32: vmx| pcpu #1 CPUID version=0xf27 id1.edx=0xbfebfbff id1.ecx=0x400 id1.ebx=0x102080b
Jan 31 12:59:32: vmx| pcpu #1 CPUID id80.eax=80000004 id81.edx=0x0 id81.ecx=0x0
Jan 31 12:59:32: vmx| CPUID id1.edx: 0xbfebfbff id1.ecx: 0x400 id81.edx: 0 id81.ecx: 0
Jan 31 12:59:32: vmx| CPUID id88.ecx: 0 id88.edx: 0x7b7040
Jan 31 12:59:32: vmx| ACL_InitCapabilities: here 1 (bug 63252)
Jan 31 12:59:32: vmx| changing directory to d:\vmware\desnadc02\.
Jan 31 12:59:32: vmx| Config file: d:\vmware\desnadc02\windows 2000 server.vmx
Jan 31 12:59:33: vmx| VMDB: Connected to serverd
Jan 31 12:59:33: vmx| IPCHandleConnection: ReadFile failed (code 109)
Jan 31 12:59:33: vmx| VMXVmdbCbVmVmxExecState: Exec state change requested to state poweredOn without reset
Jan 31 12:59:33: vmx| PowerOn
Jan 31 12:59:33: vmx| Host ACPI: can't find SRAT
Jan 31 12:59:33: vmx| HOST Windows version 5.0, build 2195, platform 2, "Service Pack 4", SMP
Jan 31 12:59:33: vmx| DICT --- USER PREFERENCES
Jan 31 12:59:33: vmx| DICT    pref.autoFitFullScreen = fitHostToGuest
Jan 31 12:59:33: vmx| DICT     pref.view.navBar.type = favorites
Jan 31 12:59:33: vmx| DICT pref.view.toolbars.power.items.count = 7
Jan 31 12:59:33: vmx| DICT pref.view.toolbars.power.item0.id = POWER_OFF
Jan 31 12:59:33: vmx| DICT pref.view.toolbars.power.item1.id = SUSPEND
Jan 31 12:59:33: vmx| DICT pref.view.toolbars.power.item2.id = POWER_ON
Jan 31 12:59:33: vmx| DICT pref.view.toolbars.power.item3.id = RESET
Jan 31 12:59:33: vmx| DICT pref.view.toolbars.power.item4.id = _SEP_
Jan 31 12:59:33: vmx| DICT pref.view.toolbars.power.item5.id = PREVIEW_PLAYER
Jan 31 12:59:33: vmx| DICT pref.view.toolbars.power.item6.id = PREVIEW_WS
Jan 31 12:59:33: vmx| DICT     pref.mruDest0.present = TRUE
Jan 31 12:59:33: vmx| DICT  pref.mruDest0.destString = DESNNM01
Jan 31 12:59:33: vmx| DICT        pref.mruDest0.user = ZANDER.DOM\Administrator
Jan 31 12:59:33: vmx| DICT     pref.mruDest1.present = FALSE
Jan 31 12:59:33: vmx| DICT  pref.mruDest1.destString = 
Jan 31 12:59:33: vmx| DICT        pref.mruDest1.user = 
Jan 31 12:59:33: vmx| DICT     pref.mruDest2.present = FALSE
Jan 31 12:59:33: vmx| DICT  pref.mruDest2.destString = 
Jan 31 12:59:33: vmx| DICT        pref.mruDest2.user = 
Jan 31 12:59:33: vmx| DICT     pref.mruDest3.present = FALSE
Jan 31 12:59:33: vmx| DICT  pref.mruDest3.destString = 
Jan 31 12:59:33: vmx| DICT        pref.mruDest3.user = 
Jan 31 12:59:33: vmx| DICT     pref.mruDest4.present = FALSE
Jan 31 12:59:33: vmx| DICT  pref.mruDest4.destString = 
Jan 31 12:59:33: vmx| DICT        pref.mruDest4.user = 
Jan 31 12:59:33: vmx| DICT     pref.mruDest5.present = FALSE
Jan 31 12:59:33: vmx| DICT  pref.mruDest5.destString = 
Jan 31 12:59:33: vmx| DICT        pref.mruDest5.user = 
Jan 31 12:59:33: vmx| DICT     pref.mruDest6.present = FALSE
Jan 31 12:59:33: vmx| DICT  pref.mruDest6.destString = 
Jan 31 12:59:33: vmx| DICT        pref.mruDest6.user = 
Jan 31 12:59:33: vmx| DICT     pref.mruDest7.present = FALSE
Jan 31 12:59:33: vmx| DICT  pref.mruDest7.destString = 
Jan 31 12:59:33: vmx| DICT        pref.mruDest7.user = 
Jan 31 12:59:33: vmx| DICT     webUpdate.checkPeriod = weekly
Jan 31 12:59:33: vmx| DICT pref.view.toolbars.proj.items.count = 4
Jan 31 12:59:33: vmx| DICT pref.view.toolbars.proj.item0.id = ADD_VM
Jan 31 12:59:33: vmx| DICT pref.view.toolbars.proj.item1.id = POLICIES
Jan 31 12:59:33: vmx| DICT pref.view.toolbars.proj.item2.id = PKG_NEW
Jan 31 12:59:33: vmx| DICT pref.view.toolbars.proj.item3.id = EDIT_SETTINGS
Jan 31 12:59:33: vmx| DICT pref.view.toolbars.snapshot.items.count = 3
Jan 31 12:59:33: vmx| DICT pref.view.toolbars.snapshot.item0.id = SNAPSHOT
Jan 31 12:59:33: vmx| DICT pref.view.toolbars.snapshot.item1.id = REVERT
Jan 31 12:59:33: vmx| DICT pref.view.toolbars.snapshot.item2.id = SNAPSHOT_MANAGER
Jan 31 12:59:33: vmx| DICT pref.view.toolbars.view.items.count = 6
Jan 31 12:59:33: vmx| DICT pref.view.toolbars.view.item0.id = FAVORITES
Jan 31 12:59:33: vmx| DICT pref.view.toolbars.view.item1.id = FULL_SCREEN
Jan 31 12:59:33: vmx| DICT pref.view.toolbars.view.item2.id = QUICK_SWITCH
Jan 31 12:59:33: vmx| DICT pref.view.toolbars.view.item3.id = _SEP_
Jan 31 12:59:33: vmx| DICT pref.view.toolbars.view.item4.id = SUMMARY
Jan 31 12:59:33: vmx| DICT pref.view.toolbars.view.item5.id = CONSOLE
Jan 31 12:59:33: vmx| DICT pref.view.toolbars.menu.position.ix = 0
Jan 31 12:59:33: vmx| DICT pref.view.toolbars.menu.position.newLine = TRUE
Jan 31 12:59:33: vmx| DICT pref.view.toolbars.power.position.ix = 1
Jan 31 12:59:33: vmx| DICT pref.view.toolbars.power.position.newLine = TRUE
Jan 31 12:59:33: vmx| DICT pref.view.toolbars.power.position.length = 118
Jan 31 12:59:33: vmx| DICT pref.view.toolbars.snapshot.position.ix = 2
Jan 31 12:59:33: vmx| DICT pref.view.toolbars.snapshot.position.length = 87
Jan 31 12:59:33: vmx| DICT pref.view.toolbars.view.position.ix = 3
Jan 31 12:59:33: vmx| DICT pref.view.toolbars.view.position.length = 153
Jan 31 12:59:33: vmx| DICT pref.console.currentObj.path = /vm/#7fcfa7372df06fa1/
Jan 31 12:59:33: vmx| DICT pref.console.currentObj.type = vm
Jan 31 12:59:33: vmx| DICT pref.console.openedObj0.present = TRUE
Jan 31 12:59:33: vmx| DICT pref.console.openedObj0.name = DESNADC02
Jan 31 12:59:33: vmx| DICT pref.console.openedObj1.present = TRUE
Jan 31 12:59:33: vmx| DICT pref.console.openedObj1.type = home
Jan 31 12:59:33: vmx| DICT pref.console.openedObj1.path = 
Jan 31 12:59:33: vmx| DICT pref.console.openedObj1.file = 
Jan 31 12:59:33: vmx| DICT pref.console.openedObj1.name = Home
Jan 31 12:59:33: vmx| DICT pref.console.openedObj1.dest = DESNNM01
Jan 31 12:59:33: vmx| DICT pref.console.openedObj1.user = ZANDER.DOM\Administrator
Jan 31 12:59:33: vmx| DICT pref.console.openedObj.maxNum = 5
Jan 31 12:59:33: vmx| DICT              pref.cnxType = local
Jan 31 12:59:33: vmx| DICT       pref.placement.left = 135
Jan 31 12:59:33: vmx| DICT        pref.placement.top = 10
Jan 31 12:59:33: vmx| DICT      pref.placement.right = 867
Jan 31 12:59:33: vmx| DICT     pref.placement.bottom = 540
Jan 31 12:59:33: vmx| DICT pref.console.openedObj2.present = TRUE
Jan 31 12:59:33: vmx| DICT pref.console.openedObj2.name = DESNADC02
Jan 31 12:59:33: vmx| DICT pref.console.openedObj3.present = FALSE
Jan 31 12:59:33: vmx| DICT pref.console.openedObj3.type = home
Jan 31 12:59:33: vmx| DICT pref.console.openedObj3.path = /vm/#7fcfa7372df06fa1/
Jan 31 12:59:33: vmx| DICT pref.console.openedObj3.file = D:\VMWARE\DESNADC02\Windows 2000 Server.vmx
Jan 31 12:59:33: vmx| DICT pref.console.openedObj3.name = Home
Jan 31 12:59:33: vmx| DICT pref.console.openedObj0.type = vm
Jan 31 12:59:33: vmx| DICT pref.console.openedObj0.path = /vm/#1cbcfc66c36df2de/
Jan 31 12:59:33: vmx| DICT pref.console.openedObj0.file = D:\VMWare\DESNADC02\Windows 2000 Server.vmx
Jan 31 12:59:33: vmx| DICT pref.console.openedObj0.dest = DESNNM01
Jan 31 12:59:33: vmx| DICT pref.console.openedObj0.user = ZANDER.DOM\Administrator
Jan 31 12:59:33: vmx| DICT pref.console.openedObj2.type = vm
Jan 31 12:59:33: vmx| DICT pref.console.openedObj2.path = /vm/#7fcfa7372df06fa1/
Jan 31 12:59:33: vmx| DICT pref.console.openedObj2.file = D:\VMWARE\DESNADC02\Windows 2000 Server.vmx
Jan 31 12:59:33: vmx| DICT pref.console.openedObj4.present = FALSE
Jan 31 12:59:33: vmx| DICT pref.console.openedObj4.type = vm
Jan 31 12:59:33: vmx| DICT pref.console.openedObj4.path = /vm/#7fcfa7372df06fa1/
Jan 31 12:59:33: vmx| DICT pref.console.openedObj4.file = D:\VMWARE\DESNADC02\Windows 2000 Server.vmx
Jan 31 12:59:33: vmx| DICT pref.console.openedObj4.name = DESNADC02
Jan 31 12:59:33: vmx| DICT          pref.view.navBar = FALSE
Jan 31 12:59:33: vmx| DICT --- USER DEFAULTS
Jan 31 12:59:33: vmx| DICT --- HOST DEFAULTS
Jan 31 12:59:33: vmx| DICT            datastore.name = local
Jan 31 12:59:33: vmx| DICT       datastore.localpath = C:\Virtual Machines\
Jan 31 12:59:33: vmx| DICT --- SITE DEFAULTS
Jan 31 12:59:33: vmx| DICT            datastore.name = local
Jan 31 12:59:33: vmx| DICT       datastore.localpath = C:\Virtual Machines\
Jan 31 12:59:33: vmx| DICT --- COMMAND LINE
Jan 31 12:59:33: vmx| DICT --- CONFIGURATION
Jan 31 12:59:33: vmx| DICT            config.version = 8
Jan 31 12:59:33: vmx| DICT         virtualHW.version = 4
Jan 31 12:59:33: vmx| DICT             scsi0.present = TRUE
Jan 31 12:59:33: vmx| DICT                   memsize = 384
Jan 31 12:59:33: vmx| DICT           scsi0:0.present = TRUE
Jan 31 12:59:33: vmx| DICT          scsi0:0.fileName = Windows 2000 Server.vmdk
Jan 31 12:59:33: vmx| DICT            ide1:0.present = TRUE
Jan 31 12:59:33: vmx| DICT           ide1:0.fileName = auto detect
Jan 31 12:59:33: vmx| DICT         ide1:0.deviceType = cdrom-raw
Jan 31 12:59:33: vmx| DICT          floppy0.fileName = A:
Jan 31 12:59:33: vmx| DICT         Ethernet0.present = TRUE
Jan 31 12:59:33: vmx| DICT               displayName = DESNADC02
Jan 31 12:59:33: vmx| DICT                   guestOS = win2000serv
Jan 31 12:59:33: vmx| DICT          priority.grabbed = normal
Jan 31 12:59:33: vmx| DICT        priority.ungrabbed = normal
Jan 31 12:59:33: vmx| DICT         ide1:0.autodetect = TRUE
Jan 31 12:59:33: vmx| DICT                workingDir = .
Jan 31 12:59:33: vmx| DICT              scsi0:0.redo = 
Jan 31 12:59:33: vmx| DICT     ide1:0.startConnected = TRUE
Jan 31 12:59:33: vmx| DICT     ethernet0.addressType = generated
Jan 31 12:59:33: vmx| DICT ethernet0.generatedAddress = 00:0c:29:58:8a:d4
Jan 31 12:59:33: vmx| DICT ethernet0.generatedAddressOffset = 0
Jan 31 12:59:33: vmx| DICT            tools.syncTime = FALSE
Jan 31 12:59:33: vmx| DICT                 autostart = poweron
Jan 31 12:59:33: vmx| DICT                  autostop = softpoweroff
Jan 31 12:59:33: vmx| DICT         snapshot.disabled = TRUE
Jan 31 12:59:33: vmx| DICT monitor_control.log_vmsample = FALSE
Jan 31 12:59:33: vmx| DICT    floppy0.startConnected = FALSE
Jan 31 12:59:33: vmx| DICT           scsi0:1.present = TRUE
Jan 31 12:59:33: vmx| DICT          scsi0:1.fileName = Windows 2000 Server DATA.vmdk
Jan 31 12:59:33: vmx| DICT              scsi0:1.redo = 
Jan 31 12:59:33: vmx| DICT                 uuid.bios = 56 4d df 54 64 a8 07 c2-a5 92 dc a1 80 58 8a d4
Jan 31 12:59:33: vmx| DICT        checkpoint.vmState = 
Jan 31 12:59:33: vmx| DICT             uuid.location = 56 4d df 54 64 a8 07 c2-a5 92 dc a1 80 58 8a d4
Jan 31 12:59:33: vmx| DICT --- USER DEFAULTS
Jan 31 12:59:33: vmx| DICT --- HOST DEFAULTS
Jan 31 12:59:33: vmx| DICT            datastore.name = local
Jan 31 12:59:33: vmx| DICT       datastore.localpath = C:\Virtual Machines\
Jan 31 12:59:33: vmx| DICT --- SITE DEFAULTS
Jan 31 12:59:33: vmx| DICT            datastore.name = local
Jan 31 12:59:33: vmx| DICT       datastore.localpath = C:\Virtual Machines\
Jan 31 12:59:33: vmx| DICT --- GLOBAL SETTINGS
Jan 31 12:59:33: vmx| WSSCAN: reserved mem (in MB) min=32 max=896 recommended=896
Jan 31 12:59:33: vmx|         hostMem=1024 maxAllowedAll=-1 maxAllowedVM=3600
Jan 31 12:59:33: vmx|         totOverhead=16
Jan 31 12:59:33: vmx| WSSCAN: used rec mem (in MB) 896
Jan 31 12:59:33: vmx| WSSCAN: Overhead 104689 paged 5791 nonpaged 4096 maxFBSize
Jan 31 12:59:33: vmx| WSSCAN 1 2 182218 195623 229376 182218 50 0
Jan 31 12:59:33: vmx| LICENSE using: 'HKEY_LOCAL_MACHINE\SOFTWARE\VMware, Inc.\VMware Server\License.vs.1.0-00' 
Jan 31 12:59:33: vmx| STATDECLGROUP stats Root "" null
Jan 31 12:59:33: vmx| Host CPUID features: version 0xf28 id1.edx 0xbfebfbff id1.ecx 0x400 id81.edx 0x0 id81.ecx 0x0
Jan 31 12:59:33: vmx| CPU.cpuFeatures = 0x9b1fe00
Jan 31 12:59:33: vmx| CPUID after masking: version 0xf28 id1.edx 0xfebfbff id1.ecx 0x0 id81.edx 0x800 id81.ecx 0x0 id88.ecx 0x0
Jan 31 12:59:33: vmx| CPU.cpuFeatures = 0x8b1fe00
Jan 31 12:59:33: vmx| APIC: Local APIC at 0xfee00000
Jan 31 12:59:33: vmx| KHZEstimate 1993530
Jan 31 12:59:33: vmx| MHZEstimate 1994
Jan 31 12:59:33: vmx| NumVCPUs 1
Jan 31 12:59:33: vmx| UUID: location-UUID is 56 4d 7f 41 c6 a6 86 c2-54 54 a5 9a 0c 6a 34 8a
Jan 31 12:59:33: vmx| UUID: canonical path is d:\vmware\desnadc02\windows 2000 server.vmx
Jan 31 12:59:33: vmx| UUID: location-UUID is 56 4d df 54 64 a8 07 c2-a5 92 dc a1 80 58 8a d4
Jan 31 12:59:33: vmx| MM: Using partialmap, 98304 pages AC 0 CE 1 TM 0 DOHU 0
Jan 31 12:59:33: vmx| UUID: canonical path is d:\vmware\desnadc02\windows 2000 server.vmx
Jan 31 12:59:33: vmx| UUID: location-UUID is 56 4d df 54 64 a8 07 c2-a5 92 dc a1 80 58 8a d4
Jan 31 12:59:33: vmx| MM: using fileName d:\vmware\desnadc02\564ddf54-64a8-07c2-a592-dca180588ad4.vmem for paging
Jan 31 12:59:33: vmx| Msg_Reset:
Jan 31 12:59:33: vmx| ----------------------------------------
Jan 31 12:59:33: vmx| Opened paging file d:\vmware\desnadc02\564ddf54-64a8-07c2-a592-dca180588ad4.vmem
Jan 31 12:59:33: vmx| Mapped mainmem as pageable
Jan 31 12:59:33: vmx| MStat: Creating Stat vm.cpuusage
Jan 31 12:59:33: vmx| MStat: Creating Stat vm.ram
Jan 31 12:59:33: vmx| MStat: Creating Stat vm.uptime
Jan 31 12:59:33: vmx| DISK: OPEN scsi0:0 'd:\vmware\desnadc02\Windows 2000 Server.vmdk' persistent R[(null)]
Jan 31 12:59:33: vmx| DISKLIB-DSCPTR: Opened [0]: "Windows 2000 Server-flat.vmdk" 0 (0xa)
Jan 31 12:59:33: vmx| DISKLIB-LINK  : Opened 'd:\vmware\desnadc02\Windows 2000 Server.vmdk' (0xa): monolithicFlat, 8388608 sectors / 4096 Mb.
Jan 31 12:59:33: vmx| DISKLIB-LIB   : Opened "d:\vmware\desnadc02\Windows 2000 Server.vmdk" (flags 0xa).
Jan 31 12:59:33: vmx| DISK: OPEN 'd:\vmware\desnadc02\Windows 2000 Server.vmdk' Geo (522/255/63) BIOS Geo (522/255/63) freeSpace=3132Mb
Jan 31 12:59:33: vmx| DISK: OPEN scsi0:1 'd:\vmware\desnadc02\Windows 2000 Server DATA.vmdk' persistent R[(null)]
Jan 31 12:59:33: vmx| DISKLIB-DSCPTR: Opened [0]: "Windows 2000 Server DATA-flat.vmdk" 0 (0xa)
Jan 31 12:59:33: vmx| DISKLIB-LINK  : Opened 'd:\vmware\desnadc02\Windows 2000 Server DATA.vmdk' (0xa): monolithicFlat, 25165824 sectors / 12288 Mb.
Jan 31 12:59:33: vmx| DISKLIB-LIB   : Opened "d:\vmware\desnadc02\Windows 2000 Server DATA.vmdk" (flags 0xa).
Jan 31 12:59:33: vmx| DISK: OPEN 'd:\vmware\desnadc02\Windows 2000 Server DATA.vmdk' Geo (1566/255/63) BIOS Geo (1566/255/63) freeSpace=3132Mb
Jan 31 12:59:33: vmx| TimeTracker host to guest rate conversion 161386683849356 @ 1993530000Hz -> 161386683849356 @ 1993530000Hz
Jan 31 12:59:33: vmx| TimeTracker host to guest rate conversion ((x * 2147483648) >> 31) + 0
Jan 31 12:59:33: vmx| MStat: Creating Stat ide1:0.bytesread
Jan 31 12:59:33: vmx| MStat: Creating Stat ide1:0.byteswritten
Jan 31 12:59:33: vmx| SCSI0: UNTAGGED commands will be converted to ORDER tags.
Jan 31 12:59:33: vmx| MStat: Creating Stat scsi0:0.bytesread
Jan 31 12:59:33: vmx| MStat: Creating Stat scsi0:0.byteswritten
Jan 31 12:59:33: vmx| DISKUTIL: scsi0:0 : capacity=8388608
Jan 31 12:59:33: vmx| DISKUTIL: scsi0:0 : geometry=522/255/63
Jan 31 12:59:33: vmx| MStat: Creating Stat scsi0:1.bytesread
Jan 31 12:59:33: vmx| MStat: Creating Stat scsi0:1.byteswritten
Jan 31 12:59:33: vmx| DISKUTIL: scsi0:1 : capacity=25165824
Jan 31 12:59:33: vmx| DISKUTIL: scsi0:1 : geometry=1566/255/63
Jan 31 12:59:33: vmx| MStat: Creating Stat vm.heartbeat
Jan 31 12:59:33: vmx| DISKUTIL: scsi0:1 : toolsVersion = 6530
Jan 31 12:59:33: vmx| DISKUTIL: scsi0:0 : toolsVersion = 6530
Jan 31 12:59:33: vmx| TOOLS INSTALL initializing state to IDLE on power on.
Jan 31 12:59:33: vmx| VLANCE: send cluster threshold is 80, size = 2 recalcInterval is 2 ticks
Jan 31 12:59:33: vmx| MStat: Creating Stat ethernet0.bytesread
Jan 31 12:59:33: vmx| MStat: Creating Stat ethernet0.byteswritten
Jan 31 12:59:33: vmx| Ethernet0 MAC Address: 00:0c:29:58:8a:d4
Jan 31 12:59:33: vmx| VMXNET: send cluster threshold is 80, size = 2 recalcInterval is 2 ticks, dontClusterSize is 128
Jan 31 12:59:33: vmx| E1000: checksum cycles/kB: C=2016 asm=3225
Jan 31 12:59:33: vmx| VirtualProtect MapProtectMem(0x1d80000,0x1000) returns 0x1e7
Jan 31 12:59:33: vmx| Can't read-only monitor pages from 0x01D80000 to 0x01D81000
Jan 31 12:59:33: vmx| VirtualProtect MapProtectMem(0x1d82000,0x0) returns 0x57
Jan 31 12:59:33: vmx| Can't read-only monitor pages from 0x01D82000 to 0x01D82000
Jan 31 12:59:33: vmx| VMX_PowerOn: ModuleTable_PowerOn = 1
Jan 31 12:59:33: vmx| VMX setting maximum IPC write buffers to 0 packets, 0 bytes
Jan 31 12:59:33: mks| Async MKS thread is alive
Jan 31 12:59:33: vmx| Create Memory BIO succeeded.
Jan 31 12:59:33: vmx| Create Memory BIO succeeded.
Jan 31 12:59:33: vmx| Accepted new connection at 900 for thread servercontrol (0x9238b8)
Jan 31 12:59:33: vmx| VUINewControlConnection: before slow ACL gunk (bug 63252).
Jan 31 12:59:33: vmx| ACL: Thread from (SYSTEM) gets full perms
Jan 31 12:59:33: vmx| VUINewControlConnection: after slow ACL gunk (bug 63252).
Jan 31 12:59:33: vmx| VUI: A new VMControl client connected.
Jan 31 12:59:33: vcpu-0| APIC: version = 0x14, max LVT = 5
Jan 31 12:59:33: vcpu-0| APIC: LDR = 0x2000000, DFR = 0xffffffff
Jan 31 12:59:33: vcpu-0| PShare: enabled 1, scanRate 32, checkRate 16
Jan 31 12:59:33: vcpu-0| guestCpuFeatures = 0x8b1fe00
Jan 31 12:59:33: vcpu-0| Init modules.
Jan 31 12:59:33: vcpu-0| CPU reset: hard
Jan 31 12:59:33: vmx| VNET: Notification enabled for Ethernet0
Jan 31 12:59:33: vmx| CDROM: Using autodetect backend Z: for ide1:0.
Jan 31 12:59:33: vcpu-0| sz=3108832
Jan 31 12:59:33: vcpu-0| vmm32 initialized: Releasebuild-29996. cflags: 0x00000002.01883000.00000054
Jan 31 12:59:33: vmx| IPC version negotiation version: VMX returning 2.1 to servercontrol
Jan 31 12:59:33: vmx| IPC vmcontrol-temp version: VMX returning 11.4 to servercontrol that tried 11.4
Jan 31 12:59:34: mks| Ignoring update request in VGA_Expose (mode change pending).
Jan 31 12:59:34: vcpu-0| SVGA: Registering MemSpace at 0xf0000000(0x0) and 0xec000000(0x0)
Jan 31 12:59:34: vcpu-0| SVGA: Unregistering MemSpace at 0xf0000000(0xf0000000) and 0xec000000(0xec000000)
Jan 31 12:59:34: vcpu-0| SVGA: Registering MemSpace at 0xf0000000(0xf0000000) and 0xec000000(0xec000000)
Jan 31 12:59:34: vcpu-0| SVGA: Unregistering MemSpace at 0xf0000000(0xf0000000) and 0xec000000(0xec000000)
Jan 31 12:59:34: vcpu-0| SVGA: Registering IOSpace at 0x1400 (0x0)
Jan 31 12:59:34: vcpu-0| SVGA: Registering MemSpace at 0xf0000000(0xf0000000) and 0xec000000(0xec000000)
Jan 31 12:59:35: mks| VNCENCODE 1 encoding mode change: (640x480x16depth,16bpp)
Jan 31 12:59:35: mks| SOCKET 1 new update req with non-empty pending: logical error on server/client?
Jan 31 12:59:35: vcpu-0| AIOGNRC: Starting 2 I/O threads.
Jan 31 12:59:35: vcpu-0| Winnt_CDROM: 'Z:' Length 24 MaxTransferLength 131072 MaxPhysPages 32
Jan 31 12:59:35: vcpu-0| Winnt_CDROM: AlignMask 0x1 !AsyncEvent !TaggedQ ScanUp PIO
Jan 31 12:59:36: vcpu-0| DISKUTIL: scsi0:0 : geometry=522/255/63
Jan 31 12:59:36: vcpu-0| DISKUTIL: scsi0:1 : geometry=1566/255/63
Jan 31 12:59:37: vcpu-0| BIOS-UUID is 56 4d df 54 64 a8 07 c2-a5 92 dc a1 80 58 8a d4
Jan 31 12:59:38: vcpu-0| DISKUTIL: scsi0:1 : toolsVersion = 6530
Jan 31 12:59:38: vcpu-0| DISKUTIL: scsi0:0 : toolsVersion = 6530
Jan 31 12:59:38: vcpu-0| DISKUTIL: scsi0:1 : toolsVersion = 6530
Jan 31 12:59:38: vcpu-0| DISKUTIL: scsi0:0 : toolsVersion = 6530
Jan 31 12:59:38: mks| VNCENCODE 1 encoding mode change: (720x400x16depth,16bpp)
Jan 31 12:59:38: vcpu-0| BUSLOGIC: Store HAL[16] = 0x09bf
Jan 31 12:59:38: vcpu-0| BUSLOGIC: Store HAL[18] = 0xfe02
Jan 31 12:59:38: vcpu-0| BUSLOGIC: Store HAL[16] = 0x09bf
Jan 31 12:59:38: vcpu-0| BUSLOGIC: Store HAL[18] = 0xfe02
Jan 31 12:59:38: vcpu-0| BUSLOGIC: Store HAL[16] = 0x09bf
Jan 31 12:59:38: vcpu-0| BUSLOGIC: Store HAL[18] = 0xfe02
Jan 31 12:59:38: vcpu-0| Unknown int 10h func 0x2000
Jan 31 12:59:38: vcpu-0| BUSLOGIC: Store HAL[16] = 0x09bf
Jan 31 12:59:38: vcpu-0| BUSLOGIC: Store HAL[18] = 0xfe02
Jan 31 12:59:38: vcpu-0| BUSLOGIC: Store HAL[16] = 0x09bf
Jan 31 12:59:38: vcpu-0| BUSLOGIC: Store HAL[18] = 0xfe02
Jan 31 12:59:38: vcpu-0| BUSLOGIC: Store HAL[16] = 0x09bf
Jan 31 12:59:38: vcpu-0| BUSLOGIC: Store HAL[18] = 0xfe02
Jan 31 12:59:38: vcpu-0| BUSLOGIC: Store HAL[20] = 0x007f
Jan 31 12:59:38: vcpu-0| BUSLOGIC: Store HAL[22] = 0xf58a
Jan 31 12:59:38: vcpu-0| BUSLOGIC: Store HAL[16] = 0x09bf
Jan 31 12:59:38: vcpu-0| BUSLOGIC: Store HAL[18] = 0xfe02
Jan 31 12:59:38: vcpu-0| BUSLOGIC: Store HAL[20] = 0x00fb
Jan 31 12:59:38: vcpu-0| BUSLOGIC: Store HAL[22] = 0x0400
Jan 31 12:59:38: vcpu-0| BUSLOGIC: Store HAL[16] = 0xffff
Jan 31 12:59:38: vcpu-0| BUSLOGIC: Store HAL[18] = 0xfe02
Jan 31 12:59:38: vcpu-0| BUSLOGIC: Store HAL[16] = 0x09bf
Jan 31 12:59:38: vcpu-0| BUSLOGIC: Store HAL[18] = 0xfe02
Jan 31 12:59:38: vcpu-0| BUSLOGIC: Store HAL[16] = 0xffff
Jan 31 12:59:38: vcpu-0| BUSLOGIC: Store HAL[18] = 0xfe02
Jan 31 12:59:38: vcpu-0| BUSLOGIC: Store HAL[16] = 0x09bf
Jan 31 12:59:38: vcpu-0| BUSLOGIC: Store HAL[18] = 0xfe02
Jan 31 12:59:38: vcpu-0| BUSLOGIC: Store HAL[16] = 0x09bf
Jan 31 12:59:38: vcpu-0| BUSLOGIC: Store HAL[18] = 0xfe02
Jan 31 12:59:43: mks| VNCENCODE 1 encoding mode change: (640x480x16depth,16bpp)
Jan 31 12:59:45: vcpu-0| SVGA: Unregistering IOSpace at 0x1400 (0x1400)
Jan 31 12:59:45: vcpu-0| SVGA: Unregistering MemSpace at 0xf0000000(0xf0000000) and 0xec000000(0xec000000)
Jan 31 12:59:45: vcpu-0| SVGA: Registering IOSpace at 0x1400 (0x0)
Jan 31 12:59:45: vcpu-0| SVGA: Registering MemSpace at 0xf0000000(0xf0000000) and 0xec000000(0xec000000)
Jan 31 12:59:47: vcpu-0| DISKUTIL: scsi0:1 : geometry=1566/255/63
Jan 31 12:59:47: vcpu-0| DISKUTIL: scsi0:0 : geometry=522/255/63
Jan 31 12:59:47: vcpu-0| SCSI0: RESET BUS
Jan 31 12:59:47: vcpu-0| DISKUTIL: scsi0:1 : geometry=1566/255/63
Jan 31 12:59:47: vcpu-0| DISKUTIL: scsi0:0 : geometry=522/255/63
Jan 31 12:59:48: vcpu-0| SCSI DEVICE (scsi0:0): MODE SENSE(6) for unsupported page 0x1c
Jan 31 12:59:48: vcpu-0| SCSI DEVICE (scsi0:1): MODE SENSE(6) for unsupported page 0x1c
Jan 31 13:00:21: vcpu-0| DISKLIB-LIB   :numIOs = 50000 numMergedIOs = 0 numSplitIOs = 0
Jan 31 13:00:54: vcpu-0| DISKLIB-LIB   :numIOs = 100000 numMergedIOs = 0 numSplitIOs = 0
Jan 31 13:01:18: vcpu-0| Guest OS = 0x5007
Jan 31 13:01:18: vcpu-0| VNET: Notification enabled for Ethernet0
Jan 31 13:01:30: mks| SVGA: Using extended FIFO: Caps 0x00000007, Flags 0x00000000
Jan 31 13:01:30: mks| VNCENCODE 1 encoding mode change: (800x600x24depth,32bpp)
Jan 31 13:01:30: mks| MKS remote display status changed, enabling remoteoptimizations
Jan 31 13:01:31: mks| HostOps hideCursor before defineCursor!
Jan 31 13:01:31: mks| SOCKET 1 new update req with non-empty pending: logical error on server/client?
Jan 31 13:01:55: vcpu-0| MKS Backdoor get pointer: first time, notify tools are running
Jan 31 13:01:55: vcpu-0| GuestRpc: Channel 1, registration number 1, guest application toolbox.
Jan 31 13:01:55: vcpu-0| TOOLS Reducing idleLoopSpinUS to 500us
Jan 31 13:01:55: vcpu-0| DISKUTIL: scsi0:1 : toolsVersion = 6530
Jan 31 13:01:55: vcpu-0| DISKUTIL: scsi0:0 : toolsVersion = 6530
Jan 31 13:01:55: vcpu-0| Guest: toolbox: Version: build-29996
Jan 31 13:01:55: vcpu-0| TOOLS setting the tools version to '6530'
Jan 31 13:01:55: vcpu-0| TOOLS unified loop capability requested by 'toolbox'; now sending options via TCLO
Jan 31 13:02:07: vcpu-0| VMMouse: CMD Read ID
Jan 31 13:02:07: vcpu-0| MKS switching absolute mouse on
Jan 31 13:02:29: vmx| Msg_Question:
Jan 31 13:02:29: vmx| [msg.vmxvmdbCb.startInstallTools] Installing the VMware Tools package will greatly enhance graphics and mouse performance in your virtual machine.
Jan 31 13:02:29: vmx| 
Jan 31 13:02:29: vmx| WARNING: You cannot install the VMware Tools package until the guest operating system is running. If your guest operating system is not running, choose Cancel and install the VMware Tools package later.----------------------------------------
Jan 31 13:02:29: vmx| Question without a remote UI:
Jan 31 13:02:29: vmx| Installing the VMware Tools package will greatly enhance graphics and mouse performance in your virtual machine.
Jan 31 13:02:29: vmx| 
Jan 31 13:02:29: vmx| WARNING: You cannot install the VMware Tools package until the guest operating system is running. If your guest operating system is not running, choose Cancel and install the VMware Tools package later.
Jan 31 13:02:37: vmx| Msg_Question: msg.vmxvmdbCb.startInstallTools reply=0
Jan 31 13:02:37: vmx| TOOLS INSTALL entering BEGINNING state.
Jan 31 13:02:37: vmx| TOOLS INSTALL using device 'ide1:0'.
Jan 31 13:02:37: vmx| TOOLS INSTALL begin not called from the UI.
Jan 31 13:02:37: vmx| SCSI0:0: Command WRITE(10) took 8.412 seconds (ok)
Jan 31 13:02:39: vcpu-0| TOOLS unified loop capability requested by 'toolbox-dnd'; now sending options via TCLO
Jan 31 13:02:39: vcpu-0| GuestRpc: Channel 2, registration number 1, guest application toolbox-dnd.
Jan 31 13:02:39: vcpu-0| DISKUTIL: scsi0:1 : toolsVersion = 6530
Jan 31 13:02:39: vcpu-0| DISKUTIL: scsi0:0 : toolsVersion = 6530
Jan 31 13:02:40: vmx| TOOLS INSTALL entering INSERTED state.
Jan 31 13:02:41: vcpu-0| ide1:0 CDROM: Forcing media change
Jan 31 13:02:51: vmx| DISKLIB-LIB   :numIOs = 150000 numMergedIOs = 0 numSplitIOs = 0
Jan 31 13:03:32: vcpu-0| GuestRpc: Channel 2 reinitialized.
Jan 31 13:03:49: vcpu-0| TOOLS unified loop capability requested by 'toolbox-dnd'; now sending options via TCLO
Jan 31 13:03:49: vcpu-0| GuestRpc: Channel 2, registration number 1, guest application toolbox-dnd.
Jan 31 13:03:49: vcpu-0| DISKUTIL: scsi0:1 : toolsVersion = 6530
Jan 31 13:03:49: vcpu-0| DISKUTIL: scsi0:0 : toolsVersion = 6530
Jan 31 13:03:57: vcpu-0| VNET: Notification enabled for Ethernet0
Jan 31 13:03:57: vcpu-0| VNET: Notification enabled for Ethernet0
Jan 31 13:04:03: vcpu-0| TOOLS INSTALL entering ENDING state.
Jan 31 13:04:06: vmx| CDROM: Using autodetect backend Z: for ide1:0.
Jan 31 13:04:06: vmx| TOOLS INSTALL entering IDLE state.
Jan 31 13:04:07: vcpu-0| Winnt_CDROM: 'Z:' Length 24 MaxTransferLength 131072 MaxPhysPages 32
Jan 31 13:04:07: vcpu-0| Winnt_CDROM: AlignMask 0x1 !AsyncEvent !TaggedQ ScanUp PIO
Jan 31 13:04:07: vcpu-0| ide1:0 CDROM: Forcing media change