lava-dispatcher, installed at version: 2019.09.0133.g05d8a6953+buster
start: 0 validate
Start time: 2019-10-17 09:11:23.640716+00:00 (UTC)
docker server, installed at version: 18.09.1
docker client, installed at version: 19.03.3
validate duration: 0.18
case: validate
case_id: 25995
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[2A[2K
1ed27930b96e: Downloading 440kB/43.17MB
[2B[1A[2K
af5b2a97c09f: Downloading 225B/225B
[1B[1A[2K
af5b2a97c09f: Verifying Checksum
[1B[1A[2K
af5b2a97c09f: Download complete
[1B[2A[2K
1ed27930b96e: Downloading 3.07MB/43.17MB
[2B[2A[2K
1ed27930b96e: Downloading 5.265MB/43.17MB
[2B[2A[2K
1ed27930b96e: Downloading 7.911MB/43.17MB
[2B[2A[2K
1ed27930b96e: Downloading 10.54MB/43.17MB
[2B[2A[2K
1ed27930b96e: Downloading 12.3MB/43.17MB
[2B[2A[2K
1ed27930b96e: Downloading 14.95MB/43.17MB
[2B[2A[2K
1ed27930b96e: Downloading 17.58MB/43.17MB
[2B[2A[2K
1ed27930b96e: Downloading 20.23MB/43.17MB
[2B[2A[2K
1ed27930b96e: Downloading 22.88MB/43.17MB
[2B[2A[2K
1ed27930b96e: Downloading 25.51MB/43.17MB
[2B[2A[2K
1ed27930b96e: Downloading 28.16MB/43.17MB
[2B[2A[2K
1ed27930b96e: Downloading 30.82MB/43.17MB
[2B[2A[2K
1ed27930b96e: Downloading 33.44MB/43.17MB
[2B[2A[2K
1ed27930b96e: Downloading 35.62MB/43.17MB
[2B[2A[2K
1ed27930b96e: Downloading 38.27MB/43.17MB
[2B[2A[2K
1ed27930b96e: Downloading 40.92MB/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 19.73MB/43.17MB
[2B[2A[2K
1ed27930b96e: Extracting 22.02MB/43.17MB
[2B[2A[2K
1ed27930b96e: Extracting 25.23MB/43.17MB
[2B[2A[2K
1ed27930b96e: Extracting 28.44MB/43.17MB
[2B[2A[2K
1ed27930b96e: Extracting 29.82MB/43.17MB
[2B[2A[2K
1ed27930b96e: Extracting 31.2MB/43.17MB
[2B[2A[2K
1ed27930b96e: Extracting 32.57MB/43.17MB
[2B[2A[2K
1ed27930b96e: Extracting 33.95MB/43.17MB
[2B[2A[2K
1ed27930b96e: Extracting 35.32MB/43.17MB
[2B[2A[2K
1ed27930b96e: Extracting 36.7MB/43.17MB
[2B[2A[2K
1ed27930b96e: Extracting 38.08MB/43.17MB
[2B[2A[2K
1ed27930b96e: Extracting 39.45MB/43.17MB
[2B[2A[2K
1ed27930b96e: Extracting 40.83MB/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-1292/lava-overlay-ppvi7j6s
makedir: /var/lib/lava/dispatcher/tmp/lavafed-1292/lava-overlay-ppvi7j6s/lava-1292/bin
makedir: /var/lib/lava/dispatcher/tmp/lavafed-1292/lava-overlay-ppvi7j6s/lava-1292/tests
makedir: /var/lib/lava/dispatcher/tmp/lavafed-1292/lava-overlay-ppvi7j6s/lava-1292/results
Creating /var/lib/lava/dispatcher/tmp/lavafed-1292/lava-overlay-ppvi7j6s/lava-1292/bin/lava-add-keys
Creating /var/lib/lava/dispatcher/tmp/lavafed-1292/lava-overlay-ppvi7j6s/lava-1292/bin/lava-add-sources
Creating /var/lib/lava/dispatcher/tmp/lavafed-1292/lava-overlay-ppvi7j6s/lava-1292/bin/lava-background-process-start
Creating /var/lib/lava/dispatcher/tmp/lavafed-1292/lava-overlay-ppvi7j6s/lava-1292/bin/lava-background-process-stop
Creating /var/lib/lava/dispatcher/tmp/lavafed-1292/lava-overlay-ppvi7j6s/lava-1292/bin/lava-common-functions
Creating /var/lib/lava/dispatcher/tmp/lavafed-1292/lava-overlay-ppvi7j6s/lava-1292/bin/lava-echo-ipv4
Creating /var/lib/lava/dispatcher/tmp/lavafed-1292/lava-overlay-ppvi7j6s/lava-1292/bin/lava-install-packages
Creating /var/lib/lava/dispatcher/tmp/lavafed-1292/lava-overlay-ppvi7j6s/lava-1292/bin/lava-installed-packages
Creating /var/lib/lava/dispatcher/tmp/lavafed-1292/lava-overlay-ppvi7j6s/lava-1292/bin/lava-os-build
Creating /var/lib/lava/dispatcher/tmp/lavafed-1292/lava-overlay-ppvi7j6s/lava-1292/bin/lava-probe-channel
Creating /var/lib/lava/dispatcher/tmp/lavafed-1292/lava-overlay-ppvi7j6s/lava-1292/bin/lava-probe-ip
Creating /var/lib/lava/dispatcher/tmp/lavafed-1292/lava-overlay-ppvi7j6s/lava-1292/bin/lava-target-ip
Creating /var/lib/lava/dispatcher/tmp/lavafed-1292/lava-overlay-ppvi7j6s/lava-1292/bin/lava-target-mac
Creating /var/lib/lava/dispatcher/tmp/lavafed-1292/lava-overlay-ppvi7j6s/lava-1292/bin/lava-target-storage
Creating /var/lib/lava/dispatcher/tmp/lavafed-1292/lava-overlay-ppvi7j6s/lava-1292/bin/lava-test-case
Creating /var/lib/lava/dispatcher/tmp/lavafed-1292/lava-overlay-ppvi7j6s/lava-1292/bin/lava-test-event
Creating /var/lib/lava/dispatcher/tmp/lavafed-1292/lava-overlay-ppvi7j6s/lava-1292/bin/lava-test-feedback
Creating /var/lib/lava/dispatcher/tmp/lavafed-1292/lava-overlay-ppvi7j6s/lava-1292/bin/lava-test-raise
Creating /var/lib/lava/dispatcher/tmp/lavafed-1292/lava-overlay-ppvi7j6s/lava-1292/bin/lava-test-reference
Creating /var/lib/lava/dispatcher/tmp/lavafed-1292/lava-overlay-ppvi7j6s/lava-1292/bin/lava-test-runner
Creating /var/lib/lava/dispatcher/tmp/lavafed-1292/lava-overlay-ppvi7j6s/lava-1292/bin/lava-test-set
Creating /var/lib/lava/dispatcher/tmp/lavafed-1292/lava-overlay-ppvi7j6s/lava-1292/bin/lava-test-shell
Creating /var/lib/lava/dispatcher/tmp/lavafed-1292/lava-overlay-ppvi7j6s/lava-1292/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-1292 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-1292/lava-overlay-ppvi7j6s/lava-1292/0/tests/0_smoke-tests --depth=1'
Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/lavafed-1292/lava-overlay-ppvi7j6s/lava-1292/0/tests/0_smoke-tests/lava-test-shell/smoke-tests-basic.yaml
uuid=1292_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: 26000
definition: lava
duration: 0.21
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: 26001
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: 26002
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-1292/lava-overlay-ppvi7j6s/lava-1292/0/tests/0_smoke-tests test_uuid 1292_1.2.4.1
end: 1.2.4.4 test-runscript-overlay (duration 00:00:00) [common]
case: test-runscript-overlay
case_id: 26003
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-1292/lava-overlay-ppvi7j6s/lava-1292/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: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:00) [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-1292-2.1.1 --volume /var/lib/lava/dispatcher/tmp/lavafed-1292/lava-overlay-ppvi7j6s/lava-1292:/lava-1292 --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.97305524349213s 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:01) [common]
start: 2.3 export-device-env (timeout 00:01:58) [common]
end: 2.3 export-device-env (duration 00:00:00) [common]
end: 2 boot-docker (duration 00:00:02) [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-1292
export SHELL=/bin/sh
export SHELL=/bin/sh
[Kroot@lava:/# #
. /lava-1292/environment
root@lava:/# export SHELL=/bin/sh. /lava-1292/environment
/lava-1292/bin/lava-test-runner /lava-1292/0
/lava-1292/bin/lava-test-runner /lava-1292/0
Test shell timeout: 10s (minimum of the action and connection timeout)
root@lava:/# . /lava-1292/environment
root@lava:/# /lava-1292/bin/lava-test-runner /lava-1292/0
+ export TESTRUN_ID=0_smoke-tests
+ cd /lava-1292/0/tests/0_smoke-tests
+ cat uuid
+ UUID=1292_1.2.4.1
+ set +x
<LAVA_SIGNAL_STARTRUN 0_smoke-tests 1292_1.2.4.1>
+ lava-test-case linux-posix-pwd --shell pwd
Received signal: <STARTRUN> 0_smoke-tests 1292_1.2.4.1
Starting test lava.0_smoke-tests (1292_1.2.4.1)
Skipping test definition patterns.
<LAVA_SIGNAL_STARTTC linux-posix-pwd>
/lava-1292/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
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: 26004
definition: 0_smoke-tests
result: pass
<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>
<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-1292/0/../bin/lava-test-case: 1: eval: vmstat: not found
<LAVA_SIGNAL_ENDTC linux-posix-vmstat>
Received signal: <STARTTC> linux-posix-uname
Received signal: <ENDTC> linux-posix-uname
Received signal: <TESTCASE> TEST_CASE_ID=linux-posix-uname RESULT=pass
case: linux-posix-uname
case_id: 26005
definition: 0_smoke-tests
result: pass
Received signal: <STARTTC> linux-posix-vmstat
Received 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-1292/0/../bin/lava-test-case: 1: eval: ifconfig: not found
<LAVA_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>
Received signal: <TESTCASE> TEST_CASE_ID=linux-posix-vmstat RESULT=fail
case: linux-posix-vmstat
case_id: 26006
definition: 0_smoke-tests
result: fail
Received signal: <STARTTC> linux-posix-ifconfig
Received signal: <ENDTC> linux-posix-ifconfig
Received signal: <TESTCASE> TEST_CASE_ID=linux-posix-ifconfig RESULT=fail
case: linux-posix-ifconfig
case_id: 26007
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: 26008
definition: 0_smoke-tests
result: pass
+ lava-test-case linux-posix-lsb_release --shell lsb_release -a
<LAVA_SIGNAL_STARTTC linux-posix-lsb_release>
/lava-1292/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 1292_1.2.4.1>
<LAVA_TEST_RUNNER EXIT>
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: 26009
definition: 0_smoke-tests
result: fail
Received signal: <ENDRUN> 0_smoke-tests 1292_1.2.4.1
Ending use of test pattern.
Ending test lava.0_smoke-tests (1292_1.2.4.1), duration 0.06
case: 0_smoke-tests
case_id: 26010
commit_id: 0f8952f18e992371f8686c59b36f44e9c6a94a1a
definition: lava
duration: 0.06
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: 1292_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-1292-2.1.1
Calling: 'nice' 'docker' 'stop' 'lava-1292-2.1.1'
>> lava-1292-2.1.1
Returned 0 in 1 seconds
Root tmp directory removed at /var/lib/lava/dispatcher/tmp/lavafed-1292
Job finished correctly
case: job
case_id: 26011
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.