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-12-19 14:16:04.493005+00:00 (UTC)
Validating that http://download.automotivelinux.org/AGL/upload/ci/29371/2/h3ulcb-nogfx/initramfs-netboot-image-h3ulcb.ext4.gz exists
Validating that http://download.automotivelinux.org/AGL/upload/ci/29371/2/h3ulcb-nogfx/Image exists
Validating that http://download.automotivelinux.org/AGL/upload/ci/29371/2/h3ulcb-nogfx/r8a77951-ulcb.dtb exists
Validating that http://download.automotivelinux.org/AGL/upload/ci/29371/2/h3ulcb-nogfx/agl-demo-platform-h3ulcb.ext4.xz exists
validate duration: 0.87
case: validate
case_id: 3984336
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 61992
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/29371/2/h3ulcb-nogfx/initramfs-netboot-image-h3ulcb.ext4.gz
saving as /var/lib/lava/dispatcher/tmp/34448/nbd-deploy-5q_nq391/initrd/initramfs-netboot-image-h3ulcb.ext4.gz
total size: 2061367 (1MB)
No compression specified
progress 1% (0MB)
progress 6% (0MB)
progress 11% (0MB)
progress 17% (0MB)
progress 22% (0MB)
progress 27% (0MB)
progress 33% (0MB)
progress 38% (0MB)
progress 44% (0MB)
progress 49% (0MB)
progress 54% (1MB)
progress 60% (1MB)
progress 65% (1MB)
progress 71% (1MB)
progress 76% (1MB)
progress 81% (1MB)
progress 87% (1MB)
progress 92% (1MB)
progress 98% (1MB)
1MB downloaded in 1.00s (1.97MB/s)
end: 1.1.1 http-download (duration 00:00:01) [common]
case: http-download
case_id: 3984422
definition: lava
duration: 1.00
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/29371/2/h3ulcb-nogfx/Image
saving as /var/lib/lava/dispatcher/tmp/34448/nbd-deploy-5q_nq391/kernel/Image
total size: 39207424 (37MB)
No compression specified
progress 0% (0MB)
progress 5% (1MB)
progress 10% (3MB)
progress 15% (5MB)
progress 20% (7MB)
progress 25% (9MB)
progress 30% (11MB)
progress 35% (13MB)
progress 40% (14MB)
progress 45% (16MB)
progress 50% (18MB)
progress 55% (20MB)
progress 60% (22MB)
progress 65% (24MB)
progress 70% (26MB)
progress 75% (28MB)
progress 80% (29MB)
progress 85% (31MB)
progress 90% (33MB)
progress 95% (35MB)
progress 100% (37MB)
37MB downloaded in 4.26s (8.77MB/s)
end: 1.2.1 http-download (duration 00:00:04) [common]
case: http-download
case_id: 3984422
definition: lava
duration: 4.27
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 http://download.automotivelinux.org/AGL/upload/ci/29371/2/h3ulcb-nogfx/r8a77951-ulcb.dtb
saving as /var/lib/lava/dispatcher/tmp/34448/nbd-deploy-5q_nq391/dtb/r8a77951-ulcb.dtb
total size: 82960 (0MB)
No compression specified
progress 39% (0MB)
progress 78% (0MB)
progress 100% (0MB)
0MB downloaded in 0.42s (0.19MB/s)
end: 1.3.1 http-download (duration 00:00:00) [common]
case: http-download
case_id: 3984422
definition: lava
duration: 0.42
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:54) [common]
start: 1.4.1 http-download (timeout 01:29:54) [common]
downloading http://download.automotivelinux.org/AGL/upload/ci/29371/2/h3ulcb-nogfx/agl-demo-platform-h3ulcb.ext4.xz
saving as /var/lib/lava/dispatcher/tmp/34448/nbd-deploy-5q_nq391/nbdroot/agl-demo-platform-h3ulcb.ext4
total size: 453729592 (432MB)
Using unxz to decompress xz
progress 0% (0MB)
progress 5% (21MB)
progress 10% (43MB)
progress 15% (64MB)
progress 20% (86MB)
progress 25% (108MB)
progress 30% (129MB)
progress 35% (151MB)
progress 40% (173MB)
progress 45% (194MB)
progress 50% (216MB)
progress 55% (238MB)
progress 60% (259MB)
progress 65% (281MB)
progress 70% (302MB)
progress 75% (324MB)
progress 80% (346MB)
progress 85% (367MB)
progress 90% (389MB)
progress 95% (411MB)
progress 100% (432MB)
432MB downloaded in 146.30s (2.96MB/s)
end: 1.4.1 http-download (duration 00:02:26) [common]
case: http-download
case_id: 3984422
definition: lava
duration: 146.31
extra: ...
level: 1.4.1
namespace: common
result: pass
end: 1.4 download-retry (duration 00:02:26) [common]
start: 1.5 lava-overlay (timeout 01:27:28) [common]
[common] Preparing overlay tarball in /var/lib/lava/dispatcher/tmp/34448/lava-overlay-dxiwrmyo
makedir: /var/lib/lava/dispatcher/tmp/34448/lava-overlay-dxiwrmyo/lava-34448/bin
makedir: /var/lib/lava/dispatcher/tmp/34448/lava-overlay-dxiwrmyo/lava-34448/tests
makedir: /var/lib/lava/dispatcher/tmp/34448/lava-overlay-dxiwrmyo/lava-34448/results
Creating /var/lib/lava/dispatcher/tmp/34448/lava-overlay-dxiwrmyo/lava-34448/bin/lava-add-keys
Creating /var/lib/lava/dispatcher/tmp/34448/lava-overlay-dxiwrmyo/lava-34448/bin/lava-add-sources
Creating /var/lib/lava/dispatcher/tmp/34448/lava-overlay-dxiwrmyo/lava-34448/bin/lava-background-process-start
Creating /var/lib/lava/dispatcher/tmp/34448/lava-overlay-dxiwrmyo/lava-34448/bin/lava-background-process-stop
Creating /var/lib/lava/dispatcher/tmp/34448/lava-overlay-dxiwrmyo/lava-34448/bin/lava-common-functions
Creating /var/lib/lava/dispatcher/tmp/34448/lava-overlay-dxiwrmyo/lava-34448/bin/lava-echo-ipv4
Creating /var/lib/lava/dispatcher/tmp/34448/lava-overlay-dxiwrmyo/lava-34448/bin/lava-install-packages
Creating /var/lib/lava/dispatcher/tmp/34448/lava-overlay-dxiwrmyo/lava-34448/bin/lava-installed-packages
Creating /var/lib/lava/dispatcher/tmp/34448/lava-overlay-dxiwrmyo/lava-34448/bin/lava-os-build
Creating /var/lib/lava/dispatcher/tmp/34448/lava-overlay-dxiwrmyo/lava-34448/bin/lava-probe-channel
Creating /var/lib/lava/dispatcher/tmp/34448/lava-overlay-dxiwrmyo/lava-34448/bin/lava-probe-ip
Creating /var/lib/lava/dispatcher/tmp/34448/lava-overlay-dxiwrmyo/lava-34448/bin/lava-target-ip
Creating /var/lib/lava/dispatcher/tmp/34448/lava-overlay-dxiwrmyo/lava-34448/bin/lava-target-mac
Creating /var/lib/lava/dispatcher/tmp/34448/lava-overlay-dxiwrmyo/lava-34448/bin/lava-target-storage
Creating /var/lib/lava/dispatcher/tmp/34448/lava-overlay-dxiwrmyo/lava-34448/bin/lava-test-case
Creating /var/lib/lava/dispatcher/tmp/34448/lava-overlay-dxiwrmyo/lava-34448/bin/lava-test-event
Creating /var/lib/lava/dispatcher/tmp/34448/lava-overlay-dxiwrmyo/lava-34448/bin/lava-test-feedback
Creating /var/lib/lava/dispatcher/tmp/34448/lava-overlay-dxiwrmyo/lava-34448/bin/lava-test-raise
Creating /var/lib/lava/dispatcher/tmp/34448/lava-overlay-dxiwrmyo/lava-34448/bin/lava-test-reference
Creating /var/lib/lava/dispatcher/tmp/34448/lava-overlay-dxiwrmyo/lava-34448/bin/lava-test-runner
Creating /var/lib/lava/dispatcher/tmp/34448/lava-overlay-dxiwrmyo/lava-34448/bin/lava-test-set
Creating /var/lib/lava/dispatcher/tmp/34448/lava-overlay-dxiwrmyo/lava-34448/bin/lava-test-shell
Updating /var/lib/lava/dispatcher/tmp/34448/lava-overlay-dxiwrmyo/lava-34448/bin/lava-install-packages (oe)
Updating /var/lib/lava/dispatcher/tmp/34448/lava-overlay-dxiwrmyo/lava-34448/bin/lava-installed-packages (oe)
Creating /var/lib/lava/dispatcher/tmp/34448/lava-overlay-dxiwrmyo/lava-34448/environment
LAVA metadata
- LAVA_JOB_ID=34448
- LAVA_DISPATCHER_IP=192.168.66.1
start: 1.5.1 ssh-authorize (timeout 01:27:28) [common]
end: 1.5.1 ssh-authorize (duration 00:00:00) [common]
start: 1.5.2 lava-vland-overlay (timeout 01:27:28) [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:28) [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:28) [common]
Loading test definitions
start: 1.5.4.1 git-repo-action (timeout 01:27:28) [common]
Using /lava-34448 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/34448/lava-overlay-dxiwrmyo/lava-34448/0/tests/0_network-veth'
Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/34448/lava-overlay-dxiwrmyo/lava-34448/0/tests/0_network-veth/test-suites/short-smoke/network-veth.yaml
uuid=34448_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: 3984443
definition: lava
duration: 1.07
extra: ...
level: 1.5.4.1
namespace: common
result: pass
start: 1.5.4.2 test-overlay (timeout 01:27:27) [common]
end: 1.5.4.2 test-overlay (duration 00:00:00) [common]
case: test-overlay
case_id: 3984444
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:27) [common]
end: 1.5.4.3 test-install-overlay (duration 00:00:00) [common]
case: test-install-overlay
case_id: 3984445
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:27) [common]
runner path: /var/lib/lava/dispatcher/tmp/34448/lava-overlay-dxiwrmyo/lava-34448/0/tests/0_network-veth test_uuid 34448_1.5.4.1
end: 1.5.4.4 test-runscript-overlay (duration 00:00:00) [common]
case: test-runscript-overlay
case_id: 3984446
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:27) [common]
Using /lava-34448 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/34448/lava-overlay-dxiwrmyo/lava-34448/1/tests/1_ci-busybox'
Removing '.git' directory in /var/lib/lava/dispatcher/tmp/34448/lava-overlay-dxiwrmyo/lava-34448/1/tests/1_ci-busybox
Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/34448/lava-overlay-dxiwrmyo/lava-34448/1/tests/1_ci-busybox/test-suites/short-smoke/busybox.yaml
uuid=34448_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: 3984443
definition: lava
duration: 1.01
extra: ...
level: 1.5.4.5
namespace: common
result: pass
start: 1.5.4.6 test-overlay (timeout 01:27:26) [common]
end: 1.5.4.6 test-overlay (duration 00:00:00) [common]
case: test-overlay
case_id: 3984444
definition: lava
duration: 0.02
extra: ...
level: 1.5.4.6
namespace: common
result: pass
start: 1.5.4.7 test-install-overlay (timeout 01:27:26) [common]
end: 1.5.4.7 test-install-overlay (duration 00:00:00) [common]
case: test-install-overlay
case_id: 3984445
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:26) [common]
runner path: /var/lib/lava/dispatcher/tmp/34448/lava-overlay-dxiwrmyo/lava-34448/1/tests/1_ci-busybox test_uuid 34448_1.5.4.5
end: 1.5.4.8 test-runscript-overlay (duration 00:00:00) [common]
case: test-runscript-overlay
case_id: 3984446
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:26) [common]
Using /lava-34448 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/34448/lava-overlay-dxiwrmyo/lava-34448/1/tests/2_ci-smoke-tests-basic'
Removing '.git' directory in /var/lib/lava/dispatcher/tmp/34448/lava-overlay-dxiwrmyo/lava-34448/1/tests/2_ci-smoke-tests-basic
Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/34448/lava-overlay-dxiwrmyo/lava-34448/1/tests/2_ci-smoke-tests-basic/test-suites/short-smoke/smoke-tests-basic.yaml
uuid=34448_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: 3984443
definition: lava
duration: 0.93
extra: ...
level: 1.5.4.9
namespace: common
result: pass
start: 1.5.4.10 test-overlay (timeout 01:27:25) [common]
end: 1.5.4.10 test-overlay (duration 00:00:00) [common]
case: test-overlay
case_id: 3984444
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:27:25) [common]
end: 1.5.4.11 test-install-overlay (duration 00:00:00) [common]
case: test-install-overlay
case_id: 3984445
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:25) [common]
runner path: /var/lib/lava/dispatcher/tmp/34448/lava-overlay-dxiwrmyo/lava-34448/1/tests/2_ci-smoke-tests-basic test_uuid 34448_1.5.4.9
end: 1.5.4.12 test-runscript-overlay (duration 00:00:00) [common]
case: test-runscript-overlay
case_id: 3984446
definition: lava
duration: 0.02
extra: ...
level: 1.5.4.12
namespace: common
result: pass
start: 1.5.4.13 git-repo-action (timeout 01:27:25) [common]
Using /lava-34448 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/34448/lava-overlay-dxiwrmyo/lava-34448/1/tests/3_ci-service-check'
Removing '.git' directory in /var/lib/lava/dispatcher/tmp/34448/lava-overlay-dxiwrmyo/lava-34448/1/tests/3_ci-service-check
Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/34448/lava-overlay-dxiwrmyo/lava-34448/1/tests/3_ci-service-check/test-suites/short-smoke/service-check.yaml
uuid=34448_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: 3984443
definition: lava
duration: 0.78
extra: ...
level: 1.5.4.13
namespace: common
result: pass
start: 1.5.4.14 test-overlay (timeout 01:27:24) [common]
end: 1.5.4.14 test-overlay (duration 00:00:00) [common]
case: test-overlay
case_id: 3984444
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:27:24) [common]
end: 1.5.4.15 test-install-overlay (duration 00:00:00) [common]
case: test-install-overlay
case_id: 3984445
definition: lava
duration: 0.02
extra: ...
level: 1.5.4.15
namespace: common
result: pass
start: 1.5.4.16 test-runscript-overlay (timeout 01:27:24) [common]
runner path: /var/lib/lava/dispatcher/tmp/34448/lava-overlay-dxiwrmyo/lava-34448/1/tests/3_ci-service-check test_uuid 34448_1.5.4.13
BUILD_TAGS=''
DEVICE_TAGS='kingfisher can bluetooth wifi audio'
end: 1.5.4.16 test-runscript-overlay (duration 00:00:00) [common]
case: test-runscript-overlay
case_id: 3984446
definition: lava
duration: 0.02
extra: ...
level: 1.5.4.16
namespace: common
result: pass
start: 1.5.4.17 git-repo-action (timeout 01:27:24) [common]
Using /lava-34448 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/34448/lava-overlay-dxiwrmyo/lava-34448/2/tests/4_screenshooter'
Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/34448/lava-overlay-dxiwrmyo/lava-34448/2/tests/4_screenshooter/test-suites/short-smoke/screenshooter.yaml
uuid=34448_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: 3984443
definition: lava
duration: 0.93
extra: ...
level: 1.5.4.17
namespace: common
result: pass
start: 1.5.4.18 test-overlay (timeout 01:27:23) [common]
end: 1.5.4.18 test-overlay (duration 00:00:00) [common]
case: test-overlay
case_id: 3984444
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:27:23) [common]
end: 1.5.4.19 test-install-overlay (duration 00:00:00) [common]
case: test-install-overlay
case_id: 3984445
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:23) [common]
runner path: /var/lib/lava/dispatcher/tmp/34448/lava-overlay-dxiwrmyo/lava-34448/2/tests/4_screenshooter test_uuid 34448_1.5.4.17
end: 1.5.4.20 test-runscript-overlay (duration 00:00:00) [common]
case: test-runscript-overlay
case_id: 3984446
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:23) [common]
Using /lava-34448 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/34448/lava-overlay-dxiwrmyo/lava-34448/3/tests/5_ci-dumpjournal'
Removing '.git' directory in /var/lib/lava/dispatcher/tmp/34448/lava-overlay-dxiwrmyo/lava-34448/3/tests/5_ci-dumpjournal
Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/34448/lava-overlay-dxiwrmyo/lava-34448/3/tests/5_ci-dumpjournal/test-suites/short-smoke/dumpjournal.yaml
uuid=34448_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: 3984443
definition: lava
duration: 0.82
extra: ...
level: 1.5.4.21
namespace: common
result: pass
start: 1.5.4.22 test-overlay (timeout 01:27:22) [common]
end: 1.5.4.22 test-overlay (duration 00:00:00) [common]
case: test-overlay
case_id: 3984444
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:27:22) [common]
end: 1.5.4.23 test-install-overlay (duration 00:00:00) [common]
case: test-install-overlay
case_id: 3984445
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:22) [common]
runner path: /var/lib/lava/dispatcher/tmp/34448/lava-overlay-dxiwrmyo/lava-34448/3/tests/5_ci-dumpjournal test_uuid 34448_1.5.4.21
end: 1.5.4.24 test-runscript-overlay (duration 00:00:00) [common]
case: test-runscript-overlay
case_id: 3984446
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/34448/lava-overlay-dxiwrmyo/lava-34448/0 for stage 0
- 0_network-veth
Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/34448/lava-overlay-dxiwrmyo/lava-34448/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/34448/lava-overlay-dxiwrmyo/lava-34448/2 for stage 2
- 4_screenshooter
Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/34448/lava-overlay-dxiwrmyo/lava-34448/3 for stage 3
- 5_ci-dumpjournal
end: 1.5.4 test-definition (duration 00:00:06) [common]
start: 1.5.5 compress-overlay (timeout 01:27:22) [common]
end: 1.5.5 compress-overlay (duration 00:00:01) [common]
start: 1.5.6 persistent-nfs-overlay (timeout 01:27:21) [common]
end: 1.5.6 persistent-nfs-overlay (duration 00:00:00) [common]
end: 1.5 lava-overlay (duration 00:00:07) [common]
start: 1.6 prepare-kernel (timeout 01:27:21) [common]
start: 1.6.1 uboot-prepare-kernel (timeout 01:27:21) [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:21) [common]
xnbd-server-deploy: starting nbd-server
NBD-IP: 192.168.66.1, NBD-PORT: 61992, NBD-ROOT: 34448/nbd-deploy-5q_nq391/nbdroot/agl-demo-platform-h3ulcb.ext4
nbd-server 61992 /var/lib/lava/dispatcher/tmp/34448/nbd-deploy-5q_nq391/nbdroot/agl-demo-platform-h3ulcb.ext4
output:
output: ** (process:19929): WARNING **: 14:18:44.389: 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:39) [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}: 34448/nbd-deploy-5q_nq391/dtb/r8a77951-ulcb.dtb
- {INITRD}: 34448/nbd-deploy-5q_nq391/initrd/initramfs-netboot-image-h3ulcb.ext4.gz
- {KERNEL_ADDR}: 0x68080000
- {KERNEL}: 34448/nbd-deploy-5q_nq391/kernel/Image
- {LAVA_MAC}: None
- {NBDSERVERIP}: 192.168.66.1
- {NBDSERVERPORT}: 61992
- {PRESEED_CONFIG}: None
- {PRESEED_LOCAL}: None
- {RAMDISK_ADDR}: 0x4a000000
- {RAMDISK}: 34448/nbd-deploy-5q_nq391/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 34448/nbd-deploy-5q_nq391/kernel/Image
- tftp 0x4a000000 34448/nbd-deploy-5q_nq391/initrd/initramfs-netboot-image-h3ulcb.ext4.gz
- setenv initrd_size ${filesize}
- tftp 0x68000000 34448/nbd-deploy-5q_nq391/dtb/r8a77951-ulcb.dtb
- setenv bootargs 'console=ttySC0,115200n8 rw nbd.server=192.168.66.1 nbd.port=61992 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 0x68080000 0x4a000000:${initrd_size} 0x68000000
end: 2.2 bootloader-overlay (duration 00:00:00) [common]
case: bootloader-overlay
case_id: 3984447
definition: lava
duration: 0.05
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:04) [common]
start: 2.4 uboot-commands (timeout 00:19:55) [common]
start: 2.4.1 reset-device (timeout 00:19:55) [common]
start: 2.4.1.1 pdu-reboot (timeout 00:19:55) [common]
Calling: 'nice' '/usr/local/bin/acme-cli' '-s' '192.168.66.2' 'reset' '2'
>> Success
>> Success
Returned 0 in 5 seconds
end: 2.4.1.1 pdu-reboot (duration 00:00:05) [common]
case: pdu-reboot
case_id: 3984448
definition: lava
duration: 5.38
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:50) [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 Gen� 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: 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� NOTICE: BL2: R-Car Gen3 Initial Program � NOTICE: BL2: R-Car Gen3 Initial Program Loader(CA57) Rev.3.0.3
NOTICE: BL2: PRR is R-Car H3 Ver.3� 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: 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 Star� 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 R� 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 HyperF� 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: B� 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� 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: 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
end: 2.4.2 bootloader-interrupt (duration 00:00:02) [common]
start: 2.4.3 bootloader-commands (timeout 00:19:48) [common]
Setting prompt string to ['=>']
bootloader-commands: Wait for prompt ['=>'] (timeout 00:19:48)
: 3 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:48)
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:48)
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:48)
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:48)
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 (1027 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:44)
setenv serverip 192.168.66.1
tftp 0x68080000 34448/nbd-deploy-5q_nq391/kernel/Image
=> tftp 0x68080000 34448/nbd-deploy-5q_nq391/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:44)
tftp 0x68080000 34448/nbd-deploy-5q_nq391/kernel/Image
Using ethernet@e6800000 device
TFTP from server 192.168.66.1; our IP address is 192.168.66.19
Filename '34448/nbd-deploy-5q_nq391/kernel/Image'.
Load address: 0x68080000
Loading: *#################################################################
#################################################################
#################################################################
#################################################################
#################################################################
#################################################################
#################################################################
#################################################################
#################################################################
#################################################################
#################################################################
#################################################################
#################################################################
#################################################################
#################################################################
#################################################################
#################################################################
#################################################################
#################################################################
#################################################################
#################################################################
#################################################################
#################################################################
#################################################################
#################################################################
#################################################################
#################################################################
#################################################################
#################################################################
#################################################################
#################################################################
#################################################################
#################################################################
#################################################################
#################################################################
#################################################################
#################################################################
#################################################################
#################################################################
#################################################################
#################################################################
######
1.7 MiB/s
done
Bytes transferred = 39207424 (2564200 hex)
tftp 0x4a000000 34448/nbd-deploy-5q_nq391/initrd/initramfs-netboot-image-h3ulcb.ext4.gz
=> tftp 0x4a000000 34448/nbd-deploy-5q_nq391/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:22)
tftp 0x4a000000 34448/nbd-deploy-5q_nq391/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 '34448/nbd-deploy-5q_nq391/initrd/initramfs-netboot-image-h3ulcb.ext4.gz'.
Load address: 0x4a000000
Loading: *#################################################################
#################################################################
###########
1.7 MiB/s
done
Bytes transferred = 2061367 (1f7437 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:20)
setenv initrd_size ${filesize}
tftp 0x68000000 34448/nbd-deploy-5q_nq391/dtb/r8a77951-ulcb.dtb
=> tftp 0x68000000 34448/nbd-deploy-5q_nq391/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:20)
tftp 0x68000000 34448/nbd-deploy-5q_nq391/dtb/r8a77951-ulcb.dtb
Using ethernet@e6800000 device
TFTP from server 192.168.66.1; our IP address is 192.168.66.19
Filename '34448/nbd-deploy-5q_nq391/dtb/r8a77951-ulcb.dtb'.
Load address: 0x68000000
Loading: *######
1.6 MiB/s
done
Bytes transferred = 82960 (14410 hex)
setenv bootargs 'console=ttySC0,115200n8 rw nbd.server=192.168.66.1 nbd.port=61992 root=/dev/ram0 ramdisk_size=16384 rootdelay=7 systemd.mask=systemd-network-generator.service ip=dhcp verbose earlyprintk systemd.log_color=false ${extraargs} rw'
=> setenv bootargs 'console=ttySC0,115200n8 rw nbd.server=192.168.66.1 nbd.port=61992 root=/dev/ram0 ramdisk_size=16384 rootdelay=7 systemd.mask=systemd-network-generator.service 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:20)
setenv bootargs 'console=ttySC0,115200n8 rw nbd.server=192.168.66.1 nbd.port=61992 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 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:20)
booti 0x68080000 0x4a000000:${initrd_size} 0x68000000
Moving Image from 0x68080000 to 0x68200000, end=6a800000
## Flattened Device Tree blob at 68000000
Booting using the fdt blob at 0x68000000
Loading Ramdisk to 7fe08000, end 7ffff437 ... 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: 000000007bf04d70 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:29) [common]
case: bootloader-commands
case_id: 3984513
definition: lava
duration: 28.68
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: 3984514
definition: lava
duration: 35.75
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:40) [common]
case: uboot-action
case_id: 3984515
definition: lava
duration: 40.29
extra: ...
level: 2
namespace: common
result: fail
Cleaning after the job
Cleaning up download directory: /var/lib/lava/dispatcher/tmp/34448/nbd-deploy-5q_nq391/initrd
Cleaning up download directory: /var/lib/lava/dispatcher/tmp/34448/nbd-deploy-5q_nq391/kernel
Cleaning up download directory: /var/lib/lava/dispatcher/tmp/34448/nbd-deploy-5q_nq391/dtb
Cleaning up download directory: /var/lib/lava/dispatcher/tmp/34448/nbd-deploy-5q_nq391/nbdroot
start: 7.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 4 seconds
end: 7.1 power-off (duration 00:00:05) [common]
case: power-off
case_id: 3984534
definition: lava
duration: 4.57
extra: ...
level: 7.1
namespace: common
result: pass
start: 7.2 read-feedback (timeout 00:29:55) [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
*** ERROR: `serverip' not set
Bad Linux ARM64 Image magic!
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 61992
Override tmp directory removed at /var/lib/lava/dispatcher/tmp/34448
Root tmp directory removed at /var/lib/lava/dispatcher/tmp/34448
InfrastructureError: The Infrastructure is not working correctly. Please report this error to LAVA admins.
case: job
case_id: 3984535
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.