Bluetooth: hci9 command 0x041b tx timeout Bluetooth: hci8 command 0x040f tx timeout Bluetooth: hci9 command 0x040f tx timeout Bluetooth: hci8 command 0x0419 tx timeout Bluetooth: hci9 command 0x0419 tx timeout BUG: workqueue lockup - pool cpus=0 node=0 flags=0x0 nice=0 stuck for 186s! INFO: task syz-executor.4:10408 blocked for more than 140 seconds. Showing busy workqueues and worker pools: workqueue events: flags=0x0 Not tainted 4.14.290-syzkaller #0 pwq 0: cpus=0 node=0 flags=0x0 nice=0 active=55/256 refcnt=56 "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. syz-executor.4 D28912 10408 7977 0x00000004 in-flight: Call Trace: 12029:rtc_timer_do_work pending: defense_work_handler, defense_work_handler, defense_work_handler, defense_work_handler, vmstat_shepherd, cache_reap, pwq_unbound_release_workfn, pwq_unbound_release_workfn context_switch kernel/sched/core.c:2811 [inline] __schedule+0x88b/0x1de0 kernel/sched/core.c:3387 , pwq_unbound_release_workfn, pwq_unbound_release_workfn, pwq_unbound_release_workfn , pwq_unbound_release_workfn, pwq_unbound_release_workfn, pwq_unbound_release_workfn, pwq_unbound_release_workfn, pwq_unbound_release_workfn, pwq_unbound_release_workfn, pwq_unbound_release_workfn, pwq_unbound_release_workfn, pwq_unbound_release_workfn, pwq_unbound_release_workfn, pwq_unbound_release_workfn, pwq_unbound_release_workfn, pwq_unbound_release_workfn , pwq_unbound_release_workfn, free_obj_work, pwq_unbound_release_workfn, pwq_unbound_release_workfn, pwq_unbound_release_workfn, pwq_unbound_release_workfn, pwq_unbound_release_workfn, pwq_unbound_release_workfn, pwq_unbound_release_workfn, pwq_unbound_release_workfn, pwq_unbound_release_workfn schedule+0x8d/0x1b0 kernel/sched/core.c:3431 , pwq_unbound_release_workfn, pwq_unbound_release_workfn, pwq_unbound_release_workfn, pwq_unbound_release_workfn, pwq_unbound_release_workfn, release_one_tty, pwq_unbound_release_workfn, wait_rcu_exp_gp, pwq_unbound_release_workfn, pwq_unbound_release_workfn _synchronize_rcu_expedited+0x522/0x770 kernel/rcu/tree_exp.h:615 , pwq_unbound_release_workfn, pwq_unbound_release_workfn, release_one_tty, release_one_tty, macvlan_process_broadcast, macvlan_process_broadcast, pwq_unbound_release_workfn, rfkill_any_led_trigger_worker, bpf_prog_free_deferred workqueue events_long: flags=0x0 pwq 0: cpus=0 node=0 flags=0x0 nice=0 active=2/256 refcnt=3 pending: br_fdb_cleanup, br_fdb_cleanup workqueue events_power_efficient: flags=0x80 pwq 0: cpus=0 node=0 flags=0x0 nice=0 active=1/256 refcnt=2 pending: do_cache_clean 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 ipv6_addrconf: flags=0x40008 pwq 0: cpus=0 node=0 flags=0x0 nice=0 active=1/1 refcnt=2 pending: addrconf_verify_work pool 0: cpus=0 node=0 flags=0x0 nice=0 hung=187s workers=2 idle: 17323 synchronize_rcu+0x98/0x130 kernel/rcu/tree_plugin.h:762 sk_common_release+0xba/0x2c0 net/core/sock.c:3042 pn_socket_release+0x78/0xa0 net/phonet/socket.c:47 __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:0x7fe9a88d5e4b RSP: 002b:00007ffc8a019e00 EFLAGS: 00000293 ORIG_RAX: 0000000000000003 RAX: 0000000000000000 RBX: 0000000000000005 RCX: 00007fe9a88d5e4b RDX: 00007fe9a8a3a5c0 RSI: ffffffffffffffff RDI: 0000000000000004 RBP: 00007fe9a8a37960 R08: 0000000000000000 R09: 00007fe9a8a3a5c8 R10: 00007ffc8a019f00 R11: 0000000000000293 R12: 00000000002092db R13: 00007ffc8a019f00 R14: 00007fe9a8a35f60 R15: 0000000000000032 INFO: task syz-executor.0:10425 blocked for more than 140 seconds. Not tainted 4.14.290-syzkaller #0 "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. syz-executor.0 D28912 10425 7971 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 sk_common_release+0xba/0x2c0 net/core/sock.c:3042 pn_socket_release+0x78/0xa0 net/phonet/socket.c:47 __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:0x7fb313455e4b RSP: 002b:00007fffeeee2cb0 EFLAGS: 00000293 ORIG_RAX: 0000000000000003 RAX: 0000000000000000 RBX: 0000000000000006 RCX: 00007fb313455e4b RDX: 00007fb3135bc5e0 RSI: ffffffffffffffff RDI: 0000000000000005 RBP: 00007fb3135b7960 R08: 0000000000000000 R09: 00007fb3135bc5e8 R10: 00007fffeeee2db0 R11: 0000000000000293 R12: 00000000002095f0 R13: 00007fffeeee2db0 R14: 00007fb3135b6030 R15: 0000000000000032 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 syz-executor.4/10408: #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.0/10425: #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:305 [inline] #1: (rcu_preempt_state.exp_mutex){+.+.}, at: [] _synchronize_rcu_expedited+0x32d/0x770 kernel/rcu/tree_exp.h:596 1 lock held by syz-executor.4/10534: #0: (net_mutex){+.+.}, at: [] copy_net_ns+0x156/0x440 net/core/net_namespace.c:413 1 lock held by syz-executor.4/10558: #0: (net_mutex){+.+.}, at: [] copy_net_ns+0x156/0x440 net/core/net_namespace.c:413 1 lock held by syz-executor.0/10560: #0: (net_mutex){+.+.}, at: [] copy_net_ns+0x156/0x440 net/core/net_namespace.c:413 ============================================= NMI backtrace for cpu 1 CPU: 1 PID: 1534 Comm: khungtaskd Not tainted 4.14.290-syzkaller #0 Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 07/22/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 1 to CPUs 0: NMI backtrace for cpu 0 CPU: 0 PID: 27401 Comm: kworker/u4:2 Not tainted 4.14.290-syzkaller #0 Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 07/22/2022 Workqueue: bat_events batadv_nc_worker task: ffff8880a1f322c0 task.stack: ffff8880b53a0000 RIP: 0010:__lock_release kernel/locking/lockdep.c:3794 [inline] RIP: 0010:lock_release+0x2e8/0x870 kernel/locking/lockdep.c:4017 RSP: 0018:ffff8880b53a7c48 EFLAGS: 00000046 RAX: 0000000000000003 RBX: 1ffff11016a74f8c RCX: 0000000000000000 RDX: ffff8880a1f32b98 RSI: ffffffff88f78100 RDI: ffff8880a1f32bba RBP: ffffffff88f78100 R08: 0000000000000000 R09: 0000000000000001 R10: 0000000000000000 R11: ffff8880a1f322c0 R12: ffffffff86eb3b70 R13: ffffffff8beccd80 R14: ffff8880a1f322c0 R15: 0000000000000003 FS: 0000000000000000(0000) GS:ffff8880ba400000(0000) knlGS:0000000000000000 CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 CR2: 00007f3e84216000 CR3: 00000000a1010000 CR4: 00000000003406f0 DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000 DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400 Call Trace: rcu_lock_release include/linux/rcupdate.h:247 [inline] rcu_read_unlock include/linux/rcupdate.h:685 [inline] batadv_nc_purge_orig_hash net/batman-adv/network-coding.c:422 [inline] batadv_nc_worker+0x5a7/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: fc ff df 48 89 f9 48 c1 e9 03 0f b6 04 01 84 c0 74 08 3c 03 0f 8e 66 03 00 00 66 83 6a 22 10 66 f7 42 22 f0 ff 0f 85 bf 00 00 00 <48> b8 00 00 00 00 00 fc ff df 48 8b 4c 24 08 8b 6c 24 38 48 c1 ---------------- Code disassembly (best guess), 2 bytes skipped: 0: df 48 89 fisttps -0x77(%rax) 3: f9 stc 4: 48 c1 e9 03 shr $0x3,%rcx 8: 0f b6 04 01 movzbl (%rcx,%rax,1),%eax c: 84 c0 test %al,%al e: 74 08 je 0x18 10: 3c 03 cmp $0x3,%al 12: 0f 8e 66 03 00 00 jle 0x37e 18: 66 83 6a 22 10 subw $0x10,0x22(%rdx) 1d: 66 f7 42 22 f0 ff testw $0xfff0,0x22(%rdx) 23: 0f 85 bf 00 00 00 jne 0xe8 * 29: 48 b8 00 00 00 00 00 movabs $0xdffffc0000000000,%rax <-- trapping instruction 30: fc ff df 33: 48 8b 4c 24 08 mov 0x8(%rsp),%rcx 38: 8b 6c 24 38 mov 0x38(%rsp),%ebp 3c: 48 rex.W 3d: c1 .byte 0xc1