[14699.232780] run fstests btrfs/056 at 2022-04-03 00:56:27
[14699.423832] BTRFS info (device dm-0): flagging fs with big metadata feature
[14699.424087] BTRFS info (device dm-0): using free space tree
[14699.424268] BTRFS info (device dm-0): has skinny extents
[14699.583621] BTRFS: device fsid ffae3e7b-f391-4e03-9df9-b017b7f054a3 devid 1 transid 6 /dev/mapper/vg0-lv9 scanned by mkfs.btrfs (2858680)
[14699.644493] BTRFS info (device dm-11): flagging fs with big metadata feature
[14699.644718] BTRFS info (device dm-11): setting incompat feature flag for COMPRESS_ZSTD (0x10)
[14699.644978] BTRFS info (device dm-11): use zstd compression, level 3
[14699.645224] BTRFS info (device dm-11): turning on async discard
[14699.645407] BTRFS info (device dm-11): disk space caching is enabled
[14699.645592] BTRFS info (device dm-11): has skinny extents
[14699.647237] BTRFS info (device dm-11): checking UUID tree
[14699.757213] BTRFS info: devid 1 device path /dev/mapper/flakey-test changed to /dev/dm-11 scanned by systemd-udevd (2855299)
[14699.757966] BTRFS info: devid 1 device path /dev/dm-11 changed to /dev/mapper/flakey-test scanned by systemd-udevd (2855299)
[14699.819933] BTRFS info (device dm-11): flagging fs with big metadata feature
[14699.820178] BTRFS info (device dm-11): setting incompat feature flag for COMPRESS_ZSTD (0x10)
[14699.820441] BTRFS info (device dm-11): use zstd compression, level 3
[14699.820622] BTRFS info (device dm-11): turning on async discard
[14699.820799] BTRFS info (device dm-11): disk space caching is enabled
[14699.820982] BTRFS info (device dm-11): has skinny extents
[14699.822389] BTRFS info (device dm-11): start tree-log replay
[14699.834915] BTRFS info (device dm-11): checking UUID tree
[14699.906010] BUG: sleeping function called from invalid context at block/blk-sysfs.c:766
[14699.906378] in_atomic(): 1, irqs_disabled(): 0, non_block: 0, pid: 0, name: swapper/1
[14699.906765] preempt_count: 101, expected: 0
[14699.906959] RCU nest depth: 0, expected: 0
[14699.907158] INFO: lockdep is turned off.
[14699.907395] Preemption disabled at:
[14699.907396] [] ___slab_alloc+0x961/0xf50
[14699.907764] CPU: 1 PID: 0 Comm: swapper/1 Tainted: G W 5.17.0+ #640
[14699.908001] Hardware name: QEMU Standard PC (Q35 + ICH9, 2009), BIOS 1.13.0-2.fc32 04/01/2014
[14699.908355] Call Trace:
[14699.908562]
[14699.908673] dump_stack_lvl+0x56/0x6f
[14699.908853] __might_resched.cold+0xff/0x13a
[14699.909007] blk_release_queue+0x24/0x120
[14699.909155] kobject_put+0x7c/0x1d0
[14699.909290] ? rcu_do_batch+0x1ac/0x570
[14699.909433] blkg_free.part.0+0x41/0x60
[14699.909576] rcu_do_batch+0x1e1/0x570
[14699.909712] ? rcu_do_batch+0x1ac/0x570
[14699.909852] rcu_core+0x2cb/0x460
[14699.909986] __do_softirq+0x178/0x517
[14699.910129] __irq_exit_rcu+0xe3/0x160
[14699.910270] irq_exit_rcu+0xa/0x20
[14699.910404] sysvec_apic_timer_interrupt+0xa2/0xd0
[14699.910562]
[14699.910674]
[14699.910787] asm_sysvec_apic_timer_interrupt+0x12/0x20
[14699.910951] RIP: 0010:native_safe_halt+0xb/0x10
[14699.911105] Code: 48 ff ff ff 7f 5b c3 65 48 8b 04 25 c0 fe 01 00 f0 80 48 02 20 48 8b 00 a8 08 75 c3 eb 80 cc eb 07 0f 00 2d 79 b7 5b 00 fb f4 0f 1f 40 00 eb 07 0f 00 2d 69 b7 5b 00 f4 c3 cc cc cc cc cc 0f
[14699.911553] RSP: 0018:ffffb18fc0093ef8 EFLAGS: 00000246
[14699.911718] RAX: ffffffffb7e64a80 RBX: ffff9e59c037b200 RCX: 0000000000000000
[14699.911913] RDX: ffff9e59c037b200 RSI: ffffffffb887e4d9 RDI: ffffffffb7e64bf6
[14699.912106] RBP: 0000000000000001 R08: 0000000000000000 R09: 0000000000000000
[14699.912296] R10: 0000000000000000 R11: ffff9e5a3bd20c88 R12: 0000000000000000
[14699.912488] R13: 0000000000000000 R14: 0000000000000000 R15: 0000000000000000
[14699.912680] ? mwait_idle+0x80/0x80
[14699.912813] ? default_idle_call+0x26/0xa0
[14699.912962] default_idle+0xa/0x10
[14699.913099] default_idle_call+0x5e/0xa0
[14699.913239] do_idle+0x1e9/0x240
[14699.913371] cpu_startup_entry+0x19/0x20
[14699.913515] secondary_startup_64_no_verify+0xd5/0xdb
[14699.913677]
[14699.993849] BTRFS: device fsid 7beadd9e-ac73-4a49-9ec2-a6c703007a1c devid 1 transid 6 /dev/mapper/vg0-lv9 scanned by mkfs.btrfs (2858817)
[14700.056085] BTRFS info (device dm-11): flagging fs with big metadata feature
[14700.056314] BTRFS info (device dm-11): setting incompat feature flag for COMPRESS_ZSTD (0x10)
[14700.056570] BTRFS info (device dm-11): use zstd compression, level 3
[14700.056751] BTRFS info (device dm-11): turning on async discard
[14700.056929] BTRFS info (device dm-11): setting incompat feature flag for COMPRESS_LZO (0x8)
[14700.057206] BTRFS info (device dm-11): force lzo compression, level 0
[14700.057390] BTRFS info (device dm-11): disk space caching is enabled
[14700.057569] BTRFS info (device dm-11): has skinny extents
[14700.059426] BTRFS info (device dm-11): checking UUID tree
[14700.168536] BTRFS info: devid 1 device path /dev/mapper/flakey-test changed to /dev/dm-11 scanned by systemd-udevd (2855299)
[14700.169509] BTRFS info: devid 1 device path /dev/dm-11 changed to /dev/mapper/flakey-test scanned by systemd-udevd (2855299)
[14700.239520] BTRFS info (device dm-11): flagging fs with big metadata feature
[14700.239739] BTRFS info (device dm-11): setting incompat feature flag for COMPRESS_ZSTD (0x10)
[14700.240017] BTRFS info (device dm-11): use zstd compression, level 3
[14700.240209] BTRFS info (device dm-11): turning on async discard
[14700.240383] BTRFS info (device dm-11): setting incompat feature flag for COMPRESS_LZO (0x8)
[14700.240632] BTRFS info (device dm-11): force lzo compression, level 0
[14700.240816] BTRFS info (device dm-11): disk space caching is enabled
[14700.241008] BTRFS info (device dm-11): has skinny extents
[14700.242858] BTRFS info (device dm-11): start tree-log replay
[14700.252978] BTRFS info (device dm-11): checking UUID tree
[14700.396773] BTRFS: device fsid 367a93e9-dd41-424d-a55d-f38a28a00ddb devid 1 transid 6 /dev/mapper/vg0-lv9 scanned by mkfs.btrfs (2858954)
[14700.458382] BTRFS info (device dm-11): flagging fs with big metadata feature
[14700.458605] BTRFS info (device dm-11): setting incompat feature flag for COMPRESS_ZSTD (0x10)
[14700.458866] BTRFS info (device dm-11): use zstd compression, level 3
[14700.459074] BTRFS info (device dm-11): turning on async discard
[14700.459262] BTRFS info (device dm-11): disk space caching is enabled
[14700.459446] BTRFS info (device dm-11): has skinny extents
[14700.461317] BTRFS info (device dm-11): checking UUID tree
[14700.567868] BTRFS info: devid 1 device path /dev/mapper/flakey-test changed to /dev/dm-11 scanned by systemd-udevd (2855299)
[14700.568760] BTRFS info: devid 1 device path /dev/dm-11 changed to /dev/mapper/flakey-test scanned by systemd-udevd (2855299)
[14700.631678] BTRFS info (device dm-11): flagging fs with big metadata feature
[14700.631910] BTRFS info (device dm-11): setting incompat feature flag for COMPRESS_ZSTD (0x10)
[14700.632206] BTRFS info (device dm-11): use zstd compression, level 3
[14700.632394] BTRFS info (device dm-11): turning on async discard
[14700.632571] BTRFS info (device dm-11): disk space caching is enabled
[14700.632756] BTRFS info (device dm-11): has skinny extents
[14700.634366] BTRFS info (device dm-11): start tree-log replay
[14700.644625] BTRFS info (device dm-11): checking UUID tree
[14700.801972] BTRFS: device fsid fc41adc4-d6a0-4124-b477-2d90a8b328a2 devid 1 transid 6 /dev/mapper/vg0-lv9 scanned by mkfs.btrfs (2859091)
[14700.870298] BTRFS info (device dm-11): flagging fs with big metadata feature
[14700.870531] BTRFS info (device dm-11): setting incompat feature flag for COMPRESS_ZSTD (0x10)
[14700.870794] BTRFS info (device dm-11): use zstd compression, level 3
[14700.870978] BTRFS info (device dm-11): turning on async discard
[14700.871184] BTRFS info (device dm-11): setting incompat feature flag for COMPRESS_LZO (0x8)
[14700.871443] BTRFS info (device dm-11): force lzo compression, level 0
[14700.871626] BTRFS info (device dm-11): disk space caching is enabled
[14700.871808] BTRFS info (device dm-11): has skinny extents
[14701.145002] BUG: sleeping function called from invalid context at block/blk-sysfs.c:766
[14701.145421] in_atomic(): 1, irqs_disabled(): 0, non_block: 0, pid: 2859229, name: 056
[14701.145725] preempt_count: 101, expected: 0
[14701.145897] RCU nest depth: 1, expected: 0
[14701.146136] INFO: lockdep is turned off.
[14701.146277] Preemption disabled at:
[14701.146278] [<0000000000000000>] 0x0
[14701.146611] CPU: 0 PID: 2859229 Comm: 056 Tainted: G W 5.17.0+ #640
[14701.146945] Hardware name: QEMU Standard PC (Q35 + ICH9, 2009), BIOS 1.13.0-2.fc32 04/01/2014
[14701.147314] Call Trace:
[14701.147486]
[14701.147652] dump_stack_lvl+0x56/0x6f
[14701.147862] __might_resched.cold+0xff/0x13a
[14701.148090] blk_release_queue+0x24/0x120
[14701.148958] kobject_put+0x7c/0x1d0
[14701.149126] ? rcu_do_batch+0x1ac/0x570
[14701.149289] blkg_free.part.0+0x41/0x60
[14701.149432] rcu_do_batch+0x1e1/0x570
[14701.149573] ? rcu_do_batch+0x1ac/0x570
[14701.149719] rcu_core+0x2cb/0x460
[14701.149851] __do_softirq+0x178/0x517
[14701.149994] __irq_exit_rcu+0xe3/0x160
[14701.150140] irq_exit_rcu+0xa/0x20
[14701.150273] sysvec_apic_timer_interrupt+0xa2/0xd0
[14701.150430]
[14701.150544]
[14701.150655] asm_sysvec_apic_timer_interrupt+0x12/0x20
[14701.150818] RIP: 0010:folio_memcg_lock+0x148/0x230
[14701.150977] Code: a3 00 49 8b 46 38 a8 01 0f 84 2b ff ff ff 48 c7 c6 68 86 83 b8 4c 89 f7 e8 f5 e7 f6 ff 0f 0b e8 1e 07 e8 ff fb 0f 1f 44 00 00 85 e8 91 04 e8 ff e9 43 ff ff ff 65 48 8b 04 25 c0 fe 01 00 48
[14701.151441] RSP: 0018:ffffb18fc5843ac8 EFLAGS: 00000246
[14701.151603] RAX: 0000000080000001 RBX: ffff9e59c37a8000 RCX: 0000000000000000
[14701.151793] RDX: 0000000000000000 RSI: ffffffffb742ba87 RDI: ffffffffb742bb32
[14701.151987] RBP: ffffb18fc5843ae8 R08: 0000000000000000 R09: 0000000000000000
[14701.152187] R10: 0000000000000000 R11: ffffffffb73cd75c R12: 0000000000000200
[14701.152384] R13: ffff9e59c37a8688 R14: ffffe16705b043c0 R15: 800000016c10f845
[14701.152580] ? free_unref_page_list+0x12c/0x560
[14701.152739] ? folio_memcg_lock+0x97/0x230
[14701.152885] ? folio_memcg_lock+0x142/0x230
[14701.153032] ? folio_memcg_lock+0x142/0x230
[14701.153185] page_remove_rmap+0x1c/0x5c0
[14701.153328] unmap_page_range+0x706/0x10e0
[14701.153471] ? lock_release+0x1ed/0x2c0
[14701.153614] unmap_vmas+0xb3/0x130
[14701.153752] exit_mmap+0x91/0x1b0
[14701.153890] __mmput+0x4a/0x150
[14701.154018] begin_new_exec+0x516/0xcf0
[14701.154167] load_elf_binary+0x69a/0x1720
[14701.154317] ? xfs_iunlock+0x12e/0x180 [xfs]
[14701.154548] ? rcu_read_lock_sched_held+0x12/0x60
[14701.154705] ? lock_acquire+0x176/0x2d0
[14701.154847] ? rcu_read_lock_sched_held+0x12/0x60
[14701.155007] bprm_execve+0x307/0x860
[14701.155148] do_execveat_common+0x1b5/0x230
[14701.155295] __x64_sys_execve+0x39/0x50
[14701.155437] do_syscall_64+0x38/0x90
[14701.155577] entry_SYSCALL_64_after_hwframe+0x44/0xae
[14701.155737] RIP: 0033:0x7fe41859e2fb
[14701.155877] Code: Unable to access opcode bytes at RIP 0x7fe41859e2d1.
[14701.156062] RSP: 002b:00007ffe72f80b98 EFLAGS: 00000246 ORIG_RAX: 000000000000003b
[14701.156303] RAX: ffffffffffffffda RBX: 0000000000000000 RCX: 00007fe41859e2fb
[14701.156495] RDX: 0000563ebc1e21b0 RSI: 0000563ebbf18e10 RDI: 0000563ebbf1c390
[14701.156690] RBP: 0000563ebbf1c390 R08: 0000563ebbf18e10 R09: 0000563e00000001
[14701.156884] R10: 00000000000001b6 R11: 0000000000000246 R12: 00000000ffffffff
[14701.157089] R13: 0000563ebbf18e10 R14: 0000563ebc1e21b0 R15: 0000563ebc1d6910
[14701.157286]