Skip site navigation (1)Skip section navigation (2)
Date:      Sat, 20 Feb 2016 06:24:16 -0600
From:      Larry Rosenman <ler@lerctr.org>
To:        freebsd-current@freebsd.org
Subject:   Re: new computer, strange usb messages at boot
Message-ID:  <20160220122416.GA1026@lrosenman-dell.lerctr.org>
In-Reply-To: <20160220120401.GA91220@kib.kiev.ua>
References:  <20160220051951.GA47875@lrosenman-dell.lerctr.org> <20160220120401.GA91220@kib.kiev.ua>

next in thread | previous in thread | raw e-mail | index | archive | help
On Sat, Feb 20, 2016 at 02:04:01PM +0200, Konstantin Belousov wrote:
> On Fri, Feb 19, 2016 at 11:19:51PM -0600, Larry Rosenman wrote:
> > Does any of this look weird?  What can I provide to help?
> 
> > Sleeping on "acmtx" with the following non-sleepable locks held:
> > exclusive sleep mutex intr sources (intr sources) r = 0 (0xffffffff81c7f630) locked @ /usr/src/sys/x86/x86/intr_machdep.c:549
> > stack backtrace:
> > #0 0xffffffff80a7f790 at witness_debugger+0x70
> > #1 0xffffffff80a80aa7 at witness_warn+0x3d7
> > #2 0xffffffff80a2e26d at _sleep+0x6d
> > #3 0xffffffff80399ff8 at AcpiOsAcquireMutex+0xc8
> > #4 0xffffffff8036891a at AcpiUtAcquireMutex+0x3a
> > #5 0xffffffff80355f2b at AcpiExEnterInterpreter+0xb
> > #6 0xffffffff8035a2fb at AcpiNsEvaluate+0x1cb
> > #7 0xffffffff8035d7b4 at AcpiEvaluateObject+0x174
> > #8 0xffffffff8039ac0d at acpi_GetInteger+0x3d
> > #9 0xffffffff80f94c01 at dmar_find_hpet+0x81
> > #10 0xffffffff80f9d54d at iommu_map_msi_intr+0x2d
> > #11 0xffffffff80fb2f91 at msi_map+0x171
> > #12 0xffffffff80e73035 at hpet_remap_intr+0xb5
> > #13 0xffffffff80fb2627 at msi_assign_cpu+0x1c7
> > #14 0xffffffff80fa9733 at intr_shuffle_irqs+0x73
> > #15 0xffffffff809c3a38 at mi_startup+0x108
> > #16 0xffffffff802fb02c at btext+0x2c
> > lock order reversal: (Giant after non-sleepable)
> >  1st 0xffffffff81c7f630 intr sources (intr sources) @ /usr/src/sys/x86/x86/intr_machdep.c:549
> >  2nd 0xffffffff81cd4d60 Giant (Giant) @ /usr/src/sys/kern/kern_synch.c:244
> > stack backtrace:
> > #0 0xffffffff80a7f790 at witness_debugger+0x70
> > #1 0xffffffff80a7f691 at witness_checkorder+0xTrying to mount root from zfs:zroot/ROOT/default []...
> > e71
> > #2 0xffffffff80a06f94 at __mtx_lock_flags+0xa4
> > #3 0xffffffff80a2e5ba at _sleep+0x3ba
> > #4 0xffffffff80399ff8 at AcpiOsAcquireMutex+0xc8
> > #5 0xffffffff8036891a at AcpiUtAcquireMutex+0x3a
> > #6 0xffffffff80355f2b at AcpiExEnterInterpreter+0xb
> > #7 0xffffffff8035a2fb at AcpiNsEvaluate+0x1cb
> > #8 0xffffffff8035d7b4 at AcpiEvaluateObject+0x174
> > #9 0xffffffff8039ac0d at acpi_GetInteger+0x3d
> > #10 0xffffffff80f94c01 at dmar_find_hpet+0x81
> > #11 0xffffffff80f9d54d at iommu_map_msi_intr+0x2d
> > #12 0xffffffff80fb2f91 at msi_map+0x171
> > #13 0xffffffff80e73035 at hpet_remap_intr+0xb5
> > #14 0xffffffff80fb2627 at msi_assign_cpu+0x1c7
> > #15 0xffffffff80fa9733 at intr_shuffle_irqs+0x73
> > #16 0xffffffff809c3a38 at mi_startup+0x108
> > #17 0xffffffff802fb02c at btext+0x2c
> 
> For these two LORs, please try the following patch. Apparently
> acpi_GetInteger() might get acpi lock.  Patch also modernizes /dev/hpet
> creation.
> 
> Just patch and boot, the LOR messages should go away as the only change
> in behaviour.

