fabianishere / udm-kernel-tools

Tools for bootstrapping custom kernels on the UniFi Dream Machine
GNU General Public License v2.0
330 stars 23 forks source link

Custom kernel not booting #10

Closed nicx closed 3 years ago

nicx commented 3 years ago

I just tried to change to the custom kernel on my UDMPRO running firmware 1.9.3, but when booting it I get an error:

root@ubnt:/# udm-bootctl list
Version                                     Initrd
4.1.37-edge2                                yes
root@ubnt:/# udm-bootctl boot 4.1.37-edge2
Loading kernel 4.1.37-edge2...
Inserting kexec kernel modules...
Triggering system boot...
root@ubnt:/# 
root@ubnt:/# uname -a
Linux ubnt 4.1.37-v1.9.3.3438-50c9676 #1 SMP Thu Apr 1 11:37:27 UTC 2021 aarch64 GNU/Linux
root@ubnt:/# Error: non zero exit code: 129: OCI runtime error
# client_loop: send disconnect: Broken pipe

After this the UDMPRO reboots to the stock kernel.

What could I do to get it running?

fabianishere commented 3 years ago

Check your system logs to see if it failed somewhere during the reboot procedure:

# Check ramoops
cat /sys/fs/pstore/*
# Check kernel log
dmesg
nicx commented 3 years ago

after the reboot to the stock kernel I get this (but is it useful anyway? as far as I understand after the reboot the stock kernel is used and there is no problem):

# cat /sys/fs/pstore/*
[    2.165635] al_dma 0000:00:05.0: writing to VF config space
[    2.283930] ahci 0001:00:00.0: writing to VF config space
[    2.291507] al_eth 0000:00:00.0: writing to VF config space
[    2.303206] al_eth 0000:00:01.0: writing to VF config space
[    4.034512] al_eth 0000:00:02.0: writing to VF config space
[    4.046170] al_eth 0000:00:03.0: writing to VF config space
[    5.528962] al_crypto 0000:00:04.0: writing to VF config space
[    5.904911] Watchdog is about to reboot system

No errors detected
# 
# dmesg
[    0.000000] Booting Linux on physical CPU 0x0
[    0.000000] Initializing cgroup subsys cpuset
[    0.000000] Initializing cgroup subsys cpu
[    0.000000] Initializing cgroup subsys cpuacct
[    0.000000] Linux version 4.1.37-v1.9.3.3438-50c9676 (builder@link-xenial-builder) (gcc version 6.4.0 (Buildroot 0.9.0.0) ) #1 SMP Thu Apr 1 11:37:27 UTC 2021
[    0.000000] CPU: AArch64 Processor [411fd073] revision 3
[    0.000000] Detected PIPT I-cache on CPU0
[    0.000000] Ignoring memory range 0x0 - 0x4000000
[    0.000000] efi: Getting EFI parameters from FDT:
[    0.000000] efi: UEFI not found.
[    0.000000] On node 0 totalpages: 1032192
[    0.000000]   DMA zone: 12032 pages used for memmap
[    0.000000]   DMA zone: 0 pages reserved
[    0.000000]   DMA zone: 770048 pages, LIFO batch:31
[    0.000000]   Normal zone: 4096 pages used for memmap
[    0.000000]   Normal zone: 262144 pages, LIFO batch:31
[    0.000000] psci: probing for conduit method from DT.
[    0.000000] psci: PSCIv0.2 detected in firmware.
[    0.000000] psci: Using standard PSCI v0.2 function IDs
[    0.000000] PERCPU: Embedded 16 pages/cpu @ffffffc23bf5f000 s25880 r8192 d31464 u65536
[    0.000000] pcpu-alloc: s25880 r8192 d31464 u65536 alloc=16*4096
[    0.000000] pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 
[    0.000000] Built 1 zonelists in Zone order, mobility grouping on.  Total pages: 1016064
[    0.000000] Kernel command line: root=/dev/sda3 rootfstype=squashfs rootwait pci=pcie_bus_perf console=ttyS0,115200 panic=3 reboot=warm
[    0.000000] PID hash table entries: 4096 (order: 3, 32768 bytes)
[    0.000000] Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes)
[    0.000000] Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes)
[    0.000000] software IO TLB [mem 0xbbfff000-0xbffff000] (64MB) mapped at [ffffffc0b7fff000-ffffffc0bbffefff]
[    0.000000] Memory: 3979424K/4128768K available (7008K kernel code, 637K rwdata, 2564K rodata, 256K init, 340K bss, 149344K reserved, 0K cma-reserved)
[    0.000000] Virtual kernel memory layout:
                   vmalloc : 0xffffff8000000000 - 0xffffffbdbfff0000   (   246 GB)
                   vmemmap : 0xffffffbdc0000000 - 0xffffffbfc0000000   (     8 GB maximum)
                             0xffffffbdc0100000 - 0xffffffbdc9000000   (   143 MB actual)
                   fixed   : 0xffffffbffabfd000 - 0xffffffbffac00000   (    12 KB)
                   PCI I/O : 0xffffffbffae00000 - 0xffffffbffbe00000   (    16 MB)
                   modules : 0xffffffbffc000000 - 0xffffffc000000000   (    64 MB)
                   memory  : 0xffffffc000000000 - 0xffffffc23c000000   (  9152 MB)
                     .init : 0xffffffc0009db000 - 0xffffffc000a1b000   (   256 KB)
                     .text : 0xffffffc000080000 - 0xffffffc0009da904   (  9579 KB)
                     .data : 0xffffffc000a28000 - 0xffffffc000ac7400   (   637 KB)\x00fffffc000ac7400   (   637 KB)
[    0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1
[    0.000000] Hierarchical RCU implementation.
[    0.000000] NR_IRQS:64 nr_irqs:64 0
[    0.000000] CPU0: found redistributor 0 region 0:0x00000000f0280000
[    0.000000] al-msix: registering 158 msixs, starting with 161
[    0.000000] Architected cp15 timer(s) running at 58.33MHz (phys).
[    0.000000] clocksource arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x1ae82a6a7f, max_idle_ns: 881590413063 ns
[    0.000002] sched_clock: 56 bits at 58MHz, resolution 17ns, wraps every 4398046511097ns
[    0.000102] Console: colour dummy device 80x25
[    0.000110] Calibrating delay loop (skipped), value calculated using timer frequency.. 116.66 BogoMIPS (lpj=583333)
[    0.000115] pid_max: default: 32768 minimum: 301
[    0.000142] Mount-cache hash table entries: 8192 (order: 4, 65536 bytes)
[    0.000146] Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes)
[    0.000373] Initializing cgroup subsys memory
[    0.000385] Initializing cgroup subsys devices
[    0.000389] Initializing cgroup subsys freezer
[    0.000476] EFI services will not be available.
[    0.000808] CPU1: Booted secondary processor
[    0.000811] Detected PIPT I-cache on CPU1
[    0.000815] CPU1: found redistributor 1 region 0:0x00000000f02a0000
[    0.000932] CPU2: Booted secondary processor
[    0.000934] Detected PIPT I-cache on CPU2
[    0.000938] CPU2: found redistributor 2 region 0:0x00000000f02c0000
[    0.001049] CPU3: Booted secondary processor
[    0.001051] Detected PIPT I-cache on CPU3
[    0.001055] CPU3: found redistributor 3 region 0:0x00000000f02e0000
[    0.001082] Brought up 4 CPUs
[    0.001093] SMP: Total of 4 processors activated.
[    0.001095] CPU: All CPU(s) started at EL2
[    0.001261] devtmpfs: initialized
[    0.003697] DMI not present or invalid.
[    0.003777] clocksource jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns
[    0.004150] NET: Registered protocol family 16
[    0.029964] cpuidle: using governor ladder
[    0.059968] cpuidle: using governor menu
[    0.060005] vdso: 2 pages (1 code @ ffffffc000a2d000, 1 data @ ffffffc000a2c000)
[    0.060054] DMA: preallocated 256 KiB pool for atomic allocations
[    0.060086] Initializing Peripheral Bus System - PBS
[    0.062041] console [pstore-1] enabled
[    0.062098] pstore: Registered ramoops as persistent store backend
[    0.062102] ramoops: attached 0x18000@0x10000000, ecc: 16/0
[    0.090351] vgaarb: loaded
[    0.090430] SCSI subsystem initialized
[    0.090474] libata version 3.00 loaded.
[    0.090554] usbcore: registered new interface driver usbfs
[    0.090570] usbcore: registered new interface driver hub
[    0.090587] usbcore: registered new device driver usb
[    0.093647] EDAC MC: Ver: 3.0.0
[    0.093759] Advanced Linux Sound Architecture Driver Initialized.
[    0.093934] Bluetooth: Core ver 2.20
[    0.093946] NET: Registered protocol family 31
[    0.093949] Bluetooth: HCI device and connection manager initialized
[    0.093953] Bluetooth: HCI socket layer initialized
[    0.093956] Bluetooth: L2CAP socket layer initialized
[    0.093965] Bluetooth: SCO socket layer initialized
[    0.094052] cfg80211: Calling CRDA to update world regulatory domain
[    0.094137] Switched to clocksource arch_sys_counter
[    0.096507] NET: Registered protocol family 2
[    0.096689] TCP established hash table entries: 32768 (order: 6, 262144 bytes)
[    0.096764] TCP bind hash table entries: 32768 (order: 7, 524288 bytes)
[    0.096889] TCP: Hash tables configured (established 32768 bind 32768)
[    0.096917] UDP hash table entries: 2048 (order: 4, 65536 bytes)
[    0.096939] UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes)
[    0.097011] NET: Registered protocol family 1
[    0.097026] PCI: CLS 0 bytes, default 64
[    0.097403] futex hash table entries: 1024 (order: 4, 65536 bytes)
[    0.097519] HugeTLB registered 2 MB page size, pre-allocated 0 pages
[    0.100771] squashfs: version 4.0 (2009/01/31) Phillip Lougher
[    0.101775] alg: No test for stdrng (krng)
[    0.101849] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 251)
[    0.101854] io scheduler noop registered
[    0.101859] io scheduler deadline registered
[    0.101951] io scheduler cfq registered (default)
[    0.102063] pl061_gpio fd887000.gpio0: PL061 GPIO chip @0x00000000fd887000 registered
[    0.102124] pl061_gpio fd888000.gpio1: PL061 GPIO chip @0x00000000fd888000 registered
[    0.102180] pl061_gpio fd889000.gpio2: PL061 GPIO chip @0x00000000fd889000 registered
[    0.102235] pl061_gpio fd88a000.gpio3: PL061 GPIO chip @0x00000000fd88a000 registered
[    0.102298] pl061_gpio fd88b000.gpio4: PL061 GPIO chip @0x00000000fd88b000 registered
[    0.102355] pl061_gpio fd897000.gpio5: PL061 GPIO chip @0x00000000fd897000 registered
[    0.102413] al-sgpo fd8b4000.sgpo: Alpine SGPO driver probed.
[    0.102471] PCI host bridge /soc/pcie-internal ranges:
[    0.102480]   MEM 0xfe000000..0xfeffffff -> 0xfe000000
[    0.102531] al-internal-pcie fbc00000.pcie-internal: PCI host bridge to bus 0000:00
[    0.102536] pci_bus 0000:00: root bus resource [bus 00]
[    0.102540] pci_bus 0000:00: root bus resource [mem 0xfe000000-0xfeffffff]
[    0.102550] pci 0000:00:00.0: [1c36:0002] type 00 class 0x020000
[    0.102559] pci 0000:00:00.0: reg 0x10: [mem 0xfe000000-0xfe01ffff 64bit]
[    0.102565] pci 0000:00:00.0: reg 0x18: [mem 0xfe020000-0xfe020fff 64bit]
[    0.102570] pci 0000:00:00.0: reg 0x20: [mem 0xfe030000-0xfe03ffff 64bit]
[    0.102588] pci 0000:00:00.0: PME# supported from D3hot D3cold
[    0.102598] pci 0000:00:00.0: reg 0x324: [mem 0x00000000-0x0001ffff 64bit]
[    0.102602] pci 0000:00:00.0: VF(n) BAR0 space: [mem 0x00000000-0x0005ffff 64bit] (contains BAR0 for 3 VFs)
[    0.102684] pci 0000:00:01.0: [1c36:0001] type 00 class 0x020000
[    0.102692] pci 0000:00:01.0: reg 0x10: [mem 0xfe040000-0xfe05ffff 64bit]
[    0.102697] pci 0000:00:01.0: reg 0x18: [mem 0xfe060000-0xfe060fff 64bit]
[    0.102702] pci 0000:00:01.0: reg 0x20: [mem 0xfe064000-0xfe067fff 64bit]
[    0.102716] pci 0000:00:01.0: PME# supported from D3hot D3cold
[    0.102783] pci 0000:00:02.0: [1c36:0002] type 00 class 0x020000
[    0.102790] pci 0000:00:02.0: reg 0x10: [mem 0xfe080000-0xfe09ffff 64bit]
[    0.102795] pci 0000:00:02.0: reg 0x18: [mem 0xfe0a0000-0xfe0a0fff 64bit]
[    0.102800] pci 0000:00:02.0: reg 0x20: [mem 0xfe0b0000-0xfe0bffff 64bit]
[    0.102815] pci 0000:00:02.0: PME# supported from D3hot D3cold
[    0.102824] pci 0000:00:02.0: reg 0x324: [mem 0x00000000-0x0001ffff 64bit]
[    0.102827] pci 0000:00:02.0: VF(n) BAR0 space: [mem 0x00000000-0x0005ffff 64bit] (contains BAR0 for 3 VFs)
[    0.102901] pci 0000:00:03.0: [1c36:0001] type 00 class 0x020000
[    0.102908] pci 0000:00:03.0: reg 0x10: [mem 0xfe0c0000-0xfe0dffff 64bit]
[    0.102913] pci 0000:00:03.0: reg 0x18: [mem 0xfe0e0000-0xfe0e0fff 64bit]
[    0.102918] pci 0000:00:03.0: reg 0x20: [mem 0xfe0e4000-0xfe0e7fff 64bit]
[    0.102932] pci 0000:00:03.0: PME# supported from D3hot D3cold
[    0.102997] pci 0000:00:04.0: [1c36:0022] type 00 class 0x100000
[    0.103004] pci 0000:00:04.0: reg 0x10: [mem 0xfe100000-0xfe11ffff 64bit]
[    0.103010] pci 0000:00:04.0: reg 0x20: [mem 0xfe120000-0xfe12ffff 64bit]
[    0.103025] pci 0000:00:04.0: PME# supported from D3hot D3cold
[    0.103033] pci 0000:00:04.0: reg 0x324: [mem 0x00000000-0x0001ffff 64bit]
[    0.103037] pci 0000:00:04.0: VF(n) BAR0 space: [mem 0x00000000-0x0001ffff 64bit] (contains BAR0 for 1 VFs)
[    0.103108] pci 0000:00:05.0: [1c36:0022] type 00 class 0x010400
[    0.103114] pci 0000:00:05.0: reg 0x10: [mem 0xfe140000-0xfe15ffff 64bit]
[    0.103121] pci 0000:00:05.0: reg 0x20: [mem 0xfe160000-0xfe16ffff 64bit]
[    0.103135] pci 0000:00:05.0: PME# supported from D3hot D3cold
[    0.103144] pci 0000:00:05.0: reg 0x324: [mem 0x00000000-0x0001ffff 64bit]
[    0.103148] pci 0000:00:05.0: VF(n) BAR0 space: [mem 0x00000000-0x0001ffff 64bit] (contains BAR0 for 1 VFs)
[    0.103252] pci 0000:00:08.0: [1c36:0031] type 00 class 0x010601
[    0.103265] pci 0000:00:08.0: reg 0x24: [mem 0xfe170000-0xfe173fff]
[    0.103278] pci 0000:00:08.0: PME# supported from D3hot D3cold
[    0.103743] pci 0000:00:00.0: BAR 0: assigned [mem 0xfe000000-0xfe01ffff 64bit]
[    0.103750] pci 0000:00:00.0: BAR 7: assigned [mem 0xfe020000-0xfe07ffff 64bit]
[    0.103755] pci 0000:00:01.0: BAR 0: assigned [mem 0xfe080000-0xfe09ffff 64bit]
[    0.103760] pci 0000:00:02.0: BAR 0: assigned [mem 0xfe0a0000-0xfe0bffff 64bit]
[    0.103766] pci 0000:00:02.0: BAR 7: assigned [mem 0xfe0c0000-0xfe11ffff 64bit]
[    0.103771] pci 0000:00:03.0: BAR 0: assigned [mem 0xfe120000-0xfe13ffff 64bit]
[    0.103776] pci 0000:00:04.0: BAR 0: assigned [mem 0xfe140000-0xfe15ffff 64bit]
[    0.103781] pci 0000:00:04.0: BAR 7: assigned [mem 0xfe160000-0xfe17ffff 64bit]
[    0.103786] pci 0000:00:05.0: BAR 0: assigned [mem 0xfe180000-0xfe19ffff 64bit]
[    0.103791] pci 0000:00:05.0: BAR 7: assigned [mem 0xfe1a0000-0xfe1bffff 64bit]
[    0.103796] pci 0000:00:00.0: BAR 4: assigned [mem 0xfe1c0000-0xfe1cffff 64bit]
[    0.103801] pci 0000:00:02.0: BAR 4: assigned [mem 0xfe1d0000-0xfe1dffff 64bit]
[    0.103806] pci 0000:00:04.0: BAR 4: assigned [mem 0xfe1e0000-0xfe1effff 64bit]
[    0.103811] pci 0000:00:05.0: BAR 4: assigned [mem 0xfe1f0000-0xfe1fffff 64bit]
[    0.103816] pci 0000:00:01.0: BAR 4: assigned [mem 0xfe200000-0xfe203fff 64bit]
[    0.103821] pci 0000:00:03.0: BAR 4: assigned [mem 0xfe204000-0xfe207fff 64bit]
[    0.103826] pci 0000:00:08.0: BAR 5: assigned [mem 0xfe208000-0xfe20bfff]
[    0.103830] pci 0000:00:00.0: BAR 2: assigned [mem 0xfe20c000-0xfe20cfff 64bit]
[    0.103836] pci 0000:00:01.0: BAR 2: assigned [mem 0xfe20d000-0xfe20dfff 64bit]
[    0.103841] pci 0000:00:02.0: BAR 2: assigned [mem 0xfe20e000-0xfe20efff 64bit]
[    0.103846] pci 0000:00:03.0: BAR 2: assigned [mem 0xfe20f000-0xfe20ffff 64bit]
[    0.103956] al-external-pcie fd800000.pcie-external0: annapurna labs msi-parent: ffffffc23700a000
[    0.103963] al-external-pcie fd800000.pcie-external0: link up: speed Gen 1 width x1
[    0.103967] PCIe 0: setting OB iATU even after link is started
[    0.104016] al-external-pcie fd800000.pcie-external0: PCI host bridge to bus 0001:00
[    0.104019] pci_bus 0001:00: root bus resource [io  0x10000-0x1ffff]
[    0.104023] pci_bus 0001:00: root bus resource [mem 0xc0010000-0xc7ffffff]
[    0.104027] pci_bus 0001:00: root bus resource [bus 00-ff]
[    0.104037] pci 0001:00:00.0: [1c36:0031] type 01 class 0x060400
[    0.104046] pci 0001:00:00.0: reg 0x10: [mem 0x00000000-0x000fffff]
[    0.104051] pci 0001:00:00.0: reg 0x14: [mem 0x00000000-0x0000ffff]
[    0.104057] pci 0001:00:00.0: reg 0x38: [mem 0x00000000-0x0000ffff pref]
[    0.104084] pci 0001:00:00.0: supports D1
[    0.104088] pci 0001:00:00.0: PME# supported from D0 D1 D3hot
[    0.104180] pci 0001:00:00.0: bridge configuration invalid ([bus 00-00]), reconfiguring
[    0.104249] pci 0001:01:00.0: [1b21:1142] type 00 class 0x0c0330
[    0.104283] pci 0001:01:00.0: reg 0x10: [mem 0xc0000000-0xc0007fff 64bit]
[    0.104434] pci 0001:01:00.0: PME# supported from D3cold
[    0.104519] pci_bus 0001:01: busn_res: [bus 01-ff] end is updated to 01
[    0.104537] pci 0001:00:00.0: BAR 0: assigned [mem 0xc0100000-0xc01fffff]
[    0.104542] pci 0001:00:00.0: BAR 14: assigned [mem 0xc0200000-0xc02fffff]
[    0.104546] pci 0001:00:00.0: BAR 1: assigned [mem 0xc0010000-0xc001ffff]
[    0.104550] pci 0001:00:00.0: BAR 6: assigned [mem 0xc0020000-0xc002ffff pref]
[    0.104555] pci 0001:01:00.0: BAR 0: assigned [mem 0xc0200000-0xc0207fff 64bit]
[    0.104570] pci 0001:00:00.0: PCI bridge to [bus 01]
[    0.104574] pci 0001:00:00.0:   bridge window [mem 0xc0200000-0xc02fffff]
[    0.104583] pci 0001:00:00.0: Max Payload Size set to  256/ 256 (was  128), Max Read Rq  256
[    0.104608] pci 0001:01:00.0: Max Payload Size set to  256/ 512 (was  128), Max Read Rq  256
[    0.104627] al_dma: Annapurna Labs DMA Driver 0.01
[    0.104636] al_dma 0000:00:05.0: writing to VF config space
[    0.104683] al_dma 0000:00:05.0: enabling device (0000 -> 0002)
[    0.214147] pci 0000:00:05.1: [1c36:8022] type 00 class 0x010400
[    0.214222] al_dma 0000:00:05.1: enabling device (0400 -> 0402)
[    0.214234] al_ssm_dma_init_aux: non optimal adapter configuration
[    0.215492] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled
[    0.215951] console [ttyS0] disabled
[    0.215963] fd883000.uart0: ttyS0 at MMIO 0xfd883000 (irq = 25, base_baud = 31250000) is a 16550A
[    0.221716] console [ttyS0] enabled
[    0.221860] fd884000.uart1: ttyS1 at MMIO 0xfd884000 (irq = 26, base_baud = 31250000) is a 16550A
[    0.221995] fd885000.uart2: ttyS2 at MMIO 0xfd885000 (irq = 27, base_baud = 31250000) is a 16550A
[    0.222584] ahci 0000:00:08.0: version 3.0
[    0.222597] ahci 0000:00:08.0: controller can't do PMP, turning off CAP_PMP
[    0.222734] ahci 0000:00:08.0: SSS flag set, parallel bus scan disabled
[    0.222750] ahci 0000:00:08.0: AHCI 0001.0300 32 slots 4 ports 6 Gbps 0xf impl SATA mode
[    0.222755] ahci 0000:00:08.0: flags: 64bit ncq sntf stag pm led clo only fbs pio slum part ccc apst 
[    0.223468] scsi host0: ahci
[    0.223588] scsi host1: ahci
[    0.223697] scsi host2: ahci
[    0.223801] scsi host3: ahci
[    0.223873] ata1: SATA max UDMA/133 abar m16384@0xfe208000 port 0xfe208100 irq 77
[    0.223876] ata2: SATA max UDMA/133 abar m16384@0xfe208000 port 0xfe208180 irq 78
[    0.223879] ata3: SATA max UDMA/133 abar m16384@0xfe208000 port 0xfe208200 irq 79
[    0.223882] ata4: SATA max UDMA/133 abar m16384@0xfe208000 port 0xfe208280 irq 80
[    0.223894] ahci 0001:00:00.0: writing to VF config space
[    0.229347] ahci: probe of 0001:00:00.0 failed with error -22
[    0.229687] m25p80 spi0.0: found mx25u6435f, expected spi_flash_jedec_detection
[    0.229693] m25p80 spi0.0: mx25u6435f (8192 Kbytes)
[    0.229804] 5 ofpart partitions found on MTD device spi0.0
[    0.229808] Creating 5 MTD partitions on "spi0.0":
[    0.229812] 0x000000000000-0x0000001c0000 : "u-boot"
[    0.230071] 0x0000001c0000-0x0000001d0000 : "u-boot-env"
[    0.230290] 0x0000001d0000-0x0000001e0000 : "u-boot-env-2"
[    0.230525] 0x0000001e0000-0x0000001f0000 : "Factory"
[    0.230740] 0x0000001f0000-0x000000200000 : "EEPROM"
[    0.231317] tun: Universal TUN/TAP device driver, 1.6
[    0.231320] tun: (C) 1999-2004 Max Krasnyansky <maxk@qualcomm.com>
[    0.231455] al_eth_drv: Initializing Peripheral Bus System (PBS) resources
[    0.231478] al_eth 0000:00:00.0: writing to VF config space
[    0.237120] al_eth 0000:00:00.0: AnnapurnaLabs unified 1GbE/10GbE/25GbE Ethernet Driver with SR-IOV al_eth v3.5.3 (Mar 14, 2019)
[    0.237127] al_eth 0000:00:00.0: driver_data is 0x0
[    0.237153] al_eth 0000:00:00.0: eth rev_id 3 (orig_rev_id 3) dev_id 2
[    0.237224] al_eth 0000:00:00.0: Board info: phy exist No. phy addr 0. mdio freq 2500 Khz. SFP connected Yes. media 5
[    0.237228] al_eth 0000:00:00.0: al_mod_eth_function_reset: performing FLR
[    0.243166] al_eth 0000:00:00.0 eth0: AnnapurnaLabs unified 1Gbe/10Gbe/25Gbe integrated found at mem fe000000, mac addr 74:ac:b9:5e:ac:09
[    0.243173] al_eth 0000:00:01.0: writing to VF config space
[    0.248780] al_eth 0000:00:01.0: driver_data is 0x0
[    0.248800] al_eth 0000:00:01.0: eth rev_id 2 (orig_rev_id 2) dev_id 1
[    0.248867] al_eth 0000:00:01.0: Board info: phy exist Yes. phy addr 4. mdio freq 2500 Khz. SFP connected No. media 1
[    0.248871] al_eth 0000:00:01.0: al_mod_eth_function_reset: performing FLR
[    0.254608] al_eth 0000:00:01.0 eth1: AnnapurnaLabs unified 1Gbe/10Gbe/25Gbe integrated found at mem fe080000, mac addr 76:ac:b9:5e:ac:09
[    0.454141] al_eth 0000:00:01.0 eth1: MDIO read failed on timeout
[    0.574144] ata1: SATA link up 6.0 Gbps (SStatus 133 SControl 300)
[    0.574178] ata1.00: failed to IDENTIFY (I/O error, err_mask=0x100)
[    0.654140] al_eth 0000:00:01.0 eth1: MDIO read failed on timeout
[    0.854140] al_eth 0000:00:01.0 eth1: MDIO read failed on timeout
[    1.054139] al_eth 0000:00:01.0 eth1: MDIO read failed on timeout
[    1.254140] al_eth 0000:00:01.0 eth1: MDIO read failed on timeout
[    1.454139] al_eth 0000:00:01.0 eth1: MDIO read failed on timeout
[    1.654140] al_eth 0000:00:01.0 eth1: MDIO read failed on timeout
[    1.854140] al_eth 0000:00:01.0 eth1: MDIO read failed on timeout
[    1.974501] al_eth 0000:00:01.0 eth1: phy[4]: device alpine_mdio_shared_8:04, driver Atheros 8031 ethernet
[    1.974505] al_eth 0000:00:01.0 eth1: phy[4]:supported 2ef adv 2ef
[    1.974512] al_eth 0000:00:02.0: writing to VF config space
[    1.980142] al_eth 0000:00:02.0: driver_data is 0x0
[    1.980166] al_eth 0000:00:02.0: eth rev_id 3 (orig_rev_id 3) dev_id 2
[    1.980231] al_eth 0000:00:02.0: Board info: phy exist No. phy addr 0. mdio freq 2500 Khz. SFP connected Yes. media 5
[    1.980234] al_eth 0000:00:02.0: al_mod_eth_function_reset: performing FLR
[    1.986158] al_eth 0000:00:02.0 eth2: AnnapurnaLabs unified 1Gbe/10Gbe/25Gbe integrated found at mem fe0a0000, mac addr 6a:bc:9a:a2:ef:e5
[    1.986165] al_eth 0000:00:03.0: writing to VF config space
[    1.991766] al_eth 0000:00:03.0: driver_data is 0x0
[    1.991786] al_eth 0000:00:03.0: eth rev_id 2 (orig_rev_id 2) dev_id 1
[    1.991847] al_eth 0000:00:03.0: Board info: phy exist Yes. phy addr 17. mdio freq 2500 Khz. SFP connected No. media 1
[    1.991851] al_eth 0000:00:03.0: al_mod_eth_function_reset: performing FLR
[    1.997616] al_eth 0000:00:03.0 eth3: AnnapurnaLabs unified 1Gbe/10Gbe/25Gbe integrated found at mem fe120000, mac addr ea:7a:20:d4:96:de
[    2.194140] al_eth 0000:00:01.0 eth1: MDIO read failed on timeout
[    2.194145] al_eth 0000:00:03.0 eth3: al_mod_eth_mdiobus_setup: cannot get phy device - auto probing failed.
[    2.602004] Setting 0 to s 0 m 0 rx 0 tx 0
[    2.779551] Setting 1 to s 2 m 1 rx 6 tx 1
[    3.254142] cfg80211: Calling CRDA to update world regulatory domain
[    3.300224] switch0: RTL8370B switch registered on alpine_mdio_shared_8
[    3.300232] al_eth 0000:00:03.0 eth3: phy[17]: device alpine_mdio_shared_8:11, driver Realtek RTL8370MB
[    3.300236] al_eth 0000:00:03.0 eth3: phy[17]:supported 2ef adv 2ef
[    3.300415] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver
[    3.300420] ehci-pci: EHCI PCI platform driver
[    3.300444] ehci-platform: EHCI generic platform driver
[    3.300486] ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver
[    3.300504] ohci-pci: OHCI PCI platform driver
[    3.300523] uhci_hcd: USB Universal Host Controller Interface driver
[    3.300590] xhci_hcd 0001:01:00.0: xHCI Host Controller
[    3.300599] xhci_hcd 0001:01:00.0: new USB bus registered, assigned bus number 1
[    3.434681] xhci_hcd 0001:01:00.0: hcc params 0x0200e081 hci version 0x100 quirks 0x00000010
[    3.435078] hub 1-0:1.0: USB hub found
[    3.435094] hub 1-0:1.0: 2 ports detected
[    3.435223] xhci_hcd 0001:01:00.0: xHCI Host Controller
[    3.435229] xhci_hcd 0001:01:00.0: new USB bus registered, assigned bus number 2
[    3.435290] usb usb2: We don't know the algorithms for LPM for this host, disabling LPM.
[    3.435465] hub 2-0:1.0: USB hub found
[    3.435479] hub 2-0:1.0: 2 ports detected
[    3.435641] usbcore: registered new interface driver cdc_acm
[    3.435644] cdc_acm: USB Abstract Control Model driver for USB modems and ISDN adapters
[    3.435674] usbcore: registered new interface driver usb-storage
[    3.435718] usbcore: registered new interface driver usbserial
[    3.435733] usbcore: registered new interface driver cp210x
[    3.435745] usbserial: USB Serial support registered for cp210x
[    3.435848] mousedev: PS/2 mouse device common for all mice
[    3.435951] i2c /dev entries driver
[    3.439121] rtc-s35390a 1-0030: rtc core: registered rtc-s35390a as rtc0
[    3.439132] i2c i2c-0: Added multiplexed i2c bus 1
[    3.439207] i2c i2c-0: Added multiplexed i2c bus 2
[    3.439280] i2c i2c-0: Added multiplexed i2c bus 3
[    3.439389] i2c i2c-0: Added multiplexed i2c bus 4
[    3.439393] pca954x 0-0071: registered 4 multiplexed busses for I2C switch pca9546
[    3.439474] alpine_reboot_probe: setting pm_power_off (old value: ffffffc000088a60) to alpine_sys_poweroff
[    3.442638] adt7475 4-002e: ADT7475 device, revision 1
[    3.442642] adt7475 4-002e: Optional features: fan4 pwm2
[    3.452715] al_thermal_probe: Thermal Sensor Loaded at: 0xffffff8000408a00.
[    3.452845] sp805-wdt fd88c000.wdt0: registration successful
[    3.452859] Bluetooth: HCI UART driver ver 2.3
[    3.452862] Bluetooth: HCI UART protocol H4 registered
[    3.452865] Bluetooth: HCI UART protocol BCSP registered
[    3.453098] al_crypto: Annapurna Labs Crypto Driver 0.01
[    3.453109] al_crypto 0000:00:04.0: writing to VF config space
[    3.459009] al_crypto 0000:00:04.0: enabling device (0000 -> 0002)
[    3.674168] pci 0000:00:04.1: [1c36:8022] type 00 class 0x100000
[    3.674269] al_crypto 0000:00:04.1: enabling device (0400 -> 0402)
[    3.785219] al_ssm_dma_init_aux: non optimal adapter configuration
[    3.788384] al_crypto 0000:00:04.1: al_crypto_pci_probe: Skipping alg/hash initialization, no allocated channels
[    3.788714] al_crypto 0000:00:04.1: crc/csum algorithms registered in /proc/crypto
[    3.788754] al_ssm_dma_init_aux: non optimal adapter configuration
[    3.793652] alg: No test for authenc(hmac(sha384),cbc(aes)) (authenc-hmac-sha384-cbc-aes-al)
[    3.793946] al_crypto 0000:00:04.0: algorithms registered in /proc/crypto
[    3.797085] al_crypto 0000:00:04.0: hash algorithms registered in /proc/crypto
[    3.797091] al_crypto 0000:00:04.0: al_crypto_pci_probe: Skipping crc initialization, no allocated channels
[    3.797326] usbcore: registered new interface driver usbhid
[    3.797328] usbhid: USB HID core driver
[    3.797454] usbcore: registered new interface driver snd-usb-audio
[    3.797483] Netfilter messages via NETLINK v0.30.
[    3.797507] nf_conntrack version 0.5.0 (16384 buckets, 65536 max)
[    3.797641] ctnetlink v0.93: registering with nfnetlink.
[    3.797724] ip_set: protocol 6
[    3.797772] IPVS: Registered protocols ()
[    3.797830] IPVS: Connection hash table configured (size=4096, memory=64Kbytes)
[    3.797864] IPVS: Creating netns size=1304 id=0
[    3.797877] IPVS: ipvs loaded.
[    3.797932] gre: GRE over IPv4 demultiplexor driver
[    3.797937] ip_gre: GRE over IPv4 tunneling driver
[    3.798240] IPv4 over IPsec tunneling driver
[    3.798441] ip_tables: (C) 2000-2006 Netfilter Core Team
[    3.798478] Initializing XFRM netlink socket
[    3.798663] NET: Registered protocol family 10
[    3.799105] ip6_tables: (C) 2000-2006 Netfilter Core Team
[    3.799175] sit: IPv6 over IPv4 tunneling driver
[    3.799356] NET: Registered protocol family 17
[    3.799365] NET: Registered protocol family 15
[    3.799378] bridge: automatic filtering via arp/ip/ip6tables has been deprecated. Update your scripts to load br_netfilter if you need this.
[    3.799419] Bluetooth: RFCOMM socket layer initialized
[    3.799428] Bluetooth: RFCOMM ver 1.11
[    3.799434] Bluetooth: BNEP (Ethernet Emulation) ver 1.3
[    3.799438] Bluetooth: BNEP socket layer initialized
[    3.799442] Bluetooth: HIDP (Human Interface Emulation) ver 1.2
[    3.799446] Bluetooth: HIDP socket layer initialized
[    3.799451] 8021q: 802.1Q VLAN Support v1.8
[    3.799680] registered taskstats version 1
[    3.800222] Key type encrypted registered
[    3.800507] input: gpio_keys as /devices/platform/gpio_keys/input/input0
[    3.801543] rtc-s35390a 1-0030: setting system clock to 2021-05-05 07:57:57 UTC (1620201477)
[    3.801672] ALSA device list:
[    3.801674]   No soundcards found.
[    3.814154] usb 1-2: new full-speed USB device number 2 using xhci_hcd
[    4.124766] cdc_acm 1-2:1.0: ttyACM0: USB ACM device
[    4.154765] usb 2-1: new SuperSpeed USB device number 2 using xhci_hcd
[    4.184636] usb-storage 2-1:1.0: USB Mass Storage device detected
[    4.184762] scsi host4: usb-storage 2-1:1.0
[    5.185544] scsi 4:0:0:0: Direct-Access     Generic  MassStorageClass 1537 PQ: 0 ANSI: 6
[    5.335498] sd 4:0:0:0: [sda] 30777344 512-byte logical blocks: (15.8 GB/14.7 GiB)
[    5.336340] sd 4:0:0:0: [sda] Write Protect is off
[    5.336345] sd 4:0:0:0: [sda] Mode Sense: 21 00 00 00
[    5.337092] sd 4:0:0:0: [sda] Write cache: disabled, read cache: enabled, doesn't support DPO or FUA
[    5.347065]  sda: sda1 sda2 sda3 sda4 sda5 sda6
[    5.349295] sd 4:0:0:0: [sda] Attached SCSI removable disk
[    5.924144] ata1: SATA link up 6.0 Gbps (SStatus 133 SControl 300)
[    5.924175] ata1.00: failed to IDENTIFY (I/O error, err_mask=0x100)
[    5.924180] ata1: limiting SATA link speed to 3.0 Gbps
[    6.414144] cfg80211: Calling CRDA to update world regulatory domain
[    9.574141] cfg80211: Calling CRDA to update world regulatory domain
[   11.274143] ata1: SATA link up 3.0 Gbps (SStatus 123 SControl 320)
[   11.274603] ata1.00: failed to read native max address (err_mask=0x100)
[   11.274606] ata1.00: HPA support seems broken, skipping HPA handling
[   12.734142] cfg80211: Calling CRDA to update world regulatory domain
[   15.894141] cfg80211: Calling CRDA to update world regulatory domain
[   16.624143] ata1: SATA link up 3.0 Gbps (SStatus 123 SControl 320)
[   16.974146] ata2: SATA link down (SStatus 0 SControl 300)
[   17.324143] ata3: SATA link down (SStatus 0 SControl 300)
[   17.674142] ata4: SATA link down (SStatus 0 SControl 300)
[   17.677325] VFS: Mounted root (squashfs filesystem) readonly on device 8:3.
[   17.680166] devtmpfs: mounted
[   17.680280] Freeing unused kernel memory: 256K (ffffffc0009db000 - ffffffc000a1b000)
[   17.680292] Freeing alternatives memory: 36K (ffffffc000a1b000 - ffffffc000a24000)
[   17.981360] random: nonblocking pool is initialized
[   18.255376] EXT4-fs (sda6): recovery complete
[   18.255968] EXT4-fs (sda6): mounted filesystem with ordered data mode. Opts: (null)
[   18.323823] ata1: exception Emask 0x10 SAct 0x0 SErr 0x10200 action 0xe frozen
[   18.331110] ata1: irq_stat 0x00400000, PHY RDY changed
[   18.336276] ata1: SError: { Persist PHYRdyChg }
[   18.340838] ata1: hard resetting link
[   19.054147] cfg80211: Calling CRDA to update world regulatory domain
[   19.084176] ata1: SATA link up 6.0 Gbps (SStatus 133 SControl 300)
[   19.084216] ata1.00: failed to IDENTIFY (I/O error, err_mask=0x100)
[   19.450091] ubnt_common: module license 'Proprietary' taints kernel.
[   19.450097] Disabling lock debugging due to kernel taint
[   19.450703] [UBNT_STA_HT] Initialize hash table
[   19.450720] [UBNT_STA_HT] done
[   19.450723] creating /proc/ubnt_peek/
[   19.450730] creating /proc/ubnt_peek/peek_disable
[   19.450733] creating /proc/ubnt_peek/loop_check
[   19.450737] creating /proc/ubnt_peek/loop_debug
[   19.450740] creating /proc/ubnt_peek/loop_uif
[   19.450743] creating /proc/ubnt_peek/tcp_debug_mac
[   19.450746] creating /proc/ubnt_peek/tcp_debug_connections
[   19.450754] qos: registered
[   19.450756] creating /proc/ubnt_frame_id/
[   19.450759] creating /proc/ubnt_frame_id/netdevs
[   19.498575] ubnthal: Ubiquiti UniFi Dream Machine PRO
[   19.499548] creating procfs for ubnthal
[   19.499558] creating proc entry for system.info
[   19.499561] creating proc entry for board
[   19.508502] Unable to find matching dts node.
[   19.508511] creating procfs for status
[   19.508517] creating proc entry for IsDefault
[   19.508521] creating proc entry for IsLocated
[   19.508524] creating proc entry for IsIsolated
[   19.508527] creating proc entry for IsLte
[   19.508530] creating proc entry for ControllerPort
[   19.508533] creating proc entry for ControllerHost
[   19.649826] EXT4-fs (sda6): mounted filesystem with ordered data mode. Opts: errors=panic
[   19.652731] EXT4-fs (sda4): mounted filesystem with ordered data mode. Opts: errors=panic
[   19.666387] creating /proc/gpio/
[   19.666396]          /proc/gpio/ledbar_control
[   19.666400]          /proc/gpio/led_pattern
[   19.666403]          /proc/gpio/led_tempo
[   19.666407]          /proc/gpio/poe_passthrough
[   19.807987] zram: Created 1 device(s)
[   19.815053] zram0: detected capacity change from 0 to 7131652096
[   19.825562] Adding 6964500k swap on /dev/zram0.  Priority:100 extents:1 across:6964500k SS
[   19.894592] watchdog watchdog0: nowayout prevents watchdog being stopped!
[   19.894598] watchdog watchdog0: watchdog did not stop!
[   20.057843] ICMPv6: process `sysctl' is using deprecated sysctl (syscall) net.ipv6.neigh.default.base_reachable_time - use net.ipv6.neigh.default.base_reachable_time_ms instead
[   20.061399] nr_pdflush_threads exported in /proc is scheduled for removal
[   20.104363] al_eth 0000:00:00.0 eth9.tmp: renamed from eth0
[   20.146602] al_eth 0000:00:02.0 eth10.tmp: renamed from eth2
[   20.176581] al_eth 0000:00:03.0 switch0.tmp: renamed from eth3
[   20.206487] al_eth 0000:00:01.0 eth8.tmp: renamed from eth1
[   20.238983] al_eth 0000:00:02.0 eth10: renamed from eth10.tmp
[   20.276163] IPv6: ADDRCONF(NETDEV_UP): eth10: link is not ready
[   20.280665] al_eth 0000:00:01.0 eth8: renamed from eth8.tmp
[   20.315442] al_eth 0000:00:01.0: al_mod_eth_function_reset: performing FLR
[   20.320797] Configured MAC to RGMII mode
[   20.321409] al_eth 0000:00:01.0 eth8: using MSI-X per Queue interrupt mode
[   20.329872] al_eth 0000:00:00.0 eth9: renamed from eth9.tmp
[   20.366123] IPv6: ADDRCONF(NETDEV_UP): eth9: link is not ready
[   20.370602] al_eth 0000:00:03.0 switch0: renamed from switch0.tmp
[   20.415472] al_eth 0000:00:03.0: al_mod_eth_function_reset: performing FLR
[   20.420858] Configured MAC to RGMII mode
[   20.421469] al_eth 0000:00:03.0 switch0: using MSI-X per Queue interrupt mode
[   20.447148] device switch0 entered promiscuous mode
[   20.449427] device eth10 entered promiscuous mode
[   20.451929] br0: port 1(switch0) entered forwarding state
[   20.451938] br0: port 1(switch0) entered forwarding state
[   20.603827] IPv6: ADDRCONF(NETDEV_UP): eth9: link is not ready
[   20.620974] br0: port 2(eth10) entered disabled state
[   20.623818] IPv6: ADDRCONF(NETDEV_UP): eth10: link is not ready
[   20.651592] br0: port 1(switch0) entered disabled state
[   20.654086] al_eth 0000:00:03.0: al_mod_eth_function_reset: performing FLR
[   20.659472] Configured MAC to RGMII mode
[   20.660085] al_eth 0000:00:03.0 switch0: using MSI-X per Queue interrupt mode
[   20.663803] br0: port 1(switch0) entered forwarding state
[   20.663812] br0: port 1(switch0) entered forwarding state
[   20.686576] al_eth 0000:00:01.0: al_mod_eth_function_reset: performing FLR
[   20.691932] Configured MAC to RGMII mode
[   20.692543] al_eth 0000:00:01.0 eth8: using MSI-X per Queue interrupt mode
[   20.992188] eth [al_mod_eth_1]: set link speed to 1000Mbps. full duplex.
[   20.992198] al_eth 0000:00:01.0 eth8: Link is Up - 1Gbps/Full - flow control off
[   22.214144] cfg80211: Calling CRDA to update world regulatory domain
[   22.294145] eth [al_mod_eth_3]: set link speed to 1000Mbps. full duplex.
[   22.294154] al_eth 0000:00:03.0 switch0: Link is Up - 1Gbps/Full - flow control off
[   24.084165] ata1: hard resetting link
[   24.434145] ata1: SATA link up 6.0 Gbps (SStatus 133 SControl 300)
[   24.434195] ata1.00: failed to IDENTIFY (I/O error, err_mask=0x100)
[   24.434199] ata1: limiting SATA link speed to 3.0 Gbps
[   25.374165] cfg80211: Calling CRDA to update world regulatory domain
[   27.525950] PPP generic driver version 2.4.2
[   28.544148] cfg80211: Calling CRDA to update world regulatory domain
[   29.434146] ata1: hard resetting link
[   29.784147] ata1: SATA link up 3.0 Gbps (SStatus 123 SControl 320)
[   29.784729] ata1.00: ATA-9: WDC WD30EFRX-68AX9N0, 80.00A80, max UDMA/133
[   29.784734] ata1.00: 5860533168 sectors, multi 0: LBA48 NCQ (depth 31/32), AA
[   29.785337] ata1.00: configured for UDMA/133
[   29.785344] ata1: EH complete
[   29.785429] scsi 0:0:0:0: Direct-Access     ATA      WDC WD30EFRX-68A 0A80 PQ: 0 ANSI: 5
[   29.785696] sd 0:0:0:0: [sdb] 5860533168 512-byte logical blocks: (3.00 TB/2.73 TiB)
[   29.785703] sd 0:0:0:0: [sdb] 4096-byte physical blocks
[   29.785782] sd 0:0:0:0: [sdb] Write Protect is off
[   29.785787] sd 0:0:0:0: [sdb] Mode Sense: 00 3a 00 00
[   29.785815] sd 0:0:0:0: [sdb] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
[   29.856871]  sdb: sdb1
[   29.886970] sd 0:0:0:0: [sdb] Attached SCSI disk
[   31.704146] cfg80211: Calling CRDA to update world regulatory domain
[   33.284849] al_mod_eth_group_lm_link_conf_apply: Trying to apply configuration for unregistered link! link:0, serdes_grp:2
[   33.284854] al_mod_eth_group_lm_link_conf_apply: Trying to apply configuration for unregistered link! link:1, serdes_grp:2
[   33.284858] al_mod_eth_group_lm_link_conf_apply: Trying to apply configuration for unregistered link! link:3, serdes_grp:2
[   34.864146] cfg80211: Exceeded CRDA call max attempts. Not calling CRDA
[   35.684137] br0: port 1(switch0) entered forwarding state
[   37.838761] al_mod_eth_group_lm_link_conf_apply: Trying to apply configuration for unregistered link! link:0, serdes_grp:3
[   37.838766] al_mod_eth_group_lm_link_conf_apply: Trying to apply configuration for unregistered link! link:1, serdes_grp:3
[   37.838771] al_mod_eth_group_lm_link_conf_apply: Trying to apply configuration for unregistered link! link:3, serdes_grp:3
[   38.523593] al_eth 0000:00:01.0 eth8: Link is Down
[   40.557696] br0: port 1(switch0) entered disabled state
[   40.672757] device switch0.1 entered promiscuous mode
[   40.672800] br0: port 1(switch0.1) entered forwarding state
[   40.672811] br0: port 1(switch0.1) entered forwarding state
[   40.742516] ICMPv6: process `ubios-udapi-ser' is using deprecated sysctl (syscall) net.ipv6.neigh.br0.base_reachable_time - use net.ipv6.neigh.br0.base_reachable_time_ms instead
[   40.782009] Init chrdev /dev/detector with major 190
[   40.782018] tdts: tcp_conn_max = 32000
\x000a
[   40.782023] tdts: tcp_conn_timeout = 300 sec
\x000a
[   41.794310] eth [al_mod_eth_1]: set link speed to 1000Mbps. full duplex.
[   41.794321] al_eth 0000:00:01.0 eth8: Link is Up - 1Gbps/Full - flow control off
[   49.595332] cgroup: podman (1818) created nested cgroup for controller "memory" which has incomplete hierarchy support. Nested cgroups may change behavior in the future.
[   49.595338] cgroup: "memory" requires setting use_hierarchy to 1 on the root
[   50.745162] systemd-journald[28]: Received request to flush runtime journal from PID 1
[   55.684148] br0: port 1(switch0.1) entered forwarding state
[   63.061305] node[3279]: syscall 291
[   63.061312] Code: aa0503e4 aa0603e5 aa0703e6 d4000001 (b13ffc1f) 
[   63.061318] CPU: 3 PID: 3279 Comm: node Tainted: P           O    4.1.37-v1.9.3.3438-50c9676 #1
[   63.061322] Hardware name: Annapurna Labs Alpine V2 UBNT (DT)
[   63.061326] task: ffffffc0ab348ac0 ti: ffffffc0aad0c000 task.ti: ffffffc0aad0c000
[   63.061335] PC is at 0x7f84949a34
[   63.061338] LR is at 0x12e8cec
[   63.061341] pc : [<0000007f84949a34>] lr : [<00000000012e8cec>] pstate: 60000000
[   63.061343] sp : 0000007fd4e04040
[   63.061346] x29: 0000007fd4e04040 x28: 0000000000000000 
[   63.061351] x27: 000000005d640121 x26: 0000007fd4e042c0 
[   63.061355] x25: 0000007fd4e04aa8 x24: 0000007fd4e04410 
[   63.061358] x23: 0000000000000004 x22: 0000000003fd2f78 
[   63.061362] x21: 0000000003fd92b8 x20: 0000000003fd92b8 
[   63.061365] x19: 0000007fd4e04330 x18: 0000000000000000 
[   63.061369] x17: 0000007f84949a10 x16: 0000000003fbfc88 
[   63.061372] x15: 001dcd6500000000 x14: 0000000000000028 
[   63.061376] x13: 736a2e7265767265 x12: 732f7070612f6572 
[   63.061379] x11: 6f63752d65736162 x10: 2d6966696e752f65 
[   63.061383] x9 : 2f7070612f65726f x8 : 0000000000000123 
[   63.061386] x7 : 6966696e752f6572 x6 : 6966696e752f6572 
[   63.061390] x5 : 6168732f7273752f x4 : 0000007fd4e04080 
[   63.061393] x3 : 0000000000000fff x2 : 0000000000000000 
[   63.061396] x1 : 0000007fd4e04490 x0 : 00000000ffffff9c 

[   69.338063] node[3438]: syscall 291
[   69.338071] Code: aa0503e4 aa0603e5 aa0703e6 d4000001 (b13ffc1f) 
[   69.338076] CPU: 1 PID: 3438 Comm: node Tainted: P           O    4.1.37-v1.9.3.3438-50c9676 #1
[   69.338080] Hardware name: Annapurna Labs Alpine V2 UBNT (DT)
[   69.338084] task: ffffffc23206c080 ti: ffffffc0ac2b0000 task.ti: ffffffc0ac2b0000
[   69.338092] PC is at 0x7f97e9aa34
[   69.338095] LR is at 0x12c7864
[   69.338098] pc : [<0000007f97e9aa34>] lr : [<00000000012c7864>] pstate: 60000000
[   69.338101] sp : 0000007fcd6d26f0
[   69.338103] x29: 0000007fcd6d26f0 x28: 431bde82d7b634db 
[   69.338108] x27: 0000000038b40119 x26: 0000000000000000 
[   69.338111] x25: 0000000000000004 x24: 0000000000a09b38 
[   69.338115] x23: 0000007fcd6d2ac0 x22: 0000007fcd6d2b40 
[   69.338119] x21: 00000000027c8000 x20: 0000007f981dd678 
[   69.338122] x19: 0000007fcd6d29e0 x18: 0000000000000000 
[   69.338125] x17: 0000007f97e9aa10 x16: 00000000027afc78 
[   69.338129] x15: 001dcd6500000000 x14: 0000000000000026 
[   69.338132] x13: 736a2e6574617267 x12: 696d6572702f7070 
[   69.338135] x11: 612f65726f632d69 x10: 66696e752f657261 
[   69.338139] x9 : 67696d6572702f70 x8 : 0000000000000123 
[   69.338142] x7 : 6966696e752f6572 x6 : 6966696e752f6572 
[   69.338145] x5 : 6168732f7273752f x4 : 0000007fcd6d2730 
[   69.338149] x3 : 0000000000000fff x2 : 0000000000000000 
[   69.338152] x1 : 0000007fcd6d2b40 x0 : 00000000ffffff9c 

[   69.594742] node[3460]: syscall 291
[   69.594749] Code: aa0503e4 aa0603e5 aa0703e6 d4000001 (b13ffc1f) 
[   69.594755] CPU: 1 PID: 3460 Comm: node Tainted: P           O    4.1.37-v1.9.3.3438-50c9676 #1
[   69.594758] Hardware name: Annapurna Labs Alpine V2 UBNT (DT)
[   69.594761] task: ffffffc23206d600 ti: ffffffc0aaec0000 task.ti: ffffffc0aaec0000
[   69.594770] PC is at 0x7f8bb17a34
[   69.594773] LR is at 0x12c7864
[   69.594776] pc : [<0000007f8bb17a34>] lr : [<00000000012c7864>] pstate: 60000000
[   69.594778] sp : 0000007fd3eb9420
[   69.594781] x29: 0000007fd3eb9420 x28: 431bde82d7b634db 
[   69.594785] x27: 0000000041880119 x26: 0000000000000000 
[   69.594789] x25: 0000000000000004 x24: 0000000000a09b38 
[   69.594793] x23: 0000007fd3eb97f0 x22: 0000007fd3eb9870 
[   69.594797] x21: 00000000027c8000 x20: 0000007f8be5a678 
[   69.594800] x19: 0000007fd3eb9710 x18: 0000000000000000 
[   69.594803] x17: 0000007f8bb17a10 x16: 00000000027afc78 
[   69.594807] x15: 001dcd6500000000 x14: 0000000000000023 
[   69.594810] x13: 736a2e6563697672 x12: 65732f7070612f65 
[   69.594813] x11: 726f632d6966696e x10: 752f65726168732f 
[   69.594817] x9 : 6369767265732f70 x8 : 0000000000000123 
[   69.594820] x7 : 6966696e752f6572 x6 : 6966696e752f6572 
[   69.594823] x5 : 6168732f7273752f x4 : 0000007fd3eb9460 
[   69.594826] x3 : 0000000000000fff x2 : 0000000000000000 
[   69.594830] x1 : 0000007fd3eb9870 x0 : 00000000ffffff9c 

[   79.402427] node[4968]: syscall 291
[   79.402434] Code: aa0503e4 aa0603e5 aa0703e6 d4000001 (b13ffc1f) 
[   79.402440] CPU: 1 PID: 4968 Comm: node Tainted: P           O    4.1.37-v1.9.3.3438-50c9676 #1
[   79.402443] Hardware name: Annapurna Labs Alpine V2 UBNT (DT)
[   79.402447] task: ffffffc0b626ab00 ti: ffffffc07b1f4000 task.ti: ffffffc07b1f4000
[   79.402456] PC is at 0x7fb5c87a34
[   79.402459] LR is at 0x12e8cec
[   79.402462] pc : [<0000007fb5c87a34>] lr : [<00000000012e8cec>] pstate: 60000000
[   79.402464] sp : 0000007fcfdc0bd0
[   79.402467] x29: 0000007fcfdc0bd0 x28: 0000000000000002 
[   79.402472] x27: 000000005e780121 x26: 0000007fcfdc0e50 
[   79.402476] x25: 0000007fcfdc1638 x24: 0000007fcfdc0fa0 
[   79.402480] x23: 0000000000000004 x22: 0000000003fd2f78 
[   79.402486] x21: 0000000003fd92b8 x20: 0000000003fd92b8 
[   79.402490] x19: 0000007fcfdc0ec0 x18: 0000000000000000 
[   79.402493] x17: 0000007fb5c87a10 x16: 0000000003fbfc88 
[   79.402497] x15: 001dcd6500000000 x14: 0000000000000022 
[   79.402500] x13: 736a2e7265767265 x12: 732f7070612f6b6c 
[   79.402504] x11: 61742d6966696e75 x10: 2f65726168732f72 
[   79.402507] x9 : 7265767265732f70 x8 : 0000000000000123 
[   79.402510] x7 : 6966696e752f6572 x6 : 6966696e752f6572 
[   79.402514] x5 : 6168732f7273752f x4 : 0000007fcfdc0c10 
[   79.402517] x3 : 0000000000000fff x2 : 0000000000000000 
[   79.402520] x1 : 0000007fcfdc1020 x0 : 00000000ffffff9c 

[  190.897063] ata1: exception Emask 0x10 SAct 0x0 SErr 0x4010200 action 0xe frozen
[  190.897068] ata1: irq_stat 0x00400000, PHY RDY changed
[  190.897073] ata1: SError: { Persist PHYRdyChg DevExch }
[  190.897079] ata1: hard resetting link
[  191.629814] ata1: SATA link up 3.0 Gbps (SStatus 123 SControl 320)
[  191.629853] ata1.00: failed to IDENTIFY (I/O error, err_mask=0x100)
[  191.629857] ata1.00: revalidation failed (errno=-5)
[  196.636668] ata1: hard resetting link
[  196.987111] ata1: SATA link up 3.0 Gbps (SStatus 123 SControl 320)
[  196.989234] ata1.00: configured for UDMA/133
[  196.989242] ata1: EH complete
fabianishere commented 3 years ago

Hmm, that's weird. I suspect that something is failing in the initramfs stage. My first suggestion would be to try again (if you haven't done so) and see if it works on your second try.

If that does not work, we need to try to debug the initramfs script to see where it fails on your device.

nicx commented 3 years ago

I already tried it several times with no luck. could you give me support how to debug it? I would do it ;)

fabianishere commented 3 years ago

The easiest way to debug issues is to open up the UDM Pro and hook up to its serial port. This way you can see the output of the entire boot process. However, if you have never opened you UDM Pro before, I suggest against doing it, as Ubiquiti may invalidate your warranty.

The other approach is trying to tweak the udm-init script until you have some evidence where the script might have failed. My first intuition is that the script fails to find the root disk within time (the deadline is 10s). On the devices I have tested, the boot disk usually becomes available after about 5s, but I see that on your device it only happens after about 30s.

To test this theory, change in the script below 5 to 25. This will wait 50s for the root disk to appear. https://github.com/fabianishere/udm-kernel-tools/blob/81f7849aa5790b89d3d232fce0ffc33a33f7d8ec/udm-init#L35-L38 You can find this script on UniFi OS at /usr/lib/udm-kernel-tools/udm-init.

Then, regenerate the initramfs file as follows:

udm-mkinitramfs -o /boot/initramfs-4.1.37-edge2.gz 4.1.37-edge2
nicx commented 3 years ago

ok, I'll choose option 2 :)

just tried it, but cannot find any editor like vi, vim or nano on the. any idea?

fabianishere commented 3 years ago

You can install it as follows:

apt install vim
nicx commented 3 years ago

ok, I changed the file and did the regeneration of initramfs. After that I tried to boot the custom kernel, but with same result. Even the timeout feels like the same as before the changes of the file, so I think the timeout is still 5s.

the file itself is correctly changed. Is it possible to check the initramfs file if its changed correctly, too?

nicx commented 3 years ago

hm wait... after I completely unplugged the power plug and plugged it in again, the behavior has changed:

root@ubnt:/# udm-bootctl boot 4.1.37-edge2
Loading kernel 4.1.37-edge2...
Inserting kexec kernel modules...
Kernel version not supported (v4.1.37-edge2)
Make sure you have installed the latest version of udm-kernel-tools...
root@ubnt:/# 

but the kernel-tools are installed:

root@ubnt:/# apt install ./udm-kernel-tools_1.0.0_arm64.deb
Reading package lists... Done
Building dependency tree       
Reading state information... Done
Note, selecting 'udm-kernel-tools' instead of './udm-kernel-tools_1.0.0_arm64.deb'
udm-kernel-tools is already the newest version (1.0.0).
0 upgraded, 0 newly installed, 0 to remove and 33 not upgraded.
fabianishere commented 3 years ago

You should check uname -a, but it looks like it has booted into the custom kernel looking at the error message.

nicx commented 3 years ago

ah cool, you are right, it booted to the custom kernel?!

root@ubnt:/# uname -a
Linux ubnt 4.1.37-edge2 #1 SMP Sun Apr 11 20:36:10 UTC 2021 aarch64 GNU/Linux
root@ubnt:/# 

I really cannot explain that, I thought a reboot will always booting into the stock kernel?

fabianishere commented 3 years ago

Did you enable the autoboot functionality (check systemctl status udm-autoboot)? If so, that would explain this behaviour.

nicx commented 3 years ago

no I didn't. just tried it again with another reboot via SSH: the stuck kernel is back again as expected. very weird.

back to the actual problem: i don't think it's a problem at all, i just misinterpreted it: as soon as I boot the custom kernel, my SSH session is killed with the error message "". After waiting for a successful boot and a new SSH login, the custom kernel is loaded correctly.

I was probably too impatient and thought the custom kernel is activated directly without me losing the SSH connection with an error.

Maybe you could include this in the documentation, that this error message is "normal" :)

Anyway, thanks a lot for your great support @fabianishere 👍

fabianishere commented 3 years ago

Great to hear that it is working for you! Will try to integrate your suggestion in the README.