OpenWrt/LEDE Project

  • Status Closed
  • Percent Complete
    100%
  • Task Type Bug Report
  • Category Base system
  • Assigned To No-one
  • Operating System All
  • Severity Medium
  • Priority Very Low
  • Reported Version Trunk
  • Due in Version Undecided
  • Due Date Undecided
  • Private
Attached to Project: OpenWrt/LEDE Project
Opened by Giuseppe Tipaldi - 26.04.2018
Last edited by Mathias Kresin - 28.07.2018

FS#1516 - Mikrotik Nand-Large: The First firmware flashing fails

Target: Mikrotik RouterBoard 2011UiAS
Problem: Sysupgrade fails the first firmware flashing.

The issue happens with the last development Snapshot builds of 24-04-2018, while the last stable release (17.01.4) it works fine.


[ 1.029222] Creating 3 MTD partitions on “ar934x-nfc”:
[ 1.034593] 0×000000000000-0×000000040000 : “booter” [ 1.041206] 0×000000040000-0×000000400000 : “kernel” [ 1.048059] 0×000000400000-0×000008000000 : “ubi” [ 1.058117] libphy: Fixed MDIO Bus: probed
[ 1.074806] libphy: ag71xx_mdio: probed
[ 1.087674] switch0: Atheros AR8327 rev. 4 switch registered on ag71xx-mdio.0
[ 1.808680] libphy: ag71xx_mdio: probed
[ 2.442551] ag71xx ag71xx.0: connected to PHY at ag71xx-mdio.0:00 [uid=004dd034, driver=Atheros AR8216/AR8236/AR8316]
[ 2.454254] eth0: Atheros AG71xx at 0xb9000000, irq 4, mode:RGMII
[ 3.082861] ag71xx-mdio.1: Found an AR934X built-in switch
[ 3.135280] eth1: Atheros AG71xx at 0xba000000, irq 5, mode:GMII
[ 3.142878] NET: Registered protocol family 10
[ 3.150596] NET: Registered protocol family 17
[ 3.155308] bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this.
[ 3.168703] 8021q: 802.1Q VLAN Support v1.8
[ 3.176639] UBI error: no valid UBI magic found inside mtd6
[ 3.182851] VFS: Cannot open root device “(null)” or unknown-block(0,0): error -6
[ 3.190578] Please append a correct “root=” boot option; here are the available partitions:
[ 3.199253] 1f00 48 mtdblock0 [ 3.203404] (driver?)
[ 3.205853] 1f01 4 mtdblock1 [ 3.209984] (driver?)
[ 3.212440] 1f02 4 mtdblock2 [ 3.216570] (driver?)
[ 3.219013] 1f03 4 mtdblock3 [ 3.223159] (driver?)
[ 3.225599] 1f04 256 mtdblock4 [ 3.229733] (driver?)
[ 3.232188] 1f05 3840 mtdblock5 [ 3.236326] (driver?)
[ 3.238770] 1f06 126976 mtdblock6 [ 3.242915] (driver?)
[ 3.245354] Kernel panic - not syncing: VFS: Unable to mount root fs on unknown-block(0,0)
[ 3.254953] Rebooting in 1 seconds..


Full boot.

RouterBOOT booter 3.24

RouterBoard 2011UiAS

CPU frequency: 600 MHz
 Memory speed: 200 MHz
  Memory size: 128 MiB
    NAND size: 128 MiB

Press any key within 2 seconds to enter setup..

