** File not found /boot/uImage **

I just updated my U-Boot to 2017.07-tld-1 (Oct 24 2017 - 22:32:36 -0700) and I'm occasionally getting the following (from netconsole):

starting USB...
USB0:   USB EHCI 1.00
scanning bus 0 for devices... 4 USB Device(s) found
       scanning usb for storage devices...
Use USB retry period from the environment: 15 second(s)

Use USB retry period from the environment: 15 second(s)
2 Storage Device(s) found
** File not found /boot/uImage **
1 bytes read in 891 ms (0 Bytes/s)
Found bootable drive on usb 1:1
3242786 bytes read in 1104 ms (2.8 MiB/s)
7638793 bytes read in 962 ms (7.6 MiB/s)
## Booting kernel from Legacy Image at 00800000 ...
   Image Name:   Linux-4.8.3-kirkwood-tld-1
   Created:      2016-11-05  19:15:48 UTC
   Image Type:   ARM Linux Kernel Image (uncompressed)
   Data Size:    3242722 Bytes = 3.1 MiB
   Load Address: 00008000
   Entry Point:  00008000
   Verifying Checksum ... OK
## Loading init Ramdisk from Legacy Image at 01100000 ...
   Image Name:   initramfs-4.8.3-kirkwood-tld-1
   Created:      2017-12-04   2:44:52 UTC
   Image Type:   ARM Linux RAMDisk Image (gzip compressed)
   Data Size:    7638729 Bytes = 7.3 MiB
   Load Address: 00000000
   Entry Point:  00000000
   Verifying Checksum ... OK


Starting kernel ...

And then I cannot login. After many attempts, I finally got a serial port capture of the boot process when it fails:

U-Boot 2017.07-tld-1 (Oct 24 2017 - 22:32:36 -0700)
Seagate FreeAgent DockStar

