Bluetooth: hci6 command 0x0406 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=0 node=0 flags=0x0 nice=0 stuck for 245s! INFO: task kworker/u4:0:5 blocked for more than 140 seconds. Showing busy workqueues and worker pools: workqueue events: flags=0x0 pwq 0: cpus=0 node=0 flags=0x0 nice=0 active=17/256 refcnt=18 Not tainted 4.14.305-syzkaller #0 in-flight: "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. 3626:rtc_timer_do_work kworker/u4:0 D pending: 28776 5 2 0x80000000 macvlan_process_broadcast Workqueue: events_unbound fsnotify_connector_destroy_workfn , defense_work_handler, defense_work_handler, defense_work_handler, vmstat_shepherd, cache_reap, macvlan_process_broadcast, rtc_timer_do_work , macvlan_process_broadcast, macvlan_process_broadcast, defense_work_handler, perf_sched_delayed Call Trace: , macvlan_process_broadcast, macvlan_process_broadcast context_switch kernel/sched/core.c:2811 [inline] __schedule+0x88b/0x1de0 kernel/sched/core.c:3386 , wait_rcu_exp_gp, free_obj_work 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 pwq 4: cpus=0-1 flags=0x4 nice=0 active=4/512 refcnt=7 in-flight: 22:fsnotify_mark_destroy_workfn fsnotify_mark_destroy_workfn, 5: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, process_srcu, do_cache_clean, neigh_periodic_work workqueue mm_percpu_wq: flags=0x8 pwq 0: cpus=0 node=0 flags=0x0 nice=0 active=2/256 refcnt=4 pending: vmstat_update, lru_add_drain_per_cpu schedule+0x8d/0x1b0 kernel/sched/core.c:3430 BAR(9891) schedule_timeout+0x80a/0xe90 kernel/time/timer.c:1724 pool 0: cpus=0 node=0 flags=0x0 nice=0 hung=245s workers=5 idle: 8049 9145 3 24 pool 4: cpus=0-1 flags=0x4 nice=0 hung=0s workers=6 idle: 7098 35 2877 359 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 kworker/u4:1:22 blocked for more than 140 seconds. Not tainted 4.14.305-syzkaller #0 "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. kworker/u4:1 D27400 22 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:3386 schedule+0x8d/0x1b0 kernel/sched/core.c:3430 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 syz-executor.2:9846 blocked for more than 140 seconds. Not tainted 4.14.305-syzkaller #0 "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. syz-executor.2 D28032 9846 8036 0x00000004 Call Trace: context_switch kernel/sched/core.c:2811 [inline] __schedule+0x88b/0x1de0 kernel/sched/core.c:3386 schedule+0x8d/0x1b0 kernel/sched/core.c:3430 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 kvm_io_bus_register_dev+0x255/0x3f0 arch/x86/kvm/../../../virt/kvm/kvm_main.c:3718 kvm_pic_init+0x1f3/0x360 arch/x86/kvm/i8259.c:607 kvm_arch_vm_ioctl+0x625/0x1420 arch/x86/kvm/x86.c:4276 kvm_vm_ioctl+0x73f/0x1420 arch/x86/kvm/../../../virt/kvm/kvm_main.c:3245 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+0x5e/0xd3 RIP: 0033:0x7f60dd6490f9 RSP: 002b:00007f60dbbbb168 EFLAGS: 00000246 ORIG_RAX: 0000000000000010 RAX: ffffffffffffffda RBX: 00007f60dd768f80 RCX: 00007f60dd6490f9 RDX: 0000000000000000 RSI: 000000000000ae60 RDI: 0000000000000007 RBP: 00007f60dd6a4ae9 R08: 0000000000000000 R09: 0000000000000000 R10: 0000000000000000 R11: 0000000000000246 R12: 0000000000000000 R13: 00007ffe915ad5ef R14: 00007f60dbbbb300 R15: 0000000000022000 INFO: task syz-executor.5:9891 blocked for more than 140 seconds. Not tainted 4.14.305-syzkaller #0 "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. syz-executor.5 D27672 9891 8030 0x00000004 Call Trace: context_switch kernel/sched/core.c:2811 [inline] __schedule+0x88b/0x1de0 kernel/sched/core.c:3386 schedule+0x8d/0x1b0 kernel/sched/core.c:3430 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 open_ctree+0x1ed0/0x7530 fs/btrfs/disk-io.c:2584 btrfs_fill_super fs/btrfs/super.c:1147 [inline] btrfs_mount+0x1915/0x1fe0 fs/btrfs/super.c:1631 mount_fs+0x92/0x2a0 fs/super.c:1237 vfs_kern_mount.part.0+0x5b/0x470 fs/namespace.c:1046 vfs_kern_mount+0x3c/0x60 fs/namespace.c:1036 mount_subvol fs/btrfs/super.c:1398 [inline] btrfs_mount+0x42a/0x1fe0 fs/btrfs/super.c:1569 mount_fs+0x92/0x2a0 fs/super.c:1237 vfs_kern_mount.part.0+0x5b/0x470 fs/namespace.c:1046 vfs_kern_mount fs/namespace.c:1036 [inline] do_new_mount fs/namespace.c:2572 [inline] do_mount+0xe65/0x2a30 fs/namespace.c:2905 SYSC_mount fs/namespace.c:3121 [inline] SyS_mount+0xa8/0x120 fs/namespace.c:3098 do_syscall_64+0x1d5/0x640 arch/x86/entry/common.c:292 entry_SYSCALL_64_after_hwframe+0x5e/0xd3 RIP: 0033:0x7f219517662a RSP: 002b:00007f21936e6f88 EFLAGS: 00000202 ORIG_RAX: 00000000000000a5 RAX: ffffffffffffffda RBX: 00000000000050ef RCX: 00007f219517662a RDX: 0000000020005100 RSI: 0000000020000000 RDI: 00007f21936e6fe0 RBP: 00007f21936e7020 R08: 00007f21936e7020 R09: 0000000000000080 R10: 0000000000000080 R11: 0000000000000202 R12: 0000000020005100 R13: 0000000020000000 R14: 00007f21936e6fe0 R15: 0000000020000040 INFO: task syz-executor.4:9895 blocked for more than 140 seconds. Not tainted 4.14.305-syzkaller #0 "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. syz-executor.4 D28176 9895 8045 0x00000004 Call Trace: context_switch kernel/sched/core.c:2811 [inline] __schedule+0x88b/0x1de0 kernel/sched/core.c:3386 schedule+0x8d/0x1b0 kernel/sched/core.c:3430 _synchronize_rcu_expedited+0x522/0x770 kernel/rcu/tree_exp.h:615 bdi_remove_from_list mm/backing-dev.c:935 [inline] bdi_unregister+0x144/0x610 mm/backing-dev.c:941 release_bdi mm/backing-dev.c:970 [inline] kref_put include/linux/kref.h:70 [inline] bdi_put+0x127/0x160 mm/backing-dev.c:979 generic_shutdown_super+0x2aa/0x370 fs/super.c:460 kill_anon_super+0x36/0x90 fs/super.c:1006 deactivate_locked_super+0x6c/0xd0 fs/super.c:319 deactivate_super+0x7f/0xa0 fs/super.c:350 cleanup_mnt+0x186/0x2c0 fs/namespace.c:1183 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 RIP: 0033:0x7ffbb73d362a RSP: 002b:00007ffbb5943f88 EFLAGS: 00000202 ORIG_RAX: 00000000000000a5 RAX: ffffffffffffffec RBX: 0000000000000000 RCX: 00007ffbb73d362a RDX: 0000000020000080 RSI: 0000000020000480 RDI: 0000000000000000 RBP: 00007ffbb5944020 R08: 00007ffbb5944020 R09: 0000000000000000 R10: 0000000000000000 R11: 0000000000000202 R12: 0000000020000080 R13: 0000000020000480 R14: 00007ffbb5943fe0 R15: 0000000020000540 INFO: task syz-executor.2:9916 blocked for more than 140 seconds. Not tainted 4.14.305-syzkaller #0 "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. syz-executor.2 D27528 9916 1 0x00000004 Call Trace: context_switch kernel/sched/core.c:2811 [inline] __schedule+0x88b/0x1de0 kernel/sched/core.c:3386 schedule+0x8d/0x1b0 kernel/sched/core.c:3430 schedule_preempt_disabled+0xf/0x20 kernel/sched/core.c:3488 __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+0x5e/0xd3 RIP: 0033:0x7f98d9dec6b7 RSP: 002b:00007ffc69549f98 EFLAGS: 00000202 ORIG_RAX: 0000000000000110 RAX: ffffffffffffffda RBX: 00007ffc6954a5d8 RCX: 00007f98d9dec6b7 RDX: 0000000000000000 RSI: 0000000000000000 RDI: 0000000040000000 RBP: 00000000ffffffff R08: 0000000000000000 R09: 0000000000000000 R10: 0000000000000000 R11: 0000000000000202 R12: 0000000000000006 R13: 00007ffc6954a070 R14: 00007f98d9f0b4f8 R15: 0000000000000006 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: (connector_reaper_work){+.+.}, at: [] process_one_work+0x6e6/0x14a0 kernel/workqueue.c:2092 2 locks held by kworker/u4:1/22: #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 syz-executor.2/9846: #0: (&kvm->lock){+.+.}, at: [] kvm_arch_vm_ioctl+0x59c/0x1420 arch/x86/kvm/x86.c:4266 #1: (&kvm->slots_lock){+.+.}, at: [] kvm_pic_init+0x1d9/0x360 arch/x86/kvm/i8259.c:606 3 locks held by syz-executor.5/9891: #0: (&type->s_umount_key#50/1){+.+.}, at: [] alloc_super fs/super.c:251 [inline] #0: (&type->s_umount_key#50/1){+.+.}, at: [] sget_userns+0x556/0xc10 fs/super.c:516 #1: (cpu_hotplug_lock.rw_sem){++++}, at: [] get_online_cpus include/linux/cpu.h:151 [inline] #1: (cpu_hotplug_lock.rw_sem){++++}, at: [] lru_add_drain_all+0xa/0x20 mm/swap.c:729 #2: (lock#5){+.+.}, at: [] lru_add_drain_all_cpuslocked+0x6c/0x450 mm/swap.c:704 1 lock held by syz-executor.4/9895: #0: (rcu_preempt_state.exp_mutex){+.+.}, at: [] exp_funnel_lock kernel/rcu/tree_exp.h:272 [inline] #0: (rcu_preempt_state.exp_mutex){+.+.}, at: [] _synchronize_rcu_expedited+0x2c2/0x770 kernel/rcu/tree_exp.h:596 2 locks held by syz-executor.2/9916: #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/9940: #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.305-syzkaller #0 Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/21/2023 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: 4608 Comm: systemd-journal Not tainted 4.14.305-syzkaller #0 Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/21/2023 task: ffff8880a2224440 task.stack: ffff8880a2228000 RIP: 0010:orc_find arch/x86/kernel/unwind_orc.c:109 [inline] RIP: 0010:unwind_next_frame+0x4fa/0x17d0 arch/x86/kernel/unwind_orc.c:348 RSP: 0018:ffff8880a222f9e8 EFLAGS: 00000a02 RAX: 0000000000000007 RBX: 1ffff11014445f44 RCX: ffffffff818681fa RDX: dffffc0000000000 RSI: 0000000000008681 RDI: ffffffff8ae92c28 RBP: 0000000000000001 R08: 0000000000008682 R09: 0000000000000000 R10: 00000000000202be R11: 0000000000066071 R12: ffff8880a222fadd R13: ffff8880a222fae0 R14: ffff8880a222faf8 R15: ffff8880a222faa8 FS: 00007fdddb8448c0(0000) GS:ffff8880ba400000(0000) knlGS:0000000000000000 CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 CR2: 00007fddd8c7a000 CR3: 00000000a2290000 CR4: 00000000003426f0 DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000 DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400 Call Trace: __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_trace+0x131/0x3d0 mm/slab.c:3618 kmalloc include/linux/slab.h:488 [inline] kzalloc include/linux/slab.h:661 [inline] aa_alloc_task_context+0x4d/0x90 security/apparmor/context.c:40 apparmor_cred_prepare+0x1a/0xb0 security/apparmor/lsm.c:84 security_prepare_creds+0x76/0xb0 security/security.c:1008 prepare_creds+0x2ef/0x490 kernel/cred.c:282 SYSC_faccessat fs/open.c:365 [inline] SyS_faccessat+0x7b/0x680 fs/open.c:353 do_syscall_64+0x1d5/0x640 arch/x86/entry/common.c:292 entry_SYSCALL_64_after_hwframe+0x5e/0xd3 RIP: 0033:0x7fdddaaff9c7 RSP: 002b:00007fff54aea498 EFLAGS: 00000246 ORIG_RAX: 0000000000000015 RAX: ffffffffffffffda RBX: 00007fff54aed4c0 RCX: 00007fdddaaff9c7 RDX: 0000000000000000 RSI: 0000000000000000 RDI: 000056303906f9a3 RBP: 00007fff54aea5e0 R08: 00005630390653e5 R09: 0000000000000018 R10: 0000000000000069 R11: 0000000000000246 R12: 0000000000000000 R13: 0000000000000000 R14: 00005630399bc8a0 R15: 00007fff54aeaad0 Code: 10 44 8d 46 01 44 8b 14 95 20 12 e7 8a 48 ba 00 00 00 00 00 fc ff df 4a 8d 3c 85 20 12 e7 8a 49 89 f9 49 c1 e9 03 45 0f b6 0c 11 <48> 89 fa 83 e2 07 83 c2 03 44 38 ca 7c 3c 45 84 c9 74 37 4c 89 ---------------- Code disassembly (best guess): 0: 10 44 8d 46 adc %al,0x46(%rbp,%rcx,4) 4: 01 44 8b 14 add %eax,0x14(%rbx,%rcx,4) 8: 95 xchg %eax,%ebp 9: 20 12 and %dl,(%rdx) b: e7 8a out %eax,$0x8a d: 48 ba 00 00 00 00 00 movabs $0xdffffc0000000000,%rdx 14: fc ff df 17: 4a 8d 3c 85 20 12 e7 lea -0x7518ede0(,%r8,4),%rdi 1e: 8a 1f: 49 89 f9 mov %rdi,%r9 22: 49 c1 e9 03 shr $0x3,%r9 26: 45 0f b6 0c 11 movzbl (%r9,%rdx,1),%r9d * 2b: 48 89 fa mov %rdi,%rdx <-- trapping instruction 2e: 83 e2 07 and $0x7,%edx 31: 83 c2 03 add $0x3,%edx 34: 44 38 ca cmp %r9b,%dl 37: 7c 3c jl 0x75 39: 45 84 c9 test %r9b,%r9b 3c: 74 37 je 0x75 3e: 4c rex.WR 3f: 89 .byte 0x89