lava-dispatcher, installed at version: 2025.02
start: 0 validate
Start time: 2025-04-20 20:52:42.874816+00:00 (UTC)
Validating that http://boot.montjoie.local/rootfs/arm64/rootfs.cpio.gz exists
Validating that http://boot.montjoie.local/storage/linux-test/arm64/sunxi/defconfig/next-20250415/Image exists
Validating that http://boot.montjoie.local/storage//linux-test/arm64/sunxi/defconfig/next-20250415/dts/allwinner/sun55i-a527-radxa-a5e.dtb exists
Validating that http://boot.montjoie.local/storage/linux-test/arm64/sunxi/defconfig/next-20250415/modules.tar.gz exists
validate duration: 0.02
definitioncaseresult
start: 1 tftp-deploy (timeout 00:20:00) [common]
start: 1.1 download-retry (timeout 00:20:00) [common]
start: 1.1.1 http-download (timeout 00:06:40) [common]
Not decompressing ramdisk as can be used compressed.
downloading http://boot.montjoie.local/rootfs/arm64/rootfs.cpio.gz
saving as /var/lib/lava/dispatcher/tmp/475922/tftp-deploy-_jvvb0yh/ramdisk/rootfs.cpio.gz
total size: 19775564 (18 MB)
progress 55 % (10 MB)
18 MB downloaded in 0.18 s (107.03 MB/s)
end: 1.1.1 http-download (duration 00:00:00) [common]
definitionnamespacecaseleveldurationresultextra
end: 1.1 download-retry (duration 00:00:00) [common]
start: 1.2 download-retry (timeout 00:20:00) [common]
start: 1.2.1 http-download (timeout 00:06:40) [common]
downloading http://boot.montjoie.local/storage/linux-test/arm64/sunxi/defconfig/next-20250415/Image
saving as /var/lib/lava/dispatcher/tmp/475922/tftp-deploy-_jvvb0yh/kernel/Image
total size: 15284232 (14 MB)
No compression specified
progress 71 % (10 MB)
14 MB downloaded in 0.14 s (105.60 MB/s)
end: 1.2.1 http-download (duration 00:00:00) [common]
definitionnamespacecaseleveldurationresultextra
end: 1.2 download-retry (duration 00:00:00) [common]
start: 1.3 download-retry (timeout 00:20:00) [common]
start: 1.3.1 http-download (timeout 00:06:40) [common]
downloading http://boot.montjoie.local/storage//linux-test/arm64/sunxi/defconfig/next-20250415/dts/allwinner/sun55i-a527-radxa-a5e.dtb
saving as /var/lib/lava/dispatcher/tmp/475922/tftp-deploy-_jvvb0yh/dtb/sun55i-a527-radxa-a5e.dtb
total size: 13764 (0 MB)
No compression specified
0 MB downloaded in 0.00 s (3.23 MB/s)
end: 1.3.1 http-download (duration 00:00:00) [common]
definitionnamespacecaseleveldurationresultextra
end: 1.3 download-retry (duration 00:00:00) [common]
start: 1.4 download-retry (timeout 00:20:00) [common]
start: 1.4.1 http-download (timeout 00:06:40) [common]
downloading http://boot.montjoie.local/storage/linux-test/arm64/sunxi/defconfig/next-20250415/modules.tar.gz
saving as /var/lib/lava/dispatcher/tmp/475922/tftp-deploy-_jvvb0yh/modules/modules.tar
total size: 3179494 (3 MB)
Using gunzip to decompress gz
progress 81 % (2 MB)
3 MB downloaded in 0.13 s (23.20 MB/s)
end: 1.4.1 http-download (duration 00:00:00) [common]
definitionnamespacecaseleveldurationresultextra
end: 1.4 download-retry (duration 00:00:00) [common]
start: 1.5 prepare-tftp-overlay (timeout 00:20:00) [common]
start: 1.5.1 extract-nfsrootfs (timeout 00:20:00) [common]
end: 1.5.1 extract-nfsrootfs (duration 00:00:00) [common]
start: 1.5.2 parse-persistent-nfs (timeout 00:20:00) [common]
end: 1.5.2 parse-persistent-nfs (duration 00:00:00) [common]
start: 1.5.3 extract-overlay-ramdisk (timeout 00:20:00) [common]
end: 1.5.3 extract-overlay-ramdisk (duration 00:00:01) [common]
start: 1.5.4 extract-modules (timeout 00:19:59) [common]
extracting modules file /var/lib/lava/dispatcher/tmp/475922/tftp-deploy-_jvvb0yh/modules/modules.tar to /var/lib/lava/dispatcher/tmp/475922/extract-overlay-ramdisk-xlbf7vpy/ramdisk
end: 1.5.4 extract-modules (duration 00:00:00) [common]
start: 1.5.5 prepare-kernel (timeout 00:19:59) [common]
start: 1.5.5.1 uboot-prepare-kernel (timeout 00:19:59) [common]
end: 1.5.5.1 uboot-prepare-kernel (duration 00:00:00) [common]
end: 1.5.5 prepare-kernel (duration 00:00:00) [common]
start: 1.5.6 configure-preseed-file (timeout 00:19:59) [common]
end: 1.5.6 configure-preseed-file (duration 00:00:00) [common]
start: 1.5.7 compress-ramdisk (timeout 00:19:59) [common]
Building ramdisk /var/lib/lava/dispatcher/tmp/475922/extract-overlay-ramdisk-xlbf7vpy/ramdisk.cpio containing /var/lib/lava/dispatcher/tmp/475922/extract-overlay-ramdisk-xlbf7vpy/ramdisk
>> 117452 blocks
Adding RAMdisk u-boot header.
mkimage -A arm64 -T ramdisk -C none -d /var/lib/lava/dispatcher/tmp/475922/extract-overlay-ramdisk-xlbf7vpy/ramdisk.cpio.gz /var/lib/lava/dispatcher/tmp/475922/extract-overlay-ramdisk-xlbf7vpy/ramdisk.cpio.gz.uboot
output: Image Name:
output: Created: Sun Apr 20 22:52:49 2025
output: Image Type: AArch64 Linux RAMDisk Image (uncompressed)
output: Data Size: 23098173 Bytes = 22556.81 KiB = 22.03 MiB
output: Load Address: 00000000
output: Entry Point: 00000000
output:
rename /var/lib/lava/dispatcher/tmp/475922/extract-overlay-ramdisk-xlbf7vpy/ramdisk.cpio.gz.uboot to /var/lib/lava/dispatcher/tmp/475922/tftp-deploy-_jvvb0yh/ramdisk/ramdisk.cpio.gz.uboot
end: 1.5.7 compress-ramdisk (duration 00:00:06) [common]
end: 1.5 prepare-tftp-overlay (duration 00:00:07) [common]
start: 1.6 lxc-create-udev-rule-action (timeout 00:19:52) [common]
No LXC device requested
end: 1.6 lxc-create-udev-rule-action (duration 00:00:00) [common]
Checking files for TFTP limit of 4294967296 bytes.
end: 1 tftp-deploy (duration 00:00:08) [common]
start: 2 uboot-action (timeout 00:05:00) [common]
start: 2.1 uboot-from-media (timeout 00:05:00) [common]
end: 2.1 uboot-from-media (duration 00:00:00) [common]
start: 2.2 bootloader-overlay (timeout 00:05:00) [common]
substitutions:
- {SERVER_IP}: 192.168.1.40
- {PRESEED_CONFIG}: None
- {PRESEED_LOCAL}: None
- {DTB}: 475922/tftp-deploy-_jvvb0yh/dtb/sun55i-a527-radxa-a5e.dtb
- {RAMDISK}: 475922/tftp-deploy-_jvvb0yh/ramdisk/ramdisk.cpio.gz.uboot
- {INITRD}: 475922/tftp-deploy-_jvvb0yh/ramdisk/ramdisk.cpio.gz.uboot
- {KERNEL}: 475922/tftp-deploy-_jvvb0yh/kernel/Image
- {LAVA_MAC}: 8a:c9:56:36:00:84
- {TEE}: None
- {DTB_BASE_ADDR}: None
- {DTB_BASE_RESIZE}: None
- {APPLY_DTBO_COMMANDS}: None
- {BOOTX}: booti 0x40000000 0x44300000 0x44000000
- {KERNEL_ADDR}: 0x40000000
- {DTB_ADDR}: 0x44000000
- {RAMDISK_ADDR}: 0x44300000
- {TEE_ADDR}: 0x83000000
- {ROOT}: None
- {ROOT_PART}: None
Parsed boot commands:
- setenv autoload no
- setenv initrd_high 0xffffffff
- setenv fdt_high 0xffffffff
- setenv ethaddr 8a:c9:56:36:00:84
- dhcp
- setenv serverip 192.168.1.40
- tftp 0x40000000 475922/tftp-deploy-_jvvb0yh/kernel/Image
- tftp 0x44300000 475922/tftp-deploy-_jvvb0yh/ramdisk/ramdisk.cpio.gz.uboot
- setenv initrd_size ${filesize}
- tftp 0x44000000 475922/tftp-deploy-_jvvb0yh/dtb/sun55i-a527-radxa-a5e.dtb
- setenv bootargs 'console=ttyS0,115200n8 root=/dev/ram0 ip=dhcp ip=dhcp'
- booti 0x40000000 0x44300000 0x44000000
end: 2.2 bootloader-overlay (duration 00:00:00) [common]
definitionnamespacecaseleveldurationresultextra
start: 2.3 connect-device (timeout 00:05:00) [common]
[common] connect-device Connecting to device using 'telnet 127.0.0.1 60019'
Setting prompt string to ['lava-test: # ']
end: 2.3 connect-device (duration 00:00:00) [common]
start: 2.4 uboot-commands (timeout 00:05:00) [common]
start: 2.4.1 reset-device (timeout 00:05:00) [common]
start: 2.4.1.1 pdu-reboot (timeout 00:05:00) [common]
Calling: ['/usr/bin/power-sispmctl.sh', 'reset', '3', '01:ff:ff:ff:ff']
>> Accessing Gembird #1 USB device 005
>> Switched outlet 3 off
>> Accessing Gembird #1 USB device 005
>> Switched outlet 3 on
Returned 0 in 3 seconds
end: 2.4.1.1 pdu-reboot (duration 00:00:03) [common]
definitionnamespacecaseleveldurationresultextra
end: 2.4.1 reset-device (duration 00:00:03) [common]
start: 2.4.2 bootloader-interrupt (timeout 00:04:57) [common]
Setting prompt string to ['Hit any key to stop autoboot']
bootloader-interrupt: Wait for prompt ['Hit any key to stop autoboot'] (timeout 00:05:00)
Trying 127.0.0.1...
Connected to 127.0.0.1.
Escape character is '^]'.

