Current master doesn't boot?

[alext-mkrs]

@htot, let me check something with you - looks like current master built from scratch (no saved state whatsoever, manually removed all the dirs) doesn't boot for me. The kernel boots up until the below and then just stalls, then in a short while the board gets reset by the watchdog timer, and the process repeats endlessly.

Have you seen anything like that? Could you please try building the image from scratch and checking if it boots for you?

<...>
[    2.216898] usb usb2: Manufacturer: Linux 4.16.0-edison-no-acpi-standard xhci-hcd
[    2.224414] usb usb2: SerialNumber: xhci-hcd.2.auto
[    2.230064] hub 2-0:1.0: USB hub found
[    2.233897] hub 2-0:1.0: 1 port detected
[    2.238788] usbcore: registered new interface driver usblp
[    2.244474] usbcore: registered new interface driver usb-storage
[    2.250681] usbcore: registered new interface driver pl2303
[    2.256361] usbserial: USB Serial support registered for pl2303

Full log inside

``` U-Boot 2018.05 (Jun 17 2018 - 12:44:14 +0000) CPU: x86_64, vendor Intel, device 406a8h DRAM: 980.6 MiB MMC: mmc@ff3fc000: 0, mmc@ff3fa000: 1 Loading Environment from MMC... OK In: serial Out: serial Err: serial Net: No ethernet found. Hit any key to stop autoboot: 0 => => run edsboot 10749632 bytes read in 473 ms (21.7 MiB/s) Valid Boot Flag Setup Size = 0x00003e00 Magic signature found Using boot protocol version 2.0d Linux kernel version 4.16.0-edison-no-acpi-standard (oe-user@oe-host) #1 SMP Sat Jul 21 19:26:21 UTC 2018 Building boot_params at 0x00090000 Loading bzImage at address 100000 (10733760 bytes) Magic signature found Kernel command line: "tty1 console=ttyS2,115200n8 root=/dev/mmcblk1 rootfstype=ext4 systemd.unit=multi-user.target hardware_id=00" Magic signature found Starting kernel ... [ 0.000000] Linux version 4.16.0-edison-no-acpi-standard (oe-user@oe-host) (gcc version 7.3.0 (GCC)) #1 SMP Sat Jul 21 19:26:21 UTC 2018 [ 0.000000] x86/fpu: x87 FPU will use FXSAVE [ 0.000000] e820: BIOS-provided physical RAM map: [ 0.000000] BIOS-e820: [mem 0x0000000000000000-0x0000000000097fff] usable [ 0.000000] BIOS-e820: [mem 0x0000000000100000-0x0000000003ffffff] usable [ 0.000000] BIOS-e820: [mem 0x0000000004000000-0x0000000005ffffff] reserved [ 0.000000] BIOS-e820: [mem 0x0000000006000000-0x000000003f4fffff] usable [ 0.000000] BIOS-e820: [mem 0x000000003f500000-0x000000003fffffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000fec00000-0x00000000fec00fff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000fec04000-0x00000000fec07fff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000fee00000-0x00000000fee00fff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000ff000000-0x00000000ffffffff] reserved [ 0.000000] Notice: NX (Execute Disable) protection cannot be enabled: non-PAE kernel! [ 0.000000] random: fast init done [ 0.000000] SMBIOS 2.6 present. [ 0.000000] DMI: Intel Corporation Merrifield/BODEGA BAY, BIOS 542 2015.01.21:18.19.48 [ 0.000000] e820: last_pfn = 0x3f500 max_arch_pfn = 0x100000 [ 0.000000] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT [ 0.000000] Scanning 1 areas for low memory corruption [ 0.000000] ACPI: Early table checksum verification disabled [ 0.000000] ACPI BIOS Error (bug): A valid RSDP was not found (20180105/tbxfroot-244) [ 0.000000] 129MB HIGHMEM available. [ 0.000000] 883MB LOWMEM available. [ 0.000000] mapped low ram: 0 - 373fe000 [ 0.000000] low ram: 0 - 373fe000 [ 0.000000] Zone ranges: [ 0.000000] DMA [mem 0x0000000000001000-0x0000000000ffffff] [ 0.000000] Normal [mem 0x0000000001000000-0x00000000373fdfff] [ 0.000000] HighMem [mem 0x00000000373fe000-0x000000003f4fffff] [ 0.000000] Movable zone start for each node [ 0.000000] Early memory node ranges [ 0.000000] node 0: [mem 0x0000000000001000-0x0000000000097fff] [ 0.000000] node 0: [mem 0x0000000000100000-0x0000000003ffffff] [ 0.000000] node 0: [mem 0x0000000006000000-0x000000003f4fffff] [ 0.000000] Initmem setup node 0 [mem 0x0000000000001000-0x000000003f4fffff] [ 0.000000] Reserved but unavailable: 1 pages [ 0.000000] Using APIC driver default [ 0.000000] SFI: Simple Firmware Interface v0.81 http://simplefirmware.org [ 0.000000] SFI: SYST E31F0, 0060 (v1 INTEL INTELFDK) [ 0.000000] SFI: CPUS E3296, 0020 (v1 INTEL INTELFDK) [ 0.000000] SFI: FREQ E32C2, 0030 (v1 INTEL INTELFDK) [ 0.000000] SFI: MMAP E32FE, 01A4 (v1 INTEL INTELFDK) [ 0.000000] SFI: XSDT E34B0, 002C (v1 INTEL INTELFDK) [ 0.000000] SFI: APIC E353E, 0020 (v1 INTEL INTELFDK) [ 0.000000] SFI: WAKE E356A, 0020 (v2 INTEL INTELFDK) [ 0.000000] SFI: DEVS E359E, 047D (v1 INTEL INTELFDK) [ 0.000000] SFI: GPIO E3A27, 0964 (v1 INTEL INTELFDK) [ 0.000000] SFI: OEMB E4397, 0060 (v5 UMGFDK CFGINFO!) [ 0.000000] SFI: registering lapic[0] [ 0.000000] SFI: registering lapic[2] [ 0.000000] IOAPIC[0]: apic_id 0, version 32, address 0xfec00000, GSI 0-54 [ 0.000000] smpboot: Allowing 2 CPUs, 0 hotplug CPUs [ 0.000000] PM: Registered nosave memory: [mem 0x00000000-0x00000fff] [ 0.000000] PM: Registered nosave memory: [mem 0x00098000-0x000fffff] [ 0.000000] PM: Registered nosave memory: [mem 0x04000000-0x05ffffff] [ 0.000000] e820: [mem 0x40000000-0xfebfffff] available for PCI devices [ 0.000000] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns [ 0.000000] setup_percpu: NR_CPUS:8 nr_cpumask_bits:8 nr_cpu_ids:2 nr_node_ids:1 [ 0.000000] percpu: Embedded 29 pages/cpu @(ptrval) s87756 r0 d31028 u118784 [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 249263 [ 0.000000] Kernel command line: tty1 console=ttyS2,115200n8 root=/dev/mmcblk1 rootfstype=ext4 systemd.unit=multi-user.target hardware_id=00 [ 0.000000] Dentry cache hash table entries: 131072 (order: 7, 524288 bytes) [ 0.000000] Inode-cache hash table entries: 65536 (order: 6, 262144 bytes) [ 0.000000] Initializing CPU#0 [ 0.000000] Initializing HighMem for node 0 (000373fe:0003f500) [ 0.000000] Initializing Movable for node 0 (00000000:00000000) [ 0.000000] Memory: 972060K/1004124K available (9562K kernel code, 987K rwdata, 2764K rodata, 8484K init, 620K bss, 32064K reserved, 0K cma-reserved, 132104K highmem) [ 0.000000] virtual kernel memory layout: [ 0.000000] fixmap : 0xfff16000 - 0xfffff000 ( 932 kB) [ 0.000000] cpu_entry : 0xff800000 - 0xff939000 (1252 kB) [ 0.000000] pkmap : 0xff400000 - 0xff800000 (4096 kB) [ 0.000000] vmalloc : 0xf7bfe000 - 0xff3fe000 ( 120 MB) [ 0.000000] lowmem : 0xc0000000 - 0xf73fe000 ( 883 MB) [ 0.000000] .init : 0xcef1d000 - 0xcf766000 (8484 kB) [ 0.000000] .data : 0xceb56930 - 0xcef07f40 (3781 kB) [ 0.000000] .text : 0xce200000 - 0xceb56930 (9562 kB) [ 0.000000] Checking if this processor honours the WP bit even in supervisor mode...Ok. [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1 [ 0.000000] Hierarchical RCU implementation. [ 0.000000] RCU event tracing is enabled. [ 0.000000] RCU restricting CPUs from NR_CPUS=8 to nr_cpu_ids=2. [ 0.000000] RCU: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=2 [ 0.000000] NR_IRQS: 2304, nr_irqs: 512, preallocated irqs: 0 [ 0.000000] Console: colour dummy device 80x25 [ 0.000000] console [ttyS2] enabled [ 0.000000] APIC: Switch to symmetric I/O mode setup [ 0.000000] Enabling APIC mode: Flat. Using 1 I/O APICs [ 0.000000] tsc: Detected 500.000 MHz processor [ 0.000046] tsc: Detected 499.200 MHz TSC [ 0.000048] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0xe642f98297, max_idle_ns: 881590439301 ns [ 0.000410] Calibrating delay loop (skipped), value calculated using timer frequency.. 998.40 BogoMIPS (lpj=499200) [ 0.000676] pid_max: default: 32768 minimum: 301 [ 0.000910] Security Framework initialized [ 0.001000] SELinux: Initializing. [ 0.001000] Mount-cache hash table entries: 2048 (order: 1, 8192 bytes) [ 0.001000] Mountpoint-cache hash table entries: 2048 (order: 1, 8192 bytes) [ 0.001000] CPU: Physical Processor ID: 0 [ 0.001000] CPU: Processor Core ID: 0 [ 0.001000] ENERGY_PERF_BIAS: Set to 'normal', was 'performance' [ 0.001000] ENERGY_PERF_BIAS: View and update with x86_energy_perf_policy(8) [ 0.001000] mce: CPU supports 6 MCE banks [ 0.001000] CPU0: Thermal monitoring enabled (TM1) [ 0.001000] process: using mwait in idle threads [ 0.001000] Last level iTLB entries: 4KB 48, 2MB 0, 4MB 0 [ 0.001000] Last level dTLB entries: 4KB 128, 2MB 16, 4MB 16, 1GB 0 [ 0.001000] Spectre V2 : Mitigation: Full generic retpoline [ 0.001000] Freeing SMP alternatives memory: 40K [ 0.001000] SFI: MCFG E34F6, 003C (v1 INTEL INTELFDK) [ 0.001000] smpboot: CPU0: Genuine Intel(R) CPU 4000 @ 500MHz (family: 0x6, model: 0x4a, stepping: 0x8) [ 0.001000] Performance Events: PEBS fmt2+, generic architected perfmon, full-width counters, Intel PMU driver. [ 0.001035] ... version: 3 [ 0.001150] ... bit width: 40 [ 0.001264] ... generic registers: 2 [ 0.001376] ... value mask: 000000ffffffffff [ 0.001519] ... max period: 0000007fffffffff [ 0.001662] ... fixed-purpose events: 3 [ 0.001773] ... event mask: 0000000700000003 [ 0.002164] Hierarchical SRCU implementation. [ 0.008695] smp: Bringing up secondary CPUs ... [ 0.009550] x86: Booting SMP configuration: [ 0.009671] .... node #0, CPUs: #1 [ 0.001000] Initializing CPU#1 [ 0.010213] smp: Brought up 1 node, 2 CPUs [ 0.010213] smpboot: Max logical packages: 1 [ 0.010262] smpboot: Total of 2 processors activated (1996.80 BogoMIPS) [ 0.011456] devtmpfs: initialized [ 0.014290] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.014454] kworker/u4:0 (20) used greatest stack depth: 7084 bytes left [ 0.014560] futex hash table entries: 512 (order: 3, 32768 bytes) [ 0.015085] pinctrl core: initialized pinctrl subsystem [ 0.015522] SFI: SFI sysfs interfaces init success [ 0.016245] RTC time: 8:37:37, date: 07/22/18 [ 0.016872] NET: Registered protocol family 16 [ 0.017677] audit: initializing netlink subsys (disabled) [ 0.018145] audit: type=2000 audit(1532248657.018:1): state=initialized audit_enabled=0 res=1 [ 0.019550] cpuidle: using governor menu [ 0.021055] Using generic wifi platform data [ 0.021898] PCI: MMCONFIG for domain 0000 [bus 00-00] at [mem 0x3f500000-0x3f5fffff] (base 0x3f500000) [ 0.022030] PCI: MMCONFIG at [mem 0x3f500000-0x3f5fffff] reserved in E820 [ 0.022211] PCI: Using MMCONFIG for extended config space [ 0.022356] Intel MID platform detected, using MID PCI ops [ 0.022505] PCI: Using configuration type 1 for base access [ 0.029676] kworker/u4:1 (68) used greatest stack depth: 6828 bytes left [ 0.063381] HugeTLB registered 4.00 MiB page size, pre-allocated 0 pages [ 0.065515] ACPI: Interpreter disabled. [ 0.065760] bcm43xx-vmmc-regulator: Failed to request enable GPIO96: -517 [ 0.065956] reg-fixed-voltage reg-fixed-voltage.0.auto: Failed to register regulator: -517 [ 0.066364] vgaarb: loaded [ 0.067105] SCSI subsystem initialized [ 0.067881] usbcore: registered new interface driver usbfs [ 0.068210] usbcore: registered new interface driver hub [ 0.068500] usbcore: registered new device driver usb [ 0.068931] pps_core: LinuxPPS API ver. 1 registered [ 0.069048] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.069340] PTP clock support registered [ 0.070233] EDAC MC: Ver: 3.0.0 [ 0.070992] Advanced Linux Sound Architecture Driver Initialized. [ 0.071112] PCI: Probing PCI hardware [ 0.071391] PCI host bridge to bus 0000:00 [ 0.071526] pci_bus 0000:00: root bus resource [io 0x0000-0xffff] [ 0.071700] pci_bus 0000:00: root bus resource [mem 0x00000000-0xffffffff] [ 0.071888] pci_bus 0000:00: No busn resource found for root bus, will use [bus 00-ff] [ 0.088621] Bluetooth: Core ver 2.22 [ 0.088799] NET: Registered protocol family 31 [ 0.088928] Bluetooth: HCI device and connection manager initialized [ 0.089036] Bluetooth: HCI socket layer initialized [ 0.089178] Bluetooth: L2CAP socket layer initialized [ 0.089376] Bluetooth: SCO socket layer initialized [ 0.089542] NetLabel: Initializing [ 0.089642] NetLabel: domain hash size = 128 [ 0.089761] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.090053] NetLabel: unlabeled traffic allowed by default [ 0.091548] clocksource: Switched to clocksource tsc-early [ 0.166406] VFS: Disk quotas dquot_6.6.0 [ 0.166625] VFS: Dquot-cache hash table entries: 1024 (order 0, 4096 bytes) [ 0.167178] pnp: PnP ACPI: disabled [ 0.182698] NET: Registered protocol family 2 [ 0.183774] tcp_listen_portaddr_hash hash table entries: 512 (order: 0, 6144 bytes) [ 0.184006] TCP established hash table entries: 8192 (order: 3, 32768 bytes) [ 0.184346] TCP bind hash table entries: 8192 (order: 4, 65536 bytes) [ 0.184671] TCP: Hash tables configured (established 8192 bind 8192) [ 0.184966] UDP hash table entries: 512 (order: 2, 16384 bytes) [ 0.185224] UDP-Lite hash table entries: 512 (order: 2, 16384 bytes) [ 0.185632] NET: Registered protocol family 1 [ 0.186459] RPC: Registered named UNIX socket transport module. [ 0.186625] RPC: Registered udp transport module. [ 0.186755] RPC: Registered tcp transport module. [ 0.186884] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.634512] DMA-API: preallocated 65536 debug entries [ 0.634661] DMA-API: debugging enabled by kernel config [ 0.635481] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0xe642f98297, max_idle_ns: 881590439301 ns [ 0.635796] clocksource: Switched to clocksource tsc [ 0.636223] platform rtc_cmos: registered platform RTC device (no PNP device found) [ 0.638402] Scanning for low memory corruption every 60 seconds [ 0.640171] pci 0000:00:04.1: Registered Bluetooth device: hci_bcm [ 0.643195] Initialise system trusted keyrings [ 0.643857] workingset: timestamp_bits=30 max_order=18 bucket_order=0 [ 0.660208] NFS: Registering the id_resolver key type [ 0.660379] Key type id_resolver registered [ 0.660499] Key type id_legacy registered [ 0.669266] Key type asymmetric registered [ 0.669393] Asymmetric key parser 'x509' registered [ 0.669600] bounce: pool size: 64 pages [ 0.669809] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 248) [ 0.670047] io scheduler noop registered [ 0.670162] io scheduler deadline registered [ 0.670642] io scheduler cfq registered (default) [ 0.670778] io scheduler mq-deadline registered [ 0.670906] io scheduler kyber registered [ 0.682296] dw_dmac_pci 0000:00:15.0: DesignWare DMA Controller, 8 channels [ 0.684366] hsu_dma_pci 0000:00:05.0: Found HSU DMA, 12 channels [ 0.685147] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 0.689055] 8250_mid: probe of 0000:00:04.0 failed with error -16 [ 0.689886] 0000:00:04.1: ttyS0 at MMIO 0xff010080 (irq = 28, base_baud = 1843200) is a TI16750 [ 0.691575] 0000:00:04.2: ttyS1 at MMIO 0xff010100 (irq = 29, base_baud = 1843200) is a TI16750 [ 0.692954] console [ttyS2] disabled [ 0.693158] 0000:00:04.3: ttyS2 at MMIO 0xff010180 (irq = 54, base_baud = 1843200) is a TI16750 [ 1.931247] console [ttyS2] enabled [ 1.935683] Non-volatile memory driver v1.3 [ 1.940487] Linux agpgart interface v0.103 [ 1.955127] loop: module loaded [ 1.960427] e100: Intel(R) PRO/100 Network Driver, 3.5.24-k2-NAPI [ 1.966588] e100: Copyright(c) 1999-2006 Intel Corporation [ 1.972246] e1000: Intel(R) PRO/1000 Network Driver - version 7.3.21-k8-NAPI [ 1.979334] e1000: Copyright (c) 1999-2006 Intel Corporation. [ 1.985241] e1000e: Intel(R) PRO/1000 Network Driver - 3.2.6-k [ 1.991112] e1000e: Copyright(c) 1999 - 2015 Intel Corporation. [ 1.997216] igb: Intel(R) Gigabit Ethernet Network Driver - version 5.4.0-k [ 2.004216] igb: Copyright (c) 2007-2014 Intel Corporation. [ 2.009941] sky2: driver version 1.30 [ 2.014404] usbcore: registered new interface driver r8152 [ 2.020049] usbcore: registered new interface driver asix [ 2.025556] usbcore: registered new interface driver ax88179_178a [ 2.031755] usbcore: registered new interface driver cdc_ether [ 2.037696] usbcore: registered new interface driver net1080 [ 2.043462] usbcore: registered new interface driver cdc_subset [ 2.049490] usbcore: registered new interface driver zaurus [ 2.055173] usbcore: registered new interface driver MOSCHIP usb-ethernet driver [ 2.062716] usbcore: registered new interface driver cdc_ncm [ 2.069260] dwc3 dwc3.1.auto: Configuration mismatch. dr_mode forced to host [ 2.077667] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver [ 2.084256] ehci-pci: EHCI PCI platform driver [ 2.088843] ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver [ 2.095078] ohci-pci: OHCI PCI platform driver [ 2.099652] uhci_hcd: USB Universal Host Controller Interface driver [ 2.106421] xhci-hcd xhci-hcd.2.auto: xHCI Host Controller [ 2.112273] xhci-hcd xhci-hcd.2.auto: new USB bus registered, assigned bus number 1 [ 2.120190] xhci-hcd xhci-hcd.2.auto: hcc params 0x0220f06c hci version 0x100 quirks 0x02010010 [ 2.129000] xhci-hcd xhci-hcd.2.auto: irq 34, io mem 0xf9100000 [ 2.135382] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002 [ 2.142234] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1 [ 2.149501] usb usb1: Product: xHCI Host Controller [ 2.154417] usb usb1: Manufacturer: Linux 4.16.0-edison-no-acpi-standard xhci-hcd [ 2.161932] usb usb1: SerialNumber: xhci-hcd.2.auto [ 2.167571] hub 1-0:1.0: USB hub found [ 2.171430] hub 1-0:1.0: 1 port detected [ 2.175937] xhci-hcd xhci-hcd.2.auto: xHCI Host Controller [ 2.181729] xhci-hcd xhci-hcd.2.auto: new USB bus registered, assigned bus number 2 [ 2.189547] usb usb2: We don't know the algorithms for LPM for this host, disabling LPM. [ 2.197888] usb usb2: New USB device found, idVendor=1d6b, idProduct=0003 [ 2.204728] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1 [ 2.211985] usb usb2: Product: xHCI Host Controller [ 2.216898] usb usb2: Manufacturer: Linux 4.16.0-edison-no-acpi-standard xhci-hcd [ 2.224414] usb usb2: SerialNumber: xhci-hcd.2.auto [ 2.230064] hub 2-0:1.0: USB hub found [ 2.233897] hub 2-0:1.0: 1 port detected [ 2.238788] usbcore: registered new interface driver usblp [ 2.244474] usbcore: registered new interface driver usb-storage [ 2.250681] usbcore: registered new interface driver pl2303 [ 2.256361] usbserial: USB Serial support registered for pl2303 ```

