Help
Invalid job definition:

extra keys not allowed @ data['actions[0]']['deploy']['tftp']

Description
[lavafed 2019.09.0089.gf1de999c2] cubietruck - nfs health-check
Device-type
cubietruck
Submitter
Rémi Duraffort
Created
6 days, 15 hours ago
Priority
Medium
Visibility
Public
Required Tags
staging.validation.linaro.org
#1216
Complete
Device
staging-cubietruck01@staging.validation.linaro.org
Started
6 days, 15 hours ago
Duration
2 minutes
Results
  
lava-dispatcher, installed at version: 2019.09.0089.gf1de999c2+stretch start: 0 validate Start time: 2019-10-09 09:07:18.897820+00:00 (UTC) Validating that http://images.validation.linaro.org/snapshots.linaro.org/components/lava/standard/debian/jessie/armhf/4/initramfs.cpio.gz exists Validating that http://images.validation.linaro.org/snapshots.linaro.org/components/lava/standard/debian/jessie/armhf/4/vmlinuz exists Validating that http://images.validation.linaro.org/snapshots.linaro.org/components/lava/standard/debian/jessie/armhf/4/dtbs/sun7i-a20-cubietruck.dtb exists Validating that http://images.validation.linaro.org/snapshots.linaro.org/components/lava/standard/debian/jessie/armhf/4/jessie-armhf-nfs.tar.gz exists Validating that http://images.validation.linaro.org/snapshots.linaro.org/components/lava/standard/debian/jessie/armhf/4/modules.tar.gz exists validate duration: 0.14 case: validate
case_id: 24518
definition: lava
result: pass
start: 1 tftp-deploy (timeout 00:10:00) [common] start: 1.1 download-retry (timeout 00:10:00) [common] start: 1.1.1 http-download (timeout 00:10:00) [common] Not decompressing ramdisk as can be used compressed. downloading http://images.validation.linaro.org/snapshots.linaro.org/components/lava/standard/debian/jessie/armhf/4/initramfs.cpio.gz saving as /var/lib/lava/dispatcher/tmp/lavafed-1216/tftp-deploy-cy0ztb04/ramdisk/initramfs.cpio.gz total size: 12427620 (11MB) No compression specified progress 0% (0MB) progress 5% (0MB) progress 10% (1MB) progress 15% (1MB) progress 20% (2MB) progress 25% (2MB) progress 30% (3MB) progress 35% (4MB) progress 40% (4MB) progress 45% (5MB) progress 50% (5MB) progress 55% (6MB) progress 60% (7MB) progress 65% (7MB) progress 70% (8MB) progress 75% (8MB) progress 80% (9MB) progress 85% (10MB) progress 90% (10MB) progress 95% (11MB) progress 100% (11MB) 11MB downloaded in 0.26s (44.86MB/s) end: 1.1.1 http-download (duration 00:00:00) [common] case: http-download
case_id: 24523
definition: lava
duration: 0.27
extra: ...
level: 1.1.1
namespace: common
result: pass
end: 1.1 download-retry (duration 00:00:00) [common] start: 1.2 download-retry (timeout 00:10:00) [common] start: 1.2.1 http-download (timeout 00:10:00) [common] downloading http://images.validation.linaro.org/snapshots.linaro.org/components/lava/standard/debian/jessie/armhf/4/vmlinuz saving as /var/lib/lava/dispatcher/tmp/lavafed-1216/tftp-deploy-cy0ztb04/kernel/vmlinuz total size: 3187808 (3MB) No compression specified progress 1% (0MB) progress 6% (0MB) progress 11% (0MB) progress 16% (0MB) progress 21% (0MB) progress 26% (0MB) progress 31% (0MB) progress 37% (1MB) progress 42% (1MB) progress 47% (1MB) progress 52% (1MB) progress 57% (1MB) progress 62% (1MB) progress 67% (2MB) progress 72% (2MB) progress 77% (2MB) progress 82% (2MB) progress 87% (2MB) progress 92% (2MB) progress 97% (2MB) 3MB downloaded in 0.13s (24.09MB/s) end: 1.2.1 http-download (duration 00:00:00) [common] case: http-download
case_id: 24523
definition: lava
duration: 0.13
extra: ...
level: 1.2.1
namespace: common
result: pass
end: 1.2 download-retry (duration 00:00:00) [common] start: 1.3 download-retry (timeout 00:10:00) [common] start: 1.3.1 http-download (timeout 00:10:00) [common] downloading http://images.validation.linaro.org/snapshots.linaro.org/components/lava/standard/debian/jessie/armhf/4/dtbs/sun7i-a20-cubietruck.dtb saving as /var/lib/lava/dispatcher/tmp/lavafed-1216/tftp-deploy-cy0ztb04/dtb/sun7i-a20-cubietruck.dtb total size: 23728 (0MB) No compression specified progress 100% (0MB) 0MB downloaded in 0.01s (1.95MB/s) end: 1.3.1 http-download (duration 00:00:00) [common] case: http-download
case_id: 24523
definition: lava
duration: 0.01
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 00:10:00) [common] start: 1.4.1 http-download (timeout 00:10:00) [common] downloading http://images.validation.linaro.org/snapshots.linaro.org/components/lava/standard/debian/jessie/armhf/4/jessie-armhf-nfs.tar.gz saving as /var/lib/lava/dispatcher/tmp/lavafed-1216/tftp-deploy-cy0ztb04/nfsrootfs/jessie-armhf-nfs.tar total size: 212059162 (202MB) Using gunzip to decompress gz progress 0% (0MB) progress 5% (10MB) progress 10% (20MB) progress 15% (30MB) progress 20% (40MB) progress 25% (50MB) progress 30% (60MB) progress 35% (70MB) progress 40% (80MB) progress 45% (91MB) progress 50% (101MB) progress 55% (111MB) progress 60% (121MB) progress 65% (131MB) progress 70% (141MB) progress 75% (151MB) progress 80% (161MB) progress 85% (171MB) progress 90% (182MB) progress 95% (192MB) progress 100% (202MB) 202MB downloaded in 7.20s (28.07MB/s) end: 1.4.1 http-download (duration 00:00:07) [common] case: http-download
case_id: 24523
definition: lava
duration: 7.21
extra: ...
level: 1.4.1
namespace: common
result: pass
end: 1.4 download-retry (duration 00:00:07) [common] start: 1.5 download-retry (timeout 00:09:52) [common] start: 1.5.1 http-download (timeout 00:09:52) [common] downloading http://images.validation.linaro.org/snapshots.linaro.org/components/lava/standard/debian/jessie/armhf/4/modules.tar.gz saving as /var/lib/lava/dispatcher/tmp/lavafed-1216/tftp-deploy-cy0ztb04/modules/modules.tar total size: 32894398 (31MB) Using gunzip to decompress gz progress 0% (0MB) progress 5% (1MB) progress 10% (3MB) progress 15% (4MB) progress 20% (6MB) progress 25% (7MB) progress 30% (9MB) progress 35% (11MB) progress 40% (12MB) progress 45% (14MB) progress 50% (15MB) progress 55% (17MB) progress 60% (18MB) progress 65% (20MB) progress 70% (21MB) progress 75% (23MB) progress 80% (25MB) progress 85% (26MB) progress 90% (28MB) progress 95% (29MB) progress 100% (31MB) 31MB downloaded in 6.45s (4.86MB/s) end: 1.5.1 http-download (duration 00:00:06) [common] case: http-download
case_id: 24523
definition: lava
duration: 6.45
extra: ...
level: 1.5.1
namespace: common
result: pass
end: 1.5 download-retry (duration 00:00:06) [common] start: 1.6 prepare-tftp-overlay (timeout 00:09:46) [common] start: 1.6.1 extract-nfsrootfs (timeout 00:09:46) [common] Extracted nfsroot to /var/lib/lava/dispatcher/tmp/lavafed-1216/extract-nfsrootfs-5w4hk2mx end: 1.6.1 extract-nfsrootfs (duration 00:00:19) [common] start: 1.6.2 lava-overlay (timeout 00:09:27) [common] [common] Preparing overlay tarball in /var/lib/lava/dispatcher/tmp/lavafed-1216/lava-overlay-0djtjj23 makedir: /var/lib/lava/dispatcher/tmp/lavafed-1216/lava-overlay-0djtjj23/lava-1216/bin makedir: /var/lib/lava/dispatcher/tmp/lavafed-1216/lava-overlay-0djtjj23/lava-1216/tests makedir: /var/lib/lava/dispatcher/tmp/lavafed-1216/lava-overlay-0djtjj23/lava-1216/results Creating /var/lib/lava/dispatcher/tmp/lavafed-1216/lava-overlay-0djtjj23/lava-1216/bin/lava-add-keys Creating /var/lib/lava/dispatcher/tmp/lavafed-1216/lava-overlay-0djtjj23/lava-1216/bin/lava-add-sources Creating /var/lib/lava/dispatcher/tmp/lavafed-1216/lava-overlay-0djtjj23/lava-1216/bin/lava-background-process-start Creating /var/lib/lava/dispatcher/tmp/lavafed-1216/lava-overlay-0djtjj23/lava-1216/bin/lava-background-process-stop Creating /var/lib/lava/dispatcher/tmp/lavafed-1216/lava-overlay-0djtjj23/lava-1216/bin/lava-common-functions Creating /var/lib/lava/dispatcher/tmp/lavafed-1216/lava-overlay-0djtjj23/lava-1216/bin/lava-echo-ipv4 Creating /var/lib/lava/dispatcher/tmp/lavafed-1216/lava-overlay-0djtjj23/lava-1216/bin/lava-install-packages Creating /var/lib/lava/dispatcher/tmp/lavafed-1216/lava-overlay-0djtjj23/lava-1216/bin/lava-installed-packages Creating /var/lib/lava/dispatcher/tmp/lavafed-1216/lava-overlay-0djtjj23/lava-1216/bin/lava-os-build Creating /var/lib/lava/dispatcher/tmp/lavafed-1216/lava-overlay-0djtjj23/lava-1216/bin/lava-probe-channel Creating /var/lib/lava/dispatcher/tmp/lavafed-1216/lava-overlay-0djtjj23/lava-1216/bin/lava-probe-ip Creating /var/lib/lava/dispatcher/tmp/lavafed-1216/lava-overlay-0djtjj23/lava-1216/bin/lava-target-ip Creating /var/lib/lava/dispatcher/tmp/lavafed-1216/lava-overlay-0djtjj23/lava-1216/bin/lava-target-mac Creating /var/lib/lava/dispatcher/tmp/lavafed-1216/lava-overlay-0djtjj23/lava-1216/bin/lava-target-storage Creating /var/lib/lava/dispatcher/tmp/lavafed-1216/lava-overlay-0djtjj23/lava-1216/bin/lava-test-case Creating /var/lib/lava/dispatcher/tmp/lavafed-1216/lava-overlay-0djtjj23/lava-1216/bin/lava-test-event Creating /var/lib/lava/dispatcher/tmp/lavafed-1216/lava-overlay-0djtjj23/lava-1216/bin/lava-test-feedback Creating /var/lib/lava/dispatcher/tmp/lavafed-1216/lava-overlay-0djtjj23/lava-1216/bin/lava-test-raise Creating /var/lib/lava/dispatcher/tmp/lavafed-1216/lava-overlay-0djtjj23/lava-1216/bin/lava-test-reference Creating /var/lib/lava/dispatcher/tmp/lavafed-1216/lava-overlay-0djtjj23/lava-1216/bin/lava-test-runner Creating /var/lib/lava/dispatcher/tmp/lavafed-1216/lava-overlay-0djtjj23/lava-1216/bin/lava-test-set Creating /var/lib/lava/dispatcher/tmp/lavafed-1216/lava-overlay-0djtjj23/lava-1216/bin/lava-test-shell Updating /var/lib/lava/dispatcher/tmp/lavafed-1216/lava-overlay-0djtjj23/lava-1216/bin/lava-add-keys (debian) Updating /var/lib/lava/dispatcher/tmp/lavafed-1216/lava-overlay-0djtjj23/lava-1216/bin/lava-add-sources (debian) Updating /var/lib/lava/dispatcher/tmp/lavafed-1216/lava-overlay-0djtjj23/lava-1216/bin/lava-install-packages (debian) Updating /var/lib/lava/dispatcher/tmp/lavafed-1216/lava-overlay-0djtjj23/lava-1216/bin/lava-installed-packages (debian) Updating /var/lib/lava/dispatcher/tmp/lavafed-1216/lava-overlay-0djtjj23/lava-1216/bin/lava-os-build (debian) Creating /var/lib/lava/dispatcher/tmp/lavafed-1216/lava-overlay-0djtjj23/lava-1216/environment start: 1.6.2.1 ssh-authorize (timeout 00:09:27) [common] end: 1.6.2.1 ssh-authorize (duration 00:00:00) [common] start: 1.6.2.2 lava-vland-overlay (timeout 00:09:27) [common] skipped lava-vland-overlay end: 1.6.2.2 lava-vland-overlay (duration 00:00:00) [common] start: 1.6.2.3 lava-multinode-overlay (timeout 00:09:27) [common] skipped lava-multinode-overlay end: 1.6.2.3 lava-multinode-overlay (duration 00:00:00) [common] start: 1.6.2.4 test-definition (timeout 00:09:27) [common] Loading test definitions start: 1.6.2.4.1 git-repo-action (timeout 00:09:27) [common] Using /lava-1216 at stage 0 Fetching tests from http://git.linaro.org/lava-team/lava-functional-tests.git Running '/usr/bin/git clone http://git.linaro.org/lava-team/lava-functional-tests.git /var/lib/lava/dispatcher/tmp/lavafed-1216/lava-overlay-0djtjj23/lava-1216/0/tests/0_smoke-tests --depth=1' Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/lavafed-1216/lava-overlay-0djtjj23/lava-1216/0/tests/0_smoke-tests/lava-test-shell/smoke-tests-basic.yaml uuid=1216_1.6.2.4.1 testdef=None end: 1.6.2.4.1 git-repo-action (duration 00:00:01) [common] case: git-repo-action
case_id: 24550
definition: lava
duration: 0.68
extra: ...
level: 1.6.2.4.1
namespace: common
result: pass
start: 1.6.2.4.2 test-overlay (timeout 00:09:26) [common] end: 1.6.2.4.2 test-overlay (duration 00:00:00) [common] case: test-overlay
case_id: 24551
definition: lava
duration: 0.01
extra: ...
level: 1.6.2.4.2
namespace: common
result: pass
start: 1.6.2.4.3 test-install-overlay (timeout 00:09:26) [common] end: 1.6.2.4.3 test-install-overlay (duration 00:00:00) [common] case: test-install-overlay
case_id: 24552
definition: lava
duration: 0.03
extra: ...
level: 1.6.2.4.3
namespace: common
result: pass
start: 1.6.2.4.4 test-runscript-overlay (timeout 00:09:26) [common] runner path: /var/lib/lava/dispatcher/tmp/lavafed-1216/lava-overlay-0djtjj23/lava-1216/0/tests/0_smoke-tests test_uuid 1216_1.6.2.4.1 end: 1.6.2.4.4 test-runscript-overlay (duration 00:00:00) [common] case: test-runscript-overlay
case_id: 24553
definition: lava
duration: 0.03
extra: ...
level: 1.6.2.4.4
namespace: common
result: pass
Creating lava-test-runner.conf files Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/lavafed-1216/lava-overlay-0djtjj23/lava-1216/0 for stage 0 - 0_smoke-tests end: 1.6.2.4 test-definition (duration 00:00:01) [common] start: 1.6.2.5 compress-overlay (timeout 00:09:26) [common] end: 1.6.2.5 compress-overlay (duration 00:00:00) [common] start: 1.6.2.6 persistent-nfs-overlay (timeout 00:09:26) [common] end: 1.6.2.6 persistent-nfs-overlay (duration 00:00:00) [common] end: 1.6.2 lava-overlay (duration 00:00:01) [common] start: 1.6.3 extract-overlay-ramdisk (timeout 00:09:26) [common] nice cpio -iud -F /var/lib/lava/dispatcher/tmp/lavafed-1216/extract-overlay-ramdisk-bm9cpkrw/ramdisk.cpio output: 61218 blocks output: end: 1.6.3 extract-overlay-ramdisk (duration 00:00:01) [common] start: 1.6.4 extract-modules (timeout 00:09:25) [common] extracting modules file /var/lib/lava/dispatcher/tmp/lavafed-1216/tftp-deploy-cy0ztb04/modules/modules.tar to /var/lib/lava/dispatcher/tmp/lavafed-1216/extract-nfsrootfs-5w4hk2mx extracting modules file /var/lib/lava/dispatcher/tmp/lavafed-1216/tftp-deploy-cy0ztb04/modules/modules.tar to /var/lib/lava/dispatcher/tmp/lavafed-1216/extract-overlay-ramdisk-bm9cpkrw/ramdisk end: 1.6.4 extract-modules (duration 00:00:10) [common] start: 1.6.5 apply-overlay-tftp (timeout 00:09:14) [common] [common] Applying overlay to NFS [common] Applying overlay /var/lib/lava/dispatcher/tmp/lavafed-1216/compress-overlay-pwa2zkk9/overlay-1.6.2.5.tar.gz to directory /var/lib/lava/dispatcher/tmp/lavafed-1216/extract-nfsrootfs-5w4hk2mx end: 1.6.5 apply-overlay-tftp (duration 00:00:00) [common] start: 1.6.6 prepare-kernel (timeout 00:09:14) [common] start: 1.6.6.1 uboot-prepare-kernel (timeout 00:09:14) [common] end: 1.6.6.1 uboot-prepare-kernel (duration 00:00:00) [common] end: 1.6.6 prepare-kernel (duration 00:00:00) [common] start: 1.6.7 configure-preseed-file (timeout 00:09:14) [common] end: 1.6.7 configure-preseed-file (duration 00:00:00) [common] start: 1.6.8 compress-ramdisk (timeout 00:09:14) [common] Building ramdisk /var/lib/lava/dispatcher/tmp/lavafed-1216/extract-overlay-ramdisk-bm9cpkrw/ramdisk.cpio containing /var/lib/lava/dispatcher/tmp/lavafed-1216/extract-overlay-ramdisk-bm9cpkrw/ramdisk find . | cpio --create --format='newc' > /var/lib/lava/dispatcher/tmp/lavafed-1216/extract-overlay-ramdisk-bm9cpkrw/ramdisk.cpio 190360 blocks Adding RAMdisk u-boot header. nice mkimage -A arm -T ramdisk -C none -d /var/lib/lava/dispatcher/tmp/lavafed-1216/extract-overlay-ramdisk-bm9cpkrw/ramdisk.cpio.gz /var/lib/lava/dispatcher/tmp/lavafed-1216/extract-overlay-ramdisk-bm9cpkrw/ramdisk.cpio.gz.uboot output: Image Name: output: Created: Wed Oct 9 09:08:17 2019 output: Image Type: ARM Linux RAMDisk Image (uncompressed) output: Data Size: 34999256 Bytes = 34178.96 kB = 33.38 MB output: Load Address: 00000000 output: Entry Point: 00000000 output: rename /var/lib/lava/dispatcher/tmp/lavafed-1216/extract-overlay-ramdisk-bm9cpkrw/ramdisk.cpio.gz.uboot to /var/lib/lava/dispatcher/tmp/lavafed-1216/tftp-deploy-cy0ztb04/ramdisk/ramdisk.cpio.gz.uboot end: 1.6.8 compress-ramdisk (duration 00:00:13) [common] end: 1.6 prepare-tftp-overlay (duration 00:00:45) [common] start: 1.7 lxc-create-udev-rule-action (timeout 00:09:01) [common] No LXC device requested end: 1.7 lxc-create-udev-rule-action (duration 00:00:00) [common] start: 1.8 deploy-device-env (timeout 00:09:01) [common] end: 1.8 deploy-device-env (duration 00:00:00) [common] Checking files for TFTP limit of 4294967296 bytes. end: 1 tftp-deploy (duration 00:00:59) [common] start: 2 uboot-action (timeout 00:09:01) [common] start: 2.1 uboot-from-media (timeout 00:09:01) [common] end: 2.1 uboot-from-media (duration 00:00:00) [common] start: 2.2 bootloader-overlay (timeout 00:09:01) [common] {'bootz': {'ramdisk': '0x43300000', 'kernel': '0x42000000', 'dtb': '0x43000000'}, 'zimage': {'ramdisk': '0x43300000', 'kernel': '0x42000000', 'dtb': '0x43000000'}, 'pass': None} Parsed boot commands: setenv autoload no; setenv initrd_high 0xffffffff; setenv fdt_high 0xffffffff; dhcp; setenv serverip 10.3.1.1; tftp 0x42000000 lavafed-1216/tftp-deploy-cy0ztb04/kernel/vmlinuz; tftp 0x43300000 lavafed-1216/tftp-deploy-cy0ztb04/ramdisk/ramdisk.cpio.gz.uboot; setenv initrd_size ${filesize}; tftp 0x43000000 lavafed-1216/tftp-deploy-cy0ztb04/dtb/sun7i-a20-cubietruck.dtb; setenv bootargs 'console=ttyS0,115200n8 root=/dev/nfs rw nfsroot=10.3.1.1:/var/lib/lava/dispatcher/tmp/lavafed-1216/extract-nfsrootfs-5w4hk2mx,tcp,hard,intr ip=dhcp'; bootz 0x42000000 0x43300000 0x43000000 end: 2.2 bootloader-overlay (duration 00:00:00) [common] case: bootloader-overlay
case_id: 24567
definition: lava
duration: 0.00
extra: ...
level: 2.2
namespace: common
result: pass
start: 2.3 connect-device (timeout 00:09:01) [common] [common] connect-device Connecting to device using 'telnet staging03 7118' end: 2.3 connect-device (duration 00:00:01) [common] start: 2.4 uboot-retry (timeout 00:09:00) [common] start: 2.4.1 reset-device (timeout 00:09:00) [common] start: 2.4.1.1 pdu-reboot (timeout 00:09:00) [common] Calling: 'nice' '/usr/local/lab-scripts/snmp_pdu_control' '--hostname' 'pdu18' '--command' 'reboot' '--port' '14' '--delay' '30' >> pdu18 port 14 now powered off >> pdu18 port 14 now powered on >> pdu18 port 14 now power cycled Returned 0 in 41 seconds end: 2.4.1.1 pdu-reboot (duration 00:00:41) [common] case: pdu-reboot
case_id: 24592
definition: lava
duration: 41.27
extra: ...
level: 2.4.1.1
namespace: common
result: pass
end: 2.4.1 reset-device (duration 00:00:41) [common] start: 2.4.2 bootloader-interrupt (timeout 00:08:19) [common] bootloader-interrupt: Wait for prompt Hit any key to stop autoboot (timeout 00:10:00) Trying 10.3.3.1... Connected to staging03.lavalab. Escape character is '^]'. Cubietruck 7118 [115200 N81] U-Boot SPL 2014.10+dfsg1-3 (Feb 21 2015 - 21:59:32) DRAM: 2048 MiB CPU: 960000000Hz, AXI/AHB/APB: 3/2/2 U-Boot 2014.10+dfsg1-3 (Feb 21 2015 - 21:59:32) Allwinner Technology CPU: Allwinner A20 (SUN7I) I2C: ready DRAM: 2 GiB MMC: SUNXI SD/MMC: 0 In: serial Out: serial Err: serial SCSI: SUNXI SCSI INIT SATA link 0 timeout. AHCI 0001.0100 32 slots 1 ports 3 Gbps 0x1 impl SATA mode flags: ncq stag pm led clo only pmp pio slum part ccc apst Net: dwmac.1c50000 Hit any key to stop autoboot: 2 end: 2.4.2 bootloader-interrupt (duration 00:00:00) [common] start: 2.4.3 bootloader-commands (timeout 00:08:19) [common] bootloader-commands: Wait for prompt ['sun7i#'] (timeout 00:08:19)  0 setenv autoload no sun7i# setenv autoload no bootloader-commands: Wait for prompt ['sun7i#', '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.', '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'] (timeout 00:08:18) setenv autoload no setenv initrd_high 0xffffffff sun7i# setenv initrd_high 0xffffffff bootloader-commands: Wait for prompt ['sun7i#', '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.', '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'] (timeout 00:08:18) setenv initrd_high 0xffffffff setenv fdt_high 0xffffffff sun7i# setenv fdt_high 0xffffffff bootloader-commands: Wait for prompt ['sun7i#', '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.', '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'] (timeout 00:08:18) setenv fdt_high 0xffffffff dhcp sun7i# dhcp bootloader-commands: Wait for prompt ['sun7i#', '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.', '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'] (timeout 00:08:18) dhcp dwmac.1c50000 Waiting for PHY auto negotiation to complete...... done Speed: 1000, full duplex BOOTP broadcast 1 BOOTP broadcast 2 BOOTP broadcast 3 DHCP client bound to address 10.15.1.209 (1334 ms) setenv serverip 10.3.1.1 sun7i# setenv serverip 10.3.1.1 bootloader-commands: Wait for prompt ['sun7i#', '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.', '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'] (timeout 00:08:14) setenv serverip 10.3.1.1 tftp 0x42000000 lavafed-1216/tftp-deploy-cy0ztb04/kernel/vmlinuz sun7i# tftp 0x42000000 lavafed-1216/tftp-deploy-cy0ztb04/kernel/vmlinuz bootloader-commands: Wait for prompt ['sun7i#', '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.', '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'] (timeout 00:08:13) tftp 0x42000000 lavafed-1216/tftp-deploy-cy0ztb04/kernel/vmlinuz Speed: 1000, full duplex Using dwmac.1c50000 device TFTP from server 10.3.1.1; our IP address is 10.15.1.209 Filename 'lavafed-1216/tftp-deploy-cy0ztb04/kernel/vmlinuz'. Load address: 0x42000000 Loading: *##T ###T #T ########################################################### ################################################################# ################################################################# ####################### 203.1 KiB/s done Bytes transferred = 3187808 (30a460 hex) tftp 0x43300000 lavafed-1216/tftp-deploy-cy0ztb04/ramdisk/ramdisk.cpio.gz.uboot sun7i# tftp 0x43300000 lavafed-1216/tftp-deploy-cy0ztb04/ramdisk/ramdisk.cpio.gz.uboot bootloader-commands: Wait for prompt ['sun7i#', '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.', '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'] (timeout 00:07:58) tftp 0x43300000 lavafed-1216/tftp-deploy-cy0ztb04/ramdisk/ramdisk.cpio.gz.uboot Speed: 1000, full duplex Using dwmac.1c50000 device TFTP from server 10.3.1.1; our IP address is 10.15.1.209 Filename 'lavafed-1216/tftp-deploy-cy0ztb04/ramdisk/ramdisk.cpio.gz.uboot'. Load address: 0x43300000 Loading: *################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ############################################# 10.8 MiB/s done Bytes transferred = 34999320 (2160c18 hex) setenv initrd_size ${filesize} sun7i# setenv initrd_size ${filesize} bootloader-commands: Wait for prompt ['sun7i#', '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.', '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'] (timeout 00:07:55) setenv initrd_size ${filesize} tftp 0x43000000 lavafed-1216/tftp-deploy-cy0ztb04/dtb/sun7i-a20-cubietruck.dtb sun7i# tftp 0x43000000 lavafed-1216/tftp-deploy-cy0ztb04/dtb/sun7i-a20-cubietruck.dtb bootloader-commands: Wait for prompt ['sun7i#', '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.', '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'] (timeout 00:07:55) tftp 0x43000000 lavafed-1216/tftp-deploy-cy0ztb04/dtb/sun7i-a20-cubietruck.dtb Speed: 1000, full duplex Using dwmac.1c50000 device TFTP from server 10.3.1.1; our IP address is 10.15.1.209 Filename 'lavafed-1216/tftp-deploy-cy0ztb04/dtb/sun7i-a20-cubietruck.dtb'. Load address: 0x43000000 Loading: *## 2.3 MiB/s done Bytes transferred = 23728 (5cb0 hex) setenv bootargs 'console=ttyS0,115200n8 root=/dev/nfs rw nfsroot=10.3.1.1:/var/lib/lava/dispatcher/tmp/lavafed-1216/extract-nfsrootfs-5w4hk2mx,tcp,hard,intr ip=dhcp' sun7i# setenv bootargs 'console=ttyS0,115200n8 root=/dev/nfs rw nfsroot=10.3.1.1:/var/lib/lava/dispatcher/tmp/lavafed-1216/extract-nfsrootfs-5w4hk2mx,tcp,hard,intr ip=dhcp' bootloader-commands: Wait for prompt ['sun7i#', '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.', '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'] (timeout 00:07:54) setenv bootargs 'console=ttyS0,115200n8 root=/dev/nfs rw nfsroot=10.3.1.1:/var/lib/lava/dispatcher/tmp/lavafed-1216/extract-nfsrootfs-5w4hk2mx,tcp,hard,intr ip=dhcp' bootz 0x42000000 0x43300000 0x43000000 sun7i# bootz 0x42000000 0x43300000 0x43000000 bootloader-commands: Wait for prompt Starting kernel (timeout 00:07:54) bootz 0x42000000 0x43300000 0x43000000 Kernel image @ 0x42000000 [ 0x000000 - 0x30a460 ] ## Loading init Ramdisk from Legacy Image at 43300000 ... Image Name: Image Type: ARM Linux RAMDisk Image (uncompressed) Data Size: 34999256 Bytes = 33.4 MiB Load Address: 00000000 Entry Point: 00000000 Verifying Checksum ... OK ## Flattened Device Tree blob at 43000000 Booting using the fdt blob at 0x43000000 Using Device Tree in place at 43000000, end 43008caf end: 2.4.3 bootloader-commands (duration 00:00:25) [common] start: 2.4.4 auto-login-action (timeout 00:07:54) [common] auto-login-action: Wait for prompt ['Linux version [0-9]', '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.', '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'] (timeout 00:10:00) Starting kernel ... [ 0.000000] Booting Linux on physical CPU 0x0 [ 0.000000] Initializing cgroup subsys cpuset [ 0.000000] Initializing cgroup subsys cpu [ 0.000000] Initializing cgroup subsys cpuacct [ 0.000000] Linux version 3.16.0-4-armmp (debian-kernel@lists.debian.org) (gcc version 4.8.4 (Debian 4.8.4-1) ) #1 SMP Debian 3.16.39-1 (2016-12-30) [ 0.000000] CPU: ARMv7 Processor [410fc074] revision 4 (ARMv7), cr=10c5387d Using line separator: #'\n'# Waiting for the login prompt Parsing kernel messages ['-+\\[ cut here \\]-+\\s+(.*\\s+-+\\[ end trace (\\w*) \\]-+)', '(Unhandled fault.*)\\r\\n', 'Kernel panic - (.*) end Kernel panic', 'Stack:\\s+(.*\\s+-+\\[ end trace (\\w*) \\]-+)', 'root@jessie:', 'login:', 'Login incorrect'] [auto-login-action] Waiting for messages, (timeout 00:07:49) [ 0.000000] CPU: PIPT / VIPT nonaliasing data cache, VIPT aliasing instruction cache [ 0.000000] Machine model: Cubietech Cubietruck [ 0.000000] Memory policy: Data cache writealloc [ 0.000000] psci: probing for conduit method from DT. [ 0.000000] psci: Using PSCI v0.1 Function IDs from DT [ 0.000000] PERCPU: Embedded 9 pages/cpu @ee7ca000 s12672 r8192 d16000 u36864 [ 0.000000] Built 1 zonelists in Zone order, mobility grouping on. Total pages: 522768 [ 0.000000] Kernel command line: console=ttyS0,115200n8 root=/dev/nfs rw nfsroot=10.3.1.1:/var/lib/lava/dispatcher/tmp/lavafed-1216/extract-nfsrootfs-5w4hk2mx,tcp,hard,intr ip=dhcp [ 0.000000] PID hash table entries: 4096 (order: 2, 16384 bytes) [ 0.000000] Dentry cache hash table entries: 131072 (order: 7, 524288 bytes) [ 0.000000] Inode-cache hash table entries: 65536 (order: 6, 262144 bytes) [ 0.000000] Memory: 2035000K/2097152K available (6434K kernel code, 830K rwdata, 2200K rodata, 688K init, 393K bss, 62152K reserved, 1318912K highmem) [ 0.000000] Virtual kernel memory layout: [ 0.000000] vector : 0xffff0000 - 0xffff1000 ( 4 kB) [ 0.000000] fixmap : 0xffc00000 - 0xffe00000 (2048 kB) [ 0.000000] vmalloc : 0xf0000000 - 0xff000000 ( 240 MB) [ 0.000000] lowmem : 0xc0000000 - 0xef800000 ( 760 MB) [ 0.000000] pkmap : 0xbfe00000 - 0xc0000000 ( 2 MB) [ 0.000000] modules : 0xbf000000 - 0xbfe00000 ( 14 MB) [ 0.000000] .text : 0xc0008000 - 0xc0876b18 (8635 kB) [ 0.000000] .init : 0xc0877000 - 0xc0923180 ( 689 kB) [ 0.000000] .data : 0xc0924000 - 0xc09f3a50 ( 831 kB) [ 0.000000] .bss : 0xc09f3a50 - 0xc0a55f74 ( 394 kB) [ 0.000000] Hierarchical RCU implementation. [ 0.000000] RCU dyntick-idle grace-period acceleration is enabled. [ 0.000000] RCU restricting CPUs from NR_CPUS=4 to nr_cpu_ids=2. [ 0.000000] RCU: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=2 [ 0.000000] NR_IRQS:16 nr_irqs:16 16 [ 0.000000] Architected cp15 timer(s) running at 24.00MHz (phys). [ 0.000010] sched_clock: 56 bits at 24MHz, resolution 41ns, wraps every 2863311519744ns [ 0.000020] Switching to timer-based delay loop [ 0.001011] sched_clock: 32 bits at 24MHz, resolution 41ns, wraps every 178956969942ns [ 0.001440] sched_clock: 32 bits at 160MHz, resolution 6ns, wraps every 26843545593ns [ 0.001896] Console: colour dummy device 80x30 [ 0.001930] Calibrating delay loop (skipped), value calculated using timer frequency.. 48.00 BogoMIPS (lpj=96000) [ 0.001947] pid_max: default: 32768 minimum: 301 [ 0.002076] Security Framework initialized [ 0.002145] AppArmor: AppArmor disabled by boot time parameter [ 0.002155] Yama: disabled by default; enable with sysctl kernel.yama.* [ 0.002237] Mount-cache hash table entries: 2048 (order: 1, 8192 bytes) [ 0.002250] Mountpoint-cache hash table entries: 2048 (order: 1, 8192 bytes) [ 0.003162] Initializing cgroup subsys memory [ 0.003200] Initializing cgroup subsys devices [ 0.003241] Initializing cgroup subsys freezer [ 0.003262] Initializing cgroup subsys net_cls [ 0.003294] Initializing cgroup subsys blkio [ 0.003319] Initializing cgroup subsys perf_event [ 0.003334] Initializing cgroup subsys net_prio [ 0.003414] CPU: Testing write buffer coherency: ok [ 0.003460] ftrace: allocating 23146 entries in 68 pages [ 0.038754] /cpus/cpu@0 missing clock-frequency property [ 0.038788] /cpus/cpu@1 missing clock-frequency property [ 0.038803] CPU0: thread -1, cpu 0, socket 0, mpidr 80000000 [ 0.039058] Setting up static identity map for 0x405c8088 - 0x405c80e0 [ 0.048689] CPU1: Booted secondary processor [ 0.048744] CPU1: thread -1, cpu 1, socket 0, mpidr 80000001 [ 0.048863] Brought up 2 CPUs [ 0.048889] SMP: Total of 2 processors activated (96.00 BogoMIPS). [ 0.048896] CPU: All CPU(s) started in HYP mode. [ 0.048901] CPU: Virtualization extensions available. [ 0.049741] devtmpfs: initialized [ 0.054348] VFP support v0.3: implementor 41 architecture 2 part 30 variant 7 rev 4 [ 0.060199] pinctrl core: initialized pinctrl subsystem [ 0.060739] regulator-dummy: no parameters [ 0.067311] NET: Registered protocol family 16 [ 0.067707] DMA: preallocated 256 KiB pool for atomic coherent allocations [ 0.075900] No ATAGs? [ 0.075954] hw-breakpoint: found 5 (+1 reserved) breakpoint and 4 watchpoint registers. [ 0.075966] hw-breakpoint: maximum watchpoint size is 8 bytes. [ 0.076145] EXYNOS: PMU not supported [ 0.077010] Serial: AMBA PL011 UART driver [ 0.098021] edma-dma-engine edma-dma-engine.0: Can't allocate PaRAM dummy slot [ 0.098054] edma-dma-engine: probe of edma-dma-engine.0 failed with error -5 [ 0.098538] reg-fixed-voltage ahci-5v: could not find pctldev for node /soc@01c00000/pinctrl@01c20800/ahci_pwr_pin@1, deferring probe [ 0.098558] platform ahci-5v: Driver reg-fixed-voltage requests probe deferral [ 0.098583] reg-fixed-voltage usb1-vbus: could not find pctldev for node /soc@01c00000/pinctrl@01c20800/usb1_vbus_pin@0, deferring probe [ 0.098597] platform usb1-vbus: Driver reg-fixed-voltage requests probe deferral [ 0.098619] reg-fixed-voltage usb2-vbus: could not find pctldev for node /soc@01c00000/pinctrl@01c20800/usb2_vbus_pin@0, deferring probe [ 0.098632] platform usb2-vbus: Driver reg-fixed-voltage requests probe deferral [ 0.098912] vcc3v0: 3000 mV [ 0.099201] vcc3v3: 3300 mV [ 0.099302] reg-fixed-voltage vmmc3: could not find pctldev for node /soc@01c00000/pinctrl@01c20800/vmmc3_pin@0, deferring probe [ 0.099320] platform vmmc3: Driver reg-fixed-voltage requests probe deferral [ 0.100707] vgaarb: loaded [ 0.101315] usbcore: registered new interface driver usbfs [ 0.101390] usbcore: registered new interface driver hub [ 0.101641] usbcore: registered new device driver usb [ 0.102351] media: Linux media interface: v0.10 [ 0.102425] Linux video capture interface: v2.00 [ 0.102537] pps_core: LinuxPPS API ver. 1 registered [ 0.102546] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it> [ 0.102577] PTP clock support registered [ 0.104216] Switched to clocksource arch_sys_counter [ 0.156813] NET: Registered protocol family 2 [ 0.158124] TCP established hash table entries: 8192 (order: 3, 32768 bytes) [ 0.158218] TCP bind hash table entries: 8192 (order: 4, 65536 bytes) [ 0.158340] TCP: Hash tables configured (established 8192 bind 8192) [ 0.158431] TCP: reno registered [ 0.158448] UDP hash table entries: 512 (order: 2, 16384 bytes) [ 0.158508] UDP-Lite hash table entries: 512 (order: 2, 16384 bytes) [ 0.158839] NET: Registered protocol family 1 [ 0.159455] Unpacking initramfs... [ 3.136726] Freeing initrd memory: 34172K (c3301000 - c5460000) [ 3.137253] hw perfevents: enabled with ARMv7 Cortex-A7 PMU driver, 5 counters available [ 3.139284] futex hash table entries: 512 (order: 3, 32768 bytes) [ 3.139453] audit: initializing netlink subsys (disabled) [ 3.139561] audit: type=2000 audit(3.124:1): initialized [ 3.141425] zbud: loaded [ 3.141874] VFS: Disk quotas dquot_6.5.2 [ 3.141949] Dquot-cache hash table entries: 1024 (order 0, 4096 bytes) [ 3.142123] msgmni has been set to 1465 [ 3.143837] alg: No test for stdrng (krng) [ 3.143984] bounce: pool size: 64 pages [ 3.144086] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 249) [ 3.144430] io scheduler noop registered [ 3.144450] io scheduler deadline registered [ 3.144604] io scheduler cfq registered (default) [ 3.147972] sun7i-a20-pinctrl 1c20800.pinctrl: initialized sunXi PIO driver [ 3.150285] Serial: 8250/16550 driver, 4 ports, IRQ sharing disabled [ 3.152470] console [ttyS0] disabled [ 3.172685] 1c28000.serial: ttyS0 at MMIO 0x1c28000 (irq = 33, base_baud = 1500000) is a U6_16550A [ 3.919281] console [ttyS0] enabled [ 3.923258] Serial: AMBA driver [ 3.926751] Serial: IMX driver [ 3.933889] mousedev: PS/2 mouse device common for all mice [ 3.941249] sunxi-rtc 1c20d00.rtc: rtc core: registered rtc-sunxi as rtc0 [ 3.948079] sunxi-rtc 1c20d00.rtc: RTC enabled [ 3.953753] ledtrig-cpu: registered to indicate activity on CPUs [ 3.959893] hidraw: raw HID events driver (C) Jiri Kosina [ 3.966002] usbcore: registered new interface driver usbhid [ 3.971592] usbhid: USB HID core driver [ 3.976293] TCP: cubic registered [ 3.979718] NET: Registered protocol family 10 [ 3.985358] mip6: Mobile IPv6 [ 3.988391] NET: Registered protocol family 17 [ 3.992873] mpls_gso: MPLS GSO support [ 3.996752] ThumbEE CPU extension supported. [ 4.001056] Registering SWP/SWPB emulation handler [ 4.006696] registered taskstats version 1 [ 4.011957] ahci-5v: 5000 mV [ 4.015514] usb1-vbus: 5000 mV [ 4.019159] usb2-vbus: 5000 mV [ 4.022742] vmmc3: 3300 mV [ 4.025993] sunxi-rtc 1c20d00.rtc: setting system clock to 1970-01-01 00:00:29 UTC (29) [ 4.038853] vmmc3: disabling [ 4.041770] usb2-vbus: disabling [ 4.045010] usb1-vbus: disabling [ 4.048277] ahci-5v: disabling [ 4.051333] vcc3v3: disabling [ 4.054317] vcc3v0: disabling [ 4.058543] Freeing unused kernel memory: 688K (c0877000 - c0923000) Loading, please wait... [ 4.138495] systemd-udevd[61]: starting version 215 [ 4.146170] random: systemd-udevd: uninitialized urandom read (16 bytes read, 0 bits of entropy available) [ 4.204172] sunxi-mmc 1c0f000.mmc: No vqmmc regulator found [ 4.213627] sunxi-mmc 1c0f000.mmc: Got CD GPIO #225. [ 4.224297] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver [ 4.233475] ehci-platform: EHCI generic platform driver [ 4.239220] SCSI subsystem initialized [ 4.243856] ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver [ 4.261102] ohci-platform: OHCI generic platform driver [ 4.269142] sunxi-mmc 1c0f000.mmc: base:0xf00c2000 irq:64 [ 4.275520] sunxi-mmc 1c12000.mmc: No vqmmc regulator found [ 4.324318] sunxi-mmc 1c12000.mmc: base:0xf01f0000 irq:67 [ 4.325852] mmc0: host does not support reading read-only switch. assuming write-enable. [ 4.330150] mmc0: new high speed SDHC card at address aaaa [ 4.343446] mmcblk0: mmc0:aaaa SU08G 7.40 GiB [ 4.346612] ehci-platform 1c14000.usb: EHCI Host Controller [ 4.347025] ehci-platform 1c14000.usb: new USB bus registered, assigned bus number 1 [ 4.347184] ehci-platform 1c14000.usb: irq 71, io mem 0x01c14000 [ 4.368515] ehci-platform 1c14000.usb: USB 2.0 started, EHCI 1.00 [ 4.374876] mmcblk0: p1 [ 4.377899] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002 [ 4.384751] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1 [ 4.392025] usb usb1: Product: EHCI Host Controller [ 4.396962] usb usb1: Manufacturer: Linux 3.16.0-4-armmp ehci_hcd [ 4.403105] usb usb1: SerialNumber: 1c14000.usb [ 4.409736] hub 1-0:1.0: USB hub found [ 4.414755] hub 1-0:1.0: 1 port detected [ 4.421658] sunxi-mmc 1c12000.mmc: smc 1 err, cmd 8, RTO !! [ 4.427532] ehci-platform 1c1c000.usb: EHCI Host Controller [ 4.433718] ehci-platform 1c1c000.usb: new USB bus registered, assigned bus number 2 [ 4.441808] ehci-platform 1c1c000.usb: irq 72, io mem 0x01c1c000 [ 4.456355] mmc1: queuing unknown CIS tuple 0x80 (2 bytes) [ 4.461987] ehci-platform 1c1c000.usb: USB 2.0 started, EHCI 1.00 [ 4.468608] usb usb2: New USB device found, idVendor=1d6b, idProduct=0002 [ 4.475506] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1 [ 4.482782] usb usb2: Product: EHCI Host Controller [ 4.487706] usb usb2: Manufacturer: Linux 3.16.0-4-armmp ehci_hcd [ 4.493839] usb usb2: SerialNumber: 1c1c000.usb [ 4.499495] hub 2-0:1.0: USB hub found [ 4.503463] hub 2-0:1.0: 1 port detected [ 4.509545] sunxi-wdt 1c20c90.watchdog: Watchdog enabled (timeout=16 sec, nowayout=0) [ 4.510178] mmc1: queuing unknown CIS tuple 0x80 (3 bytes) [ 4.511689] mmc1: queuing unknown CIS tuple 0x80 (3 bytes) [ 4.514418] mmc1: queuing unknown CIS tuple 0x80 (7 bytes) [ 4.535662] ohci-platform 1c14400.usb: Generic Platform OHCI controller [ 4.540654] mmc1: new high speed SDIO card at address 0001 [ 4.550252] ohci-platform 1c14400.usb: new USB bus registered, assigned bus number 3 [ 4.560404] ohci-platform 1c14400.usb: irq 96, io mem 0x01c14400 [ 4.625671] usb usb3: New USB device found, idVendor=1d6b, idProduct=0001 [ 4.632581] usb usb3: New USB device strings: Mfr=3, Product=2, SerialNumber=1 [ 4.638532] cfg80211: Calling CRDA to update world regulatory domain [ 4.646222] usb usb3: Product: Generic Platform OHCI controller [ 4.652191] usb usb3: Manufacturer: Linux 3.16.0-4-armmp ohci_hcd [ 4.656920] brcmfmac_sdio mmc1:0001:1: firmware: failed to load brcm/brcmfmac43362-sdio.bin (-2) [ 4.667092] usb usb3: SerialNumber: 1c14400.usb [ 4.672831] hub 3-0:1.0: USB hub found [ 4.676804] hub 3-0:1.0: 1 port detected [ 4.724335] ahci-sunxi 1c18000.sata: controller can't do PMP, turning off CAP_PMP [ 4.731963] ahci-sunxi 1c18000.sata: forcing PORTS_IMPL to 0x1 [ 4.737972] ahci-sunxi 1c18000.sata: AHCI 0001.0100 32 slots 1 ports 3 Gbps 0x1 impl platform mode [ 4.746989] ahci-sunxi 1c18000.sata: flags: ncq sntf pm led clo only pio slum part ccc [ 4.757548] scsi0 : ahci_platform [ 4.762538] ata1: SATA max UDMA/133 mmio [mem 0x01c18000-0x01c18fff] port 0x100 irq 88 [ 4.783498] Adding alias for supply acin,(null) -> acin,0-0034 [ 4.790176] Adding alias for supply vin2,(null) -> vin2,0-0034 [ 4.796442] Adding alias for supply vin3,(null) -> vin3,0-0034 [ 4.802539] Adding alias for supply ldo24in,(null) -> ldo24in,0-0034 [ 4.809133] Adding alias for supply ldo3in,(null) -> ldo3in,0-0034 [ 4.815493] Adding alias for supply ldo5in,(null) -> ldo5in,0-0034 [ 4.822917] axp20x 0-0034: AXP20X driver loaded [ 4.828945] stmmaceth 1c50000.ethernet: no regulator found [ 4.834688] stmmaceth 1c50000.ethernet: no reset control found [ 4.840639] Ring mode enabled [ 4.843706] No HW DMA feature register supported [ 4.848268] Normal descriptors [ 4.851593] TX Checksum insertion supported [ 4.875044] libphy: stmmac: probed [ 4.878502] eth0: PHY ID 001cc915 at 0 IRQ POLL (stmmac-0:00) active [ 4.884865] eth0: PHY ID 001cc915 at 1 IRQ POLL (stmmac-0:01) [ 4.890979] ohci-platform 1c1c400.usb: Generic Platform OHCI controller [ 4.899454] ohci-platform 1c1c400.usb: new USB bus registered, assigned bus number 4 [ 4.907420] ohci-platform 1c1c400.usb: irq 97, io mem 0x01c1c400 [ 4.972471] usb usb4: New USB device found, idVendor=1d6b, idProduct=0001 [ 4.979282] usb usb4: New USB device strings: Mfr=3, Product=2, SerialNumber=1 [ 4.986526] usb usb4: Product: Generic Platform OHCI controller [ 4.992458] usb usb4: Manufacturer: Linux 3.16.0-4-armmp ohci_hcd [ 4.998558] usb usb4: SerialNumber: 1c1c400.usb [ 5.004078] hub 4-0:1.0: USB hub found [ 5.007930] hub 4-0:1.0: 1 port detected [ 5.100254] ata1: SATA link down (SStatus 0 SControl 300) Begin: Loading essential drivers ... done. Begin: Running /scripts/init-premount ... done. Begin: Mounting root file system ... Begin: Running /scripts/nfs-top ... done. Begin: Running /scripts/nfs-premount ... done. [ 5.202256] FS-Cache: Loaded [ 5.223523] RPC: Registered named UNIX socket transport module. [ 5.229562] RPC: Registered udp transport module. [ 5.234282] RPC: Registered tcp transport module. [ 5.238996] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 5.263830] FS-Cache: Netfs 'nfs' registered for caching IP-Config: eth0 hardware address 02:8d:07:42:51:fa mtu 1500 DHCP [ 5.309733] No MAC Management Counters available [ 5.663586] brcmfmac: brcmf_sdio_htclk: HT Avail timeout (1000000): clkctl 0x50 [ 6.680189] brcmfmac: brcmf_sdio_htclk: HT Avail timeout (1000000): clkctl 0x50 [ 7.296549] stmmaceth 1c50000.ethernet eth0: Link is Up - 1Gbps/Full - flow control off IP-Config: no response after 2 secs - giving up IP-Config: eth0 hardware address 02:8d:07:42:51:fa mtu 1500 DHCP IP-Config: eth0 guessed broadcast address 10.15.255.255 IP-Config: eth0 complete (dhcp from 10.0.0.2): address: 10.15.1.209 broadcast: 10.15.255.255 netmask: 255.240.0.0 gateway: 10.0.0.1 dns0 : 10.0.0.2 dns1 : 0.0.0.0 domain : lavalab rootserver: 10.0.0.2 rootpath: filename : done. Begin: Running /scripts/nfs-bottom ... done. Begin: Running /scripts/init-bottom ... done. [ 8.880315] random: systemd: uninitialized urandom read (16 bytes read, 43 bits of entropy available) [ 8.893837] systemd[1]: systemd 215 running in system mode. (+PAM +AUDIT +SELINUX +IMA +SYSVINIT +LIBCRYPTSETUP +GCRYPT +ACL +XZ -SECCOMP -APPARMOR) [ 8.907686] systemd[1]: Detected architecture 'arm'. Welcome to [1mDebian GNU/Linux 8 (jessie)[0m! [ 8.971564] systemd[1]: Inserted module 'autofs4' [ 8.979335] systemd[1]: Set hostname to <jessie>. [ 9.079798] random: systemd-sysv-ge: uninitialized urandom read (16 bytes read, 47 bits of entropy available) [ 9.186984] random: systemd: uninitialized urandom read (16 bytes read, 50 bits of entropy available) [ 9.196714] random: systemd: uninitialized urandom read (16 bytes read, 50 bits of entropy available) [ 9.207188] random: systemd: uninitialized urandom read (16 bytes read, 50 bits of entropy available) [ 9.242468] random: systemd: uninitialized urandom read (16 bytes read, 51 bits of entropy available) [ 9.255353] random: systemd: uninitialized urandom read (16 bytes read, 51 bits of entropy available) [ 9.264970] random: systemd: uninitialized urandom read (16 bytes read, 51 bits of entropy available) [ 9.331738] random: systemd: uninitialized urandom read (16 bytes read, 52 bits of entropy available) [ 9.490958] systemd[1]: Cannot add dependency job for unit dbus.socket, ignoring: Unit dbus.socket failed to load: No such file or directory. [ 9.503779] systemd[1]: Cannot add dependency job for unit display-manager.service, ignoring: Unit display-manager.service failed to load: No such file or directory. [ 9.521020] systemd[1]: Expecting device dev-ttyS0.device... Expecting device dev-ttyS0.device... [ 9.532454] systemd[1]: Starting Forward Password Requests to Wall Directory Watch. [ 9.540561] systemd[1]: Started Forward Password Requests to Wall Directory Watch. [ 9.548267] systemd[1]: Starting Remote File Systems (Pre). [[32m OK [0m] Reached target Remote File Systems (Pre). [ 9.560417] systemd[1]: Reached target Remote File Systems (Pre). [ 9.566685] systemd[1]: Starting Encrypted Volumes. [[32m OK [0m] Reached target Encrypted Volumes. [ 9.580363] systemd[1]: Reached target Encrypted Volumes. [ 9.586040] systemd[1]: Starting Arbitrary Executable File Formats File System Automount Point. [[32m OK [0m] Set up automount Arbitrary Executable File Formats F...utomount Point. [ 9.608396] systemd[1]: Set up automount Arbitrary Executable File Formats File System Automount Point. [ 9.618024] systemd[1]: Starting Dispatch Password Requests to Console Directory Watch. [ 9.626398] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. [ 9.634418] systemd[1]: Starting Paths. [[32m OK [0m] Reached target Paths. [ 9.644378] systemd[1]: Reached target Paths. [ 9.648861] systemd[1]: Starting Swap. [[32m OK [0m] Reached target Swap. [ 9.660378] systemd[1]: Reached target Swap. [ 9.664791] systemd[1]: Starting Root Slice. [[32m OK [0m] Created slice Root Slice. [ 9.676359] systemd[1]: Created slice Root Slice. [ 9.681173] systemd[1]: Starting User and Session Slice. [[32m OK [0m] Created slice User and Session Slice. [ 9.696356] systemd[1]: Created slice User and Session Slice. [ 9.702222] systemd[1]: Starting /dev/initctl Compatibility Named Pipe. [[32m OK [0m] Listening on /dev/initctl Compatibility Named Pipe. [ 9.724398] systemd[1]: Listening on /dev/initctl Compatibility Named Pipe. [ 9.731531] systemd[1]: Starting Delayed Shutdown Socket. [[32m OK [0m] Listening on Delayed Shutdown Socket. [ 9.744379] systemd[1]: Listening on Delayed Shutdown Socket. [ 9.750283] systemd[1]: Starting Journal Socket (/dev/log). [[32m OK [0m] Listening on Journal Socket (/dev/log). [ 9.764404] systemd[1]: Listening on Journal Socket (/dev/log). [ 9.770534] systemd[1]: Starting udev Kernel Socket. [[32m OK [0m] Listening on udev Kernel Socket. [ 9.784399] systemd[1]: Listening on udev Kernel Socket. [ 9.789933] systemd[1]: Starting udev Control Socket. [[32m OK [0m] Listening on udev Control Socket. [ 9.804379] systemd[1]: Listening on udev Control Socket. [ 9.809943] systemd[1]: Starting Journal Socket. [[32m OK [0m] Listening on Journal Socket. [ 9.824383] systemd[1]: Listening on Journal Socket. [ 9.829558] systemd[1]: Starting System Slice. [[32m OK [0m] Created slice System Slice. [ 9.840394] systemd[1]: Created slice System Slice. [ 9.845421] systemd[1]: Starting system-getty.slice. [[32m OK [0m] Created slice system-getty.slice. [ 9.860395] systemd[1]: Created slice system-getty.slice. [ 9.865958] systemd[1]: Starting system-serial\x2dgetty.slice. [[32m OK [0m] Created slice system-serial\x2dgetty.slice. [ 9.880378] systemd[1]: Created slice system-serial\x2dgetty.slice. [ 9.886899] systemd[1]: Starting Increase datagram queue length... Starting Increase datagram queue length... [ 9.909229] systemd[1]: Starting Load Kernel Modules... Starting Load Kernel Modules... [ 9.929827] systemd[1]: Starting Create list of required static device nodes for the current kernel... Starting Create list of required static device nodes...rrent kernel... [ 9.956366] systemd[1]: Starting udev Coldplug all Devices... Starting udev Coldplug all Devices... [ 9.976189] systemd[1]: Mounting POSIX Message Queue File System... Mounting POSIX Message Queue File System... [ 9.999818] systemd[1]: Mounted Huge Pages File System. [ 10.014356] systemd[1]: Started Set Up Additional Binary Formats. [ 10.023147] systemd[1]: Mounting Debug File System... Mounting Debug File System... [ 10.039865] systemd[1]: Starting Slices. [[32m OK [0m] Reached target Slices. [ 10.052528] systemd[1]: Reached target Slices. [ 10.057430] systemd[1]: Starting Remount Root and Kernel File Systems... Starting Remount Root and Kernel File Systems... [[32m OK [0m] Mounted POSIX Message Queue File System. [ 10.092556] systemd[1]: Mounted POSIX Message Queue File System. [[32m OK [0m] Mounted Debug File System. [ 10.112520] systemd[1]: Mounted Debug File System. [[32m OK [0m] Started Increase datagram queue length. [ 10.128602] systemd[1]: Started Increase datagram queue length. [[32m OK [0m] Started Load Kernel Modules. [ 10.144462] systemd[1]: Started Load Kernel Modules. [[32m OK [0m] Started Create list of required static device nodes ...current kernel. [ 10.164492] systemd[1]: Started Create list of required static device nodes for the current kernel. [[32m OK [0m] Started Remount Root and Kernel File Systems. [ 10.192561] systemd[1]: Started Remount Root and Kernel File Systems. [[32m OK [0m] Started udev Coldplug all Devices. [ 10.248457] systemd[1]: Started udev Coldplug all Devices. [ 10.345123] systemd[1]: Starting Various fixups to make systemd work better on Debian... Starting Various fixups to make systemd work better on Debian... [ 10.367947] systemd[1]: Starting Load/Save Random Seed... Starting Load/Save Random Seed... [ 10.385218] systemd[1]: Starting Create Static Device Nodes in /dev... Starting Create Static Device Nodes in /dev... [ 10.404814] systemd[1]: Mounted Configuration File System. [ 10.412659] systemd[1]: Mounted FUSE Control File System. [ 10.421754] systemd[1]: Starting Apply Kernel Variables... Starting Apply Kernel Variables... [ 10.440059] systemd[1]: Starting Syslog Socket. [[32m OK [0m] Listening on Syslog Socket. [ 10.452547] systemd[1]: Listening on Syslog Socket. [ 10.457816] systemd[1]: Starting Sockets. [[32m OK [0m] Reached target Sockets. [ 10.468563] systemd[1]: Reached target Sockets. [ 10.473344] systemd[1]: Starting Journal Service... Starting Journal Service... [[32m OK [0m] Started Journal Service. [ 10.505857] systemd[1]: Started Journal Service. [[32m OK [0m] Started Various fixups to make systemd work better on Debian. [[32m OK [0m] Started Load/Save Random Seed. [[32m OK [0m] Started Apply Kernel Variables. [[32m OK [0m] Started Create Static Device Nodes in /dev. Starting udev Kernel Device Manager... [[32m OK [0m] Reached target Local File Systems (Pre). [[32m OK [0m] Reached target Local File Systems. Starting Create Volatile Files and Directories... [[32m OK [0m] Reached target Remote File Systems. [ 10.680162] systemd-udevd[161]: starting version 215 Starting Trigger Flushing of Journal to Persistent Storage... Starting LSB: Raise network interfaces.... [[32m OK [0m] Started udev Kernel Device Manager. Starting Copy rules generated while the root was ro... [ 10.809109] systemd-journald[159]: Received request to flush runtime journal from PID 1 [[32m OK [0m] Started Trigger Flushing of Journal to Persistent Storage. [[32m OK [0m] Started Copy rules generated while the root was ro. [[32m OK [0m] Started Create Volatile Files and Directories. Starting Update UTMP about System Boot/Shutdown... [[32m OK [0m] Started Update UTMP about System Boot/Shutdown. [[32m OK [0m] Found device /dev/ttyS0. [[32m OK [0m] Started LSB: Raise network interfaces.. [[32m OK [0m] Reached target Network. [[32m OK [0m] Reached target System Initialization. [[32m OK [0m] Reached target Timers. [[32m OK [0m] Reached target Basic System. Starting Regular background program processing daemon... [[32m OK [0m] Started Regular background program processing daemon. Starting /etc/rc.local Compatibility... Starting getty on tty2-tty6 if dbus and logind are not available... Starting LSB: daemon to balance interrupts for SMP systems... Starting System Logging Service... Starting Permit User Sessions... [[32m OK [0m] Started /etc/rc.local Compatibility. [[32m OK [0m] Started Permit User Sessions. [[32m OK [0m] Started LSB: daemon to balance interrupts for SMP systems. [[32m OK [0m] Started System Logging Service. Starting Getty on tty3... [[32m OK [0m] Started Getty on tty3. Starting Getty on tty2... [[32m OK [0m] Started Getty on tty2. Starting Getty on tty1... [[32m OK [0m] Started Getty on tty1. Starting Serial Getty on ttyS0... [[32m OK [0m] Started Serial Getty on ttyS0. [[32m OK [0m] Started getty on tty2-tty6 if dbus and logind are not available. Starting Getty on tty6... [[32m OK [0m] Started Getty on tty6. Starting Getty on tty5... [[32m OK [0m] Started Getty on tty5. Starting Getty on tty4... [[32m OK [0m] Started Getty on tty4. [[32m OK [0m] Reached target Login Prompts. [[32m OK [0m] Reached target Multi-User System. [[32m OK [0m] Reached target Graphical Interface. Starting Update UTMP about System Runlevel Changes... [[32m OK [0m] Started Update UTMP about System Runlevel Changes. Debian GNU/Linux 8 jessie ttyS0 Matched prompt #5: login: case: kernel-messages
case_id: 24615
definition: lava
duration: 10.34
extra: ...
level: 2.4.4
namespace: common
result: pass
Sending username root root jessie login: root auto-login-action: Wait for prompt ['root@jessie:', 'Login incorrect', 'Login timed out'] (timeout 00:07:38) root Linux jessie 3.16.0-4-armmp #1 SMP Debian 3.16.39-1 (2016-12-30) armv7l The programs included with the Debian GNU/Linux system are free software; the exact distribution terms for each program are described in the individual files in /usr/share/doc/*/copyright. Debian GNU/Linux comes with ABSOLUTELY NO WARRANTY, to the extent permitted by applicable law. end: 2.4.4 auto-login-action (duration 00:00:16) [common] case: auto-login-action
case_id: 24616
definition: lava
duration: 15.92
extra: ...
level: 2.4.4
namespace: common
result: pass
start: 2.4.5 expect-shell-connection (timeout 00:07:38) [common] Forcing a shell prompt, looking for ['root@jessie:'] root@jessie:~# expect-shell-connection: Wait for prompt ['root@jessie:'] (timeout 00:10:00) Waiting using forced prompt support. 299.974161863327s timeout end: 2.4.5 expect-shell-connection (duration 00:00:00) [common] start: 2.4.6 export-device-env (timeout 00:07:38) [common] end: 2.4.6 export-device-env (duration 00:00:00) [common] end: 2.4 uboot-retry (duration 00:01:22) [common] end: 2 uboot-action (duration 00:01:23) [common] start: 3 lava-test-retry (timeout 00:05:00) [common] start: 3.1 lava-test-shell (timeout 00:05:00) [common] Using namespace: common # root@jessie:~# # lava-test-shell: Wait for prompt ['root@jessie:'] (timeout 00:05:00) # Using /lava-1216 export SHELL=/bin/bash root@jessie:~# export SHELL=/bin/bash export SHELL=/bin/bash . /lava-1216/environment root@jessie:~# . /lava-1216/environment . /lava-1216/environment /lava-1216/bin/lava-test-runner /lava-1216/0 root@jessie:~# /lava-1216/bin/lava-test-runner /lava-1216/0 Test shell timeout: 10s (minimum of the action and connection timeout) /lava-1216/bin/lava-test-runner /lava-1216/0 + export TESTRUN_ID=0_smoke-tests + TESTRUN_ID=0_smoke-tests + cd /lava-1216/0/tests/0_smoke-tests ++ cat uuid + UUID=1216_1.6.2.4.1 + set +x <LAVA_SIGNAL_STARTRUN 0_smoke-tests 1216_1.6.2.4.1> + lava-test-case linux-posix-pwd --shell pwd <LAVA_SIGNAL_STARTTC linux-posix-pwd> Received signal: <STARTRUN> 0_smoke-tests 1216_1.6.2.4.1 Starting test lava.0_smoke-tests (1216_1.6.2.4.1) Skipping test definition patterns. Received signal: <STARTTC> linux-posix-pwd /lava-1216/0/tests/0_smoke-tests <LAVA_SIGNAL_ENDTC linux-posix-pwd> Received signal: <ENDTC> linux-posix-pwd Received signal: <TESTCASE> TEST_CASE_ID=linux-posix-pwd RESULT=pass case: linux-posix-pwd
case_id: 24617
definition: 0_smoke-tests
result: pass
<LAVA_SIGNAL_TESTCASE TEST_CASE_ID=linux-posix-pwd RESULT=pass> + lava-test-case linux-posix-uname --shell uname -a <LAVA_SIGNAL_STARTTC linux-posix-uname> Linux jessie 3.16.0-4-armmp #1 SMP Debian 3.16.39-1 (2016-12-30) armv7l GNU/Linux <LAVA_SIGNAL_ENDTC linux-posix-uname> Received signal: <STARTTC> linux-posix-uname Received signal: <ENDTC> linux-posix-uname <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=linux-posix-uname RESULT=pass> Received signal: <TESTCASE> TEST_CASE_ID=linux-posix-uname RESULT=pass case: linux-posix-uname
case_id: 24618
definition: 0_smoke-tests
result: pass
+ lava-test-case linux-posix-vmstat --shell vmstat <LAVA_SIGNAL_STARTTC linux-posix-vmstat> Received signal: <STARTTC> linux-posix-vmstat procs -----------memory---------- ---swap-- -----io---- -system-- ------cpu----- r b swpd free buff cache si so bi bo in cs us sy id wa st 0 0 0 2019472 0 24192 0 0 62 0 398 785 6 30 63 1 0 <LAVA_SIGNAL_ENDTC linux-posix-vmstat> <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=linux-posix-vmstat RESULT=pass> Received signal: <ENDTC> linux-posix-vmstat Received signal: <TESTCASE> TEST_CASE_ID=linux-posix-vmstat RESULT=pass case: linux-posix-vmstat
case_id: 24619
definition: 0_smoke-tests
result: pass
+ lava-test-case linux-posix-ifconfig --shell ifconfig -a <LAVA_SIGNAL_STARTTC linux-posix-ifconfig> eth0 Link encap:Ethernet HWaddr 02:8d:07:42:51:fa Received signal: <STARTTC> linux-posix-ifconfig inet addr:10.15.1.209 Bcast:10.15.255.255 Mask:255.240.0.0 inet6 addr: fe80::8d:7ff:fe42:51fa/64 Scope:Link UP BROADCAST RUNNING MULTICAST MTU:1500 Metric:1 RX packets:15601 errors:0 dropped:0 overruns:0 frame:0 TX packets:4293 errors:0 dropped:0 overruns:0 carrier:0 collisions:0 txqueuelen:1000 RX bytes:19482782 (18.5 MiB) TX bytes:740538 (723.1 KiB) Interrupt:117 lo Link encap:Local Loopback inet addr:127.0.0.1 Mask:255.0.0.0 inet6 addr: ::1/128 Scope:Host UP LOOPBACK RUNNING MTU:65536 Metric:1 RX packets:0 errors:0 dropped:0 overruns:0 frame:0 TX packets:0 errors:0 dropped:0 overruns:0 carrier:0 collisions:0 txqueuelen:0 RX bytes:0 (0.0 B) TX bytes:0 (0.0 B) <LAVA_SIGNAL_ENDTC linux-posix-ifconfig> Received signal: <ENDTC> linux-posix-ifconfig <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=linux-posix-ifconfig RESULT=pass> Received signal: <TESTCASE> TEST_CASE_ID=linux-posix-ifconfig RESULT=pass case: linux-posix-ifconfig
case_id: 24620
definition: 0_smoke-tests
result: pass
+ lava-test-case linux-posix-lscpu --shell lscpu <LAVA_SIGNAL_STARTTC linux-posix-lscpu> Received signal: <STARTTC> linux-posix-lscpu Architecture: armv7l Byte Order: Little Endian CPU(s): 2 On-line CPU(s) list: 0,1 Thread(s) per core: 1 Core(s) per socket: 2 Socket(s): 1 Model name: ARMv7 Processor rev 4 (v7l) <LAVA_SIGNAL_ENDTC linux-posix-lscpu> <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=linux-posix-lscpu RESULT=pass> Received signal: <ENDTC> linux-posix-lscpu Received signal: <TESTCASE> TEST_CASE_ID=linux-posix-lscpu RESULT=pass case: linux-posix-lscpu
case_id: 24621
definition: 0_smoke-tests
result: pass
+ lava-test-case linux-posix-lsb_release --shell lsb_release -a <LAVA_SIGNAL_STARTTC linux-posix-lsb_release> Received signal: <STARTTC> linux-posix-lsb_release /lava-1216/0/../bin/lava-test-case: line 27: lsb_release: command not found <LAVA_SIGNAL_ENDTC linux-posix-lsb_release> <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=linux-posix-lsb_release RESULT=fail> + set +x <LAVA_SIGNAL_ENDRUN 0_smoke-tests 1216_1.6.2.4.1> <LAVA_TEST_RUNNER EXIT> Received signal: <ENDTC> linux-posix-lsb_release Received signal: <TESTCASE> TEST_CASE_ID=linux-posix-lsb_release RESULT=fail case: linux-posix-lsb_release
case_id: 24622
definition: 0_smoke-tests
result: fail
Received signal: <ENDRUN> 0_smoke-tests 1216_1.6.2.4.1 Ending use of test pattern. Ending test lava.0_smoke-tests (1216_1.6.2.4.1), duration 0.32 case: 0_smoke-tests
case_id: 24623
commit_id: 0f8952f18e992371f8686c59b36f44e9c6a94a1a
definition: lava
duration: 0.32
namespace: common
path: lava-test-shell/smoke-tests-basic.yaml
repository: http://git.linaro.org/lava-team/lava-functional-tests.git
result: pass
revision: unspecified
uuid: 1216_1.6.2.4.1
ok: lava_test_shell seems to have completed linux-posix-ifconfig: pass linux-posix-lsb_release: fail linux-posix-lscpu: pass linux-posix-pwd: pass linux-posix-uname: pass linux-posix-vmstat: pass end: 3.1 lava-test-shell (duration 00:00:01) [common] end: 3 lava-test-retry (duration 00:00:01) [common] start: 4 finalize (timeout 00:00:30) [common] start: 4.1 power-off (timeout 00:00:10) [common] Calling: 'nice' '/usr/local/lab-scripts/snmp_pdu_control' '--hostname' 'pdu18' '--command' 'off' '--port' '14' >> pdu18 port 14 now powered off Returned 0 in 4 seconds end: 4.1 power-off (duration 00:00:04) [common] case: power-off
case_id: 24626
definition: lava
duration: 4.27
extra: ...
level: 4.1
namespace: common
result: pass
start: 4.2 read-feedback (timeout 00:00:26) [common] Listened to connection for namespace 'common' for 1s Finalising connection for namespace 'common' Disconnecting ShellSession Disconnecting from telnet: Finalise ] quit quit end: 4.2 read-feedback (duration 00:00:01) [common] end: 4 finalize (duration 00:00:05) [common] Cleaning after the job Cleaning up download directory: /var/lib/lava/dispatcher/tmp/lavafed-1216/tftp-deploy-cy0ztb04/ramdisk Cleaning up download directory: /var/lib/lava/dispatcher/tmp/lavafed-1216/tftp-deploy-cy0ztb04/kernel Cleaning up download directory: /var/lib/lava/dispatcher/tmp/lavafed-1216/tftp-deploy-cy0ztb04/dtb Cleaning up download directory: /var/lib/lava/dispatcher/tmp/lavafed-1216/tftp-deploy-cy0ztb04/nfsrootfs Cleaning up download directory: /var/lib/lava/dispatcher/tmp/lavafed-1216/tftp-deploy-cy0ztb04/modules Override tmp directory removed at /var/lib/lava/dispatcher/tmp/lavafed-1216 Root tmp directory removed at /var/lib/lava/dispatcher/tmp/lavafed-1216 Job finished correctly case: job
case_id: 24627
definition: lava
result: pass

