F2FS-fs (loop2): Found nat_bits in checkpoint netlink: 4 bytes leftover after parsing attributes in process `syz-executor.5'. ====================================================== WARNING: possible circular locking dependency detected 4.14.280-syzkaller #0 Not tainted F2FS-fs (loop2): sanity_check_inode: inode (ino=3) has corrupted i_extra_isize: 24, max: 12 ------------------------------------------------------ syz-executor.5/12967 is trying to acquire lock: (&xt[i].mutex){+.+.}, at: [] xt_find_target+0x3e/0x1e0 net/netfilter/x_tables.c:232 but task is already holding lock: F2FS-fs (loop2): Failed to read root inode (rtnl_mutex){+.+.}, at: [] rtnl_lock net/core/rtnetlink.c:72 [inline] (rtnl_mutex){+.+.}, at: [] rtnetlink_rcv_msg+0x31d/0xb10 net/core/rtnetlink.c:4317 which lock already depends on the new lock. the existing dependency chain (in reverse order) is: F2FS-fs (loop2): Found nat_bits in checkpoint -> #1 (rtnl_mutex){+.+.}: __mutex_lock_common kernel/locking/mutex.c:756 [inline] __mutex_lock+0xc4/0x1310 kernel/locking/mutex.c:893 unregister_netdevice_notifier+0x5e/0x2b0 net/core/dev.c:1630 tee_tg_destroy+0x5c/0xb0 net/netfilter/xt_TEE.c:123 F2FS-fs (loop2): sanity_check_inode: inode (ino=3) has corrupted i_extra_isize: 24, max: 12 cleanup_entry+0x1fd/0x2d0 net/ipv4/netfilter/ip_tables.c:666 F2FS-fs (loop2): Failed to read root inode __do_replace+0x38d/0x570 net/ipv4/netfilter/ip_tables.c:1086 do_replace net/ipv4/netfilter/ip_tables.c:1142 [inline] do_ipt_set_ctl+0x256/0x3a0 net/ipv4/netfilter/ip_tables.c:1676 nf_sockopt net/netfilter/nf_sockopt.c:106 [inline] nf_setsockopt+0x5f/0xb0 net/netfilter/nf_sockopt.c:115 ip_setsockopt net/ipv4/ip_sockglue.c:1255 [inline] ip_setsockopt+0x94/0xb0 net/ipv4/ip_sockglue.c:1240 raw_setsockopt+0xac/0xd0 net/ipv4/raw.c:860 SYSC_setsockopt net/socket.c:1865 [inline] SyS_setsockopt+0x110/0x1e0 net/socket.c:1844 do_syscall_64+0x1d5/0x640 arch/x86/entry/common.c:292 entry_SYSCALL_64_after_hwframe+0x46/0xbb -> #0 (&xt[i].mutex){+.+.}: 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 xt_find_target+0x3e/0x1e0 net/netfilter/x_tables.c:232 xt_request_find_target net/netfilter/x_tables.c:261 [inline] xt_request_find_target+0x72/0xe0 net/netfilter/x_tables.c:254 ipt_init_target+0xb9/0x250 net/sched/act_ipt.c:45 __tcf_ipt_init+0x48d/0xc00 net/sched/act_ipt.c:168 tcf_xt_init+0x43/0x50 net/sched/act_ipt.c:210 tcf_action_init_1+0x51a/0x9e0 net/sched/act_api.c:691 tcf_action_init+0x26d/0x400 net/sched/act_api.c:760 tcf_action_add net/sched/act_api.c:1088 [inline] tc_ctl_action+0x2e3/0x510 net/sched/act_api.c:1140 rtnetlink_rcv_msg+0x3be/0xb10 net/core/rtnetlink.c:4322 netlink_rcv_skb+0x125/0x390 net/netlink/af_netlink.c:2454 netlink_unicast_kernel net/netlink/af_netlink.c:1296 [inline] netlink_unicast+0x437/0x610 net/netlink/af_netlink.c:1322 netlink_sendmsg+0x648/0xbc0 net/netlink/af_netlink.c:1893 sock_sendmsg_nosec net/socket.c:646 [inline] sock_sendmsg+0xb5/0x100 net/socket.c:656 sock_no_sendpage+0xe2/0x110 net/core/sock.c:2610 kernel_sendpage net/socket.c:3407 [inline] sock_sendpage+0xdf/0x140 net/socket.c:871 pipe_to_sendpage+0x226/0x2d0 fs/splice.c:451 splice_from_pipe_feed fs/splice.c:502 [inline] __splice_from_pipe+0x326/0x7a0 fs/splice.c:626 splice_from_pipe fs/splice.c:661 [inline] generic_splice_sendpage+0xc1/0x110 fs/splice.c:832 do_splice_from fs/splice.c:851 [inline] do_splice fs/splice.c:1147 [inline] SYSC_splice fs/splice.c:1402 [inline] SyS_splice+0xd59/0x1380 fs/splice.c:1382 do_syscall_64+0x1d5/0x640 arch/x86/entry/common.c:292 entry_SYSCALL_64_after_hwframe+0x46/0xbb other info that might help us debug this: Possible unsafe locking scenario: CPU0 CPU1 ---- ---- lock(rtnl_mutex); lock(&xt[i].mutex); lock(rtnl_mutex); lock(&xt[i].mutex); *** DEADLOCK *** 2 locks held by syz-executor.5/12967: #0: (&pipe->mutex/1){+.+.}, at: [] pipe_lock_nested fs/pipe.c:82 [inline] #0: (&pipe->mutex/1){+.+.}, at: [] pipe_lock+0x58/0x70 fs/pipe.c:90 #1: (rtnl_mutex){+.+.}, at: [] rtnl_lock net/core/rtnetlink.c:72 [inline] #1: (rtnl_mutex){+.+.}, at: [] rtnetlink_rcv_msg+0x31d/0xb10 net/core/rtnetlink.c:4317 stack backtrace: CPU: 0 PID: 12967 Comm: syz-executor.5 Not tainted 4.14.280-syzkaller #0 Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 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 __mutex_lock_common kernel/locking/mutex.c:756 [inline] __mutex_lock+0xc4/0x1310 kernel/locking/mutex.c:893 xt_find_target+0x3e/0x1e0 net/netfilter/x_tables.c:232 xt_request_find_target net/netfilter/x_tables.c:261 [inline] xt_request_find_target+0x72/0xe0 net/netfilter/x_tables.c:254 ipt_init_target+0xb9/0x250 net/sched/act_ipt.c:45 __tcf_ipt_init+0x48d/0xc00 net/sched/act_ipt.c:168 tcf_xt_init+0x43/0x50 net/sched/act_ipt.c:210 tcf_action_init_1+0x51a/0x9e0 net/sched/act_api.c:691 tcf_action_init+0x26d/0x400 net/sched/act_api.c:760 tcf_action_add net/sched/act_api.c:1088 [inline] tc_ctl_action+0x2e3/0x510 net/sched/act_api.c:1140 rtnetlink_rcv_msg+0x3be/0xb10 net/core/rtnetlink.c:4322 netlink_rcv_skb+0x125/0x390 net/netlink/af_netlink.c:2454 netlink_unicast_kernel net/netlink/af_netlink.c:1296 [inline] netlink_unicast+0x437/0x610 net/netlink/af_netlink.c:1322 netlink_sendmsg+0x648/0xbc0 net/netlink/af_netlink.c:1893 sock_sendmsg_nosec net/socket.c:646 [inline] sock_sendmsg+0xb5/0x100 net/socket.c:656 sock_no_sendpage+0xe2/0x110 net/core/sock.c:2610 kernel_sendpage net/socket.c:3407 [inline] sock_sendpage+0xdf/0x140 net/socket.c:871 pipe_to_sendpage+0x226/0x2d0 fs/splice.c:451 splice_from_pipe_feed fs/splice.c:502 [inline] __splice_from_pipe+0x326/0x7a0 fs/splice.c:626 splice_from_pipe fs/splice.c:661 [inline] generic_splice_sendpage+0xc1/0x110 fs/splice.c:832 do_splice_from fs/splice.c:851 [inline] do_splice fs/splice.c:1147 [inline] SYSC_splice fs/splice.c:1402 [inline] SyS_splice+0xd59/0x1380 fs/splice.c:1382 do_syscall_64+0x1d5/0x640 arch/x86/entry/common.c:292 entry_SYSCALL_64_after_hwframe+0x46/0xbb RIP: 0033:0x7f022449f0e9 RSP: 002b:00007f0222df3168 EFLAGS: 00000246 ORIG_RAX: 0000000000000113 RAX: ffffffffffffffda RBX: 00007f02245b2030 RCX: 00007f022449f0e9 RDX: 0000000000000005 RSI: 0000000000000000 RDI: 0000000000000003 RBP: 00007f02244f908d R08: 000000000004ffe0 R09: 0000000000000000 R10: 0000000000000000 R11: 0000000000000246 R12: 0000000000000000 R13: 00007ffe41562a2f R14: 00007f0222df3300 R15: 0000000000022000 x_tables: ip_tables: .0 target: invalid size 8 (kernel) != (user) 6 unregister_netdevice: waiting for ip6gre0 to become free. Usage count = -1 EXT4-fs (loop2): inodes count not valid: 4259872 vs 32 net_ratelimit: 4 callbacks suppressed ip_tables: iptables: counters copy to user failed while replacing table netlink: 12 bytes leftover after parsing attributes in process `syz-executor.0'. kauditd_printk_skb: 2 callbacks suppressed audit: type=1800 audit(1652865801.229:70): pid=13026 uid=0 auid=4294967295 ses=4294967295 op="collect_data" cause="failed(directio)" comm="syz-executor.5" name="file0" dev="sda1" ino=13972 res=0 netlink: 68 bytes leftover after parsing attributes in process `syz-executor.0'. Cannot find add_set index 0 as target ip_tables: iptables: counters copy to user failed while replacing table EXT4-fs (loop2): inodes count not valid: 4259872 vs 32 netlink: 12 bytes leftover after parsing attributes in process `syz-executor.0'. Cannot find add_set index 0 as target ip_tables: iptables: counters copy to user failed while replacing table audit: type=1804 audit(1652865801.229:71): pid=13026 uid=0 auid=4294967295 ses=4294967295 op="invalid_pcr" cause="open_writers" comm="syz-executor.5" name="/root/syzkaller-testdir2425153929/syzkaller.GMG3Wo/112/file0" dev="sda1" ino=13972 res=1 ip_tables: iptables: counters copy to user failed while replacing table netlink: 68 bytes leftover after parsing attributes in process `syz-executor.0'. audit: type=1804 audit(1652865801.229:72): pid=13026 uid=0 auid=4294967295 ses=4294967295 op="invalid_pcr" cause="ToMToU" comm="syz-executor.5" name="/root/syzkaller-testdir2425153929/syzkaller.GMG3Wo/112/file0" dev="sda1" ino=13972 res=1 Cannot find add_set index 0 as target netlink: 12 bytes leftover after parsing attributes in process `syz-executor.4'. EXT4-fs (loop2): inodes count not valid: 4259872 vs 32 netlink: 68 bytes leftover after parsing attributes in process `syz-executor.4'. print_req_error: I/O error, dev loop2, sector 4 Buffer I/O error on dev loop2, logical block 1, async page read audit: type=1800 audit(1652865802.089:73): pid=13081 uid=0 auid=4294967295 ses=4294967295 op="collect_data" cause="failed(directio)" comm="syz-executor.5" name="file0" dev="sda1" ino=13880 res=0 audit: type=1804 audit(1652865802.099:74): pid=13081 uid=0 auid=4294967295 ses=4294967295 op="invalid_pcr" cause="open_writers" comm="syz-executor.5" name="/root/syzkaller-testdir2425153929/syzkaller.GMG3Wo/113/file0" dev="sda1" ino=13880 res=1 audit: type=1804 audit(1652865802.119:75): pid=13081 uid=0 auid=4294967295 ses=4294967295 op="invalid_pcr" cause="ToMToU" comm="syz-executor.5" name="/root/syzkaller-testdir2425153929/syzkaller.GMG3Wo/113/file0" dev="sda1" ino=13880 res=1 Cannot find add_set index 0 as target netlink: 12 bytes leftover after parsing attributes in process `syz-executor.4'. EXT4-fs (loop2): inodes count not valid: 4259872 vs 32 netlink: 12 bytes leftover after parsing attributes in process `syz-executor.0'. print_req_error: I/O error, dev loop2, sector 4 Buffer I/O error on dev loop2, logical block 1, async page read netlink: 68 bytes leftover after parsing attributes in process `syz-executor.4'. audit: type=1800 audit(1652865802.609:76): pid=13108 uid=0 auid=4294967295 ses=4294967295 op="collect_data" cause="failed(directio)" comm="syz-executor.3" name="file0" dev="sda1" ino=14377 res=0 audit: type=1804 audit(1652865802.709:77): pid=13108 uid=0 auid=4294967295 ses=4294967295 op="invalid_pcr" cause="open_writers" comm="syz-executor.3" name="/root/syzkaller-testdir3915153183/syzkaller.9mohtz/122/file0" dev="sda1" ino=14377 res=1 audit: type=1800 audit(1652865802.889:78): pid=13109 uid=0 auid=4294967295 ses=4294967295 op="collect_data" cause="failed(directio)" comm="syz-executor.1" name="file0" dev="sda1" ino=14404 res=0 audit: type=1804 audit(1652865802.889:79): pid=13110 uid=0 auid=4294967295 ses=4294967295 op="invalid_pcr" cause="open_writers" comm="syz-executor.1" name="/root/syzkaller-testdir1122779375/syzkaller.wVg19u/107/file0" dev="sda1" ino=14404 res=1 nla_parse: 7 callbacks suppressed netlink: 68 bytes leftover after parsing attributes in process `syz-executor.0'. netlink: 68 bytes leftover after parsing attributes in process `syz-executor.2'. netlink: 104 bytes leftover after parsing attributes in process `syz-executor.4'. netlink: 12 bytes leftover after parsing attributes in process `syz-executor.2'. netlink: 104 bytes leftover after parsing attributes in process `syz-executor.4'. netlink: 104 bytes leftover after parsing attributes in process `syz-executor.1'. netlink: 68 bytes leftover after parsing attributes in process `syz-executor.2'. netlink: 104 bytes leftover after parsing attributes in process `syz-executor.4'. netlink: 104 bytes leftover after parsing attributes in process `syz-executor.1'. netlink: 104 bytes leftover after parsing attributes in process `syz-executor.4'. kauditd_printk_skb: 20 callbacks suppressed audit: type=1800 audit(1652865806.859:100): pid=13239 uid=0 auid=4294967295 ses=4294967295 op="collect_data" cause="failed(directio)" comm="syz-executor.5" name="bus" dev="sda1" ino=14395 res=0 audit: type=1800 audit(1652865808.159:101): pid=13298 uid=0 auid=4294967295 ses=4294967295 op="collect_data" cause="failed(directio)" comm="syz-executor.5" name="bus" dev="sda1" ino=14440 res=0 UDF-fs: INFO Mounting volume 'LinuxUDF', timestamp 2020/09/19 18:44 (1000) UDF-fs: INFO Mounting volume 'LinuxUDF', timestamp 2020/09/19 18:44 (1000) x_tables: ip_tables: osf match: only valid for protocol 6 audit: type=1800 audit(1652865809.059:102): pid=13366 uid=0 auid=4294967295 ses=4294967295 op="collect_data" cause="failed(directio)" comm="syz-executor.5" name="bus" dev="sda1" ino=14474 res=0 UDF-fs: INFO Mounting volume 'LinuxUDF', timestamp 2020/09/19 18:44 (1000) UDF-fs: INFO Mounting volume 'LinuxUDF', timestamp 2020/09/19 18:44 (1000) audit: type=1800 audit(1652865809.299:103): pid=13421 uid=0 auid=4294967295 ses=4294967295 op="collect_data" cause="failed(directio)" comm="syz-executor.5" name="bus" dev="sda1" ino=13844 res=0