reserved # 102.72 [+0.00] [ 0.006747] [ T0] e820: remove [mem 0x000a0000-0x000fffff] usable # 102.72 [+0.00] [ 0.007907] [ T0] last_pfn = 0x3ffff max_arch_pfn = 0x10000000000 # 102.72 [+0.00] [ 0.009085] [ T0] MTRR map: 4 entries (3 fixed + 1 variable; max 19), built from 8 variable MTRRs # 102.73 [+0.00] [ 0.010756] [ T0] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT # 102.73 [+0.00] [ 0.014017] [ T0] found SMP MP-table at [mem 0x000f5690-0x000f569f] # 102.73 [+0.00] [ 0.015220] [ T0] Using GB pages for direct mapping # 102.74 [+0.00] [ 0.016291] [ T0] ACPI: Early table checksum verification disabled # 102.74 [+0.00] [ 0.017464] [ T0] ACPI: RSDP 0x00000000000F54B0 000024 (v02 BOCHS ) # 102.74 [+0.00] [ 0.018655] [ T0] ACPI: XSDT 0x000000003FFFFFCA 000034 (v01 BOCHS BXPC 00000001 BXPC 00000001) # 102.75 [+0.00] [ 0.020379] [ T0] ACPI: FACP 0x000000003FFFFE54 00010C (v05 BOCHS BXPC 00000001 BXPC 00000001) # 102.75 [+0.00] [ 0.022089] [ T0] ACPI: DSDT 0x000000003FFFF240 000C14 (v02 BOCHS BXPC 00000001 BXPC 00000001) # 102.76 [+0.00] [ 0.023798] [ T0] ACPI: APIC 0x000000003FFFFF60 00006A (v03 BOCHS BXPC 00000001 BXPC 00000001) # 102.76 [+0.00] [ 0.025498] [ T0] ACPI: Reserving FACP table memory at [mem 0x3ffffe54-0x3fffff5f] # 102.77 [+0.00] [ 0.026928] [ T0] ACPI: Reserving DSDT table memory at [mem 0x3ffff240-0x3ffffe53] # 102.77 [+0.00] [ 0.028354] [ T0] ACPI: Reserving APIC table memory at [mem 0x3fffff60-0x3fffffc9] # 102.77 [+0.00] [ 0.030304] [ T0] No NUMA configuration found # 102.78 [+0.00] [ 0.031143] [ T0] Faking a node at [mem 0x0000000000000000-0x000000003fffefff] # 102.78 [+0.00] [ 0.032510] [ T0] NODE_DATA(0) allocated [mem 0x3fffa100-0x3fffefff] # 102.78 [+0.00] [ 0.041696] [ T0] Zone ranges: # 102.78 [+0.00] [ 0.042313] [ T0] DMA [mem 0x0000000000001000-0x0000000000ffffff] # 102.79 [+0.00] [ 0.043582] [ T0] DMA32 [mem 0x0000000001000000-0x000000003fffefff] # 102.79 [+0.00] [ 0.044856] [ T0] Normal empty # 102.79 [+0.00] [ 0.045515] [ T0] Device empty # 102.79 [+0.00] [ 0.046178] [ T0] Movable zone start for each node # 102.80 [+0.00] [ 0.047096] [ T0] Early memory node ranges # 102.80 [+0.00] [ 0.047903] [ T0] node 0: [mem 0x0000000000001000-0x000000000009efff] # 102.80 [+0.00] [ 0.049184] [ T0] node 0: [mem 0x0000000000100000-0x000000003fffefff] # 102.81 [+0.00] [ 0.050468] [ T0] Initmem setup node 0 [mem 0x0000000000001000-0x000000003fffefff] # 102.81 [+0.00] [ 0.051910] [ T0] On node 0, zone DMA: 1 pages in unavailable ranges # 102.81 [+0.00] [ 0.053153] [ T0] On node 0, zone DMA: 97 pages in unavailable ranges # 102.82 [+0.00] [ 0.056605] [ T0] On node 0, zone DMA32: 1 pages in unavailable ranges # 102.82 [+0.00] [ 0.127689] [ T0] KernelAddressSanitizer initialized (generic) # 102.82 [+0.00] [ 0.129345] [ T0] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) # 102.83 [+0.00] [ 0.130714] [ T0] IOAPIC[0]: apic_id 0, version 32, address 0xfec00000, GSI 0-23 # 102.83 [+0.00] [ 0.132269] [ T0] IOAPIC[1]: apic_id 1, version 32, address 0xfec10000, GSI 24-47 # 102.83 [+0.00] [ 0.133744] [ T0] ACPI: Using ACPI (MADT) for SMP configuration information # 102.84 [+0.00] [ 0.135107] [ T0] TSC deadline timer available # 102.84 [+0.00] [ 0.135995] [ T0] CPU topo: Max. logical packages: 1 # 102.84 [+0.00] [ 0.137014] [ T0] CPU topo: Max. logical dies: 1 # 102.85 [+0.00] [ 0.138033] [ T0] CPU topo: Max. dies per package: 1 # 102.85 [+0.00] [ 0.139052] [ T0] CPU topo: Max. threads per core: 1 # 102.85 [+0.00] [ 0.140069] [ T0] CPU topo: Num. cores per package: 4 # 102.85 [+0.00] [ 0.141135] [ T0] CPU topo: Num. threads per package: 4 # 102.86 [+0.00] [ 0.142201] [ T0] CPU topo: Allowing 4 present CPUs plus 0 hotplug CPUs # 102.86 [+0.00] [ 0.143535] [ T0] kvm-guest: APIC: eoi() replaced with kvm_guest_apic_eoi_write() # 102.86 [+0.00] [ 0.145022] [ T0] kvm-guest: KVM setup pv remote TLB flush # 102.87 [+0.00] [ 0.146106] [ T0] kvm-guest: setup PV sched yield # 102.87 [+0.00] [ 0.147061] [ T0] PM: hibernation: Registered nosave memory: [mem 0x00000000-0x00000fff] # 102.87 [+0.00] [ 0.148635] [ T0] PM: hibernation: Registered nosave memory: [mem 0x0009f000-0x000fffff] # 102.88 [+0.00] [ 0.150214] [ T0] [mem 0x40000000-0xfeffbfff] available for PCI devices # 102.88 [+0.00] [ 0.151506] [ T0] Booting paravirtualized kernel on KVM # 102.88 [+0.00] [ 0.152540] [ T0] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns # 102.89 [+0.01] [ 0.163660] [ T0] setup_percpu: NR_CPUS:64 nr_cpumask_bits:4 nr_cpu_ids:4 nr_node_ids:1 # 102.89 [+0.00] [ 0.166190] [ T0] percpu: Embedded 66 pages/cpu s232080 r8192 d30064 u524288 # 102.90 [+0.00] [ 0.167577] [ T0] pcpu-alloc: s232080 r8192 d30064 u524288 alloc=1*2097152 # 102.90 [+0.00] [ 0.168934] [ T0] pcpu-alloc: [0] 0 1 2 3 # 102.90 [+0.00] [ 0.169813] [ T0] Kernel command line: virtme_hostname=vmksft-forwarding-dbg,debug-threads=on nr_open=2147483584 virtme_link_mods=/srv/vmksft/testing/wt-4/.virtme_mods/lib/modules/0.0.0 virtme_rw_overlay0=/etc virtme_rw_overlay1=/lib virtme_rw_overlay2=/home virtme_rw_overlay3=/opt virtme_rw_overlay4=/srv virtme_rw_overlay5=/usr virtme_rw_overlay6=/var virtme_rw_overlay7=/tmp console=hvc0 earlyprintk=serial,ttyS0,115200 virtme_console=ttyS0 psmouse.proto=exps TERM=dumb virtme_chdir=srv/vmksft/testing/wt-4 rootfstype=virtiofs root=ROOTFS raid=noautodetect ro debug init=/usr/lib/python3.14/site-packages/virtme/guest/bin/virtme-ng-init # 102.93 [+0.03] [ 0.180625] [ T0] Unknown kernel command line parameters "virtme_hostname=vmksft-forwarding-dbg,debug-threads=on nr_open=2147483584 virtme_link_mods=/srv/vmksft/testing/wt-4/.virtme_mods/lib/modules/0.0.0 virtme_rw_overlay0=/etc virtme_rw_overlay1=/lib virtme_rw_overlay2=/home virtme_rw_overlay3=/opt virtme_rw_overlay4=/srv virtme_rw_overlay5=/usr virtme_rw_overlay6=/var virtme_rw_overlay7=/tmp virtme_console=ttyS0 virtme_chdir=srv/vmksft/testing/wt-4", will be passed to user space. # 102.95 [+0.02] [ 0.188851] [ T0] random: crng init done # 102.95 [+0.00] [ 0.189633] [ T0] printk: log buffer data + meta data: 262144 + 917504 = 1179648 bytes # 102.95 [+0.00] [ 0.191655] [ T0] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) # 102.96 [+0.00] [ 0.193531] [ T0] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear) # 102.96 [+0.00] [ 0.195360] [ T0] Fallback order for Node 0: 0 # 102.96 [+0.00] [ 0.195367] [ T0] Built 1 zonelists, mobility grouping on. Total pages: 262045 # 102.97 [+0.00] [ 0.197699] [ T0] Policy zone: DMA32 # 102.97 [+0.00] [ 0.198424] [ T0] mem auto-init: stack:all(zero), heap alloc:off, heap free:off # 102.97 [+0.00] [ 0.199867] [ T0] stackdepot: allocating hash table via alloc_large_system_hash # 102.98 [+0.00] [ 0.201306] [ T0] stackdepot hash table entries: 1048576 (order: 12, 16777216 bytes, linear) # 102.98 [+0.00] [ 0.210211] [ T0] stackdepot: allocating space for 8192 stack pools via memblock # 102.99 [+0.00] [ 0.234259] [ T0] ********************************************************** # 102.99 [+0.00] [ 0.235679] [ T0] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** # 102.99 [+0.00] [ 0.237071] [ T0] ** ** # 103.00 [+0.00] [ 0.238458] [ T0] ** This system shows unhashed kernel memory addresses ** # 103.00 [+0.00] [ 0.239849] [ T0] ** via the console, logs, and other interfaces. This ** # 103.00 [+0.00] [ 0.241255] [ T0] ** might reduce the security of your system. ** # 103.01 [+0.00] [ 0.242645] [ T0] ** ** # 103.01 [+0.00] [ 0.244037] [ T0] ** If you see this message and you are not debugging ** # 103.02 [+0.00] [ 0.245425] [ T0] ** the kernel, report this immediately to your system ** # 103.02 [+0.00] [ 0.246816] [ T0] ** administrator! ** # 103.02 [+0.00] [ 0.248202] [ T0] ** ** # 103.03 [+0.00] [ 0.249596] [ T0] ** Use hash_pointers=always to force this mode off ** # 103.03 [+0.00] [ 0.250996] [ T0] ** ** # 103.03 [+0.00] [ 0.252397] [ T0] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** # 103.04 [+0.00] [ 0.253801] [ T0] ********************************************************** # 103.04 [+0.00] [ 0.255629] [ T0] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 # 103.05 [+0.00] [ 0.272699] [ T0] ftrace: allocating 46482 entries in 184 pages # 103.05 [+0.00] [ 0.273891] [ T0] ftrace: allocated 184 pages with 4 groups # 103.05 [+0.00] [ 0.275359] [ T0] Dynamic Preempt: full # 103.05 [+0.00] [ 0.277252] [ T0] Running RCU self tests # 103.06 [+0.00] [ 0.278046] [ T0] Running RCU synchronous self tests # 103.06 [+0.00] [ 0.279037] [ T0] rcu: Preemptible hierarchical RCU implementation. # 103.06 [+0.00] [ 0.280274] [ T0] rcu: RCU event tracing is enabled. # 103.07 [+0.00] [ 0.281280] [ T0] rcu: RCU lockdep checking is enabled. # 103.07 [+0.00] [ 0.282332] [ T0] rcu: RCU restricting CPUs from NR_CPUS=64 to nr_cpu_ids=4. # 103.07 [+0.00] [ 0.283741] [ T0] rcu: RCU callback double-/use-after-free debug is enabled. # 103.08 [+0.00] [ 0.285145] [ T0] Trampoline variant of Tasks RCU enabled. # 103.08 [+0.00] [ 0.286249] [ T0] Rude variant of Tasks RCU enabled. # 103.08 [+0.00] [ 0.287250] [ T0] Tracing variant of Tasks RCU enabled. # 103.08 [+0.00] [ 0.288302] [ T0] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. # 103.09 [+0.00] [ 0.289903] [ T0] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 # 103.09 [+0.00] [ 0.291544] [ T0] Running RCU synchronous self tests # 103.09 [+0.00] [ 0.292548] [ T0] RCU Tasks: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. # 103.10 [+0.00] [ 0.294367] [ T0] RCU Tasks Rude: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. # 103.10 [+0.00] [ 0.296285] [ T0] RCU Tasks Trace: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. # 103.11 [+0.00] [ 0.345761] [ T0] NR_IRQS: 4352, nr_irqs: 848, preallocated irqs: 0 # 103.11 [+0.00] [ 0.347245] [ T0] rcu: srcu_init: Setting srcu_struct sizes based on contention. # 103.12 [+0.00] [ 0.349546] [ T0] Console: colour *CGA 80x25 # 103.12 [+0.00] [ 0.350480] [ T0] Lock dependency validator: Copyright (c) 2006 Red Hat, Inc., Ingo Molnar # 103.12 [+0.00] [ 0.352122] [ T0] ... MAX_LOCKDEP_SUBCLASSES: 8 # 103.13 [+0.00] [ 0.353051] [ T0] ... MAX_LOCK_DEPTH: 48 # 103.13 [+0.00] [ 0.353998] [ T0] ... MAX_LOCKDEP_KEYS: 8192 # 103.13 [+0.00] [ 0.354976] [ T0] ... CLASSHASH_SIZE: 4096 # 103.13 [+0.00] [ 0.355954] [ T0] ... MAX_LOCKDEP_ENTRIES: 32768 # 103.14 [+0.00] [ 0.356948] [ T0] ... MAX_LOCKDEP_CHAINS: 65536 # 103.14 [+0.00] [ 0.357943] [ T0] ... CHAINHASH_SIZE: 32768 # 103.14 [+0.00] [ 0.358939] [ T0] memory used by lock dependency info: 6429 kB # 103.14 [+0.00] [ 0.360116] [ T0] memory used for stack traces: 4224 kB # 103.15 [+0.00] [ 0.361174] [ T0] per task-struct memory footprint: 1920 bytes # 103.15 [+0.00] [ 0.362481] [ T0] ACPI: Core revision 20250807 # 103.15 [+0.00] [ 0.363993] [ T0] APIC: Switch to symmetric I/O mode setup # 103.16 [+0.00] [ 0.366913] [ T0] x2apic enabled # 103.16 [+0.00] [ 0.371580] [ T0] APIC: Switched APIC routing to: physical x2apic # 103.16 [+0.00] [ 0.372807] [ T0] kvm-guest: APIC: send_IPI_mask() replaced with kvm_send_ipi_mask() # 103.17 [+0.00] [ 0.374345] [ T0] kvm-guest: APIC: send_IPI_mask_allbutself() replaced with kvm_send_ipi_mask_allbutself() # 103.17 [+0.00] [ 0.376245] [ T0] kvm-guest: setup PV IPIs # 103.17 [+0.00] [ 0.377257] [ T0] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x257a3c3232d, max_idle_ns: 440795236700 ns # 103.18 [+0.01] [ 0.379456] [ T0] Calibrating delay loop (skipped) preset value.. 5200.00 BogoMIPS (lpj=2600000) # 103.18 [+0.00] [ 0.380445] [ T0] RDSEED32 is broken. Disabling the corresponding CPUID bit. # 103.19 [+0.00] [ 0.380445] [ T0] x86/cpu: User Mode Instruction Prevention (UMIP) activated # 103.19 [+0.00] [ 0.380445] [ T0] numa_add_cpu cpu 0 node 0: mask now 0 # 103.19 [+0.00] [ 0.380445] [ T0] Last level iTLB entries: 4KB 512, 2MB 255, 4MB 127 # 103.20 [+0.00] [ 0.380445] [ T0] Last level dTLB entries: 4KB 512, 2MB 255, 4MB 127, 1GB 0 # 103.20 [+0.00] [ 0.380445] [ T0] mitigations: Enabled attack vectors: SMT mitigations: off # 103.20 [+0.00] [ 0.380445] [ T0] Speculative Store Bypass: Vulnerable # 103.21 [+0.00] [ 0.380445] [ T0] Spectre V2 : Vulnerable # 103.21 [+0.00] [ 0.380445] [ T0] Speculative Return Stack Overflow: Vulnerable # 103.21 [+0.00] [ 0.380445] [ T0] Spectre V1 : Vulnerable: __user pointer sanitization and usercopy barriers only; no swapgs barriers # 103.22 [+0.01] [ 0.380445] [ T0] x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' # 103.22 [+0.00] [ 0.380445] [ T0] x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' # 103.22 [+0.00] [ 0.380445] [ T0] x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' # 103.23 [+0.00] [ 0.380445] [ T0] x86/fpu: Supporting XSAVE feature 0x020: 'AVX-512 opmask' # 103.23 [+0.00] [ 0.380445] [ T0] x86/fpu: Supporting XSAVE feature 0x040: 'AVX-512 Hi256' # 103.24 [+0.00] [ 0.380445] [ T0] x86/fpu: Supporting XSAVE feature 0x080: 'AVX-512 ZMM_Hi256' # 103.24 [+0.00] [ 0.380445] [ T0] x86/fpu: Supporting XSAVE feature 0x200: 'Protection Keys User registers' # 103.24 [+0.00] [ 0.380445] [ T0] x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 # 103.25 [+0.00] [ 0.380445] [ T0] x86/fpu: xstate_offset[5]: 832, xstate_sizes[5]: 64 # 103.25 [+0.00] [ 0.380445] [ T0] x86/fpu: xstate_offset[6]: 896, xstate_sizes[6]: 512 # 103.25 [+0.00] [ 0.380445] [ T0] x86/fpu: xstate_offset[7]: 1408, xstate_sizes[7]: 1024 # 103.26 [+0.00] [ 0.380445] [ T0] x86/fpu: xstate_offset[9]: 2432, xstate_sizes[9]: 8 # 103.26 [+0.00] [ 0.380445] [ T0] x86/fpu: Enabled xstate features 0x2e7, context size is 2440 bytes, using 'compacted' format. # 103.27 [+0.01] [ 0.380445] [ T0] Freeing SMP alternatives memory: 36K # 103.27 [+0.00] [ 0.380445] [ T0] pid_max: default: 32768 minimum: 301 # 103.27 [+0.00] [ 0.380445] [ T0] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) # 103.28 [+0.00] [ 0.380445] [ T0] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) # 103.28 [+0.00] [ 0.380445] [ T0] Running RCU synchronous self tests # 103.28 [+0.00] [ 0.380445] [ T0] Running RCU synchronous self tests # 103.28 [+0.00] [ 0.380445] [ T1] smpboot: CPU0: AMD EPYC 9655P 96-Core Processor (family: 0x1a, model: 0x2, stepping: 0x1) # 103.29 [+0.00] [ 0.384831] [ T1] Performance Events: Fam17h+ core perfctr, AMD PMU driver. # 103.29 [+0.00] [ 0.385469] [ T1] ... version: 2 # 103.30 [+0.00] [ 0.386426] [ T1] ... bit width: 48 # 103.30 [+0.00] [ 0.386454] [ T1] ... generic counters: 6 # 103.30 [+0.00] [ 0.387410] [ T1] ... generic bitmap: 000000000000003f # 103.30 [+0.00] [ 0.387454] [ T1] ... fixed-purpose counters: 0 # 103.31 [+0.00] [ 0.388411] [ T1] ... fixed-purpose bitmap: 0000000000000000 # 103.31 [+0.00] [ 0.388454] [ T1] ... value mask: 0000ffffffffffff # 103.31 [+0.00] [ 0.389454] [ T1] ... max period: 00007fffffffffff # 103.32 [+0.00] [ 0.390454] [ T1] ... global_ctrl mask: 000000000000003f # 103.32 [+0.00] [ 0.391739] [ T1] signal: max sigframe size: 3376 # 103.32 [+0.00] [ 0.392866] [ T1] rcu: Hierarchical SRCU implementation. # 103.33 [+0.00] [ 0.393465] [ T1] rcu: Max phase no-delay instances is 400. # 103.33 [+0.00] [ 0.402904] [ T1] smp: Bringing up secondary CPUs ... # 103.33 [+0.00] [ 0.405961] [ T1] smpboot: x86: Booting SMP configuration: # 103.33 [+0.00] [ 0.406500] [ T1] .... node #0, CPUs: #1 #2 #3 # 103.34 [+0.00] [ 0.031717] [ T0] numa_add_cpu cpu 1 node 0: mask now 0-1 # 103.34 [+0.00] [ 0.031717] [ T0] numa_add_cpu cpu 2 node 0: mask now 0-2 # 103.34 [+0.00] [ 0.031717] [ T0] numa_add_cpu cpu 3 node 0: mask now 0-3 # 103.35 [+0.00] [ 0.412280] [ T1] smp: Brought up 1 node, 4 CPUs # 103.35 [+0.00] [ 0.416407] [ T1] smpboot: Total of 4 processors activated (20800.00 BogoMIPS) # 103.35 [+0.00] [ 0.419426] [ T1] Memory: 749204K/1048180K available (43965K kernel code, 12511K rwdata, 9108K rodata, 2448K init, 16964K bss, 288364K reserved, 0K cma-reserved) # 103.36 [+0.01] [ 0.420832] [ T1] devtmpfs: initialized # 103.36 [+0.00] [ 0.422785] [ T1] x86/mm: Memory block size: 128MB # 103.36 [+0.00] [ 0.434289] [ T1] Running RCU synchronous self tests # 103.37 [+0.00] [ 0.434476] [ T1] Running RCU synchronous self tests # 103.37 [+0.00] [ 0.436217] [ T1] Running RCU Tasks wait API self tests # 103.37 [+0.00] [ 0.436860] [ T1] Running RCU Tasks Rude wait API self tests # 103.38 [+0.00] [ 0.437459] [ T1] Running RCU Tasks Trace wait API self tests # 103.38 [+0.00] [ 0.452119] [ T1] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns # 103.38 [+0.01] [ 0.452544] [ T1] posixtimers hash table entries: 2048 (order: 6, 147456 bytes, linear) # 103.39 [+0.00] [ 0.453621] [ T1] futex hash table entries: 1024 (131072 bytes on 1 NUMA nodes, total 128 KiB, linear). # 103.39 [+0.00] [ 0.456035] [ T1] PM: RTC time: 12:07:54, date: 2025-12-30 # 103.40 [+0.00] [ 0.459886] [ T1] NET: Registered PF_NETLINK/PF_ROUTE protocol family # 103.40 [+0.00] [ 0.463448] [ T1] thermal_sys: Registered thermal governor 'step_wise' # 103.40 [+0.00] [ 0.463615] [ T1] cpuidle: using governor ladder # 103.40 [+0.00] [ 0.468822] [ T1] PCI: Fatal: No config space access function found # 103.41 [+0.00] [ 0.470117] [ T1] kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. # 103.41 [+0.00] [ 0.471458] [ T43] Callback from call_rcu_tasks_trace() invoked. # 103.42 [+0.00] [ 0.475840] [ T1] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages # 103.42 [+0.00] [ 0.476456] [ T1] HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page # 103.42 [+0.00] [ 0.477488] [ T1] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages # 103.43 [+0.00] [ 0.478462] [ T1] HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page # 103.43 [+0.00] [ 0.483857] [ T1] ACPI: Added _OSI(Module Device) # 103.43 [+0.00] [ 0.484460] [ T1] ACPI: Added _OSI(Processor Device) # 103.44 [+0.00] [ 0.485442] [ T1] ACPI: Added _OSI(Processor Aggregator Device) # 103.44 [+0.00] [ 0.534445] [ T1] ACPI: 1 ACPI AML tables successfully acquired and loaded # 103.44 [+0.00] [ 0.560255] [ T1] ACPI: Interpreter enabled # 103.45 [+0.00] [ 0.561732] [ T1] ACPI: PM: (supports S0 S5) # 103.45 [+0.00] [ 0.562464] [ T1] ACPI: Using IOAPIC for interrupt routing # 103.45 [+0.00] [ 0.563807] [ T1] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug # 103.46 [+0.01] [ 0.565455] [ T1] PCI: Using E820 reservations for host bridge windows # 103.46 [+0.00] [ 0.607319] [ T1] ACPI: PCI: Interrupt link L000 configured for IRQ 16 # 103.47 [+0.00] [ 0.609204] [ T1] ACPI: PCI: Interrupt link L001 configured for IRQ 17 # 103.47 [+0.00] [ 0.610737] [ T1] ACPI: PCI: Interrupt link L002 configured for IRQ 18 # 103.47 [+0.00] [ 0.611858] [ T1] ACPI: PCI: Interrupt link L003 configured for IRQ 19 # 103.48 [+0.00] [ 0.613762] [ T1] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) # 103.48 [+0.00] [ 0.614864] [ T1] acpi PNP0A08:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] # 103.48 [+0.00] [ 0.616456] [ T1] acpi PNP0A08:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] # 103.49 [+0.01] [ 0.628726] [ T1] acpi PNP0A08:00: ECAM [mem 0xe0000000-0xefffffff] reserved as ACPI motherboard resource # 103.49 [+0.01] [ 0.644167] [ T41] Callback from call_rcu_tasks() invoked. # 103.50 [+0.00] [ 0.651326] [ T1] acpi PNP0A08:00: ECAM [mem 0xe0000000-0xefffffff] (base 0xe0000000) # 103.50 [+0.00] [ 0.655091] [ T1] PCI host bridge to bus 0000:00 # 103.50 [+0.00] [ 0.658019] [ T1] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xdfffffff window] # 103.51 [+0.00] [ 0.659472] [ T1] pci_bus 0000:00: root bus resource [mem 0xc000000000000-0xfffffffffffff window] # 103.51 [+0.00] [ 0.660473] [ T1] pci_bus 0000:00: root bus resource [bus 00-ff] # 103.52 [+0.00] [ 0.662681] [ T1] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint # 103.52 [+0.01] [ 0.667336] [ T1] iommu: Default domain type: Translated # 103.53 [+0.00] [ 0.668483] [ T1] iommu: DMA domain TLB invalidation policy: lazy mode # 103.53 [+0.00] [ 0.673479] [ T1] SCSI subsystem initialized # 103.53 [+0.00] [ 0.675173] [ T1] libata version 3.00 loaded. # 103.53 [+0.00] [ 0.677780] [ T1] pps_core: LinuxPPS API ver. 1 registered # 103.54 [+0.00] [ 0.678479] [ T1] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti # 103.54 [+0.01] [ 0.680599] [ T1] PTP clock support registered # 103.55 [+0.00] [ 0.683271] [ T1] Advanced Linux Sound Architecture Driver Initialized. # 103.55 [+0.00] [ 0.688823] [ T1] PCI: Using ACPI for IRQ routing # 103.55 [+0.00] [ 0.689524] [ T1] PCI: pci_cache_line_size set to 64 bytes # 103.56 [+0.00] [ 0.690478] [ T1] e820: reserve RAM buffer [mem 0x0009fc00-0x0009ffff] # 103.56 [+0.00] [ 0.691492] [ T1] e820: reserve RAM buffer [mem 0x3ffff000-0x3fffffff] # 103.56 [+0.00] [ 0.694157] [ T1] vgaarb: loaded # 103.56 [+0.00] [ 0.695446] [ T1] clocksource: Switched to clocksource kvm-clock # 103.57 [+0.00] [ 0.702737] [ T1] VFS: Disk quotas dquot_6.6.0 # 103.57 [+0.00] [ 0.703634] [ T1] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) # 103.57 [+0.00] [ 0.709272] [ T1] pnp: PnP ACPI init # 103.58 [+0.00] [ 0.713423] [ T1] system 00:02: [mem 0xe0000000-0xefffffff window] has been reserved # 103.58 [+0.00] [ 0.715030] [ T1] pnp: PnP ACPI: found 3 devices # 103.58 [+0.00] [ 0.748540] [ T1] NET: Registered PF_INET protocol family # 103.59 [+0.00] [ 0.749810] [ T1] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear) # 103.59 [+0.00] [ 0.753963] [ T1] tcp_listen_portaddr_hash hash table entries: 512 (order: 4, 36864 bytes, linear) # 103.60 [+0.00] [ 0.755590] [ T1] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) # 103.60 [+0.00] [ 0.757771] [ T1] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear) # 103.61 [+0.00] [ 0.759438] [ T1] TCP bind hash table entries: 8192 (order: 9, 1179648 bytes, linear) # 103.61 [+0.00] [ 0.760987] [ T1] TCP: Hash tables configured (established 8192 bind 8192) # 103.61 [+0.00] [ 0.762550] [ T1] UDP hash table entries: 512 (order: 5, 131072 bytes, linear) # 103.62 [+0.00] [ 0.763896] [ T1] UDP-Lite hash table entries: 512 (order: 5, 131072 bytes, linear) # 103.62 [+0.00] [ 0.766036] [ T1] NET: Registered PF_UNIX/PF_LOCAL protocol family # 103.62 [+0.00] [ 0.767390] [ T1] pci_bus 0000:00: resource 4 [mem 0xc0000000-0xdfffffff window] # 103.63 [+0.00] [ 0.768747] [ T1] pci_bus 0000:00: resource 5 [mem 0xc000000000000-0xfffffffffffff window] # 103.63 [+0.00] [ 0.770293] [ T1] PCI: CLS 0 bytes, default 64 # 103.64 [+0.00] [ 0.772139] [ T1] kvm_intel: VMX not supported by CPU 2 # 103.64 [+0.00] [ 0.773330] [ T1] kvm_amd: TSC scaling supported # 103.64 [+0.00] [ 0.774198] [ T1] kvm_amd: Nested Virtualization enabled # 103.64 [+0.00] [ 0.775172] [ T1] kvm_amd: Nested Paging enabled # 103.65 [+0.00] [ 0.776090] [ T1] kvm_amd: LBR virtualization supported # 103.65 [+0.00] [ 0.777060] [ T1] kvm_amd: Virtual VMLOAD VMSAVE supported # 103.65 [+0.00] [ 0.778062] [ T1] kvm_amd: Virtual GIF supported # 103.66 [+0.00] [ 0.778915] [ T1] kvm_amd: Virtual NMI enabled # 103.66 [+0.00] [ 0.787381] [ T1] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x257a3c3232d, max_idle_ns: 440795236700 ns # 103.66 [+0.01] [ 0.800541] [ T1] Initialise system trusted keyrings # 103.67 [+0.00] [ 0.801931] [ T1] workingset: timestamp_bits=56 max_order=18 bucket_order=0 # 103.67 [+0.00] [ 0.815456] [ T1] squashfs: version 4.0 (2009/01/31) Phillip Lougher # 103.67 [+0.00] [ 0.816777] [ T1] fuse: init (API version 7.45) # 103.68 [+0.00] [ 0.818360] [ T1] 9p: Installing v9fs 9p2000 file system support # 103.68 [+0.00] [ 0.827571] [ T1] Key type asymmetric registered # 103.68 [+0.00] [ 0.828452] [ T1] Asymmetric key parser 'x509' registered # 103.68 [+0.00] [ 0.829804] [ T1] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 249) # 103.69 [+0.00] [ 0.831442] [ T1] io scheduler mq-deadline registered # 103.69 [+0.00] [ 0.832382] [ T1] io scheduler kyber registered # 103.69 [+0.00] [ 0.837207] [ T1] input: Power Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0C:00/input/input0 # 103.70 [+0.00] [ 0.839641] [ T1] ACPI: button: Power Button [PWRB] # 103.70 [+0.00] [ 0.843586] [ T1] virtiofs virtio0: discovered new tag: ROOTFS # 103.71 [+0.00] [ 0.845619] [ T1] virtiofs virtio0: virtio_fs_setup_dax: No cache capability # 103.71 [+0.00] [ 0.851211] [ T1] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled # 103.71 [+0.00] [ 0.854206] [ T1] 00:00: ttyS0 at I/O 0x3f8 (irq = 51, base_baud = 115200) is a 16550A # 103.72 [+0.00] [ 0.938871] [ T1] ACPI: bus type drm_connector registered # 103.72 [+0.00] [ 0.979598] [ T1] loop: module loaded # 103.72 [+0.00] [ 0.992141] [ T1] i8042: PNP: No PS/2 controller found. # 103.73 [+0.00] [ 0.999625] [ T1] rtc_cmos 00:01: registered as rtc0 # 103.73 [+0.00] [ 0.999679] [ T51] printk: legacy console [hvc0] enabled # 103.73 [+0.00] [ 1.000765] [ T1] rtc_cmos 00:01: setting system clock to 2025-12-30T12:07:55 UTC (1767096475) # 103.74 [+0.00] [ 1.001543] [ T51] printk: legacy bootconsole [earlyser0] disabled # 103.74 [+0.00] [ 1.003597] [ T1] rtc_cmos 00:01: alarms up to one day, 242 bytes nvram # 103.74 [+0.00] [ 1.007939] [ T1] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev # 103.75 [+0.00] [ 1.014363] [ T1] NET: Registered PF_INET6 protocol family # 103.75 [+0.00] [ 1.018923] [ T1] Segment Routing with IPv6 # 103.75 [+0.00] [ 1.019289] [ T1] In-situ OAM (IOAM) with IPv6 # 103.76 [+0.00] [ 1.019595] [ T1] sit: IPv6, IPv4 and MPLS over IPv4 tunneling driver # 103.76 [+0.00] [ 1.022949] [ T1] NET: Registered PF_PACKET protocol family # 103.76 [+0.00] [ 1.023143] [ T1] 9pnet: Installing 9P2000 support # 103.77 [+0.00] [ 1.023439] [ T1] Key type dns_resolver registered # 103.77 [+0.00] [ 1.024168] [ T1] NET: Registered PF_VSOCK protocol family # 103.77 [+0.00] [ 1.028309] [ T1] IPI shorthand broadcast: enabled # 103.77 [+0.00] [ 1.126281] [ T1] sched_clock: Marking stable (1095004770, 30717670)->(1202227400, -76504960) # 103.78 [+0.01] [ 1.128837] [ T1] registered taskstats version 1 # 103.78 [+0.00] [ 1.130588] [ T1] Loading compiled-in X.509 certificates # 103.79 [+0.00] [ 1.211655] [ T1] Demotion targets for Node 0: null # 103.79 [+0.00] [ 1.211865] [ T1] kmemleak: Kernel memory leak detector initialized (mem pool available: 12101) # 103.79 [+0.00] [ 1.212060] [ T1] page_owner is disabled # 103.80 [+0.00] [ 1.212620] [ T1] PM: Magic number: 5:629:128 # 103.80 [+0.00] [ 1.212766] [ T1] acpi PNP0C02:00: hash matches # 103.80 [+0.00] [ 1.212924] [ T1] netconsole: network logging started # 103.80 [+0.00] [ 1.214247] [ T1] ALSA device list: # 103.81 [+0.00] [ 1.214343] [ T1] No soundcards found. # 103.81 [+0.00] [ 1.215783] [ T1] check access for rdinit=/init failed: -2, ignoring # 103.81 [+0.00] [ 1.215918] [ T1] md: Skipping autodetection of RAID arrays. (raid=autodetect will force) # 103.82 [+0.00] [ 1.218137] [ T1] VFS: Mounted root (virtiofs filesystem) readonly on device 0:21. # 103.82 [+0.00] [ 1.218869] [ T1] devtmpfs: mounted # 103.82 [+0.00] [ 1.247024] [ T1] Freeing unused kernel image (initmem) memory: 2448K # 103.83 [+0.00] [ 1.247238] [ T1] Write protecting the kernel read-only data: 55296k # 103.83 [+0.00] [ 1.247964] [ T1] Freeing unused kernel image (text/rodata gap) memory: 1088K # 103.83 [+0.00] [ 1.248513] [ T1] Freeing unused kernel image (rodata/data gap) memory: 1132K # 103.84 [+0.00] [ 1.248789] [ T1] Run /usr/lib/python3.14/site-packages/virtme/guest/bin/virtme-ng-init as init process # 103.84 [+0.00] [ 1.249019] [ T1] with arguments: # 103.85 [+0.00] [ 1.249147] [ T1] /usr/lib/python3.14/site-packages/virtme/guest/bin/virtme-ng-init # 103.85 [+0.00] [ 1.249366] [ T1] with environment: # 103.85 [+0.00] [ 1.249494] [ T1] HOME=/ # 103.85 [+0.00] [ 1.249623] [ T1] TERM=dumb # 103.86 [+0.00] [ 1.249784] [ T1] virtme_hostname=vmksft-forwarding-dbg,debug-threads=on # 103.86 [+0.00] [ 1.249984] [ T1] nr_open=2147483584 # 103.86 [+0.00] [ 1.250111] [ T1] virtme_link_mods=/srv/vmksft/testing/wt-4/.virtme_mods/lib/modules/0.0.0 # 103.87 [+0.00] [ 1.250353] [ T1] virtme_rw_overlay0=/etc # 103.87 [+0.00] [ 1.250503] [ T1] virtme_rw_overlay1=/lib # 103.87 [+0.00] [ 1.250653] [ T1] virtme_rw_overlay2=/home # 103.88 [+0.00] [ 1.250834] [ T1] virtme_rw_overlay3=/opt # 103.88 [+0.00] [ 1.250983] [ T1] virtme_rw_overlay4=/srv # 103.88 [+0.00] [ 1.251133] [ T1] virtme_rw_overlay5=/usr # 103.88 [+0.00] [ 1.251291] [ T1] virtme_rw_overlay6=/var # 103.89 [+0.00] [ 1.251454] [ T1] virtme_rw_overlay7=/tmp # 103.89 [+0.00] [ 1.251606] [ T1] virtme_console=ttyS0 # 103.89 [+0.00] [ 1.251787] [ T1] virtme_chdir=srv/vmksft/testing/wt-4 # 103.89 [+0.00] [ 1.266654] [ T1] virtme-ng-init: mount devtmpfs -> /dev: EBUSY: Device or resource busy # 103.90 [+0.00] [ 1.275792] [ T1] overlayfs: failed to set xattr on upper # 103.90 [+0.00] [ 1.276041] [ T1] overlayfs: ...falling back to redirect_dir=nofollow. # 103.90 [+0.00] [ 1.276256] [ T1] overlayfs: ...falling back to uuid=null. # 103.91 [+0.00] [ 1.278751] [ T1] overlayfs: failed to set xattr on upper # 103.91 [+0.00] [ 1.278927] [ T1] overlayfs: ...falling back to redirect_dir=nofollow. # 103.91 [+0.00] [ 1.279134] [ T1] overlayfs: ...falling back to uuid=null. # 103.92 [+0.00] [ 1.281084] [ T1] overlayfs: failed to set xattr on upper # 103.92 [+0.00] [ 1.281258] [ T1] overlayfs: ...falling back to redirect_dir=nofollow. # 103.92 [+0.00] [ 1.281461] [ T1] overlayfs: ...falling back to uuid=null. # 103.93 [+0.00] [ 1.283575] [ T1] overlayfs: failed to set xattr on upper # 103.93 [+0.00] [ 1.283759] [ T1] overlayfs: ...falling back to redirect_dir=nofollow. # 103.93 [+0.00] [ 1.283959] [ T1] overlayfs: ...falling back to uuid=null. # 103.94 [+0.00] [ 1.286271] [ T1] overlayfs: failed to set xattr on upper # 103.94 [+0.00] [ 1.286448] [ T1] overlayfs: ...falling back to redirect_dir=nofollow. # 103.94 [+0.00] [ 1.286666] [ T1] overlayfs: ...falling back to uuid=null. # 103.95 [+0.00] [ 1.288424] [ T1] overlayfs: failed to set xattr on upper # 103.95 [+0.00] [ 1.288613] [ T1] overlayfs: ...falling back to redirect_dir=nofollow. # 103.95 [+0.00] [ 1.289264] [ T1] overlayfs: ...falling back to uuid=null. # 103.96 [+0.00] [ 1.291259] [ T1] overlayfs: failed to set xattr on upper # 103.96 [+0.00] [ 1.291443] [ T1] overlayfs: ...falling back to redirect_dir=nofollow. # 103.96 [+0.00] [ 1.291657] [ T1] overlayfs: ...falling back to uuid=null. # 103.97 [+0.00] [ 1.293616] [ T1] overlayfs: failed to set xattr on upper # 103.97 [+0.00] [ 1.294353] [ T1] overlayfs: ...falling back to redirect_dir=nofollow. # 103.97 [+0.00] [ 1.294560] [ T1] overlayfs: ...falling back to uuid=null. # 103.98 [+0.00] [ 3.434860] [ T71] systemd-tmpfile (71) used greatest stack depth: 24752 bytes left # 103.98 [+0.00] [ 3.435193] [ T1] virtme-ng-init: Failed to read '/usr/lib/tmpfiles.d/audit.conf': Permission denied # 103.99 [+0.00] Failed to opendir() '/proc/self/fd/3': Permission denied # 103.99 [+0.00] [ 3.471313] [ T78] ip (78) used greatest stack depth: 23768 bytes left # 103.99 [+0.00] [ 3.494935] [ T74] virtme-ng-init: Starting systemd-udevd version 258.3-2.fc43 # 104.00 [+0.00] [ 3.495266] [ T74] virtme-ng-init: triggering udev coldplug # 104.00 [+0.00] [ 6.054024] [ T74] virtme-ng-init: waiting for udev to settle # 104.00 [+0.00] [ 6.801839] [ T74] virtme-ng-init: udev is done # 104.01 [+0.00] [ 6.805473] [ T1] virtme-ng-init: initialization done # 104.01 [+0.00] [ 156.333681] [ T4645] ping6 (4645) used greatest stack depth: 23456 bytes left # 104.01 [+0.00] [ 558.655963] [T17349] br0: port 1(veth1) entered blocking state # 104.01 [+0.00] [ 558.656288] [T17349] br0: port 1(veth1) entered disabled state # 104.02 [+0.00] [ 558.656488] [T17349] veth1: entered allmulticast mode # 104.02 [+0.00] [ 558.657990] [T17349] veth1: entered promiscuous mode # 104.02 [+0.00] [ 558.684648] [T17351] br0: port 2(veth2) entered blocking state # 104.03 [+0.00] [ 558.684866] [T17351] br0: port 2(veth2) entered disabled state # 104.03 [+0.00] [ 558.685060] [T17351] veth2: entered allmulticast mode # 104.03 [+0.00] [ 558.686571] [T17351] veth2: entered promiscuous mode # 104.04 [+0.00] [ 558.739298] [ T37] br0: port 1(veth1) entered blocking state # 104.04 [+0.00] [ 558.739599] [ T37] br0: port 1(veth1) entered forwarding state # 104.04 [+0.00] [ 558.765440] [ T35] br0: port 2(veth2) entered blocking state # 104.05 [+0.00] [ 558.765676] [ T35] br0: port 2(veth2) entered forwarding state # 104.05 [+0.00] [ 569.312362] [T17379] GACT probability NOT on # 104.05 [+0.00] [ 689.025893] [T18682] br0: port 1(veth1) entered disabled state # 104.05 [+0.00] [ 694.119240] [T18687] br0: port 1(veth1) entered forwarding state # 104.06 [+0.00] [ 699.267706] [T18695] br0: vlan filtering disabled, automatically disabling multicast vlan snooping # 104.06 [+0.00] [ 709.627694] [T18719] br0: vlan filtering disabled, automatically disabling multicast vlan snooping # 104.07 [+0.00] [ 709.697772] [T18726] br0: port 2(veth2) entered disabled state # 104.07 [+0.00] [ 709.721555] [T18727] br0: port 1(veth1) entered disabled state # 104.07 [+0.00] [ 709.744178] [T18728] veth2: left allmulticast mode # 104.08 [+0.00] [ 709.744391] [T18728] veth2: left promiscuous mode # 104.08 [+0.00] [ 709.744702] [T18728] br0: port 2(veth2) entered disabled state # 104.08 [+0.00] [ 709.745997] [T18728] veth1: left allmulticast mode # 104.08 [+0.00] [ 709.746184] [T18728] veth1: left promiscuous mode # 104.09 [+0.00] [ 709.746464] [T18728] br0: port 1(veth1) entered disabled state # 104.09 [+0.00] [ 718.442290] [T18869] gre: GRE over IPv4 demultiplexer driver # 104.09 [+0.00] [ 718.456145] [T18869] ip_gre: GRE over IPv4 tunneling driver # 104.10 [+0.00] [ 718.985521] [T18893] 8021q: 802.1Q VLAN Support v1.8 # 104.10 [+0.00] [ 807.172142] [T19301] ip6_gre: GRE over IPv6 tunneling driver # 104.10 [+0.00] [ 807.214103] [ T12] ip6_tunnel: g1 xmit: Local address not yet configured! # 104.11 [+0.00] [ 807.242977] [ T12] ip6_tunnel: g1 xmit: Local address not yet configured! # 104.11 [+0.00] [ 808.561767] [ T12] ip6_tunnel: g2 xmit: Local address not yet configured! # 104.11 [+0.00] [ 808.587281] [ T12] ip6_tunnel: g2 xmit: Local address not yet configured! # 104.12 [+0.00] [ 935.832295] [T22051] br0: port 1(veth1) entered blocking state # 104.12 [+0.00] [ 935.832569] [T22051] br0: port 1(veth1) entered disabled state # 104.12 [+0.00] [ 935.833172] [T22051] veth1: entered allmulticast mode # 104.13 [+0.00] [ 935.834661] [T22051] veth1: entered promiscuous mode # 104.13 [+0.00] [ 935.854220] [T22052] br0: port 2(veth2) entered blocking state # 104.13 [+0.00] [ 935.854426] [T22052] br0: port 2(veth2) entered disabled state # 104.14 [+0.00] [ 935.854623] [T22052] veth2: entered allmulticast mode # 104.14 [+0.00] [ 935.856389] [T22052] veth2: entered promiscuous mode # 104.14 [+0.00] [ 935.909259] [T17102] br0: port 1(veth1) entered blocking state # 104.14 [+0.00] [ 935.909462] [T17102] br0: port 1(veth1) entered forwarding state # 104.15 [+0.00] [ 935.939791] [ T57] br0: port 2(veth2) entered blocking state # 104.15 [+0.00] [ 935.940019] [ T57] br0: port 2(veth2) entered forwarding state # 104.15 [+0.00] [ 962.461207] [T22142] br0: port 2(veth2) entered disabled state # 104.16 [+0.00] [ 962.484163] [T22143] br0: port 1(veth1) entered disabled state # 104.16 [+0.00] [ 962.505090] [T22144] veth2: left allmulticast mode # 104.16 [+0.00] [ 962.505253] [T22144] veth2: left promiscuous mode # 104.17 [+0.00] [ 962.505472] [T22144] br0: port 2(veth2) entered disabled state # 104.17 [+0.00] [ 962.506372] [T22144] veth1: left allmulticast mode # 104.17 [+0.00] [ 962.506511] [T22144] veth1: left promiscuous mode # 104.17 [+0.00] [ 962.506733] [T22144] br0: port 1(veth1) entered disabled state # 104.18 [+0.00] [ 970.652452] [T22270] br1: port 1(veth1) entered blocking state # 104.18 [+0.00] [ 970.652681] [T22270] br1: port 1(veth1) entered disabled state # 104.18 [+0.00] [ 970.653021] [T22270] veth1: entered allmulticast mode # 104.19 [+0.00] [ 970.654489] [T22270] veth1: entered promiscuous mode # 104.19 [+0.00] [ 970.683378] [T22020] br1: port 1(veth1) entered blocking state # 104.19 [+0.00] [ 970.683600] [T22020] br1: port 1(veth1) entered forwarding state # 104.20 [+0.00] [ 970.717129] [T22273] br1: port 2(veth2) entered blocking state # 104.20 [+0.00] [ 970.717335] [T22273] br1: port 2(veth2) entered disabled state # 104.20 [+0.00] [ 970.717529] [T22273] veth2: entered allmulticast mode # 104.21 [+0.00] [ 970.719014] [T22273] veth2: entered promiscuous mode # 104.21 [+0.00] [ 970.740331] [T22020] br1: port 2(veth2) entered blocking state # 104.21 [+0.00] [ 970.740529] [T22020] br1: port 2(veth2) entered forwarding state # 104.22 [+0.00] [ 981.339085] [ C3] tc action pedit unable to extract header offset for header type (0x5) # 104.22 [+0.00] [ 983.387833] [ C2] tc action pedit unable to extract header offset for header type (0x4) # 104.22 [+0.00] [ 988.219006] [T22699] br1: port 2(veth2) entered disabled state # 104.23 [+0.00] [ 988.244995] [T22700] veth2: left allmulticast mode # 104.23 [+0.00] [ 988.245205] [T22700] veth2: left promiscuous mode # 104.23 [+0.00] [ 988.245503] [T22700] br1: port 2(veth2) entered disabled state # 104.24 [+0.00] [ 988.274648] [T22701] br1: port 1(veth1) entered disabled state # 104.24 [+0.00] [ 988.297671] [T22702] veth1: left allmulticast mode # 104.24 [+0.00] [ 988.297843] [T22702] veth1: left promiscuous mode # 104.24 [+0.00] [ 988.298071] [T22702] br1: port 1(veth1) entered disabled state # 104.25 [+0.00] [ 997.205326] [T22867] br1: port 1(vx100) entered blocking state # 104.25 [+0.00] [ 997.205608] [T22867] br1: port 1(vx100) entered disabled state # 104.25 [+0.00] [ 997.205885] [T22867] vx100: entered allmulticast mode # 104.26 [+0.00] [ 997.207456] [T22867] vx100: entered promiscuous mode # 104.26 [+0.00] [ 997.208304] [T22867] br1: port 1(vx100) entered blocking state # 104.26 [+0.00] [ 997.208538] [T22867] br1: port 1(vx100) entered forwarding state # 104.27 [+0.00] [ 997.258698] [T22869] br1: port 2(veth1) entered blocking state # 104.27 [+0.00] [ 997.258965] [T22869] br1: port 2(veth1) entered disabled state # 104.27 [+0.00] [ 997.259197] [T22869] veth1: entered allmulticast mode # 104.27 [+0.00] [ 997.267079] [T22869] veth1: entered promiscuous mode # 104.28 [+0.00] [ 997.291271] [T17102] br1: port 2(veth1) entered blocking state # 104.28 [+0.00] [ 997.291493] [T17102] br1: port 2(veth1) entered forwarding state # 104.28 [+0.00] [ 997.339780] [T22872] br1: port 3(veth2) entered blocking state # 104.29 [+0.00] [ 997.339989] [T22872] br1: port 3(veth2) entered disabled state # 104.29 [+0.00] [ 997.340190] [T22872] veth2: entered allmulticast mode # 104.29 [+0.00] [ 997.341624] [T22872] veth2: entered promiscuous mode # 104.29 [+0.00] [ 997.359667] [T17102] br1: port 3(veth2) entered blocking state # 104.30 [+0.00] [ 997.359892] [T17102] br1: port 3(veth2) entered forwarding state # 104.30 [+0.00] [ 998.476610] [T22925] br2: port 1(w1) entered blocking state # 104.30 [+0.00] [ 998.476811] [T22925] br2: port 1(w1) entered disabled state # 104.31 [+0.00] [ 998.476970] [T22925] w1: entered allmulticast mode # 104.31 [+0.00] [ 998.478404] [T22925] w1: entered promiscuous mode # 104.31 [+0.00] [ 998.636023] [T22932] br2: port 2(vx100) entered blocking state # 104.31 [+0.00] [ 998.636269] [T22932] br2: port 2(vx100) entered disabled state # 104.32 [+0.00] [ 998.636473] [T22932] vx100: entered allmulticast mode # 104.32 [+0.00] [ 998.637938] [T22932] vx100: entered promiscuous mode # 104.32 [+0.00] [ 998.638282] [T22932] br2: port 2(vx100) entered blocking state # 104.33 [+0.00] [ 998.638462] [T22932] br2: port 2(vx100) entered forwarding state # 104.33 [+0.00] [ 998.821192] [T22020] br2: port 1(w1) entered blocking state # 104.33 [+0.00] [ 998.821380] [T22020] br2: port 1(w1) entered forwarding state # 104.34 [+0.00] [ 999.575100] [T22973] br2: port 1(w1) entered blocking state # 104.34 [+0.00] [ 999.575285] [T22973] br2: port 1(w1) entered disabled state # 104.34 [+0.00] [ 999.575440] [T22973] w1: entered allmulticast mode # 104.34 [+0.00] [ 999.576850] [T22973] w1: entered promiscuous mode # 104.35 [+0.00] [ 999.727347] [T22980] br2: port 2(vx100) entered blocking state # 104.35 [+0.00] [ 999.727559] [T22980] br2: port 2(vx100) entered disabled state # 104.35 [+0.00] [ 999.727969] [T22980] vx100: entered allmulticast mode # 104.36 [+0.00] [ 999.729366] [T22980] vx100: entered promiscuous mode # 104.36 [+0.00] [ 999.729679] [T22980] br2: port 2(vx100) entered blocking state # 104.36 [+0.00] [ 999.729858] [T22980] br2: port 2(vx100) entered forwarding state # 104.37 [+0.00] [ 999.907796] [T17102] br2: port 1(w1) entered blocking state # 104.37 [+0.00] [ 999.907990] [T17102] br2: port 1(w1) entered forwarding state # 104.37 [+0.00] [ 1009.275770] [ T12] vx100: left allmulticast mode # 104.37 [+0.00] [ 1009.276000] [ T12] vx100: left promiscuous mode # 104.38 [+0.00] [ 1009.276261] [ T12] br2: port 2(vx100) entered disabled state # 104.38 [+0.00] [ 1009.279573] [ T12] w1: left allmulticast mode # 104.38 [+0.00] [ 1009.280353] [ T12] w1: left promiscuous mode # 104.38 [+0.00] [ 1009.280663] [ T12] br2: port 1(w1) entered disabled state # 104.39 [+0.00] [ 1009.472608] [ T12] vx100: left allmulticast mode # 104.39 [+0.00] [ 1009.472786] [ T12] vx100: left promiscuous mode # 104.39 [+0.00] [ 1009.473058] [ T12] br2: port 2(vx100) entered disabled state # 104.40 [+0.00] [ 1009.474850] [ T12] w1: left allmulticast mode # 104.40 [+0.00] [ 1009.474992] [ T12] w1: left promiscuous mode # 104.40 [+0.00] [ 1009.475212] [ T12] br2: port 1(w1) entered disabled state # 104.40 [+0.00] [ 1009.987010] [T23102] br1: port 3(veth2) entered disabled state # 104.41 [+0.00] [ 1010.017131] [T23103] veth2: left allmulticast mode # 104.41 [+0.00] [ 1010.017333] [T23103] veth2: left promiscuous mode # 104.41 [+0.00] [ 1010.017567] [T23103] br1: port 3(veth2) entered disabled state # 104.42 [+0.00] [ 1010.063856] [T23105] br1: port 2(veth1) entered disabled state # 104.42 [+0.00] [ 1010.089315] [T23106] veth1: left allmulticast mode # 104.42 [+0.00] [ 1010.089533] [T23106] veth1: left promiscuous mode # 104.42 [+0.00] [ 1010.089849] [T23106] br1: port 2(veth1) entered disabled state # 104.43 [+0.00] [ 1010.115139] [T23107] vx100: left allmulticast mode # 104.43 [+0.00] [ 1010.115326] [T23107] vx100: left promiscuous mode # 104.43 [+0.00] [ 1010.115559] [T23107] br1: port 1(vx100) entered disabled state # 104.44 [+0.00] [ 1018.187874] [T23221] veth0: entered allmulticast mode # 104.44 [+0.00] [ 1018.188250] [T23221] veth1: entered allmulticast mode # 104.44 [+0.00] [ 1018.188486] [T23221] veth2: entered allmulticast mode # 104.44 [+0.00] [ 1018.188714] [T23221] veth3: entered allmulticast mode # 104.45 [+0.00] [ 1018.188958] [T23221] veth4: entered allmulticast mode # 104.45 [+0.00] [ 1018.189272] [T23221] veth5: entered allmulticast mode # 104.45 [+0.00] [ 1029.662642] [T23221] veth0: left allmulticast mode # 104.45 [+0.00] [ 1029.662945] [T23221] veth1: left allmulticast mode # 104.46 [+0.00] [ 1029.663181] [T23221] veth2: left allmulticast mode # 104.46 [+0.00] [ 1029.663415] [T23221] veth3: left allmulticast mode # 104.46 [+0.00] [ 1029.663647] [T23221] veth4: left allmulticast mode # 104.46 [+0.00] [ 1029.663894] [T23221] veth5: left allmulticast mode # 104.47 [+0.00] [ 1045.583533] [T23949] Mirror/redirect action on # 104.47 [+0.00] [ 1052.601666] [T24392] tc (24392) used greatest stack depth: 23320 bytes left # 104.47 [+0.00] [ 1060.411467] [ C0] clocksource: Long readout interval, skipping watchdog check: cs_nsec: 2023741979 wd_nsec: 2023742069 # 104.48 [+0.01] [ 1088.401494] [ C0] watchdog: BUG: soft lockup - CPU#0 stuck for 22s! [kworker/0:1:10] # 104.48 [+0.00] [ 1088.401499] [ C0] Modules linked in: act_skbedit act_ct nf_flow_table nf_tables nf_nat nf_conntrack nf_defrag_ipv6 nf_defrag_ipv4 act_mirred vxlan ip6_udp_tunnel udp_tunnel act_pedit act_vlan cls_matchall ip6_gre ip6_tunnel tunnel6 8021q ip_gre gre act_gact cls_flower sch_ingress bridge stp llc vrf veth # 104.50 [+0.01] [ 1088.401514] [ C0] irq event stamp: 1355759 # 104.50 [+0.00] [ 1088.401515] [ C0] hardirqs last enabled at (1355758): [] irqentry_exit+0xf9/0x4d0 # 104.50 [+0.01] [ 1088.401523] [ C0] hardirqs last disabled at (1355759): [] sysvec_apic_timer_interrupt+0x12/0xe0 # 104.51 [+0.01] [ 1088.401525] [ C0] softirqs last enabled at (482264): [] handle_softirqs+0x56f/0x840 # 104.51 [+0.00] [ 1088.401529] [ C0] softirqs last disabled at (486245): [] irq_exit_rcu+0xa2/0xf0 # 104.52 [+0.01] [ 1088.401534] [ C0] CPU: 0 UID: 0 PID: 10 Comm: kworker/0:1 Not tainted 6.19.0-rc2-virtme #1 PREEMPT(full) # 104.52 [+0.01] [ 1088.401538] [ C0] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 # 104.53 [+0.00] [ 1088.401539] [ C0] Workqueue: 0x0 (events_power_efficient) # 104.53 [+0.00] [ 1088.401544] [ C0] RIP: 0010:rcu_is_watching+0x0/0xd0 # 104.53 [+0.00] [ 1088.401547] [ C0] Code: 71 00 48 8b 14 24 eb 8c 66 90 f3 0f 1e fa 0f 1f 44 00 00 48 c7 c7 e0 0d 4e bc e8 2b c7 15 02 65 0f b6 05 f7 bd 1f 04 c3 66 90 0f 1e fa 53 48 83 ec 08 65 ff 05 60 3c 1e 04 e8 eb c6 15 02 48 # 104.54 [+0.01] [ 1088.401549] [ C0] RSP: 0018:ffa0000000007a68 EFLAGS: 00000202 # 104.55 [+0.00] [ 1088.401551] [ C0] RAX: 0000000000000001 RBX: ff11000014ca3e00 RCX: ffffffffbc0c2e65 # 104.55 [+0.00] [ 1088.401552] [ C0] RDX: 0000000000000002 RSI: ffffffffbcb9cc13 RDI: ffffffffbc66ae00 # 104.55 [+0.00] [ 1088.401553] [ C0] RBP: ffa0000000007ba0 R08: ff11000001ab8ab8 R09: ff11000001ab8ab8 # 104.56 [+0.00] [ 1088.401554] [ C0] R10: 0000000000000200 R11: 0000000000000001 R12: ff11000009bee6f8 # 104.56 [+0.00] [ 1088.401555] [ C0] R13: 0000000000000101 R14: ffa0000000007bf0 R15: 0000000000000000 # 104.57 [+0.00] [ 1088.401557] [ C0] FS: 0000000000000000(0000) GS:ff11000076ed4000(0000) knlGS:0000000000000000 # 104.57 [+0.00] [ 1088.401560] [ C0] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 # 104.57 [+0.00] [ 1088.401561] [ C0] CR2: 000055de81c22000 CR3: 0000000014d54005 CR4: 0000000000771ef0 # 104.58 [+0.00] [ 1088.401562] [ C0] PKRU: 55555554 # 104.58 [+0.00] [ 1088.401563] [ C0] Call Trace: # 104.58 [+0.00] [ 1088.401564] [ C0] # 104.58 [+0.00] [ 1088.401565] [ C0] ndisc_send_skb+0xaf3/0x14d0 # 104.59 [+0.00] [ 1088.401573] [ C0] ? ndisc_ns_create+0x720/0x720 # 104.59 [+0.00] [ 1088.401576] [ C0] ? __lock_release.isra.0+0x59/0x170 # 104.59 [+0.00] [ 1088.401580] [ C0] ? __asan_memcpy+0x3c/0x60 # 104.59 [+0.00] [ 1088.401583] [ C0] ? __ndisc_fill_addr_option+0xb5/0x110 # 104.60 [+0.00] [ 1088.401588] [ C0] addrconf_rs_timer+0x277/0x730 # 104.60 [+0.00] [ 1088.401593] [ C0] ? ipv6_get_lladdr+0x400/0x400 # 104.60 [+0.00] [ 1088.401595] [ C0] ? rcu_is_watching+0x15/0xd0 # 104.60 [+0.00] [ 1088.401596] [ C0] ? call_timer_fn+0x114/0x300 # 104.61 [+0.00] [ 1088.401599] [ C0] ? lock_acquire+0x10a/0x150 # 104.61 [+0.00] [ 1088.401602] [ C0] ? ipv6_get_lladdr+0x400/0x400 # 104.61 [+0.00] [ 1088.401604] [ C0] call_timer_fn+0x164/0x300 # 104.61 [+0.00] [ 1088.401606] [ C0] ? detach_if_pending+0x1c0/0x1c0 # 104.62 [+0.00] [ 1088.401609] [ C0] ? debug_object_active_state+0x430/0x430 # 104.62 [+0.00] [ 1088.401613] [ C0] ? __lock_release.isra.0+0x59/0x170 # 104.62 [+0.00] [ 1088.401616] [ C0] ? rcu_is_watching+0x15/0xd0 # 104.63 [+0.00] [ 1088.401619] [ C0] __run_timers+0x600/0x930 # 104.63 [+0.00] [ 1088.401621] [ C0] ? ipv6_get_lladdr+0x400/0x400 # 104.63 [+0.00] [ 1088.401624] [ C0] ? find_held_lock+0x2b/0x80 # 104.63 [+0.00] [ 1088.401626] [ C0] ? __bpf_trace_itimer_expire+0x10/0x10 # 104.64 [+0.00] [ 1088.401628] [ C0] ? __lock_acquire+0x577/0xc10 # 104.64 [+0.00] [ 1088.401634] [ C0] ? __rwlock_init+0x150/0x150 # 104.64 [+0.00] [ 1088.401636] [ C0] ? lock_acquire+0x10a/0x150 # 104.64 [+0.00] [ 1088.401640] [ C0] run_timer_softirq+0xf3/0x170 # 104.65 [+0.00] [ 1088.401642] [ C0] ? __run_timers+0x930/0x930 # 104.65 [+0.00] [ 1088.401645] [ C0] ? handle_softirqs+0x19f/0x840 # 104.65 [+0.00] [ 1088.401647] [ C0] ? rcu_is_watching+0x15/0xd0 # 104.65 [+0.00] [ 1088.401648] [ C0] handle_softirqs+0x1d7/0x840 # 104.66 [+0.00] [ 1088.401652] [ C0] ? _local_bh_enable+0xd0/0xd0 # 104.66 [+0.00] [ 1088.401654] [ C0] ? do_raw_spin_unlock+0x59/0x250 # 104.66 [+0.00] [ 1088.401658] [ C0] irq_exit_rcu+0xa2/0xf0 # 104.66 [+0.00] [ 1088.401660] [ C0] common_interrupt+0xb5/0xf0 # 104.67 [+0.00] [ 1088.401662] [ C0] # 104.67 [+0.00] [ 1088.401663] [ C0] # 104.67 [+0.00] [ 1088.401665] [ C0] asm_common_interrupt+0x26/0x40 # 104.67 [+0.00] [ 1088.401667] [ C0] RIP: 0010:finish_task_switch.isra.0+0x1d2/0x8b0 # 104.68 [+0.00] [ 1088.401670] [ C0] Code: 04 00 00 8b 05 b3 8b a0 03 48 85 d2 0f 85 a3 03 00 00 85 c0 0f 85 ab 02 00 00 48 89 df e8 a6 17 2c 02 e8 71 62 31 00 fb 66 90 <48> 83 7d d0 00 74 4d 49 8d bd 18 05 00 00 48 b8 00 00 00 00 00 fc # 104.69 [+0.01] [ 1088.401671] [ C0] RSP: 0018:ffa00000000a7c08 EFLAGS: 00000296 # 104.69 [+0.00] [ 1088.401672] [ C0] RAX: 0000000000076b63 RBX: ff11000035237280 RCX: ffffffffba02959f # 104.69 [+0.00] [ 1088.401673] [ C0] RDX: ff11000001ab8040 RSI: ffffffffbcbb3a4c RDI: ffffffffbc66ae00 # 104.70 [+0.00] [ 1088.401674] [ C0] RBP: ffa00000000a7c50 R08: 0000000000000000 R09: 0000000000000001 # 104.70 [+0.00] [ 1088.401674] [ C0] R10: 0000000000000000 R11: 0000000000000001 R12: ff11000001b44540 # 104.71 [+0.00] [ 1088.401675] [ C0] R13: ff11000001ab8040 R14: ffffffffbda32134 R15: ff11000035237298 # 104.71 [+0.00] [ 1088.401678] [ C0] ? finish_task_switch.isra.0+0x1cf/0x8b0 # 104.71 [+0.00] [ 1088.401681] [ C0] ? finish_task_switch.isra.0+0x1cf/0x8b0 # 104.72 [+0.00] [ 1088.401685] [ C0] __schedule+0xaea/0x1720 # 104.72 [+0.00] [ 1088.401688] [ C0] ? io_schedule_timeout+0x130/0x130 # 104.72 [+0.00] [ 1088.401691] [ C0] ? __lock_release.isra.0+0x59/0x170 # 104.73 [+0.00] [ 1088.401693] [ C0] ? __lock_release.isra.0+0x59/0x170 # 104.73 [+0.00] [ 1088.401696] [ C0] schedule+0xd4/0x260 # 104.73 [+0.00] [ 1088.401698] [ C0] ? worker_thread+0x3c7/0xd40 # 104.73 [+0.00] [ 1088.401700] [ C0] worker_thread+0x43d/0xd40 # 104.74 [+0.00] [ 1088.401703] [ C0] ? process_one_work+0x1390/0x1390 # 104.74 [+0.00] [ 1088.401704] [ C0] ? __kthread_parkme+0xb3/0x200 # 104.74 [+0.00] [ 1088.401709] [ C0] ? process_one_work+0x1390/0x1390 # 104.74 [+0.00] [ 1088.401710] [ C0] kthread+0x355/0x5b0 # 104.75 [+0.00] [ 1088.401712] [ C0] ? kthread_is_per_cpu+0xe0/0xe0 # 104.75 [+0.00] [ 1088.401713] [ C0] ? __lock_release.isra.0+0x59/0x170 # 104.75 [+0.00] [ 1088.401716] [ C0] ? rcu_is_watching+0x15/0xd0 # 104.75 [+0.00] [ 1088.401717] [ C0] ? kthread_is_per_cpu+0xe0/0xe0 # 104.76 [+0.00] [ 1088.401719] [ C0] ret_from_fork+0x3fb/0x510 # 104.76 [+0.00] [ 1088.401723] [ C0] ? arch_exit_to_user_mode_prepare.isra.0+0x140/0x140 # 104.76 [+0.00] [ 1088.401727] [ C0] ? __switch_to+0x53c/0xd00 # 104.77 [+0.00] [ 1088.401729] [ C0] ? kthread_is_per_cpu+0xe0/0xe0 # 104.77 [+0.00] [ 1088.401731] [ C0] ret_from_fork_asm+0x11/0x20 # 104.77 [+0.00] [ 1088.401739] [ C0] # 104.77 [+0.00] [ 1090.413785] [ C0] rcu: INFO: rcu_preempt self-detected stall on CPU # 104.78 [+0.00] [ 1090.414014] [ C0] rcu: 0-....: (1 GPs behind) idle=f154/1/0x4000000000000000 softirq=736932/736932 fqs=6363 # 104.78 [+0.01] [ 1090.414553] [ C0] rcu: (t=26000 jiffies g=691293 q=619 ncpus=4) # 104.78 [+0.00] [ 1090.414734] [ C0] CPU: 0 UID: 0 PID: 10 Comm: kworker/0:1 Tainted: G L 6.19.0-rc2-virtme #1 PREEMPT(full) # 104.79 [+0.01] [ 1090.414737] [ C0] Tainted: [L]=SOFTLOCKUP # 104.79 [+0.00] [ 1090.414738] [ C0] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 # 104.80 [+0.00] [ 1090.414739] [ C0] Workqueue: 0x0 (events_power_efficient) # 104.80 [+0.00] [ 1090.414743] [ C0] RIP: 0010:rcu_is_watching+0x0/0xd0 # 104.80 [+0.00] [ 1090.414746] [ C0] Code: 71 00 48 8b 14 24 eb 8c 66 90 f3 0f 1e fa 0f 1f 44 00 00 48 c7 c7 e0 0d 4e bc e8 2b c7 15 02 65 0f b6 05 f7 bd 1f 04 c3 66 90 0f 1e fa 53 48 83 ec 08 65 ff 05 60 3c 1e 04 e8 eb c6 15 02 48 # 104.81 [+0.01] [ 1090.414747] [ C0] RSP: 0018:ffa0000000007a68 EFLAGS: 00000202 # 104.82 [+0.00] [ 1090.414749] [ C0] RAX: 0000000000000001 RBX: ff11000014ca3e00 RCX: ffffffffbc0c2e65 # 104.82 [+0.00] [ 1090.414750] [ C0] RDX: 0000000000000002 RSI: ffffffffbcb9cc13 RDI: ffffffffbc66ae00 # 104.82 [+0.00] [ 1090.414750] [ C0] RBP: ffa0000000007ba0 R08: ff11000001ab8ab8 R09: ff11000001ab8ab8 # 104.83 [+0.00] [ 1090.414751] [ C0] R10: 0000000000000200 R11: 0000000000000001 R12: ff11000009bee6f8 # 104.83 [+0.00] [ 1090.414752] [ C0] R13: 0000000000000101 R14: ffa0000000007bf0 R15: 0000000000000000 # 104.84 [+0.00] [ 1090.414754] [ C0] FS: 0000000000000000(0000) GS:ff11000076ed4000(0000) knlGS:0000000000000000 # 104.84 [+0.00] [ 1090.414756] [ C0] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 # 104.84 [+0.00] [ 1090.414757] [ C0] CR2: 000055de81c22000 CR3: 0000000014d54005 CR4: 0000000000771ef0 # 104.85 [+0.00] [ 1090.414758] [ C0] PKRU: 55555554 # 104.85 [+0.00] [ 1090.414758] [ C0] Call Trace: # 104.85 [+0.00] [ 1090.414759] [ C0] # 104.85 [+0.00] [ 1090.414760] [ C0] ndisc_send_skb+0xaf3/0x14d0 # 104.86 [+0.00] [ 1090.414766] [ C0] ? ndisc_ns_create+0x720/0x720 # 104.86 [+0.00] [ 1090.414769] [ C0] ? __lock_release.isra.0+0x59/0x170 # 104.86 [+0.00] [ 1090.414772] [ C0] ? __asan_memcpy+0x3c/0x60 # 104.86 [+0.00] [ 1090.414774] [ C0] ? __ndisc_fill_addr_option+0xb5/0x110 # 104.87 [+0.00] [ 1090.414778] [ C0] addrconf_rs_timer+0x277/0x730 # 104.87 [+0.00] [ 1090.414782] [ C0] ? ipv6_get_lladdr+0x400/0x400 # 104.87 [+0.00] [ 1090.414784] [ C0] ? rcu_is_watching+0x15/0xd0 # 104.88 [+0.00] [ 1090.414786] [ C0] ? call_timer_fn+0x114/0x300 # 104.88 [+0.00] [ 1090.414788] [ C0] ? lock_acquire+0x10a/0x150 # 104.88 [+0.00] [ 1090.414790] [ C0] ? ipv6_get_lladdr+0x400/0x400 # 104.88 [+0.00] [ 1090.414792] [ C0] call_timer_fn+0x164/0x300 # 104.89 [+0.00] [ 1090.414795] [ C0] ? detach_if_pending+0x1c0/0x1c0 # 104.89 [+0.00] [ 1090.414797] [ C0] ? debug_object_active_state+0x430/0x430 # 104.89 [+0.00] [ 1090.414801] [ C0] ? __lock_release.isra.0+0x59/0x170 # 104.89 [+0.00] [ 1090.414803] [ C0] ? rcu_is_watching+0x15/0xd0 # 104.90 [+0.00] [ 1090.414806] [ C0] __run_timers+0x600/0x930 # 104.90 [+0.00] [ 1090.414808] [ C0] ? ipv6_get_lladdr+0x400/0x400 # 104.90 [+0.00] [ 1090.414811] [ C0] ? find_held_lock+0x2b/0x80 # 104.90 [+0.00] [ 1090.414813] [ C0] ? __bpf_trace_itimer_expire+0x10/0x10 # 104.91 [+0.00] [ 1090.414815] [ C0] ? __lock_acquire+0x577/0xc10 # 104.91 [+0.00] [ 1090.414821] [ C0] ? __rwlock_init+0x150/0x150 # 104.91 [+0.00] [ 1090.414823] [ C0] ? lock_acquire+0x10a/0x150 # 104.92 [+0.00] [ 1090.414827] [ C0] run_timer_softirq+0xf3/0x170 # 104.92 [+0.00] [ 1090.414829] [ C0] ? __run_timers+0x930/0x930 # 104.92 [+0.00] [ 1090.414832] [ C0] ? handle_softirqs+0x19f/0x840 # 104.92 [+0.00] [ 1090.414834] [ C0] ? rcu_is_watching+0x15/0xd0 # 104.92 [+0.00] [ 1090.414835] [ C0] handle_softirqs+0x1d7/0x840 # 104.93 [+0.00] [ 1090.414839] [ C0] ? _local_bh_enable+0xd0/0xd0 # 104.93 [+0.00] [ 1090.414841] [ C0] ? do_raw_spin_unlock+0x59/0x250 # 104.93 [+0.00] [ 1090.414845] [ C0] irq_exit_rcu+0xa2/0xf0 # 104.93 [+0.00] [ 1090.414847] [ C0] common_interrupt+0xb5/0xf0 # 104.94 [+0.00] [ 1090.414849] [ C0] # 104.94 [+0.00] [ 1090.414850] [ C0] # 104.94 [+0.00] [ 1090.414851] [ C0] asm_common_interrupt+0x26/0x40 # 104.94 [+0.00] [ 1090.414853] [ C0] RIP: 0010:finish_task_switch.isra.0+0x1d2/0x8b0 # 104.95 [+0.00] [ 1090.414855] [ C0] Code: 04 00 00 8b 05 b3 8b a0 03 48 85 d2 0f 85 a3 03 00 00 85 c0 0f 85 ab 02 00 00 48 89 df e8 a6 17 2c 02 e8 71 62 31 00 fb 66 90 <48> 83 7d d0 00 74 4d 49 8d bd 18 05 00 00 48 b8 00 00 00 00 00 fc # 104.96 [+0.01] [ 1090.414856] [ C0] RSP: 0018:ffa00000000a7c08 EFLAGS: 00000296 # 104.96 [+0.00] [ 1090.414857] [ C0] RAX: 0000000000076b63 RBX: ff11000035237280 RCX: ffffffffba02959f # 104.96 [+0.00] [ 1090.414858] [ C0] RDX: ff11000001ab8040 RSI: ffffffffbcbb3a4c RDI: ffffffffbc66ae00 # 104.97 [+0.00] [ 1090.414858] [ C0] RBP: ffa00000000a7c50 R08: 0000000000000000 R09: 0000000000000001 # 104.97 [+0.00] [ 1090.414859] [ C0] R10: 0000000000000000 R11: 0000000000000001 R12: ff11000001b44540 # 104.98 [+0.00] [ 1090.414860] [ C0] R13: ff11000001ab8040 R14: ffffffffbda32134 R15: ff11000035237298 # 104.98 [+0.00] [ 1090.414868] [ C0] ? finish_task_switch.isra.0+0x1cf/0x8b0 # 104.98 [+0.00] [ 1090.414871] [ C0] ? finish_task_switch.isra.0+0x1cf/0x8b0 # 104.99 [+0.00] [ 1090.414875] [ C0] __schedule+0xaea/0x1720 # 104.99 [+0.00] [ 1090.414878] [ C0] ? io_schedule_timeout+0x130/0x130 # 104.99 [+0.00] [ 1090.414881] [ C0] ? __lock_release.isra.0+0x59/0x170 # 104.99 [+0.00] [ 1090.414883] [ C0] ? __lock_release.isra.0+0x59/0x170 # 105.00 [+0.00] [ 1090.414887] [ C0] schedule+0xd4/0x260 # 105.00 [+0.00] [ 1090.414888] [ C0] ? worker_thread+0x3c7/0xd40 # 105.00 [+0.00] [ 1090.414889] [ C0] worker_thread+0x43d/0xd40 # 105.00 [+0.00] [ 1090.414892] [ C0] ? process_one_work+0x1390/0x1390 # 105.01 [+0.00] [ 1090.414894] [ C0] ? __kthread_parkme+0xb3/0x200 # 105.01 [+0.00] [ 1090.414897] [ C0] ? process_one_work+0x1390/0x1390 # 105.01 [+0.00] [ 1090.414898] [ C0] kthread+0x355/0x5b0 # 105.01 [+0.00] [ 1090.414900] [ C0] ? kthread_is_per_cpu+0xe0/0xe0 # 105.02 [+0.00] [ 1090.414902] [ C0] ? __lock_release.isra.0+0x59/0x170 # 105.02 [+0.00] [ 1090.414904] [ C0] ? rcu_is_watching+0x15/0xd0 # 105.02 [+0.00] [ 1090.414906] [ C0] ? kthread_is_per_cpu+0xe0/0xe0 # 105.02 [+0.00] [ 1090.414908] [ C0] ret_from_fork+0x3fb/0x510 # 105.03 [+0.00] [ 1090.414910] [ C0] ? arch_exit_to_user_mode_prepare.isra.0+0x140/0x140 # 105.03 [+0.00] [ 1090.414914] [ C0] ? __switch_to+0x53c/0xd00 # 105.03 [+0.00] [ 1090.414915] [ C0] ? kthread_is_per_cpu+0xe0/0xe0 # 105.04 [+0.00] [ 1090.414917] [ C0] ret_from_fork_asm+0x11/0x20 # 105.04 [+0.00] [ 1090.414924] [ C0] # 105.04 [+0.00] [ 1116.401456] [ C0] watchdog: BUG: soft lockup - CPU#0 stuck for 48s! [kworker/0:1:10] # 105.04 [+0.00] [ 1116.401459] [ C0] Modules linked in: act_skbedit act_ct nf_flow_table nf_tables nf_nat nf_conntrack nf_defrag_ipv6 nf_defrag_ipv4 act_mirred vxlan ip6_udp_tunnel udp_tunnel act_pedit act_vlan cls_matchall ip6_gre ip6_tunnel tunnel6 8021q ip_gre gre act_gact cls_flower sch_ingress bridge stp llc vrf veth # 105.06 [+0.01] [ 1116.401471] [ C0] irq event stamp: 2371735 # 105.06 [+0.00] [ 1116.401472] [ C0] hardirqs last enabled at (2371734): [] irqentry_exit+0xf9/0x4d0 # 105.07 [+0.01] [ 1116.401476] [ C0] hardirqs last disabled at (2371735): [] sysvec_apic_timer_interrupt+0x12/0xe0 # 105.07 [+0.01] [ 1116.401478] [ C0] softirqs last enabled at (482264): [] handle_softirqs+0x56f/0x840 # 105.08 [+0.01] [ 1116.401480] [ C0] softirqs last disabled at (486245): [] irq_exit_rcu+0xa2/0xf0 # 105.08 [+0.00] [ 1116.401484] [ C0] CPU: 0 UID: 0 PID: 10 Comm: kworker/0:1 Tainted: G L 6.19.0-rc2-virtme #1 PREEMPT(full) # 105.09 [+0.01] [ 1116.401486] [ C0] Tainted: [L]=SOFTLOCKUP # 105.09 [+0.00] [ 1116.401487] [ C0] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 # 105.09 [+0.00] [ 1116.401488] [ C0] Workqueue: 0x0 (events_power_efficient) # 105.10 [+0.00] [ 1116.401491] [ C0] RIP: 0010:lockdep_rtnl_is_held+0x4/0x20 # 105.10 [+0.00] [ 1116.401496] [ C0] Code: 1f 80 00 00 00 00 f3 0f 1e fa 0f 1f 44 00 00 48 c7 c6 80 e2 87 bd e9 9b a2 3f ff 66 66 2e 0f 1f 84 00 00 00 00 00 f3 0f 1e fa <0f> 1f 44 00 00 be ff ff ff ff 48 c7 c7 e8 e2 87 bd e8 86 f2 7e 00 # 105.11 [+0.01] [ 1116.401497] [ C0] RSP: 0018:ffa0000000007a68 EFLAGS: 00000202 # 105.11 [+0.00] [ 1116.401498] [ C0] RAX: 0000000000000001 RBX: ff11000014ca3e00 RCX: 0000000000000000 # 105.12 [+0.00] [ 1116.401499] [ C0] RDX: 1fe22000010da218 RSI: ffffffffbc66ad80 RDI: ff110000086d10c0 # 105.12 [+0.00] [ 1116.401500] [ C0] RBP: ffa0000000007ba0 R08: ff11000001ab8ae0 R09: ff11000001ab8ae0 # 105.12 [+0.00] [ 1116.401501] [ C0] R10: 0000000000000000 R11: 0000000000000001 R12: ff110000086d1000 # 105.13 [+0.00] [ 1116.401501] [ C0] R13: ffffffffbf3a8440 R14: ffa0000000007bf0 R15: ff11000002156800 # 105.13 [+0.00] [ 1116.401504] [ C0] FS: 0000000000000000(0000) GS:ff11000076ed4000(0000) knlGS:0000000000000000 # 105.14 [+0.00] [ 1116.401506] [ C0] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 # 105.14 [+0.00] [ 1116.401507] [ C0] CR2: 000055de81c22000 CR3: 0000000014d54005 CR4: 0000000000771ef0 # 105.14 [+0.00] [ 1116.401508] [ C0] PKRU: 55555554 # 105.15 [+0.00] [ 1116.401509] [ C0] Call Trace: # 105.15 [+0.00] [ 1116.401510] [ C0] # 105.15 [+0.00] [ 1116.401511] [ C0] ndisc_send_skb+0xc0d/0x14d0 # 105.15 [+0.00] [ 1116.401517] [ C0] ? ndisc_ns_create+0x720/0x720 # 105.15 [+0.00] [ 1116.401522] [ C0] ? __ndisc_fill_addr_option+0xb5/0x110 # 105.16 [+0.00] [ 1116.401527] [ C0] addrconf_rs_timer+0x277/0x730 # 105.16 [+0.00] [ 1116.401530] [ C0] ? ipv6_get_lladdr+0x400/0x400 # 105.16 [+0.00] [ 1116.401532] [ C0] ? rcu_is_watching+0x15/0xd0 # 105.17 [+0.00] [ 1116.401534] [ C0] ? call_timer_fn+0x114/0x300 # 105.17 [+0.00] [ 1116.401536] [ C0] ? lock_acquire+0x10a/0x150 # 105.17 [+0.00] [ 1116.401539] [ C0] ? ipv6_get_lladdr+0x400/0x400 # 105.17 [+0.00] [ 1116.401541] [ C0] call_timer_fn+0x164/0x300 # 105.17 [+0.00] [ 1116.401543] [ C0] ? detach_if_pending+0x1c0/0x1c0 # 105.18 [+0.00] [ 1116.401545] [ C0] ? debug_object_active_state+0x430/0x430 # 105.18 [+0.00] [ 1116.401549] [ C0] ? __lock_release.isra.0+0x59/0x170 # 105.18 [+0.00] [ 1116.401551] [ C0] ? rcu_is_watching+0x15/0xd0 # 105.19 [+0.00] [ 1116.401554] [ C0] __run_timers+0x600/0x930 # 105.19 [+0.00] [ 1116.401556] [ C0] ? ipv6_get_lladdr+0x400/0x400 # 105.19 [+0.00] [ 1116.401559] [ C0] ? find_held_lock+0x2b/0x80 # 105.19 [+0.00] [ 1116.401561] [ C0] ? __bpf_trace_itimer_expire+0x10/0x10 # 105.20 [+0.00] [ 1116.401564] [ C0] ? __lock_acquire+0x577/0xc10 # 105.20 [+0.00] [ 1116.401569] [ C0] ? __rwlock_init+0x150/0x150 # 105.20 [+0.00] [ 1116.401571] [ C0] ? lock_acquire+0x10a/0x150 # 105.20 [+0.00] [ 1116.401575] [ C0] run_timer_softirq+0xf3/0x170 # 105.21 [+0.00] [ 1116.401577] [ C0] ? __run_timers+0x930/0x930 # 105.21 [+0.00] [ 1116.401580] [ C0] ? handle_softirqs+0x19f/0x840 # 105.21 [+0.00] [ 1116.401582] [ C0] ? rcu_is_watching+0x15/0xd0 # 105.21 [+0.00] [ 1116.401583] [ C0] handle_softirqs+0x1d7/0x840 # 105.22 [+0.00] [ 1116.401587] [ C0] ? _local_bh_enable+0xd0/0xd0 # 105.22 [+0.00] [ 1116.401589] [ C0] ? do_raw_spin_unlock+0x59/0x250 # 105.22 [+0.00] [ 1116.401593] [ C0] irq_exit_rcu+0xa2/0xf0 # 105.22 [+0.00] [ 1116.401595] [ C0] common_interrupt+0xb5/0xf0 # 105.23 [+0.00] [ 1116.401597] [ C0] # 105.23 [+0.00] [ 1116.401597] [ C0] # 105.23 [+0.00] [ 1116.401598] [ C0] asm_common_interrupt+0x26/0x40 # 105.23 [+0.00] [ 1116.401600] [ C0] RIP: 0010:finish_task_switch.isra.0+0x1d2/0x8b0 # 105.23 [+0.00] [ 1116.401602] [ C0] Code: 04 00 00 8b 05 b3 8b a0 03 48 85 d2 0f 85 a3 03 00 00 85 c0 0f 85 ab 02 00 00 48 89 df e8 a6 17 2c 02 e8 71 62 31 00 fb 66 90 <48> 83 7d d0 00 74 4d 49 8d bd 18 05 00 00 48 b8 00 00 00 00 00 fc # 105.24 [+0.01] [ 1116.401603] [ C0] RSP: 0018:ffa00000000a7c08 EFLAGS: 00000296 # 105.25 [+0.00] [ 1116.401604] [ C0] RAX: 0000000000076b63 RBX: ff11000035237280 RCX: ffffffffba02959f # 105.25 [+0.00] [ 1116.401605] [ C0] RDX: ff11000001ab8040 RSI: ffffffffbcbb3a4c RDI: ffffffffbc66ae00 # 105.26 [+0.00] [ 1116.401606] [ C0] RBP: ffa00000000a7c50 R08: 0000000000000000 R09: 0000000000000001 # 105.26 [+0.00] [ 1116.401606] [ C0] R10: 0000000000000000 R11: 0000000000000001 R12: ff11000001b44540 # 105.26 [+0.00] [ 1116.401607] [ C0] R13: ff11000001ab8040 R14: ffffffffbda32134 R15: ff11000035237298 # 105.27 [+0.00] [ 1116.401610] [ C0] ? finish_task_switch.isra.0+0x1cf/0x8b0 # 105.27 [+0.00] [ 1116.401613] [ C0] ? finish_task_switch.isra.0+0x1cf/0x8b0 # 105.27 [+0.00] [ 1116.401617] [ C0] __schedule+0xaea/0x1720 # 105.28 [+0.00] [ 1116.401620] [ C0] ? io_schedule_timeout+0x130/0x130 # 105.28 [+0.00] [ 1116.401623] [ C0] ? __lock_release.isra.0+0x59/0x170 # 105.28 [+0.00] [ 1116.401625] [ C0] ? __lock_release.isra.0+0x59/0x170 # 105.29 [+0.00] [ 1116.401628] [ C0] schedule+0xd4/0x260 # 105.29 [+0.00] [ 1116.401630] [ C0] ? worker_thread+0x3c7/0xd40 # 105.29 [+0.00] [ 1116.401631] [ C0] worker_thread+0x43d/0xd40 # 105.29 [+0.00] [ 1116.401634] [ C0] ? process_one_work+0x1390/0x1390 # 105.29 [+0.00] [ 1116.401635] [ C0] ? __kthread_parkme+0xb3/0x200 # 105.30 [+0.00] [ 1116.401639] [ C0] ? process_one_work+0x1390/0x1390 # 105.30 [+0.00] [ 1116.401640] [ C0] kthread+0x355/0x5b0 # 105.30 [+0.00] [ 1116.401642] [ C0] ? kthread_is_per_cpu+0xe0/0xe0 # 105.30 [+0.00] [ 1116.401644] [ C0] ? __lock_release.isra.0+0x59/0x170 # 105.31 [+0.00] [ 1116.401646] [ C0] ? rcu_is_watching+0x15/0xd0 # 105.31 [+0.00] [ 1116.401648] [ C0] ? kthread_is_per_cpu+0xe0/0xe0 # 105.31 [+0.00] [ 1116.401650] [ C0] ret_from_fork+0x3fb/0x510 # 105.31 [+0.00] [ 1116.401652] [ C0] ? arch_exit_to_user_mode_prepare.isra.0+0x140/0x140 # 105.32 [+0.00] [ 1116.401656] [ C0] ? __switch_to+0x53c/0xd00 # 105.32 [+0.00] [ 1116.401657] [ C0] ? kthread_is_per_cpu+0xe0/0xe0 # 105.32 [+0.00] [ 1116.401659] [ C0] ret_from_fork_asm+0x11/0x20 # 105.33 [+0.00] [ 1116.401666] [ C0] # 105.33 [+0.00] [ 1136.139780] [ C0] clocksource: Long readout interval, skipping watchdog check: cs_nsec: 69328053137 wd_nsec: 69328057201 ok 1 selftests: net/forwarding: tc_actions.sh make: Leaving directory '/srv/vmksft/testing/wt-4/tools/testing/selftests' xx__-> echo $? DETECTED CRASH, lowering timeout 0 xx__->