Message-ID: <alpine.SOC.1.00.1111211431130.23697@math.ut.ee>
Date:	Wed, 23 Nov 2011 12:20:18 +0200 (EET)
From:	Meelis Roos <mroos@...ux.ee>
To:	Linux Kernel list <linux-kernel@...r.kernel.org>, linux-scsi@...ee
Subject: 3.2-rc2+git: kernel BUG at block/blk-core.c:1000!
 (__scsi_queue_insert)

Hello,

While trying 3.2.0-rc2-00143-ga767835 on Sun Ultra 10 (sparc64) with 
Adaptec SCSI controller, I can consistently get the below BUG shortly 
after bootup. Another machine, Sun Ultra 5 with IDE disk, works fine 
(config might also differ in other details than ide/scsi).

PROMLIB: Sun IEEE Boot Prom 'OBP 3.31.0 2001/07/25 20:36'
PROMLIB: Root node compatible: 
Initializing cgroup subsys cpu
Linux version 3.2.0-rc2-00143-ga767835 (mroos@u10) (gcc version 4.6.2 (Debian 4.6.2-4) ) #8 Mon Nov 21 00:18:29 EET 2011
debug: ignoring loglevel setting.
bootconsole [earlyprom0] enabled
ARCH: SUN4U
Ethernet address: 08:00:20:9e:82:fd
Kernel: Using 2 locked TLB entries for main kernel image.
Remapping the kernel... done.
OF stdout device is: /pci@1f,0/pci@1,1/ebus@...e@14,400000:a
PROM: Built device tree with 49735 bytes of memory.
Top of RAM: 0x1ff28000, Total RAM: 0x1ff16000
Memory hole size: 0MB
[0000010000000000-fffff80000c00000] page_structs=131072 node=0 entry=0/8192
[0000010000000000-fffff80001000000] page_structs=131072 node=0 entry=1/8192
Zone PFN ranges:
  Normal   0x00000000 -> 0x0000ff94
Movable zone start PFN for each node
early_node_map[2] active PFN ranges
    0: 0x00000000 -> 0x0000ff7f
    0: 0x0000ff88 -> 0x0000ff94
On node 0 totalpages: 65419
  Normal zone: 512 pages used for memmap
  Normal zone: 0 pages reserved
  Normal zone: 64907 pages, LIFO batch:15
Booting Linux...
CPU CAPS: [flush,stbar,swap,muldiv,v9,mul32,div32,v8plus]
CPU CAPS: [vis]
pcpu-alloc: s0 r0 d32768 u32768 alloc=1*32768
pcpu-alloc: [0] 0 
Built 1 zonelists in Zone order, mobility grouping on.  Total pages: 64907
Kernel command line: root=/dev/sda4 ro debug ignore_loglevel
PID hash table entries: 2048 (order: 1, 16384 bytes)
Dentry cache hash table entries: 65536 (order: 6, 524288 bytes)
Inode-cache hash table entries: 32768 (order: 5, 262144 bytes)
Memory: 508840k available (3472k kernel code, 1248k data, 168k init) [fffff80000000000,000000001ff28000]
SLUB: Genslabs=16, HWalign=32, Order=0-3, MinObjects=0, CPUs=1, Nodes=1
NR_IRQS:255
clocksource: mult[3555555] shift[24]
clockevent: mult[4ccccccd] shift[32]
Console: colour dummy device 80x25
console [tty0] enabled, bootconsole disabled
PROMLIB: Sun IEEE Boot Prom 'OBP 3.31.0 2001/07/25 20:36'
PROMLIB: Root node compatible: 
Initializing cgroup subsys cpu
Linux version 3.2.0-rc2-00143-ga767835 (mroos@u10) (gcc version 4.6.2 (Debian 4.6.2-4) ) #8 Mon Nov 21 00:18:29 EET 2011
debug: ignoring loglevel setting.
bootconsole [earlyprom0] enabled
ARCH: SUN4U
Ethernet address: 08:00:20:9e:82:fd
Kernel: Using 2 locked TLB entries for main kernel image.
Remapping the kernel... done.
OF stdout device is: /pci@1f,0/pci@1,1/ebus@...e@14,400000:a
PROM: Built device tree with 49735 bytes of memory.
Top of RAM: 0x1ff28000, Total RAM: 0x1ff16000
Memory hole size: 0MB
[0000010000000000-fffff80000c00000] page_structs=131072 node=0 entry=0/8192
[0000010000000000-fffff80001000000] page_structs=131072 node=0 entry=1/8192
Zone PFN ranges:
  Normal   0x00000000 -> 0x0000ff94
