Seite 1 von 1

VM Win XP; lässt sich nicht anpingen

Verfasst: 10.02.2009, 17:13
von l.lang
Bemerkt wurde der Fehler, als keine Verbindung mehr zustande kam.
Als dann die VMConsole gestartet ist, wird bei der WinXP-VM ein Fragezeichen angezeigt.
Die VM lässt sich auch nicht mehr anpingen.

Fehlermeldung:
msg.vmxaiomgr.retrycontabourt.rudeunplug:Operation on file "d:\vm\WIN XP Pro\WIN XP Pro.vmdk" failed.

If the file resides on a remote file system, please make sure your network connection and the server where this disk resides are functioning poperly. If the file resides on removable media, reattach the media.
Chose Retry to attempt the operation again.
Choose Abort to terminate this session.
Choose Coninue to forward the error to the guest operating system.

Unter den Events wird angezeigt, dass dieser fehler um 11:49:46 PM aufgetreten ist.

Nachdem per retry die Maschine gestartet wurde läuft diese wieder.

Hostsystem:
WinServer2003,

Ist jemandem dieses Problem auch schon aufgetreten,
Was auch merkwürdig ist, ist dass sich das Computerkonto am Server mehrmals an und abmeldet.

Kann jemand aus Erfahrung sprechen?
Vielen Dank für die Mithilfe.

Verfasst: 10.02.2009, 17:52
von continuum
poste mal das vmware.log - koennte ein Problem mit der vmdk sein

Verfasst: 10.02.2009, 17:58
von l.lang
Hier die log datei, welche als letztes in verwerndung war.