[alext-mkrs]

Hmm, this is really interesting - bumping up all the log levels and whatnot doesn't provide any additional information around the point of hang. @andy-shev, have you seen anything like that (this is a non-ACPI kernel, 4.16)?

[alext-mkrs]

Also tried without your HSU-related kernel patches (just in case, I know you've tested them, but I have no other ideas at the moment) - they are not the reason, the behavior doesn't change.

[htot]

Could it be you have acpi enabled U-Boot. Master is no-acpi so will not boot without acpi=off on the command line. Can you check?

[andy-shev]

@alext-mkrs, From the above log I can't see any problem with kernel. It looks like getty can't start by some reason.

@htot,

[ 0.065515] ACPI: Interpreter disabled.

means that it didn't find any ACPI bits from bootloader or firmware.

[htot]

Yes, I see SFI. This is the U-Boot without ACPI. I haven't tried that for a while, could it be related? I don't think so. Maybe systemd not starting anything? Only other difference with me: I'm booting from USB and have combined USB hub / eth port.

[alext-mkrs]

Thanks for suggestions, gentlemen. Yes, this is a non-ACPI U-Boot with non-ACPI kernel and I've compared the boot logs with 3.10.98 (the latest one I have booting at hand), it does an SDHC init around that place - and I haven't seen any messages about that in my current (failing kernel) log.

@htot, could you please post (attach) a log from your boot? I'll check the difference to see if there's anything interesting there. ACPI or non-ACPI is irrelevant, that's something I can account for when comparing.

[htot]

64b-acpi-boot.log This a log of journalctl -b 64b kernel with acpi (and ftrace). I will send log of same with acpi=off later. Note: when we build no-acpi kernel we apply the patch to enable i2c-6 (meaning i2c driver is build as module) so order of things will be a bit different. But you are right, it might well be the SDHC. I've seen SDHC suffering the same problem as i2c sometimes: maybe it needs a cold boot (pressing reset is not always enough).

[htot]

Same kernel/U-Boot but with acpi=off 64b-no-acpi-boot.log

[htot]

We might just be missing a module in the initramfs. I'll copy my rootfs to a sdhc this evening and try to boot it. In the meanwhile you could try to get a debug shell:

setenv acpi 'debugshell=10'
run edsboot

With lsmod I have after booting rootfs:

Module                  Size  Used by
smsc95xx               28672  0
brcmfmac              278528  0
brcmutil               16384  1 brcmfmac
spidev                 16384  0
spi_pxa2xx_platform    28672  0
pwm_lpss_pci           16384  0
pwm_lpss               16384  1 pwm_lpss_pci
spi_pxa2xx_pci         16384  0
mmc_block              40960  2
sdhci_pci              36864  0
cqhci                  24576  1 sdhci_pci
sdhci                  45056  1 sdhci_pci
led_class              16384  1 sdhci
mmc_core              139264  5 sdhci,sdhci_pci,cqhci,mmc_block,brcmfmac

[alext-mkrs]

Thanks for logs and other information - I'm a bit swamped right now to look deeper into this, but this will be useful when I get to that.

[htot]

Alright, I just booted from sd (same 64b kernel, acpi enabled). My kernel command line is slightly different than yours, but should not matter. So, it boots the kernel, runs the init script, with systemd 237, waits for rootfs (with the annoying messages I kind seem to shut up), then switches root and continues booting.

Kernel command line: "**debugshell=10 quiet** tty1 console=ttyS2,115200n8 root=/dev/mmcblk1 rootfstype=ext4 systemd.unit=multi-user.target hardware_id=00"
Magic signature found

Starting kernel ...

starting version 237
Kernel with acpi enabled detected
Loading acpi tables
**Waiting for root device /dev/mmcblk1**
  10[    2.656202] EXT4-fs (mmcblk1): couldn't mount as ext3 due to feature incompatibilities
[    2.665541] EXT4-fs (mmcblk0p5): couldn't mount as ext3 due to feature incompatibilities
  9[    2.686285] EXT4-fs (mmcblk1): couldn't mount as ext2 due to feature incompatibilities
[    2.695597] EXT4-fs (mmcblk0p5): couldn't mount as ext2 due to feature incompatibilities
[    2.749394] EXT4-fs (mmcblk0p8): couldn't mount as ext3 due to feature incompatibilities
[    2.762104] EXT4-fs (mmcblk0p10): couldn't mount as ext3 due to feature incompatibilities
[    2.762599] EXT4-fs (mmcblk0p8): couldn't mount as ext2 due to feature incompatibilities
[    2.782466] EXT4-fs (mmcblk0p10): couldn't mount as ext2 due to feature incompatibilities
**Found device '/run/media/mmcblk1'**

My rootfs I make with:

sudo umount /dev/sdc
sudo sh -c 'cat edison-image-edison.ext4 >/dev/sdc' && sync /* this appears to be faster than dd */
sudo e2fsck -f -y -v -C 0 /dev/sdc && sudo resize2fs -p /dev/sdc && sync

My core-image-minimal-initramfs-edison.manifest.

I can't reproduce with acpi enabled image.

[alext-mkrs]

Thanks. Another difference is that master is 32bit, not 64.

[htot]

Eh, yes correct. But that would be very surprising if related. Key thing in my logs is the following line:

Jul 14 19:22:39 edison kernel:  mmcblk0: p1 p2 p3 p4 p5 p6 p7 p8 p9 p10
Jul 14 19:22:39 edison kernel: mmcblk1: mmc1:e624 SU02G 1.84 GiB 
Jul 14 19:22:39 edison kernel: EXT4-fs (mmcblk1): mounted filesystem with ordered data mode. Opts: (null)

which shows the init script waiting for the devices to appear then mounting it, which I don't see in your log.

BTW how did you insert your log with the cool unfold button?

PS I will be on a holiday from Sat 28 until Thu 7 Aug so won't be able to reply in that period.

[alext-mkrs]

No worries and thanks for the information, have a nice holiday :)

