lava-dispatcher, installed at version: 2019.10.0080.g8ec704508+buster
start: 0 validate
Start time: 2019-11-06 09:11:53.848143+00:00 (UTC)
docker server, installed at version: 18.09.1
docker client, installed at version: 19.03.4
validate duration: 0.19
case: validate
case_id: 29091
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
>>
>> [1A[2K
1ed27930b96e: Pulling fs layer
[1B
>> [1A[2K
af5b2a97c09f: Pulling fs layer
[1B[1A[2K
af5b2a97c09f: Downloading 225B/225B
[1B[1A[2K
af5b2a97c09f: Verifying Checksum
[1B[1A[2K
af5b2a97c09f: Download complete
[1B[2A[2K
1ed27930b96e: Downloading 441kB/43.17MB
[2B[2A[2K
1ed27930b96e: Downloading 3.071MB/43.17MB
[2B[2A[2K
1ed27930b96e: Downloading 5.696MB/43.17MB
[2B[2A[2K
1ed27930b96e: Downloading 8.338MB/43.17MB
[2B[2A[2K
1ed27930b96e: Downloading 10.99MB/43.17MB
[2B[2A[2K
1ed27930b96e: Downloading 13.65MB/43.17MB
[2B[2A[2K
1ed27930b96e: Downloading 16.3MB/43.17MB
[2B[2A[2K
1ed27930b96e: Downloading 18.96MB/43.17MB
[2B[2A[2K
1ed27930b96e: Downloading 21.6MB/43.17MB
[2B[2A[2K
1ed27930b96e: Downloading 24.25MB/43.17MB
[2B[2A[2K
1ed27930b96e: Downloading 26.9MB/43.17MB
[2B[2A[2K
1ed27930b96e: Downloading 29.54MB/43.17MB
[2B[2A[2K
1ed27930b96e: Downloading 32.19MB/43.17MB
[2B[2A[2K
1ed27930b96e: Downloading 34.82MB/43.17MB
[2B[2A[2K
1ed27930b96e: Downloading 37.47MB/43.17MB
[2B[2A[2K
1ed27930b96e: Downloading 40.12MB/43.17MB
[2B[2A[2K
1ed27930b96e: Downloading 42.76MB/43.17MB
[2B[2A[2K
1ed27930b96e: Verifying Checksum
[2B[2A[2K
1ed27930b96e: Download complete
[2B[2A[2K
1ed27930b96e: Extracting 458.8kB/43.17MB
[2B[2A[2K
1ed27930b96e: Extracting 2.753MB/43.17MB
[2B[2A[2K
1ed27930b96e: Extracting 5.046MB/43.17MB
[2B[2A[2K
1ed27930b96e: Extracting 6.423MB/43.17MB
[2B[2A[2K
1ed27930b96e: Extracting 8.258MB/43.17MB
[2B[2A[2K
1ed27930b96e: Extracting 10.09MB/43.17MB
[2B[2A[2K
1ed27930b96e: Extracting 11.93MB/43.17MB
[2B[2A[2K
1ed27930b96e: Extracting 13.3MB/43.17MB
[2B[2A[2K
1ed27930b96e: Extracting 15.14MB/43.17MB
[2B[2A[2K
1ed27930b96e: Extracting 17.43MB/43.17MB
[2B[2A[2K
1ed27930b96e: Extracting 18.35MB/43.17MB
[2B[2A[2K
1ed27930b96e: Extracting 20.19MB/43.17MB
[2B[2A[2K
1ed27930b96e: Extracting 23.86MB/43.17MB
[2B[2A[2K
1ed27930b96e: Extracting 27.07MB/43.17MB
[2B[2A[2K
1ed27930b96e: Extracting 29.36MB/43.17MB
[2B[2A[2K
1ed27930b96e: Extracting 30.74MB/43.17MB
[2B[2A[2K
1ed27930b96e: Extracting 32.11MB/43.17MB
[2B[2A[2K
1ed27930b96e: Extracting 33.49MB/43.17MB
[2B[2A[2K
1ed27930b96e: Extracting 34.87MB/43.17MB
[2B[2A[2K
1ed27930b96e: Extracting 36.24MB/43.17MB
[2B[2A[2K
1ed27930b96e: Extracting 37.62MB/43.17MB
[2B[2A[2K
1ed27930b96e: Extracting 38.99MB/43.17MB
[2B[2A[2K
1ed27930b96e: Extracting 40.37MB/43.17MB
[2B[2A[2K
1ed27930b96e: Extracting 41.75MB/43.17MB
[2B[2A[2K
1ed27930b96e: Extracting 42.21MB/43.17MB
[2B[2A[2K
1ed27930b96e: Extracting 42.66MB/43.17MB
[2B[2A[2K
1ed27930b96e: Extracting 43.17MB/43.17MB
[2B[2A[2K
1ed27930b96e: Pull complete
[2B[1A[2K
af5b2a97c09f: Extracting 225B/225B
[1B[1A[2K
af5b2a97c09f: Extracting 225B/225B
[1B[1A[2K
af5b2a97c09f: Pull complete
[1BDigest: sha256:20e507d7281d293332cb66092f4550dcd7b5f8bf2de42bdf5906c5740968df15
>> Status: Downloaded newer image for debian:stretch-backports
>> docker.io/library/debian:stretch-backports
Returned 0 in 8 seconds
start: 1.1 deploy-device-env (timeout 00:03:51) [common]
end: 1.1 deploy-device-env (duration 00:00:00) [common]
start: 1.2 lava-overlay (timeout 00:03:51) [common]
[common] Preparing overlay tarball in /var/lib/lava/dispatcher/tmp/lavafed-1464/lava-overlay-p8yn0jqs
makedir: /var/lib/lava/dispatcher/tmp/lavafed-1464/lava-overlay-p8yn0jqs/lava-1464/bin
makedir: /var/lib/lava/dispatcher/tmp/lavafed-1464/lava-overlay-p8yn0jqs/lava-1464/tests
makedir: /var/lib/lava/dispatcher/tmp/lavafed-1464/lava-overlay-p8yn0jqs/lava-1464/results
Creating /var/lib/lava/dispatcher/tmp/lavafed-1464/lava-overlay-p8yn0jqs/lava-1464/bin/lava-add-keys
Creating /var/lib/lava/dispatcher/tmp/lavafed-1464/lava-overlay-p8yn0jqs/lava-1464/bin/lava-add-sources
Creating /var/lib/lava/dispatcher/tmp/lavafed-1464/lava-overlay-p8yn0jqs/lava-1464/bin/lava-background-process-start
Creating /var/lib/lava/dispatcher/tmp/lavafed-1464/lava-overlay-p8yn0jqs/lava-1464/bin/lava-background-process-stop
Creating /var/lib/lava/dispatcher/tmp/lavafed-1464/lava-overlay-p8yn0jqs/lava-1464/bin/lava-common-functions
Creating /var/lib/lava/dispatcher/tmp/lavafed-1464/lava-overlay-p8yn0jqs/lava-1464/bin/lava-echo-ipv4
Creating /var/lib/lava/dispatcher/tmp/lavafed-1464/lava-overlay-p8yn0jqs/lava-1464/bin/lava-install-packages
Creating /var/lib/lava/dispatcher/tmp/lavafed-1464/lava-overlay-p8yn0jqs/lava-1464/bin/lava-installed-packages
Creating /var/lib/lava/dispatcher/tmp/lavafed-1464/lava-overlay-p8yn0jqs/lava-1464/bin/lava-os-build
Creating /var/lib/lava/dispatcher/tmp/lavafed-1464/lava-overlay-p8yn0jqs/lava-1464/bin/lava-probe-channel
Creating /var/lib/lava/dispatcher/tmp/lavafed-1464/lava-overlay-p8yn0jqs/lava-1464/bin/lava-probe-ip
Creating /var/lib/lava/dispatcher/tmp/lavafed-1464/lava-overlay-p8yn0jqs/lava-1464/bin/lava-target-ip
Creating /var/lib/lava/dispatcher/tmp/lavafed-1464/lava-overlay-p8yn0jqs/lava-1464/bin/lava-target-mac
Creating /var/lib/lava/dispatcher/tmp/lavafed-1464/lava-overlay-p8yn0jqs/lava-1464/bin/lava-target-storage
Creating /var/lib/lava/dispatcher/tmp/lavafed-1464/lava-overlay-p8yn0jqs/lava-1464/bin/lava-test-case
Creating /var/lib/lava/dispatcher/tmp/lavafed-1464/lava-overlay-p8yn0jqs/lava-1464/bin/lava-test-event
Creating /var/lib/lava/dispatcher/tmp/lavafed-1464/lava-overlay-p8yn0jqs/lava-1464/bin/lava-test-feedback
Creating /var/lib/lava/dispatcher/tmp/lavafed-1464/lava-overlay-p8yn0jqs/lava-1464/bin/lava-test-raise
Creating /var/lib/lava/dispatcher/tmp/lavafed-1464/lava-overlay-p8yn0jqs/lava-1464/bin/lava-test-reference
Creating /var/lib/lava/dispatcher/tmp/lavafed-1464/lava-overlay-p8yn0jqs/lava-1464/bin/lava-test-runner
Creating /var/lib/lava/dispatcher/tmp/lavafed-1464/lava-overlay-p8yn0jqs/lava-1464/bin/lava-test-set
Creating /var/lib/lava/dispatcher/tmp/lavafed-1464/lava-overlay-p8yn0jqs/lava-1464/bin/lava-test-shell
Creating /var/lib/lava/dispatcher/tmp/lavafed-1464/lava-overlay-p8yn0jqs/lava-1464/environment
start: 1.2.1 ssh-authorize (timeout 00:03:51) [common]
end: 1.2.1 ssh-authorize (duration 00:00:00) [common]
start: 1.2.2 lava-vland-overlay (timeout 00:03:51) [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:51) [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:51) [common]
Loading test definitions
start: 1.2.4.1 git-repo-action (timeout 00:03:51) [common]
Using /lava-1464 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-1464/lava-overlay-p8yn0jqs/lava-1464/0/tests/0_smoke-tests --depth=1'
Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/lavafed-1464/lava-overlay-p8yn0jqs/lava-1464/0/tests/0_smoke-tests/lava-test-shell/smoke-tests-basic.yaml
uuid=1464_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: 29103
definition: lava
duration: 0.48
extra: ...
level: 1.2.4.1
namespace: common
result: pass
start: 1.2.4.2 test-overlay (timeout 00:03:51) [common]
end: 1.2.4.2 test-overlay (duration 00:00:00) [common]
case: test-overlay
case_id: 29104
definition: lava
duration: 0.01
extra: ...
level: 1.2.4.2
namespace: common
result: pass
start: 1.2.4.3 test-install-overlay (timeout 00:03:51) [common]
end: 1.2.4.3 test-install-overlay (duration 00:00:00) [common]
case: test-install-overlay
case_id: 29105
definition: lava
duration: 0.01
extra: ...
level: 1.2.4.3
namespace: common
result: pass
start: 1.2.4.4 test-runscript-overlay (timeout 00:03:51) [common]
runner path: /var/lib/lava/dispatcher/tmp/lavafed-1464/lava-overlay-p8yn0jqs/lava-1464/0/tests/0_smoke-tests test_uuid 1464_1.2.4.1
end: 1.2.4.4 test-runscript-overlay (duration 00:00:00) [common]
case: test-runscript-overlay
case_id: 29106
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-1464/lava-overlay-p8yn0jqs/lava-1464/0 for stage 0
- 0_smoke-tests
end: 1.2.4 test-definition (duration 00:00:01) [common]
start: 1.2.5 compress-overlay (timeout 00:03:51) [common]
end: 1.2.5 compress-overlay (duration 00:00:00) [common]
start: 1.2.6 persistent-nfs-overlay (timeout 00:03:51) [common]
end: 1.2.6 persistent-nfs-overlay (duration 00:00:00) [common]
end: 1.2 lava-overlay (duration 00:00:01) [common]
end: 1 deploy-docker (duration 00:00:09) [common]
start: 2 boot-docker (timeout 00:02:00) [common]
start: 2.1 boot-docker-retry (timeout 00:02:00) [common]
start: 2.1.1 docker-run (timeout 00:02:00) [common]
Boot command: docker run --rm --interactive --tty --hostname lava --name lava-1464-2.1.1 --volume /var/lib/lava/dispatcher/tmp/lavafed-1464/lava-overlay-p8yn0jqs/lava-1464:/lava-1464 --cpus 1 --memory 512M debian:stretch-backports bash
end: 2.1.1 docker-run (duration 00:00:01) [common]
end: 2.1 boot-docker-retry (duration 00:00:01) [common]
start: 2.2 expect-shell-connection (timeout 00:01:59) [common]
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. 59.97317922115326s timeout
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:02) [common]
start: 2.3 export-device-env (timeout 00:01:57) [common]
end: 2.3 export-device-env (duration 00:00:00) [common]
end: 2 boot-docker (duration 00:00:03) [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-1464
export SHELL=/bin/sh
export SHELL=/bin/sh
[Kroot@lava:/# #
. /lava-1464/environment
root@lava:/# export SHELL=/bin/sh. /lava-1464/environment
/lava-1464/bin/lava-test-runner /lava-1464/0
/lava-1464/bin/lava-test-runner /lava-1464/0
Test shell timeout: 10s (minimum of the action and connection timeout)
root@lava:/# . /lava-1464/environment
root@lava:/# /lava-1464/bin/lava-test-runner /lava-1464/0
+ export TESTRUN_ID=0_smoke-tests
+ cd /lava-1464/0/tests/0_smoke-tests
+ cat uuid
+ UUID=1464_1.2.4.1
+ set +x
<LAVA_SIGNAL_STARTRUN 0_smoke-tests 1464_1.2.4.1>
+ lava-test-case linux-posix-pwd --shell pwd
Received signal: <STARTRUN> 0_smoke-tests 1464_1.2.4.1
Starting test lava.0_smoke-tests (1464_1.2.4.1)
Skipping test definition patterns.
<LAVA_SIGNAL_STARTTC linux-posix-pwd>
/lava-1464/0/tests/0_smoke-tests
<LAVA_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>
Received signal: <STARTTC> linux-posix-pwd
Received signal: <ENDTC> linux-posix-pwd
Received signal: <TESTCASE> TEST_CASE_ID=linux-posix-pwd RESULT=pass
case: linux-posix-pwd
case_id: 29108
definition: 0_smoke-tests
result: pass
Received 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>
<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-1464/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-1464/0/../bin/lava-test-case: 1: eval: ifconfig: not found
<LAVA_SIGNAL_ENDTC linux-posix-ifconfig>
Received signal: <ENDTC> linux-posix-uname
Received signal: <TESTCASE> TEST_CASE_ID=linux-posix-uname RESULT=pass
case: linux-posix-uname
case_id: 29109
definition: 0_smoke-tests
result: pass
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: 29110
definition: 0_smoke-tests
result: fail
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>
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-1464/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 1464_1.2.4.1>
<LAVA_TEST_RUNNER EXIT>
Received signal: <TESTCASE> TEST_CASE_ID=linux-posix-ifconfig RESULT=fail
case: linux-posix-ifconfig
case_id: 29111
definition: 0_smoke-tests
result: fail
Received signal: <STARTTC> linux-posix-lscpu
Received signal: <ENDTC> linux-posix-lscpu
Received signal: <TESTCASE> TEST_CASE_ID=linux-posix-lscpu RESULT=pass
case: linux-posix-lscpu
case_id: 29112
definition: 0_smoke-tests
result: pass
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: 29113
definition: 0_smoke-tests
result: fail
Received signal: <ENDRUN> 0_smoke-tests 1464_1.2.4.1
Ending use of test pattern.
Ending test lava.0_smoke-tests (1464_1.2.4.1), duration 0.05
case: 0_smoke-tests
case_id: 29114
commit_id: 09cd63133c826535eb5501a90a6c025574326d71
definition: lava
duration: 0.05
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: 1464_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 1s
Finalising connection for namespace 'common'
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-1464-2.1.1
Calling: 'nice' 'docker' 'stop' 'lava-1464-2.1.1'
>> lava-1464-2.1.1
Returned 0 in 1 seconds
Root tmp directory removed at /var/lib/lava/dispatcher/tmp/lavafed-1464
Job finished correctly
case: job
case_id: 29116
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.