extra keys not allowed @ data['actions[0]']['deploy']['nbd']['initrd']['allow_modify']
Job error: auto-login-action timed out after 1168 seconds
lava-dispatcher, installed at version: 2023.10
start: 0 validate
Start time: 2024-09-07 00:22:38.505573+00:00 (UTC)
Validating that http://download.automotivelinux.org/AGL/upload/ci/30257/6/qemux86-64/initramfs-netboot-image-qemux86-64.ext4.gz exists
Validating that http://download.automotivelinux.org/AGL/upload/ci/30257/6/qemux86-64/bzImage exists
Validating that http://download.automotivelinux.org/AGL/upload/ci/30257/6/qemux86-64/agl-ivi-demo-qt-qemux86-64.ext4.xz exists
validate duration: 2.01
case: validate
case_id: 4043637
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 61966
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/30257/6/qemux86-64/initramfs-netboot-image-qemux86-64.ext4.gz
saving as /var/lib/lava/dispatcher/tmp/36332/nbd-deploy-tcfvcf6u/initrd/initramfs-netboot-image-qemux86-64.ext4.gz
total size: 2963803 (2 MB)
No compression specified
progress 1 % (0 MB)
progress 6 % (0 MB)
progress 11 % (0 MB)
progress 16 % (0 MB)
progress 21 % (0 MB)
progress 26 % (0 MB)
progress 32 % (0 MB)
progress 37 % (1 MB)
progress 42 % (1 MB)
progress 47 % (1 MB)
progress 53 % (1 MB)
progress 58 % (1 MB)
progress 63 % (1 MB)
progress 68 % (1 MB)
progress 74 % (2 MB)
progress 79 % (2 MB)
progress 84 % (2 MB)
progress 89 % (2 MB)
progress 95 % (2 MB)
progress 100 % (2 MB)
2 MB downloaded in 1.34 s (2.10 MB/s)
end: 1.1.1 http-download (duration 00:00:01) [common]
case: http-download
case_id: 4043750
definition: lava
duration: 1.35
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/30257/6/qemux86-64/bzImage
saving as /var/lib/lava/dispatcher/tmp/36332/nbd-deploy-tcfvcf6u/kernel/bzImage
total size: 13235200 (12 MB)
No compression specified
progress 0 % (0 MB)
progress 5 % (0 MB)
progress 10 % (1 MB)
progress 15 % (1 MB)
progress 20 % (2 MB)
progress 25 % (3 MB)
progress 30 % (3 MB)
progress 35 % (4 MB)
progress 40 % (5 MB)
progress 45 % (5 MB)
progress 50 % (6 MB)
progress 55 % (6 MB)
progress 60 % (7 MB)
progress 65 % (8 MB)
progress 70 % (8 MB)
progress 75 % (9 MB)
progress 80 % (10 MB)
progress 85 % (10 MB)
progress 90 % (11 MB)
progress 95 % (12 MB)
progress 100 % (12 MB)
12 MB downloaded in 3.17 s (3.98 MB/s)
end: 1.2.1 http-download (duration 00:00:03) [common]
case: http-download
case_id: 4043750
definition: lava
duration: 3.18
extra: ...
level: 1.2.1
namespace: common
result: pass
end: 1.2 download-retry (duration 00:00:03) [common]
start: 1.3 download-retry (timeout 01:29:55) [common]
start: 1.3.1 http-download (timeout 01:29:55) [common]
downloading http://download.automotivelinux.org/AGL/upload/ci/30257/6/qemux86-64/agl-ivi-demo-qt-qemux86-64.ext4.xz
saving as /var/lib/lava/dispatcher/tmp/36332/nbd-deploy-tcfvcf6u/nbdroot/agl-ivi-demo-qt-qemux86-64.ext4
total size: 729867660 (696 MB)
Using unxz to decompress xz
progress 0 % (0 MB)
progress 5 % (34 MB)
progress 10 % (69 MB)
progress 15 % (104 MB)
progress 20 % (139 MB)
progress 25 % (174 MB)
progress 30 % (208 MB)
progress 35 % (243 MB)
progress 40 % (278 MB)
progress 45 % (313 MB)
progress 50 % (348 MB)
progress 55 % (382 MB)
progress 60 % (417 MB)
progress 65 % (452 MB)
progress 70 % (487 MB)
progress 75 % (522 MB)
progress 80 % (556 MB)
progress 85 % (591 MB)
progress 90 % (626 MB)
progress 95 % (661 MB)
progress 100 % (696 MB)
696 MB downloaded in 219.69 s (3.17 MB/s)
end: 1.3.1 http-download (duration 00:03:40) [common]
case: http-download
case_id: 4043750
definition: lava
duration: 219.69
extra: ...
level: 1.3.1
namespace: common
result: pass
end: 1.3 download-retry (duration 00:03:40) [common]
start: 1.4 lava-overlay (timeout 01:26:16) [common]
[common] Preparing overlay tarball in /var/lib/lava/dispatcher/tmp/36332/lava-overlay-f_05__gj
makedir: /var/lib/lava/dispatcher/tmp/36332/lava-overlay-f_05__gj/lava-36332/bin
makedir: /var/lib/lava/dispatcher/tmp/36332/lava-overlay-f_05__gj/lava-36332/tests
makedir: /var/lib/lava/dispatcher/tmp/36332/lava-overlay-f_05__gj/lava-36332/results
Creating /var/lib/lava/dispatcher/tmp/36332/lava-overlay-f_05__gj/lava-36332/bin/lava-add-keys
Creating /var/lib/lava/dispatcher/tmp/36332/lava-overlay-f_05__gj/lava-36332/bin/lava-add-sources
Creating /var/lib/lava/dispatcher/tmp/36332/lava-overlay-f_05__gj/lava-36332/bin/lava-background-process-start
Creating /var/lib/lava/dispatcher/tmp/36332/lava-overlay-f_05__gj/lava-36332/bin/lava-background-process-stop
Creating /var/lib/lava/dispatcher/tmp/36332/lava-overlay-f_05__gj/lava-36332/bin/lava-common-functions
Creating /var/lib/lava/dispatcher/tmp/36332/lava-overlay-f_05__gj/lava-36332/bin/lava-echo-ipv4
Creating /var/lib/lava/dispatcher/tmp/36332/lava-overlay-f_05__gj/lava-36332/bin/lava-install-packages
Creating /var/lib/lava/dispatcher/tmp/36332/lava-overlay-f_05__gj/lava-36332/bin/lava-installed-packages
Creating /var/lib/lava/dispatcher/tmp/36332/lava-overlay-f_05__gj/lava-36332/bin/lava-os-build
Creating /var/lib/lava/dispatcher/tmp/36332/lava-overlay-f_05__gj/lava-36332/bin/lava-probe-channel
Creating /var/lib/lava/dispatcher/tmp/36332/lava-overlay-f_05__gj/lava-36332/bin/lava-probe-ip
Creating /var/lib/lava/dispatcher/tmp/36332/lava-overlay-f_05__gj/lava-36332/bin/lava-target-ip
Creating /var/lib/lava/dispatcher/tmp/36332/lava-overlay-f_05__gj/lava-36332/bin/lava-target-mac
Creating /var/lib/lava/dispatcher/tmp/36332/lava-overlay-f_05__gj/lava-36332/bin/lava-target-storage
Creating /var/lib/lava/dispatcher/tmp/36332/lava-overlay-f_05__gj/lava-36332/bin/lava-test-case
Creating /var/lib/lava/dispatcher/tmp/36332/lava-overlay-f_05__gj/lava-36332/bin/lava-test-event
Creating /var/lib/lava/dispatcher/tmp/36332/lava-overlay-f_05__gj/lava-36332/bin/lava-test-feedback
Creating /var/lib/lava/dispatcher/tmp/36332/lava-overlay-f_05__gj/lava-36332/bin/lava-test-raise
Creating /var/lib/lava/dispatcher/tmp/36332/lava-overlay-f_05__gj/lava-36332/bin/lava-test-reference
Creating /var/lib/lava/dispatcher/tmp/36332/lava-overlay-f_05__gj/lava-36332/bin/lava-test-runner
Creating /var/lib/lava/dispatcher/tmp/36332/lava-overlay-f_05__gj/lava-36332/bin/lava-test-set
Creating /var/lib/lava/dispatcher/tmp/36332/lava-overlay-f_05__gj/lava-36332/bin/lava-test-shell
Updating /var/lib/lava/dispatcher/tmp/36332/lava-overlay-f_05__gj/lava-36332/bin/lava-install-packages (oe)
Updating /var/lib/lava/dispatcher/tmp/36332/lava-overlay-f_05__gj/lava-36332/bin/lava-installed-packages (oe)
Creating /var/lib/lava/dispatcher/tmp/36332/lava-overlay-f_05__gj/lava-36332/environment
LAVA metadata
- LAVA_JOB_ID=36332
- LAVA_DISPATCHER_IP=192.168.66.1
start: 1.4.1 lava-vland-overlay (timeout 01:26:16) [common]
skipped lava-vland-overlay
end: 1.4.1 lava-vland-overlay (duration 00:00:00) [common]
start: 1.4.2 lava-multinode-overlay (timeout 01:26:16) [common]
skipped lava-multinode-overlay
end: 1.4.2 lava-multinode-overlay (duration 00:00:00) [common]
start: 1.4.3 test-definition (timeout 01:26:16) [common]
Loading test definitions
start: 1.4.3.1 git-repo-action (timeout 01:26:16) [common]
Using /lava-36332 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/36332/lava-overlay-f_05__gj/lava-36332/0/tests/0_network-veth'
Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/36332/lava-overlay-f_05__gj/lava-36332/0/tests/0_network-veth/test-suites/short-smoke/network-veth.yaml
uuid=36332_1.4.3.1 testdef=None
end: 1.4.3.1 git-repo-action (duration 00:00:01) [common]
case: git-repo-action
case_id: 4043772
definition: lava
duration: 0.86
extra: ...
level: 1.4.3.1
namespace: common
result: pass
start: 1.4.3.2 test-overlay (timeout 01:26:15) [common]
end: 1.4.3.2 test-overlay (duration 00:00:00) [common]
case: test-overlay
case_id: 4043773
definition: lava
duration: 0.01
extra: ...
level: 1.4.3.2
namespace: common
result: pass
start: 1.4.3.3 test-install-overlay (timeout 01:26:15) [common]
end: 1.4.3.3 test-install-overlay (duration 00:00:00) [common]
case: test-install-overlay
case_id: 4043774
definition: lava
duration: 0.01
extra: ...
level: 1.4.3.3
namespace: common
result: pass
start: 1.4.3.4 test-runscript-overlay (timeout 01:26:15) [common]
runner path: /var/lib/lava/dispatcher/tmp/36332/lava-overlay-f_05__gj/lava-36332/0/tests/0_network-veth test_uuid 36332_1.4.3.1
end: 1.4.3.4 test-runscript-overlay (duration 00:00:00) [common]
case: test-runscript-overlay
case_id: 4043775
definition: lava
duration: 0.01
extra: ...
level: 1.4.3.4
namespace: common
result: pass
start: 1.4.3.5 git-repo-action (timeout 01:26:15) [common]
Using /lava-36332 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/36332/lava-overlay-f_05__gj/lava-36332/1/tests/1_ci-busybox'
Removing '.git' directory in /var/lib/lava/dispatcher/tmp/36332/lava-overlay-f_05__gj/lava-36332/1/tests/1_ci-busybox
Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/36332/lava-overlay-f_05__gj/lava-36332/1/tests/1_ci-busybox/test-suites/short-smoke/busybox.yaml
uuid=36332_1.4.3.5 testdef={'testdef_pattern': {'pattern': '^(?P<test_case_id>[a-zA-Z0-9_-]+):\\s(?P<result>\\w+)', 'fixupdict': {'PASS': 'pass', 'FAIL': 'fail'}}}
end: 1.4.3.5 git-repo-action (duration 00:00:01) [common]
case: git-repo-action
case_id: 4043772
definition: lava
duration: 0.80
extra: ...
level: 1.4.3.5
namespace: common
result: pass
start: 1.4.3.6 test-overlay (timeout 01:26:14) [common]
end: 1.4.3.6 test-overlay (duration 00:00:00) [common]
case: test-overlay
case_id: 4043773
definition: lava
duration: 0.01
extra: ...
level: 1.4.3.6
namespace: common
result: pass
start: 1.4.3.7 test-install-overlay (timeout 01:26:14) [common]
end: 1.4.3.7 test-install-overlay (duration 00:00:00) [common]
case: test-install-overlay
case_id: 4043774
definition: lava
duration: 0.01
extra: ...
level: 1.4.3.7
namespace: common
result: pass
start: 1.4.3.8 test-runscript-overlay (timeout 01:26:14) [common]
runner path: /var/lib/lava/dispatcher/tmp/36332/lava-overlay-f_05__gj/lava-36332/1/tests/1_ci-busybox test_uuid 36332_1.4.3.5
end: 1.4.3.8 test-runscript-overlay (duration 00:00:00) [common]
case: test-runscript-overlay
case_id: 4043775
definition: lava
duration: 0.01
extra: ...
level: 1.4.3.8
namespace: common
result: pass
start: 1.4.3.9 git-repo-action (timeout 01:26:14) [common]
Using /lava-36332 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/36332/lava-overlay-f_05__gj/lava-36332/1/tests/2_ci-smoke-tests-basic'
Removing '.git' directory in /var/lib/lava/dispatcher/tmp/36332/lava-overlay-f_05__gj/lava-36332/1/tests/2_ci-smoke-tests-basic
Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/36332/lava-overlay-f_05__gj/lava-36332/1/tests/2_ci-smoke-tests-basic/test-suites/short-smoke/smoke-tests-basic.yaml
uuid=36332_1.4.3.9 testdef=None
end: 1.4.3.9 git-repo-action (duration 00:00:01) [common]
case: git-repo-action
case_id: 4043772
definition: lava
duration: 0.66
extra: ...
level: 1.4.3.9
namespace: common
result: pass
start: 1.4.3.10 test-overlay (timeout 01:26:13) [common]
end: 1.4.3.10 test-overlay (duration 00:00:00) [common]
case: test-overlay
case_id: 4043773
definition: lava
duration: 0.01
extra: ...
level: 1.4.3.10
namespace: common
result: pass
start: 1.4.3.11 test-install-overlay (timeout 01:26:13) [common]
end: 1.4.3.11 test-install-overlay (duration 00:00:00) [common]
case: test-install-overlay
case_id: 4043774
definition: lava
duration: 0.01
extra: ...
level: 1.4.3.11
namespace: common
result: pass
start: 1.4.3.12 test-runscript-overlay (timeout 01:26:13) [common]
runner path: /var/lib/lava/dispatcher/tmp/36332/lava-overlay-f_05__gj/lava-36332/1/tests/2_ci-smoke-tests-basic test_uuid 36332_1.4.3.9
end: 1.4.3.12 test-runscript-overlay (duration 00:00:00) [common]
case: test-runscript-overlay
case_id: 4043775
definition: lava
duration: 0.01
extra: ...
level: 1.4.3.12
namespace: common
result: pass
start: 1.4.3.13 git-repo-action (timeout 01:26:13) [common]
Using /lava-36332 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/36332/lava-overlay-f_05__gj/lava-36332/1/tests/3_ci-service-check'
Removing '.git' directory in /var/lib/lava/dispatcher/tmp/36332/lava-overlay-f_05__gj/lava-36332/1/tests/3_ci-service-check
Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/36332/lava-overlay-f_05__gj/lava-36332/1/tests/3_ci-service-check/test-suites/short-smoke/service-check.yaml
uuid=36332_1.4.3.13 testdef=None
end: 1.4.3.13 git-repo-action (duration 00:00:01) [common]
case: git-repo-action
case_id: 4043772
definition: lava
duration: 0.67
extra: ...
level: 1.4.3.13
namespace: common
result: pass
start: 1.4.3.14 test-overlay (timeout 01:26:13) [common]
end: 1.4.3.14 test-overlay (duration 00:00:00) [common]
case: test-overlay
case_id: 4043773
definition: lava
duration: 0.01
extra: ...
level: 1.4.3.14
namespace: common
result: pass
start: 1.4.3.15 test-install-overlay (timeout 01:26:13) [common]
end: 1.4.3.15 test-install-overlay (duration 00:00:00) [common]
case: test-install-overlay
case_id: 4043774
definition: lava
duration: 0.01
extra: ...
level: 1.4.3.15
namespace: common
result: pass
start: 1.4.3.16 test-runscript-overlay (timeout 01:26:13) [common]
runner path: /var/lib/lava/dispatcher/tmp/36332/lava-overlay-f_05__gj/lava-36332/1/tests/3_ci-service-check test_uuid 36332_1.4.3.13
BUILD_TAGS=''
DEVICE_TAGS=''
end: 1.4.3.16 test-runscript-overlay (duration 00:00:00) [common]
case: test-runscript-overlay
case_id: 4043775
definition: lava
duration: 0.01
extra: ...
level: 1.4.3.16
namespace: common
result: pass
start: 1.4.3.17 git-repo-action (timeout 01:26:13) [common]
Using /lava-36332 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/36332/lava-overlay-f_05__gj/lava-36332/2/tests/4_screenshooter'
Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/36332/lava-overlay-f_05__gj/lava-36332/2/tests/4_screenshooter/test-suites/short-smoke/screenshooter.yaml
uuid=36332_1.4.3.17 testdef=None
end: 1.4.3.17 git-repo-action (duration 00:00:01) [common]
case: git-repo-action
case_id: 4043772
definition: lava
duration: 0.67
extra: ...
level: 1.4.3.17
namespace: common
result: pass
start: 1.4.3.18 test-overlay (timeout 01:26:12) [common]
end: 1.4.3.18 test-overlay (duration 00:00:00) [common]
case: test-overlay
case_id: 4043773
definition: lava
duration: 0.01
extra: ...
level: 1.4.3.18
namespace: common
result: pass
start: 1.4.3.19 test-install-overlay (timeout 01:26:12) [common]
end: 1.4.3.19 test-install-overlay (duration 00:00:00) [common]
case: test-install-overlay
case_id: 4043774
definition: lava
duration: 0.01
extra: ...
level: 1.4.3.19
namespace: common
result: pass
start: 1.4.3.20 test-runscript-overlay (timeout 01:26:12) [common]
runner path: /var/lib/lava/dispatcher/tmp/36332/lava-overlay-f_05__gj/lava-36332/2/tests/4_screenshooter test_uuid 36332_1.4.3.17
end: 1.4.3.20 test-runscript-overlay (duration 00:00:00) [common]
case: test-runscript-overlay
case_id: 4043775
definition: lava
duration: 0.01
extra: ...
level: 1.4.3.20
namespace: common
result: pass
start: 1.4.3.21 git-repo-action (timeout 01:26:12) [common]
Using /lava-36332 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/36332/lava-overlay-f_05__gj/lava-36332/3/tests/5_ci-dumpjournal'
Removing '.git' directory in /var/lib/lava/dispatcher/tmp/36332/lava-overlay-f_05__gj/lava-36332/3/tests/5_ci-dumpjournal
Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/36332/lava-overlay-f_05__gj/lava-36332/3/tests/5_ci-dumpjournal/test-suites/short-smoke/dumpjournal.yaml
uuid=36332_1.4.3.21 testdef={'testdef_pattern': {'pattern': '^(?P<test_case_id>[a-zA-Z0-9_-]+):\\s(?P<result>\\w+)', 'fixupdict': {'PASS': 'pass', 'FAIL': 'fail'}}}
end: 1.4.3.21 git-repo-action (duration 00:00:01) [common]
case: git-repo-action
case_id: 4043772
definition: lava
duration: 0.68
extra: ...
level: 1.4.3.21
namespace: common
result: pass
start: 1.4.3.22 test-overlay (timeout 01:26:11) [common]
end: 1.4.3.22 test-overlay (duration 00:00:00) [common]
case: test-overlay
case_id: 4043773
definition: lava
duration: 0.01
extra: ...
level: 1.4.3.22
namespace: common
result: pass
start: 1.4.3.23 test-install-overlay (timeout 01:26:11) [common]
end: 1.4.3.23 test-install-overlay (duration 00:00:00) [common]
case: test-install-overlay
case_id: 4043774
definition: lava
duration: 0.01
extra: ...
level: 1.4.3.23
namespace: common
result: pass
start: 1.4.3.24 test-runscript-overlay (timeout 01:26:11) [common]
runner path: /var/lib/lava/dispatcher/tmp/36332/lava-overlay-f_05__gj/lava-36332/3/tests/5_ci-dumpjournal test_uuid 36332_1.4.3.21
end: 1.4.3.24 test-runscript-overlay (duration 00:00:00) [common]
case: test-runscript-overlay
case_id: 4043775
definition: lava
duration: 0.01
extra: ...
level: 1.4.3.24
namespace: common
result: pass
Creating lava-test-runner.conf files
Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/36332/lava-overlay-f_05__gj/lava-36332/0 for stage 0
- 0_network-veth
Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/36332/lava-overlay-f_05__gj/lava-36332/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/36332/lava-overlay-f_05__gj/lava-36332/2 for stage 2
- 4_screenshooter
Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/36332/lava-overlay-f_05__gj/lava-36332/3 for stage 3
- 5_ci-dumpjournal
end: 1.4.3 test-definition (duration 00:00:05) [common]
start: 1.4.4 compress-overlay (timeout 01:26:11) [common]
end: 1.4.4 compress-overlay (duration 00:00:01) [common]
start: 1.4.5 persistent-nfs-overlay (timeout 01:26:10) [common]
end: 1.4.5 persistent-nfs-overlay (duration 00:00:00) [common]
end: 1.4 lava-overlay (duration 00:00:05) [common]
start: 1.5 prepare-kernel (timeout 01:26:10) [common]
end: 1.5 prepare-kernel (duration 00:00:00) [common]
start: 1.6 xnbd-server-deploy (timeout 01:26:10) [common]
xnbd-server-deploy: starting nbd-server
NBD-IP: 192.168.66.1, NBD-PORT: 61966, NBD-ROOT: 36332/nbd-deploy-tcfvcf6u/nbdroot/agl-ivi-demo-qt-qemux86-64.ext4
nbd-server 61966 /var/lib/lava/dispatcher/tmp/36332/nbd-deploy-tcfvcf6u/nbdroot/agl-ivi-demo-qt-qemux86-64.ext4
output:
output: ** (process:7563): WARNING **: 00:26:30.049: Specifying an export on the command line no longer uses the oldstyle protocol.
output:
xnbd-server-deploy: starting nbd-server done
end: 1.6 xnbd-server-deploy (duration 00:00:00) [common]
end: 1 nbd-deploy (duration 00:03:50) [common]
start: 2 grub-main-action (timeout 00:20:00) [common]
start: 2.1 bootloader-from-media (timeout 00:20:00) [common]
end: 2.1 bootloader-from-media (duration 00:00:00) [common]
start: 2.2 bootloader-overlay (timeout 00:20:00) [common]
substitutions:
- {DTB}: None
- {INITRD}: 36332/nbd-deploy-tcfvcf6u/initrd/initramfs-netboot-image-qemux86-64.ext4.gz
- {KERNEL}: 36332/nbd-deploy-tcfvcf6u/kernel/bzImage
- {LAVA_MAC}: None
- {NBDSERVERIP}: 192.168.66.1
- {NBDSERVERPORT}: 61966
- {PRESEED_CONFIG}: None
- {PRESEED_LOCAL}: None
- {RAMDISK}: 36332/nbd-deploy-tcfvcf6u/initrd/initramfs-netboot-image-qemux86-64.ext4.gz
- {ROOT_PART}: None
- {ROOT}: None
- {SERVER_IP}: 192.168.66.1
- {TEE}: None
Parsed boot commands:
- clear
- insmod linux
- clear
- insmod tftp
- clear
- linux (tftp,192.168.66.1)/36332/nbd-deploy-tcfvcf6u/kernel/bzImage console=ttyS0,115200 ip=dhcp nbd.server=192.168.66.1 nbd.port=61966 root=/dev/ram0 ramdisk_size=16384 rootdelay=7 rw initrd=initramfs-netboot-image-qemux86-64.ext4.gz systemd.mask=systemd-network-generator.service
- clear
- initrd (tftp,192.168.66.1)/36332/nbd-deploy-tcfvcf6u/initrd/initramfs-netboot-image-qemux86-64.ext4.gz
- boot
end: 2.2 bootloader-overlay (duration 00:00:00) [common]
start: 2.3 connect-device (timeout 00:20:00) [common]
[common] connect-device Connecting to device using 'telnet 127.0.0.1 63002'
Setting prompt string to ['lava-test: # ']
end: 2.3 connect-device (duration 00:00:03) [common]
start: 2.4 reset-device (timeout 00:19:57) [common]
start: 2.4.1 pdu-reboot (timeout 00:19:57) [common]
Calling: '/usr/local/bin/acme-cli' '-s' '192.168.66.2' 'reset' '5'
>> Success
>> Success
Returned 0 in 4 seconds
end: 2.4.1 pdu-reboot (duration 00:00:05) [common]
case: pdu-reboot
case_id: 4043776
definition: lava
duration: 4.54
extra: ...
level: 2.4.1
namespace: common
result: pass
end: 2.4 reset-device (duration 00:00:05) [common]
start: 2.5 bootloader-interrupt (timeout 00:19:52) [common]
Setting prompt string to ['for a command-line']
bootloader-interrupt: Wait for prompt ['for a command-line'] (timeout 00:20:00)
Trying 127.0.0.1...
Connected to 127.0.0.1.
Escape character is '^]'.
[2J[1;1H[2J[1;1H[1;1H[0;37;40m[2J[1;1H[1;37;40m[2J[1;1H[1;1H
>>Checking Media Presence......
>>No Media Present......[2J[1;1H[1;1H
>>Checking Media Presence......
>>Media Present......
>>Start PXE over IPv4.
Station IP address is 192.168.66.55
Server IP address is 192.168.66.1
NBP filename is /boot/grub/x86_64-efi/core.efi
NBP filesize is 245760 Bytes[2J[1;1H[1;1H
>>Checking Media Presence......
>>Media Present......
Downloading NBP file...
Succeed to download NBP file.
[0;30;47mWelcome to GRUB!
[0;37;40m[0;30;40m[2J[1;1H[0;37;40m[2;24HGNU GRUB version 2.06-13+deb12u1
[4;2H����������������������������������������������������������������������������Ŀ[5;2H�[5;79H�[6;2H�[6;79H�[7;2H�[7;79H�[8;2H�[8;79H�[9;2H�[9;79H�[10;2H�[10;79H�[11;2H�[11;79H�[12;2H�[12;79H�[13;2H�[13;79H�[14;2H�[14;79H�[15;2H�[15;79H�[16;2H�[16;79H�[17;2H�[17;79H�[18;2H������������������������������������������������������������������������������[19;2H[20;2H Use the and keys to select which entry is highlighted.
Press enter to boot the selected OS, `e' to edit the commands
end: 2.5 bootloader-interrupt (duration 00:00:23) [common]
start: 2.6 bootloader-commands (timeout 00:19:29) [common]
Setting prompt string to ['grub>']
bootloader-commands: Wait for prompt ['grub>'] (timeout 00:19:29)
before booting or `c' for a command-line. [5;80H [0;30;47m[5;3H*fake menu [0;37;40m[5;78H[6;3H [6;78H[7;3H c [7;78H[8;3H [8;78H[9;3H [9;78H[10;3H [10;78H[11;3H [11;78H[12;3H [12;78H[13;3H [13;78H[14;3H [14;78H[15;3H [15;78H[16;3H [16;78H[17;3H [17;78H[17;80H [5;78H[23;1H [24;1H [5;78H[0;30;40m[2J[1;1H[0;37;40m[0;30;40m[2J[1;1H[0;37;40m[2;24HGNU GRUB version 2.06-13+deb12u1
Minimal BASH-like line editing is supported. For the first word, TAB
lists possible command completions. Anywhere else TAB lists possible
device or file completions. ESC at any time exits.
Setting prompt string to ['grub>', 'error: missing (.*) symbol.']
clear
grub> clear
bootloader-commands: Wait for prompt ['grub>', 'error: missing (.*) symbol.'] (timeout 00:19:29)
[9;7Hc[9;8H[9;8Hl[9;9H[9;9He[9;10H[9;10Ha[9;11H[9;11Hr[9;12H
insmod linux
[0;30;40m[2J[1;1H[0;37;40mgrub> insmod linux
bootloader-commands: Wait for prompt ['grub>', 'error: missing (.*) symbol.'] (timeout 00:19:29)
[1;7Hi[1;8H[1;8Hn[1;9H[1;9Hs[1;10H[1;10Hm[1;11H[1;11Ho[1;12H[1;12Hd[1;13H[1;13H [1;14H[1;14Hl[1;15H[1;15Hi[1;16H[1;16Hn[1;17H[1;17Hu[1;18H[1;18Hx[1;19H
clear
grub> clear
bootloader-commands: Wait for prompt ['grub>', 'error: missing (.*) symbol.'] (timeout 00:19:29)
[2;7Hc[2;8H[2;8Hl[2;9H[2;9He[2;10H[2;10Ha[2;11H[2;11Hr[2;12H
insmod tftp
[0;30;40m[2J[1;1H[0;37;40mgrub> insmod tftp
bootloader-commands: Wait for prompt ['grub>', 'error: missing (.*) symbol.'] (timeout 00:19:29)
[1;7Hi[1;8H[1;8Hn[1;9H[1;9Hs[1;10H[1;10Hm[1;11H[1;11Ho[1;12H[1;12Hd[1;13H[1;13H [1;14H[1;14Ht[1;15H[1;15Hf[1;16H[1;16Ht[1;17H[1;17Hp[1;18H
clear
grub> clear
bootloader-commands: Wait for prompt ['grub>', 'error: missing (.*) symbol.'] (timeout 00:19:29)
[2;7Hc[2;8H[2;8Hl[2;9H[2;9He[2;10H[2;10Ha[2;11H[2;11Hr[2;12H
linux (tftp,192.168.66.1)/36332/nbd-deploy-tcfvcf6u/kernel/bzImage console=ttyS0,115200 ip=dhcp nbd.server=192.168.66.1 nbd.port=61966 root=/dev/ram0 ramdisk_size=16384 rootdelay=7 rw initrd=initramfs-netboot-image-qemux86-64.ext4.gz systemd.mask=systemd-network-generator.service
[0;30;40m[2J[1;1H[0;37;40mgrub> linux (tftp,192.168.66.1)/36332/nbd-deploy-tcfvcf6u/kernel/bzImage console=ttyS0,115200 ip=dhcp nbd.server=192.168.66.1 nbd.port=61966 root=/dev/ram0 ramdisk_size=16384 rootdelay=7 rw initrd=initramfs-netboot-image-qemux86-64.ext4.gz systemd.mask=systemd-network-generator.service
bootloader-commands: Wait for prompt ['grub>', 'error: missing (.*) symbol.'] (timeout 00:19:28)
[1;7Hl[1;8H[1;8Hn[1;9H[1;9Hu[1;10H[1;10Hx[1;11H[1;11H [1;12H[1;12H([1;13H[1;13Ht[1;14H[1;14Hf[1;15H[1;15Ht[1;16H[1;16Hp[1;17H[1;17H,[1;18H[1;18H1[1;19H[1;19H9[1;20H[1;20H2[1;21H[1;21H.[1;22H[1;22H1[1;23H[1;23H6[1;24H[1;24H8[1;25H[1;25H.[1;26H[1;26H6[1;27H[1;27H6[1;28H[1;28H.[1;29H[1;29H1[1;30H[1;30H)[1;31H[1;31H/[1;32H[1;32H3[1;33H[1;33H6[1;34H[1;34H3[1;35H[1;35H3[1;36H[1;36H2[1;37H[1;37H/[1;38H[1;38Hn[1;39H[1;39Hb[1;40H[1;40Hd[1;41H[1;41H-[1;42H[1;42Hd[1;43H[1;43He[1;44H[1;44Hp[1;45H[1;45Hl[1;46H[1;46Ho[1;47H[1;47Hy[1;48H[1;48H-[1;49H[1;49Ht[1;50H[1;50Hc[1;51H[1;51Hf[1;52H[1;52Hv[1;53H[1;53Hc[1;54H[1;54Hf[1;55H[1;55H6[1;56H[1;56Hu[1;57H[1;57H/[1;58H[1;58Hk[1;59H[1;59He[1;60H[1;60Hr[1;61H[1;61Hn[1;62H[1;62He[1;63H[1;63Hl[1;64H[1;64H/[1;65H[1;65Hb[1;66H[1;66Hz[1;67H[1;67HI[1;68H[1;68Hm[1;69H[1;69Ha[1;70H[1;70Hg[1;71H[1;71He[1;72H[1;72H [1;73H[1;73Hc[1;74H[1;74Ho[1;75H[1;75Hn[1;76H[1;76Hs[1;77H[1;77Ho[1;78H[1;78Hl[1;79H[1;79He
[1;80H[1;80H=
[2;1H[2;1Ht[2;2H[2;2Ht[2;3H[2;3Hy[2;4H[2;4HS[2;5H[2;5H0[2;6H[2;6H,[2;7H[2;7H1[2;8H[2;8H1[2;9H[2;9H5[2;10H[2;10H2[2;11H[2;11H0[2;12H[2;12H0[2;13H[2;13H [2;14H[2;14Hi[2;15H[2;15Hp[2;16H[2;16H=[2;17H[2;17Hd[2;18H[2;18Hh[2;19H[2;19Hc[2;20H[2;20Hp[2;21H[2;21H [2;22H[2;22Hn[2;23H[2;23Hb[2;24H[2;24Hd[2;25H[2;25H.[2;26H[2;26Hs[2;27H[2;27He[2;28H[2;28Hr[2;29H[2;29Hv[2;30H[2;30He[2;31H[2;31Hr[2;32H[2;32H=[2;33H[2;33H1[2;34H[2;34H9[2;35H[2;35H2[2;36H[2;36H.[2;37H[2;37H1[2;38H[2;38H6[2;39H[2;39H8[2;40H[2;40H.[2;41H[2;41H6[2;42H[2;42H6[2;43H[2;43H.[2;44H[2;44H1[2;45H[2;45H [2;46H[2;46Hn[2;47H[2;47Hb[2;48H[2;48Hd[2;49H[2;49H.[2;50H[2;50Hp[2;51H[2;51Ho[2;52H[2;52Hr[2;53H[2;53Ht[2;54H[2;54H=[2;55H[2;55H6[2;56H[2;56H1[2;57H[2;57H9[2;58H[2;58H6[2;59H[2;59H6[2;60H[2;60H [2;61H[2;61Hr[2;62H[2;62Ho[2;63H[2;63Ho[2;64H[2;64Ht[2;65H[2;65H=[2;66H[2;66H/[2;67H[2;67Hd[2;68H[2;68He[2;69H[2;69Hv[2;70H[2;70H/[2;71H[2;71Hr[2;72H[2;72Ha[2;73H[2;73Hm[2;74H[2;74H0[2;75H[2;75H [2;76H[2;76Hr[2;77H[2;77Ha[2;78H[2;78Hm[2;79H[2;79Hd
[2;80H[2;80Hi
[3;1H[3;1Hs[3;2H[3;2Hk[3;3H[3;3H_[3;4H[3;4Hs[3;5H[3;5Hi[3;6H[3;6Hz[3;7H[3;7He[3;8H[3;8H=[3;9H[3;9H1[3;10H[3;10H6[3;11H[3;11H3[3;12H[3;12H8[3;13H[3;13H4[3;14H[3;14H [3;15H[3;15Hr[3;16H[3;16Ho[3;17H[3;17Ho[3;18H[3;18Ht[3;19H[3;19Hd[3;20H[3;20He[3;21H[3;21Hl[3;22H[3;22Ha[3;23H[3;23Hy[3;24H[3;24H=[3;25H[3;25H7[3;26H[3;26H [3;27H[3;27H [3;28H[3;28Hr[3;29H[3;29Hw[3;30H[3;30H [3;31H[3;31Hi[3;32H[3;32Hn[3;33H[3;33Hi[3;34H[3;34Ht[3;35H[3;35Hr[3;36H[3;36Hd[3;37H[3;37H=[3;38H[3;38Hi[3;39H[3;39Hn[3;40H[3;40Hi[3;41H[3;41Ht[3;42H[3;42Hr[3;43H[3;43Ha[3;44H[3;44Hm[3;45H[3;45Hf[3;46H[3;46Hs[3;47H[3;47H-[3;48H[3;48Hn[3;49H[3;49He[3;50H[3;50Ht[3;51H[3;51Hb[3;52H[3;52Ho[3;53H[3;53Ho[3;54H[3;54Ht[3;55H[3;55H-[3;56H[3;56Hi[3;57H[3;57Hm[3;58H[3;58Ha[3;59H[3;59Hg[3;60H[3;60He[3;61H[3;61H-[3;62H[3;62Hq[3;63H[3;63He[3;64H[3;64Hm[3;65H[3;65Hu[3;66H[3;66Hx[3;67H[3;67H8[3;68H[3;68H6[3;69H[3;69H-[3;70H[3;70H6[3;71H[3;71H4[3;72H[3;72H.[3;73H[3;73He[3;74H[3;74Hx[3;75H[3;75Ht[3;76H[3;76H4[3;77H[3;77H.[3;78H[3;78Hg[3;79H[3;79Hz
[3;80H[3;80H
[4;1H[4;1Hs[4;2H[4;2Hy[4;3H[4;3Hs[4;4H[4;4Ht[4;5H[4;5He[4;6H[4;6Hm[4;7H[4;7Hd[4;8H[4;8H.[4;9H[4;9Hm[4;10H[4;10Ha[4;11H[4;11Hs[4;12H[4;12Hk[4;13H[4;13H=[4;14H[4;14Hs[4;15H[4;15Hy[4;16H[4;16Hs[4;17H[4;17Ht[4;18H[4;18He[4;19H[4;19Hm[4;20H[4;20Hd[4;21H[4;21H-[4;22H[4;22Hn[4;23H[4;23He[4;24H[4;24Ht[4;25H[4;25Hw[4;26H[4;26Ho[4;27H[4;27Hr[4;28H[4;28Hk[4;29H[4;29H-[4;30H[4;30Hg[4;31H[4;31He[4;32H[4;32Hn[4;33H[4;33He[4;34H[4;34Hr[4;35H[4;35Ha[4;36H[4;36Ht[4;37H[4;37Ho[4;38H[4;38Hr[4;39H[4;39H.[4;40H[4;40Hs[4;41H[4;41He[4;42H[4;42Hr[4;43H[4;43Hv[4;44H[4;44Hi[4;45H[4;45Hc[4;46H[4;46He[4;47H
error: can't find command `lnux'.
clear
grub> clear
bootloader-commands: Wait for prompt ['grub>', 'error: missing (.*) symbol.'] (timeout 00:19:28)
[6;7Hc[6;8H[6;8Hl[6;9H[6;9He[6;10H[6;10Ha[6;11H[6;11Hr[6;12H
initrd (tftp,192.168.66.1)/36332/nbd-deploy-tcfvcf6u/initrd/initramfs-netboot-image-qemux86-64.ext4.gz
[0;30;40m[2J[1;1H[0;37;40mgrub> initrd (tftp,192.168.66.1)/36332/nbd-deploy-tcfvcf6u/initrd/initramfs-netboot-image-qemux86-64.ext4.gz
bootloader-commands: Wait for prompt ['grub>', 'error: missing (.*) symbol.'] (timeout 00:19:28)
[1;7Hi[1;8H[1;8Hn[1;9H[1;9Hi[1;10H[1;10Ht[1;11H[1;11Hr[1;12H[1;12Hd[1;13H[1;13H [1;14H[1;14H([1;15H[1;15Ht[1;16H[1;16Hf[1;17H[1;17Ht[1;18H[1;18Hp[1;19H[1;19H,[1;20H[1;20H1[1;21H[1;21H9[1;22H[1;22H2[1;23H[1;23H.[1;24H[1;24H1[1;25H[1;25H6[1;26H[1;26H8[1;27H[1;27H.[1;28H[1;28H6[1;29H[1;29H6[1;30H[1;30H.[1;31H[1;31H1[1;32H[1;32H)[1;33H[1;33H/[1;34H[1;34H3[1;35H[1;35H6[1;36H[1;36H3[1;37H[1;37H3[1;38H[1;38H2[1;39H[1;39H/[1;40H[1;40Hn[1;41H[1;41Hb[1;42H[1;42Hd[1;43H[1;43H-[1;44H[1;44Hd[1;45H[1;45He[1;46H[1;46Hp[1;47H[1;47Hl[1;48H[1;48Ho[1;49H[1;49Hy[1;50H[1;50H-[1;51H[1;51Ht[1;52H[1;52Hc[1;53H[1;53Hf[1;54H[1;54Hv[1;55H[1;55Hc[1;56H[1;56Hf[1;57H[1;57H6[1;58H[1;58Hu[1;59H[1;59H/[1;60H[1;60Hi[1;61H[1;61Hn[1;62H[1;62Hi[1;63H[1;63Ht[1;64H[1;64Hr[1;65H[1;65Hd[1;66H[1;66H/[1;67H[1;67Hi[1;68H[1;68Hn[1;69H[1;69Hi[1;70H[1;70Ht[1;71H[1;71Hr[1;72H[1;72Ha[1;73H[1;73Hm[1;74H[1;74Hf[1;75H[1;75Hs[1;76H[1;76H-[1;77H[1;77Hn[1;78H[1;78He[1;79H[1;79Ht
[1;80H[1;80Hb
[2;1H[2;1Ho[2;2H[2;2Ho[2;3H[2;3Ht[2;4H[2;4H-[2;5H[2;5Hi[2;6H[2;6Hm[2;7H[2;7Ha[2;8H[2;8Hg[2;9H[2;9He[2;10H[2;10H-[2;11H[2;11Hq[2;12H[2;12He[2;13H[2;13Hm[2;14H[2;14Hu[2;15H[2;15Hx[2;16H[2;16H8[2;17H[2;17H6[2;18H[2;18H-[2;19H[2;19H6[2;20H[2;20H4[2;21H[2;21H.[2;22H[2;22He[2;23H[2;23Hx[2;24H[2;24Ht[2;25H[2;25H4[2;26H[2;26H.[2;27H[2;27Hg[2;28H[2;28Hz[2;29H
error: you need to load the kernel first.
boot
grub> boot
end: 2.6 bootloader-commands (duration 00:00:02) [common]
start: 2.7 auto-login-action (timeout 00:19:28) [common]
Setting prompt string to ['Linux version [0-9]']
Setting prompt string to ['Linux version [0-9]', 'error: missing (.*) symbol.']
auto-login-action: Wait for prompt ['Linux version [0-9]', 'error: missing (.*) symbol.'] (timeout 00:20:00)
[4;7Hb[4;8H[4;8Ho[4;9H[4;9Ho[4;10H[4;10Ht[4;11H
error: you need to load the kernel first.
auto-login-action timed out after 1168 seconds
end: 2.7 auto-login-action (duration 00:19:28) [common]
case: auto-login-action
case_id: 4043859
definition: lava
duration: 1168.00
extra: ...
level: 2.7
namespace: common
result: fail
grub-main-action failed: 1 of 1 attempts. 'auto-login-action timed out after 1168 seconds'
auto-login-action timed out after 1168 seconds
end: 2 grub-main-action (duration 00:20:00) [common]
case: grub-main-action
case_id: 4043860
definition: lava
duration: 1200.45
extra: ...
level: 2
namespace: common
result: fail
Cleaning after the job
Cleaning up download directory: /var/lib/lava/dispatcher/tmp/36332/nbd-deploy-tcfvcf6u/initrd
Cleaning up download directory: /var/lib/lava/dispatcher/tmp/36332/nbd-deploy-tcfvcf6u/kernel
Cleaning up download directory: /var/lib/lava/dispatcher/tmp/36332/nbd-deploy-tcfvcf6u/nbdroot
start: 7.1 power-off (timeout 00:00:10) [common]
Calling: '/usr/local/bin/acme-cli' '-s' '192.168.66.2' 'switch_off' '5'
>> Success
Returned 0 in 3 seconds
end: 7.1 power-off (duration 00:00:03) [common]
case: power-off
case_id: 4043861
definition: lava
duration: 3.46
extra: ...
level: 7.1
namespace: common
result: pass
start: 7.2 read-feedback (timeout 00:29:57) [common]
Listened to connection for namespace 'common' for up to 1s
Finalising connection for namespace 'common'
Disconnecting from shell: Finalise
grub>
end: 7.2 read-feedback (duration 00:00:01) [common]
lava-xnbd cleanup
clean NBD port 61966
Override tmp directory removed at /var/lib/lava/dispatcher/tmp/36332
Root tmp directory removed at /var/lib/lava/dispatcher/tmp/36332
JobError: Your job cannot terminate cleanly.
case: job
case_id: 4043862
definition: lava
error_msg: auto-login-action timed out after 1168 seconds
error_type: Job
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.