Bluetooth: hci1 command 0x0406 tx timeout Bluetooth: hci3 command 0x0406 tx timeout Bluetooth: hci0 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 146s! Showing busy workqueues and worker pools: workqueue events: flags=0x0 pwq 2: cpus=1 node=0 flags=0x0 nice=0 active=23/256 refcnt=24 in-flight: 7754:rtc_timer_do_work pending: nfc_urelease_event_work, nfc_urelease_event_work, nfc_urelease_event_work, macvlan_process_broadcast, defense_work_handler, defense_work_handler, defense_work_handler, cache_reap, macvlan_process_broadcast, macvlan_process_broadcast, macvlan_process_broadcast, wait_rcu_exp_gp, nfc_urelease_event_work, nfc_urelease_event_work, nfc_urelease_event_work, nfc_urelease_event_work, nfc_urelease_event_work, nfc_urelease_event_work, macvlan_process_broadcast, macvlan_process_broadcast, free_obj_work, netstamp_clear pwq 0: cpus=0 node=0 flags=0x0 nice=0 active=1/256 refcnt=2 pending: defense_work_handler workqueue events_unbound: flags=0x2 pwq 4: cpus=0-1 flags=0x4 nice=0 active=4/512 refcnt=7 in-flight: 9255:fsnotify_mark_destroy_workfn fsnotify_mark_destroy_workfn, 2888:fsnotify_connector_destroy_workfn fsnotify_connector_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=2/256 refcnt=3 pending: fb_flashcursor, process_srcu workqueue mm_percpu_wq: flags=0x8 pwq 2: cpus=1 node=0 flags=0x0 nice=0 active=2/256 refcnt=4 pending: vmstat_update, lru_add_drain_per_cpu BAR(9559) workqueue ipv6_addrconf: flags=0x40008 pwq 2: cpus=1 node=0 flags=0x0 nice=0 active=1/1 refcnt=19 pending: addrconf_dad_work delayed: addrconf_dad_work, addrconf_dad_work, addrconf_dad_work, addrconf_dad_work, addrconf_dad_work, addrconf_dad_work, addrconf_dad_work, addrconf_dad_work, addrconf_dad_work, addrconf_dad_work, addrconf_dad_work, addrconf_dad_work, addrconf_dad_work, addrconf_dad_work, addrconf_dad_work, addrconf_dad_work, addrconf_dad_work workqueue bat_events: flags=0xe000a pwq 4: cpus=0-1 flags=0x4 nice=0 active=1/1 refcnt=10 pending: batadv_dat_purge delayed: batadv_bla_periodic_work, batadv_dat_purge, batadv_bla_periodic_work, batadv_purge_orig, batadv_purge_orig, batadv_iv_send_outstanding_bat_ogm_packet pool 2: cpus=1 node=0 flags=0x0 nice=0 hung=146s workers=5 idle: 24 2970 9179 19 pool 4: cpus=0-1 flags=0x4 nice=0 hung=0s workers=7 idle: 2880 23 5 119 71 INFO: task kworker/u4:5:2888 blocked for more than 140 seconds. Not tainted 4.14.288-syzkaller #0 "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. kworker/u4:5 D29088 2888 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:404 INFO: task kworker/u4:6:9255 blocked for more than 140 seconds. Not tainted 4.14.288-syzkaller #0 "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. kworker/u4:6 D29120 9255 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:404 INFO: task syz-executor.2:9559 blocked for more than 140 seconds. Not tainted 4.14.288-syzkaller #0 "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. syz-executor.2 D30104 9559 7984 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_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 flush_work+0x3fe/0x770 kernel/workqueue.c:2894 lru_add_drain_all_cpuslocked+0x2e6/0x450 mm/swap.c:722 lru_add_drain_all+0xf/0x20 mm/swap.c:730 migrate_prep+0xa/0x10 mm/migrate.c:70 do_migrate_pages mm/mempolicy.c:1030 [inline] SYSC_migrate_pages mm/mempolicy.c:1484 [inline] SyS_migrate_pages+0x4f9/0x6e0 mm/mempolicy.c:1401 do_syscall_64+0x1d5/0x640 arch/x86/entry/common.c:292 entry_SYSCALL_64_after_hwframe+0x46/0xbb RIP: 0033:0x7fcbff75c199 RSP: 002b:00007fcbfe0d1168 EFLAGS: 00000246 ORIG_RAX: 0000000000000100 RAX: ffffffffffffffda RBX: 00007fcbff86ef60 RCX: 00007fcbff75c199 RDX: 0000000000000000 RSI: 0000000000000005 RDI: 0000000000000000 RBP: 00007fcbff7b613b R08: 0000000000000000 R09: 0000000000000000 R10: 0000000020000540 R11: 0000000000000246 R12: 0000000000000000 R13: 00007fff02404b2f R14: 00007fcbfe0d1300 R15: 0000000000022000 INFO: task syz-executor.5:9580 blocked for more than 140 seconds. Not tainted 4.14.288-syzkaller #0 "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. syz-executor.5 D30104 9580 7986 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 account_event kernel/events/core.c:9512 [inline] perf_event_alloc.part.0+0x1c94/0x2640 kernel/events/core.c:9704 perf_event_alloc kernel/events/core.c:10025 [inline] SYSC_perf_event_open kernel/events/core.c:10129 [inline] SyS_perf_event_open+0x683/0x2530 kernel/events/core.c:10015 do_syscall_64+0x1d5/0x640 arch/x86/entry/common.c:292 entry_SYSCALL_64_after_hwframe+0x46/0xbb RIP: 0033:0x7f8d35924199 RSP: 002b:00007f8d34299168 EFLAGS: 00000246 ORIG_RAX: 000000000000012a RAX: ffffffffffffffda RBX: 00007f8d35a36f60 RCX: 00007f8d35924199 RDX: fbffffffffffffff RSI: 0000000000000000 RDI: 0000000020000440 RBP: 00007f8d3597e13b R08: 0000000000000000 R09: 0000000000000000 R10: ffffffffffffffff R11: 0000000000000246 R12: 0000000000000000 R13: 00007ffd6572857f R14: 00007f8d34299300 R15: 0000000000022000 INFO: task syz-executor.1:9576 blocked for more than 140 seconds. Not tainted 4.14.288-syzkaller #0 "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. syz-executor.1 D28880 9576 7988 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_sched+0xd8/0x1b0 kernel/rcu/tree.c:3239 account_event kernel/events/core.c:9520 [inline] perf_event_alloc.part.0+0x1e6c/0x2640 kernel/events/core.c:9704 perf_event_alloc kernel/events/core.c:10025 [inline] SYSC_perf_event_open kernel/events/core.c:10129 [inline] SyS_perf_event_open+0x683/0x2530 kernel/events/core.c:10015 do_syscall_64+0x1d5/0x640 arch/x86/entry/common.c:292 entry_SYSCALL_64_after_hwframe+0x46/0xbb RIP: 0033:0x7f0a84452199 RSP: 002b:00007f0a82dc7168 EFLAGS: 00000246 ORIG_RAX: 000000000000012a RAX: ffffffffffffffda RBX: 00007f0a84564f60 RCX: 00007f0a84452199 RDX: 0000000000000000 RSI: 0000000000000000 RDI: 0000000020000100 RBP: 00007f0a844ac13b R08: 0000000000000000 R09: 0000000000000000 R10: ffffffffffffffff R11: 0000000000000246 R12: 0000000000000000 R13: 00007fffdf73c5df R14: 00007f0a82dc7300 R15: 0000000000022000 Showing all locks held in the system: 1 lock held by khungtaskd/1534: #0: (tasklist_lock){.+.+}, at: [] debug_show_all_locks+0x7c/0x21a kernel/locking/lockdep.c:4548 2 locks held by kworker/u4:5/2888: #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 2 locks held by kworker/u4:6/9255: #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 2 locks held by syz-executor.2/9559: #0: (cpu_hotplug_lock.rw_sem){++++}, at: [] get_online_cpus include/linux/cpu.h:149 [inline] #0: (cpu_hotplug_lock.rw_sem){++++}, at: [] lru_add_drain_all+0xa/0x20 mm/swap.c:729 #1: (lock#6){+.+.}, at: [] lru_add_drain_all_cpuslocked+0x6c/0x450 mm/swap.c:704 2 locks held by syz-executor.5/9580: #0: (&sig->cred_guard_mutex){+.+.}, at: [] SYSC_perf_event_open kernel/events/core.c:10109 [inline] #0: (&sig->cred_guard_mutex){+.+.}, at: [] SyS_perf_event_open+0x131a/0x2530 kernel/events/core.c:10015 #1: (perf_sched_mutex){+.+.}, at: [] account_event kernel/events/core.c:9512 [inline] #1: (perf_sched_mutex){+.+.}, at: [] perf_event_alloc.part.0+0x1c94/0x2640 kernel/events/core.c:9704 3 locks held by syz-executor.1/9576: #0: (&sig->cred_guard_mutex){+.+.}, at: [] SYSC_perf_event_open kernel/events/core.c:10109 [inline] #0: (&sig->cred_guard_mutex){+.+.}, at: [] SyS_perf_event_open+0x131a/0x2530 kernel/events/core.c:10015 #1: (perf_sched_mutex){+.+.}, at: [] account_event kernel/events/core.c:9512 [inline] #1: (perf_sched_mutex){+.+.}, at: [] perf_event_alloc.part.0+0x1c94/0x2640 kernel/events/core.c:9704 #2: (rcu_sched_state.exp_mutex){+.+.}, at: [] exp_funnel_lock kernel/rcu/tree_exp.h:272 [inline] #2: (rcu_sched_state.exp_mutex){+.+.}, at: [] _synchronize_rcu_expedited+0x2c2/0x770 kernel/rcu/tree_exp.h:596 ============================================= NMI backtrace for cpu 0 CPU: 0 PID: 1534 Comm: khungtaskd Not tainted 4.14.288-syzkaller #0 Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 06/29/2022 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:404 Sending NMI from CPU 0 to CPUs 1: NMI backtrace for cpu 1 CPU: 1 PID: 2880 Comm: kworker/u4:4 Not tainted 4.14.288-syzkaller #0 Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 06/29/2022 Workqueue: bat_events batadv_nc_worker task: ffff8880ac392640 task.stack: ffff8880ac260000 RIP: 0010:__lock_acquire+0x3c5/0x3f20 kernel/locking/lockdep.c:3435 RSP: 0018:ffff8880ac267aa8 EFLAGS: 00000046 RAX: dffffc0000000000 RBX: 0000000000000000 RCX: 00000000000005c9 RDX: 1ffff110158725e6 RSI: 1ffff110158725e2 RDI: ffff8880ac392f28 RBP: 0000000000000002 R08: 0000000000000000 R09: 0000000000000000 R10: ffff8880ac392f18 R11: ffff8880ac392640 R12: ffff8880ac392f30 R13: 0000000000000011 R14: 0000000000000011 R15: ffffffff8beccd80 FS: 0000000000000000(0000) GS:ffff8880ba500000(0000) knlGS:0000000000000000 CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 CR2: 00007f4f30eb2000 CR3: 00000000a1ad9000 CR4: 00000000003406e0 DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000 DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400 Call Trace: lock_acquire+0x170/0x3f0 kernel/locking/lockdep.c:3998 rcu_lock_acquire include/linux/rcupdate.h:242 [inline] rcu_read_lock include/linux/rcupdate.h:629 [inline] batadv_nc_purge_orig_hash net/batman-adv/network-coding.c:418 [inline] batadv_nc_worker+0x124/0xc50 net/batman-adv/network-coding.c:728 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:404 Code: 00 fc ff df 80 3c 02 00 0f 85 4a 27 00 00 48 b8 00 00 00 00 00 fc ff df 4d 89 62 10 4d 8d 62 18 4c 89 e2 48 c1 ea 03 80 3c 02 00 <0f> 85 59 27 00 00 48 8b 84 24 b8 01 00 00 49 8d bb 54 08 00 00 ---------------- Code disassembly (best guess), 3 bytes skipped: 0: df 80 3c 02 00 0f filds 0xf00023c(%rax) 6: 85 4a 27 test %ecx,0x27(%rdx) 9: 00 00 add %al,(%rax) b: 48 b8 00 00 00 00 00 movabs $0xdffffc0000000000,%rax 12: fc ff df 15: 4d 89 62 10 mov %r12,0x10(%r10) 19: 4d 8d 62 18 lea 0x18(%r10),%r12 1d: 4c 89 e2 mov %r12,%rdx 20: 48 c1 ea 03 shr $0x3,%rdx 24: 80 3c 02 00 cmpb $0x0,(%rdx,%rax,1) * 28: 0f 85 59 27 00 00 jne 0x2787 <-- trapping instruction 2e: 48 8b 84 24 b8 01 00 mov 0x1b8(%rsp),%rax 35: 00 36: 49 8d bb 54 08 00 00 lea 0x854(%r11),%rdi