usb/140160: USB ports are no longer "active" after ACPI suspend/resume cycle.

Sun Nov 1 17:15:35 UTC 2009

On Sun, Nov 1, 2009 at 9:12 AM, Hans Petter Selasky <hselasky at c2i.net> wrote:
> On Sunday 01 November 2009 06:45:55 Brandon Gooch wrote:
>> >Number:         140160
>> >Category:       usb
>> >Synopsis:       USB ports are no longer "active" after ACPI suspend/resume
>> > cycle. Confidential:   no
>> >Severity:       non-critical
>> >Priority:       medium
>> >Responsible:    freebsd-usb
>> >State:          open
>> >Quarter:
>> >Keywords:
>> >Date-Required:
>> >Class:          sw-bug
>> >Submitter-Id:   current-users
>> >Arrival-Date:   Sun Nov 01 05:50:00 UTC 2009
>> >Closed-Date:
>> >Last-Modified:
>> >Originator:     Brandon Gooch
>> >Release:        8.0
>> >Organization:
>> >Environment:
>>
>> FreeBSD x300 8.0-RC2 FreeBSD 8.0-RC2 #0 r198667: Fri Oct 30 02:21:46 UTC
>> 2009     root at x300:/usr/obj/usr/src/sys/X300  amd64
>>
>> >Description:
>>
>> USB ports are unavailable after a suspend/resume cycle. From
>> /var/log/messages:
>>
>> Oct 31 13:41:22 x300 acpi: suspend at 20091031 13:41:22
>> Oct 31 13:41:26 x300 kernel: ugen0.2: <STMicroelectronics> at usbus0
>> (disconnected) Oct 31 13:41:35 x300 kernel: ugen6.2: <vendor 0x17ef> at
>> usbus6 (disconnected) Oct 31 13:41:35 x300 kernel: usb_alloc_device:1597:
>> set address 2 failed (USB_ERR_TIMEOUT, ignored) Oct 31 13:41:47 x300
>> kernel: usb_alloc_device:1635: getting device descriptor at addr 2 failed,
>> USB_ERR_STALLED! Oct 31 13:41:47 x300 kernel: usbus2: port reset timeout
>> Oct 31 13:41:47 x300 kernel: uhub_reattach_port:371: port 2 reset failed,
>> error=USB_ERR_TIMEOUT Oct 31 13:41:47 x300 kernel: uhub_reattach_port:458:
>> device problem (USB_ERR_TIMEOUT), disabling port 2 Oct 31 13:41:47 x300
>> kernel: usbus6: port reset timeout
>> Oct 31 13:41:47 x300 kernel: uhub_reattach_port:371: port 5 reset failed,
>> error=USB_ERR_TIMEOUT Oct 31 13:41:47 x300 kernel: uhub_reattach_port:458:
>> device problem (USB_ERR_TIMEOUT), disabling port 5 Oct 31 13:41:47 x300
>> kernel: usbd_req_re_enumerate:1539: addr=2, set address failed!
>> (USB_ERR_STALLED, ignored) Oct 31 13:41:47 x300 kernel:
>> usbd_req_re_enumerate:1553: getting device descriptor at addr 2 failed,
>> USB_ERR_STALLED! Oct 31 13:41:47 x300 kernel: usbd_req_re_enumerate:1539:
>> addr=2, set address failed! (USB_ERR_STALLED, ignored) Oct 31 13:41:47 x300
>> kernel: usbd_req_re_enumerate:1553: getting device descriptor at addr 2
>> failed, USB_ERR_STALLED! Oct 31 13:41:47 x300 kernel: ugen0.2: <(null)> at
>> usbus0 (disconnected) Oct 31 13:41:47 x300 kernel: uhub_reattach_port:436:
>> could not allocate new device! Oct 31 13:41:47 x300 acpi: resumed at
>> 20091031 13:41:47
>> Oct 31 13:41:48 x300 root: Unknown USB device: vendor 0x17ef product 0x4807
>> bus uhub6 Oct 31 13:41:48 x300 kernel: ugen6.2: <vendor 0x17ef> at usbus6
>>
>> >How-To-Repeat:
>>
>> Suspend computer, then resume computer. Plug a device into any of the USB
>> ports and realize that the ports are no longer "active".
>>
>> >Fix:
>
> Can you try to disable USB legacy support in the BIOS. Probably there is a
> BIOS handover issue at resume.
>
> --HPS
>
>

