Bug 122033 - [ral] [lor] Lock order reversal in ral0 at bootup ieee80211com/ral0 [regression]
Summary: [ral] [lor] Lock order reversal in ral0 at bootup ieee80211com/ral0 [regression]
Status: Closed FIXED
Alias: None
Product: Base System
Classification: Unclassified
Component: kern (show other bugs)
Version: 7.0-STABLE
Hardware: Any Any
: Normal Affects Only Me
Assignee: freebsd-bugs (Nobody)
URL:
Keywords:
Depends on:
Blocks:
 
Reported: 2008-03-24 06:10 UTC by Robert Jenssen
Modified: 2019-02-01 23:05 UTC (History)
1 user (show)

See Also:


Attachments

Note You need to log in before you can comment on or make changes to this bug.
Description Robert Jenssen 2008-03-24 06:10:02 UTC
Since upgrading to FreeBSD 7 I have been experiencing some frustrating
problems with my RAL wifi card. In particular, it seems to me that dhclient
fails when the ral device driver times out while scanning for my access
point. At the same time my HP PDA with Spectec WiFi SDIO card has no problems
finding my access point.

Today I made a kernel with the following options:

makeoptions     DEBUG=-g
options         INVARIANTS
options         INVARIANT_SUPPORT
options         WITNESS
options         DEBUG_LOCKS
options         DEBUG_VFS_LOCKS
options         DIAGNOSTIC
options         SOCKBUF_DEBUG
options         DDB
options         KDB

Upon rebooting the dmesg immediately showed a lock order reversal in the ral
driver in ieee80211_scan.c and rt2560.c (see below). 

