Welcome! Log In Create A New Profile

Advanced

SD Card problem loading rootfs too slow?

Posted by Sc30 
SD Card problem loading rootfs too slow?
November 13, 2023 09:57PM
I am unable to boot 6.5.7 Kirkwood Debian rootfs off of a 32gb class 10 SD card on a pogoplug v4. The error is gave up waiting for root file system device after waiting at least 10sec. Is this due to a slow card or other problem? I can boot the same system off a 4gb usb drive. I don't have any other cards to try right now. Thanks.
Re: SD Card problem loading rootfs too slow?
November 13, 2023 10:29PM
Sc30,

> I am unable to boot 6.5.7 Kirkwood Debian rootfs
> off of a 32gb class 10 SD card on a pogoplug v4.
> The error is gave up waiting for root file system
> device after waiting at least 10sec. Is this due
> to a slow card or other problem?

Most likely other problem. SD card slowness should be much less than 10 seconds.

- Check the rootfs label to make sure it is rootfs.
- If it is not the label problem, then post the entire serial console bootlog here.

-bodhi
===========================
Forum Wiki
bodhi's corner (buy bodhi a beer)
Re: SD Card problem loading rootfs too slow?
November 14, 2023 08:40AM
The partition label is rootfs and the sd is the only drive connected. Here is the log. Thanks.

U-Boot 2017.07-tld-1 (Sep 05 2017 - 00:34:01 -0700)
Pogoplug V4

SoC:   Kirkwood 88F6192_A1
DRAM:  128 MiB
WARNING: Caches not enabled
NAND:  128 MiB
MMC:   MVEBU_MMC: 0
In:    serial
Out:   serial
Err:   serial
Net:   egiga0
Hit any key to stop autoboot: 10  9  8  7  6  5  4  3  2  1  0 
starting USB...
USB0:   USB EHCI 1.00
scanning bus 0 for devices... 1 USB Device(s) found
       scanning usb for storage devices... 0 Storage Device(s) found

Reset IDE: ide_preinit failed

MMC rescan: current device # 0 initialized OK
## Unknown partition table type 0

## Unknown partition table type 0

## Unknown partition table type 0

## Unknown partition table type 0

## Unknown partition table type 0

## Unknown partition table type 0

## Unknown partition table type 0
loading envs from usb 0 ...
** Bad device usb 0 **

no IDE devices available

Partition Map for MMC device 0  --   Partition Type: DOS

Part	Start Sector	Num Sectors	UUID		Type
  1	2048      	64843776  	62cc7a8c-01	83
loading envs from mmc 0 ...
** File not found /boot/uEnv.txt **
running scan_disk ...
Scan device usb
device usb 0:1
** Bad device usb 0 **
device usb 1:1
** Bad device usb 1 **
device usb 2:1
** Bad device usb 2 **
device usb 3:1
** Bad device usb 3 **
Scan device ide

Reset IDE: ide_preinit failed
device ide 0:1
** Bad device ide 0 **
device ide 1:1
** Bad device ide 1 **
device ide 2:1
** Bad device ide 2 **
device ide 3:1
** Bad device ide 3 **
Scan device mmc

MMC rescan: current device # 0 initialized OK
device mmc 0:1
1 bytes read in 20 ms (0 Bytes/s)
Found bootable drive on mmc 0
loading uImage ...
6223944 bytes read in 337 ms (17.6 MiB/s)
loading uInitrd ...
5791271 bytes read in 314 ms (17.6 MiB/s)
loading DTB /boot/dts/kirkwood-pogoplug_v4.dtb ...
10178 bytes read in 51 ms (194.3 KiB/s)
## Booting kernel from Legacy Image at 00800000 ...
   Image Name:   Linux-6.5.7-kirkwood-tld-1
   Created:      2023-10-31  22:53:22 UTC
   Image Type:   ARM Linux Kernel Image (uncompressed)
   Data Size:    6223880 Bytes = 5.9 MiB
   Load Address: 00008000
   Entry Point:  00008000
   Verifying Checksum ... OK
## Loading init Ramdisk from Legacy Image at 01100000 ...
   Image Name:   initramfs-6.5.7-kirkwood-tld-1
   Created:      2023-10-31  22:53:55 UTC
   Image Type:   ARM Linux RAMDisk Image (gzip compressed)
   Data Size:    5791207 Bytes = 5.5 MiB
   Load Address: 00000000
   Entry Point:  00000000
   Verifying Checksum ... OK
## Flattened Device Tree blob at 01c00000
   Booting using the fdt blob at 0x1c00000
   Loading Kernel Image ... OK
   Loading Ramdisk to 07583000, end 07b08de7 ... OK
   Loading Device Tree to 0757d000, end 075827c1 ... OK
Using machid 0xf78 from environment

Starting kernel ...