Movable zone start PFN for each node
early_node_map[2] active PFN ranges
    0: 0x00000000 -> 0x0000ff7f
    0: 0x0000ff88 -> 0x0000ff94
On node 0 totalpages: 65419
  Normal zone: 512 pages used for memmap
  Normal zone: 0 pages reserved
  Normal zone: 64907 pages, LIFO batch:15
Booting Linux...
CPU CAPS: [flush,stbar,swap,muldiv,v9,mul32,div32,v8plus]
CPU CAPS: [vis]
pcpu-alloc: s0 r0 d32768 u32768 alloc=1*32768
pcpu-alloc: [0] 0 
Built 1 zonelists in Zone order, mobility grouping on.  Total pages: 64907
Kernel command line: root=/dev/sda4 ro debug ignore_loglevel
PID hash table entries: 2048 (order: 1, 16384 bytes)
Dentry cache hash table entries: 65536 (order: 6, 524288 bytes)
Inode-cache hash table entries: 32768 (order: 5, 262144 bytes)
Memory: 508840k available (3472k kernel code, 1248k data, 168k init) [fffff80000000000,000000001ff28000]
SLUB: Genslabs=16, HWalign=32, Order=0-3, MinObjects=0, CPUs=1, Nodes=1
NR_IRQS:255
clocksource: mult[3555555] shift[24]
clockevent: mult[4ccccccd] shift[32]
Console: colour dummy device 80x25
console [tty0] enabled, bootconsole disabled
Calibrating delay using timer specific routine.. 601.00 BogoMIPS (lpj=3005039)
pid_max: default: 32768 minimum: 301
Mount-cache hash table entries: 512
NET: Registered protocol family 16
/pci@1f,0: PCI IO[1fe02000000] MEM[1ff00000000]
/pci@1f,0: SABRE PCI Bus Module ver[0:0]
PCI: Scanning PBM /pci@1f,0
pci 0000:02:02.0: supports D1 D2
pci 0000:02:02.0: PME# supported from D0 D1 D2 D3hot
pci 0000:02:02.0: PME# disabled
bio: create slab <bio-0> at 0
vgaarb: device added: PCI:0000:01:02.0,decodes=io+mem,owns=none,locks=none
vgaarb: loaded
vgaarb: bridge control possible 0000:01:02.0
SCSI subsystem initialized
/pci@1f,0/pci@1,1/ebus@...eprom@14,0: Mostek regs at 0x1fff1000000
AUXIO: Found device at /pci@1f,0/pci@1,1/ebus@...uxio@14,726000
Switching to clocksource tick
NET: Registered protocol family 2
IP route cache hash table entries: 4096 (order: 2, 32768 bytes)
TCP established hash table entries: 16384 (order: 5, 262144 bytes)
TCP bind hash table entries: 16384 (order: 4, 131072 bytes)
TCP: Hash tables configured (established 16384 bind 16384)
TCP reno registered
UDP hash table entries: 256 (order: 0, 8192 bytes)
UDP-Lite hash table entries: 256 (order: 0, 8192 bytes)
NET: Registered protocol family 1
PCI: CLS 64 bytes, default 64
power: Control reg at 1fff1724000
VFS: Disk quotas dquot_6.5.2
Dquot-cache hash table entries: 1024 (order 0, 8192 bytes)
msgmni has been set to 993
Block layer SCSI generic (bsg) driver version 0.4 loaded (major 253)
io scheduler noop registered
io scheduler deadline registered
io scheduler cfq registered (default)
start plist test
end plist test
PCI: Enabling device: (0000:01:02.0), cmd 82
atyfb: 3D RAGE II+ (Mach64 GT) [0x4754 rev 0x9a]
atyfb: 2M SGRAM (1:1), 14.31818 MHz XTAL, 200 MHz PLL, 67 Mhz MCLK, 67 MHz XCLK
Console: switching to colour frame buffer device 80x30
atyfb: fb0: ATY Mach64 frame buffer device on PCI
/SUNW,ffb@1e,0: FFB at 000001fc00000000, type 51, DAC pnum[236e] rev[10] manuf_rev[1]
/pci@1f,0/pci@1,1/ebus@...u@14,3083f8: Keyboard port at 1fff13083f8, irq 6
/pci@1f,0/pci@1,1/ebus@...u@14,3062f8: Mouse port at 1fff13062f8, irq 7
f0061c64: ttyS0 at MMIO 0x1fff1400000 (irq = 5) is a SAB82532 V3.2
Console: ttyS0 (SAB82532)
console [ttyS0] enabled
f0061c64: ttyS1 at MMIO 0x1fff1400040 (irq = 5) is a SAB82532 V3.2
Floppy drive(s): fd0 is 1.44M
FDC 0 is a National Semiconductor PC87306
scsi0 : Adaptec AIC7XXX EISA/VLB/PCI SCSI HBA DRIVER, Rev 7.0
        <Adaptec 2940 Ultra SCSI adapter>
        aic7880: Ultra Wide Channel A, SCSI Id=7, 16/253 SCBs

