bhyve hangs on init on recent 10-CURRENT

Neel Natu neelnatu at gmail.com
Tue Jun 25 04:26:43 UTC 2013


Hi Dan,

On Sun, Jun 23, 2013 at 7:02 AM, Dan Mack <mack at macktronics.com> wrote:
> Is this just me?  When I start a new bhyve instance, it hangs on /sbin/init.   top shows bhyve consuming about 1 host core of CPU.   This is on a real recent current.
>

I don't see it on my system with this:
FreeBSD 10.0-CURRENT (GENERIC) #0: Sun Jun 23 02:55:37 UTC 2013

My system has a much lower clock speed that yours - 2GHz versus
3.2GHz, but other than that I kept everything else similar to your
setup.

Is this a new system or were you able to use this successfully in the
past to run bhyve?

best
Neel

>
>
> root at darkstor:/vms/porter # ./vmrun.sh porter
> Launching virtual machine "porter" ...
> Consoles: userboot
>
> FreeBSD/amd64 User boot, Revision 1.1
> (root at darkstor.macktronics.com, Sat Jun 22 22:31:58 CDT 2013)
> Loading /boot/defaults/loader.conf
> /boot/kernel/kernel text=0x6c21c7 data=0xa43b8+0xfa338 syms=[0x8+0xe76a8+0x8+0x147ee8]
> \
>   ______               ____   _____ _____
>  |  ____|             |  _ \ / ____|  __ \
>  | |___ _ __ ___  ___ | |_) | (___ | |  | |
>  |  ___| '__/ _ \/ _ \|  _ < \___ \| |  | |
>  | |   | | |  __/  __/| |_) |____) | |__| |
>  | |   | | |    |    ||     |      |      |
>  |_|   |_|  \___|\___||____/|_____/|_____/    ```                        `
>                                              s` `.....---.......--.```   -/
>  +------------Welcome to FreeBSD-----------+ +o   .--`         /y:`      +.
>  |                                         |  yo`:.            :o      `+-
>  |  1. Back to Main Menu [Backspace]       |   y/               -/`   -o/
>  |  2. Load System [D]efaults              |  .-                  ::/sy+:.
>  |                                         |  /                     `--  /
>  |  Boot Options:                          | `:                          :`
>  |  3. Safe [M]ode... off                  | `:                          :`
>  |  4. [S]ingle User. off                  |  /                          /
>  |  5. [V]erbose..... On                   |  .-                        -.
>  |                                         |   --                      -.
>  |                                         |    `:`                  `:`
>  |                                         |      .--             `--.
>  |                                         |         .---.....----.
>  +-----------------------------------------+
>
>
> Booting...
> GDB: no debug ports present
> KDB: debugger backends: ddb
> KDB: current backend: ddb
> SMAP type=01 base=0000000000000000 len=00000000000a0000
> SMAP type=01 base=0000000000100000 len=0000000089300000
> Table 'APIC' at 0xf0500
> APIC: Found table at 0xf0500
> APIC: Using the MADT enumerator.
> MADT: Found CPU APIC ID 0 ACPI ID 0: enabled
> SMP: Added CPU 0 (AP)
> MADT: Found CPU APIC ID 1 ACPI ID 1: enabled
> SMP: Added CPU 1 (AP)
> 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 #9 r251956: Tue Jun 18 12:25:25 CDT 2013
>     root at darkstor.macktronics.com:/usr/obj/usr/src/sys/MACKGEN amd64
> FreeBSD clang version 3.3 (tags/RELEASE_33/final 183502) 20130610
> Preloaded elf kernel "/boot/kernel/kernel" at 0xffffffff80e91000.
> Hypervisor: Origin = "BHyVBHyVBHyV"
> Calibrating TSC clock ... TSC clock: 3299774008 Hz
> CPU: Intel(R) Xeon(R) CPU E3-1230 V2 @ 3.30GHz (3299.77-MHz K8-class CPU)
>   Origin = "GenuineIntel"  Id = 0x306a9  Family = 0x6  Model = 0x3a  Stepping = 9
>   Features=0x8fa3ab7f<FPU,VME,DE,PSE,TSC,MSR,PAE,CX8,APIC,SEP,PGE,CMOV,PAT,PSE36,DTS,MMX,FXSR,SSE,SSE2,SS,PBE>
>   Features2=0xe3bae257<SSE3,PCLMULQDQ,DTES64,DS_CPL,SMX,SSSE3,CX16,xTPR,PDCM,PCID,SSE4.1,SSE4.2,x2APIC,POPCNT,TSCDLT,AESNI,F16C,RDRAND,HV>
>   AMD Features=0x28100800<SYSCALL,NX,RDTSCP,LM>
>   AMD Features2=0x1<LAHF>
>   TSC: P-state invariant
> real memory  = 2302672896 (2196 MB)
> Physical memory chunk(s):
> 0x0000000000001000 - 0x000000000009bfff, 634880 bytes (155 pages)
> 0x0000000000100000 - 0x00000000001fffff, 1048576 bytes (256 pages)
> 0x0000000000eb1000 - 0x0000000085bc1fff, 2228293632 bytes (544017 pages)
> avail memory = 2133233664 (2034 MB)
> Event timer "LAPIC" quality 400
> ACPI APIC Table: <BHYVE  BVMADT  >
> INTR: Adding local APIC 1 as a target
> 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
> x86bios:  IVT 0x000000-0x0004ff at 0xfffffe0000000000
> x86bios: SSEG 0x001000-0x001fff at 0xffffff800022e000
> x86bios:  ROM 0x0a0000-0x0fefff at 0xfffffe00000a0000
> APIC: CPU 0 has ACPI ID 0
> APIC: CPU 1 has ACPI ID 1
> random device not loaded; using insecure entropy
> ULE: setup cpu 0
> ULE: setup cpu 1
> ACPI: RSDP 0xf0400 00024 (v02 BHYVE )
> ACPI: XSDT 0xf0480 00034 (v01 BHYVE  BVXSDT   00000001 INTL 20130517)
> ACPI: APIC 0xf0500 00052 (v01 BHYVE  BVMADT   00000001 INTL 20130517)
> ACPI: FACP 0xf0600 0010C (v05 BHYVE  BVFACP   00000001 INTL 20130517)
> ACPI: DSDT 0xf0800 000F2 (v02 BHYVE  BVDSDT   00000001 INTL 20130517)
> ACPI: FACS 0xf0780 00040
> MADT: Found IO APIC ID 2, Interrupt 0 at 0xfec00000
> ioapic0: Changing APIC ID to 2
> ioapic0: Routing external 8259A's -> intpin 0
> MADT: Interrupt override: source 9, irq 9
> ioapic0: intpin 9 trigger: level
> ioapic0: intpin 9 polarity: low
> ioapic0 <Version 1.1> irqs 0-16 on motherboard
> cpu0 BSP:
>      ID: 0x00000000   VER: 0x00000011 LDR: 0x00000000 DFR: 0xffffffff
>   lint0: 0x00010700 lint1: 0x00000400 TPR: 0x00000000 SVR: 0x000001ff
>   timer: 0x000100ef therm: 0x00010000 err: 0x000000f0
> null: <null device, zero device>
> random: <entropy source, Hardware, Intel IvyBridge+ RNG>
> module_register_init: MOD_LOAD (vesa, 0xffffffff807b7950, 0) error 19
> io: <I/O>
> kbd0 at kbdmux0
> nfslock: pseudo-device
> mem: <memory>
> acpi0: <BHYVE BVXSDT> on motherboard
> ACPI: All ACPI Tables successfully acquired
> ioapic0: routing intpin 9 (ISA IRQ 9) to lapic 0 vector 48
> atrtc0: <AT realtime clock> port 0x70-0x71,0x72-0x77 irq 8 on acpi0
> atrtc0: registered as a time-of-day clock (resolution 1000000us, adjustment 0.500000000s)
> ioapic0: routing intpin 8 (ISA IRQ 8) to lapic 0 vector 49
> Event timer "RTC" frequency 32768 Hz quality 0
> ACPI timer: 1/6 1/5 1/6 1/5 1/7 1/5 1/4 1/4 1/4 1/4 -> 10
> 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
> pcib0: decoding 4 range 0-0xcf7
> pcib0: decoding 4 range 0xd00-0xffff
> pcib0: could not get PCI interrupt routing table for \_SB_.PCI0 - AE_NOT_FOUND
> pci0: <ACPI PCI bus> on pcib0
> pci0: domain=0, physical bus=0
> found-> vendor=0x1275, dev=0x1275, revid=0x00
>         domain=0, bus=0, slot=0, func=0
>         class=06-00-00, hdrtype=0x01, mfdev=0
>         cmdreg=0x0007, statreg=0x0010, cachelnsz=0 (dwords)
>         lattimer=0x00 (0 ns), mingnt=0x00 (0 ns), maxlat=0x00 (0 ns)
> found-> vendor=0x1af4, dev=0x1000, revid=0x00
>         domain=0, bus=0, slot=1, func=0
>         class=02-00-00, hdrtype=0x00, mfdev=0
>         cmdreg=0x0007, statreg=0x0010, cachelnsz=0 (dwords)
>         lattimer=0x00 (0 ns), mingnt=0x00 (0 ns), maxlat=0x00 (0 ns)
>         MSI-X supports 3 messages in map 0x14
>         map[10]: type I/O Port, range 32, base 0x2000, size  5, enabled
> pcib0: allocated type 4 (0x2000-0x201f) for rid 10 of pci0:0:1:0
>         map[14]: type Memory, range 32, base 0xc0000000, size 13, enabled
> found-> vendor=0x1af4, dev=0x1001, revid=0x00
>         domain=0, bus=0, slot=2, func=0
>         class=01-00-00, hdrtype=0x00, mfdev=0
>         cmdreg=0x0007, statreg=0x0010, cachelnsz=0 (dwords)
>         lattimer=0x00 (0 ns), mingnt=0x00 (0 ns), maxlat=0x00 (0 ns)
>         MSI-X supports 2 messages in map 0x14
>         map[10]: type I/O Port, range 32, base 0x2040, size  6, enabled
> pcib0: allocated type 4 (0x2040-0x207f) for rid 10 of pci0:0:2:0
>         map[14]: type Memory, range 32, base 0xc0002000, size 13, enabled
> found-> vendor=0x1af4, dev=0x1001, revid=0x00
>         domain=0, bus=0, slot=3, func=0
>         class=01-00-00, hdrtype=0x00, mfdev=0
>         cmdreg=0x0007, statreg=0x0010, cachelnsz=0 (dwords)
>         lattimer=0x00 (0 ns), mingnt=0x00 (0 ns), maxlat=0x00 (0 ns)
>         MSI-X supports 2 messages in map 0x14
>         map[10]: type I/O Port, range 32, base 0x2080, size  6, enabled
> pcib0: allocated type 4 (0x2080-0x20bf) for rid 10 of pci0:0:3:0
>         map[14]: type Memory, range 32, base 0xc0004000, size 13, enabled
> found-> vendor=0x131f, dev=0x2000, revid=0x00
>         domain=0, bus=0, slot=31, func=0
>         class=07-00-00, hdrtype=0x00, mfdev=0
>         cmdreg=0x0007, statreg=0x0000, cachelnsz=0 (dwords)
>         lattimer=0x00 (0 ns), mingnt=0x00 (0 ns), maxlat=0x00 (0 ns)
>         intpin=a, irq=4
>         map[10]: type I/O Port, range 32, base 0x3f8, size  3, enabled
> pcib0: allocated type 4 (0x3f8-0x3ff) for rid 10 of pci0:0:31:0
> 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: 0x1018020 <NotifyOnEmpty,Status,MrgRxBuf,MacAddress>
> virtio_pci0: negotiated features: 0x1018020 <NotifyOnEmpty,Status,MrgRxBuf,MacAddress>
> virtio_pci0: virtqueue 1 (vtnet0 transmit) requested indirect descriptors but not negotiated
> vtnet0: bpf attached
> vtnet0: Ethernet address: 00:a0:98:fd:e2:7e
> virtio_pci0: attempting to allocate 3 MSI-X vectors (3 supported)
> msi: routing MSI-X IRQ 256 to local APIC 0 vector 50
> msi: routing MSI-X IRQ 257 to local APIC 0 vector 51
> msi: routing MSI-X IRQ 258 to local APIC 0 vector 52
> virtio_pci0: using IRQs 256-258 for MSI-X
> virtio_pci0: using per VQ MSIX interrupts
> 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>
> virtio_pci1: attempting to allocate 2 MSI-X vectors (2 supported)
> msi: routing MSI-X IRQ 259 to local APIC 0 vector 53
> msi: routing MSI-X IRQ 260 to local APIC 0 vector 54
> virtio_pci1: using IRQs 259-260 for MSI-X
> virtio_pci1: using per VQ MSIX interrupts
> vtblk0: 18432MB (37748736 512 byte sectors)
> virtio_pci2: <VirtIO PCI Block adapter> port 0x2080-0x20bf mem 0xc0004000-0xc0005fff at device 3.0 on pci0
> vtblk1: <VirtIO Block Adapter> on virtio_pci2
> virtio_pci2: host features: 0x10000004 <RingIndirect,MaxNumSegs>
> virtio_pci2: negotiated features: 0x10000004 <RingIndirect,MaxNumSegs>
> virtio_pci2: attempting to allocate 2 MSI-X vectors (2 supported)
> msi: routing MSI-X IRQ 261 to local APIC 0 vector 55
> msi: routing MSI-X IRQ 262 to local APIC 0 vector 56
> virtio_pci2: using IRQs 261-262 for MSI-X
> virtio_pci2: using per VQ MSIX interrupts
> vtblk1: 683MB (1400648 512 byte sectors)
> uart2: <16550 or compatible> port 0x3f8-0x3ff irq 4 at device 31.0 on pci0
> ioapic0: routing intpin 4 (ISA IRQ 4) to lapic 0 vector 57
> uart2: fast interrupt
> uart2: console (9600,n,8,1)
> acpi0: wakeup code va 0xffffff80002c5000 pa 0x4000
> ctl: CAM Target Layer loaded
> Device configuration finished.
> procfs registered
> lapic: Divisor 2, Frequency 1650046000 Hz
> Timecounters tick every 10.000 msec
> vlan: initialized, using hash tables with chaining
> tcp_init: net.inet.tcp.tcbhashsize auto tuned to 32768
> lo0: bpf attached
> GEOM: new disk vtbd0
> GEOM: new disk vtbd1
> SMP: AP CPU #1 Launched!
> cpu1 AP:
>      ID: 0x01000000   VER: 0x00000011 LDR: 0x00000000 DFR: 0xffffffff
>   lint0: 0x00010700 lint1: 0x00000400 TPR: 0x00000000 SVR: 0x000001ff
>   timer: 0x000100ef therm: 0x00010000 err: 0x000000f0
> ioapic0: routing intpin 9 (ISA IRQ 9) to lapic 1 vector 48
> msi: Assigning MSI-X IRQ 257 to local APIC 1 vector 49
> msi: Assigning MSI-X IRQ 259 to local APIC 1 vector 50
> msi: Assigning MSI-X IRQ 261 to local APIC 1 vector 51
> TSC timecounter discards lower 1 bit(s)
> Timecounter "TSC-low" frequency 1649887004 Hz quality -100
> Trying to mount root from cd9660:/dev/iso9660/FREEBSD_INSTALL [ro]...
> cd9660: RockRidge Extension
> start_init: trying /sbin/init
>
>  [ hangs indefinitely ]
>
> CPUs on the host look like this:
>
> root at darkstor:~ # top -bHI
> last pid:  7781;  load averages:  1.00,  0.93,  0.62  up 0+00:51:59    07:32:45
> 28 processes:  2 running, 26 sleeping
>
> Mem: 31M Active, 27M Inact, 5408M Wired, 4096K Cache, 26G Free
> ARC: 1642M Total, 628M MFU, 992M MRU, 16K Anon, 7038K Header, 15M Other
> Swap:
>
>
>   PID USERNAME   PRI NICE   SIZE    RES STATE   C   TIME    WCPU COMMAND
>  7757 root        88    0  2221M  5160K CPU1    1   2:30  54.20% bhyve{porter vcpu 0}
>  7757 root        36    0  2221M  5160K vmidle  3   5:02  48.19% bhyve{porter vcpu 1}
>
> root at darkstor:~ # uname -a
> FreeBSD darkstor.macktronics.com 10.0-CURRENT FreeBSD 10.0-CURRENT #11 r252104: Sat Jun 22 22:45:53 CDT 2013     root at darkstor.macktronics.com:/usr/obj/usr/src/sys/MACKGEN  amd64
>
>
>
>
>
> _______________________________________________
> freebsd-virtualization at freebsd.org mailing list
> http://lists.freebsd.org/mailman/listinfo/freebsd-virtualization
> To unsubscribe, send any mail to "freebsd-virtualization-unsubscribe at freebsd.org"


More information about the freebsd-virtualization mailing list