SoC:   Kirkwood 88F6281_A0
DRAM:  128 MiB
WARNING: Caches not enabled
NAND:  256 MiB
In:    serial
Out:   serial
Err:   serial
Net:   egiga0
88E1116 Initialized on egiga0
Using egiga0 device
host 192.168.0.100 is alive
Uncompressing Linux... done, booting the kernel.
[    0.000000] Booting Linux on physical CPU 0x0
[    0.000000] Linux version 4.8.3-kirkwood-tld-1 (root@tldDebian) (gcc version 4.9.2 (Debian 4.9.2-10) ) #1 PREEMPT Thu Oct 20 23:33:18 PDT 2016
[    0.000000] CPU: Feroceon 88FR131 [56251311] revision 1 (ARMv5TE), cr=0005397f
[    0.000000] CPU: VIVT data cache, VIVT instruction cache
[    0.000000] OF: fdt:Machine model: Seagate FreeAgent Dockstar
[    0.000000] Memory policy: Data cache writeback
[    0.000000] Built 1 zonelists in Zone order, mobility grouping on.  Total pages: 32512
[    0.000000] Kernel command line: console=ttyS0,115200 root=/dev/sdb1 rootdelay=10 rootfstype=ext3 mtdparts=orion_nand:1M(u-boot),4M(uImage),32M(rootfs),-(data) zswap.enabled=1 zswap.compressor=lz4
[    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] Memory: 113100K/131072K available (6149K kernel code, 614K rwdata, 1664K rodata, 308K init, 318K bss, 17972K reserved, 0K cma-reserved)
[    0.000000] Virtual kernel memory layout:
[    0.000000]     vector  : 0xffff0000 - 0xffff1000   (   4 kB)
[    0.000000]     fixmap  : 0xffc00000 - 0xfff00000   (3072 kB)
[    0.000000]     vmalloc : 0xc8800000 - 0xff800000   ( 880 MB)
[    0.000000]     lowmem  : 0xc0000000 - 0xc8000000   ( 128 MB)
[    0.000000]     modules : 0xbf000000 - 0xc0000000   (  16 MB)
[    0.000000]       .text : 0xc0008000 - 0xc0609718   (6150 kB)
[    0.000000]       .init : 0xc07dd000 - 0xc082a000   ( 308 kB)
[    0.000000]       .data : 0xc082a000 - 0xc08c391c   ( 615 kB)
[    0.000000]        .bss : 0xc08c391c - 0xc0913170   ( 319 kB)
[    0.000000] Preemptible hierarchical RCU implementation.
[    0.000000]  Build-time adjustment of leaf fanout to 32.
[    0.000000] NR_IRQS:16 nr_irqs:16 16
[    0.000000] clocksource: orion_clocksource: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 9556302233 ns
[    0.000010] sched_clock: 32 bits at 200MHz, resolution 5ns, wraps every 10737418237ns
[    0.000544] Console: colour dummy device 80x30
[    0.000580] Calibrating delay loop... 1191.11 BogoMIPS (lpj=5955584)
[    0.090108] pid_max: default: 32768 minimum: 301
[    0.090320] Security Framework initialized
[    0.090477] Mount-cache hash table entries: 1024 (order: 0, 4096 bytes)
[    0.090499] Mountpoint-cache hash table entries: 1024 (order: 0, 4096 bytes)
[    0.091628] CPU: Testing write buffer coherency: ok
[    0.092079] Setting up static identity map for 0x8200 - 0x8258
[    0.092336] mvebu-soc-id: MVEBU SoC ID=0x6281, Rev=0x2
[    0.096496] devtmpfs: initialized
[    0.102011] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns
[    0.102291] prandom: seed boundary self test passed
[    0.106051] prandom: 100 self tests passed
[    0.106064] pinctrl core: initialized pinctrl subsystem
[    0.107360] NET: Registered protocol family 16
[    0.107840] DMA: preallocated 256 KiB pool for atomic coherent allocations
[    0.130116] cpuidle: using governor ladder
[    0.160111] cpuidle: using governor menu
[    0.160549] Feroceon L2: Enabling L2
[    0.160598] Feroceon L2: Cache support initialised.
[    0.161032] [Firmware Info]: /ocp@f1000000/ethernet-controller@72000/ethernet0-port@0: local-mac-address is not set
[    0.165799] No ATAGs?
[    0.201628] vgaarb: loaded
[    0.201984] usbcore: registered new interface driver usbfs
[    0.202103] usbcore: registered new interface driver hub
[    0.202168] usbcore: registered new device driver usb
[    0.203331] clocksource: Switched to clocksource orion_clocksource
[    0.249999] VFS: Disk quotas dquot_6.6.0
[    0.250096] VFS: Dquot-cache hash table entries: 1024 (order 0, 4096 bytes)
[    0.262169] NET: Registered protocol family 2
[    0.263026] TCP established hash table entries: 1024 (order: 0, 4096 bytes)
[    0.263053] TCP bind hash table entries: 1024 (order: 0, 4096 bytes)
[    0.263075] TCP: Hash tables configured (established 1024 bind 1024)
[    0.263140] UDP hash table entries: 256 (order: 0, 4096 bytes)
[    0.263167] UDP-Lite hash table entries: 256 (order: 0, 4096 bytes)
[    0.263433] NET: Registered protocol family 1
[    0.263988] RPC: Registered named UNIX socket transport module.
[    0.264001] RPC: Registered udp transport module.
[    0.264009] RPC: Registered tcp transport module.
[    0.264017] RPC: Registered tcp NFSv4.1 backchannel transport module.
[    0.264284] Unpacking initramfs...
[    0.949209] Freeing initrd memory: 7460K (c73c6000 - c7b0f000)
[    0.949366] NetWinder Floating Point Emulator V0.97 (double precision)
[    0.950434] futex hash table entries: 256 (order: -1, 3072 bytes)
[    0.950484] audit: initializing netlink subsys (disabled)
[    0.950539] audit: type=2000 audit(0.940:1): initialized
[    0.950867] Initialise system trusted keyrings
[    0.951088] workingset: timestamp_bits=30 max_order=15 bucket_order=0
[    0.951183] zbud: loaded
[    0.952128] NFS: Registering the id_resolver key type
[    0.952155] Key type id_resolver registered
[    0.952165] Key type id_legacy registered
[    0.952182] nfs4filelayout_init: NFSv4 File Layout Driver Registering...
[    0.952193] Installing knfsd (copyright (C) 1996 okir@monad.swb.de).
[    0.952455] jffs2: version 2.2. (NAND) (SUMMARY)  © 2001-2006 Red Hat, Inc.
[    0.952747] orangefs_init: called with debug mask: :none: :0:
[    0.953116] orangefs: module version upstream loaded
[    0.953473] Key type big_key registered
[    2.553339] random: fast init done
[    5.066057] Key type asymmetric registered
[    5.066074] Asymmetric key parser 'x509' registered
[    5.066161] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 249)
[    5.066173] io scheduler noop registered
[    5.066182] io scheduler deadline registered
[    5.066216] io scheduler cfq registered (default)
[    5.067539] kirkwood-pinctrl f1010000.pin-controller: registered pinctrl driver
[    5.068463] irq: Cannot allocate irq_descs @ IRQ32, assuming pre-allocated
[    5.069132] irq: Cannot allocate irq_descs @ IRQ64, assuming pre-allocated
[    5.069650] mv_xor f1060800.xor: Marvell shared XOR driver
[    5.124095] mv_xor f1060800.xor: Marvell XOR (Registers Mode): ( xor cpy intr )
[    5.124336] mv_xor f1060900.xor: Marvell shared XOR driver
[    5.184070] mv_xor f1060900.xor: Marvell XOR (Registers Mode): ( xor cpy intr )
[    5.184579] Serial: 8250/16550 driver, 2 ports, IRQ sharing disabled
[    5.186022] console [ttyS0] disabled
[    5.186090] f1012000.serial: ttyS0 at MMIO 0xf1012000 (irq = 25, base_baud = 12500000) is a 16550A
[    5.781673] console [ttyS0] enabled
[    5.786507] nand: device found, Manufacturer ID: 0x2c, Chip ID: 0xda
[    5.792891] nand: Micron MT29F2G08AAD
[    5.796610] nand: 256 MiB, SLC, erase size: 128 KiB, page size: 2048, OOB size: 64
[    5.804239] Scanning device for bad blocks
[    5.965874] 4 cmdlinepart partitions found on MTD device orion_nand
[    5.972174] Creating 4 MTD partitions on "orion_nand":
[    5.977363] 0x000000000000-0x000000100000 : "u-boot"
[    5.983832] 0x000000100000-0x000000500000 : "uImage"
[    5.990220] 0x000000500000-0x000002500000 : "rootfs"
[    5.996808] 0x000002500000-0x000010000000 : "data"
[    6.005842] libphy: Fixed MDIO Bus: probed
[    6.010086] libphy: mdio_driver_register: mv88e6085
[    6.015427] libphy: orion_mdio_bus: probed
[    6.019558] [Firmware Warn]: /ocp@f1000000/mdio-bus@72004/ethernet-phy@0: Whitelisted compatible string. Please remove
[    6.035387] mv643xx_eth: MV-643xx 10/100/1000 ethernet driver version 1.4
[    7.134009] mv643xx_eth_port mv643xx_eth_port.0 eth0: port 0 with MAC address 00:10:75:1a:9a:d3
[    7.142875] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver
[    7.149491] ehci-pci: EHCI PCI platform driver
[    7.154040] ehci-orion: EHCI orion driver
[    7.158245] orion-ehci f1050000.ehci: EHCI Host Controller
[    7.163822] orion-ehci f1050000.ehci: new USB bus registered, assigned bus number 1
[    7.171723] orion-ehci f1050000.ehci: irq 29, io mem 0xf1050000
[    7.203367] orion-ehci f1050000.ehci: USB 2.0 started, EHCI 1.00
[    7.209653] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002
[    7.216506] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[    7.223787] usb usb1: Product: EHCI Host Controller
[    7.228685] usb usb1: Manufacturer: Linux 4.8.3-kirkwood-tld-1 ehci_hcd
[    7.235348] usb usb1: SerialNumber: f1050000.ehci
[    7.240906] hub 1-0:1.0: USB hub found
[    7.244770] hub 1-0:1.0: 1 port detected
[    7.249735] mousedev: PS/2 mouse device common for all mice
[    7.603365] usb 1-1: new high-speed USB device number 2 using orion-ehci
[    7.795105] usb 1-1: New USB device found, idVendor=05e3, idProduct=0608
[    7.801855] usb 1-1: New USB device strings: Mfr=0, Product=1, SerialNumber=0
[    7.809063] usb 1-1: Product: USB2.0 Hub
[    7.813939] hub 1-1:1.0: USB hub found
[    7.818110] hub 1-1:1.0: 4 ports detected
[    8.143360] usb 1-1.2: new high-speed USB device number 3 using orion-ehci
[    8.294604] usb 1-1.2: New USB device found, idVendor=0781, idProduct=5567
[    8.301528] usb 1-1.2: New USB device strings: Mfr=1, Product=2, SerialNumber=3
[    8.308910] usb 1-1.2: Product: Cruzer Blade
[    8.313199] usb 1-1.2: Manufacturer: SanDisk
[    8.317510] usb 1-1.2: SerialNumber: 4C531001560901104514
[    8.323366] rtc-mv f1010300.rtc: internal RTC not ticking
[    8.328978] i2c /dev entries driver
[    8.334351] hidraw: raw HID events driver (C) Jiri Kosina
[    8.340397] drop_monitor: Initializing network drop monitor service
[    8.347036] NET: Registered protocol family 17
[    8.351708] Key type dns_resolver registered
[    8.356910] registered taskstats version 1
[    8.361029] Loading compiled-in X.509 certificates
[    8.376130] zswap: loaded using pool lz4/zbud
[    8.399569] Key type encrypted registered
[    8.404245] hctosys: unable to open rtc device (rtc0)
[    8.411581] Freeing unused kernel memory: 308K (c07dd000 - c082a000)
[    8.418141] This architecture does not have kernel memory protection.
Loading, please wait...
[    8.443789] usb 1-1.3: new high-speed USB device number 4 using orion-ehci
[    8.505973] systemd-udevd[95]: starting version 215
[    8.605997] usb 1-1.3: New USB device found, idVendor=1058, idProduct=107c
[    8.612938] usb 1-1.3: New USB device strings: Mfr=2, Product=3, SerialNumber=1
[    8.633445] usb 1-1.3: Product: Elements 107C
[    8.637843] usb 1-1.3: Manufacturer: Western Digital
[    8.642834] usb 1-1.3: SerialNumber: 574343344530484846444345
[    8.730799] SCSI subsystem initialized
[    8.762154] usb-storage 1-1.2:1.0: USB Mass Storage device detected
[    8.815958] scsi host0: usb-storage 1-1.2:1.0
[    8.827539] usb-storage 1-1.3:1.0: USB Mass Storage device detected
[    8.868228] scsi host1: usb-storage 1-1.3:1.0
[    8.881486] usbcore: registered new interface driver usb-storage
[    8.910920] usbcore: registered new interface driver uas
[    9.844458] scsi 0:0:0:0: Direct-Access     SanDisk  Cruzer Blade     1.00 PQ: 0 ANSI: 6
[    9.861807] sd 0:0:0:0: [sda] 61341696 512-byte logical blocks: (31.4 GB/29.3 GiB)
[    9.871648] sd 0:0:0:0: [sda] Write Protect is off
[    9.877263] sd 0:0:0:0: [sda] Write cache: disabled, read cache: enabled, doesn't support DPO or FUA
[    9.893263]  sda: sda1
[    9.899263] sd 0:0:0:0: [sda] Attached SCSI removable disk
[    9.909093] sd 0:0:0:0: Attached scsi generic sg0 type 0
[    9.928336] scsi 1:0:0:0: Direct-Access     WD       Elements 107C    1065 PQ: 0 ANSI: 6
[    9.943573] sd 1:0:0:0: Attached scsi generic sg1 type 0
[    9.949156] sd 1:0:0:0: [sdb] Spinning up disk...
[   11.043357] ........Begin: Loading essential drivers ... done.
Begin: Running /scripts/init-premount ... done.
Begin: Mounting root file system ... Begin: Running /scripts/local-top ... done.
Begin: Running /scripts/local-premount ... done.
Begin: Waiting for root file system ... .ready
[   19.586661] sd 1:0:0:0: [sdb] 976746240 4096-byte logical blocks: (4.00 TB/3.64 TiB)
[   19.595386] sd 1:0:0:0: [sdb] Write Protect is off
[   19.601132] sd 1:0:0:0: [sdb] No Caching mode page found
[   19.606536] sd 1:0:0:0: [sdb] Assuming drive cache: write through
[   19.710280]  sdb: sdb1
[   19.726171] sd 1:0:0:0: [sdb] Attached SCSI disk
Begin: Running /scripts/local-block ... done.
done.
modprobe: module ext3 not found in modules.dep
Begin: Will now check root file system ... fsck from util-linux 2.25.2
fsck: error 2 (No such file or directory) while executing fsck.ext4 for /dev/sdb1
fsck exited with status code 8
done.
Warning: File system check failed but did not detect errors
[   25.164210] EXT4-fs (sdb1): couldn't mount as ext3 due to feature incompatibilities
mount: mounting /dev/sdb1 on /root failed: Invalid argument
done.
Target filesystem doesn't have requested /sbin/init.
Begin: Running /scripts/local-bottom ... done.
Begin: Running /scripts/init-bottom ... mount: mounting /dev on /root/dev failed: No such file or directory
done.
No init found. Try passing init= bootarg.
modprobe: module i8042 not found in modules.dep
[   25.315797] uhci_hcd: USB Universal Host Controller Interface driver
[   25.330790] ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver
[   25.346575] usbcore: registered new interface driver usbhid
[   25.352182] usbhid: USB HID core driver