Feb 06 18:00:13.452: vmx| Log for VMware Server pid=5460 version=2.0.0 build=build-122956 option=Release
Feb 06 18:00:13.452: vmx| Hostname=cremersrv01.cremer.local
Feb 06 18:00:13.452: vmx| IP=192.168.170.10
Feb 06 18:00:13.452: vmx| IP=192.168.65.1
Feb 06 18:00:13.452: vmx| IP=192.168.57.1
Feb 06 18:00:13.452: vmx| Command line: "C:\Program Files\VMware\VMware Server\vmware-vmx.exe" "-#" "name=VMware Server;version=2.0.0;buildnumber=122956;licensename=VMware GSX Server for Win32;licenseversion=3.0 build-122956;" "-@" "pipe=\\.\pipe\vmxac89a9f138e3c379;" "d:\vm\WIN XP Pro\WIN XP Pro.vmx"
Feb 06 18:00:13.530: vmx| UI Connecting to pipe '\\.\pipe\vmxac89a9f138e3c379' with user '(null)'
Feb 06 18:00:13.593: vmx| Transitioned vmx/execState/val to poweredOff
Feb 06 18:00:13.593: vmx| MStat: Creating Stat system.cpuusage
Feb 06 18:00:13.593: vmx| MStat: Creating Stat system.ram
Feb 06 18:00:13.593: vmx| MStat: Creating Stat system.uptime
Feb 06 18:00:13.593: vmx| MStat: Creating Stat system.load
Feb 06 18:00:13.593: vmx| HD: host version is 5.2.3790
Feb 06 18:00:13.593: vmx| HD: addr 8083f526
Feb 06 18:00:13.593: vmx| HD: 808ab8e8, 8089c400
Feb 06 18:00:13.593: vmx| VMMon_GetkHzEstimate: Calculated 1599990 kHz
Feb 06 18:00:13.624: vmx| VMMon_RememberkHzEstimate: Calculated 1599990 kHz
Feb 06 18:00:13.624: vmx| CPU # 0 TSC = 537915405648
Feb 06 18:00:13.624: vmx| CPU # 1 TSC = 537915405496
Feb 06 18:00:13.624: vmx| TSC delta 152
Feb 06 18:00:13.624: vmx| PTSC: RefClockToTSC 1599990000Hz -> 1599990000Hz
Feb 06 18:00:13.624: vmx| PTSC: RefClockToTSC ((x * 2147483648) >> 31)
Feb 06 18:00:13.624: vmx| PTSC: using TSC
Feb 06 18:00:13.702: vmx| CPUID[0] vendor: GenuntelineI
Feb 06 18:00:13.702: vmx| CPUID[0] name: Intel(R) Pentium(R) Dual CPU E2140 @ 1.60GHz
Feb 06 18:00:13.702: vmx| CPUID[0] level 00000000, 0: 0x0000000a 0x756e6547 0x6c65746e 0x49656e69
Feb 06 18:00:13.702: vmx| CPUID[0] level 00000001, 0: 0x000006fd 0x00020800 0x0000e39d 0xbfebfbff
Feb 06 18:00:13.702: vmx| CPUID[0] level 00000002, 0: 0x05b0b101 0x005657f0 0x00000000 0x2cb43078
Feb 06 18:00:13.702: vmx| CPUID[0] level 00000003, 0: 0x00000000 0x00000000 0x00000000 0x00000000
Feb 06 18:00:13.702: vmx| CPUID[0] level 00000004, 0: 0x04000121 0x01c0003f 0x0000003f 0x00000001
Feb 06 18:00:13.702: vmx| CPUID[0] level 00000005, 0: 0x00000040 0x00000040 0x00000003 0x00000220
Feb 06 18:00:13.702: vmx| CPUID[0] level 00000006, 0: 0x00000001 0x00000002 0x00000001 0x00000000
Feb 06 18:00:13.702: vmx| CPUID[0] level 00000007, 0: 0x00000000 0x00000000 0x00000000 0x00000000
Feb 06 18:00:13.702: vmx| CPUID[0] level 00000008, 0: 0x00000400 0x00000000 0x00000000 0x00000000
Feb 06 18:00:13.702: vmx| CPUID[0] level 00000009, 0: 0x00000000 0x00000000 0x00000000 0x00000000
Feb 06 18:00:13.702: vmx| CPUID[0] level 0000000a, 0: 0x07280202 0x00000000 0x00000000 0x00000503
Feb 06 18:00:13.702: vmx| CPUID[0] level 80000000, 0: 0x80000008 0x00000000 0x00000000 0x00000000
Feb 06 18:00:13.702: vmx| CPUID[0] level 80000001, 0: 0x00000000 0x00000000 0x00000001 0x20100000
Feb 06 18:00:13.702: vmx| CPUID[0] level 80000002, 0: 0x65746e49 0x2952286c 0x6e655020 0x6d756974
Feb 06 18:00:13.702: vmx| CPUID[0] level 80000003, 0: 0x20295228 0x6c617544 0x50432020 0x45202055
Feb 06 18:00:13.702: vmx| CPUID[0] level 80000004, 0: 0x30343132 0x20402020 0x30362e31 0x007a4847
Feb 06 18:00:13.702: vmx| CPUID[0] level 80000005, 0: 0x00000000 0x00000000 0x00000000 0x00000000
Feb 06 18:00:13.702: vmx| CPUID[0] level 80000006, 0: 0x00000000 0x00000000 0x04004040 0x00000000
Feb 06 18:00:13.702: vmx| CPUID[0] level 80000007, 0: 0x00000000 0x00000000 0x00000000 0x00000000
Feb 06 18:00:13.702: vmx| CPUID[0] level 80000008, 0: 0x00003024 0x00000000 0x00000000 0x00000000
Feb 06 18:00:13.702: vmx| CPUID[1] vendor: GenuntelineI
Feb 06 18:00:13.702: vmx| CPUID[1] name: Intel(R) Pentium(R) Dual CPU E2140 @ 1.60GHz
Feb 06 18:00:13.702: vmx| CPUID[1] level 00000000, 0: 0x0000000a 0x756e6547 0x6c65746e 0x49656e69
Feb 06 18:00:13.702: vmx| CPUID[1] level 00000001, 0: 0x000006fd 0x01020800 0x0000e39d 0xbfebfbff
Feb 06 18:00:13.702: vmx| CPUID[1] level 00000002, 0: 0x05b0b101 0x005657f0 0x00000000 0x2cb43078
Feb 06 18:00:13.702: vmx| CPUID[1] level 00000003, 0: 0x00000000 0x00000000 0x00000000 0x00000000
Feb 06 18:00:13.702: vmx| CPUID[1] level 00000004, 0: 0x04000121 0x01c0003f 0x0000003f 0x00000001
Feb 06 18:00:13.702: vmx| CPUID[1] level 00000005, 0: 0x00000040 0x00000040 0x00000003 0x00000220
Feb 06 18:00:13.702: vmx| CPUID[1] level 00000006, 0: 0x00000001 0x00000002 0x00000001 0x00000000
Feb 06 18:00:13.702: vmx| CPUID[1] level 00000007, 0: 0x00000000 0x00000000 0x00000000 0x00000000
Feb 06 18:00:13.702: vmx| CPUID[1] level 00000008, 0: 0x00000400 0x00000000 0x00000000 0x00000000
Feb 06 18:00:13.702: vmx| CPUID[1] level 00000009, 0: 0x00000000 0x00000000 0x00000000 0x00000000
Feb 06 18:00:13.702: vmx| CPUID[1] level 0000000a, 0: 0x07280202 0x00000000 0x00000000 0x00000503
Feb 06 18:00:13.702: vmx| CPUID[1] level 80000000, 0: 0x80000008 0x00000000 0x00000000 0x00000000
Feb 06 18:00:13.702: vmx| CPUID[1] level 80000001, 0: 0x00000000 0x00000000 0x00000001 0x20100000
Feb 06 18:00:13.702: vmx| CPUID[1] level 80000002, 0: 0x65746e49 0x2952286c 0x6e655020 0x6d756974
Feb 06 18:00:13.702: vmx| CPUID[1] level 80000003, 0: 0x20295228 0x6c617544 0x50432020 0x45202055
Feb 06 18:00:13.702: vmx| CPUID[1] level 80000004, 0: 0x30343132 0x20402020 0x30362e31 0x007a4847
Feb 06 18:00:13.702: vmx| CPUID[1] level 80000005, 0: 0x00000000 0x00000000 0x00000000 0x00000000
Feb 06 18:00:13.702: vmx| CPUID[1] level 80000006, 0: 0x00000000 0x00000000 0x04004040 0x00000000
Feb 06 18:00:13.702: vmx| CPUID[1] level 80000007, 0: 0x00000000 0x00000000 0x00000000 0x00000000
Feb 06 18:00:13.702: vmx| CPUID[1] level 80000008, 0: 0x00003024 0x00000000 0x00000000 0x00000000
Feb 06 18:00:13.702: vmx| hostCPUID vendor: GenuntelineI
Feb 06 18:00:13.702: vmx| hostCPUID name: Intel(R) Pentium(R) Dual CPU E2140 @ 1.60GHz
Feb 06 18:00:13.702: vmx| hostCPUID level 00000000, 0: 0x0000000a 0x756e6547 0x6c65746e 0x49656e69
Feb 06 18:00:13.702: vmx| hostCPUID level 00000001, 0: 0x000006fd 0x00020800 0x0000e39d 0xbfebfbff
Feb 06 18:00:13.702: vmx| hostCPUID level 00000002, 0: 0x05b0b101 0x005657f0 0x00000000 0x2cb43078
Feb 06 18:00:13.702: vmx| hostCPUID level 00000003, 0: 0x00000000 0x00000000 0x00000000 0x00000000
Feb 06 18:00:13.702: vmx| hostCPUID level 00000004, 0: 0x04000121 0x01c0003f 0x0000003f 0x00000001
Feb 06 18:00:13.702: vmx| hostCPUID level 00000005, 0: 0x00000040 0x00000040 0x00000003 0x00000220
Feb 06 18:00:13.702: vmx| hostCPUID level 00000006, 0: 0x00000001 0x00000002 0x00000001 0x00000000
Feb 06 18:00:13.702: vmx| hostCPUID level 00000007, 0: 0x00000000 0x00000000 0x00000000 0x00000000
Feb 06 18:00:13.702: vmx| hostCPUID level 00000008, 0: 0x00000400 0x00000000 0x00000000 0x00000000
Feb 06 18:00:13.702: vmx| hostCPUID level 00000009, 0: 0x00000000 0x00000000 0x00000000 0x00000000
Feb 06 18:00:13.702: vmx| hostCPUID level 0000000a, 0: 0x07280202 0x00000000 0x00000000 0x00000503
Feb 06 18:00:13.702: vmx| hostCPUID level 80000000, 0: 0x80000008 0x00000000 0x00000000 0x00000000
Feb 06 18:00:13.702: vmx| hostCPUID level 80000001, 0: 0x00000000 0x00000000 0x00000001 0x20100000
Feb 06 18:00:13.702: vmx| hostCPUID level 80000002, 0: 0x65746e49 0x2952286c 0x6e655020 0x6d756974
Feb 06 18:00:13.702: vmx| hostCPUID level 80000003, 0: 0x20295228 0x6c617544 0x50432020 0x45202055
Feb 06 18:00:13.702: vmx| hostCPUID level 80000004, 0: 0x30343132 0x20402020 0x30362e31 0x007a4847
Feb 06 18:00:13.702: vmx| hostCPUID level 80000005, 0: 0x00000000 0x00000000 0x00000000 0x00000000
Feb 06 18:00:13.702: vmx| hostCPUID level 80000006, 0: 0x00000000 0x00000000 0x04004040 0x00000000
Feb 06 18:00:13.702: vmx| hostCPUID level 80000007, 0: 0x00000000 0x00000000 0x00000000 0x00000000
Feb 06 18:00:13.702: vmx| hostCPUID level 80000008, 0: 0x00003024 0x00000000 0x00000000 0x00000000
Feb 06 18:00:13.702: vmx| CPUID Maximum Physical Address Bits supported across all CPUs: 36
Feb 06 18:00:13.749: vmx| Host ACPI: can't find SRAT
Feb 06 18:00:13.749: vmx| Host: SRAT tables not found in memory
Feb 06 18:00:13.749: vmx| ACL_InitCapabilities: current IPC thread
Feb 06 18:00:13.749: vmx| ACL_InitCapabilities: done
Feb 06 18:00:13.749: vmx| changing directory to d:\vm\WIN XP Pro\.
Feb 06 18:00:13.749: vmx| Config file: d:\vm\WIN XP Pro\WIN XP Pro.vmx
Feb 06 18:00:13.749: vmx| LOG failed to remove D:\vm\WIN XP Pro\vmware-2.log failed: The system cannot find the file specified
Feb 06 18:00:13.921: vmx| VMXVmdb_LoadRawConfig: Loading raw config
Feb 06 18:00:14.124: vmx| VMXVmdbCbVmVmxExecState: Exec state change requested to state poweredOn without reset, soft.
Feb 06 18:00:14.124: vmx| TOOLS delaying state change request to state 3
Feb 06 18:00:14.124: vmx| PowerOn
Feb 06 18:00:14.124: vmx| VMX_PowerOn: VMX build 122956, UI build 122956
Feb 06 18:00:14.124: vmx| Host: WIN32 highest NUMA node 0
Feb 06 18:00:14.124: vmx| Host: WIN32 NUMA node 0, CPU mask 0x0000000000000003
Feb 06 18:00:14.124: vmx| VMXVmdb_LoadRawConfig: Loading raw config
Feb 06 18:00:14.140: vmx| HOST Windows version 5.2, build 3790, platform 2, "Service Pack 1", SMP
Feb 06 18:00:14.140: vmx| DICT --- USER PREFERENCES
Feb 06 18:00:14.140: vmx| DICT --- USER DEFAULTS
Feb 06 18:00:14.140: vmx| DICT --- HOST DEFAULTS
Feb 06 18:00:14.140: vmx| DICT authd.client.port = 902
Feb 06 18:00:14.140: vmx| DICT authd.proxy.vim = vmware-hostd:hostd-vmdb
Feb 06 18:00:14.140: vmx| DICT authd.proxy.nfc = vmware-hostd:ha-nfc
Feb 06 18:00:14.140: vmx| DICT --- SITE DEFAULTS
Feb 06 18:00:14.140: vmx| DICT authd.client.port = 902
Feb 06 18:00:14.140: vmx| DICT authd.proxy.vim = vmware-hostd:hostd-vmdb
Feb 06 18:00:14.140: vmx| DICT authd.proxy.nfc = vmware-hostd:ha-nfc
Feb 06 18:00:14.140: vmx| DICT --- COMMAND LINE
Feb 06 18:00:14.140: vmx| DICT --- CONFIGURATION
Feb 06 18:00:14.140: vmx| DICT config.version = 8
Feb 06 18:00:14.140: vmx| DICT virtualHW.version = 7
Feb 06 18:00:14.140: vmx| DICT floppy0.present = FALSE
Feb 06 18:00:14.140: vmx| DICT mks.enable3d = TRUE
Feb 06 18:00:14.140: vmx| DICT pciBridge0.present = TRUE
Feb 06 18:00:14.140: vmx| DICT pciBridge4.present = TRUE
Feb 06 18:00:14.140: vmx| DICT pciBridge4.virtualDev = pcieRootPort
Feb 06 18:00:14.140: vmx| DICT pciBridge4.functions = 8
Feb 06 18:00:14.140: vmx| DICT pciBridge5.present = TRUE
Feb 06 18:00:14.140: vmx| DICT pciBridge5.virtualDev = pcieRootPort
Feb 06 18:00:14.140: vmx| DICT pciBridge5.functions = 8
Feb 06 18:00:14.140: vmx| DICT pciBridge6.present = TRUE
Feb 06 18:00:14.140: vmx| DICT pciBridge6.virtualDev = pcieRootPort
Feb 06 18:00:14.140: vmx| DICT pciBridge6.functions = 8
Feb 06 18:00:14.140: vmx| DICT pciBridge7.present = TRUE
Feb 06 18:00:14.140: vmx| DICT pciBridge7.virtualDev = pcieRootPort
Feb 06 18:00:14.140: vmx| DICT pciBridge7.functions = 8
Feb 06 18:00:14.140: vmx| DICT vmci0.present = TRUE
Feb 06 18:00:14.140: vmx| DICT nvram = WIN XP Pro.nvram
Feb 06 18:00:14.140: vmx| DICT virtualHW.productCompatibility = hosted
Feb 06 18:00:14.140: vmx| DICT ft.secondary0.enabled = TRUE
Feb 06 18:00:14.140: vmx| DICT tools.upgrade.policy = useGlobal
Feb 06 18:00:14.140: vmx| DICT powerType.powerOff = soft
Feb 06 18:00:14.140: vmx| DICT powerType.powerOn = hard
Feb 06 18:00:14.140: vmx| DICT powerType.suspend = hard
Feb 06 18:00:14.140: vmx| DICT powerType.reset = soft
Feb 06 18:00:14.140: vmx| DICT displayName = WIN XP Pro
Feb 06 18:00:14.140: vmx| DICT extendedConfigFile = WIN XP Pro.vmxf
Feb 06 18:00:14.140: vmx| DICT memsize = 1024
Feb 06 18:00:14.140: vmx| DICT ide0:0.present = TRUE
Feb 06 18:00:14.140: vmx| DICT ide0:0.fileName = WIN XP Pro.vmdk
Feb 06 18:00:14.140: vmx| DICT ide0:0.writeThrough = TRUE
Feb 06 18:00:14.140: vmx| DICT ide1:0.present = TRUE
Feb 06 18:00:14.140: vmx| DICT ide1:0.fileName = E:
Feb 06 18:00:14.140: vmx| DICT ide1:0.deviceType = atapi-cdrom
Feb 06 18:00:14.140: vmx| DICT ide1:0.allowGuestConnectionControl = FALSE
Feb 06 18:00:14.140: vmx| DICT ethernet0.present = TRUE
Feb 06 18:00:14.140: vmx| DICT ethernet0.allowGuestConnectionControl = FALSE
Feb 06 18:00:14.140: vmx| DICT ethernet0.features = 1
Feb 06 18:00:14.140: vmx| DICT ethernet0.wakeOnPcktRcv = FALSE
Feb 06 18:00:14.140: vmx| DICT ethernet0.networkName = Bridged
Feb 06 18:00:14.140: vmx| DICT ethernet0.addressType = generated
Feb 06 18:00:14.140: vmx| DICT usb.present = TRUE
Feb 06 18:00:14.140: vmx| DICT ehci.present = TRUE
Feb 06 18:00:14.140: vmx| DICT guestOS = winxppro
Feb 06 18:00:14.140: vmx| DICT uuid.location = 56 4d 60 be 87 d8 86 fc-ca 5a ac fe 2a 9a 98 cc
Feb 06 18:00:14.140: vmx| DICT uuid.bios = 56 4d 60 be 87 d8 86 fc-ca 5a ac fe 2a 9a 98 cc
Feb 06 18:00:14.140: vmx| DICT vc.uuid = 52 57 f0 89 13 ad 7a 25-62 62 38 c3 c7 82 4d 79
Feb 06 18:00:14.140: vmx| DICT ethernet0.generatedAddress = 00:0c:29:9a:98:cc
Feb 06 18:00:14.140: vmx| DICT ide0:0.redo =
Feb 06 18:00:14.140: vmx| DICT vmotion.checkpointFBSize = 134217728
Feb 06 18:00:14.140: vmx| DICT pciBridge0.pciSlotNumber = 17
Feb 06 18:00:14.140: vmx| DICT pciBridge4.pciSlotNumber = 21
Feb 06 18:00:14.140: vmx| DICT pciBridge5.pciSlotNumber = 22
Feb 06 18:00:14.140: vmx| DICT pciBridge6.pciSlotNumber = 23
Feb 06 18:00:14.140: vmx| DICT pciBridge7.pciSlotNumber = 24
Feb 06 18:00:14.140: vmx| DICT usb.pciSlotNumber = 32
Feb 06 18:00:14.140: vmx| DICT ethernet0.pciSlotNumber = 33
Feb 06 18:00:14.140: vmx| DICT ehci.pciSlotNumber = 34
Feb 06 18:00:14.140: vmx| DICT vmci0.pciSlotNumber = 35
Feb 06 18:00:14.140: vmx| DICT usb:0.present = TRUE
Feb 06 18:00:14.140: vmx| DICT usb:1.present = TRUE
Feb 06 18:00:14.140: vmx| DICT ethernet0.generatedAddressOffset = 0
Feb 06 18:00:14.140: vmx| DICT vmci0.id = 714774732
Feb 06 18:00:14.140: vmx| DICT tools.remindInstall = TRUE
Feb 06 18:00:14.140: vmx| DICT usb:1.deviceType = hub
Feb 06 18:00:14.140: vmx| DICT usb:0.deviceType = mouse
Feb 06 18:00:14.140: vmx| DICT --- USER DEFAULTS
Feb 06 18:00:14.140: vmx| DICT --- HOST DEFAULTS
Feb 06 18:00:14.140: vmx| DICT authd.client.port = 902
Feb 06 18:00:14.140: vmx| DICT authd.proxy.vim = vmware-hostd:hostd-vmdb
Feb 06 18:00:14.140: vmx| DICT authd.proxy.nfc = vmware-hostd:ha-nfc
Feb 06 18:00:14.140: vmx| DICT --- SITE DEFAULTS
Feb 06 18:00:14.140: vmx| DICT authd.client.port = 902
Feb 06 18:00:14.140: vmx| DICT authd.proxy.vim = vmware-hostd:hostd-vmdb
Feb 06 18:00:14.140: vmx| DICT authd.proxy.nfc = vmware-hostd:ha-nfc
Feb 06 18:00:14.140: vmx| DICT --- GLOBAL SETTINGS
Feb 06 18:00:14.140: vmx| Msg_Hint: msg.guestos.xp (sent)
Feb 06 18:00:14.140: vmx| The Microsoft Windows XP product activation feature creates a key based on the virtual hardware in the virtual machine where it is installed. Changes in the virtual machine configuration may require you to reactivate the guest operating system. To minimize those changes, be sure to set the final memory size for the virtual machine and install VMware Tools before you activate Windows XP.
Feb 06 18:00:14.140: vmx| For more information about Windows XP product activation and virtual machines see our Web site at "http://vmware.com/info?id=21".
Feb 06 18:00:14.140: vmx| ---------------------------------------
Feb 06 18:00:14.265: vmx| hostCpuFeatures = 0x404001fc
Feb 06 18:00:14.265: vmx| hostNumPerfCounters = 2
Feb 06 18:00:14.265: vmx| CPU0: PMC: IA32, CORE 2 [c:0 f:1 e:1]
Feb 06 18:00:14.265: vmx| CPU1: PMC: IA32, CORE 2 [c:0 f:1 e:1]
Feb 06 18:00:14.265: vmx| MONITOR MODE: allowed modes : BT
Feb 06 18:00:14.265: vmx| MONITOR MODE: user requested modes : BT HV HWMMU
Feb 06 18:00:14.265: vmx| MONITOR MODE: guestOS preferred modes: BT HWMMU HV
Feb 06 18:00:14.265: vmx| MONITOR MODE: filtered list : BT
Feb 06 18:00:14.265: vmx| HV Settings: virtual exec = 'software'; virtual mmu = 'software'
Feb 06 18:00:14.343: vmx| VMXVmdb_LoadRawConfig: Loading raw config
Feb 06 18:00:14.358: vmx| Host display topology 1024x768.
Feb 06 18:00:14.358: vmx| SVGA using 2360x1770.
Feb 06 18:00:14.358: vmx| WSSCAN: reserved mem (in MB) min=32 max=2940 recommended=2940
Feb 06 18:00:14.358: vmx| WSSCAN: used rec mem (in MB) 2940
Feb 06 18:00:14.358: vmx| PSHARE: scan rate min 16 max 400
Feb 06 18:00:14.358: vmx| WSSCAN: Overhead 296222 paged 16918 nonpaged 32768 maxFBSize
Feb 06 18:00:14.390: vmx| WSSCAN 1 1 646651 698540 752640 654843 50 0
Feb 06 18:00:14.390: vmx| LICENSE using: 'HKEY_LOCAL_MACHINE\SOFTWARE\VMware, Inc.\VMware Server\Dormant\License.gsx.3.0-00'
Feb 06 18:00:14.499: vmx| LOG failed to remove stats\stats32-2 failed: The system cannot find the path specified
Feb 06 18:00:14.499: vmx| LOG failed to rename stats\stats32-1 -> stats\stats32-2 failed: The system cannot find the path specified
Feb 06 18:00:14.499: vmx| LOG failed to rename stats\stats32-0 -> stats\stats32-1 failed: The system cannot find the path specified
Feb 06 18:00:14.499: vmx| LOG failed to rename stats\stats32 -> stats\stats32-0 failed: The system cannot find the path specified
Feb 06 18:00:14.499: vmx| LOG failed to remove stats\stats64-2 failed: The system cannot find the path specified
Feb 06 18:00:14.499: vmx| LOG failed to rename stats\stats64-1 -> stats\stats64-2 failed: The system cannot find the path specified
Feb 06 18:00:14.499: vmx| LOG failed to rename stats\stats64-0 -> stats\stats64-1 failed: The system cannot find the path specified
Feb 06 18:00:14.499: vmx| LOG failed to rename stats\stats64 -> stats\stats64-0 failed: The system cannot find the path specified
Feb 06 18:00:14.499: vmx| LOG failed to remove stats32-2 failed: The system cannot find the file specified
Feb 06 18:00:14.499: vmx| LOG failed to remove stats64-2 failed: The system cannot find the file specified
Feb 06 18:00:14.499: vmx| Monitor_PowerOn: HostedVSMP skew tracking is disabled
Feb 06 18:00:14.499: vmx| Monitor_PowerOn: HostedVSMP crosscall yielding is disabled
Feb 06 18:00:14.499: vmx| vmm32-modules: [vmm.vmm32 .data:0x2b000-0x728 .sdata:0x2c000-0x4e4 .statvars:0x2d000-0x3a0 .peer:0x2e000-0x261c0 .shared:0x56000-0x12ac0 .bss:0x6a000-0x5c88 .rodata:0x71000-0xb310 .text:0x7d000-0x58f2d .kstatvars:0x3000-0x0, mmu-pae.vmm32 .rodata:0x7c310-0x44 .data:0x2b728-0xc .peer:0x541c0-0x16c0 .shared:0x68ac0-0x2c0 .bss:0x6fca0-0x518 .text:0xd5f30-0xa4e7 .comment:0x40000d26-0x10e .statvars:0x2000-0x0 .kstatvars:0x2000-0x0 .scb:0x400038a0-0x180 .shared_meta:0x40003c00-0x390 .peer_meta:0x40001020-0x210, sharedmmu-none.vmm32 .text:0xe0418-0x32 .comment:0x40000e34-0x12, pv-none.vmm32 .shared:0x68d80-0x180 .bss:0x701c0-0x84 .text:0xe044c-0xe6 .comment:0x40000e46-0x48 .shared_meta:0x40003f90-0x90, vprobe-none.vmm32 .text:0xe0534-0x79 .comment:0x40000e8e-0x12, hv-none.vmm32 .rodata:0x7c354-0x4 .data:0x1000-0x0 .peer:0x1000-0x0 .shared:0x1000-0x0 .bss:0x1000-0x0 .text:0xe05b0-0x2d .comment:0x40000ea0-0x12 .statvars:0x1000-0x0 .kstatvars:0x1000-0x0, gphys-sw.vmm32 .peer:0x55880-0x40 .shared:0x68f00-0x140 .bss:0x2000-0x0 .text:0xe05e0-0xb43 .comment:0x40000eb2-0x12 .scb:0x40003a20-0x60 .shared_meta:0x40004020-0x2a0 .peer_meta:0x40001230-0x60, vassert-none.vmm32 .text:0xe1124-0xf .comment:0x40000ec4-0x12, vmsafe-none.vmm32 .text:0xe1134-0xd .comment:0x40000ed6-0x12, <MonSrcFile> .rodata:0x7c358-0x36c]
Feb 06 18:00:14.499: vmx| KHZEstimate 1599990
Feb 06 18:00:14.499: vmx| MHZEstimate 1600
Feb 06 18:00:14.499: vmx| NumVCPUs 1
Feb 06 18:00:14.499: vmx| PShare: enabled 1, scanRate 32, checkRate 16
Feb 06 18:00:14.499: vmx| UUID: location-UUID is 56 4d 58 8a 1d 8d ee ad-3d a9 da 1d e2 6b 03 8b
Feb 06 18:00:14.499: vmx| UUID: location-UUID is 56 4d 60 be 87 d8 86 fc-ca 5a ac fe 2a 9a 98 cc
Feb 06 18:00:14.499: vmx| UUID: location-UUID is 56 4d 60 be 87 d8 86 fc-ca 5a ac fe 2a 9a 98 cc
Feb 06 18:00:14.515: vmx| AIOGNRC: numThreads=4 ide=1, scsi=0, passthru=1
Feb 06 18:00:14.515: vmx| WORKER: Creating new group with numThreads=4 (4)
Feb 06 18:00:14.515: vmx| Replay State = 0
Feb 06 18:00:14.515: vmx| minDEThreshold: 70
Feb 06 18:00:14.515: vmx| MM: Using partialmap, 262144 pages AC 0 CE 1 TM 0 DOHU 0
Feb 06 18:00:14.515: vmx| UUID: location-UUID is 56 4d 60 be 87 d8 86 fc-ca 5a ac fe 2a 9a 98 cc
Feb 06 18:00:14.515: vmx| FILE: ScanDirectory discarding M21852.lck from d:\vm\WIN XP Pro\564d60be-87d8-86fc-ca5a-acfe2a9a98cc.vmem.lck': invalid executionID.
Feb 06 18:00:14.515: vmx| MM: using 'd:\vm\WIN XP Pro\564d60be-87d8-86fc-ca5a-acfe2a9a98cc.vmem' as a paging file
Feb 06 18:00:14.515: vmx| Opened paging file d:\vm\WIN XP Pro\564d60be-87d8-86fc-ca5a-acfe2a9a98cc.vmem
Feb 06 18:00:14.561: vmx| Mapped mainmem as pageable
Feb 06 18:00:14.561: vmx| MMCHK: Disabling checks which are not forced, cannot do checks on certain processors.
Feb 06 18:00:14.561: vmx| MStat: Creating Stat vm.cpuusage
Feb 06 18:00:14.561: vmx| MStat: Creating Stat vm.ram
Feb 06 18:00:14.561: vmx| MStat: Creating Stat vm.uptime
Feb 06 18:00:14.561: vmx| MStat: Creating Stat vm.suspendTime
Feb 06 18:00:14.561: vmx| MStat: Creating Stat vm.powerOnTimeStamp
Feb 06 18:00:14.561: vmx| VMXAIOMGR: Using: simple=Generic unbuf=Compl
Feb 06 18:00:14.577: vmx| VMXVmdb_LoadRawConfig: Loading raw config
Feb 06 18:00:14.577: vmx| DISK: OPEN ide0:0 'd:\vm\WIN XP Pro\WIN XP Pro.vmdk' persistent R[]
Feb 06 18:00:14.624: vmx| FILE: ScanDirectory discarding M22041.lck from d:\vm\WIN XP Pro\WIN XP Pro.vmdk.lck': invalid executionID.
Feb 06 18:00:14.811: vmx| DISKLIB-DSCPTR: Opened [0]: "WIN XP Pro.vmdk" (0x2a)
Feb 06 18:00:14.811: vmx| DISKLIB-LINK : Opened 'd:\vm\WIN XP Pro\WIN XP Pro.vmdk' (0x2a): monolithicSparse, 31457280 sectors / 15 GB.
Feb 06 18:00:14.811: vmx| DISKLIB-LIB : Opened "d:\vm\WIN XP Pro\WIN XP Pro.vmdk" (flags 0x2a).
Feb 06 18:00:14.811: vmx| DiskGetGeometry: Reading of disk partition table
Feb 06 18:00:14.827: vmx| DISK: OPEN 'd:\vm\WIN XP Pro\WIN XP Pro.vmdk' Geo (16383/16/63) BIOS Geo (1958/255/63) freeSpace=85630 MB, ide
Feb 06 18:00:14.827: vmx| TimeTracker host to guest rate conversion 539842921528 @ 1599990000Hz -> 539842921528 @ 1599990000Hz
Feb 06 18:00:14.827: vmx| TimeTracker host to guest rate conversion ((x * 2147483648) >> 31) + 0
Feb 06 18:00:14.827: vmx| USB: Initializing 'Generic' backend
Feb 06 18:00:14.827: vmx| USBGW: Connected to HCMON version 3.0
Feb 06 18:00:14.827: vmx| USB: Initializing 'Virtual Hub' backend
Feb 06 18:00:14.827: vmx| USB: Initializing 'Virtual Mouse' backend
Feb 06 18:00:14.827: vmx| USB: Initializing 'Virtual Keyboard' backend
Feb 06 18:00:14.827: vmx| USB: Initializing 'Remote Device' backend
Feb 06 18:00:14.827: vmx| RemoteUSBVMX: no delay setting is TRUE.
Feb 06 18:00:14.827: vmx| USB: Initializing 'Virtual Mass Storage' backend
Feb 06 18:00:14.827: vmx| USB: Initializing 'Virtual CCID' backend
Feb 06 18:00:14.843: vmx| USB-CCID: Could not establish resource manager context for card ops: SCARD_E_NO_SERVICE(0x8010001d).
Feb 06 18:00:14.843: vmx| USB: Unable to initialize 'Virtual CCID' backend
Feb 06 18:00:14.890: vmx| USBGW: Skipping disk backing for file (d:\vm\WIN XP Pro\WIN XP Pro.vmx).
Feb 06 18:00:14.890: vmx| USBGW: Skipping disk backing for file (d:\vm\WIN XP Pro\WIN XP Pro.vmdk).
Feb 06 18:00:14.890: vmx| USBGW: Skipping disk backing for file (d:\vm\WIN XP Pro\WIN XP Pro.vmsd).
Feb 06 18:00:14.905: vmx| USBGW: Skipping disk backing for file (d:\vm\WIN XP Pro\WIN XP Pro.nvram).
Feb 06 18:00:14.968: vmx| Host display topology 1024x768.
Feb 06 18:00:14.968: vmx| SVGA using 2360x1770.
Feb 06 18:00:15.171: vmx| WORKER: Creating new group with numThreads=1 (5)
Feb 06 18:00:15.171: vmx| MKS REMOTE Loading VNC Configuration from VM config file
Feb 06 18:00:15.186: vmx| VLANCE: send cluster threshold is 80, size = 2 recalcInterval is 2 ticks
Feb 06 18:00:15.186: vmx| VMXNET: send cluster threshold is 80, size = 2 recalcInterval is 2 ticks, dontClusterSize is 128
Feb 06 18:00:15.186: vmx| NetPkt: checksum cycles/kB: C=68 asm1=680 asm2=680
Feb 06 18:00:15.186: vmx| NetPkt: copy and sum cycles/kB: C=1410 asm1=679 asm2=679
Feb 06 18:00:15.186: vmx| Chipset version: 0x13
Feb 06 18:00:15.202: vmx| DISKUTIL: ide0:0 : capacity=31457280
Feb 06 18:00:15.202: vmx| MStat: Creating Stat ide0:0.bytesread
Feb 06 18:00:15.202: vmx| MStat: Creating Stat ide0:0.byteswritten
Feb 06 18:00:15.202: vmx| DISKUTIL: ide0:0 : capacity=31457280
Feb 06 18:00:15.202: vmx| DISKUTIL: ide0:0 : geometry=1958/255/63
Feb 06 18:00:15.202: vmx| MStat: Creating Stat ide1:0.bytesread
Feb 06 18:00:15.202: vmx| MStat: Creating Stat ide1:0.byteswritten
Feb 06 18:00:15.202: vmx| DISKUTIL: ide1:0 : capacity=0
Feb 06 18:00:15.202: vmx| DISKUTIL: ide1:0 : geometry=0/0/0
Feb 06 18:00:15.202: vmx| SVGA: Advertising 3D acceleration.
Feb 06 18:00:15.202: vmx| Host display topology 1024x768 with 1 displays.
Feb 06 18:00:15.202: vmx| SVGA using 2360x1770.
Feb 06 18:00:15.202: vmx| USB: Initializing 'UHCI' host controller
Feb 06 18:00:15.202: vmx| MStat: Creating Stat ethernet0.bytesread
Feb 06 18:00:15.202: vmx| MStat: Creating Stat ethernet0.byteswritten
Feb 06 18:00:15.202: vmx| Ethernet0 MAC Address: 00:0c:29:9a:98:cc
Feb 06 18:00:15.202: vmx| USB: Initializing 'EHCI' host controller
Feb 06 18:00:15.202: vmx| MStat: Creating Stat vm.heartbeat
Feb 06 18:00:15.202: vmx| DISKUTIL: ide0:0 : toolsVersion = 0
Feb 06 18:00:15.202: vmx| DISKUTIL: Offline toolsVersion = 0
Feb 06 18:00:15.218: vmx| Msg_Hint: msg.tools.toolsReminder (sent)
Feb 06 18:00:15.218: vmx| Do not forget to install the VMware Tools package inside this virtual machine: wait until your guest operating system finishes booting, then choose VM > Install VMware Tools… and follow the instructions.
Feb 06 18:00:15.218: vmx| ---------------------------------------
Feb 06 18:00:15.499: vmx| TOOLS INSTALL initializing state to IDLE on power on.
Feb 06 18:00:15.530: vmx| PTSC to VMI Wallclock (nsec) 540964123712 @ 1599990000Hz -> 1233939615000000000 @ 1000000000Hz
Feb 06 18:00:15.530: vmx| PTSC to VMI Wallclock (nsec) ((x * 2684371337) >> 32) + 1233939276895309567
Feb 06 18:00:15.530: vmx| PTSC to ParaTime RealCycles 0 @ 1599990000Hz -> 0 @ 1599990000Hz
Feb 06 18:00:15.530: vmx| PTSC to ParaTime RealCycles ((x * 1) >> 0) + 0
Feb 06 18:00:15.530: vmx| ParaTime RealCycles to PTSC 0 @ 1599990000Hz -> 0 @ 1599990000Hz
Feb 06 18:00:15.530: vmx| ParaTime RealCycles to PTSC ((x * 1) >> 0) + 0
Feb 06 18:00:15.530: vmx| memoryHotplug: Current size = 1024MB, Minimum size = 1024MB, Maximum size = 1024MB
Feb 06 18:00:15.530: vmx| memoryHotplug: Entry[0]: 00000000000000A0-00000000000A0000
Feb 06 18:00:15.530: vmx| memoryHotplug: Entry[1]: 00000000001000A0-0000000040000000
Feb 06 18:00:15.530: vmx| guestCpuFeatures = 0x404001f8
Feb 06 18:00:15.530: vmx| guestCPUID vendor: GenuntelineI
Feb 06 18:00:15.530: vmx| guestCPUID name: Intel(R) Pentium(R) Dual CPU E2140 @ 1.60GHz
Feb 06 18:00:15.530: vmx| guestCPUID level 00000000, 0: 0x0000000a 0x756e6547 0x6c65746e 0x49656e69
Feb 06 18:00:15.530: vmx| guestCPUID level 00000001, 0: 0x000006fd 0x00010800 0x80000201 0x0febfbff
Feb 06 18:00:15.530: vmx| guestCPUID level 00000002, 0: 0x05b0b101 0x005657f0 0x00000000 0x2cb43078
Feb 06 18:00:15.530: vmx| guestCPUID level 00000003, 0: 0x00000000 0x00000000 0x00000000 0x00000000
Feb 06 18:00:15.530: vmx| guestCPUID level 00000004, 0: 0x04000121 0x01c0003f 0x0000003f 0x00000001
Feb 06 18:00:15.530: vmx| guestCPUID level 00000005, 0: 0x00000040 0x00000040 0x00000003 0x00000220
Feb 06 18:00:15.530: vmx| guestCPUID level 00000006, 0: 0x00000001 0x00000002 0x00000001 0x00000000
Feb 06 18:00:15.530: vmx| guestCPUID level 00000007, 0: 0x00000000 0x00000000 0x00000000 0x00000000
Feb 06 18:00:15.530: vmx| guestCPUID level 00000008, 0: 0x00000400 0x00000000 0x00000000 0x00000000
Feb 06 18:00:15.530: vmx| guestCPUID level 00000009, 0: 0x00000000 0x00000000 0x00000000 0x00000000
Feb 06 18:00:15.530: vmx| guestCPUID level 0000000a, 0: 0x07280202 0x00000000 0x00000000 0x00000503
Feb 06 18:00:15.530: vmx| guestCPUID level 40000000, 0: 0x40000010 0x61774d56 0x4d566572 0x65726177
Feb 06 18:00:15.530: vmx| guestCPUID level 40000010, 0: 0x001869f6 0x000101d0 0x00000000 0x00000000
Feb 06 18:00:15.530: vmx| guestCPUID level 80000000, 0: 0x80000008 0x00000000 0x00000000 0x00000000
Feb 06 18:00:15.530: vmx| guestCPUID level 80000001, 0: 0x00000000 0x00000000 0x00000000 0x00100000
Feb 06 18:00:15.530: vmx| guestCPUID level 80000002, 0: 0x65746e49 0x2952286c 0x6e655020 0x6d756974
Feb 06 18:00:15.530: vmx| guestCPUID level 80000003, 0: 0x20295228 0x6c617544 0x50432020 0x45202055
Feb 06 18:00:15.530: vmx| guestCPUID level 80000004, 0: 0x30343132 0x20402020 0x30362e31 0x007a4847
Feb 06 18:00:15.530: vmx| guestCPUID level 80000005, 0: 0x00000000 0x00000000 0x00000000 0x00000000
Feb 06 18:00:15.530: vmx| guestCPUID level 80000006, 0: 0x00000000 0x00000000 0x04004040 0x00000000
Feb 06 18:00:15.530: vmx| guestCPUID level 80000007, 0: 0x00000000 0x00000000 0x00000000 0x00000000
Feb 06 18:00:15.530: vmx| guestCPUID level 80000008, 0: 0x00003028 0x00000000 0x00000000 0x00000000
Feb 06 18:00:15.546: vmx| BusMemSample: initPercent 75 touched 0
Feb 06 18:00:15.577: vmx| VMX_PowerOn: ModuleTable_PowerOn = 1
Feb 06 18:00:15.577: vmx| VMX setting maximum IPC write buffers to 0 packets, 0 bytes
Feb 06 18:00:15.577: mks| Async MKS thread is alive
Feb 06 18:00:15.702: vcpu-0| APIC: version = 0x14, max LVT = 5
Feb 06 18:00:15.702: vcpu-0| APIC: LDR = 0x2000000, DFR = 0xffffffff
Feb 06 18:00:15.733: vcpu-0| guestCpuFeatures = 0x404001f8
Feb 06 18:00:15.733: vcpu-0| CPU reset: hard
Feb 06 18:00:15.733: vcpu-0| memoryHotplug: Entry[0]: 00000000000000A0-00000000000A0000
Feb 06 18:00:15.733: vcpu-0| memoryHotplug: Entry[1]: 00000000001000A0-0000000040000000
Feb 06 18:00:15.749: vcpu-0| VNET: Notification enabled for Ethernet0
Feb 06 18:00:15.780: vcpu-0| CDROM: Connecting ide1:0 to 'E:'. img=0 raw=0 remote=0
Feb 06 18:00:15.796: vcpu-0| Transitioned vmx/execState/val to poweredOn
Feb 06 18:00:15.858: vcpu-0| sz=2996176
Feb 06 18:00:15.874: vcpu-0| vmm32 initialized: Releasebuild-122956. cflags: 0x00000008.00040080.03000180.00000900
Feb 06 18:00:15.874: vcpu-0| MonitorInitNumaUnmapVMM32
Feb 06 18:00:16.671: vcpu-0| SVGA: Registering MemSpace at 0xd0000000(0x0) and 0xd8000000(0x0)
Feb 06 18:00:16.686: vcpu-0| SVGA: Unregistering MemSpace at 0xd0000000(0xd0000000) and 0xd8000000(0xd8000000)
Feb 06 18:00:16.905: vcpu-0| SVGA: Registering MemSpace at 0xd0000000(0xd0000000) and 0xd8000000(0xd8000000)
Feb 06 18:00:16.905: vcpu-0| SVGA: Unregistering MemSpace at 0xd0000000(0xd0000000) and 0xd8000000(0xd8000000)
Feb 06 18:00:16.936: vcpu-0| SVGA: Registering IOSpace at 0x10d0
Feb 06 18:00:16.936: vcpu-0| SVGA: Registering MemSpace at 0xd0000000(0xd0000000) and 0xd8000000(0xd8000000)
Feb 06 18:00:16.936: vcpu-0| PCIBridge4: ISA/VGA decoding enabled (ctrl 0004)
Feb 06 18:00:16.936: vcpu-0| pciBridge4:1: ISA/VGA decoding enabled (ctrl 0004)
Feb 06 18:00:16.983: vcpu-0| pciBridge4:2: ISA/VGA decoding enabled (ctrl 0004)
Feb 06 18:00:16.983: vcpu-0| pciBridge4:3: ISA/VGA decoding enabled (ctrl 0004)
Feb 06 18:00:16.983: vcpu-0| pciBridge4:4: ISA/VGA decoding enabled (ctrl 0004)
Feb 06 18:00:16.983: vcpu-0| pciBridge4:5: ISA/VGA decoding enabled (ctrl 0004)
Feb 06 18:00:16.983: vcpu-0| pciBridge4:6: ISA/VGA decoding enabled (ctrl 0004)
Feb 06 18:00:16.999: vcpu-0| pciBridge4:7: ISA/VGA decoding enabled (ctrl 0004)
Feb 06 18:00:16.999: vcpu-0| PCIBridge5: ISA/VGA decoding enabled (ctrl 0004)
Feb 06 18:00:16.999: vcpu-0| pciBridge5:1: ISA/VGA decoding enabled (ctrl 0004)
Feb 06 18:00:16.999: vcpu-0| pciBridge5:2: ISA/VGA decoding enabled (ctrl 0004)
Feb 06 18:00:16.999: vcpu-0| pciBridge5:3: ISA/VGA decoding enabled (ctrl 0004)
Feb 06 18:00:16.999: vcpu-0| pciBridge5:4: ISA/VGA decoding enabled (ctrl 0004)
Feb 06 18:00:16.999: vcpu-0| pciBridge5:5: ISA/VGA decoding enabled (ctrl 0004)
Feb 06 18:00:16.999: vcpu-0| pciBridge5:6: ISA/VGA decoding enabled (ctrl 0004)
Feb 06 18:00:16.999: vcpu-0| pciBridge5:7: ISA/VGA decoding enabled (ctrl 0004)
Feb 06 18:00:17.015: vcpu-0| PCIBridge6: ISA/VGA decoding enabled (ctrl 0004)
Feb 06 18:00:17.015: vcpu-0| pciBridge6:1: ISA/VGA decoding enabled (ctrl 0004)
Feb 06 18:00:17.015: vcpu-0| pciBridge6:2: ISA/VGA decoding enabled (ctrl 0004)
Feb 06 18:00:17.015: vcpu-0| pciBridge6:3: ISA/VGA decoding enabled (ctrl 0004)
Feb 06 18:00:17.015: vcpu-0| pciBridge6:4: ISA/VGA decoding enabled (ctrl 0004)
Feb 06 18:00:17.015: vcpu-0| pciBridge6:5: ISA/VGA decoding enabled (ctrl 0004)
Feb 06 18:00:17.015: vcpu-0| pciBridge6:6: ISA/VGA decoding enabled (ctrl 0004)
Feb 06 18:00:17.015: vcpu-0| pciBridge6:7: ISA/VGA decoding enabled (ctrl 0004)
Feb 06 18:00:17.015: vcpu-0| PCIBridge7: ISA/VGA decoding enabled (ctrl 0004)
Feb 06 18:00:17.030: vcpu-0| pciBridge7:1: ISA/VGA decoding enabled (ctrl 0004)
Feb 06 18:00:17.030: vcpu-0| pciBridge7:2: ISA/VGA decoding enabled (ctrl 0004)
Feb 06 18:00:17.030: vcpu-0| pciBridge7:3: ISA/VGA decoding enabled (ctrl 0004)
Feb 06 18:00:17.030: vcpu-0| pciBridge7:4: ISA/VGA decoding enabled (ctrl 0004)
Feb 06 18:00:17.030: vcpu-0| pciBridge7:5: ISA/VGA decoding enabled (ctrl 0004)
Feb 06 18:00:17.030: vcpu-0| pciBridge7:6: ISA/VGA decoding enabled (ctrl 0004)
Feb 06 18:00:17.030: vcpu-0| pciBridge7:7: ISA/VGA decoding enabled (ctrl 0004)
Feb 06 18:00:17.374: vmx| VMXVmdbLoadUsbDevices: New set of 1 USB devices
Feb 06 18:00:17.374: vmx| USB: Found device [name:APC\ Smart-UPS\ 1000\ FW:652.18.I\ USB\ FW:7.3 vid:051d pid:0002 path:1/2/1 speed:low family:hid]
Feb 06 18:00:17.530: vcpu-0| PCIBridge4: ISA/VGA decoding enabled (ctrl 0004)
Feb 06 18:00:17.530: vcpu-0| pciBridge4:1: ISA/VGA decoding enabled (ctrl 0004)
Feb 06 18:00:17.530: vcpu-0| pciBridge4:2: ISA/VGA decoding enabled (ctrl 0004)
Feb 06 18:00:17.530: vcpu-0| pciBridge4:3: ISA/VGA decoding enabled (ctrl 0004)
Feb 06 18:00:17.530: vcpu-0| pciBridge4:4: ISA/VGA decoding enabled (ctrl 0004)
Feb 06 18:00:17.530: vcpu-0| pciBridge4:5: ISA/VGA decoding enabled (ctrl 0004)
Feb 06 18:00:17.530: vcpu-0| pciBridge4:6: ISA/VGA decoding enabled (ctrl 0004)
Feb 06 18:00:17.530: vcpu-0| pciBridge4:7: ISA/VGA decoding enabled (ctrl 0004)
Feb 06 18:00:17.530: vcpu-0| PCIBridge5: ISA/VGA decoding enabled (ctrl 0004)
Feb 06 18:00:17.530: vcpu-0| pciBridge5:1: ISA/VGA decoding enabled (ctrl 0004)
Feb 06 18:00:17.546: vcpu-0| pciBridge5:2: ISA/VGA decoding enabled (ctrl 0004)
Feb 06 18:00:17.546: vcpu-0| pciBridge5:3: ISA/VGA decoding enabled (ctrl 0004)
Feb 06 18:00:17.546: vcpu-0| pciBridge5:4: ISA/VGA decoding enabled (ctrl 0004)
Feb 06 18:00:17.546: vcpu-0| pciBridge5:5: ISA/VGA decoding enabled (ctrl 0004)
Feb 06 18:00:17.546: vcpu-0| pciBridge5:6: ISA/VGA decoding enabled (ctrl 0004)
Feb 06 18:00:17.546: vcpu-0| pciBridge5:7: ISA/VGA decoding enabled (ctrl 0004)
Feb 06 18:00:17.546: vcpu-0| PCIBridge6: ISA/VGA decoding enabled (ctrl 0004)
Feb 06 18:00:17.546: vcpu-0| pciBridge6:1: ISA/VGA decoding enabled (ctrl 0004)
Feb 06 18:00:17.546: vcpu-0| pciBridge6:2: ISA/VGA decoding enabled (ctrl 0004)
Feb 06 18:00:17.561: vcpu-0| pciBridge6:3: ISA/VGA decoding enabled (ctrl 0004)
Feb 06 18:00:17.561: vcpu-0| pciBridge6:4: ISA/VGA decoding enabled (ctrl 0004)
Feb 06 18:00:17.561: vcpu-0| pciBridge6:5: ISA/VGA decoding enabled (ctrl 0004)
Feb 06 18:00:17.561: vcpu-0| pciBridge6:6: ISA/VGA decoding enabled (ctrl 0004)
Feb 06 18:00:17.577: vcpu-0| pciBridge6:7: ISA/VGA decoding enabled (ctrl 0004)
Feb 06 18:00:17.577: vcpu-0| PCIBridge7: ISA/VGA decoding enabled (ctrl 0004)
Feb 06 18:00:17.577: vcpu-0| pciBridge7:1: ISA/VGA decoding enabled (ctrl 0004)
Feb 06 18:00:17.577: vcpu-0| pciBridge7:2: ISA/VGA decoding enabled (ctrl 0004)
Feb 06 18:00:17.577: vcpu-0| pciBridge7:3: ISA/VGA decoding enabled (ctrl 0004)
Feb 06 18:00:17.577: vcpu-0| pciBridge7:4: ISA/VGA decoding enabled (ctrl 0004)
Feb 06 18:00:17.577: vcpu-0| pciBridge7:5: ISA/VGA decoding enabled (ctrl 0004)
Feb 06 18:00:17.577: vcpu-0| pciBridge7:6: ISA/VGA decoding enabled (ctrl 0004)
Feb 06 18:00:17.593: vcpu-0| pciBridge7:7: ISA/VGA decoding enabled (ctrl 0004)
Feb 06 18:00:17.624: vcpu-0| VIDE: Curr CHS info cyls: 17475 heads: 15 sects: 63 lba_cap: 31457280
Feb 06 18:00:17.671: vcpu-0| BIOS-UUID is 56 4d 60 be 87 d8 86 fc-ca 5a ac fe 2a 9a 98 cc
Feb 06 18:00:23.608: mks| SVGA: display status changed, using optimizations for local consoles.
Feb 06 18:00:24.905: vcpu-0| SVGA: Unregistering IOSpace at 0x10d0
Feb 06 18:00:24.905: vcpu-0| SVGA: Unregistering MemSpace at 0xd0000000(0xd0000000) and 0xd8000000(0xd8000000)
Feb 06 18:00:24.936: vcpu-0| SVGA: Registering IOSpace at 0x10d0
Feb 06 18:00:24.936: vcpu-0| SVGA: Registering MemSpace at 0xd0000000(0xd0000000) and 0xd8000000(0xd8000000)
Feb 06 18:00:24.936: vcpu-0| PCIBridge4: ISA/VGA decoding enabled (ctrl 0004)
Feb 06 18:00:24.936: vcpu-0| PCIBridge4: ISA/VGA decoding enabled (ctrl 0004)
Feb 06 18:00:24.936: vcpu-0| pciBridge4:1: ISA/VGA decoding enabled (ctrl 0004)
Feb 06 18:00:24.952: vcpu-0| pciBridge4:1: ISA/VGA decoding enabled (ctrl 0004)
Feb 06 18:00:24.952: vcpu-0| pciBridge4:2: ISA/VGA decoding enabled (ctrl 0004)
Feb 06 18:00:24.952: vcpu-0| pciBridge4:2: ISA/VGA decoding enabled (ctrl 0004)
Feb 06 18:00:24.952: vcpu-0| pciBridge4:3: ISA/VGA decoding enabled (ctrl 0004)
Feb 06 18:00:24.952: vcpu-0| pciBridge4:3: ISA/VGA decoding enabled (ctrl 0004)
Feb 06 18:00:24.952: vcpu-0| pciBridge4:4: ISA/VGA decoding enabled (ctrl 0004)
Feb 06 18:00:24.952: vcpu-0| pciBridge4:4: ISA/VGA decoding enabled (ctrl 0004)
Feb 06 18:00:24.952: vcpu-0| pciBridge4:5: ISA/VGA decoding enabled (ctrl 0004)
Feb 06 18:00:24.952: vcpu-0| pciBridge4:5: ISA/VGA decoding enabled (ctrl 0004)
Feb 06 18:00:24.952: vcpu-0| pciBridge4:6: ISA/VGA decoding enabled (ctrl 0004)
Feb 06 18:00:24.952: vcpu-0| pciBridge4:6: ISA/VGA decoding enabled (ctrl 0004)
Feb 06 18:00:24.968: vcpu-0| pciBridge4:7: ISA/VGA decoding enabled (ctrl 0004)
Feb 06 18:00:24.968: vcpu-0| pciBridge4:7: ISA/VGA decoding enabled (ctrl 0004)
Feb 06 18:00:24.968: vcpu-0| PCIBridge5: ISA/VGA decoding enabled (ctrl 0004)
Feb 06 18:00:24.968: vcpu-0| PCIBridge5: ISA/VGA decoding enabled (ctrl 0004)
Feb 06 18:00:24.968: vcpu-0| pciBridge5:1: ISA/VGA decoding enabled (ctrl 0004)
Feb 06 18:00:24.968: vcpu-0| pciBridge5:1: ISA/VGA decoding enabled (ctrl 0004)
Feb 06 18:00:24.968: vcpu-0| pciBridge5:2: ISA/VGA decoding enabled (ctrl 0004)
Feb 06 18:00:24.968: vcpu-0| pciBridge5:2: ISA/VGA decoding enabled (ctrl 0004)
Feb 06 18:00:24.968: vcpu-0| pciBridge5:3: ISA/VGA decoding enabled (ctrl 0004)
Feb 06 18:00:24.968: vcpu-0| pciBridge5:3: ISA/VGA decoding enabled (ctrl 0004)
Feb 06 18:00:24.968: vcpu-0| pciBridge5:4: ISA/VGA decoding enabled (ctrl 0004)
Feb 06 18:00:24.968: vcpu-0| pciBridge5:4: ISA/VGA decoding enabled (ctrl 0004)
Feb 06 18:00:24.983: vcpu-0| pciBridge5:5: ISA/VGA decoding enabled (ctrl 0004)
Feb 06 18:00:24.983: vcpu-0| pciBridge5:5: ISA/VGA decoding enabled (ctrl 0004)
Feb 06 18:00:24.983: vcpu-0| pciBridge5:6: ISA/VGA decoding enabled (ctrl 0004)
Feb 06 18:00:24.983: vcpu-0| pciBridge5:6: ISA/VGA decoding enabled (ctrl 0004)
Feb 06 18:00:24.983: vcpu-0| pciBridge5:7: ISA/VGA decoding enabled (ctrl 0004)
Feb 06 18:00:24.983: vcpu-0| pciBridge5:7: ISA/VGA decoding enabled (ctrl 0004)
Feb 06 18:00:24.983: vcpu-0| PCIBridge6: ISA/VGA decoding enabled (ctrl 0004)
Feb 06 18:00:24.983: vcpu-0| PCIBridge6: ISA/VGA decoding enabled (ctrl 0004)
Feb 06 18:00:24.983: vcpu-0| pciBridge6:1: ISA/VGA decoding enabled (ctrl 0004)
Feb 06 18:00:24.983: vcpu-0| pciBridge6:1: ISA/VGA decoding enabled (ctrl 0004)
Feb 06 18:00:24.983: vcpu-0| pciBridge6:2: ISA/VGA decoding enabled (ctrl 0004)
Feb 06 18:00:24.983: vcpu-0| pciBridge6:2: ISA/VGA decoding enabled (ctrl 0004)
Feb 06 18:00:24.999: vcpu-0| pciBridge6:3: ISA/VGA decoding enabled (ctrl 0004)
Feb 06 18:00:24.999: vcpu-0| pciBridge6:3: ISA/VGA decoding enabled (ctrl 0004)
Feb 06 18:00:24.999: vcpu-0| pciBridge6:4: ISA/VGA decoding enabled (ctrl 0004)
Feb 06 18:00:24.999: vcpu-0| pciBridge6:4: ISA/VGA decoding enabled (ctrl 0004)
Feb 06 18:00:24.999: vcpu-0| pciBridge6:5: ISA/VGA decoding enabled (ctrl 0004)
Feb 06 18:00:24.999: vcpu-0| pciBridge6:5: ISA/VGA decoding enabled (ctrl 0004)
Feb 06 18:00:24.999: vcpu-0| pciBridge6:6: ISA/VGA decoding enabled (ctrl 0004)
Feb 06 18:00:24.999: vcpu-0| pciBridge6:6: ISA/VGA decoding enabled (ctrl 0004)
Feb 06 18:00:25.015: vcpu-0| pciBridge6:7: ISA/VGA decoding enabled (ctrl 0004)
Feb 06 18:00:25.015: vcpu-0| pciBridge6:7: ISA/VGA decoding enabled (ctrl 0004)
Feb 06 18:00:25.015: vcpu-0| PCIBridge7: ISA/VGA decoding enabled (ctrl 0004)
Feb 06 18:00:25.015: vcpu-0| PCIBridge7: ISA/VGA decoding enabled (ctrl 0004)
Feb 06 18:00:25.015: vcpu-0| pciBridge7:1: ISA/VGA decoding enabled (ctrl 0004)
Feb 06 18:00:25.015: vcpu-0| pciBridge7:1: ISA/VGA decoding enabled (ctrl 0004)
Feb 06 18:00:25.015: vcpu-0| pciBridge7:2: ISA/VGA decoding enabled (ctrl 0004)
Feb 06 18:00:25.015: vcpu-0| pciBridge7:2: ISA/VGA decoding enabled (ctrl 0004)
Feb 06 18:00:25.015: vcpu-0| pciBridge7:3: ISA/VGA decoding enabled (ctrl 0004)
Feb 06 18:00:25.015: vcpu-0| pciBridge7:3: ISA/VGA decoding enabled (ctrl 0004)
Feb 06 18:00:25.015: vcpu-0| pciBridge7:4: ISA/VGA decoding enabled (ctrl 0004)
Feb 06 18:00:25.015: vcpu-0| pciBridge7:4: ISA/VGA decoding enabled (ctrl 0004)
Feb 06 18:00:25.030: vcpu-0| pciBridge7:5: ISA/VGA decoding enabled (ctrl 0004)
Feb 06 18:00:25.030: vcpu-0| pciBridge7:5: ISA/VGA decoding enabled (ctrl 0004)
Feb 06 18:00:25.030: vcpu-0| pciBridge7:6: ISA/VGA decoding enabled (ctrl 0004)
Feb 06 18:00:25.030: vcpu-0| pciBridge7:6: ISA/VGA decoding enabled (ctrl 0004)
Feb 06 18:00:25.030: vcpu-0| pciBridge7:7: ISA/VGA decoding enabled (ctrl 0004)
Feb 06 18:00:25.030: vcpu-0| pciBridge7:7: ISA/VGA decoding enabled (ctrl 0004)
Feb 06 18:00:26.702: vcpu-0| PCIBridge4: ISA/VGA decoding enabled (ctrl 0004)
Feb 06 18:00:26.718: vcpu-0| pciBridge4:1: ISA/VGA decoding enabled (ctrl 0004)
Feb 06 18:00:26.749: vcpu-0| pciBridge4:2: ISA/VGA decoding enabled (ctrl 0004)
Feb 06 18:00:26.765: vcpu-0| pciBridge4:3: ISA/VGA decoding enabled (ctrl 0004)
Feb 06 18:00:26.796: vcpu-0| pciBridge4:4: ISA/VGA decoding enabled (ctrl 0004)
Feb 06 18:00:26.811: vcpu-0| pciBridge4:5: ISA/VGA decoding enabled (ctrl 0004)
Feb 06 18:00:26.827: vcpu-0| pciBridge4:6: ISA/VGA decoding enabled (ctrl 0004)
Feb 06 18:00:26.843: vcpu-0| pciBridge4:7: ISA/VGA decoding enabled (ctrl 0004)
Feb 06 18:00:26.874: vcpu-0| PCIBridge5: ISA/VGA decoding enabled (ctrl 0004)
Feb 06 18:00:26.890: vcpu-0| pciBridge5:1: ISA/VGA decoding enabled (ctrl 0004)
Feb 06 18:00:26.905: vcpu-0| pciBridge5:2: ISA/VGA decoding enabled (ctrl 0004)
Feb 06 18:00:26.921: vcpu-0| pciBridge5:3: ISA/VGA decoding enabled (ctrl 0004)
Feb 06 18:00:26.952: vcpu-0| pciBridge5:4: ISA/VGA decoding enabled (ctrl 0004)
Feb 06 18:00:26.983: vcpu-0| pciBridge5:5: ISA/VGA decoding enabled (ctrl 0004)
Feb 06 18:00:26.999: vcpu-0| pciBridge5:6: ISA/VGA decoding enabled (ctrl 0004)
Feb 06 18:00:27.015: vcpu-0| pciBridge5:7: ISA/VGA decoding enabled (ctrl 0004)
Feb 06 18:00:27.030: vcpu-0| PCIBridge6: ISA/VGA decoding enabled (ctrl 0004)
Feb 06 18:00:27.061: vcpu-0| pciBridge6:1: ISA/VGA decoding enabled (ctrl 0004)
Feb 06 18:00:27.077: vcpu-0| pciBridge6:2: ISA/VGA decoding enabled (ctrl 0004)
Feb 06 18:00:27.093: vcpu-0| pciBridge6:3: ISA/VGA decoding enabled (ctrl 0004)
Feb 06 18:00:27.108: vcpu-0| pciBridge6:4: ISA/VGA decoding enabled (ctrl 0004)
Feb 06 18:00:27.124: vcpu-0| pciBridge6:5: ISA/VGA decoding enabled (ctrl 0004)
Feb 06 18:00:27.171: vcpu-0| pciBridge6:6: ISA/VGA decoding enabled (ctrl 0004)
Feb 06 18:00:27.186: vcpu-0| pciBridge6:7: ISA/VGA decoding enabled (ctrl 0004)
Feb 06 18:00:27.202: vcpu-0| PCIBridge7: ISA/VGA decoding enabled (ctrl 0004)
Feb 06 18:00:27.218: vcpu-0| pciBridge7:1: ISA/VGA decoding enabled (ctrl 0004)
Feb 06 18:00:27.233: vcpu-0| pciBridge7:2: ISA/VGA decoding enabled (ctrl 0004)
Feb 06 18:00:27.249: vcpu-0| pciBridge7:3: ISA/VGA decoding enabled (ctrl 0004)
Feb 06 18:00:27.280: vcpu-0| pciBridge7:4: ISA/VGA decoding enabled (ctrl 0004)
Feb 06 18:00:27.296: vcpu-0| pciBridge7:5: ISA/VGA decoding enabled (ctrl 0004)
Feb 06 18:00:27.311: vcpu-0| pciBridge7:6: ISA/VGA decoding enabled (ctrl 0004)
Feb 06 18:00:27.327: vcpu-0| pciBridge7:7: ISA/VGA decoding enabled (ctrl 0004)
Feb 06 18:00:28.655: vcpu-0| VIDE: Curr CHS info cyls: 17475 heads: 15 sects: 63 lba_cap: 31457280
Feb 06 18:00:29.671: vmx| CDROM: Mode Sense for Unsupported Page 0x1B
Feb 06 18:00:29.671: vmx| SCSI DEVICE (ide1:0): MODE SENSE(10) for unsupported page 0x1b
Feb 06 18:00:29.702: vcpu-0| VIDE: (0x1f0) OUTB Cmd 0xf5, Unknown ATA Command on drive 0
Feb 06 18:00:36.702: vcpu-0| CDROM: Emulate GET CONFIGURATION RT 0 start feature 0
Feb 06 18:00:36.702: vcpu-0| CDROM: Emulate GET CONFIGURATION RT 0 start feature 0
Feb 06 18:00:36.702: vcpu-0| CDROM: Emulate GET CONFIGURATION RT 0 start feature 0
Feb 06 18:00:36.702: vcpu-0| CDROM: Emulate GET CONFIGURATION RT 0 start feature 0
Feb 06 18:00:36.796: vcpu-0| The guest OS tried to update the CPU's microcode.
Feb 06 18:00:36.796: vcpu-0| Since a newer microcode version exists, please consider updating the microcode on your host.
Feb 06 18:00:36.983: vcpu-0| UHCI: Global Reset
Feb 06 18:00:42.905: mks| MKS switching absolute mouse on
Feb 06 18:00:54.124: vcpu-0| CDROM: Emulate GET CONFIGURATION RT 0 start feature 0
Feb 06 18:00:54.140: vcpu-0| CDROM: Emulate GET CONFIGURATION RT 0 start feature 30
Feb 06 18:00:54.140: vcpu-0| CDROM: Emulate GET CONFIGURATION RT 0 start feature 31
Feb 06 18:00:54.140: vcpu-0| CDROM: Emulate GET CONFIGURATION RT 0 start feature 32
Feb 06 18:00:54.140: vcpu-0| CDROM: Emulate GET CONFIGURATION RT 0 start feature 33
Feb 06 18:00:54.140: vcpu-0| CDROM: Emulate GET CONFIGURATION RT 0 start feature 35
Feb 06 18:00:54.140: vcpu-0| CDROM: Emulate GET CONFIGURATION RT 0 start feature 36
Feb 06 18:00:54.140: vcpu-0| CDROM: Emulate GET CONFIGURATION RT 0 start feature 38
Feb 06 18:00:54.140: vcpu-0| CDROM: Emulate GET CONFIGURATION RT 0 start feature 45
Feb 06 18:00:54.140: vcpu-0| CDROM: Emulate GET CONFIGURATION RT 0 start feature 46
Feb 06 18:00:54.140: vcpu-0| CDROM: Emulate GET CONFIGURATION RT 0 start feature 47
Feb 06 18:00:54.140: vcpu-0| CDROM: Emulate GET CONFIGURATION RT 0 start feature 259
Feb 06 18:01:30.280: mks| VNCENCODE 2 encoding mode change: (800x600x24depth,32bpp,3200bytes/line)
Feb 06 18:01:30.483: mks| VNCENCODE 2 encoding mode change: (800x600x24depth,32bpp,3200bytes/line)
Feb 06 18:01:32.327: mks| SVGA: display status changed, using optimizations for remote consoles.
Feb 06 18:01:50.921: vcpu-0| VIDE: (0x1f0) OUTB Cmd 0xb0, Unknown ATA Command on drive 0
Feb 06 18:04:14.186: mks| SOCKET 2 (1400) recv error 10054: An existing connection was forcibly closed by the remote host
Feb 06 18:04:14.186: mks| SOCKET 2 (1400) destroying VNC backend on socket error: 1
Feb 06 18:04:16.061: mks| SVGA: display status changed, using optimizations for local consoles.
Feb 06 18:10:15.249: vmx| TOOLS setting the tools version to '0'
Feb 07 05:02:09.574: vmx| ide1:0: Command TEST UNIT READY took 6.481 seconds (ok)
Feb 07 16:34:43.030: vcpu-0| DISKLIB-LIB : numIOs = 50000 numMergedIOs = 3470 numSplitIOs = 1224
Feb 07 18:00:15.545: vmx| LICENSE using: 'HKEY_LOCAL_MACHINE\SOFTWARE\VMware, Inc.\VMware Server\Dormant\License.gsx.3.0-00'
Feb 07 21:23:40.153: vmx| DISKLIB-LIB : numIOs = 100000 numMergedIOs = 7357 numSplitIOs = 2396
Feb 08 18:00:16.738: vmx| LICENSE using: 'HKEY_LOCAL_MACHINE\SOFTWARE\VMware, Inc.\VMware Server\Dormant\License.gsx.3.0-00'
Feb 08 19:59:53.973: vmx| ide0:0: Command WRITE(10) took 19.319 seconds (ok)
Feb 08 19:59:53.973: vmx| ide1:0: Command TEST UNIT READY took 22.200 seconds (ok)
Feb 09 02:04:51.797: vmx| DISKLIB-LIB : numIOs = 150000 numMergedIOs = 12360 numSplitIOs = 3445
Feb 09 11:31:44.073: vmx| ide0:0: Command WRITE(10) took 1.154 seconds (ok)
Feb 09 11:31:48.401: vmx| ide0:0: Command WRITE(10) took 1.148 seconds (ok)
Feb 09 12:04:41.229: vcpu-0| AIOWIN32C: 'd:\vm\WIN XP Pro\WIN XP Pro.vmdk' : Failed to write: The process cannot access the file because another process has locked a portion of the file (33).
Feb 09 12:04:41.229: vcpu-0| VMXAIOMGR: Retry on write "d:\vm\WIN XP Pro\WIN XP Pro.vmdk" : The process cannot access the file because another process has locked a portion of the file.
Feb 09 12:04:41.229: vcpu-0| VMXAIOMGR: system : err=8450 errCode=33 freeSpace=89202966528
Feb 09 12:04:41.229: vcpu-0| VMXAIOMGR: "d:\vm\WIN XP Pro\WIN XP Pro.vmdk" : write s=67763712 n=24576 ne=1
Feb 09 12:04:41.229: vcpu-0| VMXAIOMGR: v[0]=13285000:24576
Feb 09 12:04:41.229: vcpu-0| AIOWIN32C: 'd:\vm\WIN XP Pro\WIN XP Pro.vmdk' : Failed to write: The process cannot access the file because another process has locked a portion of the file (33).
Feb 09 12:04:41.229: vcpu-0| VMXAIOMGR: Retry on write "d:\vm\WIN XP Pro\WIN XP Pro.vmdk" : The process cannot access the file because another process has locked a portion of the file.
Feb 09 12:04:41.229: vcpu-0| VMXAIOMGR: system : err=8450 errCode=33 freeSpace=89202966528
Feb 09 12:04:41.229: vcpu-0| VMXAIOMGR: "d:\vm\WIN XP Pro\WIN XP Pro.vmdk" : write s=67763712 n=24576 ne=1
Feb 09 12:04:41.229: vcpu-0| VMXAIOMGR: v[0]=13285000:24576
Feb 09 12:04:41.229: vcpu-0| AIOWIN32C: 'd:\vm\WIN XP Pro\WIN XP Pro.vmdk' : Failed to write: The process cannot access the file because another process has locked a portion of the file (33).
Feb 09 12:04:41.229: vcpu-0| VMXAIOMGR: Retry on write "d:\vm\WIN XP Pro\WIN XP Pro.vmdk" : The process cannot access the file because another process has locked a portion of the file.
Feb 09 12:04:41.229: vcpu-0| VMXAIOMGR: system : err=8450 errCode=33 freeSpace=89202966528
Feb 09 12:04:41.229: vcpu-0| VMXAIOMGR: "d:\vm\WIN XP Pro\WIN XP Pro.vmdk" : write s=67763712 n=24576 ne=1
Feb 09 12:04:41.229: vcpu-0| VMXAIOMGR: v[0]=13285000:24576
Feb 09 12:04:41.229: vcpu-0| AIOWIN32C: 'd:\vm\WIN XP Pro\WIN XP Pro.vmdk' : Failed to write: The process cannot access the file because another process has locked a portion of the file (33).
Feb 09 12:04:41.229: vcpu-0| VMXAIOMGR: Retry on write "d:\vm\WIN XP Pro\WIN XP Pro.vmdk" : The process cannot access the file because another process has locked a portion of the file.
Feb 09 12:04:41.229: vcpu-0| VMXAIOMGR: system : err=8450 errCode=33 freeSpace=89202966528
Feb 09 12:04:41.229: vcpu-0| VMXAIOMGR: "d:\vm\WIN XP Pro\WIN XP Pro.vmdk" : write s=67763712 n=24576 ne=1
Feb 09 12:04:41.229: vcpu-0| VMXAIOMGR: v[0]=13285000:24576
Feb 09 12:04:41.229: vcpu-0| AIOWIN32C: 'd:\vm\WIN XP Pro\WIN XP Pro.vmdk' : Failed to write: The process cannot access the file because another process has locked a portion of the file (33).
Feb 09 12:04:41.229: vcpu-0| VMXAIOMGR: Retry on write "d:\vm\WIN XP Pro\WIN XP Pro.vmdk" : The process cannot access the file because another process has locked a portion of the file.
Feb 09 12:04:41.229: vcpu-0| VMXAIOMGR: system : err=8450 errCode=33 freeSpace=89202966528
Feb 09 12:04:41.229: vcpu-0| VMXAIOMGR: "d:\vm\WIN XP Pro\WIN XP Pro.vmdk" : write s=67763712 n=24576 ne=1
Feb 09 12:04:41.229: vcpu-0| VMXAIOMGR: v[0]=13285000:24576
Feb 09 12:04:41.229: vcpu-0| AIOWIN32C: 'd:\vm\WIN XP Pro\WIN XP Pro.vmdk' : Failed to write: The process cannot access the file because another process has locked a portion of the file (33).
Feb 09 12:04:41.229: vcpu-0| VMXAIOMGR: Retry on write "d:\vm\WIN XP Pro\WIN XP Pro.vmdk" : The process cannot access the file because another process has locked a portion of the file.
Feb 09 12:04:41.229: vcpu-0| VMXAIOMGR: system : err=8450 errCode=33 freeSpace=89202966528
Feb 09 12:04:41.229: vcpu-0| VMXAIOMGR: "d:\vm\WIN XP Pro\WIN XP Pro.vmdk" : write s=67763712 n=24576 ne=1
Feb 09 12:04:41.229: vcpu-0| VMXAIOMGR: v[0]=13285000:24576
Feb 09 12:04:41.229: vcpu-0| Msg_Question:
Feb 09 12:04:41.229: vcpu-0| [msg.vmxaiomgr.retrycontabort.rudeunplug] Operation on file "d:\vm\WIN XP Pro\WIN XP Pro.vmdk" failed.
Feb 09 12:04:41.229: vcpu-0| If the file resides on a remote file system, please make sure your network connection and the server where this disk resides are functioning properly. If the file resides on removable media, reattach the media.
Feb 09 12:04:41.229: vcpu-0| Choose Retry to attempt the operation again.
Feb 09 12:04:41.229: vcpu-0| Choose Abort to terminate this session.
Feb 09 12:04:41.229: vcpu-0| Choose Continue to forward the error to the guest operating system.----------------------------------------
Feb 09 12:04:41.229: vmx| POLL device deleted
Feb 09 14:09:43.497: vcpu-0| Msg_Question: msg.vmxaiomgr.retrycontabort.rudeunplug reply=0
Feb 09 14:09:43.497: vcpu-0| VMXAIOMGR:Reopening d:\vm\WIN XP Pro\WIN XP Pro.vmdk and retrying outstanding IOs
Feb 09 14:09:43.497: vcpu-0| VMXAIOMGR:Reopened 1 file handles and retried 0 IOs
Feb 09 14:09:43.513: vcpu-0| VLANCE: Ethernet0 skipped 1 time(s)
Feb 09 14:09:43.513: vcpu-0| VLANCE: 1 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
Feb 09 14:09:43.513: vcpu-0| VLANCE: 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
Feb 09 14:09:43.513: vcpu-0| VLANCE: Ethernet0 skipped 2 time(s)
Feb 09 14:09:43.513: vcpu-0| VLANCE: 1 0 0 0 0 0 0 1 0 0 0 0 0 0 0 0
Feb 09 14:09:43.513: vcpu-0| VLANCE: 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
Feb 09 14:09:43.528: vmx| ide0:0: Command WRITE(10) took 3207.224 seconds (ok)
Feb 09 19:39:44.172: vmx| LICENSE using: 'HKEY_LOCAL_MACHINE\SOFTWARE\VMware, Inc.\VMware Server\Dormant\License.gsx.3.0-00'
Feb 09 22:20:21.844: vmx| DISKLIB-LIB : numIOs = 200000 numMergedIOs = 19027 numSplitIOs = 4491
Feb 09 23:49:46.079: vcpu-0| AIOWIN32C: 'd:\vm\WIN XP Pro\WIN XP Pro.vmdk' : Failed to write: The process cannot access the file because another process has locked a portion of the file (33).
Feb 09 23:49:46.079: vcpu-0| VMXAIOMGR: Retry on write "d:\vm\WIN XP Pro\WIN XP Pro.vmdk" : The process cannot access the file because another process has locked a portion of the file.
Feb 09 23:49:46.079: vcpu-0| VMXAIOMGR: system : err=8450 errCode=33 freeSpace=88601493504
Feb 09 23:49:46.079: vcpu-0| VMXAIOMGR: "d:\vm\WIN XP Pro\WIN XP Pro.vmdk" : write s=5643951616 n=1536 ne=1
Feb 09 23:49:46.079: vcpu-0| VMXAIOMGR: v[0]=2A478000:1536
Feb 09 23:49:46.079: vcpu-0| AIOWIN32C: 'd:\vm\WIN XP Pro\WIN XP Pro.vmdk' : Failed to write: The process cannot access the file because another process has locked a portion of the file (33).
Feb 09 23:49:46.079: vcpu-0| VMXAIOMGR: Retry on write "d:\vm\WIN XP Pro\WIN XP Pro.vmdk" : The process cannot access the file because another process has locked a portion of the file.
Feb 09 23:49:46.079: vcpu-0| VMXAIOMGR: system : err=8450 errCode=33 freeSpace=88601493504
Feb 09 23:49:46.079: vcpu-0| VMXAIOMGR: "d:\vm\WIN XP Pro\WIN XP Pro.vmdk" : write s=5643951616 n=1536 ne=1
Feb 09 23:49:46.079: vcpu-0| VMXAIOMGR: v[0]=2A478000:1536
Feb 09 23:49:46.079: vcpu-0| AIOWIN32C: 'd:\vm\WIN XP Pro\WIN XP Pro.vmdk' : Failed to write: The process cannot access the file because another process has locked a portion of the file (33).
Feb 09 23:49:46.079: vcpu-0| VMXAIOMGR: Retry on write "d:\vm\WIN XP Pro\WIN XP Pro.vmdk" : The process cannot access the file because another process has locked a portion of the file.
Feb 09 23:49:46.079: vcpu-0| VMXAIOMGR: system : err=8450 errCode=33 freeSpace=88601493504
Feb 09 23:49:46.079: vcpu-0| VMXAIOMGR: "d:\vm\WIN XP Pro\WIN XP Pro.vmdk" : write s=5643951616 n=1536 ne=1
Feb 09 23:49:46.079: vcpu-0| VMXAIOMGR: v[0]=2A478000:1536
Feb 09 23:49:46.079: vcpu-0| AIOWIN32C: 'd:\vm\WIN XP Pro\WIN XP Pro.vmdk' : Failed to write: The process cannot access the file because another process has locked a portion of the file (33).
Feb 09 23:49:46.079: vcpu-0| VMXAIOMGR: Retry on write "d:\vm\WIN XP Pro\WIN XP Pro.vmdk" : The process cannot access the file because another process has locked a portion of the file.
Feb 09 23:49:46.079: vcpu-0| VMXAIOMGR: system : err=8450 errCode=33 freeSpace=88601493504
Feb 09 23:49:46.079: vcpu-0| VMXAIOMGR: "d:\vm\WIN XP Pro\WIN XP Pro.vmdk" : write s=5643951616 n=1536 ne=1
Feb 09 23:49:46.079: vcpu-0| VMXAIOMGR: v[0]=2A478000:1536
Feb 09 23:49:46.079: vcpu-0| AIOWIN32C: 'd:\vm\WIN XP Pro\WIN XP Pro.vmdk' : Failed to write: The process cannot access the file because another process has locked a portion of the file (33).
Feb 09 23:49:46.079: vcpu-0| VMXAIOMGR: Retry on write "d:\vm\WIN XP Pro\WIN XP Pro.vmdk" : The process cannot access the file because another process has locked a portion of the file.
Feb 09 23:49:46.079: vcpu-0| VMXAIOMGR: system : err=8450 errCode=33 freeSpace=88601493504
Feb 09 23:49:46.079: vcpu-0| VMXAIOMGR: "d:\vm\WIN XP Pro\WIN XP Pro.vmdk" : write s=5643951616 n=1536 ne=1
Feb 09 23:49:46.079: vcpu-0| VMXAIOMGR: v[0]=2A478000:1536
Feb 09 23:49:46.079: vcpu-0| AIOWIN32C: 'd:\vm\WIN XP Pro\WIN XP Pro.vmdk' : Failed to write: The process cannot access the file because another process has locked a portion of the file (33).
Feb 09 23:49:46.079: vcpu-0| VMXAIOMGR: Retry on write "d:\vm\WIN XP Pro\WIN XP Pro.vmdk" : The process cannot access the file because another process has locked a portion of the file.
Feb 09 23:49:46.079: vcpu-0| VMXAIOMGR: system : err=8450 errCode=33 freeSpace=88601493504
Feb 09 23:49:46.079: vcpu-0| VMXAIOMGR: "d:\vm\WIN XP Pro\WIN XP Pro.vmdk" : write s=5643951616 n=1536 ne=1
Feb 09 23:49:46.079: vcpu-0| VMXAIOMGR: v[0]=2A478000:1536
Feb 09 23:49:46.079: vcpu-0| Msg_Question:
Feb 09 23:49:46.079: vcpu-0| [msg.vmxaiomgr.retrycontabort.rudeunplug] Operation on file "d:\vm\WIN XP Pro\WIN XP Pro.vmdk" failed.
Feb 09 23:49:46.079: vcpu-0| If the file resides on a remote file system, please make sure your network connection and the server where this disk resides are functioning properly. If the file resides on removable media, reattach the media.
Feb 09 23:49:46.079: vcpu-0| Choose Retry to attempt the operation again.
Feb 09 23:49:46.079: vcpu-0| Choose Abort to terminate this session.
Feb 09 23:49:46.079: vcpu-0| Choose Continue to forward the error to the guest operating system.----------------------------------------
Feb 10 10:22:50.275: vcpu-0| Msg_Question: msg.vmxaiomgr.retrycontabort.rudeunplug reply=0
Feb 10 10:22:50.275: vcpu-0| VMXAIOMGR:Reopening d:\vm\WIN XP Pro\WIN XP Pro.vmdk and retrying outstanding IOs
Feb 10 10:22:50.275: vcpu-0| VMXAIOMGR:Reopened 1 file handles and retried 0 IOs
Feb 10 10:22:50.275: vcpu-0| VLANCE: Ethernet0 skipped 3 time(s)
Feb 10 10:22:50.275: vcpu-0| VLANCE: 2 0 0 0 0 0 0 1 0 0 0 0 0 0 0 0
Feb 10 10:22:50.275: vcpu-0| VLANCE: 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
Feb 10 10:22:50.306: vmx| ide0:0: Command WRITE(10) took 3624.013 seconds (ok)
Feb 10 10:22:50.306: vcpu-0| VLANCE: Ethernet0 skipped 4 time(s)
Feb 10 10:22:50.306: vcpu-0| VLANCE: 2 0 0 0 0 0 0 2 0 0 0 0 0 0 0 0
Feb 10 10:22:50.306: vcpu-0| VLANCE: 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
Feb 10 10:22:50.306: vcpu-0| VLANCE: Ethernet0 skipped 5 time(s)
Feb 10 10:22:50.306: vcpu-0| VLANCE: 2 0 0 0 0 1 0 2 0 0 0 0 0 0 0 0
Feb 10 10:22:50.306: vcpu-0| VLANCE: 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
Feb 10 10:22:50.306: vcpu-0| VLANCE: Ethernet0 skipped 6 time(s)
Feb 10 10:22:50.306: vcpu-0| VLANCE: 2 0 0 0 0 1 0 3 0 0 0 0 0 0 0 0
Feb 10 10:22:50.306: vcpu-0| VLANCE: 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
Feb 10 10:23:17.353: mks| VNCENCODE 3 encoding mode change: (800x600x24depth,32bpp,3200bytes/line)
Feb 10 10:23:17.712: mks| VNCENCODE 3 encoding mode change: (800x600x24depth,32bpp,3200bytes/line)
Feb 10 10:23:21.478: mks| SVGA: display status changed, using optimizations for remote consoles.
Feb 10 10:25:10.556: vmx| TOOLS INSTALL entering BEGINNING state.
Feb 10 10:25:10.556: vmx| TOOLS INSTALL Scanning device ide0:0 (WIN XP Pro.vmdk).
Feb 10 10:25:10.556: vmx| TOOLS INSTALL Scanning device ide0:1 ((null)).
Feb 10 10:25:10.556: vmx| TOOLS INSTALL Scanning device ide1:0 (E:).
Feb 10 10:25:10.556: vmx| TOOLS INSTALL Scanning device ide1:1 ((null)).
Feb 10 10:25:10.556: vmx| TOOLS INSTALL Attempting image mount.
Feb 10 10:25:10.556: vmx| TOOLS INSTALL using device 'ide1:0'.
Feb 10 10:25:11.837: vmx| CDROM: Disconnected cdrom: ide1:0
Feb 10 10:25:11.868: vcpu-0| CDROM: switching CDROM state from 2 to 3 using error sense key.
Feb 10 10:25:12.603: vcpu-0| CDROM: switching CDROM state from 3 to 4 using error sense key.
Feb 10 10:25:14.837: vmx| TOOLS INSTALL entering INSERTED state.
Feb 10 10:25:14.837: vmx| CDROM: Connecting ide1:0 to 'C:\Program Files\VMware\VMware Server\windows.iso'. img=1 raw=0 remote=0
Feb 10 10:25:15.603: vcpu-0| CDROM: switching CDROM state from 4 to 5 using error sense key.
Feb 10 10:25:16.603: vcpu-0| CDROM: switching CDROM state from 5 to 1 using error sense key.
Feb 10 10:25:16.634: vcpu-0| CDROM: Emulate GET CONFIGURATION RT 0 start feature 0
Feb 10 10:25:16.634: vcpu-0| CDROM: Emulate GET CONFIGURATION RT 0 start feature 30
Feb 10 10:25:16.634: vcpu-0| CDROM: Emulate GET CONFIGURATION RT 0 start feature 31
Feb 10 10:25:16.634: vcpu-0| CDROM: Emulate GET CONFIGURATION RT 0 start feature 32
Feb 10 10:25:16.634: vcpu-0| CDROM: Emulate GET CONFIGURATION RT 0 start feature 33
Feb 10 10:25:16.634: vcpu-0| CDROM: Emulate GET CONFIGURATION RT 0 start feature 35
Feb 10 10:25:16.634: vcpu-0| CDROM: Emulate GET CONFIGURATION RT 0 start feature 36
Feb 10 10:25:16.634: vcpu-0| CDROM: Emulate GET CONFIGURATION RT 0 start feature 38
Feb 10 10:25:16.634: vcpu-0| CDROM: Emulate GET CONFIGURATION RT 0 start feature 45
Feb 10 10:25:16.634: vcpu-0| CDROM: Emulate GET CONFIGURATION RT 0 start feature 46
Feb 10 10:25:16.634: vcpu-0| CDROM: Emulate GET CONFIGURATION RT 0 start feature 47
Feb 10 10:25:16.634: vcpu-0| CDROM: Emulate GET CONFIGURATION RT 0 start feature 259
Feb 10 10:26:22.525: vcpu-0| SVGA: Unregistering IOSpace at 0x10d0
Feb 10 10:26:22.525: vcpu-0| SVGA: Unregistering MemSpace at 0xd0000000(0xd0000000) and 0xd8000000(0xd8000000)
Feb 10 10:26:22.556: vcpu-0| SVGA: Registering IOSpace at 0x10d0
Feb 10 10:26:22.556: vcpu-0| SVGA: Registering MemSpace at 0xd0000000(0xd0000000) and 0xd8000000(0xd8000000)
Feb 10 10:26:22.696: mks| VNCENCODE 3 encoding mode change: (720x400x24depth,32bpp,2880bytes/line)
Feb 10 10:26:23.384: vcpu-0| Guest OS = 0x5008
Feb 10 10:26:23.587: mks| VNCENCODE 3 encoding mode change: (800x600x24depth,32bpp,3200bytes/line)
Feb 10 10:26:24.540: mks| VNCENCODE 3 encoding mode change: (800x600x24depth,32bpp,3200bytes/line)
Feb 10 10:26:30.837: vcpu-0| VNET: Notification enabled for Ethernet0
Feb 10 10:26:30.853: vcpu-0| Guest: VMXNET: Initialization completed successfully. Version 2.0 Jul 9 2008.
Feb 10 10:26:30.868: vcpu-0| Guest: VMXNET: Features:
Feb 10 10:26:30.868: vcpu-0| Guest: VMXNET: Vmxnet rx ringLen1 = 100, ringLen2 = 1
Feb 10 10:26:38.525: vcpu-0| MKS Backdoor get pointer: first time, notify tools are running
Feb 10 10:26:39.618: vcpu-0| TOOLS unified loop capability requested by 'toolbox-dnd'; now sending options via TCLO
Feb 10 10:26:39.650: vcpu-0| GuestRpc: Channel 0, guest application toolbox-dnd.
Feb 10 10:26:39.650: vcpu-0| TOOLS setting the tools version to '1'
Feb 10 10:26:39.650: vcpu-0| DISKLIB-DDB : "toolsVersion" = "1" (was "0")
Feb 10 10:26:39.728: vcpu-0| GuestRpc: Channel 1, guest application toolbox.
Feb 10 10:26:39.728: vcpu-0| TOOLS Reducing idleLoopSpinUS to 500us
Feb 10 10:26:39.728: vcpu-0| TOOLS sending 'OS_PowerOn' (3) state change request
Feb 10 10:26:39.775: vcpu-0| TOOLS autoupgrade protocol version 2
Feb 10 10:26:39.775: vcpu-0| TOOLS ToolsCapabilityGuestTempDirectory received 1 C:\WINDOWS\TEMP
Feb 10 10:26:39.775: vcpu-0| TOOLS ToolsCapabilityGue

