early console in setup code Probing EDD (edd=off to disable)... ok early console in extract_kernel input_data: 0x02604079 input_len: 0x005a7969 output: 0x01000000 output_len: 0x012e661c kernel_total_size: 0x01bc2000 needed_size: 0x01bc2000 Decompressing Linux... Parsing ELF... done. Booting the kernel (entry_offset: 0x007d0680). Linux version 6.14.0-rc1-00087-g84e0a11498cf (kbuild@1d3b3324c302) (gcc-12 (Debian 12.2.0-14) 12.2.0, GNU ld (GNU Binutils for Debian) 2.40) #1 PREEMPT_DYNAMIC Fri Mar 28 10:26:00 CST 2025 BIOS-provided physical RAM map: BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved BIOS-e820: [mem 0x0000000000100000-0x00000000bffdffff] usable BIOS-e820: [mem 0x00000000bffe0000-0x00000000bfffffff] reserved BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved BIOS-e820: [mem 0x0000000100000000-0x000000013fffffff] usable e820: remove [mem 0x100000000-0xfffffffffffffffe] usable printk: debug: ignoring loglevel setting. printk: legacy bootconsole [earlyser0] enabled Notice: NX (Execute Disable) protection cannot be enabled: non-PAE kernel! APIC: Static calls initialized user-defined physical RAM map: user: [mem 0x0000000000000000-0x000000000009fbff] usable user: [mem 0x000000000009fc00-0x000000000009ffff] reserved user: [mem 0x00000000000f0000-0x00000000000fffff] reserved user: [mem 0x0000000000100000-0x00000000bffdffff] usable user: [mem 0x00000000bffe0000-0x00000000bfffffff] reserved user: [mem 0x00000000feffc000-0x00000000feffffff] reserved user: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved Hypervisor detected: KVM kvm-clock: Using msrs 4b564d01 and 4b564d00 kvm-clock: using sched offset of 1107736597 cycles clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns tsc: Detected 2394.374 MHz processor e820: update [mem 0x00000000-0x00000fff] usable ==> reserved e820: remove [mem 0x000a0000-0x000fffff] usable last_pfn = 0xbffe0 max_arch_pfn = 0x100000 x86/PAT: PAT support disabled because CONFIG_X86_PAT is disabled in the kernel. x86/PAT: Configuration [0-7]: WB WT UC- UC WB WT UC- UC initial memory mapped: [mem 0x00000000-0x02ffffff] RAMDISK: [mem 0x779ca000-0x7fffffff] Allocated new RAMDISK: [mem 0x265c8000-0x2ebfd421] Move RAMDISK from [mem 0x779ca000-0x7ffff421] to [mem 0x265c8000-0x2ebfd421] ACPI: Early table checksum verification disabled ACPI: RSDP 0x00000000000F59B0 000014 (v00 BOCHS ) ACPI: RSDT 0x00000000BFFE1B56 000034 (v01 BOCHS BXPC 00000001 BXPC 00000001) ACPI: FACP 0x00000000BFFE1A02 000074 (v01 BOCHS BXPC 00000001 BXPC 00000001) ACPI: DSDT 0x00000000BFFE0040 0019C2 (v01 BOCHS BXPC 00000001 BXPC 00000001) ACPI: FACS 0x00000000BFFE0000 000040 ACPI: APIC 0x00000000BFFE1A76 000080 (v01 BOCHS BXPC 00000001 BXPC 00000001) ACPI: HPET 0x00000000BFFE1AF6 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) ACPI: WAET 0x00000000BFFE1B2E 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) ACPI: Reserving FACP table memory at [mem 0xbffe1a02-0xbffe1a75] ACPI: Reserving DSDT table memory at [mem 0xbffe0040-0xbffe1a01] ACPI: Reserving FACS table memory at [mem 0xbffe0000-0xbffe003f] ACPI: Reserving APIC table memory at [mem 0xbffe1a76-0xbffe1af5] ACPI: Reserving HPET table memory at [mem 0xbffe1af6-0xbffe1b2d] ACPI: Reserving WAET table memory at [mem 0xbffe1b2e-0xbffe1b55] Mapped APIC to ffffb000 ( fee00000) 2323MB HIGHMEM available. 747MB LOWMEM available. mapped low ram: 0 - 2ebfe000 low ram: 0 - 2ebfe000 Zone ranges: DMA [mem 0x0000000000001000-0x0000000000ffffff] Normal [mem 0x0000000001000000-0x000000002ebfdfff] HighMem [mem 0x000000002ebfe000-0x00000000bffdffff] Movable zone start for each node Early memory node ranges node 0: [mem 0x0000000000001000-0x000000000009efff] node 0: [mem 0x0000000000100000-0x00000000bffdffff] Initmem setup node 0 [mem 0x0000000000001000-0x00000000bffdffff] On node 0, zone DMA: 1 pages in unavailable ranges On node 0, zone DMA: 97 pages in unavailable ranges ACPI: PM-Timer IO Port: 0x608 CPU topo: CPU limit of 1 reached. Ignoring further CPUs ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) Int: type 0, pol 0, trig 0, bus 00, IRQ 00, APIC ID 0, APIC INT 02 ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) Int: type 0, pol 1, trig 3, bus 00, IRQ 05, APIC ID 0, APIC INT 05 ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) Int: type 0, pol 1, trig 3, bus 00, IRQ 09, APIC ID 0, APIC INT 09 ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) Int: type 0, pol 1, trig 3, bus 00, IRQ 0a, APIC ID 0, APIC INT 0a ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) Int: type 0, pol 1, trig 3, bus 00, IRQ 0b, APIC ID 0, APIC INT 0b Int: type 0, pol 0, trig 0, bus 00, IRQ 01, APIC ID 0, APIC INT 01 Int: type 0, pol 0, trig 0, bus 00, IRQ 03, APIC ID 0, APIC INT 03 Int: type 0, pol 0, trig 0, bus 00, IRQ 04, APIC ID 0, APIC INT 04 Int: type 0, pol 0, trig 0, bus 00, IRQ 06, APIC ID 0, APIC INT 06 Int: type 0, pol 0, trig 0, bus 00, IRQ 07, APIC ID 0, APIC INT 07 Int: type 0, pol 0, trig 0, bus 00, IRQ 08, APIC ID 0, APIC INT 08 Int: type 0, pol 0, trig 0, bus 00, IRQ 0c, APIC ID 0, APIC INT 0c Int: type 0, pol 0, trig 0, bus 00, IRQ 0d, APIC ID 0, APIC INT 0d Int: type 0, pol 0, trig 0, bus 00, IRQ 0e, APIC ID 0, APIC INT 0e Int: type 0, pol 0, trig 0, bus 00, IRQ 0f, APIC ID 0, APIC INT 0f ACPI: Using ACPI (MADT) for SMP configuration information ACPI: HPET id: 0x8086a201 base: 0xfed00000 TSC deadline timer available CPU topo: Max. logical packages: 1 CPU topo: Max. logical dies: 1 CPU topo: Max. dies per package: 1 CPU topo: Max. threads per core: 1 CPU topo: Num. cores per package: 1 CPU topo: Num. threads per package: 1 CPU topo: Allowing 1 present CPUs plus 0 hotplug CPUs CPU topo: Rejected CPUs 1 mapped IOAPIC to ffffa000 (fec00000) kvm-guest: APIC: eoi() replaced with kvm_guest_apic_eoi_write() [mem 0xc0000000-0xfeffbfff] available for PCI devices Booting paravirtualized kernel on KVM clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645519600211568 ns No bootconfig data provided, so skipping bootconfig pcpu-alloc: s0 r0 d131072 u131072 alloc=1*131072 pcpu-alloc: [0] 0 Kernel command line: ip=::::vm-meta-274::dhcp root=/dev/ram0 RESULT_ROOT=/result/rcutorture/300s-cpuhotplug-srcu/vm-snb-i386/debian-11.1-i386-20220923.cgz/i386-randconfig-012-20250327/gcc-12/84e0a11498cf967ae236f1feaf8b8b25ea738f2d/0 BOOT_IMAGE=/pkg/linux/i386-randconfig-012-20250327/gcc-12/84e0a11498cf967ae236f1feaf8b8b25ea738f2d/vmlinuz-6.14.0-rc1-00087-g84e0a11498cf branch=linux-devel/devel-hourly-20250326-215618 job=/lkp/jobs/scheduled/vm-meta-274/rcutorture-300s-cpuhotplug-srcu-debian-11.1-i386-20220923.cgz-i386-randconfig-012-20250327-84e0a11498cf-20250328-248795-dfj09i-0.yaml user=lkp ARCH=i386 kconfig=i386-randconfig-012-20250327 commit=84e0a11498cf967ae236f1feaf8b8b25ea738f2d mem=4G intremap=posted_msi vmalloc=256M initramfs_async=0 page_owner=on carrier_timeout=60 rcupdate.rcu_self_test=0 max_uptime=1200 LKP_SERVER=internal-lkp-server selinux=0 debug apic=debug sysrq_always_enabled rcupdate.rcu_cpu_stall_timeout=100 net.ifnames=0 printk.devkmsg=on panic=-1 softlockup_panic=1 nmi_watchdog=panic oo sysrq: sysrq always enabled. ignoring the deprecated load_ramdisk= option Unknown kernel command line parameters "RESULT_ROOT=/result/rcutorture/300s-cpuhotplug-srcu/vm-snb-i386/debian-11.1-i386-20220923.cgz/i386-randconfig-012-20250327/gcc-12/84e0a11498cf967ae236f1feaf8b8b25ea738f2d/0 BOOT_IMAGE=/pkg/linux/i386-randconfig-012-20250327/gcc-12/84e0a11498cf967ae236f1feaf8b8b25ea738f2d/vmlinuz-6.14.0-rc1-00087-g84e0a11498cf branch=linux-devel/devel-hourly-20250326-215618 job=/lkp/jobs/scheduled/vm-meta-274/rcutorture-300s-cpuhotplug-srcu-debian-11.1-i386-20220923.cgz-i386-randconfig-012-20250327-84e0a11498cf-20250328-248795-dfj09i-0.yaml user=lkp ARCH=i386 kconfig=i386-randconfig-012-20250327 commit=84e0a11498cf967ae236f1feaf8b8b25ea738f2d intremap=posted_msi max_uptime=1200 LKP_SERVER=internal-lkp-server selinux=0 nmi_watchdog=panic prompt_ramdisk=0 vga=normal audit=0 ia32_emulation=on riscv_isa_fallback=1", will be passed to user space. printk: log buffer data + meta data: 1048576 + 3276800 = 4325376 bytes Dentry cache hash table entries: 131072 (order: 7, 524288 bytes, linear) Inode-cache hash table entries: 65536 (order: 6, 262144 bytes, linear) Built 1 zonelists, mobility grouping on. Total pages: 786302 allocated 53544764 bytes of page_ext mem auto-init: stack:all(pattern), heap alloc:on, heap free:on mem auto-init: clearing system memory may take some time... stackdepot: allocating hash table via alloc_large_system_hash stackdepot hash table entries: 65536 (order: 7, 524288 bytes, linear) Initializing HighMem for node 0 (0002ebfe:000bffe0) Initializing Movable for node 0 (00000000:00000000) Checking if this processor honours the WP bit even in supervisor mode...Ok. SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1 Node 0, zone DMA: page owner found early allocated 0 pages Node 0, zone Normal: page owner found early allocated 49 pages Node 0, zone HighMem: page owner found early allocated 0 pages ftrace: allocating 44088 entries in 87 pages ftrace: allocated 87 pages with 5 groups Dynamic Preempt: none Running RCU self tests Running RCU synchronous self tests rcu: Preemptible hierarchical RCU implementation. rcu: RCU lockdep checking is enabled. rcu: RCU callback double-/use-after-free debug is enabled. RCU CPU stall warnings timeout set to 100 (rcu_cpu_stall_timeout). Trampoline variant of Tasks RCU enabled. Rude variant of Tasks RCU enabled. Tracing variant of Tasks RCU enabled. rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies. Running RCU synchronous self tests RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. NR_IRQS: 2304, nr_irqs: 256, preallocated irqs: 16 rcu: srcu_init: Setting srcu_struct sizes based on contention. printk: legacy console [ttyS0] enabled printk: legacy bootconsole [earlyser0] disabled Lock dependency validator: Copyright (c) 2006 Red Hat, Inc., Ingo Molnar ... MAX_LOCKDEP_SUBCLASSES: 8 ... MAX_LOCK_DEPTH: 48 ... MAX_LOCKDEP_KEYS: 8192 ... CLASSHASH_SIZE: 4096 ... MAX_LOCKDEP_ENTRIES: 32768 ... MAX_LOCKDEP_CHAINS: 65536 ... CHAINHASH_SIZE: 32768 memory used by lock dependency info: 3869 kB memory used for stack traces: 2112 kB per task-struct memory footprint: 1344 bytes ACPI: Core revision 20240827 clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns APIC: Switch to symmetric I/O mode setup Masked ExtINT on CPU#0 ENABLING IO-APIC IRQs Init IO_APIC IRQs apic 0 pin 0 not connected IOAPIC[0]: Preconfigured routing entry (0-1 -> IRQ 1 Level:0 ActiveLow:0) IOAPIC[0]: Preconfigured routing entry (0-2 -> IRQ 0 Level:0 ActiveLow:0) IOAPIC[0]: Preconfigured routing entry (0-3 -> IRQ 3 Level:0 ActiveLow:0) IOAPIC[0]: Preconfigured routing entry (0-4 -> IRQ 4 Level:0 ActiveLow:0) IOAPIC[0]: Preconfigured routing entry (0-5 -> IRQ 5 Level:1 ActiveLow:0) IOAPIC[0]: Preconfigured routing entry (0-6 -> IRQ 6 Level:0 ActiveLow:0) IOAPIC[0]: Preconfigured routing entry (0-7 -> IRQ 7 Level:0 ActiveLow:0) IOAPIC[0]: Preconfigured routing entry (0-8 -> IRQ 8 Level:0 ActiveLow:0) IOAPIC[0]: Preconfigured routing entry (0-9 -> IRQ 9 Level:1 ActiveLow:0) IOAPIC[0]: Preconfigured routing entry (0-10 -> IRQ 10 Level:1 ActiveLow:0) IOAPIC[0]: Preconfigured routing entry (0-11 -> IRQ 11 Level:1 ActiveLow:0) IOAPIC[0]: Preconfigured routing entry (0-12 -> IRQ 12 Level:0 ActiveLow:0) IOAPIC[0]: Preconfigured routing entry (0-13 -> IRQ 13 Level:0 ActiveLow:0) IOAPIC[0]: Preconfigured routing entry (0-14 -> IRQ 14 Level:0 ActiveLow:0) IOAPIC[0]: Preconfigured routing entry (0-15 -> IRQ 15 Level:0 ActiveLow:0) apic 0 pin 16 not connected apic 0 pin 17 not connected apic 0 pin 18 not connected apic 0 pin 19 not connected apic 0 pin 20 not connected apic 0 pin 21 not connected apic 0 pin 22 not connected apic 0 pin 23 not connected ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x228374dae5d, max_idle_ns: 440795268352 ns Calibrating delay loop (skipped) preset value.. 4788.74 BogoMIPS (lpj=9577496) Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 CPU: Intel Xeon E312xx (Sandy Bridge) (family: 0x6, model: 0x2a, stepping: 0x1) Speculative Store Bypass: Vulnerable L1TF: Kernel not compiled for PAE. No mitigation for L1TF x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'standard' format. pid_max: default: 4096 minimum: 301 LSM: initializing lsm=capability Mount-cache hash table entries: 2048 (order: 1, 8192 bytes, linear) Mountpoint-cache hash table entries: 2048 (order: 1, 8192 bytes, linear) Running RCU synchronous self tests Running RCU synchronous self tests Performance Events: unsupported p6 CPU model 42 no PMU driver, software events only. signal: max sigframe size: 1760 rcu: Hierarchical SRCU implementation. rcu: Max phase no-delay instances is 1000. dyndbg: Ignore empty _ddebug table in a CONFIG_DYNAMIC_DEBUG_CORE build Memory: 2892676K/3145208K available (8002K kernel code, 4863K rwdata, 5624K rodata, 760K init, 7960K bss, 249884K reserved, 0K cma-reserved, 2379656K highmem) devtmpfs: initialized Running RCU synchronous self tests Running RCU synchronous self tests Running RCU Tasks wait API self tests Running RCU Tasks Rude wait API self tests Running RCU Tasks Trace wait API self tests clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns futex hash table entries: 16 (order: -3, 768 bytes, linear) pinctrl core: initialized pinctrl subsystem pinctrl core: failed to create debugfs directory regulator: Failed to create debugfs directory NET: Registered PF_NETLINK/PF_ROUTE protocol family thermal_sys: Registered thermal governor 'bang_bang' thermal_sys: Registered thermal governor 'user_space' EISA bus registered cpuidle: using governor menu acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 PCI: Using configuration type 1 for base access regulator-dummy: 2147483647 mW budge, enabled regulator-dummy: Failed to create debugfs directory kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. HugeTLB: registered 4.00 MiB page size, pre-allocated 0 pages HugeTLB: 0 KiB vmemmap can be freed for a 4.00 MiB page cryptd: max_cpu_qlen set to 1000 ACPI: Added _OSI(Module Device) ACPI: Added _OSI(Processor Device) ACPI: Added _OSI(3.0 _SCP Extensions) ACPI: Added _OSI(Processor Aggregator Device) ACPI: 1 ACPI AML tables successfully acquired and loaded ACPI: Interpreter enabled ACPI: PM: (supports S0 S5) ACPI: Using IOAPIC for interrupt routing PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug PCI: Using E820 reservations for host bridge windows ACPI: Enabled 2 GPEs in block 00 to 0F ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] acpi PNP0A03:00: PCIe port services disabled; not requesting _OSC control acpi resource window ([0x140000000-0x1bfffffff] ignored, not CPU addressable) acpiphp: Slot [3] registered acpiphp: Slot [4] registered acpiphp: Slot [5] registered acpiphp: Slot [6] registered acpiphp: Slot [7] registered acpiphp: Slot [8] registered acpiphp: Slot [9] registered acpiphp: Slot [10] registered acpiphp: Slot [11] registered acpiphp: Slot [12] registered acpiphp: Slot [13] registered acpiphp: Slot [14] registered acpiphp: Slot [15] registered acpiphp: Slot [16] registered acpiphp: Slot [17] registered acpiphp: Slot [18] registered acpiphp: Slot [19] registered acpiphp: Slot [20] registered acpiphp: Slot [21] registered acpiphp: Slot [22] registered acpiphp: Slot [23] registered acpiphp: Slot [24] registered acpiphp: Slot [25] registered acpiphp: Slot [26] registered acpiphp: Slot [27] registered acpiphp: Slot [28] registered acpiphp: Slot [29] registered acpiphp: Slot [30] registered acpiphp: Slot [31] registered PCI host bridge to bus 0000:00 pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] pci_bus 0000:00: root bus resource [bus 00-ff] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 conventional PCI endpoint pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 conventional PCI endpoint pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 conventional PCI endpoint pci 0000:00:01.1: BAR 4 [io 0xc140-0xc14f] pci 0000:00:01.1: BAR 0 [io 0x01f0-0x01f7]: legacy IDE quirk pci 0000:00:01.1: BAR 1 [io 0x03f6]: legacy IDE quirk pci 0000:00:01.1: BAR 2 [io 0x0170-0x0177]: legacy IDE quirk pci 0000:00:01.1: BAR 3 [io 0x0376]: legacy IDE quirk pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 conventional PCI endpoint pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB pci 0000:00:02.0: [1234:1111] type 00 class 0x030000 conventional PCI endpoint pci 0000:00:02.0: BAR 0 [mem 0xfd000000-0xfdffffff pref] pci 0000:00:02.0: BAR 2 [mem 0xfebf0000-0xfebf0fff] pci 0000:00:02.0: ROM [mem 0xfebe0000-0xfebeffff pref] pci 0000:00:02.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff] pci 0000:00:03.0: [8086:100e] type 00 class 0x020000 conventional PCI endpoint pci 0000:00:03.0: BAR 0 [mem 0xfebc0000-0xfebdffff] pci 0000:00:03.0: BAR 1 [io 0xc100-0xc13f] pci 0000:00:03.0: ROM [mem 0xfeb80000-0xfebbffff pref] pci 0000:00:04.0: [8086:25ab] type 00 class 0x088000 conventional PCI endpoint pci 0000:00:04.0: BAR 0 [mem 0xfebf1000-0xfebf100f] pci 0000:00:05.0: [1b36:0005] type 00 class 0x00ff00 conventional PCI endpoint pci 0000:00:05.0: BAR 0 [mem 0xfebf2000-0xfebf2fff] pci 0000:00:05.0: BAR 1 [io 0xc000-0xc0ff] pci_bus 0000:00: on NUMA node 0 ACPI: PCI: Interrupt link LNKA configured for IRQ 10 ACPI: PCI: Interrupt link LNKB configured for IRQ 10 ACPI: PCI: Interrupt link LNKC configured for IRQ 11 ACPI: PCI: Interrupt link LNKD configured for IRQ 11 ACPI: PCI: Interrupt link LNKS configured for IRQ 9 iommu: Default domain type: Translated iommu: DMA domain TLB invalidation policy: strict mode ACPI: bus type USB registered usbcore: registered new interface driver usbfs usbcore: registered new interface driver hub usbcore: registered new device driver usb Callback from call_rcu_tasks_trace() invoked. PCI: Using ACPI for IRQ routing PCI: pci_cache_line_size set to 64 bytes e820: reserve RAM buffer [mem 0x0009fc00-0x0009ffff] e820: reserve RAM buffer [mem 0xbffe0000-0xbfffffff] pci 0000:00:02.0: vgaarb: setting as boot VGA device pci 0000:00:02.0: vgaarb: bridge control possible pci 0000:00:02.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none vgaarb: loaded hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 hpet0: 3 comparators, 64-bit 100.000000 MHz counter clocksource: Switched to clocksource kvm-clock VFS: Disk quotas dquot_6.6.0 VFS: Dquot-cache hash table entries: 1024 (order 0, 4096 bytes) pnp: PnP ACPI init pnp 00:02: [dma 2] pnp: PnP ACPI: found 7 devices clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns NET: Registered PF_INET protocol family IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear) tcp_listen_portaddr_hash hash table entries: 512 (order: 2, 20480 bytes, linear) Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) TCP established hash table entries: 8192 (order: 3, 32768 bytes, linear) TCP bind hash table entries: 8192 (order: 7, 655360 bytes, linear) TCP: Hash tables configured (established 8192 bind 8192) UDP hash table entries: 512 (order: 3, 49152 bytes, linear) UDP-Lite hash table entries: 512 (order: 3, 49152 bytes, linear) NET: Registered PF_UNIX/PF_LOCAL protocol family RPC: Registered named UNIX socket transport module. RPC: Registered udp transport module. RPC: Registered tcp transport module. RPC: Registered tcp-with-tls transport module. RPC: Registered tcp NFSv4.1 backchannel transport module. pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] pci 0000:00:01.0: PIIX3: Enabling Passive Release pci 0000:00:00.0: Limiting direct PCI/PCI transfers pci 0000:00:01.0: Activating ISA DMA hang workarounds PCI: CLS 0 bytes, default 64 Unpacking initramfs... Callback from call_rcu_tasks() invoked. Freeing initrd memory: 137432K clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x228374dae5d, max_idle_ns: 440795268352 ns Initialise system trusted keyrings workingset: timestamp_bits=14 max_order=20 bucket_order=6 fuse: init (API version 7.42) 9p: Installing v9fs 9p2000 file system support Key type asymmetric registered Asymmetric key parser 'x509' registered Asymmetric key parser 'pkcs8' registered usbcore: registered new interface driver gpio-mpsse gpio_winbond: chip ID at 2e is ffff gpio_winbond: not an our chip gpio_winbond: chip ID at 4e is ffff gpio_winbond: not an our chip ledtrig-cpu: registered to indicate activity on CPUs cpcihp_zt5550: ZT5550 CompactPCI Hot Plug Driver version: 0.2 IPMI message handler: version 39.2 ipmi_si: IPMI System Interface driver ipmi_si: Unable to find any System Interface(s) Serial: 8250/16550 driver, 4 ports, IRQ sharing disabled 00:04: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A 00:05: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A ppdev: user-space parallel port driver parport_pc 00:03: reported by Plug and Play ACPI parport0: PC-style at 0x378, irq 7 [PCSPP,TRISTATE] dummy-irq: no IRQ given. Use irq=N usbcore: registered new interface driver viperboard e1000: Intel(R) PRO/1000 Network Driver e1000: Copyright (c) 1999-2006 Intel Corporation. ACPI: \_SB_.LNKC: Enabled at IRQ 11 e1000 0000:00:03.0 eth0: (PCI:33MHz:32-bit) 52:54:00:12:34:56 e1000 0000:00:03.0 eth0: Intel(R) PRO/1000 Network Connection usbcore: registered new interface driver usblp usbcore: registered new interface driver cytherm usbcore: registered new interface driver isight_firmware usbcore: registered new interface driver ldusb usbcore: registered new interface driver legousbtower usbcore: registered new interface driver usbsevseg usbcore: registered new interface driver chaoskey usbcore: registered new device driver onboard-usb-dev dummy_hcd dummy_hcd.0: USB Host+Gadget Emulator, driver 02 May 2005 dummy_hcd dummy_hcd.0: Dummy host controller dummy_hcd dummy_hcd.0: new USB bus registered, assigned bus number 1 usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 6.14 usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1 usb usb1: Product: Dummy host controller usb usb1: Manufacturer: Linux 6.14.0-rc1-00087-g84e0a11498cf dummy_hcd usb usb1: SerialNumber: dummy_hcd.0 hub 1-0:1.0: USB hub found hub 1-0:1.0: 1 port detected zero gadget.0: Gadget Zero, version: Cinco de Mayo 2008 zero gadget.0: zero ready i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 serio: i8042 KBD port at 0x60,0x64 irq 1 serio: i8042 AUX port at 0x60,0x64 irq 12 usbcore: registered new interface driver i2c-tiny-usb Driver for 1-wire Dallas network protocol. usbcore: registered new interface driver DS9490R acquirewdt: WDT driver for Acquire single board computer initialising acquirewdt: I/O address 0x0043 already in use acquirewdt acquirewdt: probe with driver acquirewdt failed with error -5 advantechwdt: WDT driver for Advantech single board computer initialising input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input0 advantechwdt: initialized. timeout=60 sec (nowayout=0) eurotechwdt: can't misc_register on minor=130 wafer5823wdt: WDT driver for Wafer 5823 single board computer initialising wafer5823wdt: I/O address 0x0443 already in use it87_wdt: no device sbc8360: failed to register misc device sbc7240_wdt: I/O address 0x0443 already in use w83977f_wdt: driver v1.00 w83977f_wdt: cannot register miscdev on minor=130 (err=-16) sbc_epx_c3: cannot register miscdev on minor=130 (err=-16) Driver for HIFN 795x crypto accelerator chip has been successfully registered. NET: Registered PF_INET6 protocol family Segment Routing with IPv6 In-situ OAM (IOAM) with IPv6 sit: IPv6, IPv4 and MPLS over IPv4 tunneling driver NET: Registered PF_PACKET protocol family 9pnet: Installing 9P2000 support ... APIC ID: 00000000 (0) ... APIC VERSION: 00050014 0000000000000000000000000000000000000000000000000000000000000000 0000000000000000000000000000000000000000000000000000000000000000 0000000000000000000000000000000000000000000000000000000000001000 number of MP IRQ sources: 15. number of IO-APIC #0 registers: 24. testing the IO APIC....................... IO APIC #0...... .... register #00: 00000000 ....... : physical APIC id: 00 ....... : Delivery Type: 0 ....... : LTS : 0 .... register #01: 00170011 ....... : max redirection entries: 17 ....... : PRQ implemented: 0 ....... : IO APIC version: 11 .... register #02: 00000000 ....... : arbitration: 00 .... IRQ redirection table: IOAPIC 0: pin00, disabled, edge , high, V(00), IRR(0), S(0), physical, D(0000), M(0) pin01, enabled , edge , high, V(23), IRR(0), S(0), logical , D(0001), M(0) pin02, enabled , edge , high, V(30), IRR(0), S(0), logical , D(0001), M(0) pin03, disabled, edge , high, V(00), IRR(0), S(0), physical, D(0000), M(0) pin04, disabled, edge , high, V(00), IRR(0), S(0), physical, D(0000), M(0) pin05, disabled, edge , high, V(00), IRR(0), S(0), physical, D(0000), M(0) pin06, disabled, edge , high, V(00), IRR(0), S(0), physical, D(0000), M(0) pin07, enabled , edge , high, V(21), IRR(0), S(0), logical , D(0001), M(0) pin08, disabled, edge , high, V(00), IRR(0), S(0), physical, D(0000), M(0) pin09, enabled , level, high, V(20), IRR(0), S(0), logical , D(0001), M(0) pin0a, disabled, edge , high, V(00), IRR(0), S(0), physical, D(0000), M(0) pin0b, disabled, edge , high, V(00), IRR(0), S(0), physical, D(0000), M(0) pin0c, enabled , edge , high, V(22), IRR(0), S(0), logical , D(0001), M(0) pin0d, disabled, edge , high, V(00), IRR(0), S(0), physical, D(0000), M(0) pin0e, disabled, edge , high, V(00), IRR(0), S(0), physical, D(0000), M(0) pin0f, disabled, edge , high, V(00), IRR(0), S(0), physical, D(0000), M(0) pin10, disabled, edge , high, V(00), IRR(0), S(0), physical, D(0000), M(0) pin11, disabled, edge , high, V(00), IRR(0), S(0), physical, D(0000), M(0) pin12, disabled, edge , high, V(00), IRR(0), S(0), physical, D(0000), M(0) pin13, disabled, edge , high, V(00), IRR(0), S(0), physical, D(0000), M(0) pin14, disabled, edge , high, V(00), IRR(0), S(0), physical, D(0000), M(0) pin15, disabled, edge , high, V(00), IRR(0), S(0), physical, D(0000), M(0) pin16, disabled, edge , high, V(00), IRR(0), S(0), physical, D(0000), M(0) pin17, disabled, edge , high, V(00), IRR(0), S(0), physical, D(0000), M(0) IRQ to pin mappings: IRQ0 -> 0:2 IRQ1 -> 0:1 IRQ3 -> 0:3 IRQ4 -> 0:4 IRQ5 -> 0:5 IRQ6 -> 0:6 IRQ7 -> 0:7 IRQ8 -> 0:8 IRQ9 -> 0:9 IRQ10 -> 0:10 IRQ11 -> 0:11 IRQ12 -> 0:12 IRQ13 -> 0:13 IRQ14 -> 0:14 IRQ15 -> 0:15 .................................... done. sched_clock: Marking stable (8372019631, 30756020)->(8414400819, -11625168) Loading compiled-in X.509 certificates debug_vm_pgtable: [debug_vm_pgtable ]: Validating architecture page table helpers usb 1-1: new high-speed USB device number 2 using dummy_hcd usb 1-1: New USB device found, idVendor=0525, idProduct=a4a0, bcdDevice= 6.14 usb 1-1: New USB device strings: Mfr=1, Product=2, SerialNumber=3 usb 1-1: Product: Gadget Zero usb 1-1: Manufacturer: Linux 6.14.0-rc1-00087-g84e0a11498cf with dummy_udc usb 1-1: SerialNumber: 0123456789.0123456789.0123456789 e1000: eth0 NIC Link is Up 1000 Mbps Full Duplex, Flow Control: RX Sending DHCP requests ., OK IP-Config: Got DHCP answer from 10.0.2.2, my address is 10.0.2.15 IP-Config: Complete: device=eth0, hwaddr=52:54:00:12:34:56, ipaddr=10.0.2.15, mask=255.255.255.0, gw=10.0.2.2 host=vm-meta-274, domain=, nis-domain=(none) bootserver=10.0.2.2, rootserver=10.0.2.2, rootpath= nameserver0=10.0.2.3 clk: Disabling unused clocks PM: genpd: Disabling unused power domains Freeing unused kernel image (initmem) memory: 760K Write protecting kernel text and read-only data: 13628k Run /init as init process with arguments: /init with environment: HOME=/ TERM=linux RESULT_ROOT=/result/rcutorture/300s-cpuhotplug-srcu/vm-snb-i386/debian-11.1-i386-20220923.cgz/i386-randconfig-012-20250327/gcc-12/84e0a11498cf967ae236f1feaf8b8b25ea738f2d/0 BOOT_IMAGE=/pkg/linux/i386-randconfig-012-20250327/gcc-12/84e0a11498cf967ae236f1feaf8b8b25ea738f2d/vmlinuz-6.14.0-rc1-00087-g84e0a11498cf branch=linux-devel/devel-hourly-20250326-215618 job=/lkp/jobs/scheduled/vm-meta-274/rcutorture-300s-cpuhotplug-srcu-debian-11.1-i386-20220923.cgz-i386-randconfig-012-20250327-84e0a11498cf-20250328-248795-dfj09i-0.yaml user=lkp ARCH=i386 kconfig=i386-randconfig-012-20250327 commit=84e0a11498cf967ae236f1feaf8b8b25ea738f2d intremap=posted_msi max_uptime=1200 LKP_SERVER=internal-lkp-server selinux=0 nmi_watchdog=panic prompt_ramdisk=0 vga=normal audit=0 ia32_emulation=on riscv_isa_fallback=1 systemd[1]: RTC configured in localtime, applying delta of 0 minutes to system time. Welcome to Debian GNU/Linux 11 (bullseye)! random: crng init done Created slice system-getty.slice. Created slice system-modprobe.slice. Created slice User and Session Slice. Reached target Local Encrypted Volumes. Reached target Paths. Reached target Slices. Reached target Swap. Listening on RPCbind Server Activation Socket. Listening on Syslog Socket. Listening on initctl Compatibility Named Pipe. Listening on Journal Socket (/dev/log). Listening on Journal Socket. Listening on udev Control Socket. Listening on udev Kernel Socket. Mounting Huge Pages File System... Mounting RPC Pipe File System... Mounting Kernel Trace File System... Starting Load Kernel Module configfs... Starting Load Kernel Module drm... Starting Load Kernel Module fuse... Starting Journal Service... Starting Load Kernel Modules... Starting Remount Root and Kernel File Systems... Starting Coldplug All udev Devices... Mounted Huge Pages File System. Mounted RPC Pipe File System. Mounted Kernel Trace File System. Finished Load Kernel Module configfs. Finished Load Kernel Module fuse. Finished Load Kernel Modules. Finished Remount Root and Kernel File Systems. ACPI: bus type drm_connector registered Mounting FUSE Control File System... Mounting Kernel Configuration File System... Starting Load/Save Random Seed... Starting Apply Kernel Variables... Starting Create System Users... Finished Load Kernel Module drm. Mounted FUSE Control File System. Mounted Kernel Configuration File System. Finished Load/Save Random Seed. Finished Apply Kernel Variables. Finished Create System Users. Starting Create Static Device Nodes in /dev... Started Journal Service. Starting Flush Journal to Persistent Storage... Finished Create Static Device Nodes in /dev. Reached target Local File Systems (Pre). Reached target Local File Systems. Starting Preprocess NFS configuration... Finished Preprocess NFS configuration. Reached target NFS client services. Finished Flush Journal to Persistent Storage. Starting Create Volatile Files and Directories... Started Rule-based Manager for Device Events and Files. Finished Create Volatile Files and Directories. Starting RPC bind portmap service... Starting Update UTMP about System Boot/Shutdown... Started RPC bind portmap service. Reached target Remote File Systems (Pre). Reached target Remote File Systems. Reached target RPC Port Mapper. Failed to start Update UTMP about System Boot/Shutdown. See 'systemctl status systemd-update-utmp.service' for details. Finished Coldplug All udev Devices. Reached target System Initialization. Started Daily apt download activities. Started Daily apt upgrade and clean activities. Started Discard unused blocks once a week. Started Daily rotation of log files. Started Daily Cleanup of Temporary Directories. Reached target Timers. Listening on D-Bus System Message Bus Socket. Reached target Sockets. Reached target Basic System. Started Regular background program processing daemon. Started D-Bus System Message Bus. input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input3 ACPI: button: Power Button [PWRF] Starting Helper to synchronize boot up for ifupdown... Starting LSB: OpenIPMI Driver init script... Starting System Logging Service... Starting User Login Management... See 'systemctl status e2scrub_reap.service' for details. Finished Helper to synchronize boot up for ifupdown. Starting Raise network interfaces... Starting LSB: Load kernel image with kexec... Started User Login Management. Started System Logging Service. piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 input: PC Speaker as /devices/platform/pcspkr/input/input4 Finished Raise network interfaces. Started LSB: Load kernel image with kexec. Reached target Network. Linux agpgart interface v0.103 Starting LKP bootstrap... Starting /etc/rc.local Compatibility... Starting OpenBSD Secure Shell server... Starting Permit User Sessions... Failed to start LSB: OpenIPMI Driver init script. See 'systemctl status openipmi.service' for details. Reached target Hardware activated USB gadget. Started LKP bootstrap. psmouse serio1: hgpk: ID: 10 02 64 Finished Permit User Sessions. LKP: ttyS0: 175: skip deploy intel ucode as no ucode is specified Started OpenBSD Secure Shell server. usbtest 1-1:3.0: Linux gadget zero usbtest 1-1:3.0: high-speed {control in/out bulk-in bulk-out} tests (+alt) usbcore: registered new interface driver usbtest input: ImExPS/2 Generic Explorer Mouse as /devices/platform/i8042/serio1/input/input5 [drm] Found bochs VGA, ID 0xb0c5. [drm] Framebuffer size 16384 kB @ 0xfd000000, mmio @ 0xfebf0000. [drm] Initialized bochs-drm 1.0.0 for 0000:00:02.0 on minor 0 bochs-drm 0000:00:02.0: [drm] fb0: bochs-drmdrmfb frame buffer device LKP: ttyS0: 175: Kernel tests: Boot OK! LKP: ttyS0: 175: HOSTNAME vm-snb-i386, MAC 52:54:00:12:34:56, kernel 6.14.0-rc1-00087-g84e0a11498cf 1 LKP: ttyS0: 175: /lkp/lkp/src/bin/run-lkp /lkp/jobs/scheduled/vm-meta-274/rcutorture-300s-cpuhotplug-srcu-debian-11.1-i386-20220923.cgz-i386-randconfig-012-20250327-84e0a11498cf-20250328-248795-dfj09i-0.yaml is_virt=true lkp: kernel tainted state: 0 LKP: stdout: 175: Kernel tests: Boot OK! torture module --- srcu: disable_onoff_at_boot=0 ftrace_dump_at_shutdown=0 verbose_sleep_frequency=0 verbose_sleep_duration=1 random_shuffle=0 srcu-torture:--- Start of test: nreaders=1 nfakewriters=4 stat_interval=60 verbose=1 test_no_idle_hz=1 shuffle_interval=3 stutter=5 irqreader=1 fqs_duration=0 fqs_holdoff=0 fqs_stutter=3 test_boost=1/0 test_boost_interval=7 test_boost_duration=4 test_boost_holdoff=0 shutdown_secs=0 stall_cpu=0 stall_cpu_holdoff=10 stall_cpu_irqsoff=0 stall_cpu_block=0 stall_cpu_repeat=0 n_barrier_cbs=0 onoff_interval=3 onoff_holdoff=30 read_exit_delay=13 read_exit_burst=16 reader_flavor=1 nocbs_nthreads=0 nocbs_toggle=1000 test_nmis=0 preempt_duration=0 preempt_interval=1000 n_up_down=32 srcu: Start-test grace-period state: g-400 f0x0 rcu_torture_write_types: Testing expedited GPs. rcu_torture_write_types: Testing asynchronous GPs. rcu_torture_write_types: Testing polling GPs. rcu_torture_write_types: Testing normal GPs. rcu_torture_write_types: Testing 4 update types. rcu_torture_write_types: gp_cond_wi 64000 gp_cond_wi_exp 128 gp_poll_wi 64000 gp_poll_wi_exp 128 srcu-torture: Creating rcu_torture_writer task srcu-torture: Creating rcu_torture_fakewriter task srcu-torture: rcu_torture_writer task started srcu-torture: Creating rcu_torture_fakewriter task srcu-torture: rcu_torture_fakewriter task started srcu-torture: Creating rcu_torture_fakewriter task srcu-torture: rcu_torture_fakewriter task started srcu-torture: Creating rcu_torture_fakewriter task srcu-torture: rcu_torture_fakewriter task started srcu-torture: Creating rcu_torture_reader task srcu-torture: rcu_torture_fakewriter task started srcu-torture: Creating rcu_torture_updown task srcu-torture: rcu_torture_reader task started srcu-torture: Creating rcu_torture_stats task srcu-torture: rcu_torture_updown task started srcu-torture: Creating torture_shuffle task srcu-torture: rcu_torture_stats task started srcu-torture: Creating torture_stutter task srcu-torture: torture_shuffle task started srcu-torture: rcu_torture_fwd_prog_init: Limiting fwd_progress to # CPUs. srcu-torture: Creating rcu_torture_fwd_prog task srcu-torture: torture_stutter task started srcu-torture: Creating rcu_torture_read_exit task srcu-torture: rcu_torture_fwd_progress task started srcu-torture: rcu_torture_read_exit: Start of test srcu-torture: rcu_torture_read_exit: Start of episode mount: /sys/kernel/debug: mount point does not exist. check_nr_cpu: lscpu_nr_cpu 1 mismatchs with nr_cpu 2 ls: cannot access '/boot/config-*': No such file or directory srcu-torture: rcu_torture_read_exit: End of episode LKP: stdout: 175: HOSTNAME vm-snb-i386, MAC 52:54:00:12:34:56, kernel 6.14.0-rc1-00087-g84e0a11498cf 1 install debs round one: dpkg -i --force-confdef --force-depends /opt/deb/gawk_1%3a5.1.0-1_i386.deb Selecting previously unselected package gawk. (Reading database ... 16439 files and directories currently installed.) Preparing to unpack .../deb/gawk_1%3a5.1.0-1_i386.deb ... Unpacking gawk (1:5.1.0-1) ... Setting up gawk (1:5.1.0-1) ... NO_NETWORK= mount debug /sys/kernel/debug -t debugfs rootfs on / type rootfs (rw) proc on /proc type proc (rw,nosuid,nodev,noexec,relatime) sysfs on /sys type sysfs (rw,nosuid,nodev,noexec,relatime) devtmpfs on /dev type devtmpfs (rw,nosuid,noexec,size=4096k,nr_inodes=65536,mode=755) tmpfs on /dev/shm type tmpfs (rw,nosuid,nodev) devpts on /dev/pts type devpts (rw,nosuid,noexec,relatime,gid=5,mode=620,ptmxmode=000) tmpfs on /run type tmpfs (rw,nosuid,nodev,size=606704k,nr_inodes=819200,mode=755) tmpfs on /run/lock type tmpfs (rw,nosuid,nodev,noexec,relatime,size=5120k) cgroup2 on /sys/fs/cgroup type cgroup2 (rw,nosuid,nodev,noexec,relatime,nsdelegate,favordynmods,memory_recursiveprot) bpf on /sys/fs/bpf type bpf (rw,nosuid,nodev,noexec,relatime,mode=700) hugetlbfs on /dev/hugepages type hugetlbfs (rw,relatime,pagesize=4M) sunrpc on /run/rpc_pipefs type rpc_pipefs (rw,relatime) tracefs on /sys/kernel/tracing type tracefs (rw,nosuid,nodev,noexec,relatime) fusectl on /sys/fs/fuse/connections type fusectl (rw,nosuid,nodev,noexec,relatime) configfs on /sys/kernel/config type configfs (rw,nosuid,nodev,noexec,relatime) tmp on /tmp type tmpfs (rw,relatime) nodev sysfs nodev tmpfs nodev proc nodev cgroup nodev cgroup2 nodev devtmpfs nodev configfs nodev tracefs nodev sockfs nodev bpf nodev pipefs nodev ramfs nodev hugetlbfs nodev rpc_pipefs nodev devpts nodev nfs nodev nfs4 nodev autofs nodev fuse nodev fusectl nodev overlay nodev 9p LKP: stdout: 175: /lkp/lkp/src/bin/run-lkp /lkp/jobs/scheduled/vm-meta-274/rcutorture-300s-cpuhotplug-srcu-debian-11.1-i386-20220923.cgz-i386-randconfig-012-20250327-84e0a11498cf-20250328-248795-dfj09i-0.yaml RESULT_ROOT=/result/rcutorture/300s-cpuhotplug-srcu/vm-snb-i386/debian-11.1-i386-20220923.cgz/i386-randconfig-012-20250327/gcc-12/84e0a11498cf967ae236f1feaf8b8b25ea738f2d/0 job=/lkp/jobs/scheduled/vm-meta-274/rcutorture-300s-cpuhotplug-srcu-debian-11.1-i386-20220923.cgz-i386-randconfig-012-20250327-84e0a11498cf-20250328-248795-dfj09i-0.yaml srcu-torture: rcu_torture_read_exit: Start of episode srcu-torture: rcu_torture_read_exit: End of episode result_service: raw_upload, RESULT_MNT: /internal-lkp-server/result, RESULT_ROOT: /internal-lkp-server/result/rcutorture/300s-cpuhotplug-srcu/vm-snb-i386/debian-11.1-i386-20220923.cgz/i386-randconfig-012-20250327/gcc-12/84e0a11498cf967ae236f1feaf8b8b25ea738f2d/0, TMP_RESULT_ROOT: /tmp/lkp/result run-job /lkp/jobs/scheduled/vm-meta-274/rcutorture-300s-cpuhotplug-srcu-debian-11.1-i386-20220923.cgz-i386-randconfig-012-20250327-84e0a11498cf-20250328-248795-dfj09i-0.yaml /usr/bin/wget -q --timeout=3600 --tries=1 --local-encoding=UTF-8 http://internal-lkp-server:80/~lkp/cgi-bin/lkp-jobfile-append-var?job_file=/lkp/jobs/scheduled/vm-meta-274/rcutorture-300s-cpuhotplug-srcu-debian-11.1-i386-20220923.cgz-i386-randconfig-012-20250327-84e0a11498cf-20250328-248795-dfj09i-0.yaml&job_state=running -O /dev/null target ucode: check_nr_cpu CPU(s): 1 On-line CPU(s) list: 0 Thread(s) per core: 1 Core(s) per socket: 1 Socket(s): 1 check_nr_cpu: ignore the mismatch for i386-randconfig-012-20250327 2025-03-28 02:52:27 depmod 2025-03-28 02:52:56 sleep 300 srcu-torture: rcu_torture_read_exit: Start of episode srcu-torture: rcu_torture_read_exit: End of episode srcu-torture: rcu_torture_read_exit: Start of episode srcu-torture: rcu_torture_read_exit: End of episode srcu-torture: rtc: c4ab38a7 ver: 115 tfle: 0 rta: 115 rtaf: 0 rtf: 106 rtmbe: 0 rtmbkf: 0/0 rtbe: 0 rtbke: 0 rtbf: 0 rtb: 0 nt: 1566 barrier: 0/0:0 read-exits: 64 nocb-toggles: 0:0 srcu-torture: Reader Pipe: 4547304 1313 0 0 0 0 0 0 0 0 0 srcu-torture: Reader Batch: 4545100 3517 0 0 0 0 0 0 0 0 0 srcu-torture: Free-Block Circulation: 114 114 113 112 111 110 109 108 107 106 0 rcu: srcu-torture: Tree SRCU g568 state 0 (SRCU_SIZE_SMALL) per-CPU(idx=0): 0(0,0 .) T(0,0) rcu_torture_fwd_prog: Starting forward-progress test 0 rcu_torture_fwd_prog_cr: Starting forward-progress test 0 rcu_torture_fwd_prog_cr: Waiting for CBs: srcu_torture_barrier+0x0/0x16 [rcutorture]() 0 rcu_torture_fwd_prog_cr Duration 18 barrier: 8 pending 3529 n_launders: 11957 n_launders_sa: 3529 n_max_gps: 100 n_max_cbs: 8344 cver 0 gps 13 #online 1 rcu_torture_fwd_cb_hist: Callback-invocation histogram 0 (duration 26 jiffies): 1s/10: 20301:15 srcu-torture: rcu_torture_read_exit: Start of episode srcu-torture: rcu_torture_read_exit: End of episode srcu-torture: rcu_torture_read_exit: Start of episode srcu-torture: rcu_torture_read_exit: End of episode srcu-torture: rcu_torture_read_exit: Start of episode srcu-torture: rcu_torture_read_exit: End of episode srcu-torture: rcu_torture_read_exit: Start of episode srcu-torture: rcu_torture_read_exit: End of episode srcu-torture: rcu_torture_read_exit: Start of episode srcu-torture: rtc: 3f97e885 ver: 241 tfle: 0 rta: 241 rtaf: 0 rtf: 232 rtmbe: 0 rtmbkf: 0/0 rtbe: 0 rtbke: 0 rtbf: 0 rtb: 0 nt: 3749 barrier: 0/0:0 read-exits: 128 nocb-toggles: 0:0 srcu-torture: Reader Pipe: 11318207 2545 0 0 0 0 0 0 0 0 0 srcu-torture: Reader Batch: 11313495 7257 0 0 0 0 0 0 0 0 0 srcu-torture: Free-Block Circulation: 240 240 239 238 237 236 235 234 233 232 0 rcu: srcu-torture: Tree SRCU g1680 state 0 (SRCU_SIZE_SMALL) per-CPU(idx=0): 0(0,0 .) T(0,0) srcu-torture: rcu_torture_read_exit: End of episode rcu_torture_fwd_prog n_max_cbs: 8344 rcu_torture_fwd_prog: Starting forward-progress test 0 rcu_torture_fwd_prog_cr: Starting forward-progress test 0 rcu_torture_fwd_prog_cr: Waiting for CBs: srcu_torture_barrier+0x0/0x16 [rcutorture]() 0 rcu_torture_fwd_prog_cr Duration 355 barrier: 95 pending 101 n_launders: 100099 n_launders_sa: 100099 n_max_gps: 100 n_max_cbs: 50000 cver 5 gps 6 #online 1 rcu_torture_fwd_cb_hist: Callback-invocation histogram 0 (duration 450 jiffies): 1s/10: 0:1 2s/10: 0:1 3s/10: 0:0 4s/10: 0:0 5s/10: 37266:432 6s/10: 12734:0 7s/10: 0:1 8s/10: 0:0 9s/10: 12946:434 10s/10: 37053:0 11s/10: 0:1 12s/10: 0:0 13s/10: 0:0 14s/10: 37013:436 15s/10: 12986:0 16s/10: 0:1 17s/10: 0:0 18s/10: 101:438 srcu-torture: rcu_torture_read_exit: Start of episode srcu-torture: rcu_torture_read_exit: End of episode srcu-torture: rcu_torture_read_exit: Start of episode srcu-torture: rcu_torture_read_exit: End of episode srcu-torture: rcu_torture_read_exit: Start of episode srcu-torture: rcu_torture_read_exit: End of episode srcu-torture: rcu_torture_read_exit: Start of episode srcu-torture: rtc: 5534fd1c ver: 352 tfle: 0 rta: 352 rtaf: 0 rtf: 343 rtmbe: 0 rtmbkf: 0/0 rtbe: 0 rtbke: 0 rtbf: 0 rtb: 0 nt: 5808 barrier: 0/0:0 read-exits: 192 nocb-toggles: 0:0 srcu-torture: Reader Pipe: 17562742 3723 0 0 0 0 0 0 0 0 0 srcu-torture: Reader Batch: 17555766 10699 0 0 0 0 0 0 0 0 0 srcu-torture: Free-Block Circulation: 351 351 350 349 348 347 346 345 344 343 0 rcu: srcu-torture: Tree SRCU g2668 state 0 (SRCU_SIZE_SMALL) per-CPU(idx=1): 0(0,0 .) T(0,0) srcu-torture: rcu_torture_read_exit: End of episode rcu_torture_fwd_prog n_max_cbs: 50000 rcu_torture_fwd_prog: Starting forward-progress test 0 rcu_torture_fwd_prog_cr: Starting forward-progress test 0 rcu_torture_fwd_prog_cr: Waiting for CBs: srcu_torture_barrier+0x0/0x16 [rcutorture]() 0 rcu_torture_fwd_prog_cr Duration 23 barrier: 7 pending 6272 n_launders: 18611 n_launders_sa: 2182 n_max_gps: 100 n_max_cbs: 9923 cver 1 gps 8 #online 1 rcu_torture_fwd_cb_hist: Callback-invocation histogram 0 (duration 30 jiffies): 1s/10: 24705:9 2s/10: 3829:1 srcu-torture: rcu_torture_read_exit: Start of episode srcu-torture: rcu_torture_read_exit: End of episode srcu-torture: rcu_torture_read_exit: Start of episode srcu-torture: rcu_torture_read_exit: End of episode srcu-torture: rcu_torture_read_exit: Start of episode srcu-torture: rcu_torture_read_exit: End of episode srcu-torture: rcu_torture_read_exit: Start of episode srcu-torture: rcu_torture_read_exit: End of episode srcu-torture: rtc: 6d864796 ver: 474 tfle: 0 rta: 475 rtaf: 0 rtf: 465 rtmbe: 0 rtmbkf: 0/0 rtbe: 0 rtbke: 0 rtbf: 0 rtb: 0 nt: 8085 barrier: 0/0:0 read-exits: 272 nocb-toggles: 0:0 srcu-torture: Reader Pipe: 24662777 5059 0 0 0 0 0 0 0 0 0 srcu-torture: Reader Batch: 24653436 14400 0 0 0 0 0 0 0 0 0 srcu-torture: Free-Block Circulation: 474 473 472 471 470 469 468 467 466 465 0 rcu: srcu-torture: Tree SRCU g3642 state 0 (SRCU_SIZE_SMALL) per-CPU(idx=1): 0(4,3 C) T(4,3) rcu_torture_fwd_prog n_max_cbs: 9923 rcu_torture_fwd_prog: Starting forward-progress test 0 rcu_torture_fwd_prog_cr: Starting forward-progress test 0 workqueue: srcu_invoke_callbacks hogged CPU for >10000us 4 times, consider switching to WQ_UNBOUND workqueue: srcu_invoke_callbacks hogged CPU for >10000us 5 times, consider switching to WQ_UNBOUND rcu_torture_fwd_prog_cr: Waiting for CBs: srcu_torture_barrier+0x0/0x16 [rcutorture]() 0 rcu_torture_fwd_prog_cr Duration 341 barrier: 107 pending 101 n_launders: 100099 n_launders_sa: 100099 n_max_gps: 100 n_max_cbs: 50000 cver 5 gps 6 #online 1 rcu_torture_fwd_cb_hist: Callback-invocation histogram 0 (duration 448 jiffies): 1s/10: 0:1 2s/10: 0:1 3s/10: 0:1 4s/10: 6540:954 5s/10: 43460:0 6s/10: 0:1 7s/10: 0:1 8s/10: 0:0 9s/10: 32443:956 10s/10: 17556:0 11s/10: 0:1 12s/10: 0:0 13s/10: 15841:958 14s/10: 34158:0 15s/10: 0:1 16s/10: 0:1 17s/10: 0:0 18s/10: 101:960 srcu-torture: rcu_torture_read_exit: Start of episode srcu-torture: rcu_torture_read_exit: End of episode srcu-torture: rcu_torture_read_exit: Start of episode srcu-torture: rcu_torture_read_exit: End of episode srcu-torture: rcu_torture_read_exit: Start of episode srcu-torture: rcu_torture_read_exit: End of episode Started /etc/rc.local Compatibility. Reached target Login Prompts. Reached target Multi-User System. Starting watchdog daemon... Started watchdog daemon. Reached target Graphical Interface. 2025-03-28 02:57:56 rmmod rcutorture srcu-torture: Stopping torture_shuffle task srcu-torture: torture_shuffle is stopping srcu-torture: Stopping torture_stutter task ------------[ cut here ]------------ WARNING: CPU: 0 PID: 402 at kernel/rcu/rcutorture.c:2501 rcu_torture_updown+0x158/0x182 [rcutorture] Modules linked in: rcutorture(-) torture intel_rapl_msr bochs drm_client_lib usbtest evdev input_leds font drm_shmem_helper syscopyarea sysfillrect sysimgblt fb_sys_fops led_class intel_rapl_common intel_agp drm_kms_helper intel_gtt pcspkr psmouse serio_raw i2c_piix4 fb agpgart qemu_fw_cfg tiny_power_button button drm drm_panel_orientation_quirks backlight CPU: 0 UID: 0 PID: 402 Comm: rcu_torture_upd Not tainted 6.14.0-rc1-00087-g84e0a11498cf #1 EIP: rcu_torture_updown+0x158/0x182 [rcutorture] Code: c3 64 02 00 00 8b 15 04 0e 58 ef 69 05 10 07 58 ef 64 02 00 00 01 d0 39 c3 72 dd eb 11 89 d8 e8 ed aa b6 d1 80 7b 28 00 74 d5 <0f> 0b eb d1 89 d0 e8 f0 40 c9 d1 31 c0 a3 04 0e 58 ef b8 c1 06 5f EAX: 00000001 EBX: eaad84c8 ECX: 00000000 EDX: 00000000 ESI: 00000000 EDI: eaadca50 EBP: eaa17f64 ESP: eaa17f54 DS: 007b ES: 007b FS: 0000 GS: 0000 SS: 0068 EFLAGS: 00010202 CR0: 80050033 CR2: 00493adc CR3: 2a9db000 CR4: 00040690 DR0: 00000000 DR1: 00000000 DR2: 00000000 DR3: 00000000 DR6: fffe0ff0 DR7: 00000400 Call Trace: ? show_regs+0x64/0x70 ? rcu_torture_updown+0x158/0x182 [rcutorture] ? __warn+0x97/0x130 ? report_bug+0xde/0x13b ? rcu_torture_updown+0x158/0x182 [rcutorture] ? exc_overflow+0x3d/0x3d ? handle_bug+0x3d/0x5c ? exc_invalid_op+0x24/0x49 ? handle_exception+0x11a/0x11a ? xa_find+0x69/0xc0 ? exc_overflow+0x3d/0x3d ? rcu_torture_updown+0x158/0x182 [rcutorture] ? exc_overflow+0x3d/0x3d ? rcu_torture_updown+0x158/0x182 [rcutorture] kthread+0x142/0x147 ? rcu_torture_one_read_start+0x102/0x102 [rcutorture] ? kthread_is_per_cpu+0x1c/0x1c ret_from_fork+0x1e/0x37 ? kthread_is_per_cpu+0x1c/0x1c ret_from_fork_asm+0x12/0x20 entry_INT80_32+0x10c/0x10c irq event stamp: 2657215 hardirqs last enabled at (2657325): [] __up_console_sem+0x5e/0x72 hardirqs last disabled at (2657332): [] __up_console_sem+0x45/0x72 softirqs last enabled at (2657322): [] handle_softirqs+0x2ca/0x2fa softirqs last disabled at (2657223): [] __do_softirq+0xf/0x13 ---[ end trace 0000000000000000 ]--- srcu-torture: rcu_torture_updown is stopping srcu-torture: torture_stutter is stopping srcu-torture: rcu_torture_reader is stopping srcu-torture: rcu_torture_read_exit is stopping srcu-torture: Stopping rcutorture_read_exit task srcu-torture: Stopping rcu_torture_fwd_prog task rcu_torture_fwd_prog n_max_cbs: 50000 rcu_torture_fwd_prog: Starting forward-progress test 0 rcu_torture_fwd_prog_cr: Starting forward-progress test 0 srcu-torture: rtc: 3041ac47 ver: 591 tfle: 0 rta: 592 rtaf: 0 rtf: 581 rtmbe: 0 rtmbkf: 0/0 rtbe: 0 rtbke: 0 rtbf: 0 rtb: 0 nt: 10173 barrier: 0/0:0 read-exits: 320 nocb-toggles: 0:0 srcu-torture: Reader Pipe: 31012756 6308 0 0 0 0 0 0 0 0 0 srcu-torture: Reader Batch: 31001386 17678 0 0 0 0 0 0 0 0 0 srcu-torture: Free-Block Circulation: 591 590 588 587 586 585 584 583 582 581 0 rcu: srcu-torture: Tree SRCU g4462 state 0 (SRCU_SIZE_SMALL) per-CPU(idx=0): 0(12,3 C) T(12,3) srcu-torture: rcu_torture_stats is stopping INFO: task rcu_torture_fak:389 blocked for more than 491 seconds. Tainted: G W 6.14.0-rc1-00087-g84e0a11498cf #1 "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. task:rcu_torture_fak state:D stack:0 pid:389 tgid:389 ppid:2 task_flags:0x208040 flags:0x00004000 Call Trace: __schedule+0x3d7/0x409 schedule+0x42/0x5e schedule_timeout+0x2f/0xb9 ? usleep_range_state+0x8b/0x8b __wait_for_common+0x91/0xe4 wait_for_common+0x15/0x1b wait_for_completion+0x17/0x1f __synchronize_srcu+0x10b/0x122 ? rcu_tasks_pregp_step+0xf/0xf synchronize_srcu_expedited+0x1a/0x21 srcu_torture_synchronize_expedited+0x12/0x16 [rcutorture] rcu_torture_fakewriter+0x135/0x351 [rcutorture] kthread+0x142/0x147 ? srcu_read_delay+0x55/0x55 [rcutorture] ? kthread_is_per_cpu+0x1c/0x1c ret_from_fork+0x1e/0x37 ? kthread_is_per_cpu+0x1c/0x1c ret_from_fork_asm+0x12/0x20 entry_INT80_32+0x10c/0x10c INFO: task rcu_torture_fak:390 blocked for more than 491 seconds. Tainted: G W 6.14.0-rc1-00087-g84e0a11498cf #1 "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. task:rcu_torture_fak state:D stack:0 pid:390 tgid:390 ppid:2 task_flags:0x208040 flags:0x00004000 Call Trace: __schedule+0x3d7/0x409 schedule+0x42/0x5e schedule_timeout+0x2f/0xb9 ? usleep_range_state+0x8b/0x8b __wait_for_common+0x91/0xe4 wait_for_common+0x15/0x1b wait_for_completion+0x17/0x1f __synchronize_srcu+0x10b/0x122 ? rcu_tasks_pregp_step+0xf/0xf synchronize_srcu+0xa7/0xb3 srcu_torture_synchronize+0x12/0x16 [rcutorture] rcu_torture_fakewriter+0x330/0x351 [rcutorture] kthread+0x142/0x147 ? srcu_read_delay+0x55/0x55 [rcutorture] ? kthread_is_per_cpu+0x1c/0x1c ret_from_fork+0x1e/0x37 ? kthread_is_per_cpu+0x1c/0x1c ret_from_fork_asm+0x12/0x20 entry_INT80_32+0x10c/0x10c INFO: task rcu_torture_fak:394 blocked for more than 491 seconds. Tainted: G W 6.14.0-rc1-00087-g84e0a11498cf #1 "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. task:rcu_torture_fak state:D stack:0 pid:394 tgid:394 ppid:2 task_flags:0x208040 flags:0x00004000 Call Trace: __schedule+0x3d7/0x409 schedule+0x42/0x5e schedule_timeout+0x2f/0xb9 ? usleep_range_state+0x8b/0x8b __wait_for_common+0x91/0xe4 wait_for_common+0x15/0x1b wait_for_completion+0x17/0x1f __synchronize_srcu+0x10b/0x122 ? rcu_tasks_pregp_step+0xf/0xf synchronize_srcu_expedited+0x1a/0x21 srcu_torture_synchronize_expedited+0x12/0x16 [rcutorture] rcu_torture_fakewriter+0x135/0x351 [rcutorture] kthread+0x142/0x147 ? srcu_read_delay+0x55/0x55 [rcutorture] ? kthread_is_per_cpu+0x1c/0x1c ret_from_fork+0x1e/0x37 ? kthread_is_per_cpu+0x1c/0x1c ret_from_fork_asm+0x12/0x20 entry_INT80_32+0x10c/0x10c INFO: task rcu_torture_fak:398 blocked for more than 491 seconds. Tainted: G W 6.14.0-rc1-00087-g84e0a11498cf #1 "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. task:rcu_torture_fak state:D stack:0 pid:398 tgid:398 ppid:2 task_flags:0x208040 flags:0x00004000 Call Trace: __schedule+0x3d7/0x409 schedule+0x42/0x5e schedule_timeout+0x2f/0xb9 ? usleep_range_state+0x8b/0x8b __wait_for_common+0x91/0xe4 wait_for_common+0x15/0x1b wait_for_completion+0x17/0x1f __synchronize_srcu+0x10b/0x122 ? rcu_tasks_pregp_step+0xf/0xf synchronize_srcu+0xa7/0xb3 srcu_torture_synchronize+0x12/0x16 [rcutorture] rcu_torture_fakewriter+0x330/0x351 [rcutorture] kthread+0x142/0x147 ? srcu_read_delay+0x55/0x55 [rcutorture] ? kthread_is_per_cpu+0x1c/0x1c ret_from_fork+0x1e/0x37 ? kthread_is_per_cpu+0x1c/0x1c ret_from_fork_asm+0x12/0x20 entry_INT80_32+0x10c/0x10c INFO: task rcu_torture_fwd:416 blocked for more than 491 seconds. Tainted: G W 6.14.0-rc1-00087-g84e0a11498cf #1 "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. task:rcu_torture_fwd state:D stack:0 pid:416 tgid:416 ppid:2 task_flags:0x208040 flags:0x00024000 Call Trace: __schedule+0x3d7/0x409 schedule+0x42/0x5e schedule_timeout+0x2f/0xb9 ? usleep_range_state+0x8b/0x8b __wait_for_common+0x91/0xe4 wait_for_common+0x15/0x1b wait_for_completion+0x17/0x1f __synchronize_srcu+0x10b/0x122 ? rcu_tasks_pregp_step+0xf/0xf synchronize_srcu+0xa7/0xb3 srcu_torture_synchronize+0x12/0x16 [rcutorture] rcu_torture_fwd_prog_cr+0x55/0x2cf [rcutorture] ? vprintk+0x13/0x17 ? _printk+0x14/0x16 rcu_torture_fwd_prog+0x106/0x196 [rcutorture] kthread+0x142/0x147 ? rcu_torture_fwd_prog_cr+0x2cf/0x2cf [rcutorture] ? kthread_is_per_cpu+0x1c/0x1c ret_from_fork+0x1e/0x37 ? kthread_is_per_cpu+0x1c/0x1c ret_from_fork_asm+0x12/0x20 entry_INT80_32+0x10c/0x10c INFO: task rmmod:838 blocked for more than 491 seconds. Tainted: G W 6.14.0-rc1-00087-g84e0a11498cf #1 "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. task:rmmod state:D stack:0 pid:838 tgid:838 ppid:375 task_flags:0x400100 flags:0x00004000 Call Trace: __schedule+0x3d7/0x409 schedule+0x42/0x5e schedule_timeout+0x2f/0xb9 ? usleep_range_state+0x8b/0x8b __wait_for_common+0x91/0xe4 wait_for_common+0x15/0x1b wait_for_completion+0x17/0x1f kthread_stop+0xaa/0x111 _torture_stop_kthread+0x3c/0x50 [torture] rcu_torture_cleanup+0x1b8/0x914 [rcutorture] ? __mutex_unlock_slowpath+0x25/0x1ae ? lockdep_assert_rq_held+0x21/0x21 __do_sys_delete_module+0xf9/0x201 __ia32_sys_delete_module+0xf/0x11 ia32_sys_call+0x4c1/0x10e2 __do_fast_syscall_32+0xa4/0xc7 do_fast_syscall_32+0x28/0x4f do_SYSENTER_32+0x15/0x19 entry_SYSENTER_32+0xa2/0x102 EIP: 0xb7efe579 EAX: ffffffda EBX: 0048a37c ECX: 00000800 EDX: 0046f939 ESI: 0048a340 EDI: 0048a340 EBP: bfcb6c19 ESP: bfcb5f98 DS: 007b ES: 007b FS: 0000 GS: 0000 SS: 007b EFLAGS: 00200202 Showing all locks held in the system: 1 lock held by khungtaskd/24: #0: c20c0f60 (rcu_read_lock){....}-{1:3}, at: rcu_lock_acquire+0x3/0x27 1 lock held by in:imklog/150: 1 lock held by dmesg/305: ============================================= INFO: task rcu_torture_fak:389 blocked for more than 983 seconds. Tainted: G W 6.14.0-rc1-00087-g84e0a11498cf #1 "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. task:rcu_torture_fak state:D stack:0 pid:389 tgid:389 ppid:2 task_flags:0x208040 flags:0x00004000 Call Trace: __schedule+0x3d7/0x409 schedule+0x42/0x5e schedule_timeout+0x2f/0xb9 ? usleep_range_state+0x8b/0x8b __wait_for_common+0x91/0xe4 wait_for_common+0x15/0x1b wait_for_completion+0x17/0x1f __synchronize_srcu+0x10b/0x122 ? rcu_tasks_pregp_step+0xf/0xf synchronize_srcu_expedited+0x1a/0x21 srcu_torture_synchronize_expedited+0x12/0x16 [rcutorture] rcu_torture_fakewriter+0x135/0x351 [rcutorture] kthread+0x142/0x147 ? srcu_read_delay+0x55/0x55 [rcutorture] ? kthread_is_per_cpu+0x1c/0x1c ret_from_fork+0x1e/0x37 ? kthread_is_per_cpu+0x1c/0x1c ret_from_fork_asm+0x12/0x20 entry_INT80_32+0x10c/0x10c INFO: task rcu_torture_fak:390 blocked for more than 983 seconds. Tainted: G W 6.14.0-rc1-00087-g84e0a11498cf #1 "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. task:rcu_torture_fak state:D stack:0 pid:390 tgid:390 ppid:2 task_flags:0x208040 flags:0x00004000 Call Trace: __schedule+0x3d7/0x409 schedule+0x42/0x5e schedule_timeout+0x2f/0xb9 ? usleep_range_state+0x8b/0x8b __wait_for_common+0x91/0xe4 wait_for_common+0x15/0x1b wait_for_completion+0x17/0x1f __synchronize_srcu+0x10b/0x122 ? rcu_tasks_pregp_step+0xf/0xf synchronize_srcu+0xa7/0xb3 srcu_torture_synchronize+0x12/0x16 [rcutorture] rcu_torture_fakewriter+0x330/0x351 [rcutorture] kthread+0x142/0x147 ? srcu_read_delay+0x55/0x55 [rcutorture] ? kthread_is_per_cpu+0x1c/0x1c ret_from_fork+0x1e/0x37 ? kthread_is_per_cpu+0x1c/0x1c ret_from_fork_asm+0x12/0x20 entry_INT80_32+0x10c/0x10c INFO: task rcu_torture_fak:394 blocked for more than 983 seconds. Tainted: G W 6.14.0-rc1-00087-g84e0a11498cf #1 "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. task:rcu_torture_fak state:D stack:0 pid:394 tgid:394 ppid:2 task_flags:0x208040 flags:0x00004000 Call Trace: __schedule+0x3d7/0x409 schedule+0x42/0x5e schedule_timeout+0x2f/0xb9 ? usleep_range_state+0x8b/0x8b __wait_for_common+0x91/0xe4 wait_for_common+0x15/0x1b wait_for_completion+0x17/0x1f __synchronize_srcu+0x10b/0x122 ? rcu_tasks_pregp_step+0xf/0xf synchronize_srcu_expedited+0x1a/0x21 srcu_torture_synchronize_expedited+0x12/0x16 [rcutorture] rcu_torture_fakewriter+0x135/0x351 [rcutorture] kthread+0x142/0x147 ? srcu_read_delay+0x55/0x55 [rcutorture] ? kthread_is_per_cpu+0x1c/0x1c ret_from_fork+0x1e/0x37 ? kthread_is_per_cpu+0x1c/0x1c ret_from_fork_asm+0x12/0x20 entry_INT80_32+0x10c/0x10c INFO: task rcu_torture_fak:398 blocked for more than 983 seconds. Tainted: G W 6.14.0-rc1-00087-g84e0a11498cf #1 "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. task:rcu_torture_fak state:D stack:0 pid:398 tgid:398 ppid:2 task_flags:0x208040 flags:0x00004000 Call Trace: __schedule+0x3d7/0x409 schedule+0x42/0x5e schedule_timeout+0x2f/0xb9 ? usleep_range_state+0x8b/0x8b __wait_for_common+0x91/0xe4 wait_for_common+0x15/0x1b wait_for_completion+0x17/0x1f __synchronize_srcu+0x10b/0x122 ? rcu_tasks_pregp_step+0xf/0xf synchronize_srcu+0xa7/0xb3 srcu_torture_synchronize+0x12/0x16 [rcutorture] rcu_torture_fakewriter+0x330/0x351 [rcutorture] kthread+0x142/0x147 ? srcu_read_delay+0x55/0x55 [rcutorture] ? kthread_is_per_cpu+0x1c/0x1c ret_from_fork+0x1e/0x37 ? kthread_is_per_cpu+0x1c/0x1c ret_from_fork_asm+0x12/0x20 entry_INT80_32+0x10c/0x10c Future hung task reports are suppressed, see sysctl kernel.hung_task_warnings Showing all locks held in the system: 1 lock held by khungtaskd/24: #0: c20c0f60 (rcu_read_lock){....}-{1:3}, at: rcu_lock_acquire+0x3/0x27 1 lock held by in:imklog/150: 1 lock held by dmesg/305: ============================================= BUG: kernel hang in test stage Kboot worker: lkp-worker50 Elapsed time: 3120 kvm=( qemu-system-i386 -enable-kvm -cpu SandyBridge -kernel $kernel -initrd initrd-vm-meta-274.cgz -m 4096 -smp 2 -device e1000,netdev=net0 -netdev user,id=net0,hostfwd=tcp::32032-:22 -boot order=nc -no-reboot -device i6300esb -device pci-testdev -watchdog-action debug -rtc base=localtime -serial stdio -display none -monitor null ) append=( ip=::::vm-meta-274::dhcp root=/dev/ram0 RESULT_ROOT=/result/rcutorture/300s-cpuhotplug-srcu/vm-snb-i386/debian-11.1-i386-20220923.cgz/i386-randconfig-012-20250327/gcc-12/84e0a11498cf967ae236f1feaf8b8b25ea738f2d/0 BOOT_IMAGE=/pkg/linux/i386-randconfig-012-20250327/gcc-12/84e0a11498cf967ae236f1feaf8b8b25ea738f2d/vmlinuz-6.14.0-rc1-00087-g84e0a11498cf branch=linux-devel/devel-hourly-20250326-215618 job=/job-script user=lkp ARCH=i386 kconfig=i386-randconfig-012-20250327 commit=84e0a11498cf967ae236f1feaf8b8b25ea738f2d mem=4G intremap=posted_msi vmalloc=256M initramfs_async=0 page_owner=on carrier_timeout=60 rcupdate.rcu_self_test=0 max_uptime=1200 result_service=tmpfs selinux=0 debug apic=debug sysrq_always_enabled rcupdate.rcu_cpu_stall_timeout=100 net.ifnames=0 printk.devkmsg=on panic=-1 softlockup_panic=1 nmi_watchdog=panic oops=panic load_ramdisk=2 prompt_ramdisk=0 drbd.minor_count=8 systemd.log_level=err ignore_loglevel console=tty0 earlyprintk=ttyS0,115200 console=ttyS0,115200 vga=normal rw rcuperf.shutdown=0 rcuscale.shutdown=0 refscale.shutdown=0 watchdog_thresh=240 audit=0 kunit.enable=0 ia32_emulation=on riscv_isa_fallback=1 ) "${kvm[@]}" -append "${append[*]}"