lava-dispatcher, installed at version: 2023.10 start: 0 validate Start time: 2024-11-26 09:58:49.436183+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: 7.68 case: validate
case_id: 4097081
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/37869/tftp-deploy-kurz7fne/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.06 s (3.76 MB/s) end: 1.1.1 http-download (duration 00:00:01) [common] case: http-download
case_id: 4097105
definition: lava
duration: 1.07
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/37869/tftp-deploy-kurz7fne/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 2.95 s (8.02 MB/s) end: 1.2.1 http-download (duration 00:00:03) [common] case: http-download
case_id: 4097105
definition: lava
duration: 2.95
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/37869/tftp-deploy-kurz7fne/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.20 s (0.33 MB/s) end: 1.3.1 http-download (duration 00:00:00) [common] case: http-download
case_id: 4097105
definition: lava
duration: 0.20
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:56) [common] start: 1.4.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/modules.tar.xz?raw=true saving as /var/lib/lava/dispatcher/tmp/37869/tftp-deploy-kurz7fne/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.35 s (4.15 MB/s) end: 1.4.1 http-download (duration 00:00:01) [common] case: http-download
case_id: 4097105
definition: lava
duration: 1.36
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/37869/tftp-deploy-kurz7fne/modules/modules.tar to /var/lib/lava/dispatcher/tmp/37869/extract-overlay-ramdisk-xogdl9m3/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/37869/extract-overlay-ramdisk-xogdl9m3/ramdisk.cpio containing /var/lib/lava/dispatcher/tmp/37869/extract-overlay-ramdisk-xogdl9m3/ramdisk >> 85384 blocks Adding RAMdisk u-boot header. mkimage -A arm64 -T ramdisk -C none -d /var/lib/lava/dispatcher/tmp/37869/extract-overlay-ramdisk-xogdl9m3/ramdisk.cpio.gz /var/lib/lava/dispatcher/tmp/37869/extract-overlay-ramdisk-xogdl9m3/ramdisk.cpio.gz.uboot output: Image Name: output: Created: Tue Nov 26 09:59:05 2024 output: Image Type: AArch64 Linux RAMDisk Image (uncompressed) output: Data Size: 13003138 Bytes = 12698.38 KiB = 12.40 MiB output: Load Address: 00000000 output: Entry Point: 00000000 output: rename /var/lib/lava/dispatcher/tmp/37869/extract-overlay-ramdisk-xogdl9m3/ramdisk.cpio.gz.uboot to /var/lib/lava/dispatcher/tmp/37869/tftp-deploy-kurz7fne/ramdisk/ramdisk.cpio.gz.uboot end: 1.5.8 compress-ramdisk (duration 00:00:03) [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}: 37869/tftp-deploy-kurz7fne/dtb/r8a7795-h3ulcb-kf.dtb - {INITRD}: 37869/tftp-deploy-kurz7fne/ramdisk/ramdisk.cpio.gz.uboot - {KERNEL_ADDR}: 0x68080000 - {KERNEL}: 37869/tftp-deploy-kurz7fne/kernel/Image - {LAVA_MAC}: None - {PRESEED_CONFIG}: None - {PRESEED_LOCAL}: None - {RAMDISK_ADDR}: 0x4a000000 - {RAMDISK}: 37869/tftp-deploy-kurz7fne/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 37869/tftp-deploy-kurz7fne/kernel/Image - tftp 0x4a000000 37869/tftp-deploy-kurz7fne/ramdisk/ramdisk.cpio.gz.uboot - setenv initrd_size ${filesize} - tftp 0x68000000 37869/tftp-deploy-kurz7fne/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: 4097110
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: 4097111
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 Loader(�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(�NOTICE: BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.4.0.0 NO�NOTICE: BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.4.0.0 NOTICE: BL2: PRR is R-Car H3 Ver.3.0 NOTICE: BNOTICE: 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 NOTIC�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) NO�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: 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 (1010 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 37869/tftp-deploy-kurz7fne/kernel/Image => tftp 0x68080000 37869/tftp-deploy-kurz7fne/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 37869/tftp-deploy-kurz7fne/kernel/Image Using ethernet@e6800000 device TFTP from server 192.168.66.1; our IP address is 192.168.66.15 Filename '37869/tftp-deploy-kurz7fne/kernel/Image'. Load address: 0x68080000 Loading: *################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ### 3.4 MiB/s done Bytes transferred = 24838656 (17b0200 hex) tftp 0x4a000000 37869/tftp-deploy-kurz7fne/ramdisk/ramdisk.cpio.gz.uboot => tftp 0x4a000000 37869/tftp-deploy-kurz7fne/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:45) tftp 0x4a000000 37869/tftp-deploy-kurz7fne/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 '37869/tftp-deploy-kurz7fne/ramdisk/ramdisk.cpio.gz.uboot'. Load address: 0x4a000000 Loading: *################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ######################################### 3 MiB/s done Bytes transferred = 13003202 (c669c2 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:41) setenv initrd_size ${filesize} tftp 0x68000000 37869/tftp-deploy-kurz7fne/dtb/r8a7795-h3ulcb-kf.dtb => tftp 0x68000000 37869/tftp-deploy-kurz7fne/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:41) tftp 0x68000000 37869/tftp-deploy-kurz7fne/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 '37869/tftp-deploy-kurz7fne/dtb/r8a7795-h3ulcb-kf.dtb'. Load address: 0x68000000 Loading: *##### 2.7 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:41) 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:40) booti 0x68080000 0x4a000000 0x68000000 ## Loading init Ramdisk from Legacy Image at 4a000000 ... Image Name: Image Type: AArch64 Linux RAMDisk Image (uncompressed) Data Size: 13003138 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:17) [common] start: 2.4.4 auto-login-action (timeout 00:04:40) [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:39) [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:39) <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.000104] Console: colour dummy device 80x25 <6>[ 0.000170] Calibrating delay loop (skipped), value calculated using timer frequency.. 16.66 BogoMIPS (lpj=33333) <6>[ 0.000179] pid_max: default: 32768 minimum: 301 <6>[ 0.000230] LSM: Security Framework initializing <6>[ 0.000308] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, linear) <6>[ 0.000351] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, linear) <6>[ 0.023976] ASID allocator initialised with 32768 entries <6>[ 0.031964] rcu: Hierarchical SRCU implementation. <6>[ 0.040981] Detected Renesas R-Car Gen3 r8a7795 ES3.0 <6>[ 0.042215] EFI services will not be available. <6>[ 0.047989] smp: Bringing up secondary CPUs ... <6>[ 0.080199] Detected PIPT I-cache on CPU1 <6>[ 0.080235] CPU1: Booted secondary processor 0x0000000001 [0x411fd073] <6>[ 0.112203] Detected PIPT I-cache on CPU2 <6>[ 0.112224] CPU2: Booted secondary processor 0x0000000002 [0x411fd073] <6>[ 0.144235] Detected PIPT I-cache on CPU3 <6>[ 0.144254] CPU3: Booted secondary processor 0x0000000003 [0x411fd073] <6>[ 0.176275] CPU features: detected: ARM erratum 845719 <6>[ 0.176285] Detected VIPT I-cache on CPU4 <6>[ 0.176323] CPU4: Booted secondary processor 0x0000000100 [0x410fd034] <6>[ 0.208297] Detected VIPT I-cache on CPU5 <6>[ 0.208320] CPU5: Booted secondary processor 0x0000000101 [0x410fd034] <6>[ 0.240333] Detected VIPT I-cache on CPU6 <6>[ 0.240357] CPU6: Booted secondary processor 0x0000000102 [0x410fd034] <6>[ 0.272372] Detected VIPT I-cache on CPU7 <6>[ 0.272396] CPU7: Booted secondary processor 0x0000000103 [0x410fd034] <6>[ 0.272472] smp: Brought up 1 node, 8 CPUs <6>[ 0.272495] SMP: Total of 8 processors activated. <6>[ 0.272500] CPU features: detected: 32-bit EL0 Support <6>[ 0.272504] CPU features: detected: CRC32 instructions <6>[ 0.273351] CPU: All CPU(s) started at EL2 <6>[ 0.273375] alternatives: patching kernel code <6>[ 0.274428] devtmpfs: initialized <6>[ 0.279883] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns <6>[ 0.279898] futex hash table entries: 2048 (order: 5, 131072 bytes, linear) <6>[ 0.280575] pinctrl core: initialized pinctrl subsystem <6>[ 0.281739] DMI not present or invalid. <6>[ 0.281987] NET: Registered protocol family 16 <6>[ 0.282263] audit: initializing netlink subsys (disabled) <5>[ 0.282406] audit: type=2000 audit(0.280:1): state=initialized audit_enabled=0 res=1 <6>[ 0.283341] cpuidle: using governor menu <6>[ 0.283482] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. <6>[ 0.284092] DMA: preallocated 256 KiB pool for atomic allocations <6>[ 0.284964] Serial: AMBA PL011 UART driver <6>[ 0.287428] sh-pfc e6060000.pin-controller: r8a77951_pfc support registered <6>[ 0.305098] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages <6>[ 0.305106] HugeTLB registered 32.0 MiB page size, pre-allocated 0 pages <6>[ 0.305111] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages <6>[ 0.305116] HugeTLB registered 64.0 KiB page size, pre-allocated 0 pages <6>[ 0.306530] cryptd: max_cpu_qlen set to 1000 <6>[ 0.309720] ACPI: Interpreter disabled. <6>[ 0.313252] vgaarb: loaded <5>[ 0.313442] SCSI subsystem initialized <6>[ 0.313669] usbcore: registered new interface driver usbfs <6>[ 0.313691] usbcore: registered new interface driver hub <6>[ 0.313734] usbcore: registered new device driver usb <6>[ 0.314577] i2c-sh_mobile e60b0000.i2c: I2C adapter 7, bus speed 400000 Hz <6>[ 0.314830] pps_core: LinuxPPS API ver. 1 registered <6>[ 0.314835] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <[email protected]> <6>[ 0.314844] PTP clock support registered <6>[ 0.314961] EDAC MC: Ver: 3.0.0 <6>[ 0.316084] FPGA manager framework <6>[ 0.316132] Advanced Linux Sound Architecture Driver Initialized. <6>[ 0.316589] clocksource: Switched to clocksource arch_sys_counter <5>[ 0.316694] VFS: Disk quotas dquot_6.6.0 <6>[ 0.316734] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) <6>[ 0.316856] pnp: PnP ACPI: disabled <6>[ 0.319705] thermal_sys: Registered thermal governor 'step_wise' <6>[ 0.319707] thermal_sys: Registered thermal governor 'power_allocator' <6>[ 0.320209] NET: Registered protocol family 2 <6>[ 0.320449] tcp_listen_portaddr_hash hash table entries: 4096 (order: 4, 65536 bytes, linear) <6>[ 0.320514] TCP established hash table entries: 65536 (order: 7, 524288 bytes, linear) <6>[ 0.320809] TCP bind hash table entries: 65536 (order: 8, 1048576 bytes, linear) <6>[ 0.321384] TCP: Hash tables configured (established 65536 bind 65536) <6>[ 0.321498] UDP hash table entries: 4096 (order: 5, 131072 bytes, linear) <6>[ 0.321604] UDP-Lite hash table entries: 4096 (order: 5, 131072 bytes, linear) <6>[ 0.321815] NET: Registered protocol family 1 <6>[ 0.322154] RPC: Registered named UNIX socket transport module. <6>[ 0.322159] RPC: Registered udp transport module. <6>[ 0.322162] RPC: Registered tcp transport module. <6>[ 0.322166] RPC: Registered tcp NFSv4.1 backchannel transport module. <6>[ 0.322175] PCI: CLS 0 bytes, default 64 <6>[ 0.322273] Unpacking initramfs... <6>[ 0.760731] Freeing initrd memory: 12692K <6>[ 0.761558] hw perfevents: enabled with armv8_cortex_a53 PMU driver, 7 counters available <6>[ 0.761807] hw perfevents: enabled with armv8_cortex_a57 PMU driver, 7 counters available <6>[ 0.762225] kvm [1]: IPA Size Limit: 40bits <6>[ 0.762693] kvm [1]: vgic interrupt IRQ1 <6>[ 0.762841] kvm [1]: Hyp mode initialized successfully <5>[ 0.895244] Initialise system trusted keyrings <6>[ 0.895356] workingset: timestamp_bits=44 max_order=21 bucket_order=0 <6>[ 0.898528] squashfs: version 4.0 (2009/01/31) Phillip Lougher <5>[ 0.898961] NFS: Registering the id_resolver key type <5>[ 0.898978] Key type id_resolver registered <5>[ 0.898982] Key type id_legacy registered <6>[ 0.898991] nfs4filelayout_init: NFSv4 File Layout Driver Registering... <6>[ 0.899081] 9p: Installing v9fs 9p2000 file system support <5>[ 0.913565] Key type asymmetric registered <5>[ 0.913574] Asymmetric key parser 'x509' registered <6>[ 0.913622] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 245) <6>[ 0.913628] io scheduler mq-deadline registered <6>[ 0.913632] io scheduler kyber registered <6>[ 0.923106] gpio_rcar e6050000.gpio: driving 16 GPIOs <6>[ 0.923288] gpio_rcar e6051000.gpio: driving 29 GPIOs <6>[ 0.923450] gpio_rcar e6052000.gpio: driving 15 GPIOs <6>[ 0.923607] gpio_rcar e6053000.gpio: driving 16 GPIOs <6>[ 0.923764] gpio_rcar e6054000.gpio: driving 18 GPIOs <6>[ 0.923912] gpio_rcar e6055000.gpio: driving 26 GPIOs <6>[ 0.924088] gpio_rcar e6055400.gpio: driving 32 GPIOs <6>[ 0.924244] gpio_rcar e6055800.gpio: driving 4 GPIOs <6>[ 0.925587] rcar-pcie fe000000.pcie: host bridge /soc/pcie@fe000000 ranges: <6>[ 0.925611] rcar-pcie fe000000.pcie: IO 0xfe100000..0xfe1fffff -> 0x00000000 <6>[ 0.925628] rcar-pcie fe000000.pcie: MEM 0xfe200000..0xfe3fffff -> 0xfe200000 <6>[ 0.925642] rcar-pcie fe000000.pcie: MEM 0x30000000..0x37ffffff -> 0x30000000 <6>[ 0.925652] rcar-pcie fe000000.pcie: MEM 0x38000000..0x3fffffff -> 0x38000000 <6>[ 0.992224] rcar-pcie fe000000.pcie: PCIe link down <6>[ 0.992371] rcar-pcie ee800000.pcie: host bridge /soc/pcie@ee800000 ranges: <6>[ 0.992389] rcar-pcie ee800000.pcie: IO 0xee900000..0xee9fffff -> 0x00000000 <6>[ 0.992406] rcar-pcie ee800000.pcie: MEM 0xeea00000..0xeebfffff -> 0xeea00000 <6>[ 0.992420] rcar-pcie ee800000.pcie: MEM 0xc0000000..0xc7ffffff -> 0xc0000000 <6>[ 0.992430] rcar-pcie ee800000.pcie: MEM 0xc8000000..0xcfffffff -> 0xc8000000 <6>[ 1.056216] rcar-pcie ee800000.pcie: PCIe link down <4>[ 1.058030] EINJ: ACPI disabled. <6>[ 1.068185] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled <6>[ 1.069890] SuperH (H)SCI(F) driver initialized <6>[ 1.070214] e6540000.serial: ttySC1 at MMIO 0xe6540000 (irq = 34, base_baud = 0) is a hscif <6>[ 1.070716] e6e68000.serial: ttySC2 at MMIO 0xe6e68000 (irq = 121, base_baud = 0) is a scif <6>[ 1.071052] e6e88000.serial: ttySC0 at MMIO 0xe6e88000 (irq = 122, base_baud = 0) is a scif <6>[ 2.138393] printk: console [ttySC0] enabled <6>[ 2.143560] msm_serial: driver initialized <6>[ 2.154452] loop: module loaded <6>[ 2.162234] libphy: Fixed MDIO Bus: probed <6>[ 2.166732] tun: Universal TUN/TAP device driver, 1.6 <6>[ 2.172755] thunder_xcv, ver 1.0 <6>[ 2.176256] thunder_bgx, ver 1.0 <6>[ 2.179763] nicpf, ver 1.0 <6>[ 2.183285] hclge is initializing <6>[ 2.186862] hns3: Hisilicon Ethernet Network Driver for Hip08 Family - version <6>[ 2.194342] hns3: Copyright (c) 2017 Huawei Corporation. <6>[ 2.199939] e1000e: Intel(R) PRO/1000 Network Driver - 3.2.6-k <6>[ 2.206030] e1000e: Copyright(c) 1999 - 2015 Intel Corporation. <6>[ 2.212226] igb: Intel(R) Gigabit Ethernet Network Driver - version 5.6.0-k <6>[ 2.219447] igb: Copyright (c) 2007-2014 Intel Corporation. <6>[ 2.225300] igbvf: Intel(R) Gigabit Virtual Function Network Driver - version 2.4.0-k <6>[ 2.233388] igbvf: Copyright (c) 2009 - 2012 Intel Corporation. <6>[ 2.239864] sky2: driver version 1.30 <6>[ 2.244709] VFIO - User Level meta-driver version: 0.3 <6>[ 2.251437] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver <6>[ 2.258250] ehci-pci: EHCI PCI platform driver <6>[ 2.262974] ehci-platform: EHCI generic platform driver <6>[ 2.296646] ehci-platform ee080100.usb: EHCI Host Controller <6>[ 2.302590] ehci-platform ee080100.usb: new USB bus registered, assigned bus number 1 <6>[ 2.310752] ehci-platform ee080100.usb: irq 158, io mem 0xee080100 <6>[ 2.332596] ehci-platform ee080100.usb: USB 2.0 started, EHCI 1.10 <6>[ 2.339356] hub 1-0:1.0: USB hub found <6>[ 2.343383] hub 1-0:1.0: 1 port detected <6>[ 2.347831] ehci-platform ee0a0100.usb: EHCI Host Controller <6>[ 2.353763] ehci-platform ee0a0100.usb: new USB bus registered, assigned bus number 2 <6>[ 2.361901] ehci-platform ee0a0100.usb: irq 159, io mem 0xee0a0100 <6>[ 2.380597] ehci-platform ee0a0100.usb: USB 2.0 started, EHCI 1.10 <6>[ 2.387278] hub 2-0:1.0: USB hub found <6>[ 2.391302] hub 2-0:1.0: 1 port detected <6>[ 2.395692] ehci-orion: EHCI orion driver <6>[ 2.400115] ehci-exynos: EHCI EXYNOS driver <6>[ 2.404646] ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver <6>[ 2.411098] ohci-pci: OHCI PCI platform driver <6>[ 2.415830] ohci-platform: OHCI generic platform driver <6>[ 2.421458] ohci-platform ee080000.usb: Generic Platform OHCI controller <6>[ 2.428428] ohci-platform ee080000.usb: new USB bus registered, assigned bus number 3 <6>[ 2.436559] ohci-platform ee080000.usb: irq 158, io mem 0xee080000 <6>[ 2.531507] hub 3-0:1.0: USB hub found <6>[ 2.535532] hub 3-0:1.0: 1 port detected <6>[ 2.539931] ohci-platform ee0a0000.usb: Generic Platform OHCI controller <6>[ 2.546902] ohci-platform ee0a0000.usb: new USB bus registered, assigned bus number 4 <6>[ 2.555022] ohci-platform ee0a0000.usb: irq 159, io mem 0xee0a0000 <6>[ 2.651423] hub 4-0:1.0: USB hub found <6>[ 2.655448] hub 4-0:1.0: 1 port detected <6>[ 2.659861] ohci-exynos: OHCI EXYNOS driver <6>[ 2.664711] xhci-hcd ee000000.usb: xHCI Host Controller <6>[ 2.670395] xhci-hcd ee000000.usb: new USB bus registered, assigned bus number 5 <4>[ 2.678099] xhci-hcd ee000000.usb: Direct firmware load for r8a779x_usb3_v3.dlmem failed with error -2 <3>[ 2.687682] xhci-hcd ee000000.usb: can't setup: -2 <6>[ 2.692738] xhci-hcd ee000000.usb: USB bus 5 deregistered <4>[ 2.698425] xhci-hcd: probe of ee000000.usb failed with error -2 <6>[ 2.704969] usbcore: registered new interface driver usb-storage <6>[ 2.713619] i2c /dev entries driver <6>[ 2.725170] cs2000-cp 2-004f: revision - C1 <6>[ 2.729630] usb 2-1: new high-speed USB device number 2 using ehci-platform <4>[ 2.737064] pca953x 2-0074: 2-0074 supply vcc not found, using dummy regulator <6>[ 2.746692] GPIO line 340 (Audio_Out_OFF) hogged as output/high <6>[ 2.753563] GPIO line 345 (SD WiFi mux) hogged as output/low <6>[ 2.760166] GPIO line 346 (HUB pwen) hogged as output/high <6>[ 2.766598] GPIO line 347 (HUB rst) hogged as output/high <6>[ 2.773839] GPIO line 348 (OTG OFFVBUSn) hogged as output/low <6>[ 2.780531] GPIO line 349 (OTG EXTLPn) hogged as output/high <6>[ 2.787134] GPIO line 355 (SND_RST) hogged as output/high <4>[ 2.793723] pca953x 2-0075: 2-0075 supply vcc not found, using dummy regulator <6>[ 2.803444] i2c-rcar e6510000.i2c: probed <4>[ 2.808360] pca953x 4-0076: 4-0076 supply vcc not found, using dummy regulator <4>[ 2.816887] pca953x 4-0077: 4-0077 supply vcc not found, using dummy regulator <6>[ 2.825400] i2c-rcar e66d8000.i2c: probed <6>[ 2.830768] i2c i2c-2: Added multiplexed i2c bus 8 <6>[ 2.835914] i2c i2c-2: Added multiplexed i2c bus 9 <6>[ 2.841064] i2c i2c-2: Added multiplexed i2c bus 10 <6>[ 2.846293] i2c i2c-2: Added multiplexed i2c bus 11 <6>[ 2.851525] i2c i2c-2: Added multiplexed i2c bus 12 <6>[ 2.856759] i2c i2c-2: Added multiplexed i2c bus 13 <6>[ 2.861985] i2c i2c-2: Added multiplexed i2c bus 14 <6>[ 2.867327] i2c i2c-2: Added multiplexed i2c bus 15 <6>[ 2.872483] pca954x 2-0071: registered 8 multiplexed busses for I2C switch pca9548 <6>[ 2.880641] i2c i2c-4: Added multiplexed i2c bus 16 <6>[ 2.885868] i2c i2c-4: Added multiplexed i2c bus 17 <6>[ 2.891100] i2c i2c-4: Added multiplexed i2c bus 18 <6>[ 2.896327] i2c i2c-4: Added multiplexed i2c bus 19 <6>[ 2.901573] i2c i2c-4: Added multiplexed i2c bus 20 <6>[ 2.906829] i2c i2c-4: Added multiplexed i2c bus 21 <6>[ 2.912074] i2c i2c-4: Added multiplexed i2c bus 22 <6>[ 2.917330] i2c i2c-4: Added multiplexed i2c bus 23 <6>[ 2.922475] pca954x 4-0071: registered 8 multiplexed busses for I2C switch pca9548 <6>[ 2.936353] rcar_gen3_thermal e6198000.thermal: TSC0: Loaded 1 trip points <6>[ 2.947639] rcar_gen3_thermal e6198000.thermal: TSC1: Loaded 1 trip points <6>[ 2.958899] rcar_gen3_thermal e6198000.thermal: TSC2: Loaded 2 trip points <4>[ 2.968505] cpufreq: cpufreq_online: CPU0: Running at unlisted freq: 1499999 KHz <4>[ 2.976200] cpufreq: cpufreq_online: CPU0: Unlisted initial frequency changed to: 1500000 KHz <4>[ 2.985326] cpufreq: cpufreq_online: CPU4: Running at unlisted freq: 1199999 KHz <4>[ 2.992989] cpufreq: cpufreq_online: CPU4: Unlisted initial frequency changed to: 1200000 KHz <6>[ 3.002611] sdhci: Secure Digital Host Controller Interface driver <6>[ 3.009061] sdhci: Copyright(c) Pierre Ossman <6>[ 3.014259] renesas_sdhi_internal_dmac ee100000.sd: Got CD GPIO <6>[ 3.069400] renesas_sdhi_internal_dmac ee140000.sd: mmc0 base at 0xee140000 max clock rate 200 MHz <6>[ 3.079221] Synopsys Designware Multimedia Card Interface Driver <6>[ 3.086365] sdhci-pltfm: SDHCI platform and OF driver helper <6>[ 3.093924] ledtrig-cpu: registered to indicate activity on CPUs <6>[ 3.101266] usbcore: registered new interface driver usbhid <5>[ 3.107119] random: fast init done <6>[ 3.110802] usbhid: USB HID core driver <6>[ 3.117139] optee: probing for conduit method from DT. <6>[ 3.122597] optee: revision 3.22 (314ecf9d) <6>[ 3.122799] optee: dynamic shared memory is enabled <3>[ 3.132555] optee: PTA_CMD_GET_DEVICES invoke function err: ffff0006 <6>[ 3.145502] NET: Registered protocol family 17 <6>[ 3.150345] 9pnet: Installing 9P2000 support <5>[ 3.154924] Key type dns_resolver registered <6>[ 3.159830] registered taskstats version 1 <5>[ 3.164206] Loading compiled-in X.509 certificates <6>[ 3.176189] renesas_irqc e61c0000.interrupt-controller: driving 6 irqs <6>[ 3.190176] bd9571mwv 7-0030: Device: BD9571MWV rev. 4 <6>[ 3.199993] renesas_sdhi_internal_dmac ee100000.sd: Got CD GPIO <6>[ 3.232036] mmc0: new HS400 MMC card at address 0001 <6>[ 3.237652] mmcblk0: mmc0:0001 DJUD4R 119 GiB <6>[ 3.242520] mmcblk0boot0: mmc0:0001 DJUD4R partition 1 31.9 MiB <6>[ 3.248856] mmcblk0boot1: mmc0:0001 DJUD4R partition 2 31.9 MiB <6>[ 3.254655] renesas_sdhi_internal_dmac ee100000.sd: mmc1 base at 0xee100000 max clock rate 200 MHz <6>[ 3.255135] mmcblk0rpmb: mmc0:0001 DJUD4R partition 3 4.00 MiB, chardev (237:0) <6>[ 3.390719] renesas_sdhi_internal_dmac ee160000.sd: mmc2 base at 0xee160000 max clock rate 26 MHz <4>[ 3.403583] rcar-dmac e6700000.dma-controller: ignoring dependency for device, assuming no driver <4>[ 3.414806] rcar-dmac e7300000.dma-controller: ignoring dependency for device, assuming no driver <4>[ 3.425899] rcar-dmac e7310000.dma-controller: ignoring dependency for device, assuming no driver <4>[ 3.435612] renesas_sdhi_internal_dmac ee160000.sd: card claims to support voltages below defined range <4>[ 3.437077] rcar-dmac ec700000.dma-controller: ignoring dependency for device, assuming no driver <4>[ 3.456392] rcar-dmac ec720000.dma-controller: ignoring dependency for device, assuming no driver <6>[ 3.458761] mmc2: new high speed SDIO card at address 0001 <4>[ 3.468029] ravb e6800000.ethernet: ignoring dependency for device, assuming no driver <6>[ 3.479817] libphy: ravb_mii: probed <6>[ 3.490147] ravb e6800000.ethernet eth0: Base address at 0xe6800000, aa:bb:cc:dd:ee:77, IRQ 116. <6>[ 3.500414] input: keyboard as /devices/platform/keyboard/input/input0 <6>[ 3.507438] hctosys: unable to open rtc device (rtc0) <6>[ 3.587691] 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>[ 6.218867] ravb e6800000.ethernet eth0: Link is Up - 100Mbps/Full - flow control off <5>[ 6.248594] Sending DHCP requests .., OK <6>[ 9.244798] IP-Config: Got DHCP answer from 192.168.66.1, my address is 192.168.66.15 <6>[ 9.252891] IP-Config: Complete: <6>[ 9.256375] 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>[ 9.266895] host=192.168.66.15, domain=example.org, nis-domain=(none) <6>[ 9.274029] bootserver=0.0.0.0, rootserver=0.0.0.0, rootpath= <6>[ 9.274031] nameserver0=10.1.1.1 <6>[ 9.284753] ALSA device list: <6>[ 9.287979] No soundcards found. <6>[ 9.294421] Freeing unused kernel memory: 4992K <6>[ 9.304656] Run /init as init process Starting syslogd: OK Starting klogd: OK Populating /dev using udev: <30>[ 9.338120] udevd[304]: starting version 3.2.7 <5>[ 9.343311] random: udevd: uninitialized urandom read (16 bytes read) <5>[ 9.350130] random: udevd: uninitialized urandom read (16 bytes read) <5>[ 9.356856] random: udevd: uninitialized urandom read (16 bytes read) <27>[ 9.364668] udevd[304]: specified group 'kvm' unknown <30>[ 9.372970] udevd[305]: starting eudev-3.2.7 <6>[ 9.486503] renesas_usbhs e6590000.usb: no transceiver found <6>[ 9.492737] renesas_usbhs e6590000.usb: gadget probed <6>[ 9.498433] renesas_usbhs e6590000.usb: probed <6>[ 9.521105] rcar_sound ec500000.sound: probed <4>[ 9.528465] rcar-fcp fe950000.fcp: ignoring dependency for device, assuming no driver <4>[ 9.538822] rcar-fcp fe951000.fcp: ignoring dependency for device, assuming no driver <6>[ 9.539510] mc: Linux media interface: v0.10 <4>[ 9.547334] rcar-fcp fe96f000.fcp: ignoring dependency for device, assuming no driver <4>[ 9.560202] rcar-fcp fe92f000.fcp: ignoring dependency for device, assuming no driver <6>[ 9.560412] videodev: Linux video capture interface: v2.00 <4>[ 9.569117] rcar-fcp fe9af000.fcp: ignoring dependency for device, assuming no driver <4>[ 9.583515] rcar-fcp fe9bf000.fcp: ignoring dependency for device, assuming no driver <4>[ 9.592708] rcar-fcp fea27000.fcp: ignoring dependency for device, assuming no driver <4>[ 9.602417] rcar-fcp fea2f000.fcp: ignoring dependency for device, assuming no driver <4>[ 9.611796] rcar-fcp fea37000.fcp: ignoring dependency for device, assuming no driver <6>[ 9.678575] [drm] Supports vblank timestamp caching Rev 2 (21.10.2013). <6>[ 9.685577] [drm] No driver support for vblank timestamp query. <6>[ 9.784345] [drm] Supports vblank timestamp caching Rev 2 (21.10.2013). <6>[ 9.791254] [drm] No driver support for vblank timestamp query. <5>[ 9.799348] cfg80211: Loading compiled-in X.509 certificates for regulatory database <5>[ 9.823910] cfg80211: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' <4>[ 9.830772] platform regulatory.0: Direct firmware load for regulatory.db failed with error -2 <6>[ 9.839760] cfg80211: failed to load regulatory.db <4>[ 9.980487] wl18xx_driver wl18xx.2.auto: Direct firmware load for ti-connectivity/wl1271-nvs.bin failed with error -2 <4>[ 9.991399] wl18xx_driver wl18xx.2.auto: Direct firmware load for ti-connectivity/wl18xx-conf.bin failed with error -2 done <3>[ 10.002378] wlcore: ERROR could not get configuration binary ti-connectivity/wl18xx-conf.bin: -2 <4>[ 10.011931] wlcore: WARNING falling back to default config Initializing random number generator... <6>[ 10.020348] [drm] Supports vblank timestamp caching Rev 2 (21.10.2013). done. Starting <6>[ 10.028051] [drm] No driver support for vblank timestamp query. network: ip: RTNETLINK answers: File exists FAIL Starting dropbear sshd: <6>[ 10.068214] NET: Registered protocol family 10 <6>[ 10.073585] Segment Routing with IPv6 OK /bin/sh: can't access tty; job control turned off Matched prompt #10: / # case: kernel-messages
case_id: 4097113
definition: lava
duration: 8.99
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: 4097114
definition: lava
duration: 8.99
extra: ...
level: 2.4.4.1
namespace: common
result: pass
end: 2.4.4 auto-login-action (duration 00:00:11) [common] end: 2.4 uboot-commands (duration 00:00:30) [common] end: 2 uboot-action (duration 00:00:30) [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: 4097115
definition: lava
duration: 0.23
extra: ...
level: 3.1
namespace: common
result: pass
start: 3.2 read-feedback (timeout 00:09:21) [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/37869/tftp-deploy-kurz7fne/ramdisk Cleaning up download directory: /var/lib/lava/dispatcher/tmp/37869/tftp-deploy-kurz7fne/kernel Cleaning up download directory: /var/lib/lava/dispatcher/tmp/37869/tftp-deploy-kurz7fne/dtb Cleaning up download directory: /var/lib/lava/dispatcher/tmp/37869/tftp-deploy-kurz7fne/modules Override tmp directory removed at /var/lib/lava/dispatcher/tmp/37869 Root tmp directory removed at /var/lib/lava/dispatcher/tmp/37869 Job finished correctly case: job
case_id: 4097116
definition: lava
result: pass