loading kernel from nand... kernel not found
trying dhcp protocol............... OK
resolved mac address 80:3F:5D:70:46:4D
Gateway: 192.168.1.9
transfer started ................................................... transfer ok, time=5.54s
setting up elf image... OK
jumping to kernel code
[    0.000000] Linux version 4.9.91 (buildbot@buildslave) (gcc version 7.3.0 (OpenWrt GCC 7.3.0 r6755-d089a5d773) ) #0 Mon Apr 23 20:07:22 2018
[    0.000000] bootconsole [early0] enabled
[    0.000000] CPU0 revision is: 0001974c (MIPS 74Kc)
[    0.000000] SoC: Atheros AR9344 rev 3
[    0.000000] Determined physical RAM map:
[    0.000000]  memory: 08000000 @ 00000000 (usable)
[    0.000000] User-defined physical RAM map:
[    0.000000]  memory: 08000000 @ 00000000 (usable)
[    0.000000] Initrd not found or empty - disabling initrd
[    0.000000] Primary instruction cache 64kB, VIPT, 4-way, linesize 32 bytes.
[    0.000000] Primary data cache 32kB, 4-way, VIPT, cache aliases, linesize 32 bytes
[    0.000000] Zone ranges:
[    0.000000]   Normal   [mem 0x0000000000000000-0x0000000007ffffff]
[    0.000000] Movable zone start for each node
[    0.000000] Early memory node ranges
[    0.000000]   node   0: [mem 0x0000000000000000-0x0000000007ffffff]
[    0.000000] Initmem setup node 0 [mem 0x0000000000000000-0x0000000007ffffff]
[    0.000000] Built 1 zonelists in Zone order, mobility grouping on.  Total pages: 32512
[    0.000000] Kernel command line: lcd_ctrl=84 parts=1 boot_part_size=4194304 gpio=118315 HZ=300000000 mem=128M kmac=E4:8D:8C:7E:84:F6 board=2011r5 ver=3.24 hw_opt=00518004 boot=1 mlc=9 console=ttyS0,115200 rootfstype=squashfs noinitrd
[    0.000000] PID hash table entries: 512 (order: -1, 2048 bytes)
[    0.000000] Dentry cache hash table entries: 16384 (order: 4, 65536 bytes)
[    0.000000] Inode-cache hash table entries: 8192 (order: 3, 32768 bytes)
[    0.000000] Writing ErrCtl register=00000000
[    0.000000] Readback ErrCtl register=00000000
[    0.000000] Memory: 122628K/131072K available (3579K kernel code, 156K rwdata, 840K rodata, 2072K init, 217K bss, 8444K reserved, 0K cma-reserved)
[    0.000000] SLUB: HWalign=32, Order=0-3, MinObjects=0, CPUs=1, Nodes=1
[    0.000000] NR_IRQS:51
[    0.000000] Clocks: CPU:600.000MHz, DDR:400.000MHz, AHB:400.000MHz, Ref:25.000MHz
[    0.000000] clocksource: MIPS: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 6370868154 ns
[    0.000009] sched_clock: 32 bits at 300MHz, resolution 3ns, wraps every 7158278654ns
[    0.008870] Calibrating delay loop... 299.82 BogoMIPS (lpj=1499136)
[    0.081948] pid_max: default: 32768 minimum: 301
[    0.087296] Mount-cache hash table entries: 1024 (order: 0, 4096 bytes)
[    0.094813] Mountpoint-cache hash table entries: 1024 (order: 0, 4096 bytes)
[    0.105548] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns
[    0.116744] futex hash table entries: 256 (order: -1, 3072 bytes)
[    0.124834] NET: Registered protocol family 16
[    0.134658] MIPS: machine is Mikrotik RouterBOARD 2011UiAS
[    0.641080] clocksource: Switched to clocksource MIPS
[    0.647921] NET: Registered protocol family 2
[    0.653829] TCP established hash table entries: 1024 (order: 0, 4096 bytes)
[    0.661802] TCP bind hash table entries: 1024 (order: 0, 4096 bytes)
[    0.669024] TCP: Hash tables configured (established 1024 bind 1024)
[    0.676337] UDP hash table entries: 256 (order: 0, 4096 bytes)
[    0.683006] UDP-Lite hash table entries: 256 (order: 0, 4096 bytes)
[    0.690367] NET: Registered protocol family 1
[    2.571092] random: fast init done
[    2.974252] Crashlog allocated RAM at address 0x3f00000
[    2.981220] workingset: timestamp_bits=30 max_order=15 bucket_order=0
[    2.995815] squashfs: version 4.0 (2009/01/31) Phillip Lougher
[    3.002476] jffs2: version 2.2 (NAND) (SUMMARY) (LZMA) (RTIME) (CMODE_PRIORITY) (c) 2001-2006 Red Hat, Inc.
[    3.100889] io scheduler noop registered
[    3.105386] io scheduler deadline registered (default)
[    3.111634] Serial: 8250/16550 driver, 16 ports, IRQ sharing enabled
[    3.121597] console [ttyS0] disabled
[    3.145811] serial8250.0: ttyS0 at MMIO 0x18020000 (irq = 11, base_baud = 1562500) is a 16550A
[    3.155611] console [ttyS0] enabled
[    3.155611] console [ttyS0] enabled
[    3.163168] bootconsole [early0] disabled
[    3.163168] bootconsole [early0] disabled
[    3.177266] m25p80 spi0.0: found w25x10, expected m25p80
[    3.194106] m25p80 spi0.0: w25x10 (128 Kbytes)
[    3.237937] Creating 4 MTD partitions on "spi0.0":
[    3.242948] 0x000000000000-0x00000000c000 : "routerboot"
[    3.249867] 0x00000000c000-0x00000000d000 : "hard_config"
[    3.257145] 0x00000000d000-0x00000000e000 : "bios"
[    3.263768] 0x00000000e000-0x00000000f000 : "soft_config"
[    3.272445] nand: device found, Manufacturer ID: 0xef, Chip ID: 0xf1
[    3.279019] nand: Winbond NAND 128MiB 3,3V 8-bit
[    3.283822] nand: 128 MiB, SLC, erase size: 128 KiB, page size: 2048, OOB size: 64
[    3.291689] Scanning device for bad blocks
[    3.297616] Bad eraseblock 32 at 0x000000400000
[    3.302376] Bad eraseblock 33 at 0x000000420000
[    3.307110] Bad eraseblock 34 at 0x000000440000
[    3.311860] Bad eraseblock 35 at 0x000000460000
[    3.316597] Bad eraseblock 36 at 0x000000480000
[    3.321348] Bad eraseblock 37 at 0x0000004a0000
[    3.326087] Bad eraseblock 38 at 0x0000004c0000
[    3.330819] Bad eraseblock 39 at 0x0000004e0000
[    3.335567] Bad eraseblock 40 at 0x000000500000
[    3.340298] Bad eraseblock 41 at 0x000000520000
[    3.345047] Bad eraseblock 42 at 0x000000540000
[    3.349786] Bad eraseblock 43 at 0x000000560000
[    3.354535] Bad eraseblock 44 at 0x000000580000
[    3.359266] Bad eraseblock 45 at 0x0000005a0000
[    3.364015] Bad eraseblock 46 at 0x0000005c0000
[    3.437750] Creating 3 MTD partitions on "ar934x-nfc":
[    3.443125] 0x000000000000-0x000000040000 : "booter"
[    3.449675] 0x000000040000-0x000000400000 : "kernel"
[    3.456599] 0x000000400000-0x000008000000 : "ubi"
[    3.466682] libphy: Fixed MDIO Bus: probed
[    3.580738] libphy: ag71xx_mdio: probed
[    3.613057] switch0: Atheros AR8327 rev. 4 switch registered on ag71xx-mdio.0
[    4.582067] libphy: ag71xx_mdio: probed
[    5.212536] ag71xx ag71xx.0: connected to PHY at ag71xx-mdio.0:00 [uid=004dd034, driver=Atheros AR8216/AR8236/AR8316]
[    5.224235] eth0: Atheros AG71xx at 0xb9000000, irq 4, mode:RGMII
[    5.852848] ag71xx-mdio.1: Found an AR934X built-in switch
[    5.905264] eth1: Atheros AG71xx at 0xba000000, irq 5, mode:GMII
[    5.912869] NET: Registered protocol family 10
[    5.920627] NET: Registered protocol family 17
[    5.925339] bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this.
[    5.938734] 8021q: 802.1Q VLAN Support v1.8
[    5.946655] UBI error: no valid UBI magic found inside mtd6
[    5.962584] Freeing unused kernel memory: 2072K
[    5.967268] This architecture does not have kernel memory protection.
[    5.988742] init: Console is alive
[    5.992627] init: - watchdog -
[    6.015875] kmodloader: loading kernel modules from /etc/modules-boot.d/*
[    6.025353] kmodloader: done loading kernel modules from /etc/modules-boot.d/*
[    6.043528] init: - preinit -
[    6.218586] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready
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
[    7.302864] eth0: link up (1000Mbps/Full duplex)
[    7.307840] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready
[    9.368947] eth0: link down
[    9.383472] procd: - early -
[    9.386548] procd: - watchdog -
[    9.956495] procd: - watchdog -
[    9.960018] procd: - ubus -
[   10.015004] procd: - init -
Please press Enter to activate this console.
[   10.196748] kmodloader: loading kernel modules from /etc/modules.d/*
[   10.207676] ip6_tables: (C) 2000-2006 Netfilter Core Team
[   10.223843] Loading modules backported from Linux version wt-2017-11-01-0-gfe248fc2c180
[   10.232162] Backport generated by backports.git v4.14-rc2-1-31-g86cf0e5d
[   10.242153] ip_tables: (C) 2000-2006 Netfilter Core Team
[   10.256525] nf_conntrack version 0.5.0 (2048 buckets, 8192 max)
[   10.315235] xt_time: kernel timezone is -0000
[   10.375755] PPP generic driver version 2.4.2
[   10.382656] NET: Registered protocol family 24
[   10.431325] ath: Invalid EEPROM contents
[   10.435418] ath9k ar934x_wmac: failed to initialize device
[   10.441139] ath9k: probe of ar934x_wmac failed with error -22
[   10.448643] kmodloader: done loading kernel modules from /etc/modules.d/*
[   22.416153] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready
[   22.424050] eth0: link up (1000Mbps/Full duplex)
[   22.428840] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready
[   22.466227] br-lan: port 1(eth0.1) entered blocking state
[   22.471873] br-lan: port 1(eth0.1) entered disabled state
[   22.477848] device eth0.1 entered promiscuous mode
[   22.482842] device eth0 entered promiscuous mode
[   22.556284] br-lan: port 1(eth0.1) entered blocking state
[   22.561923] br-lan: port 1(eth0.1) entered forwarding state
[   22.567836] IPv6: ADDRCONF(NETDEV_UP): br-lan: link is not ready
[   22.838835] IPv6: ADDRCONF(NETDEV_UP): eth1: link is not ready
[   22.903520] br-lan: port 2(eth1.1) entered blocking state
[   22.909112] br-lan: port 2(eth1.1) entered disabled state
[   22.915128] device eth1.1 entered promiscuous mode
[   22.920081] device eth1 entered promiscuous mode
[   23.461289] IPv6: ADDRCONF(NETDEV_CHANGE): br-lan: link becomes ready



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

  _______                     ________        __
 |       |.-----.-----.-----.|  |  |  |.----.|  |_
 |   -   ||  _  |  -__|     ||  |  |  ||   _||   _|
 |_______||   __|_____|__|__||________||__|  |____|
          |__| W I R E L E S S   F R E E D O M
 -----------------------------------------------------
 OpenWrt SNAPSHOT, r6755-d089a5d773
 -----------------------------------------------------
=== WARNING! =====================================
There is no root password defined on this device!
Use the "passwd" command to set up a new password
in order to prevent unauthorized SSH logins.
--------------------------------------------------
root@OpenWrt:/# ifconfig 
br-lan    Link encap:Ethernet  HWaddr E4:8D:8C:7E:84:F6  
          inet addr:192.168.1.1  Bcast:192.168.1.255  Mask:255.255.255.0
          inet6 addr: fd32:742b:a367::1/60 Scope:Global
          inet6 addr: fe80::e68d:8cff:fe7e:84f6/64 Scope:Link
          UP BROADCAST RUNNING MULTICAST  MTU:1500  Metric:1
          RX packets:0 errors:0 dropped:0 overruns:0 frame:0
          TX packets:16 errors:0 dropped:0 overruns:0 carrier:0
          collisions:0 txqueuelen:1000 
          RX bytes:0 (0.0 B)  TX bytes:2048 (2.0 KiB)

eth0      Link encap:Ethernet  HWaddr E4:8D:8C:7E:84:F6  
          inet6 addr: fe80::e68d:8cff:fe7e:84f6/64 Scope:Link
          UP BROADCAST RUNNING MULTICAST  MTU:1500  Metric:1
          RX packets:3 errors:0 dropped:0 overruns:0 frame:0
          TX packets:56 errors:0 dropped:0 overruns:0 carrier:0
          collisions:0 txqueuelen:1000 
          RX bytes:756 (756.0 B)  TX bytes:6920 (6.7 KiB)
          Interrupt:4 

eth0.1    Link encap:Ethernet  HWaddr E4:8D:8C:7E:84:F6  
          UP BROADCAST RUNNING MULTICAST  MTU:1500  Metric:1
          RX packets:0 errors:0 dropped:0 overruns:0 frame:0
          TX packets:16 errors:0 dropped:0 overruns:0 carrier:0
          collisions:0 txqueuelen:1000 
          RX bytes:0 (0.0 B)  TX bytes:2048 (2.0 KiB)

eth0.2    Link encap:Ethernet  HWaddr E4:8D:8C:7E:84:F6  
          inet addr:192.168.1.14  Bcast:192.168.1.255  Mask:255.255.255.0
          inet6 addr: fe80::e68d:8cff:fe7e:84f6/64 Scope:Link
          UP BROADCAST RUNNING MULTICAST  MTU:1500  Metric:1
          RX packets:3 errors:0 dropped:0 overruns:0 frame:0
          TX packets:18 errors:0 dropped:0 overruns:0 carrier:0
          collisions:0 txqueuelen:1000 
          RX bytes:702 (702.0 B)  TX bytes:2440 (2.3 KiB)

eth0.3    Link encap:Ethernet  HWaddr E4:8D:8C:7E:84:F6  
          inet6 addr: fe80::e68d:8cff:fe7e:84f6/64 Scope:Link
          UP BROADCAST RUNNING MULTICAST  MTU:1500  Metric:1
          RX packets:0 errors:0 dropped:0 overruns:0 frame:0
          TX packets:8 errors:0 dropped:0 overruns:0 carrier:0
          collisions:0 txqueuelen:1000 
          RX bytes:0 (0.0 B)  TX bytes:848 (848.0 B)

eth1      Link encap:Ethernet  HWaddr E4:8D:8C:7E:84:FB  
          UP BROADCAST MULTICAST  MTU:1500  Metric:1
          RX packets:0 errors:0 dropped:0 overruns:0 frame:0
          TX packets:0 errors:0 dropped:0 overruns:0 carrier:0
          collisions:0 txqueuelen:1000 
          RX bytes:0 (0.0 B)  TX bytes:0 (0.0 B)
          Interrupt:5 

eth1.1    Link encap:Ethernet  HWaddr E4:8D:8C:7E:84:FB  
          UP BROADCAST MULTICAST  MTU:1500  Metric:1
          RX packets:0 errors:0 dropped:0 overruns:0 frame:0
          TX packets:0 errors:0 dropped:0 overruns:0 carrier:0
          collisions:0 txqueuelen:1000 
          RX bytes:0 (0.0 B)  TX bytes:0 (0.0 B)

lo        Link encap:Local Loopback  
          inet addr:127.0.0.1  Mask:255.0.0.0
          inet6 addr: ::1/128 Scope:Host
          UP LOOPBACK RUNNING  MTU:65536  Metric:1
          RX packets:24 errors:0 dropped:0 overruns:0 frame:0
          TX packets:24 errors:0 dropped:0 overruns:0 carrier:0
          collisions:0 txqueuelen:1 
          RX bytes:1800 (1.7 KiB)  TX bytes:1800 (1.7 KiB)

root@OpenWrt:/# 
root@OpenWrt:/# [  102.731123] random: crng init done

root@OpenWrt:/# sy
sync        sysctl      sysupgrade
root@OpenWrt:/# sysupgrade --help
Usage: /sbin/sysupgrade [<upgrade-option>...] <image file or URL>
       /sbin/sysupgrade [-q] [-i] <backup-command> <file>

upgrade-option:
	-f <config>  restore configuration from .tar.gz (file or url)
	-i           interactive mode
	-c           attempt to preserve all changed files in /etc/
	-n           do not save configuration over reflash
	-p           do not attempt to restore the partition table after flash.
	-T | --test
	             Verify image and config .tar.gz but do not actually flash.
	-F | --force
	             Flash image even if image checks fail, this is dangerous!
	-q           less verbose
	-v           more verbose
	-h | --help  display this help

backup-command:
	-b | --create-backup <file>
	             create .tar.gz of files specified in sysupgrade.conf
	             then exit. Does not flash an image. If file is '-',
	             i.e. stdout, verbosity is set to 0 (i.e. quiet).
	-r | --restore-backup <file>
	             restore a .tar.gz created with sysupgrade -b
	             then exit. Does not flash an image. If file is '-',
	             the archive is read from stdin.
	-l | --list-backup
	             list the files that would be backed up when calling
	             sysupgrade -b. Does not create a backup file.

root@OpenWrt:/# sysupgrade -v /tmp/fw.bin 
Image metadata not found
Cannot save config while running from ramdisk.
Commencing upgrade. All shell sessions will be closed now.
Watchdog handover: fd=3
- watchdog -
killall: telnetd: no process killed
Sending TERM to remaining processes ... udhcpc odhcp6c ntpd dnsmasq ubusd logd netifd odhcpd 
Sending KILL to remaining processes ... ntpd 
Performing system upgrade...
Unlocking kernel ...
Erasing kernel ...
Writing data to block 0 at offset 0x0
Writing data to block 1 at offset 0x20000
Writing data to block 2 at offset 0x40000
Writing data to block 3 at offset 0x60000
Writing data to block 4 at offset 0x80000
Writing data to block 5 at offset 0xa0000
Writing data to block 6 at offset 0xc0000
Writing data to block 7 at offset 0xe0000
Writing data to block 8 at offset 0x100000
Writing data to block 9 at offset 0x120000
Writing data to block 10 at offset 0x140000
Writing data to block 11 at offset 0x160000
Writing data to block 12 at offset 0x180000
Writing data to block 13 at offset 0x1a0000
[  143.883296] ubi0: attaching mtd6
[  144.532368] ubi0: scanning is finished
[  144.536247] ubi0: empty MTD device detected
[  144.560740] ubi0: attached mtd6 (name "ubi", size 124 MiB)
[  144.566465] ubi0: PEB size: 131072 bytes (128 KiB), LEB size: 129024 bytes
[  144.573579] ubi0: min./max. I/O unit sizes: 2048/2048, sub-page size 512
[  144.580502] ubi0: VID header offset: 512 (aligned 512), data offset: 2048
[  144.587518] ubi0: good PEBs: 977, bad PEBs: 15, corrupted PEBs: 0
[  144.593817] ubi0: user volume: 0, internal volumes: 1, max. volumes count: 128
[  144.601280] ubi0: max/mean erase counter: 0/0, WL threshold: 4096, image sequence number: 2387083945
[  144.610706] ubi0: available PEBs: 968, total reserved PEBs: 9, PEBs reserved for bad PEB handling: 5
[  144.620161] ubi0: background thread "ubi_bgt0d" started, PID 1647
UBI device number 0, total 977 LEBs (126056448 bytes, 120.2 MiB), available 968 LEBs (124895232 bytes, 119.1 MiB), LEB size 129024 bytes (126.0 KiB)
Volume ID 0, size 14 LEBs (1806336 bytes, 1.7 MiB), LEB size 129024 bytes (126.0 KiB), dynamic, name "none", alignment 1
Volume ID 1, size 17 LEBs (2193408 bytes, 2.1 MiB), LEB size 129024 bytes (126.0 KiB), dynamic, name "rootfs", alignment 1
Set volume size to 120895488
Volume ID 2, size 937 LEBs (120895488 bytes, 115.3 MiB), LEB size 129024 bytes (126.0 KiB), dynamic, name "rootfs_data", alignment 1
sysupgrade successful
umount: can't unmount /dev: Resource busy
umount: can't unmount /tmp: Resource busy
umount: can't unmount /: Invalid argument
[  147.498006] reboot: Restarting system


RouterBOOT booter 3.24

RouterBoard 2011UiAS

CPU frequency: 600 MHz
 Memory speed: 200 MHz
  Memory size: 128 MiB
    NAND size: 128 MiB

Press any key within 2 seconds to enter setup..

loading kernel from nand... OK
setting up elf image... OK
jumping to kernel code


OpenWrt kernel loader for AR7XXX/AR9XXX
Copyright (C) 2011 Gabor Juhos <juhosg@openwrt.org>
Decompressing kernel... done!
Starting kernel at 80060000...

[    0.000000] Linux version 4.9.91 (buildbot@buildslave) (gcc version 7.3.0 (OpenWrt GCC 7.3.0 r6755-d089a5d773) ) #0 Mon Apr 23 20:07:22 2018
[    0.000000] bootconsole [early0] enabled
[    0.000000] CPU0 revision is: 0001974c (MIPS 74Kc)
[    0.000000] SoC: Atheros AR9344 rev 3
[    0.000000] Determined physical RAM map:
[    0.000000]  memory: 08000000 @ 00000000 (usable)
[    0.000000] User-defined physical RAM map:
[    0.000000]  memory: 08000000 @ 00000000 (usable)
[    0.000000] Initrd not found or empty - disabling initrd
[    0.000000] Primary instruction cache 64kB, VIPT, 4-way, linesize 32 bytes.
[    0.000000] Primary data cache 32kB, 4-way, VIPT, cache aliases, linesize 32 bytes
[    0.000000] Zone ranges:
[    0.000000]   Normal   [mem 0x0000000000000000-0x0000000007ffffff]
[    0.000000] Movable zone start for each node
[    0.000000] Early memory node ranges
[    0.000000]   node   0: [mem 0x0000000000000000-0x0000000007ffffff]
[    0.000000] Initmem setup node 0 [mem 0x0000000000000000-0x0000000007ffffff]
[    0.000000] Built 1 zonelists in Zone order, mobility grouping on.  Total pages: 32512
[    0.000000] Kernel command line: lcd_ctrl=84 parts=1 boot_part_size=4194304 gpio=118315 HZ=300000000 mem=128M kmac=E4:8D:8C:7E:84:F6 board=2011r5 ver=3.24 hw_opt=00518004 boot=1 mlc=9 console=ttyS0,115200 rootfstype=squashfs noinitrd
[    0.000000] PID hash table entries: 512 (order: -1, 2048 bytes)
[    0.000000] Dentry cache hash table entries: 16384 (order: 4, 65536 bytes)
[    0.000000] Inode-cache hash table entries: 8192 (order: 3, 32768 bytes)
[    0.000000] Writing ErrCtl register=00000000
[    0.000000] Readback ErrCtl register=00000000
[    0.000000] Memory: 124484K/131072K available (3579K kernel code, 156K rwdata, 840K rodata, 216K init, 217K bss, 6588K reserved, 0K cma-reserved)
[    0.000000] SLUB: HWalign=32, Order=0-3, MinObjects=0, CPUs=1, Nodes=1
[    0.000000] NR_IRQS:51
[    0.000000] Clocks: CPU:600.000MHz, DDR:400.000MHz, AHB:400.000MHz, Ref:25.000MHz
[    0.000000] clocksource: MIPS: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 6370868154 ns
[    0.000009] sched_clock: 32 bits at 300MHz, resolution 3ns, wraps every 7158278654ns
[    0.008871] Calibrating delay loop... 299.82 BogoMIPS (lpj=1499136)
[    0.081948] pid_max: default: 32768 minimum: 301
[    0.087296] Mount-cache hash table entries: 1024 (order: 0, 4096 bytes)
[    0.094813] Mountpoint-cache hash table entries: 1024 (order: 0, 4096 bytes)
[    0.105545] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns
[    0.116736] futex hash table entries: 256 (order: -1, 3072 bytes)
[    0.124831] NET: Registered protocol family 16
[    0.134659] MIPS: machine is Mikrotik RouterBOARD 2011UiAS
[    0.641094] clocksource: Switched to clocksource MIPS
[    0.647937] NET: Registered protocol family 2
[    0.653849] TCP established hash table entries: 1024 (order: 0, 4096 bytes)
[    0.661821] TCP bind hash table entries: 1024 (order: 0, 4096 bytes)
[    0.669044] TCP: Hash tables configured (established 1024 bind 1024)
[    0.676357] UDP hash table entries: 256 (order: 0, 4096 bytes)
[    0.683033] UDP-Lite hash table entries: 256 (order: 0, 4096 bytes)
[    0.690395] NET: Registered protocol family 1
[    0.698994] Crashlog allocated RAM at address 0x3f00000
[    0.705916] workingset: timestamp_bits=30 max_order=15 bucket_order=0
[    0.720505] squashfs: version 4.0 (2009/01/31) Phillip Lougher
[    0.727167] jffs2: version 2.2 (NAND) (SUMMARY) (LZMA) (RTIME) (CMODE_PRIORITY) (c) 2001-2006 Red Hat, Inc.
[    0.747037] io scheduler noop registered
[    0.751531] io scheduler deadline registered (default)
[    0.757775] Serial: 8250/16550 driver, 16 ports, IRQ sharing enabled
[    0.767760] console [ttyS0] disabled
[    0.791992] serial8250.0: ttyS0 at MMIO 0x18020000 (irq = 11, base_baud = 1562500) is a 16550A
[    0.801796] console [ttyS0] enabled
[    0.801796] console [ttyS0] enabled
[    0.809342] bootconsole [early0] disabled
[    0.809342] bootconsole [early0] disabled
[    0.823330] m25p80 spi0.0: found w25x10, expected m25p80
[    0.840090] m25p80 spi0.0: w25x10 (128 Kbytes)
[    0.845137] Creating 4 MTD partitions on "spi0.0":
[    0.850105] 0x000000000000-0x00000000c000 : "routerboot"
[    0.857075] 0x00000000c000-0x00000000d000 : "hard_config"
[    0.864315] 0x00000000d000-0x00000000e000 : "bios"
[    0.870855] 0x00000000e000-0x00000000f000 : "soft_config"
[    0.879537] nand: device found, Manufacturer ID: 0xef, Chip ID: 0xf1
[    0.886145] nand: Winbond NAND 128MiB 3,3V 8-bit
[    0.890918] nand: 128 MiB, SLC, erase size: 128 KiB, page size: 2048, OOB size: 64
[    0.898784] Scanning device for bad blocks
[    0.904724] Bad eraseblock 32 at 0x000000400000
[    0.909456] Bad eraseblock 33 at 0x000000420000
[    0.914204] Bad eraseblock 34 at 0x000000440000
[    0.918944] Bad eraseblock 35 at 0x000000460000
[    0.923692] Bad eraseblock 36 at 0x000000480000
[    0.928423] Bad eraseblock 37 at 0x0000004a0000
[    0.933171] Bad eraseblock 38 at 0x0000004c0000
[    0.937902] Bad eraseblock 39 at 0x0000004e0000
[    0.942651] Bad eraseblock 40 at 0x000000500000
[    0.947382] Bad eraseblock 41 at 0x000000520000
[    0.952130] Bad eraseblock 42 at 0x000000540000
[    0.956862] Bad eraseblock 43 at 0x000000560000
[    0.961612] Bad eraseblock 44 at 0x000000580000
[    0.966342] Bad eraseblock 45 at 0x0000005a0000
[    0.971073] Bad eraseblock 46 at 0x0000005c0000
[    0.977275] random: fast init done
[    1.029222] Creating 3 MTD partitions on "ar934x-nfc":
[    1.034593] 0x000000000000-0x000000040000 : "booter"
[    1.041206] 0x000000040000-0x000000400000 : "kernel"
[    1.048059] 0x000000400000-0x000008000000 : "ubi"
[    1.058117] libphy: Fixed MDIO Bus: probed
[    1.074806] libphy: ag71xx_mdio: probed
[    1.087674] switch0: Atheros AR8327 rev. 4 switch registered on ag71xx-mdio.0
[    1.808680] libphy: ag71xx_mdio: probed
[    2.442551] ag71xx ag71xx.0: connected to PHY at ag71xx-mdio.0:00 [uid=004dd034, driver=Atheros AR8216/AR8236/AR8316]
[    2.454254] eth0: Atheros AG71xx at 0xb9000000, irq 4, mode:RGMII
[    3.082861] ag71xx-mdio.1: Found an AR934X built-in switch
[    3.135280] eth1: Atheros AG71xx at 0xba000000, irq 5, mode:GMII
[    3.142878] NET: Registered protocol family 10
[    3.150596] NET: Registered protocol family 17
[    3.155308] bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this.
[    3.168703] 8021q: 802.1Q VLAN Support v1.8
[    3.176639] UBI error: no valid UBI magic found inside mtd6
[    3.182851] VFS: Cannot open root device "(null)" or unknown-block(0,0): error -6
[    3.190578] Please append a correct "root=" boot option; here are the available partitions:
[    3.199253] 1f00              48 mtdblock0 [    3.203404]  (driver?)
[    3.205853] 1f01               4 mtdblock1 [    3.209984]  (driver?)
[    3.212440] 1f02               4 mtdblock2 [    3.216570]  (driver?)
[    3.219013] 1f03               4 mtdblock3 [    3.223159]  (driver?)
[    3.225599] 1f04             256 mtdblock4 [    3.229733]  (driver?)
[    3.232188] 1f05            3840 mtdblock5 [    3.236326]  (driver?)
[    3.238770] 1f06          126976 mtdblock6 [    3.242915]  (driver?)
[    3.245354] Kernel panic - not syncing: VFS: Unable to mount root fs on unknown-block(0,0)
[    3.254953] Rebooting in 1 seconds..


RouterBOOT booter 3.24
 


Closed by  Mathias Kresin
28.07.2018 09:05
Reason for closing:  Fixed
Additional comments about closing:  

Bad blocks are now better handled in master. It might that the issue is still something else. But since the original reported doesn't response, I'm going to close this one as fixed.

Project Manager
Mathias Kresin commented on 08.07.2018 00:33

It looks like you hit a corner case not covered by one of our OpenWrt specific functions.

The issue happens with the last development Snapshot builds of 24-04-2018, while the last stable release (17.01.4) it works fine.

It might be that I suspect the wrong issue here. But if it's what I'm thinking about it, should fail the very same way with 17.01.4 and all earlier releases.

Anyway, would you please test if your issue is fixed with the image uploaded to https://www.kresin.me/files/mikrotik/.

If you prefer to build an image on your own, I've committed the supposed fix to my staging tree.

Project Manager
Mathias Kresin commented on 08.07.2018 20:13
But if it's what I'm thinking about it, should fail the very same way with 17.01.4 and all earlier releases.

After thinking more about the fact that it works with 17.01.4 and fails with the current snapshots, I can thing of another issue that might be introduced.

The ubi auto attach code doesn't check for the UBI magic if an error occurred while reading from flash. Instead it will skip the UBI magic check and goes straight to the code that tries to (auto)mount the ubi partition.

Since your board has a bad block right at the beginning of the ubi partition ([ 0.904724] Bad eraseblock 32 at 0x000000400000), it is expected that an error occurs during reading and ubi magic check is skipped.

If for some reason this error code isn't returned, the code will jump into the ubi magic check and fail, as there is no (or garbage) data read from flash which will not have the ubi magic for sure.

Depending on what really is wrong, my changes are able to workaround the real issue or doesn't change anything and you will still see the error.

aversario commented on 26.07.2018 20:12

I have the same/similar problem on Mikrotik RB450G with openwrt-18.06-rc2

initramfs:
 
root@OpenWrt:/tmp# sysupgrade -n -v openwrt.bin
Image metadata not found
Commencing upgrade. All shell sessions will be closed now.
Watchdog handover: fd=3
- watchdog -
killall: telnetd: no process killed
Sending TERM to remaining processes ... ntpd sh getrandom dnsmasq ubusd logd rpcd netifd odhcpd uhttpd
Sending KILL to remaining processes ... ntpd
Performing system upgrade...
Unlocking kernel ...
Erasing kernel ...
Writing data to block 0 at offset 0x0
Writing data to block 1 at offset 0x40000
Writing data to block 2 at offset 0x80000
Writing data to block 3 at offset 0xc0000
Writing data to block 4 at offset 0x100000
Writing data to block 5 at offset 0x140000
Writing data to block 6 at offset 0x180000
[   58.547938] ubi: refuse attaching mtd6 - MLC NAND is not supported
ubiattach: error!: cannot attach mtd6
           error 22 (Invalid argument)
ubiformat: mtd6 (mlc-nand), size 1069547520 bytes (1020.0 MiB), 4080 eraseblocks of 262144 bytes (256.0 KiB), min. I/O size 2048 bytes
libscan: scanning[   62.896811] random: crng init done
libscan: scanning eraseblock 4079 -- 100 % complete  
ubiformat: 4079 eraseblocks are supposedly empty
ubiformat: 1 bad eraseblocks found, numbers: 3146
ubiformat: formatting eraseblock 4079 -- 100 % complete  
[  100.230802] ubi: refuse attaching mtd6 - MLC NAND is not supported
ubiattach: error!: cannot attach mtd6
           error 22 (Invalid argument)
libubi: error!: "/dev/" is not a character device
ubimkvol: error!: error while probing "/dev/"
          error 22 (Invalid argument)
cannot create kernel volume
libubi: error!: "/dev/" is not a character device
ubiupdatevol: error!: error while probing "/dev/"
              error 22 (Invalid argument)
tar: write error: Broken pipe
libubi: error!: "/dev/" is not a character device
ubiupdatevol: error!: error while probing "/dev/"
              error 22 (Invalid argument)
tar: write error: Broken pipe
sysupgrade succe[  100.321037] reboot: Restarting system
 boot:

[    0.000000] Linux version 4.9.111 (buildbot@builds-03.infra.lede-project.org) (gcc version 7.3.0 (OpenWrt GCC 7.3.0 r7101-a63e38b) ) #0 Sat Jul 18
[    0.000000] bootconsole [early0] enabled
[    0.000000] CPU0 revision is: 00019374 (MIPS 24Kc)
[    0.000000] SoC: Atheros AR7161 rev 2
[    0.000000] Determined physical RAM map:
[    0.000000]  memory: 10000000 @ 00000000 (usable)
[    0.000000] User-defined physical RAM map:
[    0.000000]  memory: 10000000 @ 00000000 (usable)
[    0.000000] Initrd not found or empty - disabling initrd
[    0.000000] Primary instruction cache 64kB, VIPT, 4-way, linesize 32 bytes.
[    0.000000] Primary data cache 32kB, 4-way, VIPT, cache aliases, linesize 32 bytes
[    0.000000] Zone ranges:
[    0.000000]   Normal   [mem 0x0000000000000000-0x000000000fffffff]
[    0.000000] Movable zone start for each node
[    0.000000] Early memory node ranges
[    0.000000]   node   0: [mem 0x0000000000000000-0x000000000fffffff]
[    0.000000] Initmem setup node 0 [mem 0x0000000000000000-0x000000000fffffff]
[    0.000000] Built 1 zonelists in Zone order, mobility grouping on.  Total pages: 65024
[    0.000000] Kernel command line: parts=1 boot_part_size=4194304 gpio=4031 HZ=340000000 mem=256M kmac=00:0C:42:54:A2:5A board=450G board=450G ver=d
[    0.000000] PID hash table entries: 1024 (order: 0, 4096 bytes)
[    0.000000] Dentry cache hash table entries: 32768 (order: 5, 131072 bytes)
[    0.000000] Inode-cache hash table entries: 16384 (order: 4, 65536 bytes)
[    0.000000] Writing ErrCtl register=00000000
[    0.000000] Readback ErrCtl register=00000000
[    0.000000] Memory: 254816K/262144K available (3575K kernel code, 156K rwdata, 452K rodata, 224K init, 217K bss, 7328K reserved, 0K cma-reserved)
[    0.000000] SLUB: HWalign=32, Order=0-3, MinObjects=0, CPUs=1, Nodes=1
[    0.000000] NR_IRQS:51
[    0.000000] Clocks: CPU:680.000MHz, DDR:340.000MHz, AHB:170.000MHz, Ref:40.000MHz
[    0.000000] clocksource: MIPS: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 5621354254 ns
[    0.000009] sched_clock: 32 bits at 340MHz, resolution 2ns, wraps every 6316128254ns
[    0.007816] Calibrating delay loop... 452.19 BogoMIPS (lpj=2260992)
[    0.070515] pid_max: default: 32768 minimum: 301
[    0.075232] Mount-cache hash table entries: 1024 (order: 0, 4096 bytes)
[    0.081836] Mountpoint-cache hash table entries: 1024 (order: 0, 4096 bytes)
[    0.091266] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns
[    0.101130] futex hash table entries: 256 (order: -1, 3072 bytes)
[    0.108288] NET: Registered protocol family 16
[    0.114268] MIPS: machine is MikroTik RouterBOARD 450G
[    0.593131] Can't analyze schedule() prologue at 803da504
[    0.606615] rb4xx-spi rb4xx-spi: master is unqueued, this is deprecated
[    0.616906] clocksource: Switched to clocksource MIPS
[    0.622903] NET: Registered protocol family 2
[    0.628171] TCP established hash table entries: 2048 (order: 1, 8192 bytes)
[    0.635162] TCP bind hash table entries: 2048 (order: 1, 8192 bytes)
[    0.641578] TCP: Hash tables configured (established 2048 bind 2048)
[    0.648030] UDP hash table entries: 256 (order: 0, 4096 bytes)
[    0.653870] UDP-Lite hash table entries: 256 (order: 0, 4096 bytes)
[    0.660391] NET: Registered protocol family 1
[    0.667219] Crashlog allocated RAM at address 0x3f00000
[    0.673264] workingset: timestamp_bits=30 max_order=16 bucket_order=0
[    0.686186] squashfs: version 4.0 (2009/01/31) Phillip Lougher
[    0.692053] jffs2: version 2.2 (NAND) (SUMMARY) (LZMA) (RTIME) (CMODE_PRIORITY) (c) 2001-2006 Red Hat, Inc.
[    0.709286] io scheduler noop registered
[    0.713209] io scheduler deadline registered (default)
[    0.718756] Serial: 8250/16550 driver, 16 ports, IRQ sharing enabled
[    0.727473] console [ttyS0] disabled
[    0.751096] serial8250.0: ttyS0 at MMIO 0x18020000 (irq = 11, base_baud = 10625000) is a 16550A
[    0.759800] console [ttyS0] enabled
[    0.759800] console [ttyS0] enabled
[    0.766756] bootconsole [early0] disabled
[    0.766756] bootconsole [early0] disabled
[    0.775766] m25p80 spi0.0: pm25lv512 (64 Kbytes)
[    0.784155] Creating 4 MTD partitions on "spi0.0":
[    0.789005] 0x000000000000-0x00000000b000 : "routerboot"
[    0.795582] 0x00000000b000-0x00000000c000 : "hard_config"
[    0.802477] 0x00000000d000-0x00000000f000 : "bios"
[    0.808769] 0x00000000f000-0x000000010000 : "soft_config"
[    0.815713] NAND flash driver for RouterBoard 4xx series version 0.2.0
[    0.822551] nand: device found, Manufacturer ID: 0xad, Chip ID: 0xd3
[    0.828896] nand: Hynix NAND 1GiB 3,3V 8-bit
[    0.833156] nand: 1024 MiB, MLC, erase size: 256 KiB, page size: 2048, OOB size: 64
[    0.840828] Scanning device for bad blocks
[    1.359186] Bad eraseblock 3162 at 0x0000316bf800
[    1.515738] Creating 3 MTD partitions on "NAND 1GiB 3,3V 8-bit":
[    1.521789] 0x000000000000-0x000000040000 : "booter"
[    1.528052] 0x000000040000-0x000000400000 : "kernel"
[    1.534463] 0x000000400000-0x000040000000 : "ubi"
[    1.547705] libphy: Fixed MDIO Bus: probed
[    1.556666] switch0: Atheros AR8316 rev. 1 switch registered on ag71xx-mdio.0
[    1.564956] libphy: ag71xx_mdio: probed
[    1.908254] ar8316: Using port 4 as PHY
[    2.586892] random: fast init done
[    3.019029] ag71xx ag71xx.1: connected to PHY at ag71xx-mdio.0:04 [uid=004dd041, driver=Atheros AR8216/AR8236/AR8316]
[    3.030265] eth0: Atheros AG71xx at 0xba000000, irq 5, mode:RGMII
[    3.368253] ar8316: Using port 4 as PHY
[    3.375665] ag71xx ag71xx.0: connected to PHY at ag71xx-mdio.0:00 [uid=004dd041, driver=Atheros AR8216/AR8236/AR8316]
[    3.386955] eth1: Atheros AG71xx at 0xb9000000, irq 4, mode:RGMII
[    3.394746] NET: Registered protocol family 10
[    3.402526] NET: Registered protocol family 17
[    3.407082] bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this.
[    3.420020] 8021q: 802.1Q VLAN Support v1.8
[    3.424358] rb: no calibration data found
[    3.431434] UBI: auto-attach mtd6
[    3.434751] ubi: refuse attaching mtd6 - MLC NAND is not supported
[    3.440968] UBI error: cannot attach mtd6
[    3.445541] VFS: Cannot open root device "(null)" or unknown-block(0,0): error -6
[    3.453064] Please append a correct "root=" boot option; here are the available partitions:
[    3.461411] 1f00              44 mtdblock0 [    3.465407]  (driver?)
[    3.467769] 1f01               4 mtdblock1 [    3.471763]  (driver?)
[    3.474114] 1f02               8 mtdblock2 [    3.478118]  (driver?)
[    3.480479] 1f03               4 mtdblock3 [    3.484474]  (driver?)
[    3.486826] 1f04             256 mtdblock4 [    3.490831]  (driver?)
[    3.493190] 1f05            3840 mtdblock5 [    3.497194]  (driver?)
[    3.499555] 1f06         1044480 mtdblock6 [    3.503550]  (driver?)
[    3.505901] Kernel panic - not syncing: VFS: Unable to mount root fs on unknown-block(0,0)
[    3.515040] Rebooting in 1 seconds..
Project Manager
Mathias Kresin commented on 28.07.2018 09:03
I have the same/similar problem on Mikrotik RB450G with openwrt-18.06-rc2

No, you have a complete different issue. Your board has a MLC NAND flash and UBI isn't supported on MLC NAND. Following https://unix.stackexchange.com/questions/448581/linux-alternative-to-ubifs-on-mlc-nand it doesn't look that good for your board.

Loading...

Available keyboard shortcuts

Tasklist

Task Details

Task Editing