As for the hiding trick - GH's Markdown allows for some HTML tags, including https://html.com/tags/details/, that's what I used. You can see the exact usage by clicking "edit" on my post.

[lybtongji]

I can not boot too.

It stuck in a bootloop.

Boot Info

****************************** PSH KERNEL VERSION: b0182b2b WR: 20104000 ****************************** SCU IPC: 0x800000d0 0xfffce92c PSH miaHOB version: TNG.B0.VVBD.0000000c microkernel built 11:24:08 Feb 5 2015 ******* PSH loader ******* PCM page cache size = 192 KB Cache Constraint = 0 Pages Arming IPC driver .. Adding page store pool .. PagestoreAddr(IMR Start Address) = 0x04899000 pageStoreSize(IMR Size) = 0x00080000 *** Ready to receive application *** U-Boot 2018.05 (Jul 29 2018 - 13:14:33 +0000) CPU: x86_64, vendor Intel, device 406a8h DRAM: 980.6 MiB MMC: mmc@ff3fc000: 0, mmc@ff3fa000: 1 Loading Environment from MMC... OK In: serial Out: serial Err: serial Net: No ethernet found. Hit any key to stop autoboot: 0 Target:blank Partitioning already done... Flashing already done... ** Unable to read file vmlinuz ** Error: Invalid Boot Flag (found 0xffef, expected 0xaa55) ## Kernel loading failed ... zboot - Boot bzImage Usage: zboot [addr] [size] [initrd addr] [initrd size] addr - The optional starting address of the bzimage. If not set it defaults to the environment variable "fileaddr". size - The optional size of the bzimage. Defaults to zero. initrd addr - The address of the initrd image to use, if any. initrd size - The size of the initrd image to use, if any. Unknown boot mode: boot Saving Environment to MMC... Writing to redundant MMC(0)... OK Resetting to default boot mode and reboot... resetting ... ****************************** PSH KERNEL VERSION: b0182b2b WR: 20104000 ****************************** SCU IPC: 0x800000d0 0xfffce92c PSH miaHOB version: TNG.B0.VVBD.0000000c microkernel built 11:24:08 Feb 5 2015 ******* PSH loader ******* PCM page cache size = 192 KB Cache Constraint = 0 Pages Arming IPC driver .. Adding page store pool .. PagestoreAddr(IMR Start Address) = 0x04899000 pageStoreSize(IMR Size) = 0x00080000 *** Ready to receive application *** U-Boot 2018.05 (Jul 29 2018 - 13:14:33 +0000) CPU: x86_64, vendor Intel, device 406a8h DRAM: 980.6 MiB MMC: mmc@ff3fc000: 0, mmc@ff3fa000: 1 Loading Environment from MMC... OK In: serial Out: serial Err: serial Net: No ethernet found. Hit any key to stop autoboot: 0 Target:blank Partitioning already done... Flashing already done... ** Unable to read file vmlinuz ** Error: Invalid Boot Flag (found 0xffef, expected 0xaa55) ## Kernel loading failed ... zboot - Boot bzImage Usage: zboot [addr] [size] [initrd addr] [initrd size] addr - The optional starting address of the bzimage. If not set it defaults to the environment variable "fileaddr". size - The optional size of the bzimage. Defaults to zero. initrd addr - The address of the initrd image to use, if any. initrd size - The size of the initrd image to use, if any. Unknown boot mode: boot Saving Environment to MMC... Writing to MMC(0)... OK Resetting to default boot mode and reboot... resetting ... ......

[htot]

@lybtongji that looks like a different issue. Maybe you press to interrupt boot and capture printenv?

[lybtongji]

@htot printenv output:

