Help
Description
[lavafed 2019.06.0097.gd4707dda2] cubietruck - ramdisk health-check
Device-type
cubietruck
Submitter
Rémi Duraffort
Created
2 weeks ago
Priority
Medium
Visibility
Publicly visible
Required Tags
staging.validation.linaro.org
#778
Complete
Device
staging-cubietruck01@staging.validation.linaro.org
Started
2 weeks ago
Duration
1 minute
Results
  
lava-dispatcher, installed at version: 2019.06.0097.gd4707dda2+stretch start: 0 validate Start time: 2019-07-06 09:10:08.909577+00:00 (UTC) Validating that https://files.lavasoftware.org/components/lava/standard/debian/stretch/armhf/3/initrd.img-4.9.0-4-armmp exists Validating that https://files.lavasoftware.org/components/lava/standard/debian/stretch/armhf/3/vmlinuz-4.9.0-4-armmp exists Validating that https://files.lavasoftware.org/components/lava/standard/debian/stretch/armhf/3/dtbs/sun7i-a20-cubietruck.dtb exists Validating that https://files.lavasoftware.org/components/lava/standard/debian/stretch/armhf/3/modules.tar.gz exists validate duration: 0.17 case: validate
case_id: 16253
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 https://files.lavasoftware.org/components/lava/standard/debian/stretch/armhf/3/initrd.img-4.9.0-4-armmp saving as /var/lib/lava/dispatcher/tmp/lavafed-778/tftp-deploy-36guy20b/ramdisk/initrd.img-4.9.0-4-armmp total size: 16078096 (15MB) No compression specified progress 0% (0MB) progress 5% (0MB) progress 10% (1MB) progress 15% (2MB) progress 20% (3MB) progress 25% (3MB) progress 30% (4MB) progress 35% (5MB) progress 40% (6MB) progress 45% (6MB) progress 50% (7MB) progress 55% (8MB) progress 60% (9MB) progress 65% (9MB) progress 70% (10MB) progress 75% (11MB) progress 80% (12MB) progress 85% (13MB) progress 90% (13MB) progress 95% (14MB) progress 100% (15MB) 15MB downloaded in 0.27s (56.86MB/s) end: 1.1.1 http-download (duration 00:00:00) [common] case: http-download
case_id: 16254
definition: lava
duration: 0.27
extra: ...
level: 1.1.1
namespace: common
result: pass
end: 1.1 download-retry (duration 00:00:00) [common] start: 1.2 download-retry (timeout 00:04:00) [common] start: 1.2.1 http-download (timeout 00:04:00) [common] downloading https://files.lavasoftware.org/components/lava/standard/debian/stretch/armhf/3/vmlinuz-4.9.0-4-armmp saving as /var/lib/lava/dispatcher/tmp/lavafed-778/tftp-deploy-36guy20b/kernel/vmlinuz-4.9.0-4-armmp total size: 3707576 (3MB) No compression specified progress 0% (0MB) progress 5% (0MB) progress 10% (0MB) progress 15% (0MB) progress 20% (0MB) progress 25% (0MB) progress 30% (1MB) progress 35% (1MB) progress 40% (1MB) progress 45% (1MB) progress 50% (1MB) progress 55% (1MB) progress 60% (2MB) progress 65% (2MB) progress 70% (2MB) progress 75% (2MB) progress 80% (2MB) progress 85% (3MB) progress 90% (3MB) progress 95% (3MB) progress 100% (3MB) 3MB downloaded in 0.10s (36.78MB/s) end: 1.2.1 http-download (duration 00:00:00) [common] case: http-download
case_id: 16254
definition: lava
duration: 0.10
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:04:00) [common] start: 1.3.1 http-download (timeout 00:04:00) [common] downloading https://files.lavasoftware.org/components/lava/standard/debian/stretch/armhf/3/dtbs/sun7i-a20-cubietruck.dtb saving as /var/lib/lava/dispatcher/tmp/lavafed-778/tftp-deploy-36guy20b/dtb/sun7i-a20-cubietruck.dtb total size: 35938 (0MB) No compression specified progress 91% (0MB) progress 100% (0MB) 0MB downloaded in 0.04s (0.94MB/s) end: 1.3.1 http-download (duration 00:00:00) [common] case: http-download
case_id: 16254
definition: lava
duration: 0.04
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:04:00) [common] start: 1.4.1 http-download (timeout 00:04:00) [common] downloading https://files.lavasoftware.org/components/lava/standard/debian/stretch/armhf/3/modules.tar.gz saving as /var/lib/lava/dispatcher/tmp/lavafed-778/tftp-deploy-36guy20b/modules/modules.tar total size: 40209031 (38MB) Using gunzip to decompress gz progress 0% (0MB) progress 5% (1MB) progress 10% (3MB) progress 15% (5MB) progress 20% (7MB) progress 25% (9MB) progress 30% (11MB) progress 35% (13MB) progress 40% (15MB) progress 45% (17MB) progress 50% (19MB) progress 55% (21MB) progress 60% (23MB) progress 65% (24MB) progress 70% (26MB) progress 75% (28MB) progress 80% (30MB) progress 85% (32MB) progress 90% (34MB) progress 95% (36MB) progress 100% (38MB) 38MB downloaded in 2.01s (19.10MB/s) end: 1.4.1 http-download (duration 00:00:02) [common] case: http-download
case_id: 16254
definition: lava
duration: 2.01
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:03:58) [common] start: 1.5.1 extract-nfsrootfs (timeout 00:03:58) [common] end: 1.5.1 extract-nfsrootfs (duration 00:00:00) [common] start: 1.5.2 lava-overlay (timeout 00:03:58) [common] [common] Preparing overlay tarball in /var/lib/lava/dispatcher/tmp/lavafed-778/lava-overlay-oqkfisai makedir: /var/lib/lava/dispatcher/tmp/lavafed-778/lava-overlay-oqkfisai/lava-778/bin makedir: /var/lib/lava/dispatcher/tmp/lavafed-778/lava-overlay-oqkfisai/lava-778/tests makedir: /var/lib/lava/dispatcher/tmp/lavafed-778/lava-overlay-oqkfisai/lava-778/results Creating /var/lib/lava/dispatcher/tmp/lavafed-778/lava-overlay-oqkfisai/lava-778/bin/lava-add-keys Creating /var/lib/lava/dispatcher/tmp/lavafed-778/lava-overlay-oqkfisai/lava-778/bin/lava-add-sources Creating /var/lib/lava/dispatcher/tmp/lavafed-778/lava-overlay-oqkfisai/lava-778/bin/lava-background-process-start Creating /var/lib/lava/dispatcher/tmp/lavafed-778/lava-overlay-oqkfisai/lava-778/bin/lava-background-process-stop Creating /var/lib/lava/dispatcher/tmp/lavafed-778/lava-overlay-oqkfisai/lava-778/bin/lava-common-functions Creating /var/lib/lava/dispatcher/tmp/lavafed-778/lava-overlay-oqkfisai/lava-778/bin/lava-echo-ipv4 Creating /var/lib/lava/dispatcher/tmp/lavafed-778/lava-overlay-oqkfisai/lava-778/bin/lava-install-packages Creating /var/lib/lava/dispatcher/tmp/lavafed-778/lava-overlay-oqkfisai/lava-778/bin/lava-installed-packages Creating /var/lib/lava/dispatcher/tmp/lavafed-778/lava-overlay-oqkfisai/lava-778/bin/lava-os-build Creating /var/lib/lava/dispatcher/tmp/lavafed-778/lava-overlay-oqkfisai/lava-778/bin/lava-probe-channel Creating /var/lib/lava/dispatcher/tmp/lavafed-778/lava-overlay-oqkfisai/lava-778/bin/lava-probe-ip Creating /var/lib/lava/dispatcher/tmp/lavafed-778/lava-overlay-oqkfisai/lava-778/bin/lava-target-ip Creating /var/lib/lava/dispatcher/tmp/lavafed-778/lava-overlay-oqkfisai/lava-778/bin/lava-target-mac Creating /var/lib/lava/dispatcher/tmp/lavafed-778/lava-overlay-oqkfisai/lava-778/bin/lava-target-storage Creating /var/lib/lava/dispatcher/tmp/lavafed-778/lava-overlay-oqkfisai/lava-778/bin/lava-test-case Creating /var/lib/lava/dispatcher/tmp/lavafed-778/lava-overlay-oqkfisai/lava-778/bin/lava-test-event Creating /var/lib/lava/dispatcher/tmp/lavafed-778/lava-overlay-oqkfisai/lava-778/bin/lava-test-feedback Creating /var/lib/lava/dispatcher/tmp/lavafed-778/lava-overlay-oqkfisai/lava-778/bin/lava-test-raise Creating /var/lib/lava/dispatcher/tmp/lavafed-778/lava-overlay-oqkfisai/lava-778/bin/lava-test-reference Creating /var/lib/lava/dispatcher/tmp/lavafed-778/lava-overlay-oqkfisai/lava-778/bin/lava-test-runner Creating /var/lib/lava/dispatcher/tmp/lavafed-778/lava-overlay-oqkfisai/lava-778/bin/lava-test-set Creating /var/lib/lava/dispatcher/tmp/lavafed-778/lava-overlay-oqkfisai/lava-778/bin/lava-test-shell Creating /var/lib/lava/dispatcher/tmp/lavafed-778/lava-overlay-oqkfisai/lava-778/environment start: 1.5.2.1 ssh-authorize (timeout 00:03:58) [common] end: 1.5.2.1 ssh-authorize (duration 00:00:00) [common] start: 1.5.2.2 lava-vland-overlay (timeout 00:03:58) [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:03:58) [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:03:58) [common] Loading test definitions start: 1.5.2.4.1 git-repo-action (timeout 00:03:58) [common] Using /lava-778 at stage 0 Fetching tests from https://git.lavasoftware.org/lava/functional-tests.git Running '/usr/bin/git clone https://git.lavasoftware.org/lava/functional-tests.git /var/lib/lava/dispatcher/tmp/lavafed-778/lava-overlay-oqkfisai/lava-778/0/tests/0_smoke-tests --depth=1' Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/lavafed-778/lava-overlay-oqkfisai/lava-778/0/tests/0_smoke-tests/posix/smoke-tests-basic.yaml uuid=778_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: 16259
definition: lava
duration: 0.42
extra: ...
level: 1.5.2.4.1
namespace: common
result: pass
start: 1.5.2.4.2 test-overlay (timeout 00:03:57) [common] end: 1.5.2.4.2 test-overlay (duration 00:00:00) [common] case: test-overlay
case_id: 16260
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:03:57) [common] end: 1.5.2.4.3 test-install-overlay (duration 00:00:00) [common] case: test-install-overlay
case_id: 16261
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:03:57) [common] runner path: /var/lib/lava/dispatcher/tmp/lavafed-778/lava-overlay-oqkfisai/lava-778/0/tests/0_smoke-tests test_uuid 778_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: 16262
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-778/lava-overlay-oqkfisai/lava-778/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:03:57) [common] end: 1.5.2.5 compress-overlay (duration 00:00:00) [common] start: 1.5.2.6 persistent-nfs-overlay (timeout 00:03:57) [common] end: 1.5.2.6 persistent-nfs-overlay (duration 00:00:00) [common] end: 1.5.2 lava-overlay (duration 00:00:01) [common] start: 1.5.3 extract-overlay-ramdisk (timeout 00:03:57) [common] nice cpio -iud -F /var/lib/lava/dispatcher/tmp/lavafed-778/extract-overlay-ramdisk-pe7uma1j/ramdisk.cpio output: 80359 blocks output: end: 1.5.3 extract-overlay-ramdisk (duration 00:00:01) [common] start: 1.5.4 extract-modules (timeout 00:03:56) [common] extracting modules file /var/lib/lava/dispatcher/tmp/lavafed-778/tftp-deploy-36guy20b/modules/modules.tar to /var/lib/lava/dispatcher/tmp/lavafed-778/extract-overlay-ramdisk-pe7uma1j/ramdisk end: 1.5.4 extract-modules (duration 00:00:01) [common] start: 1.5.5 apply-overlay-tftp (timeout 00:03:54) [common] [common] Applying overlay /var/lib/lava/dispatcher/tmp/lavafed-778/compress-overlay-qg_0goqf/overlay-1.5.2.5.tar.gz to ramdisk [common] Applying overlay /var/lib/lava/dispatcher/tmp/lavafed-778/compress-overlay-qg_0goqf/overlay-1.5.2.5.tar.gz to directory /var/lib/lava/dispatcher/tmp/lavafed-778/extract-overlay-ramdisk-pe7uma1j/ramdisk end: 1.5.5 apply-overlay-tftp (duration 00:00:00) [common] start: 1.5.6 prepare-kernel (timeout 00:03:54) [common] start: 1.5.6.1 uboot-prepare-kernel (timeout 00:03: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:03:54) [common] end: 1.5.7 configure-preseed-file (duration 00:00:00) [common] start: 1.5.8 compress-ramdisk (timeout 00:03:54) [common] Building ramdisk /var/lib/lava/dispatcher/tmp/lavafed-778/extract-overlay-ramdisk-pe7uma1j/ramdisk.cpio containing /var/lib/lava/dispatcher/tmp/lavafed-778/extract-overlay-ramdisk-pe7uma1j/ramdisk find . | cpio --create --format='newc' > /var/lib/lava/dispatcher/tmp/lavafed-778/extract-overlay-ramdisk-pe7uma1j/ramdisk.cpio 237991 blocks Adding RAMdisk u-boot header. nice mkimage -A arm -T ramdisk -C none -d /var/lib/lava/dispatcher/tmp/lavafed-778/extract-overlay-ramdisk-pe7uma1j/ramdisk.cpio.gz /var/lib/lava/dispatcher/tmp/lavafed-778/extract-overlay-ramdisk-pe7uma1j/ramdisk.cpio.gz.uboot output: Image Name: output: Created: Sat Jul 6 09:10:29 2019 output: Image Type: ARM Linux RAMDisk Image (uncompressed) output: Data Size: 42951824 Bytes = 41945.14 kB = 40.96 MB output: Load Address: 00000000 output: Entry Point: 00000000 output: rename /var/lib/lava/dispatcher/tmp/lavafed-778/extract-overlay-ramdisk-pe7uma1j/ramdisk.cpio.gz.uboot to /var/lib/lava/dispatcher/tmp/lavafed-778/tftp-deploy-36guy20b/ramdisk/ramdisk.cpio.gz.uboot end: 1.5.8 compress-ramdisk (duration 00:00:15) [common] end: 1.5 prepare-tftp-overlay (duration 00:00:19) [common] start: 1.6 lxc-create-udev-rule-action (timeout 00:03:39) [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:03:39) [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:21) [common] start: 2 uboot-action (timeout 00:02:00) [common] start: 2.1 uboot-from-media (timeout 00:02:00) [common] end: 2.1 uboot-from-media (duration 00:00:00) [common] start: 2.2 bootloader-overlay (timeout 00:02:00) [common] {'bootz': {'kernel': '0x42000000', 'ramdisk': '0x43300000', 'dtb': '0x43000000'}, 'zimage': {'kernel': '0x42000000', 'ramdisk': '0x43300000', 'dtb': '0x43000000'}, 'pass': None} Parsed boot commands: setenv autoload no; setenv initrd_high 0xffffffff; setenv fdt_high 0xffffffff; dhcp; setenv serverip 10.3.1.1; tftp 0x42000000 lavafed-778/tftp-deploy-36guy20b/kernel/vmlinuz-4.9.0-4-armmp; tftp 0x43300000 lavafed-778/tftp-deploy-36guy20b/ramdisk/ramdisk.cpio.gz.uboot; setenv initrd_size ${filesize}; tftp 0x43000000 lavafed-778/tftp-deploy-36guy20b/dtb/sun7i-a20-cubietruck.dtb; setenv bootargs 'console=ttyS0,115200n8 root=/dev/ram0 ip=dhcp'; bootz 0x42000000 0x43300000 0x43000000 end: 2.2 bootloader-overlay (duration 00:00:00) [common] case: bootloader-overlay
case_id: 16284
definition: lava
duration: 0.00
extra: ...
level: 2.2
namespace: common
result: pass
start: 2.3 connect-device (timeout 00:02:00) [common] [common] connect-device Connecting to device using 'telnet staging03 7118' end: 2.3 connect-device (duration 00:00:01) [common] start: 2.4 uboot-retry (timeout 00:01:59) [common] start: 2.4.1 reset-device (timeout 00:01:59) [common] start: 2.4.1.1 pdu-reboot (timeout 00:01:59) [common] Calling: 'nice' '/usr/local/lab-scripts/snmp_pdu_control' '--hostname' 'pdu18' '--command' 'reboot' '--port' '14' '--delay' '30' >> pdu18 port 14 now powered off >> pdu18 port 14 now powered on >> pdu18 port 14 now power cycled Returned 0 in 35 seconds end: 2.4.1.1 pdu-reboot (duration 00:00:35) [common] case: pdu-reboot
case_id: 16298
definition: lava
duration: 35.43
extra: ...
level: 2.4.1.1
namespace: common
result: pass
end: 2.4.1 reset-device (duration 00:00:35) [common] start: 2.4.2 bootloader-interrupt (timeout 00:01:24) [common] bootloader-interrupt: Wait for prompt Hit any key to stop autoboot (timeout 00:02:00) Trying 10.3.3.1... Connected to staging03.lavalab. Escape character is '^]'. Cubietruck 7118 [115200 N81] U-Boot SPL 2014.10+dfsg1-3 (Feb 21 2015 - 21:59:32) DRAM: 2048 MiB CPU: 960000000Hz, AXI/AHB/APB: 3/2/2 U-Boot 2014.10+dfsg1-3 (Feb 21 2015 - 21:59:32) Allwinner Technology CPU: Allwinner A20 (SUN7I) I2C: ready DRAM: 2 GiB MMC: SUNXI SD/MMC: 0 In: serial Out: serial Err: serial SCSI: SUNXI SCSI INIT SATA link 0 timeout. AHCI 0001.0100 32 slots 1 ports 3 Gbps 0x1 impl SATA mode flags: ncq stag pm led clo only pmp pio slum part ccc apst Net: dwmac.1c50000 Hit any key to stop autoboot: 2 end: 2.4.2 bootloader-interrupt (duration 00:00:00) [common] start: 2.4.3 bootloader-commands (timeout 00:01:23) [common] bootloader-commands: Wait for prompt ['sun7i#'] (timeout 00:01:23)  0 setenv autoload no sun7i# setenv autoload no bootloader-commands: Wait for prompt ['sun7i#', 'Resetting CPU', 'Must RESET board to recover', 'TIMEOUT', 'Retry count exceeded', 'Retry time exceeded; starting again', 'ERROR: The remote end did not respond in time.'] (timeout 00:01:23) setenv autoload no setenv initrd_high 0xffffffff sun7i# setenv initrd_high 0xffffffff bootloader-commands: Wait for prompt ['sun7i#', 'Resetting CPU', 'Must RESET board to recover', 'TIMEOUT', 'Retry count exceeded', 'Retry time exceeded; starting again', 'ERROR: The remote end did not respond in time.'] (timeout 00:01:23) setenv initrd_high 0xffffffff setenv fdt_high 0xffffffff sun7i# setenv fdt_high 0xffffffff bootloader-commands: Wait for prompt ['sun7i#', 'Resetting CPU', 'Must RESET board to recover', 'TIMEOUT', 'Retry count exceeded', 'Retry time exceeded; starting again', 'ERROR: The remote end did not respond in time.'] (timeout 00:01:23) setenv fdt_high 0xffffffff dhcp sun7i# dhcp bootloader-commands: Wait for prompt ['sun7i#', 'Resetting CPU', 'Must RESET board to recover', 'TIMEOUT', 'Retry count exceeded', 'Retry time exceeded; starting again', 'ERROR: The remote end did not respond in time.'] (timeout 00:01:23) dhcp dwmac.1c50000 Waiting for PHY auto negotiation to complete...... done Speed: 1000, full duplex BOOTP broadcast 1 BOOTP broadcast 2 BOOTP broadcast 3 DHCP client bound to address 10.15.1.209 (1255 ms) setenv serverip 10.3.1.1 sun7i# setenv serverip 10.3.1.1 bootloader-commands: Wait for prompt ['sun7i#', 'Resetting CPU', 'Must RESET board to recover', 'TIMEOUT', 'Retry count exceeded', 'Retry time exceeded; starting again', 'ERROR: The remote end did not respond in time.'] (timeout 00:01:19) setenv serverip 10.3.1.1 tftp 0x42000000 lavafed-778/tftp-deploy-36guy20b/kernel/vmlinuz-4.9.0-4-armmp sun7i# tftp 0x42000000 lavafed-778/tftp-deploy-36guy20b/kernel/vmlinuz-4.9.0-4-armmp bootloader-commands: Wait for prompt ['sun7i#', 'Resetting CPU', 'Must RESET board to recover', 'TIMEOUT', 'Retry count exceeded', 'Retry time exceeded; starting again', 'ERROR: The remote end did not respond in time.'] (timeout 00:01:18) tftp 0x42000000 lavafed-778/tftp-deploy-36guy20b/kernel/vmlinuz-4.9.0-4-armmp Speed: 1000, full duplex Using dwmac.1c50000 device TFTP from server 10.3.1.1; our IP address is 10.15.1.209 Filename 'lavafed-778/tftp-deploy-36guy20b/kernel/vmlinuz-4.9.0-4-armmp'. Load address: 0x42000000 Loading: *################################################################# ################################################################# ################################################################# ########################################################## 10.6 MiB/s done Bytes transferred = 3707576 (3892b8 hex) tftp 0x43300000 lavafed-778/tftp-deploy-36guy20b/ramdisk/ramdisk.cpio.gz.uboot sun7i# tftp 0x43300000 lavafed-778/tftp-deploy-36guy20b/ramdisk/ramdisk.cpio.gz.uboot bootloader-commands: Wait for prompt ['sun7i#', 'Resetting CPU', 'Must RESET board to recover', 'TIMEOUT', 'Retry count exceeded', 'Retry time exceeded; starting again', 'ERROR: The remote end did not respond in time.'] (timeout 00:01:18) tftp 0x43300000 lavafed-778/tftp-deploy-36guy20b/ramdisk/ramdisk.cpio.gz.uboot Speed: 1000, full duplex Using dwmac.1c50000 device TFTP from server 10.3.1.1; our IP address is 10.15.1.209 Filename 'lavafed-778/tftp-deploy-36guy20b/ramdisk/ramdisk.cpio.gz.uboot'. Load address: 0x43300000 Loading: *################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# # 10.8 MiB/s done Bytes transferred = 42951888 (28f64d0 hex) setenv initrd_size ${filesize} sun7i# setenv initrd_size ${filesize} bootloader-commands: Wait for prompt ['sun7i#', 'Resetting CPU', 'Must RESET board to recover', 'TIMEOUT', 'Retry count exceeded', 'Retry time exceeded; starting again', 'ERROR: The remote end did not respond in time.'] (timeout 00:01:14) setenv initrd_size ${filesize} tftp 0x43000000 lavafed-778/tftp-deploy-36guy20b/dtb/sun7i-a20-cubietruck.dtb sun7i# tftp 0x43000000 lavafed-778/tftp-deploy-36guy20b/dtb/sun7i-a20-cubietruck.dtb bootloader-commands: Wait for prompt ['sun7i#', 'Resetting CPU', 'Must RESET board to recover', 'TIMEOUT', 'Retry count exceeded', 'Retry time exceeded; starting again', 'ERROR: The remote end did not respond in time.'] (timeout 00:01:14) tftp 0x43000000 lavafed-778/tftp-deploy-36guy20b/dtb/sun7i-a20-cubietruck.dtb Speed: 1000, full duplex Using dwmac.1c50000 device TFTP from server 10.3.1.1; our IP address is 10.15.1.209 Filename 'lavafed-778/tftp-deploy-36guy20b/dtb/sun7i-a20-cubietruck.dtb'. Load address: 0x43000000 Loading: *### 4.9 MiB/s done Bytes transferred = 35938 (8c62 hex) setenv bootargs 'console=ttyS0,115200n8 root=/dev/ram0 ip=dhcp' sun7i# setenv bootargs 'console=ttyS0,115200n8 root=/dev/ram0 ip=dhcp' bootloader-commands: Wait for prompt ['sun7i#', 'Resetting CPU', 'Must RESET board to recover', 'TIMEOUT', 'Retry count exceeded', 'Retry time exceeded; starting again', 'ERROR: The remote end did not respond in time.'] (timeout 00:01:14) setenv bootargs 'console=ttyS0,115200n8 root=/dev/ram0 ip=dhcp' bootz 0x42000000 0x43300000 0x43000000 sun7i# bootz 0x42000000 0x43300000 0x43000000 bootloader-commands: Wait for prompt Starting kernel (timeout 00:01:14) bootz 0x42000000 0x43300000 0x43000000 Kernel image @ 0x42000000 [ 0x000000 - 0x3892b8 ] ## Loading init Ramdisk from Legacy Image at 43300000 ... Image Name: Image Type: ARM Linux RAMDisk Image (uncompressed) Data Size: 42951824 Bytes = 41 MiB Load Address: 00000000 Entry Point: 00000000 Verifying Checksum ... OK ## Flattened Device Tree blob at 43000000 Booting using the fdt blob at 0x43000000 Using Device Tree in place at 43000000, end 4300bc61 Starting kernel ... end: 2.4.3 bootloader-commands (duration 00:00:10) [common] start: 2.4.4 auto-login-action (timeout 00:01:13) [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:02:00) [ 0.000000] Booting Linux on physical CPU 0x0 [ 0.000000] Linux version 4.9.0-4-armmp (debian-kernel@lists.debian.org) (gcc version 6.3.0 20170516 (Debian 6.3.0-18) ) #1 SMP Debian 4.9.65-3 (2017-12-03) [ 0.000000] CPU: ARMv7 Processor [410fc074] revision 4 (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:01:07) Waiting using forced prompt support. 33.60911405086517s timeout [ 0.000000] CPU: div instructions available: patching division code [ 0.000000] CPU: PIPT / VIPT nonaliasing data cache, VIPT aliasing instruction cache [ 0.000000] OF: fdt:Machine model: Cubietech Cubietruck [ 0.000000] efi: Getting EFI parameters from FDT: [ 0.000000] efi: UEFI not found. [ 0.000000] cma: Reserved 16 MiB at 0xbf000000 [ 0.000000] Memory policy: Data cache writealloc [ 0.000000] psci: probing for conduit method from DT. [ 0.000000] psci: Using PSCI v0.1 Function IDs from DT [ 0.000000] percpu: Embedded 14 pages/cpu @eedc0000 s27596 r8192 d21556 u57344 [ 0.000000] Built 1 zonelists in Zone order, mobility grouping on. Total pages: 522560 [ 0.000000] Kernel command line: console=ttyS0,115200n8 root=/dev/ram0 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: 2006688K/2097152K available (7168K kernel code, 1004K rwdata, 2212K rodata, 1024K init, 335K bss, 74080K reserved, 16384K cma-reserved, 1294336K highmem) [ 0.000000] Virtual kernel memory layout: [ 0.000000] vector : 0xffff0000 - 0xffff1000 ( 4 kB) [ 0.000000] fixmap : 0xffc00000 - 0xfff00000 (3072 kB) [ 0.000000] vmalloc : 0xf0800000 - 0xff800000 ( 240 MB) [ 0.000000] lowmem : 0xc0000000 - 0xf0000000 ( 768 MB) [ 0.000000] pkmap : 0xbfe00000 - 0xc0000000 ( 2 MB) [ 0.000000] modules : 0xbf000000 - 0xbfe00000 ( 14 MB) [ 0.000000] .text : 0xc0008000 - 0xc0800000 (8160 kB) [ 0.000000] .init : 0xc0b00000 - 0xc0c00000 (1024 kB) [ 0.000000] .data : 0xc0c00000 - 0xc0cfb10c (1005 kB) [ 0.000000] .bss : 0xc0cfd000 - 0xc0d50c0c ( 336 kB) [ 0.000000] Hierarchical RCU implementation. [ 0.000000] Build-time adjustment of leaf fanout to 32. [ 0.000000] RCU restricting CPUs from NR_CPUS=8 to nr_cpu_ids=2. [ 0.000000] RCU: Adjusting geometry for rcu_fanout_leaf=32, nr_cpu_ids=2 [ 0.000000] NR_IRQS:16 nr_irqs:16 16 [ 0.000000] arm_arch_timer: Architected cp15 timer(s) running at 24.00MHz (phys). [ 0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x588fe9dc0, max_idle_ns: 440795202592 ns [ 0.000007] sched_clock: 56 bits at 24MHz, resolution 41ns, wraps every 4398046511097ns [ 0.000021] Switching to timer-based delay loop, resolution 41ns [ 0.002679] clocksource: timer: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 79635851949 ns [ 0.003554] clocksource: hstimer: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 12741736309 ns [ 0.004306] Console: colour dummy device 80x30 [ 0.004348] Calibrating delay loop (skipped), value calculated using timer frequency.. 48.00 BogoMIPS (lpj=120000) [ 0.004364] pid_max: default: 32768 minimum: 301 [ 0.004625] Security Framework initialized [ 0.004641] Yama: disabled by default; enable with sysctl kernel.yama.* [ 0.004681] AppArmor: AppArmor disabled by boot time parameter [ 0.004851] Mount-cache hash table entries: 2048 (order: 1, 8192 bytes) [ 0.004866] Mountpoint-cache hash table entries: 2048 (order: 1, 8192 bytes) [ 0.005940] CPU: Testing write buffer coherency: ok [ 0.006005] ftrace: allocating 26738 entries in 79 pages [ 0.064189] /cpus/cpu@0 missing clock-frequency property [ 0.064224] /cpus/cpu@1 missing clock-frequency property [ 0.064238] CPU0: thread -1, cpu 0, socket 0, mpidr 80000000 [ 0.064285] Setting up static identity map for 0x40100000 - 0x40100098 [ 0.069784] EFI services will not be available. [ 0.071320] CPU1: thread -1, cpu 1, socket 0, mpidr 80000001 [ 0.071470] Brought up 2 CPUs [ 0.071492] SMP: Total of 2 processors activated (96.00 BogoMIPS). [ 0.071499] CPU: All CPU(s) started in HYP mode. [ 0.071505] CPU: Virtualization extensions available. [ 0.072680] devtmpfs: initialized [ 0.083995] VFP support v0.3: implementor 41 architecture 2 part 30 variant 7 rev 4 [ 0.084565] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 9556302231375000 ns [ 0.084596] futex hash table entries: 512 (order: 3, 32768 bytes) [ 0.085510] pinctrl core: initialized pinctrl subsystem [ 0.087669] NET: Registered protocol family 16 [ 0.089518] DMA: preallocated 256 KiB pool for atomic coherent allocations [ 0.092075] No ATAGs? [ 0.092121] hw-breakpoint: found 5 (+1 reserved) breakpoint and 4 watchpoint registers. [ 0.092133] hw-breakpoint: maximum watchpoint size is 8 bytes. [ 0.093113] Serial: AMBA PL011 UART driver [ 0.127930] reg-fixed-voltage ahci-5v: could not find pctldev for node /soc@01c00000/pinctrl@01c20800/ahci_pwr_pin@1, deferring probe [ 0.127994] reg-fixed-voltage usb0-vbus: could not find pctldev for node /soc@01c00000/pinctrl@01c20800/usb0_vbus_pin@0, deferring probe [ 0.128030] reg-fixed-voltage usb1-vbus: could not find pctldev for node /soc@01c00000/pinctrl@01c20800/usb1_vbus_pin@0, deferring probe [ 0.128063] reg-fixed-voltage usb2-vbus: could not find pctldev for node /soc@01c00000/pinctrl@01c20800/usb2_vbus_pin@0, deferring probe [ 0.131676] vgaarb: loaded [ 0.132813] media: Linux media interface: v0.10 [ 0.132875] Linux video capture interface: v2.00 [ 0.132976] pps_core: LinuxPPS API ver. 1 registered [ 0.132983] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it> [ 0.133008] PTP clock support registered [ 0.135122] clocksource: Switched to clocksource arch_sys_counter [ 0.181582] VFS: Disk quotas dquot_6.6.0 [ 0.181695] VFS: Dquot-cache hash table entries: 1024 (order 0, 4096 bytes) [ 0.195889] NET: Registered protocol family 2 [ 0.196859] TCP established hash table entries: 8192 (order: 3, 32768 bytes) [ 0.196952] TCP bind hash table entries: 8192 (order: 4, 65536 bytes) [ 0.197074] TCP: Hash tables configured (established 8192 bind 8192) [ 0.197171] UDP hash table entries: 512 (order: 2, 16384 bytes) [ 0.197234] UDP-Lite hash table entries: 512 (order: 2, 16384 bytes) [ 0.197556] NET: Registered protocol family 1 [ 0.198211] Unpacking initramfs... [ 3.738525] Freeing initrd memory: 41948K [ 3.739180] hw perfevents: enabled with armv7_cortex_a7 PMU driver, 5 counters available [ 3.741317] audit: initializing netlink subsys (disabled) [ 3.741441] audit: type=2000 audit(3.705:1): initialized [ 3.742802] workingset: timestamp_bits=14 max_order=19 bucket_order=5 [ 3.742982] zbud: loaded [ 3.746702] bounce: pool size: 64 pages [ 3.746864] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 3.747079] io scheduler noop registered [ 3.747093] io scheduler deadline registered [ 3.747181] io scheduler cfq registered (default) [ 3.753318] sun7i-a20-pinctrl 1c20800.pinctrl: initialized sunXi PIO driver [ 3.768929] Serial: 8250/16550 driver, 4 ports, IRQ sharing disabled [ 3.771526] console [ttyS0] disabled [ 3.791998] 1c28000.serial: ttyS0 at MMIO 0x1c28000 (irq = 46, base_baud = 1500000) is a U6_16550A [ 4.456529] console [ttyS0] enabled [ 4.460997] Serial: AMBA driver [ 4.467599] libphy: Fixed MDIO Bus: probed [ 4.472770] mousedev: PS/2 mouse device common for all mice [ 4.480791] sunxi-rtc 1c20d00.rtc: rtc core: registered rtc-sunxi as rtc0 [ 4.487634] sunxi-rtc 1c20d00.rtc: RTC enabled [ 4.495145] ledtrig-cpu: registered to indicate activity on CPUs [ 4.502110] NET: Registered protocol family 10 [ 4.507811] mip6: Mobile IPv6 [ 4.510878] NET: Registered protocol family 17 [ 4.515360] mpls_gso: MPLS GSO support [ 4.519195] ThumbEE CPU extension supported. [ 4.523495] Registering SWP/SWPB emulation handler [ 4.529353] registered taskstats version 1 [ 4.533608] zswap: loaded using pool lzo/zbud [ 4.538326] ima: No TPM chip found, activating TPM-bypass! [ 4.554029] sunxi-rtc 1c20d00.rtc: setting system clock to 1970-01-01 00:00:16 UTC (16) [ 4.562098] sr_init: No PMIC hook to init smartreflex [ 4.567333] sr_init: platform driver register failed for SR [ 4.573365] vcc3v0: disabling [ 4.576383] vcc3v3: disabling [ 4.579351] vcc5v0: disabling [ 4.582332] ahci-5v: disabling [ 4.585408] usb0-vbus: disabling [ 4.588634] usb1-vbus: disabling [ 4.591973] usb2-vbus: disabling [ 4.596863] Freeing unused kernel memory: 1024K Loading, please wait... starting version 232 [ 4.722758] random: systemd-udevd: uninitialized urandom read (16 bytes read) [ 4.730859] random: systemd-udevd: uninitialized urandom read (16 bytes read) [ 4.731517] random: udevadm: uninitialized urandom read (16 bytes read) [ 4.733237] random: udevadm: uninitialized urandom read (16 bytes read) [ 4.733795] random: udevadm: uninitialized urandom read (16 bytes read) [ 4.734396] random: udevadm: uninitialized urandom read (16 bytes read) [ 4.734899] random: udevadm: uninitialized urandom read (16 bytes read) [ 4.735501] random: udevadm: uninitialized urandom read (16 bytes read) [ 4.736067] random: udevadm: uninitialized urandom read (16 bytes read) [ 4.736566] random: udevadm: uninitialized urandom read (16 bytes read) [ 5.020992] sunxi-mmc 1c0f000.mmc: Got CD GPIO [ 5.034621] usbcore: registered new interface driver usbfs [ 5.060429] usbcore: registered new interface driver hub [ 5.080428] sunxi-mmc 1c0f000.mmc: base:0xf08cd000 irq:27 [ 5.081599] SCSI subsystem initialized [ 5.092827] sun4i-ss 1c15000.crypto-engine: no reset control found [ 5.100827] sun4i-ss 1c15000.crypto-engine: Die ID 0 [ 5.109118] usbcore: registered new device driver usb [ 5.129481] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver [ 5.144219] ehci-platform: EHCI generic platform driver [ 5.144747] ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver [ 5.146094] ohci-platform: OHCI generic platform driver [ 5.171406] mmc0: host does not support reading read-only switch, assuming write-enable [ 5.189673] mmc0: new high speed SDHC card at address aaaa [ 5.214286] mmcblk0: mmc0:aaaa SU08G 7.40 GiB [ 5.225419] mmcblk0: p1 [ 5.235207] sunxi-mmc 1c12000.mmc: allocated mmc-pwrseq [ 5.280247] sunxi-mmc 1c12000.mmc: base:0xf0d3c000 irq:28 [ 5.291790] sunxi-mmc 1c12000.mmc: smc 1 err, cmd 8, RTO !! [ 5.307789] mmc1: queuing unknown CIS tuple 0x80 (2 bytes) [ 5.317951] mmc1: queuing unknown CIS tuple 0x80 (3 bytes) [ 5.325093] mmc1: queuing unknown CIS tuple 0x80 (3 bytes) [ 5.333417] mmc1: queuing unknown CIS tuple 0x80 (7 bytes) [ 5.340288] ahci-sunxi 1c18000.sata: controller can't do PMP, turning off CAP_PMP [ 5.347837] ahci-sunxi 1c18000.sata: forcing PORTS_IMPL to 0x1 [ 5.353813] ahci-sunxi 1c18000.sata: AHCI 0001.0100 32 slots 1 ports 3 Gbps 0x1 impl platform mode [ 5.362854] ahci-sunxi 1c18000.sata: flags: ncq sntf pm led clo only pio slum part ccc [ 5.372602] scsi host0: ahci-sunxi [ 5.376608] ata1: SATA max UDMA/133 mmio [mem 0x01c18000-0x01c18fff] port 0x100 irq 33 [ 5.386971] ohci-platform 1c14400.usb: Generic Platform OHCI controller [ 5.401396] ohci-platform 1c14400.usb: new USB bus registered, assigned bus number 1 [ 5.410083] random: fast init done [ 5.414354] mmc1: new high speed SDIO card at address 0001 [ 5.421048] ohci-platform 1c14400.usb: irq 31, io mem 0x01c14400 [ 5.489501] usb usb1: New USB device found, idVendor=1d6b, idProduct=0001 [ 5.496334] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1 [ 5.503575] usb usb1: Product: Generic Platform OHCI controller [ 5.509512] usb usb1: Manufacturer: Linux 4.9.0-4-armmp ohci_hcd [ 5.515531] usb usb1: SerialNumber: 1c14400.usb [ 5.521218] hub 1-0:1.0: USB hub found [ 5.525093] hub 1-0:1.0: 1 port detected [ 5.531851] sunxi-wdt 1c20c90.watchdog: Watchdog enabled (timeout=16 sec, nowayout=0) [ 5.540957] usb_phy_generic.0.auto supply vcc not found, using dummy regulator [ 5.549718] musb-hdrc musb-hdrc.1.auto: MUSB HDRC host driver [ 5.555635] musb-hdrc musb-hdrc.1.auto: new USB bus registered, assigned bus number 2 [ 5.563829] usb usb2: New USB device found, idVendor=1d6b, idProduct=0002 [ 5.570706] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1 [ 5.577984] usb usb2: Product: MUSB HDRC host driver [ 5.582988] usb usb2: Manufacturer: Linux 4.9.0-4-armmp musb-hcd [ 5.589010] usb usb2: SerialNumber: musb-hdrc.1.auto [ 5.595326] hub 2-0:1.0: USB hub found [ 5.599203] hub 2-0:1.0: 1 port detected [ 5.606029] ehci-platform 1c14000.usb: EHCI Host Controller [ 5.611828] ehci-platform 1c14000.usb: new USB bus registered, assigned bus number 3 [ 5.620274] ehci-platform 1c14000.usb: irq 30, io mem 0x01c14000 [ 5.640156] ehci-platform 1c14000.usb: USB 2.0 started, EHCI 1.00 [ 5.646619] usb usb3: New USB device found, idVendor=1d6b, idProduct=0002 [ 5.653431] usb usb3: New USB device strings: Mfr=3, Product=2, SerialNumber=1 [ 5.660662] usb usb3: Product: EHCI Host Controller [ 5.665554] usb usb3: Manufacturer: Linux 4.9.0-4-armmp ehci_hcd [ 5.671568] usb usb3: SerialNumber: 1c14000.usb [ 5.677174] hub 3-0:1.0: USB hub found [ 5.681083] hub 3-0:1.0: 1 port detected [ 5.688408] Registered IR keymap rc-empty [ 5.695740] input: sunxi-ir as /devices/platform/soc@01c00000/1c21800.ir/rc/rc0/input0 [ 5.703823] rc rc0: sunxi-ir as /devices/platform/soc@01c00000/1c21800.ir/rc/rc0 [ 5.713129] sunxi-ir 1c21800.ir: initialized sunXi IR driver [ 5.722291] sun4i-codec 1c22c00.codec: Codec <-> 1c22c00.codec mapping ok [ 5.739608] ata1: SATA link down (SStatus 0 SControl 300) [ 5.746871] lirc_dev: IR Remote Control driver registered, major 242 [ 5.758222] ohci-platform 1c1c400.usb: Generic Platform OHCI controller [ 5.786045] ohci-platform 1c1c400.usb: new USB bus registered, assigned bus number 4 [ 5.794774] ohci-platform 1c1c400.usb: irq 35, io mem 0x01c1c400 [ 5.799136] rc rc0: lirc_dev: driver ir-lirc-codec (sunxi-ir) registered at minor = 0 [ 5.799146] IR LIRC bridge handler initialized [ 5.874512] usb usb4: New USB device found, idVendor=1d6b, idProduct=0001 [ 5.881412] usb usb4: New USB device strings: Mfr=3, Product=2, SerialNumber=1 [ 5.888692] usb usb4: Product: Generic Platform OHCI controller [ 5.894677] usb usb4: Manufacturer: Linux 4.9.0-4-armmp ohci_hcd [ 5.900757] usb usb4: SerialNumber: 1c1c400.usb [ 5.906438] hub 4-0:1.0: USB hub found [ 5.910375] hub 4-0:1.0: 1 port detected [ 5.919262] axp20x-i2c 0-0034: AXP20x variant AXP209 found [ 5.936884] axp20x-i2c 0-0034: AXP20X driver loaded [ 5.944944] ehci-platform 1c1c000.usb: EHCI Host Controller [ 5.952352] ehci-platform 1c1c000.usb: new USB bus registered, assigned bus number 5 [ 5.962052] ehci-platform 1c1c000.usb: irq 34, io mem 0x01c1c000 [ 5.980304] ehci-platform 1c1c000.usb: USB 2.0 started, EHCI 1.00 [ 5.988203] usb usb5: New USB device found, idVendor=1d6b, idProduct=0002 [ 5.995153] usb usb5: New USB device strings: Mfr=3, Product=2, SerialNumber=1 [ 6.002455] usb usb5: Product: EHCI Host Controller [ 6.007401] usb usb5: Manufacturer: Linux 4.9.0-4-armmp ehci_hcd [ 6.013471] usb usb5: SerialNumber: 1c1c000.usb [ 6.020848] hub 5-0:1.0: USB hub found [ 6.024738] hub 5-0:1.0: 1 port detected [ 6.035436] sun7i-dwmac 1c50000.ethernet: no regulator found [ 6.048630] sun7i-dwmac 1c50000.ethernet: no reset control found [ 6.062714] Ring mode enabled [ 6.065909] DMA HW capability register supported[ 6.070487] Normal descriptors [ 6.159338] libphy: stmmac: probed [ 6.162955] eth%d: PHY ID 001cc915 at 0 IRQ POLL (stmmac-0:00) active [ 6.169465] eth%d: PHY ID 001cc915 at 1 IRQ POLL (stmmac-0:01) [ 6.247672] usbcore: registered new interface driver brcmfmac [ 6.259708] brcmfmac mmc1:0001:1: firmware: failed to load brcm/brcmfmac43362-sdio.bin (-2) [ 6.268289] brcmfmac mmc1:0001:1: Direct firmware load for brcm/brcmfmac43362-sdio.bin failed with error -2 [ 6.281977] input: axp20x-pek as /devices/platform/soc@01c00000/1c2ac00.i2c/i2c-0/0-0034/axp20x-pek/input/input1 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 ... [ 7.320217] brcmfmac: brcmf_sdio_htclk: HT Avail timeout (1000000): clkctl 0x50 Begin: Running /scripts/local-block ... done. [ 8.336099] brcmfmac: brcmf_sdio_htclk: HT Avail timeout (1000000): clkctl 0x50 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. ShellCommand command timed out.: Sending # in case of corruption. Connection timeout 00:01:07, retry in 00:00:34 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'] # # # Begin: Running /scripts/local-block ... done. done. Gave up waiting for root file system device. Common problems: - Boot args (cat /proc/cmdline) - Check rootdelay= (did the system wait long enough?) - Missing modules (cat /proc/modules; ls /dev) ALERT! /dev/ram0 does not exist. Dropping to a shell! BusyBox v1.22.1 (Debian 1:1.22.0-19+b3) built-in shell (ash) Enter 'help' for a list of built-in commands. Matched prompt #4: \(initramfs\) case: kernel-messages
case_id: 16316
definition: lava
duration: 34.05
extra: ...
level: 2.4.4
namespace: common
result: pass
end: 2.4.4 auto-login-action (duration 00:00:40) [common] case: auto-login-action
case_id: 16317
definition: lava
duration: 40.07
extra: ...
level: 2.4.4
namespace: common
result: pass
start: 2.4.5 expect-shell-connection (timeout 00:00:33) [common] Forcing a shell prompt, looking for ['\\(initramfs\\)'] (initramfs) expect-shell-connection: Wait for prompt ['\\(initramfs\\)'] (timeout 00:02:00) Waiting using forced prompt support. 59.974188685417175s timeout # end: 2.4.5 expect-shell-connection (duration 00:00:00) [common] start: 2.4.6 export-device-env (timeout 00:00:33) [common] end: 2.4.6 export-device-env (duration 00:00:00) [common] end: 2.4 uboot-retry (duration 00:01:26) [common] end: 2 uboot-action (duration 00:01:27) [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-778 export SHELL=/bin/sh (initramfs) [6n#export SHELL=/bin/sh . /lava-778/environment (initramfs) [6nexport SHELL=/bin/sh. /lava-778/environment /lava-778/bin/lava-test-runner /lava-778/0 (initramfs) [6n. /lava-778/environment/lava-778/bin/lava-test-runner /lava-778/0 Test shell timeout: 10s (minimum of the action and connection timeout) (initramfs) [6n/lava-778/bin/lava-test-runner /lava-778/0 + export TESTRUN_ID=0_smoke-tests + cd /lava-778/0/tests/0_smoke-tests + cat uuid + UUID=778_1.5.2.4.1 + set +x <LAVA_SIGNAL_STARTRUN 0_smoke-tests 778_1.5.2.4.1> + lava-test-case linux-posix-pwd --shell pwd Received signal: <STARTRUN> 0_smoke-tests 778_1.5.2.4.1 Starting test lava.0_smoke-tests (778_1.5.2.4.1) Skipping test definition patterns. Received signal: <STARTTC> linux-posix-pwd <LAVA_SIGNAL_STARTTC linux-posix-pwd> /lava-778/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 <LAVA_SIGNAL_STARTTC linux-posix-uname> Received signal: <ENDTC> linux-posix-pwd Received signal: <TESTCASE> TEST_CASE_ID=linux-posix-pwd RESULT=pass case: linux-posix-pwd
case_id: 16318
definition: 0_smoke-tests
result: pass
Received signal: <STARTTC> linux-posix-uname Linux (none) 4.9.0-4-armmp #1 SMP Debian 4.9.65-3 (2017-12-03) armv7l GNU/Linux <LAVA_SIGNAL_ENDTC 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 <LAVA_SIGNAL_STARTTC linux-posix-vmstat> /lava-778/0/../bin/lava-test-case: eval: line 1: vmstat: not found <LAVA_SIGNAL_ENDTC linux-posix-vmstat> Received signal: <TESTCASE> TEST_CASE_ID=linux-posix-uname RESULT=pass case: linux-posix-uname
case_id: 16319
definition: 0_smoke-tests
result: pass
Received signal: <STARTTC> linux-posix-vmstat Received signal: <ENDTC> linux-posix-vmstat <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=linux-posix-vmstat RESULT=fail> Received signal: <TESTCASE> TEST_CASE_ID=linux-posix-vmstat RESULT=fail case: linux-posix-vmstat
case_id: 16320
definition: 0_smoke-tests
result: fail
+ lava-test-case linux-posix-ifconfig --shell ifconfig -a <LAVA_SIGNAL_STARTTC linux-posix-ifconfig> Received signal: <STARTTC> linux-posix-ifconfig eth0 Link encap:Ethernet HWaddr 02:8D:07:42:51:FA 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) Interrupt:50 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 collisions:0 txqueuelen:1 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> Received signal: <ENDTC> linux-posix-ifconfig Received signal: <TESTCASE> TEST_CASE_ID=linux-posix-ifconfig RESULT=pass case: linux-posix-ifconfig
case_id: 16321
definition: 0_smoke-tests
result: pass
Received signal: <STARTTC> linux-posix-lscpu /lava-778/0/../bin/lava-test-case: eval: line 1: lscpu: not found <LAVA_SIGNAL_ENDTC linux-posix-lscpu> Received 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-778/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 Received signal: <TESTCASE> TEST_CASE_ID=linux-posix-lscpu RESULT=fail case: linux-posix-lscpu
case_id: 16322
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: 16323
definition: 0_smoke-tests
result: fail
<LAVA_SIGNAL_ENDRUN 0_smoke-tests 778_1.5.2.4.1> <LAVA_TEST_RUNNER EXIT> Received signal: <ENDRUN> 0_smoke-tests 778_1.5.2.4.1 Ending use of test pattern. Ending test lava.0_smoke-tests (778_1.5.2.4.1), duration 0.19 case: 0_smoke-tests
case_id: 16324
commit_id: 5550ec3211c2c7988368a91af43349db6d041f4a
definition: lava
duration: 0.19
namespace: common
path: posix/smoke-tests-basic.yaml
repository: https://git.lavasoftware.org/lava/functional-tests.git
result: pass
revision: unspecified
uuid: 778_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' 'pdu18' '--command' 'off' '--port' '14' >> pdu18 port 14 now powered off Returned 0 in 3 seconds end: 4.1 power-off (duration 00:00:03) [common] case: power-off
case_id: 16325
definition: lava
duration: 3.09
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:04) [common] Cleaning after the job Cleaning up download directory: /var/lib/lava/dispatcher/tmp/lavafed-778/tftp-deploy-36guy20b/ramdisk Cleaning up download directory: /var/lib/lava/dispatcher/tmp/lavafed-778/tftp-deploy-36guy20b/kernel Cleaning up download directory: /var/lib/lava/dispatcher/tmp/lavafed-778/tftp-deploy-36guy20b/dtb Cleaning up download directory: /var/lib/lava/dispatcher/tmp/lavafed-778/tftp-deploy-36guy20b/modules Override tmp directory removed at /var/lib/lava/dispatcher/tmp/lavafed-778 Root tmp directory removed at /var/lib/lava/dispatcher/tmp/lavafed-778 Job finished correctly case: job
case_id: 16326
definition: lava
result: pass

