Bluetooth: hci4: command 0x0406 tx timeout Bluetooth: hci5: command 0x0406 tx timeout Bluetooth: hci10: command 0x0409 tx timeout ieee802154 phy0 wpan0: encryption failed: -22 ieee802154 phy1 wpan1: encryption failed: -22 INFO: task kworker/u4:5:1208 blocked for more than 140 seconds. Not tainted 4.19.211-syzkaller #0 "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. kworker/u4:5 D26472 1208 2 0x80000000 Workqueue: events_unbound fsnotify_connector_destroy_workfn Call Trace: context_switch kernel/sched/core.c:2828 [inline] __schedule+0x887/0x2040 kernel/sched/core.c:3517 schedule+0x8d/0x1b0 kernel/sched/core.c:3561 schedule_timeout+0x92d/0xfe0 kernel/time/timer.c:1794 do_wait_for_common kernel/sched/completion.c:83 [inline] __wait_for_common kernel/sched/completion.c:104 [inline] wait_for_common+0x29c/0x470 kernel/sched/completion.c:115 __synchronize_srcu+0x124/0x210 kernel/rcu/srcutree.c:936 fsnotify_connector_destroy_workfn+0x49/0xa0 fs/notify/mark.c:174 process_one_work+0x864/0x1570 kernel/workqueue.c:2153 process_scheduled_works kernel/workqueue.c:2212 [inline] worker_thread+0x82b/0x1130 kernel/workqueue.c:2298 kthread+0x33f/0x460 kernel/kthread.c:259 ret_from_fork+0x24/0x30 arch/x86/entry/entry_64.S:415 INFO: task syz-executor.1:8129 blocked for more than 140 seconds. Not tainted 4.19.211-syzkaller #0 "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. syz-executor.1 D24120 8129 1 0x80000002 Call Trace: context_switch kernel/sched/core.c:2828 [inline] __schedule+0x887/0x2040 kernel/sched/core.c:3517 schedule+0x8d/0x1b0 kernel/sched/core.c:3561 schedule_preempt_disabled+0xf/0x20 kernel/sched/core.c:3619 __mutex_lock_common kernel/locking/mutex.c:1016 [inline] __mutex_lock+0x5f0/0x1190 kernel/locking/mutex.c:1078 _synchronize_rcu_expedited+0x419/0x6f0 kernel/rcu/tree_exp.h:686 synchronize_rcu+0xc6/0x160 kernel/rcu/tree_plugin.h:818 kern_unmount fs/namespace.c:3295 [inline] kern_unmount+0x67/0xe0 fs/namespace.c:3290 put_ipc_ns ipc/namespace.c:151 [inline] put_ipc_ns+0x50/0x60 ipc/namespace.c:146 free_nsproxy+0xcc/0x220 kernel/nsproxy.c:180 switch_task_namespaces+0xaa/0xc0 kernel/nsproxy.c:229 do_exit+0xbee/0x2be0 kernel/exit.c:869 do_group_exit+0x125/0x310 kernel/exit.c:967 __do_sys_exit_group kernel/exit.c:978 [inline] __se_sys_exit_group kernel/exit.c:976 [inline] __x64_sys_exit_group+0x3a/0x50 kernel/exit.c:976 do_syscall_64+0xf9/0x620 arch/x86/entry/common.c:293 entry_SYSCALL_64_after_hwframe+0x49/0xbe RIP: 0033:0x7f43c756d669 Code: Bad RIP value. RSP: 002b:00007ffd9a6c85e8 EFLAGS: 00000246 ORIG_RAX: 00000000000000e7 RAX: ffffffffffffffda RBX: 0000000000000064 RCX: 00007f43c756d669 RDX: 0000000000000000 RSI: 0000000000000001 RDI: 0000000000000000 RBP: 00007f43c75c7648 R08: 000000000000000c R09: 000055555694b3b8 R10: 0000000000000000 R11: 0000000000000246 R12: 0000000000000016 R13: 00007ffd9a6c98c0 R14: 000055555694b3b8 R15: 00007ffd9a6ca9c0 INFO: task kworker/1:1:8382 blocked for more than 140 seconds. caif:caif_disconnect_client(): nothing to disconnect Enabling of bearer rejected, failed to enable media Not tainted 4.19.211-syzkaller #0 "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. kworker/1:1 D25264 8382 2 0x80000000 Workqueue: events key_garbage_collector Call Trace: context_switch kernel/sched/core.c:2828 [inline] __schedule+0x887/0x2040 kernel/sched/core.c:3517 schedule+0x8d/0x1b0 kernel/sched/core.c:3561 exp_funnel_lock kernel/rcu/tree_exp.h:320 [inline] _synchronize_rcu_expedited+0x60c/0x6f0 kernel/rcu/tree_exp.h:667 synchronize_rcu+0xc6/0x160 kernel/rcu/tree_plugin.h:818 key_garbage_collector+0x388/0x8c0 security/keys/gc.c:292 process_one_work+0x864/0x1570 kernel/workqueue.c:2153 process_scheduled_works kernel/workqueue.c:2212 [inline] worker_thread+0x82b/0x1130 kernel/workqueue.c:2298 kthread+0x33f/0x460 kernel/kthread.c:259 ret_from_fork+0x24/0x30 arch/x86/entry/entry_64.S:415 Showing all locks held in the system: IPVS: ftp: loaded support on port[0] = 21 IPVS: ftp: loaded support on port[0] = 21 IPVS: ftp: loaded support on port[0] = 21 IPVS: ftp: loaded support on port[0] = 21 5 locks held by kworker/u4:2/33: 1 lock held by khungtaskd/1572: #0: 0000000010a172e9 (rcu_read_lock){....}, at: debug_show_all_locks+0x53/0x265 kernel/locking/lockdep.c:4441 3 locks held by kworker/1:2/3387: #0: 000000004ec71a9d ((wq_completion)"events"){+.+.}, at: process_one_work+0x767/0x1570 kernel/workqueue.c:2124 #1: 00000000eb161ed2 ((linkwatch_work).work){+.+.}, at: process_one_work+0x79c/0x1570 kernel/workqueue.c:2128 #2: 00000000e378616d (rtnl_mutex){+.+.}, at: linkwatch_event+0xb/0x60 net/core/link_watch.c:236 1 lock held by in:imklog/7806: #0: 0000000066342ee3 (&f->f_pos_lock){+.+.}, at: __fdget_pos+0x26f/0x310 fs/file.c:767 1 lock held by syz-executor.1/8129: #0: 00000000e378616d (rtnl_mutex){+.+.}, at: tun_detach drivers/net/tun.c:759 [inline] #0: 00000000e378616d (rtnl_mutex){+.+.}, at: tun_chr_close+0x3a/0x180 drivers/net/tun.c:3323 2 locks held by syz-executor.4/8131: #0: 00000000e378616d (rtnl_mutex){+.+.}, at: tun_detach drivers/net/tun.c:759 [inline] #0: 00000000e378616d (rtnl_mutex){+.+.}, at: tun_chr_close+0x3a/0x180 drivers/net/tun.c:3323 Bluetooth: hci7: command 0x041b tx timeout Bluetooth: hci13: command 0x0409 tx timeout Bluetooth: hci12: command 0x0409 tx timeout Bluetooth: hci10: command 0x041b tx timeout Bluetooth: hci11: command 0x0409 tx timeout #1: 00000000222ef273 (rcu_preempt_state.exp_mutex){+.+.}, at: exp_funnel_lock kernel/rcu/tree_exp.h:329 [inline] #1: 00000000222ef273 (rcu_preempt_state.exp_mutex){+.+.}, at: _synchronize_rcu_expedited+0x256/0x6f0 kernel/rcu/tree_exp.h:667 Bluetooth: hci9: command 0x0409 tx timeout Bluetooth: hci8: command 0x041b tx timeout 2 locks held by kworker/1:1/8382: #0: 00000000c8f5741b ((wq_completion)"rcu_gp"){+.+.}, at: process_one_work+0x767/0x1570 kernel/workqueue.c:2124 #1: 0000000041f1ccee ((work_completion)(&rew.rew_work)){+.+.}, at: process_one_work+0x79c/0x1570 kernel/workqueue.c:2128 2 locks held by kworker/0:3/9325: 3 locks held by kworker/u4:7/9506: #0: 00000000923fb4c7 ((wq_completion)"%s""netns"){+.+.}, at: process_one_work+0x767/0x1570 kernel/workqueue.c:2124 Bluetooth: hci6: command 0x0409 tx timeout #1: 00000000021a7dfe (net_cleanup_work){+.+.}, at: process_one_work+0x79c/0x1570 kernel/workqueue.c:2128 #2: 000000008abb9f11 (pernet_ops_rwsem){++++}, at: cleanup_net+0xa8/0x8b0 net/core/net_namespace.c:521 3 locks held by syz-executor.3/11067: #0: 00000000fa3af33a (&hdev->req_lock){+.+.}, at: hci_dev_do_close+0x122/0x1020 net/bluetooth/hci_core.c:1629 #1: 0000000029aa9297 (&hdev->lock){+.+.}, at: hci_dev_do_close+0x343/0x1020 net/bluetooth/hci_core.c:1674 #2: 0000000060142a3a (hci_cb_list_lock){+.+.}, at: hci_disconn_cfm include/net/bluetooth/hci_core.h:1260 [inline] #2: 0000000060142a3a (hci_cb_list_lock){+.+.}, at: hci_conn_hash_flush+0xda/0x260 net/bluetooth/hci_conn.c:1512 1 lock held by syz-executor.0/11095: 1 lock held by syz-executor.1/11127: #0: 00000000222ef273 (rcu_preempt_state.exp_mutex){+.+.}, at: exp_funnel_lock kernel/rcu/tree_exp.h:329 [inline] #0: 00000000222ef273 (rcu_preempt_state.exp_mutex){+.+.}, at: _synchronize_rcu_expedited+0x256/0x6f0 kernel/rcu/tree_exp.h:667 3 locks held by syz-executor.0/11139: #0: 000000003e70a30e (&hdev->req_lock){+.+.}, at: hci_dev_do_close+0x122/0x1020 net/bluetooth/hci_core.c:1629 #1: 00000000b7e437a4 (&hdev->lock){+.+.}, at: hci_dev_do_close+0x343/0x1020 net/bluetooth/hci_core.c:1674 #2: 0000000060142a3a (hci_cb_list_lock){+.+.}, at: hci_disconn_cfm include/net/bluetooth/hci_core.h:1260 [inline] #2: 0000000060142a3a (hci_cb_list_lock){+.+.}, at: hci_conn_hash_flush+0xda/0x260 net/bluetooth/hci_conn.c:1512 2 locks held by syz-executor.4/11156: #0: 000000008abb9f11 (pernet_ops_rwsem){++++}, at: copy_net_ns+0x1d8/0x340 net/core/net_namespace.c:435 #1: 00000000e378616d (rtnl_mutex){+.+.}, at: register_netdev+0x11/0x50 net/core/dev.c:8857 2 locks held by syz-executor.1/11158: #0: 000000008abb9f11 (pernet_ops_rwsem){++++}, at: copy_net_ns+0x1d8/0x340 net/core/net_namespace.c:435 #1: 00000000e378616d (rtnl_mutex){+.+.}, at: register_netdev+0x11/0x50 net/core/dev.c:8857 2 locks held by syz-executor.2/11174: #0: 000000008abb9f11 (pernet_ops_rwsem){++++}, at: copy_net_ns+0x1d8/0x340 net/core/net_namespace.c:435 #1: 00000000e378616d (rtnl_mutex){+.+.}, at: register_netdev+0x11/0x50 net/core/dev.c:8857 2 locks held by syz-executor.0/11183: #0: 000000008abb9f11 (pernet_ops_rwsem){++++}, at: copy_net_ns+0x1d8/0x340 net/core/net_namespace.c:435 #1: 00000000e378616d (rtnl_mutex){+.+.}, at: register_netdev+0x11/0x50 net/core/dev.c:8857 ============================================= NMI backtrace for cpu 1 CPU: 1 PID: 1572 Comm: khungtaskd Not tainted 4.19.211-syzkaller #0 Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 08/26/2022 Call Trace: __dump_stack lib/dump_stack.c:77 [inline] dump_stack+0x1fc/0x2ef lib/dump_stack.c:118 nmi_cpu_backtrace.cold+0x63/0xa2 lib/nmi_backtrace.c:101 nmi_trigger_cpumask_backtrace+0x1a6/0x1f0 lib/nmi_backtrace.c:62 trigger_all_cpu_backtrace include/linux/nmi.h:146 [inline] check_hung_uninterruptible_tasks kernel/hung_task.c:203 [inline] watchdog+0x991/0xe60 kernel/hung_task.c:287 kthread+0x33f/0x460 kernel/kthread.c:259 ret_from_fork+0x24/0x30 arch/x86/entry/entry_64.S:415 Sending NMI from CPU 1 to CPUs 0: NMI backtrace for cpu 0 CPU: 0 PID: 1208 Comm: kworker/u4:5 Not tainted 4.19.211-syzkaller #0 Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 08/26/2022 Workqueue: bat_events batadv_nc_worker RIP: 0010:debug_lockdep_rcu_enabled kernel/rcu/update.c:254 [inline] RIP: 0010:debug_lockdep_rcu_enabled+0x9f/0xe0 kernel/rcu/update.c:251 Code: 00 48 8d bb 84 08 00 00 48 b8 00 00 00 00 00 fc ff df 48 89 fa 48 c1 ea 03 0f b6 14 02 48 89 f8 83 e0 07 83 c0 03 38 d0 7c 04 <84> d2 75 32 8b 93 84 08 00 00 31 c0 5b 85 d2 0f 94 c0 c3 31 c0 5b RSP: 0018:ffff8880ba007ac8 EFLAGS: 00000002 RAX: 0000000000000007 RBX: ffff8880b32d0540 RCX: 0000000000000001 RDX: 0000000000000000 RSI: 0000000000000002 RDI: ffff8880b32d0dc4 RBP: ffffffff8d40d1a0 R08: 0000000000000000 R09: fffffbfff1a81a31 R10: 0000000000000000 R11: 0000000000000000 R12: 1ffff11017400f5e R13: ffffffff83774be1 R14: 0000000000000000 R15: dffffc0000000000 FS: 0000000000000000(0000) GS:ffff8880ba000000(0000) knlGS:0000000000000000 CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 CR2: 00005623ef4f0828 CR3: 0000000098fb2000 CR4: 00000000003406f0 DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000 DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400 Call Trace: trace_lock_release include/trace/events/lock.h:58 [inline] lock_release+0x5c3/0x8b0 kernel/locking/lockdep.c:3926 __raw_spin_unlock_irqrestore include/linux/spinlock_api_smp.h:158 [inline] _raw_spin_unlock_irqrestore+0x1b/0xe0 kernel/locking/spinlock.c:184 __debug_check_no_obj_freed lib/debugobjects.c:798 [inline] debug_check_no_obj_freed+0x201/0x490 lib/debugobjects.c:817 kmem_cache_free+0xff/0x260 mm/slab.c:3764 kfree_skbmem+0xc1/0x140 net/core/skbuff.c:595 __kfree_skb net/core/skbuff.c:655 [inline] consume_skb+0x120/0x3d0 net/core/skbuff.c:714 can_receive+0x358/0x530 net/can/af_can.c:712 canfd_rcv+0x12f/0x1d0 net/can/af_can.c:764 __netif_receive_skb_one_core+0x114/0x180 net/core/dev.c:4954 __netif_receive_skb+0x27/0x1c0 net/core/dev.c:5066 process_backlog+0x241/0x700 net/core/dev.c:5849 napi_poll net/core/dev.c:6280 [inline] net_rx_action+0x4ac/0xfb0 net/core/dev.c:6346 __do_softirq+0x265/0x980 kernel/softirq.c:292 do_softirq_own_stack+0x2a/0x40 arch/x86/entry/entry_64.S:1092 do_softirq.part.0+0x160/0x1c0 kernel/softirq.c:336 do_softirq kernel/softirq.c:328 [inline] __local_bh_enable_ip+0x20e/0x270 kernel/softirq.c:189 spin_unlock_bh include/linux/spinlock.h:374 [inline] batadv_nc_purge_paths+0x22d/0x310 net/batman-adv/network-coding.c:482 batadv_nc_worker+0x731/0xd50 net/batman-adv/network-coding.c:733 process_one_work+0x864/0x1570 kernel/workqueue.c:2153 worker_thread+0x64c/0x1130 kernel/workqueue.c:2296 kthread+0x33f/0x460 kernel/kthread.c:259 ret_from_fork+0x24/0x30 arch/x86/entry/entry_64.S:415