audio_codec_name=audio_codec="dummy"
boot_edsboot=zboot 0x100000
boot_target_cmd=run do_flash_os;run do_probe_dfu;run do_compute_target;run mmc-bootargs;run load_kernel;zboot ${loadaddr}
bootargs_console=console=ttyS2 earlyprintk=ttyS2,keep
bootargs_debug=loglevel=4 acpi=off
bootargs_edsboot=tty1 console=ttyS2,115200n8 root=/dev/mmcblk1 rootfstype=ext4 systemd.unit=multi-user.target hardware_id=00
bootargs_ethconfig=rndis
bootargs_target=multi-user
bootargs_usbboot=debugshell=10 tty1 console=ttyS2,115200n8 root=/dev/sda rootfstype=ext4 systemd.unit=multi-user.target hardware_id=00
bootcmd=echo "Target:${target_name}"; run do_partition; run do_handle_bootargs_mode;
bootdelay=1
dfu_alt_info_ram=kernel ram ${loadaddr} 0x800000
dfu_alt_info_reset=reset ram 0x0 0x0
dfu_to_sec=3
do_audio_support=setenv audio_support platform_mrfld_audio.${audio_codec_name}
do_boot=run boot_target_cmd;
do_bootargs_rootfs=setenv bootargs_rootfs root=/dev/mmcblk0p8 rootfstype=ext4
do_compute_target=if itest.b ${first_install_retry} -gt ${first_install_max_retries} || itest.b ${ota_update_retry} -gt ${ota_update_max_retries}; then echo "Switch to Rescue target"; setenv bootargs_target rescue; saveenv; fi
do_dfu_alt_info_ifwi=setenv dfu_alt_info "ifwi${hardware_id} raw 0 8192 mmcpart 1;ifwib${hardware_id} raw 0 8192 mmcpart 2"
do_dfu_alt_info_mmc=setenv dfu_alt_info "ifwi${hardware_id} raw 0 8192 mmcpart 1;ifwib${hardware_id} raw 0 8192 mmcpart 2;u-boot0 part 0 1;u-boot-env0 part 0 2;u-boot1 part 0 3;u-boot-env1 part 0 4;boot part 0 7;rootfs part 0 8;update part 0 9;home part 0 10;vmlinuz fat 0 7;initrd fat 0 7"
do_dnx=setenv dfu_alt_info ${dfu_alt_info_ram};dfu 0 ram 0 ram;run bootcmd
do_fallback=echo "Unknown boot mode: $bootargs_mode"; env delete -f bootargs_mode; saveenv; echo "Resetting to default boot mode and reboot..."; reset;
do_flash=run do_force_flash_os;
do_flash_ifwi=run do_dfu_alt_info_ifwi ; dfu 0 mmc 0
do_flash_os=if itest.b ${do_flash_os_done} -eq 1 ; then echo "Flashing already done..." ; else run do_force_flash_os; fi
do_flash_os_done=1
do_flashall=run do_partition;run do_flash_ifwi;run do_flash_os
do_force_flash_os=run do_dfu_alt_info_mmc ; sleep 1 ; setenv do_flash_os_done 1 ; saveenv ; dfu 0 mmc 0
do_force_partition=echo "Partitioning using GPT"; gpt write mmc 0 ${partitions} ; mmc rescan; setenv do_partition_done 1 ; saveenv
do_handle_bootargs_mode=run do_preprocess_bootargs_mode; if itest.s $bootargs_mode == "ota" ; then run do_ota; fi; if itest.s $bootargs_mode == "boot" ; then run do_boot; fi; if itest.s $bootargs_mode == "flash"; then run do_flash; fi; run do_fallback; exit;
do_load_ota_scr=if fatload mmc 0:9 $ota_script_addr ota_update.scr ; then setenv ota_status 0 ; else setenv ota_status 1 ; fi
do_ota=run do_ota_init ; run do_load_ota_scr ; run do_source_ota_scr ; run do_ota_clean
do_ota_clean=saveenv ; reset
do_ota_init=setenv ota_status 1 ; env delete -f bootargs_mode
do_partition=if itest.b ${do_partition_done} -eq 1; then echo "Partitioning already done..."; else run do_force_partition ; fi
do_partition_done=1
do_preprocess_bootargs_mode=if env exists bootargs_mode ; then ; else setenv bootargs_mode "boot" ;fi;
do_probe_dfu=run do_dfu_alt_info_mmc ; dfu 0 mmc 0 ${dfu_to_sec}
do_source_ota_scr=if test $ota_status -eq 0 ; then if source $ota_script_addr ; then setenv ota_status 0 ; else setenv ota_status 2 ; fi ; fi
edsboot=setenv bootargs ${acpi} ${bootargs_edsboot}; run load_edsboot; run boot_edsboot
fdtcontroladdr=3d4541b0
first_install_max_retries=3
first_install_retry=0
hardware_id=00
init_dfu=run do_dfu_alt_info_mmc ; saveenv
load_edsboot=load mmc 0:9 0x100000 bzImage-initramfs
load_kernel=fatload mmc 0:7 ${loadaddr} vmlinuz
load_usbboot=load mmc 0:9 0x100000 bzImage-initramfs-usb
loadaddr=0x100000
mmc-bootargs=run do_bootargs_rootfs; run do_audio_support; setenv bootargs ${bootargs_rootfs} ${bootargs_console} ${bootargs_debug} g_multi.ethernet_config=${bootargs_ethconfig} systemd.unit=${bootargs_target}.target hardware_id=${hardware_id} g_multi.iSerialNumber=${serial#} g_multi.dev_addr=${usb0addr} ${audio_support}
ota_done=0
ota_script_addr=0x100000
ota_update_max_retries=3
ota_update_retry=0
partitions=uuid_disk=${uuid_disk};name=u-boot0,start=1MiB,size=2MiB,uuid=${uuid_uboot0};name=u-boot-env0,size=1MiB,uuid=${uuid_uboot_env0};name=u-boot1,size=2MiB,uuid=${uuid_uboot1};name=u-boot-env1,size=1MiB,uuid=${uuid_uboot_env1};name=factory,size=1MiB,uuid=${uuid_factory};name=panic,size=24MiB,uuid=${uuid_panic};name=boot,size=32MiB,uuid=${uuid_boot};name=rootfs,size=1536MiB,uuid=${uuid_rootfs};name=update,size=768MiB,uuid=${uuid_update};name=home,size=-,uuid=${uuid_home};
serial#=4aa08c597b139dd507f4946de1312c8a
target_name=blank
usb0addr=02:00:86:31:2c:8a
usbboot=setenv bootargs ${acpi} ${bootargs_usbboot}; run load_usbboot; run boot_edsboot
uuid_boot=db88503d-34a5-3e41-836d-c757cb682814
uuid_disk=21200400-0804-0146-9dcc-a8c51255994f
uuid_factory=333a128e-d3e3-b94d-92f4-d3ebd9b3224f
uuid_home=f13a0978-b1b5-1a4e-8821-39438e24b627
uuid_panic=f20aa902-1c5d-294a-9177-97a513e3cae4
uuid_rootfs=012b3303-34ac-284d-99b4-34e03a2335f4
uuid_uboot0=d117f98e-6f2c-d04b-a5b2-331a19f91cb2
uuid_uboot1=8a4bb8b4-e304-ae48-8536-aff5c9c495b1
uuid_uboot_env0=25718777-d0ad-7443-9e60-02cb591c9737
uuid_uboot_env1=08992135-13c6-084b-9322-3391ff571e19
uuid_update=faec2ecf-8544-e241-b19d-757e796da607

Environment size: 5520/65531 bytes

[htot]

@lybtongji it looks like you see 2 problems. 1 - load_kernel=fatload mmc 0:7 ${loadaddr} vmlinuz seems to be failing. I can't remember why here fatload is used while we use load to read from sdhc or usb. I does work for me (as I use this as a fallback after I try to boot a broken kernel from usb). Maybe I modified this in my env manually and forgot to update edison.env? I need to check this. 2 - after fatload fails (and then zboot), it reboots to 'boot' mode, but 'boot' seems to be not recognized in do_handle_bootargs_mode, so it falls back to do_fallback. This should not be happening, maybe something changed in the processing of if itest.s $bootargs_mode == "boot"?

[lybtongji]

@htot I compared the images of rocko32 and sumo32. And found the difference in boot partition.

rocko32:

=> ls mmc 0:7
    69632   ldlinux.sys
   186500   libcom32.c32
    24148   libutil.c32
      592   syslinux.cfg
    27104   vesamenu.c32
 10666112   vmlinuz
   122308   ldlinux.c32

7 file(s), 0 dir(s)

sumo32:

=> ls mmc 0:7
  7414592   bzImage
    69632   ldlinux.sys
   186500   libcom32.c32
    24148   libutil.c32
      592   syslinux.cfg
    27104   vesamenu.c32
   122308   ldlinux.c32

7 file(s), 0 dir(s)

vmlinuz is not found in sumo32, but bzImage found.

---

After I rewrited load_kernel (vmlinuz -> bzImage) in u-boot, I got the boot log.

setenv load_kernel "fatload mmc 0:7 ${loadaddr} bzImage"

setenv bootargs_console "console=ttyS2,115200n8 earlyprintk=ttyS2,keep"

setenv bootargs_debug "loglevel=8 acpi=off"

boot

Boot log

[    0.000000] Linux version 4.16.0-edison-no-acpi-standard (oe-user@oe-host) (gcc version 7.3.0 (GCC)) #1 SMP Tue Aug 7 11:14:59 UTC 2018
[    0.000000] x86/fpu: x87 FPU will use FXSAVE
[    0.000000] e820: BIOS-provided physical RAM map:
[    0.000000] BIOS-e820: [mem 0x0000000000000000-0x0000000000097fff] usable
[    0.000000] BIOS-e820: [mem 0x0000000000100000-0x0000000003ffffff] usable
[    0.000000] BIOS-e820: [mem 0x0000000004000000-0x0000000005ffffff] reserved
[    0.000000] BIOS-e820: [mem 0x0000000006000000-0x000000003f4fffff] usable
[    0.000000] BIOS-e820: [mem 0x000000003f500000-0x000000003fffffff] reserved
[    0.000000] BIOS-e820: [mem 0x00000000fec00000-0x00000000fec00fff] reserved
[    0.000000] BIOS-e820: [mem 0x00000000fec04000-0x00000000fec07fff] reserved
[    0.000000] BIOS-e820: [mem 0x00000000fee00000-0x00000000fee00fff] reserved
[    0.000000] BIOS-e820: [mem 0x00000000ff000000-0x00000000ffffffff] reserved
[    0.000000] console [earlyser0] enabled
[    0.000000] Notice: NX (Execute Disable) protection cannot be enabled: non-PAE kernel!
[    0.000000] random: fast init done
[    0.000000] SMBIOS 2.6 present.
[    0.000000] DMI: Intel Corporation Merrifield/BODEGA BAY, BIOS 542 2015.01.21:18.19.48
[    0.000000] e820: update [mem 0x00000000-0x00000fff] usable ==> reserved
[    0.000000] e820: remove [mem 0x000a0000-0x000fffff] usable
[    0.000000] e820: last_pfn = 0x3f500 max_arch_pfn = 0x100000
[    0.000000] MTRR default type: uncachable
[    0.000000] MTRR fixed ranges enabled:
[    0.000000]   00000-9FFFF write-back
[    0.000000]   A0000-BFFFF uncachable
[    0.000000]   C0000-FFFFF write-back
[    0.000000] MTRR variable ranges enabled:
[    0.000000]   0 base 000000000 mask FC0000000 write-back
[    0.000000]   1 base 03F600000 mask FFFE00000 uncachable
[    0.000000]   2 base 03F800000 mask FFF800000 uncachable
[    0.000000]   3 base 004000000 mask FFE000000 uncachable
[    0.000000]   4 disabled
[    0.000000]   5 disabled
[    0.000000]   6 disabled
[    0.000000]   7 disabled
[    0.000000] x86/PAT: Configuration [0-7]: WB  WC  UC- UC  WB  WP  UC- WT
[    0.000000] e820: update [mem 0x04000000-0x05ffffff] usable ==> reserved
[    0.000000] Scanning 1 areas for low memory corruption
[    0.000000] initial memory mapped: [mem 0x00000000-0x07ffffff]
[    0.000000] Base memory trampoline at [(ptrval)] 94000 size 16384
[    0.000000] BRK [0x07a9b000, 0x07a9bfff] PGTABLE
[    0.000000] 129MB HIGHMEM available.
[    0.000000] 883MB LOWMEM available.
[    0.000000]   mapped low ram: 0 - 373fe000
[    0.000000]   low ram: 0 - 373fe000
[    0.000000] BRK [0x07a9c000, 0x07a9cfff] PGTABLE
[    0.000000] Zone ranges:
[    0.000000]   DMA      [mem 0x0000000000001000-0x0000000000ffffff]
[    0.000000]   Normal   [mem 0x0000000001000000-0x00000000373fdfff]
[    0.000000]   HighMem  [mem 0x00000000373fe000-0x000000003f4fffff]
[    0.000000] Movable zone start for each node
[    0.000000] Early memory node ranges
[    0.000000]   node   0: [mem 0x0000000000001000-0x0000000000097fff]
[    0.000000]   node   0: [mem 0x0000000000100000-0x0000000003ffffff]
[    0.000000]   node   0: [mem 0x0000000006000000-0x000000003f4fffff]
[    0.000000] Initmem setup node 0 [mem 0x0000000000001000-0x000000003f4fffff]
[    0.000000] On node 0 totalpages: 251031
[    0.000000]   DMA zone: 32 pages used for memmap
[    0.000000]   DMA zone: 0 pages reserved
[    0.000000]   DMA zone: 3991 pages, LIFO batch:0
[    0.000000]   Normal zone: 1736 pages used for memmap
[    0.000000]   Normal zone: 214014 pages, LIFO batch:31
[    0.000000]   HighMem zone: 33026 pages, LIFO batch:7
[    0.000000] Reserved but unavailable: 1 pages
[    0.000000] Using APIC driver default
[    0.000000] SFI: Simple Firmware Interface v0.81 http://simplefirmware.org
[    0.000000] SFI: SYST E31F0, 0060 (v1  INTEL INTELFDK)
[    0.000000] SFI: CPUS E3296, 0020 (v1  INTEL INTELFDK)
[    0.000000] SFI: FREQ E32C2, 0030 (v1  INTEL INTELFDK)
[    0.000000] SFI: MMAP E32FE, 01A4 (v1  INTEL INTELFDK)
[    0.000000] SFI: XSDT E34B0, 002C (v1  INTEL INTELFDK)
[    0.000000] SFI: APIC E353E, 0020 (v1  INTEL INTELFDK)
[    0.000000] SFI: WAKE E356A, 0020 (v2  INTEL INTELFDK)
[    0.000000] SFI: DEVS E359E, 047D (v1  INTEL INTELFDK)
[    0.000000] SFI: GPIO E3A27, 0964 (v1  INTEL INTELFDK)
[    0.000000] SFI: OEMB E4397, 0060 (v5 UMGFDK CFGINFO!)
[    0.000000] SFI: registering lapic[0]
[    0.000000] SFI: registering lapic[2]
[    0.000000] IOAPIC[0]: apic_id 0, version 32, address 0xfec00000, GSI 0-54
[    0.000000] smpboot: Allowing 2 CPUs, 0 hotplug CPUs
[    0.000000] PM: Registered nosave memory: [mem 0x00000000-0x00000fff]
[    0.000000] PM: Registered nosave memory: [mem 0x00098000-0x000fffff]
[    0.000000] PM: Registered nosave memory: [mem 0x04000000-0x05ffffff]
[    0.000000] e820: [mem 0x40000000-0xfebfffff] available for PCI devices
[    0.000000] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns
[    0.000000] setup_percpu: NR_CPUS:8 nr_cpumask_bits:8 nr_cpu_ids:2 nr_node_ids:1
[    0.000000] percpu: Embedded 29 pages/cpu @(ptrval) s87756 r0 d31028 u118784
[    0.000000] pcpu-alloc: s87756 r0 d31028 u118784 alloc=29*4096
[    0.000000] pcpu-alloc: [0] 0 [0] 1
[    0.000000] Built 1 zonelists, mobility grouping on.  Total pages: 249263
[    0.000000] Kernel command line: root=/dev/mmcblk0p8 rootfstype=ext4 console=ttyS2,115200n8 earlyprintk=ttyS2,keep loglevel=8 acpi=off g_multi.ethernet_config=rndis systemd.unit=multi-user.target hardware_id=00 g_multi.iSerialNumber=4aa08c597b139dd507f4946de1312c8a g_multi.dev_addr=02:00:86:31:2c:8a platform_mrfld_audio.audio_codec=dummy
[    0.000000] Dentry cache hash table entries: 131072 (order: 7, 524288 bytes)
[    0.000000] Inode-cache hash table entries: 65536 (order: 6, 262144 bytes)
[    0.000000] BRK [0x07a9d000, 0x07a9dfff] PGTABLE
[    0.000000] Initializing CPU#0
[    0.000000] Initializing HighMem for node 0 (000373fe:0003f500)
[    0.000000] Initializing Movable for node 0 (00000000:00000000)
[    0.000000] Memory: 979932K/1004124K available (9562K kernel code, 987K rwdata, 2764K rodata, 736K init, 620K bss, 24192K reserved, 0K cma-reserved, 132104K highmem)
[    0.000000] virtual kernel memory layout:
[    0.000000]     fixmap  : 0xfff16000 - 0xfffff000   ( 932 kB)
[    0.000000]   cpu_entry : 0xff800000 - 0xff939000   (1252 kB)
[    0.000000]     pkmap   : 0xff400000 - 0xff800000   (4096 kB)
[    0.000000]     vmalloc : 0xf7bfe000 - 0xff3fe000   ( 120 MB)
[    0.000000]     lowmem  : 0xc0000000 - 0xf73fe000   ( 883 MB)
[    0.000000]       .init : 0xc791d000 - 0xc79d5000   ( 736 kB)
[    0.000000]       .data : 0xc7556930 - 0xc7907f40   (3781 kB)
[    0.000000]       .text : 0xc6c00000 - 0xc7556930   (9562 kB)
[    0.000000] Checking if this processor honours the WP bit even in supervisor mode...Ok.
[    0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1
[    0.000000] Hierarchical RCU implementation.
[    0.000000]  RCU event tracing is enabled.
[    0.000000]  RCU restricting CPUs from NR_CPUS=8 to nr_cpu_ids=2.
[    0.000000] RCU: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=2
[    0.000000] NR_IRQS: 2304, nr_irqs: 512, preallocated irqs: 0
[    0.000000] CPU 0 irqstacks, hard=(ptrval) soft=(ptrval)
[    0.000000] Console: colour dummy device 80x25
[    0.000000] console [ttyS2] enabled
[    0.000000] APIC: Switch to symmetric I/O mode setup
[    0.000000] Enabling APIC mode:  Flat.  Using 1 I/O APICs
[    0.000000] tsc: Detected 500.000 MHz processor
[    0.000047] tsc: Detected 499.200 MHz TSC
[    0.000049] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0xe642f98297, max_idle_ns: 881590439301 ns
[    0.000732] Calibrating delay loop (skipped), value calculated using timer frequency.. 998.40 BogoMIPS (lpj=499200)
[    0.001000] pid_max: default: 32768 minimum: 301
[    0.001000] Security Framework initialized
[    0.001000] SELinux:  Initializing.
[    0.001000] SELinux:  Starting in permissive mode
[    0.001000] Mount-cache hash table entries: 2048 (order: 1, 8192 bytes)
[    0.001000] Mountpoint-cache hash table entries: 2048 (order: 1, 8192 bytes)
[    0.001000] CPU: Physical Processor ID: 0
[    0.001000] CPU: Processor Core ID: 0
[    0.001000] ENERGY_PERF_BIAS: Set to 'normal', was 'performance'
[    0.001000] ENERGY_PERF_BIAS: View and update with x86_energy_perf_policy(8)
[    0.001000] mce: CPU supports 6 MCE banks
[    0.001000] CPU0: Thermal monitoring enabled (TM1)
[    0.001000] process: using mwait in idle threads
[    0.001000] Last level iTLB entries: 4KB 48, 2MB 0, 4MB 0
[    0.001000] Last level dTLB entries: 4KB 128, 2MB 16, 4MB 16, 1GB 0
[    0.001000] Spectre V2 : Mitigation: Full generic retpoline
[    0.001000] Freeing SMP alternatives memory: 40K
[    0.001000] SFI: MCFG E34F6, 003C (v1  INTEL INTELFDK)
[    0.001000] TSC deadline timer enabled
[    0.001000] smpboot: CPU0: Genuine Intel(R) CPU   4000  @  500MHz (family: 0x6, model: 0x4a, stepping: 0x8)
[    0.001000] Performance Events: PEBS fmt2+, generic architected perfmon, full-width counters, Intel PMU driver.
[    0.001542] ... version:                3
[    0.001749] ... bit width:              40
[    0.001958] ... generic registers:      2
[    0.002023] ... value mask:             000000ffffffffff
[    0.002294] ... max period:             0000007fffffffff
[    0.002560] ... fixed-purpose events:   3
[    0.002765] ... event mask:             0000000700000003
[    0.003256] Hierarchical SRCU implementation.
[    0.009859] smp: Bringing up secondary CPUs ...
[    0.010760] CPU 1 irqstacks, hard=0d8310bb soft=596315b5
[    0.011021] x86: Booting SMP configuration:
[    0.011240] .... node  #0, CPUs:      #1
[    0.001000] Initializing CPU#1
[    0.011828] smp: Brought up 1 node, 2 CPUs
[    0.012250] smpboot: Max logical packages: 1
[    0.012472] smpboot: Total of 2 processors activated (1996.80 BogoMIPS)
[    0.013425] devtmpfs: initialized
[    0.017548] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns
[    0.017441] kworker/u4:0 (20) used greatest stack depth: 7084 bytes left
[    0.018036] futex hash table entries: 512 (order: 3, 32768 bytes)
[    0.018237] pinctrl core: initialized pinctrl subsystem
[    0.019089] SFI: SFI sysfs interfaces init success
[    0.019209] regulator-dummy: no parameters
[    0.020127] RTC time: 19:36:51, date: 08/05/18
[    0.020127] NET: Registered protocol family 16
[    0.022430] audit: initializing netlink subsys (disabled)
[    0.023780] audit: type=2000 audit(1533497811.023:1): state=initialized audit_enabled=0 res=1
[    0.024665] cpuidle: using governor menu
[    0.026716] Using generic wifi platform data
[    0.027666] PCI: MMCONFIG for domain 0000 [bus 00-00] at [mem 0x3f500000-0x3f5fffff] (base 0x3f500000)
[    0.028028] PCI: MMCONFIG at [mem 0x3f500000-0x3f5fffff] reserved in E820
[    0.028368] PCI: Using MMCONFIG for extended config space
[    0.029032] Intel MID platform detected, using MID PCI ops
[    0.029061] PCI: Using configuration type 1 for base access
[    0.036451] kworker/u4:0 (68) used greatest stack depth: 6828 bytes left
[    0.069822] HugeTLB registered 4.00 MiB page size, pre-allocated 0 pages
[    0.072186] ACPI: Interpreter disabled.
[    0.072525] bcm43xx-vmmc-regulator: Failed to request enable GPIO96: -517
[    0.072880] reg-fixed-voltage reg-fixed-voltage.0.auto: Failed to register regulator: -517
[    0.073400] vgaarb: loaded
[    0.074211] SCSI subsystem initialized
[    0.074713] libata version 3.00 loaded.
[    0.075371] usbcore: registered new interface driver usbfs
[    0.075745] usbcore: registered new interface driver hub
[    0.076158] usbcore: registered new device driver usb
[    0.076700] pps_core: LinuxPPS API ver. 1 registered
[    0.076957] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti 
[    0.077081] PTP clock support registered
[    0.077588] EDAC MC: Ver: 3.0.0
[    0.078416] Advanced Linux Sound Architecture Driver Initialized.
[    0.079055] PCI: Probing PCI hardware
[    0.079055] PCI: root bus 00: using default resources
[    0.079055] PCI: Probing PCI hardware (bus 00)
[    0.079420] PCI host bridge to bus 0000:00
[    0.079648] pci_bus 0000:00: root bus resource [io  0x0000-0xffff]
[    0.079968] pci_bus 0000:00: root bus resource [mem 0x00000000-0xffffffff]
[    0.080031] pci_bus 0000:00: No busn resource found for root bus, will use [bus 00-ff]
[    0.081020] pci 0000:00:00.0: [8086:1170] type 00 class 0x060000
[    0.081216] pci 0000:00:01.0: [8086:1190] type 00 class 0x080501
[    0.081571] pci 0000:00:01.0: reg 0x10: [mem 0xff3fc000-0xff3fc0ff]
[    0.082000] pci 0000:00:01.0: PME# supported from D0 D3hot
[    0.083272] pci 0000:00:01.2: [8086:1190] type 00 class 0x080501
[    0.083623] pci 0000:00:01.2: reg 0x10: [mem 0xff3fa000-0xff3fa0ff]
[    0.084058] pci 0000:00:01.2: PME# supported from D0 D3hot
[    0.084584] pci 0000:00:01.3: [8086:1190] type 00 class 0x080501
[    0.084933] pci 0000:00:01.3: reg 0x10: [mem 0xff3fb000-0xff3fb0ff]
[    0.085138] pci 0000:00:01.3: PME# supported from D0 D3hot
[    0.085672] pci 0000:00:02.0: [8086:1182] type 00 class 0x038000
[    0.086030] pci 0000:00:02.0: reg 0x10: [mem 0xc0000000-0xc1ffffff]
[    0.086385] pci 0000:00:02.0: reg 0x18: [mem 0x80000000-0x8fffffff]
[    0.086733] pci 0000:00:02.0: reg 0x20: [io  0x7ff8-0x7fff]
[    0.087310] pci 0000:00:04.0: [8086:1191] type 00 class 0x070002
[    0.087660] pci 0000:00:04.0: reg 0x10: [mem 0xff010000-0xff01007f]
[    0.088100] pci 0000:00:04.0: PME# supported from D0 D3hot
[    0.088621] pci 0000:00:04.1: [8086:1191] type 00 class 0x070002
[    0.088970] pci 0000:00:04.1: reg 0x10: [mem 0xff010080-0xff0100ff]
[    0.089137] pci 0000:00:04.1: PME# supported from D0 D3hot
[    0.090007] pci 0000:00:04.2: [8086:1191] type 00 class 0x070002
[    0.090007] pci 0000:00:04.2: reg 0x10: [mem 0xff010100-0xff01017f]
[    0.090137] pci 0000:00:04.2: PME# supported from D0 D3hot
[    0.091019] pci 0000:00:04.3: [8086:1191] type 00 class 0x070002
[    0.091019] pci 0000:00:04.3: reg 0x10: [mem 0xff010180-0xff0101ff]
[    0.092137] pci 0000:00:04.3: PME# supported from D0 D3hot
[    0.092660] pci 0000:00:05.0: [8086:1192] type 00 class 0x070002
[    0.093000] pci 0000:00:05.0: reg 0x10: [mem 0xff010400-0xff0107ff]
[    0.093138] pci 0000:00:05.0: PME# supported from D0 D3hot
[    0.094007] pci 0000:00:06.0: [8086:1193] type 00 class 0x088000
[    0.094026] pci 0000:00:06.0: reg 0x10: [mem 0xff2a0000-0xff2a0fff]
[    0.094458] pci 0000:00:06.0: PME# supported from D0 D3hot
[    0.095019] pci 0000:00:06.1: [8086:1193] type 00 class 0x088000
[    0.095068] pci 0000:00:06.1: reg 0x10: [mem 0xff2a1000-0xff2a1fff]
[    0.095498] pci 0000:00:06.1: PME# supported from D0 D3hot
[    0.096037] pci 0000:00:07.0: [8086:1194] type 00 class 0x088000
[    0.096387] pci 0000:00:07.0: reg 0x10: [mem 0xff188000-0xff188fff]
[    0.096817] pci 0000:00:07.0: PME# supported from D0 D3hot
[    0.097263] pci 0000:00:07.1: [8086:1194] type 00 class 0x088000
[    0.097612] pci 0000:00:07.1: reg 0x10: [mem 0xff189000-0xff189fff]
[    0.098048] pci 0000:00:07.1: PME# supported from D0 D3hot
[    0.099019] pci 0000:00:07.2: [8086:1194] type 00 class 0x088000
[    0.099019] pci 0000:00:07.2: reg 0x10: [mem 0xff18a000-0xff18afff]
[    0.099137] pci 0000:00:07.2: PME# supported from D0 D3hot
[    0.100009] pci 0000:00:08.0: [8086:1195] type 00 class 0x078000
[    0.100028] pci 0000:00:08.0: reg 0x10: [mem 0xff18b000-0xff18bfff]
[    0.100460] pci 0000:00:08.0: PME# supported from D0 D3hot
[    0.101041] pci 0000:00:08.1: [8086:1195] type 00 class 0x078000
[    0.102069] pci 0000:00:08.1: reg 0x10: [mem 0xff18c000-0xff18cfff]
[    0.102499] pci 0000:00:08.1: PME# supported from D0 D3hot
[    0.103017] pci 0000:00:08.2: [8086:1195] type 00 class 0x078000
[    0.103365] pci 0000:00:08.2: reg 0x10: [mem 0xff18d000-0xff18dfff]
[    0.103793] pci 0000:00:08.2: PME# supported from D0 D3hot
[    0.104257] pci 0000:00:08.3: [8086:1195] type 00 class 0x078000
[    0.104607] pci 0000:00:08.3: reg 0x10: [mem 0xff18e000-0xff18efff]
[    0.105042] pci 0000:00:08.3: PME# supported from D0 D3hot
[    0.105576] pci 0000:00:09.0: [8086:1196] type 00 class 0x078000
[    0.105925] pci 0000:00:09.0: reg 0x10: [mem 0xff18f000-0xff18ffff]
[    0.106139] pci 0000:00:09.0: PME# supported from D0 D3hot
[    0.107016] pci 0000:00:09.1: [8086:1196] type 00 class 0x078000
[    0.107016] pci 0000:00:09.1: reg 0x10: [mem 0xff190000-0xff190fff]
[    0.107447] pci 0000:00:09.1: PME# supported from D0 D3hot
[    0.108009] pci 0000:00:09.2: [8086:1196] type 00 class 0x078000
[    0.108068] pci 0000:00:09.2: reg 0x10: [mem 0xff191000-0xff191fff]
[    0.108499] pci 0000:00:09.2: PME# supported from D0 D3hot
[    0.109040] pci 0000:00:0a.0: [8086:1197] type 00 class 0x078000
[    0.109385] pci 0000:00:0a.0: reg 0x10: [mem 0xff3f8000-0xff3f8fff]
[    0.109814] pci 0000:00:0a.0: PME# supported from D0 D3hot
[    0.110260] pci 0000:00:0b.0: [8086:1198] type 00 class 0x108000
[    0.110609] pci 0000:00:0b.0: reg 0x10: [mem 0xf9038000-0xf903ffff]
[    0.111043] pci 0000:00:0b.0: PME# supported from D0 D3hot
[    0.112018] pci 0000:00:0c.0: [8086:1199] type 00 class 0x088000
[    0.112018] pci 0000:00:0c.0: reg 0x10: [mem 0xff008000-0xff008fff]
[    0.113046] pci 0000:00:0c.0: reg 0x14: [mem 0x000ddcc0-0x000ddccf]
[    0.113466] pci 0000:00:0c.0: PME# supported from D0 D3hot
[    0.113986] pci 0000:00:0d.0: [8086:119a] type 00 class 0x040100
[    0.114068] pci 0000:00:0d.0: reg 0x10: [mem 0x05e00000-0x05ffffff]
[    0.114404] pci 0000:00:0d.0: reg 0x14: [mem 0xff340000-0xff343fff]
[    0.114735] pci 0000:00:0d.0: reg 0x18: [mem 0xff344000-0xff344fff]
[    0.115040] pci 0000:00:0d.0: reg 0x1c: [mem 0xff2c0000-0xff2dffff]
[    0.115374] pci 0000:00:0d.0: reg 0x20: [mem 0xff300000-0xff33ffff]
[    0.115762] pci 0000:00:0d.0: PME# supported from D0 D3hot
[    0.116265] pci 0000:00:0e.0: [8086:119b] type 00 class 0x088000
[    0.116614] pci 0000:00:0e.0: reg 0x10: [mem 0xff298000-0xff29bfff]
[    0.116948] pci 0000:00:0e.0: reg 0x14: [mem 0xff2a2000-0xff2a2fff]
[    0.117128] pci 0000:00:0e.0: PME# supported from D0 D3hot
[    0.118007] pci 0000:00:11.0: [8086:119e] type 00 class 0x0c0320
[    0.118007] pci 0000:00:11.0: reg 0x10: [mem 0xf9100000-0xf911ffff]
[    0.118447] pci 0000:00:11.0: PME# supported from D0 D3hot
[    0.119009] pci 0000:00:12.0: [8086:119f] type 00 class 0x118000
[    0.119068] pci 0000:00:12.0: reg 0x10: [mem 0xf9009000-0xf9009fff]
[    0.119404] pci 0000:00:12.0: reg 0x14: [mem 0xf90a0000-0xf90affff]
[    0.119736] pci 0000:00:12.0: reg 0x18: [mem 0xfa000000-0xfaffffff]
[    0.120115] pci 0000:00:12.0: PME# supported from D0 D3hot
[    0.121037] pci 0000:00:13.0: [8086:11a0] type 00 class 0x0b4000
[    0.121037] pci 0000:00:13.0: reg 0x10: [mem 0xff009000-0xff009fff]
[    0.122137] pci 0000:00:13.0: PME# supported from D0 D3hot
[    0.122661] pci 0000:00:14.0: [8086:11a1] type 00 class 0x0b4000
[    0.123000] pci 0000:00:14.0: reg 0x10: [mem 0xff00b000-0xff00bfff]
[    0.123447] pci 0000:00:14.0: PME# supported from D0 D3hot
[    0.124006] pci 0000:00:15.0: [8086:11a2] type 00 class 0x088000
[    0.124068] pci 0000:00:15.0: reg 0x10: [mem 0xff192000-0xff192fff]
[    0.124499] pci 0000:00:15.0: PME# supported from D0 D3hot
[    0.125041] pci 0000:00:16.0: [8086:11a3] type 00 class 0x0b4000
[    0.125365] pci 0000:00:16.0: reg 0x10: [mem 0xff0d9000-0xff0d90ff]
[    0.125794] pci 0000:00:16.0: PME# supported from D0 D3hot
[    0.126268] pci 0000:00:16.1: [8086:11a4] type 00 class 0x0b4000
[    0.126617] pci 0000:00:16.1: reg 0x10: [mem 0x04819000-0x04898fff]
[    0.126950] pci 0000:00:16.1: reg 0x14: [mem 0x04919000-0x04920fff]
[    0.127127] pci 0000:00:16.1: PME# supported from D0 D3hot
[    0.128016] pci 0000:00:17.0: [8086:11a5] type 00 class 0x088000
[    0.128016] pci 0000:00:17.0: reg 0x10: [mem 0xff013000-0xff013fff]
[    0.128447] pci 0000:00:17.0: PME# supported from D0 D3hot
[    0.129041] pci 0000:00:18.0: [8086:11a6] type 00 class 0x038000
[    0.129169] pci 0000:00:18.0: PME# supported from D0 D3hot
[    0.130009] pci_bus 0000:00: busn_res: [bus 00-ff] end is updated to 00
[    0.130172] PCI: pci_cache_line_size set to 64 bytes
[    0.130595] e820: reserve RAM buffer [mem 0x00098000-0x0009ffff]
[    0.130901] e820: reserve RAM buffer [mem 0x3f500000-0x3fffffff]
[    0.132693] Bluetooth: Core ver 2.22
[    0.133047] NET: Registered protocol family 31
[    0.133047] Bluetooth: HCI device and connection manager initialized
[    0.133349] Bluetooth: HCI socket layer initialized
[    0.133603] Bluetooth: L2CAP socket layer initialized
[    0.133896] Bluetooth: SCO socket layer initialized
[    0.134049] NetLabel: Initializing
[    0.134230] NetLabel:  domain hash size = 128
[    0.134452] NetLabel:  protocols = UNLABELED CIPSOv4 CALIPSO
[    0.134837] NetLabel:  unlabeled traffic allowed by default
[    0.137131] clocksource: Switched to clocksource tsc-early
[    0.212105] VFS: Disk quotas dquot_6.6.0
[    0.212416] VFS: Dquot-cache hash table entries: 1024 (order 0, 4096 bytes)
[    0.213121] pnp: PnP ACPI: disabled
[    0.228498] pci_bus 0000:00: resource 4 [io  0x0000-0xffff]
[    0.228796] pci_bus 0000:00: resource 5 [mem 0x00000000-0xffffffff]
[    0.229297] NET: Registered protocol family 2
[    0.230435] tcp_listen_portaddr_hash hash table entries: 512 (order: 0, 6144 bytes)
[    0.230844] TCP established hash table entries: 8192 (order: 3, 32768 bytes)
[    0.231335] TCP bind hash table entries: 8192 (order: 4, 65536 bytes)
[    0.231812] TCP: Hash tables configured (established 8192 bind 8192)
[    0.232302] UDP hash table entries: 512 (order: 2, 16384 bytes)
[    0.232638] UDP-Lite hash table entries: 512 (order: 2, 16384 bytes)
[    0.233215] NET: Registered protocol family 1
[    0.234141] RPC: Registered named UNIX socket transport module.
[    0.234444] RPC: Registered udp transport module.
[    0.234682] RPC: Registered tcp transport module.
[    0.234920] RPC: Registered tcp NFSv4.1 backchannel transport module.
[    0.562070] PCI: CLS 0 bytes, default 64
[    0.591384] DMA-API: preallocated 65536 debug entries
[    0.591649] DMA-API: debugging enabled by kernel config
[    0.593331] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0xe642f98297, max_idle_ns: 881590439301 ns
[    0.593878] clocksource: Switched to clocksource tsc
[    0.594402] platform rtc_cmos: registered platform RTC device (no PNP device found)
[    0.596764] Scanning for low memory corruption every 60 seconds
[    0.598532] pci 0000:00:04.1: Registered Bluetooth device: hci_bcm
[    0.601727] Initialise system trusted keyrings
[    0.602308] workingset: timestamp_bits=30 max_order=18 bucket_order=0
[    0.618897] NFS: Registering the id_resolver key type
[    0.619251] Key type id_resolver registered
[    0.619471] Key type id_legacy registered
[    0.620384] SELinux:  Registering netfilter hooks
[    0.628340] Key type asymmetric registered
[    0.628561] Asymmetric key parser 'x509' registered
[    0.628882] bounce: pool size: 64 pages
[    0.629213] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 248)
[    0.629587] io scheduler noop registered
[    0.629790] io scheduler deadline registered
[    0.630402] io scheduler cfq registered (default)
[    0.630648] io scheduler mq-deadline registered
[    0.630881] io scheduler kyber registered
[    0.641998] dw_dmac_pci 0000:00:15.0: DesignWare DMA Controller, 8 channels
[    0.644246] hsu_dma_pci 0000:00:05.0: Found HSU DMA, 12 channels
[    0.645329] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled
[    0.649164] 8250_mid: probe of 0000:00:04.0 failed with error -16
[    0.650081] 0000:00:04.1: ttyS0 at MMIO 0xff010080 (irq = 28, base_baud = 1843200) is a TI16750
[    0.651690] 0000:00:04.2: ttyS1 at MMIO 0xff010100 (irq = 29, base_baud = 1843200) is a TI16750
[    0.653296] console [ttyS2] disabled
[    0.653526] 0000:00:04.3: ttyS2 at MMIO 0xff010180 (irq = 54, base_baud = 1843200) is a TI16750
[    2.807948] console [ttyS2] enabled
[    2.812515] Non-volatile memory driver v1.3
[    2.817462] Linux agpgart interface v0.103
[    2.832737] loop: module loaded
[    2.838146] e100: Intel(R) PRO/100 Network Driver, 3.5.24-k2-NAPI
[    2.844442] e100: Copyright(c) 1999-2006 Intel Corporation
[    2.850228] e1000: Intel(R) PRO/1000 Network Driver - version 7.3.21-k8-NAPI
[    2.857481] e1000: Copyright (c) 1999-2006 Intel Corporation.
[    2.863519] e1000e: Intel(R) PRO/1000 Network Driver - 3.2.6-k
[    2.869527] e1000e: Copyright(c) 1999 - 2015 Intel Corporation.
[    2.875772] igb: Intel(R) Gigabit Ethernet Network Driver - version 5.4.0-k
[    2.882934] igb: Copyright (c) 2007-2014 Intel Corporation.
[    2.888794] sky2: driver version 1.30
[    2.893346] usbcore: registered new interface driver r8152
[    2.899103] usbcore: registered new interface driver asix
[    2.904739] usbcore: registered new interface driver ax88179_178a
[    2.911099] usbcore: registered new interface driver cdc_ether
[    2.917176] usbcore: registered new interface driver net1080
[    2.923092] usbcore: registered new interface driver cdc_subset
[    2.929256] usbcore: registered new interface driver zaurus
[    2.935088] usbcore: registered new interface driver MOSCHIP usb-ethernet driver
[    2.942798] usbcore: registered new interface driver cdc_ncm
[    2.949442] dwc3 dwc3.1.auto: Configuration mismatch. dr_mode forced to host
[    2.958138] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver
[    2.964928] ehci-pci: EHCI PCI platform driver
[    2.969637] ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver
[    2.976031] ohci-pci: OHCI PCI platform driver
[    2.980709] uhci_hcd: USB Universal Host Controller Interface driver
[    2.987625] xhci-hcd xhci-hcd.2.auto: xHCI Host Controller
[    2.993565] xhci-hcd xhci-hcd.2.auto: new USB bus registered, assigned bus number 1
[    3.001671] xhci-hcd xhci-hcd.2.auto: hcc params 0x0220f06c hci version 0x100 quirks 0x02010010
[    3.010691] xhci-hcd xhci-hcd.2.auto: irq 34, io mem 0xf9100000
[    3.017223] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002
[    3.024223] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[    3.031652] usb usb1: Product: xHCI Host Controller
[    3.036679] usb usb1: Manufacturer: Linux 4.16.0-edison-no-acpi-standard xhci-hcd
[    3.044368] usb usb1: SerialNumber: xhci-hcd.2.auto
[    3.050132] hub 1-0:1.0: USB hub found
[    3.054090] hub 1-0:1.0: 1 port detected
[    3.058688] xhci-hcd xhci-hcd.2.auto: xHCI Host Controller
[    3.064617] xhci-hcd xhci-hcd.2.auto: new USB bus registered, assigned bus number 2
[    3.072617] usb usb2: We don't know the algorithms for LPM for this host, disabling LPM.
[    3.081169] usb usb2: New USB device found, idVendor=1d6b, idProduct=0003
[    3.088179] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[    3.095622] usb usb2: Product: xHCI Host Controller
[    3.100653] usb usb2: Manufacturer: Linux 4.16.0-edison-no-acpi-standard xhci-hcd
[    3.108345] usb usb2: SerialNumber: xhci-hcd.2.auto
[    3.114093] hub 2-0:1.0: USB hub found
[    3.118048] hub 2-0:1.0: 1 port detected
[    3.122799] usbcore: registered new interface driver usblp
[    3.128614] usbcore: registered new interface driver usb-storage
[    3.134979] usbcore: registered new interface driver pl2303
[    3.140781] usbserial: USB Serial support registered for pl2303
[    3.148292] rtc_cmos rtc_cmos: rtc core: registered rtc_cmos as rtc0
[    3.154961] rtc_cmos rtc_cmos: alarms up to one day, 114 bytes nvram
[    3.162680] device-mapper: ioctl: 4.37.0-ioctl (2017-09-20) initialised: dm-devel@redhat.com
[    3.171504] hidraw: raw HID events driver (C) Jiri Kosina
[    3.178329] usbcore: registered new interface driver usbhid
[    3.184094] usbhid: USB HID core driver
[    3.189434] intel_mid_wdt intel_mid_wdt: Intel MID watchdog device probed
[    3.197472] input: msic_power_btn as /devices/platform/msic_power_btn/input/input0
[    3.207979] Initializing XFRM netlink socket
[    3.213520] NET: Registered protocol family 10
[    3.219328] Segment Routing with IPv6
[    3.223646] sit: IPv6, IPv4 and MPLS over IPv4 tunneling driver
[    3.230827] NET: Registered protocol family 17
[    3.235514] Key type dns_resolver registered
[    3.242236] microcode: sig=0x406a8, pf=0x1, revision=0x81f
[    3.248142] microcode: Microcode Update Driver: v2.2.
[    3.248161] Using IPI No-Shortcut mode
[    3.257469] sched_clock: Marking stable (3257238091, 0)->(3271045482, -13807391)
[    3.266218] registered taskstats version 1
[    3.270458] Loading compiled-in X.509 certificates
[    3.276661] bcm43xx-vmmc-regulator: at 2000 mV
[    3.281548] reg-fixed-voltage reg-fixed-voltage.0.auto: bcm43xx-vmmc-regulator supplying 2000000uV
[    3.290887]   Magic number: 14:982:646
[    3.294937] console [netcon0] enabled
[    3.298726] netconsole: network logging started
[    3.303953] input: gpio-keys as /devices/platform/gpio-keys/input/input1
[    3.311579] cfg80211: Loading compiled-in X.509 certificates for regulatory database
[    3.327603] cfg80211: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'
[    3.334457] platform regulatory.0: Direct firmware load for regulatory.db failed with error -2
[    3.335088] bcm43xx-vmmc-regulator: disabling
[    3.343328] cfg80211: failed to load regulatory.db
[    3.347818] ALSA device list:
[    3.355826]   No soundcards found.
[    3.359758] md: Waiting for all devices to be available before autodetect
[    3.366761] md: If you don't use raid, use raid=noautodetect
[    3.373396] md: Autodetecting RAID arrays.
[    3.377643] md: autorun ...
[    3.380529] md: ... autorun DONE.
[    3.384157] VFS: Cannot open root device "mmcblk0p8" or unknown-block(0,0): error -6
[    3.392131] Please append a correct "root=" boot option; here are the available partitions:
[    3.400727] Kernel panic - not syncing: VFS: Unable to mount root fs on unknown-block(0,0)
[    3.409198] CPU: 1 PID: 1 Comm: swapper/0 Not tainted 4.16.0-edison-no-acpi-standard #1
[    3.417392] Hardware name: Intel Corporation Merrifield/BODEGA BAY, BIOS 542 2015.01.21:18.19.48
[    3.426381] Call Trace:
[    3.428906]  dump_stack+0x47/0x66
[    3.432312]  panic+0x8a/0x1cb
[    3.435361]  mount_block_root+0x12b/0x1d0
[    3.439481]  mount_root+0xf6/0x10f
[    3.442974]  prepare_namespace+0x116/0x147
[    3.447174]  kernel_init_freeable+0x18e/0x1a0
[    3.451641]  ? rest_init+0x90/0x90
[    3.455130]  kernel_init+0x8/0xf0
[    3.458533]  ret_from_fork+0x2e/0x38
[    3.462223] Kernel Offset: 0x5c00000 from 0xc1000000 (relocation range: 0xc0000000-0xf7bfdfff)
[    3.471048] ---[ end Kernel panic - not syncing: VFS: Unable to mount root fs on unknown-block(0,0)

[alext-mkrs]

@lybtongji, you seem to have an interesting mix of what @htot has on the wiki as the "on the side" boot setup - with those *edsboot variables and loading from SD card, with the default setup. How do you install the image, specifically? You basically need to implement proper U-Boot config for that, which you seem to have in edsboot vars. As for the rocko image and vmlinuz - that's actually strange, IIRC rocko produced bzImage for me all the time. Are you sure you don't have some leftovers from older versions?

And yes - that's a completely different issue that the one I had.

[lybtongji]

@alext-mkrs The commands which I used to build the image:

rocko32:

mkdir os
cd os
git clone https://github.com/htot/meta-intel-edison.git
cd meta-intel-edison
git checkout rocko32
cd ..
ln -s meta-intel-edison/utils/Makefile.mk Makefile

make clean && rm -rf bbcache/sstate-cache/

make setup
make edison-image

master:

make clean && rm -rf bbcache/sstate-cache/

cd meta-intel-edison
git clean -df
git reset --hard
git checkout master
cd ..

make setup
make edison-image

Then flash the image to the eMMC by running flashall.bat without SD card.

flashall.bat

I don't know why it changes.

[htot]

In any case it is not intended. Using flashall or Flash Tool Lite the image should just boot as is. Let me check what changed.

BTW I read people have great troubles connecting from Win10 with flashall. What is your experience here?

[htot]

@lybtongji I can confirm your problem. As @alext-mkrs mentioned it is not related to his. Can you please create a new issue for this so we can track it?

How I confirmed:

When you run make edison-image the final step is creating the directory out/current/build/toFlash/. The file that contains the boot partition that is installed by flashall is called edison-image-edison.hddimg

In linux you can mount this file to view it's contents:

sudo losetup --partscan --find --show edison-image-edison.hddimg // in my case returns /dev/loop4
sudo mount /dev/loop4 /mnt
ls -l /mnt
-rw-r--r-- 1 ferry ferry 8725264 jul 14 23:04 bzImage
-r--r--r-- 1 ferry ferry  122308 jul 14 23:04 ldlinux.c32
-r--r--r-- 1 ferry ferry   69632 jul 14 23:04 ldlinux.sys
-rw-r--r-- 1 ferry ferry  186500 jul 14 23:04 libcom32.c32
-rw-r--r-- 1 ferry ferry   24148 jul 14 23:04 libutil.c32
-rw-r--r-- 1 ferry ferry     592 jul 14 23:04 syslinux.cfg
-rw-r--r-- 1 ferry ferry   27104 jul 14 23:04 vesamenu.c32

However, my /dev/mmcblk0p7

root@edison:~# ls -l /mnt
-r-xr-xr-x 1 root root   122308 Dec 27  2017 ldlinux.c32
-r-xr-xr-x 1 root root    69632 Dec 27  2017 ldlinux.sys
-rwxr-xr-x 1 root root   186500 Dec 27  2017 libcom32.c32
-rwxr-xr-x 1 root root    24148 Dec 27  2017 libutil.c32
-rwxr-xr-x 1 root root      592 Dec 27  2017 syslinux.cfg
-rwxr-xr-x 1 root root    27104 Dec 27  2017 vesamenu.c32
-rwxr-xr-x 1 root root 10174352 Dec 27  2017 vmlinuz

So it looks like yocto switched to including bzImage instead of vmlinuz. bzImage has no initramfs (neither does vmlinuz). Afaik we only need the initramfs to boot from sd card or usb.

After you fixed boot you are running into a 2nd problem

VFS: Cannot open root device "mmcblk0p8" or unknown-block(0,0): error -6
Please append a correct "root=" boot option; here are the available partitions:

which contains the rootfs. It looks like none of the mmc partitions are available to linux. Does it need the i2c modules (which I changed from builtin to enable i2c-6)?

[lybtongji]

@htot I have no problem with flashall.bat on Windows 8.1 / 10.

I have installed USB driver (IntelEdisonDriverSetup1.2.1.exe) and FTDI driver (CDM21228_Setup.exe) before.

Just download dfu-util from the dfu-util site.

http://dfu-util.sourceforge.net/releases/dfu-util-0.9-win64.zip

Then extract dfu-util.exe and libusb-1.0.dll from dfu-util-0.9-win64.zip to toFlash folder and run flashall.bat to flash the image.

By the way, using Intel® Edison Board Configuration Tool (intel_edison_setup_win_v2016.2.007.exe) to flash the image is also OK.

[htot]

@alext-mkrs taking another look at your log, it does look you are booting the non-initramfs kernel or otherwise the sdhc modules are not being loaded. Can you please try:

setenv acpi 'debugshell=10'
run edsboot

If you are booting initramfs kernel but the sdhc subsystem is not working this will fallback to a busybox shell after 10 sec so we can investigate further.

[alext-mkrs]

you are booting the non-initramfs kernel

Nope, checked that

or otherwise the sdhc modules are not being loaded

That is what seems to be happening - I just haven't yet got any time to look deeper into that since my previous updates here. The modules are in the initramfs though. I'll check this out and post the results.

[htot]

Yeah, your bzImage is 10MB (mine 12MB, but 64bits), would be 6MB or so without initramfs.

Are you using the same hardware (Edison Arduino board)?

[alext-mkrs]

Are you using the same hardware (Edison Arduino board)?

Yes

Checked with debugshell parameter - no change, it just gets [seemingly] stuck after that pl2303 driver load and then reset kicks in. No shell appears.

[alext-mkrs]

With additional kernel debug options and added debug output I'm now seeing it stalls in the device probe function for the rtc_cmos driver and device, not the pl2303. Capturing the logs here for further investigation.

Log with debug output:

[    8.077730] usbcore: registered new interface driver pl2303
[    8.083480] bus: 'usb-serial': add driver pl2303
[    8.088308] usbserial: USB Serial support registered for pl2303 <<<< this is where my previous logs stopped
[    8.094427] bus: 'platform': add driver ci_hdrc
[    8.099176] bus: 'platform': add driver chipidea-usb2
[    8.104457] bus: 'platform': add driver msm_hsusb
[    8.109382] bus: 'platform': add driver zevio_usb
[    8.114312] bus: 'pci': add driver ci_hdrc_pci
[    8.119084] bus: 'serio': add driver atkbd
[    8.123492] bus: 'serio': add driver psmouse
[    8.128064] bus: 'platform': add driver soc_button_array
[    8.133637] bus: 'pnp': add driver rtc_cmos
[    8.138070] bus: 'platform': add driver rtc_cmos
[    8.142860] bus: 'platform': driver_probe_device: matched device rtc_cmos with driver rtc_cmos
[    8.151722] bus: 'platform': really_probe: probing driver rtc_cmos with device rtc_cmos
[    8.159968] rtc_cmos rtc_cmos: no default pinctrl state
[    8.165391] devices_kset: Moving rtc_cmos to end of list
[    8.170869] mydebug1
[    8.173141] mydebug23

Kernel source it executes upon stall, drivers/base/dd.c:

 456         } else if (drv->probe) {
 457                 pr_debug("mydebug23\n");
 458                 ret = drv->probe(dev);
 459                 pr_debug("mydebug24: %d\n", ret);
 460                 if (ret)
 461                         goto probe_failed;
 462         }

[htot]

Hmm, new territory. Do you have any i2c device connected (I have nothing on the Ardiuno connector)? What you could try is flash the rootfs on a usb stick and use run usbboot. That eliminates the need for emmc/sdhc support. Another is: enable acpi, flash U-Boot (with acpi patches) and run the new kernel with edsboot. This disables the fix for i2c-6, uses built-in i2c driver. In case it's i2c subsystem hanging.

[alext-mkrs]

I have no i2c devices connected and honestly I don't think this can be related - RTC driver doesn't touch i2c. As for the USB boot - yeah, that's an option to get it booting, but my goal here is to fix this original problem.

After glancing over the rtc_cmos code this starts to vaguely look like something related to ACPi not being fully off in our presumably "no-ACPI" master, but this is currently more of a gut feeling than something I can prove :) Will look further into that.