Applied, and it does seem to fix the LOR:

Copyright (c) 1992-2016 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 11.0-CURRENT #1 r295833M: Sat Feb 20 06:13:34 CST 2016
    root@lrosenman-dell:/usr/obj/usr/src/sys/GENERIC amd64
FreeBSD clang version 3.7.1 (tags/RELEASE_371/final 255217) 20151225
WARNING: WITNESS option enabled, expect reduced performance.
VT(efifb): resolution 2048x1200
CPU: Intel(R) Core(TM) i7-6700HQ CPU @ 2.60GHz (2592.13-MHz K8-class CPU)
  Origin="GenuineIntel"  Id=0x506e3  Family=0x6  Model=0x5e  Stepping=3
  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=0x7ffafbbf<SSE3,PCLMULQDQ,DTES64,MON,DS_CPL,VMX,EST,TM2,SSSE3,SDBG,FMA,CX16,xTPR,PDCM,PCID,SSE4.1,SSE4.2,x2APIC,MOVBE,POPCNT,TSCDLT,AESNI,XSAVE,OSXSAVE,AVX,F16C,RDRAND>
  AMD Features=0x2c100800<SYSCALL,NX,Page1GB,RDTSCP,LM>
  AMD Features2=0x121<LAHF,ABM,Prefetch>
  Structured Extended Features=0x29c6fbf<FSGSBASE,TSCADJ,BMI1,HLE,AVX2,SMEP,BMI2,ERMS,INVPCID,RTM,NFPUSG,MPX,RDSEED,ADX,SMAP,CLFLUSHOPT,PROCTRACE>
  XSAVE Features=0xf<XSAVEOPT,XSAVEC,XINUSE,XSAVES>
  VT-x: PAT,HLT,MTF,PAUSE,EPT,UG,VPID
  TSC: P-state invariant, performance statistics
