virtme: waiting for virtiofsd to start virtme: use 'microvm' QEMU architecture [ 1.301835][ T1] PPP generic driver version 2.4.2 [ 1.301904][ T1] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 1.301908][ T1] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 1, name: swapper/0 [ 1.301912][ T1] preempt_count: 1, expected: 0 [ 1.301913][ T1] RCU nest depth: 0, expected: 0 [ 1.301916][ T1] locks held by swapper/0/1: 4, last CPU#3: [ 1.301919][ T1] #0: ffffffff92799dc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 1.301938][ T1] #1: ffffffff92799e38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 1.301947][ T1] #2: ffffffff92689760 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 1.301955][ T1] #3: ffffffff92689660 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x202/0x4f0 [ 1.301964][ T1] irq event stamp: 355742 [ 1.301965][ T1] hardirqs last enabled at (355741): [] __down_trylock_console_sem+0x86/0xa0 [ 1.301970][ T1] hardirqs last disabled at (355742): [] console_emit_next_record+0x3f8/0x4f0 [ 1.301974][ T1] softirqs last enabled at (355528): [] handle_softirqs+0x67c/0x900 [ 1.301979][ T1] softirqs last disabled at (355519): [] __irq_exit_rcu+0x145/0x1c0 [ 1.301983][ T1] Preemption disabled at: [ 1.301984][ T1] [] vprintk_emit+0x31b/0x3e0 [ 1.301992][ T1] CPU: 3 UID: 0 PID: 1 Comm: swapper/0 Not tainted 7.2.0-virtme #1 PREEMPT(full) [ 1.301996][ T1] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 1.301998][ T1] Call Trace: [ 1.302001][ T1] [ 1.302006][ T1] dump_stack_lvl+0x6f/0xa0 [ 1.302015][ T1] ? vprintk_emit+0x31b/0x3e0 [ 1.302019][ T1] __might_resched.cold+0x1fe/0x2c1 [ 1.302026][ T1] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 1.302036][ T1] ? __kmalloc_noprof+0xdb/0x760 [ 1.302036][ T1] __kmalloc_noprof+0x443/0x760 [ 1.302036][ T1] ? alloc_buf.isra.0+0x4b/0x260 [ 1.302036][ T1] ? do_raw_spin_unlock+0x59/0x250 [ 1.302036][ T1] alloc_buf.isra.0+0x4b/0x260 [ 1.302036][ T1] put_chars+0x1e1/0x2f0 [ 1.302036][ T1] ? printk_get_next_message+0x2fe/0x7d0 [ 1.302036][ T1] ? __send_to_port+0x420/0x420 [ 1.302036][ T1] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 1.302036][ T1] ? validate_chain+0x38b/0xc20 [ 1.302036][ T1] hvc_console_print+0x292/0x780 [ 1.302036][ T1] ? hvc_write+0x3a0/0x3a0 [ 1.302036][ T1] ? rcu_is_watching+0x16/0xd0 [ 1.302036][ T1] ? lock_acquire+0x13c/0x160 [ 1.302036][ T1] console_emit_next_record+0x252/0x4f0 [ 1.302036][ T1] ? devkmsg_read+0x4e0/0x4e0 [ 1.302036][ T1] ? rcu_is_watching+0x16/0xd0 [ 1.302036][ T1] ? lock_acquire+0x13c/0x160 [ 1.302036][ T1] console_flush_one_record+0x46f/0x710 [ 1.302036][ T1] ? console_emit_next_record+0x4f0/0x4f0 [ 1.302036][ T1] ? __lock_acquire+0x518/0xc20 [ 1.302036][ T1] console_unlock+0xee/0x1f0 [ 1.302036][ T1] ? console_flush_one_record+0x710/0x710 [ 1.302036][ T1] ? rcu_is_watching+0x16/0xd0 [ 1.302036][ T1] ? lock_acquire+0xa0/0x160 [ 1.302036][ T1] ? __down_trylock_console_sem+0x5e/0xa0 [ 1.302036][ T1] ? vprintk_emit+0x320/0x3e0 [ 1.302036][ T1] vprintk_emit+0x37c/0x3e0 [ 1.302036][ T1] ? wake_up_klogd_work_func+0x90/0x90 [ 1.302036][ T1] ? vxlan_init_module+0x80/0x80 [ 1.302036][ T1] _printk+0xc7/0x100 [ 1.302036][ T1] ? snapshot_read.cold+0x21/0x21 [ 1.302036][ T1] ? add_chain_block+0x1e0/0x5b0 [ 1.302036][ T1] ? _raw_spin_unlock_irqrestore+0x40/0x80 [ 1.302036][ T1] ? add_device_randomness+0xbb/0x100 [ 1.302036][ T1] ? random_write_iter+0x20/0x20 [ 1.302036][ T1] ? phy_module_init+0x20/0x20 [ 1.302036][ T1] ? do_one_initcall+0x113/0x4f0 [ 1.302036][ T1] ppp_init+0x16/0x100 [ 1.302036][ T1] do_one_initcall+0x124/0x4f0 [ 1.302036][ T1] ? trace_event_raw_event_initcall_level+0x280/0x280 [ 1.302036][ T1] ? parameq+0x110/0x110 [ 1.302036][ T1] ? kernel_init_freeable+0x3f1/0x630 [ 1.302036][ T1] ? rcu_is_watching+0x16/0xd0 [ 1.302036][ T1] kernel_init_freeable+0x596/0x630 [ 1.302036][ T1] ? rest_init+0x280/0x280 [ 1.302036][ T1] kernel_init+0x21/0x150 [ 1.302036][ T1] ? rest_init+0x280/0x280 [ 1.302036][ T1] ret_from_fork+0x474/0x6b0 [ 1.302036][ T1] ? arch_exit_to_user_mode_prepare.isra.0+0x120/0x120 [ 1.302036][ T1] ? __switch_to+0x5a3/0xe00 [ 1.302036][ T1] ? rest_init+0x280/0x280 [ 1.302036][ T1] ret_from_fork_asm+0x11/0x20 [ 1.302036][ T1] [ 1.321185][ T1] NET: Registered PF_PPPOX protocol family [ 1.322268][ T1] i8042: PNP: No PS/2 controller found. [ 1.331615][ T1] rtc_cmos PNP0B00:00: registered as rtc0 [ 1.332089][ T1] rtc_cmos PNP0B00:00: setting system clock to 2026-08-28T00:41:50 UTC (1787877710) [ 1.333100][ T1] rtc_cmos PNP0B00:00: alarms up to one day, 242 bytes nvram [ 1.336151][ T1] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev [ 1.342903][ T1] ipip: IPv4 and MPLS over IPv4 tunneling driver [ 1.346385][ T1] gre: GRE over IPv4 demultiplexer driver [ 1.346591][ T1] ip_gre: GRE over IPv4 tunneling driver [ 1.354605][ T1] Initializing XFRM netlink socket [ 1.355227][ T1] NET: Registered PF_INET6 protocol family [ 1.363366][ T1] Segment Routing with IPv6 [ 1.363854][ T1] In-situ OAM (IOAM) with IPv6 [ 1.364209][ T1] sit: IPv6, IPv4 and MPLS over IPv4 tunneling driver [ 1.371126][ T1] ip6_gre: GRE over IPv6 tunneling driver [ 1.374250][ T1] NET: Registered PF_PACKET protocol family [ 1.374531][ T1] 9pnet: Installing 9P2000 support [ 1.374910][ T1] Key type dns_resolver registered [ 1.375753][ T1] NET: Registered PF_VSOCK protocol family [ 1.380652][ T1] IPI shorthand broadcast: enabled [ 1.449069][ T74] kwatchdog (74) used greatest stack depth: 29688 bytes left [ 1.499606][ T1] sched_clock: Marking stable (1462004936, 37034579)->(1579489655, -80450140) [ 1.503308][ T1] registered taskstats version 1 [ 1.505973][ T1] Loading compiled-in X.509 certificates [ 1.613467][ T1] Demotion targets for Node 0: null [ 1.613810][ T1] kmemleak: Kernel memory leak detector initialized (mem pool available: 11963) [ 1.614152][ T1] page_owner is disabled [ 1.623647][ T1] PM: Magic number: 6:247:659 [ 1.626197][ T1] ALSA device list: [ 1.626362][ T1] No soundcards found. [ 1.627025][ T1] md: Skipping autodetection of RAID arrays. (raid=autodetect will force) [ 1.631008][ T1] VFS: Mounted root (virtiofs filesystem) readonly on device 0:24. [ 1.631862][ T1] devtmpfs: mounted [ 1.632157][ T1] VFS: Pivoted into new rootfs [ 1.661717][ T1] Freeing unused kernel image (initmem) memory: 2576K [ 1.662151][ T1] Write protecting the kernel read-only data: 63488k [ 1.663273][ T1] Freeing unused kernel image (text/rodata gap) memory: 864K [ 1.664174][ T1] Freeing unused kernel image (rodata/data gap) memory: 1116K [ 1.664625][ T1] Run /usr/lib/python3.14/site-packages/virtme/guest/bin/virtme-ng-init as init process [ 1.665054][ T1] with arguments: [ 1.665253][ T1] /usr/lib/python3.14/site-packages/virtme/guest/bin/virtme-ng-init [ 1.665640][ T1] with environment: [ 1.665833][ T1] HOME=/ [ 1.666027][ T1] TERM=dumb [ 1.666234][ T1] virtme_hostname=vmksft-drv-hw-dbg,debug-threads=on [ 1.666544][ T1] nr_open=2147483584 [ 1.666726][ T1] virtme_link_mods=/srv/vmksft/testing/wt-22/.virtme_mods/lib/modules/0.0.0 [ 1.667179][ T1] virtme_rw_overlay0=/etc [ 1.667439][ T1] virtme_rw_overlay1=/lib [ 1.667684][ T1] virtme_rw_overlay2=/home [ 1.667932][ T1] virtme_rw_overlay3=/opt [ 1.668177][ T1] virtme_rw_overlay4=/srv [ 1.668428][ T1] virtme_rw_overlay5=/usr [ 1.668683][ T1] virtme_rw_overlay6=/var [ 1.668925][ T1] virtme_rw_overlay7=/tmp [ 1.669174][ T1] virtme_console=ttyS0 [ 1.669432][ T1] virtme_chdir=srv/vmksft/testing/wt-22 [ 1.694091][ T1] virtme-ng-init: mount devtmpfs -> /dev: EBUSY: Device or resource busy [ 1.696774][ T1] virtme-ng-init: Setting hostname to vmksft-drv-hw-dbg,debug-threads=on... [ 1.710448][ T1] overlayfs: failed to set xattr on upper [ 1.710842][ T1] overlayfs: ...falling back to redirect_dir=nofollow. [ 1.711308][ T1] overlayfs: ...falling back to uuid=null. [ 1.715115][ T1] overlayfs: failed to set xattr on upper [ 1.715447][ T1] overlayfs: ...falling back to redirect_dir=nofollow. [ 1.715859][ T1] overlayfs: ...falling back to uuid=null. [ 1.718949][ T1] overlayfs: failed to set xattr on upper [ 1.719334][ T1] overlayfs: ...falling back to redirect_dir=nofollow. [ 1.719740][ T1] overlayfs: ...falling back to uuid=null. [ 1.722738][ T1] overlayfs: failed to set xattr on upper [ 1.723081][ T1] overlayfs: ...falling back to redirect_dir=nofollow. [ 1.723484][ T1] overlayfs: ...falling back to uuid=null. [ 1.726581][ T1] overlayfs: failed to set xattr on upper [ 1.726942][ T1] overlayfs: ...falling back to redirect_dir=nofollow. [ 1.728330][ T1] overlayfs: ...falling back to uuid=null. [ 1.731092][ T1] overlayfs: failed to set xattr on upper [ 1.731417][ T1] overlayfs: ...falling back to redirect_dir=nofollow. [ 1.731820][ T1] overlayfs: ...falling back to uuid=null. [ 1.735263][ T1] overlayfs: failed to set xattr on upper [ 1.735592][ T1] overlayfs: ...falling back to redirect_dir=nofollow. [ 1.735975][ T1] overlayfs: ...falling back to uuid=null. [ 1.739670][ T1] overlayfs: failed to set xattr on upper [ 1.739980][ T1] overlayfs: ...falling back to redirect_dir=nofollow. [ 1.740971][ T1] overlayfs: ...falling back to uuid=null. [ 1.757863][ T1] virtme-ng-init: running systemd-tmpfiles [ 4.574191][ T75] systemd-tmpfile (75) used greatest stack depth: 24856 bytes left [ 4.574208][ T75] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 4.574210][ T75] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 75, name: systemd-tmpfile [ 4.574212][ T75] preempt_count: 2, expected: 0 [ 4.574213][ T75] RCU nest depth: 0, expected: 0 [ 4.574214][ T75] locks held by systemd-tmpfile/75: 5, last CPU#1: [ 4.574216][ T75] #0: ffffffff926027b8 (low_water_lock){+.+.}-{3:3}, at: do_exit+0x887/0xdc0 [ 4.574227][ T75] #1: ffffffff92799dc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 4.574232][ T75] #2: ffffffff92799e38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 4.574236][ T75] #3: ffffffff92689760 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 4.574240][ T75] #4: ffffffff92689660 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x202/0x4f0 [ 4.574244][ T75] irq event stamp: 2498512 [ 4.574245][ T75] hardirqs last enabled at (2498511): [] __down_trylock_console_sem+0x86/0xa0 [ 4.574248][ T75] hardirqs last disabled at (2498512): [] console_emit_next_record+0x3f8/0x4f0 [ 4.574250][ T75] softirqs last enabled at (2498396): [] handle_softirqs+0x67c/0x900 [ 4.574252][ T75] softirqs last disabled at (2498389): [] __irq_exit_rcu+0x145/0x1c0 [ 4.574254][ T75] Preemption disabled at: [ 4.574255][ T75] [<0000000000000000>] 0x0 [ 4.574262][ T75] CPU: 1 UID: 0 PID: 75 Comm: systemd-tmpfile Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 4.574266][ T75] Tainted: [W]=WARN [ 4.574266][ T75] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 4.574268][ T75] Call Trace: [ 4.574270][ T75] [ 4.574271][ T75] dump_stack_lvl+0x6f/0xa0 [ 4.574278][ T75] __might_resched.cold+0x1fe/0x2c1 [ 4.574283][ T75] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 4.574287][ T75] ? __kmalloc_noprof+0xdb/0x760 [ 4.574292][ T75] __kmalloc_noprof+0x443/0x760 [ 4.574294][ T75] ? alloc_buf.isra.0+0x4b/0x260 [ 4.574301][ T75] ? do_raw_spin_unlock+0x59/0x250 [ 4.574304][ T75] alloc_buf.isra.0+0x4b/0x260 [ 4.574307][ T75] put_chars+0x1e1/0x2f0 [ 4.574311][ T75] ? __send_to_port+0x420/0x420 [ 4.574313][ T75] ? printk_get_next_message+0x2fe/0x7d0 [ 4.574316][ T75] ? rcu_read_lock_any_held+0x3c/0x90 [ 4.574319][ T75] ? validate_chain+0x38b/0xc20 [ 4.574323][ T75] hvc_console_print+0x292/0x780 [ 4.574327][ T75] ? __lock_acquire+0x518/0xc20 [ 4.574328][ T75] ? __lock_acquire+0x518/0xc20 [ 4.574332][ T75] ? hvc_write+0x3a0/0x3a0 [ 4.574336][ T75] ? rcu_is_watching+0x16/0xd0 [ 4.574339][ T75] ? lock_acquire+0x13c/0x160 [ 4.574343][ T75] console_emit_next_record+0x252/0x4f0 [ 4.574347][ T75] ? devkmsg_read+0x4e0/0x4e0 [ 4.574351][ T75] ? rcu_is_watching+0x16/0xd0 [ 4.574353][ T75] ? lock_acquire+0x13c/0x160 [ 4.574357][ T75] console_flush_one_record+0x46f/0x710 [ 4.574361][ T75] ? console_emit_next_record+0x4f0/0x4f0 [ 4.574363][ T75] ? __lock_acquire+0x518/0xc20 [ 4.574368][ T75] console_unlock+0xee/0x1f0 [ 4.574371][ T75] ? console_flush_one_record+0x710/0x710 [ 4.574372][ T75] ? rcu_is_watching+0x16/0xd0 [ 4.574375][ T75] ? lock_acquire+0xa0/0x160 [ 4.574378][ T75] ? __down_trylock_console_sem+0x5e/0xa0 [ 4.574380][ T75] ? vprintk_emit+0x320/0x3e0 [ 4.574383][ T75] vprintk_emit+0x37c/0x3e0 [ 4.574387][ T75] ? wake_up_klogd_work_func+0x90/0x90 [ 4.574390][ T75] ? __lock_acquire+0x518/0xc20 [ 4.574394][ T75] _printk+0xc7/0x100 [ 4.574397][ T75] ? snapshot_read.cold+0x21/0x21 [ 4.574400][ T75] ? do_raw_spin_lock+0x131/0x280 [ 4.574402][ T75] ? __rwlock_init+0x150/0x150 [ 4.574406][ T75] ? do_raw_spin_lock+0x131/0x280 [ 4.574408][ T75] do_exit.cold+0x82/0x9c [ 4.574410][ T1] virtme-ng-init: Failed to read '/usr/lib/tmpfiles.d/audit.conf': Permission denied [ 4.574410][ T1] Failed to create directory or subvolume "/var/spool/at/spool": Permission denied [ 4.574410][ T1] Failed to create file /var/spool/at/.SEQ: Permission denied [ 4.574410][ T1] Failed to opendir() '/proc/self/fd/6': Permission denied [ 4.574412][ T75] ? exit_notify+0x890/0x890 [ 4.574413][ T75] ? __lock_release.isra.0+0x69/0x1a0 [ 4.574416][ T75] ? rcu_is_watching+0x16/0xd0 [ 4.574420][ T75] do_group_exit+0xb8/0x370 [ 4.574422][ T75] __x64_sys_exit_group+0x3c/0x50 [ 4.574424][ T75] x64_sys_call+0x1567/0x1570 [ 4.574426][ T75] do_syscall_64+0xff/0x530 [ 4.574429][ T75] ? exc_page_fault+0xee/0x100 [ 4.574433][ T75] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 4.574435][ T75] RIP: 0033:0x7f179434b1b8 [ 4.574445][ T75] Code: Unable to access opcode bytes at 0x7f179434b18e. [ 4.574447][ T75] RSP: 002b:00007ffe368dfbc8 EFLAGS: 00000246 ORIG_RAX: 00000000000000e7 [ 4.574449][ T75] RAX: ffffffffffffffda RBX: 00007f179447bf88 RCX: 00007f179434b1b8 [ 4.574450][ T75] RDX: 00007f1793ba94c8 RSI: fffffffffffffe90 RDI: 0000000000000049 [ 4.574451][ T75] RBP: 00007ffe368dfc20 R08: 0000000000000000 R09: 0000000000001000 [ 4.574452][ T75] R10: 00007ffe368df9e0 R11: 0000000000000246 R12: 0000000000000001 [ 4.574453][ T75] R13: 0000000000000049 R14: 00007f179447a680 R15: 00007f179447bfa0 [ 4.574459][ T75] [ 4.592638][ T1] virtme-ng-init: basic initialization done [ 4.662726][ T80] ip (80) used greatest stack depth: 24400 bytes left [ 4.665287][ T76] virtme-ng-init: Starting systemd-udevd version 259.8-1.fc44 [ 4.665761][ T76] virtme-ng-init: triggering udev coldplug [ 7.049696][ T76] virtme-ng-init: waiting for udev to settle [ 7.049711][ T76] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 7.049714][ T76] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 76, name: virtme-ng-init [ 7.049715][ T76] preempt_count: 1, expected: 0 [ 7.049716][ T76] RCU nest depth: 0, expected: 0 [ 7.049717][ T76] locks held by virtme-ng-init/76: 4, last CPU#0: [ 7.049719][ T76] #0: ffffffff92799dc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 7.049732][ T76] #1: ffffffff92799e38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 7.049736][ T76] #2: ffffffff92689760 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 7.049741][ T76] #3: ffffffff92689660 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x202/0x4f0 [ 7.049745][ T76] irq event stamp: 2870 [ 7.049746][ T76] hardirqs last enabled at (2869): [] __down_trylock_console_sem+0x86/0xa0 [ 7.049748][ T76] hardirqs last disabled at (2870): [] console_emit_next_record+0x3f8/0x4f0 [ 7.049750][ T76] softirqs last enabled at (2436): [] handle_softirqs+0x67c/0x900 [ 7.049753][ T76] softirqs last disabled at (2427): [] __irq_exit_rcu+0x145/0x1c0 [ 7.049756][ T76] Preemption disabled at: [ 7.049756][ T76] [] vprintk_emit+0x31b/0x3e0 [ 7.049763][ T76] CPU: 0 UID: 0 PID: 76 Comm: virtme-ng-init Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 7.049766][ T76] Tainted: [W]=WARN [ 7.049767][ T76] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 7.049769][ T76] Call Trace: [ 7.049771][ T76] [ 7.049772][ T76] dump_stack_lvl+0x6f/0xa0 [ 7.049778][ T76] ? vprintk_emit+0x31b/0x3e0 [ 7.049780][ T76] __might_resched.cold+0x1fe/0x2c1 [ 7.049785][ T76] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 7.049789][ T76] ? __kmalloc_noprof+0xdb/0x760 [ 7.049795][ T76] __kmalloc_noprof+0x443/0x760 [ 7.049797][ T76] ? alloc_buf.isra.0+0x4b/0x260 [ 7.049804][ T76] ? do_raw_spin_unlock+0x59/0x250 [ 7.049807][ T76] alloc_buf.isra.0+0x4b/0x260 [ 7.049811][ T76] put_chars+0x1e1/0x2f0 [ 7.049814][ T76] ? __send_to_port+0x420/0x420 [ 7.049816][ T76] ? printk_get_next_message+0x2fe/0x7d0 [ 7.049820][ T76] ? rcu_read_lock_any_held+0x3c/0x90 [ 7.049822][ T76] ? validate_chain+0x38b/0xc20 [ 7.049826][ T76] hvc_console_print+0x292/0x780 [ 7.049830][ T76] ? __lock_acquire+0x518/0xc20 [ 7.049832][ T76] ? __lock_acquire+0x518/0xc20 [ 7.049836][ T76] ? hvc_write+0x3a0/0x3a0 [ 7.049840][ T76] ? rcu_is_watching+0x16/0xd0 [ 7.049843][ T76] ? lock_acquire+0x13c/0x160 [ 7.049847][ T76] console_emit_next_record+0x252/0x4f0 [ 7.049851][ T76] ? devkmsg_read+0x4e0/0x4e0 [ 7.049856][ T76] ? rcu_is_watching+0x16/0xd0 [ 7.049858][ T76] ? lock_acquire+0x13c/0x160 [ 7.049862][ T76] console_flush_one_record+0x46f/0x710 [ 7.049866][ T76] ? console_emit_next_record+0x4f0/0x4f0 [ 7.049868][ T76] ? __lock_acquire+0x518/0xc20 [ 7.049873][ T76] console_unlock+0xee/0x1f0 [ 7.049876][ T76] ? console_flush_one_record+0x710/0x710 [ 7.049878][ T76] ? rcu_is_watching+0x16/0xd0 [ 7.049880][ T76] ? lock_acquire+0xa0/0x160 [ 7.049884][ T76] ? __down_trylock_console_sem+0x5e/0xa0 [ 7.049886][ T76] ? vprintk_emit+0x320/0x3e0 [ 7.049889][ T76] vprintk_emit+0x37c/0x3e0 [ 7.049893][ T76] ? wake_up_klogd_work_func+0x90/0x90 [ 7.049896][ T76] ? _copy_from_iter+0x1bb/0x1810 [ 7.049902][ T76] devkmsg_emit.constprop.0+0xbc/0xf1 [ 7.049906][ T76] ? vprintk_emit.cold+0x107/0x107 [ 7.049907][ T76] ? simple_strntoull+0x10f/0x140 [ 7.049912][ T76] ? date_str+0x1e0/0x1e0 [ 7.049915][ T76] ? devkmsg_write+0xd1/0x2c0 [ 7.049920][ T76] devkmsg_write.cold+0x5a/0x8b [ 7.049923][ T76] ? vprintk_default+0x20/0x20 [ 7.049927][ T76] ? vprintk_default+0x20/0x20 [ 7.049930][ T76] new_sync_write+0x33e/0x760 [ 7.049932][ T76] ? kasan_quarantine_put+0x102/0x2b0 [ 7.049936][ T76] ? new_sync_read+0x750/0x750 [ 7.049940][ T76] ? __lock_release.isra.0+0x69/0x1a0 [ 7.049945][ T76] ? __fget_files+0x1e3/0x460 [ 7.049948][ T76] vfs_write+0x6a2/0xbd0 [ 7.049953][ T76] ksys_write+0x116/0x250 [ 7.049956][ T76] ? __ia32_sys_read+0xc0/0xc0 [ 7.049958][ T76] ? rcu_is_watching+0x16/0xd0 [ 7.049962][ T76] do_syscall_64+0xff/0x530 [ 7.049965][ T76] ? exc_page_fault+0xee/0x100 [ 7.049968][ T76] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 7.049971][ T76] RIP: 0033:0x7fed8b622ed2 [ 7.049974][ 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 [ 7.049976][ T76] RSP: 002b:00007fed8b569c68 EFLAGS: 00000246 ORIG_RAX: 0000000000000001 [ 7.049979][ T76] RAX: ffffffffffffffda RBX: 000000000000002e RCX: 00007fed8b622ed2 [ 7.049980][ T76] RDX: 000000000000002e RSI: 00007fed84001090 RDI: 0000000000000003 [ 7.049981][ T76] RBP: 00007fed8b569c90 R08: 0000000000000000 R09: 0000000000000000 [ 7.049982][ T76] R10: 0000000000000000 R11: 0000000000000246 R12: 00007fed8b6ad0a0 [ 7.049983][ T76] R13: 00007fed8b5ab3e0 R14: 00007fed84001090 R15: 00007fed8b569da8 [ 7.049990][ T76] [ 7.191169][ T83] virtio_net virtio2 enp0s1: renamed from eth0 [ 7.219666][ T88] virtio_net virtio3 enp0s2: renamed from eth1 [ 7.981745][ T76] virtme-ng-init: udev is done [ 7.984838][ T1] virtme-ng-init: initialization done [ 9.823902][ T137] ip (137) used greatest stack depth: 24312 bytes left [ 9.823921][ T137] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 9.823923][ T137] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 137, name: ip [ 9.823925][ T137] preempt_count: 2, expected: 0 [ 9.823926][ T137] RCU nest depth: 0, expected: 0 [ 9.823927][ T137] locks held by ip/137: 5, last CPU#0: [ 9.823929][ T137] #0: ffffffff926027b8 (low_water_lock){+.+.}-{3:3}, at: do_exit+0x887/0xdc0 [ 9.823940][ T137] #1: ffffffff92799dc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 9.823946][ T137] #2: ffffffff92799e38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 9.823950][ T137] #3: ffffffff92689760 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 9.823954][ T137] #4: ffffffff92689660 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x202/0x4f0 [ 9.823958][ T137] irq event stamp: 26950 [ 9.823959][ T137] hardirqs last enabled at (26949): [] __down_trylock_console_sem+0x86/0xa0 [ 9.823962][ T137] hardirqs last disabled at (26950): [] console_emit_next_record+0x3f8/0x4f0 [ 9.823964][ T137] softirqs last enabled at (26016): [] handle_softirqs+0x67c/0x900 [ 9.823966][ T137] softirqs last disabled at (26011): [] __irq_exit_rcu+0x145/0x1c0 [ 9.823969][ T137] Preemption disabled at: [ 9.823969][ T137] [<0000000000000000>] 0x0 [ 9.823976][ T137] CPU: 0 UID: 0 PID: 137 Comm: ip Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 9.823980][ T137] Tainted: [W]=WARN [ 9.823981][ T137] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 9.823983][ T137] Call Trace: [ 9.823984][ T137] [ 9.823986][ T137] dump_stack_lvl+0x6f/0xa0 [ 9.823992][ T137] __might_resched.cold+0x1fe/0x2c1 [ 9.823997][ T137] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 9.824001][ T137] ? __kmalloc_noprof+0xdb/0x760 [ 9.824006][ T137] __kmalloc_noprof+0x443/0x760 [ 9.824009][ T137] ? alloc_buf.isra.0+0x4b/0x260 [ 9.824015][ T137] ? do_raw_spin_unlock+0x59/0x250 [ 9.824018][ T137] alloc_buf.isra.0+0x4b/0x260 [ 9.824022][ T137] put_chars+0x1e1/0x2f0 [ 9.824025][ T137] ? __send_to_port+0x420/0x420 [ 9.824027][ T137] ? printk_get_next_message+0x2fe/0x7d0 [ 9.824031][ T137] ? rcu_read_lock_any_held+0x3c/0x90 [ 9.824036][ T137] ? validate_chain+0x38b/0xc20 [ 9.824040][ T137] hvc_console_print+0x292/0x780 [ 9.824044][ T137] ? __lock_acquire+0x518/0xc20 [ 9.824046][ T137] ? __lock_acquire+0x518/0xc20 [ 9.824050][ T137] ? hvc_write+0x3a0/0x3a0 [ 9.824054][ T137] ? rcu_is_watching+0x16/0xd0 [ 9.824057][ T137] ? lock_acquire+0x13c/0x160 [ 9.824061][ T137] console_emit_next_record+0x252/0x4f0 [ 9.824065][ T137] ? devkmsg_read+0x4e0/0x4e0 [ 9.824069][ T137] ? rcu_is_watching+0x16/0xd0 [ 9.824072][ T137] ? lock_acquire+0x13c/0x160 [ 9.824076][ T137] console_flush_one_record+0x46f/0x710 [ 9.824080][ T137] ? console_emit_next_record+0x4f0/0x4f0 [ 9.824082][ T137] ? __lock_acquire+0x518/0xc20 [ 9.824087][ T137] console_unlock+0xee/0x1f0 [ 9.824090][ T137] ? console_flush_one_record+0x710/0x710 [ 9.824091][ T137] ? rcu_is_watching+0x16/0xd0 [ 9.824094][ T137] ? lock_acquire+0xa0/0x160 [ 9.824097][ T137] ? __down_trylock_console_sem+0x5e/0xa0 [ 9.824099][ T137] ? vprintk_emit+0x320/0x3e0 [ 9.824102][ T137] vprintk_emit+0x37c/0x3e0 [ 9.824106][ T137] ? wake_up_klogd_work_func+0x90/0x90 [ 9.824110][ T137] ? __lock_acquire+0x518/0xc20 [ 9.824115][ T137] _printk+0xc7/0x100 [ 9.824119][ T137] ? snapshot_read.cold+0x21/0x21 [ 9.824122][ T137] ? do_raw_spin_lock+0x131/0x280 [ 9.824124][ T137] ? __rwlock_init+0x150/0x150 [ 9.824128][ T137] ? do_raw_spin_lock+0x131/0x280 [ 9.824131][ T137] do_exit.cold+0x82/0x9c [ 9.824134][ T137] ? exit_notify+0x890/0x890 [ 9.824136][ T137] ? __lock_release.isra.0+0x69/0x1a0 [ 9.824138][ T137] ? rcu_is_watching+0x16/0xd0 [ 9.824143][ T137] do_group_exit+0xb8/0x370 [ 9.824145][ T137] __x64_sys_exit_group+0x3c/0x50 [ 9.824147][ T137] x64_sys_call+0x1567/0x1570 [ 9.824149][ T137] do_syscall_64+0xff/0x530 [ 9.824153][ T137] ? exc_page_fault+0xee/0x100 [ 9.824156][ T137] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 9.824159][ T137] RIP: 0033:0x7f824ed9c1b8 [ 9.824161][ T137] Code: Unable to access opcode bytes at 0x7f824ed9c18e. [ 9.824162][ T137] RSP: 002b:00007ffc9bf88018 EFLAGS: 00000246 ORIG_RAX: 00000000000000e7 [ 9.824164][ T137] RAX: ffffffffffffffda RBX: 00007f824eeccf88 RCX: 00007f824ed9c1b8 [ 9.824166][ T137] RDX: 00007f824eae5b48 RSI: fffffffffffffeb8 RDI: 0000000000000000 [ 9.824167][ T137] RBP: 00007ffc9bf88070 R08: 0000000000000000 R09: 0000000000000050 [ 9.824168][ T137] R10: 00007ffc9bf87e30 R11: 0000000000000246 R12: 0000000000000001 [ 9.824168][ T137] R13: 0000000000000000 R14: 00007f824eecb680 R15: 00007f824eeccfa0 [ 9.824175][ T137]