Hi Al,
Where does it "stall"? What's the last message you see? And if
you're not
using earlycon, please do so; that will help determine how far the boot is getting.
If I boot it with acpi=force, no output. Does my usage below look correct?
GRUB_CMDLINE_LINUX="earlycon=uart8250,mmio32,0x1c021000 acpi=force console=ttyS0,115200 loglevel=7"
How were those checked?
In setup_arch() I was calling disable_acpi() right after the acpi_boot_table_init() call as that makes the system get to the prompt.
I am about to build the kernel with your .config and test it on one of Mustangs in the Linaro Austin lab. The Boot firmware version is 1.1.0-rh-0.15.
Itaru
Hi Al,
Even with your .config the HEAD of acpi-topic-mustang does not boot with acpi=force. Here are the messages appear on the console:
EFI stub: Booting Linux Kernel... EFI stub: Using DTB from configuration table EFI stub: Exiting boot services and installing virtual address map... XhcClearBiosOwnership: called to clear BIOS ownership XhcClearBiosOwnership: called to clear BIOS ownership L3c Cache: 8MB
On 8/18/15 6:58 AM, Itaru Kitayama wrote:
Hi Al,
Where does it "stall"? What's the last message you see? And if
you're not
using earlycon, please do so; that will help determine how far the boot is getting.
If I boot it with acpi=force, no output. Does my usage below look correct?
GRUB_CMDLINE_LINUX="earlycon=uart8250,mmio32,0x1c021000 acpi=force console=ttyS0,115200 loglevel=7"
How were those checked?
In setup_arch() I was calling disable_acpi() right after the acpi_boot_table_init() call as that makes the system get to the prompt.
I am about to build the kernel with your .config and test it on one of Mustangs in the Linaro Austin lab. The Boot firmware version is 1.1.0-rh-0.15.
Itaru
Hi Al,
Below is a dmesg excerpt corresponding to the acpi_boot_table_init().
[ 0.000000] ACPI: RSDP 0x00000047FA397014 000024 (v02 APM ) [ 0.000000] ACPI: XSDT 0x00000047FA3960E8 000064 (v01 APM XGENE 00000003 01000013) [ 0.000000] ACPI: FACP 0x00000047FA38E000 00010C (v05 APM XGENE 00000003 INTL 20141107) [ 0.000000] ACPI: DSDT 0x00000047FA38F000 003D82 (v05 APM APM88xxx 00000001 INTL 20141107) [ 0.000000] ACPI: DBG2 0x00000047FA393000 0000AA (v00 APMC0D XGENEDBG 00000000 INTL 20141107) [ 0.000000] ACPI: GTDT 0x00000047FA38C000 0000E0 (v02 APM XGENE 00000001 INTL 20141107) [ 0.000000] ACPI: MCFG 0x00000047FA38B000 00003C (v01 APM XGENE 00000002 INTL 20141107) [ 0.000000] ACPI: SPCR 0x00000047FA38A000 000050 (v02 APMC0D XGENESPC 00000000 INTL 20141107) [ 0.000000] ACPI: SSDT 0x00000047FA389000 00002D (v02 APM XGENE 00000001 INTL 20141107) [ 0.000000] ACPI: APIC 0x00000047FA388000 0002A4 (v03 APM XGENE 00000003 01000013) [ 0.000000] ACPI: SSDT 0x00000047FA387000 000063 (v02 REDHAT MACADDRS 00000001 01000013)
I've been testing the Mustang topic branch on the platform with the Rev 1 CPUs with 32 GB memory.
On 8/18/15 8:02 AM, Itaru Kitayama wrote:
Hi Al,
Even with your .config the HEAD of acpi-topic-mustang does not boot with acpi=force. Here are the messages appear on the console:
EFI stub: Booting Linux Kernel... EFI stub: Using DTB from configuration table EFI stub: Exiting boot services and installing virtual address map... XhcClearBiosOwnership: called to clear BIOS ownership XhcClearBiosOwnership: called to clear BIOS ownership L3c Cache: 8MB
On 8/18/15 6:58 AM, Itaru Kitayama wrote:
Hi Al,
Where does it "stall"? What's the last message you see? And if
you're not
using earlycon, please do so; that will help determine how far the boot is getting.
If I boot it with acpi=force, no output. Does my usage below look correct?
GRUB_CMDLINE_LINUX="earlycon=uart8250,mmio32,0x1c021000 acpi=force console=ttyS0,115200 loglevel=7"
How were those checked?
In setup_arch() I was calling disable_acpi() right after the acpi_boot_table_init() call as that makes the system get to the prompt.
I am about to build the kernel with your .config and test it on one of Mustangs in the Linaro Austin lab. The Boot firmware version is 1.1.0-rh-0.15.
Itaru
On 08/19/2015 09:29 AM, Itaru Kitayama wrote:
Hi Al,
Below is a dmesg excerpt corresponding to the acpi_boot_table_init().
[ 0.000000] ACPI: RSDP 0x00000047FA397014 000024 (v02 APM ) [ 0.000000] ACPI: XSDT 0x00000047FA3960E8 000064 (v01 APM XGENE 00000003 01000013) [ 0.000000] ACPI: FACP 0x00000047FA38E000 00010C (v05 APM XGENE 00000003 INTL 20141107) [ 0.000000] ACPI: DSDT 0x00000047FA38F000 003D82 (v05 APM APM88xxx 00000001 INTL 20141107) [ 0.000000] ACPI: DBG2 0x00000047FA393000 0000AA (v00 APMC0D XGENEDBG 00000000 INTL 20141107) [ 0.000000] ACPI: GTDT 0x00000047FA38C000 0000E0 (v02 APM XGENE 00000001 INTL 20141107) [ 0.000000] ACPI: MCFG 0x00000047FA38B000 00003C (v01 APM XGENE 00000002 INTL 20141107) [ 0.000000] ACPI: SPCR 0x00000047FA38A000 000050 (v02 APMC0D XGENESPC 00000000 INTL 20141107) [ 0.000000] ACPI: SSDT 0x00000047FA389000 00002D (v02 APM XGENE 00000001 INTL 20141107) [ 0.000000] ACPI: APIC 0x00000047FA388000 0002A4 (v03 APM XGENE 00000003 01000013) [ 0.000000] ACPI: SSDT 0x00000047FA387000 000063 (v02 REDHAT MACADDRS 00000001 01000013)
I've been testing the Mustang topic branch on the platform with the Rev 1 CPUs with 32 GB memory.
Okay. That's interesting output, but it unfortunately tells me nothing. Those messages only indicate that the kernel has found ACPI tables with those names, at those addresses, and that they have valid signatures in them. The content of the tables could be completely bogus, unless the kernel command acpi_force_table_verification was used, which will at least verify the checksums in the tables are correct, even if the content is not examined. I can deduce that acpi=force has been used, but that's about it.
What would be more useful is the remainder of the dmesg/console output so we can tell how far the kernel got in using the ACPI tables that it found, all the way up to the very last message out.
On 8/18/15 8:02 AM, Itaru Kitayama wrote:
Hi Al,
Even with your .config the HEAD of acpi-topic-mustang does not boot with acpi=force. Here are the messages appear on the console:
EFI stub: Booting Linux Kernel... EFI stub: Using DTB from configuration table EFI stub: Exiting boot services and installing virtual address map... XhcClearBiosOwnership: called to clear BIOS ownership XhcClearBiosOwnership: called to clear BIOS ownership L3c Cache: 8MB
On 8/18/15 6:58 AM, Itaru Kitayama wrote:
Hi Al,
Where does it "stall"? What's the last message you see? And if you're not using earlycon, please do so; that will help determine how far the boot is getting.
If I boot it with acpi=force, no output. Does my usage below look correct?
GRUB_CMDLINE_LINUX="earlycon=uart8250,mmio32,0x1c021000 acpi=force console=ttyS0,115200 loglevel=7"
How were those checked?
In setup_arch() I was calling disable_acpi() right after the>>> Itaru
Linaro-acpi mailing list Linaro-acpi@lists.linaro.org https://lists.linaro.org/mailman/listinfo/linaro-acpi
acpi_boot_table_init() call as that makes the system get to the prompt.
I am about to build the kernel with your .config and test it on one of Mustangs in the Linaro Austin lab. The Boot firmware version is 1.1.0-rh-0.15.
I may have mentioned this before (or not), but I think we need to get this Mustang updated to newer firmware; while that version is okay, the preferred version is the latest 1.15.x from APM (x = 18 or greater, I believe). We may have to figure out how to get the Austin lab updated somehow.
Hi Al,
Below is the dmesg. In the modified kernel I call disable_acpi() right after acpi_boot_table_init(). (Otherwise, no meaningful output is obtained even with earlycon)
[ 0.000000] Booting Linux on physical CPU 0x0 [ 0.000000] Initializing cgroup subsys cpuset [ 0.000000] Initializing cgroup subsys cpu [ 0.000000] Initializing cgroup subsys cpuacct [ 0.000000] Linux version 4.2.0-rc5+ (root@r2-a21) (gcc version 5.1.1 20150618 (Red Hat 5.1.1-4) (GCC) ) #23 SMP Mon Aug 24 20:29:06 EDT 2015 [ 0.000000] CPU: AArch64 Processor [500f0001] revision 1 [ 0.000000] Detected PIPT I-cache on CPU0 [ 0.000000] earlycon: Early serial console at MMIO32 0x1c021000 (options '') [ 0.000000] bootconsole [uart0] enabled [ 0.000000] efi: Getting EFI parameters from FDT: [ 0.000000] efi: System Table: 0x00000047ff54df18 [ 0.000000] efi: MemMap Address: 0x00000047f8684018 [ 0.000000] efi: MemMap Size: 0x00000750 [ 0.000000] efi: MemMap Desc. Size: 0x00000030 [ 0.000000] efi: MemMap Desc. Version: 0x00000001 [ 0.000000] EFI v2.40 by X-Gene Mustang Board EFI Mar 13 2015 14:20:53 [ 0.000000] efi: ACPI=0x47fa397000 ACPI 2.0=0x47fa397014 SMBIOS 3.0=0x47fa395000 [ 0.000000] Processing EFI memory map: [ 0.000000] 0x004000000000-0x004000007fff [Conventional Memory| | | | | |WB|WT|WC|UC] [ 0.000000] Ignoring memory block 0x4000000000 - 0x4000010000
[ 0.000000] 0x004000008000-0x00400000ffff [Reserved | | | | | |WB|WT|WC|UC] [ 0.000000] Ignoring memory block 0x4000000000 - 0x4000010000 [ 0.000000] * [ 0.000000] 0x004000010000-0x00400001ffff [Loader Data | | | | | |WB|WT|WC|UC] [ 0.000000] Ignoring memory block 0x4000010000 - 0x4000020000
[ 0.000000] 0x004000020000-0x0040001fffff [Conventional Memory| | | | | |WB|WT|WC|UC] [ 0.000000] Ignoring memory range 0x4000020000 - 0x4000200000
[ 0.000000] 0x004000200000-0x00400147ffff [Loader Data | | | | | |WB|WT|WC|UC] [ 0.000000] 0x004001480000-0x00401fdfffff [Conventional Memory| | | | | |WB|WT|WC|UC] [ 0.000000] 0x00401fe00000-0x00401fe0ffff [Loader Data | | | | | |WB|WT|WC|UC] [ 0.000000] 0x00401fe10000-0x004793ffffff [Conventional Memory| | | | | |WB|WT|WC|UC] [ 0.000000] 0x004794000000-0x0047f7ffffff [Loader Data | | | | | |WB|WT|WC|UC] [ 0.000000] 0x0047f8000000-0x0047f801ffff [Boot Data | | | | | |WB|WT|WC|UC] [ 0.000000] 0x0047f8020000-0x0047f8683fff [Conventional Memory| | | | | |WB|WT|WC|UC] [ 0.000000] 0x0047f8684000-0x0047f9ffffff [Loader Data | | | | | |WB|WT|WC|UC] [ 0.000000] 0x0047fa000000-0x0047fa0cdfff [Loader Code | | | | | |WB|WT|WC|UC] [ 0.000000] 0x0047fa0ce000-0x0047fa378fff [Boot Code | | | | | |WB|WT|WC|UC] [ 0.000000] 0x0047fa379000-0x0047fa386fff [Runtime Code |RUN| | | | |WB|WT|WC|UC]* [ 0.000000] 0x0047fa387000-0x0047fa38cfff [ACPI Reclaim Memory| | | | | |WB|WT|WC|UC]* [ 0.000000] 0x0047fa38d000-0x0047fa38dfff [ACPI Memory NVS | | | | | |WB|WT|WC|UC]* [ 0.000000] 0x0047fa38e000-0x0047fa393fff [ACPI Reclaim Memory| | | | | |WB|WT|WC|UC]* [ 0.000000] 0x0047fa394000-0x0047fa395fff [Reserved | | | | | |WB|WT|WC|UC]* [ 0.000000] 0x0047fa396000-0x0047fa397fff [ACPI Reclaim Memory| | | | | |WB|WT|WC|UC]* [ 0.000000] 0x0047fa398000-0x0047fa39ffff [Runtime Code |RUN| | | | |WB|WT|WC|UC]* [ 0.000000] 0x0047fa3a0000-0x0047fa3c0fff [Runtime Data |RUN| | | | |WB|WT|WC|UC]* [ 0.000000] 0x0047fa3c1000-0x0047fa3d2fff [Runtime Code |RUN| | | | |WB|WT|WC|UC]* [ 0.000000] 0x0047fa3d3000-0x0047fa534fff [Runtime Data |RUN| | | | |WB|WT|WC|UC]* [ 0.000000] 0x0047fa535000-0x0047fa543fff [Runtime Code |RUN| | | | |WB|WT|WC|UC]* [ 0.000000] 0x0047fa544000-0x0047fb90ffff [Conventional Memory| | | | | |WB|WT|WC|UC] [ 0.000000] 0x0047fb910000-0x0047fbdfcfff [Boot Data | | | | | |WB|WT|WC|UC] [ 0.000000] 0x0047fbdfd000-0x0047fc1affff [Conventional Memory| | | | | |WB|WT|WC|UC] [ 0.000000] 0x0047fc1b0000-0x0047fe092fff [Boot Data | | | | | |WB|WT|WC|UC] [ 0.000000] 0x0047fe093000-0x0047fe11cfff [Conventional Memory| | | | | |WB|WT|WC|UC] [ 0.000000] 0x0047fe11d000-0x0047ff377fff [Boot Data | | | | | |WB|WT|WC|UC] [ 0.000000] 0x0047ff378000-0x0047ff507fff [Boot Code | | | | | |WB|WT|WC|UC] [ 0.000000] 0x0047ff508000-0x0047ff51bfff [Runtime Code |RUN| | | | |WB|WT|WC|UC]* [ 0.000000] 0x0047ff51c000-0x0047ff54dfff [Runtime Data |RUN| | | | |WB|WT|WC|UC]* [ 0.000000] 0x0047ff54e000-0x0047ffffffff [Boot Data | | | | | |WB|WT|WC|UC] [ 0.000000] 0x000010510000-0x000010510fff [Memory Mapped I/O |RUN| | | | | | | | ] [ 0.000000] 0x000010548000-0x000010549fff [Memory Mapped I/O |RUN| | | | | | | | ] [ 0.000000] 0x000017000000-0x000017001fff [Memory Mapped I/O |RUN| | | | | | | | ] [ 0.000000] 0x00001c025000-0x00001c025fff [Memory Mapped I/O |RUN| | | | | | | | ] [ 0.000000] cma: Reserved 512 MiB at 0x00000040e0000000 [ 0.000000] ACPI: Early table checksum verification enabled [ 0.000000] ACPI: RSDP 0x00000047FA397014 000024 (v02 APM ) [ 0.000000] ACPI: XSDT 0x00000047FA3960E8 000064 (v01 APM XGENE 00000003 01000013) [ 0.000000] ACPI: FACP 0x00000047FA38E000 00010C (v05 APM XGENE 00000003 INTL 20141107) [ 0.000000] ACPI: DSDT 0x00000047FA38F000 003D82 (v05 APM APM88xxx 00000001 INTL 20141107) [ 0.000000] ACPI: DBG2 0x00000047FA393000 0000AA (v00 APMC0D XGENEDBG 00000000 INTL 20141107) [ 0.000000] ACPI: GTDT 0x00000047FA38C000 0000E0 (v02 APM XGENE 00000001 INTL 20141107) [ 0.000000] ACPI: MCFG 0x00000047FA38B000 00003C (v01 APM XGENE 00000002 INTL 20141107) [ 0.000000] ACPI: SPCR 0x00000047FA38A000 000050 (v02 APMC0D XGENESPC 00000000 INTL 20141107) [ 0.000000] ACPI: SSDT 0x00000047FA389000 00002D (v02 APM XGENE 00000001 INTL 20141107) [ 0.000000] ACPI: APIC 0x00000047FA388000 0002A4 (v03 APM XGENE 00000003 01000013) [ 0.000000] ACPI: SSDT 0x00000047FA387000 000063 (v02 REDHAT MACADDRS 00000001 01000013) [ 0.000000] On node 0 totalpages: 524256 [ 0.000000] DMA zone: 64 pages used for memmap [ 0.000000] DMA zone: 0 pages reserved [ 0.000000] DMA zone: 65504 pages, LIFO batch:1 [ 0.000000] Normal zone: 448 pages used for memmap [ 0.000000] Normal zone: 458752 pages, LIFO batch:1 [ 0.000000] PERCPU: Embedded 2 pages/cpu @fffffe07ffca0000 s90688 r8192 d32192 u131072 [ 0.000000] pcpu-alloc: s90688 r8192 d32192 u131072 alloc=2*65536 [ 0.000000] pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 [0] 4 [0] 5 [0] 6 [0] 7 [ 0.000000] Built 1 zonelists in Zone order, mobility grouping on. Total pages: 523744 [ 0.000000] Kernel command line: BOOT_IMAGE=/vmlinuz-4.2.0-rc5+ root=UUID=60349261-1e0c-4dd3-9a59-474425cecfe4 ro earlycon=uart8250,mmio32,0x1c021000 acpi=force acpi_force_table_verification uefi_debug LANG=en_US.UTF-8 [ 0.000000] PID hash table entries: 4096 (order: -1, 32768 bytes) [ 0.000000] Dentry cache hash table entries: 4194304 (order: 9, 33554432 bytes) [ 0.000000] Inode-cache hash table entries: 2097152 (order: 8, 16777216 bytes) [ 0.000000] software IO TLB [mem 0x40dbff0000-0x40dfff0000] (64MB) mapped at [fffffe00dbdf0000-fffffe00dfdeffff] [ 0.000000] Memory: 32844224K/33552384K available (8778K kernel code, 1322K rwdata, 5376K rodata, 896K init, 1504K bss, 183872K reserved, 524288K cma-reserved) [ 0.000000] Virtual kernel memory layout: vmalloc : 0xfffffc0000000000 - 0xfffffdfedfff0000 ( 2043 GB) vmemmap : 0xfffffdfee0000000 - 0xfffffdffe0000000 ( 4 GB maximum) 0xfffffdfef0000800 - 0xfffffdfef2000000 ( 31 MB actual) fixed : 0xfffffdfffa7d0000 - 0xfffffdfffac00000 ( 4288 KB) PCI I/O : 0xfffffdfffae00000 - 0xfffffdfffbe00000 ( 16 MB) modules : 0xfffffdfffc000000 - 0xfffffe0000000000 ( 64 MB) memory : 0xfffffe0000000000 - 0xfffffe07ffe00000 ( 32766 MB) .init : 0xfffffe0000e70000 - 0xfffffe0000f50000 ( 896 KB) .text : 0xfffffe0000080000 - 0xfffffe0000e62894 ( 14219 KB) .data : 0xfffffe0000f60000 - 0xfffffe00010aaa00 ( 1323 KB) [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=8, Nodes=1 [ 0.000000] Hierarchical RCU implementation. [ 0.000000] Build-time adjustment of leaf fanout to 64. [ 0.000000] NR_IRQS:64 nr_irqs:64 0 [ 0.000000] Offload RCU callbacks from all CPUs [ 0.000000] Offload RCU callbacks from CPUs: 0-7. [ 0.000000] Architected cp15 timer(s) running at 50.00MHz (phys). [ 0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0xb8812736b, max_idle_ns: 440795202655 ns [ 0.000002] sched_clock: 56 bits at 50MHz, resolution 20ns, wraps every 4398046511100ns [ 0.008570] Console: colour dummy device 80x25 [ 0.013275] console [tty0] enabled [ 0.016875] bootconsole [uart0] disabled [ 0.021012] Calibrating delay loop (skipped), value calculated using timer frequency.. 100.00 BogoMIPS (lpj=500000) [ 0.021020] pid_max: default: 32768 minimum: 301 [ 0.021062] Security Framework initialized [ 0.021069] SELinux: Initializing. [ 0.021085] SELinux: Starting in permissive mode [ 0.021087] Yama: becoming mindful. [ 0.021151] Mount-cache hash table entries: 65536 (order: 3, 524288 bytes) [ 0.021157] Mountpoint-cache hash table entries: 65536 (order: 3, 524288 bytes) [ 0.021614] Initializing cgroup subsys blkio [ 0.021625] Initializing cgroup subsys memory [ 0.021637] Initializing cgroup subsys devices [ 0.021643] Initializing cgroup subsys freezer [ 0.021648] Initializing cgroup subsys net_cls [ 0.021653] Initializing cgroup subsys perf_event [ 0.021658] Initializing cgroup subsys net_prio [ 0.021675] ftrace: allocating 29825 entries in 8 pages [ 0.041956] hw perfevents: enabled with arm/armv8-pmuv3 PMU driver, 5 counters available [ 0.041975] Remapping and enabling EFI services. [ 0.041980] EFI remap 0x00000047fa379000 => 0000000040009000 [ 0.041990] EFI remap 0x00000047fa398000 => 0000000040028000 [ 0.041993] EFI remap 0x00000047fa3a0000 => 0000000040030000 [ 0.042000] EFI remap 0x00000047fa3c1000 => 0000000040061000 [ 0.042005] EFI remap 0x00000047fa3d3000 => 0000000040083000 [ 0.042011] EFI remap 0x00000047fa535000 => 00000000401f5000 [ 0.042016] EFI remap 0x00000047ff508000 => 0000000040218000 [ 0.042021] EFI remap 0x00000047ff51c000 => 000000004023c000 [ 0.042026] EFI remap 0x0000000010510000 => 0000000040270000 [ 0.042031] EFI remap 0x0000000010548000 => 0000000040288000 [ 0.042036] EFI remap 0x0000000017000000 => 0000000040290000 [ 0.042041] EFI remap 0x000000001c025000 => 00000000402a5000 [ 0.043032] CPU1: Booted secondary processor [ 0.043036] Detected PIPT I-cache on CPU1 [ 0.043207] CPU2: Booted secondary processor [ 0.043210] Detected PIPT I-cache on CPU2 [ 0.043363] CPU3: Booted secondary processor [ 0.043365] Detected PIPT I-cache on CPU3 [ 0.043512] CPU4: Booted secondary processor [ 0.043516] Detected PIPT I-cache on CPU4 [ 0.043668] CPU5: Booted secondary processor [ 0.043670] Detected PIPT I-cache on CPU5 [ 0.043818] CPU6: Booted secondary processor [ 0.043822] Detected PIPT I-cache on CPU6 [ 0.043980] CPU7: Booted secondary processor [ 0.043982] Detected PIPT I-cache on CPU7 [ 0.044013] Brought up 8 CPUs [ 0.044071] SMP: Total of 8 processors activated. [ 0.044076] CPU: All CPU(s) started at EL2 [ 0.044586] devtmpfs: initialized [ 0.045714] SMBIOS 3.0.0 present. [ 0.045823] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns [ 0.046202] xor: measuring software checksum speed [ 0.141826] 8regs : 5747.200 MB/sec [ 0.241847] 8regs_prefetch: 5337.600 MB/sec [ 0.341865] 32regs : 5836.800 MB/sec [ 0.441895] 32regs_prefetch: 4582.400 MB/sec [ 0.441901] xor: using function: 32regs (5836.800 MB/sec) [ 0.441923] atomic64_test: passed [ 0.442171] NET: Registered protocol family 16 [ 0.442467] vdso: 2 pages (1 code @ fffffe0000f80000, 1 data @ fffffe0000f70000) [ 0.442488] hw-breakpoint: found 4 breakpoint and 4 watchpoint registers. [ 0.442773] DMA: preallocated 256 KiB pool for atomic allocations [ 0.442807] Serial: AMBA PL011 UART driver [ 0.621938] raid6: int64x1 gen() 3041 MB/s [ 0.791995] raid6: int64x1 xor() 646 MB/s [ 0.962000] raid6: int64x2 gen() 4565 MB/s [ 1.132056] raid6: int64x2 xor() 902 MB/s [ 1.302069] raid6: int64x4 gen() 5034 MB/s [ 1.472108] raid6: int64x4 xor() 1107 MB/s [ 1.642154] raid6: int64x8 gen() 2707 MB/s [ 1.812178] raid6: int64x8 xor() 873 MB/s [ 1.982202] raid6: neonx1 gen() 3651 MB/s [ 2.152241] raid6: neonx2 gen() 4944 MB/s [ 2.322263] raid6: neonx4 gen() 5394 MB/s [ 2.492292] raid6: neonx8 gen() 4395 MB/s [ 2.492297] raid6: using algorithm neonx4 gen() 5394 MB/s [ 2.492303] raid6: using intx1 recovery algorithm [ 2.492384] ACPI: Interpreter disabled. [ 2.492550] vgaarb: loaded [ 2.492775] SCSI subsystem initialized [ 2.492860] libata version 3.00 loaded. [ 2.492938] usbcore: registered new interface driver usbfs [ 2.492959] usbcore: registered new interface driver hub [ 2.492997] usbcore: registered new device driver usb [ 2.493357] NetLabel: Initializing [ 2.493364] NetLabel: domain hash size = 128 [ 2.493369] NetLabel: protocols = UNLABELED CIPSOv4 [ 2.493394] NetLabel: unlabeled traffic allowed by default [ 2.493492] clocksource: Switched to clocksource arch_sys_counter [ 2.514940] pnp: PnP ACPI: disabled [ 2.517885] NET: Registered protocol family 2 [ 2.518149] TCP established hash table entries: 262144 (order: 5, 2097152 bytes) [ 2.518820] TCP bind hash table entries: 65536 (order: 4, 1048576 bytes) [ 2.519190] TCP: Hash tables configured (established 262144 bind 65536) [ 2.519229] UDP hash table entries: 16384 (order: 3, 524288 bytes) [ 2.519504] UDP-Lite hash table entries: 16384 (order: 3, 524288 bytes) [ 2.519888] NET: Registered protocol family 1 [ 2.520040] RPC: Registered named UNIX socket transport module. [ 2.520048] RPC: Registered udp transport module. [ 2.520053] RPC: Registered tcp transport module. [ 2.520058] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 2.520068] PCI: CLS 0 bytes, default 64 [ 2.520168] Unpacking initramfs... [ 2.738416] Freeing initrd memory: 9472K (fffffe07f8490000 - fffffe07f8dd0000) [ 2.738687] kvm [1]: GICV size 0x2000 not a multiple of page size 0x10000 [ 2.738697] kvm [1]: error: no compatible GIC info found [ 2.738792] kvm [1]: error initializing Hyp mode: -6 [ 2.739225] futex hash table entries: 2048 (order: 1, 131072 bytes) [ 2.739270] audit: initializing netlink subsys (disabled) [ 2.739300] audit: type=2000 audit(2.720:1): initialized [ 2.739602] HugeTLB registered 512 MB page size, pre-allocated 0 pages [ 2.742336] zpool: loaded [ 2.742345] zbud: loaded [ 2.742554] VFS: Disk quotas dquot_6.6.0 [ 2.742626] VFS: Dquot-cache hash table entries: 8192 (order 0, 65536 bytes) [ 2.743537] NFS: Registering the id_resolver key type [ 2.743564] Key type id_resolver registered [ 2.743569] Key type id_legacy registered [ 2.743580] nfs4filelayout_init: NFSv4 File Layout Driver Registering... [ 2.743683] SGI XFS with ACLs, security attributes, no debug enabled [ 2.744386] Key type big_key registered [ 2.744397] SELinux: Registering netfilter hooks [ 2.750019] NET: Registered protocol family 38 [ 2.750042] async_tx: api initialized (async) [ 2.750092] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 252) [ 2.750153] io scheduler noop registered [ 2.750162] io scheduler deadline registered [ 2.750184] io scheduler cfq registered (default) [ 2.750556] pci_hotplug: PCI Hot Plug PCI Core version: 0.5 [ 2.750574] pciehp: PCI Express Hot Plug Controller Driver version: 0.4 [ 2.750620] PCI host bridge /soc/pcie@1f2b0000 ranges: [ 2.750636] IO 0xe010000000..0xe01000ffff -> 0x00000000 [ 2.750644] MEM 0xe180000000..0xe1ffffffff -> 0x80000000 [ 2.750677] xgene-pcie 1f2b0000.pcie: (rc) link down [ 2.750738] xgene-pcie 1f2b0000.pcie: PCI host bridge to bus 0000:00 [ 2.750747] pci_bus 0000:00: root bus resource [bus 00-ff] [ 2.750753] pci_bus 0000:00: root bus resource [io 0x0000-0xffff] [ 2.750761] pci_bus 0000:00: root bus resource [mem 0xe180000000-0xe1ffffffff] (bus address [0x80000000-0xffffffff]) [ 2.750770] xgene-pcie 1f2b0000.pcie: failed to enable MSI [ 2.750793] pci 0000:00:00.0: [10e8:e004] type 01 class 0x060400 [ 2.750831] pci 0000:00:00.0: IOMMU is currently not supported for PCI [ 2.750865] pci 0000:00:00.0: supports D1 D2 [ 2.751022] pci 0000:00:00.0: PCI bridge to [bus 01] [ 2.751118] pcieport 0000:00:00.0: Signaling PME through PCIe PME interrupt [ 2.751129] pcie_pme 0000:00:00.0:pcie01: service driver pcie_pme loaded [ 2.751178] aer 0000:00:00.0:pcie02: service driver aer loaded [ 2.751350] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 2.751909] 1c020000.serial: ttyS0 at MMIO 0x1c020000 (irq = 7, base_baud = 3125000) is a 16550A [ 4.244358] console [ttyS0] enabled [ 4.248184] Unable to detect cache hierarchy from DT for CPU 0 [ 4.254204] xgene-ahci 1a400000.sata: skip clock and PHY initialization [ 4.260798] xgene-ahci 1a400000.sata: controller can't do NCQ, turning off CAP_NCQ [ 4.268363] xgene-ahci 1a400000.sata: AHCI 0001.0300 32 slots 2 ports 6 Gbps 0x3 impl platform mode [ 4.277373] xgene-ahci 1a400000.sata: flags: 64bit sntf pm only pmp fbs pio slum part ccc [ 4.285609] xgene-ahci 1a400000.sata: port 0 is not capable of FBS [ 4.291825] xgene-ahci 1a400000.sata: port 1 is not capable of FBS [ 4.298355] scsi host0: xgene-ahci [ 4.301882] scsi host1: xgene-ahci [ 4.305353] ata1: SATA max UDMA/133 mmio [mem 0x1a400000-0x1a400fff] port 0x100 irq 8 [ 4.313148] ata2: SATA max UDMA/133 mmio [mem 0x1a400000-0x1a400fff] port 0x180 irq 8 [ 4.320975] xgene-ahci 1a800000.sata: skip clock and PHY initialization [ 4.327570] xgene-ahci 1a800000.sata: controller can't do NCQ, turning off CAP_NCQ [ 4.335124] xgene-ahci 1a800000.sata: AHCI 0001.0300 32 slots 2 ports 6 Gbps 0x3 impl platform mode [ 4.344134] xgene-ahci 1a800000.sata: flags: 64bit sntf pm only pmp fbs pio slum part ccc [ 4.352362] xgene-ahci 1a800000.sata: port 0 is not capable of FBS [ 4.358544] xgene-ahci 1a800000.sata: port 1 is not capable of FBS [ 4.365054] scsi host2: xgene-ahci [ 4.368571] scsi host3: xgene-ahci [ 4.372032] ata3: SATA max UDMA/133 mmio [mem 0x1a800000-0x1a800fff] port 0x100 irq 9 [ 4.379840] ata4: SATA max UDMA/133 mmio [mem 0x1a800000-0x1a800fff] port 0x180 irq 9 [ 4.387756] libphy: Fixed MDIO Bus: probed [ 4.392313] libphy: APM X-Gene MDIO bus: probed [ 4.473815] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver [ 4.480322] ehci-pci: EHCI PCI platform driver [ 4.484772] ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver [ 4.490928] ohci-pci: OHCI PCI platform driver [ 4.495369] uhci_hcd: USB Universal Host Controller Interface driver [ 4.501763] usbcore: registered new interface driver usbserial [ 4.507586] usbcore: registered new interface driver usbserial_generic [ 4.514096] usbserial: USB Serial support registered for generic [ 4.520170] mousedev: PS/2 mouse device common for all mice [ 4.525965] rtc-efi rtc-efi: rtc core: registered rtc-efi as rtc0 [ 4.532140] md: linear personality registered for level -1 [ 4.537609] md: raid0 personality registered for level 0 [ 4.542897] md: raid1 personality registered for level 1 [ 4.548188] md: raid10 personality registered for level 10 [ 4.553714] md: raid6 personality registered for level 6 [ 4.559001] md: raid5 personality registered for level 5 [ 4.564291] md: raid4 personality registered for level 4 [ 4.569580] md: multipath personality registered for level -4 [ 4.575303] md: faulty personality registered for level -5 [ 4.580990] device-mapper: uevent: version 1.0.3 [ 4.585733] device-mapper: ioctl: 4.32.0-ioctl (2015-6-26) initialised: dm-devel@redhat.com [ 4.594219] device-mapper: multipath: version 1.9.0 loaded [ 4.599682] device-mapper: multipath round-robin: version 1.0.0 loaded [ 4.606188] device-mapper: multipath queue-length: version 0.1.0 loaded [ 4.612764] device-mapper: multipath service-time: version 0.2.0 loaded [ 4.619382] device-mapper: raid: Loading target version 1.7.0 [ 4.625304] EFI Variables Facility v0.08 2004-May-17 [ 4.630499] hidraw: raw HID events driver (C) Jiri Kosina [ 4.636091] usbcore: registered new interface driver usbhid [ 4.641637] usbhid: USB HID core driver [ 4.645562] drop_monitor: Initializing network drop monitor service [ 4.651886] ip_tables: (C) 2000-2006 Netfilter Core Team [ 4.657224] Initializing XFRM netlink socket [ 4.661671] NET: Registered protocol family 10 [ 4.663503] ata1: SATA link down (SStatus 0 SControl 4300) [ 4.671879] mip6: Mobile IPv6 [ 4.674853] NET: Registered protocol family 17 [ 4.679309] Key type dns_resolver registered [ 4.683857] registered taskstats version 1 [ 4.687955] zswap: loading zswap [ 4.691169] zswap: using zbud pool [ 4.694575] zswap: using lzo compressor [ 4.698575] rtc-efi rtc-efi: setting system clock to 2015-08-25 00:27:58 UTC (1440462478) [ 4.723509] ata3: SATA link down (SStatus 0 SControl 4300) [ 4.743501] ata4: SATA link down (SStatus 0 SControl 4300) [ 5.063496] ata2: SATA link up 6.0 Gbps (SStatus 133 SControl 4300) [ 5.070950] ata2.00: ATA-8: ST500DM002-1BD142, KC48, max UDMA/133 [ 5.077021] ata2.00: 976773168 sectors, multi 0: LBA48 NCQ (depth 0/32) [ 5.085058] ata2.00: configured for UDMA/133 [ 5.089472] scsi 1:0:0:0: Direct-Access ATA ST500DM002-1BD14 KC48 PQ: 0 ANSI: 5 [ 5.097801] sd 1:0:0:0: [sda] 976773168 512-byte logical blocks: (500 GB/465 GiB) [ 5.097835] sd 1:0:0:0: Attached scsi generic sg0 type 0 [ 5.110550] sd 1:0:0:0: [sda] 4096-byte physical blocks [ 5.115818] sd 1:0:0:0: [sda] Write Protect is off [ 5.120589] sd 1:0:0:0: [sda] Mode Sense: 00 3a 00 00 [ 5.120614] sd 1:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA [ 5.169757] sda: sda1 sda2 sda3 sda4 [ 5.173922] sd 1:0:0:0: [sda] Attached SCSI disk [ 5.178578] Freeing unused kernel memory: 896K (fffffe0000e70000 - fffffe0000f50000) [ 5.192099] random: systemd urandom read with 11 bits of entropy available [ 5.201075] systemd[1]: systemd 222 running in system mode. (+PAM +AUDIT +SELINUX +IMA -APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ -LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD +IDN) [ 5.219172] systemd[1]: Detected architecture arm64. [ 5.224131] systemd[1]: Running in initial RAM disk. [ 5.253605] systemd[1]: Set hostname to <r2-a21>. [ 5.343559] systemd[1]: Created slice -.slice. [ 5.348008] systemd[1]: Starting -.slice. [ 5.373537] systemd[1]: Listening on Journal Audit Socket. [ 5.379016] systemd[1]: Starting Journal Audit Socket. [ 5.403537] systemd[1]: Listening on udev Control Socket. [ 5.408929] systemd[1]: Starting udev Control Socket. [ 5.433528] systemd[1]: Reached target Local File Systems. [ 5.439001] systemd[1]: Starting Local File Systems. [ 5.463535] systemd[1]: Created slice System Slice. [ 5.468411] systemd[1]: Starting System Slice. [ 5.493530] systemd[1]: Reached target Timers. [ 5.497966] systemd[1]: Starting Timers. [ 5.523535] systemd[1]: Listening on udev Kernel Socket. [ 5.528839] systemd[1]: Starting udev Kernel Socket. [ 5.553535] systemd[1]: Listening on Journal Socket. [ 5.558496] systemd[1]: Starting Journal Socket. [ 5.563176] systemd[1]: Started dracut ask for additional cmdline parameters. [ 5.570470] systemd[1]: Started dracut cmdline hook. [ 5.575611] systemd[1]: Started Load Kernel Modules. [ 5.581365] systemd[1]: Starting Setup Virtual Console... [ 5.604527] systemd[1]: Starting Create list of required static device nodes for the current kernel... [ 5.653553] systemd[1]: Listening on Journal Socket (/dev/log). [ 5.659474] systemd[1]: Starting Journal Socket (/dev/log). [ 5.683551] systemd[1]: Reached target Sockets. [ 5.688091] systemd[1]: Starting Sockets. [ 5.692898] systemd[1]: Starting Journal Service... [ 5.733529] systemd[1]: Reached target Swap. [ 5.737799] systemd[1]: Starting Swap. [ 5.741749] systemd[1]: Started dracut pre-udev hook. [ 5.763537] systemd[1]: Reached target Slices. [ 5.767978] systemd[1]: Starting Slices. [ 5.772693] systemd[1]: Starting Apply Kernel Variables... [ 5.813539] systemd[1]: Started Journal Service. [ 5.818278] audit: type=1130 audit(1440462479.610:2): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-journald comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' [ 5.853588] audit: type=1130 audit(1440462479.650:3): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-vconsole-setup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' [ 5.893587] audit: type=1130 audit(1440462479.690:4): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=kmod-static-nodes comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' [ 5.933586] audit: type=1130 audit(1440462479.730:5): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-sysctl comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' [ 6.003604] audit: type=1130 audit(1440462479.800:6): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-tmpfiles-setup-dev comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' [ 6.063627] audit: type=1130 audit(1440462479.860:7): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-udevd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' [ 6.173639] audit: type=1130 audit(1440462479.970:8): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-udev-trigger comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' [ 6.533775] audit: type=1130 audit(1440462480.330:9): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-initqueue comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' [ 6.575643] audit: type=1130 audit(1440462480.370:10): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=plymouth-start comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' [ 7.036359] EXT4-fs (sda4): mounted filesystem with ordered data mode. Opts: (null) [ 7.303838] systemd-journald[181]: Received SIGTERM from PID 1 (systemd). [ 7.927817] SELinux: Disabled at runtime. [ 7.931939] SELinux: Unregistering netfilter hooks [ 8.033515] audit_printk_skb: 63 callbacks suppressed [ 8.038544] audit: type=1404 audit(1440462481.830:32): selinux=0 auid=4294967295 ses=4294967295 [ 9.439801] audit: type=1130 audit(1440462483.230:33): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-journald comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' [ 9.458627] audit: type=1131 audit(1440462483.250:34): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-journald comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' [ 9.477910] audit: type=1130 audit(1440462483.270:35): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=initrd-switch-root comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' [ 9.477933] audit: type=1131 audit(1440462483.270:36): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=initrd-switch-root comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' [ 9.518646] audit: type=1130 audit(1440462483.310:37): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-journald comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' [ 9.538140] audit: type=1131 audit(1440462483.330:38): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-journald comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' [ 9.758610] audit: type=1305 audit(1440462483.550:39): audit_enabled=1 old=1 auid=4294967295 ses=4294967295 res=1 [ 10.091569] audit: type=1130 audit(1440462483.880:40): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-journald comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' [ 10.162813] audit: type=1130 audit(1440462483.950:41): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=kmod-static-nodes comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' [ 10.329088] EXT4-fs (sda4): re-mounted. Opts: (null) [ 10.505742] systemd-journald[324]: Received request to flush runtime journal from PID 1 [ 11.932228] Installing knfsd (copyright (C) 1996 okir@monad.swb.de). [ 12.863048] random: nonblocking pool is initialized [ 14.426187] Adding 2047936k swap on /dev/sda3. Priority:-1 extents:1 across:2047936k FS [ 14.863593] audit_printk_skb: 30 callbacks suppressed [ 14.868627] audit: type=1130 audit(1440462488.660:52): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-fsck@dev-disk-by\x2duuid-94e16179\x2d755d\x2d403c\x2d9a81\x2d767dc3bb2f6f comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' [ 15.044408] EXT4-fs (sda2): mounted filesystem with ordered data mode. Opts: (null) [ 15.143578] audit: type=1130 audit(1440462488.940:53): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-journal-flush comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' [ 15.192960] FAT-fs (sda1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck. [ 15.307718] audit: type=1130 audit(1440462489.100:54): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=dracut-shutdown comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' [ 15.378781] audit: type=1130 audit(1440462489.170:55): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=plymouth-read-write comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' [ 15.397883] audit: type=1131 audit(1440462489.190:56): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=plymouth-read-write comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' [ 15.536017] audit: type=1130 audit(1440462489.330:57): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=nfs-config comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' [ 15.556251] audit: type=1130 audit(1440462489.350:58): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-tmpfiles-setup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' [ 15.653975] audit: type=1305 audit(1440462489.450:59): auid=4294967295 ses=4294967295 op="add_rule" key=(null) list=1 res=1 [ 15.665726] audit: type=1130 audit(1440462489.460:60): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=auditd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' [ 15.771349] audit: type=1305 audit(1440462489.560:61): audit_enabled=1 old=1 auid=4294967295 ses=4294967295 res=1 [ 17.932483] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready [ 17.938498] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready [ 19.474025] xgene-enet 17020000.ethernet eth0: Link is Down [ 19.758932] ip6_tables: (C) 2000-2006 Netfilter Core Team [ 19.937856] Ebtables v2.0 registered [ 20.567065] bridge: automatic filtering via arp/ip/ip6tables has been deprecated. Update your scripts to load br_netfilter if you need this. [ 20.609010] tun: Universal TUN/TAP device driver, 1.6 [ 20.614058] tun: (C) 1999-2004 Max Krasnyansky maxk@qualcomm.com [ 20.704048] device virbr0-nic entered promiscuous mode [ 21.059515] nf_conntrack version 0.5.0 (65536 buckets, 262144 max) [ 21.292384] virbr0: port 1(virbr0-nic) entered listening state [ 21.298218] virbr0: port 1(virbr0-nic) entered listening state [ 21.513561] virbr0: port 1(virbr0-nic) entered disabled state [ 22.474349] xgene-enet 17020000.ethernet eth0: Link is Up - 1Gbps/Full - flow control off [ 22.482507] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready
On 8/25/15 7:33 AM, Al Stone wrote:
On 08/19/2015 09:29 AM, Itaru Kitayama wrote:
Hi Al,
Below is a dmesg excerpt corresponding to the acpi_boot_table_init().
[ 0.000000] ACPI: RSDP 0x00000047FA397014 000024 (v02 APM ) [ 0.000000] ACPI: XSDT 0x00000047FA3960E8 000064 (v01 APM XGENE 00000003 01000013) [ 0.000000] ACPI: FACP 0x00000047FA38E000 00010C (v05 APM XGENE 00000003 INTL 20141107) [ 0.000000] ACPI: DSDT 0x00000047FA38F000 003D82 (v05 APM APM88xxx 00000001 INTL 20141107) [ 0.000000] ACPI: DBG2 0x00000047FA393000 0000AA (v00 APMC0D XGENEDBG 00000000 INTL 20141107) [ 0.000000] ACPI: GTDT 0x00000047FA38C000 0000E0 (v02 APM XGENE 00000001 INTL 20141107) [ 0.000000] ACPI: MCFG 0x00000047FA38B000 00003C (v01 APM XGENE 00000002 INTL 20141107) [ 0.000000] ACPI: SPCR 0x00000047FA38A000 000050 (v02 APMC0D XGENESPC 00000000 INTL 20141107) [ 0.000000] ACPI: SSDT 0x00000047FA389000 00002D (v02 APM XGENE 00000001 INTL 20141107) [ 0.000000] ACPI: APIC 0x00000047FA388000 0002A4 (v03 APM XGENE 00000003 01000013) [ 0.000000] ACPI: SSDT 0x00000047FA387000 000063 (v02 REDHAT MACADDRS 00000001 01000013)
I've been testing the Mustang topic branch on the platform with the Rev 1 CPUs with 32 GB memory.
Okay. That's interesting output, but it unfortunately tells me nothing. Those messages only indicate that the kernel has found ACPI tables with those names, at those addresses, and that they have valid signatures in them. The content of the tables could be completely bogus, unless the kernel command acpi_force_table_verification was used, which will at least verify the checksums in the tables are correct, even if the content is not examined. I can deduce that acpi=force has been used, but that's about it.
What would be more useful is the remainder of the dmesg/console output so we can tell how far the kernel got in using the ACPI tables that it found, all the way up to the very last message out.
On 8/18/15 8:02 AM, Itaru Kitayama wrote:
Hi Al,
Even with your .config the HEAD of acpi-topic-mustang does not boot with acpi=force. Here are the messages appear on the console:
EFI stub: Booting Linux Kernel... EFI stub: Using DTB from configuration table EFI stub: Exiting boot services and installing virtual address map... XhcClearBiosOwnership: called to clear BIOS ownership XhcClearBiosOwnership: called to clear BIOS ownership L3c Cache: 8MB
On 8/18/15 6:58 AM, Itaru Kitayama wrote:
Hi Al,
Where does it "stall"? What's the last message you see? And if you're not using earlycon, please do so; that will help determine how far the boot is getting.
If I boot it with acpi=force, no output. Does my usage below look correct?
GRUB_CMDLINE_LINUX="earlycon=uart8250,mmio32,0x1c021000 acpi=force console=ttyS0,115200 loglevel=7"
How were those checked?
In setup_arch() I was calling disable_acpi() right after the>>> Itaru
Linaro-acpi mailing list Linaro-acpi@lists.linaro.org https://lists.linaro.org/mailman/listinfo/linaro-acpi
acpi_boot_table_init() call as that makes the system get to the prompt.
I am about to build the kernel with your .config and test it on one of Mustangs in the Linaro Austin lab. The Boot firmware version is 1.1.0-rh-0.15.
I may have mentioned this before (or not), but I think we need to get this Mustang updated to newer firmware; while that version is okay, the preferred version is the latest 1.15.x from APM (x = 18 or greater, I believe). We may have to figure out how to get the Austin lab updated somehow.
Just to check, you do have
CONFIG_ARM64_64K_PAGES=y
in your .config?
Graeme
On 25 August 2015 at 08:59, Itaru Kitayama itaru.kitayama@riken.jp wrote:
Hi Al,
Below is the dmesg. In the modified kernel I call disable_acpi() right after acpi_boot_table_init(). (Otherwise, no meaningful output is obtained even with earlycon)
[ 0.000000] Booting Linux on physical CPU 0x0 [ 0.000000] Initializing cgroup subsys cpuset [ 0.000000] Initializing cgroup subsys cpu [ 0.000000] Initializing cgroup subsys cpuacct [ 0.000000] Linux version 4.2.0-rc5+ (root@r2-a21) (gcc version 5.1.1 20150618 (Red Hat 5.1.1-4) (GCC) ) #23 SMP Mon Aug 24 20:29:06 EDT 2015 [ 0.000000] CPU: AArch64 Processor [500f0001] revision 1 [ 0.000000] Detected PIPT I-cache on CPU0 [ 0.000000] earlycon: Early serial console at MMIO32 0x1c021000 (options '') [ 0.000000] bootconsole [uart0] enabled [ 0.000000] efi: Getting EFI parameters from FDT: [ 0.000000] efi: System Table: 0x00000047ff54df18 [ 0.000000] efi: MemMap Address: 0x00000047f8684018 [ 0.000000] efi: MemMap Size: 0x00000750 [ 0.000000] efi: MemMap Desc. Size: 0x00000030 [ 0.000000] efi: MemMap Desc. Version: 0x00000001 [ 0.000000] EFI v2.40 by X-Gene Mustang Board EFI Mar 13 2015 14:20:53 [ 0.000000] efi: ACPI=0x47fa397000 ACPI 2.0=0x47fa397014 SMBIOS 3.0=0x47fa395000 [ 0.000000] Processing EFI memory map: [ 0.000000] 0x004000000000-0x004000007fff [Conventional Memory| | | | | |WB|WT|WC|UC] [ 0.000000] Ignoring memory block 0x4000000000 - 0x4000010000
[ 0.000000] 0x004000008000-0x00400000ffff [Reserved | | | | | |WB|WT|WC|UC] [ 0.000000] Ignoring memory block 0x4000000000 - 0x4000010000 [ 0.000000] * [ 0.000000] 0x004000010000-0x00400001ffff [Loader Data | | | | | |WB|WT|WC|UC] [ 0.000000] Ignoring memory block 0x4000010000 - 0x4000020000
[ 0.000000] 0x004000020000-0x0040001fffff [Conventional Memory| | | | | |WB|WT|WC|UC] [ 0.000000] Ignoring memory range 0x4000020000 - 0x4000200000
[ 0.000000] 0x004000200000-0x00400147ffff [Loader Data | | | | | |WB|WT|WC|UC] [ 0.000000] 0x004001480000-0x00401fdfffff [Conventional Memory| | | | | |WB|WT|WC|UC] [ 0.000000] 0x00401fe00000-0x00401fe0ffff [Loader Data | | | | | |WB|WT|WC|UC] [ 0.000000] 0x00401fe10000-0x004793ffffff [Conventional Memory| | | | | |WB|WT|WC|UC] [ 0.000000] 0x004794000000-0x0047f7ffffff [Loader Data | | | | | |WB|WT|WC|UC] [ 0.000000] 0x0047f8000000-0x0047f801ffff [Boot Data | | | | | |WB|WT|WC|UC] [ 0.000000] 0x0047f8020000-0x0047f8683fff [Conventional Memory| | | | | |WB|WT|WC|UC] [ 0.000000] 0x0047f8684000-0x0047f9ffffff [Loader Data | | | | | |WB|WT|WC|UC] [ 0.000000] 0x0047fa000000-0x0047fa0cdfff [Loader Code | | | | | |WB|WT|WC|UC] [ 0.000000] 0x0047fa0ce000-0x0047fa378fff [Boot Code | | | | | |WB|WT|WC|UC] [ 0.000000] 0x0047fa379000-0x0047fa386fff [Runtime Code |RUN| | | | |WB|WT|WC|UC]* [ 0.000000] 0x0047fa387000-0x0047fa38cfff [ACPI Reclaim Memory| | | | | |WB|WT|WC|UC]* [ 0.000000] 0x0047fa38d000-0x0047fa38dfff [ACPI Memory NVS | | | | | |WB|WT|WC|UC]* [ 0.000000] 0x0047fa38e000-0x0047fa393fff [ACPI Reclaim Memory| | | | | |WB|WT|WC|UC]* [ 0.000000] 0x0047fa394000-0x0047fa395fff [Reserved | | | | | |WB|WT|WC|UC]* [ 0.000000] 0x0047fa396000-0x0047fa397fff [ACPI Reclaim Memory| | | | | |WB|WT|WC|UC]* [ 0.000000] 0x0047fa398000-0x0047fa39ffff [Runtime Code |RUN| | | | |WB|WT|WC|UC]* [ 0.000000] 0x0047fa3a0000-0x0047fa3c0fff [Runtime Data |RUN| | | | |WB|WT|WC|UC]* [ 0.000000] 0x0047fa3c1000-0x0047fa3d2fff [Runtime Code |RUN| | | | |WB|WT|WC|UC]* [ 0.000000] 0x0047fa3d3000-0x0047fa534fff [Runtime Data |RUN| | | | |WB|WT|WC|UC]* [ 0.000000] 0x0047fa535000-0x0047fa543fff [Runtime Code |RUN| | | | |WB|WT|WC|UC]* [ 0.000000] 0x0047fa544000-0x0047fb90ffff [Conventional Memory| | | | | |WB|WT|WC|UC] [ 0.000000] 0x0047fb910000-0x0047fbdfcfff [Boot Data | | | | | |WB|WT|WC|UC] [ 0.000000] 0x0047fbdfd000-0x0047fc1affff [Conventional Memory| | | | | |WB|WT|WC|UC] [ 0.000000] 0x0047fc1b0000-0x0047fe092fff [Boot Data | | | | | |WB|WT|WC|UC] [ 0.000000] 0x0047fe093000-0x0047fe11cfff [Conventional Memory| | | | | |WB|WT|WC|UC] [ 0.000000] 0x0047fe11d000-0x0047ff377fff [Boot Data | | | | | |WB|WT|WC|UC] [ 0.000000] 0x0047ff378000-0x0047ff507fff [Boot Code | | | | | |WB|WT|WC|UC] [ 0.000000] 0x0047ff508000-0x0047ff51bfff [Runtime Code |RUN| | | | |WB|WT|WC|UC]* [ 0.000000] 0x0047ff51c000-0x0047ff54dfff [Runtime Data |RUN| | | | |WB|WT|WC|UC]* [ 0.000000] 0x0047ff54e000-0x0047ffffffff [Boot Data | | | | | |WB|WT|WC|UC] [ 0.000000] 0x000010510000-0x000010510fff [Memory Mapped I/O |RUN| | | | | | | | ] [ 0.000000] 0x000010548000-0x000010549fff [Memory Mapped I/O |RUN| | | | | | | | ] [ 0.000000] 0x000017000000-0x000017001fff [Memory Mapped I/O |RUN| | | | | | | | ] [ 0.000000] 0x00001c025000-0x00001c025fff [Memory Mapped I/O |RUN| | | | | | | | ] [ 0.000000] cma: Reserved 512 MiB at 0x00000040e0000000 [ 0.000000] ACPI: Early table checksum verification enabled [ 0.000000] ACPI: RSDP 0x00000047FA397014 000024 (v02 APM ) [ 0.000000] ACPI: XSDT 0x00000047FA3960E8 000064 (v01 APM XGENE 00000003 01000013) [ 0.000000] ACPI: FACP 0x00000047FA38E000 00010C (v05 APM XGENE 00000003 INTL 20141107) [ 0.000000] ACPI: DSDT 0x00000047FA38F000 003D82 (v05 APM APM88xxx 00000001 INTL 20141107) [ 0.000000] ACPI: DBG2 0x00000047FA393000 0000AA (v00 APMC0D XGENEDBG 00000000 INTL 20141107) [ 0.000000] ACPI: GTDT 0x00000047FA38C000 0000E0 (v02 APM XGENE 00000001 INTL 20141107) [ 0.000000] ACPI: MCFG 0x00000047FA38B000 00003C (v01 APM XGENE 00000002 INTL 20141107) [ 0.000000] ACPI: SPCR 0x00000047FA38A000 000050 (v02 APMC0D XGENESPC 00000000 INTL 20141107) [ 0.000000] ACPI: SSDT 0x00000047FA389000 00002D (v02 APM XGENE 00000001 INTL 20141107) [ 0.000000] ACPI: APIC 0x00000047FA388000 0002A4 (v03 APM XGENE 00000003 01000013) [ 0.000000] ACPI: SSDT 0x00000047FA387000 000063 (v02 REDHAT MACADDRS 00000001 01000013) [ 0.000000] On node 0 totalpages: 524256 [ 0.000000] DMA zone: 64 pages used for memmap [ 0.000000] DMA zone: 0 pages reserved [ 0.000000] DMA zone: 65504 pages, LIFO batch:1 [ 0.000000] Normal zone: 448 pages used for memmap [ 0.000000] Normal zone: 458752 pages, LIFO batch:1 [ 0.000000] PERCPU: Embedded 2 pages/cpu @fffffe07ffca0000 s90688 r8192 d32192 u131072 [ 0.000000] pcpu-alloc: s90688 r8192 d32192 u131072 alloc=2*65536 [ 0.000000] pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 [0] 4 [0] 5 [0] 6 [0] 7 [ 0.000000] Built 1 zonelists in Zone order, mobility grouping on. Total pages: 523744 [ 0.000000] Kernel command line: BOOT_IMAGE=/vmlinuz-4.2.0-rc5+ root=UUID=60349261-1e0c-4dd3-9a59-474425cecfe4 ro earlycon=uart8250,mmio32,0x1c021000 acpi=force acpi_force_table_verification uefi_debug LANG=en_US.UTF-8 [ 0.000000] PID hash table entries: 4096 (order: -1, 32768 bytes) [ 0.000000] Dentry cache hash table entries: 4194304 (order: 9, 33554432 bytes) [ 0.000000] Inode-cache hash table entries: 2097152 (order: 8, 16777216 bytes) [ 0.000000] software IO TLB [mem 0x40dbff0000-0x40dfff0000] (64MB) mapped at [fffffe00dbdf0000-fffffe00dfdeffff] [ 0.000000] Memory: 32844224K/33552384K available (8778K kernel code, 1322K rwdata, 5376K rodata, 896K init, 1504K bss, 183872K reserved, 524288K cma-reserved) [ 0.000000] Virtual kernel memory layout: vmalloc : 0xfffffc0000000000 - 0xfffffdfedfff0000 ( 2043 GB) vmemmap : 0xfffffdfee0000000 - 0xfffffdffe0000000 ( 4 GB maximum) 0xfffffdfef0000800 - 0xfffffdfef2000000 ( 31 MB actual) fixed : 0xfffffdfffa7d0000 - 0xfffffdfffac00000 ( 4288 KB) PCI I/O : 0xfffffdfffae00000 - 0xfffffdfffbe00000 ( 16 MB) modules : 0xfffffdfffc000000 - 0xfffffe0000000000 ( 64 MB) memory : 0xfffffe0000000000 - 0xfffffe07ffe00000 ( 32766 MB) .init : 0xfffffe0000e70000 - 0xfffffe0000f50000 ( 896 KB) .text : 0xfffffe0000080000 - 0xfffffe0000e62894 ( 14219 KB) .data : 0xfffffe0000f60000 - 0xfffffe00010aaa00 ( 1323 KB) [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=8, Nodes=1 [ 0.000000] Hierarchical RCU implementation. [ 0.000000] Build-time adjustment of leaf fanout to 64. [ 0.000000] NR_IRQS:64 nr_irqs:64 0 [ 0.000000] Offload RCU callbacks from all CPUs [ 0.000000] Offload RCU callbacks from CPUs: 0-7. [ 0.000000] Architected cp15 timer(s) running at 50.00MHz (phys). [ 0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0xb8812736b, max_idle_ns: 440795202655 ns [ 0.000002] sched_clock: 56 bits at 50MHz, resolution 20ns, wraps every 4398046511100ns [ 0.008570] Console: colour dummy device 80x25 [ 0.013275] console [tty0] enabled [ 0.016875] bootconsole [uart0] disabled [ 0.021012] Calibrating delay loop (skipped), value calculated using timer frequency.. 100.00 BogoMIPS (lpj=500000) [ 0.021020] pid_max: default: 32768 minimum: 301 [ 0.021062] Security Framework initialized [ 0.021069] SELinux: Initializing. [ 0.021085] SELinux: Starting in permissive mode [ 0.021087] Yama: becoming mindful. [ 0.021151] Mount-cache hash table entries: 65536 (order: 3, 524288 bytes) [ 0.021157] Mountpoint-cache hash table entries: 65536 (order: 3, 524288 bytes) [ 0.021614] Initializing cgroup subsys blkio [ 0.021625] Initializing cgroup subsys memory [ 0.021637] Initializing cgroup subsys devices [ 0.021643] Initializing cgroup subsys freezer [ 0.021648] Initializing cgroup subsys net_cls [ 0.021653] Initializing cgroup subsys perf_event [ 0.021658] Initializing cgroup subsys net_prio [ 0.021675] ftrace: allocating 29825 entries in 8 pages [ 0.041956] hw perfevents: enabled with arm/armv8-pmuv3 PMU driver, 5 counters available [ 0.041975] Remapping and enabling EFI services. [ 0.041980] EFI remap 0x00000047fa379000 => 0000000040009000 [ 0.041990] EFI remap 0x00000047fa398000 => 0000000040028000 [ 0.041993] EFI remap 0x00000047fa3a0000 => 0000000040030000 [ 0.042000] EFI remap 0x00000047fa3c1000 => 0000000040061000 [ 0.042005] EFI remap 0x00000047fa3d3000 => 0000000040083000 [ 0.042011] EFI remap 0x00000047fa535000 => 00000000401f5000 [ 0.042016] EFI remap 0x00000047ff508000 => 0000000040218000 [ 0.042021] EFI remap 0x00000047ff51c000 => 000000004023c000 [ 0.042026] EFI remap 0x0000000010510000 => 0000000040270000 [ 0.042031] EFI remap 0x0000000010548000 => 0000000040288000 [ 0.042036] EFI remap 0x0000000017000000 => 0000000040290000 [ 0.042041] EFI remap 0x000000001c025000 => 00000000402a5000 [ 0.043032] CPU1: Booted secondary processor [ 0.043036] Detected PIPT I-cache on CPU1 [ 0.043207] CPU2: Booted secondary processor [ 0.043210] Detected PIPT I-cache on CPU2 [ 0.043363] CPU3: Booted secondary processor [ 0.043365] Detected PIPT I-cache on CPU3 [ 0.043512] CPU4: Booted secondary processor [ 0.043516] Detected PIPT I-cache on CPU4 [ 0.043668] CPU5: Booted secondary processor [ 0.043670] Detected PIPT I-cache on CPU5 [ 0.043818] CPU6: Booted secondary processor [ 0.043822] Detected PIPT I-cache on CPU6 [ 0.043980] CPU7: Booted secondary processor [ 0.043982] Detected PIPT I-cache on CPU7 [ 0.044013] Brought up 8 CPUs [ 0.044071] SMP: Total of 8 processors activated. [ 0.044076] CPU: All CPU(s) started at EL2 [ 0.044586] devtmpfs: initialized [ 0.045714] SMBIOS 3.0.0 present. [ 0.045823] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns [ 0.046202] xor: measuring software checksum speed [ 0.141826] 8regs : 5747.200 MB/sec [ 0.241847] 8regs_prefetch: 5337.600 MB/sec [ 0.341865] 32regs : 5836.800 MB/sec [ 0.441895] 32regs_prefetch: 4582.400 MB/sec [ 0.441901] xor: using function: 32regs (5836.800 MB/sec) [ 0.441923] atomic64_test: passed [ 0.442171] NET: Registered protocol family 16 [ 0.442467] vdso: 2 pages (1 code @ fffffe0000f80000, 1 data @ fffffe0000f70000) [ 0.442488] hw-breakpoint: found 4 breakpoint and 4 watchpoint registers. [ 0.442773] DMA: preallocated 256 KiB pool for atomic allocations [ 0.442807] Serial: AMBA PL011 UART driver [ 0.621938] raid6: int64x1 gen() 3041 MB/s [ 0.791995] raid6: int64x1 xor() 646 MB/s [ 0.962000] raid6: int64x2 gen() 4565 MB/s [ 1.132056] raid6: int64x2 xor() 902 MB/s [ 1.302069] raid6: int64x4 gen() 5034 MB/s [ 1.472108] raid6: int64x4 xor() 1107 MB/s [ 1.642154] raid6: int64x8 gen() 2707 MB/s [ 1.812178] raid6: int64x8 xor() 873 MB/s [ 1.982202] raid6: neonx1 gen() 3651 MB/s [ 2.152241] raid6: neonx2 gen() 4944 MB/s [ 2.322263] raid6: neonx4 gen() 5394 MB/s [ 2.492292] raid6: neonx8 gen() 4395 MB/s [ 2.492297] raid6: using algorithm neonx4 gen() 5394 MB/s [ 2.492303] raid6: using intx1 recovery algorithm [ 2.492384] ACPI: Interpreter disabled. [ 2.492550] vgaarb: loaded [ 2.492775] SCSI subsystem initialized [ 2.492860] libata version 3.00 loaded. [ 2.492938] usbcore: registered new interface driver usbfs [ 2.492959] usbcore: registered new interface driver hub [ 2.492997] usbcore: registered new device driver usb [ 2.493357] NetLabel: Initializing [ 2.493364] NetLabel: domain hash size = 128 [ 2.493369] NetLabel: protocols = UNLABELED CIPSOv4 [ 2.493394] NetLabel: unlabeled traffic allowed by default [ 2.493492] clocksource: Switched to clocksource arch_sys_counter [ 2.514940] pnp: PnP ACPI: disabled [ 2.517885] NET: Registered protocol family 2 [ 2.518149] TCP established hash table entries: 262144 (order: 5, 2097152 bytes) [ 2.518820] TCP bind hash table entries: 65536 (order: 4, 1048576 bytes) [ 2.519190] TCP: Hash tables configured (established 262144 bind 65536) [ 2.519229] UDP hash table entries: 16384 (order: 3, 524288 bytes) [ 2.519504] UDP-Lite hash table entries: 16384 (order: 3, 524288 bytes) [ 2.519888] NET: Registered protocol family 1 [ 2.520040] RPC: Registered named UNIX socket transport module. [ 2.520048] RPC: Registered udp transport module. [ 2.520053] RPC: Registered tcp transport module. [ 2.520058] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 2.520068] PCI: CLS 0 bytes, default 64 [ 2.520168] Unpacking initramfs... [ 2.738416] Freeing initrd memory: 9472K (fffffe07f8490000 - fffffe07f8dd0000) [ 2.738687] kvm [1]: GICV size 0x2000 not a multiple of page size 0x10000 [ 2.738697] kvm [1]: error: no compatible GIC info found [ 2.738792] kvm [1]: error initializing Hyp mode: -6 [ 2.739225] futex hash table entries: 2048 (order: 1, 131072 bytes) [ 2.739270] audit: initializing netlink subsys (disabled) [ 2.739300] audit: type=2000 audit(2.720:1): initialized [ 2.739602] HugeTLB registered 512 MB page size, pre-allocated 0 pages [ 2.742336] zpool: loaded [ 2.742345] zbud: loaded [ 2.742554] VFS: Disk quotas dquot_6.6.0 [ 2.742626] VFS: Dquot-cache hash table entries: 8192 (order 0, 65536 bytes) [ 2.743537] NFS: Registering the id_resolver key type [ 2.743564] Key type id_resolver registered [ 2.743569] Key type id_legacy registered [ 2.743580] nfs4filelayout_init: NFSv4 File Layout Driver Registering... [ 2.743683] SGI XFS with ACLs, security attributes, no debug enabled [ 2.744386] Key type big_key registered [ 2.744397] SELinux: Registering netfilter hooks [ 2.750019] NET: Registered protocol family 38 [ 2.750042] async_tx: api initialized (async) [ 2.750092] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 252) [ 2.750153] io scheduler noop registered [ 2.750162] io scheduler deadline registered [ 2.750184] io scheduler cfq registered (default) [ 2.750556] pci_hotplug: PCI Hot Plug PCI Core version: 0.5 [ 2.750574] pciehp: PCI Express Hot Plug Controller Driver version: 0.4 [ 2.750620] PCI host bridge /soc/pcie@1f2b0000 ranges: [ 2.750636] IO 0xe010000000..0xe01000ffff -> 0x00000000 [ 2.750644] MEM 0xe180000000..0xe1ffffffff -> 0x80000000 [ 2.750677] xgene-pcie 1f2b0000.pcie: (rc) link down [ 2.750738] xgene-pcie 1f2b0000.pcie: PCI host bridge to bus 0000:00 [ 2.750747] pci_bus 0000:00: root bus resource [bus 00-ff] [ 2.750753] pci_bus 0000:00: root bus resource [io 0x0000-0xffff] [ 2.750761] pci_bus 0000:00: root bus resource [mem 0xe180000000-0xe1ffffffff] (bus address [0x80000000-0xffffffff]) [ 2.750770] xgene-pcie 1f2b0000.pcie: failed to enable MSI [ 2.750793] pci 0000:00:00.0: [10e8:e004] type 01 class 0x060400 [ 2.750831] pci 0000:00:00.0: IOMMU is currently not supported for PCI [ 2.750865] pci 0000:00:00.0: supports D1 D2 [ 2.751022] pci 0000:00:00.0: PCI bridge to [bus 01] [ 2.751118] pcieport 0000:00:00.0: Signaling PME through PCIe PME interrupt [ 2.751129] pcie_pme 0000:00:00.0:pcie01: service driver pcie_pme loaded [ 2.751178] aer 0000:00:00.0:pcie02: service driver aer loaded [ 2.751350] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 2.751909] 1c020000.serial: ttyS0 at MMIO 0x1c020000 (irq = 7, base_baud = 3125000) is a 16550A [ 4.244358] console [ttyS0] enabled [ 4.248184] Unable to detect cache hierarchy from DT for CPU 0 [ 4.254204] xgene-ahci 1a400000.sata: skip clock and PHY initialization [ 4.260798] xgene-ahci 1a400000.sata: controller can't do NCQ, turning off CAP_NCQ [ 4.268363] xgene-ahci 1a400000.sata: AHCI 0001.0300 32 slots 2 ports 6 Gbps 0x3 impl platform mode [ 4.277373] xgene-ahci 1a400000.sata: flags: 64bit sntf pm only pmp fbs pio slum part ccc [ 4.285609] xgene-ahci 1a400000.sata: port 0 is not capable of FBS [ 4.291825] xgene-ahci 1a400000.sata: port 1 is not capable of FBS [ 4.298355] scsi host0: xgene-ahci [ 4.301882] scsi host1: xgene-ahci [ 4.305353] ata1: SATA max UDMA/133 mmio [mem 0x1a400000-0x1a400fff] port 0x100 irq 8 [ 4.313148] ata2: SATA max UDMA/133 mmio [mem 0x1a400000-0x1a400fff] port 0x180 irq 8 [ 4.320975] xgene-ahci 1a800000.sata: skip clock and PHY initialization [ 4.327570] xgene-ahci 1a800000.sata: controller can't do NCQ, turning off CAP_NCQ [ 4.335124] xgene-ahci 1a800000.sata: AHCI 0001.0300 32 slots 2 ports 6 Gbps 0x3 impl platform mode [ 4.344134] xgene-ahci 1a800000.sata: flags: 64bit sntf pm only pmp fbs pio slum part ccc [ 4.352362] xgene-ahci 1a800000.sata: port 0 is not capable of FBS [ 4.358544] xgene-ahci 1a800000.sata: port 1 is not capable of FBS [ 4.365054] scsi host2: xgene-ahci [ 4.368571] scsi host3: xgene-ahci [ 4.372032] ata3: SATA max UDMA/133 mmio [mem 0x1a800000-0x1a800fff] port 0x100 irq 9 [ 4.379840] ata4: SATA max UDMA/133 mmio [mem 0x1a800000-0x1a800fff] port 0x180 irq 9 [ 4.387756] libphy: Fixed MDIO Bus: probed [ 4.392313] libphy: APM X-Gene MDIO bus: probed [ 4.473815] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver [ 4.480322] ehci-pci: EHCI PCI platform driver [ 4.484772] ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver [ 4.490928] ohci-pci: OHCI PCI platform driver [ 4.495369] uhci_hcd: USB Universal Host Controller Interface driver [ 4.501763] usbcore: registered new interface driver usbserial [ 4.507586] usbcore: registered new interface driver usbserial_generic [ 4.514096] usbserial: USB Serial support registered for generic [ 4.520170] mousedev: PS/2 mouse device common for all mice [ 4.525965] rtc-efi rtc-efi: rtc core: registered rtc-efi as rtc0 [ 4.532140] md: linear personality registered for level -1 [ 4.537609] md: raid0 personality registered for level 0 [ 4.542897] md: raid1 personality registered for level 1 [ 4.548188] md: raid10 personality registered for level 10 [ 4.553714] md: raid6 personality registered for level 6 [ 4.559001] md: raid5 personality registered for level 5 [ 4.564291] md: raid4 personality registered for level 4 [ 4.569580] md: multipath personality registered for level -4 [ 4.575303] md: faulty personality registered for level -5 [ 4.580990] device-mapper: uevent: version 1.0.3 [ 4.585733] device-mapper: ioctl: 4.32.0-ioctl (2015-6-26) initialised: dm-devel@redhat.com [ 4.594219] device-mapper: multipath: version 1.9.0 loaded [ 4.599682] device-mapper: multipath round-robin: version 1.0.0 loaded [ 4.606188] device-mapper: multipath queue-length: version 0.1.0 loaded [ 4.612764] device-mapper: multipath service-time: version 0.2.0 loaded [ 4.619382] device-mapper: raid: Loading target version 1.7.0 [ 4.625304] EFI Variables Facility v0.08 2004-May-17 [ 4.630499] hidraw: raw HID events driver (C) Jiri Kosina [ 4.636091] usbcore: registered new interface driver usbhid [ 4.641637] usbhid: USB HID core driver [ 4.645562] drop_monitor: Initializing network drop monitor service [ 4.651886] ip_tables: (C) 2000-2006 Netfilter Core Team [ 4.657224] Initializing XFRM netlink socket [ 4.661671] NET: Registered protocol family 10 [ 4.663503] ata1: SATA link down (SStatus 0 SControl 4300) [ 4.671879] mip6: Mobile IPv6 [ 4.674853] NET: Registered protocol family 17 [ 4.679309] Key type dns_resolver registered [ 4.683857] registered taskstats version 1 [ 4.687955] zswap: loading zswap [ 4.691169] zswap: using zbud pool [ 4.694575] zswap: using lzo compressor [ 4.698575] rtc-efi rtc-efi: setting system clock to 2015-08-25 00:27:58 UTC (1440462478) [ 4.723509] ata3: SATA link down (SStatus 0 SControl 4300) [ 4.743501] ata4: SATA link down (SStatus 0 SControl 4300) [ 5.063496] ata2: SATA link up 6.0 Gbps (SStatus 133 SControl 4300) [ 5.070950] ata2.00: ATA-8: ST500DM002-1BD142, KC48, max UDMA/133 [ 5.077021] ata2.00: 976773168 sectors, multi 0: LBA48 NCQ (depth 0/32) [ 5.085058] ata2.00: configured for UDMA/133 [ 5.089472] scsi 1:0:0:0: Direct-Access ATA ST500DM002-1BD14 KC48 PQ: 0 ANSI: 5 [ 5.097801] sd 1:0:0:0: [sda] 976773168 512-byte logical blocks: (500 GB/465 GiB) [ 5.097835] sd 1:0:0:0: Attached scsi generic sg0 type 0 [ 5.110550] sd 1:0:0:0: [sda] 4096-byte physical blocks [ 5.115818] sd 1:0:0:0: [sda] Write Protect is off [ 5.120589] sd 1:0:0:0: [sda] Mode Sense: 00 3a 00 00 [ 5.120614] sd 1:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA [ 5.169757] sda: sda1 sda2 sda3 sda4 [ 5.173922] sd 1:0:0:0: [sda] Attached SCSI disk [ 5.178578] Freeing unused kernel memory: 896K (fffffe0000e70000 - fffffe0000f50000) [ 5.192099] random: systemd urandom read with 11 bits of entropy available [ 5.201075] systemd[1]: systemd 222 running in system mode. (+PAM +AUDIT +SELINUX +IMA -APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ -LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD +IDN) [ 5.219172] systemd[1]: Detected architecture arm64. [ 5.224131] systemd[1]: Running in initial RAM disk. [ 5.253605] systemd[1]: Set hostname to <r2-a21>. [ 5.343559] systemd[1]: Created slice -.slice. [ 5.348008] systemd[1]: Starting -.slice. [ 5.373537] systemd[1]: Listening on Journal Audit Socket. [ 5.379016] systemd[1]: Starting Journal Audit Socket. [ 5.403537] systemd[1]: Listening on udev Control Socket. [ 5.408929] systemd[1]: Starting udev Control Socket. [ 5.433528] systemd[1]: Reached target Local File Systems. [ 5.439001] systemd[1]: Starting Local File Systems. [ 5.463535] systemd[1]: Created slice System Slice. [ 5.468411] systemd[1]: Starting System Slice. [ 5.493530] systemd[1]: Reached target Timers. [ 5.497966] systemd[1]: Starting Timers. [ 5.523535] systemd[1]: Listening on udev Kernel Socket. [ 5.528839] systemd[1]: Starting udev Kernel Socket. [ 5.553535] systemd[1]: Listening on Journal Socket. [ 5.558496] systemd[1]: Starting Journal Socket. [ 5.563176] systemd[1]: Started dracut ask for additional cmdline parameters. [ 5.570470] systemd[1]: Started dracut cmdline hook. [ 5.575611] systemd[1]: Started Load Kernel Modules. [ 5.581365] systemd[1]: Starting Setup Virtual Console... [ 5.604527] systemd[1]: Starting Create list of required static device nodes for the current kernel... [ 5.653553] systemd[1]: Listening on Journal Socket (/dev/log). [ 5.659474] systemd[1]: Starting Journal Socket (/dev/log). [ 5.683551] systemd[1]: Reached target Sockets. [ 5.688091] systemd[1]: Starting Sockets. [ 5.692898] systemd[1]: Starting Journal Service... [ 5.733529] systemd[1]: Reached target Swap. [ 5.737799] systemd[1]: Starting Swap. [ 5.741749] systemd[1]: Started dracut pre-udev hook. [ 5.763537] systemd[1]: Reached target Slices. [ 5.767978] systemd[1]: Starting Slices. [ 5.772693] systemd[1]: Starting Apply Kernel Variables... [ 5.813539] systemd[1]: Started Journal Service. [ 5.818278] audit: type=1130 audit(1440462479.610:2): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-journald comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' [ 5.853588] audit: type=1130 audit(1440462479.650:3): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-vconsole-setup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' [ 5.893587] audit: type=1130 audit(1440462479.690:4): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=kmod-static-nodes comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' [ 5.933586] audit: type=1130 audit(1440462479.730:5): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-sysctl comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' [ 6.003604] audit: type=1130 audit(1440462479.800:6): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-tmpfiles-setup-dev comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' [ 6.063627] audit: type=1130 audit(1440462479.860:7): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-udevd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' [ 6.173639] audit: type=1130 audit(1440462479.970:8): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-udev-trigger comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' [ 6.533775] audit: type=1130 audit(1440462480.330:9): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-initqueue comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' [ 6.575643] audit: type=1130 audit(1440462480.370:10): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=plymouth-start comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' [ 7.036359] EXT4-fs (sda4): mounted filesystem with ordered data mode. Opts: (null) [ 7.303838] systemd-journald[181]: Received SIGTERM from PID 1 (systemd). [ 7.927817] SELinux: Disabled at runtime. [ 7.931939] SELinux: Unregistering netfilter hooks [ 8.033515] audit_printk_skb: 63 callbacks suppressed [ 8.038544] audit: type=1404 audit(1440462481.830:32): selinux=0 auid=4294967295 ses=4294967295 [ 9.439801] audit: type=1130 audit(1440462483.230:33): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-journald comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' [ 9.458627] audit: type=1131 audit(1440462483.250:34): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-journald comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' [ 9.477910] audit: type=1130 audit(1440462483.270:35): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=initrd-switch-root comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' [ 9.477933] audit: type=1131 audit(1440462483.270:36): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=initrd-switch-root comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' [ 9.518646] audit: type=1130 audit(1440462483.310:37): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-journald comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' [ 9.538140] audit: type=1131 audit(1440462483.330:38): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-journald comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' [ 9.758610] audit: type=1305 audit(1440462483.550:39): audit_enabled=1 old=1 auid=4294967295 ses=4294967295 res=1 [ 10.091569] audit: type=1130 audit(1440462483.880:40): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-journald comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' [ 10.162813] audit: type=1130 audit(1440462483.950:41): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=kmod-static-nodes comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' [ 10.329088] EXT4-fs (sda4): re-mounted. Opts: (null) [ 10.505742] systemd-journald[324]: Received request to flush runtime journal from PID 1 [ 11.932228] Installing knfsd (copyright (C) 1996 okir@monad.swb.de). [ 12.863048] random: nonblocking pool is initialized [ 14.426187] Adding 2047936k swap on /dev/sda3. Priority:-1 extents:1 across:2047936k FS [ 14.863593] audit_printk_skb: 30 callbacks suppressed [ 14.868627] audit: type=1130 audit(1440462488.660:52): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-fsck@dev-disk-by\x2duuid-94e16179\x2d755d\x2d403c\x2d9a81\x2d767dc3bb2f6f comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' [ 15.044408] EXT4-fs (sda2): mounted filesystem with ordered data mode. Opts: (null) [ 15.143578] audit: type=1130 audit(1440462488.940:53): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-journal-flush comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' [ 15.192960] FAT-fs (sda1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck. [ 15.307718] audit: type=1130 audit(1440462489.100:54): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=dracut-shutdown comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' [ 15.378781] audit: type=1130 audit(1440462489.170:55): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=plymouth-read-write comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' [ 15.397883] audit: type=1131 audit(1440462489.190:56): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=plymouth-read-write comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' [ 15.536017] audit: type=1130 audit(1440462489.330:57): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=nfs-config comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' [ 15.556251] audit: type=1130 audit(1440462489.350:58): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-tmpfiles-setup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' [ 15.653975] audit: type=1305 audit(1440462489.450:59): auid=4294967295 ses=4294967295 op="add_rule" key=(null) list=1 res=1 [ 15.665726] audit: type=1130 audit(1440462489.460:60): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=auditd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' [ 15.771349] audit: type=1305 audit(1440462489.560:61): audit_enabled=1 old=1 auid=4294967295 ses=4294967295 res=1 [ 17.932483] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready [ 17.938498] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready [ 19.474025] xgene-enet 17020000.ethernet eth0: Link is Down [ 19.758932] ip6_tables: (C) 2000-2006 Netfilter Core Team [ 19.937856] Ebtables v2.0 registered [ 20.567065] bridge: automatic filtering via arp/ip/ip6tables has been deprecated. Update your scripts to load br_netfilter if you need this. [ 20.609010] tun: Universal TUN/TAP device driver, 1.6 [ 20.614058] tun: (C) 1999-2004 Max Krasnyansky maxk@qualcomm.com [ 20.704048] device virbr0-nic entered promiscuous mode [ 21.059515] nf_conntrack version 0.5.0 (65536 buckets, 262144 max) [ 21.292384] virbr0: port 1(virbr0-nic) entered listening state [ 21.298218] virbr0: port 1(virbr0-nic) entered listening state [ 21.513561] virbr0: port 1(virbr0-nic) entered disabled state [ 22.474349] xgene-enet 17020000.ethernet eth0: Link is Up - 1Gbps/Full - flow control off [ 22.482507] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready
On 8/25/15 7:33 AM, Al Stone wrote:
On 08/19/2015 09:29 AM, Itaru Kitayama wrote:
Hi Al,
Below is a dmesg excerpt corresponding to the acpi_boot_table_init().
[ 0.000000] ACPI: RSDP 0x00000047FA397014 000024 (v02 APM ) [ 0.000000] ACPI: XSDT 0x00000047FA3960E8 000064 (v01 APM XGENE 00000003 01000013) [ 0.000000] ACPI: FACP 0x00000047FA38E000 00010C (v05 APM XGENE 00000003 INTL 20141107) [ 0.000000] ACPI: DSDT 0x00000047FA38F000 003D82 (v05 APM APM88xxx 00000001 INTL 20141107) [ 0.000000] ACPI: DBG2 0x00000047FA393000 0000AA (v00 APMC0D XGENEDBG 00000000 INTL 20141107) [ 0.000000] ACPI: GTDT 0x00000047FA38C000 0000E0 (v02 APM XGENE 00000001 INTL 20141107) [ 0.000000] ACPI: MCFG 0x00000047FA38B000 00003C (v01 APM XGENE 00000002 INTL 20141107) [ 0.000000] ACPI: SPCR 0x00000047FA38A000 000050 (v02 APMC0D XGENESPC 00000000 INTL 20141107) [ 0.000000] ACPI: SSDT 0x00000047FA389000 00002D (v02 APM XGENE 00000001 INTL 20141107) [ 0.000000] ACPI: APIC 0x00000047FA388000 0002A4 (v03 APM XGENE 00000003 01000013) [ 0.000000] ACPI: SSDT 0x00000047FA387000 000063 (v02 REDHAT MACADDRS 00000001 01000013)
I've been testing the Mustang topic branch on the platform with the Rev 1 CPUs with 32 GB memory.
Okay. That's interesting output, but it unfortunately tells me nothing. Those messages only indicate that the kernel has found ACPI tables with those names, at those addresses, and that they have valid signatures in them. The content of the tables could be completely bogus, unless the kernel command acpi_force_table_verification was used, which will at least verify the checksums in the tables are correct, even if the content is not examined. I can deduce that acpi=force has been used, but that's about it.
What would be more useful is the remainder of the dmesg/console output so we can tell how far the kernel got in using the ACPI tables that it found, all the way up to the very last message out.
On 8/18/15 8:02 AM, Itaru Kitayama wrote:
Hi Al,
Even with your .config the HEAD of acpi-topic-mustang does not boot with acpi=force. Here are the messages appear on the console:
EFI stub: Booting Linux Kernel... EFI stub: Using DTB from configuration table EFI stub: Exiting boot services and installing virtual address map... XhcClearBiosOwnership: called to clear BIOS ownership XhcClearBiosOwnership: called to clear BIOS ownership L3c Cache: 8MB
On 8/18/15 6:58 AM, Itaru Kitayama wrote:
Hi Al,
Where does it "stall"? What's the last message you see? And if you're not using earlycon, please do so; that will help determine how far the boot is getting.
If I boot it with acpi=force, no output. Does my usage below look correct?
GRUB_CMDLINE_LINUX="earlycon=uart8250,mmio32,0x1c021000 acpi=force console=ttyS0,115200 loglevel=7"
How were those checked?
In setup_arch() I was calling disable_acpi() right after the>>> Itaru
Linaro-acpi mailing list Linaro-acpi@lists.linaro.org https://lists.linaro.org/mailman/listinfo/linaro-acpi
acpi_boot_table_init() call as that makes the system get to the prompt.
I am about to build the kernel with your .config and test it on one of Mustangs in the Linaro Austin lab. The Boot firmware version is 1.1.0-rh-0.15.
I may have mentioned this before (or not), but I think we need to get this Mustang updated to newer firmware; while that version is okay, the preferred version is the latest 1.15.x from APM (x = 18 or greater, I believe). We may have to figure out how to get the Austin lab updated somehow.
Linaro-acpi mailing list Linaro-acpi@lists.linaro.org https://lists.linaro.org/mailman/listinfo/linaro-acpi
Yes, I see it set to y in the .config file
On 8/25/15 9:00 PM, G Gregory wrote:
Just to check, you do have
CONFIG_ARM64_64K_PAGES=y
in your .config?
Graeme
On 25 August 2015 at 08:59, Itaru Kitayama itaru.kitayama@riken.jp wrote:
Hi Al,
Below is the dmesg. In the modified kernel I call disable_acpi() right after acpi_boot_table_init(). (Otherwise, no meaningful output is obtained even with earlycon)
[ 0.000000] Booting Linux on physical CPU 0x0 [ 0.000000] Initializing cgroup subsys cpuset [ 0.000000] Initializing cgroup subsys cpu [ 0.000000] Initializing cgroup subsys cpuacct [ 0.000000] Linux version 4.2.0-rc5+ (root@r2-a21) (gcc version 5.1.1 20150618 (Red Hat 5.1.1-4) (GCC) ) #23 SMP Mon Aug 24 20:29:06 EDT 2015 [ 0.000000] CPU: AArch64 Processor [500f0001] revision 1 [ 0.000000] Detected PIPT I-cache on CPU0 [ 0.000000] earlycon: Early serial console at MMIO32 0x1c021000 (options '') [ 0.000000] bootconsole [uart0] enabled [ 0.000000] efi: Getting EFI parameters from FDT: [ 0.000000] efi: System Table: 0x00000047ff54df18 [ 0.000000] efi: MemMap Address: 0x00000047f8684018 [ 0.000000] efi: MemMap Size: 0x00000750 [ 0.000000] efi: MemMap Desc. Size: 0x00000030 [ 0.000000] efi: MemMap Desc. Version: 0x00000001 [ 0.000000] EFI v2.40 by X-Gene Mustang Board EFI Mar 13 2015 14:20:53 [ 0.000000] efi: ACPI=0x47fa397000 ACPI 2.0=0x47fa397014 SMBIOS 3.0=0x47fa395000 [ 0.000000] Processing EFI memory map: [ 0.000000] 0x004000000000-0x004000007fff [Conventional Memory| | | | | |WB|WT|WC|UC] [ 0.000000] Ignoring memory block 0x4000000000 - 0x4000010000
[ 0.000000] 0x004000008000-0x00400000ffff [Reserved | | | | | |WB|WT|WC|UC] [ 0.000000] Ignoring memory block 0x4000000000 - 0x4000010000 [ 0.000000] * [ 0.000000] 0x004000010000-0x00400001ffff [Loader Data | | | | | |WB|WT|WC|UC] [ 0.000000] Ignoring memory block 0x4000010000 - 0x4000020000
[ 0.000000] 0x004000020000-0x0040001fffff [Conventional Memory| | | | | |WB|WT|WC|UC] [ 0.000000] Ignoring memory range 0x4000020000 - 0x4000200000
[ 0.000000] 0x004000200000-0x00400147ffff [Loader Data | | | | | |WB|WT|WC|UC] [ 0.000000] 0x004001480000-0x00401fdfffff [Conventional Memory| | | | | |WB|WT|WC|UC] [ 0.000000] 0x00401fe00000-0x00401fe0ffff [Loader Data | | | | | |WB|WT|WC|UC] [ 0.000000] 0x00401fe10000-0x004793ffffff [Conventional Memory| | | | | |WB|WT|WC|UC] [ 0.000000] 0x004794000000-0x0047f7ffffff [Loader Data | | | | | |WB|WT|WC|UC] [ 0.000000] 0x0047f8000000-0x0047f801ffff [Boot Data | | | | | |WB|WT|WC|UC] [ 0.000000] 0x0047f8020000-0x0047f8683fff [Conventional Memory| | | | | |WB|WT|WC|UC] [ 0.000000] 0x0047f8684000-0x0047f9ffffff [Loader Data | | | | | |WB|WT|WC|UC] [ 0.000000] 0x0047fa000000-0x0047fa0cdfff [Loader Code | | | | | |WB|WT|WC|UC] [ 0.000000] 0x0047fa0ce000-0x0047fa378fff [Boot Code | | | | | |WB|WT|WC|UC] [ 0.000000] 0x0047fa379000-0x0047fa386fff [Runtime Code |RUN| | | | |WB|WT|WC|UC]* [ 0.000000] 0x0047fa387000-0x0047fa38cfff [ACPI Reclaim Memory| | | | | |WB|WT|WC|UC]* [ 0.000000] 0x0047fa38d000-0x0047fa38dfff [ACPI Memory NVS | | | | | |WB|WT|WC|UC]* [ 0.000000] 0x0047fa38e000-0x0047fa393fff [ACPI Reclaim Memory| | | | | |WB|WT|WC|UC]* [ 0.000000] 0x0047fa394000-0x0047fa395fff [Reserved | | | | | |WB|WT|WC|UC]* [ 0.000000] 0x0047fa396000-0x0047fa397fff [ACPI Reclaim Memory| | | | | |WB|WT|WC|UC]* [ 0.000000] 0x0047fa398000-0x0047fa39ffff [Runtime Code |RUN| | | | |WB|WT|WC|UC]* [ 0.000000] 0x0047fa3a0000-0x0047fa3c0fff [Runtime Data |RUN| | | | |WB|WT|WC|UC]* [ 0.000000] 0x0047fa3c1000-0x0047fa3d2fff [Runtime Code |RUN| | | | |WB|WT|WC|UC]* [ 0.000000] 0x0047fa3d3000-0x0047fa534fff [Runtime Data |RUN| | | | |WB|WT|WC|UC]* [ 0.000000] 0x0047fa535000-0x0047fa543fff [Runtime Code |RUN| | | | |WB|WT|WC|UC]* [ 0.000000] 0x0047fa544000-0x0047fb90ffff [Conventional Memory| | | | | |WB|WT|WC|UC] [ 0.000000] 0x0047fb910000-0x0047fbdfcfff [Boot Data | | | | | |WB|WT|WC|UC] [ 0.000000] 0x0047fbdfd000-0x0047fc1affff [Conventional Memory| | | | | |WB|WT|WC|UC] [ 0.000000] 0x0047fc1b0000-0x0047fe092fff [Boot Data | | | | | |WB|WT|WC|UC] [ 0.000000] 0x0047fe093000-0x0047fe11cfff [Conventional Memory| | | | | |WB|WT|WC|UC] [ 0.000000] 0x0047fe11d000-0x0047ff377fff [Boot Data | | | | | |WB|WT|WC|UC] [ 0.000000] 0x0047ff378000-0x0047ff507fff [Boot Code | | | | | |WB|WT|WC|UC] [ 0.000000] 0x0047ff508000-0x0047ff51bfff [Runtime Code |RUN| | | | |WB|WT|WC|UC]* [ 0.000000] 0x0047ff51c000-0x0047ff54dfff [Runtime Data |RUN| | | | |WB|WT|WC|UC]* [ 0.000000] 0x0047ff54e000-0x0047ffffffff [Boot Data | | | | | |WB|WT|WC|UC] [ 0.000000] 0x000010510000-0x000010510fff [Memory Mapped I/O |RUN| | | | | | | | ] [ 0.000000] 0x000010548000-0x000010549fff [Memory Mapped I/O |RUN| | | | | | | | ] [ 0.000000] 0x000017000000-0x000017001fff [Memory Mapped I/O |RUN| | | | | | | | ] [ 0.000000] 0x00001c025000-0x00001c025fff [Memory Mapped I/O |RUN| | | | | | | | ] [ 0.000000] cma: Reserved 512 MiB at 0x00000040e0000000 [ 0.000000] ACPI: Early table checksum verification enabled [ 0.000000] ACPI: RSDP 0x00000047FA397014 000024 (v02 APM ) [ 0.000000] ACPI: XSDT 0x00000047FA3960E8 000064 (v01 APM XGENE 00000003 01000013) [ 0.000000] ACPI: FACP 0x00000047FA38E000 00010C (v05 APM XGENE 00000003 INTL 20141107) [ 0.000000] ACPI: DSDT 0x00000047FA38F000 003D82 (v05 APM APM88xxx 00000001 INTL 20141107) [ 0.000000] ACPI: DBG2 0x00000047FA393000 0000AA (v00 APMC0D XGENEDBG 00000000 INTL 20141107) [ 0.000000] ACPI: GTDT 0x00000047FA38C000 0000E0 (v02 APM XGENE 00000001 INTL 20141107) [ 0.000000] ACPI: MCFG 0x00000047FA38B000 00003C (v01 APM XGENE 00000002 INTL 20141107) [ 0.000000] ACPI: SPCR 0x00000047FA38A000 000050 (v02 APMC0D XGENESPC 00000000 INTL 20141107) [ 0.000000] ACPI: SSDT 0x00000047FA389000 00002D (v02 APM XGENE 00000001 INTL 20141107) [ 0.000000] ACPI: APIC 0x00000047FA388000 0002A4 (v03 APM XGENE 00000003 01000013) [ 0.000000] ACPI: SSDT 0x00000047FA387000 000063 (v02 REDHAT MACADDRS 00000001 01000013) [ 0.000000] On node 0 totalpages: 524256 [ 0.000000] DMA zone: 64 pages used for memmap [ 0.000000] DMA zone: 0 pages reserved [ 0.000000] DMA zone: 65504 pages, LIFO batch:1 [ 0.000000] Normal zone: 448 pages used for memmap [ 0.000000] Normal zone: 458752 pages, LIFO batch:1 [ 0.000000] PERCPU: Embedded 2 pages/cpu @fffffe07ffca0000 s90688 r8192 d32192 u131072 [ 0.000000] pcpu-alloc: s90688 r8192 d32192 u131072 alloc=2*65536 [ 0.000000] pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 [0] 4 [0] 5 [0] 6 [0] 7 [ 0.000000] Built 1 zonelists in Zone order, mobility grouping on. Total pages: 523744 [ 0.000000] Kernel command line: BOOT_IMAGE=/vmlinuz-4.2.0-rc5+ root=UUID=60349261-1e0c-4dd3-9a59-474425cecfe4 ro earlycon=uart8250,mmio32,0x1c021000 acpi=force acpi_force_table_verification uefi_debug LANG=en_US.UTF-8 [ 0.000000] PID hash table entries: 4096 (order: -1, 32768 bytes) [ 0.000000] Dentry cache hash table entries: 4194304 (order: 9, 33554432 bytes) [ 0.000000] Inode-cache hash table entries: 2097152 (order: 8, 16777216 bytes) [ 0.000000] software IO TLB [mem 0x40dbff0000-0x40dfff0000] (64MB) mapped at [fffffe00dbdf0000-fffffe00dfdeffff] [ 0.000000] Memory: 32844224K/33552384K available (8778K kernel code, 1322K rwdata, 5376K rodata, 896K init, 1504K bss, 183872K reserved, 524288K cma-reserved) [ 0.000000] Virtual kernel memory layout: vmalloc : 0xfffffc0000000000 - 0xfffffdfedfff0000 ( 2043 GB) vmemmap : 0xfffffdfee0000000 - 0xfffffdffe0000000 ( 4 GB maximum) 0xfffffdfef0000800 - 0xfffffdfef2000000 ( 31 MB actual) fixed : 0xfffffdfffa7d0000 - 0xfffffdfffac00000 ( 4288 KB) PCI I/O : 0xfffffdfffae00000 - 0xfffffdfffbe00000 ( 16 MB) modules : 0xfffffdfffc000000 - 0xfffffe0000000000 ( 64 MB) memory : 0xfffffe0000000000 - 0xfffffe07ffe00000 ( 32766 MB) .init : 0xfffffe0000e70000 - 0xfffffe0000f50000 ( 896 KB) .text : 0xfffffe0000080000 - 0xfffffe0000e62894 ( 14219 KB) .data : 0xfffffe0000f60000 - 0xfffffe00010aaa00 ( 1323 KB) [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=8, Nodes=1 [ 0.000000] Hierarchical RCU implementation. [ 0.000000] Build-time adjustment of leaf fanout to 64. [ 0.000000] NR_IRQS:64 nr_irqs:64 0 [ 0.000000] Offload RCU callbacks from all CPUs [ 0.000000] Offload RCU callbacks from CPUs: 0-7. [ 0.000000] Architected cp15 timer(s) running at 50.00MHz (phys). [ 0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0xb8812736b, max_idle_ns: 440795202655 ns [ 0.000002] sched_clock: 56 bits at 50MHz, resolution 20ns, wraps every 4398046511100ns [ 0.008570] Console: colour dummy device 80x25 [ 0.013275] console [tty0] enabled [ 0.016875] bootconsole [uart0] disabled [ 0.021012] Calibrating delay loop (skipped), value calculated using timer frequency.. 100.00 BogoMIPS (lpj=500000) [ 0.021020] pid_max: default: 32768 minimum: 301 [ 0.021062] Security Framework initialized [ 0.021069] SELinux: Initializing. [ 0.021085] SELinux: Starting in permissive mode [ 0.021087] Yama: becoming mindful. [ 0.021151] Mount-cache hash table entries: 65536 (order: 3, 524288 bytes) [ 0.021157] Mountpoint-cache hash table entries: 65536 (order: 3, 524288 bytes) [ 0.021614] Initializing cgroup subsys blkio [ 0.021625] Initializing cgroup subsys memory [ 0.021637] Initializing cgroup subsys devices [ 0.021643] Initializing cgroup subsys freezer [ 0.021648] Initializing cgroup subsys net_cls [ 0.021653] Initializing cgroup subsys perf_event [ 0.021658] Initializing cgroup subsys net_prio [ 0.021675] ftrace: allocating 29825 entries in 8 pages [ 0.041956] hw perfevents: enabled with arm/armv8-pmuv3 PMU driver, 5 counters available [ 0.041975] Remapping and enabling EFI services. [ 0.041980] EFI remap 0x00000047fa379000 => 0000000040009000 [ 0.041990] EFI remap 0x00000047fa398000 => 0000000040028000 [ 0.041993] EFI remap 0x00000047fa3a0000 => 0000000040030000 [ 0.042000] EFI remap 0x00000047fa3c1000 => 0000000040061000 [ 0.042005] EFI remap 0x00000047fa3d3000 => 0000000040083000 [ 0.042011] EFI remap 0x00000047fa535000 => 00000000401f5000 [ 0.042016] EFI remap 0x00000047ff508000 => 0000000040218000 [ 0.042021] EFI remap 0x00000047ff51c000 => 000000004023c000 [ 0.042026] EFI remap 0x0000000010510000 => 0000000040270000 [ 0.042031] EFI remap 0x0000000010548000 => 0000000040288000 [ 0.042036] EFI remap 0x0000000017000000 => 0000000040290000 [ 0.042041] EFI remap 0x000000001c025000 => 00000000402a5000 [ 0.043032] CPU1: Booted secondary processor [ 0.043036] Detected PIPT I-cache on CPU1 [ 0.043207] CPU2: Booted secondary processor [ 0.043210] Detected PIPT I-cache on CPU2 [ 0.043363] CPU3: Booted secondary processor [ 0.043365] Detected PIPT I-cache on CPU3 [ 0.043512] CPU4: Booted secondary processor [ 0.043516] Detected PIPT I-cache on CPU4 [ 0.043668] CPU5: Booted secondary processor [ 0.043670] Detected PIPT I-cache on CPU5 [ 0.043818] CPU6: Booted secondary processor [ 0.043822] Detected PIPT I-cache on CPU6 [ 0.043980] CPU7: Booted secondary processor [ 0.043982] Detected PIPT I-cache on CPU7 [ 0.044013] Brought up 8 CPUs [ 0.044071] SMP: Total of 8 processors activated. [ 0.044076] CPU: All CPU(s) started at EL2 [ 0.044586] devtmpfs: initialized [ 0.045714] SMBIOS 3.0.0 present. [ 0.045823] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns [ 0.046202] xor: measuring software checksum speed [ 0.141826] 8regs : 5747.200 MB/sec [ 0.241847] 8regs_prefetch: 5337.600 MB/sec [ 0.341865] 32regs : 5836.800 MB/sec [ 0.441895] 32regs_prefetch: 4582.400 MB/sec [ 0.441901] xor: using function: 32regs (5836.800 MB/sec) [ 0.441923] atomic64_test: passed [ 0.442171] NET: Registered protocol family 16 [ 0.442467] vdso: 2 pages (1 code @ fffffe0000f80000, 1 data @ fffffe0000f70000) [ 0.442488] hw-breakpoint: found 4 breakpoint and 4 watchpoint registers. [ 0.442773] DMA: preallocated 256 KiB pool for atomic allocations [ 0.442807] Serial: AMBA PL011 UART driver [ 0.621938] raid6: int64x1 gen() 3041 MB/s [ 0.791995] raid6: int64x1 xor() 646 MB/s [ 0.962000] raid6: int64x2 gen() 4565 MB/s [ 1.132056] raid6: int64x2 xor() 902 MB/s [ 1.302069] raid6: int64x4 gen() 5034 MB/s [ 1.472108] raid6: int64x4 xor() 1107 MB/s [ 1.642154] raid6: int64x8 gen() 2707 MB/s [ 1.812178] raid6: int64x8 xor() 873 MB/s [ 1.982202] raid6: neonx1 gen() 3651 MB/s [ 2.152241] raid6: neonx2 gen() 4944 MB/s [ 2.322263] raid6: neonx4 gen() 5394 MB/s [ 2.492292] raid6: neonx8 gen() 4395 MB/s [ 2.492297] raid6: using algorithm neonx4 gen() 5394 MB/s [ 2.492303] raid6: using intx1 recovery algorithm [ 2.492384] ACPI: Interpreter disabled. [ 2.492550] vgaarb: loaded [ 2.492775] SCSI subsystem initialized [ 2.492860] libata version 3.00 loaded. [ 2.492938] usbcore: registered new interface driver usbfs [ 2.492959] usbcore: registered new interface driver hub [ 2.492997] usbcore: registered new device driver usb [ 2.493357] NetLabel: Initializing [ 2.493364] NetLabel: domain hash size = 128 [ 2.493369] NetLabel: protocols = UNLABELED CIPSOv4 [ 2.493394] NetLabel: unlabeled traffic allowed by default [ 2.493492] clocksource: Switched to clocksource arch_sys_counter [ 2.514940] pnp: PnP ACPI: disabled [ 2.517885] NET: Registered protocol family 2 [ 2.518149] TCP established hash table entries: 262144 (order: 5, 2097152 bytes) [ 2.518820] TCP bind hash table entries: 65536 (order: 4, 1048576 bytes) [ 2.519190] TCP: Hash tables configured (established 262144 bind 65536) [ 2.519229] UDP hash table entries: 16384 (order: 3, 524288 bytes) [ 2.519504] UDP-Lite hash table entries: 16384 (order: 3, 524288 bytes) [ 2.519888] NET: Registered protocol family 1 [ 2.520040] RPC: Registered named UNIX socket transport module. [ 2.520048] RPC: Registered udp transport module. [ 2.520053] RPC: Registered tcp transport module. [ 2.520058] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 2.520068] PCI: CLS 0 bytes, default 64 [ 2.520168] Unpacking initramfs... [ 2.738416] Freeing initrd memory: 9472K (fffffe07f8490000 - fffffe07f8dd0000) [ 2.738687] kvm [1]: GICV size 0x2000 not a multiple of page size 0x10000 [ 2.738697] kvm [1]: error: no compatible GIC info found [ 2.738792] kvm [1]: error initializing Hyp mode: -6 [ 2.739225] futex hash table entries: 2048 (order: 1, 131072 bytes) [ 2.739270] audit: initializing netlink subsys (disabled) [ 2.739300] audit: type=2000 audit(2.720:1): initialized [ 2.739602] HugeTLB registered 512 MB page size, pre-allocated 0 pages [ 2.742336] zpool: loaded [ 2.742345] zbud: loaded [ 2.742554] VFS: Disk quotas dquot_6.6.0 [ 2.742626] VFS: Dquot-cache hash table entries: 8192 (order 0, 65536 bytes) [ 2.743537] NFS: Registering the id_resolver key type [ 2.743564] Key type id_resolver registered [ 2.743569] Key type id_legacy registered [ 2.743580] nfs4filelayout_init: NFSv4 File Layout Driver Registering... [ 2.743683] SGI XFS with ACLs, security attributes, no debug enabled [ 2.744386] Key type big_key registered [ 2.744397] SELinux: Registering netfilter hooks [ 2.750019] NET: Registered protocol family 38 [ 2.750042] async_tx: api initialized (async) [ 2.750092] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 252) [ 2.750153] io scheduler noop registered [ 2.750162] io scheduler deadline registered [ 2.750184] io scheduler cfq registered (default) [ 2.750556] pci_hotplug: PCI Hot Plug PCI Core version: 0.5 [ 2.750574] pciehp: PCI Express Hot Plug Controller Driver version: 0.4 [ 2.750620] PCI host bridge /soc/pcie@1f2b0000 ranges: [ 2.750636] IO 0xe010000000..0xe01000ffff -> 0x00000000 [ 2.750644] MEM 0xe180000000..0xe1ffffffff -> 0x80000000 [ 2.750677] xgene-pcie 1f2b0000.pcie: (rc) link down [ 2.750738] xgene-pcie 1f2b0000.pcie: PCI host bridge to bus 0000:00 [ 2.750747] pci_bus 0000:00: root bus resource [bus 00-ff] [ 2.750753] pci_bus 0000:00: root bus resource [io 0x0000-0xffff] [ 2.750761] pci_bus 0000:00: root bus resource [mem 0xe180000000-0xe1ffffffff] (bus address [0x80000000-0xffffffff]) [ 2.750770] xgene-pcie 1f2b0000.pcie: failed to enable MSI [ 2.750793] pci 0000:00:00.0: [10e8:e004] type 01 class 0x060400 [ 2.750831] pci 0000:00:00.0: IOMMU is currently not supported for PCI [ 2.750865] pci 0000:00:00.0: supports D1 D2 [ 2.751022] pci 0000:00:00.0: PCI bridge to [bus 01] [ 2.751118] pcieport 0000:00:00.0: Signaling PME through PCIe PME interrupt [ 2.751129] pcie_pme 0000:00:00.0:pcie01: service driver pcie_pme loaded [ 2.751178] aer 0000:00:00.0:pcie02: service driver aer loaded [ 2.751350] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 2.751909] 1c020000.serial: ttyS0 at MMIO 0x1c020000 (irq = 7, base_baud = 3125000) is a 16550A [ 4.244358] console [ttyS0] enabled [ 4.248184] Unable to detect cache hierarchy from DT for CPU 0 [ 4.254204] xgene-ahci 1a400000.sata: skip clock and PHY initialization [ 4.260798] xgene-ahci 1a400000.sata: controller can't do NCQ, turning off CAP_NCQ [ 4.268363] xgene-ahci 1a400000.sata: AHCI 0001.0300 32 slots 2 ports 6 Gbps 0x3 impl platform mode [ 4.277373] xgene-ahci 1a400000.sata: flags: 64bit sntf pm only pmp fbs pio slum part ccc [ 4.285609] xgene-ahci 1a400000.sata: port 0 is not capable of FBS [ 4.291825] xgene-ahci 1a400000.sata: port 1 is not capable of FBS [ 4.298355] scsi host0: xgene-ahci [ 4.301882] scsi host1: xgene-ahci [ 4.305353] ata1: SATA max UDMA/133 mmio [mem 0x1a400000-0x1a400fff] port 0x100 irq 8 [ 4.313148] ata2: SATA max UDMA/133 mmio [mem 0x1a400000-0x1a400fff] port 0x180 irq 8 [ 4.320975] xgene-ahci 1a800000.sata: skip clock and PHY initialization [ 4.327570] xgene-ahci 1a800000.sata: controller can't do NCQ, turning off CAP_NCQ [ 4.335124] xgene-ahci 1a800000.sata: AHCI 0001.0300 32 slots 2 ports 6 Gbps 0x3 impl platform mode [ 4.344134] xgene-ahci 1a800000.sata: flags: 64bit sntf pm only pmp fbs pio slum part ccc [ 4.352362] xgene-ahci 1a800000.sata: port 0 is not capable of FBS [ 4.358544] xgene-ahci 1a800000.sata: port 1 is not capable of FBS [ 4.365054] scsi host2: xgene-ahci [ 4.368571] scsi host3: xgene-ahci [ 4.372032] ata3: SATA max UDMA/133 mmio [mem 0x1a800000-0x1a800fff] port 0x100 irq 9 [ 4.379840] ata4: SATA max UDMA/133 mmio [mem 0x1a800000-0x1a800fff] port 0x180 irq 9 [ 4.387756] libphy: Fixed MDIO Bus: probed [ 4.392313] libphy: APM X-Gene MDIO bus: probed [ 4.473815] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver [ 4.480322] ehci-pci: EHCI PCI platform driver [ 4.484772] ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver [ 4.490928] ohci-pci: OHCI PCI platform driver [ 4.495369] uhci_hcd: USB Universal Host Controller Interface driver [ 4.501763] usbcore: registered new interface driver usbserial [ 4.507586] usbcore: registered new interface driver usbserial_generic [ 4.514096] usbserial: USB Serial support registered for generic [ 4.520170] mousedev: PS/2 mouse device common for all mice [ 4.525965] rtc-efi rtc-efi: rtc core: registered rtc-efi as rtc0 [ 4.532140] md: linear personality registered for level -1 [ 4.537609] md: raid0 personality registered for level 0 [ 4.542897] md: raid1 personality registered for level 1 [ 4.548188] md: raid10 personality registered for level 10 [ 4.553714] md: raid6 personality registered for level 6 [ 4.559001] md: raid5 personality registered for level 5 [ 4.564291] md: raid4 personality registered for level 4 [ 4.569580] md: multipath personality registered for level -4 [ 4.575303] md: faulty personality registered for level -5 [ 4.580990] device-mapper: uevent: version 1.0.3 [ 4.585733] device-mapper: ioctl: 4.32.0-ioctl (2015-6-26) initialised: dm-devel@redhat.com [ 4.594219] device-mapper: multipath: version 1.9.0 loaded [ 4.599682] device-mapper: multipath round-robin: version 1.0.0 loaded [ 4.606188] device-mapper: multipath queue-length: version 0.1.0 loaded [ 4.612764] device-mapper: multipath service-time: version 0.2.0 loaded [ 4.619382] device-mapper: raid: Loading target version 1.7.0 [ 4.625304] EFI Variables Facility v0.08 2004-May-17 [ 4.630499] hidraw: raw HID events driver (C) Jiri Kosina [ 4.636091] usbcore: registered new interface driver usbhid [ 4.641637] usbhid: USB HID core driver [ 4.645562] drop_monitor: Initializing network drop monitor service [ 4.651886] ip_tables: (C) 2000-2006 Netfilter Core Team [ 4.657224] Initializing XFRM netlink socket [ 4.661671] NET: Registered protocol family 10 [ 4.663503] ata1: SATA link down (SStatus 0 SControl 4300) [ 4.671879] mip6: Mobile IPv6 [ 4.674853] NET: Registered protocol family 17 [ 4.679309] Key type dns_resolver registered [ 4.683857] registered taskstats version 1 [ 4.687955] zswap: loading zswap [ 4.691169] zswap: using zbud pool [ 4.694575] zswap: using lzo compressor [ 4.698575] rtc-efi rtc-efi: setting system clock to 2015-08-25 00:27:58 UTC (1440462478) [ 4.723509] ata3: SATA link down (SStatus 0 SControl 4300) [ 4.743501] ata4: SATA link down (SStatus 0 SControl 4300) [ 5.063496] ata2: SATA link up 6.0 Gbps (SStatus 133 SControl 4300) [ 5.070950] ata2.00: ATA-8: ST500DM002-1BD142, KC48, max UDMA/133 [ 5.077021] ata2.00: 976773168 sectors, multi 0: LBA48 NCQ (depth 0/32) [ 5.085058] ata2.00: configured for UDMA/133 [ 5.089472] scsi 1:0:0:0: Direct-Access ATA ST500DM002-1BD14 KC48 PQ: 0 ANSI: 5 [ 5.097801] sd 1:0:0:0: [sda] 976773168 512-byte logical blocks: (500 GB/465 GiB) [ 5.097835] sd 1:0:0:0: Attached scsi generic sg0 type 0 [ 5.110550] sd 1:0:0:0: [sda] 4096-byte physical blocks [ 5.115818] sd 1:0:0:0: [sda] Write Protect is off [ 5.120589] sd 1:0:0:0: [sda] Mode Sense: 00 3a 00 00 [ 5.120614] sd 1:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA [ 5.169757] sda: sda1 sda2 sda3 sda4 [ 5.173922] sd 1:0:0:0: [sda] Attached SCSI disk [ 5.178578] Freeing unused kernel memory: 896K (fffffe0000e70000 - fffffe0000f50000) [ 5.192099] random: systemd urandom read with 11 bits of entropy available [ 5.201075] systemd[1]: systemd 222 running in system mode. (+PAM +AUDIT +SELINUX +IMA -APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ -LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD +IDN) [ 5.219172] systemd[1]: Detected architecture arm64. [ 5.224131] systemd[1]: Running in initial RAM disk. [ 5.253605] systemd[1]: Set hostname to <r2-a21>. [ 5.343559] systemd[1]: Created slice -.slice. [ 5.348008] systemd[1]: Starting -.slice. [ 5.373537] systemd[1]: Listening on Journal Audit Socket. [ 5.379016] systemd[1]: Starting Journal Audit Socket. [ 5.403537] systemd[1]: Listening on udev Control Socket. [ 5.408929] systemd[1]: Starting udev Control Socket. [ 5.433528] systemd[1]: Reached target Local File Systems. [ 5.439001] systemd[1]: Starting Local File Systems. [ 5.463535] systemd[1]: Created slice System Slice. [ 5.468411] systemd[1]: Starting System Slice. [ 5.493530] systemd[1]: Reached target Timers. [ 5.497966] systemd[1]: Starting Timers. [ 5.523535] systemd[1]: Listening on udev Kernel Socket. [ 5.528839] systemd[1]: Starting udev Kernel Socket. [ 5.553535] systemd[1]: Listening on Journal Socket. [ 5.558496] systemd[1]: Starting Journal Socket. [ 5.563176] systemd[1]: Started dracut ask for additional cmdline parameters. [ 5.570470] systemd[1]: Started dracut cmdline hook. [ 5.575611] systemd[1]: Started Load Kernel Modules. [ 5.581365] systemd[1]: Starting Setup Virtual Console... [ 5.604527] systemd[1]: Starting Create list of required static device nodes for the current kernel... [ 5.653553] systemd[1]: Listening on Journal Socket (/dev/log). [ 5.659474] systemd[1]: Starting Journal Socket (/dev/log). [ 5.683551] systemd[1]: Reached target Sockets. [ 5.688091] systemd[1]: Starting Sockets. [ 5.692898] systemd[1]: Starting Journal Service... [ 5.733529] systemd[1]: Reached target Swap. [ 5.737799] systemd[1]: Starting Swap. [ 5.741749] systemd[1]: Started dracut pre-udev hook. [ 5.763537] systemd[1]: Reached target Slices. [ 5.767978] systemd[1]: Starting Slices. [ 5.772693] systemd[1]: Starting Apply Kernel Variables... [ 5.813539] systemd[1]: Started Journal Service. [ 5.818278] audit: type=1130 audit(1440462479.610:2): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-journald comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' [ 5.853588] audit: type=1130 audit(1440462479.650:3): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-vconsole-setup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' [ 5.893587] audit: type=1130 audit(1440462479.690:4): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=kmod-static-nodes comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' [ 5.933586] audit: type=1130 audit(1440462479.730:5): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-sysctl comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' [ 6.003604] audit: type=1130 audit(1440462479.800:6): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-tmpfiles-setup-dev comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' [ 6.063627] audit: type=1130 audit(1440462479.860:7): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-udevd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' [ 6.173639] audit: type=1130 audit(1440462479.970:8): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-udev-trigger comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' [ 6.533775] audit: type=1130 audit(1440462480.330:9): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-initqueue comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' [ 6.575643] audit: type=1130 audit(1440462480.370:10): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=plymouth-start comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' [ 7.036359] EXT4-fs (sda4): mounted filesystem with ordered data mode. Opts: (null) [ 7.303838] systemd-journald[181]: Received SIGTERM from PID 1 (systemd). [ 7.927817] SELinux: Disabled at runtime. [ 7.931939] SELinux: Unregistering netfilter hooks [ 8.033515] audit_printk_skb: 63 callbacks suppressed [ 8.038544] audit: type=1404 audit(1440462481.830:32): selinux=0 auid=4294967295 ses=4294967295 [ 9.439801] audit: type=1130 audit(1440462483.230:33): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-journald comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' [ 9.458627] audit: type=1131 audit(1440462483.250:34): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-journald comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' [ 9.477910] audit: type=1130 audit(1440462483.270:35): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=initrd-switch-root comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' [ 9.477933] audit: type=1131 audit(1440462483.270:36): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=initrd-switch-root comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' [ 9.518646] audit: type=1130 audit(1440462483.310:37): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-journald comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' [ 9.538140] audit: type=1131 audit(1440462483.330:38): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-journald comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' [ 9.758610] audit: type=1305 audit(1440462483.550:39): audit_enabled=1 old=1 auid=4294967295 ses=4294967295 res=1 [ 10.091569] audit: type=1130 audit(1440462483.880:40): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-journald comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' [ 10.162813] audit: type=1130 audit(1440462483.950:41): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=kmod-static-nodes comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' [ 10.329088] EXT4-fs (sda4): re-mounted. Opts: (null) [ 10.505742] systemd-journald[324]: Received request to flush runtime journal from PID 1 [ 11.932228] Installing knfsd (copyright (C) 1996 okir@monad.swb.de). [ 12.863048] random: nonblocking pool is initialized [ 14.426187] Adding 2047936k swap on /dev/sda3. Priority:-1 extents:1 across:2047936k FS [ 14.863593] audit_printk_skb: 30 callbacks suppressed [ 14.868627] audit: type=1130 audit(1440462488.660:52): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-fsck@dev-disk-by\x2duuid-94e16179\x2d755d\x2d403c\x2d9a81\x2d767dc3bb2f6f comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' [ 15.044408] EXT4-fs (sda2): mounted filesystem with ordered data mode. Opts: (null) [ 15.143578] audit: type=1130 audit(1440462488.940:53): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-journal-flush comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' [ 15.192960] FAT-fs (sda1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck. [ 15.307718] audit: type=1130 audit(1440462489.100:54): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=dracut-shutdown comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' [ 15.378781] audit: type=1130 audit(1440462489.170:55): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=plymouth-read-write comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' [ 15.397883] audit: type=1131 audit(1440462489.190:56): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=plymouth-read-write comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' [ 15.536017] audit: type=1130 audit(1440462489.330:57): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=nfs-config comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' [ 15.556251] audit: type=1130 audit(1440462489.350:58): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-tmpfiles-setup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' [ 15.653975] audit: type=1305 audit(1440462489.450:59): auid=4294967295 ses=4294967295 op="add_rule" key=(null) list=1 res=1 [ 15.665726] audit: type=1130 audit(1440462489.460:60): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=auditd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' [ 15.771349] audit: type=1305 audit(1440462489.560:61): audit_enabled=1 old=1 auid=4294967295 ses=4294967295 res=1 [ 17.932483] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready [ 17.938498] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready [ 19.474025] xgene-enet 17020000.ethernet eth0: Link is Down [ 19.758932] ip6_tables: (C) 2000-2006 Netfilter Core Team [ 19.937856] Ebtables v2.0 registered [ 20.567065] bridge: automatic filtering via arp/ip/ip6tables has been deprecated. Update your scripts to load br_netfilter if you need this. [ 20.609010] tun: Universal TUN/TAP device driver, 1.6 [ 20.614058] tun: (C) 1999-2004 Max Krasnyansky maxk@qualcomm.com [ 20.704048] device virbr0-nic entered promiscuous mode [ 21.059515] nf_conntrack version 0.5.0 (65536 buckets, 262144 max) [ 21.292384] virbr0: port 1(virbr0-nic) entered listening state [ 21.298218] virbr0: port 1(virbr0-nic) entered listening state [ 21.513561] virbr0: port 1(virbr0-nic) entered disabled state [ 22.474349] xgene-enet 17020000.ethernet eth0: Link is Up - 1Gbps/Full - flow control off [ 22.482507] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready
On 8/25/15 7:33 AM, Al Stone wrote:
On 08/19/2015 09:29 AM, Itaru Kitayama wrote:
Hi Al,
Below is a dmesg excerpt corresponding to the acpi_boot_table_init().
[ 0.000000] ACPI: RSDP 0x00000047FA397014 000024 (v02 APM ) [ 0.000000] ACPI: XSDT 0x00000047FA3960E8 000064 (v01 APM XGENE 00000003 01000013) [ 0.000000] ACPI: FACP 0x00000047FA38E000 00010C (v05 APM XGENE 00000003 INTL 20141107) [ 0.000000] ACPI: DSDT 0x00000047FA38F000 003D82 (v05 APM APM88xxx 00000001 INTL 20141107) [ 0.000000] ACPI: DBG2 0x00000047FA393000 0000AA (v00 APMC0D XGENEDBG 00000000 INTL 20141107) [ 0.000000] ACPI: GTDT 0x00000047FA38C000 0000E0 (v02 APM XGENE 00000001 INTL 20141107) [ 0.000000] ACPI: MCFG 0x00000047FA38B000 00003C (v01 APM XGENE 00000002 INTL 20141107) [ 0.000000] ACPI: SPCR 0x00000047FA38A000 000050 (v02 APMC0D XGENESPC 00000000 INTL 20141107) [ 0.000000] ACPI: SSDT 0x00000047FA389000 00002D (v02 APM XGENE 00000001 INTL 20141107) [ 0.000000] ACPI: APIC 0x00000047FA388000 0002A4 (v03 APM XGENE 00000003 01000013) [ 0.000000] ACPI: SSDT 0x00000047FA387000 000063 (v02 REDHAT MACADDRS 00000001 01000013)
I've been testing the Mustang topic branch on the platform with the Rev 1 CPUs with 32 GB memory.
Okay. That's interesting output, but it unfortunately tells me nothing. Those messages only indicate that the kernel has found ACPI tables with those names, at those addresses, and that they have valid signatures in them. The content of the tables could be completely bogus, unless the kernel command acpi_force_table_verification was used, which will at least verify the checksums in the tables are correct, even if the content is not examined. I can deduce that acpi=force has been used, but that's about it.
What would be more useful is the remainder of the dmesg/console output so we can tell how far the kernel got in using the ACPI tables that it found, all the way up to the very last message out.
On 8/18/15 8:02 AM, Itaru Kitayama wrote:
Hi Al,
Even with your .config the HEAD of acpi-topic-mustang does not boot with acpi=force. Here are the messages appear on the console:
EFI stub: Booting Linux Kernel... EFI stub: Using DTB from configuration table EFI stub: Exiting boot services and installing virtual address map... XhcClearBiosOwnership: called to clear BIOS ownership XhcClearBiosOwnership: called to clear BIOS ownership L3c Cache: 8MB
On 8/18/15 6:58 AM, Itaru Kitayama wrote:
Hi Al,
> Where does it "stall"? What's the last message you see? And if > you're not > using earlycon, please do so; that will help determine how far the > boot is > getting. If I boot it with acpi=force, no output. Does my usage below look correct?
GRUB_CMDLINE_LINUX="earlycon=uart8250,mmio32,0x1c021000 acpi=force console=ttyS0,115200 loglevel=7"
> How were those checked? In setup_arch() I was calling disable_acpi() right after the>>> Itaru
Linaro-acpi mailing list Linaro-acpi@lists.linaro.org https://lists.linaro.org/mailman/listinfo/linaro-acpi
acpi_boot_table_init() call as that makes the system get to the prompt.
I am about to build the kernel with your .config and test it on one of Mustangs in the Linaro Austin lab. The Boot firmware version is 1.1.0-rh-0.15.
I may have mentioned this before (or not), but I think we need to get this Mustang updated to newer firmware; while that version is okay, the preferred version is the latest 1.15.x from APM (x = 18 or greater, I believe). We may have to figure out how to get the Austin lab updated somehow.
Linaro-acpi mailing list Linaro-acpi@lists.linaro.org https://lists.linaro.org/mailman/listinfo/linaro-acpi
On 08/25/2015 01:59 AM, Itaru Kitayama wrote:
Hi Al,
Below is the dmesg. In the modified kernel I call disable_acpi() right after acpi_boot_table_init(). (Otherwise, no meaningful output is obtained even with earlycon)
[ 0.000000] Booting Linux on physical CPU 0x0 [ 0.000000] Initializing cgroup subsys cpuset [ 0.000000] Initializing cgroup subsys cpu [ 0.000000] Initializing cgroup subsys cpuacct [ 0.000000] Linux version 4.2.0-rc5+ (root@r2-a21) (gcc version 5.1.1 20150618 (Red Hat 5.1.1-4) (GCC) ) #23 SMP Mon Aug 24 20:29:06 EDT 2015 [snip...]
My apologies for not being clearer.
This info from dmesg shows ACPI tables being loaded, and then DT being used for the initialization of devices. The problem is that ACPI is not working. So, what I need so that I can understand is the dmesg output from when ACPI is _not_ working. Please use earlycon, of course, but take out the call to disable_acpi(). If you get nothing at that point, please make sure there is no console= on the kernel cmdline; it doesn't look like there is, but it wouldn't hurt to be sure. There are some versions of the firmware/OS combination where using console= and having an SPCR table cause no console at all.
-
Al Stone -
G Gregory -
Itaru Kitayama