Bluetooth: hci4 command 0x0406 tx timeout Bluetooth: hci3 command 0x0406 tx timeout Bluetooth: hci2 command 0x0406 tx timeout Bluetooth: hci0 command 0x0406 tx timeout Bluetooth: hci5 command 0x0406 tx timeout INFO: task syz-executor.0:11898 blocked for more than 140 seconds. Not tainted 4.14.287-syzkaller #0 "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. syz-executor.0 D25280 11898 7994 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 __blkdev_get+0x191/0x1090 fs/block_dev.c:1455 blkdev_get+0x88/0x890 fs/block_dev.c:1611 blkdev_open+0x1cc/0x250 fs/block_dev.c:1772 do_dentry_open+0x44b/0xec0 fs/open.c:777 vfs_open+0x105/0x220 fs/open.c:888 do_last fs/namei.c:3428 [inline] path_openat+0x628/0x2970 fs/namei.c:3569 do_filp_open+0x179/0x3c0 fs/namei.c:3603 do_sys_open+0x296/0x410 fs/open.c:1081 do_syscall_64+0x1d5/0x640 arch/x86/entry/common.c:292 entry_SYSCALL_64_after_hwframe+0x46/0xbb RIP: 0033:0x7f46ab327109 RSP: 002b:00007f46a9c7b168 EFLAGS: 00000246 ORIG_RAX: 0000000000000101 RAX: ffffffffffffffda RBX: 00007f46ab43a030 RCX: 00007f46ab327109 RDX: 0000000000000000 RSI: 0000000020004280 RDI: ffffffffffffff9c RBP: 00007f46ab38105d R08: 0000000000000000 R09: 0000000000000000 R10: 0000000000000000 R11: 0000000000000246 R12: 0000000000000000 R13: 00007ffcd44c8a6f R14: 00007f46a9c7b300 R15: 0000000000022000 INFO: task syz-executor.2:11899 blocked for more than 140 seconds. Not tainted 4.14.287-syzkaller #0 "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. syz-executor.2 D27176 11899 7999 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 __blkdev_get+0x191/0x1090 fs/block_dev.c:1455 __blkdev_get+0x6a9/0x1090 fs/block_dev.c:1515 blkdev_get+0x88/0x890 fs/block_dev.c:1611 blkdev_open+0x1cc/0x250 fs/block_dev.c:1772 do_dentry_open+0x44b/0xec0 fs/open.c:777 vfs_open+0x105/0x220 fs/open.c:888 do_last fs/namei.c:3428 [inline] path_openat+0x628/0x2970 fs/namei.c:3569 do_filp_open+0x179/0x3c0 fs/namei.c:3603 do_sys_open+0x296/0x410 fs/open.c:1081 do_syscall_64+0x1d5/0x640 arch/x86/entry/common.c:292 entry_SYSCALL_64_after_hwframe+0x46/0xbb RIP: 0033:0x7f6481985109 RSP: 002b:00007f64802d9168 EFLAGS: 00000246 ORIG_RAX: 0000000000000101 RAX: ffffffffffffffda RBX: 00007f6481a98030 RCX: 00007f6481985109 RDX: 0000000000000000 RSI: 0000000020004280 RDI: ffffffffffffff9c RBP: 00007f64819df05d R08: 0000000000000000 R09: 0000000000000000 R10: 0000000000000000 R11: 0000000000000246 R12: 0000000000000000 R13: 00007ffc723d94ef R14: 00007f64802d9300 R15: 0000000000022000 INFO: task syz-executor.4:11909 blocked for more than 140 seconds. Not tainted 4.14.287-syzkaller #0 "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. syz-executor.4 D27144 11909 7998 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 __blkdev_get+0x191/0x1090 fs/block_dev.c:1455 blkdev_get+0x88/0x890 fs/block_dev.c:1611 blkdev_open+0x1cc/0x250 fs/block_dev.c:1772 do_dentry_open+0x44b/0xec0 fs/open.c:777 vfs_open+0x105/0x220 fs/open.c:888 do_last fs/namei.c:3428 [inline] path_openat+0x628/0x2970 fs/namei.c:3569 do_filp_open+0x179/0x3c0 fs/namei.c:3603 do_sys_open+0x296/0x410 fs/open.c:1081 do_syscall_64+0x1d5/0x640 arch/x86/entry/common.c:292 entry_SYSCALL_64_after_hwframe+0x46/0xbb RIP: 0033:0x7f6664240109 RSP: 002b:00007f6662b94168 EFLAGS: 00000246 ORIG_RAX: 0000000000000101 RAX: ffffffffffffffda RBX: 00007f6664353030 RCX: 00007f6664240109 RDX: 0000000000000000 RSI: 0000000020004280 RDI: ffffffffffffff9c RBP: 00007f666429a05d R08: 0000000000000000 R09: 0000000000000000 R10: 0000000000000000 R11: 0000000000000246 R12: 0000000000000000 R13: 00007ffd8025d90f R14: 00007f6662b94300 R15: 0000000000022000 INFO: task systemd-udevd:11940 blocked for more than 140 seconds. Not tainted 4.14.287-syzkaller #0 "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. systemd-udevd D28712 11940 4628 0x00000304 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 __blkdev_get+0x191/0x1090 fs/block_dev.c:1455 blkdev_get+0x88/0x890 fs/block_dev.c:1611 blkdev_open+0x1cc/0x250 fs/block_dev.c:1772 do_dentry_open+0x44b/0xec0 fs/open.c:777 vfs_open+0x105/0x220 fs/open.c:888 do_last fs/namei.c:3428 [inline] path_openat+0x628/0x2970 fs/namei.c:3569 do_filp_open+0x179/0x3c0 fs/namei.c:3603 do_sys_open+0x296/0x410 fs/open.c:1081 do_syscall_64+0x1d5/0x640 arch/x86/entry/common.c:292 entry_SYSCALL_64_after_hwframe+0x46/0xbb RIP: 0033:0x7f32e25dd840 RSP: 002b:00007ffef86696c8 EFLAGS: 00000246 ORIG_RAX: 0000000000000002 RAX: ffffffffffffffda RBX: 000055e38feee7c0 RCX: 00007f32e25dd840 RDX: 000055e38ecb4fe3 RSI: 00000000000a0800 RDI: 000055e38fed9e40 RBP: 00007ffef8669840 R08: 000055e38ecb4670 R09: 0000000000000010 R10: 000055e38ecb4d0c R11: 0000000000000246 R12: 00007ffef8669790 R13: 000055e38fedc4e0 R14: 0000000000000003 R15: 000000000000000e 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 1 lock held by in:imklog/7638: #0: (&f->f_pos_lock){+.+.}, at: [] __fdget_pos+0x1fb/0x2b0 fs/file.c:819 1 lock held by syz-executor.0/11898: #0: (&bdev->bd_mutex){+.+.}, at: [] __blkdev_get+0x191/0x1090 fs/block_dev.c:1455 2 locks held by syz-executor.2/11899: #0: (&bdev->bd_mutex){+.+.}, at: [] __blkdev_get+0x191/0x1090 fs/block_dev.c:1455 #1: (&bdev->bd_mutex/1){+.+.}, at: [] __blkdev_get+0x191/0x1090 fs/block_dev.c:1455 1 lock held by syz-executor.4/11909: #0: (&bdev->bd_mutex){+.+.}, at: [] __blkdev_get+0x191/0x1090 fs/block_dev.c:1455 1 lock held by systemd-udevd/11940: #0: (&bdev->bd_mutex){+.+.}, at: [] __blkdev_get+0x191/0x1090 fs/block_dev.c:1455 ============================================= NMI backtrace for cpu 0 CPU: 0 PID: 1535 Comm: khungtaskd Not tainted 4.14.287-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: 4624 Comm: systemd-journal Not tainted 4.14.287-syzkaller #0 Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 06/29/2022 task: ffff8880a108e140 task.stack: ffff8880a1090000 RIP: 0010:__this_cpu_preempt_check+0x1/0x20 lib/smp_processor_id.c:62 RSP: 0018:ffff8880a1097b40 EFLAGS: 00000246 RAX: dffffc0000000000 RBX: 0000000000000020 RCX: 0000000000000000 RDX: 1ffff11014211e6f RSI: 0000000000000001 RDI: ffffffff87cf1080 RBP: ffffffff88e12580 R08: 0000000000000000 R09: 0000000000000000 R10: 0000000000000000 R11: ffff8880a108e140 R12: ffff8880a2ec57c0 R13: 0000000000000001 R14: 0000000000000001 R15: ffffffff88e125d0 FS: 00007f232595a8c0(0000) GS:ffff8880ba500000(0000) knlGS:0000000000000000 CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 CR2: 00007f2322d87000 CR3: 00000000a1119000 CR4: 00000000003406e0 DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000 DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400 Call Trace: percpu_counter_add_batch+0x35/0x160 lib/percpu_counter.c:88 percpu_counter_add include/linux/percpu_counter.h:55 [inline] percpu_counter_inc include/linux/percpu_counter.h:179 [inline] get_empty_filp+0xdf/0x3f0 fs/file_table.c:127 path_openat+0x84/0x2970 fs/namei.c:3545 do_filp_open+0x179/0x3c0 fs/namei.c:3603 do_sys_open+0x296/0x410 fs/open.c:1081 do_syscall_64+0x1d5/0x640 arch/x86/entry/common.c:292 entry_SYSCALL_64_after_hwframe+0x46/0xbb RIP: 0033:0x7f2324ee9840 RSP: 002b:00007ffc5cdbde98 EFLAGS: 00000246 ORIG_RAX: 0000000000000002 RAX: ffffffffffffffda RBX: 00007ffc5cdbe1a0 RCX: 00007f2324ee9840 RDX: 00000000000001a0 RSI: 0000000000080042 RDI: 000055f8cd911870 RBP: 000000000000000d R08: 000000000000c0ff R09: 00000000ffffffff R10: 0000000000000069 R11: 0000000000000246 R12: 00000000ffffffff R13: 000055f8cd90d040 R14: 00007ffc5cdbe160 R15: 000055f8cd919120 Code: ff 66 0f 1f 84 00 00 00 00 00 e8 db 35 3b fe 48 c7 c6 00 04 cd 87 48 c7 c7 40 04 cd 87 e9 a8 fd ff ff 0f 1f 84 00 00 00 00 00 55 <48> 89 fd e8 b7 35 3b fe 48 89 ee 5d 48 c7 c7 80 04 cd 87 e9 87 ---------------- Code disassembly (best guess), 1 bytes skipped: 0: 66 0f 1f 84 00 00 00 nopw 0x0(%rax,%rax,1) 7: 00 00 9: e8 db 35 3b fe callq 0xfe3b35e9 e: 48 c7 c6 00 04 cd 87 mov $0xffffffff87cd0400,%rsi 15: 48 c7 c7 40 04 cd 87 mov $0xffffffff87cd0440,%rdi 1c: e9 a8 fd ff ff jmpq 0xfffffdc9 21: 0f 1f 84 00 00 00 00 nopl 0x0(%rax,%rax,1) 28: 00 29: 55 push %rbp * 2a: 48 89 fd mov %rdi,%rbp <-- trapping instruction 2d: e8 b7 35 3b fe callq 0xfe3b35e9 32: 48 89 ee mov %rbp,%rsi 35: 5d pop %rbp 36: 48 c7 c7 80 04 cd 87 mov $0xffffffff87cd0480,%rdi 3d: e9 .byte 0xe9 3e: 87 .byte 0x87