[    0.000000][    T0] Booting Linux on physical CPU 0x0
[    0.000000][    T0] Linux version 6.5.7-kirkwood-tld-1 (root@tldDebian) (gcc (Debian 12.2.0-14) 12.2.0, GNU ld (GNU Binutils for Debian) 2.40) #2 PREEMPT Wed Oct 18 21:14:11 PDT 2023
[    0.000000][    T0] CPU: Feroceon 88FR131 [56251311] revision 1 (ARMv5TE), cr=0005397f
[    0.000000][    T0] CPU: VIVT data cache, VIVT instruction cache
[    0.000000][    T0] OF: fdt: Machine model: Pogoplug v4
[    0.000000][    T0] Memory policy: Data cache writeback
[    0.000000][    T0] Zone ranges:
[    0.000000][    T0]   Normal   [mem 0x0000000000000000-0x0000000007ffffff]
[    0.000000][    T0] Movable zone start for each node
[    0.000000][    T0] Early memory node ranges
[    0.000000][    T0]   node   0: [mem 0x0000000000000000-0x0000000007ffffff]
[    0.000000][    T0] Initmem setup node 0 [mem 0x0000000000000000-0x0000000007ffffff]
[    0.000000][    T0] Kernel command line: console=ttyS0,115200 root=LABEL=rootfs rootdelay=10 mtdparts=orion_nand:2M(u-boot),3M(uImage),3M(uImage2),8M(failsafe),112M(root)
[    0.000000][    T0] Dentry cache hash table entries: 16384 (order: 4, 65536 bytes, linear)
[    0.000000][    T0] Inode-cache hash table entries: 8192 (order: 3, 32768 bytes, linear)
[    0.000000][    T0] Built 1 zonelists, mobility grouping on.  Total pages: 32480
[    0.000000][    T0] mem auto-init: stack:off, heap alloc:on, heap free:off
[    0.000000][    T0] Memory: 102528K/131072K available (12288K kernel code, 1755K rwdata, 5416K rodata, 1024K init, 301K bss, 28544K reserved, 0K cma-reserved)
[    0.000000][    T0] SLUB: HWalign=32, Order=0-3, MinObjects=0, CPUs=1, Nodes=1
[    0.000000][    T0] trace event string verifier disabled
[    0.000000][    T0] rcu: Preemptible hierarchical RCU implementation.
[    0.000000][    T0] 	Trampoline variant of Tasks RCU enabled.
[    0.000000][    T0] 	Tracing variant of Tasks RCU enabled.
[    0.000000][    T0] rcu: RCU calculated value of scheduler-enlistment delay is 10 jiffies.
[    0.000000][    T0] NR_IRQS: 16, nr_irqs: 16, preallocated irqs: 16
[    0.000000][    T0] rcu: srcu_init: Setting srcu_struct sizes based on contention.
[    0.000000][    T0] clocksource: orion_clocksource: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 11467562657 ns
[    0.000003][    T0] sched_clock: 32 bits at 167MHz, resolution 6ns, wraps every 12884901885ns
[    0.000075][    T0] Switching to timer-based delay loop, resolution 6ns
[    0.000979][    T0] Console: colour dummy device 80x30
[    0.001090][    T0] Calibrating delay loop (skipped), value calculated using timer frequency.. 333.33 BogoMIPS (lpj=1666666)
[    0.001137][    T0] CPU: Testing write buffer coherency: ok
[    0.001342][    T0] pid_max: default: 32768 minimum: 301
[    0.014551][    T0] LSM: initializing lsm=capability,integrity
[    0.018152][    T0] Mount-cache hash table entries: 1024 (order: 0, 4096 bytes, linear)
[    0.018209][    T0] Mountpoint-cache hash table entries: 1024 (order: 0, 4096 bytes, linear)
[    0.037289][    T1] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1.
[    0.037862][    T1] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1.
[    0.038608][    T1] Setting up static identity map for 0x100000 - 0x100058
[    0.039110][    T1] mvebu-soc-id: MVEBU SoC ID=0x6281, Rev=0x3
[    0.039767][    T1] rcu: Hierarchical SRCU implementation.
[    0.039793][    T1] rcu: 	Max phase no-delay instances is 1000.
[    0.044451][    T1] devtmpfs: initialized
[    0.058151][    T1] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns
[    0.058222][    T1] futex hash table entries: 256 (order: -1, 3072 bytes, linear)
[    0.062746][    T1] prandom: seed boundary self test passed
[    0.069117][    T1] prandom: 100 self tests passed
[    0.069141][    T1] pinctrl core: initialized pinctrl subsystem
[    0.075123][    T1] NET: Registered PF_NETLINK/PF_ROUTE protocol family
[    0.076676][    T1] DMA: preallocated 256 KiB pool for atomic coherent allocations
[    0.085689][    T1] audit: initializing netlink subsys (disabled)
[    0.088908][    T1] thermal_sys: Registered thermal governor 'step_wise'
[    0.089162][   T19] audit: type=2000 audit(0.080:1): state=initialized audit_enabled=0 res=1
[    0.089242][    T1] cpuidle: using governor ladder
[    0.089368][    T1] cpuidle: using governor menu
[    0.090398][    T1] Feroceon L2: Enabling L2
[    0.090452][    T1] Feroceon L2: Cache support initialised.
[    0.104938][    T1] No ATAGs?
[    0.460102][    T1] raid6: int32x8  gen()    74 MB/s
[    0.800093][    T1] raid6: int32x4  gen()    73 MB/s
[    1.150098][    T1] raid6: int32x2  gen()    96 MB/s
[    1.490097][    T1] raid6: int32x1  gen()    74 MB/s
[    1.490118][    T1] raid6: using algorithm int32x2 gen() 96 MB/s
[    1.830091][    T1] raid6: .... xor() 61 MB/s, rmw enabled
[    1.830109][    T1] raid6: using intx1 recovery algorithm
[    1.832539][    T1] SCSI subsystem initialized
[    1.833675][    T1] usbcore: registered new interface driver usbfs
[    1.833776][    T1] usbcore: registered new interface driver hub
[    1.833918][    T1] usbcore: registered new device driver usb
[    1.834495][    T1] pps_core: LinuxPPS API ver. 1 registered
[    1.834508][    T1] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>
[    1.834556][    T1] PTP clock support registered
[    1.838724][    T1] vgaarb: loaded
[    1.839884][    T1] clocksource: Switched to clocksource orion_clocksource
[    1.864135][    T1] VFS: Disk quotas dquot_6.6.0
[    1.864783][    T1] VFS: Dquot-cache hash table entries: 1024 (order 0, 4096 bytes)
[    1.910546][    T1] NET: Registered PF_INET protocol family
[    1.920055][    T1] IP idents hash table entries: 2048 (order: 2, 16384 bytes, linear)
[    1.922936][    T1] tcp_listen_portaddr_hash hash table entries: 1024 (order: 0, 4096 bytes, linear)
[    1.923335][    T1] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)
[    1.923377][    T1] TCP established hash table entries: 1024 (order: 0, 4096 bytes, linear)
[    1.923427][    T1] TCP bind hash table entries: 1024 (order: 1, 8192 bytes, linear)
[    1.923477][    T1] TCP: Hash tables configured (established 1024 bind 1024)
[    1.924514][    T1] MPTCP token hash table entries: 512 (order: 0, 6144 bytes, linear)
[    1.924874][    T1] UDP hash table entries: 256 (order: 0, 4096 bytes, linear)
[    1.924926][    T1] UDP-Lite hash table entries: 256 (order: 0, 4096 bytes, linear)
[    1.926260][    T1] NET: Registered PF_UNIX/PF_LOCAL protocol family
[    1.940862][    T1] RPC: Registered named UNIX socket transport module.
[    1.940882][    T1] RPC: Registered udp transport module.
[    1.940892][    T1] RPC: Registered tcp transport module.
[    1.940900][    T1] RPC: Registered tcp-with-tls transport module.
[    1.940909][    T1] RPC: Registered tcp NFSv4.1 backchannel transport module.
[    1.940939][    T1] PCI: CLS 0 bytes, default 32
[    1.945163][   T32] Trying to unpack rootfs image as initramfs...
[    3.393335][   T32] Freeing initrd memory: 5656K
[    5.107324][    T1] Initialise system trusted keyrings
[    5.107461][    T1] Key type blacklist registered
[    5.109522][    T1] workingset: timestamp_bits=14 max_order=15 bucket_order=1
[    5.109794][    T1] zbud: loaded
[    5.138501][    T1] NFS: Registering the id_resolver key type
[    5.138632][    T1] Key type id_resolver registered
[    5.138647][    T1] Key type id_legacy registered
[    5.138934][    T1] nfs4filelayout_init: NFSv4 File Layout Driver Registering...
[    5.138966][    T1] nfs4flexfilelayout_init: NFSv4 Flexfile Layout Driver Registering...
[    5.141439][    T1] jffs2: version 2.2. (NAND) (SUMMARY)  © 2001-2006 Red Hat, Inc.
[    5.142855][    T1] fuse: init (API version 7.38)
[    5.144990][    T1] orangefs_debugfs_init: called with debug mask: :none: :0:
[    5.145522][    T1] orangefs_init: module version upstream loaded
[    5.146111][    T1] SGI XFS with ACLs, security attributes, realtime, scrub, quota, no debug enabled
[    5.462478][    T1] xor: measuring software checksum speed
[    5.475743][    T1]    arm4regs        :   746 MB/sec
[    5.497778][    T1]    8regs           :   447 MB/sec
[    5.513325][    T1]    32regs          :   634 MB/sec
[    5.513339][    T1] xor: using function: arm4regs (746 MB/sec)
[    5.513361][    T1] async_tx: api initialized (async)
[    5.513385][    T1] Key type asymmetric registered
[    5.513398][    T1] Asymmetric key parser 'x509' registered
[    5.588774][    T1] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 246)
[    5.589438][    T1] io scheduler bfq registered
[    5.871759][    T1] kirkwood-pinctrl f1010000.pin-controller: unknown pinctrl group 47
[    5.871793][    T1] kirkwood-pinctrl f1010000.pin-controller: unknown pinctrl group 49
[    5.872548][    T1] kirkwood-pinctrl f1010000.pin-controller: registered pinctrl driver
[    5.874989][    T1] gpio gpiochip0: Static allocation of GPIO base is deprecated, use dynamic allocation.
[    5.877000][    T1] gpio gpiochip1: Static allocation of GPIO base is deprecated, use dynamic allocation.
[    5.878602][    T1] mvebu-pcie mbus@f1000000:pcie@82000000: host bridge /mbus@f1000000/pcie@82000000 ranges:
[    5.878722][    T1] mvebu-pcie mbus@f1000000:pcie@82000000:      MEM 0x00f1040000..0x00f1041fff -> 0x0000040000
[    5.878789][    T1] mvebu-pcie mbus@f1000000:pcie@82000000:      MEM 0xffffffffffffffff..0x00fffffffe -> 0x0100000000
[    5.878832][    T1] mvebu-pcie mbus@f1000000:pcie@82000000:       IO 0xffffffffffffffff..0x00fffffffe -> 0x0100000000
[    5.880194][    T1] mvebu-pcie mbus@f1000000:pcie@82000000: PCI host bridge to bus 0000:00
[    5.880225][    T1] pci_bus 0000:00: root bus resource [bus 00-ff]
[    5.880254][    T1] pci_bus 0000:00: root bus resource [mem 0xf1040000-0xf1041fff] (bus address [0x00040000-0x00041fff])
[    5.880279][    T1] pci_bus 0000:00: root bus resource [mem 0xe0000000-0xefffffff]
[    5.880300][    T1] pci_bus 0000:00: root bus resource [io  0x1000-0xeffff]
[    5.880467][    T1] pci 0000:00:01.0: [11ab:6281] type 01 class 0x060400
[    5.882873][    T1] PCI: bus0: Fast back to back transfers disabled
[    5.882915][    T1] pci 0000:00:01.0: bridge configuration invalid ([bus 00-00]), reconfiguring
[    5.883379][    T1] PCI: bus1: Fast back to back transfers enabled
[    5.883411][    T1] pci_bus 0000:01: busn_res: [bus 01-ff] end is updated to 01
[    5.883474][    T1] pci 0000:00:01.0: PCI bridge to [bus 01]
[    5.884982][    T1] mv_xor f1060800.xor: Marvell shared XOR driver
[    5.963864][    T1] mv_xor f1060800.xor: Marvell XOR (Registers Mode): ( xor cpy intr )
[    5.971125][    T1] mv_xor f1060900.xor: Marvell shared XOR driver
[    6.043855][    T1] mv_xor f1060900.xor: Marvell XOR (Registers Mode): ( xor cpy intr )
[    6.051501][    T1] Serial: 8250/16550 driver, 2 ports, IRQ sharing disabled
[    6.055434][    T1] printk: console [ttyS0] disabled
[    6.056400][    T1] f1012000.serial: ttyS0 at MMIO 0xf1012000 (irq = 28, base_baud = 10416666) is a 16550A
[    6.056497][    T1] printk: console [ttyS0] enabled
[    7.069468][    T1] loop: module loaded
[    7.074950][    T1] sata_mv f1080000.sata: slots 32 ports 2
[    7.087771][    T1] scsi host0: sata_mv
[    7.094144][    T1] scsi host1: sata_mv
[    7.098616][    T1] ata1: SATA max UDMA/133 irq 29
[    7.103515][    T1] ata2: SATA max UDMA/133 irq 29
[    7.110411][    T1] nand: device found, Manufacturer ID: 0xad, Chip ID: 0xf1
[    7.117462][    T1] nand: Hynix H27U1G8F2BTR-BC
[    7.122026][    T1] nand: 128 MiB, SLC, erase size: 128 KiB, page size: 2048, OOB size: 64
[    7.130347][    T1] Scanning device for bad blocks
[    7.220189][    T1] 5 cmdlinepart partitions found on MTD device orion_nand
[    7.227135][    T1] Creating 5 MTD partitions on "orion_nand":
[    7.232984][    T1] 0x000000000000-0x000000200000 : "u-boot"
[    7.240030][    T1] 0x000000200000-0x000000500000 : "uImage"
[    7.246744][    T1] 0x000000500000-0x000000800000 : "uImage2"
[    7.253679][    T1] 0x000000800000-0x000001000000 : "failsafe"
[    7.260625][    T1] 0x000001000000-0x000008000000 : "root"
[    7.269287][    T1] wireguard: WireGuard 1.0.0 loaded. See www.wireguard.com for information.
[    7.277857][    T1] wireguard: Copyright (C) 2015-2019 Jason A. Donenfeld <Jason@zx2c4.com>. All Rights Reserved.
[    7.327615][    T1] mv643xx_eth: MV-643xx 10/100/1000 ethernet driver version 1.4
[    7.431751][   T53] ata1: SATA link down (SStatus 0 SControl F300)
[    7.771706][   T55] ata2: SATA link down (SStatus 0 SControl F300)
[    8.081870][    T1] mv643xx_eth_port mv643xx_eth_port.0 eth0: port 0 with MAC address 00:25:31:03:ee:c2
[    8.092282][    T1] orion-ehci f1050000.ehci: EHCI Host Controller
[    8.098510][    T1] orion-ehci f1050000.ehci: new USB bus registered, assigned bus number 1
[    8.107269][    T1] orion-ehci f1050000.ehci: irq 32, io mem 0xf1050000
[    8.139931][    T1] orion-ehci f1050000.ehci: USB 2.0 started, EHCI 1.00
[    8.147108][    T1] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 6.05
[    8.156093][    T1] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[    8.164028][    T1] usb usb1: Product: EHCI Host Controller
[    8.169589][    T1] usb usb1: Manufacturer: Linux 6.5.7-kirkwood-tld-1 ehci_hcd
[    8.176917][    T1] usb usb1: SerialNumber: f1050000.ehci
[    8.183858][    T1] hub 1-0:1.0: USB hub found
[    8.188374][    T1] hub 1-0:1.0: 1 port detected
[    8.194656][    T1] usbcore: registered new interface driver usb-storage
[    8.202323][    T1] mousedev: PS/2 mouse device common for all mice
[    9.289934][    T1] rtc-mv f1010300.rtc: internal RTC not ticking
[    9.296389][    T1] i2c_dev: i2c /dev entries driver
[    9.303002][    T1] device-mapper: uevent: version 1.0.3
[    9.309196][    T1] device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@redhat.com
[    9.319426][    T1] device-mapper: multipath round-robin: version 1.2.0 loaded
[    9.326748][    T1] device-mapper: multipath queue-length: version 0.2.0 loaded
[    9.334091][    T1] device-mapper: multipath service-time: version 0.3.0 loaded
[    9.341518][    T1] device-mapper: dm-log-userspace: version 1.3.0 loaded
[    9.351017][    T1] ledtrig-cpu: registered to indicate activity on CPUs
[    9.357938][    T1] hid: raw HID events driver (C) Jiri Kosina
[    9.364436][    T1] drop_monitor: Initializing network drop monitor service
[    9.373852][    T1] NET: Registered PF_INET6 protocol family
[    9.382654][    T1] Segment Routing with IPv6
[    9.387026][    T1] RPL Segment Routing with IPv6
[    9.391974][    T1] In-situ OAM (IOAM) with IPv6
[    9.396855][    T1] NET: Registered PF_PACKET protocol family
[    9.403183][    T1] Key type dns_resolver registered
[    9.436596][    T1] registered taskstats version 1
[    9.441989][    T1] Loading compiled-in X.509 certificates
[    9.479803][    T1] Key type .fscrypt registered
[    9.484540][    T1] Key type fscrypt-provisioning registered
[    9.500587][    T1] Key type big_key registered
[    9.572494][    T1] Key type encrypted registered
[    9.737154][  T104] "cryptomgr_test" (104) uses obsolete ecb(arc4) skcipher
[   13.165796][    T1] clk: Disabling unused clocks
[   13.175521][    T1] Freeing unused kernel image (initmem) memory: 1024K
[   13.191231][    T1] Checked W+X mappings: passed, no W+X pages found
[   13.197607][    T1] Run /init as init process
Loading, please wait...
Starting systemd-udevd version 252.17-1~deb12u1
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 ... Begin: Running /scripts/local-block ... done.
Begin: Running /scripts/local-block ... done.
Begin: Running /scripts/local-block ... done.
Begin: Running /scripts/local-block ... done.
Begin: Running /scripts/local-block ... done.
Begin: Running /scripts/local-block ... done.
Begin: Running /scripts/local-block ... done.
Begin: Running /scripts/local-block ... done.
Begin: Running /scripts/local-block ... done.
Begin: Running /scripts/local-block ... done.
Begin: Running /scripts/local-block ... done.
Begin: Running /scripts/local-block ... done.
Begin: Running /scripts/local-block ... done.
Begin: Running /scripts/local-block ... done.
Begin: Running /scripts/local-block ... done.
Begin: Running /scripts/local-block ... done.
Begin: Running /scripts/local-block ... done.
Begin: Running /scripts/local-block ... done.
Begin: Running /scripts/local-block ... done.
Begin: Running /scripts/local-block ... done.
done.
Gave up waiting for root file system device.  Common problems:
 - Boot args (cat /proc/cmdline)
   - Check rootdelay= (did the system wait long enough?)
 - Missing modules (cat /proc/modules; ls /dev)
ALERT!  LABEL=rootfs does not exist.  Dropping to a shell!


BusyBox v1.35.0 (Debian 1:1.35.0-4+b3) built-in shell (ash)
Enter 'help' for a list of built-in commands.

(initramfs) 



Edited 1 time(s). Last edit at 11/14/2023 08:41AM by Sc30.
Re: SD Card problem loading rootfs too slow?
November 14, 2023 01:13PM
Sc30,

1. Looks like this SD card might be too slow. Try running with rootwait

Boot with serial console, and then adjust the bootargs. Print out the env to see what is currently set to, and reset it with rootwait.
printenv set_bootargs
setenv set_bootargs 'setenv bootargs console=ttyS0,115200 root=LABEL=rootfs rootwait $mtdparts $custom_params'
And then
boot

-bodhi
===========================
Forum Wiki
bodhi's corner (buy bodhi a beer)
Re: SD Card problem loading rootfs too slow?
November 14, 2023 03:22PM
It results in around 10 more lines of

Begin: Running /scripts/local-block ... done

But ultimately same result.
Re: SD Card problem loading rootfs too slow?
November 14, 2023 03:46PM
> It results in around 10 more lines of
>
>
> Begin: Running /scripts/local-block ... done
>
>
> But ultimately same result.

OK. How did you create the SD rootfs? using command line fdisk to create partition, and then using command line mkfs.ext3 to format the disk ? and then being root, extract the rootfs (this step must be done by root).

If you created the SD rootfs as described above, then it's time to try another SD card.

=====

For sanity, you can also try booting with the USB rootfs, but with the SD card in the slot. This is to see if the SD card is recongnized by the kernel before looking for rootfs.

-bodhi
===========================
Forum Wiki
bodhi's corner (buy bodhi a beer)
Re: SD Card problem loading rootfs too slow?
November 14, 2023 04:29PM
Yes that is how I did it. Same procedure with a usb stick and it works. I just found an old 4gb class 4 card and got the same result.
Re: SD Card problem loading rootfs too slow?
November 14, 2023 04:37PM
This is booting with both USB and SD card attached.

U-Boot 2017.07-tld-1 (Sep 05 2017 - 00:34:01 -0700)
Pogoplug V4

SoC:   Kirkwood 88F6192_A1
DRAM:  128 MiB
WARNING: Caches not enabled
NAND:  128 MiB
MMC:   MVEBU_MMC: 0
In:    serial
Out:   serial
Err:   serial
Net:   egiga0
Hit any key to stop autoboot: 10  9  8  7  6  5  4  3  2  1  0 
starting USB...
USB0:   USB EHCI 1.00
scanning bus 0 for devices... 2 USB Device(s) found
       scanning usb for storage devices... EHCI timed out on TD - token=0x80008c80

Use USB retry period from the environment: 15 second(s)
1 Storage Device(s) found

Reset IDE: ide_preinit failed

MMC rescan: current device # 0 initialized OK

Partition Map for USB device 0  --   Partition Type: DOS

PartStart SectorNum SectorsUUIDType
  12048      7841792   ab4b51dc-0183

## Unknown partition table type 0

## Unknown partition table type 0

## Unknown partition table type 0

## Unknown partition table type 0

## Unknown partition table type 0

## Unknown partition table type 0
loading envs from usb 0 ...
** File not found /boot/uEnv.txt **

no IDE devices available

Partition Map for MMC device 0  --   Partition Type: DOS

PartStart SectorNum SectorsUUIDType
  12048      7827456   bf0257d0-0183
loading envs from mmc 0 ...
** File not found /boot/uEnv.txt **
running scan_disk ...
Scan device usb
device usb 0:1
1 bytes read in 651 ms (0 Bytes/s)
Found bootable drive on usb 0
loading uImage ...
6223944 bytes read in 1140 ms (5.2 MiB/s)
loading uInitrd ...
5791271 bytes read in 977 ms (5.7 MiB/s)
loading DTB /boot/dts/kirkwood-pogoplug_v4.dtb ...
10178 bytes read in 1892 ms (4.9 KiB/s)
## Booting kernel from Legacy Image at 00800000 ...
   Image Name:   Linux-6.5.7-kirkwood-tld-1
   Created:      2023-10-31  22:53:22 UTC
   Image Type:   ARM Linux Kernel Image (uncompressed)
   Data Size:    6223880 Bytes = 5.9 MiB
   Load Address: 00008000
   Entry Point:  00008000
   Verifying Checksum ... OK
