IPv6: ADDRCONF(NETDEV_UP): veth1_macvtap: link is not ready IPv6: ADDRCONF(NETDEV_CHANGE): veth1_macvtap: link becomes ready IPv6: ADDRCONF(NETDEV_CHANGE): veth0_macvtap: link becomes ready device veth0_macvtap entered promiscuous mode ====================================================== WARNING: possible circular locking dependency detected 4.14.290-syzkaller #0 Not tainted ------------------------------------------------------ IPv6: ADDRCONF(NETDEV_UP): macvtap0: link is not ready kworker/u4:0/5 is trying to acquire lock: ((&(&cp->cp_send_w)->work)){+.+.}, at: [] flush_work+0x88/0x770 kernel/workqueue.c:2887 but task is already holding lock: (k-sk_lock-AF_INET){+.+.}, at: [] lock_sock include/net/sock.h:1473 [inline] (k-sk_lock-AF_INET){+.+.}, at: [] rds_tcp_reset_callbacks+0x181/0x450 net/rds/tcp.c:165 which lock already depends on the new lock. the existing dependency chain (in reverse order) is: -> #1 (k-sk_lock-AF_INET){+.+.}: lock_sock_nested+0xb7/0x100 net/core/sock.c:2813 lock_sock include/net/sock.h:1473 [inline] do_tcp_setsockopt.constprop.0+0xfb/0x1c10 net/ipv4/tcp.c:2564 device veth1_macvtap entered promiscuous mode tcp_setsockopt net/ipv4/tcp.c:2832 [inline] tcp_setsockopt+0xa7/0xc0 net/ipv4/tcp.c:2824 kernel_setsockopt+0xfb/0x1b0 net/socket.c:3396 rds_tcp_cork net/rds/tcp_send.c:43 [inline] rds_tcp_xmit_path_prepare+0xaf/0xe0 net/rds/tcp_send.c:50 rds_send_xmit+0x1ae/0x1c00 net/rds/send.c:187 rds_send_worker+0x6d/0x240 net/rds/threads.c:189 IPv6: ADDRCONF(NETDEV_UP): macsec0: link is not ready process_one_work+0x793/0x14a0 kernel/workqueue.c:2117 IPv6: ADDRCONF(NETDEV_UP): veth0_to_batadv: link is not ready 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 -> #0 ((&(&cp->cp_send_w)->work)){+.+.} IPv6: ADDRCONF(NETDEV_UP): veth1_to_batadv: link is not ready : batman_adv: The newly added mac address (aa:aa:aa:aa:aa:3d) already exists on: batadv_slave_0 lock_acquire+0x170/0x3f0 kernel/locking/lockdep.c:3998 batman_adv: It is strongly recommended to keep mac addresses unique to avoid problems! flush_work+0xad/0x770 kernel/workqueue.c:2890 batman_adv: The newly added mac address (aa:aa:aa:aa:aa:3d) already exists on: batadv_slave_0 __cancel_work_timer+0x321/0x460 kernel/workqueue.c:2965 batman_adv: It is strongly recommended to keep mac addresses unique to avoid problems! rds_tcp_reset_callbacks+0x18d/0x450 net/rds/tcp.c:167 batman_adv: The newly added mac address (aa:aa:aa:aa:aa:3d) already exists on: batadv_slave_0 rds_tcp_accept_one+0x61a/0x8b0 net/rds/tcp_listen.c:194 batman_adv: It is strongly recommended to keep mac addresses unique to avoid problems! rds_tcp_accept_worker+0x4d/0x70 net/rds/tcp.c:407 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 batman_adv: The newly added mac address (aa:aa:aa:aa:aa:3d) already exists on: batadv_slave_0 other info that might help us debug this: batman_adv: It is strongly recommended to keep mac addresses unique to avoid problems! Possible unsafe locking scenario: CPU0 CPU1 ---- ---- lock(k-sk_lock-AF_INET); lock((&(&cp->cp_send_w)->work)); lock(k-sk_lock-AF_INET); lock((&(&cp->cp_send_w)->work)); batman_adv: The newly added mac address (aa:aa:aa:aa:aa:3d) already exists on: batadv_slave_0 *** DEADLOCK *** 4 locks held by kworker/u4:0/5: #0: ("%s""krdsd"){+.+.}, at: [] process_one_work+0x6b0/0x14a0 kernel/workqueue.c:2088 #1: ((&rtn->rds_tcp_accept_w)){+.+.}, at: [] process_one_work+0x6e6/0x14a0 kernel/workqueue.c:2092 #2: (&tc->t_conn_path_lock){+.+.}, at: [] rds_tcp_accept_one+0x502/0x8b0 net/rds/tcp_listen.c:186 #3: batman_adv: It is strongly recommended to keep mac addresses unique to avoid problems! (k-sk_lock-AF_INET){+.+.}, at: [] lock_sock include/net/sock.h:1473 [inline] (k-sk_lock-AF_INET){+.+.}, at: [] rds_tcp_reset_callbacks+0x181/0x450 net/rds/tcp.c:165 stack backtrace: CPU: 1 PID: 5 Comm: kworker/u4:0 Not tainted 4.14.290-syzkaller #0 Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 07/22/2022 Workqueue: krdsd rds_tcp_accept_worker IPv6: ADDRCONF(NETDEV_UP): batadv_slave_0: link is not ready 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 batman_adv: batadv0: Interface activated: batadv_slave_0 lock_acquire+0x170/0x3f0 kernel/locking/lockdep.c:3998 batman_adv: The newly added mac address (aa:aa:aa:aa:aa:3e) already exists on: batadv_slave_1 batman_adv: It is strongly recommended to keep mac addresses unique to avoid problems! flush_work+0xad/0x770 kernel/workqueue.c:2890 batman_adv: The newly added mac address (aa:aa:aa:aa:aa:3e) already exists on: batadv_slave_1 __cancel_work_timer+0x321/0x460 kernel/workqueue.c:2965 batman_adv: It is strongly recommended to keep mac addresses unique to avoid problems! rds_tcp_reset_callbacks+0x18d/0x450 net/rds/tcp.c:167 batman_adv: The newly added mac address (aa:aa:aa:aa:aa:3e) already exists on: batadv_slave_1 rds_tcp_accept_one+0x61a/0x8b0 net/rds/tcp_listen.c:194 rds_tcp_accept_worker+0x4d/0x70 net/rds/tcp.c:407 batman_adv: It is strongly recommended to keep mac addresses unique to avoid problems! process_one_work+0x793/0x14a0 kernel/workqueue.c:2117 batman_adv: The newly added mac address (aa:aa:aa:aa:aa:3e) already exists on: batadv_slave_1 worker_thread+0x5cc/0xff0 kernel/workqueue.c:2251 kthread+0x30d/0x420 kernel/kthread.c:232 batman_adv: It is strongly recommended to keep mac addresses unique to avoid problems! ret_from_fork+0x24/0x30 arch/x86/entry/entry_64.S:404 Bluetooth: hci2 command 0x0419 tx timeout batman_adv: The newly added mac address (aa:aa:aa:aa:aa:3e) already exists on: batadv_slave_1 Bluetooth: hci4 command 0x0419 tx timeout batman_adv: It is strongly recommended to keep mac addresses unique to avoid problems! Bluetooth: hci1 command 0x0419 tx timeout IPv6: ADDRCONF(NETDEV_UP): batadv_slave_1: link is not ready Bluetooth: hci3 command 0x0419 tx timeout batman_adv: batadv0: Interface activated: batadv_slave_1 Bluetooth: hci5 command 0x0419 tx timeout IPv6: ADDRCONF(NETDEV_CHANGE): macvtap0: link becomes ready Bluetooth: hci0 command 0x0419 tx timeout IPv6: ADDRCONF(NETDEV_CHANGE): macsec0: link becomes ready IPv6: ADDRCONF(NETDEV_CHANGE): batadv_slave_0: link becomes ready IPv6: ADDRCONF(NETDEV_CHANGE): veth0_to_batadv: link becomes ready IPv6: ADDRCONF(NETDEV_CHANGE): batadv_slave_1: link becomes ready IPv6: ADDRCONF(NETDEV_CHANGE): veth1_to_batadv: link becomes ready process 'syz-executor.0' launched '/dev/fd/4' with NULL argv: empty string added IPVS: ftp: loaded support on port[0] = 21 hrtimer: interrupt took 33238 ns syz-executor.5 (9562): drop_caches: 1 syz-executor.5 (9562): drop_caches: 1 hub 9-0:1.0: USB hub found hub 9-0:1.0: 8 ports detected MINIX-fs: bad superblock or unable to read bitmaps MINIX-fs: bad superblock or unable to read bitmaps print_req_error: I/O error, dev loop1, sector 0 Buffer I/O error on dev loop1, logical block 0, async page read hub 9-0:1.0: USB hub found hub 9-0:1.0: 8 ports detected MINIX-fs: bad superblock or unable to read bitmaps MINIX-fs: bad superblock or unable to read bitmaps hub 9-0:1.0: USB hub found hub 9-0:1.0: 8 ports detected print_req_error: I/O error, dev loop1, sector 0 Buffer I/O error on dev loop1, logical block 0, async page read cramfs: Error -3 while decompressing! cramfs: ffffffff8bf3eff8(27)->ffff88808ed44000(4096) cramfs: Error -3 while decompressing! cramfs: ffffffff8bf3eff8(27)->ffff88808ed44000(4096) audit: type=1800 audit(1660864367.184:2): pid=9858 uid=0 auid=4294967295 ses=4294967295 op="collect_data" cause="failed" comm="syz-executor.1" name="file0" dev="loop1" ino=244 res=0 syz-executor.1 (9842) used greatest stack depth: 24256 bytes left audit: type=1800 audit(1660864367.324:3): pid=9892 uid=0 auid=4294967295 ses=4294967295 op="collect_data" cause="failed(directio)" comm="syz-executor.4" name="bus" dev="sda1" ino=13950 res=0 ptrace attach of "/root/syz-executor.4 exec"[8011] was attempted by "/root/syz-executor.4 exec"[9892] cramfs: Error -3 while decompressing! cramfs: ffffffff8bf42ff8(27)->ffff88808bb75000(4096) cramfs: Error -3 while decompressing! cramfs: ffffffff8bf3eff8(27)->ffff88808e20e000(4096) cramfs: Error -3 while decompressing! cramfs: ffffffff8bf42ff8(27)->ffff88808bb75000(4096) cramfs: Error -3 while decompressing! cramfs: ffffffff8bf3eff8(27)->ffff88808e20e000(4096) audit: type=1800 audit(1660864367.574:4): pid=9893 uid=0 auid=4294967295 ses=4294967295 op="collect_data" cause="failed" comm="syz-executor.1" name="file0" dev="loop1" ino=244 res=0 audit: type=1800 audit(1660864367.574:5): pid=9894 uid=0 auid=4294967295 ses=4294967295 op="collect_data" cause="failed" comm="syz-executor.3" name="file0" dev="loop3" ino=244 res=0 audit: type=1800 audit(1660864367.714:6): pid=9950 uid=0 auid=4294967295 ses=4294967295 op="collect_data" cause="failed(directio)" comm="syz-executor.2" name="bus" dev="sda1" ino=13947 res=0 ptrace attach of "/root/syz-executor.4 exec"[8011] was attempted by "/root/syz-executor.4 exec"[9954] ptrace attach of "/root/syz-executor.2 exec"[8012] was attempted by "/root/syz-executor.2 exec"[9950] cramfs: Error -3 while decompressing! audit: type=1800 audit(1660864367.724:7): pid=9954 uid=0 auid=4294967295 ses=4294967295 op="collect_data" cause="failed(directio)" comm="syz-executor.4" name="bus" dev="sda1" ino=13948 res=0 cramfs: ffffffff8bf3eff8(27)->ffff88808ed39000(4096) cramfs: Error -3 while decompressing! cramfs: ffffffff8bf3eff8(27)->ffff88808ed39000(4096) cramfs: Error -3 while decompressing! cramfs: ffffffff8bf42ff8(27)->ffff888061097000(4096) audit: type=1800 audit(1660864367.894:8): pid=9939 uid=0 auid=4294967295 ses=4294967295 op="collect_data" cause="failed" comm="syz-executor.3" name="file0" dev="loop3" ino=244 res=0 cramfs: Error -3 while decompressing! cramfs: ffffffff8bf42ff8(27)->ffff888061097000(4096) audit: type=1800 audit(1660864367.944:9): pid=9948 uid=0 auid=4294967295 ses=4294967295 op="collect_data" cause="failed" comm="syz-executor.1" name="file0" dev="loop1" ino=244 res=0 audit: type=1800 audit(1660864368.094:10): pid=9994 uid=0 auid=4294967295 ses=4294967295 op="collect_data" cause="failed(directio)" comm="syz-executor.2" name="bus" dev="sda1" ino=13963 res=0 ptrace attach of "/root/syz-executor.2 exec"[8012] was attempted by "/root/syz-executor.2 exec"[9994] ptrace attach of "/root/syz-executor.4 exec"[8011] was attempted by "/root/syz-executor.4 exec"[9999] cramfs: Error -3 while decompressing! cramfs: ffffffff8bf42ff8(27)->ffff888095f3a000(4096) audit: type=1800 audit(1660864368.124:11): pid=9999 uid=0 auid=4294967295 ses=4294967295 op="collect_data" cause="failed(directio)" comm="syz-executor.4" name="bus" dev="sda1" ino=13964 res=0 cramfs: Error -3 while decompressing! cramfs: ffffffff8bf3eff8(27)->ffff88808ef1b000(4096) cramfs: Error -3 while decompressing! cramfs: ffffffff8bf42ff8(27)->ffff888095f3a000(4096) cramfs: Error -3 while decompressing! cramfs: ffffffff8bf3eff8(27)->ffff88808ef1b000(4096) bridge0: port 2(bridge_slave_1) entered disabled state bridge0: port 1(bridge_slave_0) entered disabled state Cannot find add_set index 0 as target device bridge_slave_1 left promiscuous mode bridge0: port 2(bridge_slave_1) entered disabled state ptrace attach of "/root/syz-executor.4 exec"[8011] was attempted by "/root/syz-executor.4 exec"[10045] ptrace attach of "/root/syz-executor.2 exec"[8012] was attempted by "/root/syz-executor.2 exec"[10044] device bridge_slave_0 left promiscuous mode bridge0: port 1(bridge_slave_0) entered disabled state EXT4-fs error (device sda1): mb_free_blocks:1464: group 5, inode 13937: block 164960:freeing already freed block (bit 1120); block bitmap corrupt. EXT4-fs error (device sda1): ext4_mb_generate_buddy:754: group 5, block bitmap and bg descriptor inconsistent: 18872 vs 18873 free clusters EXT4-fs (sda1): pa ffff888060011ce8: logic 0, phys. 164960, len 32 EXT4-fs error (device sda1): ext4_mb_release_inode_pa:3901: group 5, free 16, pa_free 15 EXT4-fs error (device sda1): mb_free_blocks:1464: group 8, inode 13961: block 268288:freeing already freed block (bit 6144); block bitmap corrupt. EXT4-fs error (device sda1): ext4_mb_generate_buddy:754: group 8, block bitmap and bg descriptor inconsistent: 26593 vs 26594 free clusters EXT4-fs (sda1): pa ffff888060011b38: logic 0, phys. 268288, len 32 EXT4-fs error (device sda1): ext4_mb_release_inode_pa:3901: group 8, free 16, pa_free 15 syz-executor.4 (10056) used greatest stack depth: 23888 bytes left EXT4-fs error (device sda1): mb_free_blocks:1464: group 9, inode 13962: block 294944:freeing already freed block (bit 32); block bitmap corrupt. EXT4-fs error (device sda1): ext4_mb_generate_buddy:754: group 9, block bitmap and bg descriptor inconsistent: 32735 vs 32736 free clusters EXT4-fs (sda1): pa ffff8880580c6510: logic 0, phys. 294944, len 32 EXT4-fs error (device sda1): ext4_mb_release_inode_pa:3901: group 9, free 16, pa_free 15 bridge0: port 2(bridge_slave_1) entered disabled state bridge0: port 1(bridge_slave_0) entered disabled state device bridge_slave_1 left promiscuous mode EXT4-fs error (device sda1): mb_free_blocks:1464: group 12, inode 13975: block 397312:freeing already freed block (bit 4096); block bitmap corrupt. EXT4-fs (sda1): pa ffff8880580c6360: logic 0, phys. 397312, len 32 bridge0: port 2(bridge_slave_1) entered disabled state device bridge_slave_0 left promiscuous mode EXT4-fs (sda1): pa ffff888060011988: logic 0, phys. 425984, len 32 bridge0: port 1(bridge_slave_0) entered disabled state EXT4-fs (sda1): pa ffff8880580c61b0: logic 0, phys. 483328, len 32 EXT4-fs (sda1): pa ffff8880600117d8: logic 0, phys. 227328, len 32 EXT4-fs (sda1): pa ffff888060011628: logic 0, phys. 333856, len 32 EXT4-fs (sda1): pa ffff8880580c6000: logic 0, phys. 333824, len 32 EXT4-fs (sda1): pa ffff8880581ece00: logic 0, phys. 362496, len 32 netlink: 24 bytes leftover after parsing attributes in process `syz-executor.2'. netlink: 24 bytes leftover after parsing attributes in process `syz-executor.2'. netlink: 24 bytes leftover after parsing attributes in process `syz-executor.2'. netlink: 24 bytes leftover after parsing attributes in process `syz-executor.2'.