[15565.877054] run fstests btrfs/142 at 2022-04-03 01:10:53
[15566.105248] BTRFS info (device dm-0): flagging fs with big metadata feature
[15566.105473] BTRFS info (device dm-0): using free space tree
[15566.105642] BTRFS info (device dm-0): has skinny extents
[15566.573750] BTRFS: device fsid 33b92be4-b937-4f92-b588-5f79a672d37e devid 1 transid 6 /dev/mapper/vg0-lv9 scanned by mkfs.btrfs (2962642)
[15566.574300] BTRFS: device fsid 33b92be4-b937-4f92-b588-5f79a672d37e devid 2 transid 6 /dev/mapper/vg0-lv8 scanned by mkfs.btrfs (2962642)
[15566.635611] BTRFS info (device dm-9): flagging fs with big metadata feature
[15566.635860] BTRFS info (device dm-9): setting incompat feature flag for COMPRESS_ZSTD (0x10)
[15566.636128] BTRFS info (device dm-9): use zstd compression, level 3
[15566.636314] BTRFS info (device dm-9): turning on async discard
[15566.636511] BTRFS info (device dm-9): setting nodatasum
[15566.636681] BTRFS info (device dm-9): disabling disk space caching
[15566.636898] BTRFS info (device dm-9): has skinny extents
[15566.638267] BTRFS info (device dm-9): cleaning free space cache v1
[15566.646884] BTRFS info (device dm-9): checking UUID tree
[15566.781274] BTRFS info (device dm-11): flagging fs with big metadata feature
[15566.781492] BTRFS info (device dm-11): use zstd compression, level 3
[15566.781680] BTRFS info (device dm-11): turning on async discard
[15566.781922] BTRFS info (device dm-11): has skinny extents
[15566.786393] device-mapper: dust: dust_add_block: badblock added at block 269056 with write fail count 0
[15566.788329] device-mapper: dust: enabling read failures on bad sectors
[15566.792863] btrfs_dev_stat_print_on_error: 22 callbacks suppressed
[15566.792865] BTRFS error (device dm-11): bdev /dev/mapper/dust-test errs: wr 0, rd 1, flush 0, corrupt 0, gen 0
[15566.793465] BTRFS warning (device dm-11): direct IO failed ino 257 rw 0,0 sector 0x41b07 len 512 err no 10
[15566.794092] device-mapper: dust: block 269056 removed from badblocklist by write
[15566.838776] BUG: sleeping function called from invalid context at block/blk-sysfs.c:766
[15566.839168] in_atomic(): 1, irqs_disabled(): 0, non_block: 0, pid: 1, name: systemd
[15566.839559] preempt_count: 100, expected: 0
[15566.839813] RCU nest depth: 0, expected: 0
[15566.840026] INFO: lockdep is turned off.
[15566.840223] Preemption disabled at:
[15566.840224] [] __do_softirq+0x5e/0x517
[15566.840562] CPU: 1 PID: 1 Comm: systemd Tainted: G        W         5.17.0+ #640
[15566.840933] Hardware name: QEMU Standard PC (Q35 + ICH9, 2009), BIOS 1.13.0-2.fc32 04/01/2014
[15566.841251] Call Trace:
[15566.841374]  
[15566.841491]  dump_stack_lvl+0x56/0x6f
[15566.841633]  __might_resched.cold+0xff/0x13a
[15566.841793]  blk_release_queue+0x24/0x120
[15566.841943]  kobject_put+0x7c/0x1d0
[15566.842081]  ? rcu_do_batch+0x1ac/0x570
[15566.842278]  blkg_free.part.0+0x41/0x60
[15566.842425]  rcu_do_batch+0x1e1/0x570
[15566.842564]  ? rcu_do_batch+0x1ac/0x570
[15566.842708]  rcu_core+0x2cb/0x460
[15566.842898]  __do_softirq+0x178/0x517
[15566.843043]  __irq_exit_rcu+0xe3/0x160
[15566.843225]  irq_exit_rcu+0xa/0x20
[15566.843363]  sysvec_apic_timer_interrupt+0xa2/0xd0
[15566.843526]  
[15566.843640]  
[15566.843756]  asm_sysvec_apic_timer_interrupt+0x12/0x20
[15566.843925] RIP: 0010:seq_read_iter+0x110/0x4d0
[15566.844080] Code: 28 48 c7 43 10 00 00 00 00 48 89 df 4c 89 f6 48 8b 00 ff d0 0f 1f 00 49 89 c7 89 c1 48 85 c0 0f 85 bc 00 00 00 e9 c4 00 00 00 <48> 8b 83 c8 00 00 00 4c 89 fe 48 89 df 48 8b 40 18 ff d0 0f 1f 00
[15566.844551] RSP: 0018:ffffb18fc0013e00 EFLAGS: 00000203
[15566.845359] RAX: 0000000000000001 RBX: ffff9e59d685e488 RCX: 0000000000000001
[15566.845567] RDX: 0000000000000000 RSI: 0000000000000000 RDI: ffff9e59d3079938
[15566.845773] RBP: 0000000000000000 R08: 0000000000000001 R09: 0000000000000000
[15566.845974] R10: 0000000000000000 R11: 0000000000000000 R12: ffffb18fc0013e90
[15566.846171] R13: ffffb18fc0013e68 R14: ffff9e59d685e4b0 R15: 0000000000000001
[15566.846374]  new_sync_read+0xf3/0x160
[15566.846519]  vfs_read+0x15f/0x1b0
[15566.846655]  ksys_read+0x55/0xd0
[15566.846806]  do_syscall_64+0x38/0x90
[15566.846946]  entry_SYSCALL_64_after_hwframe+0x44/0xae
[15566.847110] RIP: 0033:0x7f405110c52c
[15566.847254] Code: ec 28 48 89 54 24 18 48 89 74 24 10 89 7c 24 08 e8 d9 38 f9 ff 48 8b 54 24 18 48 8b 74 24 10 41 89 c0 8b 7c 24 08 31 c0 0f 05 <48> 3d 00 f0 ff ff 77 34 44 89 c7 48 89 44 24 08 e8 2f 39 f9 ff 48
[15566.847730] RSP: 002b:00007ffc47751e90 EFLAGS: 00000246 ORIG_RAX: 0000000000000000
[15566.847978] RAX: ffffffffffffffda RBX: 00005636bfeb11a0 RCX: 00007f405110c52c
[15566.848184] RDX: 0000000000001000 RSI: 00005636bfec9c80 RDI: 0000000000000014
[15566.848388] RBP: 00007f40511e0320 R08: 0000000000000000 R09: 00007f40511dea60
[15566.848594] R10: 0000000000000040 R11: 0000000000000246 R12: 0000000000000800
[15566.848806] R13: 00007f40511df720 R14: 0000000000000d68 R15: 0000000000000d68
[15566.849017]