## Loading init Ramdisk from Legacy Image at 01100000 ...
   Image Name:   initramfs-6.5.7-kirkwood-tld-1
   Created:      2023-10-31  22:53:55 UTC
   Image Type:   ARM Linux RAMDisk Image (gzip compressed)
   Data Size:    5791207 Bytes = 5.5 MiB
   Load Address: 00000000
   Entry Point:  00000000
   Verifying Checksum ... OK
## Flattened Device Tree blob at 01c00000
   Booting using the fdt blob at 0x1c00000
   Loading Kernel Image ... OK
   Loading Ramdisk to 07583000, end 07b08de7 ... OK
   Loading Device Tree to 0757d000, end 075827c1 ... OK
Using machid 0xf78 from environment

Starting kernel ...

[    0.000000][    T0] Booting Linux on physical CPU 0x0
[    0.000000][    T0] Linux version 6.5.7-kirkwood-tld-1 (root@tldDebian) (gcc (Debian 12.2.0-14) 12.2.0, GNU ld (GNU Binutils for Debian) 2.40) #2 PREEMPT Wed Oct 18 21:14:11 PDT 2023
[    0.000000][    T0] CPU: Feroceon 88FR131 [56251311] revision 1 (ARMv5TE), cr=0005397f
[    0.000000][    T0] CPU: VIVT data cache, VIVT instruction cache
[    0.000000][    T0] OF: fdt: Machine model: Pogoplug v4
[    0.000000][    T0] Memory policy: Data cache writeback
[    0.000000][    T0] Zone ranges:
[    0.000000][    T0]   Normal   [mem 0x0000000000000000-0x0000000007ffffff]
[    0.000000][    T0] Movable zone start for each node
[    0.000000][    T0] Early memory node ranges
[    0.000000][    T0]   node   0: [mem 0x0000000000000000-0x0000000007ffffff]
[    0.000000][    T0] Initmem setup node 0 [mem 0x0000000000000000-0x0000000007ffffff]
[    0.000000][    T0] Kernel command line: console=ttyS0,115200 root=LABEL=rootfs rootdelay=10 mtdparts=orion_nand:2M(u-boot),3M(uImage),3M(uImage2),8M(failsafe),112M(root)
[    0.000000][    T0] Dentry cache hash table entries: 16384 (order: 4, 65536 bytes, linear)
[    0.000000][    T0] Inode-cache hash table entries: 8192 (order: 3, 32768 bytes, linear)
[    0.000000][    T0] Built 1 zonelists, mobility grouping on.  Total pages: 32480
[    0.000000][    T0] mem auto-init: stack:off, heap alloc:on, heap free:off
[    0.000000][    T0] Memory: 102528K/131072K available (12288K kernel code, 1755K rwdata, 5416K rodata, 1024K init, 301K bss, 28544K reserved, 0K cma-reserved)
[    0.000000][    T0] SLUB: HWalign=32, Order=0-3, MinObjects=0, CPUs=1, Nodes=1
[    0.000000][    T0] trace event string verifier disabled
[    0.000000][    T0] rcu: Preemptible hierarchical RCU implementation.
[    0.000000][    T0] Trampoline variant of Tasks RCU enabled.
[    0.000000][    T0] Tracing variant of Tasks RCU enabled.
[    0.000000][    T0] rcu: RCU calculated value of scheduler-enlistment delay is 10 jiffies.
[    0.000000][    T0] NR_IRQS: 16, nr_irqs: 16, preallocated irqs: 16
[    0.000000][    T0] rcu: srcu_init: Setting srcu_struct sizes based on contention.
[    0.000000][    T0] clocksource: orion_clocksource: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 11467562657 ns
[    0.000003][    T0] sched_clock: 32 bits at 167MHz, resolution 6ns, wraps every 12884901885ns
[    0.000075][    T0] Switching to timer-based delay loop, resolution 6ns
[    0.000979][    T0] Console: colour dummy device 80x30
[    0.001091][    T0] Calibrating delay loop (skipped), value calculated using timer frequency.. 333.33 BogoMIPS (lpj=1666666)
[    0.001138][    T0] CPU: Testing write buffer coherency: ok
[    0.001342][    T0] pid_max: default: 32768 minimum: 301
[    0.014560][    T0] LSM: initializing lsm=capability,integrity
[    0.018157][    T0] Mount-cache hash table entries: 1024 (order: 0, 4096 bytes, linear)
[    0.018214][    T0] Mountpoint-cache hash table entries: 1024 (order: 0, 4096 bytes, linear)
[    0.037302][    T1] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1.
[    0.037880][    T1] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1.
[    0.038621][    T1] Setting up static identity map for 0x100000 - 0x100058
[    0.039123][    T1] mvebu-soc-id: MVEBU SoC ID=0x6281, Rev=0x3
[    0.039782][    T1] rcu: Hierarchical SRCU implementation.
[    0.039807][    T1] rcu: Max phase no-delay instances is 1000.
[    0.044463][    T1] devtmpfs: initialized
[    0.058132][    T1] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns
[    0.058202][    T1] futex hash table entries: 256 (order: -1, 3072 bytes, linear)
[    0.062718][    T1] prandom: seed boundary self test passed
[    0.069088][    T1] prandom: 100 self tests passed
[    0.069113][    T1] pinctrl core: initialized pinctrl subsystem
[    0.075073][    T1] NET: Registered PF_NETLINK/PF_ROUTE protocol family
[    0.076625][    T1] DMA: preallocated 256 KiB pool for atomic coherent allocations
[    0.085592][    T1] audit: initializing netlink subsys (disabled)
[    0.088810][    T1] thermal_sys: Registered thermal governor 'step_wise'
[    0.089066][   T19] audit: type=2000 audit(0.080:1): state=initialized audit_enabled=0 res=1
[    0.089144][    T1] cpuidle: using governor ladder
[    0.089270][    T1] cpuidle: using governor menu
[    0.090308][    T1] Feroceon L2: Enabling L2
[    0.090362][    T1] Feroceon L2: Cache support initialised.
[    0.104848][    T1] No ATAGs?
[    0.460104][    T1] raid6: int32x8  gen()    74 MB/s
[    0.800093][    T1] raid6: int32x4  gen()    73 MB/s
[    1.140127][    T1] raid6: int32x2  gen()    99 MB/s
[    1.490098][    T1] raid6: int32x1  gen()    75 MB/s
[    1.490119][    T1] raid6: using algorithm int32x2 gen() 99 MB/s
[    1.830092][    T1] raid6: .... xor() 61 MB/s, rmw enabled
[    1.830110][    T1] raid6: using intx1 recovery algorithm
[    1.832540][    T1] SCSI subsystem initialized
[    1.833683][    T1] usbcore: registered new interface driver usbfs
[    1.833784][    T1] usbcore: registered new interface driver hub
[    1.833929][    T1] usbcore: registered new device driver usb
[    1.834507][    T1] pps_core: LinuxPPS API ver. 1 registered
[    1.834521][    T1] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>
[    1.834569][    T1] PTP clock support registered
[    1.838742][    T1] vgaarb: loaded
[    1.839915][    T1] clocksource: Switched to clocksource orion_clocksource
[    1.864140][    T1] VFS: Disk quotas dquot_6.6.0
[    1.864788][    T1] VFS: Dquot-cache hash table entries: 1024 (order 0, 4096 bytes)
[    1.910535][    T1] NET: Registered PF_INET protocol family
[    1.920083][    T1] IP idents hash table entries: 2048 (order: 2, 16384 bytes, linear)
[    1.922972][    T1] tcp_listen_portaddr_hash hash table entries: 1024 (order: 0, 4096 bytes, linear)
[    1.923373][    T1] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)
[    1.923414][    T1] TCP established hash table entries: 1024 (order: 0, 4096 bytes, linear)
[    1.923465][    T1] TCP bind hash table entries: 1024 (order: 1, 8192 bytes, linear)
[    1.923515][    T1] TCP: Hash tables configured (established 1024 bind 1024)
[    1.924556][    T1] MPTCP token hash table entries: 512 (order: 0, 6144 bytes, linear)
[    1.924918][    T1] UDP hash table entries: 256 (order: 0, 4096 bytes, linear)
[    1.924970][    T1] UDP-Lite hash table entries: 256 (order: 0, 4096 bytes, linear)
[    1.926306][    T1] NET: Registered PF_UNIX/PF_LOCAL protocol family
[    1.928031][    T1] RPC: Registered named UNIX socket transport module.
[    1.928051][    T1] RPC: Registered udp transport module.
[    1.928061][    T1] RPC: Registered tcp transport module.
[    1.928070][    T1] RPC: Registered tcp-with-tls transport module.
[    1.928078][    T1] RPC: Registered tcp NFSv4.1 backchannel transport module.
[    1.928110][    T1] PCI: CLS 0 bytes, default 32
[    1.940613][   T32] Trying to unpack rootfs image as initramfs...
[    3.400580][   T32] Freeing initrd memory: 5656K
[    5.107721][    T1] Initialise system trusted keyrings
[    5.107902][    T1] Key type blacklist registered
[    5.110061][    T1] workingset: timestamp_bits=14 max_order=15 bucket_order=1
[    5.110327][    T1] zbud: loaded
[    5.138915][    T1] NFS: Registering the id_resolver key type
[    5.139066][    T1] Key type id_resolver registered
[    5.139081][    T1] Key type id_legacy registered
[    5.139371][    T1] nfs4filelayout_init: NFSv4 File Layout Driver Registering...
[    5.139403][    T1] nfs4flexfilelayout_init: NFSv4 Flexfile Layout Driver Registering...
[    5.141872][    T1] jffs2: version 2.2. (NAND) (SUMMARY)  © 2001-2006 Red Hat, Inc.
[    5.143291][    T1] fuse: init (API version 7.38)
[    5.145424][    T1] orangefs_debugfs_init: called with debug mask: :none: :0:
[    5.145950][    T1] orangefs_init: module version upstream loaded
[    5.146538][    T1] SGI XFS with ACLs, security attributes, realtime, scrub, quota, no debug enabled
[    5.462936][    T1] xor: measuring software checksum speed
[    5.476201][    T1]    arm4regs        :   746 MB/sec
[    5.498237][    T1]    8regs           :   447 MB/sec
[    5.513785][    T1]    32regs          :   634 MB/sec
[    5.513799][    T1] xor: using function: arm4regs (746 MB/sec)
[    5.513822][    T1] async_tx: api initialized (async)
[    5.513846][    T1] Key type asymmetric registered
[    5.513859][    T1] Asymmetric key parser 'x509' registered
[    5.589611][    T1] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 246)
[    5.590437][    T1] io scheduler bfq registered
[    5.872615][    T1] kirkwood-pinctrl f1010000.pin-controller: unknown pinctrl group 47
[    5.872649][    T1] kirkwood-pinctrl f1010000.pin-controller: unknown pinctrl group 49
[    5.873410][    T1] kirkwood-pinctrl f1010000.pin-controller: registered pinctrl driver
[    5.875844][    T1] gpio gpiochip0: Static allocation of GPIO base is deprecated, use dynamic allocation.
[    5.877908][    T1] gpio gpiochip1: Static allocation of GPIO base is deprecated, use dynamic allocation.
[    5.879509][    T1] mvebu-pcie mbus@f1000000:pcie@82000000: host bridge /mbus@f1000000/pcie@82000000 ranges:
[    5.879631][    T1] mvebu-pcie mbus@f1000000:pcie@82000000:      MEM 0x00f1040000..0x00f1041fff -> 0x0000040000
[    5.879698][    T1] mvebu-pcie mbus@f1000000:pcie@82000000:      MEM 0xffffffffffffffff..0x00fffffffe -> 0x0100000000
[    5.879741][    T1] mvebu-pcie mbus@f1000000:pcie@82000000:       IO 0xffffffffffffffff..0x00fffffffe -> 0x0100000000
[    5.881113][    T1] mvebu-pcie mbus@f1000000:pcie@82000000: PCI host bridge to bus 0000:00
[    5.881143][    T1] pci_bus 0000:00: root bus resource [bus 00-ff]
[    5.881172][    T1] pci_bus 0000:00: root bus resource [mem 0xf1040000-0xf1041fff] (bus address [0x00040000-0x00041fff])
[    5.881197][    T1] pci_bus 0000:00: root bus resource [mem 0xe0000000-0xefffffff]
[    5.881219][    T1] pci_bus 0000:00: root bus resource [io  0x1000-0xeffff]
[    5.881375][    T1] pci 0000:00:01.0: [11ab:6281] type 01 class 0x060400
[    5.883761][    T1] PCI: bus0: Fast back to back transfers disabled
[    5.883803][    T1] pci 0000:00:01.0: bridge configuration invalid ([bus 00-00]), reconfiguring
[    5.884265][    T1] PCI: bus1: Fast back to back transfers enabled
[    5.884298][    T1] pci_bus 0000:01: busn_res: [bus 01-ff] end is updated to 01
[    5.884360][    T1] pci 0000:00:01.0: PCI bridge to [bus 01]
[    5.885879][    T1] mv_xor f1060800.xor: Marvell shared XOR driver
[    5.963893][    T1] mv_xor f1060800.xor: Marvell XOR (Registers Mode): ( xor cpy intr )
[    5.971150][    T1] mv_xor f1060900.xor: Marvell shared XOR driver
[    6.043891][    T1] mv_xor f1060900.xor: Marvell XOR (Registers Mode): ( xor cpy intr )
[    6.051525][    T1] Serial: 8250/16550 driver, 2 ports, IRQ sharing disabled
[    6.055452][    T1] printk: console [ttyS0] disabled
[    6.056431][    T1] f1012000.serial: ttyS0 at MMIO 0xf1012000 (irq = 28, base_baud = 10416666) is a 16550A
[    6.056528][    T1] printk: console [ttyS0] enabled
[    7.069472][    T1] loop: module loaded
[    7.074965][    T1] sata_mv f1080000.sata: slots 32 ports 2
[    7.087791][    T1] scsi host0: sata_mv
[    7.094188][    T1] scsi host1: sata_mv
[    7.098656][    T1] ata1: SATA max UDMA/133 irq 29
[    7.103551][    T1] ata2: SATA max UDMA/133 irq 29
[    7.110444][    T1] nand: device found, Manufacturer ID: 0xad, Chip ID: 0xf1
[    7.117497][    T1] nand: Hynix H27U1G8F2BTR-BC
[    7.122060][    T1] nand: 128 MiB, SLC, erase size: 128 KiB, page size: 2048, OOB size: 64
[    7.130383][    T1] Scanning device for bad blocks
[    7.220287][    T1] 5 cmdlinepart partitions found on MTD device orion_nand
[    7.227240][    T1] Creating 5 MTD partitions on "orion_nand":
[    7.233089][    T1] 0x000000000000-0x000000200000 : "u-boot"
[    7.240142][    T1] 0x000000200000-0x000000500000 : "uImage"
[    7.246852][    T1] 0x000000500000-0x000000800000 : "uImage2"
[    7.253790][    T1] 0x000000800000-0x000001000000 : "failsafe"
[    7.260732][    T1] 0x000001000000-0x000008000000 : "root"
[    7.269381][    T1] wireguard: WireGuard 1.0.0 loaded. See www.wireguard.com for information.
[    7.277952][    T1] wireguard: Copyright (C) 2015-2019 Jason A. Donenfeld <Jason@zx2c4.com>. All Rights Reserved.
[    7.327590][    T1] mv643xx_eth: MV-643xx 10/100/1000 ethernet driver version 1.4
[    7.431726][   T53] ata1: SATA link down (SStatus 0 SControl F300)
[    7.771734][   T55] ata2: SATA link down (SStatus 0 SControl F300)
[    8.081902][    T1] mv643xx_eth_port mv643xx_eth_port.0 eth0: port 0 with MAC address 00:25:31:03:ee:c2
[    8.092315][    T1] orion-ehci f1050000.ehci: EHCI Host Controller
[    8.098544][    T1] orion-ehci f1050000.ehci: new USB bus registered, assigned bus number 1
[    8.107305][    T1] orion-ehci f1050000.ehci: irq 32, io mem 0xf1050000
[    8.139961][    T1] orion-ehci f1050000.ehci: USB 2.0 started, EHCI 1.00
[    8.147150][    T1] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 6.05
[    8.156134][    T1] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[    8.164063][    T1] usb usb1: Product: EHCI Host Controller
[    8.169626][    T1] usb usb1: Manufacturer: Linux 6.5.7-kirkwood-tld-1 ehci_hcd
[    8.176953][    T1] usb usb1: SerialNumber: f1050000.ehci
[    8.183886][    T1] hub 1-0:1.0: USB hub found
[    8.188401][    T1] hub 1-0:1.0: 1 port detected
[    8.194685][    T1] usbcore: registered new interface driver usb-storage
[    8.202356][    T1] mousedev: PS/2 mouse device common for all mice
[    8.479968][   T50] usb 1-1: new high-speed USB device number 2 using orion-ehci
[    8.832554][   T50] usb 1-1: New USB device found, idVendor=058f, idProduct=6387, bcdDevice= 1.00
[    8.841472][   T50] usb 1-1: New USB device strings: Mfr=1, Product=2, SerialNumber=3
[    8.849325][   T50] usb 1-1: Product: Mass Storage Device
[    8.854746][   T50] usb 1-1: Manufacturer: JetFlash
[    8.859624][   T50] usb 1-1: SerialNumber: ZTTI5OME
[    8.866038][   T50] usb-storage 1-1:1.0: USB Mass Storage device detected
[    8.874244][   T50] scsi host2: usb-storage 1-1:1.0
[    9.289967][    T1] rtc-mv f1010300.rtc: internal RTC not ticking
[    9.296458][    T1] i2c_dev: i2c /dev entries driver
[    9.303223][    T1] device-mapper: uevent: version 1.0.3
[    9.309441][    T1] device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@redhat.com
[    9.319603][    T1] device-mapper: multipath round-robin: version 1.2.0 loaded
[    9.326925][    T1] device-mapper: multipath queue-length: version 0.2.0 loaded
[    9.334267][    T1] device-mapper: multipath service-time: version 0.3.0 loaded
[    9.341695][    T1] device-mapper: dm-log-userspace: version 1.3.0 loaded
[    9.351157][    T1] ledtrig-cpu: registered to indicate activity on CPUs
[    9.358019][    T1] hid: raw HID events driver (C) Jiri Kosina
[    9.364629][    T1] drop_monitor: Initializing network drop monitor service
[    9.373949][    T1] NET: Registered PF_INET6 protocol family
[    9.382868][    T1] Segment Routing with IPv6
[    9.387234][    T1] RPL Segment Routing with IPv6
[    9.392188][    T1] In-situ OAM (IOAM) with IPv6
[    9.397027][    T1] NET: Registered PF_PACKET protocol family
[    9.403413][    T1] Key type dns_resolver registered
[    9.436811][    T1] registered taskstats version 1
[    9.442221][    T1] Loading compiled-in X.509 certificates
[    9.480177][    T1] Key type .fscrypt registered
[    9.484819][    T1] Key type fscrypt-provisioning registered
[    9.500718][    T1] Key type big_key registered
[    9.572804][    T1] Key type encrypted registered
[    9.737575][  T107] "cryptomgr_test" (107) uses obsolete ecb(arc4) skcipher
[    9.931307][   T18] scsi 2:0:0:0: Direct-Access     JetFlash Transcend 4GB    8.07 PQ: 0 ANSI: 2
[    9.953480][   T10] sd 2:0:0:0: [sda] 7843840 512-byte logical blocks: (4.02 GB/3.74 GiB)
[    9.970478][   T10] sd 2:0:0:0: [sda] Write Protect is off
[    9.979992][   T10] sd 2:0:0:0: [sda] No Caching mode page found
[    9.986023][   T10] sd 2:0:0:0: [sda] Assuming drive cache: write through
[   10.185313][   T10]  sda: sda1
[   10.200363][   T10] sd 2:0:0:0: [sda] Attached SCSI removable disk
[   13.222520][    T1] clk: Disabling unused clocks
[   13.232256][    T1] Freeing unused kernel image (initmem) memory: 1024K
[   13.251260][    T1] Checked W+X mappings: passed, no W+X pages found
[   13.257628][    T1] Run /init as init process
Loading, please wait...
Starting systemd-udevd version 252.17-1~deb12u1
[   15.315712][  T151] usbcore: registered new interface driver uas
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: Will now check root file system ... fsck from util-linux 2.38.1
[/sbin/fsck.ext3 (1) -- /dev/sda1] fsck.ext3 -a -C0 /dev/sda1 
rootfs: clean, 20047/245280 files, 216548/980224 blocks
done.
[   26.388420][  T177] EXT4-fs (sda1): mounting ext3 file system using the ext4 subsystem
[   26.436006][  T177] EXT4-fs (sda1): mounted filesystem 6a01e748-be12-4771-97e7-c1bb280bec15 ro with ordered data mode. Quota mode: none.
done.
Begin: Running /scripts/local-bottom ... done.
Begin: Running /scripts/init-bottom ... done.

