virtme: waiting for virtiofsd to start virtme: use 'microvm' QEMU architecture [ 1.006852][ T1] rtc_cmos PNP0B00:00: registered as rtc0 [ 1.006874][ T1] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 1.006876][ T1] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 1, name: swapper/0 [ 1.006877][ T1] preempt_count: 1, expected: 0 [ 1.006878][ T1] RCU nest depth: 0, expected: 0 [ 1.006879][ T1] locks held by swapper/0/1: 5, last CPU#0: [ 1.006881][ T1] #0: ff110000028b3938 (&dev->mutex){....}-{4:4}, at: __driver_attach+0x1ad/0x540 [ 1.006892][ T1] #1: ffffffffa0d69cc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 1.006898][ T1] #2: ffffffffa0d69d38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 1.006902][ T1] #3: ffffffffa0c89660 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 1.006906][ T1] #4: ffffffffa0c89560 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 1.006909][ T1] irq event stamp: 307428 [ 1.006910][ T1] hardirqs last enabled at (307427): [] __down_trylock_console_sem+0x86/0xa0 [ 1.006913][ T1] hardirqs last disabled at (307428): [] console_emit_next_record+0x3d4/0x4c0 [ 1.006914][ T1] softirqs last enabled at (306916): [] handle_softirqs+0x67c/0x900 [ 1.006918][ T1] softirqs last disabled at (306073): [] __irq_exit_rcu+0x145/0x1c0 [ 1.006920][ T1] Preemption disabled at: [ 1.006920][ T1] [] vprintk_emit+0x31b/0x3e0 [ 1.006925][ T1] CPU: 0 UID: 0 PID: 1 Comm: swapper/0 Not tainted 7.2.0-virtme #1 PREEMPT(full) [ 1.006928][ T1] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 1.006930][ T1] Call Trace: [ 1.006931][ T1] [ 1.006935][ T1] dump_stack_lvl+0x6f/0xa0 [ 1.006940][ T1] ? vprintk_emit+0x31b/0x3e0 [ 1.006942][ T1] __might_resched.cold+0x1fe/0x2c1 [ 1.006946][ T1] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 1.006950][ T1] ? __kmalloc_noprof+0xdb/0x760 [ 1.006955][ T1] __kmalloc_noprof+0x443/0x760 [ 1.006957][ T1] ? alloc_buf.isra.0+0x4b/0x260 [ 1.006962][ T1] ? do_raw_spin_unlock+0x59/0x250 [ 1.006965][ T1] alloc_buf.isra.0+0x4b/0x260 [ 1.006968][ T1] put_chars+0x1e1/0x2f0 [ 1.006970][ T1] ? __send_to_port+0x420/0x420 [ 1.006973][ T1] ? rcu_read_lock_any_held+0x3c/0x90 [ 1.006977][ T1] ? validate_chain+0x38b/0xc20 [ 1.006981][ T1] hvc_console_print+0x292/0x780 [ 1.006983][ T1] ? __lock_acquire+0x518/0xc20 [ 1.006985][ T1] ? __lock_acquire+0x518/0xc20 [ 1.006989][ T1] ? hvc_write+0x3a0/0x3a0 [ 1.006992][ T1] ? rcu_is_watching+0x16/0xd0 [ 1.006995][ T1] ? lock_acquire+0x13c/0x160 [ 1.006999][ T1] console_emit_next_record+0x22f/0x4c0 [ 1.007003][ T1] ? devkmsg_read+0x4b0/0x4b0 [ 1.007004][ T1] ? console_flush_one_record+0x106/0x710 [ 1.007007][ T1] ? rcu_is_watching+0x16/0xd0 [ 1.007010][ T1] ? lock_acquire+0x13c/0x160 [ 1.007014][ T1] console_flush_one_record+0x46f/0x710 [ 1.007017][ T1] ? console_emit_next_record+0x4c0/0x4c0 [ 1.007019][ T1] ? __lock_acquire+0x518/0xc20 [ 1.007024][ T1] console_unlock+0xee/0x1f0 [ 1.007027][ T1] ? console_flush_one_record+0x710/0x710 [ 1.007029][ T1] ? rcu_is_watching+0x16/0xd0 [ 1.007031][ T1] ? lock_acquire+0x60/0x160 [ 1.007035][ T1] ? __down_trylock_console_sem+0x5e/0xa0 [ 1.007036][ T1] ? vprintk_emit+0x320/0x3e0 [ 1.007039][ T1] vprintk_emit+0x37c/0x3e0 [ 1.007043][ T1] ? wake_up_klogd_work_func+0x90/0x90 [ 1.007045][ T1] ? find_held_lock+0x2b/0x80 [ 1.007047][ T1] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 1.007050][ T1] ? rcu_read_lock_any_held+0x3c/0x90 [ 1.007053][ T1] dev_vprintk_emit+0x27f/0x2c0 [ 1.007058][ T1] ? device_rename.cold+0xa/0xa [ 1.007059][ T1] ? __lock_acquire+0x518/0xc20 [ 1.007064][ T1] ? lock_acquire.part.0+0xd4/0x280 [ 1.007066][ T1] ? find_held_lock+0x2b/0x80 [ 1.007069][ T1] dev_printk_emit+0xb9/0xee [ 1.007072][ T1] ? dev_vprintk_emit+0x2c0/0x2c0 [ 1.007075][ T1] ? _raw_write_unlock+0x2d/0x50 [ 1.007079][ T1] ? proc_register+0x368/0x8b0 [ 1.007089][ T1] _dev_info+0xe2/0x116 [ 1.007092][ T1] ? _dev_notice+0x120/0x120 [ 1.007096][ T1] ? rtc_proc_add_device+0x106/0x120 [ 1.007101][ T1] ? rtc_proc_show+0x400/0x400 [ 1.007105][ T1] __devm_rtc_register_device.cold+0xc3/0x3a8 [ 1.007110][ T1] ? devm_rtc_unregister_device+0xd0/0xd0 [ 1.007115][ T1] ? __setup_irq+0xec4/0x1ee0 [ 1.007121][ T1] ? request_threaded_irq+0x1dd/0x330 [ 1.007124][ T1] cmos_do_probe+0x73b/0x98a [ 1.007127][ T1] ? cmos_platform_shutdown+0x370/0x370 [ 1.007129][ T1] ? cmos_irq_disable.cold+0x3a/0x3a [ 1.007131][ T1] ? acpi_dev_pm_attach+0x1f3/0x250 [ 1.007137][ T1] ? cmos_read_alarm_callback+0x340/0x340 [ 1.007138][ T1] ? mc146818_set_time+0x500/0x500 [ 1.007141][ T1] ? irq_get_irq_data+0xe/0x20 [ 1.007143][ T1] ? platform_get_irq_affinity+0x21f/0x450 [ 1.007149][ T1] platform_probe+0xfe/0x1f0 [ 1.007152][ T1] call_driver_probe+0x61/0x1c0 [ 1.007155][ T1] really_probe+0x199/0x760 [ 1.007158][ T1] ? __device_attach_driver+0x280/0x280 [ 1.007160][ T1] __driver_probe_device+0x24f/0x440 [ 1.007162][ T1] ? __device_attach_driver+0x280/0x280 [ 1.007164][ T1] ? __device_attach_driver+0x280/0x280 [ 1.007165][ T1] driver_probe_device+0x4a/0xf0 [ 1.007168][ T1] __driver_attach+0x1b8/0x540 [ 1.007170][ T1] ? do_raw_spin_lock+0x131/0x280 [ 1.007173][ T1] bus_for_each_dev+0x130/0x1e0 [ 1.007175][ T1] ? bus_remove_file+0x50/0x50 [ 1.007176][ T1] ? __lock_release.isra.0+0x69/0x1a0 [ 1.007179][ T1] ? rtc_dev_init+0x40/0x40 [ 1.007185][ T1] ? rtc_dev_init+0x40/0x40 [ 1.007187][ T1] bus_add_driver+0x2c8/0x530 [ 1.007190][ T1] driver_register+0x1a3/0x390 [ 1.007193][ T1] __platform_driver_probe+0x13f/0x270 [ 1.007196][ T1] cmos_init+0x31/0x40 [ 1.007198][ T1] do_one_initcall+0x124/0x4f0 [ 1.007201][ T1] ? trace_event_raw_event_initcall_level+0x280/0x280 [ 1.007203][ T1] ? parameq+0x110/0x110 [ 1.007208][ T1] ? kernel_init_freeable+0x3f1/0x630 [ 1.007211][ T1] ? rcu_is_watching+0x16/0xd0 [ 1.007214][ T1] kernel_init_freeable+0x596/0x630 [ 1.007217][ T1] ? rest_init+0x280/0x280 [ 1.007220][ T1] kernel_init+0x21/0x150 [ 1.007222][ T1] ? rest_init+0x280/0x280 [ 1.007224][ T1] ret_from_fork+0x474/0x6b0 [ 1.007228][ T1] ? arch_exit_to_user_mode_prepare.isra.0+0x120/0x120 [ 1.007231][ T1] ? __switch_to+0x5a3/0xe00 [ 1.007234][ T1] ? rest_init+0x280/0x280 [ 1.007237][ T1] ret_from_fork_asm+0x11/0x20 [ 1.007244][ T1] [ 1.038292][ T1] rtc_cmos PNP0B00:00: setting system clock to 2026-08-28T04:14:19 UTC (1787890459) [ 1.039298][ T1] rtc_cmos PNP0B00:00: alarms up to one day, 242 bytes nvram [ 1.042387][ T1] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev [ 1.052818][ T1] NET: Registered PF_INET6 protocol family [ 1.060295][ T1] Segment Routing with IPv6 [ 1.061276][ T1] In-situ OAM (IOAM) with IPv6 [ 1.061866][ T1] NET: Registered PF_PACKET protocol family [ 1.062267][ T1] 9pnet: Installing 9P2000 support [ 1.062986][ T1] Key type dns_resolver registered [ 1.064022][ T1] NET: Registered PF_VSOCK protocol family [ 1.069574][ T1] IPI shorthand broadcast: enabled [ 1.254042][ T1] sched_clock: Marking stable (1221004756, 32459025)->(1351607760, -98143979) [ 1.258219][ T1] registered taskstats version 1 [ 1.262375][ T1] Loading compiled-in X.509 certificates [ 1.400228][ T69] kwatchdog (69) used greatest stack depth: 29688 bytes left [ 1.435589][ T1] Demotion targets for Node 0: null [ 1.436057][ T1] kmemleak: Kernel memory leak detector initialized (mem pool available: 11725) [ 1.436544][ T1] page_owner is disabled [ 1.438023][ T1] PM: Magic number: 6:706:212 [ 1.438693][ T1] netconsole: network logging started [ 1.440951][ T1] ALSA device list: [ 1.441627][ T1] No soundcards found. [ 1.443059][ T1] md: Skipping autodetection of RAID arrays. (raid=autodetect will force) [ 1.447069][ T1] VFS: Mounted root (virtiofs filesystem) readonly on device 0:24. [ 1.448237][ T1] devtmpfs: mounted [ 1.448681][ T1] VFS: Pivoted into new rootfs [ 1.500012][ T1] Freeing unused kernel image (initmem) memory: 2544K [ 1.500362][ T1] Write protecting the kernel read-only data: 55296k [ 1.500906][ T1] Freeing unused kernel image (text/rodata gap) memory: 328K [ 1.501474][ T1] Freeing unused kernel image (rodata/data gap) memory: 556K [ 1.501847][ T1] Run /usr/lib/python3.14/site-packages/virtme/guest/bin/virtme-ng-init as init process [ 1.502168][ T1] with arguments: [ 1.502337][ T1] /usr/lib/python3.14/site-packages/virtme/guest/bin/virtme-ng-init [ 1.502637][ T1] with environment: [ 1.502806][ T1] HOME=/ [ 1.502969][ T1] TERM=dumb [ 1.503130][ T1] virtme_hostname=vmksft-forwarding-dbg,debug-threads=on [ 1.503436][ T1] nr_open=2147483584 [ 1.503631][ T1] virtme_link_mods=/srv/vmksft/testing/wt-4/.virtme_mods/lib/modules/0.0.0 [ 1.503949][ T1] virtme_rw_overlay0=/etc [ 1.504152][ T1] virtme_rw_overlay1=/lib [ 1.504369][ T1] virtme_rw_overlay2=/home [ 1.504618][ T1] virtme_rw_overlay3=/opt [ 1.504810][ T1] virtme_rw_overlay4=/srv [ 1.505003][ T1] virtme_rw_overlay5=/usr [ 1.505228][ T1] virtme_rw_overlay6=/var [ 1.505421][ T1] virtme_rw_overlay7=/tmp [ 1.505721][ T1] virtme_console=ttyS0 [ 1.505930][ T1] virtme_chdir=srv/vmksft/testing/wt-4 [ 1.522512][ T1] virtme-ng-init: mount devtmpfs -> /dev: EBUSY: Device or resource busy [ 1.523803][ T1] virtme-ng-init: Setting hostname to vmksft-forwarding-dbg,debug-threads=on... [ 1.541078][ T1] overlayfs: failed to set xattr on upper [ 1.541398][ T1] overlayfs: ...falling back to redirect_dir=nofollow. [ 1.541790][ T1] overlayfs: ...falling back to uuid=null. [ 1.544292][ T1] overlayfs: failed to set xattr on upper [ 1.544577][ T1] overlayfs: ...falling back to redirect_dir=nofollow. [ 1.544871][ T1] overlayfs: ...falling back to uuid=null. [ 1.546868][ T1] overlayfs: failed to set xattr on upper [ 1.547123][ T1] overlayfs: ...falling back to redirect_dir=nofollow. [ 1.547444][ T1] overlayfs: ...falling back to uuid=null. [ 1.549863][ T1] overlayfs: failed to set xattr on upper [ 1.550117][ T1] overlayfs: ...falling back to redirect_dir=nofollow. [ 1.550446][ T1] overlayfs: ...falling back to uuid=null. [ 1.552797][ T1] overlayfs: failed to set xattr on upper [ 1.553054][ T1] overlayfs: ...falling back to redirect_dir=nofollow. [ 1.553338][ T1] overlayfs: ...falling back to uuid=null. [ 1.555151][ T1] overlayfs: failed to set xattr on upper [ 1.555419][ T1] overlayfs: ...falling back to redirect_dir=nofollow. [ 1.556263][ T1] overlayfs: ...falling back to uuid=null. [ 1.558251][ T1] overlayfs: failed to set xattr on upper [ 1.558534][ T1] overlayfs: ...falling back to redirect_dir=nofollow. [ 1.558850][ T1] overlayfs: ...falling back to uuid=null. [ 1.561234][ T1] overlayfs: failed to set xattr on upper [ 1.561495][ T1] overlayfs: ...falling back to redirect_dir=nofollow. [ 1.561792][ T1] overlayfs: ...falling back to uuid=null. [ 1.572912][ T1] virtme-ng-init: running systemd-tmpfiles [ 3.974727][ T72] systemd-tmpfile (72) used greatest stack depth: 24848 bytes left [ 3.974749][ T72] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 3.974752][ T72] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 72, name: systemd-tmpfile [ 3.974755][ T72] preempt_count: 2, expected: 0 [ 3.974756][ T72] RCU nest depth: 0, expected: 0 [ 3.974757][ T72] locks held by systemd-tmpfile/72: 5, last CPU#0: [ 3.974760][ T72] #0: ffffffffa0c027b8 (low_water_lock){+.+.}-{3:3}, at: do_exit+0x887/0xdc0 [ 3.974776][ T72] #1: ffffffffa0d69cc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 3.974784][ T72] #2: ffffffffa0d69d38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 3.974790][ T72] #3: ffffffffa0c89660 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 3.974795][ T72] #4: ffffffffa0c89560 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 3.974802][ T72] irq event stamp: 2263794 [ 3.974803][ T72] hardirqs last enabled at (2263793): [] __down_trylock_console_sem+0x86/0xa0 [ 3.974807][ T72] hardirqs last disabled at (2263794): [] console_emit_next_record+0x3d4/0x4c0 [ 3.974810][ T72] softirqs last enabled at (2262668): [] handle_softirqs+0x67c/0x900 [ 3.974812][ T72] softirqs last disabled at (2262663): [] __irq_exit_rcu+0x145/0x1c0 [ 3.974816][ T72] Preemption disabled at: [ 3.974817][ T72] [<0000000000000000>] 0x0 [ 3.974826][ T72] CPU: 0 UID: 0 PID: 72 Comm: systemd-tmpfile Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 3.974830][ T72] Tainted: [W]=WARN [ 3.974832][ T72] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 3.974834][ T72] Call Trace: [ 3.974836][ T72] [ 3.974838][ T72] dump_stack_lvl+0x6f/0xa0 [ 3.974847][ T72] __might_resched.cold+0x1fe/0x2c1 [ 3.974853][ T72] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 3.974858][ T72] ? __kmalloc_noprof+0xdb/0x760 [ 3.974865][ T72] __kmalloc_noprof+0x443/0x760 [ 3.974869][ T72] ? alloc_buf.isra.0+0x4b/0x260 [ 3.974878][ T72] ? do_raw_spin_unlock+0x59/0x250 [ 3.974882][ T72] alloc_buf.isra.0+0x4b/0x260 [ 3.974887][ T72] put_chars+0x1e1/0x2f0 [ 3.974891][ T72] ? __send_to_port+0x420/0x420 [ 3.974893][ T72] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 3.974900][ T72] ? validate_chain+0x38b/0xc20 [ 3.974909][ T72] hvc_console_print+0x292/0x780 [ 3.974920][ T72] ? hvc_write+0x3a0/0x3a0 [ 3.974924][ T72] ? rcu_is_watching+0x16/0xd0 [ 3.974927][ T72] ? lock_acquire+0x13c/0x160 [ 3.974935][ T72] console_emit_next_record+0x22f/0x4c0 [ 3.974941][ T72] ? devkmsg_read+0x4b0/0x4b0 [ 3.974944][ T72] ? console_flush_one_record+0x106/0x710 [ 3.974949][ T72] ? rcu_is_watching+0x16/0xd0 [ 3.974952][ T72] ? lock_acquire+0x13c/0x160 [ 3.974954][ T1] virtme-ng-init: Failed to read '/usr/lib/tmpfiles.d/audit.conf': Permission denied [ 3.974954][ T1] Failed to create directory or subvolume "/var/spool/at/spool": Permission denied [ 3.974954][ T1] Failed to create file /var/spool/at/.SEQ: Permission denied [ 3.974954][ T1] Failed to opendir() '/proc/self/fd/6': Permission denied [ 3.974959][ T72] console_flush_one_record+0x46f/0x710 [ 3.974966][ T72] ? console_emit_next_record+0x4c0/0x4c0 [ 3.974969][ T72] ? __lock_acquire+0x518/0xc20 [ 3.974979][ T72] console_unlock+0xee/0x1f0 [ 3.974983][ T72] ? console_flush_one_record+0x710/0x710 [ 3.974986][ T72] ? rcu_is_watching+0x16/0xd0 [ 3.974989][ T72] ? lock_acquire+0x60/0x160 [ 3.974996][ T72] ? __down_trylock_console_sem+0x5e/0xa0 [ 3.974998][ T72] ? vprintk_emit+0x320/0x3e0 [ 3.975004][ T72] vprintk_emit+0x37c/0x3e0 [ 3.975010][ T72] ? wake_up_klogd_work_func+0x90/0x90 [ 3.975016][ T72] ? __lock_acquire+0x518/0xc20 [ 3.975022][ T72] _printk+0xc7/0x100 [ 3.975027][ T72] ? snapshot_read.cold+0x21/0x21 [ 3.975032][ T72] ? do_raw_spin_lock+0x131/0x280 [ 3.975036][ T72] ? __rwlock_init+0x150/0x150 [ 3.975043][ T72] ? do_raw_spin_lock+0x131/0x280 [ 3.975047][ T72] do_exit.cold+0x82/0x9c [ 3.975053][ T72] ? exit_notify+0x890/0x890 [ 3.975055][ T72] ? __lock_release.isra.0+0x69/0x1a0 [ 3.975060][ T72] ? rcu_is_watching+0x16/0xd0 [ 3.975067][ T72] do_group_exit+0xb8/0x370 [ 3.975072][ T72] __x64_sys_exit_group+0x3c/0x50 [ 3.975075][ T72] x64_sys_call+0x1567/0x1570 [ 3.975078][ T72] do_syscall_64+0xff/0x530 [ 3.975082][ T72] ? exc_page_fault+0xee/0x100 [ 3.975087][ T72] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 3.975090][ T72] RIP: 0033:0x7fe022b701b8 [ 3.975099][ T72] Code: Unable to access opcode bytes at 0x7fe022b7018e. [ 3.975101][ T72] RSP: 002b:00007ffc215a4168 EFLAGS: 00000246 ORIG_RAX: 00000000000000e7 [ 3.975104][ T72] RAX: ffffffffffffffda RBX: 00007fe022ca0f88 RCX: 00007fe022b701b8 [ 3.975106][ T72] RDX: 00007fe0223ce4c8 RSI: fffffffffffffe90 RDI: 0000000000000049 [ 3.975107][ T72] RBP: 00007ffc215a41c0 R08: 0000000000000000 R09: 0000000000001000 [ 3.975109][ T72] R10: 00007ffc215a3f80 R11: 0000000000000246 R12: 0000000000000001 [ 3.975110][ T72] R13: 0000000000000049 R14: 00007fe022c9f680 R15: 00007fe022ca0fa0 [ 3.975124][ T72] [ 3.997022][ T1] virtme-ng-init: basic initialization done [ 4.040344][ T78] ip (78) used greatest stack depth: 24832 bytes left [ 4.057528][ T74] virtme-ng-init: Starting systemd-udevd version 259.8-1.fc44 [ 4.057962][ T74] virtme-ng-init: triggering udev coldplug [ 6.538711][ T97] nfsrahead (97) used greatest stack depth: 24512 bytes left [ 6.538727][ T97] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 6.538729][ T97] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 97, name: nfsrahead [ 6.538731][ T97] preempt_count: 2, expected: 0 [ 6.538732][ T97] RCU nest depth: 0, expected: 0 [ 6.538733][ T97] locks held by nfsrahead/97: 5, last CPU#3: [ 6.538735][ T97] #0: ffffffffa0c027b8 (low_water_lock){+.+.}-{3:3}, at: do_exit+0x887/0xdc0 [ 6.538748][ T97] #1: ffffffffa0d69cc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 6.538753][ T97] #2: ffffffffa0d69d38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 6.538757][ T97] #3: ffffffffa0c89660 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 6.538761][ T97] #4: ffffffffa0c89560 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 6.538766][ T97] irq event stamp: 34824 [ 6.538767][ T97] hardirqs last enabled at (34823): [] __down_trylock_console_sem+0x86/0xa0 [ 6.538769][ T97] hardirqs last disabled at (34824): [] console_emit_next_record+0x3d4/0x4c0 [ 6.538771][ T97] softirqs last enabled at (34818): [] handle_softirqs+0x67c/0x900 [ 6.538773][ T97] softirqs last disabled at (34397): [] __irq_exit_rcu+0x145/0x1c0 [ 6.538775][ T97] Preemption disabled at: [ 6.538776][ T97] [<0000000000000000>] 0x0 [ 6.538783][ T97] CPU: 3 UID: 0 PID: 97 Comm: nfsrahead Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 6.538787][ T97] Tainted: [W]=WARN [ 6.538787][ T97] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 6.538789][ T97] Call Trace: [ 6.538791][ T97] [ 6.538792][ T97] dump_stack_lvl+0x6f/0xa0 [ 6.538799][ T97] __might_resched.cold+0x1fe/0x2c1 [ 6.538803][ T97] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 6.538807][ T97] ? __kmalloc_noprof+0xdb/0x760 [ 6.538813][ T97] __kmalloc_noprof+0x443/0x760 [ 6.538815][ T97] ? alloc_buf.isra.0+0x4b/0x260 [ 6.538821][ T97] ? do_raw_spin_unlock+0x59/0x250 [ 6.538824][ T97] alloc_buf.isra.0+0x4b/0x260 [ 6.538827][ T97] put_chars+0x1e1/0x2f0 [ 6.538830][ T97] ? __send_to_port+0x420/0x420 [ 6.538831][ T97] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 6.538836][ T97] ? validate_chain+0x38b/0xc20 [ 6.538841][ T97] hvc_console_print+0x292/0x780 [ 6.538847][ T97] ? hvc_write+0x3a0/0x3a0 [ 6.538849][ T97] ? rcu_is_watching+0x16/0xd0 [ 6.538852][ T97] ? lock_acquire+0x13c/0x160 [ 6.538856][ T97] console_emit_next_record+0x22f/0x4c0 [ 6.538860][ T97] ? devkmsg_read+0x4b0/0x4b0 [ 6.538861][ T97] ? console_flush_one_record+0x106/0x710 [ 6.538865][ T97] ? rcu_is_watching+0x16/0xd0 [ 6.538867][ T97] ? lock_acquire+0x13c/0x160 [ 6.538871][ T97] console_flush_one_record+0x46f/0x710 [ 6.538875][ T97] ? console_emit_next_record+0x4c0/0x4c0 [ 6.538877][ T97] ? __lock_acquire+0x518/0xc20 [ 6.538882][ T97] console_unlock+0xee/0x1f0 [ 6.538885][ T97] ? console_flush_one_record+0x710/0x710 [ 6.538887][ T97] ? rcu_is_watching+0x16/0xd0 [ 6.538890][ T97] ? lock_acquire+0x60/0x160 [ 6.538893][ T97] ? __down_trylock_console_sem+0x5e/0xa0 [ 6.538895][ T97] ? vprintk_emit+0x320/0x3e0 [ 6.538898][ T97] vprintk_emit+0x37c/0x3e0 [ 6.538902][ T97] ? wake_up_klogd_work_func+0x90/0x90 [ 6.538906][ T97] ? __lock_acquire+0x518/0xc20 [ 6.538909][ T97] _printk+0xc7/0x100 [ 6.538913][ T97] ? snapshot_read.cold+0x21/0x21 [ 6.538916][ T97] ? do_raw_spin_lock+0x131/0x280 [ 6.538919][ T97] ? __rwlock_init+0x150/0x150 [ 6.538923][ T97] ? do_raw_spin_lock+0x131/0x280 [ 6.538925][ T97] do_exit.cold+0x82/0x9c [ 6.538929][ T97] ? exit_notify+0x890/0x890 [ 6.538931][ T97] ? __lock_release.isra.0+0x69/0x1a0 [ 6.538934][ T97] ? rcu_is_watching+0x16/0xd0 [ 6.538938][ T97] do_group_exit+0xb8/0x370 [ 6.538941][ T97] __x64_sys_exit_group+0x3c/0x50 [ 6.538943][ T97] x64_sys_call+0x1567/0x1570 [ 6.538945][ T97] do_syscall_64+0xff/0x530 [ 6.538948][ T97] ? exc_page_fault+0xee/0x100 [ 6.538951][ T97] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 6.538953][ T97] RIP: 0033:0x7f910a4fa1b8 [ 6.538955][ T97] Code: Unable to access opcode bytes at 0x7f910a4fa18e. [ 6.538957][ T97] RSP: 002b:00007ffc61ff3df8 EFLAGS: 00000246 ORIG_RAX: 00000000000000e7 [ 6.538959][ T97] RAX: ffffffffffffffda RBX: 00007f910a62af88 RCX: 00007f910a4fa1b8 [ 6.538961][ T97] RDX: 00007f910a2e6b48 RSI: ffffffffffffffb0 RDI: 00000000ffffffed [ 6.538962][ T97] RBP: 00007ffc61ff3e50 R08: 0000000000000000 R09: 0000000000000000 [ 6.538963][ T97] R10: 00007ffc61ff3c50 R11: 0000000000000246 R12: 0000000000000001 [ 6.538964][ T97] R13: 00000000ffffffed R14: 00007f910a629680 R15: 00007f910a62afa0 [ 6.538971][ T97] [ 6.699370][ T74] virtme-ng-init: waiting for udev to settle [ 7.022272][ T74] virtme-ng-init: udev is done [ 7.031558][ T1] virtme-ng-init: initialization done