I've tried each combination of bios settings, and the problem
persists. It's strange -- the first suspend/resume cycle works great,
everything comes back on, USB ports still active, etc...

It's the subsequent suspend/resume cycles where I see the problem.
I've attached my dmesg output, in case it matters.
-------------- next part --------------
Copyright (c) 1992-2009 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 8.0-RC2 #0 r198667: Fri Oct 30 02:21:46 UTC 2009
    root at x300:/usr/obj/usr/src/sys/X300
Timecounter "i8254" frequency 1193182 Hz quality 0
CPU: Intel(R) Core(TM)2 Duo CPU     L7100  @ 1.20GHz (1197.01-MHz K8-class CPU)
  Origin = "GenuineIntel"  Id = 0x6fb  Stepping = 11
  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=0xe3bd<SSE3,DTES64,MON,DS_CPL,VMX,EST,TM2,SSSE3,CX16,xTPR,PDCM>
  AMD Features=0x20100800<SYSCALL,NX,LM>
  AMD Features2=0x1<LAHF>
  TSC: P-state invariant
real memory  = 2147483648 (2048 MB)
avail memory = 2031456256 (1937 MB)
ACPI APIC Table: <LENOVO TP-7T   >
FreeBSD/SMP: Multiprocessor System Detected: 2 CPUs
FreeBSD/SMP: 1 package(s) x 2 core(s)
 cpu0 (BSP): APIC ID:  0
 cpu1 (AP): APIC ID:  1
