virtme: waiting for virtiofsd to start qemu-system-x86_64: warning: hub 0 is not connected to host network [ 1.817263][ T1] loop: module loaded [ 1.817286][ T1] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 1.817288][ T1] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 1, name: swapper/0 [ 1.817289][ T1] preempt_count: 1, expected: 0 [ 1.817290][ T1] RCU nest depth: 0, expected: 0 [ 1.817291][ T1] locks held by swapper/0/1: 4, last CPU#0: [ 1.817293][ T1] #0: ffffffffa3b99dc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 1.817305][ T1] #1: ffffffffa3b99e38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 1.817309][ T1] #2: ffffffffa3a89760 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 1.817313][ T1] #3: ffffffffa3a89660 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x202/0x4f0 [ 1.817317][ T1] irq event stamp: 802592 [ 1.817318][ T1] hardirqs last enabled at (802591): [] __down_trylock_console_sem+0x86/0xa0 [ 1.817321][ T1] hardirqs last disabled at (802592): [] console_emit_next_record+0x3f8/0x4f0 [ 1.817323][ T1] softirqs last enabled at (802538): [] bdi_register_va+0x491/0x780 [ 1.817326][ T1] softirqs last disabled at (802536): [] bdi_register_va+0x2ef/0x780 [ 1.817329][ T1] Preemption disabled at: [ 1.817329][ T1] [] vprintk_emit+0x31b/0x3e0 [ 1.817334][ T1] CPU: 0 UID: 0 PID: 1 Comm: swapper/0 Not tainted 7.2.0-virtme #1 PREEMPT(full) [ 1.817337][ T1] Hardware name: QEMU Standard PC (Q35 + ICH9, 2009), BIOS rel-1.16.3-0-ga6ed6b701f0a-prebuilt.qemu.org 04/01/2014 [ 1.817339][ T1] Call Trace: [ 1.817340][ T1] [ 1.817344][ T1] dump_stack_lvl+0x6f/0xa0 [ 1.817349][ T1] ? vprintk_emit+0x31b/0x3e0 [ 1.817351][ T1] __might_resched.cold+0x1fe/0x2c1 [ 1.817355][ T1] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 1.817360][ T1] ? __kmalloc_noprof+0xdb/0x760 [ 1.817364][ T1] __kmalloc_noprof+0x443/0x760 [ 1.817367][ T1] ? alloc_buf.isra.0+0x4b/0x260 [ 1.817373][ T1] ? do_raw_spin_unlock+0x59/0x250 [ 1.817376][ T1] alloc_buf.isra.0+0x4b/0x260 [ 1.817379][ T1] put_chars+0x1e1/0x2f0 [ 1.817381][ T1] ? desc_read+0x271/0x2f0 [ 1.817384][ T1] ? __send_to_port+0x420/0x420 [ 1.817391][ T1] hvc_console_print+0x292/0x780 [ 1.817397][ T1] ? hvc_write+0x3a0/0x3a0 [ 1.817400][ T1] ? lock_acquire+0x13c/0x160 [ 1.817404][ T1] console_emit_next_record+0x252/0x4f0 [ 1.817408][ T1] ? devkmsg_read+0x4e0/0x4e0 [ 1.817412][ T1] ? rcu_is_watching+0x16/0xd0 [ 1.817416][ T1] ? lock_acquire+0x13c/0x160 [ 1.817419][ T1] console_flush_one_record+0x46f/0x710 [ 1.817423][ T1] ? console_emit_next_record+0x4f0/0x4f0 [ 1.817425][ T1] ? __lock_acquire+0x518/0xc20 [ 1.817430][ T1] console_unlock+0xee/0x1f0 [ 1.817433][ T1] ? console_flush_one_record+0x710/0x710 [ 1.817434][ T1] ? rcu_is_watching+0x16/0xd0 [ 1.817436][ T1] ? lock_acquire+0xa0/0x160 [ 1.817440][ T1] ? __down_trylock_console_sem+0x5e/0xa0 [ 1.817441][ T1] ? vprintk_emit+0x320/0x3e0 [ 1.817445][ T1] vprintk_emit+0x37c/0x3e0 [ 1.817448][ T1] ? wake_up_klogd_work_func+0x90/0x90 [ 1.817453][ T1] ? max_loop_setup+0x30/0x30 [ 1.817457][ T1] _printk+0xc7/0x100 [ 1.817460][ T1] ? snapshot_read.cold+0x21/0x21 [ 1.817466][ T1] ? __mutex_unlock_slowpath+0x14d/0x740 [ 1.817470][ T1] loop_init+0x12a/0x130 [ 1.817472][ T1] do_one_initcall+0x124/0x4f0 [ 1.817475][ T1] ? trace_event_raw_event_initcall_level+0x280/0x280 [ 1.817477][ T1] ? parameq+0x110/0x110 [ 1.817482][ T1] ? kernel_init_freeable+0x3f1/0x630 [ 1.817485][ T1] ? rcu_is_watching+0x16/0xd0 [ 1.817489][ T1] kernel_init_freeable+0x596/0x630 [ 1.817492][ T1] ? rest_init+0x280/0x280 [ 1.817494][ T1] kernel_init+0x21/0x150 [ 1.817496][ T1] ? rest_init+0x280/0x280 [ 1.817497][ T1] ret_from_fork+0x474/0x6b0 [ 1.817501][ T1] ? arch_exit_to_user_mode_prepare.isra.0+0x120/0x120 [ 1.817504][ T1] ? __switch_to+0x5a3/0xe00 [ 1.817507][ T1] ? rest_init+0x280/0x280 [ 1.817509][ T1] ret_from_fork_asm+0x11/0x20 [ 1.817517][ T1] [ 1.864983][ T1] ACPI: \_SB_.GSIA: Enabled at IRQ 16 [ 1.869054][ T1] ahci 0000:00:1f.2: AHCI vers 0001.0000, 32 command slots, 1.5 Gbps, SATA mode [ 1.869567][ T1] ahci 0000:00:1f.2: 6/6 ports implemented (port mask 0x3f) [ 1.869894][ T1] ahci 0000:00:1f.2: flags: 64bit ncq only [ 1.885439][ T1] scsi host0: ahci [ 1.888553][ T1] scsi host1: ahci [ 1.891493][ T1] scsi host2: ahci [ 1.894521][ T1] scsi host3: ahci [ 1.897543][ T1] scsi host4: ahci [ 1.900614][ T1] scsi host5: ahci [ 1.902456][ T1] ata1: SATA max UDMA/133 abar m4096@0xfe444000 port 0xfe444100 irq 30 lpm-pol 1 [ 1.902893][ T1] ata2: SATA max UDMA/133 abar m4096@0xfe444000 port 0xfe444180 irq 30 lpm-pol 1 [ 1.903435][ T1] ata3: SATA max UDMA/133 abar m4096@0xfe444000 port 0xfe444200 irq 30 lpm-pol 1 [ 1.903844][ T1] ata4: SATA max UDMA/133 abar m4096@0xfe444000 port 0xfe444280 irq 30 lpm-pol 1 [ 1.904271][ T1] ata5: SATA max UDMA/133 abar m4096@0xfe444000 port 0xfe444300 irq 30 lpm-pol 1 [ 1.904669][ T1] ata6: SATA max UDMA/133 abar m4096@0xfe444000 port 0xfe444380 irq 30 lpm-pol 1 [ 1.918446][ T1] e1000: Intel(R) PRO/1000 Network Driver [ 1.918672][ T1] e1000: Copyright (c) 1999-2006 Intel Corporation. [ 1.919210][ T1] e1000e: Intel(R) PRO/1000 Network Driver [ 1.919469][ T1] e1000e: Copyright(c) 1999 - 2015 Intel Corporation. [ 1.919942][ T1] sky2: driver version 1.30 fbnic-err: bad register access fbnic_mac_read_rxb address 0x80e9 [ 2.028500][ T98] kwatchdog (98) used greatest stack depth: 29688 bytes left [ 2.124639][ T1] Meta(R) Host Network Interface Driver (fbnic) [ 2.125448][ T1] PPP generic driver version 2.4.2 [ 2.126938][ T1] NET: Registered PF_PPPOX protocol family [ 2.127742][ T1] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 2.129955][ T1] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 2.131386][ T1] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 2.135047][ T1] rtc_cmos PNP0B00:00: RTC can wake from S4 [ 2.137000][ T69] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 2.143658][ T69] input: ImExPS/2 Generic Explorer Mouse as /devices/platform/i8042/serio1/input/input3 [ 2.143996][ T1] rtc_cmos PNP0B00:00: registered as rtc0 [ 2.144930][ T1] rtc_cmos PNP0B00:00: setting system clock to 2026-08-28T08:20:37 UTC (1787905237) [ 2.146063][ T1] rtc_cmos PNP0B00:00: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 2.149643][ T1] i6300ESB timer 0000:00:02.0: initialized. heartbeat=30 sec (nowayout=0) [ 2.151235][ T1] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev [ 2.158990][ T1] ipip: IPv4 and MPLS over IPv4 tunneling driver [ 2.162156][ T1] gre: GRE over IPv4 demultiplexer driver [ 2.162385][ T1] ip_gre: GRE over IPv4 tunneling driver [ 2.170448][ T1] Initializing XFRM netlink socket [ 2.171060][ T1] NET: Registered PF_INET6 protocol family [ 2.177866][ T1] Segment Routing with IPv6 [ 2.178479][ T1] In-situ OAM (IOAM) with IPv6 [ 2.178832][ T1] sit: IPv6, IPv4 and MPLS over IPv4 tunneling driver [ 2.185209][ T1] ip6_gre: GRE over IPv6 tunneling driver [ 2.188620][ T1] NET: Registered PF_PACKET protocol family [ 2.188958][ T1] 9pnet: Installing 9P2000 support [ 2.189403][ T1] Key type dns_resolver registered [ 2.190076][ T1] NET: Registered PF_VSOCK protocol family [ 2.196004][ T1] IPI shorthand broadcast: enabled [ 2.209634][ T77] ata1: SATA link down (SStatus 0 SControl 300) [ 2.210378][ T79] ata2: SATA link down (SStatus 0 SControl 300) [ 2.219589][ T81] ata3: SATA link up 1.5 Gbps (SStatus 113 SControl 300) [ 2.220187][ T83] ata4: SATA link down (SStatus 0 SControl 300) [ 2.220832][ T85] ata5: SATA link down (SStatus 0 SControl 300) [ 2.221395][ T87] ata6: SATA link down (SStatus 0 SControl 300) [ 2.221830][ T81] ata3.00: ATAPI: QEMU DVD-ROM, 2.5+, max UDMA/100 [ 2.222133][ T81] ata3.00: applying bridge limits [ 2.222511][ T81] ata3.00: configured for UDMA/100 [ 2.224649][ T60] scsi 2:0:0:0: CD-ROM QEMU QEMU DVD-ROM 2.5+ PQ: 0 ANSI: 5 [ 2.239770][ T60] scsi 2:0:0:0: Attached scsi generic sg0 type 5 [ 2.302451][ T1] sched_clock: Marking stable (2275001640, 27022237)->(2420859662, -118835785) [ 2.305870][ T1] registered taskstats version 1 [ 2.308654][ T1] Loading compiled-in X.509 certificates [ 2.408664][ T1] Demotion targets for Node 0: null [ 2.409084][ T1] kmemleak: Kernel memory leak detector initialized (mem pool available: 11280) [ 2.409387][ T1] page_owner is disabled [ 2.418171][ T1] PM: Magic number: 6:236:322 [ 2.418445][ T1] tty tty47: hash matches [ 2.421265][ T1] ALSA device list: [ 2.422000][ T1] No soundcards found. [ 2.423253][ T1] md: Skipping autodetection of RAID arrays. (raid=autodetect will force) [ 2.425674][ T1] VFS: Mounted root (virtiofs filesystem) readonly on device 0:24. [ 2.426408][ T1] devtmpfs: mounted [ 2.426676][ T1] VFS: Pivoted into new rootfs [ 2.453909][ T1] Freeing unused kernel image (initmem) memory: 2592K [ 2.456632][ T1] Write protecting the kernel read-only data: 65536k [ 2.457689][ T1] Freeing unused kernel image (text/rodata gap) memory: 1152K [ 2.458176][ T1] Freeing unused kernel image (rodata/data gap) memory: 624K [ 2.458450][ T1] Run /usr/lib/python3.14/site-packages/virtme/guest/bin/virtme-ng-init as init process [ 2.458739][ T1] with arguments: [ 2.458865][ T1] /usr/lib/python3.14/site-packages/virtme/guest/bin/virtme-ng-init [ 2.459861][ T1] with environment: [ 2.459989][ T1] HOME=/ [ 2.460159][ T1] TERM=dumb [ 2.460292][ T1] virtme_hostname=vmksft-fbnic-qemu-dbg,debug-threads=on [ 2.460531][ T1] nr_open=2147483584 [ 2.460649][ T1] virtme_link_mods=/srv/vmksft/testing/wt-24/.virtme_mods/lib/modules/0.0.0 [ 2.460933][ T1] virtme_rw_overlay0=/etc [ 2.462033][ T1] virtme_rw_overlay1=/lib [ 2.462214][ T1] virtme_rw_overlay2=/home [ 2.462375][ T1] virtme_rw_overlay3=/opt [ 2.462534][ T1] virtme_rw_overlay4=/srv [ 2.462689][ T1] virtme_rw_overlay5=/usr [ 2.462844][ T1] virtme_rw_overlay6=/var [ 2.463000][ T1] virtme_rw_overlay7=/tmp [ 2.463166][ T1] virtme_console=ttyS0 [ 2.463327][ T1] virtme_chdir=srv/vmksft/testing/wt-24 [ 2.476461][ T1] virtme-ng-init: mount devtmpfs -> /dev: EBUSY: Device or resource busy [ 2.477929][ T1] virtme-ng-init: Setting hostname to vmksft-fbnic-qemu-dbg,debug-threads=on... [ 2.486697][ T1] overlayfs: failed to set xattr on upper [ 2.486996][ T1] overlayfs: ...falling back to redirect_dir=nofollow. [ 2.487286][ T1] overlayfs: ...falling back to uuid=null. [ 2.489634][ T1] overlayfs: failed to set xattr on upper [ 2.489847][ T1] overlayfs: ...falling back to redirect_dir=nofollow. [ 2.490094][ T1] overlayfs: ...falling back to uuid=null. [ 2.491781][ T1] overlayfs: failed to set xattr on upper [ 2.491980][ T1] overlayfs: ...falling back to redirect_dir=nofollow. [ 2.492586][ T1] overlayfs: ...falling back to uuid=null. [ 2.494237][ T1] overlayfs: failed to set xattr on upper [ 2.494442][ T1] overlayfs: ...falling back to redirect_dir=nofollow. [ 2.494687][ T1] overlayfs: ...falling back to uuid=null. [ 2.496342][ T1] overlayfs: failed to set xattr on upper [ 2.496542][ T1] overlayfs: ...falling back to redirect_dir=nofollow. [ 2.496788][ T1] overlayfs: ...falling back to uuid=null. [ 2.498707][ T1] overlayfs: failed to set xattr on upper [ 2.498907][ T1] overlayfs: ...falling back to redirect_dir=nofollow. [ 2.499163][ T1] overlayfs: ...falling back to uuid=null. [ 2.500893][ T1] overlayfs: failed to set xattr on upper [ 2.501337][ T1] overlayfs: ...falling back to redirect_dir=nofollow. [ 2.501593][ T1] overlayfs: ...falling back to uuid=null. [ 2.503324][ T1] overlayfs: failed to set xattr on upper [ 2.503525][ T1] overlayfs: ...falling back to redirect_dir=nofollow. [ 2.503766][ T1] overlayfs: ...falling back to uuid=null. [ 2.513611][ T1] virtme-ng-init: running systemd-tmpfiles [ 4.303387][ T110] systemd-tmpfile (110) used greatest stack depth: 24928 bytes left [ 4.303404][ T110] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 4.303407][ T110] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 110, name: systemd-tmpfile [ 4.303408][ T110] preempt_count: 2, expected: 0 [ 4.303409][ T110] RCU nest depth: 0, expected: 0 [ 4.303410][ T110] locks held by systemd-tmpfile/110: 5, last CPU#1: [ 4.303412][ T110] #0: ffffffffa3a027b8 (low_water_lock){+.+.}-{3:3}, at: do_exit+0x887/0xdc0 [ 4.303424][ T110] #1: ffffffffa3b99dc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 4.303429][ T110] #2: ffffffffa3b99e38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 4.303433][ T110] #3: ffffffffa3a89760 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 4.303436][ T110] #4: ffffffffa3a89660 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x202/0x4f0 [ 4.303440][ T110] irq event stamp: 2193524 [ 4.303441][ T110] hardirqs last enabled at (2193523): [] __down_trylock_console_sem+0x86/0xa0 [ 4.303443][ T110] hardirqs last disabled at (2193524): [] console_emit_next_record+0x3f8/0x4f0 [ 4.303445][ T110] softirqs last enabled at (2193186): [] handle_softirqs+0x67c/0x900 [ 4.303447][ T110] softirqs last disabled at (2193181): [] __irq_exit_rcu+0x145/0x1c0 [ 4.303449][ T110] Preemption disabled at: [ 4.303450][ T110] [<0000000000000000>] 0x0 [ 4.303457][ T110] CPU: 1 UID: 0 PID: 110 Comm: systemd-tmpfile Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 4.303460][ T110] Tainted: [W]=WARN [ 4.303461][ T110] Hardware name: QEMU Standard PC (Q35 + ICH9, 2009), BIOS rel-1.16.3-0-ga6ed6b701f0a-prebuilt.qemu.org 04/01/2014 [ 4.303463][ T110] Call Trace: [ 4.303464][ T110] [ 4.303465][ T110] dump_stack_lvl+0x6f/0xa0 [ 4.303471][ T110] __might_resched.cold+0x1fe/0x2c1 [ 4.303476][ T110] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 4.303480][ T110] ? __kmalloc_noprof+0xdb/0x760 [ 4.303484][ T110] __kmalloc_noprof+0x443/0x760 [ 4.303487][ T110] ? alloc_buf.isra.0+0x4b/0x260 [ 4.303493][ T110] ? do_raw_spin_unlock+0x59/0x250 [ 4.303496][ T110] alloc_buf.isra.0+0x4b/0x260 [ 4.303500][ T110] put_chars+0x1e1/0x2f0 [ 4.303503][ T110] ? __send_to_port+0x420/0x420 [ 4.303504][ T110] ? printk_get_next_message+0x2fe/0x7d0 [ 4.303508][ T110] ? rcu_read_lock_any_held+0x3c/0x90 [ 4.303510][ T110] ? validate_chain+0x38b/0xc20 [ 4.303514][ T110] hvc_console_print+0x292/0x780 [ 4.303517][ T110] ? __lock_acquire+0x518/0xc20 [ 4.303519][ T110] ? __lock_acquire+0x518/0xc20 [ 4.303523][ T110] ? hvc_write+0x3a0/0x3a0 [ 4.303526][ T110] ? rcu_is_watching+0x16/0xd0 [ 4.303529][ T110] ? lock_acquire+0x13c/0x160 [ 4.303533][ T110] console_emit_next_record+0x252/0x4f0 [ 4.303536][ T110] ? devkmsg_read+0x4e0/0x4e0 [ 4.303540][ T110] ? rcu_is_watching+0x16/0xd0 [ 4.303543][ T110] ? lock_acquire+0x13c/0x160 [ 4.303546][ T110] console_flush_one_record+0x46f/0x710 [ 4.303550][ T110] ? console_emit_next_record+0x4f0/0x4f0 [ 4.303552][ T110] ? __lock_acquire+0x518/0xc20 [ 4.303557][ T110] console_unlock+0xee/0x1f0 [ 4.303559][ T110] ? console_flush_one_record+0x710/0x710 [ 4.303561][ T110] ? rcu_is_watching+0x16/0xd0 [ 4.303563][ T110] ? lock_acquire+0xa0/0x160 [ 4.303566][ T110] ? __down_trylock_console_sem+0x5e/0xa0 [ 4.303568][ T110] ? vprintk_emit+0x320/0x3e0 [ 4.303571][ T110] vprintk_emit+0x37c/0x3e0 [ 4.303575][ T110] ? wake_up_klogd_work_func+0x90/0x90 [ 4.303578][ T110] ? __lock_acquire+0x518/0xc20 [ 4.303581][ T110] _printk+0xc7/0x100 [ 4.303585][ T110] ? snapshot_read.cold+0x21/0x21 [ 4.303587][ T110] ? do_raw_spin_lock+0x131/0x280 [ 4.303590][ T110] ? __rwlock_init+0x150/0x150 [ 4.303593][ T110] ? do_raw_spin_lock+0x131/0x280 [ 4.303596][ T1] virtme-ng-init: Failed to read '/usr/lib/tmpfiles.d/audit.conf': Permission denied [ 4.303596][ T1] Failed to create directory or subvolume "/var/spool/at/spool": Permission denied [ 4.303596][ T1] Failed to create file /var/spool/at/.SEQ: Permission denied [ 4.303596][ T1] Failed to opendir() '/proc/self/fd/6': Permission denied [ 4.303596][ T110] do_exit.cold+0x82/0x9c [ 4.303599][ T110] ? exit_notify+0x890/0x890 [ 4.303601][ T110] ? __lock_release.isra.0+0x69/0x1a0 [ 4.303604][ T110] ? rcu_is_watching+0x16/0xd0 [ 4.303607][ T110] do_group_exit+0xb8/0x370 [ 4.303611][ T110] __x64_sys_exit_group+0x3c/0x50 [ 4.303613][ T110] x64_sys_call+0x1567/0x1570 [ 4.303616][ T110] do_syscall_64+0xff/0x530 [ 4.303620][ T110] ? exc_page_fault+0xee/0x100 [ 4.303623][ T110] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 4.303625][ T110] RIP: 0033:0x7f7d68bb61b8 [ 4.303627][ T110] Code: Unable to access opcode bytes at 0x7f7d68bb618e. [ 4.303628][ T110] RSP: 002b:00007ffd1986e6a8 EFLAGS: 00000246 ORIG_RAX: 00000000000000e7 [ 4.303631][ T110] RAX: ffffffffffffffda RBX: 00007f7d68ce6f88 RCX: 00007f7d68bb61b8 [ 4.303632][ T110] RDX: 00007f7d684144c8 RSI: fffffffffffffe90 RDI: 0000000000000049 [ 4.303633][ T110] RBP: 00007ffd1986e700 R08: 0000000000000000 R09: 0000000000001000 [ 4.303634][ T110] R10: 00007ffd1986e4c0 R11: 0000000000000246 R12: 0000000000000001 [ 4.303635][ T110] R13: 0000000000000049 R14: 00007f7d68ce5680 R15: 00007f7d68ce6fa0 [ 4.303641][ T110] [ 4.327621][ T1] virtme-ng-init: basic initialization done [ 4.377067][ T116] ip (116) used greatest stack depth: 24832 bytes left [ 4.388916][ T112] virtme-ng-init: Starting systemd-udevd version 259.8-1.fc44 [ 4.389460][ T112] virtme-ng-init: triggering udev coldplug [ 7.433524][ T112] virtme-ng-init: waiting for udev to settle [ 7.433539][ T112] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 7.433541][ T112] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 112, name: virtme-ng-init [ 7.433543][ T112] preempt_count: 1, expected: 0 [ 7.433544][ T112] RCU nest depth: 0, expected: 0 [ 7.433545][ T112] locks held by virtme-ng-init/112: 4, last CPU#0: [ 7.433547][ T112] #0: ffffffffa3b99dc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 7.433559][ T112] #1: ffffffffa3b99e38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 7.433563][ T112] #2: ffffffffa3a89760 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 7.433567][ T112] #3: ffffffffa3a89660 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x202/0x4f0 [ 7.433571][ T112] irq event stamp: 2932 [ 7.433572][ T112] hardirqs last enabled at (2931): [] __down_trylock_console_sem+0x86/0xa0 [ 7.433574][ T112] hardirqs last disabled at (2932): [] console_emit_next_record+0x3f8/0x4f0 [ 7.433576][ T112] softirqs last enabled at (2658): [] handle_softirqs+0x67c/0x900 [ 7.433579][ T112] softirqs last disabled at (2651): [] __irq_exit_rcu+0x145/0x1c0 [ 7.433582][ T112] Preemption disabled at: [ 7.433582][ T112] [] vprintk_emit+0x31b/0x3e0 [ 7.433588][ T112] CPU: 0 UID: 0 PID: 112 Comm: virtme-ng-init Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 7.433591][ T112] Tainted: [W]=WARN [ 7.433592][ T112] Hardware name: QEMU Standard PC (Q35 + ICH9, 2009), BIOS rel-1.16.3-0-ga6ed6b701f0a-prebuilt.qemu.org 04/01/2014 [ 7.433594][ T112] Call Trace: [ 7.433595][ T112] [ 7.433597][ T112] dump_stack_lvl+0x6f/0xa0 [ 7.433603][ T112] ? vprintk_emit+0x31b/0x3e0 [ 7.433605][ T112] __might_resched.cold+0x1fe/0x2c1 [ 7.433609][ T112] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 7.433613][ T112] ? __kmalloc_noprof+0xdb/0x760 [ 7.433617][ T112] __kmalloc_noprof+0x443/0x760 [ 7.433620][ T112] ? alloc_buf.isra.0+0x4b/0x260 [ 7.433626][ T112] ? do_raw_spin_unlock+0x59/0x250 [ 7.433629][ T112] alloc_buf.isra.0+0x4b/0x260 [ 7.433632][ T112] put_chars+0x1e1/0x2f0 [ 7.433635][ T112] ? __send_to_port+0x420/0x420 [ 7.433637][ T112] ? printk_get_next_message+0x2fe/0x7d0 [ 7.433640][ T112] ? rcu_read_lock_any_held+0x3c/0x90 [ 7.433643][ T112] ? validate_chain+0x38b/0xc20 [ 7.433647][ T112] hvc_console_print+0x292/0x780 [ 7.433650][ T112] ? __lock_acquire+0x518/0xc20 [ 7.433652][ T112] ? __lock_acquire+0x518/0xc20 [ 7.433656][ T112] ? hvc_write+0x3a0/0x3a0 [ 7.433659][ T112] ? rcu_is_watching+0x16/0xd0 [ 7.433662][ T112] ? lock_acquire+0x13c/0x160 [ 7.433665][ T112] console_emit_next_record+0x252/0x4f0 [ 7.433669][ T112] ? devkmsg_read+0x4e0/0x4e0 [ 7.433673][ T112] ? rcu_is_watching+0x16/0xd0 [ 7.433675][ T112] ? lock_acquire+0x13c/0x160 [ 7.433679][ T112] console_flush_one_record+0x46f/0x710 [ 7.433683][ T112] ? console_emit_next_record+0x4f0/0x4f0 [ 7.433685][ T112] ? __lock_acquire+0x518/0xc20 [ 7.433689][ T112] console_unlock+0xee/0x1f0 [ 7.433692][ T112] ? console_flush_one_record+0x710/0x710 [ 7.433694][ T112] ? rcu_is_watching+0x16/0xd0 [ 7.433696][ T112] ? lock_acquire+0xa0/0x160 [ 7.433699][ T112] ? __down_trylock_console_sem+0x5e/0xa0 [ 7.433701][ T112] ? vprintk_emit+0x320/0x3e0 [ 7.433704][ T112] vprintk_emit+0x37c/0x3e0 [ 7.433707][ T112] ? wake_up_klogd_work_func+0x90/0x90 [ 7.433710][ T112] ? _copy_from_iter+0x1bb/0x1810 [ 7.433716][ T112] devkmsg_emit.constprop.0+0xbc/0xf1 [ 7.433719][ T112] ? vprintk_emit.cold+0x107/0x107 [ 7.433721][ T112] ? simple_strntoull+0x10f/0x140 [ 7.433725][ T112] ? date_str+0x1e0/0x1e0 [ 7.433728][ T112] ? devkmsg_write+0xd1/0x2c0 [ 7.433732][ T112] devkmsg_write.cold+0x5a/0x8b [ 7.433735][ T112] ? vprintk_default+0x20/0x20 [ 7.433739][ T112] ? vprintk_default+0x20/0x20 [ 7.433741][ T112] new_sync_write+0x33e/0x760 [ 7.433745][ T112] ? kasan_quarantine_put+0x102/0x2b0 [ 7.433749][ T112] ? new_sync_read+0x750/0x750 [ 7.433753][ T112] ? __lock_release.isra.0+0x69/0x1a0 [ 7.433758][ T112] ? __fget_files+0x1e3/0x460 [ 7.433761][ T112] vfs_write+0x6a2/0xbd0 [ 7.433765][ T112] ksys_write+0x116/0x250 [ 7.433768][ T112] ? __ia32_sys_read+0xc0/0xc0 [ 7.433770][ T112] ? rcu_is_watching+0x16/0xd0 [ 7.433774][ T112] do_syscall_64+0xff/0x530 [ 7.433776][ T112] ? exc_page_fault+0xee/0x100 [ 7.433780][ T112] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 7.433782][ T112] RIP: 0033:0x7fb2747d3ed2 [ 7.433786][ T112] 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.433787][ T112] RSP: 002b:00007fb27471ac68 EFLAGS: 00000246 ORIG_RAX: 0000000000000001 [ 7.433790][ T112] RAX: ffffffffffffffda RBX: 000000000000002e RCX: 00007fb2747d3ed2 [ 7.433792][ T112] RDX: 000000000000002e RSI: 00007fb2700012c0 RDI: 0000000000000003 [ 7.433793][ T112] RBP: 00007fb27471ac90 R08: 0000000000000000 R09: 0000000000000000 [ 7.433794][ T112] R10: 0000000000000000 R11: 0000000000000246 R12: 00007fb27485e0a0 [ 7.433795][ T112] R13: 00007fb27475c3e0 R14: 00007fb2700012c0 R15: 00007fb27471ada8 [ 7.433801][ T112] [ 7.580771][ T125] virtio_net virtio2 enp0s4: renamed from eth0 [ 7.672641][ T124] fbnic 0000:01:00.0 enp1s0: renamed from eth1 [ 8.258525][ T112] virtme-ng-init: udev is done [ 8.260802][ T1] virtme-ng-init: initialization done [ 9.871644][ T184] ip (184) used greatest stack depth: 24808 bytes left [ 9.871658][ T184] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 9.871660][ T184] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 184, name: ip [ 9.871661][ T184] preempt_count: 2, expected: 0 [ 9.871662][ T184] RCU nest depth: 0, expected: 0 [ 9.871663][ T184] locks held by ip/184: 5, last CPU#1: [ 9.871665][ T184] #0: ffffffffa3a027b8 (low_water_lock){+.+.}-{3:3}, at: do_exit+0x887/0xdc0 [ 9.871677][ T184] #1: ffffffffa3b99dc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 9.871682][ T184] #2: ffffffffa3b99e38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 9.871686][ T184] #3: ffffffffa3a89760 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 9.871689][ T184] #4: ffffffffa3a89660 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x202/0x4f0 [ 9.871693][ T184] irq event stamp: 54540 [ 9.871694][ T184] hardirqs last enabled at (54539): [] __down_trylock_console_sem+0x86/0xa0 [ 9.871696][ T184] hardirqs last disabled at (54540): [] console_emit_next_record+0x3f8/0x4f0 [ 9.871698][ T184] softirqs last enabled at (53376): [] handle_softirqs+0x67c/0x900 [ 9.871700][ T184] softirqs last disabled at (53367): [] __irq_exit_rcu+0x145/0x1c0 [ 9.871702][ T184] Preemption disabled at: [ 9.871703][ T184] [<0000000000000000>] 0x0 [ 9.871710][ T184] CPU: 1 UID: 0 PID: 184 Comm: ip Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 9.871713][ T184] Tainted: [W]=WARN [ 9.871714][ T184] Hardware name: QEMU Standard PC (Q35 + ICH9, 2009), BIOS rel-1.16.3-0-ga6ed6b701f0a-prebuilt.qemu.org 04/01/2014 [ 9.871716][ T184] Call Trace: [ 9.871717][ T184] [ 9.871719][ T184] dump_stack_lvl+0x6f/0xa0 [ 9.871725][ T184] __might_resched.cold+0x1fe/0x2c1 [ 9.871729][ T184] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 9.871733][ T184] ? __kmalloc_noprof+0xdb/0x760 [ 9.871738][ T184] __kmalloc_noprof+0x443/0x760 [ 9.871740][ T184] ? alloc_buf.isra.0+0x4b/0x260 [ 9.871747][ T184] ? do_raw_spin_unlock+0x59/0x250 [ 9.871749][ T184] alloc_buf.isra.0+0x4b/0x260 [ 9.871753][ T184] put_chars+0x1e1/0x2f0 [ 9.871756][ T184] ? __send_to_port+0x420/0x420 [ 9.871758][ T184] ? printk_get_next_message+0x2fe/0x7d0 [ 9.871761][ T184] ? rcu_read_lock_any_held+0x3c/0x90 [ 9.871764][ T184] ? validate_chain+0x38b/0xc20 [ 9.871768][ T184] hvc_console_print+0x292/0x780 [ 9.871772][ T184] ? __lock_acquire+0x518/0xc20 [ 9.871773][ T184] ? __lock_acquire+0x518/0xc20 [ 9.871777][ T184] ? hvc_write+0x3a0/0x3a0 [ 9.871781][ T184] ? rcu_is_watching+0x16/0xd0 [ 9.871783][ T184] ? lock_acquire+0x13c/0x160 [ 9.871787][ T184] console_emit_next_record+0x252/0x4f0 [ 9.871791][ T184] ? devkmsg_read+0x4e0/0x4e0 [ 9.871795][ T184] ? rcu_is_watching+0x16/0xd0 [ 9.871797][ T184] ? lock_acquire+0x13c/0x160 [ 9.871800][ T184] console_flush_one_record+0x46f/0x710 [ 9.871804][ T184] ? console_emit_next_record+0x4f0/0x4f0 [ 9.871806][ T184] ? __lock_acquire+0x518/0xc20 [ 9.871811][ T184] console_unlock+0xee/0x1f0 [ 9.871814][ T184] ? console_flush_one_record+0x710/0x710 [ 9.871815][ T184] ? rcu_is_watching+0x16/0xd0 [ 9.871817][ T184] ? lock_acquire+0xa0/0x160 [ 9.871821][ T184] ? __down_trylock_console_sem+0x5e/0xa0 [ 9.871822][ T184] ? vprintk_emit+0x320/0x3e0 [ 9.871825][ T184] vprintk_emit+0x37c/0x3e0 [ 9.871829][ T184] ? wake_up_klogd_work_func+0x90/0x90 [ 9.871832][ T184] ? __lock_acquire+0x518/0xc20 [ 9.871836][ T184] _printk+0xc7/0x100 [ 9.871839][ T184] ? snapshot_read.cold+0x21/0x21 [ 9.871842][ T184] ? do_raw_spin_lock+0x131/0x280 [ 9.871844][ T184] ? __rwlock_init+0x150/0x150 [ 9.871848][ T184] ? do_raw_spin_lock+0x131/0x280 [ 9.871850][ T184] do_exit.cold+0x82/0x9c [ 9.871854][ T184] ? exit_notify+0x890/0x890 [ 9.871856][ T184] ? __lock_release.isra.0+0x69/0x1a0 [ 9.871858][ T184] ? rcu_is_watching+0x16/0xd0 [ 9.871862][ T184] do_group_exit+0xb8/0x370 [ 9.871866][ T184] __x64_sys_exit_group+0x3c/0x50 [ 9.871868][ T184] x64_sys_call+0x1567/0x1570 [ 9.871871][ T184] do_syscall_64+0xff/0x530 [ 9.871874][ T184] ? exc_page_fault+0xee/0x100 [ 9.871878][ T184] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 9.871880][ T184] RIP: 0033:0x7f3979bb31b8 [ 9.871882][ T184] Code: Unable to access opcode bytes at 0x7f3979bb318e. [ 9.871883][ T184] RSP: 002b:00007fffbd090518 EFLAGS: 00000246 ORIG_RAX: 00000000000000e7 [ 9.871885][ T184] RAX: ffffffffffffffda RBX: 00007f3979ce3f88 RCX: 00007f3979bb31b8 [ 9.871887][ T184] RDX: 00007f39798fcb48 RSI: fffffffffffffeb8 RDI: 0000000000000000 [ 9.871888][ T184] RBP: 00007fffbd090570 R08: 0000000000000000 R09: 0000000000000000 [ 9.871888][ T184] R10: 00007fffbd090330 R11: 0000000000000246 R12: 0000000000000001 [ 9.871889][ T184] R13: 0000000000000000 R14: 00007f3979ce2680 R15: 00007f3979ce3fa0 [ 9.871896][ T184] [ 9.926052][ T185] ip (185) used greatest stack depth: 24520 bytes left [ 10.246410][ T186] fbnic 0000:01:00.0 enp1s0: configuring for inband/100gbase-p link mode [ 10.247605][ T107] fbnic 0000:01:00.0 enp1s0: Link is Up - 100Gbps/Full - flow control rx/tx