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 >> >>  1ed27930b96e: Pulling fs layer  >>  af5b2a97c09f: Pulling fs layer  1ed27930b96e: Downloading 440kB/43.17MB  af5b2a97c09f: Downloading 225B/225B  af5b2a97c09f: Verifying Checksum  af5b2a97c09f: Download complete  1ed27930b96e: Downloading 3.07MB/43.17MB  1ed27930b96e: Downloading 5.265MB/43.17MB  1ed27930b96e: Downloading 7.911MB/43.17MB  1ed27930b96e: Downloading 10.54MB/43.17MB  1ed27930b96e: Downloading 12.3MB/43.17MB  1ed27930b96e: Downloading 14.95MB/43.17MB  1ed27930b96e: Downloading 17.58MB/43.17MB  1ed27930b96e: Downloading 20.23MB/43.17MB  1ed27930b96e: Downloading 22.88MB/43.17MB  1ed27930b96e: Downloading 25.51MB/43.17MB  1ed27930b96e: Downloading 28.16MB/43.17MB  1ed27930b96e: Downloading 30.82MB/43.17MB  1ed27930b96e: Downloading 33.44MB/43.17MB  1ed27930b96e: Downloading 35.62MB/43.17MB  1ed27930b96e: Downloading 38.27MB/43.17MB  1ed27930b96e: Downloading 40.92MB/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 19.73MB/43.17MB  1ed27930b96e: Extracting 22.02MB/43.17MB  1ed27930b96e: Extracting 25.23MB/43.17MB  1ed27930b96e: Extracting 28.44MB/43.17MB  1ed27930b96e: Extracting 29.82MB/43.17MB  1ed27930b96e: Extracting 31.2MB/43.17MB  1ed27930b96e: Extracting 32.57MB/43.17MB  1ed27930b96e: Extracting 33.95MB/43.17MB  1ed27930b96e: Extracting 35.32MB/43.17MB  1ed27930b96e: Extracting 36.7MB/43.17MB  1ed27930b96e: Extracting 38.08MB/43.17MB  1ed27930b96e: Extracting 39.45MB/43.17MB  1ed27930b96e: Extracting 40.83MB/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-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