reserved # 133.94 [+0.01] [ 0.006674] [ T0] e820: remove [mem 0x000a0000-0x000fffff] usable # 133.94 [+0.01] [ 0.007842] [ T0] last_pfn = 0x3ffff max_arch_pfn = 0x10000000000 # 133.95 [+0.01] [ 0.009017] [ T0] MTRR map: 4 entries (3 fixed + 1 variable; max 19), built from 8 variable MTRRs # 133.96 [+0.01] [ 0.010664] [ T0] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT # 133.97 [+0.01] [ 0.013953] [ T0] found SMP MP-table at [mem 0x000f5690-0x000f569f] # 133.98 [+0.01] [ 0.015148] [ T0] Using GB pages for direct mapping # 133.98 [+0.01] [ 0.016199] [ T0] ACPI: Early table checksum verification disabled # 133.99 [+0.01] [ 0.017360] [ T0] ACPI: RSDP 0x00000000000F54B0 000024 (v02 BOCHS ) # 134.00 [+0.01] [ 0.018542] [ T0] ACPI: XSDT 0x000000003FFFFFCA 000034 (v01 BOCHS BXPC 00000001 BXPC 00000001) # 134.01 [+0.01] [ 0.020231] [ T0] ACPI: FACP 0x000000003FFFFE54 00010C (v05 BOCHS BXPC 00000001 BXPC 00000001) # 134.02 [+0.01] [ 0.021912] [ T0] ACPI: DSDT 0x000000003FFFF240 000C14 (v02 BOCHS BXPC 00000001 BXPC 00000001) # 134.03 [+0.01] [ 0.023602] [ T0] ACPI: APIC 0x000000003FFFFF60 00006A (v03 BOCHS BXPC 00000001 BXPC 00000001) # 134.03 [+0.01] [ 0.025284] [ T0] ACPI: Reserving FACP table memory at [mem 0x3ffffe54-0x3fffff5f] # 134.04 [+0.01] [ 0.026693] [ T0] ACPI: Reserving DSDT table memory at [mem 0x3ffff240-0x3ffffe53] # 134.05 [+0.01] [ 0.028105] [ T0] ACPI: Reserving APIC table memory at [mem 0x3fffff60-0x3fffffc9] # 134.06 [+0.01] [ 0.030029] [ T0] No NUMA configuration found # 134.06 [+0.01] [ 0.030854] [ T0] Faking a node at [mem 0x0000000000000000-0x000000003fffefff] # 134.07 [+0.01] [ 0.032213] [ T0] NODE_DATA(0) allocated [mem 0x3fffa100-0x3fffefff] # 134.08 [+0.01] [ 0.041146] [ T0] Zone ranges: # 134.08 [+0.00] [ 0.041754] [ T0] DMA [mem 0x0000000000001000-0x0000000000ffffff] # 134.09 [+0.01] [ 0.043013] [ T0] DMA32 [mem 0x0000000001000000-0x000000003fffefff] # 134.10 [+0.01] [ 0.044265] [ T0] Normal empty # 134.10 [+0.00] [ 0.044916] [ T0] Device empty # 134.11 [+0.00] [ 0.045573] [ T0] Movable zone start for each node # 134.11 [+0.01] [ 0.046481] [ T0] Early memory node ranges # 134.12 [+0.00] [ 0.047264] [ T0] node 0: [mem 0x0000000000001000-0x000000000009efff] # 134.12 [+0.01] [ 0.048531] [ T0] node 0: [mem 0x0000000000100000-0x000000003fffefff] # 134.13 [+0.01] [ 0.049802] [ T0] Initmem setup node 0 [mem 0x0000000000001000-0x000000003fffefff] # 134.14 [+0.01] [ 0.051227] [ T0] On node 0, zone DMA: 1 pages in unavailable ranges # 134.15 [+0.01] [ 0.052459] [ T0] On node 0, zone DMA: 97 pages in unavailable ranges # 134.15 [+0.01] [ 0.056008] [ T0] On node 0, zone DMA32: 1 pages in unavailable ranges # 134.16 [+0.01] [ 0.125696] [ T0] KernelAddressSanitizer initialized (generic) # 134.17 [+0.01] [ 0.127269] [ T0] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) # 134.18 [+0.01] [ 0.128555] [ T0] IOAPIC[0]: apic_id 0, version 32, address 0xfec00000, GSI 0-23 # 134.18 [+0.01] [ 0.130037] [ T0] IOAPIC[1]: apic_id 1, version 32, address 0xfec10000, GSI 24-47 # 134.19 [+0.01] [ 0.131438] [ T0] ACPI: Using ACPI (MADT) for SMP configuration information # 134.20 [+0.01] [ 0.132741] [ T0] TSC deadline timer available # 134.21 [+0.01] [ 0.133589] [ T0] CPU topo: Max. logical packages: 1 # 134.21 [+0.01] [ 0.134561] [ T0] CPU topo: Max. logical dies: 1 # 134.22 [+0.01] [ 0.135532] [ T0] CPU topo: Max. dies per package: 1 # 134.22 [+0.01] [ 0.136505] [ T0] CPU topo: Max. threads per core: 1 # 134.23 [+0.01] [ 0.137477] [ T0] CPU topo: Num. cores per package: 4 # 134.23 [+0.01] [ 0.138495] [ T0] CPU topo: Num. threads per package: 4 # 134.24 [+0.01] [ 0.139514] [ T0] CPU topo: Allowing 4 present CPUs plus 0 hotplug CPUs # 134.25 [+0.01] [ 0.140783] [ T0] kvm-guest: APIC: eoi() replaced with kvm_guest_apic_eoi_write() # 134.26 [+0.01] [ 0.142205] [ T0] kvm-guest: KVM setup pv remote TLB flush # 134.26 [+0.01] [ 0.143242] [ T0] kvm-guest: setup PV sched yield # 134.27 [+0.01] [ 0.144157] [ T0] PM: hibernation: Registered nosave memory: [mem 0x00000000-0x00000fff] # 134.28 [+0.01] [ 0.145685] [ T0] PM: hibernation: Registered nosave memory: [mem 0x0009f000-0x000fffff] # 134.29 [+0.01] [ 0.147197] [ T0] [mem 0x40000000-0xfeffbfff] available for PCI devices # 134.29 [+0.01] [ 0.148436] [ T0] Booting paravirtualized kernel on KVM # 134.30 [+0.01] [ 0.149429] [ T0] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns # 134.31 [+0.01] [ 0.159749] [ T0] setup_percpu: NR_CPUS:64 nr_cpumask_bits:4 nr_cpu_ids:4 nr_node_ids:1 # 134.32 [+0.01] [ 0.162192] [ T0] percpu: Embedded 66 pages/cpu s230224 r8192 d31920 u524288 # 134.33 [+0.01] [ 0.163524] [ T0] pcpu-alloc: s230224 r8192 d31920 u524288 alloc=1*2097152 # 134.33 [+0.01] [ 0.164812] [ T0] pcpu-alloc: [0] 0 1 2 3 # 134.34 [+0.00] [ 0.165655] [ T0] Kernel command line: virtme_hostname=virtme-ng 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 # 134.39 [+0.06] [ 0.175589] [ T0] Unknown kernel command line parameters "virtme_hostname=virtme-ng 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. # 134.44 [+0.04] [ 0.182988] [ T0] random: crng init done # 134.44 [+0.00] [ 0.183744] [ T0] printk: log buffer data + meta data: 262144 + 917504 = 1179648 bytes # 134.45 [+0.01] [ 0.185691] [ T0] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) # 134.46 [+0.01] [ 0.187492] [ T0] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear) # 134.47 [+0.01] [ 0.189244] [ T0] Fallback order for Node 0: 0 # 134.47 [+0.01] [ 0.189251] [ T0] Built 1 zonelists, mobility grouping on. Total pages: 262045 # 134.48 [+0.01] [ 0.191483] [ T0] Policy zone: DMA32 # 134.48 [+0.00] [ 0.192179] [ T0] mem auto-init: stack:all(zero), heap alloc:off, heap free:off # 134.49 [+0.01] [ 0.193550] [ T0] stackdepot: allocating hash table via alloc_large_system_hash # 134.50 [+0.01] [ 0.194925] [ T0] stackdepot hash table entries: 1048576 (order: 12, 16777216 bytes, linear) # 134.51 [+0.01] [ 0.203511] [ T0] stackdepot: allocating space for 8192 stack pools via memblock # 134.52 [+0.01] [ 0.227070] [ T0] ********************************************************** # 134.53 [+0.01] [ 0.228431] [ T0] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** # 134.53 [+0.01] [ 0.229757] [ T0] ** ** # 134.54 [+0.01] [ 0.231090] [ T0] ** This system shows unhashed kernel memory addresses ** # 134.55 [+0.01] [ 0.232416] [ T0] ** via the console, logs, and other interfaces. This ** # 134.56 [+0.01] [ 0.233741] [ T0] ** might reduce the security of your system. ** # 134.56 [+0.01] [ 0.235071] [ T0] ** ** # 134.57 [+0.01] [ 0.236395] [ T0] ** If you see this message and you are not debugging ** # 134.58 [+0.01] [ 0.237719] [ T0] ** the kernel, report this immediately to your system ** # 134.59 [+0.01] [ 0.239049] [ T0] ** administrator! ** # 134.59 [+0.01] [ 0.240375] [ T0] ** ** # 134.60 [+0.01] [ 0.241702] [ T0] ** Use hash_pointers=always to force this mode off ** # 134.61 [+0.01] [ 0.243034] [ T0] ** ** # 134.62 [+0.01] [ 0.244360] [ T0] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** # 134.63 [+0.01] [ 0.245687] [ T0] ********************************************************** # 134.63 [+0.01] [ 0.247438] [ T0] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 # 134.64 [+0.01] [ 0.264410] [ T0] ftrace: allocating 46183 entries in 182 pages # 134.65 [+0.01] [ 0.265551] [ T0] ftrace: allocated 182 pages with 5 groups # 134.65 [+0.01] [ 0.266926] [ T0] Dynamic Preempt: full # 134.66 [+0.00] [ 0.268793] [ T0] Running RCU self tests # 134.66 [+0.00] [ 0.269551] [ T0] Running RCU synchronous self tests # 134.67 [+0.01] [ 0.270500] [ T0] rcu: Preemptible hierarchical RCU implementation. # 134.68 [+0.01] [ 0.271684] [ T0] rcu: RCU event tracing is enabled. # 134.68 [+0.01] [ 0.272646] [ T0] rcu: RCU lockdep checking is enabled. # 134.69 [+0.01] [ 0.273657] [ T0] rcu: RCU restricting CPUs from NR_CPUS=64 to nr_cpu_ids=4. # 134.69 [+0.01] [ 0.275001] [ T0] rcu: RCU callback double-/use-after-free debug is enabled. # 134.70 [+0.01] [ 0.276350] [ T0] Trampoline variant of Tasks RCU enabled. # 134.71 [+0.01] [ 0.277407] [ T0] Rude variant of Tasks RCU enabled. # 134.71 [+0.01] [ 0.278369] [ T0] Tracing variant of Tasks RCU enabled. # 134.72 [+0.01] [ 0.279379] [ T0] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. # 134.73 [+0.01] [ 0.280912] [ T0] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 # 134.74 [+0.01] [ 0.282518] [ T0] Running RCU synchronous self tests # 134.74 [+0.01] [ 0.283482] [ T0] RCU Tasks: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. # 134.75 [+0.01] [ 0.285237] [ T0] RCU Tasks Rude: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. # 134.76 [+0.01] [ 0.287058] [ T0] RCU Tasks Trace: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. # 134.77 [+0.01] [ 0.335633] [ T0] NR_IRQS: 4352, nr_irqs: 848, preallocated irqs: 0 # 134.78 [+0.01] [ 0.337085] [ T0] rcu: srcu_init: Setting srcu_struct sizes based on contention. # 134.79 [+0.01] [ 0.339323] [ T0] Console: colour *CGA 80x25 # 134.79 [+0.00] [ 0.340205] [ T0] Lock dependency validator: Copyright (c) 2006 Red Hat, Inc., Ingo Molnar # 134.80 [+0.01] [ 0.341754] [ T0] ... MAX_LOCKDEP_SUBCLASSES: 8 # 134.81 [+0.01] [ 0.342637] [ T0] ... MAX_LOCK_DEPTH: 48 # 134.81 [+0.01] [ 0.343538] [ T0] ... MAX_LOCKDEP_KEYS: 8192 # 134.82 [+0.01] [ 0.344471] [ T0] ... CLASSHASH_SIZE: 4096 # 134.82 [+0.01] [ 0.345402] [ T0] ... MAX_LOCKDEP_ENTRIES: 32768 # 134.83 [+0.01] [ 0.346349] [ T0] ... MAX_LOCKDEP_CHAINS: 65536 # 134.83 [+0.01] [ 0.347297] [ T0] ... CHAINHASH_SIZE: 32768 # 134.84 [+0.01] [ 0.348246] [ T0] memory used by lock dependency info: 6429 kB # 134.85 [+0.01] [ 0.349369] [ T0] memory used for stack traces: 4224 kB # 134.85 [+0.01] [ 0.350380] [ T0] per task-struct memory footprint: 1920 bytes # 134.86 [+0.01] [ 0.351641] [ T0] ACPI: Core revision 20250807 # 134.86 [+0.01] [ 0.353136] [ T0] APIC: Switch to symmetric I/O mode setup # 134.87 [+0.01] [ 0.355909] [ T0] x2apic enabled # 134.87 [+0.00] [ 0.360377] [ T0] APIC: Switched APIC routing to: physical x2apic # 134.88 [+0.01] [ 0.361539] [ T0] kvm-guest: APIC: send_IPI_mask() replaced with kvm_send_ipi_mask() # 134.89 [+0.01] [ 0.363003] [ T0] kvm-guest: APIC: send_IPI_mask_allbutself() replaced with kvm_send_ipi_mask_allbutself() # 134.90 [+0.01] [ 0.364833] [ T0] kvm-guest: setup PV IPIs # 134.90 [+0.00] [ 0.365785] [ T0] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x257a3c3232d, max_idle_ns: 440795236700 ns # 134.92 [+0.01] [ 0.367880] [ T0] Calibrating delay loop (skipped) preset value.. 5200.00 BogoMIPS (lpj=2600000) # 134.93 [+0.01] [ 0.368868] [ T0] RDSEED32 is broken. Disabling the corresponding CPUID bit. # 134.93 [+0.01] [ 0.368868] [ T0] x86/cpu: User Mode Instruction Prevention (UMIP) activated # 134.94 [+0.01] [ 0.368868] [ T0] numa_add_cpu cpu 0 node 0: mask now 0 # 134.95 [+0.01] [ 0.368868] [ T0] Last level iTLB entries: 4KB 512, 2MB 255, 4MB 127 # 134.95 [+0.01] [ 0.368868] [ T0] Last level dTLB entries: 4KB 512, 2MB 255, 4MB 127, 1GB 0 # 134.96 [+0.01] [ 0.368868] [ T0] mitigations: Enabled attack vectors: SMT mitigations: off # 134.97 [+0.01] [ 0.368868] [ T0] Speculative Store Bypass: Vulnerable # 134.97 [+0.01] [ 0.368868] [ T0] Spectre V2 : Vulnerable # 134.98 [+0.00] [ 0.368868] [ T0] Speculative Return Stack Overflow: Vulnerable # 134.99 [+0.01] [ 0.368868] [ T0] Spectre V1 : Vulnerable: __user pointer sanitization and usercopy barriers only; no swapgs barriers # 135.00 [+0.01] [ 0.368868] [ T0] x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' # 135.01 [+0.01] [ 0.368868] [ T0] x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' # 135.01 [+0.01] [ 0.368868] [ T0] x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' # 135.02 [+0.01] [ 0.368868] [ T0] x86/fpu: Supporting XSAVE feature 0x020: 'AVX-512 opmask' # 135.03 [+0.01] [ 0.368868] [ T0] x86/fpu: Supporting XSAVE feature 0x040: 'AVX-512 Hi256' # 135.04 [+0.01] [ 0.368868] [ T0] x86/fpu: Supporting XSAVE feature 0x080: 'AVX-512 ZMM_Hi256' # 135.04 [+0.01] [ 0.368868] [ T0] x86/fpu: Supporting XSAVE feature 0x200: 'Protection Keys User registers' # 135.05 [+0.01] [ 0.368868] [ T0] x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 # 135.06 [+0.01] [ 0.368868] [ T0] x86/fpu: xstate_offset[5]: 832, xstate_sizes[5]: 64 # 135.07 [+0.01] [ 0.368868] [ T0] x86/fpu: xstate_offset[6]: 896, xstate_sizes[6]: 512 # 135.07 [+0.01] [ 0.368868] [ T0] x86/fpu: xstate_offset[7]: 1408, xstate_sizes[7]: 1024 # 135.08 [+0.01] [ 0.368868] [ T0] x86/fpu: xstate_offset[9]: 2432, xstate_sizes[9]: 8 # 135.09 [+0.01] [ 0.368868] [ T0] x86/fpu: Enabled xstate features 0x2e7, context size is 2440 bytes, using 'compacted' format. # 135.10 [+0.01] [ 0.368868] [ T0] Freeing SMP alternatives memory: 36K # 135.11 [+0.01] [ 0.368868] [ T0] pid_max: default: 32768 minimum: 301 # 135.11 [+0.01] [ 0.368868] [ T0] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) # 135.12 [+0.01] [ 0.368868] [ T0] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) # 135.13 [+0.01] [ 0.368868] [ T0] Running RCU synchronous self tests # 135.13 [+0.01] [ 0.368868] [ T0] Running RCU synchronous self tests # 135.14 [+0.01] [ 0.368868] [ T1] smpboot: CPU0: AMD EPYC 9655P 96-Core Processor (family: 0x1a, model: 0x2, stepping: 0x1) # 135.15 [+0.01] [ 0.372246] [ T1] Performance Events: Fam17h+ core perfctr, AMD PMU driver. # 135.16 [+0.01] [ 0.372893] [ T1] ... version: 2 # 135.16 [+0.01] [ 0.373807] [ T1] ... bit width: 48 # 135.17 [+0.01] [ 0.373876] [ T1] ... generic counters: 6 # 135.18 [+0.01] [ 0.374789] [ T1] ... generic bitmap: 000000000000003f # 135.18 [+0.01] [ 0.374876] [ T1] ... fixed-purpose counters: 0 # 135.19 [+0.01] [ 0.375782] [ T1] ... fixed-purpose bitmap: 0000000000000000 # 135.19 [+0.01] [ 0.375876] [ T1] ... value mask: 0000ffffffffffff # 135.20 [+0.01] [ 0.376876] [ T1] ... max period: 00007fffffffffff # 135.21 [+0.01] [ 0.377876] [ T1] ... global_ctrl mask: 000000000000003f # 135.21 [+0.01] [ 0.379158] [ T1] signal: max sigframe size: 3376 # 135.22 [+0.01] [ 0.380278] [ T1] rcu: Hierarchical SRCU implementation. # 135.23 [+0.01] [ 0.380885] [ T1] rcu: Max phase no-delay instances is 400. # 135.23 [+0.01] [ 0.389884] [ T1] smp: Bringing up secondary CPUs ... # 135.24 [+0.01] [ 0.392419] [ T1] smpboot: x86: Booting SMP configuration: # 135.24 [+0.01] [ 0.392897] [ T1] .... node #0, CPUs: #1 #2 #3 # 135.25 [+0.01] [ 0.030383] [ T0] numa_add_cpu cpu 1 node 0: mask now 0-1 # 135.25 [+0.01] [ 0.030383] [ T0] numa_add_cpu cpu 2 node 0: mask now 0-2 # 135.26 [+0.01] [ 0.030383] [ T0] numa_add_cpu cpu 3 node 0: mask now 0-3 # 135.27 [+0.01] [ 0.398013] [ T1] smp: Brought up 1 node, 4 CPUs # 135.27 [+0.01] [ 0.402767] [ T1] smpboot: Total of 4 processors activated (20800.00 BogoMIPS) # 135.28 [+0.01] [ 0.405744] [ T1] Memory: 749412K/1048180K available (43559K kernel code, 12577K rwdata, 8980K rodata, 2436K init, 17024K bss, 288360K reserved, 0K cma-reserved) # 135.30 [+0.02] [ 0.407264] [ T1] devtmpfs: initialized # 135.30 [+0.00] [ 0.409029] [ T1] x86/mm: Memory block size: 128MB # 135.31 [+0.01] [ 0.420684] [ T1] Running RCU synchronous self tests # 135.31 [+0.01] [ 0.420902] [ T1] Running RCU synchronous self tests # 135.32 [+0.01] [ 0.422581] [ T1] Running RCU Tasks wait API self tests # 135.32 [+0.01] [ 0.423087] [ T1] Running RCU Tasks Rude wait API self tests # 135.33 [+0.01] [ 0.423880] [ T1] Running RCU Tasks Trace wait API self tests # 135.34 [+0.01] [ 0.438842] [ T1] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns # 135.35 [+0.01] [ 0.438945] [ T1] posixtimers hash table entries: 2048 (order: 6, 147456 bytes, linear) # 135.35 [+0.01] [ 0.440045] [ T1] futex hash table entries: 1024 (131072 bytes on 1 NUMA nodes, total 128 KiB, linear). # 135.37 [+0.01] [ 0.442443] [ T1] PM: RTC time: 18:12:50, date: 2025-11-25 # 135.37 [+0.01] [ 0.446293] [ T1] NET: Registered PF_NETLINK/PF_ROUTE protocol family # 135.38 [+0.01] [ 0.449842] [ T1] thermal_sys: Registered thermal governor 'step_wise' # 135.39 [+0.01] [ 0.450039] [ T1] cpuidle: using governor ladder # 135.39 [+0.01] [ 0.455260] [ T1] PCI: Fatal: No config space access function found # 135.40 [+0.01] [ 0.456563] [ T1] kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. # 135.41 [+0.01] [ 0.460874] [ T1] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages # 135.42 [+0.01] [ 0.461884] [ T1] HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page # 135.42 [+0.01] [ 0.463882] [ T1] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages # 135.43 [+0.01] [ 0.464887] [ T1] HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page # 135.44 [+0.01] [ 0.467879] [ T43] Callback from call_rcu_tasks_trace() invoked. # 135.45 [+0.01] [ 0.473236] [ T1] ACPI: Added _OSI(Module Device) # 135.45 [+0.01] [ 0.473879] [ T1] ACPI: Added _OSI(Processor Device) # 135.46 [+0.01] [ 0.474853] [ T1] ACPI: Added _OSI(Processor Aggregator Device) # 135.46 [+0.01] [ 0.546320] [ T1] ACPI: 1 ACPI AML tables successfully acquired and loaded # 135.47 [+0.01] [ 0.566329] [ T1] ACPI: Interpreter enabled # 135.47 [+0.00] [ 0.567264] [ T1] ACPI: PM: (supports S0 S5) # 135.48 [+0.00] [ 0.567890] [ T1] ACPI: Using IOAPIC for interrupt routing # 135.49 [+0.01] [ 0.569225] [ T1] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug # 135.50 [+0.01] [ 0.570878] [ T1] PCI: Using E820 reservations for host bridge windows # 135.50 [+0.01] [ 0.615388] [ T1] ACPI: PCI: Interrupt link L000 configured for IRQ 16 # 135.51 [+0.01] [ 0.616665] [ T1] ACPI: PCI: Interrupt link L001 configured for IRQ 17 # 135.52 [+0.01] [ 0.617192] [ T1] ACPI: PCI: Interrupt link L002 configured for IRQ 18 # 135.53 [+0.01] [ 0.618187] [ T1] ACPI: PCI: Interrupt link L003 configured for IRQ 19 # 135.53 [+0.01] [ 0.619216] [ T1] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) # 135.54 [+0.01] [ 0.620560] [ T1] acpi PNP0A08:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] # 135.55 [+0.01] [ 0.620879] [ T1] acpi PNP0A08:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] # 135.56 [+0.01] [ 0.631863] [ T1] acpi PNP0A08:00: ECAM [mem 0xe0000000-0xefffffff] reserved as ACPI motherboard resource # 135.57 [+0.01] [ 0.632334] [ T41] Callback from call_rcu_tasks() invoked. # 135.58 [+0.01] [ 0.652734] [ T1] acpi PNP0A08:00: ECAM [mem 0xe0000000-0xefffffff] (base 0xe0000000) # 135.59 [+0.01] [ 0.655677] [ T1] PCI host bridge to bus 0000:00 # 135.59 [+0.01] [ 0.657451] [ T1] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xdfffffff window] # 135.60 [+0.01] [ 0.657916] [ T1] pci_bus 0000:00: root bus resource [mem 0xc000000000000-0xfffffffffffff window] # 135.61 [+0.01] [ 0.658944] [ T1] pci_bus 0000:00: root bus resource [bus 00-ff] # 135.62 [+0.01] [ 0.660087] [ T1] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint # 135.63 [+0.01] [ 0.664662] [ T1] iommu: Default domain type: Translated # 135.63 [+0.01] [ 0.664877] [ T1] iommu: DMA domain TLB invalidation policy: lazy mode # 135.64 [+0.01] [ 0.670037] [ T1] SCSI subsystem initialized # 135.64 [+0.01] [ 0.671786] [ T1] libata version 3.00 loaded. # 135.65 [+0.01] [ 0.673196] [ T1] pps_core: LinuxPPS API ver. 1 registered # 135.65 [+0.01] [ 0.673898] [ T1] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti # 135.67 [+0.01] [ 0.675073] [ T1] PTP clock support registered # 135.67 [+0.01] [ 0.677917] [ T1] Advanced Linux Sound Architecture Driver Initialized. # 135.68 [+0.01] [ 0.683378] [ T1] PCI: Using ACPI for IRQ routing # 135.68 [+0.01] [ 0.683878] [ T1] PCI: pci_cache_line_size set to 64 bytes # 135.69 [+0.01] [ 0.684904] [ T1] e820: reserve RAM buffer [mem 0x0009fc00-0x0009ffff] # 135.70 [+0.01] [ 0.685938] [ T1] e820: reserve RAM buffer [mem 0x3ffff000-0x3fffffff] # 135.70 [+0.01] [ 0.687645] [ T1] vgaarb: loaded # 135.71 [+0.00] [ 0.688807] [ T1] clocksource: Switched to clocksource kvm-clock # 135.71 [+0.01] [ 0.694259] [ T1] VFS: Disk quotas dquot_6.6.0 # 135.72 [+0.01] [ 0.695228] [ T1] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) # 135.73 [+0.01] [ 0.701624] [ T1] pnp: PnP ACPI init # 135.73 [+0.00] [ 0.706366] [ T1] system 00:02: [mem 0xe0000000-0xefffffff window] has been reserved # 135.74 [+0.01] [ 0.708057] [ T1] pnp: PnP ACPI: found 3 devices # 135.75 [+0.01] [ 0.744616] [ T1] NET: Registered PF_INET protocol family # 135.75 [+0.01] [ 0.746112] [ T1] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear) # 135.76 [+0.01] [ 0.750638] [ T1] tcp_listen_portaddr_hash hash table entries: 512 (order: 4, 36864 bytes, linear) # 135.77 [+0.01] [ 0.752372] [ T1] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) # 135.78 [+0.01] [ 0.754042] [ T1] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear) # 135.79 [+0.01] [ 0.755839] [ T1] TCP bind hash table entries: 8192 (order: 9, 1179648 bytes, linear) # 135.80 [+0.01] [ 0.757649] [ T1] TCP: Hash tables configured (established 8192 bind 8192) # 135.80 [+0.01] [ 0.759361] [ T1] UDP hash table entries: 512 (order: 5, 131072 bytes, linear) # 135.81 [+0.01] [ 0.760778] [ T1] UDP-Lite hash table entries: 512 (order: 5, 131072 bytes, linear) # 135.82 [+0.01] [ 0.763158] [ T1] NET: Registered PF_UNIX/PF_LOCAL protocol family # 135.83 [+0.01] [ 0.764518] [ T1] pci_bus 0000:00: resource 4 [mem 0xc0000000-0xdfffffff window] # 135.84 [+0.01] [ 0.765934] [ T1] pci_bus 0000:00: resource 5 [mem 0xc000000000000-0xfffffffffffff window] # 135.84 [+0.01] [ 0.767581] [ T1] PCI: CLS 0 bytes, default 64 # 135.85 [+0.01] [ 0.768636] [ T1] kvm_intel: VMX not supported by CPU 0 # 135.85 [+0.01] [ 0.770720] [ T1] kvm_amd: TSC scaling supported # 135.86 [+0.01] [ 0.771643] [ T1] kvm_amd: Nested Virtualization enabled # 135.87 [+0.01] [ 0.772691] [ T1] kvm_amd: Nested Paging enabled # 135.87 [+0.01] [ 0.773596] [ T1] kvm_amd: LBR virtualization supported # 135.88 [+0.01] [ 0.774625] [ T1] kvm_amd: Virtual VMLOAD VMSAVE supported # 135.88 [+0.01] [ 0.775679] [ T1] kvm_amd: Virtual GIF supported # 135.89 [+0.01] [ 0.776591] [ T1] kvm_amd: Virtual NMI enabled # 135.89 [+0.01] [ 0.786858] [ T1] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x257a3c3232d, max_idle_ns: 440795236700 ns # 135.90 [+0.01] [ 0.800564] [ T1] Initialise system trusted keyrings # 135.91 [+0.01] [ 0.802097] [ T1] workingset: timestamp_bits=56 max_order=18 bucket_order=0 # 135.92 [+0.01] [ 0.816222] [ T1] squashfs: version 4.0 (2009/01/31) Phillip Lougher # 135.92 [+0.01] [ 0.817577] [ T1] fuse: init (API version 7.45) # 135.93 [+0.01] [ 0.819210] [ T1] 9p: Installing v9fs 9p2000 file system support # 135.94 [+0.01] [ 0.828079] [ T1] Key type asymmetric registered # 135.94 [+0.01] [ 0.828995] [ T1] Asymmetric key parser 'x509' registered # 135.95 [+0.01] [ 0.830424] [ T1] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 249) # 135.96 [+0.01] [ 0.832130] [ T1] io scheduler mq-deadline registered # 135.96 [+0.01] [ 0.833109] [ T1] io scheduler kyber registered # 135.97 [+0.01] [ 0.838052] [ T1] input: Power Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0C:00/input/input0 # 135.98 [+0.01] [ 0.840541] [ T1] ACPI: button: Power Button [PWRB] # 135.98 [+0.01] [ 0.844501] [ T1] virtiofs virtio0: discovered new tag: ROOTFS # 135.99 [+0.01] [ 0.846676] [ T1] virtiofs virtio0: virtio_fs_setup_dax: No cache capability # 136.00 [+0.01] [ 0.854885] [ T1] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled # 136.00 [+0.01] [ 0.857850] [ T1] 00:00: ttyS0 at I/O 0x3f8 (irq = 51, base_baud = 115200) is a 16550A # 136.01 [+0.01] [ 0.945643] [ T1] ACPI: bus type drm_connector registered # 136.02 [+0.01] [ 0.985891] [ T1] loop: module loaded # 136.02 [+0.00] [ 0.998327] [ T1] i8042: PNP: No PS/2 controller found. # 136.03 [+0.01] [ 1.005925] [ T1] rtc_cmos 00:01: registered as rtc0 # 136.03 [+0.01] [ 1.007183] [ T1] rtc_cmos 00:01: setting system clock to 2025-11-25T18:12:51 UTC (1764094371) # 136.04 [+0.01] [ 1.009129] [ T28] printk: legacy console [hvc0] enabled # 136.05 [+0.01] [ 1.009587] [ T1] rtc_cmos 00:01: alarms up to one day, 242 bytes nvram # 136.06 [+0.01] [ 1.010175] [ T28] printk: legacy bootconsole [earlyser0] disabled # 136.06 [+0.01] [ 1.014003] [ T1] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev # 136.07 [+0.01] [ 1.020745] [ T1] NET: Registered PF_INET6 protocol family # 136.08 [+0.01] [ 1.025347] [ T1] Segment Routing with IPv6 # 136.08 [+0.00] [ 1.025752] [ T1] In-situ OAM (IOAM) with IPv6 # 136.09 [+0.01] [ 1.026066] [ T1] sit: IPv6, IPv4 and MPLS over IPv4 tunneling driver # 136.09 [+0.01] [ 1.029275] [ T1] NET: Registered PF_PACKET protocol family # 136.10 [+0.01] [ 1.029492] [ T1] 9pnet: Installing 9P2000 support # 136.11 [+0.01] [ 1.029880] [ T1] Key type dns_resolver registered # 136.11 [+0.01] [ 1.030460] [ T1] NET: Registered PF_VSOCK protocol family # 136.12 [+0.01] [ 1.034711] [ T1] IPI shorthand broadcast: enabled # 136.12 [+0.01] [ 1.142218] [ T1] sched_clock: Marking stable (1112002650, 29383090)->(1228387520, -87001780) # 136.13 [+0.01] [ 1.144082] [ T1] registered taskstats version 1 # 136.14 [+0.01] [ 1.146948] [ T1] Loading compiled-in X.509 certificates # 136.14 [+0.01] [ 1.227049] [ T1] Demotion targets for Node 0: null # 136.15 [+0.01] [ 1.227273] [ T1] kmemleak: Kernel memory leak detector initialized (mem pool available: 12102) # 136.16 [+0.01] [ 1.227481] [ T1] page_owner is disabled # 136.16 [+0.00] [ 1.228110] [ T1] PM: Magic number: 1:987:241 # 136.17 [+0.01] [ 1.228285] [ T1] netconsole: network logging started # 136.17 [+0.01] [ 1.229649] [ T1] ALSA device list: # 136.18 [+0.00] [ 1.229758] [ T1] No soundcards found. # 136.18 [+0.00] [ 1.230920] [ T1] check access for rdinit=/init failed: -2, ignoring # 136.19 [+0.01] [ 1.231079] [ T1] md: Skipping autodetection of RAID arrays. (raid=autodetect will force) # 136.20 [+0.01] [ 1.233364] [ T1] VFS: Mounted root (virtiofs filesystem) readonly on device 0:20. # 136.21 [+0.01] [ 1.234072] [ T1] devtmpfs: mounted # 136.21 [+0.00] [ 1.261575] [ T1] Freeing unused kernel image (initmem) memory: 2436K # 136.22 [+0.01] [ 1.261766] [ T1] Write protecting the kernel read-only data: 55296k # 136.22 [+0.01] [ 1.262756] [ T1] Freeing unused kernel image (text/rodata gap) memory: 1496K # 136.23 [+0.01] [ 1.263279] [ T1] Freeing unused kernel image (rodata/data gap) memory: 1260K # 136.24 [+0.01] [ 1.263495] [ T1] Run /usr/lib/python3.14/site-packages/virtme/guest/bin/virtme-ng-init as init process # 136.25 [+0.01] [ 1.263688] [ T1] with arguments: # 136.25 [+0.00] [ 1.263786] [ T1] /usr/lib/python3.14/site-packages/virtme/guest/bin/virtme-ng-init # 136.26 [+0.01] [ 1.263959] [ T1] with environment: # 136.27 [+0.00] [ 1.264051] [ T1] HOME=/ # 136.27 [+0.00] [ 1.264136] [ T1] TERM=dumb # 136.27 [+0.00] [ 1.264219] [ T1] virtme_hostname=virtme-ng # 136.28 [+0.01] [ 1.264337] [ T1] nr_open=2147483584 # 136.28 [+0.00] [ 1.264428] [ T1] virtme_link_mods=/srv/vmksft/testing/wt-4/.virtme_mods/lib/modules/0.0.0 # 136.29 [+0.01] [ 1.264686] [ T1] virtme_rw_overlay0=/etc # 136.30 [+0.00] [ 1.264800] [ T1] virtme_rw_overlay1=/lib # 136.30 [+0.00] [ 1.264908] [ T1] virtme_rw_overlay2=/home # 136.31 [+0.01] [ 1.265019] [ T1] virtme_rw_overlay3=/opt # 136.31 [+0.00] [ 1.265178] [ T1] virtme_rw_overlay4=/srv # 136.32 [+0.00] [ 1.265286] [ T1] virtme_rw_overlay5=/usr # 136.32 [+0.00] [ 1.265406] [ T1] virtme_rw_overlay6=/var # 136.33 [+0.00] [ 1.265513] [ T1] virtme_rw_overlay7=/tmp # 136.33 [+0.00] [ 1.265662] [ T1] virtme_console=ttyS0 # 136.34 [+0.00] [ 1.265762] [ T1] virtme_chdir=srv/vmksft/testing/wt-4 # 136.34 [+0.01] [ 1.283541] [ T1] virtme-ng-init: mount devtmpfs -> /dev: EBUSY: Device or resource busy # 136.35 [+0.01] [ 1.293438] [ T1] overlayfs: failed to set xattr on upper # 136.36 [+0.01] [ 1.293644] [ T1] overlayfs: ...falling back to redirect_dir=nofollow. # 136.36 [+0.01] [ 1.293880] [ T1] overlayfs: ...falling back to uuid=null. # 136.37 [+0.01] [ 1.296338] [ T1] overlayfs: failed to set xattr on upper # 136.38 [+0.01] [ 1.296519] [ T1] overlayfs: ...falling back to redirect_dir=nofollow. # 136.38 [+0.01] [ 1.296727] [ T1] overlayfs: ...falling back to uuid=null. # 136.39 [+0.01] [ 1.298714] [ T1] overlayfs: failed to set xattr on upper # 136.39 [+0.01] [ 1.298880] [ T1] overlayfs: ...falling back to redirect_dir=nofollow. # 136.40 [+0.01] [ 1.299091] [ T1] overlayfs: ...falling back to uuid=null. # 136.41 [+0.01] [ 1.301125] [ T1] overlayfs: failed to set xattr on upper # 136.41 [+0.01] [ 1.301296] [ T1] overlayfs: ...falling back to redirect_dir=nofollow. # 136.42 [+0.01] [ 1.301513] [ T1] overlayfs: ...falling back to uuid=null. # 136.43 [+0.01] [ 1.303933] [ T1] overlayfs: failed to set xattr on upper # 136.43 [+0.01] [ 1.304105] [ T1] overlayfs: ...falling back to redirect_dir=nofollow. # 136.44 [+0.01] [ 1.304307] [ T1] overlayfs: ...falling back to uuid=null. # 136.45 [+0.01] [ 1.306080] [ T1] overlayfs: failed to set xattr on upper # 136.45 [+0.01] [ 1.306249] [ T1] overlayfs: ...falling back to redirect_dir=nofollow. # 136.46 [+0.01] [ 1.306782] [ T1] overlayfs: ...falling back to uuid=null. # 136.47 [+0.01] [ 1.308777] [ T1] overlayfs: failed to set xattr on upper # 136.47 [+0.01] [ 1.308948] [ T1] overlayfs: ...falling back to redirect_dir=nofollow. # 136.48 [+0.01] [ 1.309174] [ T1] overlayfs: ...falling back to uuid=null. # 136.48 [+0.01] [ 1.311144] [ T1] overlayfs: failed to set xattr on upper # 136.49 [+0.01] [ 1.311313] [ T1] overlayfs: ...falling back to redirect_dir=nofollow. # 136.50 [+0.01] [ 1.312160] [ T1] overlayfs: ...falling back to uuid=null. # 136.50 [+0.01] [ 3.469106] [ T70] systemd-tmpfile (70) used greatest stack depth: 24672 bytes left # 136.51 [+0.01] [ 3.469727] [ T1] virtme-ng-init: Failed to read '/usr/lib/tmpfiles.d/audit.conf': Permission denied # 136.52 [+0.01] Failed to opendir() '/proc/self/fd/3': Permission denied # 136.53 [+0.01] [ 3.516315] [ T76] ip (76) used greatest stack depth: 23976 bytes left # 136.54 [+0.01] [ 3.527083] [ T72] virtme-ng-init: Starting systemd-udevd version 258.2-1.fc43 # 136.54 [+0.01] [ 3.527512] [ T72] virtme-ng-init: triggering udev coldplug # 136.55 [+0.01] [ 6.122289] [ T72] virtme-ng-init: waiting for udev to settle # 136.56 [+0.01] [ 7.186645] [ T72] virtme-ng-init: udev is done # 136.56 [+0.00] [ 7.189514] [ T1] virtme-ng-init: initialization done # 136.57 [+0.01] [ 11.237967] [ T303] gre: GRE over IPv4 demultiplexer driver # 136.57 [+0.01] [ 11.249148] [ T303] ip_gre: GRE over IPv4 tunneling driver # 136.58 [+0.01] [ 11.769447] [ T326] 8021q: 802.1Q VLAN Support v1.8 # 136.58 [+0.01] [ 12.701866] [ T364] GACT probability NOT on # 136.59 [+0.00] [ 639.428753] [T17462] br1: port 1(vx1) entered blocking state # 136.59 [+0.01] [ 639.429124] [T17462] br1: port 1(vx1) entered disabled state # 136.60 [+0.01] [ 639.429357] [T17462] vx1: entered allmulticast mode # 136.61 [+0.01] [ 639.431531] [T17462] vx1: entered promiscuous mode # 136.61 [+0.01] [ 639.432564] [T17462] br1: port 1(vx1) entered blocking state # 136.62 [+0.01] [ 639.432853] [T17462] br1: port 1(vx1) entered forwarding state # 136.62 [+0.01] [ 639.460261] [T17463] br1: port 2(veth1) entered blocking state # 136.63 [+0.01] [ 639.460485] [T17463] br1: port 2(veth1) entered disabled state # 136.64 [+0.01] [ 639.460676] [T17463] veth1: entered allmulticast mode # 136.64 [+0.01] [ 639.462652] [T17463] veth1: entered promiscuous mode # 136.65 [+0.01] [ 639.487486] [ T38] br1: port 2(veth1) entered blocking state # 136.65 [+0.01] [ 639.487745] [ T38] br1: port 2(veth1) entered forwarding state # 136.66 [+0.01] [ 639.508832] [T17465] br1: port 3(veth2) entered blocking state # 136.66 [+0.01] [ 639.509027] [T17465] br1: port 3(veth2) entered disabled state # 136.67 [+0.01] [ 639.509214] [T17465] veth2: entered allmulticast mode # 136.68 [+0.01] [ 639.510682] [T17465] veth2: entered promiscuous mode # 136.68 [+0.01] [ 639.537770] [ T35] br1: port 3(veth2) entered blocking state # 136.69 [+0.01] [ 639.537962] [ T35] br1: port 3(veth2) entered forwarding state # 136.69 [+0.01] [ 640.727336] [T17517] br2: port 1(w1) entered blocking state # 136.70 [+0.01] [ 640.728247] [T17517] br2: port 1(w1) entered disabled state # 136.71 [+0.01] [ 640.728510] [T17517] w1: entered allmulticast mode # 136.71 [+0.01] [ 640.730049] [T17517] w1: entered promiscuous mode # 136.72 [+0.01] [ 640.874290] [T17523] br2: port 2(vx2) entered blocking state # 136.72 [+0.01] [ 640.874474] [T17523] br2: port 2(vx2) entered disabled state # 136.73 [+0.01] [ 640.874846] [T17523] vx2: entered allmulticast mode # 136.73 [+0.01] [ 640.876371] [T17523] vx2: entered promiscuous mode # 136.74 [+0.01] [ 640.876963] [T17523] br2: port 2(vx2) entered blocking state # 136.74 [+0.01] [ 640.877112] [T17523] br2: port 2(vx2) entered forwarding state # 136.75 [+0.01] [ 641.042369] [ T38] br2: port 1(w1) entered blocking state # 136.76 [+0.01] [ 641.042679] [ T38] br2: port 1(w1) entered forwarding state # 136.76 [+0.01] [ 641.639987] [T17555] br2: port 1(w1) entered blocking state # 136.77 [+0.01] [ 641.640194] [T17555] br2: port 1(w1) entered disabled state # 136.77 [+0.01] [ 641.640364] [T17555] w1: entered allmulticast mode # 136.78 [+0.01] [ 641.641860] [T17555] w1: entered promiscuous mode # 136.78 [+0.01] [ 641.793596] [T17561] br2: port 2(vx2) entered blocking state # 136.79 [+0.01] [ 641.793771] [T17561] br2: port 2(vx2) entered disabled state # 136.80 [+0.01] [ 641.793939] [T17561] vx2: entered allmulticast mode # 136.80 [+0.01] [ 641.795425] [T17561] vx2: entered promiscuous mode # 136.81 [+0.01] [ 641.795974] [T17561] br2: port 2(vx2) entered blocking state # 136.81 [+0.01] [ 641.796342] [T17561] br2: port 2(vx2) entered forwarding state # 136.82 [+0.01] [ 641.970318] [ T38] br2: port 1(w1) entered blocking state # 136.82 [+0.01] [ 641.970527] [ T38] br2: port 1(w1) entered forwarding state # 136.83 [+0.01] [ 662.103711] [T17981] veth3: entered promiscuous mode # 136.84 [+0.01] [ 677.725370] [T18470] veth3: left promiscuous mode # 136.84 [+0.01] [ 695.180883] [ C2] vxlan: non-ECT from 192.0.2.34 with TOS=0x1 # 136.85 [+0.01] [ 695.281933] [ C2] vxlan: non-ECT from 192.0.2.34 with TOS=0x1 # 136.85 [+0.01] [ 695.382501] [ C2] vxlan: non-ECT from 192.0.2.34 with TOS=0x1 # 136.86 [+0.01] [ 695.483096] [ C2] vxlan: non-ECT from 192.0.2.34 with TOS=0x1 # 136.87 [+0.01] [ 695.583660] [ C2] vxlan: non-ECT from 192.0.2.34 with TOS=0x1 # 136.87 [+0.01] [ 695.684119] [ C2] vxlan: non-ECT from 192.0.2.34 with TOS=0x1 # 136.88 [+0.01] [ 695.784620] [ C2] vxlan: non-ECT from 192.0.2.34 with TOS=0x1 # 136.88 [+0.01] [ 695.885124] [ C2] vxlan: non-ECT from 192.0.2.34 with TOS=0x1 # 136.89 [+0.01] [ 695.985667] [ C2] vxlan: non-ECT from 192.0.2.34 with TOS=0x1 # 136.90 [+0.01] [ 696.086177] [ C2] vxlan: non-ECT from 192.0.2.34 with TOS=0x1 # 136.90 [+0.01] [ 733.511293] [ C2] net_ratelimit: 10 callbacks suppressed # 136.91 [+0.01] [ 733.511303] [ C2] vxlan: non-ECT from 192.0.2.34 with TOS=0x3 # 136.92 [+0.01] [ 733.612061] [ C2] vxlan: non-ECT from 192.0.2.34 with TOS=0x3 # 136.92 [+0.01] [ 733.712472] [ C2] vxlan: non-ECT from 192.0.2.34 with TOS=0x3 # 136.93 [+0.01] [ 733.812883] [ C2] vxlan: non-ECT from 192.0.2.34 with TOS=0x3 # 136.93 [+0.01] [ 733.913310] [ C2] vxlan: non-ECT from 192.0.2.34 with TOS=0x3 # 136.94 [+0.01] [ 734.013850] [ C2] vxlan: non-ECT from 192.0.2.34 with TOS=0x3 # 136.95 [+0.01] [ 734.114256] [ C2] vxlan: non-ECT from 192.0.2.34 with TOS=0x3 # 136.95 [+0.01] [ 734.214665] [ C2] vxlan: non-ECT from 192.0.2.34 with TOS=0x3 # 136.96 [+0.01] [ 734.315067] [ C2] vxlan: non-ECT from 192.0.2.34 with TOS=0x3 # 136.97 [+0.01] [ 734.415452] [ C2] vxlan: non-ECT from 192.0.2.34 with TOS=0x3 # 136.97 [+0.01] [ 734.767057] [T19586] vx1: left allmulticast mode # 136.98 [+0.00] [ 734.767274] [T19586] vx1: left promiscuous mode # 136.98 [+0.00] [ 734.768540] [T19586] br1: port 1(vx1) entered disabled state # 136.99 [+0.01] [ 739.827705] [T19588] br1: port 1(vx1) entered blocking state # 137.00 [+0.01] [ 739.827938] [T19588] br1: port 1(vx1) entered disabled state # 137.00 [+0.01] [ 739.828181] [T19588] vx1: entered allmulticast mode # 137.01 [+0.01] [ 739.829785] [T19588] vx1: entered promiscuous mode # 137.01 [+0.01] [ 739.830422] [T19588] br1: port 1(vx1) entered blocking state # 137.02 [+0.01] [ 739.830611] [T19588] br1: port 1(vx1) entered forwarding state # 137.02 [+0.01] [ 760.223133] [T19959] veth3: entered promiscuous mode # 137.03 [+0.01] [ 776.378788] [T20448] veth3: left promiscuous mode # 137.03 [+0.00] [ 776.764114] [T20470] vx1: left allmulticast mode # 137.04 [+0.00] [ 776.764307] [T20470] vx1: left promiscuous mode # 137.04 [+0.00] [ 776.764558] [T20470] br1: port 1(vx1) entered disabled state # 137.05 [+0.01] [ 781.817860] [T20472] br1: port 1(vx1) entered blocking state # 137.06 [+0.01] [ 781.818102] [T20472] br1: port 1(vx1) entered disabled state # 137.06 [+0.01] [ 781.818336] [T20472] vx1: entered allmulticast mode # 137.07 [+0.01] [ 781.819965] [T20472] vx1: entered promiscuous mode # 137.07 [+0.01] [ 781.821313] [T20472] br1: port 1(vx1) entered blocking state # 137.08 [+0.01] [ 781.821529] [T20472] br1: port 1(vx1) entered forwarding state # 137.08 [+0.01] [ 881.467869] [T21382] vx1: left allmulticast mode # 137.09 [+0.00] [ 881.468063] [T21382] vx1: left promiscuous mode # 137.09 [+0.00] [ 881.468304] [T21382] br1: port 1(vx1) entered disabled state # 137.10 [+0.01] [ 886.542559] [T21384] br1: port 1(vx1) entered blocking state # 137.11 [+0.01] [ 886.542822] [T21384] br1: port 1(vx1) entered disabled state # 137.11 [+0.01] [ 886.543071] [T21384] vx1: entered allmulticast mode # 137.12 [+0.01] [ 886.545331] [T21384] vx1: entered promiscuous mode # 137.12 [+0.01] [ 886.546247] [T21384] br1: port 1(vx1) entered blocking state # 137.13 [+0.01] [ 886.546498] [T21384] br1: port 1(vx1) entered forwarding state # 137.13 [+0.01] [ 892.915474] [ T68] vx2: left allmulticast mode # 137.14 [+0.00] [ 892.915830] [ T68] vx2: left promiscuous mode # 137.14 [+0.00] [ 892.916276] [ T68] br2: port 2(vx2) entered disabled state # 137.15 [+0.01] [ 892.921508] [ T68] w1: left allmulticast mode # 137.15 [+0.00] [ 892.921773] [ T68] w1: left promiscuous mode # 137.16 [+0.00] [ 892.922150] [ T68] br2: port 1(w1) entered disabled state # 137.16 [+0.01] [ 893.085869] [ T68] vx2: left allmulticast mode # 137.17 [+0.00] [ 893.086055] [ T68] vx2: left promiscuous mode # 137.17 [+0.00] [ 893.086282] [ T68] br2: port 2(vx2) entered disabled state # 137.18 [+0.01] [ 893.089176] [ T68] w1: left allmulticast mode # 137.19 [+0.00] [ 893.089484] [ T68] w1: left promiscuous mode # 137.19 [+0.00] [ 893.089819] [ T68] br2: port 1(w1) entered disabled state # 137.20 [+0.01] [ 893.743621] [T21429] vx1: left allmulticast mode # 137.20 [+0.00] [ 893.743812] [T21429] vx1: left promiscuous mode # 137.21 [+0.00] [ 893.744040] [T21429] br1: port 1(vx1) entered disabled state # 137.21 [+0.01] [ 893.838841] [T21432] br1: port 3(veth2) entered disabled state # 137.22 [+0.01] [ 893.866979] [T21433] veth2: left allmulticast mode # 137.22 [+0.01] [ 893.867152] [T21433] veth2: left promiscuous mode # 137.23 [+0.01] [ 893.867400] [T21433] br1: port 3(veth2) entered disabled state # 137.23 [+0.01] [ 893.894010] [T21434] br1: port 2(veth1) entered disabled state # 137.24 [+0.01] [ 893.921045] [T21435] veth1: left allmulticast mode # 137.25 [+0.01] [ 893.921269] [T21435] veth1: left promiscuous mode # 137.25 [+0.00] [ 893.921613] [T21435] br1: port 2(veth1) entered disabled state # 137.26 [+0.01] [ 911.054351] [T21768] Mirror/redirect action on # 137.26 [+0.00] [ 927.901079] [ C0] clocksource: Long readout interval, skipping watchdog check: cs_nsec: 1524686749 wd_nsec: 1524686799 # 137.27 [+0.01] [ 937.966000] [ C0] clocksource: Long readout interval, skipping watchdog check: cs_nsec: 4557582958 wd_nsec: 4557583198 # 137.28 [+0.01] [ 959.033728] [ C0] clocksource: Long readout interval, skipping watchdog check: cs_nsec: 19561318844 wd_nsec: 19561319854 # 137.30 [+0.01] [ 960.387918] [ C0] watchdog: BUG: soft lockup - CPU#0 stuck for 21s! [swapper/0:0] # 137.30 [+0.01] [ 960.387927] [ C0] Modules linked in: act_skbedit act_ct nf_flow_table nf_nat nf_conntrack nf_defrag_ipv6 nf_defrag_ipv4 cls_matchall act_mirred vxlan ip6_udp_tunnel udp_tunnel bridge stp llc act_gact cls_flower sch_ingress 8021q ip_gre gre vrf veth # 137.33 [+0.02] [ 960.387948] [ C0] irq event stamp: 42071745 # 137.33 [+0.00] [ 960.387950] [ C0] hardirqs last enabled at (42071744): [] asm_common_interrupt+0x26/0x40 # 137.34 [+0.01] [ 960.387958] [ C0] hardirqs last disabled at (42071745): [] sysvec_apic_timer_interrupt+0x12/0xf0 # 137.35 [+0.01] [ 960.387962] [ C0] softirqs last enabled at (41988006): [] handle_softirqs+0x381/0x620 # 137.36 [+0.01] [ 960.387966] [ C0] softirqs last disabled at (41988011): [] irq_exit_rcu+0xa2/0xf0 # 137.37 [+0.01] [ 960.387970] [ C0] CPU: 0 UID: 0 PID: 0 Comm: swapper/0 Not tainted 6.18.0-rc6-virtme #1 PREEMPT(full) # 137.38 [+0.01] [ 960.387973] [ C0] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 # 137.39 [+0.01] [ 960.387976] [ C0] RIP: 0010:console_emit_next_record+0x2a5/0x470 # 137.40 [+0.01] [ 960.387980] [ C0] Code: ca 7f 08 84 d2 0f 85 ad 01 00 00 88 45 00 e8 52 3f 00 00 48 83 7c 24 08 00 0f 85 e8 00 00 00 9c 58 f6 c4 02 0f 85 01 01 00 00 <48> b8 00 00 00 00 00 fc ff df c7 04 03 00 00 00 00 c7 44 18 08 00 # 137.42 [+0.02] [ 960.387982] [ C0] RSP: 0018:ffa00000000077a8 EFLAGS: 00000246 # 137.42 [+0.01] [ 960.387984] [ C0] RAX: 0000000000000096 RBX: 1ff4000000000ef9 RCX: ffffffffad2f3047 # 137.43 [+0.01] [ 960.387986] [ C0] RDX: ffffffffb001e680 RSI: ffffffffafd99176 RDI: ffffffffaf85d0c0 # 137.44 [+0.01] [ 960.387987] [ C0] RBP: ffa0000000007978 R08: 0000000000000000 R09: 0000000000000001 # 137.45 [+0.01] [ 960.387987] [ C0] R10: 0000000000000000 R11: 0000000000000000 R12: 0000000000000001 # 137.45 [+0.01] [ 960.387988] [ C0] R13: 0000000000000205 R14: ffffffffb0820ec0 R15: ffffffffb0820ec0 # 137.46 [+0.01] [ 960.387991] [ C0] FS: 0000000000000000(0000) GS:ff11000083ce3000(0000) knlGS:0000000000000000 # 137.47 [+0.01] [ 960.387994] [ C0] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 # 137.48 [+0.01] [ 960.387996] [ C0] CR2: 000056114d2f3000 CR3: 00000000118b2001 CR4: 0000000000771ef0 # 137.49 [+0.01] [ 960.387997] [ C0] PKRU: 55555554 # 137.49 [+0.00] [ 960.387998] [ C0] Call Trace: # 137.49 [+0.00] [ 960.388000] [ C0] # 137.50 [+0.00] [ 960.388005] [ C0] ? devkmsg_read+0x470/0x470 # 137.50 [+0.00] [ 960.388009] [ C0] ? trace_lock_acquire+0x88/0xd0 # 137.51 [+0.01] [ 960.388012] [ C0] ? lock_acquire+0x31/0x80 # 137.51 [+0.00] [ 960.388014] [ C0] ? console_flush_all+0x12b/0x650 # 137.52 [+0.01] [ 960.388018] [ C0] console_flush_all+0x400/0x650 # 137.52 [+0.01] [ 960.388022] [ C0] ? console_emit_next_record+0x470/0x470 # 137.53 [+0.01] [ 960.388028] [ C0] console_unlock+0xcb/0x1a0 # 137.53 [+0.00] [ 960.388030] [ C0] ? console_flush_all+0x650/0x650 # 137.54 [+0.01] [ 960.388031] [ C0] ? vprintk_emit+0x260/0x2e0 # 137.54 [+0.00] [ 960.388035] [ C0] ? __down_trylock_console_sem+0x5e/0xa0 # 137.55 [+0.01] [ 960.388037] [ C0] ? vprintk_emit+0x260/0x2e0 # 137.56 [+0.00] [ 960.388040] [ C0] vprintk_emit+0x2a0/0x2e0 # 137.56 [+0.00] [ 960.388043] [ C0] ? wake_up_klogd_work_func+0x90/0x90 # 137.57 [+0.01] [ 960.388046] [ C0] ? __lock_acquire+0x34c/0x740 # 137.57 [+0.01] [ 960.388050] [ C0] _printk+0xc7/0x100 # 137.57 [+0.00] [ 960.388054] [ C0] ? snapshot_read.cold+0x20/0x20 # 137.58 [+0.01] [ 960.388057] [ C0] ? lockdep_hardirqs_on_prepare.part.0+0x9a/0x170 # 137.59 [+0.01] [ 960.388060] [ C0] ? cs_watchdog_read+0x1ba/0x410 # 137.59 [+0.01] [ 960.388065] [ C0] ? clocksource_watchdog+0x360/0xb40 # 137.60 [+0.01] [ 960.388069] [ C0] clocksource_watchdog.cold+0x19/0xc7 # 137.60 [+0.01] [ 960.388074] [ C0] ? __clocksource_unstable+0x180/0x180 # 137.61 [+0.01] [ 960.388076] [ C0] ? lock_acquire.part.0+0xbc/0x260 # 137.61 [+0.01] [ 960.388078] [ C0] ? call_timer_fn+0x10c/0x210 # 137.62 [+0.01] [ 960.388081] [ C0] ? trace_lock_acquire+0x88/0xd0 # 137.63 [+0.01] [ 960.388083] [ C0] ? rcu_is_watching+0x15/0xd0 # 137.63 [+0.01] [ 960.388086] [ C0] ? lock_acquire+0x31/0x80 # 137.63 [+0.00] [ 960.388087] [ C0] ? call_timer_fn+0x10c/0x210 # 137.64 [+0.01] [ 960.388089] [ C0] ? __clocksource_unstable+0x180/0x180 # 137.65 [+0.01] [ 960.388092] [ C0] ? call_timer_fn+0x10c/0x210 # 137.65 [+0.01] [ 960.388093] [ C0] call_timer_fn+0x12e/0x210 # 137.66 [+0.00] [ 960.388094] [ C0] ? call_timer_fn+0x10c/0x210 # 137.66 [+0.01] [ 960.388096] [ C0] ? trace_timer_expire_exit+0x130/0x130 # 137.67 [+0.01] [ 960.388101] [ C0] ? _raw_spin_unlock_irq+0x28/0x50 # 137.67 [+0.01] [ 960.388106] [ C0] __run_timers+0x5dd/0x830 # 137.68 [+0.00] [ 960.388108] [ C0] ? __clocksource_unstable+0x180/0x180 # 137.68 [+0.01] [ 960.388113] [ C0] ? call_timer_fn+0x210/0x210 # 137.69 [+0.01] [ 960.388114] [ C0] ? __lock_acquire+0x34c/0x740 # 137.69 [+0.01] [ 960.388122] [ C0] ? __rwlock_init+0x150/0x150 # 137.70 [+0.01] [ 960.388124] [ C0] ? run_timer_softirq+0xeb/0x170 # 137.70 [+0.01] [ 960.388128] [ C0] run_timer_softirq+0xf3/0x170 # 137.71 [+0.01] [ 960.388130] [ C0] ? __run_timers+0x830/0x830 # 137.71 [+0.00] [ 960.388133] [ C0] ? trace_softirq_entry+0xcc/0x130 # 137.72 [+0.01] [ 960.388134] [ C0] handle_softirqs+0x229/0x620 # 137.72 [+0.01] [ 960.388139] [ C0] ? trace_softirq_exit+0x130/0x130 # 137.73 [+0.01] [ 960.388140] [ C0] ? do_raw_spin_unlock+0x59/0x250 # 137.73 [+0.01] [ 960.388144] [ C0] irq_exit_rcu+0xa2/0xf0 # 137.74 [+0.00] [ 960.388145] [ C0] common_interrupt+0xc9/0x100 # 137.74 [+0.01] [ 960.388148] [ C0] # 137.75 [+0.00] [ 960.388149] [ C0] # 137.75 [+0.00] [ 960.388149] [ C0] ? lockdep_hardirqs_on+0x84/0x130 # 137.76 [+0.01] [ 960.388152] [ C0] asm_common_interrupt+0x26/0x40 # 137.76 [+0.01] [ 960.388154] [ C0] RIP: 0010:default_idle_call+0x98/0xa0 # 137.77 [+0.01] [ 960.388157] [ C0] Code: 3c 24 e8 3b e1 ff ff e8 c6 eb ff ff 90 e8 b0 f2 ff ff bf ff ff ff ff 89 c6 e8 d4 90 e0 fd e8 ff 6b e0 fd e8 6a 59 0c fe fb 90 90 0f 0b 90 eb af 90 65 48 8b 05 18 b5 0d 02 48 8b 00 a8 10 75 # 137.79 [+0.02] [ 960.388158] [ C0] RSP: 0018:ffffffffb0007e10 EFLAGS: 00000282 # 137.79 [+0.01] [ 960.388159] [ C0] RAX: 000000000280afa9 RBX: ffffffffb001e680 RCX: ffffffffaf461af6 # 137.80 [+0.01] [ 960.388160] [ C0] RDX: ffffffffb001e680 RSI: ffffffffafd99176 RDI: ffffffffaf85d0c0 # 137.81 [+0.01] [ 960.388161] [ C0] RBP: 0000000000000000 R08: 0000000000000000 R09: 0000000000000001 # 137.82 [+0.01] [ 960.388161] [ C0] R10: 0000000000000000 R11: 0000000000000000 R12: 1ffffffff6000fc3 # 137.83 [+0.01] [ 960.388162] [ C0] R13: 0000000000000000 R14: dffffc0000000000 R15: 0000000000014770 # 137.83 [+0.01] [ 960.388166] [ C0] ? default_idle_call+0x96/0xa0 # 137.84 [+0.01] [ 960.388171] [ C0] cpuidle_idle_call+0x21b/0x330 # 137.84 [+0.01] [ 960.388175] [ C0] ? arch_cpu_idle_exit+0x40/0x40 # 137.85 [+0.01] [ 960.388177] [ C0] ? mark_tsc_async_resets+0x30/0x30 # 137.86 [+0.01] [ 960.388179] [ C0] ? rcu_is_watching+0x15/0xd0 # 137.86 [+0.01] [ 960.388181] [ C0] ? trace_irq_enable.constprop.0+0xce/0x130 # 137.87 [+0.01] [ 960.388186] [ C0] do_idle+0xd9/0x130 # 137.87 [+0.00] [ 960.388189] [ C0] cpu_startup_entry+0x53/0x70 # 137.88 [+0.00] [ 960.388192] [ C0] rest_init+0x1f7/0x200 # 137.88 [+0.00] [ 960.388195] [ C0] start_kernel+0x3b6/0x3c0 # 137.89 [+0.00] [ 960.388200] [ C0] x86_64_start_reservations+0x24/0x30 # 137.89 [+0.01] [ 960.388203] [ C0] x86_64_start_kernel+0x122/0x130 # 137.90 [+0.01] [ 960.388206] [ C0] common_startup_64+0x13e/0x148 # 137.90 [+0.01] [ 960.388216] [ C0] # 137.90 [+0.00] [ 984.084401] [ C0] rcu: INFO: rcu_preempt self-detected stall on CPU # 137.91 [+0.01] [ 984.084437] [ C0] rcu: 0-....: (1 GPs behind) idle=e4bc/1/0x4000000000000004 softirq=581657/581657 fqs=6299 # 137.92 [+0.01] [ 984.084590] [ C0] rcu: (t=26000 jiffies g=624709 q=1351 ncpus=4) # 137.93 [+0.01] [ 984.084597] [ C0] CPU: 0 UID: 0 PID: 0 Comm: swapper/0 Tainted: G L 6.18.0-rc6-virtme #1 PREEMPT(full) # 137.94 [+0.01] [ 984.084601] [ C0] Tainted: [L]=SOFTLOCKUP # 137.94 [+0.00] [ 984.084602] [ C0] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 # 137.95 [+0.01] [ 984.084604] [ C0] RIP: 0010:console_flush_all+0x373/0x650 # 137.96 [+0.01] [ 984.084612] [ C0] Code: 89 c6 0f 85 1b fe ff ff 41 83 ff 01 0f 87 7e 01 00 00 be 02 00 00 00 48 c7 c7 e0 16 36 b0 e8 e4 91 05 00 48 c7 c7 f0 16 36 b0 <48> 8d 35 00 00 00 00 e8 51 f6 fd ff 44 89 fe 48 c7 c7 e0 16 36 b0 # 137.98 [+0.02] [ 984.084614] [ C0] RSP: 0018:ffa0000000007880 EFLAGS: 00000286 # 137.98 [+0.01] [ 984.084618] [ C0] RAX: 0000000000000003 RBX: dffffc0000000000 RCX: 0000000000000002 # 137.99 [+0.01] [ 984.084620] [ C0] RDX: 0000000000000002 RSI: 1ffffffff606c2dd RDI: ffffffffb03616f0 # 138.00 [+0.01] [ 984.084620] [ C0] RBP: 0000000000000001 R08: ff11000035224100 R09: fffffbfff603e844 # 138.01 [+0.01] [ 984.084621] [ C0] R10: 0000000000000003 R11: 0000000000000000 R12: ffa0000000007998 # 138.02 [+0.01] [ 984.084622] [ C0] R13: ffa0000000007978 R14: ffffffffb0820ec0 R15: 0000000000000001 # 138.03 [+0.01] [ 984.084625] [ C0] FS: 0000000000000000(0000) GS:ff11000083ce3000(0000) knlGS:0000000000000000 # 138.03 [+0.01] [ 984.084628] [ C0] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 # 138.04 [+0.01] [ 984.084629] [ C0] CR2: 000056114d2f3000 CR3: 00000000118b2001 CR4: 0000000000771ef0 # 138.05 [+0.01] [ 984.084630] [ C0] PKRU: 55555554 # 138.05 [+0.00] [ 984.084631] [ C0] Call Trace: # 138.06 [+0.00] [ 984.084632] [ C0] # 138.06 [+0.00] [ 984.084635] [ C0] ? console_emit_next_record+0x470/0x470 # 138.07 [+0.01] [ 984.084642] [ C0] console_unlock+0xcb/0x1a0 # 138.07 [+0.00] [ 984.084645] [ C0] ? console_flush_all+0x650/0x650 # 138.08 [+0.01] [ 984.084646] [ C0] ? vprintk_emit+0x260/0x2e0 # 138.08 [+0.00] [ 984.084650] [ C0] ? __down_trylock_console_sem+0x5e/0xa0 # 138.09 [+0.01] [ 984.084653] [ C0] ? vprintk_emit+0x260/0x2e0 # 138.09 [+0.00] [ 984.084656] [ C0] vprintk_emit+0x2a0/0x2e0 # 138.10 [+0.00] [ 984.084659] [ C0] ? wake_up_klogd_work_func+0x90/0x90 # 138.10 [+0.01] [ 984.084662] [ C0] ? __lock_acquire+0x34c/0x740 # 138.11 [+0.01] [ 984.084668] [ C0] _printk+0xc7/0x100 # 138.11 [+0.00] [ 984.084671] [ C0] ? snapshot_read.cold+0x20/0x20 # 138.12 [+0.01] [ 984.084674] [ C0] ? lockdep_hardirqs_on_prepare.part.0+0x9a/0x170 # 138.12 [+0.01] [ 984.084677] [ C0] ? cs_watchdog_read+0x1ba/0x410 # 138.13 [+0.01] [ 984.084685] [ C0] ? clocksource_watchdog+0x360/0xb40 # 138.13 [+0.01] [ 984.084689] [ C0] clocksource_watchdog.cold+0x19/0xc7 # 138.14 [+0.01] [ 984.084694] [ C0] ? __clocksource_unstable+0x180/0x180 # 138.15 [+0.01] [ 984.084696] [ C0] ? lock_acquire.part.0+0xbc/0x260 # 138.15 [+0.01] [ 984.084698] [ C0] ? call_timer_fn+0x10c/0x210 # 138.16 [+0.01] [ 984.084701] [ C0] ? trace_lock_acquire+0x88/0xd0 # 138.16 [+0.01] [ 984.084703] [ C0] ? rcu_is_watching+0x15/0xd0 # 138.17 [+0.01] [ 984.084705] [ C0] ? lock_acquire+0x31/0x80 # 138.17 [+0.00] [ 984.084707] [ C0] ? call_timer_fn+0x10c/0x210 # 138.18 [+0.01] [ 984.084709] [ C0] ? __clocksource_unstable+0x180/0x180 # 138.18 [+0.01] [ 984.084712] [ C0] ? call_timer_fn+0x10c/0x210 # 138.19 [+0.00] [ 984.084713] [ C0] call_timer_fn+0x12e/0x210 # 138.19 [+0.00] [ 984.084714] [ C0] ? call_timer_fn+0x10c/0x210 # 138.20 [+0.00] [ 984.084716] [ C0] ? trace_timer_expire_exit+0x130/0x130 # 138.20 [+0.01] [ 984.084721] [ C0] ? _raw_spin_unlock_irq+0x28/0x50 # 138.21 [+0.01] [ 984.084728] [ C0] __run_timers+0x5dd/0x830 # 138.21 [+0.00] [ 984.084729] [ C0] ? __clocksource_unstable+0x180/0x180 # 138.22 [+0.01] [ 984.084734] [ C0] ? call_timer_fn+0x210/0x210 # 138.22 [+0.00] [ 984.084736] [ C0] ? __lock_acquire+0x34c/0x740 # 138.23 [+0.01] [ 984.084743] [ C0] ? __rwlock_init+0x150/0x150 # 138.23 [+0.01] [ 984.084745] [ C0] ? run_timer_softirq+0xeb/0x170 # 138.24 [+0.01] [ 984.084749] [ C0] run_timer_softirq+0xf3/0x170 # 138.25 [+0.01] [ 984.084752] [ C0] ? __run_timers+0x830/0x830 # 138.25 [+0.00] [ 984.084754] [ C0] ? trace_softirq_entry+0xcc/0x130 # 138.26 [+0.01] [ 984.084757] [ C0] handle_softirqs+0x229/0x620 # 138.26 [+0.01] [ 984.084762] [ C0] ? trace_softirq_exit+0x130/0x130 # 138.27 [+0.01] [ 984.084763] [ C0] ? do_raw_spin_unlock+0x59/0x250 # 138.27 [+0.01] [ 984.084767] [ C0] irq_exit_rcu+0xa2/0xf0 # 138.28 [+0.00] [ 984.084768] [ C0] common_interrupt+0xc9/0x100 # 138.28 [+0.01] [ 984.084771] [ C0] # 138.28 [+0.00] [ 984.084772] [ C0] # 138.29 [+0.00] [ 984.084772] [ C0] ? lockdep_hardirqs_on+0x84/0x130 # 138.29 [+0.01] [ 984.084775] [ C0] asm_common_interrupt+0x26/0x40 # 138.30 [+0.01] [ 984.084778] [ C0] RIP: 0010:default_idle_call+0x98/0xa0 # 138.30 [+0.01] [ 984.084780] [ C0] Code: 3c 24 e8 3b e1 ff ff e8 c6 eb ff ff 90 e8 b0 f2 ff ff bf ff ff ff ff 89 c6 e8 d4 90 e0 fd e8 ff 6b e0 fd e8 6a 59 0c fe fb 90 90 0f 0b 90 eb af 90 65 48 8b 05 18 b5 0d 02 48 8b 00 a8 10 75 # 138.32 [+0.02] [ 984.084781] [ C0] RSP: 0018:ffffffffb0007e10 EFLAGS: 00000282 # 138.33 [+0.01] [ 984.084782] [ C0] RAX: 000000000280afa9 RBX: ffffffffb001e680 RCX: ffffffffaf461af6 # 138.34 [+0.01] [ 984.084783] [ C0] RDX: ffffffffb001e680 RSI: ffffffffafd99176 RDI: ffffffffaf85d0c0 # 138.35 [+0.01] [ 984.084784] [ C0] RBP: 0000000000000000 R08: 0000000000000000 R09: 0000000000000001 # 138.35 [+0.01] [ 984.084785] [ C0] R10: 0000000000000000 R11: 0000000000000000 R12: 1ffffffff6000fc3 # 138.36 [+0.01] [ 984.084785] [ C0] R13: 0000000000000000 R14: dffffc0000000000 R15: 0000000000014770 # 138.37 [+0.01] [ 984.084789] [ C0] ? default_idle_call+0x96/0xa0 # 138.38 [+0.01] [ 984.084794] [ C0] cpuidle_idle_call+0x21b/0x330 # 138.38 [+0.01] [ 984.084797] [ C0] ? arch_cpu_idle_exit+0x40/0x40 # 138.39 [+0.01] [ 984.084800] [ C0] ? mark_tsc_async_resets+0x30/0x30 # 138.39 [+0.01] [ 984.084802] [ C0] ? rcu_is_watching+0x15/0xd0 # 138.40 [+0.01] [ 984.084804] [ C0] ? trace_irq_enable.constprop.0+0xce/0x130 # 138.40 [+0.01] [ 984.084808] [ C0] do_idle+0xd9/0x130 # 138.41 [+0.00] [ 984.084812] [ C0] cpu_startup_entry+0x53/0x70 # 138.41 [+0.01] [ 984.084814] [ C0] rest_init+0x1f7/0x200 # 138.42 [+0.00] [ 984.084817] [ C0] start_kernel+0x3b6/0x3c0 # 138.42 [+0.00] [ 984.084822] [ C0] x86_64_start_reservations+0x24/0x30 # 138.43 [+0.01] [ 984.084824] [ C0] x86_64_start_kernel+0x122/0x130 # 138.43 [+0.01] [ 984.084827] [ C0] common_startup_64+0x13e/0x148 # 138.44 [+0.01] [ 984.084836] [ C0] # 138.44 [+0.00] [ 1008.387917] [ C0] watchdog: BUG: soft lockup - CPU#0 stuck for 66s! [swapper/0:0] # 138.45 [+0.01] [ 1008.387924] [ C0] Modules linked in: act_skbedit act_ct nf_flow_table nf_nat nf_conntrack nf_defrag_ipv6 nf_defrag_ipv4 cls_matchall act_mirred vxlan ip6_udp_tunnel udp_tunnel bridge stp llc act_gact cls_flower sch_ingress 8021q ip_gre gre vrf veth # 138.47 [+0.02] [ 1008.387941] [ C0] irq event stamp: 43830125 # 138.48 [+0.00] [ 1008.387942] [ C0] hardirqs last enabled at (43830124): [] asm_common_interrupt+0x26/0x40 # 138.49 [+0.01] [ 1008.387951] [ C0] hardirqs last disabled at (43830125): [] sysvec_apic_timer_interrupt+0x12/0xf0 # 138.50 [+0.01] [ 1008.387955] [ C0] softirqs last enabled at (41988006): [] handle_softirqs+0x381/0x620 # 138.51 [+0.01] [ 1008.387959] [ C0] softirqs last disabled at (41988011): [] irq_exit_rcu+0xa2/0xf0 # 138.52 [+0.01] [ 1008.387964] [ C0] CPU: 0 UID: 0 PID: 0 Comm: swapper/0 Tainted: G L 6.18.0-rc6-virtme #1 PREEMPT(full) # 138.53 [+0.01] [ 1008.387967] [ C0] Tainted: [L]=SOFTLOCKUP # 138.53 [+0.00] [ 1008.387968] [ C0] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 # 138.54 [+0.01] [ 1008.387970] [ C0] RIP: 0010:console_emit_next_record+0x2a5/0x470 # 138.55 [+0.01] [ 1008.387975] [ C0] Code: ca 7f 08 84 d2 0f 85 ad 01 00 00 88 45 00 e8 52 3f 00 00 48 83 7c 24 08 00 0f 85 e8 00 00 00 9c 58 f6 c4 02 0f 85 01 01 00 00 <48> b8 00 00 00 00 00 fc ff df c7 04 03 00 00 00 00 c7 44 18 08 00 # 138.57 [+0.02] [ 1008.387977] [ C0] RSP: 0018:ffa00000000077a8 EFLAGS: 00000246 # 138.57 [+0.01] [ 1008.387979] [ C0] RAX: 0000000000000096 RBX: 1ff4000000000ef9 RCX: ffffffffad2f3047 # 138.58 [+0.01] [ 1008.387981] [ C0] RDX: ffffffffb001e680 RSI: ffffffffafd99176 RDI: ffffffffaf85d0c0 # 138.59 [+0.01] [ 1008.387981] [ C0] RBP: ffa0000000007978 R08: 0000000000000000 R09: 0000000000000001 # 138.60 [+0.01] [ 1008.387982] [ C0] R10: 0000000000000000 R11: 0000000000000000 R12: 0000000000000001 # 138.61 [+0.01] [ 1008.387983] [ C0] R13: 000000000000023c R14: ffffffffb0820ec0 R15: ffffffffb0820ec0 # 138.62 [+0.01] [ 1008.387986] [ C0] FS: 0000000000000000(0000) GS:ff11000083ce3000(0000) knlGS:0000000000000000 # 138.62 [+0.01] [ 1008.387989] [ C0] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 # 138.63 [+0.01] [ 1008.387990] [ C0] CR2: 000056114d2f3000 CR3: 00000000118b2001 CR4: 0000000000771ef0 # 138.64 [+0.01] [ 1008.387991] [ C0] PKRU: 55555554 # 138.64 [+0.00] [ 1008.387992] [ C0] Call Trace: # 138.65 [+0.00] [ 1008.387993] [ C0] # 138.65 [+0.00] [ 1008.387997] [ C0] ? devkmsg_read+0x470/0x470 # 138.65 [+0.00] [ 1008.388001] [ C0] ? trace_lock_acquire+0x88/0xd0 # 138.66 [+0.01] [ 1008.388005] [ C0] ? lock_acquire+0x31/0x80 # 138.66 [+0.00] [ 1008.388007] [ C0] ? console_flush_all+0x12b/0x650 # 138.67 [+0.01] [ 1008.388010] [ C0] console_flush_all+0x400/0x650 # 138.68 [+0.01] [ 1008.388015] [ C0] ? console_emit_next_record+0x470/0x470 # 138.68 [+0.01] [ 1008.388020] [ C0] console_unlock+0xcb/0x1a0 # 138.69 [+0.00] [ 1008.388023] [ C0] ? console_flush_all+0x650/0x650 # 138.69 [+0.01] [ 1008.388024] [ C0] ? vprintk_emit+0x260/0x2e0 # 138.70 [+0.00] [ 1008.388027] [ C0] ? __down_trylock_console_sem+0x5e/0xa0 # 138.70 [+0.01] [ 1008.388030] [ C0] ? vprintk_emit+0x260/0x2e0 # 138.71 [+0.00] [ 1008.388032] [ C0] vprintk_emit+0x2a0/0x2e0 # 138.71 [+0.00] [ 1008.388036] [ C0] ? wake_up_klogd_work_func+0x90/0x90 # 138.72 [+0.01] [ 1008.388039] [ C0] ? __lock_acquire+0x34c/0x740 # 138.72 [+0.01] [ 1008.388043] [ C0] _printk+0xc7/0x100 # 138.73 [+0.00] [ 1008.388046] [ C0] ? snapshot_read.cold+0x20/0x20 # 138.73 [+0.01] [ 1008.388049] [ C0] ? lockdep_hardirqs_on_prepare.part.0+0x9a/0x170 # 138.74 [+0.01] [ 1008.388052] [ C0] ? cs_watchdog_read+0x1ba/0x410 # 138.74 [+0.01] [ 1008.388058] [ C0] ? clocksource_watchdog+0x360/0xb40 # 138.75 [+0.01] [ 1008.388061] [ C0] clocksource_watchdog.cold+0x19/0xc7 # 138.76 [+0.01] [ 1008.388066] [ C0] ? __clocksource_unstable+0x180/0x180 # 138.76 [+0.01] [ 1008.388068] [ C0] ? lock_acquire.part.0+0xbc/0x260 # 138.77 [+0.01] [ 1008.388070] [ C0] ? call_timer_fn+0x10c/0x210 # 138.77 [+0.00] [ 1008.388074] [ C0] ? trace_lock_acquire+0x88/0xd0 # 138.78 [+0.01] [ 1008.388075] [ C0] ? rcu_is_watching+0x15/0xd0 # 138.78 [+0.01] [ 1008.388078] [ C0] ? lock_acquire+0x31/0x80 # 138.79 [+0.00] [ 1008.388079] [ C0] ? call_timer_fn+0x10c/0x210 # 138.79 [+0.00] [ 1008.388081] [ C0] ? __clocksource_unstable+0x180/0x180 # 138.80 [+0.01] [ 1008.388084] [ C0] ? call_timer_fn+0x10c/0x210 # 138.80 [+0.00] [ 1008.388085] [ C0] call_timer_fn+0x12e/0x210 # 138.81 [+0.00] [ 1008.388086] [ C0] ? call_timer_fn+0x10c/0x210 # 138.81 [+0.00] [ 1008.388088] [ C0] ? trace_timer_expire_exit+0x130/0x130 # 138.82 [+0.01] [ 1008.388093] [ C0] ? _raw_spin_unlock_irq+0x28/0x50 # 138.82 [+0.01] [ 1008.388098] [ C0] __run_timers+0x5dd/0x830 # 138.83 [+0.00] [ 1008.388100] [ C0] ? __clocksource_unstable+0x180/0x180 # 138.83 [+0.01] [ 1008.388105] [ C0] ? call_timer_fn+0x210/0x210 # 138.84 [+0.00] [ 1008.388106] [ C0] ? __lock_acquire+0x34c/0x740 # 138.84 [+0.01] [ 1008.388113] [ C0] ? __rwlock_init+0x150/0x150 # 138.85 [+0.00] [ 1008.388115] [ C0] ? run_timer_softirq+0xeb/0x170 # 138.85 [+0.01] [ 1008.388119] [ C0] run_timer_softirq+0xf3/0x170 # 138.86 [+0.01] [ 1008.388121] [ C0] ? __run_timers+0x830/0x830 # 138.86 [+0.00] [ 1008.388124] [ C0] ? trace_softirq_entry+0xcc/0x130 # 138.87 [+0.01] [ 1008.388126] [ C0] handle_softirqs+0x229/0x620 # 138.87 [+0.00] [ 1008.388130] [ C0] ? trace_softirq_exit+0x130/0x130 # 138.88 [+0.01] [ 1008.388131] [ C0] ? do_raw_spin_unlock+0x59/0x250 # 138.89 [+0.01] [ 1008.388135] [ C0] irq_exit_rcu+0xa2/0xf0 # 138.89 [+0.00] [ 1008.388137] [ C0] common_interrupt+0xc9/0x100 # 138.90 [+0.00] [ 1008.388140] [ C0] # 138.90 [+0.00] [ 1008.388140] [ C0] # 138.90 [+0.00] [ 1008.388141] [ C0] ? lockdep_hardirqs_on+0x84/0x130 # 138.91 [+0.01] [ 1008.388143] [ C0] asm_common_interrupt+0x26/0x40 # 138.91 [+0.01] [ 1008.388145] [ C0] RIP: 0010:default_idle_call+0x98/0xa0 # 138.92 [+0.01] [ 1008.388148] [ C0] Code: 3c 24 e8 3b e1 ff ff e8 c6 eb ff ff 90 e8 b0 f2 ff ff bf ff ff ff ff 89 c6 e8 d4 90 e0 fd e8 ff 6b e0 fd e8 6a 59 0c fe fb 90 90 0f 0b 90 eb af 90 65 48 8b 05 18 b5 0d 02 48 8b 00 a8 10 75 # 138.94 [+0.02] [ 1008.388149] [ C0] RSP: 0018:ffffffffb0007e10 EFLAGS: 00000282 # 138.94 [+0.01] [ 1008.388150] [ C0] RAX: 000000000280afa9 RBX: ffffffffb001e680 RCX: ffffffffaf461af6 # 138.95 [+0.01] [ 1008.388151] [ C0] RDX: ffffffffb001e680 RSI: ffffffffafd99176 RDI: ffffffffaf85d0c0 # 138.96 [+0.01] [ 1008.388152] [ C0] RBP: 0000000000000000 R08: 0000000000000000 R09: 0000000000000001 # 138.97 [+0.01] [ 1008.388153] [ C0] R10: 0000000000000000 R11: 0000000000000000 R12: 1ffffffff6000fc3 # 138.98 [+0.01] [ 1008.388153] [ C0] R13: 0000000000000000 R14: dffffc0000000000 R15: 0000000000014770 # 138.99 [+0.01] [ 1008.388157] [ C0] ? default_idle_call+0x96/0xa0 # 138.99 [+0.01] [ 1008.388162] [ C0] cpuidle_idle_call+0x21b/0x330 # 139.00 [+0.01] [ 1008.388166] [ C0] ? arch_cpu_idle_exit+0x40/0x40 # 139.00 [+0.01] [ 1008.388169] [ C0] ? mark_tsc_async_resets+0x30/0x30 # 139.01 [+0.01] [ 1008.388171] [ C0] ? rcu_is_watching+0x15/0xd0 # 139.01 [+0.01] [ 1008.388173] [ C0] ? trace_irq_enable.constprop.0+0xce/0x130 # 139.02 [+0.01] [ 1008.388177] [ C0] do_idle+0xd9/0x130 # 139.02 [+0.00] [ 1008.388181] [ C0] cpu_startup_entry+0x53/0x70 # 139.03 [+0.01] [ 1008.388183] [ C0] rest_init+0x1f7/0x200 # 139.03 [+0.00] [ 1008.388186] [ C0] start_kernel+0x3b6/0x3c0 # 139.04 [+0.00] [ 1008.388191] [ C0] x86_64_start_reservations+0x24/0x30 # 139.04 [+0.01] [ 1008.388194] [ C0] x86_64_start_kernel+0x122/0x130 # 139.05 [+0.01] [ 1008.388196] [ C0] common_startup_64+0x13e/0x148 # 139.05 [+0.01] [ 1008.388206] [ C0] # 139.06 [+0.00] [ 1031.760414] [ C1] clocksource: Long readout interval, skipping watchdog check: cs_nsec: 72726681585 wd_nsec: 72726686040 # 139.07 [+0.01] [ 1033.166889] [T22195] tc (22195) used greatest stack depth: 23912 bytes left ok 1 selftests: net/forwarding: tc_actions.sh make: Leaving directory '/srv/vmksft/testing/wt-4/tools/testing/selftests' xx__-> echo $? 0 xx__->