ACPI Warning: 32/64X length mismatch in Gpe1Block: 0/32 20090521 tbfadt-625
ACPI Warning: Optional field Gpe1Block has zero address or length:        0    102C/0 20090521 tbfadt-655
ioapic0: Changing APIC ID to 1
ioapic0 <Version 2.0> irqs 0-23 on motherboard
kbd1 at kbdmux0
acpi0: <LENOVO TP-7T> on motherboard
acpi0: [ITHREAD]
acpi_ec0: <Embedded Controller: GPE 0x12, ECDT> port 0x62,0x66 on acpi0
acpi0: Power Button (fixed)
acpi0: reservation of 0, a0000 (3) failed
acpi0: reservation of 100000, 7ef00000 (3) failed
Timecounter "ACPI-fast" frequency 3579545 Hz quality 1000
acpi_timer0: <24-bit timer at 3.579545MHz> port 0x1008-0x100b on acpi0
acpi_hpet0: <High Precision Event Timer> iomem 0xfed00000-0xfed003ff on acpi0
Timecounter "HPET" frequency 14318180 Hz quality 900
acpi_lid0: <Control Method Lid Switch> on acpi0
acpi_button0: <Sleep Button> on acpi0
pcib0: <ACPI Host-PCI bridge> port 0xcf8-0xcff on acpi0
pci0: <ACPI PCI bus> on pcib0
vgapci0: <VGA-compatible display> port 0x1800-0x1807 mem 0xfa000000-0xfa0fffff,0xe0000000-0xefffffff irq 16 at device 2.0 on pci0
agp0: <Intel GM965 SVGA controller> on vgapci0
agp0: detected 7676k stolen memory
agp0: aperture size is 256M
drm0: <Intel i965GM> on vgapci0
info: [drm] MSI enabled 1 message(s)
vgapci0: child drm0 requested pci_enable_busmaster
info: [drm] AGP at 0xe0000000 256MB
info: [drm] Initialized i915 1.6.0 20080730
vgapci1: <VGA-compatible display> mem 0xfa100000-0xfa1fffff at device 2.1 on pci0
em0: <Intel(R) PRO/1000 Network Connection 6.9.14> port 0x1840-0x185f mem 0xfa200000-0xfa21ffff,0xfa225000-0xfa225fff irq 20 at device 25.0 on pci0
em0: Using MSI interrupt
em0: [FILTER]
em0: Ethernet address: 00:1c:25:7c:1d:59
uhci0: <Intel 82801H (ICH8) USB controller USB-D> port 0x1860-0x187f irq 20 at device 26.0 on pci0
uhci0: [ITHREAD]
usbus0: <Intel 82801H (ICH8) USB controller USB-D> on uhci0
uhci1: <Intel 82801H (ICH8) USB controller USB-E> port 0x1880-0x189f irq 21 at device 26.1 on pci0
uhci1: [ITHREAD]
usbus1: <Intel 82801H (ICH8) USB controller USB-E> on uhci1
ehci0: <Intel 82801H (ICH8) USB 2.0 controller USB2-B> mem 0xfa426c00-0xfa426fff irq 22 at device 26.7 on pci0
ehci0: [ITHREAD]
usbus2: EHCI version 1.0
usbus2: <Intel 82801H (ICH8) USB 2.0 controller USB2-B> on ehci0
hdac0: <Intel 82801H High Definition Audio Controller> mem 0xfa220000-0xfa223fff irq 17 at device 27.0 on pci0
hdac0: HDA Driver Revision: 20090624_0136
hdac0: [ITHREAD]
pcib1: <ACPI PCI-PCI bridge> irq 20 at device 28.0 on pci0
pci2: <ACPI PCI bus> on pcib1
pcib2: <ACPI PCI-PCI bridge> irq 21 at device 28.1 on pci0
pci3: <ACPI PCI bus> on pcib2
iwn0: <Intel(R) PRO/Wireless 4965BGN> mem 0xf9f00000-0xf9f01fff irq 17 at device 0.0 on pci3
===band 0===
chan lo=1, chan hi=13
chan1 num=2
chain 0, sample 0: temp=30 gain=40 power=18 pa_det=-27
chain 0, sample 1: temp=28 gain=32 power=26 pa_det=3
chain 0, sample 2: temp=29 gain=24 power=34 pa_det=38
chain 1, sample 0: temp=29 gain=41 power=18 pa_det=-22
chain 1, sample 1: temp=29 gain=33 power=26 pa_det=9
chain 1, sample 2: temp=29 gain=26 power=32 pa_det=39
chan2 num=12
chain 0, sample 0: temp=29 gain=41 power=18 pa_det=-30
chain 0, sample 1: temp=29 gain=34 power=26 pa_det=-5
chain 0, sample 2: temp=29 gain=27 power=32 pa_det=26
chain 1, sample 0: temp=29 gain=42 power=18 pa_det=-25
chain 1, sample 1: temp=29 gain=35 power=25 pa_det=0
chain 1, sample 2: temp=29 gain=28 power=32 pa_det=33
===band 1===
chan lo=34, chan hi=64
chan1 num=36
chain 0, sample 0: temp=29 gain=38 power=19 pa_det=-23
chain 0, sample 1: temp=29 gain=28 power=28 pa_det=12
chain 0, sample 2: temp=29 gain=23 power=33 pa_det=35
chain 1, sample 0: temp=29 gain=35 power=18 pa_det=-20
chain 1, sample 1: temp=29 gain=25 power=26 pa_det=14
chain 1, sample 2: temp=29 gain=20 power=31 pa_det=37
chan2 num=60
chain 0, sample 0: temp=29 gain=41 power=19 pa_det=-26
chain 0, sample 1: temp=29 gain=33 power=27 pa_det=6
chain 0, sample 2: temp=29 gain=27 power=32 pa_det=26
chain 1, sample 0: temp=29 gain=34 power=19 pa_det=-21
chain 1, sample 1: temp=29 gain=26 power=26 pa_det=4
chain 1, sample 2: temp=29 gain=20 power=32 pa_det=31
===band 2===
chan lo=100, chan hi=132
chan1 num=104
chain 0, sample 0: temp=29 gain=44 power=17 pa_det=-40
chain 0, sample 1: temp=29 gain=36 power=25 pa_det=-17
chain 0, sample 2: temp=29 gain=28 power=32 pa_det=15
chain 1, sample 0: temp=29 gain=36 power=17 pa_det=-33
chain 1, sample 1: temp=29 gain=27 power=25 pa_det=-7
chain 1, sample 2: temp=30 gain=20 power=32 pa_det=24
chan2 num=128
chain 0, sample 0: temp=29 gain=40 power=17 pa_det=-39
chain 0, sample 1: temp=29 gain=33 power=24 pa_det=-18
chain 0, sample 2: temp=29 gain=25 power=32 pa_det=13
chain 1, sample 0: temp=29 gain=37 power=17 pa_det=-34
chain 1, sample 1: temp=29 gain=27 power=26 pa_det=-4
chain 1, sample 2: temp=30 gain=21 power=32 pa_det=22
===band 3===
chan lo=134, chan hi=165
chan1 num=140
chain 0, sample 0: temp=30 gain=39 power=17 pa_det=-39
chain 0, sample 1: temp=30 gain=31 power=24 pa_det=-17
chain 0, sample 2: temp=29 gain=24 power=31 pa_det=13
chain 1, sample 0: temp=29 gain=37 power=16 pa_det=-36
chain 1, sample 1: temp=30 gain=26 power=27 pa_det=-2
chain 1, sample 2: temp=30 gain=21 power=31 pa_det=18
chan2 num=161
chain 0, sample 0: temp=30 gain=37 power=16 pa_det=-38
chain 0, sample 1: temp=30 gain=28 power=25 pa_det=-9
chain 0, sample 2: temp=30 gain=22 power=31 pa_det=17
chain 1, sample 0: temp=30 gain=32 power=16 pa_det=-34
chain 1, sample 1: temp=30 gain=22 power=26 pa_det=-2
chain 1, sample 2: temp=31 gain=16 power=31 pa_det=24
===band 4===
chan lo=0, chan hi=0
chan1 num=0
chain 0, sample 0: temp=0 gain=0 power=0 pa_det=0
chain 0, sample 1: temp=0 gain=0 power=0 pa_det=0
chain 0, sample 2: temp=0 gain=0 power=0 pa_det=0
chain 1, sample 0: temp=0 gain=0 power=0 pa_det=0
chain 1, sample 1: temp=0 gain=0 power=0 pa_det=0
chain 1, sample 2: temp=0 gain=0 power=0 pa_det=0
chan2 num=0
chain 0, sample 0: temp=0 gain=0 power=0 pa_det=0
chain 0, sample 1: temp=0 gain=0 power=0 pa_det=0
chain 0, sample 2: temp=0 gain=0 power=0 pa_det=0
chain 1, sample 0: temp=0 gain=0 power=0 pa_det=0
chain 1, sample 1: temp=0 gain=0 power=0 pa_det=0
chain 1, sample 2: temp=0 gain=0 power=0 pa_det=0
===band 5===
chan lo=0, chan hi=0
chan1 num=0
chain 0, sample 0: temp=0 gain=0 power=0 pa_det=0
chain 0, sample 1: temp=0 gain=0 power=0 pa_det=0
chain 0, sample 2: temp=0 gain=0 power=0 pa_det=0
chain 1, sample 0: temp=0 gain=0 power=0 pa_det=0
chain 1, sample 1: temp=0 gain=0 power=0 pa_det=0
chain 1, sample 2: temp=0 gain=0 power=0 pa_det=0
chan2 num=0
chain 0, sample 0: temp=0 gain=0 power=0 pa_det=0
chain 0, sample 1: temp=0 gain=0 power=0 pa_det=0
chain 0, sample 2: temp=0 gain=0 power=0 pa_det=0
chain 1, sample 0: temp=0 gain=0 power=0 pa_det=0
chain 1, sample 1: temp=0 gain=0 power=0 pa_det=0
chain 1, sample 2: temp=0 gain=0 power=0 pa_det=0
===band 6===
chan lo=0, chan hi=0
chan1 num=0
chain 0, sample 0: temp=0 gain=0 power=0 pa_det=0
chain 0, sample 1: temp=0 gain=0 power=0 pa_det=0
chain 0, sample 2: temp=0 gain=0 power=0 pa_det=0
chain 1, sample 0: temp=0 gain=0 power=0 pa_det=0
chain 1, sample 1: temp=0 gain=0 power=0 pa_det=0
chain 1, sample 2: temp=0 gain=0 power=0 pa_det=0
chan2 num=0
chain 0, sample 0: temp=0 gain=0 power=0 pa_det=0
chain 0, sample 1: temp=0 gain=0 power=0 pa_det=0
chain 0, sample 2: temp=0 gain=0 power=0 pa_det=0
chain 1, sample 0: temp=0 gain=0 power=0 pa_det=0
chain 1, sample 1: temp=0 gain=0 power=0 pa_det=0
chain 1, sample 2: temp=0 gain=0 power=0 pa_det=0
===band 7===
chan lo=0, chan hi=0
chan1 num=0
chain 0, sample 0: temp=0 gain=0 power=0 pa_det=0
chain 0, sample 1: temp=0 gain=0 power=0 pa_det=0
chain 0, sample 2: temp=0 gain=0 power=0 pa_det=0
chain 1, sample 0: temp=0 gain=0 power=0 pa_det=0
chain 1, sample 1: temp=0 gain=0 power=0 pa_det=0
chain 1, sample 2: temp=0 gain=0 power=0 pa_det=0
chan2 num=0
chain 0, sample 0: temp=0 gain=0 power=0 pa_det=0
chain 0, sample 1: temp=0 gain=0 power=0 pa_det=0
chain 0, sample 2: temp=0 gain=0 power=0 pa_det=0
chain 1, sample 0: temp=0 gain=0 power=0 pa_det=0
chain 1, sample 1: temp=0 gain=0 power=0 pa_det=0
chain 1, sample 2: temp=0 gain=0 power=0 pa_det=0
iwn0: MIMO 2T3R, MoW1, address 00:1f:3b:28:30:c5
iwn0: [ITHREAD]
iwn0: 11a rates: 6Mbps 9Mbps 12Mbps 18Mbps 24Mbps 36Mbps 48Mbps 54Mbps
iwn0: 11b rates: 1Mbps 2Mbps 5.5Mbps 11Mbps
iwn0: 11g rates: 1Mbps 2Mbps 5.5Mbps 11Mbps 6Mbps 9Mbps 12Mbps 18Mbps 24Mbps 36Mbps 48Mbps 54Mbps
iwn0: 11na MCS: 15Mbps 30Mbps 45Mbps 60Mbps 90Mbps 120Mbps 135Mbps 150Mbps 30Mbps 60Mbps 90Mbps 120Mbps 180Mbps 240Mbps 270Mbps 300Mbps
iwn0: 11ng MCS: 15Mbps 30Mbps 45Mbps 60Mbps 90Mbps 120Mbps 135Mbps 150Mbps 30Mbps 60Mbps 90Mbps 120Mbps 180Mbps 240Mbps 270Mbps 300Mbps
pcib3: <ACPI PCI-PCI bridge> irq 22 at device 28.2 on pci0
pci4: <ACPI PCI bus> on pcib3
uhci2: <Intel 82801H (ICH8) USB controller USB-A> port 0x18a0-0x18bf irq 16 at device 29.0 on pci0
uhci2: [ITHREAD]
usbus3: <Intel 82801H (ICH8) USB controller USB-A> on uhci2
uhci3: <Intel 82801H (ICH8) USB controller USB-B> port 0x18c0-0x18df irq 17 at device 29.1 on pci0
uhci3: [ITHREAD]
usbus4: <Intel 82801H (ICH8) USB controller USB-B> on uhci3
uhci4: <Intel 82801H (ICH8) USB controller USB-C> port 0x18e0-0x18ff irq 18 at device 29.2 on pci0
uhci4: [ITHREAD]
usbus5: <Intel 82801H (ICH8) USB controller USB-C> on uhci4
ehci1: <Intel 82801H (ICH8) USB 2.0 controller USB2-A> mem 0xfa427000-0xfa4273ff irq 19 at device 29.7 on pci0
ehci1: [ITHREAD]
usbus6: EHCI version 1.0
usbus6: <Intel 82801H (ICH8) USB 2.0 controller USB2-A> on ehci1
pcib4: <ACPI PCI-PCI bridge> at device 30.0 on pci0
pci13: <ACPI PCI bus> on pcib4
isab0: <PCI-ISA bridge> at device 31.0 on pci0
isa0: <ISA bus> on isab0
atapci0: <Intel ICH8M UDMA100 controller> port 0x1f0-0x1f7,0x3f6,0x170-0x177,0x376,0x1c00-0x1c0f at device 31.1 on pci0
ata0: <ATA channel 0> on atapci0
ata0: [ITHREAD]
atapci1: <Intel ICH8M SATA300 controller> port 0x1c50-0x1c57,0x1c44-0x1c47,0x1c48-0x1c4f,0x1c40-0x1c43,0x1c20-0x1c3f mem 0xfa426000-0xfa4267ff irq 16 at device 31.2 on pci0
atapci1: [ITHREAD]
atapci1: AHCI called from vendor specific driver
atapci1: AHCI v1.10 controller with 3 1.5Gbps ports, PM not supported
ata2: <ATA channel 0> on atapci1
ata2: [ITHREAD]
ata3: <ATA channel 2> on atapci1
ata3: [ITHREAD]
pci0: <serial bus, SMBus> at device 31.3 (no driver attached)
acpi_tz0: <Thermal Zone> on acpi0
acpi_tz1: <Thermal Zone> on acpi0
atrtc0: <AT realtime clock> port 0x70-0x71 irq 8 on acpi0
atkbdc0: <Keyboard controller (i8042)> port 0x60,0x64 irq 1 on acpi0
atkbd0: <AT Keyboard> irq 1 on atkbdc0
kbd0 at atkbd0
atkbd0: [GIANT-LOCKED]
atkbd0: [ITHREAD]
psm0: <PS/2 Mouse> irq 12 on atkbdc0
psm0: [GIANT-LOCKED]
psm0: [ITHREAD]
psm0: model Generic PS/2 mouse, device ID 0
battery0: <ACPI Control Method Battery> on acpi0
acpi_acad0: <AC Adapter> on acpi0
acpi_ibm0: <IBM ThinkPad ACPI Extras> on acpi0
cpu0: <ACPI CPU> on acpi0
est0: <Enhanced SpeedStep Frequency Control> on cpu0
p4tcc0: <CPU Frequency Thermal Control> on cpu0
cpu1: <ACPI CPU> on acpi0
est1: <Enhanced SpeedStep Frequency Control> on cpu1
p4tcc1: <CPU Frequency Thermal Control> on cpu1
orm0: <ISA Option ROMs> at iomem 0xc0000-0xcffff,0xd0000-0xd0fff,0xd1000-0xd1fff,0xe0000-0xeffff 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
ZFS NOTICE: Prefetch is disabled by default if less than 4GB of RAM is present;
            to enable, add "vfs.zfs.prefetch_disable=0" to /boot/loader.conf.
