Help
Description
[lavafed 2019.07.0010.g7c3c02c6b] rzn1d - health-check
Device-type
rzn1d
Submitter
Rémi Duraffort
Created
1 month ago
Priority
Medium
Visibility
Public
Required Tags
validation.linaro.org
#1018
Complete
Device
lces2-01@validation.linaro.org
Started
1 month ago
Duration
1 minute
Results
  
lava-dispatcher, installed at version: 2019.07.0010.g7c3c02c6b+stretch start: 0 validate Start time: 2019-08-13 09:17:14.363830+00:00 (UTC) Validating that http://images.validation.linaro.org/snapshots.linaro.org/openembedded/schneider/linaro-sumo/rzn1d/38/dip/fitImage-1.0-r0-rzn1-snarc.itb exists validate duration: 0.05 case: validate
case_id: 20942
definition: lava
result: pass
start: 1 tftp-deploy (timeout 00:05:00) [common] start: 1.1 download-retry (timeout 00:05:00) [common] start: 1.1.1 http-download (timeout 00:05:00) [common] downloading http://images.validation.linaro.org/snapshots.linaro.org/openembedded/schneider/linaro-sumo/rzn1d/38/dip/fitImage-1.0-r0-rzn1-snarc.itb saving as /var/lib/lava/dispatcher/tmp/lavafed-1018/tftp-deploy-8_xxyays/kernel/fitImage-1.0-r0-rzn1-snarc.itb total size: 22848300 (21MB) No compression specified progress 0% (0MB) progress 5% (1MB) progress 10% (2MB) progress 15% (3MB) progress 20% (4MB) progress 25% (5MB) progress 30% (6MB) progress 35% (7MB) progress 40% (8MB) progress 45% (9MB) progress 50% (10MB) progress 55% (12MB) progress 60% (13MB) progress 65% (14MB) progress 70% (15MB) progress 75% (16MB) progress 80% (17MB) progress 85% (18MB) progress 90% (19MB) progress 95% (20MB) progress 100% (21MB) 21MB downloaded in 0.23s (95.27MB/s) end: 1.1.1 http-download (duration 00:00:00) [common] case: http-download
case_id: 20943
definition: lava
duration: 0.23
extra: ...
level: 1.1.1
namespace: common
result: pass
end: 1.1 download-retry (duration 00:00:00) [common] start: 1.2 prepare-tftp-overlay (timeout 00:05:00) [common] start: 1.2.1 extract-nfsrootfs (timeout 00:05:00) [common] end: 1.2.1 extract-nfsrootfs (duration 00:00:00) [common] start: 1.2.2 lava-overlay (timeout 00:05:00) [common] [common] Preparing overlay tarball in /var/lib/lava/dispatcher/tmp/lavafed-1018/lava-overlay-zas8rlsr makedir: /var/lib/lava/dispatcher/tmp/lavafed-1018/lava-overlay-zas8rlsr/lava-1018/bin makedir: /var/lib/lava/dispatcher/tmp/lavafed-1018/lava-overlay-zas8rlsr/lava-1018/tests makedir: /var/lib/lava/dispatcher/tmp/lavafed-1018/lava-overlay-zas8rlsr/lava-1018/results Creating /var/lib/lava/dispatcher/tmp/lavafed-1018/lava-overlay-zas8rlsr/lava-1018/bin/lava-add-keys Creating /var/lib/lava/dispatcher/tmp/lavafed-1018/lava-overlay-zas8rlsr/lava-1018/bin/lava-add-sources Creating /var/lib/lava/dispatcher/tmp/lavafed-1018/lava-overlay-zas8rlsr/lava-1018/bin/lava-background-process-start Creating /var/lib/lava/dispatcher/tmp/lavafed-1018/lava-overlay-zas8rlsr/lava-1018/bin/lava-background-process-stop Creating /var/lib/lava/dispatcher/tmp/lavafed-1018/lava-overlay-zas8rlsr/lava-1018/bin/lava-common-functions Creating /var/lib/lava/dispatcher/tmp/lavafed-1018/lava-overlay-zas8rlsr/lava-1018/bin/lava-echo-ipv4 Creating /var/lib/lava/dispatcher/tmp/lavafed-1018/lava-overlay-zas8rlsr/lava-1018/bin/lava-install-packages Creating /var/lib/lava/dispatcher/tmp/lavafed-1018/lava-overlay-zas8rlsr/lava-1018/bin/lava-installed-packages Creating /var/lib/lava/dispatcher/tmp/lavafed-1018/lava-overlay-zas8rlsr/lava-1018/bin/lava-os-build Creating /var/lib/lava/dispatcher/tmp/lavafed-1018/lava-overlay-zas8rlsr/lava-1018/bin/lava-probe-channel Creating /var/lib/lava/dispatcher/tmp/lavafed-1018/lava-overlay-zas8rlsr/lava-1018/bin/lava-probe-ip Creating /var/lib/lava/dispatcher/tmp/lavafed-1018/lava-overlay-zas8rlsr/lava-1018/bin/lava-target-ip Creating /var/lib/lava/dispatcher/tmp/lavafed-1018/lava-overlay-zas8rlsr/lava-1018/bin/lava-target-mac Creating /var/lib/lava/dispatcher/tmp/lavafed-1018/lava-overlay-zas8rlsr/lava-1018/bin/lava-target-storage Creating /var/lib/lava/dispatcher/tmp/lavafed-1018/lava-overlay-zas8rlsr/lava-1018/bin/lava-test-case Creating /var/lib/lava/dispatcher/tmp/lavafed-1018/lava-overlay-zas8rlsr/lava-1018/bin/lava-test-event Creating /var/lib/lava/dispatcher/tmp/lavafed-1018/lava-overlay-zas8rlsr/lava-1018/bin/lava-test-feedback Creating /var/lib/lava/dispatcher/tmp/lavafed-1018/lava-overlay-zas8rlsr/lava-1018/bin/lava-test-raise Creating /var/lib/lava/dispatcher/tmp/lavafed-1018/lava-overlay-zas8rlsr/lava-1018/bin/lava-test-reference Creating /var/lib/lava/dispatcher/tmp/lavafed-1018/lava-overlay-zas8rlsr/lava-1018/bin/lava-test-runner Creating /var/lib/lava/dispatcher/tmp/lavafed-1018/lava-overlay-zas8rlsr/lava-1018/bin/lava-test-set Creating /var/lib/lava/dispatcher/tmp/lavafed-1018/lava-overlay-zas8rlsr/lava-1018/bin/lava-test-shell Creating /var/lib/lava/dispatcher/tmp/lavafed-1018/lava-overlay-zas8rlsr/lava-1018/environment start: 1.2.2.1 ssh-authorize (timeout 00:05:00) [common] end: 1.2.2.1 ssh-authorize (duration 00:00:00) [common] start: 1.2.2.2 lava-vland-overlay (timeout 00:05:00) [common] skipped lava-vland-overlay end: 1.2.2.2 lava-vland-overlay (duration 00:00:00) [common] start: 1.2.2.3 lava-multinode-overlay (timeout 00:05:00) [common] skipped lava-multinode-overlay end: 1.2.2.3 lava-multinode-overlay (duration 00:00:00) [common] start: 1.2.2.4 test-definition (timeout 00:05:00) [common] Loading test definitions start: 1.2.2.4.1 git-repo-action (timeout 00:05:00) [common] Using /lava-1018 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-1018/lava-overlay-zas8rlsr/lava-1018/0/tests/0_smoke-tests --depth=1' Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/lavafed-1018/lava-overlay-zas8rlsr/lava-1018/0/tests/0_smoke-tests/lava-test-shell/smoke-tests-basic.yaml uuid=1018_1.2.2.4.1 testdef=None end: 1.2.2.4.1 git-repo-action (duration 00:00:00) [common] case: git-repo-action
case_id: 20944
definition: lava
duration: 0.24
extra: ...
level: 1.2.2.4.1
namespace: common
result: pass
start: 1.2.2.4.2 test-overlay (timeout 00:05:00) [common] end: 1.2.2.4.2 test-overlay (duration 00:00:00) [common] case: test-overlay
case_id: 20945
definition: lava
duration: 0.01
extra: ...
level: 1.2.2.4.2
namespace: common
result: pass
start: 1.2.2.4.3 test-install-overlay (timeout 00:05:00) [common] end: 1.2.2.4.3 test-install-overlay (duration 00:00:00) [common] case: test-install-overlay
case_id: 20946
definition: lava
duration: 0.01
extra: ...
level: 1.2.2.4.3
namespace: common
result: pass
start: 1.2.2.4.4 test-runscript-overlay (timeout 00:04:59) [common] runner path: /var/lib/lava/dispatcher/tmp/lavafed-1018/lava-overlay-zas8rlsr/lava-1018/0/tests/0_smoke-tests test_uuid 1018_1.2.2.4.1 end: 1.2.2.4.4 test-runscript-overlay (duration 00:00:00) [common] case: test-runscript-overlay
case_id: 20947
definition: lava
duration: 0.04
extra: ...
level: 1.2.2.4.4
namespace: common
result: pass
Creating lava-test-runner.conf files Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/lavafed-1018/lava-overlay-zas8rlsr/lava-1018/0 for stage 0 - 0_smoke-tests end: 1.2.2.4 test-definition (duration 00:00:00) [common] start: 1.2.2.5 compress-overlay (timeout 00:04:59) [common] end: 1.2.2.5 compress-overlay (duration 00:00:00) [common] start: 1.2.2.6 persistent-nfs-overlay (timeout 00:04:59) [common] end: 1.2.2.6 persistent-nfs-overlay (duration 00:00:00) [common] end: 1.2.2 lava-overlay (duration 00:00:00) [common] start: 1.2.3 extract-overlay-ramdisk (timeout 00:04:59) [common] end: 1.2.3 extract-overlay-ramdisk (duration 00:00:00) [common] start: 1.2.4 extract-modules (timeout 00:04:59) [common] end: 1.2.4 extract-modules (duration 00:00:00) [common] start: 1.2.5 apply-overlay-tftp (timeout 00:04:59) [common] [common] No overlay directory {'kernel': {'type': 'uimage', 'url': 'http://images.validation.linaro.org/snapshots.linaro.org/openembedded/schneider/linaro-sumo/rzn1d/38/dip/fitImage-1.0-r0-rzn1-snarc.itb'}, 'deployment_data': {}, 'timeout': {'minutes': 5}, 'to': 'tftp', 'namespace': 'common'} end: 1.2.5 apply-overlay-tftp (duration 00:00:00) [common] start: 1.2.6 prepare-kernel (timeout 00:04:59) [common] start: 1.2.6.1 uboot-prepare-kernel (timeout 00:04:59) [common] end: 1.2.6.1 uboot-prepare-kernel (duration 00:00:00) [common] end: 1.2.6 prepare-kernel (duration 00:00:00) [common] start: 1.2.7 configure-preseed-file (timeout 00:04:59) [common] end: 1.2.7 configure-preseed-file (duration 00:00:00) [common] start: 1.2.8 compress-ramdisk (timeout 00:04:59) [common] end: 1.2.8 compress-ramdisk (duration 00:00:00) [common] end: 1.2 prepare-tftp-overlay (duration 00:00:00) [common] start: 1.3 lxc-create-udev-rule-action (timeout 00:04:59) [common] No LXC device requested end: 1.3 lxc-create-udev-rule-action (duration 00:00:00) [common] start: 1.4 deploy-device-env (timeout 00:04:59) [common] end: 1.4 deploy-device-env (duration 00:00:00) [common] Checking files for TFTP limit of 4294967296 bytes. end: 1 tftp-deploy (duration 00:00:01) [common] start: 2 uboot-action (timeout 00:09:59) [common] start: 2.1 uboot-from-media (timeout 00:09:59) [common] end: 2.1 uboot-from-media (duration 00:00:00) [common] start: 2.2 bootloader-overlay (timeout 00:09:59) [common] {'pass': None, 'bootm': {'dtb': '0x80f00000', 'ramdisk': '-', 'kernel': '0x84000000'}, 'uimage': {'dtb': '0x80f00000', 'ramdisk': '-', 'kernel': '0x84000000'}, 'bootz': {'dtb': '0x80f00000', 'ramdisk': '-', 'kernel': '0x84000000'}, 'zimage': {'dtb': '0x80f00000', 'ramdisk': '-', 'kernel': '0x84000000'}} Parsed boot commands: setenv autoload no; dhcp; dhcp; setenv serverip 10.2.1.1; tftp 0x84000000 lavafed-1018/tftp-deploy-8_xxyays/kernel/fitImage-1.0-r0-rzn1-snarc.itb; setenv bootargs 'console=ttyS0,115200n8 rootfstype=ramfs rootwait coherent_pool=2M'; bootm 0x84000000#conf@1 end: 2.2 bootloader-overlay (duration 00:00:00) [common] case: bootloader-overlay
case_id: 20948
definition: lava
duration: 0.00
extra: ...
level: 2.2
namespace: common
result: pass
start: 2.3 connect-device (timeout 00:09:59) [common] [common] connect-device Connecting to device using 'telnet dispatcher01 7006' end: 2.3 connect-device (duration 00:00:01) [common] start: 2.4 uboot-retry (timeout 00:09:59) [common] start: 2.4.1 reset-device (timeout 00:09:59) [common] start: 2.4.1.1 pdu-reboot (timeout 00:09:59) [common] Calling: 'nice' '/usr/local/lab-scripts/snmp_pdu_control' '--hostname' 'pdu01' '--command' 'reboot' '--port' '14' '--delay' '20' >> pdu01 port 14 now powered off >> pdu01 port 14 now powered on >> pdu01 port 14 now power cycled Returned 0 in 24 seconds end: 2.4.1.1 pdu-reboot (duration 00:00:24) [common] case: pdu-reboot
case_id: 20949
definition: lava
duration: 24.35
extra: ...
level: 2.4.1.1
namespace: common
result: pass
end: 2.4.1 reset-device (duration 00:00:24) [common] start: 2.4.2 bootloader-interrupt (timeout 00:09:35) [common] Not interrupting bootloader, waiting for bootloader prompt bootloader-interrupt: Wait for prompt => (timeout 00:10:00) Trying 10.2.1.1... Connected to dispatcher01.lavalab. Escape character is '^]'. Scheider LCES2 7006 [115200 N81] ** BOOTLOADER STAGE0 for RZN1 ** Boot ROM Version 02.00 Boot source: QSPI 00 BOOTLOADER STAGE0 Success *** Bootloader stage0 END *** *** Execute 2nd Stage Bootloader which has been loaded and verified *** [32;1mFirst Stage Boot Loader (from GNU Build)[0m FSBL version DIP-secureboot-v1.7.1-14-gfd75da4 (ralphs@maple, Mon Jun 17 14:50:25 EDT 2019) Prepare Secure/Unsecure world Unsecure Boot (authentication disabled) Unsecure configuration (debug enabled) FIP table of contents: tos-fw@0x40000 size 0x80000 tb-fw-config@0xc0000 size 0x40000 hw-config@0x100000 size 0x40000 cm3-fw@0x140000 size 0x100000 nt-fw@0x240000 size 0x80000 nt-fw@0x2c0000 size 0x80000 Initialize DDR Controller Initialize QSPI Controller Probed QSPI Jedec code : 1 20 18 Enabling crypto engine... Copy Master from QSPI ok Copying firmware partition from 0x1004010c to 0x87c00000 Copying firmware partition from 0x102400f8 to 0x87800000 Valid non-trusted firmware @0x10240000: revision: 20190614 entry: 0x87800000 + 0 data: 0x102400f8 + 454472 cms: 0x102af0a0 + 1134 Booting Secure OS from 87c00000 D/TC:0 add_phys_mem:526 VCORE_UNPG_RX_PA type TEE_RAM_RX 0x87c00000 size 0x0006a000 D/TC:0 add_phys_mem:526 VCORE_UNPG_RW_PA type TEE_RAM_RW 0x87c6a000 size 0x00096000 D/TC:0 add_phys_mem:526 TA_RAM_START type TA_RAM 0x87d00000 size 0x00300000 D/TC:0 add_phys_mem:526 TEE_SHMEM_START type NSEC_SHM 0x87a00000 size 0x00200000 D/TC:0 add_phys_mem:526 CONSOLE_UART_BASE type IO_NSEC 0x40000000 size 0x00200000 D/TC:0 add_phys_mem:526 SYSCTRL_BASE type IO_SEC 0x40000000 size 0x00200000 D/TC:0 add_phys_mem:526 GIC_BASE type IO_SEC 0x44100000 size 0x00100000 D/TC:0 verify_special_mem_areas:464 No NSEC DDR memory area defined D/TC:0 add_va_space:565 type RES_VASPACE size 0x00a00000 D/TC:0 add_va_space:565 type SHM_VASPACE size 0x02000000 D/TC:0 dump_mmap_table:698 type IO_SEC va 0x84800000..0x849fffff pa 0x40000000..0x401fffff size 0x00200000 (pgdir) D/TC:0 dump_mmap_table:698 type IO_NSEC va 0x84a00000..0x84bfffff pa 0x40000000..0x401fffff size 0x00200000 (pgdir) D/TC:0 dump_mmap_table:698 type NSEC_SHM va 0x84c00000..0x84dfffff pa 0x87a00000..0x87bfffff size 0x00200000 (pgdir) D/TC:0 dump_mmap_table:698 type TA_RAM va 0x84e00000..0x850fffff pa 0x87d00000..0x87ffffff size 0x00300000 (pgdir) D/TC:0 dump_mmap_table:698 type RES_VASPACE va 0x85100000..0x85afffff pa 0x00000000..0x009fffff size 0x00a00000 (pgdir) D/TC:0 dump_mmap_table:698 type IO_SEC va 0x85b00000..0x85bfffff pa 0x44100000..0x441fffff size 0x00100000 (pgdir) D/TC:0 dump_mmap_table:698 type SHM_VASPACE va 0x85c00000..0x87bfffff pa 0x00000000..0x01ffffff size 0x02000000 (pgdir) D/TC:0 dump_mmap_table:698 type TEE_RAM_RX va 0x87c00000..0x87c69fff pa 0x87c00000..0x87c69fff size 0x0006a000 (smallpg) D/TC:0 dump_mmap_table:698 type TEE_RAM_RW va 0x87c6a000..0x87cfffff pa 0x87c6a000..0x87cfffff size 0x00096000 (smallpg) D/TC:0 core_mmu_alloc_l2:238 L2 table used: 1/4 I/TC: D/TC:0 init_canaries:164 #Stack canaries for stack_tmp[0] with top at 0x87c96778 D/TC:0 init_canaries:164 watch *0x87c9677c D/TC:0 init_canaries:164 #Stack canaries for stack_tmp[1] with top at 0x87c96e78 D/TC:0 init_canaries:164 watch *0x87c96e7c D/TC:0 init_canaries:165 #Stack canaries for stack_abt[0] with top at 0x87c97688 D/TC:0 init_canaries:165 watch *0x87c9768c D/TC:0 init_canaries:165 #Stack canaries for stack_abt[1] with top at 0x87c97e98 D/TC:0 init_canaries:165 watch *0x87c97e9c D/TC:0 init_canaries:167 #Stack canaries for stack_thread[0] with top at 0x87c99ea8 D/TC:0 init_canaries:167 watch *0x87c99eac I/TC: OP-TEE version: 3.2.0-9-g70266083-dev #1 Mon Jun 17 18:41:20 UTC 2019 arm D/TC:0 tee_ta_register_ta_store:534 Registering TA store: 'REE' (priority 10) D/TC:0 tee_ta_register_ta_store:534 Registering TA store: 'Secure Storage TA' (priority 9) D/TC:0 mobj_mapped_shm_init:559 Shared memory address range: 85c00000, 87c00000 I/TC: Initialized D/TC:0 init_primary_helper:917 Primary CPU switching to normal world boot U-Boot 2017.01 (Jun 14 2019 - 20:41:25 +0000) Model: RZ/N1D SNARC boards DRAM: 256 MiB NAND: 512 MiB MMC: sdhci@40100000: 0 SF: Detected s25fl128s_64k with page size 256 Bytes, erase size 64 KiB, total 16 MiB, mapped at 10000000 In: serial@40060000 Out: serial@40060000 Err: serial@40060000 Net: dwmac.44002000 end: 2.4.2 bootloader-interrupt (duration 00:00:01) [common] start: 2.4.3 bootloader-commands (timeout 00:09:33) [common] setenv autoload no => setenv autoload no bootloader-commands: Wait for prompt ['=>', '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:09:33) setenv autoload no dhcp; dhcp => dhcp; dhcp bootloader-commands: Wait for prompt ['=>', '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:09:33) dhcp; dhcp dwmac.44002000 Waiting for PHY auto negotiation to complete...... done BOOTP broadcast 1 BOOTP broadcast 2 BOOTP broadcast 1 BOOTP broadcast 2 BOOTP broadcast 3 DHCP client bound to address 10.15.24.9 (800 ms) setenv serverip 10.2.1.1 => setenv serverip 10.2.1.1 bootloader-commands: Wait for prompt ['=>', '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:09:29) setenv serverip 10.2.1.1 tftp 0x84000000 lavafed-1018/tftp-deploy-8_xxyays/kernel/fitImage-1.0-r0-rzn1-snarc.itb => tftp 0x84000000 lavafed-1018/tftp-deploy-8_xxyays/kernel/fitImage-1.0-r0-rzn1-snarc.itb bootloader-commands: Wait for prompt ['=>', '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:09:29) tftp 0x84000000 lavafed-1018/tftp-deploy-8_xxyays/kernel/fitImage-1.0-r0-rzn1-snarc.itb Using dwmac.44002000 device TFTP from server 10.2.1.1; our IP address is 10.15.24.9 Filename 'lavafed-1018/tftp-deploy-8_xxyays/kernel/fitImage-1.0-r0-rzn1-snarc.itb'. Load address: 0x84000000 Loading: *################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ################################################################# ############################################################## 8.3 MiB/s done Bytes transferred = 22848300 (15ca32c hex) setenv bootargs 'console=ttyS0,115200n8 rootfstype=ramfs rootwait coherent_pool=2M' => setenv bootargs 'console=ttyS0,115200n8 rootfstype=ramfs rootwait coherent_pool=2M' bootloader-commands: Wait for prompt ['=>', '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:09:26) setenv bootargs 'console=ttyS0,115200n8 rootfstype=ramfs rootwait coherent_pool=2M' bootm 0x84000000#conf@1 => bootm 0x84000000#conf@1 bootloader-commands: Wait for prompt Starting kernel (timeout 00:09:26) bootm 0x84000000#conf@1 ## Loading kernel from FIT Image at 84000000 ... Using 'conf@1' configuration Verifying Hash Integrity ... OK Trying 'kernel@1' kernel subimage Description: Linux kernel Type: Kernel Image Compression: uncompressed Data Start: 0x84000790 Data Size: 22703224 Bytes = 21.7 MiB Architecture: ARM OS: Linux Load Address: 0x80008000 Entry Point: 0x80008000 Verifying Hash Integrity ... OK ## Loading fdt from FIT Image at 84000000 ... Using 'conf@1' configuration Trying 'fdt@1' fdt subimage Description: Flattened Device Tree blob Type: Flat Device Tree Compression: uncompressed Data Start: 0x855a786c Data Size: 46258 Bytes = 45.2 KiB Architecture: ARM Verifying Hash Integrity ... OK Booting using the fdt blob at 0x855a786c Loading Kernel Image ... OK Loading Device Tree to 877f1000, end 877ff4b1 ... OK Starting kernel ... end: 2.4.3 bootloader-commands (duration 00:00:08) [common] start: 2.4.4 auto-login-action (timeout 00:09:25) [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:10:00) Uncompressing Linux... done, booting the kernel. D/TC: init_secondary_helper:941 Secondary CPU Switching to normal world boot Booting Linux on physical CPU 0x0 Using line separator: #'\n'# Waiting for the login prompt Parsing kernel messages ['-+\\[ cut here \\]-+\\s+(.*\\s+-+\\[ end trace (\\w*) \\]-+)', '(Unhandled fault.*)\\r\\n', 'Kernel panic - (.*) end Kernel panic', 'Stack:\\s+(.*\\s+-+\\[ end trace (\\w*) \\]-+)', 'Current password: ', 'New password: ', 'Retype new password: ', 'root@rzn1-snarc:', 'login:', 'Login incorrect'] [auto-login-action] Waiting for messages, (timeout 00:09:20) Linux version 4.9.0-dip-sch (oe-user@oe-host) (gcc version 7.3.0 (GCC) ) #1 SMP Fri Mar 8 11:01:43 UTC 2019 CPU: ARMv7 Processor [410fc075] revision 5 (ARMv7), cr=30c5387d CPU: div instructions available: patching division code CPU: PIPT / VIPT nonaliasing data cache, VIPT aliasing instruction cache OF: fdt:Machine model: RZ/N1D SNARC Bestla Memory policy: Data cache writealloc psci: probing for conduit method from DT. psci: PSCIv0.2 detected in firmware. psci: Using standard PSCI v0.2 function IDs psci: MIGRATE_INFO_TYPE not supported. percpu: Embedded 14 pages/cpu @8fd6c000 s25164 r8192 d23988 u57344 Built 1 zonelists in Zone order, mobility grouping on. Total pages: 63936 Kernel command line: console=ttyS0,115200n8 rootfstype=ramfs rootwait coherent_pool=2M PID hash table entries: 1024 (order: 0, 4096 bytes) Dentry cache hash table entries: 32768 (order: 5, 131072 bytes) Inode-cache hash table entries: 16384 (order: 4, 65536 bytes) Memory: 219968K/258048K available (8192K kernel code, 256K rwdata, 2148K rodata, 18432K init, 2219K bss, 38080K reserved, 0K cma-reserved) Virtual kernel memory layout: vector : 0xffff0000 - 0xffff1000 ( 4 kB) fixmap : 0xffc00000 - 0xfff00000 (3072 kB) vmalloc : 0x90800000 - 0xff800000 (1776 MB) lowmem : 0x80000000 - 0x90000000 ( 256 MB) modules : 0x7f000000 - 0x80000000 ( 16 MB) .text : 0x80008000 - 0x80a00000 (10208 kB) .init : 0x80e00000 - 0x82000000 (18432 kB) .data : 0x82000000 - 0x820402d0 ( 257 kB) .bss : 0x82042000 - 0x8226cd04 (2220 kB) Hierarchical RCU implementation. RCU debugfs-based tracing is enabled. Build-time adjustment of leaf fanout to 32. NR_IRQS:16 nr_irqs:16 16 rzn1_clock_divider_init: clkout_d1or2 MISSING REG property, skipping arm_arch_timer: Architected cp15 timer(s) running at 6.25MHz (phys). clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x171024e6b, max_idle_ns: 440795202301 ns sched_clock: 56 bits at 6MHz, resolution 160ns, wraps every 4398046511040ns Switching to timer-based delay loop, resolution 160ns rzn1-timer: base clock is 25000000 Hz sched_clock: 16 bits at 25MHz, resolution 40ns, wraps every 1310700ns clocksource: rzn1_timer_clocksource: mask: 0xffff max_cycles: 0xffff, max_idle_ns: 1166523 ns Console: colour dummy device 80x30 Calibrating delay loop (skipped), value calculated using timer frequency.. 12.50 BogoMIPS (lpj=62500) pid_max: default: 32768 minimum: 301 Security Framework initialized Mount-cache hash table entries: 1024 (order: 0, 4096 bytes) Mountpoint-cache hash table entries: 1024 (order: 0, 4096 bytes) CPU: Testing write buffer coherency: ok Setting up static identity map for 0x80200000 - 0x80200058 Brought up 2 CPUs SMP: Total of 2 processors activated (25.00 BogoMIPS). CPU: All CPU(s) started in SVC mode. devtmpfs: initialized VFP support v0.3: implementor 41 architecture 2 part 30 variant 7 rev 5 clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns xor: measuring software checksum speed arm4regs : 628.800 MB/sec 8regs : 373.600 MB/sec 32regs : 461.600 MB/sec xor: using function: arm4regs (628.800 MB/sec) pinctrl core: initialized pinctrl subsystem NET: Registered protocol family 16 DMA: preallocated 2048 KiB pool for atomic coherent allocations cpuidle: using governor menu rzn1-pinctrl 40067000.pinctrl: initialized rzn1 pinctrl driver raid6: int32x1 gen() 79 MB/s raid6: int32x1 xor() 56 MB/s raid6: int32x2 gen() 95 MB/s raid6: int32x2 xor() 62 MB/s raid6: int32x4 gen() 80 MB/s raid6: int32x4 xor() 60 MB/s raid6: int32x8 gen() 67 MB/s raid6: int32x8 xor() 46 MB/s raid6: using algorithm int32x2 gen() 95 MB/s raid6: .... xor() 62 MB/s, rmw enabled raid6: using intx1 recovery algorithm dw_dmac 40104000.dma-controller: DesignWare DMA Controller, 8 channels dw_dmac 40104000.dma-controller: RZ/N1 init cfg_dmamux=0xffff vgaarb: loaded SCSI subsystem initialized usbcore: registered new interface driver usbfs usbcore: registered new interface driver hub usbcore: registered new device driver usb pps_core: LinuxPPS API ver. 1 registered pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it> PTP clock support registered EDAC MC: Ver: 3.0.0 Bluetooth: Core ver 2.22 NET: Registered protocol family 31 Bluetooth: HCI device and connection manager initialized Bluetooth: HCI socket layer initialized Bluetooth: L2CAP socket layer initialized Bluetooth: SCO socket layer initialized clocksource: Switched to clocksource arch_sys_counter NET: Registered protocol family 2 TCP established hash table entries: 2048 (order: 1, 8192 bytes) TCP bind hash table entries: 2048 (order: 2, 16384 bytes) TCP: Hash tables configured (established 2048 bind 2048) UDP hash table entries: 256 (order: 1, 8192 bytes) UDP-Lite hash table entries: 256 (order: 1, 8192 bytes) NET: Registered protocol family 1 RPC: Registered named UNIX socket transport module. RPC: Registered udp transport module. RPC: Registered tcp transport module. RPC: Registered tcp NFSv4.1 backchannel transport module. kvm [1]: HYP mode not available rzn1-pinctrl 40067000.pinctrl: setting RZN1_IRQ_GPIO_3 to GPIO1A[0] rzn1-pinctrl 40067000.pinctrl: setting RZN1_IRQ_GPIO_4 to GPIO2A[25] rzn1-pinctrl 40067000.pinctrl: setting RZN1_IRQ_GPIO_5 to GPIO2A[27] rzn1-pinctrl 40067000.pinctrl: setting RZN1_IRQ_GPIO_6 to GPIO0A[9] gpio_irq_mux gpioirq: probed futex hash table entries: 512 (order: 3, 32768 bytes) workingset: timestamp_bits=14 max_order=16 bucket_order=2 squashfs: version 4.0 (2009/01/31) Phillip Lougher NFS: Registering the id_resolver key type Key type id_resolver registered Key type id_legacy registered nfs4filelayout_init: NFSv4 File Layout Driver Registering... Installing knfsd (copyright (C) 1996 okir@monad.swb.de). jffs2: version 2.2. (NAND) (SUMMARY) © 2001-2006 Red Hat, Inc. Block layer SCSI generic (bsg) driver version 0.4 loaded (major 248) io scheduler noop registered io scheduler deadline registered io scheduler cfq registered (default) rzn1: USB PLL already started pci-rcar-gen2 40030000.pci: PCI: bus0 revision 11 pci-rcar-gen2 40030000.pci: PCI host bridge to bus 0000:00 pci_bus 0000:00: root bus resource [mem 0x40020000-0x400210ff] pci_bus 0000:00: No busn resource found for root bus, will use [bus 00-ff] PCI: bus0: Fast back to back transfers disabled pci 0000:00:01.0: BAR 0: assigned [mem 0x40020000-0x40020fff] pci 0000:00:02.0: BAR 0: assigned [mem 0x40021000-0x400210ff] pci 0000:00:01.0: enabling device (0140 -> 0142) pci 0000:00:02.0: enabling device (0140 -> 0142) clcd-db9000 53004000.fb: got a 800x480x24 LCD Console: switching to colour frame buffer device 100x30 clcd-db9000 53004000.fb: Core ID reg: 0x0000410F Serial: 8250/16550 driver, 4 ports, IRQ sharing disabled console [ttyS0] disabled 40060000.serial: ttyS0 at MMIO 0x40060000 (irq = 46, base_baud = 2976190) is a 16550A console [ttyS0] enabled D/TC: oem_sysreg:291 Allow read access to unknown register at 0x400671a4 D/TC: oem_sysreg:291 Allow write access to unknown register at 0x400671a4 D/TC: oem_sysreg:291 Allow read access to unknown register at 0x400671a8 D/TC: oem_sysreg:291 Allow write access to unknown register at 0x400671a8 D/TC: oem_sysreg:291 Allow read access to unknown register at 0x400671ac D/TC: oem_sysreg:291 Allow write access to unknown register at 0x400671ac D/TC: oem_sysreg:291 Allow read access to unknown register at 0x400671b0 D/TC: oem_sysreg:291 Allow write access to unknown register at 0x400671b0 40062000.serial: ttyS1 at MMIO 0x40062000 (irq = 47, base_baud = 2976190) is a 16550A D/TC: oem_sysreg:291 Allow read access to unknown register at 0x400671ec D/TC: oem_sysreg:291 Allow write access to unknown register at 0x400671ec D/TC: oem_sysreg:291 Allow read access to unknown register at 0x400671f0 D/TC: oem_sysreg:291 Allow write access to unknown register at 0x400671f0 D/TC: oem_sysreg:291 Allow read access to unknown register at 0x400671f4 D/TC: oem_sysreg:291 Allow write access to unknown register at 0x400671f4 50000000.serial: ttyS2 at MMIO 0x50000000 (irq = 48, base_baud = 2976190) is a 16550A omap_rng 40040000.crypto_eip76: Random Number Generator ver. 202b34c loop: module loaded at24 1-0050: 8192 byte 24c64 EEPROM, writable, 32 bytes/write evatronix-nand 40102000.nand: Initializing Evatronix NANDFLASH-CTRL driver evatronix-nand 40102000.nand: NAND timing setup missing, using defaults evatronix-nand 40102000.nand: Using NAND ONFi mode 0 timings nand: device found, Manufacturer ID: 0x01, Chip ID: 0xdc nand: AMD/Spansion S34ML04G2 nand: 512 MiB, SLC, erase size: 128 KiB, page size: 2048, OOB size: 128 evatronix-nand 40102000.nand: Using NAND ONFi mode 4 timings evatronix-nand 40102000.nand: Unsupported/unset ECC mode, setting HW BCH evatronix-nand 40102000.nand: Unsupported ECC step size, using default 512 Scanning device for bad blocks Bad eraseblock 0 at 0x000000000000 Bad eraseblock 1 at 0x000000020000 random: fast init done evatronix-nand 40102000.nand: ECC using hardware mode with strength 16 and block size 512. 3 ofpart partitions found on MTD device S34ML04G2 Creating 3 MTD partitions on \"S34ML04G2\": 0x000000000000-0x000002000000 : \"nand:fit0\" 0x000002000000-0x000004000000 : \"nand:fit1\" 0x000004000000-0x000020000000 : \"nand:data\" rzn1-qspi 40005000.spi: _rzn1_qspi_set_baudrate_div: output clock is 7812500Hz (div = 32) rzn1-qspi 40005000.spi: s25fl128s (16384 Kbytes) rzn1-qspi 40005000.spi: _rzn1_qspi_set_baudrate_div: output clock is 62500000Hz (div = 4) 9 ofpart partitions found on MTD device 40005000.spi Creating 9 MTD partitions on \"40005000.spi\": 0x000000000000-0x000000040000 : \"qspi0:spl\" 0x000000040000-0x000000080000 : \"qspi0:tosfit\" 0x000000080000-0x0000000c0000 : \"qspi0:configcms\" 0x0000000c0000-0x000000100000 : \"qspi0:reserved\" 0x000000100000-0x000000140000 : \"qspi0:env\" 0x000000140000-0x000000240000 : \"qspi0:cm3fit\" 0x000000240000-0x0000002c0000 : \"qspi0:uboot0fit\" 0x0000002c0000-0x000000340000 : \"qspi0:uboot1fit\" 0x000000340000-0x000001000000 : \"qspi0:data\" rzn1-qspi 40005000.spi: probed dw_spi_mmio 50005000.spi: DMA channel RX dma0chan0 dw_spi_mmio 50005000.spi: DMA channel TX dma0chan1 at25 spi32766.1: 128 KByte at25 fram, pagesize 4096 libphy: Fixed MDIO Bus: probed CAN device driver interface usbcore: registered new interface driver peak_usb sja1000 CAN netdevice driver sja1000_platform 52104000.can: sja1000_platform device registered (reg_base=a0d81000, irq=63) libphy: mdio_driver_register: mv88e6085 rzn1-pinctrl 40067000.pinctrl: setting mdio 1 to 0x7 mt5pt_switch 44050000.eth-switch: /bus/eth-switch@44050000/eth-port@2 missing phy-mode, skipping port mt5pt_switch 44050000.eth-switch: /bus/eth-switch@44050000/eth-port@3 missing phy-mode, skipping port mt5pt_switch 44050000.eth-switch: MDIO clk is 2450980 Hz mt5pt_switch 44050000.eth-switch: MoreThanIP 5-port switch revision 1024 libphy: 44050000.eth-switch: probed mt5pt_switch 44050000.eth-switch: phy@5: Marvell 88E1512 mt5pt_switch 44050000.eth-switch: open port A mt5pt_switch 44050000.eth-switch: phy@4: Marvell 88E1512 mt5pt_switch 44050000.eth-switch: open port B rzn1-pinctrl 40067000.pinctrl: setting mdio 0 to 0x1 D/TC: oem_sysreg:291 Allow read access to unknown register at 0x40067008 D/TC: oem_sysreg:291 Allow write access to unknown register at 0x40067008 D/TC: oem_sysreg:291 Allow read access to unknown register at 0x40067010 D/TC: oem_sysreg:291 Allow write access to unknown register at 0x40067010 D/TC: oem_sysreg:291 Allow read access to unknown register at 0x40067014 D/TC: oem_sysreg:291 Allow write access to unknown register at 0x40067014 D/TC: oem_sysreg:291 Allow read access to unknown register at 0x40067018 D/TC: oem_sysreg:291 Allow write access to unknown register at 0x40067018 D/TC: oem_sysreg:291 Allow read access to unknown register at 0x4006701c D/TC: oem_sysreg:291 Allow write access to unknown register at 0x4006701c D/TC: oem_sysreg:291 Allow read access to unknown register at 0x40067020 D/TC: oem_sysreg:291 Allow write access to unknown register at 0x40067020 D/TC: oem_sysreg:291 Allow read access to unknown register at 0x40067024 D/TC: oem_sysreg:291 Allow write access to unknown register at 0x40067024 D/TC: oem_sysreg:291 Allow read access to unknown register at 0x4006702c D/TC: oem_sysreg:291 Allow write access to unknown register at 0x4006702c stmmaceth 44000000.ethernet: no reset control found stmmac - user ID: 0x20, Synopsys ID: 0x37 Ring mode enabled DMA HW capability register supported Enhanced/Alternate descriptors Enabled extended descriptors RX Checksum Offload Engine supported COE Type 2 TX Checksum insertion supported Wake-Up On Lan supported Enable RX Mitigation via HW Watchdog Timer libphy: stmmac: probed stmmaceth 44002000.ethernet: no reset control found stmmac - user ID: 0x21, Synopsys ID: 0x37 Ring mode enabled DMA HW capability register supported Enhanced/Alternate descriptors Enabled extended descriptors RX Checksum Offload Engine supported COE Type 2 TX Checksum insertion supported Wake-Up On Lan supported Enable RX Mitigation via HW Watchdog Timer rzn1-miic 44030000.eth-miic: nr phy ports 5 rzn1-miic 44030000.eth-miic: /dsa@0/switch@0/port@6 phy parent has not been probed yet usbcore: registered new interface driver rt2800usb ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver ehci-pci: EHCI PCI platform driver ehci-pci 0000:00:02.0: EHCI Host Controller ehci-pci 0000:00:02.0: new USB bus registered, assigned bus number 1 ehci-pci 0000:00:02.0: irq 45, io mem 0x40021000 ehci-pci 0000:00:02.0: USB 2.0 started, EHCI 1.00 usb usb1: New USB device found, idVendor=1d6b, idProduct=0002 usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1 usb usb1: Product: EHCI Host Controller usb usb1: Manufacturer: Linux 4.9.0-dip-sch ehci_hcd usb usb1: SerialNumber: 0000:00:02.0 hub 1-0:1.0: USB hub found hub 1-0:1.0: 1 port detected ehci-platform: EHCI generic platform driver ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver ohci-pci: OHCI PCI platform driver ohci-pci 0000:00:01.0: OHCI PCI host controller ohci-pci 0000:00:01.0: new USB bus registered, assigned bus number 2 ohci-pci 0000:00:01.0: irq 45, io mem 0x40020000 usb usb2: New USB device found, idVendor=1d6b, idProduct=0001 usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1 usb usb2: Product: OHCI PCI host controller usb usb2: Manufacturer: Linux 4.9.0-dip-sch ohci_hcd usb usb2: SerialNumber: 0000:00:01.0 hub 2-0:1.0: USB hub found hub 2-0:1.0: 1 port detected ohci-platform: OHCI generic platform driver usbcore: registered new interface driver cdc_acm cdc_acm: USB Abstract Control Model driver for USB modems and ISDN adapters usbcore: registered new interface driver usb-storage usbcore: registered new interface driver usbserial usbcore: registered new interface driver usbserial_generic usbserial: USB Serial support registered for generic usbcore: registered new interface driver ftdi_sio usbserial: USB Serial support registered for FTDI USB Serial Device usbcore: registered new interface driver pl2303 usbserial: USB Serial support registered for pl2303 usbf_probe completed (gadget usbf_renesas) using random self ethernet address using random host ethernet address usb0: HOST MAC 46:e0:41:01:d6:0d usb0: MAC 9a:b2:6d:37:3f:45 using random self ethernet address using random host ethernet address g_ether gadget: Ethernet Gadget, version: Memorial Day 2008 g_ether gadget: g_ether ready usbf_renesas 4001e000.usbf: usbf_renesas bind to driver g_ether rzn1-rtc 40006000.rtc: rtc core: registered 40006000.rtc as rtc0 i2c /dev entries driver lm75 1-0049: hwmon0: sensor 'lm75' rzn1_wdtsafe 51003000.wdtsafe0: Initialized Bluetooth: HCI UART driver ver 2.3 Bluetooth: HCI UART protocol H4 registered Bluetooth: HCI UART protocol BCSP registered Bluetooth: HCI UART protocol LL registered Bluetooth: HCI UART protocol ATH3K registered Bluetooth: HCI UART protocol Three-wire (H5) registered usbcore: registered new interface driver bcm203x usbcore: registered new interface driver bpa10x usbcore: registered new interface driver bfusb usbcore: registered new interface driver btusb Bluetooth: Generic Bluetooth SDIO driver ver 0.1 usbcore: registered new interface driver ath3k D/TC: oem_sysreg:300 Blocking write of 0x8 to register 0x4000c064 (0x1d) D/TC: oem_sysreg:300 Blocking write of 0x10 to register 0x4000c064 (0x1d) D/TC: oem_sysreg:300 Blocking write of 0x1 to register 0x4000c064 (0x1d) D/TC: oem_sysreg:300 Blocking write of 0x4 to register 0x4000c064 (0x1d) D/TC: oem_sysreg:300 Blocking write of 0x0 to register 0x4000c064 (0x1d) EDAC MC0: Giving out device to module cadence_mc_edac controller cadence,ddr-ctrl: DEV 4000d000.memory-controller (INTERRUPT) DDR protected at addr 0x7c0b210, type 0x4, id 0x9 cadence_mc_edac 4000d000.memory-controller: DDR EDAC (Error Detection And Correction): ECC Disabled sdhci: Secure Digital Host Controller Interface driver sdhci: Copyright(c) Pierre Ossman sdhci-pltfm: SDHCI platform and OF driver helper mmc0: SDHCI controller on 40100000.sdhci [40100000.sdhci] using ADMA ledtrig-cpu: registered to indicate activity on CPUs usbcore: registered new interface driver usbhid usbhid: USB HID core driver Netfilter messages via NETLINK v0.30. nf_conntrack version 0.5.0 (4096 buckets, 16384 max) nf_tables: (c) 2007-2009 Patrick McHardy <kaber@trash.net> IPVS: Registered protocols () IPVS: Connection hash table configured (size=4096, memory=32Kbytes) IPVS: Creating netns size=912 id=0 IPVS: ipvs loaded. ipip: IPv4 and MPLS over IPv4 tunneling driver ip_tables: (C) 2000-2006 Netfilter Core Team arp_tables: arp_tables: (C) 2002 David S. Miller NET: Registered protocol family 10 IPv6: ADDRCONF(NETDEV_UP): sw0p0: link is not ready IPv6: ADDRCONF(NETDEV_UP): sw0p1: link is not ready sit: IPv6, IPv4 and MPLS over IPv4 tunneling driver NET: Registered protocol family 17 Distributed Switch Architecture driver version 0.1 dsa dsa@0: switch 0x352 detected: Marvell 88E6352, revision 1 libphy: mv88e6xxx SMI: probed stmmaceth 44000000.ethernet eth0: [0]: detected a Marvell 88E6352 switch Marvell 88E1540 mv88e6xxx-0:02: attached PHY driver [Marvell 88E1540] (mii_bus:phy_addr=mv88e6xxx-0:02, irq=-1) Marvell 88E1540 mv88e6xxx-0:03: attached PHY driver [Marvell 88E1540] (mii_bus:phy_addr=mv88e6xxx-0:03, irq=-1) Marvell 88E1540 mv88e6xxx-0:04: attached PHY driver [Marvell 88E1540] (mii_bus:phy_addr=mv88e6xxx-0:04, irq=-1) can: controller area network core (rev 20120528 abi 9) NET: Registered protocol family 29 can: raw protocol (rev 20120528) can: broadcast manager protocol (rev 20161123 t) can: netlink gateway (rev 20130117) max_hops=1 Bluetooth: RFCOMM TTY layer initialized Bluetooth: RFCOMM socket layer initialized Bluetooth: RFCOMM ver 1.11 Bluetooth: BNEP (Ethernet Emulation) ver 1.3 Bluetooth: BNEP filters: protocol multicast Bluetooth: BNEP socket layer initialized Bluetooth: HIDP (Human Interface Emulation) ver 1.2 Bluetooth: HIDP socket layer initialized Key type dns_resolver registered ThumbEE CPU extension supported. Registering SWP/SWPB emulation handler Btrfs loaded, crc32c=crc32c-generic rzn1-miic 44030000.eth-miic: nr phy ports 5 rzn1-miic 44030000.eth-miic: /bus/eth-miic@44030000/eth-mii0 rgmii-id rzn1-miic 44030000.eth-miic: /bus/eth-switch@44050000/eth-port@3 phy-mode is not in your DT rzn1-miic 44030000.eth-miic: /bus/eth-switch@44050000/eth-port@2 phy-mode is not in your DT rzn1-miic 44030000.eth-miic: /bus/eth-miic@44030000/eth-mii3 rgmii-id rzn1-miic 44030000.eth-miic: /bus/eth-miic@44030000/eth-mii4 rgmii-id input: gpio_keys as /devices/platform/gpio_keys/input/input0 rzn1-rtc 40006000.rtc: setting system clock to 2000-01-01 00:00:00 UTC (946684800) Warning: unable to open an initial console. Freeing unused kernel memory: 18432K (80e00000 - 82000000) cgroup: cgroup2: unknown option \"nsdelegate\" systemd[1]: systemd 237 running in system mode. (-PAM -AUDIT -SELINUX -IMA -APPARMOR -SMACK +SYSVINIT -UTMP -LIBCRYPTSETUP -GCRYPT -GNUTLS +ACL +XZ -LZ4 -SECCOMP +BLKID -ELFUTILS +KMOD -IDN2 -IDN -PCRE2 default-hierarchy=hybrid) systemd[1]: Detected architecture arm. Welcome to [1mDIP (DIP Reference Distro) 2.5+snapshot-20190308 (master)[0m! systemd[1]: Set hostname to <rzn1-snarc>. systemd[1]: Initializing machine ID from random generator. systemd[1]: File /lib/systemd/system/systemd-journald.service:35 configures an IP firewall (IPAddressDeny=any), but the local system does not support BPF/cgroup based firewalling. systemd[1]: Proceeding WITHOUT firewalling in effect! (This warning is only shown for the first loaded unit using IP firewalling.) systemd[1]: Reached target Remote File Systems. [[0;32m OK [0m] Reached target Remote File Systems. systemd[1]: Started Forward Password Requests to Wall Directory Watch. [[0;32m OK [0m] Started Forward Password Requests to Wall Directory Watch. systemd[1]: Created slice System Slice. [[0;32m OK [0m] Created slice System Slice. systemd[1]: Reached target Slices. [[0;32m OK [0m] Reached target Slices. [[0;32m OK [0m] Created slice system-getty.slice. [[0;32m OK [0m] Listening on Network Service Netlink Socket. [[0;32m OK [0m] Started Dispatch Password Requests to Console Directory Watch. [[0;32m OK [0m] Reached target Paths. [[0;32m OK [0m] Listening on udev Control Socket. [[0;32m OK [0m] Listening on Journal Socket (/dev/log). [[0;32m OK [0m] Listening on Journal Socket. Starting Apply Kernel Variables... Mounting NFSD configuration filesystem... Starting Create list of required st…ce nodes for the current kernel... Mounting Kernel Configuration File System... [[0;32m OK [0m] Reached target Swap. Mounting Temporary Directory (/tmp)... [[0;32m OK [0m] Created slice system-serial\x2dgetty.slice. [[0;32m OK [0m] Listening on /dev/initctl Compatibility Named Pipe. Starting Journal Service... Starting Remount Root and Kernel File Systems... Mounting POSIX Message Queue File System... [[0;32m OK [0m] Listening on udev Kernel Socket. Starting udev Coldplug all Devices... Mounting Kernel Debug File System... [[0;32m OK [0m] Started Apply Kernel Variables. [[0;32m OK [0m] Mounted NFSD configuration filesystem. [[0;32m OK [0m] Started Create list of required sta…vice nodes for the current kernel. [[0;32m OK [0m] Mounted Kernel Configuration File System. [[0;32m OK [0m] Mounted Temporary Directory (/tmp). [[0;32m OK [0m] Started Remount Root and Kernel File Systems. [[0;32m OK [0m] Mounted POSIX Message Queue File System. [[0;32m OK [0m] Mounted Kernel Debug File System. Starting Rebuild Hardware Database... mt5pt_switch 44050000.eth-switch sw0p1: Link is Down Starting Create Static Device Nodes in /dev... mt5pt_switch 44050000.eth-switch sw0p1: Link is Up - 1Gbps/Full - flow control off IPv6: ADDRCONF(NETDEV_CHANGE): sw0p1: link becomes ready [[0;32m OK [0m] Started Create Static Device Nodes in /dev. mt5pt_switch 44050000.eth-switch: Switch devices can't send data, use the GMAC [[0;32m OK [0m] Started Journal Service. Starting Flush Journal to Persistent Storage... [[0;32m OK [0m] Reached target Local File Systems (Pre). Mounting /var/volatile... [[0;32m OK [0m] Mounted /var/volatile. [[0;32m OK [0m] Reached target Local File Systems. Starting Rebuild Journal Catalog... systemd-journald[119]: Received request to flush runtime journal from PID 1 [[0;32m OK [0m] Started Flush Journal to Persistent Storage. Starting Create Volatile Files and Directories... [[0;32m OK [0m] Started Rebuild Journal Catalog. [[0;32m OK [0m] Started Create Volatile Files and Directories. [[0;32m OK [0m] Started udev Coldplug all Devices. Starting Run pending postinsts... Starting Network Time Synchronization... [[0;32m OK [0m] Started Network Time Synchronization. [[0;32m OK [0m] Reached target System Time Synchronized. [[0;32m OK [0m] Started Run pending postinsts. [[0;32m OK [0m] Started Rebuild Hardware Database. Starting udev Kernel Device Manager... Starting Update is Completed... [[0;32m OK [0m] Started udev Kernel Device Manager. Starting Network Service... [[0;32m OK [0m] Started Update is Completed. [[0;32m OK [0m] Reached target System Initialization. [[0;32m OK [0m] Listening on D-Bus System Message Bus Socket. [[0;32m OK [0m] Started Daily Cleanup of Temporary Directories. [[0;32m OK [0m] Reached target Timers. [[0;32m OK [0m] Listening on RPCbind Server Activation Socket. [[0;32m OK [0m] Reached target Sockets. [[0;32m OK [0m] Reached target Basic System. [[0;32m OK [0m] Started D-Bus System Message Bus. Starting RPC Bind Service... [[0;32m OK [0m] Started Switching from initramfs to rootfs. [[0;32m OK [0m] Started Getty on tty1. [[0;32m OK [0m] Started Network Service. [[0;32m OK [0m] Started RPC Bind Service. ubi0: attaching mtd2 [[0;32m OK [0m] Found device /dev/ttyS0. [[0;32m OK [0m] Found device /dev/hvc0. [[0;32m OK [0m] Started Serial Getty on hvc0. [[0;32m OK [0m] Started Serial Getty on ttyS0. [[0;32m OK [0m] Reached target Login Prompts. Starting Network Name Resolution... [[0;32m OK [0m] Reached target Multi-User System. random: crng init done [[0;32m OK [0m] Started Network Name Resolution. [[0;32m OK [0m] Reached target Host and Network Name Lookups. [[0;32m OK [0m] Reached target Network. [[0;32m OK [0m] Started NFS status monitor for NFSv2/3 locking.. evatronix-nand 40102000.nand: Uncorrected errors! ubi0 warning: ubi_io_read: error -74 (ECC error) while reading 64 bytes from PEB 3582:0, read only 64 bytes, retry evatronix-nand 40102000.nand: Uncorrected errors! ubi0 warning: ubi_io_read: error -74 (ECC error) while reading 64 bytes from PEB 3582:0, read only 64 bytes, retry evatronix-nand 40102000.nand: Uncorrected errors! ubi0 warning: ubi_io_read: error -74 (ECC error) while reading 64 bytes from PEB 3582:0, read only 64 bytes, retry evatronix-nand 40102000.nand: Uncorrected errors! ubi0 error: ubi_io_read: error -74 (ECC error) while reading 64 bytes from PEB 3582:0, read 64 bytes CPU: 1 PID: 191 Comm: ubiattach Not tainted 4.9.0-dip-sch #1 Hardware name: Renesas RZ/N1 (DT) [<80220720>] (unwind_backtrace) from [<8021ce9c>] (show_stack+0x10/0x14) [<8021ce9c>] (show_stack) from [<80552570>] (dump_stack+0x7c/0x9c) [<80552570>] (dump_stack) from [<80634164>] (ubi_io_read+0x208/0x2d4) [<80634164>] (ubi_io_read) from [<8063444c>] (ubi_io_read_ec_hdr+0x68/0x1d0) [<8063444c>] (ubi_io_read_ec_hdr) from [<80638ecc>] (ubi_attach+0x2c0/0x112c) [<80638ecc>] (ubi_attach) from [<8062f068>] (ubi_attach_mtd_dev+0x614/0xad8) [<8062f068>] (ubi_attach_mtd_dev) from [<8063049c>] (ctrl_cdev_ioctl+0xe8/0x1b8) [<8063049c>] (ctrl_cdev_ioctl) from [<803046bc>] (vfs_ioctl+0x20/0x38) [<803046bc>] (vfs_ioctl) from [<80304874>] (do_vfs_ioctl+0x94/0x7a0) [<80304874>] (do_vfs_ioctl) from [<80304fd0>] (SyS_ioctl+0x50/0x6c) [<80304fd0>] (SyS_ioctl) from [<80219ec0>] (ret_fast_syscall+0x0/0x34) evatronix-nand 40102000.nand: Uncorrected errors! ubi0 warning: ubi_io_read: error -74 (ECC error) while reading 64 bytes from PEB 3583:0, read only 64 bytes, retry evatronix-nand 40102000.nand: Uncorrected errors! ubi0 warning: ubi_io_read: error -74 (ECC error) while reading 64 bytes from PEB 3583:0, read only 64 bytes, retry evatronix-nand 40102000.nand: Uncorrected errors! ubi0 warning: ubi_io_read: error -74 (ECC error) while reading 64 bytes from PEB 3583:0, read only 64 bytes, retry evatronix-nand 40102000.nand: Uncorrected errors! ubi0 error: ubi_io_read: error -74 (ECC error) while reading 64 bytes from PEB 3583:0, read 64 bytes CPU: 1 PID: 191 Comm: ubiattach Not tainted 4.9.0-dip-sch #1 Hardware name: Renesas RZ/N1 (DT) [<80220720>] (unwind_backtrace) from [<8021ce9c>] (show_stack+0x10/0x14) [<8021ce9c>] (show_stack) from [<80552570>] (dump_stack+0x7c/0x9c) [<80552570>] (dump_stack) from [<80634164>] (ubi_io_read+0x208/0x2d4) [<80634164>] (ubi_io_read) from [<8063444c>] (ubi_io_read_ec_hdr+0x68/0x1d0) [<8063444c>] (ubi_io_read_ec_hdr) from [<80638ecc>] (ubi_attach+0x2c0/0x112c) [<80638ecc>] (ubi_attach) from [<8062f068>] (ubi_attach_mtd_dev+0x614/0xad8) [<8062f068>] (ubi_attach_mtd_dev) from [<8063049c>] (ctrl_cdev_ioctl+0xe8/0x1b8) [<8063049c>] (ctrl_cdev_ioctl) from [<803046bc>] (vfs_ioctl+0x20/0x38) [<803046bc>] (vfs_ioctl) from [<80304874>] (do_vfs_ioctl+0x94/0x7a0) [<80304874>] (do_vfs_ioctl) from [<80304fd0>] (SyS_ioctl+0x50/0x6c) [<80304fd0>] (SyS_ioctl) from [<80219ec0>] (ret_fast_syscall+0x0/0x34) ubi0: scanning is finished ubi0 error: ubi_read_volume_table: the layout volume was not found ubi0 error: ubi_attach_mtd_dev: failed to attach mtd2, error -22 DIP (DIP Reference Distro) 2.5+snapshot rzn1-snarc hvc0 Matched prompt #8: login: case: kernel-messages
case_id: 20950
definition: lava
duration: 13.18
extra: ...
level: 2.4.4
namespace: common
result: pass
Sending username root root rzn1-snarc login: root Waiting for password prompt auto-login-action: Wait for prompt ['Current password: ', 'New password: ', 'Retype new password: ', 'root@rzn1-snarc:', 'Password:', 'Login timed out'] (timeout 00:09:07) DIP (DIP Reference Distro) 2.5+snapshot rzn1-snarc ttyS0 rzn1-snarc login: root \"System is booting up. See pam_nologin(8)\" Matched prompt #4: Password: Sending password P@ssword-1 P@ssword-1 Password: P@ssword-1 auto-login-action: Wait for prompt ['Current password: ', 'New password: ', 'Retype new password: ', 'root@rzn1-snarc:', 'Login incorrect', 'Login timed out'] (timeout 00:09:06) You are required to change your password immediately (administrator enforced) Changing password for root. Running login commands P@ssword-1 Current password: P@ssword-1 azertAZERT12345 New password: azertAZERT12345 azertAZERT12345 Retype new password: azertAZERT12345 azertAZERT12345 Retype new password: azertAZERT12345 end: 2.4.4 auto-login-action (duration 00:00:24) [common] case: auto-login-action
case_id: 20951
definition: lava
duration: 23.70
extra: ...
level: 2.4.4
namespace: common
result: pass
start: 2.4.5 expect-shell-connection (timeout 00:09:02) [common] Forcing a shell prompt, looking for ['Current password: ', 'New password: ', 'Retype new password: ', 'root@rzn1-snarc:'] 7[r[999;999H[6nroot@rzn1-snarc:~# expect-shell-connection: Wait for prompt ['Current password: ', 'New password: ', 'Retype new password: ', 'root@rzn1-snarc:'] (timeout 00:10:00) Waiting using forced prompt support. 299.97415602207184s timeout end: 2.4.5 expect-shell-connection (duration 00:00:00) [common] start: 2.4.6 overlay-unpack (timeout 00:09:02) [common] rm overlay-1.2.2.5.tar.gz root@rzn1-snarc:~# rm overlay-1.2.2.5.tar.gz rm overlay-1.2.2.5.tar.gz rm: can't remove 'overlay-1.2.2.5.tar.gz': No such file or directory unset http_proxy ; dhclient eth1 ; cd /tmp ; wget http://10.2.1.1/tmp/lavafed-1018/compress-overlay-dp82ivm7/overlay-1.2.2.5.tar.gz root@rzn1-snarc:~# unset http_proxy ; dhclient eth1 ; cd /tmp ; wget http://10.2.1.1/tmp/lavafed-1018/compress-overlay-dp82ivm7/overlay-1.2.2.5.tar.gz unset http_proxy ; dhclient eth1 ; cd /tmp ; wget http://10.2..1.1/tmp/lavafed-1018/compress-overlay-dp82ivm7/overlay-1.2.2.5.tar.gz stmmac_open: No MAC address defined in QSPI or NAND stmmac_open: Use random MAC address stmmaceth 44002000.ethernet eth1: IEEE 1588-2008 Advanced Timestamp supported stmmaceth 44002000.ethernet eth1: registered PTP clock IPv6: ADDRCONF(NETDEV_UP): eth1: link is not ready stmmaceth 44002000.ethernet eth1: Link is Up - 1Gbps/Full - flow control off IPv6: ADDRCONF(NETDEV_CHANGE): eth1: link becomes ready chown: unrecognized option '--reference=/etc/resolv.conf' BusyBox v1.27.2 (2019-01-15 15:55:45 UTC) multi-call binary. Usage: chown [-RhLHPcvf]... USER[:[GRP]] FILE... chmod: unrecognized option '--reference=/etc/resolv.conf' BusyBox v1.27.2 (2019-01-15 15:55:45 UTC) multi-call binary. Usage: chmod [-Rcvf] MODE[,MODE]... FILE... --2019-01-15 16:13:31-- http://10.2.1.1/tmp/lavafed-1018/compress-overlay-dp82ivm7/overlay-1.2.2.5.tar.gz Connecting to 10.2.1.1:80... connected. HTTP request sent, awaiting response... 200 OK Length: 37736 (37K) [application/x-gzip] Saving to: 'overlay-1.2.2.5.tar.gz' overlay-1.2.2.5.tar 0%[ ] 0 --.-KB/s overlay-1.2.2.5.tar 100%[===================>] 36.85K --.-KB/s in 0.001s 2019-01-15 16:13:31 (32.6 MB/s) - 'overlay-1.2.2.5.tar.gz' saved [37736/37736] tar -C / -xzf overlay-1.2.2.5.tar.gz root@rzn1-snarc:/tmp# tar -C / -xzf overlay-1.2.2.5.tar.gz tar -C / -xzf overlay-1.2.2.5.tar.gz end: 2.4.6 overlay-unpack (duration 00:00:06) [common] start: 2.4.7 export-device-env (timeout 00:08:55) [common] end: 2.4.7 export-device-env (duration 00:00:00) [common] end: 2.4 uboot-retry (duration 00:01:04) [common] end: 2 uboot-action (duration 00:01:04) [common] start: 3 lava-test-retry (timeout 00:05:00) [common] start: 3.1 lava-test-shell (timeout 00:05:00) [common] Using namespace: common # root@rzn1-snarc:/tmp# # lava-test-shell: Wait for prompt ['Current password: ', 'New password: ', 'Retype new password: ', 'root@rzn1-snarc:'] (timeout 00:05:00) # Using /lava-1018 export SHELL=/bin/sh root@rzn1-snarc:/tmp# export SHELL=/bin/sh export SHELL=/bin/sh . /lava-1018/environment root@rzn1-snarc:/tmp# . /lava-1018/environment . /lava-1018/environment /lava-1018/bin/lava-test-runner /lava-1018/0 root@rzn1-snarc:/tmp# /lava-1018/bin/lava-test-runner /lava-1018/0 Test shell timeout: 10s (minimum of the action and connection timeout) /lava-1018/bin/lava-test-runner /lava-1018/0 + export TESTRUN_ID=0_smoke-tests + TESTRUN_ID=0_smoke-tests + cd /lava-1018/0/tests/0_smoke-tests ++ cat uuid + UUID=1018_1.2.2.4.1 + set +x <LAVA_SIGNAL_STARTRUN 0_smoke-tests 1018_1.2.2.4.1> + lava-test-case linux-posix-pwd --shell pwd <LAVA_SIGNAL_STARTTC linux-posix-pwd> /lava-1018/0/tests/0_smoke-tests <LAVA_SIGNAL_ENDTC linux-posix-pwd> <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=linux-posix-pwd RESULT=pass> Received signal: <STARTRUN> 0_smoke-tests 1018_1.2.2.4.1 Starting test lava.0_smoke-tests (1018_1.2.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: 20952
definition: 0_smoke-tests
result: pass
+ lava-test-case linux-posix-uname --shell uname -a <LAVA_SIGNAL_STARTTC linux-posix-uname> Linux rzn1-snarc 4.9.0-dip-sch #1 SMP Fri Mar 8 11:01:43 UTC 2019 armv7l GNU/Linux Received signal: <STARTTC> linux-posix-uname <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-1018/0/../bin/lava-test-case: line 27: vmstat: command not found <LAVA_SIGNAL_ENDTC linux-posix-vmstat> <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=linux-posix-vmstat RESULT=fail> Received signal: <ENDTC> linux-posix-uname Received signal: <TESTCASE> TEST_CASE_ID=linux-posix-uname RESULT=pass case: linux-posix-uname
case_id: 20953
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: 20954
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 can0 Link encap:UNSPEC HWaddr 00-00-00-00-00-00-00-00-00-00-00-00-00-00-00-00 NOARP MTU:16 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:10 RX bytes:0 (0.0 B) TX bytes:0 (0.0 B) Interrupt:63 eth0 Link encap:Ethernet HWaddr 00:00:0A:02:57:CD 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:54 Base address:0x6000 eth1 Link encap:Ethernet HWaddr A2:2C:1D:F7:23:B3 inet addr:10.15.203.243 Bcast:10.15.255.255 Mask:255.240.0.0 inet6 addr: fe80::a02c:1dff:fef7:23b3/64 Scope:Link UP BROADCAST RUNNING MULTICAST MTU:1500 Metric:1 RX packets:49 errors:0 dropped:0 overruns:0 frame:0 TX packets:29 errors:0 dropped:0 overruns:0 carrier:0 collisions:0 txqueuelen:1000 RX bytes:41727 (40.7 KiB) TX bytes:2866 (2.7 KiB) Interrupt:57 Base address:0xa000 lan0 Link encap:Ethernet HWaddr 00:00:0A:02:57:CD 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) lan1 Link encap:Ethernet HWaddr 00:00:0A:02:57:CD 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) lan2 Link encap:Ethernet HWaddr 00:00:0A:02:57:CD 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 inet addr:127.0.0.1 Mask:255.0.0.0 inet6 addr: ::1/128 Scope:Host UP LOOPBACK RUNNING MTU:65536 Metric:1 RX packets:42 errors:0 dropped:0 overruns:0 frame:0 TX packets:42 errors:0 dropped:0 overruns:0 carrier:0 collisions:0 txqueuelen:1 RX bytes:3180 (3.1 KiB) TX bytes:3180 (3.1 KiB) sit0 Link encap:IPv6-in-IPv4 NOARP MTU:1480 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) sw0p0 Link encap:Ethernet HWaddr 00:00:00:00:00:00 UP BROADCAST MULTICAST MTU:9190 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) sw0p1 Link encap:Ethernet HWaddr 00:00:00:00:00:00 inet6 addr: fe80::200:ff:fe00:0/64 Scope:Link UP BROADCAST RUNNING MULTICAST MTU:9190 Metric:1 RX packets:0 errors:0 dropped:0 overruns:0 frame:0 TX packets:0 errors:0 dropped:1 overruns:0 carrier:0 collisions:0 txqueuelen:1000 RX bytes:0 (0.0 B) TX bytes:0 (0.0 B) tunl0 Link encap:UNSPEC HWaddr 00-00-00-00-33-00-00-00-00-00-00-00-00-00-00-00 NOARP MTU:1480 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) usb0 Link encap:Ethernet HWaddr 9A:B2:6D:37:3F:45 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) <LAVA_SIGNAL_ENDTC linux-posix-ifconfig> <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=linux-posix-ifconfig RESULT=pass> Received signal: <ENDTC> linux-posix-ifconfig Received signal: <TESTCASE> TEST_CASE_ID=linux-posix-ifconfig RESULT=pass case: linux-posix-ifconfig
case_id: 20955
definition: 0_smoke-tests
result: pass
+ lava-test-case linux-posix-lscpu --shell lscpu <LAVA_SIGNAL_STARTTC linux-posix-lscpu> /lava-1018/0/../bin/lava-test-case: line 27: lscpu: command 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> 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: 20956
definition: 0_smoke-tests
result: fail
Received signal: <STARTTC> linux-posix-lsb_release /lava-1018/0/../bin/lava-test-case: line 27: lsb_release: command not found <LAVA_SIGNAL_ENDTC linux-posix-lsb_release> Received 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 1018_1.2.2.4.1> <LAVA_TEST_RUNNER EXIT> Received signal: <TESTCASE> TEST_CASE_ID=linux-posix-lsb_release RESULT=fail case: linux-posix-lsb_release
case_id: 20957
definition: 0_smoke-tests
result: fail
Received signal: <ENDRUN> 0_smoke-tests 1018_1.2.2.4.1 Ending use of test pattern. Ending test lava.0_smoke-tests (1018_1.2.2.4.1), duration 0.57 case: 0_smoke-tests
case_id: 20958
commit_id: 0f8952f18e992371f8686c59b36f44e9c6a94a1a
definition: lava
duration: 0.57
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: 1018_1.2.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' 'pdu01' '--command' 'off' '--port' '14' >> pdu01 port 14 now powered off Returned 0 in 1 seconds end: 4.1 power-off (duration 00:00:01) [common] case: power-off
case_id: 20959
definition: lava
duration: 1.48
extra: ...
level: 4.1
namespace: common
result: pass
start: 4.2 read-feedback (timeout 00:00:29) [common] Listened to connection for namespace 'common' for 1s Finalising connection for namespace 'common' end: 4.2 read-feedback (duration 00:00:01) [common] end: 4 finalize (duration 00:00:03) [common] Cleaning after the job Cleaning up download directory: /var/lib/lava/dispatcher/tmp/lavafed-1018/tftp-deploy-8_xxyays/kernel Override tmp directory removed at /var/lib/lava/dispatcher/tmp/lavafed-1018 Root tmp directory removed at /var/lib/lava/dispatcher/tmp/lavafed-1018 Job finished correctly case: job
case_id: 20960
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.