real memory  = 17179869184 (16384 MB)
avail memory = 16396095488 (15636 MB)
Event timer "LAPIC" quality 600
ACPI APIC Table: <DELL   QA09   >
FreeBSD/SMP: Multiprocessor System Detected: 8 CPUs
FreeBSD/SMP: 1 package(s) x 4 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
random: unblocking device.
ioapic0 <Version 2.0> irqs 0-119 on motherboard
random: entropy device external interface
kbd1 at kbdmux0
netmap: loaded module
module_register_init: MOD_LOAD (vesa, 0xffffffff80ee1ea0, 0) error 19
random: registering fast source Intel Secure Key RNG
random: fast provider: "Intel Secure Key RNG"
cryptosoft0: <software crypto> on motherboard
acpi0: <DELL QA09   > on motherboard
acpi0: Power Button (fixed)
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
hpet0: <High Precision Event Timer> iomem 0xfed00000-0xfed003ff on acpi0
Timecounter "HPET" frequency 24000000 Hz quality 950
Event timer "HPET" frequency 24000000 Hz quality 550
atrtc0: <AT realtime clock> port 0x70-0x77 irq 8 on acpi0
atrtc0: Warning: Couldn't map I/O.
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
Timecounter "ACPI-fast" frequency 3579545 Hz quality 900
acpi_timer0: <24-bit timer at 3.579545MHz> port 0x1808-0x180b on acpi0
acpi_ec0: <Embedded Controller: GPE 0x46> port 0x62,0x66 on acpi0
pcib0: <ACPI Host-PCI bridge> port 0xcf8-0xcff on acpi0
pci0: <ACPI PCI bus> on pcib0
pcib1: <ACPI PCI-PCI bridge> irq 16 at device 1.0 on pci0
pci1: <ACPI PCI bus> on pcib1
pcib2: <ACPI PCI-PCI bridge> irq 16 at device 1.1 on pci0
pci2: <ACPI PCI bus> on pcib2
vgapci0: <VGA-compatible display> port 0xe000-0xe07f mem 0xde000000-0xdeffffff,0xc0000000-0xcfffffff,0xd0000000-0xd1ffffff irq 17 at device 0.0 on pci2
nvidia0: <GeForce GTX 960M> on vgapci0
vgapci0: child nvidia0 requested pci_enable_io
vgapci0: child nvidia0 requested pci_enable_io
vgapci1: <VGA-compatible display> port 0xf000-0xf03f mem 0xdd000000-0xddffffff,0xb0000000-0xbfffffff irq 16 at device 2.0 on pci0
vgapci1: Boot video device
xhci0: <XHCI (generic) USB 3.0 controller> mem 0xdf410000-0xdf41ffff irq 16 at device 20.0 on pci0
xhci0: 32 bytes context size, 64-bit DMA
usbus0: waiting for BIOS to give up control
usbus0 on xhci0
pci0: <simple comms> at device 22.0 (no driver attached)
ahci0: <AHCI SATA controller> port 0xf090-0xf097,0xf080-0xf083,0xf060-0xf07f mem 0xdf430000-0xdf431fff,0xdf434000-0xdf4340ff,0xdf433000-0xdf4337ff irq 16 at device 23.0 on pci0
ahci0: AHCI v1.31 with 2 6Gbps ports, Port Multiplier not supported
ahcich0: <AHCI channel> at channel 0 on ahci0
ahcich1: <AHCI channel> at channel 1 on ahci0
ahciem0: <AHCI enclosure management bridge> on ahci0
pcib3: <ACPI PCI-PCI bridge> irq 16 at device 28.0 on pci0
pci3: <ACPI PCI bus> on pcib3
pcib4: <ACPI PCI-PCI bridge> irq 16 at device 28.4 on pci0
pci4: <ACPI PCI bus> on pcib4
re0: <RealTek 8168/8111 B/C/CP/D/DP/E/F/G PCIe Gigabit Ethernet> port 0xd000-0xd0ff mem 0xdf304000-0xdf304fff,0xdf300000-0xdf303fff irq 16 at device 0.0 on pci4
re0: Using 1 MSI-X message
re0: ASPM disabled
re0: Chip rev. 0x50800000
re0: MAC rev. 0x00100000
miibus0: <MII bus> on re0
rgephy0: <RTL8251 1000BASE-T media interface> PHY 1 on miibus0
rgephy0:  none, 10baseT, 10baseT-FDX, 10baseT-FDX-flow, 100baseTX, 100baseTX-FDX, 100baseTX-FDX-flow, 1000baseT-FDX, 1000baseT-FDX-master, 1000baseT-FDX-flow, 1000baseT-FDX-flow-master, auto, auto-flow
re0: Using defaults for TSO: 65518/35/2048
re0: Ethernet address: 20:47:47:73:07:5f
re0: netmap queues/slots: TX 1/256, RX 1/256
pcib5: <ACPI PCI-PCI bridge> irq 17 at device 28.5 on pci0
pci5: <ACPI PCI bus> on pcib5
pci5: <network> at device 0.0 (no driver attached)
pcib6: <ACPI PCI-PCI bridge> irq 18 at device 28.6 on pci0
pci6: <ACPI PCI bus> on pcib6
pci6: <unknown> at device 0.0 (no driver attached)
isab0: <PCI-ISA bridge> at device 31.0 on pci0
isa0: <ISA bus> on isab0
pci0: <memory> at device 31.2 (no driver attached)
hdac0: <Intel (0xa170) HDA Controller> mem 0xdf428000-0xdf42bfff,0xdf400000-0xdf40ffff irq 16 at device 31.3 on pci0
acpi_button0: <Sleep Button> on acpi0
acpi_button1: <Power Button> on acpi0
acpi_lid0: <Control Method Lid Switch> on acpi0
acpi_tz0: <Thermal Zone> on acpi0
acpi_tz1: <Thermal Zone> 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]
psm0: <PS/2 Mouse> irq 12 on atkbdc0
psm0: [GIANT-LOCKED]
psm0: model Generic PS/2 mouse, device ID 0
acpi_acad0: <AC Adapter> on acpi0
battery0: <ACPI Control Method Battery> on acpi0
orm0: <ISA Option ROM> at iomem 0xd0000-0xd0fff on isa0
ppc0: cannot reserve I/O port range
est0: <Enhanced SpeedStep Frequency Control> on cpu0
est1: <Enhanced SpeedStep Frequency Control> on cpu1
est2: <Enhanced SpeedStep Frequency Control> on cpu2
est3: <Enhanced SpeedStep Frequency Control> on cpu3
est4: <Enhanced SpeedStep Frequency Control> on cpu4
est5: <Enhanced SpeedStep Frequency Control> on cpu5
est6: <Enhanced SpeedStep Frequency Control> on cpu6
est7: <Enhanced SpeedStep Frequency Control> on cpu7
ZFS filesystem version: 5
ZFS storage pool version: features support (5000)
Timecounters tick every 1.000 msec
IPsec: Initialized Security Association Processing.
hdacc0: <Realtek (0x0256) HDA CODEC> at cad 0 on hdac0
hdaa0: <Realtek (0x0256) Audio Function Group> at nid 1 on hdacc0
pcm0: <Realtek (0x0256) (Internal Analog)> at nid 20 and 18 on hdaa0
pcm1: <Realtek (0x0256) (Front Analog Headphones)> at nid 33 on hdaa0
hdacc1: <Intel (0x2809) HDA CODEC> at cad 2 on hdac0
hdaa1: <Intel (0x2809) Audio Function Group> at nid 1 on hdacc1
pcm2: <Intel (0x2809) (HDMI/DP 8ch)> at nid 3 on hdaa1
usbus0: 5.0Gbps Super Speed USB v3.0
ugen0.1: <0x8086> at usbus0
uhub0: <0x8086 XHCI root HUB, class 9/0, rev 3.00/1.00, addr 1> on usbus0
ses0 at ahciem0 bus 0 scbus2 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: <SanDisk X300 M.2 2280 128GB X3520012> ACS-2 ATA SATA 3.x device
ada0: Serial Number 154595406259
ada0: 600.000MB/s transfers (SATA 3.x, UDMA6, PIO 512bytes)
ada0: Command Queueing enabled
ada0: 122104MB (250069680 512 byte sectors)
ada1 at ahcich1 bus 0 scbus1 target 0 lun 0
ada1: <ST1000LM024 HN-M101MBB 2BA30004> ATA8-ACS SATA 3.x device
ada1: Serial Number S314JB0GA02416
ada1: 600.000MB/s transfers (SATA 3.x, UDMA6, PIO 8192bytes)
ada1: Command Queueing enabled
ada1: 953869MB (1953525168 512 byte sectors)
SMP: AP CPU #1 Launched!
SMP: AP CPU #5 Launched!
SMP: AP CPU #3 Launched!
SMP: AP CPU #4 Launched!
SMP: AP CPU #2 Launched!
SMP: AP CPU #7 Launched!
SMP: AP CPU #6 Launched!
Timecounter "TSC-low" frequency 1296064060 Hz quality 1000
WARNING: WITNESS option enabled, expect reduced performance.
Trying to mount root from zfs:zroot/ROOT/default []...
Root mount waiting for: usbus0
uhub0: 24 ports with 24 removable, self powered
Root mount waiting for: usbus0
ugen0.2: <CN06307G7248759DE3VFA03> at usbus0
Root mount waiting for: usbus0
usbd_setup_device_desc: getting device descriptor at addr 2 failed, USB_ERR_IOERROR
Root mount waiting for: usbus0
usbd_setup_device_desc: getting device descriptor at addr 2 failed, USB_ERR_IOERROR
Root mount waiting for: usbus0
Root mount waiting for: usbus0
usbd_setup_device_desc: getting device descriptor at addr 2 failed, USB_ERR_IOERROR
Root mount waiting for: usbus0
usbd_setup_device_desc: getting device descriptor at addr 2 failed, USB_ERR_IOERROR
Root mount waiting for: usbus0
Root mount waiting for: usbus0
usbd_setup_device_desc: getting device descriptor at addr 2 failed, USB_ERR_IOERROR
ugen0.3: <Unknown> at usbus0 (disconnected)
uhub_reattach_port: could not allocate new device
Root mount waiting for: usbus0
ugen0.3: <Atmel> at usbus0
hid_get_item: Number of items truncated to 255
re0: link state changed to DOWN
re0: link state changed to UP
uhid0: <Atmel maXTouch Control> on usbus0
hid_get_item: Number of items truncated to 255
uhid1: <Atmel maXTouch Digitizer> on usbus0
hid_get_item: Number of items truncated to 255
hid_get_item: Number of items truncated to 255
hid_get_item: Number of items truncated to 255
-- 
Larry Rosenman                     http://www.lerctr.org/~ler
Phone: +1 214-642-9640                 E-Mail: ler@lerctr.org
US Mail: 7011 W Parmer Ln, Apt 1115, Austin, TX 78729-6961



Want to link to this message? Use this URL: <https://mail-archive.FreeBSD.org/cgi/mid.cgi?20160220122416.GA1026>