U-Boot SPL 2025.04-clabbe-g5e36f753e2e5-dirty (Apr 20 2025 - 22:44:27 +0200)
DRAM: 4096 MiB
Trying to boot from MMC1
NOTICE: BL31: v2.12.0(release):b5de74a68
NOTICE: BL31: Built : 22:44:18, Apr 20 2025
NOTICE: BL31: Detected Allwinner A523 SoC (1890)
NOTICE: BL31: Found U-Boot DTB at 0xa0a7600, model: Radxa A5E


U-Boot 2025.04-clabbe-g5e36f753e2e5-dirty (Apr 20 2025 - 22:44:27 +0200) Allwinner Technology

CPU: Allwinner A523 (SUN55I)
Model: Radxa A5E
DRAM: 4 GiB
sunxi_set_gate: (CLK#35) unhandled
Core: 80 devices, 22 uclasses, devicetree: separate
WDT: Not starting watchdog@2050000
MMC: mmc@4020000: 0, mmc@4022000: 1
Loading Environment from FAT... Unable to read "uboot.env" from mmc0:1...
In: serial@2500000
Out: serial@2500000
Err: serial@2500000
Net:
Warning: ethernet@4500000 (eth0) using random MAC address - 5a:c7:8a:ed:4c:08
eth0: ethernet@4500000
starting USB...
Bus usb@4101000: USB EHCI 1.00
Bus usb@4101400: USB OHCI 1.0
Bus usb@4200000: USB EHCI 1.00
Bus usb@4200400: USB OHCI 1.0
scanning bus usb@4101000 for devices... 1 USB Device(s) found
scanning bus usb@4101400 for devices... 1 USB Device(s) found
scanning bus usb@4200000 for devices... 1 USB Device(s) found
scanning bus usb@4200400 for devices... 1 USB Device(s) found
scanning usb for storage devices... 0 Storage Device(s) found
Sending line: ''

end: 2.4.2 bootloader-interrupt (duration 00:00:06) [common]
start: 2.4.3 bootloader-commands (timeout 00:04:51) [common]
Setting prompt string to ['=>']
bootloader-commands: Wait for prompt ['=>'] (timeout 00:04:51)
Hit any key to stop autoboot: 20  0
Setting prompt string to ['=>', 'Resetting CPU', 'Must RESET board to recover', 'TIMEOUT', 'Retry count exceeded', 'Retry time exceeded; starting again', 'The remote end did not respond in time.', 'File not found', 'Bad Linux ARM64 Image magic!', 'Wrong Ramdisk Image Format', 'Ramdisk image is corrupt or invalid', 'ERROR: Failed to allocate', 'TFTP error: trying to overwrite reserved memory', 'Bad Linux RISCV Image magic!', 'Wrong Image Format for boot', 'ERROR: Did not find a cmdline Flattened Device Tree', 'ERROR: RD image overlaps OS image', 'kernel_comp_addr_r or kernel_comp_size is not provided', 'Waiting for Ethernet connection... unable to connect.', 'Verifying Checksum ... Bad Data CRC', 'ERROR: RD image overlaps OS image', 'Image too large: increase CONFIG_SYS_BOOTM_LEN', 'U-Boot SPL']
Sending line: 'setenv autoload no'
setenv autoload no
bootloader-commands: Wait for prompt ['=>', 'Resetting CPU', 'Must RESET board to recover', 'TIMEOUT', 'Retry count exceeded', 'Retry time exceeded; starting again', 'The remote end did not respond in time.', 'File not found', 'Bad Linux ARM64 Image magic!', 'Wrong Ramdisk Image Format', 'Ramdisk image is corrupt or invalid', 'ERROR: Failed to allocate', 'TFTP error: trying to overwrite reserved memory', 'Bad Linux RISCV Image magic!', 'Wrong Image Format for boot', 'ERROR: Did not find a cmdline Flattened Device Tree', 'ERROR: RD image overlaps OS image', 'kernel_comp_addr_r or kernel_comp_size is not provided', 'Waiting for Ethernet connection... unable to connect.', 'Verifying Checksum ... Bad Data CRC', 'ERROR: RD image overlaps OS image', 'Image too large: increase CONFIG_SYS_BOOTM_LEN', 'U-Boot SPL'] (timeout 00:04:51)
=> setenv autoload no
Sending line: 'setenv initrd_high 0xffffffff'
setenv initrd_high 0xffffffff
bootloader-commands: Wait for prompt ['=>', 'Resetting CPU', 'Must RESET board to recover', 'TIMEOUT', 'Retry count exceeded', 'Retry time exceeded; starting again', 'The remote end did not respond in time.', 'File not found', 'Bad Linux ARM64 Image magic!', 'Wrong Ramdisk Image Format', 'Ramdisk image is corrupt or invalid', 'ERROR: Failed to allocate', 'TFTP error: trying to overwrite reserved memory', 'Bad Linux RISCV Image magic!', 'Wrong Image Format for boot', 'ERROR: Did not find a cmdline Flattened Device Tree', 'ERROR: RD image overlaps OS image', 'kernel_comp_addr_r or kernel_comp_size is not provided', 'Waiting for Ethernet connection... unable to connect.', 'Verifying Checksum ... Bad Data CRC', 'ERROR: RD image overlaps OS image', 'Image too large: increase CONFIG_SYS_BOOTM_LEN', 'U-Boot SPL'] (timeout 00:04:51)
=> setenv initrd_high 0xffffffff
Sending line: 'setenv fdt_high 0xffffffff'
setenv fdt_high 0xffffffff
bootloader-commands: Wait for prompt ['=>', 'Resetting CPU', 'Must RESET board to recover', 'TIMEOUT', 'Retry count exceeded', 'Retry time exceeded; starting again', 'The remote end did not respond in time.', 'File not found', 'Bad Linux ARM64 Image magic!', 'Wrong Ramdisk Image Format', 'Ramdisk image is corrupt or invalid', 'ERROR: Failed to allocate', 'TFTP error: trying to overwrite reserved memory', 'Bad Linux RISCV Image magic!', 'Wrong Image Format for boot', 'ERROR: Did not find a cmdline Flattened Device Tree', 'ERROR: RD image overlaps OS image', 'kernel_comp_addr_r or kernel_comp_size is not provided', 'Waiting for Ethernet connection... unable to connect.', 'Verifying Checksum ... Bad Data CRC', 'ERROR: RD image overlaps OS image', 'Image too large: increase CONFIG_SYS_BOOTM_LEN', 'U-Boot SPL'] (timeout 00:04:50)
=> setenv fdt_high 0xffffffff
Sending line: 'setenv ethaddr 8a:c9:56:36:00:84'
setenv ethaddr 8a:c9:56:36:00:84
bootloader-commands: Wait for prompt ['=>', 'Resetting CPU', 'Must RESET board to recover', 'TIMEOUT', 'Retry count exceeded', 'Retry time exceeded; starting again', 'The remote end did not respond in time.', 'File not found', 'Bad Linux ARM64 Image magic!', 'Wrong Ramdisk Image Format', 'Ramdisk image is corrupt or invalid', 'ERROR: Failed to allocate', 'TFTP error: trying to overwrite reserved memory', 'Bad Linux RISCV Image magic!', 'Wrong Image Format for boot', 'ERROR: Did not find a cmdline Flattened Device Tree', 'ERROR: RD image overlaps OS image', 'kernel_comp_addr_r or kernel_comp_size is not provided', 'Waiting for Ethernet connection... unable to connect.', 'Verifying Checksum ... Bad Data CRC', 'ERROR: RD image overlaps OS image', 'Image too large: increase CONFIG_SYS_BOOTM_LEN', 'U-Boot SPL'] (timeout 00:04:50)
=> setenv ethaddr 8a:c9:56:36:00:84
Sending line: 'dhcp'
dhcp
bootloader-commands: Wait for prompt ['=>', 'Resetting CPU', 'Must RESET board to recover', 'TIMEOUT', 'Retry count exceeded', 'Retry time exceeded; starting again', 'The remote end did not respond in time.', 'File not found', 'Bad Linux ARM64 Image magic!', 'Wrong Ramdisk Image Format', 'Ramdisk image is corrupt or invalid', 'ERROR: Failed to allocate', 'TFTP error: trying to overwrite reserved memory', 'Bad Linux RISCV Image magic!', 'Wrong Image Format for boot', 'ERROR: Did not find a cmdline Flattened Device Tree', 'ERROR: RD image overlaps OS image', 'kernel_comp_addr_r or kernel_comp_size is not provided', 'Waiting for Ethernet connection... unable to connect.', 'Verifying Checksum ... Bad Data CRC', 'ERROR: RD image overlaps OS image', 'Image too large: increase CONFIG_SYS_BOOTM_LEN', 'U-Boot SPL'] (timeout 00:04:50)
=> dhcp
BOOTP broadcast 1
DHCP client bound to address 192.168.1.63 (2 ms)
Sending line: 'setenv serverip 192.168.1.40'
setenv serverip 192.168.1.40
bootloader-commands: Wait for prompt ['=>', 'Resetting CPU', 'Must RESET board to recover', 'TIMEOUT', 'Retry count exceeded', 'Retry time exceeded; starting again', 'The remote end did not respond in time.', 'File not found', 'Bad Linux ARM64 Image magic!', 'Wrong Ramdisk Image Format', 'Ramdisk image is corrupt or invalid', 'ERROR: Failed to allocate', 'TFTP error: trying to overwrite reserved memory', 'Bad Linux RISCV Image magic!', 'Wrong Image Format for boot', 'ERROR: Did not find a cmdline Flattened Device Tree', 'ERROR: RD image overlaps OS image', 'kernel_comp_addr_r or kernel_comp_size is not provided', 'Waiting for Ethernet connection... unable to connect.', 'Verifying Checksum ... Bad Data CRC', 'ERROR: RD image overlaps OS image', 'Image too large: increase CONFIG_SYS_BOOTM_LEN', 'U-Boot SPL'] (timeout 00:04:50)
=> setenv serverip 192.168.1.40
Sending line: 'tftp 0x40000000 475922/tftp-deploy-_jvvb0yh/kernel/Image'
tftp 0x40000000 475922/tftp-deploy-_jvvb0yh/kernel/Image
bootloader-commands: Wait for prompt ['=>', 'Resetting CPU', 'Must RESET board to recover', 'TIMEOUT', 'Retry count exceeded', 'Retry time exceeded; starting again', 'The remote end did not respond in time.', 'File not found', 'Bad Linux ARM64 Image magic!', 'Wrong Ramdisk Image Format', 'Ramdisk image is corrupt or invalid', 'ERROR: Failed to allocate', 'TFTP error: trying to overwrite reserved memory', 'Bad Linux RISCV Image magic!', 'Wrong Image Format for boot', 'ERROR: Did not find a cmdline Flattened Device Tree', 'ERROR: RD image overlaps OS image', 'kernel_comp_addr_r or kernel_comp_size is not provided', 'Waiting for Ethernet connection... unable to connect.', 'Verifying Checksum ... Bad Data CRC', 'ERROR: RD image overlaps OS image', 'Image too large: increase CONFIG_SYS_BOOTM_LEN', 'U-Boot SPL'] (timeout 00:04:50)
=> tftp 0x40000000 475922/tftp-deploy-_jvvb0yh/kernel/Image
Using ethernet@4500000 device
TFTP from server 192.168.1.40; our IP address is 192.168.1.63
Filename '475922/tftp-deploy-_jvvb0yh/kernel/Image'.
Load address: 0x40000000
Loading: *#################################################################
#################################################################
#################################################################
#################################################################
#################################################################
#################################################################
#################################################################
#################################################################
#################################################################
#################################################################
#################################################################
#################################################################
#################################################################
#################################################################
#################################################################
#################################################################
##
3.7 MiB/s
done
Bytes transferred = 15284232 (e93808 hex)
Sending line: 'tftp 0x44300000 475922/tftp-deploy-_jvvb0yh/ramdisk/ramdisk.cpio.gz.uboot'
tftp 0x44300000 475922/tftp-deploy-_jvvb0yh/ramdisk/ramdisk.cpio.gz.uboot
bootloader-commands: Wait for prompt ['=>', 'Resetting CPU', 'Must RESET board to recover', 'TIMEOUT', 'Retry count exceeded', 'Retry time exceeded; starting again', 'The remote end did not respond in time.', 'File not found', 'Bad Linux ARM64 Image magic!', 'Wrong Ramdisk Image Format', 'Ramdisk image is corrupt or invalid', 'ERROR: Failed to allocate', 'TFTP error: trying to overwrite reserved memory', 'Bad Linux RISCV Image magic!', 'Wrong Image Format for boot', 'ERROR: Did not find a cmdline Flattened Device Tree', 'ERROR: RD image overlaps OS image', 'kernel_comp_addr_r or kernel_comp_size is not provided', 'Waiting for Ethernet connection... unable to connect.', 'Verifying Checksum ... Bad Data CRC', 'ERROR: RD image overlaps OS image', 'Image too large: increase CONFIG_SYS_BOOTM_LEN', 'U-Boot SPL'] (timeout 00:04:46)
=> tftp 0x44300000 475922/tftp-deploy-_jvvb0yh/ramdisk/ramdisk.cpio.gz.uboot
Using ethernet@4500000 device
TFTP from server 192.168.1.40; our IP address is 192.168.1.63
Filename '475922/tftp-deploy-_jvvb0yh/ramdisk/ramdisk.cpio.gz.uboot'.
Load address: 0x44300000
Loading: *#################################################################
#################################################################
#################################################################
#################################################################
############T #####################################################
#################################################################
#################################################################
#################################################################
#################################################################
#################################################################
#################################################################
#################################################################
#################################################################
#################################################################
#################################################################
#################################################################
#################################################################
#################################################################
#################################################################
#################################################################
#################################################################
#################################################################
#################################################################
#################################################################
##############
2 MiB/s
done
Bytes transferred = 23098237 (160737d hex)
Sending line: 'setenv initrd_size ${filesize}'
setenv initrd_size ${filesize}
bootloader-commands: Wait for prompt ['=>', 'Resetting CPU', 'Must RESET board to recover', 'TIMEOUT', 'Retry count exceeded', 'Retry time exceeded; starting again', 'The remote end did not respond in time.', 'File not found', 'Bad Linux ARM64 Image magic!', 'Wrong Ramdisk Image Format', 'Ramdisk image is corrupt or invalid', 'ERROR: Failed to allocate', 'TFTP error: trying to overwrite reserved memory', 'Bad Linux RISCV Image magic!', 'Wrong Image Format for boot', 'ERROR: Did not find a cmdline Flattened Device Tree', 'ERROR: RD image overlaps OS image', 'kernel_comp_addr_r or kernel_comp_size is not provided', 'Waiting for Ethernet connection... unable to connect.', 'Verifying Checksum ... Bad Data CRC', 'ERROR: RD image overlaps OS image', 'Image too large: increase CONFIG_SYS_BOOTM_LEN', 'U-Boot SPL'] (timeout 00:04:35)
=> setenv initrd_size ${filesize}
Sending line: 'tftp 0x44000000 475922/tftp-deploy-_jvvb0yh/dtb/sun55i-a527-radxa-a5e.dtb'
tftp 0x44000000 475922/tftp-deploy-_jvvb0yh/dtb/sun55i-a527-radxa-a5e.dtb
bootloader-commands: Wait for prompt ['=>', 'Resetting CPU', 'Must RESET board to recover', 'TIMEOUT', 'Retry count exceeded', 'Retry time exceeded; starting again', 'The remote end did not respond in time.', 'File not found', 'Bad Linux ARM64 Image magic!', 'Wrong Ramdisk Image Format', 'Ramdisk image is corrupt or invalid', 'ERROR: Failed to allocate', 'TFTP error: trying to overwrite reserved memory', 'Bad Linux RISCV Image magic!', 'Wrong Image Format for boot', 'ERROR: Did not find a cmdline Flattened Device Tree', 'ERROR: RD image overlaps OS image', 'kernel_comp_addr_r or kernel_comp_size is not provided', 'Waiting for Ethernet connection... unable to connect.', 'Verifying Checksum ... Bad Data CRC', 'ERROR: RD image overlaps OS image', 'Image too large: increase CONFIG_SYS_BOOTM_LEN', 'U-Boot SPL'] (timeout 00:04:35)
=> tftp 0x44000000 475922/tftp-deploy-_jvvb0yh/dtb/sun55i-a527-radxa-a5e.dtb
Using ethernet@4500000 device
TFTP from server 192.168.1.40; our IP address is 192.168.1.63
Filename '475922/tftp-deploy-_jvvb0yh/dtb/sun55i-a527-radxa-a5e.dtb'.
Load address: 0x44000000
Loading: *#
2.6 MiB/s
done
Bytes transferred = 13764 (35c4 hex)
Sending line: "setenv bootargs 'console=ttyS0,115200n8 root=/dev/ram0 ip=dhcp ip=dhcp'"
setenv bootargs 'console=ttyS0,115200n8 root=/dev/ram0 ip=dhcp ip=dhcp'
bootloader-commands: Wait for prompt ['=>', 'Resetting CPU', 'Must RESET board to recover', 'TIMEOUT', 'Retry count exceeded', 'Retry time exceeded; starting again', 'The remote end did not respond in time.', 'File not found', 'Bad Linux ARM64 Image magic!', 'Wrong Ramdisk Image Format', 'Ramdisk image is corrupt or invalid', 'ERROR: Failed to allocate', 'TFTP error: trying to overwrite reserved memory', 'Bad Linux RISCV Image magic!', 'Wrong Image Format for boot', 'ERROR: Did not find a cmdline Flattened Device Tree', 'ERROR: RD image overlaps OS image', 'kernel_comp_addr_r or kernel_comp_size is not provided', 'Waiting for Ethernet connection... unable to connect.', 'Verifying Checksum ... Bad Data CRC', 'ERROR: RD image overlaps OS image', 'Image too large: increase CONFIG_SYS_BOOTM_LEN', 'U-Boot SPL'] (timeout 00:04:35)
=> setenv bootargs 'console=ttyS0,115200n8 root=/dev/ram0 ip=dhcp ip=dhcp'
Sending line: 'booti 0x40000000 0x44300000 0x44000000'
booti 0x40000000 0x44300000 0x44000000
Setting prompt string to ['Starting kernel', 'Resetting CPU', 'Must RESET board to recover', 'TIMEOUT', 'Retry count exceeded', 'Retry time exceeded; starting again', 'The remote end did not respond in time.', 'File not found', 'Bad Linux ARM64 Image magic!', 'Wrong Ramdisk Image Format', 'Ramdisk image is corrupt or invalid', 'ERROR: Failed to allocate', 'TFTP error: trying to overwrite reserved memory', 'Bad Linux RISCV Image magic!', 'Wrong Image Format for boot', 'ERROR: Did not find a cmdline Flattened Device Tree', 'ERROR: RD image overlaps OS image', 'kernel_comp_addr_r or kernel_comp_size is not provided', 'Waiting for Ethernet connection... unable to connect.', 'Verifying Checksum ... Bad Data CRC', 'ERROR: RD image overlaps OS image', 'Image too large: increase CONFIG_SYS_BOOTM_LEN', 'U-Boot SPL']
bootloader-commands: Wait for prompt ['Starting kernel', 'Resetting CPU', 'Must RESET board to recover', 'TIMEOUT', 'Retry count exceeded', 'Retry time exceeded; starting again', 'The remote end did not respond in time.', 'File not found', 'Bad Linux ARM64 Image magic!', 'Wrong Ramdisk Image Format', 'Ramdisk image is corrupt or invalid', 'ERROR: Failed to allocate', 'TFTP error: trying to overwrite reserved memory', 'Bad Linux RISCV Image magic!', 'Wrong Image Format for boot', 'ERROR: Did not find a cmdline Flattened Device Tree', 'ERROR: RD image overlaps OS image', 'kernel_comp_addr_r or kernel_comp_size is not provided', 'Waiting for Ethernet connection... unable to connect.', 'Verifying Checksum ... Bad Data CRC', 'ERROR: RD image overlaps OS image', 'Image too large: increase CONFIG_SYS_BOOTM_LEN', 'U-Boot SPL'] (timeout 00:04:34)
=> booti 0x40000000 0x44300000 0x44000000
## Loading init Ramdisk from Legacy Image at 44300000 ...
Image Name:
Image Type: AArch64 Linux RAMDisk Image (uncompressed)
Data Size: 23098173 Bytes = 22 MiB
Load Address: 00000000
Entry Point: 00000000
Verifying Checksum ... OK
## Flattened Device Tree blob at 44000000
Booting using the fdt blob at 0x44000000
Working FDT set to 44000000
Loading Ramdisk to f9917000, end faf1e33d ... OK
Loading Device Tree to 00000000f9910000, end 00000000f99165c3 ... OK
Working FDT set to f9910000

end: 2.4.3 bootloader-commands (duration 00:00:17) [common]
start: 2.4.4 auto-login-action (timeout 00:04:34) [common]
Setting prompt string to ['Linux version [0-9]']
Setting prompt string to ['Linux version [0-9]', 'Resetting CPU', 'Must RESET board to recover', 'TIMEOUT', 'Retry count exceeded', 'Retry time exceeded; starting again', 'The remote end did not respond in time.', 'File not found', 'Bad Linux ARM64 Image magic!', 'Wrong Ramdisk Image Format', 'Ramdisk image is corrupt or invalid', 'ERROR: Failed to allocate', 'TFTP error: trying to overwrite reserved memory', 'Bad Linux RISCV Image magic!', 'Wrong Image Format for boot', 'ERROR: Did not find a cmdline Flattened Device Tree', 'ERROR: RD image overlaps OS image', 'kernel_comp_addr_r or kernel_comp_size is not provided', 'Waiting for Ethernet connection... unable to connect.', 'Verifying Checksum ... Bad Data CRC', 'ERROR: RD image overlaps OS image', 'Image too large: increase CONFIG_SYS_BOOTM_LEN', 'U-Boot SPL']
auto-login-action: Wait for prompt ['Linux version [0-9]', 'Resetting CPU', 'Must RESET board to recover', 'TIMEOUT', 'Retry count exceeded', 'Retry time exceeded; starting again', 'The remote end did not respond in time.', 'File not found', 'Bad Linux ARM64 Image magic!', 'Wrong Ramdisk Image Format', 'Ramdisk image is corrupt or invalid', 'ERROR: Failed to allocate', 'TFTP error: trying to overwrite reserved memory', 'Bad Linux RISCV Image magic!', 'Wrong Image Format for boot', 'ERROR: Did not find a cmdline Flattened Device Tree', 'ERROR: RD image overlaps OS image', 'kernel_comp_addr_r or kernel_comp_size is not provided', 'Waiting for Ethernet connection... unable to connect.', 'Verifying Checksum ... Bad Data CRC', 'ERROR: RD image overlaps OS image', 'Image too large: increase CONFIG_SYS_BOOTM_LEN', 'U-Boot SPL'] (timeout 00:05:00)
Starting kernel ...

[ 0.000000] Booting Linux on physical CPU 0x0000000000 [0x412fd050]
start: 2.4.4.1 login-action (timeout 00:04:34) [common]
The string '/ #' does not look like a typical prompt and could match status messages instead. Please check the job log files and use a prompt string which matches the actual prompt string more closely.
The string '~ #' does not look like a typical prompt and could match status messages instead. Please check the job log files and use a prompt string which matches the actual prompt string more closely.
The string 'sh-5.1#' does not look like a typical prompt and could match status messages instead. Please check the job log files and use a prompt string which matches the actual prompt string more closely.
Setting prompt string to []
Setting prompt string to ['-\\[ cut here \\]', 'Unhandled fault', 'BUG: KCSAN:', 'BUG: KASAN:', 'BUG: KFENCE:', 'Oops(?: -|:)', 'WARNING:', '(kernel BUG at|BUG:)', 'invalid opcode:', 'Kernel panic - not syncing', 'U-Boot SPL 20[0-9][0-9]']
Using line separator: #'\n'#
No login prompt set.
Parsing kernel messages
-\[ cut here \]Unhandled faultBUG: KCSAN:BUG: KASAN:BUG: KFENCE:Oops(?: -|:)WARNING:(kernel BUG at|BUG:)invalid opcode:Kernel panic - not syncingU-Boot SPL 20[0-9][0-9]/ #~ #sh-5.1#Login timed outLogin incorrect
[login-action] Waiting for messages, (timeout 00:04:34)
Waiting using forced prompt support (timeout 00:02:17)
[ 0.000000] Linux version 6.15.0-rc2-next-20250415 (compile@Red) (aarch64-unknown-linux-gnu-gcc (Gentoo 14.2.1_p20241221 p7) 14.2.1 20241221, GNU ld (Gentoo 2.44 p1) 2.44.0) #12 SMP PREEMPT Sun Apr 20 22:19:49 CEST 2025
[ 0.000000] KASLR disabled due to lack of seed
[ 0.000000] Machine model: Radxa A5E
[ 0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT
[ 0.000000] Zone ranges:
[ 0.000000] DMA [mem 0x0000000040000000-0x00000000ffffffff]
[ 0.000000] DMA32 empty
[ 0.000000] Normal [mem 0x0000000100000000-0x000000013fffffff]
[ 0.000000] Movable zone start for each node
[ 0.000000] Early memory node ranges
[ 0.000000] node 0: [mem 0x0000000040000000-0x000000013fffffff]
[ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000013fffffff]
[ 0.000000] psci: probing for conduit method from DT.
[ 0.000000] psci: PSCIv1.1 detected in firmware.
[ 0.000000] psci: Using standard PSCI v0.2 function IDs
[ 0.000000] psci: MIGRATE_INFO_TYPE not supported.
[ 0.000000] psci: SMC Calling Convention v1.5
[ 0.000000] percpu: Embedded 22 pages/cpu s49752 r8192 d32168 u90112
[ 0.000000] Detected VIPT I-cache on CPU0
[ 0.000000] CPU features: detected: GIC system register CPU interface
[ 0.000000] CPU features: detected: Virtualization Host Extensions
[ 0.000000] CPU features: detected: ARM errata 1165522, 1319367, or 1530923
[ 0.000000] alternatives: applying boot alternatives
[ 0.000000] Kernel command line: console=ttyS0,115200n8 root=/dev/ram0 ip=dhcp ip=dhcp
[ 0.000000] printk: log buffer data + meta data: 131072 + 458752 = 589824 bytes
[ 0.000000] Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear)
[ 0.000000] Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear)
[ 0.000000] software IO TLB: area num 8.
[ 0.000000] software IO TLB: mapped [mem 0x00000000fbfff000-0x00000000fffff000] (64MB)
[ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 1048576
[ 0.000000] mem auto-init: stack:all(zero), heap alloc:off, heap free:off
[ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=8, Nodes=1
[ 0.000000] rcu: Preemptible hierarchical RCU implementation.
[ 0.000000] rcu: RCU event tracing is enabled.
[ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=8.
[ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies.
[ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=8
[ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0
[ 0.000000] GIC: enabling workaround for GICv3: non-coherent attribute
[ 0.000000] GICv3: GIC: Using split EOI/Deactivate mode
[ 0.000000] GICv3: 256 SPIs implemented
[ 0.000000] GICv3: 0 Extended SPIs implemented
[ 0.000000] Root IRQ handler: gic_handle_irq
[ 0.000000] GICv3: GICv3 features: 16 PPIs
[ 0.000000] GICv3: GICD_CTRL.DS=0, SCR_EL3.FIQ=0
[ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x0000000003460000
[ 0.000000] ITS [mem 0x03440000-0x0345ffff]
[ 0.000000] GIC: enabling workaround for ITS: non-coherent attribute
[ 0.000000] ITS@0x0000000003440000: allocated 8192 Devices @100070000 (indirect, esz 8, psz 64K, shr 0)
[ 0.000000] ITS@0x0000000003440000: allocated 32768 Interrupt Collections @100080000 (flat, esz 2, psz 64K, shr 0)
[ 0.000000] ITS: using cache flushing for cmd queue
[ 0.000000] GICv3: using LPI property table @0x0000000100090000
[ 0.000000] GIC: using cache flushing for LPI property table
[ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x00000001000a0000
[ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention.
[ 0.000000] arch_timer: cp15 timer(s) running at 24.00MHz (phys).
[ 0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x588fe9dc0, max_idle_ns: 440795202592 ns
[ 0.000000] sched_clock: 56 bits at 24MHz, resolution 41ns, wraps every 4398046511097ns
[ 0.000368] Console: colour dummy device 80x25
[ 0.000428] Calibrating delay loop (skipped), value calculated using timer frequency.. 48.00 BogoMIPS (lpj=96000)
[ 0.000442] pid_max: default: 32768 minimum: 301
[ 0.000664] Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear)
[ 0.000688] Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear)
[ 0.001766] cacheinfo: Unable to detect cache hierarchy for CPU 0
[ 0.020499] rcu: Hierarchical SRCU implementation.
[ 0.020505] rcu: Max phase no-delay instances is 1000.
[ 0.020844] Timer migration: 1 hierarchy levels; 8 children per group; 1 crossnode level
[ 0.024621] smp: Bringing up secondary CPUs ...
[ 0.032482] Detected VIPT I-cache on CPU1
[ 0.032548] GICv3: CPU1: found redistributor 100 region 0:0x0000000003480000
[ 0.032580] GICv3: CPU1: using allocated LPI pending table @0x00000001000b0000
[ 0.032637] CPU1: Booted secondary processor 0x0000000100 [0x412fd050]
[ 0.036368] Detected VIPT I-cache on CPU2
[ 0.036434] GICv3: CPU2: found redistributor 200 region 0:0x00000000034a0000
[ 0.036465] GICv3: CPU2: using allocated LPI pending table @0x00000001000c0000
[ 0.036524] CPU2: Booted secondary processor 0x0000000200 [0x412fd050]
[ 0.044355] Detected VIPT I-cache on CPU3
[ 0.044415] GICv3: CPU3: found redistributor 300 region 0:0x00000000034c0000
[ 0.044445] GICv3: CPU3: using allocated LPI pending table @0x00000001000d0000
[ 0.044499] CPU3: Booted secondary processor 0x0000000300 [0x412fd050]
[ 0.048400] Detected VIPT I-cache on CPU4
[ 0.048466] GICv3: CPU4: found redistributor 400 region 0:0x00000000034e0000
[ 0.048496] GICv3: CPU4: using allocated LPI pending table @0x00000001000e0000
[ 0.048547] CPU4: Booted secondary processor 0x0000000400 [0x412fd050]
[ 0.056407] Detected VIPT I-cache on CPU5
[ 0.056478] GICv3: CPU5: found redistributor 500 region 0:0x0000000003500000
[ 0.056509] GICv3: CPU5: using allocated LPI pending table @0x00000001000f0000
[ 0.056560] CPU5: Booted secondary processor 0x0000000500 [0x412fd050]
[ 0.064454] Detected VIPT I-cache on CPU6
[ 0.064528] GICv3: CPU6: found redistributor 600 region 0:0x0000000003520000
[ 0.064559] GICv3: CPU6: using allocated LPI pending table @0x0000000100100000
[ 0.064612] CPU6: Booted secondary processor 0x0000000600 [0x412fd050]
[ 0.072484] Detected VIPT I-cache on CPU7
[ 0.072559] GICv3: CPU7: found redistributor 700 region 0:0x0000000003540000
[ 0.072589] GICv3: CPU7: using allocated LPI pending table @0x0000000100110000
[ 0.072640] CPU7: Booted secondary processor 0x0000000700 [0x412fd050]
[ 0.072830] smp: Brought up 1 node, 8 CPUs
[ 0.072842] SMP: Total of 8 processors activated.
[ 0.072846] CPU: All CPU(s) started at EL2
[ 0.072852] CPU features: detected: 32-bit EL0 Support
[ 0.072855] CPU features: detected: 32-bit EL1 Support
[ 0.072861] CPU features: detected: Data cache clean to the PoU not required for I/D coherence
[ 0.072866] CPU features: detected: Common not Private translations
[ 0.072871] CPU features: detected: CRC32 instructions
[ 0.072880] CPU features: detected: RCpc load-acquire (LDAPR)
[ 0.072885] CPU features: detected: LSE atomic instructions
[ 0.072890] CPU features: detected: Privileged Access Never
[ 0.072894] CPU features: detected: PMUv3
[ 0.072898] CPU features: detected: RAS Extension Support
[ 0.072905] CPU features: detected: Speculative Store Bypassing Safe (SSBS)
[ 0.072982] alternatives: applying system-wide alternatives
[ 0.076015] Memory: 4002708K/4194304K available (9088K kernel code, 1166K rwdata, 3384K rodata, 1152K init, 514K bss, 185120K reserved, 0K cma-reserved)
[ 0.076997] devtmpfs: initialized
[ 0.081944] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns
[ 0.081973] posixtimers hash table entries: 4096 (order: 4, 65536 bytes, linear)
[ 0.082032] futex hash table entries: 2048 (order: 5, 131072 bytes, linear)
[ 0.082191] 28880 pages in range for non-PLT usage
[ 0.082196] 520400 pages in range for PLT usage
[ 0.082280] pinctrl core: initialized pinctrl subsystem
[ 0.083701] NET: Registered PF_NETLINK/PF_ROUTE protocol family
[ 0.084484] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations
[ 0.084572] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations
[ 0.084655] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations
[ 0.084702] audit: initializing netlink subsys (disabled)
[ 0.084912] audit: type=2000 audit(0.084:1): state=initialized audit_enabled=0 res=1
[ 0.085380] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers.
[ 0.085656] ASID allocator initialised with 65536 entries
[ 0.088754] /soc/clock-controller@2001000: Fixed dependency cycle(s) with /soc/rtc@7090000
[ 0.088833] /soc/interrupt-controller@3400000: Fixed dependency cycle(s) with /soc/interrupt-controller@3400000
[ 0.088931] /soc/clock-controller@7010000: Fixed dependency cycle(s) with /soc/clock-controller@2001000
[ 0.088946] /soc/clock-controller@7010000: Fixed dependency cycle(s) with /soc/rtc@7090000
[ 0.089021] /soc/rtc@7090000: Fixed dependency cycle(s) with /soc/clock-controller@7010000
[ 0.089409] /soc/clock-controller@2001000: Fixed dependency cycle(s) with /soc/rtc@7090000
[ 0.091765] /soc/clock-controller@7010000: Fixed dependency cycle(s) with /soc/clock-controller@2001000
[ 0.091855] /soc/clock-controller@7010000: Fixed dependency cycle(s) with /soc/rtc@7090000
[ 0.092227] /soc/i2c@7081400: Fixed dependency cycle(s) with /soc/pinctrl@7022000/r-i2c-pins
[ 0.092577] /soc/clock-controller@7010000: Fixed dependency cycle(s) with /soc/rtc@7090000
[ 0.092727] /soc/clock-controller@2001000: Fixed dependency cycle(s) with /soc/rtc@7090000
[ 0.092876] /soc/rtc@7090000: Fixed dependency cycle(s) with /soc/clock-controller@7010000
[ 0.095422] iommu: Default domain type: Translated
[ 0.095429] iommu: DMA domain TLB invalidation policy: strict mode
[ 0.096100] usbcore: registered new interface driver usbfs
[ 0.096138] usbcore: registered new interface driver hub
[ 0.096183] usbcore: registered new device driver usb
[ 0.096328] mc: Linux media interface: v0.10
[ 0.096362] videodev: Linux video capture interface: v2.00
[ 0.096418] pps_core: LinuxPPS API ver. 1 registered
[ 0.096423] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti
[ 0.096442] PTP clock support registered
[ 0.096596] scmi_core: SCMI protocol bus registered
[ 0.096996] Advanced Linux Sound Architecture Driver Initialized.
[ 0.097870] clocksource: Switched to clocksource arch_sys_counter
[ 0.109806] NET: Registered PF_INET protocol family
[ 0.110099] IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear)
[ 0.114118] tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear)
[ 0.114224] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)
[ 0.114244] TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear)
[ 0.114530] TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear)
[ 0.115521] TCP: Hash tables configured (established 32768 bind 32768)
[ 0.115641] UDP hash table entries: 2048 (order: 5, 131072 bytes, linear)
[ 0.115792] UDP-Lite hash table entries: 2048 (order: 5, 131072 bytes, linear)
[ 0.116063] NET: Registered PF_UNIX/PF_LOCAL protocol family
[ 0.116295] Trying to unpack rootfs image as initramfs...
[ 0.126890] kvm [1]: nv: 566 coarse grained trap handlers
[ 0.127518] kvm [1]: IPA Size Limit: 40 bits
[ 0.127554] kvm [1]: GICv3: no GICV resource entry
[ 0.127560] kvm [1]: disabling GICv2 emulation
[ 0.127600] kvm [1]: GIC system register CPU interface enabled
[ 0.127630] kvm [1]: vgic interrupt IRQ9
[ 0.127689] kvm [1]: VHE mode initialized successfully
[ 0.130078] Initialise system trusted keyrings
[ 0.130406] workingset: timestamp_bits=46 max_order=20 bucket_order=0
[ 0.216649] Key type asymmetric registered
[ 0.216665] Asymmetric key parser 'x509' registered
[ 0.216740] io scheduler mq-deadline registered
[ 0.216748] io scheduler kyber registered
[ 0.216801] io scheduler bfq registered
[ 0.235437] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled
[ 0.248138] brd: module loaded
[ 0.255480] loop: module loaded
[ 0.256155] tun: Universal TUN/TAP device driver, 1.6
[ 0.259053] sun6i-rtc 7090000.rtc: registered as rtc0
[ 0.259167] sun6i-rtc 7090000.rtc: setting system clock to 1970-01-02T00:00:14 UTC (86414)
[ 0.259496] i2c_dev: i2c /dev entries driver
[ 0.260036] sdhci: Secure Digital Host Controller Interface driver
[ 0.260042] sdhci: Copyright(c) Pierre Ossman
[ 0.260427] SMCCC: SOC_ID: ID = jep106:091e:1890 Revision = 0x00000002
[ 0.261453] hw perfevents: enabled with armv8_cortex_a55 PMU driver, 7 (0,8000003f) counters available
[ 0.262815] NET: Registered PF_INET6 protocol family
[ 0.263902] Segment Routing with IPv6
[ 0.263958] In-situ OAM (IOAM) with IPv6
[ 0.264015] sit: IPv6, IPv4 and MPLS over IPv4 tunneling driver
[ 0.264625] NET: Registered PF_PACKET protocol family
[ 0.270870] Loading compiled-in X.509 certificates
[ 0.305823] gpio gpiochip0: Static allocation of GPIO base is deprecated, use dynamic allocation.
[ 0.308867] sun55i-a523-r-pinctrl 7022000.pinctrl: initialized sunXi PIO driver
[ 0.310643] sun55i-a523-r-pinctrl 7022000.pinctrl: supply vcc-pl not found, using dummy regulator
[ 0.311747] axp20x-i2c 0-0034: AXP20x variant AXP717 found
[ 0.320356] axp717-adc: Failed to locate of_node [id: -1]
[ 0.322278] axp20x-usb-power-supply: Failed to locate of_node [id: -1]
[ 0.322418] axp20x-battery-power-supply: Failed to locate of_node [id: -1]
[ 0.322523] axp20x-i2c 0-0034: AXP20X driver loaded
[ 0.323064] axp20x-i2c 0-0036: AXP20x variant AXP323 found
[ 0.325916] sysfs: cannot create duplicate filename '/bus/platform/devices/axp20x-regulator'
[ 0.325927] CPU: 3 UID: 0 PID: 111 Comm: kworker/u32:4 Not tainted 6.15.0-rc2-next-20250415 #12 PREEMPT
[ 0.325940] Hardware name: Radxa A5E (DT)
[ 0.325946] Workqueue: events_unbound deferred_probe_work_func
[ 0.325970] Call trace:
[ 0.325974] show_stack+0x18/0x30 (C)
[ 0.325989] dump_stack_lvl+0x60/0x80
[ 0.326003] dump_stack+0x18/0x24
[ 0.326015] sysfs_warn_dup+0x64/0x80
[ 0.326028] sysfs_do_create_link_sd+0xf0/0x100
[ 0.326039] sysfs_create_link+0x20/0x40
[ 0.326050] bus_add_device+0x64/0x110
[ 0.326065] device_add+0x2d4/0x72c
[ 0.326078] platform_device_add+0x100/0x254
[ 0.326090] mfd_add_device+0x3ec/0x4c8
[ 0.326102] mfd_add_devices+0x8c/0x124
[ 0.326113] axp20x_device_probe+0x70/0x14c
[ 0.326125] axp20x_i2c_probe+0x94/0xe0
[ 0.326136] i2c_device_probe+0x148/0x2b0
[ 0.326149] really_probe+0xbc/0x2c0
[ 0.326159] __driver_probe_device+0x78/0x120
[ 0.326169] driver_probe_device+0x3c/0x154
[ 0.326179] __device_attach_driver+0xb8/0x140
[ 0.326189] bus_for_each_drv+0x88/0xe8
[ 0.326203] __device_attach+0xa0/0x190
[ 0.326213] device_initial_probe+0x14/0x20
[ 0.326224] bus_probe_device+0xb4/0xc0
[ 0.326238] device_add+0x554/0x72c
[ 0.326251] device_register+0x20/0x34
[ 0.326263] i2c_new_client_device+0x180/0x324
[ 0.326274] of_i2c_register_devices+0x11c/0x1a0
[ 0.326287] i2c_register_adapter+0x1f0/0x670
[ 0.326298] i2c_add_adapter+0x78/0xd8
[ 0.326309] i2c_add_numbered_adapter+0x2c/0x3c
[ 0.326320] mv64xxx_i2c_probe+0x1e0/0x4ec
[ 0.326333] platform_probe+0x68/0xdc
[ 0.326345] really_probe+0xbc/0x2c0
[ 0.326354] __driver_probe_device+0x78/0x120
[ 0.326364] driver_probe_device+0x3c/0x154
[ 0.326374] __device_attach_driver+0xb8/0x140
[ 0.326384] bus_for_each_drv+0x88/0xe8
[ 0.326398] __device_attach+0xa0/0x190
[ 0.326408] device_initial_probe+0x14/0x20
[ 0.326418] bus_probe_device+0xb4/0xc0
[ 0.326433] deferred_probe_work_func+0x90/0xc8
[ 0.326448] process_one_work+0x148/0x284
[ 0.326464] worker_thread+0x2cc/0x3cc
[ 0.326478] kthread+0x12c/0x204
[ 0.326491] ret_from_fork+0x10/0x20
[ 0.326541] axp20x-i2c 0-0036: failed to add MFD devices: -17
[ 0.326890] axp20x-i2c 0-0036: probe with driver axp20x-i2c failed with error -17
[ 0.328374] gpio gpiochip1: Static allocation of GPIO base is deprecated, use dynamic allocation.
[ 0.330705] vcc-mipi-cam: Bringing 2800000uV into 3300000-3300000uV
[ 0.342597] sun55i-a523-pinctrl 2000000.pinctrl: initialized sunXi PIO driver
[ 0.343786] printk: legacy console [ttyS0] disabled
[ 0.364499] 2500000.serial: ttyS0 at MMIO 0x2500000 (irq = 455, base_baud = 1500000) is a 16550A
[ 0.364569] printk: legacy console [ttyS0] enabled
[ 1.372733] Freeing initrd memory: 22556K
[ 1.380252] musb-sunxi 4100000.usb: Invalid or missing 'dr_mode' property
[ 1.380473] phy phy-4100400.phy.0: Changing dr_mode to 1
[ 1.380491] ehci-platform 4101000.usb: EHCI Host Controller
[ 1.380517] ehci-platform 4101000.usb: new USB bus registered, assigned bus number 1
[ 1.380783] ehci-platform 4200000.usb: EHCI Host Controller
[ 1.380835] ehci-platform 4200000.usb: new USB bus registered, assigned bus number 2
[ 1.380924] sunxi-mmc 4020000.mmc: Got CD GPIO
[ 1.381003] ehci-platform 4101000.usb: irq 456, io mem 0x04101000
[ 1.381120] ehci-platform 4200000.usb: irq 458, io mem 0x04200000
[ 1.389914] ehci-platform 4101000.usb: USB 2.0 started, EHCI 1.00
[ 1.397113] musb-sunxi 4100000.usb: probe with driver musb-sunxi failed with error -22
[ 1.403616] hub 1-0:1.0: USB hub found
[ 1.405910] ehci-platform 4200000.usb: USB 2.0 started, EHCI 1.00
[ 1.406131] sunxi-mmc 4020000.mmc: initialized, max. request size: 2048 KB, uses new timings mode
[ 1.409224] ohci-platform 4200400.usb: Generic Platform OHCI controller
[ 1.413636] hub 1-0:1.0: 1 port detected
[ 1.445065] mmc0: host does not support reading read-only switch, assuming write-enable
[ 1.447670] hub 2-0:1.0: USB hub found
[ 1.462118] mmc0: new high speed SDHC card at address 59b4
[ 1.466552] hub 2-0:1.0: 1 port detected
[ 1.471256] mmcblk0: mmc0:59b4 USDU1 7.31 GiB
[ 1.474847] ohci-platform 4200400.usb: new USB bus registered, assigned bus number 3
[ 1.482820] mmcblk0: p1 p2
[ 1.483916] ohci-platform 4200400.usb: irq 460, io mem 0x04200400
[ 1.581922] ohci-platform 4101400.usb: Generic Platform OHCI controller
[ 1.646680] hub 3-0:1.0: USB hub found
[ 2.037799] hub 3-0:1.0: 1 port detected
[ 2.042193] ohci-platform 4101400.usb: new USB bus registered, assigned bus number 4
[ 2.050074] ohci-platform 4101400.usb: irq 459, io mem 0x04101400
[ 2.114648] hub 4-0:1.0: USB hub found
[ 2.118463] hub 4-0:1.0: 1 port detected
[ 14.401949] clk: Disabling unused clocks
[ 14.406180] PM: genpd: Disabling unused power domains
[ 14.411265] ALSA device list:
[ 14.414248] No soundcards found.
[ 14.418464] Freeing unused kernel memory: 1152K
[ 14.423113] Run /init as init process
Starting syslogd: OK
Starting klogd: OK
Running sysctl: OK
Populating /dev using udev: [ 14.517296] udevd[182]: starting version 3.2.11
[ 15.693899] random: crng init done
[ 15.705156] udevd[183]: starting eudev-3.2.11
[ 15.764798] sunxi-wdt 2050000.watchdog: Watchdog enabled (timeout=16 sec, nowayout=0)
done
Saving 256 bits of creditable seed for next boot
Starting rngd: OK
Starting rpcbind: OK
Starting network: Cannot find device "eth0"
Cannot find device "enp2s0"
Cannot find device "enp2s3"
Cannot find device "enp0s3"
Cannot find device "enp0s11"
Cannot find device "enp0s15"
FAIL
Starting dhcpcd...
dhcpcd-10.0.1 starting
dev: loaded udev
[ 16.239804] cfg80211: Loading compiled-in X.509 certificates for regulatory database
[ 16.307284] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'
[ 16.313992] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'
no valid interfaces found
Starting sntp: sntp 4.2.8p15@1.3728-o Thu Jul 6 12:37:25 UTC 2023 (1)
pool.ntp.org lookup error Temporary failure in name resolution
FAIL
Starting NFS statd: OK
Starting NFS services: OK
Starting NFS daemon: rpc.nfsd: Unable to access /proc/fs/nfsd errno 2 (No such file or directory).
Please try, as root, 'mount -t nfsd nfsd /proc/fs/nfsd' and then restart rpc.nfsd to correct the problem
FAIL
Starting NFS mountd: OK
/bin/sh: can't access tty; job control turned off
Matched prompt #12: ~ #
definitionnamespacecaseleveldurationresultextra
Setting prompt string to ['/ #', '~ #', 'sh-5.1#']
end: 2.4.4.1 login-action (duration 00:00:16) [common]
definitionnamespacecaseleveldurationresultextra
end: 2.4.4 auto-login-action (duration 00:00:17) [common]
end: 2.4 uboot-commands (duration 00:00:42) [common]
end: 2 uboot-action (duration 00:00:42) [common]
start: 3 finalize (timeout 00:20:00) [common]
start: 3.1 power-off (timeout 00:00:30) [common]
Calling: ['/usr/bin/power-sispmctl.sh', 'off', '3', '01:ff:ff:ff:ff']
>> Accessing Gembird #1 USB device 005
>> Switched outlet 3 off
Returned 0 in 0 seconds
end: 3.1 power-off (duration 00:00:00) [common]
definitionnamespacecaseleveldurationresultextra
start: 3.2 read-feedback (timeout 00:20:00) [common]
Finalising connection for namespace 'common'
Disconnecting from shell: Finalise
~ #
end: 3.2 read-feedback (duration 00:00:01) [common]
end: 3 finalize (duration 00:00:01) [common]
Cleaning after the job
Cleaning up downloaded image: /var/lib/lava/dispatcher/tmp/475922/tftp-deploy-_jvvb0yh/kernel/Image
Cleaning up downloaded image: /var/lib/lava/dispatcher/tmp/475922/tftp-deploy-_jvvb0yh/dtb/sun55i-a527-radxa-a5e.dtb
Cleaning up downloaded image: /var/lib/lava/dispatcher/tmp/475922/tftp-deploy-_jvvb0yh/modules/modules.tar
Removing override tmp directory at /var/lib/lava/dispatcher/tmp/475922
Removing root tmp directory at /var/lib/lava/dispatcher/tmp/475922
Job finished correctly
definitioncaseresult