[ 3262.345006] run fstests btrfs/159 at 2022-04-03 21:50:50
[ 3262.537600] BTRFS info (device dm-0): flagging fs with big metadata feature
[ 3262.537866] BTRFS info (device dm-0): using free space tree
[ 3262.538037] BTRFS info (device dm-0): has skinny extents
[ 3262.774643] BTRFS: device fsid 6a4908dc-9919-477a-8b27-de0169cd85f5 devid 1 transid 6 /dev/mapper/vg0-lv7 scanned by mkfs.btrfs (492526)
[ 3262.839138] BTRFS info (device dm-9): flagging fs with big metadata feature
[ 3262.839377] BTRFS info (device dm-9): using free space tree
[ 3262.839551] BTRFS info (device dm-9): has skinny extents
[ 3262.841869] BTRFS info (device dm-9): checking UUID tree
[ 3268.344099] BTRFS info: devid 1 device path /dev/mapper/flakey-test changed to /dev/dm-9 scanned by systemd-udevd (494239)
[ 3268.345066] BTRFS info: devid 1 device path /dev/dm-9 changed to /dev/mapper/flakey-test scanned by systemd-udevd (494239)
[ 3268.443144] BTRFS info (device dm-9): flagging fs with big metadata feature
[ 3268.443384] BTRFS info (device dm-9): using free space tree
[ 3268.443563] BTRFS info (device dm-9): has skinny extents
[ 3268.445833] BTRFS info (device dm-9): start tree-log replay
[ 3269.566343] BUG: sleeping function called from invalid context at block/blk-sysfs.c:766
[ 3269.566815] in_atomic(): 1, irqs_disabled(): 0, non_block: 0, pid: 494281, name: mkfs.btrfs
[ 3269.567236] preempt_count: 103, expected: 0
[ 3269.567439] RCU nest depth: 0, expected: 0
[ 3269.567606] INFO: lockdep is turned off.
[ 3269.567779] Preemption disabled at:
[ 3269.567779] [<0000000000000000>] 0x0
[ 3269.568145] CPU: 1 PID: 494281 Comm: mkfs.btrfs Tainted: G        W         5.17.0+ #641
[ 3269.568396] Hardware name: QEMU Standard PC (Q35 + ICH9, 2009), BIOS 1.13.0-2.fc32 04/01/2014
[ 3269.568710] Call Trace:
[ 3269.568894]  
[ 3269.569012]  dump_stack_lvl+0x56/0x6f
[ 3269.569172]  __might_resched.cold+0xff/0x13a
[ 3269.569327]  blk_release_queue+0x24/0x120
[ 3269.569477]  kobject_put+0x7c/0x1d0
[ 3269.569643]  ? rcu_do_batch+0x1ac/0x570
[ 3269.569789]  blkg_free.part.0+0x41/0x60
[ 3269.569937]  rcu_do_batch+0x1e1/0x570
[ 3269.570097]  ? rcu_do_batch+0x1ac/0x570
[ 3269.570239]  rcu_core+0x2cb/0x460
[ 3269.570375]  __do_softirq+0x178/0x517
[ 3269.570518]  __irq_exit_rcu+0xe3/0x160
[ 3269.570684]  irq_exit_rcu+0xa/0x20
[ 3269.570821]  sysvec_apic_timer_interrupt+0xa2/0xd0
[ 3269.570985]  
[ 3269.571119]  
[ 3269.571231]  asm_sysvec_apic_timer_interrupt+0x12/0x20
[ 3269.571397] RIP: 0010:_raw_spin_unlock_irqrestore+0x33/0x60
[ 3269.571573] Code: 48 83 c7 18 53 48 89 f3 48 8b 74 24 10 e8 45 1b 38 ff 48 89 ef e8 dd 44 38 ff 80 e7 02 74 0b e8 53 70 44 ff fb 0f 1f 44 00 00  01 00 00 00 e8 93 d8 33 ff 65 8b 05 6c ac 1b 5d 85 c0 74 03 5b
[ 3269.572030] RSP: 0000:ffffb94a02c6fd50 EFLAGS: 00000246
[ 3269.572213] RAX: 0000000080000003 RBX: 0000000000000246 RCX: 000000000000039e
[ 3269.572415] RDX: ffff96344e01a000 RSI: ffffffffa236c46f RDI: ffffffffa2e651fd
[ 3269.573527] RBP: ffff96344e01a050 R08: 0000000000000000 R09: 0000000000000000
[ 3269.573735] R10: 0000000000000000 R11: 0000000000000000 R12: 000000000000000f
[ 3269.573933] R13: ffffdfe604bbb400 R14: ffffdfe604aa5d48 R15: ffff96344e01a000
[ 3269.574132]  ? __pagevec_lru_add+0x28f/0x5d0
[ 3269.574287]  ? _raw_spin_unlock_irqrestore+0x2d/0x60
[ 3269.574451]  __pagevec_lru_add+0x28f/0x5d0
[ 3269.574604]  folio_add_lru+0xd5/0x220
[ 3269.574746]  __handle_mm_fault+0x1288/0x1460
[ 3269.574894]  handle_mm_fault+0x11e/0x3a0
[ 3269.575037]  do_user_addr_fault+0x1e9/0x6b0
[ 3269.575191]  exc_page_fault+0x78/0x280
[ 3269.575335]  ? asm_exc_page_fault+0x8/0x30
[ 3269.575480]  asm_exc_page_fault+0x1e/0x30
[ 3269.575626] RIP: 0033:0x7f1f40b1db71
[ 3269.575766] Code: 01 00 00 48 83 fa 40 77 77 c5 fe 7f 44 17 e0 c5 fe 7f 07 c5 f8 77 c3 66 90 f3 0f 1e fa c5 f8 77 48 89 d1 40 0f b6 c6 48 89 fa  aa 48 89 d0 c3 66 0f 1f 84 00 00 00 00 00 f3 0f 1e fa 48 39 d1
[ 3269.576225] RSP: 002b:00007ffc9f42e948 EFLAGS: 00010206
[ 3269.576389] RAX: 0000000000000000 RBX: 0000000000a78c70 RCX: 00000000001a7c70
[ 3269.576588] RDX: 0000000000a78c70 RSI: 0000000000000000 RDI: 0000000000ad1000
[ 3269.576783] RBP: 0000000000200000 R08: 0000000000a78c70 R09: 00007f1f40b7da60
[ 3269.576980] R10: fffffffffffff000 R11: 0000000000a96000 R12: 0000000000000000
[ 3269.577176] R13: 0000000000000003 R14: 000000027fe00000 R15: 0000000000010000
[ 3269.577376]  
[ 3269.641087] BTRFS: device fsid 9527b940-65c4-4897-b22a-7b6952260481 devid 1 transid 6 /dev/mapper/vg0-lv7 scanned by mkfs.btrfs (494281)
[ 3269.699399] BTRFS info (device dm-9): flagging fs with big metadata feature
[ 3269.699670] BTRFS info (device dm-9): using free space tree
[ 3269.699840] BTRFS info (device dm-9): has skinny extents
[ 3269.702038] BTRFS info (device dm-9): checking UUID tree
[ 3274.720058] BTRFS info: devid 1 device path /dev/mapper/flakey-test changed to /dev/dm-9 scanned by systemd-udevd (495995)
[ 3274.720941] BTRFS info: devid 1 device path /dev/dm-9 changed to /dev/mapper/flakey-test scanned by systemd-udevd (495995)
[ 3274.816340] BTRFS info (device dm-9): flagging fs with big metadata feature
[ 3274.816569] BTRFS info (device dm-9): using free space tree
[ 3274.816742] BTRFS info (device dm-9): has skinny extents
[ 3274.819232] BTRFS info (device dm-9): start tree-log replay
[ 3275.870298] BUG: sleeping function called from invalid context at block/blk-sysfs.c:766
[ 3275.870604] in_atomic(): 1, irqs_disabled(): 0, non_block: 0, pid: 14, name: ksoftirqd/0
[ 3275.870867] preempt_count: 100, expected: 0
[ 3275.871033] RCU nest depth: 0, expected: 0
[ 3275.871178] INFO: lockdep is turned off.
[ 3275.871336] Preemption disabled at:
[ 3275.871337] [] __do_softirq+0x5e/0x517
[ 3275.871673] CPU: 0 PID: 14 Comm: ksoftirqd/0 Tainted: G        W         5.17.0+ #641
[ 3275.871929] Hardware name: QEMU Standard PC (Q35 + ICH9, 2009), BIOS 1.13.0-2.fc32 04/01/2014
[ 3275.872219] Call Trace:
[ 3275.872347]  
[ 3275.872463]  dump_stack_lvl+0x56/0x6f
[ 3275.873446]  __might_resched.cold+0xff/0x13a
[ 3275.873619]  blk_release_queue+0x24/0x120
[ 3275.879637]  kobject_put+0x7c/0x1d0
[ 3275.879788]  ? rcu_do_batch+0x1ac/0x570
[ 3275.879935]  blkg_free.part.0+0x41/0x60
[ 3275.880095]  rcu_do_batch+0x1e1/0x570
[ 3275.880236]  ? rcu_do_batch+0x1ac/0x570
[ 3275.880381]  rcu_core+0x2cb/0x460
[ 3275.880540]  __do_softirq+0x178/0x517
[ 3275.880685]  ? smpboot_thread_fn+0x2c/0x270
[ 3275.880839]  run_ksoftirqd+0x43/0x70
[ 3275.880998]  smpboot_thread_fn+0x1c1/0x270
[ 3275.881144]  ? sort_range+0x20/0x20
[ 3275.881285]  kthread+0xf0/0x120
[ 3275.881417]  ? kthread_complete_and_exit+0x20/0x20
[ 3275.881601]  ret_from_fork+0x1f/0x30
[ 3275.881745]