BusyBox v1.22.1 (Debian 1:1.22.0-9+deb8u1) built-in shell (ash)
Enter 'help' for a list of built-in commands.

/bin/sh: can't access tty; job control turned off
(initramfs)

I don't know what to make of it.

Notice the ** File not found /boot/uImage ** message from netconsole. I can't say for sure that it has anything to do with the U-Boot update since my Dockstar was having the occasional booting problem for a while now. The boot device should be a flash drive on usb 0:1, not usb 1:1 as shown above. The usb 1:1 device is a 4TB ext4 formatted drive with no /boot directory and no rootfs label, so I can't understand why it's being picked up as the boot device.

At this point the only way to proceed is to push the reset button or unplug it, neither of which I can do remotely, so I'd really like to figure out what's going on so that I can reboot remotely and/or recover from a power outage.

-JT



Edited 1 time(s). Last edit at 12/03/2017 11:13PM by renojim.

JT,

This is a common boot behavior. When you have muliple USB drives, there is no guarantee that which drive will be spun up first. So sometime the USB flash drive is 0:1, the USB HDD is going to be 1:1. Other time, the opposite is true. USB flash drive is 1:1, the USB HDD is going to be 0:1.

However, that was perfectly OK. Since the kernel drive was detected correctly as 1:1 (by the envs). Since the USB flash is 1:1 this time.

