Subject: panic: dequeued wrong buf in -current
To: NetBSD current <current-users@netbsd.org>
From: Andreas Wrede <andreas@planix.com>
List: current-users
Date: 09/06/2004 12:05:12
--Apple-Mail-16-215395828
Content-Transfer-Encoding: 7bit
Content-Type: text/plain; charset=US-ASCII; delsp=yes; format=flowed

After upgrading from Aug 6 -current sources to today's I get a "panic:  
sdstart(): dequeued wrong buf" very early in the boot up sequence.   
There is another "panic: biodone already  done" in the syncing disks...  
step and while the kernel produces a core dump, savecore does not  
recognize it.

Note that the root fs is on a RAID-1 set.

Below, you'll find the traceback and the boot messages:
Traceback:

panic: sdstart(): dequeued wrong buf
Begin traceback...
sdstart(c1aa9f00,c1a73080,0,4,0) at netbsd:sdstart+0x2ea
sdstrategy(c1a73080,0,80,0,0) at netbsd:sdstrategy+0x1db
spec_strategy(cc927874,cc8771f8,100000,404,c05229a0) at  
netbsd:spec_strategy+0x155
VOP_STRATEGY(cc8771f8,c1a73080,cc92791c,293,72) at  
netbsd:VOP_STRATEGY+0x28
rf_DispatchKernelIO(c1a20000,c1ae5074,1,0,3ddcbf) at  
netbsd:rf_DispatchKernelIO+0x28b
rf_DiskReadFuncForThreads(c1e0c1c4,c1abd000,c1add0e4,c01aec68,0) at  
netbsd:rf_DiskReadFuncForThreads+0xb1
rf_DiskReadMirrorIdleFunc(c1e0c1c4,c1ae200c,cc927a0c,246,c1a76000) at  
netbsd:rf_DiskReadMirrorIdleFunc+0x19
FireNode(c1e0c1c4,0,c1e0c1c4,0,0) at netbsd:FireNode+0x33
FireNodeList(c1e0c1c4,0,0,0,0) at netbsd:FireNodeList+0xa5
rf_FinishNode(c1e0c380,0,0,0,1) at netbsd:rf_FinishNode+0x1b
rf_NullNodeFunc(c1e0c380,c1ade0ec,c1ade0ec,c1add0e4,c1add0e4) at  
netbsd:rf_NullNodeFunc+0x17
FireNode(c1e0c380,c1add0e4,0,c060bb40,c1ac9510) at netbsd:FireNode+0x33
FireNodeArray(1,c1add0f4,1,293,0) at netbsd:FireNodeArray+0xa5
rf_DispatchDAG(c1add0e4,c01c434c,c1ade0ec,0,10) at  
netbsd:rf_DispatchDAG+0xa2
rf_State_ExecuteDAG(c1ac9510,72,43ef40,0,c1ac9510) at  
netbsd:rf_State_ExecuteDAG+0x99
rf_ContinueRaidAccess(c1ac9510,c0511b60,400,293,43ef50) at  
netbsd:rf_ContinueRaidAccess+0x74
rf_DoAccess(c1a76000,72,1,43ef40,0) at netbsd:rf_DoAccess+0xba
raidstart(c1a76000,c1defaec,0,0,c062fb98) at netbsd:raidstart+0x28f
raidstrategy(c1defaec,0,cc927bac,c034e919,c1defaec) at  
netbsd:raidstrategy+0x131
spec_strategy(cc927ba4,0,1000,cc927c94,c05229a0) at  
netbsd:spec_strategy+0x155
VOP_STRATEGY(cc877498,c1defaec,0,0,40500) at netbsd:VOP_STRATEGY+0x28
bio_doread(cc877498,43ef40,0,2000,ffffffff) at netbsd:bio_doread+0x73
bread(cc877498,43ef40,0,2000,ffffffff) at netbsd:bread+0x20
ffs_vget(c1ab4000,88800,cc927d64,88800,cc927cd4) at  
netbsd:ffs_vget+0x1e7
ufs_lookup(cc927d94,10002,cc927dac,c035cc0a,c05221a0) at  
netbsd:ufs_lookup+0x74e
VOP_LOOKUP(cc8772a0,cc927e84,cc927e98,cc927e84,cc8772a0) at  
netbsd:VOP_LOOKUP+0x2e
lookup(cc927e74,cc8a3400,400,cc927e8c,cc894000) at netbsd:lookup+0x201
namei(cc927e74,cc8772a0,cc927e9c,c035d620,bfbfd961) at  
netbsd:namei+0x138
sys___lstat13(cc83db58,cc927f64,cc927f5c,0,246) at  
netbsd:sys___lstat13+0x58
syscall_plain() at netbsd:syscall_plain+0x7f
--- syscall (number 280) ---
0x480ed2c3:
End traceback...
syncing disks... panic: biodone already
Begin traceback...
biodone(c1a73080,cc92758c,c0689b40,297,c044c014) at netbsd:biodone+0x103
scsipi_complete(c1a25094,c1a64980,18,246,0) at  
netbsd:scsipi_complete+0x123
scsipi_done(c1a25094,2,c0689b40,cc9275ec,c044c014) at  
netbsd:scsipi_done+0x188
esiop_checkdone(c1a24e00,c0689b40,cc92760b,1,1) at  
netbsd:esiop_checkdone+0x252
esiop_intr(c1a24e00,4,10,cc920030,10) at netbsd:esiop_intr+0x5d
Xintr_legacy11() at netbsd:Xintr_legacy11+0xad
--- interrupt ---
Xspllower(4,6,cc8d0338,246,cc92770c) at netbsd:Xspllower+0xe
softintr_dispatch(0,0,cc8d0338,cc83db58,cc92772c) at  
netbsd:softintr_dispatch+0x89
DDB lost frame for netbsd:Xsoftclock+0x26, trying 0xcc9276fc
Xsoftclock() at netbsd:Xsoftclock+0x26
--- interrupt ---
0:
End traceback...

