ava-dispatcher, installed at version: 2020.05 start: 0 validate Start time: 2020-06-01 16:16:23.852190+00:00 (UTC) validate duration: 0.00 case: validate case_id: 2541 definition: lava result: pass start: 1 boot-bootloader (timeout 00:05:00) [common] start: 1.1 connect-device (timeout 00:05:00) [common] [common] connect-device Connecting to device using 'telnet localhost 2000' Setting prompt string to ['lava-test: # '] end: 1.1 connect-device (duration 00:00:00) [common] start: 1.2 bootloader-overlay (timeout 00:05:00) [common] substitutions: - {DTB}: None - {INITRD}: None - {KERNEL}: None - {LAVA_MAC}: None - {PRESEED_CONFIG}: None - {PRESEED_LOCAL}: None - {RAMDISK}: None - {ROOT_PART}: None - {ROOT}: None - {SERVER_IP}: 10.10.2.220 Parsed boot commands: end: 1.2 bootloader-overlay (duration 00:00:00) [common] start: 1.3 boot-bootloader-retry (timeout 00:05:00) [common] start: 1.3.1 reset-device (timeout 00:05:00) [common] start: 1.3.1.1 pdu-reboot (timeout 00:05:00) [common] Calling: 'nice' '/home/lava/lava-lab/shared/lab-scripts/web-power-switch-ccl' >> >> >> >> >> >> Returned 0 in 0 seconds end: 1.3.1.1 pdu-reboot (duration 00:00:00) [common] case: pdu-reboot case_id: 2542 definition: lava duration: 0.20 extra: ... level: 1.3.1.1 namespace: common result: pass end: 1.3.1 reset-device (duration 00:00:00) [common] start: 1.3.2 bootloader-interrupt (timeout 00:05:00) [common] Not interrupting bootloader, waiting for bootloader prompt Setting prompt string to ['=>'] bootloader-interrupt: Wait for prompt ['=>'] (timeout 00:05:00) Trying ::1... Connected to localhost. Escape character is '^]'. U-Boot 2017.05-g2af6a0f (Mar 07 2019 - 15:17:40 -0500) CPU: Renesas Electronics CPU rev 0.0 Board: RSKRZA1 I2C: ready DRAM: 10 MiB MMC: sh-sdhi: 0 SF: Detected s25fl512s_256k with page size 512 Bytes, erase size 256 KiB, total 64 MiB *** Warning - bad CRC, using default environment In: serial_sh Out: serial_sh Err: serial_sh SPI Flash Memory Map ------------------------------------ Start Size SPI u-boot: 0x00000000 0x080000 0 env: 0x00080000 0x040000 0 DT: 0x000C0000 0x040000 0 Kernel: 0x00100000 0x280000 0+1 (size*=2) rootfs: 0x00400000 0x1C00000 0+1 (size*=2) Net: sh_eth end: 1.3.2 bootloader-interrupt (duration 00:00:11) [common] start: 1.3.3 bootloader-commands (timeout 00:04:49) [common] Setting prompt string to ['=>'] Setting prompt string to ['=>', 'Resetting CPU', 'Must RESET board to recover', 'TIMEOUT', 'Retry count exceeded', 'ERROR: The remote end did not respond in time.'] end: 1.3.3 bootloader-commands (duration 00:00:00) [common] end: 1.3 boot-bootloader-retry (duration 00:00:11) [common] end: 1 boot-bootloader (duration 00:00:11) [common] start: 2 lava-test-interactive-retry (timeout 00:05:00) [common] start: 2.1 lava-test-interactive (timeout 00:05:00) [common] Sending 'print' print => print Waiting for '=>' print baudrate=115200 bootargs=ignore_loglevel bootdelay=3 cmdline_common=ignore_loglevel earlyprintk earlycon dtb_lcdfb_dyn=fdt set /display@fcff7400 fb_phys_addr <0x00000000> dtb_lcdfb_fixed=fdt set /display@fcff7400 fb_phys_addr <0x60000000> dtb_mem_ram=fdt memory 0x20000000 0x00A00000 dtb_mem_sdram=fdt memory 0x08000000 0x02000000 dtb_read_ram=sf probe 0; sf read 20500000 C0000 8000; fdt addr 20500000 ; setenv addr_dtb 20500000 dtb_read_sdram=sf probe 0; sf read 09800000 C0000 8000; fdt addr 09800000 ; setenv addr_dtb 09800000 ethact=sh_eth ethaddr=32:33:34:35:ff:ff fs_axfs=rootfstype=axfs rootflags=physaddr=0x18800000 fs_mtd=root=/dev/mtdblock0 ipaddr=192.168.0.55 s_boot=run dtb_read_sdram dtb_mem_sdram dtb_lcdfb_fixed; setenv bootargs ${cmdline_common} ${fs_mtd}; fdt chosen; run uImg sa_boot=run dtb_read_sdram dtb_mem_sdram dtb_lcdfb_fixed; setenv bootargs ${cmdline_common} ${fs_axfs}; fdt chosen; run uImg serverip=192.168.0.1 stderr=serial_sh stdin=serial_sh stdout=serial_sh uImg=qspi dual; cp.b 0x18200000 09000000 0x400000; bootm start 09000000 - 09800000; bootm loados ; bootm go xImg=qspi dual; setenv cmd bootx 0x18200000 ${addr_dtb}; run cmd xa_boot=run dtb_read_ram dtb_mem_ram dtb_lcdfb_dyn; setenv bootargs ${cmdline_common} ${fs_axfs}; fdt chosen; run xImg xm_boot=run dtb_read_ram dtb_mem_ram dtb_lcdfb_dyn; setenv bootargs ${cmdline_common} ${fs_mtd}; fdt chosen; run xImg xsa_boot=run dtb_read_sdram dtb_mem_sdram dtb_lcdfb_fixed; setenv bootargs ${cmdline_common} ${fs_axfs}; fdt chosen; run xImg xsm_boot=run dtb_read_sdram dtb_mem_sdram dtb_lcdfb_fixed; setenv bootargs ${cmdline_common} ${fs_mtd}; fdt chosen; run xImg Environment size: 1707/262140 bytes Matched a prompt: '=>' case: command1 case_id: 2543 definition: 0_firmware duration: 0.26 result: pass Sending 'run xa_boot' run xa_boot => run xa_boot Waiting for '=>', 'Welcome to Buildroot buildroot login: root (auto login)' run xa_boot SF: Detected s25fl512s_256k with page size 512 Bytes, erase size 256 KiB, total 64 MiB device 0 offset 0xc0000, size 0x8000 SF: 32768 bytes @ 0xc0000 Read: OK Current Mode: Read Mode (3-byte Addr) (RZ/A1 reset value) SF: Dual SPI mode SF: Detected s25fl512s_256k with page size 512 Bytes, erase size 256 KiB, total 64 MiB New Mode: Quad I/O Read Mode (4-byte Addr) Booting Linux... [ 0.000000] Booting Linux on physical CPU 0x0 [ 0.000000] Linux version 4.19.19-g65b9ebc (chris@chris-SX2800) (gcc version 7.2.1 20171011 (Linaro GCC 7.2-2017.11)) #1 Thu Mar 7 15:38:13 EST 2019 [ 0.000000] CPU: ARMv7 Processor [413fc090] revision 0 (ARMv7), cr=58c53c7d [ 0.000000] CPU: PIPT / VIPT nonaliasing data cache, VIPT aliasing instruction cache [ 0.000000] OF: fdt: Machine model: RSKRZA1 [ 0.000000] debug: ignoring loglevel setting. [ 0.000000] earlycon: scif0 at MMIO 0xe8008000 (options '115200n8') [ 0.000000] bootconsole [scif0] enabled [ 0.000000] Memory policy: Data cache writeback [ 0.000000] On node 0 totalpages: 2560 [ 0.000000] Normal zone: 20 pages used for memmap [ 0.000000] Normal zone: 0 pages reserved [ 0.000000] Normal zone: 2560 pages, LIFO batch:0 [ 0.000000] CPU: All CPU(s) started in SVC mode. [ 0.000000] random: get_random_bytes called from start_kernel+0x57/0x2cc with crng_init=0 [ 0.000000] pcpu-alloc: s0 r0 d32768 u32768 alloc=1*32768 [ 0.000000] pcpu-alloc: [0] 0 [ 0.000000] Built 1 zonelists, mobility grouping off. Total pages: 2540 [ 0.000000] Kernel command line: ignore_loglevel earlyprintk earlycon rootfstype=axfs rootflags=physaddr=0x18800000 [ 0.000000] Dentry cache hash table entries: 2048 (order: 1, 8192 bytes) [ 0.000000] Inode-cache hash table entries: 1024 (order: 0, 4096 bytes) [ 0.000000] Memory: 9492K/10240K available (2742K kernel code, 179K rwdata, 836K rodata, 142K init, 269K bss, 748K reserved, 0K cma-reserved, 0K highmem) [ 0.000000] Virtual kernel memory layout: [ 0.000000] vector : 0xffff0000 - 0xffff1000 ( 4 kB) [ 0.000000] fixmap : 0xffc00000 - 0xfff00000 (3072 kB) [ 0.000000] vmalloc : 0xc1000000 - 0xff800000 (1000 MB) [ 0.000000] lowmem : 0xc0000000 - 0xc0a00000 ( 10 MB) [ 0.000000] pkmap : 0xbfe00000 - 0xc0000000 ( 2 MB) [ 0.000000] .text : 0x(ptrval) - 0x(ptrval) (3580 kB) [ 0.000000] .init : 0x(ptrval) - 0x(ptrval) ( 48 kB) [ 0.000000] .data : 0x(ptrval) - 0x(ptrval) ( 180 kB) [ 0.000000] .bss : 0x(ptrval) - 0x(ptrval) ( 270 kB) [ 0.000000] NR_IRQS: 16, nr_irqs: 16, preallocated irqs: 16 [ 0.000000] L2C-310 dynamic clock gating enabled, standby mode enabled [ 0.000000] L2C-310 cache controller enabled, 8 ways, 128 kB [ 0.000000] L2C-310: CACHE_ID 0x410000c8, AUX_CTRL 0x06020000 [ 0.000000] clocksource: ostm: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 57352151442 ns [ 0.000026] sched_clock: 32 bits at 33MHz, resolution 30ns, wraps every 64440619504ns [ 0.007833] ostm: used for clocksource [ 0.011945] ostm: used for clock events [ 0.016661] Console: colour dummy device 80x30 [ 0.021139] console [tty0] enabled [ 0.024920] bootconsole [scif0] disabled ��[ 0.000000] Booting Linux on physical CPU 0x0 [ 0.000000] Linux version 4.19.19-g65b9ebc (chris@chris-SX2800) (gcc version 7.2.1 20171011 (Linaro GCC 7.2-2017.11)) #1 Thu Mar 7 15:38:13 EST 2019 [ 0.000000] CPU: ARMv7 Processor [413fc090] revision 0 (ARMv7), cr=58c53c7d [ 0.000000] CPU: PIPT / VIPT nonaliasing data cache, VIPT aliasing instruction cache [ 0.000000] OF: fdt: Machine model: RSKRZA1 [ 0.000000] debug: ignoring loglevel setting. [ 0.000000] earlycon: scif0 at MMIO 0xe8008000 (options '115200n8') [ 0.000000] bootconsole [scif0] enabled [ 0.000000] Memory policy: Data cache writeback [ 0.000000] On node 0 totalpages: 2560 [ 0.000000] Normal zone: 20 pages used for memmap [ 0.000000] Normal zone: 0 pages reserved [ 0.000000] Normal zone: 2560 pages, LIFO batch:0 [ 0.000000] CPU: All CPU(s) started in SVC mode. [ 0.000000] random: get_random_bytes called from start_kernel+0x57/0x2cc with crng_init=0 [ 0.000000] pcpu-alloc: s0 r0 d32768 u32768 alloc=1*32768 [ 0.000000] pcpu-alloc: [0] 0 [ 0.000000] Built 1 zonelists, mobility grouping off. Total pages: 2540 [ 0.000000] Kernel command line: ignore_loglevel earlyprintk earlycon rootfstype=axfs rootflags=physaddr=0x18800000 [ 0.000000] Dentry cache hash table entries: 2048 (order: 1, 8192 bytes) [ 0.000000] Inode-cache hash table entries: 1024 (order: 0, 4096 bytes) [ 0.000000] Memory: 9492K/10240K available (2742K kernel code, 179K rwdata, 836K rodata, 142K init, 269K bss, 748K reserved, 0K cma-reserved, 0K highmem) [ 0.000000] Virtual kernel memory layout: [ 0.000000] vector : 0xffff0000 - 0xffff1000 ( 4 kB) [ 0.000000] fixmap : 0xffc00000 - 0xfff00000 (3072 kB) [ 0.000000] vmalloc : 0xc1000000 - 0xff800000 (1000 MB) [ 0.000000] lowmem : 0xc0000000 - 0xc0a00000 ( 10 MB) [ 0.000000] pkmap : 0xbfe00000 - 0xc0000000 ( 2 MB) [ 0.000000] .text : 0x(ptrval) - 0x(ptrval) (3580 kB) [ 0.000000] .init : 0x(ptrval) - 0x(ptrval) ( 48 kB) [ 0.000000] .data : 0x(ptrval) - 0x(ptrval) ( 180 kB) [ 0.000000] .bss : 0x(ptrval) - 0x(ptrval) ( 270 kB) [ 0.000000] NR_IRQS: 16, nr_irqs: 16, preallocated irqs: 16 [ 0.000000] L2C-310 dynamic clock gating enabled, standby mode enabled [ 0.000000] L2C-310 cache controller enabled, 8 ways, 128 kB [ 0.000000] L2C-310: CACHE_ID 0x410000c8, AUX_CTRL 0x06020000 [ 0.000000] clocksource: ostm: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 57352151442 ns [ 0.000026] sched_clock: 32 bits at 33MHz, resolution 30ns, wraps every 64440619504ns [ 0.007833] ostm: used for clocksource [ 0.011945] ostm: used for clock events [ 0.016661] Console: colour dummy device 80x30 [ 0.021139] console [tty0] enabled [ 0.024920] bootconsole [scif0] disabled [ 0.029007] Calibrating delay loop (skipped) preset value.. 800.00 BogoMIPS (lpj=4000000) [ 0.029077] pid_max: default: 32768 minimum: 301 [ 0.029550] Mount-cache hash table entries: 1024 (order: 0, 4096 bytes) [ 0.029606] Mountpoint-cache hash table entries: 1024 (order: 0, 4096 bytes) [ 0.031496] CPU: Testing write buffer coherency: ok [ 0.031733] CPU0: Spectre v2: using BPIALL workaround [ 0.034115] Setting up static identity map for 0x1f8001c0 - 0x1f8001f8 [ 0.036320] Detected Renesas RZ/A1 r7s72100 [ 0.039258] devtmpfs: initialized [ 0.047580] VFP support v0.3: implementor 41 architecture 3 part 30 variant 9 rev 4 [ 0.048400] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns [ 0.048525] futex hash table entries: 256 (order: -1, 3072 bytes) [ 0.048877] pinctrl core: initialized pinctrl subsystem [ 0.053620] NET: Registered protocol family 16 [ 0.056166] DMA: preallocated 256 KiB pool for atomic coherent allocations [ 0.058548] No ATAGs? [ 0.058662] hw-breakpoint: found 5 (+1 reserved) breakpoint and 1 watchpoint registers. [ 0.058767] hw-breakpoint: maximum watchpoint size is 4 bytes. [ 0.070674] pinctrl-rza1 fcfe3000.pin-controller: Parsed gpiochip gpio-0 with 6 pins [ 0.072988] pinctrl-rza1 fcfe3000.pin-controller: Parsed gpiochip gpio-1 with 16 pins [ 0.075833] pinctrl-rza1 fcfe3000.pin-controller: Parsed gpiochip gpio-2 with 16 pins [ 0.077496] pinctrl-rza1 fcfe3000.pin-controller: Parsed gpiochip gpio-3 with 16 pins [ 0.079720] pinctrl-rza1 fcfe3000.pin-controller: Parsed gpiochip gpio-4 with 16 pins [ 0.082439] pinctrl-rza1 fcfe3000.pin-controller: Parsed gpiochip gpio-5 with 11 pins [ 0.085495] pinctrl-rza1 fcfe3000.pin-controller: Parsed gpiochip gpio-6 with 16 pins [ 0.087138] pinctrl-rza1 fcfe3000.pin-controller: Parsed gpiochip gpio-7 with 16 pins [ 0.089480] pinctrl-rza1 fcfe3000.pin-controller: Parsed gpiochip gpio-8 with 16 pins [ 0.091865] pinctrl-rza1 fcfe3000.pin-controller: Parsed gpiochip gpio-9 with 8 pins [ 0.094717] pinctrl-rza1 fcfe3000.pin-controller: Parsed gpiochip gpio-10 with 16 pins [ 0.096244] pinctrl-rza1 fcfe3000.pin-controller: Parsed gpiochip gpio-11 with 16 pins [ 0.096371] pinctrl-rza1 fcfe3000.pin-controller: Registered 12 gpio controllers [ 0.096420] pinctrl-rza1 fcfe3000.pin-controller: RZ/A1 pin controller and gpio successfully registered [ 0.161158] SCSI subsystem initialized [ 0.161820] usbcore: registered new interface driver usbfs [ 0.162371] usbcore: registered new interface driver hub [ 0.162667] usbcore: registered new device driver usb [ 0.164252] sh_mtu2 fcff0000.timer: ch0: used for clock events [ 0.170477] sh_mtu2 fcff0000.timer: PM domain cpg_clocks will not be powered off [ 0.179641] clocksource: Switched to clocksource ostm [ 0.251037] NET: Registered protocol family 2 [ 0.253244] tcp_listen_portaddr_hash hash table entries: 512 (order: 0, 4096 bytes) [ 0.253387] TCP established hash table entries: 1024 (order: 0, 4096 bytes) [ 0.253456] TCP bind hash table entries: 1024 (order: 0, 4096 bytes) [ 0.253521] TCP: Hash tables configured (established 1024 bind 1024) [ 0.254013] UDP hash table entries: 256 (order: 0, 4096 bytes) [ 0.254105] UDP-Lite hash table entries: 256 (order: 0, 4096 bytes) [ 0.254708] NET: Registered protocol family 1 [ 0.268147] workingset: timestamp_bits=30 max_order=12 bucket_order=0 [ 0.269470] jffs2: version 2.2. (NAND) © 2001-2006 Red Hat, Inc. [ 0.279511] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 251) [ 0.279953] io scheduler noop registered [ 0.280009] io scheduler deadline registered [ 0.280210] io scheduler cfq registered (default) [ 0.280256] io scheduler mq-deadline registered [ 0.282093] pinctrl-rza1 fcfe3000.pin-controller: Parsed function and group vdc50 with 28 pins [ 0.298879] vdc5fb fcff7400.display: fcff7400.display: [gwp0700cnwv04] dotclock 33.325 MHz, dcdr 2 [ 0.349616] vdc5fb: Layer 2 Enabled (800x480 @ 0x20200000) [ 0.596481] Console: switching to colour frame buffer device 100x30 [ 0.646156] graphics fb0: registered fcff7400.display as 800x480 @ 60 Hz, 32 bpp. [ 0.658689] Serial: 8250/16550 driver, 4 ports, IRQ sharing disabled [ 0.674415] SuperH (H)SCI(F) driver initialized [ 0.683930] pinctrl-rza1 fcfe3000.pin-controller: Parsed function and group serial2 with 2 pins [ 0.694529] e8008000.serial: ttySC0 at MMIO 0xe8008000 (irq = 19, base_baud = 0) is a scif [ 1.365749] console [ttySC0] enabled [ 1.383645] 1 fixed-partitions partitions found on MTD device 18000000.qspi [ 1.400131] Creating 1 MTD partitions on \"18000000.qspi\": [ 1.414562] 0x000000800000-0x000001000000 : \"user\" [ 1.439132] libphy: Fixed MDIO Bus: probed [ 1.453442] pinctrl-rza1 fcfe3000.pin-controller: Parsed function and group ether with 18 pins [ 1.474435] libphy: sh_mii: probed [ 1.490618] sh-eth e8203000.ethernet eth0: Base address at 0xe8203000, 32:33:34:35:ff:ff, IRQ 58. [ 1.669761] r8a66597_hcd r8a66597_hcd.0: USB Host Controller [ 1.685041] r8a66597_hcd r8a66597_hcd.0: new USB bus registered, assigned bus number 1 [ 1.702664] r8a66597_hcd r8a66597_hcd.0: irq 65, io base 0xe8010000 [ 1.790363] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 4.19 [ 1.808157] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1 [ 1.824656] usb usb1: Product: USB Host Controller [ 1.838477] usb usb1: Manufacturer: Linux 4.19.19-g65b9ebc r8a66597_hcd [ 1.854264] usb usb1: SerialNumber: r8a66597_hcd [ 1.871723] hub 1-0:1.0: USB hub found [ 1.886932] hub 1-0:1.0: 1 port detected [ 2.059784] r8a66597_hcd r8a66597_hcd.1: USB Host Controller [ 2.075101] r8a66597_hcd r8a66597_hcd.1: new USB bus registered, assigned bus number 2 [ 2.092725] r8a66597_hcd r8a66597_hcd.1: irq 66, io base 0xe8207000 [ 2.180338] usb usb2: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 4.19 [ 2.197784] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1 [ 2.213997] usb usb2: Product: USB Host Controller [ 2.227607] usb usb2: Manufacturer: Linux 4.19.19-g65b9ebc r8a66597_hcd [ 2.243039] usb usb2: SerialNumber: r8a66597_hcd [ 2.259488] hub 2-0:1.0: USB hub found [ 2.274003] hub 2-0:1.0: 1 port detected [ 2.288848] usbcore: registered new interface driver usb-storage [ 2.310271] sh-rtc fcff1000.rtc: rtc core: registered sh as rtc0 [ 2.325502] i2c /dev entries driver [ 2.337849] pinctrl-rza1 fcfe3000.pin-controller: Parsed function and group i2c0 with 2 pins [ 2.361447] pinctrl-rza1 fcfe3000.pin-controller: Parsed function and group irq1 with 1 pins [ 2.379903] ft5x06-ts 0-0038: ft5x06_init_panel: could not detect ft5x06 [ 2.395031] ft5x06-ts 0-0038: could not init touch panel [ 2.408543] ft5x06-ts: probe of 0-0038 failed with error -5 [ 2.422289] i2c-riic fcfee000.i2c: registered with 400000Hz bus speed [ 2.437092] pinctrl-rza1 fcfe3000.pin-controller: Parsed function and group i2c3 with 2 pins [ 2.460820] i2c-riic fcfeec00.i2c: registered with 100000Hz bus speed [ 2.485742] pinctrl-rza1 fcfe3000.pin-controller: Parsed function and group sdhi1 with 8 pins [ 2.598178] sh_mobile_sdhi e804e800.sd: mmc0 base at 0xe804e800 max clock rate 66 MHz [ 2.617384] usbcore: registered new interface driver usbhid [ 2.631386] usbhid: USB HID core driver [ 2.644534] NET: Registered protocol family 10 [ 2.659829] random: fast init done [ 2.676875] Segment Routing with IPv6 [ 2.689086] sit: IPv6, IPv4 and MPLS over IPv4 tunneling driver [ 2.706009] NET: Registered protocol family 17 [ 2.726850] sh-rtc fcff1000.rtc: setting system clock to 1970-01-01 00:00:00 UTC (0) [ 2.745573] platform regulatory.0: Direct firmware load for regulatory.db failed with error -2 [ 2.763043] cfg80211: failed to load regulatory.db �[ 2.781965] VFS: Mounted root (axfs filesystem) readonly on device 0:11. [ 2.797982] devtmpfs: mounted [ 2.809755] Freeing unused kernel memory: 48K [ 2.822356] This architecture does not have kernel memory protection. [ 2.837060] Run /sbin/init as init process �Starting logging: OK read-only file system detected...done Starting network: OK Wed Oct 31 09:00:00 UTC 2018 [ 3.759014] Console: switching to colour dummy device 80x30 [?25lfbv - The Framebuffer Viewer /root/images/renesas_logo-800x480.jpg 800 x 480 [?25h Welcome to Buildroot buildroot login: [ 90.929835] random: crng init done case: start_kernel case_id: 2544 definition: 0_firmware duration: 299.74 result: fail case: 0_firmware case_id: 2545 definition: lava duration: 300.00 result: fail lava-test-interactive timed out after 300 seconds end: 2.1 lava-test-interactive (duration 00:05:00) [common] case: lava-test-interactive case_id: 2546 definition: lava duration: 300.00 extra: ... level: 2.1 namespace: common result: fail lava-test-interactive-retry failed: 1 of 1 attempts. 'lava-test-interactive timed out after 300 seconds' lava-test-interactive timed out after 300 seconds end: 2 lava-test-interactive-retry (duration 00:05:00) [common] case: lava-test-interactive-retry case_id: 2547 definition: lava duration: 300.00 extra: ... level: 2 namespace: common result: fail Cleaning after the job start: 4.1 power-off (timeout 00:00:10) [common] end: 4.1 power-off (duration 00:00:00) [common] case: power-off case_id: 2548 definition: lava duration: 0.00 extra: ... level: 4.1 namespace: common result: pass start: 4.2 read-feedback (timeout 00:03:00) [common] Listened to connection for namespace 'common' for 1s Finalising connection for namespace 'common' Disconnecting ShellSession Disconnecting from telnet: Finalise ] quit quit end: 4.2 read-feedback (duration 00:00:01) [common] Root tmp directory removed at /var/lib/lava/dispatcher/tmp/456 TestError: A test failed to run, look at the error message. case: job case_id: 2549 definition: lava error_msg: lava-test-interactive timed out after 300 seconds error_type: Test result: fail Top of page Please read the triage guidelines for help on debugging failures in the test job, test definitions or in individual test cases.