features.1.type
device
features.1.action
boot
device.type
rzn1d
slave.version
2019.07.0010.g7c3c02c6b
features.2.type
device
features.1.name
boot.u-boot.fit
features.2.name
test.definition.git
job.name
rzn1d - health-check
features.2.description
Test definition from git
lab.name
validation.linaro.org
job.type
test
features.2.action
test
device.name
lces2-01
features.0.action
deploy
job.url
https://federation.lavasoftware.org/lava/scheduler/job/{id}
features.0.description
Deploy to tftp
slave.arch
amd64
features.1.description
Boot from u-boot with a fit image
features.0.type
device
features.0.name
deploy.tftp
device type
hostname
timeouts
  • actions: {'seconds': 10}{'minutes': 3}{'seconds': 30}{'minutes': 10}{'minutes': 2}{'minutes': 20}{'minutes': 3}{'minutes': 3}{'minutes': 2}{'minutes': 3}{'minutes': 2}{'minutes': 5}{'seconds': 45}{'minutes': 3}{'minutes': 3}{'minutes': 2}{'minutes': 5}{'minutes': 10}{'seconds': 30} power-offuboot-retrybootloader-interruptdd-imageboot-qemu-imageflash-uboot-umslava-test-shelluboot-actionauto-login-actionbootloader-commandsboot-image-retryhttp-downloadumount-retrybootloader-retrybootloader-actionapply-overlay-imagedownload-retrynfs-deployu-boot-interrupt
  • connections: {'seconds': 30}{'seconds': 30}{'minutes': 2}{'seconds': 30}{'seconds': 10}{'minutes': 10}{'seconds': 30} bootloader-commandsuboot-retryauto-login-actionbootloader-interruptlava-test-shelldd-imageu-boot-interrupt
