lava-dispatcher, installed at version: 2023.01 start: 0 validate Start time: 2023-03-02 07:25:12.849140+00:00 (UTC) Validating that http://download.automotivelinux.org/AGL/upload/ci/28489/1/bbe/initramfs-netboot-image-bbe.ext4.gz exists Validating that http://download.automotivelinux.org/AGL/upload/ci/28489/1/bbe/zImage exists Validating that http://download.automotivelinux.org/AGL/upload/ci/28489/1/bbe/am335x-sancloud-bbe.dtb exists Validating that http://download.automotivelinux.org/AGL/upload/ci/28489/1/bbe/agl-telematics-demo-platform-bbe.ext4.xz exists validate duration: 0.91 case: validate
case_id: 3882234
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 61964 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/28489/1/bbe/initramfs-netboot-image-bbe.ext4.gz saving as /var/lib/lava/dispatcher/tmp/32055/nbd-deploy-6piwujal/initrd/initramfs-netboot-image-bbe.ext4.gz total size: 1677454 (1MB) No compression specified progress 1% (0MB) progress 7% (0MB) progress 13% (0MB) progress 19% (0MB) progress 25% (0MB) progress 31% (0MB) progress 37% (0MB) progress 42% (0MB) progress 48% (0MB) progress 54% (0MB) progress 60% (0MB) progress 66% (1MB) progress 72% (1MB) progress 78% (1MB) progress 83% (1MB) progress 89% (1MB) progress 95% (1MB) progress 100% (1MB) 1MB downloaded in 0.89s (1.79MB/s) end: 1.1.1 http-download (duration 00:00:01) [common] case: http-download
case_id: 3882270
definition: lava
duration: 0.90
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/28489/1/bbe/zImage saving as /var/lib/lava/dispatcher/tmp/32055/nbd-deploy-6piwujal/kernel/zImage total size: 4571400 (4MB) No compression specified progress 0% (0MB) progress 5% (0MB) progress 10% (0MB) progress 15% (0MB) progress 20% (0MB) progress 25% (1MB) progress 30% (1MB) progress 35% (1MB) progress 40% (1MB) progress 45% (1MB) progress 50% (2MB) progress 55% (2MB) progress 60% (2MB) progress 65% (2MB) progress 70% (3MB) progress 75% (3MB) progress 80% (3MB) progress 85% (3MB) progress 90% (3MB) progress 95% (4MB) progress 100% (4MB) 4MB downloaded in 1.88s (2.31MB/s) end: 1.2.1 http-download (duration 00:00:02) [common] case: http-download
case_id: 3882270
definition: lava
duration: 1.89
extra: ...
level: 1.2.1
namespace: common
result: pass
end: 1.2 download-retry (duration 00:00:02) [common] start: 1.3 download-retry (timeout 01:29:57) [common] start: 1.3.1 http-download (timeout 01:29:57) [common] downloading http://download.automotivelinux.org/AGL/upload/ci/28489/1/bbe/am335x-sancloud-bbe.dtb saving as /var/lib/lava/dispatcher/tmp/32055/nbd-deploy-6piwujal/dtb/am335x-sancloud-bbe.dtb total size: 93513 (0MB) No compression specified progress 35% (0MB) progress 70% (0MB) progress 100% (0MB) 0MB downloaded in 0.41s (0.22MB/s) end: 1.3.1 http-download (duration 00:00:00) [common] case: http-download
case_id: 3882270
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:57) [common] start: 1.4.1 http-download (timeout 01:29:57) [common] downloading http://download.automotivelinux.org/AGL/upload/ci/28489/1/bbe/agl-telematics-demo-platform-bbe.ext4.xz saving as /var/lib/lava/dispatcher/tmp/32055/nbd-deploy-6piwujal/nbdroot/agl-telematics-demo-platform-bbe.ext4 total size: 242811716 (231MB) Using unxz to decompress xz progress 0% (0MB) progress 5% (11MB) progress 10% (23MB) progress 15% (34MB) progress 20% (46MB) progress 25% (57MB) progress 30% (69MB) progress 35% (81MB) progress 40% (92MB) progress 45% (104MB) progress 50% (115MB) progress 55% (127MB) progress 60% (138MB) progress 65% (150MB) progress 70% (162MB) progress 75% (173MB) progress 80% (185MB) progress 85% (196MB) progress 90% (208MB) progress 95% (220MB) progress 100% (231MB) 231MB downloaded in 68.93s (3.36MB/s) end: 1.4.1 http-download (duration 00:01:09) [common] case: http-download
case_id: 3882270
definition: lava
duration: 68.93
extra: ...
level: 1.4.1
namespace: common
result: pass
end: 1.4 download-retry (duration 00:01:09) [common] start: 1.5 lava-overlay (timeout 01:28:48) [common] [common] Preparing overlay tarball in /var/lib/lava/dispatcher/tmp/32055/lava-overlay-mk7lwgnc makedir: /var/lib/lava/dispatcher/tmp/32055/lava-overlay-mk7lwgnc/lava-32055/bin makedir: /var/lib/lava/dispatcher/tmp/32055/lava-overlay-mk7lwgnc/lava-32055/tests makedir: /var/lib/lava/dispatcher/tmp/32055/lava-overlay-mk7lwgnc/lava-32055/results Creating /var/lib/lava/dispatcher/tmp/32055/lava-overlay-mk7lwgnc/lava-32055/bin/lava-add-keys Creating /var/lib/lava/dispatcher/tmp/32055/lava-overlay-mk7lwgnc/lava-32055/bin/lava-add-sources Creating /var/lib/lava/dispatcher/tmp/32055/lava-overlay-mk7lwgnc/lava-32055/bin/lava-background-process-start Creating /var/lib/lava/dispatcher/tmp/32055/lava-overlay-mk7lwgnc/lava-32055/bin/lava-background-process-stop Creating /var/lib/lava/dispatcher/tmp/32055/lava-overlay-mk7lwgnc/lava-32055/bin/lava-common-functions Creating /var/lib/lava/dispatcher/tmp/32055/lava-overlay-mk7lwgnc/lava-32055/bin/lava-echo-ipv4 Creating /var/lib/lava/dispatcher/tmp/32055/lava-overlay-mk7lwgnc/lava-32055/bin/lava-install-packages Creating /var/lib/lava/dispatcher/tmp/32055/lava-overlay-mk7lwgnc/lava-32055/bin/lava-installed-packages Creating /var/lib/lava/dispatcher/tmp/32055/lava-overlay-mk7lwgnc/lava-32055/bin/lava-os-build Creating /var/lib/lava/dispatcher/tmp/32055/lava-overlay-mk7lwgnc/lava-32055/bin/lava-probe-channel Creating /var/lib/lava/dispatcher/tmp/32055/lava-overlay-mk7lwgnc/lava-32055/bin/lava-probe-ip Creating /var/lib/lava/dispatcher/tmp/32055/lava-overlay-mk7lwgnc/lava-32055/bin/lava-target-ip Creating /var/lib/lava/dispatcher/tmp/32055/lava-overlay-mk7lwgnc/lava-32055/bin/lava-target-mac Creating /var/lib/lava/dispatcher/tmp/32055/lava-overlay-mk7lwgnc/lava-32055/bin/lava-target-storage Creating /var/lib/lava/dispatcher/tmp/32055/lava-overlay-mk7lwgnc/lava-32055/bin/lava-test-case Creating /var/lib/lava/dispatcher/tmp/32055/lava-overlay-mk7lwgnc/lava-32055/bin/lava-test-event Creating /var/lib/lava/dispatcher/tmp/32055/lava-overlay-mk7lwgnc/lava-32055/bin/lava-test-feedback Creating /var/lib/lava/dispatcher/tmp/32055/lava-overlay-mk7lwgnc/lava-32055/bin/lava-test-raise Creating /var/lib/lava/dispatcher/tmp/32055/lava-overlay-mk7lwgnc/lava-32055/bin/lava-test-reference Creating /var/lib/lava/dispatcher/tmp/32055/lava-overlay-mk7lwgnc/lava-32055/bin/lava-test-runner Creating /var/lib/lava/dispatcher/tmp/32055/lava-overlay-mk7lwgnc/lava-32055/bin/lava-test-set Creating /var/lib/lava/dispatcher/tmp/32055/lava-overlay-mk7lwgnc/lava-32055/bin/lava-test-shell Updating /var/lib/lava/dispatcher/tmp/32055/lava-overlay-mk7lwgnc/lava-32055/bin/lava-install-packages (oe) Updating /var/lib/lava/dispatcher/tmp/32055/lava-overlay-mk7lwgnc/lava-32055/bin/lava-installed-packages (oe) Creating /var/lib/lava/dispatcher/tmp/32055/lava-overlay-mk7lwgnc/lava-32055/environment LAVA metadata - LAVA_JOB_ID=32055 - LAVA_DISPATCHER_IP=192.168.66.1 start: 1.5.1 ssh-authorize (timeout 01:28:48) [common] end: 1.5.1 ssh-authorize (duration 00:00:00) [common] start: 1.5.2 lava-vland-overlay (timeout 01:28:48) [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:28:48) [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:28:48) [common] Loading test definitions start: 1.5.4.1 git-repo-action (timeout 01:28:48) [common] Using /lava-32055 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/32055/lava-overlay-mk7lwgnc/lava-32055/0/tests/0_network-veth' Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/32055/lava-overlay-mk7lwgnc/lava-32055/0/tests/0_network-veth/test-suites/short-smoke/network-veth.yaml uuid=32055_1.5.4.1 testdef=None end: 1.5.4.1 git-repo-action (duration 00:00:03) [common] case: git-repo-action
case_id: 3882287
definition: lava
duration: 3.40
extra: ...
level: 1.5.4.1
namespace: common
result: pass
start: 1.5.4.2 test-overlay (timeout 01:28:44) [common] end: 1.5.4.2 test-overlay (duration 00:00:00) [common] case: test-overlay
case_id: 3882288
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:28:44) [common] end: 1.5.4.3 test-install-overlay (duration 00:00:00) [common] case: test-install-overlay
case_id: 3882289
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:28:44) [common] runner path: /var/lib/lava/dispatcher/tmp/32055/lava-overlay-mk7lwgnc/lava-32055/0/tests/0_network-veth test_uuid 32055_1.5.4.1 end: 1.5.4.4 test-runscript-overlay (duration 00:00:00) [common] case: test-runscript-overlay
case_id: 3882290
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:28:44) [common] Using /lava-32055 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/32055/lava-overlay-mk7lwgnc/lava-32055/1/tests/1_ci-busybox' Removing '.git' directory in /var/lib/lava/dispatcher/tmp/32055/lava-overlay-mk7lwgnc/lava-32055/1/tests/1_ci-busybox Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/32055/lava-overlay-mk7lwgnc/lava-32055/1/tests/1_ci-busybox/test-suites/short-smoke/busybox.yaml uuid=32055_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:02) [common] case: git-repo-action
case_id: 3882287
definition: lava
duration: 1.67
extra: ...
level: 1.5.4.5
namespace: common
result: pass
start: 1.5.4.6 test-overlay (timeout 01:28:43) [common] end: 1.5.4.6 test-overlay (duration 00:00:00) [common] case: test-overlay
case_id: 3882288
definition: lava
duration: 0.01
extra: ...
level: 1.5.4.6
namespace: common
result: pass
start: 1.5.4.7 test-install-overlay (timeout 01:28:43) [common] end: 1.5.4.7 test-install-overlay (duration 00:00:00) [common] case: test-install-overlay
case_id: 3882289
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:28:43) [common] runner path: /var/lib/lava/dispatcher/tmp/32055/lava-overlay-mk7lwgnc/lava-32055/1/tests/1_ci-busybox test_uuid 32055_1.5.4.5 end: 1.5.4.8 test-runscript-overlay (duration 00:00:00) [common] case: test-runscript-overlay
case_id: 3882290
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:28:43) [common] Using /lava-32055 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/32055/lava-overlay-mk7lwgnc/lava-32055/1/tests/2_ci-smoke-tests-basic' Removing '.git' directory in /var/lib/lava/dispatcher/tmp/32055/lava-overlay-mk7lwgnc/lava-32055/1/tests/2_ci-smoke-tests-basic Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/32055/lava-overlay-mk7lwgnc/lava-32055/1/tests/2_ci-smoke-tests-basic/test-suites/short-smoke/smoke-tests-basic.yaml uuid=32055_1.5.4.9 testdef=None end: 1.5.4.9 git-repo-action (duration 00:00:02) [common] case: git-repo-action
case_id: 3882287
definition: lava
duration: 1.66
extra: ...
level: 1.5.4.9
namespace: common
result: pass
start: 1.5.4.10 test-overlay (timeout 01:28:41) [common] end: 1.5.4.10 test-overlay (duration 00:00:00) [common] case: test-overlay
case_id: 3882288
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:28:41) [common] end: 1.5.4.11 test-install-overlay (duration 00:00:00) [common] case: test-install-overlay
case_id: 3882289
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:28:41) [common] runner path: /var/lib/lava/dispatcher/tmp/32055/lava-overlay-mk7lwgnc/lava-32055/1/tests/2_ci-smoke-tests-basic test_uuid 32055_1.5.4.9 end: 1.5.4.12 test-runscript-overlay (duration 00:00:00) [common] case: test-runscript-overlay
case_id: 3882290
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:28:41) [common] Using /lava-32055 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/32055/lava-overlay-mk7lwgnc/lava-32055/1/tests/3_ci-service-check' Removing '.git' directory in /var/lib/lava/dispatcher/tmp/32055/lava-overlay-mk7lwgnc/lava-32055/1/tests/3_ci-service-check Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/32055/lava-overlay-mk7lwgnc/lava-32055/1/tests/3_ci-service-check/test-suites/short-smoke/service-check.yaml uuid=32055_1.5.4.13 testdef=None end: 1.5.4.13 git-repo-action (duration 00:00:02) [common] case: git-repo-action
case_id: 3882287
definition: lava
duration: 1.64
extra: ...
level: 1.5.4.13
namespace: common
result: pass
start: 1.5.4.14 test-overlay (timeout 01:28:39) [common] end: 1.5.4.14 test-overlay (duration 00:00:00) [common] case: test-overlay
case_id: 3882288
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:28:39) [common] end: 1.5.4.15 test-install-overlay (duration 00:00:00) [common] case: test-install-overlay
case_id: 3882289
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:28:39) [common] runner path: /var/lib/lava/dispatcher/tmp/32055/lava-overlay-mk7lwgnc/lava-32055/1/tests/3_ci-service-check test_uuid 32055_1.5.4.13 BUILD_TAGS='' DEVICE_TAGS='' end: 1.5.4.16 test-runscript-overlay (duration 00:00:00) [common] case: test-runscript-overlay
case_id: 3882290
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:28:39) [common] Using /lava-32055 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/32055/lava-overlay-mk7lwgnc/lava-32055/2/tests/4_ci-dumpjournal' Removing '.git' directory in /var/lib/lava/dispatcher/tmp/32055/lava-overlay-mk7lwgnc/lava-32055/2/tests/4_ci-dumpjournal Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/32055/lava-overlay-mk7lwgnc/lava-32055/2/tests/4_ci-dumpjournal/test-suites/short-smoke/dumpjournal.yaml uuid=32055_1.5.4.17 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.17 git-repo-action (duration 00:00:02) [common] case: git-repo-action
case_id: 3882287
definition: lava
duration: 1.72
extra: ...
level: 1.5.4.17
namespace: common
result: pass
start: 1.5.4.18 test-overlay (timeout 01:28:38) [common] end: 1.5.4.18 test-overlay (duration 00:00:00) [common] case: test-overlay
case_id: 3882288
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:28:38) [common] end: 1.5.4.19 test-install-overlay (duration 00:00:00) [common] case: test-install-overlay
case_id: 3882289
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:28:38) [common] runner path: /var/lib/lava/dispatcher/tmp/32055/lava-overlay-mk7lwgnc/lava-32055/2/tests/4_ci-dumpjournal test_uuid 32055_1.5.4.17 end: 1.5.4.20 test-runscript-overlay (duration 00:00:00) [common] case: test-runscript-overlay
case_id: 3882290
definition: lava
duration: 0.01
extra: ...
level: 1.5.4.20
namespace: common
result: pass
Creating lava-test-runner.conf files Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/32055/lava-overlay-mk7lwgnc/lava-32055/0 for stage 0 - 0_network-veth Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/32055/lava-overlay-mk7lwgnc/lava-32055/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/32055/lava-overlay-mk7lwgnc/lava-32055/2 for stage 2 - 4_ci-dumpjournal end: 1.5.4 test-definition (duration 00:00:10) [common] start: 1.5.5 compress-overlay (timeout 01:28:38) [common] end: 1.5.5 compress-overlay (duration 00:00:01) [common] start: 1.5.6 persistent-nfs-overlay (timeout 01:28:37) [common] end: 1.5.6 persistent-nfs-overlay (duration 00:00:00) [common] end: 1.5 lava-overlay (duration 00:00:11) [common] start: 1.6 prepare-kernel (timeout 01:28:37) [common] start: 1.6.1 uboot-prepare-kernel (timeout 01:28:37) [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:28:37) [common] xnbd-server-deploy: starting nbd-server NBD-IP: 192.168.66.1, NBD-PORT: 61964, NBD-ROOT: 32055/nbd-deploy-6piwujal/nbdroot/agl-telematics-demo-platform-bbe.ext4 nbd-server 61964 /var/lib/lava/dispatcher/tmp/32055/nbd-deploy-6piwujal/nbdroot/agl-telematics-demo-platform-bbe.ext4 output: output: ** (process:337): WARNING **: 07:26:36.873: 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:01:23) [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}: bootz 0x82000000 0x83000000:${initrd_size} 0x88000000 - {DTB_ADDR}: 0x88000000 - {DTB}: 32055/nbd-deploy-6piwujal/dtb/am335x-sancloud-bbe.dtb - {INITRD}: 32055/nbd-deploy-6piwujal/initrd/initramfs-netboot-image-bbe.ext4.gz - {KERNEL_ADDR}: 0x82000000 - {KERNEL}: 32055/nbd-deploy-6piwujal/kernel/zImage - {LAVA_MAC}: None - {NBDSERVERIP}: 192.168.66.1 - {NBDSERVERPORT}: 61964 - {PRESEED_CONFIG}: None - {PRESEED_LOCAL}: None - {RAMDISK_ADDR}: 0x83000000 - {RAMDISK}: 32055/nbd-deploy-6piwujal/initrd/initramfs-netboot-image-bbe.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 0x82000000 32055/nbd-deploy-6piwujal/kernel/zImage - tftp 0x83000000 32055/nbd-deploy-6piwujal/initrd/initramfs-netboot-image-bbe.ext4.gz - setenv initrd_size ${filesize} - tftp 0x88000000 32055/nbd-deploy-6piwujal/dtb/am335x-sancloud-bbe.dtb - setenv bootargs 'console=ttyO0,115200n8 rw nbd.server=192.168.66.1 nbd.port=61964 root=/dev/ram0 ramdisk_size=16384 rootdelay=7 systemd.mask=systemd-network-generator.service ip=dhcp verbose earlyprintk systemd.log_color=false ${extraargs} rw' - bootz 0x82000000 0x83000000:${initrd_size} 0x88000000 end: 2.2 bootloader-overlay (duration 00:00:00) [common] case: bootloader-overlay
case_id: 3882291
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 63005' 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' '4' >> /usr/bin/nice: ‘/usr/local/bin/acme-cli’: No such file or directory Returned 127 in 3 seconds Unable to run 'nice' '['/usr/local/bin/acme-cli', '-s', '192.168.66.2', 'reset', '4']' Unable to reboot: '/usr/local/bin/acme-cli -s 192.168.66.2 reset 4' failed end: 2.4.1.1 pdu-reboot (duration 00:00:03) [common] case: pdu-reboot
case_id: 3882292
definition: lava
duration: 3.15
extra: ...
level: 2.4.1.1
namespace: common
result: fail
Unable to reboot: '/usr/local/bin/acme-cli -s 192.168.66.2 reset 4' failed end: 2.4.1 reset-device (duration 00:00:03) [common] case: reset-device
case_id: 3882293
definition: lava
duration: 3.16
extra: ...
level: 2.4.1
namespace: common
result: fail
Unable to reboot: '/usr/local/bin/acme-cli -s 192.168.66.2 reset 4' failed end: 2.4 uboot-commands (duration 00:00:03) [common] case: uboot-commands
case_id: 3882294
definition: lava
duration: 3.17
extra: ...
level: 2.4
namespace: common
result: fail
uboot-action failed: 1 of 1 attempts. 'Unable to reboot: '/usr/local/bin/acme-cli -s 192.168.66.2 reset 4' failed' Unable to reboot: '/usr/local/bin/acme-cli -s 192.168.66.2 reset 4' failed end: 2 uboot-action (duration 00:00:06) [common] case: uboot-action
case_id: 3882295
definition: lava
duration: 6.36
extra: ...
level: 2
namespace: common
result: fail
Cleaning after the job Cleaning up download directory: /var/lib/lava/dispatcher/tmp/32055/nbd-deploy-6piwujal/initrd Cleaning up download directory: /var/lib/lava/dispatcher/tmp/32055/nbd-deploy-6piwujal/kernel Cleaning up download directory: /var/lib/lava/dispatcher/tmp/32055/nbd-deploy-6piwujal/dtb Cleaning up download directory: /var/lib/lava/dispatcher/tmp/32055/nbd-deploy-6piwujal/nbdroot start: 6.1 power-off (timeout 00:00:10) [common] Calling: 'nice' '/usr/local/bin/acme-cli' '-s' '192.168.66.2' 'switch_off' '4' >> /usr/bin/nice: ‘/usr/local/bin/acme-cli’: No such file or directory Returned 127 in 3 seconds Unable to run 'nice' '['/usr/local/bin/acme-cli', '-s', '192.168.66.2', 'switch_off', '4']' Unable to power-off: '/usr/local/bin/acme-cli -s 192.168.66.2 switch_off 4' failed end: 6.1 power-off (duration 00:00:03) [common] case: power-off
case_id: 3882296
definition: lava
duration: 3.13
extra: ...
level: 6.1
namespace: common
result: fail
Failed to run 'finalize': Unable to power-off: '/usr/local/bin/acme-cli -s 192.168.66.2 switch_off 4' failed Traceback (most recent call last): File "/usr/lib/python3/dist-packages/lava_dispatcher/job.py", line 252, in run self._run() File "/usr/lib/python3/dist-packages/lava_dispatcher/job.py", line 242, in _run self.pipeline.run_actions(self.connection, max_end_time) File "/usr/lib/python3/dist-packages/lava_dispatcher/action.py", line 262, in run_actions new_connection = action.run(connection, action_max_end_time) File "/usr/lib/python3/dist-packages/lava_dispatcher/logical.py", line 68, in run connection = self.pipeline.run_actions(connection, max_end_time) File "/usr/lib/python3/dist-packages/lava_dispatcher/action.py", line 262, in run_actions new_connection = action.run(connection, action_max_end_time) File "/usr/lib/python3/dist-packages/lava_dispatcher/action.py", line 825, in run return self.pipeline.run_actions(connection, max_end_time) File "/usr/lib/python3/dist-packages/lava_dispatcher/action.py", line 262, in run_actions new_connection = action.run(connection, action_max_end_time) File "/usr/lib/python3/dist-packages/lava_dispatcher/action.py", line 825, in run return self.pipeline.run_actions(connection, max_end_time) File "/usr/lib/python3/dist-packages/lava_dispatcher/action.py", line 262, in run_actions new_connection = action.run(connection, action_max_end_time) File "/usr/lib/python3/dist-packages/lava_dispatcher/power.py", line 119, in run self.run_cmd(cmd, error_msg="Unable to reboot: '%s' failed" % cmd) File "/usr/lib/python3/dist-packages/lava_dispatcher/action.py", line 695, in run_cmd raise self.command_exception(error_msg) lava_common.exceptions.InfrastructureError: Unable to reboot: '/usr/local/bin/acme-cli -s 192.168.66.2 reset 4' failed During handling of the above exception, another exception occurred: Traceback (most recent call last): File "/usr/lib/python3/dist-packages/lava_dispatcher/power.py", line 351, in run connection = super().run(connection, max_end_time) File "/usr/lib/python3/dist-packages/lava_dispatcher/action.py", line 825, in run return self.pipeline.run_actions(connection, max_end_time) File "/usr/lib/python3/dist-packages/lava_dispatcher/action.py", line 262, in run_actions new_connection = action.run(connection, action_max_end_time) File "/usr/lib/python3/dist-packages/lava_dispatcher/power.py", line 244, in run self.run_cmd(cmd, error_msg="Unable to power-off: '%s' failed" % cmd) File "/usr/lib/python3/dist-packages/lava_dispatcher/action.py", line 695, in run_cmd raise self.command_exception(error_msg) lava_common.exceptions.InfrastructureError: Unable to power-off: '/usr/local/bin/acme-cli -s 192.168.66.2 switch_off 4' failed lava-xnbd cleanup clean NBD port 61964 Override tmp directory removed at /var/lib/lava/dispatcher/tmp/32055 Root tmp directory removed at /var/lib/lava/dispatcher/tmp/32055 InfrastructureError: The Infrastructure is not working correctly. Please report this error to LAVA admins. case: job
case_id: 3882297
definition: lava
error_msg: Unable to reboot: '/usr/local/bin/acme-cli -s 192.168.66.2 reset 4' failed
error_type: Infrastructure
result: fail