lock order reversal on recent (06/30) CURRENT

Till Plewe till at plewe.is.tsukuba.ac.jp
Fri Jul 4 00:54:54 PDT 2003

Is the following a known problem?
(occured while running python2.3 using kse)

lock order reversal
 1st 0xc03c3c40 smp rendezvous (smp rendezvous) @ /usr/src/sys/kern/subr_smp.c:3
 2nd 0xc03c00c0 sched lock (sched lock) @ /usr/src/sys/i386/i386/sys_machdep.c:2
Stack backtrace:
backtrace(c0343555,c03c00c0,c033fd89,c033fd89,c0357a74) at backtrace+0x17
witness_lock(c03c00c0,8,c0357a74,124,ffc00034) at witness_lock+0x697
_mtx_lock_spin_flags(c03c00c0,0,c0357a74,124,f5ec0c54) at _mtx_lock_spin_flags+0
set_user_ldt_rv(c7eed390,f5ec0c78,c01d9bbb,9a,0) at set_user_ldt_rv+0x3d
smp_rendezvous_action(9a,0,c0342f33,139,f5ec0d10) at smp_rendezvous_action+0x57
smp_rendezvous(0,c0312a10,0,c7eed390,c7eb97c0) at smp_rendezvous+0xab
i386_set_ldt(c7eed390,bfbff96c,c0357a74,5f,c8228da8) at i386_set_ldt+0x16d
sysarch(c7eed390,f5ec0d10,c0357cf5,3fd,2) at sysarch+0x64
syscall(2f,2f,2f,11,681518e4) at syscall+0x26e
Xint0x80_syscall() at Xint0x80_syscall+0x1d
--- syscall (165), eip = 0x6826dda3, esp = 0xbfbff958, ebp = 0xbfbff984 ---

===========================output of dmesg -a=======================
Copyright (c) 1992-2003 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 5.1-CURRENT #12: Thu Jul  3 13:46:40 JST 2003
    root at plewe2.is.tsukuba.ac.jp:/usr/obj/usr/src/sys/MYKERNEL
Preloaded elf kernel "/boot/kernel/kernel" at 0xc04e5000.
Preloaded elf module "/boot/kernel/acpi.ko" at 0xc04e5294.
Timecounter "i8254"  frequency 1193182 Hz
Timecounter "TSC"  frequency 2392048864 Hz
CPU: Intel(R) Xeon(TM) CPU 2.40GHz (2392.05-MHz 686-class CPU)
  Origin = "GenuineIntel"  Id = 0xf27  Stepping = 7
  Hyperthreading: 2 logical CPUs
real memory  = 2146893824 (2047 MB)
avail memory = 2084458496 (1987 MB)
Programming 24 pins in IOAPIC #0
IOAPIC #0 intpin 2 -> irq 0
Programming 24 pins in IOAPIC #1
Programming 24 pins in IOAPIC #2
FreeBSD/SMP: Multiprocessor System Detected: 4 CPUs
 cpu0 (BSP): apic id:  0, version: 0x00050014, at 0xfee00000
 cpu1 (AP):  apic id:  1, version: 0x00050014, at 0xfee00000
 cpu2 (AP):  apic id:  6, version: 0x00050014, at 0xfee00000
 cpu3 (AP):  apic id:  7, version: 0x00050014, at 0xfee00000
 io0 (APIC): apic id:  2, version: 0x00178020, at 0xfec00000
 io1 (APIC): apic id:  3, version: 0x00178020, at 0xfec80000
 io2 (APIC): apic id:  4, version: 0x00178020, at 0xfec80100
