lava-dispatcher, installed at version: 2021.11.0022.g08fafe4de
start: 0 validate
Start time: 2021-12-03 09:11:20.922044+00:00 (UTC)
docker server, installed at version: 18.09.1
docker client, installed at version: 20.10.5+dfsg1
validate duration: 0.13
case: validate
case_id: 75026
definition: lava
result: pass
start: 1 deploy-docker (timeout 00:04:00) [common]
Calling: 'nice' 'docker' 'pull' 'debian:stretch-backports'
>> stretch-backports: Pulling from library/debian
>> Digest: sha256:dc6397fc28bd2cdc28bb4b12a38ee800c4b544734fad9be04a262d3b05806a4f
>> Status: Image is up to date for debian:stretch-backports
>> docker.io/library/debian:stretch-backports
Returned 0 in 2 seconds
start: 1.1 deploy-device-env (timeout 00:03:58) [common]
end: 1.1 deploy-device-env (duration 00:00:00) [common]
start: 1.2 lava-overlay (timeout 00:03:58) [common]
[common] Preparing overlay tarball in /var/lib/lava/dispatcher/tmp/lavafed-4023/lava-overlay-okq56eyf
makedir: /var/lib/lava/dispatcher/tmp/lavafed-4023/lava-overlay-okq56eyf/lava-4023/bin
makedir: /var/lib/lava/dispatcher/tmp/lavafed-4023/lava-overlay-okq56eyf/lava-4023/tests
makedir: /var/lib/lava/dispatcher/tmp/lavafed-4023/lava-overlay-okq56eyf/lava-4023/results
Creating /var/lib/lava/dispatcher/tmp/lavafed-4023/lava-overlay-okq56eyf/lava-4023/bin/lava-add-keys
Creating /var/lib/lava/dispatcher/tmp/lavafed-4023/lava-overlay-okq56eyf/lava-4023/bin/lava-add-sources
Creating /var/lib/lava/dispatcher/tmp/lavafed-4023/lava-overlay-okq56eyf/lava-4023/bin/lava-background-process-start
Creating /var/lib/lava/dispatcher/tmp/lavafed-4023/lava-overlay-okq56eyf/lava-4023/bin/lava-background-process-stop
Creating /var/lib/lava/dispatcher/tmp/lavafed-4023/lava-overlay-okq56eyf/lava-4023/bin/lava-common-functions
Creating /var/lib/lava/dispatcher/tmp/lavafed-4023/lava-overlay-okq56eyf/lava-4023/bin/lava-echo-ipv4
Creating /var/lib/lava/dispatcher/tmp/lavafed-4023/lava-overlay-okq56eyf/lava-4023/bin/lava-install-packages
Creating /var/lib/lava/dispatcher/tmp/lavafed-4023/lava-overlay-okq56eyf/lava-4023/bin/lava-installed-packages
Creating /var/lib/lava/dispatcher/tmp/lavafed-4023/lava-overlay-okq56eyf/lava-4023/bin/lava-os-build
Creating /var/lib/lava/dispatcher/tmp/lavafed-4023/lava-overlay-okq56eyf/lava-4023/bin/lava-probe-channel
Creating /var/lib/lava/dispatcher/tmp/lavafed-4023/lava-overlay-okq56eyf/lava-4023/bin/lava-probe-ip
Creating /var/lib/lava/dispatcher/tmp/lavafed-4023/lava-overlay-okq56eyf/lava-4023/bin/lava-target-ip
Creating /var/lib/lava/dispatcher/tmp/lavafed-4023/lava-overlay-okq56eyf/lava-4023/bin/lava-target-mac
Creating /var/lib/lava/dispatcher/tmp/lavafed-4023/lava-overlay-okq56eyf/lava-4023/bin/lava-target-storage
Creating /var/lib/lava/dispatcher/tmp/lavafed-4023/lava-overlay-okq56eyf/lava-4023/bin/lava-test-case
Creating /var/lib/lava/dispatcher/tmp/lavafed-4023/lava-overlay-okq56eyf/lava-4023/bin/lava-test-event
Creating /var/lib/lava/dispatcher/tmp/lavafed-4023/lava-overlay-okq56eyf/lava-4023/bin/lava-test-feedback
Creating /var/lib/lava/dispatcher/tmp/lavafed-4023/lava-overlay-okq56eyf/lava-4023/bin/lava-test-raise
Creating /var/lib/lava/dispatcher/tmp/lavafed-4023/lava-overlay-okq56eyf/lava-4023/bin/lava-test-reference
Creating /var/lib/lava/dispatcher/tmp/lavafed-4023/lava-overlay-okq56eyf/lava-4023/bin/lava-test-runner
Creating /var/lib/lava/dispatcher/tmp/lavafed-4023/lava-overlay-okq56eyf/lava-4023/bin/lava-test-set
Creating /var/lib/lava/dispatcher/tmp/lavafed-4023/lava-overlay-okq56eyf/lava-4023/bin/lava-test-shell
Creating /var/lib/lava/dispatcher/tmp/lavafed-4023/lava-overlay-okq56eyf/lava-4023/environment
LAVA metadata
- LAVA_JOB_ID=4023
start: 1.2.1 ssh-authorize (timeout 00:03:58) [common]
end: 1.2.1 ssh-authorize (duration 00:00:00) [common]
start: 1.2.2 lava-vland-overlay (timeout 00:03:58) [common]
skipped lava-vland-overlay
end: 1.2.2 lava-vland-overlay (duration 00:00:00) [common]
start: 1.2.3 lava-multinode-overlay (timeout 00:03:58) [common]
skipped lava-multinode-overlay
end: 1.2.3 lava-multinode-overlay (duration 00:00:00) [common]
start: 1.2.4 test-definition (timeout 00:03:58) [common]
Loading test definitions
start: 1.2.4.1 git-repo-action (timeout 00:03:58) [common]
Using /lava-4023 at stage 0
Fetching tests from http://git.linaro.org/lava-team/lava-functional-tests.git
Running '/usr/bin/git clone http://git.linaro.org/lava-team/lava-functional-tests.git /var/lib/lava/dispatcher/tmp/lavafed-4023/lava-overlay-okq56eyf/lava-4023/0/tests/0_smoke-tests --depth=1'
Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/lavafed-4023/lava-overlay-okq56eyf/lava-4023/0/tests/0_smoke-tests/lava-test-shell/smoke-tests-basic.yaml
uuid=4023_1.2.4.1 testdef=None
end: 1.2.4.1 git-repo-action (duration 00:00:00) [common]
case: git-repo-action
case_id: 75027
definition: lava
duration: 0.23
extra: ...
level: 1.2.4.1
namespace: common
result: pass
start: 1.2.4.2 test-overlay (timeout 00:03:57) [common]
end: 1.2.4.2 test-overlay (duration 00:00:00) [common]
case: test-overlay
case_id: 75028
definition: lava
duration: 0.00
extra: ...
level: 1.2.4.2
namespace: common
result: pass
start: 1.2.4.3 test-install-overlay (timeout 00:03:57) [common]
end: 1.2.4.3 test-install-overlay (duration 00:00:00) [common]
case: test-install-overlay
case_id: 75029
definition: lava
duration: 0.00
extra: ...
level: 1.2.4.3
namespace: common
result: pass
start: 1.2.4.4 test-runscript-overlay (timeout 00:03:57) [common]
runner path: /var/lib/lava/dispatcher/tmp/lavafed-4023/lava-overlay-okq56eyf/lava-4023/0/tests/0_smoke-tests test_uuid 4023_1.2.4.1
end: 1.2.4.4 test-runscript-overlay (duration 00:00:00) [common]
case: test-runscript-overlay
case_id: 75030
definition: lava
duration: 0.01
extra: ...
level: 1.2.4.4
namespace: common
result: pass
Creating lava-test-runner.conf files
Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/lavafed-4023/lava-overlay-okq56eyf/lava-4023/0 for stage 0
- 0_smoke-tests
end: 1.2.4 test-definition (duration 00:00:00) [common]
start: 1.2.5 compress-overlay (timeout 00:03:57) [common]
end: 1.2.5 compress-overlay (duration 00:00:00) [common]
start: 1.2.6 persistent-nfs-overlay (timeout 00:03:57) [common]
end: 1.2.6 persistent-nfs-overlay (duration 00:00:00) [common]
end: 1.2 lava-overlay (duration 00:00:00) [common]
end: 1 deploy-docker (duration 00:00:03) [common]
start: 2 boot-docker (timeout 00:02:00) [common]
start: 2.1 docker-run (timeout 00:02:00) [common]
Boot command: docker run --rm --interactive --tty --hostname lava --name lava-4023-2.1 --volume /var/lib/lava/dispatcher/tmp/lavafed-4023/lava-overlay-okq56eyf/lava-4023:/lava-4023 --cpus 1 --memory 512M debian:stretch-backports bash
end: 2.1 docker-run (duration 00:00:01) [common]
start: 2.2 expect-shell-connection (timeout 00:01:59) [common]
Setting prompt string to ['root@lava:/#']
Forcing a shell prompt, looking for ['root@lava:/#']
expect-shell-connection: Wait for prompt ['root@lava:/#'] (timeout 00:02:00)
Waiting using forced prompt support (timeout 00:01:00)
WARNING: Your kernel does not support swap limit capabilities or the cgroup is not mounted. Memory limited without swap.
root@lava:/#
end: 2.2 expect-shell-connection (duration 00:00:01) [common]
start: 2.3 export-device-env (timeout 00:01:59) [common]
end: 2.3 export-device-env (duration 00:00:00) [common]
end: 2 boot-docker (duration 00:00:01) [common]
start: 3 lava-test-retry (timeout 00:05:00) [common]
start: 3.1 lava-test-shell (timeout 00:05:00) [common]
Using namespace: common
#
root@lava:/# #
lava-test-shell: Wait for prompt ['root@lava:/#'] (timeout 00:05:00)
Using /lava-4023
export SHELL=/bin/sh
export SHELL=/bin/sh
[Kroot@lava:/# #
root@lava:/# export SHELL=/bin/sh
. /lava-4023/environment
root@lava:/# . /lava-4023/environment
/lava-4023/bin/lava-test-runner /lava-4023/0
/lava-4023/bin/lava-test-runner /lava-4023/0
Test shell timeout: 10s (minimum of the action and connection timeout)
. /lava-4023/environment
root@lava:/# /lava-4023/bin/lava-test-runner /lava-4023/0
+ export TESTRUN_ID=0_smoke-tests
+ cd /lava-4023/0/tests/0_smoke-tests
+ cat uuid
+ UUID=4023_1.2.4.1
+ set +x
<LAVA_SIGNAL_STARTRUN 0_smoke-tests 4023_1.2.4.1>
+ lava-test-case linux-posix-pwd --shell pwd
<LAVA_SIGNAL_STARTTC linux-posix-pwd>
/lava-4023/0/tests/0_smoke-tests
<LAVA_SIGNAL_ENDTC linux-posix-pwd>
Received signal: <STARTRUN> 0_smoke-tests 4023_1.2.4.1
Starting test lava.0_smoke-tests (4023_1.2.4.1)
Skipping test definition patterns.
Received signal: <STARTTC> linux-posix-pwd
Received signal: <ENDTC> linux-posix-pwd
<LAVA_SIGNAL_TESTCASE TEST_CASE_ID=linux-posix-pwd RESULT=pass>
+ lava-test-case linux-posix-uname --shell uname -a
<LAVA_SIGNAL_STARTTC linux-posix-uname>
Linux lava 4.19.0-5-arm64 #1 SMP Debian 4.19.37-5 (2019-06-19) aarch64 GNU/Linux
<LAVA_SIGNAL_ENDTC linux-posix-uname>
Received signal: <TESTCASE> TEST_CASE_ID=linux-posix-pwd RESULT=pass
case: linux-posix-pwd
case_id: 75031
definition: 0_smoke-tests
endtc: 135
result: pass
starttc: 134
Received signal: <STARTTC> linux-posix-uname
Received signal: <ENDTC> linux-posix-uname
<LAVA_SIGNAL_TESTCASE TEST_CASE_ID=linux-posix-uname RESULT=pass>
+ lava-test-case linux-posix-vmstat --shell vmstat
<LAVA_SIGNAL_STARTTC linux-posix-vmstat>
/lava-4023/0/../bin/lava-test-case: 1: eval: vmstat: not found
<LAVA_SIGNAL_ENDTC linux-posix-vmstat>
<LAVA_SIGNAL_TESTCASE TEST_CASE_ID=linux-posix-vmstat RESULT=fail>
+ lava-test-case linux-posix-ifconfig --shell ifconfig -a
<LAVA_SIGNAL_STARTTC linux-posix-ifconfig>
/lava-4023/0/../bin/lava-test-case: 1: eval: ifconfig: not found
<LAVA_SIGNAL_ENDTC linux-posix-ifconfig>
Received signal: <TESTCASE> TEST_CASE_ID=linux-posix-uname RESULT=pass
case: linux-posix-uname
case_id: 75032
definition: 0_smoke-tests
endtc: 144
result: pass
starttc: 143
Received signal: <STARTTC> linux-posix-vmstat
Received signal: <ENDTC> linux-posix-vmstat
Received signal: <TESTCASE> TEST_CASE_ID=linux-posix-vmstat RESULT=fail
case: linux-posix-vmstat
case_id: 75033
definition: 0_smoke-tests
endtc: 158
result: fail
starttc: 157
Received signal: <STARTTC> linux-posix-ifconfig
Received signal: <ENDTC> linux-posix-ifconfig
<LAVA_SIGNAL_TESTCASE TEST_CASE_ID=linux-posix-ifconfig RESULT=fail>
+ lava-test-case linux-posix-lscpu --shell lscpu
<LAVA_SIGNAL_STARTTC linux-posix-lscpu>
Received signal: <TESTCASE> TEST_CASE_ID=linux-posix-ifconfig RESULT=fail
case: linux-posix-ifconfig
case_id: 75034
definition: 0_smoke-tests
endtc: 162
result: fail
starttc: 161
Received signal: <STARTTC> linux-posix-lscpu
Architecture: aarch64
Byte Order: Little Endian
CPU(s): 8
On-line CPU(s) list: 0-7
Thread(s) per core: 1
Core(s) per socket: 2
Socket(s): 4
NUMA node(s): 1
Model: 0
BogoMIPS: 100.00
L1d cache: unknown size
L1i cache: unknown size
L2 cache: unknown size
NUMA node0 CPU(s): 0-7
Flags: fp asimd evtstrm cpuid
<LAVA_SIGNAL_ENDTC linux-posix-lscpu>
<LAVA_SIGNAL_TESTCASE TEST_CASE_ID=linux-posix-lscpu RESULT=pass>
+ lava-test-case linux-posix-lsb_release --shell lsb_release -a
<LAVA_SIGNAL_STARTTC linux-posix-lsb_release>
/lava-4023/0/../bin/lava-test-case: 1: eval: lsb_release: not found
<LAVA_SIGNAL_ENDTC linux-posix-lsb_release>
<LAVA_SIGNAL_TESTCASE TEST_CASE_ID=linux-posix-lsb_release RESULT=fail>
+ set +x
<LAVA_SIGNAL_ENDRUN 0_smoke-tests 4023_1.2.4.1>
<LAVA_TEST_RUNNER EXIT>
Received signal: <ENDTC> linux-posix-lscpu
Received signal: <TESTCASE> TEST_CASE_ID=linux-posix-lscpu RESULT=pass
case: linux-posix-lscpu
case_id: 75035
definition: 0_smoke-tests
endtc: 194
result: pass
starttc: 168
Received signal: <STARTTC> linux-posix-lsb_release
Received signal: <ENDTC> linux-posix-lsb_release
Received signal: <TESTCASE> TEST_CASE_ID=linux-posix-lsb_release RESULT=fail
case: linux-posix-lsb_release
case_id: 75036
definition: 0_smoke-tests
endtc: 198
result: fail
starttc: 197
Received signal: <ENDRUN> 0_smoke-tests 4023_1.2.4.1
Ending use of test pattern.
Ending test lava.0_smoke-tests (4023_1.2.4.1), duration 0.03
case: 0_smoke-tests
case_id: 75037
commit_id: 7273242aa3cbdfe8018354412522ff2905639c2c
definition: lava
duration: 0.03
namespace: common
path: lava-test-shell/smoke-tests-basic.yaml
repository: http://git.linaro.org/lava-team/lava-functional-tests.git
result: pass
revision: unspecified
uuid: 4023_1.2.4.1
ok: lava_test_shell seems to have completed
linux-posix-ifconfig: fail
linux-posix-lsb_release: fail
linux-posix-lscpu: pass
linux-posix-pwd: pass
linux-posix-uname: pass
linux-posix-vmstat: fail
end: 3.1 lava-test-shell (duration 00:00:00) [common]
end: 3 lava-test-retry (duration 00:00:00) [common]
start: 4 finalize (timeout 00:00:30) [common]
start: 4.1 power-off (timeout 00:00:10) [common]
end: 4.1 power-off (duration 00:00:00) [common]
start: 4.2 read-feedback (timeout 00:00:30) [common]
Listened to connection for namespace 'common' for up to 1s
Finalising connection for namespace 'common'
root@lava:/#
end: 4.2 read-feedback (duration 00:00:01) [common]
end: 4 finalize (duration 00:00:01) [common]
Cleaning after the job
Stopping container lava-4023-2.1
Calling: 'nice' 'docker' 'stop' 'lava-4023-2.1'
>> lava-4023-2.1
Returned 0 in 1 seconds
Root tmp directory removed at /var/lib/lava/dispatcher/tmp/lavafed-4023
Job finished correctly
case: job
case_id: 75038
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.