INFO: task syz-executor.2:5218 blocked for more than 143 seconds.
Not tainted 5.15.156-syzkaller #0
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
task:syz-executor.2 state:D stack:23040 pid: 5218 ppid: 3517 flags:0x00004006
Call Trace:
context_switch kernel/sched/core.c:5030 [inline]
__schedule+0x12c4/0x45b0 kernel/sched/core.c:6376
schedule+0x11b/0x1f0 kernel/sched/core.c:6459
xlog_grant_head_wait+0x381/0x900 fs/xfs/xfs_log.c:256
xlog_grant_head_check+0x295/0x480
xfs_log_reserve+0x3b8/0xb10 fs/xfs/xfs_log.c:464
xfs_trans_reserve+0x23d/0x690 fs/xfs/xfs_trans.c:197
xfs_trans_alloc+0x420/0x7f0 fs/xfs/xfs_trans.c:288
xfs_trans_alloc_inode+0x129/0x450 fs/xfs/xfs_trans.c:1047
xfs_attr_set+0x848/0x1460 fs/xfs/libxfs/xfs_attr.c:772
xfs_xattr_set+0x227/0x3a0 fs/xfs/xfs_xattr.c:59
__vfs_setxattr+0x3e7/0x420 fs/xattr.c:182
__vfs_setxattr_noperm+0x12a/0x5e0 fs/xattr.c:216
vfs_setxattr+0x21d/0x420 fs/xattr.c:303
do_setxattr fs/xattr.c:588 [inline]
setxattr+0x27e/0x2e0 fs/xattr.c:611
path_setxattr+0x1bc/0x2a0 fs/xattr.c:630
__do_sys_setxattr fs/xattr.c:646 [inline]
__se_sys_setxattr fs/xattr.c:642 [inline]
__x64_sys_setxattr+0xb7/0xd0 fs/xattr.c:642
do_syscall_x64 arch/x86/entry/common.c:50 [inline]
do_syscall_64+0x3b/0xb0 arch/x86/entry/common.c:80
entry_SYSCALL_64_after_hwframe+0x66/0xd0
RIP: 0033:0x7fba60387ea9
RSP: 002b:00007fba5e8fa0c8 EFLAGS: 00000246 ORIG_RAX: 00000000000000bc
RAX: ffffffffffffffda RBX: 00007fba604b5f80 RCX: 00007fba60387ea9
RDX: 0000000020000000 RSI: 0000000020000100 RDI: 00000000200000c0
RBP: 00007fba603d44a4 R08: 0000000000000000 R09: 0000000000000000
R10: 000000000000feeb R11: 0000000000000246 R12: 0000000000000000
R13: 000000000000000b R14: 00007fba604b5f80 R15: 00007fff9ac99fa8
INFO: task syz-executor.2:5286 blocked for more than 144 seconds.
Not tainted 5.15.156-syzkaller #0
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
task:syz-executor.2 state:D stack:26840 pid: 5286 ppid: 3517 flags:0x00004006
Call Trace:
context_switch kernel/sched/core.c:5030 [inline]
__schedule+0x12c4/0x45b0 kernel/sched/core.c:6376
schedule+0x11b/0x1f0 kernel/sched/core.c:6459
xlog_grant_head_wait+0x381/0x900 fs/xfs/xfs_log.c:256
xlog_grant_head_check+0x295/0x480
xfs_log_reserve+0x3b8/0xb10 fs/xfs/xfs_log.c:464
xfs_trans_reserve+0x23d/0x690 fs/xfs/xfs_trans.c:197
xfs_trans_alloc+0x420/0x7f0 fs/xfs/xfs_trans.c:288
xfs_trans_alloc_icreate+0xc5/0x2b0 fs/xfs/xfs_trans.c:1102
xfs_create+0x745/0x1370 fs/xfs/xfs_inode.c:1018
xfs_generic_create+0x426/0xd00 fs/xfs/xfs_iops.c:197
lookup_open fs/namei.c:3462 [inline]
open_last_lookups fs/namei.c:3532 [inline]
path_openat+0x130a/0x2f20 fs/namei.c:3739
do_filp_open+0x21c/0x460 fs/namei.c:3769
do_sys_openat2+0x13b/0x500 fs/open.c:1253
do_sys_open fs/open.c:1269 [inline]
__do_sys_openat fs/open.c:1285 [inline]
__se_sys_openat fs/open.c:1280 [inline]
__x64_sys_openat+0x243/0x290 fs/open.c:1280
do_syscall_x64 arch/x86/entry/common.c:50 [inline]
do_syscall_64+0x3b/0xb0 arch/x86/entry/common.c:80
entry_SYSCALL_64_after_hwframe+0x66/0xd0
RIP: 0033:0x7fba60387ea9
RSP: 002b:00007fba5e8d90c8 EFLAGS: 00000246 ORIG_RAX: 0000000000000101
RAX: ffffffffffffffda RBX: 00007fba604b6050 RCX: 00007fba60387ea9
RDX: 000000000000275a RSI: 0000000020000200 RDI: ffffffffffffff9c
RBP: 00007fba603d44a4 R08: 0000000000000000 R09: 0000000000000000
R10: 0000000000000000 R11: 0000000000000246 R12: 0000000000000000
R13: 000000000000006e R14: 00007fba604b6050 R15: 00007fff9ac99fa8
Showing all locks held in the system:
1 lock held by khungtaskd/26:
#0: ffffffff8c91fb20 (rcu_read_lock){....}-{1:2}, at: rcu_lock_acquire+0x0/0x30
2 locks held by getty/3262:
#0: ffff88814b748098 (&tty->ldisc_sem){++++}-{0:0}, at: tty_ldisc_ref_wait+0x21/0x70 drivers/tty/tty_ldisc.c:252
#1: ffffc9000250b2e8 (&ldata->atomic_read_lock){+.+.}-{3:3}, at: n_tty_read+0x6af/0x1db0 drivers/tty/n_tty.c:2158
2 locks held by kworker/0:9/3726:
#0: ffff88801927bd38 ((wq_completion)xfs-sync/loop2){+.+.}-{0:0}, at: process_one_work+0x78a/0x10c0 kernel/workqueue.c:2283
#1: ffffc90003fe7d20 ((work_completion)(&(&log->l_work)->work)){+.+.}-{0:0}, at: process_one_work+0x7d0/0x10c0 kernel/workqueue.c:2285
2 locks held by kworker/1:19/3750:
#0: ffff888011c72138 ((wq_completion)rcu_gp){+.+.}-{0:0}, at: process_one_work+0x78a/0x10c0 kernel/workqueue.c:2283
#1: ffffc90003827d20 ((work_completion)(&rew.rew_work)){+.+.}-{0:0}, at: process_one_work+0x7d0/0x10c0 kernel/workqueue.c:2285
6 locks held by kworker/0:27/4649:
#0: ffff888012ad7d38 ((wq_completion)usb_hub_wq){+.+.}-{0:0}, at: process_one_work+0x78a/0x10c0 kernel/workqueue.c:2283
#1: ffffc90008bf7d20 ((work_completion)(&hub->events)){+.+.}-{0:0}, at: process_one_work+0x7d0/0x10c0 kernel/workqueue.c:2285
#2: ffff8881483a6220 (&dev->mutex){....}-{3:3}, at: device_lock include/linux/device.h:760 [inline]
#2: ffff8881483a6220 (&dev->mutex){....}-{3:3}, at: hub_event+0x208/0x54c0 drivers/usb/core/hub.c:5768
#3: ffff88801e3995c0 (&port_dev->status_lock){+.+.}-{3:3}, at: usb_lock_port drivers/usb/core/hub.c:3125 [inline]
#3: ffff88801e3995c0 (&port_dev->status_lock){+.+.}-{3:3}, at: hub_port_connect drivers/usb/core/hub.c:5350 [inline]
#3: ffff88801e3995c0 (&port_dev->status_lock){+.+.}-{3:3}, at: hub_port_connect_change drivers/usb/core/hub.c:5594 [inline]
#3: ffff88801e3995c0 (&port_dev->status_lock){+.+.}-{3:3}, at: port_event drivers/usb/core/hub.c:5740 [inline]
#3: ffff88801e3995c0 (&port_dev->status_lock){+.+.}-{3:3}, at: hub_event+0x2fc1/0x54c0 drivers/usb/core/hub.c:5822
#4: ffff88801e321168 (hcd->address0_mutex){+.+.}-{3:3}, at: hub_port_connect drivers/usb/core/hub.c:5351 [inline]
#4: ffff88801e321168 (hcd->address0_mutex){+.+.}-{3:3}, at: hub_port_connect_change drivers/usb/core/hub.c:5594 [inline]
#4: ffff88801e321168 (hcd->address0_mutex){+.+.}-{3:3}, at: port_event drivers/usb/core/hub.c:5740 [inline]
#4: ffff88801e321168 (hcd->address0_mutex){+.+.}-{3:3}, at: hub_event+0x2fec/0x54c0 drivers/usb/core/hub.c:5822
#5: ffff8880b9a3a358 (&rq->__lock){-.-.}-{2:2}, at: raw_spin_rq_lock_nested+0x26/0x140 kernel/sched/core.c:475
2 locks held by kworker/0:31/4653:
#0: ffff888011c70938 ((wq_completion)events){+.+.}-{0:0}, at: process_one_work+0x78a/0x10c0 kernel/workqueue.c:2283
#1: ffffc9000ac6fd20 ((work_completion)(&map->work)){+.+.}-{0:0}, at: process_one_work+0x7d0/0x10c0 kernel/workqueue.c:2285
3 locks held by kworker/0:32/4654:
#0: ffff888011c70938 ((wq_completion)events){+.+.}-{0:0}, at: process_one_work+0x78a/0x10c0 kernel/workqueue.c:2283
#1: ffffc9000aeefd20 ((work_completion)(&map->work)){+.+.}-{0:0}, at: process_one_work+0x7d0/0x10c0 kernel/workqueue.c:2285
#2: ffffffff8c923ff0 (rcu_state.barrier_mutex){+.+.}-{3:3}, at: rcu_barrier+0x9c/0x4e0 kernel/rcu/tree.c:4039
3 locks held by syz-executor.2/5218:
#0: ffff888076a08460 (sb_writers#18){.+.+}-{0:0}, at: mnt_want_write+0x3b/0x80 fs/namespace.c:377
#1: ffff8880713eec00 (&inode->i_sb->s_type->i_mutex_dir_key){++++}-{3:3}, at: inode_lock include/linux/fs.h:789 [inline]
#1: ffff8880713eec00 (&inode->i_sb->s_type->i_mutex_dir_key){++++}-{3:3}, at: vfs_setxattr+0x1dd/0x420 fs/xattr.c:302
#2: ffff888076a08650 (sb_internal#3){.+.+}-{0:0}, at: xfs_trans_alloc_inode+0x129/0x450 fs/xfs/xfs_trans.c:1047
3 locks held by syz-executor.2/5286:
#0: ffff888076a08460 (sb_writers#18){.+.+}-{0:0}, at: mnt_want_write+0x3b/0x80 fs/namespace.c:377
#1: ffff8880713ece00 (&inode->i_sb->s_type->i_mutex_dir_key){++++}-{3:3}, at: inode_lock include/linux/fs.h:789 [inline]
#1: ffff8880713ece00 (&inode->i_sb->s_type->i_mutex_dir_key){++++}-{3:3}, at: open_last_lookups fs/namei.c:3529 [inline]
#1: ffff8880713ece00 (&inode->i_sb->s_type->i_mutex_dir_key){++++}-{3:3}, at: path_openat+0x824/0x2f20 fs/namei.c:3739
#2: ffff888076a08650 (sb_internal#3){.+.+}-{0:0}, at: xfs_trans_alloc_icreate+0xc5/0x2b0 fs/xfs/xfs_trans.c:1102
2 locks held by syz-executor.0/5436:
1 lock held by syz-executor.2/5446:
#0: ffff8880759b40e0 (&type->s_umount_key#111){+.+.}-{3:3}, at: deactivate_super+0xa9/0xe0 fs/super.c:365
2 locks held by syz-executor.0/6388:
#0: ffff8880649740e0 (&type->s_umount_key#111){+.+.}-{3:3}, at: deactivate_super+0xa9/0xe0 fs/super.c:365
#1: ffffffff8c9240e8 (rcu_state.exp_mutex){+.+.}-{3:3}, at: exp_funnel_lock kernel/rcu/tree_exp.h:322 [inline]
#1: ffffffff8c9240e8 (rcu_state.exp_mutex){+.+.}-{3:3}, at: synchronize_rcu_expedited+0x350/0x740 kernel/rcu/tree_exp.h:845
=============================================
NMI backtrace for cpu 0
CPU: 0 PID: 26 Comm: khungtaskd Not tainted 5.15.156-syzkaller #0
Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 03/27/2024
Call Trace:
__dump_stack lib/dump_stack.c:88 [inline]
dump_stack_lvl+0x1e3/0x2d0 lib/dump_stack.c:106
nmi_cpu_backtrace+0x46a/0x4a0 lib/nmi_backtrace.c:111
nmi_trigger_cpumask_backtrace+0x181/0x2a0 lib/nmi_backtrace.c:62
trigger_all_cpu_backtrace include/linux/nmi.h:148 [inline]
check_hung_uninterruptible_tasks kernel/hung_task.c:210 [inline]
watchdog+0xe72/0xeb0 kernel/hung_task.c:295
kthread+0x3f6/0x4f0 kernel/kthread.c:334
ret_from_fork+0x1f/0x30 arch/x86/entry/entry_64.S:300
Sending NMI from CPU 0 to CPUs 1:
NMI backtrace for cpu 1
CPU: 1 PID: 5446 Comm: syz-executor.2 Not tainted 5.15.156-syzkaller #0
Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 03/27/2024
RIP: 0010:lock_acquire+0x27b/0x4f0 kernel/locking/lockdep.c:5627
Code: 45 4b c7 44 25 00 00 00 00 00 43 c7 44 25 09 00 00 00 00 43 c7 44 25 11 00 00 00 00 66 43 c7 44 25 15 00 00 43 c6 44 25 17 00 <65> 48 8b 04 25 28 00 00 00 48 3b 84 24 00 01 00 00 0f 85 52 02 00
RSP: 0018:ffffc90002ff7860 EFLAGS: 00000046
RAX: 0000000000000001 RBX: 1ffff920005fef18 RCX: ffffffff81636522
RDX: dffffc0000000000 RSI: ffffffff8a8b3c20 RDI: ffffffff8ad8f380
RBP: ffffc90002ff79b0 R08: dffffc0000000000 R09: fffffbfff1f7ec19
R10: 0000000000000000 R11: dffffc0000000001 R12: 1ffff920005fef14
R13: dffffc0000000000 R14: ffffc90002ff78c0 R15: 0000000000000046
FS: 00005555561fc480(0000) GS:ffff8880b9b00000(0000) knlGS:0000000000000000
CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
CR2: 0000000020000600 CR3: 000000007e452000 CR4: 00000000003506e0
Call Trace:
_raw_spin_lock_nested+0x2d/0x40 kernel/locking/spinlock.c:368
raw_spin_rq_lock_nested+0x26/0x140 kernel/sched/core.c:475
raw_spin_rq_lock kernel/sched/sched.h:1326 [inline]
rq_lock kernel/sched/sched.h:1621 [inline]
__schedule+0x2f2/0x45b0 kernel/sched/core.c:6290
schedule+0x11b/0x1f0 kernel/sched/core.c:6459
freezable_schedule include/linux/freezer.h:172 [inline]
do_nanosleep+0x1bc/0x7b0 kernel/time/hrtimer.c:2045
hrtimer_nanosleep+0x24d/0x490 kernel/time/hrtimer.c:2098
__do_sys_clock_nanosleep kernel/time/posix-timers.c:1313 [inline]
__se_sys_clock_nanosleep+0x323/0x3b0 kernel/time/posix-timers.c:1290
do_syscall_x64 arch/x86/entry/common.c:50 [inline]
do_syscall_64+0x3b/0xb0 arch/x86/entry/common.c:80
entry_SYSCALL_64_after_hwframe+0x66/0xd0
RIP: 0033:0x7fd4767352b5
Code: 24 0c 89 3c 24 48 89 4c 24 18 e8 f6 b9 ff ff 4c 8b 54 24 18 48 8b 54 24 10 41 89 c0 8b 74 24 0c 8b 3c 24 b8 e6 00 00 00 0f 05 <44> 89 c7 48 89 04 24 e8 4f ba ff ff 48 8b 04 24 48 83 c4 28 f7 d8
RSP: 002b:00007ffc69f095e0 EFLAGS: 00000293 ORIG_RAX: 00000000000000e6
RAX: ffffffffffffffda RBX: 00000000000001a0 RCX: 00007fd4767352b5
RDX: 00007ffc69f09620 RSI: 0000000000000000 RDI: 0000000000000000
RBP: 00007ffc69f096ac R08: 0000000000000000 R09: 0000000000000010
R10: 0000000000000000 R11: 0000000000000293 R12: 0000000000000032
R13: 0000000000042470 R14: 00000000000423e5 R15: 0000000000000007