[FS#4137] OpenWRT 21.02.1 doesn't completely boot on RaidSonic IB-NAS4220-B

OpenWrt Bugs openwrt-bugs at lists.openwrt.org
Sun Nov 14 14:20:50 PST 2021


THIS IS AN AUTOMATED MESSAGE, DO NOT REPLY.

A new Flyspray task has been opened.  Details are below. 

User who did this - Steven Maddox (Lantizia) 

Attached to Project - OpenWrt/LEDE Project
Summary - OpenWRT 21.02.1 doesn't completely boot on RaidSonic IB-NAS4220-B
Task Type - Bug Report
Category - Base system
Status - Unconfirmed
Assigned To - 
Operating System - All
Severity - Low
Priority - Very Low
Reported Version - Trunk
Due in Version - Undecided
Due Date - Undecided
Details - **Device:** RaidSonic IB-NAS4220-B
**OpenWRT version:** 21.02.1

**Problem:**

When OpenWRT 21.02.1 is flashed to the RaidSonic IB-NAS4220-B by using the following file...
[[https://downloads.openwrt.org/releases/21.02.1/targets/gemini/generic/openwrt-21.02.1-gemini-raidsonic_ib-4220-b-squashfs-factory.bin|https://downloads.openwrt.org/releases/21.02.1/targets/gemini/generic/openwrt-21.02.1-gemini-raidsonic_ib-4220-b-squashfs-factory.bin]]

It results in a system which will not boot and hangs on...
[    7.047812] Waiting for root device /dev/mtdblock3...

**Steps to reproduce:**

Once you've extracted the .bin file (linked to above... which is actually a .tar.gz in disguise)... you'll have files zImage, rd.gz and hddapp.tgz which you can put on a TFTP server so that the boot loader can access them.

**e.g. on my Linux desktop, I might download, extract and host those files via a temporary TFTP server (using dnsmasq)...**

mkdir ~/owrt-tftp
cd ~/owrt-tftp
wget -O- https://downloads.openwrt.org/releases/21.02.1/targets/gemini/generic/openwrt-21.02.1-gemini-raidsonic_ib-4220-b-squashfs-factory.bin | tar -zx
sudo dnsmasq -i enx001060b1d868 --enable-tftp --tftp-root="$(pwd)" -d -p0


**Then via serial this is how flashing it and booting it went...**

Storlink SL351x Boot Loader [Linux], version 1.0.9
Built by linux, 17:27:04, Dec 19 2007

Processor: SL3516c2
CPU Rate: 300000000
AHB Bus Clock:150MHz    Ratio:2/1
MAC 1 Address: 00:01:D2:07:0D:BD
MAC 2 Address: 00:50:C2:2B:D0:02
inet addr: 192.168.0.200/255.255.255.0
==> enter ^C to abort booting within 3 seconds ...... 
PHY 0 Addr 1 Vendor ID: 0x01410e11
mii_write: phy_addr=0x1 reg_addr=0x4 value=0x5e1 
mii_write: phy_addr=0x1 reg_addr=0x9 value=0x300 
mii_write: phy_addr=0x1 reg_addr=0x0 value=0x1200 
mii_write: phy_addr=0x1 reg_addr=0x0 value=0x9200 
mii_write: phy_addr=0x1 reg_addr=0x0 value=0x1200 

                              Boot Menu
==============================================================================
0: Reboot                                   1: Start the Kernel Code
2: List Image                               3: Delete Image
4: Create New Image                         5: Enter Command Line Interface
6: Set IP Address                           7: Set MAC Address
8: Show Configuration                       F: Create Default FIS
X: Upgrade Boot                             Y: Upgrade Kernel
Z: Upgrade Firmware                         A: Upgrade Application
R: Upgrade RAM Disk                         

=> Select: 8

Processor: SL3516c2
CPU Rate: 300000000
AHB Bus Clock:150MHz    Ratio:2/1
MAC 1 Address: 00:01:D2:07:0D:BD
MAC 2 Address: 00:50:C2:2B:D0:02
inet addr: 192.168.0.200/255.255.255.0

                              Boot Menu
==============================================================================
0: Reboot                                   1: Start the Kernel Code
2: List Image                               3: Delete Image
4: Create New Image                         5: Enter Command Line Interface
6: Set IP Address                           7: Set MAC Address
8: Show Configuration                       F: Create Default FIS
X: Upgrade Boot                             Y: Upgrade Kernel
Z: Upgrade Firmware                         A: Upgrade Application
R: Upgrade RAM Disk                         

=> Select: Y



1  : Download by X-modem
2  : Download by TFTP
ESC: Return 
==> 2
TFTP Server IP Address: 192.168.0.66
Image Path and name(e.g. /images/zImage): zImage
TFTP Download zImage from 192.168.0.66 .....................

Successful to download by TFTP! Size=2097152

Do not power-off this device while flash programming is proceeding!!
==> enter ^C to abort program flash 0x30020000 within 3 seconds ...... 

Erase flash (0x30020000): Size=3145728 ........................ OK!
Program flash (0x30020000): Size=2097152 ................ OK!

Start Update FIS data......
Erase flash (0x30fe0000): Size=131072 . OK!
Program flash (0x30fe0000): Size=5120 . OK!
Successful to upgrade (0x30020000)!

                              Boot Menu
==============================================================================
0: Reboot                                   1: Start the Kernel Code
2: List Image                               3: Delete Image
4: Create New Image                         5: Enter Command Line Interface
6: Set IP Address                           7: Set MAC Address
8: Show Configuration                       F: Create Default FIS
X: Upgrade Boot                             Y: Upgrade Kernel
Z: Upgrade Firmware                         A: Upgrade Application
R: Upgrade RAM Disk                         

=> Select: A



1  : Download by X-modem
2  : Download by TFTP
ESC: Return 
==> 2
TFTP Server IP Address: 192.168.0.66
Image Path and name(e.g. /hddapp.tgz): hddapp.tgz
TFTP Download hddapp.tgz from 192.168.0.66 .......................................................

Successful to download by TFTP! Size=6291456

Do not power-off this device while flash programming is proceeding!!
==> enter ^C to abort program flash 0x30920000 within 3 seconds ...... 

Erase flash (0x30920000): Size=6291456 ................................................ OK!
Program flash (0x30920000): Size=6291456 ................................................ OK!

Start Update FIS data......
Erase flash (0x30fe0000): Size=131072 . OK!
Program flash (0x30fe0000): Size=5120 . OK!
Successful to upgrade (0x30920000)!

                              Boot Menu
==============================================================================
0: Reboot                                   1: Start the Kernel Code
2: List Image                               3: Delete Image
4: Create New Image                         5: Enter Command Line Interface
6: Set IP Address                           7: Set MAC Address
8: Show Configuration                       F: Create Default FIS
X: Upgrade Boot                             Y: Upgrade Kernel
Z: Upgrade Firmware                         A: Upgrade Application
R: Upgrade RAM Disk                         

=> Select: R



1  : Download by X-modem
2  : Download by TFTP
ESC: Return 
==> 2
TFTP Server IP Address: 192.168.0.66
Image Path and name(e.g. /images/zImage): rd.gz
TFTP Download rd.gz from 192.168.0.66 ...........

Successful to download by TFTP! Size=955102

Do not power-off this device while flash programming is proceeding!!
==> enter ^C to abort program flash 0x30320000 within 3 seconds ...... 

Erase flash (0x30320000): Size=6291456 ................................................ OK!
Program flash (0x30320000): Size=955102 ........ OK!

Start Update FIS data......
Erase flash (0x30fe0000): Size=131072 . OK!
Program flash (0x30fe0000): Size=5120 . OK!
Successful to upgrade (0x30320000)!

                              Boot Menu
==============================================================================
0: Reboot                                   1: Start the Kernel Code
2: List Image                               3: Delete Image
4: Create New Image                         5: Enter Command Line Interface
6: Set IP Address                           7: Set MAC Address
8: Show Configuration                       F: Create Default FIS
X: Upgrade Boot                             Y: Upgrade Kernel
Z: Upgrade Firmware                         A: Upgrade Application
R: Upgrade RAM Disk                         

=> Select: 0

Reboot system...

Failed to change IP address due to illegal value
Failed to change IP Gateway due to illegal value




Storlink SL351x Boot Loader [Linux], version 1.0.9
Built by linux, 17:27:04, Dec 19 2007

Processor: SL3516c2
CPU Rate: 300000000
AHB Bus Clock:150MHz    Ratio:2/1
MAC 1 Address: 00:01:D2:07:0D:BD
MAC 2 Address: 00:50:C2:2B:D0:02
inet addr: 192.168.0.200/255.255.255.0
==> enter ^C to abort booting within 3 seconds ...... 
Load Kern image from 0x30020000 to 0x1600000 size 2097152
Load Ramdisk image from 0x30320000 to 0x800000 size 955102
[    0.000000] Booting Linux on physical CPU 0x0
[    0.000000] Linux version 5.4.154 (builder at buildhost) (gcc version 8.4.0 (OpenWrt GCC 8.4.0 r16325-88151b8303)) #0 PREEMPT Sun Oct 24 09:01:35 2021
[    0.000000] CPU: FA526 [66015261] revision 1 (ARMv4), cr=0000397f
[    0.000000] CPU: VIVT data cache, VIVT instruction cache
[    0.000000] OF: fdt: Machine model: Raidsonic NAS IB-4220-B
[    0.000000] Memory policy: Data cache writeback
[    0.000000] cma: Reserved 16 MiB at 0x07000000
[    0.000000] Built 1 zonelists, mobility grouping on.  Total pages: 32480
[    0.000000] Kernel command line: console=ttyS0,19200n8 root=/dev/mtdblock3 rw rootfstype=squashfs,jffs2 rootwait
[    0.000000] Dentry cache hash table entries: 16384 (order: 4, 65536 bytes, linear)
[    0.000000] Inode-cache hash table entries: 8192 (order: 3, 32768 bytes, linear)
[    0.000000] mem auto-init: stack:off, heap alloc:off, heap free:off
[    0.000000] Memory: 103620K/131072K available (6060K kernel code, 217K rwdata, 1644K rodata, 1024K init, 238K bss, 11068K reserved, 16384K cma-reserved, 0K highmem)
[    0.000000] SLUB: HWalign=32, Order=0-3, MinObjects=0, CPUs=1, Nodes=1
[    0.000000] rcu: Preemptible hierarchical RCU implementation.
[    0.000000] 	Tasks RCU enabled.
[    0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 10 jiffies.
[    0.000000] NR_IRQS: 16, nr_irqs: 16, preallocated irqs: 16
[    0.000000] random: get_random_bytes called from start_kernel+0x29c/0x498 with crng_init=0
[    0.000000] clocksource: FTTMR010-TIMER2: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 76450417870 ns
[    0.000029] sched_clock: 32 bits at 25MHz, resolution 40ns, wraps every 85899345900ns
[    0.000127] Switching to timer-based delay loop, resolution 40ns
[    0.000761] Console: colour dummy device 80x30
[    0.000918] Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=250000)
[    0.000981] pid_max: default: 32768 minimum: 301
[    0.001859] Mount-cache hash table entries: 1024 (order: 0, 4096 bytes, linear)
[    0.001950] Mountpoint-cache hash table entries: 1024 (order: 0, 4096 bytes, linear)
[    0.005045] CPU: Testing write buffer coherency: ok
[    0.009061] Setting up static identity map for 0x100000 - 0x100048
[    0.009648] rcu: Hierarchical SRCU implementation.
[    0.022636] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns
[    0.022712] futex hash table entries: 256 (order: -1, 3072 bytes, linear)
[    0.026641] pinctrl core: initialized pinctrl subsystem
[    0.033627] NET: Registered protocol family 16
[    0.041654] DMA: preallocated 256 KiB pool for atomic coherent allocations
[    0.053778] No ATAGs?
[    0.064247] pinctrl-gemini 40000000.syscon:pinctrl: detected 3516 chip variant
[    0.064320] pinctrl-gemini 40000000.syscon:pinctrl: GLOBAL MISC CTRL at boot: 0x83c22037
[    0.064367] pinctrl-gemini 40000000.syscon:pinctrl: flash pin is set
[    0.065952] pinctrl-gemini 40000000.syscon:pinctrl: initialized Gemini pin control driver
[    0.257678] pl08xdmac 67000000.dma-controller: FTDMAC020 1.16 rel 1
[    0.257740] pl08xdmac 67000000.dma-controller: FTDMAC020 4 channels, has built-in bridge, AHB0 and AHB1, supports linked lists
[    0.258001] pl08xdmac 67000000.dma-controller: initialized 4 virtual memcpy channels
[    0.259615] pl08xdmac 67000000.dma-controller: DMA: PL080 rev0 at 0x67000000 irq 28
[    0.260081] Gemini SoC 3516 revision c2, set arbitration 00200030
[    0.268005] vgaarb: loaded
[    0.271989] SCSI subsystem initialized
[    0.287129] workqueue: max_active 576 requested for napi_workq is out of range, clamping between 1 and 512
[    0.293429] clocksource: Switched to clocksource FTTMR010-TIMER2
[    0.384678] thermal_sys: Registered thermal governor 'step_wise'
[    0.385986] NET: Registered protocol family 2
[    0.386509] IP idents hash table entries: 2048 (order: 2, 16384 bytes, linear)
[    0.389778] tcp_listen_portaddr_hash hash table entries: 512 (order: 0, 4096 bytes, linear)
[    0.389942] TCP established hash table entries: 1024 (order: 0, 4096 bytes, linear)
[    0.390045] TCP bind hash table entries: 1024 (order: 0, 4096 bytes, linear)
[    0.390127] TCP: Hash tables configured (established 1024 bind 1024)
[    0.390624] UDP hash table entries: 256 (order: 0, 4096 bytes, linear)
[    0.390738] UDP-Lite hash table entries: 256 (order: 0, 4096 bytes, linear)
[    0.391643] NET: Registered protocol family 1
[    0.391784] PCI: CLS 0 bytes, default 32
[    0.400259] workingset: timestamp_bits=14 max_order=15 bucket_order=1
[    0.513343] squashfs: version 4.0 (2009/01/31) Phillip Lougher
[    0.513390] jffs2: version 2.2 (NAND) (SUMMARY) (LZMA) (RTIME) (CMODE_PRIORITY) (c) 2001-2006 Red Hat, Inc.
[    0.699187] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 251)
[    0.699929] io scheduler mq-deadline registered
[    0.699976] io scheduler kyber registered
[    0.706151] ftgpio010-gpio 4d000000.gpio: FTGPIO010 @(ptrval) registered
[    0.711817] ftgpio010-gpio 4e000000.gpio: FTGPIO010 @(ptrval) registered
[    0.714713] ftgpio010-gpio 4f000000.gpio: FTGPIO010 @(ptrval) registered
[    0.724974] Serial: 8250/16550 driver, 16 ports, IRQ sharing enabled
[    0.753268] printk: console [ttyS0] disabled
[    0.754208] 42000000.serial: ttyS0 at MMIO 0x42000000 (irq = 18, base_baud = 3000000) is a 16550A
[    3.632841] printk: console [ttyS0] enabled
[    3.665802] Loading iSCSI transport class v2.0-870.
[    3.704501] gemini_sata_bridge 46000000.sata: SATA ID 00000e00, PHY ID: 01000100
[    3.750173] gemini_sata_bridge 46000000.sata: set up the Gemini IDE/SATA nexus
[    3.795569] pata_ftide010 63000000.ide: set up Gemini PATA0
[    3.830157] pata_ftide010 63000000.ide: device ID 00000500, irq 26, reg [mem 0x63000000-0x63000fff]
[    3.885078] pata_ftide010 63000000.ide: SATA0 (master) start
[    4.093523] gemini_sata_bridge 46000000.sata: SATA0 PHY ready
[    4.128025] pata_ftide010 63000000.ide: brought 1 bridges online
[    4.170153] scsi host0: pata_ftide010
[    4.195220] ata1: SATA max UDMA/133 irq 26
[    4.222317] pata_ftide010 63400000.ide: set up Gemini PATA1
[    4.256481] pata_ftide010 63400000.ide: device ID 00000500, irq 27, reg [mem 0x63400000-0x63400fff]
[    4.311250] pata_ftide010 63400000.ide: SATA1 (master) start
[    4.429812] ata1.00: HPA detected: current 976771055, native 976773168
[    4.469031] ata1.00: ATA-8: WDC WD5000AAJS-22YFA0, 12.01C02, max UDMA/133
[    4.509766] ata1.00: 976771055 sectors, multi 0: LBA48 NCQ (depth 0/32)
[    4.549510] gemini_sata_bridge 46000000.sata: SATA1 PHY ready
[    4.584596] pata_ftide010 63400000.ide: brought 1 bridges online
[    4.623998] scsi 0:0:0:0: Direct-Access     ATA      WDC WD5000AAJS-2 1C02 PQ: 0 ANSI: 5
[    4.677790] scsi host1: pata_ftide010
[    4.701723] ata2: SATA max UDMA/133 irq 27
[    4.733205] sd 0:0:0:0: [sda] 976771055 512-byte logical blocks: (500 GB/466 GiB)
[    4.782402] physmap-flash 30000000.flash: no enabled pin control state
[    4.825925] physmap-flash 30000000.flash: no disabled pin control state
[    4.866174] sd 0:0:0:0: [sda] Write Protect is off
[    4.895772] sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
[    4.950469] physmap-flash 30000000.flash: initialized Gemini-specific physmap control
[    5.022102] physmap-flash 30000000.flash: physmap platform flash device: [mem 0x30000000-0x30ffffff]
[    5.079830] ata2.00: HPA detected: current 976771055, native 976773168
[    5.121754] 30000000.flash: Found 1 x16 devices at 0x0 in 16-bit bank. Manufacturer ID 0x000001 Chip ID 0x002101
[    5.183973] sd 0:0:0:0: [sda] Attached SCSI disk
[    5.213017] ata2.00: ATA-7: SAMSUNG HD502IJ, 1AA01113, max UDMA7
[    5.249280] Amd/Fujitsu Extended Query Table at 0x0040
[    5.280265] ata2.00: 976771055 sectors, multi 0: LBA48 NCQ (depth 0/32)
[    5.320148]   Amd/Fujitsu Extended Query version 1.3.
[    5.351156] number of CFI chips: 1
[    5.373939] scsi 1:0:0:0: Direct-Access     ATA      SAMSUNG HD502IJ  1113 PQ: 0 ANSI: 5
[    5.427677] Searching for RedBoot partition table in 30000000.flash at offset 0x0
[    5.479262] sd 1:0:0:0: [sdb] 976771055 512-byte logical blocks: (500 GB/466 GiB)
[    5.548038] No RedBoot partition table detected in 30000000.flash
[    5.585180] sd 1:0:0:0: [sdb] Write Protect is off
[    5.618553] Searching for RedBoot partition table in 30000000.flash at offset 0x0
[    5.664307] sd 1:0:0:0: [sdb] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
[    5.739093] No RedBoot partition table detected in 30000000.flash
[    5.800769] sd 1:0:0:0: [sdb] Attached SCSI disk
[    5.831336] mdio-gpio mdio: failed to get alias id
[    5.862316] libphy: GPIO Bitbanged MDIO: probed
[    5.889523] random: fast init done
[    5.916359] libphy: Fixed MDIO Bus: probed
[    5.988044] gmac-gemini 60000000.ethernet: Ethernet device ID: 0x000, revision 0x1
[    6.035869] gemini-ethernet-port 60008000.ethernet-port: probe 60008000.ethernet-port ID 0
[    6.087301] gemini-ethernet-port 60008000.ethernet-port: using a random ethernet address
[    6.139202] gemini-ethernet-port 60008000.ethernet-port eth0: irq 31, DMA @ 0x0x60008000, GMAC @ 0x0x6000a000
[    6.293890] Marvell 88E1118 gpio-0:01: attached PHY driver [Marvell 88E1118] (mii_bus:phy_addr=gpio-0:01, irq=POLL)
[    6.358786] gemini-ethernet-port 6000c000.ethernet-port: probe 6000c000.ethernet-port ID 1
[    6.410065] gemini-ethernet-port 6000c000.ethernet-port: using a random ethernet address
[    6.461878] gemini-ethernet-port 6000c000.ethernet-port eth1: irq 32, DMA @ 0x0x6000c000, GMAC @ 0x0x6000e000
[    6.522095] gemini-ethernet-port 6000c000.ethernet-port eth1: PHY init failed, deferring to ifup time
[    6.582733] rtc-ftrtc010 45000000.rtc: registered as rtc0
[    6.619257] gemini-poweroff 4b000000.power-controller: Gemini poweroff driver registered
[    6.731823] ftwdt010-wdt 41000000.watchdog: FTWDT010 watchdog driver enabled
[    6.785919] NET: Registered protocol family 10
[    6.818572] Segment Routing with IPv6
[    6.841325] NET: Registered protocol family 17
[    6.868323] bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this.
[    6.946599] 8021q: 802.1Q VLAN Support v1.8
[    6.983395] rtc-ftrtc010 45000000.rtc: setting system clock to 2019-11-08T09:18:18 UTC (1573204698)
[    7.039504] Waiting for root device /dev/mtdblock3...

Some seconds later you'll also get...

[  124.733643] random: crng init done

But ultimately I think it's still waiting on that 'Waiting for root device' message.

Additionally I also went back to the original firmware that this device came with (flashing it via the same method as above)... this worked (I also made a RAID 1 from the two disks this NAS has, formatted them... just to see if it would make a difference).  Then I tried to flash the 21.02.1 .bin file again, but this time via the original firmware's own upgrade web page... which flashed it perfectly fine.  But I could see via serial that booting up got stuck at the same point.

I'm about to try the ext4 version of that .bin file (which is oddly named .bin.gz... lovely consistency!) and see if I get the same problem.

Any tips most welcome... but this file just doesn't work.

More information can be found at the following URL:
https://bugs.openwrt.org/index.php?do=details&task_id=4137

You are receiving this message because you have requested it from the Flyspray bugtracking system.  If you did not expect this message or don't want to receive mails in future, you can change your notification settings at the URL shown above.



More information about the openwrt-bugs mailing list