lava-dispatcher, installed at version: 2023.10 start: 0 validate Start time: 2024-05-27 15:40:11.285791+00:00 (UTC) Validating that http://download.automotivelinux.org/AGL/upload/ci/29918/1/h3ulcb-nogfx/initramfs-netboot-image-h3ulcb.ext4.gz exists Validating that http://download.automotivelinux.org/AGL/upload/ci/29918/1/h3ulcb-nogfx/Image exists Validating that http://download.automotivelinux.org/AGL/upload/ci/29918/1/h3ulcb-nogfx/r8a77951-agl-refhw.dtb exists Validating that http://download.automotivelinux.org/AGL/upload/ci/29918/1/h3ulcb-nogfx/agl-ivi-demo-qt-h3ulcb.ext4.xz exists validate duration: 0.88 case: validate
case_id: 3985786
definition: lava
result: pass
start: 1 nbd-deploy (timeout 01:30:00) [common] Making protocol call for nbd-deploy using lava-xnbd [nbd-deploy] Checking protocol data for lava-xnbd Get a port from pool Set_port 61988 start: 1.1 download-retry (timeout 01:30:00) [common] start: 1.1.1 http-download (timeout 01:30:00) [common] downloading http://download.automotivelinux.org/AGL/upload/ci/29918/1/h3ulcb-nogfx/initramfs-netboot-image-h3ulcb.ext4.gz saving as /var/lib/lava/dispatcher/tmp/34495/nbd-deploy-keaxhurj/initrd/initramfs-netboot-image-h3ulcb.ext4.gz total size: 2151712 (2 MB) No compression specified progress 1 % (0 MB) progress 6 % (0 MB) progress 12 % (0 MB) progress 18 % (0 MB) progress 24 % (0 MB) progress 30 % (0 MB) progress 35 % (0 MB) progress 41 % (0 MB) progress 47 % (0 MB) progress 53 % (1 MB) progress 59 % (1 MB) progress 65 % (1 MB) progress 70 % (1 MB) progress 76 % (1 MB) progress 82 % (1 MB) progress 88 % (1 MB) progress 94 % (1 MB) progress 100 % (2 MB) 2 MB downloaded in 1.04 s (1.98 MB/s) end: 1.1.1 http-download (duration 00:00:01) [common] case: http-download
case_id: 3985810
definition: lava
duration: 1.04
extra: ...
level: 1.1.1
namespace: common
result: pass
end: 1.1 download-retry (duration 00:00:01) [common] start: 1.2 download-retry (timeout 01:29:59) [common] start: 1.2.1 http-download (timeout 01:29:59) [common] downloading http://download.automotivelinux.org/AGL/upload/ci/29918/1/h3ulcb-nogfx/Image saving as /var/lib/lava/dispatcher/tmp/34495/nbd-deploy-keaxhurj/kernel/Image total size: 39135744 (37 MB) No compression specified progress 0 % (0 MB) progress 5 % (1 MB) progress 10 % (3 MB) progress 15 % (5 MB) progress 20 % (7 MB) progress 25 % (9 MB) progress 30 % (11 MB) progress 35 % (13 MB) progress 40 % (14 MB) progress 45 % (16 MB) progress 50 % (18 MB) progress 55 % (20 MB) progress 60 % (22 MB) progress 65 % (24 MB) progress 70 % (26 MB) progress 75 % (28 MB) progress 80 % (29 MB) progress 85 % (31 MB) progress 90 % (33 MB) progress 95 % (35 MB) progress 100 % (37 MB) 37 MB downloaded in 3.36 s (11.12 MB/s) end: 1.2.1 http-download (duration 00:00:03) [common] case: http-download
case_id: 3985810
definition: lava
duration: 3.36
extra: ...
level: 1.2.1
namespace: common
result: pass
end: 1.2 download-retry (duration 00:00:03) [common] start: 1.3 download-retry (timeout 01:29:56) [common] start: 1.3.1 http-download (timeout 01:29:56) [common] downloading http://download.automotivelinux.org/AGL/upload/ci/29918/1/h3ulcb-nogfx/r8a77951-agl-refhw.dtb saving as /var/lib/lava/dispatcher/tmp/34495/nbd-deploy-keaxhurj/dtb/r8a77951-agl-refhw.dtb total size: 88958 (0 MB) No compression specified progress 36 % (0 MB) progress 73 % (0 MB) progress 100 % (0 MB) 0 MB downloaded in 0.42 s (0.20 MB/s) end: 1.3.1 http-download (duration 00:00:00) [common] case: http-download
case_id: 3985810
definition: lava
duration: 0.43
extra: ...
level: 1.3.1
namespace: common
result: pass
end: 1.3 download-retry (duration 00:00:00) [common] start: 1.4 download-retry (timeout 01:29:55) [common] start: 1.4.1 http-download (timeout 01:29:55) [common] downloading http://download.automotivelinux.org/AGL/upload/ci/29918/1/h3ulcb-nogfx/agl-ivi-demo-qt-h3ulcb.ext4.xz saving as /var/lib/lava/dispatcher/tmp/34495/nbd-deploy-keaxhurj/nbdroot/agl-ivi-demo-qt-h3ulcb.ext4 total size: 481496972 (459 MB) Using unxz to decompress xz progress 0 % (0 MB) progress 5 % (22 MB) progress 10 % (45 MB) progress 15 % (68 MB) progress 20 % (91 MB) progress 25 % (114 MB) progress 30 % (137 MB) progress 35 % (160 MB) progress 40 % (183 MB) progress 45 % (206 MB) progress 50 % (229 MB) progress 55 % (252 MB) progress 60 % (275 MB) progress 65 % (298 MB) progress 70 % (321 MB) progress 75 % (344 MB) progress 80 % (367 MB) progress 85 % (390 MB) progress 90 % (413 MB) progress 95 % (436 MB) progress 100 % (459 MB) 459 MB downloaded in 116.85 s (3.93 MB/s) end: 1.4.1 http-download (duration 00:01:57) [common] case: http-download
case_id: 3985810
definition: lava
duration: 116.86
extra: ...
level: 1.4.1
namespace: common
result: pass
end: 1.4 download-retry (duration 00:01:57) [common] start: 1.5 lava-overlay (timeout 01:27:58) [common] [common] Preparing overlay tarball in /var/lib/lava/dispatcher/tmp/34495/lava-overlay-dclfp_ab makedir: /var/lib/lava/dispatcher/tmp/34495/lava-overlay-dclfp_ab/lava-34495/bin makedir: /var/lib/lava/dispatcher/tmp/34495/lava-overlay-dclfp_ab/lava-34495/tests makedir: /var/lib/lava/dispatcher/tmp/34495/lava-overlay-dclfp_ab/lava-34495/results Creating /var/lib/lava/dispatcher/tmp/34495/lava-overlay-dclfp_ab/lava-34495/bin/lava-add-keys Creating /var/lib/lava/dispatcher/tmp/34495/lava-overlay-dclfp_ab/lava-34495/bin/lava-add-sources Creating /var/lib/lava/dispatcher/tmp/34495/lava-overlay-dclfp_ab/lava-34495/bin/lava-background-process-start Creating /var/lib/lava/dispatcher/tmp/34495/lava-overlay-dclfp_ab/lava-34495/bin/lava-background-process-stop Creating /var/lib/lava/dispatcher/tmp/34495/lava-overlay-dclfp_ab/lava-34495/bin/lava-common-functions Creating /var/lib/lava/dispatcher/tmp/34495/lava-overlay-dclfp_ab/lava-34495/bin/lava-echo-ipv4 Creating /var/lib/lava/dispatcher/tmp/34495/lava-overlay-dclfp_ab/lava-34495/bin/lava-install-packages Creating /var/lib/lava/dispatcher/tmp/34495/lava-overlay-dclfp_ab/lava-34495/bin/lava-installed-packages Creating /var/lib/lava/dispatcher/tmp/34495/lava-overlay-dclfp_ab/lava-34495/bin/lava-os-build Creating /var/lib/lava/dispatcher/tmp/34495/lava-overlay-dclfp_ab/lava-34495/bin/lava-probe-channel Creating /var/lib/lava/dispatcher/tmp/34495/lava-overlay-dclfp_ab/lava-34495/bin/lava-probe-ip Creating /var/lib/lava/dispatcher/tmp/34495/lava-overlay-dclfp_ab/lava-34495/bin/lava-target-ip Creating /var/lib/lava/dispatcher/tmp/34495/lava-overlay-dclfp_ab/lava-34495/bin/lava-target-mac Creating /var/lib/lava/dispatcher/tmp/34495/lava-overlay-dclfp_ab/lava-34495/bin/lava-target-storage Creating /var/lib/lava/dispatcher/tmp/34495/lava-overlay-dclfp_ab/lava-34495/bin/lava-test-case Creating /var/lib/lava/dispatcher/tmp/34495/lava-overlay-dclfp_ab/lava-34495/bin/lava-test-event Creating /var/lib/lava/dispatcher/tmp/34495/lava-overlay-dclfp_ab/lava-34495/bin/lava-test-feedback Creating /var/lib/lava/dispatcher/tmp/34495/lava-overlay-dclfp_ab/lava-34495/bin/lava-test-raise Creating /var/lib/lava/dispatcher/tmp/34495/lava-overlay-dclfp_ab/lava-34495/bin/lava-test-reference Creating /var/lib/lava/dispatcher/tmp/34495/lava-overlay-dclfp_ab/lava-34495/bin/lava-test-runner Creating /var/lib/lava/dispatcher/tmp/34495/lava-overlay-dclfp_ab/lava-34495/bin/lava-test-set Creating /var/lib/lava/dispatcher/tmp/34495/lava-overlay-dclfp_ab/lava-34495/bin/lava-test-shell Updating /var/lib/lava/dispatcher/tmp/34495/lava-overlay-dclfp_ab/lava-34495/bin/lava-install-packages (oe) Updating /var/lib/lava/dispatcher/tmp/34495/lava-overlay-dclfp_ab/lava-34495/bin/lava-installed-packages (oe) Creating /var/lib/lava/dispatcher/tmp/34495/lava-overlay-dclfp_ab/lava-34495/environment LAVA metadata - LAVA_JOB_ID=34495 - LAVA_DISPATCHER_IP=172.18.0.2 start: 1.5.1 ssh-authorize (timeout 01:27:58) [common] end: 1.5.1 ssh-authorize (duration 00:00:00) [common] start: 1.5.2 lava-vland-overlay (timeout 01:27:58) [common] skipped lava-vland-overlay end: 1.5.2 lava-vland-overlay (duration 00:00:00) [common] start: 1.5.3 lava-multinode-overlay (timeout 01:27:58) [common] skipped lava-multinode-overlay end: 1.5.3 lava-multinode-overlay (duration 00:00:00) [common] start: 1.5.4 test-definition (timeout 01:27:58) [common] Loading test definitions start: 1.5.4.1 git-repo-action (timeout 01:27:58) [common] Using /lava-34495 at stage 0 Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions Running '/usr/bin/git clone --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/34495/lava-overlay-dclfp_ab/lava-34495/0/tests/0_network-veth' Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/34495/lava-overlay-dclfp_ab/lava-34495/0/tests/0_network-veth/test-suites/short-smoke/network-veth.yaml uuid=34495_1.5.4.1 testdef=None end: 1.5.4.1 git-repo-action (duration 00:00:01) [common] case: git-repo-action
case_id: 3985831
definition: lava
duration: 0.64
extra: ...
level: 1.5.4.1
namespace: common
result: pass
start: 1.5.4.2 test-overlay (timeout 01:27:58) [common] end: 1.5.4.2 test-overlay (duration 00:00:00) [common] case: test-overlay
case_id: 3985832
definition: lava
duration: 0.01
extra: ...
level: 1.5.4.2
namespace: common
result: pass
start: 1.5.4.3 test-install-overlay (timeout 01:27:58) [common] end: 1.5.4.3 test-install-overlay (duration 00:00:00) [common] case: test-install-overlay
case_id: 3985833
definition: lava
duration: 0.01
extra: ...
level: 1.5.4.3
namespace: common
result: pass
start: 1.5.4.4 test-runscript-overlay (timeout 01:27:58) [common] runner path: /var/lib/lava/dispatcher/tmp/34495/lava-overlay-dclfp_ab/lava-34495/0/tests/0_network-veth test_uuid 34495_1.5.4.1 end: 1.5.4.4 test-runscript-overlay (duration 00:00:00) [common] case: test-runscript-overlay
case_id: 3985834
definition: lava
duration: 0.01
extra: ...
level: 1.5.4.4
namespace: common
result: pass
start: 1.5.4.5 git-repo-action (timeout 01:27:58) [common] Using /lava-34495 at stage 1 Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions Running '/usr/bin/git clone --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/34495/lava-overlay-dclfp_ab/lava-34495/1/tests/1_ci-busybox' Removing '.git' directory in /var/lib/lava/dispatcher/tmp/34495/lava-overlay-dclfp_ab/lava-34495/1/tests/1_ci-busybox Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/34495/lava-overlay-dclfp_ab/lava-34495/1/tests/1_ci-busybox/test-suites/short-smoke/busybox.yaml uuid=34495_1.5.4.5 testdef={'testdef_pattern': {'pattern': '^(?P<test_case_id>[a-zA-Z0-9_-]+):\\s(?P<result>\\w+)', 'fixupdict': {'PASS': 'pass', 'FAIL': 'fail'}}} end: 1.5.4.5 git-repo-action (duration 00:00:01) [common] case: git-repo-action
case_id: 3985831
definition: lava
duration: 0.78
extra: ...
level: 1.5.4.5
namespace: common
result: pass
start: 1.5.4.6 test-overlay (timeout 01:27:57) [common] end: 1.5.4.6 test-overlay (duration 00:00:00) [common] case: test-overlay
case_id: 3985832
definition: lava
duration: 0.00
extra: ...
level: 1.5.4.6
namespace: common
result: pass
start: 1.5.4.7 test-install-overlay (timeout 01:27:57) [common] end: 1.5.4.7 test-install-overlay (duration 00:00:00) [common] case: test-install-overlay
case_id: 3985833
definition: lava
duration: 0.01
extra: ...
level: 1.5.4.7
namespace: common
result: pass
start: 1.5.4.8 test-runscript-overlay (timeout 01:27:57) [common] runner path: /var/lib/lava/dispatcher/tmp/34495/lava-overlay-dclfp_ab/lava-34495/1/tests/1_ci-busybox test_uuid 34495_1.5.4.5 end: 1.5.4.8 test-runscript-overlay (duration 00:00:00) [common] case: test-runscript-overlay
case_id: 3985834
definition: lava
duration: 0.01
extra: ...
level: 1.5.4.8
namespace: common
result: pass
start: 1.5.4.9 git-repo-action (timeout 01:27:57) [common] Using /lava-34495 at stage 1 Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions Running '/usr/bin/git clone --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/34495/lava-overlay-dclfp_ab/lava-34495/1/tests/2_ci-smoke-tests-basic' Removing '.git' directory in /var/lib/lava/dispatcher/tmp/34495/lava-overlay-dclfp_ab/lava-34495/1/tests/2_ci-smoke-tests-basic Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/34495/lava-overlay-dclfp_ab/lava-34495/1/tests/2_ci-smoke-tests-basic/test-suites/short-smoke/smoke-tests-basic.yaml uuid=34495_1.5.4.9 testdef=None end: 1.5.4.9 git-repo-action (duration 00:00:01) [common] case: git-repo-action
case_id: 3985831
definition: lava
duration: 1.41
extra: ...
level: 1.5.4.9
namespace: common
result: pass
start: 1.5.4.10 test-overlay (timeout 01:27:55) [common] end: 1.5.4.10 test-overlay (duration 00:00:00) [common] case: test-overlay
case_id: 3985832
definition: lava
duration: 0.00
extra: ...
level: 1.5.4.10
namespace: common
result: pass
start: 1.5.4.11 test-install-overlay (timeout 01:27:55) [common] end: 1.5.4.11 test-install-overlay (duration 00:00:00) [common] case: test-install-overlay
case_id: 3985833
definition: lava
duration: 0.01
extra: ...
level: 1.5.4.11
namespace: common
result: pass
start: 1.5.4.12 test-runscript-overlay (timeout 01:27:55) [common] runner path: /var/lib/lava/dispatcher/tmp/34495/lava-overlay-dclfp_ab/lava-34495/1/tests/2_ci-smoke-tests-basic test_uuid 34495_1.5.4.9 end: 1.5.4.12 test-runscript-overlay (duration 00:00:00) [common] case: test-runscript-overlay
case_id: 3985834
definition: lava
duration: 0.01
extra: ...
level: 1.5.4.12
namespace: common
result: pass
start: 1.5.4.13 git-repo-action (timeout 01:27:55) [common] Using /lava-34495 at stage 1 Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions Running '/usr/bin/git clone --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/34495/lava-overlay-dclfp_ab/lava-34495/1/tests/3_ci-service-check' Removing '.git' directory in /var/lib/lava/dispatcher/tmp/34495/lava-overlay-dclfp_ab/lava-34495/1/tests/3_ci-service-check Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/34495/lava-overlay-dclfp_ab/lava-34495/1/tests/3_ci-service-check/test-suites/short-smoke/service-check.yaml uuid=34495_1.5.4.13 testdef=None end: 1.5.4.13 git-repo-action (duration 00:00:01) [common] case: git-repo-action
case_id: 3985831
definition: lava
duration: 1.49
extra: ...
level: 1.5.4.13
namespace: common
result: pass
start: 1.5.4.14 test-overlay (timeout 01:27:54) [common] end: 1.5.4.14 test-overlay (duration 00:00:00) [common] case: test-overlay
case_id: 3985832
definition: lava
duration: 0.00
extra: ...
level: 1.5.4.14
namespace: common
result: pass
start: 1.5.4.15 test-install-overlay (timeout 01:27:54) [common] end: 1.5.4.15 test-install-overlay (duration 00:00:00) [common] case: test-install-overlay
case_id: 3985833
definition: lava
duration: 0.01
extra: ...
level: 1.5.4.15
namespace: common
result: pass
start: 1.5.4.16 test-runscript-overlay (timeout 01:27:54) [common] runner path: /var/lib/lava/dispatcher/tmp/34495/lava-overlay-dclfp_ab/lava-34495/1/tests/3_ci-service-check test_uuid 34495_1.5.4.13 BUILD_TAGS='' DEVICE_TAGS='' end: 1.5.4.16 test-runscript-overlay (duration 00:00:00) [common] case: test-runscript-overlay
case_id: 3985834
definition: lava
duration: 0.01
extra: ...
level: 1.5.4.16
namespace: common
result: pass
start: 1.5.4.17 git-repo-action (timeout 01:27:54) [common] Using /lava-34495 at stage 2 Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions Running '/usr/bin/git clone --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/34495/lava-overlay-dclfp_ab/lava-34495/2/tests/4_screenshooter' Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/34495/lava-overlay-dclfp_ab/lava-34495/2/tests/4_screenshooter/test-suites/short-smoke/screenshooter.yaml uuid=34495_1.5.4.17 testdef=None end: 1.5.4.17 git-repo-action (duration 00:00:01) [common] case: git-repo-action
case_id: 3985831
definition: lava
duration: 1.48
extra: ...
level: 1.5.4.17
namespace: common
result: pass
start: 1.5.4.18 test-overlay (timeout 01:27:52) [common] end: 1.5.4.18 test-overlay (duration 00:00:00) [common] case: test-overlay
case_id: 3985832
definition: lava
duration: 0.00
extra: ...
level: 1.5.4.18
namespace: common
result: pass
start: 1.5.4.19 test-install-overlay (timeout 01:27:52) [common] end: 1.5.4.19 test-install-overlay (duration 00:00:00) [common] case: test-install-overlay
case_id: 3985833
definition: lava
duration: 0.01
extra: ...
level: 1.5.4.19
namespace: common
result: pass
start: 1.5.4.20 test-runscript-overlay (timeout 01:27:52) [common] runner path: /var/lib/lava/dispatcher/tmp/34495/lava-overlay-dclfp_ab/lava-34495/2/tests/4_screenshooter test_uuid 34495_1.5.4.17 end: 1.5.4.20 test-runscript-overlay (duration 00:00:00) [common] case: test-runscript-overlay
case_id: 3985834
definition: lava
duration: 0.01
extra: ...
level: 1.5.4.20
namespace: common
result: pass
start: 1.5.4.21 git-repo-action (timeout 01:27:52) [common] Using /lava-34495 at stage 3 Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions Running '/usr/bin/git clone --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/34495/lava-overlay-dclfp_ab/lava-34495/3/tests/5_ci-dumpjournal' Removing '.git' directory in /var/lib/lava/dispatcher/tmp/34495/lava-overlay-dclfp_ab/lava-34495/3/tests/5_ci-dumpjournal Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/34495/lava-overlay-dclfp_ab/lava-34495/3/tests/5_ci-dumpjournal/test-suites/short-smoke/dumpjournal.yaml uuid=34495_1.5.4.21 testdef={'testdef_pattern': {'pattern': '^(?P<test_case_id>[a-zA-Z0-9_-]+):\\s(?P<result>\\w+)', 'fixupdict': {'PASS': 'pass', 'FAIL': 'fail'}}} end: 1.5.4.21 git-repo-action (duration 00:00:01) [common] case: git-repo-action
case_id: 3985831
definition: lava
duration: 1.06
extra: ...
level: 1.5.4.21
namespace: common
result: pass
start: 1.5.4.22 test-overlay (timeout 01:27:51) [common] end: 1.5.4.22 test-overlay (duration 00:00:00) [common] case: test-overlay
case_id: 3985832
definition: lava
duration: 0.00
extra: ...
level: 1.5.4.22
namespace: common
result: pass
start: 1.5.4.23 test-install-overlay (timeout 01:27:51) [common] end: 1.5.4.23 test-install-overlay (duration 00:00:00) [common] case: test-install-overlay
case_id: 3985833
definition: lava
duration: 0.01
extra: ...
level: 1.5.4.23
namespace: common
result: pass
start: 1.5.4.24 test-runscript-overlay (timeout 01:27:51) [common] runner path: /var/lib/lava/dispatcher/tmp/34495/lava-overlay-dclfp_ab/lava-34495/3/tests/5_ci-dumpjournal test_uuid 34495_1.5.4.21 end: 1.5.4.24 test-runscript-overlay (duration 00:00:00) [common] case: test-runscript-overlay
case_id: 3985834
definition: lava
duration: 0.01
extra: ...
level: 1.5.4.24
namespace: common
result: pass
Creating lava-test-runner.conf files Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/34495/lava-overlay-dclfp_ab/lava-34495/0 for stage 0 - 0_network-veth Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/34495/lava-overlay-dclfp_ab/lava-34495/1 for stage 1 - 1_ci-busybox - 2_ci-smoke-tests-basic - 3_ci-service-check Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/34495/lava-overlay-dclfp_ab/lava-34495/2 for stage 2 - 4_screenshooter Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/34495/lava-overlay-dclfp_ab/lava-34495/3 for stage 3 - 5_ci-dumpjournal end: 1.5.4 test-definition (duration 00:00:07) [common] start: 1.5.5 compress-overlay (timeout 01:27:51) [common] end: 1.5.5 compress-overlay (duration 00:00:01) [common] start: 1.5.6 persistent-nfs-overlay (timeout 01:27:51) [common] end: 1.5.6 persistent-nfs-overlay (duration 00:00:00) [common] end: 1.5 lava-overlay (duration 00:00:08) [common] start: 1.6 prepare-kernel (timeout 01:27:51) [common] start: 1.6.1 uboot-prepare-kernel (timeout 01:27:51) [common] end: 1.6.1 uboot-prepare-kernel (duration 00:00:00) [common] end: 1.6 prepare-kernel (duration 00:00:00) [common] start: 1.7 xnbd-server-deploy (timeout 01:27:51) [common] xnbd-server-deploy: starting nbd-server NBD-IP: 172.18.0.2, NBD-PORT: 61988, NBD-ROOT: 34495/nbd-deploy-keaxhurj/nbdroot/agl-ivi-demo-qt-h3ulcb.ext4 nbd-server 61988 /var/lib/lava/dispatcher/tmp/34495/nbd-deploy-keaxhurj/nbdroot/agl-ivi-demo-qt-h3ulcb.ext4 output: output: ** (process:16120): WARNING **: 15:42:21.620: Specifying an export on the command line no longer uses the oldstyle protocol. output: xnbd-server-deploy: starting nbd-server done end: 1.7 xnbd-server-deploy (duration 00:00:00) [common] end: 1 nbd-deploy (duration 00:02:09) [common] start: 2 uboot-action (timeout 00:20:00) [common] start: 2.1 uboot-from-media (timeout 00:20:00) [common] end: 2.1 uboot-from-media (duration 00:00:00) [common] start: 2.2 bootloader-overlay (timeout 00:20:00) [common] substitutions: - {BOOTX}: booti 0x48080000 0x4c000000:${initrd_size} 0x48000000 - {DTB_ADDR}: 0x48000000 - {DTB}: 34495/nbd-deploy-keaxhurj/dtb/r8a77951-agl-refhw.dtb - {INITRD}: 34495/nbd-deploy-keaxhurj/initrd/initramfs-netboot-image-h3ulcb.ext4.gz - {KERNEL_ADDR}: 0x48080000 - {KERNEL}: 34495/nbd-deploy-keaxhurj/kernel/Image - {LAVA_MAC}: None - {NBDSERVERIP}: 172.18.0.2 - {NBDSERVERPORT}: 61988 - {PRESEED_CONFIG}: None - {PRESEED_LOCAL}: None - {RAMDISK_ADDR}: 0x4c000000 - {RAMDISK}: 34495/nbd-deploy-keaxhurj/initrd/initramfs-netboot-image-h3ulcb.ext4.gz - {ROOT_PART}: None - {ROOT}: None - {SERVER_IP}: 172.18.0.2 - {TEE_ADDR}: 0x83000000 - {TEE}: None Parsed boot commands: - setenv autoload no - setenv initrd_high 0xffffffff - setenv fdt_high 0xffffffff - dhcp - setenv serverip 172.18.0.2 - tftp 0x48080000 34495/nbd-deploy-keaxhurj/kernel/Image - tftp 0x4c000000 34495/nbd-deploy-keaxhurj/initrd/initramfs-netboot-image-h3ulcb.ext4.gz - setenv initrd_size ${filesize} - tftp 0x48000000 34495/nbd-deploy-keaxhurj/dtb/r8a77951-agl-refhw.dtb - setenv bootargs 'console=ttySC0,115200n8 rw nbd.server=172.18.0.2 nbd.port=61988 root=/dev/ram0 ramdisk_size=16384 rootdelay=7 systemd.mask=systemd-network-generator.service ip=dhcp verbose earlyprintk systemd.log_color=false ${extraargs} rw' - booti 0x48080000 0x4c000000:${initrd_size} 0x48000000 end: 2.2 bootloader-overlay (duration 00:00:00) [common] case: bootloader-overlay
case_id: 3985835
definition: lava
duration: 0.02
extra: ...
level: 2.2
namespace: common
result: pass
start: 2.3 connect-device (timeout 00:20:00) [common] [common] connect-device Connecting to device using 'telnet 127.0.0.1 63004' Setting prompt string to ['lava-test: # '] end: 2.3 connect-device (duration 00:00:03) [common] start: 2.4 uboot-commands (timeout 00:19:57) [common] start: 2.4.1 reset-device (timeout 00:19:57) [common] start: 2.4.1.1 pdu-reboot (timeout 00:19:57) [common] Calling: '/usr/local/bin/acme-cli' '-s' '192.168.66.2' 'reset' '8' >> Success >> Success Returned 0 in 4 seconds end: 2.4.1.1 pdu-reboot (duration 00:00:05) [common] case: pdu-reboot
case_id: 3985836
definition: lava
duration: 4.63
extra: ...
level: 2.4.1.1
namespace: common
result: pass
end: 2.4.1 reset-device (duration 00:00:05) [common] start: 2.4.2 bootloader-interrupt (timeout 00:19:52) [common] Setting prompt string to ['Hit any key to stop autoboot'] bootloader-interrupt: Wait for prompt ['Hit any key to stop autoboot'] (timeout 00:20:00) Trying 127.0.0.1... Connected to 127.0.0.1. Escape character is '^]'. [ 0.000143] NOTICE: BL2: R-Car H3 Initial Program Loader(CA57) [ 0.004579] NOTICE: BL2: Initial Program Loader(Rev.2.0.6) [ 0.010112] NOTICE: BL2: PRR is R-Car H3 Ver.3.0 [ 0.014775] NOTICE: BL2: Board is unknown Rev.--- [ 0.019547] NOTICE: BL2: Boot device is eMMC(50MHz x8) [ 0.024715] NOTICE: BL2: LCM state is CM [ 0.028756] NOTICE: BL2: AVS setting succeeded. DVFS_SetVID=0x53 [ 0.034732] NOTICE: BL2: CH0: 0x400000000 - 0x47fffffff, 2 GiB [ 0.040594] NOTICE: BL2: CH1: 0x500000000 - 0x57fffffff, 2 GiB [ 0.046469] NOTICE: BL2: CH2: 0x600000000 - 0x67fffffff, 2 GiB [ 0.052344] NOTICE: BL2: CH3: 0x700000000 - 0x77fffffff, 2 GiB [ 0.058261] NOTICE: BL2: DDR3200(rev.0.40) [ 0.073563] NOTICE: BL2: [COLD_BOOT] [ 0.083094] NOTICE: BL2: DRAM Split is 4ch(DDR f) [ 0.086393] NOTICE: BL2: QoS is default setting(rev.0.11) [ 0.091836] NOTICE: BL2: DRAM refresh interval 1.95 usec [ 0.097193] NOTICE: BL2: Periodic Write DQ Training [ 0.114636] NOTICE: BL2: v1.5(release):af9f429a-dirty [ 0.118280] NOTICE: BL2: Built : 02:23:33, Feb 7 2020 [ 0.123467] NOTICE: BL2: Normal boot [ 0.127103] NOTICE: BL2: eMMC boot from partition 1 [ 0.133646] NOTICE: BL2: Load dst=0xe6325100 src=(p:1)0x30000(384) len=0x200(1) [ 0.139762] NOTICE: BL2: Load dst=0x43f00000 src=(p:1)0x30400(386) len=0x1800(12) [ 0.147362] NOTICE: BL2: Load dst=0x44000000 src=(p:1)0x40000(512) len=0x10000(128) [ 0.156339] NOTICE: BL2: Load dst=0x44100000 src=(p:1)0x200000(4096) len=0x100000(2048) [ 0.187302] NOTICE: BL2: Load dst=0x50000000 src=(p:2)0x0(0) len=0x100000(2048) [ 0.216040] NOTICE: BL2: Booting BL31 U-Boot 2018.09 (May 10 2019 - 09:16:29 +0000) CPU: Renesas Electronics R8A7795 rev 3.0 Model: Renesas Salvator-XS board based on r8a7795 ES3.0+ with 8GiB (4 x 2 GiB) DRAM: 7.9 GiB Bank #0: 0x048000000 - 0x0bfffffff, 1.9 GiB Bank #1: 0x500000000 - 0x57fffffff, 2 GiB Bank #2: 0x600000000 - 0x67fffffff, 2 GiB Bank #3: 0x700000000 - 0x77fffffff, 2 GiB MMC: sd@ee100000: 0, sd@ee140000: 1, sd@ee160000: 2 Loading Environment from MMC... OK In: serial@e6e88000 Out: serial@e6e88000 Err: serial@e6e88000 Net: eth0: ethernet@e6800000 Hit any key to stop autoboot: 3 end: 2.4.2 bootloader-interrupt (duration 00:00:01) [common] start: 2.4.3 bootloader-commands (timeout 00:19:51) [common] Setting prompt string to ['=>'] bootloader-commands: Wait for prompt ['=>'] (timeout 00:19:51)  0 Setting prompt string to ['=>', 'Resetting CPU', 'Must RESET board to recover', 'TIMEOUT', 'Retry count exceeded', 'Retry time exceeded; starting again', 'ERROR: The remote end did not respond in time.', 'File not found', 'Bad Linux ARM64 Image magic!', 'Wrong Ramdisk Image Format', 'Ramdisk image is corrupt or invalid', 'ERROR: Failed to allocate', 'TFTP error: trying to overwrite reserved memory', 'Bad Linux RISCV Image magic!', 'Wrong Image Format for boot', 'ERROR: Did not find a cmdline Flattened Device Tree', 'ERROR: RD image overlaps OS image'] setenv autoload no => setenv autoload no bootloader-commands: Wait for prompt ['=>', 'Resetting CPU', 'Must RESET board to recover', 'TIMEOUT', 'Retry count exceeded', 'Retry time exceeded; starting again', 'ERROR: The remote end did not respond in time.', 'File not found', 'Bad Linux ARM64 Image magic!', 'Wrong Ramdisk Image Format', 'Ramdisk image is corrupt or invalid', 'ERROR: Failed to allocate', 'TFTP error: trying to overwrite reserved memory', 'Bad Linux RISCV Image magic!', 'Wrong Image Format for boot', 'ERROR: Did not find a cmdline Flattened Device Tree', 'ERROR: RD image overlaps OS image'] (timeout 00:19:51) setenv autoload no setenv initrd_high 0xffffffff => setenv initrd_high 0xffffffff bootloader-commands: Wait for prompt ['=>', 'Resetting CPU', 'Must RESET board to recover', 'TIMEOUT', 'Retry count exceeded', 'Retry time exceeded; starting again', 'ERROR: The remote end did not respond in time.', 'File not found', 'Bad Linux ARM64 Image magic!', 'Wrong Ramdisk Image Format', 'Ramdisk image is corrupt or invalid', 'ERROR: Failed to allocate', 'TFTP error: trying to overwrite reserved memory', 'Bad Linux RISCV Image magic!', 'Wrong Image Format for boot', 'ERROR: Did not find a cmdline Flattened Device Tree', 'ERROR: RD image overlaps OS image'] (timeout 00:19:51) setenv initrd_high 0xffffffff setenv fdt_high 0xffffffff => setenv fdt_high 0xffffffff bootloader-commands: Wait for prompt ['=>', 'Resetting CPU', 'Must RESET board to recover', 'TIMEOUT', 'Retry count exceeded', 'Retry time exceeded; starting again', 'ERROR: The remote end did not respond in time.', 'File not found', 'Bad Linux ARM64 Image magic!', 'Wrong Ramdisk Image Format', 'Ramdisk image is corrupt or invalid', 'ERROR: Failed to allocate', 'TFTP error: trying to overwrite reserved memory', 'Bad Linux RISCV Image magic!', 'Wrong Image Format for boot', 'ERROR: Did not find a cmdline Flattened Device Tree', 'ERROR: RD image overlaps OS image'] (timeout 00:19:50) setenv fdt_high 0xffffffff dhcp => dhcp bootloader-commands: Wait for prompt ['=>', 'Resetting CPU', 'Must RESET board to recover', 'TIMEOUT', 'Retry count exceeded', 'Retry time exceeded; starting again', 'ERROR: The remote end did not respond in time.', 'File not found', 'Bad Linux ARM64 Image magic!', 'Wrong Ramdisk Image Format', 'Ramdisk image is corrupt or invalid', 'ERROR: Failed to allocate', 'TFTP error: trying to overwrite reserved memory', 'Bad Linux RISCV Image magic!', 'Wrong Image Format for boot', 'ERROR: Did not find a cmdline Flattened Device Tree', 'ERROR: RD image overlaps OS image'] (timeout 00:19:50) dhcp ethernet@e6800000 Waiting for PHY auto negotiation to complete...... done BOOTP broadcast 1 BOOTP broadcast 2 BOOTP broadcast 3 DHCP client bound to address 192.168.66.28 (1301 ms) setenv serverip 172.18.0.2 => setenv serverip 172.18.0.2 bootloader-commands: Wait for prompt ['=>', 'Resetting CPU', 'Must RESET board to recover', 'TIMEOUT', 'Retry count exceeded', 'Retry time exceeded; starting again', 'ERROR: The remote end did not respond in time.', 'File not found', 'Bad Linux ARM64 Image magic!', 'Wrong Ramdisk Image Format', 'Ramdisk image is corrupt or invalid', 'ERROR: Failed to allocate', 'TFTP error: trying to overwrite reserved memory', 'Bad Linux RISCV Image magic!', 'Wrong Image Format for boot', 'ERROR: Did not find a cmdline Flattened Device Tree', 'ERROR: RD image overlaps OS image'] (timeout 00:19:43) setenv serverip 172.18.0.2 tftp 0x48080000 34495/nbd-deploy-keaxhurj/kernel/Image => tftp 0x48080000 34495/nbd-deploy-keaxhurj/kernel/Image bootloader-commands: Wait for prompt ['=>', 'Resetting CPU', 'Must RESET board to recover', 'TIMEOUT', 'Retry count exceeded', 'Retry time exceeded; starting again', 'ERROR: The remote end did not respond in time.', 'File not found', 'Bad Linux ARM64 Image magic!', 'Wrong Ramdisk Image Format', 'Ramdisk image is corrupt or invalid', 'ERROR: Failed to allocate', 'TFTP error: trying to overwrite reserved memory', 'Bad Linux RISCV Image magic!', 'Wrong Image Format for boot', 'ERROR: Did not find a cmdline Flattened Device Tree', 'ERROR: RD image overlaps OS image'] (timeout 00:19:43) tftp 0x48080000 34495/nbd-deploy-keaxhurj/kernel/Image Using ethernet@e6800000 device TFTP from server 172.18.0.2; our IP address is 192.168.66.28; sending through gateway 192.168.66.1 Filename '34495/nbd-deploy-keaxhurj/kernel/Image'. Load address: 0x48080000 Loading: *T T T T T T T T T T Retry count exceeded; starting again matched a bootloader error message: 'Retry count exceeded' (4) end: 2.4.3 bootloader-commands (duration 00:01:03) [common] case: bootloader-commands
case_id: 3985843
definition: lava
duration: 63.07
extra: ...
level: 2.4.3
namespace: common
result: fail
matched a bootloader error message: 'Retry count exceeded' (4) end: 2.4 uboot-commands (duration 00:01:09) [common] case: uboot-commands
case_id: 3985844
definition: lava
duration: 69.11
extra: ...
level: 2.4
namespace: common
result: fail
uboot-action failed: 1 of 1 attempts. 'matched a bootloader error message: 'Retry count exceeded' (4)' matched a bootloader error message: 'Retry count exceeded' (4) end: 2 uboot-action (duration 00:01:12) [common] case: uboot-action
case_id: 3985845
definition: lava
duration: 72.26
extra: ...
level: 2
namespace: common
result: fail
Cleaning after the job Cleaning up download directory: /var/lib/lava/dispatcher/tmp/34495/nbd-deploy-keaxhurj/initrd Cleaning up download directory: /var/lib/lava/dispatcher/tmp/34495/nbd-deploy-keaxhurj/kernel Cleaning up download directory: /var/lib/lava/dispatcher/tmp/34495/nbd-deploy-keaxhurj/dtb Cleaning up download directory: /var/lib/lava/dispatcher/tmp/34495/nbd-deploy-keaxhurj/nbdroot start: 7.1 power-off (timeout 00:00:10) [common] Calling: '/usr/local/bin/acme-cli' '-s' '192.168.66.2' 'switch_off' '8' >> Success Returned 0 in 3 seconds end: 7.1 power-off (duration 00:00:04) [common] case: power-off
case_id: 3985846
definition: lava
duration: 3.57
extra: ...
level: 7.1
namespace: common
result: pass
start: 7.2 read-feedback (timeout 00:29:56) [common] Listened to connection for namespace 'common' for up to 1s Finalising connection for namespace 'common' Disconnecting from shell: Finalise => end: 7.2 read-feedback (duration 00:00:01) [common] lava-xnbd cleanup clean NBD port 61988 Override tmp directory removed at /var/lib/lava/dispatcher/tmp/34495 Root tmp directory removed at /var/lib/lava/dispatcher/tmp/34495 InfrastructureError: The Infrastructure is not working correctly. Please report this error to LAVA admins. case: job
case_id: 3985847
definition: lava
error_msg: matched a bootloader error message: 'Retry count exceeded' (4)
error_type: Infrastructure
result: fail