INFO: task syz.7.369:8088 blocked for more than 143 seconds.
Not tainted 6.13.0-rc3-next-20241220-syzkaller #0
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
task:syz.7.369 state:D stack:21328 pid:8088 tgid:8076 ppid:6646 flags:0x00004004
Call Trace:
<TASK>
context_switch kernel/sched/core.c:5371 [inline]
__schedule+0x189f/0x4c80 kernel/sched/core.c:6758
__schedule_loop kernel/sched/core.c:6835 [inline]
schedule+0x14b/0x320 kernel/sched/core.c:6850
bit_wait+0x12/0xd0 kernel/sched/wait_bit.c:237
__wait_on_bit+0xb0/0x2f0 kernel/sched/wait_bit.c:49
out_of_line_wait_on_bit+0x1d5/0x260 kernel/sched/wait_bit.c:64
wait_on_bit include/linux/wait_bit.h:77 [inline]
netfs_dispatch_unbuffered_reads fs/netfs/direct_read.c:107 [inline]
netfs_unbuffered_read fs/netfs/direct_read.c:146 [inline]
netfs_unbuffered_read_iter_locked+0xd83/0x1540 fs/netfs/direct_read.c:231
netfs_unbuffered_read_iter+0xbf/0xe0 fs/netfs/direct_read.c:266
__kernel_read+0x52e/0xa00 fs/read_write.c:523
integrity_kernel_read+0xb0/0x100 security/integrity/iint.c:28
ima_calc_file_hash_tfm security/integrity/ima/ima_crypto.c:480 [inline]
ima_calc_file_shash security/integrity/ima/ima_crypto.c:511 [inline]
ima_calc_file_hash+0xae6/0x1b30 security/integrity/ima/ima_crypto.c:568
ima_collect_measurement+0x520/0xb10 security/integrity/ima/ima_api.c:293
process_measurement+0x1351/0x1fb0 security/integrity/ima/ima_main.c:372
ima_file_check+0xd9/0x120 security/integrity/ima/ima_main.c:600
security_file_post_open+0xb9/0x280 security/security.c:3130
do_open fs/namei.c:3830 [inline]
path_openat+0x2cbd/0x3580 fs/namei.c:3987
do_filp_open+0x27f/0x4e0 fs/namei.c:4014
do_sys_openat2+0x13e/0x1d0 fs/open.c:1427
do_sys_open fs/open.c:1442 [inline]
__do_sys_openat fs/open.c:1458 [inline]
__se_sys_openat fs/open.c:1453 [inline]
__x64_sys_openat+0x247/0x2a0 fs/open.c:1453
do_syscall_x64 arch/x86/entry/common.c:52 [inline]
do_syscall_64+0xf3/0x230 arch/x86/entry/common.c:83
entry_SYSCALL_64_after_hwframe+0x77/0x7f
RIP: 0033:0x7f0ca3985d29
RSP: 002b:00007f0ca4787038 EFLAGS: 00000246 ORIG_RAX: 0000000000000101
RAX: ffffffffffffffda RBX: 00007f0ca3b76080 RCX: 00007f0ca3985d29
RDX: 0000000000020842 RSI: 000000002000c380 RDI: ffffffffffffff9c
RBP: 00007f0ca3a01b08 R08: 0000000000000000 R09: 0000000000000000
R10: 0000000000000000 R11: 0000000000000246 R12: 0000000000000000
R13: 0000000000000001 R14: 00007f0ca3b76080 R15: 00007ffe2a0c1da8
</TASK>
Showing all locks held in the system:
3 locks held by kworker/u8:1/12:
1 lock held by khungtaskd/30:
#0: ffffffff8e937da0 (rcu_read_lock){....}-{1:3}, at: rcu_lock_acquire include/linux/rcupdate.h:337 [inline]
#0: ffffffff8e937da0 (rcu_read_lock){....}-{1:3}, at: rcu_read_lock include/linux/rcupdate.h:849 [inline]
#0: ffffffff8e937da0 (rcu_read_lock){....}-{1:3}, at: debug_show_all_locks+0x55/0x2a0 kernel/locking/lockdep.c:6744
3 locks held by kworker/1:2/978:
#0: ffff8880b873e998 (&rq->__lock){-.-.}-{2:2}, at: raw_spin_rq_lock_nested+0x2a/0x140 kernel/sched/core.c:598
#1: ffffffff9a5b8478 (&obj_hash[i].lock){-.-.}-{2:2}, at: process_one_work kernel/workqueue.c:3205 [inline]
#1: ffffffff9a5b8478 (&obj_hash[i].lock){-.-.}-{2:2}, at: process_scheduled_works+0x976/0x1840 kernel/workqueue.c:3310
#2: ffff888141aed0c8 (&ACCESS_PRIVATE(ssp->srcu_sup, lock)){....}-{3:3}, at: spin_lock_irq include/linux/spinlock.h:376 [inline]
#2: ffff888141aed0c8 (&ACCESS_PRIVATE(ssp->srcu_sup, lock)){....}-{3:3}, at: srcu_reschedule+0x45/0x170 kernel/rcu/srcutree.c:1822
2 locks held by getty/5587:
#0: ffff8880352d20a0 (&tty->ldisc_sem){++++}-{0:0}, at: tty_ldisc_ref_wait+0x25/0x70 drivers/tty/tty_ldisc.c:243
#1: ffffc90002fde2f0 (&ldata->atomic_read_lock){+.+.}-{4:4}, at: n_tty_read+0x6a6/0x1e00 drivers/tty/n_tty.c:2211
4 locks held by kworker/u8:10/7882:
2 locks held by syz.7.369/8088:
#0: ffff8880791197d8 (&ima_iint_mutex_key[depth]){+.+.}-{4:4}, at: process_measurement+0x7a6/0x1fb0 security/integrity/ima/ima_main.c:269
#1: ffff888052a00148 (&sb->s_type->i_mutex_key#42){++++}-{4:4}, at: netfs_start_io_direct+0x1d4/0x210 fs/netfs/locking.c:188
2 locks held by syz-executor/8294:
#0: ffffffff8f05e928 (bio_slab_lock){+.+.}-{4:4}, at: bio_put_slab block/bio.c:140 [inline]
#0: ffffffff8f05e928 (bio_slab_lock){+.+.}-{4:4}, at: bioset_exit+0x42f/0x650 block/bio.c:1755
#1: ffffffff8e93d140 (rcu_state.barrier_mutex){+.+.}-{4:4}, at: rcu_barrier+0x4c/0x530 kernel/rcu/tree.c:3741
1 lock held by syz.1.666/10571:
1 lock held by syz.0.671/10606:
#0: ffffffff8f05e928 (bio_slab_lock){+.+.}-{4:4}, at: bio_find_or_create_slab block/bio.c:122 [inline]
#0: ffffffff8f05e928 (bio_slab_lock){+.+.}-{4:4}, at: bioset_init+0x23f/0x820 block/bio.c:1796
1 lock held by cmp/10643:
=============================================
NMI backtrace for cpu 1
CPU: 1 UID: 0 PID: 30 Comm: khungtaskd Not tainted 6.13.0-rc3-next-20241220-syzkaller #0
Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 09/13/2024
Call Trace:
<TASK>
__dump_stack lib/dump_stack.c:94 [inline]
dump_stack_lvl+0x241/0x360 lib/dump_stack.c:120
nmi_cpu_backtrace+0x49c/0x4d0 lib/nmi_backtrace.c:113
nmi_trigger_cpumask_backtrace+0x198/0x320 lib/nmi_backtrace.c:62
trigger_all_cpu_backtrace include/linux/nmi.h:162 [inline]
check_hung_uninterruptible_tasks kernel/hung_task.c:234 [inline]
watchdog+0xff6/0x1040 kernel/hung_task.c:397
kthread+0x7a9/0x920 kernel/kthread.c:464
ret_from_fork+0x4b/0x80 arch/x86/kernel/process.c:148
ret_from_fork_asm+0x1a/0x30 arch/x86/entry/entry_64.S:244
</TASK>
Sending NMI from CPU 1 to CPUs 0:
NMI backtrace for cpu 0
CPU: 0 UID: 0 PID: 5841 Comm: kworker/u9:4 Not tainted 6.13.0-rc3-next-20241220-syzkaller #0
Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 09/13/2024
Workqueue: hci2 hci_cmd_timeout
RIP: 0010:preempt_count arch/x86/include/asm/preempt.h:26 [inline]
RIP: 0010:preempt_count_sub+0x3b/0x170 kernel/sched/core.c:5877
Code: c1 e8 03 49 be 00 00 00 00 00 fc ff df 42 0f b6 04 30 84 c0 0f 85 d0 00 00 00 83 3d 1e 7b b0 18 00 75 22 65 8b 05 5d 68 76 7e <89> c1 81 e1 ff ff ff 7f 39 d9 7c 27 81 fb fe 00 00 00 77 07 0f b6
RSP: 0018:ffffc90000007c78 EFLAGS: 00000046
RAX: 0000000000000102 RBX: 0000000000000001 RCX: ffffffff9a3de903
RDX: 0000000000000000 RSI: 0000000000000004 RDI: 0000000000000001
RBP: ffffc90000007df0 R08: ffff8880b862a383 R09: 1ffff110170c5470
R10: dffffc0000000000 R11: ffffed10170c5471 R12: ffffffff81876290
R13: ffffc90000007d40 R14: dffffc0000000000 R15: ffff88802872d828
FS: 0000000000000000(0000) GS:ffff8880b8600000(0000) knlGS:0000000000000000
CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
CR2: 00007f2b1a9bf440 CR3: 000000002f596000 CR4: 00000000003526f0
DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400
Call Trace:
<NMI>
</NMI>
<IRQ>
__raw_spin_unlock include/linux/spinlock_api_smp.h:143 [inline]
_raw_spin_unlock+0x28/0x50 kernel/locking/spinlock.c:186
expire_timers kernel/time/timer.c:1838 [inline]
__run_timers kernel/time/timer.c:2418 [inline]
__run_timer_base+0x685/0x8e0 kernel/time/timer.c:2430
run_timer_base kernel/time/timer.c:2439 [inline]
run_timer_softirq+0xb7/0x170 kernel/time/timer.c:2449
handle_softirqs+0x2d4/0x9b0 kernel/softirq.c:561
__do_softirq kernel/softirq.c:595 [inline]
invoke_softirq kernel/softirq.c:435 [inline]
__irq_exit_rcu+0xf7/0x220 kernel/softirq.c:662
irq_exit_rcu+0x9/0x30 kernel/softirq.c:678
instr_sysvec_apic_timer_interrupt arch/x86/kernel/apic/apic.c:1049 [inline]
sysvec_apic_timer_interrupt+0xa6/0xc0 arch/x86/kernel/apic/apic.c:1049
</IRQ>
<TASK>
asm_sysvec_apic_timer_interrupt+0x1a/0x20 arch/x86/include/asm/idtentry.h:702
RIP: 0010:console_trylock_spinning kernel/printk/printk.c:2061 [inline]
RIP: 0010:vprintk_emit+0x700/0xa10 kernel/printk/printk.c:2431
Code: 00 e8 f4 c7 20 00 4c 8d bc 24 a0 00 00 00 4d 85 e4 75 07 e8 e2 c7 20 00 eb 06 e8 db c7 20 00 fb 49 bc 00 00 00 00 00 fc ff df <48> c7 c7 60 37 81 8e 31 f6 ba 01 00 00 00 31 c9 41 b8 01 00 00 00
RSP: 0018:ffffc900040ff7a0 EFLAGS: 00000293
RAX: ffffffff819e5c45 RBX: 0000000000000000 RCX: ffff888034913c00
RDX: 0000000000000000 RSI: 0000000000000000 RDI: 0000000000000000
RBP: ffffc900040ff8b0 R08: ffffffff819e5c1e R09: 1ffffffff285af26
R10: dffffc0000000000 R11: fffffbfff285af27 R12: dffffc0000000000
R13: 1ffff9200081fef8 R14: ffffffff819e5a80 R15: ffffc900040ff840
_printk+0xd5/0x120 kernel/printk/printk.c:2457
bt_err+0x127/0x180 net/bluetooth/lib.c:296
hci_cmd_timeout+0xdd/0x1e0 net/bluetooth/hci_core.c:1452
process_one_work kernel/workqueue.c:3229 [inline]
process_scheduled_works+0xa66/0x1840 kernel/workqueue.c:3310
worker_thread+0x870/0xd30 kernel/workqueue.c:3391
kthread+0x7a9/0x920 kernel/kthread.c:464
ret_from_fork+0x4b/0x80 arch/x86/kernel/process.c:148
ret_from_fork_asm+0x1a/0x30 arch/x86/entry/entry_64.S:244
</TASK>