[htot]

Yes, but still doesn't explain why I can't reproduce. And I've tested all 4 combinations of u-boot/kernel acpi/no-acpi. And you are right: the no-acpi kernel actually has acpi - just not working. So, when booted from no-acpi U-Boot the interpreter is switched off and nothing bad happens. When booted from acpi enabled U-Boot it will crash unless you have acpi=off on the command line. This is also true for the factory kernel. The acpi kernel actually has patches for 'hw reduced acpi', which is what the acpi enabled U-Boot provides. But when booted from no-acpi U-Boot, again the interpreter is switched off. And surprisingly afaict everything still works.

[alext-mkrs]

That's why this is a gut feeling and more investigation is needed :)

Though you've mentioned you rebuilt it 64bit-acpi with ACPI disabled - have you dropped all the caches and intermediate state before rebuilding? I've noticed (when experimenting with 64 vs 32 bits earlier - though that may be more invasive than acpi/noacpi) that it may not be properly refreshing all the pieces unless I drop everything manually. Anyway, I think I'll just either do further debug of rtc_cmos or probably first get to 64bit/ACPI just as you to see if that would establish a common reference point - because if not, then we have something else different in our builds.

[alext-mkrs]

Okay, so I've just built and flashed the sumo64-acpi branch (including the U-Boot). Runs fine with acpi=on in kernel cmdline, stalls as originally reported with acpi=off (the only change). So this must have to do something with our kernel probably not being fully ACPI-free even when we disable it from the command line. Interesting. Will keep looking.