Found bootable drive on usb 1:1

So the kernel booted OK.

Now your real problem.

[    0.000000] Kernel command line: console=ttyS0,115200 root=/dev/sdb1 rootdelay=10 rootfstype=ext3 mtdparts=orion_nand:1M(u-boot),4M(uImage),32M(rootfs),-(data) zswap.enabled=1 zswap.compressor=lz4

You did not update the default envs image using uboot.2016.05-tld-1.environment.img.

So your kernel bootarg for root device is wrong. If you had, then that root device should have been root=LABEL=rootfs.

To fix this problem without actually flashing the default envs image, set your bootargs to use root=LABEL=rootfs (instead of root=/dev/sdb1).

Now why was the HDD is sda1, and the flash drive is sdb1, or vice versa? again, the USB drive enumeration is unpredictable during Linux kernel booting. Whichever is "faster" will got assigned sda1, the "slower" will get assigned "sdb1". To fix that problem, I use root=LABEL=rootfs to tell the kernel where to find the rootfs to mount it. (default envs image set that bootargs root=LABEL=rootfs). So the drives enumeration order is no longer important, it always works with the label rootfs.

-bodhi
===========================
Forum Wiki
bodhi's corner (buy bodhi a beer)



Edited 1 time(s). Last edit at 12/04/2017 12:18AM by bodhi.

Dang! I knew I was doing something stupid. You'd think I'd know how to follow instructions. I could have sworn I updated my environment variables some time ago, but I'm probably thinking of my setup of a few non-Dockstars I did in the last year or two. I'll go back and read the U-Boot thread more carefully.

Thanks, bodhi!
-JT