dumping to dev 0,1 offset 1049479
dump 1023 1022 1021 [SNIP]
rebooting...

-------------------------------------------
Boot messages:
NetBSD 2.0G (WOFFI) #0: Mon Sep  6 11:20:04 EDT 2004
          
root@woffi.planix.com:/m5/netbsd-current/obj.i386/sys/arch/i386/ 
compile/WOFFI
total memory = 1023 MB
avail memory = 995 MB
BIOS32 rev. 0 found at 0xfdb60
PCI BIOS rev. 2.1 found at 0xfdb81
PCI IRQ Routing Table rev. 1.0 found at 0xf78c0, size 224 bytes (12  
entries)
PCI Interrupt Router at 000:31:0 (Intel 82801DB LPC Interface Bridge  
compatible)
mainbus0 (root)
cpu0 at mainbus0: (uniprocessor)
cpu0: Intel Pentium 4 (686-class), 2205.12 MHz, id 0xf24
cpu0: features  
3febfbff<FPU,VME,DE,PSE,TSC,MSR,PAE,MCE,CX8,APIC,SEP,MTRR>
cpu0: features 3febfbff<PGE,MCA,CMOV,PAT,PSE36,CFLUSH,DS,ACPI,MMX>
cpu0: features 3febfbff<FXSR,SSE,SSE2,SS,HTT,TM>
cpu0: "Intel(R) Pentium(R) 4 CPU 2.20GHz"
cpu0: I-cache 12K uOp cache 8-way, D-cache 8 KB 64B/line 4-way
cpu0: L2 cache 512 KB 64B/line 8-way
cpu0: ITLB 4K/4M: 64 entries
cpu0: DTLB 4K/4M: 64 entries
cpu0: using thermal monitor 1
cpu0: 16 page colors
acpi0 at mainbus0
acpi0: using Intel ACPI CA subsystem version 20040211
acpi0: X/RSDT: OemId <AMIINT,INTEL845,00000010>, AslId <MSFT,00000097>
acpi0: SCI interrupting at int 9
acpi0: fixed-feature power button present
ACPI Object Type 'Processor' (0x0c) at acpi0 not configured
ACPI Object Type 'Processor' (0x0c) at acpi0 not configured
acpibut0 at acpi0 (PNP0C0C): ACPI Power Button
PNP0A03 [PCI Bus] at acpi0 not configured
PNP0C02 [Plug and Play motherboard register resources] at acpi0 not  
configured
INT0800 at acpi0 not configured
PNP0200 [AT DMA Controller] at acpi0 not configured
PNP0100 [AT Timer] at acpi0 not configured
PNP0B00 [AT Real-Time Clock] at acpi0 not configured
PNP0800 [AT-style speaker sound] at acpi0 not configured
npx1 at acpi0 (PNP0C04)
npx1: io 0xf0-0xff irq 13
npx1: using exception 16
pckbc1 at acpi0 (PNP0F13): aux port
pckbc1: irq 12
pckbc2 at acpi0 (PNP0303): kbd port
pckbc2: io 0x60,0x64 irq 1
fdc1 at acpi0 (PNP0700)
fdc1: io 0x3f2-0x3f5,0x3f7 irq 6 drq 2
com2 at acpi0 (PNP0501-1)
com2: io 0x3f8-0x3ff irq 4
com2: ns16550a, working fifo
com3 at acpi0 (PNP0501-2)
com3: io 0x2f8-0x2ff irq 3
com3: ns16550a, working fifo
lpt3 at acpi0 (PNP0401)
lpt3: io 0x378-0x37f,0x778-0x77b irq 7 drq 3
ACPI Object Type 'Power' (0x0b) at acpi0 not configured
ACPI Object Type 'Power' (0x0b) at acpi0 not configured
ACPI Object Type 'Power' (0x0b) at acpi0 not configured
ACPI Object Type 'Power' (0x0b) at acpi0 not configured
PNP0C0F [PCI interrupt link device] at acpi0 not configured
PNP0C0F [PCI interrupt link device] at acpi0 not configured
PNP0C0F [PCI interrupt link device] at acpi0 not configured
PNP0C0F [PCI interrupt link device] at acpi0 not configured
PNP0C0F [PCI interrupt link device] at acpi0 not configured
PNP0C0F [PCI interrupt link device] at acpi0 not configured
PNP0C0F [PCI interrupt link device] at acpi0 not configured
PNP0C0F [PCI interrupt link device] at acpi0 not configured
pckbd0 at pckbc2 (kbd slot)
wskbd0 at pckbd0: console keyboard
pms0 at pckbc2 (aux slot)
pckbc2: using irq 12 for aux slot
wsmouse0 at pms0 mux 0
pci0 at mainbus0 bus 0: configuration mode 1
pci0: i/o space, memory space enabled, rd/line, rd/mult, wr/inv ok
pchb0 at pci0 dev 0 function 0
pchb0: Intel 82845G/GL DRAM Controller / Host-Hub I/F Bridge (rev. 0x03)
agp0 at pchb0: using generic initialization for Intel AGP
agp0: aperture at 0xe0000000, size 0x4000000
ppb0 at pci0 dev 1 function 0: Intel 82845G/GL Host-AGP Bridge (rev.  
0x03)
pci1 at ppb0 bus 1
pci1: i/o space, memory space enabled
vga1 at pci1 dev 0 function 0: Matrox MGA G550 AGP (rev. 0x01)
wsdisplay0 at vga1 kbdmux 1: console (80x25, vt100 emulation), using  
wskbd0
wsmux1: connecting to wsdisplay0
uhci0 at pci0 dev 29 function 0: Intel 82801DB/DBM USB UHCI Controller  
#1 (rev. 0x02)
uhci0: interrupting at irq 10
usb0 at uhci0: USB revision 1.0
uhub0 at usb0
uhub0: Intel UHCI root hub, class 9/0, rev 1.00/1.00, addr 1
uhub0: 2 ports with 2 removable, self powered
uhci1 at pci0 dev 29 function 1: Intel 82801DB/DBM USB UHCI Controller  
#2 (rev. 0x02)
uhci1: interrupting at irq 11
usb1 at uhci1: USB revision 1.0
uhub1 at usb1
uhub1: Intel UHCI root hub, class 9/0, rev 1.00/1.00, addr 1
uhub1: 2 ports with 2 removable, self powered
uhci2 at pci0 dev 29 function 2: Intel 82801DB/DBM USB UHCI Controller  
#3 (rev. 0x02)
uhci2: interrupting at irq 11
usb2 at uhci2: USB revision 1.0
uhub2 at usb2
uhub2: Intel UHCI root hub, class 9/0, rev 1.00/1.00, addr 1
uhub2: 2 ports with 2 removable, self powered
ehci0 at pci0 dev 29 function 7: Intel 82801DB/DBM USB EHCI Controller  
(rev. 0x02)
ehci0: interrupting at irq 11
ehci0: EHCI version 1.0
ehci0: companion controllers, 2 ports each: uhci0 uhci1 uhci2
usb3 at ehci0: USB revision 2.0
uhub3 at usb3
uhub3: Intel EHCI root hub, class 9/0, rev 2.00/1.00, addr 1
uhub3: 6 ports with 6 removable, self powered
ppb1 at pci0 dev 30 function 0: Intel 82801BA Hub-PCI Bridge (rev. 0x82)
pci2 at ppb1 bus 2
pci2: i/o space, memory space enabled
cbb0 at pci2 dev 0 function 0: Ricoh 5C475 PCI-CardBus bridge (rev.  
0x80)
esiop0 at pci2 dev 1 function 0: Symbios Logic 53c875 (ultra-wide scsi)
esiop0: using on-board RAM
esiop0: interrupting at irq 11
esiop0: alloc new tag DSA table at PHY addr 0x28f5000
scsibus0 at esiop0: 16 targets, 8 luns per target
VIA Technologies VT3606 IEEE 1394 Host Controller (Firewire serial bus,  
interface 0x10, revision 0x43) at pci2 dev 2 function 0 not configured
bktr0 at pci2 dev 3 function 0
bktr0: interrupting at irq 11
bktr0: Hauppauge Model 61381 D123
bktr0: Detected a MSP3430G-A1 at 0x80
bktr0: Hauppauge WinCast/TV, Philips FR1236 NTSC FM tuner, msp3400c  
stereo, remote control.
radio0 at bktr0
Brooktree Bt878 Video Capture (Audio Section) (miscellaneous  
multimedia, revision 0x02) at pci2 dev 3 function 1 not configured
eap0 at pci2 dev 5 function 0: Ensoniq CT5880 CT5880-C (rev. 0x02)
eap0: interrupting at irq 11
eap0: ac97: SigmaTel STAC9708 codec; 18 bit DAC, 18 bit ADC, SigmaTel 3D
eap0: ac97: ext id 80<SDAC>
audio0 at eap0: full duplex, mmap, independent
midi0 at eap0: AudioPCI MIDI UART
fxp0 at pci2 dev 8 function 0: Intel PRO/100 VE Network Controller with  
82562ET/EZ (CNR) PHY, rev 130
fxp0: interrupting at irq 11
fxp0: Ethernet address 00:10:dc:c0:60:39
inphy0 at fxp0 phy 1: i82562ET 10/100 media interface, rev. 0
inphy0: 10baseT, 10baseT-FDX, 100baseTX, 100baseTX-FDX, auto
cbb0: interrupting at irq 10
cardslot0 at cbb0 slot 0 flags 0
cardbus0 at cardslot0: bus 3 device 0
pcmcia0 at cardslot0
pcib0 at pci0 dev 31 function 0
pcib0: Intel 82801DB LPC Interface Bridge (rev. 0x02)
piixide0 at pci0 dev 31 function 1
piixide0: Intel 82801DB IDE Controller (ICH4) (rev. 0x02)
piixide0: bus-master DMA support present
piixide0: primary channel wired to compatibility mode
piixide0: primary channel interrupting at irq 14
atabus0 at piixide0 channel 0
piixide0: secondary channel wired to compatibility mode
piixide0: secondary channel interrupting at irq 15
atabus1 at piixide0 channel 1
Intel 82801DB/DBM SMBus Controller (SMBus serial bus, revision 0x02) at  
pci0 dev 31 function 3 not configured
isa0 at pcib0
lm0 at isa0 port 0x290-0x297: W83627HF
pcppi0 at isa0 port 0x61
midi1 at pcppi0: PC speaker
spkr0 at pcppi0
sysbeep0 at pcppi0
isapnp0 at isa0 port 0x279: ISA Plug 'n Play device support
isapnp0: no ISA Plug 'n Play devices found
raidattach: Asked for 8 units
Kernelized RAIDframe activated
IPsec: Initialized Security Association Processing.
scsibus0: waiting 2 seconds for devices to settle...
wd0 at atabus0 drive 0: <Maxtor 33073U4>
wd0: drive supports 16-sector PIO transfers, LBA addressing
wd0: 29311 MB, 59554 cyl, 16 head, 63 sec, 512 bytes/sect x 60030432  
sectors
wd0: 32-bit data port
wd0: drive supports PIO mode 4, DMA mode 2, Ultra-DMA mode 4 (Ultra/66)
wd1 at atabus0 drive 1: <IC35L120AVVA07-0>
wd1: drive supports 16-sector PIO transfers, LBA addressing
wd1: 115 GB, 239340 cyl, 16 head, 63 sec, 512 bytes/sect x 241254720  
sectors
wd1: 32-bit data port
wd1: drive supports PIO mode 4, DMA mode 2, Ultra-DMA mode 5 (Ultra/100)
wd0(piixide0:0:0): using PIO mode 4, Ultra-DMA mode 4 (Ultra/66) (using  
DMA data transfers)
wd1(piixide0:0:1): using PIO mode 4, Ultra-DMA mode 5 (Ultra/100)  
(using DMA data transfers)
atapibus0 at atabus1: 2 targets
cd0 at atapibus0 drive 0: <HL-DT-ST RW/DVD GCC-4120B, , 2.01> cdrom  
removable
cd0: 32-bit data port
cd0: drive supports PIO mode 4, DMA mode 2
cd0(piixide0:1:0): using PIO mode 4, DMA mode 2 (using DMA data  
transfers)
wi0 at pcmcia0 function 0: <D, Link DWL-650 11Mbps WLAN Card, Version  
01.02, >
wi0: 802.11 address 00:05:5d:d7:00:12
wi0: using RF:PRISM2.5 MAC:ISL3873B(PCMCIA)
wi0: Intersil Firmware: Primary (1.0.5), Station (1.3.4)
wi0: 11b rates: 1Mbps 2Mbps 5.5Mbps 11Mbps
esiop0: alloc newcdb at PHY addr 0x2a06000
sd0 at scsibus0 target 0 lun 0: <SEAGATE, ST39173W, 6244> disk fixed
sd0: 8683 MB, 7501 cyl, 10 head, 237 sec, 512 bytes/sect x 17783240  
sectors
sd0: sync (50.00ns offset 15), 16-bit (40.000MB/s) transfers, tagged  
queueing
sd1 at scsibus0 target 1 lun 0: <SEAGATE, ST39173W, 6244> disk fixed
sd1: 8683 MB, 7501 cyl, 10 head, 237 sec, 512 bytes/sect x 17783240  
sectors
sd1: sync (50.00ns offset 15), 16-bit (40.000MB/s) transfers, tagged  
queueing
Searching for RAID components...
Component on: sd0e: 17783177
    Row: 0 Column: 0 Num Rows: 1 Num Columns: 2
    Version: 2 Serial Number: 109 Mod Counter: 2506
    Clean: Yes Status: 0
    sectPerSU: 128 SUsPerPU: 1 SUsPerRU: 1
    RAID Level: 1  blocksize: 512 numBlocks: 17783040
    Autoconfig: Yes
    Contains root partition: Yes
    Last configured as: raid0
