[ 56.241194][ T51] bridge0: port 1(bridge_slave_0) entered disabled state [ 56.263634][ T51] device veth1_macvtap left promiscuous mode [ 56.270068][ T51] device veth0_macvtap left promiscuous mode [ 56.276318][ T51] device veth1_vlan left promiscuous mode [ 56.282344][ T51] device veth0_vlan left promiscuous mode [ 56.448877][ T51] team0 (unregistering): Port device team_slave_1 removed [ 56.460955][ T51] team0 (unregistering): Port device team_slave_0 removed [ 56.476827][ T51] bond0 (unregistering): (slave bond_slave_1): Releasing backup interface [ 56.491217][ T51] bond0 (unregistering): (slave bond_slave_0): Releasing backup interface [ 56.540720][ T51] bond0 (unregistering): Released all slaves Warning: Permanently added '10.128.10.38' (ECDSA) to the list of known hosts. 2022/09/18 03:31:42 ignoring optional flag "sandboxArg"="0" 2022/09/18 03:31:42 parsed 1 programs 2022/09/18 03:31:43 executed programs: 0 [ 76.148267][ T3618] Bluetooth: hci0: unexpected cc 0x0c03 length: 249 > 1 [ 76.156425][ T3618] Bluetooth: hci0: unexpected cc 0x1003 length: 249 > 9 [ 76.164905][ T3618] Bluetooth: hci0: unexpected cc 0x1001 length: 249 > 9 [ 76.172840][ T3618] Bluetooth: hci0: unexpected cc 0x0c23 length: 249 > 4 [ 76.181655][ T3618] Bluetooth: hci0: unexpected cc 0x0c25 length: 249 > 3 [ 76.188989][ T3618] Bluetooth: hci0: unexpected cc 0x0c38 length: 249 > 2 [ 76.260803][ T4079] chnl_net:caif_netlink_parms(): no params data found [ 76.298680][ T4079] bridge0: port 1(bridge_slave_0) entered blocking state [ 76.306882][ T4079] bridge0: port 1(bridge_slave_0) entered disabled state [ 76.315251][ T4079] device bridge_slave_0 entered promiscuous mode [ 76.316417][ T14] cfg80211: failed to load regulatory.db [ 76.325824][ T4079] bridge0: port 2(bridge_slave_1) entered blocking state [ 76.334629][ T4079] bridge0: port 2(bridge_slave_1) entered disabled state [ 76.342812][ T4079] device bridge_slave_1 entered promiscuous mode [ 76.374684][ T4079] bond0: (slave bond_slave_0): Enslaving as an active interface with an up link [ 76.388834][ T4079] bond0: (slave bond_slave_1): Enslaving as an active interface with an up link [ 76.421766][ T4079] team0: Port device team_slave_0 added [ 76.431667][ T4079] team0: Port device team_slave_1 added [ 76.458507][ T4079] batman_adv: batadv0: Adding interface: batadv_slave_0 [ 76.467025][ T4079] batman_adv: batadv0: The MTU of interface batadv_slave_0 is too small (1500) to handle the transport of batman-adv packets. Packets going over this interface will be fragmented on layer2 which could impact the performance. Setting the MTU to 1560 would solve the problem. [ 76.495328][ T4079] batman_adv: batadv0: Not using interface batadv_slave_0 (retrying later): interface not active [ 76.508303][ T4079] batman_adv: batadv0: Adding interface: batadv_slave_1 [ 76.515501][ T4079] batman_adv: batadv0: The MTU of interface batadv_slave_1 is too small (1500) to handle the transport of batman-adv packets. Packets going over this interface will be fragmented on layer2 which could impact the performance. Setting the MTU to 1560 would solve the problem. [ 76.541553][ T4079] batman_adv: batadv0: Not using interface batadv_slave_1 (retrying later): interface not active [ 76.576739][ T4079] device hsr_slave_0 entered promiscuous mode [ 76.584460][ T4079] device hsr_slave_1 entered promiscuous mode [ 76.679459][ T4079] bridge0: port 2(bridge_slave_1) entered blocking state [ 76.686645][ T4079] bridge0: port 2(bridge_slave_1) entered forwarding state [ 76.694060][ T4079] bridge0: port 1(bridge_slave_0) entered blocking state [ 76.701343][ T4079] bridge0: port 1(bridge_slave_0) entered forwarding state [ 76.761924][ T4079] 8021q: adding VLAN 0 to HW filter on device bond0 [ 76.778023][ T3627] IPv6: ADDRCONF(NETDEV_CHANGE): veth0: link becomes ready [ 76.789505][ T3627] bridge0: port 1(bridge_slave_0) entered disabled state [ 76.797633][ T3627] bridge0: port 2(bridge_slave_1) entered disabled state [ 76.806138][ T3627] IPv6: ADDRCONF(NETDEV_CHANGE): bond0: link becomes ready [ 76.820195][ T4079] 8021q: adding VLAN 0 to HW filter on device team0 [ 76.833351][ T3627] IPv6: ADDRCONF(NETDEV_CHANGE): bridge_slave_0: link becomes ready [ 76.842162][ T3627] bridge0: port 1(bridge_slave_0) entered blocking state [ 76.849313][ T3627] bridge0: port 1(bridge_slave_0) entered forwarding state [ 76.875087][ T2482] IPv6: ADDRCONF(NETDEV_CHANGE): bridge_slave_1: link becomes ready [ 76.884222][ T2482] bridge0: port 2(bridge_slave_1) entered blocking state [ 76.891300][ T2482] bridge0: port 2(bridge_slave_1) entered forwarding state [ 76.900112][ T2482] IPv6: ADDRCONF(NETDEV_CHANGE): team_slave_0: link becomes ready [ 76.908759][ T2482] IPv6: ADDRCONF(NETDEV_CHANGE): team_slave_1: link becomes ready [ 76.920293][ T22] IPv6: ADDRCONF(NETDEV_CHANGE): team0: link becomes ready [ 76.928308][ T22] IPv6: ADDRCONF(NETDEV_CHANGE): hsr_slave_0: link becomes ready [ 76.940705][ T3629] IPv6: ADDRCONF(NETDEV_CHANGE): hsr_slave_1: link becomes ready [ 76.951584][ T4079] IPv6: ADDRCONF(NETDEV_CHANGE): hsr0: link becomes ready [ 76.969645][ T2482] IPv6: ADDRCONF(NETDEV_CHANGE): vxcan0: link becomes ready [ 76.977194][ T2482] IPv6: ADDRCONF(NETDEV_CHANGE): vxcan1: link becomes ready [ 76.988817][ T4079] 8021q: adding VLAN 0 to HW filter on device batadv0 [ 77.184130][ T142] IPv6: ADDRCONF(NETDEV_CHANGE): veth0_virt_wifi: link becomes ready [ 77.192920][ T142] IPv6: ADDRCONF(NETDEV_CHANGE): veth0_vlan: link becomes ready [ 77.202929][ T142] IPv6: ADDRCONF(NETDEV_CHANGE): vlan0: link becomes ready [ 77.211072][ T142] IPv6: ADDRCONF(NETDEV_CHANGE): vlan1: link becomes ready [ 77.220962][ T4079] device veth0_vlan entered promiscuous mode [ 77.232692][ T4079] device veth1_vlan entered promiscuous mode [ 77.250367][ T142] IPv6: ADDRCONF(NETDEV_CHANGE): macvlan0: link becomes ready [ 77.258947][ T142] IPv6: ADDRCONF(NETDEV_CHANGE): macvlan1: link becomes ready [ 77.268408][ T142] IPv6: ADDRCONF(NETDEV_CHANGE): veth0_macvtap: link becomes ready [ 77.278839][ T4079] device veth0_macvtap entered promiscuous mode [ 77.289940][ T4079] device veth1_macvtap entered promiscuous mode [ 77.305268][ T4079] batman_adv: batadv0: Interface activated: batadv_slave_0 [ 77.312677][ T142] IPv6: ADDRCONF(NETDEV_CHANGE): batadv_slave_0: link becomes ready [ 77.321844][ T142] IPv6: ADDRCONF(NETDEV_CHANGE): veth0_to_batadv: link becomes ready [ 77.332651][ T4079] batman_adv: batadv0: Interface activated: batadv_slave_1 [ 77.341768][ T3629] IPv6: ADDRCONF(NETDEV_CHANGE): batadv_slave_1: link becomes ready [ 77.350658][ T3629] IPv6: ADDRCONF(NETDEV_CHANGE): veth1_to_batadv: link becomes ready [ 78.224449][ T22] Bluetooth: hci0: command 0x0409 tx timeout [ 78.252304][ T4098] [ 78.254655][ T4098] ============================================ [ 78.261359][ T4098] WARNING: possible recursive locking detected [ 78.267592][ T4098] 6.0.0-rc4-syzkaller-00985-g44a8535fb87c #0 Not tainted [ 78.274610][ T4098] -------------------------------------------- [ 78.280828][ T4098] syz-executor.0/4098 is trying to acquire lock: [ 78.287160][ T4098] ffff88807b134130 (sk_lock-AF_BLUETOOTH-BTPROTO_RFCOMM){+.+.}-{0:0}, at: rfcomm_sk_state_change+0x69/0x3a0 [ 78.298863][ T4098] [ 78.298863][ T4098] but task is already holding lock: [ 78.306224][ T4098] ffff88807b134130 (sk_lock-AF_BLUETOOTH-BTPROTO_RFCOMM){+.+.}-{0:0}, at: rfcomm_sock_shutdown+0x52/0x210 [ 78.317528][ T4098] [ 78.317528][ T4098] other info that might help us debug this: [ 78.325570][ T4098] Possible unsafe locking scenario: [ 78.325570][ T4098] [ 78.333274][ T4098] CPU0 [ 78.336589][ T4098] ---- [ 78.340058][ T4098] lock(sk_lock-AF_BLUETOOTH-BTPROTO_RFCOMM); [ 78.346208][ T4098] lock(sk_lock-AF_BLUETOOTH-BTPROTO_RFCOMM); [ 78.352364][ T4098] [ 78.352364][ T4098] *** DEADLOCK *** [ 78.352364][ T4098] [ 78.360500][ T4098] May be due to missing lock nesting notation [ 78.360500][ T4098] [ 78.369042][ T4098] 4 locks held by syz-executor.0/4098: [ 78.374493][ T4098] #0: ffff888074f33210 (&sb->s_type->i_mutex_key#10){+.+.}-{3:3}, at: __sock_release+0x86/0x280 [ 78.385067][ T4098] #1: ffff88807b134130 (sk_lock-AF_BLUETOOTH-BTPROTO_RFCOMM){+.+.}-{0:0}, at: rfcomm_sock_shutdown+0x52/0x210 [ 78.397002][ T4098] #2: ffffffff8da00ac8 (rfcomm_mutex){+.+.}-{3:3}, at: rfcomm_dlc_close+0x2f/0x240 [ 78.406521][ T4098] #3: ffff88801e125528 (&d->lock){+.+.}-{3:3}, at: __rfcomm_dlc_close+0x15d/0x890 [ 78.415854][ T4098] [ 78.415854][ T4098] stack backtrace: [ 78.421756][ T4098] CPU: 0 PID: 4098 Comm: syz-executor.0 Not tainted 6.0.0-rc4-syzkaller-00985-g44a8535fb87c #0 [ 78.432093][ T4098] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 08/26/2022 [ 78.442321][ T4098] Call Trace: [ 78.445600][ T4098] [ 78.448808][ T4098] dump_stack_lvl+0xcd/0x134 [ 78.453489][ T4098] __lock_acquire.cold+0x116/0x3a7 [ 78.458631][ T4098] ? lockdep_hardirqs_on_prepare+0x400/0x400 [ 78.464708][ T4098] lock_acquire+0x1ab/0x570 [ 78.469216][ T4098] ? rfcomm_sk_state_change+0x69/0x3a0 [ 78.474881][ T4098] ? lock_release+0x780/0x780 [ 78.479560][ T4098] ? __rfcomm_dlc_close+0x15d/0x890 [ 78.484762][ T4098] ? mutex_lock_io_nested+0x1190/0x1190 [ 78.490326][ T4098] ? lockdep_hardirqs_on+0x79/0x100 [ 78.495614][ T4098] ? del_timer+0xc5/0x110 [ 78.499952][ T4098] lock_sock_nested+0x36/0xf0 [ 78.504631][ T4098] ? rfcomm_sk_state_change+0x69/0x3a0 [ 78.510374][ T4098] rfcomm_sk_state_change+0x69/0x3a0 [ 78.515756][ T4098] __rfcomm_dlc_close+0x1b1/0x890 [ 78.521135][ T4098] rfcomm_dlc_close+0x1e5/0x240 [ 78.525988][ T4098] ? __sanitizer_cov_trace_switch+0x50/0x90 [ 78.531887][ T4098] __rfcomm_sock_close+0x13c/0x250 [ 78.537004][ T4098] ? lockdep_hardirqs_on+0x79/0x100 [ 78.542289][ T4098] rfcomm_sock_shutdown+0xcc/0x210 [ 78.547418][ T4098] rfcomm_sock_release+0x64/0x140 [ 78.552449][ T4098] __sock_release+0xcd/0x280 [ 78.557139][ T4098] sock_close+0x18/0x20 [ 78.561308][ T4098] __fput+0x277/0x9d0 [ 78.565390][ T4098] ? __sock_release+0x280/0x280 [ 78.570243][ T4098] task_work_run+0xdd/0x1a0 [ 78.574747][ T4098] get_signal+0x1c3/0x2610 [ 78.579165][ T4098] ? rfcomm_sock_connect+0x155/0x450 [ 78.584468][ T4098] ? lock_downgrade+0x6e0/0x6e0 [ 78.589326][ T4098] ? exit_signals+0x8b0/0x8b0 [ 78.594013][ T4098] ? __local_bh_enable_ip+0xa0/0x120 [ 78.599299][ T4098] ? __local_bh_enable_ip+0xa0/0x120 [ 78.604586][ T4098] arch_do_signal_or_restart+0x82/0x2300 [ 78.610233][ T4098] ? task_work_add+0xa4/0x1f0 [ 78.614911][ T4098] ? fput+0xf2/0x190 [ 78.619938][ T4098] ? get_sigframe_size+0x10/0x10 [ 78.624878][ T4098] ? __sys_connect_file+0x190/0x190 [ 78.630175][ T4098] ? exit_to_user_mode_prepare+0x137/0x250 [ 78.635987][ T4098] exit_to_user_mode_prepare+0x15f/0x250 [ 78.641623][ T4098] syscall_exit_to_user_mode+0x19/0x50 [ 78.647095][ T4098] do_syscall_64+0x42/0xb0 [ 78.651527][ T4098] entry_SYSCALL_64_after_hwframe+0x63/0xcd [ 78.657511][ T4098] RIP: 0033:0x4665f9 [ 78.661404][ T4098] 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 bc ff ff ff f7 d8 64 89 01 48 [ 78.681289][ T4098] RSP: 002b:00007f5fda309188 EFLAGS: 00000246 ORIG_RAX: 000000000000002a [ 78.689703][ T4098] RAX: fffffffffffffffc RBX: 000000000056bf80 RCX: 00000000004665f9 [ 78.697674][ T4098] RDX: 0000000000000080 RSI: 0000000020000000 RDI: 0000000000000004 [ 78.705657][ T4098] RBP: 00000000004bfcc4 R08: 0000000000000000 R09: 0000000000000000 [ 78.713644][ T4098] R10: 0000000000000000 R11: 0000000000000246 R12: 000000000056bf80 [ 78.721636][ T4098] R13: 00007fff808b709f R14: 00007f5fda309300 R15: 0000000000022000 [ 78.729655][ T4098] [ 80.303172][ T14] Bluetooth: hci0: command 0x041b tx timeout [ 82.393384][ T14] Bluetooth: hci0: command 0x040f tx timeout [ 84.463235][ T14] Bluetooth: hci0: command 0x0419 tx timeout [ 86.543236][ T14] Bluetooth: hci0: command 0x0405 tx timeout