virtme: waiting for virtiofsd to start qemu-system-x86_64: warning: hub 0 is not connected to host network [ 1.812281][ T1] loop: module loaded [ 1.812302][ T1] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 1.812304][ T1] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 1, name: swapper/0 [ 1.812306][ T1] preempt_count: 1, expected: 0 [ 1.812307][ T1] RCU nest depth: 0, expected: 0 [ 1.812308][ T1] locks held by swapper/0/1: 4, last CPU#5: [ 1.812310][ T1] #0: ffffffff9df99dc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 1.812321][ T1] #1: ffffffff9df99e38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 1.812325][ T1] #2: ffffffff9de89760 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 1.812329][ T1] #3: ffffffff9de89660 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x202/0x4f0 [ 1.812333][ T1] irq event stamp: 836868 [ 1.812334][ T1] hardirqs last enabled at (836867): [] __down_trylock_console_sem+0x86/0xa0 [ 1.812337][ T1] hardirqs last disabled at (836868): [] console_emit_next_record+0x3f8/0x4f0 [ 1.812339][ T1] softirqs last enabled at (836814): [] bdi_register_va+0x491/0x780 [ 1.812342][ T1] softirqs last disabled at (836812): [] bdi_register_va+0x2ef/0x780 [ 1.812345][ T1] Preemption disabled at: [ 1.812345][ T1] [] vprintk_emit+0x31b/0x3e0 [ 1.812350][ T1] CPU: 5 UID: 0 PID: 1 Comm: swapper/0 Not tainted 7.2.0-virtme #1 PREEMPT(full) [ 1.812353][ T1] Hardware name: QEMU Standard PC (Q35 + ICH9, 2009), BIOS rel-1.16.3-0-ga6ed6b701f0a-prebuilt.qemu.org 04/01/2014 [ 1.812355][ T1] Call Trace: [ 1.812357][ T1] [ 1.812359][ T1] dump_stack_lvl+0x6f/0xa0 [ 1.812365][ T1] ? vprintk_emit+0x31b/0x3e0 [ 1.812367][ T1] __might_resched.cold+0x1fe/0x2c1 [ 1.812372][ T1] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 1.812376][ T1] ? __kmalloc_noprof+0xdb/0x760 [ 1.812380][ T1] __kmalloc_noprof+0x443/0x760 [ 1.812383][ T1] ? alloc_buf.isra.0+0x4b/0x260 [ 1.812389][ T1] ? do_raw_spin_unlock+0x59/0x250 [ 1.812392][ T1] alloc_buf.isra.0+0x4b/0x260 [ 1.812395][ T1] put_chars+0x1e1/0x2f0 [ 1.812397][ T1] ? desc_read+0x271/0x2f0 [ 1.812400][ T1] ? __send_to_port+0x420/0x420 [ 1.812406][ T1] hvc_console_print+0x292/0x780 [ 1.812413][ T1] ? hvc_write+0x3a0/0x3a0 [ 1.812416][ T1] ? lock_acquire+0x13c/0x160 [ 1.812420][ T1] console_emit_next_record+0x252/0x4f0 [ 1.812424][ T1] ? devkmsg_read+0x4e0/0x4e0 [ 1.812428][ T1] ? rcu_is_watching+0x16/0xd0 [ 1.812432][ T1] ? lock_acquire+0x13c/0x160 [ 1.812435][ T1] console_flush_one_record+0x46f/0x710 [ 1.812439][ T1] ? console_emit_next_record+0x4f0/0x4f0 [ 1.812441][ T1] ? __lock_acquire+0x518/0xc20 [ 1.812446][ T1] console_unlock+0xee/0x1f0 [ 1.812449][ T1] ? console_flush_one_record+0x710/0x710 [ 1.812451][ T1] ? rcu_is_watching+0x16/0xd0 [ 1.812453][ T1] ? lock_acquire+0xa0/0x160 [ 1.812456][ T1] ? __down_trylock_console_sem+0x5e/0xa0 [ 1.812458][ T1] ? vprintk_emit+0x320/0x3e0 [ 1.812461][ T1] vprintk_emit+0x37c/0x3e0 [ 1.812465][ T1] ? wake_up_klogd_work_func+0x90/0x90 [ 1.812470][ T1] ? max_loop_setup+0x30/0x30 [ 1.812474][ T1] _printk+0xc7/0x100 [ 1.812477][ T1] ? snapshot_read.cold+0x21/0x21 [ 1.812483][ T1] ? __mutex_unlock_slowpath+0x14d/0x740 [ 1.812488][ T1] loop_init+0x12a/0x130 [ 1.812490][ T1] do_one_initcall+0x124/0x4f0 [ 1.812493][ T1] ? trace_event_raw_event_initcall_level+0x280/0x280 [ 1.812494][ T1] ? parameq+0x110/0x110 [ 1.812500][ T1] ? kernel_init_freeable+0x3f1/0x630 [ 1.812503][ T1] ? rcu_is_watching+0x16/0xd0 [ 1.812507][ T1] kernel_init_freeable+0x596/0x630 [ 1.812510][ T1] ? rest_init+0x280/0x280 [ 1.812512][ T1] kernel_init+0x21/0x150 [ 1.812514][ T1] ? rest_init+0x280/0x280 [ 1.812515][ T1] ret_from_fork+0x474/0x6b0 [ 1.812519][ T1] ? arch_exit_to_user_mode_prepare.isra.0+0x120/0x120 [ 1.812522][ T1] ? __switch_to+0x5a3/0xe00 [ 1.812525][ T1] ? rest_init+0x280/0x280 [ 1.812527][ T1] ret_from_fork_asm+0x11/0x20 [ 1.812535][ T1] [ 1.862294][ T1] ACPI: \_SB_.GSIA: Enabled at IRQ 16 [ 1.865223][ T1] ahci 0000:00:1f.2: AHCI vers 0001.0000, 32 command slots, 1.5 Gbps, SATA mode [ 1.865751][ T1] ahci 0000:00:1f.2: 6/6 ports implemented (port mask 0x3f) [ 1.866094][ T1] ahci 0000:00:1f.2: flags: 64bit ncq only [ 1.883182][ T1] scsi host0: ahci [ 1.886574][ T1] scsi host1: ahci [ 1.889710][ T1] scsi host2: ahci [ 1.892749][ T1] scsi host3: ahci [ 1.896109][ T1] scsi host4: ahci [ 1.899234][ T1] scsi host5: ahci [ 1.901128][ T1] ata1: SATA max UDMA/133 abar m4096@0xfe444000 port 0xfe444100 irq 30 lpm-pol 1 [ 1.901710][ T1] ata2: SATA max UDMA/133 abar m4096@0xfe444000 port 0xfe444180 irq 30 lpm-pol 1 [ 1.902117][ T1] ata3: SATA max UDMA/133 abar m4096@0xfe444000 port 0xfe444200 irq 30 lpm-pol 1 [ 1.902530][ T1] ata4: SATA max UDMA/133 abar m4096@0xfe444000 port 0xfe444280 irq 30 lpm-pol 1 [ 1.902926][ T1] ata5: SATA max UDMA/133 abar m4096@0xfe444000 port 0xfe444300 irq 30 lpm-pol 1 [ 1.903344][ T1] ata6: SATA max UDMA/133 abar m4096@0xfe444000 port 0xfe444380 irq 30 lpm-pol 1 [ 1.917947][ T1] e1000: Intel(R) PRO/1000 Network Driver [ 1.918203][ T1] e1000: Copyright (c) 1999-2006 Intel Corporation. [ 1.918800][ T1] e1000e: Intel(R) PRO/1000 Network Driver [ 1.919015][ T1] e1000e: Copyright(c) 1999 - 2015 Intel Corporation. [ 1.919536][ T1] sky2: driver version 1.30 fbnic-err: bad register access fbnic_mac_read_rxb address 0x80e9 [ 2.031663][ T104] kwatchdog (104) used greatest stack depth: 29688 bytes left [ 2.124615][ T1] Meta(R) Host Network Interface Driver (fbnic) [ 2.125395][ T1] PPP generic driver version 2.4.2 [ 2.126456][ T1] NET: Registered PF_PPPOX protocol family [ 2.127622][ T1] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 2.129779][ T1] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 2.131081][ T1] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 2.135178][ T87] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 2.136723][ T1] rtc_cmos PNP0B00:00: RTC can wake from S4 [ 2.140986][ T1] rtc_cmos PNP0B00:00: registered as rtc0 [ 2.141424][ T1] rtc_cmos PNP0B00:00: setting system clock to 2026-08-28T16:58:56 UTC (1787936336) [ 2.142398][ T1] rtc_cmos PNP0B00:00: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 2.143616][ T87] input: ImExPS/2 Generic Explorer Mouse as /devices/platform/i8042/serio1/input/input3 [ 2.145919][ T1] i6300ESB timer 0000:00:02.0: initialized. heartbeat=30 sec (nowayout=0) [ 2.147441][ T1] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev [ 2.154781][ T1] ipip: IPv4 and MPLS over IPv4 tunneling driver [ 2.158342][ T1] gre: GRE over IPv4 demultiplexer driver [ 2.158524][ T1] ip_gre: GRE over IPv4 tunneling driver [ 2.166563][ T1] Initializing XFRM netlink socket [ 2.167156][ T1] NET: Registered PF_INET6 protocol family [ 2.174108][ T1] Segment Routing with IPv6 [ 2.174551][ T1] In-situ OAM (IOAM) with IPv6 [ 2.174887][ T1] sit: IPv6, IPv4 and MPLS over IPv4 tunneling driver [ 2.181630][ T1] ip6_gre: GRE over IPv6 tunneling driver [ 2.184975][ T1] NET: Registered PF_PACKET protocol family [ 2.185256][ T1] 9pnet: Installing 9P2000 support [ 2.185682][ T1] Key type dns_resolver registered [ 2.186372][ T1] NET: Registered PF_VSOCK protocol family [ 2.192065][ T1] IPI shorthand broadcast: enabled [ 2.213729][ T83] ata3: SATA link up 1.5 Gbps (SStatus 113 SControl 300) [ 2.214376][ T88] ata5: SATA link down (SStatus 0 SControl 300) [ 2.214979][ T79] ata1: SATA link down (SStatus 0 SControl 300) [ 2.215544][ T83] ata3.00: ATAPI: QEMU DVD-ROM, 2.5+, max UDMA/100 [ 2.215889][ T83] ata3.00: applying bridge limits [ 2.216389][ T83] ata3.00: configured for UDMA/100 [ 2.220756][ T81] ata2: SATA link down (SStatus 0 SControl 300) [ 2.221522][ T85] ata4: SATA link down (SStatus 0 SControl 300) [ 2.222365][ T90] ata6: SATA link down (SStatus 0 SControl 300) [ 2.224052][ T93] scsi 2:0:0:0: CD-ROM QEMU QEMU DVD-ROM 2.5+ PQ: 0 ANSI: 5 [ 2.260713][ T93] scsi 2:0:0:0: Attached scsi generic sg0 type 5 [ 2.295100][ T1] sched_clock: Marking stable (2267001765, 27127357)->(2405496060, -111366938) [ 2.298812][ T1] registered taskstats version 1 [ 2.302638][ T1] Loading compiled-in X.509 certificates [ 2.408349][ T1] Demotion targets for Node 0: null [ 2.408714][ T1] kmemleak: Kernel memory leak detector initialized (mem pool available: 11278) [ 2.409015][ T1] page_owner is disabled [ 2.418026][ T1] PM: Magic number: 6:258:996 [ 2.420619][ T1] ALSA device list: [ 2.421423][ T1] No soundcards found. [ 2.422786][ T1] md: Skipping autodetection of RAID arrays. (raid=autodetect will force) [ 2.425338][ T1] VFS: Mounted root (virtiofs filesystem) readonly on device 0:24. [ 2.426072][ T1] devtmpfs: mounted [ 2.426356][ T1] VFS: Pivoted into new rootfs [ 2.455694][ T1] Freeing unused kernel image (initmem) memory: 2592K [ 2.455941][ T1] Write protecting the kernel read-only data: 65536k [ 2.457083][ T1] Freeing unused kernel image (text/rodata gap) memory: 1152K [ 2.458028][ T1] Freeing unused kernel image (rodata/data gap) memory: 624K [ 2.458651][ T1] Run /usr/lib/python3.14/site-packages/virtme/guest/bin/virtme-ng-init as init process [ 2.458955][ T1] with arguments: [ 2.459086][ T1] /usr/lib/python3.14/site-packages/virtme/guest/bin/virtme-ng-init [ 2.460282][ T1] with environment: [ 2.460406][ T1] HOME=/ [ 2.460534][ T1] TERM=dumb [ 2.460672][ T1] virtme_hostname=vmksft-fbnic-qemu-dbg,debug-threads=on [ 2.460905][ T1] nr_open=2147483584 [ 2.461023][ T1] virtme_link_mods=/srv/vmksft/testing/wt-24/.virtme_mods/lib/modules/0.0.0 [ 2.461319][ T1] virtme_rw_overlay0=/etc [ 2.461476][ T1] virtme_rw_overlay1=/lib [ 2.461637][ T1] virtme_rw_overlay2=/home [ 2.461799][ T1] virtme_rw_overlay3=/opt [ 2.461958][ T1] virtme_rw_overlay4=/srv [ 2.462121][ T1] virtme_rw_overlay5=/usr [ 2.462288][ T1] virtme_rw_overlay6=/var [ 2.462449][ T1] virtme_rw_overlay7=/tmp [ 2.462608][ T1] virtme_console=ttyS0 [ 2.462770][ T1] virtme_chdir=srv/vmksft/testing/wt-24 [ 2.475468][ T1] virtme-ng-init: mount devtmpfs -> /dev: EBUSY: Device or resource busy [ 2.476986][ T1] virtme-ng-init: Setting hostname to vmksft-fbnic-qemu-dbg,debug-threads=on... [ 2.488947][ T1] overlayfs: failed to set xattr on upper [ 2.489316][ T1] overlayfs: ...falling back to redirect_dir=nofollow. [ 2.489580][ T1] overlayfs: ...falling back to uuid=null. [ 2.491532][ T1] overlayfs: failed to set xattr on upper [ 2.491743][ T1] overlayfs: ...falling back to redirect_dir=nofollow. [ 2.491991][ T1] overlayfs: ...falling back to uuid=null. [ 2.493710][ T1] overlayfs: failed to set xattr on upper [ 2.493910][ T1] overlayfs: ...falling back to redirect_dir=nofollow. [ 2.494161][ T1] overlayfs: ...falling back to uuid=null. [ 2.495878][ T1] overlayfs: failed to set xattr on upper [ 2.496079][ T1] overlayfs: ...falling back to redirect_dir=nofollow. [ 2.496471][ T1] overlayfs: ...falling back to uuid=null. [ 2.498172][ T1] overlayfs: failed to set xattr on upper [ 2.498378][ T1] overlayfs: ...falling back to redirect_dir=nofollow. [ 2.498628][ T1] overlayfs: ...falling back to uuid=null. [ 2.500185][ T1] overlayfs: failed to set xattr on upper [ 2.500387][ T1] overlayfs: ...falling back to redirect_dir=nofollow. [ 2.500654][ T1] overlayfs: ...falling back to uuid=null. [ 2.502826][ T1] overlayfs: failed to set xattr on upper [ 2.503035][ T1] overlayfs: ...falling back to redirect_dir=nofollow. [ 2.503297][ T1] overlayfs: ...falling back to uuid=null. [ 2.504978][ T1] overlayfs: failed to set xattr on upper [ 2.505195][ T1] overlayfs: ...falling back to redirect_dir=nofollow. [ 2.505433][ T1] overlayfs: ...falling back to uuid=null. [ 2.514568][ T1] virtme-ng-init: running systemd-tmpfiles [ 4.313045][ T1] virtme-ng-init: Failed to read '/usr/lib/tmpfiles.d/audit.conf': Permission denied [ 4.313045][ T1] Failed to create directory or subvolume "/var/spool/at/spool": Permission denied [ 4.313045][ T1] Failed to create file /var/spool/at/.SEQ: Permission denied [ 4.313045][ T1] Failed to opendir() '/proc/self/fd/6': Permission denied [ 4.313064][ T1] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 4.313066][ T1] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 1, name: virtme-ng-init [ 4.313068][ T1] preempt_count: 1, expected: 0 [ 4.313069][ T1] RCU nest depth: 0, expected: 0 [ 4.313070][ T1] locks held by virtme-ng-init/1: 4, last CPU#1: [ 4.313072][ T1] #0: ffffffff9df99dc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 4.313084][ T1] #1: ffffffff9df99e38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 4.313088][ T1] #2: ffffffff9de89760 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 4.313092][ T1] #3: ffffffff9de89660 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x202/0x4f0 [ 4.313096][ T1] irq event stamp: 1259628 [ 4.313097][ T1] hardirqs last enabled at (1259627): [] __down_trylock_console_sem+0x86/0xa0 [ 4.313099][ T1] hardirqs last disabled at (1259628): [] console_emit_next_record+0x3f8/0x4f0 [ 4.313101][ T1] softirqs last enabled at (1258938): [] fpu_clone+0xd9/0x410 [ 4.313104][ T1] softirqs last disabled at (1258936): [] fpu_clone+0x82/0x410 [ 4.313106][ T1] Preemption disabled at: [ 4.313107][ T1] [] vprintk_emit+0x31b/0x3e0 [ 4.313113][ T1] CPU: 1 UID: 0 PID: 1 Comm: virtme-ng-init Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 4.313116][ T1] Tainted: [W]=WARN [ 4.313117][ T1] Hardware name: QEMU Standard PC (Q35 + ICH9, 2009), BIOS rel-1.16.3-0-ga6ed6b701f0a-prebuilt.qemu.org 04/01/2014 [ 4.313119][ T1] Call Trace: [ 4.313121][ T1] [ 4.313122][ T1] dump_stack_lvl+0x6f/0xa0 [ 4.313129][ T1] ? vprintk_emit+0x31b/0x3e0 [ 4.313132][ T1] __might_resched.cold+0x1fe/0x2c1 [ 4.313137][ T1] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 4.313140][ T1] ? __kmalloc_noprof+0xdb/0x760 [ 4.313146][ T1] __kmalloc_noprof+0x443/0x760 [ 4.313148][ T1] ? alloc_buf.isra.0+0x4b/0x260 [ 4.313154][ T1] ? do_raw_spin_unlock+0x59/0x250 [ 4.313157][ T1] alloc_buf.isra.0+0x4b/0x260 [ 4.313160][ T1] put_chars+0x1e1/0x2f0 [ 4.313163][ T1] ? __send_to_port+0x420/0x420 [ 4.313165][ T1] ? printk_get_next_message+0x2fe/0x7d0 [ 4.313168][ T1] ? rcu_read_lock_any_held+0x3c/0x90 [ 4.313171][ T1] ? validate_chain+0x38b/0xc20 [ 4.313175][ T1] hvc_console_print+0x292/0x780 [ 4.313179][ T1] ? __lock_acquire+0x518/0xc20 [ 4.313180][ T1] ? __lock_acquire+0x518/0xc20 [ 4.313185][ T1] ? hvc_write+0x3a0/0x3a0 [ 4.313188][ T1] ? rcu_is_watching+0x16/0xd0 [ 4.313191][ T1] ? lock_acquire+0x13c/0x160 [ 4.313195][ T1] console_emit_next_record+0x252/0x4f0 [ 4.313198][ T1] ? devkmsg_read+0x4e0/0x4e0 [ 4.313203][ T1] ? rcu_is_watching+0x16/0xd0 [ 4.313205][ T1] ? lock_acquire+0x13c/0x160 [ 4.313208][ T1] console_flush_one_record+0x46f/0x710 [ 4.313213][ T1] ? console_emit_next_record+0x4f0/0x4f0 [ 4.313214][ T1] ? __lock_acquire+0x518/0xc20 [ 4.313219][ T1] console_unlock+0xee/0x1f0 [ 4.313222][ T1] ? console_flush_one_record+0x710/0x710 [ 4.313224][ T1] ? rcu_is_watching+0x16/0xd0 [ 4.313226][ T1] ? lock_acquire+0xa0/0x160 [ 4.313229][ T1] ? __down_trylock_console_sem+0x5e/0xa0 [ 4.313231][ T1] ? vprintk_emit+0x320/0x3e0 [ 4.313234][ T1] vprintk_emit+0x37c/0x3e0 [ 4.313237][ T1] ? wake_up_klogd_work_func+0x90/0x90 [ 4.313240][ T1] ? _copy_from_iter+0x1bb/0x1810 [ 4.313246][ T1] devkmsg_emit.constprop.0+0xbc/0xf1 [ 4.313249][ T1] ? vprintk_emit.cold+0x107/0x107 [ 4.313251][ T1] ? simple_strntoull+0x10f/0x140 [ 4.313255][ T1] ? date_str+0x1e0/0x1e0 [ 4.313258][ T1] ? devkmsg_write+0xd1/0x2c0 [ 4.313262][ T1] devkmsg_write.cold+0x5a/0x8b [ 4.313265][ T1] ? vprintk_default+0x20/0x20 [ 4.313269][ T1] ? vprintk_default+0x20/0x20 [ 4.313272][ T1] new_sync_write+0x33e/0x760 [ 4.313275][ T1] ? lockdep_hardirqs_on+0x91/0x130 [ 4.313278][ T1] ? kasan_quarantine_put+0x13e/0x2b0 [ 4.313282][ T1] ? new_sync_read+0x750/0x750 [ 4.313289][ T1] ? rcu_is_watching+0x16/0xd0 [ 4.313292][ T1] vfs_write+0x6a2/0xbd0 [ 4.313295][ T1] ksys_write+0x116/0x250 [ 4.313298][ T1] ? __ia32_sys_read+0xc0/0xc0 [ 4.313301][ T1] ? rcu_is_watching+0x16/0xd0 [ 4.313304][ T1] do_syscall_64+0xff/0x530 [ 4.313306][ T1] ? exc_page_fault+0xee/0x100 [ 4.313309][ T1] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 4.313311][ T1] RIP: 0033:0x7f4d91ab8bee [ 4.313315][ 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.313317][ T1] RSP: 002b:00007ffc4daf55a0 EFLAGS: 00000202 ORIG_RAX: 0000000000000001 [ 4.313319][ T1] RAX: ffffffffffffffda RBX: 000000000000011c RCX: 00007f4d91ab8bee [ 4.313321][ T1] RDX: 000000000000011c RSI: 0000555558333680 RDI: 0000000000000003 [ 4.313321][ T1] RBP: 00007ffc4daf55b0 R08: 0000000000000000 R09: 0000000000000000 [ 4.313322][ T1] R10: 0000000000000000 R11: 0000000000000202 R12: 00007f4d91b440a0 [ 4.313323][ T1] R13: 00007f4d91a423e0 R14: 0000555558333680 R15: 00007ffc4daf56c8 [ 4.313329][ T1] [ 4.316171][ T113] systemd-tmpfile (113) used greatest stack depth: 24736 bytes left [ 4.317241][ T1] virtme-ng-init: basic initialization done [ 4.374959][ T119] ip (119) used greatest stack depth: 24640 bytes left [ 4.399014][ T114] virtme-ng-init: Starting systemd-udevd version 259.8-1.fc44 [ 4.399500][ T114] virtme-ng-init: triggering udev coldplug [ 7.399197][ T114] virtme-ng-init: waiting for udev to settle [ 7.399212][ T114] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 7.399214][ T114] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 114, name: virtme-ng-init [ 7.399216][ T114] preempt_count: 1, expected: 0 [ 7.399217][ T114] RCU nest depth: 0, expected: 0 [ 7.399218][ T114] locks held by virtme-ng-init/114: 4, last CPU#2: [ 7.399220][ T114] #0: ffffffff9df99dc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 7.399232][ T114] #1: ffffffff9df99e38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 7.399236][ T114] #2: ffffffff9de89760 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 7.399240][ T114] #3: ffffffff9de89660 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x202/0x4f0 [ 7.399244][ T114] irq event stamp: 3244 [ 7.399245][ T114] hardirqs last enabled at (3243): [] __down_trylock_console_sem+0x86/0xa0 [ 7.399248][ T114] hardirqs last disabled at (3244): [] console_emit_next_record+0x3f8/0x4f0 [ 7.399249][ T114] softirqs last enabled at (3200): [] handle_softirqs+0x67c/0x900 [ 7.399252][ T114] softirqs last disabled at (3195): [] __irq_exit_rcu+0x145/0x1c0 [ 7.399254][ T114] Preemption disabled at: [ 7.399255][ T114] [] vprintk_emit+0x31b/0x3e0 [ 7.399261][ T114] CPU: 2 UID: 0 PID: 114 Comm: virtme-ng-init Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 7.399264][ T114] Tainted: [W]=WARN [ 7.399265][ T114] Hardware name: QEMU Standard PC (Q35 + ICH9, 2009), BIOS rel-1.16.3-0-ga6ed6b701f0a-prebuilt.qemu.org 04/01/2014 [ 7.399267][ T114] Call Trace: [ 7.399268][ T114] [ 7.399270][ T114] dump_stack_lvl+0x6f/0xa0 [ 7.399275][ T114] ? vprintk_emit+0x31b/0x3e0 [ 7.399277][ T114] __might_resched.cold+0x1fe/0x2c1 [ 7.399282][ T114] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 7.399286][ T114] ? __kmalloc_noprof+0xdb/0x760 [ 7.399291][ T114] __kmalloc_noprof+0x443/0x760 [ 7.399293][ T114] ? alloc_buf.isra.0+0x4b/0x260 [ 7.399300][ T114] ? do_raw_spin_unlock+0x59/0x250 [ 7.399302][ T114] alloc_buf.isra.0+0x4b/0x260 [ 7.399306][ T114] put_chars+0x1e1/0x2f0 [ 7.399309][ T114] ? __send_to_port+0x420/0x420 [ 7.399311][ T114] ? printk_get_next_message+0x2fe/0x7d0 [ 7.399314][ T114] ? rcu_read_lock_any_held+0x3c/0x90 [ 7.399317][ T114] ? validate_chain+0x38b/0xc20 [ 7.399321][ T114] hvc_console_print+0x292/0x780 [ 7.399325][ T114] ? __lock_acquire+0x518/0xc20 [ 7.399326][ T114] ? __lock_acquire+0x518/0xc20 [ 7.399330][ T114] ? hvc_write+0x3a0/0x3a0 [ 7.399334][ T114] ? rcu_is_watching+0x16/0xd0 [ 7.399337][ T114] ? lock_acquire+0x13c/0x160 [ 7.399341][ T114] console_emit_next_record+0x252/0x4f0 [ 7.399345][ T114] ? devkmsg_read+0x4e0/0x4e0 [ 7.399349][ T114] ? rcu_is_watching+0x16/0xd0 [ 7.399351][ T114] ? lock_acquire+0x13c/0x160 [ 7.399355][ T114] console_flush_one_record+0x46f/0x710 [ 7.399359][ T114] ? console_emit_next_record+0x4f0/0x4f0 [ 7.399361][ T114] ? __lock_acquire+0x518/0xc20 [ 7.399366][ T114] console_unlock+0xee/0x1f0 [ 7.399369][ T114] ? console_flush_one_record+0x710/0x710 [ 7.399371][ T114] ? rcu_is_watching+0x16/0xd0 [ 7.399373][ T114] ? lock_acquire+0xa0/0x160 [ 7.399376][ T114] ? __down_trylock_console_sem+0x5e/0xa0 [ 7.399378][ T114] ? vprintk_emit+0x320/0x3e0 [ 7.399381][ T114] vprintk_emit+0x37c/0x3e0 [ 7.399385][ T114] ? wake_up_klogd_work_func+0x90/0x90 [ 7.399388][ T114] ? _copy_from_iter+0x1bb/0x1810 [ 7.399393][ T114] devkmsg_emit.constprop.0+0xbc/0xf1 [ 7.399397][ T114] ? vprintk_emit.cold+0x107/0x107 [ 7.399399][ T114] ? simple_strntoull+0x10f/0x140 [ 7.399404][ T114] ? date_str+0x1e0/0x1e0 [ 7.399407][ T114] ? devkmsg_write+0xd1/0x2c0 [ 7.399411][ T114] devkmsg_write.cold+0x5a/0x8b [ 7.399414][ T114] ? vprintk_default+0x20/0x20 [ 7.399418][ T114] ? vprintk_default+0x20/0x20 [ 7.399421][ T114] new_sync_write+0x33e/0x760 [ 7.399425][ T114] ? kasan_quarantine_put+0x102/0x2b0 [ 7.399429][ T114] ? new_sync_read+0x750/0x750 [ 7.399433][ T114] ? __lock_release.isra.0+0x69/0x1a0 [ 7.399438][ T114] ? __fget_files+0x1e3/0x460 [ 7.399441][ T114] vfs_write+0x6a2/0xbd0 [ 7.399445][ T114] ksys_write+0x116/0x250 [ 7.399448][ T114] ? __ia32_sys_read+0xc0/0xc0 [ 7.399450][ T114] ? rcu_is_watching+0x16/0xd0 [ 7.399454][ T114] do_syscall_64+0xff/0x530 [ 7.399456][ T114] ? exc_page_fault+0xee/0x100 [ 7.399460][ T114] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 7.399462][ T114] RIP: 0033:0x7f4d91ab9ed2 [ 7.399466][ T114] 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.399468][ T114] RSP: 002b:00007f4d91a00c68 EFLAGS: 00000246 ORIG_RAX: 0000000000000001 [ 7.399470][ T114] RAX: ffffffffffffffda RBX: 000000000000002e RCX: 00007f4d91ab9ed2 [ 7.399472][ T114] RDX: 000000000000002e RSI: 00007f4d8c0012c0 RDI: 0000000000000003 [ 7.399473][ T114] RBP: 00007f4d91a00c90 R08: 0000000000000000 R09: 0000000000000000 [ 7.399473][ T114] R10: 0000000000000000 R11: 0000000000000246 R12: 00007f4d91b440a0 [ 7.399474][ T114] R13: 00007f4d91a423e0 R14: 00007f4d8c0012c0 R15: 00007f4d91a00da8 [ 7.399481][ T114] [ 7.622083][ T130] virtio_net virtio2 enp0s4: renamed from eth0 [ 7.668822][ T122] fbnic 0000:01:00.0 enp1s0: renamed from eth1 [ 8.232248][ T114] virtme-ng-init: udev is done [ 8.234510][ T1] virtme-ng-init: initialization done [ 9.911268][ T187] ip (187) used greatest stack depth: 24352 bytes left [ 9.911284][ T187] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 9.911286][ T187] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 187, name: ip [ 9.911287][ T187] preempt_count: 2, expected: 0 [ 9.911288][ T187] RCU nest depth: 0, expected: 0 [ 9.911289][ T187] locks held by ip/187: 5, last CPU#3: [ 9.911291][ T187] #0: ffffffff9de027b8 (low_water_lock){+.+.}-{3:3}, at: do_exit+0x887/0xdc0 [ 9.911303][ T187] #1: ffffffff9df99dc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 9.911309][ T187] #2: ffffffff9df99e38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 9.911312][ T187] #3: ffffffff9de89760 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 9.911316][ T187] #4: ffffffff9de89660 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x202/0x4f0 [ 9.911320][ T187] irq event stamp: 31820 [ 9.911321][ T187] hardirqs last enabled at (31819): [] __down_trylock_console_sem+0x86/0xa0 [ 9.911323][ T187] hardirqs last disabled at (31820): [] console_emit_next_record+0x3f8/0x4f0 [ 9.911325][ T187] softirqs last enabled at (31722): [] handle_softirqs+0x67c/0x900 [ 9.911327][ T187] softirqs last disabled at (31711): [] __irq_exit_rcu+0x145/0x1c0 [ 9.911329][ T187] Preemption disabled at: [ 9.911329][ T187] [<0000000000000000>] 0x0 [ 9.911336][ T187] CPU: 3 UID: 0 PID: 187 Comm: ip Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 9.911339][ T187] Tainted: [W]=WARN [ 9.911340][ T187] Hardware name: QEMU Standard PC (Q35 + ICH9, 2009), BIOS rel-1.16.3-0-ga6ed6b701f0a-prebuilt.qemu.org 04/01/2014 [ 9.911342][ T187] Call Trace: [ 9.911343][ T187] [ 9.911345][ T187] dump_stack_lvl+0x6f/0xa0 [ 9.911351][ T187] __might_resched.cold+0x1fe/0x2c1 [ 9.911355][ T187] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 9.911359][ T187] ? __kmalloc_noprof+0xdb/0x760 [ 9.911364][ T187] __kmalloc_noprof+0x443/0x760 [ 9.911367][ T187] ? alloc_buf.isra.0+0x4b/0x260 [ 9.911373][ T187] ? do_raw_spin_unlock+0x59/0x250 [ 9.911376][ T187] alloc_buf.isra.0+0x4b/0x260 [ 9.911379][ T187] put_chars+0x1e1/0x2f0 [ 9.911382][ T187] ? __send_to_port+0x420/0x420 [ 9.911384][ T187] ? printk_get_next_message+0x2fe/0x7d0 [ 9.911387][ T187] ? rcu_read_lock_any_held+0x3c/0x90 [ 9.911390][ T187] ? validate_chain+0x38b/0xc20 [ 9.911394][ T187] hvc_console_print+0x292/0x780 [ 9.911398][ T187] ? __lock_acquire+0x518/0xc20 [ 9.911399][ T187] ? __lock_acquire+0x518/0xc20 [ 9.911404][ T187] ? hvc_write+0x3a0/0x3a0 [ 9.911407][ T187] ? rcu_is_watching+0x16/0xd0 [ 9.911410][ T187] ? lock_acquire+0x13c/0x160 [ 9.911414][ T187] console_emit_next_record+0x252/0x4f0 [ 9.911417][ T187] ? devkmsg_read+0x4e0/0x4e0 [ 9.911422][ T187] ? rcu_is_watching+0x16/0xd0 [ 9.911424][ T187] ? lock_acquire+0x13c/0x160 [ 9.911427][ T187] console_flush_one_record+0x46f/0x710 [ 9.911431][ T187] ? console_emit_next_record+0x4f0/0x4f0 [ 9.911433][ T187] ? __lock_acquire+0x518/0xc20 [ 9.911438][ T187] console_unlock+0xee/0x1f0 [ 9.911441][ T187] ? console_flush_one_record+0x710/0x710 [ 9.911443][ T187] ? rcu_is_watching+0x16/0xd0 [ 9.911445][ T187] ? lock_acquire+0xa0/0x160 [ 9.911448][ T187] ? __down_trylock_console_sem+0x5e/0xa0 [ 9.911450][ T187] ? vprintk_emit+0x320/0x3e0 [ 9.911453][ T187] vprintk_emit+0x37c/0x3e0 [ 9.911457][ T187] ? wake_up_klogd_work_func+0x90/0x90 [ 9.911460][ T187] ? __lock_acquire+0x518/0xc20 [ 9.911464][ T187] _printk+0xc7/0x100 [ 9.911467][ T187] ? snapshot_read.cold+0x21/0x21 [ 9.911470][ T187] ? do_raw_spin_lock+0x131/0x280 [ 9.911472][ T187] ? __rwlock_init+0x150/0x150 [ 9.911476][ T187] ? do_raw_spin_lock+0x131/0x280 [ 9.911479][ T187] do_exit.cold+0x82/0x9c [ 9.911482][ T187] ? exit_notify+0x890/0x890 [ 9.911484][ T187] ? __lock_release.isra.0+0x69/0x1a0 [ 9.911487][ T187] ? rcu_is_watching+0x16/0xd0 [ 9.911491][ T187] do_group_exit+0xb8/0x370 [ 9.911494][ T187] __x64_sys_exit_group+0x3c/0x50 [ 9.911496][ T187] x64_sys_call+0x1567/0x1570 [ 9.911499][ T187] do_syscall_64+0xff/0x530 [ 9.911502][ T187] ? exc_page_fault+0xee/0x100 [ 9.911506][ T187] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 9.911508][ T187] RIP: 0033:0x7fe34077f1b8 [ 9.911510][ T187] Code: Unable to access opcode bytes at 0x7fe34077f18e. [ 9.911511][ T187] RSP: 002b:00007ffe42818888 EFLAGS: 00000246 ORIG_RAX: 00000000000000e7 [ 9.911514][ T187] RAX: ffffffffffffffda RBX: 00007fe3408aff88 RCX: 00007fe34077f1b8 [ 9.911515][ T187] RDX: 00007fe3404c8b48 RSI: fffffffffffffeb8 RDI: 0000000000000000 [ 9.911516][ T187] RBP: 00007ffe428188e0 R08: 0000000000000000 R09: 0000000000000050 [ 9.911517][ T187] R10: 00007ffe428186a0 R11: 0000000000000246 R12: 0000000000000001 [ 9.911518][ T187] R13: 0000000000000000 R14: 00007fe3408ae680 R15: 00007fe3408affa0 [ 9.911524][ T187] [ 10.222654][ T188] fbnic 0000:01:00.0 enp1s0: configuring for inband/100gbase-p link mode [ 10.223814][ T87] fbnic 0000:01:00.0 enp1s0: Link is Up - 100Gbps/Full - flow control rx/tx