Warning: Permanently added '10.128.1.63' (ED25519) to the list of known hosts.
2025/08/10 09:12:55 ignoring optional flag "sandboxArg"="0"
2025/08/10 09:12:56 parsed 1 programs
[ 62.881459][ T3111] Adding 124996k swap on ./swap-file. Priority:0 extents:1 across:124996k
[ 64.019823][ T2343] Bluetooth: hci0: unexpected cc 0x0c03 length: 249 > 1
[ 64.027172][ T2343] Bluetooth: hci0: unexpected cc 0x1003 length: 249 > 9
[ 64.034943][ T2343] Bluetooth: hci0: unexpected cc 0x1001 length: 249 > 9
[ 64.043075][ T2343] Bluetooth: hci0: unexpected cc 0x0c23 length: 249 > 4
[ 64.050439][ T2343] Bluetooth: hci0: unexpected cc 0x0c38 length: 249 > 2
[ 64.164677][ T3171] chnl_net:caif_netlink_parms(): no params data found
[ 64.727021][ T3171] 8021q: adding VLAN 0 to HW filter on device bond0
[ 65.164927][ T3171] 8021q: adding VLAN 0 to HW filter on device batadv0
[ 65.679523][ T612] ieee802154 phy0 wpan0: encryption failed: -22
[ 65.686090][ T612] ieee802154 phy1 wpan1: encryption failed: -22
[ 66.114082][ T12] bond0 (unregistering): Released all slaves
2025/08/10 09:13:01 executed programs: 0
[ 66.261238][ T2050] Bluetooth: hci0: unexpected cc 0x0c03 length: 249 > 1
[ 66.269363][ T2050] Bluetooth: hci0: unexpected cc 0x1003 length: 249 > 9
[ 66.277346][ T2050] Bluetooth: hci0: unexpected cc 0x1001 length: 249 > 9
[ 66.285381][ T2050] Bluetooth: hci0: unexpected cc 0x0c23 length: 249 > 4
[ 66.293346][ T2050] Bluetooth: hci0: unexpected cc 0x0c38 length: 249 > 2
[ 66.370662][ T3545] chnl_net:caif_netlink_parms(): no params data found
[ 66.922342][ T3545] 8021q: adding VLAN 0 to HW filter on device bond0
[ 67.344493][ T3545] 8021q: adding VLAN 0 to HW filter on device batadv0
[ 68.196114][ T3908] loop2: detected capacity change from 0 to 32768
[ 68.216958][ T3908] bcachefs (loop2): starting version 1.7: mi_btree_bitmap opts=metadata_checksum=none,data_checksum=none,compression=lz4,background_compression=lz4,nojournal_transaction_names,version_upgrade=none
[ 68.216964][ T3908] allowing incompatible features above 0.0: (unknown version)
[ 68.216966][ T3908] features: lz4,new_siphash,inline_data,new_extent_overwrite,btree_ptr_v2,new_varint,journal_no_flush,alloc_v2,extents_across_btree_nodes
[ 68.259004][ T3908] bcachefs (loop2): recovering from clean shutdown, journal seq 13
[ 68.273108][ T3908] bcachefs (loop2): accounting_read... done
[ 68.279574][ T3908] bcachefs (loop2): alloc_read... done
[ 68.285592][ T3908] bcachefs (loop2): snapshots_read... done
[ 68.292892][ T3908] bcachefs (loop2): going read-write
[ 68.299763][ T3908] bcachefs (loop2): journal_replay... done
[ 68.306282][ T3908] bcachefs (loop2): resume_logged_ops... done
[ 68.312974][ T3908] bcachefs (loop2): delete_dead_inodes...
[ 68.313311][ T3917] bcachefs (loop2): backpointer doesn't match extent it points to:
[ 68.313315][ T3917] u64s 9 type backpointer 0:8921088:0 len 0 ver 0: bucket=0:34:8 btree=extents level=0 data_type=user suboffset=0 len=8 gen=0 pos=1073741825:24:U32_MAX
[ 68.313317][ T3917] u64s 8 type extent 1073741825:24:U32_MAX len 24 ver 2: durability: 1 crc: c_size 8 size 24 offset 0 nonce 0 csum chacha20_poly1305_80 5c1d:75853c64f7009f9d compress lz4 ptr: 0:34:8 gen 0
[ 68.313319][ T3917] u64s 9 type backpointer 0:8921088:0 len 0 ver 0: bucket=0:34:8 btree=extents level=0 data_type=user suboffset=0 len=8 gen=0 pos=1073741825:24:U32_MAX, fixing
[ 68.320418][ T3908] done
[ 68.328259][ T2050] Bluetooth: hci0: command tx timeout
[ 68.345441][ T3917] bcachefs (loop2): Detected missing backpointers in bucket 34, now have 1/128 with missing
[ 68.364588][ T3908] bcachefs (loop2): Fixed errors, running fsck a second time to verify fs is clean
[ 68.379606][ T3917] scheduling recovery pass check_extents_to_backpointers (17)
[ 70.399372][ T3162] Bluetooth: hci0: command tx timeout
[ 70.799724][ T41] cfg80211: failed to load regulatory.db
[ 72.479388][ T3162] Bluetooth: hci0: command tx timeout
[ 74.559356][ T3162] Bluetooth: hci0: command tx timeout
[ 83.149140][ T2050] Bluetooth: hci1: unexpected cc 0x0c03 length: 249 > 1
[ 83.156639][ T2050] Bluetooth: hci1: unexpected cc 0x1003 length: 249 > 9
[ 83.163921][ T2050] Bluetooth: hci1: unexpected cc 0x1001 length: 249 > 9
[ 83.171567][ T2050] Bluetooth: hci1: unexpected cc 0x0c23 length: 249 > 4
[ 83.178929][ T2050] Bluetooth: hci1: unexpected cc 0x0c38 length: 249 > 2
[ 83.252917][ T3923] chnl_net:caif_netlink_parms(): no params data found
[ 83.796453][ T3923] 8021q: adding VLAN 0 to HW filter on device bond0
[ 84.215123][ T3923] 8021q: adding VLAN 0 to HW filter on device batadv0
2025/08/10 09:13:20 executed programs: 3
[ 85.082728][ T4287] loop3: detected capacity change from 0 to 32768
[ 85.101252][ T4287] bcachefs (loop3): starting version 1.7: mi_btree_bitmap opts=metadata_checksum=none,data_checksum=none,compression=lz4,background_compression=lz4,nojournal_transaction_names,version_upgrade=none
[ 85.101257][ T4287] allowing incompatible features above 0.0: (unknown version)
[ 85.101259][ T4287] features: lz4,new_siphash,inline_data,new_extent_overwrite,btree_ptr_v2,new_varint,journal_no_flush,alloc_v2,extents_across_btree_nodes
[ 85.144624][ T4287] bcachefs (loop3): recovering from clean shutdown, journal seq 13
[ 85.158673][ T4287] bcachefs (loop3): accounting_read... done
[ 85.165114][ T4287] bcachefs (loop3): alloc_read... done
[ 85.171535][ T4287] bcachefs (loop3): snapshots_read... done
[ 85.178864][ T4287] bcachefs (loop3): going read-write
[ 85.185656][ T4287] bcachefs (loop3): journal_replay... done
[ 85.192681][ T4287] bcachefs (loop3): resume_logged_ops... done
[ 85.199151][ T4287] bcachefs (loop3): delete_dead_inodes...
[ 85.199269][ T3162] Bluetooth: hci1: command tx timeout
[ 85.211030][ T4296] bcachefs (loop3): backpointer doesn't match extent it points to:
[ 85.211035][ T4296] u64s 9 type backpointer 0:8921088:0 len 0 ver 0: bucket=0:34:8 btree=extents level=0 data_type=user suboffset=0 len=8 gen=0 pos=1073741825:24:U32_MAX
[ 85.211037][ T4296] u64s 8 type extent 1073741825:24:U32_MAX len 24 ver 2: durability: 1 crc: c_size 8 size 24 offset 0 nonce 0 csum chacha20_poly1305_80 5c1d:75853c64f7009f9d compress lz4 ptr: 0:34:8 gen 0
[ 85.211040][ T4296] u64s 9 type backpointer 0:8921088:0 len 0 ver 0: bucket=0:34:8 btree=extents level=0 data_type=user suboffset=0 len=8 gen=0 pos=1073741825:24:U32_MAX, fixing
[ 85.212125][ T4287] done
[ 85.220543][ T4296] bcachefs (loop3): Detected missing backpointers in bucket 34, now have 1/128 with missing
[ 85.237290][ T4287] bcachefs (loop3): check_extents_to_backpointers...
[ 85.256265][ T4296] running recovery pass check_extents_to_backpointers (17), currently at delete_dead_inodes (40) - rewinding
[ 85.314689][ T4287] bcachefs (loop3): scanning for missing backpointers in 7/128 buckets
[ 85.324134][ T4287] bcachefs (loop3): missing backpointer
[ 85.324137][ T4287] for: u64s 8 type extent 1073741825:24:U32_MAX len 24 ver 2: durability: 1 crc: c_size 8 size 24 offset 0 nonce 0 csum chacha20_poly1305_80 5c1d:75853c64f7009f9d compress lz4 ptr: 0:34:8 gen 0
[ 85.324139][ T4287] want: u64s 9 type backpointer 0:8921088:0 len 0 ver 0: bucket=0:34:8 btree=extents level=0 data_type=user suboffset=0 len=8 gen=0 pos=1073741825:24:U32_MAX
[ 85.324140][ T4287] got: u64s 5 type deleted 0:8921088:0 len 0 ver 0, fixing
[ 85.377250][ T4287] done
[ 85.381027][ T4287] bcachefs (loop3): resume_logged_ops... done
[ 85.388348][ T4287] bcachefs (loop3): delete_dead_inodes... done
[ 85.396141][ T4287] bcachefs (loop3): Fixed errors, running fsck a second time to verify fs is clean
[ 85.406662][ T4287] bcachefs (loop3): resume_logged_ops... done
[ 85.413373][ T4287] bcachefs (loop3): delete_dead_inodes... done
[ 85.421515][ T4287] bcachefs (loop3): done starting filesystem
[ 87.279481][ T2050] Bluetooth: hci1: command tx timeout
[ 89.359373][ T3162] Bluetooth: hci1: command tx timeout
[ 91.439332][ T3162] Bluetooth: hci1: command tx timeout
[ 100.505768][ T2050] Bluetooth: hci2: unexpected cc 0x0c03 length: 249 > 1
[ 100.513720][ T2050] Bluetooth: hci2: unexpected cc 0x1003 length: 249 > 9
[ 100.521031][ T2050] Bluetooth: hci2: unexpected cc 0x1001 length: 249 > 9
[ 100.528889][ T2050] Bluetooth: hci2: unexpected cc 0x0c23 length: 249 > 4
[ 100.536486][ T2050] Bluetooth: hci2: unexpected cc 0x0c38 length: 249 > 2
[ 100.610623][ T4303] chnl_net:caif_netlink_parms(): no params data found
[ 101.161575][ T4303] 8021q: adding VLAN 0 to HW filter on device bond0
[ 101.572671][ T4303] 8021q: adding VLAN 0 to HW filter on device batadv0
2025/08/10 09:13:37 executed programs: 5
[ 102.450073][ T4667] loop4: detected capacity change from 0 to 32768
[ 102.469091][ T4667] bcachefs (loop4): starting version 1.7: mi_btree_bitmap opts=metadata_checksum=none,data_checksum=none,compression=lz4,background_compression=lz4,nojournal_transaction_names,version_upgrade=none
[ 102.469097][ T4667] allowing incompatible features above 0.0: (unknown version)
[ 102.469099][ T4667] features: lz4,new_siphash,inline_data,new_extent_overwrite,btree_ptr_v2,new_varint,journal_no_flush,alloc_v2,extents_across_btree_nodes
[ 102.511790][ T4667] bcachefs (loop4): recovering from clean shutdown, journal seq 13
[ 102.525321][ T4667] bcachefs (loop4): accounting_read... done
[ 102.532355][ T4667] bcachefs (loop4): alloc_read... done
[ 102.538356][ T4667] bcachefs (loop4): snapshots_read... done
[ 102.545484][ T4667] bcachefs (loop4): going read-write
[ 102.552450][ T4667] bcachefs (loop4): journal_replay... done
[ 102.559308][ T3162] Bluetooth: hci2: command tx timeout
[ 102.564990][ T4667] bcachefs (loop4): resume_logged_ops... done
[ 102.571554][ T4667] bcachefs (loop4): delete_dead_inodes... done
[ 102.572542][ T4676] bcachefs (loop4): backpointer doesn't match extent it points to:
[ 102.586608][ T4676] u64s 9 type backpointer 0:8921088:0 len 0 ver 0: bucket=0:34:8 btree=extents level=0 data_type=user suboffset=0 len=8 gen=0 pos=1073741825:24:U32_MAX
[ 102.602358][ T4676] u64s 8 type extent 1073741825:24:U32_MAX len 24 ver 2: durability: 1 crc: c_size 8 size 24 offset 0 nonce 0 csum chacha20_poly1305_80 5c1d:75853c64f7009f9d compress lz4 ptr: 0:34:8 gen 0
[ 102.622349][ T4676] u64s 9 type backpointer 0:8921088:0 len 0 ver 0: bucket=0:34:8 btree=extents level=0 data_type=user suboffset=0 len=8 gen=0 pos=1073741825:24:U32_MAX, fixing
[ 102.639102][ T4667] bcachefs (loop4): done starting filesystem
[ 102.639133][ T4676] bcachefs (loop4): backpointer doesn't match extent it points to:
[ 102.653493][ T4676] u64s 9 type backpointer 0:8921088:0 len 0 ver 0: bucket=0:34:8 btree=extents level=0 data_type=user suboffset=0 len=8 gen=0 pos=1073741825:24:U32_MAX
[ 102.669306][ T4676] u64s 8 type extent 1073741825:24:U32_MAX len 24 ver 2: durability: 1 crc: c_size 8 size 24 offset 0 nonce 0 csum chacha20_poly1305_80 5c1d:75853c64f7009f9d compress lz4 ptr: 0:34:8 gen 0
[ 102.688420][ T4676] u64s 9 type backpointer 0:8921088:0 len 0 ver 0: bucket=0:34:8 btree=extents level=0 data_type=user suboffset=0 len=8 gen=0 pos=1073741825:24:U32_MAX, fixing
[ 104.639308][ T3162] Bluetooth: hci2: command tx timeout
[ 106.719311][ T2050] Bluetooth: hci2: command tx timeout
[ 108.799384][ T2050] Bluetooth: hci2: command tx timeout
[ 117.736316][ T2050] Bluetooth: hci3: unexpected cc 0x0c03 length: 249 > 1
[ 117.744050][ T2050] Bluetooth: hci3: unexpected cc 0x1003 length: 249 > 9
[ 117.752090][ T2050] Bluetooth: hci3: unexpected cc 0x1001 length: 249 > 9
[ 117.760178][ T2050] Bluetooth: hci3: unexpected cc 0x0c23 length: 249 > 4
[ 117.767616][ T2050] Bluetooth: hci3: unexpected cc 0x0c38 length: 249 > 2
[ 117.851505][ T4679] chnl_net:caif_netlink_parms(): no params data found
[ 118.461885][ T4679] 8021q: adding VLAN 0 to HW filter on device bond0
[ 118.896017][ T4679] 8021q: adding VLAN 0 to HW filter on device batadv0
2025/08/10 09:13:55 executed programs: 7
[ 119.775334][ T5044] loop5: detected capacity change from 0 to 32768
[ 119.793390][ T5044] bcachefs (loop5): starting version 1.7: mi_btree_bitmap opts=metadata_checksum=none,data_checksum=none,compression=lz4,background_compression=lz4,nojournal_transaction_names,version_upgrade=none
[ 119.793396][ T5044] allowing incompatible features above 0.0: (unknown version)
[ 119.793399][ T5044] features: lz4,new_siphash,inline_data,new_extent_overwrite,btree_ptr_v2,new_varint,journal_no_flush,alloc_v2,extents_across_btree_nodes
[ 119.843776][ T3162] Bluetooth: hci3: command tx timeout
[ 119.843801][ T5044] bcachefs (loop5): recovering from clean shutdown, journal seq 13
[ 119.863535][ T5044] bcachefs (loop5): accounting_read... done
[ 119.870140][ T5044] bcachefs (loop5): alloc_read... done
[ 119.876369][ T5044] bcachefs (loop5): snapshots_read... done
[ 119.883872][ T5044] bcachefs (loop5): going read-write
[ 119.891702][ T5044] bcachefs (loop5): journal_replay... done
[ 119.898977][ T5044] bcachefs (loop5): resume_logged_ops... done
[ 119.906531][ T5044] bcachefs (loop5): delete_dead_inodes...
[ 119.906764][ T5053] bcachefs (loop5): backpointer doesn't match extent it points to:
[ 119.906768][ T5053] u64s 9 type backpointer 0:8921088:0 len 0 ver 0: bucket=0:34:8 btree=extents level=0 data_type=user suboffset=0 len=8 gen=0 pos=1073741825:24:U32_MAX
[ 119.906770][ T5053] u64s 8 type extent 1073741825:24:U32_MAX len 24 ver 2: durability: 1 crc: c_size 8 size 24 offset 0 nonce 0 csum chacha20_poly1305_80 5c1d:75853c64f7009f9d compress lz4 ptr: 0:34:8 gen 0
[ 119.906773][ T5053] u64s 9 type backpointer 0:8921088:0 len 0 ver 0: bucket=0:34:8 btree=extents level=0 data_type=user suboffset=0 len=8 gen=0 pos=1073741825:24:U32_MAX, fixing
[ 119.906908][ T5044] done
[ 119.913676][ T5053] bcachefs (loop5): Detected missing backpointers in bucket 34, now have 1/128 with missing
[ 119.929746][ T5044] bcachefs (loop5): check_extents_to_backpointers...
[ 119.937384][ T5053] running recovery pass check_extents_to_backpointers (17), currently at delete_dead_inodes (40) - rewinding
[ 119.959369][ T5044] bcachefs (loop5): scanning for missing backpointers in 7/128 buckets
[ 120.016805][ T5044] bcachefs (loop5): missing backpointer
[ 120.016807][ T5044] for: u64s 8 type extent 1073741825:24:U32_MAX len 24 ver 2: durability: 1 crc: c_size 8 size 24 offset 0 nonce 0 csum chacha20_poly1305_80 5c1d:75853c64f7009f9d compress lz4 ptr: 0:34:8 gen 0
[ 120.016809][ T5044] want: u64s 9 type backpointer 0:8921088:0 len 0 ver 0: bucket=0:34:8 btree=extents level=0 data_type=user suboffset=0 len=8 gen=0 pos=1073741825:24:U32_MAX
[ 120.016811][ T5044] got: u64s 5 type deleted 0:8921088:0 len 0 ver 0, fixing
[ 120.068645][ T5044] done
[ 120.072405][ T5044] bcachefs (loop5): resume_logged_ops... done
[ 120.080021][ T5044] bcachefs (loop5): delete_dead_inodes... done
[ 120.088601][ T5044] bcachefs (loop5): Fixed errors, running fsck a second time to verify fs is clean
[ 120.100265][ T5044] bcachefs (loop5): resume_logged_ops... done
[ 120.106862][ T5044] bcachefs (loop5): delete_dead_inodes... done
[ 120.114901][ T5044] bcachefs (loop5): done starting filesystem
[ 121.919389][ T2050] Bluetooth: hci3: command tx timeout
[ 123.999346][ T2343] Bluetooth: hci3: command tx timeout
[ 126.079333][ T2343] Bluetooth: hci3: command tx timeout
[ 127.119575][ T612] ieee802154 phy0 wpan0: encryption failed: -22
[ 127.126600][ T612] ieee802154 phy1 wpan1: encryption failed: -22
[ 135.207650][ T2050] Bluetooth: hci4: unexpected cc 0x0c03 length: 249 > 1
[ 135.215152][ T2050] Bluetooth: hci4: unexpected cc 0x1003 length: 249 > 9
[ 135.222337][ T2050] Bluetooth: hci4: unexpected cc 0x1001 length: 249 > 9
[ 135.230806][ T2050] Bluetooth: hci4: unexpected cc 0x0c23 length: 249 > 4
[ 135.238121][ T2050] Bluetooth: hci4: unexpected cc 0x0c38 length: 249 > 2
[ 135.320843][ T5056] chnl_net:caif_netlink_parms(): no params data found
[ 135.924879][ T5056] 8021q: adding VLAN 0 to HW filter on device bond0
[ 136.356512][ T5056] 8021q: adding VLAN 0 to HW filter on device batadv0
2025/08/10 09:14:12 executed programs: 9
[ 137.247216][ T5421] loop6: detected capacity change from 0 to 32768
[ 137.266420][ T5421] bcachefs (loop6): starting version 1.7: mi_btree_bitmap opts=metadata_checksum=none,data_checksum=none,compression=lz4,background_compression=lz4,nojournal_transaction_names,version_upgrade=none
[ 137.266426][ T5421] allowing incompatible features above 0.0: (unknown version)
[ 137.266428][ T5421] features: lz4,new_siphash,inline_data,new_extent_overwrite,btree_ptr_v2,new_varint,journal_no_flush,alloc_v2,extents_across_btree_nodes
[ 137.299154][ T2050] Bluetooth: hci4: command tx timeout
[ 137.309230][ T5421] bcachefs (loop6): recovering from clean shutdown, journal seq 13
[ 137.328769][ T5421] bcachefs (loop6): accounting_read... done
[ 137.335179][ T5421] bcachefs (loop6): alloc_read... done
[ 137.341008][ T5421] bcachefs (loop6): snapshots_read... done
[ 137.348344][ T5421] bcachefs (loop6): going read-write
[ 137.355500][ T5421] bcachefs (loop6): journal_replay... done
[ 137.362236][ T5421] bcachefs (loop6): resume_logged_ops... done
[ 137.369293][ T5421] bcachefs (loop6): delete_dead_inodes...
[ 137.369682][ T5430] bcachefs (loop6): backpointer doesn't match extent it points to:
[ 137.369686][ T5430] u64s 9 type backpointer 0:8921088:0 len 0 ver 0: bucket=0:34:8 btree=extents level=0 data_type=user suboffset=0 len=8 gen=0 pos=1073741825:24:U32_MAX
[ 137.369689][ T5430] u64s 8 type extent 1073741825:24:U32_MAX len 24 ver 2: durability: 1 crc: c_size 8 size 24 offset 0 nonce 0 csum chacha20_poly1305_80 5c1d:75853c64f7009f9d compress lz4 ptr: 0:34:8 gen 0
[ 137.369692][ T5430] u64s 9 type backpointer 0:8921088:0 len 0 ver 0: bucket=0:34:8 btree=extents level=0 data_type=user suboffset=0 len=8 gen=0 pos=1073741825:24:U32_MAX, fixing
[ 137.371313][ T5421] done
[ 137.384558][ T5430] bcachefs (loop6): Detected missing backpointers in bucket 34, now have 1/128 with missing
[ 137.400701][ T5421] bcachefs (loop6): check_extents_to_backpointers...
[ 137.419533][ T5430] running recovery pass check_extents_to_backpointers (17), currently at delete_dead_inodes (40) - rewinding
[ 137.469984][ T5421] bcachefs (loop6): scanning for missing backpointers in 7/128 buckets
[ 137.478756][ T5421] bcachefs (loop6): missing backpointer
[ 137.478759][ T5421] for: u64s 8 type extent 1073741825:24:U32_MAX len 24 ver 2: durability: 1 crc: c_size 8 size 24 offset 0 nonce 0 csum chacha20_poly1305_80 5c1d:75853c64f7009f9d compress lz4 ptr: 0:34:8 gen 0
[ 137.478761][ T5421] want: u64s 9 type backpointer 0:8921088:0 len 0 ver 0: bucket=0:34:8 btree=extents level=0 data_type=user suboffset=0 len=8 gen=0 pos=1073741825:24:U32_MAX
[ 137.478762][ T5421] got: u64s 5 type deleted 0:8921088:0 len 0 ver 0, fixing
[ 137.530082][ T5421] done
[ 137.533732][ T5421] bcachefs (loop6): resume_logged_ops... done
[ 137.540246][ T5421] bcachefs (loop6): delete_dead_inodes... done
[ 137.549533][ T5421] bcachefs (loop6): Fixed errors, running fsck a second time to verify fs is clean
[ 137.560730][ T5421] bcachefs (loop6): resume_logged_ops... done
[ 137.567198][ T5421] bcachefs (loop6): delete_dead_inodes... done
[ 137.574546][ T5421] bcachefs (loop6): done starting filesystem
[ 139.359383][ T2343] Bluetooth: hci4: command tx timeout
[ 141.439315][ T3162] Bluetooth: hci4: command tx timeout
[ 143.519322][ T3162] Bluetooth: hci4: command tx timeout
[ 146.969423][ T4295] Allocator stuck? Waited for 30 seconds
[ 146.969428][ T4295] Allocator debug:
[ 146.969430][ T4295] capacity1536
[ 146.969432][ T4295] reserved 31232
[ 146.969434][ T4295] hidden 0
[ 146.969435][ T4295] btree 0
[ 146.969437][ T4295] data 18446744073709551608
[ 146.969439][ T4295] cached 0
[ 146.969439][ T3923] Allocator stuck? Waited for 30 seconds
[ 146.969440][ T4295] reserved 0
[ 146.969441][ T3923] Allocator debug:
[ 146.969442][ T4295] online_reserved 1536
[ 146.969443][ T3923] capacity1536
[ 146.969443][ T4295] nr_inodes 0
[ 146.969444][ T3923] reserved 31232
[ 146.969445][ T4295]
[ 146.969446][ T3923] hidden 0
[ 146.969447][ T4295] freelist_wait waiting
[ 146.969447][ T3923] btree 0
[ 146.969448][ T4295] open buckets allocated1
[ 146.969449][ T3923] data 18446744073709551608
[ 146.969450][ T4295] open buckets total 1024
[ 146.969451][ T3923] cached 0
[ 146.969451][ T4295] open_buckets_wait empty
[ 146.969452][ T3923] reserved 0
[ 146.969453][ T4295] open_buckets_btree 0
[ 146.969454][ T3923] online_reserved 1536
[ 146.969454][ T4295] open_buckets_user 0
[ 146.969455][ T3923] nr_inodes 0
[ 146.969456][ T4295] btree reserve cache 0
[ 146.969457][ T3923]
[ 146.969457][ T4295]
[ 146.969458][ T3923] freelist_wait waiting
[ 146.969459][ T4295] Dev 0:
[ 146.969460][ T3923] open buckets allocated1
[ 146.969460][ T4295] buckets sectors fragmented
[ 146.969461][ T3923] open buckets total 1024
[ 146.969462][ T4295] free 0 0 0
[ 146.969463][ T3923] open_buckets_wait empty
[ 146.969464][ T4295] sb 0 0 0
[ 146.969464][ T3923] open_buckets_btree 0
[ 146.969466][ T3923] open_buckets_user 0
[ 146.969466][ T4295] journal 0 0 0
[ 146.969467][ T3923] btree reserve cache 0
[ 146.969468][ T4295] btree 0 0 0
[ 146.969469][ T3923]
[ 146.969469][ T4295] user 018446744073709551608 8
[ 146.969470][ T3923] Dev 0:
[ 146.969471][ T4295] cached 0 0 0
[ 146.969471][ T3923] buckets sectors fragmented
[ 146.969473][ T4295] parity 0 0 0
[ 146.969473][ T3923] free 0 0 0
[ 146.969475][ T4295] stripe 0 0 0
[ 146.969475][ T3923] sb 0 0 0
[ 146.969477][ T4295] need_gc_gens 0 0 0
[ 146.969477][ T3923] journal 0 0 0
[ 146.969478][ T4295] need_discard 0 0 0
[ 146.969479][ T3923] btree 0 0 0
[ 146.969480][ T4295] unstriped 0 0 0
[ 146.969480][ T3923] user 018446744073709551608 8
[ 146.969482][ T4295] capacity 128
[ 146.969482][ T3923] cached 0 0 0
[ 146.969484][ T4295]
[ 146.969484][ T3923] parity 0 0 0
[ 146.969485][ T4295] reserves:
[ 146.969486][ T3923] stripe 0 0 0
[ 146.969486][ T4295] stripe 60
[ 146.969488][ T3923] need_gc_gens 0 0 0
[ 146.969488][ T4295] normal 58
[ 146.969489][ T4295] copygc 56
[ 146.969489][ T3923] need_discard 0 0 0
[ 146.969491][ T4295] btree 28
[ 146.969491][ T3923] unstriped 0 0 0
[ 146.969492][ T4295] btree_copygc 0
[ 146.969493][ T3923] capacity 128
[ 146.969494][ T4295] reclaim 0
[ 146.969494][ T3923]
[ 146.969495][ T4295] interior_updates 0
[ 146.969496][ T3923] reserves:
[ 146.969497][ T4295]
[ 146.969497][ T3923] stripe 60
[ 146.969498][ T4295] open buckets 0
[ 146.969499][ T3923] normal 58
[ 146.969500][ T4295] buckets to invalidate 0
[ 146.969500][ T3923] copygc 56
[ 146.969501][ T4295]
[ 146.969502][ T3923] btree 28
[ 146.969503][ T4295] Copygc debug:
[ 146.969503][ T3923] btree_copygc 0
[ 146.969504][ T4295] running: 0
[ 146.969504][ T3923] reclaim 0
[ 146.969506][ T4295] copygc_wait:0
[ 146.969506][ T3923] interior_updates 0
[ 146.969507][ T4295] copygc_wait_at:0
[ 146.969507][ T3923]
[ 146.969509][ T4295] Currently waiting for:0 B
[ 146.969509][ T3923] open buckets 0
[ 146.969510][ T4295] Currently waiting since:640 KiB
[ 146.969511][ T3923] buckets to invalidate 0
[ 146.969512][ T3923]
[ 146.969512][ T4295] Currently calculated wait:
[ 146.969513][ T3923] Copygc debug:
[ 146.969514][ T4295] loop3: 0 B
[ 146.969515][ T3923] running: 0
[ 146.969515][ T4295] [<0>] bch2_copygc_thread+0x47a/0x5b0
[ 146.969516][ T3923] copygc_wait:0
[ 146.969517][ T4295] [<0>] kthread+0x203/0x230
[ 146.969518][ T3923] copygc_wait_at:0
[ 146.969518][ T4295] [<0>] ret_from_fork+0x9d/0x170
[ 146.969519][ T3923] Currently waiting for:0 B
[ 146.969520][ T4295] [<0>] ret_from_fork_asm+0x1a/0x30
[ 146.969521][ T3923] Currently waiting since:640 KiB
[ 146.969522][ T4295]
[ 146.969522][ T3923] Currently calculated wait:
[ 146.969523][ T4295] Journal debug:
[ 146.969524][ T3923] loop3: 0 B
[ 146.969524][ T4295] flags: replay_done,running,may_skip_flush
[ 146.969525][ T3923] [<0>] bch2_copygc_thread+0x47a/0x5b0
[ 146.969526][ T4295] dirty journal entries: 3/32768
[ 146.969527][ T3923] [<0>] kthread+0x203/0x230
[ 146.969528][ T4295] seq: 20
[ 146.969528][ T3923] [<0>] ret_from_fork+0x9d/0x170
[ 146.969529][ T4295] seq_ondisk: 20
[ 146.969530][ T3923] [<0>] ret_from_fork_asm+0x1a/0x30
[ 146.969531][ T4295] last_seq: 18
[ 146.969531][ T3923]
[ 146.969532][ T4295] last_seq_ondisk: 18
[ 146.969533][ T3923] Journal debug:
[ 146.969534][ T4295] flushed_seq_ondisk: 20
[ 146.969534][ T3923] flags: replay_done,running,may_skip_flush
[ 146.969535][ T4295] watermark: stripe
[ 146.969536][ T3923] dirty journal entries: 3/32768
[ 146.969537][ T4295] each entry reserved: 321
[ 146.969538][ T3923] seq: 20
[ 146.969538][ T4295] nr flush writes: 6
[ 146.969539][ T3923] seq_ondisk: 20
[ 146.969540][ T4295] nr noflush writes: 1
[ 146.969541][ T3923] last_seq: 18
[ 146.969541][ T4295] average write size: 1.84 KiB
[ 146.969542][ T3923] last_seq_ondisk: 18
[ 146.969543][ T4295] free buf: 65536
[ 146.969544][ T3923] flushed_seq_ondisk: 20
[ 146.969545][ T4295] nr direct reclaim: 0
[ 146.969545][ T3923] watermark: stripe
[ 146.969546][ T4295] nr background reclaim: 17
[ 146.969547][ T3923] each entry reserved: 321
[ 146.969548][ T4295] reclaim kicked: 0
[ 146.969548][ T3923] nr flush writes: 6
[ 146.969549][ T4295] reclaim runs in: 0 ms
[ 146.969550][ T3923] nr noflush writes: 1
[ 146.969551][ T4295] blocked: 0
[ 146.969551][ T3923] average write size: 1.84 KiB
[ 146.969552][ T4295] current entry sectors: 256
[ 146.969553][ T3923] free buf: 65536
[ 146.969554][ T4295] current entry error: (No error)
[ 146.969555][ T3923] nr direct reclaim: 0
[ 146.969555][ T4295] current entry: closed
[ 146.969556][ T3923] nr background reclaim: 17
[ 146.969557][ T4295] unwritten entries:
[ 146.969558][ T3923] reclaim kicked: 0
[ 146.969558][ T4295] last buf closed
[ 146.969559][ T3923] reclaim runs in: 0 ms
[ 146.969560][ T4295] space:
[ 146.969561][ T3923] blocked: 0
[ 146.969561][ T4295] discarded 256:1536
[ 146.969562][ T3923] current entry sectors: 256
[ 146.969563][ T4295] clean ondisk 256:1536
[ 146.969564][ T3923] current entry error: (No error)
[ 146.969564][ T4295] clean 256:1536
[ 146.969565][ T3923] current entry: closed
[ 146.969566][ T4295] total 256:2048
[ 146.969567][ T3923] unwritten entries:
[ 146.969567][ T4295] dev 0:
[ 146.969568][ T3923] last buf closed
[ 146.969569][ T4295] durability 1:
[ 146.969570][ T3923] space:
[ 146.969570][ T4295] nr 8
[ 146.969571][ T3923] discarded 256:1536
[ 146.969572][ T4295] bucket size 256
[ 146.969573][ T3923] clean ondisk 256:1536
[ 146.969573][ T4295] available 6:192
[ 146.969574][ T3923] clean 256:1536
[ 146.969575][ T4295] discard_idx 0
[ 146.969576][ T3923] total 256:2048
[ 146.969576][ T4295] dirty_ondisk 0 (seq 20)
[ 146.969577][ T3923] dev 0:
[ 146.969578][ T4295] dirty_idx 0 (seq 20)
[ 146.969579][ T3923] durability 1:
[ 146.969579][ T4295] cur_idx 0 (seq 20)
[ 146.969580][ T3923] nr 8
[ 146.969581][ T4295] replicas want 1 need 1
[ 146.969582][ T3923] bucket size 256
[ 146.969583][ T3923] available 6:192
[ 146.969584][ T3923] discard_idx 0
[ 146.969586][ T3923] dirty_ondisk 0 (seq 20)
[ 147.973039][ T3923] dirty_idx 0 (seq 20)
[ 147.979382][ T3923] cur_idx 0 (seq 20)
[ 147.984925][ T3923] replicas want 1 need 1
[ 152.655765][ T2050] Bluetooth: hci5: unexpected cc 0x0c03 length: 249 > 1
[ 152.663260][ T2050] Bluetooth: hci5: unexpected cc 0x1003 length: 249 > 9
[ 152.670530][ T2050] Bluetooth: hci5: unexpected cc 0x1001 length: 249 > 9
[ 152.678202][ T2050] Bluetooth: hci5: unexpected cc 0x0c23 length: 249 > 4
[ 152.686066][ T2050] Bluetooth: hci5: unexpected cc 0x0c38 length: 249 > 2
[ 152.759082][ T5433] chnl_net:caif_netlink_parms(): no params data found
[ 153.320020][ T5433] 8021q: adding VLAN 0 to HW filter on device bond0
[ 153.748558][ T5433] 8021q: adding VLAN 0 to HW filter on device batadv0
2025/08/10 09:14:29 executed programs: 11
[ 154.644672][ T5798] loop7: detected capacity change from 0 to 32768
[ 154.663941][ T5798] bcachefs (loop7): starting version 1.7: mi_btree_bitmap opts=metadata_checksum=none,data_checksum=none,compression=lz4,background_compression=lz4,nojournal_transaction_names,version_upgrade=none
[ 154.663947][ T5798] allowing incompatible features above 0.0: (unknown version)
[ 154.663949][ T5798] features: lz4,new_siphash,inline_data,new_extent_overwrite,btree_ptr_v2,new_varint,journal_no_flush,alloc_v2,extents_across_btree_nodes
[ 154.708407][ T5798] bcachefs (loop7): recovering from clean shutdown, journal seq 13
[ 154.722627][ T5798] bcachefs (loop7): accounting_read... done
[ 154.729127][ T5798] bcachefs (loop7): alloc_read...
[ 154.729287][ T2050] Bluetooth: hci5: command tx timeout
[ 154.736422][ T5798] done
[ 154.742911][ T5798] bcachefs (loop7): snapshots_read... done
[ 154.750010][ T5798] bcachefs (loop7): going read-write
[ 154.756815][ T5798] bcachefs (loop7): journal_replay... done
[ 154.763689][ T5798] bcachefs (loop7): resume_logged_ops... done
[ 154.770203][ T5798] bcachefs (loop7): delete_dead_inodes...
[ 154.770433][ T5807] bcachefs (loop7): backpointer doesn't match extent it points to:
[ 154.770437][ T5807] u64s 9 type backpointer 0:8921088:0 len 0 ver 0: bucket=0:34:8 btree=extents level=0 data_type=user suboffset=0 len=8 gen=0 pos=1073741825:24:U32_MAX
[ 154.770439][ T5807] u64s 8 type extent 1073741825:24:U32_MAX len 24 ver 2: durability: 1 crc: c_size 8 size 24 offset 0 nonce 0 csum chacha20_poly1305_80 5c1d:75853c64f7009f9d compress lz4 ptr: 0:34:8 gen 0
[ 154.770442][ T5807] u64s 9 type backpointer 0:8921088:0 len 0 ver 0: bucket=0:34:8 btree=extents level=0 data_type=user suboffset=0 len=8 gen=0 pos=1073741825:24:U32_MAX, fixing
[ 154.770496][ T5798] done
[ 154.779459][ T5807] bcachefs (loop7): Detected missing backpointers in bucket 34, now have 1/128 with missing
[ 154.785710][ T5798] bcachefs (loop7): check_extents_to_backpointers...
[ 154.800778][ T5807] running recovery pass check_extents_to_backpointers (17), currently at delete_dead_inodes (40) - rewinding
[ 154.870335][ T5798] bcachefs (loop7): scanning for missing backpointers in 7/128 buckets
[ 154.878714][ T5798] bcachefs (loop7): missing backpointer
[ 154.878716][ T5798] for: u64s 8 type extent 1073741825:24:U32_MAX len 24 ver 2: durability: 1 crc: c_size 8 size 24 offset 0 nonce 0 csum chacha20_poly1305_80 5c1d:75853c64f7009f9d compress lz4 ptr: 0:34:8 gen 0
[ 154.878718][ T5798] want: u64s 9 type backpointer 0:8921088:0 len 0 ver 0: bucket=0:34:8 btree=extents level=0 data_type=user suboffset=0 len=8 gen=0 pos=1073741825:24:U32_MAX
[ 154.878720][ T5798] got: u64s 5 type deleted 0:8921088:0 len 0 ver 0, fixing
[ 154.929344][ T5798] done
[ 154.932926][ T5798] bcachefs (loop7): resume_logged_ops... done
[ 154.939429][ T5798] bcachefs (loop7): delete_dead_inodes... done
[ 154.947687][ T5798] bcachefs (loop7): Fixed errors, running fsck a second time to verify fs is clean
[ 154.959097][ T5798] bcachefs (loop7): resume_logged_ops... done
[ 154.965993][ T5798] bcachefs (loop7): delete_dead_inodes... done
[ 154.973289][ T5798] bcachefs (loop7): done starting filesystem
[ 156.799333][ T3162] Bluetooth: hci5: command tx timeout
[ 158.879352][ T3162] Bluetooth: hci5: command tx timeout
[ 160.959322][ T3162] Bluetooth: hci5: command tx timeout
[ 164.249444][ T4303] Allocator stuck? Waited for 30 seconds
[ 164.249449][ T4303] Allocator debug:
[ 164.249451][ T4303] capacity1536
[ 164.249453][ T4303] reserved 31232
[ 164.249455][ T4303] hidden 0
[ 164.249456][ T4303] btree 0
[ 164.249458][ T4303] data 18446744073709551608
[ 164.249460][ T4303] cached 0
[ 164.249461][ T4303] reserved 0
[ 164.249462][ T4303] online_reserved 1536
[ 164.249463][ T4303] nr_inodes 0
[ 164.249465][ T4303]
[ 164.249466][ T4303] freelist_wait waiting
[ 164.249468][ T4303] open buckets allocated1
[ 164.249469][ T4303] open buckets total 1024
[ 164.249470][ T4303] open_buckets_wait empty
[ 164.249472][ T4303] open_buckets_btree 0
[ 164.249473][ T4303] open_buckets_user 0
[ 164.249475][ T4303] btree reserve cache 0
[ 164.249476][ T4303]
[ 164.249477][ T4303] Dev 0:
[ 164.249479][ T4303] buckets sectors fragmented
[ 164.249481][ T4303] free 0 0 0
[ 164.249482][ T4303] sb 0 0 0
[ 164.249484][ T4303] journal 0 0 0
[ 164.249486][ T4303] btree 0 0 0
[ 164.249488][ T4303] user 018446744073709551608 8
[ 164.249489][ T4303] cached 0 0 0
[ 164.249491][ T4303] parity 0 0 0
[ 164.249492][ T4676] Allocator stuck? Waited for 30 seconds
[ 164.249492][ T4303] stripe 0 0 0
[ 164.249494][ T4676] Allocator debug:
[ 164.249494][ T4303] need_gc_gens 0 0 0
[ 164.249496][ T4676] capacity1536
[ 164.249496][ T4303] need_discard 0 0 0
[ 164.249497][ T4676] reserved 31232
[ 164.249498][ T4303] unstriped 0 0 0
[ 164.249499][ T4676] hidden 0
[ 164.249500][ T4303] capacity 128
[ 164.249500][ T4676] btree 0
[ 164.249501][ T4303]
[ 164.249502][ T4676] data 18446744073709551608
[ 164.249503][ T4303] reserves:
[ 164.249503][ T4676] cached 0
[ 164.249504][ T4303] stripe 60
[ 164.249505][ T4676] reserved 0
[ 164.249506][ T4303] normal 58
[ 164.249506][ T4676] online_reserved 1536
[ 164.249507][ T4303] copygc 56
[ 164.249508][ T4676] nr_inodes 0
[ 164.249509][ T4303] btree 28
[ 164.249509][ T4676]
[ 164.249510][ T4303] btree_copygc 0
[ 164.249511][ T4676] freelist_wait waiting
[ 164.249512][ T4303] reclaim 0
[ 164.249512][ T4676] open buckets allocated1
[ 164.249514][ T4303] interior_updates 0
[ 164.249514][ T4676] open buckets total 1024
[ 164.249515][ T4303]
[ 164.249515][ T4676] open_buckets_wait empty
[ 164.249516][ T4303] open buckets 0
[ 164.249517][ T4676] open_buckets_btree 0
[ 164.249518][ T4303] buckets to invalidate 0
[ 164.249518][ T4676] open_buckets_user 0
[ 164.249519][ T4303]
[ 164.249520][ T4676] btree reserve cache 0
[ 164.249521][ T4303] Copygc debug:
[ 164.249521][ T4676]
[ 164.249522][ T4303] running: 1
[ 164.249523][ T4676] Dev 0:
[ 164.249524][ T4303] copygc_wait:0
[ 164.249524][ T4676] buckets sectors fragmented
[ 164.249525][ T4303] copygc_wait_at:0
[ 164.249526][ T4676] free 0 0 0
[ 164.249526][ T4303] Currently waiting for:0 B
[ 164.249528][ T4676] sb 0 0 0
[ 164.249528][ T4303] Currently waiting since:640 KiB
[ 164.249530][ T4303] Currently calculated wait:
[ 164.249530][ T4676] journal 0 0 0
[ 164.249531][ T4303] loop4: 0 B
[ 164.249532][ T4676] btree 0 0 0
[ 164.249532][ T4303]
[ 164.249533][ T4676] user 018446744073709551608 8
[ 164.249534][ T4303] Journal debug:
[ 164.249535][ T4676] cached 0 0 0
[ 164.249535][ T4303] flags: replay_done,running,may_skip_flush
[ 164.249537][ T4676] parity 0 0 0
[ 164.249537][ T4303] dirty journal entries: 3/32768
[ 164.249539][ T4676] stripe 0 0 0
[ 164.249539][ T4303] seq: 16
[ 164.249540][ T4676] need_gc_gens 0 0 0
[ 164.249541][ T4303] seq_ondisk: 16
[ 164.249542][ T4303] last_seq: 14
[ 164.249542][ T4676] need_discard 0 0 0
[ 164.249544][ T4303] last_seq_ondisk: 14
[ 164.249544][ T4676] unstriped 0 0 0
[ 164.249545][ T4303] flushed_seq_ondisk: 16
[ 164.249546][ T4676] capacity 128
[ 164.249547][ T4303] watermark: stripe
[ 164.249548][ T4676]
[ 164.249549][ T4303] each entry reserved: 321
[ 164.249549][ T4676] reserves:
[ 164.249550][ T4303] nr flush writes: 3
[ 164.249551][ T4676] stripe 60
[ 164.249552][ T4303] nr noflush writes: 0
[ 164.249552][ T4676] normal 58
[ 164.249553][ T4303] average write size: 2.55 KiB
[ 164.249554][ T4676] copygc 56
[ 164.249555][ T4303] free buf: 65536
[ 164.249555][ T4676] btree 28
[ 164.249556][ T4303] nr direct reclaim: 0
[ 164.249557][ T4676] btree_copygc 0
[ 164.249558][ T4303] nr background reclaim: 17
[ 164.249559][ T4676] reclaim 0
[ 164.249559][ T4303] reclaim kicked: 0
[ 164.249560][ T4676] interior_updates 0
[ 164.249561][ T4303] reclaim runs in: 0 ms
[ 164.249561][ T4676]
[ 164.249562][ T4303] blocked: 0
[ 164.249563][ T4676] open buckets 0
[ 164.249564][ T4303] current entry sectors: 256
[ 164.249564][ T4676] buckets to invalidate 0
[ 164.249565][ T4303] current entry error: (No error)
[ 164.249566][ T4676]
[ 164.249567][ T4303] current entry: closed
[ 164.249567][ T4676] Copygc debug:
[ 164.249568][ T4303] unwritten entries:
[ 164.249569][ T4676] running: 1
[ 164.249570][ T4303] last buf closed
[ 164.249570][ T4676] copygc_wait:0
[ 164.249571][ T4303] space:
[ 164.249572][ T4676] copygc_wait_at:0
[ 164.249573][ T4303] discarded 256:1536
[ 164.249573][ T4676] Currently waiting for:0 B
[ 164.249574][ T4303] clean ondisk 256:1536
[ 164.249575][ T4676] Currently waiting since:640 KiB
[ 164.249576][ T4303] clean 256:1536
[ 164.249576][ T4676] Currently calculated wait:
[ 164.249577][ T4303] total 256:2048
[ 164.249578][ T4676] loop4: 0 B
[ 164.249579][ T4303] dev 0:
[ 164.249579][ T4676] [<0>] bch2_copygc_wait_to_text+0x2a7/0x340
[ 164.249580][ T4303] durability 1:
[ 164.249582][ T4676] [<0>] bch2_print_allocator_stuck+0x1c4/0x260
[ 164.249582][ T4303] nr 8
[ 164.249583][ T4676] [<0>] __bch2_wait_on_allocator+0x64/0x80
[ 164.249584][ T4303] bucket size 256
[ 164.249585][ T4676] [<0>] bch2_btree_update_start+0x983/0x9c0
[ 164.249585][ T4303] available 6:224
[ 164.249587][ T4676] [<0>] bch2_btree_split_leaf+0x51/0x220
[ 164.249587][ T4303] discard_idx 0
[ 164.249589][ T4676] [<0>] bch2_trans_commit_error+0xc4/0x560
[ 164.249589][ T4303] dirty_ondisk 0 (seq 16)
[ 164.249590][ T4676] [<0>] __bch2_trans_commit+0x6f2/0x2250
[ 164.249590][ T4303] dirty_idx 0 (seq 16)
[ 164.249592][ T4676] [<0>] bch2_btree_write_buffer_flush_locked+0xd42/0x1020
[ 164.249592][ T4303] cur_idx 0 (seq 16)
[ 164.249593][ T4303] replicas want 1 need 1
[ 164.249594][ T4676] [<0>] btree_write_buffer_flush_seq+0x64d/0x6d0
[ 164.249595][ T4676] [<0>] bch2_btree_write_buffer_flush_sync+0x65/0xe0
[ 164.249597][ T4676] [<0>] bch2_btree_write_buffer_maybe_flush+0x232/0x550
[ 164.249598][ T4676] [<0>] backpointer_target_not_found+0x9e/0x780
[ 164.249599][ T4676] [<0>] __bch2_backpointer_get_key+0x1ee/0x220
[ 165.111211][ T4676] [<0>] bch2_backpointer_get_key+0xb/0x20
[ 165.117401][ T4676] [<0>] __bch2_move_data_phys+0x966/0xd60
[ 165.123465][ T4676] [<0>] bch2_evacuate_bucket+0xa5/0x100
[ 165.129290][ T4676] [<0>] bch2_copygc+0x1353/0x15e0
[ 165.134738][ T4676] [<0>] bch2_copygc_thread+0x3ff/0x5b0
[ 165.140475][ T4676] [<0>] kthread+0x203/0x230
[ 165.145152][ T4676] [<0>] ret_from_fork+0x9d/0x170
[ 165.150377][ T4676] [<0>] ret_from_fork_asm+0x1a/0x30
[ 165.156009][ T4676]
[ 165.158327][ T4676] Journal debug:
[ 165.162086][ T4676] flags: replay_done,running,may_skip_flush
[ 165.170168][ T4676] dirty journal entries: 3/32768
[ 165.175798][ T4676] seq: 16
[ 165.180834][ T4676] seq_ondisk: 16
[ 165.185887][ T4676] last_seq: 14
[ 165.191815][ T4676] last_seq_ondisk: 14
[ 165.196774][ T4676] flushed_seq_ondisk: 16
[ 165.201774][ T4676] watermark: stripe
[ 165.207498][ T4676] each entry reserved: 321
[ 165.212820][ T4676] nr flush writes: 3
[ 165.217619][ T4676] nr noflush writes: 0
[ 165.222824][ T4676] average write size: 2.55 KiB
[ 165.228597][ T4676] free buf: 65536
[ 165.234348][ T4676] nr direct reclaim: 0
[ 165.239255][ T4676] nr background reclaim: 17
[ 165.244435][ T4676] reclaim kicked: 0
[ 165.249259][ T4676] reclaim runs in: 0 ms
[ 165.254460][ T4676] blocked: 0
[ 165.259502][ T4676] current entry sectors: 256
[ 165.264466][ T4676] current entry error: (No error)
[ 165.270215][ T4676] current entry: closed
[ 165.275613][ T4676] unwritten entries:
[ 165.280100][ T4676] last buf closed
[ 165.284231][ T4676] space:
[ 165.287597][ T4676] discarded 256:1536
[ 165.293274][ T4676] clean ondisk 256:1536
[ 165.298768][ T4676] clean 256:1536
[ 165.304301][ T4676] total 256:2048
[ 165.309964][ T4676] dev 0:
[ 165.313250][ T4676] durability 1:
[ 165.316861][ T4676] nr 8
[ 165.321629][ T4676] bucket size 256
[ 165.326813][ T4676] available 6:224
[ 165.332051][ T4676] discard_idx 0
[ 165.336800][ T4676] dirty_ondisk 0 (seq 16)
[ 165.342441][ T4676] dirty_idx 0 (seq 16)
[ 165.348271][ T4676] cur_idx 0 (seq 16)
[ 165.354028][ T4676] replicas want 1 need 1
[ 170.057893][ T2050] Bluetooth: hci6: unexpected cc 0x0c03 length: 249 > 1
[ 170.065476][ T2050] Bluetooth: hci6: unexpected cc 0x1003 length: 249 > 9
[ 170.073153][ T2050] Bluetooth: hci6: unexpected cc 0x1001 length: 249 > 9
[ 170.082050][ T2050] Bluetooth: hci6: unexpected cc 0x0c23 length: 249 > 4
[ 170.089879][ T2050] Bluetooth: hci6: unexpected cc 0x0c38 length: 249 > 2
[ 170.171422][ T5810] chnl_net:caif_netlink_parms(): no params data found
[ 170.770216][ T5810] 8021q: adding VLAN 0 to HW filter on device bond0
[ 171.211747][ T5810] 8021q: adding VLAN 0 to HW filter on device batadv0
2025/08/10 09:14:47 executed programs: 13
[ 172.113900][ T6174] loop8: detected capacity change from 0 to 32768
[ 172.133587][ T6174] bcachefs (loop8): starting version 1.7: mi_btree_bitmap opts=metadata_checksum=none,data_checksum=none,compression=lz4,background_compression=lz4,nojournal_transaction_names,version_upgrade=none
[ 172.133592][ T6174] allowing incompatible features above 0.0: (unknown version)
[ 172.133594][ T6174] features: lz4,new_siphash,inline_data,new_extent_overwrite,btree_ptr_v2,new_varint,journal_no_flush,alloc_v2,extents_across_btree_nodes
[ 172.162087][ T2050] Bluetooth: hci6: command tx timeout
[ 172.182794][ T6174] bcachefs (loop8): recovering from clean shutdown, journal seq 13
[ 172.197129][ T6174] bcachefs (loop8): accounting_read... done
[ 172.204422][ T6174] bcachefs (loop8): alloc_read... done
[ 172.212770][ T6174] bcachefs (loop8): snapshots_read... done
[ 172.221389][ T6174] bcachefs (loop8): going read-write
[ 172.229240][ T6174] bcachefs (loop8): journal_replay... done
[ 172.238629][ T6174] bcachefs (loop8): resume_logged_ops... done
[ 172.247062][ T6174] bcachefs (loop8): delete_dead_inodes...
[ 172.247320][ T6184] bcachefs (loop8): backpointer doesn't match extent it points to:
[ 172.247322][ T6184] u64s 9 type backpointer 0:8921088:0 len 0 ver 0: bucket=0:34:8 btree=extents level=0 data_type=user suboffset=0 len=8 gen=0 pos=1073741825:24:U32_MAX
[ 172.247324][ T6184] u64s 8 type extent 1073741825:24:U32_MAX len 24 ver 2: durability: 1 crc: c_size 8 size 24 offset 0 nonce 0 csum chacha20_poly1305_80 5c1d:75853c64f7009f9d compress lz4 ptr: 0:34:8 gen 0
[ 172.247326][ T6184] u64s 9 type backpointer 0:8921088:0 len 0 ver 0: bucket=0:34:8 btree=extents level=0 data_type=user suboffset=0 len=8 gen=0 pos=1073741825:24:U32_MAX, fixing
[ 172.253776][ T6174] done
[ 172.264320][ T6184] bcachefs (loop8): Detected missing backpointers in bucket 34, now have 1/128 with missing
[ 172.278161][ T6174] bcachefs (loop8): check_extents_to_backpointers...
[ 172.297745][ T6184] running recovery pass check_extents_to_backpointers (17), currently at delete_dead_inodes (40) - rewinding
[ 172.315020][ T6174] bcachefs (loop8): scanning for missing backpointers in 7/128 buckets
[ 172.357334][ T6174] bcachefs (loop8): missing backpointer
[ 172.357338][ T6174] for: u64s 8 type extent 1073741825:24:U32_MAX len 24 ver 2: durability: 1 crc: c_size 8 size 24 offset 0 nonce 0 csum chacha20_poly1305_80 5c1d:75853c64f7009f9d compress lz4 ptr: 0:34:8 gen 0
[ 172.357341][ T6174] want: u64s 9 type backpointer 0:8921088:0 len 0 ver 0: bucket=0:34:8 btree=extents level=0 data_type=user suboffset=0 len=8 gen=0 pos=1073741825:24:U32_MAX
[ 172.357343][ T6174] got: u64s 5 type deleted 0:8921088:0 len 0 ver 0, fixing
[ 172.408976][ T6174] done
[ 172.412470][ T6174] bcachefs (loop8): resume_logged_ops... done
[ 172.419104][ T6174] bcachefs (loop8): delete_dead_inodes... done
[ 172.426889][ T6174] bcachefs (loop8): Fixed errors, running fsck a second time to verify fs is clean
[ 172.436885][ T6174] bcachefs (loop8): resume_logged_ops... done
[ 172.443823][ T6174] bcachefs (loop8): delete_dead_inodes... done
[ 172.450991][ T6174] bcachefs (loop8): done starting filesystem
[ 174.239358][ T2050] Bluetooth: hci6: command tx timeout
[ 176.319425][ T2050] Bluetooth: hci6: command tx timeout
[ 178.399299][ T2050] Bluetooth: hci6: command tx timeout
[ 181.529452][ T4679] Allocator stuck? Waited for 30 seconds
[ 181.529458][ T4679] Allocator debug:
[ 181.529460][ T4679] capacity1536
[ 181.529462][ T4679] reserved 31232
[ 181.529464][ T4679] hidden 0
[ 181.529466][ T4679] btree 0
[ 181.529467][ T4679] data 18446744073709551608
[ 181.529469][ T4679] cached 0
[ 181.529470][ T5052] Allocator stuck? Waited for 30 seconds
[ 181.529471][ T4679] reserved 0
[ 181.529472][ T5052] Allocator debug:
[ 181.529472][ T4679] online_reserved 1536
[ 181.529473][ T5052] capacity1536
[ 181.529474][ T4679] nr_inodes 0
[ 181.529475][ T5052] reserved 31232
[ 181.529475][ T4679]
[ 181.529477][ T5052] hidden 0
[ 181.529477][ T4679] freelist_wait waiting
[ 181.529478][ T5052] btree 0
[ 181.529478][ T4679] open buckets allocated1
[ 181.529480][ T5052] data 18446744073709551608
[ 181.529480][ T4679] open buckets total 1024
[ 181.529481][ T5052] cached 0
[ 181.529481][ T4679] open_buckets_wait empty
[ 181.529483][ T5052] reserved 0
[ 181.529483][ T4679] open_buckets_btree 0
[ 181.529484][ T5052] online_reserved 1536
[ 181.529485][ T4679] open_buckets_user 0
[ 181.529486][ T5052] nr_inodes 0
[ 181.529486][ T4679] btree reserve cache 0
[ 181.529487][ T5052]
[ 181.529488][ T4679]
[ 181.529489][ T5052] freelist_wait waiting
[ 181.529490][ T4679] Dev 0:
[ 181.529491][ T5052] open buckets allocated1
[ 181.529491][ T4679] buckets sectors fragmented
[ 181.529492][ T5052] open buckets total 1024
[ 181.529493][ T4679] free 0 0 0
[ 181.529494][ T5052] open_buckets_wait empty
[ 181.529495][ T4679] sb 0 0 0
[ 181.529495][ T5052] open_buckets_btree 0
[ 181.529496][ T4679] journal 0 0 0
[ 181.529497][ T5052] open_buckets_user 0
[ 181.529498][ T4679] btree 0 0 0
[ 181.529498][ T5052] btree reserve cache 0
[ 181.529500][ T5052]
[ 181.529500][ T4679] user 018446744073709551608 8
[ 181.529501][ T5052] Dev 0:
[ 181.529502][ T4679] cached 0 0 0
[ 181.529503][ T5052] buckets sectors fragmented
[ 181.529504][ T4679] parity 0 0 0
[ 181.529504][ T5052] free 0 0 0
[ 181.529505][ T4679] stripe 0 0 0
[ 181.529506][ T5052] sb 0 0 0
[ 181.529507][ T4679] need_gc_gens 0 0 0
[ 181.529508][ T5052] journal 0 0 0
[ 181.529509][ T4679] need_discard 0 0 0
[ 181.529510][ T5052] btree 0 0 0
[ 181.529511][ T4679] unstriped 0 0 0
[ 181.529512][ T5052] user 018446744073709551608 8
[ 181.529513][ T4679] capacity 128
[ 181.529513][ T5052] cached 0 0 0
[ 181.529514][ T4679]
[ 181.529515][ T5052] parity 0 0 0
[ 181.529515][ T4679] reserves:
[ 181.529517][ T4679] stripe 60
[ 181.529517][ T5052] stripe 0 0 0
[ 181.529518][ T4679] normal 58
[ 181.529519][ T5052] need_gc_gens 0 0 0
[ 181.529520][ T4679] copygc 56
[ 181.529521][ T5052] need_discard 0 0 0
[ 181.529521][ T4679] btree 28
[ 181.529522][ T5052] unstriped 0 0 0
[ 181.529523][ T4679] btree_copygc 0
[ 181.529524][ T5052] capacity 128
[ 181.529525][ T4679] reclaim 0
[ 181.529526][ T5052]
[ 181.529526][ T4679] interior_updates 0
[ 181.529527][ T5052] reserves:
[ 181.529528][ T4679]
[ 181.529529][ T5052] stripe 60
[ 181.529529][ T4679] open buckets 0
[ 181.529530][ T5052] normal 58
[ 181.529531][ T4679] buckets to invalidate 0
[ 181.529532][ T5052] copygc 56
[ 181.529532][ T4679]
[ 181.529533][ T5052] btree 28
[ 181.529533][ T4679] Copygc debug:
[ 181.529534][ T5052] btree_copygc 0
[ 181.529535][ T4679] running: 0
[ 181.529536][ T5052] reclaim 0
[ 181.529536][ T4679] copygc_wait:0
[ 181.529537][ T5052] interior_updates 0
[ 181.529538][ T4679] copygc_wait_at:0
[ 181.529539][ T5052]
[ 181.529539][ T4679] Currently waiting for:0 B
[ 181.529540][ T5052] open buckets 0
[ 181.529541][ T4679] Currently waiting since:640 KiB
[ 181.529542][ T5052] buckets to invalidate 0
[ 181.529542][ T4679] Currently calculated wait:
[ 181.529543][ T5052]
[ 181.529544][ T4679] loop5: 0 B
[ 181.529545][ T5052] Copygc debug:
[ 181.529545][ T4679] [<0>] bch2_copygc_thread+0x47a/0x5b0
[ 181.529546][ T5052] running: 0
[ 181.529547][ T4679] [<0>] kthread+0x203/0x230
[ 181.529547][ T5052] copygc_wait:0
[ 181.529549][ T4679] [<0>] ret_from_fork+0x9d/0x170
[ 181.529549][ T5052] copygc_wait_at:0
[ 181.529550][ T4679] [<0>] ret_from_fork_asm+0x1a/0x30
[ 181.529550][ T5052] Currently waiting for:0 B
[ 181.529552][ T4679]
[ 181.529552][ T5052] Currently waiting since:640 KiB
[ 181.529553][ T4679] Journal debug:
[ 181.529554][ T5052] Currently calculated wait:
[ 181.529555][ T4679] flags: replay_done,running,may_skip_flush
[ 181.529555][ T5052] loop5: 0 B
[ 181.529557][ T5052] [<0>] bch2_copygc_thread+0x47a/0x5b0
[ 181.529557][ T4679] dirty journal entries: 3/32768
[ 181.529558][ T5052] [<0>] kthread+0x203/0x230
[ 181.529560][ T5052] [<0>] ret_from_fork+0x9d/0x170
[ 181.529562][ T5052] [<0>] ret_from_fork_asm+0x1a/0x30
[ 181.529563][ T5052]
[ 181.529564][ T5052] Journal debug:
[ 181.529566][ T5052] flags: replay_done,running,may_skip_flush
[ 181.529568][ T5052] dirty journal entries: 3/32768
[ 181.529569][ T5052] seq: 20
[ 181.529570][ T5052] seq_ondisk: 20
[ 181.529572][ T5052] last_seq: 18
[ 181.529573][ T5052] last_seq_ondisk: 18
[ 181.529574][ T5052] flushed_seq_ondisk: 20
[ 181.529576][ T5052] watermark: stripe
[ 181.529578][ T5052] each entry reserved: 321
[ 181.529579][ T5052] nr flush writes: 6
[ 181.529580][ T5052] nr noflush writes: 1
[ 181.529581][ T5052] average write size: 1.84 KiB
[ 181.529583][ T5052] free buf: 65536
[ 181.529584][ T5052] nr direct reclaim: 0
[ 181.529585][ T5052] nr background reclaim: 17
[ 181.529587][ T5052] reclaim kicked: 0
[ 181.529588][ T5052] reclaim runs in: 0 ms
[ 181.529589][ T5052] blocked: 0
[ 181.529591][ T5052] current entry sectors: 256
[ 181.529592][ T5052] current entry error: (No error)
[ 181.529593][ T5052] current entry: closed
[ 181.529595][ T5052] unwritten entries:
[ 181.529596][ T5052] last buf closed
[ 181.529597][ T5052] space:
[ 181.529599][ T5052] discarded 256:1536
[ 181.529558][ T4679] seq: 20
[ 181.529601][ T5052] clean ondisk 256:1536
[ 181.529602][ T4679] seq_ondisk: 20
[ 181.529603][ T5052] clean 256:1536
[ 181.529604][ T4679] last_seq: 18
[ 181.529604][ T5052] total 256:2048
[ 181.529605][ T4679] last_seq_ondisk: 18
[ 181.529606][ T5052] dev 0:
[ 181.529607][ T4679] flushed_seq_ondisk: 20
[ 181.529607][ T5052] durability 1:
[ 181.529609][ T4679] watermark: stripe
[ 181.529609][ T5052] nr 8
[ 181.529610][ T4679] each entry reserved: 321
[ 181.529610][ T5052] bucket size 256
[ 181.529612][ T4679] nr flush writes: 6
[ 181.529612][ T5052] available 6:192
[ 181.529613][ T4679] nr noflush writes: 1
[ 181.529614][ T5052] discard_idx 0
[ 181.529615][ T4679] average write size: 1.84 KiB
[ 181.529615][ T5052] dirty_ondisk 0 (seq 20)
[ 181.529616][ T4679] free buf: 65536
[ 181.529617][ T5052] dirty_idx 0 (seq 20)
[ 181.529618][ T4679] nr direct reclaim: 0
[ 181.529618][ T5052] cur_idx 0 (seq 20)
[ 181.529620][ T4679] nr background reclaim: 17
[ 181.529620][ T5052] replicas want 1 need 1
[ 181.529621][ T4679] reclaim kicked: 0
[ 181.529622][ T4679] reclaim runs in: 0 ms
[ 181.529624][ T4679] blocked: 0
[ 181.529625][ T4679] current entry sectors: 256
[ 181.529626][ T4679] current entry error: (No error)
[ 181.529627][ T4679] current entry: closed
[ 181.529629][ T4679] unwritten entries:
[ 181.529630][ T4679] last buf closed
[ 181.529631][ T4679] space:
[ 181.529632][ T4679] discarded 256:1536
[ 181.529634][ T4679] clean ondisk 256:1536
[ 181.529635][ T4679] clean 256:1536
[ 181.529636][ T4679] total 256:2048
[ 181.529637][ T4679] dev 0:
[ 181.529639][ T4679] durability 1:
[ 181.529640][ T4679] nr 8
[ 181.529641][ T4679] bucket size 256
[ 181.529642][ T4679] available 6:192
[ 181.529643][ T4679] discard_idx 0
[ 181.529645][ T4679] dirty_ondisk 0 (seq 20)
[ 181.529646][ T4679] dirty_idx 0 (seq 20)
[ 181.529647][ T4679] cur_idx 0 (seq 20)
[ 181.529649][ T4679] replicas want 1 need 1
[ 187.563344][ T2050] Bluetooth: hci7: unexpected cc 0x0c03 length: 249 > 1
[ 187.571043][ T2050] Bluetooth: hci7: unexpected cc 0x1003 length: 249 > 9
[ 187.578500][ T2050] Bluetooth: hci7: unexpected cc 0x1001 length: 249 > 9
[ 187.586334][ T2050] Bluetooth: hci7: unexpected cc 0x0c23 length: 249 > 4
[ 187.594055][ T2050] Bluetooth: hci7: unexpected cc 0x0c38 length: 249 > 2
[ 187.678576][ T6187] chnl_net:caif_netlink_parms(): no params data found
[ 188.265570][ T6187] 8021q: adding VLAN 0 to HW filter on device bond0
[ 188.559356][ T612] ieee802154 phy0 wpan0: encryption failed: -22
[ 188.565802][ T612] ieee802154 phy1 wpan1: encryption failed: -22
[ 188.684354][ T6187] 8021q: adding VLAN 0 to HW filter on device batadv0
2025/08/10 09:15:04 executed programs: 15
[ 189.569041][ T6552] loop9: detected capacity change from 0 to 32768
[ 189.589110][ T6552] bcachefs (loop9): starting version 1.7: mi_btree_bitmap opts=metadata_checksum=none,data_checksum=none,compression=lz4,background_compression=lz4,nojournal_transaction_names,version_upgrade=none
[ 189.589116][ T6552] allowing incompatible features above 0.0: (unknown version)
[ 189.589118][ T6552] features: lz4,new_siphash,inline_data,new_extent_overwrite,btree_ptr_v2,new_varint,journal_no_flush,alloc_v2,extents_across_btree_nodes
[ 189.631721][ T6552] bcachefs (loop9): recovering from clean shutdown, journal seq 13
[ 189.645344][ T6552] bcachefs (loop9): accounting_read... done
[ 189.651681][ T6552] bcachefs (loop9): alloc_read... done
[ 189.658467][ T6552] bcachefs (loop9): snapshots_read... done
[ 189.665594][ T6552] bcachefs (loop9): going read-write
[ 189.672472][ T6552] bcachefs (loop9): journal_replay... done
[ 189.678806][ T6552] bcachefs (loop9): resume_logged_ops... done
[ 189.679230][ T3162] Bluetooth: hci7: command tx timeout
[ 189.685722][ T6552] bcachefs (loop9): delete_dead_inodes...
[ 189.691416][ T6561] bcachefs (loop9): backpointer doesn't match extent it points to:
[ 189.691420][ T6561] u64s 9 type backpointer 0:8921088:0 len 0 ver 0: bucket=0:34:8 btree=extents level=0 data_type=user suboffset=0 len=8 gen=0 pos=1073741825:24:U32_MAX
[ 189.691423][ T6561] u64s 8 type extent 1073741825:24:U32_MAX len 24 ver 2: durability: 1 crc: c_size 8 size 24 offset 0 nonce 0 csum chacha20_poly1305_80 5c1d:75853c64f7009f9d compress lz4 ptr: 0:34:8 gen 0
[ 189.691426][ T6561] u64s 9 type backpointer 0:8921088:0 len 0 ver 0: bucket=0:34:8 btree=extents level=0 data_type=user suboffset=0 len=8 gen=0 pos=1073741825:24:U32_MAX, fixing
[ 189.699593][ T6552] done
[ 189.706235][ T6561] bcachefs (loop9): Detected missing backpointers in bucket 34, now have 1/128 with missing
[ 189.724670][ T6552] bcachefs (loop9): check_extents_to_backpointers...
[ 189.740539][ T6561] running recovery pass check_extents_to_backpointers (17), currently at delete_dead_inodes (40) - rewinding
[ 189.789284][ T6552] bcachefs (loop9): scanning for missing backpointers in 7/128 buckets
[ 189.798041][ T6552] bcachefs (loop9): missing backpointer
[ 189.798044][ T6552] for: u64s 8 type extent 1073741825:24:U32_MAX len 24 ver 2: durability: 1 crc: c_size 8 size 24 offset 0 nonce 0 csum chacha20_poly1305_80 5c1d:75853c64f7009f9d compress lz4 ptr: 0:34:8 gen 0
[ 189.798046][ T6552] want: u64s 9 type backpointer 0:8921088:0 len 0 ver 0: bucket=0:34:8 btree=extents level=0 data_type=user suboffset=0 len=8 gen=0 pos=1073741825:24:U32_MAX
[ 189.798047][ T6552] got: u64s 5 type deleted 0:8921088:0 len 0 ver 0, fixing
[ 189.847818][ T6552] done
[ 189.851235][ T6552] bcachefs (loop9): resume_logged_ops... done
[ 189.857730][ T6552] bcachefs (loop9): delete_dead_inodes... done
[ 189.865241][ T6552] bcachefs (loop9): Fixed errors, running fsck a second time to verify fs is clean
[ 189.875151][ T6552] bcachefs (loop9): resume_logged_ops... done
[ 189.881662][ T6552] bcachefs (loop9): delete_dead_inodes... done
[ 189.891458][ T6552] bcachefs (loop9): done starting filesystem
[ 190.639317][ T2343] Bluetooth: hci0: command 0x0406 tx timeout
[ 191.759288][ T2050] Bluetooth: hci7: command tx timeout
[ 193.839276][ T2050] Bluetooth: hci7: command tx timeout
[ 195.919396][ T2050] Bluetooth: hci7: command tx timeout
[ 199.449439][ T5056] Allocator stuck? Waited for 30 seconds
[ 199.449444][ T5056] Allocator debug:
[ 199.449446][ T5056] capacity1536
[ 199.449448][ T5056] reserved 31232
[ 199.449449][ T5056] hidden 0
[ 199.449451][ T5056] btree 0
[ 199.449453][ T5056] data 18446744073709551608
[ 199.449455][ T5056] cached 0
[ 199.449456][ T5056] reserved 0
[ 199.449458][ T5056] online_reserved 1536
[ 199.449459][ T5056] nr_inodes 0
[ 199.449461][ T5056]
[ 199.449462][ T5056] freelist_wait waiting
[ 199.449464][ T5056] open buckets allocated1
[ 199.449466][ T5056] open buckets total 1024
[ 199.449468][ T5056] open_buckets_wait empty
[ 199.449469][ T5056] open_buckets_btree 0
[ 199.449471][ T5056] open_buckets_user 0
[ 199.449472][ T5056] btree reserve cache 0
[ 199.449474][ T5056]
[ 199.449475][ T5056] Dev 0:
[ 199.449477][ T5056] buckets sectors fragmented
[ 199.449478][ T5056] free 0 0 0
[ 199.449480][ T5056] sb 0 0 0
[ 199.449482][ T5056] journal 0 0 0
[ 199.449484][ T5056] btree 0 0 0
[ 199.449485][ T5056] user 018446744073709551608 8
[ 199.449487][ T5056] cached 0 0 0
[ 199.449489][ T5056] parity 0 0 0
[ 199.449491][ T5056] stripe 0 0 0
[ 199.449493][ T5056] need_gc_gens 0 0 0
[ 199.449494][ T5056] need_discard 0 0 0
[ 199.449496][ T5056] unstriped 0 0 0
[ 199.449498][ T5056] capacity 128
[ 199.449499][ T5056]
[ 199.449500][ T5056] reserves:
[ 199.449502][ T5056] stripe 60
[ 199.449503][ T5056] normal 58
[ 199.449505][ T5056] copygc 56
[ 199.449506][ T5056] btree 28
[ 199.449508][ T5056] btree_copygc 0
[ 199.449509][ T5056] reclaim 0
[ 199.449511][ T5056] interior_updates 0
[ 199.449512][ T5056]
[ 199.449513][ T5056] open buckets 0
[ 199.449515][ T5056] buckets to invalidate 0
[ 199.449516][ T5056]
[ 199.449517][ T5056] Copygc debug:
[ 199.449519][ T5056] running: 0
[ 199.449520][ T5056] copygc_wait:0
[ 199.449522][ T5056] copygc_wait_at:0
[ 199.449523][ T5056] Currently waiting for:0 B
[ 199.449525][ T5056] Currently waiting since:640 KiB
[ 199.449526][ T5056] Currently calculated wait:
[ 199.449528][ T5056] loop6: 0 B
[ 199.449529][ T5056] [<0>] bch2_copygc_thread+0x47a/0x5b0
[ 199.449531][ T5056] [<0>] kthread+0x203/0x230
[ 199.449532][ T5056] [<0>] ret_from_fork+0x9d/0x170
[ 199.449534][ T5056] [<0>] ret_from_fork_asm+0x1a/0x30
[ 199.449535][ T5056]
[ 199.449537][ T5056] Journal debug:
[ 199.449539][ T5056] flags: replay_done,running,may_skip_flush
[ 199.449540][ T5056] dirty journal entries: 3/32768
[ 199.449542][ T5056] seq: 20
[ 199.449544][ T5056] seq_ondisk: 20
[ 199.449545][ T5056] last_seq: 18
[ 199.449552][ T5056] last_seq_ondisk: 18
[ 199.449554][ T5056] flushed_seq_ondisk: 20
[ 199.449555][ T5056] watermark: stripe
[ 199.449557][ T5056] each entry reserved: 321
[ 199.449558][ T5056] nr flush writes: 6
[ 199.449560][ T5056] nr noflush writes: 1
[ 199.449562][ T5056] average write size: 1.84 KiB
[ 199.449564][ T5056] free buf: 65536
[ 199.449565][ T5056] nr direct reclaim: 0
[ 199.449567][ T5056] nr background reclaim: 17
[ 199.449568][ T5056] reclaim kicked: 0
[ 199.449570][ T5056] reclaim runs in: 0 ms
[ 199.449571][ T5056] blocked: 0
[ 199.449573][ T5056] current entry sectors: 256
[ 199.449574][ T5056] current entry error: (No error)
[ 199.449576][ T5056] current entry: closed
[ 199.449577][ T5056] unwritten entries:
[ 199.449579][ T5056] last buf closed
[ 199.449580][ T5056] space:
[ 199.449582][ T5056] discarded 256:1536
[ 199.449583][ T5056] clean ondisk 256:1536
[ 199.449585][ T5056] clean 256:1536
[ 199.449586][ T5056] total 256:2048
[ 199.449588][ T5056] dev 0:
[ 199.449589][ T5056] durability 1:
[ 199.449591][ T5056] nr 8
[ 199.449592][ T5056] bucket size 256
[ 199.449593][ T5056] available 6:192
[ 199.449595][ T5056] discard_idx 0
[ 199.449596][ T5056] dirty_ondisk 0 (seq 20)
[ 199.449598][ T5056] dirty_idx 0 (seq 20)
[ 199.449600][ T5056] cur_idx 0 (seq 20)
[ 199.449601][ T5056] replicas want 1 need 1
[ 199.452334][ T5429] Allocator stuck? Waited for 30 seconds
[ 199.954650][ T5429] Allocator debug:
[ 199.958371][ T5429] capacity1536
[ 199.961932][ T5429] reserved 31232
[ 199.967394][ T5429] hidden 0
[ 199.972251][ T5429] btree 0
[ 199.976957][ T5429] data 18446744073709551608
[ 199.983838][ T5429] cached 0
[ 199.988363][ T5429] reserved 0
[ 199.992924][ T5429] online_reserved 1536
[ 199.997612][ T5429] nr_inodes 0
[ 200.002227][ T5429]
[ 200.004859][ T5429] freelist_wait waiting
[ 200.010132][ T5429] open buckets allocated1
[ 200.014812][ T5429] open buckets total 1024
[ 200.019688][ T5429] open_buckets_wait empty
[ 200.024711][ T5429] open_buckets_btree 0
[ 200.029136][ T5429] open_buckets_user 0
[ 200.033568][ T5429] btree reserve cache 0
[ 200.038150][ T5429]
[ 200.040514][ T5429] Dev 0:
[ 200.043453][ T5429] buckets sectors fragmented
[ 200.051730][ T5429] free 0 0 0
[ 200.059631][ T5429] sb 0 0 0
[ 200.067346][ T5429] journal 0 0 0
[ 200.075302][ T5429] btree 0 0 0
[ 200.083349][ T5429] user 018446744073709551608 8
[ 200.091112][ T5429] cached 0 0 0
[ 200.098999][ T5429] parity 0 0 0
[ 200.106783][ T5429] stripe 0 0 0
[ 200.114518][ T5429] need_gc_gens 0 0 0
[ 200.122379][ T5429] need_discard 0 0 0
[ 200.130313][ T5429] unstriped 0 0 0
[ 200.138892][ T5429] capacity 128
[ 200.144234][ T5429]
[ 200.146964][ T5429] reserves:
[ 200.150560][ T5429] stripe 60
[ 200.156107][ T5429] normal 58
[ 200.162143][ T5429] copygc 56
[ 200.167100][ T5429] btree 28
[ 200.172669][ T5429] btree_copygc 0
[ 200.178110][ T5429] reclaim 0
[ 200.183353][ T5429] interior_updates 0
[ 200.188485][ T5429]
[ 200.191025][ T5429] open buckets 0
[ 200.196238][ T5429] buckets to invalidate 0
[ 200.201454][ T5429]
[ 200.204475][ T5429] Copygc debug:
[ 200.208364][ T5429] running: 0
[ 200.211849][ T5429] copygc_wait:0
[ 200.215685][ T5429] copygc_wait_at:0
[ 200.219690][ T5429] Currently waiting for:0 B
[ 200.224361][ T5429] Currently waiting since:640 KiB
[ 200.229859][ T5429] Currently calculated wait:
[ 200.235146][ T5429] loop6: 0 B
[ 200.238777][ T5429] [<0>] bch2_copygc_thread+0x47a/0x5b0
[ 200.244452][ T5429] [<0>] kthread+0x203/0x230
[ 200.249416][ T5429] [<0>] ret_from_fork+0x9d/0x170
[ 200.254738][ T5429] [<0>] ret_from_fork_asm+0x1a/0x30
[ 200.260360][ T5429]
[ 200.262860][ T5429] Journal debug:
[ 200.266751][ T5429] flags: replay_done,running,may_skip_flush
[ 200.274588][ T5429] dirty journal entries: 3/32768
[ 200.280048][ T5429] seq: 20
[ 200.284999][ T5429] seq_ondisk: 20
[ 200.289861][ T5429] last_seq: 18
[ 200.295054][ T5429] last_seq_ondisk: 18
[ 200.300008][ T5429] flushed_seq_ondisk: 20
[ 200.305024][ T5429] watermark: stripe
[ 200.310404][ T5429] each entry reserved: 321
[ 200.315419][ T5429] nr flush writes: 6
[ 200.320567][ T5429] nr noflush writes: 1
[ 200.325416][ T5429] average write size: 1.84 KiB
[ 200.330801][ T5429] free buf: 65536
[ 200.335910][ T5429] nr direct reclaim: 0
[ 200.341135][ T5429] nr background reclaim: 17
[ 200.346001][ T5429] reclaim kicked: 0
[ 200.350787][ T5429] reclaim runs in: 0 ms
[ 200.356409][ T5429] blocked: 0
[ 200.361661][ T5429] current entry sectors: 256
[ 200.366853][ T5429] current entry error: (No error)
[ 200.373040][ T5429] current entry: closed
[ 200.378247][ T5429] unwritten entries:
[ 200.382508][ T5429] last buf closed
[ 200.386490][ T5429] space:
[ 200.389627][ T5429] discarded 256:1536
[ 200.395200][ T5429] clean ondisk 256:1536
[ 200.400706][ T5429] clean 256:1536
[ 200.406425][ T5429] total 256:2048
[ 200.412078][ T5429] dev 0:
[ 200.415215][ T5429] durability 1:
[ 200.418954][ T5429] nr 8
[ 200.423933][ T5429] bucket size 256
[ 200.428958][ T5429] available 6:192
[ 200.434278][ T5429] discard_idx 0
[ 200.439243][ T5429] dirty_ondisk 0 (seq 20)
[ 200.444880][ T5429] dirty_idx 0 (seq 20)
[ 200.450944][ T5429] cur_idx 0 (seq 20)
[ 200.457213][ T5429] replicas want 1 need 1
[ 204.976478][ T3162] Bluetooth: hci8: unexpected cc 0x0c03 length: 249 > 1
[ 204.983646][ T3162] Bluetooth: hci8: unexpected cc 0x1003 length: 249 > 9
[ 204.991230][ T3162] Bluetooth: hci8: unexpected cc 0x1001 length: 249 > 9
[ 204.999013][ T3162] Bluetooth: hci8: unexpected cc 0x0c23 length: 249 > 4
[ 205.006607][ T3162] Bluetooth: hci8: unexpected cc 0x0c38 length: 249 > 2
[ 205.087576][ T6564] chnl_net:caif_netlink_parms(): no params data found
[ 205.696792][ T6564] 8021q: adding VLAN 0 to HW filter on device bond0
[ 205.999677][ T3162] Bluetooth: hci1: command 0x0406 tx timeout
[ 206.119863][ T6564] 8021q: adding VLAN 0 to HW filter on device batadv0
2025/08/10 09:15:22 executed programs: 17
[ 207.010066][ T6929] loop0: detected capacity change from 0 to 32768
[ 207.031931][ T6929] bcachefs (loop0): starting version 1.7: mi_btree_bitmap opts=metadata_checksum=none,data_checksum=none,compression=lz4,background_compression=lz4,nojournal_transaction_names,version_upgrade=none
[ 207.031936][ T6929] allowing incompatible features above 0.0: (unknown version)
[ 207.031937][ T6929] features: lz4,new_siphash,inline_data,new_extent_overwrite,btree_ptr_v2,new_varint,journal_no_flush,alloc_v2,extents_across_btree_nodes
[ 207.057057][ T2050] Bluetooth: hci8: command tx timeout
[ 207.059823][ T6929] bcachefs (loop0): recovering from clean shutdown, journal seq 13
[ 207.093670][ T6929] bcachefs (loop0): accounting_read... done
[ 207.100084][ T6929] bcachefs (loop0): alloc_read... done
[ 207.106195][ T6929] bcachefs (loop0): snapshots_read... done
[ 207.113649][ T6929] bcachefs (loop0): going read-write
[ 207.123037][ T6929] bcachefs (loop0): journal_replay... done
[ 207.129764][ T6929] bcachefs (loop0): resume_logged_ops... done
[ 207.136576][ T6929] bcachefs (loop0): delete_dead_inodes...
[ 207.136774][ T6938] bcachefs (loop0): backpointer doesn't match extent it points to:
[ 207.136777][ T6938] u64s 9 type backpointer 0:8921088:0 len 0 ver 0: bucket=0:34:8 btree=extents level=0 data_type=user suboffset=0 len=8 gen=0 pos=1073741825:24:U32_MAX
[ 207.136779][ T6938] u64s 8 type extent 1073741825:24:U32_MAX len 24 ver 2: durability: 1 crc: c_size 8 size 24 offset 0 nonce 0 csum chacha20_poly1305_80 5c1d:75853c64f7009f9d compress lz4 ptr: 0:34:8 gen 0
[ 207.136781][ T6938] u64s 9 type backpointer 0:8921088:0 len 0 ver 0: bucket=0:34:8 btree=extents level=0 data_type=user suboffset=0 len=8 gen=0 pos=1073741825:24:U32_MAX, fixing
[ 207.143075][ T6929] done
[ 207.152543][ T6938] bcachefs (loop0): Detected missing backpointers in bucket 34, now have 1/128 with missing
[ 207.171869][ T6929] bcachefs (loop0): check_extents_to_backpointers...
[ 207.188027][ T6938] running recovery pass check_extents_to_backpointers (17), currently at delete_dead_inodes (40) - rewinding
[ 207.236595][ T6929] bcachefs (loop0): scanning for missing backpointers in 7/128 buckets
[ 207.245272][ T6929] bcachefs (loop0): missing backpointer
[ 207.245276][ T6929] for: u64s 8 type extent 1073741825:24:U32_MAX len 24 ver 2: durability: 1 crc: c_size 8 size 24 offset 0 nonce 0 csum chacha20_poly1305_80 5c1d:75853c64f7009f9d compress lz4 ptr: 0:34:8 gen 0
[ 207.245278][ T6929] want: u64s 9 type backpointer 0:8921088:0 len 0 ver 0: bucket=0:34:8 btree=extents level=0 data_type=user suboffset=0 len=8 gen=0 pos=1073741825:24:U32_MAX
[ 207.245281][ T6929] got: u64s 5 type deleted 0:8921088:0 len 0 ver 0, fixing
[ 207.294993][ T6929] done
[ 207.298384][ T6929] bcachefs (loop0): resume_logged_ops... done
[ 207.304827][ T6929] bcachefs (loop0): delete_dead_inodes... done
[ 207.312615][ T6929] bcachefs (loop0): Fixed errors, running fsck a second time to verify fs is clean
[ 207.322628][ T6929] bcachefs (loop0): resume_logged_ops... done
[ 207.329266][ T6929] bcachefs (loop0): delete_dead_inodes... done
[ 207.338304][ T6929] bcachefs (loop0): done starting filesystem
[ 209.119368][ T2050] Bluetooth: hci8: command tx timeout
[ 211.199272][ T2050] Bluetooth: hci8: command tx timeout
[ 213.279317][ T2050] Bluetooth: hci8: command tx timeout
[ 216.729539][ T5806] Allocator stuck? Waited for 30 seconds
[ 216.729542][ T5806] Allocator debug:
[ 216.729544][ T5806] capacity1536
[ 216.729545][ T5806] reserved 31232
[ 216.729546][ T5806] hidden 0
[ 216.729547][ T5806] btree 0
[ 216.729548][ T5806] data 18446744073709551608
[ 216.729550][ T5806] cached 0
[ 216.729551][ T5806] reserved 0
[ 216.729552][ T5806] online_reserved 1536
[ 216.729553][ T5806] nr_inodes 0
[ 216.729554][ T5806]
[ 216.729555][ T5806] freelist_wait waiting
[ 216.729556][ T5806] open buckets allocated1
[ 216.729557][ T5806] open buckets total 1024
[ 216.729558][ T5806] open_buckets_wait empty
[ 216.729559][ T5806] open_buckets_btree 0
[ 216.729560][ T5806] open_buckets_user 0
[ 216.729561][ T5806] btree reserve cache 0
[ 216.729562][ T5806]
[ 216.729563][ T5806] Dev 0:
[ 216.729564][ T5806] buckets sectors fragmented
[ 216.729565][ T5806] free 0 0 0
[ 216.729566][ T5806] sb 0 0 0
[ 216.729567][ T5806] journal 0 0 0
[ 216.729568][ T5806] btree 0 0 0
[ 216.729570][ T5806] user 018446744073709551608 8
[ 216.729571][ T5806] cached 0 0 0
[ 216.729572][ T5806] parity 0 0 0
[ 216.729573][ T5806] stripe 0 0 0
[ 216.729574][ T5806] need_gc_gens 0 0 0
[ 216.729575][ T5806] need_discard 0 0 0
[ 216.729577][ T5806] unstriped 0 0 0
[ 216.729578][ T5806] capacity 128
[ 216.729579][ T5806]
[ 216.729580][ T5806] reserves:
[ 216.729581][ T5806] stripe 60
[ 216.729582][ T5806] normal 58
[ 216.729583][ T5806] copygc 56
[ 216.729583][ T5806] btree 28
[ 216.729584][ T5806] btree_copygc 0
[ 216.729585][ T5806] reclaim 0
[ 216.729586][ T5806] interior_updates 0
[ 216.729587][ T5806]
[ 216.729588][ T5806] open buckets 0
[ 216.729589][ T5806] buckets to invalidate 0
[ 216.729590][ T5806]
[ 216.729591][ T5806] Copygc debug:
[ 216.729592][ T5806] running: 0
[ 216.729593][ T5806] copygc_wait:0
[ 216.729594][ T5806] copygc_wait_at:0
[ 216.729595][ T5806] Currently waiting for:0 B
[ 216.729596][ T5806] Currently waiting since:640 KiB
[ 216.729598][ T5806] Currently calculated wait:
[ 216.729599][ T5806] loop7: 0 B
[ 216.729600][ T5806] [<0>] bch2_copygc_thread+0x47a/0x5b0
[ 216.729601][ T5806] [<0>] kthread+0x203/0x230
[ 216.729602][ T5806] [<0>] ret_from_fork+0x9d/0x170
[ 216.729603][ T5806] [<0>] ret_from_fork_asm+0x1a/0x30
[ 216.729604][ T5806]
[ 216.729605][ T5806] Journal debug:
[ 216.729606][ T5806] flags: replay_done,running,may_skip_flush
[ 216.729607][ T5806] dirty journal entries: 3/32768
[ 216.729608][ T5806] seq: 20
[ 216.729609][ T5806] seq_ondisk: 20
[ 216.729610][ T5806] last_seq: 18
[ 216.729611][ T5806] last_seq_ondisk: 18
[ 216.729612][ T5806] flushed_seq_ondisk: 20
[ 216.729613][ T5806] watermark: stripe
[ 216.729614][ T5806] each entry reserved: 321
[ 216.729615][ T5806] nr flush writes: 6
[ 216.729616][ T5806] nr noflush writes: 1
[ 216.729617][ T5806] average write size: 1.84 KiB
[ 216.729618][ T5806] free buf: 65536
[ 216.729619][ T5806] nr direct reclaim: 0
[ 216.729620][ T5806] nr background reclaim: 17
[ 216.729621][ T5806] reclaim kicked: 0
[ 216.729621][ T5806] reclaim runs in: 0 ms
[ 216.729622][ T5806] blocked: 0
[ 216.729623][ T5806] current entry sectors: 256
[ 216.729624][ T5806] current entry error: (No error)
[ 216.729625][ T5806] current entry: closed
[ 216.729626][ T5806] unwritten entries:
[ 216.729627][ T5806] last buf closed
[ 216.729628][ T5806] space:
[ 216.729629][ T5806] discarded 256:1536
[ 216.729630][ T5806] clean ondisk 256:1536
[ 216.729631][ T5806] clean 256:1536
[ 216.729632][ T5806] total 256:2048
[ 216.729633][ T5806] dev 0:
[ 216.729634][ T5806] durability 1:
[ 216.729635][ T5806] nr 8
[ 216.729636][ T5806] bucket size 256
[ 216.729637][ T5806] available 6:192
[ 216.729638][ T5806] discard_idx 0
[ 216.729639][ T5806] dirty_ondisk 0 (seq 20)
[ 216.729640][ T5806] dirty_idx 0 (seq 20)
[ 216.729641][ T5806] cur_idx 0 (seq 20)
[ 216.729643][ T5806] replicas want 1 need 1
[ 216.739563][ T5433] Allocator stuck? Waited for 30 seconds
[ 216.739567][ T5433] Allocator debug:
[ 217.243418][ T5433] capacity1536
[ 217.246957][ T5433] reserved 31232
[ 217.251730][ T5433] hidden 0
[ 217.256334][ T5433] btree 0
[ 217.260847][ T5433] data 18446744073709551608
[ 217.267336][ T5433] cached 0
[ 217.271764][ T5433] reserved 0
[ 217.276355][ T5433] online_reserved 1536
[ 217.281105][ T5433] nr_inodes 0
[ 217.285513][ T5433]
[ 217.288009][ T5433] freelist_wait waiting
[ 217.293136][ T5433] open buckets allocated1
[ 217.297982][ T5433] open buckets total 1024
[ 217.302675][ T5433] open_buckets_wait empty
[ 217.307688][ T5433] open_buckets_btree 0
[ 217.312128][ T5433] open_buckets_user 0
[ 217.316833][ T5433] btree reserve cache 0
[ 217.321813][ T5433]
[ 217.324351][ T5433] Dev 0:
[ 217.327212][ T5433] buckets sectors fragmented
[ 217.335390][ T5433] free 0 0 0
[ 217.343040][ T5433] sb 0 0 0
[ 217.350877][ T5433] journal 0 0 0
[ 217.358877][ T5433] btree 0 0 0
[ 217.366802][ T5433] user 018446744073709551608 8
[ 217.374524][ T5433] cached 0 0 0
[ 217.382241][ T5433] parity 0 0 0
[ 217.389935][ T5433] stripe 0 0 0
[ 217.398012][ T5433] need_gc_gens 0 0 0
[ 217.406298][ T5433] need_discard 0 0 0
[ 217.414329][ T5433] unstriped 0 0 0
[ 217.422169][ T5433] capacity 128
[ 217.427122][ T5433]
[ 217.429651][ T5433] reserves:
[ 217.433110][ T5433] stripe 60
[ 217.438035][ T5433] normal 58
[ 217.443177][ T5433] copygc 56
[ 217.447941][ T5433] btree 28
[ 217.453114][ T5433] btree_copygc 0
[ 217.458068][ T5433] reclaim 0
[ 217.463540][ T5433] interior_updates 0
[ 217.468394][ T5433]
[ 217.470901][ T5433] open buckets 0
[ 217.475650][ T5433] buckets to invalidate 0
[ 217.480427][ T5433]
[ 217.483790][ T5433] Copygc debug:
[ 217.487234][ T5433] running: 0
[ 217.490701][ T5433] copygc_wait:0
[ 217.494414][ T5433] copygc_wait_at:0
[ 217.498387][ T5433] Currently waiting for:0 B
[ 217.503264][ T5433] Currently waiting since:640 KiB
[ 217.508625][ T5433] Currently calculated wait:
[ 217.513750][ T5433] loop7: 0 B
[ 217.517374][ T5433] [<0>] bch2_copygc_thread+0x47a/0x5b0
[ 217.523558][ T5433] [<0>] kthread+0x203/0x230
[ 217.528325][ T5433] [<0>] ret_from_fork+0x9d/0x170
[ 217.533643][ T5433] [<0>] ret_from_fork_asm+0x1a/0x30
[ 217.539278][ T5433]
[ 217.541691][ T5433] Journal debug:
[ 217.545346][ T5433] flags: replay_done,running,may_skip_flush
[ 217.553084][ T5433] dirty journal entries: 3/32768
[ 217.558474][ T5433] seq: 20
[ 217.563505][ T5433] seq_ondisk: 20
[ 217.568409][ T5433] last_seq: 18
[ 217.573421][ T5433] last_seq_ondisk: 18
[ 217.578430][ T5433] flushed_seq_ondisk: 20
[ 217.583491][ T5433] watermark: stripe
[ 217.588769][ T5433] each entry reserved: 321
[ 217.593980][ T5433] nr flush writes: 6
[ 217.598997][ T5433] nr noflush writes: 1
[ 217.603779][ T5433] average write size: 1.84 KiB
[ 217.609397][ T5433] free buf: 65536
[ 217.614762][ T5433] nr direct reclaim: 0
[ 217.619627][ T5433] nr background reclaim: 17
[ 217.624643][ T5433] reclaim kicked: 0
[ 217.629774][ T5433] reclaim runs in: 0 ms
[ 217.634959][ T5433] blocked: 0
[ 217.639927][ T5433] current entry sectors: 256
[ 217.645211][ T5433] current entry error: (No error)
[ 217.651026][ T5433] current entry: closed
[ 217.656575][ T5433] unwritten entries:
[ 217.660921][ T5433] last buf closed
[ 217.664986][ T5433] space:
[ 217.668196][ T5433] discarded 256:1536
[ 217.673642][ T5433] clean ondisk 256:1536
[ 217.679014][ T5433] clean 256:1536
[ 217.684868][ T5433] total 256:2048
[ 217.690272][ T5433] dev 0:
[ 217.693286][ T5433] durability 1:
[ 217.697009][ T5433] nr 8
[ 217.702008][ T5433] bucket size 256
[ 217.707293][ T5433] available 6:192
[ 217.712693][ T5433] discard_idx 0
[ 217.717735][ T5433] dirty_ondisk 0 (seq 20)
[ 217.723653][ T5433] dirty_idx 0 (seq 20)
[ 217.729591][ T5433] cur_idx 0 (seq 20)
[ 217.735299][ T5433] replicas want 1 need 1
[ 219.279480][ T31] INFO: task syz.2.16:3908 blocked for more than 143 seconds.
[ 219.286960][ T31] Not tainted 6.16.0-syzkaller #0
[ 219.292554][ T31] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[ 219.301575][ T31] task:syz.2.16 state:D stack:11024 pid:3908 tgid:3907 ppid:3545 task_flags:0x400140 flags:0x00004006
[ 219.313840][ T31] Call Trace:
[ 219.317386][ T31]
[ 219.320339][ T31] __schedule+0x738/0xc00
[ 219.324668][ T31] ? schedule+0x21/0x140
[ 219.329047][ T31] schedule+0xac/0x140
[ 219.333129][ T31] schedule_timeout+0x2c/0x160
[ 219.337976][ T31] ? __down_common+0xeb/0x240
[ 219.342676][ T31] ? do_raw_spin_unlock+0x5a/0x110
[ 219.347956][ T31] __down_common+0xf3/0x240
[ 219.352482][ T31] down+0x3c/0x50
[ 219.356142][ T31] bch2_run_recovery_passes+0x8b/0xb0
[ 219.361703][ T31] bch2_fs_recovery+0xe19/0x13b0
[ 219.366648][ T31] ? bch2_fs_start+0x3ce/0x620
[ 219.371979][ T31] ? bch2_fs_start+0x276/0x620
[ 219.377451][ T31] bch2_fs_start+0x400/0x620
[ 219.382223][ T31] ? bch2_fs_start+0x276/0x620
[ 219.387077][ T31] bch2_fs_get_tree+0x2ba/0x760
[ 219.392154][ T31] vfs_get_tree+0x29/0xb0
[ 219.396486][ T31] do_new_mount+0x149/0x340
[ 219.401184][ T31] ? ns_capable+0x26/0x50
[ 219.405609][ T31] ? path_mount+0x84/0x570
[ 219.410044][ T31] __se_sys_mount+0x147/0x1b0
[ 219.414895][ T31] do_syscall_64+0x8f/0x250
[ 219.419538][ T31] ? fpregs_assert_state_consistent+0x1b/0x30
[ 219.425672][ T31] entry_SYSCALL_64_after_hwframe+0x77/0x7f
[ 219.431944][ T31] RIP: 0033:0x7fc05eb9010a
[ 219.436357][ T31] RSP: 002b:00007fc05ddfde68 EFLAGS: 00000246 ORIG_RAX: 00000000000000a5
[ 219.444984][ T31] RAX: ffffffffffffffda RBX: 00007fc05ddfdef0 RCX: 00007fc05eb9010a
[ 219.453060][ T31] RDX: 0000200000000080 RSI: 000020000000f680 RDI: 00007fc05ddfdeb0
[ 219.461352][ T31] RBP: 0000200000000080 R08: 00007fc05ddfdef0 R09: 0000000000000000
[ 219.469428][ T31] R10: 0000000000000000 R11: 0000000000000246 R12: 000020000000f680
[ 219.477406][ T31] R13: 00007fc05ddfdeb0 R14: 000000000000f62a R15: 0000200000000040
[ 219.485491][ T31]
[ 219.488703][ T31]
[ 219.488703][ T31] Showing all locks held in the system:
[ 219.496704][ T31] 1 lock held by khungtaskd/31:
[ 219.501656][ T31] #0: ffffffff847e82b0 (rcu_read_lock){....}-{1:2}, at: debug_show_all_locks+0x2e/0x100
[ 219.511664][ T31] 3 locks held by kworker/u8:3/68:
[ 219.516763][ T31] #0: ffff888237d2d2d8 (&rq->__lock){....}-{2:2}, at: raw_spin_rq_lock_nested+0x20/0x70
[ 219.526794][ T31] #1: ffffc90000c07e48 ((work_completion)(&(&kfence_timer)->work)){....}-{0:0}, at: process_scheduled_works+0x29f/0x5e0
[ 219.539839][ T31] #2: ffffffff846f7dd8 (cpu_hotplug_lock){....}-{0:0}, at: debug_object_assert_init+0x9d/0x280
[ 219.550810][ T31] 2 locks held by getty/2191:
[ 219.555590][ T31] #0: ffff8881057750a0 (&tty->ldisc_sem){....}-{0:0}, at: tty_ldisc_ref_wait+0x20/0x40
[ 219.565427][ T31] #1: ffffc90003b2f2f0 (&ldata->atomic_read_lock){....}-{3:3}, at: n_tty_read+0x1c3/0x6e0
[ 219.575623][ T31] 3 locks held by syz-executor/3923:
[ 219.581141][ T31] #0: ffff88810e695418 (sb_writers#13){....}-{0:0}, at: do_unlinkat+0xe3/0x2c0
[ 219.590396][ T31] #1: ffff888174204398 (&c->btree_trans_barrier){....}-{0:0}, at: bch2_trans_srcu_lock+0x2f/0x80
[ 219.601236][ T31] #2: ffff888174226710 (&c->gc_lock){....}-{3:3}, at: bch2_btree_update_start+0x24e/0x9c0
[ 219.611520][ T31] 4 locks held by bch-reclaim/loo/4295:
[ 219.617232][ T31] #0: ffff88817424af68 (&j->reclaim_lock){....}-{3:3}, at: bch2_journal_reclaim_thread+0x60/0x160
[ 219.628202][ T31] #1: ffff888174204740 (&wb->flushing.lock){....}-{3:3}, at: btree_write_buffer_flush_seq+0x643/0x6d0
[ 219.639419][ T31] #2: ffff888174204398 (&c->btree_trans_barrier){....}-{0:0}, at: bch2_trans_srcu_lock+0x2f/0x80
[ 219.650151][ T31] #3: ffff888174226710 (&c->gc_lock){....}-{3:3}, at: bch2_btree_update_start+0x24e/0x9c0
[ 219.660335][ T31] 3 locks held by syz-executor/4303:
[ 219.666159][ T31] #0: ffff88810e693418 (sb_writers#13){....}-{0:0}, at: do_unlinkat+0xe3/0x2c0
[ 219.675668][ T31] #1: ffff888168184398 (&c->btree_trans_barrier){....}-{0:0}, at: bch2_trans_srcu_lock+0x2f/0x80
[ 219.686498][ T31] #2: ffff8881681a6710 (&c->gc_lock){....}-{3:3}, at: bch2_btree_update_start+0x24e/0x9c0
[ 219.696937][ T31] 3 locks held by bch-reclaim/loo/4675:
[ 219.703284][ T31] #0: ffff8881681caf68 (&j->reclaim_lock){....}-{3:3}, at: bch2_journal_reclaim_thread+0x60/0x160
[ 219.714049][ T31] #1: ffff888168184398 (&c->btree_trans_barrier){....}-{0:0}, at: __bch2_trans_get+0x262/0x3d0
[ 219.725143][ T31] #2: ffff888168184740 (&wb->flushing.lock){....}-{3:3}, at: btree_write_buffer_flush_seq+0x643/0x6d0
[ 219.736409][ T31] 3 locks held by bch-copygc/loop/4676:
[ 219.742251][ T31] #0: ffff888168184740 (&wb->flushing.lock){....}-{3:3}, at: btree_write_buffer_flush_seq+0x643/0x6d0
[ 219.753567][ T31] #1: ffff888168184398 (&c->btree_trans_barrier){....}-{0:0}, at: bch2_trans_srcu_lock+0x2f/0x80
[ 219.764350][ T31] #2: ffff8881681a6710 (&c->gc_lock){....}-{3:3}, at: bch2_btree_update_start+0x24e/0x9c0
[ 219.774646][ T31] 3 locks held by syz-executor/4679:
[ 219.780237][ T31] #0: ffff888105fce418 (sb_writers#13){....}-{0:0}, at: do_unlinkat+0xe3/0x2c0
[ 219.789474][ T31] #1: ffff88817a484398 (&c->btree_trans_barrier){....}-{0:0}, at: bch2_trans_srcu_lock+0x2f/0x80
[ 219.800390][ T31] #2: ffff88817a4a6710 (&c->gc_lock){....}-{3:3}, at: bch2_btree_update_start+0x24e/0x9c0
[ 219.810501][ T31] 4 locks held by bch-reclaim/loo/5052:
[ 219.816290][ T31] #0: ffff88817a4caf68 (&j->reclaim_lock){....}-{3:3}, at: bch2_journal_reclaim_thread+0x60/0x160
[ 219.827369][ T31] #1: ffff88817a484740 (&wb->flushing.lock){....}-{3:3}, at: btree_write_buffer_flush_seq+0x643/0x6d0
[ 219.838753][ T31] #2: ffff88817a484398 (&c->btree_trans_barrier){....}-{0:0}, at: bch2_trans_srcu_lock+0x2f/0x80
[ 219.850298][ T31] #3: ffff88817a4a6710 (&c->gc_lock){....}-{3:3}, at: bch2_btree_update_start+0x24e/0x9c0
[ 219.860596][ T31] 3 locks held by syz-executor/5056:
[ 219.866047][ T31] #0: ffff88810d758418 (sb_writers#13){....}-{0:0}, at: do_unlinkat+0xe3/0x2c0
[ 219.875463][ T31] #1: ffff88815b304398 (&c->btree_trans_barrier){....}-{0:0}, at: bch2_trans_srcu_lock+0x2f/0x80
[ 219.887031][ T31] #2: ffff88815b326710 (&c->gc_lock){....}-{3:3}, at: bch2_btree_update_start+0x24e/0x9c0
[ 219.897422][ T31] 4 locks held by bch-reclaim/loo/5429:
[ 219.903349][ T31] #0: ffff88815b34af68 (&j->reclaim_lock){....}-{3:3}, at: bch2_journal_reclaim_thread+0x60/0x160
[ 219.914437][ T31] #1: ffff88815b304740 (&wb->flushing.lock){....}-{3:3}, at: btree_write_buffer_flush_seq+0x643/0x6d0
[ 219.925690][ T31] #2: ffff88815b304398 (&c->btree_trans_barrier){....}-{0:0}, at: bch2_trans_srcu_lock+0x2f/0x80
[ 219.936592][ T31] #3: ffff88815b326710 (&c->gc_lock){....}-{3:3}, at: bch2_btree_update_start+0x24e/0x9c0
[ 219.946785][ T31] 3 locks held by syz-executor/5433:
[ 219.952255][ T31] #0: ffff88810937e418 (sb_writers#13){....}-{0:0}, at: do_unlinkat+0xe3/0x2c0
[ 219.961671][ T31] #1: ffff888158e84398 (&c->btree_trans_barrier){....}-{0:0}, at: bch2_trans_srcu_lock+0x2f/0x80
[ 219.972820][ T31] #2: ffff888158ea6710 (&c->gc_lock){....}-{3:3}, at: bch2_btree_update_start+0x24e/0x9c0
[ 219.983668][ T31] 4 locks held by bch-reclaim/loo/5806:
[ 219.989225][ T31] #0: ffff888158ecaf68 (&j->reclaim_lock){....}-{3:3}, at: bch2_journal_reclaim_thread+0x60/0x160
[ 220.000024][ T31] #1: ffff888158e84740 (&wb->flushing.lock){....}-{3:3}, at: btree_write_buffer_flush_seq+0x643/0x6d0
[ 220.011901][ T31] #2: ffff888158e84398 (&c->btree_trans_barrier){....}-{0:0}, at: bch2_trans_srcu_lock+0x2f/0x80
[ 220.022540][ T31] #3: ffff888158ea6710 (&c->gc_lock){....}-{3:3}, at: bch2_btree_update_start+0x24e/0x9c0
[ 220.032795][ T31] 3 locks held by syz-executor/5810:
[ 220.038250][ T31] #0: ffff8881559f9418 (sb_writers#13){....}-{0:0}, at: do_unlinkat+0xe3/0x2c0
[ 220.047566][ T31] #1: ffff888155a04398 (&c->btree_trans_barrier){....}-{0:0}, at: bch2_trans_srcu_lock+0x2f/0x80
[ 220.058353][ T31] #2: ffff888155a26710 (&c->gc_lock){....}-{3:3}, at: bch2_btree_update_start+0x24e/0x9c0
[ 220.068881][ T31] 4 locks held by bch-reclaim/loo/6183:
[ 220.074463][ T31] #0: ffff888155a4af68 (&j->reclaim_lock){....}-{3:3}, at: bch2_journal_reclaim_thread+0x60/0x160
[ 220.085445][ T31] #1: ffff888155a04740 (&wb->flushing.lock){....}-{3:3}, at: btree_write_buffer_flush_seq+0x643/0x6d0
[ 220.096728][ T31] #2: ffff888155a04398 (&c->btree_trans_barrier){....}-{0:0}, at: bch2_trans_srcu_lock+0x2f/0x80
[ 220.107634][ T31] #3: ffff888155a26710 (&c->gc_lock){....}-{3:3}, at: bch2_btree_update_start+0x24e/0x9c0
[ 220.117915][ T31] 3 locks held by syz-executor/6187:
[ 220.123451][ T31] #0: ffff888174b33418 (sb_writers#13){....}-{0:0}, at: do_unlinkat+0xe3/0x2c0
[ 220.133651][ T31] #1: ffff888152a84398 (&c->btree_trans_barrier){....}-{0:0}, at: bch2_trans_srcu_lock+0x2f/0x80
[ 220.144490][ T31] #2: ffff888152aa6710 (&c->gc_lock){....}-{3:3}, at: bch2_btree_update_start+0x24e/0x9c0
[ 220.155026][ T31] 4 locks held by bch-reclaim/loo/6560:
[ 220.160853][ T31] #0: ffff888152acaf68 (&j->reclaim_lock){....}-{3:3}, at: bch2_journal_reclaim_thread+0x60/0x160
[ 220.172336][ T31] #1: ffff888152a84740 (&wb->flushing.lock){....}-{3:3}, at: btree_write_buffer_flush_seq+0x643/0x6d0
[ 220.198963][ T31] #2: ffff888152a84398 (&c->btree_trans_barrier){....}-{0:0}, at: bch2_trans_srcu_lock+0x2f/0x80
[ 220.209772][ T31] #3: ffff888152aa6710 (&c->gc_lock){....}-{3:3}, at: bch2_btree_update_start+0x24e/0x9c0
[ 220.220316][ T31] 3 locks held by syz-executor/6564:
[ 220.225992][ T31] #0: ffff888236f6a418 (sb_writers#13){....}-{0:0}, at: do_unlinkat+0xe3/0x2c0
[ 220.235146][ T31] #1: ffff88814fa84398 (&c->btree_trans_barrier){....}-{0:0}, at: bch2_trans_srcu_lock+0x2f/0x80
[ 220.246046][ T31] #2: ffff88814faa6710 (&c->gc_lock){....}-{3:3}, at: bch2_btree_update_start+0x24e/0x9c0
[ 220.256165][ T31] 4 locks held by bch-reclaim/loo/6937:
[ 220.261763][ T31] #0: ffff88814facaf68 (&j->reclaim_lock){....}-{3:3}, at: bch2_journal_reclaim_thread+0x60/0x160
[ 220.272827][ T31] #1: ffff88814fa84740 (&wb->flushing.lock){....}-{3:3}, at: btree_write_buffer_flush_seq+0x643/0x6d0
[ 220.284237][ T31] #2: ffff88814fa84398 (&c->btree_trans_barrier){....}-{0:0}, at: bch2_trans_srcu_lock+0x2f/0x80
[ 220.295128][ T31] #3: ffff88814faa6710 (&c->gc_lock){....}-{3:3}, at: bch2_btree_update_start+0x24e/0x9c0
[ 220.305232][ T31]
[ 220.307567][ T31] =============================================
[ 220.307567][ T31]
[ 220.316624][ T31] NMI backtrace for cpu 0
[ 220.316638][ T31] CPU: 0 UID: 0 PID: 31 Comm: khungtaskd Not tainted 6.16.0-syzkaller #0 PREEMPT(none)
[ 220.316642][ T31] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 07/12/2025
[ 220.316644][ T31] Call Trace:
[ 220.316648][ T31]
[ 220.316652][ T31] dump_stack_lvl+0xa2/0xf0
[ 220.316662][ T31] nmi_cpu_backtrace+0x109/0x170
[ 220.316669][ T31] ? __pfx_nmi_raise_cpu_backtrace+0x10/0x10
[ 220.316675][ T31] nmi_trigger_cpumask_backtrace+0x8e/0x140
[ 220.316681][ T31] watchdog+0x581/0x5b0
[ 220.316686][ T31] ? watchdog+0xd1/0x5b0
[ 220.316690][ T31] ? __pfx_watchdog+0x10/0x10
[ 220.316694][ T31] kthread+0x203/0x230
[ 220.316702][ T31] ? __pfx_kthread+0x10/0x10
[ 220.316706][ T31] ret_from_fork+0x9d/0x170
[ 220.316712][ T31] ? __pfx_kthread+0x10/0x10
[ 220.316717][ T31] ret_from_fork_asm+0x1a/0x30
[ 220.316728][ T31]
[ 220.316729][ T31] Sending NMI from CPU 0 to CPUs 1:
[ 220.416739][ C1] NMI backtrace for cpu 1
[ 220.416745][ C1] CPU: 1 UID: 0 PID: 0 Comm: swapper/1 Not tainted 6.16.0-syzkaller #0 PREEMPT(none)
[ 220.416748][ C1] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 07/12/2025
[ 220.416750][ C1] RIP: 0010:pv_native_safe_halt+0x13/0x20
[ 220.416759][ C1] Code: d3 28 01 00 cc cc cc 90 90 90 90 90 90 90 90 90 90 90 90 90 90 90 90 f3 0f 1e fa 66 90 0f 00 2d 59 11 0a 00 f3 0f 1e fa fb f4 cc cc cc cc cc cc cc cc cc cc cc cc 90 90 90 90 90 90 90 90 90
[ 220.416762][ C1] RSP: 0018:ffffc900000abee0 EFLAGS: 000002c6
[ 220.416766][ C1] RAX: cca10ffcea8fc500 RBX: 0000000000000001 RCX: cca10ffcea8fc500
[ 220.416768][ C1] RDX: 00000000ffffe071 RSI: ffffffff840ea050 RDI: ffffffff8430cf10
[ 220.416769][ C1] RBP: ffffc900000abef8 R08: 0000000000080000 R09: 000000000000006e
[ 220.416772][ C1] R10: 0000000000000000 R11: ffffffff81274f10 R12: 0000000000000000
[ 220.416773][ C1] R13: ffff888101685280 R14: 0000000000000000 R15: 0000000000000000
[ 220.416775][ C1] FS: 0000000000000000(0000) GS:ffff8882b225c000(0000) knlGS:0000000000000000
[ 220.416777][ C1] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[ 220.416778][ C1] CR2: 00007f34f649fe80 CR3: 0000000109b1e000 CR4: 00000000003506f0
[ 220.416784][ C1] Call Trace:
[ 220.416787][ C1]
[ 220.416789][ C1] default_idle+0x13/0x20
[ 220.416793][ C1] default_idle_call+0x3d/0x70
[ 220.416795][ C1] do_idle+0xd7/0x240
[ 220.416803][ C1] cpu_startup_entry+0x25/0x30
[ 220.416806][ C1] start_secondary+0x95/0xa0
[ 220.416812][ C1] common_startup_64+0x13e/0x147
[ 220.416822][ C1]
[ 220.417734][ T31] Kernel panic - not syncing: hung_task: blocked tasks
[ 220.585224][ T31] Kernel Offset: disabled
[ 220.589555][ T31] Rebooting in 86400 seconds..