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 >> >>  1ed27930b96e: Pulling fs layer  >>  af5b2a97c09f: Pulling fs layer  af5b2a97c09f: Downloading 225B/225B  af5b2a97c09f: Verifying Checksum  af5b2a97c09f: Download complete  1ed27930b96e: Downloading 441kB/43.17MB  1ed27930b96e: Downloading 3.071MB/43.17MB  1ed27930b96e: Downloading 5.696MB/43.17MB  1ed27930b96e: Downloading 8.338MB/43.17MB  1ed27930b96e: Downloading 10.99MB/43.17MB  1ed27930b96e: Downloading 13.65MB/43.17MB  1ed27930b96e: Downloading 16.3MB/43.17MB  1ed27930b96e: Downloading 18.96MB/43.17MB  1ed27930b96e: Downloading 21.6MB/43.17MB  1ed27930b96e: Downloading 24.25MB/43.17MB  1ed27930b96e: Downloading 26.9MB/43.17MB  1ed27930b96e: Downloading 29.54MB/43.17MB  1ed27930b96e: Downloading 32.19MB/43.17MB  1ed27930b96e: Downloading 34.82MB/43.17MB  1ed27930b96e: Downloading 37.47MB/43.17MB  1ed27930b96e: Downloading 40.12MB/43.17MB  1ed27930b96e: Downloading 42.76MB/43.17MB  1ed27930b96e: Verifying Checksum  1ed27930b96e: Download complete  1ed27930b96e: Extracting 458.8kB/43.17MB  1ed27930b96e: Extracting 2.753MB/43.17MB  1ed27930b96e: Extracting 5.046MB/43.17MB  1ed27930b96e: Extracting 6.423MB/43.17MB  1ed27930b96e: Extracting 8.258MB/43.17MB  1ed27930b96e: Extracting 10.09MB/43.17MB  1ed27930b96e: Extracting 11.93MB/43.17MB  1ed27930b96e: Extracting 13.3MB/43.17MB  1ed27930b96e: Extracting 15.14MB/43.17MB  1ed27930b96e: Extracting 17.43MB/43.17MB  1ed27930b96e: Extracting 18.35MB/43.17MB  1ed27930b96e: Extracting 20.19MB/43.17MB  1ed27930b96e: Extracting 23.86MB/43.17MB  1ed27930b96e: Extracting 27.07MB/43.17MB  1ed27930b96e: Extracting 29.36MB/43.17MB  1ed27930b96e: Extracting 30.74MB/43.17MB  1ed27930b96e: Extracting 32.11MB/43.17MB  1ed27930b96e: Extracting 33.49MB/43.17MB  1ed27930b96e: Extracting 34.87MB/43.17MB  1ed27930b96e: Extracting 36.24MB/43.17MB  1ed27930b96e: Extracting 37.62MB/43.17MB  1ed27930b96e: Extracting 38.99MB/43.17MB  1ed27930b96e: Extracting 40.37MB/43.17MB  1ed27930b96e: Extracting 41.75MB/43.17MB  1ed27930b96e: Extracting 42.21MB/43.17MB  1ed27930b96e: Extracting 42.66MB/43.17MB  1ed27930b96e: Extracting 43.17MB/43.17MB  1ed27930b96e: Pull complete  af5b2a97c09f: Extracting 225B/225B  af5b2a97c09f: Extracting 225B/225B  af5b2a97c09f: Pull complete Digest: 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