Root tmp directory created at /var/lib/lava/dispatcher/tmp/3715 start: 0 validate no device environment specified booti, bootm and bootz are being deprecated soon, please use 'image','uimage' or 'zimage' validate duration: 0.01 start: 1 tftp-deploy (max 300s) start: 1.1 download_retry (max 300s) start: 1.1.1 file_download (max 300s) No compression specified. downloading file:///users/matb_data/soft4squash/SW13_tag_opensdk-image-weston-2.1.13_RC1_SW_decode_extra/uImage as /var/lib/lava/dispatcher/tmp/3715/tftp-deploy-FGl9Hq/uImage total size: 5658480 (5MB) progress 0% (0MB) progress 5% (0MB) progress 10% (0MB) progress 15% (0MB) progress 20% (1MB) progress 25% (1MB) progress 30% (1MB) progress 35% (1MB) progress 40% (2MB) progress 45% (2MB) progress 50% (2MB) progress 55% (2MB) progress 60% (3MB) progress 65% (3MB) progress 70% (3MB) progress 75% (4MB) progress 80% (4MB) progress 85% (4MB) progress 90% (4MB) progress 95% (5MB) progress 100% (5MB) 5MB downloaded in 0.07s (73.75MB/s) md5sum of downloaded content: 526da7426b8486ccd789369410053d7c sha256sum of downloaded content: f472d2aca15f68ddc0dded5c6d6fb07050320ac5010188b1b335ebba94114579 file_download duration: 0.08 download_retry duration: 0.08 start: 1.2 download_retry (max 300s) start: 1.2.1 file_download (max 300s) No compression specified. downloading file:///users/matb_data/soft4squash/SW13_tag_opensdk-image-weston-2.1.13_RC1_SW_decode_extra/stih410-b2260.dtb as /var/lib/lava/dispatcher/tmp/3715/tftp-deploy-FGl9Hq/stih410-b2260.dtb total size: 90774 (0MB) progress 36% (0MB) progress 72% (0MB) progress 100% (0MB) 0MB downloaded in 0.00s (18.74MB/s) md5sum of downloaded content: 936241aec81973d5fd6fb06115cd02f6 sha256sum of downloaded content: 9284c877f4fa8b12792d05b5521a1ec0541064c1d6828322a0a308aad4ca95fd file_download duration: 0.01 download_retry duration: 0.01 start: 1.3 download_retry (max 300s) start: 1.3.1 file_download (max 300s) Using xz decompression downloading file:///users/matb_data/soft4squash/SW13_tag_opensdk-image-weston-2.1.13_RC1_SW_decode_extra/opensdk-image-weston-st-core-extra-sti-lts.tar.xz as /var/lib/lava/dispatcher/tmp/3715/tftp-deploy-FGl9Hq/opensdk-image-weston-st-core-extra-sti-lts.tar total size: 126542876 (120MB) progress 0% (0MB) progress 5% (6MB) progress 10% (12MB) progress 15% (18MB) progress 20% (24MB) progress 25% (30MB) progress 30% (36MB) progress 35% (42MB) progress 40% (48MB) progress 45% (54MB) progress 50% (60MB) progress 55% (66MB) progress 60% (72MB) progress 65% (78MB) progress 70% (84MB) progress 75% (90MB) progress 80% (96MB) progress 85% (102MB) progress 90% (108MB) progress 95% (114MB) progress 100% (120MB) 120MB downloaded in 11.66s (10.35MB/s) md5sum of downloaded content: 252f431178dccc3dafec1525df89df08 sha256sum of downloaded content: d25df453e9fa4edcaf418f740319758cb316d1494155ee0f8e683214c78a8ce6 file_download duration: 11.67 download_retry duration: 11.67 start: 1.4 prepare-tftp-overlay (max 300s) start: 1.4.1 extract-nfsrootfs (max 300s) Extracted nfsroot to /var/lib/lava/dispatcher/tmp/3715/extract-nfsrootfs-LFbihlextract-nfsrootfs duration: 9.82start: 1.4.2 lava-overlay (max 300s)[common] Preparing overlay tarball in /var/lib/lava/dispatcher/tmp/3715/lava-overlay-PrSxSwmakedir: /var/lib/lava/dispatcher/tmp/3715/lava-overlay-PrSxSw/lava-3715/binmakedir: /var/lib/lava/dispatcher/tmp/3715/lava-overlay-PrSxSw/lava-3715/testsmakedir: /var/lib/lava/dispatcher/tmp/3715/lava-overlay-PrSxSw/lava-3715/resultsCreating /var/lib/lava/dispatcher/tmp/3715/lava-overlay-PrSxSw/lava-3715/bin/lava-test-shellCreating /var/lib/lava/dispatcher/tmp/3715/lava-overlay-PrSxSw/lava-3715/bin/lava-echo-ipv4Creating /var/lib/lava/dispatcher/tmp/3715/lava-overlay-PrSxSw/lava-3715/bin/lava-test-case-metadataCreating /var/lib/lava/dispatcher/tmp/3715/lava-overlay-PrSxSw/lava-3715/bin/lava-background-process-startCreating /var/lib/lava/dispatcher/tmp/3715/lava-overlay-PrSxSw/lava-3715/bin/lava-test-run-attachCreating /var/lib/lava/dispatcher/tmp/3715/lava-overlay-PrSxSw/lava-3715/bin/lava-background-process-stopCreating /var/lib/lava/dispatcher/tmp/3715/lava-overlay-PrSxSw/lava-3715/bin/lava-vm-groups-setup-hostCreating /var/lib/lava/dispatcher/tmp/3715/lava-overlay-PrSxSw/lava-3715/bin/lava-installed-packagesCreating /var/lib/lava/dispatcher/tmp/3715/lava-overlay-PrSxSw/lava-3715/bin/lava-os-buildCreating /var/lib/lava/dispatcher/tmp/3715/lava-overlay-PrSxSw/lava-3715/bin/lava-test-case-attachCreating /var/lib/lava/dispatcher/tmp/3715/lava-overlay-PrSxSw/lava-3715/bin/lava-test-setCreating /var/lib/lava/dispatcher/tmp/3715/lava-overlay-PrSxSw/lava-3715/bin/lava-test-runnerCreating /var/lib/lava/dispatcher/tmp/3715/lava-overlay-PrSxSw/lava-3715/bin/lava-test-caseCreating /var/lib/lava/dispatcher/tmp/3715/lava-overlay-PrSxSw/lava-3715/bin/lava-install-packagesCreating /var/lib/lava/dispatcher/tmp/3715/lava-overlay-PrSxSw/lava-3715/bin/lava-installed-packagesUpdating /var/lib/lava/dispatcher/tmp/3715/lava-overlay-PrSxSw/lava-3715/bin/lava-target-ipUpdating /var/lib/lava/dispatcher/tmp/3715/lava-overlay-PrSxSw/lava-3715/bin/lava-target-macUpdating /var/lib/lava/dispatcher/tmp/3715/lava-overlay-PrSxSw/lava-3715/bin/lava-test-runnerUpdating /var/lib/lava/dispatcher/tmp/3715/lava-overlay-PrSxSw/lava-3715/bin/lava-test-casestart: 1.4.2.1 lava-vland-overlay (max 300s)skipped lava-vland-overlaylava-vland-overlay duration: 0.00start: 1.4.2.2 lava-multinode-overlay (max 300s)skipped lava-multinode-overlaylava-multinode-overlay duration: 0.00start: 1.4.2.3 test-definition (max 300s)Loading test definitionsstart: 1.4.2.3.1 git-repo-action (max 300s)Using /lava-3715 at stage 0Fetching tests from /local/dev/frq08954/dispatcher/lava-testsRunning '/usr/bin/git clone /local/dev/frq08954/dispatcher/lava-tests /var/lib/lava/dispatcher/tmp/3715/lava-overlay-PrSxSw/lava-3715/0/tests/0_TC_BENCH_DISK_AIO_STRESS'Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/3715/lava-overlay-PrSxSw/lava-3715/0/tests/0_TC_BENCH_DISK_AIO_STRESS/tests-def/benchmark/TC_BENCH_DISK_AIO_STRESS.yamluuid=3715_1.4.2.3.1 testdef={'testdef_pattern': {'pattern': '^(?P<test_case_id>\\w+) RESULT:(?P<result>(pass|fail|unknown))', 'fixupdict': {'FAILED': 'fail', 'SUCCESS': 'pass', 'ABORTED': 'unknown'}}}git-repo-action duration: 2.38case: git-repo-action
definition: lava
result: pass
level: 1.4.2.3.1
extra: ...
start: 1.4.2.3.2 test-overlay (max 300s)test-overlay duration: 0.01case: test-overlay
definition: lava
result: pass
level: 1.4.2.3.2
extra: ...
start: 1.4.2.3.3 test-install-overlay (max 300s)test-install-overlay duration: 0.02case: test-install-overlay
definition: lava
result: pass
level: 1.4.2.3.3
extra: ...
start: 1.4.2.3.4 test-runscript-overlay (max 300s)runner path: /var/lib/lava/dispatcher/tmp/3715/lava-overlay-PrSxSw/lava-3715/0/tests/0_TC_BENCH_DISK_AIO_STRESS test_uuid 3715_1.4.2.3.1test-runscript-overlay duration: 0.02case: test-runscript-overlay
definition: lava
result: pass
level: 1.4.2.3.4
extra: ...
start: 1.4.2.3.5 git-repo-action (max 300s)Using /lava-3715 at stage 1Fetching tests from /local/dev/frq08954/dispatcher/lava-testsRunning '/usr/bin/git clone /local/dev/frq08954/dispatcher/lava-tests /var/lib/lava/dispatcher/tmp/3715/lava-overlay-PrSxSw/lava-3715/1/tests/1_TC_BENCH_DISK_HDPARM_READ'Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/3715/lava-overlay-PrSxSw/lava-3715/1/tests/1_TC_BENCH_DISK_HDPARM_READ/tests-def/benchmark/TC_BENCH_DISK_HDPARM_READ.yamluuid=3715_1.4.2.3.5 testdef={'testdef_pattern': {'pattern': '^(?P<test_case_id>\\w+) RESULT:(?P<result>(pass|fail|unknown))', 'fixupdict': {'FAILED': 'fail', 'SUCCESS': 'pass', 'ABORTED': 'unknown'}}}git-repo-action duration: 0.13case: git-repo-action
definition: lava
result: pass
level: 1.4.2.3.5
extra: ...
start: 1.4.2.3.6 test-overlay (max 300s)test-overlay duration: 0.01case: test-overlay
definition: lava
result: pass
level: 1.4.2.3.6
extra: ...
start: 1.4.2.3.7 test-install-overlay (max 300s)test-install-overlay duration: 0.01case: test-install-overlay
definition: lava
result: pass
level: 1.4.2.3.7
extra: ...
start: 1.4.2.3.8 test-runscript-overlay (max 300s)runner path: /var/lib/lava/dispatcher/tmp/3715/lava-overlay-PrSxSw/lava-3715/1/tests/1_TC_BENCH_DISK_HDPARM_READ test_uuid 3715_1.4.2.3.5test-runscript-overlay duration: 0.02case: test-runscript-overlay
definition: lava
result: pass
level: 1.4.2.3.8
extra: ...
start: 1.4.2.3.9 git-repo-action (max 300s)Using /lava-3715 at stage 2Fetching tests from /local/dev/frq08954/dispatcher/lava-testsRunning '/usr/bin/git clone /local/dev/frq08954/dispatcher/lava-tests /var/lib/lava/dispatcher/tmp/3715/lava-overlay-PrSxSw/lava-3715/2/tests/2_TC_BENCH_DISK_IOZONE_READ'Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/3715/lava-overlay-PrSxSw/lava-3715/2/tests/2_TC_BENCH_DISK_IOZONE_READ/tests-def/benchmark/TC_BENCH_DISK_IOZONE_READ.yamluuid=3715_1.4.2.3.9 testdef={'testdef_pattern': {'pattern': '^(?P<test_case_id>\\w+) RESULT:(?P<result>(pass|fail|unknown))', 'fixupdict': {'FAILED': 'fail', 'SUCCESS': 'pass', 'ABORTED': 'unknown'}}}git-repo-action duration: 0.16case: git-repo-action
definition: lava
result: pass
level: 1.4.2.3.9
extra: ...
start: 1.4.2.3.10 test-overlay (max 300s)test-overlay duration: 0.01case: test-overlay
definition: lava
result: pass
level: 1.4.2.3.10
extra: ...
start: 1.4.2.3.11 test-install-overlay (max 300s)test-install-overlay duration: 0.01case: test-install-overlay
definition: lava
result: pass
level: 1.4.2.3.11
extra: ...
start: 1.4.2.3.12 test-runscript-overlay (max 300s)runner path: /var/lib/lava/dispatcher/tmp/3715/lava-overlay-PrSxSw/lava-3715/2/tests/2_TC_BENCH_DISK_IOZONE_READ test_uuid 3715_1.4.2.3.9test-runscript-overlay duration: 0.01case: test-runscript-overlay
definition: lava
result: pass
level: 1.4.2.3.12
extra: ...
start: 1.4.2.3.13 git-repo-action (max 300s)Using /lava-3715 at stage 3Fetching tests from /local/dev/frq08954/dispatcher/lava-testsRunning '/usr/bin/git clone /local/dev/frq08954/dispatcher/lava-tests /var/lib/lava/dispatcher/tmp/3715/lava-overlay-PrSxSw/lava-3715/3/tests/3_TC_BENCH_DISK_IOZONE_WRITE'Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/3715/lava-overlay-PrSxSw/lava-3715/3/tests/3_TC_BENCH_DISK_IOZONE_WRITE/tests-def/benchmark/TC_BENCH_DISK_IOZONE_WRITE.yamluuid=3715_1.4.2.3.13 testdef={'testdef_pattern': {'pattern': '^(?P<test_case_id>\\w+) RESULT:(?P<result>(pass|fail|unknown))', 'fixupdict': {'FAILED': 'fail', 'SUCCESS': 'pass', 'ABORTED': 'unknown'}}}git-repo-action duration: 0.13case: git-repo-action
definition: lava
result: pass
level: 1.4.2.3.13
extra: ...
start: 1.4.2.3.14 test-overlay (max 300s)test-overlay duration: 0.01case: test-overlay
definition: lava
result: pass
level: 1.4.2.3.14
extra: ...
start: 1.4.2.3.15 test-install-overlay (max 300s)test-install-overlay duration: 0.01case: test-install-overlay
definition: lava
result: pass
level: 1.4.2.3.15
extra: ...
start: 1.4.2.3.16 test-runscript-overlay (max 300s)runner path: /var/lib/lava/dispatcher/tmp/3715/lava-overlay-PrSxSw/lava-3715/3/tests/3_TC_BENCH_DISK_IOZONE_WRITE test_uuid 3715_1.4.2.3.13test-runscript-overlay duration: 0.01case: test-runscript-overlay
definition: lava
result: pass
level: 1.4.2.3.16
extra: ...
lava-test-runner.conf name TC_BENCH_DISK_HDPARM_READ stage 1Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/3715/lava-overlay-PrSxSw/lava-3715/1Writing to runner_conf test-definition 1lava-test-runner.conf name TC_BENCH_DISK_IOZONE_WRITE stage 3Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/3715/lava-overlay-PrSxSw/lava-3715/3Writing to runner_conf test-definition 3lava-test-runner.conf name TC_BENCH_DISK_IOZONE_READ stage 2Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/3715/lava-overlay-PrSxSw/lava-3715/2Writing to runner_conf test-definition 2lava-test-runner.conf name TC_BENCH_DISK_AIO_STRESS stage 0Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/3715/lava-overlay-PrSxSw/lava-3715/0Writing to runner_conf test-definition 0test-definition duration: 3.01start: 1.4.2.4 compress-overlay (max 300s)compress-overlay duration: 26.21start: 1.4.2.5 persistent-nfs-overlay (max 300s)persistent-nfs-overlay duration: 0.00lava-overlay duration: 29.42start: 1.4.3 extract-overlay-ramdisk (max 300s)extract-overlay-ramdisk duration: 0.00start: 1.4.4 extract-modules (max 300s)extract-modules duration: 0.00start: 1.4.5 apply-overlay-tftp (max 300s)Applying overlay to NFSapply-overlay-tftp duration: 10.76start: 1.4.6 configure-preseed-file (max 300s)configure-preseed-file duration: 0.00start: 1.4.7 compress-ramdisk (max 300s)compress-ramdisk duration: 0.00prepare-tftp-overlay duration: 50.00start: 1.5 deploy-device-env (max 300s)deploy-device-env duration: 0.00tftp-deploy duration: 61.76start: 2 uboot-action (max 600s)start: 2.1 uboot-prepare-kernel (max 600s)uboot-prepare-kernel duration: 0.00start: 2.2 uboot-from-media (max 600s)uboot-from-media duration: 0.00start: 2.3 uboot-overlay (max 600s)Parsed boot commands: setenv autoload no; setenv initrd_high '0xffffffff'; setenv fdt_high '0xffffffff'; setenv kernel_addr_r '0x60000000'; setenv initrd_addr_r '0x45000000'; setenv fdt_addr_r '0x47000000'; setenv loadkernel 'tftp ${kernel_addr_r} 3715/tftp-deploy-FGl9Hq/uImage'; setenv loadinitrd 'tftp ${initrd_addr_r} {RAMDISK}; setenv initrd_size ${filesize}'; setenv loadfdt 'tftp ${fdt_addr_r} 3715/tftp-deploy-FGl9Hq/stih410-b2260.dtb'; setenv nfsargs 'setenv bootargs console=ttyAS1,115200 consoleblank=0 ip=::::stiH410::dhcp kmac=dev:eth0,addr:00:80:E1:12:81:5F mem=992M@0x40000000 vmalloc=256m clk_ignore_unused root=/dev/nfs rw nfsroot=10.48.1.24:/var/lib/lava/dispatcher/tmp/3715/extract-nfsrootfs-LFbihl,tcp,hard,intr earlyprintk'; setenv bootcmd 'dhcp; setenv serverip 10.48.1.24; run loadkernel; run loadfdt; run nfsargs; bootm 0x60000000 - 0x47000000'; setenv load_env_addr 0x40000000; setenv filesize b7e9; setenv ethaddr 00:80:E1:12:81:5F; setenv ipaddr 10.48.2.150; setenv filesize b7e9; env export -t -s $filesize $load_env_addr; fatwrite mmc 0:1 $load_env_addr uenv.txt ${filesize}; bootuboot-overlay duration: 0.00start: 2.4 connect-device (max 600s)connect-device Connecting to device using 'telnet localhost 2004'connect-device duration: 0.00start: 2.5 uboot-retry (max 600s)start: 2.5.1 reboot-device (max 600s)start: 2.5.1.1 soft-reboot (max 600s)soft-reboot duration: 0.00start: 2.5.1.2 pdu_reboot (max 600s)pdu_reboot duration: 0.00start: 2.5.1.3 power_on (max 600s)nice /local/lava/server/lava-config/scripts/epower_switch.sh -u ePowerSw_01.lme.st.com -p 104 -c REBOOTcommand output Send reboot command switch ePowerSw_01.lme.st.com on prt 104 Switch OFF Power supply --2017-04-11 11:31:46-- http://epowersw_01.lme.st.com/hidden.htm?S1:O4=Off Resolving epowersw_01.lme.st.com (epowersw_01.lme.st.com)... 10.48.2.146 Connecting to epowersw_01.lme.st.com (epowersw_01.lme.st.com)|10.48.2.146|:80... connected. HTTP request sent, awaiting response... 200 OK Length: unspecified [text/html] Saving to: ���hidden.htm?S1:O4=Off.716��� 0K 332K=0.001s 2017-04-11 11:31:46 (332 KB/s) - ���hidden.htm?S1:O4=Off.716��� saved [448] Switch ON Power supply --2017-04-11 11:31:51-- http://epowersw_01.lme.st.com/hidden.htm?S1:O4=On Resolving epowersw_01.lme.st.com (epowersw_01.lme.st.com)... 10.48.2.146 Connecting to epowersw_01.lme.st.com (epowersw_01.lme.st.com)|10.48.2.146|:80... connected. HTTP request sent, awaiting response... 200 OK Length: unspecified [text/html] Saving to: ���hidden.htm?S1:O4=On.416��� 0K 449K=0.001s 2017-04-11 11:31:51 (449 KB/s) - ���hidden.htm?S1:O4=On.416��� saved [447] power_on duration: 5.10case: power_on
definition: lava
result: pass
level: 2.5.1.3
extra: ...
reboot-device duration: 5.10start: 2.5.2 u-boot-interrupt (max 600s)Changing prompt to 'Hit any key to stop autoboot'u-boot-interrupt: Wait for prompt Hit any key to stop autoboot. 240 secondsTrying ::1...Trying 127.0.0.1...Connected to localhost.Escape character is '^]'.ser2net port 2004 device /dev/ttyUSB.b2260_003 [115200 N81] (Debian GNU/Linux)<<lava: broken line>>[u-bootrom] display versionU-Boot 2015.01-st-2015 (Dec 08 2016 - 09:15:46) - stm24-2016-01-20arm-stcore-linux-gnueabi-gcc (GCC) 5.3.0GNU ld (GNU Binutils) 2.26.0.20160226[u-bootrom] starting...[u-bootrom] silently sourcing [mmc 0:1]/b2260/u-bootrom.script[bootscript] boot from b2260/u-bootrom.script-ubootreading b2260/u-bootrom.script-uboot1228 bytes read in 21 ms ( 56.6 KiB/s)reading b2260/u-boot.bin423748 bytes read in 65 ms ( 6.2 MiB/s)## Kick secondaries back in new hpen config## Secondary status update: 0x1## Starting application at 0x40000000 ...U-Boot 2015.01-st-2015 (Nov 10 2016 - 12:14:37) - stm24-2016-01-20Board: B2260-revx-STxH410 [ARM] I2C: readyDRAM: 992 MiBMMC: boot: non-eMMC.stm-sdhci0: 0SPI: Using default environmentIn: serialOut: serialErr: serialNet: STM-GMAC: entering stmac_eth_register()stmac-1Error: stmac-1 address not set. Hit any key to stop autoboot: 3 u-boot-interrupt: Wait for prompt B2260. 240 seconds 0 B2260> u-boot-interrupt duration: 2.45start: 2.5.3 expect-bootloader-connection (max 600s)expect-bootloader-connection: Waiting for promptexpect-bootloader-connection: Wait for prompt B2260. 240 secondsexpect-bootloader-connection duration: 0.00start: 2.5.4 u-boot-commands (max 600s)Changing prompt to B2260u-boot-commands: Wait for prompt B2260. 120 secondsB2260> setenv autoload no B2260> setenv autoload nou-boot-commands: Wait for prompt B2260. 120 secondssetenv autoload nosetenv initrd_high '0xffffffff' B2260> setenv initrd_high '0xffffffff'u-boot-commands: Wait for prompt B2260. 120 secondssetenv initrd_high '0xffffffff'setenv fdt_high '0xffffffff' B2260> setenv fdt_high '0xffffffff'u-boot-commands: Wait for prompt B2260. 120 secondssetenv fdt_high '0xffffffff'setenv kernel_addr_r '0x60000000' B2260> setenv kernel_addr_r '0x60000000'u-boot-commands: Wait for prompt B2260. 120 secondssetenv kernel_addr_r '0x60000000'setenv initrd_addr_r '0x45000000' B2260> setenv initrd_addr_r '0x45000000'u-boot-commands: Wait for prompt B2260. 120 secondssetenv initrd_addr_r '0x45000000'setenv fdt_addr_r '0x47000000' B2260> setenv fdt_addr_r '0x47000000'u-boot-commands: Wait for prompt B2260. 120 secondssetenv fdt_addr_r '0x47000000'setenv loadkernel 'tftp ${kernel_addr_r} 3715/tftp-deploy-FGl9Hq/uImage' B2260> setenv loadkernel 'tftp ${kernel_addr_r} 3715/tftp-deploy-FGl9Hq/uImage'u-boot-commands: Wait for prompt B2260. 120 secondssetenv loadkernel 'tftp ${kernel_addr_r} 3715/tftp-deploy-FGl9Hq/uImage'setenv loadinitrd 'tftp ${initrd_addr_r} {RAMDISK}; setenv initrd_size ${filesize}' B2260> setenv loadinitrd 'tftp ${initrd_addr_r} {RAMDISK}; setenv initrd_size ${filesize}'u-boot-commands: Wait for prompt B2260. 120 secondssetenv loadinitrd 'tftp ${initrd_addr_r} {RAMDISK}; setenv initrd_size ${filesize}'setenv loadfdt 'tftp ${fdt_addr_r} 3715/tftp-deploy-FGl9Hq/stih410-b2260.dtb' B2260> setenv loadfdt 'tftp ${fdt_addr_r} 3715/tftp-deploy-FGl9Hq/stih410-b2260.dtb'u-boot-commands: Wait for prompt B2260. 120 secondssetenv loadfdt 'tftp ${fdt_addr_r} 3715/tftp-deploy-FGl9Hq/stih410-b2260.dtb'setenv nfsargs 'setenv bootargs console=ttyAS1,115200 consoleblank=0 ip=::::stiH410::dhcp kmac=dev:eth0,addr:00:80:E1:12:81:5F mem=992M@0x40000000 vmalloc=256m clk_ignore_unused root=/dev/nfs rw nfsroot=10.48.1.24:/var/lib/lava/dispatcher/tmp/3715/extract-nfsrootfs-LFbihl,tcp,hard,intr earlyprintk' B2260> setenv nfsargs 'setenv bootargs console=ttyAS1,115200 consoleblank=0 ip=::::stiH410::dhcp kmac=dev:eth0,addr:00:80:E1:12:81:5F mem=992M@0x40000000 vmalloc=256m clk_ignore_unused root=/dev/nfs rw nfsroot=10.48.1.24:/var/lib/lava/dispatcher/tmp/3715/extract-nfsrootfs-LFbihl,tcp,hard,intr earlyprintk'u-boot-commands: Wait for prompt B2260. 120 secondssetenv nfsargs 'setenv bootargs console=ttyAS1,115200 consoleblank=0 ip=::::stiH410::dhcp kmac=dev:eth0,addr:00:80:E1:12:81:5F mem=992M@0x40000000 vmalloc=256m clk_ignore_unused root=/dev/nfs rw nfsroot=10.48.1.24:/var/lib/lava/dispatcher/tmp/3715/extract-nfsrootfs-LFbihl,tcp,hard,intr earlyprintk'setenv bootcmd 'dhcp; setenv serverip 10.48.1.24; run loadkernel; run loadfdt; run nfsargs; bootm 0x60000000 - 0x47000000' B2260> setenv bootcmd 'dhcp; setenv serverip 10.48.1.24; run loadkernel; run loadfdt; run nfsargs; bootm 0x60000000 - 0x47000000'u-boot-commands: Wait for prompt B2260. 120 secondssetenv bootcmd 'dhcp; setenv serverip 10.48.1.24; run loadkernel; run loadfdt; run nfsargs; bootm 0x60000000 - 0x47000000'setenv load_env_addr 0x40000000 B2260> setenv load_env_addr 0x40000000u-boot-commands: Wait for prompt B2260. 120 secondssetenv load_env_addr 0x40000000setenv filesize b7e9 B2260> setenv filesize b7e9u-boot-commands: Wait for prompt B2260. 120 secondssetenv filesize b7e9setenv ethaddr 00:80:E1:12:81:5F B2260> setenv ethaddr 00:80:E1:12:81:5Fu-boot-commands: Wait for prompt B2260. 120 secondssetenv ethaddr 00:80:E1:12:81:5Fsetenv ipaddr 10.48.2.150 B2260> setenv ipaddr 10.48.2.150u-boot-commands: Wait for prompt B2260. 120 secondssetenv ipaddr 10.48.2.150setenv filesize b7e9 B2260> setenv filesize b7e9u-boot-commands: Wait for prompt B2260. 120 secondssetenv filesize b7e9env export -t -s $filesize $load_env_addr B2260> env export -t -s $filesize $load_env_addru-boot-commands: Wait for prompt B2260. 120 secondsenv export -t -s $filesize $load_env_addrfatwrite mmc 0:1 $load_env_addr uenv.txt ${filesize} B2260> fatwrite mmc 0:1 $load_env_addr uenv.txt ${filesize}u-boot-commands: Wait for prompt B2260. 120 secondsfatwrite mmc 0:1 $load_env_addr uenv.txt ${filesize}writing uenv.txt47081 bytes writtenboot B2260> bootChanging prompt to Starting kernelu-boot-commands: Wait for prompt Starting kernel. 120 secondsbootSTM-GMAC: entering stmac_halt()STM-GMAC: MAC RX/TX disabledSTM-GMAC: entering stmac_init()Using MAC Address 00:80:e1:12:81:5fSTM-GMAC: REALTEK RTL8211E(G) foundSTM-GMAC: 100Mbs full duplex link detectedSTM-GMAC: stmac_set_mac_mii_cap(full_duplex=1, speed=100)STM-GMAC: allocate and init the DMA RX/TX listsSTM-GMAC: Allocating coherent descriptors Successfully allocated coherent descriptors STM-GMAC: DMA Core setupSTM-GMAC: MAC RX/TX enabledBOOTP broadcast 1*** WARNING: using vendor optional boot file*** WARNING: using vendor optional boot fileDHCP client bound to address 10.48.2.150 (56 ms)STM-GMAC: entering stmac_halt()STM-GMAC: MAC RX/TX disabledSTM-GMAC: entering stmac_halt()STM-GMAC: MAC RX/TX disabledSTM-GMAC: entering stmac_init()Using MAC Address 00:80:e1:12:81:5fSTM-GMAC: REALTEK RTL8211E(G) foundSTM-GMAC: 100Mbs full duplex link detectedSTM-GMAC: stmac_set_mac_mii_cap(full_duplex=1, speed=100)STM-GMAC: allocate and init the DMA RX/TX listsSTM-GMAC: Using already allocated descriptors.STM-GMAC: DMA Core setupSTM-GMAC: MAC RX/TX enabledUsing stmac-1 deviceTFTP from server 10.48.1.24; our IP address is 10.48.2.150Filename '3715/tftp-deploy-FGl9Hq/uImage'.Load address: 0x60000000Loading: *##########################################################T ####### ################################################################# ################################################################# ################################################################# ################################################################# ############################################################# 683.6 KiB/sdoneBytes transferred = 5658480 (565770 hex)STM-GMAC: entering stmac_halt()STM-GMAC: MAC RX/TX disabledSTM-GMAC: entering stmac_halt()STM-GMAC: MAC RX/TX disabledSTM-GMAC: entering stmac_init()Using MAC Address 00:80:e1:12:81:5fSTM-GMAC: REALTEK RTL8211E(G) foundSTM-GMAC: 100Mbs full duplex link detectedSTM-GMAC: stmac_set_mac_mii_cap(full_duplex=1, speed=100)STM-GMAC: allocate and init the DMA RX/TX listsSTM-GMAC: Using already allocated descriptors.STM-GMAC: DMA Core setupSTM-GMAC: MAC RX/TX enabledUsing stmac-1 deviceTFTP from server 10.48.1.24; our IP address is 10.48.2.150Filename '3715/tftp-deploy-FGl9Hq/stih410-b2260.dtb'.Load address: 0x47000000Loading: *####### 1.5 MiB/sdoneBytes transferred = 90774 (16296 hex)STM-GMAC: entering stmac_halt()STM-GMAC: MAC RX/TX disabled## Booting kernel from Legacy Image at 60000000 ... Image Name: Linux-4.9.0 Created: 2017-01-13 18:54:49 UTC Image Type: ARM Linux Kernel Image (uncompressed) Data Size: 5658416 Bytes = 5.4 MiB Load Address: 40008000 Entry Point: 40008000 Verifying Checksum ... OK## Flattened Device Tree blob at 47000000 Booting using the fdt blob at 0x47000000 Loading Kernel Image ... OK Using Device Tree in place at 47000000, end 47019295u-boot-commands duration: 122.40start: 2.5.5 auto-login-action (max 600s)The string 'root@' does not look like a typical prompt and could match status messages instead. Please check the job log files and use a prompt string which matches the actual prompt string more closely.Using line separator: #'\n'#Skipping of auto loginParsing kernel messages-+\[ cut here \]-+\s+(.*\s+-+\[ end trace (\w*) \]-+),(Unhandled fault.*) ,Kernel panic - (.*) end Kernel panic,Stack:\s+(.*\s+-+\[ end trace (\w*) \]-+),ALERT! .* does not exist.\s+Dropping to a shell!,root@Starting kernel ... Using FDT blob at 0x47000000Starting kernel ...[ 0.000000] Booting Linux on physical CPU 0x0[ 0.000000] Linux version 4.9.0 (acitlsqa@gnbsx50056) (gcc version 5.3.0 (GCC) ) #1 SMP Fri Jan 13 13:54:07 EST 2017[ 0.000000] CPU: ARMv7 Processor [413fc090] revision 0 (ARMv7), cr=10c5387d[ 0.000000] CPU: PIPT / VIPT nonaliasing data cache, VIPT aliasing instruction cache[ 0.000000] OF: fdt:Machine model: STiH410 B2260[ 0.000000] efi: Getting EFI parameters from FDT:[ 0.000000] efi: UEFI not found.[ 0.000000] Reserved memory: created DMA memory pool at 0x44000000, size 16 MiB[ 0.000000] OF: reserved mem: initialized node rproc@44000000, compatible id shared-dma-pool[ 0.000000] Reserved memory: created DMA memory pool at 0x45000000, size 4 MiB[ 0.000000] OF: reserved mem: initialized node rproc@45000000, compatible id shared-dma-pool[ 0.000000] cma: Reserved 256 MiB at 0x5f800000[ 0.000000] Memory policy: Data cache writealloc[ 0.000000] percpu: Embedded 14 pages/cpu @def50000 s25996 r8192 d23156 u57344[ 0.000000] Built 1 zonelists in Zone order, mobility grouping on. Total pages: 247312[ 0.000000] Kernel command line: console=ttyAS1,115200 consoleblank=0 ip=::::stiH410::dhcp kmac=dev:eth0,addr:00:80:E1:12:81:5F mem=992M@0x40000000 vmalloc=256m clk_ignore_unused root=/dev/nfs rw nfsroot=10.48.1.24:/var/lib/lava/dispatcher/tmp/3715/extract-nfsrootfs-LFbihl,tcp,hard,intr earlyprintk[ 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: 710536K/995328K available (8192K kernel code, 395K rwdata, 2212K rodata, 1024K init, 306K bss, 22648K reserved, 262144K cma-reserved, 237568K highmem)[ 0.000000] Virtual kernel memory layout:[ 0.000000] vector : 0xffff0000 - 0xffff1000 ( 4 kB)[ 0.000000] fixmap : 0xffc00000 - 0xfff00000 (3072 kB)[ 0.000000] vmalloc : 0xf0000000 - 0xff800000 ( 248 MB)[ 0.000000] lowmem : 0xc0000000 - 0xef800000 ( 760 MB)[ 0.000000] pkmap : 0xbfe00000 - 0xc0000000 ( 2 MB)[ 0.000000] modules : 0xbf000000 - 0xbfe00000 ( 14 MB)[ 0.000000] .text : 0xc0008000 - 0xc0900000 (9184 kB)[ 0.000000] .init : 0xc0c00000 - 0xc0d00000 (1024 kB)[ 0.000000] .data : 0xc0d00000 - 0xc0d62dc0 ( 396 kB)[ 0.000000] .bss : 0xc0d64000 - 0xc0db0a28 ( 307 kB)[ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1[ 0.000000] Hierarchical RCU implementation.[ 0.000000] Build-time adjustment of leaf fanout to 32.[ 0.000000] RCU restricting CPUs from NR_CPUS=16 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] L2C: platform modifies aux control register: 0x02080000 -> 0x30480000[ 0.000000] L2C: DT/platform modifies aux control register: 0x02080000 -> 0x30480000[ 0.000000] L2C: DT/platform tries to modify or specify cache size[ 0.000000] L2C-310 erratum 769419 enabled[ 0.000000] L2C-310 enabling early BRESP for Cortex-A9[ 0.000000] L2C-310 full line of zeros enabled for Cortex-A9[ 0.000000] L2C-310 dynamic clock gating enabled, standby mode enabled[ 0.000000] L2C-310 cache controller enabled, 8 ways, 1024 kB[ 0.000000] L2C-310: CACHE_ID 0x410000c8, AUX_CTRL 0x44480001[ 0.000010] sched_clock: 32 bits at 30MHz, resolution 33ns, wraps every 71582788591ns[ 0.000025] clocksource: clksrc-st-lpc: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 63708681553 ns[ 0.000031] clksrc-st-lpc: clocksource initialised - running @ 30000000Hz[ 0.000207] GIC: PPI11 is secure or misconfigured[ 0.000218] clocksource: arm_global_timer: mask: 0xffffffffffffffff max_cycles: 0xacf9151134, max_idle_ns: 440795214493 ns[ 0.000233] GIC: PPI11 is secure or misconfigured[ 0.000241] Switching to timer-based delay loop, resolution 1ns[ 0.000526] Console: colour dummy device 80x30[ 0.000539] Calibrating delay loop (skipped), value calculated using timer frequency.. 1500.00 BogoMIPS (lpj=7500000)[ 0.000547] pid_max: default: 32768 minimum: 301[ 0.000600] Mount-cache hash table entries: 2048 (order: 1, 8192 bytes)[ 0.000606] Mountpoint-cache hash table entries: 2048 (order: 1, 8192 bytes)[ 0.001001] CPU: Testing write buffer coherency: ok[ 0.001215] CPU0: thread -1, cpu 0, socket 0, mpidr 80000000[ 0.001400] Setting up static identity map for 0x40100000 - 0x40100058[ 0.004807] EFI services will not be available.[ 0.005253] GIC: PPI11 is secure or misconfigured[ 0.005262] CPU1: thread -1, cpu 1, socket 0, mpidr 80000001[ 0.005318] Brought up 2 CPUs[ 0.005329] SMP: Total of 2 processors activated (3000.00 BogoMIPS).[ 0.005333] CPU: All CPU(s) started in SVC mode.[ 0.005844] devtmpfs: initialized[ 0.018059] VFP support v0.3: implementor 41 architecture 3 part 30 variant 9 rev 4[ 0.018205] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns[ 0.027472] pinctrl core: initialized pinctrl subsystem[ 0.028261] NET: Registered protocol family 16[ 0.030567] DMA: preallocated 256 KiB pool for atomic coherent allocations[ 0.070246] cpuidle: using governor menu[ 0.070382] No ATAGs?[ 0.070398] hw-breakpoint: found 5 (+1 reserved) breakpoint and 1 watchpoint registers.[ 0.070404] hw-breakpoint: maximum watchpoint size is 4 bytes.[ 0.072018] st-pinctrl 961f080.pin-controller-sbc: nbanks = 6[ 0.072026] st-pinctrl 961f080.pin-controller-sbc: nfunctions = 12[ 0.072031] st-pinctrl 961f080.pin-controller-sbc: ngroups = 27[ 0.072277] st-pinctrl 961f080.pin-controller-sbc: PIO0 bank added.[ 0.072590] st-pinctrl 961f080.pin-controller-sbc: PIO1 bank added.[ 0.072872] st-pinctrl 961f080.pin-controller-sbc: PIO2 bank added.[ 0.073159] st-pinctrl 961f080.pin-controller-sbc: PIO3 bank added.[ 0.073445] st-pinctrl 961f080.pin-controller-sbc: PIO4 bank added.[ 0.073725] st-pinctrl 961f080.pin-controller-sbc: PIO5 bank added.[ 0.073895] st-pinctrl 961f080.pin-controller-sbc: Function[0 name:cec0, groups:1][ 0.073916] st-pinctrl 961f080.pin-controller-sbc: Function[1 name:rc, groups:4][ 0.073928] st-pinctrl 961f080.pin-controller-sbc: Function[2 name:sbc_serial0, groups:1][ 0.073940] st-pinctrl 961f080.pin-controller-sbc: Function[3 name:sbc_serial1, groups:1][ 0.073952] st-pinctrl 961f080.pin-controller-sbc: Function[4 name:i2c10, groups:1][ 0.073964] st-pinctrl 961f080.pin-controller-sbc: Function[5 name:i2c11, groups:1][ 0.073985] st-pinctrl 961f080.pin-controller-sbc: Function[6 name:keyscan, groups:1][ 0.074067] st-pinctrl 961f080.pin-controller-sbc: Function[7 name:gmac1, groups:7][ 0.074096] st-pinctrl 961f080.pin-controller-sbc: Function[8 name:pwm1, groups:4][ 0.074113] st-pinctrl 961f080.pin-controller-sbc: Function[9 name:spi10, groups:2][ 0.074130] st-pinctrl 961f080.pin-controller-sbc: Function[10 name:spi11, groups:2][ 0.074147] st-pinctrl 961f080.pin-controller-sbc: Function[11 name:spi12, groups:2][ 0.074409] st-pinctrl 920f080.pin-controller-front0: nbanks = 10[ 0.074415] st-pinctrl 920f080.pin-controller-front0: nfunctions = 22[ 0.074420] st-pinctrl 920f080.pin-controller-front0: ngroups = 48[ 0.074582] st-pinctrl 920f080.pin-controller-front0: PIO10 bank added.[ 0.074875] st-pinctrl 920f080.pin-controller-front0: PIO11 bank added.[ 0.075170] st-pinctrl 920f080.pin-controller-front0: PIO12 bank added.[ 0.075460] st-pinctrl 920f080.pin-controller-front0: PIO13 bank added.[ 0.075753] st-pinctrl 920f080.pin-controller-front0: PIO14 bank added.[ 0.076048] st-pinctrl 920f080.pin-controller-front0: PIO15 bank added.[ 0.076342] st-pinctrl 920f080.pin-controller-front0: PIO16 bank added.[ 0.076625] st-pinctrl 920f080.pin-controller-front0: PIO17 bank added.[ 0.076891] st-pinctrl 920f080.pin-controller-front0: PIO18 bank added.[ 0.077168] st-pinctrl 920f080.pin-controller-front0: PIO19 bank added.[ 0.077349] st-pinctrl 920f080.pin-controller-front0: Function[0 name:serial0, groups:3][ 0.077364] st-pinctrl 920f080.pin-controller-front0: Function[1 name:serial1, groups:1][ 0.077376] st-pinctrl 920f080.pin-controller-front0: Function[2 name:serial2, groups:1][ 0.077405] st-pinctrl 920f080.pin-controller-front0: Function[3 name:mmc1, groups:1][ 0.077418] st-pinctrl 920f080.pin-controller-front0: Function[4 name:i2c0, groups:1][ 0.077429] st-pinctrl 920f080.pin-controller-front0: Function[5 name:i2c1, groups:1][ 0.077445] st-pinctrl 920f080.pin-controller-front0: Function[6 name:i2c2, groups:2][ 0.077466] st-pinctrl 920f080.pin-controller-front0: Function[7 name:i2c3, groups:3][ 0.077493] st-pinctrl 920f080.pin-controller-front0: Function[8 name:spi0, groups:4][ 0.077520] st-pinctrl 920f080.pin-controller-front0: Function[9 name:spi1, groups:4][ 0.077562] st-pinctrl 920f080.pin-controller-front0: Function[10 name:spi2, groups:6][ 0.077598] st-pinctrl 920f080.pin-controller-front0: Function[11 name:spi3, groups:6][ 0.077632] st-pinctrl 920f080.pin-controller-front0: Function[12 name:tsin0, groups:2][ 0.077667] st-pinctrl 920f080.pin-controller-front0: Function[13 name:tsin1, groups:2][ 0.077702] st-pinctrl 920f080.pin-controller-front0: Function[14 name:tsin2, groups:2][ 0.077717] st-pinctrl 920f080.pin-controller-front0: Function[15 name:tsin3, groups:1][ 0.077732] st-pinctrl 920f080.pin-controller-front0: Function[16 name:tsin4, groups:1][ 0.077757] st-pinctrl 920f080.pin-controller-front0: Function[17 name:tsin5, groups:2][ 0.077789] st-pinctrl 920f080.pin-controller-front0: Function[18 name:tsout0, groups:2][ 0.077805] st-pinctrl 920f080.pin-controller-front0: Function[19 name:tsout1, groups:1][ 0.077833] st-pinctrl 920f080.pin-controller-front0: Function[20 name:mtsin0, groups:1][ 0.077851] st-pinctrl 920f080.pin-controller-front0: Function[21 name:systrace, groups:1][ 0.078062] st-pinctrl 921f080.pin-controller-front1: nbanks = 1[ 0.078069] st-pinctrl 921f080.pin-controller-front1: nfunctions = 1[ 0.078074] st-pinctrl 921f080.pin-controller-front1: ngroups = 1[ 0.078222] st-pinctrl 921f080.pin-controller-front1: PIO20 bank added.[ 0.078398] st-pinctrl 921f080.pin-controller-front1: Function[0 name:tsin4, groups:1][ 0.078576] st-pinctrl 922f080.pin-controller-rear: nbanks = 6[ 0.078582] st-pinctrl 922f080.pin-controller-rear: nfunctions = 12[ 0.078588] st-pinctrl 922f080.pin-controller-rear: ngroups = 17[ 0.078752] st-pinctrl 922f080.pin-controller-rear: PIO30 bank added.[ 0.079041] st-pinctrl 922f080.pin-controller-rear: PIO31 bank added.[ 0.079340] st-pinctrl 922f080.pin-controller-rear: PIO32 bank added.[ 0.079634] st-pinctrl 922f080.pin-controller-rear: PIO33 bank added.[ 0.079919] st-pinctrl 922f080.pin-controller-rear: PIO34 bank added.[ 0.080212] st-pinctrl 922f080.pin-controller-rear: PIO35 bank added.[ 0.080464] st-pinctrl 922f080.pin-controller-rear: Function[0 name:dvo, groups:1][ 0.080478] st-pinctrl 922f080.pin-controller-rear: Function[1 name:i2c4, groups:1][ 0.080491] st-pinctrl 922f080.pin-controller-rear: Function[2 name:i2c5, groups:1][ 0.080506] st-pinctrl 922f080.pin-controller-rear: Function[3 name:usb3, groups:1][ 0.080518] st-pinctrl 922f080.pin-controller-rear: Function[4 name:pwm0, groups:1][ 0.080549] st-pinctrl 922f080.pin-controller-rear: Function[5 name:spi4, groups:4][ 0.080577] st-pinctrl 922f080.pin-controller-rear: Function[6 name:i2s_out, groups:2][ 0.080606] st-pinctrl 922f080.pin-controller-rear: Function[7 name:i2s_in, groups:2][ 0.080616] st-pinctrl 922f080.pin-controller-rear: Function[8 name:spdif_out, groups:1][ 0.080628] st-pinctrl 922f080.pin-controller-rear: Function[9 name:serial3, groups:1][ 0.080640] st-pinctrl 922f080.pin-controller-rear: Function[10 name:usb0, groups:1][ 0.080652] st-pinctrl 922f080.pin-controller-rear: Function[11 name:usb1, groups:1][ 0.080860] st-pinctrl 923f080.pin-controller-flash: nbanks = 3[ 0.080867] st-pinctrl 923f080.pin-controller-flash: nfunctions = 3[ 0.080872] st-pinctrl 923f080.pin-controller-flash: ngroups = 4[ 0.081053] st-pinctrl 923f080.pin-controller-flash: PIO40 bank added.[ 0.081328] st-pinctrl 923f080.pin-controller-flash: PIO41 bank added.[ 0.081603] st-pinctrl 923f080.pin-controller-flash: PIO42 bank added.[ 0.081800] st-pinctrl 923f080.pin-controller-flash: Function[0 name:mmc0, groups:2][ 0.081821] st-pinctrl 923f080.pin-controller-flash: Function[1 name:fsm, groups:1][ 0.081859] st-pinctrl 923f080.pin-controller-flash: Function[2 name:nand, groups:1][ 0.082798] reset-stih407 soc:powerdown-controller: registered[ 0.084964] reset-stih407 soc:softreset-controller: registered[ 0.085233] reset-stih407 soc:picophyreset-controller: registered[ 0.121229] SCSI subsystem initialized[ 0.121425] usbcore: registered new interface driver usbfs[ 0.121454] usbcore: registered new interface driver hub[ 0.121480] usbcore: registered new device driver usb[ 0.121599] media: Linux media interface: v0.10[ 0.121621] Linux video capture interface: v2.00[ 0.121647] pps_core: LinuxPPS API ver. 1 registered[ 0.121652] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>[ 0.121664] PTP clock support registered[ 0.121674] EDAC MC: Ver: 3.0.0[ 0.121998] st-socinfo soc:socinfo: No syscfg-socinfo phandle specified[ 0.122264] Advanced Linux Sound Architecture Driver Initialized.[ 0.122541] Bluetooth: Core ver 2.22[ 0.122562] NET: Registered protocol family 31[ 0.122567] Bluetooth: HCI device and connection manager initialized[ 0.122576] Bluetooth: HCI socket layer initialized[ 0.122582] Bluetooth: L2CAP socket layer initialized[ 0.122597] Bluetooth: SCO socket layer initialized[ 0.123120] clocksource: Switched to clocksource clksrc-st-lpc[ 0.127949] NET: Registered protocol family 2[ 0.128247] TCP established hash table entries: 8192 (order: 3, 32768 bytes)[ 0.128295] TCP bind hash table entries: 8192 (order: 4, 65536 bytes)[ 0.128362] TCP: Hash tables configured (established 8192 bind 8192)[ 0.128442] UDP hash table entries: 512 (order: 2, 16384 bytes)[ 0.128492] UDP-Lite hash table entries: 512 (order: 2, 16384 bytes)[ 0.128621] NET: Registered protocol family 1[ 0.128863] RPC: Registered named UNIX socket transport module.[ 0.128870] RPC: Registered udp transport module.[ 0.128873] RPC: Registered tcp transport module.[ 0.128877] RPC: Registered tcp NFSv4.1 backchannel transport module.[ 0.129471] hw perfevents: enabled with armv7_cortex_a9 PMU driver, 7 counters available[ 0.130713] futex hash table entries: 512 (order: 3, 32768 bytes)[ 0.131073] workingset: timestamp_bits=30 max_order=18 bucket_order=0[ 0.134776] squashfs: version 4.0 (2009/01/31) Phillip Lougher[ 0.135227] NFS: Registering the id_resolver key type[ 0.135247] Key type id_resolver registered[ 0.135252] Key type id_legacy registered[ 0.135340] ntfs: driver 2.1.32 [Flags: R/O].[ 0.135584] fuse init (API version 7.26)[ 0.137793] bounce: pool size: 64 pages[ 0.137828] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247)[ 0.137835] io scheduler noop registered[ 0.137839] io scheduler deadline registered[ 0.137924] io scheduler cfq registered (default)[ 0.138492] stih407-usb-genphy soc:phy1: STiH407 USB Generic picoPHY driver probed![ 0.138587] stih407-usb-genphy soc:phy2: STiH407 USB Generic picoPHY driver probed![ 0.138657] stih407-usb-genphy soc:phy3: STiH407 USB Generic picoPHY driver probed![ 0.138892] st-pinctrl 922f080.pin-controller-rear: maps: function pwm0 group pwm0-0-default num 3[ 0.139135] st-pinctrl 961f080.pin-controller-sbc: maps: function pwm1 group pwm1-0-default num 3[ 0.139146] st-pinctrl 961f080.pin-controller-sbc: maps: function pwm1 group pwm1-1-default num 3[ 0.139155] st-pinctrl 961f080.pin-controller-sbc: maps: function pwm1 group pwm1-2-default num 2[ 0.139164] st-pinctrl 961f080.pin-controller-sbc: maps: function pwm1 group pwm1-3-default num 2[ 0.139294] sti-pwm 9510000.pwm: Failed to obtain IRQ[ 0.139868] pwm-regulator pwm-regulator: Failed to get PWM: -517[ 0.166663] STMicroelectronics ASC driver initialized[ 0.166782] st-pinctrl 920f080.pin-controller-front0: maps: function serial0 group serial0-0 num 3[ 0.166933] 9830000.serial: ttyAS0 at MMIO 0x9830000 (irq = 232, base_baud = 12500000) is a st-asc[ 0.167086] st-pinctrl 920f080.pin-controller-front0: maps: function serial1 group serial1-0 num 3[ 0.167160] 9831000.serial: ttyAS1 at MMIO 0x9831000 (irq = 233, base_baud = 12500000) is a st-asc[ 1.687477] console [ttyAS1] enabled[ 1.691490] st-hwrandom 8a89000.rng: Successfully registered HW RNG[ 1.697793] st-hwrandom 8a8a000.rng: Successfully registered HW RNG[ 1.704117] [drm] Initialized[ 1.707633] [drm] vtg_probe 8d02800.sti-vtg-main[ 1.712323] [drm] vtg_probe 8d00200.sti-vtg-aux[ 1.717631] [drm] sti_tvout_probe[ 1.721107] [drm] sti_hdmi_probe[ 1.730368] brd: module loaded[ 1.736816] loop: module loaded[ 1.740513] miphy28lp-phy soc:miphy28lp@9b22000: sata-up mode, addr 0xf00fd000[ 1.748746] st_ahci 9b28000.sata: forcing port_map 0x0 -> 0x1[ 1.754542] st_ahci 9b28000.sata: AHCI 0001.0300 32 slots 1 ports 6 Gbps 0x1 impl platform mode[ 1.763253] st_ahci 9b28000.sata: flags: ncq sntf pm led clo only pmp pio slum part ccc apst [ 1.772694] scsi host0: st_ahci[ 1.775987] ata1: SATA max UDMA/133 mmio [mem 0x09b28000-0x09b28fff] port 0x100 irq 244[ 1.784246] st-pinctrl 922f080.pin-controller-rear: maps: function spi4 group spi4-4w-alt1-0 num 4[ 1.793696] libphy: Fixed MDIO Bus: probed[ 1.798330] st-pinctrl 961f080.pin-controller-sbc: maps: function gmac1 group rgmii1-0 num 15[ 1.806870] st-pinctrl 961f080.pin-controller-sbc: maps: function gmac1 group rgmii1-mdio-1 num 3[ 1.815964] sti-dwmac 9630000.dwmac: snps,phy-addr property is deprecated[ 1.822774] sti-dwmac 9630000.dwmac: Use internal clock source[ 1.828711] stmmac - user ID: 0xca, Synopsys ID: 0x37[ 1.833764] Ring mode enabled[ 1.836806] DMA HW capability register supported[ 1.841327] Enhanced/Alternate descriptors[ 1.845507] Enabled extended descriptors[ 1.849503] RX Checksum Offload Engine supported[ 1.854206] COE Type 2[ 1.856640] TX Checksum insertion supported[ 1.860897] Wake-Up On Lan supported[ 1.864580] Enable RX Mitigation via HW Watchdog Timer[ 2.124461] ata1: SATA link down (SStatus 0 SControl 300)[ 2.955674] libphy: stmmac: probed[ 2.959066] eth0: PHY ID 001cc915 at 0 IRQ POLL (stmmac-0:00) active[ 2.965424] eth0: PHY ID 001cc915 at 4 IRQ POLL (stmmac-0:04)[ 2.971393] pegasus: v0.9.3 (2013/04/25), Pegasus/Pegasus II USB Ethernet driver[ 2.978831] usbcore: registered new interface driver pegasus[ 2.984516] usbcore: registered new interface driver asix[ 2.989923] usbcore: registered new interface driver ax88179_178a[ 2.996034] usbcore: registered new interface driver cdc_ether[ 3.001883] usbcore: registered new interface driver smsc75xx[ 3.007658] usbcore: registered new interface driver smsc95xx[ 3.013421] usbcore: registered new interface driver net1080[ 3.019089] usbcore: registered new interface driver cdc_subset[ 3.025025] usbcore: registered new interface driver zaurus[ 3.030619] usbcore: registered new interface driver cdc_ncm[ 3.036526] st-pinctrl 922f080.pin-controller-rear: maps: function usb3 group usb3-2 num 4[ 3.045268] miphy28lp-phy soc:miphy28lp@9b22000: usb3-up mode, addr 0xf024d000[ 3.053790] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver[ 3.060317] ehci-platform: EHCI generic platform driver[ 3.065653] ehci-st: EHCI STMicroelectronics driver[ 3.070580] st-pinctrl 922f080.pin-controller-rear: maps: function usb0 group usb2-0 num 3[ 3.078930] st-ehci 9a03e00.usb: 48MHz clk not found[ 3.083928] st-ehci 9a03e00.usb: EHCI Host Controller[ 3.088985] st-ehci 9a03e00.usb: new USB bus registered, assigned bus number 1[ 3.096345] st-ehci 9a03e00.usb: irq 255, io mem 0x09a03e00[ 3.123125] st-ehci 9a03e00.usb: USB 2.0 started, EHCI 1.00[ 3.129150] hub 1-0:1.0: USB hub found[ 3.132907] hub 1-0:1.0: 1 port detected[ 3.137036] st-pinctrl 922f080.pin-controller-rear: maps: function usb1 group usb2-1 num 3[ 3.145391] st-ehci 9a83e00.usb: 48MHz clk not found[ 3.150373] st-ehci 9a83e00.usb: EHCI Host Controller[ 3.155439] st-ehci 9a83e00.usb: new USB bus registered, assigned bus number 2[ 3.162777] st-ehci 9a83e00.usb: irq 257, io mem 0x09a83e00[ 3.193128] st-ehci 9a83e00.usb: USB 2.0 started, EHCI 1.00[ 3.199067] hub 2-0:1.0: USB hub found[ 3.202820] hub 2-0:1.0: 1 port detected[ 3.206959] ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver[ 3.213146] ohci-platform: OHCI generic platform driver[ 3.218471] ohci-st: OHCI STMicroelectronics driver[ 3.223439] st-ohci 9a03c00.usb: 48MHz clk not found[ 3.228414] st-ohci 9a03c00.usb: ST OHCI controller[ 3.233299] st-ohci 9a03c00.usb: new USB bus registered, assigned bus number 3[ 3.240587] st-ohci 9a03c00.usb: irq 254, io mem 0x09a03c00[ 3.317556] hub 3-0:1.0: USB hub found[ 3.321312] hub 3-0:1.0: 1 port detected[ 3.325429] st-ohci 9a83c00.usb: 48MHz clk not found[ 3.330403] st-ohci 9a83c00.usb: ST OHCI controller[ 3.335294] st-ohci 9a83c00.usb: new USB bus registered, assigned bus number 4[ 3.342586] st-ohci 9a83c00.usb: irq 256, io mem 0x09a83c00[ 3.417477] hub 4-0:1.0: USB hub found[ 3.421231] hub 4-0:1.0: 1 port detected[ 3.425664] xhci-hcd xhci-hcd.0.auto: xHCI Host Controller[ 3.431149] xhci-hcd xhci-hcd.0.auto: new USB bus registered, assigned bus number 5[ 3.439151] xhci-hcd xhci-hcd.0.auto: hcc params 0x0228f06c hci version 0x100 quirks 0x00010010[ 3.447882] xhci-hcd xhci-hcd.0.auto: irq 276, io mem 0x09900000[ 3.454264] hub 5-0:1.0: USB hub found[ 3.458020] hub 5-0:1.0: 1 port detected[ 3.462099] xhci-hcd xhci-hcd.0.auto: xHCI Host Controller[ 3.467595] xhci-hcd xhci-hcd.0.auto: new USB bus registered, assigned bus number 6[ 3.475296] usb usb6: We don't know the algorithms for LPM for this host, disabling LPM.[ 3.483713] hub 6-0:1.0: USB hub found[ 3.487466] hub 6-0:1.0: 1 port detected[ 3.491620] usbcore: registered new interface driver cdc_acm[ 3.497283] cdc_acm: USB Abstract Control Model driver for USB modems and ISDN adapters[ 3.505377] usbcore: registered new interface driver usb-storage[ 3.511424] udc-core: couldn't find an available UDC - added [g_serial] to list of pending drivers[ 3.520606] i2c /dev entries driver[ 3.524162] st-pinctrl 920f080.pin-controller-front0: maps: function i2c0 group i2c0-default num 3[ 3.533749] st-i2c 9840000.i2c: ST I2C(0x09840000) initialized[ 3.539605] st-pinctrl 920f080.pin-controller-front0: maps: function i2c1 group i2c1-default num 3[ 3.548808] st-i2c 9841000.i2c: ST I2C(0x09841000) initialized[ 3.554661] st-pinctrl 920f080.pin-controller-front0: maps: function i2c2 group i2c2-alt2-1 num 3[ 3.563800] st-i2c 9842000.i2c: ST I2C(0x09842000) initialized[ 3.569647] st-pinctrl 920f080.pin-controller-front0: maps: function i2c3 group i2c3-alt3-0 num 3[ 3.578764] st-i2c 9843000.i2c: ST I2C(0x09843000) initialized[ 3.584620] st-pinctrl 961f080.pin-controller-sbc: maps: function i2c11 group i2c11-default num 3[ 3.593771] st-i2c 9541000.i2c: ST I2C(0x09541000) initialized[ 3.599720] IR NEC protocol handler initialized[ 3.604256] IR RC5(x/sz) protocol handler initialized[ 3.609297] IR RC6 protocol handler initialized[ 3.613827] IR JVC protocol handler initialized[ 3.618346] IR Sony protocol handler initialized[ 3.622953] IR SANYO protocol handler initialized[ 3.627657] IR Sharp protocol handler initialized[ 3.632349] IR MCE Keyboard/mouse protocol handler initialized[ 3.638182] IR XMP protocol handler initialized[ 3.642911] usbcore: registered new interface driver uvcvideo[ 3.648657] USB Video Class driver (1.1.1)[ 3.652742] gspca_main: v2.14.0 registered[ 3.657413] usb 2-1: new high-speed USB device number 2 using st-ehci[ 3.664078] st_thermal_mmap 91a0000.temp0: can't request region for resource [mem 0x091a0000-0x091a0027][ 3.673571] st_thermal_mmap 91a0000.temp0: failed to remap IO[ 3.679311] st_thermal_mmap: probe of 91a0000.temp0 failed with error -16[ 3.686375] st-lpc-wdt 8787000.lpc: LPC Watchdog driver registered, reset type is cold[ 3.696128] sdhci: Secure Digital Host Controller Interface driver[ 3.702301] sdhci: Copyright(c) Pierre Ossman[ 3.706663] sdhci-pltfm: SDHCI platform and OF driver helper[ 3.712375] st-pinctrl 923f080.pin-controller-flash: maps: function mmc0 group sd0-0 num 12[ 3.783133] mmc0: SDHCI controller on 9060000.sdhci [9060000.sdhci] using ADMA[ 3.790353] sdhci-st 9060000.sdhci: SDHCI ST Initialised: Host Version: 0x2 Vendor Version 0x10[ 3.799080] st-pinctrl 920f080.pin-controller-front0: maps: function mmc1 group sd1-0 num 11[ 3.807727] sdhci-st 9080000.sdhci: invalid resource[ 3.812688] sdhci-st 9080000.sdhci: FlashSS Top Dly registers not available[ 3.855449] hub 2-1:1.0: USB hub found[ 3.859368] hub 2-1:1.0: 3 ports detected[ 3.885146] mmc1: SDHCI controller on 9080000.sdhci [9080000.sdhci] using ADMA[ 3.892365] sdhci-st 9080000.sdhci: SDHCI ST Initialised: Host Version: 0x2 Vendor Version 0x10[ 3.902783] mmc0: new high speed SDHC card at address 59b4[ 3.908457] ledtrig-cpu: registered to indicate activity on CPUs[ 3.914632] usbcore: registered new interface driver usbhid[ 3.920195] usbhid: USB HID core driver[ 3.924192] mmcblk0: mmc0:59b4 USD 3.75 GiB [ 3.926296] sti-mailbox 8f00000.mailbox: a9: Registered Tx/Rx Mailbox[ 3.926329] sti-mailbox 8f01000.mailbox: st231_gp_1: Registered Tx only Mailbox[ 3.926359] sti-mailbox 8f02000.mailbox: st231_gp_0: Registered Tx only Mailbox[ 3.926386] sti-mailbox 8f03000.mailbox: st231_audio_video: Registered Tx only Mailbox[ 3.926618] sti-mailbox 8f00000.mailbox: Mbox: a9: Created channel: instance: 0 channel: 2[ 3.926627] sti-mailbox 8f02000.mailbox: Mbox: st231_gp_0: Created channel: instance: 0 channel: 1[ 3.926635] sti-mailbox 8f00000.mailbox: Mbox: a9: Created channel: instance: 0 channel: 3[ 3.926643] sti-mailbox 8f02000.mailbox: Mbox: st231_gp_0: Created channel: instance: 0 channel: 0[ 3.926686] remoteproc remoteproc0: st231-gp0 is available[ 3.926689] remoteproc remoteproc0: Note: remoteproc is still under development and considered experimental.[ 3.926692] remoteproc remoteproc0: THE BINARY FORMAT IS NOT YET FINALIZED, and backward compatibility isn't yet guaranteed.[ 3.926849] sti-mailbox 8f00000.mailbox: Mbox: a9: Created channel: instance: 0 channel: 0[ 3.926858] sti-mailbox 8f03000.mailbox: Mbox: st231_audio_video: Created channel: instance: 0 channel: 1[ 3.926865] sti-mailbox 8f00000.mailbox: Mbox: a9: Created channel: instance: 0 channel: 1[ 3.926873] sti-mailbox 8f03000.mailbox: Mbox: st231_audio_video: Created channel: instance: 0 channel: 0[ 3.926913] remoteproc remoteproc1: st231-delta is available[ 3.926916] remoteproc remoteproc1: Note: remoteproc is still under development and considered experimental.[ 3.926919] remoteproc remoteproc1: THE BINARY FORMAT IS NOT YET FINALIZED, and backward compatibility isn't yet guaranteed.[ 3.927082] remoteproc remoteproc0: Direct firmware load for rproc-st231-gp0-fw failed with error -2[ 3.927108] remoteproc remoteproc1: Direct firmware load for rproc-st231-delta-fw failed with error -2[ 3.954336] nf_conntrack version 0.5.0 (15360 buckets, 61440 max)[ 3.954646] ip_tables: (C) 2000-2006 Netfilter Core Team[ 3.954654] arp_tables: arp_tables: (C) 2002 David S. Miller[ 3.954947] NET: Registered protocol family 10[ 3.955578] mip6: Mobile IPv6[ 3.955642] sit: IPv6, IPv4 and MPLS over IPv4 tunneling driver[ 3.956273] NET: Registered protocol family 17[ 3.956294] bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this.[ 3.956297] Ebtables v2.0 registered[ 3.956323] 8021q: 802.1Q VLAN Support v1.8[ 3.956426] Key type dns_resolver registered[ 3.956563] ThumbEE CPU extension supported.[ 3.956569] Registering SWP/SWPB emulation handler[ 3.956887] registered taskstats version 1[ 3.960447] pwm-regulator pwm-regulator: Failed to get PWM: -517[ 3.960617] [drm] sti_hdmi_probe[ 3.966194] [drm] Supports vblank timestamp caching Rev 2 (21.10.2013).[ 3.966197] [drm] No driver support for vblank timestamp query.[ 3.966211] sti soc:sti-display-subsystem: bound 9d11000.sti-compositor (ops 0xc093a98c)[ 3.966229] sti soc:sti-display-subsystem: bound 8d08000.sti-tvout (ops 0xc093beb0)[ 3.966436] [drm] hdmi-audio-codec Driver bound 8d04000.sti-hdmi[ 3.966443] sti soc:sti-display-subsystem: bound 8d04000.sti-hdmi (ops 0xc093ac08)[ 3.966518] sti soc:sti-display-subsystem: bound 9c00000.sti-hqvdp (ops 0xc093c1c4)[ 3.966556] [drm] sti: debugfs installed[ 3.966705] [drm] sti: debugfs installed[ 3.966914] sti soc:sti-display-subsystem: No connectors reported connected with modes[ 3.966922] [drm] Cannot find any crtc or sizes - going 1024x768[ 4.183128] usb 2-1.1: new high-speed USB device number 3 using st-ehci[ 4.259353] sti soc:sti-display-subsystem: fb0: frame buffer device[ 4.269884] mmcblk0: p1 p2 p3[ 4.303652] pwm-regulator pwm-regulator: Failed to get PWM: -517[ 4.310024] hctosys: unable to open rtc device (rtc0)[ 4.315087] of_cfs_init[ 4.317536] of_cfs_init: OK[ 4.332850] sti-dwmac 9630000.dwmac eth0: IEEE 1588-2008 Advanced Timestamp supported[ 4.340947] sti-dwmac 9630000.dwmac eth0: registered PTP clock[ 4.346895] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready[ 4.355798] pwm-regulator pwm-regulator: Failed to get PWM: -517[ 9.519668] sti-dwmac 9630000.dwmac eth0: Link is Up - 1Gbps/Full - flow control rx/tx[ 9.569081] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready[ 9.617177] Sending DHCP requests .[ 9.686659] Link is Up - 1000/Full[ 9.719846] , OK[ 9.832531] IP-Config: Got DHCP answer from 10.48.3.253, my address is 10.48.2.150[ 9.840200] IP-Config: Complete:[ 9.843438] device=eth0, hwaddr=00:80:e1:12:81:5f, ipaddr=10.48.2.150, mask=255.255.252.0, gw=10.48.3.254[ 9.853485] host=Cannes2_B2260_003, domain=lme.st.com, nis-domain=(none)[ 9.860625] bootserver=0.0.0.0, rootserver=10.48.1.24, rootpath= nameserver0=10.201.16.209, nameserver1=10.129.252.253[ 9.872420] clk: Not disabling unused clocks[ 9.877466] remoteproc remoteproc2: dma-controller is available[ 9.883398] remoteproc remoteproc2: Note: remoteproc is still under development and considered experimental.[ 9.893304] remoteproc remoteproc2: THE BINARY FORMAT IS NOT YET FINALIZED, and backward compatibility isn't yet guaranteed.[ 9.906711] st-fdma 8e20000.dma-controller: ST FDMA engine driver, irq:250[ 9.914014] remoteproc remoteproc3: dma-controller is available[ 9.919945] remoteproc remoteproc3: Note: remoteproc is still under development and considered experimental.[ 9.929782] remoteproc remoteproc3: THE BINARY FORMAT IS NOT YET FINALIZED, and backward compatibility isn't yet guaranteed.[ 9.943229] st-fdma 8e40000.dma-controller: ST FDMA engine driver, irq:251[ 9.950586] remoteproc remoteproc4: dma-controller is available[ 9.956517] remoteproc remoteproc4: Note: remoteproc is still under development and considered experimental.[ 9.966405] remoteproc remoteproc4: THE BINARY FORMAT IS NOT YET FINALIZED, and backward compatibility isn't yet guaranteed.[ 9.979867] st-fdma 8e60000.dma-controller: ST FDMA engine driver, irq:252[ 9.986940] ALSA device list:[ 9.989905] No soundcards found.[ 9.994187] remoteproc remoteproc2: Direct firmware load for fdma_STiH407_0.elf failed with error -2[ 10.004281] remoteproc remoteproc2: powering up dma-controller[ 10.010200] remoteproc remoteproc2: Direct firmware load for fdma_STiH407_0.elf failed with error -2[ 10.019349] remoteproc remoteproc2: request_firmware failed: -2[ 10.025509] pwm-regulator pwm-regulator: Failed to get PWM: -517[ 10.031882] remoteproc remoteproc3: Direct firmware load for fdma_STiH407_1.elf failed with error -2[ 10.041258] remoteproc remoteproc4: Direct firmware load for fdma_STiH407_2.elf failed with error -2[ 10.066594] VFS: Mounted root (nfs filesystem) on device 0:15.[ 10.073301] devtmpfs: mounted[ 10.077615] remoteproc remoteproc2: powering up dma-controller[ 10.085281] Freeing unused kernel memory: 1024K (c0c00000 - c0d00000)[ 10.098854] remoteproc remoteproc2: Booting fw image fdma_STiH407_0.elf, size 44416[ 10.108100] remoteproc remoteproc2: fw rev:1.0 on SLIM 0.134413[ 10.114042] remoteproc remoteproc2: remote processor dma-controller is now up[ 10.121554] pwm-regulator pwm-regulator: Failed to get PWM: -517[ 10.133157] asoc-simple-card soc:sound: hdmi-hifi.0 <-> 8d80000.sti-uni-player mapping ok[ 10.143167] pwm-regulator pwm-regulator: Failed to get PWM: -517[ 10.151365] random: fast init done[ 10.325210] systemd[1]: System time before build time, advancing clock.[ 10.390299] systemd[1]: systemd 229 running in system mode. (+PAM -AUDIT -SELINUX +IMA -APPARMOR +SMACK +SYSVINIT +UTMP -LIBCRYPTSETUP -GCRYPT -GNUTLS +ACL +XZ -LZ4 -SECCOMP +BLKID -ELFUTILS +KMOD -IDN)[ 10.409141] systemd[1]: Detected architecture arm.Welcome to [1mST OpenSDK - software - (A Yocto Project 2.0 based Distro) 2.1 (krogoth)[0m![ 10.449004] systemd[1]: Set hostname to <sti-lts>.[ 10.460076] systemd[1]: Initializing machine ID from random generator.[ 10.949810] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.[[0;32m OK [0m] Started Dispatch Password Requests to Console Directory Watch.[ 10.988242] systemd[1]: Listening on Journal Socket.[[0;32m OK [0m] Listening on Journal Socket.[ 11.018730] systemd[1]: Created slice User and Session Slice.[[0;32m OK [0m] Created slice User and Session Slice.[ 11.071045] systemd[1]: Listening on udev Kernel Socket.[[0;32m OK [0m] Listening on udev Kernel Socket.[ 11.098013] systemd[1]: Reached target Remote File Systems.[[0;32m OK [0m] Reached target Remote File Systems.[ 11.141247] systemd[1]: Created slice System Slice.[[0;32m OK [0m] Created slice System Slice. Starting Setup Virtual Console... Mounting Debug File System... Mounting FUSE Control File System... Starting Apply Kernel Variables...[[0;32m OK [0m] Created slice system-serial\x2dgetty.slice. Mounting Configuration File System...[[0;32m OK [0m] Created slice system-getty.slice. Mounting POSIX Message Queue File System...[[0;32m OK [0m] Listening on /dev/initctl Compatibility Named Pipe.[[0;32m OK [0m] Reached target Swap. Starting Remount Root and Kernel File Systems...[[0;32m OK [0m] Listening on Syslog Socket.[[0;32m OK [0m] Started Forward Password Requests to Wall Directory Watch.[[0;32m OK [0m] Reached target Paths.[[0;32m OK [0m] Listening on Journal Socket (/dev/log). Starting Journal Service... Mounting Temporary Directory... Starting Create list of required st... nodes for the current kernel...[[0;32m OK [0m] Listening on udev Control Socket.[[0;32m OK [0m] Reached target Slices.[[0;32m OK [0m] Mounted Debug File System.[[0;32m OK [0m] Mounted FUSE Control File System.[[0;32m OK [0m] Mounted Configuration File System.[[0;32m OK [0m] Mounted POSIX Message Queue File System.[[0;32m OK [0m] Mounted Temporary Directory.[[0;32m OK [0m] Started Journal Service.[[0;32m OK [0m] Started Setup Virtual Console.[[0;32m OK [0m] Started Apply Kernel Variables.[[0;32m OK [0m] Started Remount Root and Kernel File Systems.[[0;32m OK [0m] Started Create list of required sta...ce nodes for the current kernel. Starting Rebuild Dynamic Linker Cache...[[0;32m OK [0m] Started Starts Psplash Boot screen. Starting Rebuild Hardware Database... Starting Create System Users... Starting Flush Journal to Persistent Storage...[ 12.458877] systemd-journald[163]: Received request to flush runtime journal from PID 1[[0;32m OK [0m] Started Flush Journal to Persistent Storage.[[0;32m OK [0m] Started Create System Users. Starting Create Static Device Nodes in /dev...[[0;32m OK [0m] Started Create Static Device Nodes in /dev.[[0;32m OK [0m] Reached target Local File Systems (Pre). Mounting /var/volatile... Starting udev Kernel Device Manager...[[0;32m OK [0m] Mounted /var/volatile. Starting Load/Save Random Seed...[[0;32m OK [0m] Reached target Local File Systems. Starting Rebuild Journal Catalog... Starting Create Volatile Files and Directories...[[0;32m OK [0m] Started Load/Save Random Seed.[[0;32m OK [0m] Started udev Kernel Device Manager.[[0;32m OK [0m] Started Rebuild Journal Catalog.[ 13.202965] random: crng init done[[0;32m OK [0m] Started Create Volatile Files and Directories. Starting Update UTMP about System Boot/Shutdown... Starting dpkg first boot configure... Starting Network Time Synchronization... Starting Run pending postinsts...[[0;32m OK [0m] Started Update UTMP about System Boot/Shutdown.[[0;32m OK [0m] Started Network Time Synchronization.[[0;32m OK [0m] Reached target System Time Synchronized.[[0;32m OK [0m] Started Rebuild Dynamic Linker Cache.[[0;32m OK [0m] Started Rebuild Hardware Database. Starting Update is Completed... Starting udev Coldplug all Devices...[[0;32m OK [0m] Started Update is Completed.[[0;32m OK [0m] Started udev Coldplug all Devices.[[0;32m OK [0m] Found device /dev/ttyAS0.[ 15.756603] mali: loading out-of-tree module taints kernel.[[0;32m OK [0m] Found device /dev/ttyAS1.[ 15.798874] pwm-regulator pwm-regulator: Failed to get PWM: -517[ 15.829379] RTL871X: module init start[ 15.833175] RTL871X: rtl8723bu v4.3.6.11_12942.20141204_BTCOEX20140507-4E40[ 15.840148] RTL871X: rtl8723bu BT-Coex version = BTCOEX20140507-4E40[ 15.850135] Mali: [ 15.851965] Mali device driver loaded[ 15.856014] pwm-regulator pwm-regulator: Failed to get PWM: -517[ 15.902807] usbcore: registered new interface driver btusb[ 15.909754] pwm-regulator pwm-regulator: Failed to get PWM: -517[ 15.932345] Bluetooth: hci0: rtl: examining hci_ver=06 hci_rev=000b lmp_ver=06 lmp_subver=8723[ 15.941083] Bluetooth: hci0: rtl: loading rtl_bt/rtl8723b_config.bin[ 15.955923] bluetooth hci0: Direct firmware load for rtl_bt/rtl8723b_config.bin failed with error -2[ 15.965096] Bluetooth: hci0: Failed to load rtl_bt/rtl8723b_config.bin[ 15.971655] Bluetooth: hci0: rtl: loading rtl_bt/rtl8723b_fw.bin[ 15.983770] Bluetooth: hci0: rom_version status=0 version=1[ 15.989445] Bluetooth: cfg_sz 0, total size 22496[ 16.225750] RTL871X: rtw_ndev_init(wlan0)[ 16.230548] usbcore: registered new interface driver rtl8723bu[ 16.236454] RTL871X: module init ret=0[ 16.240354] pwm-regulator pwm-regulator: Failed to get PWM: -517[[0;32m OK [0m] Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.[[0;32m OK [0m] Reached target Sound Card. Starting Load/Save RF Kill Switch Status...[[0;32m OK [0m] Started Load/Save RF Kill Switch Status.[[0;32m OK [0m] Started Run pending postinsts.[[0;32m OK [0m] Started dpkg first boot configure.[[0;32m OK [0m] Reached target System Initialization.[[0;32m OK [0m] Listening on dropbear.socket.[[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. Starting Console System Startup Logging...[[0;32m OK [0m] Listening on Avahi mDNS/DNS-SD Stack Activation Socket.[[0;32m OK [0m] Listening on Sound System.[[0;32m OK [0m] Reached target Sockets.[[0;32m OK [0m] Reached target Basic System. Starting Target Communication Framework agent... Starting Login Service... Starting Resize root filesystem to fit available disk space...[[0;32m OK [0m] Started System Logging Service. Starting Netdata, Real-time performance monitoring... Starting Bluetooth service... Starting Weston Wayland compositor startup... Starting Avahi mDNS/DNS-SD Stack... Starting Permit User Sessions...[[0;32m OK [0m] Started D-Bus System Message Bus.[[0;32m OK [0m] Started Bluetooth service.[[0;32m OK [0m] Started Avahi mDNS/DNS-SD Stack.[[0;32m OK [0m] Reached target Bluetooth. Starting Sound Service... Starting Save/Restore Sound Card State...[[0;32m OK [0m] Started ST Dynamic Host Configuration Protocol (DHCP).[[0;32m OK [0m] Started Kernel Logging Service.[[0;32m OK [0m] Started Console System Startup Logging.[[0;32m OK [0m] Started Target Communication Framework agent.[[0;32m OK [0m] Started Permit User Sessions.[[0;32m OK [0m] Started Save/Restore Sound Card State.[ 22.844080] remoteproc remoteproc1: powering up st231-delta[ 22.944497] remoteproc remoteproc1: Booting fw image rproc-st231-delta-fw, size 1188156[ 22.961885] virtio_rpmsg_bus virtio0: rpmsg host is online[ 22.967441] remoteproc remoteproc1: registered virtio0 (type 7)[ 22.977452] remoteproc remoteproc1: Started from 0x44000300[ 22.979359] virtio_rpmsg_bus virtio0: creating channel rpmsg-delta addr 0x0[ 22.990067] remoteproc remoteproc1: remote processor st231-delta is now up[ 22.998142] pwm-regulator pwm-regulator: Failed to get PWM: -517[[0;32m OK [0m] Started Netdata, Real-time performance monitoring.[[0;32m OK [0m] Started Sound Service.[[0;32m OK [0m] Started Weston Wayland compositor startup.[[0;32m OK [0m] Started Resize root filesystem to fit available disk space.[[0;32m OK [0m] Started Login Service. Starting Hostname Service...[[0;32m OK [0m] Started Serial Getty on ttyAS0.[[0;32m OK [0m] Started Getty on tty1.[[0;32m OK [0m] Started Serial Getty on ttyAS1.[[0;32m OK [0m] Reached target Login Prompts.[[0;32m OK [0m] Reached target Multi-User System. Starting Update UTMP about System Runlevel Changes...[[0;1;31mFAILED[0m] Failed to start Hostname Service.See 'systemctl status systemd-hostnamed.service' for details.[[0;32m OK [0m] Started Update UTMP about System Runlevel Changes.ST OpenSDK - software - (A Yocto Project 2.0 based Distro) 2.1 sti-lts ttyAS1sti-lts login: root (automatic login)7[r[999;999H[6nresize: Time out occurredMatched prompt #5: root@Setting shell prompt(s) to ['root@', 'lava-test: # ']export PS1="lava-test: # " root@sti-lts:~# export PS1=\"lava-test: # \"auto-login-action duration: 39.93case: auto-login-action
definition: lava
result: pass
level: 2.5.5
extra: ...
start: 2.5.6 expect-shell-connection (max 600s)expect-shell-connection: Waiting for prompt root@, lava-test: # expect-shell-connection: Wait for prompt ['root@', 'lava-test: # ']. 240 secondsexport PS1=\"lava-test: # \"expect-shell-connection duration: 0.00start: 2.5.7 export-device-env (max 600s)export-device-env duration: 0.00Setting default test shell promptroot@,lava-test: # uboot-retry: Wait for prompt ['root@', 'lava-test: # ']. 240 secondsuboot-retry duration: 169.91uboot-action duration: 169.92start: 3 lava-test-retry (max 3600s)start: 3.1 lava-test-shell (max 3600s)Executing test definitions using ShellSession# lava-test: # #lava-test-shell: Wait for prompt ['root@', 'lava-test: # ']. 240 seconds#Using /lava-3715ls -l /lava-3715/ lava-test: # ls -l /lava-3715//lava-3715/bin/lava-test-runner /lava-3715/0 /lava-3715/bin/lava-test-runner /lava-3715/0Test shell will use the higher of the action timeout and connection timeout.Setting action timeout: 3600 secondsls -l /lava-3715//total 28drwxrwxrwx 3 root root 4096 Apr 11 2017 0drwxrwxrwx 3 root root 4096 Apr 11 2017 1drwxrwxrwx 3 root root 4096 Apr 11 2017 2drwxrwxrwx 3 root root 4096 Apr 11 2017 3drwxr-xr-x 2 root root 4096 Apr 11 2017 bindrwxr-xr-x 2 root root 4096 Apr 11 2017 resultsdrwxr-xr-x 2 root root 4096 Apr 11 2017 testslava-test: # /lava-3715/bin/lava-test-runner /lava-3715/0/lava-3715/0<LAVA_TEST_RUNNER>: started<LAVA_TEST_RUNNER>: looking for work in /lava-3715/0/lava-test-runner.conf-1484266027<LAVA_TEST_RUNNER>: running 0_TC_BENCH_DISK_AIO_STRESS under lava-test-shell...<LAVA_TEST_RUNNER>: running 0_TC_BENCH_DISK_AIO_STRESS installer .../lava-3715/0/tests/0_TC_BENCH_DISK_AIO_STRESS/install.sh: line 5: lava-add-sources: command not found/lava-3715/0/tests/0_TC_BENCH_DISK_AIO_STRESS/install.sh: line 6: lava-add-sources: command not found/lava-3715/0/tests/0_TC_BENCH_DISK_AIO_STRESS/install.sh: line 7: lava-add-sources: command not foundError: OPKG package manager not found in the path.<LAVA_TEST_RUNNER>: 0_TC_BENCH_DISK_AIO_STRESS installer failed, skipping0+1 records in0+1 records out64 bytes (64 B) copied, 0.00125037 s, 51.2 kB/s0_TC_BENCH_DISK_AIO_STRESS-1484266027ok: lava_test_shell seems to have completedUsing /lava-3715ls -l /lava-3715/ <LAVA_TEST_RUNNER>: exiting lavls -l /lava-3715//lava-3715/bin/lava-test-runner /lava-3715/1 /lava-3715/bin/lava-test-runner /lava-3715/1Test shell will use the higher of the action timeout and connection timeout.Setting action timeout: 3600 secondsa-test-runnerlava-test: # ls -l /lava-3715//total 28drwxrwxrwx 4 root root 4096 Apr 11 2017 0drwxrwxrwx 3 root root 4096 Apr 11 2017 1drwxrwxrwx 3 root root 4096 Apr 11 2017 2drwxrwxrwx 3 root root 4096 Apr 11 2017 3drwxr-xr-x 2 root root 4096 Apr 11 2017 bindrwxr-xr-x 2 root root 4096 Apr 11 2017 resultsdrwxr-xr-x 2 root root 4096 Apr 11 2017 testsllava-test: # /lava-3715/bin/lava-test-runner /lava-3715/1/lava-3715/1<LAVA_TEST_RUNNER>: started<LAVA_TEST_RUNNER>: looking for work in /lava-3715/1/lava-test-runner.conf-1484266031<LAVA_TEST_RUNNER>: running 1_TC_BENCH_DISK_HDPARM_READ under lava-test-shell...<LAVA_TEST_RUNNER>: save hardware/software context info for 1_TC_BENCH_DISK_HDPARM_READ...0+1 records in0+1 records out64 bytes (64 B) copied, 0.00126853 s, 50.5 kB/s+ export TESTRUN_ID=1_TC_BENCH_DISK_HDPARM_READ+ TESTRUN_ID=1_TC_BENCH_DISK_HDPARM_READ+ cd /lava-3715/1/tests/1_TC_BENCH_DISK_HDPARM_READ++ cat uuid+ UUID=3715_1.4.2.3.5Received signal: <STARTRUN> 1_TC_BENCH_DISK_HDPARM_READ 3715_1.4.2.3.5Starting test lava.1_TC_BENCH_DISK_HDPARM_READ (3715_1.4.2.3.5)Enabling test definition pattern '^(?P<test_case_id>\\w+) RESULT:(?P<result>(pass|fail|unknown))'Enabling test definition fixup {'FAILED': 'fail', 'ABORTED': 'unknown', 'SUCCESS': 'pass'}case: 1_TC_BENCH_DISK_HDPARM_READ
definition: lava
result: fail
uuid: 3715_1.4.2.3.5
+ echo '<LAVA_SIGNAL_STARTRUN 1_TC_BENCH_DISK_HDPARM_READ 3715_1.4.2.3.5>'<LAVA_SIGNAL_STARTRUN 1_TC_BENCH_DISK_HDPARM_READ 3715_1.4.2.3.5>Received signal: <STARTRUN> 1_TC_BENCH_DISK_HDPARM_READ 3715_1.4.2.3.5Starting test lava.1_TC_BENCH_DISK_HDPARM_READ (3715_1.4.2.3.5)Enabling test definition pattern '^(?P<test_case_id>\\w+) RESULT:(?P<result>(pass|fail|unknown))'Enabling test definition fixup {'FAILED': 'fail', 'ABORTED': 'unknown', 'SUCCESS': 'pass'}case: 1_TC_BENCH_DISK_HDPARM_READ
definition: lava
result: fail
uuid: 3715_1.4.2.3.5
+ ./tests-target/benchmark/run_benchmark.sh TC_BENCH_DISK_HDPARM_READ./tests-target/benchmark/run_benchmark.sh: line 65: py.test: command not found+ find -name TC_BENCH_DISK_HDPARM_READ.log+ xargs catTC_BENCH_DISK_HDPARM_READ RESULT:failcase: TC_BENCH_DISK_HDPARM_READ
definition: 1_TC_BENCH_DISK_HDPARM_READ
result: fail
Received signal: <ENDRUN> 1_TC_BENCH_DISK_HDPARM_READ 3715_1.4.2.3.5Ending use of test pattern.Ending test lava.1_TC_BENCH_DISK_HDPARM_READ (3715_1.4.2.3.5), duration 2.54case: 1_TC_BENCH_DISK_HDPARM_READ
definition: lava
duration: 2.54
result: pass
uuid: 3715_1.4.2.3.5
+ echo '<LAVA_SIGNAL_ENDRUN 1_TC_BENCH_DISK_HDPARM_READ 3715_1.4.2.3.5>'<LAVA_SIGNAL_ENDRUN 1_TC_BENCH_DISK_HDPARM_READ 3715_1.4.2.3.5>Received signal: <ENDRUN> 1_TC_BENCH_DISK_HDPARM_READ 3715_1.4.2.3.5Ending use of test pattern.Ending test lava.1_TC_BENCH_DISK_HDPARM_READ (3715_1.4.2.3.5), duration 0.00case: 1_TC_BENCH_DISK_HDPARM_READ
definition: lava
duration: 0.00
result: pass
uuid: 3715_1.4.2.3.5
<LAVA_TEST_RUNNER>: 1_TC_BENCH_DISK_HDPARM_READ exited with: 01_TC_BENCH_DISK_HDPARM_READ-1484266031ok: lava_test_shell seems to have completedUsing /lava-3715ls -l /lava-3715/ <LAVA_TEST_RUNNER>: exiting lava-testls -l /lava-3715//lava-3715/bin/lava-test-runner /lava-3715/2 /lava-3715/bin/lava-test-runner /lava-3715/2Test shell will use the higher of the action timeout and connection timeout.Setting action timeout: 3600 seconds-runnerlava-test: # ls -l /lava-3715//total 28drwxrwxrwx 4 root root 4096 Apr 11 2017 0drwxrwxrwx 4 root root 4096 Apr 11 2017 1drwxrwxrwx 3 root root 4096 Apr 11 2017 2drwxrwxrwx 3 root root 4096 Apr 11 2017 3drwxr-xr-x 2 root root 4096 Apr 11 2017 bindrwxr-xr-x 2 root root 4096 Apr 11 2017 resultsdrwxr-xr-x 2 root root 4096 Apr 11 2017 testsllava-test: # /lava-3715/bin/lava-test-runner /lava-3715/2/lava-3715/2<LAVA_TEST_RUNNER>: started<LAVA_TEST_RUNNER>: looking for work in /lava-3715/2/lava-test-runner.conf-1484266037<LAVA_TEST_RUNNER>: running 2_TC_BENCH_DISK_IOZONE_READ under lava-test-shell...<LAVA_TEST_RUNNER>: save hardware/software context info for 2_TC_BENCH_DISK_IOZONE_READ...0+1 records in0+1 records out64 bytes (64 B) copied, 0.000970533 s, 65.9 kB/s+ export TESTRUN_ID=2_TC_BENCH_DISK_IOZONE_READ+ TESTRUN_ID=2_TC_BENCH_DISK_IOZONE_READ+ cd /lava-3715/2/tests/2_TC_BENCH_DISK_IOZONE_READ++ cat uuid+ UUID=3715_1.4.2.3.9Received signal: <STARTRUN> 2_TC_BENCH_DISK_IOZONE_READ 3715_1.4.2.3.9Starting test lava.2_TC_BENCH_DISK_IOZONE_READ (3715_1.4.2.3.9)Enabling test definition pattern '^(?P<test_case_id>\\w+) RESULT:(?P<result>(pass|fail|unknown))'Enabling test definition fixup {'FAILED': 'fail', 'ABORTED': 'unknown', 'SUCCESS': 'pass'}case: 2_TC_BENCH_DISK_IOZONE_READ
definition: lava
result: fail
uuid: 3715_1.4.2.3.9
+ echo '<LAVA_SIGNAL_STARTRUN 2_TC_BENCH_DISK_IOZONE_READ 3715_1.4.2.3.9>'<LAVA_SIGNAL_STARTRUN 2_TC_BENCH_DISK_IOZONE_READ 3715_1.4.2.3.9>Received signal: <STARTRUN> 2_TC_BENCH_DISK_IOZONE_READ 3715_1.4.2.3.9Starting test lava.2_TC_BENCH_DISK_IOZONE_READ (3715_1.4.2.3.9)Enabling test definition pattern '^(?P<test_case_id>\\w+) RESULT:(?P<result>(pass|fail|unknown))'Enabling test definition fixup {'FAILED': 'fail', 'ABORTED': 'unknown', 'SUCCESS': 'pass'}case: 2_TC_BENCH_DISK_IOZONE_READ
definition: lava
result: fail
uuid: 3715_1.4.2.3.9
+ ./tests-target/benchmark/run_benchmark.sh TC_BENCH_DISK_IOZONE_READ./tests-target/benchmark/run_benchmark.sh: line 65: py.test: command not found+ xargs cat+ find -name TC_BENCH_DISK_IOZONE_READ.logTC_BENCH_DISK_IOZONE_READ RESULT:failcase: TC_BENCH_DISK_IOZONE_READ
definition: 2_TC_BENCH_DISK_IOZONE_READ
result: fail
+ echo '<LAVA_SIGNAL_ENDRUN 2_TC_BENCH_DISK_IOZONE_READ 3715_1.4.2.3.9>'Received signal: <ENDRUN> 2_TC_BENCH_DISK_IOZONE_READ 3715_1.4.2.3.9Ending use of test pattern.Ending test lava.2_TC_BENCH_DISK_IOZONE_READ (3715_1.4.2.3.9), duration 2.58case: 2_TC_BENCH_DISK_IOZONE_READ
definition: lava
duration: 2.58
result: pass
uuid: 3715_1.4.2.3.9
<LAVA_SIGNAL_ENDRUN 2_TC_BENCH_DISK_IOZONE_READ 3715_1.4.2.3.9>Received signal: <ENDRUN> 2_TC_BENCH_DISK_IOZONE_READ 3715_1.4.2.3.9Ending use of test pattern.Ending test lava.2_TC_BENCH_DISK_IOZONE_READ (3715_1.4.2.3.9), duration 0.00case: 2_TC_BENCH_DISK_IOZONE_READ
definition: lava
duration: 0.00
result: pass
uuid: 3715_1.4.2.3.9
<LAVA_TEST_RUNNER>: 2_TC_BENCH_DISK_IOZONE_READ exited with: 02_TC_BENCH_DISK_IOZONE_READ-1484266037ok: lava_test_shell seems to have completedUsing /lava-3715ls -l /lava-3715/ <LAVA_TEST_RUNNER>: exiting lavals -l /lava-3715//lava-3715/bin/lava-test-runner /lava-3715/3 /lava-3715/bin/lava-test-runner /lava-3715/3Test shell will use the higher of the action timeout and connection timeout.Setting action timeout: 3600 seconds-test-runnerlava-test: # ls -l /lava-3715//total 28drwxrwxrwx 4 root root 4096 Apr 11 2017 0drwxrwxrwx 4 root root 4096 Apr 11 2017 1drwxrwxrwx 4 root root 4096 Apr 11 2017 2drwxrwxrwx 3 root root 4096 Apr 11 2017 3drwxr-xr-x 2 root root 4096 Apr 11 2017 bindrwxr-xr-x 2 root root 4096 Apr 11 2017 resultsdrwxr-xr-x 2 root root 4096 Apr 11 2017 testsllava-test: # /lava-3715/bin/lava-test-runner /lava-3715/3/lava-3715/3<LAVA_TEST_RUNNER>: started<LAVA_TEST_RUNNER>: looking for work in /lava-3715/3/lava-test-runner.conf-1484266043<LAVA_TEST_RUNNER>: running 3_TC_BENCH_DISK_IOZONE_WRITE under lava-test-shell...<LAVA_TEST_RUNNER>: save hardware/software context info for 3_TC_BENCH_DISK_IOZONE_WRITE...0+1 records in0+1 records out64 bytes (64 B) copied, 0.00114707 s, 55.8 kB/s+ export TESTRUN_ID=3_TC_BENCH_DISK_IOZONE_WRITE+ TESTRUN_ID=3_TC_BENCH_DISK_IOZONE_WRITE+ cd /lava-3715/3/tests/3_TC_BENCH_DISK_IOZONE_WRITE++ cat uuid+ UUID=3715_1.4.2.3.13+ echo '<LAVA_SIGNAL_STARTRUN 3_TC_BENCH_DISK_IOZONE_WRITE 3715_1.4.2.3.13>'Received signal: <STARTRUN> 3_TC_BENCH_DISK_IOZONE_WRITE 3715_1.4.2.3.13Starting test lava.3_TC_BENCH_DISK_IOZONE_WRITE (3715_1.4.2.3.13)Enabling test definition pattern '^(?P<test_case_id>\\w+) RESULT:(?P<result>(pass|fail|unknown))'Enabling test definition fixup {'FAILED': 'fail', 'ABORTED': 'unknown', 'SUCCESS': 'pass'}case: 3_TC_BENCH_DISK_IOZONE_WRITE
definition: lava
result: fail
uuid: 3715_1.4.2.3.13
<LAVA_SIGNAL_STARTRUN 3_TC_BENCH_DISK_IOZONE_WRITE 3715_1.4.2.3.13>Received signal: <STARTRUN> 3_TC_BENCH_DISK_IOZONE_WRITE 3715_1.4.2.3.13Starting test lava.3_TC_BENCH_DISK_IOZONE_WRITE (3715_1.4.2.3.13)Enabling test definition pattern '^(?P<test_case_id>\\w+) RESULT:(?P<result>(pass|fail|unknown))'Enabling test definition fixup {'FAILED': 'fail', 'ABORTED': 'unknown', 'SUCCESS': 'pass'}case: 3_TC_BENCH_DISK_IOZONE_WRITE
definition: lava
result: fail
uuid: 3715_1.4.2.3.13
+ ./tests-target/benchmark/run_benchmark.sh TC_BENCH_DISK_IOZONE_WRITE./tests-target/benchmark/run_benchmark.sh: line 65: py.test: command not found+ find -name TC_BENCH_DISK_IOZONE_WRITE.log+ xargs catTC_BENCH_DISK_IOZONE_WRITE RESULT:failcase: TC_BENCH_DISK_IOZONE_WRITE
definition: 3_TC_BENCH_DISK_IOZONE_WRITE
result: fail
Received signal: <ENDRUN> 3_TC_BENCH_DISK_IOZONE_WRITE 3715_1.4.2.3.13Ending use of test pattern.Ending test lava.3_TC_BENCH_DISK_IOZONE_WRITE (3715_1.4.2.3.13), duration 2.51case: 3_TC_BENCH_DISK_IOZONE_WRITE
definition: lava
duration: 2.51
result: pass
uuid: 3715_1.4.2.3.13
+ echo '<LAVA_SIGNAL_ENDRUN 3_TC_BENCH_DISK_IOZONE_WRITE 3715_1.4.2.3.13>'Received signal: <ENDRUN> 3_TC_BENCH_DISK_IOZONE_WRITE 3715_1.4.2.3.13Ending use of test pattern.Ending test lava.3_TC_BENCH_DISK_IOZONE_WRITE (3715_1.4.2.3.13), duration 0.00case: 3_TC_BENCH_DISK_IOZONE_WRITE
definition: lava
duration: 0.00
result: pass
uuid: 3715_1.4.2.3.13
<LAVA_SIGNAL_ENDRUN 3_TC_BENCH_DISK_IOZONE_WRITE 3715_1.4.2.3.13><LAVA_TEST_RUNNER>: 3_TC_BENCH_DISK_IOZONE_WRITE exited with: 03_TC_BENCH_DISK_IOZONE_WRITE-1484266043ok: lava_test_shell seems to have completedTC_BENCH_DISK_HDPARM_READ: fail TC_BENCH_DISK_IOZONE_READ: fail TC_BENCH_DISK_IOZONE_WRITE: fail lava-test-shell duration: 22.43lava-test-retry duration: 22.43start: 4 finalize (max 30s)start: 4.1 power_off (max 5s)nice /local/lava/server/lava-config/scripts/epower_switch.sh -u ePowerSw_01.lme.st.com -p 104 -c OFFcommand output Send reboot command switch ePowerSw_01.lme.st.com on prt 104 Switch OFF Power supply --2017-04-11 11:34:58-- http://epowersw_01.lme.st.com/hidden.htm?S1:O4=Off Resolving epowersw_01.lme.st.com (epowersw_01.lme.st.com)... 10.48.2.146 Connecting to epowersw_01.lme.st.com (epowersw_01.lme.st.com)|10.48.2.146|:80... connected. HTTP request sent, awaiting response... 200 OK Length: unspecified [text/html] Saving to: ���hidden.htm?S1:O4=Off.717��� 0K 269K=0.002s 2017-04-11 11:34:58 (269 KB/s) - ���hidden.htm?S1:O4=Off.717��� saved [448] power_off duration: 0.05case: power_off
definition: lava
result: pass
level: 4.1
extra: ...
Status: Completefinalize duration: 0.16case: finalize
definition: lava
result: pass
level: 4
extra: ...
Cleanup: removing /var/lib/lava/dispatcher/tmp/3715