Help with MF286D & OpenWRT

Installing and Using OpenWrt
1

Hi,
I'm trying to install OpenWRT on my MF286D which was perviosly running Nordic.
I can get the initramfs version to run with the OpenWRT on MF286D guide, and I can also do the sysupgrade to get the final install done, but... when I reboot it gets stuck in a loop at uboot with it constantly restarting. It does sometimes manage to start the first time, but when I then try a second time - in this case after rebooting following the installation of the modem driver - it just sits there booting and re-booting.
I did wonder whether I'm doing something wrong with clearing/erasing the mtd8/9 partitions with ubi format, but I'm not sure.

Format: Log Type - Time(microsec) - Message - Optional Info
Log Type: B - Since Boot(Power On Reset),  D - Delta,  S - Statistic
S - QC_IMAGE_VERSION_STRING=BOOT.BF.3.1.1-00126
S - IMAGE_VARIANT_STRING=DAABANAZA
S - OEM_IMAGE_VERSION_STRING=CRM
S - Boot Config, 0x00000021
S - Reset status Config, 0x00000000
S - Core 0 Frequency, 0 MHz
B -       261 - PBL, Start
B -      1339 - bootable_media_detect_entry, Start
B -      1678 - bootable_media_detect_success, Start
B -      1692 - elf_loader_entry, Start
B -      5069 - auth_hash_seg_entry, Start
B -      7213 - auth_hash_seg_exit, Start
B -    577128 - elf_segs_hash_verify_entry, Start
B -    694840 - PBL, End
B -    694864 - SBL1, Start
B -    785683 - pm_device_init, Start
D -         7 - pm_device_init, Delta
B -    787145 - boot_flash_init, Start
D -     52868 - boot_flash_init, Delta
B -    844154 - boot_config_data_table_init, Start
D -      3831 - boot_config_data_table_init, Delta - (419 Bytes)
B -    851354 - clock_init, Start
D -      7565 - clock_init, Delta
B -    863391 - CDT version:2,Platform ID:8,Major ID:1,Minor ID:0,Subtype:1
B -    866805 - sbl1_ddr_set_params, Start
B -    871903 - cpr_init, Start
D -         2 - cpr_init, Delta
B -    876286 - Pre_DDR_clock_init, Start
D -         4 - Pre_DDR_clock_init, Delta
D -     13177 - sbl1_ddr_set_params, Delta
B -    889600 - pm_driver_init, Start
D -         2 - pm_driver_init, Delta
B -    959966 - sbl1_wait_for_ddr_training, Start
D -        28 - sbl1_wait_for_ddr_training, Delta
B -    975521 - Image Load, Start
D -    153185 - QSEE Image Loaded, Delta - (299560 Bytes)
B -   1129134 - Image Load, Start
D -      1445 - SEC Image Loaded, Delta - (2048 Bytes)
B -   1139426 - Image Load, Start
D -    247720 - APPSBL Image Loaded, Delta - (507011 Bytes)
B -   1387543 - QSEE Execution, Start
D -        60 - QSEE Execution, Delta
B -   1393755 - SBL1, End
D -    700999 - SBL1, Delta
S - Flash Throughput, 2011 KB/s  (809038 Bytes,  402154 us)
S - DDR Frequency, 672 MHz


U-Boot 2012.07 [Chaos Calmer 15.05.1,f53cef1+r49254] (Mar 05 2021 - 15:20:47)

smem ram ptable found: ver: 1 len: 3
DRAM:  256 MiB
machid : 0x8010001
NAND:  ID = 302012c
Vendor = 2c
Device = 1
ONFI device found
SF: Detected GD25Q16 with page size 4 KiB, total 2 MiB
ipq_spi: page_size: 0x100, sector_size: 0x1000, size: 0x200000
130 MiB
*** Warning - bad CRC, using default environment

In:    serial
Out:   serial
Err:   serial
machid: 8010001
flash_type: 0
Uaztemain: enter into !
ZTE_InitFotaFlashPara: nand_curr_device=0
zte_getHandOffState: read data=0xff from 0x0
Press ESC to abort autoboot in 0 seconds
Creating 1 MTD partitions on "nand0":
0x000001800000-0x000003500000 : "mtd=0"
UBI: attaching mtd2 to ubi0
UBI: physical eraseblock size:   131072 bytes (128 KiB)
UBI: logical eraseblock size:    126976 bytes
UBI: smallest flash I/O unit:    2048
UBI: VID header offset:          2048 (aligned 2048)
UBI: data offset:                4096
UBI: attached mtd2 to ubi0
UBI: MTD device name:            "mtd=0"
UBI: MTD device size:            29 MiB
UBI: number of good PEBs:        232
UBI: number of bad PEBs:         0
UBI: max. allowed volumes:       128
UBI: wear-leveling threshold:    4096
UBI: number of internal volumes: 1
UBI: number of user volumes:     3
UBI: available PEBs:             18
UBI: total number of reserved PEBs: 214
UBI: number of PEBs reserved for bad PEB handling: 2
UBI: max/mean erase counter: 6/4
Read 0 bytes from volume kernel to 84000000
No size specified -> Using max size (3047424)
## Booting kernel from FIT Image at 84000000 ...
   Using 'config@ap.dk04.1-c1' configuration
   Trying 'kernel-1' kernel subimage
     Description:  ARM OpenWrt Linux-5.15.137
     Type:         Kernel Image
     Compression:  uncompressed
     Data Start:   0x840000e4
     Data Size:    2957736 Bytes = 2.8 MiB
     Architecture: ARM
     OS:           Linux
     Load Address: 0x80208000
     Entry Point:  0x80208000
     Hash algo:    crc32
     Hash value:   e5c6f96a
     Hash algo:    sha1
     Hash value:   0dfef4204d4e6146c4f4e38e25f0651fe5185ca5
   Verifying Hash Integrity ... crc32+ sha1+ OK
## Flattened Device Tree from FIT Image at 84000000
   Using 'config@ap.dk04.1-c1' configuration
   Trying 'fdt-1' FDT blob subimage
     Description:  ARM OpenWrt zte_mf286d device tree blob
     Type:         Flat Device Tree
     Compression:  uncompressed
     Data Start:   0x842d23c0
     Data Size:    19105 Bytes = 18.7 KiB
     Architecture: ARM
     Hash algo:    crc32
     Hash value:   40fdc7b4
     Hash algo:    sha1
     Hash value:   1992cbaae5ebb80f2739e73ef7e793d6b34d7033
   Verifying Hash Integrity ... crc32+ sha1+ OK
   Booting using the fdt blob at 0x842d23c0
   Loading Kernel Image ... OK
OK
   Loading Device Tree to 86ff8000, end 86fffaa0 ... OK
eth0 MAC Address from ART is not valid
eth1 MAC Address from ART is not valid
Using machid 0x8010001 from environment

Starting kernel ...