INIT: version 3.06 booting


INIT: No inittab.d directory found

Using makefile-style concurrent boot in runlevel S.
Setting hostname to 'debian'...done.
Starting hotplug events dispatcher: systemd-udevd.
Synthesizing the initial hotplug events (subsystems)...done.
Synthesizing the initial hotplug events (devices)...done.
Waiting for /dev to be fully populated...[   31.178747][  T358] input: gpio_keys as /devices/platform/gpio_keys/input/input0
[   31.808301][  T354] orion_wdt: Initial timeout 25 sec
[   32.349501][   T10] mvsdio f1090000.mvsdio: Got CD GPIO
[   32.431913][    T9] mmc0: host does not support reading read-only switch, assuming write-enable
[   32.471839][    T9] mmc0: new high speed SDHC card at address b368
[   32.902186][  T358] marvell-cesa f1030000.crypto: CESA device successfully registered
[   32.910347][  T361] sd 2:0:0:0: Attached scsi generic sg0 type 0
[   33.420890][   T60] random: crng init done
[   34.398519][  T354] mmcblk0: mmc0:b368 LEXAR 3.73 GiB
[   34.411360][  T354]  mmcblk0: p1
done.
Activating swap:.
[   35.699243][  T519] EXT4-fs (sda1): re-mounted 6a01e748-be12-4771-97e7-c1bb280bec15 r/w. Quota mode: none.
Will now check all file systems.
Checking all file systems.
LABEL=rootfs is not mounted
[/sbin/fsck.ext3 (1) -- /] fsck.ext3 -y -C0 /dev/mmcblk0p1 
e2fsck 1.47.0 (5-Feb-2023)
rootfs: clean, 20037/244800 files, 216489/978432 blocks
Done checking file systems.
Log is being saved in /var/log/fsck/checkfs if that location is writable.
Cleaning up temporary files...Cleaning /tmp...done.
 /tmp.
Will now mount local filesystems:.
Will now activate swapfile swap, if any:done.
Checking minimum space in /tmp...done.
Cleaning up temporary files....
Starting Setting kernel variables: sysctl.
Initializing random number generator...done.
Configuring network interfaces...Internet Systems Consortium DHCP Client 4.4.3-P1
Copyright 2004-2022 Internet Systems Consortium.
All rights reserved.
For info, please visit https://www.isc.org/software/dhcp/

Listening on LPF/eth0/00:25:31:03:ee:c2
Sending on   LPF/eth0/00:25:31:03:ee:c2
Sending on   Socket/fallback
DHCPREQUEST for 192.168.11.76 on eth0 to 255.255.255.255 port 67
[   43.033584][    C0] mv643xx_eth_port mv643xx_eth_port.0 eth0: link up, 1000 Mb/s, full duplex, flow control disabled
DHCPREQUEST for 192.168.11.76 on eth0 to 255.255.255.255 port 67
DHCPDISCOVER on eth0 to 255.255.255.255 port 67 interval 8
DHCPOFFER of 192.168.11.76 from 192.168.11.1
DHCPREQUEST for 192.168.11.76 on eth0 to 255.255.255.255 port 67
DHCPACK of 192.168.11.76 from 192.168.11.1
bound to 192.168.11.76 -- renewal in 37349 seconds.
done.
Starting RPC port mapper daemon: rpcbind.
Starting NFS common utilities: statd idmapd.
Cleaning up temporary files....

INIT: Entering runlevel: 2

Using makefile-style concurrent boot in runlevel 2.
Not starting NFS kernel daemon: no exports ... (warning).
Starting busybox' syslogd implementation : syslogdStarting /sbin/syslogd...
1033 (syslogd)
.
Starting system message bus: dbus.
Starting Avahi mDNS/DNS-SD Daemon: avahi-daemon.
Starting NTP server: ntpd1969-12-31T16:00:57 ntpd[1076]: INIT: ntpd ntpsec-1.2.2: Starting
1969-12-31T16:00:57 ntpd[1076]: INIT: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:106
.
Starting NTP server: ntpd.
Starting OpenBSD Secure Shell server: sshd.
Starting busybox' klogd implementation : klogdStarting /sbin/klogd...
1052 (klogd)
.
Running local boot scripts (/etc/rc.local)
.


Debian GNU/Linux 12 debian ttyS0

debian login: 
Re: SD Card problem loading rootfs too slow?
November 14, 2023 05:00PM
OK so the SD card was slow coming up in Linux.

Looks like the card was also mounted as rootfs.

[/sbin/fsck.ext3 (1) -- /] fsck.ext3 -y -C0 /dev/mmcblk0p1

Log in and double check which rootfs is mounted
mount

So probably there was nothing wrong. It was just slow coming up.

=====

Let me think a bit about this and come back.

In the mean time, if you have another SD card, try it. BTW, Sandisk is most reliable brand SD and USB used in booting.

-bodhi
===========================
Forum Wiki
bodhi's corner (buy bodhi a beer)
Re: SD Card problem loading rootfs too slow?
November 14, 2023 05:06PM
> In the mean time, if you have another SD card, try
> it. BTW, Sandisk is most reliable brand SD and
> USB used in booting.

Nevermind. I think I know what's going on. Let me write a few commands in the next post.

-bodhi
===========================
Forum Wiki
bodhi's corner (buy bodhi a beer)
Re: SD Card problem loading rootfs too slow?
November 14, 2023 05:21PM
Let's do this work around.

Repeat the boot with only USB attached.

Log in and mount the SD card. Assuming it is mounted at /media/SD.
mount

Then use the uEnv.txt capaility to adjust the bootargs used by u-boot during booting.

This will tell the kernel to find root at /dev/mmcblk0p1 and hopefully will spin up the card faster.

cd /media/SD/boot
echo "set_bootargs=setenv bootargs console=ttyS0,115200 root=/dev/mmcblk0p1 rootwait $mtdparts $custom_params" > uEnv.txt
sync
sync
And then shutdown, remove the USB drive, and power up.

===========

We actually don't want to boot this way with a specific device. We'd want to use the root=LABEL=rootfs approach so that the rootfs can be on any device.

If the above works then I will reconfigure MMC in the next kernel release to solve the problem with slow card.

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



Edited 1 time(s). Last edit at 11/14/2023 05:24PM by bodhi.
Re: SD Card problem loading rootfs too slow?
November 14, 2023 05:43PM
It did not change anything. Why the card detect io signal not detected until 32 seconds?
Re: SD Card problem loading rootfs too slow?
November 14, 2023 05:47PM
> It did not change anything. Why the card detect io
> signal not detected until 32 seconds?

Please post the serial boot log for this.

-bodhi
===========================
Forum Wiki
bodhi's corner (buy bodhi a beer)
Re: SD Card problem loading rootfs too slow?
November 14, 2023 05:52PM
U-Boot 2017.07-tld-1 (Sep 05 2017 - 00:34:01 -0700)
Pogoplug V4

SoC:   Kirkwood 88F6192_A1
DRAM:  128 MiB
WARNING: Caches not enabled
NAND:  128 MiB
MMC:   MVEBU_MMC: 0
In:    serial
Out:   serial
Err:   serial
Net:   egiga0
Hit any key to stop autoboot: 10  9  8  7  6  5  4  3  2  1  0 
starting USB...
USB0:   USB EHCI 1.00
scanning bus 0 for devices... 1 USB Device(s) found
       scanning usb for storage devices... 0 Storage Device(s) found

Reset IDE: ide_preinit failed

MMC rescan: current device # 0 initialized OK
## Unknown partition table type 0

## Unknown partition table type 0

## Unknown partition table type 0

## Unknown partition table type 0

## Unknown partition table type 0

