5.75 u-boot log

Spoiler

U-Boot SPL 2018.11-armbian (Feb 08 2019 - 08:51:22 +0100)
DRAM: 1024 MiB
CPU: 912000000Hz, AXI/AHB/APB: 3/2/2
Trying to boot from MMC1


U-Boot 2018.11-armbian (Feb 08 2019 - 08:51:22 +0100) Allwinner Technology

CPU:   Allwinner A20 (SUN7I)
Model: LeMaker Banana Pi
I2C:   ready
DRAM:  1 GiB
MMC:   SUNXI SD/MMC: 0
Loading Environment from EXT4... ** File not found /boot/boot.env **

** Unable to read "/boot/boot.env" from mmc0:1 **
Setting up a 720x576i composite-pal console (overscan 32x20)
In:    serial
Out:   vga
Err:   vga
SCSI:  SATA link 0 timeout.
AHCI 0001.0100 32 slots 1 ports 3 Gbps 0x1 impl SATA mode
flags: ncq stag pm led clo only pmp pio slum part ccc apst 

Net:   eth0: ethernet@1c50000
230454 bytes read in 22 ms (10 MiB/s)
starting USB...
USB0:   USB EHCI 1.00
USB1:   USB OHCI 1.0
USB2:   USB EHCI 1.00
USB3:   USB OHCI 1.0
scanning bus 0 for devices... 1 USB Device(s) found
scanning bus 2 for devices... 1 USB Device(s) found
       scanning usb for storage devices... 0 Storage Device(s) found
Autoboot in 1 seconds, press <Space> to stop
switch to partitions #0, OK
mmc0 is current device
Scanning mmc 0:1...
Found U-Boot script /boot/boot.scr
3798 bytes read in 13 ms (285.2 KiB/s)
## Executing script at 43100000
U-boot loaded from SD
Boot script loaded from mmc
204 bytes read in 11 ms (17.6 KiB/s)
4921844 bytes read in 289 ms (16.2 MiB/s)
7323200 bytes read in 423 ms (16.5 MiB/s)
Found mainline kernel configuration
40331 bytes read in 32 ms (1.2 MiB/s)
5845 bytes read in 107 ms (52.7 KiB/s)
Applying kernel provided DT fixup script (sun7i-a20-fixup.scr)
## Executing script at 44000000
## Loading init Ramdisk from Legacy Image at 43300000 ...
   Image Name:   uInitrd
   Image Type:   ARM Linux RAMDisk Image (gzip compressed)
   Data Size:    4921780 Bytes = 4.7 MiB
   Load Address: 00000000
   Entry Point:  00000000
   Verifying Checksum ... OK
## Flattened Device Tree blob at 43000000
   Booting using the fdt blob at 0x43000000
EHCI failed to shut down host controller.
EHCI failed to shut down host controller.
   Loading Ramdisk to 49b4e000, end 49fff9b4 ... OK
   reserving fdt memory region: addr=43000000 size=70000
   Loading Device Tree to 49adb000, end 49b4dfff ... OK

Starting kernel ...