scsi 0:0:0:0: Direct-Access     QUANTUM  ATLAS IV 18 WLS  0909 PQ: 0 ANSI: 3
scsi0:A:0:0: Tagged Queuing enabled.  Depth 253
scsi target0:0:0: Beginning Domain Validation
scsi target0:0:0: wide asynchronous
scsi target0:0:0: FAST-10 WIDE SCSI 20.0 MB/s ST (100 ns, offset 8)
scsi target0:0:0: Domain Validation skipping write tests
scsi target0:0:0: Ending Domain Validation
sd 0:0:0:0: [sda] 35885168 512-byte logical blocks: (18.3 GB/17.1 GiB)
PCI: Enabling device: (0000:01:01.1), cmd 2
sunhme.c:v3.10 August 26, 2008 David S. Miller (davem@...emloft.net)
eth0: HAPPY MEAL (PCI/CheerIO) 10/100BaseT Ethernet 08:00:20:9e:82:fd
sd 0:0:0:0: [sda] Write Protect is off
sd 0:0:0:0: [sda] Mode Sense: e3 00 10 08
mousedev: PS/2 mouse device common for all mice
sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, supports DPO and FUA
rtc-m48t59 rtc-m48t59.0: rtc core: registered m48t59 as rtc0
TCP cubic registered
NET: Registered protocol family 17
Registering the dns_resolver key type
registered taskstats version 1
rtc-m48t59 rtc-m48t59.0: setting system clock to 2011-11-21 11:41:58 UTC (1321875718)
 sda: sda1 sda2 sda3 sda4
sd 0:0:0:0: [sda] Attached SCSI disk
input: Sun Mouse as /devices/root/f005f9c0/f00601b4/f0061504/f0064df4/serio1/input/input0
EXT3-fs (sda4): error: couldn't mount because of unsupported optional features (240)
EXT2-fs (sda4): error: couldn't mount because of unsupported optional features (244)
EXT4-fs (sda4): INFO: recovery required on readonly filesystem
EXT4-fs (sda4): write access will be enabled during recovery
EXT4-fs (sda4): recovery complete
EXT4-fs (sda4): mounted filesystem with ordered data mode. Opts: (null)
VFS: Mounted root (ext4 filesystem) readonly on device 8:4.
8139too: 8139too Fast Ethernet driver 0.9.28
PCI: Enabling device: (0000:02:02.0), cmd 3
8139too 0000:02:02.0: eth1: RealTek RTL8139 at 0x1ff00020000, 00:10:b5:a3:e4:a6, IRQ 16
/pci@1f,0/pci@1,1/ebus@...lashprom@10,0: OBP Flash, RD 1fff0000000[100000] WR 1fff0000000[100000]
libata version 3.00 loaded.
scsi1 : pata_cmd64x
scsi2 : pata_cmd64x
ata1: PATA max MWDMA2 cmd 0x1fe02c00000 ctl 0x1fe02c00008 bmdma 0x1fe02c00020 irq 14
ata2: PATA max MWDMA2 cmd 0x1fe02c00010 ctl 0x1fe02c00018 bmdma 0x1fe02c00028 irq 14
pata_cmd64x: active 10 recovery 10 setup 3.
pata_cmd64x: active 10 recovery 10 setup 3.
pata_cmd64x: active 10 recovery 10 setup 3.
pata_cmd64x: active 10 recovery 10 setup 3.
Adding 1052248k swap on /dev/sda2.  Priority:-1 extents:1 across:1052248k 
EXT4-fs (sda4): re-mounted. Opts: (null)
eth0: Link is up using internal transceiver at 100Mb/s, Full Duplex.
eth0: no IPv6 routers present
kernel BUG at block/blk-core.c:1000!
              \|/ ____ \|/
              "@'/ .. \`@"
              /_| \__/ |_\
                 \__U_/
