Bluetooth: hci0 command 0x0406 tx timeout Bluetooth: hci2 command 0x0406 tx timeout Bluetooth: hci1 command 0x0406 tx timeout Bluetooth: hci5 command 0x0406 tx timeout BUG: workqueue lockup - pool cpus=0 node=0 flags=0x0 nice=0 stuck for 157s! INFO: task kworker/u4:0:5 blocked for more than 140 seconds. Showing busy workqueues and worker pools: workqueue events: flags=0x0 Not tainted 4.14.280-syzkaller #0 pwq 0: "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. kworker/u4:0 D27264 5 2 0x80000000 Workqueue: events_unbound fsnotify_connector_destroy_workfn Call Trace: cpus=0 node=0 flags=0x0 nice=0 active=23/256 refcnt=24 in-flight: 24:rtc_timer_do_work pending: defense_work_handler, defense_work_handler, sg_remove_sfp_usercontext, perf_sched_delayed, vmstat_shepherd, cache_reap, smc_close_sock_put_work, defense_work_handler, smc_close_sock_put_work, defense_work_handler context_switch kernel/sched/core.c:2811 [inline] __schedule+0x88b/0x1de0 kernel/sched/core.c:3387 , defense_work_handler , destroy_super_work , wait_rcu_exp_gp schedule+0x8d/0x1b0 kernel/sched/core.c:3431 , macvlan_process_broadcast, macvlan_process_broadcast, macvlan_process_broadcast, macvlan_process_broadcast, macvlan_process_broadcast, macvlan_process_broadcast, macvlan_process_broadcast, hci_cmd_timeout schedule_timeout+0x80a/0xe90 kernel/time/timer.c:1724 , hci_cmd_timeout workqueue events_unbound: flags=0x2 pwq 4: cpus=0-1 flags=0x4 nice=0 active=4/512 refcnt=7 in-flight: 1023:fsnotify_mark_destroy_workfn fsnotify_mark_destroy_workfn, 5:fsnotify_connector_destroy_workfn fsnotify_connector_destroy_workfn workqueue events_power_efficient: flags=0x80 pwq 0: cpus=0 node=0 flags=0x0 nice=0 active=2/256 refcnt=3 pending: do_cache_clean, process_srcu workqueue mm_percpu_wq: flags=0x8 pwq 0: cpus=0 node=0 flags=0x0 nice=0 active=1/256 refcnt=2 pending: vmstat_update workqueue dm_bufio_cache: flags=0x8 pwq 0: cpus=0 node=0 flags=0x0 nice=0 active=1/256 refcnt=2 pending: work_fn workqueue bat_events: flags=0xe000a pwq 4: cpus=0-1 flags=0x4 nice=0 active=1/1 refcnt=6 pending: batadv_nc_worker delayed: batadv_nc_worker, batadv_nc_worker pool 0: cpus=0 node=0 flags=0x0 nice=0 hung=157s workers=5 idle: 3626 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 9096 9107 3 pool 4: cpus=0-1 flags=0x4 nice=0 hung=0s workers=6 idle: 25 9336 2857 22 __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:3:1023 blocked for more than 140 seconds. Not tainted 4.14.280-syzkaller #0 "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. kworker/u4:3 D27040 1023 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.3:10128 blocked for more than 140 seconds. Not tainted 4.14.280-syzkaller #0 "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. syz-executor.3 D28912 10128 8005 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 synchronize_net+0x3a/0x40 net/core/dev.c:8250 packet_release+0x740/0xa80 net/packet/af_packet.c:3117 __sock_release+0xcd/0x2b0 net/socket.c:602 sock_close+0x15/0x20 net/socket.c:1139 __fput+0x25f/0x7a0 fs/file_table.c:210 task_work_run+0x11f/0x190 kernel/task_work.c:113 tracehook_notify_resume include/linux/tracehook.h:191 [inline] exit_to_usermode_loop+0x1ad/0x200 arch/x86/entry/common.c:164 prepare_exit_to_usermode arch/x86/entry/common.c:199 [inline] syscall_return_slowpath arch/x86/entry/common.c:270 [inline] do_syscall_64+0x4a3/0x640 arch/x86/entry/common.c:297 entry_SYSCALL_64_after_hwframe+0x46/0xbb RIP: 0033:0x7f2d6c275d2b RSP: 002b:00007ffee7f49b50 EFLAGS: 00000293 ORIG_RAX: 0000000000000003 RAX: 0000000000000000 RBX: 0000000000000012 RCX: 00007f2d6c275d2b RDX: 0000000000000000 RSI: 0000001b2f9258d0 RDI: 0000000000000011 RBP: 00007f2d6c3d7960 R08: 0000000000000000 R09: 00000000cd772a31 R10: 0000000000000000 R11: 0000000000000293 R12: 000000000001f792 R13: 00007ffee7f49c50 R14: 00007f2d6c3d6030 R15: 0000000000000064 INFO: task syz-executor.5:10184 blocked for more than 140 seconds. Not tainted 4.14.280-syzkaller #0 "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. syz-executor.5 D29344 10184 8003 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 synchronize_net+0x3a/0x40 net/core/dev.c:8250 packet_set_ring+0x369/0x1a10 net/packet/af_packet.c:4459 packet_setsockopt+0x1047/0x26d0 net/packet/af_packet.c:3778 SYSC_setsockopt net/socket.c:1865 [inline] SyS_setsockopt+0x110/0x1e0 net/socket.c:1844 do_syscall_64+0x1d5/0x640 arch/x86/entry/common.c:292 entry_SYSCALL_64_after_hwframe+0x46/0xbb RIP: 0033:0x7f3e9f6ff0e9 RSP: 002b:00007f3e9e074168 EFLAGS: 00000246 ORIG_RAX: 0000000000000036 RAX: ffffffffffffffda RBX: 00007f3e9f811f60 RCX: 00007f3e9f6ff0e9 RDX: 0000000000000005 RSI: 0000000000000107 RDI: 0000000000000003 RBP: 00007f3e9f75908d R08: 000000000000001c R09: 0000000000000000 R10: 0000000020000000 R11: 0000000000000246 R12: 0000000000000000 R13: 00007ffe4fb5afdf R14: 00007f3e9e074300 R15: 0000000000022000 Showing all locks held in the system: 2 locks held by kworker/u4:0/5: #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:3/1023: #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 2 locks held by syz-executor.3/10128: #0: (&sb->s_type->i_mutex_key#13){+.+.}, at: [] inode_lock include/linux/fs.h:719 [inline] #0: (&sb->s_type->i_mutex_key#13){+.+.}, at: [] __sock_release+0x86/0x2b0 net/socket.c:601 #1: (rcu_preempt_state.exp_mutex){+.+.}, at: [] exp_funnel_lock kernel/rcu/tree_exp.h:272 [inline] #1: (rcu_preempt_state.exp_mutex){+.+.}, at: [] _synchronize_rcu_expedited+0x2c2/0x770 kernel/rcu/tree_exp.h:596 2 locks held by syz-executor.5/10184: #0: (sk_lock-AF_PACKET){+.+.}, at: [] lock_sock include/net/sock.h:1473 [inline] #0: (sk_lock-AF_PACKET){+.+.}, at: [] packet_setsockopt+0x32f/0x26d0 net/packet/af_packet.c:3761 #1: (rcu_preempt_state.exp_mutex){+.+.}, at: [] exp_funnel_lock kernel/rcu/tree_exp.h:305 [inline] #1: (rcu_preempt_state.exp_mutex){+.+.}, at: [] _synchronize_rcu_expedited+0x32d/0x770 kernel/rcu/tree_exp.h:596 ============================================= NMI backtrace for cpu 1 CPU: 1 PID: 1533 Comm: khungtaskd Not tainted 4.14.280-syzkaller #0 Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 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 1 to CPUs 0: NMI backtrace for cpu 0 CPU: 0 PID: 25 Comm: kworker/u4:2 Not tainted 4.14.280-syzkaller #0 Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 Workqueue: bat_events batadv_nc_worker task: ffff8880b562e680 task.stack: ffff8880b5638000 RIP: 0010:arch_local_irq_restore arch/x86/include/asm/paravirt.h:779 [inline] RIP: 0010:lock_release+0x41e/0x870 kernel/locking/lockdep.c:4020 RSP: 0018:ffff8880b563fbc8 EFLAGS: 00000282 RAX: 1ffffffff11e1311 RBX: 1ffff11016ac7f7c RCX: 1ffff11016ac5deb RDX: dffffc0000000000 RSI: 0000000000000003 RDI: 0000000000000282 RBP: ffff8880b562e680 R08: ffffffff8b9d01d8 R09: 0000000000000002 R10: 0000000000000000 R11: ffff8880b562e680 R12: effd37c6d001733a R13: 0000000000000003 R14: ffff8880b562e680 R15: 0000000000000003 FS: 0000000000000000(0000) GS:ffff8880ba400000(0000) knlGS:0000000000000000 CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 CR2: 00007fae2bdc1000 CR3: 00000000a952b000 CR4: 00000000003406f0 DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000 DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400 Call Trace: __raw_spin_unlock_bh include/linux/spinlock_api_smp.h:174 [inline] _raw_spin_unlock_bh+0x17/0x30 kernel/locking/spinlock.c:208 spin_unlock_bh include/linux/spinlock.h:362 [inline] batadv_nc_purge_paths+0x20c/0x300 net/batman-adv/network-coding.c:481 batadv_nc_worker+0x660/0xc50 net/batman-adv/network-coding.c:731 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: 85 84 08 00 00 00 00 00 00 48 c1 e8 03 80 3c 10 00 0f 85 88 03 00 00 48 83 3d 96 c6 ae 07 00 0f 84 ba 01 00 00 48 8b 3c 24 57 9d <0f> 1f 44 00 00 48 b8 00 00 00 00 00 fc ff df 48 c7 04 03 00 00 ---------------- Code disassembly (best guess): 0: 85 84 08 00 00 00 00 test %eax,0x0(%rax,%rcx,1) 7: 00 00 add %al,(%rax) 9: 48 c1 e8 03 shr $0x3,%rax d: 80 3c 10 00 cmpb $0x0,(%rax,%rdx,1) 11: 0f 85 88 03 00 00 jne 0x39f 17: 48 83 3d 96 c6 ae 07 cmpq $0x0,0x7aec696(%rip) # 0x7aec6b5 1e: 00 1f: 0f 84 ba 01 00 00 je 0x1df 25: 48 8b 3c 24 mov (%rsp),%rdi 29: 57 push %rdi 2a: 9d popfq * 2b: 0f 1f 44 00 00 nopl 0x0(%rax,%rax,1) <-- trapping instruction 30: 48 b8 00 00 00 00 00 movabs $0xdffffc0000000000,%rax 37: fc ff df 3a: 48 rex.W 3b: c7 .byte 0xc7 3c: 04 03 add $0x3,%al