[htot]

I did make clean and deleted bbcache/sstate-cache/, so not 100% fresh (this way I don't re-download everything), but close.

[htot]

So now you have U-Boot and kernel with acpi. Turning acpi=off it should still boot (and does for me). Coincidentally I just tried to document this stuff here: https://htot.github.io/meta-intel-edison/1.3-ACPI-or-not.html (except your case acpi U-Boot + kernel + acpi=off is not described :-( )

[lybtongji]

@htot I flashed sumo64-acpi and found blink-led not working. Is it due to ACPI?

[htot]

Yes, because of acpi pin names/numbers have changed. This affects blink-led and mraa and probably some other code too. I found the best way to access pins is from the libgpiod provided tools gpiodetect gpiofind gpioget gpioinfo gpiomon gpioset. See also Edison wiki.

For now I think we need the code first to detect if we are using acpi (Edit: /sys/kernel/config/acpi/table/arduino/ /sys/firmware/acpi exists).

I didn't find time to fix blink-led, but if you want to take this, please do.

[htot]

@alext-mkrs At least the acpi kernel has no i2c patches that could be causing this trouble.

I remember @andy-shev mentioned that running acpi kernel from non-acpi U-Boot is not exactly the same as running acpi kernel from acpi U-Boot with acpi=off. But then, still we don't know why that works for me. Or why your non-acpi U-Boot/kernel crashes.

Looking at your first log (non-acpi U-Boot/kernel) I see: platform rtc_cmos: registered platform RTC device (no PNP device found)

and in your log with debug code:

bus: 'pnp': add driver rtc_cmos
bus: 'platform': add driver rtc_cmos

As if the rtc_cmos driver gets probed 2x. What additional logging did you turn on? I'll try the same for comparison.

[andy-shev]

So, I have only few (crazy) ideas about this:

• SD card (if it's the case) may be somehow broken and driver doesn't initializes it properly
• On opposite it might be a hidden bug in the driver for such SD cards
• The image goes behind the free memory region (I use to have similar issue with recent kernels and I had to move initramfs to 0x06000000; I'm using separate kernel and initramfs images)
• Try latest and greatest vanilla (v4.18 as of this writing)
• If hardware works with other v4.x kernels, than it might be some change in the kernel which makes this happen (git bisect is your choice in this case)

Other than that I lack of ideas...

[htot]

@alext-mkrs You can download my kernel here: https://drive.google.com/file/d/1B78zm0dYqVPQjft2vyp-QaehWRjzF5-P/view?usp=sharing

This is 64b + acpi. Only I added bridge support and ftrace (is my debug kernel). It should be at least enough to get you into a debugshell. But if you still have a hang at least we know it's not related to the build system.

[alext-mkrs]

Ok, thanks gentlemen - I'll check it out hopefully onthe upcoming weekend and let you know.

[htot]

In the meanwhile I have kernel 4.18 + U-Boot v2018.09-rc2 (both acpi enabled) booting. It shows some errors in the log that seem to do no harm. After testing this week I will push to my repo probably next weekend.

[alext-mkrs]

Now that's weird. I've just started looking into this further - by just booting the board as-is, left untouched (with acpi=off) from the last week's experiment. And all of a sudden it booted fine. By now I've done about a dozen boots with both acpi=on and off and it boots just fine in both. So now I don't know what was that when it didn't boot the last week and on vs. off made a difference.

[alext-mkrs]

So this rules out the SD card (and it anyway worked fine in Windows where I write it and with official 3.10 kernel) - plus it actually never got to even try to mount it, that rtc driver stall happened much earlier than that.

Kernel version probably as well (unless the 32-bit has a bug 64 version doesn't, which is not impossible, but less likely).

Memory corruption looks like an interesting option, @andy-shev, how did you detect that in your case (or where I can read about that)?

[htot]

Or maybe it's an initialization thing. We have seen that pressing the reset button does not always reset I2C and also SD card correct. I have even seen that the factory 3.10 had that issue. I have now made it my habit to plug/unplug power when resetting, and then issues with i2c not detecting the mux'es or sd card not appearing. Otoh iirc when booting from sd card, if it doesn't appear I would expect initramfs falling back to the debugshell.

[htot]

BTW with linux 4.18 and U-Boot v2018.09-rc2 (both acpi enabled) I do get an error - maybe not coincidentally in rtc_cmos:

root@edison:~# journalctl -b | grep rtc
Aug 14 19:08:12 edison kernel: platform rtc_cmos: registered platform RTC device (no PNP device found)
Aug 14 19:08:12 edison kernel: genirq: Flags mismatch irq 8. 00000080 (rtc0) vs. 00004000 (0000:00:14.0)
Aug 14 19:08:12 edison kernel:  ? rtc_wake_on+0x20/0x20
Aug 14 19:08:12 edison kernel:  ? rtc_dev_init+0x2b/0x2b
Aug 14 19:08:12 edison kernel:  ? rtc_dev_init+0x2b/0x2b
Aug 14 19:08:12 edison kernel:  ? rtc_dev_init+0x2b/0x2b
Aug 14 19:08:12 edison kernel: rtc_cmos: probe of rtc_cmos failed with error -16

Boot continues fortunately. 0000:00:14.0 is the PMU, with driver intel_mid_pwr. The error goes away when I boot with acpi=off, the other way around from what you saw.

[alext-mkrs]

Yeah, unclean reset (there's AFAIK some hw- or board-level bug/peculiarity, which doesn't reset it properly using reset button) was something I suspected, but I (1) don't use the button and (2) I tried various combinations today (warm/cold resets) and it didn't show up. Oh well. Anyway, I decided to give it one more try by debugging the driver itself. If that doesn't fly, my plan is to get back to our past discussion and check what do we lose by going 64 bit instead of 32 and propose to make 64 bit the default and just focus on ironing out any problems like that UART DMA you've faced. Just to have fewer configs (ideally one acpi-64, if only Andy didn't say ACPI is totally not prime time yet ;) to take care of.

[alext-mkrs]

...and yes, that error you observe is interesting - there seems to be something about RTC settings/driver we have, in ACPI vs. non-ACPI environment.

[htot]

When I asked Andy he said "I know about IRQ flags mismatch, but this doesn't prevent boot anyhow. One should create an AML excerpt for RTC eventually (and perhaps HPET) and it might require some refactoring in the kernel.". But I don't remember seeing this error before.

The UART problems on 64b seem to be caused by an enormous interrupt latency that isn't present on 32b. I haven't been able to find the root of that.

Other then that I haven't seen any other 64b related problems, no particular advantages either, see https://htot.github.io/meta-intel-edison/1.4-i686-or-x86_64.html (this is from my master/docs generated automatically by gh, solves #12 once we find it is complete enough).