Spitz (GL-X750V2) slow boot?

I have a Spitz (GL-X750V2) that takes ~2 minutes to boot. Is this expected? Info below, biggest delay is

...
[   49.645167] kmodloader: ["gl_system": "kernel_load"]
[   99.886381] tertf: br-lan registered, add it
...
root@spitz:~# cat /etc/openwrt_release 
DISTRIB_ID='OpenWrt'
DISTRIB_RELEASE='22.03.4'
DISTRIB_REVISION='r20123-38ccc47687'
DISTRIB_TARGET='ath79/nand'
DISTRIB_ARCH='mips_24kc'
DISTRIB_DESCRIPTION='OpenWrt 22.03.4 r20123-38ccc47687'
DISTRIB_TAINTS='busybox'

root@spitz:~# uname -a
Linux spitz 5.10.176 #0 Sun Apr 9 12:27:46 2023 mips GNU/Linux

root@spitz:~# dmesg
[    0.000000] Linux version 5.10.176 (glinet@glinet) (mips-openwrt-linux-musl-gcc (OpenWrt GCC 11.2.0 r20123-38ccc47687) 11.2.0, GNU ld (GNU Binutils) 2.37) #0 Sun Apr 9 12:27:46 2023
[    0.000000] printk: bootconsole [early0] enabled
[    0.000000] CPU0 revision is: 00019374 (MIPS 24Kc)
[    0.000000] MIPS: machine is GL.iNet GL-X750
[    0.000000] SoC: Qualcomm Atheros QCA9533 ver 2 rev 0
[    0.000000] Initrd not found or empty - disabling initrd
[    0.000000] Primary instruction cache 64kB, VIPT, 4-way, linesize 32 bytes.
[    0.000000] Primary data cache 32kB, 4-way, VIPT, cache aliases, linesize 32 bytes
[    0.000000] Zone ranges:
[    0.000000]   Normal   [mem 0x0000000000000000-0x0000000007ffffff]
[    0.000000] Movable zone start for each node
[    0.000000] Early memory node ranges
[    0.000000]   node   0: [mem 0x0000000000000000-0x0000000007ffffff]
[    0.000000] Initmem setup node 0 [mem 0x0000000000000000-0x0000000007ffffff]
[    0.000000] On node 0 totalpages: 32768
[    0.000000]   Normal zone: 288 pages used for memmap
[    0.000000]   Normal zone: 0 pages reserved
[    0.000000]   Normal zone: 32768 pages, LIFO batch:7
[    0.000000] pcpu-alloc: s0 r0 d32768 u32768 alloc=1*32768
[    0.000000] pcpu-alloc: [0] 0 
[    0.000000] Built 1 zonelists, mobility grouping on.  Total pages: 32480
[    0.000000] Kernel command line: console=ttyS0,115200n8 rootfstype=squashfs,jffs2
[    0.000000] Dentry cache hash table entries: 16384 (order: 4, 65536 bytes, linear)
[    0.000000] Inode-cache hash table entries: 8192 (order: 3, 32768 bytes, linear)
[    0.000000] Writing ErrCtl register=00000000
[    0.000000] Readback ErrCtl register=00000000
[    0.000000] mem auto-init: stack:off, heap alloc:off, heap free:off
[    0.000000] Memory: 120000K/131072K available (6399K kernel code, 599K rwdata, 1340K rodata, 1188K init, 214K bss, 11072K reserved, 0K cma-reserved)
[    0.000000] SLUB: HWalign=32, Order=0-3, MinObjects=0, CPUs=1, Nodes=1
[    0.000000] NR_IRQS: 51
[    0.000000] CPU clock: 650.000 MHz
[    0.000000] clocksource: MIPS: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 5880801374 ns
[    0.000008] sched_clock: 32 bits at 325MHz, resolution 3ns, wraps every 6607641598ns
[    0.008323] Calibrating delay loop... 432.53 BogoMIPS (lpj=2162688)
[    0.074929] pid_max: default: 32768 minimum: 301
[    0.079969] Mount-cache hash table entries: 1024 (order: 0, 4096 bytes, linear)
[    0.087676] Mountpoint-cache hash table entries: 1024 (order: 0, 4096 bytes, linear)
[    0.098213] dyndbg: Ignore empty _ddebug table in a CONFIG_DYNAMIC_DEBUG_CORE build
[    0.109310] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns
[    0.119733] futex hash table entries: 256 (order: -1, 3072 bytes, linear)
[    0.127029] pinctrl core: initialized pinctrl subsystem
[    0.137468] NET: Registered protocol family 16
[    0.142989] thermal_sys: Registered thermal governor 'step_wise'
[    0.206933] clocksource: Switched to clocksource MIPS
[    0.220034] NET: Registered protocol family 2
[    0.224882] IP idents hash table entries: 2048 (order: 2, 16384 bytes, linear)
[    0.233424] tcp_listen_portaddr_hash hash table entries: 512 (order: 0, 4096 bytes, linear)
[    0.242376] TCP established hash table entries: 1024 (order: 0, 4096 bytes, linear)
[    0.250487] TCP bind hash table entries: 1024 (order: 0, 4096 bytes, linear)
[    0.257942] TCP: Hash tables configured (established 1024 bind 1024)
[    0.264806] UDP hash table entries: 256 (order: 0, 4096 bytes, linear)
[    0.271762] UDP-Lite hash table entries: 256 (order: 0, 4096 bytes, linear)
[    0.279490] NET: Registered protocol family 1
[    0.284110] PCI: CLS 0 bytes, default 32
[    0.295294] workingset: timestamp_bits=14 max_order=15 bucket_order=1
[    0.307080] squashfs: version 4.0 (2009/01/31) Phillip Lougher
[    0.313233] jffs2: version 2.2 (NAND) (SUMMARY) (LZMA) (RTIME) (CMODE_PRIORITY) (c) 2001-2006 Red Hat, Inc.
[    0.325640] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 252)
[    0.337053] pinctrl-single 1804002c.pinmux: 576 pins, size 72
[    0.343853] gpio-export gpio-export: 3 gpio(s) exported
[    0.350250] Serial: 8250/16550 driver, 16 ports, IRQ sharing enabled
[    0.359533] printk: console [ttyS0] disabled
[    0.364120] 18020000.uart: ttyS0 at MMIO 0x18020000 (irq = 9, base_baud = 1562500) is a 16550A
[    0.373290] printk: console [ttyS0] enabled
[    0.382357] printk: bootconsole [early0] disabled
[    0.412387] spi-nor spi0.0: w25q128 (16384 Kbytes)
[    0.417483] 4 fixed-partitions partitions found on MTD device spi0.0
[    0.424103] OF: Bad cell count for /ahb/spi@1f000000/flash@0/partitions
[    0.431001] OF: Bad cell count for /ahb/spi@1f000000/flash@0/partitions
[    0.438202] OF: Bad cell count for /ahb/spi@1f000000/flash@0/partitions
[    0.445058] OF: Bad cell count for /ahb/spi@1f000000/flash@0/partitions
[    0.452167] Creating 4 MTD partitions on "spi0.0":
[    0.457166] 0x000000000000-0x000000040000 : "u-boot"
[    0.467790] 0x000000040000-0x000000050000 : "u-boot-env"
[    0.474409] 0x000000050000-0x000000060000 : "art"
[    0.482284] 0x000000060000-0x000001000000 : "firmware"
[    0.488984] 2 uimage-fw partitions found on MTD device firmware
[    0.495121] Creating 2 MTD partitions on "firmware":
[    0.500329] 0x000000000000-0x00000027a123 : "kernel"
[    0.505456] mtd: partition "kernel" doesn't end on an erase/write block -- force read-only
[    0.516760] 0x00000027a123-0x000000fa0000 : "rootfs"
[    0.521959] mtd: partition "rootfs" doesn't start on an erase/write block boundary -- force read-only
[    0.532592] mtd: setting mtd5 (rootfs) as root device
[    0.538866] 1 squashfs-split partitions found on MTD device rootfs
[    0.545270] 0x000000e40000-0x000000fa0000 : "rootfs_data"
[    0.557319] ag71xx 19000000.eth: invalid MAC address, using random address
[    0.898039] ag71xx 19000000.eth: Could not connect to PHY device. Deferring probe.
[    0.906211] ag71xx 1a000000.eth: invalid MAC address, using random address
[    1.877406] mdio.0:1f: Found an AR934X built-in switch
[    1.945094] ag71xx 1a000000.eth: connected to PHY at fixed-0:00 [uid=00000000, driver=Generic PHY]
[    1.955094] eth1: Atheros AG71xx at 0xba000000, irq 5, mode: gmii
[    1.963973] NET: Registered protocol family 10
[    1.977630] Segment Routing with IPv6
[    1.981553] NET: Registered protocol family 17
[    1.986225] bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this.
[    1.999675] 8021q: 802.1Q VLAN Support v1.8
[    2.009469] PCI host bridge /ahb/pcie-controller@180c0000 ranges:
[    2.015812]  MEM 0x0000000010000000..0x0000000013ffffff
[    2.021281]   IO 0x0000000000000000..0x0000000000000000
[    2.026885] PCI host bridge to bus 0000:00
[    2.031172] pci_bus 0000:00: root bus resource [mem 0x10000000-0x13ffffff]
[    2.038285] pci_bus 0000:00: root bus resource [io  0x0000]
[    2.044044] pci_bus 0000:00: root bus resource [??? 0x00000000 flags 0x0]
[    2.051071] pci_bus 0000:00: No busn resource found for root bus, will use [bus 00-ff]
[    2.059316] pci 0000:00:00.0: [168c:0050] type 00 class 0x028000
[    2.065555] pci 0000:00:00.0: reg 0x10: [mem 0x00000000-0x001fffff 64bit]
[    2.072626] pci 0000:00:00.0: reg 0x30: [mem 0x00000000-0x0000ffff pref]
[    2.079639] pci 0000:00:00.0: supports D1
[    2.083780] pci 0000:00:00.0: PME# supported from D0 D1 D3hot
[    2.090827] pci_bus 0000:00: busn_res: [bus 00-ff] end is updated to 00
[    2.097723] pci 0000:00:00.0: BAR 0: assigned [mem 0x10000000-0x101fffff 64bit]
[    2.105296] pci 0000:00:00.0: BAR 6: assigned [mem 0x10200000-0x1020ffff pref]
[    2.113552] ag71xx 19000000.eth: invalid MAC address, using random address
[    2.458998] ag71xx 19000000.eth: connected to PHY at mdio.0:1f:04 [uid=004dd042, driver=Generic PHY]
[    2.469434] eth0: Atheros AG71xx at 0xb9000000, irq 4, mode: mii
[    2.488716] VFS: Mounted root (squashfs filesystem) readonly on device 31:5.
[    2.503330] Freeing unused kernel memory: 1188K
[    2.508044] This architecture does not have kernel memory protection.
[    2.514701] Run /sbin/init as init process
[    2.518935]   with arguments:
[    2.518940]     /sbin/init
[    2.518944]   with environment:
[    2.518949]     HOME=/
[    2.518953]     TERM=linux
[    3.337576] init: Console is alive
[    3.341636] init: - watchdog -
[    6.086035] kmodloader: loading kernel modules from /etc/modules-boot.d/*
[    6.210557] install gl_hw_info
[    6.242794] usbcore: registered new interface driver usbfs
[    6.248603] usbcore: registered new interface driver hub
[    6.254197] usbcore: registered new device driver usb
[    6.304293] gl-tertf: (C) 2021 jianhui zhao <jianhui.zhao@gl-inet.com>
[    6.320140] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver
[    6.336287] SCSI subsystem initialized
[    6.347921] fsl-ehci: Freescale EHCI Host controller driver
[    6.357273] ehci-platform: EHCI generic platform driver
[    6.363088] ehci-platform 1b000000.usb: EHCI Host Controller
[    6.369040] ehci-platform 1b000000.usb: new USB bus registered, assigned bus number 1
[    6.377289] ehci-platform 1b000000.usb: irq 3, io mem 0x1b000000
[    6.406992] ehci-platform 1b000000.usb: USB 2.0 started, EHCI 1.00
[    6.414444] hub 1-0:1.0: USB hub found
[    6.419292] hub 1-0:1.0: 1 port detected
[    6.433903] usbcore: registered new interface driver usb-storage
[    6.441776] kmodloader: done loading kernel modules from /etc/modules-boot.d/*
[    6.449807] kmodloader: ["gl_system": "kernel_load"]
[    6.465529] init: - preinit -
[    6.747225] usb 1-1: new high-speed USB device number 2 using ehci-platform
[    7.087290] hub 1-1:1.0: USB hub found
[    7.149755] hub 1-1:1.0: 4 ports detected
[    7.284106] random: procd: uninitialized urandom read (4 bytes read)
[    7.497239] usb 1-1.1: new high-speed USB device number 3 using ehci-platform
[    7.817956] usb-storage 1-1.1:1.0: USB Mass Storage device detected
[    7.831354] scsi host0: usb-storage 1-1.1:1.0
[    7.937031] usb 1-1.2: new high-speed USB device number 4 using ehci-platform
[    8.300536] random: jshn: uninitialized urandom read (4 bytes read)
[    8.877553] random: jshn: uninitialized urandom read (4 bytes read)
[    8.898823] scsi 0:0:0:0: Direct-Access     Generic  STORAGE DEVICE   0233 PQ: 0 ANSI: 0
[    8.930625] random: jshn: uninitialized urandom read (4 bytes read)
[    9.305787] sd 0:0:0:0: [sda] 3805184 512-byte logical blocks: (1.95 GB/1.81 GiB)
[    9.327087] sd 0:0:0:0: [sda] Write Protect is off
[    9.332062] sd 0:0:0:0: [sda] Mode Sense: 0b 00 00 08
[    9.333476] sd 0:0:0:0: [sda] No Caching mode page found
[    9.339045] sd 0:0:0:0: [sda] Assuming drive cache: write through
[    9.363349]  sda: sda1
[    9.380117] sd 0:0:0:0: [sda] Attached SCSI removable disk
[    9.411126] random: jshn: uninitialized urandom read (4 bytes read)
[    9.475347] random: jshn: uninitialized urandom read (4 bytes read)
[    9.546227] random: jshn: uninitialized urandom read (4 bytes read)
[   10.309182] eth1: link up (1000Mbps/Full duplex)
[   10.313996] IPv6: ADDRCONF(NETDEV_CHANGE): eth1: link becomes ready
[   14.685736] mount_root: loading kmods from internal overlay
[   14.773907] kmodloader: loading kernel modules from //etc/modules-boot.d/*
[   14.795017] kmodloader: done loading kernel modules from //etc/modules-boot.d/*
[   14.803066] kmodloader: ["gl_system": "kernel_load"]
[   15.787864] jffs2: notice: (513) jffs2_build_xattr_subsystem: complete building xattr subsystem, 44 of xdatum (38 unchecked, 6 orphan) and 66 of xref (7 dead, 1 orphan) found.
[   15.804883] block: attempting to load /tmp/jffs_cfg/upper/etc/config/fstab
[   15.822689] block: extroot: not configured
[   15.918142] jffs2: notice: (511) jffs2_build_xattr_subsystem: complete building xattr subsystem, 44 of xdatum (38 unchecked, 6 orphan) and 66 of xref (7 dead, 1 orphan) found.
[   15.936008] mount_root: loading kmods from internal overlay
[   16.014614] kmodloader: loading kernel modules from /tmp/overlay/upper/etc/modules-boot.d/*
[   16.027181] kmodloader: done loading kernel modules from /tmp/overlay/upper/etc/modules-boot.d/*
[   16.036680] kmodloader: ["gl_system": "kernel_load"]
[   16.748946] block: attempting to load /tmp/jffs_cfg/upper/etc/config/fstab
[   16.763250] block: extroot: not configured
[   16.769113] mount_root: switching to jffs2 overlay
[   16.789911] overlayfs: upper fs does not support tmpfile.
[   16.812019] urandom-seed: Seeding with /etc/urandom.seed
[   17.271907] eth1: link down
[   17.290210] procd: - early -
[   17.293613] procd: - watchdog -
[   18.242661] random: jshn: uninitialized urandom read (4 bytes read)
[   18.297289] procd: - watchdog -
[   18.402027] procd: - ubus -
[   18.797597] random: ubusd: uninitialized urandom read (4 bytes read)
[   18.808068] random: ubusd: uninitialized urandom read (4 bytes read)
[   18.834335] procd: - init -
[   23.844777] urngd: v1.0.2 started.
[   24.697697] kmodloader: loading kernel modules from /etc/modules.d/*
[   25.655159] random: crng init done
[   25.658749] random: 58 urandom warning(s) missed due to ratelimiting
[   27.291073] ntfs: driver 2.1.32 [Flags: R/O MODULE].
[   27.358957] tun: Universal TUN/TAP device driver, 1.6
[   27.698272] GACT probability on
[   27.709245] Mirror/redirect action on
[   27.745450] u32 classifier
[   27.748344]     input device check on
[   27.752120]     Actions configured
[   27.849128] Bridge firewalling registered
[   27.870164] usbcore: registered new interface driver cdc_acm
[   27.876025] cdc_acm: USB Abstract Control Model driver for USB modems and ISDN adapters
[   27.905350] usbcore: registered new interface driver cdc_wdm
[   27.928266] Loading modules backported from Linux version v5.15.92-0-ge515b9902f5f
[   27.936101] Backport generated by backports.git v5.15.92-1-0-gdfe0f60c
[   27.969169] usbcore: registered new interface driver ipheth
[   28.173984] usbcore: registered new interface driver usbserial_generic
[   28.180921] usbserial: USB Serial support registered for generic
[   28.223819] wireguard: WireGuard 1.0.20220627 loaded. See www.wireguard.com for information.
[   28.232622] wireguard: Copyright (C) 2015-2019 Jason A. Donenfeld <Jason@zx2c4.com>. All Rights Reserved.
[   28.392887] xt_time: kernel timezone is -0000
[   28.410820] usbcore: registered new interface driver cdc_ether
[   28.432099] usbcore: registered new interface driver cdc_ncm
[   28.533754] usbcore: registered new interface driver huawei_cdc_ncm
[   29.282509] PPP generic driver version 2.4.2
[   29.308726] NET: Registered protocol family 24
[   29.327292] qmi_wwan 1-1.2:1.4: cdc-wdm0: USB WDM device
[   29.335608] qmi_wwan 1-1.2:1.4: QuectelEC25&EC21&EG91&EG95&EG06&EP06&EM06&BG96&AG35 qmi wwan detected
[   29.346202] qmi_wwan 1-1.2:1.4 wwan0: register 'qmi_wwan' at usb-1b000000.usb-1.2, WWAN/QMI device, 56:a8:38:0a:4d:e6
[   29.357488] usbcore: registered new interface driver qmi_wwan
[   29.396826] usbcore: registered new interface driver rndis_host
[   29.517938] ath10k_pci 0000:00:00.0: enabling device (0000 -> 0002)
[   29.526630] ath10k_pci 0000:00:00.0: pci irq legacy oper_irq_mode 1 irq_mode 0 reset_mode 0
[   44.183175] ath10k_pci 0000:00:00.0: qca9887 hw1.0 target 0x4100016d chip_id 0x004000ff sub 0000:0000
[   44.192798] ath10k_pci 0000:00:00.0: kconfig debug 0 debugfs 1 tracing 0 dfs 1 testmode 0
[   44.205769] ath10k_pci 0000:00:00.0: firmware ver 10.2.4-1.0-00047 api 5 features no-p2p,ignore-otp,skip-clock-init,mfp,allows-mesh-bcast crc32 62f7565f
[   44.973020] ath10k_pci 0000:00:00.0: board_file api 1 bmi_id N/A crc32 546cca0d
[   44.980996] ath10k_pci 0000:00:00.0: failed to read device EEPROM at 0000
[   44.988041] ath10k_pci 0000:00:00.0: failed to read calibration data from EEPROM: -22
[   46.157434] ath10k_pci 0000:00:00.0: htt-ver 2.1 wmi-op 5 htt-op 2 cal file max-sta 128 raw 0 hwcrypto 1
[   49.235972] ath10k_pci 0000:00:00.0: failed to receive initialized event from target: 00000000
[   49.288733] ath: EEPROM regdomain: 0x0
[   49.288747] ath: EEPROM indicates default country code should be used
[   49.288752] ath: doing EEPROM country->regdmn map search
[   49.288765] ath: country maps to regdmn code: 0x3a
[   49.288772] ath: Country alpha2 being used: US
[   49.288777] ath: Regpair used: 0x3a
[   49.374621] usbcore: registered new interface driver option
[   49.380562] usbserial: USB Serial support registered for GSM modem (1-port)
[   49.388226] option 1-1.2:1.0: GSM modem (1-port) converter detected
[   49.394971] usb 1-1.2: GSM modem (1-port) converter now attached to ttyUSB0
[   49.402446] option 1-1.2:1.1: GSM modem (1-port) converter detected
[   49.409276] usb 1-1.2: GSM modem (1-port) converter now attached to ttyUSB1
[   49.416652] option 1-1.2:1.2: GSM modem (1-port) converter detected
[   49.423512] usb 1-1.2: GSM modem (1-port) converter now attached to ttyUSB2
[   49.430969] option 1-1.2:1.3: GSM modem (1-port) converter detected
[   49.437814] usb 1-1.2: GSM modem (1-port) converter now attached to ttyUSB3
[   49.583736] ath: EEPROM regdomain: 0x0
[   49.583752] ath: EEPROM indicates default country code should be used
[   49.583757] ath: doing EEPROM country->regdmn map search
[   49.583773] ath: country maps to regdmn code: 0x3a
[   49.583780] ath: Country alpha2 being used: US
[   49.583785] ath: Regpair used: 0x3a
[   49.596373] ieee80211 phy1: Selected rate control algorithm 'minstrel_ht'
[   49.598821] ieee80211 phy1: Atheros AR9531 Rev:2 mem=0xb8100000, irq=13
[   49.637769] kmodloader: done loading kernel modules from /etc/modules.d/*
[   49.645167] kmodloader: ["gl_system": "kernel_load"]
[   99.886381] tertf: br-lan registered, add it
[   99.927995] eth1: link up (1000Mbps/Full duplex)
[   99.936261] br-lan: port 1(eth1) entered blocking state
[   99.941824] br-lan: port 1(eth1) entered disabled state
[   99.947628] device eth1 entered promiscuous mode
[  100.014300] br-lan: port 1(eth1) entered blocking state
[  100.019819] br-lan: port 1(eth1) entered forwarding state
[  100.897052] IPv6: ADDRCONF(NETDEV_CHANGE): br-lan: link becomes ready
[  110.088194] ath10k_pci 0000:00:00.0: failed to read device EEPROM at 0000
[  110.095229] ath10k_pci 0000:00:00.0: failed to read calibration data from EEPROM: -22
[  119.793066] ath10k_pci 0000:00:00.0: pdev param 0 not supported by firmware
[  120.104667] br-lan: port 2(wlan1) entered blocking state
[  120.110322] br-lan: port 2(wlan1) entered disabled state
[  120.116247] device wlan1 entered promiscuous mode
[  124.950985] IPv6: ADDRCONF(NETDEV_CHANGE): wlan1: link becomes ready
[  124.957880] br-lan: port 2(wlan1) entered blocking state
[  124.963399] br-lan: port 2(wlan1) entered forwarding state

I would say 2 minutes are totally fine. Same on my Flint2 and XE3000

1 Like

Yeah. About 2 minutes on the mark is where my “older” GL-X750 w/EP06-A modem takes.

[  123.286984] IPv6: ADDRCONF(NETDEV_CHANGE): wlan1: link becomes ready
[  123.293942] br-lan: port 2(wlan1) entered blocking state
[  123.299457] br-lan: port 2(wlan1) entered forwarding state
1 Like

Sounds right for the Spitz X750 and x750v2. They are slow to boot but work great.

They are one of my favorite routers. I have a couple of them. I leave one in Germany with the EP06-E modem. I’ve got two in the US here: one with a SIerra Wireless MC7455 modem (router is flashed to Rooter firmware) and the other with a Quectel EG25-G modem.

Eric

2 Likes

OK thanks for the feedback. I do wonder about that 50 second hole in dmesg…