Failure to run backup on nvme usb disk

Failure to run backup on nvme usb disk

[Rory Campbell-Lange]

I periodically run backup to an nvme disk connected to my machine on usb3. It just failed twice with the error below.

My machine:
5.10.0-18-amd64 #1 SMP Debian 5.10.140-1 (2022-09-02) x86_64 GNU/Linux

btrfs version etc
ii  btrfs-progs  5.10.1-2   amd64  Checksumming Copy on Write Filesystem utilities

Any thoughts? I can find a similar "btrfs_assert_delayed_root_empty" error in https://bugzilla.redhat.com/show_bug.cgi?id=1170905 from 2014.

Rory

Sep 24 19:39:30 rory-t470s kernel: [338264.688534] ------------[ cut here ]------------
Sep 24 19:39:30 rory-t470s kernel: [338264.688712] WARNING: CPU: 3 PID: 1268625 at fs/btrfs/delayed-inode.c:1397 btrfs_assert_delayed_root_empty+0x70/0x90 [btrfs]
Sep 24 19:39:30 rory-t470s kernel: [338264.688718] Modules linked in: tcp_diag udp_diag inet_diag cdc_ether usbnet mii ctr ccm rfcomm nft_chain_nat xt_MASQUERADE nf_nat nf_conntrack_netlink xfrm_user xfrm_algo br_netfilter bridge stp llc cmac algif_hash algif_skcipher af_alg bnep btusb btrtl btbcm btintel bluetooth uvcvideo videobuf2_vmalloc videobuf2_memops videobuf2_v4l2 jitterentropy_rng videobuf2_common drbg videodev ansi_cprng ecdh_generic mc ecc sg overlay snd_hda_codec_hdmi snd_hda_codec_realtek snd_hda_codec_generic intel_rapl_msr intel_rapl_common intel_pmc_core_pltdrv snd_soc_skl intel_pmc_core binfmt_misc snd_soc_hdac_hda snd_hda_ext_core snd_soc_sst_ipc snd_soc_sst_dsp snd_soc_acpi_intel_match snd_soc_acpi snd_hda_intel snd_intel_dspcfg nls_ascii joydev x86_pkg_temp_thermal intel_powerclamp soundwire_intel coretemp kvm_intel mei_wdt mei_hdcp nls_cp437 soundwire_generic_allocation vfat fat kvm snd_soc_core irqbypass iwlmvm ext4 rapl snd_compress soundwire_cadence intel_cstate crc16 mac80211 snd_hda_codec mbcache i915
Sep 24 19:39:30 rory-t470s kernel: [338264.689059]  intel_uncore jbd2 evdev libarc4 snd_hda_core serio_raw efi_pstore iwlwifi pcspkr snd_hwdep soundwire_bus wmi_bmof nf_log_ipv6 iTCO_wdt intel_pmc_bxt snd_pcm intel_wmi_thunderbolt rmi_smbus iTCO_vendor_support ip6t_REJECT rmi_core snd_timer nf_reject_ipv6 watchdog cfg80211 xt_hl ip6_tables drm_kms_helper mei_me ucsi_acpi typec_ucsi intel_xhci_usb_role_switch ip6t_rt thinkpad_acpi typec mei roles cec intel_pch_thermal nvram ledtrig_audio i2c_algo_bit snd soundcore nf_log_ipv4 rfkill nf_log_common ipt_REJECT nf_reject_ipv4 ac xt_LOG acpi_pad nft_limit button xt_limit xt_addrtype xt_tcpudp xt_conntrack nf_conntrack nf_defrag_ipv6 nf_defrag_ipv4 nft_compat nft_counter drm msr nf_tables parport_pc ppdev nfnetlink lp fuse parport sunrpc configfs efivarfs ip_tables x_tables autofs4 btrfs blake2b_generic xor raid6_pq libcrc32c crc32c_generic sd_mod uas usb_storage scsi_mod dm_crypt dm_mod crc32_pclmul crc32c_intel xhci_pci ghash_clmulni_intel xhci_hcd nvme e1000e usbcore nvme_core
Sep 24 19:39:30 rory-t470s kernel: [338264.689400]  t10_pi crc_t10dif crct10dif_generic aesni_intel i2c_i801 libaes ptp crypto_simd crct10dif_pclmul psmouse cryptd glue_helper pps_core i2c_smbus usb_common crct10dif_common wmi battery video
Sep 24 19:39:30 rory-t470s kernel: [338264.689481] CPU: 3 PID: 1268625 Comm: btrfs-transacti Tainted: G        W         5.10.0-18-amd64 #1 Debian 5.10.140-1
Sep 24 19:39:30 rory-t470s kernel: [338264.689488] Hardware name: LENOVO 20HGS6XY01/20HGS6XY01, BIOS N1WET66W (1.45 ) 01/27/2022
Sep 24 19:39:30 rory-t470s kernel: [338264.689650] RIP: 0010:btrfs_assert_delayed_root_empty+0x70/0x90 [btrfs]
Sep 24 19:39:30 rory-t470s kernel: [338264.689663] Code: c2 01 78 1a 48 89 ef c6 07 00 0f 1f 40 00 48 85 db 75 17 5b 5d c3 cc cc cc cc 31 db eb e6 be 01 00 00 00 e8 d2 20 89 dd eb da <0f> 0b 5b 5d c3 cc cc cc cc be 02 00 00 00 e8 bd 20 89 dd eb c5 66
Sep 24 19:39:30 rory-t470s kernel: [338264.689672] RSP: 0018:ffffad1b09263e40 EFLAGS: 00010282
Sep 24 19:39:30 rory-t470s kernel: [338264.689683] RAX: ffff9a12c84e63c0 RBX: ffff9a12c84e63a8 RCX: 0000000000000000
Sep 24 19:39:30 rory-t470s kernel: [338264.689689] RDX: 000000000000000d RSI: ffff9a12f4399018 RDI: ffff9a12f4399000
Sep 24 19:39:30 rory-t470s kernel: [338264.689696] RBP: ffff9a12f4399000 R08: ffff9a12f4399018 R09: ffff9a12f4399018
Sep 24 19:39:30 rory-t470s kernel: [338264.689703] R10: 0000000000000c00 R11: 0000000000000000 R12: ffff9a1303b0a000
Sep 24 19:39:30 rory-t470s kernel: [338264.689730] R13: ffff9a1303b0a520 R14: ffff9a1303b0a550 R15: 0000000000000000
Sep 24 19:39:30 rory-t470s kernel: [338264.689740] FS:  0000000000000000(0000) GS:ffff9a1516580000(0000) knlGS:0000000000000000
Sep 24 19:39:30 rory-t470s kernel: [338264.689748] CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
Sep 24 19:39:30 rory-t470s kernel: [338264.689756] CR2: 00007fe623dc5000 CR3: 00000001d2c0a005 CR4: 00000000003706e0
Sep 24 19:39:30 rory-t470s kernel: [338264.689764] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
Sep 24 19:39:30 rory-t470s kernel: [338264.689770] DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400
Sep 24 19:39:30 rory-t470s kernel: [338264.689776] Call Trace:
Sep 24 19:39:30 rory-t470s kernel: [338264.689915]  btrfs_cleanup_transaction.isra.0+0x381/0x590 [btrfs]
Sep 24 19:39:30 rory-t470s kernel: [338264.689935]  ? ttwu_do_wakeup+0x17/0x140
Sep 24 19:39:30 rory-t470s kernel: [338264.690062]  transaction_kthread+0x160/0x180 [btrfs]
Sep 24 19:39:30 rory-t470s kernel: [338264.690182]  ? btrfs_cleanup_transaction.isra.0+0x590/0x590 [btrfs]
Sep 24 19:39:30 rory-t470s kernel: [338264.690199]  kthread+0x118/0x140
Sep 24 19:39:30 rory-t470s kernel: [338264.690213]  ? __kthread_bind_mask+0x60/0x60
Sep 24 19:39:30 rory-t470s kernel: [338264.690228]  ret_from_fork+0x1f/0x30
Sep 24 19:39:30 rory-t470s kernel: [338264.690249] ---[ end trace 6f9ff3b4a0c48a03 ]---