Pogo V4 uBoot - USB reset errors

Hello all.

I've now updated 3 Pogo V4 uboots (thanks bodhi!), but am having problems with at least 2 of them with the high-speed USB device resetting constantly (the third is so fresh I can't tell if it's having problems too or not, yet). When this happens it essentially hangs the box.

I was unsure if I completed the uboot install properly due to these errors, so I was checking the env prior to booting and I think the dtb_file was set wrong, so I re-set it and saved it. After booting the same problem was happening, so I assumed my change didn't clear it.

Here's the entire boot process from a serial connection, showing the string of errors right around the DHCPREQUEST:

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:  0
starting USB...
USB0:   USB EHCI 1.00
scanning bus 0 for devices... 2 USB Device(s) found
       scanning usb for storage devices...
Use USB retry period from the environment: 15 second(s)
1 Storage Device(s) found

Reset IDE: ide_preinit failed
Card did not respond to voltage select!
mmc_init: -95, time 14

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

Part    Start Sector    Num Sectors     UUID            Type
  1     2048            120162304       00000000-01     83

## 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 ...
44 bytes read in 784 ms (0 Bytes/s)

no IDE devices available
Card did not respond to voltage select!
mmc_init: -95, time 14
importing envs ...
running scan_disk ...
Scan device usb
device usb 0:1
1 bytes read in 722 ms (0 Bytes/s)
Found bootable drive on usb 0
loading uImage ...
5435888 bytes read in 1013 ms (5.1 MiB/s)
loading uInitrd ...
9671091 bytes read in 966 ms (9.5 MiB/s)
loading DTB /boot/dts/kirkwood-pogoplug_v4.dtb ...
9970 bytes read in 1875 ms (4.9 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

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, 1
[    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=)
[    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,)
[    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_s
[    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.001391][    T0] Console: colour dummy device 80x30
[    0.001505][    T0] Calibrating delay loop (skipped), value calculated using timer frequency.. 333.33 )
[    0.001569][    T0] pid_max: default: 32768 minimum: 301
[    0.002175][    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.004729][    T0] CPU: Testing write buffer coherency: ok
[    0.008417][    T1] Setting up static identity map for 0x100000 - 0x100058
[    0.008884][    T1] mvebu-soc-id: MVEBU SoC ID=0x6281, Rev=0x3
[    0.009400][    T1] rcu: Hierarchical SRCU implementation.
[    0.011663][    T1] devtmpfs: initialized
[    0.020005][    T1] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 191126s
[    0.020170][    T1] futex hash table entries: 256 (order: -1, 3072 bytes, linear)
[    0.076188][    T1] prandom32: self test passed (less than 6 bits correlated)
[    0.076257][    T1] prandom: seed boundary self test passed
[    0.082593][    T1] prandom: 100 self tests passed
[    0.082640][    T1] pinctrl core: initialized pinctrl subsystem
[    0.085148][    T1] NET: Registered protocol family 16
[    0.086491][    T1] DMA: preallocated 256 KiB pool for atomic coherent allocations
[    0.088006][    T1] audit: initializing netlink subsys (disabled)
[    0.090283][    T1] thermal_sys: Registered thermal governor 'step_wise'
[    0.090849][   T16] audit: type=2000 audit(0.080:1): state=initialized audit_enabled=0 res=1
[    0.091142][    T1] cpuidle: using governor ladder
[    0.091289][    T1] cpuidle: using governor menu
[    0.092069][    T1] Feroceon L2: Enabling L2
[    0.092147][    T1] Feroceon L2: Cache support initialised.
[    0.105727][    T1] No ATAGs?
[    2.560099][    C0] random: fast init done
[    7.401095][   T39] "cryptomgr_test" (39) uses obsolete ecb(arc4) skcipher
[    7.440269][   T59] wait_for_initramfs() called before rootfs_initcalls
[    7.650270][    T1] raid6: int32x8  gen()    74 MB/s
[    7.820301][    T1] raid6: int32x8  xor()    46 MB/s
[    7.990192][    T1] raid6: int32x4  gen()    76 MB/s
[    8.160313][    T1] raid6: int32x4  xor()    49 MB/s
[    8.330352][    T1] raid6: int32x2  gen()   100 MB/s
[    8.500244][    T1] raid6: int32x2  xor()    60 MB/s
[    8.670411][    T1] raid6: int32x1  gen()    75 MB/s
[    8.840419][    T1] raid6: int32x1  xor()    43 MB/s
[    8.840445][    T1] raid6: using algorithm int32x2 gen() 100 MB/s
[    8.840466][    T1] raid6: .... xor() 60 MB/s, rmw enabled
[    8.840484][    T1] raid6: using intx1 recovery algorithm
[    8.840878][    T1] vgaarb: loaded
[    8.841738][    T1] SCSI subsystem initialized
[    8.842534][    T1] usbcore: registered new interface driver usbfs
[    8.842644][    T1] usbcore: registered new interface driver hub
[    8.842730][    T1] usbcore: registered new device driver usb
[    8.843065][    T1] pps_core: LinuxPPS API ver. 1 registered
[    8.843090][    T1] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@lin>
[    8.843142][    T1] PTP clock support registered
[    8.845886][    T1] clocksource: Switched to clocksource orion_clocksource
[    8.996901][    T1] VFS: Disk quotas dquot_6.6.0
[    8.997062][    T1] VFS: Dquot-cache hash table entries: 1024 (order 0, 4096 bytes)
[    9.019997][    T1] NET: Registered protocol family 2
[    9.020243][    T1] IP idents hash table entries: 2048 (order: 2, 16384 bytes, linear)
[    9.021654][    T1] tcp_listen_portaddr_hash hash table entries: 512 (order: 0, 4096 bytes, linear)
[    9.021731][    T1] TCP established hash table entries: 1024 (order: 0, 4096 bytes, linear)
[    9.021783][    T1] TCP bind hash table entries: 1024 (order: 0, 4096 bytes, linear)
[    9.021826][    T1] TCP: Hash tables configured (established 1024 bind 1024)
[    9.022218][    T1] MPTCP token hash table entries: 512 (order: 0, 6144 bytes, linear)
[    9.022333][    T1] UDP hash table entries: 256 (order: 0, 4096 bytes, linear)
[    9.022395][    T1] UDP-Lite hash table entries: 256 (order: 0, 4096 bytes, linear)
[    9.022747][    T1] NET: Registered protocol family 1
[    9.036515][    T1] RPC: Registered named UNIX socket transport module.
[    9.036550][    T1] RPC: Registered udp transport module.
[    9.036569][    T1] RPC: Registered tcp transport module.
[    9.036586][    T1] RPC: Registered tcp NFSv4.1 backchannel transport module.
[    9.036613][    T1] PCI: CLS 0 bytes, default 32
[    9.036882][    T1] NetWinder Floating Point Emulator V0.97 (double precision)
[    9.037770][   T58] Trying to unpack rootfs image as initramfs...
[   11.604385][   T58] Freeing initrd memory: 9448K
[   12.553957][    T1] Initialise system trusted keyrings
[   12.554066][    T1] Key type blacklist registered
[   12.554454][    T1] workingset: timestamp_bits=30 max_order=15 bucket_order=0
[   12.554641][    T1] zbud: loaded
[   12.557052][    T1] NFS: Registering the id_resolver key type
[   12.557125][    T1] Key type id_resolver registered
[   12.557147][    T1] Key type id_legacy registered
[   12.557214][    T1] nfs4filelayout_init: NFSv4 File Layout Driver Registering...
[   12.557251][    T1] nfs4flexfilelayout_init: NFSv4 Flexfile Layout Driver Registering...
[   12.557275][    T1] Installing knfsd (copyright (C) 1996 okir@monad.swb.de).
[   12.557689][    T1] jffs2: version 2.2. (NAND) (SUMMARY)  © 2001-2006 Red Hat, Inc.
[   12.558527][    T1] fuse: init (API version 7.34)
[   12.559133][    T1] orangefs_debugfs_init: called with debug mask: :none: :0:
[   12.559468][    T1] orangefs_init: module version upstream loaded
[   12.559497][    T1] SGI XFS with ACLs, security attributes, realtime, scrub, quota, no debug enabled
[   12.715175][    T1] xor: measuring software checksum speed
[   12.728665][    T1]    arm4regs        :   739 MB/sec
[   12.750782][    T1]    8regs           :   447 MB/sec
[   12.766012][    T1]    32regs          :   650 MB/sec
[   12.766038][    T1] xor: using function: arm4regs (739 MB/sec)
[   12.766072][    T1] async_tx: api initialized (async)
[   12.766104][    T1] Key type asymmetric registered
[   12.766124][    T1] Asymmetric key parser 'x509' registered
[   12.766218][    T1] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 246)
[   12.766334][    T1] io scheduler bfq registered
[   13.063772][    T1] kirkwood-pinctrl f1010000.pin-controller: unknown pinctrl group 47
[   13.063820][    T1] kirkwood-pinctrl f1010000.pin-controller: unknown pinctrl group 49
[   13.064395][    T1] kirkwood-pinctrl f1010000.pin-controller: registered pinctrl driver
[   13.067350][    T1] mvebu-pcie mbus@f1000000:pcie@82000000: host bridge /mbus@f1000000/pcie@82000000 r:
[   13.067484][    T1] mvebu-pcie mbus@f1000000:pcie@82000000:      MEM 0x00f1040000..0x00f1041fff -> 0x00
[   13.067552][    T1] mvebu-pcie mbus@f1000000:pcie@82000000:      MEM 0xffffffffffffffff..0x00fffffffe 0
[   13.067603][    T1] mvebu-pcie mbus@f1000000:pcie@82000000:       IO 0xffffffffffffffff..0x00fffffffe 0
[   13.068039][    T1] mvebu-pcie mbus@f1000000:pcie@82000000: PCI host bridge to bus 0000:00
[   13.068076][    T1] pci_bus 0000:00: root bus resource [bus 00-ff]
[   13.068113][    T1] pci_bus 0000:00: root bus resource [mem 0xf1040000-0xf1041fff] (bus address [0x000)
[   13.068145][    T1] pci_bus 0000:00: root bus resource [mem 0xe0000000-0xefffffff]
[   13.068175][    T1] pci_bus 0000:00: root bus resource [io  0x1000-0xeffff]
[   13.068358][    T1] pci 0000:00:01.0: [11ab:6281] type 01 class 0x060400
[   13.068418][    T1] pci 0000:00:01.0: reg 0x38: [mem 0x00000000-0x000007ff pref]
[   13.070135][    T1] PCI: bus0: Fast back to back transfers disabled
[   13.070190][    T1] pci 0000:00:01.0: bridge configuration invalid ([bus 00-00]), reconfiguring
[   13.070528][    T1] pci 0000:01:00.0: [1b73:1009] type 00 class 0x0c0330
[   13.070586][    T1] pci 0000:01:00.0: reg 0x10: [mem 0x00000000-0x0000ffff 64bit]
[   13.070632][    T1] pci 0000:01:00.0: reg 0x18: [mem 0x00000000-0x00000fff 64bit]
[   13.070674][    T1] pci 0000:01:00.0: reg 0x20: [mem 0x00000000-0x00000fff 64bit]
[   13.070833][    T1] pci 0000:01:00.0: supports D1
[   13.070861][    T1] pci 0000:01:00.0: PME# supported from D0 D1 D3hot
[   13.070975][    T1] pci 0000:01:00.0: 2.000 Gb/s available PCIe bandwidth, limited by 2.5 GT/s PCIe x1)
[   13.097270][    T1] PCI: bus1: Fast back to back transfers disabled
[   13.097321][    T1] pci_bus 0000:01: busn_res: [bus 01-ff] end is updated to 01
[   13.097648][    T1] pci 0000:00:01.0: BAR 14: assigned [mem 0xe0000000-0xe00fffff]
[   13.097691][    T1] pci 0000:00:01.0: BAR 6: assigned [mem 0xe0100000-0xe01007ff pref]
[   13.097734][    T1] pci 0000:01:00.0: BAR 0: assigned [mem 0xe0000000-0xe000ffff 64bit]
[   13.097781][    T1] pci 0000:01:00.0: BAR 2: assigned [mem 0xe0010000-0xe0010fff 64bit]
[   13.097825][    T1] pci 0000:01:00.0: BAR 4: assigned [mem 0xe0011000-0xe0011fff 64bit]
[   13.097869][    T1] pci 0000:00:01.0: PCI bridge to [bus 01]
[   13.097901][    T1] pci 0000:00:01.0:   bridge window [mem 0xe0000000-0xe00fffff]
[   13.098092][    T1] pcieport 0000:00:01.0: enabling device (0140 -> 0142)
[   13.098239][    T1] pci 0000:01:00.0: enabling device (0140 -> 0142)
[   13.099070][    T1] mv_xor f1060800.xor: Marvell shared XOR driver
[   13.157570][    T1] mv_xor f1060800.xor: Marvell XOR (Registers Mode): ( xor cpy intr )
[   13.161556][    T1] mv_xor f1060900.xor: Marvell shared XOR driver
[   13.227556][    T1] mv_xor f1060900.xor: Marvell XOR (Registers Mode): ( xor cpy intr )
[   13.231962][    T1] Serial: 8250/16550 driver, 2 ports, IRQ sharing disabled
[   13.234090][    T1] printk: console [ttyS0] disabled
[   13.234238][    T1] f1012000.serial: ttyS0 at MMIO 0xf1012000 (irq = 25, base_baud = 10416666) is a 16A
[   14.339049][    T1] printk: console [ttyS0] enabled
[   14.357174][    T1] loop: module loaded
[   14.362229][    T1] sata_mv f1080000.sata: slots 32 ports 2
[   14.373515][    T1] scsi host0: sata_mv
[   14.378436][    T1] scsi host1: sata_mv
[   14.382679][    T1] ata1: SATA max UDMA/133 irq 32
[   14.387548][    T1] ata2: SATA max UDMA/133 irq 32
[   14.393917][    T1] nand: device found, Manufacturer ID: 0xad, Chip ID: 0xf1
[   14.401008][    T1] nand: Hynix H27U1G8F2BTR-BC
[   14.405532][    T1] nand: 128 MiB, SLC, erase size: 128 KiB, page size: 2048, OOB size: 64
[   14.414246][    T1] Scanning device for bad blocks
[   14.520265][    T1] 5 cmdlinepart partitions found on MTD device orion_nand
[   14.527246][    T1] Creating 5 MTD partitions on "orion_nand":
[   14.533073][    T1] 0x000000000000-0x000000200000 : "u-boot"
[   14.541038][    T1] 0x000000200000-0x000000500000 : "uImage"
[   14.548851][    T1] 0x000000500000-0x000000800000 : "uImage2"
[   14.556734][    T1] 0x000000800000-0x000001000000 : "failsafe"
[   14.564673][    T1] 0x000001000000-0x000008000000 : "root"
[   14.573846][    T1] wireguard: WireGuard 1.0.0 loaded. See www.wireguard.com for information.
[   14.582416][    T1] wireguard: Copyright (C) 2015-2019 Jason A. Donenfeld <Jason@zx2c4.com>. All Right.
[   14.594505][    T1] libphy: Fixed MDIO Bus: probed
[   14.601598][    T1] libphy: orion_mdio_bus: probed
[   14.631127][    T1] mv643xx_eth: MV-643xx 10/100/1000 ethernet driver version 1.4
[   14.719982][   T94] ata1: SATA link down (SStatus 0 SControl F300)
[   15.067687][   T96] ata2: SATA link down (SStatus 0 SControl F300)
[   15.397401][    T1] mv643xx_eth_port mv643xx_eth_port.0 eth0: port 0 with MAC address 00:25:31:05:35:03
[   15.407058][    T1] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver
[   15.414305][    T1] ehci-pci: EHCI PCI platform driver
[   15.419554][    T1] ehci-orion: EHCI orion driver
[   15.424544][    T1] orion-ehci f1050000.ehci: EHCI Host Controller
[   15.430819][    T1] orion-ehci f1050000.ehci: new USB bus registered, assigned bus number 1
[   15.439453][    T1] orion-ehci f1050000.ehci: irq 29, io mem 0xf1050000
[   15.475963][    T1] orion-ehci f1050000.ehci: USB 2.0 started, EHCI 1.00
[   15.483084][    T1] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 5.13
[   15.492071][    T1] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[   15.499999][    T1] usb usb1: Product: EHCI Host Controller
[   15.505562][    T1] usb usb1: Manufacturer: Linux 5.13.6-kirkwood-tld-1 ehci_hcd
[   15.512967][    T1] usb usb1: SerialNumber: f1050000.ehci
[   15.519421][    T1] hub 1-0:1.0: USB hub found
[   15.523945][    T1] hub 1-0:1.0: 1 port detected
[   15.529964][    T1] xhci_hcd 0000:01:00.0: xHCI Host Controller
[   15.536005][    T1] xhci_hcd 0000:01:00.0: new USB bus registered, assigned bus number 2
[   15.545828][    T1] xhci_hcd 0000:01:00.0: hcc params 0x200073a1 hci version 0x100 quirks 0x00000000000
[   15.556732][    T1] usb usb2: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 5.13
[   15.565680][    T1] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[   15.573619][    T1] usb usb2: Product: xHCI Host Controller
[   15.579210][    T1] usb usb2: Manufacturer: Linux 5.13.6-kirkwood-tld-1 xhci-hcd
[   15.586613][    T1] usb usb2: SerialNumber: 0000:01:00.0
[   15.592864][    T1] hub 2-0:1.0: USB hub found
[   15.597457][    T1] hub 2-0:1.0: 2 ports detected
[   15.603145][    T1] xhci_hcd 0000:01:00.0: xHCI Host Controller
[   15.609186][    T1] xhci_hcd 0000:01:00.0: new USB bus registered, assigned bus number 3
[   15.617338][    T1] xhci_hcd 0000:01:00.0: Host supports USB 3.0 SuperSpeed
[   15.624413][    T1] usb usb3: We don't know the algorithms for LPM for this host, disabling LPM.
[   15.633697][    T1] usb usb3: New USB device found, idVendor=1d6b, idProduct=0003, bcdDevice= 5.13
[   15.642684][    T1] usb usb3: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[   15.650612][    T1] usb usb3: Product: xHCI Host Controller
[   15.656200][    T1] usb usb3: Manufacturer: Linux 5.13.6-kirkwood-tld-1 xhci-hcd
[   15.663579][    T1] usb usb3: SerialNumber: 0000:01:00.0
[   15.670028][    T1] hub 3-0:1.0: USB hub found
[   15.674531][    T1] hub 3-0:1.0: 2 ports detected
[   15.680689][    T1] usbcore: registered new interface driver usb-storage
[   15.688139][    T1] mousedev: PS/2 mouse device common for all mice
[   15.815932][   T17] usb 1-1: new high-speed USB device number 2 using orion-ehci
[   16.017339][   T17] usb 1-1: New USB device found, idVendor=0781, idProduct=5583, bcdDevice= 1.00
[   16.026241][   T17] usb 1-1: New USB device strings: Mfr=1, Product=2, SerialNumber=3
[   16.034088][   T17] usb 1-1: Product:  SanDisk 3.2Gen1
[   16.039247][   T17] usb 1-1: Manufacturer:  USB
[   16.043773][   T17] usb 1-1: SerialNumber: 0401eab0bfe5e2791483232c55e1640c510347fdcd098eda93db1d90cb52
[   16.059524][   T17] usb-storage 1-1:1.0: USB Mass Storage device detected
[   16.067284][   T17] scsi host2: usb-storage 1-1:1.0
[   16.725938][    T1] rtc-mv f1010300.rtc: internal RTC not ticking
[   16.732297][    T1] i2c /dev entries driver
[   16.737457][    T1] device-mapper: uevent: version 1.0.3
[   16.743171][    T1] device-mapper: ioctl: 4.45.0-ioctl (2021-03-22) initialised: dm-devel@redhat.com
[   16.753006][    T1] device-mapper: multipath round-robin: version 1.2.0 loaded
[   16.760311][    T1] device-mapper: multipath queue-length: version 0.2.0 loaded
[   16.767647][    T1] device-mapper: multipath service-time: version 0.3.0 loaded
[   16.775027][    T1] device-mapper: dm-log-userspace: version 1.3.0 loaded
[   16.781835][    T1] device-mapper: raid: Loading target version 1.15.1
[   16.790451][    T1] hid: raw HID events driver (C) Jiri Kosina
[   16.796797][    T1] drop_monitor: Initializing network drop monitor service
[   16.804446][    T1] NET: Registered protocol family 10
[   16.811725][    T1] Segment Routing with IPv6
[   16.816213][    T1] RPL Segment Routing with IPv6
[   16.821044][    T1] NET: Registered protocol family 17
[   16.826534][    T1] Key type dns_resolver registered
[   16.832360][    T1] registered taskstats version 1
[   16.837263][    T1] Loading compiled-in X.509 certificates
[   16.843408][    T1] zswap: loaded using pool lzo/zbud
[   16.849087][    T1] Key type ._fscrypt registered
[   16.853791][    T1] Key type .fscrypt registered
[   16.858497][    T1] Key type fscrypt-provisioning registered
[   16.867792][    T1] Key type big_key registered
[   16.917561][    T1] Key type encrypted registered
[   16.931884][    T1] Freeing unused kernel memory: 1024K
[   16.957232][    T1] Checked W+X mappings: passed, no W+X pages found
[   16.963599][    T1] Run /init as init process
Loading, please wait...
[   17.127625][   T58] scsi 2:0:0:0: Direct-Access      USB      SanDisk 3.2Gen1 1.00 PQ: 0 ANSI: 6
[   17.141168][    T7] sd 2:0:0:0: [sda] 120164352 512-byte logical blocks: (61.5 GB/57.3 GiB)
[   17.157299][    T7] sd 2:0:0:0: [sda] Write Protect is off
[   17.165509][    T7] sd 2:0:0:0: [sda] Write cache: disabled, read cache: enabled, doesn't support DPO A
[   17.195403][    T7]  sda: sda1
[   17.210657][    T7] sd 2:0:0:0: [sda] Attached SCSI removable disk
Starting version 247.3-6
[   18.291151][  T142] input: gpio_keys as /devices/platform/gpio_keys/input/input0
[   18.560569][  T142] usbcore: registered new interface driver uas
[   18.721691][    T7] mvsdio f1090000.mvsdio: Got CD GPIO
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/sda1] fsck.ext3 -a -C0 /dev/sda1
rootfs: recovering journal
rootfs: clean, 15654/3760128 files, 497826/15020288 blocks
done.
[   29.891862][  T168] EXT4-fs (sda1): mounting ext3 file system using the ext4 subsystem
[   29.989933][  T168] EXT4-fs (sda1): mounted filesystem with ordered data mode. Opts: (null). Quota mod.
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.852860][    C0] random: crng init done
[   34.568221][  T354] orion_wdt: Initial timeout 25 sec
[   35.032493][  T357] sd 2:0:0:0: Attached scsi generic sg0 type 0
[   35.323621][  T351] marvell-cesa f1030000.crypto: CESA device successfully registered
done.
Activating swap:.
[   36.863679][  T500] EXT4-fs (sda1): 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....
Starting Setting kernel variables: sysctl.
Initializing random number generator...done.
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:05:35:03
Sending on   LPF/eth0/00:25:31:05:35:03
Sending on   Socket/fallback
DHCPREQUEST for 192.168.1.120 on eth0 to 255.255.255.255 port 67
[   43.882387][    C0] mv643xx_eth_port mv643xx_eth_port.0 eth0: link up, 1000 Mb/s, full duplex, flow cod
[   43.892984][   T17] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready
[   48.955931][  T103] usb 1-1: reset high-speed USB device number 2 using orion-ehci
DHCPREQUEST for 192.168.1.120 on eth0 to 255.255.255.255 port 67
DHCPACK of 192.168.1.120 from 192.168.1.254
[   79.375931][  T103] usb 1-1: reset high-speed USB device number 2 using orion-ehci
[  243.685941][   T18] INFO: task kworker/0:1:17 blocked for more than 122 seconds.
[  243.693347][   T18]       Not tainted 5.13.6-kirkwood-tld-1 #1.0
[  243.699375][   T18] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[  243.707901][   T18] task:kworker/0:1     state:D stack:    0 pid:   17 ppid:     2 flags:0x00000000
[  243.716972][   T18] Workqueue: events_freezable_power_ disk_events_workfn
[  243.723787][   T18] [<80b0a148>] (__schedule) from [<80b0a458>] (schedule+0xa0/0xf8)
[  243.731575][   T18] [<80b0a458>] (schedule) from [<80b0a85c>] (io_schedule+0x14/0x24)
[  243.739440][   T18] [<80b0a85c>] (io_schedule) from [<80636780>] (blk_mq_get_tag+0x178/0x260)
[  243.747995][   T18] [<80636780>] (blk_mq_get_tag) from [<80632d64>] (__blk_mq_alloc_request+0xc8/0x108)
[  243.757430][   T18] [<80632d64>] (__blk_mq_alloc_request) from [<80632e00>] (blk_mq_alloc_request+0x5c)
[  243.767295][   T18] [<80632e00>] (blk_mq_alloc_request) from [<806281ac>] (blk_get_request+0x80/0xb8)
[  243.776560][   T18] [<806281ac>] (blk_get_request) from [<8079572c>] (__scsi_execute+0x40/0x17c)
[  243.785363][   T18] [<8079572c>] (__scsi_execute) from [<80796d0c>] (scsi_test_unit_ready+0x68/0xf4)
[  243.794523][   T18] [<80796d0c>] (scsi_test_unit_ready) from [<807a0d08>] (sd_check_events+0xd0/0x12c)
[  243.803881][   T18] [<807a0d08>] (sd_check_events) from [<8063a628>] (disk_check_events+0x44/0x178)
[  243.812969][   T18] [<8063a628>] (disk_check_events) from [<80135288>] (process_one_work+0x210/0x320)
[  243.822230][   T18] [<80135288>] (process_one_work) from [<80135dc4>] (worker_thread+0x27c/0x368)
[  243.831128][   T18] [<80135dc4>] (worker_thread) from [<8013b520>] (kthread+0x134/0x13c)
[  243.839249][   T18] [<8013b520>] (kthread) from [<80100148>] (ret_from_fork+0x14/0x2c)
[  243.847187][   T18] Exception stack(0x81515fb0 to 0x81515ff8)
[  243.852929][   T18] 5fa0:                                     00000000 00000000 00000000 00000000
[  243.861806][   T18] 5fc0: 00000000 00000000 00000000 00000000 00000000 00000000 00000000 00000000
[  243.870692][   T18] 5fe0: 00000000 00000000 00000000 00000000 00000013 00000000
[  243.878012][   T18] INFO: task kworker/u2:1:58 blocked for more than 123 seconds.
[  243.885479][   T18]       Not tainted 5.13.6-kirkwood-tld-1 #1.0
[  243.891492][   T18] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[  243.900010][   T18] task:kworker/u2:1    state:D stack:    0 pid:   58 ppid:     2 flags:0x00000000
[  243.909070][   T18] Workqueue: writeback wb_workfn (flush-8:0)
[  243.914929][   T18] [<80b0a148>] (__schedule) from [<80b0a458>] (schedule+0xa0/0xf8)
[  243.922706][   T18] [<80b0a458>] (schedule) from [<80b0a85c>] (io_schedule+0x14/0x24)
[  243.930566][   T18] [<80b0a85c>] (io_schedule) from [<80636780>] (blk_mq_get_tag+0x178/0x260)
[  243.939119][   T18] [<80636780>] (blk_mq_get_tag) from [<80632d64>] (__blk_mq_alloc_request+0xc8/0x108)
[  243.948547][   T18] [<80632d64>] (__blk_mq_alloc_request) from [<80634294>] (blk_mq_submit_bio+0xc8/0x)
[  243.958271][   T18] [<80634294>] (blk_mq_submit_bio) from [<806296c4>] (submit_bio_noacct+0xb0/0x224)
[  243.968511][   T18] [<806296c4>] (submit_bio_noacct) from [<8062986c>] (submit_bio+0x34/0x1a4)
[  243.977255][   T18] [<8062986c>] (submit_bio) from [<802bd194>] (submit_bh_wbc.constprop.25+0x180/0x19)
[  243.986790][   T18] [<802bd194>] (submit_bh_wbc.constprop.25) from [<802c0268>] (__block_write_full_pa)
[  243.997466][   T18] [<802c0268>] (__block_write_full_page) from [<802c0494>] (block_write_full_page+0x)
[  244.007665][   T18] [<802c0494>] (block_write_full_page) from [<8022e540>] (__writepage+0x14/0x78)
[  244.016721][   T18] [<8022e540>] (__writepage) from [<8022e900>] (write_cache_pages+0x28c/0x338)
[  244.025512][   T18] [<8022e900>] (write_cache_pages) from [<8022e9f8>] (generic_writepages+0x4c/0x80)
[  244.034831][   T18] [<8022e9f8>] (generic_writepages) from [<8022f188>] (do_writepages+0x34/0x68)
[  244.043779][   T18] [<8022f188>] (do_writepages) from [<802b1aec>] (__writeback_single_inode+0xa4/0x38)
[  244.053302][   T18] [<802b1aec>] (__writeback_single_inode) from [<802b2354>] (writeback_sb_inodes+0x2)
[  244.063445][   T18] [<802b2354>] (writeback_sb_inodes) from [<802b25f0>] (__writeback_inodes_wb+0x64/0)
[  244.073129][   T18] [<802b25f0>] (__writeback_inodes_wb) from [<802b2928>] (wb_writeback+0x2fc/0x31c)
[  244.082408][   T18] [<802b2928>] (wb_writeback) from [<802b365c>] (wb_workfn+0x2f4/0x408)
[  244.090814][   T18] [<802b365c>] (wb_workfn) from [<80135288>] (process_one_work+0x210/0x320)
[  244.099429][   T18] [<80135288>] (process_one_work) from [<80135dc4>] (worker_thread+0x27c/0x368)
[  244.108337][   T18] [<80135dc4>] (worker_thread) from [<8013b520>] (kthread+0x134/0x13c)
[  244.116486][   T18] [<8013b520>] (kthread) from [<80100148>] (ret_from_fork+0x14/0x2c)
[  244.124403][   T18] Exception stack(0x81589fb0 to 0x81589ff8)
[  244.130172][   T18] 9fa0:                                     00000000 00000000 00000000 00000000
[  244.139091][   T18] 9fc0: 00000000 00000000 00000000 00000000 00000000 00000000 00000000 00000000
[  244.147971][   T18] 9fe0: 00000000 00000000 00000000 00000000 00000013 00000000
[  244.155315][   T18] INFO: task scsi_eh_2:101 blocked for more than 123 seconds.
[  244.162681][   T18]       Not tainted 5.13.6-kirkwood-tld-1 #1.0
[  244.168719][   T18] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[  244.177244][   T18] task:scsi_eh_2       state:D stack:    0 pid:  101 ppid:     2 flags:0x00000000
[  244.186319][   T18] [<80b0a148>] (__schedule) from [<80b0a458>] (schedule+0xa0/0xf8)
[  244.194077][   T18] [<80b0a458>] (schedule) from [<80b0a79c>] (schedule_preempt_disabled+0x14/0x20)
[  244.203151][   T18] [<80b0a79c>] (schedule_preempt_disabled) from [<80b0b7ec>] (__mutex_lock.constprop)
[  244.213700][   T18] [<80b0b7ec>] (__mutex_lock.constprop.9) from [<80866444>] (device_reset+0x1c/0x50)
[  244.223057][   T18] [<80866444>] (device_reset) from [<8079224c>] (scsi_try_bus_device_reset+0x24/0x58)
[  244.232483][   T18] [<8079224c>] (scsi_try_bus_device_reset) from [<80793970>] (scsi_eh_ready_devs+0x3)
[  244.242591][   T18] [<80793970>] (scsi_eh_ready_devs) from [<80794f6c>] (scsi_error_handler+0x2a8/0x5e)
[  244.252104][   T18] [<80794f6c>] (scsi_error_handler) from [<8013b520>] (kthread+0x134/0x13c)
[  244.260658][   T18] [<8013b520>] (kthread) from [<80100148>] (ret_from_fork+0x14/0x2c)
[  244.269628][   T18] Exception stack(0x8793bfb0 to 0x8793bff8)
[  244.275383][   T18] bfa0:                                     00000000 00000000 00000000 00000000
[  244.284329][   T18] bfc0: 00000000 00000000 00000000 00000000 00000000 00000000 00000000 00000000
[  244.293225][   T18] bfe0: 00000000 00000000 00000000 00000000 00000013 00000000
[  244.300600][   T18] INFO: task usb-storage:103 blocked for more than 123 seconds.
[  244.308108][   T18]       Not tainted 5.13.6-kirkwood-tld-1 #1.0
[  244.314101][   T18] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[  244.322624][   T18] task:usb-storage     state:D stack:    0 pid:  103 ppid:     2 flags:0x00000000
[  244.331738][   T18] [<80b0a148>] (__schedule) from [<80b0a458>] (schedule+0xa0/0xf8)
[  244.339568][   T18] [<80b0a458>] (schedule) from [<80b0d660>] (schedule_timeout+0x24/0xe8)
[  244.347912][   T18] [<80b0d660>] (schedule_timeout) from [<80b0adb4>] (__wait_for_common+0x110/0x1e0)
[  244.357174][   T18] [<80b0adb4>] (__wait_for_common) from [<8082fb24>] (usb_sg_wait+0xac/0x1f4)
[  244.365909][   T18] [<8082fb24>] (usb_sg_wait) from [<80867154>] (usb_stor_bulk_transfer_sglist+0x90/0)
[  244.375579][   T18] [<80867154>] (usb_stor_bulk_transfer_sglist) from [<808671d4>] (usb_stor_bulk_srb+)
[  244.385784][   T18] [<808671d4>] (usb_stor_bulk_srb) from [<80867564>] (usb_stor_Bulk_transport+0x13c/)
[  244.395647][   T18] [<80867564>] (usb_stor_Bulk_transport) from [<8086792c>] (usb_stor_invoke_transpor)
[  244.406105][   T18] [<8086792c>] (usb_stor_invoke_transport) from [<808689a4>] (usb_stor_control_threa)
[  244.416643][   T18] [<808689a4>] (usb_stor_control_thread) from [<8013b520>] (kthread+0x134/0x13c)
[  244.425602][   T18] [<8013b520>] (kthread) from [<80100148>] (ret_from_fork+0x14/0x2c)
[  244.433541][   T18] Exception stack(0x87943fb0 to 0x87943ff8)
[  244.439308][   T18] 3fa0:                                     00000000 00000000 00000000 00000000
[  244.448183][   T18] 3fc0: 00000000 00000000 00000000 00000000 00000000 00000000 00000000 00000000
[  244.457055][   T18] 3fe0: 00000000 00000000 00000000 00000000 00000013 00000000
[  244.464364][   T18] INFO: task dhclient-script:912 blocked for more than 123 seconds.
[  244.472244][   T18]       Not tainted 5.13.6-kirkwood-tld-1 #1.0
[  244.478272][   T18] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[  244.486796][   T18] task:dhclient-script state:D stack:    0 pid:  912 ppid:   905 flags:0x00000000
[  244.495831][   T18] [<80b0a148>] (__schedule) from [<80b0a458>] (schedule+0xa0/0xf8)
[  244.503613][   T18] [<80b0a458>] (schedule) from [<80b0a85c>] (io_schedule+0x14/0x24)
[  244.511476][   T18] [<80b0a85c>] (io_schedule) from [<80b0abd0>] (bit_wait_io+0xc/0x30)
[  244.519511][   T18] [<80b0abd0>] (bit_wait_io) from [<80b0a8b4>] (__wait_on_bit+0x48/0x88)
[  244.527805][   T18] [<80b0a8b4>] (__wait_on_bit) from [<80b0a974>] (out_of_line_wait_on_bit+0x80/0xa4)
[  244.537137][   T18] [<80b0a974>] (out_of_line_wait_on_bit) from [<80369d4c>] (ext4_read_bh+0x64/0x74)
[  244.546402][   T18] [<80369d4c>] (ext4_read_bh) from [<8032d36c>] (ext4_read_inode_bitmap+0x3d8/0x480)
[  244.555732][   T18] [<8032d36c>] (ext4_read_inode_bitmap) from [<8032e270>] (__ext4_new_inode+0x658/0x)
[  244.565504][   T18] [<8032e270>] (__ext4_new_inode) from [<80352a7c>] (ext4_create+0xbc/0x198)
[  244.575149][   T18] [<80352a7c>] (ext4_create) from [<802941f8>] (path_openat+0x79c/0xaa4)
[  244.583537][   T18] [<802941f8>] (path_openat) from [<8029453c>] (do_filp_open+0x3c/0x9c)
[  244.591752][   T18] [<8029453c>] (do_filp_open) from [<80283118>] (do_sys_openat2+0x6c/0xec)
[  244.600252][   T18] [<80283118>] (do_sys_openat2) from [<80283278>] (do_sys_open+0x3c/0x5c)
[  244.608642][   T18] [<80283278>] (do_sys_open) from [<80100060>] (ret_fast_syscall+0x0/0x54)
[  244.617136][   T18] Exception stack(0x87655fa8 to 0x87655ff0)
[  244.622886][   T18] 5fa0:                   011aeaa4 76f4bc20 ffffff9c 011aeaa4 00020441 000001b6
[  244.631763][   T18] 5fc0: 011aeaa4 76f4bc20 004e9d48 00000142 00000000 004ec380 004ec788 004ea1f0
[  244.640666][   T18] 5fe0: 76f1c000 7ed78ce0 76e968d0 76e968f0
...
[  366.565938][   T18] INFO: task kworker/0:1:17 blocked for more than 245 seconds.
...etc

