Bug 828939 - lpfc call trace observed when starting dracut.
lpfc call trace observed when starting dracut.
Status: CLOSED DUPLICATE of bug 823387
Product: Red Hat Enterprise Linux 7
Classification: Red Hat
Component: kernel (Show other bugs)
7.0
Unspecified Unspecified
unspecified Severity urgent
: rc
: ---
Assigned To: Red Hat Kernel Manager
Red Hat Kernel QE team
:
Depends On:
Blocks:
  Show dependency treegraph
 
Reported: 2012-06-05 11:18 EDT by Xiaowei Li
Modified: 2015-01-26 19:10 EST (History)
1 user (show)

See Also:
Fixed In Version:
Doc Type: Bug Fix
Doc Text:
Story Points: ---
Clone Of:
Environment:
Last Closed: 2012-06-05 11:25:29 EDT
Type: Bug
Regression: ---
Mount Type: ---
Documentation: ---
CRM:
Verified Versions:
Category: ---
oVirt Team: ---
RHEL 7.3 requirements from Atomic Host:
Cloudforms Team: ---


Attachments (Terms of Use)
console.log (969.55 KB, text/plain)
2012-06-05 11:20 EDT, Xiaowei Li
no flags Details

  None (edit)
Description Xiaowei Li 2012-06-05 11:18:57 EDT
Description of problem:
lpfc call trace observed when starting dracut.

Version-Release number of selected component (if applicable):
Emulex LightPulse HBA (kernel module: lpfc) 
[   19.194053] Emulex LightPulse Fibre Channel SCSI driver 8.3.28 
[   19.286959] scsi1 : Emulex LPe12000 1Gb PCIe Fibre Channel Adapter on PCI bus 0d device 00 irq 30 

RHEL-7.0-20120604.n.0-Server-x86_64

How reproducible:


Steps to Reproduce:
1. install RHEL 7 to the host with emulex FC HBA(lpfc)
2. 
3.
  
