[LEDE-DEV] WRT1900ACS - Kernel 4.4.12 boot failure

Dheeran Senthilvel dheeranmech at gmail.com
Tue Jun 7 07:00:35 PDT 2016


=======> Step 1: Reboot <=======

BusyBox v1.24.2 () built-in shell (ash)

     _________
    /        /\      _    ___ ___  ___
   /  LE    /  \    | |  | __|   \| __|
  /    DE  /    \   | |__| _|| |) | _|
 /________/  LE  \  |____|___|___/|___|                      lede-project.org
 \        \   DE /
  \    LE  \    /  -----------------------------------------------------------
   \  DE    \  /    Reboot (HEAD, r504)
    \________\/    -----------------------------------------------------------

root at WRT1900ACS:/# reboot





=======> Step 2: resetenv & reset <=======
 __   __                      _ _
|  \/  | __ _ _ ____   _____| | |
| |\/| |/ _` | '__\ \ / / _ \ | |
| |  | | (_| | |   \ V /  __/ | |
|_|  |_|\__,_|_|    \_/ \___|_|_|
         _   _     ____              _
        | | | |   | __ )  ___   ___ | |_
        | | | |___|  _ \ / _ \ / _ \| __|
        | |_| |___| |_) | (_) | (_) | |_
         \___/    |____/ \___/ \___/ \__|
 ** LOADER **


U-Boot 2013.01 (Mar 27 2015 - 16:50:46) Marvell version: 2014_T3.0p6

Boot version : v1.0.13

Board: RD-NAS-88F6820-DDR3
SoC:   MV88F6820 Rev A0
       running 2 CPUs
CPU:   ARM Cortex A9 MPCore (Rev 1) LE
       CPU 0
       CPU    @ 1600 [MHz]
       L2     @ 800 [MHz]
       TClock @ 200 [MHz]
       DDR    @ 800 [MHz]
       DDR 32 Bit Width, FastPath Memory Access, DLB Enabled, ECC Disabled
DRAM:  512 MiB

Map:   Code:                    0x1fea9000:0x1ff7632c
       BSS:                     0x1ffef6b4
       Stack:                   0x1f9a8f20
       Heap:                    0x1f9a9000:0x1fea9000
raise: Signal # 8 caught
U-ENV offset == 0x200000
raise: Signal # 8 caught
U-ENV offset == 0x200000
       U-Boot Environment:      0x00200000:0x00220000 (NAND)

NAND:  128 MiB
MMC:   mv_sdh: 0
DEVINFO offset == 0x900000
U-ENV offset == 0x200000
U-ENV offset == 0x200000
S-ENV offset == 0x240000


#### auto_recovery ####
[u_env] get auto_recovery == yes
[u_env] get auto_recovery == yes
[u_env] get boot_part == <NULL>
set boot_part=1 as default value at u_env
[u_env] get boot_part_ready == <NULL>
set boot_part_ready=3 as default value at u_env
auto_recovery enabled:1, boot_part:1, boot_part_ready:3

S-ENV offset == 0x240000
[boot_count_read] block:0x240000, size:128KB, records:64
[boot_count_read_record] boot_count:0, next_record:18

[boot_count_write] erase:0, auto_recovery->block_offset:0x240000 offset=0x249000

Updating boot_count ...
[boot_count_write] offset:0x249000 , length:2048
done

PCI-e 0 (IF 0 - bus 0) Root Complex Interface, Detected Link X1, GEN 2.0
PCI-e 1 (IF 1 - bus 1) Root Complex Interface, Detected Link X1, GEN 1.1
USB2.0 0: Host Mode
USB3.0 1: Host Mode
USB3.0 0: Host Mode
Board configuration detected:
mvEthE6171SwitchBasicInit init
Net:
|  port  | Interface | PHY address  |
|--------|-----------|--------------|
| egiga0 |   RGMII   |     0x01     |
| egiga1 |   SGMII   |     0x00     |
egiga0 [PRIME], egiga1
auto_recovery_check changes bootcmd: run nandboot
Hit any key to stop autoboot:  0
## Error: "nandboot" not defined
Marvell>> resetenv
Erasing 0x200000 - 0x240000:[Done]
Warning: Default Environment Variables will take effect Only after RESET
Marvell>>reset





=======> Step 3: Intrupt boot into failsafe <=======

Press the [f] key and hit [enter] to enter failsafe mode
Press the [1], [2], [3] or [4] key and hit [enter] to select the debug level
f
- failsafe -
[    4.986830] random: dropbearkey urandom read with 121 bits of entropy available
Generating key, this may take a while...
Public key portion is:
ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAAAgQDrNbAtbpvkCbOb93M8CIhkOklAmgzdXTLykIIDBiy6aGZB2LCVU9DCM09CyKJUOYWF2Pvk5i5THb3+TwM3ILr+iWraogyZzrTsAus1byAtJS0jEH0bHEzbv/hU
fd0XSVpypP8q+1R2UpGtzZm7vIy2V6dD5LCaABnTD6mNRKSXRQ++ root@(none)
Fingerprint: md5 ec:58:8a:60:48:32:94:26:30:0c:6d:e2:dd:10:5e:6e


BusyBox v1.24.2 () built-in shell (ash)

ash: can't access tty; job control turned off
     _________
    /        /\      _    ___ ___  ___
   /  LE    /  \    | |  | __|   \| __|
  /    DE  /    \   | |__| _|| |) | _|
 /________/  LE  \  |____|___|___/|___|                      lede-project.org
 \        \   DE /
  \    LE  \    /  -----------------------------------------------------------
   \  DE    \  /    Reboot (HEAD, r504)
    \________\/    -----------------------------------------------------------

================= FAILSAFE MODE active ================
special commands:
* firstboot          reset settings to factory defaults
* mount_root     mount root-partition with config files

after mount_root:
* passwd                         change root's password
* /etc/config               directory with config files

for more help see:
http://wiki.openwrt.org/doc/howto/generic.failsafe
=======================================================

root@(none):/# strings /dev/mtd1
[   44.008437] random: nonblocking pool is initialized
root@(none):/# reboot
reboot
root@(none):/# [  113.204287] reboot: Restarting system





=======> Step 4: Reboot <=======

BootROM - 1.73
Booting from NAND flash

General initialization - Version: 1.0.0
Detected Device ID 6820
High speed PHY - Version: 2.0

Init RD NAS topology Serdes Lane 3 is USB3
Serdes Lane 4 is SGMII
board SerDes lanes topology details:
 | Lane #  | Speed |  Type       |
 --------------------------------
 |   0    |  06   |  SATA0      |
 |   1    |  05   |  PCIe0      |
 |   2    |  06   |  SATA1      |
 |   3    |  05   |  USB3 HOST1 |
 |   4    |  05   |  PCIe1      |
 |   5    |  00   |  SGMII2     |
 --------------------------------
:** Link is Gen1, check the EP capability
PCIe, Idx 0: Link upgraded to Gen2 based on client cpabilities
:** Link is Gen1, check the EP capability
PCIe, Idx 1: remains Gen1
High speed PHY - Ended Successfully
DDR3 Training Sequence - Ver TIP-1.26.0
mvSysEnvGetTopologyUpdateInfo: TWSI Read failed
DDR3 Training Sequence - Switching XBAR Window to FastPath Window
DDR3 Training Sequence - Ended Successfully
Not detected suspend to RAM indication
BootROM: Image checksum verification PASSED

 __   __                      _ _
|  \/  | __ _ _ ____   _____| | |
| |\/| |/ _` | '__\ \ / / _ \ | |
| |  | | (_| | |   \ V /  __/ | |
|_|  |_|\__,_|_|    \_/ \___|_|_|
         _   _     ____              _
        | | | |   | __ )  ___   ___ | |_
        | | | |___|  _ \ / _ \ / _ \| __|
        | |_| |___| |_) | (_) | (_) | |_
         \___/    |____/ \___/ \___/ \__|
 ** LOADER **