Any ideas on what I may have done wrong here - or what I can look at to help the troubleshooting?

dhargens,

[   17.127625][   T58] scsi 2:0:0:0: Direct-Access      USB      SanDisk 3.2Gen1 1.00 PQ: 0 ANSI: 6
[   17.141168][    T7] sd 2:0:0:0: [sda] 120164352 512-byte logical blocks: (61.5 GB/57.3 GiB)
[   17.157299][    T7] sd 2:0:0:0: [sda] Write Protect is off
[   17.165509][    T7] sd 2:0:0:0: [sda] Write cache: disabled, read cache: enabled, doesn't support DPO A
[   17.195403][    T7]  sda: sda1
[   17.210657][    T7] sd 2:0:0:0: [sda] Attached SCSI removable disk

1. I see that the USB drive is 64GB. And it looks like a USB 3.0 drive.
2. How was it formatted? Ext3 or Ext4?

If it is a USB 3.0 drive, and you've plugged it in the top USB port, then sometime it's problematic. The Pogo V4 USB 2.0 hub is ancient, and it cannot handle some USB 3.0 flash drives that plugged directly to the top port.

If you can,

- Use an external USB 3.0 hub and plug this drive ot that hub, and connect the hub to the top port.
- Or clone this drive to another USB 2.0 flash drive, and boot with that.

