virtme: waiting for virtiofsd to start virtme: use 'microvm' QEMU architecture [ 1.202114][ T1] PPP generic driver version 2.4.2 [ 1.202207][ T1] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 1.202211][ T1] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 1, name: swapper/0 [ 1.202214][ T1] preempt_count: 1, expected: 0 [ 1.202216][ T1] RCU nest depth: 0, expected: 0 [ 1.202218][ T1] locks held by swapper/0/1: 4, last CPU#1: [ 1.202221][ T1] #0: ffffffff91b99dc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 1.202240][ T1] #1: ffffffff91b99e38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 1.202248][ T1] #2: ffffffff91a89760 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 1.202256][ T1] #3: ffffffff91a89660 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x202/0x4f0 [ 1.202263][ T1] irq event stamp: 357568 [ 1.202265][ T1] hardirqs last enabled at (357567): [] __down_trylock_console_sem+0x86/0xa0 [ 1.202269][ T1] hardirqs last disabled at (357568): [] console_emit_next_record+0x3f8/0x4f0 [ 1.202273][ T1] softirqs last enabled at (357396): [] handle_softirqs+0x67c/0x900 [ 1.202278][ T1] softirqs last disabled at (357363): [] __irq_exit_rcu+0x145/0x1c0 [ 1.202282][ T1] Preemption disabled at: [ 1.202283][ T1] [] vprintk_emit+0x31b/0x3e0 [ 1.202289][ T1] CPU: 1 UID: 0 PID: 1 Comm: swapper/0 Not tainted 7.2.0-virtme #1 PREEMPT(full) [ 1.202292][ T1] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 1.202303][ T1] Call Trace: [ 1.202305][ T1] [ 1.202309][ T1] dump_stack_lvl+0x6f/0xa0 [ 1.202316][ T1] ? vprintk_emit+0x31b/0x3e0 [ 1.202322][ T1] __might_resched.cold+0x1fe/0x2c1 [ 1.202327][ T1] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 1.202331][ T1] ? __kmalloc_noprof+0xdb/0x760 [ 1.202337][ T1] __kmalloc_noprof+0x443/0x760 [ 1.202337][ T1] ? alloc_buf.isra.0+0x4b/0x260 [ 1.202337][ T1] ? do_raw_spin_unlock+0x59/0x250 [ 1.202337][ T1] alloc_buf.isra.0+0x4b/0x260 [ 1.202337][ T1] put_chars+0x1e1/0x2f0 [ 1.202337][ T1] ? printk_get_next_message+0x2fe/0x7d0 [ 1.202337][ T1] ? __send_to_port+0x420/0x420 [ 1.202337][ T1] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 1.202337][ T1] ? validate_chain+0x38b/0xc20 [ 1.202337][ T1] hvc_console_print+0x292/0x780 [ 1.202337][ T1] ? hvc_write+0x3a0/0x3a0 [ 1.202337][ T1] ? rcu_is_watching+0x16/0xd0 [ 1.202337][ T1] ? lock_acquire+0x13c/0x160 [ 1.202337][ T1] console_emit_next_record+0x252/0x4f0 [ 1.202337][ T1] ? devkmsg_read+0x4e0/0x4e0 [ 1.202337][ T1] ? rcu_is_watching+0x16/0xd0 [ 1.202337][ T1] ? lock_acquire+0x13c/0x160 [ 1.202337][ T1] console_flush_one_record+0x46f/0x710 [ 1.202337][ T1] ? console_emit_next_record+0x4f0/0x4f0 [ 1.202337][ T1] ? __lock_acquire+0x518/0xc20 [ 1.202337][ T1] console_unlock+0xee/0x1f0 [ 1.202337][ T1] ? console_flush_one_record+0x710/0x710 [ 1.202337][ T1] ? rcu_is_watching+0x16/0xd0 [ 1.202337][ T1] ? lock_acquire+0xa0/0x160 [ 1.202337][ T1] ? __down_trylock_console_sem+0x5e/0xa0 [ 1.202337][ T1] ? vprintk_emit+0x320/0x3e0 [ 1.202337][ T1] vprintk_emit+0x37c/0x3e0 [ 1.202337][ T1] ? wake_up_klogd_work_func+0x90/0x90 [ 1.202337][ T1] ? vxlan_init_module+0x80/0x80 [ 1.202337][ T1] _printk+0xc7/0x100 [ 1.202337][ T1] ? snapshot_read.cold+0x21/0x21 [ 1.202337][ T1] ? add_chain_block+0x1e0/0x5b0 [ 1.202337][ T1] ? _raw_spin_unlock_irqrestore+0x40/0x80 [ 1.202337][ T1] ? add_device_randomness+0xbb/0x100 [ 1.202337][ T1] ? random_write_iter+0x20/0x20 [ 1.202337][ T1] ? phy_module_init+0x20/0x20 [ 1.202337][ T1] ? do_one_initcall+0x113/0x4f0 [ 1.202337][ T1] ppp_init+0x16/0x100 [ 1.202337][ T1] do_one_initcall+0x124/0x4f0 [ 1.202337][ T1] ? trace_event_raw_event_initcall_level+0x280/0x280 [ 1.202337][ T1] ? parameq+0x110/0x110 [ 1.202337][ T1] ? kernel_init_freeable+0x3f1/0x630 [ 1.202337][ T1] ? rcu_is_watching+0x16/0xd0 [ 1.202337][ T1] kernel_init_freeable+0x596/0x630 [ 1.202337][ T1] ? rest_init+0x280/0x280 [ 1.202337][ T1] kernel_init+0x21/0x150 [ 1.202337][ T1] ? rest_init+0x280/0x280 [ 1.202337][ T1] ret_from_fork+0x474/0x6b0 [ 1.202337][ T1] ? arch_exit_to_user_mode_prepare.isra.0+0x120/0x120 [ 1.202337][ T1] ? __switch_to+0x5a3/0xe00 [ 1.202337][ T1] ? rest_init+0x280/0x280 [ 1.202337][ T1] ret_from_fork_asm+0x11/0x20 [ 1.202337][ T1] [ 1.219862][ T1] NET: Registered PF_PPPOX protocol family [ 1.220803][ T1] i8042: PNP: No PS/2 controller found. [ 1.230705][ T1] rtc_cmos PNP0B00:00: registered as rtc0 [ 1.231135][ T1] rtc_cmos PNP0B00:00: setting system clock to 2026-08-28T16:17:38 UTC (1787933858) [ 1.232072][ T1] rtc_cmos PNP0B00:00: alarms up to one day, 242 bytes nvram [ 1.235052][ T1] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev [ 1.241812][ T1] ipip: IPv4 and MPLS over IPv4 tunneling driver [ 1.244861][ T1] gre: GRE over IPv4 demultiplexer driver [ 1.245045][ T1] ip_gre: GRE over IPv4 tunneling driver [ 1.253343][ T1] Initializing XFRM netlink socket [ 1.253969][ T1] NET: Registered PF_INET6 protocol family [ 1.260954][ T1] Segment Routing with IPv6 [ 1.261391][ T1] In-situ OAM (IOAM) with IPv6 [ 1.261733][ T1] sit: IPv6, IPv4 and MPLS over IPv4 tunneling driver [ 1.268945][ T1] ip6_gre: GRE over IPv6 tunneling driver [ 1.272087][ T1] NET: Registered PF_PACKET protocol family [ 1.272469][ T1] 9pnet: Installing 9P2000 support [ 1.272852][ T1] Key type dns_resolver registered [ 1.273811][ T1] NET: Registered PF_VSOCK protocol family [ 1.279136][ T1] IPI shorthand broadcast: enabled [ 1.393318][ T1] sched_clock: Marking stable (1360001893, 32320175)->(1498655253, -106333185) [ 1.397351][ T1] registered taskstats version 1 [ 1.400709][ T1] Loading compiled-in X.509 certificates [ 1.448420][ T70] kwatchdog (70) used greatest stack depth: 29688 bytes left [ 1.509118][ T1] Demotion targets for Node 0: null [ 1.509546][ T1] kmemleak: Kernel memory leak detector initialized (mem pool available: 11965) [ 1.509865][ T1] page_owner is disabled [ 1.519406][ T1] PM: Magic number: 6:534:288 [ 1.519646][ T1] tty tty21: hash matches [ 1.522279][ T1] ALSA device list: [ 1.523249][ T1] No soundcards found. [ 1.524791][ T1] md: Skipping autodetection of RAID arrays. (raid=autodetect will force) [ 1.527421][ T1] VFS: Mounted root (virtiofs filesystem) readonly on device 0:24. [ 1.528281][ T1] devtmpfs: mounted [ 1.528644][ T1] VFS: Pivoted into new rootfs [ 1.560726][ T1] Freeing unused kernel image (initmem) memory: 2576K [ 1.561013][ T1] Write protecting the kernel read-only data: 63488k [ 1.561892][ T1] Freeing unused kernel image (text/rodata gap) memory: 864K [ 1.563038][ T1] Freeing unused kernel image (rodata/data gap) memory: 1116K [ 1.563309][ T1] Run /usr/lib/python3.14/site-packages/virtme/guest/bin/virtme-ng-init as init process [ 1.563578][ T1] with arguments: [ 1.563696][ T1] /usr/lib/python3.14/site-packages/virtme/guest/bin/virtme-ng-init [ 1.563924][ T1] with environment: [ 1.564044][ T1] HOME=/ [ 1.564170][ T1] TERM=dumb [ 1.564279][ T1] virtme_hostname=vmksft-drv-hw-dbg,debug-threads=on [ 1.564477][ T1] nr_open=2147483584 [ 1.564586][ T1] virtme_link_mods=/srv/vmksft/testing/wt-22/.virtme_mods/lib/modules/0.0.0 [ 1.564837][ T1] virtme_rw_overlay0=/etc [ 1.564995][ T1] virtme_rw_overlay1=/lib [ 1.565142][ T1] virtme_rw_overlay2=/home [ 1.565286][ T1] virtme_rw_overlay3=/opt [ 1.565448][ T1] virtme_rw_overlay4=/srv [ 1.565598][ T1] virtme_rw_overlay5=/usr [ 1.565741][ T1] virtme_rw_overlay6=/var [ 1.565890][ T1] virtme_rw_overlay7=/tmp [ 1.566041][ T1] virtme_console=ttyS0 [ 1.566191][ T1] virtme_chdir=srv/vmksft/testing/wt-22 [ 1.586209][ T1] virtme-ng-init: mount devtmpfs -> /dev: EBUSY: Device or resource busy [ 1.587991][ T1] virtme-ng-init: Setting hostname to vmksft-drv-hw-dbg,debug-threads=on... [ 1.596597][ T1] overlayfs: failed to set xattr on upper [ 1.596910][ T1] overlayfs: ...falling back to redirect_dir=nofollow. [ 1.597267][ T1] overlayfs: ...falling back to uuid=null. [ 1.599943][ T1] overlayfs: failed to set xattr on upper [ 1.600191][ T1] overlayfs: ...falling back to redirect_dir=nofollow. [ 1.600576][ T1] overlayfs: ...falling back to uuid=null. [ 1.602639][ T1] overlayfs: failed to set xattr on upper [ 1.602878][ T1] overlayfs: ...falling back to redirect_dir=nofollow. [ 1.603173][ T1] overlayfs: ...falling back to uuid=null. [ 1.605203][ T1] overlayfs: failed to set xattr on upper [ 1.605451][ T1] overlayfs: ...falling back to redirect_dir=nofollow. [ 1.605729][ T1] overlayfs: ...falling back to uuid=null. [ 1.608452][ T1] overlayfs: failed to set xattr on upper [ 1.608684][ T1] overlayfs: ...falling back to redirect_dir=nofollow. [ 1.608995][ T1] overlayfs: ...falling back to uuid=null. [ 1.610956][ T1] overlayfs: failed to set xattr on upper [ 1.611200][ T1] overlayfs: ...falling back to redirect_dir=nofollow. [ 1.611893][ T1] overlayfs: ...falling back to uuid=null. [ 1.614036][ T1] overlayfs: failed to set xattr on upper [ 1.614277][ T1] overlayfs: ...falling back to redirect_dir=nofollow. [ 1.615081][ T1] overlayfs: ...falling back to uuid=null. [ 1.617238][ T1] overlayfs: failed to set xattr on upper [ 1.617507][ T1] overlayfs: ...falling back to redirect_dir=nofollow. [ 1.617800][ T1] overlayfs: ...falling back to uuid=null. [ 1.630308][ T1] virtme-ng-init: running systemd-tmpfiles [ 4.249649][ T72] systemd-tmpfile (72) used greatest stack depth: 25104 bytes left [ 4.249666][ T72] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 4.249669][ T72] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 72, name: systemd-tmpfile [ 4.249670][ T72] preempt_count: 2, expected: 0 [ 4.249671][ T72] RCU nest depth: 0, expected: 0 [ 4.249672][ T72] locks held by systemd-tmpfile/72: 5, last CPU#1: [ 4.249675][ T72] #0: ffffffff91a027b8 (low_water_lock){+.+.}-{3:3}, at: do_exit+0x887/0xdc0 [ 4.249687][ T72] #1: ffffffff91b99dc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 4.249692][ T72] #2: ffffffff91b99e38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 4.249697][ T72] #3: ffffffff91a89760 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 4.249701][ T72] #4: ffffffff91a89660 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x202/0x4f0 [ 4.249705][ T72] irq event stamp: 2454686 [ 4.249706][ T72] hardirqs last enabled at (2454685): [] __down_trylock_console_sem+0x86/0xa0 [ 4.249709][ T72] hardirqs last disabled at (2454686): [] console_emit_next_record+0x3f8/0x4f0 [ 4.249711][ T72] softirqs last enabled at (2454500): [] handle_softirqs+0x67c/0x900 [ 4.249713][ T72] softirqs last disabled at (2454495): [] __irq_exit_rcu+0x145/0x1c0 [ 4.249715][ T72] Preemption disabled at: [ 4.249716][ T72] [<0000000000000000>] 0x0 [ 4.249724][ T72] CPU: 1 UID: 0 PID: 72 Comm: systemd-tmpfile Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 4.249727][ T72] Tainted: [W]=WARN [ 4.249728][ T72] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 4.249730][ T72] Call Trace: [ 4.249731][ T72] [ 4.249733][ T72] dump_stack_lvl+0x6f/0xa0 [ 4.249739][ T72] __might_resched.cold+0x1fe/0x2c1 [ 4.249744][ T72] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 4.249748][ T72] ? __kmalloc_noprof+0xdb/0x760 [ 4.249753][ T72] __kmalloc_noprof+0x443/0x760 [ 4.249756][ T72] ? alloc_buf.isra.0+0x4b/0x260 [ 4.249763][ T72] ? do_raw_spin_unlock+0x59/0x250 [ 4.249766][ T72] alloc_buf.isra.0+0x4b/0x260 [ 4.249770][ T72] put_chars+0x1e1/0x2f0 [ 4.249773][ T72] ? __send_to_port+0x420/0x420 [ 4.249775][ T72] ? printk_get_next_message+0x2fe/0x7d0 [ 4.249779][ T72] ? rcu_read_lock_any_held+0x3c/0x90 [ 4.249782][ T72] ? validate_chain+0x38b/0xc20 [ 4.249786][ T72] hvc_console_print+0x292/0x780 [ 4.249791][ T72] ? __lock_acquire+0x518/0xc20 [ 4.249792][ T72] ? __lock_acquire+0x518/0xc20 [ 4.249796][ T72] ? hvc_write+0x3a0/0x3a0 [ 4.249800][ T72] ? rcu_is_watching+0x16/0xd0 [ 4.249804][ T72] ? lock_acquire+0x13c/0x160 [ 4.249808][ T72] console_emit_next_record+0x252/0x4f0 [ 4.249812][ T72] ? devkmsg_read+0x4e0/0x4e0 [ 4.249817][ T72] ? rcu_is_watching+0x16/0xd0 [ 4.249819][ T72] ? lock_acquire+0x13c/0x160 [ 4.249823][ T72] console_flush_one_record+0x46f/0x710 [ 4.249828][ T72] ? console_emit_next_record+0x4f0/0x4f0 [ 4.249830][ T72] ? __lock_acquire+0x518/0xc20 [ 4.249835][ T72] console_unlock+0xee/0x1f0 [ 4.249838][ T72] ? console_flush_one_record+0x710/0x710 [ 4.249840][ T72] ? rcu_is_watching+0x16/0xd0 [ 4.249842][ T72] ? lock_acquire+0xa0/0x160 [ 4.249846][ T72] ? __down_trylock_console_sem+0x5e/0xa0 [ 4.249848][ T72] ? vprintk_emit+0x320/0x3e0 [ 4.249851][ T72] vprintk_emit+0x37c/0x3e0 [ 4.249855][ T72] ? wake_up_klogd_work_func+0x90/0x90 [ 4.249859][ T72] ? __lock_acquire+0x518/0xc20 [ 4.249862][ T72] _printk+0xc7/0x100 [ 4.249866][ T72] ? snapshot_read.cold+0x21/0x21 [ 4.249869][ T72] ? do_raw_spin_lock+0x131/0x280 [ 4.249871][ T72] ? __rwlock_init+0x150/0x150 [ 4.249875][ T72] ? do_raw_spin_lock+0x131/0x280 [ 4.249878][ T72] do_exit.cold+0x82/0x9c [ 4.249882][ T72] ? exit_notify+0x890/0x890 [ 4.249883][ T72] ? __lock_release.isra.0+0x69/0x1a0 [ 4.249886][ T72] ? rcu_is_watching+0x16/0xd0 [ 4.249890][ T72] do_group_exit+0xb8/0x370 [ 4.249893][ T72] __x64_sys_exit_group+0x3c/0x50 [ 4.249895][ T72] x64_sys_call+0x1567/0x1570 [ 4.249897][ T1] virtme-ng-init: Failed to read '/usr/lib/tmpfiles.d/audit.conf': Permission denied [ 4.249897][ T1] Failed to create directory or subvolume "/var/spool/at/spool": Permission denied [ 4.249897][ T1] Failed to create file /var/spool/at/.SEQ: Permission denied [ 4.249897][ T1] Failed to opendir() '/proc/self/fd/6': Permission denied [ 4.249897][ T72] do_syscall_64+0xff/0x530 [ 4.249901][ T72] ? exc_page_fault+0xee/0x100 [ 4.249905][ T72] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 4.249907][ T72] RIP: 0033:0x7f6877ca71b8 [ 4.249909][ T72] Code: Unable to access opcode bytes at 0x7f6877ca718e. [ 4.249910][ T72] RSP: 002b:00007ffd362ea0f8 EFLAGS: 00000246 ORIG_RAX: 00000000000000e7 [ 4.249913][ T72] RAX: ffffffffffffffda RBX: 00007f6877dd7f88 RCX: 00007f6877ca71b8 [ 4.249914][ T72] RDX: 00007f68775054c8 RSI: fffffffffffffe90 RDI: 0000000000000049 [ 4.249915][ T72] RBP: 00007ffd362ea150 R08: 0000000000000000 R09: 0000000000001000 [ 4.249916][ T72] R10: 00007ffd362e9f10 R11: 0000000000000246 R12: 0000000000000001 [ 4.249917][ T72] R13: 0000000000000049 R14: 00007f6877dd6680 R15: 00007f6877dd7fa0 [ 4.249925][ T72] [ 4.272043][ T1] virtme-ng-init: basic initialization done [ 4.321888][ T79] ip (79) used greatest stack depth: 24680 bytes left [ 4.349450][ T75] virtme-ng-init: Starting systemd-udevd version 259.8-1.fc44 [ 4.350098][ T75] virtme-ng-init: triggering udev coldplug [ 6.706420][ T85] virtio_net virtio2 enp0s1: renamed from eth0 [ 6.706477][ T85] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 6.706480][ T85] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 85, name: (udev-worker) [ 6.706482][ T85] preempt_count: 1, expected: 0 [ 6.706483][ T85] RCU nest depth: 0, expected: 0 [ 6.706484][ T85] locks held by (udev-worker)/85: 5, last CPU#2: [ 6.706486][ T85] #0: ffffffff92312c80 (rtnl_mutex){+.+.}-{4:4}, at: rtnl_setlink+0x29d/0x920 [ 6.706498][ T85] #1: ffffffff91b99dc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 6.706505][ T85] #2: ffffffff91b99e38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 6.706509][ T85] #3: ffffffff91a89760 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 6.706513][ T85] #4: ffffffff91a89660 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x202/0x4f0 [ 6.706517][ T85] irq event stamp: 202992 [ 6.706518][ T85] hardirqs last enabled at (202991): [] __down_trylock_console_sem+0x86/0xa0 [ 6.706520][ T85] hardirqs last disabled at (202992): [] console_emit_next_record+0x3f8/0x4f0 [ 6.706522][ T85] softirqs last enabled at (202986): [] netif_change_name+0x216/0x8c0 [ 6.706525][ T85] softirqs last disabled at (202984): [] netif_change_name+0x1ad/0x8c0 [ 6.706528][ T85] Preemption disabled at: [ 6.706528][ T85] [] vprintk_emit+0x31b/0x3e0 [ 6.706534][ T85] CPU: 2 UID: 0 PID: 85 Comm: (udev-worker) Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 6.706538][ T85] Tainted: [W]=WARN [ 6.706539][ T85] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 6.706541][ T85] Call Trace: [ 6.706543][ T85] [ 6.706544][ T85] dump_stack_lvl+0x6f/0xa0 [ 6.706550][ T85] ? vprintk_emit+0x31b/0x3e0 [ 6.706553][ T85] __might_resched.cold+0x1fe/0x2c1 [ 6.706557][ T85] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 6.706562][ T85] ? __kmalloc_noprof+0xdb/0x760 [ 6.706567][ T85] __kmalloc_noprof+0x443/0x760 [ 6.706570][ T85] ? alloc_buf.isra.0+0x4b/0x260 [ 6.706576][ T85] ? do_raw_spin_unlock+0x59/0x250 [ 6.706579][ T85] alloc_buf.isra.0+0x4b/0x260 [ 6.706583][ T85] put_chars+0x1e1/0x2f0 [ 6.706587][ T85] ? __send_to_port+0x420/0x420 [ 6.706591][ T85] ? validate_chain+0x34a/0xc20 [ 6.706596][ T85] hvc_console_print+0x292/0x780 [ 6.706599][ T85] ? __lock_acquire+0x518/0xc20 [ 6.706605][ T85] ? hvc_write+0x3a0/0x3a0 [ 6.706608][ T85] ? rcu_is_watching+0x16/0xd0 [ 6.706614][ T85] console_emit_next_record+0x252/0x4f0 [ 6.706618][ T85] ? devkmsg_read+0x4e0/0x4e0 [ 6.706623][ T85] ? rcu_is_watching+0x16/0xd0 [ 6.706625][ T85] ? lock_acquire+0x13c/0x160 [ 6.706629][ T85] console_flush_one_record+0x46f/0x710 [ 6.706633][ T85] ? console_emit_next_record+0x4f0/0x4f0 [ 6.706635][ T85] ? __lock_acquire+0x518/0xc20 [ 6.706641][ T85] console_unlock+0xee/0x1f0 [ 6.706644][ T85] ? console_flush_one_record+0x710/0x710 [ 6.706646][ T85] ? rcu_is_watching+0x16/0xd0 [ 6.706648][ T85] ? lock_acquire+0xa0/0x160 [ 6.706652][ T85] ? __down_trylock_console_sem+0x5e/0xa0 [ 6.706653][ T85] ? vprintk_emit+0x320/0x3e0 [ 6.706657][ T85] vprintk_emit+0x37c/0x3e0 [ 6.706661][ T85] ? wake_up_klogd_work_func+0x90/0x90 [ 6.706663][ T85] ? write_profile+0xf0/0xf0 [ 6.706666][ T85] ? unwind_get_return_address+0x67/0xd0 [ 6.706672][ T85] dev_vprintk_emit+0x27f/0x2c0 [ 6.706677][ T85] ? device_rename.cold+0xa/0xa [ 6.706681][ T85] ? filter_irq_stacks+0xd0/0xd0 [ 6.706687][ T85] dev_printk_emit+0xb9/0xee [ 6.706689][ T85] ? dev_vprintk_emit+0x2c0/0x2c0 [ 6.706693][ T85] ? check_prev_add+0x316/0xe90 [ 6.706698][ T85] __netdev_printk+0x160/0x1d0 [ 6.706705][ T85] netdev_info+0xe2/0x116 [ 6.706707][ T85] ? netdev_notice+0x120/0x120 [ 6.706710][ T85] ? find_held_lock+0x2b/0x80 [ 6.706714][ T85] ? __lock_release.isra.0+0x69/0x1a0 [ 6.706716][ T85] ? mark_held_locks+0x40/0x70 [ 6.706720][ T85] ? do_setlink.isra.0+0x1f7d/0x2a60 [ 6.706722][ T85] netif_change_name.cold+0x4f/0x89 [ 6.706725][ T85] ? __lock_acquire+0x518/0xc20 [ 6.706727][ T85] ? add_chain_block+0x1e7/0x5b0 [ 6.706729][ T85] ? static_obj+0x52/0x90 [ 6.706731][ T85] ? netdev_adjacent_rename_links+0x470/0x470 [ 6.706733][ T85] ? lock_acquire.part.0+0xd4/0x280 [ 6.706735][ T85] ? find_held_lock+0x2b/0x80 [ 6.706738][ T85] ? __asan_memset+0x27/0x50 [ 6.706743][ T85] do_setlink.isra.0+0x1f7d/0x2a60 [ 6.706746][ T85] ? __lock_acquire+0x518/0xc20 [ 6.706749][ T85] ? rtnl_link_get_size+0x350/0x350 [ 6.706751][ T85] ? mark_usage+0x61/0x170 [ 6.706753][ T85] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 6.706755][ T85] ? rcu_read_lock_any_held+0x3c/0x90 [ 6.706758][ T85] ? validate_chain+0x38b/0xc20 [ 6.706763][ T85] ? __lock_acquire+0x518/0xc20 [ 6.706767][ T85] ? lock_acquire.part.0+0xd4/0x280 [ 6.706769][ T85] ? rtnl_setlink+0x29d/0x920 [ 6.706772][ T85] ? rcu_is_watching+0x16/0xd0 [ 6.706774][ T85] ? lock_acquire+0x13c/0x160 [ 6.706776][ T85] ? rcu_is_watching+0x16/0xd0 [ 6.706778][ T85] ? rcu_is_watching+0x16/0xd0 [ 6.706780][ T85] ? trace_contention_end+0xb3/0x180 [ 6.706784][ T85] ? __mutex_lock+0x1db/0x1ea0 [ 6.706787][ T85] ? __mutex_lock+0x9a3/0x1ea0 [ 6.706789][ T85] ? __lock_acquire+0x518/0xc20 [ 6.706791][ T85] ? rtnl_setlink+0x29d/0x920 [ 6.706793][ T85] ? mark_usage+0x61/0x170 [ 6.706796][ T85] ? ww_mutex_lock+0x160/0x160 [ 6.706798][ T85] ? nla_get_range_signed+0x3d0/0x3d0 [ 6.706804][ T85] ? mark_usage+0x61/0x170 [ 6.706807][ T85] ? cap_capable+0x1d7/0x3d0 [ 6.706815][ T85] rtnl_setlink+0x527/0x920 [ 6.706818][ T85] ? __rtnl_newlink+0xa50/0xa50 [ 6.706820][ T85] ? __lock_acquire+0x518/0xc20 [ 6.706844][ T85] ? lock_acquire.part.0+0xd4/0x280 [ 6.706846][ T85] ? find_held_lock+0x2b/0x80 [ 6.706848][ T85] ? __lock_release.isra.0+0x69/0x1a0 [ 6.706850][ T85] ? mark_usage+0x61/0x170 [ 6.706852][ T85] ? __lock_acquire+0x518/0xc20 [ 6.706857][ T85] ? lock_acquire.part.0+0xd4/0x280 [ 6.706859][ T85] ? find_held_lock+0x2b/0x80 [ 6.706861][ T85] ? __rtnl_newlink+0xa50/0xa50 [ 6.706863][ T85] ? __lock_release.isra.0+0x69/0x1a0 [ 6.706867][ T85] ? __rtnl_newlink+0xa50/0xa50 [ 6.706869][ T85] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 6.706873][ T85] ? rtnl_link_fill+0x900/0x900 [ 6.706874][ T85] ? __lock_acquire+0x518/0xc20 [ 6.706879][ T85] ? lock_acquire.part.0+0xd4/0x280 [ 6.706881][ T85] ? find_held_lock+0x2b/0x80 [ 6.706885][ T85] netlink_rcv_skb+0x14e/0x3a0 [ 6.706888][ T85] ? rtnl_link_fill+0x900/0x900 [ 6.706892][ T85] ? netlink_ack+0xcd0/0xcd0 [ 6.706898][ T85] ? netlink_deliver_tap+0xc5/0x330 [ 6.706900][ T85] ? netlink_deliver_tap+0x13c/0x330 [ 6.706905][ T85] netlink_unicast+0x486/0x750 [ 6.706909][ T85] ? netlink_attachskb+0x810/0x810 [ 6.706912][ T85] ? __lock_acquire+0x518/0xc20 [ 6.706917][ T85] netlink_sendmsg+0x735/0xc60 [ 6.706922][ T85] ? netlink_unicast+0x750/0x750 [ 6.706926][ T85] ? __might_fault+0x97/0x140 [ 6.706930][ T85] ? __might_fault+0x97/0x140 [ 6.706934][ T85] __sys_sendto+0x2aa/0x400 [ 6.706938][ T85] ? __ia32_sys_getpeername+0xd0/0xd0 [ 6.706949][ T85] ? exc_page_fault+0x87/0x100 [ 6.706955][ T85] __x64_sys_sendto+0xe4/0x1f0 [ 6.706957][ T85] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 6.706960][ T85] ? lockdep_hardirqs_on+0x91/0x130 [ 6.706962][ T85] ? do_syscall_64+0xa6/0x530 [ 6.706964][ T85] do_syscall_64+0xff/0x530 [ 6.706965][ T85] ? exc_page_fault+0xee/0x100 [ 6.706968][ T85] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 6.706971][ T85] RIP: 0033:0x7fbf6ddc954e [ 6.706975][ T85] Code: 4d 89 d8 e8 b4 bd 00 00 4c 8b 5d f8 41 8b 93 08 03 00 00 59 5e 48 83 f8 fc 74 11 c9 c3 0f 1f 80 00 00 00 00 48 8b 45 10 0f 05 c3 83 e2 39 83 fa 08 75 e7 e8 03 ff ff ff 0f 1f 00 f3 0f 1e fa [ 6.706976][ T85] RSP: 002b:00007ffdbd07ae20 EFLAGS: 00000202 ORIG_RAX: 000000000000002c [ 6.706979][ T85] RAX: ffffffffffffffda RBX: 00005595b6894f40 RCX: 00007fbf6ddc954e [ 6.706980][ T85] RDX: 000000000000002c RSI: 00005595b69e5800 RDI: 0000000000000018 [ 6.706981][ T85] RBP: 00007ffdbd07ae30 R08: 00007ffdbd07ae80 R09: 0000000000000080 [ 6.706982][ T85] R10: 0000000000000000 R11: 0000000000000202 R12: 00005595b69e81e0 [ 6.706983][ T85] R13: 00007ffdbd07af64 R14: 0000000000000000 R15: 0000000000000000 [ 6.706990][ T85] [ 6.790176][ T90] virtio_net virtio3 enp0s2: renamed from eth1 [ 7.065644][ T75] virtme-ng-init: waiting for udev to settle [ 7.657946][ T75] virtme-ng-init: udev is done [ 7.666536][ T1] virtme-ng-init: initialization done [ 9.406788][ T137] ip (137) used greatest stack depth: 24512 bytes left [ 9.406805][ T137] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 9.406807][ T137] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 137, name: ip [ 9.406808][ T137] preempt_count: 2, expected: 0 [ 9.406809][ T137] RCU nest depth: 0, expected: 0 [ 9.406810][ T137] locks held by ip/137: 5, last CPU#1: [ 9.406812][ T137] #0: ffffffff91a027b8 (low_water_lock){+.+.}-{3:3}, at: do_exit+0x887/0xdc0 [ 9.406824][ T137] #1: ffffffff91b99dc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 9.406829][ T137] #2: ffffffff91b99e38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 9.406834][ T137] #3: ffffffff91a89760 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 9.406838][ T137] #4: ffffffff91a89660 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x202/0x4f0 [ 9.406842][ T137] irq event stamp: 26384 [ 9.406843][ T137] hardirqs last enabled at (26383): [] __down_trylock_console_sem+0x86/0xa0 [ 9.406845][ T137] hardirqs last disabled at (26384): [] console_emit_next_record+0x3f8/0x4f0 [ 9.406847][ T137] softirqs last enabled at (26148): [] handle_softirqs+0x67c/0x900 [ 9.406849][ T137] softirqs last disabled at (26143): [] __irq_exit_rcu+0x145/0x1c0 [ 9.406851][ T137] Preemption disabled at: [ 9.406852][ T137] [<0000000000000000>] 0x0 [ 9.406859][ T137] CPU: 1 UID: 0 PID: 137 Comm: ip Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 9.406863][ T137] Tainted: [W]=WARN [ 9.406864][ T137] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 9.406865][ T137] Call Trace: [ 9.406867][ T137] [ 9.406868][ T137] dump_stack_lvl+0x6f/0xa0 [ 9.406875][ T137] __might_resched.cold+0x1fe/0x2c1 [ 9.406880][ T137] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 9.406884][ T137] ? __kmalloc_noprof+0xdb/0x760 [ 9.406889][ T137] __kmalloc_noprof+0x443/0x760 [ 9.406891][ T137] ? alloc_buf.isra.0+0x4b/0x260 [ 9.406897][ T137] ? do_raw_spin_unlock+0x59/0x250 [ 9.406900][ T137] alloc_buf.isra.0+0x4b/0x260 [ 9.406904][ T137] put_chars+0x1e1/0x2f0 [ 9.406907][ T137] ? __send_to_port+0x420/0x420 [ 9.406909][ T137] ? printk_get_next_message+0x2fe/0x7d0 [ 9.406913][ T137] ? rcu_read_lock_any_held+0x3c/0x90 [ 9.406916][ T137] ? validate_chain+0x38b/0xc20 [ 9.406920][ T137] hvc_console_print+0x292/0x780 [ 9.406924][ T137] ? __lock_acquire+0x518/0xc20 [ 9.406925][ T137] ? __lock_acquire+0x518/0xc20 [ 9.406930][ T137] ? hvc_write+0x3a0/0x3a0 [ 9.406933][ T137] ? rcu_is_watching+0x16/0xd0 [ 9.406937][ T137] ? lock_acquire+0x13c/0x160 [ 9.406941][ T137] console_emit_next_record+0x252/0x4f0 [ 9.406945][ T137] ? devkmsg_read+0x4e0/0x4e0 [ 9.406949][ T137] ? rcu_is_watching+0x16/0xd0 [ 9.406951][ T137] ? lock_acquire+0x13c/0x160 [ 9.406955][ T137] console_flush_one_record+0x46f/0x710 [ 9.406959][ T137] ? console_emit_next_record+0x4f0/0x4f0 [ 9.406961][ T137] ? __lock_acquire+0x518/0xc20 [ 9.406966][ T137] console_unlock+0xee/0x1f0 [ 9.406969][ T137] ? console_flush_one_record+0x710/0x710 [ 9.406971][ T137] ? rcu_is_watching+0x16/0xd0 [ 9.406973][ T137] ? lock_acquire+0xa0/0x160 [ 9.406977][ T137] ? __down_trylock_console_sem+0x5e/0xa0 [ 9.406978][ T137] ? vprintk_emit+0x320/0x3e0 [ 9.406982][ T137] vprintk_emit+0x37c/0x3e0 [ 9.406986][ T137] ? wake_up_klogd_work_func+0x90/0x90 [ 9.406989][ T137] ? __lock_acquire+0x518/0xc20 [ 9.406993][ T137] _printk+0xc7/0x100 [ 9.406996][ T137] ? snapshot_read.cold+0x21/0x21 [ 9.406999][ T137] ? do_raw_spin_lock+0x131/0x280 [ 9.407002][ T137] ? __rwlock_init+0x150/0x150 [ 9.407006][ T137] ? do_raw_spin_lock+0x131/0x280 [ 9.407008][ T137] do_exit.cold+0x82/0x9c [ 9.407012][ T137] ? exit_notify+0x890/0x890 [ 9.407014][ T137] ? __lock_release.isra.0+0x69/0x1a0 [ 9.407016][ T137] ? rcu_is_watching+0x16/0xd0 [ 9.407021][ T137] do_group_exit+0xb8/0x370 [ 9.407024][ T137] __x64_sys_exit_group+0x3c/0x50 [ 9.407025][ T137] x64_sys_call+0x1567/0x1570 [ 9.407027][ T137] do_syscall_64+0xff/0x530 [ 9.407031][ T137] ? exc_page_fault+0xee/0x100 [ 9.407034][ T137] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 9.407037][ T137] RIP: 0033:0x7f176c87b1b8 [ 9.407039][ T137] Code: Unable to access opcode bytes at 0x7f176c87b18e. [ 9.407040][ T137] RSP: 002b:00007ffc84aa5ac8 EFLAGS: 00000246 ORIG_RAX: 00000000000000e7 [ 9.407042][ T137] RAX: ffffffffffffffda RBX: 00007f176c9abf88 RCX: 00007f176c87b1b8 [ 9.407044][ T137] RDX: 00007f176c5c4b48 RSI: fffffffffffffeb8 RDI: 0000000000000000 [ 9.407045][ T137] RBP: 00007ffc84aa5b20 R08: 0000000000000000 R09: 0000000000000050 [ 9.407045][ T137] R10: 00007ffc84aa58e0 R11: 0000000000000246 R12: 0000000000000001 [ 9.407046][ T137] R13: 0000000000000000 R14: 00007f176c9aa680 R15: 00007f176c9abfa0 [ 9.407054][ T137]