Bluetooth: hci3 command 0x0406 tx timeout Bluetooth: hci7 command 0x0409 tx timeout Bluetooth: hci7 command 0x041b tx timeout Bluetooth: hci7 command 0x040f tx timeout Bluetooth: hci7 command 0x0419 tx timeout BUG: workqueue lockup - pool cpus=0 INFO: task kworker/u4:3:201 blocked for more than 140 seconds. Not tainted 4.14.300-syzkaller #0 "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. kworker/u4:3 D26088 201 2 0x80000000 node=0 flags=0x0 nice=0 stuck for 166s! Workqueue: events_unbound fsnotify_mark_destroy_workfn Showing busy workqueues and worker pools: workqueue events: flags=0x0 pwq 0: cpus=0 Call Trace: node=0 flags=0x0 nice=0 context_switch kernel/sched/core.c:2811 [inline] __schedule+0x88b/0x1de0 kernel/sched/core.c:3387 active=27/256 refcnt=28 in-flight: 8464:rtc_timer_do_work schedule+0x8d/0x1b0 kernel/sched/core.c:3431 pending: destroy_super_work, wait_rcu_exp_gp, nfc_urelease_event_work, release_one_tty, release_one_tty, defense_work_handler, defense_work_handler, macvlan_process_broadcast, defense_work_handler schedule_timeout+0x80a/0xe90 kernel/time/timer.c:1724 , vmstat_shepherd, cache_reap, perf_sched_delayed, macvlan_process_broadcast, macvlan_process_broadcast, macvlan_process_broadcast, macvlan_process_broadcast, macvlan_process_broadcast, macvlan_process_broadcast , macvlan_process_broadcast , proc_cleanup_work, hci_cmd_timeout, hci_cmd_timeout, hci_cmd_timeout, hci_cmd_timeout, hci_cmd_timeout, rfkill_any_led_trigger_worker workqueue events_long: flags=0x0 pwq 0: cpus=0 node=0 flags=0x0 nice=0 active=1/256 refcnt=2 pending: gc_worker workqueue events_unbound: flags=0x2 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 pwq 4: cpus=0-1 flags=0x4 nice=0 active=4/512 refcnt=7 __synchronize_srcu+0x10a/0x1d0 kernel/rcu/srcutree.c:898 in-flight: 201:fsnotify_mark_destroy_workfn fsnotify_mark_destroy_workfn, 8964: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=4/256 refcnt=5 pending: process_srcu, fb_flashcursor, neigh_periodic_work, neigh_periodic_work 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 ipv6_addrconf: flags=0x40008 pwq 0: cpus=0 node=0 flags=0x0 nice=0 active=1/1 refcnt=4 pending: addrconf_dad_work delayed: addrconf_dad_work , addrconf_dad_work pool 0: fsnotify_mark_destroy_workfn+0xed/0x2e0 fs/notify/mark.c:757 cpus=0 node=0 flags=0x0 nice=0 hung=166s workers=5 idle: 3 3559 9077 24 pool 4: cpus=0-1 flags=0x4 nice=0 hung=0s workers=7 idle: 22 34 3060 9359 5 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:5:8964 blocked for more than 140 seconds. Not tainted 4.14.300-syzkaller #0 "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. kworker/u4:5 D26992 8964 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 INFO: task syz-executor.3:9728 blocked for more than 140 seconds. Not tainted 4.14.300-syzkaller #0 "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. syz-executor.3 D29056 9728 8033 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+0x5e/0xd3 Showing all locks held in the system: 2 locks held by kworker/u4:3/201: #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 kworker/u4:5/8964: #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 syz-executor.3/9728: #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.2/9788: #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.3/9826: #0: (net_mutex){+.+.}, at: [] copy_net_ns+0x156/0x440 net/core/net_namespace.c:413 1 lock held by syz-executor.3/9838: #0: (net_mutex){+.+.}, at: [] copy_net_ns+0x156/0x440 net/core/net_namespace.c:413 ============================================= NMI backtrace for cpu 1 CPU: 1 PID: 1533 Comm: khungtaskd Not tainted 4.14.300-syzkaller #0 Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 10/26/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:406 Sending NMI from CPU 1 to CPUs 0: NMI backtrace for cpu 0 CPU: 0 PID: 8464 Comm: kworker/0:3 Not tainted 4.14.300-syzkaller #0 Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 10/26/2022 Workqueue: events rtc_timer_do_work task: ffff888090e42440 task.stack: ffff888090df8000 RIP: 0010:rol32 include/linux/bitops.h:83 [inline] RIP: 0010:iterate_chain_key kernel/locking/lockdep.c:323 [inline] RIP: 0010:__lock_acquire+0x7af/0x3f20 kernel/locking/lockdep.c:3486 RSP: 0018:ffff888090dff960 EFLAGS: 00000086 RAX: 00000000c1f0ebfa RBX: c359f5c25168ecf9 RCX: 00000000b3bb0874 RDX: 00000000976dbfc5 RSI: 00000000e3b2b751 RDI: ffff888090e42d61 RBP: 0000000000000000 R08: 0000000000000001 R09: 00000000000c0627 R10: ffff888090e42d40 R11: ffff888090e42440 R12: ffff888090e42d58 R13: 0000000000000000 R14: 000000000e35e386 R15: ffffffff8becbdc0 FS: 0000000000000000(0000) GS:ffff8880ba400000(0000) knlGS:0000000000000000 CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 CR2: 00007f219cdbe000 CR3: 00000000a29e9000 CR4: 00000000003406f0 DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000 DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400 Call Trace: lock_acquire+0x170/0x3f0 kernel/locking/lockdep.c:3998 __raw_spin_lock_irqsave include/linux/spinlock_api_smp.h:110 [inline] _raw_spin_lock_irqsave+0x8c/0xc0 kernel/locking/spinlock.c:160 rtc_handle_legacy_irq+0x28/0x180 drivers/rtc/interface.c:513 rtc_timer_do_work+0x1f7/0x5a0 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: 01 f0 c1 c1 08 31 ca 89 d1 41 29 d6 01 c2 c1 c1 10 89 d6 41 31 ce 44 89 f1 44 29 f0 41 01 d6 c1 c9 0d 31 c8 89 c1 29 c6 44 01 f0 c1 04 31 f1 48 c1 e1 20 48 09 c1 48 83 bc 24 b8 01 00 00 00 ---------------- Code disassembly (best guess): 0: 01 f0 add %esi,%eax 2: c1 c1 08 rol $0x8,%ecx 5: 31 ca xor %ecx,%edx 7: 89 d1 mov %edx,%ecx 9: 41 29 d6 sub %edx,%r14d c: 01 c2 add %eax,%edx e: c1 c1 10 rol $0x10,%ecx 11: 89 d6 mov %edx,%esi 13: 41 31 ce xor %ecx,%r14d 16: 44 89 f1 mov %r14d,%ecx 19: 44 29 f0 sub %r14d,%eax 1c: 41 01 d6 add %edx,%r14d 1f: c1 c9 0d ror $0xd,%ecx 22: 31 c8 xor %ecx,%eax 24: 89 c1 mov %eax,%ecx 26: 29 c6 sub %eax,%esi 28: 44 01 f0 add %r14d,%eax * 2b: c1 c1 04 rol $0x4,%ecx <-- trapping instruction 2e: 31 f1 xor %esi,%ecx 30: 48 c1 e1 20 shl $0x20,%rcx 34: 48 09 c1 or %rax,%rcx 37: 48 83 bc 24 b8 01 00 cmpq $0x0,0x1b8(%rsp) 3e: 00 00