U-Boot 2013.01 (Mar 27 2015 - 16:50:46) Marvell version: 2014_T3.0p6

Boot version : v1.0.13

Board: RD-NAS-88F6820-DDR3
SoC:   MV88F6820 Rev A0
       running 2 CPUs
CPU:   ARM Cortex A9 MPCore (Rev 1) LE
       CPU 0
       CPU    @ 1600 [MHz]
       L2     @ 800 [MHz]
       TClock @ 200 [MHz]
       DDR    @ 800 [MHz]
       DDR 32 Bit Width, FastPath Memory Access, DLB Enabled, ECC Disabled
DRAM:  512 MiB

Map:   Code:                    0x1fea9000:0x1ff7632c
       BSS:                     0x1ffef6b4
       Stack:                   0x1f9a8f20
       Heap:                    0x1f9a9000:0x1fea9000
raise: Signal # 8 caught
U-ENV offset == 0x200000
raise: Signal # 8 caught
U-ENV offset == 0x200000
       U-Boot Environment:      0x00200000:0x00220000 (NAND)

NAND:  128 MiB
MMC:   mv_sdh: 0
DEVINFO offset == 0x900000
U-ENV offset == 0x200000
U-ENV offset == 0x200000
*** Warning - bad CRC, using default environment

S-ENV offset == 0x240000


#### auto_recovery ####
[u_env] get auto_recovery == yes
[u_env] get auto_recovery == yes
[u_env] get boot_part == 1
[u_env] get boot_part_ready == 3
auto_recovery enabled:1, boot_part:1, boot_part_ready:3

S-ENV offset == 0x240000
[boot_count_read] block:0x240000, size:128KB, records:64
[boot_count_read_record] boot_count:2, next_record:20

[boot_count_write] erase:0, auto_recovery->block_offset:0x240000 offset=0x24A000

Updating boot_count ...
[boot_count_write] offset:0x24A000 , length:2048
done

PCI-e 0 (IF 0 - bus 0) Root Complex Interface, Detected Link X1, GEN 2.0
PCI-e 1 (IF 1 - bus 1) Root Complex Interface, Detected Link X1, GEN 1.1
USB2.0 0: Host Mode
USB3.0 1: Host Mode
USB3.0 0: Host Mode
Board configuration detected:
mvEthE6171SwitchBasicInit init
Net:
|  port  | Interface | PHY address  |
|--------|-----------|--------------|
| egiga0 |   RGMII   |     0x01     |
| egiga1 |   SGMII   |     0x00     |
egiga0 [PRIME], egiga1
auto_recovery_check changes bootcmd: run nandboot
Hit any key to stop autoboot:  0
Marvell>> run nandboot

