Help
Description
[lavafed 2019.04.0032.g55afae484] panda - health-check
Device-type
panda
Submitter
Rémi Duraffort
Created
1 week, 4 days ago
Priority
Medium
Visibility
Publicly visible
Required Tags
staging.validation.linaro.org
#467
Complete
Device
staging-panda01@staging.validation.linaro.org
Started
1 week, 4 days ago
Duration
3 minutes
Results
  
lava-dispatcher, installed at version: 2019.04.0032.g55afae484+stretch start: 0 validate Start time: 2019-05-15 09:07:37.113603+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/omap4-panda.dtb 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.12 case: validate
case_id: 10067
definition: lava
result: pass
start: 1 tftp-deploy (timeout 00:03:00) [common] start: 1.1 download-retry (timeout 00:03:00) [common] start: 1.1.1 http-download (timeout 00:03: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-467/tftp-deploy-dk70_ss9/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.50s (23.78MB/s) end: 1.1.1 http-download (duration 00:00:01) [common] case: http-download
case_id: 10071
definition: lava
duration: 0.50
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:02:59) [common] start: 1.2.1 http-download (timeout 00:02: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-467/tftp-deploy-dk70_ss9/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.27s (11.27MB/s) end: 1.2.1 http-download (duration 00:00:00) [common] case: http-download
case_id: 10071
definition: lava
duration: 0.27
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:02:59) [common] start: 1.3.1 http-download (timeout 00:02:59) [common] downloading http://images.validation.linaro.org/snapshots.linaro.org/components/lava/standard/debian/jessie/armhf/4/dtbs/omap4-panda.dtb saving as /var/lib/lava/dispatcher/tmp/lavafed-467/tftp-deploy-dk70_ss9/dtb/omap4-panda.dtb total size: 55135 (0MB) No compression specified progress 59% (0MB) progress 100% (0MB) 0MB downloaded in 0.05s (1.04MB/s) end: 1.3.1 http-download (duration 00:00:00) [common] case: http-download
case_id: 10071
definition: lava
duration: 0.05
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:02:59) [common] start: 1.4.1 http-download (timeout 00:02:59) [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-467/tftp-deploy-dk70_ss9/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 1.94s (16.19MB/s) end: 1.4.1 http-download (duration 00:00:02) [common] case: http-download
case_id: 10071
definition: lava
duration: 1.94
extra: ...
level: 1.4.1
namespace: common
result: pass
end: 1.4 download-retry (duration 00:00:02) [common] start: 1.5 prepare-tftp-overlay (timeout 00:02:57) [common] start: 1.5.1 extract-nfsrootfs (timeout 00:02:57) [common] end: 1.5.1 extract-nfsrootfs (duration 00:00:00) [common] start: 1.5.2 lava-overlay (timeout 00:02:57) [common] [common] Preparing overlay tarball in /var/lib/lava/dispatcher/tmp/lavafed-467/lava-overlay-zo84q73q makedir: /var/lib/lava/dispatcher/tmp/lavafed-467/lava-overlay-zo84q73q/lava-467/bin makedir: /var/lib/lava/dispatcher/tmp/lavafed-467/lava-overlay-zo84q73q/lava-467/tests makedir: /var/lib/lava/dispatcher/tmp/lavafed-467/lava-overlay-zo84q73q/lava-467/results Creating /var/lib/lava/dispatcher/tmp/lavafed-467/lava-overlay-zo84q73q/lava-467/bin/lava-add-keys Creating /var/lib/lava/dispatcher/tmp/lavafed-467/lava-overlay-zo84q73q/lava-467/bin/lava-add-sources Creating /var/lib/lava/dispatcher/tmp/lavafed-467/lava-overlay-zo84q73q/lava-467/bin/lava-background-process-start Creating /var/lib/lava/dispatcher/tmp/lavafed-467/lava-overlay-zo84q73q/lava-467/bin/lava-background-process-stop Creating /var/lib/lava/dispatcher/tmp/lavafed-467/lava-overlay-zo84q73q/lava-467/bin/lava-common-functions Creating /var/lib/lava/dispatcher/tmp/lavafed-467/lava-overlay-zo84q73q/lava-467/bin/lava-echo-ipv4 Creating /var/lib/lava/dispatcher/tmp/lavafed-467/lava-overlay-zo84q73q/lava-467/bin/lava-install-packages Creating /var/lib/lava/dispatcher/tmp/lavafed-467/lava-overlay-zo84q73q/lava-467/bin/lava-installed-packages Creating /var/lib/lava/dispatcher/tmp/lavafed-467/lava-overlay-zo84q73q/lava-467/bin/lava-os-build Creating /var/lib/lava/dispatcher/tmp/lavafed-467/lava-overlay-zo84q73q/lava-467/bin/lava-probe-channel Creating /var/lib/lava/dispatcher/tmp/lavafed-467/lava-overlay-zo84q73q/lava-467/bin/lava-probe-ip Creating /var/lib/lava/dispatcher/tmp/lavafed-467/lava-overlay-zo84q73q/lava-467/bin/lava-target-ip Creating /var/lib/lava/dispatcher/tmp/lavafed-467/lava-overlay-zo84q73q/lava-467/bin/lava-target-mac Creating /var/lib/lava/dispatcher/tmp/lavafed-467/lava-overlay-zo84q73q/lava-467/bin/lava-target-storage Creating /var/lib/lava/dispatcher/tmp/lavafed-467/lava-overlay-zo84q73q/lava-467/bin/lava-test-case Creating /var/lib/lava/dispatcher/tmp/lavafed-467/lava-overlay-zo84q73q/lava-467/bin/lava-test-event Creating /var/lib/lava/dispatcher/tmp/lavafed-467/lava-overlay-zo84q73q/lava-467/bin/lava-test-feedback Creating /var/lib/lava/dispatcher/tmp/lavafed-467/lava-overlay-zo84q73q/lava-467/bin/lava-test-raise Creating /var/lib/lava/dispatcher/tmp/lavafed-467/lava-overlay-zo84q73q/lava-467/bin/lava-test-reference Creating /var/lib/lava/dispatcher/tmp/lavafed-467/lava-overlay-zo84q73q/lava-467/bin/lava-test-runner Creating /var/lib/lava/dispatcher/tmp/lavafed-467/lava-overlay-zo84q73q/lava-467/bin/lava-test-set Creating /var/lib/lava/dispatcher/tmp/lavafed-467/lava-overlay-zo84q73q/lava-467/bin/lava-test-shell Updating /var/lib/lava/dispatcher/tmp/lavafed-467/lava-overlay-zo84q73q/lava-467/bin/lava-install-packages (oe) Updating /var/lib/lava/dispatcher/tmp/lavafed-467/lava-overlay-zo84q73q/lava-467/bin/lava-installed-packages (oe) start: 1.5.2.1 ssh-authorize (timeout 00:02:57) [common] end: 1.5.2.1 ssh-authorize (duration 00:00:00) [common] start: 1.5.2.2 lava-vland-overlay (timeout 00:02:57) [common] skipped lava-vland-overlay end: 1.5.2.2 lava-vland-overlay (duration 00:00:00) [common] start: 1.5.2.3 lava-multinode-overlay (timeout 00:02:57) [common] skipped lava-multinode-overlay end: 1.5.2.3 lava-multinode-overlay (duration 00:00:00) [common] start: 1.5.2.4 test-definition (timeout 00:02:57) [common] Loading test definitions start: 1.5.2.4.1 git-repo-action (timeout 00:02:57) [common] Using /lava-467 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-467/lava-overlay-zo84q73q/lava-467/0/tests/0_smoke-tests --depth=1' Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/lavafed-467/lava-overlay-zo84q73q/lava-467/0/tests/0_smoke-tests/lava-test-shell/smoke-tests-basic.yaml uuid=467_1.5.2.4.1 testdef=None end: 1.5.2.4.1 git-repo-action (duration 00:00:00) [common] case: git-repo-action
case_id: 10093
definition: lava
duration: 0.21
extra: ...
level: 1.5.2.4.1
namespace: common
result: pass
start: 1.5.2.4.2 test-overlay (timeout 00:02:57) [common] end: 1.5.2.4.2 test-overlay (duration 00:00:00) [common] case: test-overlay
case_id: 10094
definition: lava
duration: 0.01
extra: ...
level: 1.5.2.4.2
namespace: common
result: pass
start: 1.5.2.4.3 test-install-overlay (timeout 00:02:57) [common] end: 1.5.2.4.3 test-install-overlay (duration 00:00:00) [common] case: test-install-overlay
case_id: 10095
definition: lava
duration: 0.01
extra: ...
level: 1.5.2.4.3
namespace: common
result: pass
start: 1.5.2.4.4 test-runscript-overlay (timeout 00:02:57) [common] runner path: /var/lib/lava/dispatcher/tmp/lavafed-467/lava-overlay-zo84q73q/lava-467/0/tests/0_smoke-tests test_uuid 467_1.5.2.4.1 end: 1.5.2.4.4 test-runscript-overlay (duration 00:00:00) [common] case: test-runscript-overlay
case_id: 10096
definition: lava
duration: 0.01
extra: ...
level: 1.5.2.4.4
namespace: common
result: pass
Creating lava-test-runner.conf files Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/lavafed-467/lava-overlay-zo84q73q/lava-467/0 for stage 0 - 0_smoke-tests end: 1.5.2.4 test-definition (duration 00:00:00) [common] start: 1.5.2.5 compress-overlay (timeout 00:02:57) [common] end: 1.5.2.5 compress-overlay (duration 00:00:00) [common] start: 1.5.2.6 persistent-nfs-overlay (timeout 00:02:57) [common] end: 1.5.2.6 persistent-nfs-overlay (duration 00:00:00) [common] end: 1.5.2 lava-overlay (duration 00:00:00) [common] start: 1.5.3 extract-overlay-ramdisk (timeout 00:02:57) [common] nice cpio -iud -F /var/lib/lava/dispatcher/tmp/lavafed-467/extract-overlay-ramdisk-87sgyjmn/ramdisk.cpio output: 61218 blocks output: end: 1.5.3 extract-overlay-ramdisk (duration 00:00:01) [common] start: 1.5.4 extract-modules (timeout 00:02:56) [common] extracting modules file /var/lib/lava/dispatcher/tmp/lavafed-467/tftp-deploy-dk70_ss9/modules/modules.tar to /var/lib/lava/dispatcher/tmp/lavafed-467/extract-overlay-ramdisk-87sgyjmn/ramdisk end: 1.5.4 extract-modules (duration 00:00:02) [common] start: 1.5.5 apply-overlay-tftp (timeout 00:02:54) [common] [common] Applying overlay /var/lib/lava/dispatcher/tmp/lavafed-467/compress-overlay-wk494tnc/overlay-1.5.2.5.tar.gz to ramdisk [common] Applying overlay /var/lib/lava/dispatcher/tmp/lavafed-467/compress-overlay-wk494tnc/overlay-1.5.2.5.tar.gz to directory /var/lib/lava/dispatcher/tmp/lavafed-467/extract-overlay-ramdisk-87sgyjmn/ramdisk end: 1.5.5 apply-overlay-tftp (duration 00:00:00) [common] start: 1.5.6 prepare-kernel (timeout 00:02:54) [common] start: 1.5.6.1 uboot-prepare-kernel (timeout 00:02:54) [common] end: 1.5.6.1 uboot-prepare-kernel (duration 00:00:00) [common] end: 1.5.6 prepare-kernel (duration 00:00:00) [common] start: 1.5.7 configure-preseed-file (timeout 00:02:54) [common] end: 1.5.7 configure-preseed-file (duration 00:00:00) [common] start: 1.5.8 compress-ramdisk (timeout 00:02:54) [common] Building ramdisk /var/lib/lava/dispatcher/tmp/lavafed-467/extract-overlay-ramdisk-87sgyjmn/ramdisk.cpio containing /var/lib/lava/dispatcher/tmp/lavafed-467/extract-overlay-ramdisk-87sgyjmn/ramdisk find . | cpio --create --format='newc' > /var/lib/lava/dispatcher/tmp/lavafed-467/extract-overlay-ramdisk-87sgyjmn/ramdisk.cpio 190561 blocks Adding RAMdisk u-boot header. nice mkimage -A arm -T ramdisk -C none -d /var/lib/lava/dispatcher/tmp/lavafed-467/extract-overlay-ramdisk-87sgyjmn/ramdisk.cpio.gz /var/lib/lava/dispatcher/tmp/lavafed-467/extract-overlay-ramdisk-87sgyjmn/ramdisk.cpio.gz.uboot output: Image Name: output: Created: Wed May 15 09:07:57 2019 output: Image Type: ARM Linux RAMDisk Image (uncompressed) output: Data Size: 35033721 Bytes = 34212.62 kB = 33.41 MB output: Load Address: 00000000 output: Entry Point: 00000000 output: rename /var/lib/lava/dispatcher/tmp/lavafed-467/extract-overlay-ramdisk-87sgyjmn/ramdisk.cpio.gz.uboot to /var/lib/lava/dispatcher/tmp/lavafed-467/tftp-deploy-dk70_ss9/ramdisk/ramdisk.cpio.gz.uboot end: 1.5.8 compress-ramdisk (duration 00:00:18) [common] end: 1.5 prepare-tftp-overlay (duration 00:00:21) [common] start: 1.6 lxc-create-udev-rule-action (timeout 00:02:36) [common] No LXC device requested end: 1.6 lxc-create-udev-rule-action (duration 00:00:00) [common] start: 1.7 deploy-device-env (timeout 00:02:36) [common] end: 1.7 deploy-device-env (duration 00:00:00) [common] Checking files for TFTP limit of 4294967296 bytes. end: 1 tftp-deploy (duration 00:00:24) [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] {'bootm': {'kernel': '0x82000000', 'dtb': '0x83f00000', 'ramdisk': '0x84000000'}, 'pass': None, 'zimage': {'kernel': '0x82000000', 'dtb': '0x83f00000', 'ramdisk': '0x84000000'}, 'bootz': {'kernel': '0x82000000', 'dtb': '0x83f00000', 'ramdisk': '0x84000000'}, 'uimage': {'kernel': '0x82000000', 'dtb': '0x83f00000', 'ramdisk': '0x84000000'}} Parsed boot commands: usb start; setenv autoload no; setenv initrd_high 0xffffffff; setenv fdt_high 0xffffffff; dhcp; setenv serverip 10.3.1.1; tftp 0x82000000 lavafed-467/tftp-deploy-dk70_ss9/kernel/vmlinuz; tftp 0x84000000 lavafed-467/tftp-deploy-dk70_ss9/ramdisk/ramdisk.cpio.gz.uboot; setenv initrd_size ${filesize}; tftp 0x83f00000 lavafed-467/tftp-deploy-dk70_ss9/dtb/omap4-panda.dtb; setenv bootargs 'console=ttyO2,115200n8 root=/dev/ram0 fixrtc nocompcache vram=48M omapfb.vram=0:24M mem=456M@0x80000000 mem=512M@0xA0000000 rootdelay=5 ip=dhcp'; bootz 0x82000000 0x84000000 0x83f00000 end: 2.2 bootloader-overlay (duration 00:00:00) [common] case: bootloader-overlay
case_id: 10102
definition: lava
duration: 0.00
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 serial4 7012' end: 2.3 connect-device (duration 00:00:01) [common] start: 2.4 uboot-retry (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' '23' >> pdu15 port 23 now powered off >> pdu15 port 23 now powered on >> pdu15 port 23 now power cycled Returned 0 in 21 seconds end: 2.4.1.1 pdu-reboot (duration 00:00:22) [common] case: pdu-reboot
case_id: 10108
definition: lava
duration: 21.86
extra: ...
level: 2.4.1.1
namespace: common
result: pass
end: 2.4.1 reset-device (duration 00:00:22) [common] start: 2.4.2 bootloader-interrupt (timeout 00:03:37) [common] bootloader-interrupt: Wait for prompt Hit any key to stop autoboot (timeout 00:04:00) Trying 10.0.2.4... Connected to serial4.lavalab. Escape character is '^]'. U-Boot SPL 2015.04+dfsg1-2 (May 26 2015 - 05:33:36) OMAP4430 ES2.1 SPL: Please implement spl_start_uboot() for your board SPL: Direct Linux boot not active! reading u-boot.img reading u-boot.img U-Boot 2015.04+dfsg1-2 (May 26 2015 - 05:33:36) CPU : OMAP4430 ES2.1 Board: OMAP4 Panda I2C: ready DRAM: 1 GiB MMC: OMAP SD/MMC: 0 reading uboot.env Net: No ethernet found. Hit any key to stop autoboot: 10 end: 2.4.2 bootloader-interrupt (duration 00:00:00) [common] start: 2.4.3 bootloader-commands (timeout 00:03:37) [common] bootloader-commands: Wait for prompt ['U-Boot'] (timeout 00:03:37)  0 usb start U-Boot# usb start 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.'] (timeout 00:03:37) usb start starting USB... USB0: USB EHCI 1.00 scanning bus 0 for devices... 4 USB Device(s) found scanning usb for storage devices... 1 Storage Device(s) found scanning usb for ethernet devices... 1 Ethernet Device(s) found 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.'] (timeout 00:03:32) setenv autoload no 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.'] (timeout 00:03:31) setenv initrd_high 0xffffffff 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.'] (timeout 00:03:31) setenv fdt_high 0xffffffff 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.'] (timeout 00:03:31) dhcp Waiting for Ethernet connection... done. BOOTP broadcast 1 BOOTP broadcast 2 BOOTP broadcast 3 DHCP client bound to address 10.15.193.162 (1899 ms) 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.'] (timeout 00:03:27) setenv serverip 10.3.1.1 tftp 0x82000000 lavafed-467/tftp-deploy-dk70_ss9/kernel/vmlinuz U-Boot# tftp 0x82000000 lavafed-467/tftp-deploy-dk70_ss9/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.'] (timeout 00:03:27) tftp 0x82000000 lavafed-467/tftp-deploy-dk70_ss9/kernel/vmlinuz Waiting for Ethernet connection... done. Using sms0 device TFTP from server 10.3.1.1; our IP address is 10.15.193.162 Filename 'lavafed-467/tftp-deploy-dk70_ss9/kernel/vmlinuz'. Load address: 0x82000000 Loading: *EHCI timed out on TD - token=0x88008d80 T ################################################################# ################################################################# ################################################################# ####################### 509.8 KiB/s done Bytes transferred = 3187808 (30a460 hex) tftp 0x84000000 lavafed-467/tftp-deploy-dk70_ss9/ramdisk/ramdisk.cpio.gz.uboot U-Boot# tftp 0x84000000 lavafed-467/tftp-deploy-dk70_ss9/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.'] (timeout 00:03:19) tftp 0x84000000 lavafed-467/tftp-deploy-dk70_ss9/ramdisk/ramdisk.cpio.gz.uboot Waiting for Ethernet connection... done. Using sms0 device TFTP from server 10.3.1.1; our IP address is 10.15.193.162 Filename 'lavafed-467/tftp-deploy-dk70_ss9/ramdisk/ramdisk.cpio.gz.uboot'. Load address: 0x84000000 Loading: *T ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ############################################### 1.8 MiB/s done Bytes transferred = 35033785 (21692b9 hex) 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.'] (timeout 00:02:59) setenv initrd_size ${filesize} tftp 0x83f00000 lavafed-467/tftp-deploy-dk70_ss9/dtb/omap4-panda.dtb U-Boot# tftp 0x83f00000 lavafed-467/tftp-deploy-dk70_ss9/dtb/omap4-panda.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.'] (timeout 00:02:58) tftp 0x83f00000 lavafed-467/tftp-deploy-dk70_ss9/dtb/omap4-panda.dtb Waiting for Ethernet connection... done. Using sms0 device TFTP from server 10.3.1.1; our IP address is 10.15.193.162 Filename 'lavafed-467/tftp-deploy-dk70_ss9/dtb/omap4-panda.dtb'. Load address: 0x83f00000 Loading: *EHCI timed out on TD - token=0x88008d80 T #### 8.8 KiB/s done Bytes transferred = 55135 (d75f hex) setenv bootargs 'console=ttyO2,115200n8 root=/dev/ram0 fixrtc nocompcache vram=48M omapfb.vram=0:24M mem=456M@0x80000000 mem=512M@0xA0000000 rootdelay=5 ip=dhcp' U-Boot# setenv bootargs 'console=ttyO2,115200n8 root=/dev/ram0 fixrtc nocompcache vram=48M omapfb.vram=0:24M mem=456M@0x80000000 mem=512M@0xA0000000 rootdelay=5 ip=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.'] (timeout 00:02:51) setenv bootargs 'console=ttyO2,115200n8 root=/dev/ram0 fixrtc nocompcache vram=48M omapfb.vram=0:24M mem=456M@0x80000000 mem=512M@0xA0000000 rootdelay=5 ip=dhcp' bootz 0x82000000 0x84000000 0x83f00000 U-Boot# bootz 0x82000000 0x84000000 0x83f00000 bootloader-commands: Wait for prompt Starting kernel (timeout 00:02:51) bootz 0x82000000 0x84000000 0x83f00000 Kernel image @ 0x82000000 [ 0x000000 - 0x30a460 ] ## Loading init Ramdisk from Legacy Image at 84000000 ... Image Name: Image Type: ARM Linux RAMDisk Image (uncompressed) Data Size: 35033721 Bytes = 33.4 MiB Load Address: 00000000 Entry Point: 00000000 Verifying Checksum ... OK ## Flattened Device Tree blob at 83f00000 Booting using the fdt blob at 0x83f00000 Using Device Tree in place at 83f00000, end 83f1075e Starting kernel ... end: 2.4.3 bootloader-commands (duration 00:00:47) [common] start: 2.4.4 auto-login-action (timeout 00:02:50) [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.'] (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 [411fc092] revision 2 (ARMv7), cr=10c5387d Using line separator: #'\n'# No login prompt set. 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*) \\]-+)', '\\(initramfs\\)', 'Login timed out', 'Login incorrect'] [auto-login-action] Waiting for messages, (timeout 00:02:43) Waiting using forced prompt support. 81.54592049121857s timeout [ 0.000000] CPU: PIPT / VIPT nonaliasing data cache, VIPT aliasing instruction cache [ 0.000000] Machine model: TI OMAP4 PandaBoard [ 0.000000] Memory policy: Data cache writealloc [ 0.000000] OMAP4430 ES2.1 [ 0.000000] L2C: platform modifies aux control register: 0x0e070000 -> 0x3e470000 [ 0.000000] L2C: DT/platform modifies aux control register: 0x0e070000 -> 0x3e470000 [ 0.000000] L2C-310 errata 727915 769419 enabled [ 0.000000] L2C-310 enabling early BRESP for Cortex-A9 [ 0.000000] L2C-310 ID prefetch enabled, offset 1 lines [ 0.000000] L2C-310 cache controller enabled, 16 ways, 1024 kB [ 0.000000] L2C-310: CACHE_ID 0x410000c4, AUX_CTRL 0x7e470000 [ 0.000000] PERCPU: Embedded 9 pages/cpu @eefb2000 s12672 r8192 d16000 u36864 [ 0.000000] Built 1 zonelists in Zone order, mobility grouping on. Total pages: 246032 [ 0.000000] Kernel command line: console=ttyO2,115200n8 root=/dev/ram0 fixrtc nocompcache vram=48M omapfb.vram=0:24M mem=456M@0x80000000 mem=512M@0xA0000000 rootdelay=5 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: 936528K/990208K available (6434K kernel code, 830K rwdata, 2200K rodata, 688K init, 393K bss, 53680K reserved, 269312K 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] ti_dt_clocks_register: failed to lookup clock node div_ts_ck [ 0.000000] ti_dt_clocks_register: failed to lookup clock node bandgap_ts_fclk [ 0.000000] OMAP clockevent source: timer1 at 32768 Hz [ 0.000000] sched_clock: 32 bits at 32kHz, resolution 30517ns, wraps every 65536000000000ns [ 0.000030] OMAP clocksource: 32k_counter at 32768 Hz [ 0.005676] Console: colour dummy device 80x30 [ 0.005706] Calibrating delay loop... 1191.93 BogoMIPS (lpj=2383872) [ 0.024383] pid_max: default: 32768 minimum: 301 [ 0.024536] Security Framework initialized [ 0.024597] AppArmor: AppArmor disabled by boot time parameter [ 0.024597] Yama: disabled by default; enable with sysctl kernel.yama.* [ 0.024688] Mount-cache hash table entries: 2048 (order: 1, 8192 bytes) [ 0.024719] Mountpoint-cache hash table entries: 2048 (order: 1, 8192 bytes) [ 0.025817] Initializing cgroup subsys memory [ 0.025848] Initializing cgroup subsys devices [ 0.025878] Initializing cgroup subsys freezer [ 0.025909] Initializing cgroup subsys net_cls [ 0.025939] Initializing cgroup subsys blkio [ 0.025970] Initializing cgroup subsys perf_event [ 0.026000] Initializing cgroup subsys net_prio [ 0.026092] CPU: Testing write buffer coherency: ok [ 0.026153] ftrace: allocating 23146 entries in 68 pages [ 0.078155] CPU0: thread -1, cpu 0, socket 0, mpidr 80000000 [ 0.078796] Setting up static identity map for 0x805c8088 - 0x805c80e0 [ 0.092956] CPU1: Booted secondary processor [ 0.116333] CPU1: thread -1, cpu 1, socket 0, mpidr 80000001 [ 0.116455] Brought up 2 CPUs [ 0.116485] SMP: Total of 2 processors activated (2390.01 BogoMIPS). [ 0.116485] CPU: All CPU(s) started in SVC mode. [ 0.117218] devtmpfs: initialized [ 0.122314] VFP support v0.3: implementor 41 architecture 3 part 30 variant 9 rev 1 [ 0.124542] omap_hwmod: l3_main_3 using broken dt data from ocp [ 0.126220] omap_hwmod: l3_main_2 using broken dt data from ocp [ 0.232727] pinctrl core: initialized pinctrl subsystem [ 0.233215] regulator-dummy: no parameters [ 0.252746] NET: Registered protocol family 16 [ 0.253875] DMA: preallocated 256 KiB pool for atomic coherent allocations [ 0.266235] OMAP GPIO hardware version 0.1 [ 0.270874] omap-gpmc 50000000.gpmc: GPMC revision 6.0 [ 0.271392] irq: no irq domain found for /ocp/pinmux@4a100040 ! [ 0.271759] irq: no irq domain found for /ocp/pinmux@4a100040 ! [ 0.272094] irq: no irq domain found for /ocp/pinmux@4a100040 ! [ 0.282958] platform 4b501000.aes: Cannot lookup hwmod 'aes' [ 0.283233] platform 480a5000.des: Cannot lookup hwmod 'des' [ 0.288635] No ATAGs? [ 0.288665] hw-breakpoint: found 5 (+1 reserved) breakpoint and 1 watchpoint registers. [ 0.288696] hw-breakpoint: maximum watchpoint size is 4 bytes. [ 0.288970] EXYNOS: PMU not supported [ 0.289459] omap_init_mbox: hwmod doesn't have valid attrs [ 0.290252] OMAP DMA hardware revision 0.0 [ 0.291320] Serial: AMBA PL011 UART driver [ 0.292144] ARM PMU: not yet supported on OMAP4430 due to missing CTI driver [ 0.313293] edma-dma-engine edma-dma-engine.0: Can't allocate PaRAM dummy slot [ 0.313293] edma-dma-engine: probe of edma-dma-engine.0 failed with error -5 [ 0.329925] omap-dma-engine 4a056000.dma-controller: OMAP DMA engine driver [ 0.404388] hsusb1_vbus: 3300 mV [ 0.404541] reg-fixed-voltage wl12xx_vmmc: could not find pctldev for node /ocp/pinmux@4a100040/pinmux_wl12xx_gpio, deferring probe [ 0.404571] platform wl12xx_vmmc: Driver reg-fixed-voltage requests probe deferral [ 0.407745] vgaarb: loaded [ 0.408386] usbcore: registered new interface driver usbfs [ 0.408477] usbcore: registered new interface driver hub [ 0.408660] usbcore: registered new device driver usb [ 0.409790] omap_i2c 48070000.i2c: could not find pctldev for node /ocp/pinmux@4a100040/pinmux_i2c1_pins, deferring probe [ 0.409790] platform 48070000.i2c: Driver omap_i2c requests probe deferral [ 0.409851] omap_i2c 48072000.i2c: could not find pctldev for node /ocp/pinmux@4a100040/pinmux_i2c2_pins, deferring probe [ 0.409881] platform 48072000.i2c: Driver omap_i2c requests probe deferral [ 0.409912] omap_i2c 48060000.i2c: could not find pctldev for node /ocp/pinmux@4a100040/pinmux_i2c3_pins, deferring probe [ 0.409912] platform 48060000.i2c: Driver omap_i2c requests probe deferral [ 0.409973] omap_i2c 48350000.i2c: could not find pctldev for node /ocp/pinmux@4a100040/pinmux_i2c4_pins, deferring probe [ 0.409973] platform 48350000.i2c: Driver omap_i2c requests probe deferral [ 0.410583] media: Linux media interface: v0.10 [ 0.410675] Linux video capture interface: v2.00 [ 0.410797] pps_core: LinuxPPS API ver. 1 registered [ 0.410797] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it> [ 0.410858] PTP clock support registered [ 0.411743] omap-iommu 4a066000.mmu: 4a066000.mmu registered [ 0.411865] omap-iommu 55082000.mmu: 55082000.mmu registered [ 0.413177] Switched to clocksource 32k_counter [ 0.471282] NET: Registered protocol family 2 [ 0.472442] TCP established hash table entries: 8192 (order: 3, 32768 bytes) [ 0.472564] TCP bind hash table entries: 8192 (order: 4, 65536 bytes) [ 0.472717] TCP: Hash tables configured (established 8192 bind 8192) [ 0.472808] TCP: reno registered [ 0.472808] UDP hash table entries: 512 (order: 2, 16384 bytes) [ 0.472900] UDP-Lite hash table entries: 512 (order: 2, 16384 bytes) [ 0.473266] NET: Registered protocol family 1 [ 0.475280] Unpacking initramfs... [ 4.287445] Freeing initrd memory: 34208K (c4001000 - c6169000) [ 4.291839] futex hash table entries: 512 (order: 3, 32768 bytes) [ 4.292022] audit: initializing netlink subsys (disabled) [ 4.292114] audit: type=2000 audit(4.261:1): initialized [ 4.294555] zbud: loaded [ 4.294982] VFS: Disk quotas dquot_6.5.2 [ 4.295074] Dquot-cache hash table entries: 1024 (order 0, 4096 bytes) [ 4.295227] msgmni has been set to 1369 [ 4.296905] alg: No test for stdrng (krng) [ 4.297027] bounce: pool size: 64 pages [ 4.297119] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 249) [ 4.297393] io scheduler noop registered [ 4.297424] io scheduler deadline registered [ 4.297607] io scheduler cfq registered (default) [ 4.299224] pinctrl-single 4a100040.pinmux: 203 pins at pa fc100040 size 406 [ 4.299468] pinctrl-single 4a31e040.pinmux: 28 pins at pa fc31e040 size 56 [ 4.305023] Serial: 8250/16550 driver, 4 ports, IRQ sharing disabled [ 4.307067] Serial: AMBA driver [ 4.307586] Serial: IMX driver [ 4.308685] omap_uart 4806a000.serial: no wakeirq for uart0 [ 4.308898] 4806a000.serial: ttyO0 at MMIO 0x4806a000 (irq = 104, base_baud = 3000000) is a OMAP UART0 [ 4.309570] 4806c000.serial: ttyO1 at MMIO 0x4806c000 (irq = 105, base_baud = 3000000) is a OMAP UART1 [ 4.310150] 48020000.serial: ttyO2 at MMIO 0x48020000 (irq = 106, base_baud = 3000000) is a OMAP UART2 [ 5.234161] console [ttyO2] enabled [ 5.238464] 4806e000.serial: ttyO3 at MMIO 0x4806e000 (irq = 102, base_baud = 3000000) is a OMAP UART3 [ 5.253540] mousedev: PS/2 mouse device common for all mice [ 5.263610] ledtrig-cpu: registered to indicate activity on CPUs [ 5.270050] hidraw: raw HID events driver (C) Jiri Kosina [ 5.276428] usbcore: registered new interface driver usbhid [ 5.282287] usbhid: USB HID core driver [ 5.287689] TCP: cubic registered [ 5.291290] NET: Registered protocol family 10 [ 5.296966] mip6: Mobile IPv6 [ 5.300140] NET: Registered protocol family 17 [ 5.304870] mpls_gso: MPLS GSO support [ 5.309082] twl: not initialized [ 5.312499] twl6030_uv_to_vsel:OUT OF RANGE! non mapped vsel for 1375000 Vs max 1316660 [ 5.320892] twl6030_uv_to_vsel:OUT OF RANGE! non mapped vsel for 1375000 Vs max 1316660 [ 5.329345] twl6030_uv_to_vsel:OUT OF RANGE! non mapped vsel for 1375000 Vs max 1316660 [ 5.337738] twl6030_uv_to_vsel:OUT OF RANGE! non mapped vsel for 1375000 Vs max 1316660 [ 5.346160] twl6030_uv_to_vsel:OUT OF RANGE! non mapped vsel for 1375000 Vs max 1316660 [ 5.354553] twl6030_uv_to_vsel:OUT OF RANGE! non mapped vsel for 1375000 Vs max 1316660 [ 5.362976] twl6030_uv_to_vsel:OUT OF RANGE! non mapped vsel for 1410000 Vs max 1316660 [ 5.371398] omap2_set_init_voltage: unable to find boot up OPP for vdd_mpu [ 5.378601] omap2_set_init_voltage: unable to set vdd_mpu [ 5.384307] omap2_set_init_voltage: unable to find boot up OPP for vdd_core [ 5.391601] omap2_set_init_voltage: unable to set vdd_core [ 5.397369] omap2_set_init_voltage: unable to find boot up OPP for vdd_iva [ 5.404602] omap2_set_init_voltage: unable to set vdd_iva [ 5.410675] Power Management for TI OMAP4+ devices. [ 5.415802] OMAP4 PM: u-boot >= v2012.07 is required for full PM support [ 5.423004] ThumbEE CPU extension supported. [ 5.423004] Registering SWP/SWPB emulation handler [ 5.432891] registered taskstats version 1 [ 5.438323] vwl1271: 1800 mV [ 5.449523] Skipping twl internal clock init and using bootloader value (unknown osc rate) [ 5.459930] twl 0-0048: PIH (irq 39) nested IRQs [ 5.465972] twl_rtc 48070000.i2c:twl@48:rtc: Power up reset detected. [ 5.473449] twl_rtc 48070000.i2c:twl@48:rtc: Enabling TWL-RTC [ 5.482391] twl_rtc 48070000.i2c:twl@48:rtc: rtc core: registered 48070000.i2c:twl@48 as rtc0 [ 5.492492] VAUX1_6030: 1000 <--> 3000 mV at 1800 mV [ 5.498870] VAUX2_6030: 1200 <--> 2800 mV at 1800 mV [ 5.505218] VAUX3_6030: 1000 <--> 3000 mV at 1200 mV [ 5.511535] VMMC: 1200 <--> 3000 mV at 3000 mV [ 5.517303] VPP: 1800 <--> 2500 mV at 1900 mV [ 5.523040] VUSIM: 1200 <--> 2900 mV at 1800 mV [ 5.528076] VDAC: 1800 mV [ 5.531555] VANA: 2100 mV [ 5.536010] VCXIO: 1800 mV [ 5.536010] VUSB: 3300 mV [ 5.543365] V1V8: 1800 mV [ 5.547149] V2V1: 2100 mV [ 5.556610] twl6040 0-004b: clk32k is not handled [ 5.565277] omap_i2c 48070000.i2c: bus 0 rev0.10 at 400 kHz [ 5.571746] omap_i2c 48072000.i2c: bus 1 rev0.10 at 400 kHz [ 5.583374] omap_i2c 48060000.i2c: bus 2 rev0.10 at 100 kHz [ 5.589874] omap_i2c 48350000.i2c: bus 3 rev0.10 at 400 kHz [ 5.597442] twl_rtc 48070000.i2c:twl@48:rtc: setting system clock to 2000-01-01 00:00:00 UTC (946684800) [ 5.617095] VUSB: disabling [ 5.620605] VANA: disabling [ 5.624114] VDAC: disabling [ 5.628448] VMMC: disabling [ 5.632781] vwl1271: disabling [ 5.637176] Freeing unused kernel memory: 688K (c0877000 - c0923000) Loading, please wait... [ 5.719177] systemd-udevd[74]: starting version 215 [ 5.726684] random: systemd-udevd: uninitialized urandom read (16 bytes read, 3 bits of entropy available) [ 5.905212] omap_hsmmc 4809c000.mmc: pins are not configured from the driver [ 6.023559] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver [ 6.041778] ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver [ 6.053161] ehci-omap: OMAP-EHCI Host Controller driver [ 6.059661] ohci-omap3: OHCI OMAP3 driver [ 6.064331] ohci-omap3 4a064800.ohci: OHCI Host Controller [ 6.070678] ohci-omap3 4a064800.ohci: new USB bus registered, assigned bus number 1 [ 6.080139] ohci-omap3 4a064800.ohci: irq 108, io mem 0x4a064800 [ 6.083557] HS USB OTG: no transceiver configured [ 6.083557] musb-hdrc musb-hdrc.0.auto: musb_init_controller failed with status -517 [ 6.083587] platform musb-hdrc.0.auto: Driver musb-hdrc requests probe deferral [ 6.085906] HS USB OTG: no transceiver configured [ 6.085906] musb-hdrc musb-hdrc.0.auto: musb_init_controller failed with status -517 [ 6.085906] platform musb-hdrc.0.auto: Driver musb-hdrc requests probe deferral [ 6.087585] HS USB OTG: no transceiver configured [ 6.087585] musb-hdrc musb-hdrc.0.auto: musb_init_controller failed with status -517 [ 6.087585] platform musb-hdrc.0.auto: Driver musb-hdrc requests probe deferral [ 6.094970] omap_wdt: OMAP Watchdog Timer Rev 0x00: initial timeout 60 sec [ 6.095153] HS USB OTG: no transceiver configured [ 6.095153] musb-hdrc musb-hdrc.0.auto: musb_init_controller failed with status -517 [ 6.095153] platform musb-hdrc.0.auto: Driver musb-hdrc requests probe deferral [ 6.100067] omap4-keypad 4a31c000.keypad: number of keypad rows/columns not specified [ 6.100585] omap4-keypad: probe of 4a31c000.keypad failed with error -22 [ 6.108551] omap-aes 4b501000.aes: _od_fail_runtime_resume: FIXME: missing hwmod/omap_dev info [ 6.108551] omap-aes 4b501000.aes: omap_aes_probe: failed to get_sync(-19) [ 6.109313] omap-aes 4b501000.aes: initialization failed. [ 6.150573] HS USB OTG: no transceiver configured [ 6.150939] musb-hdrc musb-hdrc.0.auto: musb_init_controller failed with status -517 [ 6.150939] platform musb-hdrc.0.auto: Driver musb-hdrc requests probe deferral [ 6.236053] emif 4c000000.emif: emif_probe: device configured with addr = f02aa000 and IRQ142 [ 6.245910] HS USB OTG: no transceiver configured [ 6.250885] musb-hdrc musb-hdrc.0.auto: musb_init_controller failed with status -517 [ 6.259613] platform musb-hdrc.0.auto: Driver musb-hdrc requests probe deferral [ 6.267639] emif 4d000000.emif: emif_probe: device configured with addr = f0314000 and IRQ143 [ 6.276885] twl6030_usb 48070000.i2c:twl@48:usb-comparator: Initialized TWL6030 USB module [ 6.288299] musb-hdrc musb-hdrc.0.auto: MUSB HDRC host driver [ 6.288299] abb_mpu: 1025 <--> 1389 mV [ 6.301513] pbias_mmc_omap4: 1800 <--> 3000 mV at 3000 mV [ 6.307769] mmc0: host does not support reading read-only switch. assuming write-enable. [ 6.320770] musb-hdrc musb-hdrc.0.auto: new USB bus registered, assigned bus number 2 [ 6.330688] OMAP DSS rev 4.0 [ 6.346557] usb usb1: New USB device found, idVendor=1d6b, idProduct=0001 [ 6.353759] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1 [ 6.361419] usb usb1: Product: OHCI Host Controller [ 6.366577] usb usb1: Manufacturer: Linux 3.16.0-4-armmp ohci_hcd [ 6.372985] usb usb1: SerialNumber: 4a064800.ohci [ 6.373077] mmc0: new high speed SDHC card at address aaaa [ 6.385498] mmcblk0: mmc0:aaaa SU16G 14.8 GiB [ 6.392944] hub 1-0:1.0: USB hub found [ 6.397064] hub 1-0:1.0: 3 ports detected [ 6.398284] connector-hdmi connector@1: failed to find video source [ 6.399169] platform connector@1: Driver connector-hdmi requests probe deferral [ 6.419799] usb usb2: New USB device found, idVendor=1d6b, idProduct=0002 [ 6.424926] mmcblk0: p1 p2 p3 p4 < p5 p6 p7 > [ 6.431671] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1 [ 6.432250] [drm] Initialized drm 1.1.0 20060810 [ 6.441345] ehci-omap 4a064c00.ehci: EHCI Host Controller [ 6.449768] usb usb2: Product: MUSB HDRC host driver [ 6.455047] usb usb2: Manufacturer: Linux 3.16.0-4-armmp musb-hcd [ 6.461486] usb usb2: SerialNumber: musb-hdrc.0.auto [ 6.474578] omap-abe-twl6040 sound: twl6040-legacy <-> 40132000.mcpdm mapping ok [ 6.477172] dmm 4e000000.dmm: initialized all PAT entries [ 6.478271] platform omapdrm.0: Driver omapdrm requests probe deferral [ 6.498901] hub 2-0:1.0: USB hub found [ 6.504974] hub 2-0:1.0: 1 port detected [ 6.511444] ehci-omap 4a064c00.ehci: new USB bus registered, assigned bus number 3 [ 6.524200] ehci-omap 4a064c00.ehci: irq 109, io mem 0x4a064c00 [ 6.542846] [drm] Supports vblank timestamp caching Rev 2 (21.10.2013). [ 6.549865] [drm] No driver support for vblank timestamp query. [ 6.553314] ehci-omap 4a064c00.ehci: USB 2.0 started, EHCI 1.00 [ 6.553680] usb usb3: New USB device found, idVendor=1d6b, idProduct=0002 [ 6.553680] usb usb3: New USB device strings: Mfr=3, Product=2, SerialNumber=1 [ 6.553680] usb usb3: Product: EHCI Host Controller [ 6.553741] usb usb3: Manufacturer: Linux 3.16.0-4-armmp ehci_hcd [ 6.553741] usb usb3: SerialNumber: 4a064c00.ehci [ 6.598663] omapdrm omapdrm.0: No connectors reported connected with modes [ 6.599243] hub 3-0:1.0: USB hub found [ 6.599548] hub 3-0:1.0: 3 ports detected [ 6.614257] [drm] Cannot find any crtc or sizes - going 1024x768 [ 6.638732] [drm] Enabling DMM ywrap scrolling [ 6.644134] Console: switching to colour frame buffer device 128x48 [ 6.663513] omapdrm omapdrm.0: fb0: omapdrm frame buffer device [ 6.670288] omapdrm omapdrm.0: registered panic notifier [ 6.693176] [drm] Initialized omapdrm 1.0.0 20110917 on minor 0 [ 6.913238] usb 3-1: new high-speed USB device number 2 using ehci-omap [ 7.049621] usb 3-1: New USB device found, idVendor=0424, idProduct=9514 [ 7.056701] usb 3-1: New USB device strings: Mfr=0, Product=0, SerialNumber=0 [ 7.065765] hub 3-1:1.0: USB hub found [ 7.069885] hub 3-1:1.0: 5 ports detected [ 7.403533] usb 3-1.1: new high-speed USB device number 3 using ehci-omap [ 7.513610] usb 3-1.1: New USB device found, idVendor=0424, idProduct=ec00 [ 7.520874] usb 3-1.1: New USB device strings: Mfr=0, Product=0, SerialNumber=0 [ 7.543060] smsc95xx v1.0.4 [ 7.613739] smsc95xx 3-1.1:1.0 eth0: register 'smsc95xx' at usb-4a064c00.ehci-1.1, smsc95xx USB 2.0 Ethernet, a2:ea:07:89:40:c3 [ 7.625244] usb 3-1.2: new high-speed USB device number 4 using ehci-omap [ 7.633514] usbcore: registered new interface driver smsc95xx [ 7.754119] usb 3-1.2: New USB device found, idVendor=0781, idProduct=5567 [ 7.761352] usb 3-1.2: New USB device strings: Mfr=1, Product=2, SerialNumber=3 [ 7.769012] usb 3-1.2: Product: Cruzer Blade [ 7.773529] usb 3-1.2: Manufacturer: SanDisk [ 7.778015] usb 3-1.2: SerialNumber: 2006026651028482F256 [ 7.801055] SCSI subsystem initialized [ 7.811157] usb-storage 3-1.2:1.0: USB Mass Storage device detected [ 7.818389] scsi0 : usb-storage 3-1.2:1.0 [ 7.823760] usbcore: registered new interface driver usb-storage [ 8.851440] scsi 0:0:0:0: Direct-Access SanDisk Cruzer Blade 1.20 PQ: 0 ANSI: 5 [ 8.870452] sd 0:0:0:0: [sda] 31266816 512-byte logical blocks: (16.0 GB/14.9 GiB) [ 8.880065] sd 0:0:0:0: [sda] Write Protect is off [ 8.886199] sd 0:0:0:0: [sda] Write cache: disabled, read cache: enabled, doesn't support DPO or FUA [ 8.906402] sda: sda1 sda2 [ 8.914062] sd 0:0:0:0: [sda] Attached SCSI removable disk [ 8.922515] sd 0:0:0:0: Attached scsi generic sg0 type 0 Begin: Loading essential drivers ... done. Begin: Running /scripts/init-premount ... done. Begin: Mounting root file system ... Begin: Running /scripts/local-top ... done. Begin: Running /scripts/local-premount ... done. Begin: Waiting for root file system ... Begin: Running /scripts/local-block ... done. Begin: Running /scripts/local-block ... done. Begin: Running /scripts/local-block ... done. Begin: Running /scripts/local-block ... done. Begin: Running /scripts/local-block ... done. Begin: Running /scripts/local-block ... done. Begin: Running /scripts/local-block ... done. Begin: Running /scripts/local-block ... done. Begin: Running /scripts/local-block ... done. Begin: Running /scripts/local-block ... done. Begin: Running /scripts/local-block ... done. Begin: Running /scripts/local-block ... done. Begin: Running /scripts/local-block ... done. Begin: Running /scripts/local-block ... done. Begin: Running /scripts/local-block ... done. Begin: Running /scripts/local-block ... done. Begin: Running /scripts/local-block ... done. Begin: Running /scripts/local-block ... done. Begin: Running /scripts/local-block ... done. Begin: Running /scripts/local-block ... done. Begin: Running /scripts/local-block ... done. Begin: Running /scripts/local-block ... done. Begin: Running /scripts/local-block ... done. Begin: Running /scripts/local-block ... done. Begin: Running /scripts/local-block ... done. Begin: Running /scripts/local-block ... done. Begin: Running /scripts/local-block ... done. Begin: Running /scripts/local-block ... done. Begin: Running /scripts/local-block ... done. Begin: Running /scripts/local-block ... done. done. Gave up waiting for root device. Common problems: - Boot args (cat /proc/cmdline) - Check rootdelay= (did the system wait long enough?) - Check root= (did the system wait for the right device?) - Missing modules (cat /proc/modules; ls /dev) ALERT! /dev/ram0 does not exist. Dropping to a shell! modprobe: module i8042 not found in modules.dep [ 43.462768] ehci-pci: EHCI PCI platform driver [ 43.479400] ehci-orion: EHCI orion driver modprobe: module uhci-hcd not found in modules.dep �ѵJ��������B ͡�jRT動Ɂ:B����2�Ɂ �b��сz��ե�ѵJ��������͹jRԤ�IV��iK����� ����́����R������ɽ����ɹ���z��5RBJ���Ʌ��ͥڴer�[ 75.318939] random: nonblocking pool is initialized ShellCommand command timed out.: Sending # in case of corruption. Connection timeout 00:02:43, retry in 00:01:22 pattern: ['-+\\[ cut here \\]-+\\s+(.*\\s+-+\\[ end trace (\\w*) \\]-+)', '(Unhandled fault.*)\\r\\n', 'Kernel panic - (.*) end Kernel panic', 'Stack:\\s+(.*\\s+-+\\[ end trace (\\w*) \\]-+)', '\\(initramfs\\)', 'Login timed out', 'Login incorrect'] # # # Matched prompt #4: \(initramfs\) case: kernel-messages
case_id: 10200
definition: lava
duration: 81.91
extra: ...
level: 2.4.4
namespace: common
result: pass
end: 2.4.4 auto-login-action (duration 00:01:29) [common] case: auto-login-action
case_id: 10201
definition: lava
duration: 88.71
extra: ...
level: 2.4.4
namespace: common
result: pass
start: 2.4.5 expect-shell-connection (timeout 00:01:21) [common] Forcing a shell prompt, looking for ['\\(initramfs\\)'] (initramfs) [6n expect-shell-connection: Wait for prompt ['\\(initramfs\\)'] (timeout 00:04:00) Waiting using forced prompt support. 119.97401428222656s timeout end: 2.4.5 expect-shell-connection (duration 00:00:00) [common] start: 2.4.6 export-device-env (timeout 00:01:21) [common] end: 2.4.6 export-device-env (duration 00:00:00) [common] end: 2.4 uboot-retry (duration 00:02:38) [common] end: 2 uboot-action (duration 00:02:39) [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 # (initramfs) [6n# lava-test-shell: Wait for prompt ['\\(initramfs\\)'] (timeout 00:05:00) # Using /lava-467 export SHELL=/bin/sh (initramfs) [6nexport SHELL=/bin/sh export SHELL=/bin/sh /lava-467/bin/lava-test-runner /lava-467/0 (initramfs) [6n/lava-467/bin/lava-test-runner /lava-467/0 Test shell timeout: 10s (minimum of the action and connection timeout) /lava-467/bin/lava-test-runner /lava-467/0 + export TESTRUN_ID=0_smoke-tests + cd /lava-467/0/tests/0_smoke-tests + cat uuid + UUID=467_1.5.2.4.1 + set +x <LAVA_SIGNAL_STARTRUN 0_smoke-tests 467_1.5.2.4.1> + lava-test-case linux-posix-pwd --shell pwd <LAVA_SIGNAL_STARTTC linux-posix-pwd> /lava-467/0/tests/0_smoke-tests <LAVA_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: <STARTRUN> 0_smoke-tests 467_1.5.2.4.1 Starting test lava.0_smoke-tests (467_1.5.2.4.1) Skipping test definition patterns. Received signal: <STARTTC> 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: 10202
definition: 0_smoke-tests
result: pass
<LAVA_SIGNAL_STARTTC linux-posix-uname> Linux (none) 3.16.0-4-armmp #1 SMP Debian 3.16.39-1 (2016-12-30) armv7l GNU/Linux <LAVA_SIGNAL_ENDTC linux-posix-uname> <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=linux-posix-uname RESULT=pass> + lava-test-case linux-posix-vmstat --shell vmstat <LAVA_SIGNAL_STARTTC linux-posix-vmstat> /lava-467/0/../bin/lava-test-case: eval: line 1: vmstat: not found <LAVA_SIGNAL_ENDTC linux-posix-vmstat> <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=linux-posix-vmstat RESULT=fail> + lava-test-case linux-posix-ifconfig --shell ifconfig -a <LAVA_SIGNAL_STARTTC linux-posix-ifconfig> eth0 Link encap:Ethernet HWaddr A2:EA:07:89:40:C3 BROADCAST MULTICAST MTU:1500 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:1000 RX bytes:0 (0.0 B) TX bytes:0 (0.0 B) lo Link encap:Local Loopback LOOPBACK 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 Received signal: <STARTTC> linux-posix-uname Received signal: <ENDTC> linux-posix-uname Received signal: <TESTCASE> TEST_CASE_ID=linux-posix-uname RESULT=pass case: linux-posix-uname
case_id: 10203
definition: 0_smoke-tests
result: pass
Received signal: <STARTTC> linux-posix-vmstat Received signal: <ENDTC> linux-posix-vmstat Received signal: <TESTCASE> TEST_CASE_ID=linux-posix-vmstat RESULT=fail case: linux-posix-vmstat
case_id: 10204
definition: 0_smoke-tests
result: fail
Received signal: <STARTTC> linux-posix-ifconfig collisions:0 txqueuelen:0 RX bytes:0 (0.0 B) TX bytes:0 (0.0 B) <LAVA_SIGNAL_ENDTC linux-posix-ifconfig> <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=linux-posix-ifconfig RESULT=pass> + lava-test-case linux-posix-lscpu --shell lscpu <LAVA_SIGNAL_STARTTC linux-posix-lscpu> /lava-467/0/../bin/lava-test-case: eval: line 1: lscpu: not found <LAVA_SIGNAL_ENDTC linux-posix-lscpu> <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=linux-posix-lscpu RESULT=fail> + lava-test-case linux-posix-lsb_release --shell lsb_release -a <LAVA_SIGNAL_STARTTC linux-posix-lsb_release> /lava-467/0/../bin/lava-test-case: eval: line 1: lsb_release: 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 467_1.5.2.4.1> <LAVA_TEST_RUNNER EXIT> Received signal: <ENDTC> linux-posix-ifconfig Received signal: <TESTCASE> TEST_CASE_ID=linux-posix-ifconfig RESULT=pass case: linux-posix-ifconfig
case_id: 10205
definition: 0_smoke-tests
result: pass
Received signal: <STARTTC> linux-posix-lscpu Received signal: <ENDTC> linux-posix-lscpu Received signal: <TESTCASE> TEST_CASE_ID=linux-posix-lscpu RESULT=fail case: linux-posix-lscpu
case_id: 10206
definition: 0_smoke-tests
result: fail
Received signal: <STARTTC> linux-posix-lsb_release 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: 10207
definition: 0_smoke-tests
result: fail
Received signal: <ENDRUN> 0_smoke-tests 467_1.5.2.4.1 Ending use of test pattern. Ending test lava.0_smoke-tests (467_1.5.2.4.1), duration 0.25 case: 0_smoke-tests
case_id: 10208
commit_id: 0f8952f18e992371f8686c59b36f44e9c6a94a1a
definition: lava
duration: 0.25
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: 467_1.5.2.4.1
ok: lava_test_shell seems to have completed linux-posix-ifconfig: pass linux-posix-lsb_release: fail linux-posix-lscpu: fail linux-posix-pwd: pass linux-posix-uname: pass linux-posix-vmstat: fail 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' '23' >> pdu15 port 23 now powered off Returned 0 in 3 seconds end: 4.1 power-off (duration 00:00:03) [common] case: power-off
case_id: 10209
definition: lava
duration: 3.45
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 1s Finalising connection for namespace 'common' Disconnecting ShellSession Disconnecting from telnet: Finalise ] quit (initramfs) [6nquit 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-467/tftp-deploy-dk70_ss9/ramdisk Cleaning up download directory: /var/lib/lava/dispatcher/tmp/lavafed-467/tftp-deploy-dk70_ss9/kernel Cleaning up download directory: /var/lib/lava/dispatcher/tmp/lavafed-467/tftp-deploy-dk70_ss9/dtb Cleaning up download directory: /var/lib/lava/dispatcher/tmp/lavafed-467/tftp-deploy-dk70_ss9/modules Override tmp directory removed at /var/lib/lava/dispatcher/tmp/lavafed-467 Root tmp directory removed at /var/lib/lava/dispatcher/tmp/lavafed-467 Job finished correctly case: job
case_id: 10211
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 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.

device type
hostname
timeouts
  • actions: {'minutes': 20}{'seconds': 45}{'seconds': 10}{'minutes': 10}{'minutes': 2}{'minutes': 3}{'minutes': 2}{'minutes': 3}{'minutes': 3}{'seconds': 30}{'minutes': 3}{'minutes': 3}{'minutes': 2}{'minutes': 3}{'minutes': 10}{'minutes': 5}{'minutes': 2}{'seconds': 30}{'minutes': 5} flash-uboot-umsumount-retrypower-offdd-imageboot-image-retrybootloader-retryapply-overlay-imageuboot-retrylava-test-shellu-boot-interruptbootloader-actionuboot-actionboot-qemu-imagebootloader-commandsnfs-deploydownload-retryauto-login-actionbootloader-interrupthttp-download
  • connections: {'minutes': 10}{'seconds': 30}{'seconds': 10}{'seconds': 30}{'seconds': 30}{'minutes': 2}{'seconds': 30} dd-imageuboot-retrylava-test-shellu-boot-interruptbootloader-commandsauto-login-actionbootloader-interrupt
device action methods
deploy:
  • lxc:
  • nbd:
  • image:
  • ssh:
  • usb:
  • tftp:
  • overlay:
boot:
  • dfu:
    • enter-commands: ['dfu']
    • command: dfu-util
  • minimal:
  • u-boot:
    • bootloader_prompt: U-Boot
    • mkimage_arch: arm
    • interrupt_char:
    • interrupt_prompt: Hit any key to stop autoboot
    • needs_interrupt: True
  • ssh:
target
...
timeouts
  • job: {'seconds': 600}

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

  1. namespace
    common
    prompts
    ['\\(initramfs\\)']
    method
    u-boot
    commands
    ramdisk
    Timeout
    • 4 minutes

  1. namespace
    common
    stage
    0
    Definitions
    • from git
    • repository http://git.linaro.org/lava-team/lava-functional-tests.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...