lava-dispatcher, installed at version: 2021.11.0112.g9b3a9a21d
start: 0 validate
Start time: 2022-01-14 09:09:39.560849+00:00 (UTC)
Validating that http://images.validation.linaro.org/snapshots.linaro.org/components/lava/standard/debian/jessie/armhf/4/vmlinuz exists
validate duration: 0.05
case: validate
case_id: 77211
definition: lava
result: pass
start: 1 tftp-deploy (timeout 00:04:00) [common]
start: 1.1 download-retry (timeout 00:04:00) [common]
start: 1.1.1 http-download (timeout 00:04:00) [common]
downloading http://images.validation.linaro.org/snapshots.linaro.org/components/lava/standard/debian/jessie/armhf/4/vmlinuz
saving as /var/lib/lava/dispatcher/tmp/lavafed-4143/tftp-deploy-r2ayjmgg/kernel/vmlinuz
total size: 3187808 (3MB)
No compression specified
progress 1% (0MB)
progress 6% (0MB)
progress 11% (0MB)
progress 16% (0MB)
progress 21% (0MB)
progress 26% (0MB)
progress 31% (0MB)
progress 37% (1MB)
progress 42% (1MB)
progress 47% (1MB)
progress 52% (1MB)
progress 57% (1MB)
progress 62% (1MB)
progress 67% (2MB)
progress 72% (2MB)
progress 77% (2MB)
progress 82% (2MB)
progress 87% (2MB)
progress 92% (2MB)
progress 97% (2MB)
3MB downloaded in 0.14s (22.21MB/s)
end: 1.1.1 http-download (duration 00:00:00) [common]
case: http-download
case_id: 77212
definition: lava
duration: 0.14
extra: ...
level: 1.1.1
namespace: common
result: pass
end: 1.1 download-retry (duration 00:00:00) [common]
start: 1.2 prepare-tftp-overlay (timeout 00:04:00) [common]
start: 1.2.1 extract-nfsrootfs (timeout 00:04:00) [common]
end: 1.2.1 extract-nfsrootfs (duration 00:00:00) [common]
start: 1.2.2 lava-overlay (timeout 00:04:00) [common]
[common] skipped lava-overlay - no test action.
end: 1.2.2 lava-overlay (duration 00:00:00) [common]
start: 1.2.3 extract-overlay-ramdisk (timeout 00:04:00) [common]
end: 1.2.3 extract-overlay-ramdisk (duration 00:00:00) [common]
start: 1.2.4 extract-modules (timeout 00:04:00) [common]
end: 1.2.4 extract-modules (duration 00:00:00) [common]
start: 1.2.5 apply-overlay-tftp (timeout 00:04:00) [common]
[common] No overlay directory
end: 1.2.5 apply-overlay-tftp (duration 00:00:00) [common]
start: 1.2.6 prepare-kernel (timeout 00:04:00) [common]
start: 1.2.6.1 uboot-prepare-kernel (timeout 00:04:00) [common]
end: 1.2.6.1 uboot-prepare-kernel (duration 00:00:00) [common]
end: 1.2.6 prepare-kernel (duration 00:00:00) [common]
start: 1.2.7 configure-preseed-file (timeout 00:04:00) [common]
end: 1.2.7 configure-preseed-file (duration 00:00:00) [common]
start: 1.2.8 compress-ramdisk (timeout 00:04:00) [common]
end: 1.2.8 compress-ramdisk (duration 00:00:00) [common]
end: 1.2 prepare-tftp-overlay (duration 00:00:00) [common]
start: 1.3 lxc-create-udev-rule-action (timeout 00:04:00) [common]
No LXC device requested
end: 1.3 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:00) [common]
start: 2 boot-bootloader-retry (timeout 00:03:00) [common]
start: 2.1 bootloader-overlay (timeout 00:03:00) [common]
substitutions:
- {BOOTX}: bootz 0x82000000 - 0x88000000
- {DTB_ADDR}: 0x88000000
- {DTB}: None
- {INITRD}: None
- {KERNEL_ADDR}: 0x82000000
- {KERNEL}: lavafed-4143/tftp-deploy-r2ayjmgg/kernel/vmlinuz
- {LAVA_MAC}: None
- {PRESEED_CONFIG}: None
- {PRESEED_LOCAL}: None
- {RAMDISK_ADDR}: -
- {RAMDISK}: None
- {ROOT_PART}: None
- {ROOT}: None
- {SERVER_IP}: 10.3.1.1
- {TEE_ADDR}: 0x83000000
- {TEE}: None
Parsed boot commands:
end: 2.1 bootloader-overlay (duration 00:00:00) [common]
case: bootloader-overlay
case_id: 77213
definition: lava
duration: 0.00
extra: ...
level: 2.1
namespace: common
result: pass
start: 2.2 boot-bootloader (timeout 00:03:00) [common]
start: 2.2.1 connect-device (timeout 00:03:00) [common]
[common] connect-device Connecting to device using 'telnet staging01 7103'
Setting prompt string to ['lava-test: # ']
end: 2.2.1 connect-device (duration 00:00:01) [common]
start: 2.2.2 reset-device (timeout 00:02:59) [common]
start: 2.2.2.1 pdu-reboot (timeout 00:02:59) [common]
Calling: 'nice' '/usr/local/lab-scripts/snmp_pdu_control' '--hostname' 'pdu15' '--command' 'reboot' '--port' '12'
>> pdu15 port 12 now powered off
>> pdu15 port 12 now powered on
>> pdu15 port 12 now power cycled
Returned 0 in 12 seconds
end: 2.2.2.1 pdu-reboot (duration 00:00:12) [common]
case: pdu-reboot
case_id: 77216
definition: lava
duration: 12.35
extra: ...
level: 2.2.2.1
namespace: common
result: pass
end: 2.2.2 reset-device (duration 00:00:12) [common]
start: 2.2.3 bootloader-interrupt (timeout 00:02:47) [common]
Setting prompt string to ['Hit any key to stop autoboot']
bootloader-interrupt: Wait for prompt ['Hit any key to stop autoboot'] (timeout 00:03:00)
Trying 10.3.1.1...
Connected to staging01.lavalab.
Escape character is '^]'.
black-01 7103 [s]
U-Boot SPL 2014.04-00014-g47880f5 (Apr 22 2014 - 13:23:54)
reading args
spl_load_image_fat_os: error reading image args, err - -1
reading u-boot.img
reading u-boot.img
U-Boot 2014.04-00014-g47880f5 (Apr 22 2014 - 13:23:54)
I2C: ready
DRAM: 512 MiB
NAND: 0 MiB
MMC: OMAP SD/MMC: 0, OMAP SD/MMC: 1
*** Warning - readenv() failed, using default environment
Net: <ethaddr> not set. Validating first E-fuse MAC
cpsw, usb_ether
Hit any key to stop autoboot: 1
end: 2.2.3 bootloader-interrupt (duration 00:00:02) [common]
start: 2.2.4 bootloader-commands (timeout 00:02:45) [common]
Setting prompt string to ['U-Boot']
bootloader-commands: Wait for prompt ['U-Boot'] (timeout 00:02:45)
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']
end: 2.2.4 bootloader-commands (duration 00:00:00) [common]
end: 2.2 boot-bootloader (duration 00:00:15) [common]
end: 2 boot-bootloader-retry (duration 00:00:15) [common]
start: 3 lava-test-interactive-retry (timeout 00:04:00) [common]
start: 3.1 lava-test-interactive (timeout 00:04:00) [common]
Setting prompt string to ['=> ', 'U-Boot# ']
Test interactive timeout: 30s (minimum of the action and connection timeout)
Sending 'echo "u-boot echo test"'
echo "u-boot echo test"
U-Boot# echo \"u-boot echo test\"
Waiting for '=> ', 'U-Boot# ', 'u-boot echo test'
Matched a success: 'u-boot echo test'
echo \"u-boot echo test\"
u-boot echo test
Matched a prompt: 'U-Boot# '
case: echo
case_id: 77217
definition: 0_basic-cmds
duration: 0.11
result: pass
Sending 'version'
version
U-Boot# version
Waiting for '=> ', 'U-Boot# ', 'U-Boot'
version
Matched a success: 'U-Boot'
U-Boot 2014.04-00014-g47880f5 (Apr 22 2014 - 13:23:54)
arm-linux-gnueabihf-gcc (crosstool-NG linaro-1.13.1-4.8-2014.03 - Linaro GCC 2014.03) 4.8.3 20140303 (prerelease)
GNU ld (crosstool-NG linaro-1.13.1-4.8-2014.03 - Linaro GCC 2014.03) 2.24.0.20140311 Linaro 2014.03
Matched a prompt: 'U-Boot# '
case: version
case_id: 77218
definition: 0_basic-cmds
duration: 0.14
result: pass
Sending 'help test'
help test
U-Boot# help test
Waiting for '=> ', 'U-Boot# ', 'test - minimal test like /bin/sh'
help test
test - minimal test like /bin/sh
Usage:
Matched a success: 'test - minimal test like /bin/sh'
test [args..]
Matched a prompt: 'U-Boot# '
case: help
case_id: 77219
definition: 0_basic-cmds
duration: 0.13
result: pass
Sending 'setenv test_var test123printenv'
setenv test_var test123printenv
U-Boot# setenv test_var test123printenv
Waiting for '=> ', 'U-Boot# '
setenv test_var test123printenv
Matched a prompt: 'U-Boot# '
Sending 'printenv'
printenv
U-Boot# printenv
Waiting for '=> ', 'U-Boot# ', 'test_var=test123'
printenv
arch=arm
baudrate=115200
board=am335x
board_name=A335BNLT
board_rev=0A5A
boot_fdt=try
bootcmd=gpio set 53; i2c mw 0x24 1 0x3e; run findfdt; run mmcboot;gpio clear 56; gpio clear 55; gpio clear 54; setenv mmcdev 1; setenv bootpart 1:1; run mmcboot;run nandboot;
bootcount=1
bootdelay=1
bootenv=uEnv.txt
bootfile=zImage
bootpart=0:1
console=ttyO0,115200n8
cpu=armv7
dfu_alt_info_emmc=rawemmc mmc 0 3751936
dfu_alt_info_mmc=boot part 0 1;rootfs part 0 2;MLO fat 0 1;MLO.raw mmc 100 100;u-boot.img.raw mmc 300 400;spl-os-args.raw mmc 80 80;spl-os-image.raw mmc 900 2000;spl-os-args fat 0 1;spl-os-image fat 0 1;u-boot.img fat 0 1;uEnv.txt fat 0 1
dfu_alt_info_nand=SPL part 0 1;SPL.backup1 part 0 2;SPL.backup2 part 0 3;SPL.backup3 part 0 4;u-boot part 0 5;u-boot-spl-os part 0 6;kernel part 0 8;rootfs part 0 9
dfu_alt_info_ram=kernel ram 0x80200000 0xD80000;fdt ram 0x80F80000 0x80000;ramdisk ram 0x81000000 0x4000000
eth1addr=c8:a0:30:c2:cf:71
ethact=cpsw
ethaddr=c8:a0:30:c2:cf:6f
fdt_high=0xffffffff
fdtaddr=0x88000000
fdtdir=/dtbs
fdtfile=undefined
findfdt=if test $board_name = A335BONE; then setenv fdtfile am335x-bone.dtb; setenv fdtbase am335x-bone; fi; if test $board_name = A335BNLT; then setenv fdtfile am335x-boneblack.dtb; setenv fdtbase am335x-boneblack; fi; if test $board_name = A33515BB; then setenv fdtfile am335x-evm.dtb; fi; if test $board_name = A335X_SK; then setenv fdtfile am335x-evmsk.dtb; fi; if test $fdtfile = undefined; then echo WARNING: Could not determine device tree to use; fi;
importbootenv=echo Importing environment from mmc ...; env import -t $loadaddr $filesize
loadaddr=0x82000000
loadbootenv=load mmc ${bootpart} ${loadaddr} ${bootenv}
loadfdt=load mmc ${bootpart} ${fdtaddr} ${fdtdir}/${fdtfile}
loadimage=load mmc ${bootpart} ${loadaddr} ${bootdir}/${bootfile}
loadramdisk=load mmc ${mmcdev} ${rdaddr} ramdisk.gz
mmcargs=setenv bootargs console=${console} ${optargs} root=${mmcroot} rootfstype=${mmcrootfstype}
mmcboot=mmc dev ${mmcdev}; if mmc rescan; then gpio set 54;echo SD/MMC found on device ${mmcdev};if run loadbootenv; then gpio set 55;echo Loaded environment from ${bootenv};run importbootenv;fi;if test -n $cape; then if test -e mmc ${bootpart} ${fdtdir}/$fdtbase-$cape.dtb; then setenv fdtfile $fdtbase-$cape.dtb; fi; echo using: $fdtfile...; fi; echo Checking if uenvcmd is set ...;if test -n $uenvcmd; then gpio set 56; echo Running uenvcmd ...;run uenvcmd;fi;echo; echo uenvcmd was not defined in uEnv.txt ...;fi;
mmcdev=0
mmcloados=run mmcargs; if test ${boot_fdt} = yes || test ${boot_fdt} = try; then if run loadfdt; then bootz ${loadaddr} - ${fdtaddr}; else if test ${boot_fdt} = try; then bootz; else echo WARN: Cannot load the DT; fi; fi; else bootz; fi;
mmcpart=1
mmcroot=/dev/mmcblk0p2 ro fixrtc
mmcrootfstype=ext4 rootwait
mtdids=nand0=omap2-nand.0
mtdparts=mtdparts=omap2-nand.0:128k(SPL),128k(SPL.backup1),128k(SPL.backup2),128k(SPL.backup3),1792k(u-boot),128k(u-boot-spl-os),128k(u-boot-env),5m(kernel),-(rootfs)
nandargs=setenv bootargs console=${console} ${optargs} root=${nandroot} rootfstype=${nandrootfstype}
nandboot=echo Booting from nand ...; run nandargs; nand read ${fdtaddr} u-boot-spl-os; nand read ${loadaddr} kernel; bootz ${loadaddr} - ${fdtaddr}
nandroot=ubi0:rootfs rw ubi.mtd=7,2048
nandrootfstype=ubifs rootwait=1
netargs=setenv bootargs console=${console} ${optargs} root=/dev/nfs nfsroot=${serverip}:${rootpath},${nfsopts} rw ip=dhcp
netboot=echo Booting from network ...; setenv autoload no; dhcp; tftp ${loadaddr} ${bootfile}; tftp ${fdtaddr} ${fdtfile}; run netargs; bootz ${loadaddr} - ${fdtaddr}
nfsopts=nolock
partitions=uuid_disk=${uuid_gpt_disk};name=rootfs,start=2MiB,size=-,uuid=${uuid_gpt_rootfs}
ramargs=setenv bootargs console=${console} ${optargs} root=${ramroot} rootfstype=${ramrootfstype}
ramboot=echo Booting from ramdisk ...; run ramargs; bootz ${loadaddr} ${rdaddr} ${fdtaddr}
ramroot=/dev/ram0 rw ramdisk_size=65536 initrd=${rdaddr},64M
ramrootfstype=ext2
rdaddr=0x88080000
rootpath=/export/rootfs
soc=am33xx
spiargs=setenv bootargs console=${console} ${optargs} root=${spiroot} rootfstype=${spirootfstype}
spiboot=echo Booting from spi ...; run spiargs; sf probe ${spibusno}:0; sf read ${loadaddr} ${spisrcaddr} ${spiimgsize}; bootz ${loadaddr}
spibusno=0
spiimgsize=0x362000
spiroot=/dev/mtdblock4 rw
spirootfstype=jffs2
spisrcaddr=0xe0000
static_ip=${ipaddr}:${serverip}:${gatewayip}:${netmask}:${hostname}::off
stderr=serial
stdin=serial
stdout=serial
test_var=test123printenv
usbnet_devaddr=c8:a0:30:c2:cf:71
vendor=ti
Matched a success: 'test_var=test123'
ver=U-Boot 2014.04-00014-g47880f5 (Apr 22 2014 - 13:23:54)
Environment size: 4737/131068 bytes
Matched a prompt: 'U-Boot# '
case: setenv-and-printenv
case_id: 77220
definition: 0_basic-cmds
duration: 0.53
result: pass
case: 0_basic-cmds
case_id: 77221
definition: lava
duration: 1.03
result: pass
Setting prompt string to ['=> ', 'U-Boot# ']
Test interactive timeout: 30s (minimum of the action and connection timeout)
Sending 'setenv autoload no ; dhcp'
setenv autoload no ; dhcp
U-Boot# setenv autoload no ; dhcp
Waiting for '=> ', 'U-Boot# ', 'DHCP client bound to address'
setenv autoload no ; dhcp
cpsw Waiting for PHY auto negotiation to complete. done
link up on port 0, speed 100, full duplex
BOOTP broadcast 1
BOOTP broadcast 2
BOOTP broadcast 3
DHCP client bound to address 10.15.1.249
Matched a success: 'DHCP client bound to address'
Matched a prompt: 'U-Boot# '
case: dhcp
case_id: 77228
definition: 0_dhcp-cmds
duration: 7.58
result: pass
case: 0_dhcp-cmds
case_id: 77229
definition: lava
duration: 7.58
result: pass
Setting prompt string to ['=> ', 'U-Boot# ']
Test interactive timeout: 30s (minimum of the action and connection timeout)
Sending 'setenv serverip 10.3.1.1 ; tftp 0x82000000 lavafed-4143/tftp-deploy-r2ayjmgg/kernel/vmlinuz'
setenv serverip 10.3.1.1 ; tftp 0x82000000 lavafed-4143/tftp-deploy-r2ayjmgg/kernel/vmlinuz
U-Boot# setenv serverip 10.3.1.1 ; tftp 0x82000000 lavafed-4143/tftp-deploy-r2ayjmgg/kernel/vmlinuz
Waiting for '=> ', 'U-Boot# ', 'Bytes transferred ='
setenv serverip 10.3.1.1 ; tftp 0x82000000 lavafed-4143/tftp-deploy-r2ayjmgg/kernel/vmlinuz
link up on port 0, speed 100, full duplex
Using cpsw device
TFTP from server 10.3.1.1; our IP address is 10.15.1.249
Filename 'lavafed-4143/tftp-deploy-r2ayjmgg/kernel/vmlinuz'.
Load address: 0x82000000
Loading: *#################################################################
#################################################################
#################################################################
#######################
4.3 MiB/s
done
Matched a success: 'Bytes transferred ='
Bytes transferred = 3187808 (30a460 hex)
Matched a prompt: 'U-Boot# '
case: tftp
case_id: 77230
definition: 0_tftp-cmds
duration: 0.86
result: pass
case: 0_tftp-cmds
case_id: 77231
definition: lava
duration: 0.86
result: pass
end: 3.1 lava-test-interactive (duration 00:00:09) [common]
end: 3 lava-test-interactive-retry (duration 00:00:09) [common]
start: 4 finalize (timeout 00:00:30) [common]
start: 4.1 power-off (timeout 00:00:10) [common]
Calling: 'nice' '/usr/local/lab-scripts/snmp_pdu_control' '--hostname' 'pdu15' '--command' 'off' '--port' '12'
>> pdu15 port 12 now powered off
Returned 0 in 1 seconds
end: 4.1 power-off (duration 00:00:02) [common]
case: power-off
case_id: 77232
definition: lava
duration: 1.60
extra: ...
level: 4.1
namespace: common
result: pass
start: 4.2 read-feedback (timeout 00:00:28) [common]
Finalising connection for namespace 'common'
Disconnecting from telnet: Finalise
]
quit
U-Boot# quit
end: 4.2 read-feedback (duration 00:00:01) [common]
end: 4 finalize (duration 00:00:03) [common]
Cleaning after the job
Cleaning up download directory: /var/lib/lava/dispatcher/tmp/lavafed-4143/tftp-deploy-r2ayjmgg/kernel
Override tmp directory removed at /var/lib/lava/dispatcher/tmp/lavafed-4143
Root tmp directory removed at /var/lib/lava/dispatcher/tmp/lavafed-4143
Job finished correctly
case: job
case_id: 77233
definition: lava
result: pass
Please read the triage guidelines for help on debugging failures in the test job, test definitions or in individual test cases.
Pipeline jobs use the job definition to generate a descriptive YAML file when the pipeline is validated. The file represents the data sent to the dispatcher at the start of the job, including details of the device as well as the job parameters and the details of the parameters sent to each of the deploy, boot and test actions in the job pipeline.
Click on the Metadata, Device or Job Actions headings below to see some of the information from the description file for this job or download the complete file as YAML using the link above.
This section helps test writers to check for actions with a duration which is much shorter than the requested timeout. Reducing these timeouts will allow failures to be identified more quickly.
The graph only shows actions that are longer than 1 second. The full list is anyway available in the table.