swapper(0): Kernel bad sw trap 5 [#1]
TSTATE: 0000008080e01602 TPC: 00000000005c3380 TNPC: 00000000005c3384 Y: 00000000    Not tainted
TPC: <blk_requeue_request+0x60/0x80>
g0: 0000000000000003 g1: 0000000000872000 g2: 0000000000000001 g3: ffffffffffffffd8
g4: 0000000000869b50 g5: f7ddef20f7767b60 g6: 0000000000860000 g7: 0000000000001000
o0: 0000000000000028 o1: 0000000000816440 o2: 00000000000003e8 o3: 00000000ffffa86e
o4: fffff8001f00cb60 o5: 0000000000816440 sp: fffff8001fefb471 ret_pc: 00000000005c3378
RPC: <blk_requeue_request+0x58/0x80>
l0: 00000000fffc00f0 l1: 0000000000883790 l2: 0000000000000001 l3: fffff8001f0060c0
l4: fffff8001f0020c0 l5: 0000000000000000 l6: 00000000008a2e18 l7: fffffffffffffff8
i0: fffff8001f2d3100 i1: fffff8001f329a20 i2: 000000000091d56c i3: 000000000091d400
i4: 0000000000000001 i5: 0000000000000000 i6: fffff8001fefb521 i7: 000000000065dc10
I7: <__scsi_queue_insert+0xb0/0x100>
Call Trace:
 [000000000065dc10] __scsi_queue_insert+0xb0/0x100
 [00000000005c812c] blk_done_softirq+0x6c/0xa0
 [000000000045a530] __do_softirq+0x90/0x120
 [000000000042b054] do_softirq+0x74/0xa0
 [000000000045a82c] irq_exit+0x8c/0xa0
 [000000000042af9c] handler_irq+0x9c/0xe0
 [00000000004208b4] tl0_irq5+0x14/0x20
 [0000000000439484] touch_nmi_watchdog+0x4/0x40
 [00000000008a8788] start_kernel+0x318/0x328
 [000000000074af88] tlb_fixup_done+0x80/0x88
 [0000000000000000]           (null)
Disabling lock debugging due to kernel taint
Caller[000000000065dc10]: __scsi_queue_insert+0xb0/0x100
Caller[00000000005c812c]: blk_done_softirq+0x6c/0xa0
Caller[000000000045a530]: __do_softirq+0x90/0x120
Caller[000000000042b054]: do_softirq+0x74/0xa0
Caller[000000000045a82c]: irq_exit+0x8c/0xa0
Caller[000000000042af9c]: handler_irq+0x9c/0xe0
Caller[00000000004208b4]: tl0_irq5+0x14/0x20
Caller[000000000042b828]: cpu_idle+0x28/0x60
Caller[00000000008a8788]: start_kernel+0x318/0x328
Caller[000000000074af88]: tlb_fixup_done+0x80/0x88
Caller[0000000000000000]:           (null)
Instruction DUMP: 921023e8  7ff99282  90122040 <91d02005> 30680007  01000000  01000000  01000000  01000000 
Kernel panic - not syncing: Aiee, killing interrupt handler!
Call Trace:
 [00000000004586ec] do_exit+0x2cc/0x300
 [00000000004274c0] die_if_kernel+0x180/0x260
 [00000000004282d4] bad_trap+0xd4/0xe0
 [00000000004220b0] tl0_resv104+0x30/0xa0
 [00000000005c3380] blk_requeue_request+0x60/0x80
 [000000000065dc10] __scsi_queue_insert+0xb0/0x100
 [00000000005c812c] blk_done_softirq+0x6c/0xa0
 [000000000045a530] __do_softirq+0x90/0x120
 [000000000042b054] do_softirq+0x74/0xa0
 [000000000045a82c] irq_exit+0x8c/0xa0
 [000000000042af9c] handler_irq+0x9c/0xe0
 [00000000004208b4] tl0_irq5+0x14/0x20
 [0000000000439484] touch_nmi_watchdog+0x4/0x40
 [00000000008a8788] start_kernel+0x318/0x328
 [000000000074af88] tlb_fixup_done+0x80/0x88
 [0000000000000000]           (null)
Press Stop-A (L1-A) to return to the boot prom

-- 
Meelis Roos (mroos@...ux.ee)
--
To unsubscribe from this list: send the line "unsubscribe linux-kernel" in
the body of a message to majordomo@...r.kernel.org
More majordomo info at  http://vger.kernel.org/majordomo-info.html
Please read the FAQ at  http://www.tux.org/lkml/

Powered by blists - more mailing lists