## Unknown partition table type 0

## Unknown partition table type 0
loading envs from usb 0 ...
** Bad device usb 0 **

no IDE devices available

Partition Map for MMC device 0  --   Partition Type: DOS

PartStart SectorNum SectorsUUIDType
  12048      7827456   bf0257d0-0183
loading envs from mmc 0 ...
81 bytes read in 18 ms (3.9 KiB/s)
importing envs ...
running scan_disk ...
Scan device usb
device usb 0:1
** Bad device usb 0 **
device usb 1:1
** Bad device usb 1 **
device usb 2:1
** Bad device usb 2 **
device usb 3:1
** Bad device usb 3 **
Scan device ide

Reset IDE: ide_preinit failed
device ide 0:1
** Bad device ide 0 **
device ide 1:1
** Bad device ide 1 **
device ide 2:1
** Bad device ide 2 **
device ide 3:1
** Bad device ide 3 **
Scan device mmc

MMC rescan: current device # 0 initialized OK
device mmc 0:1
1 bytes read in 16 ms (0 Bytes/s)
Found bootable drive on mmc 0
loading uImage ...
6223944 bytes read in 311 ms (19.1 MiB/s)
loading uInitrd ...
5791271 bytes read in 287 ms (19.2 MiB/s)
loading DTB /boot/dts/kirkwood-pogoplug_v4.dtb ...
10178 bytes read in 42 ms (236.3 KiB/s)
## Booting kernel from Legacy Image at 00800000 ...
   Image Name:   Linux-6.5.7-kirkwood-tld-1
   Created:      2023-10-31  22:53:22 UTC
   Image Type:   ARM Linux Kernel Image (uncompressed)
   Data Size:    6223880 Bytes = 5.9 MiB
   Load Address: 00008000
   Entry Point:  00008000
   Verifying Checksum ... OK
## Loading init Ramdisk from Legacy Image at 01100000 ...
   Image Name:   initramfs-6.5.7-kirkwood-tld-1
   Created:      2023-10-31  22:53:55 UTC
   Image Type:   ARM Linux RAMDisk Image (gzip compressed)
   Data Size:    5791207 Bytes = 5.5 MiB
   Load Address: 00000000
   Entry Point:  00000000
   Verifying Checksum ... OK
## Flattened Device Tree blob at 01c00000
   Booting using the fdt blob at 0x1c00000
   Loading Kernel Image ... OK
   Loading Ramdisk to 07583000, end 07b08de7 ... OK
   Loading Device Tree to 0757d000, end 075827c1 ... OK
Using machid 0xf78 from environment

Starting kernel ...

[    0.000000][    T0] Booting Linux on physical CPU 0x0
[    0.000000][    T0] Linux version 6.5.7-kirkwood-tld-1 (root@tldDebian) (gcc (Debian 12.2.0-14) 12.2.0, GNU ld (GNU Binutils for Debian) 2.40) #2 PREEMPT Wed Oct 18 21:14:11 PDT 2023
[    0.000000][    T0] CPU: Feroceon 88FR131 [56251311] revision 1 (ARMv5TE), cr=0005397f
[    0.000000][    T0] CPU: VIVT data cache, VIVT instruction cache
[    0.000000][    T0] OF: fdt: Machine model: Pogoplug v4
[    0.000000][    T0] Memory policy: Data cache writeback
[    0.000000][    T0] Zone ranges:
[    0.000000][    T0]   Normal   [mem 0x0000000000000000-0x0000000007ffffff]
[    0.000000][    T0] Movable zone start for each node
[    0.000000][    T0] Early memory node ranges
[    0.000000][    T0]   node   0: [mem 0x0000000000000000-0x0000000007ffffff]
[    0.000000][    T0] Initmem setup node 0 [mem 0x0000000000000000-0x0000000007ffffff]
[    0.000000][    T0] Kernel command line: console=ttyS0,115200 root=/dev/mmcblk0p1 rootwait
[    0.000000][    T0] Dentry cache hash table entries: 16384 (order: 4, 65536 bytes, linear)
[    0.000000][    T0] Inode-cache hash table entries: 8192 (order: 3, 32768 bytes, linear)
[    0.000000][    T0] Built 1 zonelists, mobility grouping on.  Total pages: 32480
[    0.000000][    T0] mem auto-init: stack:off, heap alloc:on, heap free:off
[    0.000000][    T0] Memory: 102528K/131072K available (12288K kernel code, 1755K rwdata, 5416K rodata, 1024K init, 301K bss, 28544K reserved, 0K cma-reserved)
[    0.000000][    T0] SLUB: HWalign=32, Order=0-3, MinObjects=0, CPUs=1, Nodes=1
[    0.000000][    T0] trace event string verifier disabled
[    0.000000][    T0] rcu: Preemptible hierarchical RCU implementation.
[    0.000000][    T0] Trampoline variant of Tasks RCU enabled.
[    0.000000][    T0] Tracing variant of Tasks RCU enabled.
[    0.000000][    T0] rcu: RCU calculated value of scheduler-enlistment delay is 10 jiffies.
[    0.000000][    T0] NR_IRQS: 16, nr_irqs: 16, preallocated irqs: 16
[    0.000000][    T0] rcu: srcu_init: Setting srcu_struct sizes based on contention.
[    0.000000][    T0] clocksource: orion_clocksource: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 11467562657 ns
[    0.000003][    T0] sched_clock: 32 bits at 167MHz, resolution 6ns, wraps every 12884901885ns
[    0.000076][    T0] Switching to timer-based delay loop, resolution 6ns
[    0.000980][    T0] Console: colour dummy device 80x30
[    0.001092][    T0] Calibrating delay loop (skipped), value calculated using timer frequency.. 333.33 BogoMIPS (lpj=1666666)
[    0.001139][    T0] CPU: Testing write buffer coherency: ok
[    0.001344][    T0] pid_max: default: 32768 minimum: 301
[    0.014556][    T0] LSM: initializing lsm=capability,integrity
[    0.018171][    T0] Mount-cache hash table entries: 1024 (order: 0, 4096 bytes, linear)
[    0.018230][    T0] Mountpoint-cache hash table entries: 1024 (order: 0, 4096 bytes, linear)
[    0.037315][    T1] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1.
[    0.037890][    T1] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1.
[    0.038629][    T1] Setting up static identity map for 0x100000 - 0x100058
[    0.039131][    T1] mvebu-soc-id: MVEBU SoC ID=0x6281, Rev=0x3
[    0.039794][    T1] rcu: Hierarchical SRCU implementation.
[    0.039819][    T1] rcu: Max phase no-delay instances is 1000.
[    0.044473][    T1] devtmpfs: initialized
[    0.058049][    T1] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns
[    0.058119][    T1] futex hash table entries: 256 (order: -1, 3072 bytes, linear)
[    0.062665][    T1] prandom: seed boundary self test passed
[    0.069036][    T1] prandom: 100 self tests passed
[    0.069059][    T1] pinctrl core: initialized pinctrl subsystem
[    0.074992][    T1] NET: Registered PF_NETLINK/PF_ROUTE protocol family
[    0.076492][    T1] DMA: preallocated 256 KiB pool for atomic coherent allocations
[    0.085584][    T1] audit: initializing netlink subsys (disabled)
[    0.088810][    T1] thermal_sys: Registered thermal governor 'step_wise'
[    0.089064][   T19] audit: type=2000 audit(0.080:1): state=initialized audit_enabled=0 res=1
[    0.089143][    T1] cpuidle: using governor ladder
[    0.089269][    T1] cpuidle: using governor menu
[    0.090244][    T1] Feroceon L2: Enabling L2
[    0.090298][    T1] Feroceon L2: Cache support initialised.
[    0.104785][    T1] No ATAGs?
[    0.460102][    T1] raid6: int32x8  gen()    74 MB/s
[    0.800093][    T1] raid6: int32x4  gen()    73 MB/s
[    1.140129][    T1] raid6: int32x2  gen()    96 MB/s
[    1.480097][    T1] raid6: int32x1  gen()    76 MB/s
[    1.480117][    T1] raid6: using algorithm int32x2 gen() 96 MB/s
[    1.820093][    T1] raid6: .... xor() 61 MB/s, rmw enabled
[    1.820110][    T1] raid6: using intx1 recovery algorithm
[    1.822536][    T1] SCSI subsystem initialized
[    1.830907][    T1] usbcore: registered new interface driver usbfs
[    1.831009][    T1] usbcore: registered new interface driver hub
[    1.831103][    T1] usbcore: registered new device driver usb
[    1.831722][    T1] pps_core: LinuxPPS API ver. 1 registered
[    1.831735][    T1] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>
[    1.831783][    T1] PTP clock support registered
[    1.840179][    T1] vgaarb: loaded
[    1.841354][    T1] clocksource: Switched to clocksource orion_clocksource
[    1.866546][    T1] VFS: Disk quotas dquot_6.6.0
[    1.867193][    T1] VFS: Dquot-cache hash table entries: 1024 (order 0, 4096 bytes)
[    1.913457][    T1] NET: Registered PF_INET protocol family
[    1.913897][    T1] IP idents hash table entries: 2048 (order: 2, 16384 bytes, linear)
[    1.921642][    T1] tcp_listen_portaddr_hash hash table entries: 1024 (order: 0, 4096 bytes, linear)
[    1.922043][    T1] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)
[    1.922084][    T1] TCP established hash table entries: 1024 (order: 0, 4096 bytes, linear)
[    1.922135][    T1] TCP bind hash table entries: 1024 (order: 1, 8192 bytes, linear)
[    1.922184][    T1] TCP: Hash tables configured (established 1024 bind 1024)
[    1.923227][    T1] MPTCP token hash table entries: 512 (order: 0, 6144 bytes, linear)
[    1.923586][    T1] UDP hash table entries: 256 (order: 0, 4096 bytes, linear)
[    1.923639][    T1] UDP-Lite hash table entries: 256 (order: 0, 4096 bytes, linear)
[    1.924980][    T1] NET: Registered PF_UNIX/PF_LOCAL protocol family
[    1.932351][    T1] RPC: Registered named UNIX socket transport module.
[    1.932373][    T1] RPC: Registered udp transport module.
[    1.932382][    T1] RPC: Registered tcp transport module.
[    1.932391][    T1] RPC: Registered tcp-with-tls transport module.
[    1.932399][    T1] RPC: Registered tcp NFSv4.1 backchannel transport module.
[    1.932431][    T1] PCI: CLS 0 bytes, default 32
[    1.936588][   T32] Trying to unpack rootfs image as initramfs...
[    3.394669][   T32] Freeing initrd memory: 5656K
[    5.106775][    T1] Initialise system trusted keyrings
[    5.106930][    T1] Key type blacklist registered
[    5.109014][    T1] workingset: timestamp_bits=14 max_order=15 bucket_order=1
[    5.109282][    T1] zbud: loaded
[    5.137988][    T1] NFS: Registering the id_resolver key type
[    5.138118][    T1] Key type id_resolver registered
[    5.138133][    T1] Key type id_legacy registered
[    5.138419][    T1] nfs4filelayout_init: NFSv4 File Layout Driver Registering...
[    5.138450][    T1] nfs4flexfilelayout_init: NFSv4 Flexfile Layout Driver Registering...
[    5.140788][    T1] jffs2: version 2.2. (NAND) (SUMMARY)  © 2001-2006 Red Hat, Inc.
[    5.142292][    T1] fuse: init (API version 7.38)
[    5.144420][    T1] orangefs_debugfs_init: called with debug mask: :none: :0:
[    5.144957][    T1] orangefs_init: module version upstream loaded
[    5.145545][    T1] SGI XFS with ACLs, security attributes, realtime, scrub, quota, no debug enabled
[    5.461785][    T1] xor: measuring software checksum speed
[    5.475169][    T1]    arm4regs        :   739 MB/sec
[    5.497205][    T1]    8regs           :   447 MB/sec
[    5.512753][    T1]    32regs          :   634 MB/sec
[    5.512768][    T1] xor: using function: arm4regs (739 MB/sec)
[    5.512791][    T1] async_tx: api initialized (async)
[    5.512814][    T1] Key type asymmetric registered
[    5.512826][    T1] Asymmetric key parser 'x509' registered
[    5.588483][    T1] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 246)
[    5.589149][    T1] io scheduler bfq registered
[    5.871265][    T1] kirkwood-pinctrl f1010000.pin-controller: unknown pinctrl group 47
[    5.871299][    T1] kirkwood-pinctrl f1010000.pin-controller: unknown pinctrl group 49
[    5.872305][    T1] kirkwood-pinctrl f1010000.pin-controller: registered pinctrl driver
[    5.874781][    T1] gpio gpiochip0: Static allocation of GPIO base is deprecated, use dynamic allocation.
[    5.876666][    T1] gpio gpiochip1: Static allocation of GPIO base is deprecated, use dynamic allocation.
[    5.878216][    T1] mvebu-pcie mbus@f1000000:pcie@82000000: host bridge /mbus@f1000000/pcie@82000000 ranges:
[    5.878339][    T1] mvebu-pcie mbus@f1000000:pcie@82000000:      MEM 0x00f1040000..0x00f1041fff -> 0x0000040000
[    5.878406][    T1] mvebu-pcie mbus@f1000000:pcie@82000000:      MEM 0xffffffffffffffff..0x00fffffffe -> 0x0100000000
[    5.878448][    T1] mvebu-pcie mbus@f1000000:pcie@82000000:       IO 0xffffffffffffffff..0x00fffffffe -> 0x0100000000
[    5.879603][    T1] mvebu-pcie mbus@f1000000:pcie@82000000: PCI host bridge to bus 0000:00
[    5.879632][    T1] pci_bus 0000:00: root bus resource [bus 00-ff]
[    5.879661][    T1] pci_bus 0000:00: root bus resource [mem 0xf1040000-0xf1041fff] (bus address [0x00040000-0x00041fff])
[    5.879685][    T1] pci_bus 0000:00: root bus resource [mem 0xe0000000-0xefffffff]
[    5.879707][    T1] pci_bus 0000:00: root bus resource [io  0x1000-0xeffff]
[    5.879863][    T1] pci 0000:00:01.0: [11ab:6281] type 01 class 0x060400
[    5.882513][    T1] PCI: bus0: Fast back to back transfers disabled
[    5.882557][    T1] pci 0000:00:01.0: bridge configuration invalid ([bus 00-00]), reconfiguring
[    5.883045][    T1] PCI: bus1: Fast back to back transfers enabled
[    5.883078][    T1] pci_bus 0000:01: busn_res: [bus 01-ff] end is updated to 01
[    5.883141][    T1] pci 0000:00:01.0: PCI bridge to [bus 01]
[    5.884655][    T1] mv_xor f1060800.xor: Marvell shared XOR driver
[    5.965339][    T1] mv_xor f1060800.xor: Marvell XOR (Registers Mode): ( xor cpy intr )
[    5.972594][    T1] mv_xor f1060900.xor: Marvell shared XOR driver
[    6.045327][    T1] mv_xor f1060900.xor: Marvell XOR (Registers Mode): ( xor cpy intr )
[    6.052965][    T1] Serial: 8250/16550 driver, 2 ports, IRQ sharing disabled
[    6.056875][    T1] printk: console [ttyS0] disabled
[    6.057850][    T1] f1012000.serial: ttyS0 at MMIO 0xf1012000 (irq = 28, base_baud = 10416666) is a 16550A
[    6.057948][    T1] printk: console [ttyS0] enabled
[    7.064057][    T1] loop: module loaded
[    7.069464][    T1] sata_mv f1080000.sata: slots 32 ports 2
[    7.082395][    T1] scsi host0: sata_mv
[    7.088637][    T1] scsi host1: sata_mv
[    7.093225][    T1] ata1: SATA max UDMA/133 irq 29
[    7.098022][    T1] ata2: SATA max UDMA/133 irq 29
[    7.104895][    T1] nand: device found, Manufacturer ID: 0xad, Chip ID: 0xf1
[    7.111999][    T1] nand: Hynix H27U1G8F2BTR-BC
[    7.116526][    T1] nand: 128 MiB, SLC, erase size: 128 KiB, page size: 2048, OOB size: 64
[    7.124845][    T1] Scanning device for bad blocks
[    7.214769][    T1] 5 fixed-partitions partitions found on MTD device orion_nand
[    7.222181][    T1] Creating 5 MTD partitions on "orion_nand":
[    7.228008][    T1] 0x000000000000-0x000000200000 : "u-boot"
[    7.235041][    T1] 0x000000200000-0x000000500000 : "uImage"
[    7.241799][    T1] 0x000000500000-0x000000800000 : "uImage2"
[    7.248675][    T1] 0x000000800000-0x000001000000 : "failsafe"
[    7.255614][    T1] 0x000001000000-0x000008000000 : "root"
[    7.264315][    T1] wireguard: WireGuard 1.0.0 loaded. See www.wireguard.com for information.
[    7.272877][    T1] wireguard: Copyright (C) 2015-2019 Jason A. Donenfeld <Jason@zx2c4.com>. All Rights Reserved.
[    7.322639][    T1] mv643xx_eth: MV-643xx 10/100/1000 ethernet driver version 1.4
[    7.431803][   T53] ata1: SATA link down (SStatus 0 SControl F300)
[    7.773180][   T55] ata2: SATA link down (SStatus 0 SControl F300)
[    8.063406][    T1] mv643xx_eth_port mv643xx_eth_port.0 eth0: port 0 with MAC address 00:25:31:03:ee:c2
[    8.073817][    T1] orion-ehci f1050000.ehci: EHCI Host Controller
[    8.080045][    T1] orion-ehci f1050000.ehci: new USB bus registered, assigned bus number 1
[    8.088804][    T1] orion-ehci f1050000.ehci: irq 32, io mem 0xf1050000
[    8.121401][    T1] orion-ehci f1050000.ehci: USB 2.0 started, EHCI 1.00
[    8.128578][    T1] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 6.05
[    8.137569][    T1] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[    8.145505][    T1] usb usb1: Product: EHCI Host Controller
[    8.151075][    T1] usb usb1: Manufacturer: Linux 6.5.7-kirkwood-tld-1 ehci_hcd
[    8.158393][    T1] usb usb1: SerialNumber: f1050000.ehci
[    8.165332][    T1] hub 1-0:1.0: USB hub found
[    8.169841][    T1] hub 1-0:1.0: 1 port detected
[    8.176074][    T1] usbcore: registered new interface driver usb-storage
[    8.183783][    T1] mousedev: PS/2 mouse device common for all mice
[    9.201441][    T1] rtc-mv f1010300.rtc: internal RTC not ticking
[    9.207900][    T1] i2c_dev: i2c /dev entries driver
[    9.214580][    T1] device-mapper: uevent: version 1.0.3
[    9.220712][    T1] device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@redhat.com
[    9.230947][    T1] device-mapper: multipath round-robin: version 1.2.0 loaded
[    9.238277][    T1] device-mapper: multipath queue-length: version 0.2.0 loaded
[    9.245619][    T1] device-mapper: multipath service-time: version 0.3.0 loaded
[    9.253046][    T1] device-mapper: dm-log-userspace: version 1.3.0 loaded
[    9.262495][    T1] ledtrig-cpu: registered to indicate activity on CPUs
[    9.269420][    T1] hid: raw HID events driver (C) Jiri Kosina
[    9.275919][    T1] drop_monitor: Initializing network drop monitor service
[    9.285328][    T1] NET: Registered PF_INET6 protocol family
[    9.294165][    T1] Segment Routing with IPv6
[    9.298535][    T1] RPL Segment Routing with IPv6
[    9.303488][    T1] In-situ OAM (IOAM) with IPv6
[    9.308373][    T1] NET: Registered PF_PACKET protocol family
[    9.314758][    T1] Key type dns_resolver registered
[    9.348047][    T1] registered taskstats version 1
[    9.353465][    T1] Loading compiled-in X.509 certificates
[    9.391254][    T1] Key type .fscrypt registered
[    9.395987][    T1] Key type fscrypt-provisioning registered
[    9.412019][    T1] Key type big_key registered
[    9.484119][    T1] Key type encrypted registered
[    9.648560][  T104] "cryptomgr_test" (104) uses obsolete ecb(arc4) skcipher
[   13.077210][    T1] clk: Disabling unused clocks
[   13.086949][    T1] Freeing unused kernel image (initmem) memory: 1024K
[   13.102700][    T1] Checked W+X mappings: passed, no W+X pages found
[   13.109069][    T1] Run /init as init process
Loading, please wait...
Starting systemd-udevd version 252.17-1~deb12u1
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 ... Begin: Running /scripts/local-block ... done.
Begin: Running /scripts/local-block ... done.
Begin: Running /scripts/local-block ... done.
Begin: Running /scripts/local-block ... done.
Begin: Running /scripts/local-block ... done.
Begin: Running /scripts/local-block ... done.
Begin: Running /scripts/local-block ... done.
Begin: Running /scripts/local-block ... done.
Begin: Running /scripts/local-block ... done.
Begin: Running /scripts/local-block ... done.
Begin: Running /scripts/local-block ... done.
Begin: Running /scripts/local-block ... done.
Begin: Running /scripts/local-block ... done.
Begin: Running /scripts/local-block ... done.
Begin: Running /scripts/local-block ... done.
Begin: Running /scripts/local-block ... done.
Begin: Running /scripts/local-block ... done.
Begin: Running /scripts/local-block ... done.
Begin: Running /scripts/local-block ... done.
Begin: Running /scripts/local-block ... done.
Begin: Running /scripts/local-block ... done.
Begin: Running /scripts/local-block ... done.
Begin: Running /scripts/local-block ... done.
Begin: Running /scripts/local-block ... done.
Begin: Running /scripts/local-block ... done.
Begin: Running /scripts/local-block ... done.
Begin: Running /scripts/local-block ... done.
Begin: Running /scripts/local-block ... done.
Begin: Running /scripts/local-block ... done.
Begin: Running /scripts/local-block ... done.
done.
Gave up waiting for root file system device.  Common problems:
 - Boot args (cat /proc/cmdline)
   - Check rootdelay= (did the system wait long enough?)
 - Missing modules (cat /proc/modules; ls /dev)
