From mboxrd@z Thu Jan 1 00:00:00 1970 Return-Path: Received: (majordomo@vger.kernel.org) by vger.kernel.org via listexpand id S1750729AbVJ1XTa (ORCPT ); Fri, 28 Oct 2005 19:19:30 -0400 Received: (majordomo@vger.kernel.org) by vger.kernel.org id S1750722AbVJ1XTa (ORCPT ); Fri, 28 Oct 2005 19:19:30 -0400 Received: from pat.qlogic.com ([198.70.193.2]:27258 "EHLO avexch01.qlogic.com") by vger.kernel.org with ESMTP id S1750729AbVJ1XT3 (ORCPT ); Fri, 28 Oct 2005 19:19:29 -0400 Date: Fri, 28 Oct 2005 16:19:24 -0700 From: Andrew Vasquez To: Linux Kernel Mailing List Subject: Badness in as_del_arq_rb at drivers/block/as-iosched.c:402 Message-ID: <20051028231924.GJ15018@plap.qlogic.org> Mime-Version: 1.0 Content-Type: text/plain; charset=us-ascii Content-Disposition: inline Organization: QLogic Corporation User-Agent: Mutt/1.5.9i X-OriginalArrivalTime: 28 Oct 2005 23:19:24.0563 (UTC) FILETIME=[08D70E30:01C5DC16] Sender: linux-kernel-owner@vger.kernel.org X-Mailing-List: linux-kernel@vger.kernel.org There seems to be some problems with the latest linux-2.6.git tree where running some basic I/O with DT against some FC storage, yields numerous badness warnings, null pointer dereferencing and subsequent hangs. I'm trying to track it down now, but it seems to be related to the recent merge: Merge branch 'generic-dispatch' of git://brick.kernel.dk/data/git/linux-2.6-block 28d721e24c88496ff8e9c4a0959bdc1415c0658e Here's the log captured via serial ------------------------- 8< ---------------------------- Badness in as_del_arq_rb at drivers/block/as-iosched.c:402 Call Trace:{as_merged_request+172} {elv_merged_request+38} {__make_request+804} {generic_make_request+382} {submit_bio+216} {dio_bio_submit+107} {__blockdev_direct_IO+2601} {_read_unlock_irq+9} {blkdev_direct_IO+69} {blkdev_get_blocks+0} {generic_file_direct_IO+188} {__generic_file_aio_read+228} {generic_file_read+187} {_spin_lock_irqsave+14} {do_page_fault+1113} {vma_link+133} {autoremove_wake_function+0} {vfs_read+197} {sys_read+83} {system_call+126} Badness in as_activate_request at drivers/block/as-iosched.c:1413 Call Trace:{as_activate_request+49} {elv_next_request+56} {scsi_request_fn+136} {as_work_handler+0} {as_work_handler+53} {worker_thread+476} {default_wake_function+0} {default_wake_function+0} {keventd_create_kthread+0} {worker_thread+0} {keventd_create_kthread+0} {kthread+217} {child_rip+8} {keventd_create_kthread+0} {kthread+0} {child_rip+0} Badness in as_move_to_dispatch at drivers/block/as-iosched.c:1134 Call Trace: {as_move_to_dispatch+349} {as_dispatch_request+1046} {elv_next_request+307} {scsi_request_fn+136} {blk_run_queue+61} {scsi_end_request+207} {scsi_io_completion+617} {sd_rw_intr+737} {scsi_finish_command+198} {scsi_softirq+380} {__do_softirq+107} {call_softirq+31} {do_softirq+49} {apic_timer_interrupt+132} {_spin_unlock_irq+14} {__make_request+1280} {generic_make_request+382} {submit_bio+216} {dio_bio_submit+107} {__blockdev_direct_IO+2601} {_read_unlock_irq+9} {blkdev_direct_IO+69} {blkdev_get_blocks+0} {generic_file_direct_IO+188} {__generic_file_aio_read+228} {generic_file_read+187} {_spin_lock_irqsave+14} {do_page_fault+1113} {vma_link+133} {autoremove_wake_function+0} {vfs_read+197} {sys_read+83} {system_call+126} Badness in as_move_to_dispatch at drivers/block/as-iosched.c:1134 Call Trace: {as_move_to_dispatch+349} {as_dispatch_request+1046} {elv_next_request+307} {scsi_request_fn+136} {blk_run_queue+61} {scsi_end_request+207} {scsi_io_completion+617} {sd_rw_intr+737} {scsi_finish_command+198} {scsi_softirq+380} {__do_softirq+107} {call_softirq+31} {do_softirq+49} {apic_timer_interrupt+132} {_spin_unlock_irq+14} {__make_request+1280} {generic_make_request+382} {submit_bio+216} {dio_bio_submit+107} {__blockdev_direct_IO+2601} {_read_unlock_irq+9} {blkdev_direct_IO+69} {blkdev_get_blocks+0} {generic_file_direct_IO+188} {__generic_file_aio_read+228} {generic_file_read+187} {_spin_lock_irqsave+14} {do_page_fault+1113} {vma_link+133} {autoremove_wake_function+0} {vfs_read+197} {sys_read+83} {system_call+126} Badness in as_move_to_dispatch at drivers/block/as-iosched.c:1134 Call Trace: {as_move_to_dispatch+349} {as_dispatch_request+1046} {elv_next_request+307} {scsi_request_fn+136} {blk_run_queue+61} {scsi_end_request+207} {scsi_io_completion+617} {sd_rw_intr+737} {scsi_finish_command+198} {scsi_softirq+380} {__do_softirq+107} {call_softirq+31} {do_softirq+49} {apic_timer_interrupt+132} {_spin_unlock_irq+14} {__make_request+1280} {generic_make_request+382} {submit_bio+216} {dio_bio_submit+107} {__blockdev_direct_IO+2601} {_read_unlock_irq+9} {blkdev_direct_IO+69} {blkdev_get_blocks+0} {generic_file_direct_IO+188} {__generic_file_aio_read+228} {generic_file_read+187} {_spin_lock_irqsave+14} {do_page_fault+1113} {vma_link+133} {autoremove_wake_function+0} {vfs_read+197} {sys_read+83} {system_call+126} Badness in as_move_to_dispatch at drivers/block/as-iosched.c:1134 Call Trace: {as_move_to_dispatch+349} {as_dispatch_request+1046} {elv_next_request+307} {scsi_request_fn+136} {blk_run_queue+61} {scsi_end_request+207} {scsi_io_completion+617} {sd_rw_intr+737} {scsi_finish_command+198} {scsi_softirq+380} {__do_softirq+107} {call_softirq+31} {do_softirq+49} {apic_timer_interrupt+132} {_spin_unlock_irq+14} {__make_request+1280} {generic_make_request+382} {submit_bio+216} {dio_bio_submit+107} {__blockdev_direct_IO+2601} {_read_unlock_irq+9} {blkdev_direct_IO+69} {blkdev_get_blocks+0} {generic_file_direct_IO+188} {__generic_file_aio_read+228} {generic_file_read+187} {_spin_lock_irqsave+14} {do_page_fault+1113} {vma_link+133} {autoremove_wake_function+0} {vfs_read+197} {sys_read+83} {system_call+126} Badness in as_move_to_dispatch at drivers/block/as-iosched.c:1134 Call Trace: {as_move_to_dispatch+349} {as_dispatch_request+1046} {elv_next_request+307} {scsi_request_fn+136} {blk_run_queue+61} {scsi_end_request+207} {scsi_io_completion+617} {sd_rw_intr+737} {scsi_finish_command+198} {scsi_softirq+380} {__do_softirq+107} {call_softirq+31} {do_softirq+49} {apic_timer_interrupt+132} {_spin_unlock_irq+14} {__make_request+1280} {generic_make_request+382} {submit_bio+216} {dio_bio_submit+107} {__blockdev_direct_IO+2601} {_read_unlock_irq+9} {blkdev_direct_IO+69} {blkdev_get_blocks+0} {generic_file_direct_IO+188} {__generic_file_aio_read+228} {generic_file_read+187} {_spin_lock_irqsave+14} {do_page_fault+1113} {vma_link+133} {autoremove_wake_function+0} {vfs_read+197} {sys_read+83} {system_call+126} Badness in as_move_to_dispatch at drivers/block/as-iosched.c:1134 Call Trace: {as_move_to_dispatch+349} {as_dispatch_request+1046} {elv_next_request+307} {scsi_request_fn+136} {blk_run_queue+61} {scsi_end_request+207} {scsi_io_completion+617} {sd_rw_intr+737} {scsi_finish_command+198} {scsi_softirq+380} {__do_softirq+107} {call_softirq+31} {do_softirq+49} {apic_timer_interrupt+132} {_spin_unlock_irq+14} {__make_request+1280} {generic_make_request+382} {submit_bio+216} {dio_bio_submit+107} {__blockdev_direct_IO+2601} {_read_unlock_irq+9} {blkdev_direct_IO+69} {blkdev_get_blocks+0} {generic_file_direct_IO+188} {__generic_file_aio_read+228} {generic_file_read+187} {_spin_lock_irqsave+14} {do_page_fault+1113} {vma_link+133} {autoremove_wake_function+0} {vfs_read+197} {sys_read+83} {system_call+126} Badness in as_move_to_dispatch at drivers/block/as-iosched.c:1134 Call Trace: {as_move_to_dispatch+349} {as_dispatch_request+1046} {elv_next_request+307} {scsi_request_fn+136} {blk_run_queue+61} {scsi_end_request+207} {scsi_io_completion+617} {sd_rw_intr+737} {scsi_finish_command+198} {scsi_softirq+380} {__do_softirq+107} {call_softirq+31} {do_softirq+49} {apic_timer_interrupt+132} {_spin_unlock_irq+14} {__make_request+1280} {generic_make_request+382} {submit_bio+216} {dio_bio_submit+107} {__blockdev_direct_IO+2601} {_read_unlock_irq+9} {blkdev_direct_IO+69} {blkdev_get_blocks+0} {generic_file_direct_IO+188} {__generic_file_aio_read+228} {generic_file_read+187} {_spin_lock_irqsave+14} {do_page_fault+1113} {vma_link+133} {autoremove_wake_function+0} {vfs_read+197} {sys_read+83} {system_call+126} Badness in as_move_to_dispatch at drivers/block/as-iosched.c:1134 Call Trace: {as_move_to_dispatch+349} {as_dispatch_request+1046} {elv_next_request+307} {scsi_request_fn+136} {blk_run_queue+61} {scsi_end_request+207} {scsi_io_completion+617} {sd_rw_intr+737} {scsi_finish_command+198} {scsi_softirq+380} {__do_softirq+107} {call_softirq+31} {do_softirq+49} {apic_timer_interrupt+132} {_spin_unlock_irq+14} {__make_request+1280} {generic_make_request+382} {submit_bio+216} {dio_bio_submit+107} {__blockdev_direct_IO+2601} {_read_unlock_irq+9} {blkdev_direct_IO+69} {blkdev_get_blocks+0} {generic_file_direct_IO+188} {__generic_file_aio_read+228} {generic_file_read+187} {_spin_lock_irqsave+14} {do_page_fault+1113} {vma_link+133} {autoremove_wake_function+0} {vfs_read+197} {sys_read+83} {system_call+126} Badness in as_move_to_dispatch at drivers/block/as-iosched.c:1134 Call Trace: {as_move_to_dispatch+349} {as_dispatch_request+1046} {elv_next_request+307} {scsi_request_fn+136} {blk_run_queue+61} {scsi_end_request+207} {scsi_io_completion+617} {sd_rw_intr+737} {scsi_finish_command+198} {scsi_softirq+380} {__do_softirq+107} {call_softirq+31} {do_softirq+49} {apic_timer_interrupt+132} {_spin_unlock_irq+14} {__make_request+1280} {generic_make_request+382} {submit_bio+216} {dio_bio_submit+107} {__blockdev_direct_IO+2601} {_read_unlock_irq+9} {blkdev_direct_IO+69} {blkdev_get_blocks+0} {generic_file_direct_IO+188} {__generic_file_aio_read+228} {generic_file_read+187} {_spin_lock_irqsave+14} {do_page_fault+1113} {vma_link+133} {autoremove_wake_function+0} {vfs_read+197} {sys_read+83} {system_call+126} Badness in as_move_to_dispatch at drivers/block/as-iosched.c:1134 Call Trace: {as_move_to_dispatch+349} {as_dispatch_request+1046} {elv_next_request+307} {scsi_request_fn+136} {blk_run_queue+61} {scsi_end_request+207} {scsi_io_completion+617} {sd_rw_intr+737} {scsi_finish_command+198} {scsi_softirq+380} {__do_softirq+107} {call_softirq+31} {do_softirq+49} {apic_timer_interrupt+132} {_spin_unlock_irq+14} {__make_request+1280} {generic_make_request+382} {submit_bio+216} {dio_bio_submit+107} {__blockdev_direct_IO+2601} {_read_unlock_irq+9} {blkdev_direct_IO+69} {blkdev_get_blocks+0} {generic_file_direct_IO+188} {__generic_file_aio_read+228} {generic_file_read+187} {_spin_lock_irqsave+14} {do_page_fault+1113} {vma_link+133} {autoremove_wake_function+0} {vfs_read+197} {sys_read+83} {system_call+126} Unable to handle kernel NULL pointer dereference at 0000000000000030 RIP: {as_move_to_dispatch+283} PGD 37b14067 PUD 37dfb067 PMD 0 Oops: 0000 [1] SMP CPU 0 Modules linked in: qla2xxx scsi_transport_fc Pid: 10944, comm: dt Not tainted 2.6.14 #13 RIP: 0010:[] {as_move_to_dispatch+283} RSP: 0018:ffffffff80453268 EFLAGS: 00010002 RAX: ffff81003684ea90 RBX: 0000000000000000 RCX: ffff81002d45ce60 RDX: ffff81003fe93050 RSI: 0000000000000092 RDI: ffffffff803e8d10 RBP: ffff81002e63e720 R08: 0000000000000000 R09: 0000000000000000 R10: 00000000ffffffff R11: 0000000000000000 R12: ffff81002c1f60f0 R13: ffff81003fbfe6c0 R14: 0000000000000001 R15: ffff81002e5e7778 FS: 00002aaaab2890a0(0000) GS:ffffffff8048e800(0000) knlGS:0000000000000000 CS: 0010 DS: 0000 ES: 0000 CR0: 000000008005003b CR2: 0000000000000030 CR3: 0000000028470000 CR4: 00000000000006e0 Process dt (pid: 10944, threadinfo ffff810035940000, task ffff810032898ae0) Stack: ffff81002e63e720 0000000000000001 0000000000000000 ffff81003fbfe6c0 ffff81003dce2638 ffffffff802650d6 0000000000000292 0000000000000000 0000000000000000 ffff81003fe93050 Call Trace: {as_dispatch_request+1046} {elv_next_request+307} {scsi_request_fn+136} {blk_run_queue+61} {scsi_end_request+207} {scsi_io_completion+617} {sd_rw_intr+737} {scsi_finish_command+198} {scsi_softirq+380} {__do_softirq+107} {call_softirq+31} {do_softirq+49} {apic_timer_interrupt+132} {_spin_unlock_irq+14} {__make_request+1280} {generic_make_request+382} {submit_bio+216} {dio_bio_submit+107} {__blockdev_direct_IO+2601} {_read_unlock_irq+9} {blkdev_direct_IO+69} {blkdev_get_blocks+0} {generic_file_direct_IO+188} {__generic_file_aio_read+228} {generic_file_read+187} {_spin_lock_irqsave+14} {do_page_fault+1113} {vma_link+133} {autoremove_wake_function+0} {vfs_read+197} {sys_read+83} {system_call+126} Code: 48 8b 43 30 48 85 c0 74 0d 48 8b 40 28 48 85 c0 74 04 f0 ff RIP {as_move_to_dispatch+283} RSP CR2: 0000000000000030 <3>Debug: sleeping function called from invalid context at include/linux/rwsem.h:43 in_atomic():1, irqs_disabled():1 Call Trace: {__might_sleep+199} {profile_task_exit+41} {do_exit+34} {do_unblank_screen+53} {do_page_fault+1830} {printk+141} {activate_task+149} {error_exit+0} {as_move_to_dispatch+283} {as_move_to_dispatch+349} {as_dispatch_request+1046} {elv_next_request+307} {scsi_request_fn+136} {blk_run_queue+61} {scsi_end_request+207} {scsi_io_completion+617} {sd_rw_intr+737} {scsi_finish_command+198} {scsi_softirq+380} {__do_softirq+107} {call_softirq+31} {do_softirq+49} {apic_timer_interrupt+132} {_spin_unlock_irq+14} {__make_request+1280} {generic_make_request+382} {submit_bio+216} {dio_bio_submit+107} {__blockdev_direct_IO+2601} {_read_unlock_irq+9} {blkdev_direct_IO+69} {blkdev_get_blocks+0} {generic_file_direct_IO+188} {__generic_file_aio_read+228} {generic_file_read+187} {_spin_lock_irqsave+14} {do_page_fault+1113} {vma_link+133} {autoremove_wake_function+0} {vfs_read+197} {sys_read+83} {system_call+126} Kernel panic - not syncing: Aiee, killing interrupt handler! BUG: spinlock lockup on CPU#1, kblockd/1/124, ffff81003fe93260 Call Trace:{_raw_spin_lock+235} {scsi_request_fn+754} {as_work_handler+0} {as_work_handler+53} {worker_thread+476} {default_wake_function+0} {default_wake_function+0} {keventd_create_kthread+0} {worker_thread+0} {keventd_create_kthread+0} {kthread+217} {child_rip+8} {keventd_create_kthread+0} {kthread+0} {child_rip+0}