Bluetooth: hci0 command 0x0406 tx timeout Bluetooth: hci2 command 0x0406 tx timeout Bluetooth: hci3 command 0x0406 tx timeout Bluetooth: hci4 command 0x0406 tx timeout Bluetooth: hci5 command 0x0406 tx timeout BUG: workqueue lockup - pool cpus=1 node=0 flags=0x0 nice=0 stuck for 169s! Showing busy workqueues and worker pools: workqueue events: flags=0x0 pwq 2: cpus=1 node=0 flags=0x0 nice=0 active=12/256 refcnt=13 in-flight: 23:rtc_timer_do_work pending: wait_rcu_exp_gp, cache_reap, defense_work_handler, macvlan_process_broadcast, macvlan_process_broadcast, macvlan_process_broadcast, macvlan_process_broadcast, macvlan_process_broadcast, macvlan_process_broadcast, macvlan_process_broadcast, macvlan_process_broadcast pwq 0: cpus=0 node=0 flags=0x0 nice=0 active=2/256 refcnt=3 in-flight: 25:xfrm_state_gc_task xfrm_state_gc_task workqueue events_unbound: flags=0x2 pwq 4: cpus=0-1 flags=0x4 nice=0 active=4/512 refcnt=7 in-flight: 9355:fsnotify_connector_destroy_workfn fsnotify_connector_destroy_workfn, 78:fsnotify_mark_destroy_workfn fsnotify_mark_destroy_workfn workqueue events_freezable: flags=0x4 pwq 2: cpus=1 node=0 flags=0x0 nice=0 active=1/256 refcnt=2 pending: update_balloon_stats_func workqueue events_power_efficient: flags=0x80 pwq 2: cpus=1 node=0 flags=0x0 nice=0 active=4/256 refcnt=5 pending: process_srcu, fb_flashcursor, neigh_periodic_work, check_lifetime workqueue mm_percpu_wq: flags=0x8 pwq 2: cpus=1 node=0 flags=0x0 nice=0 active=1/256 refcnt=2 pending: vmstat_update workqueue ipv6_addrconf: flags=0x40008 pwq 2: cpus=1 node=0 flags=0x0 nice=0 active=1/1 refcnt=2 pending: addrconf_verify_work workqueue bat_events: flags=0xe000a pwq 4: cpus=0-1 flags=0x4 nice=0 active=1/1 refcnt=7 pending: batadv_dat_purge delayed: batadv_bla_periodic_work, batadv_dat_purge, batadv_bla_periodic_work pool 0: cpus=0 node=0 flags=0x0 nice=0 hung=0s workers=5 idle: 8594 3626 9131 3 pool 2: cpus=1 node=0 flags=0x0 nice=0 hung=169s workers=4 idle: 2969 9027 18 pool 4: cpus=0-1 flags=0x4 nice=0 hung=0s workers=6 idle: 22 5 2883 950 INFO: task kworker/0:1:25 blocked for more than 140 seconds. Not tainted 4.14.304-syzkaller #0 "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. kworker/0:1 D27264 25 2 0x80000000 Workqueue: events xfrm_state_gc_task Call Trace: context_switch kernel/sched/core.c:2811 [inline] __schedule+0x88b/0x1de0 kernel/sched/core.c:3387 schedule+0x8d/0x1b0 kernel/sched/core.c:3431 exp_funnel_lock kernel/rcu/tree_exp.h:295 [inline] _synchronize_rcu_expedited+0x5f6/0x770 kernel/rcu/tree_exp.h:596 synchronize_rcu+0x98/0x130 kernel/rcu/tree_plugin.h:762 xfrm_state_gc_task+0xb7/0x7e0 net/xfrm/xfrm_state.c:469 process_one_work+0x793/0x14a0 kernel/workqueue.c:2117 worker_thread+0x5cc/0xff0 kernel/workqueue.c:2251 kthread+0x30d/0x420 kernel/kthread.c:232 ret_from_fork+0x24/0x30 arch/x86/entry/entry_64.S:406 INFO: task kworker/u4:2:78 blocked for more than 140 seconds. Not tainted 4.14.304-syzkaller #0 "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. kworker/u4:2 D27064 78 2 0x80000000 Workqueue: events_unbound fsnotify_mark_destroy_workfn Call Trace: context_switch kernel/sched/core.c:2811 [inline] __schedule+0x88b/0x1de0 kernel/sched/core.c:3387 schedule+0x8d/0x1b0 kernel/sched/core.c:3431 schedule_timeout+0x80a/0xe90 kernel/time/timer.c:1724 do_wait_for_common kernel/sched/completion.c:91 [inline] __wait_for_common kernel/sched/completion.c:112 [inline] wait_for_common+0x272/0x430 kernel/sched/completion.c:123 __synchronize_srcu+0x10a/0x1d0 kernel/rcu/srcutree.c:898 fsnotify_mark_destroy_workfn+0xed/0x2e0 fs/notify/mark.c:757 process_one_work+0x793/0x14a0 kernel/workqueue.c:2117 worker_thread+0x5cc/0xff0 kernel/workqueue.c:2251 kthread+0x30d/0x420 kernel/kthread.c:232 ret_from_fork+0x24/0x30 arch/x86/entry/entry_64.S:406 INFO: task syz-executor.5:7980 blocked for more than 140 seconds. Not tainted 4.14.304-syzkaller #0 "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. syz-executor.5 D25112 7980 1 0x00000004 Call Trace: context_switch kernel/sched/core.c:2811 [inline] __schedule+0x88b/0x1de0 kernel/sched/core.c:3387 schedule+0x8d/0x1b0 kernel/sched/core.c:3431 schedule_preempt_disabled+0xf/0x20 kernel/sched/core.c:3489 __mutex_lock_common kernel/locking/mutex.c:833 [inline] __mutex_lock+0x669/0x1310 kernel/locking/mutex.c:893 exp_funnel_lock kernel/rcu/tree_exp.h:305 [inline] _synchronize_rcu_expedited+0x32d/0x770 kernel/rcu/tree_exp.h:596 synchronize_rcu+0x98/0x130 kernel/rcu/tree_plugin.h:762 namespace_unlock fs/namespace.c:1448 [inline] do_umount fs/namespace.c:1650 [inline] SYSC_umount fs/namespace.c:1754 [inline] SyS_umount+0x791/0xeb0 fs/namespace.c:1723 do_syscall_64+0x1d5/0x640 arch/x86/entry/common.c:292 entry_SYSCALL_64_after_hwframe+0x5e/0xd3 RIP: 0033:0x7fc2e18c9537 RSP: 002b:00007ffef6ec49e8 EFLAGS: 00000246 ORIG_RAX: 00000000000000a6 RAX: ffffffffffffffda RBX: 0000000000000000 RCX: 00007fc2e18c9537 RDX: 00007ffef6ec4aba RSI: 000000000000000a RDI: 00007ffef6ec4ab0 RBP: 00007ffef6ec4ab0 R08: 00000000ffffffff R09: 00007ffef6ec4880 R10: 00005555563ef903 R11: 0000000000000246 R12: 00007fc2e1922b24 R13: 00007ffef6ec5b70 R14: 00005555563ef810 R15: 00007ffef6ec5bb0 INFO: task syz-executor.2:8009 blocked for more than 140 seconds. Not tainted 4.14.304-syzkaller #0 "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. syz-executor.2 D25632 8009 1 0x00000004 Call Trace: context_switch kernel/sched/core.c:2811 [inline] __schedule+0x88b/0x1de0 kernel/sched/core.c:3387 schedule+0x8d/0x1b0 kernel/sched/core.c:3431 _synchronize_rcu_expedited+0x522/0x770 kernel/rcu/tree_exp.h:615 synchronize_rcu+0x98/0x130 kernel/rcu/tree_plugin.h:762 namespace_unlock fs/namespace.c:1448 [inline] do_umount fs/namespace.c:1650 [inline] SYSC_umount fs/namespace.c:1754 [inline] SyS_umount+0x791/0xeb0 fs/namespace.c:1723 do_syscall_64+0x1d5/0x640 arch/x86/entry/common.c:292 entry_SYSCALL_64_after_hwframe+0x5e/0xd3 RIP: 0033:0x7fdce01c2537 RSP: 002b:00007ffdfdb8bd38 EFLAGS: 00000246 ORIG_RAX: 00000000000000a6 RAX: ffffffffffffffda RBX: 0000000000000000 RCX: 00007fdce01c2537 RDX: 00007ffdfdb8be0a RSI: 000000000000000a RDI: 00007ffdfdb8be00 RBP: 00007ffdfdb8be00 R08: 00000000ffffffff R09: 00007ffdfdb8bbd0 R10: 0000555556d69903 R11: 0000000000000246 R12: 00007fdce021bb24 R13: 00007ffdfdb8cec0 R14: 0000555556d69810 R15: 00007ffdfdb8cf00 INFO: task kworker/u4:5:9355 blocked for more than 140 seconds. Not tainted 4.14.304-syzkaller #0 "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. kworker/u4:5 D26664 9355 2 0x80000000 Workqueue: events_unbound fsnotify_connector_destroy_workfn Call Trace: context_switch kernel/sched/core.c:2811 [inline] __schedule+0x88b/0x1de0 kernel/sched/core.c:3387 schedule+0x8d/0x1b0 kernel/sched/core.c:3431 schedule_timeout+0x80a/0xe90 kernel/time/timer.c:1724 do_wait_for_common kernel/sched/completion.c:91 [inline] __wait_for_common kernel/sched/completion.c:112 [inline] wait_for_common+0x272/0x430 kernel/sched/completion.c:123 __synchronize_srcu+0x10a/0x1d0 kernel/rcu/srcutree.c:898 fsnotify_connector_destroy_workfn+0x49/0xa0 fs/notify/mark.c:156 process_one_work+0x793/0x14a0 kernel/workqueue.c:2117 worker_thread+0x5cc/0xff0 kernel/workqueue.c:2251 kthread+0x30d/0x420 kernel/kthread.c:232 ret_from_fork+0x24/0x30 arch/x86/entry/entry_64.S:406 Showing all locks held in the system: 2 locks held by kworker/0:1/25: #0: ("events"){+.+.}, at: [] process_one_work+0x6b0/0x14a0 kernel/workqueue.c:2088 #1: (xfrm_state_gc_work){+.+.}, at: [] process_one_work+0x6e6/0x14a0 kernel/workqueue.c:2092 2 locks held by kworker/u4:2/78: #0: ("events_unbound"){+.+.}, at: [] process_one_work+0x6b0/0x14a0 kernel/workqueue.c:2088 #1: ((reaper_work).work){+.+.}, at: [] process_one_work+0x6e6/0x14a0 kernel/workqueue.c:2092 1 lock held by khungtaskd/1533: #0: (tasklist_lock){.+.+}, at: [] debug_show_all_locks+0x7c/0x21a kernel/locking/lockdep.c:4548 1 lock held by syz-executor.5/7980: #0: (rcu_preempt_state.exp_mutex){+.+.}, at: [] exp_funnel_lock kernel/rcu/tree_exp.h:305 [inline] #0: (rcu_preempt_state.exp_mutex){+.+.}, at: [] _synchronize_rcu_expedited+0x32d/0x770 kernel/rcu/tree_exp.h:596 1 lock held by syz-executor.2/8009: #0: (rcu_preempt_state.exp_mutex){+.+.}, at: [] exp_funnel_lock kernel/rcu/tree_exp.h:272 [inline] #0: (rcu_preempt_state.exp_mutex){+.+.}, at: [] _synchronize_rcu_expedited+0x2c2/0x770 kernel/rcu/tree_exp.h:596 2 locks held by kworker/u4:5/9355: #0: ("events_unbound"){+.+.}, at: [] process_one_work+0x6b0/0x14a0 kernel/workqueue.c:2088 #1: (connector_reaper_work){+.+.}, at: [] process_one_work+0x6e6/0x14a0 kernel/workqueue.c:2092 ============================================= NMI backtrace for cpu 0 CPU: 0 PID: 1533 Comm: khungtaskd Not tainted 4.14.304-syzkaller #0 Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/12/2023 Call Trace: __dump_stack lib/dump_stack.c:17 [inline] dump_stack+0x1b2/0x281 lib/dump_stack.c:58 nmi_cpu_backtrace.cold+0x57/0x93 lib/nmi_backtrace.c:101 nmi_trigger_cpumask_backtrace+0x13a/0x180 lib/nmi_backtrace.c:62 trigger_all_cpu_backtrace include/linux/nmi.h:140 [inline] check_hung_uninterruptible_tasks kernel/hung_task.c:195 [inline] watchdog+0x5b9/0xb40 kernel/hung_task.c:274 kthread+0x30d/0x420 kernel/kthread.c:232 ret_from_fork+0x24/0x30 arch/x86/entry/entry_64.S:406 Sending NMI from CPU 0 to CPUs 1: NMI backtrace for cpu 1 CPU: 1 PID: 23 Comm: kworker/1:1 Not tainted 4.14.304-syzkaller #0 Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/12/2023 Workqueue: events rtc_timer_do_work task: ffff8880b5600600 task.stack: ffff8880b5608000 RIP: 0010:mark_lock+0xa6/0x1050 kernel/locking/lockdep.c:3168 RSP: 0018:ffff8880b560fa30 EFLAGS: 00000046 RAX: dffffc0000000000 RBX: ffffffff8b9ffd10 RCX: 1ffff11016ac01d5 RDX: 1ffffffff173ffa8 RSI: ffff8880b5600e88 RDI: ffffffff8b9ffd40 RBP: ffff8880b5600e88 R08: ffff888237900b20 R09: 0000000000000000 R10: 0000000000000000 R11: 0000000000000000 R12: 0000000000000040 R13: ffff8880b5600600 R14: ffff8880b5600ea8 R15: 0000000000000006 FS: 0000000000000000(0000) GS:ffff8880ba500000(0000) knlGS:0000000000000000 CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 CR2: 00007f2190619000 CR3: 00000000a15d8000 CR4: 00000000003406e0 DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000 DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400 Call Trace: mark_held_locks+0xa6/0xf0 kernel/locking/lockdep.c:2854 __trace_hardirqs_on_caller kernel/locking/lockdep.c:2883 [inline] trace_hardirqs_on_caller+0x3a8/0x580 kernel/locking/lockdep.c:2930 __raw_spin_unlock_irqrestore include/linux/spinlock_api_smp.h:160 [inline] _raw_spin_unlock_irqrestore+0x79/0xe0 kernel/locking/spinlock.c:192 spin_unlock_irqrestore include/linux/spinlock.h:372 [inline] __wake_up_common_lock+0xcd/0x140 kernel/sched/wait.c:127 rtc_handle_legacy_irq+0x121/0x180 drivers/rtc/interface.c:523 rtc_timer_do_work+0x220/0x590 drivers/rtc/interface.c:881 process_one_work+0x793/0x14a0 kernel/workqueue.c:2117 worker_thread+0x5cc/0xff0 kernel/workqueue.c:2251 kthread+0x30d/0x420 kernel/kthread.c:232 ret_from_fork+0x24/0x30 arch/x86/entry/entry_64.S:406 Code: 50 01 00 00 48 81 eb 50 01 00 00 48 81 c3 40 cb 9e 8b 48 8d 7b 30 48 b8 00 00 00 00 00 fc ff df 48 89 fa 48 c1 ea 03 80 3c 02 00 <0f> 85 07 0c 00 00 4c 89 e0 48 23 43 30 0f 84 9c 00 00 00 b8 01 ---------------- Code disassembly (best guess): 0: 50 push %rax 1: 01 00 add %eax,(%rax) 3: 00 48 81 add %cl,-0x7f(%rax) 6: eb 50 jmp 0x58 8: 01 00 add %eax,(%rax) a: 00 48 81 add %cl,-0x7f(%rax) d: c3 retq e: 40 cb rex lret 10: 9e sahf 11: 8b 48 8d mov -0x73(%rax),%ecx 14: 7b 30 jnp 0x46 16: 48 b8 00 00 00 00 00 movabs $0xdffffc0000000000,%rax 1d: fc ff df 20: 48 89 fa mov %rdi,%rdx 23: 48 c1 ea 03 shr $0x3,%rdx 27: 80 3c 02 00 cmpb $0x0,(%rdx,%rax,1) * 2b: 0f 85 07 0c 00 00 jne 0xc38 <-- trapping instruction 31: 4c 89 e0 mov %r12,%rax 34: 48 23 43 30 and 0x30(%rbx),%rax 38: 0f 84 9c 00 00 00 je 0xda 3e: b8 .byte 0xb8 3f: 01 .byte 0x1