ALERT!  /dev/mmcblk0p1 does not exist.  Dropping to a shell!


BusyBox v1.35.0 (Debian 1:1.35.0-4+b3) built-in shell (ash)
Enter 'help' for a list of built-in commands.

(initramfs)
Re: SD Card problem loading rootfs too slow?
November 14, 2023 06:47PM
Sc30,

> It did not change anything. Why the card detect io
> signal not detected until 32 seconds?

Apparently this SD card is not good for booting. One of my Pogo V4's boots with SD card. And it is usually detected at around 15 seconds. Like this,

[   15.831029][   T18] mvsdio f1090000.mvsdio: Got CD GPIO
[   15.912201][    T9] mmc0: host does not support reading read-only switch, assuming write-enable
[   15.931273][    T9] mmc0: new high speed SDHC card at address e624
[   16.301269][  T148] mmcblk0: mmc0:e624 SU16G 14.8 GiB
[   16.313249][  T148]  mmcblk0: p1
Generally, card detection is not a problem for this Pogo V4. But not all SD cards are equal in that respect during booting.

I might be able to help overcome slow SD cards, but it will be in the next kernel release. In the mean time, try using a different SD card (preferably Sandisk brand which is most reliable for booting).

-bodhi
===========================
Forum Wiki
bodhi's corner (buy bodhi a beer)
Re: SD Card problem loading rootfs too slow?
November 14, 2023 08:09PM
Thanks for the information. Are you running the same kernel on your pogo v4? What is the mmc driver doing that is dependent on the speed of the card? Shouldn't the card detect come before the card is even initialized?
Re: SD Card problem loading rootfs too slow?
November 14, 2023 09:10PM
> Thanks for the information. Are you running the
> same kernel on your pogo v4?

Yes, the latest kernel 6.5.7 kirkwood.

> What is the mmc
> driver doing that is dependent on the speed of the
> card? Shouldn't the card detect come before the
> card is even initialized?

That's not quite how it works in this case. This port has CD (card detection) GPIO enabled, so when the kernel enumerates devices from the DTS, it'll trigger the whole sequence.

Whatever the Lexar card controller does, we don't know.

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



Edited 2 time(s). Last edit at 11/14/2023 09:26PM by bodhi.
Re: SD Card problem loading rootfs too slow?
November 15, 2023 07:55AM
Hmm. I am still confused as why your CD is coming so much earlier. Even when I put a dummy card (just plastic) in the slot I get the CD but still not until 32 seconds, after hotplug is started. Do you have pogoplug V4 or Mobile? Mine is Mobile. Can you post your whole boot log?

Starting hotplug events dispatcher: systemd-udevd.
Synthesizing the initial hotplug events (subsystems)...done.
Synthesizing the initial hotplug events (devices)...done.
Waiting for /dev to be fully populated...[   31.073498][  T356] input: gpio_keys as /devices/platform/gpio_keys/input/input0
[   31.622158][  T360] orion_wdt: Initial timeout 25 sec
[   32.366615][   T10] mvsdio f1090000.mvsdio: Got CD GPIO
[   32.611604][  T355] marvell-cesa f1030000.crypto: CESA device successfully registered
[   32.703737][  T359] sd 2:0:0:0: Attached scsi generic sg0 type 0
[   32.949271][   T60] random: crng init done
done.

It seems possibly similar to https://forum.doozan.com/read.php?2,13355,13366



Edited 1 time(s). Last edit at 11/15/2023 08:04AM by Sc30.
Re: SD Card problem loading rootfs too slow?
November 15, 2023 10:51AM
I decided to try Debian 5.13.6 kirkwood and it works even on class 4 card, so I think probably a kernel configuration problem.

[   17.925001][  T137] input: gpio_keys as /devices/platform/gpio_keys/input/input0
[   18.275032][   T58] mvsdio f1090000.mvsdio: Got CD GPIO
[   18.343508][  T104] mmc0: host does not support reading read-only switch, assuming write-enable
[   18.360517][  T104] mmc0: new high speed SDHC card at address b368
[   18.718699][  T137] mmcblk0: mmc0:b368 LEXAR 3.73 GiB 
[   18.728328][  T137]  mmcblk0: p1


U-Boot 2017.07-tld-1 (Sep 05 2017 - 00:34:01 -0700)
Pogoplug V4

SoC:   Kirkwood 88F6192_A1
DRAM:  128 MiB
WARNING: Caches not enabled
NAND:  128 MiB
MMC:   MVEBU_MMC: 0
In:    serial
Out:   serial
Err:   serial
Net:   egiga0
Hit any key to stop autoboot: 10  9  8  7  6  5  4  3  2  1  0 
starting USB...
USB0:   USB EHCI 1.00
scanning bus 0 for devices... 1 USB Device(s) found
       scanning usb for storage devices... 0 Storage Device(s) found

Reset IDE: ide_preinit failed

MMC rescan: current device # 0 initialized OK
## Unknown partition table type 0

## Unknown partition table type 0

## Unknown partition table type 0

## Unknown partition table type 0

## Unknown partition table type 0

## Unknown partition table type 0

## Unknown partition table type 0
loading envs from usb 0 ...
** Bad device usb 0 **

no IDE devices available

Partition Map for MMC device 0  --   Partition Type: DOS

PartStart SectorNum SectorsUUIDType
  12048      7827456   bf0257d0-0183
loading envs from mmc 0 ...
** File not found /boot/uEnv.txt **
running scan_disk ...
Scan device usb
device usb 0:1
** Bad device usb 0 **
device usb 1:1
** Bad device usb 1 **
device usb 2:1
** Bad device usb 2 **
device usb 3:1
** Bad device usb 3 **
Scan device ide

Reset IDE: ide_preinit failed
device ide 0:1
** Bad device ide 0 **
device ide 1:1
** Bad device ide 1 **
device ide 2:1
** Bad device ide 2 **
device ide 3:1
** Bad device ide 3 **
Scan device mmc

MMC rescan: current device # 0 initialized OK
device mmc 0:1
1 bytes read in 14 ms (0 Bytes/s)
Found bootable drive on mmc 0
loading uImage ...
5435888 bytes read in 259 ms (20 MiB/s)
loading uInitrd ...
9671091 bytes read in 457 ms (20.2 MiB/s)
loading DTB /boot/dts/kirkwood-pogoplug_v4.dtb ...
9970 bytes read in 36 ms (269.5 KiB/s)
## Booting kernel from Legacy Image at 00800000 ...
   Image Name:   Linux-5.13.6-kirkwood-tld-1
   Created:      2021-09-24   0:22:58 UTC
   Image Type:   ARM Linux Kernel Image (uncompressed)
   Data Size:    5435824 Bytes = 5.2 MiB
   Load Address: 00008000
   Entry Point:  00008000
   Verifying Checksum ... OK
