ESXi

 View Only
  • 1.  VMware hangs after a couple of hours

    Posted Oct 08, 2014 06:19 PM

    Hi, i had an esxi 5.1 running succesfully for a while till the mainboard broke.

    Replaced the mainboard, installed 5.5, ran into the realtek related network issues, added an intel card and now the system is running fine for a couple of hours.

    Replaces power supply, no luck. swapped and tested memory with different amounts and places on the mainboard.. Checked disks.

    Then the system hangs. Nothing is really logged as far as i can see.

    Only thing i see sometimes in the kernel log is the following:

    2014-10-08T17:46:24.412Z cpu4:32895)MCE: 222: cpu4: bank2: status=0x9850c000000c0176: (VAL=1, OVFLW=0, UC=0, EN=1, PCC=0, S=0, AR=0), ECC=no, Addr:0x0 (valid), Misc:0x0 (invalid)

    2014-10-08T17:46:24.412Z cpu4:32895)MCE: 231: cpu4: bank2: MCA recoverable error (CE): "Unknown MCE encoding:0x9850c000000c0176."

    2014-10-08T17:46:28.412Z cpu4:32895)MCE: 1118: cpu4: MCA error detected via Polling (Gbl status=0x0): Restart IP: invalid, Error IP: invalid, MCE in progress: no.

    2014-10-08T17:46:28.412Z cpu4:32895)MCE: 222: cpu4: bank2: status=0x9850c000000c0176: (VAL=1, OVFLW=0, UC=0, EN=1, PCC=0, S=0, AR=0), ECC=no, Addr:0x0 (valid), Misc:0x0 (invalid)

    2014-10-08T17:46:28.412Z cpu4:32895)MCE: 231: cpu4: bank2: MCA recoverable error (CE): "Unknown MCE encoding:0x9850c000000c0176."

    2014-10-08T17:48:10.662Z cpu4:34271)MCE: 1118: cpu4: MCA error detected via Polling (Gbl status=0x0): Restart IP: invalid, Error IP: invalid, MCE in progress: no.

    2014-10-08T17:48:10.662Z cpu4:34271)MCE: 222: cpu4: bank2: status=0x9850c000000c0176: (VAL=1, OVFLW=0, UC=0, EN=1, PCC=0, S=0, AR=0), ECC=no, Addr:0x0 (valid), Misc:0x0 (invalid)

    2014-10-08T17:48:10.662Z cpu4:34271)MCE: 231: cpu4: bank2: MCA recoverable error (CE): "Unknown MCE encoding:0x9850c000000c0176."

    2014-10-08T17:51:19.412Z cpu4:34234)MCE: 1118: cpu4: MCA error detected via Polling (Gbl status=0x0): Restart IP: invalid, Error IP: invalid, MCE in progress: no.

    2014-10-08T17:51:19.412Z cpu4:34234)MCE: 222: cpu4: bank2: status=0xdc50c01000040136: (VAL=1, OVFLW=1, UC=0, EN=1, PCC=0, S=0, AR=0), ECC=no, Addr:0x0 (valid), Misc:0x13f115f50 (valid)

    2014-10-08T17:51:19.412Z cpu4:34234)MCE: 231: cpu4: bank2: MCA recoverable error (CE): "Unknown MCE encoding:0xdc50c01000040136."

    then it stops  for i while and later on it is there again. It says recoverable so i dont know for sure

    Does this mean the processor is damaged?  Or could there be another hardware error?

    Complete log:

    0:00:00:00.436 cpu0:1)SpecialStack: 248: mapping mpn: 10dcd0

    0:00:00:00.436 cpu0:1)SpecialStack: 248: mapping mpn: 10dca8

    0:00:00:00.436 cpu0:1)SpecialStack: 248: mapping mpn: 10dcb8

    0:00:00:00.436 cpu0:1)SpecialStack: 248: mapping mpn: 10dca4

    0:00:00:00.436 cpu0:1)SpecialStack: 248: mapping mpn: 102ebf

    0:00:00:00.436 cpu0:1)SpecialStack: 248: mapping mpn: 102ec0

    0:00:00:00.436 cpu0:1)SpecialStack: 248: mapping mpn: 10dcd4

    0:00:00:00.436 cpu0:1)SpecialStack: 248: mapping mpn: 10dcac

    0:00:00:00.436 cpu0:1)SpecialStack: 248: mapping mpn: 13f07c

    0:00:00:00.436 cpu0:1)SpecialStack: 248: mapping mpn: 10dca2

    0:00:00:00.436 cpu0:1)SpecialStack: 248: mapping mpn: 10dcd2

    0:00:00:00.436 cpu0:1)SpecialStack: 248: mapping mpn: 10dcaa

    0:00:00:00.436 cpu0:1)SpecialStack: 248: mapping mpn: 10dcba

    0:00:00:00.436 cpu0:1)SpecialStack: 248: mapping mpn: 10dca6

    0:00:00:00.436 cpu0:1)SpecialStack: 248: mapping mpn: 10dcd6

    0:00:00:00.436 cpu0:1)SpecialStack: 248: mapping mpn: 10dcae

    0:00:00:00.436 cpu0:1)SpecialStack: 248: mapping mpn: 13f07e

    0:00:00:00.436 cpu0:1)SpecialStack: 248: mapping mpn: 10dca1

    0:00:00:00.436 cpu0:1)SpecialStack: 248: mapping mpn: 10dcd1

    0:00:00:00.436 cpu0:1)SpecialStack: 248: mapping mpn: 10dca9

    0:00:00:00.436 cpu0:1)SpecialStack: 248: mapping mpn: 102ec2

    0:00:00:00.436 cpu0:1)SpecialStack: 248: mapping mpn: 102ec3

    0:00:00:00.436 cpu0:1)SpecialStack: 248: mapping mpn: 10dcb9

    0:00:00:00.436 cpu0:1)SpecialStack: 248: mapping mpn: 10dca5

    0:00:00:00.436 cpu0:1)SpecialStack: 248: mapping mpn: 10dcf5

    0:00:00:00.436 cpu0:1)SpecialStack: 248: mapping mpn: 10dccd

    0:00:00:00.436 cpu0:1)SpecialStack: 248: mapping mpn: 10dc9d

    0:00:00:00.436 cpu0:1)SpecialStack: 248: mapping mpn: 10dcc3

    0:00:00:00.436 cpu0:1)SpecialStack: 248: mapping mpn: 10dcf3

    0:00:00:00.436 cpu0:1)SpecialStack: 248: mapping mpn: 10dccb

    0:00:00:00.436 cpu0:1)SpecialStack: 248: mapping mpn: 10dcdb

    0:00:00:00.436 cpu0:1)SpecialStack: 248: mapping mpn: 10dcc7

    0:00:00:00.436 cpu0:1)SpecialStack: 248: mapping mpn: 10dcf7

    0:00:00:00.436 cpu0:1)SpecialStack: 248: mapping mpn: 10dccf

    0:00:00:00.436 cpu0:1)SpecialStack: 248: mapping mpn: 10dc9f

    0:00:00:00.436 cpu0:1)SpecialStack: 248: mapping mpn: 10dcc0

    0:00:00:00.437 cpu0:1)Vmkperf: 3930: 0 perf counters in use by BIOS

    0:00:00:00.437 cpu0:1)Vmkperf: 3932: 0 perf counters are not functional

    0:00:00:00.437 cpu0:1)Initializing timing ...

    0:00:00:00.440 cpu0:1)HPET: 206: 32-bit, 14318180 Hz HPET at 0xfed00

    0:00:00:00.440 cpu0:1)HPET: 208: HPET capabilities 0x429b17e43538210, configuration 0x0

    0:00:00:00.441 cpu0:1)HPET: 98: 1000 calls to HPET_Read32() took 2050936 TSC cycles

    0:00:00:00.441 cpu0:1)HPET: 99: 1000 calls to HPET_Read() took 2625781 TSC cycles

    0:00:00:00.471 cpu0:1)HPET: 118: Counter start 21651, end 451199, diffUS 30000

    0:00:00:02.471 cpu0:1)Timer: 1488: cpu 0: measured cpu speed (using TSC): 3093307583 Hz, bus speed: 199568200 Hz

    0:00:00:02.471 cpu0:1)Initializing scheduler ...

    0:00:00:02.476 cpu0:1)IRQ: 540: 0xf6 <resched> exclusive, flags 0x0

    0:00:00:02.476 cpu0:1)CpuSched: 3894: defaultAffinity="0-7"

    0:00:00:02.476 cpu0:1)CpuSched: 3914: node 0: mask="0-7"

    0:00:00:02.477 cpu0:1)MemSched: 12871: Updated free state thresholds: minFreePages: 167144, softStatePct: 64, hardStatePct: 32, lowStatePct: 16,

    0:00:00:02.482 cpu0:1)VmkAccess: 255: VMkernel Access Control Subsystem initalized

    0:00:00:02.482 cpu0:1)WorldStore: 612: Using physically discontiguous vmkernel stack.

    0:00:00:02.482 cpu0:1)World: 1332: cpuidFeatures = 0x178bfbff

    0:00:00:02.482 cpu0:32768)Sched: vm 32768: 1448: name='bootstrap'

    0:00:00:02.482 cpu0:32768)MCE: 1998: Detected 7 MCE banks. MCG_CAP MSR:0x107

    0:00:00:02.482 cpu0:32768)SMP: 1644: Booting APs...

    0:00:00:02.483 cpu0:32768)SMP: 1681: Kicking pcpu 1, apicID 0x00000011

    0:00:00:02.496 cpu1:1)TSCSync: 430: TSC in sync with cpu0; offset within [-207,204]

    0:00:00:02.496 cpu1:1)APIC: 1885: APIC interrupt state:

    0:00:00:02.496 cpu1:32769)Cpu: 975: id1.version 600f12

    0:00:00:02.496 cpu1:32769)CPUAMD: 667: PCPU 1 : initial apic id=0x1 differs from the ACPI apic id=0x11

    0:00:00:02.496 cpu1:32769)Cpu: 1015: initial APICID=0x1

    0:00:00:02.496 cpu1:32769)MicrocodeUpdate: 212: Microcode patch level 0x6000626.

    0:00:00:02.496 cpu1:32769)MTRR: 691: MTRR: cap=0x508 with 8 variable MTRRs on AP 1

    0:00:00:02.497 cpu1:32769)Cpu: 89: Changing PAT MSR on PCPU 1 from 0x7040600070406 to 0x7010600070106

    0:00:00:02.497 cpu0:32768)SMP: 1681: Kicking pcpu 2, apicID 0x00000012

    0:00:00:02.511 cpu2:1)TSCSync: 430: TSC in sync with cpu0; offset within [-281,324]

    0:00:00:02.511 cpu2:1)APIC: 1885: APIC interrupt state:

    0:00:00:02.511 cpu2:32770)Cpu: 975: id1.version 600f12

    0:00:00:02.511 cpu2:32770)CPUAMD: 667: PCPU 2 : initial apic id=0x2 differs from the ACPI apic id=0x12

    0:00:00:02.511 cpu2:32770)Cpu: 1015: initial APICID=0x2

    0:00:00:02.511 cpu2:32770)MicrocodeUpdate: 212: Microcode patch level 0x6000626.

    0:00:00:02.511 cpu2:32770)MTRR: 691: MTRR: cap=0x508 with 8 variable MTRRs on AP 2

    0:00:00:02.512 cpu2:32770)Cpu: 89: Changing PAT MSR on PCPU 2 from 0x7040600070406 to 0x7010600070106

    0:00:00:02.512 cpu0:32768)SMP: 1681: Kicking pcpu 3, apicID 0x00000013

    0:00:00:02.527 cpu3:1)TSCSync: 430: TSC in sync with cpu0; offset within [-328,325]

    0:00:00:02.527 cpu3:1)APIC: 1885: APIC interrupt state:

    0:00:00:02.527 cpu3:32771)Cpu: 975: id1.version 600f12

    0:00:00:02.527 cpu3:32771)CPUAMD: 667: PCPU 3 : initial apic id=0x3 differs from the ACPI apic id=0x13

    0:00:00:02.527 cpu3:32771)Cpu: 1015: initial APICID=0x3

    0:00:00:02.527 cpu3:32771)MicrocodeUpdate: 212: Microcode patch level 0x6000626.

    0:00:00:02.527 cpu3:32771)MTRR: 691: MTRR: cap=0x508 with 8 variable MTRRs on AP 3

    0:00:00:02.528 cpu3:32771)Cpu: 89: Changing PAT MSR on PCPU 3 from 0x7040600070406 to 0x7010600070106

    0:00:00:02.528 cpu0:32768)SMP: 1681: Kicking pcpu 4, apicID 0x00000014

    0:00:00:02.542 cpu4:1)TSCSync: 430: TSC in sync with cpu0; offset within [-279,326]

    0:00:00:02.542 cpu4:1)APIC: 1885: APIC interrupt state:

    0:00:00:02.542 cpu4:32772)Cpu: 975: id1.version 600f12

    0:00:00:02.542 cpu4:32772)CPUAMD: 667: PCPU 4 : initial apic id=0x4 differs from the ACPI apic id=0x14

    0:00:00:02.542 cpu4:32772)Cpu: 1015: initial APICID=0x4

    0:00:00:02.542 cpu4:32772)MicrocodeUpdate: 212: Microcode patch level 0x6000626.

    0:00:00:02.542 cpu4:32772)MTRR: 691: MTRR: cap=0x508 with 8 variable MTRRs on AP 4

    0:00:00:02.543 cpu4:32772)Cpu: 89: Changing PAT MSR on PCPU 4 from 0x7040600070406 to 0x7010600070106

    0:00:00:02.543 cpu0:32768)SMP: 1681: Kicking pcpu 5, apicID 0x00000015

    0:00:00:02.558 cpu5:1)TSCSync: 430: TSC in sync with cpu0; offset within [-327,326]

    0:00:00:02.558 cpu5:1)APIC: 1885: APIC interrupt state:

    0:00:00:02.558 cpu5:32773)Cpu: 975: id1.version 600f12

    0:00:00:02.558 cpu5:32773)CPUAMD: 667: PCPU 5 : initial apic id=0x5 differs from the ACPI apic id=0x15

    0:00:00:02.558 cpu5:32773)Cpu: 1015: initial APICID=0x5

    0:00:00:02.558 cpu5:32773)MicrocodeUpdate: 212: Microcode patch level 0x6000626.

    0:00:00:02.558 cpu5:32773)MTRR: 691: MTRR: cap=0x508 with 8 variable MTRRs on AP 5

    0:00:00:02.559 cpu5:32773)Cpu: 89: Changing PAT MSR on PCPU 5 from 0x7040600070406 to 0x7010600070106

    0:00:00:02.559 cpu0:32768)SMP: 1681: Kicking pcpu 6, apicID 0x00000016

    0:00:00:02.573 cpu6:1)TSCSync: 430: TSC in sync with cpu0; offset within [-279,351]

    0:00:00:02.573 cpu6:1)APIC: 1885: APIC interrupt state:

    0:00:00:02.573 cpu6:32774)Cpu: 975: id1.version 600f12

    0:00:00:02.573 cpu6:32774)CPUAMD: 667: PCPU 6 : initial apic id=0x6 differs from the ACPI apic id=0x16

    0:00:00:02.573 cpu6:32774)Cpu: 1015: initial APICID=0x6

    0:00:00:02.573 cpu6:32774)MicrocodeUpdate: 212: Microcode patch level 0x6000626.

    0:00:00:02.573 cpu6:32774)MTRR: 691: MTRR: cap=0x508 with 8 variable MTRRs on AP 6

    0:00:00:02.574 cpu6:32774)Cpu: 89: Changing PAT MSR on PCPU 6 from 0x7040600070406 to 0x7010600070106

    0:00:00:02.574 cpu0:32768)SMP: 1681: Kicking pcpu 7, apicID 0x00000017

    0:00:00:02.589 cpu7:1)TSCSync: 430: TSC in sync with cpu0; offset within [-327,325]

    0:00:00:02.589 cpu7:1)APIC: 1885: APIC interrupt state:

    0:00:00:02.589 cpu7:32775)Cpu: 975: id1.version 600f12

    0:00:00:02.589 cpu7:32775)CPUAMD: 667: PCPU 7 : initial apic id=0x7 differs from the ACPI apic id=0x17

    0:00:00:02.589 cpu7:32775)Cpu: 1015: initial APICID=0x7

    0:00:00:02.589 cpu7:32775)MicrocodeUpdate: 212: Microcode patch level 0x6000626.

    0:00:00:02.589 cpu7:32775)MTRR: 691: MTRR: cap=0x508 with 8 variable MTRRs on AP 7

    0:00:00:02.590 cpu7:32775)Cpu: 89: Changing PAT MSR on PCPU 7 from 0x7040600070406 to 0x7010600070106

    0:00:00:02.590 cpu0:32768)CpuTopology: 1094: CPU distance map 0: 0:6:6 1:5:5 2:3:3 3:3:3 4:3:3 5:3:3 6:3:3 7:3:3

    0:00:00:02.590 cpu0:32768)CpuTopology: 1094: CPU distance map 1: 1:6:6 0:5:5 2:3:3 3:3:3 4:3:3 5:3:3 6:3:3 7:3:3

    0:00:00:02.590 cpu0:32768)CpuTopology: 1094: CPU distance map 2: 2:6:6 3:5:5 0:3:3 1:3:3 4:3:3 5:3:3 6:3:3 7:3:3

    0:00:00:02.590 cpu0:32768)CpuTopology: 1094: CPU distance map 3: 3:6:6 2:5:5 0:3:3 1:3:3 4:3:3 5:3:3 6:3:3 7:3:3

    0:00:00:02.590 cpu0:32768)CpuTopology: 1094: CPU distance map 4: 4:6:6 5:5:5 0:3:3 1:3:3 2:3:3 3:3:3 6:3:3 7:3:3

    0:00:00:02.590 cpu0:32768)CpuTopology: 1094: CPU distance map 5: 5:6:6 4:5:5 0:3:3 1:3:3 2:3:3 3:3:3 6:3:3 7:3:3

    0:00:00:02.590 cpu0:32768)CpuTopology: 1094: CPU distance map 6: 6:6:6 7:5:5 0:3:3 1:3:3 2:3:3 3:3:3 4:3:3 5:3:3

    0:00:00:02.590 cpu0:32768)CpuTopology: 1094: CPU distance map 7: 7:6:6 6:5:5 0:3:3 1:3:3 2:3:3 3:3:3 4:3:3 5:3:3

    0:00:00:04.690 cpu0:32768)SMP: 1790: ...finished booting APs, numPCPUs=8, running=8

    0:00:00:04.697 cpu0:32768)Initializing device support ...

    0:00:00:04.700 cpu0:32768)IRQ: 540: 0xf8 <tlb> exclusive, flags 0x0

    0:00:00:04.701 cpu0:32768)Initializing processors ...

    0:00:00:04.705 cpu0:32768)FastSlab: 1596: Need 2881 pages to manage VA space

    0:00:00:04.729 cpu0:32768)DMA: 162: 'DMANull' DMA mapper is now available..

    0:00:00:04.729 cpu0:32768)CpuSched: 583: user latency of 32782 netLegacyRx 0 changed by 32768 bootstrap -1

    0:00:00:04.729 cpu0:32768)Net: 295: netPktPool reservation 0 MB , limit 1125 MB

    0:00:00:04.729 cpu0:32768)Net: 331: Created slab 'NetPktSlab-PktHandle' objSize 256 minObjs 0 (0 MB) maxObjs 4608000 (1125 MB)

    0:00:00:04.729 cpu0:32768)Net: 331: Created slab 'NetPktSlab-Clone' objSize 896 minObjs 0 (0 MB) maxObjs 1316571 (1125 MB)

    0:00:00:04.729 cpu0:32768)Net: 331: Created slab 'NetPktSlab-StdMTU' objSize 2304 minObjs 0 (0 MB) maxObjs 512000 (1125 MB)

    0:00:00:04.729 cpu0:32768)Net: 331: Created slab 'NetPktSlab-2K' objSize 2816 minObjs 0 (0 MB) maxObjs 418909 (1125 MB)

    0:00:00:04.729 cpu0:32768)Net: 331: Created slab 'NetPktSlab-3K' objSize 3840 minObjs 0 (0 MB) maxObjs 307200 (1125 MB)

    0:00:00:04.729 cpu0:32768)Net: 331: Created slab 'NetPktSlab-Jumbo' objSize 9984 minObjs 0 (0 MB) maxObjs 118153 (1125 MB)

    0:00:00:04.729 cpu0:32768)Net: 331: Created slab 'NetPktSlab-Clone(LowMem)' objSize 896 minObjs 0 (0 MB) maxObjs 1316571 (1125 MB)

    0:00:00:04.729 cpu0:32768)Net: 331: Created slab 'NetPktSlab-StdMTU(LowMem)' objSize 2304 minObjs 0 (0 MB) maxObjs 512000 (1125 MB)

    0:00:00:04.729 cpu0:32768)Net: 331: Created slab 'NetPktSlab-Jumbo(LowMem)' objSize 9984 minObjs 0 (0 MB) maxObjs 118153 (1125 MB)

    0:00:00:04.729 cpu0:32768)Net: 364: Created slab 'NetPktSlab-Attr' objSize 104 minObjs 0 (0 MB) maxObjs 11342769 (1125 MB)

    0:00:00:04.730 cpu0:32768)Net: 47: netPktHeapLow minSize 0 MB maxSize 90 MB

    0:00:00:04.730 cpu0:32768)PktHeader: 121: Initialized static packet header types.

    0:00:00:04.730 cpu0:32768)PktParser: 130: Initialized packet parsing infrastructure.

    0:00:00:04.730 cpu0:32768)PktParser: 220: Registered parser for Ethernet

    0:00:00:04.730 cpu0:32768)PktParser: 220: Registered parser for IPv4

    0:00:00:04.730 cpu0:32768)PktParser: 220: Registered parser for IPv6

    0:00:00:04.730 cpu0:32768)PktParser: 220: Registered parser for TCP

    0:00:00:04.730 cpu0:32768)PktParser: 220: Registered parser for UDP

    0:00:00:04.730 cpu0:32768)PktParser: 220: Registered parser for ICMP

    0:00:00:04.730 cpu0:32768)PktParser: 220: Registered parser for PIM

    0:00:00:04.730 cpu0:32768)PktParser: 220: Registered parser for GRE

    0:00:00:04.730 cpu0:32768)PktParser: 220: Registered parser for IPv6 Extension Header

    0:00:00:04.730 cpu0:32768)PktParser: 220: Registered parser for VXLAN

    0:00:00:04.730 cpu0:32768)CpuSched: 583: user latency of 32783 coalesceWorld-0 0 changed by 32768 bootstrap -1

    0:00:00:04.730 cpu0:32768)CpuSched: 583: user latency of 32784 netCoalesce2World 0 changed by 32768 bootstrap -1

    0:00:00:04.731 cpu0:32768)CpuSched: 583: user latency of 32785 netCoalesce2FineWorld 0 changed by 32768 bootstrap -1

    0:00:00:04.732 cpu0:32768)Net: 3300: PktCap framework initialized successfully

    0:00:00:04.732 cpu0:32768)ScsiCore: 436: Storage heap max size 61121689 (maxDevices 256, maxPaths 1024)

    0:00:00:04.735 cpu0:32768)CpuSched: 583: user latency of 32789 CmdCompl-0 0 changed by 32768 bootstrap -1

    0:00:00:04.735 cpu0:32768)CpuSched: 583: user latency of 32790 CmdCompl-1 0 changed by 32768 bootstrap -1

    0:00:00:04.736 cpu0:32768)CpuSched: 583: user latency of 32791 CmdCompl-2 0 changed by 32768 bootstrap -1

    0:00:00:04.736 cpu0:32768)CpuSched: 583: user latency of 32792 CmdCompl-3 0 changed by 32768 bootstrap -1

    0:00:00:04.736 cpu0:32768)CpuSched: 583: user latency of 32793 CmdCompl-4 0 changed by 32768 bootstrap -1

    0:00:00:04.736 cpu0:32768)CpuSched: 583: user latency of 32794 CmdCompl-5 0 changed by 32768 bootstrap -1

    0:00:00:04.737 cpu0:32768)CpuSched: 583: user latency of 32795 CmdCompl-6 0 changed by 32768 bootstrap -1

    0:00:00:04.737 cpu0:32768)CpuSched: 583: user latency of 32796 CmdCompl-7 0 changed by 32768 bootstrap -1

    0:00:00:04.738 cpu0:32768)ScsiEvents: 501: Event Subsystem: APD_Event_Subsystem, Created!

    0:00:00:04.738 cpu0:32768)ScsiEvents: 301: EventSubsystem: APD_Event_Subsystem, Event Mask: 0, Parameter: 0x0, Registered!

    0:00:00:04.738 cpu0:32768)ScsiEvents: 501: Event Subsystem: ScsiDHE_Subsystem, Created!

    0:00:00:04.738 cpu0:32768)ScsiEvents: 301: EventSubsystem: ScsiDHE_Subsystem, Event Mask: 0, Parameter: 0x0, Registered!

    0:00:00:04.741 cpu0:32768)Timer: 4139: reference timer is TSC at 3093307583 Hz

    0:00:00:04.741 cpu5:32773)Sched: vm 32773: 1448: name='idle5'

    0:00:00:04.741 cpu1:32769)Sched: vm 32769: 1448: name='idle1'

    0:00:00:04.741 cpu6:32774)Sched: vm 32774: 1448: name='idle6'

    0:00:00:04.741 cpu4:32772)Sched: vm 32772: 1448: name='idle4'

    0:00:00:04.741 cpu3:32771)Sched: vm 32771: 1448: name='idle3'

    0:00:00:04.741 cpu7:32775)Sched: vm 32775: 1448: name='idle7'

    0:00:00:04.741 cpu2:32770)Sched: vm 32770: 1448: name='idle2'

    0:00:00:04.741 cpu0:32768)Cpu: 2149: Cache latency measurement: min= 196 max= 1083 avg= 212

    0:00:00:04.741 cpu0:32768)CacheSched: 1098: cache miss cost is 108 cycles on average (ignore= 71468390154240)

    0:00:00:04.741 cpu0:32768)CacheSched: 1098: cache miss cost is 108 cycles on average (ignore= 71468390154240)

    0:00:00:04.742 cpu0:32768)CacheSched: 1098: cache miss cost is 108 cycles on average (ignore= 71468390154240)

    0:00:00:04.742 cpu0:32768)CacheSched: 1471: Monitored cache = L3, numLinesLg=17

    0:00:00:04.756 cpu0:32768)Initializing IOV ...

    0:00:00:04.759 cpu0:32768)IOV: 58: IOV not supported

    0:00:00:04.759 cpu0:32768)Initializing chipset IOAPIC ...

    0:00:00:04.762 cpu0:32768)IOAPIC: 646: found 0 (id 09 @ 0xfec00000)

    0:00:00:04.762 cpu0:32768)IOAPIC: 694: version 0x21, number of entries 24

    0:00:00:04.762 cpu0:32768)IOAPIC: 646: found 1 (id 10 @ 0xfec20000)

    0:00:00:04.762 cpu0:32768)IOAPIC: 694: version 0x21, number of entries 32

    0:00:00:04.762 cpu0:32768)CpuSched: 583: