Preface: I believe this is a regression in VirtualBox 5.x and reported
it to them a while ago (https://www.virtualbox.org/ticket/14515). I'm
sending it along to bugs@ just in case the trace is of interest to
anyone. If not, feel free to disregard.

Symptom: When booting 5.8-stable i386 with > 1 CPU, system halts on
boot before /etc/rc with "cpu1 failed to become ready".

Workarounds:
- Revert to VirtualBox 4.3.30.
- Drop CPU count to 1.
- Use 5.7-stable i386.
- Use any version of amd64 with any number of CPUs.
- Don't use VirtualBox.

Dmesg, trace, ps, registers below. Thanks for your time.

Brian Conway

>> OpenBSD/i386 BOOT 3.26
boot>
booting hd0a:/bsd: 9749372+1069036 [83+411760+405701]=0xb18eb8
entry point at 0x200120

[ using 818000 bytes of bsd ELF symbol table ]
Copyright (c) 1982, 1986, 1989, 1991, 1993
The Regents of the University of California.  All rights reserved.
Copyright (c) 1995-2015 OpenBSD. All rights reserved.  http://www.OpenBSD.org

OpenBSD 5.8-stable (GENERIC.MP) #0: Fri Oct  2 22:21:38 UTC 2015
    r...@buildi386.int.rcesoftware.com:/usr/src/sys/arch/i386/compile/GENERIC.MP
cpu0: Intel(R) Core(TM) i7-3615QM CPU @ 2.30GHz ("GenuineIntel"
686-class) 2.30 GHz
cpu0: 
FPU,V86,DE,PSE,TSC,MSR,PAE,MCE,CX8,APIC,SEP,MTRR,PGE,MCA,CMOV,PAT,PSE36,CFLUSH,MMX,FXSR,SSE,SSE2,HTT,NXE,LONG,SSE3,PCLMUL,SSSE3,CX16,SSE4.1,SSE4.2,POPCNT,AES,XSAVE,AVX,RDRAND,HV,LAHF,ITSC
real mem  = 804732928 (767MB)
avail mem = 776245248 (740MB)
mpath0 at root
scsibus0 at mpath0: 256 targets
mainbus0 at root
bios0 at mainbus0: date 06/23/99, BIOS32 rev. 0 @ 0xfda00, SMBIOS rev.
2.5 @ 0xe1000 (10 entries)
bios0: vendor innotek GmbH version "VirtualBox" date 12/01/2006
bios0: innotek GmbH VirtualBox
acpi0 at bios0: rev 2
acpi0: sleep states S0 S5
acpi0: tables DSDT FACP APIC SSDT
acpi0: wakeup devices
acpitimer0 at acpi0: 3579545 Hz, 32 bits
acpimadt0 at acpi0 addr 0xfee00000: PC-AT compat
cpu0 at mainbus0: apid 0 (boot processor)
mtrr: CPU supports MTRRs but not enabled by BIOS
cpu0: apic clock running at 1000MHz
cpu1 at mainbus0: apid 1 (application processor)
cpu1: Intel(R) Core(TM) i7-3615QM CPU @ 2.30GHz ("GenuineIntel"
686-class) 2.30 GHz
cpu1: 
FPU,V86,DE,PSE,TSC,MSR,PAE,MCE,CX8,APIC,SEP,MTRR,PGE,MCA,CMOV,PAT,PSE36,CFLUSH,MMX,FXSR,SSE,SSE2,HTT,NXE,LONG,SSE3,PCLMUL,SSSE3,CX16,SSE4.1,SSE4.2,POPCNT,AES,XSAVE,AVX,RDRAND,HV,LAHF,ITSC
cpu2 at mainbus0: apid 2 (application processor)
cpu2: Intel(R) Core(TM) i7-3615QM CPU @ 2.30GHz ("GenuineIntel"
686-class) 2.30 GHz
cpu2: 
FPU,V86,DE,PSE,TSC,MSR,PAE,MCE,CX8,APIC,SEP,MTRR,PGE,MCA,CMOV,PAT,PSE36,CFLUSH,MMX,FXSR,SSE,SSE2,HTT,NXE,LONG,SSE3,PCLMUL,SSSE3,CX16,SSE4.1,SSE4.2,POPCNT,AES,XSAVE,AVX,RDRAND,HV,LAHF,ITSC
ioapic0 at mainbus0: apid 3 pa 0xfec00000, version 11, 24 pins
acpiprt0 at acpi0: bus 0 (PCI0)
acpicpu0 at acpi0: C1(@1 halt!)
acpicpu1 at acpi0: C1(@1 halt!)
acpicpu2 at acpi0: C1(@1 halt!)
acpibat0 at acpi0: BAT0 model "1" serial 0 type VBOX oem "innotek"
acpiac0 at acpi0: AC unit online
acpivideo0 at acpi0: GFX0
bios0: ROM list: 0xc0000/0x8000 0xe2000/0xd000
pvbus0 at mainbus0: KVM
pci0 at mainbus0 bus 0: configuration mode 1 (bios)
pchb0 at pci0 dev 0 function 0 "Intel 82441FX" rev 0x02
pcib0 at pci0 dev 1 function 0 "Intel 82371SB ISA" rev 0x00
vga1 at pci0 dev 2 function 0 "InnoTek VirtualBox Graphics Adapter" rev 0x00
wsdisplay0 at vga1 mux 1: console (80x25, vt100 emulation)
wsdisplay0: screen 1-5 added (80x25, vt100 emulation)
em0 at pci0 dev 3 function 0 "Intel 82540EM" rev 0x02: apic 3 int 19,
address 08:00:27:c3:57:d8
"InnoTek VirtualBox Guest Service" rev 0x00 at pci0 dev 4 function 0
not configured
piixpm0 at pci0 dev 7 function 0 "Intel 82371AB Power" rev 0x08: SMBus disabled
ahci0 at pci0 dev 13 function 0 "Intel 82801HBM AHCI" rev 0x02: apic 3
int 21, AHCI 1.1
ahci0: device on port 0 didn't come ready, TFD: 0x171<ERR>
ahci0: port 0: 3.0Gb/s
scsibus1 at ahci0: 32 targets
sd0 at scsibus1 targ 0 lun 0: <ATA, VBOX HARDDISK, 1.0> SCSI3 0/direct
fixed t10.ATA_VBOX_HARDDISK_VBe5c20152-ef51bee2_
sd0: 10240MB, 512 bytes/sector, 20971520 sectors
isa0 at pcib0
isadma0 at isa0
com0 at isa0 port 0x3f8/8 irq 4: ns16550a, 16 byte fifo
com0: console
pckbc0 at isa0 port 0x60/5 irq 1 irq 12
pckbd0 at pckbc0 (kbd slot)
wskbd0 at pckbd0: console keyboard, using wsdisplay0
pms0 at pckbc0 (aux slot)
wsmouse0 at pms0 mux 0
pcppi0 at isa0 port 0x61
spkr0 at pcppi0
npx0 at isa0 port 0xf0/16: reported by CPUID; using exception 16
vscsi0 at root
scsibus2 at vscsi0: 256 targets
softraid0 at root
scsibus3 at softraid0: 256 targets
root on sd0a (1e7613432019dde1.a) swap on sd0b dump on sd0b
cpu1 failed to become ready
Stopped at      Debugger+0x7:   leave
ddb{0}> show panic
the kernel did not panic
ddb{0}> trace
Debugger(d0aecb8b,d1f9f014,d0d1af18,d0390c7b,d0b2a368) at Debugger+0x7
cpu_boot_secondary(d1f9f000,d09d1f82,0,d03fa6c9,1) at cpu_boot_secondary+0x84
cpu_boot_secondary_processors(d05149d0,0,0,d09af8e1,d039d640) at cpu_boot_secon
dary_processors+0x60
main(d0200503,d020050b,0,0,0) at main+0x7e8
ddb{0}> ps
   PID   PPID   PGRP    UID  S       FLAGS  WAIT          COMMAND
  6077      0      0      0  2     0x14200                zerothread
 20663      0      0      0  2     0x14200                aiodoned
 26137      0      0      0  2     0x14200                update
  3248      0      0      0  2     0x14200                cleaner
 17017      0      0      0  2     0x14200                reaper
 13840      0      0      0  2     0x14200                pagedaemon
  2302      0      0      0  3     0x14200  bored         crypto
 15051      0      0      0  2     0x14600                pfpurge
 18982      0      0      0  3  0x40014200  acpi0         acpi0
 21315      0      0      0  1     0x14200                idle2
  7307      0      0      0  1     0x14200                idle1
   965      0      0      0  3     0x14200  bored         softnet
  9324      0      0      0  3     0x14200  bored         systqmp
 29195      0      0      0  2     0x14200                systq
  9770      0      0      0  3  0x40014200                idle0
 31005      0      0      0  3     0x14200  kmalloc       kmthread
     1      0      0      0  3           0  initexec      swapper
*    0     -1      0      0  7     0x10200                swapper
ddb{0}> show registers
ds                  0x10
es                  0x10
fs                  0x20
gs                     0
edi             0xd27000
esi           0xd1f9f000        end+0x134db78
ebp           0xd0d1aee8        end+0xc9a60
ebx                    0
edx                    0
ecx           0xd0b2c280        kprintf_mutex
eax                 0x1c
eip           0xd0556437        Debugger+0x7
cs                   0x8
eflags             0x286
esp           0xd0d1aed8        end+0xc9a50
ss                  0x10
Debugger+0x7:   leave
ddb{0}>

Reply via email to