[ 50.962792][ T50] wlan0: Creating new IBSS network, BSSID 50:50:50:50:50:50 [ 50.985971][ T921] IPv6: ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready [ 50.993837][ T994] wlan1: Created IBSS using preconfigured BSSID 50:50:50:50:50:50 [ 51.005202][ T994] wlan1: Creating new IBSS network, BSSID 50:50:50:50:50:50 [ 51.015333][ T921] IPv6: ADDRCONF(NETDEV_CHANGE): wlan1: link becomes ready [ 51.169066][ T50] netdevsim netdevsim0 netdevsim3 (unregistering): unset [1, 0] type 2 family 0 port 6081 - 0 [ 51.722614][ T6582] can: request_module (can-proto-0) failed. [ 51.735528][ T6582] can: request_module (can-proto-0) failed. [ 51.749816][ T6582] can: request_module (can-proto-0) failed. [ 52.907886][ T25] audit: type=1804 audit(1637210896.827:2): pid=7007 uid=0 auid=4294967295 ses=4294967295 subj==unconfined op=invalid_pcr cause=ToMToU comm="scp" name="/root/syz-executor" dev="sda1" ino=13860 res=1 errno=0 [ 53.919546][ T6600] syz-executor.0 (6600) used greatest stack depth: 22840 bytes left [ 54.001577][ T50] netdevsim netdevsim0 netdevsim2 (unregistering): unset [1, 0] type 2 family 0 port 6081 - 0 [ 56.180713][ T50] netdevsim netdevsim0 netdevsim1 (unregistering): unset [1, 0] type 2 family 0 port 6081 - 0 [ 56.221465][ T50] netdevsim netdevsim0 netdevsim0 (unregistering): unset [1, 0] type 2 family 0 port 6081 - 0 [ 57.174992][ T50] device hsr_slave_0 left promiscuous mode [ 57.184350][ T50] device hsr_slave_1 left promiscuous mode [ 57.192208][ T50] batman_adv: batadv0: Interface deactivated: batadv_slave_0 [ 57.200155][ T50] batman_adv: batadv0: Removing interface: batadv_slave_0 [ 57.213821][ T50] batman_adv: batadv0: Interface deactivated: batadv_slave_1 [ 57.222202][ T50] batman_adv: batadv0: Removing interface: batadv_slave_1 [ 57.231788][ T50] device bridge_slave_1 left promiscuous mode [ 57.239179][ T50] bridge0: port 2(bridge_slave_1) entered disabled state [ 57.251101][ T50] device bridge_slave_0 left promiscuous mode [ 57.257461][ T50] bridge0: port 1(bridge_slave_0) entered disabled state [ 57.272491][ T50] device veth1_macvtap left promiscuous mode [ 57.278784][ T50] device veth0_macvtap left promiscuous mode [ 57.284811][ T50] device veth1_vlan left promiscuous mode [ 57.292099][ T50] device veth0_vlan left promiscuous mode [ 57.397787][ T50] team0 (unregistering): Port device team_slave_1 removed [ 57.411219][ T50] team0 (unregistering): Port device team_slave_0 removed [ 57.423263][ T50] bond0 (unregistering): (slave bond_slave_1): Releasing backup interface [ 57.441082][ T50] bond0 (unregistering): (slave bond_slave_0): Releasing backup interface [ 57.483799][ T50] bond0 (unregistering): Released all slaves Warning: Permanently added '10.128.10.13' (ECDSA) to the list of known hosts. 2021/11/18 04:48:22 parsed 1 programs 2021/11/18 04:48:23 executed programs: 0 [ 61.344479][ T7085] chnl_net:caif_netlink_parms(): no params data found [ 61.512311][ T7085] bridge0: port 1(bridge_slave_0) entered blocking state [ 61.521816][ T7085] bridge0: port 1(bridge_slave_0) entered disabled state [ 61.530552][ T7085] device bridge_slave_0 entered promiscuous mode [ 61.538268][ T7092] chnl_net:caif_netlink_parms(): no params data found [ 61.607883][ T7085] bridge0: port 2(bridge_slave_1) entered blocking state [ 61.620669][ T7085] bridge0: port 2(bridge_slave_1) entered disabled state [ 61.630396][ T7085] device bridge_slave_1 entered promiscuous mode [ 61.740907][ T7090] chnl_net:caif_netlink_parms(): no params data found [ 61.768397][ T7093] chnl_net:caif_netlink_parms(): no params data found [ 61.778687][ T7085] bond0: (slave bond_slave_0): Enslaving as an active interface with an up link [ 61.792973][ T7088] chnl_net:caif_netlink_parms(): no params data found [ 61.822029][ T7085] bond0: (slave bond_slave_1): Enslaving as an active interface with an up link [ 61.935765][ T7092] bridge0: port 1(bridge_slave_0) entered blocking state [ 61.943157][ T7092] bridge0: port 1(bridge_slave_0) entered disabled state [ 61.959895][ T7092] device bridge_slave_0 entered promiscuous mode [ 62.015153][ T7085] team0: Port device team_slave_0 added [ 62.021609][ T7092] bridge0: port 2(bridge_slave_1) entered blocking state [ 62.034141][ T7092] bridge0: port 2(bridge_slave_1) entered disabled state [ 62.043398][ T7092] device bridge_slave_1 entered promiscuous mode [ 62.065209][ T7090] bridge0: port 1(bridge_slave_0) entered blocking state [ 62.072443][ T7090] bridge0: port 1(bridge_slave_0) entered disabled state [ 62.080556][ T7090] device bridge_slave_0 entered promiscuous mode [ 62.100283][ T7085] team0: Port device team_slave_1 added [ 62.116822][ T7090] bridge0: port 2(bridge_slave_1) entered blocking state [ 62.124035][ T7090] bridge0: port 2(bridge_slave_1) entered disabled state [ 62.139337][ T7090] device bridge_slave_1 entered promiscuous mode [ 62.146992][ T7093] bridge0: port 1(bridge_slave_0) entered blocking state [ 62.154136][ T7093] bridge0: port 1(bridge_slave_0) entered disabled state [ 62.162231][ T7093] device bridge_slave_0 entered promiscuous mode [ 62.172885][ T7093] bridge0: port 2(bridge_slave_1) entered blocking state [ 62.181746][ T7093] bridge0: port 2(bridge_slave_1) entered disabled state [ 62.189775][ T7093] device bridge_slave_1 entered promiscuous mode [ 62.198962][ T7092] bond0: (slave bond_slave_0): Enslaving as an active interface with an up link [ 62.246046][ T7092] bond0: (slave bond_slave_1): Enslaving as an active interface with an up link [ 62.323270][ T7085] batman_adv: batadv0: Adding interface: batadv_slave_0 [ 62.330871][ T7085] 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. [ 62.358053][ T7085] batman_adv: batadv0: Not using interface batadv_slave_0 (retrying later): interface not active [ 62.372452][ T7090] bond0: (slave bond_slave_0): Enslaving as an active interface with an up link [ 62.382428][ T7088] bridge0: port 1(bridge_slave_0) entered blocking state [ 62.389734][ T7088] bridge0: port 1(bridge_slave_0) entered disabled state [ 62.399252][ T7088] device bridge_slave_0 entered promiscuous mode [ 62.408275][ T7093] bond0: (slave bond_slave_0): Enslaving as an active interface with an up link [ 62.420306][ T7092] team0: Port device team_slave_0 added [ 62.429889][ T7085] batman_adv: batadv0: Adding interface: batadv_slave_1 [ 62.437182][ T7085] 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. [ 62.464217][ T7085] batman_adv: batadv0: Not using interface batadv_slave_1 (retrying later): interface not active [ 62.477652][ T7090] bond0: (slave bond_slave_1): Enslaving as an active interface with an up link [ 62.501763][ T7088] bridge0: port 2(bridge_slave_1) entered blocking state [ 62.510211][ T7088] bridge0: port 2(bridge_slave_1) entered disabled state [ 62.518500][ T7088] device bridge_slave_1 entered promiscuous mode [ 62.526955][ T7093] bond0: (slave bond_slave_1): Enslaving as an active interface with an up link [ 62.537627][ T7092] team0: Port device team_slave_1 added [ 62.595824][ T7090] team0: Port device team_slave_0 added [ 62.619196][ T7092] batman_adv: batadv0: Adding interface: batadv_slave_0 [ 62.626606][ T7092] 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. [ 62.655278][ T7092] batman_adv: batadv0: Not using interface batadv_slave_0 (retrying later): interface not active [ 62.687061][ T7090] team0: Port device team_slave_1 added [ 62.695904][ T7093] team0: Port device team_slave_0 added [ 62.703007][ T7088] bond0: (slave bond_slave_0): Enslaving as an active interface with an up link [ 62.713301][ T7092] batman_adv: batadv0: Adding interface: batadv_slave_1 [ 62.721955][ T7092] 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. [ 62.749207][ T7092] batman_adv: batadv0: Not using interface batadv_slave_1 (retrying later): interface not active [ 62.762313][ T7085] device hsr_slave_0 entered promiscuous mode [ 62.769790][ T7085] device hsr_slave_1 entered promiscuous mode [ 62.785311][ T7093] team0: Port device team_slave_1 added [ 62.792426][ T7088] bond0: (slave bond_slave_1): Enslaving as an active interface with an up link [ 62.864415][ T7090] batman_adv: batadv0: Adding interface: batadv_slave_0 [ 62.871646][ T7090] 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. [ 62.898681][ T7090] batman_adv: batadv0: Not using interface batadv_slave_0 (retrying later): interface not active [ 62.932982][ T7088] team0: Port device team_slave_0 added [ 62.946841][ T7090] batman_adv: batadv0: Adding interface: batadv_slave_1 [ 62.953963][ T7090] 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. [ 62.965376][ T5] Bluetooth: hci1: command 0x0409 tx timeout [ 62.981626][ T7090] batman_adv: batadv0: Not using interface batadv_slave_1 (retrying later): interface not active [ 63.007817][ T7093] batman_adv: batadv0: Adding interface: batadv_slave_0 [ 63.015698][ T7093] 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. [ 63.041845][ T7093] batman_adv: batadv0: Not using interface batadv_slave_0 (retrying later): interface not active [ 63.052780][ T20] Bluetooth: hci5: command 0x0409 tx timeout [ 63.056545][ T7088] team0: Port device team_slave_1 added [ 63.080455][ T7092] device hsr_slave_0 entered promiscuous mode [ 63.087651][ T7092] device hsr_slave_1 entered promiscuous mode [ 63.095123][ T7092] debugfs: Directory 'hsr0' with parent 'hsr' already present! [ 63.102848][ T7092] Cannot create hsr debugfs directory [ 63.109576][ T7093] batman_adv: batadv0: Adding interface: batadv_slave_1 [ 63.116907][ T7093] 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. [ 63.143713][ T7093] batman_adv: batadv0: Not using interface batadv_slave_1 (retrying later): interface not active [ 63.155670][ T20] Bluetooth: hci2: command 0x0409 tx timeout [ 63.161942][ T20] Bluetooth: hci4: command 0x0409 tx timeout [ 63.169146][ T20] Bluetooth: hci3: command 0x0409 tx timeout [ 63.233499][ T7090] device hsr_slave_0 entered promiscuous mode [ 63.241216][ T7090] device hsr_slave_1 entered promiscuous mode [ 63.250485][ T7090] debugfs: Directory 'hsr0' with parent 'hsr' already present! [ 63.259416][ T7090] Cannot create hsr debugfs directory [ 63.280460][ T7093] device hsr_slave_0 entered promiscuous mode [ 63.289140][ T7093] device hsr_slave_1 entered promiscuous mode [ 63.296070][ T7093] debugfs: Directory 'hsr0' with parent 'hsr' already present! [ 63.303908][ T7093] Cannot create hsr debugfs directory [ 63.316414][ T7088] batman_adv: batadv0: Adding interface: batadv_slave_0 [ 63.323980][ T7088] 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. [ 63.351085][ T7088] batman_adv: batadv0: Not using interface batadv_slave_0 (retrying later): interface not active [ 63.407074][ T7088] batman_adv: batadv0: Adding interface: batadv_slave_1 [ 63.414138][ T7088] 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. [ 63.441602][ T7088] batman_adv: batadv0: Not using interface batadv_slave_1 (retrying later): interface not active [ 63.588348][ T7088] device hsr_slave_0 entered promiscuous mode [ 63.595535][ T7088] device hsr_slave_1 entered promiscuous mode [ 63.602161][ T7088] debugfs: Directory 'hsr0' with parent 'hsr' already present! [ 63.610017][ T7088] Cannot create hsr debugfs directory [ 63.730472][ T7085] netdevsim netdevsim2 netdevsim0: renamed from eth0 [ 63.763015][ T7085] netdevsim netdevsim2 netdevsim1: renamed from eth1 [ 63.772543][ T7085] netdevsim netdevsim2 netdevsim2: renamed from eth2 [ 63.811838][ T7085] netdevsim netdevsim2 netdevsim3: renamed from eth3 [ 63.947051][ T7090] netdevsim netdevsim3 netdevsim0: renamed from eth0 [ 63.979638][ T7093] netdevsim netdevsim4 netdevsim0: renamed from eth0 [ 63.991234][ T7090] netdevsim netdevsim3 netdevsim1: renamed from eth1 [ 64.027176][ T7093] netdevsim netdevsim4 netdevsim1: renamed from eth1 [ 64.043490][ T7093] netdevsim netdevsim4 netdevsim2: renamed from eth2 [ 64.052233][ T7090] netdevsim netdevsim3 netdevsim2: renamed from eth2 [ 64.063615][ T7090] netdevsim netdevsim3 netdevsim3: renamed from eth3 [ 64.081464][ T7085] 8021q: adding VLAN 0 to HW filter on device bond0 [ 64.093803][ T7093] netdevsim netdevsim4 netdevsim3: renamed from eth3 [ 64.114539][ T7088] netdevsim netdevsim1 netdevsim0: renamed from eth0 [ 64.131778][ T7085] 8021q: adding VLAN 0 to HW filter on device team0 [ 64.155811][ T7088] netdevsim netdevsim1 netdevsim1: renamed from eth1 [ 64.168099][ T921] IPv6: ADDRCONF(NETDEV_CHANGE): veth1: link becomes ready [ 64.176162][ T921] IPv6: ADDRCONF(NETDEV_CHANGE): veth0: link becomes ready [ 64.201755][ T7088] netdevsim netdevsim1 netdevsim2: renamed from eth2 [ 64.213296][ T7088] netdevsim netdevsim1 netdevsim3: renamed from eth3 [ 64.226265][ T921] IPv6: ADDRCONF(NETDEV_CHANGE): veth0_to_bridge: link becomes ready [ 64.235085][ T921] IPv6: ADDRCONF(NETDEV_CHANGE): bridge_slave_0: link becomes ready [ 64.243382][ T921] bridge0: port 1(bridge_slave_0) entered blocking state [ 64.250478][ T921] bridge0: port 1(bridge_slave_0) entered forwarding state [ 64.277748][ T7092] 8021q: adding VLAN 0 to HW filter on device bond0 [ 64.301049][ T6923] IPv6: ADDRCONF(NETDEV_CHANGE): bridge0: link becomes ready [ 64.309338][ T6923] IPv6: ADDRCONF(NETDEV_CHANGE): veth1_to_bridge: link becomes ready [ 64.318279][ T6923] IPv6: ADDRCONF(NETDEV_CHANGE): bridge_slave_1: link becomes ready [ 64.326797][ T6923] bridge0: port 2(bridge_slave_1) entered blocking state [ 64.334193][ T6923] bridge0: port 2(bridge_slave_1) entered forwarding state [ 64.341850][ T6923] IPv6: ADDRCONF(NETDEV_CHANGE): veth0_to_bond: link becomes ready [ 64.350532][ T6923] IPv6: ADDRCONF(NETDEV_CHANGE): veth1_to_bond: link becomes ready [ 64.365944][ T921] IPv6: ADDRCONF(NETDEV_CHANGE): veth0_to_team: link becomes ready [ 64.375142][ T921] IPv6: ADDRCONF(NETDEV_CHANGE): team_slave_0: link becomes ready [ 64.384089][ T921] IPv6: ADDRCONF(NETDEV_CHANGE): team0: link becomes ready [ 64.398457][ T7092] 8021q: adding VLAN 0 to HW filter on device team0 [ 64.434170][ T921] IPv6: ADDRCONF(NETDEV_CHANGE): veth1: link becomes ready [ 64.442008][ T921] IPv6: ADDRCONF(NETDEV_CHANGE): veth0: link becomes ready [ 64.450430][ T921] IPv6: ADDRCONF(NETDEV_CHANGE): veth1_to_team: link becomes ready [ 64.459509][ T921] IPv6: ADDRCONF(NETDEV_CHANGE): team_slave_1: link becomes ready [ 64.474097][ T7085] hsr0: Slave A (hsr_slave_0) is not up; please bring it up to get a fully working HSR network [ 64.489848][ T7085] hsr0: Slave B (hsr_slave_1) is not up; please bring it up to get a fully working HSR network [ 64.504624][ T7090] 8021q: adding VLAN 0 to HW filter on device bond0 [ 64.517026][ T921] IPv6: ADDRCONF(NETDEV_CHANGE): veth0_to_hsr: link becomes ready [ 64.526230][ T921] IPv6: ADDRCONF(NETDEV_CHANGE): hsr_slave_0: link becomes ready [ 64.536272][ T921] IPv6: ADDRCONF(NETDEV_CHANGE): veth1_to_hsr: link becomes ready [ 64.544791][ T921] IPv6: ADDRCONF(NETDEV_CHANGE): hsr_slave_1: link becomes ready [ 64.553226][ T921] IPv6: ADDRCONF(NETDEV_CHANGE): hsr0: link becomes ready [ 64.573189][ T7085] 8021q: adding VLAN 0 to HW filter on device batadv0 [ 64.596837][ T7093] 8021q: adding VLAN 0 to HW filter on device bond0 [ 64.608501][ T6923] IPv6: ADDRCONF(NETDEV_CHANGE): vxcan1: link becomes ready [ 64.616469][ T6923] IPv6: ADDRCONF(NETDEV_CHANGE): vxcan0: link becomes ready [ 64.624054][ T6923] IPv6: ADDRCONF(NETDEV_CHANGE): veth0_to_bridge: link becomes ready [ 64.632655][ T6923] IPv6: ADDRCONF(NETDEV_CHANGE): bridge_slave_0: link becomes ready [ 64.641549][ T6923] bridge0: port 1(bridge_slave_0) entered blocking state [ 64.648821][ T6923] bridge0: port 1(bridge_slave_0) entered forwarding state [ 64.670323][ T7090] 8021q: adding VLAN 0 to HW filter on device team0 [ 64.701599][ T6922] IPv6: ADDRCONF(NETDEV_CHANGE): bridge0: link becomes ready [ 64.710147][ T6922] IPv6: ADDRCONF(NETDEV_CHANGE): veth1_to_bridge: link becomes ready [ 64.719740][ T6922] IPv6: ADDRCONF(NETDEV_CHANGE): bridge_slave_1: link becomes ready [ 64.729288][ T6922] bridge0: port 2(bridge_slave_1) entered blocking state [ 64.736701][ T6922] bridge0: port 2(bridge_slave_1) entered forwarding state [ 64.744775][ T6922] IPv6: ADDRCONF(NETDEV_CHANGE): veth1: link becomes ready [ 64.752675][ T6922] IPv6: ADDRCONF(NETDEV_CHANGE): veth0: link becomes ready [ 64.761519][ T6922] IPv6: ADDRCONF(NETDEV_CHANGE): veth0_to_bridge: link becomes ready [ 64.770955][ T6922] IPv6: ADDRCONF(NETDEV_CHANGE): bridge_slave_0: link becomes ready [ 64.780034][ T6922] bridge0: port 1(bridge_slave_0) entered blocking state [ 64.787503][ T6922] bridge0: port 1(bridge_slave_0) entered forwarding state [ 64.800086][ T6922] IPv6: ADDRCONF(NETDEV_CHANGE): veth0_to_bond: link becomes ready [ 64.810855][ T6922] IPv6: ADDRCONF(NETDEV_CHANGE): veth1_to_bond: link becomes ready [ 64.824538][ T6922] IPv6: ADDRCONF(NETDEV_CHANGE): veth0_to_team: link becomes ready [ 64.833412][ T6922] IPv6: ADDRCONF(NETDEV_CHANGE): team_slave_0: link becomes ready [ 64.846250][ T6922] IPv6: ADDRCONF(NETDEV_CHANGE): bridge0: link becomes ready [ 64.856823][ T6922] IPv6: ADDRCONF(NETDEV_CHANGE): team0: link becomes ready [ 64.873600][ T7093] 8021q: adding VLAN 0 to HW filter on device team0 [ 64.896562][ T8721] IPv6: ADDRCONF(NETDEV_CHANGE): veth1: link becomes ready [ 64.905582][ T8721] IPv6: ADDRCONF(NETDEV_CHANGE): veth0: link becomes ready [ 64.913600][ T8721] IPv6: ADDRCONF(NETDEV_CHANGE): veth0_to_bridge: link becomes ready [ 64.924819][ T8721] IPv6: ADDRCONF(NETDEV_CHANGE): bridge_slave_0: link becomes ready [ 64.933978][ T8721] bridge0: port 1(bridge_slave_0) entered blocking state [ 64.941585][ T8721] bridge0: port 1(bridge_slave_0) entered forwarding state [ 64.950037][ T8721] IPv6: ADDRCONF(NETDEV_CHANGE): veth1_to_bridge: link becomes ready [ 64.959073][ T8721] IPv6: ADDRCONF(NETDEV_CHANGE): bridge_slave_1: link becomes ready [ 64.967766][ T8721] bridge0: port 2(bridge_slave_1) entered blocking state [ 64.975075][ T8721] bridge0: port 2(bridge_slave_1) entered forwarding state [ 64.982648][ T8721] IPv6: ADDRCONF(NETDEV_CHANGE): veth0_to_bond: link becomes ready [ 64.992004][ T8721] IPv6: ADDRCONF(NETDEV_CHANGE): veth1_to_bridge: link becomes ready [ 65.000994][ T8721] IPv6: ADDRCONF(NETDEV_CHANGE): bridge_slave_1: link becomes ready [ 65.010015][ T8721] bridge0: port 2(bridge_slave_1) entered blocking state [ 65.017430][ T8721] bridge0: port 2(bridge_slave_1) entered forwarding state [ 65.025799][ T8721] IPv6: ADDRCONF(NETDEV_CHANGE): veth1_to_bond: link becomes ready [ 65.034663][ T8721] IPv6: ADDRCONF(NETDEV_CHANGE): bridge0: link becomes ready [ 65.045451][ T7088] 8021q: adding VLAN 0 to HW filter on device bond0 [ 65.052377][ T8722] Bluetooth: hci1: command 0x041b tx timeout [ 65.078417][ T8721] IPv6: ADDRCONF(NETDEV_CHANGE): veth1_to_team: link becomes ready [ 65.087809][ T8721] IPv6: ADDRCONF(NETDEV_CHANGE): team_slave_1: link becomes ready [ 65.098567][ T8721] IPv6: ADDRCONF(NETDEV_CHANGE): veth0_to_bond: link becomes ready [ 65.107565][ T8721] IPv6: ADDRCONF(NETDEV_CHANGE): veth1_to_bond: link becomes ready [ 65.116850][ T8721] IPv6: ADDRCONF(NETDEV_CHANGE): veth0_to_team: link becomes ready [ 65.126517][ T8721] IPv6: ADDRCONF(NETDEV_CHANGE): team_slave_0: link becomes ready [ 65.140738][ T7088] 8021q: adding VLAN 0 to HW filter on device team0 [ 65.141186][ T8721] Bluetooth: hci5: command 0x041b tx timeout [ 65.155271][ T8721] IPv6: ADDRCONF(NETDEV_CHANGE): team0: link becomes ready [ 65.163073][ T8721] IPv6: ADDRCONF(NETDEV_CHANGE): veth1: link becomes ready [ 65.171204][ T8721] IPv6: ADDRCONF(NETDEV_CHANGE): veth0: link becomes ready [ 65.179077][ T8721] IPv6: ADDRCONF(NETDEV_CHANGE): veth1_virt_wifi: link becomes ready [ 65.188015][ T8721] IPv6: ADDRCONF(NETDEV_CHANGE): veth0_virt_wifi: link becomes ready [ 65.197130][ T8721] IPv6: ADDRCONF(NETDEV_CHANGE): veth0_to_team: link becomes ready [ 65.207102][ T8721] IPv6: ADDRCONF(NETDEV_CHANGE): team_slave_0: link becomes ready [ 65.222208][ T7092] hsr0: Slave A (hsr_slave_0) is not up; please bring it up to get a fully working HSR network [ 65.233012][ T7092] hsr0: Slave B (hsr_slave_1) is not up; please bring it up to get a fully working HSR network [ 65.236319][ T8721] Bluetooth: hci3: command 0x041b tx timeout [ 65.250164][ T8721] Bluetooth: hci4: command 0x041b tx timeout [ 65.264221][ T8721] Bluetooth: hci2: command 0x041b tx timeout [ 65.279961][ T8721] IPv6: ADDRCONF(NETDEV_CHANGE): team0: link becomes ready [ 65.288397][ T8721] IPv6: ADDRCONF(NETDEV_CHANGE): veth0_to_hsr: link becomes ready [ 65.298184][ T8721] IPv6: ADDRCONF(NETDEV_CHANGE): hsr_slave_0: link becomes ready [ 65.307172][ T8721] IPv6: ADDRCONF(NETDEV_CHANGE): veth1_to_team: link becomes ready [ 65.316041][ T8721] IPv6: ADDRCONF(NETDEV_CHANGE): team_slave_1: link becomes ready [ 65.324693][ T8721] IPv6: ADDRCONF(NETDEV_CHANGE): veth1_to_hsr: link becomes ready [ 65.333121][ T8721] IPv6: ADDRCONF(NETDEV_CHANGE): hsr_slave_1: link becomes ready [ 65.341864][ T8721] IPv6: ADDRCONF(NETDEV_CHANGE): veth0_to_hsr: link becomes ready [ 65.350567][ T8721] IPv6: ADDRCONF(NETDEV_CHANGE): hsr_slave_0: link becomes ready [ 65.359522][ T8721] IPv6: ADDRCONF(NETDEV_CHANGE): veth1_to_team: link becomes ready [ 65.368694][ T8721] IPv6: ADDRCONF(NETDEV_CHANGE): team_slave_1: link becomes ready [ 65.377383][ T8721] IPv6: ADDRCONF(NETDEV_CHANGE): veth1_vlan: link becomes ready [ 65.385818][ T8721] IPv6: ADDRCONF(NETDEV_CHANGE): veth0_vlan: link becomes ready [ 65.394310][ T8721] IPv6: ADDRCONF(NETDEV_CHANGE): veth0_to_bridge: link becomes ready [ 65.403033][ T8721] IPv6: ADDRCONF(NETDEV_CHANGE): bridge_slave_0: link becomes ready [ 65.411682][ T8721] bridge0: port 1(bridge_slave_0) entered blocking state [ 65.418902][ T8721] bridge0: port 1(bridge_slave_0) entered forwarding state [ 65.426720][ T8721] IPv6: ADDRCONF(NETDEV_CHANGE): veth1_to_bridge: link becomes ready [ 65.435550][ T8721] IPv6: ADDRCONF(NETDEV_CHANGE): bridge_slave_1: link becomes ready [ 65.443991][ T8721] bridge0: port 2(bridge_slave_1) entered blocking state [ 65.451023][ T8721] bridge0: port 2(bridge_slave_1) entered forwarding state [ 65.461380][ T7090] hsr0: Slave B (hsr_slave_1) is not up; please bring it up to get a fully working HSR network [ 65.473647][ T7090] IPv6: ADDRCONF(NETDEV_CHANGE): hsr0: link becomes ready [ 65.492012][ T7088] hsr0: Slave A (hsr_slave_0) is not up; please bring it up to get a fully working HSR network [ 65.503158][ T7088] hsr0: Slave B (hsr_slave_1) is not up; please bring it up to get a fully working HSR network [ 65.526695][ T7085] device veth0_vlan entered promiscuous mode [ 65.533665][ T8721] IPv6: ADDRCONF(NETDEV_CHANGE): hsr0: link becomes ready [ 65.541958][ T8721] IPv6: ADDRCONF(NETDEV_CHANGE): vlan0: link becomes ready [ 65.550389][ T8721] IPv6: ADDRCONF(NETDEV_CHANGE): vlan1: link becomes ready [ 65.559778][ T8721] IPv6: ADDRCONF(NETDEV_CHANGE): bridge0: link becomes ready [ 65.568312][ T8721] IPv6: ADDRCONF(NETDEV_CHANGE): veth1_to_hsr: link becomes ready [ 65.577002][ T8721] IPv6: ADDRCONF(NETDEV_CHANGE): hsr_slave_1: link becomes ready [ 65.585517][ T8721] IPv6: ADDRCONF(NETDEV_CHANGE): veth0_to_bond: link becomes ready [ 65.594003][ T8721] IPv6: ADDRCONF(NETDEV_CHANGE): veth1_to_bond: link becomes ready [ 65.602478][ T8721] IPv6: ADDRCONF(NETDEV_CHANGE): veth0_to_team: link becomes ready [ 65.611775][ T8721] IPv6: ADDRCONF(NETDEV_CHANGE): team_slave_0: link becomes ready [ 65.620276][ T8721] IPv6: ADDRCONF(NETDEV_CHANGE): veth1_to_team: link becomes ready [ 65.629265][ T8721] IPv6: ADDRCONF(NETDEV_CHANGE): team_slave_1: link becomes ready [ 65.637842][ T8721] IPv6: ADDRCONF(NETDEV_CHANGE): veth0_to_hsr: link becomes ready [ 65.646634][ T8721] IPv6: ADDRCONF(NETDEV_CHANGE): hsr_slave_0: link becomes ready [ 65.655298][ T8721] IPv6: ADDRCONF(NETDEV_CHANGE): veth1_to_hsr: link becomes ready [ 65.663859][ T8721] IPv6: ADDRCONF(NETDEV_CHANGE): hsr_slave_1: link becomes ready [ 65.672091][ T8721] IPv6: ADDRCONF(NETDEV_CHANGE): veth0_to_hsr: link becomes ready [ 65.681009][ T8721] IPv6: ADDRCONF(NETDEV_CHANGE): hsr_slave_0: link becomes ready [ 65.689432][ T8721] IPv6: ADDRCONF(NETDEV_CHANGE): vxcan1: link becomes ready [ 65.696986][ T8721] IPv6: ADDRCONF(NETDEV_CHANGE): vxcan0: link becomes ready [ 65.717607][ T7093] hsr0: Slave B (hsr_slave_1) is not up; please bring it up to get a fully working HSR network [ 65.729834][ T7093] IPv6: ADDRCONF(NETDEV_CHANGE): hsr0: link becomes ready [ 65.743578][ T7092] 8021q: adding VLAN 0 to HW filter on device batadv0 [ 65.751183][ T8721] IPv6: ADDRCONF(NETDEV_CHANGE): team0: link becomes ready [ 65.759987][ T8721] IPv6: ADDRCONF(NETDEV_CHANGE): hsr0: link becomes ready [ 65.768297][ T8721] IPv6: ADDRCONF(NETDEV_CHANGE): vxcan1: link becomes ready [ 65.775894][ T8721] IPv6: ADDRCONF(NETDEV_CHANGE): vxcan0: link becomes ready [ 65.783415][ T8721] IPv6: ADDRCONF(NETDEV_CHANGE): veth1_to_hsr: link becomes ready [ 65.799116][ T8721] IPv6: ADDRCONF(NETDEV_CHANGE): hsr_slave_1: link becomes ready [ 65.812843][ T7090] 8021q: adding VLAN 0 to HW filter on device batadv0 [ 65.838336][ T7088] 8021q: adding VLAN 0 to HW filter on device batadv0 [ 65.847656][ T7085] device veth1_vlan entered promiscuous mode [ 65.867461][ T7093] 8021q: adding VLAN 0 to HW filter on device batadv0 [ 65.880697][ T7] IPv6: ADDRCONF(NETDEV_CHANGE): vxcan1: link becomes ready [ 65.889388][ T7] IPv6: ADDRCONF(NETDEV_CHANGE): vxcan0: link becomes ready [ 65.906222][ T7] IPv6: ADDRCONF(NETDEV_CHANGE): vxcan1: link becomes ready [ 65.913648][ T7] IPv6: ADDRCONF(NETDEV_CHANGE): vxcan0: link becomes ready [ 65.926617][ T1223] ieee802154 phy0 wpan0: encryption failed: -22 [ 65.933087][ T1223] ieee802154 phy1 wpan1: encryption failed: -22 [ 65.969443][ T8708] IPv6: ADDRCONF(NETDEV_CHANGE): veth1_virt_wifi: link becomes ready [ 65.979253][ T8708] IPv6: ADDRCONF(NETDEV_CHANGE): veth0_virt_wifi: link becomes ready [ 65.989896][ T8708] IPv6: ADDRCONF(NETDEV_CHANGE): veth1_virt_wifi: link becomes ready [ 65.999594][ T8708] IPv6: ADDRCONF(NETDEV_CHANGE): veth0_virt_wifi: link becomes ready [ 66.029699][ T7085] device veth0_macvtap entered promiscuous mode [ 66.038008][ T7] IPv6: ADDRCONF(NETDEV_CHANGE): veth1_macvtap: link becomes ready [ 66.049602][ T7] IPv6: ADDRCONF(NETDEV_CHANGE): veth0_macvtap: link becomes ready [ 66.062034][ T7] IPv6: ADDRCONF(NETDEV_CHANGE): veth1_virt_wifi: link becomes ready [ 66.072785][ T7] IPv6: ADDRCONF(NETDEV_CHANGE): veth0_virt_wifi: link becomes ready [ 66.085609][ T7] IPv6: ADDRCONF(NETDEV_CHANGE): macvtap0: link becomes ready [ 66.099503][ T7090] device veth0_vlan entered promiscuous mode [ 66.108355][ T7092] device veth0_vlan entered promiscuous mode [ 66.120737][ T7085] device veth1_macvtap entered promiscuous mode [ 66.141138][ T6922] IPv6: ADDRCONF(NETDEV_CHANGE): macsec0: link becomes ready [ 66.149113][ T6922] IPv6: ADDRCONF(NETDEV_CHANGE): veth1_vlan: link becomes ready [ 66.158405][ T6922] IPv6: ADDRCONF(NETDEV_CHANGE): veth0_vlan: link becomes ready [ 66.167944][ T6922] IPv6: ADDRCONF(NETDEV_CHANGE): veth1_vlan: link becomes ready [ 66.176759][ T6922] IPv6: ADDRCONF(NETDEV_CHANGE): veth0_vlan: link becomes ready [ 66.192563][ T7090] device veth1_vlan entered promiscuous mode [ 66.200857][ T6922] IPv6: ADDRCONF(NETDEV_CHANGE): vlan0: link becomes ready [ 66.209555][ T6922] IPv6: ADDRCONF(NETDEV_CHANGE): vlan1: link becomes ready [ 66.217967][ T6922] IPv6: ADDRCONF(NETDEV_CHANGE): macvlan0: link becomes ready [ 66.227279][ T6922] IPv6: ADDRCONF(NETDEV_CHANGE): vlan0: link becomes ready [ 66.235670][ T6922] IPv6: ADDRCONF(NETDEV_CHANGE): vlan1: link becomes ready [ 66.279576][ T7085] batman_adv: batadv0: Interface activated: batadv_slave_0 [ 66.298472][ T7088] device veth0_vlan entered promiscuous mode [ 66.307726][ T7092] device veth1_vlan entered promiscuous mode [ 66.317745][ T6922] IPv6: ADDRCONF(NETDEV_CHANGE): macvlan0: link becomes ready [ 66.329743][ T6922] IPv6: ADDRCONF(NETDEV_CHANGE): veth1_virt_wifi: link becomes ready [ 66.339461][ T6922] IPv6: ADDRCONF(NETDEV_CHANGE): veth0_virt_wifi: link becomes ready [ 66.350826][ T6922] IPv6: ADDRCONF(NETDEV_CHANGE): veth1_vlan: link becomes ready [ 66.360421][ T6922] IPv6: ADDRCONF(NETDEV_CHANGE): veth0_vlan: link becomes ready [ 66.371657][ T6922] IPv6: ADDRCONF(NETDEV_CHANGE): batadv_slave_0: link becomes ready [ 66.381513][ T6922] IPv6: ADDRCONF(NETDEV_CHANGE): veth0_to_batadv: link becomes ready [ 66.393385][ T6922] IPv6: ADDRCONF(NETDEV_CHANGE): veth1_vlan: link becomes ready [ 66.402691][ T6922] IPv6: ADDRCONF(NETDEV_CHANGE): veth0_vlan: link becomes ready [ 66.413879][ T7093] device veth0_vlan entered promiscuous mode [ 66.423230][ T7085] batman_adv: batadv0: Interface activated: batadv_slave_1 [ 66.439637][ T7088] device veth1_vlan entered promiscuous mode [ 66.447190][ T6922] IPv6: ADDRCONF(NETDEV_CHANGE): macvlan1: link becomes ready [ 66.456492][ T6922] IPv6: ADDRCONF(NETDEV_CHANGE): vlan0: link becomes ready [ 66.465149][ T6922] IPv6: ADDRCONF(NETDEV_CHANGE): vlan1: link becomes ready [ 66.473016][ T6922] IPv6: ADDRCONF(NETDEV_CHANGE): vlan0: link becomes ready [ 66.481899][ T6922] IPv6: ADDRCONF(NETDEV_CHANGE): vlan1: link becomes ready [ 66.490640][ T6922] IPv6: ADDRCONF(NETDEV_CHANGE): macvlan0: link becomes ready [ 66.498919][ T6922] IPv6: ADDRCONF(NETDEV_CHANGE): batadv_slave_1: link becomes ready [ 66.507726][ T6922] IPv6: ADDRCONF(NETDEV_CHANGE): veth1_to_batadv: link becomes ready [ 66.521091][ T7093] device veth1_vlan entered promiscuous mode [ 66.531454][ T7085] netdevsim netdevsim2 netdevsim0: set [1, 0] type 2 family 0 port 6081 - 0 [ 66.544775][ T7085] netdevsim netdevsim2 netdevsim1: set [1, 0] type 2 family 0 port 6081 - 0 [ 66.553565][ T7085] netdevsim netdevsim2 netdevsim2: set [1, 0] type 2 family 0 port 6081 - 0 [ 66.562720][ T7085] netdevsim netdevsim2 netdevsim3: set [1, 0] type 2 family 0 port 6081 - 0 [ 66.605356][ T8721] IPv6: ADDRCONF(NETDEV_CHANGE): macvlan1: link becomes ready [ 66.613384][ T8721] IPv6: ADDRCONF(NETDEV_CHANGE): macvlan0: link becomes ready [ 66.623336][ T8721] IPv6: ADDRCONF(NETDEV_CHANGE): macvlan1: link becomes ready [ 66.631678][ T8721] IPv6: ADDRCONF(NETDEV_CHANGE): macvlan1: link becomes ready [ 66.650161][ T7090] device veth0_macvtap entered promiscuous mode [ 66.665058][ T7093] device veth0_macvtap entered promiscuous mode [ 66.672944][ T8721] IPv6: ADDRCONF(NETDEV_CHANGE): veth1_macvtap: link becomes ready [ 66.682226][ T8721] IPv6: ADDRCONF(NETDEV_CHANGE): veth0_macvtap: link becomes ready [ 66.691364][ T8721] IPv6: ADDRCONF(NETDEV_CHANGE): veth1_macvtap: link becomes ready [ 66.700037][ T8721] IPv6: ADDRCONF(NETDEV_CHANGE): veth0_macvtap: link becomes ready [ 66.708525][ T8721] IPv6: ADDRCONF(NETDEV_CHANGE): veth1_macvtap: link becomes ready [ 66.717337][ T8721] IPv6: ADDRCONF(NETDEV_CHANGE): veth0_macvtap: link becomes ready [ 66.739522][ T7090] device veth1_macvtap entered promiscuous mode [ 66.752939][ T7092] device veth0_macvtap entered promiscuous mode [ 66.761880][ T8721] IPv6: ADDRCONF(NETDEV_CHANGE): macvtap0: link becomes ready [ 66.774430][ T8721] IPv6: ADDRCONF(NETDEV_CHANGE): macsec0: link becomes ready [ 66.782348][ T8721] IPv6: ADDRCONF(NETDEV_CHANGE): macvtap0: link becomes ready [ 66.794441][ T8721] IPv6: ADDRCONF(NETDEV_CHANGE): macvtap0: link becomes ready [ 66.818837][ T7090] batman_adv: The newly added mac address (aa:aa:aa:aa:aa:3d) already exists on: batadv_slave_0 [ 66.833687][ T7090] batman_adv: It is strongly recommended to keep mac addresses unique to avoid problems! [ 66.845306][ T7090] batman_adv: batadv0: Interface activated: batadv_slave_0 [ 66.856597][ T7092] device veth1_macvtap entered promiscuous mode [ 66.869313][ T921] IPv6: ADDRCONF(NETDEV_CHANGE): macsec0: link becomes ready [ 66.880685][ T921] IPv6: ADDRCONF(NETDEV_CHANGE): batadv_slave_0: link becomes ready [ 66.890578][ T921] IPv6: ADDRCONF(NETDEV_CHANGE): veth0_to_batadv: link becomes ready [ 66.899495][ T921] IPv6: ADDRCONF(NETDEV_CHANGE): veth1_macvtap: link becomes ready [ 66.909059][ T921] IPv6: ADDRCONF(NETDEV_CHANGE): veth0_macvtap: link becomes ready [ 66.918542][ T7093] device veth1_macvtap entered promiscuous mode [ 66.929624][ T7088] device veth0_macvtap entered promiscuous mode [ 66.953368][ T7090] batman_adv: The newly added mac address (aa:aa:aa:aa:aa:3e) already exists on: batadv_slave_1 [ 66.977399][ T7090] batman_adv: It is strongly recommended to keep mac addresses unique to avoid problems! [ 66.997124][ T7090] batman_adv: batadv0: Interface activated: batadv_slave_1 [ 67.012841][ T7093] batman_adv: The newly added mac address (aa:aa:aa:aa:aa:3d) already exists on: batadv_slave_0 [ 67.030631][ T7093] batman_adv: It is strongly recommended to keep mac addresses unique to avoid problems! [ 67.043645][ T7093] batman_adv: The newly added mac address (aa:aa:aa:aa:aa:3d) already exists on: batadv_slave_0 [ 67.058697][ T7093] batman_adv: It is strongly recommended to keep mac addresses unique to avoid problems! [ 67.070991][ T7093] batman_adv: batadv0: Interface activated: batadv_slave_0 [ 67.081353][ T7088] device veth1_macvtap entered promiscuous mode [ 67.098950][ T8721] IPv6: ADDRCONF(NETDEV_CHANGE): macsec0: link becomes ready [ 67.107169][ T8721] IPv6: ADDRCONF(NETDEV_CHANGE): macvtap0: link becomes ready [ 67.116108][ T8721] IPv6: ADDRCONF(NETDEV_CHANGE): macsec0: link becomes ready [ 67.124056][ T8721] IPv6: ADDRCONF(NETDEV_CHANGE): batadv_slave_1: link becomes ready [ 67.132434][ T8721] IPv6: ADDRCONF(NETDEV_CHANGE): veth1_to_batadv: link becomes ready [ 67.141583][ T8721] IPv6: ADDRCONF(NETDEV_CHANGE): batadv_slave_0: link becomes ready [ 67.150634][ T8721] IPv6: ADDRCONF(NETDEV_CHANGE): veth0_to_batadv: link becomes ready [ 67.161685][ T7090] netdevsim netdevsim3 netdevsim0: set [1, 0] type 2 family 0 port 6081 - 0 [ 67.171032][ T8721] Bluetooth: hci1: command 0x040f tx timeout [ 67.177137][ T7090] netdevsim netdevsim3 netdevsim1: set [1, 0] type 2 family 0 port 6081 - 0 [ 67.186395][ T7090] netdevsim netdevsim3 netdevsim2: set [1, 0] type 2 family 0 port 6081 - 0 [ 67.196896][ T7090] netdevsim netdevsim3 netdevsim3: set [1, 0] type 2 family 0 port 6081 - 0 [ 67.209088][ T6922] Bluetooth: hci5: command 0x040f tx timeout [ 67.217515][ T7093] batman_adv: The newly added mac address (aa:aa:aa:aa:aa:3e) already exists on: batadv_slave_1 [ 67.229267][ T7093] batman_adv: It is strongly recommended to keep mac addresses unique to avoid problems! [ 67.239595][ T7093] batman_adv: The newly added mac address (aa:aa:aa:aa:aa:3e) already exists on: batadv_slave_1 [ 67.250135][ T7093] batman_adv: It is strongly recommended to keep mac addresses unique to avoid problems! [ 67.260921][ T7093] batman_adv: batadv0: Interface activated: batadv_slave_1 [ 67.270746][ T7092] batman_adv: The newly added mac address (aa:aa:aa:aa:aa:3d) already exists on: batadv_slave_0 [ 67.281845][ T7092] batman_adv: It is strongly recommended to keep mac addresses unique to avoid problems! [ 67.291874][ T7092] batman_adv: The newly added mac address (aa:aa:aa:aa:aa:3d) already exists on: batadv_slave_0 [ 67.294099][ T921] Bluetooth: hci2: command 0x040f tx timeout [ 67.309245][ T7092] batman_adv: It is strongly recommended to keep mac addresses unique to avoid problems! [ 67.315241][ T921] Bluetooth: hci4: command 0x040f tx timeout [ 67.319566][ T7092] batman_adv: The newly added mac address (aa:aa:aa:aa:aa:3d) already exists on: batadv_slave_0 [ 67.331279][ T921] Bluetooth: hci3: command 0x040f tx timeout [ 67.339162][ T7092] batman_adv: It is strongly recommended to keep mac addresses unique to avoid problems! [ 67.353295][ T7092] batman_adv: batadv0: Interface activated: batadv_slave_0 [ 67.367499][ T8721] IPv6: ADDRCONF(NETDEV_CHANGE): batadv_slave_1: link becomes ready [ 67.376564][ T8721] IPv6: ADDRCONF(NETDEV_CHANGE): veth1_to_batadv: link becomes ready [ 67.385262][ T8721] IPv6: ADDRCONF(NETDEV_CHANGE): batadv_slave_0: link becomes ready [ 67.394118][ T8721] IPv6: ADDRCONF(NETDEV_CHANGE): veth0_to_batadv: link becomes ready [ 67.405942][ T7093] netdevsim netdevsim4 netdevsim0: set [1, 0] type 2 family 0 port 6081 - 0 [ 67.418359][ T7093] netdevsim netdevsim4 netdevsim1: set [1, 0] type 2 family 0 port 6081 - 0 [ 67.427854][ T7093] netdevsim netdevsim4 netdevsim2: set [1, 0] type 2 family 0 port 6081 - 0 [ 67.437009][ T7093] netdevsim netdevsim4 netdevsim3: set [1, 0] type 2 family 0 port 6081 - 0 [ 67.448510][ T50] wlan0: Created IBSS using preconfigured BSSID 50:50:50:50:50:50 [ 67.475524][ T7092] batman_adv: The newly added mac address (aa:aa:aa:aa:aa:3e) already exists on: batadv_slave_1 [ 67.481311][ T50] wlan0: Creating new IBSS network, BSSID 50:50:50:50:50:50 [ 67.487078][ T7092] batman_adv: It is strongly recommended to keep mac addresses unique to avoid problems! [ 67.504264][ T7092] batman_adv: The newly added mac address (aa:aa:aa:aa:aa:3e) already exists on: batadv_slave_1 [ 67.515287][ T7092] batman_adv: It is strongly recommended to keep mac addresses unique to avoid problems! [ 67.525972][ T7092] batman_adv: The newly added mac address (aa:aa:aa:aa:aa:3e) already exists on: batadv_slave_1 [ 67.536680][ T7092] batman_adv: It is strongly recommended to keep mac addresses unique to avoid problems! [ 67.548210][ T7092] batman_adv: batadv0: Interface activated: batadv_slave_1 [ 67.575325][ T7088] batman_adv: The newly added mac address (aa:aa:aa:aa:aa:3d) already exists on: batadv_slave_0 [ 67.599071][ T7088] batman_adv: It is strongly recommended to keep mac addresses unique to avoid problems! [ 67.618890][ T7088] batman_adv: The newly added mac address (aa:aa:aa:aa:aa:3d) already exists on: batadv_slave_0 [ 67.629533][ T7088] batman_adv: It is strongly recommended to keep mac addresses unique to avoid problems! [ 67.639625][ T7088] batman_adv: The newly added mac address (aa:aa:aa:aa:aa:3d) already exists on: batadv_slave_0 [ 67.651377][ T7088] batman_adv: It is strongly recommended to keep mac addresses unique to avoid problems! [ 67.663956][ T7088] batman_adv: The newly added mac address (aa:aa:aa:aa:aa:3d) already exists on: batadv_slave_0 [ 67.674966][ T7088] batman_adv: It is strongly recommended to keep mac addresses unique to avoid problems! [ 67.687630][ T7088] batman_adv: batadv0: Interface activated: batadv_slave_0 [ 67.696812][ T921] IPv6: ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready [ 67.704914][ T921] IPv6: ADDRCONF(NETDEV_CHANGE): batadv_slave_1: link becomes ready [ 67.714700][ T921] IPv6: ADDRCONF(NETDEV_CHANGE): veth1_to_batadv: link becomes ready [ 67.723194][ T921] IPv6: ADDRCONF(NETDEV_CHANGE): batadv_slave_0: link becomes ready [ 67.732021][ T921] IPv6: ADDRCONF(NETDEV_CHANGE): veth0_to_batadv: link becomes ready [ 67.782020][ T7088] batman_adv: The newly added mac address (aa:aa:aa:aa:aa:3e) already exists on: batadv_slave_1 [ 67.793136][ T50] wlan1: Created IBSS using preconfigured BSSID 50:50:50:50:50:50 [ 67.801241][ T7088] batman_adv: It is strongly recommended to keep mac addresses unique to avoid problems! [ 67.810225][ T50] wlan1: Creating new IBSS network, BSSID 50:50:50:50:50:50 [ 67.825361][ T7088] batman_adv: The newly added mac address (aa:aa:aa:aa:aa:3e) already exists on: batadv_slave_1 [ 67.836185][ T7088] batman_adv: It is strongly recommended to keep mac addresses unique to avoid problems! [ 67.846653][ T7088] batman_adv: The newly added mac address (aa:aa:aa:aa:aa:3e) already exists on: batadv_slave_1 [ 67.857806][ T7088] batman_adv: It is strongly recommended to keep mac addresses unique to avoid problems! [ 67.868083][ T7088] batman_adv: The newly added mac address (aa:aa:aa:aa:aa:3e) already exists on: batadv_slave_1 [ 67.879005][ T7088] batman_adv: It is strongly recommended to keep mac addresses unique to avoid problems! [ 67.890601][ T7088] batman_adv: batadv0: Interface activated: batadv_slave_1 [ 67.914285][ T1136] IPv6: ADDRCONF(NETDEV_CHANGE): wlan1: link becomes ready [ 67.922329][ T1136] IPv6: ADDRCONF(NETDEV_CHANGE): batadv_slave_1: link becomes ready [ 67.935808][ T1136] IPv6: ADDRCONF(NETDEV_CHANGE): veth1_to_batadv: link becomes ready [ 67.956780][ T7088] netdevsim netdevsim1 netdevsim0: set [1, 0] type 2 family 0 port 6081 - 0 [ 67.975278][ T10] wlan0: Created IBSS using preconfigured BSSID 50:50:50:50:50:50 [ 67.988644][ T7088] netdevsim netdevsim1 netdevsim1: set [1, 0] type 2 family 0 port 6081 - 0 [ 68.004012][ T7088] netdevsim netdevsim1 netdevsim2: set [1, 0] type 2 family 0 port 6081 - 0 [ 68.014098][ T10] wlan0: Creating new IBSS network, BSSID 50:50:50:50:50:50 [ 68.034407][ T7088] netdevsim netdevsim1 netdevsim3: set [1, 0] type 2 family 0 port 6081 - 0 [ 68.095994][ T1136] IPv6: ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready [ 68.233681][ T8] wlan0: Created IBSS using preconfigured BSSID 50:50:50:50:50:50 [ 68.239190][ T994] wlan0: Created IBSS using preconfigured BSSID 50:50:50:50:50:50 [ 68.241730][ T8] wlan0: Creating new IBSS network, BSSID 50:50:50:50:50:50 [ 68.252734][ T994] wlan0: Creating new IBSS network, BSSID 50:50:50:50:50:50 [ 68.293729][ T50] wlan1: Created IBSS using preconfigured BSSID 50:50:50:50:50:50 [ 68.307148][ T921] IPv6: ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready [ 68.312329][ T50] wlan1: Creating new IBSS network, BSSID 50:50:50:50:50:50 [ 68.341370][ T8721] IPv6: ADDRCONF(NETDEV_CHANGE): wlan1: link becomes ready [ 68.357337][ T8721] IPv6: ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready [ 68.388705][ T1290] wlan1: Created IBSS using preconfigured BSSID 50:50:50:50:50:50 [ 68.440257][ T1290] wlan1: Creating new IBSS network, BSSID 50:50:50:50:50:50 [ 68.449615][ T8813] wlan1: Created IBSS using preconfigured BSSID 50:50:50:50:50:50 [ 68.468690][ T8813] wlan1: Creating new IBSS network, BSSID 50:50:50:50:50:50 [ 68.483168][ T10] wlan0: Created IBSS using preconfigured BSSID 50:50:50:50:50:50 [ 68.510971][ T1136] IPv6: ADDRCONF(NETDEV_CHANGE): wlan1: link becomes ready [ 68.526876][ T10] wlan0: Creating new IBSS network, BSSID 50:50:50:50:50:50 [ 68.530502][ T1136] IPv6: ADDRCONF(NETDEV_CHANGE): wlan1: link becomes ready [ 68.557680][ T1136] IPv6: ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready [ 68.640144][ T1290] wlan1: Created IBSS using preconfigured BSSID 50:50:50:50:50:50 [ 68.660982][ T1290] wlan1: Creating new IBSS network, BSSID 50:50:50:50:50:50 [ 68.672535][ T8804] IPv6: ADDRCONF(NETDEV_CHANGE): wlan1: link becomes ready 2021/11/18 04:48:32 executed programs: 6 [ 69.214576][ T8804] Bluetooth: hci1: command 0x0419 tx timeout [ 69.284718][ T35] Bluetooth: hci5: command 0x0419 tx timeout [ 69.374252][ T35] Bluetooth: hci3: command 0x0419 tx timeout [ 69.380558][ T35] Bluetooth: hci4: command 0x0419 tx timeout [ 69.386777][ T35] Bluetooth: hci2: command 0x0419 tx timeout [ 85.456504][ T8977] chnl_net:caif_netlink_parms(): no params data found [ 85.519249][ T8977] bridge0: port 1(bridge_slave_0) entered blocking state [ 85.528865][ T8977] bridge0: port 1(bridge_slave_0) entered disabled state [ 85.538024][ T8977] device bridge_slave_0 entered promiscuous mode [ 85.546629][ T8977] bridge0: port 2(bridge_slave_1) entered blocking state [ 85.555066][ T8977] bridge0: port 2(bridge_slave_1) entered disabled state [ 85.563853][ T8977] device bridge_slave_1 entered promiscuous mode [ 85.589512][ T8977] bond0: (slave bond_slave_0): Enslaving as an active interface with an up link [ 85.601027][ T8977] bond0: (slave bond_slave_1): Enslaving as an active interface with an up link [ 85.638878][ T8977] team0: Port device team_slave_0 added [ 85.649606][ T8977] team0: Port device team_slave_1 added [ 85.679558][ T8977] batman_adv: batadv0: Adding interface: batadv_slave_0 [ 85.690477][ T8977] 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. [ 85.718421][ T8977] batman_adv: batadv0: Not using interface batadv_slave_0 (retrying later): interface not active [ 85.734587][ T8977] batman_adv: batadv0: Adding interface: batadv_slave_1 [ 85.742068][ T8977] 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. [ 85.768792][ T8977] batman_adv: batadv0: Not using interface batadv_slave_1 (retrying later): interface not active [ 85.805526][ T8977] device hsr_slave_0 entered promiscuous mode [ 85.812735][ T8977] device hsr_slave_1 entered promiscuous mode [ 85.819536][ T8977] debugfs: Directory 'hsr0' with parent 'hsr' already present! [ 85.827678][ T8977] Cannot create hsr debugfs directory [ 85.945751][ T8977] netdevsim netdevsim5 netdevsim0: renamed from eth0 [ 85.957654][ T8977] netdevsim netdevsim5 netdevsim1: renamed from eth1 [ 85.970789][ T8977] netdevsim netdevsim5 netdevsim2: renamed from eth2 [ 85.983313][ T8977] netdevsim netdevsim5 netdevsim3: renamed from eth3 [ 86.004696][ T8977] bridge0: port 2(bridge_slave_1) entered blocking state [ 86.011916][ T8977] bridge0: port 2(bridge_slave_1) entered forwarding state [ 86.019324][ T8977] bridge0: port 1(bridge_slave_0) entered blocking state [ 86.026906][ T8977] bridge0: port 1(bridge_slave_0) entered forwarding state [ 86.070910][ T8977] 8021q: adding VLAN 0 to HW filter on device bond0 [ 86.085184][ T1136] IPv6: ADDRCONF(NETDEV_CHANGE): veth0: link becomes ready [ 86.094218][ T1136] bridge0: port 1(bridge_slave_0) entered disabled state [ 86.102235][ T1136] bridge0: port 2(bridge_slave_1) entered disabled state [ 86.111316][ T1136] IPv6: ADDRCONF(NETDEV_CHANGE): bond0: link becomes ready [ 86.125213][ T8977] 8021q: adding VLAN 0 to HW filter on device team0 [ 86.135856][ T1136] IPv6: ADDRCONF(NETDEV_CHANGE): bridge_slave_0: link becomes ready [ 86.145230][ T1136] bridge0: port 1(bridge_slave_0) entered blocking state [ 86.152613][ T1136] bridge0: port 1(bridge_slave_0) entered forwarding state [ 86.172784][ T6922] IPv6: ADDRCONF(NETDEV_CHANGE): bridge_slave_1: link becomes ready [ 86.183053][ T6922] bridge0: port 2(bridge_slave_1) entered blocking state [ 86.190392][ T6922] bridge0: port 2(bridge_slave_1) entered forwarding state [ 86.199557][ T6922] IPv6: ADDRCONF(NETDEV_CHANGE): team_slave_0: link becomes ready [ 86.209275][ T6922] IPv6: ADDRCONF(NETDEV_CHANGE): team_slave_1: link becomes ready [ 86.219962][ T6922] IPv6: ADDRCONF(NETDEV_CHANGE): team0: link becomes ready [ 86.232324][ T1136] IPv6: ADDRCONF(NETDEV_CHANGE): hsr_slave_0: link becomes ready [ 86.245373][ T8977] hsr0: Slave B (hsr_slave_1) is not up; please bring it up to get a fully working HSR network [ 86.258159][ T8977] IPv6: ADDRCONF(NETDEV_CHANGE): hsr0: link becomes ready [ 86.267602][ T6922] IPv6: ADDRCONF(NETDEV_CHANGE): hsr_slave_1: link becomes ready [ 86.286562][ T6922] IPv6: ADDRCONF(NETDEV_CHANGE): vxcan0: link becomes ready [ 86.294520][ T6922] IPv6: ADDRCONF(NETDEV_CHANGE): vxcan1: link becomes ready [ 86.309083][ T8977] 8021q: adding VLAN 0 to HW filter on device batadv0 [ 86.374927][ T1136] IPv6: ADDRCONF(NETDEV_CHANGE): veth0_virt_wifi: link becomes ready [ 86.389591][ T5] IPv6: ADDRCONF(NETDEV_CHANGE): veth0_vlan: link becomes ready [ 86.400018][ T5] IPv6: ADDRCONF(NETDEV_CHANGE): vlan0: link becomes ready [ 86.408782][ T5] IPv6: ADDRCONF(NETDEV_CHANGE): vlan1: link becomes ready [ 86.418512][ T8977] device veth0_vlan entered promiscuous mode [ 86.434130][ T8977] device veth1_vlan entered promiscuous mode [ 86.457713][ T5] IPv6: ADDRCONF(NETDEV_CHANGE): veth1_macvtap: link becomes ready [ 86.467657][ T5] IPv6: ADDRCONF(NETDEV_CHANGE): veth0_macvtap: link becomes ready [ 86.479160][ T8977] device veth0_macvtap entered promiscuous mode [ 86.489851][ T8977] device veth1_macvtap entered promiscuous mode [ 86.508406][ T8977] batman_adv: The newly added mac address (aa:aa:aa:aa:aa:3d) already exists on: batadv_slave_0 [ 86.519618][ T8977] batman_adv: It is strongly recommended to keep mac addresses unique to avoid problems! [ 86.530160][ T8977] batman_adv: The newly added mac address (aa:aa:aa:aa:aa:3d) already exists on: batadv_slave_0 [ 86.541337][ T8977] batman_adv: It is strongly recommended to keep mac addresses unique to avoid problems! [ 86.553086][ T8977] batman_adv: The newly added mac address (aa:aa:aa:aa:aa:3d) already exists on: batadv_slave_0 [ 86.563872][ T8977] batman_adv: It is strongly recommended to keep mac addresses unique to avoid problems! [ 86.573786][ T8977] batman_adv: The newly added mac address (aa:aa:aa:aa:aa:3d) already exists on: batadv_slave_0 [ 86.584428][ T8977] batman_adv: It is strongly recommended to keep mac addresses unique to avoid problems! [ 86.594488][ T8977] batman_adv: The newly added mac address (aa:aa:aa:aa:aa:3d) already exists on: batadv_slave_0 [ 86.605110][ T8977] batman_adv: It is strongly recommended to keep mac addresses unique to avoid problems! [ 86.617728][ T8977] batman_adv: batadv0: Interface activated: batadv_slave_0 [ 86.626315][ T1136] IPv6: ADDRCONF(NETDEV_CHANGE): macvtap0: link becomes ready [ 86.635067][ T1136] IPv6: ADDRCONF(NETDEV_CHANGE): macsec0: link becomes ready [ 86.643421][ T1136] IPv6: ADDRCONF(NETDEV_CHANGE): batadv_slave_0: link becomes ready [ 86.652179][ T1136] IPv6: ADDRCONF(NETDEV_CHANGE): veth0_to_batadv: link becomes ready [ 86.663544][ T8977] batman_adv: The newly added mac address (aa:aa:aa:aa:aa:3e) already exists on: batadv_slave_1 [ 86.676066][ T8977] batman_adv: It is strongly recommended to keep mac addresses unique to avoid problems! [ 86.686375][ T8977] batman_adv: The newly added mac address (aa:aa:aa:aa:aa:3e) already exists on: batadv_slave_1 [ 86.697266][ T8977] batman_adv: It is strongly recommended to keep mac addresses unique to avoid problems! [ 86.708007][ T8977] batman_adv: The newly added mac address (aa:aa:aa:aa:aa:3e) already exists on: batadv_slave_1 [ 86.721232][ T8977] batman_adv: It is strongly recommended to keep mac addresses unique to avoid problems! [ 86.733998][ T8977] batman_adv: The newly added mac address (aa:aa:aa:aa:aa:3e) already exists on: batadv_slave_1 [ 86.745474][ T8977] batman_adv: It is strongly recommended to keep mac addresses unique to avoid problems! [ 86.757270][ T8977] batman_adv: The newly added mac address (aa:aa:aa:aa:aa:3e) already exists on: batadv_slave_1 [ 86.768417][ T8977] batman_adv: It is strongly recommended to keep mac addresses unique to avoid problems! [ 86.780419][ T8977] batman_adv: batadv0: Interface activated: batadv_slave_1 [ 86.789780][ T6924] IPv6: ADDRCONF(NETDEV_CHANGE): batadv_slave_1: link becomes ready [ 86.799524][ T6924] IPv6: ADDRCONF(NETDEV_CHANGE): veth1_to_batadv: link becomes ready [ 86.814679][ T8977] netdevsim netdevsim5 netdevsim0: set [1, 0] type 2 family 0 port 6081 - 0 [ 86.824238][ T8977] netdevsim netdevsim5 netdevsim1: set [1, 0] type 2 family 0 port 6081 - 0 [ 86.833213][ T8977] netdevsim netdevsim5 netdevsim2: set [1, 0] type 2 family 0 port 6081 - 0 [ 86.843121][ T8977] netdevsim netdevsim5 netdevsim3: set [1, 0] type 2 family 0 port 6081 - 0 [ 86.908302][ T1068] wlan0: Created IBSS using preconfigured BSSID 50:50:50:50:50:50 [ 86.925201][ T1068] wlan0: Creating new IBSS network, BSSID 50:50:50:50:50:50 [ 86.973180][ T1136] IPv6: ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready [ 86.982126][ T8813] wlan1: Created IBSS using preconfigured BSSID 50:50:50:50:50:50 [ 86.991046][ T8813] wlan1: Creating new IBSS network, BSSID 50:50:50:50:50:50 [ 87.006599][ T6924] IPv6: ADDRCONF(NETDEV_CHANGE): wlan1: link becomes ready [ 87.372343][ T20] Bluetooth: hci0: command 0x0409 tx timeout 2021/11/18 04:48:51 executed programs: 18 [ 89.451447][ T8775] Bluetooth: hci0: command 0x041b tx timeout [ 91.522217][ T8775] Bluetooth: hci0: command 0x040f tx timeout 2021/11/18 04:48:57 executed programs: 48 [ 93.619624][ T8775] Bluetooth: hci0: command 0x0419 tx timeout 2021/11/18 04:49:02 executed programs: 74 2021/11/18 04:49:08 executed programs: 97 2021/11/18 04:49:13 executed programs: 135 [ 112.924589][T10708] syz-executor.1: vmalloc error: size 4096, page order 0, failed to allocate pages, mode:0xdc0(GFP_KERNEL|__GFP_ZERO), nodemask=(null),cpuset=/,mems_allowed=0-1 [ 112.945142][T10708] CPU: 1 PID: 10708 Comm: syz-executor.1 Not tainted 5.16.0-rc1-syzkaller #0 [ 112.954105][T10708] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 [ 112.964332][T10708] Call Trace: [ 112.967609][T10708] [ 112.970547][T10708] dump_stack_lvl+0x57/0x7d [ 112.975229][T10708] warn_alloc.cold+0x87/0x17a [ 112.980093][T10708] ? zone_watermark_ok_safe+0x290/0x290 [ 112.985634][T10708] ? __kmalloc_node+0x62/0x390 [ 112.990415][T10708] __vmalloc_node_range+0x6c7/0x940 [ 112.995705][T10708] ? vfree_atomic+0x90/0x90 [ 113.000463][T10708] ? rcu_read_lock_sched_held+0x3a/0x70 [ 113.006229][T10708] copy_process+0x720/0x6a50 [ 113.011041][T10708] ? kernel_clone+0xb8/0x7f0 [ 113.015613][T10708] ? lock_chain_count+0x20/0x20 [ 113.020451][T10708] ? lock_chain_count+0x20/0x20 [ 113.025459][T10708] ? find_held_lock+0x2d/0x110 [ 113.030212][T10708] ? __cleanup_sighand+0x60/0x60 [ 113.035238][T10708] kernel_clone+0xb8/0x7f0 [ 113.039733][T10708] ? create_io_thread+0xe0/0xe0 [ 113.044609][T10708] ? find_held_lock+0x2d/0x110 [ 113.049528][T10708] __do_sys_clone+0xaf/0xf0 [ 113.054099][T10708] ? kernel_clone+0x7f0/0x7f0 [ 113.058838][T10708] ? lockdep_hardirqs_on_prepare+0x17b/0x400 [ 113.065015][T10708] ? syscall_enter_from_user_mode+0x21/0x70 [ 113.071239][T10708] ? lockdep_hardirqs_on+0x79/0x100 [ 113.076413][T10708] ? syscall_enter_from_user_mode+0x21/0x70 [ 113.082445][T10708] do_syscall_64+0x35/0xb0 [ 113.086855][T10708] entry_SYSCALL_64_after_hwframe+0x44/0xae [ 113.092749][T10708] RIP: 0033:0x467a01 [ 113.096898][T10708] Code: 48 85 ff 74 3d 48 85 f6 74 38 48 83 ee 10 48 89 4e 08 48 89 3e 48 89 d7 4c 89 c2 4d 89 c8 4c 8b 54 24 08 b8 38 00 00 00 0f 05 <48> 85 c0 7c 13 74 01 c3 31 ed 58 5f ff d0 48 89 c7 b8 3c 00 00 00 [ 113.116758][T10708] RSP: 002b:00007ffdb9ecf308 EFLAGS: 00000206 ORIG_RAX: 0000000000000038 [ 113.125379][T10708] RAX: ffffffffffffffda RBX: 00007fea52ec2700 RCX: 0000000000467a01 [ 113.133509][T10708] RDX: 00007fea52ec29d0 RSI: 00007fea52ec22f0 RDI: 00000000003d0f00 [ 113.141635][T10708] RBP: 00007ffdb9ecf540 R08: 00007fea52ec2700 R09: 00007fea52ec2700 [ 113.149678][T10708] R10: 00007fea52ec29d0 R11: 0000000000000206 R12: 00007ffdb9ecf3be [ 113.157740][T10708] R13: 00007ffdb9ecf3bf R14: 00007fea52ec2300 R15: 0000000000022000 [ 113.166071][T10708] [ 113.177877][T10708] Mem-Info: [ 113.181223][T10708] active_anon:272 inactive_anon:16223 isolated_anon:0 [ 113.181223][T10708] active_file:9394 inactive_file:42257 isolated_file:0 [ 113.181223][T10708] unevictable:768 dirty:178 writeback:0 [ 113.181223][T10708] slab_reclaimable:21968 slab_unreclaimable:98999 [ 113.181223][T10708] mapped:7398 shmem:6315 pagetables:2447 bounce:0 [ 113.181223][T10708] kernel_misc_reclaimable:0 [ 113.181223][T10708] free:1436198 free_pcp:8199 free_cma:0 [ 113.307809][T10708] Node 0 active_anon:1088kB inactive_anon:64180kB active_file:37460kB inactive_file:169028kB unevictable:1536kB isolated(anon):0kB isolated(file):0kB mapped:29692kB dirty:708kB writeback:0kB shmem:22812kB shmem_thp: 0kB shmem_pmdmapped: 0kB anon_thp: 10240kB writeback_tmp:0kB kernel_stack:22080kB pagetables:9784kB all_unreclaimable? no [ 113.375316][T10708] Node 1 active_anon:0kB inactive_anon:912kB active_file:116kB inactive_file:0kB unevictable:1536kB isolated(anon):0kB isolated(file):0kB mapped:0kB dirty:4kB writeback:0kB shmem:2448kB shmem_thp: 0kB shmem_pmdmapped: 0kB anon_thp: 0kB writeback_tmp:0kB kernel_stack:16kB pagetables:4kB all_unreclaimable? no [ 113.408219][T10708] Node 0 DMA free:15360kB boost:0kB min:200kB low:248kB high:296kB reserved_highatomic:0KB active_anon:0kB inactive_anon:0kB active_file:0kB inactive_file:0kB unevictable:0kB writepending:0kB present:15992kB managed:15360kB mlocked:0kB bounce:0kB free_pcp:0kB local_pcp:0kB free_cma:0kB [ 113.446126][T10708] lowmem_reserve[]: 0 2663 2663 2663 2663 [ 113.452354][T10708] Node 0 DMA32 free:1786848kB boost:0kB min:35820kB low:44772kB high:53724kB reserved_highatomic:0KB active_anon:1088kB inactive_anon:64180kB active_file:37460kB inactive_file:169028kB unevictable:1536kB writepending:708kB present:3129332kB managed:2734056kB mlocked:0kB bounce:0kB free_pcp:25832kB local_pcp:19380kB free_cma:0kB [ 113.485500][T10708] lowmem_reserve[]: 0 0 0 0 0 [ 113.491171][T10708] Node 0 Normal free:0kB boost:0kB min:4kB low:4kB high:4kB reserved_highatomic:0KB active_anon:0kB inactive_anon:0kB active_file:0kB inactive_file:0kB unevictable:0kB writepending:0kB present:1048576kB managed:400kB mlocked:0kB bounce:0kB free_pcp:0kB local_pcp:0kB free_cma:0kB [ 113.520760][T10708] lowmem_reserve[]: 0 0 0 0 0 [ 113.525558][T10708] Node 1 Normal free:3941660kB boost:0kB min:54080kB low:67600kB high:81120kB reserved_highatomic:0KB active_anon:0kB inactive_anon:912kB active_file:116kB inactive_file:0kB unevictable:1536kB writepending:4kB present:4194304kB managed:4117620kB mlocked:0kB bounce:0kB free_pcp:7972kB local_pcp:7556kB free_cma:0kB [ 113.556353][T10708] lowmem_reserve[]: 0 0 0 0 0 [ 113.566138][T10708] Node 0 DMA: 0*4kB 0*8kB 0*16kB 0*32kB 0*64kB 0*128kB 0*256kB 0*512kB 1*1024kB (U) 1*2048kB (M) 3*4096kB (M) = 15360kB [ 113.616124][T10708] Node 0 DMA32: 1*4kB (E) 4*8kB (UME) 2*16kB (UE) 1*32kB (U) 1*64kB (U) 1*128kB (M) 2*256kB (UE) 1*512kB (U) 1*1024kB (U) 1*2048kB (U) 435*4096kB (M) = 1786148kB [ 113.652804][T10708] Node 0 Normal: 0*4kB 0*8kB 0*16kB 0*32kB 0*64kB 0*128kB 0*256kB 0*512kB 0*1024kB 0*2048kB 0*4096kB = 0kB [ 113.691423][T10708] Node 1 Normal: 67*4kB (UE) 24*8kB (UE) 19*16kB (UE) 105*32kB (UME) 36*64kB (UME) 14*128kB (UME) 5*256kB (UE) 6*512kB (UE) 1*1024kB (E) 2*2048kB (UE) 958*4096kB (M) = 3941660kB [ 113.714849][T10708] Node 0 hugepages_total=0 hugepages_free=0 hugepages_surp=0 hugepages_size=1048576kB [ 113.725334][T10708] Node 0 hugepages_total=2 hugepages_free=2 hugepages_surp=0 hugepages_size=2048kB [ 113.749412][T10708] Node 1 hugepages_total=0 hugepages_free=0 hugepages_surp=0 hugepages_size=1048576kB [ 113.764466][T10708] Node 1 hugepages_total=2 hugepages_free=2 hugepages_surp=0 hugepages_size=2048kB [ 113.785347][T10708] 57728 total pagecache pages [ 113.804526][T10708] 0 pages in swap cache [ 113.809608][T10708] Swap cache stats: add 0, delete 0, find 0/0 [ 113.815808][T10708] Free swap = 0kB [ 113.825336][T10708] Total swap = 0kB [ 113.829795][T10708] 2097051 pages RAM [ 113.833793][T10708] 0 pages HighMem/MovableOnly [ 113.839064][T10708] 380192 pages reserved [ 113.843292][T10708] 0 pages cma reserved 2021/11/18 04:49:18 executed programs: 169 2021/11/18 04:49:23 executed programs: 199 2021/11/18 04:49:29 executed programs: 231 [ 127.358073][ T1223] ieee802154 phy0 wpan0: encryption failed: -22 [ 127.364481][ T1223] ieee802154 phy1 wpan1: encryption failed: -22 2021/11/18 04:49:34 executed programs: 265 2021/11/18 04:49:39 executed programs: 293 2021/11/18 04:49:44 executed programs: 318 2021/11/18 04:49:50 executed programs: 355 2021/11/18 04:49:55 executed programs: 384 2021/11/18 04:50:00 executed programs: 410 [ 157.431514][T13158] syz-executor.1: vmalloc error: size 4096, page order 0, failed to allocate pages, mode:0xdc0(GFP_KERNEL|__GFP_ZERO), nodemask=(null),cpuset=/,mems_allowed=0-1 [ 157.457271][T13158] CPU: 1 PID: 13158 Comm: syz-executor.1 Not tainted 5.16.0-rc1-syzkaller #0 [ 157.466477][T13158] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 [ 157.476534][T13158] Call Trace: [ 157.479823][T13158] [ 157.482747][T13158] dump_stack_lvl+0x57/0x7d [ 157.487441][T13158] warn_alloc.cold+0x87/0x17a [ 157.492296][T13158] ? zone_watermark_ok_safe+0x290/0x290 [ 157.497837][T13158] ? __kmalloc_node+0x62/0x390 [ 157.503735][T13158] __vmalloc_node_range+0x6c7/0x940 [ 157.509019][T13158] ? vfree_atomic+0x90/0x90 [ 157.513513][T13158] ? rcu_read_lock_sched_held+0x3a/0x70 [ 157.519057][T13158] copy_process+0x720/0x6a50 [ 157.523630][T13158] ? kernel_clone+0xb8/0x7f0 [ 157.528332][T13158] ? lock_chain_count+0x20/0x20 [ 157.533282][T13158] ? lock_chain_count+0x20/0x20 [ 157.538135][T13158] ? find_held_lock+0x2d/0x110 [ 157.542993][T13158] ? __cleanup_sighand+0x60/0x60 [ 157.547943][T13158] kernel_clone+0xb8/0x7f0 [ 157.552435][T13158] ? create_io_thread+0xe0/0xe0 [ 157.557318][T13158] ? find_held_lock+0x2d/0x110 [ 157.562091][T13158] __do_sys_clone+0xaf/0xf0 [ 157.566593][T13158] ? kernel_clone+0x7f0/0x7f0 [ 157.571277][T13158] ? lockdep_hardirqs_on_prepare+0x17b/0x400 [ 157.577258][T13158] ? syscall_enter_from_user_mode+0x21/0x70 [ 157.583147][T13158] ? lockdep_hardirqs_on+0x79/0x100 [ 157.588344][T13158] ? syscall_enter_from_user_mode+0x21/0x70 [ 157.594229][T13158] do_syscall_64+0x35/0xb0 [ 157.598645][T13158] entry_SYSCALL_64_after_hwframe+0x44/0xae [ 157.604531][T13158] RIP: 0033:0x467a01 [ 157.608419][T13158] Code: 48 85 ff 74 3d 48 85 f6 74 38 48 83 ee 10 48 89 4e 08 48 89 3e 48 89 d7 4c 89 c2 4d 89 c8 4c 8b 54 24 08 b8 38 00 00 00 0f 05 <48> 85 c0 7c 13 74 01 c3 31 ed 58 5f ff d0 48 89 c7 b8 3c 00 00 00 [ 157.628105][T13158] RSP: 002b:00007ffdb9ecf308 EFLAGS: 00000206 ORIG_RAX: 0000000000000038 [ 157.636601][T13158] RAX: ffffffffffffffda RBX: 00007fea52ee4700 RCX: 0000000000467a01 [ 157.644566][T13158] RDX: 00007fea52ee49d0 RSI: 00007fea52ee42f0 RDI: 00000000003d0f00 [ 157.652669][T13158] RBP: 00007ffdb9ecf540 R08: 00007fea52ee4700 R09: 00007fea52ee4700 [ 157.660710][T13158] R10: 00007fea52ee49d0 R11: 0000000000000206 R12: 00007ffdb9ecf3be [ 157.668669][T13158] R13: 00007ffdb9ecf3bf R14: 00007fea52ee4300 R15: 0000000000022000 [ 157.676744][T13158] [ 157.685156][T13158] Mem-Info: [ 157.688314][T13158] active_anon:275 inactive_anon:23044 isolated_anon:0 [ 157.688314][T13158] active_file:9803 inactive_file:42297 isolated_file:0 [ 157.688314][T13158] unevictable:768 dirty:109 writeback:0 [ 157.688314][T13158] slab_reclaimable:22270 slab_unreclaimable:101436 [ 157.688314][T13158] mapped:7406 shmem:6315 pagetables:5291 bounce:0 [ 157.688314][T13158] kernel_misc_reclaimable:0 [ 157.688314][T13158] free:1415805 free_pcp:9397 free_cma:0 [ 157.731247][T13158] Node 0 active_anon:1100kB inactive_anon:91264kB active_file:39096kB inactive_file:169188kB unevictable:1536kB isolated(anon):0kB isolated(file):0kB mapped:29624kB dirty:432kB writeback:0kB shmem:22812kB shmem_thp: 0kB shmem_pmdmapped: 0kB anon_thp: 16384kB writeback_tmp:0kB kernel_stack:40780kB pagetables:21160kB all_unreclaimable? no [ 157.778961][T13158] Node 1 active_anon:0kB inactive_anon:912kB active_file:116kB inactive_file:0kB unevictable:1536kB isolated(anon):0kB isolated(file):0kB mapped:0kB dirty:4kB writeback:0kB shmem:2448kB shmem_thp: 0kB shmem_pmdmapped: 0kB anon_thp: 0kB writeback_tmp:0kB kernel_stack:16kB pagetables:4kB all_unreclaimable? no [ 157.812315][T13158] Node 0 DMA free:15360kB boost:0kB min:200kB low:248kB high:296kB reserved_highatomic:0KB active_anon:0kB inactive_anon:0kB active_file:0kB inactive_file:0kB unevictable:0kB writepending:0kB present:15992kB managed:15360kB mlocked:0kB bounce:0kB free_pcp:0kB local_pcp:0kB free_cma:0kB [ 157.841896][T13158] lowmem_reserve[]: 0 2663 2663 2663 2663 [ 157.849113][T13158] Node 0 DMA32 free:1705440kB boost:0kB min:35820kB low:44772kB high:53724kB reserved_highatomic:0KB active_anon:1100kB inactive_anon:91564kB active_file:39096kB inactive_file:169188kB unevictable:1536kB writepending:432kB present:3129332kB managed:2734056kB mlocked:0kB bounce:0kB free_pcp:28556kB local_pcp:11880kB free_cma:0kB [ 157.889653][T13158] lowmem_reserve[]: 0 0 0 0 0 [ 157.901088][T13158] Node 0 Normal free:0kB boost:0kB min:4kB low:4kB high:4kB reserved_highatomic:0KB active_anon:0kB inactive_anon:0kB active_file:0kB inactive_file:0kB unevictable:0kB writepending:0kB present:1048576kB managed:400kB mlocked:0kB bounce:0kB free_pcp:0kB local_pcp:0kB free_cma:0kB [ 157.930496][T13158] lowmem_reserve[]: 0 0 0 0 0 [ 157.936181][T13158] Node 1 Normal free:3941916kB boost:0kB min:54080kB low:67600kB high:81120kB reserved_highatomic:0KB active_anon:0kB inactive_anon:912kB active_file:116kB inactive_file:0kB unevictable:1536kB writepending:4kB present:4194304kB managed:4117620kB mlocked:0kB bounce:0kB free_pcp:7748kB local_pcp:7556kB free_cma:0kB [ 157.967060][T13158] lowmem_reserve[]: 0 0 0 0 0 [ 157.972219][T13158] Node 0 DMA: 0*4kB 0*8kB 0*16kB 0*32kB 0*64kB 0*128kB 0*256kB 0*512kB 1*1024kB (U) 1*2048kB (M) 3*4096kB (M) = 15360kB [ 157.986085][T13158] Node 0 DMA32: 2*4kB (UE) 2*8kB (UE) 1*16kB (E) 0*32kB 1*64kB (E) 1*128kB (E) 1*256kB (M) 1*512kB (U) 2*1024kB (UE) 3*2048kB (UME) 414*4096kB (M) = 1704936kB [ 158.003515][T13158] Node 0 Normal: 0*4kB 0*8kB 0*16kB 0*32kB 0*64kB 0*128kB 0*256kB 0*512kB 0*1024kB 0*2048kB 0*4096kB = 0kB [ 158.017081][T13158] Node 1 Normal: 67*4kB (UE) 24*8kB (UE) 19*16kB (UE) 109*32kB (UME) 36*64kB (UME) 15*128kB (UME) 5*256kB (UE) 6*512kB (UE) 1*1024kB (E) 2*2048kB (UE) 958*4096kB (M) = 3941916kB [ 158.036202][T13158] Node 0 hugepages_total=0 hugepages_free=0 hugepages_surp=0 hugepages_size=1048576kB [ 158.046909][T13158] Node 0 hugepages_total=2 hugepages_free=2 hugepages_surp=0 hugepages_size=2048kB [ 158.057953][T13158] Node 1 hugepages_total=0 hugepages_free=0 hugepages_surp=0 hugepages_size=1048576kB [ 158.068341][T13158] Node 1 hugepages_total=2 hugepages_free=2 hugepages_surp=0 hugepages_size=2048kB [ 158.078830][T13158] 58164 total pagecache pages [ 158.083900][T13158] 0 pages in swap cache [ 158.089197][T13158] Swap cache stats: add 0, delete 0, find 0/0 [ 158.096330][T13158] Free swap = 0kB [ 158.100297][T13158] Total swap = 0kB [ 158.104250][T13158] 2097051 pages RAM [ 158.110312][T13158] 0 pages HighMem/MovableOnly [ 158.115762][T13158] 380192 pages reserved [ 158.120196][T13158] 0 pages cma reserved 2021/11/18 04:50:05 executed programs: 434 2021/11/18 04:50:11 executed programs: 463 2021/11/18 04:50:17 executed programs: 500 [ 177.414363][T14236] warn_alloc: 1 callbacks suppressed [ 177.414374][T14236] syz-executor.0: vmalloc error: size 4096, page order 0, failed to allocate pages, mode:0xdc0(GFP_KERNEL|__GFP_ZERO), nodemask=(null),cpuset=/,mems_allowed=0-1 [ 177.437731][T14236] CPU: 1 PID: 14236 Comm: syz-executor.0 Not tainted 5.16.0-rc1-syzkaller #0 [ 177.446495][T14236] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 [ 177.456568][T14236] Call Trace: [ 177.459862][T14236] [ 177.462786][T14236] dump_stack_lvl+0x57/0x7d [ 177.467710][T14236] warn_alloc.cold+0x87/0x17a [ 177.472549][T14236] ? zone_watermark_ok_safe+0x290/0x290 [ 177.478185][T14236] ? __kmalloc_node+0x62/0x390 [ 177.482968][T14236] __vmalloc_node_range+0x6c7/0x940 [ 177.488271][T14236] ? vfree_atomic+0x90/0x90 [ 177.492757][T14236] ? rcu_read_lock_sched_held+0x3a/0x70 [ 177.498292][T14236] copy_process+0x720/0x6a50 [ 177.502877][T14236] ? kernel_clone+0xb8/0x7f0 [ 177.507531][T14236] ? lock_chain_count+0x20/0x20 [ 177.512368][T14236] ? lock_chain_count+0x20/0x20 [ 177.517211][T14236] ? find_held_lock+0x2d/0x110 [ 177.522161][T14236] ? __cleanup_sighand+0x60/0x60 [ 177.527121][T14236] kernel_clone+0xb8/0x7f0 [ 177.531523][T14236] ? create_io_thread+0xe0/0xe0 [ 177.536359][T14236] ? find_held_lock+0x2d/0x110 [ 177.541262][T14236] __do_sys_clone+0xaf/0xf0 [ 177.545843][T14236] ? kernel_clone+0x7f0/0x7f0 [ 177.550593][T14236] ? lockdep_hardirqs_on_prepare+0x17b/0x400 [ 177.556558][T14236] ? syscall_enter_from_user_mode+0x21/0x70 [ 177.562436][T14236] ? lockdep_hardirqs_on+0x79/0x100 [ 177.567620][T14236] ? syscall_enter_from_user_mode+0x21/0x70 [ 177.573692][T14236] do_syscall_64+0x35/0xb0 [ 177.578382][T14236] entry_SYSCALL_64_after_hwframe+0x44/0xae [ 177.584437][T14236] RIP: 0033:0x467a01 [ 177.588309][T14236] Code: 48 85 ff 74 3d 48 85 f6 74 38 48 83 ee 10 48 89 4e 08 48 89 3e 48 89 d7 4c 89 c2 4d 89 c8 4c 8b 54 24 08 b8 38 00 00 00 0f 05 <48> 85 c0 7c 13 74 01 c3 31 ed 58 5f ff d0 48 89 c7 b8 3c 00 00 00 [ 177.608252][T14236] RSP: 002b:00007fff8661c3e8 EFLAGS: 00000206 ORIG_RAX: 0000000000000038 [ 177.616754][T14236] RAX: ffffffffffffffda RBX: 00007f79dd9a4700 RCX: 0000000000467a01 [ 177.624802][T14236] RDX: 00007f79dd9a49d0 RSI: 00007f79dd9a42f0 RDI: 00000000003d0f00 [ 177.632752][T14236] RBP: 00007fff8661c620 R08: 00007f79dd9a4700 R09: 00007f79dd9a4700 [ 177.640700][T14236] R10: 00007f79dd9a49d0 R11: 0000000000000206 R12: 00007fff8661c49e [ 177.648732][T14236] R13: 00007fff8661c49f R14: 00007f79dd9a4300 R15: 0000000000022000 [ 177.656700][T14236] [ 177.677954][T14236] Mem-Info: [ 177.699896][T14236] active_anon:275 inactive_anon:25794 isolated_anon:0 [ 177.699896][T14236] active_file:9984 inactive_file:42312 isolated_file:0 [ 177.699896][T14236] unevictable:768 dirty:74 writeback:0 [ 177.699896][T14236] slab_reclaimable:22412 slab_unreclaimable:103429 [ 177.699896][T14236] mapped:7413 shmem:6315 pagetables:6486 bounce:0 [ 177.699896][T14236] kernel_misc_reclaimable:0 [ 177.699896][T14236] free:1407409 free_pcp:8118 free_cma:0 [ 177.752985][T14236] Node 0 active_anon:1100kB inactive_anon:102364kB active_file:39820kB inactive_file:169248kB unevictable:1536kB isolated(anon):0kB isolated(file):0kB mapped:29752kB dirty:292kB writeback:0kB shmem:22812kB shmem_thp: 0kB shmem_pmdmapped: 0kB anon_thp: 18432kB writeback_tmp:0kB kernel_stack:48668kB pagetables:26040kB all_unreclaimable? no [ 177.789584][T14236] Node 1 active_anon:0kB inactive_anon:912kB active_file:116kB inactive_file:0kB unevictable:1536kB isolated(anon):0kB isolated(file):0kB mapped:0kB dirty:4kB writeback:0kB shmem:2448kB shmem_thp: 0kB shmem_pmdmapped: 0kB anon_thp: 0kB writeback_tmp:0kB kernel_stack:16kB pagetables:4kB all_unreclaimable? no [ 177.819916][T14236] Node 0 DMA free:15360kB boost:0kB min:200kB low:248kB high:296kB reserved_highatomic:0KB active_anon:0kB inactive_anon:0kB active_file:0kB inactive_file:0kB unevictable:0kB writepending:0kB present:15992kB managed:15360kB mlocked:0kB bounce:0kB free_pcp:0kB local_pcp:0kB free_cma:0kB [ 177.847767][T14236] lowmem_reserve[]: 0 2663 2663 2663 2663 [ 177.853663][T14236] Node 0 DMA32 free:1671856kB boost:0kB min:35820kB low:44772kB high:53724kB reserved_highatomic:0KB active_anon:1100kB inactive_anon:102364kB active_file:39820kB inactive_file:169248kB unevictable:1536kB writepending:292kB present:3129332kB managed:2734056kB mlocked:0kB bounce:0kB free_pcp:24784kB local_pcp:18976kB free_cma:0kB [ 177.887270][T14236] lowmem_reserve[]: 0 0 0 0 0 [ 177.892060][T14236] Node 0 Normal free:0kB boost:0kB min:4kB low:4kB high:4kB reserved_highatomic:0KB active_anon:0kB inactive_anon:0kB active_file:0kB inactive_file:0kB unevictable:0kB writepending:0kB present:1048576kB managed:400kB mlocked:0kB bounce:0kB free_pcp:0kB local_pcp:0kB free_cma:0kB [ 177.931828][T14236] lowmem_reserve[]: 0 0 0 0 0 [ 177.937152][T14236] Node 1 Normal free:3941916kB boost:0kB min:54080kB low:67600kB high:81120kB reserved_highatomic:0KB active_anon:0kB inactive_anon:912kB active_file:116kB inactive_file:0kB unevictable:1536kB writepending:4kB present:4194304kB managed:4117620kB mlocked:0kB bounce:0kB free_pcp:7748kB local_pcp:7556kB free_cma:0kB [ 177.971967][T14236] lowmem_reserve[]: 0 0 0 0 0 [ 177.976920][T14236] Node 0 DMA: 0*4kB 0*8kB 0*16kB 0*32kB 0*64kB 0*128kB 0*256kB 0*512kB 1*1024kB (U) 1*2048kB (M) 3*4096kB (M) = 15360kB [ 177.990790][T14236] Node 0 DMA32: 2*4kB (UE) 1*8kB (M) 2*16kB (UM) 0*32kB 0*64kB 1*128kB (M) 2*256kB (UM) 0*512kB 2*1024kB (ME) 1*2048kB (E) 407*4096kB (M) = 1671856kB [ 178.006840][T14236] Node 0 Normal: 0*4kB 0*8kB 0*16kB 0*32kB 0*64kB 0*128kB 0*256kB 0*512kB 0*1024kB 0*2048kB 0*4096kB = 0kB [ 178.018598][T14236] Node 1 Normal: 67*4kB (UE) 24*8kB (UE) 19*16kB (UE) 109*32kB (UME) 36*64kB (UME) 15*128kB (UME) 5*256kB (UE) 6*512kB (UE) 1*1024kB (E) 2*2048kB (UE) 958*4096kB (M) = 3941916kB [ 178.038114][T14236] Node 0 hugepages_total=0 hugepages_free=0 hugepages_surp=0 hugepages_size=1048576kB [ 178.049058][T14236] Node 0 hugepages_total=2 hugepages_free=2 hugepages_surp=0 hugepages_size=2048kB [ 178.062403][T14236] Node 1 hugepages_total=0 hugepages_free=0 hugepages_surp=0 hugepages_size=1048576kB [ 178.076315][T14236] Node 1 hugepages_total=2 hugepages_free=2 hugepages_surp=0 hugepages_size=2048kB [ 178.102015][T14236] 58359 total pagecache pages [ 178.114365][T14236] 0 pages in swap cache [ 178.125623][T14236] Swap cache stats: add 0, delete 0, find 0/0 [ 178.139043][T14236] Free swap = 0kB [ 178.151267][T14236] Total swap = 0kB 2021/11/18 04:50:22 executed programs: 532 [ 178.164359][T14236] 2097051 pages RAM [ 178.179033][T14236] 0 pages HighMem/MovableOnly [ 178.190740][T14236] 380192 pages reserved [ 178.202037][T14236] 0 pages cma reserved 2021/11/18 04:50:27 executed programs: 558 [ 185.753801][ T6922] Bluetooth: hci1: command 0x0406 tx timeout [ 185.753833][ T1136] Bluetooth: hci5: command 0x0406 tx timeout [ 185.770846][ T1136] Bluetooth: hci3: command 0x0406 tx timeout [ 185.777644][ T1136] Bluetooth: hci4: command 0x0406 tx timeout [ 185.784384][ T1136] Bluetooth: hci2: command 0x0406 tx timeout 2021/11/18 04:50:32 executed programs: 590 [ 188.800161][ T1223] ieee802154 phy0 wpan0: encryption failed: -22 [ 188.806484][ T1223] ieee802154 phy1 wpan1: encryption failed: -22 2021/11/18 04:50:37 executed programs: 621 2021/11/18 04:50:42 executed programs: 643 2021/11/18 04:50:47 executed programs: 671 2021/11/18 04:50:52 executed programs: 708 [ 211.352548][ T7] Bluetooth: hci0: command 0x0406 tx timeout 2021/11/18 04:50:58 executed programs: 737 2021/11/18 04:51:04 executed programs: 768 2021/11/18 04:51:09 executed programs: 799 [ 227.316571][T16885] syz-executor.4: vmalloc error: size 4096, page order 0, failed to allocate pages, mode:0xdc0(GFP_KERNEL|__GFP_ZERO), nodemask=(null),cpuset=/,mems_allowed=0-1 [ 227.333487][T16885] CPU: 1 PID: 16885 Comm: syz-executor.4 Not tainted 5.16.0-rc1-syzkaller #0 [ 227.342363][T16885] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 [ 227.352426][T16885] Call Trace: [ 227.355690][T16885] [ 227.358616][T16885] dump_stack_lvl+0x57/0x7d [ 227.363098][T16885] warn_alloc.cold+0x87/0x17a [ 227.367756][T16885] ? zone_watermark_ok_safe+0x290/0x290 [ 227.373278][T16885] ? __kmalloc_node+0x62/0x390 [ 227.378038][T16885] __vmalloc_node_range+0x6c7/0x940 [ 227.383257][T16885] ? vfree_atomic+0x90/0x90 [ 227.387732][T16885] ? rcu_read_lock_sched_held+0x3a/0x70 [ 227.393255][T16885] copy_process+0x720/0x6a50 [ 227.397813][T16885] ? kernel_clone+0xb8/0x7f0 [ 227.402373][T16885] ? lock_chain_count+0x20/0x20 [ 227.407198][T16885] ? lock_chain_count+0x20/0x20 [ 227.412019][T16885] ? find_held_lock+0x2d/0x110 [ 227.416968][T16885] ? __cleanup_sighand+0x60/0x60 [ 227.422015][T16885] kernel_clone+0xb8/0x7f0 [ 227.426496][T16885] ? create_io_thread+0xe0/0xe0 [ 227.431321][T16885] ? find_held_lock+0x2d/0x110 [ 227.436067][T16885] __do_sys_clone+0xaf/0xf0 [ 227.440545][T16885] ? kernel_clone+0x7f0/0x7f0 [ 227.445394][T16885] ? lockdep_hardirqs_on_prepare+0x17b/0x400 [ 227.451350][T16885] ? syscall_enter_from_user_mode+0x21/0x70 [ 227.457404][T16885] ? lockdep_hardirqs_on+0x79/0x100 [ 227.462573][T16885] ? syscall_enter_from_user_mode+0x21/0x70 [ 227.468442][T16885] do_syscall_64+0x35/0xb0 [ 227.472834][T16885] entry_SYSCALL_64_after_hwframe+0x44/0xae [ 227.478699][T16885] RIP: 0033:0x467a01 [ 227.482569][T16885] Code: 48 85 ff 74 3d 48 85 f6 74 38 48 83 ee 10 48 89 4e 08 48 89 3e 48 89 d7 4c 89 c2 4d 89 c8 4c 8b 54 24 08 b8 38 00 00 00 0f 05 <48> 85 c0 7c 13 74 01 c3 31 ed 58 5f ff d0 48 89 c7 b8 3c 00 00 00 [ 227.502934][T16885] RSP: 002b:00007ffc117a82e8 EFLAGS: 00000206 ORIG_RAX: 0000000000000038 [ 227.511408][T16885] RAX: ffffffffffffffda RBX: 00007f2bc6f5b700 RCX: 0000000000467a01 [ 227.519365][T16885] RDX: 00007f2bc6f5b9d0 RSI: 00007f2bc6f5b2f0 RDI: 00000000003d0f00 [ 227.527757][T16885] RBP: 00007ffc117a8520 R08: 00007f2bc6f5b700 R09: 00007f2bc6f5b700 [ 227.535803][T16885] R10: 00007f2bc6f5b9d0 R11: 0000000000000206 R12: 00007ffc117a839e [ 227.543897][T16885] R13: 00007ffc117a839f R14: 00007f2bc6f5b300 R15: 0000000000022000 [ 227.552120][T16885] [ 227.575704][T16885] Mem-Info: [ 227.578860][T16885] active_anon:275 inactive_anon:33968 isolated_anon:0 [ 227.578860][T16885] active_file:10447 inactive_file:42351 isolated_file:0 [ 227.578860][T16885] unevictable:768 dirty:188 writeback:0 [ 227.578860][T16885] slab_reclaimable:22764 slab_unreclaimable:107830 [ 227.578860][T16885] mapped:7425 shmem:6315 pagetables:9747 bounce:0 [ 227.578860][T16885] kernel_misc_reclaimable:0 [ 227.578860][T16885] free:1382334 free_pcp:9046 free_cma:0 [ 227.697007][T16885] Node 0 active_anon:1100kB inactive_anon:135560kB active_file:41672kB inactive_file:169404kB unevictable:1536kB isolated(anon):0kB isolated(file):0kB mapped:29800kB dirty:748kB writeback:0kB shmem:22812kB shmem_thp: 0kB shmem_pmdmapped: 0kB anon_thp: 26624kB writeback_tmp:0kB kernel_stack:70188kB pagetables:39284kB all_unreclaimable? no [ 227.738473][T16885] Node 1 active_anon:0kB inactive_anon:912kB active_file:116kB inactive_file:0kB unevictable:1536kB isolated(anon):0kB isolated(file):0kB mapped:0kB dirty:4kB writeback:0kB shmem:2448kB shmem_thp: 0kB shmem_pmdmapped: 0kB anon_thp: 0kB writeback_tmp:0kB kernel_stack:16kB pagetables:4kB all_unreclaimable? no [ 227.771379][T16885] Node 0 DMA free:15360kB boost:0kB min:200kB low:248kB high:296kB reserved_highatomic:0KB active_anon:0kB inactive_anon:0kB active_file:0kB inactive_file:0kB unevictable:0kB writepending:0kB present:15992kB managed:15360kB mlocked:0kB bounce:0kB free_pcp:0kB local_pcp:0kB free_cma:0kB [ 227.807925][T16885] lowmem_reserve[]: 0 2663 2663 2663 2663 [ 227.814953][T16885] Node 0 DMA32 free:1569848kB boost:0kB min:35820kB low:44772kB high:53724kB reserved_highatomic:0KB active_anon:1100kB inactive_anon:135860kB active_file:41672kB inactive_file:169404kB unevictable:1536kB writepending:748kB present:3129332kB managed:2734056kB mlocked:0kB bounce:0kB free_pcp:28476kB local_pcp:11104kB free_cma:0kB [ 227.862225][T16885] lowmem_reserve[]: 0 0 0 0 0 [ 227.868354][T16885] Node 0 Normal free:0kB boost:0kB min:4kB low:4kB high:4kB reserved_highatomic:0KB active_anon:0kB inactive_anon:0kB active_file:0kB inactive_file:0kB unevictable:0kB writepending:0kB present:1048576kB managed:400kB mlocked:0kB bounce:0kB free_pcp:0kB local_pcp:0kB free_cma:0kB [ 227.898300][T16885] lowmem_reserve[]: 0 0 0 0 0 [ 227.903492][T16885] Node 1 Normal free:3942364kB boost:0kB min:54080kB low:67600kB high:81120kB reserved_highatomic:0KB active_anon:0kB inactive_anon:912kB active_file:116kB inactive_file:0kB unevictable:1536kB writepending:4kB present:4194304kB managed:4117620kB mlocked:0kB bounce:0kB free_pcp:7300kB local_pcp:7300kB free_cma:0kB [ 227.934114][T16885] lowmem_reserve[]: 0 0 0 0 0 [ 227.938973][T16885] Node 0 DMA: 0*4kB 0*8kB 0*16kB 0*32kB 0*64kB 0*128kB 0*256kB 0*512kB 1*1024kB (U) 1*2048kB (M) 3*4096kB (M) = 15360kB [ 227.953374][T16885] Node 0 DMA32: 2*4kB (UE) 2*8kB (ME) 2*16kB (UE) 2*32kB (UM) 3*64kB (UME) 2*128kB (UE) 2*256kB (UE) 2*512kB (UM) 3*1024kB (UME) 2*2048kB (UM) 381*4096kB (M) = 1569848kB [ 227.973013][T16885] Node 0 Normal: 0*4kB 0*8kB 0*16kB 0*32kB 0*64kB 0*128kB 0*256kB 0*512kB 0*1024kB 0*2048kB 0*4096kB = 0kB [ 227.984833][T16885] Node 1 Normal: 67*4kB (UE) 24*8kB (UE) 19*16kB (UE) 119*32kB (UME) 36*64kB (UME) 14*128kB (UME) 4*256kB (UE) 7*512kB (UE) 1*1024kB (E) 2*2048kB (UE) 958*4096kB (M) = 3942364kB [ 228.005506][T16885] Node 0 hugepages_total=0 hugepages_free=0 hugepages_surp=0 hugepages_size=1048576kB [ 228.019464][T16885] Node 0 hugepages_total=2 hugepages_free=2 hugepages_surp=0 hugepages_size=2048kB [ 228.028981][T16885] Node 1 hugepages_total=0 hugepages_free=0 hugepages_surp=0 hugepages_size=1048576kB [ 228.045082][T16885] Node 1 hugepages_total=2 hugepages_free=2 hugepages_surp=0 hugepages_size=2048kB [ 228.054806][T16885] 58864 total pagecache pages [ 228.061358][T16885] 0 pages in swap cache [ 228.065742][T16885] Swap cache stats: add 0, delete 0, find 0/0 [ 228.072529][T16885] Free swap = 0kB [ 228.077424][T16885] Total swap = 0kB [ 228.081856][T16885] 2097051 pages RAM [ 228.085842][T16885] 0 pages HighMem/MovableOnly [ 228.095734][T16885] 380192 pages reserved [ 228.100487][T16885] 0 pages cma reserved 2021/11/18 04:51:15 executed programs: 829 [ 232.309301][ T26] INFO: task syz-executor.3:8837 blocked for more than 143 seconds. [ 232.328259][ T26] Not tainted 5.16.0-rc1-syzkaller #0 [ 232.340427][ T26] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 232.349779][ T26] task:syz-executor.3 state:D stack:29384 pid: 8837 ppid: 8836 flags:0x00000000 [ 232.360324][ T26] Call Trace: [ 232.363960][ T26] [ 232.367128][ T26] __schedule+0x90d/0x26c0 [ 232.371986][ T26] ? io_schedule_timeout+0x180/0x180 [ 232.377678][ T26] schedule+0xd2/0x260 [ 232.382135][ T26] schedule_timeout+0x19d/0x250 [ 232.387571][ T26] ? usleep_range+0x150/0x150 [ 232.394748][ T26] ? wait_for_completion+0x16c/0x270 [ 232.405934][ T26] ? lock_downgrade+0x6e0/0x6e0 [ 232.411392][ T26] ? do_raw_spin_lock+0x120/0x2b0 [ 232.416508][ T26] ? rwlock_bug.part.0+0x90/0x90 [ 232.427391][ T26] ? lockdep_hardirqs_on_prepare+0x17b/0x400 [ 232.434471][ T26] ? _raw_spin_unlock_irq+0x1f/0x40 [ 232.445282][ T26] wait_for_completion+0x174/0x270 [ 232.451095][ T26] ? percpu_ref_put_many.constprop.0+0x6a/0x1a0 [ 232.457431][ T26] ? bit_wait_io_timeout+0x160/0x160 [ 232.471673][ T26] __x64_sys_io_destroy+0x15a/0x1b0 [ 232.477063][ T26] ? __x64_compat_sys_io_setup+0x180/0x180 [ 232.483521][ T26] ? lockdep_hardirqs_on_prepare+0x17b/0x400 [ 232.489837][ T26] ? syscall_enter_from_user_mode+0x21/0x70 [ 232.495737][ T26] ? lockdep_hardirqs_on+0x79/0x100 [ 232.504379][ T26] do_syscall_64+0x35/0xb0 [ 232.511619][ T26] entry_SYSCALL_64_after_hwframe+0x44/0xae [ 232.517626][ T26] RIP: 0033:0x4665d9 [ 232.525757][ T26] RSP: 002b:00007f7bf9150188 EFLAGS: 00000246 ORIG_RAX: 00000000000000cf [ 232.536566][ T26] RAX: ffffffffffffffda RBX: 000000000056bf80 RCX: 00000000004665d9 [ 232.548735][ T26] RDX: 0000000000000000 RSI: 0000000000000000 RDI: 00007f7bf912f000 [ 232.556720][ T26] RBP: 00000000004bfcb9 R08: 0000000000000000 R09: 0000000000000000 [ 232.572877][ T26] R10: 0000000000000000 R11: 0000000000000246 R12: 000000000056bf80 [ 232.583244][ T26] R13: 00007ffc21b0cdff R14: 00007f7bf9150300 R15: 0000000000022000 [ 232.596763][ T26] [ 232.601454][ T26] INFO: task syz-executor.0:8848 blocked for more than 143 seconds. [ 232.614832][ T26] Not tainted 5.16.0-rc1-syzkaller #0 [ 232.622870][ T26] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 232.636595][ T26] task:syz-executor.0 state:D stack:29032 pid: 8848 ppid: 8847 flags:0x00000000 [ 232.647475][ T26] Call Trace: [ 232.655569][ T26] [ 232.658529][ T26] __schedule+0x90d/0x26c0 [ 232.666315][ T26] ? io_schedule_timeout+0x180/0x180 [ 232.676734][ T26] schedule+0xd2/0x260 [ 232.682505][ T26] schedule_timeout+0x19d/0x250 [ 232.687362][ T26] ? usleep_range+0x150/0x150 [ 232.698357][ T26] ? wait_for_completion+0x16c/0x270 [ 232.705546][ T26] ? lock_downgrade+0x6e0/0x6e0 [ 232.718563][ T26] ? do_raw_spin_lock+0x120/0x2b0 [ 232.724253][ T26] ? rwlock_bug.part.0+0x90/0x90 [ 232.735276][ T26] ? lockdep_hardirqs_on_prepare+0x17b/0x400 [ 232.742133][ T26] ? _raw_spin_unlock_irq+0x1f/0x40 [ 232.747470][ T26] wait_for_completion+0x174/0x270 [ 232.759106][ T26] ? percpu_ref_put_many.constprop.0+0x6a/0x1a0 [ 232.765548][ T26] ? bit_wait_io_timeout+0x160/0x160 [ 232.773490][ T26] __x64_sys_io_destroy+0x15a/0x1b0 [ 232.779450][ T26] ? __x64_compat_sys_io_setup+0x180/0x180 [ 232.785419][ T26] ? lockdep_hardirqs_on_prepare+0x17b/0x400 [ 232.793138][ T26] ? syscall_enter_from_user_mode+0x21/0x70 [ 232.801550][ T26] ? lockdep_hardirqs_on+0x79/0x100 [ 232.806956][ T26] do_syscall_64+0x35/0xb0 [ 232.812663][ T26] entry_SYSCALL_64_after_hwframe+0x44/0xae [ 232.819518][ T26] RIP: 0033:0x4665d9 [ 232.823544][ T26] RSP: 002b:00007f79dd9e6188 EFLAGS: 00000246 ORIG_RAX: 00000000000000cf [ 232.833474][ T26] RAX: ffffffffffffffda RBX: 000000000056bf80 RCX: 00000000004665d9 [ 232.843069][ T26] RDX: 0000000000000000 RSI: 0000000000000000 RDI: 00007f79dd9c5000 [ 232.852526][ T26] RBP: 00000000004bfcb9 R08: 0000000000000000 R09: 0000000000000000 [ 232.861863][ T26] R10: 0000000000000000 R11: 0000000000000246 R12: 000000000056bf80 [ 232.872777][ T26] R13: 00007fff8661c49f R14: 00007f79dd9e6300 R15: 0000000000022000 [ 232.882068][ T26] [ 232.885239][ T26] INFO: task syz-executor.4:8852 blocked for more than 143 seconds. [ 232.894412][ T26] Not tainted 5.16.0-rc1-syzkaller #0 [ 232.901474][ T26] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 232.912391][ T26] task:syz-executor.4 state:D stack:29384 pid: 8852 ppid: 8851 flags:0x00000000 [ 232.923011][ T26] Call Trace: [ 232.926404][ T26] [ 232.931079][ T26] __schedule+0x90d/0x26c0 [ 232.935582][ T26] ? io_schedule_timeout+0x180/0x180 [ 232.942342][ T26] schedule+0xd2/0x260 [ 232.947335][ T26] schedule_timeout+0x19d/0x250 [ 232.956650][ T26] ? usleep_range+0x150/0x150 [ 232.962555][ T26] ? wait_for_completion+0x16c/0x270 [ 232.967963][ T26] ? lock_downgrade+0x6e0/0x6e0 [ 232.975607][ T26] ? do_raw_spin_lock+0x120/0x2b0 [ 232.984273][ T26] ? rwlock_bug.part.0+0x90/0x90 [ 232.990680][ T26] ? lockdep_hardirqs_on_prepare+0x17b/0x400 [ 232.997302][ T26] ? _raw_spin_unlock_irq+0x1f/0x40 [ 233.003780][ T26] wait_for_completion+0x174/0x270 [ 233.009947][ T26] ? percpu_ref_put_many.constprop.0+0x6a/0x1a0 [ 233.016586][ T26] ? bit_wait_io_timeout+0x160/0x160 [ 233.024747][ T26] __x64_sys_io_destroy+0x15a/0x1b0 [ 233.038732][ T26] ? __x64_compat_sys_io_setup+0x180/0x180 [ 233.048244][ T26] ? lockdep_hardirqs_on_prepare+0x17b/0x400 [ 233.057953][ T26] ? syscall_enter_from_user_mode+0x21/0x70 [ 233.065949][ T26] ? lockdep_hardirqs_on+0x79/0x100 [ 233.074105][ T26] do_syscall_64+0x35/0xb0 [ 233.083486][ T26] entry_SYSCALL_64_after_hwframe+0x44/0xae [ 233.090606][ T26] RIP: 0033:0x4665d9 [ 233.095305][ T26] RSP: 002b:00007f2bc6fe0188 EFLAGS: 00000246 ORIG_RAX: 00000000000000cf [ 233.111386][ T26] RAX: ffffffffffffffda RBX: 000000000056bf80 RCX: 00000000004665d9 [ 233.121852][ T26] RDX: 0000000000000000 RSI: 0000000000000000 RDI: 00007f2bc6fbf000 [ 233.150073][ T26] RBP: 00000000004bfcb9 R08: 0000000000000000 R09: 0000000000000000 [ 233.161034][ T26] R10: 0000000000000000 R11: 0000000000000246 R12: 000000000056bf80 [ 233.177370][ T26] R13: 00007ffc117a839f R14: 00007f2bc6fe0300 R15: 0000000000022000 [ 233.186841][ T26] [ 233.201030][ T26] INFO: task syz-executor.1:8858 blocked for more than 144 seconds. [ 233.220885][ T26] Not tainted 5.16.0-rc1-syzkaller #0 [ 233.227011][ T26] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 233.242995][ T26] task:syz-executor.1 state:D stack:29728 pid: 8858 ppid: 8857 flags:0x00000000 [ 233.255100][ T26] Call Trace: [ 233.263859][ T26] [ 233.267380][ T26] __schedule+0x90d/0x26c0 [ 233.282991][ T26] ? io_schedule_timeout+0x180/0x180 [ 233.303744][ T26] schedule+0xd2/0x260 [ 233.315338][ T26] schedule_timeout+0x19d/0x250 [ 233.332064][ T26] ? usleep_range+0x150/0x150 [ 233.352417][ T26] ? wait_for_completion+0x16c/0x270 [ 233.358006][ T26] ? lock_downgrade+0x6e0/0x6e0 [ 233.369957][ T26] ? do_raw_spin_lock+0x120/0x2b0 [ 233.376828][ T26] ? rwlock_bug.part.0+0x90/0x90 [ 233.387452][ T26] ? lockdep_hardirqs_on_prepare+0x17b/0x400 [ 233.396141][ T26] ? _raw_spin_unlock_irq+0x1f/0x40 [ 233.405311][ T26] wait_for_completion+0x174/0x270 [ 233.413081][ T26] ? percpu_ref_put_many.constprop.0+0x6a/0x1a0 [ 233.423721][ T26] ? bit_wait_io_timeout+0x160/0x160 [ 233.433109][ T26] __x64_sys_io_destroy+0x15a/0x1b0 [ 233.438396][ T26] ? __x64_compat_sys_io_setup+0x180/0x180 [ 233.451453][ T26] ? lockdep_hardirqs_on_prepare+0x17b/0x400 [ 233.457493][ T26] ? syscall_enter_from_user_mode+0x21/0x70 [ 233.466199][ T26] ? lockdep_hardirqs_on+0x79/0x100 [ 233.475528][ T26] do_syscall_64+0x35/0xb0 [ 233.484889][ T26] entry_SYSCALL_64_after_hwframe+0x44/0xae [ 233.491110][ T26] RIP: 0033:0x4665d9 [ 233.495229][ T26] RSP: 002b:00007fea52f47188 EFLAGS: 00000246 ORIG_RAX: 00000000000000cf [ 233.503941][ T26] RAX: ffffffffffffffda RBX: 000000000056bf80 RCX: 00000000004665d9 [ 233.512350][ T26] RDX: 0000000000000000 RSI: 0000000000000000 RDI: 00007fea52f26000 [ 233.521598][ T26] RBP: 00000000004bfcb9 R08: 0000000000000000 R09: 0000000000000000 [ 233.530324][ T26] R10: 0000000000000000 R11: 0000000000000246 R12: 000000000056bf80 [ 233.538291][ T26] R13: 00007ffdb9ecf3bf R14: 00007fea52f47300 R15: 0000000000022000 [ 233.547218][ T26] [ 233.551233][ T26] INFO: task syz-executor.2:8870 blocked for more than 144 seconds. [ 233.560516][ T26] Not tainted 5.16.0-rc1-syzkaller #0 [ 233.566645][ T26] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 233.576300][ T26] task:syz-executor.2 state:D stack:29848 pid: 8870 ppid: 8869 flags:0x00000000 [ 233.587575][ T26] Call Trace: [ 233.595144][ T26] [ 233.598168][ T26] __schedule+0x90d/0x26c0 [ 233.605801][ T26] ? io_schedule_timeout+0x180/0x180 [ 233.614055][ T26] schedule+0xd2/0x260 [ 233.618257][ T26] schedule_timeout+0x19d/0x250 [ 233.626398][ T26] ? usleep_range+0x150/0x150 [ 233.634692][ T26] ? wait_for_completion+0x16c/0x270 [ 233.640614][ T26] ? lock_downgrade+0x6e0/0x6e0 [ 233.645713][ T26] ? do_raw_spin_lock+0x120/0x2b0 [ 233.651325][ T26] ? rwlock_bug.part.0+0x90/0x90 [ 233.656469][ T26] ? lockdep_hardirqs_on_prepare+0x17b/0x400 [ 233.663465][ T26] ? _raw_spin_unlock_irq+0x1f/0x40 [ 233.670309][ T26] wait_for_completion+0x174/0x270 [ 233.675672][ T26] ? percpu_ref_put_many.constprop.0+0x6a/0x1a0 [ 233.683455][ T26] ? bit_wait_io_timeout+0x160/0x160 [ 233.690826][ T26] __x64_sys_io_destroy+0x15a/0x1b0 [ 233.696349][ T26] ? __x64_compat_sys_io_setup+0x180/0x180 [ 233.705217][ T26] ? lockdep_hardirqs_on_prepare+0x17b/0x400 [ 233.712825][ T26] ? syscall_enter_from_user_mode+0x21/0x70 [ 233.720003][ T26] ? lockdep_hardirqs_on+0x79/0x100 [ 233.725371][ T26] do_syscall_64+0x35/0xb0 [ 233.731391][ T26] entry_SYSCALL_64_after_hwframe+0x44/0xae [ 233.737708][ T26] RIP: 0033:0x4665d9 [ 233.743145][ T26] RSP: 002b:00007f89eca2d188 EFLAGS: 00000246 ORIG_RAX: 00000000000000cf [ 233.753432][ T26] RAX: ffffffffffffffda RBX: 000000000056bf80 RCX: 00000000004665d9 [ 233.762851][ T26] RDX: 0000000000000000 RSI: 0000000000000000 RDI: 00007f89eca0c000 [ 233.771697][ T26] RBP: 00000000004bfcb9 R08: 0000000000000000 R09: 0000000000000000 [ 233.780586][ T26] R10: 0000000000000000 R11: 0000000000000246 R12: 000000000056bf80 [ 233.789201][ T26] R13: 00007fff067820af R14: 00007f89eca2d300 R15: 0000000000022000 [ 233.802599][ T26] [ 233.805858][ T26] INFO: task syz-executor.0:8889 blocked for more than 144 seconds. [ 233.818219][ T26] Not tainted 5.16.0-rc1-syzkaller #0 [ 233.828341][ T26] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 233.841518][ T26] task:syz-executor.0 state:D stack:29848 pid: 8889 ppid: 8888 flags:0x00000000 [ 233.851553][ T26] Call Trace: [ 233.855313][ T26] [ 233.858937][ T26] __schedule+0x90d/0x26c0 [ 233.863584][ T26] ? io_schedule_timeout+0x180/0x180 [ 233.869692][ T26] schedule+0xd2/0x260 [ 233.873966][ T26] schedule_timeout+0x19d/0x250 [ 233.879077][ T26] ? usleep_range+0x150/0x150 [ 233.884017][ T26] ? wait_for_completion+0x16c/0x270 [ 233.889773][ T26] ? lock_downgrade+0x6e0/0x6e0 [ 233.897877][ T26] ? do_raw_spin_lock+0x120/0x2b0 [ 233.903643][ T26] ? rwlock_bug.part.0+0x90/0x90 [ 233.913677][ T26] ? lockdep_hardirqs_on_prepare+0x17b/0x400 [ 233.927786][ T26] ? _raw_spin_unlock_irq+0x1f/0x40 [ 233.948130][ T26] wait_for_completion+0x174/0x270 [ 233.953866][ T26] ? percpu_ref_put_many.constprop.0+0x6a/0x1a0 [ 233.960782][ T26] ? bit_wait_io_timeout+0x160/0x160 [ 233.967434][ T26] __x64_sys_io_destroy+0x15a/0x1b0 [ 233.973568][ T26] ? __x64_compat_sys_io_setup+0x180/0x180 [ 233.979864][ T26] ? lockdep_hardirqs_on_prepare+0x17b/0x400 [ 233.995501][ T26] ? syscall_enter_from_user_mode+0x21/0x70 [ 234.016863][ T26] ? lockdep_hardirqs_on+0x79/0x100 [ 234.026122][ T26] do_syscall_64+0x35/0xb0 [ 234.032416][ T26] entry_SYSCALL_64_after_hwframe+0x44/0xae [ 234.040574][ T26] RIP: 0033:0x4665d9 [ 234.044827][ T26] RSP: 002b:00007f79dd9e6188 EFLAGS: 00000246 ORIG_RAX: 00000000000000cf [ 234.054750][ T26] RAX: ffffffffffffffda RBX: 000000000056bf80 RCX: 00000000004665d9 [ 234.068610][ T26] RDX: 0000000000000000 RSI: 0000000000000000 RDI: 00007f79dd9c5000 [ 234.095791][ T26] RBP: 00000000004bfcb9 R08: 0000000000000000 R09: 0000000000000000 [ 234.106083][ T26] R10: 0000000000000000 R11: 0000000000000246 R12: 000000000056bf80 [ 234.134249][ T26] R13: 00007fff8661c49f R14: 00007f79dd9e6300 R15: 0000000000022000 [ 234.149489][ T26] [ 234.152727][ T26] INFO: task syz-executor.4:8901 blocked for more than 145 seconds. [ 234.170893][ T26] Not tainted 5.16.0-rc1-syzkaller #0 [ 234.181059][ T26] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 234.190485][ T26] task:syz-executor.4 state:D stack:29848 pid: 8901 ppid: 8899 flags:0x00000000 [ 234.202365][ T26] Call Trace: [ 234.205759][ T26] [ 234.209086][ T26] __schedule+0x90d/0x26c0 [ 234.213720][ T26] ? io_schedule_timeout+0x180/0x180 [ 234.219571][ T26] schedule+0xd2/0x260 [ 234.223870][ T26] schedule_timeout+0x19d/0x250 [ 234.229964][ T26] ? usleep_range+0x150/0x150 [ 234.234801][ T26] ? wait_for_completion+0x16c/0x270 [ 234.240778][ T26] ? lock_downgrade+0x6e0/0x6e0 [ 234.245828][ T26] ? do_raw_spin_lock+0x120/0x2b0 [ 234.251215][ T26] ? rwlock_bug.part.0+0x90/0x90 [ 234.256159][ T26] ? lockdep_hardirqs_on_prepare+0x17b/0x400 [ 234.262635][ T26] ? _raw_spin_unlock_irq+0x1f/0x40 [ 234.267923][ T26] wait_for_completion+0x174/0x270 [ 234.274469][ T26] ? percpu_ref_put_many.constprop.0+0x6a/0x1a0 [ 234.281290][ T26] ? bit_wait_io_timeout+0x160/0x160 [ 234.286766][ T26] __x64_sys_io_destroy+0x15a/0x1b0 [ 234.292448][ T26] ? __x64_compat_sys_io_setup+0x180/0x180 [ 234.298267][ T26] ? lockdep_hardirqs_on_prepare+0x17b/0x400 [ 234.304988][ T26] ? syscall_enter_from_user_mode+0x21/0x70 [ 234.311584][ T26] ? lockdep_hardirqs_on+0x79/0x100 [ 234.317029][ T26] do_syscall_64+0x35/0xb0 [ 234.321902][ T26] entry_SYSCALL_64_after_hwframe+0x44/0xae [ 234.327796][ T26] RIP: 0033:0x4665d9 [ 234.332199][ T26] RSP: 002b:00007f2bc6fe0188 EFLAGS: 00000246 ORIG_RAX: 00000000000000cf [ 234.341215][ T26] RAX: ffffffffffffffda RBX: 000000000056bf80 RCX: 00000000004665d9 [ 234.349553][ T26] RDX: 0000000000000000 RSI: 0000000000000000 RDI: 00007f2bc6fbf000 [ 234.357553][ T26] RBP: 00000000004bfcb9 R08: 0000000000000000 R09: 0000000000000000 [ 234.365875][ T26] R10: 0000000000000000 R11: 0000000000000246 R12: 000000000056bf80 [ 234.374043][ T26] R13: 00007ffc117a839f R14: 00007f2bc6fe0300 R15: 0000000000022000 [ 234.383567][ T26] [ 234.386726][ T26] INFO: task syz-executor.2:8920 blocked for more than 145 seconds. [ 234.395862][ T26] Not tainted 5.16.0-rc1-syzkaller #0 [ 234.402926][ T26] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 234.413303][ T26] task:syz-executor.2 state:D stack:29848 pid: 8920 ppid: 8919 flags:0x00000000 [ 234.422897][ T26] Call Trace: [ 234.426278][ T26] [ 234.429793][ T26] __schedule+0x90d/0x26c0 [ 234.434313][ T26] ? io_schedule_timeout+0x180/0x180 [ 234.439925][ T26] schedule+0xd2/0x260 [ 234.444097][ T26] schedule_timeout+0x19d/0x250 [ 234.449698][ T26] ? usleep_range+0x150/0x150 [ 234.454483][ T26] ? wait_for_completion+0x16c/0x270 [ 234.460476][ T26] ? lock_downgrade+0x6e0/0x6e0 [ 234.465511][ T26] ? do_raw_spin_lock+0x120/0x2b0 [ 234.471088][ T26] ? rwlock_bug.part.0+0x90/0x90 [ 234.476150][ T26] ? lockdep_hardirqs_on_prepare+0x17b/0x400 [ 234.484548][ T26] ? _raw_spin_unlock_irq+0x1f/0x40 [ 234.490705][ T26] wait_for_completion+0x174/0x270 [ 234.495933][ T26] ? percpu_ref_put_many.constprop.0+0x6a/0x1a0 [ 234.502521][ T26] ? bit_wait_io_timeout+0x160/0x160 [ 234.508218][ T26] __x64_sys_io_destroy+0x15a/0x1b0 [ 234.514181][ T26] ? __x64_compat_sys_io_setup+0x180/0x180 [ 234.520639][ T26] ? lockdep_hardirqs_on_prepare+0x17b/0x400 [ 234.526721][ T26] ? syscall_enter_from_user_mode+0x21/0x70 [ 234.532872][ T26] ? lockdep_hardirqs_on+0x79/0x100 [ 234.538220][ T26] do_syscall_64+0x35/0xb0 [ 234.542904][ T26] entry_SYSCALL_64_after_hwframe+0x44/0xae [ 234.549848][ T26] RIP: 0033:0x4665d9 [ 234.553886][ T26] RSP: 002b:00007f89eca2d188 EFLAGS: 00000246 ORIG_RAX: 00000000000000cf [ 234.564135][ T26] RAX: ffffffffffffffda RBX: 000000000056bf80 RCX: 00000000004665d9 [ 234.573429][ T26] RDX: 0000000000000000 RSI: 0000000000000000 RDI: 00007f89eca0c000 [ 234.582135][ T26] RBP: 00000000004bfcb9 R08: 0000000000000000 R09: 0000000000000000 [ 234.590522][ T26] R10: 0000000000000000 R11: 0000000000000246 R12: 000000000056bf80 [ 234.600384][ T26] R13: 00007fff067820af R14: 00007f89eca2d300 R15: 0000000000022000 [ 234.609850][ T26] [ 234.613132][ T26] INFO: task syz-executor.0:8936 blocked for more than 145 seconds. [ 234.622514][ T26] Not tainted 5.16.0-rc1-syzkaller #0 [ 234.629434][ T26] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 234.638210][ T26] task:syz-executor.0 state:D stack:29848 pid: 8936 ppid: 8935 flags:0x00000000 [ 234.647598][ T26] Call Trace: [ 234.651149][ T26] [ 234.654389][ T26] __schedule+0x90d/0x26c0 [ 234.659732][ T26] ? io_schedule_timeout+0x180/0x180 [ 234.665266][ T26] schedule+0xd2/0x260 [ 234.670728][ T26] schedule_timeout+0x19d/0x250 [ 234.675763][ T26] ? usleep_range+0x150/0x150 [ 234.682307][ T26] ? wait_for_completion+0x16c/0x270 [ 234.687805][ T26] ? lock_downgrade+0x6e0/0x6e0 [ 234.693622][ T26] ? do_raw_spin_lock+0x120/0x2b0 [ 234.699604][ T26] ? rwlock_bug.part.0+0x90/0x90 [ 234.704810][ T26] ? lockdep_hardirqs_on_prepare+0x17b/0x400 [ 234.715111][ T26] ? _raw_spin_unlock_irq+0x1f/0x40 [ 234.721346][ T26] wait_for_completion+0x174/0x270 [ 234.726687][ T26] ? percpu_ref_put_many.constprop.0+0x6a/0x1a0 [ 234.734024][ T26] ? bit_wait_io_timeout+0x160/0x160 [ 234.742874][ T26] __x64_sys_io_destroy+0x15a/0x1b0 [ 234.748740][ T26] ? __x64_compat_sys_io_setup+0x180/0x180 [ 234.754836][ T26] ? lockdep_hardirqs_on_prepare+0x17b/0x400 [ 234.761670][ T26] ? syscall_enter_from_user_mode+0x21/0x70 [ 234.767944][ T26] ? lockdep_hardirqs_on+0x79/0x100 [ 234.774114][ T26] do_syscall_64+0x35/0xb0 [ 234.779098][ T26] entry_SYSCALL_64_after_hwframe+0x44/0xae [ 234.785592][ T26] RIP: 0033:0x4665d9 [ 234.790863][ T26] RSP: 002b:00007f79dd9e6188 EFLAGS: 00000246 ORIG_RAX: 00000000000000cf [ 234.807262][ T26] RAX: ffffffffffffffda RBX: 000000000056bf80 RCX: 00000000004665d9 [ 234.817855][ T26] RDX: 0000000000000000 RSI: 0000000000000000 RDI: 00007f79dd9c5000 [ 234.835014][ T26] RBP: 00000000004bfcb9 R08: 0000000000000000 R09: 0000000000000000 [ 234.845457][ T26] R10: 0000000000000000 R11: 0000000000000246 R12: 000000000056bf80 [ 234.854774][ T26] R13: 00007fff8661c49f R14: 00007f79dd9e6300 R15: 0000000000022000 [ 234.863410][ T26] [ 234.866565][ T26] INFO: task syz-executor.5:9331 blocked for more than 145 seconds. [ 234.877345][ T26] Not tainted 5.16.0-rc1-syzkaller #0 [ 234.883562][ T26] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 234.892747][ T26] task:syz-executor.5 state:D stack:29728 pid: 9331 ppid: 9330 flags:0x00000000 [ 234.902627][ T26] Call Trace: [ 234.905905][ T26] [ 234.909000][ T26] __schedule+0x90d/0x26c0 [ 234.913508][ T26] ? io_schedule_timeout+0x180/0x180 [ 234.919404][ T26] schedule+0xd2/0x260 [ 234.923495][ T26] schedule_timeout+0x19d/0x250 [ 234.930220][ T26] ? usleep_range+0x150/0x150 [ 234.934899][ T26] ? wait_for_completion+0x16c/0x270 [ 234.940255][ T26] ? lock_downgrade+0x6e0/0x6e0 [ 234.945106][ T26] ? do_raw_spin_lock+0x120/0x2b0 [ 234.950802][ T26] ? rwlock_bug.part.0+0x90/0x90 [ 234.955752][ T26] ? lockdep_hardirqs_on_prepare+0x17b/0x400 [ 234.962008][ T26] ? _raw_spin_unlock_irq+0x1f/0x40 [ 234.967522][ T26] wait_for_completion+0x174/0x270 [ 234.972719][ T26] ? percpu_ref_put_many.constprop.0+0x6a/0x1a0 [ 234.979143][ T26] ? bit_wait_io_timeout+0x160/0x160 [ 234.984458][ T26] __x64_sys_io_destroy+0x15a/0x1b0 [ 234.989850][ T26] ? __x64_compat_sys_io_setup+0x180/0x180 [ 234.995675][ T26] ? lockdep_hardirqs_on_prepare+0x17b/0x400 [ 235.002011][ T26] ? syscall_enter_from_user_mode+0x21/0x70 [ 235.008024][ T26] ? lockdep_hardirqs_on+0x79/0x100 [ 235.013293][ T26] do_syscall_64+0x35/0xb0 [ 235.017786][ T26] entry_SYSCALL_64_after_hwframe+0x44/0xae [ 235.024108][ T26] RIP: 0033:0x4665d9 [ 235.028097][ T26] RSP: 002b:00007f6449283188 EFLAGS: 00000246 ORIG_RAX: 00000000000000cf [ 235.036958][ T26] RAX: ffffffffffffffda RBX: 000000000056bf80 RCX: 00000000004665d9 [ 235.046977][ T26] RDX: 0000000000000000 RSI: 0000000000000000 RDI: 00007f6449262000 [ 235.055116][ T26] RBP: 00000000004bfcb9 R08: 0000000000000000 R09: 0000000000000000 [ 235.063160][ T26] R10: 0000000000000000 R11: 0000000000000246 R12: 000000000056bf80 [ 235.071643][ T26] R13: 00007ffe2a7caf7f R14: 00007f6449283300 R15: 0000000000022000 [ 235.079778][ T26] [ 235.083532][ T26] [ 235.083532][ T26] Showing all locks held in the system: [ 235.092615][ T26] 2 locks held by ksoftirqd/1/19: [ 235.097883][ T26] #0: ffff8880b9f39a58 (&rq->__lock){-.-.}-{2:2}, at: raw_spin_rq_lock_nested+0x1e/0x30 [ 235.110167][ T26] #1: ffff8880b9f279c8 (&per_cpu_ptr(group->pcpu, cpu)->seq){-.-.}-{0:0}, at: psi_task_switch+0x173/0x490 [ 235.124071][ T26] 1 lock held by khungtaskd/26: [ 235.131218][ T26] #0: ffffffff8ad787c0 (rcu_read_lock){....}-{1:2}, at: debug_show_all_locks+0x53/0x260 [ 235.141812][ T26] 1 lock held by in:imklog/6267: [ 235.146823][ T26] #0: ffff888021ff0ff0 (&f->f_pos_lock){+.+.}-{3:3}, at: __fdget_pos+0x9c/0xb0 [ 235.157086][ T26] [ 235.159483][ T26] ============================================= [ 235.159483][ T26] [ 235.168021][ T26] NMI backtrace for cpu 1 [ 235.172574][ T26] CPU: 1 PID: 26 Comm: khungtaskd Not tainted 5.16.0-rc1-syzkaller #0 [ 235.180698][ T26] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 [ 235.190727][ T26] Call Trace: [ 235.193986][ T26] [ 235.196896][ T26] dump_stack_lvl+0x57/0x7d [ 235.201430][ T26] nmi_cpu_backtrace.cold+0x30/0xc0 [ 235.206615][ T26] ? lapic_can_unplug_cpu+0x80/0x80 [ 235.211794][ T26] nmi_trigger_cpumask_backtrace+0x11a/0x160 [ 235.217896][ T26] watchdog+0x88c/0xbf0 [ 235.222165][ T26] ? reset_hung_task_detector+0x20/0x20 [ 235.227788][ T26] kthread+0x3ab/0x480 [ 235.231837][ T26] ? _raw_spin_unlock_irq+0x1f/0x40 [ 235.237108][ T26] ? set_kthread_struct+0x100/0x100 [ 235.242293][ T26] ret_from_fork+0x1f/0x30 [ 235.246694][ T26] [ 235.250229][ T26] Sending NMI from CPU 1 to CPUs 0: [ 235.255523][ C0] NMI backtrace for cpu 0 [ 235.255528][ C0] CPU: 0 PID: 994 Comm: kworker/u4:4 Not tainted 5.16.0-rc1-syzkaller #0 [ 235.255535][ C0] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 [ 235.255539][ C0] Workqueue: bat_events batadv_nc_worker [ 235.255613][ C0] RIP: 0010:match_held_lock+0xe/0xc0 [ 235.255621][ C0] Code: 48 c7 c7 60 56 eb 88 e8 7e 6a be ff e8 b8 1b cf ff 31 c0 5d c3 0f 1f 80 00 00 00 00 53 48 89 fb 48 83 ec 08 48 39 77 10 74 6a <66> f7 47 22 f0 ff 74 5a 48 8b 46 08 48 89 f7 48 85 c0 74 42 8b 15 [ 235.255626][ C0] RSP: 0018:ffffc90004a67b30 EFLAGS: 00000087 [ 235.255630][ C0] RAX: 0000000000000005 RBX: ffff88801a80c3c8 RCX: 0000000000000001 [ 235.255634][ C0] RDX: 0000000000000000 RSI: ffffffff8ad78700 RDI: ffff88801a80c3c8 [ 235.255637][ C0] RBP: ffffffff8ad78700 R08: 0000000000000000 R09: ffffffff8ca1d297 [ 235.255640][ C0] R10: fffffbfff1943a52 R11: 0000000000000001 R12: ffff88801a80b980 [ 235.255643][ C0] R13: ffff88801a80c3a0 R14: 00000000ffffffff R15: ffff88801a80c3c8 [ 235.255647][ C0] FS: 0000000000000000(0000) GS:ffff8880b9e00000(0000) knlGS:0000000000000000 [ 235.255654][ C0] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 [ 235.255657][ C0] CR2: 00007fd80cdf4000 CR3: 0000000071f2e000 CR4: 00000000003506f0 [ 235.255661][ C0] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000 [ 235.255663][ C0] DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400 [ 235.255666][ C0] Call Trace: [ 235.255669][ C0] [ 235.255672][ C0] lock_is_held_type+0xa7/0x140 [ 235.255678][ C0] rcu_read_lock_sched_held+0x3a/0x70 [ 235.255685][ C0] lock_acquire+0x442/0x510 [ 235.255692][ C0] ? lock_release+0x720/0x720 [ 235.255696][ C0] ? batadv_nc_worker+0x650/0xd70 [ 235.255701][ C0] ? lock_downgrade+0x6e0/0x6e0 [ 235.255709][ C0] batadv_nc_worker+0x108/0xd70 [ 235.255713][ C0] ? batadv_nc_worker+0xd3/0xd70 [ 235.255721][ C0] process_one_work+0x87f/0x1450 [ 235.255728][ C0] ? lock_release+0x720/0x720 [ 235.255733][ C0] ? pwq_dec_nr_in_flight+0x230/0x230 [ 235.255739][ C0] ? rwlock_bug.part.0+0x90/0x90 [ 235.255744][ C0] ? _raw_spin_lock_irq+0x41/0x50 [ 235.255752][ C0] worker_thread+0x598/0x1040 [ 235.255759][ C0] ? process_one_work+0x1450/0x1450 [ 235.255764][ C0] kthread+0x3ab/0x480 [ 235.255769][ C0] ? _raw_spin_unlock_irq+0x1f/0x40 [ 235.255774][ C0] ? set_kthread_struct+0x100/0x100 [ 235.255779][ C0] ret_from_fork+0x1f/0x30 [ 235.255790][ C0] [ 235.256725][ T26] Kernel panic - not syncing: hung_task: blocked tasks [ 235.506660][ T26] CPU: 1 PID: 26 Comm: khungtaskd Not tainted 5.16.0-rc1-syzkaller #0 [ 235.514883][ T26] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 [ 235.524912][ T26] Call Trace: [ 235.528256][ T26] [ 235.531172][ T26] dump_stack_lvl+0x57/0x7d [ 235.535662][ T26] panic+0x214/0x49f [ 235.539553][ T26] ? __warn_printk+0xee/0xee [ 235.544118][ T26] ? lapic_can_unplug_cpu+0x80/0x80 [ 235.549555][ T26] ? preempt_schedule_thunk+0x16/0x18 [ 235.554927][ T26] watchdog.cold+0x111/0x157 [ 235.559545][ T26] ? reset_hung_task_detector+0x20/0x20 [ 235.565081][ T26] kthread+0x3ab/0x480 [ 235.569137][ T26] ? _raw_spin_unlock_irq+0x1f/0x40 [ 235.574321][ T26] ? set_kthread_struct+0x100/0x100 [ 235.579500][ T26] ret_from_fork+0x1f/0x30 [ 235.584000][ T26] [ 235.587230][ T26] Kernel Offset: disabled [ 235.591668][ T26] Rebooting in 86400 seconds..