Feb 11 02:40:34.542: vmx| Log for VMware ESX pid=935914 version=4.1.0 build=build-800380 option=BETA Feb 11 02:40:34.542: vmx| The process is 64-bit. Feb 11 02:40:34.542: vmx| Host codepage=UTF-8 encoding=UTF-8 Feb 11 02:40:34.542: vmx| IP=127.0.0.1 (lo0) Feb 11 02:40:34.542: vmx| IP=192.168.0.81 (vmk0) Feb 11 02:40:34.542: vmx| Command line: "/bin/vmx-debug" "-ssched.group=host/user" "-#" "name=VMware ESX;version=4.1.0;buildnumber=800380;licensename=VMware ESX Server;licenseversion=4.0 build-800380;" "-@" "pipe=/tmp/vmhsdaemon-0/vmx5d227adad2397fc2;" "/vmfs/volumes/55820bef-c4728a1a-8410-74e6e2d448b4/TURNKEY REDMINE/TURNKEY REDMINE.vmx" Feb 11 02:40:34.542: vmx| Environment: "USER=root" "HOME=/" "SHELL=/bin/ash" "LANG=C" Feb 11 02:40:34.542: vmx| Msg_SetLocale: HostLocale=UTF-8 UserLocale=NULL Feb 11 02:40:34.566: vmx| UI Connecting to pipe '/tmp/vmhsdaemon-0/vmx5d227adad2397fc2' with user '(null)' Feb 11 02:40:34.566: vmx| VMXVmdb: Local connection timeout: 60000 ms. Feb 11 02:40:34.586: vmx| VmdbAddConnection: cnxPath=/db/connection/#1/, cnxIx=1 Feb 11 02:40:34.586: vmx| Hostname=dell.viaregra.com Feb 11 02:40:34.587: vmx| /vmfs/volumes/55820bef-c4728a1a-8410-74e6e2d448b4/TURNKEY REDMINE/TURNKEY REDMINE.vmx: Setup symlink /var/run/vmware/7d3689aa034260a3941247da59824ee7 -> /var/run/vmware/root_0/1455158434542947_935914 Feb 11 02:40:34.587: vmx| Vix: [935914 mainDispatch.c:398]: VMAutomation: Initializing VMAutomation. Feb 11 02:40:34.587: vmx| Vix: [935914 mainDispatch.c:420]: VMAutomation: Detected the VM is not managed Feb 11 02:40:34.587: vmx| Vix: [935914 mainDispatch.c:582]: VMAutomationOpenListenerSocket() listening Feb 11 02:40:34.587: vmx| Sig_Init already initialized Feb 11 02:40:34.588: vmx| Vix: [935914 mainDispatch.c:3709]: VMAutomation_ReportPowerOpFinished: statevar=0, newAppState=1870, success=1 additionalError=0 Feb 11 02:40:34.588: vmx| Transitioned vmx/execState/val to poweredOff Feb 11 02:40:34.588: vmx| Vix: [935914 mainDispatch.c:3709]: VMAutomation_ReportPowerOpFinished: statevar=1, newAppState=1873, success=1 additionalError=0 Feb 11 02:40:34.588: vmx| Vix: [935914 mainDispatch.c:3709]: VMAutomation_ReportPowerOpFinished: statevar=2, newAppState=1877, success=1 additionalError=0 Feb 11 02:40:34.588: vmx| Vix: [935914 mainDispatch.c:3709]: VMAutomation_ReportPowerOpFinished: statevar=3, newAppState=1881, success=1 additionalError=0 Feb 11 02:40:34.588: vmx| hostCPUID vendor: GenuineIntel Feb 11 02:40:34.588: vmx| hostCPUID family: 0x6 model: 0x3a stepping: 0x9 Feb 11 02:40:34.588: vmx| hostCPUID codename: Unknown Feb 11 02:40:34.588: vmx| hostCPUID name: Intel(R) Xeon(R) CPU E3-1220 V2 @ 3.10GHz Feb 11 02:40:34.588: vmx| hostCPUID level 00000000, 0: 0x0000000d 0x756e6547 0x6c65746e 0x49656e69 Feb 11 02:40:34.588: vmx| hostCPUID level 00000001, 0: 0x000306a9 0x00100800 0x77bae3ff 0xbfebfbff Feb 11 02:40:34.588: vmx| hostCPUID level 00000002, 0: 0x76035a01 0x00f0b0ff 0x00000000 0x00ca0000 Feb 11 02:40:34.588: vmx| hostCPUID level 00000003, 0: 0x00000000 0x00000000 0x00000000 0x00000000 Feb 11 02:40:34.588: vmx| hostCPUID level 00000004, 0: 0x1c004121 0x01c0003f 0x0000003f 0x00000000 Feb 11 02:40:34.588: vmx| hostCPUID level 00000005, 0: 0x00000040 0x00000040 0x00000003 0x00001120 Feb 11 02:40:34.588: vmx| hostCPUID level 00000006, 0: 0x00000077 0x00000002 0x00000009 0x00000000 Feb 11 02:40:34.588: vmx| hostCPUID level 00000007, 0: 0x00000000 0x00000281 0x00000000 0x00000000 Feb 11 02:40:34.588: vmx| hostCPUID level 00000008, 0: 0x00000000 0x00000000 0x00000000 0x00000000 Feb 11 02:40:34.588: vmx| hostCPUID level 00000009, 0: 0x00000000 0x00000000 0x00000000 0x00000000 Feb 11 02:40:34.588: vmx| hostCPUID level 0000000a, 0: 0x07300803 0x00000000 0x00000000 0x00000603 Feb 11 02:40:34.588: vmx| hostCPUID level 0000000b, 0: 0x00000001 0x00000001 0x00000100 0x00000000 Feb 11 02:40:34.588: vmx| hostCPUID level 0000000c, 0: 0x00000000 0x00000000 0x00000000 0x00000000 Feb 11 02:40:34.588: vmx| hostCPUID level 0000000d, 0: 0x00000007 0x00000240 0x00000340 0x00000000 Feb 11 02:40:34.588: vmx| hostCPUID level 80000000, 0: 0x80000008 0x00000000 0x00000000 0x00000000 Feb 11 02:40:34.588: vmx| hostCPUID level 80000001, 0: 0x00000000 0x00000000 0x00000001 0x28100800 Feb 11 02:40:34.588: vmx| hostCPUID level 80000002, 0: 0x20202020 0x6e492020 0x286c6574 0x58202952 Feb 11 02:40:34.588: vmx| hostCPUID level 80000003, 0: 0x286e6f65 0x43202952 0x45205550 0x32312d33 Feb 11 02:40:34.588: vmx| hostCPUID level 80000004, 0: 0x56203032 0x20402032 0x30312e33 0x007a4847 Feb 11 02:40:34.588: vmx| hostCPUID level 80000005, 0: 0x00000000 0x00000000 0x00000000 0x00000000 Feb 11 02:40:34.588: vmx| hostCPUID level 80000006, 0: 0x00000000 0x00000000 0x01006040 0x00000000 Feb 11 02:40:34.588: vmx| hostCPUID level 80000007, 0: 0x00000000 0x00000000 0x00000000 0x00000100 Feb 11 02:40:34.588: vmx| hostCPUID level 80000008, 0: 0x00003024 0x00000000 0x00000000 0x00000000 Feb 11 02:40:34.588: vmx| CPUID differences from hostCPUID. Feb 11 02:40:34.588: vmx| CPUID[1] level 00000001, 0: 0x000306a9 0x02100800 0x77bae3ff 0xbfebfbff Feb 11 02:40:34.588: vmx| CPUID[1] level 0000000b, 0: 0x00000001 0x00000001 0x00000100 0x00000002 Feb 11 02:40:34.588: vmx| CPUID[2] level 00000001, 0: 0x000306a9 0x04100800 0x77bae3ff 0xbfebfbff Feb 11 02:40:34.588: vmx| CPUID[2] level 0000000b, 0: 0x00000001 0x00000001 0x00000100 0x00000004 Feb 11 02:40:34.588: vmx| CPUID[3] level 00000001, 0: 0x000306a9 0x06100800 0x77bae3ff 0xbfebfbff Feb 11 02:40:34.588: vmx| CPUID[3] level 0000000b, 0: 0x00000001 0x00000001 0x00000100 0x00000006 Feb 11 02:40:34.588: vmx| CPUID Maximum Physical Address Bits supported across all CPUs: 36 Feb 11 02:40:34.588: vmx| Common: MSR 0x3a = 0x5 Feb 11 02:40:34.588: vmx| Common: MSR 0x480 = 0xda040000000010 Feb 11 02:40:34.588: vmx| Common: MSR 0x481 = 0x7f00000016 Feb 11 02:40:34.588: vmx| Common: MSR 0x482 = 0xfff9fffe04006172 Feb 11 02:40:34.588: vmx| Common: MSR 0x483 = 0x7fffff00036dfb Feb 11 02:40:34.588: vmx| Common: MSR 0x484 = 0xffff000011fb Feb 11 02:40:34.588: vmx| Common: MSR 0x485 = 0x100401e5 Feb 11 02:40:34.588: vmx| Common: MSR 0x486 = 0x80000021 Feb 11 02:40:34.588: vmx| Common: MSR 0x487 = 0xffffffff Feb 11 02:40:34.588: vmx| Common: MSR 0x488 = 0x2000 Feb 11 02:40:34.588: vmx| Common: MSR 0x489 = 0x1767ff Feb 11 02:40:34.588: vmx| Common: MSR 0x48a = 0x2a Feb 11 02:40:34.588: vmx| Common: MSR 0x48b = 0x8ff00000000 Feb 11 02:40:34.588: vmx| Common: MSR 0x48c = 0xf0106114141 Feb 11 02:40:34.588: vmx| Common: MSR 0x48d = 0x7f00000016 Feb 11 02:40:34.588: vmx| Common: MSR 0x48e = 0xfff9fffe04006172 Feb 11 02:40:34.588: vmx| Common: MSR 0x48f = 0x7fffff00036dfb Feb 11 02:40:34.588: vmx| Common: MSR 0x490 = 0xffff000011fb Feb 11 02:40:34.588: vmx| VMIOP: Init started Feb 11 02:40:34.592: vmx| changing directory to /vmfs/volumes/55820bef-c4728a1a-8410-74e6e2d448b4/TURNKEY REDMINE/. Feb 11 02:40:34.592: vmx| Config file: /vmfs/volumes/55820bef-c4728a1a-8410-74e6e2d448b4/TURNKEY REDMINE/TURNKEY REDMINE.vmx Feb 11 02:40:34.613: vmx| Vix: [935914 mainDispatch.c:3709]: VMAutomation_ReportPowerOpFinished: statevar=1, newAppState=1873, success=1 additionalError=0 Feb 11 02:40:34.613: vmx| Vix: [935914 mainDispatch.c:3709]: VMAutomation_ReportPowerOpFinished: statevar=2, newAppState=1878, success=1 additionalError=0 Feb 11 02:40:34.667: vmx| VMXVmdb_LoadRawConfig: Loading raw config Feb 11 02:40:34.723: vmx| VMXVmdbCbVmVmxExecState: Exec state change requested to state poweredOn without reset, soft. Feb 11 02:40:34.723: vmx| TOOLS delaying state change request to state 3 Feb 11 02:40:34.723: vmx| PowerOn Feb 11 02:40:34.725: vmx| VMXVmdb_LoadRawConfig: Loading raw config Feb 11 02:40:34.728: vmx| Vix: [935914 mainDispatch.c:3709]: VMAutomation_ReportPowerOpFinished: statevar=0, newAppState=1871, success=1 additionalError=0 Feb 11 02:40:34.728: vmx| UNAME VMkernel dell.viaregra.com 4.1.0 #1 SMP Release build-800380 Aug 2 2012 11:45:34 x86_64 (uwglibc release: vmware, version: 2.5) Feb 11 02:40:34.728: vmx| DICT --- USER PREFERENCES //.vmware/preferences Feb 11 02:40:34.728: vmx| DICT --- USER DEFAULTS //.vmware/config Feb 11 02:40:34.728: vmx| DICT --- HOST DEFAULTS /etc/vmware/config Feb 11 02:40:34.728: vmx| DICT libdir = /usr/lib/vmware Feb 11 02:40:34.728: vmx| DICT authd.proxy.vim = vmware-hostd:hostd-vmdb Feb 11 02:40:34.728: vmx| DICT authd.proxy.nfc = vmware-hostd:ha-nfc Feb 11 02:40:34.728: vmx| DICT authd.proxy.nfcssl = vmware-hostd:ha-nfcssl Feb 11 02:40:34.728: vmx| DICT vmauthd.logEnabled = FALSE Feb 11 02:40:34.728: vmx| DICT log.vmauthdFileName = /var/log/vmware/authd.log Feb 11 02:40:34.728: vmx| DICT authd.fullpath = /sbin/authd Feb 11 02:40:34.728: vmx| DICT authd.soapServer = TRUE Feb 11 02:40:34.728: vmx| DICT vmauthd.server.alwaysProxy = TRUE Feb 11 02:40:34.728: vmx| DICT vmx.fullpath = /bin/vmx Feb 11 02:40:34.728: vmx| DICT --- SITE DEFAULTS /usr/lib/vmware/config Feb 11 02:40:34.728: vmx| DICT --- NONPERSISTENT Feb 11 02:40:34.728: vmx| DICT sched.group = host/user Feb 11 02:40:34.728: vmx| DICT --- COMMAND LINE Feb 11 02:40:34.728: vmx| DICT sched.group = host/user Feb 11 02:40:34.728: vmx| DICT --- RECORDING Feb 11 02:40:34.728: vmx| DICT sched.group = host/user Feb 11 02:40:34.728: vmx| DICT --- CONFIGURATION /vmfs/volumes/55820bef-c4728a1a-8410-74e6e2d448b4/TURNKEY REDMINE/TURNKEY REDMINE.vmx Feb 11 02:40:34.728: vmx| DICT config.version = 8 Feb 11 02:40:34.728: vmx| DICT virtualHW.version = 7 Feb 11 02:40:34.728: vmx| DICT pciBridge0.present = TRUE Feb 11 02:40:34.728: vmx| DICT pciBridge4.present = TRUE Feb 11 02:40:34.728: vmx| DICT pciBridge4.virtualDev = pcieRootPort Feb 11 02:40:34.728: vmx| DICT pciBridge4.functions = 8 Feb 11 02:40:34.728: vmx| DICT pciBridge5.present = TRUE Feb 11 02:40:34.728: vmx| DICT pciBridge5.virtualDev = pcieRootPort Feb 11 02:40:34.728: vmx| DICT pciBridge5.functions = 8 Feb 11 02:40:34.728: vmx| DICT pciBridge6.present = TRUE Feb 11 02:40:34.728: vmx| DICT pciBridge6.virtualDev = pcieRootPort Feb 11 02:40:34.728: vmx| DICT pciBridge6.functions = 8 Feb 11 02:40:34.728: vmx| DICT pciBridge7.present = TRUE Feb 11 02:40:34.728: vmx| DICT pciBridge7.virtualDev = pcieRootPort Feb 11 02:40:34.728: vmx| DICT pciBridge7.functions = 8 Feb 11 02:40:34.728: vmx| DICT vmci0.present = TRUE Feb 11 02:40:34.728: vmx| DICT nvram = TURNKEY REDMINE.nvram Feb 11 02:40:34.728: vmx| DICT virtualHW.productCompatibility = hosted Feb 11 02:40:34.728: vmx| DICT powerType.powerOff = hard Feb 11 02:40:34.728: vmx| DICT powerType.powerOn = hard Feb 11 02:40:34.728: vmx| DICT powerType.suspend = hard Feb 11 02:40:34.728: vmx| DICT powerType.reset = hard Feb 11 02:40:34.728: vmx| DICT displayName = TURNKEY REDMINE Feb 11 02:40:34.728: vmx| DICT extendedConfigFile = TURNKEY REDMINE.vmxf Feb 11 02:40:34.728: vmx| DICT scsi0.present = TRUE Feb 11 02:40:34.728: vmx| DICT scsi0.sharedBus = none Feb 11 02:40:34.728: vmx| DICT scsi0.virtualDev = lsilogic Feb 11 02:40:34.728: vmx| DICT memsize = 512 Feb 11 02:40:34.728: vmx| DICT scsi0:0.present = TRUE Feb 11 02:40:34.728: vmx| DICT scsi0:0.fileName = TURNKEY REDMINE.vmdk Feb 11 02:40:34.728: vmx| DICT scsi0:0.deviceType = scsi-hardDisk Feb 11 02:40:34.728: vmx| DICT ethernet0.present = TRUE Feb 11 02:40:34.728: vmx| DICT ethernet0.wakeOnPcktRcv = FALSE Feb 11 02:40:34.728: vmx| DICT ethernet0.networkName = VM Network Feb 11 02:40:34.728: vmx| DICT ethernet0.addressType = generated Feb 11 02:40:34.728: vmx| DICT ehci.present = TRUE Feb 11 02:40:34.728: vmx| DICT tools.syncTime = TRUE Feb 11 02:40:34.728: vmx| DICT guestOS = other-64 Feb 11 02:40:34.728: vmx| DICT annotation = http://www.turnkeylinux.org/redmine Feb 11 02:40:34.728: vmx| DICT uuid.location = 56 4d 0a 47 e4 86 d0 de-d2 08 64 ad 0b 25 34 b3 Feb 11 02:40:34.728: vmx| DICT uuid.bios = 56 4d 0a 47 e4 86 d0 de-d2 08 64 ad 0b 25 34 b3 Feb 11 02:40:34.728: vmx| DICT vc.uuid = 52 6e e9 5c e1 6f 96 2d-98 85 73 54 b4 31 12 08 Feb 11 02:40:34.728: vmx| DICT sched.cpu.min = 0 Feb 11 02:40:34.728: vmx| DICT sched.cpu.units = mhz Feb 11 02:40:34.728: vmx| DICT sched.cpu.shares = normal Feb 11 02:40:34.728: vmx| DICT sched.mem.minsize = 0 Feb 11 02:40:34.728: vmx| DICT sched.mem.shares = normal Feb 11 02:40:34.728: vmx| DICT ethernet0.generatedAddress = 00:0c:29:25:34:b3 Feb 11 02:40:34.728: vmx| DICT vmci0.id = 186987699 Feb 11 02:40:34.728: vmx| DICT cleanShutdown = TRUE Feb 11 02:40:34.728: vmx| DICT replay.supported = FALSE Feb 11 02:40:34.728: vmx| DICT sched.swap.derivedName = /vmfs/volumes/55820bef-c4728a1a-8410-74e6e2d448b4/TURNKEY REDMINE/TURNKEY REDMINE-8334e425.vswp Feb 11 02:40:34.728: vmx| DICT replay.filename = Feb 11 02:40:34.728: vmx| DICT scsi0:0.redo = Feb 11 02:40:34.728: vmx| DICT pciBridge0.pciSlotNumber = 17 Feb 11 02:40:34.728: vmx| DICT pciBridge4.pciSlotNumber = 21 Feb 11 02:40:34.728: vmx| DICT pciBridge5.pciSlotNumber = 22 Feb 11 02:40:34.728: vmx| DICT pciBridge6.pciSlotNumber = 23 Feb 11 02:40:34.728: vmx| DICT pciBridge7.pciSlotNumber = 24 Feb 11 02:40:34.728: vmx| DICT scsi0.pciSlotNumber = 16 Feb 11 02:40:34.728: vmx| DICT ethernet0.pciSlotNumber = 33 Feb 11 02:40:34.728: vmx| DICT ehci.pciSlotNumber = 34 Feb 11 02:40:34.728: vmx| DICT vmci0.pciSlotNumber = 35 Feb 11 02:40:34.728: vmx| DICT vmotion.checkpointFBSize = 4194304 Feb 11 02:40:34.728: vmx| DICT ethernet0.generatedAddressOffset = 0 Feb 11 02:40:34.728: vmx| DICT hostCPUID.0 = 0000000d756e65476c65746e49656e69 Feb 11 02:40:34.728: vmx| DICT hostCPUID.1 = 000306a90010080077bae3ffbfebfbff Feb 11 02:40:34.728: vmx| DICT hostCPUID.80000001 = 00000000000000000000000128100800 Feb 11 02:40:34.728: vmx| DICT guestCPUID.0 = 0000000d756e65476c65746e49656e69 Feb 11 02:40:34.728: vmx| DICT guestCPUID.1 = 000306a900010800829822030febfbff Feb 11 02:40:34.728: vmx| DICT guestCPUID.80000001 = 00000000000000000000000128100800 Feb 11 02:40:34.729: vmx| DICT userCPUID.0 = 0000000d756e65476c65746e49656e69 Feb 11 02:40:34.729: vmx| DICT userCPUID.1 = 000306a90010080077bae3ffbfebfbff Feb 11 02:40:34.729: vmx| DICT userCPUID.80000001 = 00000000000000000000000128100800 Feb 11 02:40:34.729: vmx| DICT evcCompatibilityMode = FALSE Feb 11 02:40:34.729: vmx| DICT monitor = debug Feb 11 02:40:34.729: vmx| DICT disable_acceleration = TRUE Feb 11 02:40:34.729: vmx| DICT ide1:0.present = FALSE Feb 11 02:40:34.729: vmx| DICT usb.present = FALSE Feb 11 02:40:34.729: vmx| DICT floppy0.present = FALSE Feb 11 02:40:34.729: vmx| DICT --- USER DEFAULTS ~/.vmware/config Feb 11 02:40:34.729: vmx| DICT --- HOST DEFAULTS /etc/vmware/config Feb 11 02:40:34.729: vmx| DICT libdir = /usr/lib/vmware Feb 11 02:40:34.729: vmx| DICT authd.proxy.vim = vmware-hostd:hostd-vmdb Feb 11 02:40:34.729: vmx| DICT authd.proxy.nfc = vmware-hostd:ha-nfc Feb 11 02:40:34.729: vmx| DICT authd.proxy.nfcssl = vmware-hostd:ha-nfcssl Feb 11 02:40:34.729: vmx| DICT vmauthd.logEnabled = FALSE Feb 11 02:40:34.729: vmx| DICT log.vmauthdFileName = /var/log/vmware/authd.log Feb 11 02:40:34.729: vmx| DICT authd.fullpath = /sbin/authd Feb 11 02:40:34.729: vmx| DICT authd.soapServer = TRUE Feb 11 02:40:34.729: vmx| DICT vmauthd.server.alwaysProxy = TRUE Feb 11 02:40:34.729: vmx| DICT vmx.fullpath = /bin/vmx Feb 11 02:40:34.729: vmx| DICT --- SITE DEFAULTS /usr/lib/vmware/config Feb 11 02:40:34.729: vmx| DICT --- GLOBAL SETTINGS /usr/lib/vmware/settings Feb 11 02:40:34.738: vmx| Vix: [935914 mainDispatch.c:3709]: VMAutomation_ReportPowerOpFinished: statevar=1, newAppState=1873, success=1 additionalError=0 Feb 11 02:40:34.740: vmx| VMXVmdb_LoadRawConfig: Loading raw config Feb 11 02:40:34.743: vmx| hostCpuFeatures = 0x4c4001fd Feb 11 02:40:34.743: vmx| hostNumPerfCounters = 8 Feb 11 02:40:34.743: vmx| MONITOR MODE: allowed modes : BT32 HV HWMMU Feb 11 02:40:34.743: vmx| MONITOR MODE: user requested modes : BT32 HV HWMMU Feb 11 02:40:34.743: vmx| MONITOR MODE: guestOS preferred modes: HWMMU HV BT32 Feb 11 02:40:34.743: vmx| MONITOR MODE: filtered list : HWMMU HV BT32 Feb 11 02:40:34.743: vmx| CPU0: PMC: unused [c:0 f:1 e:1] Feb 11 02:40:34.743: vmx| CPU1: PMC: unused [c:0 f:1 e:1] Feb 11 02:40:34.743: vmx| CPU2: PMC: unused [c:0 f:1 e:1] Feb 11 02:40:34.743: vmx| CPU3: PMC: unused [c:0 f:1 e:1] Feb 11 02:40:34.743: vmx| HV Settings: virtual exec = 'hardware'; virtual mmu = 'hardware' Feb 11 02:40:34.743: vmx| Basic VMX Information (0x00da040000000010) Feb 11 02:40:34.743: vmx| VMCS revision ID 16 Feb 11 02:40:34.743: vmx| VMCS region length 1024 Feb 11 02:40:34.743: vmx| VMX physical-address width natural Feb 11 02:40:34.743: vmx| SMM dual-monitor mode yes Feb 11 02:40:34.743: vmx| Advanced INS/OUTS info yes Feb 11 02:40:34.743: vmx| True VMX MSRs yes Feb 11 02:40:34.743: vmx| VMCS memory type WB Feb 11 02:40:34.743: vmx| Pin-Based VM-Execution Controls (0x0000007f00000016) Feb 11 02:40:34.743: vmx| External-interrupt exiting {0,1} Feb 11 02:40:34.743: vmx| NMI exiting {0,1} Feb 11 02:40:34.743: vmx| Virtual NMIs {0,1} Feb 11 02:40:34.743: vmx| Activate VMX-preemption timer {0,1} Feb 11 02:40:34.743: vmx| Primary Processor-Based VM-Execution Controls (0xfff9fffe04006172) Feb 11 02:40:34.743: vmx| Interrupt-window exiting {0,1} Feb 11 02:40:34.743: vmx| Use TSC offsetting {0,1} Feb 11 02:40:34.743: vmx| HLT exiting {0,1} Feb 11 02:40:34.743: vmx| INVLPG exiting {0,1} Feb 11 02:40:34.743: vmx| MWAIT exiting {0,1} Feb 11 02:40:34.743: vmx| RDPMC exiting {0,1} Feb 11 02:40:34.743: vmx| RDTSC exiting {0,1} Feb 11 02:40:34.743: vmx| CR3-load exiting {0,1} Feb 11 02:40:34.743: vmx| CR3-store exiting {0,1} Feb 11 02:40:34.743: vmx| CR8-load exiting {0,1} Feb 11 02:40:34.743: vmx| CR8-store exiting {0,1} Feb 11 02:40:34.743: vmx| Use TPR shadow {0,1} Feb 11 02:40:34.743: vmx| NMI-window exiting {0,1} Feb 11 02:40:34.743: vmx| MOV-DR exiting {0,1} Feb 11 02:40:34.743: vmx| Unconditional I/O exiting {0,1} Feb 11 02:40:34.743: vmx| Use I/O bitmaps {0,1} Feb 11 02:40:34.743: vmx| Monitor trap flag {0,1} Feb 11 02:40:34.743: vmx| Use MSR bitmaps {0,1} Feb 11 02:40:34.743: vmx| MONITOR exiting {0,1} Feb 11 02:40:34.743: vmx| PAUSE exiting {0,1} Feb 11 02:40:34.743: vmx| Activate secondary controls {0,1} Feb 11 02:40:34.743: vmx| Secondary Processor-Based VM-Execution Controls (0x000008ff00000000) Feb 11 02:40:34.743: vmx| Virtualize APIC accesses {0,1} Feb 11 02:40:34.743: vmx| Enable EPT {0,1} Feb 11 02:40:34.743: vmx| Descriptor-table exiting {0,1} Feb 11 02:40:34.743: vmx| Enable RDTSCP {0,1} Feb 11 02:40:34.743: vmx| Virtualize x2APIC mode {0,1} Feb 11 02:40:34.743: vmx| Enable VPID {0,1} Feb 11 02:40:34.743: vmx| WBINVD exiting {0,1} Feb 11 02:40:34.743: vmx| Unrestricted guest {0,1} Feb 11 02:40:34.743: vmx| PAUSE-loop exiting { 0 } Feb 11 02:40:34.743: vmx| VM-Exit Controls (0x007fffff00036dfb) Feb 11 02:40:34.743: vmx| Save debug controls {0,1} Feb 11 02:40:34.743: vmx| Host address-space size {0,1} Feb 11 02:40:34.743: vmx| Load IA32_PERF_GLOBAL_CTRL {0,1} Feb 11 02:40:34.743: vmx| Acknowledge interrupt on exit {0,1} Feb 11 02:40:34.743: vmx| Save IA32_PAT {0,1} Feb 11 02:40:34.743: vmx| Load IA32_PAT {0,1} Feb 11 02:40:34.743: vmx| Save IA32_EFER {0,1} Feb 11 02:40:34.743: vmx| Load IA32_EFER {0,1} Feb 11 02:40:34.743: vmx| Save VMX-preemption timer {0,1} Feb 11 02:40:34.743: vmx| VM-Entry Controls (0x0000ffff000011fb) Feb 11 02:40:34.743: vmx| Load debug controls {0,1} Feb 11 02:40:34.743: vmx| IA-32e mode guest {0,1} Feb 11 02:40:34.743: vmx| Entry to SMM {0,1} Feb 11 02:40:34.743: vmx| Deactivate dual-monitor mode {0,1} Feb 11 02:40:34.743: vmx| Load IA32_PERF_GLOBAL_CTRL {0,1} Feb 11 02:40:34.743: vmx| Load IA32_PAT {0,1} Feb 11 02:40:34.743: vmx| Load IA32_EFER {0,1} Feb 11 02:40:34.743: vmx| VPID and EPT Capabilities (0x00000f0106114141) Feb 11 02:40:34.743: vmx| R=0/W=0/X=1 yes Feb 11 02:40:34.743: vmx| Page-walk length 3 yes Feb 11 02:40:34.743: vmx| EPT memory type WB yes Feb 11 02:40:34.744: vmx| 2MB super-page yes Feb 11 02:40:34.744: vmx| INVEPT support yes Feb 11 02:40:34.744: vmx| Type 1 INVEPT yes Feb 11 02:40:34.744: vmx| Type 2 INVEPT yes Feb 11 02:40:34.744: vmx| INVVPID support yes Feb 11 02:40:34.744: vmx| Type 0 INVVPID yes Feb 11 02:40:34.744: vmx| Type 1 INVVPID yes Feb 11 02:40:34.744: vmx| Type 2 INVVPID yes Feb 11 02:40:34.744: vmx| Type 3 INVVPID yes Feb 11 02:40:34.744: vmx| Miscellaneous VMX Data (0x00000000100401e5) Feb 11 02:40:34.744: vmx| TSC to preemption timer ratio 5 Feb 11 02:40:34.744: vmx| Unrestricted guests yes Feb 11 02:40:34.744: vmx| Activity State HLT yes Feb 11 02:40:34.744: vmx| Activity State shutdown yes Feb 11 02:40:34.744: vmx| Activity State wait-for-SIPI yes Feb 11 02:40:34.744: vmx| CR3 targets supported 4 Feb 11 02:40:34.744: vmx| Maximum MSR list size 512 Feb 11 02:40:34.744: vmx| MSEG revision ID 0 Feb 11 02:40:34.744: vmx| VMX-Fixed Bits in CR0 (0x0000000080000021/0x00000000ffffffff) Feb 11 02:40:34.744: vmx| Fixed to 0 0xffffffff00000000 Feb 11 02:40:34.744: vmx| Fixed to 1 0x0000000080000021 Feb 11 02:40:34.744: vmx| Variable 0x000000007fffffde Feb 11 02:40:34.744: vmx| VMX-Fixed Bits in CR4 (0x0000000000002000/0x00000000001767ff) Feb 11 02:40:34.744: vmx| Fixed to 0 0xffffffffffe89800 Feb 11 02:40:34.744: vmx| Fixed to 1 0x0000000000002000 Feb 11 02:40:34.744: vmx| Variable 0x00000000001747ff Feb 11 02:40:34.744: vmx| VMCS Enumeration (0x000000000000002a) Feb 11 02:40:34.744: vmx| Highest index 0x15 Feb 11 02:40:34.785: vmx| MsgHint: msg.loader.debug (sent) Feb 11 02:40:34.785: vmx| You are running the virtual machine with the DEBUG option. Please be advised that the additional logging and error checking enabled by this option results in substantially slower execution. Feb 11 02:40:34.785: vmx| Use the "Gather debugging information" setting in the Advanced panel of the virtual machine settings to change this option. Feb 11 02:40:34.785: vmx| --------------------------------------- Feb 11 02:40:34.789: vmx| VMMon_ConfigMemSched: vmmon.numVCPUs=1 Feb 11 02:40:34.789: vmx| CreateVM: Swap: generating normal swap file name. Feb 11 02:40:34.790: vmx| Swap file path: '/vmfs/volumes/55820bef-c4728a1a-8410-74e6e2d448b4/TURNKEY REDMINE/TURNKEY REDMINE-8334e425.vswp' Feb 11 02:40:34.815: vmx| Using swap file '/vmfs/volumes/55820bef-c4728a1a-8410-74e6e2d448b4/TURNKEY REDMINE/TURNKEY REDMINE-8334e425.vswp' Feb 11 02:40:34.815: vmx| VMXVmdb_LoadRawConfig: Loading raw config Feb 11 02:40:34.818: vmx| MXSemaphoreInit: Registered sema.935914.0 (cnxID = 1) Feb 11 02:40:34.818: vmx| MXSemaphoreInit: Registered sema.935914.1 (cnxID = 2) Feb 11 02:40:34.818: vmx| MX: init lock: rank(userlevelLock)=100 lid=0 Feb 11 02:40:34.818: vmx| MXSemaphoreInit: Registered sema.935914.2 (cnxID = 3) Feb 11 02:40:34.818: vmx| MX: init lock: rank(bigDeviceLock)=110 lid=1 Feb 11 02:40:34.818: vmx| MXSemaphoreInit: Registered sema.935914.3 (cnxID = 4) Feb 11 02:40:34.818: vmx| MX: init lock: rank(monAct)=65534 lid=2 Feb 11 02:40:34.818: vmx| MXSemaphoreInit: Registered sema.935914.4 (cnxID = 5) Feb 11 02:40:34.818: vmx| MXSemaphoreInit: Registered sema.935914.5 (cnxID = 6) Feb 11 02:40:34.818: vmx| MX: init lock: rank(stTable)=99 lid=3 Feb 11 02:40:34.818: vmx| MXSemaphoreInit: Registered sema.935914.6 (cnxID = 7) Feb 11 02:40:34.818: vmx| MX: init lock: rank(log)=0 lid=4 Feb 11 02:40:34.818: vmx| BusMem: Alloc'ing region BusError flags 0x890 numPages 1 Feb 11 02:40:34.818: vmx| BusMem: Alloc'ing frames for region BusError with 1 pages, size 4096 and 1 frame mapping entries. Feb 11 02:40:34.818: vmx| MX: init condvar: RestoreCond Feb 11 02:40:34.818: vmx| Monitor64_PowerOn() Feb 11 02:40:34.819: vmx| vmm64->cr3 = 0xbf7fb000, vmkContext = 0x4100bcf63ed8, vmkCR3 = 0x3f39f000 Feb 11 02:40:34.819: vmx| vmm64-modules: [vmm.vmm64 .data:0xfffffffffc052000-0x17c4 .pervcpu:0xfffffffffc054000-0xb40 .sdata:0xfffffffffc055000-0x9b0 .statvars:0xfffffffffc056000-0x210 .shared:0xfffffffffc057000-0x20f00 .bss:0xfffffffffc07a000-0x3c9e8 .rodata:0xfffffffffc200000-0x193e0 .text:0xfffffffffc21f000-0xd7d14 .kstatvars:0x3000-0x0 .eh_frame:0xfffffffffc338000-0x1ad80, mmu-hwmmu.vmm64 .rodata:0xfffffffffc2193e0-0x5bf .data:0xfffffffffc0537c4-0x14 .shared:0xfffffffffc077f00-0x540 .bss:0xfffffffffc0b6a00-0x201d .text:0xfffffffffc2f6d20-0x19df8 .statvars:0x2000-0x0 .kstatvars:0x2000-0x0 .scb:0x4fe0-0x120 .shared_meta:0x55e0-0x2d0 .patchtext:0x420-0xb0 .eh_frame:0xfffffffffc352d80-0x2610 .comment:0xefa-0x10e, pv-none.vmm64 .shared:0xfffffffffc078440-0x440 .bss:0x1000-0x0 .text:0xfffffffffc310b18-0x4 .shared_meta:0x58b0-0x90 .eh_frame:0xfffffffffc355390-0x48 .comment:0x1008-0x48, vprobe-none.vmm64 .text:0xfffffffffc310b1c-0x1a .eh_frame:0xfffffffffc3553d8-0xf0 .comment:0x1050-0x12, hv-vt.vmm64 .rodata:0xfffffffffc2199a0-0x2abc .data:0xfffffffffc0537e0-0x50 .shared:0x4000-0x0 .bss:0xfffffffffc0b8a20-0x32c11 .text:0xfffffffffc310b40-0x22015 .statvars:0x4000-0x0 .kstatvars:0x4000-0x0 .eh_frame:0xfffffffffc3554c8-0x3628 .comment:0x1062-0x5a .patchtext:0x4d0-0x20, gphys-ept.vmm64 .rodata:0xfffffffffc21c460-0x2b7 .data:0xfffffffffc053830-0xc .shared:0xfffffffffc078880-0xc0 .bss:0xfffffffffc0eb640-0xa2 .text:0xfffffffffc332b60-0x4944 .statvars:0x2000-0x0 .kstatvars:0x2000-0x0 .scb:0x5100-0x60 .shared_meta:0x5940-0x330 .patchtext:0x4f0-0x30 .eh_frame:0xfffffffffc358af0-0x830 .comment:0x10bc-0x12, vmsafe-none.vmm64 .shared:0xfffffffffc078940-0x40 .bss:0x1000-0x0 .text:0xfffffffffc3374a4-0x32 .shared_meta:0x5c70-0x30 .eh_frame:0xfffffffffc359320-0xc8 .comment:0x10ce-0x12, videio-none.vmm64 .rodata:0xfffffffffc21c718-0xdf .data:0x1000-0x0 .shared:0x1000-0x0 .bss:0x1000-0x0 .text:0xfffffffffc3374d8-0xaa .statvars:0x1000-0x0 .kstatvars:0x1000-0x0 .eh_frame:0xfffffffffc3593e8-0x160 .comment:0x10e0-0x12, {PerVcpu} .shared:0xfffffffffc079000-0x1000, .rodata:0xfffffffffc21c7f7-0x2093] Feb 11 02:40:34.819: vmx| vmm64-vcpus: 1 Feb 11 02:40:34.821: vmx| KHZEstimate 3092975 Feb 11 02:40:34.821: vmx| MHZEstimate 3093 Feb 11 02:40:34.821: vmx| NumVCPUs 1 Feb 11 02:40:34.821: vmx| Guest OS (20505) not supported for replay debugging. Feb 11 02:40:34.821: vmx| MXSemaphoreInit: Registered sema.935914.7 (cnxID = 8) Feb 11 02:40:34.821: vmx| MX: init lock: rank(iospaceLock)=120 lid=5 Feb 11 02:40:34.821: vmx| MXSemaphoreInit: Registered sema.935914.8 (cnxID = 9) Feb 11 02:40:34.821: vmx| MX: init lock: rank(BackdoorHintsBufferLock)=65534 lid=6 Feb 11 02:40:34.822: vmx| MXSemaphoreInit: Registered sema.935914.9 (cnxID = 10) Feb 11 02:40:34.822: vmx| MX: init lock: rank(BusMemRemapQueueLock)=65534 lid=7 Feb 11 02:40:34.822: vmx| UUID: location-UUID is 56 4d 0a 47 e4 86 d0 de-d2 08 64 ad 0b 25 34 b3 Feb 11 02:40:34.822: vmx| MXSemaphoreInit: Registered sema.935914.10 (cnxID = 11) Feb 11 02:40:34.822: vmx| MX: init lock: rank(WorkerLock)=65534 lid=8 Feb 11 02:40:34.822: vmx| MX: init condvar: WorkerPending Feb 11 02:40:34.822: vmx| MXSemaphoreInit: Registered sema.935914.11 (cnxID = 12) Feb 11 02:40:34.822: vmx| MX: init condvar: WorkerCompleted Feb 11 02:40:34.822: vmx| AIOGNRC: numThreads=1 ide=0, scsi=0, passthru=0 Feb 11 02:40:34.822: vmx| WORKER: Creating new group with numThreads=1 (1) Feb 11 02:40:34.832: vmx| StateLogger::nonDeterFxsaveBPs: 0x0 0x0 Feb 11 02:40:34.832: vmx| StateLogger::Replay State = 0 Feb 11 02:40:34.832: vmx| StateLogger::minDEThreshold: 79 Feb 11 02:40:34.832: vmx| BusMem: Alloc'ing region MainMem flags 0x2400 numPages 131072 Feb 11 02:40:34.832: vmx| BusMem: Alloc'ing frames for region MainMem with 512 pages, size 2097152 and 1 frame mapping entries. Feb 11 02:40:34.834: vmx| MX: init condvar: MMCpt Feb 11 02:40:34.834: vmx| MXSemaphoreInit: Registered sema.935914.12 (cnxID = 13) Feb 11 02:40:34.834: vmx| MX: init lock: rank(MMCpt)=112 lid=9 Feb 11 02:40:34.834: vmx| MMC: Initialized PLS=1 PLR=0 PFS=0 TS=1 BS=0 WZ=1 BufM=0 SOR=0 BlkP=64 W=25 PF=512 Feb 11 02:40:34.834: vmx| MMCHK: Disabling checks which are not forced, cannot do checks on certain processors. Feb 11 02:40:34.834: vmx| MStat: Creating Stat vm.uptime Feb 11 02:40:34.834: vmx| MStat: Creating Stat vm.suspendTime Feb 11 02:40:34.834: vmx| MStat: Creating Stat vm.powerOnTimeStamp Feb 11 02:40:34.834: vmx| VMXAIOMGR: Using: simple=Generic unbuf=Generic Feb 11 02:40:34.836: vmx| MXSemaphoreInit: Registered sema.935914.13 (cnxID = 14) Feb 11 02:40:34.836: vmx| MX: init lock: rank(intrLock)=65531 lid=10 Feb 11 02:40:34.836: vmx| BusMem: Alloc'ing region LocalApic flags 0x1230 numPages 1 Feb 11 02:40:34.836: vmx| BusMem: Alloc'ing frames for region LocalApic with 1 pages, size 4096 and 1 frame mapping entries. Feb 11 02:40:34.840: vmx| VMXVmdb_LoadRawConfig: Loading raw config Feb 11 02:40:34.842: vmx| DISK: OPEN scsi0:0 '/vmfs/volumes/55820bef-c4728a1a-8410-74e6e2d448b4/TURNKEY REDMINE/TURNKEY REDMINE.vmdk' persistent R[] Feb 11 02:40:34.844: vmx| DISKLIB-VMFS : "/vmfs/volumes/55820bef-c4728a1a-8410-74e6e2d448b4/TURNKEY REDMINE/TURNKEY REDMINE-flat.vmdk" : open successful (10) size = 21474836480, hd = 10158935. Type 3 Feb 11 02:40:34.844: vmx| DISKLIB-DSCPTR: Opened [0]: "TURNKEY REDMINE-flat.vmdk" (0xa) Feb 11 02:40:34.844: vmx| DISKLIB-LINK : Opened '/vmfs/volumes/55820bef-c4728a1a-8410-74e6e2d448b4/TURNKEY REDMINE/TURNKEY REDMINE.vmdk' (0xa): vmfs, 41943040 sectors / 20 GB. Feb 11 02:40:34.844: vmx| DISKLIB-LIB : Opened "/vmfs/volumes/55820bef-c4728a1a-8410-74e6e2d448b4/TURNKEY REDMINE/TURNKEY REDMINE.vmdk" (flags 0xa, type vmfs). Feb 11 02:40:34.845: vmx| DISKLIB-LIB : Content-ID check is now enabled. Feb 11 02:40:34.845: vmx| DISK: Disk '/vmfs/volumes/55820bef-c4728a1a-8410-74e6e2d448b4/TURNKEY REDMINE/TURNKEY REDMINE.vmdk' has UUID '60 00 c2 91 b3 20 ab 14-1f 92 18 ab d0 a1 7d 22' Feb 11 02:40:34.845: vmx| DISK: OPEN '/vmfs/volumes/55820bef-c4728a1a-8410-74e6e2d448b4/TURNKEY REDMINE/TURNKEY REDMINE.vmdk' Geo (2610/255/63) BIOS Geo (0/0/0) Feb 11 02:40:34.847: vmx| MXSemaphoreInit: Registered sema.935914.14 (cnxID = 15) Feb 11 02:40:34.847: vmx| MX: init lock: rank(timeTracker)=65532 lid=11 Feb 11 02:40:34.847: vmx| TimeTracker host to guest rate conversion 1395960878457093 @ 3092975000Hz -> 1395960878457093 @ 3092975000Hz Feb 11 02:40:34.847: vmx| TimeTracker host to guest rate conversion ((x * 2147483648) >> 31) + 0 Feb 11 02:40:34.847: vmx| DISK: DELAY: vide.vtdelay=0 hard-disk.minVirtualTime=0 magicBoot1=0 Feb 11 02:40:34.847: vmx| VUsb powered on, but no USB controllers. Feb 11 02:40:34.847: vmx| MXSemaphoreInit: Registered sema.935914.15 (cnxID = 16) Feb 11 02:40:34.847: vmx| MX: init lock: rank(mksLock)=130 lid=12 Feb 11 02:40:34.847: vmx| MXSemaphoreInit: Registered sema.935914.16 (cnxID = 17) Feb 11 02:40:34.847: vmx| MX: init lock: rank(PseudoCallUserLock)=128 lid=13 Feb 11 02:40:34.847: vmx| MXSemaphoreInit: Registered sema.935914.17 (cnxID = 18) Feb 11 02:40:34.847: vmx| MX: init lock: rank(mksRolePseudoCallLock)=129 lid=14 Feb 11 02:40:34.847: vmx| MX: init condvar: mksRolePseudoCallCondVar Feb 11 02:40:34.847: vmx| MKS not attempting to initialize X. Feb 11 02:40:34.848: vmx| WORKER: Creating new group with numThreads=1 (2) Feb 11 02:40:34.848: vmx| MKS: Base polling period is 1000000us Feb 11 02:40:34.848: vmx| KHBKL: Unable to parse keystring at: '' Feb 11 02:40:34.848: vmx| MKS REMOTE Loading VNC Configuration from VM config file Feb 11 02:40:34.849: vmx| VLANCE: send cluster threshold is 80, size = 2 recalcInterval is 2 ticks Feb 11 02:40:34.849: vmx| BusMem: Alloc'ing region pcieMMIO flags 0x1020 numPages 65536 Feb 11 02:40:34.849: vmx| BusMem: Alloc'ing frames for region pcieMMIO with 256 pages, size 1048576 and 1 frame mapping entries. Feb 11 02:40:34.849: vmx| Chipset version: 0x13 Feb 11 02:40:34.850: vmx| BusMem: Alloc'ing region FlashRam flags 0x10 numPages 128 Feb 11 02:40:34.850: vmx| BusMem: Alloc'ing frames for region FlashRam with 1 pages, size 4096 and 1 frame mapping entries. Feb 11 02:40:34.850: vmx| BusMem: Alloc'ing region ExtCfgDeviceMMIO flags 0x1020 numPages 24 Feb 11 02:40:34.850: vmx| BusMem: Alloc'ing frames for region ExtCfgDeviceMMIO with 1 pages, size 4096 and 1 frame mapping entries. Feb 11 02:40:34.853: vmx| MXSemaphoreInit: Registered sema.935914.18 (cnxID = 19) Feb 11 02:40:34.853: vmx| MX: init lock: rank(VIDELockMR)=60 lid=15 Feb 11 02:40:34.853: vmx| MXSemaphoreInit: Registered sema.935914.19 (cnxID = 20) Feb 11 02:40:34.853: vmx| MX: init lock: rank(VIDELock)=111 lid=16 Feb 11 02:40:34.853: vmx| SCSI: scsi0: intr coalescing: on period=50msec cifTh=4 iopsTh=2000 hlt=0 Feb 11 02:40:34.853: vmx| BusMem: Alloc'ing region LSI_MEM_0 flags 0x1020 numPages 1 Feb 11 02:40:34.853: vmx| BusMem: Alloc'ing frames for region LSI_MEM_0 with 1 pages, size 4096 and 1 frame mapping entries. Feb 11 02:40:34.853: vmx| BusMem: Alloc'ing region LSI_MEM2_0 flags 0x1020 numPages 32 Feb 11 02:40:34.853: vmx| BusMem: Alloc'ing frames for region LSI_MEM2_0 with 1 pages, size 4096 and 1 frame mapping entries. Feb 11 02:40:34.853: vmx| MXSemaphoreInit: Registered sema.935914.20 (cnxID = 21) Feb 11 02:40:34.853: vmx| MX: init lock: rank(LSI_LCK_0)=60 lid=17 Feb 11 02:40:34.853: vmx| SCSI0: UNTAGGED commands will be converted to ORDER tags. Feb 11 02:40:34.853: vmx| Creating virtual dev for scsi0:0 Feb 11 02:40:34.853: vmx| DumpDiskInfo: scsi0:0 createType=11, capacity = 41943040, numLinks = 1, allocationType = 1 Feb 11 02:40:34.854: vmx| StateLogger::VM has zeroedthick disk scsi0:0; FT enable will be disallowedSCSIDiskESXPopulateVDevDesc: Using FS backend Feb 11 02:40:34.854: vmx| MXSemaphoreInit: Registered sema.935914.21 (cnxID = 22) Feb 11 02:40:34.854: vmx| MX: init lock: rank(vgaLock)=60 lid=18 Feb 11 02:40:34.854: vmx| MXSemaphoreInit: Registered sema.935914.22 (cnxID = 23) Feb 11 02:40:34.854: vmx| MX: init lock: rank(svgaLock)=30 lid=19 Feb 11 02:40:34.854: vmx| MXSemaphoreInit: Registered sema.935914.23 (cnxID = 24) Feb 11 02:40:34.854: vmx| MX: init lock: rank(svgaIrqMutex)=65530 lid=20 Feb 11 02:40:34.854: vmx| MXSemaphoreInit: Registered sema.935914.24 (cnxID = 25) Feb 11 02:40:34.854: vmx| MX: init lock: rank(PseudoCallUserLock)=100 lid=21 Feb 11 02:40:34.854: vmx| MXSemaphoreInit: Registered sema.935914.25 (cnxID = 26) Feb 11 02:40:34.854: vmx| MX: init lock: rank(svgaPseudoCallLock)=101 lid=22 Feb 11 02:40:34.854: vmx| MX: init condvar: svgaPseudoCallCondVar Feb 11 02:40:34.854: vmx| MXSemaphoreInit: Registered sema.935914.26 (cnxID = 27) Feb 11 02:40:34.854: vmx| MX: init lock: rank(SVGAGuestMemLock)=65532 lid=23 Feb 11 02:40:34.854: vmx| SVGA: Device capabilities 0x001f83e2 Feb 11 02:40:34.858: vmx| Msg_Post: Warning Feb 11 02:40:34.858: vmx| [msg.svgaUI.badLimits] Insufficient video RAM. The maximum resolution of the virtual machine will be limited to 1176x885. To use the configured maximum resolution of 2560x1600, increase the amount of video RAM allocated to this virtual machine by setting svga.vramSize="16384000" in the virtual machine's configuration file. Feb 11 02:40:34.858: vmx| Feb 11 02:40:34.858: vmx| ---------------------------------------- Feb 11 02:40:34.870: vmx| SVGA: Truncated max res to VRAM size: 4194304 bytes VRAM, 1176x885 Max Resolution Feb 11 02:40:34.870: vmx| SVGA: FIFO capabilities 0x0000007f Feb 11 02:40:34.870: vmx| MXSemaphoreInit: Registered sema.935914.27 (cnxID = 28) Feb 11 02:40:34.870: vmx| MX: init lock: rank(vlanceLock)=30 lid=24 Feb 11 02:40:34.870: vmx| Ethernet0 MAC Address: 00:0c:29:25:34:b3 Feb 11 02:40:34.870: vmx| MXSemaphoreInit: Registered sema.935914.28 (cnxID = 29) Feb 11 02:40:34.870: vmx| MX: init lock: rank(VMCIDgTableVMX)=65534 lid=25 Feb 11 02:40:34.875: vmx| VMXVmdb_LoadRawConfig: Loading raw config Feb 11 02:40:34.877: vmx| MXSemaphoreInit: Registered sema.935914.29 (cnxID = 30) Feb 11 02:40:34.877: vmx| MXSemaphoreInit: Registered sema.935914.30 (cnxID = 31) Feb 11 02:40:34.877: vmx| MXSemaphoreInit: Registered sema.935914.31 (cnxID = 32) Feb 11 02:40:34.877: vmx| MXSemaphoreInit: Registered sema.935914.32 (cnxID = 33) Feb 11 02:40:34.877: vmx| MX: init lock: rank(VSockLookupLock)=65534 lid=26 Feb 11 02:40:34.877: vmx| MXSemaphoreInit: Registered sema.935914.33 (cnxID = 34) Feb 11 02:40:34.877: vmx| MXSemaphoreInit: Registered sema.935914.34 (cnxID = 35) Feb 11 02:40:34.877: vmx| MX: init lock: rank(timer)=30 lid=27 Feb 11 02:40:34.877: vmx| MStat: Creating Stat vm.heartbeat Feb 11 02:40:34.877: vmx| TOOLS Generated SessionId 10420092632977338546 Feb 11 02:40:34.877: vmx| VMXVmdbGuest_GetToolsVersion did nothing; tools version has not yet been initialized Feb 11 02:40:34.877: vmx| DISKUTIL: scsi0:0 : toolsVersion = 0 Feb 11 02:40:34.877: vmx| DISKUTIL: Offline toolsVersion = 0 Feb 11 02:40:34.877: vmx| VMXVmdbGuest_GetToolsVersion did nothing; tools version has not yet been initialized Feb 11 02:40:34.878: vmx| TOOLS setting legacy tools version to '0', manifest status is 5 Feb 11 02:40:34.878: vmx| VMXVmdb_SetToolsVersionState: status value set to 'notAvailable' Feb 11 02:40:34.878: vmx| VMXVmdb_SetToolsVersionState: status value set to 'notAvailable' Feb 11 02:40:34.878: vmx| TOOLS INSTALL initializing state to IDLE on power on. Feb 11 02:40:34.878: vmx| MXSemaphoreInit: Registered sema.935914.35 (cnxID = 36) Feb 11 02:40:34.878: vmx| MX: init lock: rank(PseudoCallUserLock)=128 lid=28 Feb 11 02:40:34.878: vmx| MXSemaphoreInit: Registered sema.935914.36 (cnxID = 37) Feb 11 02:40:34.878: vmx| MX: init lock: rank(vmxPseudoCallLock)=129 lid=29 Feb 11 02:40:34.878: vmx| MX: init condvar: vmxPseudoCallCondVar Feb 11 02:40:34.882: vmx| MXSemaphoreInit: Registered sema.935914.37 (cnxID = 38) Feb 11 02:40:34.882: vmx| MX: init lock: rank(InputEventLock)=65534 lid=30 Feb 11 02:40:34.882: vmx| MXSemaphoreInit: Registered sema.935914.38 (cnxID = 39) Feb 11 02:40:34.882: vmx| MX: init lock: rank(paraTime)=65532 lid=31 Feb 11 02:40:34.882: vmx| PTSC to VMI Wallclock (nsec) 1395960987859294 @ 3092975000Hz -> 1455158434000000000 @ 1000000000Hz Feb 11 02:40:34.882: vmx| PTSC to VMI Wallclock (nsec) ((x * 2777240227) >> 33) + 1454707101225660118 Feb 11 02:40:34.882: vmx| PTSC to ParaTime RealCycles 0 @ 3092975000Hz -> 0 @ 3092975000Hz Feb 11 02:40:34.882: vmx| PTSC to ParaTime RealCycles ((x * 1) >> 0) + 0 Feb 11 02:40:34.882: vmx| ParaTime RealCycles to PTSC 0 @ 3092975000Hz -> 0 @ 3092975000Hz Feb 11 02:40:34.882: vmx| ParaTime RealCycles to PTSC ((x * 1) >> 0) + 0 Feb 11 02:40:34.882: vmx| memoryHotplug: Current size = 512MB, Minimum size = 512MB, Maximum size = 512MB Feb 11 02:40:34.882: vmx| memoryHotplug: Entry[0]: 00000000000000A0-00000000000A0000 Feb 11 02:40:34.882: vmx| memoryHotplug: Entry[1]: 00000000001000A0-0000000020000000 Feb 11 02:40:34.882: vmx| OO: 0 16622 7514 : 0 16622 7514 : 0 13749 4442 Feb 11 02:40:34.882: vmx| VMMEM: Initial Reservation: 90MB (MainMem=512MB SVGA=4MB) Feb 11 02:40:34.882: vmx| OO: 0 11982 4442 Feb 11 02:40:34.882: vmx| VMMEM: Precise Reservation: 59MB (MainMem=512MB SVGA=4MB) Feb 11 02:40:34.882: vmx| BusMem: Alloc'ing region LSIBIOS flags 0x90 numPages 4 Feb 11 02:40:34.882: vmx| BusMem: Alloc'ing frames for region LSIBIOS with 1 pages, size 4096 and 1 frame mapping entries. Feb 11 02:40:34.882: vmx| BusMem: Alloc'ing region VBIOS flags 0x90 numPages 8 Feb 11 02:40:34.882: vmx| BusMem: Alloc'ing frames for region VBIOS with 1 pages, size 4096 and 1 frame mapping entries. Feb 11 02:40:34.883: vmx| BusMem: Alloc'ing region VLANCE flags 0x90 numPages 16 Feb 11 02:40:34.883: vmx| BusMem: Alloc'ing frames for region VLANCE with 1 pages, size 4096 and 1 frame mapping entries. Feb 11 02:40:34.883: vmx| guestCpuFeatures = 0x404001fd Feb 11 02:40:34.885: vmx| VMXVmdb_LoadRawConfig: Loading raw config Feb 11 02:40:34.887: vmx| Guest CPUID differences from hostCPUID. Feb 11 02:40:34.887: vmx| guestCPUID level 00000001, 0: 0x000306a9 0x00010800 0x82982203 0x0febfbff Feb 11 02:40:34.887: vmx| guestCPUID level 40000000, 0: 0x40000010 0x61774d56 0x4d566572 0x65726177 Feb 11 02:40:34.887: vmx| guestCPUID level 40000010, 0: 0x002f31ef 0x000101d0 0x00000000 0x00000000 Feb 11 02:40:34.887: vmx| guestCPUID level 80000008, 0: 0x00003028 0x00000000 0x00000000 0x00000000 Feb 11 02:40:34.888: vmx| BusMem: Alloc'ing region SVGAFB flags 0x10 numPages 1024 Feb 11 02:40:34.888: vmx| BusMem: Alloc'ing frames for region SVGAFB with 4 pages, size 16384 and 1 frame mapping entries. Feb 11 02:40:34.889: vmx| BusMem: Alloc'ing region SVGAMEM flags 0x10 numPages 64 Feb 11 02:40:34.889: vmx| BusMem: Alloc'ing frames for region SVGAMEM with 1 pages, size 4096 and 1 frame mapping entries. Feb 11 02:40:34.889: vmx| BusMemSample: touched: initPct 75 pages 98304 : dirtied: initPct 75 pages 98304 Feb 11 02:40:34.889: vmx| MXSemaphoreInit: Registered sema.935914.39 (cnxID = 40) Feb 11 02:40:34.889: vmx| MX: init lock: rank(pollLock)=65533 lid=32 Feb 11 02:40:34.889: vmx| MXSemaphoreInit: Registered sema.935914.40 (cnxID = 41) Feb 11 02:40:34.889: vmx| MXSemaphoreInit: Registered sema.935914.41 (cnxID = 42) Feb 11 02:40:34.889: vmx| MXSemaphoreInit: Registered sema.935914.42 (cnxID = 43) Feb 11 02:40:34.889: vmx| MXSemaphoreInit: Registered sema.935914.43 (cnxID = 44) Feb 11 02:40:34.889: vmx| MXSemaphoreInit: Registered sema.935914.44 (cnxID = 45) Feb 11 02:40:34.889: vmx| MXSemaphoreInit: Registered sema.935914.45 (cnxID = 46) Feb 11 02:40:34.889: vmx| MXSemaphoreInit: Registered sema.935914.46 (cnxID = 47) Feb 11 02:40:34.889: vmx| Vix: [935914 mainDispatch.c:736]: VMAutomation_PowerOn. Powering on. Feb 11 02:40:34.889: vmx| MXSemaphoreInit: Registered sema.935914.47 (cnxID = 48) Feb 11 02:40:34.889: vmx| MX: init lock: rank(VMAutomationConnectionListLock)=0 lid=33 Feb 11 02:40:34.889: vmx| MXSemaphoreInit: Registered sema.935914.48 (cnxID = 49) Feb 11 02:40:34.889: vmx| MX: init lock: rank(vmxStateCacheLock)=0 lid=34 Feb 11 02:40:34.889: vmx| VMX_PowerOn: ModuleTable_PowerOn = 1 Feb 11 02:40:34.906: vmx| VMXVmdb_LoadRawConfig: Loading raw config Feb 11 02:40:34.923: vmx| Setting thread 1 stack size to 1048576. Feb 11 02:40:34.923: mks| MXSemaphoreInit: Registered sema.935914.49 (cnxID = 50) Feb 11 02:40:34.923: mks| Async MKS thread is alive Feb 11 02:40:34.923: vmx| Setting thread 4 stack size to 524288. Feb 11 02:40:34.923: vcpu-0| MXSemaphoreInit: Registered sema.935914.50 (cnxID = 51) Feb 11 02:40:34.923: vcpu-0| VMMon_Start: vcpu-0: worldID=940011 Feb 11 02:40:34.924: vcpu-0| MXSemaphoreInit: Registered sema.935914.51 (cnxID = 52) Feb 11 02:40:34.924: vcpu-0| MX: init lock: rank(PassthruMgrLock)=65534 lid=35 Feb 11 02:40:34.924: vcpu-0| MXSemaphoreInit: Registered sema.935914.52 (cnxID = 53) Feb 11 02:40:34.924: vcpu-0| MX: init lock: rank(monitorPoll)=65533 lid=36 Feb 11 02:40:34.924: vcpu-0| APIC: version = 0x15, max LVT = 6 Feb 11 02:40:34.924: vcpu-0| APIC: LDR = 0x2000000, DFR = 0xffffffff Feb 11 02:40:34.924: vcpu-0| MXSemaphoreInit: Registered sema.935914.53 (cnxID = 54) Feb 11 02:40:34.924: vcpu-0| MX: init lock: rank(stopLock)=1 lid=37 Feb 11 02:40:34.924: vcpu-0| MXSemaphoreInit: Registered sema.935914.54 (cnxID = 55) Feb 11 02:40:34.924: vcpu-0| MXSemaphoreInit: Registered sema.935914.55 (cnxID = 56) Feb 11 02:40:34.924: vcpu-0| BUSMEM: Initialization (boot-vcpu=1 vmm32=0) Feb 11 02:40:34.924: vcpu-0| MXSemaphoreInit: Registered sema.935914.56 (cnxID = 57) Feb 11 02:40:34.924: vcpu-0| MX: init lock: rank(busMemLock)=80 lid=38 Feb 11 02:40:34.924: vcpu-0| BUSMEM: vmm32 initialization Feb 11 02:40:34.924: vcpu-0| BUSMEM: Allocating frames for slot 0 Feb 11 02:40:34.924: vcpu-0| BUSMEM: Allocating frames for slot 1 Feb 11 02:40:34.925: vcpu-0| BUSMEM: Allocating frames for slot 2 Feb 11 02:40:34.925: vcpu-0| BUSMEM: Allocating frames for slot 3 Feb 11 02:40:34.929: vcpu-0| BUSMEM: Allocating frames for slot 4 Feb 11 02:40:34.929: vcpu-0| BUSMEM: Allocating frames for slot 5 Feb 11 02:40:34.929: vcpu-0| BUSMEM: Allocating frames for slot 6 Feb 11 02:40:34.929: vcpu-0| BUSMEM: Allocating frames for slot 7 Feb 11 02:40:34.929: vcpu-0| BUSMEM: Allocating frames for slot 8 Feb 11 02:40:34.929: vcpu-0| BUSMEM: Allocating frames for slot 9 Feb 11 02:40:34.929: vcpu-0| BUSMEM: Allocating frames for slot 10 Feb 11 02:40:34.929: vcpu-0| BUSMEM: Allocating frames for slot 11 Feb 11 02:40:34.929: vcpu-0| BUSMEM: Allocating frames for slot 12 Feb 11 02:40:34.929: vcpu-0| BUSMEM: mtag {0 frame; size 16} Feb 11 02:40:34.929: vcpu-0| MXSemaphoreInit: Registered sema.935914.57 (cnxID = 58) Feb 11 02:40:34.930: vcpu-0| MX: init lock: rank(mmuInfoLock)=98 lid=39 Feb 11 02:40:34.930: vcpu-0| BranchTracingInit (before): debugCtl 0x0 Feb 11 02:40:34.930: vcpu-0| BranchTracingInit (after): debugCtl 0x1 Feb 11 02:40:34.930: vcpu-0| MXSemaphoreInit: Registered sema.935914.58 (cnxID = 59) Feb 11 02:40:34.930: vcpu-0| MX: init lock: rank(mwaitLock)=39 lid=40 Feb 11 02:40:34.930: vcpu-0| Init modules. Feb 11 02:40:34.930: vcpu-0| BusMem: Alloc'ing region VGA flags 0x1020 numPages 32 Feb 11 02:40:34.930: vcpu-0| BusMem: Alloc'ing frames for region VGA with 1 pages, size 4096 and 1 frame mapping entries. Feb 11 02:40:34.930: vcpu-0| BUSMEM: Allocating frames for slot 13 Feb 11 02:40:34.930: vcpu-0| LSI:Initialize adapter 0 Feb 11 02:40:34.930: vcpu-0| BusMem: Alloc'ing region IOAPIC flags 0x1020 numPages 1 Feb 11 02:40:34.930: vcpu-0| BusMem: Alloc'ing frames for region IOAPIC with 1 pages, size 4096 and 1 frame mapping entries. Feb 11 02:40:34.930: vcpu-0| BUSMEM: Allocating frames for slot 14 Feb 11 02:40:34.930: vcpu-0| CPU reset: hard (mode 2) Feb 11 02:40:34.930: vcpu-0| VT_ClearVirtualTSC(void) -1395960878457094 Feb 11 02:40:34.930: vcpu-0| deviceLock.tryLock.failProb debug failure probability = 0.000000 Feb 11 02:40:34.930: vcpu-0| deviceLock.tryLock.seed = 935914 Feb 11 02:40:34.930: vcpu-0| memoryHotplug: Entry[0]: 00000000000000A0-00000000000A0000 Feb 11 02:40:34.930: vcpu-0| memoryHotplug: Entry[1]: 00000000001000A0-0000000020000000 Feb 11 02:40:34.930: vcpu-0| PIIX4: PM Resuming from suspend type 0x0, chipset.onlineStandby 1 Feb 11 02:40:34.931: vcpu-0| Intel VT: FlexPriority enabled. Feb 11 02:40:34.931: vcpu-0| Intel VT: VPID enabled. Feb 11 02:40:34.931: vcpu-0| HV enabled Feb 11 02:40:34.931: vcpu-0| MXSemaphoreInit: Registered sema.935914.59 (cnxID = 60) Feb 11 02:40:34.931: vcpu-0| MX: init lock: rank(gbusLock)=65534 lid=41 Feb 11 02:40:34.931: vcpu-0| MXSemaphoreInit: Registered sema.935914.60 (cnxID = 61) Feb 11 02:40:34.931: vcpu-0| MX: init lock: rank(tcCohLock)=39 lid=42 Feb 11 02:40:34.937: vcpu-0| MXSemaphoreInit: Registered sema.935914.61 (cnxID = 62) Feb 11 02:40:34.937: vcpu-0| MX: init lock: rank(barrierLck)=1 lid=43 Feb 11 02:40:34.937: vcpu-0| MX: init condvar: barrierCV Feb 11 02:40:34.937: vcpu-0| MXSemaphoreInit: Registered sema.935914.62 (cnxID = 63) Feb 11 02:40:34.937: vcpu-0| MX: init lock: rank(restoreLock)=99 lid=44 Feb 11 02:40:34.937: vcpu-0| Vix: [944110 mainDispatch.c:3709]: VMAutomation_ReportPowerOpFinished: statevar=0, newAppState=1872, success=1 additionalError=0 Feb 11 02:40:34.939: vcpu-0| Vix: [944110 mainDispatch.c:3630]: VMAutomationReportPowerStateChange: Reporting power state change (opcode=0, err=0). Feb 11 02:40:34.939: vcpu-0| Vix: [944110 mainDispatch.c:3630]: VMAutomationReportPowerStateChange: Reporting power state change (opcode=2, err=0). Feb 11 02:40:34.939: vcpu-0| Transitioned vmx/execState/val to poweredOn Feb 11 02:40:34.939: vcpu-0| VMM64 initialized Feb 11 02:40:34.999: vcpu-0| MonitorInitNumaUnmapVMM64 Feb 11 02:40:35.000: vmx| Vix: [935914 mainDispatch.c:1567]: VMAutomationAcceptNewConnection: Connection from local (Privileged? Yes). Feb 11 02:40:35.017: vcpu-0| RDMSR: unknown MSR[0x17] (read as zero): rip=0x45a9 count=1 Feb 11 02:40:35.022: vcpu-0| WRMSR: unknown MSR[0x404]:=0 (sinking): rip=0x15d5 count=1 Feb 11 02:40:35.022: vcpu-0| WRMSR: unknown MSR[0x408]:=0 (sinking): rip=0x15d5 count=1 Feb 11 02:40:35.022: vcpu-0| WRMSR: unknown MSR[0x40c]:=0 (sinking): rip=0x15d5 count=1 Feb 11 02:40:35.023: vcpu-0| WRMSR: unknown MSR[0x410]:=0 (sinking): rip=0x15d5 count=1 Feb 11 02:40:35.040: vcpu-0| WRMSR: unknown MSR[0x8b]:=1b00000001 (sinking): rip=0x2ec0 count=1 Feb 11 02:40:35.074: vcpu-0| RDMSR: MSR[0x11e] (read as 708101): rip=0x4651 Feb 11 02:40:35.074: vcpu-0| WRMSR: unknown MSR[0x11e]:=708001 (sinking): rip=0x4656 count=1 Feb 11 02:40:35.076: vcpu-0| RDMSR: MSR[0x11e] (read as 708101): rip=0x4651 Feb 11 02:40:35.076: vcpu-0| WRMSR: unknown MSR[0x11e]:=708001 (sinking): rip=0x4656 count=2 Feb 11 02:40:35.078: vcpu-0| RDMSR: MSR[0x11e] (read as 708101): rip=0x4651 Feb 11 02:40:35.078: vcpu-0| WRMSR: unknown MSR[0x11e]:=708001 (sinking): rip=0x4656 count=3 Feb 11 02:40:35.095: vcpu-0| RDMSR: unknown MSR[0x2a] (read as zero): rip=0x57cc count=1 Feb 11 02:40:35.095: vcpu-0| RDMSR: MSR[0x11e] (read as 708101): rip=0x57e8 Feb 11 02:40:35.096: vcpu-0| RDMSR: MSR[0x11e] (read as 708101): rip=0x5ba0 Feb 11 02:40:35.101: vcpu-0| WRMSR: unknown MSR[0x116]:=0 (sinking): rip=0x152c count=1 Feb 11 02:40:35.102: vcpu-0| WRMSR: unknown MSR[0x88]:=0 (sinking): rip=0x153a count=1 Feb 11 02:40:35.102: vcpu-0| WRMSR: unknown MSR[0x89]:=0 (sinking): rip=0x153a count=1 Feb 11 02:40:35.102: vcpu-0| WRMSR: unknown MSR[0x8a]:=0 (sinking): rip=0x153a count=1 Feb 11 02:40:35.102: vcpu-0| WRMSR: unknown MSR[0x8b]:=0 (sinking): rip=0x153a count=2 Feb 11 02:40:35.102: vcpu-0| RDMSR: unknown MSR[0x119] (read as zero): rip=0x154d count=1 Feb 11 02:40:35.102: vcpu-0| WRMSR: unknown MSR[0x119]:=2 (sinking): rip=0x155a count=2 Feb 11 02:40:35.102: vcpu-0| WRMSR: unknown MSR[0x11a]:=0 (sinking): rip=0x1568 count=1 Feb 11 02:40:35.102: vcpu-0| RDMSR: unknown MSR[0x11b] (read as zero): rip=0x1573 count=1 Feb 11 02:40:35.102: vcpu-0| RDMSR: unknown MSR[0x116] (read as zero): rip=0x14f1 count=2 Feb 11 02:40:35.102: vcpu-0| WRMSR: unknown MSR[0x116]:=60 (sinking): rip=0x152c count=3 Feb 11 02:40:35.102: vcpu-0| WRMSR: unknown MSR[0x88]:=0 (sinking): rip=0x153a count=2 Feb 11 02:40:35.102: vcpu-0| WRMSR: unknown MSR[0x89]:=0 (sinking): rip=0x153a count=2 Feb 11 02:40:35.102: vcpu-0| WRMSR: unknown MSR[0x8a]:=0 (sinking): rip=0x153a count=2 Feb 11 02:40:35.102: vcpu-0| WRMSR: unknown MSR[0x8b]:=0 (sinking): rip=0x153a count=3 Feb 11 02:40:35.102: vcpu-0| RDMSR: unknown MSR[0x119] (read as zero): rip=0x154d count=3 Feb 11 02:40:35.102: vcpu-0| WRMSR: unknown MSR[0x119]:=2 (sinking): rip=0x155a count=4 Feb 11 02:40:35.102: vcpu-0| WRMSR: unknown MSR[0x11a]:=0 (sinking): rip=0x1568 count=2 Feb 11 02:40:35.102: vcpu-0| RDMSR: unknown MSR[0x11b] (read as zero): rip=0x1573 count=2 Feb 11 02:40:35.102: vcpu-0| RDMSR: unknown MSR[0x116] (read as zero): rip=0x14f1 count=4 Feb 11 02:40:35.335: vcpu-0| SVGA: Registering MemSpace at 0xd4000000(0x0) and 0xd8000000(0x0) Feb 11 02:40:35.339: vcpu-0| SVGA: Unregistering MemSpace at 0xd4000000(0xd4000000) and 0xd8000000(0xd8000000) Feb 11 02:40:35.450: vcpu-0| SVGA: Registering MemSpace at 0xd4000000(0xd4000000) and 0xd8000000(0xd8000000) Feb 11 02:40:35.456: vcpu-0| RDMSR: MSR[0x11e] (read as 708101): rip=0x4651 Feb 11 02:40:35.456: vcpu-0| WRMSR: unknown MSR[0x11e]:=708001 (sinking): rip=0x4656 count=4 Feb 11 02:40:35.457: vcpu-0| SVGA: Unregistering MemSpace at 0xd4000000(0xd4000000) and 0xd8000000(0xd8000000) Feb 11 02:40:35.459: vcpu-0| SVGA: Registering IOSpace at 0x10d0 Feb 11 02:40:35.459: vcpu-0| SVGA: Registering MemSpace at 0xd4000000(0xd4000000) and 0xd8000000(0xd8000000) Feb 11 02:40:35.466: vcpu-0| PCIBridge4: ISA/VGA decoding enabled (ctrl 0004) Feb 11 02:40:35.467: vcpu-0| pciBridge4:1: ISA/VGA decoding enabled (ctrl 0004) Feb 11 02:40:35.468: vcpu-0| pciBridge4:2: ISA/VGA decoding enabled (ctrl 0004) Feb 11 02:40:35.469: vcpu-0| pciBridge4:3: ISA/VGA decoding enabled (ctrl 0004) Feb 11 02:40:35.470: vcpu-0| pciBridge4:4: ISA/VGA decoding enabled (ctrl 0004) Feb 11 02:40:35.471: vcpu-0| pciBridge4:5: ISA/VGA decoding enabled (ctrl 0004) Feb 11 02:40:35.472: vcpu-0| pciBridge4:6: ISA/VGA decoding enabled (ctrl 0004) Feb 11 02:40:35.473: vcpu-0| pciBridge4:7: ISA/VGA decoding enabled (ctrl 0004) Feb 11 02:40:35.474: vcpu-0| PCIBridge5: ISA/VGA decoding enabled (ctrl 0004) Feb 11 02:40:35.475: vcpu-0| pciBridge5:1: ISA/VGA decoding enabled (ctrl 0004) Feb 11 02:40:35.476: vcpu-0| pciBridge5:2: ISA/VGA decoding enabled (ctrl 0004) Feb 11 02:40:35.477: vcpu-0| pciBridge5:3: ISA/VGA decoding enabled (ctrl 0004) Feb 11 02:40:35.478: vcpu-0| pciBridge5:4: ISA/VGA decoding enabled (ctrl 0004) Feb 11 02:40:35.479: vcpu-0| pciBridge5:5: ISA/VGA decoding enabled (ctrl 0004) Feb 11 02:40:35.480: vcpu-0| pciBridge5:6: ISA/VGA decoding enabled (ctrl 0004) Feb 11 02:40:35.481: vcpu-0| pciBridge5:7: ISA/VGA decoding enabled (ctrl 0004) Feb 11 02:40:35.482: vcpu-0| PCIBridge6: ISA/VGA decoding enabled (ctrl 0004) Feb 11 02:40:35.483: vcpu-0| pciBridge6:1: ISA/VGA decoding enabled (ctrl 0004) Feb 11 02:40:35.484: vcpu-0| pciBridge6:2: ISA/VGA decoding enabled (ctrl 0004) Feb 11 02:40:35.485: vcpu-0| pciBridge6:3: ISA/VGA decoding enabled (ctrl 0004) Feb 11 02:40:35.486: vcpu-0| pciBridge6:4: ISA/VGA decoding enabled (ctrl 0004) Feb 11 02:40:35.487: vcpu-0| pciBridge6:5: ISA/VGA decoding enabled (ctrl 0004) Feb 11 02:40:35.488: vcpu-0| pciBridge6:6: ISA/VGA decoding enabled (ctrl 0004) Feb 11 02:40:35.489: vcpu-0| pciBridge6:7: ISA/VGA decoding enabled (ctrl 0004) Feb 11 02:40:35.490: vcpu-0| PCIBridge7: ISA/VGA decoding enabled (ctrl 0004) Feb 11 02:40:35.492: vcpu-0| pciBridge7:1: ISA/VGA decoding enabled (ctrl 0004) Feb 11 02:40:35.493: vcpu-0| pciBridge7:2: ISA/VGA decoding enabled (ctrl 0004) Feb 11 02:40:35.494: vcpu-0| pciBridge7:3: ISA/VGA decoding enabled (ctrl 0004) Feb 11 02:40:35.495: vcpu-0| pciBridge7:4: ISA/VGA decoding enabled (ctrl 0004) Feb 11 02:40:35.496: vcpu-0| pciBridge7:5: ISA/VGA decoding enabled (ctrl 0004) Feb 11 02:40:35.497: vcpu-0| pciBridge7:6: ISA/VGA decoding enabled (ctrl 0004) Feb 11 02:40:35.498: vcpu-0| pciBridge7:7: ISA/VGA decoding enabled (ctrl 0004) Feb 11 02:40:35.566: vcpu-0| RDMSR: MSR[0x11e] (read as 708101): rip=0x4651 Feb 11 02:40:35.566: vcpu-0| WRMSR: unknown MSR[0x11e]:=708001 (sinking): rip=0x4656 count=5 Feb 11 02:40:35.567: vcpu-0| RDMSR: MSR[0x11e] (read as 708101): rip=0x4651 Feb 11 02:40:35.567: vcpu-0| WRMSR: unknown MSR[0x11e]:=708001 (sinking): rip=0x4656 count=6 Feb 11 02:40:35.674: vcpu-0| maxStackDepth(1) = 1424 Feb 11 02:40:35.701: vcpu-0| maxStackDepth(1) = 3984 Feb 11 02:40:35.756: vcpu-0| RDMSR: MSR[0x11e] (read as 708101): rip=0x166b Feb 11 02:40:35.817: vcpu-0| PCIBridge4: ISA/VGA decoding enabled (ctrl 0004) Feb 11 02:40:35.818: vcpu-0| pciBridge4:1: ISA/VGA decoding enabled (ctrl 0004) Feb 11 02:40:35.819: vcpu-0| pciBridge4:2: ISA/VGA decoding enabled (ctrl 0004) Feb 11 02:40:35.820: vcpu-0| pciBridge4:3: ISA/VGA decoding enabled (ctrl 0004) Feb 11 02:40:35.821: vcpu-0| pciBridge4:4: ISA/VGA decoding enabled (ctrl 0004) Feb 11 02:40:35.822: vcpu-0| pciBridge4:5: ISA/VGA decoding enabled (ctrl 0004) Feb 11 02:40:35.823: vcpu-0| pciBridge4:6: ISA/VGA decoding enabled (ctrl 0004) Feb 11 02:40:35.824: vcpu-0| pciBridge4:7: ISA/VGA decoding enabled (ctrl 0004) Feb 11 02:40:35.825: vcpu-0| PCIBridge5: ISA/VGA decoding enabled (ctrl 0004) Feb 11 02:40:35.826: vcpu-0| pciBridge5:1: ISA/VGA decoding enabled (ctrl 0004) Feb 11 02:40:35.827: vcpu-0| pciBridge5:2: ISA/VGA decoding enabled (ctrl 0004) Feb 11 02:40:35.828: vcpu-0| pciBridge5:3: ISA/VGA decoding enabled (ctrl 0004) Feb 11 02:40:35.830: vcpu-0| pciBridge5:4: ISA/VGA decoding enabled (ctrl 0004) Feb 11 02:40:35.831: vcpu-0| pciBridge5:5: ISA/VGA decoding enabled (ctrl 0004) Feb 11 02:40:35.832: vcpu-0| pciBridge5:6: ISA/VGA decoding enabled (ctrl 0004) Feb 11 02:40:35.833: vcpu-0| pciBridge5:7: ISA/VGA decoding enabled (ctrl 0004) Feb 11 02:40:35.834: vcpu-0| PCIBridge6: ISA/VGA decoding enabled (ctrl 0004) Feb 11 02:40:35.835: vcpu-0| pciBridge6:1: ISA/VGA decoding enabled (ctrl 0004) Feb 11 02:40:35.836: vcpu-0| pciBridge6:2: ISA/VGA decoding enabled (ctrl 0004) Feb 11 02:40:35.837: vcpu-0| pciBridge6:3: ISA/VGA decoding enabled (ctrl 0004) Feb 11 02:40:35.838: vcpu-0| pciBridge6:4: ISA/VGA decoding enabled (ctrl 0004) Feb 11 02:40:35.839: vcpu-0| pciBridge6:5: ISA/VGA decoding enabled (ctrl 0004) Feb 11 02:40:35.840: vcpu-0| pciBridge6:6: ISA/VGA decoding enabled (ctrl 0004) Feb 11 02:40:35.841: vcpu-0| pciBridge6:7: ISA/VGA decoding enabled (ctrl 0004) Feb 11 02:40:35.842: vcpu-0| PCIBridge7: ISA/VGA decoding enabled (ctrl 0004) Feb 11 02:40:35.843: vcpu-0| pciBridge7:1: ISA/VGA decoding enabled (ctrl 0004) Feb 11 02:40:35.844: vcpu-0| pciBridge7:2: ISA/VGA decoding enabled (ctrl 0004) Feb 11 02:40:35.845: vcpu-0| pciBridge7:3: ISA/VGA decoding enabled (ctrl 0004) Feb 11 02:40:35.846: vcpu-0| pciBridge7:4: ISA/VGA decoding enabled (ctrl 0004) Feb 11 02:40:35.847: vcpu-0| pciBridge7:5: ISA/VGA decoding enabled (ctrl 0004) Feb 11 02:40:35.848: vcpu-0| pciBridge7:6: ISA/VGA decoding enabled (ctrl 0004) Feb 11 02:40:35.850: vcpu-0| pciBridge7:7: ISA/VGA decoding enabled (ctrl 0004) Feb 11 02:40:35.851: mks| MKSHostOps_HideCursor before defineCursor! Feb 11 02:40:35.853: vcpu-0| RDMSR: MSR[0x11e] (read as 708101): rip=0x4651 Feb 11 02:40:35.853: vcpu-0| WRMSR: unknown MSR[0x11e]:=708001 (sinking): rip=0x4656 count=7 Feb 11 02:40:35.859: vcpu-0| RDMSR: MSR[0x11e] (read as 708101): rip=0x4651 Feb 11 02:40:35.859: vcpu-0| WRMSR: unknown MSR[0x11e]:=708001 (sinking): rip=0x4656 count=8 Feb 11 02:40:35.860: vcpu-0| MainMemPageMapperNoLazyHook called 0 times, offset = 200 Feb 11 02:40:35.860: vcpu-0| MainMemPageMapperNoLazyHook called 0 times Feb 11 02:40:35.860: vcpu-0| DISKUTIL: scsi0:0 : geometry=2610/255/63 Feb 11 02:40:35.887: vcpu-0| DISKUTIL: scsi0:0 : capacity=41943040 Feb 11 02:40:35.901: vcpu-0| RDMSR: MSR[0x11e] (read as 708101): rip=0x4651 Feb 11 02:40:35.901: vcpu-0| WRMSR: unknown MSR[0x11e]:=708001 (sinking): rip=0x4656 count=9 Feb 11 02:40:35.902: vcpu-0| RDMSR: MSR[0x11e] (read as 708101): rip=0x4651 Feb 11 02:40:35.902: vcpu-0| WRMSR: unknown MSR[0x11e]:=708001 (sinking): rip=0x4656 count=10 Feb 11 02:40:35.902: vcpu-0| RDMSR: MSR[0x11e] (read as 708101): rip=0x4651 Feb 11 02:40:35.902: vcpu-0| WRMSR: unknown MSR[0x11e]:=708001 (sinking): rip=0x4656 count=11 Feb 11 02:40:35.903: vcpu-0| RDMSR: MSR[0x11e] (read as 708101): rip=0x4651 Feb 11 02:40:35.903: vcpu-0| WRMSR: unknown MSR[0x11e]:=708001 (sinking): rip=0x4656 count=12 Feb 11 02:40:35.909: vcpu-0| RDMSR: MSR[0x11e] (read as 708101): rip=0x4651 Feb 11 02:40:35.909: vcpu-0| WRMSR: unknown MSR[0x11e]:=708001 (sinking): rip=0x4656 count=13 Feb 11 02:40:35.914: vcpu-0| RDMSR: MSR[0x11e] (read as 708101): rip=0x4651 Feb 11 02:40:35.914: vcpu-0| WRMSR: unknown MSR[0x11e]:=708001 (sinking): rip=0x4656 count=14 Feb 11 02:40:35.915: vcpu-0| RDMSR: MSR[0x11e] (read as 708101): rip=0x4651 Feb 11 02:40:35.915: vcpu-0| WRMSR: unknown MSR[0x11e]:=708001 (sinking): rip=0x4656 count=15 Feb 11 02:40:35.915: vcpu-0| RDMSR: MSR[0x11e] (read as 708101): rip=0x4651 Feb 11 02:40:35.915: vcpu-0| WRMSR: unknown MSR[0x11e]:=708001 (sinking): rip=0x4656 count=16 Feb 11 02:40:35.916: vcpu-0| RDMSR: MSR[0x11e] (read as 708101): rip=0x4651 Feb 11 02:40:35.916: vcpu-0| WRMSR: unknown MSR[0x11e]:=708001 (sinking): rip=0x4656 count=17 Feb 11 02:40:35.932: vcpu-0| RDMSR: MSR[0x11e] (read as 708101): rip=0x4651 Feb 11 02:40:35.932: vcpu-0| WRMSR: unknown MSR[0x11e]:=708001 (sinking): rip=0x4656 count=18 Feb 11 02:40:35.935: vcpu-0| RDMSR: MSR[0x11e] (read as 708101): rip=0x4651 Feb 11 02:40:35.935: vcpu-0| WRMSR: unknown MSR[0x11e]:=708001 (sinking): rip=0x4656 count=19 Feb 11 02:40:35.938: vcpu-0| RDMSR: MSR[0x11e] (read as 708101): rip=0x4651 Feb 11 02:40:35.938: vcpu-0| WRMSR: unknown MSR[0x11e]:=708001 (sinking): rip=0x4656 count=20 Feb 11 02:40:35.938: vcpu-0| RDMSR: MSR[0x11e] (read as 708101): rip=0x4651 Feb 11 02:40:35.938: vcpu-0| WRMSR: unknown MSR[0x11e]:=708001 (sinking): rip=0x4656 count=21 Feb 11 02:40:35.938: vcpu-0| RDMSR: MSR[0x11e] (read as 708101): rip=0x4651 Feb 11 02:40:35.938: vcpu-0| WRMSR: unknown MSR[0x11e]:=708001 (sinking): rip=0x4656 count=22 Feb 11 02:40:35.939: vcpu-0| RDMSR: MSR[0x11e] (read as 708101): rip=0x4651 Feb 11 02:40:35.939: vcpu-0| WRMSR: unknown MSR[0x11e]:=708001 (sinking): rip=0x4656 count=23 Feb 11 02:40:35.939: vcpu-0| RDMSR: MSR[0x11e] (read as 708101): rip=0x4651 Feb 11 02:40:35.939: vcpu-0| WRMSR: unknown MSR[0x11e]:=708001 (sinking): rip=0x4656 count=24 Feb 11 02:40:35.940: vcpu-0| RDMSR: MSR[0x11e] (read as 708101): rip=0x4651 Feb 11 02:40:35.940: vcpu-0| WRMSR: unknown MSR[0x11e]:=708001 (sinking): rip=0x4656 count=25 Feb 11 02:40:35.968: vcpu-0| maxStackDepth(1) = 6912 Feb 11 02:40:36.075: vcpu-0| RDMSR: MSR[0x11e] (read as 708101): rip=0x5904 Feb 11 02:40:36.075: vcpu-0| WRMSR: unknown MSR[0x11e]:=8101 (sinking): rip=0x590f count=26 Feb 11 02:40:36.127: vcpu-0| BIOS-UUID is 56 4d 0a 47 e4 86 d0 de-d2 08 64 ad 0b 25 34 b3 Feb 11 02:40:36.129: vcpu-0| RDMSR: unknown MSR[0x17] (read as zero): rip=0x6828 count=2 Feb 11 02:40:36.396: vcpu-0| RDMSR: MSR[0x11e] (read as 708101): rip=0x4651 Feb 11 02:40:36.396: vcpu-0| WRMSR: unknown MSR[0x11e]:=708001 (sinking): rip=0x4656 count=27 Feb 11 02:40:36.401: vcpu-0| RDMSR: MSR[0x11e] (read as 708101): rip=0x4651 Feb 11 02:40:36.401: vcpu-0| WRMSR: unknown MSR[0x11e]:=708001 (sinking): rip=0x4656 count=28 Feb 11 02:40:36.509: mks| MKS: Base polling period is 10000us Feb 11 02:40:36.520: mks| PrimaryHostOpsImplForwardvideoFlush: Can't take stats on unknown video overlay format 0 Feb 11 02:40:36.520: mks| MKSHostOps_HideCursor before defineCursor! Feb 11 02:40:36.618: mks| VNCENCODE 2 encoding mode change: (720x400x24depth,32bpp,2880bytes/line) Feb 11 02:40:46.892: vcpu-0| RDMSR: unknown MSR[0x1a0] (read as zero): rip=0x12ebf2a count=1 Feb 11 02:40:46.892: vcpu-0| GPhysGuestPgWalk4Work: paRsvd=fff0000000000 Feb 11 02:40:47.167: vcpu-0| RDMSR: unknown MSR[0x17] (read as zero): rip=0xffffffff8103f643 count=3 Feb 11 02:40:47.167: vcpu-0| WRMSR: unknown MSR[0x8b]:=0 (sinking): rip=0xffffffff8103f661 count=4 Feb 11 02:40:47.167: vcpu-0| RDMSR: unknown MSR[0x1a0] (read as zero): rip=0xffffffff81051aa2 count=2 Feb 11 02:40:47.167: vcpu-0| WRMSR: unknown MSR[0x8b]:=0 (sinking): rip=0xffffffff81051ac4 count=5 Feb 11 02:40:47.167: vcpu-0| RDMSR: unknown MSR[0x1a0] (read as zero): rip=0xffffffff81051aa2 count=3 Feb 11 02:40:47.232: vcpu-0| RDMSR: unknown MSR[0x277] (read as zero): rip=0xffffffff81051aa2 count=1 Feb 11 02:40:47.232: vcpu-0| WRMSR: unknown MSR[0x277]:=7010600070106 (sinking): rip=0xffffffff81051ac4 count=2 Feb 11 02:40:47.388: vcpu-0| RDMSR: unknown MSR[0x1a0] (read as zero): rip=0xffffffff81051aa2 count=4 Feb 11 02:40:47.388: vcpu-0| WRMSR: unknown MSR[0x8b]:=0 (sinking): rip=0xffffffff81051ac4 count=6 Feb 11 02:40:47.388: vcpu-0| RDMSR: unknown MSR[0x1a0] (read as zero): rip=0xffffffff81051aa2 count=5 Feb 11 02:40:47.388: vcpu-0| RDMSR: unknown MSR[0x1a0] (read as zero): rip=0xffffffff81051aa2 count=6 Feb 11 02:40:47.388: vcpu-0| RDMSR: unknown MSR[0x1b0] (read as zero): rip=0xffffffff81051aa2 count=1 Feb 11 02:40:47.389: vcpu-0| WRMSR: unknown MSR[0x1b0]:=6 (sinking): rip=0xffffffff81051ac4 count=2 Feb 11 02:40:54.791: vmx| OvhdMemVmxSizeCheck: total=11170pgs text=3212pgs, data=809pgs, heap=1252pgs stacks=1408pgs maps=4489pgs Feb 11 02:40:54.791: vmx| OvhdMemVmxSizeCheck: total=43.6MB text=12.5MB, data=3.2MB, heap=4.9MB stacks=5.5MB maps=17.5MB