[ 1485.794675] run fstests btrfs/017 at 2020-11-20 21:15:04
[ 1486.474203] BTRFS: device fsid 8c392985-a051-4e5d-98e3-f0628ec2575d devid 1 transid 5 /dev/mapper/vg0-lv9 scanned by mkfs.btrfs (223684)
[ 1486.510104] BTRFS info (device dm-9): turning on async discard
[ 1486.510107] BTRFS info (device dm-9): disk space caching is enabled
[ 1486.510108] BTRFS info (device dm-9): has skinny extents
[ 1486.510110] BTRFS info (device dm-9): flagging fs with big metadata feature
[ 1486.533737] BTRFS info (device dm-9): checking UUID tree
[ 1486.596427] ======================================================
[ 1486.596780] WARNING: possible circular locking dependency detected
[ 1486.597137] 5.10.0-rc4+ #53 Not tainted
[ 1486.597367] ------------------------------------------------------
[ 1486.597720] btrfs/223732 is trying to acquire lock:
[ 1486.598013] ffff94a45108e650 (sb_internal#2){.+.+}-{0:0}, at: btrfs_quota_enable+0x8b/0x8f0
[ 1486.598496]
but task is already holding lock:
[ 1486.598831] ffff94a446139800 (&fs_info->qgroup_ioctl_lock){+.+.}-{3:3}, at: btrfs_quota_enable+0x2b/0x8f0
[ 1486.599379]
which lock already depends on the new lock.
[ 1486.599841]
the existing dependency chain (in reverse order) is:
[ 1486.600269]
-> #1 (&fs_info->qgroup_ioctl_lock){+.+.}-{3:3}:
[ 1486.600682] __mutex_lock+0x7e/0x7b0
[ 1486.600922] btrfs_qgroup_inherit+0x5d/0x740
[ 1486.601200] create_subvol+0x192/0x8e0
[ 1486.601448] btrfs_mksubvol+0x3e9/0x460
[ 1486.601700] __btrfs_ioctl_snap_create+0x119/0x180
[ 1486.602005] btrfs_ioctl_snap_create+0x58/0x80
[ 1486.602294] btrfs_ioctl+0x1229/0x30a0
[ 1486.602541] __x64_sys_ioctl+0x83/0xb0
[ 1486.602790] do_syscall_64+0x33/0x40
[ 1486.603028] entry_SYSCALL_64_after_hwframe+0x44/0xa9
[ 1486.603350]
-> #0 (sb_internal#2){.+.+}-{0:0}:
[ 1486.603692] __lock_acquire+0x1221/0x2140
[ 1486.603953] lock_acquire+0x116/0x3e0
[ 1486.604197] start_transaction+0x43b/0x6f0
[ 1486.604467] btrfs_quota_enable+0x8b/0x8f0
[ 1486.604733] btrfs_ioctl+0x2a2a/0x30a0
[ 1486.604982] __x64_sys_ioctl+0x83/0xb0
[ 1486.605232] do_syscall_64+0x33/0x40
[ 1486.605473] entry_SYSCALL_64_after_hwframe+0x44/0xa9
[ 1486.605795]
other info that might help us debug this:
[ 1486.606250] Possible unsafe locking scenario:
[ 1486.606589] CPU0 CPU1
[ 1486.606850] ---- ----
[ 1486.607112] lock(&fs_info->qgroup_ioctl_lock);
[ 1486.607380] lock(sb_internal#2);
[ 1486.607719] lock(&fs_info->qgroup_ioctl_lock);
[ 1486.608125] lock(sb_internal#2);
[ 1486.608328]
*** DEADLOCK ***
[ 1486.608667] 3 locks held by btrfs/223732:
[ 1486.608898] #0: ffff94a45108e460 (sb_writers#12){.+.+}-{0:0}, at: btrfs_ioctl+0xab2/0x30a0
[ 1486.609383] #1: ffff94a446138bb8 (&fs_info->subvol_sem){++++}-{3:3}, at: btrfs_ioctl+0x2280/0x30a0
[ 1486.609897] #2: ffff94a446139800 (&fs_info->qgroup_ioctl_lock){+.+.}-{3:3}, at: btrfs_quota_enable+0x2b/0x8f0
[ 1486.610470]
stack backtrace:
[ 1486.610722] CPU: 0 PID: 223732 Comm: btrfs Not tainted 5.10.0-rc4+ #53
[ 1486.611095] Hardware name: QEMU Standard PC (Q35 + ICH9, 2009), BIOS 1.13.0-2.fc32 04/01/2014
[ 1486.611582] Call Trace:
[ 1486.611733] dump_stack+0x8b/0xb0
[ 1486.611929] check_noncircular+0xcf/0xf0
[ 1486.612158] __lock_acquire+0x1221/0x2140
[ 1486.612396] lock_acquire+0x116/0x3e0
[ 1486.612610] ? btrfs_quota_enable+0x8b/0x8f0
[ 1486.612859] start_transaction+0x43b/0x6f0
[ 1486.613098] ? btrfs_quota_enable+0x8b/0x8f0
[ 1486.613350] btrfs_quota_enable+0x8b/0x8f0
[ 1486.613590] btrfs_ioctl+0x2a2a/0x30a0
[ 1486.613807] ? _raw_spin_unlock+0x1f/0x30
[ 1486.614041] ? handle_mm_fault+0x1061/0x1960
[ 1486.614293] ? find_held_lock+0x2b/0x80
[ 1486.614516] ? do_user_addr_fault+0x215/0x440
[ 1486.614770] ? __x64_sys_ioctl+0x83/0xb0
[ 1486.614999] __x64_sys_ioctl+0x83/0xb0
[ 1486.615219] do_syscall_64+0x33/0x40
[ 1486.615432] entry_SYSCALL_64_after_hwframe+0x44/0xa9
[ 1486.615723] RIP: 0033:0x7f125dcfa4cb
[ 1486.615935] Code: 89 d8 49 8d 3c 1c 48 f7 d8 49 39 c4 72 b5 e8 1c ff ff ff 85 c0 78 ba 4c 89 e0 5b 5d 41 5c c3 f3 0f 1e fa b8 10 00 00 00 0f 05 <48> 3d 01 f0 ff ff 73 01 c3 48 8b 0d 7d b9 0c 00 f7 d8 64 89 01 48
[ 1486.616989] RSP: 002b:00007fff58abd9c8 EFLAGS: 00000246 ORIG_RAX: 0000000000000010
[ 1486.617423] RAX: ffffffffffffffda RBX: 0000557be65c3220 RCX: 00007f125dcfa4cb
[ 1486.617829] RDX: 00007fff58abd9e0 RSI: 00000000c0109428 RDI: 0000000000000003
[ 1486.618234] RBP: 0000000000000003 R08: 0000557be72912a0 R09: 00007f125ddc6a60
[ 1486.618641] R10: 0000000000000231 R11: 0000000000000246 R12: 00007fff58abd9e0
[ 1486.619046] R13: 0000557be6600b40 R14: 0000557be65ba345 R15: 0000557be65ba1f5
[ 1486.644695] BTRFS warning (device dm-9): qgroup rescan is already in progress
[ 1486.661134] BTRFS info (device dm-9): qgroup scan completed (inconsistency flag cleared)
[ 1486.819279] BTRFS info (device dm-9): turning on async discard
[ 1486.819631] BTRFS info (device dm-9): disk space caching is enabled
[ 1486.819987] BTRFS info (device dm-9): has skinny extents