Warning: Permanently added '10.128.0.130' (ECDSA) to the list of known hosts. 2023/01/04 07:41:38 ignoring optional flag "sandboxArg"="0" 2023/01/04 07:41:38 parsed 1 programs 2023/01/04 07:41:38 executed programs: 0 [ 75.789369][ T4388] Bluetooth: hci0: unexpected cc 0x0c03 length: 249 > 1 [ 75.797410][ T4388] Bluetooth: hci0: unexpected cc 0x1003 length: 249 > 9 [ 75.805337][ T4388] Bluetooth: hci0: unexpected cc 0x1001 length: 249 > 9 [ 75.813317][ T4388] Bluetooth: hci0: unexpected cc 0x0c23 length: 249 > 4 [ 75.821659][ T4388] Bluetooth: hci0: unexpected cc 0x0c25 length: 249 > 3 [ 75.828981][ T4388] Bluetooth: hci0: unexpected cc 0x0c38 length: 249 > 2 [ 75.936237][ T5534] chnl_net:caif_netlink_parms(): no params data found [ 75.976641][ T5534] bridge0: port 1(bridge_slave_0) entered blocking state [ 75.984045][ T5534] bridge0: port 1(bridge_slave_0) entered disabled state [ 75.992152][ T5534] device bridge_slave_0 entered promiscuous mode [ 76.000272][ T5534] bridge0: port 2(bridge_slave_1) entered blocking state [ 76.008293][ T5534] bridge0: port 2(bridge_slave_1) entered disabled state [ 76.016324][ T5534] device bridge_slave_1 entered promiscuous mode [ 76.036697][ T5534] bond0: (slave bond_slave_0): Enslaving as an active interface with an up link [ 76.047894][ T5534] bond0: (slave bond_slave_1): Enslaving as an active interface with an up link [ 76.070999][ T5534] team0: Port device team_slave_0 added [ 76.078711][ T5534] team0: Port device team_slave_1 added [ 76.097626][ T5534] batman_adv: batadv0: Adding interface: batadv_slave_0 [ 76.105835][ T5534] batman_adv: batadv0: The MTU of interface batadv_slave_0 is too small (1500) to handle the transport of batman-adv packets. Packets going over this interface will be fragmented on layer2 which could impact the performance. Setting the MTU to 1560 would solve the problem. [ 76.132794][ T5534] batman_adv: batadv0: Not using interface batadv_slave_0 (retrying later): interface not active [ 76.146082][ T5534] batman_adv: batadv0: Adding interface: batadv_slave_1 [ 76.153132][ T5534] batman_adv: batadv0: The MTU of interface batadv_slave_1 is too small (1500) to handle the transport of batman-adv packets. Packets going over this interface will be fragmented on layer2 which could impact the performance. Setting the MTU to 1560 would solve the problem. [ 76.180639][ T5534] batman_adv: batadv0: Not using interface batadv_slave_1 (retrying later): interface not active [ 76.209564][ T5534] device hsr_slave_0 entered promiscuous mode [ 76.216242][ T5534] device hsr_slave_1 entered promiscuous mode [ 76.376780][ T2198] cfg80211: failed to load regulatory.db [ 76.732942][ T5534] netdevsim netdevsim0 netdevsim0: renamed from eth0 [ 76.743239][ T5534] netdevsim netdevsim0 netdevsim1: renamed from eth1 [ 76.754601][ T5534] netdevsim netdevsim0 netdevsim2: renamed from eth2 [ 76.764849][ T5534] netdevsim netdevsim0 netdevsim3: renamed from eth3 [ 76.790021][ T5534] bridge0: port 2(bridge_slave_1) entered blocking state [ 76.797218][ T5534] bridge0: port 2(bridge_slave_1) entered forwarding state [ 76.804631][ T5534] bridge0: port 1(bridge_slave_0) entered blocking state [ 76.812087][ T5534] bridge0: port 1(bridge_slave_0) entered forwarding state [ 76.873708][ T5534] 8021q: adding VLAN 0 to HW filter on device bond0 [ 76.889567][ T7] IPv6: ADDRCONF(NETDEV_CHANGE): veth0: link becomes ready [ 76.898325][ T7] bridge0: port 1(bridge_slave_0) entered disabled state [ 76.915590][ T7] bridge0: port 2(bridge_slave_1) entered disabled state [ 76.927585][ T7] IPv6: ADDRCONF(NETDEV_CHANGE): bond0: link becomes ready [ 76.941951][ T5534] 8021q: adding VLAN 0 to HW filter on device team0 [ 76.955027][ T7] IPv6: ADDRCONF(NETDEV_CHANGE): veth0_to_bridge: link becomes ready [ 76.963840][ T7] IPv6: ADDRCONF(NETDEV_CHANGE): bridge_slave_0: link becomes ready [ 76.973590][ T7] bridge0: port 1(bridge_slave_0) entered blocking state [ 76.980894][ T7] bridge0: port 1(bridge_slave_0) entered forwarding state [ 76.995797][ T7] IPv6: ADDRCONF(NETDEV_CHANGE): veth1_to_bridge: link becomes ready [ 77.004533][ T7] IPv6: ADDRCONF(NETDEV_CHANGE): bridge_slave_1: link becomes ready [ 77.013764][ T7] bridge0: port 2(bridge_slave_1) entered blocking state [ 77.020953][ T7] bridge0: port 2(bridge_slave_1) entered forwarding state [ 77.029434][ T7] IPv6: ADDRCONF(NETDEV_CHANGE): veth0_to_bond: link becomes ready [ 77.046191][ T7] IPv6: ADDRCONF(NETDEV_CHANGE): veth1_to_bond: link becomes ready [ 77.055825][ T7] IPv6: ADDRCONF(NETDEV_CHANGE): veth0_to_team: link becomes ready [ 77.075354][ T7] IPv6: ADDRCONF(NETDEV_CHANGE): team0: link becomes ready [ 77.083369][ T7] IPv6: ADDRCONF(NETDEV_CHANGE): veth1_to_team: link becomes ready [ 77.093620][ T7] IPv6: ADDRCONF(NETDEV_CHANGE): veth0_to_hsr: link becomes ready [ 77.102259][ T7] IPv6: ADDRCONF(NETDEV_CHANGE): hsr_slave_0: link becomes ready [ 77.111394][ T7] IPv6: ADDRCONF(NETDEV_CHANGE): veth1_to_hsr: link becomes ready [ 77.120941][ T7] IPv6: ADDRCONF(NETDEV_CHANGE): hsr_slave_1: link becomes ready [ 77.132348][ T5534] IPv6: ADDRCONF(NETDEV_CHANGE): hsr0: link becomes ready [ 77.347598][ T22] IPv6: ADDRCONF(NETDEV_CHANGE): vxcan1: link becomes ready [ 77.355711][ T22] IPv6: ADDRCONF(NETDEV_CHANGE): vxcan0: link becomes ready [ 77.367157][ T5534] 8021q: adding VLAN 0 to HW filter on device batadv0 [ 77.390193][ T22] IPv6: ADDRCONF(NETDEV_CHANGE): veth1_virt_wifi: link becomes ready [ 77.401102][ T22] IPv6: ADDRCONF(NETDEV_CHANGE): veth0_virt_wifi: link becomes ready [ 77.427133][ T22] IPv6: ADDRCONF(NETDEV_CHANGE): veth1_vlan: link becomes ready [ 77.438471][ T22] IPv6: ADDRCONF(NETDEV_CHANGE): veth0_vlan: link becomes ready [ 77.449102][ T5534] device veth0_vlan entered promiscuous mode [ 77.458946][ T2198] IPv6: ADDRCONF(NETDEV_CHANGE): vlan0: link becomes ready [ 77.467187][ T2198] IPv6: ADDRCONF(NETDEV_CHANGE): vlan1: link becomes ready [ 77.480119][ T5534] device veth1_vlan entered promiscuous mode [ 77.509897][ T22] IPv6: ADDRCONF(NETDEV_CHANGE): macvlan0: link becomes ready [ 77.519412][ T22] IPv6: ADDRCONF(NETDEV_CHANGE): macvlan1: link becomes ready [ 77.529652][ T22] IPv6: ADDRCONF(NETDEV_CHANGE): veth1_macvtap: link becomes ready [ 77.538856][ T22] IPv6: ADDRCONF(NETDEV_CHANGE): veth0_macvtap: link becomes ready [ 77.551391][ T5534] device veth0_macvtap entered promiscuous mode [ 77.563634][ T5534] device veth1_macvtap entered promiscuous mode [ 77.587897][ T5534] batman_adv: batadv0: Interface activated: batadv_slave_0 [ 77.595323][ T22] IPv6: ADDRCONF(NETDEV_CHANGE): macvtap0: link becomes ready [ 77.604092][ T22] IPv6: ADDRCONF(NETDEV_CHANGE): macsec0: link becomes ready [ 77.614600][ T22] IPv6: ADDRCONF(NETDEV_CHANGE): batadv_slave_0: link becomes ready [ 77.624203][ T22] IPv6: ADDRCONF(NETDEV_CHANGE): veth0_to_batadv: link becomes ready [ 77.637540][ T5534] batman_adv: batadv0: Interface activated: batadv_slave_1 [ 77.647993][ T2198] IPv6: ADDRCONF(NETDEV_CHANGE): batadv_slave_1: link becomes ready [ 77.657040][ T2198] IPv6: ADDRCONF(NETDEV_CHANGE): veth1_to_batadv: link becomes ready [ 77.668476][ T5534] netdevsim netdevsim0 netdevsim0: set [1, 0] type 2 family 0 port 6081 - 0 [ 77.678953][ T5534] netdevsim netdevsim0 netdevsim1: set [1, 0] type 2 family 0 port 6081 - 0 [ 77.688573][ T5534] netdevsim netdevsim0 netdevsim2: set [1, 0] type 2 family 0 port 6081 - 0 [ 77.698065][ T5534] netdevsim netdevsim0 netdevsim3: set [1, 0] type 2 family 0 port 6081 - 0 [ 77.769952][ T38] wlan0: Created IBSS using preconfigured BSSID 50:50:50:50:50:50 [ 77.796508][ T38] wlan0: Creating new IBSS network, BSSID 50:50:50:50:50:50 [ 77.806916][ T68] wlan1: Created IBSS using preconfigured BSSID 50:50:50:50:50:50 [ 77.807726][ T7] IPv6: ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready [ 77.814844][ T68] wlan1: Creating new IBSS network, BSSID 50:50:50:50:50:50 [ 77.839008][ T2198] IPv6: ADDRCONF(NETDEV_CHANGE): wlan1: link becomes ready [ 77.895825][ T5074] Bluetooth: hci0: command 0x0409 tx timeout [ 78.266991][ T5589] loop0: detected capacity change from 0 to 32768 [ 78.281694][ T5589] gfs2: fsid=loop0: Trying to join cluster "lock_nolock", "loop0" [ 78.291435][ T5589] gfs2: fsid=loop0: Now mounting FS (format 1801)... [ 78.311533][ T5589] gfs2: fsid=loop0.0: journal 0 mapped with 5 extents in 0ms [ 78.323846][ T2198] gfs2: fsid=loop0.0: jid=0, already locked for use [ 78.331202][ T2198] gfs2: fsid=loop0.0: jid=0: Looking at journal... [ 78.383663][ T2198] kworker/1:2: attempt to access beyond end of device [ 78.383663][ T2198] loop0: rw=0, sector=507904, nr_sectors = 8 limit=32768 [ 78.401282][ T2198] gfs2: fsid=loop0.0: jid=0: Failed [ 78.408688][ T5589] gfs2: fsid=loop0.0: error recovering journal 0: -5 [ 78.980398][ T5610] loop0: detected capacity change from 0 to 32768 [ 78.997504][ T5610] gfs2: fsid=loop0: Trying to join cluster "lock_nolock", "loop0" [ 79.007894][ T5610] gfs2: fsid=loop0: Now mounting FS (format 1801)... [ 79.030875][ T5610] gfs2: fsid=loop0.0: journal 0 mapped with 5 extents in 0ms [ 79.040293][ T2198] gfs2: fsid=loop0.0: jid=0, already locked for use [ 79.048402][ T2198] gfs2: fsid=loop0.0: jid=0: Looking at journal... [ 79.091658][ T2198] kworker/1:2: attempt to access beyond end of device [ 79.091658][ T2198] loop0: rw=0, sector=507904, nr_sectors = 8 limit=32768 [ 79.110785][ T2198] gfs2: fsid=loop0.0: jid=0: Failed [ 79.116854][ T5610] gfs2: fsid=loop0.0: error recovering journal 0: -5 [ 79.738601][ T5632] loop0: detected capacity change from 0 to 32768 [ 79.749116][ T5632] gfs2: fsid=loop0: Trying to join cluster "lock_nolock", "loop0" [ 79.758857][ T5632] gfs2: fsid=loop0: Now mounting FS (format 1801)... [ 79.769735][ T5632] gfs2: fsid=loop0.0: journal 0 mapped with 5 extents in 0ms [ 79.785255][ T2198] gfs2: fsid=loop0.0: jid=0, already locked for use [ 79.791897][ T2198] gfs2: fsid=loop0.0: jid=0: Looking at journal... [ 79.840358][ T2198] kworker/1:2: attempt to access beyond end of device [ 79.840358][ T2198] loop0: rw=0, sector=507904, nr_sectors = 8 limit=32768 [ 79.858660][ T2198] gfs2: fsid=loop0.0: jid=0: Failed [ 79.867824][ T5632] gfs2: fsid=loop0.0: error recovering journal 0: -5 [ 79.965040][ T5074] Bluetooth: hci0: command 0x041b tx timeout [ 80.492902][ T5654] loop0: detected capacity change from 0 to 32768 [ 80.503009][ T5654] gfs2: fsid=loop0: Trying to join cluster "lock_nolock", "loop0" [ 80.512581][ T5654] gfs2: fsid=loop0: Now mounting FS (format 1801)... [ 80.523548][ T5654] gfs2: fsid=loop0.0: journal 0 mapped with 5 extents in 0ms [ 80.534094][ T5083] gfs2: fsid=loop0.0: jid=0, already locked for use [ 80.540937][ T5083] gfs2: fsid=loop0.0: jid=0: Looking at journal... [ 80.607191][ T5083] kworker/0:3: attempt to access beyond end of device [ 80.607191][ T5083] loop0: rw=0, sector=507904, nr_sectors = 8 limit=32768 [ 80.626674][ T5083] gfs2: fsid=loop0.0: jid=0: Failed [ 80.632298][ T5654] gfs2: fsid=loop0.0: error recovering journal 0: -5 2023/01/04 07:41:43 executed programs: 4 [ 81.203983][ T5677] loop0: detected capacity change from 0 to 32768 [ 81.217285][ T5677] gfs2: fsid=loop0: Trying to join cluster "lock_nolock", "loop0" [ 81.226526][ T5677] gfs2: fsid=loop0: Now mounting FS (format 1801)... [ 81.238159][ T5677] gfs2: fsid=loop0.0: journal 0 mapped with 5 extents in 0ms [ 81.257132][ T22] gfs2: fsid=loop0.0: jid=0, already locked for use [ 81.263797][ T22] gfs2: fsid=loop0.0: jid=0: Looking at journal... [ 81.322421][ T22] kworker/1:0: attempt to access beyond end of device [ 81.322421][ T22] loop0: rw=0, sector=507904, nr_sectors = 8 limit=32768 [ 81.343095][ T22] gfs2: fsid=loop0.0: jid=0: Failed [ 81.350900][ T5677] gfs2: fsid=loop0.0: error recovering journal 0: -5 [ 81.898436][ T5698] loop0: detected capacity change from 0 to 32768 [ 81.908768][ T5698] gfs2: fsid=loop0: Trying to join cluster "lock_nolock", "loop0" [ 81.917357][ T5698] gfs2: fsid=loop0: Now mounting FS (format 1801)... [ 81.929477][ T5698] gfs2: fsid=loop0.0: journal 0 mapped with 5 extents in 0ms [ 81.941413][ T7] gfs2: fsid=loop0.0: jid=0, already locked for use [ 81.949773][ T7] gfs2: fsid=loop0.0: jid=0: Looking at journal... [ 81.999822][ T7] kworker/0:0: attempt to access beyond end of device [ 81.999822][ T7] loop0: rw=0, sector=507904, nr_sectors = 8 limit=32768 [ 82.019220][ T7] gfs2: fsid=loop0.0: jid=0: Failed [ 82.028277][ T5698] gfs2: fsid=loop0.0: error recovering journal 0: -5 [ 82.045491][ T5074] Bluetooth: hci0: command 0x040f tx timeout [ 82.547692][ T5717] loop0: detected capacity change from 0 to 32768 [ 82.558366][ T5717] gfs2: fsid=loop0: Trying to join cluster "lock_nolock", "loop0" [ 82.568139][ T5717] gfs2: fsid=loop0: Now mounting FS (format 1801)... [ 82.578747][ T5717] gfs2: fsid=loop0.0: journal 0 mapped with 5 extents in 0ms [ 82.589176][ T898] gfs2: fsid=loop0.0: jid=0, already locked for use [ 82.596243][ T898] gfs2: fsid=loop0.0: jid=0: Looking at journal... [ 82.649681][ T898] kworker/0:2: attempt to access beyond end of device [ 82.649681][ T898] loop0: rw=0, sector=507904, nr_sectors = 8 limit=32768 [ 82.668671][ T898] gfs2: fsid=loop0.0: jid=0: Failed [ 82.676323][ T5717] gfs2: fsid=loop0.0: error recovering journal 0: -5 [ 83.222439][ T5735] loop0: detected capacity change from 0 to 32768 [ 83.234260][ T5735] gfs2: fsid=loop0: Trying to join cluster "lock_nolock", "loop0" [ 83.242672][ T5735] gfs2: fsid=loop0: Now mounting FS (format 1801)... [ 83.262599][ T5735] gfs2: fsid=loop0.0: journal 0 mapped with 5 extents in 1ms [ 83.272579][ T22] gfs2: fsid=loop0.0: jid=0, already locked for use [ 83.281271][ T22] gfs2: fsid=loop0.0: jid=0: Looking at journal... [ 83.341894][ T22] kworker/1:0: attempt to access beyond end of device [ 83.341894][ T22] loop0: rw=0, sector=507904, nr_sectors = 8 limit=32768 [ 83.361136][ T22] gfs2: fsid=loop0.0: jid=0: Failed [ 83.368872][ T5735] gfs2: fsid=loop0.0: error recovering journal 0: -5 [ 83.931885][ T5757] loop0: detected capacity change from 0 to 32768 [ 83.942247][ T5757] gfs2: fsid=loop0: Trying to join cluster "lock_nolock", "loop0" [ 83.951046][ T5757] gfs2: fsid=loop0: Now mounting FS (format 1801)... [ 83.962763][ T5757] gfs2: fsid=loop0.0: journal 0 mapped with 5 extents in 0ms [ 83.972059][ T7] gfs2: fsid=loop0.0: jid=0, already locked for use [ 83.980910][ T7] gfs2: fsid=loop0.0: jid=0: Looking at journal... [ 84.040789][ T7] kworker/0:0: attempt to access beyond end of device [ 84.040789][ T7] loop0: rw=0, sector=507904, nr_sectors = 8 limit=32768 [ 84.060595][ T7] gfs2: fsid=loop0.0: jid=0: Failed [ 84.066175][ T5757] gfs2: fsid=loop0.0: error recovering journal 0: -5 [ 84.125449][ T5074] Bluetooth: hci0: command 0x0419 tx timeout [ 84.647307][ T5778] loop0: detected capacity change from 0 to 32768 [ 84.659026][ T5778] gfs2: fsid=loop0: Trying to join cluster "lock_nolock", "loop0" [ 84.667090][ T5778] gfs2: fsid=loop0: Now mounting FS (format 1801)... [ 84.679256][ T5778] gfs2: fsid=loop0.0: journal 0 mapped with 5 extents in 0ms [ 84.688639][ T898] gfs2: fsid=loop0.0: jid=0, already locked for use [ 84.696205][ T898] gfs2: fsid=loop0.0: jid=0: Looking at journal... [ 84.745852][ T898] kworker/0:2: attempt to access beyond end of device [ 84.745852][ T898] loop0: rw=0, sector=507904, nr_sectors = 8 limit=32768 [ 84.762148][ T898] gfs2: fsid=loop0.0: jid=0: Failed [ 84.769256][ T5778] gfs2: fsid=loop0.0: error recovering journal 0: -5 [ 85.307925][ T5798] loop0: detected capacity change from 0 to 32768 [ 85.320882][ T5798] gfs2: fsid=loop0: Trying to join cluster "lock_nolock", "loop0" [ 85.330375][ T5798] gfs2: fsid=loop0: Now mounting FS (format 1801)... [ 85.341861][ T5798] gfs2: fsid=loop0.0: journal 0 mapped with 5 extents in 0ms [ 85.350929][ T22] gfs2: fsid=loop0.0: jid=0, already locked for use [ 85.359121][ T22] gfs2: fsid=loop0.0: jid=0: Looking at journal... [ 85.418992][ T22] kworker/1:0: attempt to access beyond end of device [ 85.418992][ T22] loop0: rw=0, sector=507904, nr_sectors = 8 limit=32768 [ 85.441099][ T22] gfs2: fsid=loop0.0: jid=0: Failed [ 85.448558][ T5798] gfs2: fsid=loop0.0: error recovering journal 0: -5 [ 86.079068][ T5820] loop0: detected capacity change from 0 to 32768 [ 86.089657][ T5820] gfs2: fsid=loop0: Trying to join cluster "lock_nolock", "loop0" [ 86.100277][ T5820] gfs2: fsid=loop0: Now mounting FS (format 1801)... [ 86.111704][ T5820] gfs2: fsid=loop0.0: journal 0 mapped with 5 extents in 0ms [ 86.122414][ T898] gfs2: fsid=loop0.0: jid=0, already locked for use [ 86.129905][ T898] gfs2: fsid=loop0.0: jid=0: Looking at journal... [ 86.173563][ T898] kworker/0:2: attempt to access beyond end of device [ 86.173563][ T898] loop0: rw=0, sector=507904, nr_sectors = 8 limit=32768 [ 86.190407][ T898] gfs2: fsid=loop0.0: jid=0: Failed [ 86.197783][ T5820] gfs2: fsid=loop0.0: error recovering journal 0: -5 2023/01/04 07:41:49 executed programs: 12 [ 86.756486][ T5843] loop0: detected capacity change from 0 to 32768 [ 86.766992][ T5843] gfs2: fsid=loop0: Trying to join cluster "lock_nolock", "loop0" [ 86.774848][ T5843] gfs2: fsid=loop0: Now mounting FS (format 1801)... [ 86.786905][ T5843] gfs2: fsid=loop0.0: journal 0 mapped with 5 extents in 0ms [ 86.796002][ T898] gfs2: fsid=loop0.0: jid=0, already locked for use [ 86.802754][ T898] gfs2: fsid=loop0.0: jid=0: Looking at journal... [ 86.860147][ T898] gfs2: fsid=loop0.0: jid=0: Journal head lookup took 57ms [ 86.869410][ T898] gfs2: fsid=loop0.0: jid=0: Done [ 86.874517][ T5843] gfs2: fsid=loop0.0: first mount done, others may mount [ 87.599112][ T5869] loop0: detected capacity change from 0 to 32768 [ 87.611702][ T5869] gfs2: fsid=loop0: Trying to join cluster "lock_nolock", "loop0" [ 87.620119][ T5869] gfs2: fsid=loop0: Now mounting FS (format 1801)... [ 87.632477][ T5869] gfs2: fsid=loop0.0: journal 0 mapped with 5 extents in 0ms [ 87.642538][ T898] gfs2: fsid=loop0.0: jid=0, already locked for use [ 87.650873][ T898] gfs2: fsid=loop0.0: jid=0: Looking at journal... [ 87.704108][ T898] kworker/0:2: attempt to access beyond end of device [ 87.704108][ T898] loop0: rw=0, sector=507904, nr_sectors = 8 limit=32768 [ 87.721748][ T898] gfs2: fsid=loop0.0: jid=0: Failed [ 87.729492][ T5869] gfs2: fsid=loop0.0: error recovering journal 0: -5 [ 88.319525][ T5891] loop0: detected capacity change from 0 to 32768 [ 88.331359][ T5891] gfs2: fsid=loop0: Trying to join cluster "lock_nolock", "loop0" [ 88.340690][ T5891] gfs2: fsid=loop0: Now mounting FS (format 1801)... [ 88.351428][ T5891] gfs2: fsid=loop0.0: journal 0 mapped with 5 extents in 0ms [ 88.361961][ T2198] gfs2: fsid=loop0.0: jid=0, already locked for use [ 88.369293][ T2198] gfs2: fsid=loop0.0: jid=0: Looking at journal... [ 88.428087][ T2198] gfs2: fsid=loop0.0: jid=0: Journal head lookup took 58ms [ 88.438574][ T2198] gfs2: fsid=loop0.0: jid=0: Done [ 88.443684][ T5891] gfs2: fsid=loop0.0: first mount done, others may mount [ 88.516178][ T5891] syz-executor.0: attempt to access beyond end of device [ 88.516178][ T5891] loop0: rw=0, sector=507904, nr_sectors = 8 limit=32768 [ 88.556126][ T5891] gfs2: fsid=loop0.0: can't make FS RW: -5 [ 88.567047][ T93] [ 88.569412][ T93] ====================================================== [ 88.576534][ T93] WARNING: possible circular locking dependency detected [ 88.583546][ T93] 6.2.0-rc2-syzkaller-00127-g69b41ac87e4a-dirty #0 Not tainted [ 88.591080][ T93] ------------------------------------------------------ [ 88.598082][ T93] kworker/1:1H/93 is trying to acquire lock: [ 88.604056][ T93] ffff88802666e0e0 (&type->s_umount_key#50){+.+.}-{3:3}, at: freeze_super+0x45/0x420 [ 88.613560][ T93] [ 88.613560][ T93] but task is already holding lock: [ 88.620915][ T93] ffffc90002587d00 ((work_completion)(&(&gl->gl_work)->work)){+.+.}-{0:0}, at: process_one_work+0x831/0xdb0 [ 88.632392][ T93] [ 88.632392][ T93] which lock already depends on the new lock. [ 88.632392][ T93] [ 88.642785][ T93] [ 88.642785][ T93] the existing dependency chain (in reverse order) is: [ 88.651797][ T93] [ 88.651797][ T93] -> #2 ((work_completion)(&(&gl->gl_work)->work)){+.+.}-{0:0}: [ 88.661861][ T93] lock_acquire+0x182/0x3c0 [ 88.666876][ T93] process_one_work+0x852/0xdb0 [ 88.672242][ T93] worker_thread+0xb14/0x1330 [ 88.677437][ T93] kthread+0x266/0x300 [ 88.682037][ T93] ret_from_fork+0x1f/0x30 [ 88.686968][ T93] [ 88.686968][ T93] -> #1 ((wq_completion)glock_workqueue){+.+.}-{0:0}: [ 88.695900][ T93] lock_acquire+0x182/0x3c0 [ 88.701781][ T93] __flush_workqueue+0x17c/0x16d0 [ 88.707314][ T93] gfs2_gl_hash_clear+0xa3/0x300 [ 88.712768][ T93] gfs2_put_super+0x862/0x8d0 [ 88.717951][ T93] generic_shutdown_super+0x130/0x310 [ 88.723832][ T93] kill_block_super+0x79/0xd0 [ 88.729022][ T93] deactivate_locked_super+0xa7/0xf0 [ 88.734816][ T93] cleanup_mnt+0x494/0x520 [ 88.739915][ T93] task_work_run+0x243/0x300 [ 88.745295][ T93] exit_to_user_mode_loop+0x124/0x150 [ 88.751270][ T93] exit_to_user_mode_prepare+0xb2/0x140 [ 88.757437][ T93] syscall_exit_to_user_mode+0x26/0x60 [ 88.763415][ T93] do_syscall_64+0x49/0xb0 [ 88.768344][ T93] entry_SYSCALL_64_after_hwframe+0x63/0xcd [ 88.774754][ T93] [ 88.774754][ T93] -> #0 (&type->s_umount_key#50){+.+.}-{3:3}: [ 88.783001][ T93] validate_chain+0x1898/0x6ae0 [ 88.788363][ T93] __lock_acquire+0x1292/0x1f60 [ 88.793730][ T93] lock_acquire+0x182/0x3c0 [ 88.798742][ T93] down_write+0x9c/0x270 [ 88.803496][ T93] freeze_super+0x45/0x420 [ 88.808421][ T93] freeze_go_sync+0x178/0x340 [ 88.813610][ T93] do_xmote+0x34d/0x13d0 [ 88.818391][ T93] glock_work_func+0x2c2/0x450 [ 88.823699][ T93] process_one_work+0x877/0xdb0 [ 88.829064][ T93] worker_thread+0xb14/0x1330 [ 88.834253][ T93] kthread+0x266/0x300 [ 88.838834][ T93] ret_from_fork+0x1f/0x30 [ 88.843763][ T93] [ 88.843763][ T93] other info that might help us debug this: [ 88.843763][ T93] [ 88.853993][ T93] Chain exists of: [ 88.853993][ T93] &type->s_umount_key#50 --> (wq_completion)glock_workqueue --> (work_completion)(&(&gl->gl_work)->work) [ 88.853993][ T93] [ 88.871373][ T93] Possible unsafe locking scenario: [ 88.871373][ T93] [ 88.878809][ T93] CPU0 CPU1 [ 88.884170][ T93] ---- ---- [ 88.889609][ T93] lock((work_completion)(&(&gl->gl_work)->work)); [ 88.896187][ T93] lock((wq_completion)glock_workqueue); [ 88.904768][ T93] lock((work_completion)(&(&gl->gl_work)->work)); [ 88.914037][ T93] lock(&type->s_umount_key#50); [ 88.919067][ T93] [ 88.919067][ T93] *** DEADLOCK *** [ 88.919067][ T93] [ 88.927196][ T93] 2 locks held by kworker/1:1H/93: [ 88.932300][ T93] #0: ffff888012bf7d38 ((wq_completion)glock_workqueue){+.+.}-{0:0}, at: process_one_work+0x7f2/0xdb0 [ 88.943425][ T93] #1: ffffc90002587d00 ((work_completion)(&(&gl->gl_work)->work)){+.+.}-{0:0}, at: process_one_work+0x831/0xdb0 [ 88.955414][ T93] [ 88.955414][ T93] stack backtrace: [ 88.961291][ T93] CPU: 1 PID: 93 Comm: kworker/1:1H Not tainted 6.2.0-rc2-syzkaller-00127-g69b41ac87e4a-dirty #0 [ 88.971776][ T93] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 10/26/2022 [ 88.981820][ T93] Workqueue: glock_workqueue glock_work_func [ 88.987796][ T93] Call Trace: [ 88.991066][ T93] [ 88.993987][ T93] dump_stack_lvl+0x1b1/0x290 [ 88.998669][ T93] ? nf_tcp_handle_invalid+0x630/0x630 [ 89.004207][ T93] ? print_circular_bug+0x13e/0x1c0 [ 89.009407][ T93] check_noncircular+0x2cc/0x390 [ 89.014335][ T93] ? add_chain_block+0x850/0x850 [ 89.019266][ T93] ? lockdep_lock+0x102/0x290 [ 89.023932][ T93] ? validate_chain+0x177/0x6ae0 [ 89.028860][ T93] ? _find_first_zero_bit+0xe8/0x110 [ 89.034135][ T93] validate_chain+0x1898/0x6ae0 [ 89.039010][ T93] ? validate_chain+0x177/0x6ae0 [ 89.043958][ T93] ? reacquire_held_locks+0x650/0x650 [ 89.049682][ T93] ? validate_chain+0x177/0x6ae0 [ 89.054610][ T93] ? validate_chain+0x177/0x6ae0 [ 89.059543][ T93] ? validate_chain+0x177/0x6ae0 [ 89.064482][ T93] ? reacquire_held_locks+0x650/0x650 [ 89.069858][ T93] ? reacquire_held_locks+0x650/0x650 [ 89.075218][ T93] ? reacquire_held_locks+0x650/0x650 [ 89.080586][ T93] ? reacquire_held_locks+0x650/0x650 [ 89.085946][ T93] ? validate_chain+0x177/0x6ae0 [ 89.090879][ T93] ? validate_chain+0x177/0x6ae0 [ 89.095825][ T93] ? reacquire_held_locks+0x650/0x650 [ 89.101217][ T93] ? validate_chain+0x177/0x6ae0 [ 89.106182][ T93] ? rcu_read_lock_sched_held+0x87/0x110 [ 89.111982][ T93] ? __bpf_trace_rcu_stall_warning+0x10/0x10 [ 89.118044][ T93] ? finish_lock_switch+0x89/0x100 [ 89.123145][ T93] ? trace_lock_release+0x95/0x220 [ 89.128247][ T93] ? mark_lock+0x9a/0x350 [ 89.132575][ T93] ? lockdep_hardirqs_on_prepare+0x428/0x790 [ 89.138548][ T93] ? do_raw_spin_unlock+0x134/0x8a0 [ 89.143828][ T93] ? finish_lock_switch+0x8e/0x100 [ 89.148937][ T93] ? lockdep_hardirqs_on+0x8d/0x130 [ 89.154235][ T93] ? deref_stack_reg+0x17a/0x210 [ 89.159202][ T93] ? unwind_next_frame+0x1b06/0x24c0 [ 89.164502][ T93] ? deref_stack_reg+0x17a/0x210 [ 89.169520][ T93] ? unwind_next_frame+0x10d7/0x24c0 [ 89.174976][ T93] ? ret_from_fork+0x1f/0x30 [ 89.179823][ T93] ? stack_trace_save+0x1e0/0x1e0 [ 89.184849][ T93] ? arch_stack_walk+0xb2/0xe0 [ 89.189602][ T93] ? deref_stack_reg+0x17a/0x210 [ 89.194528][ T93] ? ret_from_fork+0x1f/0x30 [ 89.199116][ T93] ? unwind_next_frame+0x1b06/0x24c0 [ 89.204389][ T93] ? deref_stack_reg+0x17a/0x210 [ 89.209318][ T93] ? preempt_count_add+0x8d/0x180 [ 89.214346][ T93] ? unwind_next_frame+0x10d7/0x24c0 [ 89.220094][ T93] ? rcu_read_lock_sched_held+0x87/0x110 [ 89.225742][ T93] ? __bpf_trace_rcu_stall_warning+0x10/0x10 [ 89.233805][ T93] ? debug_check_no_obj_freed+0x5f2/0x680 [ 89.239522][ T93] ? trace_lock_release+0x95/0x220 [ 89.244674][ T93] ? deref_stack_reg+0x17a/0x210 [ 89.249616][ T93] ? unwind_next_frame+0x1b06/0x24c0 [ 89.255346][ T93] ? deref_stack_reg+0x17a/0x210 [ 89.264309][ T93] ? deref_stack_reg+0x17a/0x210 [ 89.269273][ T93] ? unwind_next_frame+0x1b06/0x24c0 [ 89.274571][ T93] ? deref_stack_reg+0x17a/0x210 [ 89.279502][ T93] ? preempt_count_add+0x8d/0x180 [ 89.284526][ T93] ? unwind_next_frame+0x10d7/0x24c0 [ 89.289823][ T93] ? ret_from_fork+0x1f/0x30 [ 89.294440][ T93] ? stack_trace_save+0x1e0/0x1e0 [ 89.299526][ T93] ? arch_stack_walk+0xb2/0xe0 [ 89.304298][ T93] ? register_lock_class+0xc2/0x930 [ 89.309511][ T93] ? ret_from_fork+0x1f/0x30 [ 89.314121][ T93] ? is_dynamic_key+0x1f0/0x1f0 [ 89.318977][ T93] ? stack_trace_save+0x104/0x1e0 [ 89.324017][ T93] ? mark_lock+0x9a/0x350 [ 89.328351][ T93] __lock_acquire+0x1292/0x1f60 [ 89.333296][ T93] lock_acquire+0x182/0x3c0 [ 89.337793][ T93] ? freeze_super+0x45/0x420 [ 89.342376][ T93] ? read_lock_is_recursive+0x10/0x10 [ 89.347740][ T93] ? __might_sleep+0xc0/0xc0 [ 89.352334][ T93] ? rcu_read_lock_sched_held+0x87/0x110 [ 89.357975][ T93] ? __bpf_trace_rcu_stall_warning+0x10/0x10 [ 89.364052][ T93] ? mark_lock+0x9a/0x350 [ 89.368408][ T93] ? do_xmote+0x325/0x13d0 [ 89.372823][ T93] down_write+0x9c/0x270 [ 89.377077][ T93] ? freeze_super+0x45/0x420 [ 89.381660][ T93] ? down_read_killable+0x80/0x80 [ 89.386689][ T93] ? do_xmote+0x325/0x13d0 [ 89.391120][ T93] ? __lock_acquire+0x1f60/0x1f60 [ 89.396206][ T93] freeze_super+0x45/0x420 [ 89.400639][ T93] freeze_go_sync+0x178/0x340 [ 89.405367][ T93] do_xmote+0x34d/0x13d0 [ 89.409610][ T93] glock_work_func+0x2c2/0x450 [ 89.414368][ T93] process_one_work+0x877/0xdb0 [ 89.419218][ T93] ? worker_detach_from_pool+0x260/0x260 [ 89.424945][ T93] ? _raw_spin_lock_irq+0xba/0xf0 [ 89.429973][ T93] ? _raw_spin_lock_irqsave+0x100/0x100 [ 89.435557][ T93] worker_thread+0xb14/0x1330 [ 89.440236][ T93] ? _raw_spin_unlock_irqrestore+0xc1/0x120 [ 89.446309][ T93] kthread+0x266/0x300 [ 89.450373][ T93] ? rcu_lock_release+0x20/0x20 [ 89.455245][ T93] ? kthread_blkcg+0xd0/0xd0 [ 89.459827][ T93] ret_from_fork+0x1f/0x30 [ 89.464254][ T93]