Bluetooth: hci7 command 0x0419 tx timeout Bluetooth: hci8 command 0x0409 tx timeout Bluetooth: hci8 command 0x041b tx timeout Bluetooth: hci8 command 0x040f tx timeout Bluetooth: hci8 command 0x0419 tx timeout BUG: workqueue lockup - pool cpus=1 node=0 flags=0x0 nice=0 stuck for 243s! Showing busy workqueues and worker pools: workqueue events: flags=0x0 pwq 2: cpus=1 node=0 flags=0x0 nice=0 active=31/256 refcnt=32 in-flight: 9143:rtc_timer_do_work pending: smc_close_sock_put_work, macvlan_process_broadcast, cache_reap, macvlan_process_broadcast, macvlan_process_broadcast, macvlan_process_broadcast, macvlan_process_broadcast, macvlan_process_broadcast, macvlan_process_broadcast, macvlan_process_broadcast, macvlan_process_broadcast, sg_remove_sfp_usercontext, nfc_urelease_event_work, nfc_urelease_event_work, nfc_urelease_event_work, nfc_urelease_event_work, nfc_urelease_event_work, pwq_unbound_release_workfn, wait_rcu_exp_gp, nfc_urelease_event_work, nfc_urelease_event_work, nfc_urelease_event_work, nfc_urelease_event_work, hci_cmd_timeout, l2cap_info_timeout, hci_cmd_timeout, hci_cmd_timeout, hci_cmd_timeout, hci_cmd_timeout, hci_cmd_timeout workqueue events_long: flags=0x0 pwq 2: cpus=1 node=0 flags=0x0 nice=0 active=1/256 refcnt=2 pending: gc_worker workqueue events_unbound: flags=0x2 pwq 4: cpus=0-1 flags=0x4 nice=0 active=4/512 refcnt=8 in-flight: 1145:fsnotify_mark_destroy_workfn fsnotify_mark_destroy_workfn BAR(9957), 2864: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=4/256 refcnt=5 pending: process_srcu, fb_flashcursor, do_cache_clean, neigh_periodic_work 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 0: cpus=0 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=6 pending: batadv_iv_send_outstanding_bat_ogm_packet delayed: batadv_iv_send_outstanding_bat_ogm_packet, batadv_iv_send_outstanding_bat_ogm_packet pool 2: cpus=1 node=0 flags=0x0 nice=0 hung=243s workers=5 idle: 18 3476 9182 23 pool 4: cpus=0-1 flags=0x4 nice=0 hung=0s workers=7 idle: 34 10019 5 9381 22 INFO: task kworker/u4:3:1145 blocked for more than 140 seconds. Not tainted 4.14.299-syzkaller #0 "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. kworker/u4:3 D27264 1145 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 kworker/u4:4:2864 blocked for more than 140 seconds. Not tainted 4.14.299-syzkaller #0 "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. kworker/u4:4 D27600 2864 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.1:9957 blocked for more than 140 seconds. Not tainted 4.14.299-syzkaller #0 "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. syz-executor.1 D29144 9957 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 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 fsnotify_destroy_group+0x111/0x2a0 fs/notify/group.c:85 inotify_release+0x46/0x80 fs/notify/inotify/inotify_user.c:280 __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 INFO: task syz-executor.2:9999 blocked for more than 140 seconds. Not tainted 4.14.299-syzkaller #0 "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. syz-executor.2 D29912 9999 8013 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 caif_disconnect_client+0xb8/0x1d0 net/caif/cfcnfg.c:197 caif_release+0x174/0x340 net/caif/caif_socket.c:926 __sock_release+0x1fc/0x2b0 net/socket.c:602 sock_release net/socket.c:623 [inline] SYSC_socketpair net/socket.c:1460 [inline] SyS_socketpair+0x360/0x480 net/socket.c:1366 do_syscall_64+0x1d5/0x640 arch/x86/entry/common.c:292 entry_SYSCALL_64_after_hwframe+0x5e/0xd3 INFO: task syz-executor.0:10017 blocked for more than 140 seconds. Not tainted 4.14.299-syzkaller #0 "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. syz-executor.0 D27808 10017 7996 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 nf_tables_abort+0x12ff/0x2360 net/netfilter/nf_tables_api.c:5367 nfnetlink_rcv_batch net/netfilter/nfnetlink.c:448 [inline] nfnetlink_rcv_skb_batch net/netfilter/nfnetlink.c:495 [inline] nfnetlink_rcv+0x13e3/0x1da0 net/netfilter/nfnetlink.c:513 netlink_unicast_kernel net/netlink/af_netlink.c:1296 [inline] netlink_unicast+0x437/0x610 net/netlink/af_netlink.c:1322 netlink_sendmsg+0x648/0xbc0 net/netlink/af_netlink.c:1893 sock_sendmsg_nosec net/socket.c:646 [inline] sock_sendmsg+0xb5/0x100 net/socket.c:656 ___sys_sendmsg+0x6c8/0x800 net/socket.c:2062 __sys_sendmsg+0xa3/0x120 net/socket.c:2096 SYSC_sendmsg net/socket.c:2107 [inline] SyS_sendmsg+0x27/0x40 net/socket.c:2103 do_syscall_64+0x1d5/0x640 arch/x86/entry/common.c:292 entry_SYSCALL_64_after_hwframe+0x5e/0xd3 INFO: task syz-executor.1:10033 blocked for more than 140 seconds. Not tainted 4.14.299-syzkaller #0 "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. syz-executor.1 D27760 10033 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 nft_register_afinfo+0x63/0x190 net/netfilter/nf_tables_api.c:39 nf_tables_inet_init_net+0xb5/0x140 net/netfilter/nf_tables_inet.c:48 ops_init+0xaa/0x3e0 net/core/net_namespace.c:118 setup_net+0x22f/0x530 net/core/net_namespace.c:298 copy_net_ns+0x19b/0x440 net/core/net_namespace.c:422 create_new_namespaces+0x375/0x720 kernel/nsproxy.c:107 unshare_nsproxy_namespaces+0xa1/0x1d0 kernel/nsproxy.c:206 SYSC_unshare kernel/fork.c:2413 [inline] SyS_unshare+0x308/0x7f0 kernel/fork.c:2363 do_syscall_64+0x1d5/0x640 arch/x86/entry/common.c:292 entry_SYSCALL_64_after_hwframe+0x5e/0xd3 Showing all locks held in the system: 2 locks held by kworker/u4:3/1145: #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:4/2864: #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.2/9999: #0: (sk_lock-AF_CAIF){+.+.}, at: [] lock_sock include/net/sock.h:1473 [inline] #0: (sk_lock-AF_CAIF){+.+.}, at: [] caif_release+0xda/0x340 net/caif/caif_socket.c:922 #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/10017: #0: (&table[i].mutex){+.+.}, at: [] nf_tables_afinfo_lookup+0xf4/0x1d0 net/netfilter/nf_tables_api.c:87 #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 2 locks held by syz-executor.1/10033: #0: (net_mutex){+.+.}, at: [] copy_net_ns+0x156/0x440 net/core/net_namespace.c:413 #1: (&table[i].mutex){+.+.}, at: [] nft_register_afinfo+0x63/0x190 net/netfilter/nf_tables_api.c:39 1 lock held by syz-executor.1/10057: #0: (net_mutex){+.+.}, at: [] copy_net_ns+0x156/0x440 net/core/net_namespace.c:413 ============================================= NMI backtrace for cpu 0 CPU: 0 PID: 1533 Comm: khungtaskd Not tainted 4.14.299-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 0 to CPUs 1: NMI backtrace for cpu 1 CPU: 1 PID: 9143 Comm: kworker/1:3 Not tainted 4.14.299-syzkaller #0 Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 10/26/2022 Workqueue: events rtc_timer_do_work task: ffff88808b202280 task.stack: ffff88808b208000 RIP: 0010:debug_lockdep_rcu_enabled kernel/rcu/update.c:299 [inline] RIP: 0010:debug_lockdep_rcu_enabled+0xae/0xe0 kernel/rcu/update.c:297 RSP: 0018:ffff88808b20fb30 EFLAGS: 00000002 RAX: 0000000000000000 RBX: ffff88808b202280 RCX: 0000000000000001 RDX: 0000000000000001 RSI: 0000000000000002 RDI: ffff88808b202b04 RBP: ffff8880a8e5e970 R08: 0000000000000001 R09: 0000000000000000 R10: 0000000000000000 R11: 0000000000000000 R12: 0000000000000000 R13: 0000000000000000 R14: 0000000000000000 R15: 0000000000000001 FS: 0000000000000000(0000) GS:ffff8880ba500000(0000) knlGS:0000000000000000 CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 CR2: 00007f21a8b1e018 CR3: 00000000a1f68000 CR4: 00000000003406e0 DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000 DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400 Call Trace: trace_lock_acquire include/trace/events/lock.h:13 [inline] lock_acquire+0x307/0x3f0 kernel/locking/lockdep.c:3997 __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+0x82/0x180 drivers/rtc/interface.c:518 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: 00 fc ff df 48 89 fa 48 c1 ea 03 0f b6 14 02 48 89 f8 83 e0 07 83 c0 03 38 d0 7c 04 84 d2 75 32 8b 93 84 08 00 00 31 c0 5b 85 d2 <0f> 94 c0 c3 31 c0 5b c3 48 c7 c7 04 39 09 8a e8 be e2 38 00 e9 ---------------- Code disassembly (best guess), 3 bytes skipped: 0: df 48 89 fisttps -0x77(%rax) 3: fa cli 4: 48 c1 ea 03 shr $0x3,%rdx 8: 0f b6 14 02 movzbl (%rdx,%rax,1),%edx c: 48 89 f8 mov %rdi,%rax f: 83 e0 07 and $0x7,%eax 12: 83 c0 03 add $0x3,%eax 15: 38 d0 cmp %dl,%al 17: 7c 04 jl 0x1d 19: 84 d2 test %dl,%dl 1b: 75 32 jne 0x4f 1d: 8b 93 84 08 00 00 mov 0x884(%rbx),%edx 23: 31 c0 xor %eax,%eax 25: 5b pop %rbx 26: 85 d2 test %edx,%edx * 28: 0f 94 c0 sete %al <-- trapping instruction 2b: c3 retq 2c: 31 c0 xor %eax,%eax 2e: 5b pop %rbx 2f: c3 retq 30: 48 c7 c7 04 39 09 8a mov $0xffffffff8a093904,%rdi 37: e8 be e2 38 00 callq 0x38e2fa 3c: e9 .byte 0xe9