Top of page

Please read the triage guidelines for help on debugging failures in the test job, test definitions or in individual test cases.

Job Description YAML

Pipeline jobs use the job definition to generate a descriptive YAML file when the pipeline is validated. The file represents the data sent to the dispatcher at the start of the job, including details of the device as well as the job parameters and the details of the parameters sent to each of the deploy, boot and test actions in the job pipeline.

Click on the Metadata, Device or Job Actions headings below to see some of the information from the description file for this job or download the complete file as YAML using the link above.

slave.version
2019.09.0089.gf1de999c2
features.0.description
Deploy to tftp
features.0.action
deploy
features.0.type
device
job.url
https://federation.lavasoftware.org/lava/scheduler/job/{id}
features.2.description
Test definition from git
job.name
cubietruck - nfs health-check
features.2.type
device
features.1.name
boot.u-boot.nfs
slave.arch
amd64
features.2.name
test.definition.git
features.0.name
deploy.tftp
device.name
staging-cubietruck01
features.1.type
device
features.1.action
boot
job.type
test
features.1.description
Boot from u-boot with an nfs rootfs
features.2.action
test
device.type
cubietruck
lab.name
staging.validation.linaro.org
device type
hostname
timeouts
  • connections: {'minutes': 2}{'seconds': 30}{'seconds': 10}{'seconds': 30}{'seconds': 30}{'minutes': 10}{'seconds': 30} auto-login-actionu-boot-interruptlava-test-shellbootloader-interruptbootloader-commandsdd-imageuboot-retry
  • actions: {'minutes': 2}{'minutes': 3}{'seconds': 30}{'minutes': 3}{'seconds': 10}{'seconds': 45}{'minutes': 5}{'minutes': 2}{'minutes': 2}{'minutes': 10}{'minutes': 3}{'minutes': 3}{'minutes': 3}{'minutes': 20}{'minutes': 5}{'minutes': 3}{'seconds': 30}{'minutes': 10}{'minutes': 2} auto-login-actionlava-test-shellbootloader-interruptbootloader-commandspower-offumount-retryhttp-downloadapply-overlay-imageboot-qemu-imagedd-imagebootloader-actionuboot-retryuboot-actionflash-uboot-umsdownload-retrybootloader-retryu-boot-interruptnfs-deployboot-image-retry
