virtme: waiting for virtiofsd to start virtme: use 'microvm' QEMU architecture [ 1.215088][ T1] PPP generic driver version 2.4.2 [ 1.215123][ T1] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 1.215125][ T1] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 1, name: swapper/0 [ 1.215127][ T1] preempt_count: 1, expected: 0 [ 1.215128][ T1] RCU nest depth: 0, expected: 0 [ 1.215129][ T1] locks held by swapper/0/1: 4, last CPU#3: [ 1.215131][ T1] #0: ffffffffa7799dc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 1.215143][ T1] #1: ffffffffa7799e38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 1.215148][ T1] #2: ffffffffa7689760 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 1.215152][ T1] #3: ffffffffa7689660 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x202/0x4f0 [ 1.215156][ T1] irq event stamp: 356708 [ 1.215157][ T1] hardirqs last enabled at (356707): [] __down_trylock_console_sem+0x86/0xa0 [ 1.215160][ T1] hardirqs last disabled at (356708): [] console_emit_next_record+0x3f8/0x4f0 [ 1.215162][ T1] softirqs last enabled at (356594): [] handle_softirqs+0x67c/0x900 [ 1.215165][ T1] softirqs last disabled at (356561): [] __irq_exit_rcu+0x145/0x1c0 [ 1.215167][ T1] Preemption disabled at: [ 1.215167][ T1] [] vprintk_emit+0x31b/0x3e0 [ 1.215172][ T1] CPU: 3 UID: 0 PID: 1 Comm: swapper/0 Not tainted 7.2.0-virtme #1 PREEMPT(full) [ 1.215175][ T1] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 1.215177][ T1] Call Trace: [ 1.215179][ T1] [ 1.215182][ T1] dump_stack_lvl+0x6f/0xa0 [ 1.215187][ T1] ? vprintk_emit+0x31b/0x3e0 [ 1.215189][ T1] __might_resched.cold+0x1fe/0x2c1 [ 1.215194][ T1] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 1.215198][ T1] ? __kmalloc_noprof+0xdb/0x760 [ 1.215203][ T1] __kmalloc_noprof+0x443/0x760 [ 1.215205][ T1] ? alloc_buf.isra.0+0x4b/0x260 [ 1.215212][ T1] ? do_raw_spin_unlock+0x59/0x250 [ 1.215215][ T1] alloc_buf.isra.0+0x4b/0x260 [ 1.215218][ T1] put_chars+0x1e1/0x2f0 [ 1.215220][ T1] ? printk_get_next_message+0x2fe/0x7d0 [ 1.215223][ T1] ? __send_to_port+0x420/0x420 [ 1.215225][ T1] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 1.215229][ T1] ? validate_chain+0x38b/0xc20 [ 1.215234][ T1] hvc_console_print+0x292/0x780 [ 1.215241][ T1] ? hvc_write+0x3a0/0x3a0 [ 1.215244][ T1] ? rcu_is_watching+0x16/0xd0 [ 1.215246][ T1] ? lock_acquire+0x13c/0x160 [ 1.215250][ T1] console_emit_next_record+0x252/0x4f0 [ 1.215254][ T1] ? devkmsg_read+0x4e0/0x4e0 [ 1.215258][ T1] ? rcu_is_watching+0x16/0xd0 [ 1.215260][ T1] ? lock_acquire+0x13c/0x160 [ 1.215264][ T1] console_flush_one_record+0x46f/0x710 [ 1.215268][ T1] ? console_emit_next_record+0x4f0/0x4f0 [ 1.215270][ T1] ? __lock_acquire+0x518/0xc20 [ 1.215275][ T1] console_unlock+0xee/0x1f0 [ 1.215278][ T1] ? console_flush_one_record+0x710/0x710 [ 1.215280][ T1] ? rcu_is_watching+0x16/0xd0 [ 1.215282][ T1] ? lock_acquire+0xa0/0x160 [ 1.215286][ T1] ? __down_trylock_console_sem+0x5e/0xa0 [ 1.215287][ T1] ? vprintk_emit+0x320/0x3e0 [ 1.215291][ T1] vprintk_emit+0x37c/0x3e0 [ 1.215294][ T1] ? wake_up_klogd_work_func+0x90/0x90 [ 1.215299][ T1] ? vxlan_init_module+0x80/0x80 [ 1.215303][ T1] _printk+0xc7/0x100 [ 1.215306][ T1] ? snapshot_read.cold+0x21/0x21 [ 1.215308][ T1] ? add_chain_block+0x1e0/0x5b0 [ 1.215311][ T1] ? _raw_spin_unlock_irqrestore+0x40/0x80 [ 1.215315][ T1] ? add_device_randomness+0xbb/0x100 [ 1.215317][ T1] ? random_write_iter+0x20/0x20 [ 1.215320][ T1] ? phy_module_init+0x20/0x20 [ 1.215322][ T1] ? do_one_initcall+0x113/0x4f0 [ 1.215325][ T1] ppp_init+0x16/0x100 [ 1.215327][ T1] do_one_initcall+0x124/0x4f0 [ 1.215329][ T1] ? trace_event_raw_event_initcall_level+0x280/0x280 [ 1.215331][ T1] ? parameq+0x110/0x110 [ 1.215336][ T1] ? kernel_init_freeable+0x3f1/0x630 [ 1.215340][ T1] ? rcu_is_watching+0x16/0xd0 [ 1.215343][ T1] kernel_init_freeable+0x596/0x630 [ 1.215346][ T1] ? rest_init+0x280/0x280 [ 1.215349][ T1] kernel_init+0x21/0x150 [ 1.215350][ T1] ? rest_init+0x280/0x280 [ 1.215352][ T1] ret_from_fork+0x474/0x6b0 [ 1.215356][ T1] ? arch_exit_to_user_mode_prepare.isra.0+0x120/0x120 [ 1.215359][ T1] ? __switch_to+0x5a3/0xe00 [ 1.215362][ T1] ? rest_init+0x280/0x280 [ 1.215364][ T1] ret_from_fork_asm+0x11/0x20 [ 1.215372][ T1] [ 1.232946][ T1] NET: Registered PF_PPPOX protocol family [ 1.233737][ T1] i8042: PNP: No PS/2 controller found. [ 1.242948][ T1] rtc_cmos PNP0B00:00: registered as rtc0 [ 1.243385][ T1] rtc_cmos PNP0B00:00: setting system clock to 2026-08-28T16:32:29 UTC (1787934749) [ 1.244331][ T1] rtc_cmos PNP0B00:00: alarms up to one day, 242 bytes nvram [ 1.247161][ T1] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev [ 1.253484][ T1] ipip: IPv4 and MPLS over IPv4 tunneling driver [ 1.256460][ T1] gre: GRE over IPv4 demultiplexer driver [ 1.256644][ T1] ip_gre: GRE over IPv4 tunneling driver [ 1.264865][ T1] Initializing XFRM netlink socket [ 1.265467][ T1] NET: Registered PF_INET6 protocol family [ 1.271850][ T1] Segment Routing with IPv6 [ 1.272708][ T1] In-situ OAM (IOAM) with IPv6 [ 1.273112][ T1] sit: IPv6, IPv4 and MPLS over IPv4 tunneling driver [ 1.280146][ T1] ip6_gre: GRE over IPv6 tunneling driver [ 1.283314][ T1] NET: Registered PF_PACKET protocol family [ 1.283647][ T1] 9pnet: Installing 9P2000 support [ 1.284263][ T1] Key type dns_resolver registered [ 1.285082][ T1] NET: Registered PF_VSOCK protocol family [ 1.289654][ T1] IPI shorthand broadcast: enabled [ 1.400715][ T1] sched_clock: Marking stable (1361001920, 38950450)->(1482170610, -82218240) [ 1.403830][ T1] registered taskstats version 1 [ 1.406033][ T1] Loading compiled-in X.509 certificates [ 1.434688][ T68] kwatchdog (68) used greatest stack depth: 29688 bytes left [ 1.510815][ T1] Demotion targets for Node 0: null [ 1.511161][ T1] kmemleak: Kernel memory leak detector initialized (mem pool available: 11963) [ 1.511453][ T1] page_owner is disabled [ 1.520742][ T1] PM: Magic number: 6:293:541 [ 1.523236][ T1] ALSA device list: [ 1.523389][ T1] No soundcards found. [ 1.525366][ T1] md: Skipping autodetection of RAID arrays. (raid=autodetect will force) [ 1.528347][ T1] VFS: Mounted root (virtiofs filesystem) readonly on device 0:24. [ 1.529167][ T1] devtmpfs: mounted [ 1.529466][ T1] VFS: Pivoted into new rootfs [ 1.560988][ T1] Freeing unused kernel image (initmem) memory: 2576K [ 1.561237][ T1] Write protecting the kernel read-only data: 63488k [ 1.561895][ T1] Freeing unused kernel image (text/rodata gap) memory: 864K [ 1.562696][ T1] Freeing unused kernel image (rodata/data gap) memory: 1116K [ 1.564099][ T1] Run /usr/lib/python3.14/site-packages/virtme/guest/bin/virtme-ng-init as init process [ 1.564460][ T1] with arguments: [ 1.564603][ T1] /usr/lib/python3.14/site-packages/virtme/guest/bin/virtme-ng-init [ 1.564880][ T1] with environment: [ 1.565038][ T1] HOME=/ [ 1.565214][ T1] TERM=dumb [ 1.565336][ T1] virtme_hostname=vmksft-drv-hw-dbg,debug-threads=on [ 1.565545][ T1] nr_open=2147483584 [ 1.565665][ T1] virtme_link_mods=/srv/vmksft/testing/wt-22/.virtme_mods/lib/modules/0.0.0 [ 1.565963][ T1] virtme_rw_overlay0=/etc [ 1.566133][ T1] virtme_rw_overlay1=/lib [ 1.566296][ T1] virtme_rw_overlay2=/home [ 1.566460][ T1] virtme_rw_overlay3=/opt [ 1.566618][ T1] virtme_rw_overlay4=/srv [ 1.566781][ T1] virtme_rw_overlay5=/usr [ 1.566941][ T1] virtme_rw_overlay6=/var [ 1.567125][ T1] virtme_rw_overlay7=/tmp [ 1.567286][ T1] virtme_console=ttyS0 [ 1.567455][ T1] virtme_chdir=srv/vmksft/testing/wt-22 [ 1.584567][ T1] virtme-ng-init: mount devtmpfs -> /dev: EBUSY: Device or resource busy [ 1.586264][ T1] virtme-ng-init: Setting hostname to vmksft-drv-hw-dbg,debug-threads=on... [ 1.596146][ T1] overlayfs: failed to set xattr on upper [ 1.596431][ T1] overlayfs: ...falling back to redirect_dir=nofollow. [ 1.596730][ T1] overlayfs: ...falling back to uuid=null. [ 1.599191][ T1] overlayfs: failed to set xattr on upper [ 1.599423][ T1] overlayfs: ...falling back to redirect_dir=nofollow. [ 1.599671][ T1] overlayfs: ...falling back to uuid=null. [ 1.601602][ T1] overlayfs: failed to set xattr on upper [ 1.601804][ T1] overlayfs: ...falling back to redirect_dir=nofollow. [ 1.602070][ T1] overlayfs: ...falling back to uuid=null. [ 1.604020][ T1] overlayfs: failed to set xattr on upper [ 1.604258][ T1] overlayfs: ...falling back to redirect_dir=nofollow. [ 1.604496][ T1] overlayfs: ...falling back to uuid=null. [ 1.606384][ T1] overlayfs: failed to set xattr on upper [ 1.606608][ T1] overlayfs: ...falling back to redirect_dir=nofollow. [ 1.606893][ T1] overlayfs: ...falling back to uuid=null. [ 1.608617][ T1] overlayfs: failed to set xattr on upper [ 1.608862][ T1] overlayfs: ...falling back to redirect_dir=nofollow. [ 1.609761][ T1] overlayfs: ...falling back to uuid=null. [ 1.611712][ T1] overlayfs: failed to set xattr on upper [ 1.611976][ T1] overlayfs: ...falling back to redirect_dir=nofollow. [ 1.612238][ T1] overlayfs: ...falling back to uuid=null. [ 1.614697][ T1] overlayfs: failed to set xattr on upper [ 1.614939][ T1] overlayfs: ...falling back to redirect_dir=nofollow. [ 1.615265][ T1] overlayfs: ...falling back to uuid=null. [ 1.626836][ T1] virtme-ng-init: running systemd-tmpfiles [ 3.934202][ T72] systemd-tmpfile (72) used greatest stack depth: 24560 bytes left [ 3.934221][ T72] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 3.934223][ T72] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 72, name: systemd-tmpfile [ 3.934225][ T72] preempt_count: 2, expected: 0 [ 3.934226][ T72] RCU nest depth: 0, expected: 0 [ 3.934227][ T72] locks held by systemd-tmpfile/72: 5, last CPU#1: [ 3.934229][ T72] #0: ffffffffa76027b8 (low_water_lock){+.+.}-{3:3}, at: do_exit+0x887/0xdc0 [ 3.934241][ T72] #1: ffffffffa7799dc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 3.934246][ T72] #2: ffffffffa7799e38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 3.934250][ T72] #3: ffffffffa7689760 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 3.934254][ T72] #4: ffffffffa7689660 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x202/0x4f0 [ 3.934258][ T72] irq event stamp: 2385424 [ 3.934259][ T72] hardirqs last enabled at (2385423): [] __down_trylock_console_sem+0x86/0xa0 [ 3.934262][ T72] hardirqs last disabled at (2385424): [] console_emit_next_record+0x3f8/0x4f0 [ 3.934264][ T72] softirqs last enabled at (2385276): [] handle_softirqs+0x67c/0x900 [ 3.934266][ T72] softirqs last disabled at (2385271): [] __irq_exit_rcu+0x145/0x1c0 [ 3.934269][ T72] Preemption disabled at: [ 3.934269][ T72] [<0000000000000000>] 0x0 [ 3.934276][ T72] CPU: 1 UID: 0 PID: 72 Comm: systemd-tmpfile Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 3.934280][ T72] Tainted: [W]=WARN [ 3.934282][ T72] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 3.934283][ T72] Call Trace: [ 3.934285][ T72] [ 3.934286][ T72] dump_stack_lvl+0x6f/0xa0 [ 3.934293][ T72] __might_resched.cold+0x1fe/0x2c1 [ 3.934297][ T72] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 3.934301][ T72] ? __kmalloc_noprof+0xdb/0x760 [ 3.934307][ T72] __kmalloc_noprof+0x443/0x760 [ 3.934310][ T72] ? alloc_buf.isra.0+0x4b/0x260 [ 3.934316][ T72] ? do_raw_spin_unlock+0x59/0x250 [ 3.934319][ T72] alloc_buf.isra.0+0x4b/0x260 [ 3.934323][ T72] put_chars+0x1e1/0x2f0 [ 3.934327][ T72] ? __send_to_port+0x420/0x420 [ 3.934329][ T72] ? printk_get_next_message+0x2fe/0x7d0 [ 3.934332][ T72] ? rcu_read_lock_any_held+0x3c/0x90 [ 3.934335][ T72] ? validate_chain+0x38b/0xc20 [ 3.934339][ T72] hvc_console_print+0x292/0x780 [ 3.934344][ T72] ? __lock_acquire+0x518/0xc20 [ 3.934345][ T72] ? __lock_acquire+0x518/0xc20 [ 3.934350][ T72] ? hvc_write+0x3a0/0x3a0 [ 3.934353][ T72] ? rcu_is_watching+0x16/0xd0 [ 3.934357][ T72] ? lock_acquire+0x13c/0x160 [ 3.934361][ T72] console_emit_next_record+0x252/0x4f0 [ 3.934365][ T72] ? devkmsg_read+0x4e0/0x4e0 [ 3.934369][ T72] ? rcu_is_watching+0x16/0xd0 [ 3.934372][ T72] ? lock_acquire+0x13c/0x160 [ 3.934375][ T72] console_flush_one_record+0x46f/0x710 [ 3.934380][ T72] ? console_emit_next_record+0x4f0/0x4f0 [ 3.934381][ T72] ? __lock_acquire+0x518/0xc20 [ 3.934387][ T72] console_unlock+0xee/0x1f0 [ 3.934390][ T72] ? console_flush_one_record+0x710/0x710 [ 3.934391][ T72] ? rcu_is_watching+0x16/0xd0 [ 3.934394][ T72] ? lock_acquire+0xa0/0x160 [ 3.934397][ T72] ? __down_trylock_console_sem+0x5e/0xa0 [ 3.934399][ T72] ? vprintk_emit+0x320/0x3e0 [ 3.934403][ T72] vprintk_emit+0x37c/0x3e0 [ 3.934406][ T72] ? wake_up_klogd_work_func+0x90/0x90 [ 3.934410][ T72] ? __lock_acquire+0x518/0xc20 [ 3.934414][ T72] _printk+0xc7/0x100 [ 3.934417][ T72] ? snapshot_read.cold+0x21/0x21 [ 3.934418][ T1] virtme-ng-init: Failed to read '/usr/lib/tmpfiles.d/audit.conf': Permission denied [ 3.934418][ T1] Failed to create directory or subvolume "/var/spool/at/spool": Permission denied [ 3.934418][ T1] Failed to create file /var/spool/at/.SEQ: Permission denied [ 3.934418][ T1] Failed to opendir() '/proc/self/fd/6': Permission denied [ 3.934420][ T72] ? do_raw_spin_lock+0x131/0x280 [ 3.934423][ T72] ? __rwlock_init+0x150/0x150 [ 3.934426][ T72] ? do_raw_spin_lock+0x131/0x280 [ 3.934429][ T72] do_exit.cold+0x82/0x9c [ 3.934433][ T72] ? exit_notify+0x890/0x890 [ 3.934434][ T72] ? __lock_release.isra.0+0x69/0x1a0 [ 3.934437][ T72] ? rcu_is_watching+0x16/0xd0 [ 3.934441][ T72] do_group_exit+0xb8/0x370 [ 3.934444][ T72] __x64_sys_exit_group+0x3c/0x50 [ 3.934446][ T72] x64_sys_call+0x1567/0x1570 [ 3.934448][ T72] do_syscall_64+0xff/0x530 [ 3.934452][ T72] ? exc_page_fault+0xee/0x100 [ 3.934455][ T72] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 3.934457][ T72] RIP: 0033:0x7fd232bc21b8 [ 3.934460][ T72] Code: Unable to access opcode bytes at 0x7fd232bc218e. [ 3.934461][ T72] RSP: 002b:00007ffe81858f18 EFLAGS: 00000246 ORIG_RAX: 00000000000000e7 [ 3.934463][ T72] RAX: ffffffffffffffda RBX: 00007fd232cf2f88 RCX: 00007fd232bc21b8 [ 3.934464][ T72] RDX: 00007fd2324204c8 RSI: fffffffffffffe90 RDI: 0000000000000049 [ 3.934465][ T72] RBP: 00007ffe81858f70 R08: 0000000000000000 R09: 0000000000001000 [ 3.934466][ T72] R10: 00007ffe81858d30 R11: 0000000000000246 R12: 0000000000000001 [ 3.934467][ T72] R13: 0000000000000049 R14: 00007fd232cf1680 R15: 00007fd232cf2fa0 [ 3.934474][ T72] [ 3.952888][ T1] virtme-ng-init: basic initialization done [ 4.003556][ T77] ip (77) used greatest stack depth: 24368 bytes left [ 4.011404][ T74] virtme-ng-init: Starting systemd-udevd version 259.8-1.fc44 [ 4.011867][ T74] virtme-ng-init: triggering udev coldplug [ 6.197280][ T81] virtio_net virtio3 enp0s2: renamed from eth1 [ 6.197340][ T81] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 6.197343][ T81] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 81, name: (udev-worker) [ 6.197345][ T81] preempt_count: 1, expected: 0 [ 6.197346][ T81] RCU nest depth: 0, expected: 0 [ 6.197347][ T81] locks held by (udev-worker)/81: 5, last CPU#1: [ 6.197349][ T81] #0: ffffffffa7f12c80 (rtnl_mutex){+.+.}-{4:4}, at: rtnl_setlink+0x29d/0x920 [ 6.197362][ T81] #1: ffffffffa7799dc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 6.197368][ T81] #2: ffffffffa7799e38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 6.197372][ T81] #3: ffffffffa7689760 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 6.197376][ T81] #4: ffffffffa7689660 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x202/0x4f0 [ 6.197380][ T81] irq event stamp: 244054 [ 6.197381][ T81] hardirqs last enabled at (244053): [] __down_trylock_console_sem+0x86/0xa0 [ 6.197383][ T81] hardirqs last disabled at (244054): [] console_emit_next_record+0x3f8/0x4f0 [ 6.197385][ T81] softirqs last enabled at (244048): [] netif_change_name+0x216/0x8c0 [ 6.197388][ T81] softirqs last disabled at (244046): [] netif_change_name+0x1ad/0x8c0 [ 6.197390][ T81] Preemption disabled at: [ 6.197391][ T81] [] vprintk_emit+0x31b/0x3e0 [ 6.197397][ T81] CPU: 1 UID: 0 PID: 81 Comm: (udev-worker) Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 6.197401][ T81] Tainted: [W]=WARN [ 6.197402][ T81] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 6.197404][ T81] Call Trace: [ 6.197405][ T81] [ 6.197407][ T81] dump_stack_lvl+0x6f/0xa0 [ 6.197413][ T81] ? vprintk_emit+0x31b/0x3e0 [ 6.197416][ T81] __might_resched.cold+0x1fe/0x2c1 [ 6.197421][ T81] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 6.197425][ T81] ? __kmalloc_noprof+0xdb/0x760 [ 6.197430][ T81] __kmalloc_noprof+0x443/0x760 [ 6.197433][ T81] ? alloc_buf.isra.0+0x4b/0x260 [ 6.197440][ T81] ? do_raw_spin_unlock+0x59/0x250 [ 6.197442][ T81] alloc_buf.isra.0+0x4b/0x260 [ 6.197446][ T81] put_chars+0x1e1/0x2f0 [ 6.197450][ T81] ? __send_to_port+0x420/0x420 [ 6.197454][ T81] ? validate_chain+0x34a/0xc20 [ 6.197458][ T81] hvc_console_print+0x292/0x780 [ 6.197462][ T81] ? __lock_acquire+0x518/0xc20 [ 6.197467][ T81] ? hvc_write+0x3a0/0x3a0 [ 6.197471][ T81] ? rcu_is_watching+0x16/0xd0 [ 6.197478][ T81] console_emit_next_record+0x252/0x4f0 [ 6.197481][ T81] ? devkmsg_read+0x4e0/0x4e0 [ 6.197486][ T81] ? rcu_is_watching+0x16/0xd0 [ 6.197489][ T81] ? lock_acquire+0x13c/0x160 [ 6.197493][ T81] console_flush_one_record+0x46f/0x710 [ 6.197497][ T81] ? console_emit_next_record+0x4f0/0x4f0 [ 6.197499][ T81] ? __lock_acquire+0x518/0xc20 [ 6.197504][ T81] console_unlock+0xee/0x1f0 [ 6.197507][ T81] ? console_flush_one_record+0x710/0x710 [ 6.197509][ T81] ? rcu_is_watching+0x16/0xd0 [ 6.197511][ T81] ? lock_acquire+0xa0/0x160 [ 6.197515][ T81] ? __down_trylock_console_sem+0x5e/0xa0 [ 6.197517][ T81] ? vprintk_emit+0x320/0x3e0 [ 6.197520][ T81] vprintk_emit+0x37c/0x3e0 [ 6.197524][ T81] ? wake_up_klogd_work_func+0x90/0x90 [ 6.197526][ T81] ? write_profile+0xf0/0xf0 [ 6.197529][ T81] ? unwind_get_return_address+0x67/0xd0 [ 6.197535][ T81] dev_vprintk_emit+0x27f/0x2c0 [ 6.197540][ T81] ? device_rename.cold+0xa/0xa [ 6.197544][ T81] ? filter_irq_stacks+0xd0/0xd0 [ 6.197550][ T81] dev_printk_emit+0xb9/0xee [ 6.197552][ T81] ? dev_vprintk_emit+0x2c0/0x2c0 [ 6.197556][ T81] ? check_prev_add+0x316/0xe90 [ 6.197561][ T81] __netdev_printk+0x160/0x1d0 [ 6.197567][ T81] netdev_info+0xe2/0x116 [ 6.197570][ T81] ? netdev_notice+0x120/0x120 [ 6.197572][ T81] ? find_held_lock+0x2b/0x80 [ 6.197576][ T81] ? __lock_release.isra.0+0x69/0x1a0 [ 6.197578][ T81] ? mark_held_locks+0x40/0x70 [ 6.197582][ T81] ? do_setlink.isra.0+0x1f7d/0x2a60 [ 6.197585][ T81] netif_change_name.cold+0x4f/0x89 [ 6.197587][ T81] ? __lock_acquire+0x518/0xc20 [ 6.197589][ T81] ? add_chain_block+0x1e7/0x5b0 [ 6.197591][ T81] ? static_obj+0x52/0x90 [ 6.197594][ T81] ? netdev_adjacent_rename_links+0x470/0x470 [ 6.197596][ T81] ? lock_acquire.part.0+0xd4/0x280 [ 6.197597][ T81] ? find_held_lock+0x2b/0x80 [ 6.197600][ T81] ? __asan_memset+0x27/0x50 [ 6.197605][ T81] do_setlink.isra.0+0x1f7d/0x2a60 [ 6.197608][ T81] ? __lock_acquire+0x518/0xc20 [ 6.197611][ T81] ? rtnl_link_get_size+0x350/0x350 [ 6.197613][ T81] ? mark_usage+0x61/0x170 [ 6.197614][ T81] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 6.197617][ T81] ? rcu_read_lock_any_held+0x3c/0x90 [ 6.197620][ T81] ? validate_chain+0x38b/0xc20 [ 6.197624][ T81] ? __lock_acquire+0x518/0xc20 [ 6.197629][ T81] ? lock_acquire.part.0+0xd4/0x280 [ 6.197631][ T81] ? rtnl_setlink+0x29d/0x920 [ 6.197634][ T81] ? rcu_is_watching+0x16/0xd0 [ 6.197636][ T81] ? lock_acquire+0x13c/0x160 [ 6.197638][ T81] ? rcu_is_watching+0x16/0xd0 [ 6.197639][ T81] ? rcu_is_watching+0x16/0xd0 [ 6.197642][ T81] ? trace_contention_end+0xb3/0x180 [ 6.197645][ T81] ? __mutex_lock+0x1db/0x1ea0 [ 6.197649][ T81] ? __mutex_lock+0x9a3/0x1ea0 [ 6.197650][ T81] ? __lock_acquire+0x518/0xc20 [ 6.197652][ T81] ? rtnl_setlink+0x29d/0x920 [ 6.197654][ T81] ? mark_usage+0x61/0x170 [ 6.197657][ T81] ? ww_mutex_lock+0x160/0x160 [ 6.197659][ T81] ? nla_get_range_signed+0x3d0/0x3d0 [ 6.197665][ T81] ? mark_usage+0x61/0x170 [ 6.197668][ T81] ? cap_capable+0x1d7/0x3d0 [ 6.197675][ T81] rtnl_setlink+0x527/0x920 [ 6.197679][ T81] ? __rtnl_newlink+0xa50/0xa50 [ 6.197680][ T81] ? __lock_acquire+0x518/0xc20 [ 6.197703][ T81] ? lock_acquire.part.0+0xd4/0x280 [ 6.197705][ T81] ? find_held_lock+0x2b/0x80 [ 6.197708][ T81] ? __lock_release.isra.0+0x69/0x1a0 [ 6.197710][ T81] ? mark_usage+0x61/0x170 [ 6.197712][ T81] ? __lock_acquire+0x518/0xc20 [ 6.197716][ T81] ? lock_acquire.part.0+0xd4/0x280 [ 6.197718][ T81] ? find_held_lock+0x2b/0x80 [ 6.197720][ T81] ? __rtnl_newlink+0xa50/0xa50 [ 6.197722][ T81] ? __lock_release.isra.0+0x69/0x1a0 [ 6.197726][ T81] ? __rtnl_newlink+0xa50/0xa50 [ 6.197729][ T81] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 6.197732][ T81] ? rtnl_link_fill+0x900/0x900 [ 6.197734][ T81] ? __lock_acquire+0x518/0xc20 [ 6.197738][ T81] ? lock_acquire.part.0+0xd4/0x280 [ 6.197740][ T81] ? find_held_lock+0x2b/0x80 [ 6.197744][ T81] netlink_rcv_skb+0x14e/0x3a0 [ 6.197747][ T81] ? rtnl_link_fill+0x900/0x900 [ 6.197751][ T81] ? netlink_ack+0xcd0/0xcd0 [ 6.197757][ T81] ? netlink_deliver_tap+0xc5/0x330 [ 6.197759][ T81] ? netlink_deliver_tap+0x13c/0x330 [ 6.197764][ T81] netlink_unicast+0x486/0x750 [ 6.197768][ T81] ? netlink_attachskb+0x810/0x810 [ 6.197771][ T81] ? __lock_acquire+0x518/0xc20 [ 6.197775][ T81] netlink_sendmsg+0x735/0xc60 [ 6.197780][ T81] ? netlink_unicast+0x750/0x750 [ 6.197783][ T81] ? __might_fault+0x97/0x140 [ 6.197787][ T81] ? __might_fault+0x97/0x140 [ 6.197791][ T81] __sys_sendto+0x2aa/0x400 [ 6.197795][ T81] ? __ia32_sys_getpeername+0xd0/0xd0 [ 6.197805][ T81] ? exc_page_fault+0x87/0x100 [ 6.197812][ T81] __x64_sys_sendto+0xe4/0x1f0 [ 6.197813][ T81] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 6.197817][ T81] ? lockdep_hardirqs_on+0x91/0x130 [ 6.197819][ T81] ? do_syscall_64+0xa6/0x530 [ 6.197821][ T81] do_syscall_64+0xff/0x530 [ 6.197822][ T81] ? exc_page_fault+0xee/0x100 [ 6.197825][ T81] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 6.197827][ T81] RIP: 0033:0x7f452bff554e [ 6.197831][ T81] 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.197833][ T81] RSP: 002b:00007ffe8eb305e0 EFLAGS: 00000202 ORIG_RAX: 000000000000002c [ 6.197836][ T81] RAX: ffffffffffffffda RBX: 00005638ed59bf40 RCX: 00007f452bff554e [ 6.197837][ T81] RDX: 000000000000002c RSI: 00005638ed598890 RDI: 0000000000000011 [ 6.197838][ T81] RBP: 00007ffe8eb305f0 R08: 00007ffe8eb30640 R09: 0000000000000080 [ 6.197839][ T81] R10: 0000000000000000 R11: 0000000000000202 R12: 00005638ed6eb8f0 [ 6.197840][ T81] R13: 00007ffe8eb30724 R14: 0000000000000000 R15: 0000000000000000 [ 6.197847][ T81] [ 6.387229][ T74] virtme-ng-init: waiting for udev to settle [ 6.474657][ T81] virtio_net virtio2 enp0s1: renamed from eth0 [ 6.646708][ T98] nfsrahead (98) used greatest stack depth: 23920 bytes left [ 7.252610][ T74] virtme-ng-init: udev is done [ 7.252626][ T74] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 7.252629][ T74] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 74, name: virtme-ng-init [ 7.252631][ T74] preempt_count: 1, expected: 0 [ 7.252632][ T74] RCU nest depth: 0, expected: 0 [ 7.252633][ T74] locks held by virtme-ng-init/74: 4, last CPU#1: [ 7.252635][ T74] #0: ffffffffa7799dc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 7.252647][ T74] #1: ffffffffa7799e38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 7.252651][ T74] #2: ffffffffa7689760 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 7.252656][ T74] #3: ffffffffa7689660 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x202/0x4f0 [ 7.252660][ T74] irq event stamp: 3790 [ 7.252661][ T74] hardirqs last enabled at (3789): [] __down_trylock_console_sem+0x86/0xa0 [ 7.252663][ T74] hardirqs last disabled at (3790): [] console_emit_next_record+0x3f8/0x4f0 [ 7.252665][ T74] softirqs last enabled at (3312): [] fpu_clone+0xd9/0x410 [ 7.252669][ T74] softirqs last disabled at (3310): [] fpu_clone+0x82/0x410 [ 7.252671][ T74] Preemption disabled at: [ 7.252672][ T74] [] vprintk_emit+0x31b/0x3e0 [ 7.252678][ T74] CPU: 1 UID: 0 PID: 74 Comm: virtme-ng-init Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 7.252682][ T74] Tainted: [W]=WARN [ 7.252683][ T74] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 7.252684][ T74] Call Trace: [ 7.252686][ T74] [ 7.252688][ T74] dump_stack_lvl+0x6f/0xa0 [ 7.252693][ T74] ? vprintk_emit+0x31b/0x3e0 [ 7.252696][ T74] __might_resched.cold+0x1fe/0x2c1 [ 7.252700][ T74] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 7.252704][ T74] ? __kmalloc_noprof+0xdb/0x760 [ 7.252709][ T74] __kmalloc_noprof+0x443/0x760 [ 7.252712][ T74] ? alloc_buf.isra.0+0x4b/0x260 [ 7.252718][ T74] ? do_raw_spin_unlock+0x59/0x250 [ 7.252721][ T74] alloc_buf.isra.0+0x4b/0x260 [ 7.252725][ T74] put_chars+0x1e1/0x2f0 [ 7.252728][ T74] ? __send_to_port+0x420/0x420 [ 7.252730][ T74] ? printk_get_next_message+0x2fe/0x7d0 [ 7.252734][ T74] ? rcu_read_lock_any_held+0x3c/0x90 [ 7.252737][ T74] ? validate_chain+0x38b/0xc20 [ 7.252741][ T74] hvc_console_print+0x292/0x780 [ 7.252745][ T74] ? __lock_acquire+0x518/0xc20 [ 7.252747][ T74] ? __lock_acquire+0x518/0xc20 [ 7.252751][ T74] ? hvc_write+0x3a0/0x3a0 [ 7.252755][ T74] ? rcu_is_watching+0x16/0xd0 [ 7.252758][ T74] ? lock_acquire+0x13c/0x160 [ 7.252762][ T74] console_emit_next_record+0x252/0x4f0 [ 7.252766][ T74] ? devkmsg_read+0x4e0/0x4e0 [ 7.252770][ T74] ? rcu_is_watching+0x16/0xd0 [ 7.252773][ T74] ? lock_acquire+0x13c/0x160 [ 7.252777][ T74] console_flush_one_record+0x46f/0x710 [ 7.252781][ T74] ? console_emit_next_record+0x4f0/0x4f0 [ 7.252783][ T74] ? __lock_acquire+0x518/0xc20 [ 7.252788][ T74] console_unlock+0xee/0x1f0 [ 7.252790][ T74] ? console_flush_one_record+0x710/0x710 [ 7.252792][ T74] ? rcu_is_watching+0x16/0xd0 [ 7.252795][ T74] ? lock_acquire+0xa0/0x160 [ 7.252798][ T74] ? __down_trylock_console_sem+0x5e/0xa0 [ 7.252800][ T74] ? vprintk_emit+0x320/0x3e0 [ 7.252803][ T74] vprintk_emit+0x37c/0x3e0 [ 7.252807][ T74] ? wake_up_klogd_work_func+0x90/0x90 [ 7.252810][ T74] ? _copy_from_iter+0x1bb/0x1810 [ 7.252815][ T74] devkmsg_emit.constprop.0+0xbc/0xf1 [ 7.252819][ T74] ? vprintk_emit.cold+0x107/0x107 [ 7.252821][ T74] ? simple_strntoull+0x10f/0x140 [ 7.252826][ T74] ? date_str+0x1e0/0x1e0 [ 7.252829][ T74] ? devkmsg_write+0xd1/0x2c0 [ 7.252833][ T74] devkmsg_write.cold+0x5a/0x8b [ 7.252836][ T74] ? vprintk_default+0x20/0x20 [ 7.252840][ T74] ? vprintk_default+0x20/0x20 [ 7.252843][ T74] new_sync_write+0x33e/0x760 [ 7.252846][ T74] ? kasan_quarantine_put+0x102/0x2b0 [ 7.252850][ T74] ? new_sync_read+0x750/0x750 [ 7.252853][ T74] ? __lock_release.isra.0+0x69/0x1a0 [ 7.252858][ T74] ? __fget_files+0x1e3/0x460 [ 7.252862][ T74] vfs_write+0x6a2/0xbd0 [ 7.252866][ T74] ksys_write+0x116/0x250 [ 7.252869][ T74] ? __ia32_sys_read+0xc0/0xc0 [ 7.252871][ T74] ? rcu_is_watching+0x16/0xd0 [ 7.252875][ T74] do_syscall_64+0xff/0x530 [ 7.252878][ T74] ? exc_page_fault+0xee/0x100 [ 7.252881][ T74] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 7.252884][ T74] RIP: 0033:0x7f9c6adc3ed2 [ 7.252888][ T74] 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.252889][ T74] RSP: 002b:00007f9c6ad0ac68 EFLAGS: 00000246 ORIG_RAX: 0000000000000001 [ 7.252892][ T74] RAX: ffffffffffffffda RBX: 0000000000000020 RCX: 00007f9c6adc3ed2 [ 7.252894][ T74] RDX: 0000000000000020 RSI: 00007f9c64001170 RDI: 0000000000000003 [ 7.252894][ T74] RBP: 00007f9c6ad0ac90 R08: 0000000000000000 R09: 0000000000000000 [ 7.252895][ T74] R10: 0000000000000000 R11: 0000000000000246 R12: 00007f9c6ae4e0a0 [ 7.252896][ T74] R13: 00007f9c6ad4c3e0 R14: 00007f9c64001170 R15: 00007f9c6ad0ada8 [ 7.252904][ T74] [ 7.274360][ T1] virtme-ng-init: initialization done