Top of page

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

Job Description YAML

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

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

job.url
https://federation.lavasoftware.org/lava/scheduler/job/{id}
features.1.type
device
features.0.action
deploy
features.2.description
Test definition from git
device.name
staging-cubietruck01
lab.name
staging.validation.linaro.org
job.type
test
job.name
cubietruck - ramdisk health-check
features.0.name
deploy.tftp
features.2.type
device
slave.version
2019.06.0097.gd4707dda2
features.0.description
Deploy to tftp
slave.arch
amd64
features.1.action
boot
features.0.type
device
features.2.action
test
features.1.description
Boot from u-boot with a ramdisk
features.2.name
test.definition.git
device.type
cubietruck
features.1.name
boot.u-boot.ramdisk
device type
hostname
timeouts
  • actions: {'minutes': 20}{'minutes': 3}{'minutes': 2}{'minutes': 3}{'minutes': 3}{'minutes': 3}{'seconds': 30}{'minutes': 10}{'seconds': 10}{'minutes': 5}{'minutes': 3}{'minutes': 10}{'minutes': 3}{'minutes': 2}{'seconds': 30}{'minutes': 2}{'minutes': 2}{'minutes': 5}{'seconds': 45} flash-uboot-umsbootloader-commandsauto-login-actionlava-test-shelluboot-actionbootloader-actionu-boot-interruptnfs-deploypower-offdownload-retrybootloader-retrydd-imageuboot-retryboot-qemu-imagebootloader-interruptboot-image-retryapply-overlay-imagehttp-downloadumount-retry
  • connections: {'seconds': 30}{'seconds': 30}{'minutes': 10}{'minutes': 2}{'seconds': 30}{'seconds': 10}{'seconds': 30} bootloader-interruptu-boot-interruptdd-imageauto-login-actionbootloader-commandslava-test-shelluboot-retry
