virtme: waiting for virtiofsd to start qemu-system-x86_64: warning: hub 0 is not connected to host network [ 1.966238][ T1] loop: module loaded [ 1.966273][ T1] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 1.966276][ T1] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 1, name: swapper/0 [ 1.966277][ T1] preempt_count: 1, expected: 0 [ 1.966278][ T1] RCU nest depth: 0, expected: 0 [ 1.966279][ T1] locks held by swapper/0/1: 4, last CPU#2: [ 1.966281][ T1] #0: ffffffff9c399dc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 1.966294][ T1] #1: ffffffff9c399e38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 1.966299][ T1] #2: ffffffff9c289760 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 1.966303][ T1] #3: ffffffff9c289660 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x202/0x4f0 [ 1.966307][ T1] irq event stamp: 805526 [ 1.966308][ T1] hardirqs last enabled at (805525): [] __down_trylock_console_sem+0x86/0xa0 [ 1.966311][ T1] hardirqs last disabled at (805526): [] console_emit_next_record+0x3f8/0x4f0 [ 1.966313][ T1] softirqs last enabled at (805472): [] bdi_register_va+0x491/0x780 [ 1.966317][ T1] softirqs last disabled at (805470): [] bdi_register_va+0x2ef/0x780 [ 1.966320][ T1] Preemption disabled at: [ 1.966320][ T1] [] vprintk_emit+0x31b/0x3e0 [ 1.966326][ T1] CPU: 2 UID: 0 PID: 1 Comm: swapper/0 Not tainted 7.2.0-virtme #1 PREEMPT(full) [ 1.966329][ T1] Hardware name: QEMU Standard PC (Q35 + ICH9, 2009), BIOS rel-1.16.3-0-ga6ed6b701f0a-prebuilt.qemu.org 04/01/2014 [ 1.966331][ T1] Call Trace: [ 1.966333][ T1] [ 1.966337][ T1] dump_stack_lvl+0x6f/0xa0 [ 1.966343][ T1] ? vprintk_emit+0x31b/0x3e0 [ 1.966345][ T1] __might_resched.cold+0x1fe/0x2c1 [ 1.966350][ T1] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 1.966354][ T1] ? __kmalloc_noprof+0xdb/0x760 [ 1.966358][ T1] __kmalloc_noprof+0x443/0x760 [ 1.966361][ T1] ? alloc_buf.isra.0+0x4b/0x260 [ 1.966368][ T1] ? do_raw_spin_unlock+0x59/0x250 [ 1.966371][ T1] alloc_buf.isra.0+0x4b/0x260 [ 1.966376][ T1] put_chars+0x1e1/0x2f0 [ 1.966376][ T1] ? desc_read+0x271/0x2f0 [ 1.966376][ T1] ? __send_to_port+0x420/0x420 [ 1.966376][ T1] hvc_console_print+0x292/0x780 [ 1.966376][ T1] ? hvc_write+0x3a0/0x3a0 [ 1.966376][ T1] ? lock_acquire+0x13c/0x160 [ 1.966376][ T1] console_emit_next_record+0x252/0x4f0 [ 1.966376][ T1] ? devkmsg_read+0x4e0/0x4e0 [ 1.966376][ T1] ? rcu_is_watching+0x16/0xd0 [ 1.966376][ T1] ? lock_acquire+0x13c/0x160 [ 1.966376][ T1] console_flush_one_record+0x46f/0x710 [ 1.966376][ T1] ? console_emit_next_record+0x4f0/0x4f0 [ 1.966376][ T1] ? __lock_acquire+0x518/0xc20 [ 1.966376][ T1] console_unlock+0xee/0x1f0 [ 1.966376][ T1] ? console_flush_one_record+0x710/0x710 [ 1.966376][ T1] ? rcu_is_watching+0x16/0xd0 [ 1.966376][ T1] ? lock_acquire+0xa0/0x160 [ 1.966376][ T1] ? __down_trylock_console_sem+0x5e/0xa0 [ 1.966376][ T1] ? vprintk_emit+0x320/0x3e0 [ 1.966376][ T1] vprintk_emit+0x37c/0x3e0 [ 1.966376][ T1] ? wake_up_klogd_work_func+0x90/0x90 [ 1.966376][ T1] ? max_loop_setup+0x30/0x30 [ 1.966376][ T1] _printk+0xc7/0x100 [ 1.966376][ T1] ? snapshot_read.cold+0x21/0x21 [ 1.966376][ T1] ? __mutex_unlock_slowpath+0x14d/0x740 [ 1.966376][ T1] loop_init+0x12a/0x130 [ 1.966376][ T1] do_one_initcall+0x124/0x4f0 [ 1.966376][ T1] ? trace_event_raw_event_initcall_level+0x280/0x280 [ 1.966376][ T1] ? parameq+0x110/0x110 [ 1.966376][ T1] ? kernel_init_freeable+0x3f1/0x630 [ 1.966376][ T1] ? rcu_is_watching+0x16/0xd0 [ 1.966376][ T1] kernel_init_freeable+0x596/0x630 [ 1.966376][ T1] ? rest_init+0x280/0x280 [ 1.966376][ T1] kernel_init+0x21/0x150 [ 1.966376][ T1] ? rest_init+0x280/0x280 [ 1.966376][ T1] ret_from_fork+0x474/0x6b0 [ 1.966376][ T1] ? arch_exit_to_user_mode_prepare.isra.0+0x120/0x120 [ 1.966376][ T1] ? __switch_to+0x5a3/0xe00 [ 1.966376][ T1] ? rest_init+0x280/0x280 [ 1.966376][ T1] ret_from_fork_asm+0x11/0x20 [ 1.966376][ T1] [ 2.018278][ T1] ACPI: \_SB_.GSIA: Enabled at IRQ 16 [ 2.022209][ T1] ahci 0000:00:1f.2: AHCI vers 0001.0000, 32 command slots, 1.5 Gbps, SATA mode [ 2.022697][ T1] ahci 0000:00:1f.2: 6/6 ports implemented (port mask 0x3f) [ 2.023044][ T1] ahci 0000:00:1f.2: flags: 64bit ncq only [ 2.040058][ T1] scsi host0: ahci [ 2.043355][ T1] scsi host1: ahci [ 2.046534][ T1] scsi host2: ahci [ 2.049650][ T1] scsi host3: ahci [ 2.052903][ T1] scsi host4: ahci [ 2.056082][ T1] scsi host5: ahci [ 2.058009][ T1] ata1: SATA max UDMA/133 abar m4096@0xfe444000 port 0xfe444100 irq 30 lpm-pol 1 [ 2.058560][ T1] ata2: SATA max UDMA/133 abar m4096@0xfe444000 port 0xfe444180 irq 30 lpm-pol 1 [ 2.058969][ T1] ata3: SATA max UDMA/133 abar m4096@0xfe444000 port 0xfe444200 irq 30 lpm-pol 1 [ 2.059410][ T1] ata4: SATA max UDMA/133 abar m4096@0xfe444000 port 0xfe444280 irq 30 lpm-pol 1 [ 2.059823][ T1] ata5: SATA max UDMA/133 abar m4096@0xfe444000 port 0xfe444300 irq 30 lpm-pol 1 [ 2.060243][ T1] ata6: SATA max UDMA/133 abar m4096@0xfe444000 port 0xfe444380 irq 30 lpm-pol 1 [ 2.075789][ T1] e1000: Intel(R) PRO/1000 Network Driver [ 2.076001][ T1] e1000: Copyright (c) 1999-2006 Intel Corporation. [ 2.076631][ T1] e1000e: Intel(R) PRO/1000 Network Driver [ 2.076853][ T1] e1000e: Copyright(c) 1999 - 2015 Intel Corporation. [ 2.077424][ T1] sky2: driver version 1.30 fbnic-err: bad register access fbnic_mac_read_rxb address 0x80e9 [ 2.298566][ T1] Meta(R) Host Network Interface Driver (fbnic) [ 2.299392][ T1] PPP generic driver version 2.4.2 [ 2.300719][ T1] NET: Registered PF_PPPOX protocol family [ 2.301532][ T1] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 2.303960][ T1] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 2.305025][ T1] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 2.313365][ T66] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 2.315182][ T1] rtc_cmos PNP0B00:00: RTC can wake from S4 [ 2.324346][ T1] rtc_cmos PNP0B00:00: registered as rtc0 [ 2.324733][ T66] input: ImExPS/2 Generic Explorer Mouse as /devices/platform/i8042/serio1/input/input3 [ 2.325045][ T1] rtc_cmos PNP0B00:00: setting system clock to 2026-08-28T01:27:28 UTC (1787880448) [ 2.327445][ T1] rtc_cmos PNP0B00:00: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 2.333584][ T1] i6300ESB timer 0000:00:02.0: initialized. heartbeat=30 sec (nowayout=0) [ 2.336712][ T1] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev [ 2.350452][ T1] ipip: IPv4 and MPLS over IPv4 tunneling driver [ 2.356244][ T1] gre: GRE over IPv4 demultiplexer driver [ 2.356561][ T1] ip_gre: GRE over IPv4 tunneling driver [ 2.371206][ T89] ata4: SATA link down (SStatus 0 SControl 300) [ 2.371351][ T1] Initializing XFRM netlink socket [ 2.372127][ T85] ata2: SATA link down (SStatus 0 SControl 300) [ 2.372807][ T1] NET: Registered PF_INET6 protocol family [ 2.372843][ T87] ata3: SATA link up 1.5 Gbps (SStatus 113 SControl 300) [ 2.373806][ T91] ata5: SATA link down (SStatus 0 SControl 300) [ 2.374499][ T93] ata6: SATA link down (SStatus 0 SControl 300) [ 2.375072][ T83] ata1: SATA link down (SStatus 0 SControl 300) [ 2.375506][ T87] ata3.00: ATAPI: QEMU DVD-ROM, 2.5+, max UDMA/100 [ 2.375809][ T87] ata3.00: applying bridge limits [ 2.376232][ T87] ata3.00: configured for UDMA/100 [ 2.378571][ T96] scsi 2:0:0:0: CD-ROM QEMU QEMU DVD-ROM 2.5+ PQ: 0 ANSI: 5 [ 2.386663][ T1] Segment Routing with IPv6 [ 2.387510][ T1] In-situ OAM (IOAM) with IPv6 [ 2.388159][ T1] sit: IPv6, IPv4 and MPLS over IPv4 tunneling driver [ 2.399013][ T96] scsi 2:0:0:0: Attached scsi generic sg0 type 5 [ 2.401164][ T1] ip6_gre: GRE over IPv6 tunneling driver [ 2.407642][ T1] NET: Registered PF_PACKET protocol family [ 2.408194][ T1] 9pnet: Installing 9P2000 support [ 2.409485][ T1] Key type dns_resolver registered [ 2.410666][ T1] NET: Registered PF_VSOCK protocol family [ 2.419126][ T1] IPI shorthand broadcast: enabled [ 2.565519][ C0] clocksource: Watchdog remote CPU 2 read timed out [ 2.565562][ C0] [ 2.565564][ C0] ======================================================== [ 2.565565][ C0] WARNING: possible irq lock inversion dependency detected [ 2.565567][ C0] 7.2.0-virtme #1 Tainted: G W [ 2.565569][ C0] -------------------------------------------------------- [ 2.565569][ C0] swapper/0/0 just changed the state of lock: [ 2.565570][ C0] ffffffff9c289760 (console_owner){..-.}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 2.565584][ C0] but this lock took another, SOFTIRQ-unsafe lock in the past: [ 2.565585][ C0] (fs_reclaim){+.+.}-{0:0} [ 2.565587][ C0] [ 2.565587][ C0] [ 2.565587][ C0] and interrupts could create inverse lock ordering between them. [ 2.565587][ C0] [ 2.565588][ C0] [ 2.565588][ C0] other info that might help us debug this: [ 2.565588][ C0] Possible interrupt unsafe locking scenario: [ 2.565588][ C0] [ 2.565589][ C0] CPU0 CPU1 [ 2.565589][ C0] ---- ---- [ 2.565590][ C0] lock(fs_reclaim); [ 2.565591][ C0] local_irq_disable(); [ 2.565591][ C0] lock(console_owner); [ 2.565592][ C0] lock(fs_reclaim); [ 2.565593][ C0] [ 2.565593][ C0] lock(console_owner); [ 2.565594][ C0] [ 2.565594][ C0] *** DEADLOCK *** [ 2.565594][ C0] [ 2.565595][ C0] locks held by swapper/0/0: 4, last CPU#0: [ 2.565596][ C0] #0: ffa0000000007c90 ((&watchdog_timer)){+.-.}-{0:0}, at: call_timer_fn+0x110/0x4d0 [ 2.565602][ C0] #1: ffffffff9c3fe9f8 (watchdog_lock){+.-.}-{3:3}, at: clocksource_watchdog+0x15/0x30 [ 2.565606][ C0] #2: ffffffff9c399dc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 2.565610][ C0] #3: ffffffff9c399e38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 2.565613][ C0] [ 2.565613][ C0] the shortest dependencies between 2nd lock and 1st lock: [ 2.565617][ C0] -> (fs_reclaim){+.+.}-{0:0} { [ 2.565619][ C0] HARDIRQ-ON-W at: [ 2.565621][ C0] __lock_acquire+0x388/0xc20 [ 2.565624][ C0] lock_acquire.part.0+0xd4/0x280 [ 2.565626][ C0] fs_reclaim_acquire+0xd5/0x120 [ 2.565629][ C0] __kmalloc_cache_noprof+0x6e/0x620 [ 2.565632][ C0] kthread_create_worker_on_node+0xea/0x210 [ 2.565635][ C0] workqueue_init+0x2a/0x680 [ 2.565638][ C0] kernel_init_freeable+0x2fe/0x630 [ 2.565641][ C0] kernel_init+0x21/0x150 [ 2.565644][ C0] ret_from_fork+0x474/0x6b0 [ 2.565648][ C0] ret_from_fork_asm+0x11/0x20 [ 2.565650][ C0] SOFTIRQ-ON-W at: [ 2.565651][ C0] __lock_acquire+0x388/0xc20 [ 2.565653][ C0] lock_acquire.part.0+0xd4/0x280 [ 2.565654][ C0] fs_reclaim_acquire+0xd5/0x120 [ 2.565655][ C0] __kmalloc_cache_noprof+0x6e/0x620 [ 2.565657][ C0] kthread_create_worker_on_node+0xea/0x210 [ 2.565658][ C0] workqueue_init+0x2a/0x680 [ 2.565659][ C0] kernel_init_freeable+0x2fe/0x630 [ 2.565661][ C0] kernel_init+0x21/0x150 [ 2.565662][ C0] ret_from_fork+0x474/0x6b0 [ 2.565663][ C0] ret_from_fork_asm+0x11/0x20 [ 2.565664][ C0] INITIAL USE at: [ 2.565665][ C0] __lock_acquire+0x388/0xc20 [ 2.565666][ C0] lock_acquire.part.0+0xd4/0x280 [ 2.565667][ C0] fs_reclaim_acquire+0xd5/0x120 [ 2.565669][ C0] __kmalloc_cache_noprof+0x6e/0x620 [ 2.565670][ C0] kthread_create_worker_on_node+0xea/0x210 [ 2.565672][ C0] workqueue_init+0x2a/0x680 [ 2.565673][ C0] kernel_init_freeable+0x2fe/0x630 [ 2.565675][ C0] kernel_init+0x21/0x150 [ 2.565676][ C0] ret_from_fork+0x474/0x6b0 [ 2.565677][ C0] ret_from_fork_asm+0x11/0x20 [ 2.565678][ C0] } [ 2.565678][ C0] ... key at: [] __fs_reclaim_map+0x0/0xbe0 [ 2.565683][ C0] ... acquired at: [ 2.565684][ C0] __lock_acquire+0x518/0xc20 [ 2.565685][ C0] lock_acquire.part.0+0xd4/0x280 [ 2.565686][ C0] fs_reclaim_acquire+0xd5/0x120 [ 2.565687][ C0] __kmalloc_noprof+0xd3/0x760 [ 2.565689][ C0] alloc_buf.isra.0+0x4b/0x260 [ 2.565692][ C0] put_chars+0x1e1/0x2f0 [ 2.565694][ C0] hvc_console_print+0x292/0x780 [ 2.565697][ C0] console_emit_next_record+0x252/0x4f0 [ 2.565699][ C0] console_flush_one_record+0x46f/0x710 [ 2.565700][ C0] console_unlock+0xee/0x1f0 [ 2.565702][ C0] vprintk_emit+0x37c/0x3e0 [ 2.565703][ C0] _printk+0xc7/0x100 [ 2.565706][ C0] loop_init+0x12a/0x130 [ 2.565709][ C0] do_one_initcall+0x124/0x4f0 [ 2.565710][ C0] kernel_init_freeable+0x596/0x630 [ 2.565711][ C0] kernel_init+0x21/0x150 [ 2.565712][ C0] ret_from_fork+0x474/0x6b0 [ 2.565713][ C0] ret_from_fork_asm+0x11/0x20 [ 2.565715][ C0] [ 2.565715][ C0] -> (console_owner){..-.}-{0:0} { [ 2.565717][ C0] IN-SOFTIRQ-W at: [ 2.565718][ C0] __lock_acquire+0x388/0xc20 [ 2.565719][ C0] lock_acquire.part.0+0xd4/0x280 [ 2.565720][ C0] console_lock_spinning_enable+0x5c/0x60 [ 2.565722][ C0] console_emit_next_record+0x1f4/0x4f0 [ 2.565723][ C0] console_flush_one_record+0x46f/0x710 [ 2.565725][ C0] console_unlock+0xee/0x1f0 [ 2.565726][ C0] vprintk_emit+0x37c/0x3e0 [ 2.565728][ C0] _printk+0xc7/0x100 [ 2.565729][ C0] clocksource_watchdog.part.0.cold+0x105/0x1dd [ 2.565732][ C0] call_timer_fn+0x160/0x4d0 [ 2.565733][ C0] __run_timers+0x68f/0xaa0 [ 2.565735][ C0] run_timer_softirq+0xf0/0x160 [ 2.565737][ C0] handle_softirqs+0x1d3/0x900 [ 2.565739][ C0] __irq_exit_rcu+0x145/0x1c0 [ 2.565741][ C0] irq_exit_rcu+0xe/0x30 [ 2.565742][ C0] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 2.565745][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 2.565747][ C0] pv_native_safe_halt+0xf/0x10 [ 2.565749][ C0] default_idle+0x9/0x10 [ 2.565750][ C0] default_idle_call+0x6e/0xb0 [ 2.565751][ C0] cpuidle_idle_call.constprop.0+0x237/0x410 [ 2.565754][ C0] do_idle+0xd8/0x190 [ 2.565755][ C0] cpu_startup_entry+0x53/0x70 [ 2.565756][ C0] rest_init+0x279/0x280 [ 2.565757][ C0] start_kernel+0x3af/0x3b0 [ 2.565759][ C0] x86_64_start_reservations+0x24/0x30 [ 2.565761][ C0] x86_64_start_kernel+0x12b/0x130 [ 2.565763][ C0] common_startup_64+0x13e/0x148 [ 2.565765][ C0] INITIAL USE at: [ 2.565766][ C0] } [ 2.565766][ C0] ... key at: [] console_owner_dep_map+0x0/0x60 [ 2.565769][ C0] ... acquired at: [ 2.565770][ C0] mark_lock+0x1d7/0xa00 [ 2.565771][ C0] mark_usage+0x42/0x170 [ 2.565773][ C0] __lock_acquire+0x388/0xc20 [ 2.565774][ C0] lock_acquire.part.0+0xd4/0x280 [ 2.565775][ C0] console_lock_spinning_enable+0x5c/0x60 [ 2.565777][ C0] console_emit_next_record+0x1f4/0x4f0 [ 2.565778][ C0] console_flush_one_record+0x46f/0x710 [ 2.565780][ C0] console_unlock+0xee/0x1f0 [ 2.565781][ C0] vprintk_emit+0x37c/0x3e0 [ 2.565783][ C0] _printk+0xc7/0x100 [ 2.565784][ C0] clocksource_watchdog.part.0.cold+0x105/0x1dd [ 2.565785][ C0] call_timer_fn+0x160/0x4d0 [ 2.565787][ C0] __run_timers+0x68f/0xaa0 [ 2.565788][ C0] run_timer_softirq+0xf0/0x160 [ 2.565790][ C0] handle_softirqs+0x1d3/0x900 [ 2.565791][ C0] __irq_exit_rcu+0x145/0x1c0 [ 2.565792][ C0] irq_exit_rcu+0xe/0x30 [ 2.565793][ C0] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 2.565795][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 2.565796][ C0] pv_native_safe_halt+0xf/0x10 [ 2.565798][ C0] default_idle+0x9/0x10 [ 2.565799][ C0] default_idle_call+0x6e/0xb0 [ 2.565800][ C0] cpuidle_idle_call.constprop.0+0x237/0x410 [ 2.565802][ C0] do_idle+0xd8/0x190 [ 2.565803][ C0] cpu_startup_entry+0x53/0x70 [ 2.565804][ C0] rest_init+0x279/0x280 [ 2.565805][ C0] start_kernel+0x3af/0x3b0 [ 2.565806][ C0] x86_64_start_reservations+0x24/0x30 [ 2.565808][ C0] x86_64_start_kernel+0x12b/0x130 [ 2.565810][ C0] common_startup_64+0x13e/0x148 [ 2.565811][ C0] [ 2.565811][ C0] [ 2.565811][ C0] stack backtrace: [ 2.565814][ C0] CPU: 0 UID: 0 PID: 0 Comm: swapper/0 Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 2.565818][ C0] Tainted: [W]=WARN [ 2.565818][ C0] Hardware name: QEMU Standard PC (Q35 + ICH9, 2009), BIOS rel-1.16.3-0-ga6ed6b701f0a-prebuilt.qemu.org 04/01/2014 [ 2.565821][ C0] Call Trace: [ 2.565822][ C0] [ 2.565825][ C0] dump_stack_lvl+0x6f/0xa0 [ 2.565829][ C0] print_irq_inversion_bug.part.0.cold+0xe6/0x143 [ 2.565832][ C0] mark_lock_irq+0x989/0x9c0 [ 2.565833][ C0] ? add_lock_to_list+0x2c/0x1a0 [ 2.565836][ C0] mark_lock+0x1d7/0xa00 [ 2.565838][ C0] mark_usage+0x42/0x170 [ 2.565839][ C0] __lock_acquire+0x388/0xc20 [ 2.565841][ C0] lock_acquire.part.0+0xd4/0x280 [ 2.565843][ C0] ? console_lock_spinning_enable+0x40/0x60 [ 2.565845][ C0] ? rcu_is_watching+0x16/0xd0 [ 2.565848][ C0] ? lock_acquire+0x13c/0x160 [ 2.565850][ C0] console_lock_spinning_enable+0x5c/0x60 [ 2.565852][ C0] ? console_lock_spinning_enable+0x40/0x60 [ 2.565853][ C0] console_emit_next_record+0x1f4/0x4f0 [ 2.565855][ C0] ? devkmsg_read+0x4e0/0x4e0 [ 2.565858][ C0] ? rcu_is_watching+0x16/0xd0 [ 2.565859][ C0] ? lock_acquire+0x13c/0x160 [ 2.565861][ C0] console_flush_one_record+0x46f/0x710 [ 2.565863][ C0] ? console_emit_next_record+0x4f0/0x4f0 [ 2.565865][ C0] ? __lock_acquire+0x518/0xc20 [ 2.565867][ C0] console_unlock+0xee/0x1f0 [ 2.565869][ C0] ? console_flush_one_record+0x710/0x710 [ 2.565871][ C0] ? rcu_is_watching+0x16/0xd0 [ 2.565872][ C0] ? lock_acquire+0xa0/0x160 [ 2.565874][ C0] ? __down_trylock_console_sem+0x5e/0xa0 [ 2.565876][ C0] ? vprintk_emit+0x320/0x3e0 [ 2.565878][ C0] vprintk_emit+0x37c/0x3e0 [ 2.565880][ C0] ? wake_up_klogd_work_func+0x90/0x90 [ 2.565883][ C0] ? clocksource_watchdog.part.0+0x4d0/0x540 [ 2.565884][ C0] _printk+0xc7/0x100 [ 2.565886][ C0] ? snapshot_read.cold+0x21/0x21 [ 2.565888][ C0] ? clocksource_watchdog.part.0+0x4d0/0x540 [ 2.565890][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 2.565892][ C0] clocksource_watchdog.part.0.cold+0x105/0x1dd [ 2.565893][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 2.565895][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 2.565897][ C0] call_timer_fn+0x160/0x4d0 [ 2.565898][ C0] ? detach_if_pending+0x1d0/0x1d0 [ 2.565900][ C0] ? debug_object_active_state+0x430/0x430 [ 2.565903][ C0] ? find_held_lock+0x2b/0x80 [ 2.565905][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 2.565907][ C0] ? rcu_is_watching+0x16/0xd0 [ 2.565909][ C0] __run_timers+0x68f/0xaa0 [ 2.565910][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 2.565912][ C0] ? __bpf_trace_itimer_expire+0x10/0x10 [ 2.565914][ C0] ? __lock_acquire+0x518/0xc20 [ 2.565917][ C0] ? __rwlock_init+0x150/0x150 [ 2.565919][ C0] run_timer_softirq+0xf0/0x160 [ 2.565921][ C0] ? __run_timers+0xaa0/0xaa0 [ 2.565923][ C0] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 2.565925][ C0] ? rcu_is_watching+0x16/0xd0 [ 2.565927][ C0] handle_softirqs+0x1d3/0x900 [ 2.565929][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 2.565931][ C0] ? _local_bh_enable+0xc0/0xc0 [ 2.565933][ C0] __irq_exit_rcu+0x145/0x1c0 [ 2.565934][ C0] irq_exit_rcu+0xe/0x30 [ 2.565935][ C0] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 2.565937][ C0] [ 2.565938][ C0] [ 2.565939][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 2.565941][ C0] RIP: 0010:pv_native_safe_halt+0xf/0x10 [ 2.565944][ C0] Code: 48 8b 3d 94 52 73 02 e8 1f 00 00 00 48 2b 05 58 e3 ae 00 c3 0f 1f 80 00 00 00 00 f3 0f 1e fa eb 07 0f 00 2d 13 76 1e 00 fb f4 0f 1f 40 d6 48 83 ec 20 8b 17 49 89 f8 83 e2 fe 41 89 d2 0f 01 [ 2.565945][ C0] RSP: 0018:ffffffff9c007cf8 EFLAGS: 00000296 [ 2.565948][ C0] RAX: 000000000000cd6d RBX: ffffffff9c01c600 RCX: ffffffff987103a7 [ 2.565949][ C0] RDX: ffffffff9c01c600 RSI: ffffffff9b8f7c2a RDI: ffffffff9b2913e0 [ 2.565950][ C0] RBP: 0000000000000000 R08: 0000000000000000 R09: 0000000000000000 [ 2.565951][ C0] R10: 0000000000000000 R11: 0000000000000001 R12: 1ffffffff3800fa2 [ 2.565952][ C0] R13: 0000000000000000 R14: dffffc0000000000 R15: 0000000000014770 [ 2.565953][ C0] ? cpuidle_idle_call.constprop.0+0x237/0x410 [ 2.565956][ C0] ? lockdep_hardirqs_on+0x91/0x130 [ 2.565958][ C0] default_idle+0x9/0x10 [ 2.565959][ C0] default_idle_call+0x6e/0xb0 [ 2.565961][ C0] cpuidle_idle_call.constprop.0+0x237/0x410 [ 2.565962][ C0] ? arch_cpu_idle_exit+0x40/0x40 [ 2.565964][ C0] ? mark_tsc_async_resets+0x30/0x30 [ 2.565966][ C0] ? rcu_is_watching+0x16/0xd0 [ 2.565968][ C0] do_idle+0xd8/0x190 [ 2.565970][ C0] cpu_startup_entry+0x53/0x70 [ 2.565971][ C0] rest_init+0x279/0x280 [ 2.565973][ C0] ? __cpuidle_text_end+0x3b/0x3b [ 2.565975][ C0] ? rest_init+0x280/0x280 [ 2.565976][ C0] ? acpi_hw_write_port+0x5c/0xe0 [ 2.565980][ C0] ? acpi_enable+0x112/0x330 [ 2.565981][ C0] start_kernel+0x3af/0x3b0 [ 2.565983][ C0] x86_64_start_reservations+0x24/0x30 [ 2.565985][ C0] x86_64_start_kernel+0x12b/0x130 [ 2.565987][ C0] common_startup_64+0x13e/0x148 [ 2.565990][ C0] [ 2.572726][ T1] sched_clock: Marking stable (2545001462, 27374647)->(2618996555, -46620446) [ 2.606556][ T1] registered taskstats version 1 [ 2.608566][ T1] Loading compiled-in X.509 certificates [ 2.666719][ T1] Demotion targets for Node 0: null [ 2.666901][ T1] kmemleak: Kernel memory leak detector initialized (mem pool available: 11789) [ 2.667107][ T1] page_owner is disabled [ 2.672196][ T1] PM: Magic number: 6:147:459 [ 2.674008][ T1] ALSA device list: [ 2.674110][ T1] No soundcards found. [ 2.674434][ T1] md: Skipping autodetection of RAID arrays. (raid=autodetect will force) [ 2.675730][ T1] VFS: Mounted root (virtiofs filesystem) readonly on device 0:24. [ 2.676118][ T1] devtmpfs: mounted [ 2.676291][ T1] VFS: Pivoted into new rootfs [ 2.690358][ T1] Freeing unused kernel image (initmem) memory: 2592K [ 2.690696][ T1] Write protecting the kernel read-only data: 65536k [ 2.691220][ T1] Freeing unused kernel image (text/rodata gap) memory: 1152K [ 2.691684][ T1] Freeing unused kernel image (rodata/data gap) memory: 624K [ 2.692105][ T1] Run /usr/lib/python3.14/site-packages/virtme/guest/bin/virtme-ng-init as init process [ 2.692303][ T1] with arguments: [ 2.692395][ T1] /usr/lib/python3.14/site-packages/virtme/guest/bin/virtme-ng-init [ 2.692555][ T1] with environment: [ 2.692644][ T1] HOME=/ [ 2.692724][ T1] TERM=dumb [ 2.692803][ T1] virtme_hostname=vmksft-fbnic-qemu-dbg,debug-threads=on [ 2.692966][ T1] nr_open=2147483584 [ 2.693047][ T1] virtme_link_mods=/srv/vmksft/testing/wt-24/.virtme_mods/lib/modules/0.0.0 [ 2.693230][ T1] virtme_rw_overlay0=/etc [ 2.693340][ T1] virtme_rw_overlay1=/lib [ 2.693460][ T1] virtme_rw_overlay2=/home [ 2.693561][ T1] virtme_rw_overlay3=/opt [ 2.693670][ T1] virtme_rw_overlay4=/srv [ 2.693773][ T1] virtme_rw_overlay5=/usr [ 2.693879][ T1] virtme_rw_overlay6=/var [ 2.693995][ T1] virtme_rw_overlay7=/tmp [ 2.694098][ T1] virtme_console=ttyS0 [ 2.694206][ T1] virtme_chdir=srv/vmksft/testing/wt-24 [ 2.703585][ T1] virtme-ng-init: mount devtmpfs -> /dev: EBUSY: Device or resource busy [ 2.704424][ T1] virtme-ng-init: Setting hostname to vmksft-fbnic-qemu-dbg,debug-threads=on... [ 2.710558][ T1] overlayfs: failed to set xattr on upper [ 2.710699][ T1] overlayfs: ...falling back to redirect_dir=nofollow. [ 2.710852][ T1] overlayfs: ...falling back to uuid=null. [ 2.712016][ T1] overlayfs: failed to set xattr on upper [ 2.712139][ T1] overlayfs: ...falling back to redirect_dir=nofollow. [ 2.712292][ T1] overlayfs: ...falling back to uuid=null. [ 2.713301][ T1] overlayfs: failed to set xattr on upper [ 2.713440][ T1] overlayfs: ...falling back to redirect_dir=nofollow. [ 2.713591][ T1] overlayfs: ...falling back to uuid=null. [ 2.714560][ T1] overlayfs: failed to set xattr on upper [ 2.714690][ T1] overlayfs: ...falling back to redirect_dir=nofollow. [ 2.714835][ T1] overlayfs: ...falling back to uuid=null. [ 2.716047][ T1] overlayfs: failed to set xattr on upper [ 2.716181][ T1] overlayfs: ...falling back to redirect_dir=nofollow. [ 2.716335][ T1] overlayfs: ...falling back to uuid=null. [ 2.717293][ T1] overlayfs: failed to set xattr on upper [ 2.717433][ T1] overlayfs: ...falling back to redirect_dir=nofollow. [ 2.717585][ T1] overlayfs: ...falling back to uuid=null. [ 2.718561][ T1] overlayfs: failed to set xattr on upper [ 2.718690][ T1] overlayfs: ...falling back to redirect_dir=nofollow. [ 2.718837][ T1] overlayfs: ...falling back to uuid=null. [ 2.720031][ T1] overlayfs: failed to set xattr on upper [ 2.720172][ T1] overlayfs: ...falling back to redirect_dir=nofollow. [ 2.720332][ T1] overlayfs: ...falling back to uuid=null. [ 2.725684][ T1] virtme-ng-init: running systemd-tmpfiles [ 3.940832][ T113] systemd-tmpfile (113) used greatest stack depth: 24496 bytes left [ 3.940854][ T113] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 3.940857][ T113] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 113, name: systemd-tmpfile [ 3.940859][ T113] preempt_count: 2, expected: 0 [ 3.940861][ T113] RCU nest depth: 0, expected: 0 [ 3.940862][ T113] INFO: lockdep is turned off. [ 3.940863][ T113] irq event stamp: 0 [ 3.940865][ T113] hardirqs last enabled at (0): [<0000000000000000>] 0x0 [ 3.940871][ T113] hardirqs last disabled at (0): [] copy_process+0x1d3b/0x62d0 [ 3.940878][ T113] softirqs last enabled at (0): [] copy_process+0x1d8f/0x62d0 [ 3.940880][ T113] softirqs last disabled at (0): [<0000000000000000>] 0x0 [ 3.940883][ T113] Preemption disabled at: [ 3.940883][ T113] [<0000000000000000>] 0x0 [ 3.940890][ T113] CPU: 1 UID: 0 PID: 113 Comm: systemd-tmpfile Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 3.940895][ T113] Tainted: [W]=WARN [ 3.940896][ T113] Hardware name: QEMU Standard PC (Q35 + ICH9, 2009), BIOS rel-1.16.3-0-ga6ed6b701f0a-prebuilt.qemu.org 04/01/2014 [ 3.940899][ T113] Call Trace: [ 3.940901][ T113] [ 3.940902][ T113] dump_stack_lvl+0x6f/0xa0 [ 3.940909][ T113] __might_resched.cold+0x1fe/0x2c1 [ 3.940913][ T113] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 3.940917][ T113] ? rcu_is_watching+0x16/0xd0 [ 3.940922][ T113] __kmalloc_noprof+0x443/0x760 [ 3.940926][ T113] ? __rwlock_init+0x150/0x150 [ 3.940929][ T113] ? alloc_buf.isra.0+0x4b/0x260 [ 3.940935][ T113] ? do_raw_spin_unlock+0x59/0x250 [ 3.940937][ T113] alloc_buf.isra.0+0x4b/0x260 [ 3.940941][ T113] put_chars+0x1e1/0x2f0 [ 3.940944][ T113] ? __send_to_port+0x420/0x420 [ 3.940946][ T113] ? printk_get_next_message+0x2fe/0x7d0 [ 3.940950][ T113] ? console_prepend_replay+0x20/0x20 [ 3.940952][ T113] ? desc_read+0x219/0x2f0 [ 3.940956][ T113] hvc_console_print+0x292/0x780 [ 3.940962][ T113] ? hvc_write+0x3a0/0x3a0 [ 3.940964][ T113] ? __rwlock_init+0x150/0x150 [ 3.940966][ T113] ? rcu_is_watching+0x16/0xd0 [ 3.940969][ T113] ? rcu_is_watching+0x16/0xd0 [ 3.940971][ T113] ? lock_acquire+0x13c/0x160 [ 3.940974][ T113] console_emit_next_record+0x252/0x4f0 [ 3.940976][ T1] virtme-ng-init: Failed to read '/usr/lib/tmpfiles.d/audit.conf': Permission denied [ 3.940976][ T1] Failed to create directory or subvolume "/var/spool/at/spool": Permission denied [ 3.940976][ T1] Failed to create file /var/spool/at/.SEQ: Permission denied [ 3.940976][ T1] Failed to opendir() '/proc/self/fd/6': Permission denied [ 3.940977][ T113] ? devkmsg_read+0x4e0/0x4e0 [ 3.940979][ T113] ? _prb_read_valid+0x6e0/0x6e0 [ 3.940982][ T113] ? __asan_memmove+0x3c/0x60 [ 3.940986][ T113] ? rcu_is_watching+0x16/0xd0 [ 3.940988][ T113] ? rcu_is_watching+0x16/0xd0 [ 3.940990][ T113] ? rcu_is_watching+0x16/0xd0 [ 3.940992][ T113] ? lock_acquire+0x13c/0x160 [ 3.940995][ T113] ? console_flush_one_record+0x111/0x710 [ 3.940997][ T113] console_flush_one_record+0x46f/0x710 [ 3.941000][ T113] ? console_emit_next_record+0x4f0/0x4f0 [ 3.941004][ T113] console_unlock+0xee/0x1f0 [ 3.941006][ T113] ? lock_acquire+0x13c/0x160 [ 3.941008][ T113] ? console_flush_one_record+0x710/0x710 [ 3.941011][ T113] ? rcu_is_watching+0x16/0xd0 [ 3.941013][ T113] ? lock_release+0x184/0x1f0 [ 3.941015][ T113] ? lock_acquire+0xa0/0x160 [ 3.941017][ T113] ? sync_exp_reset_tree_hotplug+0x3e0/0x3f0 [ 3.941020][ T113] ? __down_trylock_console_sem+0x5e/0xa0 [ 3.941022][ T113] ? vprintk_emit+0x320/0x3e0 [ 3.941025][ T113] vprintk_emit+0x37c/0x3e0 [ 3.941028][ T113] ? wake_up_klogd_work_func+0x90/0x90 [ 3.941032][ T113] _printk+0xc7/0x100 [ 3.941036][ T113] ? snapshot_read.cold+0x21/0x21 [ 3.941038][ T113] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 3.941042][ T113] ? do_raw_spin_lock+0x131/0x280 [ 3.941044][ T113] ? __rwlock_init+0x150/0x150 [ 3.941047][ T113] ? do_raw_spin_lock+0x131/0x280 [ 3.941050][ T113] do_exit.cold+0x82/0x9c [ 3.941053][ T113] ? exit_notify+0x890/0x890 [ 3.941057][ T113] ? posixtimer_sig_unignore.isra.0+0x5f0/0x5f0 [ 3.941060][ T113] ? rcu_is_watching+0x16/0xd0 [ 3.941063][ T113] do_group_exit+0xb8/0x370 [ 3.941066][ T113] __x64_sys_exit_group+0x3c/0x50 [ 3.941069][ T113] x64_sys_call+0x1567/0x1570 [ 3.941072][ T113] do_syscall_64+0xff/0x530 [ 3.941076][ T113] ? exc_page_fault+0xee/0x100 [ 3.941080][ T113] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 3.941083][ T113] RIP: 0033:0x7f7c6c2de1b8 [ 3.941085][ T113] Code: Unable to access opcode bytes at 0x7f7c6c2de18e. [ 3.941087][ T113] RSP: 002b:00007ffe473adfd8 EFLAGS: 00000246 ORIG_RAX: 00000000000000e7 [ 3.941091][ T113] RAX: ffffffffffffffda RBX: 00007f7c6c40ef88 RCX: 00007f7c6c2de1b8 [ 3.941093][ T113] RDX: 00007f7c6bb3c4c8 RSI: fffffffffffffe90 RDI: 0000000000000049 [ 3.941095][ T113] RBP: 00007ffe473ae030 R08: 0000000000000000 R09: 0000000000001000 [ 3.941096][ T113] R10: 00007ffe473addf0 R11: 0000000000000246 R12: 0000000000000001 [ 3.941097][ T113] R13: 0000000000000049 R14: 00007f7c6c40d680 R15: 00007f7c6c40efa0 [ 3.941101][ T113] [ 3.957461][ T1] virtme-ng-init: basic initialization done [ 3.998688][ T115] virtme-ng-init: Starting systemd-udevd version 259.8-1.fc44 [ 3.999083][ T115] virtme-ng-init: triggering udev coldplug [ 6.036416][ T115] virtme-ng-init: waiting for udev to settle [ 6.036431][ T115] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 6.036435][ T115] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 115, name: virtme-ng-init [ 6.036437][ T115] preempt_count: 1, expected: 0 [ 6.036439][ T115] RCU nest depth: 0, expected: 0 [ 6.036440][ T115] INFO: lockdep is turned off. [ 6.036442][ T115] irq event stamp: 0 [ 6.036443][ T115] hardirqs last enabled at (0): [<0000000000000000>] 0x0 [ 6.036449][ T115] hardirqs last disabled at (0): [] copy_process+0x1d3b/0x62d0 [ 6.036456][ T115] softirqs last enabled at (0): [] copy_process+0x1d8f/0x62d0 [ 6.036459][ T115] softirqs last disabled at (0): [<0000000000000000>] 0x0 [ 6.036461][ T115] Preemption disabled at: [ 6.036462][ T115] [] vprintk_emit+0x31b/0x3e0 [ 6.036472][ T115] CPU: 0 UID: 0 PID: 115 Comm: virtme-ng-init Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 6.036477][ T115] Tainted: [W]=WARN [ 6.036478][ T115] Hardware name: QEMU Standard PC (Q35 + ICH9, 2009), BIOS rel-1.16.3-0-ga6ed6b701f0a-prebuilt.qemu.org 04/01/2014 [ 6.036480][ T115] Call Trace: [ 6.036482][ T115] [ 6.036484][ T115] dump_stack_lvl+0x6f/0xa0 [ 6.036491][ T115] ? vprintk_emit+0x31b/0x3e0 [ 6.036493][ T115] __might_resched.cold+0x1fe/0x2c1 [ 6.036498][ T115] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 6.036502][ T115] ? rcu_is_watching+0x16/0xd0 [ 6.036507][ T115] __kmalloc_noprof+0x443/0x760 [ 6.036511][ T115] ? __rwlock_init+0x150/0x150 [ 6.036515][ T115] ? alloc_buf.isra.0+0x4b/0x260 [ 6.036520][ T115] ? do_raw_spin_unlock+0x59/0x250 [ 6.036523][ T115] alloc_buf.isra.0+0x4b/0x260 [ 6.036526][ T115] put_chars+0x1e1/0x2f0 [ 6.036530][ T115] ? __send_to_port+0x420/0x420 [ 6.036533][ T115] ? printk_get_next_message+0x2fe/0x7d0 [ 6.036536][ T115] ? console_prepend_replay+0x20/0x20 [ 6.036538][ T115] ? desc_read+0x219/0x2f0 [ 6.036542][ T115] hvc_console_print+0x292/0x780 [ 6.036548][ T115] ? hvc_write+0x3a0/0x3a0 [ 6.036551][ T115] ? __rwlock_init+0x150/0x150 [ 6.036553][ T115] ? rcu_is_watching+0x16/0xd0 [ 6.036555][ T115] ? rcu_is_watching+0x16/0xd0 [ 6.036558][ T115] ? lock_acquire+0x13c/0x160 [ 6.036561][ T115] console_emit_next_record+0x252/0x4f0 [ 6.036565][ T115] ? devkmsg_read+0x4e0/0x4e0 [ 6.036567][ T115] ? _prb_read_valid+0x6e0/0x6e0 [ 6.036570][ T115] ? rcu_is_watching+0x16/0xd0 [ 6.036572][ T115] ? rcu_is_watching+0x16/0xd0 [ 6.036575][ T115] ? rcu_is_watching+0x16/0xd0 [ 6.036577][ T115] ? lock_acquire+0x13c/0x160 [ 6.036580][ T115] ? console_flush_one_record+0x111/0x710 [ 6.036582][ T115] console_flush_one_record+0x46f/0x710 [ 6.036586][ T115] ? console_emit_next_record+0x4f0/0x4f0 [ 6.036590][ T115] console_unlock+0xee/0x1f0 [ 6.036592][ T115] ? lock_acquire+0x13c/0x160 [ 6.036595][ T115] ? console_flush_one_record+0x710/0x710 [ 6.036597][ T115] ? rcu_is_watching+0x16/0xd0 [ 6.036599][ T115] ? lock_release+0x184/0x1f0 [ 6.036601][ T115] ? lock_acquire+0xa0/0x160 [ 6.036604][ T115] ? sync_exp_reset_tree_hotplug+0x3e0/0x3f0 [ 6.036607][ T115] ? __down_trylock_console_sem+0x5e/0xa0 [ 6.036609][ T115] ? vprintk_emit+0x320/0x3e0 [ 6.036612][ T115] vprintk_emit+0x37c/0x3e0 [ 6.036616][ T115] ? wake_up_klogd_work_func+0x90/0x90 [ 6.036618][ T115] ? lock_release+0x184/0x1f0 [ 6.036620][ T115] ? _copy_from_iter+0x1bb/0x1810 [ 6.036625][ T115] devkmsg_emit.constprop.0+0xbc/0xf1 [ 6.036629][ T115] ? vprintk_emit.cold+0x107/0x107 [ 6.036632][ T115] ? simple_strntoull+0x10f/0x140 [ 6.036636][ T115] ? date_str+0x1e0/0x1e0 [ 6.036639][ T115] ? devkmsg_write+0xd1/0x2c0 [ 6.036643][ T115] devkmsg_write.cold+0x5a/0x8b [ 6.036646][ T115] ? vprintk_default+0x20/0x20 [ 6.036649][ T115] ? vprintk_default+0x20/0x20 [ 6.036652][ T115] new_sync_write+0x33e/0x760 [ 6.036656][ T115] ? trace_hardirqs_on+0x36/0x40 [ 6.036660][ T115] ? new_sync_read+0x750/0x750 [ 6.036664][ T115] ? rcu_is_watching+0x16/0xd0 [ 6.036667][ T115] ? lock_release+0x184/0x1f0 [ 6.036669][ T115] ? __fget_files+0x54/0x460 [ 6.036673][ T115] ? __fget_files+0x1e3/0x460 [ 6.036675][ T115] vfs_write+0x6a2/0xbd0 [ 6.036678][ T115] ksys_write+0x116/0x250 [ 6.036681][ T115] ? __ia32_sys_read+0xc0/0xc0 [ 6.036683][ T115] ? rcu_is_watching+0x16/0xd0 [ 6.036686][ T115] do_syscall_64+0xff/0x530 [ 6.036690][ T115] ? exc_page_fault+0xee/0x100 [ 6.036693][ T115] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 6.036696][ T115] RIP: 0033:0x7fda072d8ed2 [ 6.036699][ T115] Code: 08 0f 85 51 ec ff ff 49 89 fb 48 89 f0 48 89 d7 48 89 ce 4c 89 c2 4d 89 ca 4c 8b 44 24 08 4c 8b 4c 24 10 4c 89 5c 24 08 0f 05 66 2e 0f 1f 84 00 00 00 00 00 0f 1f 00 f3 0f 1e fa 55 48 89 e5 [ 6.036702][ T115] RSP: 002b:00007fda0721fc68 EFLAGS: 00000246 ORIG_RAX: 0000000000000001 [ 6.036706][ T115] RAX: ffffffffffffffda RBX: 000000000000002e RCX: 00007fda072d8ed2 [ 6.036708][ T115] RDX: 000000000000002e RSI: 00007fda00001090 RDI: 0000000000000003 [ 6.036710][ T115] RBP: 00007fda0721fc90 R08: 0000000000000000 R09: 0000000000000000 [ 6.036711][ T115] R10: 0000000000000000 R11: 0000000000000246 R12: 00007fda073630a0 [ 6.036713][ T115] R13: 00007fda072613e0 R14: 00007fda00001090 R15: 00007fda0721fda8 [ 6.036716][ T115] [ 6.143746][ T126] virtio_net virtio2 enp0s4: renamed from eth0 [ 6.248331][ T122] fbnic 0000:01:00.0 enp1s0: renamed from eth1 [ 6.709605][ T115] virtme-ng-init: udev is done [ 6.720008][ T1] virtme-ng-init: initialization done [ 8.500054][ T189] fbnic 0000:01:00.0 enp1s0: configuring for inband/100gbase-p link mode [ 8.500072][ T189] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 8.500075][ T189] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 189, name: ip [ 8.500076][ T189] preempt_count: 1, expected: 0 [ 8.500077][ T189] RCU nest depth: 0, expected: 0 [ 8.500079][ T189] INFO: lockdep is turned off. [ 8.500080][ T189] irq event stamp: 0 [ 8.500080][ T189] hardirqs last enabled at (0): [<0000000000000000>] 0x0 [ 8.500085][ T189] hardirqs last disabled at (0): [] copy_process+0x1d3b/0x62d0 [ 8.500091][ T189] softirqs last enabled at (0): [] copy_process+0x1d8f/0x62d0 [ 8.500093][ T189] softirqs last disabled at (0): [<0000000000000000>] 0x0 [ 8.500095][ T189] Preemption disabled at: [ 8.500096][ T189] [] vprintk_emit+0x31b/0x3e0 [ 8.500104][ T189] CPU: 5 UID: 0 PID: 189 Comm: ip Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 8.500107][ T189] Tainted: [W]=WARN [ 8.500108][ T189] Hardware name: QEMU Standard PC (Q35 + ICH9, 2009), BIOS rel-1.16.3-0-ga6ed6b701f0a-prebuilt.qemu.org 04/01/2014 [ 8.500110][ T189] Call Trace: [ 8.500112][ T189] [ 8.500114][ T189] dump_stack_lvl+0x6f/0xa0 [ 8.500119][ T189] ? vprintk_emit+0x31b/0x3e0 [ 8.500121][ T189] __might_resched.cold+0x1fe/0x2c1 [ 8.500125][ T189] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 8.500129][ T189] ? rcu_is_watching+0x16/0xd0 [ 8.500133][ T189] __kmalloc_noprof+0x443/0x760 [ 8.500136][ T189] ? __rwlock_init+0x150/0x150 [ 8.500139][ T189] ? alloc_buf.isra.0+0x4b/0x260 [ 8.500144][ T189] ? do_raw_spin_unlock+0x59/0x250 [ 8.500146][ T189] alloc_buf.isra.0+0x4b/0x260 [ 8.500148][ T189] put_chars+0x1e1/0x2f0 [ 8.500151][ T189] ? __send_to_port+0x420/0x420 [ 8.500153][ T189] ? console_prepend_replay+0x20/0x20 [ 8.500155][ T189] ? desc_read+0x219/0x2f0 [ 8.500158][ T189] hvc_console_print+0x292/0x780 [ 8.500162][ T189] ? hvc_write+0x3a0/0x3a0 [ 8.500173][ T189] ? __rwlock_init+0x150/0x150 [ 8.500174][ T189] ? rcu_is_watching+0x16/0xd0 [ 8.500176][ T189] ? rcu_is_watching+0x16/0xd0 [ 8.500178][ T189] console_emit_next_record+0x252/0x4f0 [ 8.500181][ T189] ? devkmsg_read+0x4e0/0x4e0 [ 8.500183][ T189] ? _prb_read_valid+0x6e0/0x6e0 [ 8.500184][ T189] ? rcu_is_watching+0x16/0xd0 [ 8.500186][ T189] ? rcu_is_watching+0x16/0xd0 [ 8.500188][ T189] ? rcu_is_watching+0x16/0xd0 [ 8.500189][ T189] ? lock_acquire+0x13c/0x160 [ 8.500191][ T189] ? console_flush_one_record+0x111/0x710 [ 8.500193][ T189] console_flush_one_record+0x46f/0x710 [ 8.500195][ T189] ? console_emit_next_record+0x4f0/0x4f0 [ 8.500198][ T189] console_unlock+0xee/0x1f0 [ 8.500200][ T189] ? lock_acquire+0x13c/0x160 [ 8.500201][ T189] ? console_flush_one_record+0x710/0x710 [ 8.500203][ T189] ? rcu_is_watching+0x16/0xd0 [ 8.500204][ T189] ? lock_release+0x184/0x1f0 [ 8.500205][ T189] ? lock_acquire+0xa0/0x160 [ 8.500207][ T189] ? sync_exp_reset_tree_hotplug+0x3e0/0x3f0 [ 8.500209][ T189] ? __down_trylock_console_sem+0x5e/0xa0 [ 8.500211][ T189] ? vprintk_emit+0x320/0x3e0 [ 8.500213][ T189] vprintk_emit+0x37c/0x3e0 [ 8.500215][ T189] ? wake_up_klogd_work_func+0x90/0x90 [ 8.500217][ T189] ? do_raw_read_unlock+0x70/0x70 [ 8.500219][ T189] ? rcu_is_watching+0x16/0xd0 [ 8.500221][ T189] dev_vprintk_emit+0x27f/0x2c0 [ 8.500225][ T189] ? device_rename.cold+0xa/0xa [ 8.500227][ T189] ? proc_mkdir+0xf6/0x160 [ 8.500231][ T189] ? proc_create_mount_point+0x160/0x160 [ 8.500233][ T189] ? _raw_write_unlock+0x2d/0x50 [ 8.500237][ T189] dev_printk_emit+0xb9/0xee [ 8.500239][ T189] ? dev_vprintk_emit+0x2c0/0x2c0 [ 8.500241][ T189] ? irq_seq_stop+0x120/0x120 [ 8.500243][ T189] ? proc_create_mount_point+0x160/0x160 [ 8.500245][ T189] __netdev_printk+0x160/0x1d0 [ 8.500249][ T189] netdev_printk+0xdb/0x120 [ 8.500251][ T189] ? __netdev_printk+0x1d0/0x1d0 [ 8.500254][ T189] ? fbnic_irq_test+0x1c0/0x1c0 [ 8.500258][ T189] phylink_start.cold+0x74/0x92 [ 8.500261][ T189] ? rtnl_is_locked+0x15/0x20 [ 8.500265][ T189] __fbnic_open+0x153/0x190 [ 8.500267][ T189] fbnic_open+0x47/0x70 [ 8.500269][ T189] __dev_open+0x322/0x7a0 [ 8.500271][ T189] ? netif_state_change+0x3d0/0x3d0 [ 8.500273][ T189] ? do_setlink.isra.0+0x27a/0x2a60 [ 8.500275][ T189] ? rcu_is_watching+0x16/0xd0 [ 8.500276][ T189] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 8.500280][ T189] __dev_change_flags+0x4a4/0x6d0 [ 8.500282][ T189] ? netif_set_allmulti+0x2c0/0x2c0 [ 8.500284][ T189] ? __kernel_text_address+0x12/0x30 [ 8.500287][ T189] ? write_profile+0xf0/0xf0 [ 8.500290][ T189] netif_change_flags+0x87/0x190 [ 8.500292][ T189] ? do_setlink.isra.0+0x27a/0x2a60 [ 8.500293][ T189] do_setlink.isra.0+0x1d25/0x2a60 [ 8.500295][ T189] ? stack_trace_save+0x93/0xc0 [ 8.500297][ T189] ? rtnl_link_get_size+0x350/0x350 [ 8.500298][ T189] ? rcu_is_watching+0x16/0xd0 [ 8.500300][ T189] ? stack_depot_save_flags+0x1bc/0x790 [ 8.500305][ T189] ? kasan_save_stack+0x3d/0x50 [ 8.500308][ T189] ? kasan_save_stack+0x2f/0x50 [ 8.500310][ T189] ? kasan_save_track+0x14/0x30 [ 8.500311][ T189] ? __kasan_kmalloc+0x7b/0x90 [ 8.500313][ T189] ? __kmalloc_cache_noprof+0x29a/0x620 [ 8.500315][ T189] ? rtnl_newlink+0x124/0x11b0 [ 8.500317][ T189] ? rtnetlink_rcv_msg+0x6fd/0xbd0 [ 8.500318][ T189] ? netlink_rcv_skb+0x14e/0x3a0 [ 8.500322][ T189] ? netlink_unicast+0x486/0x750 [ 8.500324][ T189] ? netlink_sendmsg+0x735/0xc60 [ 8.500325][ T189] ? ____sys_sendmsg+0x415/0x880 [ 8.500329][ T189] ? ___sys_sendmsg+0x14e/0x1d0 [ 8.500330][ T189] ? __sys_sendmsg+0x12c/0x1d0 [ 8.500331][ T189] ? do_syscall_64+0xff/0x530 [ 8.500333][ T189] ? entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 8.500336][ T189] ? rcu_is_watching+0x16/0xd0 [ 8.500338][ T189] ? lock_acquire+0x13c/0x160 [ 8.500339][ T189] ? rcu_is_watching+0x16/0xd0 [ 8.500341][ T189] ? rcu_is_watching+0x16/0xd0 [ 8.500343][ T189] ? trace_contention_end+0xb3/0x180 [ 8.500345][ T189] ? __mutex_lock+0x1db/0x1ea0 [ 8.500347][ T189] ? __mutex_lock+0x9a3/0x1ea0 [ 8.500349][ T189] ? rtnl_newlink+0x9a8/0x11b0 [ 8.500351][ T189] ? ww_mutex_lock+0x160/0x160 [ 8.500353][ T189] ? __rtnl_newlink+0x3fa/0xa50 [ 8.500356][ T189] rtnl_newlink+0x9f1/0x11b0 [ 8.500358][ T189] ? rcu_is_watching+0x16/0xd0 [ 8.500360][ T189] ? rtnl_bridge_getlink+0x850/0x850 [ 8.500361][ T189] ? lock_acquire+0x13c/0x160 [ 8.500363][ T189] ? rcu_is_watching+0x16/0xd0 [ 8.500365][ T189] ? is_bpf_text_address+0x3e/0x110 [ 8.500367][ T189] ? is_bpf_text_address+0x72/0x110 [ 8.500368][ T189] ? kernel_text_address+0x149/0x170 [ 8.500370][ T189] ? __kernel_text_address+0x12/0x30 [ 8.500371][ T189] ? write_profile+0xf0/0xf0 [ 8.500375][ T189] ? unwind_get_return_address+0x67/0xd0 [ 8.500378][ T189] ? rcu_is_watching+0x16/0xd0 [ 8.500379][ T189] ? rcu_is_watching+0x16/0xd0 [ 8.500381][ T189] ? cap_capable+0x1d7/0x3d0 [ 8.500384][ T189] ? lock_acquire+0x13c/0x160 [ 8.500385][ T189] ? rcu_is_watching+0x16/0xd0 [ 8.500387][ T189] ? rtnl_bridge_getlink+0x850/0x850 [ 8.500389][ T189] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 8.500392][ T189] ? rtnl_link_fill+0x900/0x900 [ 8.500394][ T189] ? filter_irq_stacks+0xd0/0xd0 [ 8.500395][ T189] ? stack_depot_save_flags+0x1bc/0x790 [ 8.500399][ T189] netlink_rcv_skb+0x14e/0x3a0 [ 8.500401][ T189] ? rtnl_link_fill+0x900/0x900 [ 8.500403][ T189] ? lock_release+0x184/0x1f0 [ 8.500405][ T189] ? netlink_ack+0xcd0/0xcd0 [ 8.500406][ T189] ? __netlink_lookup+0xb9/0x6b0 [ 8.500410][ T189] ? rcu_is_watching+0x16/0xd0 [ 8.500412][ T189] ? lock_release+0x184/0x1f0 [ 8.500414][ T189] netlink_unicast+0x486/0x750 [ 8.500416][ T189] ? netlink_attachskb+0x810/0x810 [ 8.500419][ T189] ? rcu_is_watching+0x16/0xd0 [ 8.500420][ T189] ? rcu_is_watching+0x16/0xd0 [ 8.500422][ T189] ? lock_acquire+0x13c/0x160 [ 8.500424][ T189] netlink_sendmsg+0x735/0xc60 [ 8.500427][ T189] ? netlink_unicast+0x750/0x750 [ 8.500430][ T189] ? rcu_is_watching+0x16/0xd0 [ 8.500432][ T189] ? lock_release+0x184/0x1f0 [ 8.500434][ T189] ____sys_sendmsg+0x415/0x880 [ 8.500436][ T189] ? copy_msghdr_from_user+0x279/0x420 [ 8.500437][ T189] ? get_timestamp.constprop.0+0x3a0/0x3a0 [ 8.500440][ T189] ? move_addr_to_kernel+0x40/0x40 [ 8.500442][ T189] ? filter_irq_stacks+0xd0/0xd0 [ 8.500445][ T189] ___sys_sendmsg+0x14e/0x1d0 [ 8.500446][ T189] ? copy_msghdr_from_user+0x420/0x420 [ 8.500452][ T189] __sys_sendmsg+0x12c/0x1d0 [ 8.500454][ T189] ? __sys_sendmsg_sock+0x20/0x20 [ 8.500457][ T189] ? rcu_is_watching+0x16/0xd0 [ 8.500459][ T189] do_syscall_64+0xff/0x530 [ 8.500461][ T189] ? exc_page_fault+0xee/0x100 [ 8.500463][ T189] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 8.500465][ T189] RIP: 0033:0x7f255922554e [ 8.500467][ T189] Code: 4d 89 d8 e8 b4 bd 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 [ 8.500469][ T189] RSP: 002b:00007ffdda4f53b0 EFLAGS: 00000202 ORIG_RAX: 000000000000002e [ 8.500473][ T189] RAX: ffffffffffffffda RBX: 0000000000000003 RCX: 00007f255922554e [ 8.500475][ T189] RDX: 0000000000000000 RSI: 00007ffdda4f5460 RDI: 0000000000000003 [ 8.500476][ T189] RBP: 00007ffdda4f53c0 R08: 0000000000000000 R09: 0000000000000000 [ 8.500477][ T189] R10: 0000000000000000 R11: 0000000000000202 R12: 000000006a90e407 [ 8.500478][ T189] R13: 00000000004a1620 R14: 0000000000000000 R15: 00007ffdda4f5b20 [ 8.500480][ T189] [ 8.596040][ T66] fbnic 0000:01:00.0 enp1s0: Link is Up - 100Gbps/Full - flow control rx/tx [ 8.790101][ T189] ip (189) used greatest stack depth: 22632 bytes left