From owner-freebsd-current Sat Sep 28 19:20:22 2002 Delivered-To: freebsd-current@freebsd.org Received: from mx1.FreeBSD.org (mx1.FreeBSD.org [216.136.204.125]) by hub.freebsd.org (Postfix) with ESMTP id 7C9FB37B401 for ; Sat, 28 Sep 2002 19:20:10 -0700 (PDT) Received: from boreas.isi.edu (boreas.isi.edu [128.9.160.161]) by mx1.FreeBSD.org (Postfix) with ESMTP id 4972C43E65 for ; Sat, 28 Sep 2002 19:20:09 -0700 (PDT) (envelope-from larse@ISI.EDU) Received: from isi.edu (nik.isi.edu [128.9.168.58]) by boreas.isi.edu (8.11.6/8.11.2) with ESMTP id g8T2K8C21002 for ; Sat, 28 Sep 2002 19:20:08 -0700 (PDT) Message-ID: <3D966358.8010606@isi.edu> Date: Sat, 28 Sep 2002 19:20:08 -0700 From: Lars Eggert User-Agent: Mozilla/5.0 (X11; U; Linux i386; en-US; rv:1.1) Gecko/20020826 X-Accept-Language: en-us, de-de MIME-Version: 1.0 To: current Subject: kernel panic when booting with USB CF reader Content-Type: multipart/signed; protocol="application/x-pkcs7-signature"; micalg=sha1; boundary="------------ms030200090201090607090800" Sender: owner-freebsd-current@FreeBSD.ORG Precedence: bulk List-ID: List-Archive: (Web Archive) List-Help: (List Instructions) List-Subscribe: List-Unsubscribe: X-Loop: FreeBSD.ORG This is a cryptographically signed message in MIME format. --------------ms030200090201090607090800 Content-Type: multipart/mixed; boundary="------------010402080709060808000608" This is a multi-part message in MIME format. --------------010402080709060808000608 Content-Type: text/plain; charset=us-ascii; format=flowed Content-Transfer-Encoding: 7bit Hi, I'm seeing a kernel panic on -current (9/26) when booting with a SanDisk ImageMate II USB comact flash reader plugged in. The panic occurs after the kernel has loaded when the first rc.d scripts execute (dumpon, vinum, etc). If I boot with the device disconnected, I can plug it in and unplug it without problems later. Attached is a boot trace and a gdb backtrace. (gdb crashed on me, so I couldn't get more information.) Here's the relevant info from the trace: SMP: AP CPU #1 Launched! Mounting root from ufs:/dev/da0s1a Entropy harvesting: interrupts ethernet point_to_point. kernel dumps on /dev/da1s1b vinum: loaded umass0: BBB reset failed, STALLED da2 at umass-sim0 bus 0 target 0 lun 0 da2: Removable Direct Access SCSI-2 device da2: 1.000MB/s transfers da2: Attempt to query device size failed: NOT READY, Medium not present (da2:umass-sim0:0:0:0): READ CAPACITY. CDB: 25 0 0 0 0 0 0 0 0 0 (da2:umass-sim0:0:0:0): CAM Status: SCSI Status Error (da2:umass-sim0:0:0:0): SCSI Status: Check Condition (da2:umass-sim0:0:0:0): NOT READY asc:3a,0 (da2:umass-sim0:0:0:0): Medium not present (da2:umass-sim0:0:0:0): Unretryable error Fatal trap 18: integer divide fault while in kernel mode cpuid = 1; lapic.id = 02000000 instruction pointer = 0x8:0xc0437608 stack pointer = 0x10:0xeb484870 frame pointer = 0x10:0xeb4848f8 code segment = base 0x0, limit 0xfffff, type 0x1b = DPL 0, pres 1, def32 1, gran 1 processor eflags = interrupt enabled, resume, IOPL = 0 current process = 67 (vinum) kernel: type 18 trap, code=0 Stopped at __qdivrem+0x38: divl %ecx,%eax Thanks, Lars -- Lars Eggert USC Information Sciences Institute --------------010402080709060808000608 Content-Type: text/plain; name="script.txt" Content-Transfer-Encoding: 7bit Content-Disposition: inline; filename="script.txt" Copyright (c) 1992-2002 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.0-CURRENT #0: Sat Sep 28 18:54:56 PDT 2002 root@nik.isi.edu:/usr/obj/usr/src/sys/KERNEL-1.4 Preloaded elf kernel "/boot/kernel/kernel" at 0xc06c0000. Preloaded elf module "/boot/kernel/snd_maestro3.ko" at 0xc06c00b4. Preloaded elf module "/boot/kernel/firewire.ko" at 0xc06c0168. Preloaded elf module "/boot/kernel/acpi.ko" at 0xc06c0218. Timecounter "i8254" frequency 1193119 Hz CPU: Pentium 4 (2372.79-MHz 686-class CPU) Origin = "GenuineIntel" Id = 0xf24 Stepping = 4 Features=0x3febfbff real memory = 1073180672 (1048028K bytes) avail memory = 1034698752 (1010448K bytes) Changing APIC ID for IO APIC #0 from 0 to 4 on chip Programming 24 pins in IOAPIC #0 IOAPIC #0 intpin 2 -> irq 0 FreeBSD/SMP: Multiprocessor System Detected: 2 CPUs cpu0 (BSP): apic id: 0, version: 0x00050014, at 0xfee00000 cpu1 (AP): apic id: 2, version: 0x00050014, at 0xfee00000 io0 (APIC): apic id: 4, version: 0x00178020, at 0xfec00000 Pentium Pro MTRR support enabled VESA: v2.0, 65536k memory, flags:0x1, mode table:0xc05aaa62 (1000022) VESA: ATI RADEON RV250 npx0: on motherboard npx0: INT 16 interface acpi0: on motherboard Using $PIR table, 12 entries at 0xc00fb9a0 acpi0: power button is handled as a fixed feature programming model. Timecounter "ACPI-fast" frequency 3579545 Hz acpi_timer0: <24-bit timer at 3.579545MHz> port 0x808-0x80b on acpi0 acpi_cpu0: on acpi0 acpi_cpu1: on acpi0 acpi_cpu2: on acpi0 acpi_cpu3: on acpi0 acpi_button0: on acpi0 pcib0: port 0xcf8-0xcff on acpi0 pci0: on pcib0 IOAPIC #0 intpin 19 -> irq 2 IOAPIC #0 intpin 17 -> irq 13 IOAPIC #0 intpin 23 -> irq 16 agp0: mem 0xe8000000-0xefffffff at device 0.0 on pci0 pcib1: at device 1.0 on pci0 pci1: on pcib1 IOAPIC #0 intpin 16 -> irq 17 pci1: at device 0.0 (no driver attached) pci1: at device 0.1 (no driver attached) pcib2: at device 2.0 on pci0 pci2: on pcib2 pcib3: at device 31.0 on pci2 pci3: on pcib3 IOAPIC #0 intpin 20 -> irq 18 IOAPIC #0 intpin 21 -> irq 19 pci3: at device 0.0 (no driver attached) mpt0: port 0xdc00-0xdcff mem 0xff6a0000-0xff6bffff,0xff6c0000-0xff6dffff irq 18 at device 12.0 on pci3 mpt1: port 0xd800-0xd8ff mem 0xff660000-0xff67ffff,0xff680000-0xff69ffff irq 19 at device 12.1 on pci3 pcib4: at device 30.0 on pci0 pci4: on pcib4 IOAPIC #0 intpin 18 -> irq 20 xl0: <3Com 3c905C-TX Fast Etherlink XL> port 0xcc80-0xccff mem 0xff3ffc00-0xff3ffc7f irq 16 at device 11.0 on pci4 /usr/src/sys/vm/uma_core.c:1307: could sleep with "xl0" locked from /usr/src/sys/pci/if_xl.c:1264 /usr/src/sys/vm/uma_core.c:1307: could sleep with "xl0" locked from /usr/src/sys/pci/if_xl.c:1264 lock order reversal 1st 0xc6248bd4 xl0 (network driver) @ /usr/src/sys/pci/if_xl.c:1264 2nd 0xc0502140 allproc (allproc) @ /usr/src/sys/kern/kern_fork.c:317 /usr/src/sys/vm/uma_core.c:1307: could sleep with "xl0" locked from /usr/src/sys/pci/if_xl.c:1264 /usr/src/sys/vm/uma_core.c:1307: could sleep with "xl0" locked from /usr/src/sys/pci/if_xl.c:1264 /usr/src/sys/vm/uma_core.c:1307: could sleep with "xl0" locked from /usr/src/sys/pci/if_xl.c:1264 xl0: Ethernet address: 00:06:5b:bd:ee:48 miibus0: on xl0 ukphy0: on miibus0 ukphy0: 10baseT, 10baseT-FDX, 100baseTX, 100baseTX-FDX, auto /usr/src/sys/vm/uma_core.c:1307: could sleep with "xl0" locked from /usr/src/sys/pci/if_xl.c:647 fwohci0: mem 0xff3f8000-0xff3fbfff,0xff3ff000-0xff3ff7ff irq 17 at device 12.0 on pci4 fwohci0: PCI bus latency was changing to 250. cache size 16. fwohci0: OHCI version 1.0 (ROM=0) fwohci0: No. of Isochronous channel is 4. fwohci0: resetting OHCI...done (0) fwohci0: BUS_OPT 0xa002 -> 0xf800a002 fwohci0: Link 1394a available S400, 2 ports, maxrec 2048 bytes. fwohci0: Enable 1394a Enhancements fwohci0: EUI64 80:5b:00:00:48:ee:bd:00 fwochi_set_intr: 1 firewire0: on fwohci0 firewire0: firewire bus attach em0: port 0xcc40-0xcc7f mem 0xff3a0000-0xff3bffff,0xff3c0000-0xff3dffff irq 13 at device 13.0 on pci4 em0: Speed:100 Mbps Duplex:Full pcm0: port 0xcc20-0xcc3f irq 20 at device 14.0 on pci4 /usr/src/sys/vm/uma_core.c:1307: could sleep with "pcm0" locked from /usr/src/sys/dev/sound/pcm/sound.c:134 /usr/src/sys/vm/uma_core.c:1307: could sleep with "pcm0" locked from /usr/src/sys/dev/sound/pcm/sound.c:134 /usr/src/sys/vm/uma_core.c:1307: could sleep with "pcm0" locked from /usr/src/sys/dev/sound/pcm/sound.c:134 /usr/src/sys/vm/uma_core.c:1307: could sleep with "pcm0:fake" locked from /usr/src/sys/dev/sound/pcm/channel.c:677 /usr/src/sys/vm/uma_core.c:1307: could sleep with "pcm0" locked from /usr/src/sys/dev/sound/pcm/sound.c:134 /usr/src/sys/vm/uma_core.c:1307: could sleep with "pcm0:fake" locked from /usr/src/sys/dev/sound/pcm/channel.c:677 /usr/src/sys/vm/uma_core.c:1307: could sleep with "pcm0" locked from /usr/src/sys/dev/sound/pcm/sound.c:134 /usr/src/sys/vm/uma_core.c:1307: could sleep with "pcm0:fake" locked from /usr/src/sys/dev/sound/pcm/channel.c:677 /usr/src/sys/vm/uma_core.c:1307: could sleep with "pcm0" locked from /usr/src/sys/dev/sound/pcm/sound.c:134 /usr/src/sys/vm/uma_core.c:1307: could sleep with "pcm0:fake" locked from /usr/src/sys/dev/sound/pcm/channel.c:677 /usr/src/sys/vm/uma_core.c:1307: could sleep with "pcm0" locked from /usr/src/sys/dev/sound/pcm/sound.c:134 /usr/src/sys/vm/uma_core.c:1307: could sleep with "pcm0" locked from /usr/src/sys/dev/sound/pcm/sound.c:134 /usr/src/sys/vm/uma_core.c:1307: could sleep with "pcm0" locked from /usr/src/sys/dev/sound/pcm/sound.c:134 /usr/src/sys/vm/uma_core.c:1307: could sleep with "pcm0" locked from /usr/src/sys/dev/sound/pcm/sound.c:134 /usr/src/sys/vm/uma_core.c:1307: could sleep with "pcm0" locked from /usr/src/sys/dev/sound/pcm/sound.c:134 /usr/src/sys/vm/uma_core.c:1307: could sleep with "pcm0" locked from /usr/src/sys/dev/sound/pcm/sound.c:134 /usr/src/sys/vm/uma_core.c:1307: could sleep with "pcm0" locked from /usr/src/sys/dev/sound/pcm/sound.c:134 /usr/src/sys/vm/uma_core.c:1307: could sleep with "pcm0" locked from /usr/src/sys/dev/sound/pcm/sound.c:134 /usr/src/sys/vm/uma_core.c:1307: could sleep with "pcm0" locked from /usr/src/sys/dev/sound/pcm/sound.c:134 /usr/src/sys/vm/uma_core.c:1307: could sleep with "pcm0" locked from /usr/src/sys/dev/sound/pcm/sound.c:134 /usr/src/sys/vm/uma_core.c:1307: could sleep with "pcm0" locked from /usr/src/sys/dev/sound/pcm/sound.c:134 /usr/src/sys/vm/uma_core.c:1307: could sleep with "pcm0" locked from /usr/src/sys/dev/sound/pcm/sound.c:134 /usr/src/sys/vm/uma_core.c:1307: could sleep with "pcm0" locked from /usr/src/sys/dev/sound/pcm/sound.c:134 /usr/src/sys/vm/uma_core.c:1307: could sleep with "pcm0" locked from /usr/src/sys/dev/sound/pcm/sound.c:134 /usr/src/sys/vm/uma_core.c:1307: could sleep with "pcm0:play:0" locked from /usr/src/sys/dev/sound/pcm/channel.c:677 /usr/src/sys/vm/uma_core.c:1307: could sleep with "pcm0:play:0" locked from /usr/src/sys/dev/sound/pcm/channel.c:677 /usr/src/sys/vm/uma_core.c:1307: could sleep with "pcm0:play:0" locked from /usr/src/sys/dev/sound/pcm/channel.c:677 /usr/src/sys/vm/uma_core.c:1307: could sleep with "pcm0" locked from /usr/src/sys/dev/sound/pcm/sound.c:134 /usr/src/sys/vm/uma_core.c:1307: could sleep with "pcm0:play:0" locked from /usr/src/sys/dev/sound/pcm/channel.c:677 /usr/src/sys/vm/uma_core.c:1307: could sleep with "pcm0:play:1" locked from /usr/src/sys/dev/sound/pcm/channel.c:677 /usr/src/sys/vm/uma_core.c:1307: could sleep with "pcm0:play:1" locked from /usr/src/sys/dev/sound/pcm/channel.c:677 /usr/src/sys/vm/uma_core.c:1307: could sleep with "pcm0:play:1" locked from /usr/src/sys/dev/sound/pcm/channel.c:677 /usr/src/sys/vm/uma_core.c:1307: could sleep with "pcm0" locked from /usr/src/sys/dev/sound/pcm/sound.c:134 /usr/src/sys/vm/uma_core.c:1307: could sleep with "pcm0:play:1" locked from /usr/src/sys/dev/sound/pcm/channel.c:677 /usr/src/sys/vm/uma_core.c:1307: could sleep with "pcm0:play:2" locked from /usr/src/sys/dev/sound/pcm/channel.c:677 /usr/src/sys/vm/uma_core.c:1307: could sleep with "pcm0:play:2" locked from /usr/src/sys/dev/sound/pcm/channel.c:677 /usr/src/sys/vm/uma_core.c:1307: could sleep with "pcm0:play:2" locked from /usr/src/sys/dev/sound/pcm/channel.c:677 /usr/src/sys/vm/uma_core.c:1307: could sleep with "pcm0" locked from /usr/src/sys/dev/sound/pcm/sound.c:134 /usr/src/sys/vm/uma_core.c:1307: could sleep with "pcm0:play:2" locked from /usr/src/sys/dev/sound/pcm/channel.c:677 /usr/src/sys/vm/uma_core.c:1307: could sleep with "pcm0:play:3" locked from /usr/src/sys/dev/sound/pcm/channel.c:677 /usr/src/sys/vm/uma_core.c:1307: could sleep with "pcm0:play:3" locked from /usr/src/sys/dev/sound/pcm/channel.c:677 /usr/src/sys/vm/uma_core.c:1307: could sleep with "pcm0:play:3" locked from /usr/src/sys/dev/sound/pcm/channel.c:677 /usr/src/sys/vm/uma_core.c:1307: could sleep with "pcm0" locked from /usr/src/sys/dev/sound/pcm/sound.c:134 /usr/src/sys/vm/uma_core.c:1307: could sleep with "pcm0:play:3" locked from /usr/src/sys/dev/sound/pcm/channel.c:677 /usr/src/sys/vm/uma_core.c:1307: could sleep with "pcm0" locked from /usr/src/sys/dev/sound/pcm/sound.c:134 /usr/src/sys/vm/uma_core.c:1307: could sleep with "pcm0" locked from /usr/src/sys/dev/sound/pcm/sound.c:134 /usr/src/sys/vm/uma_core.c:1307: could sleep with "pcm0" locked from /usr/src/sys/dev/sound/pcm/sound.c:134 /usr/src/sys/vm/uma_core.c:1307: could sleep with "pcm0:record:0" locked from /usr/src/sys/dev/sound/pcm/channel.c:677 /usr/src/sys/vm/uma_core.c:1307: could sleep with "pcm0:record:0" locked from /usr/src/sys/dev/sound/pcm/channel.c:677 /usr/src/sys/vm/uma_core.c:1307: could sleep with "pcm0:record:0" locked from /usr/src/sys/dev/sound/pcm/channel.c:677 /usr/src/sys/vm/uma_core.c:1307: could sleep with "pcm0:record:0" locked from /usr/src/sys/dev/sound/pcm/channel.c:677 /usr/src/sys/vm/uma_core.c:1307: could sleep with "pcm0" locked from /usr/src/sys/dev/sound/pcm/sound.c:134 /usr/src/sys/vm/uma_core.c:1307: could sleep with "pcm0" locked from /usr/src/sys/dev/sound/pcm/sound.c:134 /usr/src/sys/vm/uma_core.c:1307: could sleep with "pcm0" locked from /usr/src/sys/dev/sound/pcm/sound.c:134 /usr/src/sys/vm/uma_core.c:1307: could sleep with "pcm0" locked from /usr/src/sys/dev/sound/pcm/sound.c:134 /usr/src/sys/vm/uma_core.c:1307: could sleep with "pcm0:record:1" locked from /usr/src/sys/dev/sound/pcm/channel.c:677 /usr/src/sys/vm/uma_core.c:1307: could sleep with "pcm0:record:1" locked from /usr/src/sys/dev/sound/pcm/channel.c:677 /usr/src/sys/vm/uma_core.c:1307: could sleep with "pcm0:record:1" locked from /usr/src/sys/dev/sound/pcm/channel.c:677 /usr/src/sys/vm/uma_core.c:1307: could sleep with "pcm0:record:1" locked from /usr/src/sys/dev/sound/pcm/channel.c:677 /usr/src/sys/vm/uma_core.c:1307: could sleep with "pcm0" locked from /usr/src/sys/dev/sound/pcm/sound.c:134 /usr/src/sys/vm/uma_core.c:1307: could sleep with "pcm0" locked from /usr/src/sys/dev/sound/pcm/sound.c:134 /usr/src/sys/vm/uma_core.c:1307: could sleep with "pcm0" locked from /usr/src/sys/dev/sound/pcm/sound.c:134 /usr/src/sys/vm/uma_core.c:1307: could sleep with "pcm0" locked from /usr/src/sys/dev/sound/pcm/sound.c:134 bktr0: mem 0xf8001000-0xf8001fff irq 2 at device 15.0 on pci4 bktr0: Hauppauge Model 61381 D423 bktr0: Detected a MSP3430G-A4 at 0x80 bktr0: Hauppauge WinCast/TV, Philips FR1236 NTSC FM tuner, msp3400c stereo, remote control. pci4: at device 15.1 (no driver attached) isab0: at device 31.0 on pci0 isa0: on isab0 atapci0: port 0xffa0-0xffaf at device 31.1 on pci0 ata0: at 0x1f0 irq 14 on atapci0 ata1: at 0x170 irq 15 on atapci0 uhci0: port 0xff80-0xff9f irq 2 at device 31.2 on pci0 usb0: 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 uhub1: NMB Dell USB Keyboard Hub, class 9/0, rev 1.10/0.01, addr 2 uhub1: 3 ports with 2 removable, bus powered ukbd0: NMB Dell USB 7HK Keyboard, rev 1.10/0.01, addr 3, iclass 3/1 kbd1 at ukbd0 uhid0: NMB Dell USB 7HK Keyboard, rev 1.10/0.01, addr 3, iclass 3/0 ums0: Microsoft Microsoft 5-Button Mouse with IntelliEye(TM), rev 1.10/3.00, addr 4, iclass 3/1 ums0: 5 buttons and Z dir. ichsmb0: port 0xbcd0-0xbcdf irq 13 at device 31.3 on pci0 smbus0: on ichsmb0 smb0: on smbus0 uhci1: port 0xff60-0xff7f irq 16 at device 31.4 on pci0 usb1: on uhci1 usb1: 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 umass0: SanDisk Corporation ImageMate CompactFlash USB, rev 1.10/0.09, addr 2 umass0: Get Max Lun not supported (STALLED) fdc0: 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 atkbdc0: port 0x64,0x60 irq 1 on acpi0 atkbd0: flags 0x1 irq 1 on atkbdc0 kbd0 at atkbd0 sio0: configured irq 4 not in bitmap of probed irqs 0 sio0: port may not be enabled sio0 port 0x3f8-0x3ff irq 4 on acpi0 sio0: type 16550A, console sio1: configured irq 3 not in bitmap of probed irqs 0 sio1: port may not be enabled sio1 port 0x2f8-0x2ff irq 3 on acpi0 sio1: type 16550A ppc0 port 0x778-0x77f,0x378-0x37f irq 7 drq 1 on acpi0 ppc0: SMC-like chipset (ECP/EPP/PS2/NIBBLE) in COMPATIBLE mode ppc0: FIFO with 16/16/8 bytes threshold lpt0: on ppbus0 lpt0: Interrupt-driven port ppi0: on ppbus0 orm0: