[ 5061.439125] run fstests btrfs/190 at 2021-03-01 22:14:24
[ 5061.724941] dm-9: detected capacity change from 0 to 20971520
[ 5061.841769] BTRFS: device fsid 262daddf-f7c9-44c3-82d8-8158c8d7e1cb devid 1 transid 5 /dev/mapper/logwrites-test scanned by mkfs.btrfs (810914)
[ 5061.849055] BTRFS info (device dm-9): enabling free space tree
[ 5061.849441] BTRFS info (device dm-9): using free space tree
[ 5061.849776] BTRFS info (device dm-9): has skinny extents
[ 5061.850122] BTRFS info (device dm-9): flagging fs with big metadata feature
[ 5061.856058] BTRFS info (device dm-9): creating free space tree
[ 5061.856522] BTRFS info (device dm-9): setting compat-ro feature flag for FREE_SPACE_TREE (0x1)
[ 5061.857063] BTRFS info (device dm-9): setting compat-ro feature flag for FREE_SPACE_TREE_VALID (0x2)
[ 5061.866460] BTRFS info (device dm-9): cleaning free space cache v1
[ 5061.875488] BTRFS info (device dm-9): checking UUID tree
[ 5061.890031] BTRFS warning (device dm-9): qgroup rescan is already in progress
[ 5061.896995] BTRFS info (device dm-9): qgroup scan completed (inconsistency flag cleared)
[ 5063.214066] BTRFS info (device dm-9): balance: start -f -m -s
[ 5063.214611] BTRFS info (device dm-9): relocating block group 30408704 flags metadata|dup
[ 5063.338830] BTRFS info (device dm-9): found 84 extents, stage: move data extents
[ 5063.528759] BTRFS info (device dm-9): relocating block group 22020096 flags system|dup
[ 5063.531145] ------------[ cut here ]------------
[ 5063.531418] WARNING: CPU: 0 PID: 809716 at drivers/gpu/drm/ttm/ttm_bo.c:512 ttm_bo_release+0x4f9/0x5c0 [ttm]
[ 5063.531996] Modules linked in: 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 xfs intel_rapl_msr iTCO_wdt iTCO_vendor_support snd_hda_codec_generic intel_rapl_common snd_hda_intel joydev i2c_i801 i2c_smbus snd_intel_dspcfg snd_hda_codec snd_hwdep virtio_balloon snd_hda_core snd_pcm lpc_ich snd_timer snd soundcore zram ip_tables crct10dif_pclmul crc32_pclmul crc32c_intel ghash_clmulni_intel qxl drm_ttm_helper serio_raw ttm drm_kms_helper cec drm virtio_blk qemu_fw_cfg
[ 5063.535496] CPU: 0 PID: 809716 Comm: kworker/0:12 Tainted: G        W         5.12.0-rc1+ #153
[ 5063.536198] Hardware name: QEMU Standard PC (Q35 + ICH9, 2009), BIOS 1.13.0-2.fc32 04/01/2014
[ 5063.536860] Workqueue: events qxl_gc_work [qxl]
[ 5063.537142] RIP: 0010:ttm_bo_release+0x4f9/0x5c0 [ttm]
[ 5063.537448] Code: ff 48 8b bd e8 fe ff ff b9 30 75 00 00 31 d2 be 01 00 00 00 e8 38 75 55 e5 48 8b 45 e0 e9 fb fe ff ff 4c 89 e8 e9 f3 fe ff ff <0f> 0b c7 85 94 00 00 00 00 00 00 00 48 8b 3c 24 48 8d 75 08 31 d2
[ 5063.538505] RSP: 0018:ffff9cbc01467d80 EFLAGS: 00010202
[ 5063.538855] RAX: 0000000000000001 RBX: 0000000000000009 RCX: 0000000000000000
[ 5063.539444] RDX: 0000000000000001 RSI: 0000000000000000 RDI: ffffffffc03ff168
[ 5063.539998] RBP: ffff8fc7c3920a48 R08: 0000000000000000 R09: 0000000000000000
[ 5063.540599] R10: 0000000000000000 R11: ffff8fc7c26d8ff8 R12: ffff8fc7c57a80c0
[ 5063.541180] R13: ffff8fc7cc8d4d90 R14: ffff8fc7c3920ab0 R15: ffff8fc7d141eb00
[ 5063.541750] FS:  0000000000000000(0000) GS:ffff8fc83bc00000(0000) knlGS:0000000000000000
[ 5063.542405] CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[ 5063.542858] CR2: 000055b291585c70 CR3: 000000010cba6003 CR4: 0000000000370ef0
[ 5063.543437] Call Trace:
[ 5063.543653]  qxl_bo_unref+0x31/0x50 [qxl]
[ 5063.544004]  qxl_release_free_list+0x52/0xa0 [qxl]
[ 5063.544397]  qxl_release_free+0x76/0xe0 [qxl]
[ 5063.544756]  qxl_garbage_collect+0xbd/0x170 [qxl]
[ 5063.545152]  process_one_work+0x26e/0x570
[ 5063.545539]  worker_thread+0x55/0x3c0
[ 5063.546023]  ? process_one_work+0x570/0x570
[ 5063.546396]  kthread+0x137/0x150
[ 5063.546658]  ? __kthread_bind_mask+0x60/0x60
[ 5063.547019]  ret_from_fork+0x1f/0x30
[ 5063.547325] irq event stamp: 0
[ 5063.547578] hardirqs last  enabled at (0): [<0000000000000000>] 0x0
[ 5063.548092] hardirqs last disabled at (0): [] copy_process+0x8c4/0x1ca0
[ 5063.548742] softirqs last  enabled at (0): [] copy_process+0x8c4/0x1ca0
[ 5063.549396] softirqs last disabled at (0): [<0000000000000000>] 0x0
[ 5063.549900] ---[ end trace 5f7855eb2e852be3 ]---
[ 5063.550386] ------------[ cut here ]------------
[ 5063.550867] WARNING: CPU: 0 PID: 809716 at drivers/gpu/drm/ttm/ttm_bo.c:512 ttm_bo_release+0x4f9/0x5c0 [ttm]
[ 5063.551700] Modules linked in: 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 xfs intel_rapl_msr iTCO_wdt iTCO_vendor_support snd_hda_codec_generic intel_rapl_common snd_hda_intel joydev i2c_i801 i2c_smbus snd_intel_dspcfg snd_hda_codec snd_hwdep virtio_balloon snd_hda_core snd_pcm lpc_ich snd_timer snd soundcore zram ip_tables crct10dif_pclmul crc32_pclmul crc32c_intel ghash_clmulni_intel qxl drm_ttm_helper serio_raw ttm drm_kms_helper cec drm virtio_blk qemu_fw_cfg
[ 5063.556447] CPU: 0 PID: 809716 Comm: kworker/0:12 Tainted: G        W         5.12.0-rc1+ #153
[ 5063.557303] Hardware name: QEMU Standard PC (Q35 + ICH9, 2009), BIOS 1.13.0-2.fc32 04/01/2014
[ 5063.558006] Workqueue: events qxl_gc_work [qxl]
[ 5063.558382] RIP: 0010:ttm_bo_release+0x4f9/0x5c0 [ttm]
[ 5063.558793] Code: ff 48 8b bd e8 fe ff ff b9 30 75 00 00 31 d2 be 01 00 00 00 e8 38 75 55 e5 48 8b 45 e0 e9 fb fe ff ff 4c 89 e8 e9 f3 fe ff ff <0f> 0b c7 85 94 00 00 00 00 00 00 00 48 8b 3c 24 48 8d 75 08 31 d2
[ 5063.560279] RSP: 0018:ffff9cbc01467d80 EFLAGS: 00010202
[ 5063.560695] RAX: 0000000000000001 RBX: 000000000000000f RCX: 0000000000000000
[ 5063.561273] RDX: 0000000000000001 RSI: 0000000000000000 RDI: ffffffffc03ff168
[ 5063.561864] RBP: ffff8fc7d3290e48 R08: 0000000000000000 R09: 0000000000000000
[ 5063.561943] BTRFS info (device dm-9): found 1 extents, stage: move data extents
[ 5063.562357] R10: 0000000000000000 R11: 0000000000000000 R12: ffff8fc7d13cef00
[ 5063.563173] R13: ffff8fc7cc8d4d90 R14: ffff8fc7d3290eb0 R15: ffff8fc7d13ce000
[ 5063.563648] FS:  0000000000000000(0000) GS:ffff8fc83bc00000(0000) knlGS:0000000000000000
[ 5063.564123] CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[ 5063.564450] CR2: 000055b291585c70 CR3: 000000010cba6003 CR4: 0000000000370ef0
[ 5063.564845] Call Trace:
[ 5063.565003]  qxl_bo_unref+0x31/0x50 [qxl]
[ 5063.565240]  qxl_release_free_list+0x52/0xa0 [qxl]
[ 5063.565515]  qxl_release_free+0x76/0xe0 [qxl]
[ 5063.565771]  qxl_garbage_collect+0xbd/0x170 [qxl]
[ 5063.566052]  process_one_work+0x26e/0x570
[ 5063.566292]  worker_thread+0x55/0x3c0
[ 5063.566510]  ? process_one_work+0x570/0x570
[ 5063.566769]  kthread+0x137/0x150
[ 5063.566974]  ? __kthread_bind_mask+0x60/0x60
[ 5063.567227]  ret_from_fork+0x1f/0x30
[ 5063.567487] irq event stamp: 0
[ 5063.567660] hardirqs last  enabled at (0): [<0000000000000000>] 0x0
[ 5063.568054] hardirqs last disabled at (0): [] copy_process+0x8c4/0x1ca0
[ 5063.568609] softirqs last  enabled at (0): [] copy_process+0x8c4/0x1ca0
[ 5063.569092] softirqs last disabled at (0): [<0000000000000000>] 0x0
[ 5063.569452] ---[ end trace 5f7855eb2e852be4 ]---
[ 5063.590766] BTRFS info (device dm-9): balance: ended with status: 0
[ 5063.702809] BTRFS info (device dm-7): using free space tree
[ 5063.703308] BTRFS info (device dm-7): has skinny extents
[ 5063.710165] BTRFS info (device dm-7): cleaning free space cache v1
[ 5063.718260] BTRFS info (device dm-7): checking UUID tree
[ 5063.799832] BTRFS info (device dm-7): using free space tree
[ 5063.800341] BTRFS info (device dm-7): has skinny extents
[ 5063.802490] BTRFS info (device dm-7): checking UUID tree
[ 5063.880719] BTRFS info (device dm-7): using free space tree
[ 5063.881232] BTRFS info (device dm-7): has skinny extents
[ 5064.021768] BTRFS info (device dm-7): using free space tree
[ 5064.022129] BTRFS info (device dm-7): has skinny extents
[ 5064.186669] BTRFS info (device dm-7): qgroup scan completed (inconsistency flag cleared)
[ 5064.419725] BTRFS info (device dm-7): using free space tree
[ 5064.420203] BTRFS info (device dm-7): has skinny extents
[ 5064.680342] BTRFS info (device dm-7): using free space tree
[ 5064.680804] BTRFS info (device dm-7): has skinny extents
[ 5064.894236] BTRFS info (device dm-7): balance: resume -f -musage=90 -susage=90
[ 5064.894670] BTRFS info (device dm-7): balance: paused
[ 5065.007174] BTRFS info (device dm-7): using free space tree
[ 5065.007520] BTRFS info (device dm-7): has skinny extents
[ 5065.009952] BTRFS info (device dm-7): checking UUID tree
[ 5065.018021] BTRFS info (device dm-7): balance: resume -f -musage=90 -susage=90
[ 5065.018543] BTRFS info (device dm-7): relocating block group 298844160 flags metadata|dup
[ 5065.066846] BTRFS info (device dm-7): balance: paused
[ 5065.202085] BTRFS info (device dm-7): using free space tree
[ 5065.202532] BTRFS info (device dm-7): has skinny extents
[ 5065.239454] BTRFS info (device dm-7): checking UUID tree
[ 5065.249295] BTRFS info (device dm-7): balance: resume -f -musage=90 -susage=90
[ 5065.249768] BTRFS info (device dm-7): balance: paused
[ 5065.376438] BTRFS info (device dm-7): using free space tree
[ 5065.376772] BTRFS info (device dm-7): has skinny extents
[ 5065.629818] BTRFS info (device dm-7): checking UUID tree
[ 5065.642195] BTRFS info (device dm-7): balance: resume -f -musage=90 -susage=90
[ 5065.642705] BTRFS info (device dm-7): balance: paused
[ 5065.777637] BTRFS info (device dm-7): using free space tree
[ 5065.778010] BTRFS info (device dm-7): has skinny extents
[ 5065.779871] BTRFS info (device dm-7): checking UUID tree
[ 5065.802783] BTRFS info (device dm-7): balance: resume -f -musage=90 -susage=90
[ 5065.803250] BTRFS info (device dm-7): balance: paused
[ 5065.943127] BTRFS info (device dm-7): using free space tree
[ 5065.943566] BTRFS info (device dm-7): has skinny extents
[ 5065.945795] BTRFS info (device dm-7): checking UUID tree
[ 5065.955818] BTRFS info (device dm-7): balance: resume -f -musage=90 -susage=90
[ 5065.956298] BTRFS info (device dm-7): balance: paused
[ 5066.088391] BTRFS info (device dm-7): using free space tree
[ 5066.088731] BTRFS info (device dm-7): has skinny extents
[ 5066.090943] BTRFS info (device dm-7): checking UUID tree
[ 5066.158696] BTRFS info (device dm-7): balance: resume -f -musage=90 -susage=90
[ 5066.159158] BTRFS info (device dm-7): balance: paused
[ 5066.466743] BTRFS info (device dm-7): using free space tree
[ 5066.467093] BTRFS info (device dm-7): has skinny extents
[ 5066.471262] BTRFS info (device dm-7): checking UUID tree
[ 5066.483889] BTRFS info (device dm-7): balance: resume -f -musage=90 -susage=90
[ 5066.484451] BTRFS info (device dm-7): relocating block group 567279616 flags system|dup
[ 5066.529320] BTRFS info (device dm-7): balance: paused
[ 5066.662527] BTRFS info (device dm-7): using free space tree
[ 5066.662917] BTRFS info (device dm-7): has skinny extents
[ 5066.669863] BTRFS info (device dm-7): checking UUID tree
[ 5066.682439] BTRFS info (device dm-7): balance: resume -f -musage=90 -susage=90
[ 5066.682982] BTRFS info (device dm-7): balance: paused
[ 5066.820166] BTRFS info (device dm-7): using free space tree
[ 5066.820523] BTRFS info (device dm-7): has skinny extents
[ 5066.822752] BTRFS info (device dm-7): checking UUID tree
[ 5066.999500] BTRFS info (device dm-7): balance: resume -f -musage=90 -susage=90
[ 5066.999952] BTRFS info (device dm-7): balance: paused
[ 5067.200984] BTRFS info (device dm-7): using free space tree
[ 5067.201453] BTRFS info (device dm-7): has skinny extents
[ 5067.203927] BTRFS info (device dm-7): checking UUID tree
[ 5067.217750] BTRFS info (device dm-7): balance: resume -f -musage=90 -susage=90
[ 5067.218486] BTRFS info (device dm-7): relocating block group 567279616 flags system|dup
[ 5067.250670] BTRFS info (device dm-7): found 1 extents, stage: move data extents
[ 5067.273281] BTRFS info (device dm-7): balance: paused
[ 5067.412144] BTRFS info (device dm-7): using free space tree
[ 5067.412641] BTRFS info (device dm-7): has skinny extents
[ 5067.415098] BTRFS info (device dm-7): checking UUID tree
[ 5067.423598] BTRFS info (device dm-7): balance: resume -f -musage=90 -susage=90
[ 5067.424362] BTRFS info (device dm-7): relocating block group 567279616 flags system|dup
[ 5067.454713] BTRFS info (device dm-7): found 1 extents, stage: move data extents
[ 5067.477521] BTRFS info (device dm-7): balance: paused
[ 5067.790561] BTRFS info (device dm-7): using free space tree
[ 5067.790909] BTRFS info (device dm-7): has skinny extents
[ 5067.793030] BTRFS info (device dm-7): checking UUID tree
[ 5067.814577] BTRFS info (device dm-7): balance: resume -f -musage=90 -susage=90
[ 5067.819459] BTRFS info (device dm-7): relocating block group 567279616 flags system|dup
[ 5067.831588] BTRFS info (device dm-7): found 1 extents, stage: move data extents
[ 5067.845879] BTRFS info (device dm-7): balance: paused
[ 5067.979606] BTRFS info (device dm-7): using free space tree
[ 5067.979966] BTRFS info (device dm-7): has skinny extents
[ 5068.131538] BTRFS info (device dm-7): using free space tree
[ 5068.131896] BTRFS info (device dm-7): has skinny extents