[    0.000000] Booting Linux on physical CPU 0x0
[    0.000000] Linux version 5.15.137 (builder@buildhost) (arm-openwrt-linux-mus                                                                                                             lgnueabi-gcc (OpenWrt GCC 12.3.0 r23630-842932a63d) 12.3.0, GNU ld (GNU Binutils                                                                                                             ) 2.40.0) #0 SMP Tue Nov 14 13:38:11 2023
[    0.000000] CPU: ARMv7 Processor [410fc075] revision 5 (ARMv7), cr=10c5387d
[    0.000000] CPU: div instructions available: patching division code
[    0.000000] CPU: PIPT / VIPT nonaliasing data cache, VIPT aliasing instructio                                                                                                             n cache
[    0.000000] OF: fdt: Machine model: ZTE MF286D
[    0.000000] Memory policy: Data cache writealloc
[    0.000000] Zone ranges:
[    0.000000]   Normal   [mem 0x0000000080000000-0x000000008fffffff]
[    0.000000]   HighMem  empty
[    0.000000] Movable zone start for each node
[    0.000000] Early memory node ranges
[    0.000000]   node   0: [mem 0x0000000080000000-0x0000000087dfffff]
[    0.000000]   node   0: [mem 0x0000000087e00000-0x0000000087ffffff]
[    0.000000]   node   0: [mem 0x0000000088000000-0x000000008fffffff]
[    0.000000] Initmem setup node 0 [mem 0x0000000080000000-0x000000008fffffff]
[    0.000000] percpu: Embedded 12 pages/cpu s16588 r8192 d24372 u49152
[    0.000000] Built 1 zonelists, mobility grouping on.  Total pages: 64960
[    0.000000] Kernel command line: ubi.mtd=rootfs root=mtd:ubi_rootfs rootfstyp                                                                                                             e=squashfs rootwait root=/dev/ubiblock0_1
[    0.000000] Dentry cache hash table entries: 32768 (order: 5, 131072 bytes, l                                                                                                             inear)
[    0.000000] Inode-cache hash table entries: 16384 (order: 4, 65536 bytes, lin                                                                                                             ear)
[    0.000000] mem auto-init: stack:off, heap alloc:off, heap free:off
[    0.000000] Memory: 247200K/262144K available (6746K kernel code, 610K rwdata                                                                                                             , 1020K rodata, 1024K init, 247K bss, 14944K reserved, 0K cma-reserved, 0K highm                                                                                                             em)
[    0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1
[    0.000000] rcu: Hierarchical RCU implementation.
[    0.000000]  Tracing variant of Tasks RCU enabled.
[    0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 10 jif                                                                                                             fies.
[    0.000000] NR_IRQS: 16, nr_irqs: 16, preallocated irqs: 16
[    0.000000] arch_timer: cp15 timer(s) running at 48.00MHz (virt).
[    0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles:                                                                                                              0xb11fd3bfb, max_idle_ns: 440795203732 ns
[    0.000001] sched_clock: 56 bits at 48MHz, resolution 20ns, wraps every 43980                                                                                                             46511096ns
[    0.000023] Switching to timer-based delay loop, resolution 20ns
[    0.000325] Calibrating delay loop (skipped), value calculated using timer fr                                                                                                             equency.. 96.00 BogoMIPS (lpj=480000)
[    0.000353] CPU: Testing write buffer coherency: ok
[    0.000407] pid_max: default: 32768 minimum: 301
[    0.001259] Mount-cache hash table entries: 1024 (order: 0, 4096 bytes, linea                                                                                                             r)
[    0.001288] Mountpoint-cache hash table entries: 1024 (order: 0, 4096 bytes,                                                                                                              linear)
[    0.003244] qcom_scm: convention: smc legacy
[    0.004427] Setting up static identity map for 0x80300000 - 0x8030003c
[    0.004597] rcu: Hierarchical SRCU implementation.
[    0.005175] smp: Bringing up secondary CPUs ...
[    0.008559] smp: Brought up 1 node, 4 CPUs
[    0.008595] SMP: Total of 4 processors activated (384.00 BogoMIPS).
[    0.008611] CPU: All CPU(s) started in SVC mode.
[    0.015096] VFP support v0.3: implementor 41 architecture 2 part 30 variant 7                                                                                                              rev 5
[    0.015260] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, ma                                                                                                             x_idle_ns: 19112604462750000 ns
[    0.015296] futex hash table entries: 1024 (order: 4, 65536 bytes, linear)
[    0.015658] pinctrl core: initialized pinctrl subsystem
[    0.017680] NET: Registered PF_NETLINK/PF_ROUTE protocol family
[    0.018062] DMA: preallocated 256 KiB pool for atomic coherent allocations
[    0.019378] thermal_sys: Registered thermal governor 'step_wise'
[    0.019808] cpuidle: using governor ladder
[    0.019871] cpuidle: using governor menu
[    0.048845] cryptd: max_cpu_qlen set to 1000
[    0.053463] usbcore: registered new interface driver usbfs
[    0.053542] usbcore: registered new interface driver hub
[    0.053604] usbcore: registered new device driver usb
[    0.053691] pps_core: LinuxPPS API ver. 1 registered
[    0.053705] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giome                                                                                                             tti <giometti@linux.it>
[    0.053739] PTP clock support registered
[    0.055902] clocksource: Switched to clocksource arch_sys_counter
[    0.057403] NET: Registered PF_INET protocol family
[    0.057629] IP idents hash table entries: 4096 (order: 3, 32768 bytes, linear                                                                                                             )
[    0.058546] tcp_listen_portaddr_hash hash table entries: 512 (order: 0, 6144                                                                                                              bytes, linear)
[    0.058601] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes,                                                                                                              linear)
[    0.058627] TCP established hash table entries: 2048 (order: 1, 8192 bytes, l                                                                                                             inear)
[    0.058669] TCP bind hash table entries: 2048 (order: 2, 16384 bytes, linear)
[    0.058725] TCP: Hash tables configured (established 2048 bind 2048)
[    0.058895] UDP hash table entries: 256 (order: 1, 8192 bytes, linear)
[    0.058972] UDP-Lite hash table entries: 256 (order: 1, 8192 bytes, linear)
[    0.059305] NET: Registered PF_UNIX/PF_LOCAL protocol family
[    0.059357] PCI: CLS 0 bytes, default 64
[    0.061243] workingset: timestamp_bits=14 max_order=16 bucket_order=2
[    0.067112] squashfs: version 4.0 (2009/01/31) Phillip Lougher
[    0.067144] jffs2: version 2.2 (NAND) (SUMMARY) (LZMA) (RTIME) (CMODE_PRIORIT                                                                                                             Y) (c) 2001-2006 Red Hat, Inc.
[    0.197882] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 2                                                                                                             48)
[    0.202232] bam-dma-engine 8e04000.dma: num-channels unspecified in dt
[    0.202264] bam-dma-engine 8e04000.dma: num-ees unspecified in dt
[    0.203776] tcsr 1949000.tcsr: setting wifi_glb_cfg = 41000000
[    0.203888] tcsr 194b000.tcsr: setting usb hs phy mode select = e700e7
[    0.203989] tcsr 1953000.ess_tcsr: setting ess interface select = 0
[    0.204146] tcsr 1957000.tcsr: setting wifi_noc_memtype_m0_m2 = 2222222
[    0.204480] Serial: 8250/16550 driver, 16 ports, IRQ sharing enabled
[    0.206619] msm_serial 78af000.serial: msm_serial: detected port #0
[    0.206675] msm_serial 78af000.serial: uartclk = 1843200
[    0.206733] 78af000.serial: ttyMSM0 at MMIO 0x78af000 (irq = 33, base_baud =                                                                                                              115200) is a MSM
[    0.206773] msm_serial: console setup on port #0
[    0.758008] printk: console [ttyMSM0] enabled
[    0.763218] msm_serial: driver initialized
[    0.773075] loop: module loaded
[    0.774981] nand: device found, Manufacturer ID: 0x2c, Chip ID: 0xf1
[    0.775036] nand: Micron MT29F1G08ABAEAWP
[    0.781683] nand: 128 MiB, SLC, erase size: 128 KiB, page size: 2048, OOB siz                                                                                                             e: 64
[    0.785661] 12 fixed-partitions partitions found on MTD device qcom_nand.0
[    0.793074] OF: Bad cell count for /soc/nand-controller@79b0000/nand@0/partit                                                                                                             ions
[    0.799918] OF: Bad cell count for /soc/nand-controller@79b0000/nand@0/partit                                                                                                             ions
[    0.807915] OF: Bad cell count for /soc/nand-controller@79b0000/nand@0/partit                                                                                                             ions
[    0.814901] OF: Bad cell count for /soc/nand-controller@79b0000/nand@0/partit                                                                                                             ions
[    0.822927] Creating 12 MTD partitions on "qcom_nand.0":
[    0.829844] 0x000000000000-0x0000000a0000 : "fota-flag"
[    0.836811] 0x0000000a0000-0x000000120000 : "ART"
[    0.841527] 0x000000120000-0x0000001a0000 : "mac"
[    0.846335] 0x0000001a0000-0x000000260000 : "reserved2"
[    0.851381] 0x000000260000-0x000000660000 : "cfg-param"
[    0.861307] 0x000000660000-0x000000a60000 : "log"
[    0.867829] 0x000000a60000-0x000000b00000 : "oops"
[    0.869359] 0x000000b00000-0x000001000000 : "reserved3"
[    0.879485] 0x000001000000-0x000001800000 : "web"
[    0.891923] 0x000001800000-0x000003500000 : "rootfs"
[    0.935277] mtd: setting mtd9 (rootfs) as root device
[    0.935907] mtdsplit: no squashfs found in "rootfs"
[    0.939335] 0x000003500000-0x000004e00000 : "data"
[    0.981409] 0x000004e00000-0x000008000000 : "fota"
[    1.061535] spi_qup 78b5000.spi: IN:block:16, fifo:64, OUT:block:16, fifo:64
[    1.063327] spi-nor spi0.0: gd25q16 (2048 Kbytes)
[    1.067803] 8 fixed-partitions partitions found on MTD device spi0.0
[    1.072279] Creating 8 MTD partitions on "spi0.0":
[    1.078722] 0x000000000000-0x000000040000 : "0:SBL1"
[    1.083991] 0x000000040000-0x000000060000 : "0:MIBIB"
[    1.089016] 0x000000060000-0x0000000c0000 : "0:QSEE"
[    1.093874] 0x0000000c0000-0x0000000d0000 : "0:CDT"
[    1.099044] 0x0000000d0000-0x0000000e0000 : "0:DDRPARAMS"
[    1.103595] 0x0000000e0000-0x0000000f0000 : "0:APPSBLENV"
[    1.109183] 0x0000000f0000-0x0000001b0000 : "0:APPSBL"
[    1.114521] 0x0000001b0000-0x000000200000 : "0:reserved1"
[    1.179303] i2c_dev: i2c /dev entries driver
[    1.181542] sdhci: Secure Digital Host Controller Interface driver
[    1.182654] sdhci: Copyright(c) Pierre Ossman
[    1.188678] sdhci-pltfm: SDHCI platform and OF driver helper
[    1.197306] NET: Registered PF_INET6 protocol family
[    1.200506] Segment Routing with IPv6
[    1.203808] In-situ OAM (IOAM) with IPv6
[    1.207435] NET: Registered PF_PACKET protocol family
[    1.211391] bridge: filtering via arp/ip/ip6tables is no longer available by                                                                                                              default. Update your scripts to load br_netfilter if you need this.
[    1.216656] 8021q: 802.1Q VLAN Support v1.8
[    1.229414] Registering SWP/SWPB emulation handler
[    1.246723] qca8k-ipq4019 c000000.switch: configuring for fixed/internal link                                                                                                              mode
[    1.247048] qca8k-ipq4019 c000000.switch: Link is Up - 1Gbps/Full - flow cont                                                                                                             rol rx/tx
[    1.329310] qca8k-ipq4019 c000000.switch lan4 (uninitialized): PHY [90000.mdi                                                                                                             o-1:01] driver [Qualcomm QCA8075] (irq=POLL)
[    1.409322] qca8k-ipq4019 c000000.switch lan3 (uninitialized): PHY [90000.mdi                                                                                                             o-1:02] driver [Qualcomm QCA8075] (irq=POLL)
[    1.489328] qca8k-ipq4019 c000000.switch lan2 (uninitialized): PHY [90000.mdi                                                                                                             o-1:03] driver [Qualcomm QCA8075] (irq=POLL)
[    1.570799] qca8k-ipq4019 c000000.switch wan (uninitialized): PHY [90000.mdio                                                                                                             -1:04] driver [Qualcomm QCA8075] (irq=POLL)
[    1.571765] DSA: tree 0 setup
[    1.581382] ubi0: attaching mtd9
[    1.884804] ubi0: scanning is finished
[    1.898519] ubi0: attached mtd9 (name "rootfs", size 29 MiB)
[    1.898568] ubi0: PEB size: 131072 bytes (128 KiB), LEB size: 126976 bytes
[    1.903264] ubi0: min./max. I/O unit sizes: 2048/2048, sub-page size 2048
[    1.909990] ubi0: VID header offset: 2048 (aligned 2048), data offset: 4096
[    1.916821] ubi0: good PEBs: 232, bad PEBs: 0, corrupted PEBs: 0
[    1.923575] ubi0: user volume: 3, internal volumes: 1, max. volumes count: 12                                                                                                             8
[    1.929843] ubi0: max/mean erase counter: 6/4, WL threshold: 4096, image sequ                                                                                                             ence number: 1821825753
[    1.936876] ubi0: available PEBs: 0, total reserved PEBs: 232, PEBs reserved                                                                                                              for bad PEB handling: 20
[    1.946179] ubi0: background thread "ubi_bgt0d" started, PID 137
[    ▒[    1.969042] VFS: Mounted root (squashfs filesystem) readonly on device                                                                                                              254:0.
[    1.971038] Freeing unused kernel image (initmem) memory: 1024K
[    1.996198] Run /sbin/init as init process
[    2.408077] init: Console is alive
[    2.408453] init: - watchdog -
[    3.320704] kmodloader: loading kernel modules from /etc/modules-boot.d/*
[    3.794510] xhci-hcd xhci-hcd.0.auto: xHCI Host Controller
[    3.794589] xhci-hcd xhci-hcd.0.auto: new USB bus registered, assigned bus nu                                                                                                             mber 1
[    3.799224] xhci-hcd xhci-hcd.0.auto: hcc params 0x0228f665 hci version 0x100                                                                                                              quirks 0x0000000002010010
[    3.806562] xhci-hcd xhci-hcd.0.auto: irq 104, io mem 0x08a00000
[    3.816088] xhci-hcd xhci-hcd.0.auto: xHCI Host Controller
[    3.822111] xhci-hcd xhci-hcd.0.auto: new USB bus registered, assigned bus nu                                                                                                             mber 2
[    3.827418] xhci-hcd xhci-hcd.0.auto: Host supports USB 3.0 SuperSpeed
[    3.835643] hub 1-0:1.0: USB hub found
[    3.841609] hub 1-0:1.0: 1 port detected
[    3.845724] usb usb2: We don't know the algorithms for LPM for this host, dis                                                                                                             abling LPM.
[    3.852682] hub 2-0:1.0: USB hub found
[    3.857497] hub 2-0:1.0: 1 port detected
[    3.861502] xhci-hcd xhci-hcd.1.auto: xHCI Host Controller
[    3.865079] xhci-hcd xhci-hcd.1.auto: new USB bus registered, assigned bus nu                                                                                                             mber 3
[    3.870647] xhci-hcd xhci-hcd.1.auto: hcc params 0x0220f665 hci version 0x100                                                                                                              quirks 0x0000000002010010
[    3.878007] xhci-hcd xhci-hcd.1.auto: irq 105, io mem 0x06000000
[    3.887488] xhci-hcd xhci-hcd.1.auto: xHCI Host Controller
[    3.893551] xhci-hcd xhci-hcd.1.auto: new USB bus registered, assigned bus nu                                                                                                             mber 4
[    3.898857] xhci-hcd xhci-hcd.1.auto: Host supports USB 3.0 SuperSpeed
[    3.907129] hub 3-0:1.0: USB hub found
[    3.913013] hub 3-0:1.0: 1 port detected
[    3.917195] usb usb4: We don't know the algorithms for LPM for this host, dis                                                                                                             abling LPM.
[    3.921348] hub 4-0:1.0: USB hub found
[    3.929919] hub 4-0:1.0: config failed, hub doesn't have any ports! (err -19)
[    3.934710] kmodloader: done loading kernel modules from /etc/modules-boot.d/                                                                                                             *
[    3.944246] init: - preinit -
[    4.694173] random: jshn: uninitialized urandom read (4 bytes read)
[    4.738932] random: jshn: uninitialized urandom read (4 bytes read)
[    4.766942] random: jshn: uninitialized urandom read (4 bytes read)
[    5.004591] ipqess-edma c080000.ethernet eth0: configuring for fixed/internal                                                                                                              link mode
[    5.005330] qca8k-ipq4019 c000000.switch lan2: configuring for phy/psgmii lin                                                                                                             k mode
[    5.011574] ipqess-edma c080000.ethernet eth0: Link is Up - 1Gbps/Full - flow                                                                                                              control rx/tx
[    6.544232] qca8k-ipq4019 c000000.switch: PSGMII work was stabilized after 1                                                                                                              calibration retries !
[    6.544875] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready
[    6.561478] random: procd: uninitialized urandom read (4 bytes read)
Press the [f] key and hit [enter] to enter failsafe mode
Press the [1], [2], [3] or [4] key and hit [enter] to select the debug level
[    7.656085] usb 2-1: new SuperSpeed USB device number 2 using xhci-hcd
[    8.726728] UBIFS (ubi0:2): Mounting in unauthenticated mode
[    8.726988] UBIFS (ubi0:2): background thread "ubifs_bgt0_2" started, PID 241
[    8.796914] UBIFS (ubi0:2): recovery needed
[    8.936916] UBIFS (ubi0:2): recovery completed
[    8.937102] UBIFS (ubi0:2): UBIFS: mounted UBI device 0, volume 2, name "rootfs_data"
[    8.940278] UBIFS (ubi0:2): LEB size: 126976 bytes (124 KiB), min./max. I/O unit sizes: 2048 bytes/2048 bytes
[    8.948232] UBIFS (ubi0:2): FS size: 18030592 bytes (17 MiB, 142 LEBs), max 152 LEBs, journal size 1015809 bytes (0 MiB, 7 LEBs)
[    8.958100] UBIFS (ubi0:2): reserved for root: 851628 bytes (831 KiB)
[    8.969717] UBIFS (ubi0:2): media format: w5/r0 (latest is w5/r0), UUID 4DD449C3-5477-4235-9A79-2ABB106C2D8F, small LPT model
[    8.982501] mount_root: switching to ubifs overlay
[    9.002218] urandom-seed: Seeding with /etc/urandom.seed
[    9.174168] procd: - early -
[    9.174378] procd: - watchdog -
[    9.808629] procd: - watchdog -
[    9.811584] procd: - ubus -
[    9.922421] random: ubusd: uninitialized urandom read (4 bytes read)
[    9.966127] random: ubusd: uninitialized urandom read (4 bytes read)
[    9.970640] random: ubusd: uninitialized urandom read (4 bytes read)
[    9.974464] procd: - init -
Please press Enter to activate this console.
[   10.655243] kmodloader: loading kernel modules from /etc/modules.d/*
[   10.675925] random: crng init done
[   10.675977] random: 31 urandom warning(s) missed due to ratelimiting
[   10.775331] usbcore: registered new interface driver cdc_wdm
[   10.776094] Loading modules backported from Linux version v6.1.24-0-g0102425ac76b
[   10.780078] Backport generated by backports.git v5.15.92-1-44-gd6ea70fafd36
[   10.800107] usbcore: registered new interface driver usbserial_generic
[   10.800216] usbserial: USB Serial support registered for generic
[   10.896730] PPP generic driver version 2.4.2
[   10.897869] NET: Registered PF_PPPOX protocol family
[   10.907618] qmi_wwan 2-1:1.5: cdc-wdm0: USB WDM device
[   10.908720] qmi_wwan 2-1:1.5 wwan0: register 'qmi_wwan' at usb-xhci-hcd.0.auto-1, WWAN/QMI device, ba:0a:b8:e2:7c:fc
[   10.911980] usbcore: registered new interface driver qmi_wwan
[   11.544191] urngd: v1.0.2 started.
[   12.040762] ath10k_ahb a000000.wifi: qca4019 hw1.0 target 0x01000000 chip_id 0x003b00ff sub 0000:0000
[   12.040832] ath10k_ahb a000000.wifi: kconfig debug 0 debugfs 1 tracing 0 dfs 1 testmode 0
[   12.052649] ath10k_ahb a000000.wifi: firmware ver 10.4b-ct-4019-fW-13-5ae337bb1 api 5 features mfp,peer-flow-ctrl,txstatus-noack,wmi-10.x-CT,ratemask-CT,regdump-CT,txrate-CT,flush-all-CT,pingpong-CT,ch-regs-CT,nop-CT,set-special-CT,tx-rc-CT,cust-stats-CT,txrate2-CT,beacon-cb-CT,wmi-block-ack-CT,wmi-bcn-rc-CT crc32 6b2b5c5b
[   12.107827] ath10k_ahb a000000.wifi: Loading BDF type 0
[   12.210559] ath10k_ahb a000000.wifi: board_file api 2 bmi_id 0:20 crc32 d140cd7d
[   13.501721] ath10k_ahb a000000.wifi: 10.4 wmi init: vdevs: 16  peers: 48  tid: 96
[   13.501794] ath10k_ahb a000000.wifi: msdu-desc: 2500  skid: 32
[   13.551277] ath10k_ahb a000000.wifi: wmi print 'P 48/48 V 16 K 144 PH 176 T 186  msdu-desc: 2500  sw-crypt: 0 ct-sta: 0'
[   13.552230] ath10k_ahb a000000.wifi: wmi print 'free: 53252 iram: 13432 sram: 35752'
[   13.691180] ath10k_ahb a000000.wifi: htt-ver 2.2 wmi-op 6 htt-op 4 cal pre-cal-nvmem max-sta 32 raw 0 hwcrypto 1
[   14.958377] ath10k_ahb a800000.wifi: qca4019 hw1.0 target 0x01000000 chip_id 0x003b00ff sub 0000:0000
[   14.958449] ath10k_ahb a800000.wifi: kconfig debug 0 debugfs 1 tracing 0 dfs 1 testmode 0
[   14.970346] ath10k_ahb a800000.wifi: firmware ver 10.4b-ct-4019-fW-13-5ae337bb1 api 5 features mfp,peer-flow-ctrl,txstatus-noack,wmi-10.x-CT,ratemask-CT,regdump-CT,txrate-CT,flush-all-CT,pingpong-CT,ch-regs-CT,nop-CT,set-special-CT,tx-rc-CT,cust-stats-CT,txrate2-CT,beacon-cb-CT,wmi-block-ack-CT,wmi-bcn-rc-CT crc32 6b2b5c5b
[   15.025407] ath10k_ahb a800000.wifi: Loading BDF type 0
[   15.037456] ath10k_ahb a800000.wifi: board_file api 2 bmi_id 0:21 crc32 d140cd7d
[   16.328923] ath10k_ahb a800000.wifi: 10.4 wmi init: vdevs: 16  peers: 48  tid: 96
[   16.328996] ath10k_ahb a800000.wifi: msdu-desc: 2500  skid: 32
[   16.375953] ath10k_ahb a800000.wifi: wmi print 'P 48/48 V 16 K 144 PH 176 T 186  msdu-desc: 2500  sw-crypt: 0 ct-sta: 0'
[   16.376897] ath10k_ahb a800000.wifi: wmi print 'free: 53252 iram: 13432 sram: 35752'
[   16.513220] ath10k_ahb a800000.wifi: htt-ver 2.2 wmi-op 6 htt-op 4 cal pre-cal-nvmem max-sta 32 raw 0 hwcrypto 1
[   16.636587] usbcore: registered new interface driver option
[   16.636754] usbserial: USB Serial support registered for GSM modem (1-port)
[   16.641593] option 2-1:1.2: GSM modem (1-port) converter detected
[   16.648387] usb 2-1: GSM modem (1-port) converter now attached to ttyUSB0
[   16.654596] option 2-1:1.3: GSM modem (1-port) converter detected
[   16.661334] usb 2-1: GSM modem (1-port) converter now attached to ttyUSB1
[   16.667516] option 2-1:1.4: GSM modem (1-port) converter detected
[   16.674140] usb 2-1: GSM modem (1-port) converter now attached to ttyUSB2
[   16.683079] kmodloader: done loading kernel modules from /etc/modules.d/*

And then it repeats again...

Does anyone have any ideas what I'm doing wrong?

2

are you able to do that ?

1 Like
3

Hi, it took a couple of tries, but yes I can get into that.

1 Like
4

Then do the reset as described in https://openwrt.org/docs/guide-user/troubleshooting/failsafe_and_factory_reset

1 Like
5

Hi, so I've got into failsafe mode but I'm limited in what I can do as the ethernet isn't up and running so all I have is the putty terminal via serial. I've done the hard reset section which I'd hoped would take it back to the base install of OpenWRT without any of the further tweaks I'd then done (sftp, luci-proto-qmi) just in case it was one of those that caused the issue but it's still gets stuck in the loop when I reboot. A few questions that I have:-

  1. Am I wiping the old Nordic install off correctly, I tried two methods that I found
    From the official Install guide:-
  ubiattach -m 9 
  ubirmvol /dev/ubi0 -N ubi_rootfs 
  ubirmvol /dev/ubi0 -N ubi_rootfs_data

There was then a further info elsewhere so I also tried as an add on to this:-

  ubidetach -m 8
  ubiformat -y /dev/mtd8
  ubidetach -m 9
  ubiformat -y /dev/mtd9

There was one further install guide on a Nordic install site that suggested using the uboot console to erase the NAND memory with:-

  nand erase 0x1800000 0x1d00000

  1. Is it worth trying a different version of openwrt? (I'd been going straight for the latest 23.05.2)

  2. Failing those, I can try going back to Nordic. Unfortunately the backup I did for my mtd partitions didn't actually make it on to the USB stick for some reason, but there's other sites where I can get these from.

6

@Leo-PL any suggestions ?

7

It doesn't look like it's related to UBI. I've noticed some ubifs errors when downgrading from main to 22.03, but these would cause the router to either not mount RW overlay or spew out a ton of ubifs errors at boot.

@Matt2uk could you show the exact moment when the unit reboots and not cut the log at the repetition?

I can get you backup for mtd8 and mtd9 for some recent versions of Nordic FW. Which version were you running?

1 Like
8

The loop pretty much does restart at the position I cut the code, although I don't think I could guarantee that it was always exactly the same. At one stage I thought it varied so might have been a watchdog running in the background.

If it helps, I've caught it and forced a Level 4 debug as follows, which might assist although I've had to drop a lot of the initial section due to exceeding the character count:-

[   12.905683] procd: Command: makedev
[   12.927340] procd:  /dev/mtdblock8
[   12.930606] procd:  0600
[   12.934073] procd: Message:
[   12.936808] procd:  ACTION=add
[   12.939286] procd:  DEVPATH=/devices/platform/soc/79b0000.nand-controller/mtd/mtd8/mtdblock8
[   12.942419] procd:  SUBSYSTEM=block
[   12.951056] procd:  SYNTH_UUID=0
[   12.954216] procd:  MAJOR=31
[   12.957759] procd:  MINOR=8
[   12.960555] procd:  DEVNAME=mtdblock8
[   12.963070] procd:  DEVTYPE=disk
[   12.966925] procd:  DISKSEQ=17
[   12.970189] procd:  SEQNUM=783
[   12.973298] procd: {{"ACTION":"add","DEVPATH":"/devices/virtual/block/loop5","SUBSYSTEM":"block","SYNTH_UUID":"0","MAJOR":"7","MINOR":"5","DEVNAME":"loop5","DEVTYPE":"disk","DISKSEQ":"6","SEQNUM":"784"}}
[   12.976312] procd: Command: makedev
[   12.994102] procd:  /dev/loop5
[   12.997558] procd:  0600
[   13.000652] procd: Message:
[   13.003349] procd:  ACTION=add
[   13.005863] procd:  DEVPATH=/devices/virtual/block/loop5
[   13.009012] procd:  SUBSYSTEM=block
[   13.014457] procd:  SYNTH_UUID=0
[   13.017696] procd:  MAJOR=7
[   13.021140] procd:  MINOR=5
[   13.023659] procd:  DEVNAME=loop5
[   13.026455] procd:  DEVTYPE=disk
[   13.029909] procd:  DISKSEQ=6
[   13.033206] procd:  SEQNUM=784
[   13.036333] procd: {{"ACTION":"add","DEVPATH":"/devices/platform/soc/78b5000.spi/spi_master/spi0/spi0.0/mtd/mtd17/mtdblock17","SUBSYSTEM":"block","SYNTH_UUID":"0","MAJOR":"31","MINOR":"17","DEVNAME":"mtdblock17","DEVTYPE":"disk","DISKSEQ":"26","SEQNUM":"785"}}
[   13.040111] procd: Command: makedev
[   13.062238] procd:  /dev/mtdblock17
[   13.065413] procd:  0600
[   13.068905] procd: Message:
[   13.071663] procd:  ACTION=add
[   13.074185] procd:  DEVPATH=/devices/platform/soc/78b5000.spi/spi_master/spi0/spi0.0/mtd/mtd17/mtdblock17
[   13.077339] procd:  SUBSYSTEM=block
[   13.086961] procd:  SYNTH_UUID=0
[   13.090239] procd:  MAJOR=31
[   13.093710] procd:  MINOR=17
[   13.096610] procd:  DEVNAME=mtdblock17
[   13.099443] procd:  DEVTYPE=disk
[   13.102999] procd:  DISKSEQ=26
[   13.106432] procd:  SEQNUM=785
[   13.109493] procd: {{"ACTION":"add","DEVPATH":"/devices/platform/soc/79b0000.nand-controller/mtd/mtd6/mtdblock6","SUBSYSTEM":"block","SYNTH_UUID":"0","MAJOR":"31","MINOR":"6","DEVNAME":"mtdblock6","DEVTYPE":"disk","DISKSEQ":"15","SEQNUM":"786"}}
[   13.112456] procd: Command: makedev
[   13.134113] procd:  /dev/mtdblock6
[   13.137395] procd:  0600
[   13.140845] procd: Message:
[   13.143539] procd:  ACTION=add
[   13.146080] procd:  DEVPATH=/devices/platform/soc/79b0000.nand-controller/mtd/mtd6/mtdblock6
[   13.149189] procd:  SUBSYSTEM=block
[   13.157792] procd:  SYNTH_UUID=0
[   13.160987] procd:  MAJOR=31
[   13.164456] procd:  MINOR=6
[   13.167344] procd:  DEVNAME=mtdblock6
[   13.169839] procd:  DEVTYPE=disk
[   13.173660] procd:  DISKSEQ=15
[   13.176975] procd:  SEQNUM=786
[   13.180060] procd: {{"ACTION":"add","DEVPATH":"/devices/virtual/block/loop3","SUBSYSTEM":"block","SYNTH_UUID":"0","MAJOR":"7","MINOR":"3","DEVNAME":"loop3","DEVTYPE":"disk","DISKSEQ":"4","SEQNUM":"787"}}
[   13.183022] procd: Command: makedev
[   13.200858] procd:  /dev/loop3
[   13.204302] procd:  0600
[   13.207452] procd: Message:
[   13.210116] procd:  ACTION=add
[   13.212635] procd:  DEVPATH=/devices/virtual/block/loop3
[   13.215764] procd:  SUBSYSTEM=block
[   13.221254] procd:  SYNTH_UUID=0
[   13.224441] procd:  MAJOR=7
[   13.227960] procd:  MINOR=3
[   13.230432] procd:  DEVNAME=loop3
[   13.233208] procd:  DEVTYPE=disk
[   13.236736] procd:  DISKSEQ=4
[   13.239979] procd:  SEQNUM=787
[   13.243089] procd: {{"ACTION":"add","DEVPATH":"/devices/platform/soc/8af8800.usb3/8a00000.dwc3/xhci-hcd.0.auto/usb2/2-1","SUBSYSTEM":"usb","MAJOR":"189","MINOR":"129","DEVNAME":"bus/usb/002/002","DEVTYPE":"usb_device","PRODUCT":"19d2/1485/5287","TYPE":"0/0/0","BUSNUM":"002","DEVNUM":"002","SEQNUM":"788"}}
[   13.250727] procd: Command: makedev
[   13.272821] procd:  /dev/bus/usb/002/002
[   13.276203] procd:  0600
[   13.280341] procd: Message:
[   13.282860] procd:  ACTION=add
[   13.285380] procd:  DEVPATH=/devices/platform/soc/8af8800.usb3/8a00000.dwc3/xhci-hcd.0.auto/usb2/2-1
[   13.288531] procd:  SUBSYSTEM=usb
[   13.297814] procd:  MAJOR=189
[   13.301003] procd:  MINOR=129
[   13.303954] procd:  DEVNAME=bus/usb/002/002
[   13.306923] procd:  DEVTYPE=usb_device
[   13.311008] procd:  PRODUCT=19d2/1485/5287
[   13.314729] procd:  TYPE=0/0/0
[   13.318827] procd:  BUSNUM=002
[   13.321836] procd:  DEVNUM=002
[   13.324875] procd:  SEQNUM=788
[   13.328139] procd: Command: exec
[   13.330958] procd:  /sbin/hotplug-call
[   13.334338] procd:  usb
[   13.337924] procd: Message:
[   13.340242] procd:  ACTION=add
[   13.343018] procd:  DEVPATH=/devices/platform/soc/8af8800.usb3/8a00000.dwc3/xhci-hcd.0.auto/usb2/2-1
[   13.346194] procd:  SUBSYSTEM=usb
[   13.355433] procd:  MAJOR=189
[   13.358680] procd:  MINOR=129
[   13.361594] procd:  DEVNAME=bus/usb/002/002
[   13.364547] procd:  DEVTYPE=usb_device
[   13.368591] procd:  PRODUCT=19d2/1485/5287
[   13.372360] procd:  TYPE=0/0/0
[   13.376458] procd:  BUSNUM=002
[   13.379474] procd:  DEVNUM=002
[   13.382511] procd:  SEQNUM=788
[   13.386103] procd: Launched hotplug exec instance, pid=629
[   13.388798] procd: {{"ACTION":"add","DEVPATH":"/devices/platform/soc/8af8800.usb3/8a00000.dwc3/xhci-hcd.0.auto/usb2/2-1/2-1:1.0","SUBSYSTEM":"usb","DEVTYPE":"usb_interface","PRODUCT":"19d2/1485/5287","TYPE":"0/0/0","INTERFACE":"2/2/255","MODALIAS":"usb:v19D2p1485d5287dc00dsc00dp00ic02isc02ipFFin00","SEQNUM":"789"}}
[   13.400236] procd: Command: exec
[   13.422239] procd:  /sbin/hotplug-call
[   13.425413] procd:  usb
[   13.428976] procd: Message:
[   13.431299] procd:  ACTION=add
[   13.434085] procd:  DEVPATH=/devices/platform/soc/8af8800.usb3/8a00000.dwc3/xhci-hcd.0.auto/usb2/2-1/2-1:1.0
[   13.437181] procd:  SUBSYSTEM=usb
[   13.447137] procd:  DEVTYPE=usb_interface
[   13.450314] procd:  PRODUCT=19d2/1485/5287
[   13.454304] procd:  TYPE=0/0/0
[   13.458323] procd:  INTERFACE=2/2/255
[   13.461335] procd:  MODALIAS=usb:v19D2p1485d5287dc00dsc00dp00ic02isc02ipFFin00
[   13.465074] procd:  SEQNUM=789
[   13.472284] procd: Finished hotplug exec instance, pid=629
[   13.475802] procd: Launched hotplug exec instance, pid=635
[   13.480947] procd: {{"ACTION":"add","DEVPATH":"/devices/platform/soc/8af8800.usb3/8a00000.dwc3/xhci-hcd.0.auto/usb2/2-1/2-1:1.1","SUBSYSTEM":"usb","DEVTYPE":"usb_interface","PRODUCT":"19d2/1485/5287","TYPE":"0/0/0","INTERFACE":"10/0/0","MODALIAS":"usb:v19D2p1485d5287dc00dsc00dp00ic0Aisc00ip00in01","SEQNUM":"790"}}
[   13.491948] procd: Command: exec
[   13.514024] procd:  /sbin/hotplug-call
[   13.517401] procd:  usb
[   13.520883] procd: Message:
[   13.523228] procd:  ACTION=add
[   13.526032] procd:  DEVPATH=/devices/platform/soc/8af8800.usb3/8a00000.dwc3/xhci-hcd.0.auto/usb2/2-1/2-1:1.1
[   13.529141] procd:  SUBSYSTEM=usb
[   13.539139] procd:  DEVTYPE=usb_interface
[   13.542325] procd:  PRODUCT=19d2/1485/5287
[   13.546339] procd:  TYPE=0/0/0
[   13.550308] procd:  INTERFACE=10/0/0
[   13.553347] procd:  MODALIAS=usb:v19D2p1485d5287dc00dsc00dp00ic0Aisc00ip00in01
[   13.557110] procd:  SEQNUM=790
[   13.564171] procd: Finished hotplug exec instance, pid=635
[   13.567685] procd: Launched hotplug exec instance, pid=639
[   13.572844] procd: {{"ACTION":"add","DEVPATH":"/devices/platform/soc/8af8800.usb3/8a00000.dwc3/xhci-hcd.0.auto/usb2/2-1/2-1:1.2","SUBSYSTEM":"usb","DEVTYPE":"usb_interface","PRODUCT":"19d2/1485/5287","TYPE":"0/0/0","INTERFACE":"255/255/255","MODALIAS":"usb:v19D2p1485d5287dc00dsc00dp00icFFiscFFipFFin02","SEQNUM":"791"}}
[   13.584667] procd: Command: exec
[   13.606628] procd:  /sbin/hotplug-call
[   13.609696] procd:  usb
[   13.613242] procd: Message:
[   13.615587] procd:  ACTION=add
[   13.618406] procd:  DEVPATH=/devices/platform/soc/8af8800.usb3/8a00000.dwc3/xhci-hcd.0.auto/usb2/2-1/2-1:1.2
[   13.621503] procd:  SUBSYSTEM=usb
[   13.631501] procd:  DEVTYPE=usb_interface
[   13.634687] procd:  PRODUCT=19d2/1485/5287
[   13.638707] procd:  TYPE=0/0/0
[   13.642673] procd:  INTERFACE=255/255/255
[   13.645709] procd:  MODALIAS=usb:v19D2p1485d5287dc00dsc00dp00icFFiscFFipFFin02
[   13.649835] procd:  SEQNUM=791
[   13.657028] procd: Finished hotplug exec instance, pid=639
[   13.660460] procd: Launched hotplug exec instance, pid=643
[   13.665638] procd: {{"ACTION":"add","DEVPATH":"/devices/platform/soc/8af8800.usb3/8a00000.dwc3/xhci-hcd.0.auto/usb2/2-1/2-1:1.3","SUBSYSTEM":"usb","DEVTYPE":"usb_interface","PRODUCT":"19d2/1485/5287","TYPE":"0/0/0","INTERFACE":"255/255/255","MODALIAS":"usb:v19D2p1485d5287dc00dsc00dp00icFFiscFFipFFin03","SEQNUM":"792"}}
[   13.677511] procd: Command: exec
[   13.699418] procd:  /sbin/hotplug-call
[   13.702488] procd:  usb
[   13.706074] procd: Message:
[   13.708387] procd:  ACTION=add
[   13.711161] procd:  DEVPATH=/devices/platform/soc/8af8800.usb3/8a00000.dwc3/xhci-hcd.0.auto/usb2/2-1/2-1:1.3
[   13.714296] procd:  SUBSYSTEM=usb
[   13.724294] procd:  DEVTYPE=usb_interface
[   13.727518] procd:  PRODUCT=19d2/1485/5287
[   13.731475] procd:  TYPE=0/0/0
[   13.735464] procd:  INTERFACE=255/255/255
[   13.738527] procd:  MODALIAS=usb:v19D2p1485d5287dc00dsc00dp00icFFiscFFipFFin03
[   13.742590] procd:  SEQNUM=792
[   13.749781] procd: Finished hotplug exec instance, pid=643
[   13.753310] procd: Launched hotplug exec instance, pid=647
[   13.758487] procd: {{"ACTION":"add","DEVPATH":"/devices/platform/soc/8af8800.usb3/8a00000.dwc3/xhci-hcd.0.auto/usb2/2-1/2-1:1.4","SUBSYSTEM":"usb","DEVTYPE":"usb_interface","PRODUCT":"19d2/1485/5287","TYPE":"0/0/0","INTERFACE":"255/255/255","MODALIAS":"usb:v19D2p1485d5287dc00dsc00dp00icFFiscFFipFFin04","SEQNUM":"793"}}
[   13.770283] procd: Command: exec
[   13.792233] procd:  /sbin/hotplug-call
[   13.795286] procd:  usb
[   13.798911] procd: Message:
[   13.801180] procd:  ACTION=add
[   13.803958] procd:  DEVPATH=/devices/platform/soc/8af8800.usb3/8a00000.dwc3/xhci-hcd.0.auto/usb2/2-1/2-1:1.4
[   13.807128] procd:  SUBSYSTEM=usb
[   13.817095] procd:  DEVTYPE=usb_interface
[   13.820278] procd:  PRODUCT=19d2/1485/5287
[   13.824268] procd:  TYPE=0/0/0
[   13.828285] procd:  INTERFACE=255/255/255
[   13.831301] procd:  MODALIAS=usb:v19D2p1485d5287dc00dsc00dp00icFFiscFFipFFin04
[   13.835384] procd:  SEQNUM=793
[   13.842583] procd: Finished hotplug exec instance, pid=647
[   13.846102] procd: Launched hotplug exec instance, pid=651
[   13.851204] procd: {{"ACTION":"add","DEVPATH":"/devices/platform/soc/8af8800.usb3/8a00000.dwc3/xhci-hcd.0.auto/usb2/2-1/2-1:1.5","SUBSYSTEM":"usb","DEVTYPE":"usb_interface","PRODUCT":"19d2/1485/5287","TYPE":"0/0/0","INTERFACE":"255/255/255","MODALIAS":"usb:v19D2p1485d5287dc00dsc00dp00icFFiscFFipFFin05","SEQNUM":"794"}}
[   13.863057] procd: Command: exec
[   13.885005] procd:  /sbin/hotplug-call
[   13.888119] procd:  usb
[   13.891629] procd: Message:
[   13.893972] procd:  ACTION=add
[   13.896779] procd:  DEVPATH=/devices/platform/soc/8af8800.usb3/8a00000.dwc3/xhci-hcd.0.auto/usb2/2-1/2-1:1.5
[   13.899889] procd:  SUBSYSTEM=usb
[   13.909887] procd:  DEVTYPE=usb_interface
[   13.913072] procd:  PRODUCT=19d2/1485/5287
[   13.917105] procd:  TYPE=0/0/0
[   13.921057] procd:  INTERFACE=255/255/255
[   13.924094] procd:  MODALIAS=usb:v19D2p1485d5287dc00dsc00dp00icFFiscFFipFFin05
[   13.928254] procd:  SEQNUM=794
[   13.935354] procd: Finished hotplug exec instance, pid=651
[   13.938929] procd: Launched hotplug exec instance, pid=655
[   13.944020] procd: {{"ACTION":"add","DEVPATH":"/devices/platform/soc/8af8800.usb3/8a00000.dwc3/xhci-hcd.0.auto/usb2/2-1/2-1:1.6","SUBSYSTEM":"usb","DEVTYPE":"usb_interface","PRODUCT":"19d2/1485/5287","TYPE":"0/0/0","INTERFACE":"255/66/1","MODALIAS":"usb:v19D2p1485d5287dc00dsc00dp00icFFisc42ip01in06","SEQNUM":"795"}}
[   13.955463] procd: Command: exec
[   13.977467] procd:  /sbin/hotplug-call
[   13.980618] procd:  usb
[   13.984163] procd: Message:
[   13.986554] procd:  ACTION=add
[   13.989291] procd:  DEVPATH=/devices/platform/soc/8af8800.usb3/8a00000.dwc3/xhci-hcd.0.auto/usb2/2-1/2-1:1.6
[   13.992423] procd:  SUBSYSTEM=usb
[   14.002485] procd:  DEVTYPE=usb_interface
[   14.005611] procd:  PRODUCT=19d2/1485/5287
[   14.009649] procd:  TYPE=0/0/0
[   14.013596] procd:  INTERFACE=255/66/1
[   14.016701] procd:  MODALIAS=usb:v19D2p1485d5287dc00dsc00dp00icFFisc42ip01in06
[   14.020372] procd:  SEQNUM=795
[   14.027654] procd: Finished hotplug exec instance, pid=655
[   14.031125] procd: Launched hotplug exec instance, pid=659
[   14.036328] procd: {{"ACTION":"bind","DEVPATH":"/devices/platform/soc/8af8800.usb3/8a00000.dwc3/xhci-hcd.0.auto/usb2/2-1","SUBSYSTEM":"usb","MAJOR":"189","MINOR":"129","DEVNAME":"bus/usb/002/002","DEVTYPE":"usb_device","DRIVER":"usb","PRODUCT":"19d2/1485/5287","TYPE":"0/0/0","BUSNUM":"002","DEVNUM":"002","SEQNUM":"796"}}
[   14.047985] procd: Command: exec
[   14.070176] procd:  /sbin/hotplug-call
[   14.073329] procd:  usb
[   14.076918] procd: Message:
[   14.079221] procd:  ACTION=bind
[   14.081993] procd:  DEVPATH=/devices/platform/soc/8af8800.usb3/8a00000.dwc3/xhci-hcd.0.auto/usb2/2-1
[   14.085127] procd:  SUBSYSTEM=usb
[   14.094528] procd:  MAJOR=189
[   14.097748] procd:  MINOR=129
[   14.100659] procd:  DEVNAME=bus/usb/002/002
[   14.103609] procd:  DEVTYPE=usb_device
[   14.107627] procd:  DRIVER=usb
[   14.111421] procd:  PRODUCT=19d2/1485/5287
[   14.114460] procd:  TYPE=0/0/0
[   14.118561] procd:  BUSNUM=002
[   14.121579] procd:  DEVNUM=002
[   14.124614] procd:  SEQNUM=796
[   14.127733] procd: Finished hotplug exec instance, pid=659
[   14.131210] procd: Launched hotplug exec instance, pid=663
[   14.151553] procd: Finished hotplug exec instance, pid=663
[   14.627253] procd: Coldplug complete
[   14.627357] procd: Change state 1 -> 2
[   14.629953] procd: - watchdog -
[   14.633511] procd: Ping
[   14.636624] procd: Opened watchdog with timeout 30s
[   14.638986] procd: Watchdog did not previously reset the system
[   14.644126] procd: - ubus -
[   14.649922] procd: Create service ubus
[   14.652739] procd: Start instance ubus::instance1
[   14.656840] procd: Started instance ubus::instance1[667]
[   14.661261] procd: running /etc/init.d/ubus running
[   14.679101] procd: glob failed on /etc/init.d/ubus
[   14.699694] procd: Connection to ubus failed
[   14.810156] procd: Connected to ubus, id=ab43fd7d
[   14.810293] procd: Change state 2 -> 3
[   14.813904] procd: - init -
[   14.826091] procd: Parsing inittab - ::sysinit:/etc/init.d/rcS S boot
[   14.826268] procd: Parsing inittab - ::shutdown:/etc/init.d/rcS K shutdown
[   14.831634] procd: Parsing inittab - ttyMSM0::askfirst:/usr/libexec/login.sh
[   14.838492] procd: Parsing inittab - ttyMSM1::askfirst:/usr/libexec/login.sh
[   14.846245] procd: Launched new askfirst action, pid=668
[   14.852705] procd: Skipping ttyMSM1
[   14.857992] procd: running /etc/rc.d/S* boot
Please press Enter to activate this console.
[   15.574486] kmodloader: loading kernel modules from /etc/modules.d/*
[   15.692040] usbcore: registered new interface driver cdc_wdm
[   15.692744] Loading modules backported from Linux version v6.1.24-0-g0102425ac76b
[   15.696821] Backport generated by backports.git v5.15.92-1-44-gd6ea70fafd36
[   15.716853] usbcore: registered new interface driver usbserial_generic
[   15.716961] usbserial: USB Serial support registered for generic
[   15.813279] PPP generic driver version 2.4.2
[   15.816346] NET: Registered PF_PPPOX protocol family
[   15.836115] qmi_wwan 2-1:1.5: cdc-wdm0: USB WDM device
[   15.838337] qmi_wwan 2-1:1.5 wwan0: register 'qmi_wwan' at usb-xhci-hcd.0.auto-1, WWAN/QMI device, 2a:ab:15:1a:5d:c1
[   15.840532] usbcore: registered new interface driver qmi_wwan
[   16.449577] urngd: v1.0.2 started.
[   17.148837] ath10k_ahb a000000.wifi: qca4019 hw1.0 target 0x01000000 chip_id 0x003b00ff sub 0000:0000
[   17.148907] ath10k_ahb a000000.wifi: kconfig debug 0 debugfs 1 tracing 0 dfs 1 testmode 0
[   17.160695] ath10k_ahb a000000.wifi: firmware ver 10.4b-ct-4019-fW-13-5ae337bb1 api 5 features mfp,peer-flow-ctrl,txstatus-noack,wmi-10.x-CT,ratemask-CT,regdump-CT,txrate-CT,flush-all-CT,pingpong-CT,ch-regs-CT,nop-CT,set-special-CT,tx-rc-CT,cust-stats-CT,txrate2-CT,beacon-cb-CT,wmi-block-ack-CT,wmi-bcn-rc-CT crc32 6b2b5c5b
[   17.215859] ath10k_ahb a000000.wifi: Loading BDF type 0
[   17.317999] ath10k_ahb a000000.wifi: board_file api 2 bmi_id 0:20 crc32 d140cd7d
[   18.609252] ath10k_ahb a000000.wifi: 10.4 wmi init: vdevs: 16  peers: 48  tid: 96
[   18.609325] ath10k_ahb a000000.wifi: msdu-desc: 2500  skid: 32
[   18.658430] ath10k_ahb a000000.wifi: wmi print 'P 48/48 V 16 K 144 PH 176 T 186  msdu-desc: 2500  sw-crypt: 0 ct-sta: 0'
[   18.659379] ath10k_ahb a000000.wifi: wmi print 'free: 53252 iram: 13432 sram: 35752'
[   18.799788] ath10k_ahb a000000.wifi: htt-ver 2.2 wmi-op 6 htt-op 4 cal pre-cal-nvmem max-sta 32 raw 0 hwcrypto 1
WARNING: Variable 'wlan' does not exist or is not an array/object
ls: /sys/class/net/wlan0/device/usbmisc/: No such file or directory
[   20.168108] ath10k_ahb a800000.wifi: qca4019 hw1.0 target 0x01000000 chip_id 0x003b00ff sub 0000:0000
[   20.168195] ath10k_ahb a800000.wifi: kconfig debug 0 debugfs 1 tracing 0 dfs 1 testmode 0
[   20.180078] ath10k_ahb a800000.wifi: firmware ver 10.4b-ct-4019-fW-13-5ae337bb1 api 5 features mfp,peer-flow-ctrl,txstatus-noack,wmi-10.x-CT,ratemask-CT,regdump-CT,txrate-CT,flush-all-CT,pingpong-CT,ch-regs-CT,nop-CT,set-special-CT,tx-rc-CT,cust-stats-CT,txrate2-CT,beacon-cb-CT,wmi-block-ack-CT,wmi-bcn-rc-CT crc32 6b2b5c5b
[   20.235152] ath10k_ahb a800000.wifi: Loading BDF type 0
[   20.247160] ath10k_ahb a800000.wifi: board_file api 2 bmi_id 0:21 crc32 d140cd7d
[   21.538995] ath10k_ahb a800000.wifi: 10.4 wmi init: vdevs: 16  peers: 48  tid: 96
[   21.539065] ath10k_ahb a800000.wifi: msdu-desc: 2500  skid: 32
[   21.586061] ath10k_ahb a800000.wifi: wmi print 'P 48/48 V 16 K 144 PH 176 T 186  msdu-desc: 2500  sw-crypt: 0 ct-sta: 0'
[   21.587004] ath10k_ahb a800000.wifi: wmi print 'free: 53252 iram: 13432 sram: 35752'
[   21.723578] ath10k_ahb a800000.wifi: htt-ver 2.2 wmi-op 6 htt-op 4 cal pre-cal-nvmem max-sta 32 raw 0 hwcrypto 1
[   21.846576] usbcore: registered new interface driver option
[   21.846700] usbserial: USB Serial support registered for GSM modem (1-port)
[   21.851726] option 2-1:1.2: GSM modem (1-port) converter detected
[   21.858379] usb 2-1: GSM modem (1-port) converter now attached to ttyUSB0
[   21.864590] option 2-1:1.3: GSM modem (1-port) converter detected
[   21.871304] usb 2-1: GSM modem (1-port) converter now attached to ttyUSB1
[   21.877516] option 2-1:1.4: GSM modem (1-port) converter detected
[   21.884255] usb 2-1: GSM modem (1-port) converter now attached to ttyUSB2
[   21.893032] kmodloader: done loading kernel modules from /etc/modules.d/*
WARNING: Variable 'wlan' does not exist or is not an array/object
WARNING: Variable 'wlan' does not exist or is not an array/object
WARNING: Variable 'wlan' does not exist or is not an array/object
ls: /sys/class/net/wlan1/device/usbmisc/: No such file or directory

Interestingly one of the times when I was trying to catch a full level 4 debug log it did manage to boot correctly.

Before trying to install OpenWRT, I was running Nordic_MF286D_B11

9

since it's still loading the modem related packages, I assume you didn't try the firstboot && reboot ?

10

These are included in the image for the device. It looks to me like the modem is misbehaving for some reason, though.

@Matt2uk: see here for B11 backup: http://dl.eko.one.pl/orig/zte_mf286d/NORDIC/B11/
I recommend upgrading to B12 before attempting to reinstall OpenWrt and to perform ubiformat on mtd9 before initial sysupgrade.

The modem has ability to reboot the router part, and IIRC there was a thread either on the mailing list or the forum regarding recent changes to uqmi causing the device to reboot when it executes the SIM reset command, but - IIRC - this wasn't backported to openwrt-23.05 branch yet. But if I'm not mistaken, none of that should happen if WAN connection over QMI is not defined.

11

interesting, that's usually not the case for openwrt capable LTE devices.

good to know, thnx.

12

So, yes I had tried it and there was no difference, however since then I've found that if I remove the SIM card from the router it does boot up ok. As such if I reboot with the card in, I get the boot loop, if I take it out it boots ok. What @Leo-PL mentioned about it possibly being the modem causing the reboot does now sound like it could be the issue.
I've not gone so far as to try modifying the base setup to add in the tweaks I would have as need to have it working with the SIM card. I think I'll try the Nordic versions first and see where that takes me, then hopefully come back to the OpenWRT install afterwards as that's what I'm after.

13

Yeah, that's the thing!

It seems that only some SIMs trigger that.
See what happens if you revert this change from /lib/netifd/proto/qmi.sh:

(you can just remove this part with vi) on the device.

Or try this without SIM, then reboot the router with SIM installed:

uci set system.power_btn_block.value=1
uci commit system
14

I got contacted by @andyboeh on PM citing very similar symptoms on MF287 Pro - and never on my own units of MF286D (i have three in my network).

According to @andyboeh the issue was fixed by completing the upgrade and finishing it off manually on the modem. Given that, what does AT+ZCDRUN? command return on your modem? Maybe there is an incomplete upgrade on your unit.

I recommend going back to stock for a while and finishing upgrade to B12 before returning to OpenWrt.

15

Hi, so I've tried several things and now running OpenWRT 22.03 which doesn't start the boot loop unless I put the SIM card in. I've ordered a USB modem card adapter so I can try and see if anything is odd with that.
One other thing that I've tried is completely removing the modem but that doesn't seem to make any difference, still putting the SIM in creates the boot loop.
AZ+ZCDRUN? returns OK

uci set system.power_btn_block.value=1
uci commit system

Doesn't seem to make any difference and it still gets stuck in boot loop when I reboot it with the SIM in..

Before you're message I had tried to get Nordic11 and 12 to run from the mtd files but it wasn't easy although I managed it, but quickly went to OpenWRT again as that's my end game. Are you saying that if I can get the modem up to version B12 then OpenWRT potentially work and is this because OpenWRT is somehow matched the modem firmware version?
I'll see if the USB adapter helps, but failing that it sounds like I need to go back to the Nordic though and try updating the modem.

16

There is a chance that it'll work better, but not because of firmware version, but because of complete update process.
Try running AT+ZCDRUN=E and rebooting the modem. This should take the modem out of update mode, if it was there previously.

You can update the modem to B12 from within OpenWrt too, there was a description of the procedure on eko.one.pl forum, but it's in polish and I don't have the link on hand. Try looking for it, if you can't find it then I can locate it.

17

Hi, I've sorted it. Basically iteratively brought the modem up to B12, and still didn't get stability with OpenWRT, every time I put the SIM in and booted, it looped. I'd given up and was in the process or ripping open another MF286D, got it all set up and then bottled it for the sake of one last go on the old one which then worked absolutely fine. The difference though was that this time I'd used the PSU that came with the new MF286D. So... I deduce that when I was trying to put the SIM in, it was turning on the Modem transmitter, drawing more power and tripping everything out. Crazy really as the new PSU was only 1.5A compared to the original 2A. What a waste of a week messing around.

18

Just in case anyone has any clues on the next step then any help appreciated. So, my SIM provider gives me a CGNAT IP address which is no use for remote access so I'm trying to install PureVPN to get round it. I've successfully had the PureVPN running on an OpenWRT Mikrotik Hex unit I have, but getting it working on the MF286D is a bit of a pain as I just can't get the tun0 interface to behave. I did have a major issue with the OpenWRT 23 version not bringing up the ethernet interfaces (the OpenWRT 22 version was fine), so I had to play with that via Serial until it worked so there's a chance it's caused the issue.