Oct 25 13:22:16: vmx| Log for VMware Server pid=24083 version=1.0.4 build=build-56528 option=Release Oct 25 13:22:16: vmx| Command line: "/usr/lib/vmware/bin/vmware-vmx" "-C" "-@" """" "/vmmachines/k-win3/k-win.vmx" Oct 25 13:22:16: vmx| vmxvmdb: Index name being generated from config file Oct 25 13:22:16: vmx| VMXVmdbConnectServerd - Trying to discover serverd Oct 25 13:22:16: vmx| MStat: Creating Stat system.cpuusage Oct 25 13:22:16: vmx| MStat: Creating Stat system.ram Oct 25 13:22:16: vmx| MStat: Creating Stat system.uptime Oct 25 13:22:16: vmx| MStat: Creating Stat system.load Oct 25 13:22:16: vmx| cpuids[0].id81.ecx = 0x1f Oct 25 13:22:16: vmx| cpuids[1].id81.ecx = 0x1f Oct 25 13:22:16: vmx| cpuids[2].id81.ecx = 0x1f Oct 25 13:22:16: vmx| cpuids[3].id81.ecx = 0x1f Oct 25 13:22:16: vmx| pcpu #0 CPUID numEntries=1 AuthcAMDenti Oct 25 13:22:16: vmx| pcpu #0 CPUID version=0x40f13 id1.edx=0x178bfbff id1.ecx=0x2001 id1.ebx=0x20800 Oct 25 13:22:16: vmx| pcpu #0 CPUID id80.eax=80000018 id81.edx=0xebd3fbff id81.ecx=0x1f Oct 25 13:22:16: vmx| pcpu #1 CPUID numEntries=1 AuthcAMDenti Oct 25 13:22:16: vmx| pcpu #1 CPUID version=0x40f13 id1.edx=0x178bfbff id1.ecx=0x2001 id1.ebx=0x1020800 Oct 25 13:22:16: vmx| pcpu #1 CPUID id80.eax=80000018 id81.edx=0xebd3fbff id81.ecx=0x1f Oct 25 13:22:16: vmx| pcpu #2 CPUID numEntries=1 AuthcAMDenti Oct 25 13:22:16: vmx| pcpu #2 CPUID version=0x40f13 id1.edx=0x178bfbff id1.ecx=0x2001 id1.ebx=0x2020800 Oct 25 13:22:16: vmx| pcpu #2 CPUID id80.eax=80000018 id81.edx=0xebd3fbff id81.ecx=0x1f Oct 25 13:22:16: vmx| pcpu #3 CPUID numEntries=1 AuthcAMDenti Oct 25 13:22:16: vmx| pcpu #3 CPUID version=0x40f13 id1.edx=0x178bfbff id1.ecx=0x2001 id1.ebx=0x3020800 Oct 25 13:22:16: vmx| pcpu #3 CPUID id80.eax=80000018 id81.edx=0xebd3fbff id81.ecx=0x1f Oct 25 13:22:16: vmx| CPUID id1.edx: 0x178bfbff id1.ecx: 0x2001 id81.edx: 0xebd3fbff id81.ecx: 0x1f Oct 25 13:22:16: vmx| CPUID id88.ecx: 0 id88.edx: 0 Oct 25 13:22:16: vmx| Setup symlink /var/run/vmware/%2Fvmmachines%2Fk%2Dwin3%2Fk%2Dwin%2Evmx -> /var/run/vmware/root/24083 Oct 25 13:22:16: vmx| ACL_InitCapabilities: here 1 (bug 63252) Oct 25 13:22:16: vmx| changing directory to /vmmachines/k-win3/. Oct 25 13:22:16: vmx| Config file: /vmmachines/k-win3/k-win.vmx Oct 25 13:22:16: vmx| CnxAcceptConnection: Could not receive fd on 27: invalid control message Oct 25 13:22:16: vmx| Failed to get IPC connection Oct 25 13:22:16: vmx| Read from FIFO 32 -- connecting to serverd... Oct 25 13:22:16: vmx| VMDB: Connected to serverd Oct 25 13:22:16: vmx| VMXVmdbCbVmVmxExecState: Exec state change requested to state poweredOn without reset Oct 25 13:22:16: vmx| TOOLS delaying state change request to state 4 Oct 25 13:22:16: vmx| PowerOn Oct 25 13:22:16: vmx| Host ACPI: SRAT @ 0xd7fd4d50 Oct 25 13:22:16: vmx| Host ACPI: SRAT: LAPIC ID 0x00, proximity domain low 0x00 Oct 25 13:22:16: vmx| Host ACPI: SRAT: LAPIC ID 0x01, proximity domain low 0x00 Oct 25 13:22:16: vmx| Host ACPI: SRAT: LAPIC ID 0x02, proximity domain low 0x01 Oct 25 13:22:16: vmx| Host ACPI: SRAT: LAPIC ID 0x03, proximity domain low 0x01 Oct 25 13:22:16: vmx| Host: CPU #0 LAPIC ID 0x00, node 0x00 Oct 25 13:22:16: vmx| Host: CPU #1 LAPIC ID 0x01, node 0x00 Oct 25 13:22:16: vmx| Host: CPU #2 LAPIC ID 0x02, node 0x01 Oct 25 13:22:16: vmx| Host: CPU #3 LAPIC ID 0x03, node 0x01 Oct 25 13:22:16: vmx| Resuming virtual machine from /vmmachines/k-win3/k-win.vmss Oct 25 13:22:16: vmx| DUMPER: restoring checkpoint version 8 Oct 25 13:22:16: vmx| Checkpointed in VMware Server, 1.0.4 build-56528, build-56528, Linux Host Oct 25 13:22:16: vmx| HOST sysname Linux, nodename hades, release 2.6.16.53-0.16-smp, version #1 SMP Tue Oct 2 16:57:49 UTC 2007, machine x86_64, SMP, hz=250 Oct 25 13:22:16: vmx| DICT --- USER PREFERENCES Oct 25 13:22:16: vmx| DICT pref.grabOnKeyPress = FALSE Oct 25 13:22:16: vmx| DICT pref.autoFitFullScreen = fitHostToGuest Oct 25 13:22:16: vmx| DICT pref.view.navBar.type = favorites Oct 25 13:22:16: vmx| DICT pref.mruDest0.present = TRUE Oct 25 13:22:16: vmx| DICT pref.mruDest0.destString = localhost Oct 25 13:22:16: vmx| DICT pref.mruDest0.user = root Oct 25 13:22:16: vmx| DICT pref.mruDest1.present = TRUE Oct 25 13:22:16: vmx| DICT pref.mruDest1.destString = hades Oct 25 13:22:16: vmx| DICT pref.mruDest1.user = root Oct 25 13:22:16: vmx| DICT pref.mruDest2.present = FALSE Oct 25 13:22:16: vmx| DICT pref.mruDest2.destString = Oct 25 13:22:16: vmx| DICT pref.mruDest2.user = Oct 25 13:22:16: vmx| DICT pref.mruDest3.present = FALSE Oct 25 13:22:16: vmx| DICT pref.mruDest3.destString = Oct 25 13:22:16: vmx| DICT pref.mruDest3.user = Oct 25 13:22:16: vmx| DICT pref.mruDest4.present = FALSE Oct 25 13:22:16: vmx| DICT pref.mruDest4.destString = Oct 25 13:22:16: vmx| DICT pref.mruDest4.user = Oct 25 13:22:16: vmx| DICT pref.mruDest5.present = FALSE Oct 25 13:22:16: vmx| DICT pref.mruDest5.destString = Oct 25 13:22:16: vmx| DICT pref.mruDest5.user = Oct 25 13:22:16: vmx| DICT pref.mruDest6.present = FALSE Oct 25 13:22:16: vmx| DICT pref.mruDest6.destString = Oct 25 13:22:16: vmx| DICT pref.mruDest6.user = Oct 25 13:22:16: vmx| DICT pref.mruDest7.present = FALSE Oct 25 13:22:16: vmx| DICT pref.mruDest7.destString = Oct 25 13:22:16: vmx| DICT pref.mruDest7.user = Oct 25 13:22:16: vmx| DICT webUpdate.checkPeriod = weekly Oct 25 13:22:16: vmx| DICT webUpdate.checkLast = 1193233838 Oct 25 13:22:16: vmx| DICT pref.console.openedObj0.present = TRUE Oct 25 13:22:16: vmx| DICT pref.console.openedObj0.type = vm Oct 25 13:22:16: vmx| DICT pref.console.openedObj0.path = /vm/#ce7cd6dd2273d9fb/ Oct 25 13:22:16: vmx| DICT pref.console.openedObj0.file = /vmmachines/k-win3/k-win.vmx Oct 25 13:22:16: vmx| DICT pref.console.openedObj0.dest = /host2/#695f4e9b8d250684/ Oct 25 13:22:16: vmx| DICT pref.console.openedObj.maxNum = 3 Oct 25 13:22:16: vmx| DICT pref.console.currentObj.path = /vm/#7f6e7df5eb1cd1c3/ Oct 25 13:22:16: vmx| DICT pref.console.currentObj.type = vm Oct 25 13:22:16: vmx| DICT pref.placement.left = 124 Oct 25 13:22:16: vmx| DICT pref.placement.top = 90 Oct 25 13:22:16: vmx| DICT pref.placement.right = 1084 Oct 25 13:22:16: vmx| DICT pref.placement.bottom = 656 Oct 25 13:22:16: vmx| DICT pref.console.openedObj1.present = TRUE Oct 25 13:22:16: vmx| DICT pref.console.openedObj1.type = vm Oct 25 13:22:16: vmx| DICT pref.console.openedObj1.path = /vm/#070dc96a629564ec/ Oct 25 13:22:16: vmx| DICT pref.console.openedObj1.file = /vmmachines/Windows 2000 Server/Windows 2000 Server.vmx Oct 25 13:22:16: vmx| DICT pref.console.openedObj1.dest = /host2/#695f4e9b8d250684/ Oct 25 13:22:16: vmx| DICT pref.console.openedObj2.present = TRUE Oct 25 13:22:16: vmx| DICT pref.console.openedObj2.type = vm Oct 25 13:22:16: vmx| DICT pref.console.openedObj2.path = /vm/#7f6e7df5eb1cd1c3/ Oct 25 13:22:16: vmx| DICT pref.console.openedObj2.file = /vmmachines/xp-new/xp-new.vmx Oct 25 13:22:16: vmx| DICT pref.console.openedObj2.dest = /host2/#695f4e9b8d250684/ Oct 25 13:22:16: vmx| DICT pref.console.openedObj3.present = TRUE Oct 25 13:22:16: vmx| DICT pref.console.openedObj3.type = vm Oct 25 13:22:16: vmx| DICT pref.console.openedObj3.path = /vm/#070dc96a629564ec/ Oct 25 13:22:16: vmx| DICT pref.console.openedObj3.file = /vmmachines/Windows 2000 Server/Windows 2000 Server.vmx Oct 25 13:22:16: vmx| DICT pref.console.openedObj3.dest = /host2/#695f4e9b8d250684/ Oct 25 13:22:16: vmx| DICT pref.cnxType = local Oct 25 13:22:16: vmx| DICT --- USER DEFAULTS Oct 25 13:22:16: vmx| DICT --- HOST DEFAULTS Oct 25 13:22:16: vmx| DICT serverd.init.fullpath = /usr/lib/vmware/serverd/init.pl Oct 25 13:22:16: vmx| DICT authd.client.port = 904 Oct 25 13:22:16: vmx| DICT control.fullpath = /usr/bin/vmware-cmd Oct 25 13:22:16: vmx| DICT authd.fullpath = /usr/sbin/vmware-authd Oct 25 13:22:16: vmx| DICT loop.fullpath = /usr/bin/vmware-loop Oct 25 13:22:16: vmx| DICT libdir = /usr/lib/vmware Oct 25 13:22:16: vmx| DICT vmware.fullpath = /usr/bin/vmware Oct 25 13:22:16: vmx| DICT vmdir = /vmmachines Oct 25 13:22:16: vmx| DICT dhcpd.fullpath = /usr/bin/vmnet-dhcpd Oct 25 13:22:16: vmx| DICT serverd.fullpath = /usr/sbin/vmware-serverd Oct 25 13:22:16: vmx| DICT datastore.name = local Oct 25 13:22:16: vmx| DICT datastore.localpath = /vmmachines/ Oct 25 13:22:16: vmx| DICT prefvmx.useRecommendedLockedMemSize = FALSE Oct 25 13:22:16: vmx| DICT prefvmx.allVMMemoryLimit = 6590 Oct 25 13:22:16: vmx| DICT prefvmx.minVmMemPct = 100 Oct 25 13:22:16: vmx| DICT defaultVMPath = /vmmachines Oct 25 13:22:16: vmx| DICT priority.grabbed = normal Oct 25 13:22:16: vmx| DICT priority.ungrabbed = normal Oct 25 13:22:16: vmx| DICT security.host.ruissl = TRUE Oct 25 13:22:16: vmx| DICT --- SITE DEFAULTS Oct 25 13:22:16: vmx| DICT tag.help = introduction.htm Oct 25 13:22:16: vmx| DICT tag.configurationEditor = config_editor_newvm.htm Oct 25 13:22:16: vmx| DICT tag.ideConfig = devices_virtualdrive.htm Oct 25 13:22:16: vmx| DICT tag.floppyConfig = devices_floppy.htm Oct 25 13:22:16: vmx| DICT tag.mouseConfig = devices_mouse.htm Oct 25 13:22:16: vmx| DICT tag.netConfig = devices_netadapter.htm Oct 25 13:22:16: vmx| DICT tag.parallelConfig = devices_parallel.htm Oct 25 13:22:16: vmx| DICT tag.serialConfig = devices_serial.htm Oct 25 13:22:16: vmx| DICT tag.soundConfig = devices_sound.htm Oct 25 13:22:16: vmx| DICT tag.memConfig = configvm_memory.htm Oct 25 13:22:16: vmx| DICT tag.miscConfig = configvm.htm Oct 25 13:22:16: vmx| DICT tag.usbConfig = devices_usb.htm Oct 25 13:22:16: vmx| DICT tag.displayConfig = configvm_display-problems.htm Oct 25 13:22:16: vmx| DICT tag.tools = vmtools.htm Oct 25 13:22:16: vmx| DICT --- COMMAND LINE Oct 25 13:22:16: vmx| DICT gui.managementUI = TRUE Oct 25 13:22:16: vmx| DICT --- CONFIGURATION Oct 25 13:22:16: vmx| DICT config.version = 8 Oct 25 13:22:16: vmx| DICT virtualHW.version = 4 Oct 25 13:22:16: vmx| DICT memsize = 1536 Oct 25 13:22:16: vmx| DICT MemAllowAutoScaleDown = FALSE Oct 25 13:22:16: vmx| DICT MemTrimRate = -1 Oct 25 13:22:16: vmx| DICT displayName = k-win Oct 25 13:22:16: vmx| DICT guestOS = win2000advserv Oct 25 13:22:16: vmx| DICT numvcpus = 1 Oct 25 13:22:16: vmx| DICT tools.remindInstall = FALSE Oct 25 13:22:16: vmx| DICT floppy0.present = TRUE Oct 25 13:22:16: vmx| DICT floppy0.fileName = /dev/fd0 Oct 25 13:22:16: vmx| DICT usb.present = TRUE Oct 25 13:22:16: vmx| DICT ethernet0.present = TRUE Oct 25 13:22:16: vmx| DICT ethernet0.addressType = generated Oct 25 13:22:16: vmx| DICT ethernet0.connectionType = bridged Oct 25 13:22:16: vmx| DICT ethernet0.startConnected = FALSE Oct 25 13:22:16: vmx| DICT ethernet1.present = TRUE Oct 25 13:22:16: vmx| DICT ethernet1.addressType = generated Oct 25 13:22:16: vmx| DICT ethernet1.connectionType = bridged Oct 25 13:22:16: vmx| DICT ethernet1.startConnected = FALSE Oct 25 13:22:16: vmx| DICT scsi0:0.present = TRUE Oct 25 13:22:16: vmx| DICT scsi0:0.fileName = k-win.vmdk Oct 25 13:22:16: vmx| DICT ide0:0.present = TRUE Oct 25 13:22:16: vmx| DICT ide0:0.autodetect = TRUE Oct 25 13:22:16: vmx| DICT ide0:0.filename = /usr/lib/vmware/isoimages/windows.iso Oct 25 13:22:16: vmx| DICT ide0:0.deviceType = cdrom-image Oct 25 13:22:16: vmx| DICT ide0:1.present = TRUE Oct 25 13:22:16: vmx| DICT ide0:1.autodetect = TRUE Oct 25 13:22:16: vmx| DICT ide0:1.filename = auto detect Oct 25 13:22:16: vmx| DICT ide0:1.deviceType = atapi-cdrom Oct 25 13:22:16: vmx| DICT scsi0.present = TRUE Oct 25 13:22:16: vmx| DICT scsi0.virtualDev = buslogic Oct 25 13:22:16: vmx| DICT buslogic.noDriver = FALSE Oct 25 13:22:16: vmx| DICT priority.grabbed = normal Oct 25 13:22:16: vmx| DICT priority.ungrabbed = normal Oct 25 13:22:16: vmx| DICT scsi0:0.redo = Oct 25 13:22:16: vmx| DICT ide0:0.startConnected = FALSE Oct 25 13:22:16: vmx| DICT ethernet0.generatedAddress = 00:0c:29:0a:4b:48 Oct 25 13:22:16: vmx| DICT ethernet1.generatedAddress = 00:0c:29:0a:4b:52 Oct 25 13:22:16: vmx| DICT uuid.location = 56 4d 0e e9 da 9d 2e a8-d5 1b 76 98 fc 0a 4b 48 Oct 25 13:22:16: vmx| DICT uuid.bios = 56 4d 0e e9 da 9d 2e a8-d5 1b 76 98 fc 0a 4b 48 Oct 25 13:22:16: vmx| DICT ethernet0.generatedAddressOffset = 0 Oct 25 13:22:16: vmx| DICT ethernet1.generatedAddressOffset = 10 Oct 25 13:22:16: vmx| DICT tools.syncTime = FALSE Oct 25 13:22:16: vmx| DICT checkpoint.vmState = k-win.vmss Oct 25 13:22:16: vmx| DICT --- USER DEFAULTS Oct 25 13:22:16: vmx| DICT --- HOST DEFAULTS Oct 25 13:22:16: vmx| DICT serverd.init.fullpath = /usr/lib/vmware/serverd/init.pl Oct 25 13:22:16: vmx| DICT authd.client.port = 904 Oct 25 13:22:16: vmx| DICT control.fullpath = /usr/bin/vmware-cmd Oct 25 13:22:16: vmx| DICT authd.fullpath = /usr/sbin/vmware-authd Oct 25 13:22:16: vmx| DICT loop.fullpath = /usr/bin/vmware-loop Oct 25 13:22:16: vmx| DICT libdir = /usr/lib/vmware Oct 25 13:22:16: vmx| DICT vmware.fullpath = /usr/bin/vmware Oct 25 13:22:16: vmx| DICT vmdir = /vmmachines Oct 25 13:22:16: vmx| DICT dhcpd.fullpath = /usr/bin/vmnet-dhcpd Oct 25 13:22:16: vmx| DICT serverd.fullpath = /usr/sbin/vmware-serverd Oct 25 13:22:16: vmx| DICT datastore.name = local Oct 25 13:22:16: vmx| DICT datastore.localpath = /vmmachines/ Oct 25 13:22:16: vmx| DICT prefvmx.useRecommendedLockedMemSize = FALSE Oct 25 13:22:16: vmx| DICT prefvmx.allVMMemoryLimit = 6590 Oct 25 13:22:16: vmx| DICT prefvmx.minVmMemPct = 100 Oct 25 13:22:16: vmx| DICT defaultVMPath = /vmmachines Oct 25 13:22:16: vmx| DICT priority.grabbed = normal Oct 25 13:22:16: vmx| DICT priority.ungrabbed = normal Oct 25 13:22:16: vmx| DICT security.host.ruissl = TRUE Oct 25 13:22:16: vmx| DICT --- SITE DEFAULTS Oct 25 13:22:16: vmx| DICT tag.help = introduction.htm Oct 25 13:22:16: vmx| DICT tag.configurationEditor = config_editor_newvm.htm Oct 25 13:22:16: vmx| DICT tag.ideConfig = devices_virtualdrive.htm Oct 25 13:22:16: vmx| DICT tag.floppyConfig = devices_floppy.htm Oct 25 13:22:16: vmx| DICT tag.mouseConfig = devices_mouse.htm Oct 25 13:22:16: vmx| DICT tag.netConfig = devices_netadapter.htm Oct 25 13:22:16: vmx| DICT tag.parallelConfig = devices_parallel.htm Oct 25 13:22:16: vmx| DICT tag.serialConfig = devices_serial.htm Oct 25 13:22:16: vmx| DICT tag.soundConfig = devices_sound.htm Oct 25 13:22:16: vmx| DICT tag.memConfig = configvm_memory.htm Oct 25 13:22:16: vmx| DICT tag.miscConfig = configvm.htm Oct 25 13:22:16: vmx| DICT tag.usbConfig = devices_usb.htm Oct 25 13:22:16: vmx| DICT tag.displayConfig = configvm_display-problems.htm Oct 25 13:22:16: vmx| DICT tag.tools = vmtools.htm Oct 25 13:22:16: vmx| DICT --- GLOBAL SETTINGS Oct 25 13:22:16: vmx| WSSCAN: reserved mem (in MB) min=32 max=7968 recommended=7968 Oct 25 13:22:16: vmx| hostMem=8064 maxAllowedAll=-1 maxAllowedVM=3600 Oct 25 13:22:16: vmx| totOverhead=16 Oct 25 13:22:16: vmx| WSSCAN: reserved mem (in MB) 6590, min=32 max=7968 Oct 25 13:22:16: vmx| WSSCAN: Overhead 399601 paged 8097 nonpaged 4096 maxFBSize Oct 25 13:22:16: vmx| WSSCAN 1 1 1687040 -1 1687040 -1 100 0 Oct 25 13:22:16: vmx| LICENSE using: '/etc/vmware/license.vs.1.0-00' Oct 25 13:22:16: vmx| STATDECLGROUP stats Root "" null Oct 25 13:22:16: vmx| Host CPUID features: version 0x40f13 id1.edx 0x178bfbff id1.ecx 0x2001 id81.edx 0xebd3fbff id81.ecx 0x1f Oct 25 13:22:16: vmx| CPU.cpuFeatures = 0xd83dfff8 Oct 25 13:22:16: vmx| CPUID after masking: version 0x40f13 id1.edx 0x78bfbff id1.ecx 0x2001 id81.edx 0xebd3fbff id81.ecx 0x9 id88.ecx 0x0 Oct 25 13:22:16: vmx| CPU.cpuFeatures = 0xd83dfff8 Oct 25 13:22:16: vmx| APIC: Local APIC at 0xfee00000 Oct 25 13:22:16: vmx| KHZEstimate 2400000 Oct 25 13:22:16: vmx| MHZEstimate 2400 Oct 25 13:22:16: vmx| NumVCPUs 1 Oct 25 13:22:16: vmx| UUID: location-UUID is 56 4d 0e e9 da 9d 2e a8-d5 1b 76 98 fc 0a 4b 48 Oct 25 13:22:16: vmx| MM: Using partialmap, 393216 pages AC 0 CE 1 TM 0 DOHU 0 Oct 25 13:22:16: vmx| MStat: Creating Stat vm.cpuusage Oct 25 13:22:16: vmx| MStat: Creating Stat vm.ram Oct 25 13:22:16: vmx| MStat: Creating Stat vm.uptime Oct 25 13:22:16: vmx| DISK: OPEN scsi0:0 '/vmmachines/k-win3/k-win.vmdk' persistent R[(null)] Oct 25 13:22:16: vmx| AIOGNRC: Starting 7 I/O threads. Oct 25 13:22:16: vmx| DISKLIB-DSCPTR: Opened [0]: "k-win-flat.vmdk" 0 (0xa) Oct 25 13:22:16: vmx| DISKLIB-LINK : Opened '/vmmachines/k-win3/k-win.vmdk' (0xa): monolithicFlat, 81938274 sectors / 40009 Mb. Oct 25 13:22:16: vmx| DISKLIB-LIB : Opened "/vmmachines/k-win3/k-win.vmdk" (flags 0xa). Oct 25 13:22:16: vmx| DISK: OPEN '/vmmachines/k-win3/k-win.vmdk' Geo (5100/255/63) BIOS Geo (5100/255/63) freeSpace=245818Mb Oct 25 13:22:16: vmx| TimeTracker host to guest rate conversion 282992638712275 @ 2400000000Hz -> 282992638712275 @ 2400000000Hz Oct 25 13:22:16: vmx| TimeTracker host to guest rate conversion ((x * 2147483648) >> 31) + 0 Oct 25 13:22:16: vmx| MStat: Creating Stat ide0:0.bytesread Oct 25 13:22:16: vmx| MStat: Creating Stat ide0:0.byteswritten Oct 25 13:22:16: vmx| MStat: Creating Stat ide0:1.bytesread Oct 25 13:22:16: vmx| MStat: Creating Stat ide0:1.byteswritten Oct 25 13:22:16: vmx| SCSI0: UNTAGGED commands will be converted to ORDER tags. Oct 25 13:22:16: vmx| MStat: Creating Stat scsi0:0.bytesread Oct 25 13:22:16: vmx| MStat: Creating Stat scsi0:0.byteswritten Oct 25 13:22:16: vmx| DISKUTIL: scsi0:0 : capacity=81938274 Oct 25 13:22:16: vmx| DISKUTIL: scsi0:0 : geometry=5100/255/63 Oct 25 13:22:16: vmx| MStat: Creating Stat vm.heartbeat Oct 25 13:22:16: vmx| DISKUTIL: scsi0:0 : toolsVersion = 6532 Oct 25 13:22:16: vmx| TOOLS INSTALL initializing state to IDLE on power on. Oct 25 13:22:16: vmx| DVGA: DVGA is not supported since the host uses kernel framebuffer Oct 25 13:22:16: vmx| DVGA: Full screen VGA will not be available. Oct 25 13:22:16: vmx| USB: Initializing UHCI host controller Oct 25 13:22:16: vmx| USB: Initializing USB Generic backend Oct 25 13:22:16: vmx| USB: Unable to open "/proc/bus/usb/devices" (No such file or directory). Oct 25 13:22:16: vmx| USB: Unable to initialize USB Generic backend. Oct 25 13:22:16: vmx| VMMon_GetkHzEstimate: Calculated 2400311 kHz Oct 25 13:22:16: vmx| VLANCE: send cluster threshold is 80, size = 2 recalcInterval is 2 ticks Oct 25 13:22:16: vmx| MStat: Creating Stat ethernet0.bytesread Oct 25 13:22:16: vmx| MStat: Creating Stat ethernet0.byteswritten Oct 25 13:22:16: vmx| Ethernet0 MAC Address: 00:0c:29:0a:4b:48 Oct 25 13:22:16: vmx| MStat: Creating Stat ethernet1.bytesread Oct 25 13:22:16: vmx| MStat: Creating Stat ethernet1.byteswritten Oct 25 13:22:16: vmx| Ethernet1 MAC Address: 00:0c:29:0a:4b:52 Oct 25 13:22:16: vmx| VMXNET: send cluster threshold is 80, size = 2 recalcInterval is 2 ticks, dontClusterSize is 128 Oct 25 13:22:16: vmx| E1000: checksum cycles/kB: C=908 asm=291 Oct 25 13:22:16: vmx| CPT: Restoring checkpoint /vmmachines/k-win3/k-win.vmss Oct 25 13:22:16: vmx| DUMPER: restoring checkpoint version 8 Oct 25 13:22:16: vmx| checkpointCPUID: cpt vendor AuthcAMDenti host vendor AuthcAMDenti Oct 25 13:22:16: vmx| checkpointCPUID: cpt family 0f model 413 host family 0f model 413 Oct 25 13:22:16: vmx| checkpointCPUID: cpt id1.ecx 2001 id1.edx 78bfbff id81.ecx 9 id81.edx ebd3fbff Oct 25 13:22:16: vmx| checkpointCPUID: host id1.ecx 2001 id1.edx 78bfbff id81.ecx 9 id81.edx ebd3fbff Oct 25 13:22:16: vmx| DUMPER: item eip [0,-1] in group cpu not used Oct 25 13:22:16: vmx| restoring Snapshot Oct 25 13:22:16: vmx| DUMPER: item cfgFile [-1,-1] in group Snapshot not used Oct 25 13:22:16: vmx| DUMPER: item nvramFile [-1,-1] in group Snapshot not used Oct 25 13:22:16: vmx| restoring memory Oct 25 13:22:16: vmx| Mapped mainmem as pageable Oct 25 13:22:16: vmx| Msg_Reset: Oct 25 13:22:16: vmx| ---------------------------------------- Oct 25 13:22:16: vmx| Opened paging file /vmmachines/k-win3/k-win.vmem Oct 25 13:22:16: vmx| Lazy Restore with prefetch of 307 MB Oct 25 13:22:16: vmx| Msg_Hint: msg.mainmem.lazyResumeHint (not shown) Oct 25 13:22:16: MMPageWalker| Entering MM LazySave Loop Oct 25 13:22:16: vmx| Accepted new connection at 155 for thread servercontrol (0x84da360) Oct 25 13:22:16: vmx| VUINewControlConnection: before slow ACL gunk (bug 63252). Oct 25 13:22:16: vmx| ACL_InitCapabilities: here 2 (bug 63252) Oct 25 13:22:16: vmx| VUINewControlConnection: after slow ACL gunk (bug 63252). Oct 25 13:22:16: vmx| VUI: A new VMControl client connected. Oct 25 13:22:16: vmx| IPC version negotiation version: VMX returning 2.1 to servercontrol Oct 25 13:22:16: vmx| IPC vmcontrol-temp version: VMX returning 11.4 to servercontrol that tried 11.4 Oct 25 13:22:17: vmx| restoring MStats Oct 25 13:22:17: vmx| restoring pic Oct 25 13:22:17: vmx| restoring TimeTracker Oct 25 13:22:17: vmx| TimeTracker host to guest rate conversion 282992638712275 @ 2400000000Hz -> 95216603693558 @ 2400000000Hz Oct 25 13:22:17: vmx| TimeTracker host to guest rate conversion ((x * 2147483648) >> 31) + -187776035018717 Oct 25 13:22:17: vmx| restoring Floppy Oct 25 13:22:17: vmx| restoring GuestMsg Oct 25 13:22:17: vmx| restoring GuestRpc Oct 25 13:22:17: vmx| restoring PCI Oct 25 13:22:17: vmx| restoring Cs440bx Oct 25 13:22:17: vmx| DUMPER: item drb [1,-1] in group Cs440bx not used Oct 25 13:22:17: vmx| DUMPER: item drb [0,-1] in group Cs440bx not used Oct 25 13:22:17: vmx| restoring scsi0:0 Oct 25 13:22:17: vmx| restoring ide0:1 Oct 25 13:22:17: vmx| restoring ide0:0 Oct 25 13:22:17: vmx| restoring vide Oct 25 13:22:17: vmx| DUMPER: Item error [0, -1] not found Oct 25 13:22:17: vmx| DUMPER: Item features [0, -1] not found Oct 25 13:22:17: vmx| DUMPER: Item sectorCount [0, -1] not found Oct 25 13:22:17: vmx| DUMPER: Item sectorNum [0, -1] not found Oct 25 13:22:17: vmx| DUMPER: Item cylinder [0, -1] not found Oct 25 13:22:17: vmx| DUMPER: Item head [0, -1] not found Oct 25 13:22:17: vmx| DUMPER: Item powerMode [0, -1] not found Oct 25 13:22:17: vmx| DUMPER: Item useLBA [0, -1] not found Oct 25 13:22:17: vmx| DUMPER: Item status [0, -1] not found Oct 25 13:22:17: vmx| DUMPER: Item command [0, -1] not found Oct 25 13:22:17: vmx| DUMPER: Item control [0, -1] not found Oct 25 13:22:17: vmx| DUMPER: Item numTransferred [0, -1] not found Oct 25 13:22:17: vmx| DUMPER: BlockItem monbuf [0, -1] not found Oct 25 13:22:17: vmx| DUMPER: BlockItem monbuf [1, -1] not found Oct 25 13:22:17: vmx| restoring SCSI0 Oct 25 13:22:17: vmx| restoring Timer Oct 25 13:22:17: vmx| restoring Tools Oct 25 13:22:17: vmx| restoring Tools Install Oct 25 13:22:17: vmx| TOOLS INSTALL setting state to 0 on restore. Oct 25 13:22:17: vmx| restoring MKSVMX Oct 25 13:22:17: vmx| DUMPER: item imageData [-1,-1] in group MKSVMX not used Oct 25 13:22:17: vmx| restoring VGA Oct 25 13:22:17: vmx| restoring DMA Oct 25 13:22:17: vmx| restoring BackdoorAPM Oct 25 13:22:17: vmx| restoring CMOS Oct 25 13:22:17: vmx| restoring FlashRam Oct 25 13:22:17: vmx| restoring A20 Oct 25 13:22:17: vmx| restoring Keyboard Oct 25 13:22:17: vmx| restoring backdoorAbsMouse Oct 25 13:22:17: vmx| restoring SIO Oct 25 13:22:17: vmx| restoring serial0 Oct 25 13:22:17: vmx| restoring serial1 Oct 25 13:22:17: vmx| restoring serial2 Oct 25 13:22:17: vmx| restoring serial3 Oct 25 13:22:17: vmx| restoring parallel0 Oct 25 13:22:17: vmx| restoring parallel1 Oct 25 13:22:17: vmx| restoring parallel2 Oct 25 13:22:17: vmx| restoring SVGA Oct 25 13:22:17: vmx| SVGA phys sizes: 2360x1770x32 offset 0 fb 0x01000000 Oct 25 13:22:17: vmx| SVGA compatible geometry on checkpoint restore Oct 25 13:22:17: vmx| SVGA old geometry 2360x1770, max size = 16777216 Oct 25 13:22:17: vmx| SVGA new geometry 2360x1770, max size = 16777216 Oct 25 13:22:17: vmx| restoring USB Oct 25 13:22:17: vmx| restoring Ethernet0 Oct 25 13:22:17: vmx| restoring Ethernet1 Oct 25 13:22:17: vmx| restoring monitorLate Oct 25 13:22:17: vmx| restoring devices Oct 25 13:22:17: vmx| DUMPER: Dumper_EndRestore: update hdr magic Oct 25 13:22:17: vmx| VMX_PowerOn: ModuleTable_PowerOn = 1 Oct 25 13:22:17: vmx| VMX setting maximum IPC write buffers to 0 packets, 0 bytes Oct 25 13:22:17: mks| Async MKS thread is alive Oct 25 13:22:17: vcpu-0| APIC: version = 0x10, max LVT = 5 Oct 25 13:22:17: vcpu-0| APIC: LDR = 0x8000000, DFR = 0xffffffff Oct 25 13:22:17: vcpu-0| PShare: enabled 1, scanRate 32, checkRate 16 Oct 25 13:22:17: vcpu-0| guestCpuFeatures = 0xd83dfff8 Oct 25 13:22:17: vcpu-0| Init modules. Oct 25 13:22:17: vcpu-0| Cpt monModules(3). Oct 25 13:22:17: vcpu-0| Done Cpt monModules(3). Oct 25 13:22:17: vmx| CDROM: Using autodetect backend /dev/cdrom for ide0:1. Oct 25 13:22:17: vmx| CDROM: Implementing mediaChange workaround. Oct 25 13:22:17: vmx| CDROM: SEND PACKET API Heuristic active. Oct 25 13:22:17: vmx| CDROM: Using SG_IO ioctl for pass-through. Oct 25 13:22:17: vcpu-0| sz=3100640 Oct 25 13:22:17: vcpu-0| vmm32 initialized: Releasebuild-56528. cflags: 0x00000002.01883000.00000054 Oct 25 13:22:17: vcpu-0| Restoring VM Oct 25 13:22:17: vcpu-0| Sync monModules(4). Oct 25 13:22:17: vcpu-0| Done Sync monModules(4). Oct 25 13:22:17: vcpu-0| SVGA: Registering IOSpace at 0x14a0 (0x0) Oct 25 13:22:17: vcpu-0| SVGA: Registering MemSpace at 0xf0000000(0xf0000000) and 0xe8000000(0xe8000000) Oct 25 13:22:17: vcpu-0| GuestRpc: Channel 0, registration number 3, guest application toolbox. Oct 25 13:22:17: vcpu-0| TOOLS Reducing idleLoopSpinUS to 500us Oct 25 13:22:17: vcpu-0| TOOLS sending 'OS_Resume' (4) state change request Oct 25 13:22:17: vcpu-0| DISKUTIL: scsi0:0 : toolsVersion = 6532 Oct 25 13:22:17: vcpu-0| Guest: toolbox: Version: build-56528 Oct 25 13:22:17: vcpu-0| TOOLS setting the tools version to '6532' Oct 25 13:22:17: vcpu-0| TOOLS unified loop capability requested by 'toolbox'; now sending options via TCLO Oct 25 13:22:17: vcpu-0| TOOLS unified loop capability requested by 'toolbox-dnd'; now sending options via TCLO Oct 25 13:22:17: vcpu-0| GuestRpc: Channel 1, registration number 3, guest application toolbox-dnd. Oct 25 13:22:17: vcpu-0| DISKUTIL: scsi0:0 : toolsVersion = 6532 Oct 25 13:23:22: mks| MKS set guest selection request with invalid state 2 Oct 25 13:23:54: vcpu-0| MKS Backdoor get pointer: first time, notify tools are running Oct 25 13:28:52: MMPageWalker| Exit MM Lazy Loop