virtme: waiting for virtiofsd to start virtme: use 'microvm' QEMU architecture [ 1.079033][ T1] loop: module loaded [ 1.079070][ T1] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 1.079073][ T1] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 1, name: swapper/0 [ 1.079076][ T1] preempt_count: 1, expected: 0 [ 1.079077][ T1] RCU nest depth: 0, expected: 0 [ 1.079079][ T1] locks held by swapper/0/1: 4, last CPU#3: [ 1.079081][ T1] #0: ffffffffb5599dc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 1.079097][ T1] #1: ffffffffb5599e38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 1.079103][ T1] #2: ffffffffb5489760 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 1.079109][ T1] #3: ffffffffb5489660 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x202/0x4f0 [ 1.079115][ T1] irq event stamp: 332070 [ 1.079117][ T1] hardirqs last enabled at (332069): [] __down_trylock_console_sem+0x86/0xa0 [ 1.079121][ T1] hardirqs last disabled at (332070): [] console_emit_next_record+0x3f8/0x4f0 [ 1.079124][ T1] softirqs last enabled at (332018): [] bdi_register_va+0x491/0x780 [ 1.079129][ T1] softirqs last disabled at (332016): [] bdi_register_va+0x2ef/0x780 [ 1.079133][ T1] Preemption disabled at: [ 1.079134][ T1] [] vprintk_emit+0x31b/0x3e0 [ 1.079140][ T1] CPU: 3 UID: 0 PID: 1 Comm: swapper/0 Not tainted 7.2.0-virtme #1 PREEMPT(full) [ 1.079144][ T1] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 1.079147][ T1] Call Trace: [ 1.079149][ T1] [ 1.079153][ T1] dump_stack_lvl+0x6f/0xa0 [ 1.079160][ T1] ? vprintk_emit+0x31b/0x3e0 [ 1.079164][ T1] __might_resched.cold+0x1fe/0x2c1 [ 1.079170][ T1] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 1.079175][ T1] ? __kmalloc_noprof+0xdb/0x760 [ 1.079182][ T1] __kmalloc_noprof+0x443/0x760 [ 1.079186][ T1] ? alloc_buf.isra.0+0x4b/0x260 [ 1.079195][ T1] ? do_raw_spin_unlock+0x59/0x250 [ 1.079199][ T1] alloc_buf.isra.0+0x4b/0x260 [ 1.079204][ T1] put_chars+0x1e1/0x2f0 [ 1.079207][ T1] ? desc_read+0x271/0x2f0 [ 1.079211][ T1] ? __send_to_port+0x420/0x420 [ 1.079222][ T1] hvc_console_print+0x292/0x780 [ 1.079233][ T1] ? hvc_write+0x3a0/0x3a0 [ 1.079238][ T1] ? lock_acquire+0x13c/0x160 [ 1.079245][ T1] console_emit_next_record+0x252/0x4f0 [ 1.079251][ T1] ? devkmsg_read+0x4e0/0x4e0 [ 1.079259][ T1] ? rcu_is_watching+0x16/0xd0 [ 1.079264][ T1] ? lock_acquire+0x13c/0x160 [ 1.079271][ T1] console_flush_one_record+0x46f/0x710 [ 1.079278][ T1] ? console_emit_next_record+0x4f0/0x4f0 [ 1.079280][ T1] ? __lock_acquire+0x518/0xc20 [ 1.079289][ T1] console_unlock+0xee/0x1f0 [ 1.079294][ T1] ? console_flush_one_record+0x710/0x710 [ 1.079296][ T1] ? rcu_is_watching+0x16/0xd0 [ 1.079300][ T1] ? lock_acquire+0xa0/0x160 [ 1.079307][ T1] ? __down_trylock_console_sem+0x5e/0xa0 [ 1.079309][ T1] ? vprintk_emit+0x320/0x3e0 [ 1.079315][ T1] vprintk_emit+0x37c/0x3e0 [ 1.079320][ T1] ? wake_up_klogd_work_func+0x90/0x90 [ 1.079329][ T1] ? max_loop_setup+0x30/0x30 [ 1.079335][ T1] _printk+0xc7/0x100 [ 1.079340][ T1] ? snapshot_read.cold+0x21/0x21 [ 1.079351][ T1] ? __mutex_unlock_slowpath+0x14d/0x740 [ 1.079357][ T1] loop_init+0x12a/0x130 [ 1.079361][ T1] do_one_initcall+0x124/0x4f0 [ 1.079366][ T1] ? trace_event_raw_event_initcall_level+0x280/0x280 [ 1.079368][ T1] ? parameq+0x110/0x110 [ 1.079377][ T1] ? kernel_init_freeable+0x3f1/0x630 [ 1.079383][ T1] ? rcu_is_watching+0x16/0xd0 [ 1.079388][ T1] kernel_init_freeable+0x596/0x630 [ 1.079393][ T1] ? rest_init+0x280/0x280 [ 1.079402][ T1] kernel_init+0x21/0x150 [ 1.079405][ T1] ? rest_init+0x280/0x280 [ 1.079407][ T1] ret_from_fork+0x474/0x6b0 [ 1.079413][ T1] ? arch_exit_to_user_mode_prepare.isra.0+0x120/0x120 [ 1.079418][ T1] ? __switch_to+0x5a3/0xe00 [ 1.079423][ T1] ? rest_init+0x280/0x280 [ 1.079427][ T1] ret_from_fork_asm+0x11/0x20 [ 1.079441][ T1] [ 1.261171][ T1] PPP generic driver version 2.4.2 [ 1.262760][ T1] NET: Registered PF_PPPOX protocol family [ 1.264095][ T1] i8042: PNP: No PS/2 controller found. [ 1.275847][ T1] rtc_cmos PNP0B00:00: registered as rtc0 [ 1.276457][ T1] rtc_cmos PNP0B00:00: setting system clock to 2026-08-28T00:55:59 UTC (1787878559) [ 1.277976][ T1] rtc_cmos PNP0B00:00: alarms up to one day, 242 bytes nvram [ 1.282611][ T1] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev [ 1.293930][ T1] ipip: IPv4 and MPLS over IPv4 tunneling driver [ 1.298930][ T1] gre: GRE over IPv4 demultiplexer driver [ 1.299215][ T1] ip_gre: GRE over IPv4 tunneling driver [ 1.313142][ T1] Initializing XFRM netlink socket [ 1.314119][ T1] NET: Registered PF_INET6 protocol family [ 1.325410][ T1] Segment Routing with IPv6 [ 1.326065][ T1] In-situ OAM (IOAM) with IPv6 [ 1.326519][ T1] sit: IPv6, IPv4 and MPLS over IPv4 tunneling driver [ 1.338999][ T1] ip6_gre: GRE over IPv6 tunneling driver [ 1.344516][ T1] NET: Registered PF_PACKET protocol family [ 1.345085][ T1] 9pnet: Installing 9P2000 support [ 1.345704][ T1] Key type dns_resolver registered [ 1.346925][ T1] NET: Registered PF_VSOCK protocol family [ 1.352869][ T1] IPI shorthand broadcast: enabled [ 1.402256][ T73] kwatchdog (73) used greatest stack depth: 29688 bytes left [ 1.480462][ T1] sched_clock: Marking stable (1447002023, 32774395)->(1555646697, -75870279) [ 1.484100][ T1] registered taskstats version 1 [ 1.486917][ T1] Loading compiled-in X.509 certificates [ 1.590620][ T1] Demotion targets for Node 0: null [ 1.590986][ T1] kmemleak: Kernel memory leak detector initialized (mem pool available: 11961) [ 1.591291][ T1] page_owner is disabled [ 1.600940][ T1] PM: Magic number: 6:6:912 [ 1.603327][ T1] ALSA device list: [ 1.604403][ T1] No soundcards found. [ 1.605262][ T1] md: Skipping autodetection of RAID arrays. (raid=autodetect will force) [ 1.607913][ T1] VFS: Mounted root (virtiofs filesystem) readonly on device 0:24. [ 1.608758][ T1] devtmpfs: mounted [ 1.609057][ T1] VFS: Pivoted into new rootfs [ 1.636558][ T1] Freeing unused kernel image (initmem) memory: 2576K [ 1.636861][ T1] Write protecting the kernel read-only data: 63488k [ 1.637500][ T1] Freeing unused kernel image (text/rodata gap) memory: 864K [ 1.638114][ T1] Freeing unused kernel image (rodata/data gap) memory: 1116K [ 1.638444][ T1] Run /usr/lib/python3.14/site-packages/virtme/guest/bin/virtme-ng-init as init process [ 1.638753][ T1] with arguments: [ 1.638896][ T1] /usr/lib/python3.14/site-packages/virtme/guest/bin/virtme-ng-init [ 1.639177][ T1] with environment: [ 1.639297][ T1] HOME=/ [ 1.639471][ T1] TERM=dumb [ 1.639592][ T1] virtme_hostname=vmksft-drv-hw-dbg,debug-threads=on [ 1.639844][ T1] nr_open=2147483584 [ 1.639956][ T1] virtme_link_mods=/srv/vmksft/testing/wt-22/.virtme_mods/lib/modules/0.0.0 [ 1.640272][ T1] virtme_rw_overlay0=/etc [ 1.640432][ T1] virtme_rw_overlay1=/lib [ 1.640643][ T1] virtme_rw_overlay2=/home [ 1.640796][ T1] virtme_rw_overlay3=/opt [ 1.641002][ T1] virtme_rw_overlay4=/srv [ 1.641158][ T1] virtme_rw_overlay5=/usr [ 1.641355][ T1] virtme_rw_overlay6=/var [ 1.641506][ T1] virtme_rw_overlay7=/tmp [ 1.641707][ T1] virtme_console=ttyS0 [ 1.641867][ T1] virtme_chdir=srv/vmksft/testing/wt-22 [ 1.660081][ T1] virtme-ng-init: mount devtmpfs -> /dev: EBUSY: Device or resource busy [ 1.661686][ T1] virtme-ng-init: Setting hostname to vmksft-drv-hw-dbg,debug-threads=on... [ 1.673408][ T1] overlayfs: failed to set xattr on upper [ 1.673690][ T1] overlayfs: ...falling back to redirect_dir=nofollow. [ 1.674087][ T1] overlayfs: ...falling back to uuid=null. [ 1.676485][ T1] overlayfs: failed to set xattr on upper [ 1.676717][ T1] overlayfs: ...falling back to redirect_dir=nofollow. [ 1.677133][ T1] overlayfs: ...falling back to uuid=null. [ 1.679116][ T1] overlayfs: failed to set xattr on upper [ 1.679350][ T1] overlayfs: ...falling back to redirect_dir=nofollow. [ 1.679652][ T1] overlayfs: ...falling back to uuid=null. [ 1.681808][ T1] overlayfs: failed to set xattr on upper [ 1.682036][ T1] overlayfs: ...falling back to redirect_dir=nofollow. [ 1.682309][ T1] overlayfs: ...falling back to uuid=null. [ 1.684279][ T1] overlayfs: failed to set xattr on upper [ 1.684513][ T1] overlayfs: ...falling back to redirect_dir=nofollow. [ 1.685146][ T1] overlayfs: ...falling back to uuid=null. [ 1.686944][ T1] overlayfs: failed to set xattr on upper [ 1.687173][ T1] overlayfs: ...falling back to redirect_dir=nofollow. [ 1.687479][ T1] overlayfs: ...falling back to uuid=null. [ 1.689874][ T1] overlayfs: failed to set xattr on upper [ 1.690111][ T1] overlayfs: ...falling back to redirect_dir=nofollow. [ 1.690423][ T1] overlayfs: ...falling back to uuid=null. [ 1.692354][ T1] overlayfs: failed to set xattr on upper [ 1.692590][ T1] overlayfs: ...falling back to redirect_dir=nofollow. [ 1.693411][ T1] overlayfs: ...falling back to uuid=null. [ 1.703949][ T1] virtme-ng-init: running systemd-tmpfiles [ 3.912949][ T75] systemd-tmpfile (75) used greatest stack depth: 24760 bytes left [ 3.912966][ T75] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 3.912968][ T75] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 75, name: systemd-tmpfile [ 3.912970][ T75] preempt_count: 2, expected: 0 [ 3.912971][ T75] RCU nest depth: 0, expected: 0 [ 3.912972][ T75] locks held by systemd-tmpfile/75: 5, last CPU#1: [ 3.912974][ T75] #0: ffffffffb54027b8 (low_water_lock){+.+.}-{3:3}, at: do_exit+0x887/0xdc0 [ 3.912986][ T75] #1: ffffffffb5599dc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 3.912992][ T75] #2: ffffffffb5599e38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 3.912996][ T75] #3: ffffffffb5489760 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 3.912999][ T75] #4: ffffffffb5489660 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x202/0x4f0 [ 3.913003][ T75] irq event stamp: 2379968 [ 3.913004][ T75] hardirqs last enabled at (2379967): [] __down_trylock_console_sem+0x86/0xa0 [ 3.913007][ T75] hardirqs last disabled at (2379968): [] console_emit_next_record+0x3f8/0x4f0 [ 3.913009][ T75] softirqs last enabled at (2379852): [] handle_softirqs+0x67c/0x900 [ 3.913011][ T75] softirqs last disabled at (2379841): [] __irq_exit_rcu+0x145/0x1c0 [ 3.913013][ T75] Preemption disabled at: [ 3.913014][ T75] [<0000000000000000>] 0x0 [ 3.913021][ T75] CPU: 1 UID: 0 PID: 75 Comm: systemd-tmpfile Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 3.913025][ T75] Tainted: [W]=WARN [ 3.913026][ T75] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 3.913027][ T75] Call Trace: [ 3.913029][ T75] [ 3.913031][ T75] dump_stack_lvl+0x6f/0xa0 [ 3.913036][ T75] __might_resched.cold+0x1fe/0x2c1 [ 3.913041][ T75] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 3.913045][ T75] ? __kmalloc_noprof+0xdb/0x760 [ 3.913050][ T75] __kmalloc_noprof+0x443/0x760 [ 3.913053][ T75] ? alloc_buf.isra.0+0x4b/0x260 [ 3.913059][ T75] ? do_raw_spin_unlock+0x59/0x250 [ 3.913062][ T75] alloc_buf.isra.0+0x4b/0x260 [ 3.913066][ T75] put_chars+0x1e1/0x2f0 [ 3.913069][ T75] ? __send_to_port+0x420/0x420 [ 3.913071][ T75] ? printk_get_next_message+0x2fe/0x7d0 [ 3.913075][ T75] ? rcu_read_lock_any_held+0x3c/0x90 [ 3.913078][ T75] ? validate_chain+0x38b/0xc20 [ 3.913082][ T75] hvc_console_print+0x292/0x780 [ 3.913086][ T75] ? __lock_acquire+0x518/0xc20 [ 3.913087][ T75] ? __lock_acquire+0x518/0xc20 [ 3.913091][ T75] ? hvc_write+0x3a0/0x3a0 [ 3.913095][ T75] ? rcu_is_watching+0x16/0xd0 [ 3.913099][ T75] ? lock_acquire+0x13c/0x160 [ 3.913102][ T75] console_emit_next_record+0x252/0x4f0 [ 3.913106][ T75] ? devkmsg_read+0x4e0/0x4e0 [ 3.913111][ T75] ? rcu_is_watching+0x16/0xd0 [ 3.913113][ T75] ? lock_acquire+0x13c/0x160 [ 3.913117][ T75] console_flush_one_record+0x46f/0x710 [ 3.913121][ T75] ? console_emit_next_record+0x4f0/0x4f0 [ 3.913123][ T75] ? __lock_acquire+0x518/0xc20 [ 3.913128][ T75] console_unlock+0xee/0x1f0 [ 3.913130][ T75] ? console_flush_one_record+0x710/0x710 [ 3.913132][ T75] ? rcu_is_watching+0x16/0xd0 [ 3.913135][ T75] ? lock_acquire+0xa0/0x160 [ 3.913138][ T75] ? __down_trylock_console_sem+0x5e/0xa0 [ 3.913140][ T75] ? vprintk_emit+0x320/0x3e0 [ 3.913143][ T75] vprintk_emit+0x37c/0x3e0 [ 3.913147][ T75] ? wake_up_klogd_work_func+0x90/0x90 [ 3.913150][ T75] ? __lock_acquire+0x518/0xc20 [ 3.913154][ T75] _printk+0xc7/0x100 [ 3.913157][ T75] ? snapshot_read.cold+0x21/0x21 [ 3.913160][ T75] ? do_raw_spin_lock+0x131/0x280 [ 3.913163][ T75] ? __rwlock_init+0x150/0x150 [ 3.913166][ T75] ? do_raw_spin_lock+0x131/0x280 [ 3.913169][ T75] do_exit.cold+0x82/0x9c [ 3.913173][ T75] ? exit_notify+0x890/0x890 [ 3.913174][ T75] ? __lock_release.isra.0+0x69/0x1a0 [ 3.913177][ T75] ? rcu_is_watching+0x16/0xd0 [ 3.913181][ T75] do_group_exit+0xb8/0x370 [ 3.913184][ T75] __x64_sys_exit_group+0x3c/0x50 [ 3.913185][ T1] virtme-ng-init: Failed to read '/usr/lib/tmpfiles.d/audit.conf': Permission denied [ 3.913185][ T1] Failed to create directory or subvolume "/var/spool/at/spool": Permission denied [ 3.913185][ T1] Failed to create file /var/spool/at/.SEQ: Permission denied [ 3.913185][ T1] Failed to opendir() '/proc/self/fd/6': Permission denied [ 3.913186][ T75] x64_sys_call+0x1567/0x1570 [ 3.913188][ T75] do_syscall_64+0xff/0x530 [ 3.913192][ T75] ? exc_page_fault+0xee/0x100 [ 3.913195][ T75] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 3.913197][ T75] RIP: 0033:0x7f3e5c4461b8 [ 3.913199][ T75] Code: Unable to access opcode bytes at 0x7f3e5c44618e. [ 3.913201][ T75] RSP: 002b:00007ffccbd81698 EFLAGS: 00000246 ORIG_RAX: 00000000000000e7 [ 3.913203][ T75] RAX: ffffffffffffffda RBX: 00007f3e5c576f88 RCX: 00007f3e5c4461b8 [ 3.913204][ T75] RDX: 00007f3e5bca44c8 RSI: fffffffffffffe90 RDI: 0000000000000049 [ 3.913205][ T75] RBP: 00007ffccbd816f0 R08: 0000000000000000 R09: 0000000000001000 [ 3.913206][ T75] R10: 00007ffccbd814b0 R11: 0000000000000246 R12: 0000000000000001 [ 3.913207][ T75] R13: 0000000000000049 R14: 00007f3e5c575680 R15: 00007f3e5c576fa0 [ 3.913213][ T75] [ 3.934894][ T1] virtme-ng-init: basic initialization done [ 4.006531][ T76] virtme-ng-init: Starting systemd-udevd version 259.8-1.fc44 [ 4.007195][ T76] virtme-ng-init: triggering udev coldplug [ 6.325392][ T76] virtme-ng-init: waiting for udev to settle [ 6.325405][ T76] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 6.325407][ T76] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 76, name: virtme-ng-init [ 6.325409][ T76] preempt_count: 1, expected: 0 [ 6.325410][ T76] RCU nest depth: 0, expected: 0 [ 6.325411][ T76] locks held by virtme-ng-init/76: 4, last CPU#1: [ 6.325413][ T76] #0: ffffffffb5599dc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 6.325426][ T76] #1: ffffffffb5599e38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 6.325430][ T76] #2: ffffffffb5489760 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 6.325434][ T76] #3: ffffffffb5489660 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x202/0x4f0 [ 6.325438][ T76] irq event stamp: 3278 [ 6.325439][ T76] hardirqs last enabled at (3277): [] __down_trylock_console_sem+0x86/0xa0 [ 6.325441][ T76] hardirqs last disabled at (3278): [] console_emit_next_record+0x3f8/0x4f0 [ 6.325443][ T76] softirqs last enabled at (2790): [] fpu_clone+0xd9/0x410 [ 6.325447][ T76] softirqs last disabled at (2788): [] fpu_clone+0x82/0x410 [ 6.325450][ T76] Preemption disabled at: [ 6.325450][ T76] [] vprintk_emit+0x31b/0x3e0 [ 6.325456][ T76] CPU: 1 UID: 0 PID: 76 Comm: virtme-ng-init Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 6.325460][ T76] Tainted: [W]=WARN [ 6.325461][ T76] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 6.325462][ T76] Call Trace: [ 6.325464][ T76] [ 6.325465][ T76] dump_stack_lvl+0x6f/0xa0 [ 6.325470][ T76] ? vprintk_emit+0x31b/0x3e0 [ 6.325473][ T76] __might_resched.cold+0x1fe/0x2c1 [ 6.325477][ T76] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 6.325481][ T76] ? __kmalloc_noprof+0xdb/0x760 [ 6.325486][ T76] __kmalloc_noprof+0x443/0x760 [ 6.325488][ T76] ? alloc_buf.isra.0+0x4b/0x260 [ 6.325495][ T76] ? do_raw_spin_unlock+0x59/0x250 [ 6.325497][ T76] alloc_buf.isra.0+0x4b/0x260 [ 6.325501][ T76] put_chars+0x1e1/0x2f0 [ 6.325504][ T76] ? __send_to_port+0x420/0x420 [ 6.325506][ T76] ? printk_get_next_message+0x2fe/0x7d0 [ 6.325510][ T76] ? rcu_read_lock_any_held+0x3c/0x90 [ 6.325512][ T76] ? validate_chain+0x38b/0xc20 [ 6.325516][ T76] hvc_console_print+0x292/0x780 [ 6.325520][ T76] ? __lock_acquire+0x518/0xc20 [ 6.325522][ T76] ? __lock_acquire+0x518/0xc20 [ 6.325526][ T76] ? hvc_write+0x3a0/0x3a0 [ 6.325529][ T76] ? rcu_is_watching+0x16/0xd0 [ 6.325533][ T76] ? lock_acquire+0x13c/0x160 [ 6.325537][ T76] console_emit_next_record+0x252/0x4f0 [ 6.325540][ T76] ? devkmsg_read+0x4e0/0x4e0 [ 6.325544][ T76] ? rcu_is_watching+0x16/0xd0 [ 6.325547][ T76] ? lock_acquire+0x13c/0x160 [ 6.325550][ T76] console_flush_one_record+0x46f/0x710 [ 6.325554][ T76] ? console_emit_next_record+0x4f0/0x4f0 [ 6.325556][ T76] ? __lock_acquire+0x518/0xc20 [ 6.325561][ T76] console_unlock+0xee/0x1f0 [ 6.325564][ T76] ? console_flush_one_record+0x710/0x710 [ 6.325566][ T76] ? rcu_is_watching+0x16/0xd0 [ 6.325568][ T76] ? lock_acquire+0xa0/0x160 [ 6.325571][ T76] ? __down_trylock_console_sem+0x5e/0xa0 [ 6.325573][ T76] ? vprintk_emit+0x320/0x3e0 [ 6.325576][ T76] vprintk_emit+0x37c/0x3e0 [ 6.325580][ T76] ? wake_up_klogd_work_func+0x90/0x90 [ 6.325583][ T76] ? _copy_from_iter+0x1bb/0x1810 [ 6.325588][ T76] devkmsg_emit.constprop.0+0xbc/0xf1 [ 6.325592][ T76] ? vprintk_emit.cold+0x107/0x107 [ 6.325594][ T76] ? simple_strntoull+0x10f/0x140 [ 6.325598][ T76] ? date_str+0x1e0/0x1e0 [ 6.325601][ T76] ? devkmsg_write+0xd1/0x2c0 [ 6.325605][ T76] devkmsg_write.cold+0x5a/0x8b [ 6.325608][ T76] ? vprintk_default+0x20/0x20 [ 6.325612][ T76] ? vprintk_default+0x20/0x20 [ 6.325615][ T76] new_sync_write+0x33e/0x760 [ 6.325618][ T76] ? kasan_quarantine_put+0x102/0x2b0 [ 6.325621][ T76] ? new_sync_read+0x750/0x750 [ 6.325625][ T76] ? __lock_release.isra.0+0x69/0x1a0 [ 6.325629][ T76] ? __fget_files+0x1e3/0x460 [ 6.325633][ T76] vfs_write+0x6a2/0xbd0 [ 6.325637][ T76] ksys_write+0x116/0x250 [ 6.325640][ T76] ? __ia32_sys_read+0xc0/0xc0 [ 6.325642][ T76] ? rcu_is_watching+0x16/0xd0 [ 6.325646][ T76] do_syscall_64+0xff/0x530 [ 6.325649][ T76] ? exc_page_fault+0xee/0x100 [ 6.325652][ T76] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 6.325654][ T76] RIP: 0033:0x7fef9d69fed2 [ 6.325658][ T76] Code: 08 0f 85 51 ec ff ff 49 89 fb 48 89 f0 48 89 d7 48 89 ce 4c 89 c2 4d 89 ca 4c 8b 44 24 08 4c 8b 4c 24 10 4c 89 5c 24 08 0f 05 66 2e 0f 1f 84 00 00 00 00 00 0f 1f 00 f3 0f 1e fa 55 48 89 e5 [ 6.325660][ T76] RSP: 002b:00007fef9d5e6c68 EFLAGS: 00000246 ORIG_RAX: 0000000000000001 [ 6.325663][ T76] RAX: ffffffffffffffda RBX: 000000000000002e RCX: 00007fef9d69fed2 [ 6.325664][ T76] RDX: 000000000000002e RSI: 00007fef98001090 RDI: 0000000000000003 [ 6.325665][ T76] RBP: 00007fef9d5e6c90 R08: 0000000000000000 R09: 0000000000000000 [ 6.325666][ T76] R10: 0000000000000000 R11: 0000000000000246 R12: 00007fef9d72a0a0 [ 6.325667][ T76] R13: 00007fef9d6283e0 R14: 00007fef98001090 R15: 00007fef9d5e6da8 [ 6.325673][ T76] [ 6.387612][ T89] virtio_net virtio3 enp0s2: renamed from eth1 [ 6.412218][ T86] virtio_net virtio2 enp0s1: renamed from eth0 [ 7.171745][ T94] udevadm (94) used greatest stack depth: 24352 bytes left [ 7.172318][ T76] virtme-ng-init: udev is done [ 7.175355][ T1] virtme-ng-init: initialization done [ 9.106186][ T140] ip (140) used greatest stack depth: 23264 bytes left [ 9.106207][ T140] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 9.106209][ T140] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 140, name: ip [ 9.106211][ T140] preempt_count: 2, expected: 0 [ 9.106211][ T140] RCU nest depth: 0, expected: 0 [ 9.106212][ T140] locks held by ip/140: 5, last CPU#0: [ 9.106214][ T140] #0: ffffffffb54027b8 (low_water_lock){+.+.}-{3:3}, at: do_exit+0x887/0xdc0 [ 9.106227][ T140] #1: ffffffffb5599dc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 9.106232][ T140] #2: ffffffffb5599e38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 9.106237][ T140] #3: ffffffffb5489760 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 9.106241][ T140] #4: ffffffffb5489660 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x202/0x4f0 [ 9.106244][ T140] irq event stamp: 25720 [ 9.106245][ T140] hardirqs last enabled at (25719): [] __down_trylock_console_sem+0x86/0xa0 [ 9.106248][ T140] hardirqs last disabled at (25720): [] console_emit_next_record+0x3f8/0x4f0 [ 9.106250][ T140] softirqs last enabled at (24840): [] handle_softirqs+0x67c/0x900 [ 9.106252][ T140] softirqs last disabled at (24831): [] __irq_exit_rcu+0x145/0x1c0 [ 9.106254][ T140] Preemption disabled at: [ 9.106255][ T140] [<0000000000000000>] 0x0 [ 9.106262][ T140] CPU: 0 UID: 0 PID: 140 Comm: ip Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 9.106265][ T140] Tainted: [W]=WARN [ 9.106266][ T140] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 9.106268][ T140] Call Trace: [ 9.106269][ T140] [ 9.106271][ T140] dump_stack_lvl+0x6f/0xa0 [ 9.106277][ T140] __might_resched.cold+0x1fe/0x2c1 [ 9.106282][ T140] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 9.106286][ T140] ? __kmalloc_noprof+0xdb/0x760 [ 9.106292][ T140] __kmalloc_noprof+0x443/0x760 [ 9.106294][ T140] ? alloc_buf.isra.0+0x4b/0x260 [ 9.106301][ T140] ? do_raw_spin_unlock+0x59/0x250 [ 9.106304][ T140] alloc_buf.isra.0+0x4b/0x260 [ 9.106308][ T140] put_chars+0x1e1/0x2f0 [ 9.106311][ T140] ? __send_to_port+0x420/0x420 [ 9.106313][ T140] ? printk_get_next_message+0x2fe/0x7d0 [ 9.106317][ T140] ? rcu_read_lock_any_held+0x3c/0x90 [ 9.106320][ T140] ? validate_chain+0x38b/0xc20 [ 9.106324][ T140] hvc_console_print+0x292/0x780 [ 9.106328][ T140] ? __lock_acquire+0x518/0xc20 [ 9.106330][ T140] ? __lock_acquire+0x518/0xc20 [ 9.106337][ T140] ? hvc_write+0x3a0/0x3a0 [ 9.106341][ T140] ? rcu_is_watching+0x16/0xd0 [ 9.106344][ T140] ? lock_acquire+0x13c/0x160 [ 9.106348][ T140] console_emit_next_record+0x252/0x4f0 [ 9.106352][ T140] ? devkmsg_read+0x4e0/0x4e0 [ 9.106357][ T140] ? rcu_is_watching+0x16/0xd0 [ 9.106359][ T140] ? lock_acquire+0x13c/0x160 [ 9.106363][ T140] console_flush_one_record+0x46f/0x710 [ 9.106367][ T140] ? console_emit_next_record+0x4f0/0x4f0 [ 9.106369][ T140] ? __lock_acquire+0x518/0xc20 [ 9.106374][ T140] console_unlock+0xee/0x1f0 [ 9.106377][ T140] ? console_flush_one_record+0x710/0x710 [ 9.106379][ T140] ? rcu_is_watching+0x16/0xd0 [ 9.106381][ T140] ? lock_acquire+0xa0/0x160 [ 9.106384][ T140] ? __down_trylock_console_sem+0x5e/0xa0 [ 9.106386][ T140] ? vprintk_emit+0x320/0x3e0 [ 9.106390][ T140] vprintk_emit+0x37c/0x3e0 [ 9.106393][ T140] ? wake_up_klogd_work_func+0x90/0x90 [ 9.106397][ T140] ? __lock_acquire+0x518/0xc20 [ 9.106400][ T140] _printk+0xc7/0x100 [ 9.106404][ T140] ? snapshot_read.cold+0x21/0x21 [ 9.106407][ T140] ? do_raw_spin_lock+0x131/0x280 [ 9.106409][ T140] ? __rwlock_init+0x150/0x150 [ 9.106413][ T140] ? do_raw_spin_lock+0x131/0x280 [ 9.106416][ T140] do_exit.cold+0x82/0x9c [ 9.106420][ T140] ? exit_notify+0x890/0x890 [ 9.106421][ T140] ? __lock_release.isra.0+0x69/0x1a0 [ 9.106424][ T140] ? rcu_is_watching+0x16/0xd0 [ 9.106428][ T140] do_group_exit+0xb8/0x370 [ 9.106431][ T140] __x64_sys_exit_group+0x3c/0x50 [ 9.106432][ T140] x64_sys_call+0x1567/0x1570 [ 9.106435][ T140] do_syscall_64+0xff/0x530 [ 9.106438][ T140] ? exc_page_fault+0xee/0x100 [ 9.106442][ T140] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 9.106444][ T140] RIP: 0033:0x7f39608da1b8 [ 9.106447][ T140] Code: Unable to access opcode bytes at 0x7f39608da18e. [ 9.106448][ T140] RSP: 002b:00007ffc16dff0e8 EFLAGS: 00000246 ORIG_RAX: 00000000000000e7 [ 9.106450][ T140] RAX: ffffffffffffffda RBX: 00007f3960a0af88 RCX: 00007f39608da1b8 [ 9.106452][ T140] RDX: 00007f3960623b48 RSI: fffffffffffffeb8 RDI: 0000000000000000 [ 9.106453][ T140] RBP: 00007ffc16dff140 R08: 0000000000000000 R09: 0000000000008010 [ 9.106454][ T140] R10: 00007ffc16dfef00 R11: 0000000000000246 R12: 0000000000000001 [ 9.106454][ T140] R13: 0000000000000000 R14: 00007f3960a09680 R15: 00007f3960a0afa0 [ 9.106461][ T140]