FVP boot failed with linux next 20220712. Reported-by: Linux Kernel Functional Testing <lkft@xxxxxxxxxx> Reported-by: Naresh Kamboju <naresh.kamboju@xxxxxxxxxx> Boot log: Starting kernel ... [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0x410fd0f0] [ 0.000000] Linux version 5.19.0-rc6-next-20220711 (tuxmake@tuxmake) (aarch64-linux-gnu-gcc (Debian 11.3.0-3) 11.3.0, GNU ld (GNU Binutils for Debian) 2.38) #1 SMP PREEMPT @1657539508 [ 0.000000] Machine model: FVP Base RevC [ 0.000000] earlycon: pl11 at MMIO 0x000000001c090000 (options '') [ 0.000000] printk: bootconsole [pl11] enabled [ 0.000000] efi: UEFI not found. [ 0.000000] Reserved memory: created DMA memory pool at 0x0000000018000000, size 8 MiB [ 0.000000] OF: reserved mem: initialized node vram@18000000, compatible id shared-dma-pool [ 0.000000] NUMA: No NUMA configuration found [ 0.000000] NUMA: Faking a node at [mem 0x0000000080000000-0x00000008ffffffff] [ 0.000000] NUMA: NODE_DATA [mem 0x8ff7f6b40-0x8ff7f8fff] [ 0.000000] Zone ranges: [ 0.000000] DMA [mem 0x0000000080000000-0x00000000ffffffff] [ 0.000000] DMA32 empty [ 0.000000] Normal [mem 0x0000000100000000-0x00000008ffffffff] [ 0.000000] Movable zone start for each node [ 0.000000] Early memory node ranges [ 0.000000] node 0: [mem 0x0000000080000000-0x00000000feffffff] [ 0.000000] node 0: [mem 0x0000000880000000-0x00000008ffffffff] [ 0.000000] Initmem setup node 0 [mem 0x0000000080000000-0x00000008ffffffff] [ 0.000000] On node 0, zone Normal: 4096 pages in unavailable ranges [ 0.000000] cma: Reserved 32 MiB at 0x00000000fd000000 [ 0.000000] psci: probing for conduit method from DT. [ 0.000000] psci: PSCIv1.1 detected in firmware. [ 0.000000] psci: Using standard PSCI v0.2 function IDs [ 0.000000] psci: MIGRATE_INFO_TYPE not supported. [ 0.000000] psci: SMC Calling Convention v1.2 [ 0.000000] percpu: Embedded 30 pages/cpu s82856 r8192 d31832 u122880 [ 0.000000] pcpu-alloc: s82856 r8192 d31832 u122880 alloc=30*4096 [ 0.000000] pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 [0] 4 [0] 5 [0] 6 [0] 7 [ 0.000000] Detected PIPT I-cache on CPU0 [ 0.000000] CPU features: detected: Branch Target Identification [ 0.000000] CPU features: detected: Address authentication (IMP DEF algorithm) [ 0.000000] CPU features: detected: GIC system register CPU interface [ 0.000000] CPU features: detected: Virtualization Host Extensions [ 0.000000] CPU features: detected: Hardware dirty bit management [ 0.000000] CPU features: detected: Spectre-v4 [ 0.000000] alternatives: patching kernel code [ 0.000000] Fallback order for Node 0: 0 [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 1028096 [ 0.000000] Policy zone: Normal [ 0.000000] Kernel command line: console=ttyAMA0 earlycon=pl011,0x1c090000 root=/dev/vda rw ip=dhcp debug user_debug=31 loglevel=9 [ 0.000000] Unknown kernel command line parameters \"user_debug=31\", will be passed to user space. [ 0.000000] Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) [ 0.000000] Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) [ 0.000000] mem auto-init: stack:off, heap alloc:off, heap free:off [ 0.000000] software IO TLB: mapped [mem 0x00000000f9000000-0x00000000fd000000] (64MB) [ 0.000000] Memory: 3947088K/4177920K available (20288K kernel code, 4902K rwdata, 11308K rodata, 11136K init, 955K bss, 198064K reserved, 32768K cma-reserved) [ 0.000000] SLUB: HWalign=128, Order=0-3, MinObjects=0, CPUs=8, Nodes=1 [ 0.000000] ftrace: allocating 65660 entries in 257 pages [ 0.000000] ------------[ ftrace bug ]------------ [ 0.000000] ftrace failed to modify [ 0.000000] [<ffff800009f80030>] udpv6_init+0x8/0x74 [ 0.000000] actual: 00:00:00:00 [ 0.000000] Initializing ftrace call sites [ 0.000000] ftrace record flags: 0 [ 0.000000] (0) [ 0.000000] expected tramp: ffff80000802f3a8 [ 0.000000] ------------[ cut here ]------------ [ 0.000000] WARNING: CPU: 0 PID: 0 at kernel/trace/ftrace.c:2084 ftrace_bug+0x280/0x2b0 [ 0.000000] Modules linked in: [ 0.000000] CPU: 0 PID: 0 Comm: swapper Not tainted 5.19.0-rc6-next-20220711 #1 [ 0.000000] Hardware name: FVP Base RevC (DT) [ 0.000000] pstate: 600000c9 (nZCv daIF -PAN -UAO -TCO -DIT -SSBS BTYPE=--) [ 0.000000] pc : ftrace_bug+0x280/0x2b0 [ 0.000000] lr : ftrace_bug+0x280/0x2b0 [ 0.000000] sp : ffff80000a9e3d50 [ 0.000000] x29: ffff80000a9e3d50 x28: 0000000000000000 x27: ffff80000a9eed28 [ 0.000000] x26: 0000000000000052 x25: ffff80000a118008 x24: ffff80000aee3000 [ 0.000000] x23: 00000000ffffffea x22: ffff800009befc30 x21: ffff80000a9eed28 [ 0.000000] x20: ffff000800005510 x19: ffff80000a9ee000 x18: ffffffffffffffff [ 0.000000] x17: 000000000000003f x16: 0000000000000008 x15: ffff80008a9e3a47 [ 0.000000] x14: 0000000000000000 x13: 3861336632303830 x12: 3030303866666666 [ 0.000000] x11: 203a706d61727420 x10: ffff80000aa6a960 x9 : ffff800008121910 [ 0.000000] x8 : 00000000ffffefff x7 : ffff80000aa6a960 x6 : 0000000000000000 [ 0.000000] x5 : 000000000000bff4 x4 : 0000000000000000 x3 : 0000000000000000 [ 0.000000] x2 : 0000000000000000 x1 : ffff80000a9f9e40 x0 : 0000000000000022 [ 0.000000] Call trace: [ 0.000000] ftrace_bug+0x280/0x2b0 [ 0.000000] ftrace_process_locs+0x310/0x400 [ 0.000000] ftrace_init+0xa0/0x16c [ 0.000000] start_kernel+0x45c/0x738 [ 0.000000] __primary_switched+0xb8/0xc0 [ 0.000000] ---[ end trace 0000000000000000 ]--- [ 0.000000] ftrace: allocated 257 pages with 2 groups [ 0.0# 00000] trace event string verifier disabled [ 0.000000] rcu: Preemptible hierarchical RCU implementation. [ 0.000000] rcu: RCU event tracing is enabled. [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=256 to nr_cpu_ids=8. [ 0.000000] Trampoline variant of Tasks RCU enabled. [ 0.000000] Rude variant of Tasks RCU enabled. [ 0.000000] Tracing variant of Tasks RCU enabled. [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies. [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=8 [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 [ 0.000000] GICv3: GIC: Using split EOI/Deactivate mode [ 0.000000] GICv3: 224 SPIs implemented [ 0.000000] GICv3: 0 Extended SPIs implemented [ 0.000000] Root IRQ handler: gic_handle_irq [ 0.000000] GICv3: GICv3 features: 16 PPIs [ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x000000002f100000 [ 0.000000] ITS [mem 0x2f020000-0x2f03ffff] [ 0.000000] ITS@0x000000002f020000: allocated 8192 Devices @8800f0000 (indirect, esz 8, psz 64K, shr 1) [ 0.000000] ITS@0x000000002f020000: allocated 8192 Virtual CPUs @880200000 (indirect, esz 8, psz 64K, shr 1) [ 0.000000] ITS@0x000000002f020000: allocated 8192 Interrupt Collections @880210000 (flat, esz 8, psz 64K, shr 1) [ 0.000000] GICv3: using LPI property table @0x0000000880220000 [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000880230000 [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention. [ 0.000000] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____) [ 0.000000] timer_sp804: timer clock not found: -517 [ 0.000000] timer_sp804: arm,sp804 clock not found: -2 [ 0.000000] Failed to initialize '/bus@8000000/motherboard-bus/iofpga-bus@300000000/timer@110000': -22 [ 0.000000] timer_sp804: timer clock not found: -517 [ 0.000000] timer_sp804: arm,sp804 clock not found: -2 [ 0.000000] Failed to initialize '/bus@8000000/motherboard-bus/iofpga-bus@300000000/timer@120000': -22 [ 0.000000] arch_timer: cp15 timer(s) running at 100.00MHz (phys). [ 0.000000] clocksource: arch_sys_counter: mask: 0x1ffffffffffffff max_cycles: 0x171024e7e0, max_idle_ns: 440795205315 ns [ 0.000009] sched_clock: 57 bits at 100MHz, resolution 10ns, wraps every 4398046511100ns [ 0.001009] random: crng init done [ 0.002015] Console: colour dummy device 80x25 [ 0.002709] Calibrating delay loop (skipped), value calculated using timer frequency.. 200.00 BogoMIPS (lpj=400000) [ 0.002949] pid_max: default: 32768 minimum: 301 [ 0.003909] LSM: Security Framework initializing [ 0.004751] Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) [ 0.004915] Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) [ 0.012948] cacheinfo: Unable to detect cache hierarchy for CPU 0 [ 0.013122] Early cacheinfo failed, ret = -2 [ 0.023187] cblist_init_generic: Setting adjustable number of callback queues. [ 0.023309] cblist_init_generic: Setting shift to 3 and lim to 1. [ 0.024609] cblist_init_generic: Setting shift to 3 and lim to 1. [ 0.025809] cblist_init_generic: Setting shift to 3 and lim to 1. [ 0.028426] rcu: Hierarchical SRCU implementation. [ 0.028590] rcu: Max phase no-delay instances is 1000. [ 0.042209] Platform MSI: msi-controller@2f020000 domain created [ 0.043171] PCI/MSI: /interrupt-controller@2f000000/msi-controller@2f020000 domain created [ 0.044359] fsl-mc MSI: msi-controller@2f020000 domain created [ 0.052019] EFI services will not be available. [ 0.057426] smp: Bringing up secondary CPUs ... [ 0.064109] Detected PIPT I-cache on CPU1 [ 0.064230] GICv3: CPU1: found redistributor 100 region 0:0x000000002f120000 [ 0.064316] GICv3: CPU1: using allocated LPI pending table @0x0000000880240000 [ 0.064509] CPU1: Booted secondary processor 0x0000000100 [0x410fd0f0] [ 0.072609] Detected PIPT I-cache on CPU2 [ 0.072809] GICv3: CPU2: found redistributor 200 region 0:0x000000002f140000 [ 0.072809] GICv3: CPU2: using allocated LPI pending table @0x0000000880250000 [ 0.073009] CPU2: Booted secondary processor 0x0000000200 [0x410fd0f0] [ 0.081109] Detected PIPT I-cache on CPU3 [ 0.081209] GICv3: CPU3: found redistributor 300 region 0:0x000000002f160000 [ 0.081346] GICv3: CPU3: using allocated LPI pending table @0x0000000880260000 [ 0.081510] CPU3: Booted secondary processor 0x0000000300 [0x410fd0f0] [ 0.089809] Detected PIPT I-cache on CPU4 [ 0.090009] GICv3: CPU4: found redistributor 10000 region 0:0x000000002f180000 [ 0.090030] GICv3: CPU4: using allocated LPI pending table @0x0000000880270000 [ 0.090261] CPU4: Booted secondary processor 0x0000010000 [0x410fd0f0] [ 0.098509] Detected PIPT I-cache on CPU5 [ 0.098678] GICv3: CPU5: found redistributor 10100 region 0:0x000000002f1a0000 [ 0.098713] GICv3: CPU5: using allocated LPI pending table @0x0000000880280000 [ 0.098909] CPU5: Booted secondary processor 0x0000010100 [0x410fd0f0] [ 0.107010] Detected PIPT I-cache on CPU6 [ 0.107233] GICv3: CPU6: found redistributor 10200 region 0:0x000000002f1c0000 [ 0.107315] GICv3: CPU6: using allocated LPI pending table @0x0000000880290000 [ 0.107441] CPU6: Booted secondary processor 0x0000010200 [0x410fd0f0] [ 0.115909] Detected PIPT I-cache on CPU7 [ 0.116119] GICv3: CPU7: found redistributor 10300 region 0:0x000000002f1e0000 [ 0.116119] GICv3: CPU7: using allocated LPI pending table @0x00000008802a0000 [ 0.116326] CPU7: Booted secondary processor 0x0000010300 [0x410fd0f0] [ 0.117909] smp: Brought up 1 node, 8 CPUs [ 0.119709] SMP: Total of 8 processors activated. [ 0.119909] CPU features: detected: 32-bit EL0 Support [ 0.120023] CPU features: detected: 32-bit EL1 Support [ 0.120176] CPU features: detected: ARMv8.4 Translation Table Level [ 0.120326] CPU features: detected: Data cache clean to the PoU not required for I/D coherence [ 0.120457] CPU features: detected: Common not Private translations [ 0.120609] CPU features: detected: CRC32 instructions [ 0.120717] CPU features: detected: E0PD [ 0.120832] CPU features: detected: Enhanced Counter Virtualization [ 0.121009] CPU features: detected: Generic authentication (IMP DEF algorithm) [ 0.121151] CPU features: detected: RCpc load-acquire (LDAPR) [ 0.121323] CPU features: detected: LSE atomic instructions [ 0.121809] CPU features: detected: Privileged Access Never [ 0.121932] CPU features: detected: RAS Extension Support [ 0.122060] CPU features: detected: Random Number Generator [ 0.122209] CPU features: detected: Speculation barrier (SB) [ 0.122309] CPU features: detected: Stage-2 Force Write-Back [ 0.122470] CPU features: detected: TLB range maintenance instructions [ 0.122634] CPU features: detected: Speculative Store Bypassing Safe (SSBS) [ 0.122716] CPU features: detected: Scalable Vector Extension [ 0.413916] SVE: maximum available vector length 64 bytes per vector [ 0.414109] SVE: default vector length 64 bytes per vector [ 0.430509] CPU features: No Cache Writeback Granule information, assuming 128 [ 0.430655] CPU: All CPU(s) started at EL2 [ 0.449009] devtmpfs: initialized [ 0.476391] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns [ 0.476610] futex hash table entries: 2048 (order: 5, 131072 bytes, linear) [ 0.484509] pinctrl core: initialized pinctrl subsystem [ 0.499875] DMI not present or invalid. [ 0.500613] ------------[ cut here ]------------ [ 0.500709] kernel BUG at arch/arm64/kernel/traps.c:497! [ 0.500777] Internal error: Oops - BUG: 0 [#1] PREEMPT SMP [ 0.500860] Modules linked in: [ 0.500947] CPU: 0 PID: 1 Comm: swapper/0 Tainted: G W 5.19.0-rc6-next-20220711 #1 [ 0.501034] Hardware name: FVP Base RevC (DT) [ 0.501109] pstate: 00400009 (nzcv daif +PAN -UAO -TCO -DIT -SSBS BTYPE=--) [ 0.501209] pc : do_undefinstr+0x284/0x2b4 [ 0.501381] lr : do_undefinstr+0x150/0x2b4 [ 0.501509] sp : ffff80000affbbc0 [ 0.501509] x29: ffff80000affbbc0 x28: ffff000800330000 x27: ffff800009f00444 [ 0.501759] x26: ffff800009fe6038 x25: 0000000000000001 x24: ffff80000a115968 [ 0.501923] x23: 0000000040400009 x22: ffff800009f80de0 x21: ffff80000affbd90 [ 0.502109] x20: 0000000001000000 x19: ffff80000affbc40 x18: ffffffffffffffff [ 0.502309] x17: 000000000000003f x16: 000000000000000a x15: ffff0008002b1a1c [ 0.502422] x14: ffffffffffffffff x13: ffff0008002b1268 x12: 20726f20746e6573 [ 0.502683] x11: ffff80000aa12950 x10: 0000000000000073 x9 : ffff8000093bcd6c [ 0.502856] x8 : 000000003d4d554e x7 : ffff80000affbce0 x6 : 0000000000000001 [ 0.503030] x5 : ffff80000a9ef000 x4 : ffff80000a9ef2e0 x3 : 0000000000000000 [ 0.503234] x2 : ffff000800330000 x1 : ffff000800330000 x0 : 0000000040400009 [ 0.503409] Call trace: [ 0.503480] do_undefinstr+0x284/0x2b4 [ 0.503562] el1_undef+0x30/0x60 [ 0.503709] el1h_64_sync_handler+0x84/0xd0 [ 0.503811] el1h_64_sync+0x64/0x68 [ 0.503909] net_sysctl_init+0x8/0x7c [ 0.504053] do_one_initcall+0x50/0x2b0 [ 0.504158] kernel_init_freeable+0x24c/0x2d4 [ 0.504245] kernel_init+0x30/0x140 [ 0.504381] ret_from_fork+0x10/0x20 [ 0.504509] Code: 17ffff98 a9425bf5 17ffffbb a9025bf5 (d4210000) [ 0.504627] ---[ end trace 0000000000000000 ]--- [ 0.504678] note: sw# apper/0[1] exited with preempt_count 1 [ 0.504852] Kernel panic - not syncing: Attempted to kill init! exitcode=0x0000000b [ 0.504939] SMP: stopping secondary CPUs [ 0.505209] ---[ end Kernel panic - not syncing: Attempted to kill init! exitcode=0x0000000b ]--- Build: https://qa-reports.linaro.org/lkft/linux-next-master/build/next-20220711/testrun/10743317/suite/log-parser-test/tests/ https://builds.tuxbuild.com/2BnQMpJj3kDTJXoCwd2pY5gW9CN/ Best regards Naresh Kamboju -- Linaro LKFT https://lkft.linaro.org
