lava-dispatcher, installed at version: 2021.11.0022.g08fafe4de start: 0 validate Start time: 2021-12-03 09:07:01.099857+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/am335x-boneblack.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.22 case: validate
case_id: 74900
definition: lava
result: pass
start: 1 tftp-deploy (timeout 00:04:00) [common] start: 1.1 download-retry (timeout 00:04:00) [common] start: 1.1.1 http-download (timeout 00:04: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-4017/tftp-deploy-bqo4_71b/ramdisk/initramfs.cpio.gz total size: 12427620 (11MB) 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 1.30s (9.12MB/s) end: 1.1.1 http-download (duration 00:00:01) [common] case: http-download
case_id: 74914
definition: lava
duration: 1.30
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 00:03:59) [common] start: 1.2.1 http-download (timeout 00:03:59) [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-4017/tftp-deploy-bqo4_71b/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.30s (10.23MB/s) end: 1.2.1 http-download (duration 00:00:00) [common] case: http-download
case_id: 74914
definition: lava
duration: 0.30
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:03:58) [common] start: 1.3.1 http-download (timeout 00:03:58) [common] downloading http://images.validation.linaro.org/snapshots.linaro.org/components/lava/standard/debian/jessie/armhf/4/dtbs/am335x-boneblack.dtb saving as /var/lib/lava/dispatcher/tmp/lavafed-4017/tftp-deploy-bqo4_71b/dtb/am335x-boneblack.dtb total size: 29018 (0MB) No compression specified progress 100% (0MB) 0MB downloaded in 0.03s (0.87MB/s) end: 1.3.1 http-download (duration 00:00:00) [common] case: http-download
case_id: 74914
definition: lava
duration: 0.03
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:03:58) [common] start: 1.4.1 http-download (timeout 00:03:58) [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-4017/tftp-deploy-bqo4_71b/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 23.42s (8.64MB/s) end: 1.4.1 http-download (duration 00:00:23) [common] case: http-download
case_id: 74914
definition: lava
duration: 23.42
extra: ...
level: 1.4.1
namespace: common
result: pass
end: 1.4 download-retry (duration 00:00:23) [common] start: 1.5 download-retry (timeout 00:03:35) [common] start: 1.5.1 http-download (timeout 00:03:35) [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-4017/tftp-deploy-bqo4_71b/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 2.79s (11.25MB/s) end: 1.5.1 http-download (duration 00:00:03) [common] case: http-download
case_id: 74914
definition: lava
duration: 2.79
extra: ...
level: 1.5.1
namespace: common
result: pass
end: 1.5 download-retry (duration 00:00:03) [common] start: 1.6 prepare-tftp-overlay (timeout 00:03:32) [common] start: 1.6.1 extract-nfsrootfs (timeout 00:03:32) [common] Extracted nfsroot to /var/lib/lava/dispatcher/tmp/lavafed-4017/extract-nfsrootfs-uthsqtm7 end: 1.6.1 extract-nfsrootfs (duration 00:00:16) [common] start: 1.6.2 lava-overlay (timeout 00:03:16) [common] [common] Preparing overlay tarball in /var/lib/lava/dispatcher/tmp/lavafed-4017/lava-overlay-qlqhjz_3 makedir: /var/lib/lava/dispatcher/tmp/lavafed-4017/lava-overlay-qlqhjz_3/lava-4017/bin makedir: /var/lib/lava/dispatcher/tmp/lavafed-4017/lava-overlay-qlqhjz_3/lava-4017/tests makedir: /var/lib/lava/dispatcher/tmp/lavafed-4017/lava-overlay-qlqhjz_3/lava-4017/results Creating /var/lib/lava/dispatcher/tmp/lavafed-4017/lava-overlay-qlqhjz_3/lava-4017/bin/lava-add-keys Creating /var/lib/lava/dispatcher/tmp/lavafed-4017/lava-overlay-qlqhjz_3/lava-4017/bin/lava-add-sources Creating /var/lib/lava/dispatcher/tmp/lavafed-4017/lava-overlay-qlqhjz_3/lava-4017/bin/lava-background-process-start Creating /var/lib/lava/dispatcher/tmp/lavafed-4017/lava-overlay-qlqhjz_3/lava-4017/bin/lava-background-process-stop Creating /var/lib/lava/dispatcher/tmp/lavafed-4017/lava-overlay-qlqhjz_3/lava-4017/bin/lava-common-functions Creating /var/lib/lava/dispatcher/tmp/lavafed-4017/lava-overlay-qlqhjz_3/lava-4017/bin/lava-echo-ipv4 Creating /var/lib/lava/dispatcher/tmp/lavafed-4017/lava-overlay-qlqhjz_3/lava-4017/bin/lava-install-packages Creating /var/lib/lava/dispatcher/tmp/lavafed-4017/lava-overlay-qlqhjz_3/lava-4017/bin/lava-installed-packages Creating /var/lib/lava/dispatcher/tmp/lavafed-4017/lava-overlay-qlqhjz_3/lava-4017/bin/lava-os-build Creating /var/lib/lava/dispatcher/tmp/lavafed-4017/lava-overlay-qlqhjz_3/lava-4017/bin/lava-probe-channel Creating /var/lib/lava/dispatcher/tmp/lavafed-4017/lava-overlay-qlqhjz_3/lava-4017/bin/lava-probe-ip Creating /var/lib/lava/dispatcher/tmp/lavafed-4017/lava-overlay-qlqhjz_3/lava-4017/bin/lava-target-ip Creating /var/lib/lava/dispatcher/tmp/lavafed-4017/lava-overlay-qlqhjz_3/lava-4017/bin/lava-target-mac Creating /var/lib/lava/dispatcher/tmp/lavafed-4017/lava-overlay-qlqhjz_3/lava-4017/bin/lava-target-storage Creating /var/lib/lava/dispatcher/tmp/lavafed-4017/lava-overlay-qlqhjz_3/lava-4017/bin/lava-test-case Creating /var/lib/lava/dispatcher/tmp/lavafed-4017/lava-overlay-qlqhjz_3/lava-4017/bin/lava-test-event Creating /var/lib/lava/dispatcher/tmp/lavafed-4017/lava-overlay-qlqhjz_3/lava-4017/bin/lava-test-feedback Creating /var/lib/lava/dispatcher/tmp/lavafed-4017/lava-overlay-qlqhjz_3/lava-4017/bin/lava-test-raise Creating /var/lib/lava/dispatcher/tmp/lavafed-4017/lava-overlay-qlqhjz_3/lava-4017/bin/lava-test-reference Creating /var/lib/lava/dispatcher/tmp/lavafed-4017/lava-overlay-qlqhjz_3/lava-4017/bin/lava-test-runner Creating /var/lib/lava/dispatcher/tmp/lavafed-4017/lava-overlay-qlqhjz_3/lava-4017/bin/lava-test-set Creating /var/lib/lava/dispatcher/tmp/lavafed-4017/lava-overlay-qlqhjz_3/lava-4017/bin/lava-test-shell Updating /var/lib/lava/dispatcher/tmp/lavafed-4017/lava-overlay-qlqhjz_3/lava-4017/bin/lava-add-keys (debian) Updating /var/lib/lava/dispatcher/tmp/lavafed-4017/lava-overlay-qlqhjz_3/lava-4017/bin/lava-add-sources (debian) Updating /var/lib/lava/dispatcher/tmp/lavafed-4017/lava-overlay-qlqhjz_3/lava-4017/bin/lava-install-packages (debian) Updating /var/lib/lava/dispatcher/tmp/lavafed-4017/lava-overlay-qlqhjz_3/lava-4017/bin/lava-installed-packages (debian) Updating /var/lib/lava/dispatcher/tmp/lavafed-4017/lava-overlay-qlqhjz_3/lava-4017/bin/lava-os-build (debian) Creating /var/lib/lava/dispatcher/tmp/lavafed-4017/lava-overlay-qlqhjz_3/lava-4017/environment LAVA metadata - LAVA_JOB_ID=4017 start: 1.6.2.1 ssh-authorize (timeout 00:03:16) [common] end: 1.6.2.1 ssh-authorize (duration 00:00:00) [common] start: 1.6.2.2 lava-vland-overlay (timeout 00:03:16) [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:03:16) [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:03:16) [common] Loading test definitions start: 1.6.2.4.1 git-repo-action (timeout 00:03:16) [common] Using /lava-4017 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-4017/lava-overlay-qlqhjz_3/lava-4017/0/tests/0_smoke-tests --depth=1' Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/lavafed-4017/lava-overlay-qlqhjz_3/lava-4017/0/tests/0_smoke-tests/lava-test-shell/smoke-tests-basic.yaml uuid=4017_1.6.2.4.1 testdef=None end: 1.6.2.4.1 git-repo-action (duration 00:00:00) [common] case: git-repo-action
case_id: 74935
definition: lava
duration: 0.31
extra: ...
level: 1.6.2.4.1
namespace: common
result: pass
start: 1.6.2.4.2 test-overlay (timeout 00:03:15) [common] end: 1.6.2.4.2 test-overlay (duration 00:00:00) [common] case: test-overlay
case_id: 74936
definition: lava
duration: 0.00
extra: ...
level: 1.6.2.4.2
namespace: common
result: pass
start: 1.6.2.4.3 test-install-overlay (timeout 00:03:15) [common] end: 1.6.2.4.3 test-install-overlay (duration 00:00:00) [common] case: test-install-overlay
case_id: 74937
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:03:15) [common] runner path: /var/lib/lava/dispatcher/tmp/lavafed-4017/lava-overlay-qlqhjz_3/lava-4017/0/tests/0_smoke-tests test_uuid 4017_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: 74938
definition: lava
duration: 0.05
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-4017/lava-overlay-qlqhjz_3/lava-4017/0 for stage 0 - 0_smoke-tests end: 1.6.2.4 test-definition (duration 00:00:00) [common] start: 1.6.2.5 compress-overlay (timeout 00:03:15) [common] end: 1.6.2.5 compress-overlay (duration 00:00:00) [common] start: 1.6.2.6 persistent-nfs-overlay (timeout 00:03:15) [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:03:15) [common] end: 1.6.3 extract-overlay-ramdisk (duration 00:00:02) [common] start: 1.6.4 extract-modules (timeout 00:03:13) [common] extracting modules file /var/lib/lava/dispatcher/tmp/lavafed-4017/tftp-deploy-bqo4_71b/modules/modules.tar to /var/lib/lava/dispatcher/tmp/lavafed-4017/extract-nfsrootfs-uthsqtm7 extracting modules file /var/lib/lava/dispatcher/tmp/lavafed-4017/tftp-deploy-bqo4_71b/modules/modules.tar to /var/lib/lava/dispatcher/tmp/lavafed-4017/extract-overlay-ramdisk-2q091qup/ramdisk end: 1.6.4 extract-modules (duration 00:00:14) [common] start: 1.6.5 apply-overlay-tftp (timeout 00:02:59) [common] [common] Applying overlay to NFS [common] Applying overlay /var/lib/lava/dispatcher/tmp/lavafed-4017/compress-overlay-dbgxbbgr/overlay-1.6.2.5.tar.gz to directory /var/lib/lava/dispatcher/tmp/lavafed-4017/extract-nfsrootfs-uthsqtm7 end: 1.6.5 apply-overlay-tftp (duration 00:00:00) [common] start: 1.6.6 prepare-kernel (timeout 00:02:59) [common] start: 1.6.6.1 uboot-prepare-kernel (timeout 00:02:59) [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:02:59) [common] end: 1.6.7 configure-preseed-file (duration 00:00:00) [common] start: 1.6.8 compress-ramdisk (timeout 00:02:59) [common] Building ramdisk /var/lib/lava/dispatcher/tmp/lavafed-4017/extract-overlay-ramdisk-2q091qup/ramdisk.cpio containing /var/lib/lava/dispatcher/tmp/lavafed-4017/extract-overlay-ramdisk-2q091qup/ramdisk >> 190360 blocks Adding RAMdisk u-boot header. mkimage -A arm -T ramdisk -C none -d /var/lib/lava/dispatcher/tmp/lavafed-4017/extract-overlay-ramdisk-2q091qup/ramdisk.cpio.gz /var/lib/lava/dispatcher/tmp/lavafed-4017/extract-overlay-ramdisk-2q091qup/ramdisk.cpio.gz.uboot output: Image Name: output: Created: Fri Dec 3 09:08:17 2021 output: Image Type: ARM Linux RAMDisk Image (uncompressed) output: Data Size: 34993696 Bytes = 34173.53 KiB = 33.37 MiB output: Load Address: 00000000 output: Entry Point: 00000000 output: rename /var/lib/lava/dispatcher/tmp/lavafed-4017/extract-overlay-ramdisk-2q091qup/ramdisk.cpio.gz.uboot to /var/lib/lava/dispatcher/tmp/lavafed-4017/tftp-deploy-bqo4_71b/ramdisk/ramdisk.cpio.gz.uboot end: 1.6.8 compress-ramdisk (duration 00:00:17) [common] end: 1.6 prepare-tftp-overlay (duration 00:00:50) [common] start: 1.7 lxc-create-udev-rule-action (timeout 00:02:42) [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:02:42) [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:01:18) [common] start: 2 uboot-action (timeout 00:04:00) [common] start: 2.1 uboot-from-media (timeout 00:04:00) [common] end: 2.1 uboot-from-media (duration 00:00:00) [common] start: 2.2 bootloader-overlay (timeout 00:04:00) [common] substitutions: - {BOOTX}: bootz 0x82000000 0x83000000 0x88000000 - {DTB_ADDR}: 0x88000000 - {DTB}: lavafed-4017/tftp-deploy-bqo4_71b/dtb/am335x-boneblack.dtb - {INITRD}: lavafed-4017/tftp-deploy-bqo4_71b/ramdisk/ramdisk.cpio.gz.uboot - {KERNEL_ADDR}: 0x82000000 - {KERNEL}: lavafed-4017/tftp-deploy-bqo4_71b/kernel/vmlinuz - {LAVA_MAC}: None - {NFSROOTFS}: /var/lib/lava/dispatcher/tmp/lavafed-4017/extract-nfsrootfs-uthsqtm7 - {NFS_SERVER_IP}: 10.3.1.1 - {PRESEED_CONFIG}: None - {PRESEED_LOCAL}: None - {RAMDISK_ADDR}: 0x83000000 - {RAMDISK}: lavafed-4017/tftp-deploy-bqo4_71b/ramdisk/ramdisk.cpio.gz.uboot - {ROOT_PART}: None - {ROOT}: None - {SERVER_IP}: 10.3.1.1 - {TEE_ADDR}: 0x83000000 - {TEE}: None Parsed boot commands: - setenv autoload no - setenv initrd_high 0xffffffff - setenv fdt_high 0xffffffff - dhcp - setenv serverip 10.3.1.1 - tftp 0x82000000 lavafed-4017/tftp-deploy-bqo4_71b/kernel/vmlinuz - tftp 0x83000000 lavafed-4017/tftp-deploy-bqo4_71b/ramdisk/ramdisk.cpio.gz.uboot - setenv initrd_size ${filesize} - tftp 0x88000000 lavafed-4017/tftp-deploy-bqo4_71b/dtb/am335x-boneblack.dtb - setenv bootargs 'console=ttyO0,115200n8 root=/dev/nfs rw nfsroot=10.3.1.1:/var/lib/lava/dispatcher/tmp/lavafed-4017/extract-nfsrootfs-uthsqtm7,tcp,hard ip=:::::eth0:dhcp' - bootz 0x82000000 0x83000000 0x88000000 end: 2.2 bootloader-overlay (duration 00:00:00) [common] case: bootloader-overlay
case_id: 74942
definition: lava
duration: 0.03
extra: ...
level: 2.2
namespace: common
result: pass
start: 2.3 connect-device (timeout 00:04:00) [common] [common] connect-device Connecting to device using 'telnet staging01 7103' Setting prompt string to ['lava-test: # '] end: 2.3 connect-device (duration 00:00:01) [common] start: 2.4 uboot-commands (timeout 00:03:59) [common] start: 2.4.1 reset-device (timeout 00:03:59) [common] start: 2.4.1.1 pdu-reboot (timeout 00:03:59) [common] Calling: 'nice' '/usr/local/lab-scripts/snmp_pdu_control' '--hostname' 'pdu15' '--command' 'reboot' '--port' '12' >> pdu15 port 12 now powered off >> pdu15 port 12 now powered on >> pdu15 port 12 now power cycled Returned 0 in 13 seconds end: 2.4.1.1 pdu-reboot (duration 00:00:14) [common] case: pdu-reboot
case_id: 74966
definition: lava
duration: 14.06
extra: ...
level: 2.4.1.1
namespace: common
result: pass
end: 2.4.1 reset-device (duration 00:00:14) [common] start: 2.4.2 bootloader-interrupt (timeout 00:03:45) [common] Setting prompt string to ['Hit any key to stop autoboot'] bootloader-interrupt: Wait for prompt ['Hit any key to stop autoboot'] (timeout 00:04:00) Trying 10.3.1.1... Connected to staging01.lavalab. Escape character is '^]'. black-01 7103 [115200 N81] U-Boot SPL 2014.04-00014-g47880f5 (Apr 22 2014 - 13:23:54) reading args spl_load_image_fat_os: error reading image args, err - -1 reading u-boot.img reading u-boot.img U-Boot 2014.04-00014-g47880f5 (Apr 22 2014 - 13:23:54) I2C: ready DRAM: 512 MiB NAND: 0 MiB MMC: OMAP SD/MMC: 0, OMAP SD/MMC: 1 *** Warning - readenv() failed, using default environment Net: <ethaddr> not set. Validating first E-fuse MAC cpsw, usb_ether Hit any key to stop autoboot: 1 end: 2.4.2 bootloader-interrupt (duration 00:00:02) [common] start: 2.4.3 bootloader-commands (timeout 00:03:43) [common] Setting prompt string to ['U-Boot'] bootloader-commands: Wait for prompt ['U-Boot'] (timeout 00:03:43)  0 Setting prompt string to ['U-Boot', 'Resetting CPU', 'Must RESET board to recover', 'TIMEOUT', 'Retry count exceeded', 'Retry time exceeded; starting again', 'ERROR: The remote end did not respond in time.', 'File not found', 'Bad Linux ARM64 Image magic!', 'Wrong Ramdisk Image Format', 'Ramdisk image is corrupt or invalid', 'ERROR: Failed to allocate', 'TFTP error: trying to overwrite reserved memory', 'Bad Linux RISCV Image magic!', 'Wrong Image Format for boot', 'ERROR: Did not find a cmdline Flattened Device Tree', 'ERROR: RD image overlaps OS image'] Sending with 10 millisecond of delay setenv autoload no U-Boot# setenv autoload no bootloader-commands: Wait for prompt ['U-Boot', 'Resetting CPU', 'Must RESET board to recover', 'TIMEOUT', 'Retry count exceeded', 'Retry time exceeded; starting again', 'ERROR: The remote end did not respond in time.', 'File not found', 'Bad Linux ARM64 Image magic!', 'Wrong Ramdisk Image Format', 'Ramdisk image is corrupt or invalid', 'ERROR: Failed to allocate', 'TFTP error: trying to overwrite reserved memory', 'Bad Linux RISCV Image magic!', 'Wrong Image Format for boot', 'ERROR: Did not find a cmdline Flattened Device Tree', 'ERROR: RD image overlaps OS image'] (timeout 00:03:41) setenv autoload no Sending with 10 millisecond of delay setenv initrd_high 0xffffffff U-Boot# setenv initrd_high 0xffffffff bootloader-commands: Wait for prompt ['U-Boot', 'Resetting CPU', 'Must RESET board to recover', 'TIMEOUT', 'Retry count exceeded', 'Retry time exceeded; starting again', 'ERROR: The remote end did not respond in time.', 'File not found', 'Bad Linux ARM64 Image magic!', 'Wrong Ramdisk Image Format', 'Ramdisk image is corrupt or invalid', 'ERROR: Failed to allocate', 'TFTP error: trying to overwrite reserved memory', 'Bad Linux RISCV Image magic!', 'Wrong Image Format for boot', 'ERROR: Did not find a cmdline Flattened Device Tree', 'ERROR: RD image overlaps OS image'] (timeout 00:03:40) setenv initrd_high 0xffffffff Sending with 10 millisecond of delay setenv fdt_high 0xffffffff U-Boot# setenv fdt_high 0xffffffff bootloader-commands: Wait for prompt ['U-Boot', 'Resetting CPU', 'Must RESET board to recover', 'TIMEOUT', 'Retry count exceeded', 'Retry time exceeded; starting again', 'ERROR: The remote end did not respond in time.', 'File not found', 'Bad Linux ARM64 Image magic!', 'Wrong Ramdisk Image Format', 'Ramdisk image is corrupt or invalid', 'ERROR: Failed to allocate', 'TFTP error: trying to overwrite reserved memory', 'Bad Linux RISCV Image magic!', 'Wrong Image Format for boot', 'ERROR: Did not find a cmdline Flattened Device Tree', 'ERROR: RD image overlaps OS image'] (timeout 00:03:38) setenv fdt_high 0xffffffff Sending with 10 millisecond of delay dhcp U-Boot# dhcp bootloader-commands: Wait for prompt ['U-Boot', 'Resetting CPU', 'Must RESET board to recover', 'TIMEOUT', 'Retry count exceeded', 'Retry time exceeded; starting again', 'ERROR: The remote end did not respond in time.', 'File not found', 'Bad Linux ARM64 Image magic!', 'Wrong Ramdisk Image Format', 'Ramdisk image is corrupt or invalid', 'ERROR: Failed to allocate', 'TFTP error: trying to overwrite reserved memory', 'Bad Linux RISCV Image magic!', 'Wrong Image Format for boot', 'ERROR: Did not find a cmdline Flattened Device Tree', 'ERROR: RD image overlaps OS image'] (timeout 00:03:38) dhcp link up on port 0, speed 100, full duplex BOOTP broadcast 1 BOOTP broadcast 2 DHCP client bound to address 10.15.1.249 Sending with 10 millisecond of delay setenv serverip 10.3.1.1 U-Boot# setenv serverip 10.3.1.1 bootloader-commands: Wait for prompt ['U-Boot', 'Resetting CPU', 'Must RESET board to recover', 'TIMEOUT', 'Retry count exceeded', 'Retry time exceeded; starting again', 'ERROR: The remote end did not respond in time.', 'File not found', 'Bad Linux ARM64 Image magic!', 'Wrong Ramdisk Image Format', 'Ramdisk image is corrupt or invalid', 'ERROR: Failed to allocate', 'TFTP error: trying to overwrite reserved memory', 'Bad Linux RISCV Image magic!', 'Wrong Image Format for boot', 'ERROR: Did not find a cmdline Flattened Device Tree', 'ERROR: RD image overlaps OS image'] (timeout 00:03:32) setenv serverip 10.3.1.1 Sending with 10 millisecond of delay tftp 0x82000000 lavafed-4017/tftp-deploy-bqo4_71b/kernel/vmlinuz U-Boot# tftp 0x82000000 lavafed-4017/tftp-deploy-bqo4_71b/kernel/vmlinuz bootloader-commands: Wait for prompt ['U-Boot', 'Resetting CPU', 'Must RESET board to recover', 'TIMEOUT', 'Retry count exceeded', 'Retry time exceeded; starting again', 'ERROR: The remote end did not respond in time.', 'File not found', 'Bad Linux ARM64 Image magic!', 'Wrong Ramdisk Image Format', 'Ramdisk image is corrupt or invalid', 'ERROR: Failed to allocate', 'TFTP error: trying to overwrite reserved memory', 'Bad Linux RISCV Image magic!', 'Wrong Image Format for boot', 'ERROR: Did not find a cmdline Flattened Device Tree', 'ERROR: RD image overlaps OS image'] (timeout 00:03:28) tftp 0x82000000 lavafed-4017/tftp-deploy-bqo4_71b/kernel/vmlinuz link up on port 0, speed 100, full duplex Using cpsw device TFTP from server 10.3.1.1; our IP address is 10.15.1.249 Filename 'lavafed-4017/tftp-deploy-bqo4_71b/kernel/vmlinuz'. Load address: 0x82000000 Loading: *################################################################# ################################################################# ################################################################# ####################### 3.2 MiB/s done Bytes transferred = 3187808 (30a460 hex) Sending with 10 millisecond of delay tftp 0x83000000 lavafed-4017/tftp-deploy-bqo4_71b/ramdisk/ramdisk.cpio.gz.uboot U-Boot# tftp 0x83000000 lavafed-4017/tftp-deploy-bqo4_71b/ramdisk/ramdisk.cpio.gz.uboot bootloader-commands: Wait for prompt ['U-Boot', 'Resetting CPU', 'Must RESET board to recover', 'TIMEOUT', 'Retry count exceeded', 'Retry time exceeded; starting again', 'ERROR: The remote end did not respond in time.', 'File not found', 'Bad Linux ARM64 Image magic!', 'Wrong Ramdisk Image Format', 'Ramdisk image is corrupt or invalid', 'ERROR: Failed to allocate', 'TFTP error: trying to overwrite reserved memory', 'Bad Linux RISCV Image magic!', 'Wrong Image Format for boot', 'ERROR: Did not find a cmdline Flattened Device Tree', 'ERROR: RD image overlaps OS image'] (timeout 00:03:22) tftp 0x83000000 lavafed-4017/tftp-deploy-bqo4_71b/ramdisk/ramdisk.cpio.gz.uboot link up on port 0, speed 100, full duplex Using cpsw device TFTP from server 10.3.1.1; our IP address is 10.15.1.249 Filename 'lavafed-4017/tftp-deploy-bqo4_71b/ramdisk/ramdisk.cpio.gz.uboot'. Load address: 0x83000000 Loading: *################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ############################################ 3.8 MiB/s done Bytes transferred = 34993760 (215f660 hex) Sending with 10 millisecond of delay setenv initrd_size ${filesize} U-Boot# setenv initrd_size ${filesize} bootloader-commands: Wait for prompt ['U-Boot', 'Resetting CPU', 'Must RESET board to recover', 'TIMEOUT', 'Retry count exceeded', 'Retry time exceeded; starting again', 'ERROR: The remote end did not respond in time.', 'File not found', 'Bad Linux ARM64 Image magic!', 'Wrong Ramdisk Image Format', 'Ramdisk image is corrupt or invalid', 'ERROR: Failed to allocate', 'TFTP error: trying to overwrite reserved memory', 'Bad Linux RISCV Image magic!', 'Wrong Image Format for boot', 'ERROR: Did not find a cmdline Flattened Device Tree', 'ERROR: RD image overlaps OS image'] (timeout 00:03:12) setenv initrd_size ${filesize} Sending with 10 millisecond of delay tftp 0x88000000 lavafed-4017/tftp-deploy-bqo4_71b/dtb/am335x-boneblack.dtb U-Boot# tftp 0x88000000 lavafed-4017/tftp-deploy-bqo4_71b/dtb/am335x-boneblack.dtb bootloader-commands: Wait for prompt ['U-Boot', 'Resetting CPU', 'Must RESET board to recover', 'TIMEOUT', 'Retry count exceeded', 'Retry time exceeded; starting again', 'ERROR: The remote end did not respond in time.', 'File not found', 'Bad Linux ARM64 Image magic!', 'Wrong Ramdisk Image Format', 'Ramdisk image is corrupt or invalid', 'ERROR: Failed to allocate', 'TFTP error: trying to overwrite reserved memory', 'Bad Linux RISCV Image magic!', 'Wrong Image Format for boot', 'ERROR: Did not find a cmdline Flattened Device Tree', 'ERROR: RD image overlaps OS image'] (timeout 00:03:07) tftp 0x88000000 lavafed-4017/tftp-deploy-bqo4_71b/dtb/am335x-boneblack.dtb link up on port 0, speed 100, full duplex Using cpsw device TFTP from server 10.3.1.1; our IP address is 10.15.1.249 Filename 'lavafed-4017/tftp-deploy-bqo4_71b/dtb/am335x-boneblack.dtb'. Load address: 0x88000000 Loading: *## 1.8 MiB/s done Bytes transferred = 29018 (715a hex) Sending with 10 millisecond of delay setenv bootargs 'console=ttyO0,115200n8 root=/dev/nfs rw nfsroot=10.3.1.1:/var/lib/lava/dispatcher/tmp/lavafed-4017/extract-nfsrootfs-uthsqtm7,tcp,hard ip=:::::eth0:dhcp' U-Boot# setenv bootargs 'console=ttyO0,115200n8 root=/dev/nfs rw nfsroot=10.3.1.1:/var/lib/lava/dispatcher/tmp/lavafed-4017/extract-nfsrootfs-uthsqtm7,tcp,hard ip=:::::eth0:dhcp' bootloader-commands: Wait for prompt ['U-Boot', 'Resetting CPU', 'Must RESET board to recover', 'TIMEOUT', 'Retry count exceeded', 'Retry time exceeded; starting again', 'ERROR: The remote end did not respond in time.', 'File not found', 'Bad Linux ARM64 Image magic!', 'Wrong Ramdisk Image Format', 'Ramdisk image is corrupt or invalid', 'ERROR: Failed to allocate', 'TFTP error: trying to overwrite reserved memory', 'Bad Linux RISCV Image magic!', 'Wrong Image Format for boot', 'ERROR: Did not find a cmdline Flattened Device Tree', 'ERROR: RD image overlaps OS image'] (timeout 00:02:57) setenv bootargs 'console=ttyO0,115200n8 root=/dev/nfs rw nfsroot=10.3.1.1:/var/lib/lava/dispatcher/tmp/lavafed-4017/extract-nfsrootfs-uthsqtm7,tcp,hard ip=:::::eth0:dhcp' Sending with 10 millisecond of delay bootz 0x82000000 0x83000000 0x88000000 U-Boot# bootz 0x82000000 0x83000000 0x88000000 Setting prompt string to ['Starting kernel'] bootloader-commands: Wait for prompt ['Starting kernel'] (timeout 00:02:54) bootz 0x82000000 0x83000000 0x88000000 Kernel image @ 0x82000000 [ 0x000000 - 0x30a460 ] ## Loading init Ramdisk from Legacy Image at 83000000 ... Image Name: Image Type: ARM Linux RAMDisk Image (uncompressed) Data Size: 34993696 Bytes = 33.4 MiB Load Address: 00000000 Entry Point: 00000000 Verifying Checksum ... OK ## Flattened Device Tree blob at 88000000 Booting using the fdt blob at 0x88000000 Using Device Tree in place at 88000000, end 8800a159 Starting kernel ... end: 2.4.3 bootloader-commands (duration 00:00:49) [common] start: 2.4.4 auto-login-action (timeout 00:02:54) [common] Setting prompt string to ['Linux version [0-9]'] Setting prompt string to ['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.', 'File not found', 'Bad Linux ARM64 Image magic!', 'Wrong Ramdisk Image Format', 'Ramdisk image is corrupt or invalid', 'ERROR: Failed to allocate', 'TFTP error: trying to overwrite reserved memory', 'Bad Linux RISCV Image magic!', 'Wrong Image Format for boot', 'ERROR: Did not find a cmdline Flattened Device Tree', 'ERROR: RD image overlaps OS image'] 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.', 'File not found', 'Bad Linux ARM64 Image magic!', 'Wrong Ramdisk Image Format', 'Ramdisk image is corrupt or invalid', 'ERROR: Failed to allocate', 'TFTP error: trying to overwrite reserved memory', 'Bad Linux RISCV Image magic!', 'Wrong Image Format for boot', 'ERROR: Did not find a cmdline Flattened Device Tree', 'ERROR: RD image overlaps OS image'] (timeout 00:04:00) [ 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 [413fc082] revision 2 (ARMv7), cr=10c5387d start: 2.4.4.1 login-action (timeout 00:02:49) [common] Setting prompt string to ['-+\\[ cut here \\]-+\\s+(.*\\s+-+\\[ end trace (\\w*) \\]-+)', '(Unhandled fault.*)\\r\\n', 'Kernel panic - (.*) end Kernel panic', 'Stack:\\s+(.*\\s+-+\\[ end trace (\\w*) \\]-+)', '^[^\\n]+WARNING:.*?$', '^[^\\n]+Oops(?: -|:).*?$', '^[^\\n]+BUG:.*?$'] 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*) \\]-+)', '^[^\\n]+WARNING:.*?$', '^[^\\n]+Oops(?: -|:).*?$', '^[^\\n]+BUG:.*?$', 'root@jessie:', 'login:', 'Login incorrect'] [login-action] Waiting for messages, (timeout 00:02:49) [ 0.000000] CPU: PIPT / VIPT nonaliasing data cache, VIPT aliasing instruction cache [ 0.000000] Machine model: TI AM335x BeagleBone [ 0.000000] Memory policy: Data cache writeback [ 0.000000] HighMem zone: 1048574 pages exceeds freesize 0 [ 0.000000] CPU: All CPU(s) started in SVC mode. [ 0.000000] AM335X ES2.0 (sgx neon ) [ 0.000000] PERCPU: Embedded 9 pages/cpu @dfac9000 s12672 r8192 d16000 u36864 [ 0.000000] Built 1 zonelists in Zone order, mobility grouping on. Total pages: 129792 [ 0.000000] Kernel command line: console=ttyO0,115200n8 root=/dev/nfs rw nfsroot=10.3.1.1:/var/lib/lava/dispatcher/tmp/lavafed-4017/extract-nfsrootfs-uthsqtm7,tcp,hard ip=:::::eth0:dhcp [ 0.000000] PID hash table entries: 2048 (order: 1, 8192 bytes) [ 0.000000] Dentry cache hash table entries: 65536 (order: 6, 262144 bytes) [ 0.000000] Inode-cache hash table entries: 32768 (order: 5, 131072 bytes) [ 0.000000] Memory: 473784K/523264K available (6434K kernel code, 830K rwdata, 2200K rodata, 688K init, 393K bss, 49480K reserved, 0K highmem) [ 0.000000] Virtual kernel memory layout: [ 0.000000] vector : 0xffff0000 - 0xffff1000 ( 4 kB) [ 0.000000] fixmap : 0xffc00000 - 0xffe00000 (2048 kB) [ 0.000000] vmalloc : 0xe0800000 - 0xff000000 ( 488 MB) [ 0.000000] lowmem : 0xc0000000 - 0xe0000000 ( 512 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=1. [ 0.000000] RCU: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1 [ 0.000000] NR_IRQS:16 nr_irqs:16 16 [ 0.000000] IRQ: Found an INTC at 0xfa200000 (revision 5.0) with 128 interrupts [ 0.000000] Total of 128 interrupts on 1 active controller [ 0.000000] OMAP clockevent source: timer2 at 24000000 Hz [ 0.000012] sched_clock: 32 bits at 24MHz, resolution 41ns, wraps every 178956969942ns [ 0.000032] OMAP clocksource: timer1 at 24000000 Hz [ 0.000378] Console: colour dummy device 80x30 [ 0.000412] Calibrating delay loop... 995.32 BogoMIPS (lpj=1990656) [ 0.047292] pid_max: default: 32768 minimum: 301 [ 0.047421] Security Framework initialized [ 0.047506] AppArmor: AppArmor disabled by boot time parameter [ 0.047515] Yama: disabled by default; enable with sysctl kernel.yama.* [ 0.047594] Mount-cache hash table entries: 1024 (order: 0, 4096 bytes) [ 0.047606] Mountpoint-cache hash table entries: 1024 (order: 0, 4096 bytes) [ 0.048528] Initializing cgroup subsys memory [ 0.048562] Initializing cgroup subsys devices [ 0.048598] Initializing cgroup subsys freezer [ 0.048618] Initializing cgroup subsys net_cls [ 0.048648] Initializing cgroup subsys blkio [ 0.048671] Initializing cgroup subsys perf_event [ 0.048686] Initializing cgroup subsys net_prio [ 0.048754] CPU: Testing write buffer coherency: ok [ 0.048813] ftrace: allocating 23146 entries in 68 pages [ 0.105180] CPU0: thread -1, cpu 0, socket -1, mpidr 0 [ 0.105559] Setting up static identity map for 0x805c8088 - 0x805c80e0 [ 0.113864] Brought up 1 CPUs [ 0.113887] SMP: Total of 1 processors activated (995.32 BogoMIPS). [ 0.113896] CPU: All CPU(s) started in SVC mode. [ 0.114714] devtmpfs: initialized [ 0.118788] VFP support v0.3: implementor 41 architecture 3 part 30 variant c rev 3 [ 0.129384] omap_hwmod: tptc0 using broken dt data from edma [ 0.129508] omap_hwmod: tptc1 using broken dt data from edma [ 0.129616] omap_hwmod: tptc2 using broken dt data from edma [ 0.134103] omap_hwmod: debugss: _wait_target_disable failed [ 0.192438] pinctrl core: initialized pinctrl subsystem [ 0.192978] regulator-dummy: no parameters [ 0.201655] NET: Registered protocol family 16 [ 0.202193] DMA: preallocated 256 KiB pool for atomic coherent allocations [ 0.209968] platform 49000000.edma: alias fck already exists [ 0.209998] platform 49000000.edma: alias fck already exists [ 0.210011] platform 49000000.edma: alias fck already exists [ 0.210804] OMAP GPIO hardware version 0.1 [ 0.218686] No ATAGs? [ 0.218716] hw-breakpoint: debug architecture 0x4 unsupported. [ 0.219630] EXYNOS: PMU not supported [ 0.219957] omap_init_mbox: hwmod doesn't have valid attrs [ 0.220831] Serial: AMBA PL011 UART driver [ 0.237547] edma-dma-engine edma-dma-engine.0: TI EDMA DMA engine driver [ 0.238295] vmmcsd_fixed: 3300 mV [ 0.239710] vgaarb: loaded [ 0.240261] usbcore: registered new interface driver usbfs [ 0.240330] usbcore: registered new interface driver hub [ 0.240576] usbcore: registered new device driver usb [ 0.241058] omap_i2c 44e0b000.i2c: could not find pctldev for node /pinmux@44e10800/pinmux_i2c0_pins, deferring probe [ 0.241083] platform 44e0b000.i2c: Driver omap_i2c requests probe deferral [ 0.241320] media: Linux media interface: v0.10 [ 0.241380] Linux video capture interface: v2.00 [ 0.241478] pps_core: LinuxPPS API ver. 1 registered [ 0.241485] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it> [ 0.241515] PTP clock support registered [ 0.243062] Switched to clocksource timer1 [ 0.298593] NET: Registered protocol family 2 [ 0.299638] TCP established hash table entries: 4096 (order: 2, 16384 bytes) [ 0.299688] TCP bind hash table entries: 4096 (order: 3, 32768 bytes) [ 0.299749] TCP: Hash tables configured (established 4096 bind 4096) [ 0.299820] TCP: reno registered [ 0.299833] UDP hash table entries: 256 (order: 1, 8192 bytes) [ 0.299854] UDP-Lite hash table entries: 256 (order: 1, 8192 bytes) [ 0.300056] NET: Registered protocol family 1 [ 0.300651] Unpacking initramfs... [ 2.924598] Freeing initrd memory: 34168K (c3001000 - c515f000) [ 2.925101] hw perfevents: enabled with ARMv7 Cortex-A8 PMU driver, 5 counters available [ 2.927727] futex hash table entries: 256 (order: 2, 16384 bytes) [ 2.927832] audit: initializing netlink subsys (disabled) [ 2.927926] audit: type=2000 audit(2.892:1): initialized [ 2.929614] zbud: loaded [ 2.929968] VFS: Disk quotas dquot_6.5.2 [ 2.930038] Dquot-cache hash table entries: 1024 (order 0, 4096 bytes) [ 2.930210] msgmni has been set to 992 [ 2.932063] alg: No test for stdrng (krng) [ 2.932218] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 249) [ 2.932485] io scheduler noop registered [ 2.932501] io scheduler deadline registered [ 2.932636] io scheduler cfq registered (default) [ 2.933460] pinctrl-single 44e10800.pinmux: 142 pins at pa f9e10800 size 568 [ 2.936481] Serial: 8250/16550 driver, 4 ports, IRQ sharing disabled [ 2.938178] Serial: AMBA driver [ 2.938411] Serial: IMX driver [ 2.938978] omap_uart 44e09000.serial: no wakeirq for uart0 [ 2.939270] 44e09000.serial: ttyO0 at MMIO 0x44e09000 (irq = 88, base_baud = 3000000) is a OMAP UART0 [ 3.661728] console [ttyO0] enabled [ 3.669246] mousedev: PS/2 mouse device common for all mice [ 3.676474] omap_rtc 44e3e000.rtc: rtc core: registered 44e3e000.rtc as rtc0 [ 3.685548] ledtrig-cpu: registered to indicate activity on CPUs [ 3.692021] hidraw: raw HID events driver (C) Jiri Kosina [ 3.698303] usbcore: registered new interface driver usbhid [ 3.704147] usbhid: USB HID core driver [ 3.708925] TCP: cubic registered [ 3.712503] NET: Registered protocol family 10 [ 3.718256] mip6: Mobile IPv6 [ 3.721461] NET: Registered protocol family 17 [ 3.726142] mpls_gso: MPLS GSO support [ 3.730223] omap_voltage_late_init: Voltage driver support not added [ 3.737368] ThumbEE CPU extension supported. [ 3.741899] Registering SWP/SWPB emulation handler [ 3.747754] registered taskstats version 1 [ 3.760007] omap_i2c 44e0b000.i2c: bus 0 rev0.11 at 400 kHz [ 3.766386] omap_rtc 44e3e000.rtc: setting system clock to 2000-01-01 00:00:00 UTC (946684800) [ 3.779911] vmmcsd_fixed: disabling [ 3.784901] Freeing unused kernel memory: 688K (c0877000 - c0923000) Loading, please wait... [ 3.874901] systemd-udevd[59]: starting version 215 [ 3.889476] random: systemd-udevd: uninitialized urandom read (16 bytes read, 1 bits of entropy available) [ 4.014560] omap_wdt: OMAP Watchdog Timer Rev 0x01: initial timeout 60 sec [ 4.141676] omap_rng 48310000.rng: OMAP Random Number Generator ver. 20 [ 4.160767] [drm] Initialized drm 1.1.0 20060810 [ 4.169361] omap-sham 53100000.sham: hw accel on OMAP rev 4.3 [ 4.185059] omap-sham 53100000.sham: initialization failed. [ 4.211978] omap-sham: probe of 53100000.sham failed with error -22 [ 4.218931] omap-aes 53500000.aes: OMAP AES hw accel rev: 3.2 [ 4.251146] mmc0: host does not support reading read-only switch. assuming write-enable. [ 4.270235] cpsw 4a100000.ethernet: Detected MACID = c8:a0:30:c2:cf:6f [ 4.295173] mmc0: new high speed SDHC card at address aaaa [ 4.334232] mmcblk0: mmc0:aaaa SU16G 14.8 GiB [ 4.361635] mmcblk0: p1 p2 p3 p4 < p5 p6 p7 > [ 4.456576] mmc1: BKOPS_EN bit is not set [ 4.470047] mmc1: new high speed MMC card at address 0001 [ 4.498794] musb-hdrc musb-hdrc.0.auto: musb_init_controller failed with status -517 [ 4.510777] mmcblk1: mmc1:0001 MMC02G 1.78 GiB [ 4.530569] mmcblk1boot0: mmc1:0001 MMC02G partition 1 1.00 MiB [ 4.539773] tda998x 0-0070: found TDA19988 [ 4.550481] [drm] Supports vblank timestamp caching Rev 2 (21.10.2013). [ 4.557481] [drm] No driver support for vblank timestamp query. [ 4.565534] platform musb-hdrc.0.auto: Driver musb-hdrc requests probe deferral [ 4.599994] mmcblk1boot1: mmc1:0001 MMC02G partition 2 1.00 MiB [ 4.615526] tilcdc 4830e000.lcdc: No connectors reported connected with modes [ 4.626797] musb-hdrc musb-hdrc.0.auto: musb_init_controller failed with status -517 [ 4.637218] 47401300.usb-phy supply vcc not found, using dummy regulator [ 4.652349] mmcblk1: p1 p2 [ 4.678303] platform musb-hdrc.0.auto: Driver musb-hdrc requests probe deferral [ 4.693064] [drm] Cannot find any crtc or sizes - going 1024x768 [ 4.702258] mmcblk1boot1: unknown partition table [ 4.712944] mmcblk1boot0: unknown partition table [ 4.730576] musb-hdrc musb-hdrc.1.auto: musb_init_controller failed with status -517 [ 4.748152] Console: switching to colour frame buffer device 128x48 [ 4.759929] davinci_mdio 4a101000.mdio: davinci mdio revision 1.6 [ 4.759936] davinci_mdio 4a101000.mdio: detected phy mask fffffffe [ 4.794419] platform musb-hdrc.1.auto: Driver musb-hdrc requests probe deferral [ 4.799361] musb-hdrc musb-hdrc.1.auto: musb_init_controller failed with status -517 [ 4.799411] platform musb-hdrc.1.auto: Driver musb-hdrc requests probe deferral [ 4.800527] musb-hdrc musb-hdrc.1.auto: musb_init_controller failed with status -517 [ 4.800944] platform musb-hdrc.1.auto: Driver musb-hdrc requests probe deferral [ 4.801349] 47401b00.usb-phy supply vcc not found, using dummy regulator [ 4.810163] musb-hdrc musb-hdrc.1.auto: MUSB HDRC host driver [ 4.812568] musb-hdrc musb-hdrc.1.auto: new USB bus registered, assigned bus number 1 [ 4.833199] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002 [ 4.833207] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1 [ 4.833213] usb usb1: Product: MUSB HDRC host driver [ 4.833218] usb usb1: Manufacturer: Linux 3.16.0-4-armmp musb-hcd [ 4.833224] usb usb1: SerialNumber: musb-hdrc.1.auto [ 4.849751] hub 1-0:1.0: USB hub found [ 4.853599] hub 1-0:1.0: 1 port detected [ 4.876918] libphy: 4a101000.mdio: probed [ 4.876936] davinci_mdio 4a101000.mdio: phy[0]: device 4a101000.mdio:00, driver SMSC LAN8710/LAN8720 [ 5.098515] tilcdc 4830e000.lcdc: fb0: frame buffer device [ 5.104418] tilcdc 4830e000.lcdc: registered panic notifier [ 5.110692] [drm] Initialized tilcdc 1.0.0 20121205 on minor 0 [ 5.266261] random: nonblocking pool is initialized 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.351874] FS-Cache: Loaded [ 5.374170] RPC: Registered named UNIX socket transport module. [ 5.380484] RPC: Registered udp transport module. [ 5.385411] RPC: Registered tcp transport module. [ 5.390330] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 5.422161] FS-Cache: Netfs 'nfs' registered for caching IP-Config: eth0 hardware address c8:a0:30:c2:cf:[ 5.460115] net eth0: initializing cpsw version 1.12 (0) 6f mtu 1500 DHCP [ 5.472319] net eth0: phy found : id is : 0x7c0f1 [ 5.477483] libphy: PHY 4a101000.mdio:01 not found [ 5.482531] net eth0: phy 4a101000.mdio:01 not found on slave 1 [ 5.495691] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready IP-Config: no response after 2 secs - giving up IP-Config: eth0 hardware address c8:a0:30:c2:cf:6f mtu 1500 DHCP [ 8.539710] cpsw 4a100000.ethernet eth0: Link is Up - 100Mbps/Full - flow control off [ 8.547995] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready IP-Config: no response after 3 secs - giving up IP-Config: eth0 hardware address c8:a0:30:c2:cf:6f 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.249 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. [ 13.575937] systemd[1]: systemd 215 running in system mode. (+PAM +AUDIT +SELINUX +IMA +SYSVINIT +LIBCRYPTSETUP +GCRYPT +ACL +XZ -SECCOMP -APPARMOR) [ 13.590395] systemd[1]: Detected architecture 'arm'. Welcome to [1mDebian GNU/Linux 8 (jessie)[0m! [ 13.753364] systemd[1]: Inserted module 'autofs4' [ 13.761630] systemd[1]: Set hostname to <jessie>. [ 14.293615] systemd[1]: Cannot add dependency job for unit dbus.socket, ignoring: Unit dbus.socket failed to load: No such file or directory. [ 14.307164] systemd[1]: Cannot add dependency job for unit display-manager.service, ignoring: Unit display-manager.service failed to load: No such file or directory. [ 14.325215] systemd[1]: Expecting device dev-ttyO0.device... Expecting device dev-ttyO0.device... [ 14.339286] systemd[1]: Starting Forward Password Requests to Wall Directory Watch. [ 14.347824] systemd[1]: Started Forward Password Requests to Wall Directory Watch. [ 14.355932] systemd[1]: Starting Remote File Systems (Pre). [[32m OK [0m] Reached target Remote File Systems (Pre). [ 14.371194] systemd[1]: Reached target Remote File Systems (Pre). [ 14.377732] systemd[1]: Starting Encrypted Volumes. [[32m OK [0m] Reached target Encrypted Volumes. [ 14.391178] systemd[1]: Reached target Encrypted Volumes. [ 14.397108] systemd[1]: Starting Arbitrary Executable File Formats File System Automount Point. [[32m OK [0m] Set up automount Arbitrary Executable File Formats F...utomount Point. [ 14.419194] systemd[1]: Set up automount Arbitrary Executable File Formats File System Automount Point. [ 14.429273] systemd[1]: Starting Dispatch Password Requests to Console Directory Watch. [ 14.438221] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. [ 14.446674] systemd[1]: Starting Paths. [[32m OK [0m] Reached target Paths. [ 14.459195] systemd[1]: Reached target Paths. [ 14.463903] systemd[1]: Starting Swap. [[32m OK [0m] Reached target Swap. [ 14.475166] systemd[1]: Reached target Swap. [ 14.479769] systemd[1]: Starting Root Slice. [[32m OK [0m] Created slice Root Slice. [ 14.491181] systemd[1]: Created slice Root Slice. [ 14.496241] systemd[1]: Starting User and Session Slice. [[32m OK [0m] Created slice User and Session Slice. [ 14.511187] systemd[1]: Created slice User and Session Slice. [ 14.517337] systemd[1]: Starting /dev/initctl Compatibility Named Pipe. [[32m OK [0m] Listening on /dev/initctl Compatibility Named Pipe. [ 14.535215] systemd[1]: Listening on /dev/initctl Compatibility Named Pipe. [ 14.542660] systemd[1]: Starting Delayed Shutdown Socket. [[32m OK [0m] Listening on Delayed Shutdown Socket. [ 14.555193] systemd[1]: Listening on Delayed Shutdown Socket. [ 14.561359] systemd[1]: Starting Journal Socket (/dev/log). [[32m OK [0m] Listening on Journal Socket (/dev/log). [ 14.575223] systemd[1]: Listening on Journal Socket (/dev/log). [ 14.581669] systemd[1]: Starting udev Kernel Socket. [[32m OK [0m] Listening on udev Kernel Socket. [ 14.595201] systemd[1]: Listening on udev Kernel Socket. [ 14.600956] systemd[1]: Starting udev Control Socket. [[32m OK [0m] Listening on udev Control Socket. [ 14.615207] systemd[1]: Listening on udev Control Socket. [ 14.621040] systemd[1]: Starting Journal Socket. [[32m OK [0m] Listening on Journal Socket. [ 14.635198] systemd[1]: Listening on Journal Socket. [ 14.640621] systemd[1]: Starting System Slice. [[32m OK [0m] Created slice System Slice. [ 14.655225] systemd[1]: Created slice System Slice. [ 14.660512] systemd[1]: Starting system-getty.slice. [[32m OK [0m] Created slice system-getty.slice. [ 14.675207] systemd[1]: Created slice system-getty.slice. [ 14.681007] systemd[1]: Starting system-serial\x2dgetty.slice. [[32m OK [0m] Created slice system-serial\x2dgetty.slice. [ 14.695198] systemd[1]: Created slice system-serial\x2dgetty.slice. [ 14.702037] systemd[1]: Starting Increase datagram queue length... Starting Increase datagram queue length... [ 14.729130] systemd[1]: Starting Load Kernel Modules... Starting Load Kernel Modules... [ 14.761037] systemd[1]: Starting Create list of required static device nodes for the current kernel... Starting Create list of required static device nodes...rrent kernel... [ 14.811616] systemd[1]: Starting udev Coldplug all Devices... Starting udev Coldplug all Devices... [ 14.853218] systemd[1]: Mounting POSIX Message Queue File System... Mounting POSIX Message Queue File System... [ 14.897728] systemd[1]: Mounted Huge Pages File System. [ 14.930086] systemd[1]: Started Set Up Additional Binary Formats. [ 14.949673] systemd[1]: Mounting Debug File System... Mounting Debug File System... [ 14.982125] systemd[1]: Starting Slices. [[32m OK [0m] Reached target Slices. [ 15.011401] systemd[1]: Reached target Slices. [ 15.024572] systemd[1]: Starting Remount Root and Kernel File Systems... Starting Remount Root and Kernel File Systems... [[32m OK [0m] Mounted Debug File System. [ 15.083768] systemd[1]: Mounted Debug File System. [[32m OK [0m] Mounted POSIX Message Queue File System. [ 15.107394] systemd[1]: Mounted POSIX Message Queue File System. [[32m OK [0m] Started Increase datagram queue length. [ 15.127322] systemd[1]: Started Increase datagram queue length. [[32m OK [0m] Started Load Kernel Modules. [ 15.143512] systemd[1]: Started Load Kernel Modules. [[32m OK [0m] Started Create list of required static device nodes ...current kernel. [ 15.163263] systemd[1]: Started Create list of required static device nodes for the current kernel. [[32m OK [0m] Started Remount Root and Kernel File Systems. [ 15.191378] systemd[1]: Started Remount Root and Kernel File Systems. [[32m OK [0m] Started udev Coldplug all Devices. [ 15.339325] systemd[1]: Started udev Coldplug all Devices. [ 15.398036] systemd[1]: Starting Various fixups to make systemd work better on Debian... Starting Various fixups to make systemd work better on Debian... [ 15.418564] systemd[1]: Starting Load/Save Random Seed... Starting Load/Save Random Seed... [ 15.438973] systemd[1]: Starting Create Static Device Nodes in /dev... Starting Create Static Device Nodes in /dev... [ 15.482019] systemd[1]: Mounted Configuration File System. [ 15.506319] systemd[1]: Mounted FUSE Control File System. [ 15.531659] systemd[1]: Starting Apply Kernel Variables... Starting Apply Kernel Variables... [ 15.562736] systemd[1]: Starting Syslog Socket. [[32m OK [0m] Listening on Syslog Socket. [ 15.591848] systemd[1]: Listening on Syslog Socket. [ 15.607795] systemd[1]: Starting Sockets. [[32m OK [0m] Reached target Sockets. [ 15.630027] systemd[1]: Reached target Sockets. [ 15.644833] systemd[1]: Starting Journal Service... Starting Journal Service... [[32m OK [0m] Started Journal Service. [ 15.683404] 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 Create Static Device Nodes in /dev. [[32m OK [0m] Started Apply Kernel Variables. 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. [ 15.980865] systemd-udevd[149]: 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... [[32m OK [0m] Started Copy rules generated while the root was ro. [ 16.377342] systemd-journald[148]: Received request to flush runtime journal from PID 1 [[32m OK [0m] Started Trigger Flushing of Journal to Persistent Storage. [[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/ttyO0. [[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. [[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. 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 ttyO0... [[32m OK [0m] Started Serial Getty on ttyO0. [[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 ttyO0 Matched prompt #8: login: case: kernel-messages
case_id: 74984
definition: lava
duration: 18.45
extra: ...
level: 2.4.4.1
namespace: common
result: pass
Sending username root Sending with 10 millisecond of delay root jessie login: root Setting prompt string to ['root@jessie:'] login-action: Wait for prompt ['root@jessie:', 'Login incorrect', 'Login timed out'] (timeout 00:02:30) 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. Setting prompt string to ['root@jessie:'] end: 2.4.4.1 login-action (duration 00:00:19) [common] case: login-action
case_id: 74985
definition: lava
duration: 19.24
extra: ...
level: 2.4.4.1
namespace: common
result: pass
end: 2.4.4 auto-login-action (duration 00:00:24) [common] start: 2.4.5 expect-shell-connection (timeout 00:02:30) [common] Setting prompt string to ['root@jessie:'] Forcing a shell prompt, looking for ['root@jessie:'] root@jessie:~# expect-shell-connection: Wait for prompt ['root@jessie:'] (timeout 00:04:00) Waiting using forced prompt support (timeout 00:02:00) end: 2.4.5 expect-shell-connection (duration 00:00:00) [common] start: 2.4.6 export-device-env (timeout 00:02:30) [common] Sending with 10 millisecond of delay export NFS_ROOTFS='/var/lib/lava/dispatcher/tmp/lavafed-4017/extract-nfsrootfs-uthsqtm7' root@jessie:~# export NFS_ROOTFS='/var/lib/lava/dispatcher/tmp/lavafed-4017/extract-nfsrootfs-uthsqtm7' export NFS_ROOTFS='/var/lib/lava/dispatcher/tmp/lavafed-4017/extr act-nfsrootfs-uthsqtm7' Sending with 10 millisecond of delay export NFS_SERVER_IP='10.3.1.1' root@jessie:~# export NFS_SERVER_IP='10.3.1.1' export NFS_SERVER_IP='10.3.1.1' end: 2.4.6 export-device-env (duration 00:00:07) [common] end: 2.4 uboot-commands (duration 00:01:36) [common] end: 2 uboot-action (duration 00:01:37) [common] start: 3 lava-test-retry (timeout 00:03:00) [common] start: 3.1 lava-test-shell (timeout 00:03:00) [common] Using namespace: common # root@jessie:~# # lava-test-shell: Wait for prompt ['root@jessie:'] (timeout 00:03:00) # Using /lava-4017 export SHELL=/bin/bash root@jessie:~# export SHELL=/bin/bash export SHELL=/bin/bash . /lava-4017/environment root@jessie:~# . /lava-4017/environment . /lava-4017/environment /lava-4017/bin/lava-test-runner /lava-4017/0 root@jessie:~# /lava-4017/bin/lava-test-runner /lava-4017/0 Test shell timeout: 10s (minimum of the action and connection timeout) /lava-4017/bin/lava-test-runner /lava-4017/0 + export TESTRUN_ID=0_smoke-tests + TESTRUN_ID=0_smoke-tests + cd /lava-4017/0/tests/0_smoke-tests ++ cat uuid + UUID=4017_1.6.2.4.1 + set +x <LAVA_SIGNAL_STARTRUN 0_smoke-tests 4017_1.6.2.4.1> + lava-test-case linux-posix-pwd --shell pwd <LAVA_SIGNAL_STARTTC linux-posix-pwd> /lava-4017/0/tests/0_smoke-tests <LAVA_SIGNAL_ENDTC linux-posix-pwd> Received signal: <STARTRUN> 0_smoke-tests 4017_1.6.2.4.1 Starting test lava.0_smoke-tests (4017_1.6.2.4.1) Skipping test definition patterns. Received signal: <STARTTC> linux-posix-pwd Received signal: <ENDTC> linux-posix-pwd <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=linux-posix-pwd RESULT=pass> + lava-test-case linux-posix-uname --shell uname -a Received signal: <TESTCASE> TEST_CASE_ID=linux-posix-pwd RESULT=pass case: linux-posix-pwd
case_id: 74988
definition: 0_smoke-tests
endtc: 983
result: pass
starttc: 982
<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> + lava-test-case linux-posix-vmstat --shell vmstat Received signal: <TESTCASE> TEST_CASE_ID=linux-posix-uname RESULT=pass case: linux-posix-uname
case_id: 74989
definition: 0_smoke-tests
endtc: 992
result: pass
starttc: 991
<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 464856 0 23288 0 0 288 0 2329 980 7 34 58 1 0 <LAVA_SIGNAL_ENDTC linux-posix-vmstat> <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=linux-posix-vmstat RESULT=pass> + lava-test-case linux-posix-ifconfig --shell ifconfig -a <LAVA_SIGNAL_STARTTC linux-posix-ifconfig> Received signal: <ENDTC> linux-posix-vmstat Received signal: <TESTCASE> TEST_CASE_ID=linux-posix-vmstat RESULT=pass case: linux-posix-vmstat
case_id: 74990
definition: 0_smoke-tests
endtc: 1006
result: pass
starttc: 998
Received signal: <STARTTC> linux-posix-ifconfig eth0 Link encap:Ethernet HWaddr c8:a0:30:c2:cf:6f inet addr:10.15.1.249 Bcast:10.15.255.255 Mask:255.240.0.0 inet6 addr: fe80::caa0:30ff:fec2:cf6f/64 Scope:Link UP BROADCAST RUNNING MULTICAST MTU:1500 Metric:1 RX packets:16381 errors:0 dropped:0 overruns:0 frame:0 TX packets:10790 errors:0 dropped:0 overruns:0 carrier:0 collisions:0 txqueuelen:1000 RX bytes:19709398 (18.7 MiB) TX bytes:1253318 (1.1 MiB) Interrupt:56 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: 74991
definition: 0_smoke-tests
endtc: 1030
result: pass
starttc: 1009
+ lava-test-case linux-posix-lscpu --shell lscpu <LAVA_SIGNAL_STARTTC linux-posix-lscpu> Architecture: armv7l Byte Order: Little Endian CPU(s): 1 On-line CPU(s) list: 0 Thread(s) per core: 1 Core(s) per socket: 1 Socket(s): 1 Model name: ARMv7 Processor rev 2 (v7l) <LAVA_SIGNAL_ENDTC linux-posix-lscpu> <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=linux-posix-lscpu RESULT=pass> Received signal: <STARTTC> linux-posix-lscpu Received signal: <ENDTC> linux-posix-lscpu Received signal: <TESTCASE> TEST_CASE_ID=linux-posix-lscpu RESULT=pass case: linux-posix-lscpu
case_id: 74992
definition: 0_smoke-tests
endtc: 1047
result: pass
starttc: 1046
+ 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-4017/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 4017_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: 74993
definition: 0_smoke-tests
endtc: 1059
result: fail
starttc: 1052
Received signal: <ENDRUN> 0_smoke-tests 4017_1.6.2.4.1 Ending use of test pattern. Ending test lava.0_smoke-tests (4017_1.6.2.4.1), duration 0.34 case: 0_smoke-tests
case_id: 74994
commit_id: 7273242aa3cbdfe8018354412522ff2905639c2c
definition: lava
duration: 0.34
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: 4017_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' 'pdu15' '--command' 'off' '--port' '12' >> pdu15 port 12 now powered off Returned 0 in 2 seconds end: 4.1 power-off (duration 00:00:03) [common] case: power-off
case_id: 74995
definition: lava
duration: 2.65
extra: ...
level: 4.1
namespace: common
result: pass
start: 4.2 read-feedback (timeout 00:00:27) [common] Listened to connection for namespace 'common' for up to 1s Finalising connection for namespace 'common' Disconnecting ShellSession Disconnecting from telnet: Finalise ] quit root@jessie:~# quit end: 4.2 read-feedback (duration 00:00:01) [common] end: 4 finalize (duration 00:00:04) [common] Cleaning after the job Cleaning up download directory: /var/lib/lava/dispatcher/tmp/lavafed-4017/tftp-deploy-bqo4_71b/ramdisk Cleaning up download directory: /var/lib/lava/dispatcher/tmp/lavafed-4017/tftp-deploy-bqo4_71b/kernel Cleaning up download directory: /var/lib/lava/dispatcher/tmp/lavafed-4017/tftp-deploy-bqo4_71b/dtb Cleaning up download directory: /var/lib/lava/dispatcher/tmp/lavafed-4017/tftp-deploy-bqo4_71b/nfsrootfs Cleaning up download directory: /var/lib/lava/dispatcher/tmp/lavafed-4017/tftp-deploy-bqo4_71b/modules Override tmp directory removed at /var/lib/lava/dispatcher/tmp/lavafed-4017 Root tmp directory removed at /var/lib/lava/dispatcher/tmp/lavafed-4017 Job finished correctly case: job
case_id: 74996
definition: lava
result: pass