Warning: Permanently added '10.128.1.140' (ECDSA) to the list of known hosts. 2022/12/07 23:25:58 ignoring optional flag "sandboxArg"="0" 2022/12/07 23:25:58 parsed 1 programs 2022/12/07 23:25:59 executed programs: 0 [ 73.439959][ T4077] cgroup: Unknown subsys name 'net' [ 73.450900][ T4077] cgroup: Unknown subsys name 'rlimit' [ 76.268870][ T7] cfg80211: failed to load regulatory.db [ 76.667757][ T3642] Bluetooth: hci0: Opcode 0x c03 failed: -110 [ 80.827760][ T3642] Bluetooth: hci0: Opcode 0x c03 failed: -110 [ 82.914373][ T48] Bluetooth: hci0: unexpected cc 0x0c03 length: 249 > 1 [ 82.922522][ T48] Bluetooth: hci0: unexpected cc 0x1003 length: 249 > 9 [ 82.930612][ T48] Bluetooth: hci0: unexpected cc 0x1001 length: 249 > 9 [ 82.939170][ T48] Bluetooth: hci0: unexpected cc 0x0c23 length: 249 > 4 [ 82.947157][ T48] Bluetooth: hci0: unexpected cc 0x0c25 length: 249 > 3 [ 82.955350][ T48] Bluetooth: hci0: unexpected cc 0x0c38 length: 249 > 2 [ 82.964371][ T4099] Bluetooth: hci0: HCI_REQ-0x0c1a [ 83.029484][ T4099] chnl_net:caif_netlink_parms(): no params data found [ 83.064587][ T4099] bridge0: port 1(bridge_slave_0) entered blocking state [ 83.072457][ T4099] bridge0: port 1(bridge_slave_0) entered disabled state [ 83.080121][ T4099] device bridge_slave_0 entered promiscuous mode [ 83.088282][ T4099] bridge0: port 2(bridge_slave_1) entered blocking state [ 83.095654][ T4099] bridge0: port 2(bridge_slave_1) entered disabled state [ 83.103544][ T4099] device bridge_slave_1 entered promiscuous mode [ 83.122873][ T4099] bond0: (slave bond_slave_0): Enslaving as an active interface with an up link [ 83.133867][ T4099] bond0: (slave bond_slave_1): Enslaving as an active interface with an up link [ 83.156024][ T4099] team0: Port device team_slave_0 added [ 83.163372][ T4099] team0: Port device team_slave_1 added [ 83.179456][ T4099] batman_adv: batadv0: Adding interface: batadv_slave_0 [ 83.186705][ T4099] 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. [ 83.213720][ T4099] batman_adv: batadv0: Not using interface batadv_slave_0 (retrying later): interface not active [ 83.226290][ T4099] batman_adv: batadv0: Adding interface: batadv_slave_1 [ 83.234247][ T4099] 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. [ 83.261709][ T4099] batman_adv: batadv0: Not using interface batadv_slave_1 (retrying later): interface not active [ 83.287479][ T4099] device hsr_slave_0 entered promiscuous mode [ 83.294811][ T4099] device hsr_slave_1 entered promiscuous mode [ 83.350368][ T4099] bridge0: port 2(bridge_slave_1) entered blocking state [ 83.357614][ T4099] bridge0: port 2(bridge_slave_1) entered forwarding state [ 83.365487][ T4099] bridge0: port 1(bridge_slave_0) entered blocking state [ 83.372929][ T4099] bridge0: port 1(bridge_slave_0) entered forwarding state [ 83.411764][ T4099] 8021q: adding VLAN 0 to HW filter on device bond0 [ 83.423454][ T7] IPv6: ADDRCONF(NETDEV_CHANGE): veth0: link becomes ready [ 83.432449][ T7] bridge0: port 1(bridge_slave_0) entered disabled state [ 83.440855][ T7] bridge0: port 2(bridge_slave_1) entered disabled state [ 83.449119][ T7] IPv6: ADDRCONF(NETDEV_CHANGE): bond0: link becomes ready [ 83.462885][ T4099] 8021q: adding VLAN 0 to HW filter on device team0 [ 83.473237][ T7] IPv6: ADDRCONF(NETDEV_CHANGE): bridge_slave_0: link becomes ready [ 83.482151][ T7] bridge0: port 1(bridge_slave_0) entered blocking state [ 83.489654][ T7] bridge0: port 1(bridge_slave_0) entered forwarding state [ 83.509324][ T7] IPv6: ADDRCONF(NETDEV_CHANGE): bridge_slave_1: link becomes ready [ 83.519301][ T7] bridge0: port 2(bridge_slave_1) entered blocking state [ 83.526373][ T7] bridge0: port 2(bridge_slave_1) entered forwarding state [ 83.536294][ T7] IPv6: ADDRCONF(NETDEV_CHANGE): team_slave_0: link becomes ready [ 83.556493][ T4099] hsr0: Slave A (hsr_slave_0) is not up; please bring it up to get a fully working HSR network [ 83.567437][ T4099] hsr0: Slave B (hsr_slave_1) is not up; please bring it up to get a fully working HSR network [ 83.580467][ T3649] IPv6: ADDRCONF(NETDEV_CHANGE): team0: link becomes ready [ 83.589065][ T3649] IPv6: ADDRCONF(NETDEV_CHANGE): team_slave_1: link becomes ready [ 83.597417][ T3649] IPv6: ADDRCONF(NETDEV_CHANGE): hsr_slave_0: link becomes ready [ 83.605766][ T3649] IPv6: ADDRCONF(NETDEV_CHANGE): hsr_slave_1: link becomes ready [ 83.614347][ T3649] IPv6: ADDRCONF(NETDEV_CHANGE): hsr0: link becomes ready [ 83.631245][ T150] IPv6: ADDRCONF(NETDEV_CHANGE): vxcan0: link becomes ready [ 83.639450][ T150] IPv6: ADDRCONF(NETDEV_CHANGE): vxcan1: link becomes ready [ 83.651100][ T4099] 8021q: adding VLAN 0 to HW filter on device batadv0 [ 83.960168][ T33] IPv6: ADDRCONF(NETDEV_CHANGE): veth0_virt_wifi: link becomes ready [ 83.973393][ T4099] device veth0_vlan entered promiscuous mode [ 83.981602][ T33] IPv6: ADDRCONF(NETDEV_CHANGE): veth0_vlan: link becomes ready [ 83.993469][ T4099] device veth1_vlan entered promiscuous mode [ 84.000798][ T33] IPv6: ADDRCONF(NETDEV_CHANGE): macvlan0: link becomes ready [ 84.009442][ T33] IPv6: ADDRCONF(NETDEV_CHANGE): vlan0: link becomes ready [ 84.017043][ T33] IPv6: ADDRCONF(NETDEV_CHANGE): vlan1: link becomes ready [ 84.035968][ T3649] IPv6: ADDRCONF(NETDEV_CHANGE): veth1_macvtap: link becomes ready [ 84.044815][ T3649] IPv6: ADDRCONF(NETDEV_CHANGE): veth0_macvtap: link becomes ready [ 84.058939][ T4099] device veth0_macvtap entered promiscuous mode [ 84.068183][ T4099] device veth1_macvtap entered promiscuous mode [ 84.083510][ T4099] batman_adv: batadv0: Interface activated: batadv_slave_0 [ 84.091565][ T3649] IPv6: ADDRCONF(NETDEV_CHANGE): macvtap0: link becomes ready [ 84.099790][ T3649] IPv6: ADDRCONF(NETDEV_CHANGE): macsec0: link becomes ready [ 84.107850][ T3649] IPv6: ADDRCONF(NETDEV_CHANGE): batadv_slave_0: link becomes ready [ 84.117175][ T3649] IPv6: ADDRCONF(NETDEV_CHANGE): veth0_to_batadv: link becomes ready [ 84.129866][ T4099] batman_adv: batadv0: Interface activated: batadv_slave_1 [ 84.137769][ T7] IPv6: ADDRCONF(NETDEV_CHANGE): batadv_slave_1: link becomes ready [ 84.146443][ T7] IPv6: ADDRCONF(NETDEV_CHANGE): veth1_to_batadv: link becomes ready [ 84.194449][ T11] wlan0: Created IBSS using preconfigured BSSID 50:50:50:50:50:50 [ 84.209725][ T11] wlan0: Creating new IBSS network, BSSID 50:50:50:50:50:50 [ 84.219709][ T14] IPv6: ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready [ 84.231018][ T56] wlan1: Created IBSS using preconfigured BSSID 50:50:50:50:50:50 [ 84.239504][ T56] wlan1: Creating new IBSS network, BSSID 50:50:50:50:50:50 [ 84.248886][ T14] IPv6: ADDRCONF(NETDEV_CHANGE): wlan1: link becomes ready [ 84.988890][ T48] Bluetooth: hci0: command 0x0409 tx timeout [ 85.120615][ T4118] [ 85.122976][ T4118] ====================================================== [ 85.130086][ T4118] WARNING: possible circular locking dependency detected [ 85.137633][ T4118] 6.1.0-rc8-syzkaller-00018-g479174d402bc #0 Not tainted [ 85.144645][ T4118] ------------------------------------------------------ [ 85.151674][ T4118] syz-executor.0/4118 is trying to acquire lock: [ 85.158008][ T4118] ffff888078a26130 (sk_lock-AF_BLUETOOTH-BTPROTO_RFCOMM){+.+.}-{0:0}, at: rfcomm_sk_state_change+0x52/0x2f0 [ 85.169492][ T4118] [ 85.169492][ T4118] but task is already holding lock: [ 85.176913][ T4118] ffff88806fc05d28 (&d->lock){+.+.}-{3:3}, at: __rfcomm_dlc_close+0x276/0x470 [ 85.185866][ T4118] [ 85.185866][ T4118] which lock already depends on the new lock. [ 85.185866][ T4118] [ 85.196341][ T4118] [ 85.196341][ T4118] the existing dependency chain (in reverse order) is: [ 85.205491][ T4118] [ 85.205491][ T4118] -> #2 (&d->lock){+.+.}-{3:3}: [ 85.212621][ T4118] lock_acquire+0x1a7/0x400 [ 85.217638][ T4118] __mutex_lock_common+0x1de/0x26c0 [ 85.223452][ T4118] mutex_lock_nested+0x17/0x20 [ 85.228749][ T4118] __rfcomm_dlc_close+0x276/0x470 [ 85.234709][ T4118] rfcomm_dlc_close+0x10d/0x1c0 [ 85.240112][ T4118] __rfcomm_sock_close+0x101/0x220 [ 85.245842][ T4118] rfcomm_sock_shutdown+0xad/0x230 [ 85.251473][ T4118] rfcomm_sock_release+0x55/0x120 [ 85.257026][ T4118] sock_close+0xd7/0x260 [ 85.261808][ T4118] __fput+0x3ba/0x880 [ 85.266616][ T4118] task_work_run+0x243/0x300 [ 85.271816][ T4118] get_signal+0x1642/0x1810 [ 85.276853][ T4118] arch_do_signal_or_restart+0x8d/0x750 [ 85.282930][ T4118] exit_to_user_mode_loop+0x74/0x160 [ 85.288782][ T4118] exit_to_user_mode_prepare+0xad/0x110 [ 85.294858][ T4118] syscall_exit_to_user_mode+0x2e/0x60 [ 85.300873][ T4118] entry_SYSCALL_64_after_hwframe+0x63/0xcd [ 85.307406][ T4118] [ 85.307406][ T4118] -> #1 (rfcomm_mutex){+.+.}-{3:3}: [ 85.314793][ T4118] lock_acquire+0x1a7/0x400 [ 85.319816][ T4118] __mutex_lock_common+0x1de/0x26c0 [ 85.325540][ T4118] mutex_lock_nested+0x17/0x20 [ 85.330835][ T4118] rfcomm_dlc_open+0x25/0x50 [ 85.335969][ T4118] rfcomm_sock_connect+0x285/0x470 [ 85.341646][ T4118] __sys_connect+0x29b/0x2d0 [ 85.346783][ T4118] __x64_sys_connect+0x76/0x80 [ 85.352116][ T4118] do_syscall_64+0x2b/0x70 [ 85.357068][ T4118] entry_SYSCALL_64_after_hwframe+0x63/0xcd [ 85.363479][ T4118] [ 85.363479][ T4118] -> #0 (sk_lock-AF_BLUETOOTH-BTPROTO_RFCOMM){+.+.}-{0:0}: [ 85.372981][ T4118] validate_chain+0x184a/0x6470 [ 85.378526][ T4118] __lock_acquire+0x1292/0x1f60 [ 85.383912][ T4118] lock_acquire+0x1a7/0x400 [ 85.388957][ T4118] lock_sock_nested+0x44/0xf0 [ 85.394169][ T4118] rfcomm_sk_state_change+0x52/0x2f0 [ 85.400062][ T4118] __rfcomm_dlc_close+0x2bb/0x470 [ 85.405614][ T4118] rfcomm_dlc_close+0x10d/0x1c0 [ 85.411007][ T4118] __rfcomm_sock_close+0x101/0x220 [ 85.416642][ T4118] rfcomm_sock_shutdown+0xad/0x230 [ 85.422378][ T4118] rfcomm_sock_release+0x55/0x120 [ 85.427942][ T4118] sock_close+0xd7/0x260 [ 85.432771][ T4118] __fput+0x3ba/0x880 [ 85.437283][ T4118] task_work_run+0x243/0x300 [ 85.442412][ T4118] get_signal+0x1642/0x1810 [ 85.447440][ T4118] arch_do_signal_or_restart+0x8d/0x750 [ 85.453497][ T4118] exit_to_user_mode_loop+0x74/0x160 [ 85.459291][ T4118] exit_to_user_mode_prepare+0xad/0x110 [ 85.465366][ T4118] syscall_exit_to_user_mode+0x2e/0x60 [ 85.471467][ T4118] entry_SYSCALL_64_after_hwframe+0x63/0xcd [ 85.478178][ T4118] [ 85.478178][ T4118] other info that might help us debug this: [ 85.478178][ T4118] [ 85.489068][ T4118] Chain exists of: [ 85.489068][ T4118] sk_lock-AF_BLUETOOTH-BTPROTO_RFCOMM --> rfcomm_mutex --> &d->lock [ 85.489068][ T4118] [ 85.502982][ T4118] Possible unsafe locking scenario: [ 85.502982][ T4118] [ 85.510425][ T4118] CPU0 CPU1 [ 85.515799][ T4118] ---- ---- [ 85.521150][ T4118] lock(&d->lock); [ 85.525038][ T4118] lock(rfcomm_mutex); [ 85.532156][ T4118] lock(&d->lock); [ 85.538485][ T4118] lock(sk_lock-AF_BLUETOOTH-BTPROTO_RFCOMM); [ 85.544637][ T4118] [ 85.544637][ T4118] *** DEADLOCK *** [ 85.544637][ T4118] [ 85.552868][ T4118] 3 locks held by syz-executor.0/4118: [ 85.558315][ T4118] #0: ffff88806f837410 (&sb->s_type->i_mutex_key#9){+.+.}-{3:3}, at: sock_close+0x93/0x260 [ 85.568409][ T4118] #1: ffffffff8e5df5c8 (rfcomm_mutex){+.+.}-{3:3}, at: rfcomm_dlc_close+0x32/0x1c0 [ 85.577796][ T4118] #2: ffff88806fc05d28 (&d->lock){+.+.}-{3:3}, at: __rfcomm_dlc_close+0x276/0x470 [ 85.587128][ T4118] [ 85.587128][ T4118] stack backtrace: [ 85.593123][ T4118] CPU: 1 PID: 4118 Comm: syz-executor.0 Not tainted 6.1.0-rc8-syzkaller-00018-g479174d402bc #0 [ 85.603549][ T4118] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 10/26/2022 [ 85.613601][ T4118] Call Trace: [ 85.617233][ T4118] [ 85.620165][ T4118] dump_stack_lvl+0x1e3/0x2cb [ 85.624831][ T4118] ? nf_tcp_handle_invalid+0x62e/0x62e [ 85.630301][ T4118] ? print_circular_bug+0x13e/0x1c0 [ 85.635577][ T4118] check_noncircular+0x2f9/0x3b0 [ 85.640676][ T4118] ? add_chain_block+0x850/0x850 [ 85.645740][ T4118] ? lockdep_lock+0x11d/0x2a0 [ 85.650434][ T4118] ? _find_first_zero_bit+0xe8/0x110 [ 85.655785][ T4118] validate_chain+0x184a/0x6470 [ 85.660690][ T4118] ? reacquire_held_locks+0x680/0x680 [ 85.666148][ T4118] ? register_lock_class+0xfe/0x9b0 [ 85.671424][ T4118] ? is_dynamic_key+0x1f0/0x1f0 [ 85.676611][ T4118] ? mark_lock+0x9a/0x350 [ 85.681132][ T4118] ? __lock_acquire+0x1292/0x1f60 [ 85.686255][ T4118] ? mark_lock+0x9a/0x350 [ 85.690611][ T4118] __lock_acquire+0x1292/0x1f60 [ 85.695573][ T4118] lock_acquire+0x1a7/0x400 [ 85.700083][ T4118] ? rfcomm_sk_state_change+0x52/0x2f0 [ 85.705565][ T4118] ? read_lock_is_recursive+0x10/0x10 [ 85.710947][ T4118] ? __mutex_lock_common+0x45d/0x26c0 [ 85.716345][ T4118] ? del_timer+0x340/0x3d0 [ 85.720942][ T4118] ? __rfcomm_dlc_close+0x276/0x470 [ 85.726158][ T4118] ? mutex_lock_io_nested+0x60/0x60 [ 85.731364][ T4118] lock_sock_nested+0x44/0xf0 [ 85.736050][ T4118] ? rfcomm_sk_state_change+0x52/0x2f0 [ 85.741624][ T4118] rfcomm_sk_state_change+0x52/0x2f0 [ 85.747014][ T4118] __rfcomm_dlc_close+0x2bb/0x470 [ 85.752183][ T4118] rfcomm_dlc_close+0x10d/0x1c0 [ 85.757144][ T4118] __rfcomm_sock_close+0x101/0x220 [ 85.762268][ T4118] rfcomm_sock_shutdown+0xad/0x230 [ 85.767505][ T4118] rfcomm_sock_release+0x55/0x120 [ 85.772805][ T4118] sock_close+0xd7/0x260 [ 85.777056][ T4118] ? __fput+0x3b2/0x880 [ 85.781397][ T4118] ? sock_mmap+0x90/0x90 [ 85.785714][ T4118] __fput+0x3ba/0x880 [ 85.789712][ T4118] task_work_run+0x243/0x300 [ 85.794319][ T4118] ? task_work_cancel+0x290/0x290 [ 85.799626][ T4118] get_signal+0x1642/0x1810 [ 85.804124][ T4118] ? kick_process+0xd6/0x140 [ 85.808797][ T4118] ? task_work_add+0x2e6/0x340 [ 85.813657][ T4118] ? rcu_lock_release+0x20/0x20 [ 85.818526][ T4118] ? ptrace_notify+0x340/0x340 [ 85.823314][ T4118] arch_do_signal_or_restart+0x8d/0x750 [ 85.829050][ T4118] ? __sys_connect+0x157/0x2d0 [ 85.833805][ T4118] ? get_sigframe_size+0x10/0x10 [ 85.838729][ T4118] ? exit_to_user_mode_loop+0x42/0x160 [ 85.844186][ T4118] exit_to_user_mode_loop+0x74/0x160 [ 85.849716][ T4118] exit_to_user_mode_prepare+0xad/0x110 [ 85.855269][ T4118] syscall_exit_to_user_mode+0x2e/0x60 [ 85.860713][ T4118] entry_SYSCALL_64_after_hwframe+0x63/0xcd [ 85.866760][ T4118] RIP: 0033:0x7f979c289049 [ 85.871159][ T4118] 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 [ 85.890765][ T4118] RSP: 002b:00007f979d36d168 EFLAGS: 00000246 ORIG_RAX: 000000000000002a [ 85.899164][ T4118] RAX: fffffffffffffffc RBX: 00007f979c39bf60 RCX: 00007f979c289049 [ 85.907117][ T4118] RDX: 0000000000000080 RSI: 0000000020000600 RDI: 0000000000000004 [ 85.915085][ T4118] RBP: 00007f979c2e308d R08: 0000000000000000 R09: 0000000000000000 2022/12/07 23:26:11 executed programs: 1 [ 85.923297][ T4118] R10: 0000000000000000 R11: 0000000000000246 R12: 0000000000000000 [ 85.931253][ T4118] R13: 00007ffe8ef0a90f R14: 00007f979d36d300 R15: 0000000000022000 [ 85.939213][ T4118] [ 87.067810][ T48] Bluetooth: hci0: command 0x041b tx timeout [ 89.147635][ T48] Bluetooth: hci0: command 0x040f tx timeout 2022/12/07 23:26:16 executed programs: 7 [ 91.227663][ T48] Bluetooth: hci0: command 0x0419 tx timeout [ 93.307661][ T48] Bluetooth: hci0: command 0x0405 tx timeout