device macvtap1 entered promiscuous mode device dummy0 entered promiscuous mode team0: Device macvtap1 failed to register rx_handler device dummy0 left promiscuous mode ============================================ WARNING: possible recursive locking detected 4.14.305-syzkaller #0 Not tainted -------------------------------------------- syz-executor.2/11991 is trying to acquire lock: (&HFSPLUS_I(inode)->extents_lock){+.+.}, at: [] hfsplus_get_block+0x1f9/0x820 fs/hfsplus/extents.c:260 but task is already holding lock: (&HFSPLUS_I(inode)->extents_lock){+.+.}, at: [] hfsplus_file_extend+0x188/0xef0 fs/hfsplus/extents.c:452 other info that might help us debug this: Possible unsafe locking scenario: CPU0 ---- lock(&HFSPLUS_I(inode)->extents_lock); lock(&HFSPLUS_I(inode)->extents_lock); *** DEADLOCK *** May be due to missing lock nesting notation 5 locks held by syz-executor.2/11991: #0: (&type->s_umount_key#64/1){+.+.}, at: [] alloc_super fs/super.c:251 [inline] #0: (&type->s_umount_key#64/1){+.+.}, at: [] sget_userns+0x556/0xc10 fs/super.c:516 #1: (&sbi->vh_mutex){+.+.}, at: [] hfsplus_fill_super+0x1314/0x1850 fs/hfsplus/super.c:553 #2: (&tree->tree_lock#2){+.+.}, at: [] hfsplus_find_init+0x1a8/0x220 fs/hfsplus/bfind.c:30 #3: (&HFSPLUS_I(inode)->extents_lock){+.+.}, at: [] hfsplus_file_extend+0x188/0xef0 fs/hfsplus/extents.c:452 #4: (&sbi->alloc_mutex){+.+.}, at: [] hfsplus_block_allocate+0xd2/0x910 fs/hfsplus/bitmap.c:35 stack backtrace: CPU: 0 PID: 11991 Comm: syz-executor.2 Not tainted 4.14.305-syzkaller #0 device macvtap1 entered promiscuous mode Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/21/2023 Call Trace: __dump_stack lib/dump_stack.c:17 [inline] dump_stack+0x1b2/0x281 lib/dump_stack.c:58 print_deadlock_bug kernel/locking/lockdep.c:1800 [inline] check_deadlock kernel/locking/lockdep.c:1847 [inline] validate_chain kernel/locking/lockdep.c:2448 [inline] __lock_acquire.cold+0x180/0x97c kernel/locking/lockdep.c:3491 device dummy0 entered promiscuous mode team0: Device macvtap1 failed to register rx_handler lock_acquire+0x170/0x3f0 kernel/locking/lockdep.c:3998 __mutex_lock_common kernel/locking/mutex.c:756 [inline] __mutex_lock+0xc4/0x1310 kernel/locking/mutex.c:893 hfsplus_get_block+0x1f9/0x820 fs/hfsplus/extents.c:260 block_read_full_page+0x25e/0x8d0 fs/buffer.c:2316 do_read_cache_page+0x38e/0xc10 mm/filemap.c:2713 read_mapping_page include/linux/pagemap.h:398 [inline] hfsplus_block_allocate+0x189/0x910 fs/hfsplus/bitmap.c:37 hfsplus_file_extend+0x421/0xef0 fs/hfsplus/extents.c:463 REISERFS (device loop4): found reiserfs format "3.6" with non-standard journal hfsplus_bmap_reserve+0x26e/0x410 fs/hfsplus/btree.c:357 hfsplus_create_cat+0x1af/0x10d0 fs/hfsplus/catalog.c:272 REISERFS (device loop4): using ordered data mode reiserfs: using flush barriers REISERFS (device loop4): journal params: device loop4, size 512, journal first block 18, max trans len 256, max batch 225, max commit age 30, max trans age 30 REISERFS (device loop4): checking transaction log (loop4) REISERFS (device loop4): Using r5 hash to sort names hfsplus_fill_super+0x1386/0x1850 fs/hfsplus/super.c:560 REISERFS (device loop4): Created .reiserfs_priv - reserved for xattr storage. mount_bdev+0x2b3/0x360 fs/super.c:1134 mount_fs+0x92/0x2a0 fs/super.c:1237 vfs_kern_mount.part.0+0x5b/0x470 fs/namespace.c:1046 vfs_kern_mount fs/namespace.c:1036 [inline] do_new_mount fs/namespace.c:2572 [inline] do_mount+0xe65/0x2a30 fs/namespace.c:2905 SYSC_mount fs/namespace.c:3121 [inline] SyS_mount+0xa8/0x120 fs/namespace.c:3098 do_syscall_64+0x1d5/0x640 arch/x86/entry/common.c:292 entry_SYSCALL_64_after_hwframe+0x5e/0xd3 RIP: 0033:0x7fc4391bc62a RSP: 002b:00007fc43772cf88 EFLAGS: 00000202 ORIG_RAX: 00000000000000a5 RAX: ffffffffffffffda RBX: 000000000000061b RCX: 00007fc4391bc62a RDX: 0000000020000600 RSI: 00000000200001c0 RDI: 00007fc43772cfe0 RBP: 00007fc43772d020 R08: 00007fc43772d020 R09: 0000000001a00010 R10: 0000000001a00010 R11: 0000000000000202 R12: 0000000020000600 R13: 00000000200001c0 R14: 00007fc43772cfe0 R15: 0000000020000140 device dummy0 left promiscuous mode device macvtap1 entered promiscuous mode device dummy0 entered promiscuous mode team0: Device macvtap1 failed to register rx_handler device dummy0 left promiscuous mode device macvtap1 entered promiscuous mode device dummy0 entered promiscuous mode team0: Device macvtap1 failed to register rx_handler device dummy0 left promiscuous mode device macvtap1 entered promiscuous mode device dummy0 entered promiscuous mode print_req_error: I/O error, dev loop0, sector 0 print_req_error: I/O error, dev loop2, sector 0 team0: Device macvtap1 failed to register rx_handler device dummy0 left promiscuous mode JFS: discard option not supported on device JFS: discard option not supported on device UDF-fs: error (device loop2): udf_read_tagged: read failed, block=256, location=256 UDF-fs: error (device loop2): udf_read_tagged: read failed, block=512, location=512 UDF-fs: warning (device loop2): udf_load_vrs: No anchor found UDF-fs: Scanning with blocksize 512 failed UDF-fs: error (device loop2): udf_read_tagged: read failed, block=256, location=256 UDF-fs: INFO Mounting volume 'LinuxUDF', timestamp 2022/11/22 14:59 (1000) device macvtap1 entered promiscuous mode device dummy0 entered promiscuous mode team0: Device macvtap1 failed to register rx_handler device dummy0 left promiscuous mode UDF-fs: error (device loop2): udf_read_tagged: read failed, block=256, location=256 JFS: discard option not supported on device JFS: discard option not supported on device JFS: discard option not supported on device UDF-fs: error (device loop2): udf_read_tagged: read failed, block=512, location=512 device macvtap1 entered promiscuous mode JFS: discard option not supported on device device dummy0 entered promiscuous mode UDF-fs: warning (device loop2): udf_load_vrs: No anchor found UDF-fs: Scanning with blocksize 512 failed team0: Device macvtap1 failed to register rx_handler UDF-fs: error (device loop2): udf_read_tagged: read failed, block=256, location=256 device dummy0 left promiscuous mode UDF-fs: INFO Mounting volume 'LinuxUDF', timestamp 2022/11/22 14:59 (1000) JFS: discard option not supported on device JFS: discard option not supported on device UDF-fs: error (device loop2): udf_read_tagged: read failed, block=256, location=256 UDF-fs: error (device loop2): udf_read_tagged: read failed, block=512, location=512 device macvtap1 entered promiscuous mode UDF-fs: warning (device loop2): udf_load_vrs: No anchor found UDF-fs: Scanning with blocksize 512 failed UDF-fs: error (device loop2): udf_read_tagged: read failed, block=256, location=256 device dummy0 entered promiscuous mode UDF-fs: INFO Mounting volume 'LinuxUDF', timestamp 2022/11/22 14:59 (1000) team0: Device macvtap1 failed to register rx_handler JFS: discard option not supported on device device dummy0 left promiscuous mode JFS: discard option not supported on device UDF-fs: error (device loop2): udf_read_tagged: read failed, block=256, location=256 UDF-fs: error (device loop2): udf_read_tagged: read failed, block=512, location=512 UDF-fs: warning (device loop2): udf_load_vrs: No anchor found UDF-fs: Scanning with blocksize 512 failed UDF-fs: error (device loop2): udf_read_tagged: read failed, block=256, location=256 device macvtap1 entered promiscuous mode JFS: discard option not supported on device UDF-fs: INFO Mounting volume 'LinuxUDF', timestamp 2022/11/22 14:59 (1000) JFS: discard option not supported on device device dummy0 entered promiscuous mode team0: Device macvtap1 failed to register rx_handler device dummy0 left promiscuous mode device macvtap1 entered promiscuous mode device dummy0 entered promiscuous mode team0: Device macvtap1 failed to register rx_handler device dummy0 left promiscuous mode JFS: discard option not supported on device JFS: discard option not supported on device netlink: 12 bytes leftover after parsing attributes in process `syz-executor.1'. netlink: 12 bytes leftover after parsing attributes in process `syz-executor.1'. netlink: 12 bytes leftover after parsing attributes in process `syz-executor.4'. ntfs: volume version 3.1. netlink: 12 bytes leftover after parsing attributes in process `syz-executor.1'. netlink: 12 bytes leftover after parsing attributes in process `syz-executor.4'. ntfs: volume version 3.1. netlink: 12 bytes leftover after parsing attributes in process `syz-executor.1'. netlink: 12 bytes leftover after parsing attributes in process `syz-executor.4'. ntfs: volume version 3.1. ntfs: volume version 3.1. Bluetooth: hci1 command 0x0406 tx timeout Bluetooth: hci2 command 0x0406 tx timeout Bluetooth: hci3 command 0x0406 tx timeout Bluetooth: hci4 command 0x0406 tx timeout Bluetooth: hci5 command 0x0406 tx timeout kauditd_printk_skb: 41 callbacks suppressed audit: type=1800 audit(1676543504.589:173): pid=12517 uid=0 auid=4294967295 ses=4294967295 op="collect_data" cause="failed(directio)" comm="syz-executor.5" name="bus" dev="loop5" ino=3 res=0 attempt to access beyond end of device loop5: rw=2049, want=2064, limit=2048 kvm: emulating exchange as write audit: type=1800 audit(1676543505.479:174): pid=12585 uid=0 auid=4294967295 ses=4294967295 op="collect_data" cause="failed(directio)" comm="syz-executor.5" name="bus" dev="loop5" ino=4 res=0 attempt to access beyond end of device loop5: rw=2049, want=2064, limit=2048 audit: type=1800 audit(1676543506.099:175): pid=12647 uid=0 auid=4294967295 ses=4294967295 op="collect_data" cause="failed(directio)" comm="syz-executor.5" name="bus" dev="loop5" ino=5 res=0 attempt to access beyond end of device loop5: rw=2049, want=2064, limit=2048 audit: type=1800 audit(1676543506.709:176): pid=12694 uid=0 auid=4294967295 ses=4294967295 op="collect_data" cause="failed(directio)" comm="syz-executor.5" name="bus" dev="loop5" ino=6 res=0 attempt to access beyond end of device loop5: rw=2049, want=2064, limit=2048 audit: type=1800 audit(1676543507.109:177): pid=12729 uid=0 auid=4294967295 ses=4294967295 op="collect_data" cause="failed(directio)" comm="syz-executor.3" name="bus" dev="loop3" ino=7 res=0 attempt to access beyond end of device loop3: rw=2049, want=2064, limit=2048 audit: type=1800 audit(1676543507.579:178): pid=12752 uid=0 auid=4294967295 ses=4294967295 op="collect_data" cause="failed(directio)" comm="syz-executor.5" name="bus" dev="loop5" ino=8 res=0 audit: type=1800 audit(1676543507.839:179): pid=12778 uid=0 auid=4294967295 ses=4294967295 op="collect_data" cause="failed(directio)" comm="syz-executor.3" name="bus" dev="loop3" ino=9 res=0 attempt to access beyond end of device loop5: rw=2049, want=2064, limit=2048 attempt to access beyond end of device loop3: rw=2049, want=2064, limit=2048 audit: type=1800 audit(1676543508.049:180): pid=12789 uid=0 auid=4294967295 ses=4294967295 op="collect_data" cause="failed(directio)" comm="syz-executor.0" name="bus" dev="loop0" ino=10 res=0 attempt to access beyond end of device loop0: rw=2049, want=2064, limit=2048 netlink: 12 bytes leftover after parsing attributes in process `syz-executor.2'. IPv6: ADDRCONF(NETDEV_CHANGE): lo: link becomes ready IPv6: ADDRCONF(NETDEV_CHANGE): lo: link becomes ready IPv6: ADDRCONF(NETDEV_CHANGE): vcan0: link becomes ready IPv6: ADDRCONF(NETDEV_CHANGE): vcan0: link becomes ready chnl_net:chnl_net_open(): err: Unable to register and open device, Err:-19 caif:caif_disconnect_client(): nothing to disconnect chnl_net:chnl_flowctrl_cb(): NET flowctrl func called flow: CLOSE/DEINIT chnl_net:chnl_net_open(): state disconnected A link change request failed with some changes committed already. Interface caif0 may have been left with an inconsistent configuration, please check. bridge0: port 2(bridge_slave_1) entered disabled state bridge0: port 1(bridge_slave_0) entered disabled state ntfs: volume version 3.1. batman_adv: batadv0: Interface deactivated: batadv_slave_0 batman_adv: batadv0: Interface deactivated: batadv_slave_1 audit: type=1800 audit(1676543508.729:181): pid=12818 uid=0 auid=4294967295 ses=4294967295 op="collect_data" cause="failed(directio)" comm="syz-executor.5" name="bus" dev="loop5" ino=11 res=0 attempt to access beyond end of device loop5: rw=2049, want=2064, limit=2048 ntfs: volume version 3.1. ntfs: volume version 3.1. audit: type=1800 audit(1676543509.109:182): pid=12843 uid=0 auid=4294967295 ses=4294967295 op="collect_data" cause="failed(directio)" comm="syz-executor.3" name="bus" dev="loop3" ino=12 res=0 netlink: 12 bytes leftover after parsing attributes in process `syz-executor.2'. IPv6: ADDRCONF(NETDEV_CHANGE): lo: link becomes ready IPv6: ADDRCONF(NETDEV_CHANGE): lo: link becomes ready IPv6: ADDRCONF(NETDEV_UP): bridge0: link is not ready IPv6: ADDRCONF(NETDEV_CHANGE): vcan0: link becomes ready IPv6: ADDRCONF(NETDEV_CHANGE): vcan0: link becomes ready 8021q: adding VLAN 0 to HW filter on device bond0 IPv6: ADDRCONF(NETDEV_UP): team0: link is not ready 8021q: adding VLAN 0 to HW filter on device team0 chnl_net:chnl_net_open(): err: Unable to register and open device, Err:-19 caif:caif_disconnect_client(): nothing to disconnect chnl_net:chnl_flowctrl_cb(): NET flowctrl func called flow: CLOSE/DEINIT chnl_net:chnl_net_open(): state disconnected A link change request failed with some changes committed already. Interface caif0 may have been left with an inconsistent configuration, please check. attempt to access beyond end of device loop3: rw=2049, want=2064, limit=2048 audit: type=1800 audit(1676543509.599:183): pid=12858 uid=0 auid=4294967295 ses=4294967295 op="collect_data" cause="failed(directio)" comm="syz-executor.0" name="bus" dev="loop0" ino=13 res=0 attempt to access beyond end of device loop0: rw=2049, want=2064, limit=2048 ntfs: volume version 3.1. audit: type=1800 audit(1676543509.859:184): pid=12875 uid=0 auid=4294967295 ses=4294967295 op="collect_data" cause="failed(directio)" comm="syz-executor.5" name="bus" dev="loop5" ino=14 res=0 ntfs: volume version 3.1. attempt to access beyond end of device loop5: rw=2049, want=2064, limit=2048