Uncompressing Linux... done, booting the kernel.
[    0.000000] Booting Linux on physical CPU 0x0
[    0.000000] Linux version 4.19.20-sunxi (root@armbian.com) (gcc version 7.2.1 20171011 (Linaro GCC 7.2-2017.11)) #5.75 SMP Sat Feb 9 19:02:47 CET 2019
[    0.000000] CPU: ARMv7 Processor [410fc074] revision 4 (ARMv7), cr=50c5387d
[    0.000000] CPU: div instructions available: patching division code
[    0.000000] CPU: PIPT / VIPT nonaliasing data cache, VIPT aliasing instruction cache
[    0.000000] OF: fdt: Machine model: LeMaker Banana Pi
[    0.000000] Memory policy: Data cache writealloc
[    0.000000] cma: Reserved 128 MiB at 0x77c00000
[    0.000000] psci: probing for conduit method from DT.
[    0.000000] psci: Using PSCI v0.1 Function IDs from DT
[    0.000000] random: get_random_bytes called from start_kernel+0x8d/0x3be with crng_init=0
[    0.000000] percpu: Embedded 18 pages/cpu @(ptrval) s41484 r8192 d24052 u73728
[    0.000000] Built 1 zonelists, mobility grouping on.  Total pages: 260010
[    0.000000] Kernel command line: root=UUID=da7fde2c-8e42-4cb9-a8f2-20692463ff4e rootwait rootfstype=ext4 console=ttyS0,115200 hdmi.audio=EDID:0 disp.screen0_output_mode=1920x1080p60 panic=10 consoleblank=0 loglevel=7 ubootpart=cbddecb1-01 ubootsource=mmc usb-storage.quirks=0x2537:0x1066:u,0x2537:0x1068:u   sunxi_ve_mem_reserve=0 sunxi_g2d_mem_reserve=0 sunxi_fb_mem_reserve=16 cgroup_enable=memory swapaccount=1
[    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] allocated 1051048 bytes of page_ext
[    0.000000] Memory: 885688K/1046952K available (8192K kernel code, 984K rwdata, 2516K rodata, 1024K init, 317K bss, 30192K reserved, 131072K cma-reserved, 129448K highmem)
[    0.000000] Virtual kernel memory layout:
[    0.000000]     vector  : 0xffff0000 - 0xffff1000   (   4 kB)
[    0.000000]     fixmap  : 0xffc00000 - 0xfff00000   (3072 kB)
[    0.000000]     vmalloc : 0xf0800000 - 0xff800000   ( 240 MB)
[    0.000000]     lowmem  : 0xc0000000 - 0xf0000000   ( 768 MB)
[    0.000000]     pkmap   : 0xbfe00000 - 0xc0000000   (   2 MB)
[    0.000000]     modules : 0xbf800000 - 0xbfe00000   (   6 MB)
[    0.000000]       .text : 0x(ptrval) - 0x(ptrval)   (9184 kB)
[    0.000000]       .init : 0x(ptrval) - 0x(ptrval)   (1024 kB)
[    0.000000]       .data : 0x(ptrval) - 0x(ptrval)   ( 985 kB)
[    0.000000]        .bss : 0x(ptrval) - 0x(ptrval)   ( 318 kB)
[    0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1
[    0.000000] ftrace: allocating 38664 entries in 76 pages
[    0.000000] rcu: Hierarchical RCU implementation.
[    0.000000] rcu: 	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: 16, nr_irqs: 16, preallocated irqs: 16
[    0.000000] GIC: Using split EOI/Deactivate mode
[    0.000000] arch_timer: cp15 timer(s) running at 24.00MHz (phys).
[    0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x588fe9dc0, max_idle_ns: 440795202592 ns
[    0.000006] sched_clock: 56 bits at 24MHz, resolution 41ns, wraps every 4398046511097ns
[    0.000020] Switching to timer-based delay loop, resolution 41ns
[    0.000374] clocksource: timer: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 79635851949 ns
[    0.000621] clocksource: hstimer: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 6370868154 ns
[    0.000891] Console: colour dummy device 80x30
[    0.000962] Calibrating delay loop (skipped), value calculated using timer frequency.. 48.00 BogoMIPS (lpj=96000)
[    0.000977] pid_max: default: 32768 minimum: 301
[    0.001279] Mount-cache hash table entries: 2048 (order: 1, 8192 bytes)
[    0.001296] Mountpoint-cache hash table entries: 2048 (order: 1, 8192 bytes)
[    0.002277] CPU: Testing write buffer coherency: ok
[    0.002731] /cpus/cpu@0 missing clock-frequency property
[    0.002751] /cpus/cpu@1 missing clock-frequency property
[    0.002764] CPU0: thread -1, cpu 0, socket 0, mpidr 80000000
[    0.003400] Setting up static identity map for 0x40100000 - 0x40100054
[    0.003579] rcu: Hierarchical SRCU implementation.
[    0.004633] smp: Bringing up secondary CPUs ...
[    0.015475] CPU1: thread -1, cpu 1, socket 0, mpidr 80000001
[    0.015628] smp: Brought up 1 node, 2 CPUs
[    0.015648] SMP: Total of 2 processors activated (96.00 BogoMIPS).
[    0.015654] CPU: All CPU(s) started in HYP mode.
[    0.015660] CPU: Virtualization extensions available.
[    0.016965] devtmpfs: initialized
[    0.025980] VFP support v0.3: implementor 41 architecture 2 part 30 variant 7 rev 4
[    0.026274] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns
[    0.026299] futex hash table entries: 512 (order: 3, 32768 bytes)
[    0.031518] xor: measuring software checksum speed
[    0.071469]    arm4regs  :  1137.000 MB/sec
[    0.111529]    8regs     :   677.000 MB/sec
[    0.151591]    32regs    :   693.000 MB/sec
[    0.191653]    neon      :  1151.000 MB/sec
[    0.191661] xor: using function: neon (1151.000 MB/sec)
[    0.191753] pinctrl core: initialized pinctrl subsystem
[    0.192984] NET: Registered protocol family 16
[    0.195861] DMA: preallocated 2048 KiB pool for atomic coherent allocations
[    0.196441] audit: initializing netlink subsys (disabled)
[    0.196733] audit: type=2000 audit(0.188:1): state=initialized audit_enabled=0 res=1
[    0.197390] cpuidle: using governor ladder
[    0.197438] cpuidle: using governor menu
[    0.197994] hw-breakpoint: found 5 (+1 reserved) breakpoint and 4 watchpoint registers.
[    0.198005] hw-breakpoint: maximum watchpoint size is 8 bytes.
[    0.284152] raid6: int32x1  gen()   169 MB/s
[    0.352402] raid6: int32x1  xor()   145 MB/s
[    0.420414] raid6: int32x2  gen()   231 MB/s
[    0.488598] raid6: int32x2  xor()   179 MB/s
[    0.556832] raid6: int32x4  gen()   234 MB/s
[    0.624849] raid6: int32x4  xor()   174 MB/s
[    0.692993] raid6: int32x8  gen()   223 MB/s
[    0.761139] raid6: int32x8  xor()   155 MB/s
[    0.829220] raid6: neonx1   gen()   447 MB/s
[    0.897388] raid6: neonx1   xor()   416 MB/s
[    0.965563] raid6: neonx2   gen()   600 MB/s
[    1.033670] raid6: neonx2   xor()   537 MB/s
[    1.101821] raid6: neonx4   gen()   721 MB/s
[    1.169934] raid6: neonx4   xor()   605 MB/s
[    1.238124] raid6: neonx8   gen()   649 MB/s
[    1.306235] raid6: neonx8   xor()   548 MB/s
[    1.306244] raid6: using algorithm neonx4 gen() 721 MB/s
[    1.306251] raid6: .... xor() 605 MB/s, rmw enabled
[    1.306258] raid6: using neon recovery algorithm
[    1.308158] SCSI subsystem initialized
[    1.308763] usbcore: registered new interface driver usbfs
[    1.308828] usbcore: registered new interface driver hub
[    1.308897] usbcore: registered new device driver usb
[    1.309145] media: Linux media interface: v0.10
[    1.309183] videodev: Linux video capture interface: v2.00
[    1.309338] pps_core: LinuxPPS API ver. 1 registered
[    1.309347] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>
[    1.309368] PTP clock support registered
[    1.311249] clocksource: Switched to clocksource arch_sys_counter
[    1.427167] VFS: Disk quotas dquot_6.6.0
[    1.427338] VFS: Dquot-cache hash table entries: 1024 (order 0, 4096 bytes)
[    1.428108] simple-framebuffer 7fe79000.framebuffer: framebuffer at 0x7fe79000, 0x178e00 bytes, mapped to 0x(ptrval)
[    1.428126] simple-framebuffer 7fe79000.framebuffer: format=x8r8g8b8, mode=656x536x32, linelength=2880
[    1.435774] fbcon_init: disable boot-logo (boot-logo bigger than screen).
[    1.435786] Console: switching to colour frame buffer device 82x33
[    1.442754] simple-framebuffer 7fe79000.framebuffer: fb0: simplefb registered!
[    1.451016] NET: Registered protocol family 2
[    1.451969] tcp_listen_portaddr_hash hash table entries: 512 (order: 0, 6144 bytes)
[    1.452007] TCP established hash table entries: 8192 (order: 3, 32768 bytes)
[    1.452104] TCP bind hash table entries: 8192 (order: 4, 65536 bytes)
[    1.452230] TCP: Hash tables configured (established 8192 bind 8192)
[    1.452371] UDP hash table entries: 512 (order: 2, 16384 bytes)
[    1.452440] UDP-Lite hash table entries: 512 (order: 2, 16384 bytes)
[    1.452726] NET: Registered protocol family 1
[    1.453449] RPC: Registered named UNIX socket transport module.
[    1.453461] RPC: Registered udp transport module.
[    1.453467] RPC: Registered tcp transport module.
[    1.453474] RPC: Registered tcp NFSv4.1 backchannel transport module.
[    1.453722] Trying to unpack rootfs image as initramfs...
[    1.806007] Freeing initrd memory: 4808K
[    1.806722] hw perfevents: no interrupt-affinity property for /pmu, guessing.
[    1.807055] hw perfevents: enabled with armv7_cortex_a7 PMU driver, 5 counters available
[    1.808721] Initialise system trusted keyrings
[    1.809053] workingset: timestamp_bits=14 max_order=18 bucket_order=4
[    1.815512] zbud: loaded
[    1.818133] NFS: Registering the id_resolver key type
[    1.818176] Key type id_resolver registered
[    1.818185] Key type id_legacy registered
[    1.818201] nfs4filelayout_init: NFSv4 File Layout Driver Registering...
[    1.818209] Installing knfsd (copyright (C) 1996 okir@monad.swb.de).
[    1.819670] JFS: nTxBlock = 7981, nTxLock = 63848
[    1.830918] SGI XFS with ACLs, security attributes, realtime, no debug enabled
[    1.840822] Key type asymmetric registered
[    1.840846] Asymmetric key parser 'x509' registered
[    1.840919] bounce: pool size: 64 pages
[    1.841011] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 246)
[    1.841221] io scheduler noop registered
[    1.841235] io scheduler deadline registered
[    1.841468] io scheduler cfq registered (default)
[    1.841478] io scheduler mq-deadline registered
[    1.841486] io scheduler kyber registered
[    1.841646] io scheduler bfq registered
[    1.846507] sun4i-pinctrl 1c20800.pinctrl: initialized sunXi PIO driver
[    1.907168] Serial: 8250/16550 driver, 8 ports, IRQ sharing disabled
[    1.909946] console [ttyS0] disabled
[    1.930132] 1c28000.serial: ttyS0 at MMIO 0x1c28000 (irq = 48, base_baud = 1500000) is a U6_16550A
[    2.841841] console [ttyS0] enabled
[    2.868606] 1c28c00.serial: ttyS1 at MMIO 0x1c28c00 (irq = 49, base_baud = 1500000) is a U6_16550A
[    2.900733] 1c29c00.serial: ttyS2 at MMIO 0x1c29c00 (irq = 50, base_baud = 1500000) is a U6_16550A
[    2.914241] sun4i-backend 1e60000.display-backend: Couldn't find matching frontend, frontend features disabled
[    2.924627] sun4i-drm display-engine: bound 1e60000.display-backend (ops 0xc0981614)
[    2.932414] sun4i-backend 1e40000.display-backend: Couldn't find matching frontend, frontend features disabled
[    2.942726] sun4i-drm display-engine: bound 1e40000.display-backend (ops 0xc0981614)
[    2.950912] sun4i-drm display-engine: No panel or bridge found... RGB output disabled
[    2.958781] sun4i-drm display-engine: bound 1c0c000.lcd-controller (ops 0xc0980594)
[    2.966795] sun4i-drm display-engine: No panel or bridge found... RGB output disabled
[    2.974657] sun4i-drm display-engine: bound 1c0d000.lcd-controller (ops 0xc0980594)
[    2.983188] sun4i-drm display-engine: bound 1c16000.hdmi (ops 0xc0982020)
[    2.990032] [drm] Supports vblank timestamp caching Rev 2 (21.10.2013).
[    2.996660] [drm] No driver support for vblank timestamp query.
[    3.002594] fb: switching to sun4i-drm-fb from simple
[    3.007765] Console: switching to colour dummy device 80x30
[    3.513723] [drm] Cannot find any crtc or sizes
[    3.518907] [drm] Initialized sun4i-drm 1.0.0 20150629 for display-engine on minor 0
[    3.530692] brd: module loaded
[    3.541083] loop: module loaded
[    3.599290] ahci-sunxi 1c18000.sata: controller can't do PMP, turning off CAP_PMP
[    3.606838] ahci-sunxi 1c18000.sata: SSS flag set, parallel bus scan disabled
[    3.614008] ahci-sunxi 1c18000.sata: AHCI 0001.0100 32 slots 1 ports 3 Gbps 0x1 impl platform mode
[    3.622978] ahci-sunxi 1c18000.sata: flags: ncq sntf stag pm led clo only pio slum part ccc 
[    3.633164] scsi host0: ahci-sunxi
[    3.637055] ata1: SATA max UDMA/133 mmio [mem 0x01c18000-0x01c18fff] port 0x100 irq 36
[    3.648231] libphy: Fixed MDIO Bus: probed
[    3.653030] sun7i-dwmac 1c50000.ethernet: PTP uses main clock
[    3.658830] sun7i-dwmac 1c50000.ethernet: no reset control found
[    3.666093] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver
[    3.672657] ehci-platform: EHCI generic platform driver
[    3.678291] ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver
[    3.684527] ohci-platform: OHCI generic platform driver
[    3.690580] usbcore: registered new interface driver usb-storage
[    3.697715] sunxi-rtc 1c20d00.rtc: registered as rtc0
[    3.702819] sunxi-rtc 1c20d00.rtc: RTC enabled
[    3.707428] i2c /dev entries driver
[    3.712073] axp20x-i2c 1-0034: AXP20x variant AXP209 found
[    3.732201] input: axp20x-pek as /devices/platform/soc@1c00000/1c2ac00.i2c/i2c-1/1-0034/axp20x-pek/input/input0
[    3.743040] ldo1: supplied by regulator-dummy
[    3.747701] ldo2: supplied by regulator-dummy
[    3.753165] ldo3: supplied by regulator-dummy
[    3.758231] ldo4: supplied by regulator-dummy
[    3.762844] ldo5: supplied by regulator-dummy
[    3.768305] dcdc2: supplied by regulator-dummy
[    3.773432] dcdc3: supplied by regulator-dummy
[    3.782261] axp20x-i2c 1-0034: Backup (RTC) battery charging is disabled
[    3.789129] axp20x-i2c 1-0034: AXP20X driver loaded
[    3.796701] sunxi-wdt 1c20c90.watchdog: Watchdog enabled (timeout=16 sec, nowayout=0)
[    3.805879] sunxi-mmc 1c0f000.mmc: Linked as a consumer to regulator.2
[    3.813025] ledtrig-cpu: registered to indicate activity on CPUs
[    3.813157] sunxi-mmc 1c0f000.mmc: Got CD GPIO
[    3.819198] hidraw: raw HID events driver (C) Jiri Kosina
[    3.829092] usbcore: registered new interface driver usbhid
[    3.834691] usbhid: USB HID core driver
[    3.839852] Initializing XFRM netlink socket
[    3.844904] NET: Registered protocol family 10
[    3.849206] sunxi-mmc 1c0f000.mmc: initialized, max. request size: 16384 KB
[    3.863311] random: fast init done
[    3.877051] Segment Routing with IPv6
[    3.880890] NET: Registered protocol family 17
[    3.885413] NET: Registered protocol family 15
[    3.889949] bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this.
[    3.902967] 8021q: 802.1Q VLAN Support v1.8
[    3.907229] Key type dns_resolver registered
[    3.911952] Registering SWP/SWPB emulation handler
[    3.917511] registered taskstats version 1
[    3.921663] Loading compiled-in X.509 certificates
[    3.926584] zswap: loaded using pool lzo/zbud
[    3.933244] Btrfs loaded, crc32c=crc32c-generic
[    3.945169] mmc0: host does not support reading read-only switch, assuming write-enable
[    3.949838] Key type encrypted registered
[    3.957008] mmc0: new high speed SDHC card at address aaaa
[    3.964715] mmcblk0: mmc0:aaaa SL16G 14.8 GiB 
[    3.969331] ata1: SATA link down (SStatus 0 SControl 300)
[    3.971900] sun7i-dwmac 1c50000.ethernet: PTP uses main clock
[    3.976900]  mmcblk0: p1
[    3.980559] sun7i-dwmac 1c50000.ethernet: no reset control found
[    3.989207] sun7i-dwmac 1c50000.ethernet: Linked as a consumer to regulator.14
[    4.103468] sun7i-dwmac 1c50000.ethernet: Version ID not available
[    4.109693] sun7i-dwmac 1c50000.ethernet: 	DWMAC1000
[    4.114673] sun7i-dwmac 1c50000.ethernet: DMA HW capability register supported
[    4.121903] sun7i-dwmac 1c50000.ethernet: Normal descriptors
[    4.127570] sun7i-dwmac 1c50000.ethernet: Ring mode enabled
[    4.151923] libphy: stmmac: probed
[    4.155396] RTL8211E Gigabit Ethernet stmmac-0:00: attached PHY driver [RTL8211E Gigabit Ethernet] (mii_bus:phy_addr=stmmac-0:00, irq=POLL)
[    4.167916] RTL8211E Gigabit Ethernet stmmac-0:01: attached PHY driver [RTL8211E Gigabit Ethernet] (mii_bus:phy_addr=stmmac-0:01, irq=POLL)
[    4.187461] of_cfs_init
[    4.190037] of_cfs_init: OK
[    4.193122] vcc3v0: disabling
[    4.196115] vcc5v0: disabling
[    4.199097] usb0-vbus: disabling
[    4.202334] usb1-vbus: disabling
[    4.205573] usb2-vbus: disabling
[    4.213918] Freeing unused kernel memory: 1024K
[    4.227458] Run /init as init process
Loading, please wait...
starting version 232
[    4.292385] random: systemd-udevd: uninitialized urandom read (16 bytes read)
[    4.299744] random: udevadm: uninitialized urandom read (16 bytes read)
[    4.300344] random: systemd-udevd: uninitialized urandom read (16 bytes read)
[    5.104029] axp20x-gpio axp20x-gpio: DMA mask not set
[    5.123657] axp20x-gpio axp20x-gpio: AXP209 pinctrl and GPIO driver loaded
[    5.519475] [drm] Cannot find any crtc or sizes
Begin: Loading essential drivers ... done.
Begin: Running /scripts/init-premount ... done.
Begin: Mounting root file system ... Begin: Running /scripts/local-top ... done.
Begin: Running /scripts/local-premount ... Scanning for Btrfs filesystems
done.
Begin: Will now check root file system ... fsck from util-linux 2.29.2
[/sbin/fsck.ext4 (1) -- /dev/mmcblk0p1] fsck.ext4 -a -C0 /dev/mmcblk0p1 
/dev/mmcblk0p1: clean, 38054/883584 files, 282722/3849616 blocks
done.
[    6.318519] EXT4-fs (mmcblk0p1): mounted filesystem with writeback data mode. Opts: (null)
done.
Begin: Running /scripts/local-bottom ... done.
Begin: Running /scripts/init-bottom ... done.
[    6.814805] systemd[1]: System time before build time, advancing clock.
[    6.873954] systemd[1]: systemd 232 running in system mode. (+PAM +AUDIT +SELINUX +IMA +APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD +IDN)
[    6.892576] systemd[1]: Detected architecture arm.

Welcome to Debian GNU/Linux 9 (stretch)!

[    6.916365] systemd[1]: Set hostname to <bananapi>.
[    7.396930] systemd[1]: Listening on Syslog Socket.
[  OK  ] Listening on Syslog Socket.
[    7.415784] systemd[1]: Listening on fsck to fsckd communication Socket.
[  OK  ] Listening on fsck to fsckd communication Socket.
[    7.435781] systemd[1]: Listening on Journal Socket.
[  OK  ] Listening on Journal Socket.
[    7.452107] systemd[1]: Listening on Journal Audit Socket.
[  OK  ] Listening on Journal Audit Socket.
[    7.472575] systemd[1]: Created slice System Slice.
[  OK  ] Created slice System Slice.
[    7.497048] systemd[1]: Starting Restore / save the current clock...
         Starting Restore / save the current clock...
[    7.532521] systemd[1]: Starting Load Kernel Modules...
         Starting Load Kernel Modules...
[  OK  ] Created slice User and Session Slice.
[  OK  ] Started Dispatch Password Requests to Console Directory Watch.
[  OK  ] Set up automount Arbitrary Executab…rmats File System Automount Point.
[  OK  ] Created slice system-serial\x2dgetty.slice.
         Mounting POSIX Message Queue File System...
[  OK  ] Listening on /dev/initctl Compatibility Named Pipe.
[  OK  ] Reached target Swap.
[    7.649739] cfg80211: Loading compiled-in X.509 certificates for regulatory database
         Starting Remount Root and Kernel File Systems...
[  OK  ] Listening on udev Control Socket.
[    7.695771] cfg80211: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'
         Mounting Debug File System...
[    7.703896] platform regulatory.0: Direct firmware load for regulatory.db failed with error -2
[    7.715338] platform regulatory.0: Falling back to syfs fallback for: regulatory.db
[  OK  ] Listening on udev Kernel Socket.
[    7.725964] EXT4-fs (mmcblk0p1): re-mounted. Opts: commit=600,errors=remount-ro
[  OK  ] Reached target Slices.
         Starting Create list of required st…ce nodes for the current kernel...
[  OK  ] Listening on Journal Socket (/dev/log).
[    7.782825] Ethernet Channel Bonding Driver: v3.7.1 (April 27, 2011)
         Starting Journal Service...
[  OK  ] Reached target Remote File Systems.
         Starting Set the console keyboard layout...
[  OK  ] Created slice system-getty.slice.
[  OK  ] Started Forward Password Requests to Wall Directory Watch.
[  OK  ] Reached target Paths.
[  OK  ] Reached target Encrypted Volumes.
         Starting Nameserver information manager...
[  OK  ] Mounted Debug File System.
[  OK  ] Mounted POSIX Message Queue File System.
[  OK  ] Started Restore / save the current clock.
[  OK  ] Started Load Kernel Modules.
[  OK  ] Started Remount Root and Kernel File Systems.
[  OK  ] Started Create list of required sta…vice nodes for the current kernel.
[  OK  ] Started Journal Service.
[  OK  ] Started Nameserver information manager.
         Starting Create Static Device Nodes in /dev...
         Starting Flush Journal to Persistent Storage...
         Starting Load/Save Random Seed...
         Starting udev Coldplug all Devices...
         Starting Apply Kernel Variables...
         Mounting Configuration File System...
[  OK  ] Started Create Static Device Nodes in /dev.
[  OK  ] Started Load/Save Random Seed.
[  OK  ] Started Apply Kernel Variables.
[  OK  ] Mounted Configuration File System.
         Starting udev Kernel Device Manager...
[  OK  ] Started Flush Journal to Persistent Storage.
[  OK  ] Started Set the console keyboard layout.
[  OK  ] Reached target Local File Systems (Pre).
         Mounting /tmp...
[  OK  ] Started udev Kernel Device Manager.
[  OK  ] Mounted /tmp.
[  OK  ] Reached target Local File Systems.
         Starting Set console font and keymap...
         Starting Armbian ZRAM config...
         Starting Create Volatile Files and Directories...
         Starting Raise network interfaces...
[  OK  ] Started Set console font and keymap.
[  OK  ] Started Create Volatile Files and Directories.
         Starting Update UTMP about System Boot/Shutdown...
[  OK  ] Started Entropy daemon using the HAVEGE algorithm.
[  OK  ] Reached target System Time Synchronized.
[  OK  ] Started Update UTMP about System Boot/Shutdown.
[  OK  ] Started udev Coldplug all Devices.
[  OK  ] Started Armbian ZRAM config.
         Starting Armbian memory supported logging...
[  OK  ] Found device /dev/ttyS0.
[  OK  ] Started Raise network interfaces.
[  OK  ] Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.
[  OK  ] Started Armbian memory supported logging.
[  OK  ] Reached target Sound Card.
[  OK  ] Reached target System Initialization.
[  OK  ] Listening on D-Bus System Message Bus Socket.
[  OK  ] Reached target Sockets.
         Starting Armbian hardware monitoring...
         Starting Armbian hardware optimization...
[  OK  ] Started Daily apt download activities.
[  OK  ] Started Daily apt upgrade and clean activities.
[  OK  ] Started Daily Cleanup of Temporary Directories.
[  OK  ] Reached target Timers.
[  OK  ] Started Armbian hardware monitoring.
[  OK  ] Started Armbian hardware optimization.
[  OK  ] Reached target Basic System.
         Starting Login Service...
         Starting LSB: Start/stop sysstat's sadc...
         Starting System Logging Service...
         Starting LSB: Load kernel modules needed to enable cpufreq scaling...
[  OK  ] Started D-Bus System Message Bus.
         Starting Network Manager...
         Starting Save/Restore Sound Card State...
[  OK  ] Started Regular background program processing daemon.
[  OK  ] Started System Logging Service.
[  OK  ] Started Save/Restore Sound Card State.
[  OK  ] Started LSB: Start/stop sysstat's sadc.
[  OK  ] Started Login Service.
[  OK  ] Started Network Manager.
         Starting Network Manager Script Dispatcher Service...
[  OK  ] Reached target Network.
[  OK  ] Started Unattended Upgrades Shutdown.
         Starting Permit User Sessions...
         Starting OpenBSD Secure Shell server...
         Starting Network Manager Wait Online...
[  OK  ] Started Permit User Sessions.
[  OK  ] Started LSB: Load kernel modules needed to enable cpufreq scaling.
         Starting LSB: set CPUFreq kernel parameters...
[  OK  ] Started Network Manager Script Dispatcher Service.
         Starting Hostname Service...
[  OK  ] Started LSB: set CPUFreq kernel parameters.
         Starting LSB: Set sysfs variables from /etc/sysfs.conf...
[  OK  ] Started OpenBSD Secure Shell server.
[  OK  ] Started Hostname Service.
[  OK  ] Started LSB: Set sysfs variables from /etc/sysfs.conf.
         Starting Authorization Manager...
[  OK  ] Started Authorization Manager.
[  OK  ] Started Network Manager Wait Online.
[  OK  ] Reached target Network is Online.
         Starting LSB: Advanced IEEE 802.11 management daemon...
         Starting /etc/rc.local Compatibility...
         Starting Daily apt download activities...
         Starting LSB: Start NTP daemon...
[  OK  ] Started LSB: Advanced IEEE 802.11 management daemon.
[  OK  ] Started /etc/rc.local Compatibility.
[  OK  ] Started Serial Getty on ttyS0.
[  OK  ] Started Getty on tty1.
[  OK  ] Reached target Login Prompts.
[  OK  ] Started Daily apt download activities.
         Starting Daily apt upgrade and clean activities...
[  OK  ] Started LSB: Start NTP daemon.
[  OK  ] Reached target Multi-User System.
[  OK  ] Reached target Graphical Interface.
         Starting Update UTMP about System Runlevel Changes...
[  OK  ] Started Update UTMP about System Runlevel Changes.
[  OK  ] Started Daily apt upgrade and clean activities.

Debian GNU/Linux 9 bananapi ttyS0

bananapi login: 

 

If you need something and can help, then if I can, can I help?

I suppose investigating of this issue needs better-than-mine understanding of low level details (u-boot, at least).

I see some suspicious logs in u-boot:
 

EHCI failed to shut down host controller.
EHCI failed to shut down host controller. 

Also, the size of device tree decreased from 71000 (5.65) to 70000 (5.75)

That's probably ok, but I would try to check the integrity of device tree.

For example, the presence of the following node in the device tree (on running kernel):

# ls /proc/device-tree/soc@1c00000/phy@1c13400/
'#phy-cells'   clocks       name      pinctrl-0       reg         reset-names   status             usb0_vbus-supply         usb1_vbus-supply
 clock-names   compatible   phandle   pinctrl-names   reg-names   resets        usb0_id_det-gpio   usb0_vbus_power-supply   usb2_vbus-supply

Please check this node if you can boot from SD card with 5.75.

root@bananapi:~# ls /proc/device-tree/soc@1c00000/phy@1c13400/
 clock-names  '#phy-cells'     reset-names              usb0_vbus-supply
 clocks        pinctrl-0       resets                   usb1_vbus-supply
 compatible    pinctrl-names   status                   usb2_vbus-supply
 name          reg             usb0_id_det-gpio
 phandle       reg-names       usb0_vbus_power-supply

 

Well, and what is the output of 2 more commands?
 

# lsusb

Bus 004 Device 001: ID 1d6b:0001 Linux Foundation 1.1 root hub
Bus 002 Device 001: ID 1d6b:0002 Linux Foundation 2.0 root hub
Bus 003 Device 001: ID 1d6b:0001 Linux Foundation 1.1 root hub
Bus 001 Device 001: ID 1d6b:0002 Linux Foundation 2.0 root hub
Bus 005 Device 001: ID 1d6b:0002 Linux Foundation 2.0 root hub

# ls -l /sys/bus/usb/devices/
total 0
lrwxrwxrwx 1 root root 0 Apr 30 21:51 1-0:1.0 -> ../../../devices/platform/soc@1c00000/1c14000.usb/usb1/1-0:1.0
lrwxrwxrwx 1 root root 0 Apr 30 21:51 2-0:1.0 -> ../../../devices/platform/soc@1c00000/1c1c000.usb/usb2/2-0:1.0
lrwxrwxrwx 1 root root 0 Apr 30 21:51 3-0:1.0 -> ../../../devices/platform/soc@1c00000/1c14400.usb/usb3/3-0:1.0
lrwxrwxrwx 1 root root 0 Apr 30 21:51 4-0:1.0 -> ../../../devices/platform/soc@1c00000/1c1c400.usb/usb4/4-0:1.0
lrwxrwxrwx 1 root root 0 Apr 30 21:51 5-0:1.0 -> ../../../devices/platform/soc@1c00000/1c13000.usb/musb-hdrc.1.auto/usb5/5-0:1.0
lrwxrwxrwx 1 root root 0 Apr 30 21:51 usb1 -> ../../../devices/platform/soc@1c00000/1c14000.usb/usb1
lrwxrwxrwx 1 root root 0 Apr 30 21:51 usb2 -> ../../../devices/platform/soc@1c00000/1c1c000.usb/usb2
lrwxrwxrwx 1 root root 0 Apr 30 21:51 usb3 -> ../../../devices/platform/soc@1c00000/1c14400.usb/usb3
lrwxrwxrwx 1 root root 0 Apr 30 21:51 usb4 -> ../../../devices/platform/soc@1c00000/1c1c400.usb/usb4
lrwxrwxrwx 1 root root 0 Apr 30 21:51 usb5 -> ../../../devices/platform/soc@1c00000/1c13000.usb/musb-hdrc.1.auto/usb5

Does the previous comment (ls phy@1c13400) match the log of "5.75 u-boot" log ? Can you see any USB device with this boot with lsusb?

 

 

root@bananapi:~# lsusb
Bus 004 Device 001: ID 1d6b:0001 Linux Foundation 1.1 root hub
Bus 002 Device 002: ID 090c:1000 Silicon Motion, Inc. - Taiwan (formerly Feiya Technology Corp.) Flash Drive
Bus 002 Device 001: ID 1d6b:0002 Linux Foundation 2.0 root hub
Bus 003 Device 001: ID 1d6b:0001 Linux Foundation 1.1 root hub
Bus 001 Device 001: ID 1d6b:0002 Linux Foundation 2.0 root hub
Bus 005 Device 001: ID 1d6b:0002 Linux Foundation 2.0 root hub
root@bananapi:~# ls -l /sys/bus/usb/devices/
total 0
lrwxrwxrwx 1 root root 0 Apr 30 12:45 1-0:1.0 -> ../../../devices/platform/soc@1c00000/1c14000.usb/usb1/1-0:1.0
lrwxrwxrwx 1 root root 0 Apr 30 12:45 2-0:1.0 -> ../../../devices/platform/soc@1c00000/1c1c000.usb/usb2/2-0:1.0
lrwxrwxrwx 1 root root 0 Apr 30 12:45 2-1 -> ../../../devices/platform/soc@1c00000/1c1c000.usb/usb2/2-1
lrwxrwxrwx 1 root root 0 Apr 30 12:45 2-1:1.0 -> ../../../devices/platform/soc@1c00000/1c1c000.usb/usb2/2-1/2-1:1.0
lrwxrwxrwx 1 root root 0 Apr 30 12:45 3-0:1.0 -> ../../../devices/platform/soc@1c00000/1c14400.usb/usb3/3-0:1.0
lrwxrwxrwx 1 root root 0 Apr 30 12:45 4-0:1.0 -> ../../../devices/platform/soc@1c00000/1c1c400.usb/usb4/4-0:1.0
lrwxrwxrwx 1 root root 0 Apr 30 12:45 5-0:1.0 -> ../../../devices/platform/soc@1c00000/1c13000.usb/musb-hdrc.1.auto/usb5/5-0:1.0
lrwxrwxrwx 1 root root 0 Apr 30 12:45 usb1 -> ../../../devices/platform/soc@1c00000/1c14000.usb/usb1
lrwxrwxrwx 1 root root 0 Apr 30 12:45 usb2 -> ../../../devices/platform/soc@1c00000/1c1c000.usb/usb2
lrwxrwxrwx 1 root root 0 Apr 30 12:45 usb3 -> ../../../devices/platform/soc@1c00000/1c14400.usb/usb3
lrwxrwxrwx 1 root root 0 Apr 30 12:45 usb4 -> ../../../devices/platform/soc@1c00000/1c1c400.usb/usb4
lrwxrwxrwx 1 root root 0 Apr 30 12:45 usb5 -> ../../../devices/platform/soc@1c00000/1c13000.usb/musb-hdrc.1.auto/usb5

 

Ok, this kernel obviously can detect the USB drive.

I've noticed USB could be initialized a little bit later than systemd tries to mount a root partition.

On my board dmesg shows first ext4 mount at ~6s, whereas USB hub is inited at ~9.2s.

Perhaps USB init is not yet completed in your case too.

I doubt I can help with the exact root cause for this (whether this is u-boot issue or another reason).

 

I would propose to delay the kernel boot for few seconds, maybe u-boot or armbian can be configured somehow to delay it.

 

You can try also to reboot the board with reset key after the failed pendrive boot - just to check whether USB init is the reason (this might not help, but who knows...)

 

I tried the option bootdelay = 20 by default is bootdelay = 1

I also tried the reset button.

Unfortunately, it does not help.

Well, that's all I can do. I suppose some debugging is needed for u-boot and/or kernel.

Hm, one more crazy idea:

what if install the 5.65 (from SD and transfer to nand) first and upgrade to 5.75 on a running board? The idea is to upgrade kernel and system but not the u-boot. Not sure this is possible however...

It is possible. Install nand sata install 5.65 to pendrive. Reboot all ok.

apt-mark hold linux-u-boot-bananapi-next

apt update

apt upgrade

Reboot system. The error appears.

 

Test hold kernel.Install nand sata install 5.65 to pendrive. Reboot all ok.

apt-mark hold linux-image-next-sunxi

apt update

apt ugrade

Reboot system . All working.

 

And what is the final configuration: versions of u-boot, kernel and dtb (was linux-dtb-next-sunxi updated in any of the test above)?

Everything has been updated except for the stopped package which I gave in apt-mark.

On 5/1/2019 at 9:40 PM, alien said:

I tried the option bootdelay = 20 by default is bootdelay = 1

I also tried the reset button.

Unfortunately, it does not help.

I've made my install on SATA hard drive few weeks ago without issues, but I can see something weird over here.

 

I suppose you should use rootdelay, not bootdelay.

 

Quote

The "bootdelay" variable in U-Boot indicates how long U-Boot will wait before it begins booting the system into Linux. Usually, you see it set to 'bootdelay=3' or 'bootdelay=5'; which will give the user 3 (or 5) seconds to type any key and stop the system from booting.

Quote

rootdelay= n

Wait n seconds before trying to mount the root filesystem. This can be useful if the root filesystem is on a USB or Firewire device, as those disk devices take a bit longer to be discovered by the kernel.

 

rootdelay not help.