rendered paste bodyOct 27 21:55:58.511: vmx| Log for VMware Workstation pid=6272 version=7.0.0 build=build-203739 option=Release
Oct 27 21:55:58.511: vmx| The process is 64-bit.
Oct 27 21:55:58.511: vmx| Host codepage=UTF-8 encoding=UTF-8
Oct 27 21:55:58.511: vmx| Hostname=localhost.localdomain
Oct 27 21:55:58.511: vmx| IP=127.0.0.1 (lo)
Oct 27 21:55:58.511: vmx| IP=192.168.2.2 (eth0)
Oct 27 21:55:58.511: vmx| Command line: "/usr/lib/vmware/bin/vmware-vmx" "-s" "vmx.stdio.keep=TRUE" "-#" "product=1;name=VMware Workstation;version=7.0.0;buildnumber=203739;licensename=VMware Workstation;licenseversion=7.0;" "-@" "pipe=/tmp/vmware-ranguvar/vmx50d4c7003d1e135a;readyEvent=88" "/home/ranguvar/soft/vmware/snowy/snowy.vmx"
Oct 27 21:55:58.511: vmx| Msg_SetLocale: HostLocale=UTF-8 UserLocale=NULL
Oct 27 21:55:58.511: vmx| Ready event: 88
Oct 27 21:55:58.545: vmx| UI Connecting to pipe '/tmp/vmware-ranguvar/vmx50d4c7003d1e135a' with user '(null)'
Oct 27 21:55:58.545: vmx| VMXVmdb: Local connection timeout: 60000 ms.
Oct 27 21:55:58.551: vmx| /home/ranguvar/soft/vmware/snowy/snowy.vmx: Setup symlink /var/run/vmware/293ec87187bb375d4d0c8ff9d1c2581d -> /var/run/vmware/ranguvar_1000/1256694958511792_6272
Oct 27 21:55:58.551: vmx| Vix: [6272 mainDispatch.c:369]: VMAutomation: Initializing VMAutomation.
Oct 27 21:55:58.551: vmx| Vix: [6272 mainDispatch.c:391]: VMAutomation: Detected the VM is not managed
Oct 27 21:55:58.551: vmx| Vix: [6272 mainDispatch.c:555]: VMAutomationOpenListenerSocket() listening
Oct 27 21:55:58.551: vmx| Sig_Init already initialized
Oct 27 21:55:58.552: vmx| Vix: [6272 mainDispatch.c:3485]: VMAutomation_ReportPowerOpFinished: statevar=0, newAppState=1870, success=1
Oct 27 21:55:58.552: vmx| Transitioned vmx/execState/val to poweredOff
Oct 27 21:55:58.552: vmx| Vix: [6272 mainDispatch.c:3485]: VMAutomation_ReportPowerOpFinished: statevar=1, newAppState=1873, success=1
Oct 27 21:55:58.552: vmx| Vix: [6272 mainDispatch.c:3485]: VMAutomation_ReportPowerOpFinished: statevar=2, newAppState=1877, success=1
Oct 27 21:55:58.552: vmx| Vix: [6272 mainDispatch.c:3485]: VMAutomation_ReportPowerOpFinished: statevar=3, newAppState=1881, success=1
Oct 27 21:55:58.552: vmx| VMMon_GetkHzEstimate: Calculated 2400015 kHz
Oct 27 21:55:58.552: vmx| TSC kHz estimates: vmmon 2400015, cpuinfo 2400014, cpufreq 0. Using 2400015 kHz
Oct 27 21:55:58.552: vmx| CPU # 0 TSC = 9572505923934
Oct 27 21:55:58.552: vmx| CPU # 1 TSC = 9572505924132
Oct 27 21:55:58.552: vmx| CPU # 2 TSC = 9572505924285
Oct 27 21:55:58.552: vmx| CPU # 3 TSC = 9572505923619
Oct 27 21:55:58.552: vmx| TSC delta 666
Oct 27 21:55:58.552: vmx| CPU # 0 TSC = 9572506028217
Oct 27 21:55:58.552: vmx| CPU # 1 TSC = 9572506028586
Oct 27 21:55:58.552: vmx| CPU # 2 TSC = 9572506027830
Oct 27 21:55:58.552: vmx| CPU # 3 TSC = 9572506027758
Oct 27 21:55:58.552: vmx| TSC delta 828
Oct 27 21:55:58.552: vmx| CPU # 0 TSC = 9572506140474
Oct 27 21:55:58.552: vmx| CPU # 1 TSC = 9572506140321
Oct 27 21:55:58.552: vmx| CPU # 2 TSC = 9572506139394
Oct 27 21:55:58.552: vmx| CPU # 3 TSC = 9572506139466
Oct 27 21:55:58.552: vmx| TSC delta 1080
Oct 27 21:55:58.552: vmx| CPU # 0 TSC = 9572506253892
Oct 27 21:55:58.552: vmx| CPU # 1 TSC = 9572506253460
Oct 27 21:55:58.552: vmx| CPU # 2 TSC = 9572506253487
Oct 27 21:55:58.552: vmx| CPU # 3 TSC = 9572506253415
Oct 27 21:55:58.552: vmx| TSC delta 477
Oct 27 21:55:58.552: vmx| TSC min delta 477
Oct 27 21:55:58.552: vmx| PTSC: RefClockToTSC 1000000Hz -> 2400015000Hz
Oct 27 21:55:58.552: vmx| PTSC: RefClockToTSC ((x * 2516598128) >> 20)
Oct 27 21:55:58.552: vmx| PTSC: using TSC
Oct 27 21:55:58.553: vmx| CPUID[0] vendor: GenuineIntel
Oct 27 21:55:58.553: vmx| CPUID[0] name: Intel(R) Core(TM)2 Quad CPU Q6600 @ 2.40GHz
Oct 27 21:55:58.553: vmx| CPUID[0] level 00000000, 0: 0x0000000a 0x756e6547 0x6c65746e 0x49656e69
Oct 27 21:55:58.553: vmx| CPUID[0] level 00000001, 0: 0x000006fb 0x02040800 0x0000e3bd 0xbfebfbff
Oct 27 21:55:58.553: vmx| CPUID[0] level 00000002, 0: 0x05b0b101 0x005657f0 0x00000000 0x2cb43049
Oct 27 21:55:58.553: vmx| CPUID[0] level 00000003, 0: 0x00000000 0x00000000 0x00000000 0x00000000
Oct 27 21:55:58.553: vmx| CPUID[0] level 00000004, 0: 0x0c000121 0x01c0003f 0x0000003f 0x00000001
Oct 27 21:55:58.553: vmx| CPUID[0] level 00000005, 0: 0x00000040 0x00000040 0x00000003 0x00000020
Oct 27 21:55:58.553: vmx| CPUID[0] level 00000006, 0: 0x00000001 0x00000002 0x00000001 0x00000000
Oct 27 21:55:58.553: vmx| CPUID[0] level 00000007, 0: 0x00000000 0x00000000 0x00000000 0x00000000
Oct 27 21:55:58.553: vmx| CPUID[0] level 00000008, 0: 0x00000400 0x00000000 0x00000000 0x00000000
Oct 27 21:55:58.553: vmx| CPUID[0] level 00000009, 0: 0x00000000 0x00000000 0x00000000 0x00000000
Oct 27 21:55:58.553: vmx| CPUID[0] level 0000000a, 0: 0x07280202 0x00000000 0x00000000 0x00000503
Oct 27 21:55:58.553: vmx| CPUID[0] level 80000000, 0: 0x80000008 0x00000000 0x00000000 0x00000000
Oct 27 21:55:58.553: vmx| CPUID[0] level 80000001, 0: 0x00000000 0x00000000 0x00000001 0x20100800
Oct 27 21:55:58.553: vmx| CPUID[0] level 80000002, 0: 0x65746e49 0x2952286c 0x726f4320 0x4d542865
Oct 27 21:55:58.553: vmx| CPUID[0] level 80000003, 0: 0x51203229 0x20646175 0x20555043 0x51202020
Oct 27 21:55:58.553: vmx| CPUID[0] level 80000004, 0: 0x30303636 0x20402020 0x30342e32 0x007a4847
Oct 27 21:55:58.553: vmx| CPUID[0] level 80000005, 0: 0x00000000 0x00000000 0x00000000 0x00000000
Oct 27 21:55:58.553: vmx| CPUID[0] level 80000006, 0: 0x00000000 0x00000000 0x10008040 0x00000000
Oct 27 21:55:58.553: vmx| CPUID[0] level 80000007, 0: 0x00000000 0x00000000 0x00000000 0x00000000
Oct 27 21:55:58.553: vmx| CPUID[0] level 80000008, 0: 0x00003024 0x00000000 0x00000000 0x00000000
Oct 27 21:55:58.553: vmx| CPUID[1] vendor: GenuineIntel
Oct 27 21:55:58.553: vmx| CPUID[1] name: Intel(R) Core(TM)2 Quad CPU Q6600 @ 2.40GHz
Oct 27 21:55:58.553: vmx| CPUID[1] level 00000000, 0: 0x0000000a 0x756e6547 0x6c65746e 0x49656e69
Oct 27 21:55:58.553: vmx| CPUID[1] level 00000001, 0: 0x000006fb 0x03040800 0x0000e3bd 0xbfebfbff
Oct 27 21:55:58.553: vmx| CPUID[1] level 00000002, 0: 0x05b0b101 0x005657f0 0x00000000 0x2cb43049
Oct 27 21:55:58.553: vmx| CPUID[1] level 00000003, 0: 0x00000000 0x00000000 0x00000000 0x00000000
Oct 27 21:55:58.553: vmx| CPUID[1] level 00000004, 0: 0x0c000121 0x01c0003f 0x0000003f 0x00000001
Oct 27 21:55:58.553: vmx| CPUID[1] level 00000005, 0: 0x00000040 0x00000040 0x00000003 0x00000020
Oct 27 21:55:58.553: vmx| CPUID[1] level 00000006, 0: 0x00000001 0x00000002 0x00000001 0x00000000
Oct 27 21:55:58.553: vmx| CPUID[1] level 00000007, 0: 0x00000000 0x00000000 0x00000000 0x00000000
Oct 27 21:55:58.553: vmx| CPUID[1] level 00000008, 0: 0x00000400 0x00000000 0x00000000 0x00000000
Oct 27 21:55:58.553: vmx| CPUID[1] level 00000009, 0: 0x00000000 0x00000000 0x00000000 0x00000000
Oct 27 21:55:58.553: vmx| CPUID[1] level 0000000a, 0: 0x07280202 0x00000000 0x00000000 0x00000503
Oct 27 21:55:58.553: vmx| CPUID[1] level 80000000, 0: 0x80000008 0x00000000 0x00000000 0x00000000
Oct 27 21:55:58.553: vmx| CPUID[1] level 80000001, 0: 0x00000000 0x00000000 0x00000001 0x20100800
Oct 27 21:55:58.553: vmx| CPUID[1] level 80000002, 0: 0x65746e49 0x2952286c 0x726f4320 0x4d542865
Oct 27 21:55:58.553: vmx| CPUID[1] level 80000003, 0: 0x51203229 0x20646175 0x20555043 0x51202020
Oct 27 21:55:58.553: vmx| CPUID[1] level 80000004, 0: 0x30303636 0x20402020 0x30342e32 0x007a4847
Oct 27 21:55:58.553: vmx| CPUID[1] level 80000005, 0: 0x00000000 0x00000000 0x00000000 0x00000000
Oct 27 21:55:58.553: vmx| CPUID[1] level 80000006, 0: 0x00000000 0x00000000 0x10008040 0x00000000
Oct 27 21:55:58.553: vmx| CPUID[1] level 80000007, 0: 0x00000000 0x00000000 0x00000000 0x00000000
Oct 27 21:55:58.553: vmx| CPUID[1] level 80000008, 0: 0x00003024 0x00000000 0x00000000 0x00000000
Oct 27 21:55:58.553: vmx| CPUID[2] vendor: GenuineIntel
Oct 27 21:55:58.553: vmx| CPUID[2] name: Intel(R) Core(TM)2 Quad CPU Q6600 @ 2.40GHz
Oct 27 21:55:58.553: vmx| CPUID[2] level 00000000, 0: 0x0000000a 0x756e6547 0x6c65746e 0x49656e69
Oct 27 21:55:58.553: vmx| CPUID[2] level 00000001, 0: 0x000006fb 0x01040800 0x0000e3bd 0xbfebfbff
Oct 27 21:55:58.553: vmx| CPUID[2] level 00000002, 0: 0x05b0b101 0x005657f0 0x00000000 0x2cb43049
Oct 27 21:55:58.553: vmx| CPUID[2] level 00000003, 0: 0x00000000 0x00000000 0x00000000 0x00000000
Oct 27 21:55:58.553: vmx| CPUID[2] level 00000004, 0: 0x0c000121 0x01c0003f 0x0000003f 0x00000001
Oct 27 21:55:58.553: vmx| CPUID[2] level 00000005, 0: 0x00000040 0x00000040 0x00000003 0x00000020
Oct 27 21:55:58.553: vmx| CPUID[2] level 00000006, 0: 0x00000001 0x00000002 0x00000001 0x00000000
Oct 27 21:55:58.553: vmx| CPUID[2] level 00000007, 0: 0x00000000 0x00000000 0x00000000 0x00000000
Oct 27 21:55:58.553: vmx| CPUID[2] level 00000008, 0: 0x00000400 0x00000000 0x00000000 0x00000000
Oct 27 21:55:58.553: vmx| CPUID[2] level 00000009, 0: 0x00000000 0x00000000 0x00000000 0x00000000
Oct 27 21:55:58.553: vmx| CPUID[2] level 0000000a, 0: 0x07280202 0x00000000 0x00000000 0x00000503
Oct 27 21:55:58.553: vmx| CPUID[2] level 80000000, 0: 0x80000008 0x00000000 0x00000000 0x00000000
Oct 27 21:55:58.553: vmx| CPUID[2] level 80000001, 0: 0x00000000 0x00000000 0x00000001 0x20100800
Oct 27 21:55:58.553: vmx| CPUID[2] level 80000002, 0: 0x65746e49 0x2952286c 0x726f4320 0x4d542865
Oct 27 21:55:58.553: vmx| CPUID[2] level 80000003, 0: 0x51203229 0x20646175 0x20555043 0x51202020
Oct 27 21:55:58.553: vmx| CPUID[2] level 80000004, 0: 0x30303636 0x20402020 0x30342e32 0x007a4847
Oct 27 21:55:58.553: vmx| CPUID[2] level 80000005, 0: 0x00000000 0x00000000 0x00000000 0x00000000
Oct 27 21:55:58.553: vmx| CPUID[2] level 80000006, 0: 0x00000000 0x00000000 0x10008040 0x00000000
Oct 27 21:55:58.553: vmx| CPUID[2] level 80000007, 0: 0x00000000 0x00000000 0x00000000 0x00000000
Oct 27 21:55:58.553: vmx| CPUID[2] level 80000008, 0: 0x00003024 0x00000000 0x00000000 0x00000000
Oct 27 21:55:58.553: vmx| CPUID[3] vendor: GenuineIntel
Oct 27 21:55:58.553: vmx| CPUID[3] name: Intel(R) Core(TM)2 Quad CPU Q6600 @ 2.40GHz
Oct 27 21:55:58.553: vmx| CPUID[3] level 00000000, 0: 0x0000000a 0x756e6547 0x6c65746e 0x49656e69
Oct 27 21:55:58.553: vmx| CPUID[3] level 00000001, 0: 0x000006fb 0x00040800 0x0000e3bd 0xbfebfbff
Oct 27 21:55:58.553: vmx| CPUID[3] level 00000002, 0: 0x05b0b101 0x005657f0 0x00000000 0x2cb43049
Oct 27 21:55:58.553: vmx| CPUID[3] level 00000003, 0: 0x00000000 0x00000000 0x00000000 0x00000000
Oct 27 21:55:58.553: vmx| CPUID[3] level 00000004, 0: 0x0c000121 0x01c0003f 0x0000003f 0x00000001
Oct 27 21:55:58.553: vmx| CPUID[3] level 00000005, 0: 0x00000040 0x00000040 0x00000003 0x00000020
Oct 27 21:55:58.553: vmx| CPUID[3] level 00000006, 0: 0x00000001 0x00000002 0x00000001 0x00000000
Oct 27 21:55:58.553: vmx| CPUID[3] level 00000007, 0: 0x00000000 0x00000000 0x00000000 0x00000000
Oct 27 21:55:58.553: vmx| CPUID[3] level 00000008, 0: 0x00000400 0x00000000 0x00000000 0x00000000
Oct 27 21:55:58.553: vmx| CPUID[3] level 00000009, 0: 0x00000000 0x00000000 0x00000000 0x00000000
Oct 27 21:55:58.553: vmx| CPUID[3] level 0000000a, 0: 0x07280202 0x00000000 0x00000000 0x00000503
Oct 27 21:55:58.553: vmx| CPUID[3] level 80000000, 0: 0x80000008 0x00000000 0x00000000 0x00000000
Oct 27 21:55:58.553: vmx| CPUID[3] level 80000001, 0: 0x00000000 0x00000000 0x00000001 0x20100800
Oct 27 21:55:58.553: vmx| CPUID[3] level 80000002, 0: 0x65746e49 0x2952286c 0x726f4320 0x4d542865
Oct 27 21:55:58.553: vmx| CPUID[3] level 80000003, 0: 0x51203229 0x20646175 0x20555043 0x51202020
Oct 27 21:55:58.553: vmx| CPUID[3] level 80000004, 0: 0x30303636 0x20402020 0x30342e32 0x007a4847
Oct 27 21:55:58.553: vmx| CPUID[3] level 80000005, 0: 0x00000000 0x00000000 0x00000000 0x00000000
Oct 27 21:55:58.553: vmx| CPUID[3] level 80000006, 0: 0x00000000 0x00000000 0x10008040 0x00000000
Oct 27 21:55:58.553: vmx| CPUID[3] level 80000007, 0: 0x00000000 0x00000000 0x00000000 0x00000000
Oct 27 21:55:58.553: vmx| CPUID[3] level 80000008, 0: 0x00003024 0x00000000 0x00000000 0x00000000
Oct 27 21:55:58.553: vmx| hostCPUID vendor: GenuineIntel
Oct 27 21:55:58.553: vmx| hostCPUID name: Intel(R) Core(TM)2 Quad CPU Q6600 @ 2.40GHz
Oct 27 21:55:58.553: vmx| hostCPUID level 00000000, 0: 0x0000000a 0x756e6547 0x6c65746e 0x49656e69
Oct 27 21:55:58.554: vmx| hostCPUID level 00000001, 0: 0x000006fb 0x02040800 0x0000e3bd 0xbfebfbff
Oct 27 21:55:58.554: vmx| hostCPUID level 00000002, 0: 0x05b0b101 0x005657f0 0x00000000 0x2cb43049
Oct 27 21:55:58.554: vmx| hostCPUID level 00000003, 0: 0x00000000 0x00000000 0x00000000 0x00000000
Oct 27 21:55:58.554: vmx| hostCPUID level 00000004, 0: 0x0c000121 0x01c0003f 0x0000003f 0x00000001
Oct 27 21:55:58.554: vmx| hostCPUID level 00000005, 0: 0x00000040 0x00000040 0x00000003 0x00000020
Oct 27 21:55:58.554: vmx| hostCPUID level 00000006, 0: 0x00000001 0x00000002 0x00000001 0x00000000
Oct 27 21:55:58.554: vmx| hostCPUID level 00000007, 0: 0x00000000 0x00000000 0x00000000 0x00000000
Oct 27 21:55:58.554: vmx| hostCPUID level 00000008, 0: 0x00000400 0x00000000 0x00000000 0x00000000
Oct 27 21:55:58.554: vmx| hostCPUID level 00000009, 0: 0x00000000 0x00000000 0x00000000 0x00000000
Oct 27 21:55:58.554: vmx| hostCPUID level 0000000a, 0: 0x07280202 0x00000000 0x00000000 0x00000503
Oct 27 21:55:58.554: vmx| hostCPUID level 80000000, 0: 0x80000008 0x00000000 0x00000000 0x00000000
Oct 27 21:55:58.554: vmx| hostCPUID level 80000001, 0: 0x00000000 0x00000000 0x00000001 0x20100800
Oct 27 21:55:58.554: vmx| hostCPUID level 80000002, 0: 0x65746e49 0x2952286c 0x726f4320 0x4d542865
Oct 27 21:55:58.554: vmx| hostCPUID level 80000003, 0: 0x51203229 0x20646175 0x20555043 0x51202020
Oct 27 21:55:58.554: vmx| hostCPUID level 80000004, 0: 0x30303636 0x20402020 0x30342e32 0x007a4847
Oct 27 21:55:58.554: vmx| hostCPUID level 80000005, 0: 0x00000000 0x00000000 0x00000000 0x00000000
Oct 27 21:55:58.554: vmx| hostCPUID level 80000006, 0: 0x00000000 0x00000000 0x10008040 0x00000000
Oct 27 21:55:58.554: vmx| hostCPUID level 80000007, 0: 0x00000000 0x00000000 0x00000000 0x00000000
Oct 27 21:55:58.554: vmx| hostCPUID level 80000008, 0: 0x00003024 0x00000000 0x00000000 0x00000000
Oct 27 21:55:58.554: vmx| CPUID Maximum Physical Address Bits supported across all CPUs: 36
Oct 27 21:55:58.554: vmx| CPU[0]: MSR 0x3a = 0x5
Oct 27 21:55:58.554: vmx| CPU[1]: MSR 0x3a = 0x5
Oct 27 21:55:58.554: vmx| CPU[2]: MSR 0x3a = 0x5
Oct 27 21:55:58.554: vmx| CPU[3]: MSR 0x3a = 0x5
Oct 27 21:55:58.554: vmx| Common: MSR 0x3a = 0x5
Oct 27 21:55:58.554: vmx| CPU[0]: MSR 0x480 = 0x5a08000000000b
Oct 27 21:55:58.554: vmx| CPU[1]: MSR 0x480 = 0x5a08000000000b
Oct 27 21:55:58.554: vmx| CPU[2]: MSR 0x480 = 0x5a08000000000b
Oct 27 21:55:58.554: vmx| CPU[3]: MSR 0x480 = 0x5a08000000000b
Oct 27 21:55:58.554: vmx| Common: MSR 0x480 = 0x5a08000000000b
Oct 27 21:55:58.554: vmx| CPU[0]: MSR 0x481 = 0x3f00000016
Oct 27 21:55:58.554: vmx| CPU[1]: MSR 0x481 = 0x3f00000016
Oct 27 21:55:58.554: vmx| CPU[2]: MSR 0x481 = 0x3f00000016
Oct 27 21:55:58.554: vmx| CPU[3]: MSR 0x481 = 0x3f00000016
Oct 27 21:55:58.554: vmx| Common: MSR 0x481 = 0x3f00000016
Oct 27 21:55:58.554: vmx| CPU[0]: MSR 0x482 = 0xf7f9fffe0401e172
Oct 27 21:55:58.554: vmx| CPU[1]: MSR 0x482 = 0xf7f9fffe0401e172
Oct 27 21:55:58.554: vmx| CPU[2]: MSR 0x482 = 0xf7f9fffe0401e172
Oct 27 21:55:58.554: vmx| CPU[3]: MSR 0x482 = 0xf7f9fffe0401e172
Oct 27 21:55:58.554: vmx| Common: MSR 0x482 = 0xf7f9fffe0401e172
Oct 27 21:55:58.554: vmx| CPU[0]: MSR 0x483 = 0x3efff00036dff
Oct 27 21:55:58.554: vmx| CPU[1]: MSR 0x483 = 0x3efff00036dff
Oct 27 21:55:58.554: vmx| CPU[2]: MSR 0x483 = 0x3efff00036dff
Oct 27 21:55:58.554: vmx| CPU[3]: MSR 0x483 = 0x3efff00036dff
Oct 27 21:55:58.554: vmx| Common: MSR 0x483 = 0x3efff00036dff
Oct 27 21:55:58.554: vmx| CPU[0]: MSR 0x484 = 0x1fff000011ff
Oct 27 21:55:58.554: vmx| CPU[1]: MSR 0x484 = 0x1fff000011ff
Oct 27 21:55:58.554: vmx| CPU[2]: MSR 0x484 = 0x1fff000011ff
Oct 27 21:55:58.554: vmx| CPU[3]: MSR 0x484 = 0x1fff000011ff
Oct 27 21:55:58.554: vmx| Common: MSR 0x484 = 0x1fff000011ff
Oct 27 21:55:58.554: vmx| CPU[0]: MSR 0x485 = 0x403c0
Oct 27 21:55:58.554: vmx| CPU[1]: MSR 0x485 = 0x403c0
Oct 27 21:55:58.554: vmx| CPU[2]: MSR 0x485 = 0x403c0
Oct 27 21:55:58.554: vmx| CPU[3]: MSR 0x485 = 0x403c0
Oct 27 21:55:58.554: vmx| Common: MSR 0x485 = 0x403c0
Oct 27 21:55:58.554: vmx| CPU[0]: MSR 0x486 = 0x80000021
Oct 27 21:55:58.554: vmx| CPU[1]: MSR 0x486 = 0x80000021
Oct 27 21:55:58.554: vmx| CPU[2]: MSR 0x486 = 0x80000021
Oct 27 21:55:58.554: vmx| CPU[3]: MSR 0x486 = 0x80000021
Oct 27 21:55:58.554: vmx| Common: MSR 0x486 = 0x80000021
Oct 27 21:55:58.554: vmx| CPU[0]: MSR 0x487 = 0xffffffff
Oct 27 21:55:58.554: vmx| CPU[1]: MSR 0x487 = 0xffffffff
Oct 27 21:55:58.554: vmx| CPU[2]: MSR 0x487 = 0xffffffff
Oct 27 21:55:58.554: vmx| CPU[3]: MSR 0x487 = 0xffffffff
Oct 27 21:55:58.554: vmx| Common: MSR 0x487 = 0xffffffff
Oct 27 21:55:58.554: vmx| CPU[0]: MSR 0x488 = 0x2000
Oct 27 21:55:58.554: vmx| CPU[1]: MSR 0x488 = 0x2000
Oct 27 21:55:58.554: vmx| CPU[2]: MSR 0x488 = 0x2000
Oct 27 21:55:58.554: vmx| CPU[3]: MSR 0x488 = 0x2000
Oct 27 21:55:58.554: vmx| Common: MSR 0x488 = 0x2000
Oct 27 21:55:58.554: vmx| CPU[0]: MSR 0x489 = 0x27ff
Oct 27 21:55:58.554: vmx| CPU[1]: MSR 0x489 = 0x27ff
Oct 27 21:55:58.554: vmx| CPU[2]: MSR 0x489 = 0x27ff
Oct 27 21:55:58.554: vmx| CPU[3]: MSR 0x489 = 0x27ff
Oct 27 21:55:58.554: vmx| Common: MSR 0x489 = 0x27ff
Oct 27 21:55:58.554: vmx| CPU[0]: MSR 0x48a = 0x2c
Oct 27 21:55:58.554: vmx| CPU[1]: MSR 0x48a = 0x2c
Oct 27 21:55:58.554: vmx| CPU[2]: MSR 0x48a = 0x2c
Oct 27 21:55:58.554: vmx| CPU[3]: MSR 0x48a = 0x2c
Oct 27 21:55:58.554: vmx| Common: MSR 0x48a = 0x2c
Oct 27 21:55:58.554: vmx| CPU[0]: MSR 0x48b = 0x100000000
Oct 27 21:55:58.554: vmx| CPU[1]: MSR 0x48b = 0x100000000
Oct 27 21:55:58.554: vmx| CPU[2]: MSR 0x48b = 0x100000000
Oct 27 21:55:58.554: vmx| CPU[3]: MSR 0x48b = 0x100000000
Oct 27 21:55:58.554: vmx| Common: MSR 0x48b = 0x100000000
Oct 27 21:55:58.554: vmx| CPU[0]: MSR 0x48c = 0
Oct 27 21:55:58.554: vmx| CPU[1]: MSR 0x48c = 0
Oct 27 21:55:58.554: vmx| CPU[2]: MSR 0x48c = 0
Oct 27 21:55:58.554: vmx| CPU[3]: MSR 0x48c = 0
Oct 27 21:55:58.554: vmx| Common: MSR 0x48c = 0
Oct 27 21:55:58.554: vmx| CPU[0]: MSR 0x48d = 0
Oct 27 21:55:58.554: vmx| CPU[1]: MSR 0x48d = 0
Oct 27 21:55:58.554: vmx| CPU[2]: MSR 0x48d = 0
Oct 27 21:55:58.554: vmx| CPU[3]: MSR 0x48d = 0
Oct 27 21:55:58.554: vmx| Common: MSR 0x48d = 0
Oct 27 21:55:58.554: vmx| CPU[0]: MSR 0x48e = 0
Oct 27 21:55:58.554: vmx| CPU[1]: MSR 0x48e = 0
Oct 27 21:55:58.554: vmx| CPU[2]: MSR 0x48e = 0
Oct 27 21:55:58.554: vmx| CPU[3]: MSR 0x48e = 0
Oct 27 21:55:58.554: vmx| Common: MSR 0x48e = 0
Oct 27 21:55:58.554: vmx| CPU[0]: MSR 0x48f = 0
Oct 27 21:55:58.554: vmx| CPU[1]: MSR 0x48f = 0
Oct 27 21:55:58.554: vmx| CPU[2]: MSR 0x48f = 0
Oct 27 21:55:58.554: vmx| CPU[3]: MSR 0x48f = 0
Oct 27 21:55:58.554: vmx| Common: MSR 0x48f = 0
Oct 27 21:55:58.554: vmx| CPU[0]: MSR 0x490 = 0
Oct 27 21:55:58.554: vmx| CPU[1]: MSR 0x490 = 0
Oct 27 21:55:58.554: vmx| CPU[2]: MSR 0x490 = 0
Oct 27 21:55:58.555: vmx| CPU[3]: MSR 0x490 = 0
Oct 27 21:55:58.555: vmx| Common: MSR 0x490 = 0
Oct 27 21:55:58.565: vmx| Host ACPI: can't find SRAT
Oct 27 21:55:58.565: vmx| Host: SRAT tables not found in memory
Oct 27 21:55:58.607: vmx| ConfigCheck: No rules file found. Checks are disabled.
Oct 27 21:55:58.607: vmx| changing directory to /home/ranguvar/soft/vmware/snowy/.
Oct 27 21:55:58.607: vmx| Config file: /home/ranguvar/soft/vmware/snowy/snowy.vmx
Oct 27 21:55:58.608: vmx| Vix: [6272 mainDispatch.c:3485]: VMAutomation_ReportPowerOpFinished: statevar=1, newAppState=1873, success=1
Oct 27 21:55:58.608: vmx| Vix: [6272 mainDispatch.c:3485]: VMAutomation_ReportPowerOpFinished: statevar=2, newAppState=1878, success=1
Oct 27 21:55:58.623: vmx| VMXVmdb_LoadRawConfig: Loading raw config
Oct 27 21:55:58.693: vmx| VMXVmdbCbVmVmxExecState: Exec state change requested to state poweredOn without reset, hard.
Oct 27 21:55:58.693: vmx| PowerOn
Oct 27 21:55:58.693: vmx| VMX_PowerOn: VMX build 203739, UI build 203739
Oct 27 21:55:58.693: vmx| Host ACPI: can't find SRAT
Oct 27 21:55:58.693: vmx| Host: SRAT tables not found in memory
Oct 27 21:55:58.698: vmx| VMXVmdb_LoadRawConfig: Loading raw config
Oct 27 21:55:58.702: vmx| Vix: [6272 mainDispatch.c:3485]: VMAutomation_ReportPowerOpFinished: statevar=0, newAppState=1871, success=1
Oct 27 21:55:58.702: vmx| HOST sysname Linux, nodename box, release 2.6.31-ARCH, version #1 SMP PREEMPT Sat Oct 24 13:26:05 EDT 2009, machine x86_64, SMP, hz=250
Oct 27 21:55:58.702: vmx| DICT --- USER PREFERENCES /home/ranguvar/.vmware/preferences
Oct 27 21:55:58.702: vmx| DICT pref.grabOnKeyPress = FALSE
Oct 27 21:55:58.702: vmx| DICT pref.eula.0.appName = VMware Workstation
Oct 27 21:55:58.702: vmx| DICT pref.eula.0.buildNumber = 197124
Oct 27 21:55:58.702: vmx| DICT pref.usbDev.maxDevs = 0
Oct 27 21:55:58.702: vmx| DICT pref.keyboardAndMouse.maxProfiles = 0
Oct 27 21:55:58.702: vmx| DICT webUpdate.lastCheckTime = 1256682178
Oct 27 21:55:58.702: vmx| DICT pref.placement.left = 441
Oct 27 21:55:58.702: vmx| DICT pref.placement.top = 126
Oct 27 21:55:58.702: vmx| DICT pref.placement.right = 1477
Oct 27 21:55:58.702: vmx| DICT pref.placement.bottom = 1023
Oct 27 21:55:58.702: vmx| DICT prefvmx.defaultVMPath = /home/ranguvar/soft/vmware
Oct 27 21:55:58.702: vmx| DICT pref.downloadPermission = ask
Oct 27 21:55:58.702: vmx| DICT webUpdate.enabled = TRUE
Oct 27 21:55:58.702: vmx| DICT pref.screenCapture.saveAutomatically = FALSE
Oct 27 21:55:58.702: vmx| DICT pref.autoFitGuestToWindow = FALSE
Oct 27 21:55:58.702: vmx| DICT pref.autoFitFullScreen = none
Oct 27 21:55:58.702: vmx| DICT vmWizard.mruISO0.isoLocation = /home/ranguvar/soft/osen/macos/macos_10.6.0.iso
Oct 27 21:55:58.702: vmx| DICT vmWizard.mode = custom
Oct 27 21:55:58.702: vmx| DICT vmWizard.guestKey = freebsd-64
Oct 27 21:55:58.702: vmx| DICT vmWizard.installMediaType = later
Oct 27 21:55:58.702: vmx| DICT pref.ws.openedObj.maxNum = 1
Oct 27 21:55:58.702: vmx| DICT pref.ws.currentObj.path = /vm/#50d4c7003d1e135a/
Oct 27 21:55:58.702: vmx| DICT pref.ws.currentObj.type = vm
Oct 27 21:55:58.702: vmx| DICT pref.view.navBar = FALSE
Oct 27 21:55:58.702: vmx| DICT pref.view.toolbars.style = icons
Oct 27 21:55:58.702: vmx| DICT pref.license.maxNum = 1
Oct 27 21:55:58.702: vmx| DICT pref.license0.version = 7.0
Oct 27 21:55:58.702: vmx| DICT pref.license0.lastEvalReminder = 3
Oct 27 21:55:58.702: vmx| DICT vmWizard.mruISO1.isoLocation = /home/ranguvar/soft/osen/ubuntu/ubuntu-9.10rc-amd64.iso
Oct 27 21:55:58.702: vmx| DICT pref.eula.1.appName = VMware Workstation
Oct 27 21:55:58.702: vmx| DICT pref.eula.1.buildNumber = 203739
Oct 27 21:55:58.702: vmx| DICT vmWizard.mruISO2.isoLocation = autoinst.iso
Oct 27 21:55:58.702: vmx| DICT vmWizard.mruISO3.isoLocation = /home/ranguvar/soft/osen/ubuntu/xubuntu-9.04-desktop-i386.iso
Oct 27 21:55:58.702: vmx| DICT vmWizard.mruISO4.isoLocation = /home/ranguvar/soft/osen/ubuntu/crunchbang-9.04.01.i386.iso
Oct 27 21:55:58.702: vmx| DICT vmWizard.lastPowerOnValue = FALSE
Oct 27 21:55:58.702: vmx| DICT vmWizard.mruISO5.isoLocation = /home/ranguvar/soft/osen/ubuntu/ubuntu-9.10b-desktop-i386.iso
Oct 27 21:55:58.702: vmx| DICT hint.tools.toolsImage = FALSE
Oct 27 21:55:58.702: vmx| DICT vmWizard.physicalBackend = /dev/sr0
Oct 27 21:55:58.702: vmx| DICT hint.ui.wizard.vm.showSuccessPage = FALSE
Oct 27 21:55:58.702: vmx| DICT pref.ws.openedObj0.present = TRUE
Oct 27 21:55:58.702: vmx| DICT pref.ws.openedObj0.type = vm
Oct 27 21:55:58.702: vmx| DICT pref.ws.openedObj0.path = /vm/#50d4c7003d1e135a/
Oct 27 21:55:58.703: vmx| DICT pref.ws.openedObj0.file = /home/ranguvar/soft/vmware/snowy/snowy.vmx
Oct 27 21:55:58.703: vmx| DICT pref.ws.openedObj0.dest = /host2/#_client/
Oct 27 21:55:58.703: vmx| DICT hint.hbaHosted.rawDisk = FALSE
Oct 27 21:55:58.703: vmx| DICT --- USER DEFAULTS /home/ranguvar/.vmware/config
Oct 27 21:55:58.703: vmx| DICT --- HOST DEFAULTS /etc/vmware/config
Oct 27 21:55:58.703: vmx| DICT vmware.fullpath = /usr/bin/vmware
Oct 27 21:55:58.703: vmx| DICT vix.libdir = /usr/lib/vmware-vix
Oct 27 21:55:58.703: vmx| DICT NETWORKING = yes
Oct 27 21:55:58.703: vmx| DICT VMBLOCK_CONFED = yes
Oct 27 21:55:58.703: vmx| DICT gksu.rootMethod = sudo
Oct 27 21:55:58.703: vmx| DICT libdir = /usr/lib/vmware
Oct 27 21:55:58.703: vmx| DICT VMCI_CONFED = yes
Oct 27 21:55:58.703: vmx| DICT vix.config.version = 1
Oct 27 21:55:58.703: vmx| DICT VSOCK_CONFED = yes
Oct 27 21:55:58.703: vmx| DICT initscriptdir = /etc/rc.d/
Oct 27 21:55:58.703: vmx| DICT player.product.version = 3.0.0
Oct 27 21:55:58.703: vmx| DICT authd.fullpath = /usr/sbin/vmware-authd
Oct 27 21:55:58.703: vmx| DICT bindir = /usr/bin
Oct 27 21:55:58.703: vmx| DICT product.buildNumber = 203739
Oct 27 21:55:58.703: vmx| DICT product.version = 7.0.0
Oct 27 21:55:58.703: vmx| DICT workstation.product.version = 7.0.0
Oct 27 21:55:58.703: vmx| DICT product.name = VMware Workstation
Oct 27 21:55:58.703: vmx| DICT --- SITE DEFAULTS /usr/lib/vmware/config
Oct 27 21:55:58.703: vmx| DICT tag.help = introduction.htm
Oct 27 21:55:58.703: vmx| DICT tag.configurationEditor = config_editor_newvm.htm
Oct 27 21:55:58.703: vmx| DICT tag.ideConfig = devices_virtualdrive.htm
Oct 27 21:55:58.703: vmx| DICT tag.floppyConfig = devices_floppy.htm
Oct 27 21:55:58.703: vmx| DICT tag.mouseConfig = devices_mouse.htm
Oct 27 21:55:58.703: vmx| DICT tag.netConfig = devices_netadapter.htm
Oct 27 21:55:58.703: vmx| DICT tag.parallelConfig = devices_parallel.htm
Oct 27 21:55:58.703: vmx| DICT tag.serialConfig = devices_serial.htm
Oct 27 21:55:58.703: vmx| DICT tag.soundConfig = devices_sound.htm
Oct 27 21:55:58.703: vmx| DICT tag.memConfig = configvm_memory.htm
Oct 27 21:55:58.703: vmx| DICT tag.miscConfig = configvm.htm
Oct 27 21:55:58.703: vmx| DICT tag.usbConfig = devices_usb.htm
Oct 27 21:55:58.703: vmx| DICT tag.displayConfig = configvm_display-problems.htm
Oct 27 21:55:58.703: vmx| DICT tag.tools = vmtools.htm
Oct 27 21:55:58.703: vmx| DICT --- COMMAND LINE
Oct 27 21:55:58.703: vmx| DICT vmx.stdio.keep = TRUE
Oct 27 21:55:58.703: vmx| DICT gui.available = TRUE
Oct 27 21:55:58.703: vmx| DICT --- CONFIGURATION /home/ranguvar/soft/vmware/snowy/snowy.vmx
Oct 27 21:55:58.703: vmx| DICT config.version = 8
Oct 27 21:55:58.703: vmx| DICT virtualHW.version = 7
Oct 27 21:55:58.703: vmx| DICT maxvcpus = 4
Oct 27 21:55:58.703: vmx| DICT scsi0.present = TRUE
Oct 27 21:55:58.703: vmx| DICT scsi0.virtualDev = lsilogic
Oct 27 21:55:58.703: vmx| DICT memsize = 2048
Oct 27 21:55:58.703: vmx| DICT scsi0:0.present = TRUE
Oct 27 21:55:58.703: vmx| DICT scsi0:0.fileName = snowy.vmdk
Oct 27 21:55:58.703: vmx| DICT scsi0:0.deviceType = rawDisk
Oct 27 21:55:58.703: vmx| DICT ide1:0.present = TRUE
Oct 27 21:55:58.703: vmx| DICT ide1:0.fileName = /home/ranguvar/soft/osen/macos/macos_10.6.0.iso
Oct 27 21:55:58.703: vmx| DICT ide1:0.deviceType = cdrom-image
Oct 27 21:55:58.703: vmx| DICT ethernet0.present = TRUE
Oct 27 21:55:58.703: vmx| DICT ethernet0.connectionType = nat
Oct 27 21:55:58.703: vmx| DICT ethernet0.virtualDev = e1000
Oct 27 21:55:58.703: vmx| DICT ethernet0.wakeOnPcktRcv = FALSE
Oct 27 21:55:58.703: vmx| DICT ethernet0.addressType = generated
Oct 27 21:55:58.703: vmx| DICT usb.present = TRUE
Oct 27 21:55:58.703: vmx| DICT ehci.present = TRUE
Oct 27 21:55:58.703: vmx| DICT sound.present = TRUE
Oct 27 21:55:58.703: vmx| DICT sound.fileName = -1
Oct 27 21:55:58.703: vmx| DICT sound.autodetect = TRUE
Oct 27 21:55:58.703: vmx| DICT mks.enable3d = FALSE
Oct 27 21:55:58.703: vmx| DICT pciBridge0.present = TRUE
Oct 27 21:55:58.703: vmx| DICT pciBridge4.present = TRUE
Oct 27 21:55:58.703: vmx| DICT pciBridge4.virtualDev = pcieRootPort
Oct 27 21:55:58.703: vmx| DICT pciBridge4.functions = 8
Oct 27 21:55:58.703: vmx| DICT pciBridge5.present = TRUE
Oct 27 21:55:58.703: vmx| DICT pciBridge5.virtualDev = pcieRootPort
Oct 27 21:55:58.703: vmx| DICT pciBridge5.functions = 8
Oct 27 21:55:58.703: vmx| DICT pciBridge6.present = TRUE
Oct 27 21:55:58.703: vmx| DICT pciBridge6.virtualDev = pcieRootPort
Oct 27 21:55:58.703: vmx| DICT pciBridge6.functions = 8
Oct 27 21:55:58.703: vmx| DICT pciBridge7.present = TRUE
Oct 27 21:55:58.703: vmx| DICT pciBridge7.virtualDev = pcieRootPort
Oct 27 21:55:58.703: vmx| DICT pciBridge7.functions = 8
Oct 27 21:55:58.703: vmx| DICT vmci0.present = TRUE
Oct 27 21:55:58.703: vmx| DICT roamingVM.exitBehavior = go
Oct 27 21:55:58.703: vmx| DICT displayName = snowy
Oct 27 21:55:58.703: vmx| DICT guestOS = darwin-64
Oct 27 21:55:58.703: vmx| DICT nvram = snowy.nvram
Oct 27 21:55:58.703: vmx| DICT virtualHW.productCompatibility = hosted
Oct 27 21:55:58.703: vmx| DICT extendedConfigFile = snowy.vmxf
Oct 27 21:55:58.703: vmx| DICT ethernet0.generatedAddress = 00:0c:29:3e:1e:4b
Oct 27 21:55:58.703: vmx| DICT uuid.location = 56 4d 7c 46 e8 ce 35 53-99 a6 5d a3 fa 3e 1e 4b
Oct 27 21:55:58.703: vmx| DICT uuid.bios = 56 4d 7c 46 e8 ce 35 53-99 a6 5d a3 fa 3e 1e 4b
Oct 27 21:55:58.703: vmx| DICT cleanShutdown = TRUE
Oct 27 21:55:58.703: vmx| DICT replay.supported = FALSE
Oct 27 21:55:58.703: vmx| DICT replay.filename =
Oct 27 21:55:58.703: vmx| DICT scsi0:0.redo =
Oct 27 21:55:58.703: vmx| DICT pciBridge0.pciSlotNumber = 17
Oct 27 21:55:58.703: vmx| DICT pciBridge4.pciSlotNumber = 21
Oct 27 21:55:58.703: vmx| DICT pciBridge5.pciSlotNumber = 22
Oct 27 21:55:58.703: vmx| DICT pciBridge6.pciSlotNumber = 23
Oct 27 21:55:58.703: vmx| DICT pciBridge7.pciSlotNumber = 24
Oct 27 21:55:58.703: vmx| DICT scsi0.pciSlotNumber = 16
Oct 27 21:55:58.703: vmx| DICT usb.pciSlotNumber = 32
Oct 27 21:55:58.703: vmx| DICT ethernet0.pciSlotNumber = 33
Oct 27 21:55:58.703: vmx| DICT sound.pciSlotNumber = 34
Oct 27 21:55:58.703: vmx| DICT ehci.pciSlotNumber = 35
Oct 27 21:55:58.703: vmx| DICT vmci0.pciSlotNumber = 36
Oct 27 21:55:58.703: vmx| DICT vmotion.checkpointFBSize = 16777216
Oct 27 21:55:58.703: vmx| DICT ethernet0.generatedAddressOffset = 0
Oct 27 21:55:58.703: vmx| DICT vmci0.id = -96592309
Oct 27 21:55:58.703: vmx| DICT ich7m.present = TRUE
Oct 27 21:55:58.703: vmx| DICT keyboard.vusb.enable = TRUE
Oct 27 21:55:58.703: vmx| DICT mouse.vusb.enable = TRUE
Oct 27 21:55:58.703: vmx| DICT monitor.virtual_exec = hardware
Oct 27 21:55:58.703: vmx| DICT monitor.virtual_mmu = software
Oct 27 21:55:58.703: vmx| DICT paevm = TRUE
Oct 27 21:55:58.703: vmx| DICT ide1:0.startConnected = TRUE
Oct 27 21:55:58.703: vmx| DICT usb:0.present = TRUE
Oct 27 21:55:58.703: vmx| DICT usb:1.present = TRUE
Oct 27 21:55:58.704: vmx| DICT usb:1.deviceType = hub
Oct 27 21:55:58.704: vmx| DICT usb:0.deviceType = mouse
Oct 27 21:55:58.704: vmx| DICT ethernet0.startConnected = FALSE
Oct 27 21:55:58.704: vmx| DICT sound.startConnected = FALSE
Oct 27 21:55:58.704: vmx| DICT floppy0.present = FALSE
Oct 27 21:55:58.704: vmx| DICT smc.present = FALSE
Oct 27 21:55:58.704: vmx| DICT --- USER DEFAULTS ~/.vmware/config
Oct 27 21:55:58.704: vmx| DICT --- HOST DEFAULTS /etc/vmware/config
Oct 27 21:55:58.704: vmx| DICT vmware.fullpath = /usr/bin/vmware
Oct 27 21:55:58.704: vmx| DICT vix.libdir = /usr/lib/vmware-vix
Oct 27 21:55:58.704: vmx| DICT NETWORKING = yes
Oct 27 21:55:58.704: vmx| DICT VMBLOCK_CONFED = yes
Oct 27 21:55:58.704: vmx| DICT gksu.rootMethod = sudo
Oct 27 21:55:58.704: vmx| DICT libdir = /usr/lib/vmware
Oct 27 21:55:58.704: vmx| DICT VMCI_CONFED = yes
Oct 27 21:55:58.704: vmx| DICT vix.config.version = 1
Oct 27 21:55:58.704: vmx| DICT VSOCK_CONFED = yes
Oct 27 21:55:58.704: vmx| DICT initscriptdir = /etc/rc.d/
Oct 27 21:55:58.704: vmx| DICT player.product.version = 3.0.0
Oct 27 21:55:58.704: vmx| DICT authd.fullpath = /usr/sbin/vmware-authd
Oct 27 21:55:58.704: vmx| DICT bindir = /usr/bin
Oct 27 21:55:58.704: vmx| DICT product.buildNumber = 203739
Oct 27 21:55:58.704: vmx| DICT product.version = 7.0.0
Oct 27 21:55:58.704: vmx| DICT workstation.product.version = 7.0.0
Oct 27 21:55:58.704: vmx| DICT product.name = VMware Workstation
Oct 27 21:55:58.704: vmx| DICT --- SITE DEFAULTS /usr/lib/vmware/config
Oct 27 21:55:58.704: vmx| DICT tag.help = introduction.htm
Oct 27 21:55:58.704: vmx| DICT tag.configurationEditor = config_editor_newvm.htm
Oct 27 21:55:58.704: vmx| DICT tag.ideConfig = devices_virtualdrive.htm
Oct 27 21:55:58.704: vmx| DICT tag.floppyConfig = devices_floppy.htm
Oct 27 21:55:58.704: vmx| DICT tag.mouseConfig = devices_mouse.htm
Oct 27 21:55:58.704: vmx| DICT tag.netConfig = devices_netadapter.htm
Oct 27 21:55:58.704: vmx| DICT tag.parallelConfig = devices_parallel.htm
Oct 27 21:55:58.704: vmx| DICT tag.serialConfig = devices_serial.htm
Oct 27 21:55:58.704: vmx| DICT tag.soundConfig = devices_sound.htm
Oct 27 21:55:58.704: vmx| DICT tag.memConfig = configvm_memory.htm
Oct 27 21:55:58.704: vmx| DICT tag.miscConfig = configvm.htm
Oct 27 21:55:58.704: vmx| DICT tag.usbConfig = devices_usb.htm
Oct 27 21:55:58.704: vmx| DICT tag.displayConfig = configvm_display-problems.htm
Oct 27 21:55:58.704: vmx| DICT tag.tools = vmtools.htm
Oct 27 21:55:58.704: vmx| DICT --- GLOBAL SETTINGS /usr/lib/vmware/settings
Oct 27 21:55:58.704: vmx| Vix: [6272 mainDispatch.c:3485]: VMAutomation_ReportPowerOpFinished: statevar=1, newAppState=1873, success=1
Oct 27 21:55:58.709: vmx| VMXVmdb_LoadRawConfig: Loading raw config
Oct 27 21:55:58.713: vmx| hostCpuFeatures = 0x444001fc
Oct 27 21:55:58.713: vmx| hostNumPerfCounters = 2
Oct 27 21:55:58.713: vmx| MONITOR MODE: allowed modes : BT32 HV
Oct 27 21:55:58.713: vmx| MONITOR MODE: user requested modes : HV
Oct 27 21:55:58.713: vmx| MONITOR MODE: guestOS preferred modes: HWMMU BT32 HV
Oct 27 21:55:58.713: vmx| MONITOR MODE: filtered list : HV
Oct 27 21:55:58.713: vmx| HV Settings: virtual exec = 'hardware'; virtual mmu = 'software'
Oct 27 21:55:58.713: vmx| Basic VMX Information (0x005a08000000000b)
Oct 27 21:55:58.713: vmx| VMCS revision ID 11
Oct 27 21:55:58.713: vmx| VMCS region length 2048
Oct 27 21:55:58.713: vmx| VMX physical-address width natural
Oct 27 21:55:58.713: vmx| SMM dual-monitor mode yes
Oct 27 21:55:58.713: vmx| Advanced INS/OUTS info yes
Oct 27 21:55:58.713: vmx| True VMX MSRs no
Oct 27 21:55:58.713: vmx| VMCS memory type WB
Oct 27 21:55:58.713: vmx| Pin-Based VM-Execution Controls (0x0000003f00000016)
Oct 27 21:55:58.713: vmx| External-interrupt exiting {0,1}
Oct 27 21:55:58.713: vmx| NMI exiting {0,1}
Oct 27 21:55:58.713: vmx| Virtual NMIs {0,1}
Oct 27 21:55:58.713: vmx| Activate VMX-preemption timer { 0 }
Oct 27 21:55:58.713: vmx| Primary Processor-Based VM-Execution Controls (0xf7f9fffe0401e172)
Oct 27 21:55:58.713: vmx| Interrupt-window exiting {0,1}
Oct 27 21:55:58.713: vmx| Use TSC offsetting {0,1}
Oct 27 21:55:58.713: vmx| HLT exiting {0,1}
Oct 27 21:55:58.713: vmx| INVLPG exiting {0,1}
Oct 27 21:55:58.713: vmx| MWAIT exiting {0,1}
Oct 27 21:55:58.714: vmx| RDPMC exiting {0,1}
Oct 27 21:55:58.714: vmx| RDTSC exiting {0,1}
Oct 27 21:55:58.714: vmx| CR3-load exiting { 1 }
Oct 27 21:55:58.714: vmx| CR3-store exiting { 1 }
Oct 27 21:55:58.714: vmx| CR8-load exiting {0,1}
Oct 27 21:55:58.714: vmx| CR8-store exiting {0,1}
Oct 27 21:55:58.714: vmx| Use TPR shadow {0,1}
Oct 27 21:55:58.714: vmx| NMI-window exiting {0,1}
Oct 27 21:55:58.714: vmx| MOV-DR exiting {0,1}
Oct 27 21:55:58.714: vmx| Unconditional I/O exiting {0,1}
Oct 27 21:55:58.714: vmx| Use I/O bitmaps {0,1}
Oct 27 21:55:58.714: vmx| Monitor trap flag { 0 }
Oct 27 21:55:58.714: vmx| Use MSR bitmaps {0,1}
Oct 27 21:55:58.714: vmx| MONITOR exiting {0,1}
Oct 27 21:55:58.714: vmx| PAUSE exiting {0,1}
Oct 27 21:55:58.714: vmx| Activate secondary controls {0,1}
Oct 27 21:55:58.714: vmx| Secondary Processor-Based VM-Execution Controls (0x0000000100000000)
Oct 27 21:55:58.714: vmx| Virtualize APIC accesses {0,1}
Oct 27 21:55:58.714: vmx| Enable EPT { 0 }
Oct 27 21:55:58.714: vmx| Descriptor-table exiting { 0 }
Oct 27 21:55:58.714: vmx| Enable RDTSCP { 0 }
Oct 27 21:55:58.714: vmx| Virtualize x2APIC mode { 0 }
Oct 27 21:55:58.714: vmx| Enable VPID { 0 }
Oct 27 21:55:58.714: vmx| WBINVD exiting { 0 }
Oct 27 21:55:58.714: vmx| VM-Exit Controls (0x0003efff00036dff)
Oct 27 21:55:58.714: vmx| Save debug controls { 1 }
Oct 27 21:55:58.714: vmx| Host address-space size {0,1}
Oct 27 21:55:58.714: vmx| Load IA32_PERF_GLOBAL_CTRL { 0 }
Oct 27 21:55:58.714: vmx| Acknowledge interrupt on exit {0,1}
Oct 27 21:55:58.714: vmx| Save IA32_PAT { 0 }
Oct 27 21:55:58.714: vmx| Load IA32_PAT { 0 }
Oct 27 21:55:58.714: vmx| Save IA32_EFER { 0 }
Oct 27 21:55:58.714: vmx| Load IA32_EFER { 0 }
Oct 27 21:55:58.714: vmx| Save VMX-preemption timer { 0 }
Oct 27 21:55:58.714: vmx| VM-Entry Controls (0x00001fff000011ff)
Oct 27 21:55:58.714: vmx| Load debug controls { 1 }
Oct 27 21:55:58.714: vmx| IA-32e mode guest {0,1}
Oct 27 21:55:58.714: vmx| Entry to SMM {0,1}
Oct 27 21:55:58.714: vmx| Deactivate dual-monitor mode {0,1}
Oct 27 21:55:58.714: vmx| Load IA32_PERF_GLOBAL_CTRL { 0 }
Oct 27 21:55:58.714: vmx| Load IA32_PAT { 0 }
Oct 27 21:55:58.714: vmx| Load IA32_EFER { 0 }
Oct 27 21:55:58.714: vmx| VPID and EPT Capabilities (0x0000000000000000)
Oct 27 21:55:58.714: vmx| R=0/W=0/X=1 no
Oct 27 21:55:58.714: vmx| Page-walk length 3 no
Oct 27 21:55:58.714: vmx| EPT memory type WB no
Oct 27 21:55:58.714: vmx| 2MB super-page no
Oct 27 21:55:58.714: vmx| INVEPT support no
Oct 27 21:55:58.714: vmx| Type 1 INVEPT no
Oct 27 21:55:58.714: vmx| Type 2 INVEPT no
Oct 27 21:55:58.714: vmx| INVVPID support no
Oct 27 21:55:58.714: vmx| Type 0 INVVPID no
Oct 27 21:55:58.714: vmx| Type 1 INVVPID no
Oct 27 21:55:58.714: vmx| Type 2 INVVPID no
Oct 27 21:55:58.714: vmx| Type 3 INVVPID no
Oct 27 21:55:58.714: vmx| Miscellaneous VMX Data (0x00000000000403c0)
Oct 27 21:55:58.714: vmx| TSC to preemption timer ratio 0
Oct 27 21:55:58.714: vmx| Activity State HLT yes
Oct 27 21:55:58.714: vmx| Activity State shutdown yes
Oct 27 21:55:58.714: vmx| Activity State wait-for-SIPI yes
Oct 27 21:55:58.714: vmx| CR3 targets supported 4
Oct 27 21:55:58.714: vmx| Maximum MSR list size 512
Oct 27 21:55:58.714: vmx| MSEG revision ID 0
Oct 27 21:55:58.714: vmx| VMX-Fixed Bits in CR0 (0x0000000080000021/0x00000000ffffffff)
Oct 27 21:55:58.714: vmx| Fixed to 0 0xffffffff00000000
Oct 27 21:55:58.714: vmx| Fixed to 1 0x0000000080000021
Oct 27 21:55:58.714: vmx| Variable 0x000000007fffffde
Oct 27 21:55:58.714: vmx| VMX-Fixed Bits in CR4 (0x0000000000002000/0x00000000000027ff)
Oct 27 21:55:58.714: vmx| Fixed to 0 0xffffffffffffd800
Oct 27 21:55:58.714: vmx| Fixed to 1 0x0000000000002000
Oct 27 21:55:58.714: vmx| Variable 0x00000000000007ff
Oct 27 21:55:58.714: vmx| VMCS Enumeration (0x000000000000002c)
Oct 27 21:55:58.714: vmx| Highest index 0x16
Oct 27 21:55:58.751: vmx| XINFO X fd is 73
Oct 27 21:55:58.751: vmx| XINFO depth 24 bpp 32 class 4
Oct 27 21:55:58.754: vmx| Host display topology 1920x1200.
Oct 27 21:55:58.754: vmx| SVGA: Max size 2560x1600
Oct 27 21:55:58.754: vmx| WSSCAN: reserved mem (in MB) min=32 max=5888 recommended=5888
Oct 27 21:55:58.754: vmx| WSSCAN: used rec mem (in MB) 5888
Oct 27 21:55:58.754: vmx| PShare: enabled 1 adaptive 1 local 1 scanRate [16, 400]
Oct 27 21:55:58.754: vmx| WSSCAN: 558665 16391 524288 32768
Oct 27 21:55:58.754: vmx| WSSCAN 1 0 1499136 1548288 1507328 -1 50 0
Oct 27 21:55:58.754: vmx| Host: Disabling thread priority boosting to work around Linux SMP bug.
Oct 27 21:55:58.754: vmx| MXSemaphoreInitFromPipe: eventfd usage: Enabled
Oct 27 21:55:58.755: vmx| LICENSE using: '/usr/lib/vmware/licenses/user/license-ws-70-e2-200904'
Oct 27 21:55:58.755: vmx| Msg_Post: Extend license info
Oct 27 21:55:58.755: vmx| [msg.License.near_expired.express.product_days_url] Your VMware Workstation license will expire in 3 day(s).
Oct 27 21:55:58.755: vmx| Make sure that your computer's date and time are set correctly.
Oct 27 21:55:58.755: vmx| You can go to our web site at "http://vmware.com/info?id=27&build=203739" to obtain a new license.
Oct 27 21:55:58.755: vmx| ----------------------------------------
Oct 27 21:55:58.782: vmx| LOG failed to remove stats/stats32-2 failed: No such file or directory
Oct 27 21:55:58.782: vmx| LOG failed to remove stats/stats64-2 failed: No such file or directory
Oct 27 21:55:58.782: vmx| LOG failed to remove stats32-2 failed: No such file or directory
Oct 27 21:55:58.782: vmx| LOG failed to remove stats64-2 failed: No such file or directory
Oct 27 21:55:58.782: vmx| Host IPI vectors: 0xfc 0xfb
Oct 27 21:55:58.783: vmx| Monitor_PowerOn: HostedVSMP skew tracking is disabled
Oct 27 21:55:58.783: vmx| Monitor_PowerOn: HostedVSMP crosscall yielding is disabled
Oct 27 21:55:58.783: vmx| vmm32-modules: [vmm.vmm32 .data:0x2b000-0x748 .sdata:0x2c000-0x4e4 .statvars:0x2d000-0x3a0 .peer:0x2e000-0x26200 .shared:0x5a000-0x143c0 .bss:0x6f000-0x5de8 .rodata:0x76000-0xb6c0 .text:0x82000-0x5c51d .kstatvars:0x3000-0x0, mmu-nohv.vmm32 .rodata:0x816c0-0x44 .data:0x2b748-0x10 .peer:0x54200-0x5640 .shared:0x6e3c0-0x340 .bss:0x74e00-0x51c .text:0xde520-0xa4e3 .comment:0x40000d5c-0x10e .statvars:0x2000-0x0 .kstatvars:0x2000-0x0 .scb:0x40003cc0-0x180 .shared_meta:0x400042f0-0x330 .peer_meta:0x40000f90-0x1e0 .patchtext:0x40000500-0xb0, pv-none.vmm32 .shared:0x6e700-0x180 .bss:0x75320-0x84 .text:0xe8a04-0xe2 .comment:0x40000e6a-0x48 .shared_meta:0x40004620-0x90, vprobe-none.vmm32 .text:0xe8ae8-0x19 .comment:0x40000eb2-0x12, hv-vt.vmm32 .rodata:0x81704-0x4 .data:0x1000-0x0 .peer:0x1000-0x0 .shared:0x1000-0x0 .bss:0x1000-0x0 .text:0xe8b10-0x10f .comment:0x40000ec4-0x12 .statvars:0x1000-0x0 .kstatvars:0x1000-0x0 .patchtext:0x400005b0-0x10, gphys-sw.vmm32 .peer:0x59840-0x40 .shared:0x6e880-0x80 .bss:0x2000-0x0 .text:0xe8c20-0x7d1 .comment:0x40000ed6-0x12 .scb:0x40003e40-0x60 .shared_meta:0x400046b0-0x240 .peer_meta:0x40001170-0x30, vassert-none.vmm32 .text:0xe93f4-0x39 .comment:0x40000ee8-0x12, vmsafe-none.vmm32 .text:0xe9430-0x1b .comment:0x40000efa-0x12, <MonSrcFile> .rodata:0x81708-0x3b1]
Oct 27 21:55:58.785: vmx| KHZEstimate 2400015
Oct 27 21:55:58.785: vmx| MHZEstimate 2400
Oct 27 21:55:58.785: vmx| NumVCPUs 1
Oct 27 21:55:58.785: vmx| Guest OS (20519) not supported for replay debugging.
Oct 27 21:55:58.786: vmx| PShare: checkRate 16
Oct 27 21:55:58.786: vmx| UUID: SMBIOS UUID is reported as '00 00 00 00 00 00 00 00-00 00 00 1d 7d aa f4 29'.
Oct 27 21:55:58.786: vmx| UUID: location-UUID is 56 4d 7c 46 e8 ce 35 53-99 a6 5d a3 fa 3e 1e 4b
Oct 27 21:55:58.786: vmx| AIOGNRC: numThreads=18 ide=0, scsi=1, passthru=1
Oct 27 21:55:58.786: vmx| WORKER: Creating new group with numThreads=18 (18)
Oct 27 21:55:58.786: vmx| Couldn't init aioLinux: failed to dlopen libaio: libaio.so.1: cannot open shared object file: No such file or directory
Oct 27 21:55:58.786: vmx| CPU0: PMC: IA32, CORE 2 [c:0 f:1 e:1]
Oct 27 21:55:58.786: vmx| CPU1: PMC: IA32, CORE 2 [c:0 f:1 e:1]
Oct 27 21:55:58.786: vmx| CPU2: PMC: IA32, CORE 2 [c:0 f:1 e:1]
Oct 27 21:55:58.786: vmx| CPU3: PMC: IA32, CORE 2 [c:0 f:1 e:1]
Oct 27 21:55:58.821: vmx| Replay State = 0
Oct 27 21:55:58.821: vmx| minDEThreshold: 70
Oct 27 21:55:58.884: vmx| MMC: Initialized PLS=1 PLR=0 PFS=0 TS=1 BS=1 WZ=0 BufM=0 SOR=1 BlkP=32 W=50 PF=2048
Oct 27 21:55:58.884: vmx| MM: Opened paging file, 'Swap0'.
Oct 27 21:55:58.888: vmx| MMCHK: Disabling checks which are not forced, cannot do checks on certain processors.
Oct 27 21:55:58.889: vmx| MStat: Creating Stat vm.uptime
Oct 27 21:55:58.889: vmx| MStat: Creating Stat vm.suspendTime
Oct 27 21:55:58.889: vmx| MStat: Creating Stat vm.powerOnTimeStamp
Oct 27 21:55:58.889: vmx| VMXAIOMGR: Using: simple=Generic unbuf=Generic
Oct 27 21:55:58.895: vmx| VMXVmdb_LoadRawConfig: Loading raw config
Oct 27 21:55:58.899: vmx| DISK: OPEN scsi0:0 '/home/ranguvar/soft/vmware/snowy/snowy.vmdk' persistent R[]
Oct 27 21:55:58.900: vmx| DISKLIB-DSCPTR: Opened [0]: "/dev/sdb" 0 (0x1a)
Oct 27 21:55:58.900: vmx| DISKLIB-LINK : Opened '/home/ranguvar/soft/vmware/snowy/snowy.vmdk' (0x1a): fullDevice, 586070255 sectors / 279.5 GB.
Oct 27 21:55:58.900: vmx| DISKLIB-LIB : Opened "/home/ranguvar/soft/vmware/snowy/snowy.vmdk" (flags 0x1a).
Oct 27 21:55:58.900: vmx| DISK: OPEN '/home/ranguvar/soft/vmware/snowy/snowy.vmdk' Geo (36481/255/63) BIOS Geo (36481/255/63)
Oct 27 21:55:58.946: vmx| TimeTracker: busMultiplier 26
Oct 27 21:55:58.946: vmx| TimeTracker: apicBusHz 92308269
Oct 27 21:55:58.946: vmx| TimeTracker host to guest rate conversion 9573451475526 @ 2400015000Hz -> 9573451475526 @ 2400015000Hz
Oct 27 21:55:58.946: vmx| TimeTracker host to guest rate conversion ((x * 2147483648) >> 31) + 0
Oct 27 21:55:58.947: vmx| USB: Initializing 'Generic' backend
Oct 27 21:55:58.947: vmx| USB: Unable to open "/proc/bus/usb/devices" (No such file or directory).
Oct 27 21:55:58.947: vmx| USB: Initializing 'Virtual Hub' backend
Oct 27 21:55:58.947: vmx| USB: Initializing 'Virtual Mouse' backend
Oct 27 21:55:58.947: vmx| USB: Initializing 'Virtual Keyboard' backend
Oct 27 21:55:58.947: vmx| USB: Initializing 'Virtual Mass Storage' backend
Oct 27 21:55:58.947: vmx| USB: Initializing 'Virtual CCID' backend
Oct 27 21:55:58.947: vmx| USB-CCID: CCID init: could not dlopen /usr/lib/vmware/bin/libdir/lib/libpcsclite.so.1.0.0/libpcsclite.so.1.0.0. (/usr/lib/vmware/bin/libdir/lib/libpcsclite.so.1.0.0/libpcsclite.so.1.0.0: cannot open shared object file: No such file or directory)
Oct 27 21:55:58.947: vmx| CCID init: dlopened /usr/lib/vmware/bin/../lib/libpcsclite.so.1.0.0/libpcsclite.so.1.0.0.
Oct 27 21:55:58.947: vmx| USB-CCID: Could not establish resource manager context for card ops: SCARD_E_NO_SERVICE(0x8010001d).
Oct 27 21:55:58.947: vmx| USB: Unable to initialize 'Virtual CCID' backend
Oct 27 21:55:58.951: vmx| XINFO X fd is 73
Oct 27 21:55:58.951: vmx| XINFO depth 24 bpp 32 class 4
Oct 27 21:55:58.953: vmx| DisplayTopologyInit: Using the NV-Control backend with screen 0.
Oct 27 21:55:58.955: vmx| WORKER: Creating new group with numThreads=1 (19)
Oct 27 21:55:58.955: vmx| MKS: Base polling period is 1000000us
Oct 27 21:55:58.955: vmx| KHBKL: Unable to parse keystring at: ''
Oct 27 21:55:58.955: vmx| MKS REMOTE Loading VNC Configuration from VM config file
Oct 27 21:55:58.956: vmx| VLANCE: send cluster threshold is 80, size = 2 recalcInterval is 2 ticks
Oct 27 21:55:58.956: vmx| VMXNET: send cluster threshold is 80, size = 2 recalcInterval is 2 ticks, dontClusterSize is 128
Oct 27 21:55:58.956: vmx| NetPkt: checksum cycles/kB: C=388 asm1=785 asm2=402
Oct 27 21:55:58.956: vmx| NetPkt: copy and sum cycles/kB: C=432 asm1=792 asm2=413
Oct 27 21:55:58.961: vmx| Chipset version: 0x13
Oct 27 21:55:58.997: vmx| DISKUTIL: ide1:0 : capacity=0
Oct 27 21:55:58.997: vmx| DISKUTIL: ide1:0 : geometry=0/0/0
Oct 27 21:55:58.997: vmx| LSI: vIC initialized 50/4/100/0
Oct 27 21:55:58.998: vmx| SCSI0: UNTAGGED commands will be converted to ORDER tags.
Oct 27 21:55:58.998: vmx| Msg_Hint: msg.hbaHosted.rawDisk (sent)
Oct 27 21:55:58.998: vmx| You are using a SCSI physical disk. SCSI physical disks do not dual-boot well (that is. an already installed operating system is unlikely to boot correctly inside a virtual machine). However, SCSI physical disks should work fine if used exclusively within the virtual machine.
Oct 27 21:55:58.998: vmx| ---------------------------------------
Oct 27 21:55:59.036: vmx| DISKUTIL: scsi0:0 : capacity=586070255
Oct 27 21:55:59.036: vmx| DISKUTIL: scsi0:0 : geometry=36481/255/63
Oct 27 21:55:59.036: vmx| SVGA: Device capabilities 0x003fc3e2
Oct 27 21:55:59.036: vmx| Host display topology 1920x1200.
Oct 27 21:55:59.036: vmx| SVGA: Max size 2560x1600
Oct 27 21:55:59.089: vmx| Host display topology 1920x1200 with 1 displays.
Oct 27 21:55:59.089: vmx| SVGA: Max size 2560x1600
Oct 27 21:55:59.089: vmx| SVGA: FIFO capabilities 0x000000ff
Oct 27 21:55:59.089: vmx| USB: Initializing 'UHCI' host controller
Oct 27 21:55:59.090: vmx| Ethernet0 MAC Address: 00:0c:29:3e:1e:4b
Oct 27 21:55:59.091: vmx| SOUND 959.091339 Alsa library version: 1.0.21a.
Oct 27 21:55:59.091: vmx| USB: Initializing 'EHCI' host controller
Oct 27 21:55:59.139: vmx| MStat: Creating Stat vm.heartbeat
Oct 27 21:55:59.139: vmx| TOOLS Generated SessionId 3589690059855728303
Oct 27 21:55:59.140: vmx| VMXVmdbGuest_GetToolsVersion did nothing; tools version has not yet been initialized
Oct 27 21:55:59.140: vmx| DISKUTIL: scsi0:0 : toolsVersion = 0
Oct 27 21:55:59.140: vmx| DISKUTIL: Offline toolsVersion = 0
Oct 27 21:55:59.140: vmx| VMXVmdbGuest_GetToolsVersion did nothing; tools version has not yet been initialized
Oct 27 21:55:59.140: vmx| TOOLS setting legacy tools version to '0', manifest status is 5
Oct 27 21:55:59.140: vmx| VMXVmdb_SetToolsVersionState: status value set to 'noTools'
Oct 27 21:55:59.140: vmx| VMXVmdb_SetToolsVersionState: status value set to 'noTools'
Oct 27 21:55:59.140: vmx| TOOLS INSTALL initializing state to IDLE on power on.
Oct 27 21:55:59.143: vmx| PTSC to VMI Wallclock (nsec) 9573924041352 @ 2400015000Hz -> 1256694959000000000 @ 1000000000Hz
Oct 27 21:55:59.143: vmx| PTSC to VMI Wallclock (nsec) ((x * 3579117043) >> 33) + 1256690969889915658
Oct 27 21:55:59.143: vmx| PTSC to ParaTime RealCycles 0 @ 2400015000Hz -> 0 @ 2400015000Hz
Oct 27 21:55:59.143: vmx| PTSC to ParaTime RealCycles ((x * 1) >> 0) + 0
Oct 27 21:55:59.143: vmx| ParaTime RealCycles to PTSC 0 @ 2400015000Hz -> 0 @ 2400015000Hz
Oct 27 21:55:59.143: vmx| ParaTime RealCycles to PTSC ((x * 1) >> 0) + 0
Oct 27 21:55:59.143: vmx| memoryHotplug: Current size = 2048MB, Minimum size = 2048MB, Maximum size = 2048MB
Oct 27 21:55:59.143: vmx| memoryHotplug: Entry[0]: 00000000000000A0-00000000000A0000
Oct 27 21:55:59.143: vmx| memoryHotplug: Entry[1]: 00000000001000A0-0000000080000000
Oct 27 21:55:59.144: vmx| guestCpuFeatures = 0x404001fc
Oct 27 21:55:59.145: vmx| guestCPUID vendor: GenuineIntel
Oct 27 21:55:59.145: vmx| guestCPUID name: Intel(R) Core(TM)2 Quad CPU Q6600 @ 2.40GHz
Oct 27 21:55:59.145: vmx| guestCPUID level 00000000, 0: 0x0000000a 0x756e6547 0x6c65746e 0x49656e69
Oct 27 21:55:59.145: vmx| guestCPUID level 00000001, 0: 0x000006fb 0x00010800 0x80002201 0x0febfbff
Oct 27 21:55:59.145: vmx| guestCPUID level 00000002, 0: 0x05b0b101 0x005657f0 0x00000000 0x2cb43049
Oct 27 21:55:59.145: vmx| guestCPUID level 00000003, 0: 0x00000000 0x00000000 0x00000000 0x00000000
Oct 27 21:55:59.145: vmx| guestCPUID level 00000004, 0: 0x0c000121 0x01c0003f 0x0000003f 0x00000001
Oct 27 21:55:59.145: vmx| guestCPUID level 00000005, 0: 0x00000040 0x00000040 0x00000003 0x00000020
Oct 27 21:55:59.145: vmx| guestCPUID level 00000006, 0: 0x00000001 0x00000002 0x00000001 0x00000000
Oct 27 21:55:59.145: vmx| guestCPUID level 00000007, 0: 0x00000000 0x00000000 0x00000000 0x00000000
Oct 27 21:55:59.145: vmx| guestCPUID level 00000008, 0: 0x00000400 0x00000000 0x00000000 0x00000000
Oct 27 21:55:59.145: vmx| guestCPUID level 00000009, 0: 0x00000000 0x00000000 0x00000000 0x00000000
Oct 27 21:55:59.145: vmx| guestCPUID level 0000000a, 0: 0x07280202 0x00000000 0x00000000 0x00000503
Oct 27 21:55:59.145: vmx| guestCPUID level 40000000, 0: 0x40000010 0x61774d56 0x4d566572 0x65726177
Oct 27 21:55:59.145: vmx| guestCPUID level 40000010, 0: 0x00249f0f 0x00016894 0x00000000 0x00000000
Oct 27 21:55:59.145: vmx| guestCPUID level 80000000, 0: 0x80000008 0x00000000 0x00000000 0x00000000
Oct 27 21:55:59.145: vmx| guestCPUID level 80000001, 0: 0x00000000 0x00000000 0x00000001 0x20100800
Oct 27 21:55:59.145: vmx| guestCPUID level 80000002, 0: 0x65746e49 0x2952286c 0x726f4320 0x4d542865
Oct 27 21:55:59.145: vmx| guestCPUID level 80000003, 0: 0x51203229 0x20646175 0x20555043 0x51202020
Oct 27 21:55:59.145: vmx| guestCPUID level 80000004, 0: 0x30303636 0x20402020 0x30342e32 0x007a4847
Oct 27 21:55:59.145: vmx| guestCPUID level 80000005, 0: 0x00000000 0x00000000 0x00000000 0x00000000
Oct 27 21:55:59.145: vmx| guestCPUID level 80000006, 0: 0x00000000 0x00000000 0x10008040 0x00000000
Oct 27 21:55:59.145: vmx| guestCPUID level 80000007, 0: 0x00000000 0x00000000 0x00000000 0x00000000
Oct 27 21:55:59.145: vmx| guestCPUID level 80000008, 0: 0x00003028 0x00000000 0x00000000 0x00000000
Oct 27 21:55:59.146: vmx| BusMemSample: initPercent 75 touched 393216
Oct 27 21:55:59.146: vmx| TOOLS received request in VMX to set option 'enableDnD' -> '1'
Oct 27 21:55:59.146: vmx| TOOLS received request in VMX to set option 'copypaste' -> '1'
Oct 27 21:55:59.147: vmx| Vix: [6272 mainDispatch.c:710]: VMAutomation_PowerOn. Powering on.
Oct 27 21:55:59.147: vmx| VMX_PowerOn: ModuleTable_PowerOn = 1
Oct 27 21:55:59.147: vmx| Setting thread 1 stack size to 2097152.
Oct 27 21:55:59.147: mks| Async MKS thread is alive
Oct 27 21:55:59.147: vmx| Setting thread 4 stack size to 1048576.
Oct 27 21:55:59.147: mks| Connecting to window system.
Oct 27 21:55:59.148: mks| XINFO X fd is 73
Oct 27 21:55:59.148: mks| XINFO depth 24 bpp 32 class 4
Oct 27 21:55:59.151: mks| DisplayTopologyInit: Using the NV-Control backend with screen 0.
Oct 27 21:55:59.151: mks| MKS: Base polling period is 10000us
Oct 27 21:55:59.151: mks| XKeymap_PowerOn: use evdev keycode mapping.
Oct 27 21:55:59.151: mks| rasterops MMXEXT accelerations enabled
Oct 27 21:55:59.196: mks| XInfoFetchModes: host display mode 0: 1920x1200
Oct 27 21:55:59.196: mks| XInfoFetchModes: host display mode 1: 1680x1050
Oct 27 21:55:59.196: mks| XInfoFetchModes: host display mode 2: 1600x1200
Oct 27 21:55:59.196: mks| XInfoFetchModes: host display mode 3: 1440x900
Oct 27 21:55:59.196: mks| XInfoFetchModes: host display mode 4: 1400x1050
Oct 27 21:55:59.196: mks| XInfoFetchModes: host display mode 5: 1280x1024
Oct 27 21:55:59.196: mks| XInfoFetchModes: host display mode 6: 1280x960
Oct 27 21:55:59.196: mks| XInfoFetchModes: host display mode 7: 1280x800
Oct 27 21:55:59.196: mks| XInfoFetchModes: host display mode 8: 1152x864
Oct 27 21:55:59.196: mks| XInfoFetchModes: host display mode 9: 1152x720
Oct 27 21:55:59.196: mks| XInfoFetchModes: host display mode 10: 1024x768
Oct 27 21:55:59.196: mks| XInfoFetchModes: host display mode 11: 960x720
Oct 27 21:55:59.196: mks| XInfoFetchModes: host display mode 12: 928x696
Oct 27 21:55:59.197: mks| XInfoFetchModes: host display mode 13: 896x672
Oct 27 21:55:59.197: mks| XInfoFetchModes: host display mode 14: 832x624
Oct 27 21:55:59.197: mks| XInfoFetchModes: host display mode 15: 800x600
Oct 27 21:55:59.197: mks| XInfoFetchModes: host display mode 16: 720x400
Oct 27 21:55:59.197: mks| XInfoFetchModes: host display mode 17: 700x525
Oct 27 21:55:59.197: mks| XInfoFetchModes: host display mode 18: 640x512
Oct 27 21:55:59.197: mks| XInfoFetchModes: host display mode 19: 640x480
Oct 27 21:55:59.197: mks| XInfoFetchModes: host display mode 20: 640x400
Oct 27 21:55:59.197: mks| XInfoFetchModes: host display mode 21: 640x350
Oct 27 21:55:59.197: mks| XInfoFetchModes: host display mode 22: 576x432
Oct 27 21:55:59.197: mks| XInfoFetchModes: host display mode 23: 512x384
Oct 27 21:55:59.197: mks| XInfoFetchModes: host display mode 24: 416x312
Oct 27 21:55:59.197: mks| XInfoFetchModes: host display mode 25: 400x300
Oct 27 21:55:59.197: mks| XInfoFetchModes: host display mode 26: 360x200
Oct 27 21:55:59.197: mks| XInfoFetchModes: host display mode 27: 320x240
Oct 27 21:55:59.197: mks| XInfoFetchModes: host display mode 28: 320x200
Oct 27 21:55:59.197: mks| XInfoFetchModes: host display mode 29: 320x175
Oct 27 21:55:59.197: mks| XI major version 2, minor version 0
Oct 27 21:55:59.198: vmx| USB: Connecting device 0x600000010e0f0006
Oct 27 21:55:59.202: vmx| DnDRegisterRpc: DnD rpc already set to 1
Oct 27 21:55:59.202: vmx| CopyPasteRegisterRpc: already set to 1
Oct 27 21:55:59.202: vcpu-0| APIC: version = 0x14, max LVT = 5
Oct 27 21:55:59.202: vcpu-0| APIC: LDR = 0x2000000, DFR = 0xffffffff
Oct 27 21:55:59.207: vcpu-0| CPU reset: hard (mode 2)
Oct 27 21:55:59.207: vcpu-0| memoryHotplug: Entry[0]: 00000000000000A0-00000000000A0000
Oct 27 21:55:59.207: vcpu-0| memoryHotplug: Entry[1]: 00000000001000A0-0000000080000000
Oct 27 21:55:59.207: vcpu-0| PIIX4: PM Resuming from suspend type 0x0.
Oct 27 21:55:59.207: vcpu-0| CDROM: Connecting ide1:0 to '/home/ranguvar/soft/osen/macos/macos_10.6.0.iso'. img=1 raw=0 remote=0
Oct 27 21:55:59.207: vcpu-0| Vix: [6276 mainDispatch.c:3485]: VMAutomation_ReportPowerOpFinished: statevar=0, newAppState=1872, success=1
Oct 27 21:55:59.208: vcpu-0| Vix: [6276 mainDispatch.c:3406]: VMAutomationReportPowerStateChange: Reporting power state change (opcode=0, err=0).
Oct 27 21:55:59.208: vcpu-0| Vix: [6276 mainDispatch.c:3406]: VMAutomationReportPowerStateChange: Reporting power state change (opcode=2, err=0).
Oct 27 21:55:59.208: vcpu-0| Transitioned vmx/execState/val to poweredOn
Oct 27 21:55:59.210: mks| HostOps hideCursor before defineCursor!
Oct 27 21:55:59.252: vmx| VMXVmdb_LoadRawConfig: Loading raw config
Oct 27 21:55:59.352: mks| KHBKL: Unable to parse keystring at: ''
Oct 27 21:55:59.355: vcpu-0| sz=2953184
Oct 27 21:55:59.357: vcpu-0| vmm32 initialized: Releasebuild-203739. cflags: 0x00000000.00001000.00042000.00040003
Oct 27 21:55:59.359: vcpu-0| MonitorInitNumaUnmapVMM32
Oct 27 21:55:59.377: vcpu-0| Monitor64_PowerOn()
Oct 27 21:55:59.418: vcpu-0| vmm64-modules: [vmm.vmm64 .data:0xfffffffffc051000-0xc00 .sdata:0xfffffffffc052000-0x9b8 .statvars:0xfffffffffc053000-0x1f8 .peer:0xfffffffffc054000-0x26200 .shared:0xfffffffffc080000-0x143c0 .bss:0xfffffffffc095000-0xb980 .rodata:0xfffffffffc200000-0x14de0 .text:0xfffffffffc216000-0x6dede .kstatvars:0x3000-0x0 .eh_frame:0xfffffffffc29f000-0x13a88, mmu-scratchas.vmm64 .rodata:0xfffffffffc214de0-0xd2 .data:0xfffffffffc051c00-0x14 .peer:0xfffffffffc07a200-0x5640 .shared:0xfffffffffc0943c0-0x340 .bss:0xfffffffffc0a0980-0x950 .text:0xfffffffffc283ee0-0xb0f1 .statvars:0x2000-0x0 .kstatvars:0x2000-0x0 .scb:0x3cc0-0x180 .shared_meta:0x42f0-0x330 .peer_meta:0xf90-0x1e0 .eh_frame:0xfffffffffc2b2a88-0x1c70 .comment:0xe34-0x10e .patchtext:0x3e0-0xa0, pv-none.vmm64 .shared:0xfffffffffc094700-0x180 .bss:0x1000-0x0 .text:0xfffffffffc28efd4-0x4 .shared_meta:0x4620-0x90 .eh_frame:0xfffffffffc2b46f8-0x48 .comment:0xf42-0x48, vprobe-none.vmm64 .text:0xfffffffffc28efd8-0x1a .eh_frame:0xfffffffffc2b4740-0xf0 .comment:0xf8a-0x12, hv-vt.vmm64 .rodata:0xfffffffffc214ec0-0x7c4 .data:0xfffffffffc051c20-0x30 .peer:0x2000-0x0 .shared:0x2000-0x0 .bss:0xfffffffffc0a12e0-0x12b70 .text:0xfffffffffc28f000-0xdd8d .statvars:0x2000-0x0 .kstatvars:0x2000-0x0 .eh_frame:0xfffffffffc2b4830-0x26a0 .comment:0xf9c-0x5a .patchtext:0x480-0x10, gphys-sw.vmm64 .rodata:0xfffffffffc215688-0x29 .data:0x1000-0x0 .peer:0xfffffffffc07f840-0x40 .shared:0xfffffffffc094880-0x80 .bss:0xfffffffffc0b3e50-0x10 .text:0xfffffffffc29cd90-0x158a .statvars:0x1000-0x0 .kstatvars:0x1000-0x0 .scb:0x3e40-0x60 .shared_meta:0x46b0-0x240 .peer_meta:0x1170-0x30 .eh_frame:0xfffffffffc2b6ed0-0x460 .comment:0xff6-0x12, vassert-none.vmm64 .text:0xfffffffffc29e31c-0x3b .eh_frame:0xfffffffffc2b7330-0xc0 .comment:0x1008-0x12, vmsafe-none.vmm64 .text:0xfffffffffc29e358-0x13 .eh_frame:0xfffffffffc2b73f0-0xd8 .comment:0x101a-0x12, <MonSrcFile> .rodata:0xfffffffffc2156b1-0x3eb| 0x5a000]
Oct 27 21:55:59.422: vcpu-0| APIC: version = 0x14, max LVT = 5
Oct 27 21:55:59.422: vcpu-0| APIC: LDR = 0x1000000, DFR = 0xffffffff
Oct 27 21:55:59.423: vcpu-0| Intel VT: FlexPriority enabled.
Oct 27 21:55:59.423: vcpu-0| HV enabled
Oct 27 21:55:59.426: vcpu-0| VMM64 initialized
Oct 27 21:55:59.431: vcpu-0| MonitorInitNumaUnmapVMM64
Oct 27 21:55:59.782: vcpu-0| SVGA: Registering MemSpace at 0xd0000000(0x0) and 0xd8000000(0x0)
Oct 27 21:55:59.787: vcpu-0| SVGA: Unregistering MemSpace at 0xd0000000(0xd0000000) and 0xd8000000(0xd8000000)
Oct 27 21:56:00.000: vcpu-0| SVGA: Registering MemSpace at 0xd0000000(0xd0000000) and 0xd8000000(0xd8000000)
Oct 27 21:56:00.007: vcpu-0| SVGA: Unregistering MemSpace at 0xd0000000(0xd0000000) and 0xd8000000(0xd8000000)
Oct 27 21:56:00.010: vcpu-0| SVGA: Registering IOSpace at 0x10d0
Oct 27 21:56:00.011: vcpu-0| SVGA: Registering MemSpace at 0xd0000000(0xd0000000) and 0xd8000000(0xd8000000)
Oct 27 21:56:00.017: vcpu-0| PCIBridge4: ISA/VGA decoding enabled (ctrl 0004)
Oct 27 21:56:00.018: vcpu-0| pciBridge4:1: ISA/VGA decoding enabled (ctrl 0004)
Oct 27 21:56:00.019: vcpu-0| pciBridge4:2: ISA/VGA decoding enabled (ctrl 0004)
Oct 27 21:56:00.020: vcpu-0| pciBridge4:3: ISA/VGA decoding enabled (ctrl 0004)
Oct 27 21:56:00.022: vcpu-0| pciBridge4:4: ISA/VGA decoding enabled (ctrl 0004)
Oct 27 21:56:00.023: vcpu-0| pciBridge4:5: ISA/VGA decoding enabled (ctrl 0004)
Oct 27 21:56:00.024: vcpu-0| pciBridge4:6: ISA/VGA decoding enabled (ctrl 0004)
Oct 27 21:56:00.026: vcpu-0| pciBridge4:7: ISA/VGA decoding enabled (ctrl 0004)
Oct 27 21:56:00.027: vcpu-0| PCIBridge5: ISA/VGA decoding enabled (ctrl 0004)
Oct 27 21:56:00.028: vcpu-0| pciBridge5:1: ISA/VGA decoding enabled (ctrl 0004)
Oct 27 21:56:00.029: vcpu-0| pciBridge5:2: ISA/VGA decoding enabled (ctrl 0004)
Oct 27 21:56:00.031: vcpu-0| pciBridge5:3: ISA/VGA decoding enabled (ctrl 0004)
Oct 27 21:56:00.032: vcpu-0| pciBridge5:4: ISA/VGA decoding enabled (ctrl 0004)
Oct 27 21:56:00.033: vcpu-0| pciBridge5:5: ISA/VGA decoding enabled (ctrl 0004)
Oct 27 21:56:00.034: vcpu-0| pciBridge5:6: ISA/VGA decoding enabled (ctrl 0004)
Oct 27 21:56:00.036: vcpu-0| pciBridge5:7: ISA/VGA decoding enabled (ctrl 0004)
Oct 27 21:56:00.037: vcpu-0| PCIBridge6: ISA/VGA decoding enabled (ctrl 0004)
Oct 27 21:56:00.038: vcpu-0| pciBridge6:1: ISA/VGA decoding enabled (ctrl 0004)
Oct 27 21:56:00.039: vcpu-0| pciBridge6:2: ISA/VGA decoding enabled (ctrl 0004)
Oct 27 21:56:00.041: vcpu-0| pciBridge6:3: ISA/VGA decoding enabled (ctrl 0004)
Oct 27 21:56:00.042: vcpu-0| pciBridge6:4: ISA/VGA decoding enabled (ctrl 0004)
Oct 27 21:56:00.043: vcpu-0| pciBridge6:5: ISA/VGA decoding enabled (ctrl 0004)
Oct 27 21:56:00.044: vcpu-0| pciBridge6:6: ISA/VGA decoding enabled (ctrl 0004)
Oct 27 21:56:00.046: vcpu-0| pciBridge6:7: ISA/VGA decoding enabled (ctrl 0004)
Oct 27 21:56:00.047: vcpu-0| PCIBridge7: ISA/VGA decoding enabled (ctrl 0004)
Oct 27 21:56:00.048: vcpu-0| pciBridge7:1: ISA/VGA decoding enabled (ctrl 0004)
Oct 27 21:56:00.050: vcpu-0| pciBridge7:2: ISA/VGA decoding enabled (ctrl 0004)
Oct 27 21:56:00.051: vcpu-0| pciBridge7:3: ISA/VGA decoding enabled (ctrl 0004)
Oct 27 21:56:00.052: vcpu-0| pciBridge7:4: ISA/VGA decoding enabled (ctrl 0004)
Oct 27 21:56:00.053: vcpu-0| pciBridge7:5: ISA/VGA decoding enabled (ctrl 0004)
Oct 27 21:56:00.055: vcpu-0| pciBridge7:6: ISA/VGA decoding enabled (ctrl 0004)
Oct 27 21:56:00.056: vcpu-0| pciBridge7:7: ISA/VGA decoding enabled (ctrl 0004)
Oct 27 21:56:00.265: vcpu-0| PCIBridge4: ISA/VGA decoding enabled (ctrl 0004)
Oct 27 21:56:00.267: vcpu-0| pciBridge4:1: ISA/VGA decoding enabled (ctrl 0004)
Oct 27 21:56:00.268: vcpu-0| pciBridge4:2: ISA/VGA decoding enabled (ctrl 0004)
Oct 27 21:56:00.269: vcpu-0| pciBridge4:3: ISA/VGA decoding enabled (ctrl 0004)
Oct 27 21:56:00.271: vcpu-0| pciBridge4:4: ISA/VGA decoding enabled (ctrl 0004)
Oct 27 21:56:00.272: vcpu-0| pciBridge4:5: ISA/VGA decoding enabled (ctrl 0004)
Oct 27 21:56:00.273: vcpu-0| pciBridge4:6: ISA/VGA decoding enabled (ctrl 0004)
Oct 27 21:56:00.274: vcpu-0| pciBridge4:7: ISA/VGA decoding enabled (ctrl 0004)
Oct 27 21:56:00.276: vcpu-0| PCIBridge5: ISA/VGA decoding enabled (ctrl 0004)
Oct 27 21:56:00.277: vcpu-0| pciBridge5:1: ISA/VGA decoding enabled (ctrl 0004)
Oct 27 21:56:00.278: vcpu-0| pciBridge5:2: ISA/VGA decoding enabled (ctrl 0004)
Oct 27 21:56:00.280: vcpu-0| pciBridge5:3: ISA/VGA decoding enabled (ctrl 0004)
Oct 27 21:56:00.281: vcpu-0| pciBridge5:4: ISA/VGA decoding enabled (ctrl 0004)
Oct 27 21:56:00.282: vcpu-0| pciBridge5:5: ISA/VGA decoding enabled (ctrl 0004)
Oct 27 21:56:00.283: vcpu-0| pciBridge5:6: ISA/VGA decoding enabled (ctrl 0004)
Oct 27 21:56:00.285: vcpu-0| pciBridge5:7: ISA/VGA decoding enabled (ctrl 0004)
Oct 27 21:56:00.286: vcpu-0| PCIBridge6: ISA/VGA decoding enabled (ctrl 0004)
Oct 27 21:56:00.287: vcpu-0| pciBridge6:1: ISA/VGA decoding enabled (ctrl 0004)
Oct 27 21:56:00.288: vcpu-0| pciBridge6:2: ISA/VGA decoding enabled (ctrl 0004)
Oct 27 21:56:00.290: vcpu-0| pciBridge6:3: ISA/VGA decoding enabled (ctrl 0004)
Oct 27 21:56:00.291: vcpu-0| pciBridge6:4: ISA/VGA decoding enabled (ctrl 0004)
Oct 27 21:56:00.292: vcpu-0| pciBridge6:5: ISA/VGA decoding enabled (ctrl 0004)
Oct 27 21:56:00.293: vcpu-0| pciBridge6:6: ISA/VGA decoding enabled (ctrl 0004)
Oct 27 21:56:00.295: vcpu-0| pciBridge6:7: ISA/VGA decoding enabled (ctrl 0004)
Oct 27 21:56:00.296: vcpu-0| PCIBridge7: ISA/VGA decoding enabled (ctrl 0004)
Oct 27 21:56:00.297: vcpu-0| pciBridge7:1: ISA/VGA decoding enabled (ctrl 0004)
Oct 27 21:56:00.298: vcpu-0| pciBridge7:2: ISA/VGA decoding enabled (ctrl 0004)
Oct 27 21:56:00.300: vcpu-0| pciBridge7:3: ISA/VGA decoding enabled (ctrl 0004)
Oct 27 21:56:00.301: vcpu-0| pciBridge7:4: ISA/VGA decoding enabled (ctrl 0004)
Oct 27 21:56:00.302: vcpu-0| pciBridge7:5: ISA/VGA decoding enabled (ctrl 0004)
Oct 27 21:56:00.303: vcpu-0| pciBridge7:6: ISA/VGA decoding enabled (ctrl 0004)
Oct 27 21:56:00.305: vcpu-0| pciBridge7:7: ISA/VGA decoding enabled (ctrl 0004)
Oct 27 21:56:00.313: vcpu-0| DISKUTIL: scsi0:0 : geometry=36481/255/63
Oct 27 21:56:00.313: vcpu-0| DISKUTIL: scsi0:0 : capacity=586070255
Oct 27 21:56:00.314: vcpu-0| SCSI0: RESET BUS
Oct 27 21:56:00.499: vcpu-0| BIOS-UUID is 56 4d 7c 46 e8 ce 35 53-99 a6 5d a3 fa 3e 1e 4b
Oct 27 21:56:00.751: mks| HostOps hideCursor before defineCursor!
Oct 27 21:56:00.772: vcpu-0| TOOLS INSTALL entering BEGINNING state.
Oct 27 21:56:00.772: vcpu-0| TOOLS INSTALL Scanning device ide0:0 ((null)).
Oct 27 21:56:00.772: vcpu-0| TOOLS INSTALL Scanning device ide0:1 ((null)).
Oct 27 21:56:00.772: vcpu-0| TOOLS INSTALL Scanning device ide1:0 (/home/ranguvar/soft/osen/macos/macos_10.6.0.iso).
Oct 27 21:56:00.772: vcpu-0| TOOLS INSTALL Scanning device ide1:1 ((null)).
Oct 27 21:56:00.772: vcpu-0| TOOLS INSTALL Attempting image mount.
Oct 27 21:56:00.772: vcpu-0| TOOLS INSTALL using device 'ide1:0'.
Oct 27 21:56:00.864: vcpu-0| CDROM: Disconnected cdrom: ide1:0
Oct 27 21:56:00.869: vcpu-0| VMXVmdb_LoadRawConfig: Loading raw config
Oct 27 21:56:00.902: vcpu-0| TOOLS INSTALL entering INSERTED state.
Oct 27 21:56:00.902: vcpu-0| CDROM: Connecting ide1:0 to '/usr/lib/vmware/isoimages/darwin.iso'. img=1 raw=0 remote=0
Oct 27 21:56:00.902: vcpu-0| CDROM: Reset guest state on ide1:0. Invalidating last disconnect time.
Oct 27 21:56:00.941: vcpu-0| TOOLS INSTALL attempted to begin tools install while tools image was not in the correct state (idle). Ignoring...
Oct 27 21:56:00.942: vcpu-0| TOOLS INSTALL attempted to begin tools install while tools image was not in the correct state (idle). Ignoring...
Oct 27 21:56:01.225: vcpu-0| CDROM: switching CDROM state from 5 to 1 using error sense key.
Oct 27 21:56:01.225: vcpu-0| CDROM ide1:0: CMD 0x43 (READ TOC) FAILED (key 0x6 asc 0x28 ascq 0)
Oct 27 21:56:01.509: vcpu-0| Setting thread 36 stack size to 1048576.
Oct 27 21:56:01.509: vcpu-0| DMG_Open: Not an unencrypted .dmg file (footer signature 0x00000000).
Oct 27 21:56:01.509: vcpu-0| DMG_Close: max cached entries 8
Oct 27 21:56:01.999: vmx| CDROM: Guest eject on ide1:0. Cancelling tools install.
Oct 27 21:56:01.999: vmx| TOOLS INSTALL cancelling tools installation.
Oct 27 21:56:01.999: vmx| CDROM: Disconnected cdrom: ide1:0
Oct 27 21:56:01.999: vmx| TOOLS INSTALL entering ENDING state.
Oct 27 21:56:02.004: vmx| VMXVmdb_LoadRawConfig: Loading raw config
Oct 27 21:56:02.038: vmx| CDROM: Connecting ide1:0 to '/home/ranguvar/soft/osen/macos/macos_10.6.0.iso'. img=1 raw=0 remote=0
Oct 27 21:56:02.038: vmx| TOOLS INSTALL entering IDLE state.
Oct 27 21:56:02.073: vcpu-0| CDROM: switching CDROM state from 2 to 3 using error sense key.
Oct 27 21:56:02.073: vcpu-0| VIDE: ATAPI 0x28 Failed: key 0x2, asc 0x3a, ascq 0x2
Oct 27 21:56:02.074: vcpu-0| VIDE: ATAPI 0x28 Failed: key 0x2, asc 0x3a, ascq 0x0
Oct 27 21:56:02.074: vcpu-0| VIDE: ATAPI 0x28 Failed: key 0x2, asc 0x3a, ascq 0x0
Oct 27 21:56:02.074: vcpu-0| VIDE: ATAPI 0x28 Failed: key 0x2, asc 0x3a, ascq 0x0
Oct 27 21:56:02.074: vcpu-0| VIDE: ATAPI 0x28 Failed: key 0x2, asc 0x3a, ascq 0x0
Oct 27 21:56:02.074: vcpu-0| VIDE: ATAPI 0x28 Failed: key 0x2, asc 0x3a, ascq 0x0
Oct 27 21:56:02.074: vcpu-0| VIDE: ATAPI 0x28 Failed: key 0x2, asc 0x3a, ascq 0x0
Oct 27 21:56:02.075: vcpu-0| VIDE: ATAPI 0x28 Failed: key 0x2, asc 0x3a, ascq 0x0
Oct 27 21:56:02.075: vcpu-0| VIDE: ATAPI 0x28 Failed: key 0x2, asc 0x3a, ascq 0x0
Oct 27 21:56:02.075: vcpu-0| VIDE: ATAPI 0x28 Failed: key 0x2, asc 0x3a, ascq 0x0
Oct 27 21:56:02.075: vcpu-0| VIDE: ATAPI 0x28 Failed: key 0x2, asc 0x3a, ascq 0x0
Oct 27 21:56:02.075: vcpu-0| VIDE: ATAPI 0x28 Failed: key 0x2, asc 0x3a, ascq 0x0
Oct 27 21:56:02.076: vcpu-0| VIDE: ATAPI 0x28 Failed: key 0x2, asc 0x3a, ascq 0x0
Oct 27 21:56:02.076: vcpu-0| VIDE: ATAPI 0x28 Failed: key 0x2, asc 0x3a, ascq 0x0
Oct 27 21:56:02.076: vcpu-0| VIDE: ATAPI 0x28 Failed: key 0x2, asc 0x3a, ascq 0x0
Oct 27 21:56:02.076: vcpu-0| VIDE: ATAPI 0x28 Failed: key 0x2, asc 0x3a, ascq 0x0
Oct 27 21:56:02.076: vcpu-0| VIDE: ATAPI 0x28 Failed: key 0x2, asc 0x3a, ascq 0x0
Oct 27 21:56:02.076: vcpu-0| VIDE: ATAPI 0x28 Failed: key 0x2, asc 0x3a, ascq 0x0
Oct 27 21:56:02.077: vcpu-0| VIDE: ATAPI 0x28 Failed: key 0x2, asc 0x3a, ascq 0x0
Oct 27 21:56:02.077: vcpu-0| VIDE: ATAPI 0x28 Failed: key 0x2, asc 0x3a, ascq 0x0
Oct 27 21:56:02.077: vcpu-0| VIDE: ATAPI 0x28 Failed: key 0x2, asc 0x3a, ascq 0x0
Oct 27 21:56:02.077: vcpu-0| VIDE: ATAPI 0x28 Failed: key 0x2, asc 0x3a, ascq 0x0
Oct 27 21:56:02.077: vcpu-0| VIDE: ATAPI 0x28 Failed: key 0x2, asc 0x3a, ascq 0x0
Oct 27 21:56:02.078: vcpu-0| VIDE: ATAPI 0x28 Failed: key 0x2, asc 0x3a, ascq 0x0
Oct 27 21:56:02.078: vcpu-0| VIDE: ATAPI 0x28 Failed: key 0x2, asc 0x3a, ascq 0x0
Oct 27 21:56:02.078: vcpu-0| VIDE: ATAPI 0x28 Failed: key 0x2, asc 0x3a, ascq 0x0
Oct 27 21:56:02.078: vcpu-0| VIDE: ATAPI 0x28 Failed: key 0x2, asc 0x3a, ascq 0x0
Oct 27 21:56:02.078: vcpu-0| VIDE: ATAPI 0x28 Failed: key 0x2, asc 0x3a, ascq 0x0
Oct 27 21:56:02.078: vcpu-0| VIDE: ATAPI 0x28 Failed: key 0x2, asc 0x3a, ascq 0x0
Oct 27 21:56:02.078: vcpu-0| VIDE: ATAPI 0x28 Failed: key 0x2, asc 0x3a, ascq 0x0
Oct 27 21:56:02.079: vcpu-0| VIDE: ATAPI 0x28 Failed: key 0x2, asc 0x3a, ascq 0x0
Oct 27 21:56:02.079: vcpu-0| VIDE: ATAPI 0x28 Failed: key 0x2, asc 0x3a, ascq 0x0
Oct 27 21:56:02.079: vcpu-0| VIDE: ATAPI 0x28 Failed: key 0x2, asc 0x3a, ascq 0x0
Oct 27 21:56:02.079: vcpu-0| VIDE: ATAPI 0x28 Failed: key 0x2, asc 0x3a, ascq 0x0
Oct 27 21:56:02.079: vcpu-0| VIDE: ATAPI 0x28 Failed: key 0x2, asc 0x3a, ascq 0x0
Oct 27 21:56:02.080: vcpu-0| VIDE: ATAPI 0x28 Failed: key 0x2, asc 0x3a, ascq 0x0
Oct 27 21:56:02.080: vcpu-0| VIDE: ATAPI 0x28 Failed: key 0x2, asc 0x3a, ascq 0x0
Oct 27 21:56:02.080: vcpu-0| VIDE: ATAPI 0x28 Failed: key 0x2, asc 0x3a, ascq 0x0
Oct 27 21:56:02.080: vcpu-0| VIDE: ATAPI 0x28 Failed: key 0x2, asc 0x3a, ascq 0x0
Oct 27 21:56:02.080: vcpu-0| VIDE: ATAPI 0x28 Failed: key 0x2, asc 0x3a, ascq 0x0
Oct 27 21:56:02.081: vcpu-0| VIDE: ATAPI 0x28 Failed: key 0x2, asc 0x3a, ascq 0x0
Oct 27 21:56:02.081: vcpu-0| VIDE: ATAPI 0x28 Failed: key 0x2, asc 0x3a, ascq 0x0
Oct 27 21:56:02.081: vcpu-0| VIDE: ATAPI 0x28 Failed: key 0x2, asc 0x3a, ascq 0x0
Oct 27 21:56:02.081: vcpu-0| VIDE: ATAPI 0x28 Failed: key 0x2, asc 0x3a, ascq 0x0
Oct 27 21:56:02.081: vcpu-0| VIDE: ATAPI 0x28 Failed: key 0x2, asc 0x3a, ascq 0x0
Oct 27 21:56:02.081: vcpu-0| VIDE: ATAPI 0x28 Failed: key 0x2, asc 0x3a, ascq 0x0
Oct 27 21:56:02.081: vcpu-0| VIDE: ATAPI 0x28 Failed: key 0x2, asc 0x3a, ascq 0x0
Oct 27 21:56:02.082: vcpu-0| VIDE: ATAPI 0x28 Failed: key 0x2, asc 0x3a, ascq 0x0
Oct 27 21:56:02.082: vcpu-0| VIDE: ATAPI 0x28 Failed: key 0x2, asc 0x3a, ascq 0x0
Oct 27 21:56:02.082: vcpu-0| VIDE: ATAPI 0x28 Failed: key 0x2, asc 0x3a, ascq 0x0
Oct 27 21:56:02.082: vcpu-0| VIDE: ATAPI 0x28 Failed: key 0x2, asc 0x3a, ascq 0x0
Oct 27 21:56:02.082: vcpu-0| VIDE: ATAPI 0x28 Failed: key 0x2, asc 0x3a, ascq 0x0
Oct 27 21:56:02.083: vcpu-0| VIDE: ATAPI 0x28 Failed: key 0x2, asc 0x3a, ascq 0x0
Oct 27 21:56:02.083: vcpu-0| VIDE: ATAPI 0x28 Failed: key 0x2, asc 0x3a, ascq 0x0
Oct 27 21:56:02.083: vcpu-0| VIDE: ATAPI 0x28 Failed: key 0x2, asc 0x3a, ascq 0x0
Oct 27 21:56:02.083: vcpu-0| VIDE: ATAPI 0x28 Failed: key 0x2, asc 0x3a, ascq 0x0
Oct 27 21:56:02.083: vcpu-0| VIDE: ATAPI 0x28 Failed: key 0x2, asc 0x3a, ascq 0x0
Oct 27 21:56:02.083: vcpu-0| VIDE: ATAPI 0x28 Failed: key 0x2, asc 0x3a, ascq 0x0
Oct 27 21:56:02.084: vcpu-0| VIDE: ATAPI 0x28 Failed: key 0x2, asc 0x3a, ascq 0x0
Oct 27 21:56:02.084: vcpu-0| VIDE: ATAPI 0x28 Failed: key 0x2, asc 0x3a, ascq 0x0
Oct 27 21:56:02.084: vcpu-0| VIDE: ATAPI 0x28 Failed: key 0x2, asc 0x3a, ascq 0x0
Oct 27 21:56:02.084: vcpu-0| VIDE: ATAPI 0x28 Failed: key 0x2, asc 0x3a, ascq 0x0
Oct 27 21:56:02.084: vcpu-0| VIDE: ATAPI 0x28 Failed: key 0x2, asc 0x3a, ascq 0x0
Oct 27 21:56:02.084: vcpu-0| VIDE: ATAPI 0x28 Failed: key 0x2, asc 0x3a, ascq 0x0
Oct 27 21:56:02.085: vcpu-0| VIDE: ATAPI 0x28 Failed: key 0x2, asc 0x3a, ascq 0x0
Oct 27 21:56:02.085: vcpu-0| VIDE: ATAPI 0x28 Failed: key 0x2, asc 0x3a, ascq 0x0
Oct 27 21:56:02.085: vcpu-0| VIDE: ATAPI 0x28 Failed: key 0x2, asc 0x3a, ascq 0x0
Oct 27 21:56:02.085: vcpu-0| VIDE: ATAPI 0x28 Failed: key 0x2, asc 0x3a, ascq 0x0
Oct 27 21:56:02.085: vcpu-0| VIDE: ATAPI 0x28 Failed: key 0x2, asc 0x3a, ascq 0x0
Oct 27 21:56:02.085: vcpu-0| VIDE: ATAPI 0x28 Failed: key 0x2, asc 0x3a, ascq 0x0
Oct 27 21:56:02.086: vcpu-0| VIDE: ATAPI 0x28 Failed: key 0x2, asc 0x3a, ascq 0x0
Oct 27 21:56:02.086: vcpu-0| VIDE: ATAPI 0x28 Failed: key 0x2, asc 0x3a, ascq 0x0
Oct 27 21:56:02.086: vcpu-0| VIDE: ATAPI 0x28 Failed: key 0x2, asc 0x3a, ascq 0x0
Oct 27 21:56:02.086: vcpu-0| VIDE: ATAPI 0x28 Failed: key 0x2, asc 0x3a, ascq 0x0
Oct 27 21:56:02.086: vcpu-0| VIDE: ATAPI 0x28 Failed: key 0x2, asc 0x3a, ascq 0x0
Oct 27 21:56:02.086: vcpu-0| VIDE: ATAPI 0x28 Failed: key 0x2, asc 0x3a, ascq 0x0
Oct 27 21:56:02.087: vcpu-0| VIDE: ATAPI 0x28 Failed: key 0x2, asc 0x3a, ascq 0x0
Oct 27 21:56:02.087: vcpu-0| VIDE: ATAPI 0x28 Failed: key 0x2, asc 0x3a, ascq 0x0
Oct 27 21:56:02.087: vcpu-0| VIDE: ATAPI 0x28 Failed: key 0x2, asc 0x3a, ascq 0x0
Oct 27 21:56:02.087: vcpu-0| VIDE: ATAPI 0x28 Failed: key 0x2, asc 0x3a, ascq 0x0
Oct 27 21:56:02.087: vcpu-0| VIDE: ATAPI 0x28 Failed: key 0x2, asc 0x3a, ascq 0x0
Oct 27 21:56:02.088: vcpu-0| VIDE: ATAPI 0x28 Failed: key 0x2, asc 0x3a, ascq 0x0
Oct 27 21:56:02.088: vcpu-0| VIDE: ATAPI 0x28 Failed: key 0x2, asc 0x3a, ascq 0x0
Oct 27 21:56:02.088: vcpu-0| VIDE: ATAPI 0x28 Failed: key 0x2, asc 0x3a, ascq 0x0
Oct 27 21:56:02.088: vcpu-0| VIDE: ATAPI 0x28 Failed: key 0x2, asc 0x3a, ascq 0x0
Oct 27 21:56:02.088: vcpu-0| VIDE: ATAPI 0x28 Failed: key 0x2, asc 0x3a, ascq 0x0
Oct 27 21:56:02.088: vcpu-0| VIDE: ATAPI 0x28 Failed: key 0x2, asc 0x3a, ascq 0x0
Oct 27 21:56:02.089: vcpu-0| VIDE: ATAPI 0x28 Failed: key 0x2, asc 0x3a, ascq 0x0
Oct 27 21:56:02.089: vcpu-0| VIDE: ATAPI 0x28 Failed: key 0x2, asc 0x3a, ascq 0x0
Oct 27 21:56:02.089: vcpu-0| VIDE: ATAPI 0x28 Failed: key 0x2, asc 0x3a, ascq 0x0
Oct 27 21:56:02.089: vcpu-0| VIDE: ATAPI 0x28 Failed: key 0x2, asc 0x3a, ascq 0x0
Oct 27 21:56:02.089: vcpu-0| VIDE: ATAPI 0x28 Failed: key 0x2, asc 0x3a, ascq 0x0
Oct 27 21:56:02.089: vcpu-0| VIDE: ATAPI 0x28 Failed: key 0x2, asc 0x3a, ascq 0x0
Oct 27 21:56:02.090: vcpu-0| VIDE: ATAPI 0x28 Failed: key 0x2, asc 0x3a, ascq 0x0
Oct 27 21:56:02.090: vcpu-0| VIDE: ATAPI 0x28 Failed: key 0x2, asc 0x3a, ascq 0x0
Oct 27 21:56:02.090: vcpu-0| VIDE: ATAPI 0x28 Failed: key 0x2, asc 0x3a, ascq 0x0
Oct 27 21:56:02.090: vcpu-0| VIDE: ATAPI 0x28 Failed: key 0x2, asc 0x3a, ascq 0x0
Oct 27 21:56:02.090: vcpu-0| VIDE: ATAPI 0x28 Failed: key 0x2, asc 0x3a, ascq 0x0
Oct 27 21:56:02.090: vcpu-0| VIDE: ATAPI 0x28 Failed: key 0x2, asc 0x3a, ascq 0x0
Oct 27 21:56:02.091: vcpu-0| VIDE: ATAPI 0x28 Failed: key 0x2, asc 0x3a, ascq 0x0
Oct 27 21:56:02.091: vcpu-0| VIDE: ATAPI 0x28 Failed: key 0x2, asc 0x3a, ascq 0x0
Oct 27 21:56:02.091: vcpu-0| VIDE: ATAPI 0x28 Failed: key 0x2, asc 0x3a, ascq 0x0
Oct 27 21:56:02.091: vcpu-0| VIDE: ATAPI 0x28 Failed: key 0x2, asc 0x3a, ascq 0x0
Oct 27 21:56:02.091: vcpu-0| VIDE: ATAPI 0x28 Failed: key 0x2, asc 0x3a, ascq 0x0
Oct 27 21:56:02.092: vcpu-0| VIDE: ATAPI 0x28 Failed: key 0x2, asc 0x3a, ascq 0x0
Oct 27 21:56:02.092: vcpu-0| VIDE: ATAPI 0x28 Failed: key 0x2, asc 0x3a, ascq 0x0
Oct 27 21:56:02.092: vcpu-0| VIDE: ATAPI 0x28 Failed: key 0x2, asc 0x3a, ascq 0x0
Oct 27 21:56:02.092: vcpu-0| VIDE: ATAPI 0x28 Failed: key 0x2, asc 0x3a, ascq 0x0
Oct 27 21:56:02.092: vcpu-0| VIDE: ATAPI 0x28 Failed: key 0x2, asc 0x3a, ascq 0x0
Oct 27 21:56:02.092: vcpu-0| VIDE: ATAPI 0x28 Failed: key 0x2, asc 0x3a, ascq 0x0
Oct 27 21:56:02.093: vcpu-0| VIDE: ATAPI 0x28 Failed: key 0x2, asc 0x3a, ascq 0x0
Oct 27 21:56:02.093: vcpu-0| VIDE: ATAPI 0x28 Failed: key 0x2, asc 0x3a, ascq 0x0
Oct 27 21:56:02.093: vcpu-0| VIDE: ATAPI 0x28 Failed: key 0x2, asc 0x3a, ascq 0x0
Oct 27 21:56:02.093: vcpu-0| VIDE: ATAPI 0x28 Failed: key 0x2, asc 0x3a, ascq 0x0
Oct 27 21:56:02.093: vcpu-0| VIDE: ATAPI 0x28 Failed: key 0x2, asc 0x3a, ascq 0x0
Oct 27 21:56:02.093: vcpu-0| VIDE: ATAPI 0x28 Failed: key 0x2, asc 0x3a, ascq 0x0
Oct 27 21:56:02.094: vcpu-0| VIDE: ATAPI 0x28 Failed: key 0x2, asc 0x3a, ascq 0x0
Oct 27 21:56:02.094: vcpu-0| VIDE: ATAPI 0x28 Failed: key 0x2, asc 0x3a, ascq 0x0
Oct 27 21:56:02.094: vcpu-0| VIDE: ATAPI 0x28 Failed: key 0x2, asc 0x3a, ascq 0x0
Oct 27 21:56:02.094: vcpu-0| VIDE: ATAPI 0x28 Failed: key 0x2, asc 0x3a, ascq 0x0
Oct 27 21:56:03.040: vcpu-0| VIDE: ATAPI 0x28 Failed: key 0x2, asc 0x3a, ascq 0x0
Oct 27 21:56:03.040: vcpu-0| VIDE: ATAPI 0x28 Failed: key 0x2, asc 0x3a, ascq 0x0
Oct 27 21:56:03.041: vcpu-0| VIDE: ATAPI 0x28 Failed: key 0x2, asc 0x3a, ascq 0x0
Oct 27 21:56:03.041: vcpu-0| VIDE: ATAPI 0x28 Failed: key 0x2, asc 0x3a, ascq 0x0
Oct 27 21:56:03.041: vcpu-0| VIDE: ATAPI 0x28 Failed: key 0x2, asc 0x3a, ascq 0x0
Oct 27 21:56:03.041: vcpu-0| VIDE: ATAPI 0x28 Failed: key 0x2, asc 0x3a, ascq 0x0
Oct 27 21:56:03.041: vcpu-0| VIDE: ATAPI 0x28 Failed: key 0x2, asc 0x3a, ascq 0x0
Oct 27 21:56:03.041: vcpu-0| VIDE: ATAPI 0x28 Failed: key 0x2, asc 0x3a, ascq 0x0
Oct 27 21:56:03.042: vcpu-0| VIDE: ATAPI 0x28 Failed: key 0x2, asc 0x3a, ascq 0x0
Oct 27 21:56:03.042: vcpu-0| VIDE: ATAPI 0x28 Failed: key 0x2, asc 0x3a, ascq 0x0
Oct 27 21:56:03.042: vcpu-0| VIDE: ATAPI 0x28 Failed: key 0x2, asc 0x3a, ascq 0x0
Oct 27 21:56:03.042: vcpu-0| VIDE: ATAPI 0x28 Failed: key 0x2, asc 0x3a, ascq 0x0
Oct 27 21:56:03.042: vcpu-0| VIDE: ATAPI 0x28 Failed: key 0x2, asc 0x3a, ascq 0x0
Oct 27 21:56:03.043: vcpu-0| VIDE: ATAPI 0x28 Failed: key 0x2, asc 0x3a, ascq 0x0
Oct 27 21:56:03.043: vcpu-0| VIDE: ATAPI 0x28 Failed: key 0x2, asc 0x3a, ascq 0x0
Oct 27 21:56:03.043: vcpu-0| VIDE: ATAPI 0x28 Failed: key 0x2, asc 0x3a, ascq 0x0
Oct 27 21:56:03.043: vcpu-0| VIDE: ATAPI 0x28 Failed: key 0x2, asc 0x3a, ascq 0x0
Oct 27 21:56:03.043: vcpu-0| VIDE: ATAPI 0x28 Failed: key 0x2, asc 0x3a, ascq 0x0
Oct 27 21:56:03.043: vcpu-0| VIDE: ATAPI 0x28 Failed: key 0x2, asc 0x3a, ascq 0x0
Oct 27 21:56:03.044: vcpu-0| VIDE: ATAPI 0x28 Failed: key 0x2, asc 0x3a, ascq 0x0
Oct 27 21:56:03.044: vcpu-0| VIDE: ATAPI 0x28 Failed: key 0x2, asc 0x3a, ascq 0x0
Oct 27 21:56:03.044: vcpu-0| VIDE: ATAPI 0x28 Failed: key 0x2, asc 0x3a, ascq 0x0
Oct 27 21:56:03.044: vcpu-0| VIDE: ATAPI 0x28 Failed: key 0x2, asc 0x3a, ascq 0x0
Oct 27 21:56:03.044: vcpu-0| VIDE: ATAPI 0x28 Failed: key 0x2, asc 0x3a, ascq 0x0
Oct 27 21:56:03.045: vcpu-0| VIDE: ATAPI 0x28 Failed: key 0x2, asc 0x3a, ascq 0x0
Oct 27 21:56:03.045: vcpu-0| VIDE: ATAPI 0x28 Failed: key 0x2, asc 0x3a, ascq 0x0
Oct 27 21:56:03.045: vcpu-0| VIDE: ATAPI 0x28 Failed: key 0x2, asc 0x3a, ascq 0x0
Oct 27 21:56:03.045: vcpu-0| VIDE: ATAPI 0x28 Failed: key 0x2, asc 0x3a, ascq 0x0
Oct 27 21:56:03.045: vcpu-0| VIDE: ATAPI 0x28 Failed: key 0x2, asc 0x3a, ascq 0x0
Oct 27 21:56:03.045: vcpu-0| VIDE: ATAPI 0x28 Failed: key 0x2, asc 0x3a, ascq 0x0
Oct 27 21:56:03.046: vcpu-0| VIDE: ATAPI 0x28 Failed: key 0x2, asc 0x3a, ascq 0x0
Oct 27 21:56:03.046: vcpu-0| VIDE: ATAPI 0x28 Failed: key 0x2, asc 0x3a, ascq 0x0
Oct 27 21:56:03.046: vcpu-0| VIDE: ATAPI 0x28 Failed: key 0x2, asc 0x3a, ascq 0x0
Oct 27 21:56:03.046: vcpu-0| VIDE: ATAPI 0x28 Failed: key 0x2, asc 0x3a, ascq 0x0
Oct 27 21:56:03.046: vcpu-0| VIDE: ATAPI 0x28 Failed: key 0x2, asc 0x3a, ascq 0x0
Oct 27 21:56:03.046: vcpu-0| VIDE: ATAPI 0x28 Failed: key 0x2, asc 0x3a, ascq 0x0
Oct 27 21:56:03.047: vcpu-0| VIDE: ATAPI 0x28 Failed: key 0x2, asc 0x3a, ascq 0x0
Oct 27 21:56:03.047: vcpu-0| VIDE: ATAPI 0x28 Failed: key 0x2, asc 0x3a, ascq 0x0
Oct 27 21:56:03.047: vcpu-0| VIDE: ATAPI 0x28 Failed: key 0x2, asc 0x3a, ascq 0x0
Oct 27 21:56:03.047: vcpu-0| VIDE: ATAPI 0x28 Failed: key 0x2, asc 0x3a, ascq 0x0
Oct 27 21:56:03.047: vcpu-0| VIDE: ATAPI 0x28 Failed: key 0x2, asc 0x3a, ascq 0x0
Oct 27 21:56:03.048: vcpu-0| VIDE: ATAPI 0x28 Failed: key 0x2, asc 0x3a, ascq 0x0
Oct 27 21:56:03.048: vcpu-0| VIDE: ATAPI 0x28 Failed: key 0x2, asc 0x3a, ascq 0x0
Oct 27 21:56:03.048: vcpu-0| VIDE: ATAPI 0x28 Failed: key 0x2, asc 0x3a, ascq 0x0
Oct 27 21:56:03.048: vcpu-0| VIDE: ATAPI 0x28 Failed: key 0x2, asc 0x3a, ascq 0x0
Oct 27 21:56:03.048: vcpu-0| VIDE: ATAPI 0x28 Failed: key 0x2, asc 0x3a, ascq 0x0
Oct 27 21:56:03.048: vcpu-0| VIDE: ATAPI 0x28 Failed: key 0x2, asc 0x3a, ascq 0x0
Oct 27 21:56:03.048: vcpu-0| VIDE: ATAPI 0x28 Failed: key 0x2, asc 0x3a, ascq 0x0
Oct 27 21:56:03.049: vcpu-0| VIDE: ATAPI 0x28 Failed: key 0x2, asc 0x3a, ascq 0x0
Oct 27 21:56:03.049: vcpu-0| VIDE: ATAPI 0x28 Failed: key 0x2, asc 0x3a, ascq 0x0
Oct 27 21:56:03.049: vcpu-0| VIDE: ATAPI 0x28 Failed: key 0x2, asc 0x3a, ascq 0x0
Oct 27 21:56:03.049: vcpu-0| VIDE: ATAPI 0x28 Failed: key 0x2, asc 0x3a, ascq 0x0
Oct 27 21:56:03.049: vcpu-0| VIDE: ATAPI 0x28 Failed: key 0x2, asc 0x3a, ascq 0x0
Oct 27 21:56:03.050: vcpu-0| VIDE: ATAPI 0x28 Failed: key 0x2, asc 0x3a, ascq 0x0
Oct 27 21:56:03.050: vcpu-0| VIDE: ATAPI 0x28 Failed: key 0x2, asc 0x3a, ascq 0x0
Oct 27 21:56:03.050: vcpu-0| VIDE: ATAPI 0x28 Failed: key 0x2, asc 0x3a, ascq 0x0
Oct 27 21:56:03.050: vcpu-0| VIDE: ATAPI 0x28 Failed: key 0x2, asc 0x3a, ascq 0x0
Oct 27 21:56:03.050: vcpu-0| VIDE: ATAPI 0x28 Failed: key 0x2, asc 0x3a, ascq 0x0
Oct 27 21:56:03.050: vcpu-0| VIDE: ATAPI 0x28 Failed: key 0x2, asc 0x3a, ascq 0x0
Oct 27 21:56:03.051: vcpu-0| VIDE: ATAPI 0x28 Failed: key 0x2, asc 0x3a, ascq 0x0
Oct 27 21:56:04.029: vcpu-0| CDROM: switching CDROM state from 3 to 4 using error sense key.
Oct 27 21:56:04.029: vcpu-0| VIDE: ATAPI 0x28 Failed: key 0x2, asc 0x3a, ascq 0x0
Oct 27 21:56:04.029: vcpu-0| CDROM: switching CDROM state from 4 to 5 using error sense key.
Oct 27 21:56:04.029: vcpu-0| CDROM ide1:0: CMD 0x28 (READ(10)) FAILED (key 0x2 asc 0x4 ascq 0x1)
Oct 27 21:56:04.029: vcpu-0| VIDE: ATAPI 0x28 Failed: key 0x2, asc 0x4, ascq 0x1
Oct 27 21:56:04.029: vcpu-0| CDROM: switching CDROM state from 5 to 1 using error sense key.
Oct 27 21:56:04.029: vcpu-0| CDROM ide1:0: CMD 0x28 (READ(10)) FAILED (key 0x6 asc 0x28 ascq 0)
Oct 27 21:56:04.029: vcpu-0| VIDE: ATAPI 0x28 Failed: key 0x6, asc 0x28, ascq 0x0
Oct 27 21:56:04.030: vcpu-0| DMG_Open: Not an unencrypted .dmg file (footer signature 0x00000000).
Oct 27 21:56:04.030: vcpu-0| DMG_Close: max cached entries 8
Oct 27 21:56:04.528: mks| SVGA: display status changed, using optimizations for local consoles.
Oct 27 21:56:06.112: mks| HostOps hideCursor before defineCursor!
Oct 27 21:56:06.339: vcpu-0| MMUValidateWork: FlexPriority enabled for APIC, mpn: 0
Oct 27 21:56:06.348: vcpu-0| Vix: [6276 vmxCommands.c:9688]: VMAutomation_HandleCLIHLTEvent. Do nothing.
Oct 27 21:56:06.348: vcpu-0| Msg_Hint: msg.monitorevent.halt (sent)
Oct 27 21:56:06.348: vcpu-0| The CPU has been disabled by the guest operating system. You will need to power off or reset the virtual machine at this point.
Oct 27 21:56:06.348: vcpu-0| ---------------------------------------
Oct 27 21:56:09.683: vmx| VMXVmdbCbVmVmxExecState: Exec state change requested to state poweredOff without reset, hard.
Oct 27 21:56:09.683: vmx| Stopping VCPU threads...
Oct 27 21:56:09.728: mks| Ending MKSRole pseudocalls, state was 1
Oct 27 21:56:09.728: mks| Detaching from window system.
Oct 27 21:56:09.729: mks| MKS: Base polling period is 1000000us
Oct 27 21:56:09.729: mks| Async MKS thread is exiting
Oct 27 21:56:09.729: vmx| USB: Disconnecting device 0x600000010e0f0006
Oct 27 21:56:09.729: vmx| Vix: [6272 mainDispatch.c:912]: VMAutomation_PowerOff: Powering off.
Oct 27 21:56:09.729: vmx| TOOLS received request in VMX to set option 'enableDnD' -> '0'
Oct 27 21:56:09.729: vmx| TOOLS received request in VMX to set option 'copypaste' -> '0'
Oct 27 21:56:09.729: vmx| TOOLS received request in VMX to set option 'copypaste' -> '0'
Oct 27 21:56:09.729: vmx| VMXVmdb_SetToolsVersionState: status value set to 'ok'
Oct 27 21:56:09.732: vmx| USB: Disconnecting device 0x200000010e0f0002
Oct 27 21:56:09.732: vmx| USB: Disconnecting device 0x400000010e0f0003
Oct 27 21:56:09.734: vmx| HostOps hideCursor before defineCursor!
Oct 27 21:56:09.736: vmx| MKS local poweroff
Oct 27 21:56:09.737: vmx| scsi0:0: numIOs = 1 numMergedIOs = 0 numSplitIOs = 0 ( 0.0%)
Oct 27 21:56:09.768: vmx| WORKER: asyncOps=782 maxActiveOps=1 maxPending=0 maxCompleted=0
Oct 27 21:56:09.775: vmx| Vix: [6272 mainDispatch.c:3485]: VMAutomation_ReportPowerOpFinished: statevar=1, newAppState=1873, success=1
Oct 27 21:56:09.776: vmx| Vix: [6272 mainDispatch.c:3491]: VMAutomation: Ignoring ReportPowerOpFinished because the VMX is shutting down.
Oct 27 21:56:09.787: vmx| VMMon_UnlockPage: Failed addr 7F14B0107000 errno 22
Oct 27 21:56:09.789: vmx| Vix: [6272 mainDispatch.c:3485]: VMAutomation_ReportPowerOpFinished: statevar=0, newAppState=1870, success=1
Oct 27 21:56:09.789: vmx| Vix: [6272 mainDispatch.c:3491]: VMAutomation: Ignoring ReportPowerOpFinished because the VMX is shutting down.
Oct 27 21:56:09.789: vmx| Transitioned vmx/execState/val to poweredOff
Oct 27 21:56:09.832: vmx| VMXVmdb_LoadRawConfig: Loading raw config
Oct 27 21:56:09.886: vmx| VMX idle exit
Oct 27 21:56:09.887: vmx| Vix: [6272 mainDispatch.c:644]: VMAutomation_LateShutdown()
Oct 27 21:56:09.887: vmx| Vix: [6272 mainDispatch.c:594]: VMAutomationCloseListenerSocket. Closing listener socket.
Oct 27 21:56:09.887: vmx| Flushing VMX VMDB connections
Oct 27 21:56:09.894: vmx| VMX exit (0).
Oct 27 21:56:09.894: vmx| AIOMGR-S : stat o=2 r=6 w=0 i=0 br=98304 bw=0
Oct 27 21:56:09.894: vmx| FileTrack_Exit: done