NAND read: device 0 offset 0xa00000, size 0x600000
 6291456 bytes read: OK
## Booting kernel from Legacy Image at 02000000 ...
   Image Name:   ARM LEDE Linux-4.4.12
   Created:      2016-06-05  13:42:27 UTC
   Image Type:   ARM Linux Kernel Image (uncompressed)
   Data Size:    2084373 Bytes = 2 MiB
   Load Address: 00008000
   Entry Point:  00008000
   Verifying Checksum ... OK
   Loading Kernel Image ... OK
OK

Starting kernel ...

[    0.000000] Booting Linux on physical CPU 0x0
[    0.000000] Linux version 4.4.12 (dhees at XPS) (gcc version 5.3.0 (LEDE GCC 5.3.0 r504) ) #1 SMP Sun Jun 5 15:29:33 UTC 2016
[    0.000000] CPU: ARMv7 Processor [414fc091] revision 1 (ARMv7), cr=10c5387d
[    0.000000] CPU: PIPT / VIPT nonaliasing data cache, VIPT aliasing instruction cache
[    0.000000] Machine model: Linksys WRT1900ACS
[    0.000000] Memory policy: Data cache writealloc
[    0.000000] PERCPU: Embedded 12 pages/cpu @dfbcf000 s17984 r8192 d22976 u49152
[    0.000000] Built 1 zonelists in Zone order, mobility grouping on.  Total pages: 130048
[    0.000000] Kernel command line: console=ttyS0,115200 root=/dev/mtdblock5 ro rootdelay=1 rootfstype=jffs2 earlyprintk mtdparts=armada-nand:2048K(uboot)ro,256
K(u_env),256K(s_env),1m at 9m(devinfo),40m at 10m(kernel),34m at 16m(rootfs),40m at 50m(alt_kernel),34m at 56m(alt_rootfs),80m at 10m(ubifs),- at 90m(syscfg)
[    0.000000] PID hash table entries: 2048 (order: 1, 8192 bytes)
[    0.000000] Dentry cache hash table entries: 65536 (order: 6, 262144 bytes)
[    0.000000] Inode-cache hash table entries: 32768 (order: 5, 131072 bytes)
[    0.000000] Memory: 513220K/524288K available (4079K kernel code, 171K rwdata, 1528K rodata, 260K init, 295K bss, 11068K reserved, 0K cma-reserved, 0K highme
m)
[    0.000000] Virtual kernel memory layout:
[    0.000000]     vector  : 0xffff0000 - 0xffff1000   (   4 kB)
[    0.000000]     fixmap  : 0xffc00000 - 0xfff00000   (3072 kB)
[    0.000000]     vmalloc : 0xe0800000 - 0xff800000   ( 496 MB)
[    0.000000]     lowmem  : 0xc0000000 - 0xe0000000   ( 512 MB)
[    0.000000]     pkmap   : 0xbfe00000 - 0xc0000000   (   2 MB)
[    0.000000]     modules : 0xbf000000 - 0xbfe00000   (  14 MB)
[    0.000000]       .text : 0xc0008000 - 0xc05821d8   (5609 kB)
[    0.000000]       .init : 0xc0583000 - 0xc05c4000   ( 260 kB)
[    0.000000]       .data : 0xc05c4000 - 0xc05eedf4   ( 172 kB)
[    0.000000]        .bss : 0xc05eedf4 - 0xc0638a6c   ( 296 kB)
[    0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1
[    0.000000] Hierarchical RCU implementation.
[    0.000000]  RCU restricting CPUs from NR_CPUS=4 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 16
[    0.000000] mvebu_mbus: [Firmware Warn]: deprecated mbus-mvebu Device Tree, suspend/resume will not work
[    0.000000] L2C-310 enabling early BRESP for Cortex-A9
[    0.000000] L2C-310 full line of zeros enabled for Cortex-A9
[    0.000000] L2C-310 D prefetch enabled, offset 1 lines
[    0.000000] L2C-310 dynamic clock gating enabled, standby mode enabled
[    0.000000] L2C-310 Coherent cache controller enabled, 16 ways, 1024 kB
[    0.000000] L2C-310 Coherent: CACHE_ID 0x410054c9, AUX_CTRL 0x56070001
[    0.000000] Switching to timer-based delay loop, resolution 40ns
[    0.000003] sched_clock: 32 bits at 25MHz, resolution 40ns, wraps every 85899345900ns
[    0.000009] clocksource: armada_370_xp_clocksource: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 76450417870 ns
[    0.000112] Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=250000)
[    0.000119] pid_max: default: 32768 minimum: 301
[    0.000154] Mount-cache hash table entries: 1024 (order: 0, 4096 bytes)
[    0.000159] Mountpoint-cache hash table entries: 1024 (order: 0, 4096 bytes)
[    0.000337] CPU: Testing write buffer coherency: ok
[    0.000434] CPU0: thread -1, cpu 0, socket 0, mpidr 80000000
[    0.000457] Setting up static identity map for 0x8280 - 0x82d8
[    0.000534] mvebu-soc-id: MVEBU SoC ID=0x6820, Rev=0x4
[    0.000609] mvebu-pmsu: Initializing Power Management Service Unit
[    0.000993] Booting CPU 1
[    0.001152] CPU1: thread -1, cpu 1, socket 0, mpidr 80000001
[    0.001179] Brought up 2 CPUs
[    0.001185] SMP: Total of 2 processors activated (100.00 BogoMIPS).
[    0.001188] CPU: All CPU(s) started in SVC mode.
[    0.002855] VFP support v0.3: implementor 41 architecture 3 part 30 variant 9 rev 4
[    0.002929] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns
[    0.002968] pinctrl core: initialized pinctrl subsystem
[    0.003274] NET: Registered protocol family 16
[    0.003766] DMA: preallocated 256 KiB pool for atomic coherent allocations
[    0.029940] cpuidle: using governor ladder
[    0.032347] mvebu-pmsu: CPU hotplug support is currently broken on Armada 38x: disabling
[    0.032355] mvebu-pmsu: CPU idle is currently broken on Armada 38x: disabling
[    0.060502] reg-fixed-voltage xhci0-vbus: could not find pctldev for node /soc/internal-regs/pinctrl at 18000/xhci0-vbus-pins, deferring probe
[    0.060646] SCSI subsystem initialized
[    0.060888] usbcore: registered new interface driver usbfs
[    0.060912] usbcore: registered new interface driver hub
[    0.060934] usbcore: registered new device driver usb
[    0.061438] clocksource: Switched to clocksource armada_370_xp_clocksource
[    0.061839] NET: Registered protocol family 2
[    0.062050] TCP established hash table entries: 4096 (order: 2, 16384 bytes)
[    0.062075] TCP bind hash table entries: 4096 (order: 3, 32768 bytes)
[    0.062108] TCP: Hash tables configured (established 4096 bind 4096)
[    0.062133] UDP hash table entries: 256 (order: 1, 8192 bytes)
[    0.062145] UDP-Lite hash table entries: 256 (order: 1, 8192 bytes)
[    0.062208] NET: Registered protocol family 1
[    0.062586] futex hash table entries: 512 (order: 3, 32768 bytes)
[    0.065248] squashfs: version 4.0 (2009/01/31) Phillip Lougher
[    0.065258] jffs2: version 2.2 (NAND) (SUMMARY) (LZMA) (RTIME) (CMODE_PRIORITY) (c) 2001-2006 Red Hat, Inc.
[    0.066108] io scheduler noop registered
[    0.066114] io scheduler deadline registered (default)
[    0.066494] armada-38x-pinctrl f1018000.pinctrl: registered pinctrl driver
[    0.066791] irq: Cannot allocate irq_descs @ IRQ44, assuming pre-allocated
[    0.066927] irq: Cannot allocate irq_descs @ IRQ76, assuming pre-allocated
[    0.067212] mvebu-pcie soc:pcie-controller: PCI host bridge to bus 0000:00
[    0.067219] pci_bus 0000:00: root bus resource [io  0x1000-0xfffff]
[    0.067224] pci_bus 0000:00: root bus resource [mem 0xe0000000-0xe7ffffff]
[    0.067229] pci_bus 0000:00: root bus resource [bus 00-ff]
[    0.067408] PCI: bus0: Fast back to back transfers disabled
[    0.067414] pci 0000:00:01.0: bridge configuration invalid ([bus 00-00]), reconfiguring
[    0.067420] pci 0000:00:02.0: bridge configuration invalid ([bus 00-00]), reconfiguring
[    0.067631] PCI: bus1: Fast back to back transfers disabled
[    0.067879] PCI: bus2: Fast back to back transfers disabled
[    0.067931] pci 0000:00:01.0: BAR 8: assigned [mem 0xe0000000-0xe01fffff]
[    0.067937] pci 0000:00:02.0: BAR 8: assigned [mem 0xe0200000-0xe03fffff]
[    0.067943] pci 0000:01:00.0: BAR 0: assigned [mem 0xe0000000-0xe00fffff 64bit pref]
[    0.067953] pci 0000:01:00.0: BAR 2: assigned [mem 0xe0100000-0xe01fffff 64bit pref]
[    0.067962] pci 0000:00:01.0: PCI bridge to [bus 01]
[    0.067967] pci 0000:00:01.0:   bridge window [mem 0xe0000000-0xe01fffff]
[    0.067974] pci 0000:02:00.0: BAR 0: assigned [mem 0xe0200000-0xe02fffff 64bit pref]
[    0.067985] pci 0000:02:00.0: BAR 2: assigned [mem 0xe0300000-0xe03fffff 64bit pref]
[    0.067994] pci 0000:00:02.0: PCI bridge to [bus 02]
[    0.067999] pci 0000:00:02.0:   bridge window [mem 0xe0200000-0xe03fffff]
[    0.068088] mv_xor f1060800.xor: Marvell shared XOR driver
[    0.101877] mv_xor f1060800.xor: Marvell XOR (Descriptor Mode): ( xor cpy intr )
[    0.101956] mv_xor f1060900.xor: Marvell shared XOR driver
[    0.141858] mv_xor f1060900.xor: Marvell XOR (Descriptor Mode): ( xor cpy intr )
[    0.141991] Serial: 8250/16550 driver, 2 ports, IRQ sharing disabled
[    0.142243] console [ttyS0] disabled
[    0.162310] f1012000.serial: ttyS0 at MMIO 0xf1012000 (irq = 21, base_baud = 12500000) is a 16550A
[    0.871491] console [ttyS0] enabled
[    0.875319] ahci-mvebu f10a8000.sata: AHCI 0001.0000 32 slots 2 ports 6 Gbps 0x3 impl platform mode
[    0.884418] ahci-mvebu f10a8000.sata: flags: 64bit ncq sntf led only pmp fbs pio slum part sxs
[    0.893815] scsi host0: ahci-mvebu
[    0.897377] scsi host1: ahci-mvebu
[    0.900876] ata1: SATA max UDMA/133 mmio [mem 0xf10a8000-0xf10a9fff] port 0x100 irq 41
[    0.908836] ata2: SATA max UDMA/133 mmio [mem 0xf10a8000-0xf10a9fff] port 0x180 irq 41
[    0.917171] pxa3xx-nand f10d0000.flash: This platform can't do DMA on this device
[    0.924987] nand: device found, Manufacturer ID: 0x01, Chip ID: 0xf1
[    0.931366] nand: AMD/Spansion S34ML01G2
[    0.935313] nand: 128 MiB, SLC, erase size: 128 KiB, page size: 2048, OOB size: 64
[    0.942920] pxa3xx-nand f10d0000.flash: ECC strength 16, ECC step size 2048
[    0.950098] Bad block table found at page 65472, version 0x01
[    0.956210] Bad block table found at page 65408, version 0x01
[    0.962247] 10 ofpart partitions found on MTD device pxa3xx_nand-0
[    0.968452] Creating 10 MTD partitions on "pxa3xx_nand-0":
[    0.973967] 0x000000000000-0x000000200000 : "u-boot"
[    0.979249] 0x000000200000-0x000000240000 : "u_env"
[    0.984428] 0x000000240000-0x000000280000 : "s_env"
[    0.989579] 0x000000900000-0x000000a00000 : "devinfo"
[    0.994916] 0x000000a00000-0x000003200000 : "kernel1"
[    1.000270] 0x000001000000-0x000003200000 : "ubi"
[    1.005295] 0x000003200000-0x000005a00000 : "kernel2"
[    1.010635] 0x000003800000-0x000005a00000 : "rootfs2"
[    1.015979] 0x000005a00000-0x000008000000 : "syscfg"
[    1.021245] 0x000000280000-0x000000900000 : "unused_area"
[    1.027367] libphy: Fixed MDIO Bus: probed
[    1.031591] libphy: orion_mdio_bus: probed
[    1.035842] mvneta_bm f10c8000.bm: Buffer Manager for network controller enabled
[    1.044750] mvneta f1034000.ethernet eth0: Using random mac address 2e:a7:f8:7e:27:4b
[    1.053775] mvneta f1070000.ethernet eth1: Using hardware mac address 00:50:43:45:e1:e5
[    1.061878] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver
[    1.068434] ehci-pci: EHCI PCI platform driver
[    1.072940] ehci-platform: EHCI generic platform driver
[    1.078273] ehci-orion: EHCI orion driver
[    1.082370] orion-ehci f1058000.usb: EHCI Host Controller
[    1.087802] orion-ehci f1058000.usb: new USB bus registered, assigned bus number 1
[    1.095465] orion-ehci f1058000.usb: irq 36, io mem 0xf1058000
[    1.111441] orion-ehci f1058000.usb: USB 2.0 started, EHCI 1.00
[    1.117615] hub 1-0:1.0: USB hub found
[    1.121391] hub 1-0:1.0: 1 port detected
[    1.125693] usbcore: registered new interface driver usb-storage
[    1.131874] armada38x-rtc f10a3800.rtc: rtc core: registered f10a3800.rtc as rtc0
[    1.139518] i2c /dev entries driver
[    1.154508] orion_wdt: Initial timeout 171 sec
[    1.162326] marvell-cesa f1090000.crypto: CESA device successfully registered
[    1.169978] NET: Registered protocol family 10
[    1.174885] NET: Registered protocol family 17
[    1.179363] bridge: automatic filtering via arp/ip/ip6tables has been deprecated. Update your scripts to load br_netfilter if you need this.
[    1.192037] 8021q: 802.1Q VLAN Support v1.8
[    1.196301] Registering SWP/SWPB emulation handler
[    1.201888] xhci-hcd f10f8000.usb3: xHCI Host Controller
[    1.207234] xhci-hcd f10f8000.usb3: new USB bus registered, assigned bus number 2
[    1.214856] xhci-hcd f10f8000.usb3: hcc params 0x0a000990 hci version 0x100 quirks 0x00010010
[    1.223449] xhci-hcd f10f8000.usb3: irq 43, io mem 0xf10f8000
[    1.229507] hub 2-0:1.0: USB hub found
[    1.233305] hub 2-0:1.0: 1 port detected
[    1.237327] xhci-hcd f10f8000.usb3: xHCI Host Controller
[    1.242673] xhci-hcd f10f8000.usb3: new USB bus registered, assigned bus number 3
[    1.250215] usb usb3: We don't know the algorithms for LPM for this host, disabling LPM.
[    1.258541] hub 3-0:1.0: USB hub found
[    1.261450] ata2: SATA link down (SStatus 0 SControl 300)
[    1.261471] ata1: SATA link down (SStatus 0 SControl 300)
[    1.273172] hub 3-0:1.0: 1 port detected
[    1.277444] UBI: auto-attach mtd5
[    1.280598] ubi0: attaching mtd5
[    1.376194] ubi0: scanning is finished
[    1.384288] ubi0: attached mtd5 (name "ubi", size 34 MiB)
[    1.389709] ubi0: PEB size: 131072 bytes (128 KiB), LEB size: 126976 bytes
[    1.396622] ubi0: min./max. I/O unit sizes: 2048/2048, sub-page size 2048
[    1.403443] ubi0: VID header offset: 2048 (aligned 2048), data offset: 4096
[    1.410432] ubi0: good PEBs: 272, bad PEBs: 0, corrupted PEBs: 0
[    1.416466] ubi0: user volume: 2, internal volumes: 1, max. volumes count: 128
[    1.423721] ubi0: max/mean erase counter: 2/0, WL threshold: 4096, image sequence number: 86527830
[    1.432719] ubi0: available PEBs: 0, total reserved PEBs: 272, PEBs reserved for bad PEB handling: 20
[    1.441993] ubi0: background thread "ubi_bgt0d" started, PID 552
[    1.448390] block ubiblock0_0: created from ubi0:0(rootfs)
[    1.453909] ubiblock: device ubiblock0_0 (rootfs) set to be root filesystem
[    1.461084] mvsw61xx 10.mvsw61xx: Found MV88E6176 at f1072004.mdio-mi:10
[    1.467821] mvsw61xx 10.mvsw61xx: Using direct addressing
[    1.473287] armada38x-rtc f10a3800.rtc: setting system clock to 2067-10-27 11:56:41 UTC (3086942201)
[    1.483223] Waiting 1 sec before mounting root device...
[    2.492621] VFS: Mounted root (squashfs filesystem) readonly on device 254:0.
[    2.499919] Freeing unused kernel memory: 260K (c0583000 - c05c4000)
[    2.712564] init: Console is alive
[    2.716037] init: - watchdog -
[    3.001095] init: - preinit -
Press the [f] key and hit [enter] to enter failsafe mode
Press the [1], [2], [3] or [4] key and hit [enter] to select the debug level
[    6.286174] mount_root: loading kmods from internal overlay
[    6.327645] random: nonblocking pool is initialized
[    6.371533] UBIFS (ubi0:1): background thread "ubifs_bgt0_1" started, PID 633
[    6.400043] UBIFS (ubi0:1): recovery needed
[    6.447012] UBIFS (ubi0:1): recovery completed
[    6.451514] UBIFS (ubi0:1): UBIFS: mounted UBI device 0, volume 1, name "rootfs_data"
[    6.459377] UBIFS (ubi0:1): LEB size: 126976 bytes (124 KiB), min./max. I/O unit sizes: 2048 bytes/2048 bytes
[    6.469339] UBIFS (ubi0:1): FS size: 25903104 bytes (24 MiB, 204 LEBs), journal size 1269760 bytes (1 MiB, 10 LEBs)
[    6.479820] UBIFS (ubi0:1): reserved for root: 1223467 bytes (1194 KiB)
[    6.486467] UBIFS (ubi0:1): media format: w4/r0 (latest is w4/r0), UUID 2C714E96-005A-4368-A08F-AA0D97858BED, small LPT model
[    6.498427] block: attempting to load /tmp/ubifs_cfg/upper/etc/config/fstab
[    6.508344] block: extroot: not configured
[    6.541492] UBIFS (ubi0:1): un-mount UBI device 0
[    6.546222] UBIFS (ubi0:1): background thread "ubifs_bgt0_1" stops
[    6.554064] UBIFS (ubi0:1): background thread "ubifs_bgt0_1" started, PID 636
[    6.613752] UBIFS (ubi0:1): UBIFS: mounted UBI device 0, volume 1, name "rootfs_data"
[    6.621626] UBIFS (ubi0:1): LEB size: 126976 bytes (124 KiB), min./max. I/O unit sizes: 2048 bytes/2048 bytes
[    6.631585] UBIFS (ubi0:1): FS size: 25903104 bytes (24 MiB, 204 LEBs), journal size 1269760 bytes (1 MiB, 10 LEBs)
[    6.642065] UBIFS (ubi0:1): reserved for root: 1223467 bytes (1194 KiB)
[    6.648707] UBIFS (ubi0:1): media format: w4/r0 (latest is w4/r0), UUID 2C714E96-005A-4368-A08F-AA0D97858BED, small LPT model
[    6.718997] block: attempting to load /tmp/ubifs_cfg/upper/etc/config/fstab
[    6.728235] block: extroot: not configured
[    6.762147] mount_root: switching to jffs2 overlay
[    6.800087] ubi1: attaching mtd8
[    6.903447] ubi1: scanning is finished
[    6.911558] ubi1: attached mtd8 (name "syscfg", size 38 MiB)
[    6.917242] ubi1: PEB size: 131072 bytes (128 KiB), LEB size: 126976 bytes
[    6.924150] ubi1: min./max. I/O unit sizes: 2048/2048, sub-page size 2048
[    6.930965] ubi1: VID header offset: 2048 (aligned 2048), data offset: 4096
[    6.937957] ubi1: good PEBs: 296, bad PEBs: 8, corrupted PEBs: 0
[    6.943990] ubi1: user volume: 1, internal volumes: 1, max. volumes count: 128
[    6.951240] ubi1: max/mean erase counter: 207/138, WL threshold: 4096, image sequence number: 1220797378
[    6.960761] ubi1: available PEBs: 0, total reserved PEBs: 296, PEBs reserved for bad PEB handling: 12
[    6.970025] ubi1: background thread "ubi_bgt1d" started, PID 646
UBI device number 1, total 296 LEBs (37584896 bytes, 35.8 MiB), [    6.980555] UBIFS (ubi1:0): background thread "ubifs_bgt1_0" started, PID 650
available 0 LEBs (0 bytes), LEB size 126976 bytes (124.0 KiB)
[    7.030143] UBIFS (ubi1:0): UBIFS: mounted UBI device 1, volume 0, name "syscfg"
[    7.037578] UBIFS (ubi1:0): LEB size: 126976 bytes (124 KiB), min./max. I/O unit sizes: 2048 bytes/2048 bytes
[    7.047537] UBIFS (ubi1:0): FS size: 34283520 bytes (32 MiB, 270 LEBs), journal size 1777664 bytes (1 MiB, 14 LEBs)
[    7.058017] UBIFS (ubi1:0): reserved for root: 1619295 bytes (1581 KiB)
[    7.064662] UBIFS (ubi1:0): media format: w4/r0 (latest is w4/r0), UUID 966E9803-824D-461B-8D6E-52CC0641547D, small LPT model
[    7.078942] procd: - early -
[    7.081871] procd: - watchdog -
[    7.676652] procd: - ubus -
[    7.731554] procd: - init -
Please press Enter to activate this console.
[    7.816111] ntfs: driver 2.1.32 [Flags: R/O MODULE].
[    7.824067] ip6_tables: (C) 2000-2006 Netfilter Core Team
[    7.831670] Loading modules backported from Linux version wt-2016-05-12-0-g7a54796
[    7.839277] Backport generated by backports.git backports-20160216-0-ge3c56e4
[    7.854105] <<Marvell 802.11ac Wireless Network Driver version 10.3.0.17-20160520-1>>
[    7.861846] pci 0000:00:01.0: enabling device (0140 -> 0142)
[    8.930453] ieee80211 phy0: firmware version: 0x7020902
[    8.960729] ieee80211 phy0: 2G disabled, 5G enabled
[    8.965656] ieee80211 phy0: 4 TX antennas, 4 RX antennas
[    8.971101] pci 0000:00:02.0: enabling device (0140 -> 0142)
[    9.980455] ieee80211 phy1: firmware version: 0x7020902
[   10.008546] ieee80211 phy1: 2G enabled, 5G disabled
[   10.013473] ieee80211 phy1: 4 TX antennas, 4 RX antennas
[   10.037984] u32 classifier
[   10.040704]     input device check on
[   10.044388]     Actions configured
[   10.048195] Mirror/redirect action on
[   10.058043] ip_tables: (C) 2000-2006 Netfilter Core Team
[   10.067707] nf_conntrack version 0.5.0 (8023 buckets, 32092 max)
[   10.169055] xt_time: kernel timezone is -0000
[   10.175972] PPP generic driver version 2.4.2
[   10.180654] NET: Registered protocol family 24
[   12.205668] device eth1 entered promiscuous mode
[   12.212332] br-lan: port 1(eth1) entered forwarding state
[   12.217785] br-lan: port 1(eth1) entered forwarding state
[   12.235143] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready
[   13.197804] br-lan: port 1(eth1) entered disabled state
[   13.950656] IPv6: ADDRCONF(NETDEV_UP): wlan1: link is not ready
[   14.201449] mvneta f1070000.ethernet eth1: Link is Up - 1Gbps/Full - flow control off
[   14.218536] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready
[   14.224641] device wlan1 entered promiscuous mode
[   14.229398] br-lan: port 2(wlan1) entered forwarding state
[   14.234932] br-lan: port 2(wlan1) entered forwarding state
[   14.240626] br-lan: port 2(wlan1) entered disabled state
[   14.241458] mvneta f1034000.ethernet eth0: Link is Up - 1Gbps/Full - flow control off
[   14.253926] br-lan: port 1(eth1) entered forwarding state
[   14.259358] br-lan: port 1(eth1) entered forwarding state
[   14.264883] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready
[   14.271367] device wlan0 entered promiscuous mode
[   16.251445] br-lan: port 1(eth1) entered forwarding state
[   28.174224] IPv6: ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready
[   28.180666] br-lan: port 3(wlan0) entered forwarding state
[   28.186196] br-lan: port 3(wlan0) entered forwarding state
[   29.586164] IPv6: ADDRCONF(NETDEV_CHANGE): wlan1: link becomes ready
[   29.592644] br-lan: port 2(wlan1) entered forwarding state
[   29.598172] br-lan: port 2(wlan1) entered forwarding state
[   30.181120] br-lan: port 3(wlan0) entered forwarding state
[   31.591085] br-lan: port 2(wlan1) entered forwarding state





