virtme: waiting for virtiofsd to start virtme: use 'microvm' QEMU architecture [ 1.052898][ T1] i8042: PNP: No PS/2 controller found. [ 1.052944][ T1] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 1.052947][ T1] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 1, name: swapper/0 [ 1.052949][ T1] preempt_count: 1, expected: 0 [ 1.052950][ T1] RCU nest depth: 0, expected: 0 [ 1.052951][ T1] locks held by swapper/0/1: 4, last CPU#0: [ 1.052954][ T1] #0: ffffffff9d569cc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 1.052968][ T1] #1: ffffffff9d569d38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 1.052974][ T1] #2: ffffffff9d489660 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 1.052980][ T1] #3: ffffffff9d489560 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 1.052986][ T1] irq event stamp: 311094 [ 1.052987][ T1] hardirqs last enabled at (311093): [] __down_trylock_console_sem+0x86/0xa0 [ 1.052990][ T1] hardirqs last disabled at (311094): [] console_emit_next_record+0x3d4/0x4c0 [ 1.052993][ T1] softirqs last enabled at (310744): [] handle_softirqs+0x67c/0x900 [ 1.052996][ T1] softirqs last disabled at (310735): [] __irq_exit_rcu+0x145/0x1c0 [ 1.052999][ T1] Preemption disabled at: [ 1.053000][ T1] [] vprintk_emit+0x31b/0x3e0 [ 1.053007][ T1] CPU: 0 UID: 0 PID: 1 Comm: swapper/0 Not tainted 7.2.0-virtme #1 PREEMPT(full) [ 1.053011][ T1] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 1.053013][ T1] Call Trace: [ 1.053015][ T1] [ 1.053019][ T1] dump_stack_lvl+0x6f/0xa0 [ 1.053027][ T1] ? vprintk_emit+0x31b/0x3e0 [ 1.053030][ T1] __might_resched.cold+0x1fe/0x2c1 [ 1.053036][ T1] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 1.053041][ T1] ? __kmalloc_noprof+0xdb/0x760 [ 1.053048][ T1] __kmalloc_noprof+0x443/0x760 [ 1.053051][ T1] ? alloc_buf.isra.0+0x4b/0x260 [ 1.053060][ T1] ? do_raw_spin_unlock+0x59/0x250 [ 1.053063][ T1] alloc_buf.isra.0+0x4b/0x260 [ 1.053068][ T1] put_chars+0x1e1/0x2f0 [ 1.053070][ T1] ? desc_read_finalized_seq+0x79/0x120 [ 1.053073][ T1] ? __send_to_port+0x420/0x420 [ 1.053078][ T1] ? rcu_read_lock_any_held+0x3c/0x90 [ 1.053082][ T1] ? validate_chain+0x38b/0xc20 [ 1.053089][ T1] hvc_console_print+0x292/0x780 [ 1.053092][ T1] ? __lock_acquire+0x518/0xc20 [ 1.053095][ T1] ? __lock_acquire+0x518/0xc20 [ 1.053102][ T1] ? hvc_write+0x3a0/0x3a0 [ 1.053104][ T1] ? console_emit_next_record+0x1df/0x4c0 [ 1.053109][ T1] ? rcu_is_watching+0x16/0xd0 [ 1.053112][ T1] ? lock_acquire+0x13c/0x160 [ 1.053119][ T1] console_emit_next_record+0x22f/0x4c0 [ 1.053124][ T1] ? devkmsg_read+0x4b0/0x4b0 [ 1.053126][ T1] ? console_flush_one_record+0x106/0x710 [ 1.053131][ T1] ? rcu_is_watching+0x16/0xd0 [ 1.053135][ T1] ? lock_acquire+0x13c/0x160 [ 1.053141][ T1] console_flush_one_record+0x46f/0x710 [ 1.053147][ T1] ? console_emit_next_record+0x4c0/0x4c0 [ 1.053150][ T1] ? __lock_acquire+0x518/0xc20 [ 1.053158][ T1] console_unlock+0xee/0x1f0 [ 1.053162][ T1] ? console_flush_one_record+0x710/0x710 [ 1.053164][ T1] ? rcu_is_watching+0x16/0xd0 [ 1.053168][ T1] ? lock_acquire+0x60/0x160 [ 1.053174][ T1] ? __down_trylock_console_sem+0x5e/0xa0 [ 1.053176][ T1] ? vprintk_emit+0x320/0x3e0 [ 1.053181][ T1] vprintk_emit+0x37c/0x3e0 [ 1.053186][ T1] ? wake_up_klogd_work_func+0x90/0x90 [ 1.053191][ T1] ? rcu_is_watching+0x16/0xd0 [ 1.053197][ T1] ? i8042_platform_init+0x460/0x460 [ 1.053203][ T1] _printk+0xc7/0x100 [ 1.053208][ T1] ? snapshot_read.cold+0x21/0x21 [ 1.053214][ T1] ? kobject_put+0x50/0xc0 [ 1.053219][ T1] ? i8042_platform_init+0x460/0x460 [ 1.053222][ T1] ? i8042_platform_init+0x460/0x460 [ 1.053225][ T1] ? kobject_put+0x50/0xc0 [ 1.053228][ T1] ? kobject_put+0x50/0xc0 [ 1.053232][ T1] i8042_pnp_init+0xf7/0x3c0 [ 1.053236][ T1] ? i8042_check_quirks+0x120/0x120 [ 1.053243][ T1] ? i8042_platform_init+0x460/0x460 [ 1.053248][ T1] i8042_platform_init+0x3f9/0x460 [ 1.053253][ T1] ? validate_chain+0x730/0xc20 [ 1.053255][ T1] ? lockdep_hardirqs_on_prepare.part.0+0x9a/0x160 [ 1.053257][ T1] ? lockdep_hardirqs_on+0x91/0x130 [ 1.053261][ T1] ? _raw_spin_unlock_irqrestore+0x53/0x80 [ 1.053265][ T1] ? i8042_pnp_init+0x3c0/0x3c0 [ 1.053268][ T1] ? add_device_randomness+0xbb/0x100 [ 1.053272][ T1] ? random_write_iter+0x20/0x20 [ 1.053276][ T1] ? lb_init_module+0x20/0x20 [ 1.053282][ T1] ? i8042_platform_init+0x460/0x460 [ 1.053290][ T1] i8042_init+0x45/0x130 [ 1.053293][ T1] do_one_initcall+0x124/0x4f0 [ 1.053297][ T1] ? trace_event_raw_event_initcall_level+0x280/0x280 [ 1.053300][ T1] ? parameq+0x110/0x110 [ 1.053307][ T1] ? kernel_init_freeable+0x3f1/0x630 [ 1.053312][ T1] ? rcu_is_watching+0x16/0xd0 [ 1.053318][ T1] kernel_init_freeable+0x596/0x630 [ 1.053322][ T1] ? rest_init+0x280/0x280 [ 1.053327][ T1] kernel_init+0x21/0x150 [ 1.053330][ T1] ? rest_init+0x280/0x280 [ 1.053332][ T1] ret_from_fork+0x474/0x6b0 [ 1.053338][ T1] ? arch_exit_to_user_mode_prepare.isra.0+0x120/0x120 [ 1.053345][ T1] ? __switch_to+0x5a3/0xe00 [ 1.053345][ T1] ? rest_init+0x280/0x280 [ 1.053345][ T1] ret_from_fork_asm+0x11/0x20 [ 1.053345][ T1] [ 1.099836][ T1] rtc_cmos PNP0B00:00: registered as rtc0 [ 1.100431][ T1] rtc_cmos PNP0B00:00: setting system clock to 2026-08-28T10:15:01 UTC (1787912101) [ 1.101832][ T1] rtc_cmos PNP0B00:00: alarms up to one day, 242 bytes nvram [ 1.106415][ T1] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev [ 1.117487][ T1] NET: Registered PF_INET6 protocol family [ 1.124478][ T1] Segment Routing with IPv6 [ 1.125140][ T1] In-situ OAM (IOAM) with IPv6 [ 1.125847][ T1] NET: Registered PF_PACKET protocol family [ 1.126233][ T1] 9pnet: Installing 9P2000 support [ 1.126820][ T1] Key type dns_resolver registered [ 1.127858][ T1] NET: Registered PF_VSOCK protocol family [ 1.134277][ T1] IPI shorthand broadcast: enabled [ 1.320042][ T1] sched_clock: Marking stable (1280004158, 39341371)->(1398230585, -78885056) [ 1.324670][ T1] registered taskstats version 1 [ 1.328797][ T1] Loading compiled-in X.509 certificates [ 1.418184][ T73] kwatchdog (73) used greatest stack depth: 28672 bytes left [ 1.462633][ T1] Demotion targets for Node 0: null [ 1.462956][ T1] kmemleak: Kernel memory leak detector initialized (mem pool available: 12235) [ 1.463234][ T1] page_owner is disabled [ 1.463894][ T1] PM: Magic number: 6:895:275 [ 1.464095][ T1] tty tty12: hash matches [ 1.464533][ T1] netconsole: network logging started [ 1.465942][ T1] ALSA device list: [ 1.466813][ T1] No soundcards found. [ 1.467641][ T1] md: Skipping autodetection of RAID arrays. (raid=autodetect will force) [ 1.471211][ T1] VFS: Mounted root (virtiofs filesystem) readonly on device 0:24. [ 1.471977][ T1] devtmpfs: mounted [ 1.472252][ T1] VFS: Pivoted into new rootfs [ 1.500971][ T1] Freeing unused kernel image (initmem) memory: 2544K [ 1.501203][ T1] Write protecting the kernel read-only data: 55296k [ 1.502657][ T1] Freeing unused kernel image (text/rodata gap) memory: 328K [ 1.503100][ T1] Freeing unused kernel image (rodata/data gap) memory: 556K [ 1.503400][ T1] Run /usr/lib/python3.14/site-packages/virtme/guest/bin/virtme-ng-init as init process [ 1.503666][ T1] with arguments: [ 1.503792][ T1] /usr/lib/python3.14/site-packages/virtme/guest/bin/virtme-ng-init [ 1.504025][ T1] with environment: [ 1.504143][ T1] HOME=/ [ 1.504275][ T1] TERM=dumb [ 1.504409][ T1] virtme_hostname=vmksft-forwarding-dbg,debug-threads=on [ 1.504637][ T1] nr_open=2147483584 [ 1.504758][ T1] virtme_link_mods=/srv/vmksft/testing/wt-4/.virtme_mods/lib/modules/0.0.0 [ 1.505027][ T1] virtme_rw_overlay0=/etc [ 1.505186][ T1] virtme_rw_overlay1=/lib [ 1.505351][ T1] virtme_rw_overlay2=/home [ 1.505511][ T1] virtme_rw_overlay3=/opt [ 1.505667][ T1] virtme_rw_overlay4=/srv [ 1.505826][ T1] virtme_rw_overlay5=/usr [ 1.505980][ T1] virtme_rw_overlay6=/var [ 1.506137][ T1] virtme_rw_overlay7=/tmp [ 1.506301][ T1] virtme_console=ttyS0 [ 1.506463][ T1] virtme_chdir=srv/vmksft/testing/wt-4 [ 1.524386][ T1] virtme-ng-init: mount devtmpfs -> /dev: EBUSY: Device or resource busy [ 1.525630][ T1] virtme-ng-init: Setting hostname to vmksft-forwarding-dbg,debug-threads=on... [ 1.538425][ T1] overlayfs: failed to set xattr on upper [ 1.538710][ T1] overlayfs: ...falling back to redirect_dir=nofollow. [ 1.538969][ T1] overlayfs: ...falling back to uuid=null. [ 1.541296][ T1] overlayfs: failed to set xattr on upper [ 1.541590][ T1] overlayfs: ...falling back to redirect_dir=nofollow. [ 1.541838][ T1] overlayfs: ...falling back to uuid=null. [ 1.543780][ T1] overlayfs: failed to set xattr on upper [ 1.544002][ T1] overlayfs: ...falling back to redirect_dir=nofollow. [ 1.544242][ T1] overlayfs: ...falling back to uuid=null. [ 1.546268][ T1] overlayfs: failed to set xattr on upper [ 1.546512][ T1] overlayfs: ...falling back to redirect_dir=nofollow. [ 1.546772][ T1] overlayfs: ...falling back to uuid=null. [ 1.549150][ T1] overlayfs: failed to set xattr on upper [ 1.550375][ T1] overlayfs: ...falling back to redirect_dir=nofollow. [ 1.550796][ T1] overlayfs: ...falling back to uuid=null. [ 1.554579][ T1] overlayfs: failed to set xattr on upper [ 1.554913][ T1] overlayfs: ...falling back to redirect_dir=nofollow. [ 1.555311][ T1] overlayfs: ...falling back to uuid=null. [ 1.559456][ T1] overlayfs: failed to set xattr on upper [ 1.559800][ T1] overlayfs: ...falling back to redirect_dir=nofollow. [ 1.560190][ T1] overlayfs: ...falling back to uuid=null. [ 1.563889][ T1] overlayfs: failed to set xattr on upper [ 1.564201][ T1] overlayfs: ...falling back to redirect_dir=nofollow. [ 1.564831][ T1] overlayfs: ...falling back to uuid=null. [ 1.582907][ T1] virtme-ng-init: running systemd-tmpfiles [ 4.279248][ T75] systemd-tmpfile (75) used greatest stack depth: 24728 bytes left [ 4.279265][ T75] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 4.279267][ T75] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 75, name: systemd-tmpfile [ 4.279269][ T75] preempt_count: 2, expected: 0 [ 4.279270][ T75] RCU nest depth: 0, expected: 0 [ 4.279271][ T75] locks held by systemd-tmpfile/75: 5, last CPU#1: [ 4.279273][ T75] #0: ffffffff9d4027b8 (low_water_lock){+.+.}-{3:3}, at: do_exit+0x887/0xdc0 [ 4.279285][ T75] #1: ffffffff9d569cc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 4.279291][ T75] #2: ffffffff9d569d38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 4.279294][ T75] #3: ffffffff9d489660 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 4.279305][ T75] #4: ffffffff9d489560 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 4.279309][ T75] irq event stamp: 2489306 [ 4.279309][ T75] hardirqs last enabled at (2489305): [] __down_trylock_console_sem+0x86/0xa0 [ 4.279312][ T75] hardirqs last disabled at (2489306): [] console_emit_next_record+0x3d4/0x4c0 [ 4.279314][ T75] softirqs last enabled at (2484382): [] handle_softirqs+0x67c/0x900 [ 4.279316][ T75] softirqs last disabled at (2483431): [] __irq_exit_rcu+0x145/0x1c0 [ 4.279318][ T75] Preemption disabled at: [ 4.279319][ T75] [<0000000000000000>] 0x0 [ 4.279325][ T75] CPU: 1 UID: 0 PID: 75 Comm: systemd-tmpfile Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 4.279329][ T75] Tainted: [W]=WARN [ 4.279330][ T75] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 4.279332][ T75] Call Trace: [ 4.279333][ T75] [ 4.279335][ T75] dump_stack_lvl+0x6f/0xa0 [ 4.279344][ T75] __might_resched.cold+0x1fe/0x2c1 [ 4.279348][ T75] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 4.279352][ T75] ? __kmalloc_noprof+0xdb/0x760 [ 4.279357][ T75] __kmalloc_noprof+0x443/0x760 [ 4.279360][ T75] ? alloc_buf.isra.0+0x4b/0x260 [ 4.279365][ T75] ? do_raw_spin_unlock+0x59/0x250 [ 4.279368][ T75] alloc_buf.isra.0+0x4b/0x260 [ 4.279371][ T75] put_chars+0x1e1/0x2f0 [ 4.279374][ T75] ? __send_to_port+0x420/0x420 [ 4.279375][ T75] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 4.279380][ T75] ? validate_chain+0x38b/0xc20 [ 4.279384][ T75] hvc_console_print+0x292/0x780 [ 4.279390][ T75] ? hvc_write+0x3a0/0x3a0 [ 4.279392][ T75] ? rcu_is_watching+0x16/0xd0 [ 4.279395][ T75] ? lock_acquire+0x13c/0x160 [ 4.279398][ T75] console_emit_next_record+0x22f/0x4c0 [ 4.279402][ T75] ? devkmsg_read+0x4b0/0x4b0 [ 4.279404][ T75] ? console_flush_one_record+0x106/0x710 [ 4.279407][ T75] ? rcu_is_watching+0x16/0xd0 [ 4.279409][ T75] ? lock_acquire+0x13c/0x160 [ 4.279413][ T75] console_flush_one_record+0x46f/0x710 [ 4.279417][ T75] ? console_emit_next_record+0x4c0/0x4c0 [ 4.279419][ T75] ? __lock_acquire+0x518/0xc20 [ 4.279424][ T75] console_unlock+0xee/0x1f0 [ 4.279426][ T75] ? console_flush_one_record+0x710/0x710 [ 4.279428][ T75] ? rcu_is_watching+0x16/0xd0 [ 4.279430][ T75] ? lock_acquire+0x60/0x160 [ 4.279434][ T75] ? __down_trylock_console_sem+0x5e/0xa0 [ 4.279435][ T75] ? vprintk_emit+0x320/0x3e0 [ 4.279439][ T75] vprintk_emit+0x37c/0x3e0 [ 4.279442][ T75] ? wake_up_klogd_work_func+0x90/0x90 [ 4.279446][ T75] ? __lock_acquire+0x518/0xc20 [ 4.279449][ T75] _printk+0xc7/0x100 [ 4.279453][ T75] ? snapshot_read.cold+0x21/0x21 [ 4.279455][ T75] ? do_raw_spin_lock+0x131/0x280 [ 4.279458][ T75] ? __rwlock_init+0x150/0x150 [ 4.279461][ T75] ? do_raw_spin_lock+0x131/0x280 [ 4.279464][ T75] do_exit.cold+0x82/0x9c [ 4.279467][ T75] ? exit_notify+0x890/0x890 [ 4.279469][ T75] ? __lock_release.isra.0+0x69/0x1a0 [ 4.279472][ T75] ? rcu_is_watching+0x16/0xd0 [ 4.279476][ T75] do_group_exit+0xb8/0x370 [ 4.279478][ T75] __x64_sys_exit_group+0x3c/0x50 [ 4.279480][ T75] x64_sys_call+0x1567/0x1570 [ 4.279482][ T75] do_syscall_64+0xff/0x530 [ 4.279486][ T75] ? exc_page_fault+0xee/0x100 [ 4.279489][ T75] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 4.279491][ T75] RIP: 0033:0x7f69b3e771b8 [ 4.279493][ T75] Code: Unable to access opcode bytes at 0x7f69b3e7718e. [ 4.279494][ T75] RSP: 002b:00007ffede063b18 EFLAGS: 00000246 ORIG_RAX: 00000000000000e7 [ 4.279496][ T75] RAX: ffffffffffffffda RBX: 00007f69b3fa7f88 RCX: 00007f69b3e771b8 [ 4.279497][ T75] RDX: 00007f69b36d54c8 RSI: fffffffffffffe90 RDI: 0000000000000049 [ 4.279498][ T75] RBP: 00007ffede063b70 R08: 0000000000000000 R09: 0000000000001000 [ 4.279499][ T75] R10: 00007ffede063930 R11: 0000000000000246 R12: 0000000000000001 [ 4.279500][ T75] R13: 0000000000000049 R14: 00007f69b3fa6680 R15: 00007f69b3fa7fa0 [ 4.279506][ T75] [ 4.279613][ T1] virtme-ng-init: Failed to read '/usr/lib/tmpfiles.d/audit.conf': Permission denied [ 4.279613][ T1] Failed to create directory or subvolume "/var/spool/at/spool": Permission denied [ 4.279613][ T1] Failed to create file /var/spool/at/.SEQ: Permission denied [ 4.279613][ T1] Failed to opendir() '/proc/self/fd/6': Permission denied [ 4.301591][ T1] virtme-ng-init: basic initialization done [ 4.363643][ T76] virtme-ng-init: Starting systemd-udevd version 259.8-1.fc44 [ 4.364063][ T76] virtme-ng-init: triggering udev coldplug [ 7.378545][ T76] virtme-ng-init: waiting for udev to settle [ 7.378561][ T76] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 7.378563][ T76] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 76, name: virtme-ng-init [ 7.378565][ T76] preempt_count: 1, expected: 0 [ 7.378566][ T76] RCU nest depth: 0, expected: 0 [ 7.378567][ T76] locks held by virtme-ng-init/76: 4, last CPU#0: [ 7.378570][ T76] #0: ffffffff9d569cc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 7.378583][ T76] #1: ffffffff9d569d38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 7.378588][ T76] #2: ffffffff9d489660 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 7.378592][ T76] #3: ffffffff9d489560 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 7.378596][ T76] irq event stamp: 3738 [ 7.378597][ T76] hardirqs last enabled at (3737): [] __down_trylock_console_sem+0x86/0xa0 [ 7.378599][ T76] hardirqs last disabled at (3738): [] console_emit_next_record+0x3d4/0x4c0 [ 7.378601][ T76] softirqs last enabled at (3592): [] handle_softirqs+0x67c/0x900 [ 7.378605][ T76] softirqs last disabled at (3587): [] __irq_exit_rcu+0x145/0x1c0 [ 7.378607][ T76] Preemption disabled at: [ 7.378608][ T76] [] vprintk_emit+0x31b/0x3e0 [ 7.378613][ T76] CPU: 0 UID: 0 PID: 76 Comm: virtme-ng-init Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 7.378617][ T76] Tainted: [W]=WARN [ 7.378618][ T76] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 7.378620][ T76] Call Trace: [ 7.378622][ T76] [ 7.378623][ T76] dump_stack_lvl+0x6f/0xa0 [ 7.378629][ T76] ? vprintk_emit+0x31b/0x3e0 [ 7.378631][ T76] __might_resched.cold+0x1fe/0x2c1 [ 7.378636][ T76] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 7.378640][ T76] ? __kmalloc_noprof+0xdb/0x760 [ 7.378645][ T76] __kmalloc_noprof+0x443/0x760 [ 7.378648][ T76] ? alloc_buf.isra.0+0x4b/0x260 [ 7.378654][ T76] ? do_raw_spin_unlock+0x59/0x250 [ 7.378657][ T76] alloc_buf.isra.0+0x4b/0x260 [ 7.378660][ T76] put_chars+0x1e1/0x2f0 [ 7.378663][ T76] ? __send_to_port+0x420/0x420 [ 7.378664][ T76] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 7.378669][ T76] ? validate_chain+0x38b/0xc20 [ 7.378674][ T76] hvc_console_print+0x292/0x780 [ 7.378680][ T76] ? hvc_write+0x3a0/0x3a0 [ 7.378683][ T76] ? rcu_is_watching+0x16/0xd0 [ 7.378685][ T76] ? lock_acquire+0x13c/0x160 [ 7.378689][ T76] console_emit_next_record+0x22f/0x4c0 [ 7.378693][ T76] ? devkmsg_read+0x4b0/0x4b0 [ 7.378695][ T76] ? console_flush_one_record+0x106/0x710 [ 7.378698][ T76] ? rcu_is_watching+0x16/0xd0 [ 7.378701][ T76] ? lock_acquire+0x13c/0x160 [ 7.378705][ T76] console_flush_one_record+0x46f/0x710 [ 7.378709][ T76] ? console_emit_next_record+0x4c0/0x4c0 [ 7.378711][ T76] ? __lock_acquire+0x518/0xc20 [ 7.378717][ T76] console_unlock+0xee/0x1f0 [ 7.378720][ T76] ? console_flush_one_record+0x710/0x710 [ 7.378721][ T76] ? rcu_is_watching+0x16/0xd0 [ 7.378724][ T76] ? lock_acquire+0x60/0x160 [ 7.378728][ T76] ? __down_trylock_console_sem+0x5e/0xa0 [ 7.378729][ T76] ? vprintk_emit+0x320/0x3e0 [ 7.378733][ T76] vprintk_emit+0x37c/0x3e0 [ 7.378736][ T76] ? wake_up_klogd_work_func+0x90/0x90 [ 7.378740][ T76] ? _copy_from_iter+0x1bb/0x1810 [ 7.378746][ T76] devkmsg_emit.constprop.0+0xbc/0xf1 [ 7.378750][ T76] ? vprintk_emit.cold+0x107/0x107 [ 7.378752][ T76] ? simple_strntoull+0x10f/0x140 [ 7.378756][ T76] ? date_str+0x1e0/0x1e0 [ 7.378759][ T76] ? devkmsg_write+0xd1/0x2c0 [ 7.378763][ T76] devkmsg_write.cold+0x5a/0x8b [ 7.378766][ T76] ? vprintk_default+0x20/0x20 [ 7.378771][ T76] ? vprintk_default+0x20/0x20 [ 7.378773][ T76] new_sync_write+0x33e/0x760 [ 7.378777][ T76] ? kasan_quarantine_put+0x42/0x2b0 [ 7.378781][ T76] ? new_sync_read+0x750/0x750 [ 7.378785][ T76] ? __lock_release.isra.0+0x69/0x1a0 [ 7.378790][ T76] ? __fget_files+0x1e3/0x460 [ 7.378794][ T76] vfs_write+0x6a2/0xbd0 [ 7.378799][ T76] ksys_write+0x116/0x250 [ 7.378802][ T76] ? __ia32_sys_read+0xc0/0xc0 [ 7.378806][ T76] ? rcu_is_watching+0x16/0xd0 [ 7.378809][ T76] do_syscall_64+0xff/0x530 [ 7.378812][ T76] ? exc_page_fault+0xee/0x100 [ 7.378815][ T76] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 7.378817][ T76] RIP: 0033:0x7f7174f86ed2 [ 7.378821][ T76] 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.378823][ T76] RSP: 002b:00007f7174ecdc68 EFLAGS: 00000246 ORIG_RAX: 0000000000000001 [ 7.378826][ T76] RAX: ffffffffffffffda RBX: 000000000000002e RCX: 00007f7174f86ed2 [ 7.378828][ T76] RDX: 000000000000002e RSI: 00007f71700012c0 RDI: 0000000000000003 [ 7.378829][ T76] RBP: 00007f7174ecdc90 R08: 0000000000000000 R09: 0000000000000000 [ 7.378829][ T76] R10: 0000000000000000 R11: 0000000000000246 R12: 00007f71750110a0 [ 7.378830][ T76] R13: 00007f7174f0f3e0 R14: 00007f71700012c0 R15: 00007f7174ecdda8 [ 7.378837][ T76] [ 7.417483][ C0] clocksource: Watchdog remote CPU 1 read timed out [ 7.417558][ C0] [ 7.417560][ C0] ======================================================== [ 7.417561][ C0] WARNING: possible irq lock inversion dependency detected [ 7.417563][ C0] 7.2.0-virtme #1 Tainted: G W [ 7.417565][ C0] -------------------------------------------------------- [ 7.417565][ C0] udevadm/103 just changed the state of lock: [ 7.417567][ C0] ffffffff9d489660 (console_owner){..-.}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 7.417580][ C0] but this lock took another, SOFTIRQ-unsafe lock in the past: [ 7.417581][ C0] (fs_reclaim){+.+.}-{0:0} [ 7.417582][ C0] [ 7.417582][ C0] [ 7.417582][ C0] and interrupts could create inverse lock ordering between them. [ 7.417582][ C0] [ 7.417583][ C0] [ 7.417583][ C0] other info that might help us debug this: [ 7.417583][ C0] Possible interrupt unsafe locking scenario: [ 7.417583][ C0] [ 7.417584][ C0] CPU0 CPU1 [ 7.417585][ C0] ---- ---- [ 7.417585][ C0] lock(fs_reclaim); [ 7.417586][ C0] local_irq_disable(); [ 7.417587][ C0] lock(console_owner); [ 7.417588][ C0] lock(fs_reclaim); [ 7.417589][ C0] [ 7.417589][ C0] lock(console_owner); [ 7.417590][ C0] [ 7.417590][ C0] *** DEADLOCK *** [ 7.417590][ C0] [ 7.417590][ C0] locks held by udevadm/103: 5, last CPU#0: [ 7.417592][ C0] #0: ffffffff9d580b00 (rcu_read_lock){....}-{1:3}, at: unwind_next_frame+0x17e/0x1ea0 [ 7.417597][ C0] #1: ffa0000000007c90 ((&watchdog_timer)){+.-.}-{0:0}, at: call_timer_fn+0x110/0x4d0 [ 7.417602][ C0] #2: ffffffff9d5ce8b8 (watchdog_lock){+.-.}-{3:3}, at: clocksource_watchdog+0x15/0x30 [ 7.417606][ C0] #3: ffffffff9d569cc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 7.417610][ C0] #4: ffffffff9d569d38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 7.417613][ C0] [ 7.417613][ C0] the shortest dependencies between 2nd lock and 1st lock: [ 7.417618][ C0] -> (fs_reclaim){+.+.}-{0:0} { [ 7.417620][ C0] HARDIRQ-ON-W at: [ 7.417622][ C0] __lock_acquire+0x388/0xc20 [ 7.417625][ C0] lock_acquire.part.0+0xd4/0x280 [ 7.417627][ C0] fs_reclaim_acquire+0xd5/0x120 [ 7.417630][ C0] __kmalloc_cache_noprof+0x6e/0x620 [ 7.417632][ C0] kthread_create_worker_on_node+0xea/0x210 [ 7.417635][ C0] workqueue_init+0x2a/0x680 [ 7.417639][ C0] kernel_init_freeable+0x2fe/0x630 [ 7.417641][ C0] kernel_init+0x21/0x150 [ 7.417645][ C0] ret_from_fork+0x474/0x6b0 [ 7.417648][ C0] ret_from_fork_asm+0x11/0x20 [ 7.417651][ C0] SOFTIRQ-ON-W at: [ 7.417651][ C0] __lock_acquire+0x388/0xc20 [ 7.417653][ C0] lock_acquire.part.0+0xd4/0x280 [ 7.417654][ C0] fs_reclaim_acquire+0xd5/0x120 [ 7.417655][ C0] __kmalloc_cache_noprof+0x6e/0x620 [ 7.417657][ C0] kthread_create_worker_on_node+0xea/0x210 [ 7.417658][ C0] workqueue_init+0x2a/0x680 [ 7.417659][ C0] kernel_init_freeable+0x2fe/0x630 [ 7.417661][ C0] kernel_init+0x21/0x150 [ 7.417662][ C0] ret_from_fork+0x474/0x6b0 [ 7.417663][ C0] ret_from_fork_asm+0x11/0x20 [ 7.417665][ C0] INITIAL USE at: [ 7.417665][ C0] __lock_acquire+0x388/0xc20 [ 7.417667][ C0] lock_acquire.part.0+0xd4/0x280 [ 7.417668][ C0] fs_reclaim_acquire+0xd5/0x120 [ 7.417669][ C0] __kmalloc_cache_noprof+0x6e/0x620 [ 7.417671][ C0] kthread_create_worker_on_node+0xea/0x210 [ 7.417672][ C0] workqueue_init+0x2a/0x680 [ 7.417673][ C0] kernel_init_freeable+0x2fe/0x630 [ 7.417674][ C0] kernel_init+0x21/0x150 [ 7.417676][ C0] ret_from_fork+0x474/0x6b0 [ 7.417677][ C0] ret_from_fork_asm+0x11/0x20 [ 7.417678][ C0] } [ 7.417679][ C0] ... key at: [] __fs_reclaim_map+0x0/0xbe0 [ 7.417683][ C0] ... acquired at: [ 7.417684][ C0] __lock_acquire+0x518/0xc20 [ 7.417685][ C0] lock_acquire.part.0+0xd4/0x280 [ 7.417686][ C0] fs_reclaim_acquire+0xd5/0x120 [ 7.417687][ C0] __kmalloc_noprof+0xd3/0x760 [ 7.417689][ C0] alloc_buf.isra.0+0x4b/0x260 [ 7.417692][ C0] put_chars+0x1e1/0x2f0 [ 7.417693][ C0] hvc_console_print+0x292/0x780 [ 7.417695][ C0] console_emit_next_record+0x22f/0x4c0 [ 7.417696][ C0] console_flush_one_record+0x46f/0x710 [ 7.417698][ C0] console_unlock+0xee/0x1f0 [ 7.417699][ C0] vprintk_emit+0x37c/0x3e0 [ 7.417701][ C0] _printk+0xc7/0x100 [ 7.417704][ C0] i8042_pnp_init+0xf7/0x3c0 [ 7.417707][ C0] i8042_platform_init+0x3f9/0x460 [ 7.417709][ C0] i8042_init+0x45/0x130 [ 7.417710][ C0] do_one_initcall+0x124/0x4f0 [ 7.417712][ C0] kernel_init_freeable+0x596/0x630 [ 7.417713][ C0] kernel_init+0x21/0x150 [ 7.417714][ C0] ret_from_fork+0x474/0x6b0 [ 7.417715][ C0] ret_from_fork_asm+0x11/0x20 [ 7.417717][ C0] [ 7.417717][ C0] -> (console_owner){..-.}-{0:0} { [ 7.417719][ C0] IN-SOFTIRQ-W at: [ 7.417720][ C0] __lock_acquire+0x388/0xc20 [ 7.417721][ C0] lock_acquire.part.0+0xd4/0x280 [ 7.417722][ C0] console_lock_spinning_enable+0x5c/0x60 [ 7.417724][ C0] console_emit_next_record+0x1d1/0x4c0 [ 7.417725][ C0] console_flush_one_record+0x46f/0x710 [ 7.417727][ C0] console_unlock+0xee/0x1f0 [ 7.417728][ C0] vprintk_emit+0x37c/0x3e0 [ 7.417730][ C0] _printk+0xc7/0x100 [ 7.417731][ C0] clocksource_watchdog.part.0.cold+0x105/0x1dd [ 7.417734][ C0] call_timer_fn+0x160/0x4d0 [ 7.417735][ C0] __run_timers+0x68f/0xaa0 [ 7.417737][ C0] run_timer_softirq+0xf0/0x160 [ 7.417738][ C0] handle_softirqs+0x1d3/0x900 [ 7.417741][ C0] __irq_exit_rcu+0x145/0x1c0 [ 7.417742][ C0] irq_exit_rcu+0xe/0x30 [ 7.417743][ C0] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 7.417745][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 7.417747][ C0] unwind_next_frame+0x13c0/0x1ea0 [ 7.417748][ C0] arch_stack_walk+0xd7/0x130 [ 7.417750][ C0] stack_trace_save+0x93/0xc0 [ 7.417752][ C0] kasan_save_stack+0x2f/0x50 [ 7.417754][ C0] kasan_save_track+0x14/0x30 [ 7.417756][ C0] __kasan_kmalloc+0x7b/0x90 [ 7.417757][ C0] __kmalloc_noprof+0x2e3/0x760 [ 7.417759][ C0] copy_args_to_argbuf+0x132/0x2b0 [ 7.417762][ C0] virtio_fs_enqueue_req+0x3d5/0xfa0 [ 7.417764][ C0] virtio_fs_send_req+0xf6/0x480 [ 7.417766][ C0] fuse_chan_send+0x45f/0x7a0 [ 7.417768][ C0] fuse_send_open+0x222/0x350 [ 7.417770][ C0] fuse_file_open+0x31d/0x8f0 [ 7.417772][ C0] fuse_open+0x1b9/0x800 [ 7.417773][ C0] do_dentry_open+0x408/0x1310 [ 7.417775][ C0] vfs_open+0x7a/0x440 [ 7.417777][ C0] backing_file_open+0x83/0xf0 [ 7.417779][ C0] ovl_open_realfile+0x2bb/0x370 [ 7.417781][ C0] ovl_open+0x1ab/0x2f0 [ 7.417783][ C0] do_dentry_open+0x408/0x1310 [ 7.417784][ C0] vfs_open+0x7a/0x440 [ 7.417785][ C0] do_open+0x646/0xdd0 [ 7.417787][ C0] path_openat+0x1d0/0x3b0 [ 7.417789][ C0] do_file_open+0x209/0x480 [ 7.417790][ C0] do_sys_openat2+0xe0/0x170 [ 7.417791][ C0] __x64_sys_openat+0x10e/0x210 [ 7.417793][ C0] do_syscall_64+0xff/0x530 [ 7.417794][ C0] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 7.417796][ C0] INITIAL USE at: [ 7.417797][ C0] } [ 7.417797][ C0] ... key at: [] console_owner_dep_map+0x0/0x60 [ 7.417800][ C0] ... acquired at: [ 7.417801][ C0] mark_lock+0x1d7/0xa00 [ 7.417802][ C0] mark_usage+0x42/0x170 [ 7.417803][ C0] __lock_acquire+0x388/0xc20 [ 7.417805][ C0] lock_acquire.part.0+0xd4/0x280 [ 7.417806][ C0] console_lock_spinning_enable+0x5c/0x60 [ 7.417808][ C0] console_emit_next_record+0x1d1/0x4c0 [ 7.417809][ C0] console_flush_one_record+0x46f/0x710 [ 7.417811][ C0] console_unlock+0xee/0x1f0 [ 7.417812][ C0] vprintk_emit+0x37c/0x3e0 [ 7.417814][ C0] _printk+0xc7/0x100 [ 7.417815][ C0] clocksource_watchdog.part.0.cold+0x105/0x1dd [ 7.417816][ C0] call_timer_fn+0x160/0x4d0 [ 7.417818][ C0] __run_timers+0x68f/0xaa0 [ 7.417819][ C0] run_timer_softirq+0xf0/0x160 [ 7.417821][ C0] handle_softirqs+0x1d3/0x900 [ 7.417822][ C0] __irq_exit_rcu+0x145/0x1c0 [ 7.417823][ C0] irq_exit_rcu+0xe/0x30 [ 7.417824][ C0] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 7.417826][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 7.417827][ C0] unwind_next_frame+0x13c0/0x1ea0 [ 7.417828][ C0] arch_stack_walk+0xd7/0x130 [ 7.417830][ C0] stack_trace_save+0x93/0xc0 [ 7.417831][ C0] kasan_save_stack+0x2f/0x50 [ 7.417832][ C0] kasan_save_track+0x14/0x30 [ 7.417833][ C0] __kasan_kmalloc+0x7b/0x90 [ 7.417835][ C0] __kmalloc_noprof+0x2e3/0x760 [ 7.417836][ C0] copy_args_to_argbuf+0x132/0x2b0 [ 7.417838][ C0] virtio_fs_enqueue_req+0x3d5/0xfa0 [ 7.417839][ C0] virtio_fs_send_req+0xf6/0x480 [ 7.417841][ C0] fuse_chan_send+0x45f/0x7a0 [ 7.417842][ C0] fuse_send_open+0x222/0x350 [ 7.417843][ C0] fuse_file_open+0x31d/0x8f0 [ 7.417845][ C0] fuse_open+0x1b9/0x800 [ 7.417846][ C0] do_dentry_open+0x408/0x1310 [ 7.417848][ C0] vfs_open+0x7a/0x440 [ 7.417849][ C0] backing_file_open+0x83/0xf0 [ 7.417850][ C0] ovl_open_realfile+0x2bb/0x370 [ 7.417851][ C0] ovl_open+0x1ab/0x2f0 [ 7.417853][ C0] do_dentry_open+0x408/0x1310 [ 7.417854][ C0] vfs_open+0x7a/0x440 [ 7.417855][ C0] do_open+0x646/0xdd0 [ 7.417856][ C0] path_openat+0x1d0/0x3b0 [ 7.417858][ C0] do_file_open+0x209/0x480 [ 7.417859][ C0] do_sys_openat2+0xe0/0x170 [ 7.417860][ C0] __x64_sys_openat+0x10e/0x210 [ 7.417862][ C0] do_syscall_64+0xff/0x530 [ 7.417863][ C0] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 7.417864][ C0] [ 7.417864][ C0] [ 7.417864][ C0] stack backtrace: [ 7.417867][ C0] CPU: 0 UID: 0 PID: 103 Comm: udevadm Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 7.417870][ C0] Tainted: [W]=WARN [ 7.417871][ C0] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 7.417873][ C0] Call Trace: [ 7.417874][ C0] [ 7.417875][ C0] dump_stack_lvl+0x6f/0xa0 [ 7.417879][ C0] print_irq_inversion_bug.part.0.cold+0xe6/0x143 [ 7.417882][ C0] mark_lock_irq+0x989/0x9c0 [ 7.417883][ C0] ? add_lock_to_list+0x2c/0x1a0 [ 7.417886][ C0] mark_lock+0x1d7/0xa00 [ 7.417888][ C0] mark_usage+0x42/0x170 [ 7.417890][ C0] __lock_acquire+0x388/0xc20 [ 7.417892][ C0] lock_acquire.part.0+0xd4/0x280 [ 7.417894][ C0] ? console_lock_spinning_enable+0x40/0x60 [ 7.417896][ C0] ? rcu_is_watching+0x16/0xd0 [ 7.417899][ C0] ? lock_acquire+0x13c/0x160 [ 7.417901][ C0] console_lock_spinning_enable+0x5c/0x60 [ 7.417903][ C0] ? console_lock_spinning_enable+0x40/0x60 [ 7.417904][ C0] console_emit_next_record+0x1d1/0x4c0 [ 7.417906][ C0] ? devkmsg_read+0x4b0/0x4b0 [ 7.417908][ C0] ? console_flush_one_record+0x106/0x710 [ 7.417910][ C0] ? rcu_is_watching+0x16/0xd0 [ 7.417912][ C0] ? lock_acquire+0x13c/0x160 [ 7.417914][ C0] console_flush_one_record+0x46f/0x710 [ 7.417916][ C0] ? console_emit_next_record+0x4c0/0x4c0 [ 7.417918][ C0] ? __lock_acquire+0x518/0xc20 [ 7.417920][ C0] console_unlock+0xee/0x1f0 [ 7.417922][ C0] ? console_flush_one_record+0x710/0x710 [ 7.417923][ C0] ? rcu_is_watching+0x16/0xd0 [ 7.417925][ C0] ? lock_acquire+0x60/0x160 [ 7.417927][ C0] ? __down_trylock_console_sem+0x5e/0xa0 [ 7.417929][ C0] ? vprintk_emit+0x320/0x3e0 [ 7.417931][ C0] vprintk_emit+0x37c/0x3e0 [ 7.417933][ C0] ? wake_up_klogd_work_func+0x90/0x90 [ 7.417935][ C0] ? clocksource_watchdog.part.0+0x450/0x540 [ 7.417937][ C0] _printk+0xc7/0x100 [ 7.417939][ C0] ? snapshot_read.cold+0x21/0x21 [ 7.417941][ C0] ? clocksource_watchdog.part.0+0x450/0x540 [ 7.417942][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 7.417945][ C0] clocksource_watchdog.part.0.cold+0x105/0x1dd [ 7.417946][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 7.417948][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 7.417950][ C0] call_timer_fn+0x160/0x4d0 [ 7.417952][ C0] ? detach_if_pending+0x1d0/0x1d0 [ 7.417953][ C0] ? debug_object_active_state+0x430/0x430 [ 7.417956][ C0] ? find_held_lock+0x2b/0x80 [ 7.417958][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 7.417960][ C0] ? rcu_is_watching+0x16/0xd0 [ 7.417962][ C0] __run_timers+0x68f/0xaa0 [ 7.417964][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 7.417966][ C0] ? __bpf_trace_itimer_expire+0x10/0x10 [ 7.417968][ C0] ? __lock_acquire+0x518/0xc20 [ 7.417970][ C0] ? __rwlock_init+0x150/0x150 [ 7.417973][ C0] run_timer_softirq+0xf0/0x160 [ 7.417975][ C0] ? __run_timers+0xaa0/0xaa0 [ 7.417977][ C0] ? rcu_is_watching+0x16/0xd0 [ 7.417979][ C0] handle_softirqs+0x1d3/0x900 [ 7.417980][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 7.417982][ C0] ? _local_bh_enable+0xc0/0xc0 [ 7.417984][ C0] __irq_exit_rcu+0x145/0x1c0 [ 7.417985][ C0] irq_exit_rcu+0xe/0x30 [ 7.417987][ C0] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 7.417989][ C0] [ 7.417989][ C0] [ 7.417990][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 7.417992][ C0] RIP: 0010:unwind_next_frame+0x13c0/0x1ea0 [ 7.417994][ C0] Code: 03 48 89 c6 48 c1 ee 03 0f b6 14 16 48 89 fe 83 e6 07 40 38 f1 40 0f 9e c6 84 c9 0f 95 c1 40 84 ce 0f 85 d1 08 00 00 83 e0 07 <38> c2 0f 9e c1 84 d2 0f 95 c0 84 c1 0f 85 bc 08 00 00 4d 0f bf 60 [ 7.417996][ C0] RSP: 0018:ffa0000000676e08 EFLAGS: 00000202 [ 7.417999][ C0] RAX: 0000000000000007 RBX: 1ff40000000cedca RCX: 0000000000000000 [ 7.418000][ C0] RDX: 0000000000000000 RSI: 0000000000000001 RDI: ffffffff9e1e5356 [ 7.418001][ C0] RBP: 0000000000000001 R08: ffffffff9e1e5354 R09: ffffffff9df9546c [ 7.418002][ C0] R10: ffffffff9aba83c4 R11: 0000000000000008 R12: ffffffff9e1e5359 [ 7.418003][ C0] R13: ffa0000000676f08 R14: ffa0000000677250 R15: 0000000000000820 [ 7.418004][ C0] ? kasan_save_track+0x14/0x30 [ 7.418007][ C0] ? unwind_next_frame+0x476/0x1ea0 [ 7.418008][ C0] ? kasan_save_stack+0x2f/0x50 [ 7.418010][ C0] ? stack_access_ok+0x1e0/0x1e0 [ 7.418011][ C0] ? validate_chain+0x737/0xc20 [ 7.418013][ C0] ? __unwind_start+0x42e/0x900 [ 7.418015][ C0] ? write_profile+0xf0/0xf0 [ 7.418016][ C0] arch_stack_walk+0xd7/0x130 [ 7.418019][ C0] ? kasan_save_track+0x14/0x30 [ 7.418021][ C0] stack_trace_save+0x93/0xc0 [ 7.418022][ C0] ? filter_irq_stacks+0xd0/0xd0 [ 7.418024][ C0] ? fuse_chan_send+0x45f/0x7a0 [ 7.418025][ C0] ? __unwind_start+0x42e/0x900 [ 7.418027][ C0] kasan_save_stack+0x2f/0x50 [ 7.418028][ C0] ? kasan_save_stack+0x2f/0x50 [ 7.418034][ C0] kasan_save_track+0x14/0x30 [ 7.418036][ C0] __kasan_kmalloc+0x7b/0x90 [ 7.418038][ C0] __kmalloc_noprof+0x2e3/0x760 [ 7.418039][ C0] ? validate_chain+0x38b/0xc20 [ 7.418041][ C0] ? copy_args_to_argbuf+0x132/0x2b0 [ 7.418043][ C0] copy_args_to_argbuf+0x132/0x2b0 [ 7.418045][ C0] ? net_dim+0x616/0xd70 [ 7.418048][ C0] virtio_fs_enqueue_req+0x3d5/0xfa0 [ 7.418050][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 7.418052][ C0] ? fuse_open+0x1b9/0x800 [ 7.418053][ C0] ? virtio_fs_probe+0x310/0x310 [ 7.418055][ C0] ? rcu_read_lock_any_held+0x3c/0x90 [ 7.418058][ C0] ? rcu_is_watching+0x16/0xd0 [ 7.418059][ C0] ? __create_object+0x78/0x110 [ 7.418064][ C0] ? __rwlock_init+0x150/0x150 [ 7.418066][ C0] ? do_raw_spin_unlock+0x59/0x250 [ 7.418068][ C0] virtio_fs_send_req+0xf6/0x480 [ 7.418070][ C0] fuse_chan_send+0x45f/0x7a0 [ 7.418072][ C0] fuse_send_open+0x222/0x350 [ 7.418074][ C0] ? fuse_folios_need_send+0x440/0x440 [ 7.418078][ C0] ? __init_waitqueue_head+0xcb/0x160 [ 7.418080][ C0] ? fuse_file_alloc+0x1c1/0x2a0 [ 7.418082][ C0] ? lock_acquire.part.0+0xd4/0x280 [ 7.418083][ C0] fuse_file_open+0x31d/0x8f0 [ 7.418085][ C0] fuse_open+0x1b9/0x800 [ 7.418087][ C0] ? fuse_finish_open+0x2a0/0x2a0 [ 7.418089][ C0] do_dentry_open+0x408/0x1310 [ 7.418092][ C0] vfs_open+0x7a/0x440 [ 7.418093][ C0] ? do_raw_spin_unlock+0x59/0x250 [ 7.418095][ C0] backing_file_open+0x83/0xf0 [ 7.418097][ C0] ? inode_owner_or_capable+0x97/0x190 [ 7.418099][ C0] ovl_open_realfile+0x2bb/0x370 [ 7.418101][ C0] ovl_open+0x1ab/0x2f0 [ 7.418103][ C0] ? ovl_llseek+0x2e0/0x2e0 [ 7.418105][ C0] ? do_raw_spin_unlock+0x59/0x250 [ 7.418107][ C0] ? ovl_llseek+0x2e0/0x2e0 [ 7.418108][ C0] do_dentry_open+0x408/0x1310 [ 7.418111][ C0] vfs_open+0x7a/0x440 [ 7.418112][ C0] ? may_open+0xe6/0x330 [ 7.418114][ C0] do_open+0x646/0xdd0 [ 7.418116][ C0] ? open_last_lookups+0x7e3/0x1030 [ 7.418117][ C0] path_openat+0x1d0/0x3b0 [ 7.418119][ C0] ? rcu_read_lock_any_held+0x3c/0x90 [ 7.418121][ C0] ? kernel_tmpfile_open+0x70/0x70 [ 7.418122][ C0] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 7.418125][ C0] do_file_open+0x209/0x480 [ 7.418127][ C0] ? path_openat+0x3b0/0x3b0 [ 7.418128][ C0] ? __lock_acquire+0x518/0xc20 [ 7.418131][ C0] ? page_get_link_raw+0x70/0x70 [ 7.418133][ C0] ? _raw_spin_unlock+0x2d/0x50 [ 7.418135][ C0] ? alloc_fd+0x33a/0x600 [ 7.418139][ C0] do_sys_openat2+0xe0/0x170 [ 7.418140][ C0] ? build_open_flags+0x6e0/0x6e0 [ 7.418142][ C0] ? rcu_is_watching+0x16/0xd0 [ 7.418144][ C0] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 7.418147][ C0] ? lockdep_hardirqs_on+0x91/0x130 [ 7.418149][ C0] __x64_sys_openat+0x10e/0x210 [ 7.418150][ C0] ? fput_close_sync+0xde/0x1b0 [ 7.418152][ C0] ? __ia32_sys_open+0x1b0/0x1b0 [ 7.418154][ C0] ? rcu_is_watching+0x16/0xd0 [ 7.418156][ C0] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 7.418157][ C0] do_syscall_64+0xff/0x530 [ 7.418158][ C0] ? exc_page_fault+0xee/0x100 [ 7.418160][ C0] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 7.418161][ C0] RIP: 0033:0x7fbaeb3a36e5 [ 7.418164][ C0] Code: 48 89 e5 48 89 55 e0 41 83 e2 40 75 28 89 f2 f7 d2 81 e2 00 00 41 00 74 1c 89 f2 bf 9c ff ff ff 48 89 c6 b8 01 01 00 00 0f 05 <48> 3d 00 f0 ff ff 77 23 5d c3 90 48 8d 4d 10 c7 45 b8 10 00 00 00 [ 7.418165][ C0] RSP: 002b:00007fff50b21ca0 EFLAGS: 00000206 ORIG_RAX: 0000000000000101 [ 7.418167][ C0] RAX: ffffffffffffffda RBX: 00007fff50b21d90 RCX: 00007fbaeb3a36e5 [ 7.418168][ C0] RDX: 0000000000080000 RSI: 00007fbaeb375bf0 RDI: 00000000ffffff9c [ 7.418169][ C0] RBP: 00007fff50b21ca0 R08: 00007fff50b21d77 R09: 0000000000000000 [ 7.418169][ C0] R10: 0000000000000000 R11: 0000000000000206 R12: 00007fbaeb375bf0 [ 7.418170][ C0] R13: 0000000000000000 R14: 0000000000000000 R15: 0000000000000000 [ 7.418173][ C0] [ 7.743201][ T76] virtme-ng-init: udev is done [ 7.745279][ T1] virtme-ng-init: initialization done