lava-dispatcher, installed at version: 2023.01 start: 0 validate Start time: 2023-12-17 17:42:41.730433+00:00 (UTC) Validating that https://github.com/montjoie/lava-healthchecks-binary/blob/master/images/rootfs/buildroot/kci-2019.02-11-g17e793fa4728/arm64/base/rootfs.cpio.gz?raw=true exists Validating that https://github.com/montjoie/lava-healthchecks-binary/blob/master/stable/linux-5.6.y/v5.6.12/arm64/defconfig/gcc-8/Image?raw=true exists Validating that https://github.com/montjoie/lava-healthchecks-binary/blob/master/stable/linux-5.6.y/v5.6.12/arm64/defconfig/gcc-8/dtbs/broadcom/bcm2711-rpi-4-b.dtb?raw=true exists Validating that https://github.com/montjoie/lava-healthchecks-binary/blob/master/stable/linux-5.6.y/v5.6.12/arm64/defconfig/gcc-8/modules.tar.xz?raw=true exists validate duration: 3.54 case: validate
case_id: 3982668
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-11-g17e793fa4728/arm64/base/rootfs.cpio.gz?raw=true saving as /var/lib/lava/dispatcher/tmp/34400/tftp-deploy-ziac5h7b/ramdisk/rootfs.cpio.gz total size: 4192947 (3MB) progress 0% (0MB) progress 5% (0MB) progress 10% (0MB) progress 15% (0MB) progress 20% (0MB) progress 25% (1MB) progress 30% (1MB) progress 35% (1MB) progress 40% (1MB) progress 45% (1MB) progress 50% (2MB) progress 55% (2MB) progress 60% (2MB) progress 65% (2MB) progress 70% (2MB) progress 75% (3MB) progress 80% (3MB) progress 85% (3MB) progress 90% (3MB) progress 95% (3MB) progress 100% (3MB) 3MB downloaded in 1.36s (2.93MB/s) end: 1.1.1 http-download (duration 00:00:01) [common] case: http-download
case_id: 3982675
definition: lava
duration: 1.37
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.6.y/v5.6.12/arm64/defconfig/gcc-8/Image?raw=true saving as /var/lib/lava/dispatcher/tmp/34400/tftp-deploy-ziac5h7b/kernel/Image total size: 27247104 (25MB) No compression specified progress 0% (0MB) progress 5% (1MB) progress 10% (2MB) progress 15% (3MB) progress 20% (5MB) progress 25% (6MB) progress 30% (7MB) progress 35% (9MB) progress 40% (10MB) progress 45% (11MB) progress 50% (13MB) progress 55% (14MB) progress 60% (15MB) progress 65% (16MB) progress 70% (18MB) progress 75% (19MB) progress 80% (20MB) progress 85% (22MB) progress 90% (23MB) progress 95% (24MB) progress 100% (25MB) 25MB downloaded in 1.90s (13.71MB/s) end: 1.2.1 http-download (duration 00:00:02) [common] case: http-download
case_id: 3982675
definition: lava
duration: 1.90
extra: ...
level: 1.2.1
namespace: common
result: pass
end: 1.2 download-retry (duration 00:00:02) [common] start: 1.3 download-retry (timeout 00:09:57) [common] start: 1.3.1 http-download (timeout 00:09:57) [common] downloading https://github.com/montjoie/lava-healthchecks-binary/blob/master/stable/linux-5.6.y/v5.6.12/arm64/defconfig/gcc-8/dtbs/broadcom/bcm2711-rpi-4-b.dtb?raw=true saving as /var/lib/lava/dispatcher/tmp/34400/tftp-deploy-ziac5h7b/dtb/bcm2711-rpi-4-b.dtb total size: 22210 (0MB) No compression specified progress 100% (0MB) 0MB downloaded in 0.29s (0.07MB/s) end: 1.3.1 http-download (duration 00:00:00) [common] case: http-download
case_id: 3982675
definition: lava
duration: 0.29
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.6.y/v5.6.12/arm64/defconfig/gcc-8/modules.tar.xz?raw=true saving as /var/lib/lava/dispatcher/tmp/34400/tftp-deploy-ziac5h7b/modules/modules.tar total size: 6392588 (6MB) Using unxz to decompress xz progress 0% (0MB) progress 5% (0MB) progress 10% (0MB) progress 15% (0MB) progress 20% (1MB) progress 25% (1MB) progress 30% (1MB) progress 35% (2MB) progress 40% (2MB) progress 45% (2MB) progress 50% (3MB) progress 55% (3MB) progress 60% (3MB) progress 65% (3MB) progress 70% (4MB) progress 75% (4MB) progress 80% (4MB) progress 85% (5MB) progress 90% (5MB) progress 95% (5MB) progress 100% (6MB) 6MB downloaded in 1.70s (3.58MB/s) end: 1.4.1 http-download (duration 00:00:02) [common] case: http-download
case_id: 3982675
definition: lava
duration: 1.71
extra: ...
level: 1.4.1
namespace: common
result: pass
end: 1.4 download-retry (duration 00:00:02) [common] start: 1.5 prepare-tftp-overlay (timeout 00:09:55) [common] start: 1.5.1 extract-nfsrootfs (timeout 00:09:55) [common] end: 1.5.1 extract-nfsrootfs (duration 00:00:00) [common] start: 1.5.2 lava-overlay (timeout 00:09:55) [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:55) [common] end: 1.5.3 extract-overlay-ramdisk (duration 00:00:01) [common] start: 1.5.4 extract-modules (timeout 00:09:54) [common] extracting modules file /var/lib/lava/dispatcher/tmp/34400/tftp-deploy-ziac5h7b/modules/modules.tar to /var/lib/lava/dispatcher/tmp/34400/extract-overlay-ramdisk-ysckfkf9/ramdisk end: 1.5.4 extract-modules (duration 00:00:01) [common] start: 1.5.5 apply-overlay-tftp (timeout 00:09:52) [common] end: 1.5.5 apply-overlay-tftp (duration 00:00:00) [common] start: 1.5.6 prepare-kernel (timeout 00:09:52) [common] start: 1.5.6.1 uboot-prepare-kernel (timeout 00:09:52) [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:52) [common] end: 1.5.7 configure-preseed-file (duration 00:00:00) [common] start: 1.5.8 compress-ramdisk (timeout 00:09:52) [common] Building ramdisk /var/lib/lava/dispatcher/tmp/34400/extract-overlay-ramdisk-ysckfkf9/ramdisk.cpio containing /var/lib/lava/dispatcher/tmp/34400/extract-overlay-ramdisk-ysckfkf9/ramdisk >> 90939 blocks Adding RAMdisk u-boot header. mkimage -A arm64 -T ramdisk -C none -d /var/lib/lava/dispatcher/tmp/34400/extract-overlay-ramdisk-ysckfkf9/ramdisk.cpio.gz /var/lib/lava/dispatcher/tmp/34400/extract-overlay-ramdisk-ysckfkf9/ramdisk.cpio.gz.uboot output: Image Name: output: Created: Sun Dec 17 17:43:01 2023 output: Image Type: AArch64 Linux RAMDisk Image (uncompressed) output: Data Size: 13780634 Bytes = 13457.65 KiB = 13.14 MiB output: Load Address: 00000000 output: Entry Point: 00000000 output: rename /var/lib/lava/dispatcher/tmp/34400/extract-overlay-ramdisk-ysckfkf9/ramdisk.cpio.gz.uboot to /var/lib/lava/dispatcher/tmp/34400/tftp-deploy-ziac5h7b/ramdisk/ramdisk.cpio.gz.uboot end: 1.5.8 compress-ramdisk (duration 00:00:09) [common] end: 1.5 prepare-tftp-overlay (duration 00:00:11) [common] start: 1.6 lxc-create-udev-rule-action (timeout 00:09:44) [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:16) [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 0x00080000 0x02700000 0x02400000 - {DTB_ADDR}: 0x02400000 - {DTB}: 34400/tftp-deploy-ziac5h7b/dtb/bcm2711-rpi-4-b.dtb - {INITRD}: 34400/tftp-deploy-ziac5h7b/ramdisk/ramdisk.cpio.gz.uboot - {KERNEL_ADDR}: 0x00080000 - {KERNEL}: 34400/tftp-deploy-ziac5h7b/kernel/Image - {LAVA_MAC}: None - {PRESEED_CONFIG}: None - {PRESEED_LOCAL}: None - {RAMDISK_ADDR}: 0x02700000 - {RAMDISK}: 34400/tftp-deploy-ziac5h7b/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 0xffffffff - setenv fdt_high 0xffffffff - dhcp - setenv serverip 192.168.66.1 - tftp 0x00080000 34400/tftp-deploy-ziac5h7b/kernel/Image - tftp 0x02700000 34400/tftp-deploy-ziac5h7b/ramdisk/ramdisk.cpio.gz.uboot - setenv initrd_size ${filesize} - tftp 0x02400000 34400/tftp-deploy-ziac5h7b/dtb/bcm2711-rpi-4-b.dtb - setenv bootargs 'console=ttyS1,115200n8 root=/dev/ram0 console_msg_format=syslog ip=dhcp' - fdt addr 0x02400000; fdt boardsetup; fdt resize; fdt set audio status okay; booti 0x00080000 0x02700000 0x02400000 end: 2.2 bootloader-overlay (duration 00:00:00) [common] case: bootloader-overlay
case_id: 3982677
definition: lava
duration: 0.02
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 63003' Setting prompt string to ['lava-test: # '] end: 2.3 connect-device (duration 00:00:03) [common] start: 2.4 uboot-commands (timeout 00:04:57) [common] start: 2.4.1 reset-device (timeout 00:04:57) [common] start: 2.4.1.1 pdu-reboot (timeout 00:04:57) [common] Calling: 'nice' '/usr/local/bin/acme-cli' '-s' '192.168.66.2' 'reset' '7' >> Success >> Success Returned 0 in 4 seconds end: 2.4.1.1 pdu-reboot (duration 00:00:05) [common] case: pdu-reboot
case_id: 3982678
definition: lava
duration: 4.68
extra: ...
level: 2.4.1.1
namespace: common
result: pass
end: 2.4.1 reset-device (duration 00:00:05) [common] start: 2.4.2 bootloader-interrupt (timeout 00:04:52) [common] Setting prompt string to ['Hit any key to stop autoboot'] bootloader-interrupt: Wait for prompt ['Hit any key to stop autoboot'] (timeout 00:05:00) Trying 127.0.0.1... Connected to 127.0.0.1. Escape character is '^]'. U-Boot 2020.07-00717-gfee68b98fe-dirty (Jul 17 2020 - 09:59:08 +0200) DRAM: 3.9 GiB RPI 4 Model B (0xc03111) MMC: emmc2@7e340000: 0, mmcnr@7e300000: 1 Loading Environment from FAT... *** Warning - bad CRC, using default environment In: serial Out: serial Err: serial Net: eth0: genet@7d580000 starting USB... No working controllers found c end: 2.4.2 bootloader-interrupt (duration 00:00:07) [common] start: 2.4.3 bootloader-commands (timeout 00:04:45) [common] Setting prompt string to ['U-Boot>'] bootloader-commands: Wait for prompt ['U-Boot>'] (timeout 00:04:45) Hit any key to stop autoboot: 2  0 Setting prompt string to ['U-Boot>', '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 U-Boot> setenv autoload no bootloader-commands: Wait for prompt ['U-Boot>', '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) setenv autoload no setenv initrd_high 0xffffffff U-Boot> setenv initrd_high 0xffffffff bootloader-commands: Wait for prompt ['U-Boot>', '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) setenv initrd_high 0xffffffff setenv fdt_high 0xffffffff U-Boot> setenv fdt_high 0xffffffff bootloader-commands: Wait for prompt ['U-Boot>', '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) setenv fdt_high 0xffffffff dhcp U-Boot> dhcp bootloader-commands: Wait for prompt ['U-Boot>', '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) dhcp genet@7d580000 Waiting for PHY auto negotiation to complete....... done BOOTP broadcast 1 BOOTP broadcast 2 BOOTP broadcast 3 BOOTP broadcast 4 DHCP client bound to address 192.168.66.241 (1783 ms) setenv serverip 192.168.66.1 U-Boot> setenv serverip 192.168.66.1 bootloader-commands: Wait for prompt ['U-Boot>', '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 serverip 192.168.66.1 tftp 0x00080000 34400/tftp-deploy-ziac5h7b/kernel/Image U-Boot> tftp 0x00080000 34400/tftp-deploy-ziac5h7b/kernel/Image bootloader-commands: Wait for prompt ['U-Boot>', '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) tftp 0x00080000 34400/tftp-deploy-ziac5h7b/kernel/Image Using genet@7d580000 device TFTP from server 192.168.66.1; our IP address is 192.168.66.241 Filename '34400/tftp-deploy-ziac5h7b/kernel/Image'. Load address: 0x80000 Loading: *T #T ################################################# 26 MiB 1.3 MiB/s done Bytes transferred = 27247104 (19fc200 hex) tftp 0x02700000 34400/tftp-deploy-ziac5h7b/ramdisk/ramdisk.cpio.gz.uboot U-Boot> tftp 0x02700000 34400/tftp-deploy-ziac5h7b/ramdisk/ramdisk.cpio.gz.uboot bootloader-commands: Wait for prompt ['U-Boot>', '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:19) tftp 0x02700000 34400/tftp-deploy-ziac5h7b/ramdisk/ramdisk.cpio.gz.uboot Using genet@7d580000 device TFTP from server 192.168.66.1; our IP address is 192.168.66.241 Filename '34400/tftp-deploy-ziac5h7b/ramdisk/ramdisk.cpio.gz.uboot'. Load address: 0x2700000 Loading: *################################################## 13.1 MiB 2.7 MiB/s done Bytes transferred = 13780698 (d246da hex) setenv initrd_size ${filesize} U-Boot> setenv initrd_size ${filesize} bootloader-commands: Wait for prompt ['U-Boot>', '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:14) setenv initrd_size ${filesize} tftp 0x02400000 34400/tftp-deploy-ziac5h7b/dtb/bcm2711-rpi-4-b.dtb U-Boot> tftp 0x02400000 34400/tftp-deploy-ziac5h7b/dtb/bcm2711-rpi-4-b.dtb bootloader-commands: Wait for prompt ['U-Boot>', '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:14) tftp 0x02400000 34400/tftp-deploy-ziac5h7b/dtb/bcm2711-rpi-4-b.dtb Using genet@7d580000 device TFTP from server 192.168.66.1; our IP address is 192.168.66.241 Filename '34400/tftp-deploy-ziac5h7b/dtb/bcm2711-rpi-4-b.dtb'. Load address: 0x2400000 Loading: *################################################## 21.7 KiB 1.2 MiB/s done Bytes transferred = 22210 (56c2 hex) setenv bootargs 'console=ttyS1,115200n8 root=/dev/ram0 console_msg_format=syslog ip=dhcp' U-Boot> setenv bootargs 'console=ttyS1,115200n8 root=/dev/ram0 console_msg_format=syslog ip=dhcp' bootloader-commands: Wait for prompt ['U-Boot>', '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:14) setenv bootargs 'console=ttyS1,115200n8 root=/dev/ram0 console_msg_format=syslog ip=dhcp' fdt addr 0x02400000; fdt boardsetup; fdt resize; fdt set audio status okay; booti 0x00080000 0x02700000 0x02400000 U-Boot> fdt addr 0x02400000; fdt boardsetup; fdt resize; fdt set audio status okay; booti 0x00080000 0x02700000 0x02400000 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:14) fdt addr 0x02400000; fdt boardsetup; fdt resize; fdt set audio status okay; booti 0x00080000 0x02700000 0x02400000 libfdt fdt_path_offset() returned FDT_ERR_BADPATH ## Loading init Ramdisk from Legacy Image at 02700000 ... Image Name: Image Type: AArch64 Linux RAMDisk Image (uncompressed) Data Size: 13780634 Bytes = 13.1 MiB Load Address: 00000000 Entry Point: 00000000 Verifying Checksum ... OK ## Flattened Device Tree blob at 02400000 Booting using the fdt blob at 0x2400000 Loading Ramdisk to 3a224000, end 3af4869a ... OK Loading Device Tree to 000000003a21b000, end 000000003a223fff ... OK end: 2.4.3 bootloader-commands (duration 00:00:32) [common] start: 2.4.4 auto-login-action (timeout 00:04:14) [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) Starting kernel ... <6>[ 0.000000] Booting Linux on physical CPU 0x0000000000 [0x410fd083] <5>[ 0.000000] Linux version 5.6.12 (KernelCI@44cce1cf70dc) (gcc version 8.3.0 (Debian 8.3.0-2)) #1 SMP PREEMPT Sun May 10 14:46:40 UTC 2020 <6>[ 0.000000] Machine model: Raspberry Pi 4 Model B <6>[ 0.000000] efi: Getting EFI parameters from FDT: <6>[ 0.000000] efi: UEFI not found. <6>[ 0.000000] Reserved memory: created CMA memory pool at 0x0000000036000000, size 64 MiB start: 2.4.4.1 login-action (timeout 00:04:12) [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 ['-\\[ 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:12) <6>[ 0.000000] OF: reserved mem: initialized node linux,cma, compatible id shared-dma-pool <6>[ 0.000000] NUMA: No NUMA configuration found <6>[ 0.000000] NUMA: Faking a node at [mem 0x0000000000000000-0x00000000fbffffff] <6>[ 0.000000] NUMA: NODE_DATA [mem 0xfb81d100-0xfb81efff] <6>[ 0.000000] Zone ranges: <6>[ 0.000000] DMA [mem 0x0000000000000000-0x000000003fffffff] <6>[ 0.000000] DMA32 [mem 0x0000000040000000-0x00000000fbffffff] <6>[ 0.000000] Normal empty <6>[ 0.000000] Movable zone start for each node <6>[ 0.000000] Early memory node ranges <6>[ 0.000000] node 0: [mem 0x0000000000000000-0x000000003b3fffff] <6>[ 0.000000] node 0: [mem 0x0000000040000000-0x00000000fbffffff] <6>[ 0.000000] Initmem setup node 0 [mem 0x0000000000000000-0x00000000fbffffff] <6>[ 0.000000] percpu: Embedded 23 pages/cpu s53272 r8192 d32744 u94208 <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] CPU features: detected: ARM erratum 1319367 <6>[ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 996912 <6>[ 0.000000] Policy zone: DMA32 <5>[ 0.000000] Kernel command line: console=ttyS1,115200n8 root=/dev/ram0 console_msg_format=syslog ip=dhcp <6>[ 0.000000] Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) <6>[ 0.000000] Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) <6>[ 0.000000] mem auto-init: stack:off, heap alloc:off, heap free:off <6>[ 0.000000] software IO TLB: mapped [mem 0x32000000-0x36000000] (64MB) <6>[ 0.000000] Memory: 3798776K/4050944K available (12348K kernel code, 1968K rwdata, 6952K rodata, 5312K init, 462K bss, 186632K reserved, 65536K cma-reserved) <6>[ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 <6>[ 0.000000] rcu: Preemptible hierarchical RCU implementation. <6>[ 0.000000] rcu: RCU event tracing is enabled. <6>[ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=256 to nr_cpu_ids=4. <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=4 <6>[ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 <6>[ 0.000000] GIC: Using split EOI/Deactivate mode <5>[ 0.000000] random: get_random_bytes called from start_kernel+0x2a8/0x434 with crng_init=0 <6>[ 0.000000] arch_timer: cp15 timer(s) running at 54.00MHz (phys). <6>[ 0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0xc743ce346, max_idle_ns: 440795203123 ns <6>[ 0.000005] sched_clock: 56 bits at 54MHz, resolution 18ns, wraps every 4398046511102ns <6>[ 0.000180] Console: colour dummy device 80x25 <6>[ 0.000266] Calibrating delay loop (skipped), value calculated using timer frequency.. 108.00 BogoMIPS (lpj=216000) <6>[ 0.000280] pid_max: default: 32768 minimum: 301 <6>[ 0.000381] LSM: Security Framework initializing <6>[ 0.000508] Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) <6>[ 0.000577] Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) <6>[ 0.032022] rcu: Hierarchical SRCU implementation. <6>[ 0.041792] EFI services will not be available. <6>[ 0.048062] smp: Bringing up secondary CPUs ... <6>[ 0.080195] Detected PIPT I-cache on CPU1 <6>[ 0.080252] CPU1: Booted secondary processor 0x0000000001 [0x410fd083] <6>[ 0.112261] Detected PIPT I-cache on CPU2 <6>[ 0.112301] CPU2: Booted secondary processor 0x0000000002 [0x410fd083] <6>[ 0.144344] Detected PIPT I-cache on CPU3 <6>[ 0.144382] CPU3: Booted secondary processor 0x0000000003 [0x410fd083] <6>[ 0.144465] smp: Brought up 1 node, 4 CPUs <6>[ 0.144493] SMP: Total of 4 processors activated. <6>[ 0.144503] CPU features: detected: 32-bit EL0 Support <6>[ 0.144513] CPU features: detected: CRC32 instructions <6>[ 0.161253] CPU: All CPU(s) started at EL2 <6>[ 0.161293] alternatives: patching kernel code <6>[ 0.163016] devtmpfs: initialized <4>[ 0.167433] KASLR disabled due to lack of seed <6>[ 0.167833] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns <6>[ 0.167856] futex hash table entries: 1024 (order: 4, 65536 bytes, linear) <6>[ 0.169670] pinctrl core: initialized pinctrl subsystem <6>[ 0.170949] thermal_sys: Registered thermal governor 'step_wise' <6>[ 0.170953] thermal_sys: Registered thermal governor 'power_allocator' <6>[ 0.171152] DMI not present or invalid. <6>[ 0.171762] NET: Registered protocol family 16 <6>[ 0.173256] DMA: preallocated 256 KiB pool for atomic allocations <6>[ 0.173274] audit: initializing netlink subsys (disabled) <5>[ 0.173446] audit: type=2000 audit(0.172:1): state=initialized audit_enabled=0 res=1 <6>[ 0.174572] cpuidle: using governor menu <6>[ 0.174837] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. <6>[ 0.174927] ASID allocator initialised with 65536 entries <6>[ 0.176175] Serial: AMBA PL011 UART driver <6>[ 0.196125] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages <6>[ 0.196139] HugeTLB registered 32.0 MiB page size, pre-allocated 0 pages <6>[ 0.196149] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages <6>[ 0.196158] HugeTLB registered 64.0 KiB page size, pre-allocated 0 pages <6>[ 0.198803] cryptd: max_cpu_qlen set to 1000 <6>[ 0.203647] ACPI: Interpreter disabled. <6>[ 0.204889] iommu: Default domain type: Translated <6>[ 0.205113] vgaarb: loaded <5>[ 0.205429] SCSI subsystem initialized <6>[ 0.205825] usbcore: registered new interface driver usbfs <6>[ 0.205868] usbcore: registered new interface driver hub <6>[ 0.205940] usbcore: registered new device driver usb <4>[ 0.206093] usb_phy_generic phy: phy supply vcc not found, using dummy regulator <6>[ 0.206695] pps_core: LinuxPPS API ver. 1 registered <6>[ 0.206705] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <[email protected]> <6>[ 0.206723] PTP clock support registered <6>[ 0.206841] EDAC MC: Ver: 3.0.0 <6>[ 0.208008] FPGA manager framework <6>[ 0.208087] Advanced Linux Sound Architecture Driver Initialized. <6>[ 0.208851] clocksource: Switched to clocksource arch_sys_counter <5>[ 0.209054] VFS: Disk quotas dquot_6.6.0 <6>[ 0.209122] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) <6>[ 0.209310] pnp: PnP ACPI: disabled <6>[ 0.215597] NET: Registered protocol family 2 <6>[ 0.216062] tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) <6>[ 0.216101] TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) <6>[ 0.216287] TCP bind hash table entries: 32768 (order: 7, 524288 bytes, linear) <6>[ 0.216713] TCP: Hash tables configured (established 32768 bind 32768) <6>[ 0.216938] UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) <6>[ 0.216991] UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) <6>[ 0.217181] NET: Registered protocol family 1 <6>[ 0.217697] RPC: Registered named UNIX socket transport module. <6>[ 0.217707] RPC: Registered udp transport module. <6>[ 0.217715] RPC: Registered tcp transport module. <6>[ 0.217722] RPC: Registered tcp NFSv4.1 backchannel transport module. <6>[ 0.217735] PCI: CLS 0 bytes, default 64 <6>[ 0.217942] Unpacking initramfs... <6>[ 1.261831] Freeing initrd memory: 13456K <6>[ 1.262721] hw perfevents: enabled with armv8_cortex_a72 PMU driver, 7 counters available <6>[ 1.262845] kvm [1]: IPA Size Limit: 44bits <6>[ 1.263833] kvm [1]: vgic interrupt IRQ1 <6>[ 1.263984] kvm [1]: Hyp mode initialized successfully <5>[ 1.337686] Initialise system trusted keyrings <6>[ 1.337860] workingset: timestamp_bits=44 max_order=20 bucket_order=0 <6>[ 1.344970] squashfs: version 4.0 (2009/01/31) Phillip Lougher <5>[ 1.345751] NFS: Registering the id_resolver key type <5>[ 1.345777] Key type id_resolver registered <5>[ 1.345786] Key type id_legacy registered <6>[ 1.345807] nfs4filelayout_init: NFSv4 File Layout Driver Registering... <6>[ 1.345971] 9p: Installing v9fs 9p2000 file system support <5>[ 1.381372] Key type asymmetric registered <5>[ 1.381386] Asymmetric key parser 'x509' registered <6>[ 1.381442] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 245) <6>[ 1.381451] io scheduler mq-deadline registered <6>[ 1.381460] io scheduler kyber registered <4>[ 1.391672] EINJ: ACPI disabled. <6>[ 1.405364] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled <6>[ 1.407089] printk: console [ttyS1] disabled <6>[ 1.407172] fe215040.serial: ttyS1 at MMIO 0xfe215040 (irq = 18, base_baud = 62499999) is a 16550 <6>[ 2.258130] printk: console [ttyS1] enabled <6>[ 2.263698] SuperH (H)SCI(F) driver initialized <6>[ 2.269076] msm_serial: driver initialized <4>[ 2.274870] cacheinfo: Unable to detect cache hierarchy for CPU 0 <6>[ 2.289652] loop: module loaded <6>[ 2.294178] bcm2835-power bcm2835-power: Broadcom BCM2835 power domains driver <6>[ 2.306388] libphy: Fixed MDIO Bus: probed <6>[ 2.311109] tun: Universal TUN/TAP device driver, 1.6 <6>[ 2.317250] thunder_xcv, ver 1.0 <6>[ 2.320833] thunder_bgx, ver 1.0 <6>[ 2.324420] nicpf, ver 1.0 <6>[ 2.327983] hclge is initializing <6>[ 2.331812] hns3: Hisilicon Ethernet Network Driver for Hip08 Family - version <6>[ 2.339426] hns3: Copyright (c) 2017 Huawei Corporation. <6>[ 2.345161] e1000e: Intel(R) PRO/1000 Network Driver - 3.2.6-k <6>[ 2.351352] e1000e: Copyright(c) 1999 - 2015 Intel Corporation. <6>[ 2.357666] igb: Intel(R) Gigabit Ethernet Network Driver - version 5.6.0-k <6>[ 2.365000] igb: Copyright (c) 2007-2014 Intel Corporation. <6>[ 2.370970] igbvf: Intel(R) Gigabit Virtual Function Network Driver - version 2.4.0-k <6>[ 2.379189] igbvf: Copyright (c) 2009 - 2012 Intel Corporation. <6>[ 2.385806] sky2: driver version 1.30 <6>[ 2.390476] VFIO - User Level meta-driver version: 0.3 <6>[ 2.397636] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver <6>[ 2.404543] ehci-pci: EHCI PCI platform driver <6>[ 2.409354] ehci-platform: EHCI generic platform driver <6>[ 2.415040] ehci-orion: EHCI orion driver <6>[ 2.419471] ehci-exynos: EHCI Exynos driver <6>[ 2.424076] ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver <6>[ 2.430638] ohci-pci: OHCI PCI platform driver <6>[ 2.435450] ohci-platform: OHCI generic platform driver <6>[ 2.441131] ohci-exynos: OHCI Exynos driver <6>[ 2.446082] usbcore: registered new interface driver usb-storage <6>[ 2.455057] i2c /dev entries driver <6>[ 2.463356] bcm2835-wdt bcm2835-wdt: Broadcom BCM2835 watchdog timer <6>[ 2.471039] sdhci: Secure Digital Host Controller Interface driver <6>[ 2.477592] sdhci: Copyright(c) Pierre Ossman <6>[ 2.482680] Synopsys Designware Multimedia Card Interface Driver <6>[ 2.489977] sdhci-pltfm: SDHCI platform and OF driver helper <6>[ 2.498211] ledtrig-cpu: registered to indicate activity on CPUs <6>[ 2.506087] usbcore: registered new interface driver usbhid <6>[ 2.512020] usbhid: USB HID core driver <6>[ 2.517036] bcm2835-mbox fe00b880.mailbox: mailbox enabled <6>[ 2.526214] NET: Registered protocol family 17 <6>[ 2.531190] 9pnet: Installing 9P2000 support <5>[ 2.535846] Key type dns_resolver registered <6>[ 2.540776] registered taskstats version 1 <5>[ 2.545215] Loading compiled-in X.509 certificates <6>[ 2.554826] fe201000.serial: ttyAMA0 at MMIO 0xfe201000 (irq = 16, base_baud = 0) is a PL011 rev2 <6>[ 2.564290] serial serial0: tty port ttyAMA0 registered <6>[ 2.579847] raspberrypi-firmware soc:firmware: Attached to firmware from 2020-01-22 17:23 <4>[ 2.592761] dwc2 fe980000.usb: fe980000.usb supply vusb_d not found, using dummy regulator <4>[ 2.601550] dwc2 fe980000.usb: fe980000.usb supply vusb_a not found, using dummy regulator <6>[ 2.711058] dwc2 fe980000.usb: EPs: 8, dedicated fifos, 4080 entries in SPRAM <6>[ 2.722353] sdhci-iproc fe300000.sdhci: allocated mmc-pwrseq <6>[ 2.761144] mmc0: SDHCI controller on fe300000.sdhci [fe300000.sdhci] using PIO <4>[ 2.787085] mmc0: queuing unknown CIS tuple 0x80 (2 bytes) <4>[ 2.794618] mmc0: queuing unknown CIS tuple 0x80 (3 bytes) <4>[ 2.802139] mmc0: queuing unknown CIS tuple 0x80 (3 bytes) <4>[ 2.811149] mmc0: queuing unknown CIS tuple 0x80 (7 bytes) <4>[ 2.818662] mmc0: queuing unknown CIS tuple 0x80 (3 bytes) <6>[ 2.824522] mmc1: SDHCI controller on fe340000.emmc2 [fe340000.emmc2] using ADMA <6>[ 2.834100] hctosys: unable to open rtc device (rtc0) <5>[ 2.885382] random: fast init done <6>[ 2.901797] mmc0: new high speed SDIO card at address 0001 <6>[ 2.944641] mmc1: new ultra high speed DDR50 SDHC card at address 59b4 <6>[ 2.952186] mmcblk1: mmc1:59b4 USDU1 7.51 GiB <6>[ 2.958630] mmcblk1: p1 p2 <6>[ 15.132906] ALSA device list: <6>[ 15.136184] No soundcards found. <6>[ 15.144551] Freeing unused kernel memory: 5312K <6>[ 15.149622] Run /init as init process Starting syslogd: OK Starting klogd: OK Populating /dev using udev: <30>[ 15.206510] udevd[149]: starting version 3.2.7 <5>[ 15.212099] random: udevd: uninitialized urandom read (16 bytes read) <5>[ 15.219130] random: udevd: uninitialized urandom read (16 bytes read) <5>[ 15.225997] random: udevd: uninitialized urandom read (16 bytes read) <27>[ 15.235005] udevd[149]: specified group 'kvm' unknown <30>[ 15.245749] udevd[150]: starting eudev-3.2.7 <6>[ 15.418698] brcm-pcie fd500000.pcie: host bridge /scb/pcie@7d500000 ranges: <6>[ 15.426333] brcm-pcie fd500000.pcie: No bus range found for /scb/pcie@7d500000, using [bus 00-ff] <6>[ 15.441701] brcm-pcie fd500000.pcie: MEM 0x0600000000..0x0603ffffff -> 0x00f8000000 <4>[ 15.442719] bcmgenet fd580000.ethernet: failed to get enet clock <6>[ 15.451712] brcm-pcie fd500000.pcie: IB MEM 0x0000000000..0x00bfffffff -> 0x0000000000 <6>[ 15.471257] iproc-rng200 fe104000.rng: hwrng registered <6>[ 15.476938] bcmgenet fd580000.ethernet: GENET 5.0 EPHY: 0x0000 <4>[ 15.483217] bcmgenet fd580000.ethernet: failed to get enet-wol clock <6>[ 15.488899] raspberrypi-clk raspberrypi-clk: CPU frequency range: min 600000000, max 1500000000 <4>[ 15.490490] bcmgenet fd580000.ethernet: failed to get enet-eee clock <5>[ 15.519319] cfg80211: Loading compiled-in X.509 certificates for regulatory database <6>[ 15.522793] brcm-pcie fd500000.pcie: link up, 5 GT/s x1 (SSC) <6>[ 15.539157] brcm-pcie fd500000.pcie: PCI host bridge to bus 0000:00 <6>[ 15.547077] pci_bus 0000:00: root bus resource [bus 00-ff] <6>[ 15.550689] Bluetooth: Core ver 2.22 <6>[ 15.553900] pci_bus 0000:00: root bus resource [mem 0x600000000-0x603ffffff] (bus address [0xf8000000-0xfbffffff]) <6>[ 15.557545] NET: Registered protocol family 31 <6>[ 15.569462] pci 0000:00:00.0: [14e4:2711] type 01 class 0x060400 <6>[ 15.573245] Bluetooth: HCI device and connection manager initialized <6>[ 15.579828] pci 0000:00:00.0: PME# supported from D0 D3hot <5>[ 15.585495] cfg80211: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' <4>[ 15.585603] platform regulatory.0: Direct firmware load for regulatory.db failed with error -2 <4>[ 15.585611] platform regulatory.0: Falling back to sysfs fallback for: regulatory.db <6>[ 15.586278] Bluetooth: HCI socket layer initialized <6>[ 15.594583] pci 0000:00:00.0: bridge configuration invalid ([bus 00-00]), reconfiguring <6>[ 15.599101] Bluetooth: L2CAP socket layer initialized <6>[ 15.609055] pci 0000:01:00.0: [1106:3483] type 00 class 0x0c0330 <6>[ 15.616673] Bluetooth: SCO socket layer initialized <6>[ 15.622754] pci 0000:01:00.0: reg 0x10: [mem 0x00000000-0x00000fff 64bit] <6>[ 15.652893] libphy: bcmgenet MII bus: probed <6>[ 15.654270] pci 0000:01:00.0: PME# supported from D0 D3cold <6>[ 15.658869] Bluetooth: HCI UART driver ver 2.3 <6>[ 15.667821] pci_bus 0000:01: busn_res: [bus 01-ff] end is updated to 01 <6>[ 15.669081] Bluetooth: HCI UART protocol H4 registered <6>[ 15.676321] pci 0000:00:00.0: BAR 14: assigned [mem 0x600000000-0x6000fffff] <6>[ 15.681655] Bluetooth: HCI UART protocol LL registered <6>[ 15.689260] pci 0000:01:00.0: BAR 0: assigned [mem 0x600000000-0x600000fff 64bit] <6>[ 15.693868] unimac-mdio unimac-mdio.-19: Broadcom UniMAC MDIO bus <6>[ 15.694820] Bluetooth: HCI UART protocol Broadcom registered <6>[ 15.702682] pci 0000:00:00.0: PCI bridge to [bus 01] <4>[ 15.708975] hci_uart_bcm serial0-0: serial0-0 supply vbat not found, using dummy regulator <6>[ 15.709039] Bluetooth: HCI UART protocol QCA registered <6>[ 15.715018] pci 0000:00:00.0: bridge window [mem 0x600000000-0x6000fffff] <4>[ 15.720389] hci_uart_bcm serial0-0: serial0-0 supply vddio not found, using dummy regulator <6>[ 15.729420] pcieport 0000:00:00.0: enabling device (0000 -> 0002) <6>[ 15.757388] pcieport 0000:00:00.0: PME: Signaling with IRQ 40 <6>[ 15.757589] brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6 <6>[ 15.765341] pcieport 0000:00:00.0: AER: enabled with IRQ 40 <4>[ 15.772938] brcmfmac mmc0:0001:1: Direct firmware load for brcm/brcmfmac43455-sdio.bin failed with error -2 <6>[ 15.778848] pci 0000:01:00.0: enabling device (0000 -> 0002) <4>[ 15.788692] brcmfmac mmc0:0001:1: Falling back to sysfs fallback for: brcm/brcmfmac43455-sdio.bin <6>[ 15.794770] pci 0000:01:00.0: quirk_usb_early_handoff+0x0/0x850 took 15538 usecs <6>[ 15.812143] xhci_hcd 0000:01:00.0: xHCI Host Controller <6>[ 15.817789] xhci_hcd 0000:01:00.0: new USB bus registered, assigned bus number 1 <6>[ 15.826099] xhci_hcd 0000:01:00.0: hcc params 0x002841eb hci version 0x100 quirks 0x0000000000000090 <6>[ 15.836949] hub 1-0:1.0: USB hub found <6>[ 15.841101] hub 1-0:1.0: 1 port detected <6>[ 15.845920] xhci_hcd 0000:01:00.0: xHCI Host Controller <6>[ 15.851518] xhci_hcd 0000:01:00.0: new USB bus registered, assigned bus number 2 <6>[ 15.859333] xhci_hcd 0000:01:00.0: Host supports USB 3.0 SuperSpeed <6>[ 15.866051] usb usb2: We don't know the algorithms for LPM for this host, disabling LPM. <6>[ 15.866196] uart-pl011 fe201000.serial: no DMA platform data <6>[ 15.875147] hub 2-0:1.0: USB hub found <6>[ 15.884682] hub 2-0:1.0: 4 ports detected done Initializing random number generator... done. Starting network: OK Starting dropbear sshd: <6>[ 16.106503] NET: Registered protocol family 10 <6>[ 16.112263] Segment Routing with IPv6 OK <6>[ 16.121549] Bluetooth: hci0: BCM: chip id 107 <6>[ 16.126631] Bluetooth: hci0: BCM: features 0x2f /bin/sh: can't access tt<6>[ 16.133065] Bluetooth: hci0: BCM4345C0 y; job c<6>[ 16.137754] Bluetooth: hci0: BCM4345C0 (003.001.025) build 0000 ontrol t<4>[ 16.144794] bluetooth hci0: Direct firmware load for brcm/BCM4345C0.hcd failed with error -2 urned of<4>[ 16.154260] bluetooth hci0: Falling back to sysfs fallback for: brcm/BCM4345C0.hcd f Matched prompt #10: / # case: kernel-messages
case_id: 3982683
definition: lava
duration: 14.71
extra: ...
level: 2.4.4.1
namespace: common
result: pass
Setting prompt string to ['/ #'] end: 2.4.4.1 login-action (duration 00:00:15) [common] case: login-action
case_id: 3982684
definition: lava
duration: 14.72
extra: ...
level: 2.4.4.1
namespace: common
result: pass
end: 2.4.4 auto-login-action (duration 00:00:17) [common] end: 2.4 uboot-commands (duration 00:01:00) [common] end: 2 uboot-action (duration 00:01:03) [common] start: 3 finalize (timeout 00:08:41) [common] start: 3.1 power-off (timeout 00:00:30) [common] Calling: 'nice' '/usr/local/bin/acme-cli' '-s' '192.168.66.2' 'switch_off' '7' >> Success Returned 0 in 3 seconds end: 3.1 power-off (duration 00:00:04) [common] case: power-off
case_id: 3982685
definition: lava
duration: 3.59
extra: ...
level: 3.1
namespace: common
result: pass
start: 3.2 read-feedback (timeout 00:08:37) [common] / # <6>[ 16.180878] usb 1-1: new high-speed USB device number 2 using xhci_hcd <6>[ 16.335243] hub 1-1:1.0: USB hub found <6>[ 16.339502] hub 1-1:1.0: 4 ports detected <6>[ 16.636877] usb 1-1.3: new high-speed USB device number 3 using xhci_hcd <6>[ 16.743819] hub 1-1.3:1.0: USB hub found <6>[ 16.748273] hub 1-1.3:1.0: 4 ports detected <6>[ 17.056876] usb 1-1.3.1: new high-speed USB device number 4 using xhci_hcd <6>[ 17.179975] hub 1-1.3.1:1.0: USB hub found <6>[ 17.184561] hub 1-1.3.1:1.0: 4 ports detected <6>[ 17.492875] usb 1-1.3.1.1: new high-speed USB device number 5 using xhci_hcd <2>[ 17.500906] hwmon hwmon1: Undervoltage detected! Listened to connection for namespace 'common' for up to 1s 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:05) [common] Cleaning after the job Cleaning up download directory: /var/lib/lava/dispatcher/tmp/34400/tftp-deploy-ziac5h7b/ramdisk Cleaning up download directory: /var/lib/lava/dispatcher/tmp/34400/tftp-deploy-ziac5h7b/kernel Cleaning up download directory: /var/lib/lava/dispatcher/tmp/34400/tftp-deploy-ziac5h7b/dtb Cleaning up download directory: /var/lib/lava/dispatcher/tmp/34400/tftp-deploy-ziac5h7b/modules Override tmp directory removed at /var/lib/lava/dispatcher/tmp/34400 Root tmp directory removed at /var/lib/lava/dispatcher/tmp/34400 Job finished correctly case: job
case_id: 3982686
definition: lava
result: pass