device action methods
deploy:
  • image:
  • ssh:
  • overlay:
  • tftp:
  • nbd:
  • lxc:
  • usb:
boot:
  • dfu:
    • enter-commands: ['dfu']
    • command: dfu-util
  • ssh:
  • minimal:
  • u-boot:
    • needs_interrupt: True
    • interrupt_char:
    • mkimage_arch: arm
    • interrupt_prompt: Hit any key to stop autoboot
    • bootloader_prompt: sun7i#
target
...
timeouts
  • job: {'seconds': 600}

  1. dtb
    {'sha256sum': 'b727c17dce4a67a865cbcd53447f7297e35ffbd18f0a99c7e9f8a10026237cea', 'url': 'https://files.lavasoftware.org/components/lava/standard/debian/stretch/armhf/3/dtbs/sun7i-a20-cubietruck.dtb'}
    namespace
    common
    modules
    {'sha256sum': '10e6930e9282dd44905cfd3f3a2d5a5058a1d400374afb2619412554e1067d58', 'compression': 'gz', 'url': 'https://files.lavasoftware.org/components/lava/standard/debian/stretch/armhf/3/modules.tar.gz'}
    deployment_data
    {}
    ramdisk
    {'sha256sum': '4cc25f499ae74e72b5d74c9c5e65e143de8c2e3b019f5d1781abbf519479b843', 'compression': 'gz', 'url': 'https://files.lavasoftware.org/components/lava/standard/debian/stretch/armhf/3/initrd.img-4.9.0-4-armmp'}
    Timeout
    • 4 minutes
    to
    tftp
    kernel
    {'sha256sum': 'b6043cc5a07e2cead3f7f098018e7706ea7840eece2a456ba5fcfaddaf98a21e', 'type': 'zimage', 'url': 'https://files.lavasoftware.org/components/lava/standard/debian/stretch/armhf/3/vmlinuz-4.9.0-4-armmp'}

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

  1. Timeout
    • 5 minutes
    Definitions
    • name smoke-tests
    • path posix/smoke-tests-basic.yaml
    • repository https://git.lavasoftware.org/lava/functional-tests.git
    • from git
    namespace
    common
    stage
    0

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...