Subject: [BUG] linux-next: Tree for May 29 - WARNING: at kernel/lockdep.c:2680

From: Kamalesh Babulal <[email-masked]>
Date: Fri, 30 May 2008 01:51:07 +0530

Hi Stephen,

next-20080529 kernel panic's, while booting up on the x86 box. Sorry the
boot log is corrupted towards logs end. The .config file has been attached.

Loading mptbase.ko module[    6.132900] Fusion MPT base driver 3.04.06

[    6.163544] Copyright (c) 1999-2007 LSI Corporation
Loading mptscsih.ko module
Loading mptspi.ko module
[    6.206134] Fusion MPT SPI Host driver 3.04.06
[    6.231689] ACPI: PCI Interrupt 0000:01:01.0[A] -> GSI 22 (level, low) -> IRQ 22
[    6.270223] mptbase: ioc0: Initiating bringup
[    6.415091] ioc0: LSI53C1030 B2: Capabilities={Initiator}
[    6.436423] mptbase: ioc0: PCI-MSI enabled
[    7.516395] ------------[ cut here ]------------
[    7.529596] WARNING: at kernel/lockdep.c:2680 check_flags+0x8a/0x12d()
[    7.529596] Modules linked in: mptspi(+) mptscsih mptbase scsi_transport_spi sd_mod scsi_mod ext3 jbd ehci_hcd ohci_hcd uhci_hcd
[    7.529596] Pid: 5, comm: watchdog/0 Not tainted 2.6.26-rc4-next-20080529-autotest #1
[    7.529596]  [<c0425477>] warn_on_slowpath+0x41/0x6b
[    7.529596]  [<c04405ef>] __lock_acquire+0xab7/0xafb
[    7.529596]  [<c043d9b1>] trace_hardirqs_off+0xb/0xd
[    7.529596]  [<c0408361>] native_sched_clock+0x84/0x96
[    7.529596]  [<c043f783>] trace_hardirqs_on+0xb/0xd
[    7.529596]  [<c043f750>] trace_hardirqs_on_caller+0xe9/0x111
[    7.529596]  [<c043f783>] trace_hardirqs_on+0xb/0xd
[    7.529596]  [<c063b4b3>] _spin_unlock_irq+0x22/0x26
[    7.529596]  [<c042029f>] finish_task_switch+0x47/0x9b
[    7.529596]  [<c043d61e>] check_flags+0x8a/0x12d
[    7.529596]  [<c044066e>] lock_acquire+0x3b/0x89
[    7.529596]  [<c063b38a>] _read_lock+0x1c/0x49
[    7.529596]  [<c0452652>] watchdog+0x97/0x1a9
[    7.529596]  [<c04525bb>] watchdog+0x0/0x1a9
[    7.529596]  [<c0452652>] watchdog+0x97/0x1a9
[    7.529596]  [<c04525bb>] watchdog+0x0/0x1a9
[    7.529596]  [<c0435670>] kthread+0x3b/0x63
[    7.529596]  [<c0435635>] kthread+0x0/0x63
[    7.529596]  [<c04045ab>] kernel_thread_helper+0x7/0x10
[    7.529596]  =======================
[    7.529596] ---[ end trace 542de2eb2a474f99 ]---
[    7.529596] possible reason: unannotated irqs-on.
[    7.529596] irq event stamp: 14
[    7.529596] hardirqs last  enabled at (13): [<c043f783>] trace_hardirqs_on+0xb/0xd
[    7.529596] hardirqs last disabled at (14): [<c043d9b1>] trace_hardirqs_off+0xb/0xd
[    7.529596] softirqs last  enabled at (0): [<c0423adf>] copy_process+0x30a/0x104b
[    7.529596] softirqs last disabled at (0): [<00000000>] 0x0
[   17.650260] mptbase: ioc0: Initiating recovery
[   17.674685] mptbase: ioc0: WARNING - IOC is in FAULT state!!!
[   17.706024] mptbase: ioc0: WARNING -            FAULT code = 8112h
[   22.752375] mptbase: ioc0: ERROR - Doorbell ACK timeout (count=4999), IntStatus=80000009!
[   40.049898] mptbase: ioc0: Recovered from IOC FAULT
[   40.151060] Clocksource tsc unstable (delta = 18746945381 ns)
[   40.184577] BUG: unable to handle kernel NULL pointer dereference at 00000538
[   40.218281] IP: [<f88a8d5a>] :mptspi:mptspi_dv_renegotiate_work+0xc/0xab
[   40.253594] *pde = 00000000 
[   40.268768] Oops: 0000 [#1] SMP 
[   40.275753] last sysfs file: /sys/block/ram15/dev
[   40.275753] Modules linked in: mptspi(+) mptscsih mptbase scsi_transport_spi sd_mod scsi_mod ext3 jbd ehci_hcd ohci_hcd uhci_hcd
[   40.275753] 
[   40.275753] Pid: 15, comm: events/0 Tainted: G        W (2.6.26-rc4-next-20080529-autotest #1)
[   40.275753] EIP: 0060:[<f88a8d5a>] EFLAGS: 00010282 CPU: 0
[   40.275753] EIP is at mptspi_dv_renegotiate_work+0xc/0xab [mptspi]
[   40.275753] EAX: f6bae290 EBX: f6bae294 ECX: 00000000 EDX: 00000000
[   40.275753] ESI: f7c66da0 EDI: 00000538 EBP: f7c9bf78 ESP: f7c9bf58
[   40.275753]  DS: 007b ES: 007b FS: 00d8 GS: 0000 SS: 0068
[   40.275753] Process events/0 (pid: 15, ti=f7c9b000 task=f7c9a020 task.ti=f7c9b000)
[   40.275753] Stack: 00000000 00000002 00000000 c043290d f7c9bf90 f6bae294 f7c66da0 f6bae290 
[   40.275753]        f7c9bfac c0432948 00000000 00000002 c043290d f88a8d4e 5a5a5a5a 5a5a5a5a 
[   40.275753]        5a5a5a5a 5a5a5a5a f7c66da0 c04331ad f7c66dc8 f7c9bfd0 c0433261 00000000 
[   40.275753] Call Trace:
[   40.275753]  [<c043290d>] run_workqueue+0x80/0x186
[   40.275753]  [<c0432948>] run_workqueue+0xbb/0x186
[   40.275753]  [<c043290d>] run_workqueue+0x80/0x186
[   40.275753]  [<f88a8d4e>] mptspi_dv_renegotiate_work+0x0/0xab [mptspi]
[   40.275753]  [<c04331ad>] worker_thread+0x0/0xbf
[   40.275753]  [<c0433261>] worker_thread+0xb4/0xbf
[   40.275753]  [<c0435731>] autoremove_wake_function+0x0/0x33
[   40.275753]  [<c0435670>] kthread+0x3b/0x63
[   40.275753]  [<c0435635>] kthread+0x0/0x63
[   40.275753]  [<c04045ab>] kernel_thread_helper+0x7/0x10
[   40.275753]  =======================
[   40.275753] Code: ff 8b 87 8c 00 00 00 e8 3e 48 f7 ff 8b 87 8c 00 00 00 e8 0c f8 ff ff 8d 65 f4 5b 5e 5f 5d c3 55 89 e5 57 56 53 83 ec 14 8b 78 20 <8b> 17 89 55 e0 e8 26 cf bc c7 8b 55 e0 66 83 bf b2 02 00 00 00 
[   40.275753] EIP: [<f88a8d5a>] mptspi_dv_renegotiate_work+0xc/0xab [mptspi] SS:ESP 0068:f7c9bf58
[   41.355242] ---[ end trace 542de2eb2a474f99 ]---
[   51.376047] mptbase: ioc0: Initiating recovery
[   51.400492] mptbase: ioc0: WARNING - IOC is in FAULT state!!!
[   51.431824] mptbase: ioc0: WARNING -            FAULT code = 8112h
[   56.477758] mptbase: ioc0: ERROR - Doorbell ACK timeout (count=4999), IntStatus=80000009!
[   73.775291] mptbase: ioc0: Recovered from IOC FAULT
.
.
<snip>
.
.
[  236.349632] scsi0 : ioc0: LSI53C1030 B2, FwRev=01032715h, Ports=1, MaxQ=222, IRQ=223
[  236.394123] kobject: 'host0' (f6b3078c): kobject_add_internal: parent: '0000:01:01.0', set: 'devices'
[  236.447081] kobject: 'host0' (f6b3078c): kobject_uevent_env
[  236.477923] kobject: 'host0' (f6b3078c): kobject_uevent_env: filter function caused the event to drop!
[  236.530988] kobject: 'host0' (f6b30968): kobject_add_internal: parent: 'scsi_host', set: 'devices'
[  236.582093] kobject: 'host0' (f6b30968): kobject_uevent_env
[  236.613015] kobject: 'host0' (f6b30968): fill_kobj_path: path = '/class/scsi_host/host0'
[  236.659466] kobject: '0000:01:01.0' (f7e2b394): fill_kobj_path: path = '/devices/pci0000:01/0000:01:01.0'
[  236.714488]  target0:0:0: mptspi: ioc0: dma_alloc_coherent for parameters failed
[  242.253546] mptscsih: ioc0: attempting task abort! (sc=f7e79ca8)
[  242.286877] scsi 0:0:0:0: CDB: Inquiry: 12 00 00 00 24 00
[  242.310827] mptscsih: ioc0: WARNING - TM Handler for type=1: IOC Not operational (0x40008112)!
[  242.360803] mptscsih: ioc0: WARNING -  Issuing HardReset!!
[  242.391363] mptbase: ioc0: Initiating recovery
[  242.415801] mptbase: ioc0: WARNING - IOC is in FAULT state!!!
[  242.448134] mptbase: ioc0: WARNING -            FAULT code = 8112h
[  252.476243] mptbase: ioc0: ERROR - Doorbell ACK timeout (count=4999), IntStatus=80000009!
[  253.625175] scsi 0:0:0:0: mptscsih: ioc0: completing cmds: fw_channel 0, fw_id 0, sc=f7e79ca8, mf = f6ec2da0, idx=f
[  269.871450] mptbase: ioc0: Recovered from IOC FAULT
[  270.043869] mptscsih: ioc0: task abort: FAILED (sc=f7e79ca8)
[  270.074282] mptscsih: ioc0: attempting target reset! (sc=f7e79ca8)
[  270.108916] scsi 0:0:0:0: CDB: Inquiry: 12 00 00 00 24 00
[  270.132248] mptscsih: ioc0: WARNING - TM Handler for type=3: IOC Not operational (0x40008112)!
[  270.181896] mptscsih: ioc0: WARNING -  Issuing HardReset!!
[  270.212463] mptbase: ioc0: Initiating recovery
[  270.235896] mptbase: ioc0: WARNING - IOC is in FAULT state!!!
[  270.268232] mptbase: ioc0: WARNING -            FAULT code = 8112h
[  280.295765] mptbase: ioc0: ERROR - Doorbell ACK timeout (count=4999), IntStatus=80000009!
[  297.630725] mptbase: ioc0: Recovered from IOC FAULT
[  297.803112] mptscsih: ioc0: target reset: FAILED (sc=f7e79ca8)
[  297.835050] mptscsih: ioc0: attempting bus reset! (sc=f7e79ca8)
[  297.867905] scsi 0:0:0:0: CDB: Inquiry: 12 00 00 00 24 00
[  297.891758] mptscsih: ioc0: WARNING - TM Handler for type=4: IOC Not operational (0x40008112)!
[  297.941418] mptscsih: ioc0: WARNING -  Issuing HardReset!!
[  297.971980] mptbase: ioc0: Initiating recovery
[  297.996412] mptbase: ioc0: WARNING - IOC is in FAULT state!!!
[  298.027751] mptbase: ioc0: WARNING -            FAULT code = 8112h
[  308.055539] mptbase: ioc0: ERROR - Doorbell ACK timeout (count=4999), IntStatus=80000009!
[  325.388372] mptbase: ioc0: Recovered from IOC FAULT
[  325.560765] mptscsih: ioc0: bus reset: FAILED (sc=f7e79ca8)
[  325.590916] mptscsih: ioc0: attempting host reset! (sc=f7e79ca8)
[  325.624031] mptbase: ioc0: Initiating recovery
[  325.648465] mptbase: ioc0: WARNING - IOC is in FAULT state!!!
[  325.680800] mptbase: ioc0: WARNING -            FAULT code = 8112h
[  335.708909] mptbase: ioc0: ERROR - Doorbell ACK timeout (count=4999), IntStatus=80000009!
[  353.036168] mptbase: ioc0: Recovered from IOC FAULT
[  353.208563] mptscsih: ioc0: host reset: SUCCESS (sc=f7e79ca8)
[  353.240241] scsi 0:0:0:0: Device offlined - not ready after error recovery
[  353.261232] kobject: '<NULL>' (f6be0e8c): kobject_cleanup
[  353.294958] kobject: '<NULL>' (f6be0e8c): calling ktype release
[  353.312967] kobject: '<NULL>' (f6b5e980): kobject_cleanup
[  353.329235] kobject: '<NULL>' (f6b5e980): calling ktype release
[  353.347120] kobject: '<NULL>' (f6bac3e4): kobject_cleanup
[  353.363396] kobject: '<NULL>' (f6bac3e4): calling ktype release
[  353.381300] kobject: '<NULL>' (f6a78e84): kobject_cleanup
[  353.397588] kobject: '<NULL>' (f6a78e84): calling ktype release
[  353.415441]  target0:0:1: mptspi: ioc0: dma_alloc_coherent for parameters failed
[  358.936063] mptscsih: ioc0: attempting task abort! (sc=f7e79e68)
[  358.954189] scsi 0:0:1:0: CDB: Inquiry: 12 00 00 00 24 00
[  358.971530] mptscsih: ioc0: WARNING - TM Handler for type=1: IOC Not operational (0x40008112)!
[  358.997495] mptscsih: ioc0: WARNING -  Issuing HardReset!!
[  359.014418] mptbase: ioc0: Initiating recovery
[  359.032452] mptbase: ioc0: WARNING - IOC is in FAULT state!!!
[  359.053792] mptbase: ioc0: WARNING -            FAULT code = 8112h
[  369.065916] mptbase: ioc0: ERROR - Doorbell ACK timeout (count=4999), IntStatus=80000009!
[  370.193471] scsi 0:0:1:0: mptscsih: ioc0: completing cmds: fw_channel 0, fw_id 1, sc=f7e79e68, mf = f6ec2f80, idx=14
[  386.405797] mptbase: ioc0: Recovered from IOC FAULT
[  386.566751] mptscsih: ioc0: task abort: FAILED (sc=f7e79e68)
[  386.583818] mptscsih: ioc0: attempting target reset! (sc=f7e79e68)
[  386.610732] scsi 0:0:1:0: CDB: Inquiry: 12 00 00 00 24 00
[  386.628263] mptscsih: ioc0: WARNING - TM Handler for type=3: IOC Not operational (0x40008112)!
[  386.655503] mptscsih: ioc0: WARNING -  Issuing HardReset!!
[  386.675072] mptbase: ioc0: Initiating recovery
[  386.689071] mptbase: ioc0: WARNING - IOC is in FAULT state!!!
[  386.710410] mptbase: ioc0: WARNING -            FAULT code = 8112h
[  386.729477]  target0:0:1: mptspi: ioc0: dma_alloc_coherent for parameters failed
[  386.751814]  target0:0:1: asynchronous
[  386.763474]  target0:0:1: mptspi: ioc0: dma_alloc_coherent for parameters failed
[  396.742618] mptbase: ioc0: ERROR - Doorbell ACK timeout (count=4999), IntStatus=80000009!
.
.
<snip>
.
.
[ 1983.231993] scsi 0:0:15:0: Device offlined - not ready after error recovery
[ 1983.252983] kobject: '<NULL>' (f6be0e8c): kobject_cleanup
[ 1983.287195] kobject: '<NULL>' (f6be0e8c): calling ktype release
[ 1983.305219] kobject: '<NULL>' (f6b5e980): kobject_cleanup
[ 1983.321503] kobject: '<NULL>' (f6b5e980): calling ktype release
[ 1983.339381] kobject: '<NULL>' (f6bac3e4): kobject_cleanup
[ 1983.356234] kobject: '<NULL>' (f6bac3e4): calling ktype release
[ 1983.373558] kobject: '<NULL>' (f6a78e84): kobject_cleanup
[ 1983.390394] kobject: '<NULL>' (f6a78e84): calling ktype release
[ 1983.408322] kobject: '<NULL>' (f6be0e8c): kobject_cleanup
[ 1983.424580] kobject: '<NULL>' (f6be0e8c): calling ktype release
[ 1983.442427] kobject: '<NULL>' (f6b5e980): kobject_cleanup
[ 1983.458696] kobject: '<NULL>' (f6b5e980): calling ktype release
[ 1983.476534] kobject: '<NULL>' (f6bac3e4): kobject_cleanup
[ 1983.492814] kobject: '<NULL>' (f6bac3e4): calling ktype release
[ 1983.510670] kobject: '<NULL>' (f6a78e84): kobject_cleanup
[ 1983.527131] kobject: '<NULL>' (f6a78e84): calling ktype release
[ 1983.545309] kobject: '<NULL>' (f6be0e8c): kobject_cleanup
.
.
<snip>
.
.
[ 1985.400907] kobject: '<NULL>' (f6bac3e4): kobject_cleanup
[ 1985.417185] kobject: '<NULL>' (f6bac3e4): calling ktype release
[ 1985.435063] kobject: '<NULL>' (f6a78e84): kobject_cleanup
[ 1985.451350] kobject: '<NULL>' (f6a78e84): calling ktype release
[ 1985.469240] kobject: 'drivers' (f6a66600): kobject_add_internal: parent: 'mptspi', set: '<NULL>'
[ 1985.495739] kobject: 'mptspi' (f6b3a3a8): kobject_uevent_env
[ 1985.513041] kobject: 'mptspi' (f6b3a3a8): fill_kobj_path: path = '/bus/pci/drivers/mptspi'
[ 1985.538178] insmod used greatest stack depth: 2020 bytes left
Waiting for driver initialization.
Creating root device.
Mounting root filesystem.
mount: could not find filesystem[ 1988.132958] Kernel panic - not syncing: Attempted to kill init!
 '/dev/root'
Se[ 1988.152018] Pid: 1, comm: init Tainted: G      D W 2.6.26-rc4-next-20080529-autotest #1
tting up other f[ 1988.180174]  [<c0425385>] ilesystems.
Setpanic+0x3f/0xf0
ting up new root[ 1988.200781]  [<c0427df1>]  fs
setuproot: do_exit+0x5b/0x5fd
moving /dev fail[ 1988.222162]  [<c04383f6>] ed: No such file?  or directory
nup_read+0x16/0x2c
o fstab.sys, mou[ 1988.247559]  [<c04283f2>] nting internal ddo_group_exit+0x5f/0x88
efaults
setupro[ 1988.270143]  [<c042842a>] ot: error mountisys_exit_group+0xf/0x11
ng /proc: No suc[ 1988.292573]  [<c040397e>] h file or directsyscall_call+0x7/0xb
ory
setuproot: [ 1988.314284]  =======================
error mounting /

-- 
Thanks & Regards,
Kamalesh Babulal,
Linux Technology Center,
IBM, ISTL.