Mar 23 18:29:49 kraken syslogd: kernel boot file is /boot/kernel/kernel
Mar 23 18:29:49 kraken kernel: Copyright (c) 1992-2008 The FreeBSD Project.
Mar 23 18:29:49 kraken kernel: Copyright (c) 1979, 1980, 1983, 1986, 1988, 
1989, 1991, 1992, 1993, 1994
Mar 23 18:29:49 kraken kernel: The Regents of the University of California. 
All rights reserved.
Mar 23 18:29:49 kraken kernel: FreeBSD is a registered trademark of The 
FreeBSD Foundation.
Mar 23 18:29:49 kraken kernel: FreeBSD 7.0-STABLE #0: Sun Mar 23 17:39:25 EST 
2008
Mar 23 18:29:49 kraken kernel: 
root@kraken.wollstonecraft:/usr/obj/usr/src/sys/KRAKEN_DEBUG
Mar 23 18:29:49 kraken kernel: WARNING: WITNESS option enabled, expect reduced 
performance.
Mar 23 18:29:49 kraken kernel: WARNING: DIAGNOSTIC option enabled, expect 
reduced performance.
Mar 23 18:29:49 kraken kernel: Timecounter "i8254" frequency 1193182 Hz 
quality 0
Mar 23 18:29:49 kraken kernel: CPU: Intel(R) Celeron(R) CPU 2.80GHz 
(2856.49-MHz 686-class CPU)
Mar 23 18:29:49 kraken kernel: Origin = "GenuineIntel"  Id = 0xf29  Stepping = 
9
Mar 23 18:29:49 kraken kernel: 
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>
Mar 23 18:29:49 kraken kernel: Features2=0x4400<CNXT-ID,xTPR>
Mar 23 18:29:49 kraken kernel: real memory  = 1073676288 (1023 MB)
Mar 23 18:29:49 kraken kernel: avail memory = 1040924672 (992 MB)
Mar 23 18:29:49 kraken kernel: ACPI APIC Table: <IntelR AWRDACPI>
Mar 23 18:29:49 kraken kernel: WITNESS: spin lock intrcnt not in order list
Mar 23 18:29:49 kraken kernel: ioapic0 <Version 2.0> irqs 0-23 on motherboard
Mar 23 18:29:49 kraken kernel: kbd1 at kbdmux0
Mar 23 18:29:49 kraken kernel: acpi0: <IntelR AWRDACPI> on motherboard
Mar 23 18:29:49 kraken kernel: acpi0: [ITHREAD]
Mar 23 18:29:49 kraken kernel: acpi0: Power Button (fixed)
Mar 23 18:29:49 kraken kernel: acpi0: reservation of 0, a0000 (3) failed
Mar 23 18:29:49 kraken kernel: acpi0: reservation of 100000, 3fef0000 (3) 
failed
Mar 23 18:29:49 kraken kernel: Timecounter "ACPI-fast" frequency 3579545 Hz 
quality 1000
Mar 23 18:29:49 kraken kernel: acpi_timer0: <24-bit timer at 3.579545MHz> port 
0x408-0x40b on acpi0
Mar 23 18:29:49 kraken kernel: cpu0: <ACPI CPU> on acpi0
Mar 23 18:29:49 kraken kernel: acpi_button0: <Power Button> on acpi0
Mar 23 18:29:49 kraken kernel: pcib0: <ACPI Host-PCI bridge> port 0xcf8-0xcff 
on acpi0
Mar 23 18:29:49 kraken kernel: pci0: <ACPI PCI bus> on pcib0
Mar 23 18:29:49 kraken kernel: agp0: <Intel 82865 host to AGP bridge> on 
hostb0
Mar 23 18:29:49 kraken kernel: pcib1: <PCI-PCI bridge> at device 1.0 on pci0
Mar 23 18:29:49 kraken kernel: pci1: <PCI bus> on pcib1
Mar 23 18:29:49 kraken kernel: vgapci0: <VGA-compatible display> port 
0x9000-0x90ff mem 0xe0000000-0xe7ffffff,0xf1000000-0xf100ffff irq 16 at 
device 0.0 on pci1
Mar 23 18:29:49 kraken kernel: vgapci1: <VGA-compatible display> mem 
0xe8000000-0xefffffff,0xf1010000-0xf101ffff at device 0.1 on pci1
Mar 23 18:29:49 kraken kernel: uhci0: <Intel 82801EB (ICH5) USB controller 
USB-A> port 0xbc00-0xbc1f irq 16 at device 29.0 on pci0
Mar 23 18:29:49 kraken kernel: uhci0: [GIANT-LOCKED]
Mar 23 18:29:49 kraken kernel: uhci0: [ITHREAD]
Mar 23 18:29:49 kraken kernel: usb0: <Intel 82801EB (ICH5) USB controller 
USB-A> on uhci0
Mar 23 18:29:49 kraken kernel: usb0: USB revision 1.0
Mar 23 18:29:49 kraken kernel: uhub0: <Intel UHCI root hub, class 9/0, rev 
1.00/1.00, addr 1> on usb0
Mar 23 18:29:49 kraken kernel: uhub0: 2 ports with 2 removable, self powered
Mar 23 18:29:49 kraken kernel: uhci1: <Intel 82801EB (ICH5) USB controller 
USB-B> port 0xb000-0xb01f irq 19 at device 29.1 on pci0
Mar 23 18:29:49 kraken kernel: uhci1: [GIANT-LOCKED]
Mar 23 18:29:49 kraken kernel: uhci1: [ITHREAD]
Mar 23 18:29:49 kraken kernel: usb1: <Intel 82801EB (ICH5) USB controller 
USB-B> on uhci1
Mar 23 18:29:49 kraken kernel: usb1: USB revision 1.0
Mar 23 18:29:49 kraken kernel: uhub1: <Intel UHCI root hub, class 9/0, rev 
1.00/1.00, addr 1> on usb1
Mar 23 18:29:49 kraken kernel: uhub1: 2 ports with 2 removable, self powered
Mar 23 18:29:49 kraken kernel: uhci2: <Intel 82801EB (ICH5) USB controller 
USB-C> port 0xb400-0xb41f irq 18 at device 29.2 on pci0
Mar 23 18:29:49 kraken kernel: uhci2: [GIANT-LOCKED]
Mar 23 18:29:49 kraken kernel: uhci2: [ITHREAD]
Mar 23 18:29:49 kraken kernel: usb2: <Intel 82801EB (ICH5) USB controller 
USB-C> on uhci2
Mar 23 18:29:49 kraken kernel: usb2: USB revision 1.0
Mar 23 18:29:49 kraken kernel: uhub2: <Intel UHCI root hub, class 9/0, rev 
1.00/1.00, addr 1> on usb2
Mar 23 18:29:49 kraken kernel: uhub2: 2 ports with 2 removable, self powered
Mar 23 18:29:49 kraken kernel: uhci3: <Intel 82801EB (ICH5) USB controller 
USB-D> port 0xb800-0xb81f irq 16 at device 29.3 on pci0
Mar 23 18:29:49 kraken kernel: uhci3: [GIANT-LOCKED]
Mar 23 18:29:49 kraken kernel: uhci3: [ITHREAD]
Mar 23 18:29:49 kraken kernel: usb3: <Intel 82801EB (ICH5) USB controller 
USB-D> on uhci3
Mar 23 18:29:49 kraken kernel: usb3: USB revision 1.0
Mar 23 18:29:49 kraken kernel: uhub3: <Intel UHCI root hub, class 9/0, rev 
1.00/1.00, addr 1> on usb3
Mar 23 18:29:49 kraken kernel: uhub3: 2 ports with 2 removable, self powered
Mar 23 18:29:49 kraken kernel: ehci0: <Intel 82801EB/R (ICH5) USB 2.0 
controller> mem 0xf2200000-0xf22003ff irq 23 at device 29.7 on pci0
Mar 23 18:29:49 kraken kernel: ehci0: [GIANT-LOCKED]
Mar 23 18:29:49 kraken kernel: ehci0: [ITHREAD]
Mar 23 18:29:49 kraken kernel: usb4: EHCI version 1.0
Mar 23 18:29:49 kraken kernel: usb4: companion controllers, 2 ports each: usb0 
usb1 usb2 usb3
Mar 23 18:29:49 kraken kernel: usb4: <Intel 82801EB/R (ICH5) USB 2.0 
controller> on ehci0
Mar 23 18:29:49 kraken kernel: usb4: USB revision 2.0
Mar 23 18:29:49 kraken kernel: uhub4: <Intel EHCI root hub, class 9/0, rev 
2.00/1.00, addr 1> on usb4
Mar 23 18:29:49 kraken kernel: uhub4: 8 ports with 8 removable, self powered
Mar 23 18:29:49 kraken kernel: usb4: handing over low speed device on port 2 
to usb0
Mar 23 18:29:49 kraken kernel: uhub4: port 2, device disappeared after reset
Mar 23 18:29:49 kraken kernel: pcib2: <ACPI PCI-PCI bridge> at device 30.0 on 
pci0
Mar 23 18:29:49 kraken kernel: pci2: <ACPI PCI bus> on pcib2
Mar 23 18:29:49 kraken kernel: fwohci0: <Texas Instruments TSB43AB23> mem 
0xf2007000-0xf20077ff,0xf2000000-0xf2003fff irq 17 at device 1.0 on pci2
Mar 23 18:29:49 kraken kernel: fwohci0: [FILTER]
Mar 23 18:29:49 kraken kernel: fwohci0: OHCI version 1.10 (ROM=1)
Mar 23 18:29:49 kraken kernel: fwohci0: No. of Isochronous channels is 4.
Mar 23 18:29:49 kraken kernel: fwohci0: EUI64 00:50:8d:00:00:e2:cc:9b
Mar 23 18:29:49 kraken kernel: fwohci0: Phy 1394a available S400, 3 ports.
Mar 23 18:29:49 kraken kernel: fwohci0: Link S400, max_rec 2048 bytes.
Mar 23 18:29:49 kraken kernel: firewire0: <IEEE1394(FireWire) bus> on fwohci0
Mar 23 18:29:49 kraken kernel: fwip0: <IP over FireWire> on firewire0
Mar 23 18:29:49 kraken kernel: fwip0: Firewire address: 
00:50:8d:00:00:e2:cc:9b @ 0xfffe00000000, S400, maxrec 2048
Mar 23 18:29:49 kraken kernel: sbp0: <SBP-2/SCSI over FireWire> on firewire0
Mar 23 18:29:49 kraken kernel: dcons_crom0: <dcons configuration ROM> on 
firewire0
Mar 23 18:29:49 kraken kernel: dcons_crom0: bus_addr 0x138c000
Mar 23 18:29:49 kraken kernel: fwe0: <Ethernet over FireWire> on firewire0
Mar 23 18:29:49 kraken kernel: if_fwe0: Fake Ethernet address: 
02:50:8d:e2:cc:9b
Mar 23 18:29:49 kraken kernel: fwe0: Ethernet address: xx:xx:xx:xx:xx:xx
Mar 23 18:29:49 kraken kernel: fwohci0: Initiate bus reset
Mar 23 18:29:49 kraken kernel: fwohci0: BUS reset
Mar 23 18:29:49 kraken kernel: fwohci0: node_id=0xc800ffc0, gen=1, CYCLEMASTER 
mode
Mar 23 18:29:49 kraken kernel: rl0: <RealTek 8139 10/100BaseTX> port 
0xa000-0xa0ff mem 0xf2006000-0xf20060ff irq 18 at device 2.0 on pci2
Mar 23 18:29:49 kraken kernel: miibus0: <MII bus> on rl0
Mar 23 18:29:49 kraken kernel: rlphy0: <RealTek internal media interface> PHY 
0 on miibus0
Mar 23 18:29:49 kraken kernel: rlphy0:  10baseT, 10baseT-FDX, 100baseTX, 
100baseTX-FDX, auto
Mar 23 18:29:49 kraken kernel: rl0: Ethernet address: xx:xx:xx:xx:xx:xx
Mar 23 18:29:49 kraken kernel: rl0: [ITHREAD]
Mar 23 18:29:49 kraken kernel: pci2: <processor> at device 4.0 (no driver 
attached)
Mar 23 18:29:49 kraken kernel: ral0: <Ralink Technology RT2560> mem 
0xf2004000-0xf2005fff irq 22 at device 6.0 on pci2
Mar 23 18:29:49 kraken kernel: ral0: MAC/BBP RT2560 (rev 0x04), RF RT2525
Mar 23 18:29:49 kraken kernel: ral0: Ethernet address: xx:xx:xx:xx:xx:xx
Mar 23 18:29:49 kraken kernel: ral0: [ITHREAD]
Mar 23 18:29:49 kraken kernel: isab0: <PCI-ISA bridge> at device 31.0 on pci0
Mar 23 18:29:49 kraken kernel: isa0: <ISA bus> on isab0
Mar 23 18:29:49 kraken kernel: atapci0: <Intel ICH5 UDMA100 controller> port 
0x1f0-0x1f7,0x3f6,0x170-0x177,0x376,0xf000-0xf00f at device 31.1 on pci0
Mar 23 18:29:49 kraken kernel: ata0: <ATA channel 0> on atapci0
Mar 23 18:29:49 kraken kernel: ata0: [ITHREAD]
Mar 23 18:29:49 kraken kernel: ata1: <ATA channel 1> on atapci0
Mar 23 18:29:49 kraken kernel: ata1: [ITHREAD]
Mar 23 18:29:49 kraken kernel: atapci1: <Intel ICH5 SATA150 controller> port 
0xc000-0xc007,0xc400-0xc403,0xc800-0xc807,0xcc00-0xcc03,0xd000-0xd00f irq 18 
at device 31.2 on pci0
Mar 23 18:29:49 kraken kernel: atapci1: [ITHREAD]
Mar 23 18:29:49 kraken kernel: ata2: <ATA channel 0> on atapci1
Mar 23 18:29:49 kraken kernel: ata2: [ITHREAD]
Mar 23 18:29:49 kraken kernel: ata3: <ATA channel 1> on atapci1
Mar 23 18:29:49 kraken kernel: ata3: [ITHREAD]
Mar 23 18:29:49 kraken kernel: pci0: <serial bus, SMBus> at device 31.3 (no 
driver attached)
Mar 23 18:29:49 kraken kernel: pcm0: <Intel ICH5 (82801EB)> port 
0xd800-0xd8ff,0xdc00-0xdc3f mem 0xf2201000-0xf22011ff,0xf2202000-0xf22020ff 
irq 17 at device 31.5 on pci0
Mar 23 18:29:49 kraken kernel: pcm0: [ITHREAD]
Mar 23 18:29:49 kraken kernel: pcm0: <Avance Logic ALC658 AC97 Codec>
Mar 23 18:29:49 kraken kernel: acpi_tz0: <Thermal Zone> on acpi0
Mar 23 18:29:49 kraken kernel: fdc0: <floppy drive controller> port 
0x3f0-0x3f5,0x3f7 irq 6 drq 2 on acpi0
Mar 23 18:29:49 kraken kernel: fdc0: [FILTER]
Mar 23 18:29:49 kraken kernel: fd0: <1440-KB 3.5" drive> on fdc0 drive 0
Mar 23 18:29:49 kraken kernel: sio0: <16550A-compatible COM port> port 
0x3f8-0x3ff irq 4 flags 0x10 on acpi0
Mar 23 18:29:49 kraken kernel: sio0: type 16550A
Mar 23 18:29:49 kraken kernel: sio0: [FILTER]
Mar 23 18:29:49 kraken kernel: atkbdc0: <Keyboard controller (i8042)> port 
0x60,0x64 irq 1 on acpi0
Mar 23 18:29:49 kraken kernel: atkbd0: <AT Keyboard> irq 1 on atkbdc0
Mar 23 18:29:49 kraken kernel: kbd0 at atkbd0
Mar 23 18:29:49 kraken kernel: atkbd0: [GIANT-LOCKED]
Mar 23 18:29:49 kraken kernel: atkbd0: [ITHREAD]
Mar 23 18:29:49 kraken kernel: pmtimer0 on isa0
Mar 23 18:29:49 kraken kernel: orm0: <ISA Option ROM> at iomem 0xc0000-0xccfff 
pnpid ORM0000 on isa0
Mar 23 18:29:49 kraken kernel: ppc0: <Parallel port> at port 0x378-0x37f irq 7 
on isa0
Mar 23 18:29:49 kraken kernel: ppc0: Generic chipset (NIBBLE-only) in 
COMPATIBLE mode
Mar 23 18:29:49 kraken kernel: ppbus0: <Parallel port bus> on ppc0
Mar 23 18:29:49 kraken kernel: ppbus0: [ITHREAD]
Mar 23 18:29:49 kraken kernel: plip0: <PLIP network interface> on ppbus0
Mar 23 18:29:49 kraken kernel: lpt0: <Printer> on ppbus0
Mar 23 18:29:49 kraken kernel: lpt0: Interrupt-driven port
Mar 23 18:29:49 kraken kernel: ppi0: <Parallel I/O> on ppbus0
Mar 23 18:29:49 kraken kernel: ppc0: [GIANT-LOCKED]
Mar 23 18:29:49 kraken kernel: ppc0: [ITHREAD]
Mar 23 18:29:49 kraken kernel: sc0: <System console> at flags 0x100 on isa0
Mar 23 18:29:49 kraken kernel: sc0: VGA <16 virtual consoles, flags=0x300>
Mar 23 18:29:49 kraken kernel: sio1: configured irq 3 not in bitmap of probed 
irqs 0
Mar 23 18:29:49 kraken kernel: sio1: port may not be enabled
Mar 23 18:29:49 kraken kernel: vga0: <Generic ISA VGA> at port 0x3c0-0x3df 
iomem 0xa0000-0xbffff on isa0
Mar 23 18:29:49 kraken kernel: ums0: <Microsoft Microsoft Basic Optical Mouse, 
class 0/0, rev 1.10/3.90, addr 2> on uhub0
Mar 23 18:29:49 kraken kernel: ums0: 3 buttons and Z dir.
Mar 23 18:29:49 kraken kernel: Timecounter "TSC" frequency 2856488788 Hz 
quality 800
Mar 23 18:29:49 kraken kernel: Timecounters tick every 1.000 msec
Mar 23 18:29:49 kraken kernel: ipfw2 (+ipv6) initialized, divert enabled, nat 
loadable, rule-based forwarding firewire0: 1 nodes, maxhop <= 0, cable IRM = 
0 (me)
Mar 23 18:29:49 kraken kernel: firewire0: bus manager 0 (me)
Mar 23 18:29:49 kraken kernel: disabled, default to accept, logging limited to 
5 packets/entry by default
Mar 23 18:29:49 kraken kernel: acd0: DVDR <SONY DVD RW DW-U18A/UYS1> at 
ata0-master UDMA33
Mar 23 18:29:49 kraken kernel: acd0: FAILURE - INQUIRY ILLEGAL REQUEST 
asc=0x24 ascq=0x00
Mar 23 18:29:49 kraken kernel: acd1: CDROM <ATAPI CD-ROM DRIVE 36X 
MAXIMUM/36GP> at ata1-master UDMA33
Mar 23 18:29:49 kraken kernel: ad4: 152627MB <WDC WD1600AAJS-00PSA0 05.06H05> 
at ata2-master SATA150
Mar 23 18:29:49 kraken kernel: ad6: 76319MB <Seagate ST380013AS 3.05> at 
ata3-master SATA150
Mar 23 18:29:49 kraken kernel: acd0: FAILURE - INQUIRY ILLEGAL REQUEST 
asc=0x24 ascq=0x00
Mar 23 18:29:49 kraken kernel: cd0 at ata0 bus 0 target 0 lun 0
Mar 23 18:29:49 kraken kernel: cd0: <SONY DVD RW DW-U18A UYS1> Removable 
CD-ROM SCSI-0 device
Mar 23 18:29:49 kraken kernel: cd0: 33.000MB/s transfers
Mar 23 18:29:49 kraken kernel: cd0: Attempt to query device size failed: NOT 
READY, Medium not present
Mar 23 18:29:49 kraken kernel: cd1 at ata1 bus 0 target 0 lun 0
Mar 23 18:29:49 kraken kernel: cd1: <ATAPI CD-ROM DRIVE-36X 36GP> Removable 
CD-ROM SCSI-0 device
Mar 23 18:29:49 kraken kernel: cd1: 33.000MB/s transfers
Mar 23 18:29:49 kraken kernel: cd1: Attempt to query device size failed: NOT 
READY, Medium not present
Mar 23 18:29:49 kraken kernel: hwpmc: TSC/1/0x20<REA> 
P4/18/0xfff<INT,USR,SYS,EDG,THR,REA,WRI,INV,QUA,PRC,TAG,CSC>
Mar 23 18:29:49 kraken kernel: WARNING: WITNESS option enabled, expect reduced 
performance.
Mar 23 18:29:49 kraken kernel: WARNING: DIAGNOSTIC option enabled, expect 
reduced performance.
Mar 23 18:29:49 kraken kernel: Trying to mount root from ufs:/dev/ad4s2a
Mar 23 18:29:49 kraken savecore: no dumps found
Mar 23 18:30:05 kraken kernel: ipfw: limit 10 reached on entry 3240
Mar 23 18:30:36 kraken kernel: ipfw: limit 10 reached on entry 1300
Mar 23 18:30:36 kraken nmbd[879]: [2008/03/23 18:30:36, 0] 
nmbd/nmbd_become_dmb.c:become_domain_master_browser_wins(335)
Mar 23 18:30:36 kraken nmbd[879]:   become_domain_master_browser_wins:
Mar 23 18:30:36 kraken nmbd[879]:   Attempting to become domain master browser 
on workgroup WORKGROUP, subnet UNICAST_SUBNET.
Mar 23 18:30:36 kraken nmbd[879]: [2008/03/23 18:30:36, 0] 
nmbd/nmbd_become_dmb.c:become_domain_master_browser_wins(349)
Mar 23 18:30:36 kraken nmbd[879]:   become_domain_master_browser_wins: 
querying WINS server from IP 192.168.0.4 for domain master browser name 
WORKGROUP<1b> on workgroup WORKGROUP
Mar 23 18:30:36 kraken nmbd[879]: [2008/03/23 18:30:36, 0] 
nmbd/nmbd_become_dmb.c:become_domain_master_stage2(113)
Mar 23 18:30:36 kraken nmbd[879]:   *****
Mar 23 18:30:36 kraken nmbd[879]:
Mar 23 18:30:36 kraken nmbd[879]:   Samba server KRAKEN is now a domain master 
browser for workgroup WORKGROUP on subnet UNICAST_SUBNET
Mar 23 18:30:36 kraken nmbd[879]:
Mar 23 18:30:36 kraken nmbd[879]:   *****
Mar 23 18:30:36 kraken nmbd[879]: [2008/03/23 18:30:36, 0] 
nmbd/nmbd_become_dmb.c:become_domain_master_browser_bcast(290)
Mar 23 18:30:36 kraken nmbd[879]:   become_domain_master_browser_bcast:
Mar 23 18:30:36 kraken nmbd[879]:   Attempting to become domain master browser 
on workgroup WORKGROUP on subnet 192.168.0.4
Mar 23 18:30:36 kraken nmbd[879]: [2008/03/23 18:30:36, 0] 
nmbd/nmbd_become_dmb.c:become_domain_master_browser_bcast(303)
Mar 23 18:30:36 kraken nmbd[879]:   become_domain_master_browser_bcast: 
querying subnet 192.168.0.4 for domain master browser on workgroup WORKGROUP
Mar 23 18:30:44 kraken nmbd[879]: [2008/03/23 18:30:44, 0] 
nmbd/nmbd_become_dmb.c:become_domain_master_stage2(113)
Mar 23 18:30:44 kraken nmbd[879]:   *****
Mar 23 18:30:44 kraken nmbd[879]:
Mar 23 18:30:44 kraken nmbd[879]:   Samba server KRAKEN is now a domain master 
browser for workgroup WORKGROUP on subnet 192.168.0.4
Mar 23 18:30:44 kraken nmbd[879]:
Mar 23 18:30:44 kraken nmbd[879]:   *****
Mar 23 18:30:59 kraken nmbd[879]: [2008/03/23 18:30:59, 0] 
nmbd/nmbd_become_lmb.c:become_local_master_stage2(396)
Mar 23 18:30:59 kraken nmbd[879]:   *****
Mar 23 18:30:59 kraken nmbd[879]:
Mar 23 18:30:59 kraken nmbd[879]:   Samba name server KRAKEN is now a local 
master browser for workgroup WORKGROUP on subnet 192.168.0.4
Mar 23 18:30:59 kraken nmbd[879]:
Mar 23 18:30:59 kraken nmbd[879]:   *****
Mar 23 18:31:37 kraken winbindd[888]: [2008/03/23 18:31:37, 0] 
nsswitch/winbindd_cache.c:initialize_winbindd_cache(2223)
Mar 23 18:31:37 kraken winbindd[888]:   initialize_winbindd_cache: clearing 
cache and re-creating with version number 1
Mar 23 18:31:37 kraken lpd[908]: lpd startup: logging=0
Mar 23 18:31:37 kraken ntpd: logging to file /var/log/ntpd.log
Mar 23 18:31:50 kraken kernel: rl0: link state changed to DOWN
Mar 23 18:31:56 kraken kernel: Expensive timeout(9) function: 0xc05302a7
(0xc0988260) 0.004545697 s
Mar 23 18:33:07 kraken kernel: lock order reversal:
Mar 23 18:33:07 kraken kernel: 1st 0xc3cd900c ieee80211com (802.11 com lock) 
@ /usr/src/sys/net80211/ieee80211_scan.c:524
Mar 23 18:33:07 kraken kernel: 2nd 0xc3cda41c ral0 (network driver) 
@ /usr/src/sys/dev/ral/rt2560.c:2006
Mar 23 18:33:07 kraken kernel: KDB: stack backtrace:
Mar 23 18:33:07 kraken kernel: 
db_trace_self_wrapper(c08867d1,e4119914,c060505a,c0888c56,c3cda41c,...) at 
db_trace_self_wrapper+0x26
Mar 23 18:33:07 kraken kernel: 
kdb_backtrace(c0888c56,c3cda41c,c3cd80c0,c086c50c,c08707e8,...) at 
kdb_backtrace+0x29
Mar 23 18:33:07 kraken kernel: 
witness_checkorder(c3cda41c,9,c08707e8,7d6,c0604871,...) at 
witness_checkorder+0x6b7
Mar 23 18:33:07 kraken kernel: 
_mtx_lock_flags(c3cda41c,0,c08707e8,7d6,c0894568,...) at _mtx_lock_flags+0xb0
Mar 23 18:33:07 kraken kernel: 
rt2560_start(c3cd7c00,c3cd9004,e4119a10,c068dfb2,c3cd7c00,...) at 
rt2560_start+0x3e
Mar 23 18:33:07 kraken kernel: if_start(c3cd7c00,0,c0894568,184,e41199f4,...) 
at if_start+0x4f
Mar 23 18:33:07 kraken kernel: 
ieee80211_send_nulldata(c40e7000,38,c0888342,6ce,c3cd900c) at 
ieee80211_send_nulldata+0x1ea
Mar 23 18:33:07 kraken kernel: 
ieee80211_sta_pwrsave(c3cd9004,1,20c,6400,c3bfe000,...) at 
ieee80211_sta_pwrsave+0x1fe
Mar 23 18:33:07 kraken kernel: 
scan_restart(c3bfe000,c3cd9004,c0895099,20c,2,...) at scan_restart+0x89
Mar 23 18:33:07 kraken kernel: 
ieee80211_bg_scan(c3cd9004,c3d10818,8,1,c092b858,...) at 
ieee80211_bg_scan+0x113
Mar 23 18:33:07 kraken kernel: 
ieee80211_recv_mgmt(c3cd9004,c3ce3400,c40e7000,80,32,...) at 
ieee80211_recv_mgmt+0xd9c
Mar 23 18:33:07 kraken kernel: 
ieee80211_input(c3cd9004,c3ce3400,c40e7000,32,ffffffa1,...) at 
ieee80211_input+0x141d
Mar 23 18:33:07 kraken kernel: 
rt2560_intr(c3cd9000,0,c088062e,471,c3af32e4,...) at rt2560_intr+0x824
Mar 23 18:33:07 kraken kernel: 
ithread_loop(c3bf0940,e4119d38,c08803bd,305,c3bdaab0,...) at 
ithread_loop+0x1a4
Mar 23 18:33:07 kraken kernel: fork_exit(c05b4780,c3bf0940,e4119d38) at 
fork_exit+0xb8
Mar 23 18:33:07 kraken kernel: fork_trampoline() at fork_trampoline+0x8
Mar 23 18:33:07 kraken kernel: --- trap 0, eip = 0, esp = 0xe4119d70, ebp = 
0 ---
Mar 23 18:34:38 kraken kernel: Expensive timeout(9) function: 0xc05302a7
(0xc0988260) 0.009589366 s
Mar 23 18:34:43 kraken login: ROOT LOGIN (root) ON ttyv0

How-To-Repeat: Rebooting a debug kernel almost always repeats the problem. The problem appears to occur very rarely when I simply do "/etc/rc.d/netif restart".
Comment 1 Remko Lodder freebsd_committer freebsd_triage 2008-03-24 11:01:07 UTC
Responsible Changed
From-To: freebsd-bugs->freebsd-net

Send to -net team, this is a nic.
Comment 2 Eitan Adler freebsd_committer freebsd_triage 2017-12-31 07:58:57 UTC
For bugs matching the following criteria:

Status: In Progress Changed: (is less than) 2014-06-01

Reset to default assignee and clear in-progress tags.

Mail being skipped
Comment 3 Andriy Voskoboinyk freebsd_committer freebsd_triage 2019-02-01 23:05:46 UTC
There were few places for com <-> rt2560 LORs. In most cases, the com lock was obtained before the driver lock; however, there were multiple ieee80211_cancel_scan() calls (from rt2560_start, rt2560_newstate), where the driver lock was taken before the call - and that results in LOR because ieee80211_cancel_scan() takes com lock inside.

The issue was fixed in base r178354 - cancel_scan() calls from rt2560_start were dropped and rt2560_newstate is now called under com lock (and never called directly from the driver code).