virtme: waiting for virtiofsd to start virtme: use 'microvm' QEMU architecture [ 1.073337][ T1] PPP generic driver version 2.4.2 [ 1.073364][ T1] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 1.073366][ T1] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 1, name: swapper/0 [ 1.073367][ T1] preempt_count: 1, expected: 0 [ 1.073368][ T1] RCU nest depth: 0, expected: 0 [ 1.073369][ T1] locks held by swapper/0/1: 4, last CPU#0: [ 1.073371][ T1] #0: ffffffff9c199dc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 1.073384][ T1] #1: ffffffff9c199e38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 1.073389][ T1] #2: ffffffff9c089760 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 1.073393][ T1] #3: ffffffff9c089660 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x202/0x4f0 [ 1.073397][ T1] irq event stamp: 355650 [ 1.073398][ T1] hardirqs last enabled at (355649): [] __down_trylock_console_sem+0x86/0xa0 [ 1.073401][ T1] hardirqs last disabled at (355650): [] console_emit_next_record+0x3f8/0x4f0 [ 1.073403][ T1] softirqs last enabled at (355400): [] handle_softirqs+0x67c/0x900 [ 1.073410][ T1] softirqs last disabled at (355391): [] __irq_exit_rcu+0x145/0x1c0 [ 1.073413][ T1] Preemption disabled at: [ 1.073413][ T1] [] vprintk_emit+0x31b/0x3e0 [ 1.073420][ T1] CPU: 0 UID: 0 PID: 1 Comm: swapper/0 Not tainted 7.2.0-virtme #1 PREEMPT(full) [ 1.073424][ T1] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 1.073426][ T1] Call Trace: [ 1.073428][ T1] [ 1.073432][ T1] dump_stack_lvl+0x6f/0xa0 [ 1.073438][ T1] ? vprintk_emit+0x31b/0x3e0 [ 1.073441][ T1] __might_resched.cold+0x1fe/0x2c1 [ 1.073446][ T1] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 1.073451][ T1] ? __kmalloc_noprof+0xdb/0x760 [ 1.073456][ T1] __kmalloc_noprof+0x443/0x760 [ 1.073459][ T1] ? alloc_buf.isra.0+0x4b/0x260 [ 1.073466][ T1] ? do_raw_spin_unlock+0x59/0x250 [ 1.073469][ T1] alloc_buf.isra.0+0x4b/0x260 [ 1.073472][ T1] put_chars+0x1e1/0x2f0 [ 1.073475][ T1] ? printk_get_next_message+0x2fe/0x7d0 [ 1.073477][ T1] ? __send_to_port+0x420/0x420 [ 1.073479][ T1] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 1.073484][ T1] ? validate_chain+0x38b/0xc20 [ 1.073490][ T1] hvc_console_print+0x292/0x780 [ 1.073496][ T1] ? hvc_write+0x3a0/0x3a0 [ 1.073499][ T1] ? rcu_is_watching+0x16/0xd0 [ 1.073501][ T1] ? lock_acquire+0x13c/0x160 [ 1.073505][ T1] console_emit_next_record+0x252/0x4f0 [ 1.073509][ T1] ? devkmsg_read+0x4e0/0x4e0 [ 1.073513][ T1] ? rcu_is_watching+0x16/0xd0 [ 1.073515][ T1] ? lock_acquire+0x13c/0x160 [ 1.073519][ T1] console_flush_one_record+0x46f/0x710 [ 1.073523][ T1] ? console_emit_next_record+0x4f0/0x4f0 [ 1.073525][ T1] ? __lock_acquire+0x518/0xc20 [ 1.073530][ T1] console_unlock+0xee/0x1f0 [ 1.073533][ T1] ? console_flush_one_record+0x710/0x710 [ 1.073534][ T1] ? rcu_is_watching+0x16/0xd0 [ 1.073537][ T1] ? lock_acquire+0xa0/0x160 [ 1.073540][ T1] ? __down_trylock_console_sem+0x5e/0xa0 [ 1.073542][ T1] ? vprintk_emit+0x320/0x3e0 [ 1.073545][ T1] vprintk_emit+0x37c/0x3e0 [ 1.073548][ T1] ? wake_up_klogd_work_func+0x90/0x90 [ 1.073554][ T1] ? vxlan_init_module+0x80/0x80 [ 1.073558][ T1] _printk+0xc7/0x100 [ 1.073561][ T1] ? snapshot_read.cold+0x21/0x21 [ 1.073563][ T1] ? add_chain_block+0x1e0/0x5b0 [ 1.073565][ T1] ? _raw_spin_unlock_irqrestore+0x40/0x80 [ 1.073570][ T1] ? add_device_randomness+0xbb/0x100 [ 1.073573][ T1] ? random_write_iter+0x20/0x20 [ 1.073575][ T1] ? phy_module_init+0x20/0x20 [ 1.073577][ T1] ? do_one_initcall+0x113/0x4f0 [ 1.073580][ T1] ppp_init+0x16/0x100 [ 1.073582][ T1] do_one_initcall+0x124/0x4f0 [ 1.073584][ T1] ? trace_event_raw_event_initcall_level+0x280/0x280 [ 1.073586][ T1] ? parameq+0x110/0x110 [ 1.073591][ T1] ? kernel_init_freeable+0x3f1/0x630 [ 1.073595][ T1] ? rcu_is_watching+0x16/0xd0 [ 1.073598][ T1] kernel_init_freeable+0x596/0x630 [ 1.073601][ T1] ? rest_init+0x280/0x280 [ 1.073604][ T1] kernel_init+0x21/0x150 [ 1.073605][ T1] ? rest_init+0x280/0x280 [ 1.073606][ T1] ret_from_fork+0x474/0x6b0 [ 1.073610][ T1] ? arch_exit_to_user_mode_prepare.isra.0+0x120/0x120 [ 1.073613][ T1] ? __switch_to+0x5a3/0xe00 [ 1.073617][ T1] ? rest_init+0x280/0x280 [ 1.073619][ T1] ret_from_fork_asm+0x11/0x20 [ 1.073626][ T1] [ 1.089117][ T1] NET: Registered PF_PPPOX protocol family [ 1.089892][ T1] i8042: PNP: No PS/2 controller found. [ 1.098422][ T1] rtc_cmos PNP0B00:00: registered as rtc0 [ 1.098813][ T1] rtc_cmos PNP0B00:00: setting system clock to 2026-08-28T01:55:03 UTC (1787882103) [ 1.099694][ T1] rtc_cmos PNP0B00:00: alarms up to one day, 242 bytes nvram [ 1.102335][ T1] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev [ 1.108315][ T1] ipip: IPv4 and MPLS over IPv4 tunneling driver [ 1.111042][ T1] gre: GRE over IPv4 demultiplexer driver [ 1.111206][ T1] ip_gre: GRE over IPv4 tunneling driver [ 1.118669][ T1] Initializing XFRM netlink socket [ 1.119213][ T1] NET: Registered PF_INET6 protocol family [ 1.125798][ T1] Segment Routing with IPv6 [ 1.126188][ T1] In-situ OAM (IOAM) with IPv6 [ 1.126510][ T1] sit: IPv6, IPv4 and MPLS over IPv4 tunneling driver [ 1.132661][ T1] ip6_gre: GRE over IPv6 tunneling driver [ 1.136050][ T1] NET: Registered PF_PACKET protocol family [ 1.136297][ T1] 9pnet: Installing 9P2000 support [ 1.136645][ T1] Key type dns_resolver registered [ 1.137312][ T1] NET: Registered PF_VSOCK protocol family [ 1.141614][ T1] IPI shorthand broadcast: enabled [ 1.245338][ T1] sched_clock: Marking stable (1209002380, 35407377)->(1337661879, -93252122) [ 1.247670][ T1] registered taskstats version 1 [ 1.250720][ T1] Loading compiled-in X.509 certificates [ 1.349042][ T1] Demotion targets for Node 0: null [ 1.349303][ T1] kmemleak: Kernel memory leak detector initialized (mem pool available: 12474) [ 1.349586][ T1] page_owner is disabled [ 1.358354][ T1] PM: Magic number: 6:112:914 [ 1.360614][ T1] ALSA device list: [ 1.361368][ T1] No soundcards found. [ 1.361947][ T1] md: Skipping autodetection of RAID arrays. (raid=autodetect will force) [ 1.364965][ T1] VFS: Mounted root (virtiofs filesystem) readonly on device 0:24. [ 1.365692][ T1] devtmpfs: mounted [ 1.365920][ T1] VFS: Pivoted into new rootfs [ 1.391995][ T71] kwatchdog (71) used greatest stack depth: 29568 bytes left [ 1.393282][ T1] Freeing unused kernel image (initmem) memory: 2576K [ 1.395910][ T1] Write protecting the kernel read-only data: 63488k [ 1.396568][ T1] Freeing unused kernel image (text/rodata gap) memory: 864K [ 1.397089][ T1] Freeing unused kernel image (rodata/data gap) memory: 1116K [ 1.397335][ T1] Run /usr/lib/python3.14/site-packages/virtme/guest/bin/virtme-ng-init as init process [ 1.399425][ T1] with arguments: [ 1.399597][ T1] /usr/lib/python3.14/site-packages/virtme/guest/bin/virtme-ng-init [ 1.399875][ T1] with environment: [ 1.400044][ T1] HOME=/ [ 1.400213][ T1] TERM=dumb [ 1.400363][ T1] virtme_hostname=vmksft-drv-hw-dbg,debug-threads=on [ 1.400620][ T1] nr_open=2147483584 [ 1.400768][ T1] virtme_link_mods=/srv/vmksft/testing/wt-22/.virtme_mods/lib/modules/0.0.0 [ 1.401064][ T1] virtme_rw_overlay0=/etc [ 1.401261][ T1] virtme_rw_overlay1=/lib [ 1.401484][ T1] virtme_rw_overlay2=/home [ 1.401682][ T1] virtme_rw_overlay3=/opt [ 1.401862][ T1] virtme_rw_overlay4=/srv [ 1.402053][ T1] virtme_rw_overlay5=/usr [ 1.402241][ T1] virtme_rw_overlay6=/var [ 1.402455][ T1] virtme_rw_overlay7=/tmp [ 1.402637][ T1] virtme_console=ttyS0 [ 1.402828][ T1] virtme_chdir=srv/vmksft/testing/wt-22 [ 1.417315][ T1] virtme-ng-init: mount devtmpfs -> /dev: EBUSY: Device or resource busy [ 1.418842][ T1] virtme-ng-init: Setting hostname to vmksft-drv-hw-dbg,debug-threads=on... [ 1.430284][ T1] overlayfs: failed to set xattr on upper [ 1.430589][ T1] overlayfs: ...falling back to redirect_dir=nofollow. [ 1.430880][ T1] overlayfs: ...falling back to uuid=null. [ 1.433118][ T1] overlayfs: failed to set xattr on upper [ 1.433339][ T1] overlayfs: ...falling back to redirect_dir=nofollow. [ 1.433703][ T1] overlayfs: ...falling back to uuid=null. [ 1.435558][ T1] overlayfs: failed to set xattr on upper [ 1.435771][ T1] overlayfs: ...falling back to redirect_dir=nofollow. [ 1.436043][ T1] overlayfs: ...falling back to uuid=null. [ 1.437863][ T1] overlayfs: failed to set xattr on upper [ 1.438106][ T1] overlayfs: ...falling back to redirect_dir=nofollow. [ 1.438375][ T1] overlayfs: ...falling back to uuid=null. [ 1.440256][ T1] overlayfs: failed to set xattr on upper [ 1.441000][ T1] overlayfs: ...falling back to redirect_dir=nofollow. [ 1.441285][ T1] overlayfs: ...falling back to uuid=null. [ 1.442993][ T1] overlayfs: failed to set xattr on upper [ 1.443214][ T1] overlayfs: ...falling back to redirect_dir=nofollow. [ 1.443489][ T1] overlayfs: ...falling back to uuid=null. [ 1.445319][ T1] overlayfs: failed to set xattr on upper [ 1.446067][ T1] overlayfs: ...falling back to redirect_dir=nofollow. [ 1.446346][ T1] overlayfs: ...falling back to uuid=null. [ 1.448196][ T1] overlayfs: failed to set xattr on upper [ 1.448608][ T1] overlayfs: ...falling back to redirect_dir=nofollow. [ 1.448875][ T1] overlayfs: ...falling back to uuid=null. [ 1.458702][ T1] virtme-ng-init: running systemd-tmpfiles [ 3.479982][ T72] systemd-tmpfile (72) used greatest stack depth: 24880 bytes left [ 3.479998][ T72] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 3.480000][ T72] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 72, name: systemd-tmpfile [ 3.480002][ T72] preempt_count: 2, expected: 0 [ 3.480003][ T72] RCU nest depth: 0, expected: 0 [ 3.480004][ T72] locks held by systemd-tmpfile/72: 5, last CPU#1: [ 3.480006][ T72] #0: ffffffff9c0027b8 (low_water_lock){+.+.}-{3:3}, at: do_exit+0x887/0xdc0 [ 3.480017][ T72] #1: ffffffff9c199dc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 3.480023][ T72] #2: ffffffff9c199e38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 3.480027][ T72] #3: ffffffff9c089760 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 3.480031][ T72] #4: ffffffff9c089660 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x202/0x4f0 [ 3.480034][ T72] irq event stamp: 2343334 [ 3.480035][ T72] hardirqs last enabled at (2343333): [] __down_trylock_console_sem+0x86/0xa0 [ 3.480038][ T72] hardirqs last disabled at (2343334): [] console_emit_next_record+0x3f8/0x4f0 [ 3.480039][ T72] softirqs last enabled at (2343014): [] handle_softirqs+0x67c/0x900 [ 3.480042][ T72] softirqs last disabled at (2342969): [] __irq_exit_rcu+0x145/0x1c0 [ 3.480044][ T72] Preemption disabled at: [ 3.480044][ T72] [<0000000000000000>] 0x0 [ 3.480051][ T72] CPU: 1 UID: 0 PID: 72 Comm: systemd-tmpfile Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 3.480055][ T72] Tainted: [W]=WARN [ 3.480056][ T72] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 3.480057][ T72] Call Trace: [ 3.480059][ T72] [ 3.480061][ T72] dump_stack_lvl+0x6f/0xa0 [ 3.480067][ T72] __might_resched.cold+0x1fe/0x2c1 [ 3.480072][ T72] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 3.480075][ T72] ? __kmalloc_noprof+0xdb/0x760 [ 3.480080][ T72] __kmalloc_noprof+0x443/0x760 [ 3.480083][ T72] ? alloc_buf.isra.0+0x4b/0x260 [ 3.480090][ T72] ? do_raw_spin_unlock+0x59/0x250 [ 3.480092][ T72] alloc_buf.isra.0+0x4b/0x260 [ 3.480095][ T72] put_chars+0x1e1/0x2f0 [ 3.480099][ T72] ? __send_to_port+0x420/0x420 [ 3.480101][ T72] ? printk_get_next_message+0x2fe/0x7d0 [ 3.480104][ T72] ? rcu_read_lock_any_held+0x3c/0x90 [ 3.480107][ T72] ? validate_chain+0x38b/0xc20 [ 3.480111][ T72] hvc_console_print+0x292/0x780 [ 3.480114][ T72] ? __lock_acquire+0x518/0xc20 [ 3.480116][ T72] ? __lock_acquire+0x518/0xc20 [ 3.480120][ T72] ? hvc_write+0x3a0/0x3a0 [ 3.480124][ T72] ? rcu_is_watching+0x16/0xd0 [ 3.480127][ T72] ? lock_acquire+0x13c/0x160 [ 3.480131][ T72] console_emit_next_record+0x252/0x4f0 [ 3.480135][ T72] ? devkmsg_read+0x4e0/0x4e0 [ 3.480139][ T72] ? rcu_is_watching+0x16/0xd0 [ 3.480142][ T72] ? lock_acquire+0x13c/0x160 [ 3.480145][ T72] console_flush_one_record+0x46f/0x710 [ 3.480149][ T72] ? console_emit_next_record+0x4f0/0x4f0 [ 3.480151][ T72] ? __lock_acquire+0x518/0xc20 [ 3.480156][ T72] console_unlock+0xee/0x1f0 [ 3.480159][ T72] ? console_flush_one_record+0x710/0x710 [ 3.480161][ T72] ? rcu_is_watching+0x16/0xd0 [ 3.480163][ T72] ? lock_acquire+0xa0/0x160 [ 3.480167][ T72] ? __down_trylock_console_sem+0x5e/0xa0 [ 3.480168][ T72] ? vprintk_emit+0x320/0x3e0 [ 3.480172][ T72] vprintk_emit+0x37c/0x3e0 [ 3.480183][ T72] ? wake_up_klogd_work_func+0x90/0x90 [ 3.480186][ T72] ? __lock_acquire+0x518/0xc20 [ 3.480190][ T72] _printk+0xc7/0x100 [ 3.480193][ T72] ? snapshot_read.cold+0x21/0x21 [ 3.480196][ T72] ? do_raw_spin_lock+0x131/0x280 [ 3.480199][ T72] ? __rwlock_init+0x150/0x150 [ 3.480202][ T72] ? do_raw_spin_lock+0x131/0x280 [ 3.480205][ T72] do_exit.cold+0x82/0x9c [ 3.480207][ T1] virtme-ng-init: Failed to read '/usr/lib/tmpfiles.d/audit.conf': Permission denied [ 3.480207][ T1] Failed to create directory or subvolume "/var/spool/at/spool": Permission denied [ 3.480207][ T1] Failed to create file /var/spool/at/.SEQ: Permission denied [ 3.480207][ T1] Failed to opendir() '/proc/self/fd/6': Permission denied [ 3.480209][ T72] ? exit_notify+0x890/0x890 [ 3.480210][ T72] ? __lock_release.isra.0+0x69/0x1a0 [ 3.480213][ T72] ? rcu_is_watching+0x16/0xd0 [ 3.480217][ T72] do_group_exit+0xb8/0x370 [ 3.480219][ T72] __x64_sys_exit_group+0x3c/0x50 [ 3.480221][ T72] x64_sys_call+0x1567/0x1570 [ 3.480224][ T72] do_syscall_64+0xff/0x530 [ 3.480227][ T72] ? exc_page_fault+0xee/0x100 [ 3.480230][ T72] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 3.480233][ T72] RIP: 0033:0x7f3b38d921b8 [ 3.480235][ T72] Code: Unable to access opcode bytes at 0x7f3b38d9218e. [ 3.480236][ T72] RSP: 002b:00007ffe92309e98 EFLAGS: 00000246 ORIG_RAX: 00000000000000e7 [ 3.480238][ T72] RAX: ffffffffffffffda RBX: 00007f3b38ec2f88 RCX: 00007f3b38d921b8 [ 3.480239][ T72] RDX: 00007f3b385f04c8 RSI: fffffffffffffe90 RDI: 0000000000000049 [ 3.480240][ T72] RBP: 00007ffe92309ef0 R08: 0000000000000000 R09: 0000000000001000 [ 3.480241][ T72] R10: 00007ffe92309cb0 R11: 0000000000000246 R12: 0000000000000001 [ 3.480242][ T72] R13: 0000000000000049 R14: 00007f3b38ec1680 R15: 00007f3b38ec2fa0 [ 3.480249][ T72] [ 3.497291][ T1] virtme-ng-init: basic initialization done [ 3.507793][ T74] virtme-ng-init (74) used greatest stack depth: 24800 bytes left [ 3.535180][ T76] ip (76) used greatest stack depth: 24584 bytes left [ 3.553817][ T73] virtme-ng-init: Starting systemd-udevd version 259.8-1.fc44 [ 3.554186][ T73] virtme-ng-init: triggering udev coldplug [ 5.647724][ T86] virtio_net virtio2 enp0s1: renamed from eth0 [ 5.647779][ T86] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 5.647782][ T86] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 86, name: (udev-worker) [ 5.647783][ T86] preempt_count: 1, expected: 0 [ 5.647785][ T86] RCU nest depth: 0, expected: 0 [ 5.647786][ T86] locks held by (udev-worker)/86: 5, last CPU#0: [ 5.647788][ T86] #0: ffffffff9c912c80 (rtnl_mutex){+.+.}-{4:4}, at: rtnl_setlink+0x29d/0x920 [ 5.647800][ T86] #1: ffffffff9c199dc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 5.647807][ T86] #2: ffffffff9c199e38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 5.647810][ T86] #3: ffffffff9c089760 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 5.647814][ T86] #4: ffffffff9c089660 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x202/0x4f0 [ 5.647818][ T86] irq event stamp: 231356 [ 5.647819][ T86] hardirqs last enabled at (231355): [] __down_trylock_console_sem+0x86/0xa0 [ 5.647822][ T86] hardirqs last disabled at (231356): [] console_emit_next_record+0x3f8/0x4f0 [ 5.647824][ T86] softirqs last enabled at (231350): [] netif_change_name+0x216/0x8c0 [ 5.647826][ T86] softirqs last disabled at (231348): [] netif_change_name+0x1ad/0x8c0 [ 5.647829][ T86] Preemption disabled at: [ 5.647829][ T86] [] vprintk_emit+0x31b/0x3e0 [ 5.647835][ T86] CPU: 0 UID: 0 PID: 86 Comm: (udev-worker) Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 5.647839][ T86] Tainted: [W]=WARN [ 5.647840][ T86] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 5.647842][ T86] Call Trace: [ 5.647843][ T86] [ 5.647845][ T86] dump_stack_lvl+0x6f/0xa0 [ 5.647851][ T86] ? vprintk_emit+0x31b/0x3e0 [ 5.647853][ T86] __might_resched.cold+0x1fe/0x2c1 [ 5.647858][ T86] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 5.647862][ T86] ? __kmalloc_noprof+0xdb/0x760 [ 5.647867][ T86] __kmalloc_noprof+0x443/0x760 [ 5.647870][ T86] ? alloc_buf.isra.0+0x4b/0x260 [ 5.647876][ T86] ? do_raw_spin_unlock+0x59/0x250 [ 5.647879][ T86] alloc_buf.isra.0+0x4b/0x260 [ 5.647883][ T86] put_chars+0x1e1/0x2f0 [ 5.647887][ T86] ? __send_to_port+0x420/0x420 [ 5.647891][ T86] ? validate_chain+0x34a/0xc20 [ 5.647896][ T86] hvc_console_print+0x292/0x780 [ 5.647899][ T86] ? __lock_acquire+0x518/0xc20 [ 5.647905][ T86] ? hvc_write+0x3a0/0x3a0 [ 5.647908][ T86] ? rcu_is_watching+0x16/0xd0 [ 5.647915][ T86] console_emit_next_record+0x252/0x4f0 [ 5.647919][ T86] ? devkmsg_read+0x4e0/0x4e0 [ 5.647924][ T86] ? rcu_is_watching+0x16/0xd0 [ 5.647926][ T86] ? lock_acquire+0x13c/0x160 [ 5.647930][ T86] console_flush_one_record+0x46f/0x710 [ 5.647935][ T86] ? console_emit_next_record+0x4f0/0x4f0 [ 5.647937][ T86] ? __lock_acquire+0x518/0xc20 [ 5.647942][ T86] console_unlock+0xee/0x1f0 [ 5.647945][ T86] ? console_flush_one_record+0x710/0x710 [ 5.647947][ T86] ? rcu_is_watching+0x16/0xd0 [ 5.647949][ T86] ? lock_acquire+0xa0/0x160 [ 5.647953][ T86] ? __down_trylock_console_sem+0x5e/0xa0 [ 5.647955][ T86] ? vprintk_emit+0x320/0x3e0 [ 5.647958][ T86] vprintk_emit+0x37c/0x3e0 [ 5.647962][ T86] ? wake_up_klogd_work_func+0x90/0x90 [ 5.647965][ T86] ? write_profile+0xf0/0xf0 [ 5.647967][ T86] ? unwind_get_return_address+0x67/0xd0 [ 5.647973][ T86] dev_vprintk_emit+0x27f/0x2c0 [ 5.647978][ T86] ? device_rename.cold+0xa/0xa [ 5.647983][ T86] ? filter_irq_stacks+0xd0/0xd0 [ 5.647988][ T86] dev_printk_emit+0xb9/0xee [ 5.647991][ T86] ? dev_vprintk_emit+0x2c0/0x2c0 [ 5.647995][ T86] ? check_prev_add+0x316/0xe90 [ 5.648000][ T86] __netdev_printk+0x160/0x1d0 [ 5.648007][ T86] netdev_info+0xe2/0x116 [ 5.648009][ T86] ? netdev_notice+0x120/0x120 [ 5.648012][ T86] ? find_held_lock+0x2b/0x80 [ 5.648015][ T86] ? __lock_release.isra.0+0x69/0x1a0 [ 5.648018][ T86] ? mark_held_locks+0x40/0x70 [ 5.648021][ T86] ? do_setlink.isra.0+0x1f7d/0x2a60 [ 5.648024][ T86] netif_change_name.cold+0x4f/0x89 [ 5.648027][ T86] ? __lock_acquire+0x518/0xc20 [ 5.648029][ T86] ? add_chain_block+0x1e7/0x5b0 [ 5.648030][ T86] ? static_obj+0x52/0x90 [ 5.648033][ T86] ? netdev_adjacent_rename_links+0x470/0x470 [ 5.648035][ T86] ? lock_acquire.part.0+0xd4/0x280 [ 5.648037][ T86] ? find_held_lock+0x2b/0x80 [ 5.648040][ T86] ? __asan_memset+0x27/0x50 [ 5.648045][ T86] do_setlink.isra.0+0x1f7d/0x2a60 [ 5.648048][ T86] ? __lock_acquire+0x518/0xc20 [ 5.648051][ T86] ? rtnl_link_get_size+0x350/0x350 [ 5.648053][ T86] ? mark_usage+0x61/0x170 [ 5.648054][ T86] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 5.648057][ T86] ? rcu_read_lock_any_held+0x3c/0x90 [ 5.648060][ T86] ? validate_chain+0x38b/0xc20 [ 5.648064][ T86] ? __lock_acquire+0x518/0xc20 [ 5.648069][ T86] ? lock_acquire.part.0+0xd4/0x280 [ 5.648071][ T86] ? rtnl_setlink+0x29d/0x920 [ 5.648074][ T86] ? rcu_is_watching+0x16/0xd0 [ 5.648077][ T86] ? lock_acquire+0x13c/0x160 [ 5.648078][ T86] ? rcu_is_watching+0x16/0xd0 [ 5.648080][ T86] ? rcu_is_watching+0x16/0xd0 [ 5.648082][ T86] ? trace_contention_end+0xb3/0x180 [ 5.648086][ T86] ? __mutex_lock+0x1db/0x1ea0 [ 5.648089][ T86] ? __mutex_lock+0x9a3/0x1ea0 [ 5.648091][ T86] ? __lock_acquire+0x518/0xc20 [ 5.648093][ T86] ? rtnl_setlink+0x29d/0x920 [ 5.648096][ T86] ? mark_usage+0x61/0x170 [ 5.648098][ T86] ? ww_mutex_lock+0x160/0x160 [ 5.648100][ T86] ? nla_get_range_signed+0x3d0/0x3d0 [ 5.648106][ T86] ? mark_usage+0x61/0x170 [ 5.648110][ T86] ? cap_capable+0x1d7/0x3d0 [ 5.648117][ T86] rtnl_setlink+0x527/0x920 [ 5.648121][ T86] ? __rtnl_newlink+0xa50/0xa50 [ 5.648122][ T86] ? __lock_acquire+0x518/0xc20 [ 5.648147][ T86] ? lock_acquire.part.0+0xd4/0x280 [ 5.648149][ T86] ? find_held_lock+0x2b/0x80 [ 5.648151][ T86] ? __lock_release.isra.0+0x69/0x1a0 [ 5.648153][ T86] ? mark_usage+0x61/0x170 [ 5.648155][ T86] ? __lock_acquire+0x518/0xc20 [ 5.648160][ T86] ? lock_acquire.part.0+0xd4/0x280 [ 5.648162][ T86] ? find_held_lock+0x2b/0x80 [ 5.648164][ T86] ? __rtnl_newlink+0xa50/0xa50 [ 5.648166][ T86] ? __lock_release.isra.0+0x69/0x1a0 [ 5.648170][ T86] ? __rtnl_newlink+0xa50/0xa50 [ 5.648173][ T86] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 5.648176][ T86] ? rtnl_link_fill+0x900/0x900 [ 5.648178][ T86] ? __lock_acquire+0x518/0xc20 [ 5.648188][ T86] ? lock_acquire.part.0+0xd4/0x280 [ 5.648190][ T86] ? find_held_lock+0x2b/0x80 [ 5.648193][ T86] netlink_rcv_skb+0x14e/0x3a0 [ 5.648197][ T86] ? rtnl_link_fill+0x900/0x900 [ 5.648200][ T86] ? netlink_ack+0xcd0/0xcd0 [ 5.648207][ T86] ? netlink_deliver_tap+0xc5/0x330 [ 5.648209][ T86] ? netlink_deliver_tap+0x13c/0x330 [ 5.648214][ T86] netlink_unicast+0x486/0x750 [ 5.648218][ T86] ? netlink_attachskb+0x810/0x810 [ 5.648220][ T86] ? __lock_acquire+0x518/0xc20 [ 5.648225][ T86] netlink_sendmsg+0x735/0xc60 [ 5.648230][ T86] ? netlink_unicast+0x750/0x750 [ 5.648233][ T86] ? __might_fault+0x97/0x140 [ 5.648237][ T86] ? __might_fault+0x97/0x140 [ 5.648241][ T86] __sys_sendto+0x2aa/0x400 [ 5.648245][ T86] ? __ia32_sys_getpeername+0xd0/0xd0 [ 5.648255][ T86] ? exc_page_fault+0x87/0x100 [ 5.648261][ T86] __x64_sys_sendto+0xe4/0x1f0 [ 5.648263][ T86] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 5.648266][ T86] ? lockdep_hardirqs_on+0x91/0x130 [ 5.648268][ T86] ? do_syscall_64+0xa6/0x530 [ 5.648270][ T86] do_syscall_64+0xff/0x530 [ 5.648272][ T86] ? exc_page_fault+0xee/0x100 [ 5.648275][ T86] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 5.648277][ T86] RIP: 0033:0x7fdb4668754e [ 5.648281][ T86] 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 [ 5.648283][ T86] RSP: 002b:00007ffee8a9b040 EFLAGS: 00000202 ORIG_RAX: 000000000000002c [ 5.648286][ T86] RAX: ffffffffffffffda RBX: 000055e4838f8f40 RCX: 00007fdb4668754e [ 5.648287][ T86] RDX: 000000000000002c RSI: 000055e483a48bf0 RDI: 000000000000001e [ 5.648288][ T86] RBP: 00007ffee8a9b050 R08: 00007ffee8a9b0a0 R09: 0000000000000080 [ 5.648289][ T86] R10: 0000000000000000 R11: 0000000000000202 R12: 000055e483a4afa0 [ 5.648289][ T86] R13: 00007ffee8a9b184 R14: 0000000000000000 R15: 0000000000000000 [ 5.648296][ T86] [ 5.696428][ T89] virtio_net virtio3 enp0s2: renamed from eth1 [ 5.722124][ T73] virtme-ng-init: waiting for udev to settle [ 6.380131][ T73] virtme-ng-init: udev is done [ 6.383844][ T1] virtme-ng-init: initialization done [ 8.109533][ T138] ip (138) used greatest stack depth: 24568 bytes left [ 8.109550][ T138] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 8.109552][ T138] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 138, name: ip [ 8.109554][ T138] preempt_count: 2, expected: 0 [ 8.109555][ T138] RCU nest depth: 0, expected: 0 [ 8.109556][ T138] locks held by ip/138: 5, last CPU#0: [ 8.109558][ T138] #0: ffffffff9c0027b8 (low_water_lock){+.+.}-{3:3}, at: do_exit+0x887/0xdc0 [ 8.109569][ T138] #1: ffffffff9c199dc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 8.109575][ T138] #2: ffffffff9c199e38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 8.109579][ T138] #3: ffffffff9c089760 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 8.109583][ T138] #4: ffffffff9c089660 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x202/0x4f0 [ 8.109586][ T138] irq event stamp: 28084 [ 8.109587][ T138] hardirqs last enabled at (28083): [] __down_trylock_console_sem+0x86/0xa0 [ 8.109589][ T138] hardirqs last disabled at (28084): [] console_emit_next_record+0x3f8/0x4f0 [ 8.109591][ T138] softirqs last enabled at (27122): [] handle_softirqs+0x67c/0x900 [ 8.109593][ T138] softirqs last disabled at (27117): [] __irq_exit_rcu+0x145/0x1c0 [ 8.109596][ T138] Preemption disabled at: [ 8.109596][ T138] [<0000000000000000>] 0x0 [ 8.109603][ T138] CPU: 0 UID: 0 PID: 138 Comm: ip Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 8.109607][ T138] Tainted: [W]=WARN [ 8.109607][ T138] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 8.109609][ T138] Call Trace: [ 8.109610][ T138] [ 8.109612][ T138] dump_stack_lvl+0x6f/0xa0 [ 8.109618][ T138] __might_resched.cold+0x1fe/0x2c1 [ 8.109623][ T138] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 8.109626][ T138] ? __kmalloc_noprof+0xdb/0x760 [ 8.109632][ T138] __kmalloc_noprof+0x443/0x760 [ 8.109634][ T138] ? alloc_buf.isra.0+0x4b/0x260 [ 8.109641][ T138] ? do_raw_spin_unlock+0x59/0x250 [ 8.109643][ T138] alloc_buf.isra.0+0x4b/0x260 [ 8.109647][ T138] put_chars+0x1e1/0x2f0 [ 8.109651][ T138] ? __send_to_port+0x420/0x420 [ 8.109653][ T138] ? printk_get_next_message+0x2fe/0x7d0 [ 8.109656][ T138] ? rcu_read_lock_any_held+0x3c/0x90 [ 8.109659][ T138] ? validate_chain+0x38b/0xc20 [ 8.109663][ T138] hvc_console_print+0x292/0x780 [ 8.109667][ T138] ? __lock_acquire+0x518/0xc20 [ 8.109668][ T138] ? __lock_acquire+0x518/0xc20 [ 8.109673][ T138] ? hvc_write+0x3a0/0x3a0 [ 8.109676][ T138] ? rcu_is_watching+0x16/0xd0 [ 8.109680][ T138] ? lock_acquire+0x13c/0x160 [ 8.109684][ T138] console_emit_next_record+0x252/0x4f0 [ 8.109687][ T138] ? devkmsg_read+0x4e0/0x4e0 [ 8.109692][ T138] ? rcu_is_watching+0x16/0xd0 [ 8.109694][ T138] ? lock_acquire+0x13c/0x160 [ 8.109698][ T138] console_flush_one_record+0x46f/0x710 [ 8.109702][ T138] ? console_emit_next_record+0x4f0/0x4f0 [ 8.109704][ T138] ? __lock_acquire+0x518/0xc20 [ 8.109709][ T138] console_unlock+0xee/0x1f0 [ 8.109712][ T138] ? console_flush_one_record+0x710/0x710 [ 8.109714][ T138] ? rcu_is_watching+0x16/0xd0 [ 8.109716][ T138] ? lock_acquire+0xa0/0x160 [ 8.109719][ T138] ? __down_trylock_console_sem+0x5e/0xa0 [ 8.109721][ T138] ? vprintk_emit+0x320/0x3e0 [ 8.109724][ T138] vprintk_emit+0x37c/0x3e0 [ 8.109728][ T138] ? wake_up_klogd_work_func+0x90/0x90 [ 8.109732][ T138] ? __lock_acquire+0x518/0xc20 [ 8.109735][ T138] _printk+0xc7/0x100 [ 8.109739][ T138] ? snapshot_read.cold+0x21/0x21 [ 8.109742][ T138] ? do_raw_spin_lock+0x131/0x280 [ 8.109744][ T138] ? __rwlock_init+0x150/0x150 [ 8.109748][ T138] ? do_raw_spin_lock+0x131/0x280 [ 8.109751][ T138] do_exit.cold+0x82/0x9c [ 8.109755][ T138] ? exit_notify+0x890/0x890 [ 8.109756][ T138] ? __lock_release.isra.0+0x69/0x1a0 [ 8.109758][ T138] ? rcu_is_watching+0x16/0xd0 [ 8.109763][ T138] do_group_exit+0xb8/0x370 [ 8.109765][ T138] __x64_sys_exit_group+0x3c/0x50 [ 8.109767][ T138] x64_sys_call+0x1567/0x1570 [ 8.109769][ T138] do_syscall_64+0xff/0x530 [ 8.109773][ T138] ? exc_page_fault+0xee/0x100 [ 8.109776][ T138] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 8.109778][ T138] RIP: 0033:0x7ff762ea41b8 [ 8.109780][ T138] Code: Unable to access opcode bytes at 0x7ff762ea418e. [ 8.109781][ T138] RSP: 002b:00007ffef4d03188 EFLAGS: 00000246 ORIG_RAX: 00000000000000e7 [ 8.109784][ T138] RAX: ffffffffffffffda RBX: 00007ff762fd4f88 RCX: 00007ff762ea41b8 [ 8.109785][ T138] RDX: 00007ff762bedb48 RSI: fffffffffffffeb8 RDI: 0000000000000000 [ 8.109786][ T138] RBP: 00007ffef4d031e0 R08: 0000000000000000 R09: 0000000000000050 [ 8.109787][ T138] R10: 00007ffef4d02fa0 R11: 0000000000000246 R12: 0000000000000001 [ 8.109787][ T138] R13: 0000000000000000 R14: 00007ff762fd3680 R15: 00007ff762fd4fa0 [ 8.109794][ T138]