Test output


[205697.120897] run fstests generic/416 at 2020-10-28 23:11:37
[205697.405295] BTRFS info (device dm-0): disk space caching is enabled
[205697.405892] BTRFS info (device dm-0): has skinny extents
[205697.539937] BTRFS: device fsid 39ba28e7-d9e9-4031-821e-d7ff607d3fe2 devid 1 transid 5 /dev/mapper/vg0-lv9 scanned by mkfs.btrfs (1321860)
[205697.578626] BTRFS info (device dm-9): disk space caching is enabled
[205697.579247] BTRFS info (device dm-9): has skinny extents
[205697.584692] BTRFS info (device dm-9): checking UUID tree
[205786.869378] BTRFS info (device dm-9): space_info 5 has 651264 free, is full
[205786.869827] BTRFS info (device dm-9): space_info total=128974848, used=124747776, pinned=450560, reserved=45056, may_use=3014656, readonly=65536
[205786.870570] BTRFS info (device dm-9): global_block_rsv: size 3014656 reserved 3014656
[205786.871034] BTRFS info (device dm-9): trans_block_rsv: size 0 reserved 0
[205786.871459] BTRFS info (device dm-9): chunk_block_rsv: size 0 reserved 0
[205786.871855] BTRFS info (device dm-9): delayed_block_rsv: size 0 reserved 0
[205786.872271] BTRFS info (device dm-9): delayed_refs_rsv: size 1638400 reserved 0
[205786.872756] BTRFS info (device dm-9): space_info 5 has 651264 free, is full
[205786.873183] BTRFS info (device dm-9): space_info total=128974848, used=124755968, pinned=450560, reserved=40960, may_use=3010560, readonly=65536
[205786.873919] BTRFS info (device dm-9): global_block_rsv: size 3014656 reserved 3010560
[205786.874362] BTRFS info (device dm-9): trans_block_rsv: size 0 reserved 0
[205786.874767] BTRFS info (device dm-9): chunk_block_rsv: size 0 reserved 0
[205786.875151] BTRFS info (device dm-9): delayed_block_rsv: size 0 reserved 0
[205786.875544] BTRFS info (device dm-9): delayed_refs_rsv: size 1638400 reserved 0
[205786.876025] BTRFS info (device dm-9): space_info 5 has 651264 free, is full
[205786.876431] BTRFS info (device dm-9): space_info total=128974848, used=124764160, pinned=450560, reserved=36864, may_use=3006464, readonly=65536
[205786.877184] BTRFS info (device dm-9): global_block_rsv: size 3014656 reserved 3006464
[205786.877643] BTRFS info (device dm-9): trans_block_rsv: size 0 reserved 0
[205786.878034] BTRFS info (device dm-9): chunk_block_rsv: size 0 reserved 0
[205786.878426] BTRFS info (device dm-9): delayed_block_rsv: size 0 reserved 0
[205786.878831] BTRFS info (device dm-9): delayed_refs_rsv: size 1638400 reserved 0
[205786.879287] BTRFS info (device dm-9): space_info 5 has 651264 free, is full
[205786.879687] BTRFS info (device dm-9): space_info total=128974848, used=124755968, pinned=458752, reserved=40960, may_use=3002368, readonly=65536
[205786.880415] BTRFS info (device dm-9): global_block_rsv: size 3014656 reserved 3002368
[205786.880871] BTRFS info (device dm-9): trans_block_rsv: size 0 reserved 0
[205786.881253] BTRFS info (device dm-9): chunk_block_rsv: size 0 reserved 0
[205786.881636] BTRFS info (device dm-9): delayed_block_rsv: size 0 reserved 0
[205786.882035] BTRFS info (device dm-9): delayed_refs_rsv: size 1638400 reserved 0
[205786.882503] BTRFS info (device dm-9): space_info 5 has 651264 free, is full
[205786.882937] BTRFS info (device dm-9): space_info total=128974848, used=124768256, pinned=458752, reserved=32768, may_use=2998272, readonly=65536
[205786.883666] BTRFS info (device dm-9): global_block_rsv: size 3014656 reserved 2998272
[205786.884123] BTRFS info (device dm-9): trans_block_rsv: size 0 reserved 0
[205786.884503] BTRFS info (device dm-9): chunk_block_rsv: size 0 reserved 0
[205786.884915] BTRFS info (device dm-9): delayed_block_rsv: size 0 reserved 0
[205786.885306] BTRFS info (device dm-9): delayed_refs_rsv: size 1572864 reserved 0
[205786.885778] BTRFS info (device dm-9): space_info 5 has 651264 free, is full
[205786.886174] BTRFS info (device dm-9): space_info total=128974848, used=124772352, pinned=458752, reserved=32768, may_use=2994176, readonly=65536
[205786.886936] BTRFS info (device dm-9): global_block_rsv: size 3014656 reserved 2994176
[205786.887482] BTRFS info (device dm-9): trans_block_rsv: size 0 reserved 0
[205786.887940] BTRFS info (device dm-9): chunk_block_rsv: size 0 reserved 0
[205786.888452] BTRFS info (device dm-9): delayed_block_rsv: size 0 reserved 0
[205786.889007] BTRFS info (device dm-9): delayed_refs_rsv: size 1638400 reserved 0
[205786.889603] BTRFS info (device dm-9): space_info 5 has 651264 free, is full
[205786.890117] BTRFS info (device dm-9): space_info total=128974848, used=124776448, pinned=458752, reserved=32768, may_use=2990080, readonly=65536
[205786.891125] BTRFS info (device dm-9): global_block_rsv: size 3014656 reserved 2990080
[205786.891768] BTRFS info (device dm-9): trans_block_rsv: size 0 reserved 0
[205786.892290] BTRFS info (device dm-9): chunk_block_rsv: size 0 reserved 0
[205786.892830] BTRFS info (device dm-9): delayed_block_rsv: size 0 reserved 0
[205786.893368] BTRFS info (device dm-9): delayed_refs_rsv: size 1703936 reserved 0
[205786.894011] BTRFS info (device dm-9): space_info 5 has 651264 free, is full
[205786.894559] BTRFS info (device dm-9): space_info total=128974848, used=124784640, pinned=458752, reserved=28672, may_use=2985984, readonly=65536
[205786.895563] BTRFS info (device dm-9): global_block_rsv: size 3014656 reserved 2985984
[205786.896183] BTRFS info (device dm-9): trans_block_rsv: size 0 reserved 0
[205786.896708] BTRFS info (device dm-9): chunk_block_rsv: size 0 reserved 0
[205786.897241] BTRFS info (device dm-9): delayed_block_rsv: size 0 reserved 0
[205786.897796] BTRFS info (device dm-9): delayed_refs_rsv: size 1703936 reserved 0
[205786.898440] BTRFS info (device dm-9): space_info 5 has 651264 free, is full
[205786.899008] BTRFS info (device dm-9): space_info total=128974848, used=124796928, pinned=458752, reserved=20480, may_use=2981888, readonly=65536
[205786.900014] BTRFS info (device dm-9): global_block_rsv: size 3014656 reserved 2981888
[205786.900622] BTRFS info (device dm-9): trans_block_rsv: size 0 reserved 0
[205786.901159] BTRFS info (device dm-9): chunk_block_rsv: size 0 reserved 0
[205786.901688] BTRFS info (device dm-9): delayed_block_rsv: size 0 reserved 0
[205786.902235] BTRFS info (device dm-9): delayed_refs_rsv: size 1638400 reserved 0
[205786.902903] BTRFS info (device dm-9): space_info 5 has 651264 free, is full
[205786.903445] BTRFS info (device dm-9): space_info total=128974848, used=124809216, pinned=458752, reserved=12288, may_use=2977792, readonly=65536
[205786.904452] BTRFS info (device dm-9): global_block_rsv: size 3014656 reserved 2977792
[205786.905088] BTRFS info (device dm-9): trans_block_rsv: size 0 reserved 0
[205786.905627] BTRFS info (device dm-9): chunk_block_rsv: size 0 reserved 0
[205786.906182] BTRFS info (device dm-9): delayed_block_rsv: size 0 reserved 0
[205786.906749] BTRFS info (device dm-9): delayed_refs_rsv: size 1572864 reserved 0
[205786.907394] BTRFS info (device dm-9): space_info 5 has 651264 free, is full
[205786.907966] BTRFS info (device dm-9): space_info total=128974848, used=124817408, pinned=458752, reserved=8192, may_use=2973696, readonly=65536
[205786.908992] BTRFS info (device dm-9): global_block_rsv: size 3014656 reserved 2973696
[205786.909608] BTRFS info (device dm-9): trans_block_rsv: size 0 reserved 0
[205786.910134] BTRFS info (device dm-9): chunk_block_rsv: size 0 reserved 0
[205786.910667] BTRFS info (device dm-9): delayed_block_rsv: size 0 reserved 0
[205786.911225] BTRFS info (device dm-9): delayed_refs_rsv: size 1572864 reserved 0
[205786.911848] BTRFS info (device dm-9): space_info 5 has 651264 free, is full
[205786.912377] BTRFS info (device dm-9): space_info total=128974848, used=124821504, pinned=458752, reserved=8192, may_use=2969600, readonly=65536
[205786.913386] BTRFS info (device dm-9): global_block_rsv: size 3014656 reserved 2969600
[205786.914044] BTRFS info (device dm-9): trans_block_rsv: size 0 reserved 0
[205786.914562] BTRFS info (device dm-9): chunk_block_rsv: size 0 reserved 0
[205786.915090] BTRFS info (device dm-9): delayed_block_rsv: size 0 reserved 0
[205786.915626] BTRFS info (device dm-9): delayed_refs_rsv: size 1638400 reserved 0
[205786.916233] BTRFS info (device dm-9): space_info 5 has 651264 free, is full
[205786.916801] BTRFS info (device dm-9): space_info total=128974848, used=124829696, pinned=458752, reserved=4096, may_use=2965504, readonly=65536
[205786.917792] BTRFS info (device dm-9): global_block_rsv: size 3014656 reserved 2965504
[205786.918398] BTRFS info (device dm-9): trans_block_rsv: size 0 reserved 0
[205786.918941] BTRFS info (device dm-9): chunk_block_rsv: size 0 reserved 0
[205786.919461] BTRFS info (device dm-9): delayed_block_rsv: size 0 reserved 0
[205786.920015] BTRFS info (device dm-9): delayed_refs_rsv: size 1638400 reserved 0
[205786.920635] BTRFS info (device dm-9): space_info 5 has 651264 free, is full
[205786.921136] BTRFS info (device dm-9): space_info total=128974848, used=124833792, pinned=458752, reserved=4096, may_use=2961408, readonly=65536
[205786.922145] BTRFS info (device dm-9): global_block_rsv: size 3014656 reserved 2961408
[205786.922768] BTRFS info (device dm-9): trans_block_rsv: size 0 reserved 0
[205786.923262] BTRFS info (device dm-9): chunk_block_rsv: size 0 reserved 0
[205786.923751] BTRFS info (device dm-9): delayed_block_rsv: size 0 reserved 0
[205786.924148] BTRFS info (device dm-9): delayed_refs_rsv: size 1703936 reserved 0
[205786.924690] BTRFS info (device dm-9): space_info 5 has 651264 free, is full
[205786.925149] BTRFS info (device dm-9): space_info total=128974848, used=124801024, pinned=499712, reserved=0, may_use=2957312, readonly=65536
[205791.009621] btrfs_use_block_rsv: 1 callbacks suppressed
[205791.009622] ------------[ cut here ]------------
[205791.011858] BTRFS: block rsv 1 returned -28
[205791.012790] WARNING: CPU: 1 PID: 1059473 at fs/btrfs/block-rsv.c:521 btrfs_use_block_rsv+0x197/0x240
[205791.016649] Modules linked in: dm_thin_pool dm_persistent_data dm_bio_prison dm_log_writes dm_dust dm_flakey loop nft_fib_inet nft_fib_ipv4 nft_fib_ipv6 nft_fib nft_reject_inet nf_reject_ipv4 nf_reject_ipv6 nft_reject nft_ct nft_chain_nat nf_nat nf_conntrack nf_defrag_ipv6 nf_defrag_ipv4 ip6_tables nft_compat ip_set rfkill nf_tables nfnetlink snd_hda_codec_generic iTCO_wdt intel_rapl_msr iTCO_vendor_support snd_hda_intel snd_intel_dspcfg snd_hda_codec snd_hwdep snd_hda_core snd_pcm snd_timer joydev virtio_balloon intel_rapl_common snd i2c_i801 soundcore lpc_ich i2c_smbus zram ip_tables xfs qxl drm_ttm_helper ttm drm_kms_helper cec crct10dif_pclmul crc32_pclmul crc32c_intel drm ghash_clmulni_intel serio_raw virtio_blk qemu_fw_cfg
[205791.024443] CPU: 1 PID: 1059473 Comm: kworker/u4:3 Tainted: G        W         5.9.0-rc8+ #142
[205791.025166] Hardware name: QEMU Standard PC (Q35 + ICH9, 2009), BIOS 1.13.0-2.fc32 04/01/2014
[205791.025879] Workqueue: btrfs-endio-write btrfs_work_helper
[205791.026323] RIP: 0010:btrfs_use_block_rsv+0x197/0x240
[205791.026745] Code: c7 c7 c0 6c df 99 e8 e8 7d 16 00 85 c0 0f 84 35 ff ff ff 41 0f b7 74 24 5a ba e4 ff ff ff 48 c7 c7 60 bb 5f 99 e8 fd 3c b4 ff <0f> 0b e9 17 ff ff ff 4c 89 ff e8 2a f2 6e 00 4c 89 e0 5b 5d 41 5c
[205791.028278] RSP: 0018:ffffa67f02def9d0 EFLAGS: 00010286
[205791.028699] RAX: 000000000000001f RBX: 0000000000001000 RCX: ffff8aa2bbd19448
[205791.029289] RDX: 00000000ffffffd8 RSI: 0000000000000027 RDI: ffff8aa2bbd19440
[205791.029894] RBP: ffff8aa263c65000 R08: 0000000000000000 R09: 0000000000000000
[205791.030451] R10: 0000000000000000 R11: ffff8aa291cb0000 R12: ffff8aa25e2cec20
[205791.031035] R13: ffff8aa2b2d08000 R14: ffff8aa2b2d08220 R15: ffff8aa25e2cec38
[205791.031587] FS:  0000000000000000(0000) GS:ffff8aa2bbd00000(0000) knlGS:0000000000000000
[205791.032251] CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[205791.032704] CR2: 00007f66a681dba0 CR3: 0000000172be4005 CR4: 0000000000370ee0
[205791.033291] Call Trace:
[205791.033515]  btrfs_alloc_tree_block+0x77/0x320
[205791.033894]  alloc_tree_block_no_bg_flush+0x4f/0x60
[205791.034274]  __btrfs_cow_block+0x122/0x570
[205791.034622]  btrfs_cow_block+0x109/0x230
[205791.035001]  btrfs_search_slot+0x530/0x9d0
[205791.035348]  btrfs_lookup_csum+0x65/0x140
[205791.035677]  btrfs_csum_file_blocks+0x179/0x670
[205791.036081]  ? unpin_extent_cache+0x94/0xd0
[205791.036415]  btrfs_finish_ordered_io.isra.0+0x3c8/0x7f0
[205791.036879]  btrfs_work_helper+0xee/0x3c0
[205791.037214]  process_one_work+0x236/0x560
[205791.037538]  worker_thread+0x55/0x3c0
[205791.037854]  ? process_one_work+0x560/0x560
[205791.038216]  kthread+0x13a/0x150
[205791.038489]  ? kthread_create_worker_on_cpu+0x40/0x40
[205791.038919]  ret_from_fork+0x1f/0x30
[205791.039218] irq event stamp: 0
[205791.039473] hardirqs last  enabled at (0): [<0000000000000000>] 0x0
[205791.039980] hardirqs last disabled at (0): [] copy_process+0x6b9/0x1ba0
[205791.040635] softirqs last  enabled at (0): [] copy_process+0x6b9/0x1ba0
[205791.041293] softirqs last disabled at (0): [<0000000000000000>] 0x0
[205791.041808] ---[ end trace f11993b17b69ec1f ]---