extra keys not allowed @ data['actions[0]']['deploy']['nbd']['initrd']['allow_modify']
Infrastructure error: matched a bootloader error message: 'Resetting CPU' (1)
lava-dispatcher, installed at version: 2023.01
start: 0 validate
Start time: 2023-11-30 16:08:33.700178+00:00 (UTC)
Validating that https://download.automotivelinux.org/AGL/upload/ci/lamprey/12.1.16/h3ulcb-nogfx/deploy/images/h3ulcb//initramfs-netboot-image-h3ulcb.ext4.gz exists
Validating that https://download.automotivelinux.org/AGL/upload/ci/lamprey/12.1.16/h3ulcb-nogfx/deploy/images/h3ulcb//Image exists
Validating that https://download.automotivelinux.org/AGL/upload/ci/lamprey/12.1.16/h3ulcb-nogfx/deploy/images/h3ulcb//r8a77951-ulcb.dtb exists
Validating that https://download.automotivelinux.org/AGL/upload/ci/lamprey/12.1.16/h3ulcb-nogfx/deploy/images/h3ulcb//agl-demo-platform-crosssdk-h3ulcb.ext4.xz exists
validate duration: 2.11
case: validate
case_id: 3966285
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 61962
start: 1.1 download-retry (timeout 01:30:00) [common]
start: 1.1.1 http-download (timeout 01:30:00) [common]
downloading https://download.automotivelinux.org/AGL/upload/ci/lamprey/12.1.16/h3ulcb-nogfx/deploy/images/h3ulcb//initramfs-netboot-image-h3ulcb.ext4.gz
saving as /var/lib/lava/dispatcher/tmp/33954/nbd-deploy-7cgpx1nc/initrd/initramfs-netboot-image-h3ulcb.ext4.gz
total size: 2029960 (1MB)
No compression specified
progress 1% (0MB)
progress 6% (0MB)
progress 11% (0MB)
progress 16% (0MB)
progress 22% (0MB)
progress 27% (0MB)
progress 32% (0MB)
progress 37% (0MB)
progress 43% (0MB)
progress 48% (0MB)
progress 53% (1MB)
progress 58% (1MB)
progress 64% (1MB)
progress 69% (1MB)
progress 74% (1MB)
progress 79% (1MB)
progress 85% (1MB)
progress 90% (1MB)
progress 95% (1MB)
progress 100% (1MB)
1MB downloaded in 1.22s (1.59MB/s)
end: 1.1.1 http-download (duration 00:00:01) [common]
case: http-download
case_id: 3966322
definition: lava
duration: 1.22
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 https://download.automotivelinux.org/AGL/upload/ci/lamprey/12.1.16/h3ulcb-nogfx/deploy/images/h3ulcb//Image
saving as /var/lib/lava/dispatcher/tmp/33954/nbd-deploy-7cgpx1nc/kernel/Image
total size: 38128128 (36MB)
No compression specified
progress 0% (0MB)
progress 5% (1MB)
progress 10% (3MB)
progress 15% (5MB)
progress 20% (7MB)
progress 25% (9MB)
progress 30% (10MB)
progress 35% (12MB)
progress 40% (14MB)
progress 45% (16MB)
progress 50% (18MB)
progress 55% (20MB)
progress 60% (21MB)
progress 65% (23MB)
progress 70% (25MB)
progress 75% (27MB)
progress 80% (29MB)
progress 85% (30MB)
progress 90% (32MB)
progress 95% (34MB)
progress 100% (36MB)
36MB downloaded in 3.95s (9.20MB/s)
end: 1.2.1 http-download (duration 00:00:04) [common]
case: http-download
case_id: 3966322
definition: lava
duration: 3.96
extra: ...
level: 1.2.1
namespace: common
result: pass
end: 1.2 download-retry (duration 00:00:04) [common]
start: 1.3 download-retry (timeout 01:29:55) [common]
start: 1.3.1 http-download (timeout 01:29:55) [common]
downloading https://download.automotivelinux.org/AGL/upload/ci/lamprey/12.1.16/h3ulcb-nogfx/deploy/images/h3ulcb//r8a77951-ulcb.dtb
saving as /var/lib/lava/dispatcher/tmp/33954/nbd-deploy-7cgpx1nc/dtb/r8a77951-ulcb.dtb
total size: 82960 (0MB)
No compression specified
progress 39% (0MB)
progress 78% (0MB)
progress 100% (0MB)
0MB downloaded in 0.68s (0.12MB/s)
end: 1.3.1 http-download (duration 00:00:01) [common]
case: http-download
case_id: 3966322
definition: lava
duration: 0.69
extra: ...
level: 1.3.1
namespace: common
result: pass
end: 1.3 download-retry (duration 00:00:01) [common]
start: 1.4 download-retry (timeout 01:29:54) [common]
start: 1.4.1 http-download (timeout 01:29:54) [common]
downloading https://download.automotivelinux.org/AGL/upload/ci/lamprey/12.1.16/h3ulcb-nogfx/deploy/images/h3ulcb//agl-demo-platform-crosssdk-h3ulcb.ext4.xz
saving as /var/lib/lava/dispatcher/tmp/33954/nbd-deploy-7cgpx1nc/nbdroot/agl-demo-platform-crosssdk-h3ulcb.ext4
total size: 870340464 (830MB)
Using unxz to decompress xz
progress 0% (0MB)
progress 5% (41MB)
progress 10% (83MB)
progress 15% (124MB)
progress 20% (166MB)
progress 25% (207MB)
progress 30% (249MB)
progress 35% (290MB)
progress 40% (332MB)
progress 45% (373MB)
progress 50% (415MB)
progress 55% (456MB)
progress 60% (498MB)
progress 65% (539MB)
progress 70% (581MB)
progress 75% (622MB)
progress 80% (664MB)
progress 85% (705MB)
progress 90% (747MB)
progress 95% (788MB)
progress 100% (830MB)
830MB downloaded in 209.75s (3.96MB/s)
end: 1.4.1 http-download (duration 00:03:30) [common]
case: http-download
case_id: 3966322
definition: lava
duration: 209.75
extra: ...
level: 1.4.1
namespace: common
result: pass
end: 1.4 download-retry (duration 00:03:30) [common]
start: 1.5 lava-overlay (timeout 01:26:24) [common]
[common] Preparing overlay tarball in /var/lib/lava/dispatcher/tmp/33954/lava-overlay-0lidze8k
makedir: /var/lib/lava/dispatcher/tmp/33954/lava-overlay-0lidze8k/lava-33954/bin
makedir: /var/lib/lava/dispatcher/tmp/33954/lava-overlay-0lidze8k/lava-33954/tests
makedir: /var/lib/lava/dispatcher/tmp/33954/lava-overlay-0lidze8k/lava-33954/results
Creating /var/lib/lava/dispatcher/tmp/33954/lava-overlay-0lidze8k/lava-33954/bin/lava-add-keys
Creating /var/lib/lava/dispatcher/tmp/33954/lava-overlay-0lidze8k/lava-33954/bin/lava-add-sources
Creating /var/lib/lava/dispatcher/tmp/33954/lava-overlay-0lidze8k/lava-33954/bin/lava-background-process-start
Creating /var/lib/lava/dispatcher/tmp/33954/lava-overlay-0lidze8k/lava-33954/bin/lava-background-process-stop
Creating /var/lib/lava/dispatcher/tmp/33954/lava-overlay-0lidze8k/lava-33954/bin/lava-common-functions
Creating /var/lib/lava/dispatcher/tmp/33954/lava-overlay-0lidze8k/lava-33954/bin/lava-echo-ipv4
Creating /var/lib/lava/dispatcher/tmp/33954/lava-overlay-0lidze8k/lava-33954/bin/lava-install-packages
Creating /var/lib/lava/dispatcher/tmp/33954/lava-overlay-0lidze8k/lava-33954/bin/lava-installed-packages
Creating /var/lib/lava/dispatcher/tmp/33954/lava-overlay-0lidze8k/lava-33954/bin/lava-os-build
Creating /var/lib/lava/dispatcher/tmp/33954/lava-overlay-0lidze8k/lava-33954/bin/lava-probe-channel
Creating /var/lib/lava/dispatcher/tmp/33954/lava-overlay-0lidze8k/lava-33954/bin/lava-probe-ip
Creating /var/lib/lava/dispatcher/tmp/33954/lava-overlay-0lidze8k/lava-33954/bin/lava-target-ip
Creating /var/lib/lava/dispatcher/tmp/33954/lava-overlay-0lidze8k/lava-33954/bin/lava-target-mac
Creating /var/lib/lava/dispatcher/tmp/33954/lava-overlay-0lidze8k/lava-33954/bin/lava-target-storage
Creating /var/lib/lava/dispatcher/tmp/33954/lava-overlay-0lidze8k/lava-33954/bin/lava-test-case
Creating /var/lib/lava/dispatcher/tmp/33954/lava-overlay-0lidze8k/lava-33954/bin/lava-test-event
Creating /var/lib/lava/dispatcher/tmp/33954/lava-overlay-0lidze8k/lava-33954/bin/lava-test-feedback
Creating /var/lib/lava/dispatcher/tmp/33954/lava-overlay-0lidze8k/lava-33954/bin/lava-test-raise
Creating /var/lib/lava/dispatcher/tmp/33954/lava-overlay-0lidze8k/lava-33954/bin/lava-test-reference
Creating /var/lib/lava/dispatcher/tmp/33954/lava-overlay-0lidze8k/lava-33954/bin/lava-test-runner
Creating /var/lib/lava/dispatcher/tmp/33954/lava-overlay-0lidze8k/lava-33954/bin/lava-test-set
Creating /var/lib/lava/dispatcher/tmp/33954/lava-overlay-0lidze8k/lava-33954/bin/lava-test-shell
Updating /var/lib/lava/dispatcher/tmp/33954/lava-overlay-0lidze8k/lava-33954/bin/lava-install-packages (oe)
Updating /var/lib/lava/dispatcher/tmp/33954/lava-overlay-0lidze8k/lava-33954/bin/lava-installed-packages (oe)
Creating /var/lib/lava/dispatcher/tmp/33954/lava-overlay-0lidze8k/lava-33954/environment
LAVA metadata
- LAVA_JOB_ID=33954
- LAVA_DISPATCHER_IP=192.168.66.1
start: 1.5.1 ssh-authorize (timeout 01:26:24) [common]
end: 1.5.1 ssh-authorize (duration 00:00:00) [common]
start: 1.5.2 lava-vland-overlay (timeout 01:26:24) [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:26:24) [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:26:24) [common]
Loading test definitions
start: 1.5.4.1 git-repo-action (timeout 01:26:24) [common]
Using /lava-33954 at stage 0
Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions
Running '/usr/bin/git clone -b lamprey --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/33954/lava-overlay-0lidze8k/lava-33954/0/tests/0_network-veth'
Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/33954/lava-overlay-0lidze8k/lava-33954/0/tests/0_network-veth/test-suites/short-smoke/network-veth.yaml
uuid=33954_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: 3966354
definition: lava
duration: 0.71
extra: ...
level: 1.5.4.1
namespace: common
result: pass
start: 1.5.4.2 test-overlay (timeout 01:26:24) [common]
end: 1.5.4.2 test-overlay (duration 00:00:00) [common]
case: test-overlay
case_id: 3966355
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:26:24) [common]
end: 1.5.4.3 test-install-overlay (duration 00:00:00) [common]
case: test-install-overlay
case_id: 3966356
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:26:24) [common]
runner path: /var/lib/lava/dispatcher/tmp/33954/lava-overlay-0lidze8k/lava-33954/0/tests/0_network-veth test_uuid 33954_1.5.4.1
end: 1.5.4.4 test-runscript-overlay (duration 00:00:00) [common]
case: test-runscript-overlay
case_id: 3966357
definition: lava
duration: 0.01
extra: ...
level: 1.5.4.4
namespace: common
result: pass
start: 1.5.4.5 inline-repo-action (timeout 01:26:24) [common]
Using /lava-33954 at stage 1
uuid=33954_1.5.4.5 testdef=None
end: 1.5.4.5 inline-repo-action (duration 00:00:00) [common]
start: 1.5.4.6 test-overlay (timeout 01:26:24) [common]
end: 1.5.4.6 test-overlay (duration 00:00:00) [common]
case: test-overlay
case_id: 3966355
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:26:24) [common]
end: 1.5.4.7 test-install-overlay (duration 00:00:00) [common]
case: test-install-overlay
case_id: 3966356
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:26:24) [common]
runner path: /var/lib/lava/dispatcher/tmp/33954/lava-overlay-0lidze8k/lava-33954/1/tests/1_HACK-SPEC-3655 test_uuid 33954_1.5.4.5
end: 1.5.4.8 test-runscript-overlay (duration 00:00:00) [common]
case: test-runscript-overlay
case_id: 3966357
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:26:24) [common]
Using /lava-33954 at stage 2
Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions
Running '/usr/bin/git clone -b lamprey --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/33954/lava-overlay-0lidze8k/lava-33954/2/tests/2_release-busybox'
Removing '.git' directory in /var/lib/lava/dispatcher/tmp/33954/lava-overlay-0lidze8k/lava-33954/2/tests/2_release-busybox
Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/33954/lava-overlay-0lidze8k/lava-33954/2/tests/2_release-busybox/test-suites/short-smoke/busybox.yaml
uuid=33954_1.5.4.9 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.9 git-repo-action (duration 00:00:01) [common]
case: git-repo-action
case_id: 3966354
definition: lava
duration: 0.69
extra: ...
level: 1.5.4.9
namespace: common
result: pass
start: 1.5.4.10 test-overlay (timeout 01:26:23) [common]
end: 1.5.4.10 test-overlay (duration 00:00:00) [common]
case: test-overlay
case_id: 3966355
definition: lava
duration: 0.01
extra: ...
level: 1.5.4.10
namespace: common
result: pass
start: 1.5.4.11 test-install-overlay (timeout 01:26:23) [common]
end: 1.5.4.11 test-install-overlay (duration 00:00:00) [common]
case: test-install-overlay
case_id: 3966356
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:26:23) [common]
runner path: /var/lib/lava/dispatcher/tmp/33954/lava-overlay-0lidze8k/lava-33954/2/tests/2_release-busybox test_uuid 33954_1.5.4.9
end: 1.5.4.12 test-runscript-overlay (duration 00:00:00) [common]
case: test-runscript-overlay
case_id: 3966357
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:26:23) [common]
Using /lava-33954 at stage 2
Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions
Running '/usr/bin/git clone -b lamprey --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/33954/lava-overlay-0lidze8k/lava-33954/2/tests/3_release-smoke-tests-basic'
Removing '.git' directory in /var/lib/lava/dispatcher/tmp/33954/lava-overlay-0lidze8k/lava-33954/2/tests/3_release-smoke-tests-basic
Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/33954/lava-overlay-0lidze8k/lava-33954/2/tests/3_release-smoke-tests-basic/test-suites/short-smoke/smoke-tests-basic.yaml
uuid=33954_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: 3966354
definition: lava
duration: 0.69
extra: ...
level: 1.5.4.13
namespace: common
result: pass
start: 1.5.4.14 test-overlay (timeout 01:26:22) [common]
end: 1.5.4.14 test-overlay (duration 00:00:00) [common]
case: test-overlay
case_id: 3966355
definition: lava
duration: 0.01
extra: ...
level: 1.5.4.14
namespace: common
result: pass
start: 1.5.4.15 test-install-overlay (timeout 01:26:22) [common]
end: 1.5.4.15 test-install-overlay (duration 00:00:00) [common]
case: test-install-overlay
case_id: 3966356
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:26:22) [common]
runner path: /var/lib/lava/dispatcher/tmp/33954/lava-overlay-0lidze8k/lava-33954/2/tests/3_release-smoke-tests-basic test_uuid 33954_1.5.4.13
end: 1.5.4.16 test-runscript-overlay (duration 00:00:00) [common]
case: test-runscript-overlay
case_id: 3966357
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:26:22) [common]
Using /lava-33954 at stage 2
Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions
Running '/usr/bin/git clone -b lamprey --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/33954/lava-overlay-0lidze8k/lava-33954/2/tests/4_release-service-check'
Removing '.git' directory in /var/lib/lava/dispatcher/tmp/33954/lava-overlay-0lidze8k/lava-33954/2/tests/4_release-service-check
Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/33954/lava-overlay-0lidze8k/lava-33954/2/tests/4_release-service-check/test-suites/short-smoke/service-check.yaml
uuid=33954_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: 3966354
definition: lava
duration: 0.69
extra: ...
level: 1.5.4.17
namespace: common
result: pass
start: 1.5.4.18 test-overlay (timeout 01:26:21) [common]
end: 1.5.4.18 test-overlay (duration 00:00:00) [common]
case: test-overlay
case_id: 3966355
definition: lava
duration: 0.01
extra: ...
level: 1.5.4.18
namespace: common
result: pass
start: 1.5.4.19 test-install-overlay (timeout 01:26:21) [common]
end: 1.5.4.19 test-install-overlay (duration 00:00:00) [common]
case: test-install-overlay
case_id: 3966356
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:26:21) [common]
runner path: /var/lib/lava/dispatcher/tmp/33954/lava-overlay-0lidze8k/lava-33954/2/tests/4_release-service-check test_uuid 33954_1.5.4.17
BUILD_TAGS=''
DEVICE_TAGS=''
end: 1.5.4.20 test-runscript-overlay (duration 00:00:00) [common]
case: test-runscript-overlay
case_id: 3966357
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:26:21) [common]
Using /lava-33954 at stage 3
Fetching tests from https://git.linaro.org/qa/test-definitions.git
Running '/usr/bin/git clone --depth=1 https://git.linaro.org/qa/test-definitions.git /var/lib/lava/dispatcher/tmp/33954/lava-overlay-0lidze8k/lava-33954/3/tests/5_release-linaro-ptest'
Removing '.git' directory in /var/lib/lava/dispatcher/tmp/33954/lava-overlay-0lidze8k/lava-33954/3/tests/5_release-linaro-ptest
Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/33954/lava-overlay-0lidze8k/lava-33954/3/tests/5_release-linaro-ptest/automated/linux/ptest/ptest.yaml
uuid=33954_1.5.4.21 testdef=None
end: 1.5.4.21 git-repo-action (duration 00:00:04) [common]
case: git-repo-action
case_id: 3966354
definition: lava
duration: 4.42
extra: ...
level: 1.5.4.21
namespace: common
result: pass
start: 1.5.4.22 test-overlay (timeout 01:26:17) [common]
end: 1.5.4.22 test-overlay (duration 00:00:00) [common]
case: test-overlay
case_id: 3966355
definition: lava
duration: 0.01
extra: ...
level: 1.5.4.22
namespace: common
result: pass
start: 1.5.4.23 test-install-overlay (timeout 01:26:17) [common]
end: 1.5.4.23 test-install-overlay (duration 00:00:00) [common]
case: test-install-overlay
case_id: 3966356
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:26:17) [common]
runner path: /var/lib/lava/dispatcher/tmp/33954/lava-overlay-0lidze8k/lava-33954/3/tests/5_release-linaro-ptest test_uuid 33954_1.5.4.21
TESTS='xmlsec1 libxml2 lua'
end: 1.5.4.24 test-runscript-overlay (duration 00:00:00) [common]
case: test-runscript-overlay
case_id: 3966357
definition: lava
duration: 0.01
extra: ...
level: 1.5.4.24
namespace: common
result: pass
start: 1.5.4.25 git-repo-action (timeout 01:26:17) [common]
Using /lava-33954 at stage 4
Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions
Running '/usr/bin/git clone -b lamprey --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/33954/lava-overlay-0lidze8k/lava-33954/4/tests/6_pyagl-tests'
Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/33954/lava-overlay-0lidze8k/lava-33954/4/tests/6_pyagl-tests/test-suites/short-smoke/pyagl-nohw-oneshot.yaml
uuid=33954_1.5.4.25 testdef=None
end: 1.5.4.25 git-repo-action (duration 00:00:01) [common]
case: git-repo-action
case_id: 3966354
definition: lava
duration: 0.68
extra: ...
level: 1.5.4.25
namespace: common
result: pass
start: 1.5.4.26 test-overlay (timeout 01:26:16) [common]
end: 1.5.4.26 test-overlay (duration 00:00:00) [common]
case: test-overlay
case_id: 3966355
definition: lava
duration: 0.01
extra: ...
level: 1.5.4.26
namespace: common
result: pass
start: 1.5.4.27 test-install-overlay (timeout 01:26:16) [common]
end: 1.5.4.27 test-install-overlay (duration 00:00:00) [common]
case: test-install-overlay
case_id: 3966356
definition: lava
duration: 0.01
extra: ...
level: 1.5.4.27
namespace: common
result: pass
start: 1.5.4.28 test-runscript-overlay (timeout 01:26:16) [common]
runner path: /var/lib/lava/dispatcher/tmp/33954/lava-overlay-0lidze8k/lava-33954/4/tests/6_pyagl-tests test_uuid 33954_1.5.4.25
end: 1.5.4.28 test-runscript-overlay (duration 00:00:00) [common]
case: test-runscript-overlay
case_id: 3966357
definition: lava
duration: 0.01
extra: ...
level: 1.5.4.28
namespace: common
result: pass
start: 1.5.4.29 git-repo-action (timeout 01:26:16) [common]
Using /lava-33954 at stage 5
Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions
Running '/usr/bin/git clone -b lamprey --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/33954/lava-overlay-0lidze8k/lava-33954/5/tests/7_screenshooter'
Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/33954/lava-overlay-0lidze8k/lava-33954/5/tests/7_screenshooter/test-suites/short-smoke/screenshooter.yaml
uuid=33954_1.5.4.29 testdef=None
end: 1.5.4.29 git-repo-action (duration 00:00:01) [common]
case: git-repo-action
case_id: 3966354
definition: lava
duration: 0.67
extra: ...
level: 1.5.4.29
namespace: common
result: pass
start: 1.5.4.30 test-overlay (timeout 01:26:16) [common]
end: 1.5.4.30 test-overlay (duration 00:00:00) [common]
case: test-overlay
case_id: 3966355
definition: lava
duration: 0.00
extra: ...
level: 1.5.4.30
namespace: common
result: pass
start: 1.5.4.31 test-install-overlay (timeout 01:26:16) [common]
end: 1.5.4.31 test-install-overlay (duration 00:00:00) [common]
case: test-install-overlay
case_id: 3966356
definition: lava
duration: 0.01
extra: ...
level: 1.5.4.31
namespace: common
result: pass
start: 1.5.4.32 test-runscript-overlay (timeout 01:26:16) [common]
runner path: /var/lib/lava/dispatcher/tmp/33954/lava-overlay-0lidze8k/lava-33954/5/tests/7_screenshooter test_uuid 33954_1.5.4.29
end: 1.5.4.32 test-runscript-overlay (duration 00:00:00) [common]
case: test-runscript-overlay
case_id: 3966357
definition: lava
duration: 0.01
extra: ...
level: 1.5.4.32
namespace: common
result: pass
start: 1.5.4.33 git-repo-action (timeout 01:26:16) [common]
Using /lava-33954 at stage 6
Fetching tests from https://git.automotivelinux.org/src/qa-testdefinitions
Running '/usr/bin/git clone -b lamprey --depth=1 https://git.automotivelinux.org/src/qa-testdefinitions /var/lib/lava/dispatcher/tmp/33954/lava-overlay-0lidze8k/lava-33954/6/tests/8_ci-dumpjournal'
Removing '.git' directory in /var/lib/lava/dispatcher/tmp/33954/lava-overlay-0lidze8k/lava-33954/6/tests/8_ci-dumpjournal
Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/33954/lava-overlay-0lidze8k/lava-33954/6/tests/8_ci-dumpjournal/test-suites/short-smoke/dumpjournal.yaml
uuid=33954_1.5.4.33 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.33 git-repo-action (duration 00:00:01) [common]
case: git-repo-action
case_id: 3966354
definition: lava
duration: 0.67
extra: ...
level: 1.5.4.33
namespace: common
result: pass
start: 1.5.4.34 test-overlay (timeout 01:26:15) [common]
end: 1.5.4.34 test-overlay (duration 00:00:00) [common]
case: test-overlay
case_id: 3966355
definition: lava
duration: 0.01
extra: ...
level: 1.5.4.34
namespace: common
result: pass
start: 1.5.4.35 test-install-overlay (timeout 01:26:15) [common]
end: 1.5.4.35 test-install-overlay (duration 00:00:00) [common]
case: test-install-overlay
case_id: 3966356
definition: lava
duration: 0.01
extra: ...
level: 1.5.4.35
namespace: common
result: pass
start: 1.5.4.36 test-runscript-overlay (timeout 01:26:15) [common]
runner path: /var/lib/lava/dispatcher/tmp/33954/lava-overlay-0lidze8k/lava-33954/6/tests/8_ci-dumpjournal test_uuid 33954_1.5.4.33
end: 1.5.4.36 test-runscript-overlay (duration 00:00:00) [common]
case: test-runscript-overlay
case_id: 3966357
definition: lava
duration: 0.01
extra: ...
level: 1.5.4.36
namespace: common
result: pass
Creating lava-test-runner.conf files
Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/33954/lava-overlay-0lidze8k/lava-33954/0 for stage 0
- 0_network-veth
Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/33954/lava-overlay-0lidze8k/lava-33954/1 for stage 1
- 1_HACK-SPEC-3655
Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/33954/lava-overlay-0lidze8k/lava-33954/2 for stage 2
- 2_release-busybox
- 3_release-smoke-tests-basic
- 4_release-service-check
Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/33954/lava-overlay-0lidze8k/lava-33954/3 for stage 3
- 5_release-linaro-ptest
Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/33954/lava-overlay-0lidze8k/lava-33954/4 for stage 4
- 6_pyagl-tests
Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/33954/lava-overlay-0lidze8k/lava-33954/5 for stage 5
- 7_screenshooter
Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/33954/lava-overlay-0lidze8k/lava-33954/6 for stage 6
- 8_ci-dumpjournal
end: 1.5.4 test-definition (duration 00:00:10) [common]
start: 1.5.5 compress-overlay (timeout 01:26:15) [common]
end: 1.5.5 compress-overlay (duration 00:00:27) [common]
start: 1.5.6 persistent-nfs-overlay (timeout 01:25:48) [common]
end: 1.5.6 persistent-nfs-overlay (duration 00:00:00) [common]
end: 1.5 lava-overlay (duration 00:00:37) [common]
start: 1.6 prepare-kernel (timeout 01:25:48) [common]
start: 1.6.1 uboot-prepare-kernel (timeout 01:25:48) [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:25:48) [common]
xnbd-server-deploy: starting nbd-server
NBD-IP: 192.168.66.1, NBD-PORT: 61962, NBD-ROOT: 33954/nbd-deploy-7cgpx1nc/nbdroot/agl-demo-platform-crosssdk-h3ulcb.ext4
nbd-server 61962 /var/lib/lava/dispatcher/tmp/33954/nbd-deploy-7cgpx1nc/nbdroot/agl-demo-platform-crosssdk-h3ulcb.ext4
output:
output: ** (process:18278): WARNING **: 16:12:48.183: 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:04:12) [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 0x68080000 0x4a000000:${initrd_size} 0x68000000
- {DTB_ADDR}: 0x68000000
- {DTB}: 33954/nbd-deploy-7cgpx1nc/dtb/r8a77951-ulcb.dtb
- {INITRD}: 33954/nbd-deploy-7cgpx1nc/initrd/initramfs-netboot-image-h3ulcb.ext4.gz
- {KERNEL_ADDR}: 0x68080000
- {KERNEL}: 33954/nbd-deploy-7cgpx1nc/kernel/Image
- {LAVA_MAC}: None
- {NBDSERVERIP}: 192.168.66.1
- {NBDSERVERPORT}: 61962
- {PRESEED_CONFIG}: None
- {PRESEED_LOCAL}: None
- {RAMDISK_ADDR}: 0x4a000000
- {RAMDISK}: 33954/nbd-deploy-7cgpx1nc/initrd/initramfs-netboot-image-h3ulcb.ext4.gz
- {ROOT_PART}: None
- {ROOT}: None
- {SERVER_IP}: 192.168.66.1
- {TEE_ADDR}: 0x83000000
- {TEE}: None
Parsed boot commands:
- setenv autoload no
- setenv initrd_high 0xffffffff
- setenv fdt_high 0xffffffff
- dhcp
- setenv serverip 192.168.66.1
- tftp 0x68080000 33954/nbd-deploy-7cgpx1nc/kernel/Image
- tftp 0x4a000000 33954/nbd-deploy-7cgpx1nc/initrd/initramfs-netboot-image-h3ulcb.ext4.gz
- setenv initrd_size ${filesize}
- tftp 0x68000000 33954/nbd-deploy-7cgpx1nc/dtb/r8a77951-ulcb.dtb
- setenv bootargs 'console=ttySC0,115200n8 rw nbd.server=192.168.66.1 nbd.port=61962 root=/dev/ram0 ramdisk_size=16384 rootdelay=7 ip=dhcp verbose earlyprintk systemd.log_color=false ${extraargs} rw'
- booti 0x68080000 0x4a000000:${initrd_size} 0x68000000
end: 2.2 bootloader-overlay (duration 00:00:00) [common]
case: bootloader-overlay
case_id: 3966373
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 63001'
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: 'nice' '/usr/local/bin/acme-cli' '-s' '192.168.66.2' 'reset' '2'
>> Success
>> Success
Returned 0 in 4 seconds
end: 2.4.1.1 pdu-reboot (duration 00:00:05) [common]
case: pdu-reboot
case_id: 3966390
definition: lava
duration: 4.62
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 '^]'.
NOTICE: BL2: R-Car Gen3 Init� NOTICE: BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.3.0.3
NOTICE: BL2: PRR is R-Car H3 Ver.3.0
NOTICE: NOTICE: BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.3.0.3
NOTICE: BL2: PRR is R-Car H3 Ver.3.0
NOTICE: BL2: Board is Starter Kit Pre� NOTICE: BL2: R-Car Gen3 Initial Progra� NOTICE: BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.3.0.3
NOTICE: BL2: PRR is R-Car H3 Ver.3.0
NOTICE NOTICE: BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.3.0.3
NOTICE: BL2: PRR is R-Car H3 Ver.3.0
NOTICE: BL2: Board is S� NOTICE: BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.3.0.3
NOTICE: BL2: PRR is R-Car H3 Ver.3.0
NOTICE: BL2: Board is Starter Kit Premier� NOTICE: BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.3.0.3
NOTICE: BL2: PRR is R-Car H3 Ver.3.0
NOTICE: BL2: Board is Starter Kit Premier Rev.2.0
NOTICE: BL2: NOTICE: BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.3.0.3
NOTICE: BL2: PRR is R-Car H3 Ver.3.0
NOTICE: BL2: Board is Starter Kit Premier Rev.2.0
NOTICE: BL2: Boot device is HyperFl� NOTICE: BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.3.0.3
NOTICE: BL2: PRR is R-Car H3 Ver.3.0
NOTICE: BL2: Board is Starter Kit Premier Rev.2.0
NOTICE: BL2: Boot device is HyperFlash(80MHz)
NOTICE: BL2: LCM � NOTICE: BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.3.0.3
NOTICE: BL2: PRR is R-Car H3 Ver.3.0
NOTICE: BL2: Board is Starter Kit Premier Rev.2.0
NOTICE: BL2: Boot device is HyperFlash(80MHz)
NOTICE: BL2: LCM state is CM
NOTICE: AVS setting succeeded. DVFS_SetV� NOTICE: BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.3.0.3
NOTICE: BL2: PRR is R-Car H3 Ver.3.0
NOTICE: BL2: Board is Starter Kit Premier Rev.2.0
NOTICE: BL2: Boot device is HyperFlash(80MHz)
NOTICE: BL2: LCM state is CM
NOTICE: AVS setting succeeded. DVFS_SetVID=0x53
NOTICE: BL2: DDR3200(rev.0.42)
NOTICE: BL2: [COLD_BOOT]
NOTICE: BL2: DRAM Split is 4ch(DDR f)
NOTICE: BL2: QoS is default setting(rev.0.11)
NOTICE: BL2: DRAM refresh interval 1.95 usec
NOTICE: BL2: Periodic Write DQ Training
NOTICE: BL2: CH0: 400000000 - 43fffffff, 1 GiB
NOTICE: BL2: CH1: 500000000 - 53fffffff, 1 GiB
NOTICE: BL2: CH2: 600000000 - 63fffffff, 1 GiB
NOTICE: BL2: CH3: 700000000 - 73fffffff, 1 GiB
NOTICE: BL2: FDT at 0xe6326f70
NOTICE: BL2: v2.5(release):268df1d86
NOTICE: BL2: Built : 17:33:20, Jan 30 2023
NOTICE: BL2: Normal boot
NOTICE: BL2: dst=0xe6326800 src=0x8180000 len=512(0x200)
NOTICE: BL2: dst=0x43f00000 src=0x8180400 len=6144(0x1800)
NOTICE: rcar_file_len: len: 0x0003e000
NOTICE: BL2: dst=0x44000000 src=0x81c0000 len=253952(0x3e000)
NOTICE: rcar_file_len: len: 0x00200000
NOTICE: BL2: dst=0x44100000 src=0x8200000 len=2097152(0x200000)
NOTICE: rcar_file_len: len: 0x00100000
NOTICE: BL2: dst=0x50000000 src=0x8640000 len=1048576(0x100000)
NOTICE: BL2: Booting BL31
U-Boot 2020.10 (Jan 30 2023 - 17:33:20 +0000)
CPU: Renesas Electronics R8A7795 rev 3.0
Model: Renesas H3ULCB board based on r8a77950
DRAM: 3.9 GiB
RAM Configuration:
Bank #0: 0x048000000 - 0x07fffffff, 896 MiB
Bank #1: 0x500000000 - 0x53fffffff, 1 GiB
Bank #2: 0x600000000 - 0x63fffffff, 1 GiB
Bank #3: 0x700000000 - 0x73fffffff, 1 GiB
MMC: sd@ee100000: 0, sd@ee140000: 1
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:02) [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:50)
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.19 (1047 ms)
setenv serverip 192.168.66.1
=> setenv serverip 192.168.66.1
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:46)
setenv serverip 192.168.66.1
tftp 0x68080000 33954/nbd-deploy-7cgpx1nc/kernel/Image
=> tftp 0x68080000 33954/nbd-deploy-7cgpx1nc/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:46)
tftp 0x68080000 33954/nbd-deploy-7cgpx1nc/kernel/Image
Using ethernet@e6800000 device
TFTP from server 192.168.66.1; our IP address is 192.168.66.19
Filename '33954/nbd-deploy-7cgpx1nc/kernel/Image'.
Load address: 0x68080000
Loading: *#################################################################
#################################################################
#################################################################
#################################################################
#################################################################
#################################################################
#################################################################
#################################################################
#################################################################
#################################################################
#################################################################
#################################################################
#################################################################
#################################################################
#################################################################
#################################################################
#################################################################
#################################################################
#################################################################
#################################################################
#################################################################
#################################################################
#################################################################
#################################################################
#################################################################
#################################################################
#################################################################
#################################################################
#################################################################
#################################################################
#################################################################
#################################################################
#################################################################
#################################################################
#################################################################
#################################################################
#################################################################
#################################################################
#################################################################
###############################################################
1.6 MiB/s
done
Bytes transferred = 38128128 (245ca00 hex)
tftp 0x4a000000 33954/nbd-deploy-7cgpx1nc/initrd/initramfs-netboot-image-h3ulcb.ext4.gz
=> tftp 0x4a000000 33954/nbd-deploy-7cgpx1nc/initrd/initramfs-netboot-image-h3ulcb.ext4.gz
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:23)
tftp 0x4a000000 33954/nbd-deploy-7cgpx1nc/initrd/initramfs-netboot-image-h3ulcb.ext4.gz
Using ethernet@e6800000 device
TFTP from server 192.168.66.1; our IP address is 192.168.66.19
Filename '33954/nbd-deploy-7cgpx1nc/initrd/initramfs-netboot-image-h3ulcb.ext4.gz'.
Load address: 0x4a000000
Loading: *#################################################################
#################################################################
#########
1.6 MiB/s
done
Bytes transferred = 2029960 (1ef988 hex)
setenv initrd_size ${filesize}
=> setenv initrd_size ${filesize}
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:22)
setenv initrd_size ${filesize}
tftp 0x68000000 33954/nbd-deploy-7cgpx1nc/dtb/r8a77951-ulcb.dtb
=> tftp 0x68000000 33954/nbd-deploy-7cgpx1nc/dtb/r8a77951-ulcb.dtb
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:21)
tftp 0x68000000 33954/nbd-deploy-7cgpx1nc/dtb/r8a77951-ulcb.dtb
Using ethernet@e6800000 device
TFTP from server 192.168.66.1; our IP address is 192.168.66.19
Filename '33954/nbd-deploy-7cgpx1nc/dtb/r8a77951-ulcb.dtb'.
Load address: 0x68000000
Loading: *######
1.3 MiB/s
done
Bytes transferred = 82960 (14410 hex)
setenv bootargs 'console=ttySC0,115200n8 rw nbd.server=192.168.66.1 nbd.port=61962 root=/dev/ram0 ramdisk_size=16384 rootdelay=7 ip=dhcp verbose earlyprintk systemd.log_color=false ${extraargs} rw'
=> setenv bootargs 'console=ttySC0,115200n8 rw nbd.server=192.168.66.1 nbd.port=61962 root=/dev/ram0 ramdisk_size=16384 rootdelay=7 ip=dhcp verbose earlyprintk systemd.log_color=false ${extraargs} rw'
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:21)
setenv bootargs 'console=ttySC0,115200n8 rw nbd.server=192.168.66.1 nbd.port=61962 root=/dev/ram0 ramdisk_size=16384 rootdelay=7 ip=dhcp verbose earlyprintk systemd.log_color=false ${extraargs} rw'
booti 0x68080000 0x4a000000:${initrd_size} 0x68000000
=> booti 0x68080000 0x4a000000:${initrd_size} 0x68000000
Setting prompt string to ['Starting kernel', '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']
bootloader-commands: Wait for prompt ['Starting kernel', '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:21)
booti 0x68080000 0x4a000000:${initrd_size} 0x68000000
Moving Image from 0x68080000 to 0x68200000, end=6a6f0000
## Flattened Device Tree blob at 68000000
Booting using the fdt blob at 0x68000000
Loading Ramdisk to 7fe10000, end 7ffff988 ... OK
Loading Device Tree to 0000000053fe8000, end 0000000053fff40f ... \"Synchronous Abort\" handler, esr 0x96000042
elr: 000000002016db9c lr : 000000002016dbfc (reloc)
elr: 0000000050069b9c lr : 0000000050069bfc
x0 : 0000000053fe8000 x1 : 0000000068000000
x2 : 0000000000014410 x3 : 0000000000000000
x4 : 10440100edfe0dd0 x5 : 0000000053fe8000
x6 : 0000000053fe8000 x7 : 000000007beeed80
x8 : 000000007beeed48 x9 : 0000000000000008
x10: 0000000053fff40f x11: 0000000053fff40f
x12: 0000000048000000 x13: fffffffffffff000
x14: 0000000053fe8000 x15: 0000000000000021
x16: 0000000050038720 x17: 0000000000000000
x18: 000000007befbdb0 x19: 0000000053fe8000
x20: 0000000068000000 x21: 0000000000017410
x22: 0000000010440100 x23: 0000000000000010
x24: 00000000500e3980 x25: 000000007beeedf8
x26: 0000000000000000 x27: 0000000050002e3c
x28: 000000007bf04550 x29: 000000007beeed10
Code: eb03005f 540001e1 d65f03c0 f8636824 (f8236804)
Resetting CPU ...
matched a bootloader error message: 'Resetting CPU' (1)
end: 2.4.3 bootloader-commands (duration 00:00:30) [common]
case: bootloader-commands
case_id: 3966393
definition: lava
duration: 29.79
extra: ...
level: 2.4.3
namespace: common
result: fail
matched a bootloader error message: 'Resetting CPU' (1)
end: 2.4 uboot-commands (duration 00:00:36) [common]
case: uboot-commands
case_id: 3966394
definition: lava
duration: 36.00
extra: ...
level: 2.4
namespace: common
result: fail
uboot-action failed: 1 of 1 attempts. 'matched a bootloader error message: 'Resetting CPU' (1)'
matched a bootloader error message: 'Resetting CPU' (1)
end: 2 uboot-action (duration 00:00:39) [common]
case: uboot-action
case_id: 3966395
definition: lava
duration: 39.16
extra: ...
level: 2
namespace: common
result: fail
Cleaning after the job
Cleaning up download directory: /var/lib/lava/dispatcher/tmp/33954/nbd-deploy-7cgpx1nc/initrd
Cleaning up download directory: /var/lib/lava/dispatcher/tmp/33954/nbd-deploy-7cgpx1nc/kernel
Cleaning up download directory: /var/lib/lava/dispatcher/tmp/33954/nbd-deploy-7cgpx1nc/dtb
Cleaning up download directory: /var/lib/lava/dispatcher/tmp/33954/nbd-deploy-7cgpx1nc/nbdroot
start: 10.1 power-off (timeout 00:00:10) [common]
Calling: 'nice' '/usr/local/bin/acme-cli' '-s' '192.168.66.2' 'switch_off' '2'
>> Success
Returned 0 in 3 seconds
end: 10.1 power-off (duration 00:00:04) [common]
case: power-off
case_id: 3966396
definition: lava
duration: 3.56
extra: ...
level: 10.1
namespace: common
result: pass
start: 10.2 read-feedback (timeout 00:29:56) [common]
Listened to connection for namespace 'common' for up to 1s
resetting ...
NOTICE: BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.3.0.3
NOTICE: BL2: PRR is R-Car H3 Ver.3.0
NOTICE: BL2: Board is Starter Kit Premier Rev.2.0
NOTICE: BL2: Boot device is HyperFlash(80MHz)
NOTICE: BL2: LCM state is CM
NOTICE: AVS setting succeeded. DVFS_SetVID=0x53
NOTICE: BL2: DDR3200(rev.0.42)
NOTICE: BL2: [COLD_BOOT]
NOTICE: BL2: DRAM Split is 4ch(DDR f)
NOTICE: BL2: QoS is default setting(rev.0.11)
NOTICE: BL2: DRAM refresh interval 1.95 usec
NOTICE: BL2: Periodic Write DQ Training
NOTICE: BL2: CH0: 400000000 - 43fffffff, 1 GiB
NOTICE: BL2: CH1: 500000000 - 53fffffff, 1 GiB
NOTICE: BL2: CH2: 600000000 - 63fffffff, 1 GiB
NOTICE: BL2: CH3: 700000000 - 73fffffff, 1 GiB
NOTICE: BL2: FDT at 0xe6326f70
NOTICE: BL2: v2.5(release):268df1d86
NOTICE: BL2: Built : 17:33:20, Jan 30 2023
NOTICE: BL2: Normal boot
NOTICE: BL2: dst=0xe6326800 src=0x8180000 len=512(0x200)
NOTICE: BL2: dst=0x43f00000 src=0x8180400 len=6144(0x1800)
NOTICE: rcar_file_len: len: 0x0003e000
NOTICE: BL2: dst=0x44000000 src=0x81c0000 len=253952(0x3e000)
NOTICE: rcar_file_len: len: 0x00200000
NOTICE: BL2: dst=0x44100000 src=0x8200000 len=2097152(0x200000)
NOTICE: rcar_file_len: len: 0x00100000
NOTICE: BL2: dst=0x50000000 src=0x8640000 len=1048576(0x100000)
NOTICE: BL2: Booting BL31
U-Boot 2020.10 (Jan 30 2023 - 17:33:20 +0000)
CPU: Renesas Electronics R8A7795 rev 3.0
Model: Renesas H3ULCB board based on r8a77950
DRAM: 3.9 GiB
RAM Configuration:
Bank #0: 0x048000000 - 0x07fffffff, 896 MiB
Bank #1: 0x500000000 - 0x53fffffff, 1 GiB
Bank #2: 0x600000000 - 0x63fffffff, 1 GiB
Bank #3: 0x700000000 - 0x73fffffff, 1 GiB
MMC: sd@ee100000: 0, sd@ee140000: 1
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 2 1 0
*** ERROR: `serverip' not set
Listened to connection for namespace 'common' for up to 1s
Finalising connection for namespace 'common'
Disconnecting from shell: Finalise
*** ERRO�
end: 10.2 read-feedback (duration 00:00:01) [common]
lava-xnbd cleanup
clean NBD port 61962
Override tmp directory removed at /var/lib/lava/dispatcher/tmp/33954
Root tmp directory removed at /var/lib/lava/dispatcher/tmp/33954
InfrastructureError: The Infrastructure is not working correctly. Please report this error to LAVA admins.
case: job
case_id: 3966397
definition: lava
error_msg: matched a bootloader error message: 'Resetting CPU' (1)
error_type: Infrastructure
result: fail
Please read the triage guidelines for help on debugging failures in the test job, test definitions or in individual test cases.
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.