Actual results:
[   21.487057] scsi4 : Emulex LPe12000 1Gb PCIe Fibre Channel Adapter on PCI bus 0d device 01 irq 37 
[   21.834220] lpfc 0000:0d:00.0: 0:1303 Link Up Event x1 received Data: x1 xf7 x20 xa9 x0 x0 0 
[   21.876436]  
[   21.883141] ================================= 
[   21.906420] [ INFO: inconsistent lock state ] 
[   21.930064] 3.3.0-0.13.el7.x86_64 #1 Not tainted 
[   21.948224] --------------------------------- 
[   21.963211] inconsistent {IN-HARDIRQ-W} -> {HARDIRQ-ON-W} usage. 
[   21.989934] lpfc_worker_0/339 [HC0[0]:SC0[0]:HE1:SE1] takes: 
[   22.015997]  (&(&phba->hbalock)->rlock){?.....}, at: [<ffffffffa02abcb5>] lpfc_mbx_process_link_up+0x45/0x870 [lpfc] 
[   22.066843] {IN-HARDIRQ-W} state was registered at: 
[   22.082841]   [<ffffffff810ce0f5>] __lock_acquire+0x805/0x1ad0 
[   22.111746]   [<ffffffff810cfa91>] lock_acquire+0xa1/0x1e0 
[   22.138876]   [<ffffffff81662d65>] _raw_spin_lock_irqsave+0x65/0xb0 
[   22.159680]   [<ffffffffa028df78>] lpfc_sli_sp_intr_handler+0x1c8/0x700 [lpfc] 
[   22.192626]   [<ffffffff8110bd3c>] handle_irq_event_percpu+0x6c/0x390 
[   22.225550]   [<ffffffff8110c0a8>] handle_irq_event+0x48/0x70 
[   22.248573]   [<ffffffff8110f467>] handle_edge_irq+0x77/0x110 
[   22.275464]   [<ffffffff8101a48f>] handle_irq+0xbf/0x150 
[   22.303429]   [<ffffffff8166d6ba>] do_IRQ+0x5a/0xe0 
[   22.322428]   [<ffffffff81662fb3>] ret_from_intr+0x0/0x1a 
[   22.345376]   [<ffffffff814dfc33>] cpuidle_idle_call+0xb3/0x540 
[   22.374303]   [<ffffffff81017225>] cpu_idle+0xc5/0x140 
[   22.401262]   [<ffffffff8163979f>] rest_init+0x133/0x144 
[   22.424231]   [<ffffffff81c2bc3d>] start_kernel+0x3f7/0x402 
[   22.448218]   [<ffffffff81c2b346>] x86_64_start_reservations+0x131/0x135 
[   22.473308]   [<ffffffff81c2b44a>] x86_64_start_kernel+0x100/0x10f 
[   22.501072] irq event stamp: 234 
[   22.512103] hardirqs last  enabled at (233): [<ffffffff81663074>] restore_args+0x0/0x30 
[   22.549032] hardirqs last disabled at (234): [<ffffffff8166224f>] _raw_spin_lock_irq+0x1f/0x90 
[   22.585104] softirqs last  enabled at (232): [<ffffffff8106d1a4>] __do_softirq+0x154/0x380 
[   22.615935] softirqs last disabled at (217): [<ffffffff8166ce2c>] call_softirq+0x1c/0x30 
[   22.651869]  
[   22.651869] other info that might help us debug this: 
[   22.681779]  Possible unsafe locking scenario: 
[   22.681779]  
[   22.711764]        CPU0 
[   22.721730]        ---- 
[   22.731726]   lock(&(&phba->hbalock)->rlock); 
[   22.753850]   <Interrupt> 
[   22.765025]     lock(&(&phba->hbalock)->rlock); 
[   22.786959]  
[   22.786960]  *** DEADLOCK *** 
[   22.786960]  
[   22.816559] 1 lock held by lpfc_worker_0/339: 
[   22.833544]  #0:  (&(&phba->hbalock)->rlock){?.....}, at: [<ffffffffa02abcb5>] lpfc_mbx_process_link_up+0x45/0x870 [lpfc] 
[   22.887474]  
[   22.887475] stack backtrace: 
[   22.907433] Pid: 339, comm: lpfc_worker_0 Not tainted 3.3.0-0.13.el7.x86_64 #1 
[   22.943340] Call Trace: 
[   22.953601]  [<ffffffff816569bb>] print_usage_bug+0x1f7/0x208 
[   22.984321]  [<ffffffff810273df>] ? save_stack_trace+0x2f/0x50 
[   23.011293]  [<ffffffff810cce40>] ? check_usage_forwards+0x140/0x140 
[   23.031237]  [<ffffffff810cd8b2>] mark_lock+0x272/0x2b0 
[   23.056176]  [<ffffffff810d04a2>] mark_held_locks+0xb2/0x130 
[   23.074137]  [<ffffffff81662b60>] ? _raw_spin_unlock_irq+0x30/0x50 
[   23.099227]  [<ffffffff810d05d2>] trace_hardirqs_on_caller+0xb2/0x1a0 
[   23.126056]  [<ffffffff810d06cd>] trace_hardirqs_on+0xd/0x10 
[   23.153021]  [<ffffffff81662b60>] _raw_spin_unlock_irq+0x30/0x50 
[   23.180995]  [<ffffffffa02abd5a>] lpfc_mbx_process_link_up+0xea/0x870 [lpfc] 
[   23.212935]  [<ffffffff81418375>] ? dev_printk+0x45/0x50 
[   23.237876]  [<ffffffffa02aec51>] lpfc_mbx_cmpl_read_topology+0x491/0x630 [lpfc] 
[   23.273834]  [<ffffffffa0286e7b>] lpfc_sli_handle_mb_event+0x14b/0x450 [lpfc] 
[   23.309745]  [<ffffffff810200b9>] ? sched_clock+0x9/0x10 
[   23.338721]  [<ffffffff810a5f15>] ? sched_clock_cpu+0xc5/0x120 
[   23.364761]  [<ffffffff810ca39d>] ? trace_hardirqs_off+0xd/0x10 
[   23.390640]  [<ffffffff810a5fdf>] ? local_clock+0x6f/0x80 
[   23.409550]  [<ffffffff81662b60>] ? _raw_spin_unlock_irq+0x30/0x50 
[   23.442597]  [<ffffffff810d062d>] ? trace_hardirqs_on_caller+0x10d/0x1a0 
[   23.472525]  [<ffffffffa02afc58>] lpfc_do_work+0xe68/0x1660 [lpfc] 
[   23.502427]  [<ffffffff810d06cd>] ? trace_hardirqs_on+0xd/0x10 
[   23.527512]  [<ffffffff81662b60>] ? _raw_spin_unlock_irq+0x30/0x50 
[   23.553421]  [<ffffffff8109a8fc>] ? finish_task_switch+0x7c/0x120 
[   23.580302]  [<ffffffff8109a8bf>] ? finish_task_switch+0x3f/0x120 
[   23.609385]  [<ffffffff8108dfb0>] ? wake_up_bit+0x40/0x40 
[   23.636205]  [<ffffffffa02aedf0>] ? lpfc_mbx_cmpl_read_topology+0x630/0x630 [lpfc] 
[   23.666146]  [<ffffffff8108d787>] kthread+0xb7/0xc0 
[   23.679117]  [<ffffffff8166cd34>] kernel_thread_helper+0x4/0x10 
[   23.698141]  [<ffffffff81663074>] ? retint_restore_args+0x13/0x13 
[   23.730128]  [<ffffffff8108d6d0>] ? kthread_worker_fn+0x1a0/0x1a0 
[   23.755009]  [<ffffffff8166cd30>] ? gs_change+0x13/0x13 
[   23.781389] bnx2 0000:02:00.0: em1: NIC Copper Link is Up, 1000 Mbps full duplex 
[   23.781507] lpfc 0000:0d:00.1: irq 82 for MSI/MSI-X 
[   23.781721] lpfc 0000:0d:00.1: irq 83 for MSI/MSI-X 


Expected results:


Additional info:
Comment 1 Xiaowei Li 2012-06-05 11:20:27 EDT
Created attachment 589559 [details]
console.log
Comment 2 Xiaowei Li 2012-06-05 11:25:29 EDT

*** This bug has been marked as a duplicate of bug 823387 ***

Note You need to log in before you can comment on or make changes to this bug.