virtme: waiting for virtiofsd to start virtme: use 'microvm' QEMU architecture [ 1.129649][ T1] IPI shorthand broadcast: enabled [ 1.129670][ T1] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 1.129672][ T1] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 1, name: swapper/0 [ 1.129673][ T1] preempt_count: 1, expected: 0 [ 1.129674][ T1] RCU nest depth: 0, expected: 0 [ 1.129675][ T1] locks held by swapper/0/1: 4, last CPU#1: [ 1.129677][ T1] #0: ffffffffaed99cc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 1.129688][ T1] #1: ffffffffaed99d38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 1.129693][ T1] #2: ffffffffaec89660 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 1.129697][ T1] #3: ffffffffaec89560 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x202/0x4f0 [ 1.129701][ T1] irq event stamp: 323384 [ 1.129702][ T1] hardirqs last enabled at (323383): [] __down_trylock_console_sem+0x86/0xa0 [ 1.129705][ T1] hardirqs last disabled at (323384): [] console_emit_next_record+0x3f8/0x4f0 [ 1.129707][ T1] softirqs last enabled at (323298): [] handle_softirqs+0x67c/0x900 [ 1.129710][ T1] softirqs last disabled at (323225): [] __irq_exit_rcu+0x145/0x1c0 [ 1.129713][ T1] Preemption disabled at: [ 1.129713][ T1] [] vprintk_emit+0x31b/0x3e0 [ 1.129718][ T1] CPU: 1 UID: 0 PID: 1 Comm: swapper/0 Not tainted 7.2.0-virtme #1 PREEMPT(full) [ 1.129720][ T1] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 1.129722][ T1] Call Trace: [ 1.129724][ T1] [ 1.129727][ T1] dump_stack_lvl+0x6f/0xa0 [ 1.129733][ T1] ? vprintk_emit+0x31b/0x3e0 [ 1.129735][ T1] __might_resched.cold+0x1fe/0x2c1 [ 1.129739][ T1] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 1.129743][ T1] ? __kmalloc_noprof+0xdb/0x760 [ 1.129749][ T1] __kmalloc_noprof+0x443/0x760 [ 1.129752][ T1] ? alloc_buf.isra.0+0x4b/0x260 [ 1.129758][ T1] ? do_raw_spin_unlock+0x59/0x250 [ 1.129760][ T1] alloc_buf.isra.0+0x4b/0x260 [ 1.129764][ T1] put_chars+0x1e1/0x2f0 [ 1.129766][ T1] ? __send_to_port+0x420/0x420 [ 1.129770][ T1] ? validate_chain+0x38b/0xc20 [ 1.129775][ T1] hvc_console_print+0x292/0x780 [ 1.129781][ T1] ? hvc_write+0x3a0/0x3a0 [ 1.129784][ T1] ? rcu_is_watching+0x16/0xd0 [ 1.129787][ T1] ? lock_acquire+0x13c/0x160 [ 1.129791][ T1] console_emit_next_record+0x252/0x4f0 [ 1.129794][ T1] ? devkmsg_read+0x4e0/0x4e0 [ 1.129799][ T1] ? rcu_is_watching+0x16/0xd0 [ 1.129800][ T1] ? lock_acquire+0x13c/0x160 [ 1.129804][ T1] console_flush_one_record+0x46f/0x710 [ 1.129808][ T1] ? console_emit_next_record+0x4f0/0x4f0 [ 1.129810][ T1] ? __lock_acquire+0x518/0xc20 [ 1.129815][ T1] console_unlock+0xee/0x1f0 [ 1.129818][ T1] ? console_flush_one_record+0x710/0x710 [ 1.129820][ T1] ? rcu_is_watching+0x16/0xd0 [ 1.129822][ T1] ? lock_acquire+0x60/0x160 [ 1.129825][ T1] ? __down_trylock_console_sem+0x5e/0xa0 [ 1.129827][ T1] ? vprintk_emit+0x320/0x3e0 [ 1.129830][ T1] vprintk_emit+0x37c/0x3e0 [ 1.129833][ T1] ? wake_up_klogd_work_func+0x90/0x90 [ 1.129837][ T1] ? apic_ipi_shorthand+0xa0/0xa0 [ 1.129841][ T1] _printk+0xc7/0x100 [ 1.129845][ T1] ? snapshot_read.cold+0x21/0x21 [ 1.129846][ T1] ? lockdep_hardirqs_on_prepare.part.0+0x9a/0x160 [ 1.129849][ T1] ? add_device_randomness+0xbb/0x100 [ 1.129852][ T1] ? random_write_iter+0x20/0x20 [ 1.129854][ T1] ? insert_resource+0x3c/0x50 [ 1.129857][ T1] ? do_one_initcall+0x113/0x4f0 [ 1.129860][ T1] print_ipi_mode+0x2e/0x40 [ 1.129862][ T1] do_one_initcall+0x124/0x4f0 [ 1.129864][ T1] ? trace_event_raw_event_initcall_level+0x280/0x280 [ 1.129866][ T1] ? parameq+0x110/0x110 [ 1.129871][ T1] ? kernel_init_freeable+0x3f1/0x630 [ 1.129875][ T1] ? rcu_is_watching+0x16/0xd0 [ 1.129878][ T1] kernel_init_freeable+0x596/0x630 [ 1.129881][ T1] ? rest_init+0x280/0x280 [ 1.129885][ T1] kernel_init+0x21/0x150 [ 1.129887][ T1] ? rest_init+0x280/0x280 [ 1.129889][ T1] ret_from_fork+0x474/0x6b0 [ 1.129893][ T1] ? arch_exit_to_user_mode_prepare.isra.0+0x120/0x120 [ 1.129896][ T1] ? __switch_to+0x5a3/0xe00 [ 1.129899][ T1] ? rest_init+0x280/0x280 [ 1.129902][ T1] ret_from_fork_asm+0x11/0x20 [ 1.129909][ T1] [ 1.251175][ T1] sched_clock: Marking stable (1216002403, 34517297)->(1331326574, -80806874) [ 1.253474][ T1] registered taskstats version 1 [ 1.256465][ T1] Loading compiled-in X.509 certificates [ 1.356137][ T1] Demotion targets for Node 0: null [ 1.356465][ T1] kmemleak: Kernel memory leak detector initialized (mem pool available: 11973) [ 1.356769][ T1] page_owner is disabled [ 1.357391][ T1] PM: Magic number: 6:912:414 [ 1.359355][ T1] ALSA device list: [ 1.360080][ T1] No soundcards found. [ 1.361443][ T1] md: Skipping autodetection of RAID arrays. (raid=autodetect will force) [ 1.363979][ T1] VFS: Mounted root (virtiofs filesystem) readonly on device 0:24. [ 1.364805][ T1] devtmpfs: mounted [ 1.365076][ T1] VFS: Pivoted into new rootfs [ 1.395002][ T1] Freeing unused kernel image (initmem) memory: 2548K [ 1.395404][ T1] Write protecting the kernel read-only data: 63488k [ 1.396978][ T1] Freeing unused kernel image (text/rodata gap) memory: 1916K [ 1.397947][ T1] Freeing unused kernel image (rodata/data gap) memory: 1316K [ 1.398388][ T1] Run /usr/lib/python3.14/site-packages/virtme/guest/bin/virtme-ng-init as init process [ 1.399574][ T1] with arguments: [ 1.399783][ T1] /usr/lib/python3.14/site-packages/virtme/guest/bin/virtme-ng-init [ 1.400156][ T1] with environment: [ 1.400359][ T1] HOME=/ [ 1.400562][ T1] TERM=dumb [ 1.400745][ T1] virtme_hostname=vmksft-net-drv-dbg,debug-threads=on [ 1.401060][ T1] nr_open=2147483584 [ 1.401242][ T1] virtme_link_mods=/srv/vmksft/testing/wt-18/.virtme_mods/lib/modules/0.0.0 [ 1.401905][ T1] virtme_rw_overlay0=/etc [ 1.402166][ T1] virtme_rw_overlay1=/lib [ 1.402415][ T1] virtme_rw_overlay2=/home [ 1.402663][ T1] virtme_rw_overlay3=/opt [ 1.402916][ T1] virtme_rw_overlay4=/srv [ 1.403163][ T1] virtme_rw_overlay5=/usr [ 1.403415][ T1] virtme_rw_overlay6=/var [ 1.403679][ T1] virtme_rw_overlay7=/tmp [ 1.403938][ T1] virtme_console=ttyS0 [ 1.404189][ T1] virtme_chdir=srv/vmksft/testing/wt-18 [ 1.427740][ T1] virtme-ng-init: mount devtmpfs -> /dev: EBUSY: Device or resource busy [ 1.430242][ T1] virtme-ng-init: Setting hostname to vmksft-net-drv-dbg,debug-threads=on... [ 1.441868][ T1] overlayfs: failed to set xattr on upper [ 1.442149][ T73] kwatchdog (73) used greatest stack depth: 29688 bytes left [ 1.442275][ T1] overlayfs: ...falling back to redirect_dir=nofollow. [ 1.442280][ T1] overlayfs: ...falling back to uuid=null. [ 1.445684][ T1] overlayfs: failed to set xattr on upper [ 1.446012][ T1] overlayfs: ...falling back to redirect_dir=nofollow. [ 1.446431][ T1] overlayfs: ...falling back to uuid=null. [ 1.449428][ T1] overlayfs: failed to set xattr on upper [ 1.450178][ T1] overlayfs: ...falling back to redirect_dir=nofollow. [ 1.450576][ T1] overlayfs: ...falling back to uuid=null. [ 1.453504][ T1] overlayfs: failed to set xattr on upper [ 1.453835][ T1] overlayfs: ...falling back to redirect_dir=nofollow. [ 1.454214][ T1] overlayfs: ...falling back to uuid=null. [ 1.458321][ T1] overlayfs: failed to set xattr on upper [ 1.458644][ T1] overlayfs: ...falling back to redirect_dir=nofollow. [ 1.459030][ T1] overlayfs: ...falling back to uuid=null. [ 1.462388][ T1] overlayfs: failed to set xattr on upper [ 1.462725][ T1] overlayfs: ...falling back to redirect_dir=nofollow. [ 1.463123][ T1] overlayfs: ...falling back to uuid=null. [ 1.466138][ T1] overlayfs: failed to set xattr on upper [ 1.466478][ T1] overlayfs: ...falling back to redirect_dir=nofollow. [ 1.466873][ T1] overlayfs: ...falling back to uuid=null. [ 1.470107][ T1] overlayfs: failed to set xattr on upper [ 1.470441][ T1] overlayfs: ...falling back to redirect_dir=nofollow. [ 1.470842][ T1] overlayfs: ...falling back to uuid=null. [ 1.489471][ T1] virtme-ng-init: running systemd-tmpfiles [ 4.207603][ T1] virtme-ng-init: Failed to read '/usr/lib/tmpfiles.d/audit.conf': Permission denied [ 4.207603][ T1] Failed to create directory or subvolume "/var/spool/at/spool": Permission denied [ 4.207603][ T1] Failed to create file /var/spool/at/.SEQ: Permission denied [ 4.207603][ T1] Failed to opendir() '/proc/self/fd/6': Permission denied [ 4.207619][ T1] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 4.207621][ T1] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 1, name: virtme-ng-init [ 4.207623][ T1] preempt_count: 1, expected: 0 [ 4.207623][ T1] RCU nest depth: 0, expected: 0 [ 4.207624][ T1] locks held by virtme-ng-init/1: 4, last CPU#3: [ 4.207626][ T1] #0: ffffffffaed99cc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 4.207637][ T1] #1: ffffffffaed99d38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 4.207642][ T1] #2: ffffffffaec89660 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 4.207646][ T1] #3: ffffffffaec89560 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x202/0x4f0 [ 4.207650][ T1] irq event stamp: 566324 [ 4.207651][ T1] hardirqs last enabled at (566323): [] __down_trylock_console_sem+0x86/0xa0 [ 4.207654][ T1] hardirqs last disabled at (566324): [] console_emit_next_record+0x3f8/0x4f0 [ 4.207656][ T1] softirqs last enabled at (566274): [] handle_softirqs+0x67c/0x900 [ 4.207660][ T1] softirqs last disabled at (566267): [] __irq_exit_rcu+0x145/0x1c0 [ 4.207662][ T1] Preemption disabled at: [ 4.207663][ T1] [] vprintk_emit+0x31b/0x3e0 [ 4.207668][ T1] CPU: 3 UID: 0 PID: 1 Comm: virtme-ng-init Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 4.207671][ T1] Tainted: [W]=WARN [ 4.207672][ T1] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 4.207674][ T1] Call Trace: [ 4.207675][ T1] [ 4.207677][ T1] dump_stack_lvl+0x6f/0xa0 [ 4.207683][ T1] ? vprintk_emit+0x31b/0x3e0 [ 4.207684][ T1] __might_resched.cold+0x1fe/0x2c1 [ 4.207689][ T1] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 4.207693][ T1] ? __kmalloc_noprof+0xdb/0x760 [ 4.207698][ T1] __kmalloc_noprof+0x443/0x760 [ 4.207701][ T1] ? alloc_buf.isra.0+0x4b/0x260 [ 4.207707][ T1] ? do_raw_spin_unlock+0x59/0x250 [ 4.207710][ T1] alloc_buf.isra.0+0x4b/0x260 [ 4.207714][ T1] put_chars+0x1e1/0x2f0 [ 4.207717][ T1] ? __send_to_port+0x420/0x420 [ 4.207718][ T1] ? printk_get_next_message+0x2fe/0x7d0 [ 4.207722][ T1] ? rcu_read_lock_any_held+0x3c/0x90 [ 4.207725][ T1] ? validate_chain+0x38b/0xc20 [ 4.207730][ T1] hvc_console_print+0x292/0x780 [ 4.207733][ T1] ? __lock_acquire+0x518/0xc20 [ 4.207735][ T1] ? __lock_acquire+0x518/0xc20 [ 4.207739][ T1] ? hvc_write+0x3a0/0x3a0 [ 4.207742][ T1] ? rcu_is_watching+0x16/0xd0 [ 4.207744][ T1] ? lock_acquire+0x13c/0x160 [ 4.207748][ T1] console_emit_next_record+0x252/0x4f0 [ 4.207752][ T1] ? devkmsg_read+0x4e0/0x4e0 [ 4.207757][ T1] ? rcu_is_watching+0x16/0xd0 [ 4.207758][ T1] ? lock_acquire+0x13c/0x160 [ 4.207762][ T1] console_flush_one_record+0x46f/0x710 [ 4.207766][ T1] ? console_emit_next_record+0x4f0/0x4f0 [ 4.207768][ T1] ? __lock_acquire+0x518/0xc20 [ 4.207773][ T1] console_unlock+0xee/0x1f0 [ 4.207776][ T1] ? console_flush_one_record+0x710/0x710 [ 4.207778][ T1] ? rcu_is_watching+0x16/0xd0 [ 4.207780][ T1] ? lock_acquire+0x60/0x160 [ 4.207783][ T1] ? __down_trylock_console_sem+0x5e/0xa0 [ 4.207785][ T1] ? vprintk_emit+0x320/0x3e0 [ 4.207788][ T1] vprintk_emit+0x37c/0x3e0 [ 4.207791][ T1] ? wake_up_klogd_work_func+0x90/0x90 [ 4.207793][ T1] ? _copy_from_iter+0x1bb/0x1810 [ 4.207799][ T1] devkmsg_emit.constprop.0+0xbc/0xf1 [ 4.207802][ T1] ? vprintk_emit.cold+0x107/0x107 [ 4.207804][ T1] ? simple_strntoull+0x10f/0x140 [ 4.207809][ T1] ? date_str+0x1e0/0x1e0 [ 4.207813][ T1] ? devkmsg_write+0xd1/0x2c0 [ 4.207816][ T1] devkmsg_write.cold+0x5a/0x8b [ 4.207819][ T1] ? vprintk_default+0x20/0x20 [ 4.207823][ T1] ? vprintk_default+0x20/0x20 [ 4.207825][ T1] new_sync_write+0x33e/0x760 [ 4.207828][ T1] ? lockdep_hardirqs_on+0x91/0x130 [ 4.207831][ T1] ? kasan_quarantine_put+0x13e/0x2b0 [ 4.207835][ T1] ? new_sync_read+0x750/0x750 [ 4.207841][ T1] ? rcu_is_watching+0x16/0xd0 [ 4.207844][ T1] vfs_write+0x6a2/0xbd0 [ 4.207848][ T1] ksys_write+0x116/0x250 [ 4.207850][ T1] ? __ia32_sys_read+0xc0/0xc0 [ 4.207853][ T1] ? rcu_is_watching+0x16/0xd0 [ 4.207856][ T1] do_syscall_64+0xff/0x530 [ 4.207858][ T1] ? exc_page_fault+0xee/0x100 [ 4.207861][ T1] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 4.207863][ T1] RIP: 0033:0x7f2493bd4bee [ 4.207867][ T1] Code: 4d 89 d8 e8 d4 12 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 [ 4.207869][ T1] RSP: 002b:00007ffe5f7ad620 EFLAGS: 00000202 ORIG_RAX: 0000000000000001 [ 4.207871][ T1] RAX: ffffffffffffffda RBX: 000000000000011c RCX: 00007f2493bd4bee [ 4.207873][ T1] RDX: 000000000000011c RSI: 0000555571139640 RDI: 0000000000000003 [ 4.207874][ T1] RBP: 00007ffe5f7ad630 R08: 0000000000000000 R09: 0000000000000000 [ 4.207874][ T1] R10: 0000000000000000 R11: 0000000000000202 R12: 00007f2493c600a0 [ 4.207875][ T1] R13: 00007f2493b5e3e0 R14: 0000555571139640 R15: 00007ffe5f7ad748 [ 4.207882][ T1] [ 4.208838][ T74] systemd-tmpfile (74) used greatest stack depth: 25120 bytes left [ 4.209295][ T1] virtme-ng-init: basic initialization done [ 4.274862][ T79] ip (79) used greatest stack depth: 24832 bytes left [ 4.324021][ T75] virtme-ng-init: Starting systemd-udevd version 259.8-1.fc44 [ 4.324665][ T75] virtme-ng-init: triggering udev coldplug [ 6.971730][ T75] virtme-ng-init: waiting for udev to settle [ 6.971744][ T75] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 6.971747][ T75] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 75, name: virtme-ng-init [ 6.971749][ T75] preempt_count: 1, expected: 0 [ 6.971750][ T75] RCU nest depth: 0, expected: 0 [ 6.971751][ T75] locks held by virtme-ng-init/75: 4, last CPU#1: [ 6.971753][ T75] #0: ffffffffaed99cc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 6.971765][ T75] #1: ffffffffaed99d38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 6.971769][ T75] #2: ffffffffaec89660 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 6.971773][ T75] #3: ffffffffaec89560 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x202/0x4f0 [ 6.971777][ T75] irq event stamp: 3006 [ 6.971778][ T75] hardirqs last enabled at (3005): [] __down_trylock_console_sem+0x86/0xa0 [ 6.971781][ T75] hardirqs last disabled at (3006): [] console_emit_next_record+0x3f8/0x4f0 [ 6.971783][ T75] softirqs last enabled at (2502): [] fpu_clone+0xd9/0x410 [ 6.971787][ T75] softirqs last disabled at (2500): [] fpu_clone+0x82/0x410 [ 6.971789][ T75] Preemption disabled at: [ 6.971790][ T75] [] vprintk_emit+0x31b/0x3e0 [ 6.971795][ T75] CPU: 1 UID: 0 PID: 75 Comm: virtme-ng-init Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 6.971798][ T75] Tainted: [W]=WARN [ 6.971799][ T75] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 6.971801][ T75] Call Trace: [ 6.971802][ T75] [ 6.971804][ T75] dump_stack_lvl+0x6f/0xa0 [ 6.971809][ T75] ? vprintk_emit+0x31b/0x3e0 [ 6.971811][ T75] __might_resched.cold+0x1fe/0x2c1 [ 6.971815][ T75] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 6.971819][ T75] ? __kmalloc_noprof+0xdb/0x760 [ 6.971825][ T75] __kmalloc_noprof+0x443/0x760 [ 6.971827][ T75] ? alloc_buf.isra.0+0x4b/0x260 [ 6.971834][ T75] ? do_raw_spin_unlock+0x59/0x250 [ 6.971836][ T75] alloc_buf.isra.0+0x4b/0x260 [ 6.971840][ T75] put_chars+0x1e1/0x2f0 [ 6.971843][ T75] ? __send_to_port+0x420/0x420 [ 6.971845][ T75] ? printk_get_next_message+0x2fe/0x7d0 [ 6.971848][ T75] ? rcu_read_lock_any_held+0x3c/0x90 [ 6.971852][ T75] ? validate_chain+0x38b/0xc20 [ 6.971856][ T75] hvc_console_print+0x292/0x780 [ 6.971859][ T75] ? __lock_acquire+0x518/0xc20 [ 6.971861][ T75] ? __lock_acquire+0x518/0xc20 [ 6.971865][ T75] ? hvc_write+0x3a0/0x3a0 [ 6.971869][ T75] ? rcu_is_watching+0x16/0xd0 [ 6.971871][ T75] ? lock_acquire+0x13c/0x160 [ 6.971875][ T75] console_emit_next_record+0x252/0x4f0 [ 6.971879][ T75] ? devkmsg_read+0x4e0/0x4e0 [ 6.971883][ T75] ? rcu_is_watching+0x16/0xd0 [ 6.971885][ T75] ? lock_acquire+0x13c/0x160 [ 6.971889][ T75] console_flush_one_record+0x46f/0x710 [ 6.971893][ T75] ? console_emit_next_record+0x4f0/0x4f0 [ 6.971895][ T75] ? __lock_acquire+0x518/0xc20 [ 6.971900][ T75] console_unlock+0xee/0x1f0 [ 6.971903][ T75] ? console_flush_one_record+0x710/0x710 [ 6.971905][ T75] ? rcu_is_watching+0x16/0xd0 [ 6.971906][ T75] ? lock_acquire+0x60/0x160 [ 6.971910][ T75] ? __down_trylock_console_sem+0x5e/0xa0 [ 6.971912][ T75] ? vprintk_emit+0x320/0x3e0 [ 6.971915][ T75] vprintk_emit+0x37c/0x3e0 [ 6.971918][ T75] ? wake_up_klogd_work_func+0x90/0x90 [ 6.971921][ T75] ? _copy_from_iter+0x1bb/0x1810 [ 6.971926][ T75] devkmsg_emit.constprop.0+0xbc/0xf1 [ 6.971930][ T75] ? vprintk_emit.cold+0x107/0x107 [ 6.971932][ T75] ? simple_strntoull+0x10f/0x140 [ 6.971937][ T75] ? date_str+0x1e0/0x1e0 [ 6.971940][ T75] ? devkmsg_write+0xd1/0x2c0 [ 6.971944][ T75] devkmsg_write.cold+0x5a/0x8b [ 6.971947][ T75] ? vprintk_default+0x20/0x20 [ 6.971951][ T75] ? vprintk_default+0x20/0x20 [ 6.971952][ T75] new_sync_write+0x33e/0x760 [ 6.971956][ T75] ? kasan_quarantine_put+0xc2/0x2b0 [ 6.971960][ T75] ? new_sync_read+0x750/0x750 [ 6.971964][ T75] ? __lock_release.isra.0+0x69/0x1a0 [ 6.971969][ T75] ? __fget_files+0x1e3/0x460 [ 6.971973][ T75] vfs_write+0x6a2/0xbd0 [ 6.971977][ T75] ksys_write+0x116/0x250 [ 6.971979][ T75] ? __ia32_sys_read+0xc0/0xc0 [ 6.971981][ T75] ? rcu_is_watching+0x16/0xd0 [ 6.971984][ T75] do_syscall_64+0xff/0x530 [ 6.971987][ T75] ? exc_page_fault+0xee/0x100 [ 6.971990][ T75] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 6.971992][ T75] RIP: 0033:0x7f2493bd5ed2 [ 6.971996][ T75] Code: 08 0f 85 51 ec ff ff 49 89 fb 48 89 f0 48 89 d7 48 89 ce 4c 89 c2 4d 89 ca 4c 8b 44 24 08 4c 8b 4c 24 10 4c 89 5c 24 08 0f 05 66 2e 0f 1f 84 00 00 00 00 00 0f 1f 00 f3 0f 1e fa 55 48 89 e5 [ 6.971998][ T75] RSP: 002b:00007f2493b1cc68 EFLAGS: 00000246 ORIG_RAX: 0000000000000001 [ 6.972000][ T75] RAX: ffffffffffffffda RBX: 000000000000002e RCX: 00007f2493bd5ed2 [ 6.972002][ T75] RDX: 000000000000002e RSI: 00007f248c0012c0 RDI: 0000000000000003 [ 6.972002][ T75] RBP: 00007f2493b1cc90 R08: 0000000000000000 R09: 0000000000000000 [ 6.972003][ T75] R10: 0000000000000000 R11: 0000000000000246 R12: 00007f2493c600a0 [ 6.972004][ T75] R13: 00007f2493b5e3e0 R14: 00007f248c0012c0 R15: 00007f2493b1cda8 [ 6.972011][ T75] [ 7.888814][ T75] virtme-ng-init: udev is done [ 7.896534][ T1] virtme-ng-init: initialization done [ 8.034971][ T110] modprobe (110) used greatest stack depth: 24752 bytes left [ 8.034994][ T110] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 8.034997][ T110] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 110, name: modprobe [ 8.034999][ T110] preempt_count: 2, expected: 0 [ 8.035001][ T110] RCU nest depth: 0, expected: 0 [ 8.035002][ T110] locks held by modprobe/110: 5, last CPU#0: [ 8.035005][ T110] #0: ffffffffaec027b8 (low_water_lock){+.+.}-{3:3}, at: do_exit+0x887/0xdc0 [ 8.035020][ T110] #1: ffffffffaed99cc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 8.035027][ T110] #2: ffffffffaed99d38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 8.035033][ T110] #3: ffffffffaec89660 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 8.035039][ T110] #4: ffffffffaec89560 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x202/0x4f0 [ 8.035046][ T110] irq event stamp: 21362 [ 8.035047][ T110] hardirqs last enabled at (21361): [] __down_trylock_console_sem+0x86/0xa0 [ 8.035051][ T110] hardirqs last disabled at (21362): [] console_emit_next_record+0x3f8/0x4f0 [ 8.035054][ T110] softirqs last enabled at (21112): [] handle_softirqs+0x67c/0x900 [ 8.035058][ T110] softirqs last disabled at (21107): [] __irq_exit_rcu+0x145/0x1c0 [ 8.035061][ T110] Preemption disabled at: [ 8.035062][ T110] [<0000000000000000>] 0x0 [ 8.035071][ T110] CPU: 0 UID: 0 PID: 110 Comm: modprobe Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 8.035076][ T110] Tainted: [W]=WARN [ 8.035077][ T110] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 8.035080][ T110] Call Trace: [ 8.035082][ T110] [ 8.035084][ T110] dump_stack_lvl+0x6f/0xa0 [ 8.035092][ T110] __might_resched.cold+0x1fe/0x2c1 [ 8.035099][ T110] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 8.035104][ T110] ? __kmalloc_noprof+0xdb/0x760 [ 8.035113][ T110] __kmalloc_noprof+0x443/0x760 [ 8.035116][ T110] ? alloc_buf.isra.0+0x4b/0x260 [ 8.035126][ T110] ? do_raw_spin_unlock+0x59/0x250 [ 8.035130][ T110] alloc_buf.isra.0+0x4b/0x260 [ 8.035136][ T110] put_chars+0x1e1/0x2f0 [ 8.035140][ T110] ? __send_to_port+0x420/0x420 [ 8.035143][ T110] ? printk_get_next_message+0x2fe/0x7d0 [ 8.035149][ T110] ? rcu_read_lock_any_held+0x3c/0x90 [ 8.035153][ T110] ? validate_chain+0x38b/0xc20 [ 8.035161][ T110] hvc_console_print+0x292/0x780 [ 8.035165][ T110] ? __lock_acquire+0x518/0xc20 [ 8.035168][ T110] ? __lock_acquire+0x518/0xc20 [ 8.035176][ T110] ? hvc_write+0x3a0/0x3a0 [ 8.035182][ T110] ? rcu_is_watching+0x16/0xd0 [ 8.035185][ T110] ? lock_acquire+0x13c/0x160 [ 8.035192][ T110] console_emit_next_record+0x252/0x4f0 [ 8.035199][ T110] ? devkmsg_read+0x4e0/0x4e0 [ 8.035206][ T110] ? rcu_is_watching+0x16/0xd0 [ 8.035210][ T110] ? lock_acquire+0x13c/0x160 [ 8.035216][ T110] console_flush_one_record+0x46f/0x710 [ 8.035223][ T110] ? console_emit_next_record+0x4f0/0x4f0 [ 8.035226][ T110] ? __lock_acquire+0x518/0xc20 [ 8.035236][ T110] console_unlock+0xee/0x1f0 [ 8.035240][ T110] ? console_flush_one_record+0x710/0x710 [ 8.035243][ T110] ? rcu_is_watching+0x16/0xd0 [ 8.035246][ T110] ? lock_acquire+0x60/0x160 [ 8.035253][ T110] ? __down_trylock_console_sem+0x5e/0xa0 [ 8.035256][ T110] ? vprintk_emit+0x320/0x3e0 [ 8.035261][ T110] vprintk_emit+0x37c/0x3e0 [ 8.035266][ T110] ? wake_up_klogd_work_func+0x90/0x90 [ 8.035271][ T110] ? __lock_acquire+0x518/0xc20 [ 8.035278][ T110] _printk+0xc7/0x100 [ 8.035282][ T110] ? snapshot_read.cold+0x21/0x21 [ 8.035287][ T110] ? do_raw_spin_lock+0x131/0x280 [ 8.035291][ T110] ? __rwlock_init+0x150/0x150 [ 8.035298][ T110] ? do_raw_spin_lock+0x131/0x280 [ 8.035302][ T110] do_exit.cold+0x82/0x9c [ 8.035308][ T110] ? exit_notify+0x890/0x890 [ 8.035310][ T110] ? __lock_release.isra.0+0x69/0x1a0 [ 8.035315][ T110] ? rcu_is_watching+0x16/0xd0 [ 8.035321][ T110] do_group_exit+0xb8/0x370 [ 8.035331][ T110] __x64_sys_exit_group+0x3c/0x50 [ 8.035334][ T110] x64_sys_call+0x1567/0x1570 [ 8.035338][ T110] do_syscall_64+0xff/0x530 [ 8.035343][ T110] ? exc_page_fault+0xee/0x100 [ 8.035348][ T110] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 8.035351][ T110] RIP: 0033:0x7f94ed9641b8 [ 8.035354][ T110] Code: Unable to access opcode bytes at 0x7f94ed96418e. [ 8.035356][ T110] RSP: 002b:00007ffdc0da5be8 EFLAGS: 00000246 ORIG_RAX: 00000000000000e7 [ 8.035359][ T110] RAX: ffffffffffffffda RBX: 00007f94eda94f88 RCX: 00007f94ed9641b8 [ 8.035361][ T110] RDX: 00007f94ed89fa88 RSI: ffffffffffffffa8 RDI: 0000000000000001 [ 8.035363][ T110] RBP: 00007ffdc0da5c40 R08: 0000000000000000 R09: 00000000000000a0 [ 8.035364][ T110] R10: 00007ffdc0da5a40 R11: 0000000000000246 R12: 0000000000000001 [ 8.035366][ T110] R13: 0000000000000001 R14: 00007f94eda93680 R15: 00007f94eda94fa0 [ 8.035378][ T110]