ZFS filesystem version 13
ZFS storage pool version 13
Timecounters tick every 1.000 msec
module_register_init: MOD_LOAD (ng_vboxnetflt, 0xffffffff80b95580, 0xffffffff80d782e0) error 22
vboxdrv: fAsync=0 offMin=0xf6 offMax=0x22e
vboxnet0: Ethernet address: 0a:00:27:00:00:00
usbus0: 12Mbps Full Speed USB v1.0
usbus1: 12Mbps Full Speed USB v1.0
usbus2: 480Mbps High Speed USB v2.0
usbus3: 12Mbps Full Speed USB v1.0
usbus4: 12Mbps Full Speed USB v1.0
usbus5: 12Mbps Full Speed USB v1.0
usbus6: 480Mbps High Speed USB v2.0
ugen0.1: <Intel> at usbus0
uhub0: <Intel UHCI root HUB, class 9/0, rev 1.00/1.00, addr 1> on usbus0
ugen1.1: <Intel> at usbus1
uhub1: <Intel UHCI root HUB, class 9/0, rev 1.00/1.00, addr 1> on usbus1
ugen2.1: <Intel> at usbus2
uhub2: <Intel EHCI root HUB, class 9/0, rev 2.00/1.00, addr 1> on usbus2
ugen3.1: <Intel> at usbus3
uhub3: <Intel UHCI root HUB, class 9/0, rev 1.00/1.00, addr 1> on usbus3
ugen4.1: <Intel> at usbus4
uhub4: <Intel UHCI root HUB, class 9/0, rev 1.00/1.00, addr 1> on usbus4
ugen5.1: <Intel> at usbus5
uhub5: <Intel UHCI root HUB, class 9/0, rev 1.00/1.00, addr 1> on usbus5
ugen6.1: <Intel> at usbus6
uhub6: <Intel EHCI root HUB, class 9/0, rev 2.00/1.00, addr 1> on usbus6
acd0: DVDR <MATSHITADVD-RAM UJ-844/RC02> at ata0-master UDMA33
ad4: 61057MB <SAMSUNG MCCOE64G8MPP-0VA PS105L15> at ata2-master SATA150
hdac0: HDA Codec #0: Analog Devices AD1984A
pcm0: <HDA Analog Devices AD1984A PCM #0 Analog> at cad 0 nid 1 on hdac0
SMP: AP CPU #1 Launched!
Root mount waiting for: usbus6 usbus5 usbus4 usbus3 usbus2 usbus1 usbus0
uhub0: 2 ports with 2 removable, self powered
uhub1: 2 ports with 2 removable, self powered
uhub3: 2 ports with 2 removable, self powered
uhub4: 2 ports with 2 removable, self powered
uhub5: 2 ports with 2 removable, self powered
Root mount waiting for: usbus6 usbus2
uhub2: 4 ports with 4 removable, self powered
Root mount waiting for: usbus6 usbus2
uhub6: 6 ports with 6 removable, self powered
ugen6.2: <vendor 0x17ef> at usbus6
Trying to mount root from zfs:zroot
ugen1.2: <vendor 0x0461> at usbus1
ums0: <vendor 0x0461 USB Optical Mouse, class 0/0, rev 2.00/2.00, addr 2> on usbus1
ugen0.2: <STMicroelectronics> at usbus0
ums0: 3 buttons and [XYZ] coordinates ID=0
wlan0: Ethernet address: 00:1f:3b:28:30:c5
ipfw2 (+ipv6) initialized, divert loadable, nat loadable, rule-based forwarding disabled, default to deny, logging disabled
wlan0: link state changed to UP
iwn0: need multicast update callback
iwn0: need multicast update callback
iwn0: need multicast update callback
drm0: [ITHREAD]
iwn0: need multicast update callback
wlan0: link state changed to DOWN
stray irq0
ugen0.2: <STMicroelectronics> at usbus0 (disconnected)ugen1.2: <vendor 0x0461> at usbus1 (disconnected)