## Loading init Ramdisk from Legacy Image at 01100000 ...
   Image Name:   initramfs-5.13.6-kirkwood-tld-1
   Created:      2021-09-24   1:06:32 UTC
   Image Type:   ARM Linux RAMDisk Image (gzip compressed)
   Data Size:    9671027 Bytes = 9.2 MiB
   Load Address: 00000000
   Entry Point:  00000000
   Verifying Checksum ... OK
## Flattened Device Tree blob at 01c00000
   Booting using the fdt blob at 0x1c00000
   Loading Kernel Image ... OK
   Loading Ramdisk to 071d0000, end 07b09173 ... OK
   Loading Device Tree to 071ca000, end 071cf6f1 ... OK
Using machid 0xf78 from environment

Starting kernel ...

Uncompressing Linux... done, booting the kernel.
[    0.000000][    T0] Booting Linux on physical CPU 0x0
[    0.000000][    T0] Linux version 5.13.6-kirkwood-tld-1 (root@tldDebian) (gcc (Debian 8.3.0-6) 8.3.0, GNU ld (GNU Binutils for Debian) 2.31.1) #1.0 PREEMPT Sat Jul 31 22:10:39 PDT 2021
[    0.000000][    T0] CPU: Feroceon 88FR131 [56251311] revision 1 (ARMv5TE), cr=0005397f
[    0.000000][    T0] CPU: VIVT data cache, VIVT instruction cache
[    0.000000][    T0] OF: fdt: Machine model: Pogoplug v4
[    0.000000][    T0] Memory policy: Data cache writeback
[    0.000000][    T0] Zone ranges:
[    0.000000][    T0]   Normal   [mem 0x0000000000000000-0x0000000007ffffff]
[    0.000000][    T0] Movable zone start for each node
[    0.000000][    T0] Early memory node ranges
[    0.000000][    T0]   node   0: [mem 0x0000000000000000-0x0000000007ffffff]
[    0.000000][    T0] Initmem setup node 0 [mem 0x0000000000000000-0x0000000007ffffff]
[    0.000000][    T0] Built 1 zonelists, mobility grouping on.  Total pages: 32512
[    0.000000][    T0] Kernel command line: console=ttyS0,115200 root=LABEL=rootfs rootdelay=10 mtdparts=orion_nand:2M(u-boot),3M(uImage),3M(uImage2),8M(failsafe),112M(root)
[    0.000000][    T0] Dentry cache hash table entries: 16384 (order: 4, 65536 bytes, linear)
[    0.000000][    T0] Inode-cache hash table entries: 8192 (order: 3, 32768 bytes, linear)
[    0.000000][    T0] mem auto-init: stack:off, heap alloc:on, heap free:off
[    0.000000][    T0] Memory: 101108K/131072K available (11264K kernel code, 1553K rwdata, 4272K rodata, 1024K init, 309K bss, 29964K reserved, 0K cma-reserved)
[    0.000000][    T0] random: get_random_u32 called from ____cache_alloc+0x408/0x7b4 with crng_init=0
[    0.000000][    T0] trace event string verifier disabled
[    0.000000][    T0] rcu: Preemptible hierarchical RCU implementation.
[    0.000000][    T0] Trampoline variant of Tasks RCU enabled.
[    0.000000][    T0] Tracing variant of Tasks RCU enabled.
[    0.000000][    T0] rcu: RCU calculated value of scheduler-enlistment delay is 10 jiffies.
[    0.000000][    T0] NR_IRQS: 16, nr_irqs: 16, preallocated irqs: 16
[    0.000000][    T0] clocksource: orion_clocksource: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 11467562657 ns
[    0.000003][    T0] sched_clock: 32 bits at 166MHz, resolution 6ns, wraps every 12884901885ns
[    0.000090][    T0] Switching to timer-based delay loop, resolution 6ns
[    0.001390][    T0] Console: colour dummy device 80x30
[    0.001505][    T0] Calibrating delay loop (skipped), value calculated using timer frequency.. 333.33 BogoMIPS (lpj=1666666)
[    0.001568][    T0] pid_max: default: 32768 minimum: 301
[    0.002174][    T0] LSM: Security Framework initializing
[    0.002444][    T0] Mount-cache hash table entries: 1024 (order: 0, 4096 bytes, linear)
[    0.002518][    T0] Mountpoint-cache hash table entries: 1024 (order: 0, 4096 bytes, linear)
[    0.004730][    T0] CPU: Testing write buffer coherency: ok
[    0.008420][    T1] Setting up static identity map for 0x100000 - 0x100058
[    0.008887][    T1] mvebu-soc-id: MVEBU SoC ID=0x6281, Rev=0x3
[    0.009403][    T1] rcu: Hierarchical SRCU implementation.
[    0.011667][    T1] devtmpfs: initialized
[    0.020016][    T1] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns
[    0.020182][    T1] futex hash table entries: 256 (order: -1, 3072 bytes, linear)
[    0.076205][    T1] prandom32: self test passed (less than 6 bits correlated)
[    0.076273][    T1] prandom: seed boundary self test passed
[    0.082608][    T1] prandom: 100 self tests passed
[    0.082656][    T1] pinctrl core: initialized pinctrl subsystem
[    0.085163][    T1] NET: Registered protocol family 16
[    0.086505][    T1] DMA: preallocated 256 KiB pool for atomic coherent allocations
[    0.088029][    T1] audit: initializing netlink subsys (disabled)
[    0.090310][    T1] thermal_sys: Registered thermal governor 'step_wise'
[    0.090869][   T16] audit: type=2000 audit(0.080:1): state=initialized audit_enabled=0 res=1
[    0.091162][    T1] cpuidle: using governor ladder
[    0.091308][    T1] cpuidle: using governor menu
[    0.092088][    T1] Feroceon L2: Enabling L2
[    0.092167][    T1] Feroceon L2: Cache support initialised.
[    0.105745][    T1] No ATAGs?
[    2.560099][    C0] random: fast init done
[    7.400928][   T39] "cryptomgr_test" (39) uses obsolete ecb(arc4) skcipher
[    7.439934][   T59] wait_for_initramfs() called before rootfs_initcalls
[    7.650273][    T1] raid6: int32x8  gen()    74 MB/s
[    7.820199][    T1] raid6: int32x8  xor()    46 MB/s
[    7.990397][    T1] raid6: int32x4  gen()    76 MB/s
[    8.160248][    T1] raid6: int32x4  xor()    49 MB/s
[    8.330269][    T1] raid6: int32x2  gen()    99 MB/s
[    8.500234][    T1] raid6: int32x2  xor()    60 MB/s
[    8.670183][    T1] raid6: int32x1  gen()    75 MB/s
[    8.840134][    T1] raid6: int32x1  xor()    42 MB/s
[    8.840159][    T1] raid6: using algorithm int32x2 gen() 99 MB/s
[    8.840180][    T1] raid6: .... xor() 60 MB/s, rmw enabled
[    8.840198][    T1] raid6: using intx1 recovery algorithm
[    8.840591][    T1] vgaarb: loaded
[    8.841452][    T1] SCSI subsystem initialized
[    8.842246][    T1] usbcore: registered new interface driver usbfs
[    8.842355][    T1] usbcore: registered new interface driver hub
[    8.842440][    T1] usbcore: registered new device driver usb
[    8.842777][    T1] pps_core: LinuxPPS API ver. 1 registered
[    8.842800][    T1] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>
[    8.842853][    T1] PTP clock support registered
[    8.845596][    T1] clocksource: Switched to clocksource orion_clocksource
[    8.996613][    T1] VFS: Disk quotas dquot_6.6.0
[    8.996775][    T1] VFS: Dquot-cache hash table entries: 1024 (order 0, 4096 bytes)
[    9.019711][    T1] NET: Registered protocol family 2
[    9.019960][    T1] IP idents hash table entries: 2048 (order: 2, 16384 bytes, linear)
[    9.021366][    T1] tcp_listen_portaddr_hash hash table entries: 512 (order: 0, 4096 bytes, linear)
[    9.021446][    T1] TCP established hash table entries: 1024 (order: 0, 4096 bytes, linear)
[    9.021498][    T1] TCP bind hash table entries: 1024 (order: 0, 4096 bytes, linear)
[    9.021541][    T1] TCP: Hash tables configured (established 1024 bind 1024)
[    9.021932][    T1] MPTCP token hash table entries: 512 (order: 0, 6144 bytes, linear)
[    9.022045][    T1] UDP hash table entries: 256 (order: 0, 4096 bytes, linear)
[    9.022109][    T1] UDP-Lite hash table entries: 256 (order: 0, 4096 bytes, linear)
[    9.022459][    T1] NET: Registered protocol family 1
[    9.036222][    T1] RPC: Registered named UNIX socket transport module.
[    9.036260][    T1] RPC: Registered udp transport module.
[    9.036279][    T1] RPC: Registered tcp transport module.
[    9.036296][    T1] RPC: Registered tcp NFSv4.1 backchannel transport module.
[    9.036323][    T1] PCI: CLS 0 bytes, default 32
[    9.036592][    T1] NetWinder Floating Point Emulator V0.97 (double precision)
[    9.037479][   T58] Trying to unpack rootfs image as initramfs...
[   11.616394][   T58] Freeing initrd memory: 9448K
[   12.555746][    T1] Initialise system trusted keyrings
[   12.555848][    T1] Key type blacklist registered
[   12.556234][    T1] workingset: timestamp_bits=30 max_order=15 bucket_order=0
[   12.556421][    T1] zbud: loaded
[   12.558676][    T1] NFS: Registering the id_resolver key type
[   12.558741][    T1] Key type id_resolver registered
[   12.558763][    T1] Key type id_legacy registered
[   12.558829][    T1] nfs4filelayout_init: NFSv4 File Layout Driver Registering...
[   12.558866][    T1] nfs4flexfilelayout_init: NFSv4 Flexfile Layout Driver Registering...
[   12.558889][    T1] Installing knfsd (copyright (C) 1996 okir@monad.swb.de).
[   12.559297][    T1] jffs2: version 2.2. (NAND) (SUMMARY)  © 2001-2006 Red Hat, Inc.
[   12.560134][    T1] fuse: init (API version 7.34)
[   12.560739][    T1] orangefs_debugfs_init: called with debug mask: :none: :0:
[   12.561072][    T1] orangefs_init: module version upstream loaded
[   12.561102][    T1] SGI XFS with ACLs, security attributes, realtime, scrub, quota, no debug enabled
[   12.712050][    T1] xor: measuring software checksum speed
[   12.725512][    T1]    arm4regs        :   739 MB/sec
[   12.747655][    T1]    8regs           :   446 MB/sec
[   12.762857][    T1]    32regs          :   651 MB/sec
[   12.762884][    T1] xor: using function: arm4regs (739 MB/sec)
[   12.762919][    T1] async_tx: api initialized (async)
[   12.762949][    T1] Key type asymmetric registered
[   12.762970][    T1] Asymmetric key parser 'x509' registered
[   12.763063][    T1] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 246)
[   12.763181][    T1] io scheduler bfq registered
[   13.060638][    T1] kirkwood-pinctrl f1010000.pin-controller: unknown pinctrl group 47
[   13.060685][    T1] kirkwood-pinctrl f1010000.pin-controller: unknown pinctrl group 49
[   13.061262][    T1] kirkwood-pinctrl f1010000.pin-controller: registered pinctrl driver
[   13.064128][    T1] mvebu-pcie mbus@f1000000:pcie@82000000: host bridge /mbus@f1000000/pcie@82000000 ranges:
[   13.064261][    T1] mvebu-pcie mbus@f1000000:pcie@82000000:      MEM 0x00f1040000..0x00f1041fff -> 0x0000040000
[   13.064331][    T1] mvebu-pcie mbus@f1000000:pcie@82000000:      MEM 0xffffffffffffffff..0x00fffffffe -> 0x0100000000
[   13.064381][    T1] mvebu-pcie mbus@f1000000:pcie@82000000:       IO 0xffffffffffffffff..0x00fffffffe -> 0x0100000000
[   13.064820][    T1] mvebu-pcie mbus@f1000000:pcie@82000000: PCI host bridge to bus 0000:00
[   13.064857][    T1] pci_bus 0000:00: root bus resource [bus 00-ff]
[   13.064893][    T1] pci_bus 0000:00: root bus resource [mem 0xf1040000-0xf1041fff] (bus address [0x00040000-0x00041fff])
[   13.064926][    T1] pci_bus 0000:00: root bus resource [mem 0xe0000000-0xefffffff]
[   13.064956][    T1] pci_bus 0000:00: root bus resource [io  0x1000-0xeffff]
[   13.065137][    T1] pci 0000:00:01.0: [11ab:6281] type 01 class 0x060400
[   13.065196][    T1] pci 0000:00:01.0: reg 0x38: [mem 0x00000000-0x000007ff pref]
[   13.066981][    T1] PCI: bus0: Fast back to back transfers disabled
[   13.067037][    T1] pci 0000:00:01.0: bridge configuration invalid ([bus 00-00]), reconfiguring
[   13.068566][    T1] PCI: bus1: Fast back to back transfers enabled
[   13.068612][    T1] pci_bus 0000:01: busn_res: [bus 01-ff] end is updated to 01
[   13.068929][    T1] pci 0000:00:01.0: BAR 6: assigned [mem 0xe0000000-0xe00007ff pref]
[   13.068973][    T1] pci 0000:00:01.0: PCI bridge to [bus 01]
[   13.069897][    T1] mv_xor f1060800.xor: Marvell shared XOR driver
[   13.127274][    T1] mv_xor f1060800.xor: Marvell XOR (Registers Mode): ( xor cpy intr )
[   13.131229][    T1] mv_xor f1060900.xor: Marvell shared XOR driver
[   13.197271][    T1] mv_xor f1060900.xor: Marvell XOR (Registers Mode): ( xor cpy intr )
[   13.201634][    T1] Serial: 8250/16550 driver, 2 ports, IRQ sharing disabled
[   13.203754][    T1] printk: console [ttyS0] disabled
[   13.203900][    T1] f1012000.serial: ttyS0 at MMIO 0xf1012000 (irq = 25, base_baud = 10416666) is a 16550A
[   14.200713][    T1] printk: console [ttyS0] enabled
[   14.218833][    T1] loop: module loaded
[   14.223886][    T1] sata_mv f1080000.sata: slots 32 ports 2
[   14.235203][    T1] scsi host0: sata_mv
[   14.240086][    T1] scsi host1: sata_mv
[   14.244328][    T1] ata1: SATA max UDMA/133 irq 32
[   14.249200][    T1] ata2: SATA max UDMA/133 irq 32
[   14.255576][    T1] nand: device found, Manufacturer ID: 0xad, Chip ID: 0xf1
[   14.262670][    T1] nand: Hynix H27U1G8F2BTR-BC
[   14.267214][    T1] nand: 128 MiB, SLC, erase size: 128 KiB, page size: 2048, OOB size: 64
[   14.275930][    T1] Scanning device for bad blocks
[   14.381723][    T1] 5 cmdlinepart partitions found on MTD device orion_nand
[   14.388702][    T1] Creating 5 MTD partitions on "orion_nand":
[   14.394529][    T1] 0x000000000000-0x000000200000 : "u-boot"
[   14.402516][    T1] 0x000000200000-0x000000500000 : "uImage"
[   14.410338][    T1] 0x000000500000-0x000000800000 : "uImage2"
[   14.418215][    T1] 0x000000800000-0x000001000000 : "failsafe"
[   14.426198][    T1] 0x000001000000-0x000008000000 : "root"
[   14.435321][    T1] wireguard: WireGuard 1.0.0 loaded. See www.wireguard.com for information.
[   14.443893][    T1] wireguard: Copyright (C) 2015-2019 Jason A. Donenfeld <Jason@zx2c4.com>. All Rights Reserved.
[   14.456104][    T1] libphy: Fixed MDIO Bus: probed
[   14.463134][    T1] libphy: orion_mdio_bus: probed
[   14.492759][    T1] mv643xx_eth: MV-643xx 10/100/1000 ethernet driver version 1.4
[   14.576665][   T94] ata1: SATA link down (SStatus 0 SControl F300)
[   14.917405][   T96] ata2: SATA link down (SStatus 0 SControl F300)
[   15.257059][    T1] mv643xx_eth_port mv643xx_eth_port.0 eth0: port 0 with MAC address 00:25:31:03:ee:c2
[   15.266716][    T1] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver
[   15.273961][    T1] ehci-pci: EHCI PCI platform driver
[   15.279213][    T1] ehci-orion: EHCI orion driver
[   15.284215][    T1] orion-ehci f1050000.ehci: EHCI Host Controller
[   15.290474][    T1] orion-ehci f1050000.ehci: new USB bus registered, assigned bus number 1
[   15.299099][    T1] orion-ehci f1050000.ehci: irq 29, io mem 0xf1050000
[   15.335648][    T1] orion-ehci f1050000.ehci: USB 2.0 started, EHCI 1.00
[   15.342751][    T1] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 5.13
[   15.351736][    T1] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[   15.359665][    T1] usb usb1: Product: EHCI Host Controller
[   15.365228][    T1] usb usb1: Manufacturer: Linux 5.13.6-kirkwood-tld-1 ehci_hcd
[   15.372640][    T1] usb usb1: SerialNumber: f1050000.ehci
[   15.379074][    T1] hub 1-0:1.0: USB hub found
[   15.383595][    T1] hub 1-0:1.0: 1 port detected
[   15.389480][    T1] usbcore: registered new interface driver usb-storage
[   15.396831][    T1] mousedev: PS/2 mouse device common for all mice
[   16.485796][    T1] rtc-mv f1010300.rtc: internal RTC not ticking
[   16.492140][    T1] i2c /dev entries driver
[   16.497379][    T1] device-mapper: uevent: version 1.0.3
[   16.503141][    T1] device-mapper: ioctl: 4.45.0-ioctl (2021-03-22) initialised: dm-devel@redhat.com
[   16.512974][    T1] device-mapper: multipath round-robin: version 1.2.0 loaded
[   16.520274][    T1] device-mapper: multipath queue-length: version 0.2.0 loaded
[   16.527610][    T1] device-mapper: multipath service-time: version 0.3.0 loaded
[   16.534995][    T1] device-mapper: dm-log-userspace: version 1.3.0 loaded
[   16.541797][    T1] device-mapper: raid: Loading target version 1.15.1
[   16.550548][    T1] hid: raw HID events driver (C) Jiri Kosina
[   16.557046][    T1] drop_monitor: Initializing network drop monitor service
[   16.564704][    T1] NET: Registered protocol family 10
[   16.571976][    T1] Segment Routing with IPv6
[   16.576473][    T1] RPL Segment Routing with IPv6
[   16.581299][    T1] NET: Registered protocol family 17
[   16.586832][    T1] Key type dns_resolver registered
[   16.592611][    T1] registered taskstats version 1
[   16.597515][    T1] Loading compiled-in X.509 certificates
[   16.603661][    T1] zswap: loaded using pool lzo/zbud
[   16.609299][    T1] Key type ._fscrypt registered
[   16.614003][    T1] Key type .fscrypt registered
[   16.618711][    T1] Key type fscrypt-provisioning registered
[   16.627979][    T1] Key type big_key registered
[   16.677732][    T1] Key type encrypted registered
[   16.692084][    T1] Freeing unused kernel memory: 1024K
[   16.716912][    T1] Checked W+X mappings: passed, no W+X pages found
[   16.723285][    T1] Run /init as init process
Loading, please wait...
Starting version 247.3-6
[   17.925001][  T137] input: gpio_keys as /devices/platform/gpio_keys/input/input0
[   18.275032][   T58] mvsdio f1090000.mvsdio: Got CD GPIO
[   18.343508][  T104] mmc0: host does not support reading read-only switch, assuming write-enable
[   18.360517][  T104] mmc0: new high speed SDHC card at address b368
[   18.718699][  T137] mmcblk0: mmc0:b368 LEXAR 3.73 GiB 
[   18.728328][  T137]  mmcblk0: p1
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: Will now check root file system ... fsck from util-linux 2.36.1
[/sbin/fsck.ext3 (1) -- /dev/mmcblk0p1] fsck.ext3 -a -C0 /dev/mmcblk0p1 
rootfs: clean, 15640/244800 files, 200265/978432 blocks
done.
[   29.306329][  T165] EXT4-fs (mmcblk0p1): mounting ext3 file system using the ext4 subsystem
[   29.476101][  T165] EXT4-fs (mmcblk0p1): mounted filesystem with ordered data mode. Opts: (null). Quota mode: none.
done.
Begin: Running /scripts/local-bottom ... done.
Begin: Running /scripts/init-bottom ... done.

INIT: version 2.96 booting

Using makefile-style concurrent boot in runlevel S.
Setting hostname to 'debian'...done.
Starting hotplug events dispatcher: systemd-udevd.
Synthesizing the initial hotplug events (subsystems)...done.
Synthesizing the initial hotplug events (devices)...done.
Waiting for /dev to be fully populated...[   33.882627][  T356] orion_wdt: Initial timeout 25 sec
[   34.612894][  T350] marvell-cesa f1030000.crypto: CESA device successfully registered
done.
Activating swap:.
[   35.931126][  T498] EXT4-fs (mmcblk0p1): re-mounted. Opts: errors=remount-ro. Quota mode: none.
Will now check all file systems.
Checking all file systems.
LABEL=rootfs is mounted
Done checking file systems.
Log is being saved in /var/log/fsck/checkfs if that location is writable.
Cleaning up temporary files...Cleaning /tmp...done.
 /tmp.
Will now mount local filesystems:.
Will now activate swapfile swap, if any:done.
Checking minimum space in /tmp...done.
Cleaning up temporary files....
[   39.197644][  T870] random: dd: uninitialized urandom read (512 bytes read)
Initializing random number generator...done.
Starting Setting kernel variables: sysctl.
Configuring network interfaces...Internet Systems Consortium DHCP Client 4.4.1
Copyright 2004-2018 Internet Systems Consortium.
All rights reserved.
For info, please visit https://www.isc.org/software/dhcp/

Listening on LPF/eth0/00:25:31:03:ee:c2
Sending on   LPF/eth0/00:25:31:03:ee:c2
Sending on   Socket/fallback
DHCPREQUEST for 192.168.11.75 on eth0 to 255.255.255.255 port 67
[   43.057043][    C0] mv643xx_eth_port mv643xx_eth_port.0 eth0: link up, 1000 Mb/s, full duplex, flow control disabled
[   43.067663][  T104] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready
DHCPREQUEST for 192.168.11.75 on eth0 to 255.255.255.255 port 67
DHCPREQUEST for 192.168.11.75 on eth0 to 255.255.255.255 port 67
DHCPDISCOVER on eth0 to 255.255.255.255 port 67 interval 3
DHCPOFFER of 192.168.11.75 from 192.168.11.1
DHCPREQUEST for 192.168.11.75 on eth0 to 255.255.255.255 port 67
DHCPACK of 192.168.11.75 from 192.168.11.1
bound to 192.168.11.75 -- renewal in 36948 seconds.
done.
Starting RPC port mapper daemon: rpcbind.
Starting NFS common utilities: statd idmapd.
Cleaning up temporary files....

INIT: Entering runlevel: 2

Using makefile-style concurrent boot in runlevel 2.
Starting busybox' syslogd implementation : syslogdStarting /sbin/syslogd...
1050 (syslogd)
.
Starting NFS common utilities: statd idmapd.
[   54.489406][ T1096] random: dbus-daemon: uninitialized urandom read (12 bytes read)
[   54.532768][ T1096] random: dbus-daemon: uninitialized urandom read (12 bytes read)
Starting system message bus: dbus.
[   54.744278][ T1106] random: avahi-daemon: uninitialized urandom read (4 bytes read)
Not starting NFS kernel daemon: no exports. ... (warning).
Starting Avahi mDNS/DNS-SD Daemon: avahi-daemon.
Starting NTP server: ntpd.
Starting busybox' klogd implementation : klogdStarting /sbin/klogd...
1082 (klogd)
.
[   57.725704][    C0] random: crng init done
[   57.729822][    C0] random: 1 urandom warning(s) missed due to ratelimiting
Starting OpenBSD Secure Shell server: sshd.
Running local boot scripts (/etc/rc.local)
.


Debian GNU/Linux 11 debian ttyS0

debian login: 
Re: SD Card problem loading rootfs too slow?
November 15, 2023 11:15AM
Delete



Edited 1 time(s). Last edit at 11/15/2023 11:16AM by Sc30.
Re: SD Card problem loading rootfs too slow?
November 15, 2023 12:48PM
Quote

Do you have pogoplug V4 or Mobile?

I have both version. The Pogo V4 is one that boots with SD rootfs only.

Quote

I decided to try Debian 5.13.6 kirkwood and it works even on class 4 card, so I think probably a kernel configuration problem.

Very unlikely :) There was no MMC related change in kernel config between 5.13.6-kirkwood-tld-1 and 6.5.7-kirkwood-tld-1.

-bodhi
===========================
Forum Wiki
bodhi's corner (buy bodhi a beer)
Re: SD Card problem loading rootfs too slow?
November 15, 2023 06:56PM
Well something has to account for the difference in mmc bringup and it seems eerily similar to that usb problem.
Re: SD Card problem loading rootfs too slow?
November 15, 2023 08:17PM
> Well something has to account for the difference
> in mmc bringup and it seems eerily similar to that
> usb problem.

What USB problem?

-bodhi
===========================
Forum Wiki
bodhi's corner (buy bodhi a beer)
Re: SD Card problem loading rootfs too slow?
November 15, 2023 09:33PM
Re: SD Card problem loading rootfs too slow?
November 15, 2023 10:06PM
> https://forum.doozan.com/read.php?2,13355,13366

That does not explain why my Pogo V4 with a Sandisk card has no problem. The card was enumerated at 15 sec. Your Pogo Mobile with a Lexar card has problem, and it was enumerated at 32 sec. And we are running the same kernel.

Somewhere in the mainline kernel 5.13.6 to 6.5.7, the MMC drivers must have some changes that caused the different behavior you are seeing.

====

However, I had mentioned above that I might be able to help pushing the slow cards in the next kernel release. We'll will see.

-bodhi
===========================
Forum Wiki
bodhi's corner (buy bodhi a beer)
Re: SD Card problem loading rootfs too slow?
November 15, 2023 11:12PM
I can't explain why your box works, are you sure you are running 6.5.7 on it?

I don't think it has anything to do with the card itself. I get the card detect with the dummy card at the same time as a real card. This is booting 5.13.6 from USB with a dummy card:

[   18.641056][    T7] mvsdio f1090000.mvsdio: Got CD GPIO
Re: SD Card problem loading rootfs too slow?
November 18, 2023 11:36AM
Any new thoughts on this? Maybe ask on the lkml? I can use the old kernel but would like to figure it out.
Re: SD Card problem loading rootfs too slow?
November 18, 2023 02:11PM
I don't have any suggestion other than what I have posted before: 1) Try another SD card. 2) Wait for new kernel release.

-bodhi
===========================
Forum Wiki
bodhi's corner (buy bodhi a beer)
Re: SD Card problem loading rootfs too slow?
December 03, 2023 09:12PM
Install kernel linux-6.6.3-kirkwood-tld-1 on your USB rootfs:

https://forum.doozan.com/read.php?2,12096

And copy the uImage on the USB rootfs /boot to the SD card /boot. Boot the SD card with it and then do the full installation.

-bodhi
===========================
Forum Wiki
bodhi's corner (buy bodhi a beer)
Re: SD Card problem loading rootfs too slow?
December 08, 2023 08:34PM
Thank you, it works. I bought some new SD cards and unfortunately they are giving kernel CRC error when trying to boot, doesn't matter which kernel.
Author:

Your Email:


Subject:


Spam prevention:
Please, enter the code that you see below in the input field. This is for blocking bots that try to post this form automatically. If the code is hard to read, then just try to guess it right. If you enter the wrong code, a new image is created and you get another chance to enter it right.
Message: