Bluetooth: hci7 command 0x0409 tx timeout Bluetooth: hci7 command 0x041b tx timeout Bluetooth: hci7 command 0x040f tx timeout Bluetooth: hci7 command 0x0419 tx timeout Bluetooth: hci6 command 0x0406 tx timeout BUG: workqueue lockup - pool cpus=0 node=0 flags=0x0 nice=0 stuck for 218s! INFO: task kworker/u4:1:8157 blocked for more than 140 seconds. Showing busy workqueues and worker pools: workqueue events: flags=0x0 Not tainted 4.14.295-syzkaller #0 pwq 0: cpus=0 node=0 flags=0x0 nice=0 active=22/256 refcnt=23 "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. in-flight: 8015:rtc_timer_do_work kworker/u4:1 D pending: wait_rcu_exp_gp, pwq_unbound_release_workfn, vmstat_shepherd, cache_reap, defense_work_handler 28688 8157 2 0x80000000 Workqueue: events_unbound fsnotify_mark_destroy_workfn , macvlan_process_broadcast, defense_work_handler, macvlan_process_broadcast, macvlan_process_broadcast , macvlan_process_broadcast, macvlan_process_broadcast, macvlan_process_broadcast, wait_rcu_exp_gp, nfc_urelease_event_work, nfc_urelease_event_work, key_garbage_collector, rht_deferred_worker Call Trace: context_switch kernel/sched/core.c:2811 [inline] __schedule+0x88b/0x1de0 kernel/sched/core.c:3387 , rfkill_any_led_trigger_worker, hci_cmd_timeout, hci_cmd_timeout, l2cap_info_timeout workqueue events_long: flags=0x0 schedule+0x8d/0x1b0 kernel/sched/core.c:3431 pwq 0: cpus=0 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=7 in-flight: 8157:fsnotify_mark_destroy_workfn fsnotify_mark_destroy_workfn, 9359: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: process_srcu, check_lifetime workqueue mm_percpu_wq: flags=0x8 pwq 0: cpus=0 node=0 flags=0x0 nice=0 active=1/256 refcnt=2 pending: vmstat_update schedule_timeout+0x80a/0xe90 kernel/time/timer.c:1724 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=9 pending: addrconf_dad_work delayed: addrconf_dad_work, addrconf_dad_work, addrconf_dad_work , addrconf_dad_work, addrconf_dad_work, addrconf_dad_work, addrconf_verify_work pool 0: cpus=0 node=0 flags=0x0 nice=0 hung=218s workers=5 idle: 8773 9017 25 3627 pool 4: cpus=0-1 flags=0x4 nice=0 hung=0s workers=7 idle: 8289 8095 35 3547 9336 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 kworker/u4:6:9359 blocked for more than 140 seconds. Not tainted 4.14.295-syzkaller #0 "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. kworker/u4:6 D29136 9359 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 syz-executor.2:9839 blocked for more than 140 seconds. Not tainted 4.14.295-syzkaller #0 "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. syz-executor.2 D28912 9839 7989 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 pep_sock_unhash+0x261/0x2e0 net/phonet/pep.c:1331 sk_common_release+0xba/0x2c0 net/core/sock.c:3042 pep_sock_close+0x29/0x530 net/phonet/pep.c:753 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:0x7f6278f8337b RSP: 002b:00007ffd9e93f190 EFLAGS: 00000293 ORIG_RAX: 0000000000000003 RAX: 0000000000000000 RBX: 0000000000000004 RCX: 00007f6278f8337b RDX: 00007f62790f7108 RSI: ffffffffffffffff RDI: 0000000000000003 RBP: 00007f62790f4980 R08: 0000000000000000 R09: 00007f62790f7110 R10: 00007ffd9e93f290 R11: 0000000000000293 R12: 00000000000561c5 R13: 00007ffd9e93f290 R14: 00007f62790f2f80 R15: 0000000000000032 INFO: task syz-executor.1:9890 blocked for more than 140 seconds. Not tainted 4.14.295-syzkaller #0 "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. syz-executor.1 D29648 9890 7980 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:10042 [inline] SYSC_perf_event_open kernel/events/core.c:10146 [inline] SyS_perf_event_open+0x683/0x2530 kernel/events/core.c:10032 do_syscall_64+0x1d5/0x640 arch/x86/entry/common.c:292 entry_SYSCALL_64_after_hwframe+0x46/0xbb RIP: 0033:0x7fa7eb7125a9 RSP: 002b:00007fa7ea086168 EFLAGS: 00000246 ORIG_RAX: 000000000000012a RAX: ffffffffffffffda RBX: 00007fa7eb833f80 RCX: 00007fa7eb7125a9 RDX: 0000000000000000 RSI: 0000000000000000 RDI: 0000000020000000 RBP: 00007fa7eb76d580 R08: 0000000000000000 R09: 0000000000000000 R10: ffffffffffffffff R11: 0000000000000246 R12: 0000000000000000 R13: 00007ffdc1efd9ff R14: 00007fa7ea086300 R15: 0000000000022000 INFO: task syz-executor.2:9914 blocked for more than 140 seconds. Not tainted 4.14.295-syzkaller #0 "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. syz-executor.2 D27528 9914 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 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 nf_register_net_hook+0x6a5/0xa40 net/netfilter/core.c:295 nf_register_net_hooks+0x47/0xa0 net/netfilter/core.c:383 ebt_register_table+0x912/0xbb0 net/bridge/netfilter/ebtables.c:1227 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+0x46/0xbb RIP: 0033:0x7f9ccfbefb67 RSP: 002b:00007ffcd3c798a8 EFLAGS: 00000206 ORIG_RAX: 0000000000000110 RAX: ffffffffffffffda RBX: 00007ffcd3c79ee8 RCX: 00007f9ccfbefb67 RDX: 0000000000000000 RSI: 0000000000000000 RDI: 0000000040000000 RBP: 00000000ffffffff R08: 0000000000000000 R09: 0000000000000000 R10: 0000000000000000 R11: 0000000000000206 R12: 0000000000000006 R13: 00007ffcd3c79980 R14: 00007f9ccfd104f8 R15: 0000000000000006 Showing all locks held in the system: 1 lock held by khungtaskd/1535: #0: (tasklist_lock){.+.+}, at: [] debug_show_all_locks+0x7c/0x21a kernel/locking/lockdep.c:4548 2 locks held by kworker/u4:1/8157: #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 kworker/u4:6/9359: #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 3 locks held by syz-executor.2/9839: #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: (sk_lock-AF_PHONET){+.+.}, at: [] lock_sock include/net/sock.h:1473 [inline] #1: (sk_lock-AF_PHONET){+.+.}, at: [] pep_sock_unhash+0x18/0x2e0 net/phonet/pep.c:1315 #2: (rcu_preempt_state.exp_mutex){+.+.}, at: [] exp_funnel_lock kernel/rcu/tree_exp.h:272 [inline] #2: (rcu_preempt_state.exp_mutex){+.+.}, at: [] _synchronize_rcu_expedited+0x2c2/0x770 kernel/rcu/tree_exp.h:596 3 locks held by syz-executor.1/9890: #0: (&sig->cred_guard_mutex){+.+.}, at: [] SYSC_perf_event_open kernel/events/core.c:10126 [inline] #0: (&sig->cred_guard_mutex){+.+.}, at: [] SyS_perf_event_open+0x131a/0x2530 kernel/events/core.c:10032 #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 2 locks held by syz-executor.2/9914: #0: (net_mutex){+.+.}, at: [] copy_net_ns+0x156/0x440 net/core/net_namespace.c:413 #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.2/9938: #0: (net_mutex){+.+.}, at: [] copy_net_ns+0x156/0x440 net/core/net_namespace.c:413 ============================================= NMI backtrace for cpu 1 CPU: 1 PID: 1535 Comm: khungtaskd Not tainted 4.14.295-syzkaller #0 Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 09/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: 4627 Comm: systemd-journal Not tainted 4.14.295-syzkaller #0 Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 09/22/2022 task: ffff8880a1626200 task.stack: ffff8880a1628000 RIP: 0010:get_lock_parent_ip include/linux/ftrace.h:730 [inline] RIP: 0010:preempt_latency_start kernel/sched/core.c:3088 [inline] RIP: 0010:preempt_count_add+0xa7/0x170 kernel/sched/core.c:3113 RSP: 0018:ffff8880a162fa20 EFLAGS: 00000246 RAX: 0000000000000001 RBX: ffffffff81237042 RCX: 0000000000000000 RDX: 0000000000000000 RSI: ffff8880a162f9e8 RDI: 0000000000000001 RBP: 0000000000000001 R08: 0000000000000001 R09: 0000000000000001 R10: ffff8880a162ff58 R11: 0000000000000001 R12: ffff8880a162fb80 R13: ffff8880a162fb30 R14: ffff88823f8c1200 R15: ffff8880a162faf8 FS: 00007f30d9ca18c0(0000) GS:ffff8880ba400000(0000) knlGS:0000000000000000 CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 CR2: 00007f30d70c6000 CR3: 00000000a14c1000 CR4: 00000000003406f0 DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000 DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400 Call Trace: unwind_next_frame+0xc2/0x17d0 arch/x86/kernel/unwind_orc.c:333 __save_stack_trace+0x90/0x160 arch/x86/kernel/stacktrace.c:44 save_stack mm/kasan/kasan.c:447 [inline] set_track mm/kasan/kasan.c:459 [inline] kasan_kmalloc+0xeb/0x160 mm/kasan/kasan.c:551 kmem_cache_alloc+0x124/0x3c0 mm/slab.c:3552 getname_flags+0xc8/0x550 fs/namei.c:138 do_sys_open+0x1ce/0x410 fs/open.c:1075 do_syscall_64+0x1d5/0x640 arch/x86/entry/common.c:292 entry_SYSCALL_64_after_hwframe+0x46/0xbb RIP: 0033:0x7f30d9230840 RSP: 002b:00007ffdeb49f088 EFLAGS: 00000246 ORIG_RAX: 0000000000000002 RAX: ffffffffffffffda RBX: 00007ffdeb49f390 RCX: 00007f30d9230840 RDX: 00000000000001a0 RSI: 0000000000080042 RDI: 000056460db564a0 RBP: 000000000000000d R08: 00000000000001e0 R09: 00000000ffffffff R10: 0000000000000069 R11: 0000000000000246 R12: 00000000ffffffff R13: 000056460db48040 R14: 00007ffdeb49f350 R15: 000056460db55ed0 Code: 75 11 65 8b 05 ab 5a c9 7e 0f b6 c0 3d f4 00 00 00 7f 60 65 8b 05 9a 5a c9 7e 25 ff ff ff 7f 39 c5 74 03 5b 5d c3 48 8b 5c 24 10 <48> 89 df e8 a1 da 08 00 85 c0 75 31 65 48 8b 2c 25 c0 7f 02 00 ---------------- Code disassembly (best guess): 0: 75 11 jne 0x13 2: 65 8b 05 ab 5a c9 7e mov %gs:0x7ec95aab(%rip),%eax # 0x7ec95ab4 9: 0f b6 c0 movzbl %al,%eax c: 3d f4 00 00 00 cmp $0xf4,%eax 11: 7f 60 jg 0x73 13: 65 8b 05 9a 5a c9 7e mov %gs:0x7ec95a9a(%rip),%eax # 0x7ec95ab4 1a: 25 ff ff ff 7f and $0x7fffffff,%eax 1f: 39 c5 cmp %eax,%ebp 21: 74 03 je 0x26 23: 5b pop %rbx 24: 5d pop %rbp 25: c3 retq 26: 48 8b 5c 24 10 mov 0x10(%rsp),%rbx * 2b: 48 89 df mov %rbx,%rdi <-- trapping instruction 2e: e8 a1 da 08 00 callq 0x8dad4 33: 85 c0 test %eax,%eax 35: 75 31 jne 0x68 37: 65 48 8b 2c 25 c0 7f mov %gs:0x27fc0,%rbp 3e: 02 00