Bluetooth: hci4 command 0x0406 tx timeout Bluetooth: hci0 command 0x0406 tx timeout Bluetooth: hci3 command 0x0406 tx timeout Bluetooth: hci1 command 0x0406 tx timeout Bluetooth: hci5 command 0x0406 tx timeout BUG: workqueue lockup - pool cpus=1 node=0 flags=0x0 nice=0 stuck for 145s! Showing busy workqueues and worker pools: workqueue events: flags=0x0 pwq 2: cpus=1 node=0 flags=0x0 nice=0 active=14/256 refcnt=15 in-flight: 24:rtc_timer_do_work pending: defense_work_handler, macvlan_process_broadcast, wait_rcu_exp_gp, macvlan_process_broadcast, defense_work_handler, cache_reap, defense_work_handler, macvlan_process_broadcast, macvlan_process_broadcast, nfc_urelease_event_work, macvlan_process_broadcast, wait_rcu_exp_gp, macvlan_process_broadcast workqueue events_highpri: flags=0x10 pwq 1: cpus=0 node=0 flags=0x0 nice=-20 active=1/256 refcnt=2 pending: mix_interrupt_randomness workqueue events_long: flags=0x0 pwq 2: cpus=1 node=0 flags=0x0 nice=0 active=1/256 refcnt=2 pending: gc_worker INFO: task kworker/u4:0:5 blocked for more than 140 seconds. workqueue events_unbound: flags=0x2 Not tainted 4.14.288-syzkaller #0 pwq 4: cpus=0-1 flags=0x4 nice=0 active=4/512 refcnt=7 in-flight: 5:fsnotify_mark_destroy_workfn fsnotify_mark_destroy_workfn, 9403: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 "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. workqueue events_power_efficient: flags=0x80 pwq 2: cpus=1 node=0 flags=0x0 nice=0 active=3/256 refcnt=4 pending: process_srcu, fb_flashcursor, neigh_periodic_work kworker/u4:0 D26040 5 2 0x80000000 workqueue mm_percpu_wq: flags=0x8 pwq 2: cpus=1 node=0 flags=0x0 nice=0 active=2/256 refcnt=4 pending: lru_add_drain_per_cpu BAR(9513), vmstat_update Workqueue: events_unbound fsnotify_mark_destroy_workfn workqueue dm_bufio_cache: flags=0x8 pwq 2: cpus=1 node=0 flags=0x0 nice=0 active=1/256 refcnt=2 Call Trace: pending: work_fn context_switch kernel/sched/core.c:2811 [inline] __schedule+0x88b/0x1de0 kernel/sched/core.c:3387 workqueue ipv6_addrconf: flags=0x40008 pwq 2: cpus=1 node=0 flags=0x0 nice=0 active=1/1 refcnt=50 pending: addrconf_dad_work schedule+0x8d/0x1b0 kernel/sched/core.c:3431 delayed: addrconf_dad_work, addrconf_dad_work, addrconf_dad_work, addrconf_dad_work, addrconf_dad_work, addrconf_dad_work, addrconf_dad_work, addrconf_dad_work, addrconf_dad_work, addrconf_dad_work, addrconf_dad_work, addrconf_dad_work, addrconf_dad_work, addrconf_dad_work, addrconf_dad_work, addrconf_dad_work, addrconf_dad_work, addrconf_dad_work, addrconf_dad_work, addrconf_dad_work, addrconf_dad_work, addrconf_dad_work, addrconf_dad_work, addrconf_dad_work, addrconf_dad_work, addrconf_dad_work, addrconf_dad_work, addrconf_dad_work, addrconf_dad_work, addrconf_dad_work, addrconf_dad_work, addrconf_dad_work, addrconf_dad_work, addrconf_dad_work, addrconf_dad_work, addrconf_dad_work, addrconf_dad_work, addrconf_dad_work, addrconf_dad_work, addrconf_dad_work, addrconf_dad_work, addrconf_dad_work schedule_timeout+0x80a/0xe90 kernel/time/timer.c:1724 , addrconf_dad_work, addrconf_dad_work, addrconf_dad_work, addrconf_dad_work, addrconf_dad_work, addrconf_dad_work pool 2: cpus=1 node=0 flags=0x0 nice=0 hung=146s workers=5 idle: 4646 9029 3447 19 pool 4: cpus=0-1 flags=0x4 nice=0 hung=0s workers=8 idle: 1651 23 6981 391 2850 9384 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:7:9403 blocked for more than 140 seconds. Not tainted 4.14.288-syzkaller #0 "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. kworker/u4:7 D29136 9403 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:9508 blocked for more than 140 seconds. Not tainted 4.14.288-syzkaller #0 "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. syz-executor.2 D28912 9508 7979 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+0x6af/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:0x7f359a445d4b RSP: 002b:00007ffefebff750 EFLAGS: 00000293 ORIG_RAX: 0000000000000003 RAX: 0000000000000000 RBX: 0000000000000005 RCX: 00007f359a445d4b RDX: ffffffffffffffb8 RSI: 0000001b2e1279f8 RDI: 0000000000000004 RBP: 00007f359a5a7960 R08: 0000000000000000 R09: 00000000828b9db1 R10: 001482d26900aabd R11: 0000000000000293 R12: 00000000000221a6 R13: 00007ffefebff850 R14: 00007ffefebff870 R15: 0000000000000032 INFO: task syz-executor.2:9510 blocked for more than 140 seconds. Not tainted 4.14.288-syzkaller #0 "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. syz-executor.2 D29768 9510 7979 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:10025 [inline] SYSC_perf_event_open kernel/events/core.c:10129 [inline] SyS_perf_event_open+0x683/0x2530 kernel/events/core.c:10015 do_syscall_64+0x1d5/0x640 arch/x86/entry/common.c:292 entry_SYSCALL_64_after_hwframe+0x46/0xbb RIP: 0033:0x7f359a493109 RSP: 002b:00007f3598e08168 EFLAGS: 00000246 ORIG_RAX: 000000000000012a RAX: ffffffffffffffda RBX: 00007f359a5a5f60 RCX: 00007f359a493109 RDX: ffffffffffffffff RSI: 0000000000000000 RDI: 0000000020000880 RBP: 00007f359a4ed0ed R08: 0000000000000000 R09: 0000000000000000 R10: ffffffffffffffff R11: 0000000000000246 R12: 0000000000000000 R13: 00007ffefebff6ef R14: 00007f3598e08300 R15: 0000000000022000 INFO: task syz-executor.4:9513 blocked for more than 140 seconds. Not tainted 4.14.288-syzkaller #0 "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. syz-executor.4 D27984 9513 7978 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 lru_add_drain_all_cpuslocked+0x2e6/0x450 mm/swap.c:722 lru_add_drain_all+0xf/0x20 mm/swap.c:730 invalidate_bdev+0x8a/0xc0 fs/block_dev.c:109 loop_clr_fd+0x4c8/0xc20 drivers/block/loop.c:1062 lo_ioctl+0x895/0x1cd0 drivers/block/loop.c:1424 __blkdev_driver_ioctl block/ioctl.c:297 [inline] blkdev_ioctl+0x540/0x1830 block/ioctl.c:594 block_ioctl+0xd9/0x120 fs/block_dev.c:1893 vfs_ioctl fs/ioctl.c:46 [inline] file_ioctl fs/ioctl.c:500 [inline] do_vfs_ioctl+0x75a/0xff0 fs/ioctl.c:684 SYSC_ioctl fs/ioctl.c:701 [inline] SyS_ioctl+0x7f/0xb0 fs/ioctl.c:692 do_syscall_64+0x1d5/0x640 arch/x86/entry/common.c:292 entry_SYSCALL_64_after_hwframe+0x46/0xbb RIP: 0033:0x7f381de1dec7 RSP: 002b:00007f381c792f88 EFLAGS: 00000246 ORIG_RAX: 0000000000000010 RAX: ffffffffffffffda RBX: 0000000020000200 RCX: 00007f381de1dec7 RDX: 0000000000000000 RSI: 0000000000004c01 RDI: 0000000000000004 RBP: 00007f381c7936b8 R08: 00007f381c793020 R09: 0000000020000000 R10: 0000000000000000 R11: 0000000000000246 R12: ffffffffffffffff R13: 0000000000000014 R14: 00007f381c792fe0 R15: 0000000020000680 INFO: task systemd-udevd:9518 blocked for more than 140 seconds. Not tainted 4.14.288-syzkaller #0 "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. systemd-udevd D28712 9518 4627 0x00000300 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 blk_queue_enter+0x3e2/0x4b0 block/blk-core.c:800 generic_make_request block/blk-core.c:2223 [inline] generic_make_request+0x19d/0x850 block/blk-core.c:2173 submit_bio+0x234/0x390 block/blk-core.c:2303 submit_bh_wbc+0x526/0x6f0 fs/buffer.c:3147 submit_bh fs/buffer.c:3153 [inline] block_read_full_page+0x6ce/0x870 fs/buffer.c:2367 generic_file_buffered_read mm/filemap.c:2140 [inline] generic_file_read_iter+0xaed/0x21c0 mm/filemap.c:2273 blkdev_read_iter+0x111/0x1b0 fs/block_dev.c:1958 call_read_iter include/linux/fs.h:1774 [inline] new_sync_read fs/read_write.c:401 [inline] __vfs_read+0x449/0x620 fs/read_write.c:413 vfs_read+0x139/0x340 fs/read_write.c:447 SYSC_read fs/read_write.c:574 [inline] SyS_read+0xf2/0x210 fs/read_write.c:567 do_syscall_64+0x1d5/0x640 arch/x86/entry/common.c:292 entry_SYSCALL_64_after_hwframe+0x46/0xbb RIP: 0033:0x7fde91f6e210 RSP: 002b:00007ffea7a7b6b8 EFLAGS: 00000246 ORIG_RAX: 0000000000000000 RAX: ffffffffffffffda RBX: 000055dc8ddd5120 RCX: 00007fde91f6e210 RDX: 0000000000000400 RSI: 000055dc8ddd5148 RDI: 000000000000000f RBP: 000055dc8ddec2d0 R08: 00007fde91f58f78 R09: 0000000000000430 R10: 000000000000006d R11: 0000000000000246 R12: 0000000000000000 R13: 0000000000000400 R14: 000055dc8ddec320 R15: 0000000000000400 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: ((reaper_work).work){+.+.}, at: [] process_one_work+0x6e6/0x14a0 kernel/workqueue.c:2092 1 lock held by khungtaskd/1532: #0: (tasklist_lock){.+.+}, at: [] debug_show_all_locks+0x7c/0x21a kernel/locking/lockdep.c:4548 2 locks held by kworker/u4:7/9403: #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/9508: #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 3 locks held by syz-executor.2/9510: #0: (&sig->cred_guard_mutex){+.+.}, at: [] SYSC_perf_event_open kernel/events/core.c:10109 [inline] #0: (&sig->cred_guard_mutex){+.+.}, at: [] SyS_perf_event_open+0x131a/0x2530 kernel/events/core.c:10015 #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 3 locks held by syz-executor.4/9513: #0: (&lo->lo_ctl_mutex/1){+.+.}, at: [] lo_ioctl+0x87/0x1cd0 drivers/block/loop.c:1414 #1: (cpu_hotplug_lock.rw_sem){++++}, at: [] get_online_cpus include/linux/cpu.h:149 [inline] #1: (cpu_hotplug_lock.rw_sem){++++}, at: [] lru_add_drain_all+0xa/0x20 mm/swap.c:729 #2: (lock#6){+.+.}, at: [] lru_add_drain_all_cpuslocked+0x6c/0x450 mm/swap.c:704 ============================================= NMI backtrace for cpu 0 CPU: 0 PID: 1532 Comm: khungtaskd Not tainted 4.14.288-syzkaller #0 Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 06/29/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 0 to CPUs 1: NMI backtrace for cpu 1 CPU: 1 PID: 24 Comm: kworker/1:1 Not tainted 4.14.288-syzkaller #0 Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 06/29/2022 Workqueue: events rtc_timer_do_work task: ffff8880b5602640 task.stack: ffff8880b5610000 RIP: 0010:mark_lock+0xc2/0x1050 kernel/locking/lockdep.c:3221 RSP: 0018:ffff8880b5617a38 EFLAGS: 00000082 RAX: 0000000000000001 RBX: ffffffff8b9faff0 RCX: 1ffff11016ac05dd RDX: 1ffffffff173f604 RSI: ffff8880b5602ec8 RDI: ffffffff8b9fb020 RBP: ffff8880b5602ec8 R08: ffff8880a8f4b1e0 R09: 0000000000000000 R10: 0000000000000000 R11: 0000000000000000 R12: 0000000000000004 R13: ffff8880b5602640 R14: ffff8880b5602ee8 R15: 0000000000000002 FS: 0000000000000000(0000) GS:ffff8880ba500000(0000) knlGS:0000000000000000 CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 CR2: 00007fb0c0415000 CR3: 000000009a124000 CR4: 00000000003406e0 DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000 DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400 Call Trace: mark_held_locks+0xa6/0xf0 kernel/locking/lockdep.c:2854 __trace_hardirqs_on_caller kernel/locking/lockdep.c:2875 [inline] trace_hardirqs_on_caller+0x288/0x580 kernel/locking/lockdep.c:2930 __raw_spin_unlock_irqrestore include/linux/spinlock_api_smp.h:160 [inline] _raw_spin_unlock_irqrestore+0x79/0xe0 kernel/locking/spinlock.c:192 spin_unlock_irqrestore include/linux/spinlock.h:372 [inline] __wake_up_common_lock+0xcd/0x140 kernel/sched/wait.c:127 rtc_handle_legacy_irq+0x121/0x180 drivers/rtc/interface.c:523 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:404 Code: 00 fc ff df 48 89 fa 48 c1 ea 03 80 3c 02 00 0f 85 07 0c 00 00 4c 89 e0 48 23 43 30 0f 84 9c 00 00 00 b8 01 00 00 00 48 83 c4 18 <5b> 5d 41 5c 41 5d 41 5e 41 5f c3 48 c7 c0 80 cd ec 8b 48 ba 00 ---------------- 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: 80 3c 02 00 cmpb $0x0,(%rdx,%rax,1) c: 0f 85 07 0c 00 00 jne 0xc19 12: 4c 89 e0 mov %r12,%rax 15: 48 23 43 30 and 0x30(%rbx),%rax 19: 0f 84 9c 00 00 00 je 0xbb 1f: b8 01 00 00 00 mov $0x1,%eax 24: 48 83 c4 18 add $0x18,%rsp * 28: 5b pop %rbx <-- trapping instruction 29: 5d pop %rbp 2a: 41 5c pop %r12 2c: 41 5d pop %r13 2e: 41 5e pop %r14 30: 41 5f pop %r15 32: c3 retq 33: 48 c7 c0 80 cd ec 8b mov $0xffffffff8beccd80,%rax 3a: 48 rex.W 3b: ba .byte 0xba