Verfasst: 10.02.2009, 18:15
von continuum
Stell mal deine Virenscanner so ein, das sie die vmdks in Ruhe lassen.
Koennte auch ein defrag-service sein ... ?

Verfasst: 11.02.2009, 10:39
von l.lang
Vielen Dank für eure Tipps,
Das Problem ist eruiert. Das Verzeichnis haben wir von der Sicherung ausgeschlossen.
Und eine separate, einmalige Sicherung des VM Verzeichnisses ausgeführt. Siehe da.
Die VM ist wieder abgestürzt.
Scheinbar kommt die VM damit nicht klar, wenn sie läuft gleichzeitig gesichert zu werden.

Hat jemand vielleicht einen vorschlag, wie man das Verzeichnis sichern kann, obwohl die VM aktiv ist?

Vielen Dank.

Verfasst: 11.02.2009, 12:03
von Dayworker
Vorschlag? Ja, ich. ;)
Setz dich mal mit dem Feature "Schattenkopien" oder neudeutsch "Volume Shadow Copy Service" auseinander. Bei der Server2-Inst unter W2k3 sollte auch ein Dienst namens "VSS Writer" eingerichtet werden. Näheres dazu steht auch im Server2-Manual.

Selbst XPSP2 hat die Schattenkopien schon an Board und aktiviert sie nur aus mir unbekannten Gründen nicht. Leider läßt sich unter XP mangels GUI auch nichts weiter einstellen und zum Beispiel der Platz oder die Zeit begrenzen. Soweit ich es mitbekommen habe, scheint der Platz für Schattenkopien unter XP fest auf 10% der Laufwerksgröße begrenzt und auf unbegrenzte Vorhaltezeit eingestellt zu sein. Sowohl "NTBACKUP" als auch der Imager "DRIVE SNAPSHOT" sind in der Lage, Schattenkopien zu nutzen.

[edit]
Schattenkopien bzw die Technik dahinter namens "Copy-on-Write" gibt es auch für Linux und MacOS. Solaris hat ein geniales Datei-System (ZFS wenn ich mich nicht irre) dafür entwickelt, MacOS hat seinen Finder aufgebohrt und um einen Zeitstrahl erweitert. Das ganze läuft dann wohl unter dem Stichwort "Way Back Maschine".