DUID 00:04:99:cf:64:81:91:3a:f8:b3:88:a8:0e:05:2a:78:8a:13 forked to background, child pid 4656 [ 52.640512][ T4657] 8021q: adding VLAN 0 to HW filter on device bond0 [ 52.665991][ T4657] eql: remember to turn off Van-Jacobson compression on your slave devices Starting sshd: OK syzkaller Warning: Permanently added '10.128.10.7' (ECDSA) to the list of known hosts. 2023/02/24 14:04:29 fuzzer started 2023/02/24 14:04:30 connecting to host at 10.128.0.169:42943 2023/02/24 14:04:30 checking machine... 2023/02/24 14:04:30 checking revisions... 2023/02/24 14:04:30 testing simple program... syzkaller login: [ 74.743632][ T5081] cgroup: Unknown subsys name 'net' [ 74.894231][ T5081] cgroup: Unknown subsys name 'rlimit' [ 75.007541][ T5074] memfd_create() without MFD_EXEC nor MFD_NOEXEC_SEAL, pid=5074 'syz-fuzzer' [ 75.178186][ T5086] Bluetooth: hci0: unexpected cc 0x0c03 length: 249 > 1 [ 75.186697][ T5086] Bluetooth: hci0: unexpected cc 0x1003 length: 249 > 9 [ 75.194536][ T5086] Bluetooth: hci0: unexpected cc 0x1001 length: 249 > 9 [ 75.203438][ T5086] Bluetooth: hci0: unexpected cc 0x0c23 length: 249 > 4 [ 75.211604][ T5086] Bluetooth: hci0: unexpected cc 0x0c25 length: 249 > 3 [ 75.219494][ T5086] Bluetooth: hci0: unexpected cc 0x0c38 length: 249 > 2 [ 75.381634][ T5083] chnl_net:caif_netlink_parms(): no params data found [ 75.442529][ T5083] bridge0: port 1(bridge_slave_0) entered blocking state [ 75.450527][ T5083] bridge0: port 1(bridge_slave_0) entered disabled state [ 75.458953][ T5083] bridge_slave_0: entered allmulticast mode [ 75.467345][ T5083] bridge_slave_0: entered promiscuous mode [ 75.477452][ T5083] bridge0: port 2(bridge_slave_1) entered blocking state [ 75.484813][ T5083] bridge0: port 2(bridge_slave_1) entered disabled state [ 75.492959][ T5083] bridge_slave_1: entered allmulticast mode [ 75.499903][ T5083] bridge_slave_1: entered promiscuous mode [ 75.527289][ T5083] bond0: (slave bond_slave_0): Enslaving as an active interface with an up link [ 75.539130][ T5083] bond0: (slave bond_slave_1): Enslaving as an active interface with an up link [ 75.568593][ T5083] team0: Port device team_slave_0 added [ 75.577619][ T5083] team0: Port device team_slave_1 added [ 75.601837][ T5083] batman_adv: batadv0: Adding interface: batadv_slave_0 [ 75.608883][ T5083] 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. [ 75.635282][ T5083] batman_adv: batadv0: Not using interface batadv_slave_0 (retrying later): interface not active [ 75.649070][ T5083] batman_adv: batadv0: Adding interface: batadv_slave_1 [ 75.656175][ T5083] 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. [ 75.682436][ T5083] batman_adv: batadv0: Not using interface batadv_slave_1 (retrying later): interface not active [ 75.721820][ T5083] hsr_slave_0: entered promiscuous mode [ 75.728544][ T5083] hsr_slave_1: entered promiscuous mode [ 75.850137][ T5083] netdevsim netdevsim0 netdevsim0: renamed from eth0 [ 75.862755][ T5083] netdevsim netdevsim0 netdevsim1: renamed from eth1 [ 75.872753][ T5083] netdevsim netdevsim0 netdevsim2: renamed from eth2 [ 75.883977][ T5083] netdevsim netdevsim0 netdevsim3: renamed from eth3 [ 75.914094][ T5083] bridge0: port 2(bridge_slave_1) entered blocking state [ 75.921719][ T5083] bridge0: port 2(bridge_slave_1) entered forwarding state [ 75.929923][ T5083] bridge0: port 1(bridge_slave_0) entered blocking state [ 75.937137][ T5083] bridge0: port 1(bridge_slave_0) entered forwarding state [ 76.001164][ T5083] 8021q: adding VLAN 0 to HW filter on device bond0 [ 76.015947][ T898] IPv6: ADDRCONF(NETDEV_CHANGE): veth0: link becomes ready [ 76.028029][ T898] bridge0: port 1(bridge_slave_0) entered disabled state [ 76.037162][ T898] bridge0: port 2(bridge_slave_1) entered disabled state [ 76.048069][ T898] IPv6: ADDRCONF(NETDEV_CHANGE): bond0: link becomes ready [ 76.063371][ T5083] 8021q: adding VLAN 0 to HW filter on device team0 [ 76.077432][ T22] IPv6: ADDRCONF(NETDEV_CHANGE): bridge_slave_0: link becomes ready [ 76.086696][ T22] bridge0: port 1(bridge_slave_0) entered blocking state [ 76.093832][ T22] bridge0: port 1(bridge_slave_0) entered forwarding state [ 76.115830][ T22] IPv6: ADDRCONF(NETDEV_CHANGE): bridge_slave_1: link becomes ready [ 76.124480][ T22] bridge0: port 2(bridge_slave_1) entered blocking state [ 76.131630][ T22] bridge0: port 2(bridge_slave_1) entered forwarding state [ 76.144702][ T898] IPv6: ADDRCONF(NETDEV_CHANGE): team_slave_0: link becomes ready [ 76.153815][ T898] IPv6: ADDRCONF(NETDEV_CHANGE): team0: link becomes ready [ 76.171257][ T5094] IPv6: ADDRCONF(NETDEV_CHANGE): team_slave_1: link becomes ready [ 76.189022][ T5093] IPv6: ADDRCONF(NETDEV_CHANGE): hsr_slave_0: link becomes ready [ 76.201831][ T5094] IPv6: ADDRCONF(NETDEV_CHANGE): hsr_slave_1: link becomes ready [ 76.213444][ T5083] IPv6: ADDRCONF(NETDEV_CHANGE): hsr0: link becomes ready [ 76.414060][ T5083] 8021q: adding VLAN 0 to HW filter on device batadv0 [ 76.422276][ T22] IPv6: ADDRCONF(NETDEV_CHANGE): vxcan1: link becomes ready [ 76.430945][ T22] IPv6: ADDRCONF(NETDEV_CHANGE): vxcan0: link becomes ready [ 76.456727][ T5093] IPv6: ADDRCONF(NETDEV_CHANGE): veth1_virt_wifi: link becomes ready [ 76.466256][ T5093] IPv6: ADDRCONF(NETDEV_CHANGE): veth0_virt_wifi: link becomes ready [ 76.484707][ T5094] IPv6: ADDRCONF(NETDEV_CHANGE): veth1_vlan: link becomes ready [ 76.493970][ T5094] IPv6: ADDRCONF(NETDEV_CHANGE): veth0_vlan: link becomes ready [ 76.504063][ T5094] IPv6: ADDRCONF(NETDEV_CHANGE): vlan0: link becomes ready [ 76.513342][ T5094] IPv6: ADDRCONF(NETDEV_CHANGE): vlan1: link becomes ready [ 76.523111][ T5083] veth0_vlan: entered promiscuous mode [ 76.536456][ T5083] veth1_vlan: entered promiscuous mode [ 76.560261][ T5094] IPv6: ADDRCONF(NETDEV_CHANGE): macvlan0: link becomes ready [ 76.568613][ T5094] IPv6: ADDRCONF(NETDEV_CHANGE): macvlan1: link becomes ready [ 76.578007][ T5094] IPv6: ADDRCONF(NETDEV_CHANGE): veth1_macvtap: link becomes ready [ 76.587280][ T5094] IPv6: ADDRCONF(NETDEV_CHANGE): veth0_macvtap: link becomes ready [ 76.599050][ T5083] veth0_macvtap: entered promiscuous mode [ 76.609249][ T5083] veth1_macvtap: entered promiscuous mode [ 76.630347][ T5083] batman_adv: batadv0: Interface activated: batadv_slave_0 [ 76.638201][ T5094] IPv6: ADDRCONF(NETDEV_CHANGE): macvtap0: link becomes ready [ 76.647085][ T5094] IPv6: ADDRCONF(NETDEV_CHANGE): macsec0: link becomes ready [ 76.656204][ T5094] IPv6: ADDRCONF(NETDEV_CHANGE): batadv_slave_0: link becomes ready [ 76.665620][ T5094] IPv6: ADDRCONF(NETDEV_CHANGE): veth0_to_batadv: link becomes ready [ 76.679459][ T5083] batman_adv: batadv0: Interface activated: batadv_slave_1 [ 76.686908][ T5094] IPv6: ADDRCONF(NETDEV_CHANGE): batadv_slave_1: link becomes ready [ 76.696279][ T5094] IPv6: ADDRCONF(NETDEV_CHANGE): veth1_to_batadv: link becomes ready [ 76.709879][ T5083] netdevsim netdevsim0 netdevsim0: set [1, 0] type 2 family 0 port 6081 - 0 [ 76.720552][ T5083] netdevsim netdevsim0 netdevsim1: set [1, 0] type 2 family 0 port 6081 - 0 [ 76.730608][ T5083] netdevsim netdevsim0 netdevsim2: set [1, 0] type 2 family 0 port 6081 - 0 [ 76.740832][ T5083] netdevsim netdevsim0 netdevsim3: set [1, 0] type 2 family 0 port 6081 - 0 [ 76.830810][ T56] wlan0: Created IBSS using preconfigured BSSID 50:50:50:50:50:50 [ 76.850745][ T56] wlan0: Creating new IBSS network, BSSID 50:50:50:50:50:50 [ 76.866386][ T5093] IPv6: ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready [ 76.876589][ T56] wlan1: Created IBSS using preconfigured BSSID 50:50:50:50:50:50 [ 76.885042][ T56] wlan1: Creating new IBSS network, BSSID 50:50:50:50:50:50 [ 76.894263][ T5093] IPv6: ADDRCONF(NETDEV_CHANGE): wlan1: link becomes ready 2023/02/24 14:04:32 building call list... [ 77.128857][ T5083] [ 77.131248][ T5083] ====================================================== [ 77.138286][ T5083] WARNING: possible circular locking dependency detected [ 77.145341][ T5083] 6.2.0-next-20230224-syzkaller #0 Not tainted [ 77.151534][ T5083] ------------------------------------------------------ [ 77.158574][ T5083] syz-executor.0/5083 is trying to acquire lock: [ 77.164967][ T5083] ffff88807b5cc880 ((work_completion)(&hdev->cmd_sync_work)){+.+.}-{0:0}, at: __flush_work+0xdd/0xb60 [ 77.176023][ T5083] [ 77.176023][ T5083] but task is already holding lock: [ 77.183412][ T5083] ffff88807b5cc920 (&hdev->cmd_sync_work_lock){+.+.}-{3:3}, at: hci_cmd_sync_clear+0x3c/0x2a0 [ 77.193770][ T5083] [ 77.193770][ T5083] which lock already depends on the new lock. [ 77.193770][ T5083] [ 77.204191][ T5083] [ 77.204191][ T5083] the existing dependency chain (in reverse order) is: [ 77.213415][ T5083] [ 77.213415][ T5083] -> #1 (&hdev->cmd_sync_work_lock){+.+.}-{3:3}: [ 77.221980][ T5083] __mutex_lock+0x12f/0x1350 [ 77.227161][ T5083] hci_cmd_sync_work+0x26f/0x450 [ 77.232661][ T5083] process_one_work+0x9bf/0x1820 [ 77.238151][ T5083] worker_thread+0x669/0x1090 [ 77.243406][ T5083] kthread+0x2e8/0x3a0 [ 77.248018][ T5083] ret_from_fork+0x1f/0x30 [ 77.252991][ T5083] [ 77.252991][ T5083] -> #0 ((work_completion)(&hdev->cmd_sync_work)){+.+.}-{0:0}: [ 77.262742][ T5083] __lock_acquire+0x2ec7/0x5d40 [ 77.268150][ T5083] lock_acquire.part.0+0x11a/0x370 [ 77.273827][ T5083] __flush_work+0x109/0xb60 [ 77.278901][ T5083] __cancel_work_timer+0x3f9/0x570 [ 77.284570][ T5083] hci_cmd_sync_clear+0x48/0x2a0 [ 77.290145][ T5083] hci_unregister_dev+0x16b/0x580 [ 77.296850][ T5083] vhci_release+0x80/0xf0 [ 77.301729][ T5083] __fput+0x27c/0xa90 [ 77.306254][ T5083] task_work_run+0x16f/0x270 [ 77.311406][ T5083] do_exit+0xb42/0x2b60 [ 77.316097][ T5083] do_group_exit+0xd4/0x2a0 [ 77.321144][ T5083] get_signal+0x2315/0x25b0 [ 77.326190][ T5083] arch_do_signal_or_restart+0x79/0x5c0 [ 77.332280][ T5083] exit_to_user_mode_prepare+0x15f/0x250 [ 77.338458][ T5083] syscall_exit_to_user_mode+0x1d/0x50 [ 77.344502][ T5083] do_syscall_64+0x46/0xb0 [ 77.349472][ T5083] entry_SYSCALL_64_after_hwframe+0x63/0xcd [ 77.355901][ T5083] [ 77.355901][ T5083] other info that might help us debug this: [ 77.355901][ T5083] [ 77.366143][ T5083] Possible unsafe locking scenario: [ 77.366143][ T5083] [ 77.373604][ T5083] CPU0 CPU1 [ 77.378974][ T5083] ---- ---- [ 77.384348][ T5083] lock(&hdev->cmd_sync_work_lock); [ 77.389907][ T5083] lock((work_completion)(&hdev->cmd_sync_work)); [ 77.399037][ T5083] lock(&hdev->cmd_sync_work_lock); [ 77.406855][ T5083] lock((work_completion)(&hdev->cmd_sync_work)); [ 77.413369][ T5083] [ 77.413369][ T5083] *** DEADLOCK *** [ 77.413369][ T5083] [ 77.421693][ T5083] 1 lock held by syz-executor.0/5083: [ 77.427089][ T5083] #0: ffff88807b5cc920 (&hdev->cmd_sync_work_lock){+.+.}-{3:3}, at: hci_cmd_sync_clear+0x3c/0x2a0 [ 77.437841][ T5083] [ 77.437841][ T5083] stack backtrace: [ 77.443741][ T5083] CPU: 1 PID: 5083 Comm: syz-executor.0 Not tainted 6.2.0-next-20230224-syzkaller #0 [ 77.453215][ T5083] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 02/16/2023 [ 77.463412][ T5083] Call Trace: [ 77.466820][ T5083] [ 77.469882][ T5083] dump_stack_lvl+0xd9/0x150 [ 77.474522][ T5083] check_noncircular+0x25f/0x2e0 [ 77.479597][ T5083] ? register_lock_class+0xbe/0x1120 [ 77.484924][ T5083] ? print_circular_bug+0x5c0/0x5c0 [ 77.490165][ T5083] ? is_dynamic_key.part.0+0x1f0/0x1f0 [ 77.495662][ T5083] __lock_acquire+0x2ec7/0x5d40 [ 77.500553][ T5083] ? lockdep_hardirqs_on_prepare+0x410/0x410 [ 77.506562][ T5083] ? lockdep_hardirqs_on_prepare+0x410/0x410 [ 77.512570][ T5083] ? lockdep_hardirqs_on_prepare+0x410/0x410 [ 77.518574][ T5083] ? find_held_lock+0x2d/0x110 [ 77.523364][ T5083] lock_acquire.part.0+0x11a/0x370 [ 77.528508][ T5083] ? __flush_work+0xdd/0xb60 [ 77.533131][ T5083] ? lock_release+0x780/0x780 [ 77.537854][ T5083] ? __flush_work+0xdd/0xb60 [ 77.542746][ T5083] ? rcu_read_lock_sched_held+0x3e/0x70 [ 77.548319][ T5083] ? trace_lock_acquire+0x1f1/0x2b0 [ 77.553547][ T5083] ? __flush_work+0xdd/0xb60 [ 77.558163][ T5083] ? lock_acquire+0x32/0xc0 [ 77.562693][ T5083] ? __flush_work+0xdd/0xb60 [ 77.567312][ T5083] __flush_work+0x109/0xb60 [ 77.571850][ T5083] ? __flush_work+0xdd/0xb60 [ 77.576476][ T5083] ? _raw_spin_unlock_irq+0x23/0x50 [ 77.581807][ T5083] ? print_usage_bug.part.0+0x660/0x660 [ 77.587378][ T5083] ? mod_delayed_work_on+0x220/0x220 [ 77.592737][ T5083] ? lock_release+0x780/0x780 [ 77.597437][ T5083] ? hci_cmd_sync_clear+0x3c/0x2a0 [ 77.602586][ T5083] ? mark_held_locks+0x9f/0xe0 [ 77.607468][ T5083] __cancel_work_timer+0x3f9/0x570 [ 77.612609][ T5083] ? work_on_cpu_safe+0xe0/0xe0 [ 77.617514][ T5083] ? __cancel_work_timer+0x339/0x570 [ 77.622847][ T5083] ? work_on_cpu_safe+0xe0/0xe0 [ 77.627727][ T5083] ? hci_unregister_dev+0x157/0x580 [ 77.632947][ T5083] ? lock_downgrade+0x690/0x690 [ 77.637820][ T5083] ? do_raw_write_lock+0x11e/0x3b0 [ 77.642965][ T5083] hci_cmd_sync_clear+0x48/0x2a0 [ 77.647935][ T5083] hci_unregister_dev+0x16b/0x580 [ 77.652980][ T5083] vhci_release+0x80/0xf0 [ 77.657348][ T5083] __fput+0x27c/0xa90 [ 77.661355][ T5083] ? vhci_close_dev+0x50/0x50 [ 77.666067][ T5083] task_work_run+0x16f/0x270 [ 77.670709][ T5083] ? task_work_cancel+0x30/0x30 [ 77.675602][ T5083] do_exit+0xb42/0x2b60 [ 77.679776][ T5083] ? find_held_lock+0x2d/0x110 [ 77.684561][ T5083] ? get_signal+0x89d/0x25b0 [ 77.689172][ T5083] ? mm_update_next_owner+0x7b0/0x7b0 [ 77.694940][ T5083] do_group_exit+0xd4/0x2a0 [ 77.699462][ T5083] get_signal+0x2315/0x25b0 [ 77.703990][ T5083] ? vfs_write+0x49e/0xe10 [ 77.710604][ T5083] ? exit_signals+0x910/0x910 [ 77.715305][ T5083] ? kernel_write+0x670/0x670 [ 77.720011][ T5083] arch_do_signal_or_restart+0x79/0x5c0 [ 77.725587][ T5083] ? get_sigframe_size+0x10/0x10 [ 77.730556][ T5083] exit_to_user_mode_prepare+0x15f/0x250 [ 77.736236][ T5083] syscall_exit_to_user_mode+0x1d/0x50 [ 77.741733][ T5083] do_syscall_64+0x46/0xb0 [ 77.746175][ T5083] entry_SYSCALL_64_after_hwframe+0x63/0xcd [ 77.752100][ T5083] RIP: 0033:0x7f87b283de7f [ 77.756530][ T5083] Code: Unable to access opcode bytes at 0x7f87b283de55. [ 77.763550][ T5083] RSP: 002b:00007ffe29e53d70 EFLAGS: 00000293 ORIG_RAX: 0000000000000001 [ 77.771979][ T5083] RAX: 000000000000000c RBX: 0000000000000003 RCX: 00007f87b283de7f executing program [ 77.779962][ T5083] RDX: 000000000000000c RSI: 00007ffe29e53e60 RDI: 00000000000000f8 [ 77.787948][ T5083] RBP: 00007ffe29e53dfc R08: 0000000000000000 R09: 00007ffe29f5a080 [ 77.795937][ T5083] R10: 0000000000000000 R11: 0000000000000293 R12: 0000000000000032 [ 77.803921][ T5083] R13: 00000000000126b0 R14: 0000000000000003 R15: 00007ffe29e53e60 [ 77.811917][ T5083] [ 77.815286][ T4398] Bluetooth: hci0: command 0x0409 tx timeout [ 80.378787][ T5078] damon-dbgfs: DAMON debugfs interface is deprecated, so users should move to DAMON_SYSFS. If you cannot, please report your usecase to damon@lists.linux.dev and linux-mm@kvack.org. executing program [ 80.521395][ T5078] can: request_module (can-proto-0) failed. [ 80.557049][ T5078] can: request_module (can-proto-0) failed. [ 80.591959][ T5078] can: request_module (can-proto-0) failed. [ 81.742112][ T11] netdevsim netdevsim0 netdevsim3 (unregistering): unset [1, 0] type 2 family 0 port 6081 - 0 [ 81.783689][ T5078] base_sock_release(ffff888077da0c00) sk=ffff888029016000