Pentium Pro MTRR support enabled
npx0: <math processor> on motherboard
npx0: INT 16 interface
acpi0: <PTLTD    RSDT  > on motherboard
pcibios: BIOS version 2.10
acpi0: power button is handled as a fixed feature programming model.
Timecounter "ACPI-fast"  frequency 3579545 Hz
can't fetch resources for \\_SB_.PCI0.LPC0.SIO_.LPT_ - AE_AML_INVALID_RESOURCE_TYPE
acpi_timer0: <24-bit timer at 3.579545MHz> port 0x1008-0x100b on acpi0
acpi_cpu0: <CPU> on acpi0
acpi_cpu1: <CPU> on acpi0
pcib0: <ACPI Host-PCI bridge> port 0xcf8-0xcff on acpi0
pci0: <ACPI PCI bus> on pcib0
IOAPIC #0 intpin 16 -> irq 2
IOAPIC #0 intpin 19 -> irq 5
IOAPIC #0 intpin 18 -> irq 10
IOAPIC #0 intpin 23 -> irq 11
agp0: <Intel Generic host to PCI bridge> mem 0xe0000000-0xefffffff at device 0.0 on pci0
pci0: <unknown> at device 0.1 (no driver attached)
pcib1: <ACPI PCI-PCI bridge> mem 0xd4000000-0xd7ffffff at device 1.0 on pci0
pci1: <ACPI PCI bus> on pcib1
pcib2: <ACPI PCI-PCI bridge> at device 2.0 on pci0
pcib2: could not get PCI interrupt routing table for \\_SB_.PCI0.HLB_ - AE_NOT_FOUND
pci2: <ACPI PCI bus> on pcib2
pci2: <base peripheral, interrupt controller> at device 28.0 (no driver attached)
pcib3: <ACPI PCI-PCI bridge> at device 29.0 on pci2
pci3: <ACPI PCI bus> on pcib3
pci2: <base peripheral, interrupt controller> at device 30.0 (no driver attached)
pcib4: <ACPI PCI-PCI bridge> at device 31.0 on pci2
pci4: <ACPI PCI bus> on pcib4
IOAPIC #2 intpin 0 -> irq 16
IOAPIC #2 intpin 4 -> irq 17
ti0: <Netgear GA620 1000baseT Gigabit Ethernet> mem 0xd0210000-0xd0213fff irq 16 at device 3.0 on pci4
ti0: Ethernet address: 00:a0:cc:73:49:65
bge0: <Broadcom BCM5702X Gigabit Ethernet, ASIC rev. 0x1002> mem 0xd0200000-0xd020ffff irq 17 at device 4.0 on pci4
bge0: Ethernet address: 00:50:45:00:96:f7
miibus0: <MII bus> on bge0
brgphy0: <BCM5703 10/100/1000baseTX PHY> on miibus0
brgphy0:  10baseT, 10baseT-FDX, 100baseTX, 100baseTX-FDX, 1000baseTX, 1000baseTX-FDX, auto
pci0: <serial bus, USB> at device 29.0 (no driver attached)
pci0: <serial bus, USB> at device 29.1 (no driver attached)
pci0: <serial bus, USB> at device 29.2 (no driver attached)
pci0: <serial bus, USB> at device 29.7 (no driver attached)
pcib5: <ACPI PCI-PCI bridge> at device 30.0 on pci0
pci5: <ACPI PCI bus> on pcib5
IOAPIC #0 intpin 21 -> irq 18
IOAPIC #0 intpin 22 -> irq 19
pci5: <serial bus, FireWire> at device 0.0 (no driver attached)
pci5: <display, VGA> at device 1.0 (no driver attached)
isab0: <PCI-ISA bridge> at device 31.0 on pci0
isa0: <ISA bus> on isab0
atapci0: <Intel ICH4 UDMA100 controller> port 0x1460-0x146f,0-0x3,0-0x7,0-0x3,0-0x7 irq 0 at device 31.1 on pci0
ata0: at 0x1f0 irq 14 on atapci0
ata1: at 0x170 irq 15 on atapci0
pci0: <serial bus, SMBus> at device 31.3 (no driver attached)
acpi_button0: <Power Button> on acpi0
fdc0: <Enhanced floppy controller (i82077, NE72065 or clone)> port 0x3f7,0x3f0-0x3f5 irq 6 drq 2 on acpi0
fdc0: FIFO enabled, 8 bytes threshold
fd0: <1440-KB 3.5" drive> on fdc0 drive 0
ppc0 port 0x378-0x37f on acpi0
ppc0: SMC-like chipset (ECP/EPP/PS2/NIBBLE) in COMPATIBLE mode
ppc0: FIFO with 16/16/8 bytes threshold
ppbus0: <Parallel port bus> on ppc0
plip0: <PLIP network interface> on ppbus0
lpt0: <Printer> on ppbus0
lpt0: Polled port
ppi0: <Parallel I/O> on ppbus0
sio0 port 0x2f8-0x2ff irq 3 on acpi0
sio0: type 16550A
sio1 port 0x3f8-0x3ff irq 4 on acpi0
sio1: type 16550A
atkbdc0: <Keyboard controller (i8042)> port 0x64,0x60 irq 1 on acpi0
atkbd0: <AT Keyboard> flags 0x1 irq 1 on atkbdc0
kbd0 at atkbd0
psm0: <PS/2 Mouse> irq 12 on atkbdc0
psm0: model Generic PS/2 mouse, device ID 0
pmtimer0 on isa0
orm0: <Option ROMs> at iomem 0xe0000-0xe3fff,0xc0000-0xc7fff on isa0
sc0: <System console> on isa0
sc0: VGA <16 virtual consoles, flags=0x200>
vga0: <Generic ISA VGA> at port 0x3c0-0x3df iomem 0xa0000-0xbffff on isa0
APIC_IO: Testing 8254 interrupt delivery
APIC_IO: routing 8254 via IOAPIC #0 intpin 2
Timecounters tick every 10.000 msec
acpi_cpu: throttling enabled, 8 steps (100% to 12.5%), currently 100.0%
ad0: 78533MB <IC35L080AVVA07-0> [159560/16/63] at ata0-master UDMA100
acd0: DVD-R <HL-DT-ST DVDRAM GMA-4020B> at ata1-master PIO4
SMP: AP CPU #1 Launched!
SMP: AP CPU #3 Launched!
SMP: AP CPU #2 Launched!
Mounting root from ufs:/dev/ad0s1a
WARNING: / was not properly dismounted
Loading configuration files.
Entropy harvesting:
kernel dumps on /dev/ad0s1b
swapon: adding /dev/ad0s1b as swap device
Starting file system checks:
/dev/ad0s1a: 3402 files, 85647 used, 43272 free (712 frags, 5320 blocks, 0.6% fragmentation)
WARNING: /tmp was not properly dismounted
WARNING: /usr was not properly dismounted
/usr: mount pending error: blocks 0 files 1
/usr: superblock summary recomputed
WARNING: /var was not properly dismounted
Setting hostname: plewe2.is.tsukuba.ac.jp.
	inet netmask 0xffffff00 broadcast
	inet6 fe80::250:45ff:fe00:96f7%bge0 prefixlen 64 tentative scopeid 0x2 
	ether 00:50:45:00:96:f7
	media: Ethernet autoselect (none)
	status: no carrier