ums0: at uhub1, port 1, addr 2 (disconnected)
ugen1.2: <vendor 0x0461> at usbus1
ums0: <vendor 0x0461 USB Optical Mouse, class 0/0, rev 2.00/2.00, addr 2> on usbus1
ums0: 3 buttons and [XYZ] coordinates ID=0
ugen0.2: <STMicroelectronics> at usbus0
drm0: [ITHREAD]
wlan0: link state changed to UP
iwn0: need multicast update callback
iwn0: need multicast update callback
iwn0: need multicast update callback
iwn0: need multicast update callback
wlan0: link state changed to DOWN
stray irq0
acpi_ec0: warning: EC done before starting event wait
ugen0.2: <STMicroelectronics> at usbus0 (disconnected)
ugen1.2: <vendor 0x0461> at usbus1 (disconnected)
ums0: at uhub1, port 1, addr 2 (disconnected)
ugen6.2: <vendor 0x17ef> at usbus6 (disconnected)
ugen6.2: <vendor 0x17ef> at usbus6
ugen0.2: <STMicroelectronics> at usbus0
drm0: [ITHREAD]
wlan0: link state changed to UP
iwn0: need multicast update callback
iwn0: need multicast update callback
iwn0: need multicast update callback
iwn0: need multicast update callback
wlan0: link state changed to DOWN
stray irq0
ugen0.2: <STMicroelectronics> at usbus0 (disconnected)
ugen0.2: <STMicroelectronics> at usbus0
drm0: [ITHREAD]
wlan0: link state changed to UP
iwn0: need multicast update callback
iwn0: need multicast update callback
iwn0: need multicast update callback
iwn0: need multicast update callback
wlan0: link state changed to DOWN
stray irq0
ugen0.2: <STMicroelectronics> at usbus0 (disconnected)
usbus2: port reset timeout
uhub_reattach_port:371: port 2 reset failed, error=USB_ERR_TIMEOUT
uhub_reattach_port:458: device problem (USB_ERR_TIMEOUT), disabling port 2
ugen6.2: <vendor 0x17ef> at usbus6 (disconnected)
usbus6: port reset timeout
uhub_reattach_port:371: port 5 reset failed, error=USB_ERR_TIMEOUT
uhub_reattach_port:458: device problem (USB_ERR_TIMEOUT), disabling port 5
drm0: [ITHREAD]
ugen6.2: <vendor 0x17ef> at usbus6
wlan0: link state changed to UP
iwn0: need multicast update callback
iwn0: need multicast update callback
iwn0: need multicast update callback