device action methods
boot:
  • minimal:
  • ssh:
  • dfu:
    • enter-commands: None
    • command: dfu-util
  • u-boot:
    • needs_interrupt: True
    • interrupt_char:
    • bootloader_prompt: sun7i#
    • interrupt_prompt: Hit any key to stop autoboot
    • mkimage_arch: arm
deploy:
  • tftp:
  • overlay:
  • usb:
  • image:
  • ssh:
  • nbd:
  • lxc:
target
...
timeouts
  • job: {'seconds': 600}

  1. modules
    {'compression': 'gz', 'url': 'http://images.validation.linaro.org/snapshots.linaro.org/components/lava/standard/debian/jessie/armhf/4/modules.tar.gz'}
    namespace
    common
    Timeout
    • 10 minutes
    os
    debian
    dtb
    {'url': 'http://images.validation.linaro.org/snapshots.linaro.org/components/lava/standard/debian/jessie/armhf/4/dtbs/sun7i-a20-cubietruck.dtb'}
    to
    tftp
    kernel
    {'url': 'http://images.validation.linaro.org/snapshots.linaro.org/components/lava/standard/debian/jessie/armhf/4/vmlinuz', 'type': 'zimage'}
    ramdisk
    {'compression': 'gz', 'url': 'http://images.validation.linaro.org/snapshots.linaro.org/components/lava/standard/debian/jessie/armhf/4/initramfs.cpio.gz', 'add-header': 'u-boot'}
    deployment_data
    {'line_separator': '\n', 'lava_test_dir': '/lava-%s', 'lava_test_sh_cmd': '/bin/bash', 'distro': 'debian', 'tar_flags': '--warning no-timestamp', 'TESTER_PS1': 'linaro-test [rc=$(echo \\$?)]# ', 'lava_test_shell_file': '~/.bashrc', 'TESTER_PS1_PATTERN': 'linaro-test \\[rc=(\\d+)\\]# ', 'lava_test_results_part_attr': 'root_part', 'TESTER_PS1_INCLUDES_RC': True, 'lava_test_results_dir': '/lava-%s', 'boot_cmds': 'boot_cmds'}
    nfsrootfs
    {'compression': 'gz', 'url': 'http://images.validation.linaro.org/snapshots.linaro.org/components/lava/standard/debian/jessie/armhf/4/jessie-armhf-nfs.tar.gz'}

  1. commands
    nfs
    method
    u-boot
    prompts
    ['root@jessie:']
    namespace
    common
    Timeout
    • 10 minutes
    auto_login
    {'login_prompt': 'login:', 'username': 'root'}

  1. stage
    0
    namespace
    common
    Definitions
    • repository http://git.linaro.org/lava-team/lava-functional-tests.git
    • from git
    • name smoke-tests
    • path lava-test-shell/smoke-tests-basic.yaml
    Timeout
    • 5 minutes

Job Timings

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.

Loading the data...