lo0: flags=8049<UP,LOOPBACK,RUNNING,MULTICAST> mtu 16384
	inet6 ::1 prefixlen 128 
	inet6 fe80::1%lo0 prefixlen 64 scopeid 0x4 
	inet netmask 0xff000000 
add net default: gateway
Additional routing options:

Mounting NFS file systems:
Starting syslogd.
Jul  4 16:35:44  syslogd: /var/log/debug.log: No such file or directory
Jul  4 16:35:44 plewe2 syslogd: kernel boot file is /boot/kernel/kernel
Checking for core dump...
savecore: no dumps found
Starting rpcbind.
ELF ldconfig path: /usr/lib /usr/lib/compat /usr/X11R6/lib /usr/local/lib
a.out ldconfig path: /usr/lib/aout /usr/lib/compat/aout /usr/X11R6/lib/aout
Starting usbd.
uhci0: <Intel 82801DB (ICH4) USB controller USB-A> port 0x1400-0x141f irq 2 at device 29.0 on pci0
usb0: <Intel 82801DB (ICH4) USB controller USB-A> on uhci0
usb0: USB revision 1.0
uhub0: Intel UHCI root hub, class 9/0, rev 1.00/1.00, addr 1
uhub0: 2 ports with 2 removable, self powered
uhci1: <Intel 82801DB (ICH4) USB controller USB-B> port 0x1420-0x143f irq 5 at device 29.1 on pci0
usb1: <Intel 82801DB (ICH4) USB controller USB-B> on uhci1
usb1: USB revision 1.0
uhub1: Intel UHCI root hub, class 9/0, rev 1.00/1.00, addr 1
uhub1: 2 ports with 2 removable, self powered
uhci2: <Intel 82801DB (ICH4) USB controller USB-C> port 0x1440-0x145f irq 10 at device 29.2 on pci0
usb2: <Intel 82801DB (ICH4) USB controller USB-C> on uhci2
usb2: USB revision 1.0
uhub2: Intel UHCI root hub, class 9/0, rev 1.00/1.00, addr 1
uhub2: 2 ports with 2 removable, self powered
ehci0: <EHCI (generic) USB 2.0 controller> mem 0xd0000000-0xd00003ff irq 11 at device 29.7 on pci0
ehci_pci_attach: companion usb0
ehci_pci_attach: companion usb1
ehci_pci_attach: companion usb2
usb3: EHCI version 1.0
usb3: companion controllers, 2 ports each: usb0 usb1 usb2
usb3: <EHCI (generic) USB 2.0 controller> on ehci0
usb3: USB revision 2.0
uhub3: (0x8086) EHCI root hub, class 9/0, rev 2.00/1.00, addr 1
uhub3: 6 ports with 6 removable, self powered
Starting local daemons:
Updating motd.
Configuring syscons:
Starting sshd.
Starting sendmail.
Initial i386 initialization:
Additional ABI support:
Starting cron.
Local package initialization:
Additional TCP options:
Starting moused:
Starting background file system checks in 60 seconds.

Fri Jul  4 16:36:28 JST 2003
Jul  4 16:38:07 plewe2 login: ROOT LOGIN (root) ON ttyv0
lock order reversal
 1st 0xc03c3c40 smp rendezvous (smp rendezvous) @ /usr/src/sys/kern/subr_smp.c:313
 2nd 0xc03c00c0 sched lock (sched lock) @ /usr/src/sys/i386/i386/sys_machdep.c:292
Stack backtrace:

More information about the freebsd-current mailing list