lava-dispatcher, installed at version: 2023.10 start: 0 validate Start time: 2024-11-25 13:47:33.678659+00:00 (UTC) Validating that https://github.com/montjoie/lava-healthchecks-binary/blob/master/images/rootfs/buildroot/kci-2019.02-8-gd700ebb99e8f/arm64/base/rootfs.cpio.gz?raw=true exists Validating that https://github.com/montjoie/lava-healthchecks-binary/blob/master/stable/linux-5.3.y/v5.3.10/arm64/defconfig/gcc-8/Image?raw=true exists Validating that https://github.com/montjoie/lava-healthchecks-binary/blob/master/stable/linux-5.3.y/v5.3.10/arm64/defconfig/gcc-8/dtbs/renesas/r8a7795-h3ulcb-kf.dtb?raw=true exists Validating that https://github.com/montjoie/lava-healthchecks-binary/blob/master/stable/linux-5.3.y/v5.3.10/arm64/defconfig/gcc-8/modules.tar.xz?raw=true exists validate duration: 6.21 case: validate
case_id: 4093422
definition: lava
result: pass
start: 1 tftp-deploy (timeout 00:10:00) [common] start: 1.1 download-retry (timeout 00:10:00) [common] start: 1.1.1 http-download (timeout 00:10:00) [common] Not decompressing ramdisk as can be used compressed. downloading https://github.com/montjoie/lava-healthchecks-binary/blob/master/images/rootfs/buildroot/kci-2019.02-8-gd700ebb99e8f/arm64/base/rootfs.cpio.gz?raw=true saving as /var/lib/lava/dispatcher/tmp/37782/tftp-deploy-gswohl4k/ramdisk/rootfs.cpio.gz total size: 4192802 (3 MB) progress 0 % (0 MB) progress 5 % (0 MB) progress 10 % (0 MB) progress 15 % (0 MB) progress 20 % (0 MB) progress 25 % (1 MB) progress 30 % (1 MB) progress 35 % (1 MB) progress 40 % (1 MB) progress 45 % (1 MB) progress 50 % (2 MB) progress 55 % (2 MB) progress 60 % (2 MB) progress 65 % (2 MB) progress 70 % (2 MB) progress 75 % (3 MB) progress 80 % (3 MB) progress 85 % (3 MB) progress 90 % (3 MB) progress 95 % (3 MB) progress 100 % (3 MB) 3 MB downloaded in 1.35 s (2.96 MB/s) end: 1.1.1 http-download (duration 00:00:01) [common] case: http-download
case_id: 4093445
definition: lava
duration: 1.35
extra: ...
level: 1.1.1
namespace: common
result: pass
end: 1.1 download-retry (duration 00:00:01) [common] start: 1.2 download-retry (timeout 00:09:59) [common] start: 1.2.1 http-download (timeout 00:09:59) [common] downloading https://github.com/montjoie/lava-healthchecks-binary/blob/master/stable/linux-5.3.y/v5.3.10/arm64/defconfig/gcc-8/Image?raw=true saving as /var/lib/lava/dispatcher/tmp/37782/tftp-deploy-gswohl4k/kernel/Image total size: 24838656 (23 MB) No compression specified progress 0 % (0 MB) progress 5 % (1 MB) progress 10 % (2 MB) progress 15 % (3 MB) progress 20 % (4 MB) progress 25 % (5 MB) progress 30 % (7 MB) progress 35 % (8 MB) progress 40 % (9 MB) progress 45 % (10 MB) progress 50 % (11 MB) progress 55 % (13 MB) progress 60 % (14 MB) progress 65 % (15 MB) progress 70 % (16 MB) progress 75 % (17 MB) progress 80 % (18 MB) progress 85 % (20 MB) progress 90 % (21 MB) progress 95 % (22 MB) progress 100 % (23 MB) 23 MB downloaded in 3.11 s (7.63 MB/s) end: 1.2.1 http-download (duration 00:00:03) [common] case: http-download
case_id: 4093445
definition: lava
duration: 3.11
extra: ...
level: 1.2.1
namespace: common
result: pass
end: 1.2 download-retry (duration 00:00:03) [common] start: 1.3 download-retry (timeout 00:09:56) [common] start: 1.3.1 http-download (timeout 00:09:56) [common] downloading https://github.com/montjoie/lava-healthchecks-binary/blob/master/stable/linux-5.3.y/v5.3.10/arm64/defconfig/gcc-8/dtbs/renesas/r8a7795-h3ulcb-kf.dtb?raw=true saving as /var/lib/lava/dispatcher/tmp/37782/tftp-deploy-gswohl4k/dtb/r8a7795-h3ulcb-kf.dtb total size: 69103 (0 MB) No compression specified progress 47 % (0 MB) progress 94 % (0 MB) progress 100 % (0 MB) 0 MB downloaded in 0.27 s (0.24 MB/s) end: 1.3.1 http-download (duration 00:00:00) [common] case: http-download
case_id: 4093445
definition: lava
duration: 0.27
extra: ...
level: 1.3.1
namespace: common
result: pass
end: 1.3 download-retry (duration 00:00:00) [common] start: 1.4 download-retry (timeout 00:09:55) [common] start: 1.4.1 http-download (timeout 00:09:55) [common] downloading https://github.com/montjoie/lava-healthchecks-binary/blob/master/stable/linux-5.3.y/v5.3.10/arm64/defconfig/gcc-8/modules.tar.xz?raw=true saving as /var/lib/lava/dispatcher/tmp/37782/tftp-deploy-gswohl4k/modules/modules.tar total size: 5900632 (5 MB) Using unxz to decompress xz progress 0 % (0 MB) progress 5 % (0 MB) progress 10 % (0 MB) progress 15 % (0 MB) progress 20 % (1 MB) progress 25 % (1 MB) progress 30 % (1 MB) progress 35 % (2 MB) progress 40 % (2 MB) progress 45 % (2 MB) progress 50 % (2 MB) progress 55 % (3 MB) progress 60 % (3 MB) progress 65 % (3 MB) progress 70 % (3 MB) progress 75 % (4 MB) progress 80 % (4 MB) progress 85 % (4 MB) progress 90 % (5 MB) progress 95 % (5 MB) progress 100 % (5 MB) 5 MB downloaded in 1.00 s (5.60 MB/s) end: 1.4.1 http-download (duration 00:00:01) [common] case: http-download
case_id: 4093445
definition: lava
duration: 1.01
extra: ...
level: 1.4.1
namespace: common
result: pass
end: 1.4 download-retry (duration 00:00:01) [common] start: 1.5 prepare-tftp-overlay (timeout 00:09:54) [common] start: 1.5.1 extract-nfsrootfs (timeout 00:09:54) [common] end: 1.5.1 extract-nfsrootfs (duration 00:00:00) [common] start: 1.5.2 lava-overlay (timeout 00:09:54) [common] [common] skipped lava-overlay - no test action. end: 1.5.2 lava-overlay (duration 00:00:00) [common] start: 1.5.3 extract-overlay-ramdisk (timeout 00:09:54) [common] end: 1.5.3 extract-overlay-ramdisk (duration 00:00:00) [common] start: 1.5.4 extract-modules (timeout 00:09:54) [common] extracting modules file /var/lib/lava/dispatcher/tmp/37782/tftp-deploy-gswohl4k/modules/modules.tar to /var/lib/lava/dispatcher/tmp/37782/extract-overlay-ramdisk-rjg4pyrc/ramdisk end: 1.5.4 extract-modules (duration 00:00:00) [common] start: 1.5.5 apply-overlay-tftp (timeout 00:09:54) [common] end: 1.5.5 apply-overlay-tftp (duration 00:00:00) [common] start: 1.5.6 prepare-kernel (timeout 00:09:54) [common] start: 1.5.6.1 uboot-prepare-kernel (timeout 00:09:54) [common] end: 1.5.6.1 uboot-prepare-kernel (duration 00:00:00) [common] end: 1.5.6 prepare-kernel (duration 00:00:00) [common] start: 1.5.7 configure-preseed-file (timeout 00:09:54) [common] end: 1.5.7 configure-preseed-file (duration 00:00:00) [common] start: 1.5.8 compress-ramdisk (timeout 00:09:54) [common] Building ramdisk /var/lib/lava/dispatcher/tmp/37782/extract-overlay-ramdisk-rjg4pyrc/ramdisk.cpio containing /var/lib/lava/dispatcher/tmp/37782/extract-overlay-ramdisk-rjg4pyrc/ramdisk >> 85384 blocks Adding RAMdisk u-boot header. mkimage -A arm64 -T ramdisk -C none -d /var/lib/lava/dispatcher/tmp/37782/extract-overlay-ramdisk-rjg4pyrc/ramdisk.cpio.gz /var/lib/lava/dispatcher/tmp/37782/extract-overlay-ramdisk-rjg4pyrc/ramdisk.cpio.gz.uboot output: Image Name: output: Created: Mon Nov 25 13:47:48 2024 output: Image Type: AArch64 Linux RAMDisk Image (uncompressed) output: Data Size: 13002720 Bytes = 12697.97 KiB = 12.40 MiB output: Load Address: 00000000 output: Entry Point: 00000000 output: rename /var/lib/lava/dispatcher/tmp/37782/extract-overlay-ramdisk-rjg4pyrc/ramdisk.cpio.gz.uboot to /var/lib/lava/dispatcher/tmp/37782/tftp-deploy-gswohl4k/ramdisk/ramdisk.cpio.gz.uboot end: 1.5.8 compress-ramdisk (duration 00:00:02) [common] end: 1.5 prepare-tftp-overlay (duration 00:00:03) [common] start: 1.6 lxc-create-udev-rule-action (timeout 00:09:51) [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:09) [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: - {BOOTX}: booti 0x68080000 0x4a000000 0x68000000 - {DTB_ADDR}: 0x68000000 - {DTB}: 37782/tftp-deploy-gswohl4k/dtb/r8a7795-h3ulcb-kf.dtb - {INITRD}: 37782/tftp-deploy-gswohl4k/ramdisk/ramdisk.cpio.gz.uboot - {KERNEL_ADDR}: 0x68080000 - {KERNEL}: 37782/tftp-deploy-gswohl4k/kernel/Image - {LAVA_MAC}: None - {PRESEED_CONFIG}: None - {PRESEED_LOCAL}: None - {RAMDISK_ADDR}: 0x4a000000 - {RAMDISK}: 37782/tftp-deploy-gswohl4k/ramdisk/ramdisk.cpio.gz.uboot - {ROOT_PART}: None - {ROOT}: None - {SERVER_IP}: 192.168.66.1 - {TEE_ADDR}: 0x83000000 - {TEE}: None Parsed boot commands: - setenv autoload no - setenv initrd_high 0xffffffffffffffff - setenv fdt_high 0xffffffffffffffff - dhcp - setenv serverip 192.168.66.1 - tftp 0x68080000 37782/tftp-deploy-gswohl4k/kernel/Image - tftp 0x4a000000 37782/tftp-deploy-gswohl4k/ramdisk/ramdisk.cpio.gz.uboot - setenv initrd_size ${filesize} - tftp 0x68000000 37782/tftp-deploy-gswohl4k/dtb/r8a7795-h3ulcb-kf.dtb - setenv bootargs 'console=ttySC0,115200n8 root=/dev/ram0 console_msg_format=syslog ip=dhcp' - booti 0x68080000 0x4a000000 0x68000000 end: 2.2 bootloader-overlay (duration 00:00:00) [common] case: bootloader-overlay
case_id: 4093446
definition: lava
duration: 0.00
extra: ...
level: 2.2
namespace: common
result: pass
start: 2.3 connect-device (timeout 00:05:00) [common] [common] connect-device Connecting to device using 'telnet 127.0.0.1 63001' 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/local/bin/acme-cli' '-s' '192.168.66.2' 'reset' '2' >> Success >> Success Returned 0 in 1 seconds end: 2.4.1.1 pdu-reboot (duration 00:00:01) [common] case: pdu-reboot
case_id: 4093458
definition: lava
duration: 1.28
extra: ...
level: 2.4.1.1
namespace: common
result: pass
end: 2.4.1 reset-device (duration 00:00:01) [common] start: 2.4.2 bootloader-interrupt (timeout 00:04:59) [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 '^]'. NOTICE: BL2: R-Car Gen3 Initial Program Loa�NOTICE: BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.4.0.0 NOTICE: BL2: PRR is R-Car H3 Ver.3.0 NOTICE: BL2: Board is Starter Kit Premier Rev.2.0 NOTICE: �NOTICE: BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.4.0.0 NOTICE: BL2: PRR is R-Car H3 Ver.3.0 NOTICE: BL2: Board is Starter Kit Premier Rev.2.0 NOTICE: BL2: Boot device is HyperFlash(80MHz) NOTICE: BL2: LCM state is CM NOTICE: AVS setting succeeded. DVFS_SetVID=0x53 NOTICE: BL2: D�NOTICE: BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.4.0.0 NOTICE: BL2: PRR is R-Car H3 Ver.3.0 NOTICE: BL2: Board is Starter Kit Premier Rev.2.0 N�NOTICE: BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.4.0.0 NOTICE: BL2: PRR is R-Car H3 Ver.3.0 NOTICE: BL2: Board is Starter Kit Premier Rev.2.0 NOTICE: BL2: Boot device is HyperFl�NOTICE: BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.4.0.0 NOTICE: BL2: PRR is R-Car H3 Ver.3.0 NOTICE: BL2: Board is Starter Kit Premier Rev.2.0 NOTICE: BL2: Boot device is HyperFlash(80MHz) NOTICE: BL2: LCM state is CM NOTICE: AVS setting succeeded. DVFS_Set�NOTICE: BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.4.0.0 NOTICE: BL2: PRR is R-Car H3 Ver.3.0 NOTICE: BL2: Board is Starter Kit Premier Rev.2.0 NOTICE: BL2: Boot device is HyperFlash(80MHz) NOTICE: BL2: LCM state is CM NOTICE: AVS setting succeeded. DVFS_SetVID=0x53 NOTICE: BL2: DDR3200(rev.0.42) NOTICE: BL2: [COLD_BOOT] NOTICE: BL2: DRAM Split is 4ch(DDR f) NOTICE: BL2: QoS is default setting(rev.0.11) NOTICE: BL2: DRAM refresh interval 1.95 usec NOTICE: BL2: Periodic Write DQ Training NOTICE: BL2: CH0: 400000000 - 47fffffff, 2 GiB NOTICE: BL2: CH1: 500000000 - 57fffffff, 2 GiB NOTICE: BL2: CH2: 600000000 - 67fffffff, 2 GiB NOTICE: BL2: CH3: 700000000 - 77fffffff, 2 GiB NOTICE: BL2: FDT at 0xe6326f78 NOTICE: BL2: v2.9(release):9cdb21f75-dirty NOTICE: BL2: Built : 14:13:42, Oct 24 2024 NOTICE: BL2: Normal boot NOTICE: BL2: dst=0xe6326800 src=0x8180000 len=512(0x200) NOTICE: BL2: dst=0x43f00000 src=0x8180400 len=6144(0x1800) NOTICE: rcar_file_len: len: 0x0003e000 NOTICE: BL2: dst=0x44000000 src=0x81c0000 len=253952(0x3e000) NOTICE: rcar_file_len: len: 0x00200000 NOTICE: BL2: dst=0x44100000 src=0x8200000 len=2097152(0x200000) NOTICE: rcar_file_len: len: 0x00100000 NOTICE: BL2: dst=0x50000000 src=0x8640000 len=1048576(0x100000) NOTICE: BL2: Booting BL31 U-Boot 2020.10 (Oct 24 2024 - 14:13:42 +0000) CPU: Renesas Electronics R8A7795 rev 3.0 Model: Renesas H3ULCB board based on r8a77950 DRAM: 7.9 GiB RAM Configuration: Bank #0: 0x048000000 - 0x0bfffffff, 1.9 GiB Bank #1: 0x500000000 - 0x57fffffff, 2 GiB Bank #2: 0x600000000 - 0x67fffffff, 2 GiB Bank #3: 0x700000000 - 0x77fffffff, 2 GiB MMC: sd@ee100000: 0, sd@ee140000: 1 Loading Environment from MMC... OK In: serial@e6e88000 Out: serial@e6e88000 Err: serial@e6e88000 Net: eth0: ethernet@e6800000 Hit any key to stop autoboot: end: 2.4.2 bootloader-interrupt (duration 00:00:02) [common] start: 2.4.3 bootloader-commands (timeout 00:04:57) [common] Setting prompt string to ['=>'] bootloader-commands: Wait for prompt ['=>'] (timeout 00:04:57) 3  0 Setting prompt string to ['=>', 'Resetting CPU', 'Must RESET board to recover', 'TIMEOUT', 'Retry count exceeded', 'Retry time exceeded; starting again', 'ERROR: 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'] 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', 'ERROR: 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'] (timeout 00:04:57) setenv autoload no setenv initrd_high 0xffffffffffffffff => setenv initrd_high 0xffffffffffffffff bootloader-commands: Wait for prompt ['=>', 'Resetting CPU', 'Must RESET board to recover', 'TIMEOUT', 'Retry count exceeded', 'Retry time exceeded; starting again', 'ERROR: 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'] (timeout 00:04:57) setenv initrd_high 0xffffffffffffffff setenv fdt_high 0xffffffffffffffff => setenv fdt_high 0xffffffffffffffff bootloader-commands: Wait for prompt ['=>', 'Resetting CPU', 'Must RESET board to recover', 'TIMEOUT', 'Retry count exceeded', 'Retry time exceeded; starting again', 'ERROR: 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'] (timeout 00:04:57) setenv fdt_high 0xffffffffffffffff dhcp => dhcp bootloader-commands: Wait for prompt ['=>', 'Resetting CPU', 'Must RESET board to recover', 'TIMEOUT', 'Retry count exceeded', 'Retry time exceeded; starting again', 'ERROR: 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'] (timeout 00:04:57) dhcp ethernet@e6800000 Waiting for PHY auto negotiation to complete....... done BOOTP broadcast 1 BOOTP broadcast 2 BOOTP broadcast 3 DHCP client bound to address 192.168.66.15 (1012 ms) setenv serverip 192.168.66.1 => setenv serverip 192.168.66.1 bootloader-commands: Wait for prompt ['=>', 'Resetting CPU', 'Must RESET board to recover', 'TIMEOUT', 'Retry count exceeded', 'Retry time exceeded; starting again', 'ERROR: 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'] (timeout 00:04:52) setenv serverip 192.168.66.1 tftp 0x68080000 37782/tftp-deploy-gswohl4k/kernel/Image => tftp 0x68080000 37782/tftp-deploy-gswohl4k/kernel/Image bootloader-commands: Wait for prompt ['=>', 'Resetting CPU', 'Must RESET board to recover', 'TIMEOUT', 'Retry count exceeded', 'Retry time exceeded; starting again', 'ERROR: 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'] (timeout 00:04:52) tftp 0x68080000 37782/tftp-deploy-gswohl4k/kernel/Image Using ethernet@e6800000 device TFTP from server 192.168.66.1; our IP address is 192.168.66.15 Filename '37782/tftp-deploy-gswohl4k/kernel/Image'. Load address: 0x68080000 Loading: *################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ### 3.1 MiB/s done Bytes transferred = 24838656 (17b0200 hex) tftp 0x4a000000 37782/tftp-deploy-gswohl4k/ramdisk/ramdisk.cpio.gz.uboot => tftp 0x4a000000 37782/tftp-deploy-gswohl4k/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', 'ERROR: 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'] (timeout 00:04:44) tftp 0x4a000000 37782/tftp-deploy-gswohl4k/ramdisk/ramdisk.cpio.gz.uboot Using ethernet@e6800000 device TFTP from server 192.168.66.1; our IP address is 192.168.66.15 Filename '37782/tftp-deploy-gswohl4k/ramdisk/ramdisk.cpio.gz.uboot'. Load address: 0x4a000000 Loading: *################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ######################################### 2.7 MiB/s done Bytes transferred = 13002784 (c66820 hex) 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', 'ERROR: 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'] (timeout 00:04:40) setenv initrd_size ${filesize} tftp 0x68000000 37782/tftp-deploy-gswohl4k/dtb/r8a7795-h3ulcb-kf.dtb => tftp 0x68000000 37782/tftp-deploy-gswohl4k/dtb/r8a7795-h3ulcb-kf.dtb bootloader-commands: Wait for prompt ['=>', 'Resetting CPU', 'Must RESET board to recover', 'TIMEOUT', 'Retry count exceeded', 'Retry time exceeded; starting again', 'ERROR: 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'] (timeout 00:04:40) tftp 0x68000000 37782/tftp-deploy-gswohl4k/dtb/r8a7795-h3ulcb-kf.dtb Using ethernet@e6800000 device TFTP from server 192.168.66.1; our IP address is 192.168.66.15 Filename '37782/tftp-deploy-gswohl4k/dtb/r8a7795-h3ulcb-kf.dtb'. Load address: 0x68000000 Loading: *##### 2.6 MiB/s done Bytes transferred = 69103 (10def hex) setenv bootargs 'console=ttySC0,115200n8 root=/dev/ram0 console_msg_format=syslog ip=dhcp' =>setenv bootargs 'console=ttySC0,115200n8 root=/dev/ram0 console_msg_format=syslog ip=dhcp' bootloader-commands: Wait for prompt ['=>', 'Resetting CPU', 'Must RESET board to recover', 'TIMEOUT', 'Retry count exceeded', 'Retry time exceeded; starting again', 'ERROR: 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'] (timeout 00:04:40) setenv bootargs 'console=ttySC0,115200n8 root=/dev/ram0 console_msg_format=syslog ip=dhcp' booti 0x68080000 0x4a000000 0x68000000 => booti 0x68080000 0x4a000000 0x68000000 Setting prompt string to ['Starting kernel', 'Resetting CPU', 'Must RESET board to recover', 'TIMEOUT', 'Retry count exceeded', 'Retry time exceeded; starting again', 'ERROR: 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'] bootloader-commands: Wait for prompt ['Starting kernel', 'Resetting CPU', 'Must RESET board to recover', 'TIMEOUT', 'Retry count exceeded', 'Retry time exceeded; starting again', 'ERROR: 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'] (timeout 00:04:39) booti 0x68080000 0x4a000000 0x68000000 ## Loading init Ramdisk from Legacy Image at 4a000000 ... Image Name: Image Type: AArch64 Linux RAMDisk Image (uncompressed) Data Size: 13002720 Bytes = 12.4 MiB Load Address: 00000000 Entry Point: 00000000 Verifying Checksum ... OK ## Flattened Device Tree blob at 68000000 Booting using the fdt blob at 0x68000000 Using Device Tree in place at 0000000068000000, end 0000000068013dee Starting kernel ... end: 2.4.3 bootloader-commands (duration 00:00:18) [common] start: 2.4.4 auto-login-action (timeout 00:04:39) [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', 'ERROR: 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'] 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', 'ERROR: 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'] (timeout 00:05:00) <6>[ 0.000000] Booting Linux on physical CPU 0x0000000000 [0x411fd073] <5>[ 0.000000] Linux version 5.3.10 (KernelCI@149ca0145d59) (gcc version 8.3.0 (Debian 8.3.0-2)) #1 SMP PREEMPT Sun Nov 10 13:41:13 UTC 2019 start: 2.4.4.1 login-action (timeout 00:04:38) [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. 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'] Using line separator: #'\n'# No login prompt set. Parsing kernel messages ['-\\[ cut here \\]', 'Unhandled fault', 'BUG: KCSAN:', 'BUG: KASAN:', 'BUG: KFENCE:', 'Oops(?: -|:)', 'WARNING:', '(kernel BUG at|BUG:)', 'invalid opcode:', 'Kernel panic - not syncing', '/ #', 'Login timed out', 'Login incorrect'] [login-action] Waiting for messages, (timeout 00:04:38) <6>[ 0.000000] Machine model: Renesas H3ULCB Kingfisher board based on r8a7795 ES2.0+ <6>[ 0.000000] efi: Getting EFI parameters from FDT: <6>[ 0.000000] efi: UEFI not found. <6>[ 0.000000] cma: Reserved 32 MiB at 0x00000000be000000 <6>[ 0.000000] NUMA: No NUMA configuration found <6>[ 0.000000] NUMA: Faking a node at [mem 0x0000000048000000-0x000000077fffffff] <6>[ 0.000000] NUMA: NODE_DATA [mem 0x77efdc840-0x77efddfff] <6>[ 0.000000] Zone ranges: <6>[ 0.000000] DMA32 [mem 0x0000000048000000-0x00000000ffffffff] <6>[ 0.000000] Normal [mem 0x0000000100000000-0x000000077fffffff] <6>[ 0.000000] Movable zone start for each node <6>[ 0.000000] Early memory node ranges <6>[ 0.000000] node 0: [mem 0x0000000048000000-0x00000000bfffffff] <6>[ 0.000000] node 0: [mem 0x0000000500000000-0x000000057fffffff] <6>[ 0.000000] node 0: [mem 0x0000000600000000-0x000000067fffffff] <6>[ 0.000000] node 0: [mem 0x0000000700000000-0x000000077fffffff] <6>[ 0.000000] Initmem setup node 0 [mem 0x0000000048000000-0x000000077fffffff] <6>[ 0.000000] psci: probing for conduit method from DT. <6>[ 0.000000] psci: PSCIv1.1 detected in firmware. <6>[ 0.000000] psci: Using standard PSCI v0.2 function IDs <6>[ 0.000000] psci: Trusted OS resident on physical CPU 0x0 <6>[ 0.000000] psci: SMC Calling Convention v1.0 <6>[ 0.000000] percpu: Embedded 22 pages/cpu s52632 r8192 d29288 u90112 <6>[ 0.000000] Detected PIPT I-cache on CPU0 <6>[ 0.000000] CPU features: detected: EL2 vector hardening <4>[ 0.000000] ARM_SMCCC_ARCH_WORKAROUND_1 missing from firmware <6>[ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 2032128 <6>[ 0.000000] Policy zone: Normal <5>[ 0.000000] Kernel command line: console=ttySC0,115200n8 root=/dev/ram0 console_msg_format=syslog ip=dhcp <6>[ 0.000000] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, linear) <6>[ 0.000000] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) <6>[ 0.000000] mem auto-init: stack:off, heap alloc:off, heap free:off <6>[ 0.000000] software IO TLB: mapped [mem 0xba000000-0xbe000000] (64MB) <6>[ 0.000000] Memory: 7960592K/8257536K available (11388K kernel code, 1792K rwdata, 6024K rodata, 4992K init, 447K bss, 264176K reserved, 32768K cma-reserved) <6>[ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=8, Nodes=1 <6>[ 0.000000] rcu: Preemptible hierarchical RCU implementation. <6>[ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=256 to nr_cpu_ids=8. <6>[ 0.000000] Tasks RCU enabled. <6>[ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies. <6>[ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=8 <6>[ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 <4>[ 0.000000] GIC: Adjusting CPU interface base to 0x00000000f102f000 <6>[ 0.000000] GIC: Using split EOI/Deactivate mode <5>[ 0.000000] random: get_random_bytes called from start_kernel+0x300/0x494 with crng_init=0 <6>[ 0.000000] arch_timer: cp15 timer(s) running at 8.33MHz (phys). <6>[ 0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x1ec02923e, max_idle_ns: 440795202125 ns <6>[ 0.000002] sched_clock: 56 bits at 8MHz, resolution 120ns, wraps every 2199023255496ns <6>[ 0.000107] Console: colour dummy device 80x25 <6>[ 0.000172] Calibrating delay loop (skipped), value calculated using timer frequency.. 16.66 BogoMIPS (lpj=33333) <6>[ 0.000180] pid_max: default: 32768 minimum: 301 <6>[ 0.000234] LSM: Security Framework initializing <6>[ 0.000311] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, linear) <6>[ 0.000356] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, linear) <6>[ 0.023975] ASID allocator initialised with 32768 entries <6>[ 0.031965] rcu: Hierarchical SRCU implementation. <6>[ 0.040983] Detected Renesas R-Car Gen3 r8a7795 ES3.0 <6>[ 0.042224] EFI services will not be available. <6>[ 0.047988] smp: Bringing up secondary CPUs ... <6>[ 0.080201] Detected PIPT I-cache on CPU1 <6>[ 0.080236] CPU1: Booted secondary processor 0x0000000001 [0x411fd073] <6>[ 0.112203] Detected PIPT I-cache on CPU2 <6>[ 0.112222] CPU2: Booted secondary processor 0x0000000002 [0x411fd073] <6>[ 0.144237] Detected PIPT I-cache on CPU3 <6>[ 0.144257] CPU3: Booted secondary processor 0x0000000003 [0x411fd073] <6>[ 0.176280] CPU features: detected: ARM erratum 845719 <6>[ 0.176291] Detected VIPT I-cache on CPU4 <6>[ 0.176329] CPU4: Booted secondary processor 0x0000000100 [0x410fd034] <6>[ 0.208303] Detected VIPT I-cache on CPU5 <6>[ 0.208326] CPU5: Booted secondary processor 0x0000000101 [0x410fd034] <6>[ 0.240338] Detected VIPT I-cache on CPU6 <6>[ 0.240361] CPU6: Booted secondary processor 0x0000000102 [0x410fd034] <6>[ 0.272376] Detected VIPT I-cache on CPU7 <6>[ 0.272399] CPU7: Booted secondary processor 0x0000000103 [0x410fd034] <6>[ 0.272475] smp: Brought up 1 node, 8 CPUs <6>[ 0.272497] SMP: Total of 8 processors activated. <6>[ 0.272502] CPU features: detected: 32-bit EL0 Support <6>[ 0.272507] CPU features: detected: CRC32 instructions <6>[ 0.273355] CPU: All CPU(s) started at EL2 <6>[ 0.273379] alternatives: patching kernel code <6>[ 0.274442] devtmpfs: initialized <6>[ 0.279938] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns <6>[ 0.279952] futex hash table entries: 2048 (order: 5, 131072 bytes, linear) <6>[ 0.280631] pinctrl core: initialized pinctrl subsystem <6>[ 0.281775] DMI not present or invalid. <6>[ 0.282016] NET: Registered protocol family 16 <6>[ 0.282301] audit: initializing netlink subsys (disabled) <5>[ 0.282445] audit: type=2000 audit(0.280:1): state=initialized audit_enabled=0 res=1 <6>[ 0.283375] cpuidle: using governor menu <6>[ 0.283513] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. <6>[ 0.284129] DMA: preallocated 256 KiB pool for atomic allocations <6>[ 0.284989] Serial: AMBA PL011 UART driver <6>[ 0.287469] sh-pfc e6060000.pin-controller: r8a77951_pfc support registered <6>[ 0.305162] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages <6>[ 0.305170] HugeTLB registered 32.0 MiB page size, pre-allocated 0 pages <6>[ 0.305175] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages <6>[ 0.305179] HugeTLB registered 64.0 KiB page size, pre-allocated 0 pages <6>[ 0.306572] cryptd: max_cpu_qlen set to 1000 <6>[ 0.309682] ACPI: Interpreter disabled. <6>[ 0.313217] vgaarb: loaded <5>[ 0.313411] SCSI subsystem initialized <6>[ 0.313631] usbcore: registered new interface driver usbfs <6>[ 0.313653] usbcore: registered new interface driver hub <6>[ 0.313696] usbcore: registered new device driver usb <6>[ 0.314532] i2c-sh_mobile e60b0000.i2c: I2C adapter 7, bus speed 400000 Hz <6>[ 0.314781] pps_core: LinuxPPS API ver. 1 registered <6>[ 0.314786] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <[email protected]> <6>[ 0.314795] PTP clock support registered <6>[ 0.314911] EDAC MC: Ver: 3.0.0 <6>[ 0.316039] FPGA manager framework <6>[ 0.316088] Advanced Linux Sound Architecture Driver Initialized. <6>[ 0.316536] clocksource: Switched to clocksource arch_sys_counter <5>[ 0.316640] VFS: Disk quotas dquot_6.6.0 <6>[ 0.316682] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) <6>[ 0.316799] pnp: PnP ACPI: disabled <6>[ 0.319642] thermal_sys: Registered thermal governor 'step_wise' <6>[ 0.319644] thermal_sys: Registered thermal governor 'power_allocator' <6>[ 0.320146] NET: Registered protocol family 2 <6>[ 0.320388] tcp_listen_portaddr_hash hash table entries: 4096 (order: 4, 65536 bytes, linear) <6>[ 0.320451] TCP established hash table entries: 65536 (order: 7, 524288 bytes, linear) <6>[ 0.320741] TCP bind hash table entries: 65536 (order: 8, 1048576 bytes, linear) <6>[ 0.321315] TCP: Hash tables configured (established 65536 bind 65536) <6>[ 0.321431] UDP hash table entries: 4096 (order: 5, 131072 bytes, linear) <6>[ 0.321537] UDP-Lite hash table entries: 4096 (order: 5, 131072 bytes, linear) <6>[ 0.321744] NET: Registered protocol family 1 <6>[ 0.322092] RPC: Registered named UNIX socket transport module. <6>[ 0.322097] RPC: Registered udp transport module. <6>[ 0.322100] RPC: Registered tcp transport module. <6>[ 0.322103] RPC: Registered tcp NFSv4.1 backchannel transport module. <6>[ 0.322112] PCI: CLS 0 bytes, default 64 <6>[ 0.322214] Unpacking initramfs... <6>[ 0.760720] Freeing initrd memory: 12692K <6>[ 0.761545] hw perfevents: enabled with armv8_cortex_a53 PMU driver, 7 counters available <6>[ 0.761796] hw perfevents: enabled with armv8_cortex_a57 PMU driver, 7 counters available <6>[ 0.762212] kvm [1]: IPA Size Limit: 40bits <6>[ 0.762680] kvm [1]: vgic interrupt IRQ1 <6>[ 0.762827] kvm [1]: Hyp mode initialized successfully <5>[ 0.895271] Initialise system trusted keyrings <6>[ 0.895375] workingset: timestamp_bits=44 max_order=21 bucket_order=0 <6>[ 0.898587] squashfs: version 4.0 (2009/01/31) Phillip Lougher <5>[ 0.898991] NFS: Registering the id_resolver key type <5>[ 0.899009] Key type id_resolver registered <5>[ 0.899013] Key type id_legacy registered <6>[ 0.899022] nfs4filelayout_init: NFSv4 File Layout Driver Registering... <6>[ 0.899111] 9p: Installing v9fs 9p2000 file system support <5>[ 0.913861] Key type asymmetric registered <5>[ 0.913869] Asymmetric key parser 'x509' registered <6>[ 0.913909] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 245) <6>[ 0.913915] io scheduler mq-deadline registered <6>[ 0.913918] io scheduler kyber registered <6>[ 0.923335] gpio_rcar e6050000.gpio: driving 16 GPIOs <6>[ 0.923520] gpio_rcar e6051000.gpio: driving 29 GPIOs <6>[ 0.923684] gpio_rcar e6052000.gpio: driving 15 GPIOs <6>[ 0.923841] gpio_rcar e6053000.gpio: driving 16 GPIOs <6>[ 0.923996] gpio_rcar e6054000.gpio: driving 18 GPIOs <6>[ 0.924148] gpio_rcar e6055000.gpio: driving 26 GPIOs <6>[ 0.924319] gpio_rcar e6055400.gpio: driving 32 GPIOs <6>[ 0.924468] gpio_rcar e6055800.gpio: driving 4 GPIOs <6>[ 0.925805] rcar-pcie fe000000.pcie: host bridge /soc/pcie@fe000000 ranges: <6>[ 0.925831] rcar-pcie fe000000.pcie: IO 0xfe100000..0xfe1fffff -> 0x00000000 <6>[ 0.925848] rcar-pcie fe000000.pcie: MEM 0xfe200000..0xfe3fffff -> 0xfe200000 <6>[ 0.925862] rcar-pcie fe000000.pcie: MEM 0x30000000..0x37ffffff -> 0x30000000 <6>[ 0.925871] rcar-pcie fe000000.pcie: MEM 0x38000000..0x3fffffff -> 0x38000000 <6>[ 0.992160] rcar-pcie fe000000.pcie: PCIe link down <6>[ 0.992293] rcar-pcie ee800000.pcie: host bridge /soc/pcie@ee800000 ranges: <6>[ 0.992310] rcar-pcie ee800000.pcie: IO 0xee900000..0xee9fffff -> 0x00000000 <6>[ 0.992324] rcar-pcie ee800000.pcie: MEM 0xeea00000..0xeebfffff -> 0xeea00000 <6>[ 0.992337] rcar-pcie ee800000.pcie: MEM 0xc0000000..0xc7ffffff -> 0xc0000000 <6>[ 0.992346] rcar-pcie ee800000.pcie: MEM 0xc8000000..0xcfffffff -> 0xc8000000 <6>[ 1.056164] rcar-pcie ee800000.pcie: PCIe link down <4>[ 1.057971] EINJ: ACPI disabled. <6>[ 1.068085] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled <6>[ 1.069825] SuperH (H)SCI(F) driver initialized <6>[ 1.070150] e6540000.serial: ttySC1 at MMIO 0xe6540000 (irq = 34, base_baud = 0) is a hscif <6>[ 1.070648] e6e68000.serial: ttySC2 at MMIO 0xe6e68000 (irq = 121, base_baud = 0) is a scif <6>[ 1.070986] e6e88000.serial: ttySC0 at MMIO 0xe6e88000 (irq = 122, base_baud = 0) is a scif <6>[ 2.138372] printk: console [ttySC0] enabled <6>[ 2.143552] msm_serial: driver initialized <6>[ 2.154352] loop: module loaded <6>[ 2.162065] libphy: Fixed MDIO Bus: probed <6>[ 2.166565] tun: Universal TUN/TAP device driver, 1.6 <6>[ 2.172599] thunder_xcv, ver 1.0 <6>[ 2.176100] thunder_bgx, ver 1.0 <6>[ 2.179607] nicpf, ver 1.0 <6>[ 2.183129] hclge is initializing <6>[ 2.186706] hns3: Hisilicon Ethernet Network Driver for Hip08 Family - version <6>[ 2.194186] hns3: Copyright (c) 2017 Huawei Corporation. <6>[ 2.199786] e1000e: Intel(R) PRO/1000 Network Driver - 3.2.6-k <6>[ 2.205877] e1000e: Copyright(c) 1999 - 2015 Intel Corporation. <6>[ 2.212073] igb: Intel(R) Gigabit Ethernet Network Driver - version 5.6.0-k <6>[ 2.219293] igb: Copyright (c) 2007-2014 Intel Corporation. <6>[ 2.225140] igbvf: Intel(R) Gigabit Virtual Function Network Driver - version 2.4.0-k <6>[ 2.233228] igbvf: Copyright (c) 2009 - 2012 Intel Corporation. <6>[ 2.239709] sky2: driver version 1.30 <6>[ 2.244568] VFIO - User Level meta-driver version: 0.3 <6>[ 2.251295] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver <6>[ 2.258091] ehci-pci: EHCI PCI platform driver <6>[ 2.262813] ehci-platform: EHCI generic platform driver <6>[ 2.292593] ehci-platform ee080100.usb: EHCI Host Controller <6>[ 2.298529] ehci-platform ee080100.usb: new USB bus registered, assigned bus number 1 <6>[ 2.306695] ehci-platform ee080100.usb: irq 158, io mem 0xee080100 <6>[ 2.328545] ehci-platform ee080100.usb: USB 2.0 started, EHCI 1.10 <6>[ 2.335331] hub 1-0:1.0: USB hub found <6>[ 2.339357] hub 1-0:1.0: 1 port detected <6>[ 2.343811] ehci-platform ee0a0100.usb: EHCI Host Controller <6>[ 2.349741] ehci-platform ee0a0100.usb: new USB bus registered, assigned bus number 2 <6>[ 2.357881] ehci-platform ee0a0100.usb: irq 159, io mem 0xee0a0100 <6>[ 2.376546] ehci-platform ee0a0100.usb: USB 2.0 started, EHCI 1.10 <6>[ 2.383231] hub 2-0:1.0: USB hub found <6>[ 2.387259] hub 2-0:1.0: 1 port detected <6>[ 2.391651] ehci-orion: EHCI orion driver <6>[ 2.396081] ehci-exynos: EHCI EXYNOS driver <6>[ 2.400613] ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver <6>[ 2.407064] ohci-pci: OHCI PCI platform driver <6>[ 2.411805] ohci-platform: OHCI generic platform driver <6>[ 2.417434] ohci-platform ee080000.usb: Generic Platform OHCI controller <6>[ 2.424406] ohci-platform ee080000.usb: new USB bus registered, assigned bus number 3 <6>[ 2.432548] ohci-platform ee080000.usb: irq 158, io mem 0xee080000 <6>[ 2.527479] hub 3-0:1.0: USB hub found <6>[ 2.531507] hub 3-0:1.0: 1 port detected <6>[ 2.535899] ohci-platform ee0a0000.usb: Generic Platform OHCI controller <6>[ 2.542869] ohci-platform ee0a0000.usb: new USB bus registered, assigned bus number 4 <6>[ 2.550989] ohci-platform ee0a0000.usb: irq 159, io mem 0xee0a0000 <6>[ 2.647457] hub 4-0:1.0: USB hub found <6>[ 2.651487] hub 4-0:1.0: 1 port detected <6>[ 2.655874] ohci-exynos: OHCI EXYNOS driver <6>[ 2.660697] xhci-hcd ee000000.usb: xHCI Host Controller <6>[ 2.666189] xhci-hcd ee000000.usb: new USB bus registered, assigned bus number 5 <4>[ 2.673893] xhci-hcd ee000000.usb: Direct firmware load for r8a779x_usb3_v3.dlmem failed with error -2 <3>[ 2.683471] xhci-hcd ee000000.usb: can't setup: -2 <6>[ 2.688536] xhci-hcd ee000000.usb: USB bus 5 deregistered <4>[ 2.694225] xhci-hcd: probe of ee000000.usb failed with error -2 <6>[ 2.700771] usbcore: registered new interface driver usb-storage <6>[ 2.709408] i2c /dev entries driver <6>[ 2.720993] cs2000-cp 2-004f: revision - C1 <6>[ 2.725452] usb 2-1: new high-speed USB device number 2 using ehci-platform <4>[ 2.732876] pca953x 2-0074: 2-0074 supply vcc not found, using dummy regulator <6>[ 2.742526] GPIO line 340 (Audio_Out_OFF) hogged as output/high <6>[ 2.749404] GPIO line 345 (SD WiFi mux) hogged as output/low <6>[ 2.756011] GPIO line 346 (HUB pwen) hogged as output/high <6>[ 2.762448] GPIO line 347 (HUB rst) hogged as output/high <6>[ 2.769693] GPIO line 348 (OTG OFFVBUSn) hogged as output/low <6>[ 2.776390] GPIO line 349 (OTG EXTLPn) hogged as output/high <6>[ 2.782999] GPIO line 355 (SND_RST) hogged as output/high <4>[ 2.789543] pca953x 2-0075: 2-0075 supply vcc not found, using dummy regulator <6>[ 2.799253] i2c-rcar e6510000.i2c: probed <4>[ 2.803949] pca953x 4-0076: 4-0076 supply vcc not found, using dummy regulator <4>[ 2.812477] pca953x 4-0077: 4-0077 supply vcc not found, using dummy regulator <6>[ 2.820963] i2c-rcar e66d8000.i2c: probed <6>[ 2.826326] i2c i2c-2: Added multiplexed i2c bus 8 <6>[ 2.831488] i2c i2c-2: Added multiplexed i2c bus 9 <6>[ 2.836627] i2c i2c-2: Added multiplexed i2c bus 10 <6>[ 2.841862] i2c i2c-2: Added multiplexed i2c bus 11 <6>[ 2.847087] i2c i2c-2: Added multiplexed i2c bus 12 <6>[ 2.852313] i2c i2c-2: Added multiplexed i2c bus 13 <6>[ 2.857557] i2c i2c-2: Added multiplexed i2c bus 14 <6>[ 2.862881] i2c i2c-2: Added multiplexed i2c bus 15 <6>[ 2.868024] pca954x 2-0071: registered 8 multiplexed busses for I2C switch pca9548 <6>[ 2.876190] i2c i2c-4: Added multiplexed i2c bus 16 <6>[ 2.881421] i2c i2c-4: Added multiplexed i2c bus 17 <6>[ 2.886649] i2c i2c-4: Added multiplexed i2c bus 18 <6>[ 2.891898] i2c i2c-4: Added multiplexed i2c bus 19 <6>[ 2.897166] i2c i2c-4: Added multiplexed i2c bus 20 <6>[ 2.902421] i2c i2c-4: Added multiplexed i2c bus 21 <6>[ 2.907679] i2c i2c-4: Added multiplexed i2c bus 22 <6>[ 2.912938] i2c i2c-4: Added multiplexed i2c bus 23 <6>[ 2.918111] pca954x 4-0071: registered 8 multiplexed busses for I2C switch pca9548 <6>[ 2.932005] rcar_gen3_thermal e6198000.thermal: TSC0: Loaded 1 trip points <6>[ 2.943282] rcar_gen3_thermal e6198000.thermal: TSC1: Loaded 1 trip points <6>[ 2.954292] rcar_gen3_thermal e6198000.thermal: TSC2: Loaded 2 trip points <4>[ 2.964047] cpufreq: cpufreq_online: CPU0: Running at unlisted freq: 1499999 KHz <4>[ 2.971739] cpufreq: cpufreq_online: CPU0: Unlisted initial frequency changed to: 1500000 KHz <4>[ 2.980873] cpufreq: cpufreq_online: CPU4: Running at unlisted freq: 1199999 KHz <4>[ 2.988539] cpufreq: cpufreq_online: CPU4: Unlisted initial frequency changed to: 1200000 KHz <6>[ 2.998162] sdhci: Secure Digital Host Controller Interface driver <6>[ 3.004614] sdhci: Copyright(c) Pierre Ossman <6>[ 3.009827] renesas_sdhi_internal_dmac ee100000.sd: Got CD GPIO <6>[ 3.064964] renesas_sdhi_internal_dmac ee140000.sd: mmc0 base at 0xee140000 max clock rate 200 MHz <6>[ 3.074773] Synopsys Designware Multimedia Card Interface Driver <6>[ 3.081922] sdhci-pltfm: SDHCI platform and OF driver helper <6>[ 3.089468] ledtrig-cpu: registered to indicate activity on CPUs <6>[ 3.096793] usbcore: registered new interface driver usbhid <6>[ 3.102625] usbhid: USB HID core driver <5>[ 3.107481] random: fast init done <6>[ 3.108941] optee: probing for conduit method from DT. <6>[ 3.116561] optee: revision 3.22 (314ecf9d) <6>[ 3.116753] optee: dynamic shared memory is enabled <3>[ 3.126471] optee: PTA_CMD_GET_DEVICES invoke function err: ffff0006 <6>[ 3.141428] NET: Registered protocol family 17 <6>[ 3.146264] 9pnet: Installing 9P2000 support <5>[ 3.150854] Key type dns_resolver registered <6>[ 3.155727] registered taskstats version 1 <5>[ 3.160111] Loading compiled-in X.509 certificates <6>[ 3.171969] renesas_irqc e61c0000.interrupt-controller: driving 6 irqs <6>[ 3.186013] bd9571mwv 7-0030: Device: BD9571MWV rev. 4 <6>[ 3.195860] renesas_sdhi_internal_dmac ee100000.sd: Got CD GPIO <6>[ 3.211254] mmc0: new HS400 MMC card at address 0001 <6>[ 3.216897] mmcblk0: mmc0:0001 DJUD4R 119 GiB <6>[ 3.221767] mmcblk0boot0: mmc0:0001 DJUD4R partition 1 31.9 MiB <6>[ 3.228101] mmcblk0boot1: mmc0:0001 DJUD4R partition 2 31.9 MiB <6>[ 3.234377] mmcblk0rpmb: mmc0:0001 DJUD4R partition 3 4.00 MiB, chardev (237:0) <6>[ 3.250553] renesas_sdhi_internal_dmac ee100000.sd: mmc1 base at 0xee100000 max clock rate 200 MHz <6>[ 3.386655] renesas_sdhi_internal_dmac ee160000.sd: mmc2 base at 0xee160000 max clock rate 26 MHz <4>[ 3.399225] rcar-dmac e6700000.dma-controller: ignoring dependency for device, assuming no driver <4>[ 3.410435] rcar-dmac e7300000.dma-controller: ignoring dependency for device, assuming no driver <4>[ 3.421574] rcar-dmac e7310000.dma-controller: ignoring dependency for device, assuming no driver <4>[ 3.431245] renesas_sdhi_internal_dmac ee160000.sd: card claims to support voltages below defined range <4>[ 3.432655] rcar-dmac ec700000.dma-controller: ignoring dependency for device, assuming no driver <4>[ 3.452010] rcar-dmac ec720000.dma-controller: ignoring dependency for device, assuming no driver <6>[ 3.454252] mmc2: new high speed SDIO card at address 0001 <4>[ 3.463659] ravb e6800000.ethernet: ignoring dependency for device, assuming no driver <6>[ 3.475432] libphy: ravb_mii: probed <6>[ 3.485717] ravb e6800000.ethernet eth0: Base address at 0xe6800000, aa:bb:cc:dd:ee:77, IRQ 116. <6>[ 3.495908] input: keyboard as /devices/platform/keyboard/input/input0 <6>[ 3.502909] hctosys: unable to open rtc device (rtc0) <6>[ 3.583634] Micrel KSZ9031 Gigabit PHY e6800000.ethernet-ffffffff:00: attached PHY driver [Micrel KSZ9031 Gigabit PHY] (mii_bus:phy_addr=e6800000.ethernet-ffffffff:00, irq=189) <6>[ 5.941180] ravb e6800000.ethernet eth0: Link is Up - 100Mbps/Full - flow control off <5>[ 5.968542] Sending DHCP requests .., OK <6>[ 8.372744] IP-Config: Got DHCP answer from 192.168.66.1, my address is 192.168.66.15 <6>[ 8.380836] IP-Config: Complete: <6>[ 8.384321] device=eth0, hwaddr=aa:bb:cc:dd:ee:77, ipaddr=192.168.66.15, mask=255.255.255.0, gw=192.168.66.1 <6>[ 8.394839] host=192.168.66.15, domain=example.org, nis-domain=(none) <6>[ 8.401971] bootserver=0.0.0.0, rootserver=0.0.0.0, rootpath= <6>[ 8.401973] nameserver0=10.1.1.1 <6>[ 8.412687] ALSA device list: <6>[ 8.415913] No soundcards found. <6>[ 8.422319] Freeing unused kernel memory: 4992K <6>[ 8.432603] Run /init as init process Starting syslogd: OK Starting klogd: OK Populating /dev using udev: <30>[ 8.464704] udevd[303]: starting version 3.2.7 <5>[ 8.470065] random: udevd: uninitialized urandom read (16 bytes read) <5>[ 8.476889] random: udevd: uninitialized urandom read (16 bytes read) <5>[ 8.483614] random: udevd: uninitialized urandom read (16 bytes read) <27>[ 8.491391] udevd[303]: specified group 'kvm' unknown <30>[ 8.499655] udevd[304]: starting eudev-3.2.7 <6>[ 8.610819] renesas_usbhs e6590000.usb: no transceiver found <6>[ 8.617031] renesas_usbhs e6590000.usb: gadget probed <6>[ 8.623292] renesas_usbhs e6590000.usb: probed <6>[ 8.642066] rcar_sound ec500000.sound: probed <6>[ 8.646994] mc: Linux media interface: v0.10 <4>[ 8.647326] rcar-fcp fe950000.fcp: ignoring dependency for device, assuming no driver <4>[ 8.660020] rcar-fcp fe951000.fcp: ignoring dependency for device, assuming no driver <6>[ 8.660373] videodev: Linux video capture interface: v2.00 <4>[ 8.668343] rcar-fcp fe96f000.fcp: ignoring dependency for device, assuming no driver <4>[ 8.682397] rcar-fcp fe92f000.fcp: ignoring dependency for device, assuming no driver <4>[ 8.692338] rcar-fcp fe9af000.fcp: ignoring dependency for device, assuming no driver <4>[ 8.702017] rcar-fcp fe9bf000.fcp: ignoring dependency for device, assuming no driver <4>[ 8.711166] rcar-fcp fea27000.fcp: ignoring dependency for device, assuming no driver <4>[ 8.720462] rcar-fcp fea2f000.fcp: ignoring dependency for device, assuming no driver <4>[ 8.729935] rcar-fcp fea37000.fcp: ignoring dependency for device, assuming no driver <6>[ 8.786985] [drm] Supports vblank timestamp caching Rev 2 (21.10.2013). <6>[ 8.793929] [drm] No driver support for vblank timestamp query. <6>[ 8.904464] [drm] Supports vblank timestamp caching Rev 2 (21.10.2013). <6>[ 8.911366] [drm] No driver support for vblank timestamp query. <5>[ 8.921898] cfg80211: Loading compiled-in X.509 certificates for regulatory database <5>[ 8.946469] cfg80211: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' <4>[ 8.953321] platform regulatory.0: Direct firmware load for regulatory.db failed with error -2 <6>[ 8.962209] cfg80211: failed to load regulatory.db <4>[ 9.104084] wl18xx_driver wl18xx.2.auto: Direct firmware load for ti-connectivity/wl1271-nvs.bin failed with error -2 <4>[ 9.114995] wl18xx_driver wl18xx.2.auto: Direct firmware load for ti-connectivity/wl18xx-conf.bin failed with error -2 <6>[ 9.117642] [drm] Supports vblank timestamp caching Rev 2 (21.10.2013). <3>[ 9.125971] wlcore: ERROR could not get configuration binary ti-connectivity/wl18xx-conf.bin: -2 <6>[ 9.132835] [drm] No driver support for vblank timestamp query. <4>[ 9.148054] wlcore: WARNING falling back to default config <6>[ 9.641465] wlcore: wl18xx HW: 183x or 180x, PG 2.2 (ROM 0x11) <6>[ 9.650024] wlcore: loaded done Initializing random number generator... <4>[ 9.673114] urandom_read: 3 callbacks suppressed <5>[ 9.673119] random: dd: uninitialized urandom read (512 bytes read) done. Starting network: ip: RTNETLINK answers: File exists FAIL Starting dropbear sshd: <5>[ 9.708852] random: dropbear: uninitialized urandom read (32 bytes read) <6>[ 9.733799] NET: Registered protocol family 10 <6>[ 9.739234] Segment Routing with IPv6 OK /bin/sh: can't access tty; job control turned off Matched prompt #10: / # case: kernel-messages
case_id: 4093460
definition: lava
duration: 8.65
extra: ...
level: 2.4.4.1
namespace: common
result: pass
Setting prompt string to ['/ #'] end: 2.4.4.1 login-action (duration 00:00:09) [common] case: login-action
case_id: 4093461
definition: lava
duration: 8.65
extra: ...
level: 2.4.4.1
namespace: common
result: pass
end: 2.4.4 auto-login-action (duration 00:00:10) [common] end: 2.4 uboot-commands (duration 00:00:31) [common] end: 2 uboot-action (duration 00:00:31) [common] start: 3 finalize (timeout 00:09:21) [common] start: 3.1 power-off (timeout 00:00:30) [common] Calling: '/usr/local/bin/acme-cli' '-s' '192.168.66.2' 'switch_off' '2' >> Success Returned 0 in 0 seconds end: 3.1 power-off (duration 00:00:00) [common] case: power-off
case_id: 4093462
definition: lava
duration: 0.23
extra: ...
level: 3.1
namespace: common
result: pass
start: 3.2 read-feedback (timeout 00:09:20) [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 download directory: /var/lib/lava/dispatcher/tmp/37782/tftp-deploy-gswohl4k/ramdisk Cleaning up download directory: /var/lib/lava/dispatcher/tmp/37782/tftp-deploy-gswohl4k/kernel Cleaning up download directory: /var/lib/lava/dispatcher/tmp/37782/tftp-deploy-gswohl4k/dtb Cleaning up download directory: /var/lib/lava/dispatcher/tmp/37782/tftp-deploy-gswohl4k/modules Override tmp directory removed at /var/lib/lava/dispatcher/tmp/37782 Root tmp directory removed at /var/lib/lava/dispatcher/tmp/37782 Job finished correctly case: job
case_id: 4093463
definition: lava
result: pass