Nov 18 12:28:43.375: vmx| Log for VMware Workstation pid=4672 version=6.0.4 build=build-93057 option=Release Nov 18 12:28:43.375: vmx| Hostname=TASWIN Nov 18 12:28:43.375: vmx| Command line: "C:\Program Files\VMware\VMware Workstation\bin\vmware-vmx.exe" "-#" "name=VMware Workstation;version=6.0.4;licensename=VMware Workstation for Win32;licenseversion=6.0 build-93057;" "-@" "pipe=\\.\pipe\vmx534919f8c1500d3e;" "C:\Documents and Settings\Thinkpad2\My Documents\My Virtual Machines\Ubuntu 8.04\Ubuntu.vmx" Nov 18 12:28:43.390: vmx| UI Connecting to pipe '\\.\pipe\vmx534919f8c1500d3e' with user '(null)' Nov 18 12:28:43.500: vmx| HD: host version is 5.1.2600 Nov 18 12:28:43.500: vmx| HD: addr 80509850 Nov 18 12:28:43.500: vmx| HD: 805620dc, 80554280 Nov 18 12:28:43.500: vmx| CPU #0 TSC = 7763143216224 Nov 18 12:28:43.500: vmx| CPU #1 TSC = 7763143216200 Nov 18 12:28:43.500: vmx| TSC delta 24 Nov 18 12:28:43.500: vmx| VMMon_GetkHzEstimate: Calculated 875700 kHz Nov 18 12:28:43.500: vmx| Measured CPU as 875700 kHz, but OS says 2394000 kHz; using 2394000 Nov 18 12:28:43.500: vmx| Msg_Hint: msg.hostWin32.cpuSpeedMismatch (sent) Nov 18 12:28:43.500: vmx| VMware Workstation has measured your CPU speed to be 875 MHz, but Windows reports that it is 2394 MHz. This may mean that your computer has a power-saving feature that varies the processor speed. As a result, the clock in your virtual machine may run too fast or too slow. Nov 18 12:28:43.500: vmx| Nov 18 12:28:43.500: vmx| For a workaround, please refer to the VMware knowledge base article at: Nov 18 12:28:43.500: vmx| http://www.vmware.com/info?id=97 Nov 18 12:28:43.500: vmx| --------------------------------------- Nov 18 12:28:43.718: vmx| VMMon_RememberkHzEstimate: Calculated 2394000 kHz Nov 18 12:28:43.718: vmx| CPUID[0] vendor: GenuntelineI Nov 18 12:28:43.718: vmx| CPUID[0] level 00000000, 0: 0x0000000a 0x756e6547 0x6c65746e 0x49656e69 Nov 18 12:28:43.718: vmx| CPUID[0] level 00000001, 0: 0x000006fb 0x00020800 0x0000e3bd 0xbfebfbff Nov 18 12:28:43.718: vmx| CPUID[0] level 00000002, 0: 0x05b0b101 0x005657f0 0x00000000 0x2cb43049 Nov 18 12:28:43.718: vmx| CPUID[0] level 00000003, 0: 0x00000000 0x00000000 0x00000000 0x00000000 Nov 18 12:28:43.718: vmx| CPUID[0] level 00000004, 0: 0x00000000 0x00000000 0x00000000 0x00000000 Nov 18 12:28:43.718: vmx| CPUID[0] level 00000005, 0: 0x00000040 0x00000040 0x00000003 0x00022220 Nov 18 12:28:43.718: vmx| CPUID[0] level 00000006, 0: 0x00000003 0x00000002 0x00000001 0x00000000 Nov 18 12:28:43.718: vmx| CPUID[0] level 00000007, 0: 0x00000000 0x00000000 0x00000000 0x00000000 Nov 18 12:28:43.718: vmx| CPUID[0] level 00000008, 0: 0x00000400 0x00000000 0x00000000 0x00000000 Nov 18 12:28:43.718: vmx| CPUID[0] level 00000009, 0: 0x00000000 0x00000000 0x00000000 0x00000000 Nov 18 12:28:43.718: vmx| CPUID[0] level 0000000a, 0: 0x07280202 0x00000000 0x00000000 0x00000503 Nov 18 12:28:43.718: vmx| CPUID[0] level 80000000, 0: 0x80000008 0x00000000 0x00000000 0x00000000 Nov 18 12:28:43.718: vmx| CPUID[0] level 80000001, 0: 0x00000000 0x00000000 0x00000001 0x20100000 Nov 18 12:28:43.718: vmx| CPUID[0] level 80000002, 0: 0x65746e49 0x2952286c 0x726f4320 0x4d542865 Nov 18 12:28:43.718: vmx| CPUID[0] level 80000003, 0: 0x44203229 0x43206f75 0x20205550 0x54202020 Nov 18 12:28:43.718: vmx| CPUID[0] level 80000004, 0: 0x30303737 0x20402020 0x30342e32 0x007a4847 Nov 18 12:28:43.718: vmx| CPUID[0] level 80000005, 0: 0x00000000 0x00000000 0x00000000 0x00000000 Nov 18 12:28:43.718: vmx| CPUID[0] level 80000006, 0: 0x00000000 0x00000000 0x10008040 0x00000000 Nov 18 12:28:43.718: vmx| CPUID[0] level 80000007, 0: 0x00000000 0x00000000 0x00000000 0x00000000 Nov 18 12:28:43.718: vmx| CPUID[0] level 80000008, 0: 0x00003024 0x00000000 0x00000000 0x00000000 Nov 18 12:28:43.718: vmx| CPUID[1] vendor: GenuntelineI Nov 18 12:28:43.718: vmx| CPUID[1] level 00000000, 0: 0x0000000a 0x756e6547 0x6c65746e 0x49656e69 Nov 18 12:28:43.718: vmx| CPUID[1] level 00000001, 0: 0x000006fb 0x01020800 0x0000e3bd 0xbfebfbff Nov 18 12:28:43.718: vmx| CPUID[1] level 00000002, 0: 0x05b0b101 0x005657f0 0x00000000 0x2cb43049 Nov 18 12:28:43.718: vmx| CPUID[1] level 00000003, 0: 0x00000000 0x00000000 0x00000000 0x00000000 Nov 18 12:28:43.718: vmx| CPUID[1] level 00000004, 0: 0x00000000 0x00000000 0x00000000 0x00000000 Nov 18 12:28:43.718: vmx| CPUID[1] level 00000005, 0: 0x00000040 0x00000040 0x00000003 0x00022220 Nov 18 12:28:43.718: vmx| CPUID[1] level 00000006, 0: 0x00000003 0x00000002 0x00000001 0x00000000 Nov 18 12:28:43.718: vmx| CPUID[1] level 00000007, 0: 0x00000000 0x00000000 0x00000000 0x00000000 Nov 18 12:28:43.718: vmx| CPUID[1] level 00000008, 0: 0x00000400 0x00000000 0x00000000 0x00000000 Nov 18 12:28:43.718: vmx| CPUID[1] level 00000009, 0: 0x00000000 0x00000000 0x00000000 0x00000000 Nov 18 12:28:43.718: vmx| CPUID[1] level 0000000a, 0: 0x07280202 0x00000000 0x00000000 0x00000503 Nov 18 12:28:43.718: vmx| CPUID[1] level 80000000, 0: 0x80000008 0x00000000 0x00000000 0x00000000 Nov 18 12:28:43.718: vmx| CPUID[1] level 80000001, 0: 0x00000000 0x00000000 0x00000001 0x20100000 Nov 18 12:28:43.718: vmx| CPUID[1] level 80000002, 0: 0x65746e49 0x2952286c 0x726f4320 0x4d542865 Nov 18 12:28:43.718: vmx| CPUID[1] level 80000003, 0: 0x44203229 0x43206f75 0x20205550 0x54202020 Nov 18 12:28:43.718: vmx| CPUID[1] level 80000004, 0: 0x30303737 0x20402020 0x30342e32 0x007a4847 Nov 18 12:28:43.718: vmx| CPUID[1] level 80000005, 0: 0x00000000 0x00000000 0x00000000 0x00000000 Nov 18 12:28:43.718: vmx| CPUID[1] level 80000006, 0: 0x00000000 0x00000000 0x10008040 0x00000000 Nov 18 12:28:43.718: vmx| CPUID[1] level 80000007, 0: 0x00000000 0x00000000 0x00000000 0x00000000 Nov 18 12:28:43.718: vmx| CPUID[1] level 80000008, 0: 0x00003024 0x00000000 0x00000000 0x00000000 Nov 18 12:28:43.718: vmx| hostCPUID vendor: GenuntelineI Nov 18 12:28:43.718: vmx| hostCPUID level 00000000, 0: 0x0000000a 0x756e6547 0x6c65746e 0x49656e69 Nov 18 12:28:43.718: vmx| hostCPUID level 00000001, 0: 0x000006fb 0x00020800 0x0000e3bd 0xbfebfbff Nov 18 12:28:43.718: vmx| hostCPUID level 00000002, 0: 0x05b0b101 0x005657f0 0x00000000 0x2cb43049 Nov 18 12:28:43.718: vmx| hostCPUID level 00000003, 0: 0x00000000 0x00000000 0x00000000 0x00000000 Nov 18 12:28:43.718: vmx| hostCPUID level 00000004, 0: 0x00000000 0x00000000 0x00000000 0x00000000 Nov 18 12:28:43.718: vmx| hostCPUID level 00000005, 0: 0x00000040 0x00000040 0x00000003 0x00022220 Nov 18 12:28:43.718: vmx| hostCPUID level 00000006, 0: 0x00000003 0x00000002 0x00000001 0x00000000 Nov 18 12:28:43.718: vmx| hostCPUID level 00000007, 0: 0x00000000 0x00000000 0x00000000 0x00000000 Nov 18 12:28:43.718: vmx| hostCPUID level 00000008, 0: 0x00000400 0x00000000 0x00000000 0x00000000 Nov 18 12:28:43.718: vmx| hostCPUID level 00000009, 0: 0x00000000 0x00000000 0x00000000 0x00000000 Nov 18 12:28:43.718: vmx| hostCPUID level 0000000a, 0: 0x07280202 0x00000000 0x00000000 0x00000503 Nov 18 12:28:43.718: vmx| hostCPUID level 80000000, 0: 0x80000008 0x00000000 0x00000000 0x00000000 Nov 18 12:28:43.718: vmx| hostCPUID level 80000001, 0: 0x00000000 0x00000000 0x00000001 0x20100000 Nov 18 12:28:43.718: vmx| hostCPUID level 80000002, 0: 0x65746e49 0x2952286c 0x726f4320 0x4d542865 Nov 18 12:28:43.718: vmx| hostCPUID level 80000003, 0: 0x44203229 0x43206f75 0x20205550 0x54202020 Nov 18 12:28:43.718: vmx| hostCPUID level 80000004, 0: 0x30303737 0x20402020 0x30342e32 0x007a4847 Nov 18 12:28:43.718: vmx| hostCPUID level 80000005, 0: 0x00000000 0x00000000 0x00000000 0x00000000 Nov 18 12:28:43.718: vmx| hostCPUID level 80000006, 0: 0x00000000 0x00000000 0x10008040 0x00000000 Nov 18 12:28:43.718: vmx| hostCPUID level 80000007, 0: 0x00000000 0x00000000 0x00000000 0x00000000 Nov 18 12:28:43.718: vmx| hostCPUID level 80000008, 0: 0x00003024 0x00000000 0x00000000 0x00000000 Nov 18 12:28:43.718: vmx| CPUID Maximum Physical Address Bits supported across all CPUs : 36 Nov 18 12:28:43.765: vmx| Host ACPI: can't find SRAT Nov 18 12:28:43.765: vmx| Host: SRAT tables not found in memory Nov 18 12:28:43.781: vmx| ACL_InitCapabilities: current IPC thread Nov 18 12:28:43.781: vmx| ACL_InitCapabilities: done Nov 18 12:28:43.781: vmx| changing directory to C:\Documents and Settings\Thinkpad2\My Documents\My Virtual Machines\Ubuntu 8.04\. Nov 18 12:28:43.781: vmx| Config file: C:\Documents and Settings\Thinkpad2\My Documents\My Virtual Machines\Ubuntu 8.04\Ubuntu.vmx Nov 18 12:28:43.875: vmx| VMXVmdb_LoadRawConfig: Loading raw config Nov 18 12:28:43.906: vmx| VMXVmdbCbVmVmxExecState: Exec state change requested to state poweredOn without reset Nov 18 12:28:43.906: vmx| PowerOn Nov 18 12:28:43.906: vmx| Host: WIN32 highest NUMA node 0 Nov 18 12:28:43.906: vmx| Host: WIN32 NUMA node 0, CPU mask 0x0000000000000003 Nov 18 12:28:43.906: vmx| VMXVmdb_LoadRawConfig: Loading raw config Nov 18 12:28:43.921: vmx| HOST Windows version 5.1, build 2600, platform 2, "Service Pack 3", SMP Nov 18 12:28:43.921: vmx| DICT --- USER PREFERENCES Nov 18 12:28:43.921: vmx| DICT pref.eula.0.appName = VMware Workstation Nov 18 12:28:43.921: vmx| DICT pref.eula.0.buildNumber = 93057 Nov 18 12:28:43.921: vmx| DICT pref.mruDest0.present = FALSE Nov 18 12:28:43.921: vmx| DICT pref.mruDest0.destString = Nov 18 12:28:43.921: vmx| DICT pref.mruDest0.user = Nov 18 12:28:43.921: vmx| DICT pref.mruDest1.present = FALSE Nov 18 12:28:43.921: vmx| DICT pref.mruDest1.destString = Nov 18 12:28:43.921: vmx| DICT pref.mruDest1.user = Nov 18 12:28:43.921: vmx| DICT pref.mruDest2.present = FALSE Nov 18 12:28:43.921: vmx| DICT pref.mruDest2.destString = Nov 18 12:28:43.921: vmx| DICT pref.mruDest2.user = Nov 18 12:28:43.921: vmx| DICT pref.mruDest3.present = FALSE Nov 18 12:28:43.921: vmx| DICT pref.mruDest3.destString = Nov 18 12:28:43.921: vmx| DICT pref.mruDest3.user = Nov 18 12:28:43.921: vmx| DICT pref.mruDest4.present = FALSE Nov 18 12:28:43.921: vmx| DICT pref.mruDest4.destString = Nov 18 12:28:43.921: vmx| DICT pref.mruDest4.user = Nov 18 12:28:43.921: vmx| DICT pref.mruDest5.present = FALSE Nov 18 12:28:43.921: vmx| DICT pref.mruDest5.destString = Nov 18 12:28:43.921: vmx| DICT pref.mruDest5.user = Nov 18 12:28:43.921: vmx| DICT pref.mruDest6.present = FALSE Nov 18 12:28:43.921: vmx| DICT pref.mruDest6.destString = Nov 18 12:28:43.921: vmx| DICT pref.mruDest6.user = Nov 18 12:28:43.921: vmx| DICT pref.mruDest7.present = FALSE Nov 18 12:28:43.921: vmx| DICT pref.mruDest7.destString = Nov 18 12:28:43.921: vmx| DICT pref.mruDest7.user = Nov 18 12:28:43.921: vmx| DICT pref.mruATS0.present = FALSE Nov 18 12:28:43.921: vmx| DICT pref.mruATS0.atsString = Nov 18 12:28:43.921: vmx| DICT pref.mruATS0.domain = Nov 18 12:28:43.921: vmx| DICT pref.mruATS0.user = Nov 18 12:28:43.921: vmx| DICT pref.mruATS0.secure = FALSE Nov 18 12:28:43.921: vmx| DICT pref.mruATS0.port = 0 Nov 18 12:28:43.921: vmx| DICT pref.mruATS1.present = FALSE Nov 18 12:28:43.921: vmx| DICT pref.mruATS1.atsString = Nov 18 12:28:43.921: vmx| DICT pref.mruATS1.domain = Nov 18 12:28:43.921: vmx| DICT pref.mruATS1.user = Nov 18 12:28:43.921: vmx| DICT pref.mruATS1.secure = FALSE Nov 18 12:28:43.921: vmx| DICT pref.mruATS1.port = 0 Nov 18 12:28:43.921: vmx| DICT pref.mruATS2.present = FALSE Nov 18 12:28:43.921: vmx| DICT pref.mruATS2.atsString = Nov 18 12:28:43.921: vmx| DICT pref.mruATS2.domain = Nov 18 12:28:43.921: vmx| DICT pref.mruATS2.user = Nov 18 12:28:43.921: vmx| DICT pref.mruATS2.secure = FALSE Nov 18 12:28:43.921: vmx| DICT pref.mruATS2.port = 0 Nov 18 12:28:43.921: vmx| DICT pref.mruATS3.present = FALSE Nov 18 12:28:43.921: vmx| DICT pref.mruATS3.atsString = Nov 18 12:28:43.921: vmx| DICT pref.mruATS3.domain = Nov 18 12:28:43.921: vmx| DICT pref.mruATS3.user = Nov 18 12:28:43.921: vmx| DICT pref.mruATS3.secure = FALSE Nov 18 12:28:43.921: vmx| DICT pref.mruATS3.port = 0 Nov 18 12:28:43.921: vmx| DICT pref.mruATS4.present = FALSE Nov 18 12:28:43.921: vmx| DICT pref.mruATS4.atsString = Nov 18 12:28:43.921: vmx| DICT pref.mruATS4.domain = Nov 18 12:28:43.921: vmx| DICT pref.mruATS4.user = Nov 18 12:28:43.921: vmx| DICT pref.mruATS4.secure = FALSE Nov 18 12:28:43.921: vmx| DICT pref.mruATS4.port = 0 Nov 18 12:28:43.921: vmx| DICT pref.mruATS5.present = FALSE Nov 18 12:28:43.921: vmx| DICT pref.mruATS5.atsString = Nov 18 12:28:43.921: vmx| DICT pref.mruATS5.domain = Nov 18 12:28:43.921: vmx| DICT pref.mruATS5.user = Nov 18 12:28:43.921: vmx| DICT pref.mruATS5.secure = FALSE Nov 18 12:28:43.921: vmx| DICT pref.mruATS5.port = 0 Nov 18 12:28:43.921: vmx| DICT pref.mruATS6.present = FALSE Nov 18 12:28:43.921: vmx| DICT pref.mruATS6.atsString = Nov 18 12:28:43.921: vmx| DICT pref.mruATS6.domain = Nov 18 12:28:43.921: vmx| DICT pref.mruATS6.user = Nov 18 12:28:43.921: vmx| DICT pref.mruATS6.secure = FALSE Nov 18 12:28:43.921: vmx| DICT pref.mruATS6.port = 0 Nov 18 12:28:43.921: vmx| DICT pref.mruATS7.present = FALSE Nov 18 12:28:43.921: vmx| DICT pref.mruATS7.atsString = Nov 18 12:28:43.921: vmx| DICT pref.mruATS7.domain = Nov 18 12:28:43.921: vmx| DICT pref.mruATS7.user = Nov 18 12:28:43.921: vmx| DICT pref.mruATS7.secure = FALSE Nov 18 12:28:43.921: vmx| DICT pref.mruATS7.port = 0 Nov 18 12:28:43.921: vmx| DICT webUpdate.checkLast = 1226955612 Nov 18 12:28:43.921: vmx| DICT pref.view.toolbars.list = _SEP_,POWER_OFF,SUSPEND,POWER_ON,RESET,SNAPSHOT,REVERT,SNAPSHOT_MANAGER,FAVORITES,QUICK_SWITCH,FULL_SCREEN,SUMMARY,APPLIANCE,CONSOLE,QUICK_MINIMIZE,FILE_EXIT,VPLAY_PLAY,VPLAY_RECORD,VPLAY_STOP Nov 18 12:28:43.921: vmx| DICT pref.ws.openedObj0.present = TRUE Nov 18 12:28:43.921: vmx| DICT pref.ws.openedObj0.name = Home Nov 18 12:28:43.921: vmx| DICT pref.ws.openedObj.maxNum = 2 Nov 18 12:28:43.921: vmx| DICT pref.placement.left = 27 Nov 18 12:28:43.921: vmx| DICT pref.placement.top = -20 Nov 18 12:28:43.921: vmx| DICT pref.placement.right = 1397 Nov 18 12:28:43.921: vmx| DICT pref.placement.bottom = 972 Nov 18 12:28:43.921: vmx| DICT pref.tip.index = 19 Nov 18 12:28:43.921: vmx| DICT webUpdate.lastCheck.status = done_updates Nov 18 12:28:43.921: vmx| DICT pref.fullscreen.autohide = TRUE Nov 18 12:28:43.921: vmx| DICT pref.autoFitFullScreen = fitHostToGuest Nov 18 12:28:43.921: vmx| DICT hint.leaveFullScreen = FALSE Nov 18 12:28:43.921: vmx| DICT pref.fullscreen.toolbarPixels = 0 Nov 18 12:28:43.921: vmx| DICT pref.view.navBar = TRUE Nov 18 12:28:43.921: vmx| DICT pref.autoFit = FALSE Nov 18 12:28:43.921: vmx| DICT pref.view.statusBar = FALSE Nov 18 12:28:43.921: vmx| DICT pref.view.tabs = FALSE Nov 18 12:28:43.921: vmx| DICT pref.view.toolbars.menu.position.ix = 0 Nov 18 12:28:43.921: vmx| DICT pref.view.toolbars.menu.position.newLine = TRUE Nov 18 12:28:43.921: vmx| DICT pref.view.toolbars.menu.position.length = 0 Nov 18 12:28:43.921: vmx| DICT pref.view.toolbars.power.position.ix = 1 Nov 18 12:28:43.921: vmx| DICT pref.view.toolbars.power.position.length = 114 Nov 18 12:28:43.921: vmx| DICT pref.view.toolbars.snapshot.position.ix = 2 Nov 18 12:28:43.921: vmx| DICT pref.view.toolbars.snapshot.position.length = 75 Nov 18 12:28:43.921: vmx| DICT pref.view.toolbars.view.position.ix = 3 Nov 18 12:28:43.921: vmx| DICT pref.view.toolbars.view.position.length = 158 Nov 18 12:28:43.921: vmx| DICT pref.view.toolbars.vplay.position.ix = 4 Nov 18 12:28:43.921: vmx| DICT pref.view.toolbars.vplay.position.length = 75 Nov 18 12:28:43.921: vmx| DICT pref.ws.currentObj.path = Nov 18 12:28:43.921: vmx| DICT pref.ws.currentObj.type = home Nov 18 12:28:43.921: vmx| DICT pref.ws.openedObj1.present = TRUE Nov 18 12:28:43.921: vmx| DICT pref.ws.openedObj1.name = Ubuntu 8.04 Nov 18 12:28:43.921: vmx| DICT pref.ws.openedObj2.present = TRUE Nov 18 12:28:43.921: vmx| DICT pref.ws.openedObj2.type = vm Nov 18 12:28:43.921: vmx| DICT pref.ws.openedObj2.path = /vm/#534919f8c1500d3e/ Nov 18 12:28:43.921: vmx| DICT pref.ws.openedObj2.file = C:\Documents and Settings\Thinkpad2\My Documents\My Virtual Machines\Ubuntu 8.04\Ubuntu.vmx Nov 18 12:28:43.921: vmx| DICT pref.ws.openedObj2.name = Ubuntu 8.04 Nov 18 12:28:43.921: vmx| DICT pref.ws.openedObj1.type = vm Nov 18 12:28:43.921: vmx| DICT pref.ws.openedObj1.path = /vm/#534919f8c1500d3e/ Nov 18 12:28:43.921: vmx| DICT pref.ws.openedObj1.file = C:\Documents and Settings\Thinkpad2\My Documents\My Virtual Machines\Ubuntu 8.04\Ubuntu.vmx Nov 18 12:28:43.921: vmx| DICT pref.view.toolbars.view.1.items.count = 7 Nov 18 12:28:43.921: vmx| DICT pref.view.toolbars.view.1.item0.id = FAVORITES Nov 18 12:28:43.921: vmx| DICT pref.view.toolbars.view.1.item1.id = QUICK_SWITCH Nov 18 12:28:43.921: vmx| DICT pref.view.toolbars.view.1.item2.id = FULL_SCREEN Nov 18 12:28:43.921: vmx| DICT pref.view.toolbars.view.1.item3.id = _SEP_ Nov 18 12:28:43.921: vmx| DICT pref.view.toolbars.view.1.item4.id = SUMMARY Nov 18 12:28:43.921: vmx| DICT pref.view.toolbars.view.1.item5.id = APPLIANCE Nov 18 12:28:43.921: vmx| DICT pref.view.toolbars.view.1.item6.id = CONSOLE Nov 18 12:28:43.921: vmx| DICT hints.hideAll = TRUE Nov 18 12:28:43.921: vmx| DICT pref.ws.openedObj3.type = vm Nov 18 12:28:43.921: vmx| DICT pref.ws.openedObj3.path = /vm/#534919f8c1500d3e/ Nov 18 12:28:43.921: vmx| DICT pref.ws.openedObj3.file = C:\Documents and Settings\Thinkpad2\My Documents\My Virtual Machines\Ubuntu 8.04\Ubuntu.vmx Nov 18 12:28:43.921: vmx| DICT pref.ws.openedObj3.name = Ubuntu 8.04 Nov 18 12:28:43.921: vmx| DICT pref.enableAllSharedFolders = TRUE Nov 18 12:28:43.921: vmx| DICT pref.autoFitGuestToWindow = FALSE Nov 18 12:28:43.921: vmx| DICT --- USER DEFAULTS Nov 18 12:28:43.921: vmx| DICT --- HOST DEFAULTS Nov 18 12:28:43.921: vmx| DICT prefvmx.useRecommendedLockedMemSize = TRUE Nov 18 12:28:43.921: vmx| DICT --- SITE DEFAULTS Nov 18 12:28:43.921: vmx| DICT prefvmx.useRecommendedLockedMemSize = TRUE Nov 18 12:28:43.921: vmx| DICT --- COMMAND LINE Nov 18 12:28:43.921: vmx| DICT gui.available = TRUE Nov 18 12:28:43.921: vmx| DICT --- CONFIGURATION Nov 18 12:28:43.921: vmx| DICT config.version = 8 Nov 18 12:28:43.921: vmx| DICT virtualHW.version = 6 Nov 18 12:28:43.921: vmx| DICT scsi0.present = TRUE Nov 18 12:28:43.921: vmx| DICT scsi0.virtualDev = lsilogic Nov 18 12:28:43.921: vmx| DICT memsize = 512 Nov 18 12:28:43.921: vmx| DICT scsi0:0.present = TRUE Nov 18 12:28:43.921: vmx| DICT scsi0:0.fileName = Ubuntu.vmdk Nov 18 12:28:43.921: vmx| DICT ide1:0.present = TRUE Nov 18 12:28:43.921: vmx| DICT ide1:0.fileName = auto detect Nov 18 12:28:43.921: vmx| DICT ide1:0.deviceType = cdrom-raw Nov 18 12:28:43.921: vmx| DICT floppy0.autodetect = TRUE Nov 18 12:28:43.921: vmx| DICT ethernet0.present = TRUE Nov 18 12:28:43.921: vmx| DICT ethernet0.wakeOnPcktRcv = FALSE Nov 18 12:28:43.921: vmx| DICT usb.present = TRUE Nov 18 12:28:43.921: vmx| DICT ehci.present = TRUE Nov 18 12:28:43.921: vmx| DICT sound.present = TRUE Nov 18 12:28:43.921: vmx| DICT sound.fileName = -1 Nov 18 12:28:43.921: vmx| DICT sound.autodetect = TRUE Nov 18 12:28:43.921: vmx| DICT svga.autodetect = TRUE Nov 18 12:28:43.921: vmx| DICT pciBridge0.present = TRUE Nov 18 12:28:43.921: vmx| DICT mks.keyboardFilter = allow Nov 18 12:28:43.921: vmx| DICT displayName = Ubuntu 8.04 Nov 18 12:28:43.921: vmx| DICT guestOS = ubuntu Nov 18 12:28:43.921: vmx| DICT nvram = Ubuntu.nvram Nov 18 12:28:43.921: vmx| DICT deploymentPlatform = windows Nov 18 12:28:43.921: vmx| DICT virtualHW.productCompatibility = hosted Nov 18 12:28:43.921: vmx| DICT tools.upgrade.policy = useGlobal Nov 18 12:28:43.921: vmx| DICT ide1:0.autodetect = TRUE Nov 18 12:28:43.921: vmx| DICT floppy0.fileName = A: Nov 18 12:28:43.921: vmx| DICT extendedConfigFile = Ubuntu.vmxf Nov 18 12:28:43.921: vmx| DICT ide1:0.startConnected = TRUE Nov 18 12:28:43.921: vmx| DICT ethernet0.addressType = generated Nov 18 12:28:43.921: vmx| DICT uuid.location = 56 4d a8 52 51 2e da 8e-41 13 82 7b 2b 8d 3f 3d Nov 18 12:28:43.921: vmx| DICT uuid.bios = 56 4d a8 52 51 2e da 8e-41 13 82 7b 2b 8d 3f 3d Nov 18 12:28:43.921: vmx| DICT scsi0:0.redo = Nov 18 12:28:43.921: vmx| DICT pciBridge0.pciSlotNumber = 17 Nov 18 12:28:43.921: vmx| DICT scsi0.pciSlotNumber = 16 Nov 18 12:28:43.921: vmx| DICT ethernet0.pciSlotNumber = 32 Nov 18 12:28:43.921: vmx| DICT sound.pciSlotNumber = 33 Nov 18 12:28:43.921: vmx| DICT ehci.pciSlotNumber = 34 Nov 18 12:28:43.921: vmx| DICT ethernet0.generatedAddress = 00:0c:29:8d:3f:3d Nov 18 12:28:43.921: vmx| DICT ethernet0.generatedAddressOffset = 0 Nov 18 12:28:43.921: vmx| DICT tools.syncTime = FALSE Nov 18 12:28:43.921: vmx| DICT ethernet0.connectionType = nat Nov 18 12:28:43.921: vmx| DICT checkpoint.vmState = Nov 18 12:28:43.921: vmx| DICT tools.remindInstall = TRUE Nov 18 12:28:43.921: vmx| DICT floppy0.startConnected = FALSE Nov 18 12:28:43.921: vmx| DICT --- USER DEFAULTS Nov 18 12:28:43.921: vmx| DICT --- HOST DEFAULTS Nov 18 12:28:43.921: vmx| DICT prefvmx.useRecommendedLockedMemSize = TRUE Nov 18 12:28:43.921: vmx| DICT --- SITE DEFAULTS Nov 18 12:28:43.921: vmx| DICT prefvmx.useRecommendedLockedMemSize = TRUE Nov 18 12:28:43.921: vmx| DICT --- GLOBAL SETTINGS Nov 18 12:28:43.937: vmx| hostCpuFeatures = 0x500001fa Nov 18 12:28:43.937: vmx| hostNumPerfCounters = 2 Nov 18 12:28:43.953: vmx| Host display topology 1400x1050. Nov 18 12:28:43.953: vmx| SVGA using 2360x1770. Nov 18 12:28:43.953: vmx| WSSCAN: reserved mem (in MB) min=32 max=2944 recommended=2944 Nov 18 12:28:43.953: vmx| hostMem=3072 maxAllowedAll=-1 maxAllowedVM=8192 Nov 18 12:28:43.953: vmx| totOverhead=16 Nov 18 12:28:43.953: vmx| WSSCAN: used rec mem (in MB) 2944 Nov 18 12:28:43.953: vmx| WSSCAN: Overhead 139069 paged 6585 nonpaged 4096 maxFBSize Nov 18 12:28:43.953: vmx| WSSCAN 1 1 622551 691344 753664 622551 50 0 Nov 18 12:28:43.953: vmx| LICENSE using: 'HKEY_LOCAL_MACHINE\SOFTWARE\VMware, Inc.\VMware Workstation\License.ws.6.0.200907' Nov 18 12:28:44.015: vmx| LOG failed to remove stats32-2 failed: The system cannot find the file specified Nov 18 12:28:44.015: vmx| LOG failed to remove stats64-2 failed: The system cannot find the file specified Nov 18 12:28:44.062: vmx| guestCpuFeatures = 0x500001f0 Nov 18 12:28:44.062: vmx| APIC: Host Local APIC at 0xfee00000 Nov 18 12:28:44.078: vmx| vmm32-modules: [vmm.vmm32 .data:0x2b000 .sdata:0x2c000 .statvars:0x2d000 .peer:0x2e000 .shared:0x54000 .bss:0x65000 .rodata:0x6c000 .text:0x78000 .kstatvars:0x3000 ,mmu-pae.vmm32 .rodata:0x76dcc .data:0x2bdb0 .peer:0x5293c .shared:0x63a4d .bss:0x6abc0 .text:0xcb584 .comment:0x40000cf0 .statvars:0x2000 .kstatvars:0x2000 .scb:0x400035a0 .shared_meta:0x40003480 .peer_meta:0x40000bd0 ,sharedmmu-none.vmm32 .text:0xd5504 .comment:0x40000dec ,pv-none.vmm32 .data:0x2bdcc .peer:0x1000 .shared:0x6435d .bss:0x6b120 .text:0xd552c .comment:0x40000dfe .statvars:0x1000 .kstatvars:0x1000 .shared_meta:0x400036f0 ,hv-none.vmm32 .data:0x2bdd0 .peer:0x1000 .shared:0x1000 .bss:0x6b1a4 .text:0xd5634 .comment:0x40000e46 .statvars:0x1000 .kstatvars:0x1000 , .rodata:0x76ddc ] Nov 18 12:28:44.078: vmx| KHZEstimate 2394000 Nov 18 12:28:44.078: vmx| MHZEstimate 2394 Nov 18 12:28:44.078: vmx| NumVCPUs 1 Nov 18 12:28:44.078: vmx| guestCPUID vendor: GenuntelineI Nov 18 12:28:44.078: vmx| guestCPUID level 00000000, 0: 0x0000000a 0x756e6547 0x6c65746e 0x49656e69 Nov 18 12:28:44.078: vmx| guestCPUID level 00000001, 0: 0x000006f8 0x00010800 0x00000211 0x0febfbff Nov 18 12:28:44.078: vmx| guestCPUID level 00000002, 0: 0x05b0b101 0x005657f0 0x00000000 0x2cb43049 Nov 18 12:28:44.078: vmx| guestCPUID level 00000003, 0: 0x00000000 0x00000000 0x00000000 0x00000000 Nov 18 12:28:44.078: vmx| guestCPUID level 00000004, 0: 0x00000000 0x00000000 0x00000000 0x00000000 Nov 18 12:28:44.078: vmx| guestCPUID level 00000005, 0: 0x00000040 0x00000040 0x00000003 0x00022220 Nov 18 12:28:44.078: vmx| guestCPUID level 00000006, 0: 0x00000003 0x00000002 0x00000001 0x00000000 Nov 18 12:28:44.078: vmx| guestCPUID level 00000007, 0: 0x00000000 0x00000000 0x00000000 0x00000000 Nov 18 12:28:44.078: vmx| guestCPUID level 00000008, 0: 0x00000400 0x00000000 0x00000000 0x00000000 Nov 18 12:28:44.078: vmx| guestCPUID level 00000009, 0: 0x00000000 0x00000000 0x00000000 0x00000000 Nov 18 12:28:44.078: vmx| guestCPUID level 0000000a, 0: 0x07280202 0x00000000 0x00000000 0x00000503 Nov 18 12:28:44.078: vmx| guestCPUID level 80000000, 0: 0x80000008 0x00000000 0x00000000 0x00000000 Nov 18 12:28:44.078: vmx| guestCPUID level 80000001, 0: 0x00000000 0x00000000 0x00000000 0x00100800 Nov 18 12:28:44.078: vmx| guestCPUID level 80000002, 0: 0x65746e49 0x2952286c 0x726f4320 0x4d542865 Nov 18 12:28:44.078: vmx| guestCPUID level 80000003, 0: 0x44203229 0x43206f75 0x20205550 0x54202020 Nov 18 12:28:44.078: vmx| guestCPUID level 80000004, 0: 0x30303737 0x20402020 0x30342e32 0x007a4847 Nov 18 12:28:44.078: vmx| guestCPUID level 80000005, 0: 0x00000000 0x00000000 0x00000000 0x00000000 Nov 18 12:28:44.078: vmx| guestCPUID level 80000006, 0: 0x00000000 0x00000000 0x10008040 0x00000000 Nov 18 12:28:44.078: vmx| guestCPUID level 80000007, 0: 0x00000000 0x00000000 0x00000000 0x00000000 Nov 18 12:28:44.078: vmx| guestCPUID level 80000008, 0: 0x00003024 0x00000000 0x00000000 0x00000000 Nov 18 12:28:44.078: vmx| PShare: enabled 1, scanRate 32, checkRate 16 Nov 18 12:28:44.078: vmx| UUID: location-UUID is 56 4d 4b e7 2c 87 5d 48-23 15 53 8e 71 e8 14 5b Nov 18 12:28:44.078: vmx| UUID: canonical path is c:\documents and settings\thinkpad2\my documents\my virtual machines\ubuntu 8.04\ubuntu.vmx Nov 18 12:28:44.078: vmx| UUID: location-UUID is 56 4d a8 52 51 2e da 8e-41 13 82 7b 2b 8d 3f 3d Nov 18 12:28:44.078: vmx| UUID: canonical path is c:\documents and settings\thinkpad2\my documents\my virtual machines\ubuntu 8.04\ubuntu.vmx Nov 18 12:28:44.078: vmx| UUID: location-UUID is 56 4d a8 52 51 2e da 8e-41 13 82 7b 2b 8d 3f 3d Nov 18 12:28:44.078: vmx| WORKER: Creating new group with numThreads=18 (18) Nov 18 12:28:44.078: vmx| MM: Using partialmap, 131072 pages AC 0 CE 1 TM 0 DOHU 0 Nov 18 12:28:44.078: vmx| UUID: canonical path is c:\documents and settings\thinkpad2\my documents\my virtual machines\ubuntu 8.04\ubuntu.vmx Nov 18 12:28:44.078: vmx| UUID: location-UUID is 56 4d a8 52 51 2e da 8e-41 13 82 7b 2b 8d 3f 3d Nov 18 12:28:44.093: vmx| FILE: ScanDirectory discarding file 'C:\Documents and Settings\Thinkpad2\My Documents\My Virtual Machines\Ubuntu 8.04\564da852-512e-da8e-4113-827b2b8d3f3d.vmem.lck\M55006.lck'; invalid executionID. Nov 18 12:28:44.093: vmx| MM: using 'C:\Documents and Settings\Thinkpad2\My Documents\My Virtual Machines\Ubuntu 8.04\564da852-512e-da8e-4113-827b2b8d3f3d.vmem' as a paging file Nov 18 12:28:44.093: vmx| Msg_Reset: Nov 18 12:28:44.093: vmx| ---------------------------------------- Nov 18 12:28:44.093: vmx| Opened paging file C:\Documents and Settings\Thinkpad2\My Documents\My Virtual Machines\Ubuntu 8.04\564da852-512e-da8e-4113-827b2b8d3f3d.vmem Nov 18 12:28:44.093: vmx| Mapped mainmem as pageable Nov 18 12:28:44.093: vmx| MStat: Creating Stat vm.uptime Nov 18 12:28:44.093: vmx| MStat: Creating Stat vm.suspendTime Nov 18 12:28:44.093: vmx| MStat: Creating Stat vm.powerOnTimeStamp Nov 18 12:28:44.109: vmx| VMXVmdb_LoadRawConfig: Loading raw config Nov 18 12:28:44.109: vmx| DISK: OPEN scsi0:0 'C:\Documents and Settings\Thinkpad2\My Documents\My Virtual Machines\Ubuntu 8.04\Ubuntu.vmdk' persistent R[(null)] Nov 18 12:28:44.125: vmx| FILE: ScanDirectory discarding file 'C:\Documents and Settings\Thinkpad2\My Documents\My Virtual Machines\Ubuntu 8.04\Ubuntu.vmdk.lck\M00381.lck'; invalid executionID. Nov 18 12:28:44.125: vmx| DISKLIB-DSCPTR: Opened [0]: "Ubuntu-flat.vmdk" 0 (0xa) Nov 18 12:28:44.125: vmx| DISKLIB-LINK : Opened 'C:\Documents and Settings\Thinkpad2\My Documents\My Virtual Machines\Ubuntu 8.04\Ubuntu.vmdk' (0xa): monolithicFlat, 73400320 sectors / 35840 Mb. Nov 18 12:28:44.125: vmx| DISKLIB-LIB : Opened "C:\Documents and Settings\Thinkpad2\My Documents\My Virtual Machines\Ubuntu 8.04\Ubuntu.vmdk" (flags 0xa). 1521A24 Nov 18 12:28:44.125: vmx| DiskGetGeometry: Reading of disk partition table Nov 18 12:28:44.156: vmx| DISK: OPEN 'C:\Documents and Settings\Thinkpad2\My Documents\My Virtual Machines\Ubuntu 8.04\Ubuntu.vmdk' Geo (4568/255/63) BIOS Geo (4568/255/63) freeSpace=59394Mb, lsilogic Nov 18 12:28:44.156: vmx| Msg_Reset: Nov 18 12:28:44.156: vmx| ---------------------------------------- Nov 18 12:28:44.156: vmx| TimeTracker host to guest rate conversion 7764440481540 @ 2394000000Hz -> 7764440481540 @ 2394000000Hz Nov 18 12:28:44.156: vmx| TimeTracker host to guest rate conversion ((x * 2147483648) >> 31) + 0 Nov 18 12:28:44.156: vmx| USB: Initializing 'Generic' backend Nov 18 12:28:44.156: vmx| USBGW: Connected to HCMON version 2.3 Nov 18 12:28:44.156: vmx| USBGW: Skipping disk backing for file (C:\Documents and Settings\Thinkpad2\My Documents\My Virtual Machines\Ubuntu 8.04\Ubuntu.vmx). Nov 18 12:28:44.156: vmx| USBGW: Skipping disk backing for file (C:\Documents and Settings\Thinkpad2\My Documents\My Virtual Machines\Ubuntu 8.04\Ubuntu.vmdk). Nov 18 12:28:44.171: vmx| USBGW: Skipping disk backing for file (C:\Documents and Settings\Thinkpad2\My Documents\My Virtual Machines\Ubuntu 8.04\Ubuntu.vmsd). Nov 18 12:28:44.171: vmx| USBGW: Skipping disk backing for file (C:\Documents and Settings\Thinkpad2\My Documents\My Virtual Machines\Ubuntu 8.04\Ubuntu.nvram). Nov 18 12:28:44.187: vmx| USB: Initializing 'Virtual Hub' backend Nov 18 12:28:44.187: vmx| USB: Initializing 'Virtual Mouse' backend Nov 18 12:28:44.234: vmx| Host display topology 1400x1050. Nov 18 12:28:44.234: vmx| SVGA using 2360x1770. Nov 18 12:28:44.234: vmx| MKS REMOTE Loading VNC Configuration from VM config file Nov 18 12:28:44.234: vmx| Host display topology 1400x1050 with 1 displays. Nov 18 12:28:44.234: vmx| SVGA using 2360x1770. Nov 18 12:28:44.234: vmx| DISKUTIL: scsi0:0 : capacity=73400320 Nov 18 12:28:44.234: vmx| DISKUTIL: scsi0:0 : geometry=4568/255/63 Nov 18 12:28:44.234: vmx| SCSI0: UNTAGGED commands will be converted to ORDER tags. Nov 18 12:28:44.234: vmx| VLANCE: send cluster threshold is 80, size = 2 recalcInterval is 2 ticks Nov 18 12:28:44.234: vmx| Ethernet0 MAC Address: 00:0c:29:8d:3f:3d Nov 18 12:28:44.234: vmx| VMXNET: send cluster threshold is 80, size = 2 recalcInterval is 2 ticks, dontClusterSize is 128 Nov 18 12:28:44.234: vmx| E1000: checksum cycles/kB: C=1028 asm=733 Nov 18 12:28:44.234: vmx| USB: Initializing 'UHCI' host controller Nov 18 12:28:44.234: vmx| USB: Initializing 'EHCI' host controller Nov 18 12:28:44.234: vmx| MStat: Creating Stat vm.heartbeat Nov 18 12:28:44.234: vmx| DISKUTIL: scsi0:0 : toolsVersion = 0 Nov 18 12:28:44.234: vmx| DISKUTIL: Offline toolsVersion = 0 Nov 18 12:28:44.234: vmx| Msg_Hint: msg.tools.toolsReminder (sent) Nov 18 12:28:44.234: 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. Nov 18 12:28:44.234: vmx| Nov 18 12:28:44.234: vmx| --------------------------------------- Nov 18 12:28:44.250: vmx| TOOLS INSTALL initializing state to IDLE on power on. Nov 18 12:28:44.250: vmx| PTSC to VMI Wallclock (nsec) 7764666834780 @ 2394000000Hz -> 1227040124000000000 @ 1000000000Hz Nov 18 12:28:44.250: vmx| PTSC to VMI Wallclock (nsec) ((x * 3588109687) >> 33) + 1227036880613686891 Nov 18 12:28:44.250: vmx| VirtualProtect MapProtectMem(0x1a10000,0x1000) returns 0x1e7 Nov 18 12:28:44.250: vmx| VirtualProtect MapProtectMem(0x1a12000,0x0) returns 0x57 Nov 18 12:28:44.250: vmx| TOOLS received request in VMX to set option 'enableDnD' -> '1' Nov 18 12:28:44.250: vmx| TOOLS received request in VMX to set option 'copypaste' -> '1' Nov 18 12:28:44.265: vmx| VMX_PowerOn: ModuleTable_PowerOn = 1 Nov 18 12:28:44.265: vmx| VMX setting maximum IPC write buffers to 0 packets, 0 bytes Nov 18 12:28:44.265: mks| Async MKS thread is alive Nov 18 12:28:44.265: vmx| DnD rpc already set to 1 Nov 18 12:28:44.265: vmx| DnD CopyPasteRegisterRpc already set to 1 Nov 18 12:28:44.265: vcpu-0| APIC: version = 0x14, max LVT = 5 Nov 18 12:28:44.265: vcpu-0| APIC: LDR = 0x1000000, DFR = 0xffffffff Nov 18 12:28:44.281: vcpu-0| guestCpuFeatures = 0x500001f0 Nov 18 12:28:44.281: vcpu-0| Init modules. Nov 18 12:28:44.281: vcpu-0| CPU reset: hard Nov 18 12:28:44.312: vmx| SOUND 000.930228 ES1371Win32GetSelected mixerGetControlDetails error 11 Nov 18 12:28:44.312: vmx| VNET: Notification enabled for Ethernet0 Nov 18 12:28:44.312: vmx| CDROM: Using autodetect backend F: for ide1:0. Nov 18 12:28:44.312: vmx| CDROM: Connecting ide1:0 to 'F:'. img=0 raw=1 excl=0 remote=0 Nov 18 12:28:44.343: vcpu-0| sz=3047392 Nov 18 12:28:44.343: vcpu-0| vmm32 initialized: Releasebuild-93057. cflags: 0x00000002.09000002.a000070c.00000006 Nov 18 12:28:44.359: vcpu-0| MonitorInitNumaUnmapVMM32 Nov 18 12:28:44.390: mks| Connecting to window system. Nov 18 12:28:44.390: mks| KHBKL: Unable to parse keystring at: '' Nov 18 12:28:44.406: mks| Ignoring update request in VGA_Expose (mode change pending). Nov 18 12:28:44.406: mks| VMXAIOMGR: Disallowing USB connect. 1025584 us since last reopen Nov 18 12:28:44.437: mks| Ignoring update request in VGA_Expose (mode change pending). Nov 18 12:28:44.671: mks| VMXAIOMGR: Disallowing USB connect. 1294351 us since last reopen Nov 18 12:28:44.734: vcpu-0| SVGA: Registering MemSpace at 0xf0000000(0x0) and 0xe8000000(0x0) Nov 18 12:28:44.750: vcpu-0| SVGA: Unregistering MemSpace at 0xf0000000(0xf0000000) and 0xe8000000(0xe8000000) Nov 18 12:28:44.765: vcpu-0| SVGA: Registering MemSpace at 0xf0000000(0xf0000000) and 0xe8000000(0xe8000000) Nov 18 12:28:44.781: vcpu-0| SVGA: Unregistering MemSpace at 0xf0000000(0xf0000000) and 0xe8000000(0xe8000000) Nov 18 12:28:44.781: vcpu-0| SVGA: Registering IOSpace at 0x1400 Nov 18 12:28:44.781: vcpu-0| SVGA: Registering MemSpace at 0xf0000000(0xf0000000) and 0xe8000000(0xe8000000) Nov 18 12:28:45.125: vcpu-0| CDROM-WIN: 'F:' Length 24 MaxTransferLength 131072 MaxPhysPages 17 Nov 18 12:28:45.125: vcpu-0| CDROM-WIN: AlignMask 0x0 !AsyncEvent !TaggedQ ScanUp PIO Nov 18 12:28:45.140: vcpu-0| DISKUTIL: scsi0:0 : geometry=4568/255/63 Nov 18 12:28:45.187: vcpu-0| BIOS-UUID is 56 4d a8 52 51 2e da 8e-41 13 82 7b 2b 8d 3f 3d Nov 18 12:28:45.328: vcpu-0| DISKUTIL: scsi0:0 : toolsVersion = 0 Nov 18 12:28:45.328: vcpu-0| DISKUTIL: Offline toolsVersion = 0 Nov 18 12:28:47.359: vmx| VMXVmdbLoadUsbDevices: New set of 1 USB devices Nov 18 12:28:47.359: vmx| USB: Found device [name:Sierra\ Wireless\ USB\ Device vid:1199 pid:0220 path:1/4/0 speed:full family:vendor] Nov 18 12:28:53.781: vmx| PTSC slow TSC 0.5555 Nov 18 12:28:53.781: vmx| PTSC TSC 7782132012612 - 7775439807312, 2394 MHz Nov 18 12:28:53.781: vmx| PTSC PC 41599202430 - 41581192320, 3579 kHz Nov 18 12:28:53.781: vmx| PTSC using fake TSC Nov 18 12:28:53.781: vmx| PTSC 41599202841 @ 3579545Hz -> 7782132287220 @ 2394000000Hz Nov 18 12:28:53.781: vmx| PTSC ((x * 2805150871) >> 22) + -20039418101673 Nov 18 12:28:55.000: mks| SVGA: Remote display status changed, enabling local optimizations Nov 18 12:28:57.234: vcpu-0| Unknown int 10h func 0x0000 Nov 18 12:28:59.453: vcpu-0| SVGA: Unregistering IOSpace at 0x1400 Nov 18 12:28:59.453: vcpu-0| SVGA: Registering IOSpace at 0xfffffff0 Nov 18 12:28:59.453: vcpu-0| SVGA: Unregistering IOSpace at 0xfffffff0 Nov 18 12:28:59.453: vcpu-0| SVGA: Registering IOSpace at 0x1400 Nov 18 12:29:00.375: vcpu-0| UHCI: HCReset Nov 18 12:29:03.468: vcpu-0| BM-VIDE: Access to busmaster registers w/o busmaster enabled Nov 18 12:29:03.468: vcpu-0| BM-VIDE: Access to busmaster registers w/o busmaster enabled Nov 18 12:29:04.484: vcpu-0| UHCI: HCReset Nov 18 12:29:04.984: vcpu-0| SCSI0: RESET BUS Nov 18 12:29:05.046: vcpu-0| SCSI DEVICE (scsi0:0): MODE SENSE(6) for unsupported page 0 Nov 18 12:29:05.046: vcpu-0| SCSI DEVICE (scsi0:0): MODE SENSE(6) for unsupported page 0x8 Nov 18 12:29:05.046: vcpu-0| SCSI DEVICE (scsi0:0): MODE SENSE(6) for unsupported page 0 Nov 18 12:29:05.046: vcpu-0| SCSI DEVICE (scsi0:0): MODE SENSE(6) for unsupported page 0x8 Nov 18 12:29:05.109: vcpu-0| SCSI DEVICE (scsi0:0): INQUIRY request with EVPD set Nov 18 12:29:05.390: vmx| VIDE CDROM: Emulating Inquiry response on raw drive Nov 18 12:29:55.343: vcpu-0| SCSI DEVICE (scsi0:0): INQUIRY request with EVPD set Nov 18 12:29:55.359: vmx| VIDE CDROM: Emulating Inquiry response on raw drive Nov 18 12:29:57.437: vcpu-0| VLANCE: Returning 0x0 for LANCE_EXTINT IN Nov 18 12:29:57.437: vcpu-0| VLANCE: Ignoring LANCE_EXTINT OUT of 0x1 Nov 18 12:29:57.640: vcpu-0| VLANCE: IN on LANCE_MODE while not stopped: 0x73 Nov 18 12:29:57.640: vcpu-0| VLANCE: OUT on LANCE_PADR2 while not stopped: 0x73, word: 0x0 Nov 18 12:29:57.640: vcpu-0| VLANCE: OUT on LANCE_LADRF0 while not stopped: 0x73, word: 0x0 Nov 18 12:29:57.640: vcpu-0| VLANCE: OUT on LANCE_LADRF1 while not stopped: 0x73, word: 0x0 Nov 18 12:29:57.640: vcpu-0| VLANCE: OUT on LANCE_LADRF2 while not stopped: 0x73, word: 0x0 Nov 18 12:29:57.640: vcpu-0| VLANCE: OUT on LANCE_LADRF3 while not stopped: 0x73, word: 0x0 Nov 18 12:29:59.953: vcpu-0| SOUND 076.569833 ES1371Win32SetSelected mixerGetControlDetails error 11 Nov 18 12:30:10.296: mks| HostOps hideCursor before defineCursor! Nov 18 12:30:10.812: vcpu-0| SVGA: Unregistering IOSpace at 0x1400 Nov 18 12:30:10.812: vcpu-0| SVGA: Unregistering MemSpace at 0xf0000000(0xf0000000) and 0xe8000000(0xe8000000) Nov 18 12:30:10.828: vcpu-0| SVGA: Registering IOSpace at 0x1400 Nov 18 12:30:10.828: vcpu-0| SVGA: Registering MemSpace at 0xf0000000(0xf0000000) and 0xe8000000(0xe8000000) Nov 18 12:30:11.078: vcpu-0| Guest OS = 0x5008 Nov 18 12:30:11.078: mks| HostOps hideCursor before defineCursor! Nov 18 12:30:11.531: vcpu-0| VMMouse: CMD Read ID Nov 18 12:30:11.531: vcpu-0| VMMouse: CMD Disable Nov 18 12:30:11.531: vcpu-0| VMMouse: Disabling VMMouse mode Nov 18 12:30:12.828: vcpu-0| VMMouse: CMD Read ID Nov 18 12:30:12.828: vcpu-0| MKS switching absolute mouse on Nov 18 12:34:46.140: vmx| SOCKET 2 client closed connection Nov 18 12:36:10.437: vmx| SCSI0:0: Command READ(10) took 1.138 seconds (ok) Nov 18 12:38:44.234: vmx| TOOLS setting the tools version to '0' Nov 18 13:34:22.000: vmx| SCSI0:0: Command WRITE(10) took 1.000 seconds (ok) Nov 18 17:26:11.640: vmx| DISKLIB-LIB :numIOs = 50000 numMergedIOs = 0 numSplitIOs = 0 Nov 18 18:26:39.750: vmx| VMXVmdbLoadUsbDevices: New set of 2 USB devices Nov 18 18:26:39.750: vmx| USB: Found device [name:Sierra\ Wireless\ USB\ Device vid:1199 pid:0220 path:1/4/0 speed:full family:vendor] Nov 18 18:26:39.750: vmx| USB: Found device [name:Logitech\ USB\ Device vid:046d pid:09c1 path:1/6/1 speed:high family:other] Nov 18 18:30:24.828: vmx| VMXVmdbLoadUsbDevices: New set of 2 USB devices Nov 18 18:30:24.828: vmx| USB: Found device [name:Logitech\ USB\ Device vid:046d pid:09c1 path:1/3/1 speed:full family:other] Nov 18 18:30:24.828: vmx| USB: Found device [name:Sierra\ Wireless\ USB\ Device vid:1199 pid:0220 path:1/4/0 speed:full family:vendor] Nov 18 18:30:30.625: vmx| VMXVmdbLoadUsbDevices: New set of 1 USB devices Nov 18 18:30:30.625: vmx| USB: Found device [name:Sierra\ Wireless\ USB\ Device vid:1199 pid:0220 path:1/4/0 speed:full family:vendor] Nov 18 18:30:33.453: vmx| VMXVmdbLoadUsbDevices: New set of 2 USB devices Nov 18 18:30:33.453: vmx| USB: Found device [name:Sierra\ Wireless\ USB\ Device vid:1199 pid:0220 path:1/4/0 speed:full family:vendor] Nov 18 18:30:33.453: vmx| USB: Found device [name:Logitech\ USB\ Device vid:046d pid:09c1 path:1/6/1 speed:high family:other] Nov 18 18:32:14.515: vmx| VMXVmdbLoadUsbDevices: New set of 1 USB devices Nov 18 18:32:14.515: vmx| USB: Found device [name:Sierra\ Wireless\ USB\ Device vid:1199 pid:0220 path:1/4/0 speed:full family:vendor] Nov 18 18:32:23.562: vmx| VMXVmdbLoadUsbDevices: New set of 2 USB devices Nov 18 18:32:23.562: vmx| USB: Found device [name:Sierra\ Wireless\ USB\ Device vid:1199 pid:0220 path:1/4/0 speed:full family:vendor] Nov 18 18:32:23.562: vmx| USB: Found device [name:Logitech\ USB\ Device vid:046d pid:09c1 path:1/6/1 speed:high family:other] Nov 18 18:32:45.515: vmx| VMXVmdbLoadUsbDevices: New set of 1 USB devices Nov 18 18:32:45.515: vmx| USB: Found device [name:Sierra\ Wireless\ USB\ Device vid:1199 pid:0220 path:1/4/0 speed:full family:vendor] Nov 18 18:32:56.562: vmx| VMXVmdbLoadUsbDevices: New set of 2 USB devices Nov 18 18:32:56.562: vmx| USB: Found device [name:Sierra\ Wireless\ USB\ Device vid:1199 pid:0220 path:1/4/0 speed:full family:vendor] Nov 18 18:32:56.562: vmx| USB: Found device [name:Logitech\ USB\ Device vid:046d pid:09c1 path:1/6/1 speed:high family:other] Nov 18 21:18:07.718: vmx| [Vmdb_Set] Stale data for path: /vm/#_VMX/mks/focusEvent/ (current value '0' -> new value '1') [166->166] Nov 19 01:04:07.093: vmx| VMXVmdbLoadUsbDevices: New set of 1 USB devices Nov 19 01:04:07.093: vmx| USB: Found device [name:Sierra\ Wireless\ USB\ Device vid:1199 pid:0220 path:1/4/0 speed:full family:vendor] Nov 19 01:27:20.375: vmx| SCSI0:0: Command WRITE(10) took 1.001 seconds (ok) Nov 19 01:27:20.375: vmx| SCSI0:0: Command WRITE(10) took 1.001 seconds (ok) Nov 19 01:27:20.375: vmx| SCSI0:0: Command WRITE(10) took 1.001 seconds (ok) Nov 19 10:03:01.046: vmx| SCSI0:0: Command WRITE(10) took 1.000 seconds (ok) Nov 19 10:12:16.718: vmx| SCSI0:0: Command WRITE(10) took 2.501 seconds (ok) Nov 19 10:12:16.718: vmx| SCSI0:0: Command WRITE(10) took 2.501 seconds (ok) Nov 19 10:16:11.296: vmx| SCSI0:0: Command WRITE(10) took 2.000 seconds (ok) Nov 19 10:16:11.296: vmx| SCSI0:0: Command WRITE(10) took 2.000 seconds (ok) Nov 19 10:19:30.265: vmx| SCSI0:0: Command WRITE(10) took 1.000 seconds (ok) Nov 19 10:19:30.265: vmx| SCSI0:0: Command WRITE(10) took 1.000 seconds (ok) Nov 19 10:19:30.265: vmx| SCSI0:0: Command WRITE(10) took 1.000 seconds (ok) Nov 19 10:19:30.265: vmx| SCSI0:0: Command WRITE(10) took 1.000 seconds (ok) Nov 19 10:19:30.265: vmx| SCSI0:0: Command WRITE(10) took 1.000 seconds (ok) Nov 19 10:19:30.265: vmx| SCSI0:0: Command WRITE(10) took 1.000 seconds (ok) Nov 19 10:19:30.265: vmx| SCSI0:0: Command WRITE(10) took 1.001 seconds (ok) Nov 19 10:19:30.265: vmx| SCSI0:0: Command WRITE(10) took 1.001 seconds (ok) Nov 19 19:06:08.265: vmx| DISK: DISK/CDROM timeout of 31509.214 seconds on ide1:0 (ok) Nov 19 19:06:34.531: vmx| SCSI0:0: Command WRITE(10) took 1.046 seconds (ok) Nov 19 19:06:34.531: vmx| SCSI0:0: Command WRITE(10) took 1.046 seconds (ok) Nov 19 19:06:34.531: vmx| SCSI0:0: Command WRITE(10) took 1.046 seconds (ok) Nov 19 19:06:34.531: vmx| SCSI0:0: Command WRITE(10) took 1.046 seconds (ok) Nov 19 19:06:34.531: vmx| SCSI0:0: Command WRITE(10) took 1.046 seconds (ok) Nov 19 19:06:34.531: vmx| SCSI0:0: Command WRITE(10) took 1.047 seconds (ok) Nov 19 19:06:34.531: vmx| SCSI0:0: Command WRITE(10) took 1.047 seconds (ok) Nov 19 19:06:34.718: vmx| SCSI0:0: Command WRITE(10) took 1.234 seconds (ok) Nov 19 19:06:34.718: vmx| SCSI0:0: Command WRITE(10) took 1.234 seconds (ok) Nov 19 19:06:34.718: vmx| SCSI0:0: Command WRITE(10) took 1.235 seconds (ok) Nov 19 19:06:34.718: vmx| SCSI0:0: Command WRITE(10) took 1.235 seconds (ok) Nov 19 19:06:34.718: vmx| SCSI0:0: Command WRITE(10) took 1.236 seconds (ok) Nov 19 19:06:34.718: vmx| SCSI0:0: Command WRITE(10) took 1.236 seconds (ok) Nov 19 19:06:34.718: vmx| SCSI0:0: Command WRITE(10) took 1.236 seconds (ok) Nov 19 19:06:34.718: vmx| SCSI0:0: Command WRITE(10) took 1.236 seconds (ok) Nov 19 19:06:34.718: vmx| SCSI0:0: Command WRITE(10) took 1.240 seconds (ok) Nov 19 19:06:48.919: vcpu-0| VLANCE: Ethernet0 skipped 1 time(s) Nov 19 19:06:48.919: vcpu-0| VLANCE: 1 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 Nov 19 19:06:48.919: vcpu-0| VLANCE: 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 Nov 19 19:06:48.919: vcpu-0| VLANCE: Ethernet0 skipped 2 time(s) Nov 19 19:06:48.919: vcpu-0| VLANCE: 1 0 0 0 0 1 0 0 0 0 0 0 0 0 0 0 Nov 19 19:06:48.919: vcpu-0| VLANCE: 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 Nov 19 19:06:48.919: vcpu-0| VLANCE: Ethernet0 skipped 3 time(s) Nov 19 19:06:48.919: vcpu-0| VLANCE: 2 0 0 0 0 1 0 0 0 0 0 0 0 0 0 0 Nov 19 19:06:48.919: vcpu-0| VLANCE: 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 Nov 19 19:06:48.919: vcpu-0| VLANCE: Ethernet0 skipped 4 time(s) Nov 19 19:06:48.919: vcpu-0| VLANCE: 3 0 0 0 0 1 0 0 0 0 0 0 0 0 0 0 Nov 19 19:06:48.919: vcpu-0| VLANCE: 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 Nov 19 19:06:48.919: vcpu-0| VLANCE: Ethernet0 skipped 5 time(s) Nov 19 19:06:48.919: vcpu-0| VLANCE: 4 0 0 0 0 1 0 0 0 0 0 0 0 0 0 0 Nov 19 19:06:48.919: vcpu-0| VLANCE: 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 Nov 19 19:06:48.919: vcpu-0| VLANCE: Ethernet0 skipped 6 time(s) Nov 19 19:06:48.919: vcpu-0| VLANCE: 5 0 0 0 0 1 0 0 0 0 0 0 0 0 0 0 Nov 19 19:06:48.919: vcpu-0| VLANCE: 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 Nov 19 19:06:49.091: vcpu-0| VLANCE: Ethernet0 skipped 7 time(s) Nov 19 19:06:49.091: vcpu-0| VLANCE: 6 0 0 0 0 1 0 0 0 0 0 0 0 0 0 0 Nov 19 19:06:49.091: vcpu-0| VLANCE: 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 Nov 19 19:06:49.091: vcpu-0| VLANCE: Ethernet0 skipped 8 time(s) Nov 19 19:06:49.091: vcpu-0| VLANCE: 6 1 0 0 0 1 0 0 0 0 0 0 0 0 0 0 Nov 19 19:06:49.091: vcpu-0| VLANCE: 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 Nov 19 19:06:49.091: vcpu-0| VLANCE: Ethernet0 skipped 9 time(s) Nov 19 19:06:49.091: vcpu-0| VLANCE: 6 1 0 1 0 1 0 0 0 0 0 0 0 0 0 0 Nov 19 19:06:49.091: vcpu-0| VLANCE: 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 Nov 19 19:18:29.373: vmx| SCSI0:0: Command WRITE(10) took 1.000 seconds (ok) Nov 19 19:19:18.935: vmx| SCSI0:0: Command WRITE(10) took 1.001 seconds (ok) Nov 19 19:19:18.935: vmx| SCSI0:0: Command WRITE(10) took 1.000 seconds (ok) Nov 19 19:19:18.935: vmx| SCSI0:0: Command WRITE(10) took 1.001 seconds (ok) Nov 19 19:19:18.935: vmx| SCSI0:0: Command WRITE(10) took 1.001 seconds (ok) Nov 19 19:19:18.935: vmx| SCSI0:0: Command WRITE(10) took 1.001 seconds (ok) Nov 19 19:19:18.935: vmx| SCSI0:0: Command WRITE(10) took 1.001 seconds (ok) Nov 19 19:19:18.935: vmx| SCSI0:0: Command WRITE(10) took 1.001 seconds (ok) Nov 19 19:19:18.935: vmx| SCSI0:0: Command WRITE(10) took 1.001 seconds (ok) Nov 19 19:19:18.935: vmx| SCSI0:0: Command WRITE(10) took 1.001 seconds (ok) Nov 19 19:19:18.935: vmx| SCSI0:0: Command WRITE(10) took 1.002 seconds (ok) Nov 19 19:21:21.607: vmx| SCSI0:0: Command WRITE(10) took 4.981 seconds (ok) Nov 19 19:21:21.607: vmx| SCSI0:0: Command WRITE(10) took 3.713 seconds (ok) Nov 19 19:22:32.013: vmx| SCSI0:0: Command WRITE(10) took 1.001 seconds (ok) Nov 19 19:22:50.232: vmx| SCSI0:0: Command WRITE(10) took 11.500 seconds (ok) Nov 19 19:39:50.748: vmx| SCSI0:0: Command READ(10) took 1.130 seconds (ok) Nov 19 19:39:50.748: vmx| SCSI0:0: Command READ(10) took 2.718 seconds (ok) Nov 19 20:33:43.434: vmx| SCSI0:0: Command WRITE(10) took 3.621 seconds (ok) Nov 19 20:33:43.434: vmx| SCSI0:0: Command WRITE(10) took 8.623 seconds (ok) Nov 19 20:33:43.434: vmx| SCSI0:0: Command WRITE(10) took 8.623 seconds (ok) Nov 19 20:33:43.434: vmx| SCSI0:0: Command WRITE(10) took 8.623 seconds (ok) Nov 19 20:33:43.434: vmx| SCSI0:0: Command WRITE(10) took 8.623 seconds (ok) Nov 19 20:33:43.434: vmx| SCSI0:0: Command WRITE(10) took 8.623 seconds (ok) Nov 19 20:33:43.434: vmx| SCSI0:0: Command WRITE(10) took 8.623 seconds (ok) Nov 19 20:33:43.434: vmx| SCSI0:0: Command WRITE(10) took 8.624 seconds (ok) Nov 19 20:33:43.434: vmx| SCSI0:0: Command WRITE(10) took 8.624 seconds (ok) Nov 19 20:33:43.434: vmx| SCSI0:0: Command WRITE(10) took 8.621 seconds (ok) Nov 20 08:41:34.234: vcpu-0| VLANCE: Ethernet0 skipped 10 time(s) Nov 20 08:41:34.234: vcpu-0| VLANCE: 7 1 0 1 0 1 0 0 0 0 0 0 0 0 0 0 Nov 20 08:41:34.234: vcpu-0| VLANCE: 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 Nov 20 08:41:34.234: vcpu-0| VLANCE: Ethernet0 skipped 11 time(s) Nov 20 08:41:34.234: vcpu-0| VLANCE: 7 2 0 1 0 1 0 0 0 0 0 0 0 0 0 0 Nov 20 08:41:34.234: vcpu-0| VLANCE: 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 Nov 20 08:41:34.921: vmx| VMXVmdbLoadUsbDevices: New set of 0 USB devices Nov 20 08:41:41.578: vmx| VMXVmdbLoadUsbDevices: New set of 1 USB devices Nov 20 08:41:41.578: vmx| USB: Found device [name:Sierra\ Wireless\ USB\ Device vid:1199 pid:0220 path:1/4/0 speed:full family:vendor] Nov 20 08:42:40.406: vmx| SCSI0:0: Command WRITE(10) took 1.190 seconds (ok) Nov 20 08:42:42.609: vmx| VMXVmdbLoadUsbDevices: New set of 2 USB devices Nov 20 08:42:42.609: vmx| USB: Found device [name:Research\ In\ Motion\ USB\ Device vid:0fca pid:0001 path:1/3/1 speed:full family:vendor] Nov 20 08:42:42.609: vmx| USB: Found device [name:Sierra\ Wireless\ USB\ Device vid:1199 pid:0220 path:1/4/0 speed:full family:vendor] Nov 20 08:43:03.531: vmx| VMXVmdbLoadUsbDevices: New set of 1 USB devices Nov 20 08:43:03.531: vmx| USB: Found device [name:Sierra\ Wireless\ USB\ Device vid:1199 pid:0220 path:1/4/0 speed:full family:vendor] Nov 20 08:45:43.328: vmx| VMXVmdbLoadUsbDevices: New set of 2 USB devices Nov 20 08:45:43.328: vmx| USB: Found device [name:Research\ In\ Motion\ USB\ Device vid:0fca pid:0001 path:1/3/1 speed:full family:vendor] Nov 20 08:45:43.328: vmx| USB: Found device [name:Sierra\ Wireless\ USB\ Device vid:1199 pid:0220 path:1/4/0 speed:full family:vendor] Nov 20 08:45:46.265: vmx| VMXVmdbLoadUsbDevices: New set of 1 USB devices Nov 20 08:45:46.265: vmx| USB: Found device [name:Sierra\ Wireless\ USB\ Device vid:1199 pid:0220 path:1/4/0 speed:full family:vendor] Nov 20 08:45:55.421: vmx| VMXVmdbLoadUsbDevices: New set of 2 USB devices Nov 20 08:45:55.421: vmx| USB: Found device [name:Research\ In\ Motion\ Removable\ Disk vid:0fca pid:0004 path:1/3/1 speed:full family:vendor,storage] Nov 20 08:45:55.421: vmx| USB: Found device [name:Sierra\ Wireless\ USB\ Device vid:1199 pid:0220 path:1/4/0 speed:full family:vendor] Nov 20 08:48:50.796: vmx| VMXVmdbLoadUsbDevices: New set of 1 USB devices Nov 20 08:48:50.796: vmx| USB: Found device [name:Sierra\ Wireless\ USB\ Device vid:1199 pid:0220 path:1/4/0 speed:full family:vendor] Nov 20 08:48:54.000: vmx| VMXVmdbLoadUsbDevices: New set of 2 USB devices Nov 20 08:48:54.000: vmx| USB: Found device [name:Research\ In\ Motion\ Removable\ Disk vid:0fca pid:0004 path:1/3/1 speed:full family:vendor,storage] Nov 20 08:48:54.000: vmx| USB: Found device [name:Sierra\ Wireless\ USB\ Device vid:1199 pid:0220 path:1/4/0 speed:full family:vendor] Nov 20 08:49:28.468: vmx| SCSI0:0: Command WRITE(10) took 1.500 seconds (ok) Nov 20 08:52:59.500: vmx| DISKLIB-LIB :numIOs = 100000 numMergedIOs = 0 numSplitIOs = 0 Nov 20 08:58:28.250: vmx| SCSI0:0: Command WRITE(10) took 1.500 seconds (ok) Nov 20 09:08:51.078: vmx| SCSI0:0: Command WRITE(10) took 3.001 seconds (ok) Nov 20 09:24:22.109: vmx| SCSI0:0: Command WRITE(10) took 1.500 seconds (ok) Nov 20 09:24:22.109: vmx| SCSI0:0: Command WRITE(10) took 1.500 seconds (ok) Nov 20 09:24:22.109: vmx| SCSI0:0: Command WRITE(10) took 1.500 seconds (ok) Nov 20 10:01:11.671: vmx| VMXVmdbLoadUsbDevices: New set of 1 USB devices Nov 20 10:01:11.671: vmx| USB: Found device [name:Sierra\ Wireless\ USB\ Device vid:1199 pid:0220 path:1/4/0 speed:full family:vendor] Nov 20 11:41:34.234: vmx| LICENSE using: 'HKEY_LOCAL_MACHINE\SOFTWARE\VMware, Inc.\VMware Workstation\License.ws.6.0.200907' Nov 20 15:21:31.406: vmx| SCSI0:0: Command WRITE(10) took 1.500 seconds (ok) Nov 20 15:58:20.421: vmx| SCSI0:0: Command WRITE(10) took 2.999 seconds (ok) Nov 20 15:58:28.625: vmx| SCSI0:0: Command WRITE(10) took 6.001 seconds (ok) Nov 20 15:58:28.625: vmx| SCSI0:0: Command WRITE(10) took 6.001 seconds (ok) Nov 20 15:58:28.625: vmx| SCSI0:0: Command WRITE(10) took 6.001 seconds (ok) Nov 20 15:58:28.625: vmx| SCSI0:0: Command WRITE(10) took 6.001 seconds (ok) Nov 20 15:58:28.625: vmx| SCSI0:0: Command WRITE(10) took 6.001 seconds (ok) Nov 20 15:58:28.625: vmx| SCSI0:0: Command WRITE(10) took 6.001 seconds (ok) Nov 20 15:58:28.625: vmx| SCSI0:0: Command WRITE(10) took 6.001 seconds (ok) Nov 20 15:58:28.625: vmx| SCSI0:0: Command WRITE(10) took 6.001 seconds (ok) Nov 20 15:58:28.625: vmx| SCSI0:0: Command WRITE(10) took 6.002 seconds (ok) Nov 20 15:58:28.625: vmx| SCSI0:0: Command WRITE(10) took 6.002 seconds (ok) Nov 20 15:58:28.625: vmx| SCSI0:0: Command WRITE(10) took 6.002 seconds (ok) Nov 20 15:58:28.625: vmx| SCSI0:0: Command WRITE(10) took 6.002 seconds (ok) Nov 20 15:58:28.625: vmx| SCSI0:0: Command WRITE(10) took 6.002 seconds (ok) Nov 20 15:58:28.625: vmx| SCSI0:0: Command WRITE(10) took 6.002 seconds (ok) Nov 20 15:58:28.625: vmx| SCSI0:0: Command WRITE(10) took 6.002 seconds (ok) Nov 20 15:58:28.625: vmx| SCSI0:0: Command WRITE(10) took 6.003 seconds (ok) Nov 20 15:59:45.765: vmx| SCSI0:0: Command WRITE(10) took 1.000 seconds (ok) Nov 20 16:10:25.187: vmx| SCSI0:0: Command WRITE(10) took 2.000 seconds (ok) Nov 20 16:10:25.187: vmx| SCSI0:0: Command WRITE(10) took 2.000 seconds (ok) Nov 20 16:10:25.187: vmx| SCSI0:0: Command WRITE(10) took 2.000 seconds (ok) Nov 20 16:12:03.515: vmx| SCSI0:0: Command READ(10) took 1.109 seconds (ok) Nov 20 16:12:03.515: vmx| SCSI0:0: Command READ(10) took 1.109 seconds (ok) Nov 20 16:12:03.515: vmx| SCSI0:0: Command READ(10) took 1.110 seconds (ok) Nov 20 16:12:03.515: vmx| SCSI0:0: Command READ(10) took 1.110 seconds (ok) Nov 20 16:12:03.515: vmx| SCSI0:0: Command READ(10) took 1.112 seconds (ok) Nov 20 16:36:15.890: vmx| SCSI0:0: Command WRITE(10) took 1.000 seconds (ok) Nov 20 16:36:15.890: vmx| SCSI0:0: Command WRITE(10) took 1.000 seconds (ok) Nov 20 16:36:19.718: vmx| SCSI0:0: Command READ(10) took 1.035 seconds (ok) Nov 20 16:36:46.484: vmx| SCSI0:0: Command WRITE(10) took 4.500 seconds (ok) Nov 20 16:36:46.484: vmx| SCSI0:0: Command WRITE(10) took 4.500 seconds (ok) Nov 20 16:36:46.484: vmx| SCSI0:0: Command WRITE(10) took 4.500 seconds (ok) Nov 20 16:36:46.484: vmx| SCSI0:0: Command WRITE(10) took 4.500 seconds (ok) Nov 20 16:36:46.484: vmx| SCSI0:0: Command WRITE(10) took 4.500 seconds (ok) Nov 20 16:36:46.484: vmx| SCSI0:0: Command WRITE(10) took 4.501 seconds (ok) Nov 20 16:36:46.484: vmx| SCSI0:0: Command WRITE(10) took 4.501 seconds (ok) Nov 20 16:36:46.484: vmx| SCSI0:0: Command WRITE(10) took 4.501 seconds (ok) Nov 20 16:36:46.484: vmx| SCSI0:0: Command WRITE(10) took 4.501 seconds (ok) Nov 20 16:36:46.484: vmx| SCSI0:0: Command WRITE(10) took 4.501 seconds (ok) Nov 20 16:36:46.484: vmx| SCSI0:0: Command WRITE(10) took 4.501 seconds (ok) Nov 20 16:36:46.484: vmx| SCSI0:0: Command WRITE(10) took 4.501 seconds (ok) Nov 20 17:09:29.828: vmx| SCSI0:0: Command WRITE(10) took 3.228 seconds (ok) Nov 20 17:09:29.828: vmx| SCSI0:0: Command WRITE(10) took 3.229 seconds (ok) Nov 20 17:09:29.828: vmx| SCSI0:0: Command WRITE(10) took 3.229 seconds (ok) Nov 20 17:09:29.828: vmx| SCSI0:0: Command WRITE(10) took 3.229 seconds (ok) Nov 20 17:09:29.828: vmx| SCSI0:0: Command WRITE(10) took 3.229 seconds (ok) Nov 20 17:09:29.828: vmx| SCSI0:0: Command WRITE(10) took 3.229 seconds (ok) Nov 20 17:09:29.828: vmx| SCSI0:0: Command WRITE(10) took 1.501 seconds (ok) Nov 20 17:09:29.828: vmx| SCSI0:0: Command WRITE(10) took 1.501 seconds (ok) Nov 20 17:09:29.828: vmx| SCSI0:0: Command WRITE(10) took 1.501 seconds (ok) Nov 20 17:09:29.828: vmx| SCSI0:0: Command WRITE(10) took 3.230 seconds (ok) Nov 20 17:09:29.828: vmx| SCSI0:0: Command WRITE(10) took 3.230 seconds (ok) Nov 20 17:09:29.828: vmx| SCSI0:0: Command WRITE(10) took 3.226 seconds (ok) Nov 20 19:22:34.375: vmx| VMXVmdbLoadUsbDevices: New set of 2 USB devices Nov 20 19:22:34.375: vmx| USB: Found device [name:Research\ In\ Motion\ Removable\ Disk vid:0fca pid:0004 path:1/3/1 speed:full family:vendor,storage] Nov 20 19:22:34.375: vmx| USB: Found device [name:Sierra\ Wireless\ USB\ Device vid:1199 pid:0220 path:1/4/0 speed:full family:vendor] Nov 20 19:22:39.109: usbEnum| USBGW: Autoconnecting e30000010fca0004 Nov 20 19:22:39.109: usbEnum| USB: Automatically connecting virtual hub Nov 20 19:22:39.109: usbEnum| USB: Connecting device 0xc00000010e0f0002 Nov 20 19:22:39.125: vmx| VMXVmdbLoadUsbDevices: New set of 2 USB devices Nov 20 19:22:39.125: vmx| USB: Found device [name:Research\ In\ Motion\ Removable\ Disk vid:0fca pid:0004 path:1/3/1 speed:full family:vendor,storage] Nov 20 19:22:39.125: vmx| USB: Found device [name:Sierra\ Wireless\ USB\ Device vid:1199 pid:0220 path:1/4/0 speed:full family:vendor] Nov 20 19:24:38.265: vmx| [Vmdb_Set] Stale data for path: /vm/#_VMX/mks/focusEvent/ (current value '0' -> new value '1') [312->312] Nov 20 19:39:29.218: vmx| SCSI0:0: Command WRITE(10) took 1.482 seconds (ok) Nov 20 19:39:29.218: vmx| SCSI0:0: Command WRITE(10) took 1.483 seconds (ok) Nov 20 19:39:29.218: vmx| SCSI0:0: Command WRITE(10) took 1.483 seconds (ok) Nov 20 19:39:29.218: vmx| SCSI0:0: Command WRITE(10) took 1.483 seconds (ok) Nov 20 19:39:29.218: vmx| SCSI0:0: Command WRITE(10) took 1.483 seconds (ok) Nov 20 19:39:29.218: vmx| SCSI0:0: Command WRITE(10) took 1.483 seconds (ok) Nov 20 20:07:20.531: vmx| SCSI0:0: Command WRITE(10) took 1.503 seconds (ok) Nov 20 20:07:20.531: vmx| SCSI0:0: Command WRITE(10) took 1.503 seconds (ok) Nov 20 20:07:20.531: vmx| SCSI0:0: Command WRITE(10) took 1.503 seconds (ok) Nov 20 20:07:20.531: vmx| SCSI0:0: Command WRITE(10) took 1.503 seconds (ok) Nov 20 20:07:20.531: vmx| SCSI0:0: Command WRITE(10) took 1.500 seconds (ok) Nov 20 20:07:20.531: vmx| SCSI0:0: Command WRITE(10) took 1.500 seconds (ok) Nov 20 20:12:53.218: vmx| [Vmdb_Set] Stale data for path: /vm/#_VMX/mks/focusEvent/ (current value '0' -> new value '1') [366->366] Nov 20 20:32:05.906: vmx| [Vmdb_Set] Stale data for path: /vm/#_VMX/mks/focusEvent/ (current value '0' -> new value '1') [376->376] Nov 20 20:41:30.015: vmx| SCSI0:0: Command WRITE(10) took 1.501 seconds (ok) Nov 20 20:41:30.015: vmx| SCSI0:0: Command WRITE(10) took 1.502 seconds (ok) Nov 20 20:41:30.015: vmx| SCSI0:0: Command WRITE(10) took 1.502 seconds (ok) Nov 20 20:41:30.015: vmx| SCSI0:0: Command WRITE(10) took 1.502 seconds (ok) Nov 20 20:41:30.015: vmx| SCSI0:0: Command WRITE(10) took 1.503 seconds (ok) Nov 20 20:41:30.015: vmx| SCSI0:0: Command WRITE(10) took 1.503 seconds (ok) Nov 20 20:41:30.031: vmx| SCSI0:0: Command WRITE(10) took 1.506 seconds (ok) Nov 20 20:41:30.031: vmx| SCSI0:0: Command WRITE(10) took 1.505 seconds (ok) Nov 20 20:41:30.031: vmx| SCSI0:0: Command WRITE(10) took 1.507 seconds (ok) Nov 20 20:41:30.031: vmx| SCSI0:0: Command WRITE(10) took 1.510 seconds (ok) Nov 20 20:41:30.031: vmx| SCSI0:0: Command WRITE(10) took 1.512 seconds (ok) Nov 20 20:41:30.031: vmx| SCSI0:0: Command WRITE(10) took 1.512 seconds (ok) Nov 20 20:41:30.031: vmx| SCSI0:0: Command WRITE(10) took 1.514 seconds (ok) Nov 20 20:41:30.031: vmx| SCSI0:0: Command WRITE(10) took 1.514 seconds (ok) Nov 20 20:41:30.140: vmx| SCSI0:0: Command WRITE(10) took 1.618 seconds (ok) Nov 20 20:41:30.156: vmx| SCSI0:0: Command WRITE(10) took 1.635 seconds (ok) Nov 20 20:41:37.687: vmx| [Vmdb_Set] Stale data for path: /vm/#_VMX/mks/focusEvent/ (current value '0' -> new value '1') [382->382] Nov 20 20:44:30.375: vmx| SCSI0:0: Command WRITE(10) took 2.000 seconds (ok) Nov 20 20:44:30.375: vmx| SCSI0:0: Command WRITE(10) took 2.001 seconds (ok) Nov 20 20:44:30.375: vmx| SCSI0:0: Command WRITE(10) took 2.001 seconds (ok) Nov 20 20:44:30.375: vmx| SCSI0:0: Command WRITE(10) took 2.001 seconds (ok) Nov 20 20:44:30.375: vmx| SCSI0:0: Command WRITE(10) took 2.001 seconds (ok) Nov 20 20:44:30.375: vmx| SCSI0:0: Command WRITE(10) took 2.001 seconds (ok) Nov 20 20:44:30.375: vmx| SCSI0:0: Command WRITE(10) took 2.001 seconds (ok) Nov 20 20:44:30.375: vmx| SCSI0:0: Command WRITE(10) took 2.001 seconds (ok) Nov 20 20:44:30.375: vmx| SCSI0:0: Command WRITE(10) took 2.001 seconds (ok) Nov 20 20:44:30.375: vmx| SCSI0:0: Command WRITE(10) took 2.001 seconds (ok) Nov 20 20:44:30.375: vmx| SCSI0:0: Command WRITE(10) took 2.001 seconds (ok) Nov 20 20:44:30.375: vmx| SCSI0:0: Command WRITE(10) took 2.002 seconds (ok) Nov 20 20:44:30.375: vmx| SCSI0:0: Command WRITE(10) took 2.002 seconds (ok) Nov 20 20:44:30.375: vmx| SCSI0:0: Command WRITE(10) took 2.002 seconds (ok) Nov 20 20:44:30.375: vmx| SCSI0:0: Command WRITE(10) took 2.002 seconds (ok) Nov 20 20:44:30.375: vmx| SCSI0:0: Command WRITE(10) took 2.002 seconds (ok) Nov 20 21:10:21.843: usbEnum| USB: Disconnecting device 0xe30000010fca0004 Nov 20 21:10:24.250: vmx| VMXVmdbLoadUsbDevices: New set of 1 USB devices Nov 20 21:10:24.250: vmx| USB: Found device [name:Sierra\ Wireless\ USB\ Device vid:1199 pid:0220 path:1/4/0 speed:full family:vendor] Nov 20 22:22:42.593: vmx| VLANCE: Ethernet0 skipped 12 time(s) Nov 20 22:22:42.593: vmx| VLANCE: 8 2 0 1 0 1 0 0 0 0 0 0 0 0 0 0 Nov 20 22:22:42.593: vmx| VLANCE: 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 Nov 20 22:22:42.593: vcpu-0| VLANCE: Ethernet0 skipped 13 time(s) Nov 20 22:22:42.593: vcpu-0| VLANCE: 8 3 0 1 0 1 0 0 0 0 0 0 0 0 0 0 Nov 20 22:22:42.593: vcpu-0| VLANCE: 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 Nov 20 22:54:51.812: vcpu-0| VLANCE: Ethernet0 skipped 14 time(s) Nov 20 22:54:51.812: vcpu-0| VLANCE: 9 3 0 1 0 1 0 0 0 0 0 0 0 0 0 0 Nov 20 22:54:51.812: vcpu-0| VLANCE: 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 Nov 20 22:54:51.812: vcpu-0| VLANCE: Ethernet0 skipped 15 time(s) Nov 20 22:54:51.812: vcpu-0| VLANCE: 9 4 0 1 0 1 0 0 0 0 0 0 0 0 0 0 Nov 20 22:54:51.812: vcpu-0| VLANCE: 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 Nov 20 22:56:11.875: vmx| SCSI0:0: Command WRITE(10) took 4.362 seconds (ok) Nov 20 22:56:11.875: vmx| SCSI0:0: Command WRITE(10) took 4.362 seconds (ok) Nov 20 22:56:11.890: vmx| SCSI0:0: Command WRITE(10) took 4.365 seconds (ok) Nov 20 22:56:11.890: vmx| SCSI0:0: Command WRITE(10) took 4.365 seconds (ok) Nov 20 22:56:11.890: vmx| SCSI0:0: Command WRITE(10) took 4.365 seconds (ok) Nov 20 22:56:11.890: vmx| SCSI0:0: Command WRITE(10) took 4.365 seconds (ok) Nov 20 22:56:11.890: vmx| SCSI0:0: Command WRITE(10) took 4.365 seconds (ok) Nov 20 22:56:11.890: vmx| SCSI0:0: Command WRITE(10) took 4.365 seconds (ok) Nov 20 22:56:11.890: vmx| SCSI0:0: Command WRITE(10) took 4.365 seconds (ok) Nov 20 22:56:11.890: vmx| SCSI0:0: Command WRITE(10) took 4.366 seconds (ok) Nov 20 22:56:11.890: vmx| SCSI0:0: Command WRITE(10) took 4.367 seconds (ok) Nov 20 22:56:11.890: vmx| SCSI0:0: Command WRITE(10) took 4.367 seconds (ok) Nov 20 22:56:11.890: vmx| SCSI0:0: Command WRITE(10) took 4.367 seconds (ok) Nov 20 22:56:11.890: vmx| SCSI0:0: Command WRITE(10) took 4.367 seconds (ok) Nov 20 22:56:11.890: vmx| SCSI0:0: Command WRITE(10) took 4.367 seconds (ok) Nov 20 22:56:11.890: vmx| SCSI0:0: Command WRITE(10) took 4.367 seconds (ok) Nov 20 22:56:30.281: vmx| SCSI0:0: Command WRITE(10) took 1.028 seconds (ok) Nov 20 22:56:30.281: vmx| SCSI0:0: Command WRITE(10) took 1.028 seconds (ok) Nov 20 22:56:30.281: vmx| SCSI0:0: Command WRITE(10) took 1.028 seconds (ok) Nov 20 22:56:30.281: vmx| SCSI0:0: Command WRITE(10) took 1.028 seconds (ok) Nov 20 22:56:30.281: vmx| SCSI0:0: Command WRITE(10) took 1.028 seconds (ok) Nov 20 22:56:30.281: vmx| SCSI0:0: Command WRITE(10) took 1.028 seconds (ok) Nov 20 22:56:30.281: vmx| SCSI0:0: Command WRITE(10) took 1.028 seconds (ok) Nov 20 22:56:30.281: vmx| SCSI0:0: Command WRITE(10) took 1.028 seconds (ok) Nov 20 22:56:49.218: vmx| SCSI0:0: Command WRITE(10) took 10.000 seconds (ok) Nov 20 23:19:51.687: vmx| SCSI0:0: Command WRITE(10) took 1.022 seconds (ok) Nov 20 23:19:51.687: vmx| SCSI0:0: Command WRITE(10) took 1.023 seconds (ok) Nov 20 23:19:51.687: vmx| SCSI0:0: Command WRITE(10) took 1.023 seconds (ok) Nov 20 23:19:51.687: vmx| SCSI0:0: Command WRITE(10) took 1.005 seconds (ok) Nov 20 23:19:51.687: vmx| SCSI0:0: Command WRITE(10) took 1.005 seconds (ok) Nov 20 23:19:51.687: vmx| SCSI0:0: Command WRITE(10) took 1.005 seconds (ok) Nov 20 23:19:51.687: vmx| SCSI0:0: Command WRITE(10) took 1.005 seconds (ok) Nov 20 23:19:51.687: vmx| SCSI0:0: Command WRITE(10) took 1.005 seconds (ok) Nov 20 23:19:51.687: vmx| SCSI0:0: Command WRITE(10) took 1.005 seconds (ok) Nov 20 23:19:51.687: vmx| SCSI0:0: Command WRITE(10) took 1.025 seconds (ok) Nov 20 23:19:51.687: vmx| SCSI0:0: Command WRITE(10) took 1.024 seconds (ok) Nov 20 23:19:51.687: vmx| SCSI0:0: Command WRITE(10) took 1.024 seconds (ok) Nov 20 23:19:51.687: vmx| SCSI0:0: Command WRITE(10) took 1.017 seconds (ok) Nov 20 23:19:51.687: vmx| SCSI0:0: Command WRITE(10) took 1.017 seconds (ok) Nov 20 23:19:51.687: vmx| SCSI0:0: Command WRITE(10) took 1.008 seconds (ok) Nov 20 23:19:51.687: vmx| SCSI0:0: Command WRITE(10) took 1.008 seconds (ok) Nov 20 23:20:38.406: vmx| VLANCE: Ethernet0 skipped 16 time(s) Nov 20 23:20:38.406: vmx| VLANCE: 10 4 0 1 0 1 0 0 0 0 0 0 0 0 0 0 Nov 20 23:20:38.406: vmx| VLANCE: 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 Nov 20 23:22:08.515: vmx| SCSI0:0: Command WRITE(10) took 3.500 seconds (ok) Nov 20 23:22:08.515: vmx| SCSI0:0: Command WRITE(10) took 3.503 seconds (ok) Nov 20 23:22:08.531: vmx| SCSI0:0: Command WRITE(10) took 3.505 seconds (ok) Nov 20 23:22:39.000: vmx| SCSI0:0: Command READ(10) took 2.119 seconds (ok) Nov 21 00:03:35.359: vmx| SCSI0:0: Command WRITE(10) took 1.215 seconds (ok) Nov 21 00:03:35.359: vmx| SCSI0:0: Command WRITE(10) took 1.215 seconds (ok) Nov 21 00:03:35.359: vmx| SCSI0:0: Command WRITE(10) took 1.215 seconds (ok) Nov 21 00:03:35.359: vmx| SCSI0:0: Command WRITE(10) took 1.215 seconds (ok) Nov 21 00:03:35.359: vmx| SCSI0:0: Command WRITE(10) took 1.215 seconds (ok) Nov 21 00:03:35.359: vmx| SCSI0:0: Command WRITE(10) took 1.215 seconds (ok) Nov 21 00:03:35.359: vmx| SCSI0:0: Command WRITE(10) took 1.215 seconds (ok) Nov 21 00:03:35.359: vmx| SCSI0:0: Command WRITE(10) took 1.215 seconds (ok) Nov 21 00:03:35.359: vmx| SCSI0:0: Command WRITE(10) took 1.216 seconds (ok) Nov 21 00:03:35.359: vmx| SCSI0:0: Command WRITE(10) took 1.216 seconds (ok) Nov 21 00:03:35.937: vmx| SCSI0:0: Command WRITE(10) took 1.798 seconds (ok) Nov 21 00:03:35.937: vmx| SCSI0:0: Command WRITE(10) took 1.799 seconds (ok) Nov 21 00:03:35.937: vmx| SCSI0:0: Command WRITE(10) took 1.799 seconds (ok) Nov 21 00:03:35.937: vmx| SCSI0:0: Command WRITE(10) took 1.800 seconds (ok) Nov 21 00:03:35.937: vmx| SCSI0:0: Command WRITE(10) took 1.800 seconds (ok) Nov 21 00:33:21.609: vmx| SCSI0:0: Command WRITE(10) took 5.001 seconds (ok) Nov 21 00:33:21.609: vmx| SCSI0:0: Command WRITE(10) took 5.001 seconds (ok) Nov 21 00:33:37.562: vmx| SCSI0:0: Command WRITE(10) took 1.000 seconds (ok) Nov 21 00:33:37.562: vmx| SCSI0:0: Command WRITE(10) took 1.000 seconds (ok) Nov 21 00:33:37.562: vmx| SCSI0:0: Command WRITE(10) took 1.000 seconds (ok) Nov 21 00:33:37.562: vmx| SCSI0:0: Command WRITE(10) took 1.000 seconds (ok) Nov 21 00:33:37.562: vmx| SCSI0:0: Command WRITE(10) took 1.001 seconds (ok) Nov 21 00:37:44.484: vmx| SCSI0:0: Command WRITE(10) took 1.004 seconds (ok) Nov 21 00:37:44.484: vmx| SCSI0:0: Command WRITE(10) took 1.005 seconds (ok) Nov 21 00:37:44.484: vmx| SCSI0:0: Command WRITE(10) took 1.000 seconds (ok) Nov 21 00:37:44.484: vmx| SCSI0:0: Command WRITE(10) took 1.005 seconds (ok) Nov 21 00:37:44.484: vmx| SCSI0:0: Command WRITE(10) took 1.001 seconds (ok) Nov 21 00:37:44.484: vmx| SCSI0:0: Command WRITE(10) took 1.001 seconds (ok) Nov 21 00:37:44.484: vmx| SCSI0:0: Command WRITE(10) took 1.001 seconds (ok) Nov 21 00:37:44.484: vmx| SCSI0:0: Command WRITE(10) took 1.001 seconds (ok) Nov 21 00:37:44.484: vmx| SCSI0:0: Command WRITE(10) took 1.006 seconds (ok) Nov 21 00:37:44.484: vmx| SCSI0:0: Command WRITE(10) took 1.006 seconds (ok) Nov 21 00:37:44.484: vmx| SCSI0:0: Command WRITE(10) took 1.006 seconds (ok) Nov 21 00:37:44.484: vmx| SCSI0:0: Command WRITE(10) took 1.002 seconds (ok) Nov 21 00:37:44.484: vmx| SCSI0:0: Command WRITE(10) took 1.002 seconds (ok) Nov 21 01:29:26.781: vmx| SCSI0:0: Command WRITE(10) took 13.000 seconds (ok) Nov 21 09:56:01.796: vmx| SCSI0:0: Command WRITE(10) took 2.000 seconds (ok) Nov 21 09:58:02.593: vmx| SCSI0:0: Command WRITE(10) took 2.812 seconds (ok) Nov 21 10:06:17.625: vmx| SCSI0:0: Command WRITE(10) took 1.500 seconds (ok) Nov 21 10:06:17.625: vmx| SCSI0:0: Command WRITE(10) took 1.500 seconds (ok) Nov 21 10:06:17.625: vmx| SCSI0:0: Command WRITE(10) took 1.500 seconds (ok) Nov 21 10:09:58.031: vmx| SCSI0:0: Command WRITE(10) took 2.251 seconds (ok) Nov 21 10:14:04.984: vmx| SCSI0:0: Command WRITE(10) took 1.283 seconds (ok) Nov 21 11:14:07.812: vmx| SCSI0:0: Command WRITE(10) took 2.451 seconds (ok) Nov 21 16:11:36.109: vcpu-0| DISKLIB-LIB :numIOs = 150000 numMergedIOs = 0 numSplitIOs = 0 Nov 21 17:47:11.703: vmx| SCSI0:0: Command WRITE(10) took 22.000 seconds (ok) Nov 21 17:47:11.703: vmx| SCSI0:0: Command WRITE(10) took 22.000 seconds (ok) Nov 21 17:47:11.703: vmx| SCSI0:0: Command WRITE(10) took 22.001 seconds (ok) Nov 21 17:47:20.843: vmx| SCSI0:0: Command WRITE(10) took 4.001 seconds (ok) Nov 21 17:59:05.640: vmx| SCSI0:0: Command WRITE(10) took 1.000 seconds (ok) Nov 21 17:59:05.640: vmx| SCSI0:0: Command WRITE(10) took 1.000 seconds (ok) Nov 21 17:59:05.640: vmx| SCSI0:0: Command WRITE(10) took 1.000 seconds (ok) Nov 21 17:59:05.640: vmx| SCSI0:0: Command WRITE(10) took 1.000 seconds (ok) Nov 21 17:59:05.640: vmx| SCSI0:0: Command WRITE(10) took 1.001 seconds (ok) Nov 21 17:59:05.640: vmx| SCSI0:0: Command WRITE(10) took 1.001 seconds (ok) Nov 21 17:59:05.656: vmx| SCSI0:0: Command WRITE(10) took 1.006 seconds (ok) Nov 21 17:59:05.656: vmx| SCSI0:0: Command WRITE(10) took 1.006 seconds (ok) Nov 21 17:59:05.656: vmx| SCSI0:0: Command WRITE(10) took 1.006 seconds (ok) Nov 21 17:59:05.656: vmx| SCSI0:0: Command WRITE(10) took 1.006 seconds (ok) Nov 21 17:59:05.656: vmx| SCSI0:0: Command WRITE(10) took 1.006 seconds (ok) Nov 21 18:15:52.078: vmx| SCSI0:0: Command WRITE(10) took 2.501 seconds (ok) Nov 21 18:15:52.078: vmx| SCSI0:0: Command WRITE(10) took 2.501 seconds (ok) Nov 21 18:15:52.078: vmx| SCSI0:0: Command WRITE(10) took 2.501 seconds (ok) Nov 21 18:29:50.515: vmx| SCSI0:0: Command WRITE(10) took 1.000 seconds (ok) Nov 21 18:29:50.515: vmx| SCSI0:0: Command WRITE(10) took 1.000 seconds (ok) Nov 21 18:29:50.515: vmx| SCSI0:0: Command WRITE(10) took 1.000 seconds (ok) Nov 21 18:29:50.515: vmx| SCSI0:0: Command WRITE(10) took 1.001 seconds (ok) Nov 21 18:29:50.515: vmx| SCSI0:0: Command WRITE(10) took 1.001 seconds (ok) Nov 21 18:29:50.515: vmx| SCSI0:0: Command WRITE(10) took 1.001 seconds (ok) Nov 21 18:29:50.515: vmx| SCSI0:0: Command WRITE(10) took 1.001 seconds (ok) Nov 21 19:37:02.031: vmx| SCSI0:0: Command WRITE(10) took 2.774 seconds (ok) Nov 21 19:38:55.750: vmx| SCSI0:0: Command WRITE(10) took 1.501 seconds (ok) Nov 21 19:38:55.750: vmx| SCSI0:0: Command WRITE(10) took 1.501 seconds (ok) Nov 21 19:48:56.937: vmx| SCSI0:0: Command WRITE(10) took 1.950 seconds (ok) Nov 21 19:48:56.937: vmx| SCSI0:0: Command WRITE(10) took 1.951 seconds (ok) Nov 21 19:55:26.265: vmx| LICENSE using: 'HKEY_LOCAL_MACHINE\SOFTWARE\VMware, Inc.\VMware Workstation\License.ws.6.0.200907' Nov 21 20:22:23.203: vmx| SCSI0:0: Command WRITE(10) took 1.000 seconds (ok) Nov 21 20:25:19.078: vmx| SCSI0:0: Command WRITE(10) took 1.999 seconds (ok) Nov 21 20:28:56.796: vmx| SCSI0:0: Command WRITE(10) took 2.000 seconds (ok) Nov 21 20:28:56.796: vmx| SCSI0:0: Command WRITE(10) took 2.001 seconds (ok) Nov 21 20:28:56.796: vmx| SCSI0:0: Command WRITE(10) took 2.001 seconds (ok) Nov 21 20:35:19.359: vmx| SCSI0:0: Command WRITE(10) took 2.000 seconds (ok) Nov 21 20:55:08.187: vmx| SCSI0:0: Command WRITE(10) took 3.500 seconds (ok) Nov 21 20:55:08.187: vmx| SCSI0:0: Command WRITE(10) took 3.500 seconds (ok) Nov 21 20:56:01.937: vmx| SCSI0:0: Command WRITE(10) took 2.230 seconds (ok) Nov 21 21:00:26.531: vmx| SCSI0:0: Command WRITE(10) took 1.500 seconds (ok) Nov 22 07:32:06.515: vmx| VMXVmdbLoadUsbDevices: New set of 0 USB devices Nov 22 07:32:12.343: vmx| VMXVmdbLoadUsbDevices: New set of 1 USB devices Nov 22 07:32:12.343: vmx| USB: Found device [name:Sierra\ Wireless\ USB\ Device vid:1199 pid:0220 path:1/4/0 speed:full family:vendor] Nov 22 07:59:08.984: vmx| VMXVmdbLoadUsbDevices: New set of 2 USB devices Nov 22 07:59:08.984: vmx| USB: Found device [name:Research\ In\ Motion\ USB\ Device vid:0fca pid:0006 path:1/3/0 speed:full family:unknown] Nov 22 07:59:08.984: vmx| USB: Found device [name:Sierra\ Wireless\ USB\ Device vid:1199 pid:0220 path:1/4/0 speed:full family:vendor] Nov 22 07:59:11.796: vmx| VMXVmdbLoadUsbDevices: New set of 1 USB devices Nov 22 07:59:11.796: vmx| USB: Found device [name:Sierra\ Wireless\ USB\ Device vid:1199 pid:0220 path:1/4/0 speed:full family:vendor] Nov 22 07:59:17.484: vmx| VMXVmdbLoadUsbDevices: New set of 2 USB devices Nov 22 07:59:17.484: vmx| USB: Found device [name:Research\ In\ Motion\ Removable\ Disk vid:0fca pid:0004 path:1/3/0 speed:full family:vendor,storage] Nov 22 07:59:17.484: vmx| USB: Found device [name:Sierra\ Wireless\ USB\ Device vid:1199 pid:0220 path:1/4/0 speed:full family:vendor] Nov 22 08:12:33.000: vmx| SCSI0:0: Command WRITE(10) took 2.500 seconds (ok) Nov 22 08:12:33.109: vmx| VMXVmdbLoadUsbDevices: New set of 1 USB devices Nov 22 08:12:33.109: vmx| USB: Found device [name:Sierra\ Wireless\ USB\ Device vid:1199 pid:0220 path:1/4/0 speed:full family:vendor] Nov 22 11:08:20.609: vmx| VMXVmdbLoadUsbDevices: New set of 2 USB devices Nov 22 11:08:20.609: vmx| USB: Found device [name:Research\ In\ Motion\ USB\ Device vid:0fca pid:0006 path:1/3/0 speed:full family:unknown] Nov 22 11:08:20.609: vmx| USB: Found device [name:Sierra\ Wireless\ USB\ Device vid:1199 pid:0220 path:1/4/0 speed:full family:vendor] Nov 22 11:08:23.375: vmx| VMXVmdbLoadUsbDevices: New set of 2 USB devices Nov 22 11:08:23.375: vmx| USB: Found device [name:Research\ In\ Motion\ Removable\ Disk vid:0fca pid:0004 path:1/3/0 speed:full family:vendor,storage] Nov 22 11:08:23.375: vmx| USB: Found device [name:Sierra\ Wireless\ USB\ Device vid:1199 pid:0220 path:1/4/0 speed:full family:vendor] Nov 22 12:36:55.484: vmx| SCSI0:0: Command WRITE(10) took 2.500 seconds (ok) Nov 22 12:41:07.656: vmx| SCSI0:0: Command WRITE(10) took 1.001 seconds (ok) Nov 22 13:08:41.546: vmx| SCSI0:0: Command WRITE(10) took 3.000 seconds (ok) Nov 22 13:17:09.812: vmx| SCSI0:0: Command WRITE(10) took 2.000 seconds (ok) Nov 22 13:17:09.812: vmx| SCSI0:0: Command WRITE(10) took 2.000 seconds (ok) Nov 22 13:44:45.140: vmx| SCSI0:0: Command WRITE(10) took 2.500 seconds (ok) Nov 22 13:44:45.140: vmx| SCSI0:0: Command WRITE(10) took 2.500 seconds (ok) Nov 22 13:44:45.140: vmx| SCSI0:0: Command WRITE(10) took 2.500 seconds (ok) Nov 22 13:44:45.140: vmx| SCSI0:0: Command WRITE(10) took 2.500 seconds (ok) Nov 22 13:44:45.140: vmx| SCSI0:0: Command WRITE(10) took 2.500 seconds (ok) Nov 22 13:44:45.140: vmx| SCSI0:0: Command WRITE(10) took 2.500 seconds (ok) Nov 22 13:44:45.140: vmx| SCSI0:0: Command WRITE(10) took 2.500 seconds (ok) Nov 22 13:54:08.984: vmx| [Vmdb_Set] Stale data for path: /vm/#_VMX/mks/focusEvent/ (current value '0' -> new value '1') [576->576] Nov 22 13:58:55.500: vmx| SCSI0:0: Command WRITE(10) took 3.000 seconds (ok) Nov 22 14:14:15.453: vmx| SCSI0:0: Command WRITE(10) took 3.000 seconds (ok) Nov 22 14:14:15.453: vmx| SCSI0:0: Command WRITE(10) took 3.000 seconds (ok) Nov 22 14:14:15.453: vmx| SCSI0:0: Command WRITE(10) took 3.000 seconds (ok) Nov 22 14:25:50.390: vmx| SCSI0:0: Command WRITE(10) took 3.000 seconds (ok) Nov 22 14:25:50.390: vmx| SCSI0:0: Command WRITE(10) took 3.000 seconds (ok) Nov 22 14:26:33.578: vmx| SCSI0:0: Command WRITE(10) took 1.196 seconds (ok) Nov 22 14:29:09.109: vmx| SCSI0:0: Command WRITE(10) took 1.735 seconds (ok) Nov 22 14:29:53.375: vmx| SCSI0:0: Command WRITE(10) took 1.000 seconds (ok) Nov 22 14:29:53.375: vmx| SCSI0:0: Command WRITE(10) took 1.000 seconds (ok) Nov 22 14:34:00.359: vmx| SCSI0:0: Command WRITE(10) took 3.000 seconds (ok) Nov 22 14:35:33.359: vmx| SCSI0:0: Command WRITE(10) took 1.000 seconds (ok) Nov 22 14:35:33.359: vmx| SCSI0:0: Command WRITE(10) took 2.308 seconds (ok) Nov 22 14:38:45.140: vmx| VMXVmdbLoadUsbDevices: New set of 1 USB devices Nov 22 14:38:45.140: vmx| USB: Found device [name:Sierra\ Wireless\ USB\ Device vid:1199 pid:0220 path:1/4/0 speed:full family:vendor] Nov 22 16:13:15.734: vmx| SCSI0:0: Command WRITE(10) took 4.500 seconds (ok) Nov 22 16:16:32.687: vmx| SCSI0:0: Command WRITE(10) took 1.500 seconds (ok) Nov 22 16:17:36.218: vmx| SCSI0:0: Command WRITE(10) took 7.048 seconds (ok) Nov 22 16:17:36.218: vmx| SCSI0:0: Command WRITE(10) took 3.853 seconds (ok) Nov 22 16:34:44.578: vmx| SCSI0:0: Command WRITE(10) took 1.500 seconds (ok) Nov 22 17:18:55.359: vmx| SCSI0:0: Command WRITE(10) took 2.500 seconds (ok) Nov 22 17:27:55.484: vmx| SCSI0:0: Command WRITE(10) took 1.000 seconds (ok) Nov 22 17:29:05.812: vmx| SCSI0:0: Command WRITE(10) took 1.000 seconds (ok) Nov 22 17:30:42.500: vmx| SCSI0:0: Command WRITE(10) took 1.001 seconds (ok) Nov 22 17:30:42.500: vmx| SCSI0:0: Command WRITE(10) took 1.001 seconds (ok) Nov 22 17:31:15.796: vmx| SCSI0:0: Command WRITE(10) took 3.000 seconds (ok) Nov 22 18:13:21.656: vmx| SCSI0:0: Command WRITE(10) took 1.079 seconds (ok) Nov 23 07:38:11.625: vmx| LICENSE using: 'HKEY_LOCAL_MACHINE\SOFTWARE\VMware, Inc.\VMware Workstation\License.ws.6.0.200907' Nov 23 08:57:31.109: vmx| SCSI0:0: Command WRITE(10) took 11.052 seconds (ok) Nov 23 08:57:31.109: vmx| SCSI0:0: Command WRITE(10) took 11.052 seconds (ok) Nov 23 08:57:31.109: vmx| SCSI0:0: Command WRITE(10) took 11.052 seconds (ok) Nov 23 09:01:32.375: vmx| SCSI0:0: Command WRITE(10) took 2.328 seconds (ok) Nov 23 10:43:28.984: vmx| VMXVmdbLoadUsbDevices: New set of 0 USB devices Nov 23 10:43:37.750: vmx| VMXVmdbLoadUsbDevices: New set of 1 USB devices Nov 23 10:43:37.750: vmx| USB: Found device [name:Sierra\ Wireless\ USB\ Device vid:1199 pid:0220 path:1/4/0 speed:full family:vendor] Nov 23 10:49:42.531: vmx| [Vmdb_Set] Stale data for path: /vm/#_VMX/mks/focusEvent/ (current value '0' -> new value '1') [615->615] Nov 23 11:05:14.453: vmx| SCSI0:0: Command WRITE(10) took 2.500 seconds (ok) Nov 23 11:05:14.453: vmx| SCSI0:0: Command WRITE(10) took 2.500 seconds (ok) Nov 23 11:05:14.453: vmx| SCSI0:0: Command WRITE(10) took 2.500 seconds (ok) Nov 23 11:05:14.453: vmx| SCSI0:0: Command WRITE(10) took 2.500 seconds (ok) Nov 23 11:05:14.453: vmx| SCSI0:0: Command WRITE(10) took 2.500 seconds (ok) Nov 23 11:05:14.453: vmx| SCSI0:0: Command WRITE(10) took 2.501 seconds (ok) Nov 23 11:05:14.453: vmx| SCSI0:0: Command WRITE(10) took 2.501 seconds (ok) Nov 23 11:05:14.453: vmx| SCSI0:0: Command WRITE(10) took 2.501 seconds (ok) Nov 23 11:05:14.453: vmx| SCSI0:0: Command WRITE(10) took 2.501 seconds (ok) Nov 23 11:05:14.453: vmx| SCSI0:0: Command WRITE(10) took 2.501 seconds (ok) Nov 23 11:05:14.453: vmx| SCSI0:0: Command WRITE(10) took 2.501 seconds (ok) Nov 23 11:05:14.453: vmx| SCSI0:0: Command WRITE(10) took 2.502 seconds (ok) Nov 23 11:05:14.453: vmx| SCSI0:0: Command WRITE(10) took 2.502 seconds (ok) Nov 23 11:05:14.453: vmx| SCSI0:0: Command WRITE(10) took 2.502 seconds (ok) Nov 23 11:05:14.453: vmx| SCSI0:0: Command WRITE(10) took 2.502 seconds (ok) Nov 23 11:13:48.484: vmx| SCSI0:0: Command READ(10) took 1.014 seconds (ok) Nov 23 11:14:28.218: vmx| VMXVmdbCbVmVmxExecState: Exec state change requested to state suspended without reset Nov 23 11:14:28.218: vcpu-0| Sync monModules(1). Nov 23 11:14:28.218: vcpu-0| Done Sync monModules(1). Nov 23 11:14:28.250: vmx| USB: Disconnecting device 0xc00000010e0f0002 Nov 23 11:14:28.281: vcpu-0| Cpt monModules(2). Nov 23 11:14:28.281: vcpu-0| Done Cpt monModules(2). Nov 23 11:14:28.281: vmx| DUMPER: Creating checkpoint file .\Ubuntu.vmss Nov 23 11:14:28.281: vmx| Checkpointed in VMware Workstation, 6.0.4 build-93057, build-93057, Windows Host Nov 23 11:14:28.312: vmx| MM: Renamed C:\Documents and Settings\Thinkpad2\My Documents\My Virtual Machines\Ubuntu 8.04\564da852-512e-da8e-4113-827b2b8d3f3d.vmem to .\Ubuntu.vmem Nov 23 11:14:34.484: vmx| Msg_Reset: Nov 23 11:14:34.484: vmx| ---------------------------------------- Nov 23 11:14:34.484: vmx| Stopping VCPU threads... Nov 23 11:14:35.484: mks| Detaching from window system. Nov 23 11:14:35.484: mks| Async MKS thread is exiting Nov 23 11:14:35.484: vmx| TOOLS received request in VMX to set option 'enableDnD' -> '0' Nov 23 11:14:35.484: vmx| TOOLS received request in VMX to set option 'copypaste' -> '0' Nov 23 11:14:35.484: vmx| TOOLS received request in VMX to set option 'copypaste' -> '0' Nov 23 11:14:35.578: vmx| MKS local poweroff Nov 23 11:14:35.593: vmx| AIOWIN32: asyncOps=194058 syncOps=0 bufSize=0Kb delayed=57236 fixed=0 sgOp=194058 sgOn=1 Nov 23 11:14:35.593: vmx| WORKER: asyncOps=627231 maxActiveOps=1 maxPending=0 maxCompleted=0 Nov 23 11:14:37.171: vmx| vmdbPipe_Streams: Couldn't read Nov 23 11:14:37.171: vmx| VMX idle exit Nov 23 11:14:37.171: vmx| Flushing VMX VMDB connections Nov 23 11:14:37.171: vmx| IPC_exit: disconnecting all threads Nov 23 11:14:37.171: vmx| VMX exit (0).