crypto: mxs-dcp deadlock bug?

* crypto: mxs-dcp deadlock bug?
@ 2019-07-12  6:19 Yang Liu
  2019-07-23  7:14 ` Петр Борисенко
  0 siblings, 1 reply; 2+ messages in thread
From: Yang Liu @ 2019-07-12  6:19 UTC (permalink / raw)
  To: meta-freescale

Hi, meta-freescale gurus,

I am testing a simple VPN (openswan) configuration on a customised
i.mx287 board. The Linux kernel is Linux version
5.0.7-fslc+g39f695df6f5f. There is a kernel dump printed on screen
after several seconds. It looks like a deadlock happened in mxs-dcp
driver.

[ 4176.961195] ================================
[ 4176.965485] WARNING: inconsistent lock state
[ 4176.969775] 5.0.7-fslc+g39f695df6f5f #1 Not tainted
[ 4176.974667] --------------------------------
[ 4176.978954] inconsistent {SOFTIRQ-ON-W} -> {IN-SOFTIRQ-W} usage.
[ 4176.984984] swapper/0 [HC0[0]:SC1[1]:HE1:SE0] takes:
[ 4176.989973] 6c0f210d (&(&sdcp->lock[i])->rlock){+.?.}, at:
mxs_dcp_aes_enqueue+0x4c/0x98
[ 4176.998137] {SOFTIRQ-ON-W} state was registered at:
[ 4177.003049]   _raw_spin_lock+0x28/0x38
[ 4177.006828]   dcp_chan_thread_sha+0x4c/0x2e4
[ 4177.011133]   kthread+0x120/0x138
[ 4177.014475]   ret_from_fork+0x14/0x24
[ 4177.018155]     (null)
[ 4177.020531] irq event stamp: 2503736
[ 4177.024150] hardirqs last  enabled at (2503736): [<c0073f74>]
ktime_get_real_seconds+0x90/0xb0
[ 4177.032795] hardirqs last disabled at (2503735): [<c0073f3c>]
ktime_get_real_seconds+0x58/0xb0
[ 4177.041438] softirqs last  enabled at (2503728): [<c001f85c>]
irq_enter+0x64/0x80
[ 4177.048947] softirqs last disabled at (2503729): [<c001f9c0>]
irq_exit+0x148/0x19c
[ 4177.056528]
[ 4177.056528] other info that might help us debug this:
[ 4177.063064]  Possible unsafe locking scenario:
[ 4177.063064]
[ 4177.068991]        CPU0
[ 4177.071446]        ----
[ 4177.073901]   lock(&(&sdcp->lock[i])->rlock);
[ 4177.078281]   <Interrupt>
[ 4177.080910]     lock(&(&sdcp->lock[i])->rlock);
[ 4177.085462]
[ 4177.085462]  *** DEADLOCK ***
[ 4177.085462]
[ 4177.091397] 2 locks held by swapper/0:
[ 4177.095155]  #0: 9c353098 (rcu_read_lock){....}, at:
netif_receive_skb_internal+0x28/0x19c
[ 4177.103503]  #1: 9c353098 (rcu_read_lock){....}, at:
ip_local_deliver_finish+0x2c/0xb8
[ 4177.111500]
[ 4177.111500] stack backtrace:
[ 4177.115883] CPU: 0 PID: 0 Comm: swapper Not tainted
5.0.7-fslc+g39f695df6f5f #1
[ 4177.123208] Hardware name: Freescale MXS (Device Tree)
[ 4177.128415] [<c0010df8>] (unwind_backtrace) from [<c000e730>]
(show_stack+0x10/0x14)
[ 4177.136211] [<c000e730>] (show_stack) from [<c0055a74>]
(mark_lock+0x534/0x6f0)
[ 4177.143560] [<c0055a74>] (mark_lock) from [<c005612c>]
(__lock_acquire+0x484/0x1870)
[ 4177.151339] [<c005612c>] (__lock_acquire) from [<c0057e54>]
(lock_acquire+0xb4/0x178)
[ 4177.159210] [<c0057e54>] (lock_acquire) from [<c068adb8>]
(_raw_spin_lock+0x28/0x38)
[ 4177.166996] [<c068adb8>] (_raw_spin_lock) from [<c049e024>]
(mxs_dcp_aes_enqueue+0x4c/0x98)
[ 4177.175416] [<c049e024>] (mxs_dcp_aes_enqueue) from [<bf0dc52c>]
(esp_input+0x1d8/0x300 [esp4])
[ 4177.184190] [<bf0dc52c>] (esp_input [esp4]) from [<c05d9540>]
(xfrm_input+0x83c/0xad0)
[ 4177.192171] [<c05d9540>] (xfrm_input) from [<c05c8bd0>]
(xfrm4_esp_rcv+0x68/0x120)
[ 4177.199798] [<c05c8bd0>] (xfrm4_esp_rcv) from [<c0564a1c>]
(ip_protocol_deliver_rcu+0x7c/0x2cc)
[ 4177.208547] [<c0564a1c>] (ip_protocol_deliver_rcu) from
[<c0564cf4>] (ip_local_deliver_finish+0x88/0xb8)
[ 4177.218072] [<c0564cf4>] (ip_local_deliver_finish) from
[<c0564e54>] (ip_local_deliver+0x130/0x1a8)
[ 4177.227162] [<c0564e54>] (ip_local_deliver) from [<c0564fe8>]
(ip_rcv+0x11c/0x174)
[ 4177.234778] [<c0564fe8>] (ip_rcv) from [<c05139a8>]
(__netif_receive_skb_one_core+0x4c/0x6c)
[ 4177.243263] [<c05139a8>] (__netif_receive_skb_one_core) from
[<c0519370>] (netif_receive_skb_internal+0x58/0x19c)
[ 4177.253568] [<c0519370>] (netif_receive_skb_internal) from
[<c0519f10>] (napi_gro_receive+0x148/0x1d0)
[ 4177.262924] [<c0519f10>] (napi_gro_receive) from [<c042164c>]
(fec_enet_rx_napi+0x3d0/0x9b8)
[ 4177.271406] [<c042164c>] (fec_enet_rx_napi) from [<c051a668>]
(net_rx_action+0xe4/0x3dc)
[ 4177.279542] [<c051a668>] (net_rx_action) from [<c000a15c>]
(__do_softirq+0x134/0x434)
[ 4177.287416] [<c000a15c>] (__do_softirq) from [<c001f9c0>]
(irq_exit+0x148/0x19c)
[ 4177.294853] [<c001f9c0>] (irq_exit) from [<c006345c>]
(__handle_domain_irq+0x50/0xa8)
[ 4177.302720] [<c006345c>] (__handle_domain_irq) from [<c00099cc>]
(__irq_svc+0x6c/0x8c)
[ 4177.310658] Exception stack(0xc0919f40 to 0xc0919f88)
[ 4177.315744] 9f40: 00000001 00000001 00000000 20000013 ffffe000
c09230c4 c09a453f c07e839c
[ 4177.323953] 9f60: c0923060 c7ee8e80 c090aa50 00000000 00000000
c0919f90 c0058020 c000bc30
[ 4177.332147] 9f80: 20000013 ffffffff
[ 4177.335675] [<c00099cc>] (__irq_svc) from [<c000bc30>]
(arch_cpu_idle+0x28/0x38)
[ 4177.343115] [<c000bc30>] (arch_cpu_idle) from [<c0047078>]
(do_idle+0x8c/0xec)
[ 4177.350374] [<c0047078>] (do_idle) from [<c004745c>]
(cpu_startup_entry+0xc/0x10)
[ 4177.357909] [<c004745c>] (cpu_startup_entry) from [<c08c9d8c>]
(start_kernel+0x3cc/0x474)
[ 4177.460138] NOHZ: local_softirq_pending 08
[ 4177.960322] NOHZ: local_softirq_pending 08
[ 4178.045081] NOHZ: local_softirq_pending 08
[ 4178.126861] NOHZ: local_softirq_pending 08
[ 4178.157288] NOHZ: local_softirq_pending 08
[ 4178.191966] NOHZ: local_softirq_pending 08
[ 4178.218160] NOHZ: local_softirq_pending 08
[ 4178.237144] NOHZ: local_softirq_pending 08
[ 4178.267594] NOHZ: local_softirq_pending 08
[ 4178.286991] NOHZ: local_softirq_pending 08

Can someone give me some suggestions about this bug?

Best Regards,
yliu

^ permalink raw reply	[flat|nested] 2+ messages in thread