audit: type=1800 audit(1660965050.380:1443): pid=29915 uid=0 auid=4294967295 ses=4294967295 subj==unconfined op=collect_data cause=failed(directio) comm="syz-executor.1" name="bus" dev="sda1" ino=13873 res=0 ============================================ WARNING: possible recursive locking detected 4.19.211-syzkaller #0 Not tainted -------------------------------------------- syz-executor.5/29935 is trying to acquire lock: 0000000090e48ad9 (&team->lock){+.+.}, at: team_vlan_rx_add_vid+0x38/0x1e0 drivers/net/team/team.c:1896 but task is already holding lock: 00000000401c95a4 (&team->lock){+.+.}, at: team_add_slave+0x98/0x1fa0 drivers/net/team/team.c:1975 other info that might help us debug this: Possible unsafe locking scenario: CPU0 ---- lock(&team->lock); lock(&team->lock); *** DEADLOCK *** audit: type=1800 audit(1660965050.380:1444): pid=29911 uid=0 auid=4294967295 ses=4294967295 subj==unconfined op=collect_data cause=failed(directio) comm="syz-executor.1" name="file0" dev="sda1" ino=15850 res=0 May be due to missing lock nesting notation 2 locks held by syz-executor.5/29935: #0: 0000000082407403 (rtnl_mutex){+.+.}, at: rtnl_lock net/core/rtnetlink.c:77 [inline] #0: 0000000082407403 (rtnl_mutex){+.+.}, at: rtnetlink_rcv_msg+0x3fe/0xb80 net/core/rtnetlink.c:4779 #1: 00000000401c95a4 (&team->lock){+.+.}, at: team_add_slave+0x98/0x1fa0 drivers/net/team/team.c:1975 stack backtrace: CPU: 0 PID: 29935 Comm: syz-executor.5 Not tainted 4.19.211-syzkaller #0 Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 07/22/2022 Call Trace: __dump_stack lib/dump_stack.c:77 [inline] dump_stack+0x1fc/0x2ef lib/dump_stack.c:118 print_deadlock_bug kernel/locking/lockdep.c:1764 [inline] check_deadlock kernel/locking/lockdep.c:1808 [inline] validate_chain kernel/locking/lockdep.c:2404 [inline] __lock_acquire.cold+0x121/0x57e kernel/locking/lockdep.c:3416 lock_acquire+0x170/0x3c0 kernel/locking/lockdep.c:3908 __mutex_lock_common kernel/locking/mutex.c:937 [inline] __mutex_lock+0xd7/0x1190 kernel/locking/mutex.c:1078 team_vlan_rx_add_vid+0x38/0x1e0 drivers/net/team/team.c:1896 vlan_add_rx_filter_info+0x13c/0x1c0 net/8021q/vlan_core.c:210 __vlan_vid_add net/8021q/vlan_core.c:278 [inline] vlan_vid_add+0x3ee/0x7e0 net/8021q/vlan_core.c:308 vlan_add_rx_filter_info+0x13c/0x1c0 net/8021q/vlan_core.c:210 __vlan_vid_add net/8021q/vlan_core.c:278 [inline] vlan_vid_add+0x3ee/0x7e0 net/8021q/vlan_core.c:308 vlan_device_event.cold+0x28/0x2d net/8021q/vlan.c:382 audit: type=1804 audit(1660965051.100:1445): pid=29957 uid=0 auid=4294967295 ses=4294967295 subj==unconfined op=invalid_pcr cause=open_writers comm="syz-executor.4" name="/root/syzkaller-testdir785943050/syzkaller.Y0TICN/859/bus" dev="sda1" ino=15867 res=1 audit: type=1800 audit(1660965051.140:1446): pid=29957 uid=0 auid=4294967295 ses=4294967295 subj==unconfined op=collect_data cause=failed(directio) comm="syz-executor.4" name="bus" dev="sda1" ino=15867 res=0 notifier_call_chain+0xc0/0x230 kernel/notifier.c:93 call_netdevice_notifiers net/core/dev.c:1762 [inline] dev_open net/core/dev.c:1442 [inline] dev_open+0x128/0x140 net/core/dev.c:1430 team_port_add drivers/net/team/team.c:1220 [inline] team_add_slave+0xb02/0x1fa0 drivers/net/team/team.c:1976 do_set_master+0x1c8/0x220 net/core/rtnetlink.c:2321 rtnl_newlink+0x1279/0x15c0 net/core/rtnetlink.c:3170 audit: type=1800 audit(1660965051.230:1447): pid=29950 uid=0 auid=4294967295 ses=4294967295 subj==unconfined op=collect_data cause=failed(directio) comm="syz-executor.1" name="bus" dev="sda1" ino=15869 res=0 rtnetlink_rcv_msg+0x453/0xb80 net/core/rtnetlink.c:4782 netlink_rcv_skb+0x160/0x440 net/netlink/af_netlink.c:2463 netlink_unicast_kernel net/netlink/af_netlink.c:1325 [inline] netlink_unicast+0x4d5/0x690 net/netlink/af_netlink.c:1351 netlink_sendmsg+0x6c3/0xc50 net/netlink/af_netlink.c:1917 sock_sendmsg_nosec net/socket.c:651 [inline] sock_sendmsg+0xc3/0x120 net/socket.c:661 ___sys_sendmsg+0x7bb/0x8e0 net/socket.c:2227 __sys_sendmsg net/socket.c:2265 [inline] __do_sys_sendmsg net/socket.c:2274 [inline] __se_sys_sendmsg net/socket.c:2272 [inline] __x64_sys_sendmsg+0x132/0x220 net/socket.c:2272 do_syscall_64+0xf9/0x620 arch/x86/entry/common.c:293 entry_SYSCALL_64_after_hwframe+0x49/0xbe RIP: 0033:0x7f2082b74279 Code: ff ff c3 66 2e 0f 1f 84 00 00 00 00 00 0f 1f 40 00 48 89 f8 48 89 f7 48 89 d6 48 89 ca 4d 89 c2 4d 89 c8 4c 8b 4c 24 08 0f 05 <48> 3d 01 f0 ff ff 73 01 c3 48 c7 c1 b8 ff ff ff f7 d8 64 89 01 48 RSP: 002b:00007f20814e9168 EFLAGS: 00000246 ORIG_RAX: 000000000000002e RAX: ffffffffffffffda RBX: 00007f2082c86f80 RCX: 00007f2082b74279 RDX: 0000000000000000 RSI: 0000000020000080 RDI: 0000000000000003 RBP: 00007f2082bce189 R08: 0000000000000000 R09: 0000000000000000 R10: 0000000000000000 R11: 0000000000000246 R12: 0000000000000000 R13: 00007ffc84870d4f R14: 00007f20814e9300 R15: 0000000000022000 team0: Device macvtap1 failed to register rx_handler netlink: 332 bytes leftover after parsing attributes in process `syz-executor.3'. netlink: 332 bytes leftover after parsing attributes in process `syz-executor.3'. netlink: 332 bytes leftover after parsing attributes in process `syz-executor.3'. netlink: 24 bytes leftover after parsing attributes in process `syz-executor.0'. netlink: 4 bytes leftover after parsing attributes in process `syz-executor.0'. netlink: 332 bytes leftover after parsing attributes in process `syz-executor.3'. netlink: 24 bytes leftover after parsing attributes in process `syz-executor.0'. netlink: 4 bytes leftover after parsing attributes in process `syz-executor.0'. netlink: 24 bytes leftover after parsing attributes in process `syz-executor.0'. kauditd_printk_skb: 96 callbacks suppressed audit: type=1800 audit(1660965054.980:1544): pid=30303 uid=0 auid=4294967295 ses=4294967295 subj==unconfined op=collect_data cause=failed(directio) comm="syz-executor.1" name="bus" dev="sda1" ino=15250 res=0 audit: type=1800 audit(1660965055.020:1545): pid=30303 uid=0 auid=4294967295 ses=4294967295 subj==unconfined op=collect_data cause=failed(directio) comm="syz-executor.1" name="bus" dev="sda1" ino=15250 res=0 audit: type=1800 audit(1660965055.020:1546): pid=30303 uid=0 auid=4294967295 ses=4294967295 subj==unconfined op=collect_data cause=failed(directio) comm="syz-executor.1" name="bus" dev="sda1" ino=15250 res=0 audit: type=1800 audit(1660965055.020:1547): pid=30303 uid=0 auid=4294967295 ses=4294967295 subj==unconfined op=collect_data cause=failed(directio) comm="syz-executor.1" name="bus" dev="sda1" ino=15250 res=0 audit: type=1800 audit(1660965055.020:1548): pid=30303 uid=0 auid=4294967295 ses=4294967295 subj==unconfined op=collect_data cause=failed(directio) comm="syz-executor.1" name="bus" dev="sda1" ino=15250 res=0 audit: type=1800 audit(1660965055.020:1549): pid=30303 uid=0 auid=4294967295 ses=4294967295 subj==unconfined op=collect_data cause=failed(directio) comm="syz-executor.1" name="bus" dev="sda1" ino=15250 res=0 audit: type=1800 audit(1660965055.020:1550): pid=30303 uid=0 auid=4294967295 ses=4294967295 subj==unconfined op=collect_data cause=failed(directio) comm="syz-executor.1" name="bus" dev="sda1" ino=15250 res=0 audit: type=1800 audit(1660965055.020:1551): pid=30303 uid=0 auid=4294967295 ses=4294967295 subj==unconfined op=collect_data cause=failed(directio) comm="syz-executor.1" name="bus" dev="sda1" ino=15250 res=0 audit: type=1800 audit(1660965055.020:1552): pid=30303 uid=0 auid=4294967295 ses=4294967295 subj==unconfined op=collect_data cause=failed(directio) comm="syz-executor.1" name="bus" dev="sda1" ino=15250 res=0 audit: type=1800 audit(1660965055.020:1553): pid=30303 uid=0 auid=4294967295 ses=4294967295 subj==unconfined op=collect_data cause=failed(directio) comm="syz-executor.1" name="bus" dev="sda1" ino=15250 res=0 VFS: could not find a valid V7 on loop5. VFS: could not find a valid V7 on loop5. VFS: could not find a valid V7 on loop4. nla_parse: 3 callbacks suppressed netlink: 116 bytes leftover after parsing attributes in process `syz-executor.2'. VFS: could not find a valid V7 on loop5. tmpfs: No value for mount option 'mpol' VFS: could not find a valid V7 on loop5. tmpfs: Bad value 'bind=static:2' for mount option 'mpol' EXT4-fs (loop2): VFS: Can't find ext4 filesystem ax25_connect(): syz-executor.2 uses autobind, please contact jreuter@yaina.de F2FS-fs (loop5): Magic Mismatch, valid(0xf2f52010) - read(0x0) F2FS-fs (loop5): Can't find valid F2FS filesystem in 1th superblock F2FS-fs (loop5): Magic Mismatch, valid(0xf2f52010) - read(0x0) F2FS-fs (loop5): Can't find valid F2FS filesystem in 2th superblock F2FS-fs (loop5): Magic Mismatch, valid(0xf2f52010) - read(0x0) F2FS-fs (loop5): Can't find valid F2FS filesystem in 1th superblock F2FS-fs (loop5): Magic Mismatch, valid(0xf2f52010) - read(0x0) F2FS-fs (loop5): Can't find valid F2FS filesystem in 2th superblock F2FS-fs (loop4): Magic Mismatch, valid(0xf2f52010) - read(0x0) F2FS-fs (loop4): Can't find valid F2FS filesystem in 1th superblock kauditd_printk_skb: 133 callbacks suppressed audit: type=1800 audit(1660965060.100:1687): pid=30886 uid=0 auid=4294967295 ses=4294967295 subj==unconfined op=collect_data cause=failed(directio) comm="syz-executor.1" name="file0" dev="sda1" ino=15979 res=0 VFS: could not find a valid V7 on loop4. audit: type=1804 audit(1660965060.120:1688): pid=30886 uid=0 auid=4294967295 ses=4294967295 subj==unconfined op=invalid_pcr cause=open_writers comm="syz-executor.1" name="/root/syzkaller-testdir2575151858/syzkaller.dsrOOd/724/file0" dev="sda1" ino=15979 res=1 audit: type=1800 audit(1660965060.140:1689): pid=30886 uid=0 auid=4294967295 ses=4294967295 subj==unconfined op=collect_data cause=failed(directio) comm="syz-executor.1" name="bus" dev="sda1" ino=15186 res=0 FAT-fs (loop5): Unrecognized mount option "subj_user=./binderfs/binder1" or missing value