I have upgraded this Pogo V4 u-boot to 2023.04 version, but I am still working on the envs. The new u-boot will allow booting from USB 3.0 ports in the back. Will release this in the near future.

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

1. Yes, it's a v3.0 - I bought a 10-pack to update all my Pogos and boot from these. Guess that wasn't such a good idea here. I've used Cruzer Fits on previous V4s, and they've worked without errors. I'll find some Fits and get those working - they fit better in the case anyway. (I'll use the v3s once the 2023.04 version is released, so they won't go to waste.)

2. I formatted it Ext3. Since I don't trust myself, I wasn't sure I was installing the latest U-Boot (I guess I was), so I erred to the safe side and did it Ext3. I finalized the format anyway for less chance of error (using "-E lazy_itable_init=0"). I'll set one up with Ext4 shortly.

Also, is the UART/kwboot process just to confirm that this will all work on the V4? I kept thinking it was booting 2017.07 for the install too after the "reset", but it reverts to the stock boot for the uboot install. Makes sense why that's the case now, but was confused at first. This way I can use minicom for the connection after I've confirmed it's updateable.



Edited 1 time(s). Last edit at 04/06/2023 06:42PM by dhargens.

dhargens,

> 1. Yes, it's a v3.0 - I bought a 10-pack to update
> all my Pogos and boot from these. Guess that
> wasn't such a good idea here. I've used Cruzer
> Fits on previous V4s, and they've worked without
> errors. I'll find some Fits and get those working
> - they fit better in the case anyway. (I'll use
> the v3s once the 2023.04 version is released, so
> they won't go to waste.)

Yes. I'm running with USB 3.0 in the back ports right now.


>
> 2. I formatted it Ext3. Since I don't trust
> myself, I wasn't sure I was installing the latest
> U-Boot (I guess I was), so I erred to the safe
> side and did it Ext3. I finalized the format
> anyway for less chance of error (using "-E
> lazy_itable_init=0"). I'll set one up with Ext4
> shortly.
>
> Also, is the UART/kwboot process just to confirm
> that this will all work on the V4? I kept thinking
> it was booting 2017.07 for the install too after
> the "reset", but it reverts to the stock boot for
> the uboot install.

Right!.

> Makes sense why that's the case
> now, but was confused at first. This way I can use
> minicom for the connection after I've confirmed
> it's updateable.

You need to flash to NAND it to make it permanent.

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

Quote

Yes. I'm running with USB 3.0 in the back ports right now.

I'm very much looking forward to that!