device action methods
boot:
  • u-boot:
    • interrupt_char:
    • interrupt_prompt: Hit any key to stop autoboot
    • mkimage_arch: arm
    • needs_interrupt: False
    • bootloader_prompt: =>
  • minimal:
  • dfu:
    • enter-commands: ['dfu']
    • command: dfu-util
  • ssh:
deploy:
  • nbd:
  • tftp:
  • lxc:
  • ssh:
  • overlay:
  • usb:
  • image:
target
...
timeouts
  • job: {'seconds': 600}

  1. kernel
    {'type': 'uimage', 'url': 'http://images.validation.linaro.org/snapshots.linaro.org/openembedded/schneider/linaro-sumo/rzn1d/38/dip/fitImage-1.0-r0-rzn1-snarc.itb'}
    deployment_data
    {}
    Timeout
    • 5 minutes
    to
    tftp
    namespace
    common

  1. prompts
    ['Current password: ', 'New password: ', 'Retype new password: ', 'root@rzn1-snarc:']
    Timeout
    • 10 minutes
    method
    u-boot
    namespace
    common
    commands
    fit
    auto_login
    {'password': 'P@ssword-1', 'login_prompt': 'login:', 'login_commands': ['P@ssword-1', 'azertAZERT12345', 'azertAZERT12345', 'azertAZERT12345'], 'password_prompt': 'Password:', 'username': 'root'}
    transfer_overlay
    {'download_command': 'unset http_proxy ; dhclient eth1 ; cd /tmp ; wget', 'unpack_command': 'tar -C / -xzf'}

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

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