=======> Step 5: Check @root <=======

BusyBox v1.24.2 () built-in shell (ash)

     _________
    /        /\      _    ___ ___  ___
   /  LE    /  \    | |  | __|   \| __|
  /    DE  /    \   | |__| _|| |) | _|
 /________/  LE  \  |____|___|___/|___|                      lede-project.org
 \        \   DE /
  \    LE  \    /  -----------------------------------------------------------
   \  DE    \  /    Reboot (HEAD, r504)
    \________\/    -----------------------------------------------------------

root at WRT1900ACS:/# fw_printenv
bootcmd=bootp; setenv bootargs root=/dev/nfs nfsroot=${serverip}:${rootpath} ip=${ipaddr}:${serverip}:${gatewayip}:${netmask}:${hostname}::off; bootm
bootdelay=5
baudrate=115200
auto_recovery=yes
root at WRT1900ACS:/# strings /dev/mtd1
Z'ybootcmd=bootp; setenv bootargs root=/dev/nfs nfsroot=${serverip}:${rootpath} ip=${ipaddr}:${serverip}:${gatewayip}:${netmask}:${hostname}::off; bootm
bootdelay=5
baudrate=115200
auto_recovery=yes



	I think this will help. Let me know if you need to do any other test.

> On 07-Jun-2016, at 7:03 PM, Claudio Leite <leitec at gmail.com> wrote:
> 
> Hello Dheeran,
> 
> * Dheeran Senthilvel (dheeranmech at gmail.com) wrote:
>> Hi,
>> 	Yes even after I run resetenv followed by a reset command, the fw_printenv produces the same output every time. 
>> 
>>> On 07-Jun-2016, at 3:54 PM, Claudio Leite <leitec at gmail.com> wrote:
>>> 
>>> Hi,
>>> 
>>> * Dheeran Senthilvel (dheeranmech at gmail.com) wrote:
>>>> Hi,
>>>>      Thanks for the reply. But I already documented it in my previous
>>>> mails in May 2016.
>>>> This is temporary, once again when reboot is done the same error occurs.
>>> 
>>> I see, sorry for the noise.
>>> 
>>>> 
>>>>      I haven't been able to solve this issue. Also if altnandboot is
>>>> performed and used having lede in nandboot there seems to be no problem.
>>>> But once lede boots the error is imminent upon reboot.
>>> 
>>> I'm not sure I understand this part. Is the same image, with no custom
>>> settings, stored in both partitions?
>> Sorry for that! What I mean to say is if I boot ‘altnandboot’ (recovery official firmware) having lede in the primary_image partition, the problem doesn’t occur. Once I invoke nandboot and allow lede to boot and issue a reboot command, the error occurs. I gave this statement to clarify that the problem lede and not the 'hardware' or 'u-boot’.
> 
> OK, I understand now-you have the factory firmware on the secondary
> partition.
> 
>>> 
>>> What does fw_printenv look like after a "resetenv; reset" and boot into
>>> a recent LEDE? At that point, the linksys-recovery stuff will already
>>> have run.
>> But I really don’t understand the problem. This OpenWrt WiKi page - https://wiki.openwrt.org/doc/techref/bootloader/uboot.config , shows that this kind of error show only when the partition map is incorrect (That is what i understood) and as the following command shows that flash mapping recognised by lede is incorrect
> 
> It's not incorrect; it's just how LEDE/OpenWrt defines it. The partition
> map is baked into the kernel image via the dtb. The relevant partition
> is mtd1, u_env (and s_env, for the 'resetbc' stuff) which is correctly
> defined and matches up with the bootloader's (and factory firmware's)
> expectations.
> 
> From your own output:
> 
> lede:
> 
> [    0.973995] 0x000000000000-0x000000200000 : "u-boot"
> [    0.979273] 0x000000200000-0x000000240000 : "u_env"
> [    0.984435] 0x000000240000-0x000000280000 : "s_env"
> 
> factory:
> 
> 0x000000000000-0x000000200000 : "uboot"
> 0x000000200000-0x000000240000 : "u_env"
> 0x000000240000-0x000000280000 : "s_env"
> 
> Given the output you posted from fw_printenv earlier,
> 
> root at WRT1900ACS:/# fw_printenv
> bootcmd=bootp; setenv bootargs root=/dev/nfs
> nfsroot=${serverip}:${rootpath}
> ip=${ipaddr}:${serverip}:${gatewayip}:${netmask}:${hostname}::off; bootm
> bootdelay=5
> baudrate=115200
> auto_recovery=yes 
> 
> there's some very strange corruption going on. It would probably help to
> determine if this is happening during the auto_recovery/resetbc stuff
> during boot, when it writes to u-env. If there's something off still
> with the NAND driver this could cause the corruption you see.
> 
> Could you try this:
> 
> 1. reboot and run "resetenv; reset"
> 2. boot into LEDE, but drop into failsafe mode (when prompted, type
> f<enter>)
> 
> 3. from failsafe mode, run
> 	strings /dev/mtd1
> 
> this should be the complete and long set of default variables.
> 
> 4. still from failsafe, run 'reboot'
> 
> During this time nothing new is written to u-env, so upon a reboot it
> should still be functional. The 'printenv' command from u-boot should
> list the variables intact, and it should boot normally.
> 
> Once booted into LEDE normally, if the fw_setenv command during the boot
> sequence is causing the corruption, fw_printenv or "strings /dev/mtd1"
> will now show some sort of corrupted output.
> 
> -Claudio




More information about the Lede-dev mailing list