Component on: sd1e: 17783177
    Row: 0 Column: 1 Num Rows: 1 Num Columns: 2
    Version: 2 Serial Number: 109 Mod Counter: 2506
    Clean: Yes Status: 0
    sectPerSU: 128 SUsPerPU: 1 SUsPerRU: 1
    RAID Level: 1  blocksize: 512 numBlocks: 17783040
    Autoconfig: Yes
    Contains root partition: Yes
    Last configured as: raid0
Found: sd0e at 0
Found: sd1e at 1
RAID autoconfigure
Configuring raid0:
Starting autoconfiguration of RAID set...
Looking for 0 in autoconfig
Found: sd0e at 0
Looking for 1 in autoconfig
Found: sd1e at 1
raid0: allocating 20 buffers of 65536 bytes.
raid0: RAID Level 1
raid0: Components: /dev/sd0e /dev/sd1e
raid0: Total Sectors: 17783040 (8683 MB)
boot device: raid0
root on raid0a dumps on raid0b
mountroot: trying smbfs...
mountroot: trying coda...
mountroot: trying msdos...
mountroot: trying cd9660...
mountroot: trying ntfs...
mountroot: trying nfs...
mountroot: trying lfs...
mountroot: trying ext2fs...
mountroot: trying ffs...
root file system type: ffs
cc_microset[0]: delta 2072407624, resetting state
init: copying out path `/sbin/init' 11
wsdisplay0: screen 1 added (80x25, vt100 emulation)
wsdisplay0: screen 2 added (80x25, vt100 emulation)
wsdisplay0: screen 3 added (80x25, vt100 emulation)
wsdisplay0: screen 4 added (80x25, vt100 emulation)
wsdisplay0: screen 5 added (80x50, vt100 emulation)
wsdisplay0: screen 6 added (80x25bf, vt100 emulation)
wsdisplay0: screen 7 added (80x25, vt100 emulation)


-- 
	aew

--Apple-Mail-16-215395828
content-type: application/pgp-signature; x-mac-type=70674453;
	name=PGP.sig
content-description: This is a digitally signed message part
content-disposition: inline; filename=PGP.sig
content-transfer-encoding: 7bit

-----BEGIN PGP SIGNATURE-----
Version: GnuPG v1.2.4 (Darwin)

iD8DBQFBPIq4Eh/h9J/TQyERAshZAJ9LCXW7XealYx9JF02Wa4XV/pYasgCfQPj0
mCsc88VjMLLdRdQFoJD4yPE=
=Ollr
-----END PGP SIGNATURE-----

--Apple-Mail-16-215395828--