Subject: traceback for lockdebug glitch
To: None <tech-smp@netbsd.org>
From: Sean Doran <smd@ebone.net>
List: tech-smp
Date: 03/07/2001 11:18:16
I finally got around to going to sleep doing a couple big builds
(XFree86 CVS, for example) leaving myself on the (text vty) console
rather than in X itself, so I could get the results of the
traceback below.
Sources were synced (to trunk and branch) on the date the kerel
was made (see below).
Sean.
- --
Stopped in pid 4 (ioflush) at cpu_Debugger+0x4: movl
%ebp,%esp
db{0}> t
cpu_debugger(c0300cb4,c0fbaa00,b0,c01edd99,c0300c80) at cpu_Debugger+0x4
_simple_lock(c0300cc4,c025ef60,d61,c0148f9,c1269000,4,c029cf1c,ea60,c02e8c80,b0,4000,c01d3492,c0300cc4,c0301fa0,d8307650,c01e8bb8,c0301680,c1269000,13e11163,d830764c,c028e000,c1269000,c02c9f1c,a,c8ff2000,1,d8307690,c01d3885,c1269000,4000,3d8,c0148c1d,c02c9e60,70,4000,c02463a9,c02c9e60,70,c02c9eb0,4000,c02e8c00,0,d83076c0,c01d27c8,c02c9e60,c1269000,c126d000,d83076bc,c1269000,70,4000,c02ff444,0,0,d83076e0,c01d0ff0,c02c9e60,c1269000,c126d000,c14254f0,c103ffd8) at _simple_lock+0xd3
pmap_tlb_shootdown(c0301680,c1269000,13e1163,d830764c) at pmap_tlb_shootdown+0xac
pmap_kremove(c1269000,4000,3d8,c0148c1d,c02c9e60) at pmap_kremove+0x9c
uvm_unmap_remove(c02c9e60,c1269000,c126d000,d83076bc,c1269000) at uvm_unmap_remove+0x185
uvm_unmap(c2c9e60,c1269000,c126d000,c14254f0,c103ffd8) at uvm_unmap+0x104
uvm_km_free(c02c9e60,c1269000,4000,c0148f9c,ca545218) at uvm_km_free+0x2c
free(c1269000,68,c132a2c0,c01bdaa7,cac9f00) at free+0xc7
softdep_disk_write_complete(ca545218,c103fd4c,c0260580,a4) at softdep_write_complete+0x221
biodone(ca545218,1,d83077c0,c010ee4d,d7aaaf1c) at biodone+0x56
scsipi_done(c103fd4c,2,d83077f0,c0110702,c0fd1c00) at scsipi_done+0x16c
adw_isr_callback(c0fd1c00,d7aaaf1c,c0101f80,d8307834,c0fd1c00) at adw_isr_callback+0x417
AdwISR(c0fd1c00,c02f3080,400042,0) at AdwISR+0x1aa
adw_intr(c0fd1c00) at adw_intr+0x13
Xintrioapic2() at Xintrioapic2+0x5c
--- interrupt ---
pmap_test_attrs(c062f558,40,1ed,246)
uvn_flush(d961dd64,0,0,fffff000,7fffffff,11,156,0) at uvn_Flush+0x3cc
ffs_full_fsync(d8307e60,0,d8307df4,c017dca6,0) at ffs_full_sync+0x7e
ffs_fsync(d8307e60,10012,c02c39d8,c024ba40,0) at ffs_fsync+0x40
VOP_FSYNC(d961dd64,c0fbdf00,0,0,0) at VOP_FSYNC+0x59
ffs_sync(c1143e00,3,c0fbdf00,d82f7558) at ffs_sync+0x155
sync_fsync(d8307f30,6,d97797ec,c024c5a0,0) at sync_fsync+0x79
VOP_FSYNC(d95a167c,c0fbdf00,8,0,0,0,0,d82f7558) at VOP_FSYNC+0x59
sched_sync(d82f7558) at sched_sync+0xfe
- --
NetBSD 1.5S (SCREAM) #0: Thu Mar 1 04:06:08 CET 2001
smd@crasse.smd.ebone.net:/usr/src/sys/arch/i386/compile/SCREAM
total memory = 511 MB
avail memory = 465 MB
using 6573 buffers containing 26292 KB of memory
BIOS32 rev. 0 found at 0xfb410
PCI BIOS rev. 2.1 found at 0xfb440
pcibios: config mechanism [1][x], special cycles [1][x], last bus 1
PCI IRQ Routing Table rev. 1.0 found at 0xfdef0, size 144 bytes (7 entries)
PCI Interrupt Router at 000:07:0 (Intel 82371SB PCI-to-ISA Bridge (PIIX3))
PCI Exclusive IRQs: 5 10 14 15
------------------------------------------
device vendor product pin PIRQ IRQ stage
------------------------------------------
000:07:2 0x8086 0x7112 D 0x03 5 0 fixed up
000:15:0 0x1274 0x1371 A 0x00 15 0 WARNING: ignored
000:16:0 0x1077 0x1020 A 0x01 10 0 already assigned
000:18:0 0x10cd 0x2500 A 0x02 14 0 WARNING: ignored
000:19:0 0x9004 0x8178 A 0x03 5 0 already assigned
------------------------------------------
PCI bridge 0: primary 0, secondary 1, subordinate 1
PCI bus #1 is the last bus
[System BIOS Setting]-----------------------
device vendor product
register space address size
--------------------------------------------
000:00:0 0x8086 0x7190
10h mem 0xd0000000 0x10000000
[OK]
000:01:0 0x8086 0x7191
[OK]
000:07:0 0x8086 0x7110
[OK]
000:07:1 0x8086 0x7111
20h port 0x0000f000 0x00000010
[OK]
000:07:2 0x8086 0x7112
20h port 0x0000d000 0x00000020
[OK]
000:07:3 0x8086 0x7113
[OK]
000:15:0 0x1274 0x1371
10h port 0x0000d400 0x00000040
[OK]
000:16:0 0x1077 0x1020
10h port 0x0000d800 0x00000100
14h mem 0xed000000 0x00001000
[OK]
000:18:0 0x10cd 0x2500
10h port 0x0000dc00 0x00000100
14h mem 0xed002000 0x00000100
[OK]
000:19:0 0x9004 0x8178
10h port 0x0000e000 0x00000100
14h mem 0xed001000 0x00001000
[OK]
001:00:0 0x102b 0x0525
10h mem 0xe8000000 0x02000000
14h mem 0xe4000000 0x00004000
18h mem 0xe5000000 0x00800000
[OK]
--------------------------[ 0 devices bogus]
Physical memory end: 0x1ffe9000
PCI memory mapped I/O space start: 0x20000000
mainbus0 (root)
mainbus0: scanning 0x9fc00 to 0x9fff0 for MP signature
mainbus0: scanning 0x9f800 to 0x9fbf0 for MP signature
mainbus0: scanning 0xf0000 to 0xffff0 for MP signature
mainbus0: MP floating pointer found in bios at 0xf5990
mainbus0: MP config table at 0xf1400, 308 bytes long
mainbus0: Intel MP Specification (Version 1.1) (OEM00000 PROD00000000)
cpu0 at mainbus0: apid 0 (boot processor)
cpu0: Intel Pentium III (Coppermine) Pentium III (686-class), 651.52 MHz
cpu0: features 383fbff<FPU,VME,DE,PSE,TSC,MSR,PAE,MCE,CX8,APIC,SEP,MTRR>
cpu0: features 383fbff<PGE,MCA,CMOV,FGPAT,PSE36,MMX,FXSR,XMM>
cpu0: I-cache 16K 32b/line 4-way, D-cache 16K 32b/line 2/4-way
cpu0: L2 cache 256K 32b/line 8-way
cpu0: ITLB 4K: 32 entries 4-way, 4M: 2 entries
cpu0: DTLB 4K: 64 entries 4-way, 4M: 8 entries 4-way
cpu0: calibrating local timer
cpu0: apic clock running at 100 MHz
cpu0: kstack at 0xd7a7d000 for 8192 bytes
cpu0: idle pcb at 0xd7a7d000, idle sp at 0xd7a7efa0
cpu1 at mainbus0: apid 1 (application processor)
cpu1: starting
cpu1: Intel Pentium III (Coppermine) Pentium III (686-class), 651.48 MHz
cpu1: features 383fbff<FPU,VME,DE,PSE,TSC,MSR,PAE,MCE,CX8,APIC,SEP,MTRR>
cpu1: features 383fbff<PGE,MCA,CMOV,FGPAT,PSE36,MMX,FXSR,XMM>
cpu1: I-cache 16K 32b/line 4-way, D-cache 16K 32b/line 2/4-way
cpu1: L2 cache 256K 32b/line 8-way
cpu1: ITLB 4K: 32 entries 4-way, 4M: 2 entries
cpu1: DTLB 4K: 64 entries 4-way, 4M: 8 entries 4-way
cpu1: kstack at 0xd7a7f000 for 8192 bytes
cpu1: idle pcb at 0xd7a7f000, idle sp at 0xd7a80fa0
ioapic0 at mainbus0 apid 2 (I/O APIC)
ioapic0: pa 0xfec00000, virtual wire mode, version 11, 24 pins
ioapic0: int0 attached to ExtINT (type 3<type=3=ExtINT> flags 0<pol=0,trig=0>)
ioapic0: int1 attached to isa0 irq 1 (type 0<type=0> flags 0<pol=0,trig=0>)
ioapic0: int2 attached to isa0 irq 0 (type 0<type=0> flags 0<pol=0,trig=0>)
ioapic0: int3 attached to isa0 irq 3 (type 0<type=0> flags 0<pol=0,trig=0>)
ioapic0: int4 attached to isa0 irq 4 (type 0<type=0> flags 0<pol=0,trig=0>)
ioapic0: int5 attached to isa0 irq 5 (type 0<type=0> flags 0<pol=0,trig=0>)
ioapic0: int6 attached to isa0 irq 6 (type 0<type=0> flags 0<pol=0,trig=0>)
ioapic0: int7 attached to isa0 irq 7 (type 0<type=0> flags 0<pol=0,trig=0>)
ioapic0: int8 attached to isa0 irq 8 (type 0<type=0> flags 5<pol=1=Act Hi,trig=1=Edge>)
ioapic0: int9 attached to isa0 irq 9 (type 0<type=0> flags 0<pol=0,trig=0>)
ioapic0: int10 attached to isa0 irq 10 (type 0<type=0> flags 0<pol=0,trig=0>)
ioapic0: int11 attached to isa0 irq 11 (type 0<type=0> flags 0<pol=0,trig=0>)
ioapic0: int12 attached to isa0 irq 12 (type 0<type=0> flags 0<pol=0,trig=0>)
ioapic0: int13 attached to isa0 irq 13 (type 0<type=0> flags 0<pol=0,trig=0>)
ioapic0: int14 attached to isa0 irq 14 (type 0<type=0> flags 0<pol=0,trig=0>)
ioapic0: int15 attached to isa0 irq 15 (type 0<type=0> flags 0<pol=0,trig=0>)
ioapic0: int16 attached to pci0 device 15 INT_A (type 0<type=0> flags f<pol=3=Act Lo,trig=3=Level>)
ioapic0: int17 attached to pci0 device 16 INT_A (type 0<type=0> flags f<pol=3=Act Lo,trig=3=Level>)
ioapic0: int18 attached to pci0 device 18 INT_A (type 0<type=0> flags f<pol=3=Act Lo,trig=3=Level>)
ioapic0: int19 attached to pci0 device 19 INT_A (type 0<type=0> flags f<pol=3=Act Lo,trig=3=Level>)
ioapic0: int16 attached to pci1 device 0 INT_A (type 0<type=0> flags f<pol=3=Act Lo,trig=3=Level>)
ioapic0: int23 attached to SMI (type 2<type=2=SMI> flags 0<pol=0,trig=0>)
local apic: int0 attached to ExtINT (type 3<type=3=ExtINT> flags 0<pol=0,trig=0>)
local apic: int1 attached to NMI (type 1<type=1=NMI> flags 0<pol=0,trig=0>)
pci0 at mainbus0 bus 0: configuration mode 1
pci0: i/o space, memory space enabled
pchb0 at pci0 dev 0 function 0
pchb0: Intel 82443BX Host Bridge/Controller (rev. 0x03)
ppb0 at pci0 dev 1 function 0: Intel 82443BX AGP Interface (rev. 0x03)
pci1 at ppb0 bus 1
pci1: i/o space, memory space enabled
vga1 at pci1 dev 0 function 0: Matrox MGA G400 AGP (rev. 0x04)
wsdisplay0 at vga1: console (80x25, vt100 emulation)
pcib0 at pci0 dev 7 function 0
pcib0: Intel 82371AB PCI-to-ISA Bridge (PIIX4) (rev. 0x02)
Intel 82371AB IDE controller (PIIX4) (IDE mass storage, interface 0x80, revision 0x01) at pci0 dev 7 function 1 not configured
Intel 82371AB USB Host Controller (PIIX4) (USB serial bus, revision 0x01) at pci0 dev 7 function 2 not configured
Intel 82371AB Power Management Controller (PIIX4) (miscellaneous bridge, revision 0x02) at pci0 dev 7 function 3 not configured
eap0 at pci0 dev 15 function 0: Ensoniq AudioPCI 97 ES1373B (rev. 0x06)
eap0: interrupting at apic 2 int 16 (irq 15)
eap0: Crystal CS4297 codec; headphone, 18 bit DAC, 18 bit ADC, no 3D stereo
audio0 at eap0: full duplex, mmap, independent
midi0 at eap0: AudioPCI MIDI UART
isp0 at pci0 dev 16 function 0
isp0: interrupting at apic 2 int 17 (irq 10)
scsibus0 at isp0: 16 targets, 8 luns per target
adw0 at pci0 dev 18 function 0: AdvanSys ASB-3940U2W-00 SCSI adapter
adw0: interrupting at apic 2 int 18 (irq 14)
scsibus1 at adw0: 16 targets, 8 luns per target
ahc0 at pci0 dev 19 function 0
ahc0: interrupting at apic 2 int 19 (irq 5)
ahc0: aic7880 Wide Channel A, SCSI Id=7, 16/255 SCBs
scsibus2 at ahc0 channel 0: 16 targets, 8 luns per target
isa0 at pcib0
ep0 at isa0 port 0x300-0x30f irq 11: 3Com 3C509 Ethernet
ep0: address 00:20:af:4f:32:13, 8KB byte-wide FIFO, 5:3 Rx:Tx split
ep0: 10baseT, 10base5, 10base2 (default 10base2)
com0 at isa0 port 0x3f8-0x3ff irq 4: ns16550a, working fifo
com1 at isa0 port 0x2f8-0x2ff irq 3: ns16550a, working fifo
pckbc0 at isa0 port 0x60-0x64
pckbd0 at pckbc0 (kbd slot)
pckbc0: using irq 1 for kbd slot
wskbd0 at pckbd0: console keyboard, using wsdisplay0
pmsi0 at pckbc0 (aux slot)
pckbc0: using irq 12 for aux slot
wsmouse0 at pmsi0 mux 0
lm0 at isa0 port 0x290-0x297: LM78J
lpt0 at isa0 port 0x378-0x37b irq 7
pcppi0 at isa0 port 0x61
sysbeep0 at pcppi0
npx0 at isa0 port 0xf0-0xff: using exception 16
fdc0 at isa0 port 0x3f0-0x3f7 irq 6 drq 2
fd0 at fdc0 drive 0: 1.44MB, 80 cyl, 2 head, 18 sec
biomask 0 netmask 0 ttymask 0
cpu0: prelint0 700<vector=0,delmode=7,dest=0> 0<target=0>
cpu0: prelint1 400<vector=0,delmode=4,dest=0> 0<target=0>
cpu0: timer0 300d0<vector=d0,delmode=0,masked,dest=0> 0<target=0>
cpu0: pcint0 10000<vector=0,delmode=0,masked,dest=0> 0<target=0>
cpu0: lint0 10700<vector=0,delmode=7,masked,dest=0> 0<target=0>
cpu0: lint1 400<vector=0,delmode=4,dest=0> 0<target=0>
cpu0: err0 10000<vector=0,delmode=0,masked,dest=0> 0<target=0>
ioapic0: enabling
ioapic0: int0 10700<vector=0,delmode=7,masked,dest=0> 0<target=0>
ioapic0: int1 1a0<vector=a0,delmode=1,dest=0> 0<target=0>
ioapic0: int2 10100<vector=0,delmode=1,masked,dest=0> 0<target=0>
ioapic0: int3 1e1<vector=e1,delmode=1,dest=0> 0<target=0>
ioapic0: int4 1e2<vector=e2,delmode=1,dest=0> 0<target=0>
ioapic0: int5 10100<vector=0,delmode=1,masked,dest=0> 0<target=0>
ioapic0: int6 170<vector=70,delmode=1,dest=0> 0<target=0>
ioapic0: int7 1a1<vector=a1,delmode=1,dest=0> 0<target=0>
ioapic0: int8 10100<vector=0,delmode=1,masked,dest=0> 0<target=0>
ioapic0: int9 10100<vector=0,delmode=1,masked,dest=0> 0<target=0>
ioapic0: int10 10100<vector=0,delmode=1,masked,dest=0> 0<target=0>
ioapic0: int11 181<vector=81,delmode=1,dest=0> 0<target=0>
ioapic0: int12 1a2<vector=a2,delmode=1,dest=0> 0<target=0>
ioapic0: int13 10100<vector=0,delmode=1,masked,dest=0> 0<target=0>
ioapic0: int14 10100<vector=0,delmode=1,masked,dest=0> 0<target=0>
ioapic0: int15 10100<vector=0,delmode=1,masked,dest=0> 0<target=0>
ioapic0: int16 a1c0<vector=c0,delmode=1,actlo,level,dest=0> 0<target=0>
ioapic0: int17 a171<vector=71,delmode=1,actlo,level,dest=0> 0<target=0>
ioapic0: int18 a172<vector=72,delmode=1,actlo,level,dest=0> 0<target=0>
ioapic0: int19 a173<vector=73,delmode=1,actlo,level,dest=0> 0<target=0>
ioapic0: int20 10000<vector=0,delmode=0,masked,dest=0> 0<target=0>
ioapic0: int21 10000<vector=0,delmode=0,masked,dest=0> 0<target=0>
ioapic0: int22 10000<vector=0,delmode=0,masked,dest=0> 0<target=0>
ioapic0: int23 200<vector=0,delmode=2,dest=0> 0<target=0>
scsibus0: waiting 2 seconds for devices to settle...
sd0 at scsibus0 target 0 lun 0: <IBM, DGHS09U, 0350> SCSI3 0/direct fixed
sd0: 8748 MB, 8152 cyl, 10 head, 219 sec, 512 bytes/sect x 17916240 sectors
sd1 at scsibus0 target 6 lun 0: <QUANTUM, QM318000TD-SW, N491> SCSI2 0/direct fixed
sd1: 17366 MB, 8057 cyl, 20 head, 220 sec, 512 bytes/sect x 35566500 sectors
scsibus1: waiting 2 seconds for devices to settle...
adw0: target 0 using 16-bits wide, 40.0 MHz synchronous transfers
sd2 at scsibus1 target 0 lun 0: <IBM, DDYS-T36950N, S93E> SCSI3 0/direct fixed
sd2: 35003 MB, 15110 cyl, 12 head, 395 sec, 512 bytes/sect x 71687340 sectors
adw0: target 4 using 16-bits wide, 40.0 MHz synchronous transfers
sd3 at scsibus1 target 4 lun 0: <IBM, DPSS-336950N, S80D> SCSI3 0/direct fixed
sd3: 35239 MB, 14627 cyl, 10 head, 493 sec, 512 bytes/sect x 72170880 sectors
adw0: target 6 using 16-bits wide, 40.0 MHz synchronous transfers
sd4 at scsibus1 target 6 lun 0: <IBM, DGHS18D, 03E0> SCSI3 0/direct fixed
sd4: 17501 MB, 8154 cyl, 20 head, 219 sec, 512 bytes/sect x 35843670 sectors
scsibus2: waiting 2 seconds for devices to settle...
ahc0: target 1 using 8bit transfers
ahc0: target 1 synchronous at 10.0MHz, offset = 0xf
cd0 at scsibus2 target 1 lun 0: <MATSHITA, PD-2 LF-D100, A113> SCSI2 5/cdrom removable
boot device: sd0
root on sd0a dumps on sd0b
cpu1: prelint0 10000<vector=0,delmode=0,masked,dest=0> 0<target=0>
cpu1: prelint1 10000<vector=0,delmode=0,masked,dest=0> 0<target=0>
cpu1: timer0 200d0<vector=d0,delmode=0,dest=0> 0<target=0>
cpu1: pcint0 10000<vector=0,delmode=0,masked,dest=0> 0<target=0>
cpu1: lint0 10700<vector=0,delmode=7,masked,dest=0> 0<target=0>
cpu1: lint1 400<vector=0,delmode=4,dest=0> 0<target=0>
cpu1: err0 10000<vector=0,delmode=0,masked,dest=0> 0<target=0>
cpu1: CPU 1 running
wsdisplay0: screen 1 added (80x50, vt100 emulation)
wsdisplay0: screen 2 added (80x50, vt100 emulation)
wsdisplay0: screen 3 added (80x50, vt100 emulation)
wsdisplay0: screen 4 added (80x50, vt100 emulation)
wsmux1: connecting to wsdisplay0
sd4(adw0:6:0): timed out
sd4(adw0:6:0): timed out
sd4(adw0:6:0): timed out AGAIN. Resetting SCSI Bus
simple_lock: locking against myself
lock: 0xc0300cc4, currently at: ../../../../arch/i386/i386/pmap.c:3425
on cpu 0
last locked: ../../../../arch/i386/i386/pmap.c:613
last unlocked: ../../../../arch/i386/i386/pmap.c:3570
simple_unlock: lock not held
lock: 0xc0300cc4, currently at: ../../../../arch/i386/i386/pmap.c:615
on cpu 0
last locked: ../../../../arch/i386/i386/pmap.c:3544
last unlocked: ../../../../arch/i386/i386/pmap.c:3570
simple_lock: locking against myself
lock: 0xc0300cc4, currently at: ../../../../arch/i386/i386/pmap.c:3425
on cpu 0
last locked: ../../../../arch/i386/i386/pmap.c:613
last unlocked: ../../../../arch/i386/i386/pmap.c:3570
simple_unlock: lock not held
lock: 0xc0300cc4, currently at: ../../../../arch/i386/i386/pmap.c:615
on cpu 0
last locked: ../../../../arch/i386/i386/pmap.c:3544
last unlocked: ../../../../arch/i386/i386/pmap.c:3570
simple_lock: locking against myself
lock: 0xc0300cc4, currently at: ../../../../arch/i386/i386/pmap.c:3425
on cpu 0
last locked: ../../../../arch/i386/i386/pmap.c:613
last unlocked: ../../../../arch/i386/i386/pmap.c:3570
simple_unlock: lock not held
lock: 0xc0300cc4, currently at: ../../../../arch/i386/i386/pmap.c:615
on cpu 0
last locked: ../../../../arch/i386/i386/pmap.c:3544
last unlocked: ../../../../arch/i386/i386/pmap.c:3570
simple_lock: locking against myself
lock: 0xc0300cc4, currently at: ../../../../arch/i386/i386/pmap.c:3425
on cpu 0
last locked: ../../../../arch/i386/i386/pmap.c:613
last unlocked: ../../../../arch/i386/i386/pmap.c:3570
simple_unlock: lock not held
lock: 0xc0300cc4, currently at: ../../../../arch/i386/i386/pmap.c:615
on cpu 0
last locked: ../../../../arch/i386/i386/pmap.c:3544
last unlocked: ../../../../arch/i386/i386/pmap.c:3570
simple_lock: locking against myself
lock: 0xc0300cc4, currently at: ../../../../arch/i386/i386/pmap.c:3425
on cpu 0
last locked: ../../../../arch/i386/i386/pmap.c:613
last unlocked: ../../../../arch/i386/i386/pmap.c:3570
simple_unlock: lock not held
lock: 0xc0300cc4, currently at: ../../../../arch/i386/i386/pmap.c:615
on cpu 0
last locked: ../../../../arch/i386/i386/pmap.c:3544
last unlocked: ../../../../arch/i386/i386/pmap.c:3570