bhyve tty / login problems / panic
Neel Natu
neelnatu at gmail.com
Sun Mar 31 04:03:49 UTC 2013
Hi Dan,
On Sat, Mar 30, 2013 at 12:17 PM, Dan Mack <mack at macktronics.com> wrote:
> Here you go:
>
> - uni-processor output:
>
>
> root at olive:~ # bhyvectl --vm=coco --get-stats --cpu=0
> vcpu0
> vm exits due to external interrupt 1330
> number of times hlt was intercepted 676
> number of times %cr access was intercepted 1
> number of times rdmsr was intercepted 0
> number of times wrmsr was intercepted 4
> number of monitor trap exits 0
> number of times pause was intercepted 428499
> vm exits due to interrupt window opening 1231
> vm exits due to nmi window opening 0
> number of times in/out was intercepted 423985
> number of times cpuid was intercepted 22
> vm exits due to nested page fault 7523
> number of vm exits for unknown reason 0
> number of times astpending at exit 38
> number of vm exits handled in userspace 853290
>
> number of NMIs delivered to vcpu 0
> vcpu total runtime 6954314530
> number of ticks vcpu was idle 59572
> vcpu migration across host cpus 29
>
> number of times hlt was ignored 0
> total number of vm exits 863271
>
> And here is when running with vcpu==2:
>
>
> root at olive:~ # bhyvectl --vm=coco --get-stats --cpu=0
> vcpu0
> vm exits due to external interrupt 19234
> number of times hlt was intercepted 1965
> number of times %cr access was intercepted 1
> number of times rdmsr was intercepted 0
> number of times wrmsr was intercepted 4
> number of monitor trap exits 0
> number of times pause was intercepted 3907709
> vm exits due to interrupt window opening 8105
> vm exits due to nmi window opening 0
> number of times in/out was intercepted 457998
> number of times cpuid was intercepted 50
> vm exits due to nested page fault 940909
> number of vm exits for unknown reason 0
> number of times astpending at exit 102
> number of vm exits handled in userspace 4367825
>
> number of NMIs delivered to vcpu 0
> vcpu total runtime 51834598990
> number of ticks vcpu was idle 887
> vcpu migration across host cpus 25
>
> number of times hlt was ignored 0
> total number of vm exits 5335975
>
> root at olive:~ # bhyvectl --vm=coco --get-stats --cpu=1
> vcpu1
> vm exits due to external interrupt 51249
> number of times hlt was intercepted 3676
> number of times %cr access was intercepted 2
> number of times rdmsr was intercepted 0
> number of times wrmsr was intercepted 4
> number of monitor trap exits 0
> number of times pause was intercepted 4667527
> vm exits due to interrupt window opening 32453
> vm exits due to nmi window opening 0
> number of times in/out was intercepted 57278
> number of times cpuid was intercepted 63
> vm exits due to nested page fault 266091
> number of vm exits for unknown reason 0
> number of times astpending at exit 164
> number of vm exits handled in userspace 4728481
>
> number of NMIs delivered to vcpu 0
> vcpu total runtime 53833466076
> number of ticks vcpu was idle 965
> vcpu migration across host cpus 16
>
> number of times hlt was ignored 0
> total number of vm exits 5078343
>
> The panic comes eventually like this:
>
> login: panic: deadlkres: possible deadlock detected for
> 0xfffffe0090fb5490, blocked for 217798 ticks
>
> cpuid = 0
> Uptime: 255d6h29m56s
> Automatic reboot in 15 seconds - press a key on the console to abort
>
>
Thanks for capturing that.
In the SMP case the aberrant source of VM exits is the nested page table
faults - which means local apic accesses since these are being entirely
handled in the kernel. They are an order of magnitude more than in the UP
case - even after adjusting for the difference in the run times in the two
cases.
Also, the uptime message (255d6h29m56s) is indicates something funky
happening with the time keeping.
I have added one more stat to vmm.ko that counts the number of times a
local apic timer interrupt is generated for each vcpu.
Also, I would like to install your release.iso if you are able to upload
it. I very much doubt, it but want to rule out those specific bits as the
source of the problem.
Thanks again for helping to debug this.
best
Neel
>
> Hope this helps, and FYI, here's a dmesg from the host:
>
>
> Copyright (c) 1992-2013 The FreeBSD Project.
> Copyright (c) 1979, 1980, 1983, 1986, 1988, 1989, 1991, 1992, 1993, 1994
> The Regents of the University of California. All rights reserved.
> FreeBSD is a registered trademark of The FreeBSD Foundation.
> FreeBSD 10.0-CURRENT #7 r248935: Sat Mar 30 13:19:43 CDT 2013
> root at olive.example.com:/usr/**obj/usr/src/sys/MACKGEN amd64
>
> FreeBSD clang version 3.2 (tags/RELEASE_32/final 170710) 20121221
> CPU: Intel(R) Core(TM) i7-3930K CPU @ 3.20GHz (3200.19-MHz K8-class CPU)
>
> Origin = "GenuineIntel" Id = 0x206d7 Family = 0x6 Model = 0x2d
> Stepping = 7
>
> Features=0xbfebfbff<FPU,VME,**DE,PSE,TSC,MSR,PAE,MCE,CX8,**
> APIC,SEP,MTRR,PGE,MCA,CMOV,**PAT,PSE36,CLFLUSH,DTS,ACPI,**
> MMX,FXSR,SSE,SSE2,SS,HTT,TM,**PBE>
>
> Features2=0x1fbee3bf<SSE3,**PCLMULQDQ,DTES64,MON,DS_CPL,**
> VMX,EST,TM2,SSSE3,CX16,xTPR,**PDCM,PCID,DCA,SSE4.1,SSE4.2,**
> x2APIC,POPCNT,TSCDLT,AESNI,**XSAVE,OSXSAVE,AVX>
> AMD Features=0x2c100800<SYSCALL,**NX,Page1GB,RDTSCP,LM>
> AMD Features2=0x1<LAHF>
> TSC: P-state invariant, performance statistics
> real memory = 68719476736 (65536 MB)
> avail memory = 63123800064 (60199 MB)
> Event timer "LAPIC" quality 600
> ACPI APIC Table: <INTEL DX79SI >
> FreeBSD/SMP: Multiprocessor System Detected: 12 CPUs
> FreeBSD/SMP: 1 package(s) x 6 core(s) x 2 SMT threads
>
> cpu0 (BSP): APIC ID: 0
> cpu1 (AP): APIC ID: 1
> cpu2 (AP): APIC ID: 2
> cpu3 (AP): APIC ID: 3
> cpu4 (AP): APIC ID: 4
> cpu5 (AP): APIC ID: 5
> cpu6 (AP): APIC ID: 6
> cpu7 (AP): APIC ID: 7
> cpu8 (AP): APIC ID: 8
> cpu9 (AP): APIC ID: 9
> cpu10 (AP): APIC ID: 10
> cpu11 (AP): APIC ID: 11
> ioapic1 <Version 2.0> irqs 24-47 on motherboard
> ioapic0 <Version 2.0> irqs 0-23 on motherboard
> lapic0: Forcing LINT1 to edge trigger
> kbd1 at kbdmux0
> acpi0: <INTEL DX79SI> on motherboard
> acpi0: Power Button (fixed)
> acpi0: reservation of f0000000, 18000000 (3) failed
> cpu0: <ACPI CPU> on acpi0
> cpu1: <ACPI CPU> on acpi0
> cpu2: <ACPI CPU> on acpi0
> cpu3: <ACPI CPU> on acpi0
> cpu4: <ACPI CPU> on acpi0
> cpu5: <ACPI CPU> on acpi0
> cpu6: <ACPI CPU> on acpi0
> cpu7: <ACPI CPU> on acpi0
> cpu8: <ACPI CPU> on acpi0
> cpu9: <ACPI CPU> on acpi0
> cpu10: <ACPI CPU> on acpi0
> cpu11: <ACPI CPU> on acpi0
> atrtc0: <AT realtime clock> port 0x70-0x71,0x74-0x77 irq 8 on acpi0
>
> Event timer "RTC" frequency 32768 Hz quality 0
> attimer0: <AT timer> port 0x40-0x43,0x50-0x53 irq 0 on acpi0
> Timecounter "i8254" frequency 1193182 Hz quality 0
> Event timer "i8254" frequency 1193182 Hz quality 100
> hpet0: <High Precision Event Timer> iomem 0xfed00000-0xfed03fff on acpi0
> Timecounter "HPET" frequency 14318180 Hz quality 950
> Event timer "HPET" frequency 14318180 Hz quality 350
> Event timer "HPET1" frequency 14318180 Hz quality 340
> Event timer "HPET2" frequency 14318180 Hz quality 340
> Event timer "HPET3" frequency 14318180 Hz quality 340
> Event timer "HPET4" frequency 14318180 Hz quality 340
> Event timer "HPET5" frequency 14318180 Hz quality 340
> Event timer "HPET6" frequency 14318180 Hz quality 340
> Event timer "HPET7" frequency 14318180 Hz quality 340
>
> Timecounter "ACPI-fast" frequency 3579545 Hz quality 900
> acpi_timer0: <24-bit timer at 3.579545MHz> port 0x408-0x40b on acpi0
> acpi_button0: <Sleep Button> on acpi0
>
> pcib0: <ACPI Host-PCI bridge> port 0xcf8-0xcff on acpi0
> pci0: <ACPI PCI bus> on pcib0
> pcib1: <ACPI PCI-PCI bridge> at device 1.0 on pci0
> pci1: <ACPI PCI bus> on pcib1
> pcib2: <ACPI PCI-PCI bridge> at device 1.1 on pci0
> pci2: <ACPI PCI bus> on pcib2
> xhci0: <NEC uPD720200 USB 3.0 controller> mem 0xe3400000-0xe3401fff irq 16
> at device 0.0 on pci2
> usbus0: waiting for BIOS to give up control
> xhci0: 32 byte context size.
> usbus0 on xhci0
> pcib3: <ACPI PCI-PCI bridge> at device 2.0 on pci0
> pci3: <ACPI PCI bus> on pcib3
> vgapci0: <VGA-compatible display> port 0x3000-0x307f mem
> 0xe2000000-0xe2ffffff,**0xd0000000-0xdfffffff,**0xe0000000-0xe1ffffff irq
> 16 at device 0.0 on pci3
> pci3: <multimedia, HDA> at device 0.1 (no driver attached)
> pcib4: <ACPI PCI-PCI bridge> at device 3.0 on pci0
> pci4: <ACPI PCI bus> on pcib4
> pci0: <base peripheral> at device 5.0 (no driver attached)
> pci0: <base peripheral> at device 5.2 (no driver attached)
> pcib5: <ACPI PCI-PCI bridge> at device 17.0 on pci0
> pci5: <ACPI PCI bus> on pcib5
> pci0: <simple comms> at device 22.0 (no driver attached)
> em0: <Intel(R) PRO/1000 Network Connection 7.3.7> port 0x4040-0x405f mem
> 0xe3500000-0xe351ffff,**0xe3521000-0xe3521fff irq 20 at device 25.0 on
> pci0
> em0: Using an MSI interrupt
> em0: Ethernet address: 4c:72:b9:d1:b2:d2
> ehci0: <EHCI (generic) USB 2.0 controller> mem 0xe3601000-0xe36013ff irq
> 16 at device 26.0 on pci0
> usbus1: EHCI version 1.0
> usbus1 on ehci0
> pcib6: <ACPI PCI-PCI bridge> at device 28.0 on pci0
> pci6: <ACPI PCI bus> on pcib6
> pcib7: <ACPI PCI-PCI bridge> at device 28.2 on pci0
> pci7: <ACPI PCI bus> on pcib7
> pci7: <serial bus, FireWire> at device 0.0 (no driver attached)
> pcib8: <ACPI PCI-PCI bridge> at device 28.3 on pci0
> pci8: <ACPI PCI bus> on pcib8
> xhci1: <NEC uPD720200 USB 3.0 controller> mem 0xe3200000-0xe3201fff irq 19
> at device 0.0 on pci8
> usbus2: waiting for BIOS to give up control
> xhci1: 32 byte context size.
> usbus2 on xhci1
> pcib9: <ACPI PCI-PCI bridge> at device 28.5 on pci0
> pci9: <ACPI PCI bus> on pcib9
> em1: <Intel(R) PRO/1000 Network Connection 7.3.7> port 0x1000-0x101f mem
> 0xe3100000-0xe311ffff,**0xe3120000-0xe3123fff irq 17 at device 0.0 on pci9
> em1: Using MSIX interrupts with 3 vectors
> em1: Ethernet address: 4c:72:b9:d1:b2:d3
> ehci1: <EHCI (generic) USB 2.0 controller> mem 0xe3602000-0xe36023ff irq
> 23 at device 29.0 on pci0
> usbus3: EHCI version 1.0
> usbus3 on ehci1
> pcib10: <ACPI PCI-PCI bridge> at device 30.0 on pci0
> pci10: <ACPI PCI bus> on pcib10
> isab0: <PCI-ISA bridge> at device 31.0 on pci0
> isa0: <ISA bus> on isab0
> ahci0: <Intel Patsburg AHCI SATA controller> port
> 0x4068-0x406f,0x4074-0x4077,**0x4060-0x4067,0x4070-0x4073,**0x4020-0x403f
> mem 0xe3523000-0xe35237ff irq 18 at device 31.2 on pci0
> ahci0: AHCI v1.30 with 6 6Gbps ports, Port Multiplier not supported
> ahcich0: <AHCI channel> at channel 0 on ahci0
> ahcich1: <AHCI channel> at channel 1 on ahci0
> ahcich2: <AHCI channel> at channel 2 on ahci0
> ahcich3: <AHCI channel> at channel 3 on ahci0
> ahcich4: <AHCI channel> at channel 4 on ahci0
> ahcich5: <AHCI channel> at channel 5 on ahci0
> ahciem0: <AHCI enclosure management bridge> on ahci0
> pci0: <serial bus, SMBus> at device 31.3 (no driver attached)
> orm0: <ISA Option ROMs> at iomem 0xd1000-0xd1fff,0xd2000-**0xd2fff on isa0
> sc0: <System console> at flags 0x100 on isa0
> sc0: VGA <16 virtual consoles, flags=0x300>
> vga0: <Generic ISA VGA> at port 0x3c0-0x3df iomem 0xa0000-0xbffff on isa0
> atkbdc0: <Keyboard controller (i8042)> at port 0x60,0x64 on isa0
> atkbd0: <AT Keyboard> irq 1 on atkbdc0
> kbd0 at atkbd0
> atkbd0: [GIANT-LOCKED]
> ppc0: cannot reserve I/O port range
> est0: <Enhanced SpeedStep Frequency Control> on cpu0
> p4tcc0: <CPU Frequency Thermal Control> on cpu0
> est1: <Enhanced SpeedStep Frequency Control> on cpu1
> p4tcc1: <CPU Frequency Thermal Control> on cpu1
> est2: <Enhanced SpeedStep Frequency Control> on cpu2
> p4tcc2: <CPU Frequency Thermal Control> on cpu2
> est3: <Enhanced SpeedStep Frequency Control> on cpu3
> p4tcc3: <CPU Frequency Thermal Control> on cpu3
> est4: <Enhanced SpeedStep Frequency Control> on cpu4
> p4tcc4: <CPU Frequency Thermal Control> on cpu4
> est5: <Enhanced SpeedStep Frequency Control> on cpu5
> p4tcc5: <CPU Frequency Thermal Control> on cpu5
> est6: <Enhanced SpeedStep Frequency Control> on cpu6
> p4tcc6: <CPU Frequency Thermal Control> on cpu6
> est7: <Enhanced SpeedStep Frequency Control> on cpu7
> p4tcc7: <CPU Frequency Thermal Control> on cpu7
> est8: <Enhanced SpeedStep Frequency Control> on cpu8
> p4tcc8: <CPU Frequency Thermal Control> on cpu8
> est9: <Enhanced SpeedStep Frequency Control> on cpu9
> p4tcc9: <CPU Frequency Thermal Control> on cpu9
> est10: <Enhanced SpeedStep Frequency Control> on cpu10
> p4tcc10: <CPU Frequency Thermal Control> on cpu10
> est11: <Enhanced SpeedStep Frequency Control> on cpu11
> p4tcc11: <CPU Frequency Thermal Control> on cpu11
> ZFS filesystem version: 5
> ZFS storage pool version: features support (5000)
> Timecounters tick every 1.000 msec
> usbus0: 5.0Gbps Super Speed USB v3.0
> usbus1: 480Mbps High Speed USB v2.0
> usbus2: 5.0Gbps Super Speed USB v3.0
> usbus3: 480Mbps High Speed USB v2.0
> ugen1.1: <Intel> at usbus1
> uhub0: <Intel EHCI root HUB, class 9/0, rev 2.00/1.00, addr 1> on usbus1
> ugen0.1: <0x1033> at usbus0
> uhub1: <0x1033 XHCI root HUB, class 9/0, rev 3.00/1.00, addr 1> on usbus0
> ugen3.1: <Intel> at usbus3
> uhub2: <Intel EHCI root HUB, class 9/0, rev 2.00/1.00, addr 1> on usbus3
> ugen2.1: <0x1033> at usbus2
> uhub3: <0x1033 XHCI root HUB, class 9/0, rev 3.00/1.00, addr 1> on usbus2
> ses0 at ahciem0 bus 0 scbus6 target 0 lun 0
> ses0: <AHCI SGPIO Enclosure 1.00 0001> SEMB S-E-S 2.00 device
> ses0: SEMB SES Device
> ada0 at ahcich0 bus 0 scbus0 target 0 lun 0
> ada0: <OCZ-VERTEX4 1.5> ATA-9 SATA 3.x device
> ada0: 600.000MB/s transfers (SATA 3.x, UDMA6, PIO 8192bytes)
> ada0: Command Queueing enabled
> ada0: 122104MB (250069680 512 byte sectors: 16H 63S/T 16383C)
> ada0: Previously was known as ad4
> ada1 at ahcich1 bus 0 scbus1 target 0 lun 0
> ada1: <INTEL SSDSC2CW120A3 400i> ATA-9 SATA 3.x device
> ada1: 600.000MB/s transfers (SATA 3.x, UDMA6, PIO 8192bytes)
> ada1: Command Queueing enabled
> ada1: 114473MB (234441648 512 byte sectors: 16H 63S/T 16383C)
> ada1: Previously was known as ad6
> ada2 at ahcich2 bus 0 scbus2 target 0 lun 0
> ada2: <Hitachi HUS724020ALE640 MJ6OA3B0> ATA-8 SATA 3.x device
> ada2: 300.000MB/s transfers (SATA 2.x, UDMA6, PIO 8192bytes)
> ada2: Command Queueing enabled
> ada2: 1907729MB (3907029168 512 byte sectors: 16H 63S/T 16383C)
> ada2: Previously was known as ad8
> ada3 at ahcich3 bus 0 scbus3 target 0 lun 0
> ada3: <Hitachi HUS724020ALE640 MJ6OA3B0> ATA-8 SATA 3.x device
> ada3: 300.000MB/s transfers (SATA 2.x, UDMA6, PIO 8192bytes)
> ada3: Command Queueing enabled
> ada3: 1907729MB (3907029168 512 byte sectors: 16H 63S/T 16383C)
> ada3: Previously was known as ad10
> ada4 at ahcich4 bus 0 scbus4 target 0 lun 0
> ada4: <Hitachi HUS724020ALE640 MJ6OA3B0> ATA-8 SATA 3.x device
> ada4: 300.000MB/s transfers (SATA 2.x, UDMA6, PIO 8192bytes)
> ada4: Command Queueing enabled
> ada4: 1907729MB (3907029168 512 byte sectors: 16H 63S/T 16383C)
> ada4: Previously was known as ad12
> ada5 at ahcich5 bus 0 scbus5 target 0 lun 0
> ada5: <Hitachi HUS724020ALE640 MJ6OA3B0> ATA-8 SATA 3.x device
> ada5: 300.000MB/s transfers (SATA 2.x, UDMA6, PIO 8192bytes)
> ada5: Command Queueing enabled
> ada5: 1907729MB (3907029168 512 byte sectors: 16H 63S/T 16383C)
> ada5: Previously was known as ad14
> lapic1: Forcing LINT1 to edge trigger
>
> SMP: AP CPU #1 Launched!
> lapic4: Forcing LINT1 to edge trigger
> SMP: AP CPU #4 Launched!
> lapic11: Forcing LINT1 to edge trigger
> SMP: AP CPU #11 Launched!
> lapic7: Forcing LINT1 to edge trigger
> SMP: AP CPU #7 Launched!
> lapic5: Forcing LINT1 to edge trigger
> SMP: AP CPU #5 Launched!
> lapic9: Forcing LINT1 to edge trigger
> SMP: AP CPU #9 Launched!
> lapic2: Forcing LINT1 to edge trigger
> SMP: AP CPU #2 Launched!
> lapic3: Forcing LINT1 to edge trigger
> SMP: AP CPU #3 Launched!
> lapic10: Forcing LINT1 to edge trigger
> SMP: AP CPU #10 Launched!
> lapic6: Forcing LINT1 to edge trigger
> SMP: AP CPU #6 Launched!
> lapic8: Forcing LINT1 to edge trigger
> SMP: AP CPU #8 Launched!
> uhub3: 4 ports with 4 removable, self powered
> uhub1: 4 ports with 4 removable, self powered
> Root mount waiting for: usbus3 usbus1
> uhub0: 2 ports with 2 removable, self powered
> uhub2: 2 ports with 2 removable, self powered
> Root mount waiting for: usbus3 usbus1
> ugen1.2: <vendor 0x8087> at usbus1
> uhub4: <vendor 0x8087 product 0x0024, class 9/0, rev 2.00/0.00, addr 2> on
> usbus1
> ugen3.2: <vendor 0x8087> at usbus3
> uhub5: <vendor 0x8087 product 0x0024, class 9/0, rev 2.00/0.00, addr 2> on
> usbus3
> Root mount waiting for: usbus3 usbus1
> uhub4: 6 ports with 6 removable, self powered
> uhub5: 8 ports with 8 removable, self powered
> ugen1.3: <vendor 0x060b> at usbus1
> ukbd0: <vendor 0x060b USB Keyboard, class 0/0, rev 2.00/1.10, addr 3> on
> usbus1
> kbd2 at ukbd0
> ugen3.3: <Ralink> at usbus3
> Root mount waiting for: usbus3
> ugen3.4: <vendor 0x0db0> at usbus3
> Trying to mount root from zfs:tron []...
> ums0: <vendor 0x060b USB Keyboard, class 0/0, rev 2.00/1.10, addr 3> on
> usbus1
> ums0: 5 buttons and [XYZ] coordinates ID=0
> run0: <1.0> on usbus3
> run0: MAC/BBP RT3070 (rev 0x0201), RF RT3020 (MIMO 1T1R), address
> 94:db:c9:e3:6e:c8
> ubt0: <vendor 0x0db0 product 0xa871, class 224/1, rev 2.00/52.76, addr 4>
> on usbus3
> WARNING: attempt to domain_add(bluetooth) after domainfinalize()
> WARNING: attempt to domain_add(netgraph) after domainfinalize()
> tap0: Ethernet address: 00:bd:8c:54:07:00
> tap0: link state changed to UP
> bridge0: Ethernet address: 02:4c:89:ce:33:00
> tap0: promiscuous mode enabled
> bridge0: link state changed to UP
> em1: link state changed to DOWN
> em1: promiscuous mode enabled
> em1: link state changed to UP
> tap0: link state changed to DOWN
> tap0: link state changed to UP
> tap0: link state changed to DOWN
> tap0: link state changed to UP
>
>
>
>
>> Yep, no problem. I saw the checkin and started a rebuild a few minutes
>> ago :-)
>>
>> Thanks,
>>
>> Dan
>>
>> On Sat, 30 Mar 2013, Neel Natu wrote:
>>
>> Hi Dan,
>>>
>>> On Fri, Mar 29, 2013 at 3:20 PM, Dan Mack <mack at macktronics.com> wrote:
>>>
>>>
>>>>
>>>> Here you go Neel:
>>>>
>>>> root at olive:~ # bhyvectl --vm=coco --get-stats --cpu=0
>>>> vcpu0
>>>> vm exits due to external interrupt 45051
>>>> number of times hlt was intercepted 2277
>>>> number of NMIs delivered to vcpu 0
>>>> vcpu total runtime 105001652482
>>>> number of ticks vcpu was idle 919
>>>> vcpu migration across host cpus 13
>>>> number of times hlt was ignored 0
>>>> total number of vm exits 9240925
>>>> root at olive:~ #
>>>> root at olive:~ #
>>>> root at olive:~ # bhyvectl --vm=coco --get-stats --cpu=1
>>>> vcpu1
>>>> vm exits due to external interrupt 149431
>>>> number of times hlt was intercepted 4222
>>>> number of NMIs delivered to vcpu 0
>>>> vcpu total runtime 106876622528
>>>> number of ticks vcpu was idle 869
>>>> vcpu migration across host cpus 20
>>>> number of times hlt was ignored 0
>>>> total number of vm exits 8065909
>>>>
>>>>
>>>> That did not help a whole lot because we were not keeping track of all
>>> the
>>> reasons a vcpu could exit.
>>>
>>> I have fixed that in r248935:
>>> http://svnweb.freebsd.org/**base?view=revision&revision=**248935<http://svnweb.freebsd.org/base?view=revision&revision=248935>
>>>
>>> Do you mind updating your vmm.ko with the change and getting the stats
>>> again?
>>>
>>> best
>>> Neel
>>>
>>>
>>> Dan
>>>>
>>>>
>>>>
>>>> On Fri, 29 Mar 2013, Neel Natu wrote:
>>>>
>>>> Hi Dan,
>>>>
>>>>>
>>>>> On Fri, Mar 29, 2013 at 12:38 PM, Dan Mack <mack at macktronics.com>
>>>>> wrote:
>>>>>
>>>>>
>>>>> I ran the procsystime dtrace script on bhyve when it was chewing up
>>>>>> all
>>>>>> the CPU in vCPU=2 mode, and this is what I see for about 10s of
>>>>>> runtime:
>>>>>>
>>>>>> root at olive:/usr/share/dtrace/******toolkit # ./procsystime -n bhyve
>>>>>> -aT
>>>>>>
>>>>>> Tracing... Hit Ctrl-C to end...
>>>>>> dtrace: 158536 dynamic variable drops with non-empty dirty list
>>>>>> dtrace: 207447 dynamic variable drops with non-empty dirty list
>>>>>> dtrace: 189205 dynamic variable drops with non-empty dirty list
>>>>>> dtrace: 164341 dynamic variable drops with non-empty dirty list
>>>>>> dtrace: 246307 dynamic variable drops with non-empty dirty list
>>>>>> dtrace: 187640 dynamic variable drops with non-empty dirty list
>>>>>> dtrace: 214771 dynamic variable drops with non-empty dirty list
>>>>>> dtrace: 221265 dynamic variable drops with non-empty dirty list
>>>>>> ^C
>>>>>> dtrace: 243468 dynamic variable drops with non-empty dirty list
>>>>>>
>>>>>> Elapsed Times for processes bhyve,
>>>>>>
>>>>>> SYSCALL TIME (ns)
>>>>>> _umtx_op 18349
>>>>>> writev 135712
>>>>>> preadv 16175267
>>>>>> pwritev 22924378
>>>>>> ioctl 4353897920
>>>>>> TOTAL: 4393151626
>>>>>>
>>>>>> CPU Times for processes bhyve,
>>>>>>
>>>>>> SYSCALL TIME (ns)
>>>>>> _umtx_op 8815
>>>>>> writev 103145
>>>>>> pwritev 10647023
>>>>>> preadv 15159976
>>>>>> ioctl 3943399889
>>>>>> TOTAL: 3969318848
>>>>>>
>>>>>> Syscall Counts for processes bhyve,
>>>>>>
>>>>>> SYSCALL COUNT
>>>>>> _umtx_op 17
>>>>>> writev 43
>>>>>> pwritev 639
>>>>>> preadv 1183
>>>>>> ioctl 652782
>>>>>> TOTAL: 654664
>>>>>>
>>>>>> Not sure if that helps or not. Hotkernel shows the cpu in the
>>>>>> kernel`acpi_cpu_c1 call most of the time, normal ?
>>>>>>
>>>>>> Dan
>>>>>>
>>>>>> root at olive:/usr/share/dtrace/******toolkit # ./hotkernel
>>>>>>
>>>>>> Sampling... Hit Ctrl-C to end.
>>>>>> ^C
>>>>>> FUNCTION COUNT PCNT
>>>>>> zfs.ko`space_map_sync 1 0.0%
>>>>>> kernel`syscall_thread_exit 1 0.0%
>>>>>> kernel`should_yield 1 0.0%
>>>>>> zfs.ko`lzjb_compress 1 0.0%
>>>>>> zfs.ko`dbuf_rele 1 0.0%
>>>>>> zfs.ko`dsl_pool_tempreserve_******space 1
>>>>>> 0.0%
>>>>>>
>>>>>> kernel`_bus_dmamap_load_buffer 1 0.0%
>>>>>> kernel`cpu_idleclock 1 0.0%
>>>>>> kernel`_rw_runlock_cookie 1 0.0%
>>>>>> kernel`pmap_enter 1 0.0%
>>>>>> kernel`__lockmgr_args 1 0.0%
>>>>>> zfs.ko`vdev_geom_io_start 1 0.0%
>>>>>> kernel`ahci_ch_intr 1 0.0%
>>>>>> kernel`__rw_rlock 1 0.0%
>>>>>> kernel`VOP_UNLOCK_APV 1 0.0%
>>>>>> zfs.ko`arc_buf_thaw 1 0.0%
>>>>>> kernel`atomic_set_int 1 0.0%
>>>>>> kernel`g_io_schedule_down 1 0.0%
>>>>>> kernel`__mtx_lock_flags 1 0.0%
>>>>>> kernel`_callout_stop_safe 1 0.0%
>>>>>> kernel`sched_idletd 1 0.0%
>>>>>> kernel`_sx_slock 1 0.0%
>>>>>> vmm.ko`lapic_set_intr 1 0.0%
>>>>>> kernel`cpusetobj_ffs 1 0.0%
>>>>>> zfs.ko`dbuf_hold 1 0.0%
>>>>>> vmm.ko`vlapic_intr_accepted 1 0.0%
>>>>>> zfs.ko`sa_attr_op 1 0.0%
>>>>>> zfs.ko`dmu_buf_hold_array_by_******dnode 1
>>>>>> 0.0%
>>>>>>
>>>>>> zfs.ko`taskq_run_safe 1 0.0%
>>>>>> kernel`vm_reserv_free_page 1 0.0%
>>>>>> kernel`_sx_sunlock 1 0.0%
>>>>>> kernel`vn_rlimit_fsize 1 0.0%
>>>>>> zfs.ko`vdev_queue_io_to_issue 1 0.0%
>>>>>> kernel`vn_write 1 0.0%
>>>>>> kernel`lock_init 1 0.0%
>>>>>> vmm.ko`vmx_resume 1 0.0%
>>>>>> zfs.ko`zio_gang_tree_free 1 0.0%
>>>>>> zfs.ko`dbuf_rele_and_unlock 1 0.0%
>>>>>> kernel`mnt_vnode_next_active 1 0.0%
>>>>>> kernel`vm_page_remove 1 0.0%
>>>>>> zfs.ko`vdev_queue_deadline_******compare 1
>>>>>> 0.0%
>>>>>>
>>>>>> kernel`zone_free_item 1 0.0%
>>>>>> vmm.ko`vmx_launch 1 0.0%
>>>>>> kernel`m_pullup 1 0.0%
>>>>>> kernel`pmap_change_wiring 1 0.0%
>>>>>> kernel`sched_userret 1 0.0%
>>>>>> zfs.ko`dbuf_write_done 1 0.0%
>>>>>> zfs.ko`zio_execute 1 0.0%
>>>>>> kernel`PHYS_TO_VM_PAGE 2 0.0%
>>>>>> zfs.ko`avl_rotation 2 0.0%
>>>>>> kernel`ehci_device_isoc_fs_******enter 2
>>>>>> 0.0%
>>>>>>
>>>>>> vmm.ko`vm_exitinfo 2 0.0%
>>>>>> zfs.ko`zfs_freebsd_write 2 0.0%
>>>>>> vmm.ko`vcpu_stats 2 0.0%
>>>>>> kernel`atomic_add_long 2 0.0%
>>>>>> vmm.ko`0xffffffff81 2 0.0%
>>>>>> kernel`vm_reserv_alloc_page 2 0.0%
>>>>>> kernel`pagezero 2 0.0%
>>>>>> kernel`bzero 2 0.0%
>>>>>> zfs.ko`fletcher_2_native 2 0.0%
>>>>>> vmm.ko`vmx_getreg 2 0.0%
>>>>>> vmm.ko`vm_guest_msrs 3 0.0%
>>>>>> vmm.ko`vmx_setreg 3 0.0%
>>>>>> vmm.ko`vm_get_register 3 0.0%
>>>>>> kernel`sx_try_xlock_ 3 0.0%
>>>>>> kernel`vm_radix_lookup_le 3 0.0%
>>>>>> vmm.ko`lapic_pending_intr 4 0.0%
>>>>>> kernel`syscall_thread_enter 4 0.0%
>>>>>> kernel`cpu_set_syscall_retval 4 0.0%
>>>>>> kernel`__mnt_vnode_next_all 4 0.0%
>>>>>> kernel`vfs_stdsync 4 0.0%
>>>>>> vmm.ko`vmm_fetch_instruction 5 0.0%
>>>>>> vmm.ko`vlapic_op_mem_read 6 0.0%
>>>>>> kernel`_sx_xunlock 7 0.0%
>>>>>> vmm.ko`vmm_emulate_instruction 7 0.0%
>>>>>> vmm.ko`vlapic_op_mem_write 7 0.0%
>>>>>> kernel`__mtx_lock_spin_flags 8 0.0%
>>>>>> vmm.ko`lapic_timer_tick 8 0.0%
>>>>>> zfs.ko`list_next 8 0.0%
>>>>>> zfs.ko`l2arc_feed_thread 8 0.0%
>>>>>> kernel`userret 9 0.0%
>>>>>> vmm.ko`vm_gpa2hpa 9 0.0%
>>>>>> vmm.ko`vmm_decode_instruction 11 0.0%
>>>>>> vmm.ko`ept_vmmmap_get 11 0.0%
>>>>>> vmm.ko`vm_lapic 11 0.0%
>>>>>> kernel`spinlock_enter 14 0.0%
>>>>>> kernel`__mtx_lock_sleep 15 0.0%
>>>>>> vmm.ko`vcpu_set_state 16 0.0%
>>>>>> vmm.ko`vm_nmi_pending 16 0.0%
>>>>>> kernel`malloc_type_zone_******allocated 16
>>>>>> 0.0%
>>>>>>
>>>>>> kernel`devfs_ioctl_f 16 0.0%
>>>>>> kernel`pmap_kextract 17 0.0%
>>>>>> kernel`sys_ioctl 18 0.0%
>>>>>> kernel`malloc 21 0.0%
>>>>>> kernel`cpu_fetch_syscall_args 24 0.0%
>>>>>> kernel`uma_zfree_arg 28 0.0%
>>>>>> vmm.ko`vlapic_pending_intr 29 0.0%
>>>>>> kernel`trap 30 0.0%
>>>>>> kernel`hpet_get_timecount 31 0.0%
>>>>>> dtrace.ko`dtrace_trap 35 0.0%
>>>>>> vmm.ko`vlapic_update_ppr 39 0.0%
>>>>>> vmm.ko`vmmdev_ioctl 40 0.0%
>>>>>> kernel`copyout 43 0.0%
>>>>>> kernel`critical_enter 44 0.0%
>>>>>> kernel`copyin 51 0.1%
>>>>>> vmm.ko`vmcs_read 53 0.1%
>>>>>> kernel`fpusave 61 0.1%
>>>>>> kernel`uma_zalloc_arg 62 0.1%
>>>>>> kernel`devvn_refthread 69 0.1%
>>>>>> kernel`atomic_load_acq_int 70 0.1%
>>>>>> kernel`fpurestore 70 0.1%
>>>>>> vmm.ko`vlapic_timer_tick 75 0.1%
>>>>>> kernel`amd64_syscall 77 0.1%
>>>>>> kernel`bcopy 91 0.1%
>>>>>> kernel`fpudna 103 0.1%
>>>>>> kernel`free 103 0.1%
>>>>>> kernel`critical_exit 105 0.1%
>>>>>> kernel`dev_relthread 108 0.1%
>>>>>> kernel`spinlock_exit 119 0.1%
>>>>>> kernel`kern_ioctl 138 0.1%
>>>>>> kernel`fpuexit 242 0.3%
>>>>>> kernel`0xffffffff80 306 0.3%
>>>>>> vmm.ko`restore_host_msrs 398 0.4%
>>>>>> vmm.ko`vm_run 428 0.4%
>>>>>> vmm.ko`restore_guest_msrs 433 0.5%
>>>>>> kernel`cpu_idle 906 1.0%
>>>>>> vmm.ko`vmx_run 9266 9.7%
>>>>>> kernel`acpi_cpu_c1 81134 85.2%
>>>>>>
>>>>>> Hope this helps, let me know if you have any ideas on what to try
>>>>>> next.
>>>>>>
>>>>>>
>>>>>> Could you try the following command to get vcpu stats?
>>>>>>
>>>>> sudo bhyvectl --vm=vm1 --get-stats --cpu=0
>>>>> sudo bhyvectl --vm=vm1 --get-stats --cpu=1
>>>>>
>>>>> best
>>>>> Neel
>>>>>
>>>>>
>>>>> Dan
>>>>>
>>>>>>
>>>>>>
>>>>>>
>>>>>>
>>>>>> On Fri, 29 Mar 2013, Dan Mack wrote:
>>>>>>
>>>>>> On Thu, 28 Mar 2013, Peter Grehan wrote:
>>>>>>
>>>>>>
>>>>>>> Hi Dan,
>>>>>>>
>>>>>>>
>>>>>>>> Any ideas?
>>>>>>>>
>>>>>>>>
>>>>>>>>>
>>>>>>>>> Just to confirm: your host system is an i7 3930k, which looks
>>>>>>>> like a 6
>>>>>>>> core system. Should be plenty beefy enough for a 2 vCPU guest.
>>>>>>>>
>>>>>>>>
>>>>>>>> That's what I thought, 6 cores, 12 threads, 64 GB RAM.
>>>>>>>
>>>>>>> The issue you are seeing looks a bit like what happens when systems
>>>>>>> are
>>>>>>>
>>>>>>> heavily oversubscribed and vCPUs can't get enough run time.
>>>>>>>>
>>>>>>>>
>>>>>>>> I was running in on an idle system; only bhyve was running.
>>>>>>>
>>>>>>>
>>>>>>> How much spare RAM is on the host when you started the vm ? Also, is
>>>>>>>
>>>>>>> there much running on the host when the guest is started ?
>>>>>>>>
>>>>>>>>
>>>>>>>> 64GB
>>>>>>>
>>>>>>> Here's some more information (sorry for the formatting):
>>>>>>>
>>>>>>> Case 1: single vCPU via:
>>>>>>> root 64112 1.3 0.0 4219232 25588 1 D+ 2:09PM 0:02.47
>>>>>>> /usr/sbin/bhyve -c 1 -m 4096 -AI -H -P -g 0 -s 0:0,hostbridge -s
>>>>>>> 1:0,virtio-net,tap0 -s 2:0,virtio-blk,./diskdev -S 31,uart,stdio coco
>>>>>>>
>>>>>>> Top shows it idle (note low load, 4GB memory given to guest, still
>>>>>>> 53GB
>>>>>>> free RAM, nothing else clocking CPU cycles):
>>>>>>>
>>>>>>> root at olive:~ # top -bH
>>>>>>> last pid: 64148; load averages: 0.03, 0.22, 0.22 up 0+16:29:31
>>>>>>> 14:11:51
>>>>>>> 24 processes: 1 running, 23 sleeping
>>>>>>>
>>>>>>> Mem: 32M Active, 50M Inact, 8498M Wired, 504K Cache, 53G Free
>>>>>>> ARC: 778M Total, 146M MFU, 390M MRU, 16K Anon, 16M Header, 226M Other
>>>>>>> Swap:
>>>>>>>
>>>>>>>
>>>>>>> PID USERNAME PRI NICE SIZE RES STATE C TIME WCPU
>>>>>>> COMMAND
>>>>>>> 64112 root 20 0 4120M 25980K vmidle 2 0:03 0.00%
>>>>>>> bhyve{coco vcpu 0}
>>>>>>> 925 root 20 0 14296K 2044K select 11 0:00 0.00%
>>>>>>> syslogd
>>>>>>> 2695 root 20 0 81452K 6952K select 2 0:00 0.00% sshd
>>>>>>> 2697 root 20 0 20224K 4632K ttyin 1 0:00 0.00% csh
>>>>>>> 64112 root 52 0 4120M 25980K kqread 9 0:00 0.00%
>>>>>>> bhyve{coco mevent}
>>>>>>>
>>>>>>>
>>>>>>> So, case #1 all is normal. I was able to build a kernel with very
>>>>>>> good
>>>>>>> performance.
>>>>>>>
>>>>>>> The boot sequence looks like the following:
>>>>>>>
>>>>>>> root at olive:/vms/coco # ./vmrun.sh coco
>>>>>>> Launching virtual machine "coco" ...
>>>>>>> Consoles: userboot
>>>>>>>
>>>>>>> FreeBSD/amd64 User boot, Revision 1.1
>>>>>>> (root at olive.example.com, Thu Mar 28 21:20:49 CDT 2013)
>>>>>>> Loading /boot/defaults/loader.conf /boot/kernel/kernel text=0x5a2178
>>>>>>> data=0x98088+0xef8b0 syms=[0x8+0xca8d8+0x8+******0x12bb08]
>>>>>>>
>>>>>>> /
>>>>>>> ______ ____ _____ _____
>>>>>>> | ____| | _ \ / ____| __ \
>>>>>>> | |___ _ __ ___ ___ | |_) | (___ | | | |
>>>>>>> | ___| '__/ _ \/ _ \| _ < \___ \| | | |
>>>>>>> | | | | | __/ __/| |_) |____) | |__| |
>>>>>>> | | | | | | || | | |
>>>>>>> |_| |_| \___|\___||____/|_____/|_____/ ```
>>>>>>> `
>>>>>>> s` `.....---.......--.```
>>>>>>> -/
>>>>>>> +------------Welcome to FreeBSD-----------+ +o .--` /y:`
>>>>>>> +.
>>>>>>> | | yo`:. :o
>>>>>>> `+-
>>>>>>> | 1. Boot Multi User [Enter] | y/ -/`
>>>>>>> -o/
>>>>>>> | 2. Boot [S]ingle User | .-
>>>>>>> ::/sy+:.
>>>>>>> | 3. [Esc]ape to loader prompt | /
>>>>>>> `--
>>>>>>> /
>>>>>>> | 4. Reboot | `:
>>>>>>> :`
>>>>>>> | | `:
>>>>>>> :`
>>>>>>> | Options: | /
>>>>>>> /
>>>>>>> | 5. Configure Boot [O]ptions... | .-
>>>>>>> -.
>>>>>>> | | -- -.
>>>>>>> | | `:`
>>>>>>> `:`
>>>>>>> | | .-- `--.
>>>>>>> | | .---.....----.
>>>>>>> +-----------------------------******------------+
>>>>>>>
>>>>>>>
>>>>>>>
>>>>>>> Booting...
>>>>>>> Copyright (c) 1992-2013 The FreeBSD Project.
>>>>>>> Copyright (c) 1979, 1980, 1983, 1986, 1988, 1989, 1991, 1992, 1993,
>>>>>>> 1994
>>>>>>> The Regents of the University of California. All rights
>>>>>>> reserved.
>>>>>>> FreeBSD is a registered trademark of The FreeBSD Foundation.
>>>>>>> FreeBSD 10.0-CURRENT #0 r248859: Thu Mar 28 19:52:06 CDT 2013
>>>>>>> root at cocopuff.example.com:/******sys/amd64/compile/BHYVE amd64
>>>>>>>
>>>>>>> FreeBSD clang version 3.2 (tags/RELEASE_32/final 170710) 20121221
>>>>>>> CPU: Intel(R) Core(TM) i7-3930K CPU @ 3.20GHz (3199.84-MHz K8-class
>>>>>>> CPU)
>>>>>>> Origin = "GenuineIntel" Id = 0x206d7 Family = 0x6 Model = 0x2d
>>>>>>> Stepping = 7
>>>>>>> Features=0x8fa3ab7f<FPU,VME,******DE,PSE,TSC,MSR,PAE,CX8,APIC,****
>>>>>>> SEP,PGE,CMOV,PAT,PSE36,DTS,******MMX,FXSR,SSE,SSE2,SS,PBE>
>>>>>>> Features2=0x83bee217<SSE3,******PCLMULQDQ,DTES64,DS_CPL,SSSE3,**
>>>>>>> ****
>>>>>>> CX16,xTPR,PDCM,PCID,DCA,SSE4.******1,SSE4.2,x2APIC,POPCNT,**
>>>>>>> TSCDLT,**AESNI,HV>
>>>>>>> AMD Features=0x2c100800<SYSCALL,******NX,Page1GB,RDTSCP,LM>
>>>>>>>
>>>>>>> AMD Features2=0x1<LAHF>
>>>>>>> TSC: P-state invariant
>>>>>>> real memory = 5368709120 (5120 MB)
>>>>>>> avail memory = 3957534720 (3774 MB)
>>>>>>> Event timer "LAPIC" quality 400
>>>>>>> ACPI APIC Table: <BHYVE BVMADT >
>>>>>>> random device not loaded; using insecure entropy
>>>>>>> ioapic0: Changing APIC ID to 1
>>>>>>> ioapic0 <Version 1.1> irqs 0-16 on motherboard
>>>>>>> kbd0 at kbdmux0
>>>>>>> acpi0: <BHYVE BVXSDT> on motherboard
>>>>>>> atrtc0: <AT realtime clock> port 0x70-0x71,0x72-0x77 irq 8 on acpi0
>>>>>>> Event timer "RTC" frequency 32768 Hz quality 0
>>>>>>> Timecounter "ACPI-fast" frequency 3579545 Hz quality 900
>>>>>>> acpi_timer0: <32-bit timer at 3.579545MHz> port 0x408-0x40b on acpi0
>>>>>>> pcib0: <ACPI Host-PCI bridge> port 0xcf8-0xcff on acpi0
>>>>>>> pci0: <ACPI PCI bus> on pcib0
>>>>>>> pcib0: no PRT entry for 0.31.INTA
>>>>>>> virtio_pci0: <VirtIO PCI Network adapter> port 0x2000-0x201f mem
>>>>>>> 0xc0000000-0xc0001fff at device 1.0 on pci0
>>>>>>> vtnet0: <VirtIO Networking Adapter> on virtio_pci0
>>>>>>> virtio_pci0: host features: 0x18020 <Status,MrgRxBuf,MacAddress>
>>>>>>> virtio_pci0: negotiated features: 0x18020
>>>>>>> <Status,MrgRxBuf,MacAddress>
>>>>>>> vtnet0: Ethernet address: 00:a0:98:ac:0d:df
>>>>>>> virtio_pci1: <VirtIO PCI Block adapter> port 0x2040-0x207f mem
>>>>>>> 0xc0002000-0xc0003fff at device 2.0 on pci0
>>>>>>> vtblk0: <VirtIO Block Adapter> on virtio_pci1
>>>>>>> virtio_pci1: host features: 0x10000004 <RingIndirect,MaxNumSegs>
>>>>>>> virtio_pci1: negotiated features: 0x10000004
>>>>>>> <RingIndirect,MaxNumSegs>
>>>>>>> vtblk0: 8192MB (16777216 512 byte sectors)
>>>>>>> uart2: <16550 or compatible> port 0x3f8-0x3ff irq 4 at device 31.0 on
>>>>>>> pci0
>>>>>>> uart2: console (9600,n,8,1)
>>>>>>> Timecounters tick every 10.000 msec
>>>>>>> g_handleattr: vtbd0 bio_length 24 len 28 -> EFAULT
>>>>>>> g_handleattr: vtbd0 bio_length 24 len 28 -> EFAULT
>>>>>>> g_handleattr: vtbd0 bio_length 24 len 28 -> EFAULT
>>>>>>> g_handleattr: vtbd0 bio_length 24 len 28 -> EFAULT
>>>>>>> g_handleattr: vtbd0 bio_length 24 len 28 -> EFAULT
>>>>>>> g_handleattr: vtbd0 bio_length 24 len 28 -> EFAULT
>>>>>>> g_handleattr: vtbd0 bio_length 24 len 28 -> EFAULT
>>>>>>> g_handleattr: vtbd0 bio_length 24 len 28 -> EFAULT
>>>>>>> Timecounter "TSC-low" frequency 1599917752 Hz quality 1000
>>>>>>> Trying to mount root from ufs:/dev/vtbd0p2 [rw]...
>>>>>>> Setting hostuuid: d4e0b8a0-97c6-11e2-b4c8-******00a098ac0ddf.
>>>>>>>
>>>>>>> Setting hostid: 0x24bfd025.
>>>>>>> Entropy harvesting: interrupts ethernet point_to_point kickstart.
>>>>>>> Starting file system checks:
>>>>>>> /dev/vtbd0p2: FILE SYSTEM CLEAN; SKIPPING CHECKS
>>>>>>> /dev/vtbd0p2: clean, 610778 free (14298 frags, 74560 blocks, 0.7%
>>>>>>> fragmentation)
>>>>>>> g_handleattr: vtbd0 bio_length 24 len 28 -> EFAULT
>>>>>>> g_handleattr: vtbd0 bio_length 24 len 28 -> EFAULT
>>>>>>> g_handleattr: vtbd0 bio_length 24 len 28 -> EFAULT
>>>>>>> Mounting local file systems:.
>>>>>>> Writing entropy file:.
>>>>>>> Setting hostname: cocopuff.example.com.
>>>>>>> vtnet0: link state changed to UP
>>>>>>> Starting Network: lo0 vtnet0.
>>>>>>> lo0: flags=8049<UP,LOOPBACK,******RUNNING,MULTICAST> metric 0 mtu
>>>>>>> 16384
>>>>>>> options=600003<RXCSUM,TXCSUM,******RXCSUM_IPV6,TXCSUM_IPV6>
>>>>>>>
>>>>>>> inet6 ::1 prefixlen 128
>>>>>>> inet6 fe80::1%lo0 prefixlen 64 scopeid 0x2
>>>>>>> inet 127.0.0.1 netmask 0xff000000
>>>>>>> nd6 options=21<PERFORMNUD,AUTO_******LINKLOCAL>
>>>>>>> vtnet0: flags=8943<UP,BROADCAST,******RUNNING,PROMISC,SIMPLEX,****
>>>>>>> MULTICAST>
>>>>>>> metric 0 mtu 1500
>>>>>>> options=80028<VLAN_MTU,JUMBO_******MTU,LINKSTATE>
>>>>>>>
>>>>>>> ether 00:a0:98:ac:0d:df
>>>>>>> inet 172.22.222.230 netmask 0xffff0000 broadcast
>>>>>>> 172.22.255.255
>>>>>>> inet6 fe80::2a0:98ff:feac:ddf%vtnet0 prefixlen 64 scopeid 0x1
>>>>>>> nd6 options=29<PERFORMNUD,******IFDISABLED,AUTO_LINKLOCAL>
>>>>>>>
>>>>>>> media: Ethernet 1000baseT <full-duplex>
>>>>>>> status: active
>>>>>>> Starting devd.
>>>>>>> add net default: gateway 172.22.22.252
>>>>>>> add net ::ffff:0.0.0.0: gateway ::1
>>>>>>> add net ::0.0.0.0: gateway ::1
>>>>>>> add net fe80::: gateway ::1
>>>>>>> add net ff02::: gateway ::1
>>>>>>> ELF ldconfig path: /lib /usr/lib /usr/lib/compat /usr/local/lib
>>>>>>> 32-bit compatibility ldconfig path: /usr/lib32
>>>>>>> Creating and/or trimming log files.
>>>>>>> Starting syslogd.
>>>>>>> Clearing /tmp (X related).
>>>>>>> Updating motd:.
>>>>>>> Performing sanity check on sshd configuration.
>>>>>>> Starting sshd.
>>>>>>> Starting cron.
>>>>>>> Starting background file system checks in 60 seconds.
>>>>>>>
>>>>>>> Fri Mar 29 14:09:20 CDT 2013
>>>>>>>
>>>>>>> FreeBSD/amd64 (cocopuff.example.com) (console)
>>>>>>>
>>>>>>> login:
>>>>>>>
>>>>>>>
>>>>>>> ====== Next, boot the same image with two (2) vCPU:
>>>>>>>
>>>>>>> Command run by vmrun.sh:
>>>>>>>
>>>>>>> root 64163 182.5 0.1 4219360 48968 1 R+ 2:16PM 1:10.42
>>>>>>> /usr/sbin/bhyve -c 2 -m 4096 -AI -H -P -g 0 -s 0:0,hostbridge -s
>>>>>>> 1:0,virtio-net,tap0 -s 2:0,virtio-blk,./diskdev -S 31,uart,stdio coco
>>>>>>>
>>>>>>> Top shows it running out of control:
>>>>>>>
>>>>>>> root at olive:~ # top -bH
>>>>>>> last pid: 64191; load averages: 1.45, 0.52, 0.31 up 0+16:35:12
>>>>>>> 14:17:32
>>>>>>> 25 processes: 3 running, 22 sleeping
>>>>>>>
>>>>>>> Mem: 32M Active, 50M Inact, 9016M Wired, 504K Cache, 53G Free
>>>>>>> ARC: 1293M Total, 370M MFU, 671M MRU, 8208K Anon, 17M Header, 227M
>>>>>>> Other
>>>>>>> Swap:
>>>>>>>
>>>>>>>
>>>>>>> PID USERNAME PRI NICE SIZE RES STATE C TIME WCPU
>>>>>>> COMMAND
>>>>>>> 64163 root 102 0 4120M 120M CPU2 2 1:01 100.00%
>>>>>>> bhyve{coco vcpu 1}
>>>>>>> 64163 root 102 0 4120M 120M CPU8 8 1:03 94.48%
>>>>>>> bhyve{coco vcpu 0}
>>>>>>> 15751 root 20 0 81452K 7092K select 5 0:00 0.00% sshd
>>>>>>> 807 root 20 0 13112K 4812K select 5 0:00 0.00% devd
>>>>>>> 925 root 20 0 14296K 2044K select 9 0:00 0.00%
>>>>>>> syslogd
>>>>>>> 1031 root 20 0 16380K 2400K nanslp 10 0:00 0.00% cron
>>>>>>> 2695 root 20 0 81452K 6952K select 2 0:00 0.00% sshd
>>>>>>> 2697 root 20 0 20224K 4632K ttyin 1 0:00 0.00% csh
>>>>>>> 64163 root 52 0 4120M 120M kqread 5 0:00 0.00%
>>>>>>> bhyve{coco mevent}
>>>>>>> 15753 root 20 0 20224K 4580K pause 1 0:00 0.00% csh
>>>>>>>
>>>>>>> Even though, it's just sitting at the login prompt:
>>>>>>>
>>>>>>> root at olive:/vms/coco # ./vmrun.sh coco
>>>>>>> Launching virtual machine "coco" ...
>>>>>>> Consoles: userboot
>>>>>>>
>>>>>>> FreeBSD/amd64 User boot, Revision 1.1
>>>>>>> (root at olive.example.com, Thu Mar 28 21:20:49 CDT 2013)
>>>>>>> Loading /boot/defaults/loader.conf /boot/kernel/kernel text=0x5a2178
>>>>>>> data=0x98088+0xef8b0 syms=[0x8+0xca8d8+0x8+******0x12bb08]
>>>>>>>
>>>>>>> /
>>>>>>> ______ ____ _____ _____
>>>>>>> | ____| | _ \ / ____| __ \
>>>>>>> | |___ _ __ ___ ___ | |_) | (___ | | | |
>>>>>>> | ___| '__/ _ \/ _ \| _ < \___ \| | | |
>>>>>>> | | | | | __/ __/| |_) |____) | |__| |
>>>>>>> | | | | | | || | | |
>>>>>>> |_| |_| \___|\___||____/|_____/|_____/ ```
>>>>>>> `
>>>>>>> s` `.....---.......--.```
>>>>>>> -/
>>>>>>> +------------Welcome to FreeBSD-----------+ +o .--` /y:`
>>>>>>> +.
>>>>>>> | | yo`:. :o
>>>>>>> `+-
>>>>>>> | 1. Boot Multi User [Enter] | y/ -/`
>>>>>>> -o/
>>>>>>> | 2. Boot [S]ingle User | .-
>>>>>>> ::/sy+:.
>>>>>>> | 3. [Esc]ape to loader prompt | /
>>>>>>> `--
>>>>>>> /
>>>>>>> | 4. Reboot | `:
>>>>>>> :`
>>>>>>> | | `:
>>>>>>> :`
>>>>>>> | Options: | /
>>>>>>> /
>>>>>>> | 5. Configure Boot [O]ptions... | .-
>>>>>>> -.
>>>>>>> | | -- -.
>>>>>>> | | `:`
>>>>>>> `:`
>>>>>>> | | .-- `--.
>>>>>>> | | .---.....----.
>>>>>>> +-----------------------------******------------+
>>>>>>>
>>>>>>>
>>>>>>>
>>>>>>> Booting...
>>>>>>> Copyright (c) 1992-2013 The FreeBSD Project.
>>>>>>> Copyright (c) 1979, 1980, 1983, 1986, 1988, 1989, 1991, 1992, 1993,
>>>>>>> 1994
>>>>>>> The Regents of the University of California. All rights
>>>>>>> reserved.
>>>>>>> FreeBSD is a registered trademark of The FreeBSD Foundation.
>>>>>>> FreeBSD 10.0-CURRENT #0 r248859: Thu Mar 28 19:52:06 CDT 2013
>>>>>>> root at cocopuff.example.com:/******sys/amd64/compile/BHYVE amd64
>>>>>>>
>>>>>>> FreeBSD clang version 3.2 (tags/RELEASE_32/final 170710) 20121221
>>>>>>> CPU: Intel(R) Core(TM) i7-3930K CPU @ 3.20GHz (3199.82-MHz K8-class
>>>>>>> CPU)
>>>>>>> Origin = "GenuineIntel" Id = 0x206d7 Family = 0x6 Model = 0x2d
>>>>>>> Stepping = 7
>>>>>>> Features=0x8fa3ab7f<FPU,VME,******DE,PSE,TSC,MSR,PAE,CX8,APIC,****
>>>>>>> SEP,PGE,CMOV,PAT,PSE36,DTS,******MMX,FXSR,SSE,SSE2,SS,PBE>
>>>>>>> Features2=0x83bee217<SSE3,******PCLMULQDQ,DTES64,DS_CPL,SSSE3,**
>>>>>>> ****
>>>>>>> CX16,xTPR,PDCM,PCID,DCA,SSE4.******1,SSE4.2,x2APIC,POPCNT,**
>>>>>>> TSCDLT,**AESNI,HV>
>>>>>>> AMD Features=0x2c100800<SYSCALL,******NX,Page1GB,RDTSCP,LM>
>>>>>>>
>>>>>>> AMD Features2=0x1<LAHF>
>>>>>>> TSC: P-state invariant
>>>>>>> real memory = 5368709120 (5120 MB)
>>>>>>> avail memory = 3957534720 (3774 MB)
>>>>>>> Event timer "LAPIC" quality 400
>>>>>>> ACPI APIC Table: <BHYVE BVMADT >
>>>>>>> FreeBSD/SMP: Multiprocessor System Detected: 2 CPUs
>>>>>>> FreeBSD/SMP: 2 package(s) x 1 core(s)
>>>>>>> cpu0 (BSP): APIC ID: 0
>>>>>>> cpu1 (AP): APIC ID: 1
>>>>>>> random device not loaded; using insecure entropy
>>>>>>> ioapic0: Changing APIC ID to 2
>>>>>>> ioapic0 <Version 1.1> irqs 0-16 on motherboard
>>>>>>> kbd0 at kbdmux0
>>>>>>> acpi0: <BHYVE BVXSDT> on motherboard
>>>>>>> atrtc0: <AT realtime clock> port 0x70-0x71,0x72-0x77 irq 8 on acpi0
>>>>>>> Event timer "RTC" frequency 32768 Hz quality 0
>>>>>>> Timecounter "ACPI-fast" frequency 3579545 Hz quality 900
>>>>>>> acpi_timer0: <32-bit timer at 3.579545MHz> port 0x408-0x40b on acpi0
>>>>>>> pcib0: <ACPI Host-PCI bridge> port 0xcf8-0xcff on acpi0
>>>>>>> pci0: <ACPI PCI bus> on pcib0
>>>>>>> pcib0: no PRT entry for 0.31.INTA
>>>>>>> virtio_pci0: <VirtIO PCI Network adapter> port 0x2000-0x201f mem
>>>>>>> 0xc0000000-0xc0001fff at device 1.0 on pci0
>>>>>>> vtnet0: <VirtIO Networking Adapter> on virtio_pci0
>>>>>>> virtio_pci0: host features: 0x18020 <Status,MrgRxBuf,MacAddress>
>>>>>>> virtio_pci0: negotiated features: 0x18020
>>>>>>> <Status,MrgRxBuf,MacAddress>
>>>>>>> vtnet0: Ethernet address: 00:a0:98:ac:0d:df
>>>>>>> virtio_pci1: <VirtIO PCI Block adapter> port 0x2040-0x207f mem
>>>>>>> 0xc0002000-0xc0003fff at device 2.0 on pci0
>>>>>>> vtblk0: <VirtIO Block Adapter> on virtio_pci1
>>>>>>> virtio_pci1: host features: 0x10000004 <RingIndirect,MaxNumSegs>
>>>>>>> virtio_pci1: negotiated features: 0x10000004
>>>>>>> <RingIndirect,MaxNumSegs>
>>>>>>> vtblk0: 8192MB (16777216 512 byte sectors)
>>>>>>> uart2: <16550 or compatible> port 0x3f8-0x3ff irq 4 at device 31.0 on
>>>>>>> pci0
>>>>>>> uart2: console (9600,n,8,1)
>>>>>>> Timecounters tick every 10.000 msec
>>>>>>> g_handleattr: vtbd0 bio_length 24 len 28 -> EFAULT
>>>>>>> g_handleattr: vtbd0 bio_length 24 len 28 -> EFAULT
>>>>>>> g_handleattr: vtbd0 bio_length 24 len 28 -> EFAULT
>>>>>>> g_handleattr: vtbd0 bio_length 24 len 28 -> EFAULT
>>>>>>> g_handleattr: vtbd0 bio_length 24 len 28 -> EFAULT
>>>>>>> g_handleattr: vtbd0 bio_length 24 len 28 -> EFAULT
>>>>>>> g_handleattr: vtbd0 bio_length 24 len 28 -> EFAULT
>>>>>>> g_handleattr: vtbd0 bio_length 24 len 28 -> EFAULT
>>>>>>> SMP: AP CPU #1 Launched!
>>>>>>> Trying to mount root from ufs:/dev/vtbd0p2 [rw]...
>>>>>>> Setting hostuuid: d4e0b8a0-97c6-11e2-b4c8-******00a098ac0ddf.
>>>>>>>
>>>>>>> Setting hostid: 0x24bfd025.
>>>>>>> Entropy harvesting: interrupts ethernet point_to_point kickstart.
>>>>>>> Starting file system checks:
>>>>>>> /dev/vtbd0p2: FILE SYSTEM CLEAN; SKIPPING CHECKS
>>>>>>> /dev/vtbd0p2: clean, 610776 free (14296 frags, 74560 blocks, 0.7%
>>>>>>> fragmentation)
>>>>>>> g_handleattr: vtbd0 bio_length 24 len 28 -> EFAULT
>>>>>>> g_handleattr: vtbd0 bio_length 24 len 28 -> EFAULT
>>>>>>> g_handleattr: vtbd0 bio_length 24 len 28 -> EFAULT
>>>>>>> Mounting local file systems:.
>>>>>>> Writing entropy file:.
>>>>>>> Setting hostname: cocopuff.example.com.
>>>>>>> vtnet0: link state changed to UP
>>>>>>> Starting Network: lo0 vtnet0.
>>>>>>> lo0: flags=8049<UP,LOOPBACK,******RUNNING,MULTICAST> metric 0 mtu
>>>>>>> 16384
>>>>>>> options=600003<RXCSUM,TXCSUM,******RXCSUM_IPV6,TXCSUM_IPV6>
>>>>>>>
>>>>>>> inet6 ::1 prefixlen 128
>>>>>>> inet6 fe80::1%lo0 prefixlen 64 scopeid 0x2
>>>>>>> inet 127.0.0.1 netmask 0xff000000
>>>>>>> nd6 options=21<PERFORMNUD,AUTO_******LINKLOCAL>
>>>>>>> vtnet0: flags=8943<UP,BROADCAST,******RUNNING,PROMISC,SIMPLEX,****
>>>>>>> MULTICAST>
>>>>>>> metric 0 mtu 1500
>>>>>>> options=80028<VLAN_MTU,JUMBO_******MTU,LINKSTATE>
>>>>>>>
>>>>>>> ether 00:a0:98:ac:0d:df
>>>>>>> inet 172.22.222.230 netmask 0xffff0000 broadcast
>>>>>>> 172.22.255.255
>>>>>>> inet6 fe80::2a0:98ff:feac:ddf%vtnet0 prefixlen 64 scopeid 0x1
>>>>>>> nd6 options=29<PERFORMNUD,******IFDISABLED,AUTO_LINKLOCAL>
>>>>>>>
>>>>>>> media: Ethernet 1000baseT <full-duplex>
>>>>>>> status: active
>>>>>>> Starting devd.
>>>>>>> add net default: gateway 172.22.22.252
>>>>>>> add net ::ffff:0.0.0.0: gateway ::1
>>>>>>> add net ::0.0.0.0: gateway ::1
>>>>>>> add net fe80::: gateway ::1
>>>>>>> add net ff02::: gateway ::1
>>>>>>> ELF ldconfig path: /lib /usr/lib /usr/lib/compat /usr/local/lib
>>>>>>> 32-bit compatibility ldconfig path: /usr/lib32
>>>>>>> Creating and/or trimming log files.
>>>>>>> Starting syslogd.
>>>>>>> syslogd: timed out waiting for child
>>>>>>> /etc/rc: WARNING: failed to start syslogd
>>>>>>> Clearing /tmp (X related).
>>>>>>> Updating motd:.
>>>>>>> Performing sanity check on sshd configuration.
>>>>>>> Starting sshd.
>>>>>>> Starting cron.
>>>>>>> Starting background file system checks in 60 seconds.
>>>>>>>
>>>>>>> Thu Apr 18 02:06:43 CDT 2013
>>>>>>>
>>>>>>> FreeBSD/amd64 (cocopuff.example.com) (console)
>>>>>>>
>>>>>>> login:
>>>>>>>
>>>>>>>
>>>>>>> ========== end of boot sequence
>>>>>>>
>>>>>>> So, something is broken with SMP on the guest, I'd be happy to help
>>>>>>> just
>>>>>>> let me know.
>>>>>>>
>>>>>>> Dan
>>>>>>>
>>>>>>> ______________________________******_________________
>>>>>>> freebsd-virtualization@****freeb**sd.org <http://freebsd.org> <
>>>>>>> freebsd-virtualization@**freeb**sd.org <http://freebsd.org><
>>>>>>> freebsd-virtualization@**freebsd.org<freebsd-virtualization at freebsd.org>
>>>>>>> >>mailing
>>>>>>> list
>>>>>>> http://lists.freebsd.org/******mailman/listinfo/freebsd-****<http://lists.freebsd.org/****mailman/listinfo/freebsd-****>
>>>>>>> virtualization<http://lists.**freebsd.org/**mailman/**
>>>>>>> listinfo/freebsd-****virtualization<http://lists.freebsd.org/**mailman/listinfo/freebsd-**virtualization>
>>>>>>> >
>>>>>>> <http://lists.**freebsd.org/**mailman/listinfo/**freebsd-**
>>>>>>> virtualization<http://freebsd.org/mailman/listinfo/**freebsd-virtualization>
>>>>>>> <http://lists.**freebsd.org/mailman/listinfo/**
>>>>>>> freebsd-virtualization<http://lists.freebsd.org/mailman/listinfo/freebsd-virtualization>
>>>>>>> >
>>>>>>>
>>>>>>>>
>>>>>>>> To unsubscribe, send any mail to "freebsd-virtualization-**
>>>>>>> unsubscribe at freebsd.org <freebsd-virtualization-**
>>>>>>> unsubscribe at freebsd.org<freebs**d-virtualization-unsubscribe@**
>>>>>>> freebsd.org <freebsd-virtualization-unsubscribe at freebsd.org>>
>>>>>>>
>>>>>>>> "
>>>>>>>>
>>>>>>>
>>>>>>> ______________________________******_________________
>>>>>>>
>>>>>>> freebsd-virtualization@****freeb**sd.org <http://freebsd.org> <
>>>>>> freebsd-virtualization@**freeb**sd.org <http://freebsd.org><
>>>>>> freebsd-virtualization@**freebsd.org<freebsd-virtualization at freebsd.org>
>>>>>> >>mailing
>>>>>> list
>>>>>> http://lists.freebsd.org/******mailman/listinfo/freebsd-******
>>>>>> virtualization<http://lists.freebsd.org/****mailman/listinfo/freebsd-****virtualization>
>>>>>> <http://lists.**freebsd.org/**mailman/**listinfo/freebsd-****
>>>>>> virtualization<http://lists.freebsd.org/**mailman/listinfo/freebsd-**virtualization>
>>>>>> >
>>>>>> <http://lists.**freebsd.org/**mailman/listinfo/**freebsd-**
>>>>>> virtualization<http://freebsd.org/mailman/listinfo/**freebsd-virtualization>
>>>>>> <http://lists.**freebsd.org/mailman/listinfo/**freebsd-virtualization<http://lists.freebsd.org/mailman/listinfo/freebsd-virtualization>
>>>>>> >
>>>>>>
>>>>>>>
>>>>>>> To unsubscribe, send any mail to "freebsd-virtualization-**
>>>>>> unsubscribe at freebsd.org <freebsd-virtualization-**
>>>>>> unsubscribe at freebsd.org <freebsd-virtualization-**
>>>>>> unsubscribe at freebsd.org<freebsd-virtualization-unsubscribe at freebsd.org>
>>>>>> >
>>>>>>
>>>>>>> "
>>>>>>>
>>>>>>
>>>>>>
>>>>>>
>>>>>
>>> ______________________________**_________________
>> freebsd-virtualization@**freebsd.org <freebsd-virtualization at freebsd.org>mailing list
>> http://lists.freebsd.org/**mailman/listinfo/freebsd-**virtualization<http://lists.freebsd.org/mailman/listinfo/freebsd-virtualization>
>> To unsubscribe, send any mail to "freebsd-virtualization-**
>> unsubscribe at freebsd.org <freebsd-virtualization-unsubscribe at freebsd.org>"
>>
>>
More information about the freebsd-virtualization
mailing list