[ 437.816495][ T10] netdevsim netdevsim0 netdevsim1 (unregistering): unset [1, 0] type 2 family 0 port 6081 - 0 [ 437.880124][ T10] netdevsim netdevsim0 netdevsim0 (unregistering): unset [1, 0] type 2 family 0 port 6081 - 0 [ 438.006243][ T26] kauditd_printk_skb: 1 callbacks suppressed [ 438.006255][ T26] audit: type=1400 audit(1681275125.639:179): avc: denied { read } for pid=4640 comm="dhcpcd" scontext=system_u:system_r:dhcpc_t tcontext=system_u:system_r:dhcpc_t tclass=netlink_kobject_uevent_socket permissive=1 [ 438.082590][ T26] audit: type=1400 audit(1681275125.669:180): avc: denied { search } for pid=4640 comm="dhcpcd" name="/" dev="tmpfs" ino=1 scontext=system_u:system_r:dhcpc_t tcontext=system_u:object_r:tmpfs_t tclass=dir permissive=1 [ 438.148470][ T26] audit: type=1400 audit(1681275125.779:181): avc: denied { read } for pid=6061 comm="dhcpcd-run-hook" name="resolv.conf" dev="tmpfs" ino=1405 scontext=system_u:system_r:dhcpc_t tcontext=system_u:object_r:tmpfs_t tclass=dir permissive=1 [ 438.171654][ T26] audit: type=1400 audit(1681275125.779:182): avc: denied { open } for pid=6061 comm="dhcpcd-run-hook" path="/run/dhcpcd/hook-state/resolv.conf" dev="tmpfs" ino=1405 scontext=system_u:system_r:dhcpc_t tcontext=system_u:object_r:tmpfs_t tclass=dir permissive=1 [ 438.197209][ T26] audit: type=1400 audit(1681275125.779:183): avc: denied { getattr } for pid=6061 comm="dhcpcd-run-hook" path="/run/dhcpcd/hook-state/resolv.conf" dev="tmpfs" ino=1405 scontext=system_u:system_r:dhcpc_t tcontext=system_u:object_r:tmpfs_t tclass=dir permissive=1 [ 438.303216][ T26] audit: type=1400 audit(1681275125.939:184): avc: denied { write } for pid=6060 comm="dhcpcd-run-hook" name="hook-state" dev="tmpfs" ino=1404 scontext=system_u:system_r:dhcpc_t tcontext=system_u:object_r:tmpfs_t tclass=dir permissive=1 [ 438.330934][ T26] audit: type=1400 audit(1681275125.959:185): avc: denied { add_name } for pid=6060 comm="dhcpcd-run-hook" name="resolv.conf.eth1.link" scontext=system_u:system_r:dhcpc_t tcontext=system_u:object_r:tmpfs_t tclass=dir permissive=1 [ 438.398776][ T26] audit: type=1400 audit(1681275126.029:186): avc: denied { remove_name } for pid=6071 comm="rm" name="resolv.conf.eth1.link" dev="tmpfs" ino=3239 scontext=system_u:system_r:dhcpc_t tcontext=system_u:object_r:tmpfs_t tclass=dir permissive=1 [ 438.674678][ T10] hsr_slave_0: left promiscuous mode [ 438.682759][ T10] hsr_slave_1: left promiscuous mode [ 438.690070][ T10] batman_adv: batadv0: Interface deactivated: batadv_slave_0 [ 438.698115][ T10] batman_adv: batadv0: Removing interface: batadv_slave_0 [ 438.708322][ T10] batman_adv: batadv0: Interface deactivated: batadv_slave_1 [ 438.715876][ T10] batman_adv: batadv0: Removing interface: batadv_slave_1 [ 438.725418][ T10] bridge_slave_1: left allmulticast mode [ 438.731478][ T10] bridge_slave_1: left promiscuous mode [ 438.738174][ T10] bridge0: port 2(bridge_slave_1) entered disabled state [ 438.749232][ T10] bridge_slave_0: left allmulticast mode [ 438.755108][ T10] bridge_slave_0: left promiscuous mode [ 438.761338][ T10] bridge0: port 1(bridge_slave_0) entered disabled state [ 438.776926][ T10] veth1_macvtap: left promiscuous mode [ 438.783119][ T10] veth0_macvtap: left promiscuous mode [ 438.788755][ T10] veth1_vlan: left promiscuous mode [ 438.794782][ T10] veth0_vlan: left promiscuous mode [ 439.022428][ T10] team0 (unregistering): Port device team_slave_1 removed [ 439.038364][ T10] team0 (unregistering): Port device team_slave_0 removed [ 439.053719][ T10] bond0 (unregistering): (slave bond_slave_1): Releasing backup interface [ 439.069436][ T10] bond0 (unregistering): (slave bond_slave_0): Releasing backup interface [ 439.141343][ T10] bond0 (unregistering): Released all slaves Warning: Permanently added '10.128.1.77' (ECDSA) to the list of known hosts. [ 443.086519][ T26] audit: type=1400 audit(1681275130.719:187): avc: denied { transition } for pid=6246 comm="sshd" path="/bin/sh" dev="sda1" ino=73 scontext=system_u:system_r:initrc_t tcontext=root:sysadm_r:sysadm_t tclass=process permissive=1 [ 443.193607][ T6247] chnl_net:caif_netlink_parms(): no params data found [ 443.231709][ T6247] bridge0: port 1(bridge_slave_0) entered blocking state [ 443.238857][ T6247] bridge0: port 1(bridge_slave_0) entered disabled state [ 443.246474][ T6247] bridge_slave_0: entered allmulticast mode [ 443.253264][ T6247] bridge_slave_0: entered promiscuous mode [ 443.261291][ T6247] bridge0: port 2(bridge_slave_1) entered blocking state [ 443.268407][ T6247] bridge0: port 2(bridge_slave_1) entered disabled state [ 443.275686][ T6247] bridge_slave_1: entered allmulticast mode [ 443.283091][ T6247] bridge_slave_1: entered promiscuous mode [ 443.302652][ T6247] bond0: (slave bond_slave_0): Enslaving as an active interface with an up link [ 443.314375][ T6247] bond0: (slave bond_slave_1): Enslaving as an active interface with an up link [ 443.336367][ T6247] team0: Port device team_slave_0 added [ 443.343759][ T6247] team0: Port device team_slave_1 added [ 443.362874][ T6247] batman_adv: batadv0: Adding interface: batadv_slave_0 [ 443.369844][ T6247] 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. [ 443.396837][ T6247] batman_adv: batadv0: Not using interface batadv_slave_0 (retrying later): interface not active [ 443.409194][ T6247] batman_adv: batadv0: Adding interface: batadv_slave_1 [ 443.416316][ T6247] 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. [ 443.442628][ T6247] batman_adv: batadv0: Not using interface batadv_slave_1 (retrying later): interface not active [ 443.468363][ T6247] hsr_slave_0: entered promiscuous mode [ 443.474595][ T6247] hsr_slave_1: entered promiscuous mode [ 443.534957][ T6247] bridge0: port 2(bridge_slave_1) entered blocking state [ 443.542101][ T6247] bridge0: port 2(bridge_slave_1) entered forwarding state [ 443.549422][ T6247] bridge0: port 1(bridge_slave_0) entered blocking state [ 443.556676][ T6247] bridge0: port 1(bridge_slave_0) entered forwarding state [ 443.595646][ T6247] 8021q: adding VLAN 0 to HW filter on device bond0 [ 443.608011][ T895] IPv6: ADDRCONF(NETDEV_CHANGE): veth0: link becomes ready [ 443.616706][ T895] bridge0: port 1(bridge_slave_0) entered disabled state [ 443.624724][ T895] bridge0: port 2(bridge_slave_1) entered disabled state [ 443.633085][ T895] IPv6: ADDRCONF(NETDEV_CHANGE): bond0: link becomes ready [ 443.645571][ T6247] 8021q: adding VLAN 0 to HW filter on device team0 [ 443.664204][ T5151] IPv6: ADDRCONF(NETDEV_CHANGE): bridge_slave_0: link becomes ready [ 443.674275][ T5151] bridge0: port 1(bridge_slave_0) entered blocking state [ 443.681426][ T5151] bridge0: port 1(bridge_slave_0) entered forwarding state [ 443.700720][ T895] IPv6: ADDRCONF(NETDEV_CHANGE): bridge_slave_1: link becomes ready [ 443.709429][ T895] bridge0: port 2(bridge_slave_1) entered blocking state [ 443.716691][ T895] bridge0: port 2(bridge_slave_1) entered forwarding state [ 443.725096][ T895] IPv6: ADDRCONF(NETDEV_CHANGE): team_slave_0: link becomes ready [ 443.734293][ T895] IPv6: ADDRCONF(NETDEV_CHANGE): team0: link becomes ready [ 443.745757][ T25] IPv6: ADDRCONF(NETDEV_CHANGE): team_slave_1: link becomes ready [ 443.759106][ T6247] hsr0: Slave A (hsr_slave_0) is not up; please bring it up to get a fully working HSR network [ 443.769977][ T6247] hsr0: Slave B (hsr_slave_1) is not up; please bring it up to get a fully working HSR network [ 443.782655][ T895] IPv6: ADDRCONF(NETDEV_CHANGE): hsr_slave_0: link becomes ready [ 443.792084][ T895] IPv6: ADDRCONF(NETDEV_CHANGE): hsr_slave_1: link becomes ready [ 443.801951][ T895] IPv6: ADDRCONF(NETDEV_CHANGE): hsr0: link becomes ready [ 443.822002][ T6247] 8021q: adding VLAN 0 to HW filter on device batadv0 [ 443.829603][ T895] IPv6: ADDRCONF(NETDEV_CHANGE): vxcan0: link becomes ready [ 443.837306][ T895] IPv6: ADDRCONF(NETDEV_CHANGE): vxcan1: link becomes ready [ 444.150703][ T895] IPv6: ADDRCONF(NETDEV_CHANGE): veth0_virt_wifi: link becomes ready [ 444.163588][ T5151] IPv6: ADDRCONF(NETDEV_CHANGE): veth0_vlan: link becomes ready [ 444.172769][ T5151] IPv6: ADDRCONF(NETDEV_CHANGE): vlan0: link becomes ready [ 444.181626][ T5151] IPv6: ADDRCONF(NETDEV_CHANGE): vlan1: link becomes ready [ 444.192430][ T6247] veth0_vlan: entered promiscuous mode [ 444.202985][ T6247] veth1_vlan: entered promiscuous mode [ 444.219725][ T25] IPv6: ADDRCONF(NETDEV_CHANGE): veth1_macvtap: link becomes ready [ 444.228370][ T25] IPv6: ADDRCONF(NETDEV_CHANGE): veth0_macvtap: link becomes ready [ 444.242243][ T6247] veth0_macvtap: entered promiscuous mode [ 444.250664][ T6247] veth1_macvtap: entered promiscuous mode [ 444.265376][ T6247] batman_adv: batadv0: Interface activated: batadv_slave_0 [ 444.273893][ T895] IPv6: ADDRCONF(NETDEV_CHANGE): macvtap0: link becomes ready [ 444.283200][ T895] IPv6: ADDRCONF(NETDEV_CHANGE): macsec0: link becomes ready [ 444.291484][ T895] IPv6: ADDRCONF(NETDEV_CHANGE): batadv_slave_0: link becomes ready [ 444.299999][ T895] IPv6: ADDRCONF(NETDEV_CHANGE): veth0_to_batadv: link becomes ready [ 444.311640][ T6247] batman_adv: batadv0: Interface activated: batadv_slave_1 [ 444.320334][ T895] IPv6: ADDRCONF(NETDEV_CHANGE): batadv_slave_1: link becomes ready [ 444.328980][ T895] IPv6: ADDRCONF(NETDEV_CHANGE): veth1_to_batadv: link becomes ready [ 445.397456][ T47] Bluetooth: hci0: unexpected cc 0x0c03 length: 249 > 1 [ 445.407842][ T6267] Bluetooth: hci0: unexpected cc 0x1003 length: 249 > 9 [ 445.416941][ T6267] Bluetooth: hci0: unexpected cc 0x1001 length: 249 > 9 [ 445.425660][ T6267] Bluetooth: hci0: unexpected cc 0x0c23 length: 249 > 4 [ 445.433487][ T6267] Bluetooth: hci0: unexpected cc 0x0c25 length: 249 > 3 [ 445.441039][ T6267] Bluetooth: hci0: unexpected cc 0x0c38 length: 249 > 2 [ 445.456048][ T6267] Bluetooth: hci0: unexpected event 0x40 length: 31 > 2 [ 447.470401][ T6267] Bluetooth: hci0: command 0x0405 tx timeout [ 449.550519][ T6267] Bluetooth: hci0: command 0x0409 tx timeout [ 451.630670][ T6267] Bluetooth: hci0: command 0x041b tx timeout [ 453.710776][ T6267] Bluetooth: hci0: command 0x040f tx timeout [ 455.790417][ T6267] Bluetooth: hci0: command 0x0419 tx timeout [ 486.032568][ T898] [ 486.034937][ T898] ====================================================== [ 486.042010][ T898] WARNING: possible circular locking dependency detected [ 486.049116][ T898] 6.3.0-rc6-syzkaller-ge62252bc55b6 #0 Not tainted [ 486.055651][ T898] ------------------------------------------------------ [ 486.062669][ T898] kworker/0:2/898 is trying to acquire lock: [ 486.068637][ T898] ffff88807ca91130 (sk_lock-AF_BLUETOOTH-BTPROTO_SCO){+.+.}-{0:0}, at: sco_sock_timeout+0xd1/0x290 [ 486.079402][ T898] [ 486.079402][ T898] but task is already holding lock: [ 486.086768][ T898] ffffc900042e7da8 ((work_completion)(&(&conn->timeout_work)->work)){+.+.}-{0:0}, at: process_one_work+0x8ae/0x15c0 [ 486.099095][ T898] [ 486.099095][ T898] which lock already depends on the new lock. [ 486.099095][ T898] [ 486.109574][ T898] [ 486.109574][ T898] the existing dependency chain (in reverse order) is: [ 486.118578][ T898] [ 486.118578][ T898] -> #3 ((work_completion)(&(&conn->timeout_work)->work)){+.+.}-{0:0}: [ 486.129260][ T898] __flush_work+0x109/0xb60 [ 486.134299][ T898] __cancel_work_timer+0x3f9/0x570 [ 486.140041][ T898] sco_conn_del+0x1b5/0x2b0 [ 486.146292][ T898] sco_disconn_cfm+0x75/0xb0 [ 486.152280][ T898] hci_conn_hash_flush+0x126/0x260 [ 486.157988][ T898] hci_dev_close_sync+0x5fb/0x1200 [ 486.163777][ T898] hci_dev_do_close+0x31/0x70 [ 486.169022][ T898] hci_unregister_dev+0x1ce/0x580 [ 486.174577][ T898] vhci_release+0x80/0xf0 [ 486.179521][ T898] __fput+0x27c/0xa90 [ 486.184120][ T898] task_work_run+0x16f/0x270 [ 486.189239][ T898] do_exit+0xad3/0x2960 [ 486.193923][ T898] do_group_exit+0xd4/0x2a0 [ 486.199050][ T898] get_signal+0x2315/0x25b0 [ 486.204073][ T898] arch_do_signal_or_restart+0x79/0x5c0 [ 486.210142][ T898] exit_to_user_mode_prepare+0x11f/0x240 [ 486.216364][ T898] syscall_exit_to_user_mode+0x1d/0x50 [ 486.222361][ T898] do_syscall_64+0x46/0xb0 [ 486.227369][ T898] entry_SYSCALL_64_after_hwframe+0x63/0xcd [ 486.233837][ T898] [ 486.233837][ T898] -> #2 (hci_cb_list_lock){+.+.}-{3:3}: [ 486.241657][ T898] __mutex_lock+0x12f/0x1350 [ 486.246815][ T898] hci_remote_features_evt+0x4d8/0xa50 [ 486.252940][ T898] hci_event_packet+0x956/0xfd0 [ 486.258324][ T898] hci_rx_work+0xaeb/0x1340 [ 486.263394][ T898] process_one_work+0x991/0x15c0 [ 486.268959][ T898] worker_thread+0x669/0x1090 [ 486.274164][ T898] kthread+0x2e8/0x3a0 [ 486.278757][ T898] ret_from_fork+0x1f/0x30 [ 486.283742][ T898] [ 486.283742][ T898] -> #1 (&hdev->lock){+.+.}-{3:3}: [ 486.291135][ T898] __mutex_lock+0x12f/0x1350 [ 486.296276][ T898] sco_sock_connect+0x1ea/0xa60 [ 486.301682][ T898] __sys_connect_file+0x153/0x1a0 [ 486.307341][ T898] __sys_connect+0x165/0x1a0 [ 486.312478][ T898] __x64_sys_connect+0x73/0xb0 [ 486.317952][ T898] do_syscall_64+0x39/0xb0 [ 486.322898][ T898] entry_SYSCALL_64_after_hwframe+0x63/0xcd [ 486.329405][ T898] [ 486.329405][ T898] -> #0 (sk_lock-AF_BLUETOOTH-BTPROTO_SCO){+.+.}-{0:0}: [ 486.338528][ T898] __lock_acquire+0x2ec7/0x5d40 [ 486.343988][ T898] lock_acquire+0x1af/0x520 [ 486.349012][ T898] lock_sock_nested+0x3a/0xf0 [ 486.354212][ T898] sco_sock_timeout+0xd1/0x290 [ 486.359505][ T898] process_one_work+0x991/0x15c0 [ 486.365137][ T898] worker_thread+0x669/0x1090 [ 486.370338][ T898] kthread+0x2e8/0x3a0 [ 486.374931][ T898] ret_from_fork+0x1f/0x30 [ 486.379885][ T898] [ 486.379885][ T898] other info that might help us debug this: [ 486.379885][ T898] [ 486.390205][ T898] Chain exists of: [ 486.390205][ T898] sk_lock-AF_BLUETOOTH-BTPROTO_SCO --> hci_cb_list_lock --> (work_completion)(&(&conn->timeout_work)->work) [ 486.390205][ T898] [ 486.407926][ T898] Possible unsafe locking scenario: [ 486.407926][ T898] [ 486.415367][ T898] CPU0 CPU1 [ 486.420722][ T898] ---- ---- [ 486.426086][ T898] lock((work_completion)(&(&conn->timeout_work)->work)); [ 486.433278][ T898] lock(hci_cb_list_lock); [ 486.440313][ T898] lock((work_completion)(&(&conn->timeout_work)->work)); [ 486.450036][ T898] lock(sk_lock-AF_BLUETOOTH-BTPROTO_SCO); [ 486.455972][ T898] [ 486.455972][ T898] *** DEADLOCK *** [ 486.455972][ T898] [ 486.464116][ T898] 2 locks held by kworker/0:2/898: [ 486.469234][ T898] #0: ffff888012469d38 ((wq_completion)events){+.+.}-{0:0}, at: process_one_work+0x87a/0x15c0 [ 486.479621][ T898] #1: ffffc900042e7da8 ((work_completion)(&(&conn->timeout_work)->work)){+.+.}-{0:0}, at: process_one_work+0x8ae/0x15c0 [ 486.492483][ T898] [ 486.492483][ T898] stack backtrace: [ 486.498420][ T898] CPU: 0 PID: 898 Comm: kworker/0:2 Not tainted 6.3.0-rc6-syzkaller-ge62252bc55b6 #0 [ 486.507998][ T898] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 03/30/2023 [ 486.518147][ T898] Workqueue: events sco_sock_timeout [ 486.523448][ T898] Call Trace: [ 486.526723][ T898] [ 486.529650][ T898] dump_stack_lvl+0xd9/0x150 [ 486.534292][ T898] check_noncircular+0x25f/0x2e0 [ 486.539242][ T898] ? print_circular_bug+0x5c0/0x5c0 [ 486.544439][ T898] ? __pv_queued_spin_lock_slowpath+0x268/0xb50 [ 486.550747][ T898] ? save_trace+0x3f/0xb20 [ 486.555262][ T898] __lock_acquire+0x2ec7/0x5d40 [ 486.560115][ T898] ? lockdep_hardirqs_on_prepare+0x410/0x410 [ 486.566182][ T898] ? lockdep_hardirqs_on_prepare+0x410/0x410 [ 486.572161][ T898] ? lockdep_hardirqs_on_prepare+0x410/0x410 [ 486.578139][ T898] ? lock_downgrade+0x690/0x690 [ 486.582986][ T898] lock_acquire+0x1af/0x520 [ 486.587486][ T898] ? sco_sock_timeout+0xd1/0x290 [ 486.592445][ T898] ? lock_release+0x670/0x670 [ 486.597120][ T898] ? sco_sock_timeout+0xbd/0x290 [ 486.602143][ T898] ? lock_downgrade+0x690/0x690 [ 486.607076][ T898] ? do_raw_spin_lock+0x124/0x2b0 [ 486.612105][ T898] ? spin_bug+0x1c0/0x1c0 [ 486.616448][ T898] lock_sock_nested+0x3a/0xf0 [ 486.621131][ T898] ? sco_sock_timeout+0xd1/0x290 [ 486.626074][ T898] sco_sock_timeout+0xd1/0x290 [ 486.630936][ T898] process_one_work+0x991/0x15c0 [ 486.635879][ T898] ? pwq_dec_nr_in_flight+0x2a0/0x2a0 [ 486.641270][ T898] ? spin_bug+0x1c0/0x1c0 [ 486.645687][ T898] ? _raw_spin_lock_irq+0x45/0x50 [ 486.650741][ T898] worker_thread+0x669/0x1090 [ 486.655421][ T898] ? process_one_work+0x15c0/0x15c0 [ 486.660625][ T898] kthread+0x2e8/0x3a0 [ 486.664698][ T898] ? kthread_complete_and_exit+0x40/0x40 [ 486.670353][ T898] ret_from_fork+0x1f/0x30 [ 486.674787][ T898]