netlink: 12 bytes leftover after parsing attributes in process `syz-executor.1'.
print_req_error: I/O error, dev loop2, sector 0
======================================================
WARNING: possible circular locking dependency detected
4.14.281-syzkaller #0 Not tainted
------------------------------------------------------
kworker/u4:3/239 is trying to acquire lock:
 (sk_lock-AF_INET){+.+.}, at: [<ffffffff86825ace>] do_strp_work net/strparser/strparser.c:415 [inline]
 (sk_lock-AF_INET){+.+.}, at: [<ffffffff86825ace>] strp_work+0x3e/0x100 net/strparser/strparser.c:434

but task is already holding lock:
 ((&strp->work)){+.+.}, at: [<ffffffff81364fb6>] process_one_work+0x6e6/0x14a0 kernel/workqueue.c:2092

which lock already depends on the new lock.


the existing dependency chain (in reverse order) is:

-> #1 ((&strp->work)){+.+.}:
       flush_work+0xad/0x770 kernel/workqueue.c:2890
       __cancel_work_timer+0x321/0x460 kernel/workqueue.c:2965
       strp_done+0x53/0xd0 net/strparser/strparser.c:519
       kcm_attach net/kcm/kcmsock.c:1429 [inline]
       kcm_attach_ioctl net/kcm/kcmsock.c:1490 [inline]
       kcm_ioctl+0x828/0xfb0 net/kcm/kcmsock.c:1701
       sock_do_ioctl net/socket.c:974 [inline]
       sock_ioctl+0x2cc/0x4c0 net/socket.c:1071
       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+0x46/0xbb

-> #0 (sk_lock-AF_INET){+.+.}:
       lock_acquire+0x170/0x3f0 kernel/locking/lockdep.c:3998
       lock_sock_nested+0xb7/0x100 net/core/sock.c:2813
       do_strp_work net/strparser/strparser.c:415 [inline]
       strp_work+0x3e/0x100 net/strparser/strparser.c:434
       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:404

other info that might help us debug this:

 Possible unsafe locking scenario:

       CPU0                    CPU1
       ----                    ----
  lock((&strp->work));
                               lock(sk_lock-AF_INET);
                               lock((&strp->work));
  lock(sk_lock-AF_INET);

 *** DEADLOCK ***

2 locks held by kworker/u4:3/239:
 #0:  ("%s""kstrp"){+.+.}, at: [<ffffffff81364f80>] process_one_work+0x6b0/0x14a0 kernel/workqueue.c:2088
 #1:  ((&strp->work)){+.+.}, at: [<ffffffff81364fb6>] process_one_work+0x6e6/0x14a0 kernel/workqueue.c:2092

stack backtrace:
CPU: 1 PID: 239 Comm: kworker/u4:3 Not tainted 4.14.281-syzkaller #0
Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011
Workqueue: kstrp strp_work
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
 lock_sock_nested+0xb7/0x100 net/core/sock.c:2813
 do_strp_work net/strparser/strparser.c:415 [inline]
 strp_work+0x3e/0x100 net/strparser/strparser.c:434
 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:404
FAT-fs (loop3): Invalid FSINFO signature: 0x00000000, 0x00000000 (sector = 1)
omfs: Invalid superblock (0)
FAT-fs (loop3): error, fat_get_cluster: invalid start cluster (i_pos 0, start f8000000)
FAT-fs (loop3): error, fat_get_cluster: invalid start cluster (i_pos 0, start f8000000)
FAT-fs (loop3): error, fat_get_cluster: invalid start cluster (i_pos 0, start f8000000)
FAT-fs (loop1): Invalid FSINFO signature: 0x00000000, 0x00000000 (sector = 1)
FAT-fs (loop3): error, fat_get_cluster: invalid start cluster (i_pos 0, start f8000000)
FAT-fs (loop3): error, fat_get_cluster: invalid start cluster (i_pos 0, start f8000000)
FAT-fs (loop3): error, fat_get_cluster: invalid start cluster (i_pos 0, start f8000000)
FAT-fs (loop3): error, fat_get_cluster: invalid start cluster (i_pos 0, start f8000000)
FAT-fs (loop3): error, fat_get_cluster: invalid start cluster (i_pos 0, start f8000000)
FAT-fs (loop3): error, fat_get_cluster: invalid start cluster (i_pos 0, start f8000000)
FAT-fs (loop3): error, fat_get_cluster: invalid start cluster (i_pos 0, start f8000000)
FAT-fs (loop1): error, fat_get_cluster: invalid start cluster (i_pos 0, start f8000000)
audit: type=1800 audit(1654151174.132:5): pid=12108 uid=0 auid=4294967295 ses=4294967295 op="collect_data" cause="failed" comm="syz-executor.3" name="/" dev="loop3" ino=18 res=0
FAT-fs (loop1): error, fat_get_cluster: invalid start cluster (i_pos 0, start f8000000)
FAT-fs (loop1): error, fat_get_cluster: invalid start cluster (i_pos 0, start f8000000)
FAT-fs (loop1): error, fat_get_cluster: invalid start cluster (i_pos 0, start f8000000)
FAT-fs (loop1): error, fat_get_cluster: invalid start cluster (i_pos 0, start f8000000)
FAT-fs (loop1): error, fat_get_cluster: invalid start cluster (i_pos 0, start f8000000)
FAT-fs (loop1): error, fat_get_cluster: invalid start cluster (i_pos 0, start f8000000)
FAT-fs (loop1): error, fat_get_cluster: invalid start cluster (i_pos 0, start f8000000)
FAT-fs (loop1): error, fat_get_cluster: invalid start cluster (i_pos 0, start f8000000)
FAT-fs (loop1): error, fat_get_cluster: invalid start cluster (i_pos 0, start f8000000)
audit: type=1800 audit(1654151174.272:6): pid=12120 uid=0 auid=4294967295 ses=4294967295 op="collect_data" cause="failed" comm="syz-executor.1" name="/" dev="loop1" ino=19 res=0
omfs: Invalid superblock (0)
audit: type=1804 audit(1654151174.392:7): pid=12165 uid=0 auid=4294967295 ses=4294967295 op="invalid_pcr" cause="open_writers" comm="syz-executor.1" name="/root/syzkaller-testdir4283422462/syzkaller.vOoXPf/52/bus" dev="sda1" ino=14138 res=1
audit: type=1804 audit(1654151174.392:8): pid=12165 uid=0 auid=4294967295 ses=4294967295 op="invalid_pcr" cause="ToMToU" comm="syz-executor.1" name="/root/syzkaller-testdir4283422462/syzkaller.vOoXPf/52/bus" dev="sda1" ino=14138 res=1
audit: type=1804 audit(1654151174.392:9): pid=12165 uid=0 auid=4294967295 ses=4294967295 op="invalid_pcr" cause="ToMToU" comm="syz-executor.1" name="/root/syzkaller-testdir4283422462/syzkaller.vOoXPf/52/bus" dev="sda1" ino=14138 res=1
netlink: 40 bytes leftover after parsing attributes in process `syz-executor.3'.
BTRFS: device fsid 10728f38-6baa-48ac-8c08-0b0b5bf8fd92 
print_req_error: I/O error, dev loop2, sector 0
devid 1 transid 7 /dev/loop5
isofs_fill_super: get root inode failed
audit: type=1804 audit(1654151174.392:10): pid=12165 uid=0 auid=4294967295 ses=4294967295 op="invalid_pcr" cause="ToMToU" comm="syz-executor.1" name="/root/syzkaller-testdir4283422462/syzkaller.vOoXPf/52/bus" dev="sda1" ino=14138 res=1
netlink: 4 bytes leftover after parsing attributes in process `syz-executor.2'.
BTRFS error (device loop5): unsupported checksum algorithm 2
BTRFS error (device loop5): superblock checksum mismatch
device batadv1 entered promiscuous mode
8021q: adding VLAN 0 to HW filter on device batadv1
audit: type=1804 audit(1654151174.662:11): pid=12224 uid=0 auid=4294967295 ses=4294967295 op="invalid_pcr" cause="open_writers" comm="syz-executor.1" name="/root/syzkaller-testdir4283422462/syzkaller.vOoXPf/54/bus" dev="sda1" ino=14108 res=1
BTRFS error (device loop5): open_ctree failed
netlink: 40 bytes leftover after parsing attributes in process `syz-executor.3'.
netlink: 4 bytes leftover after parsing attributes in process `syz-executor.2'.
audit: type=1804 audit(1654151174.662:12): pid=12225 uid=0 auid=4294967295 ses=4294967295 op="invalid_pcr" cause="ToMToU" comm="syz-executor.1" name="/root/syzkaller-testdir4283422462/syzkaller.vOoXPf/54/bus" dev="sda1" ino=14108 res=1
audit: type=1804 audit(1654151174.662:13): pid=12226 uid=0 auid=4294967295 ses=4294967295 op="invalid_pcr" cause="ToMToU" comm="syz-executor.1" name="/root/syzkaller-testdir4283422462/syzkaller.vOoXPf/54/bus" dev="sda1" ino=14108 res=1
device batadv2 entered promiscuous mode
BTRFS error (device loop5): unsupported checksum algorithm 2
BTRFS error (device loop5): superblock checksum mismatch
8021q: adding VLAN 0 to HW filter on device batadv2
IPv6: ADDRCONF(NETDEV_CHANGE): vcan0: link becomes ready
BTRFS error (device loop5): open_ctree failed
IPv6: ADDRCONF(NETDEV_CHANGE): vxcan0: link becomes ready
IPv6: ADDRCONF(NETDEV_CHANGE): vxcan1: link becomes ready
netlink: 40 bytes leftover after parsing attributes in process `syz-executor.3'.
netlink: 4 bytes leftover after parsing attributes in process `syz-executor.2'.
device batadv3 entered promiscuous mode
BTRFS error (device loop5): unsupported checksum algorithm 2
8021q: adding VLAN 0 to HW filter on device batadv3
BTRFS error (device loop5): superblock checksum mismatch
IPv6: ADDRCONF(NETDEV_CHANGE): vcan0: link becomes ready
IPv6: ADDRCONF(NETDEV_CHANGE): vxcan0: link becomes ready
BTRFS error (device loop5): open_ctree failed
IPv6: ADDRCONF(NETDEV_CHANGE): vxcan1: link becomes ready
IPv6: ADDRCONF(NETDEV_CHANGE): vcan0: link becomes ready
IPv6: ADDRCONF(NETDEV_CHANGE): vxcan0: link becomes ready
IPv6: ADDRCONF(NETDEV_CHANGE): vxcan1: link becomes ready
ISO 9660 Extensions: Microsoft Joliet Level 3
ntfs: volume version 3.1.
overlayfs: unrecognized mount option "nfs_export=off" or missing value
ISO 9660 Extensions: Microsoft Joliet Level 3
ISO 9660 Extensions: Microsoft Joliet Level 3
ntfs: volume version 3.1.
overlayfs: unrecognized mount option "nfs_export=off" or missing value
overlayfs: unrecognized mount option "nfs_export=off" or missing value
ntfs: volume version 3.1.
overlayfs: unrecognized mount option "nfs_export=off" or missing value
TCP: request_sock_TCPv6: Possible SYN flooding on port 20002. Sending cookies.  Check SNMP counters.
BTRFS: device fsid f61a6f51-b276-4a52-81db-d7190d9a34a8 devid 1 transid 7 /dev/loop4
BFS-fs: bfs_fill_super(): No BFS filesystem on loop3 (magic=16e30000)
BTRFS error (device loop4): unsupported checksum algorithm 2
BTRFS error (device loop4): superblock checksum mismatch
BFS-fs: bfs_fill_super(): No BFS filesystem on loop3 (magic=16e30000)
BTRFS error (device loop4): open_ctree failed
ntfs: volume version 3.1.
BTRFS error (device loop4): unsupported checksum algorithm 2
BTRFS error (device loop4): superblock checksum mismatch
BTRFS error (device loop4): open_ctree failed
print_req_error: I/O error, dev loop0, sector 0
BTRFS error (device loop4): unsupported checksum algorithm 2
BTRFS error (device loop4): superblock checksum mismatch
BTRFS error (device loop4): open_ctree failed
print_req_error: I/O error, dev loop1, sector 0
TCP: request_sock_TCPv6: Possible SYN flooding on port 20002. Sending cookies.  Check SNMP counters.
TCP: request_sock_TCPv6: Possible SYN flooding on port 20002. Sending cookies.  Check SNMP counters.
print_req_error: I/O error, dev loop1, sector 0
TCP: request_sock_TCPv6: Possible SYN flooding on port 20002. Sending cookies.  Check SNMP counters.
TCP: request_sock_TCPv6: Possible SYN flooding on port 20002. Sending cookies.  Check SNMP counters.
TCP: request_sock_TCPv6: Possible SYN flooding on port 20002. Sending cookies.  Check SNMP counters.
kauditd_printk_skb: 13 callbacks suppressed
audit: type=1804 audit(1654151180.612:27): pid=12862 uid=0 auid=4294967295 ses=4294967295 op="invalid_pcr" cause="open_writers" comm="syz-executor.3" name="/root/syzkaller-testdir2764124727/syzkaller.6clGjp/65/bus" dev="sda1" ino=14162 res=1
TCP: request_sock_TCPv6: Possible SYN flooding on port 20002. Sending cookies.  Check SNMP counters.
audit: type=1804 audit(1654151181.062:28): pid=12904 uid=0 auid=4294967295 ses=4294967295 op="invalid_pcr" cause="open_writers" comm="syz-executor.0" name="/root/syzkaller-testdir684959856/syzkaller.yLP5Ud/70/bus" dev="sda1" ino=14161 res=1
TCP: request_sock_TCPv6: Possible SYN flooding on port 20002. Sending cookies.  Check SNMP counters.
audit: type=1804 audit(1654151182.252:29): pid=12944 uid=0 auid=4294967295 ses=4294967295 op="invalid_pcr" cause="open_writers" comm="syz-executor.3" name="/root/syzkaller-testdir2764124727/syzkaller.6clGjp/66/bus" dev="sda1" ino=14166 res=1
TCP: request_sock_TCPv6: Possible SYN flooding on port 20002. Sending cookies.  Check SNMP counters.
print_req_error: I/O error, dev loop0, sector 0
print_req_error: I/O error, dev loop1, sector 0
print_req_error: I/O error, dev loop5, sector 0
print_req_error: I/O error, dev loop5, sector 0
print_req_error: I/O error, dev loop0, sector 0
print_req_error: I/O error, dev loop1, sector 0
print_req_error: I/O error, dev loop3, sector 0