audit: type=1800 audit(1677507175.962:34): pid=10551 uid=0 auid=4294967295 ses=4294967295 op="collect_data" cause="failed(directio)" comm="syz-executor.0" name="bus" dev="sda1" ino=14033 res=0 ====================================================== WARNING: possible circular locking dependency detected 4.14.307-syzkaller #0 Not tainted ------------------------------------------------------ syz-executor.2/10500 is trying to acquire lock: ("%s-%s""btrfs", name){+.+.}, at: [] flush_workqueue+0xcb/0x1310 kernel/workqueue.c:2622 but task is already holding lock: (&fs_info->scrub_lock){+.+.}, at: [] btrfs_scrub_dev+0x506/0xcd0 fs/btrfs/scrub.c:4217 which lock already depends on the new lock. the existing dependency chain (in reverse order) is: -> #3 (&fs_info->scrub_lock){+.+.}: __mutex_lock_common kernel/locking/mutex.c:756 [inline] __mutex_lock+0xc4/0x1310 kernel/locking/mutex.c:893 btrfs_scrub_pause+0x80/0x330 fs/btrfs/scrub.c:4229 btrfs_commit_transaction+0x534/0x2210 fs/btrfs/transaction.c:2099 btrfs_ioctl_quota_ctl fs/btrfs/ioctl.c:4956 [inline] btrfs_ioctl+0x4797/0x5b20 fs/btrfs/ioctl.c:5703 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 -> #2 (sb_internal#2){.+.+}: percpu_down_read_preempt_disable include/linux/percpu-rwsem.h:36 [inline] percpu_down_read include/linux/percpu-rwsem.h:59 [inline] __sb_start_write+0x64/0x260 fs/super.c:1342 sb_start_intwrite include/linux/fs.h:1598 [inline] start_transaction+0x6de/0xf30 fs/btrfs/transaction.c:548 btrfs_qgroup_rescan_worker+0x176/0x1060 fs/btrfs/qgroup.c:2632 normal_work_helper+0x304/0x1330 fs/btrfs/async-thread.c:376 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 -> #1 ((&work->normal_work)){+.+.}: process_one_work+0x736/0x14a0 kernel/workqueue.c:2093 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 -> #0 ("%s-%s""btrfs", name){+.+.}: lock_acquire+0x170/0x3f0 kernel/locking/lockdep.c:3998 flush_workqueue+0xfa/0x1310 kernel/workqueue.c:2625 drain_workqueue+0x177/0x3e0 kernel/workqueue.c:2790 destroy_workqueue+0x71/0x710 kernel/workqueue.c:4116 __btrfs_destroy_workqueue fs/btrfs/async-thread.c:436 [inline] btrfs_destroy_workqueue+0xf8/0x630 fs/btrfs/async-thread.c:447 scrub_workers_put+0x90/0x1a0 fs/btrfs/scrub.c:4075 btrfs_scrub_dev+0x536/0xcd0 fs/btrfs/scrub.c:4219 btrfs_ioctl_scrub fs/btrfs/ioctl.c:4451 [inline] btrfs_ioctl+0xba8/0x5b20 fs/btrfs/ioctl.c:5681 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 other info that might help us debug this: Chain exists of: "%s-%s""btrfs", name --> sb_internal#2 --> &fs_info->scrub_lock Possible unsafe locking scenario: CPU0 CPU1 ---- ---- lock(&fs_info->scrub_lock); lock(sb_internal#2); lock(&fs_info->scrub_lock); lock("%s-%s""btrfs", name); *** DEADLOCK *** 1 lock held by syz-executor.2/10500: #0: (&fs_info->scrub_lock){+.+.}, at: [] btrfs_scrub_dev+0x506/0xcd0 fs/btrfs/scrub.c:4217 stack backtrace: CPU: 1 PID: 10500 Comm: syz-executor.2 Not tainted 4.14.307-syzkaller #0 Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 02/16/2023 Call Trace: __dump_stack lib/dump_stack.c:17 [inline] dump_stack+0x1b2/0x281 lib/dump_stack.c:58 print_circular_bug.constprop.0.cold+0x2d7/0x41e kernel/locking/lockdep.c:1258 check_prev_add kernel/locking/lockdep.c:1905 [inline] check_prevs_add kernel/locking/lockdep.c:2022 [inline] validate_chain kernel/locking/lockdep.c:2464 [inline] __lock_acquire+0x2e0e/0x3f20 kernel/locking/lockdep.c:3491 lock_acquire+0x170/0x3f0 kernel/locking/lockdep.c:3998 flush_workqueue+0xfa/0x1310 kernel/workqueue.c:2625 drain_workqueue+0x177/0x3e0 kernel/workqueue.c:2790 destroy_workqueue+0x71/0x710 kernel/workqueue.c:4116 __btrfs_destroy_workqueue fs/btrfs/async-thread.c:436 [inline] btrfs_destroy_workqueue+0xf8/0x630 fs/btrfs/async-thread.c:447 scrub_workers_put+0x90/0x1a0 fs/btrfs/scrub.c:4075 btrfs_scrub_dev+0x536/0xcd0 fs/btrfs/scrub.c:4219 btrfs_ioctl_scrub fs/btrfs/ioctl.c:4451 [inline] btrfs_ioctl+0xba8/0x5b20 fs/btrfs/ioctl.c:5681 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:0x7fe96cb980f9 RSP: 002b:00007fe96b10a168 EFLAGS: 00000246 ORIG_RAX: 0000000000000010 RAX: ffffffffffffffda RBX: 00007fe96ccb7f80 RCX: 00007fe96cb980f9 RDX: 0000000020000100 RSI: 00000000c400941b RDI: 0000000000000004 RBP: 00007fe96cbf3ae9 R08: 0000000000000000 R09: 0000000000000000 R10: 0000000000000000 R11: 0000000000000246 R12: 0000000000000000 R13: 00007ffe7eeabaff R14: 00007fe96b10a300 R15: 0000000000022000 unregister_netdevice: waiting for ip6gre0 to become free. Usage count = -1 audit: type=1804 audit(1677507177.472:35): pid=10606 uid=0 auid=4294967295 ses=4294967295 op="invalid_pcr" cause="open_writers" comm="syz-executor.0" name="/root/syzkaller-testdir116642880/syzkaller.i9aOyG/35/bus" dev="sda1" ino=14040 res=1 audit: type=1800 audit(1677507177.472:36): pid=10606 uid=0 auid=4294967295 ses=4294967295 op="collect_data" cause="failed(directio)" comm="syz-executor.0" name="bus" dev="sda1" ino=14040 res=0 device batadv0 entered promiscuous mode lo: Cannot use loopback or non-ethernet device as HSR slave. device batadv0 left promiscuous mode BTRFS info (device loop2): enabling inode map caching BTRFS info (device loop2): trying to use backup root at mount time BTRFS info (device loop2): use zlib compression BTRFS info (device loop2): enabling ssd optimizations BTRFS info (device loop2): using spread ssd allocation scheme audit: type=1804 audit(1677507178.372:37): pid=10655 uid=0 auid=4294967295 ses=4294967295 op="invalid_pcr" cause="open_writers" comm="syz-executor.0" name="/root/syzkaller-testdir116642880/syzkaller.i9aOyG/36/bus" dev="sda1" ino=13929 res=1 BTRFS info (device loop2): using free space tree BTRFS info (device loop2): has skinny extents audit: type=1800 audit(1677507178.372:38): pid=10655 uid=0 auid=4294967295 ses=4294967295 op="collect_data" cause="failed(directio)" comm="syz-executor.0" name="bus" dev="sda1" ino=13929 res=0 device bond1 entered promiscuous mode IPv6: ADDRCONF(NETDEV_UP): bond1: link is not ready 8021q: adding VLAN 0 to HW filter on device bond1 device batadv0 entered promiscuous mode lo: Cannot use loopback or non-ethernet device as HSR slave. device batadv0 left promiscuous mode device batadv0 entered promiscuous mode lo: Cannot use loopback or non-ethernet device as HSR slave. device batadv0 left promiscuous mode BTRFS info (device loop2): enabling inode map caching BTRFS info (device loop2): trying to use backup root at mount time BTRFS info (device loop2): use zlib compression BTRFS info (device loop2): enabling ssd optimizations BTRFS info (device loop2): using spread ssd allocation scheme BTRFS info (device loop2): using free space tree BTRFS info (device loop2): has skinny extents device bond1 entered promiscuous mode IPv6: ADDRCONF(NETDEV_UP): bond1: link is not ready 8021q: adding VLAN 0 to HW filter on device bond1 device bond2 entered promiscuous mode IPv6: ADDRCONF(NETDEV_UP): bond2: link is not ready 8021q: adding VLAN 0 to HW filter on device bond2 device batadv0 entered promiscuous mode lo: Cannot use loopback or non-ethernet device as HSR slave. device batadv0 left promiscuous mode BTRFS info (device loop2): enabling inode map caching BTRFS info (device loop2): trying to use backup root at mount time BTRFS info (device loop2): use zlib compression BTRFS info (device loop2): enabling ssd optimizations BTRFS info (device loop2): using spread ssd allocation scheme BTRFS info (device loop2): using free space tree BTRFS info (device loop2): has skinny extents device bond3 entered promiscuous mode IPv6: ADDRCONF(NETDEV_UP): bond3: link is not ready 8021q: adding VLAN 0 to HW filter on device bond3 device bond2 entered promiscuous mode IPv6: ADDRCONF(NETDEV_UP): bond2: link is not ready 8021q: adding VLAN 0 to HW filter on device bond2 device bond4 entered promiscuous mode IPv6: ADDRCONF(NETDEV_UP): bond4: link is not ready 8021q: adding VLAN 0 to HW filter on device bond4 attempt to access beyond end of device loop3: rw=1, want=2052, limit=2048 Buffer I/O error on dev loop3, logical block 512, lost async page write attempt to access beyond end of device loop3: rw=1, want=2056, limit=2048 Buffer I/O error on dev loop3, logical block 513, lost async page write attempt to access beyond end of device loop3: rw=1, want=2060, limit=2048 Buffer I/O error on dev loop3, logical block 514, lost async page write attempt to access beyond end of device loop3: rw=1, want=2064, limit=2048 Buffer I/O error on dev loop3, logical block 515, lost async page write device bond3 entered promiscuous mode IPv6: ADDRCONF(NETDEV_UP): bond3: link is not ready 8021q: adding VLAN 0 to HW filter on device bond3 attempt to access beyond end of device loop3: rw=1, want=2052, limit=2048 Buffer I/O error on dev loop3, logical block 512, lost async page write cramfs: Error -3 while decompressing! attempt to access beyond end of device loop3: rw=1, want=2056, limit=2048 cramfs: ffffffff8bf440b8(27)->ffff8880502ed000(4096) cramfs: Error -3 while decompressing! Buffer I/O error on dev loop3, logical block 513, lost async page write cramfs: ffffffff8bf440b8(27)->ffff8880502ed000(4096) attempt to access beyond end of device audit: type=1800 audit(1677507182.112:39): pid=11228 uid=0 auid=4294967295 ses=4294967295 op="collect_data" cause="failed" comm="syz-executor.2" name="file0" dev="loop2" ino=244 res=0 loop3: rw=1, want=2060, limit=2048 Buffer I/O error on dev loop3, logical block 514, lost async page write attempt to access beyond end of device loop3: rw=1, want=2064, limit=2048 Buffer I/O error on dev loop3, logical block 515, lost async page write EXT4-fs (loop0): mounted filesystem without journal. Opts: ,errors=continue f2fs_msg: 30 callbacks suppressed F2FS-fs (loop4): Mismatch start address, segment0(512) cp_blkaddr(605) F2FS-fs (loop4): Can't find valid F2FS filesystem in 1th superblock F2FS-fs (loop4): invalid crc value F2FS-fs (loop4): Found nat_bits in checkpoint F2FS-fs (loop4): Mounted with checkpoint version = 753bd00b attempt to access beyond end of device loop4: rw=2049, want=77952, limit=63271 cramfs: Error -3 while decompressing! cramfs: ffffffff8bf480b8(27)->ffff88805c82c000(4096) cramfs: Error -3 while decompressing! cramfs: ffffffff8bf480b8(27)->ffff88805c82c000(4096) EXT4-fs (loop0): mounted filesystem without journal. Opts: ,errors=continue audit: type=1800 audit(1677507182.622:40): pid=11275 uid=0 auid=4294967295 ses=4294967295 op="collect_data" cause="failed" comm="syz-executor.2" name="file0" dev="loop2" ino=244 res=0 cramfs: Error -3 while decompressing! cramfs: ffffffff8bf440b8(27)->ffff8880564fb000(4096) attempt to access beyond end of device cramfs: Error -3 while decompressing! loop3: rw=1, want=2052, limit=2048 cramfs: ffffffff8bf440b8(27)->ffff8880564fb000(4096) Buffer I/O error on dev loop3, logical block 512, lost async page write audit: type=1800 audit(1677507182.832:41): pid=11299 uid=0 auid=4294967295 ses=4294967295 op="collect_data" cause="failed" comm="syz-executor.2" name="file0" dev="loop2" ino=244 res=0 attempt to access beyond end of device loop3: rw=1, want=2056, limit=2048 Buffer I/O error on dev loop3, logical block 513, lost async page write attempt to access beyond end of device loop3: rw=1, want=2060, limit=2048 cramfs: Error -3 while decompressing! cramfs: ffffffff8bf480b8(27)->ffff888051bd9000(4096) attempt to access beyond end of device loop3: rw=1, want=2064, limit=2048 cramfs: Error -3 while decompressing! EXT4-fs (loop0): mounted filesystem without journal. Opts: ,errors=continue cramfs: ffffffff8bf480b8(27)->ffff888051bd9000(4096) audit: type=1800 audit(1677507183.072:42): pid=11311 uid=0 auid=4294967295 ses=4294967295 op="collect_data" cause="failed" comm="syz-executor.2" name="file0" dev="loop2" ino=244 res=0 F2FS-fs (loop5): Mismatch start address, segment0(512) cp_blkaddr(605) F2FS-fs (loop5): Can't find valid F2FS filesystem in 1th superblock F2FS-fs (loop5): invalid crc value F2FS-fs (loop1): Mismatch start address, segment0(512) cp_blkaddr(605) F2FS-fs (loop5): Found nat_bits in checkpoint EXT4-fs (loop0): mounted filesystem without journal. Opts: ,errors=continue attempt to access beyond end of device loop3: rw=1, want=2052, limit=2048 attempt to access beyond end of device loop3: rw=1, want=2056, limit=2048 attempt to access beyond end of device loop3: rw=1, want=2060, limit=2048 attempt to access beyond end of device loop3: rw=1, want=2064, limit=2048 attempt to access beyond end of device loop5: rw=2049, want=77952, limit=63271 attempt to access beyond end of device loop1: rw=2049, want=77952, limit=63271 attempt to access beyond end of device loop4: rw=2049, want=77952, limit=63271 attempt to access beyond end of device loop2: rw=2049, want=77952, limit=63271 attempt to access beyond end of device loop0: rw=2049, want=77952, limit=63271 attempt to access beyond end of device loop3: rw=2049, want=77952, limit=63271 attempt to access beyond end of device loop5: rw=2049, want=77952, limit=63271 attempt to access beyond end of device loop1: rw=2049, want=77952, limit=63271 attempt to access beyond end of device loop4: rw=2049, want=77952, limit=63271 attempt to access beyond end of device loop2: rw=2049, want=77952, limit=63271 attempt to access beyond end of device loop0: rw=2049, want=77952, limit=63271 attempt to access beyond end of device loop3: rw=2049, want=77952, limit=63271