Help
Description
[lavafed 2019.09.0089.gf1de999c2] stm32mp157c-dk2 - health-check
Device-type
stm32mp157c-dk2
Submitter
lavafed.st.com
Created
6 days, 21 hours ago
Priority
Medium
Visibility
Public
Required Tags
dispatcher-01.st.com
#1214
Complete
Device
lmecxd0318-mp157revc_disco_048@dispatcher-01.st.com
Started
6 days, 21 hours ago
Duration
12 minutes
Results
  
lava-dispatcher, installed at version: 2019.09.0089.gf1de999c2+stretch start: 0 validate Start time: 2019-10-09 03:04:27.976093+00:00 (UTC) Validating that http://citools.st.com/artifacts/artifactory/oeivi-codex-st-com/oemanifest-int-mmdv/refs/tags/openstlinux-4.19-thud-mp1-19-04-05/openstlinux-weston/images/stm32mp1/flashlayout_st-image-weston/FlashLayout_sdcard_stm32mp157c-dk2-trusted.tsv exists Validating that http://citools.st.com/artifacts/artifactory/oeivi-codex-st-com/oemanifest-int-mmdv/refs/tags/openstlinux-4.19-thud-mp1-19-04-05/openstlinux-weston/IMAGES-st-image-weston-openstlinux-weston-stm32mp1-openstlinux-4.19-thud-mp1-19-04-05.tar.xz exists validate duration: 0.09 case: validate
case_id: 24502
definition: lava
result: pass
start: 1 deploy-flasher-retry (timeout 00:20:00) [common] start: 1.1 deploy-flasher (timeout 00:20:00) [common] start: 1.1.1 download-retry (timeout 00:20:00) [common] start: 1.1.1.1 http-download (timeout 00:20:00) [common] downloading http://citools.st.com/artifacts/artifactory/oeivi-codex-st-com/oemanifest-int-mmdv/refs/tags/openstlinux-4.19-thud-mp1-19-04-05/openstlinux-weston/images/stm32mp1/flashlayout_st-image-weston/FlashLayout_sdcard_stm32mp157c-dk2-trusted.tsv saving as /var/lib/lava/dispatcher/tmp/lavafed-1214/deploy-flasher-qgvy6_ti/layout/FlashLayout_sdcard_stm32mp157c-dk2-trusted.tsv total size: 748 (0MB) No compression specified progress 100% (0MB) 0MB downloaded in 0.06s (0.01MB/s) end: 1.1.1.1 http-download (duration 00:00:00) [common] case: http-download
case_id: 24503
definition: lava
duration: 0.06
extra: ...
level: 1.1.1.1
namespace: common
result: pass
end: 1.1.1 download-retry (duration 00:00:00) [common] start: 1.1.2 download-retry (timeout 00:20:00) [common] start: 1.1.2.1 http-download (timeout 00:20:00) [common] downloading http://citools.st.com/artifacts/artifactory/oeivi-codex-st-com/oemanifest-int-mmdv/refs/tags/openstlinux-4.19-thud-mp1-19-04-05/openstlinux-weston/IMAGES-st-image-weston-openstlinux-weston-stm32mp1-openstlinux-4.19-thud-mp1-19-04-05.tar.xz saving as /var/lib/lava/dispatcher/tmp/lavafed-1214/deploy-flasher-qgvy6_ti/tarball/IMAGES-st-image-weston-openstlinux-weston-stm32mp1-openstlinux-4.19-thud-mp1-19-04-05.tar.xz total size: 618861804 (590MB) No compression specified progress 0% (0MB) progress 5% (29MB) progress 10% (59MB) progress 15% (88MB) progress 20% (118MB) progress 25% (147MB) progress 30% (177MB) progress 35% (206MB) progress 40% (236MB) progress 45% (265MB) progress 50% (295MB) progress 55% (324MB) progress 60% (354MB) progress 65% (383MB) progress 70% (413MB) progress 75% (442MB) progress 80% (472MB) progress 85% (501MB) progress 90% (531MB) progress 95% (560MB) progress 100% (590MB) 590MB downloaded in 55.76s (10.58MB/s) end: 1.1.2.1 http-download (duration 00:00:56) [common] case: http-download
case_id: 24503
definition: lava
duration: 55.76
extra: ...
level: 1.1.2.1
namespace: common
result: pass
end: 1.1.2 download-retry (duration 00:00:56) [common] start: 1.1.3 deploy-device-env (timeout 00:19:04) [common] end: 1.1.3 deploy-device-env (duration 00:00:00) [common] start: 1.1.4 lava-overlay (timeout 00:19:04) [common] [common] Preparing overlay tarball in /var/lib/lava/dispatcher/tmp/lavafed-1214/lava-overlay-z8sn4a5k makedir: /var/lib/lava/dispatcher/tmp/lavafed-1214/lava-overlay-z8sn4a5k/lava-1214/bin makedir: /var/lib/lava/dispatcher/tmp/lavafed-1214/lava-overlay-z8sn4a5k/lava-1214/tests makedir: /var/lib/lava/dispatcher/tmp/lavafed-1214/lava-overlay-z8sn4a5k/lava-1214/results Creating /var/lib/lava/dispatcher/tmp/lavafed-1214/lava-overlay-z8sn4a5k/lava-1214/bin/lava-add-keys Creating /var/lib/lava/dispatcher/tmp/lavafed-1214/lava-overlay-z8sn4a5k/lava-1214/bin/lava-add-sources Creating /var/lib/lava/dispatcher/tmp/lavafed-1214/lava-overlay-z8sn4a5k/lava-1214/bin/lava-background-process-start Creating /var/lib/lava/dispatcher/tmp/lavafed-1214/lava-overlay-z8sn4a5k/lava-1214/bin/lava-background-process-stop Creating /var/lib/lava/dispatcher/tmp/lavafed-1214/lava-overlay-z8sn4a5k/lava-1214/bin/lava-common-functions Creating /var/lib/lava/dispatcher/tmp/lavafed-1214/lava-overlay-z8sn4a5k/lava-1214/bin/lava-echo-ipv4 Creating /var/lib/lava/dispatcher/tmp/lavafed-1214/lava-overlay-z8sn4a5k/lava-1214/bin/lava-install-packages Creating /var/lib/lava/dispatcher/tmp/lavafed-1214/lava-overlay-z8sn4a5k/lava-1214/bin/lava-installed-packages Creating /var/lib/lava/dispatcher/tmp/lavafed-1214/lava-overlay-z8sn4a5k/lava-1214/bin/lava-os-build Creating /var/lib/lava/dispatcher/tmp/lavafed-1214/lava-overlay-z8sn4a5k/lava-1214/bin/lava-probe-channel Creating /var/lib/lava/dispatcher/tmp/lavafed-1214/lava-overlay-z8sn4a5k/lava-1214/bin/lava-probe-ip Creating /var/lib/lava/dispatcher/tmp/lavafed-1214/lava-overlay-z8sn4a5k/lava-1214/bin/lava-target-ip Creating /var/lib/lava/dispatcher/tmp/lavafed-1214/lava-overlay-z8sn4a5k/lava-1214/bin/lava-target-mac Creating /var/lib/lava/dispatcher/tmp/lavafed-1214/lava-overlay-z8sn4a5k/lava-1214/bin/lava-target-storage Creating /var/lib/lava/dispatcher/tmp/lavafed-1214/lava-overlay-z8sn4a5k/lava-1214/bin/lava-test-case Creating /var/lib/lava/dispatcher/tmp/lavafed-1214/lava-overlay-z8sn4a5k/lava-1214/bin/lava-test-event Creating /var/lib/lava/dispatcher/tmp/lavafed-1214/lava-overlay-z8sn4a5k/lava-1214/bin/lava-test-feedback Creating /var/lib/lava/dispatcher/tmp/lavafed-1214/lava-overlay-z8sn4a5k/lava-1214/bin/lava-test-raise Creating /var/lib/lava/dispatcher/tmp/lavafed-1214/lava-overlay-z8sn4a5k/lava-1214/bin/lava-test-reference Creating /var/lib/lava/dispatcher/tmp/lavafed-1214/lava-overlay-z8sn4a5k/lava-1214/bin/lava-test-runner Creating /var/lib/lava/dispatcher/tmp/lavafed-1214/lava-overlay-z8sn4a5k/lava-1214/bin/lava-test-set Creating /var/lib/lava/dispatcher/tmp/lavafed-1214/lava-overlay-z8sn4a5k/lava-1214/bin/lava-test-shell Updating /var/lib/lava/dispatcher/tmp/lavafed-1214/lava-overlay-z8sn4a5k/lava-1214/bin/lava-install-packages (oe) Updating /var/lib/lava/dispatcher/tmp/lavafed-1214/lava-overlay-z8sn4a5k/lava-1214/bin/lava-installed-packages (oe) Creating /var/lib/lava/dispatcher/tmp/lavafed-1214/lava-overlay-z8sn4a5k/lava-1214/environment start: 1.1.4.1 ssh-authorize (timeout 00:19:04) [common] end: 1.1.4.1 ssh-authorize (duration 00:00:00) [common] start: 1.1.4.2 lava-vland-overlay (timeout 00:19:04) [common] skipped lava-vland-overlay end: 1.1.4.2 lava-vland-overlay (duration 00:00:00) [common] start: 1.1.4.3 lava-multinode-overlay (timeout 00:19:04) [common] skipped lava-multinode-overlay end: 1.1.4.3 lava-multinode-overlay (duration 00:00:00) [common] start: 1.1.4.4 test-definition (timeout 00:19:04) [common] Loading test definitions start: 1.1.4.4.1 git-repo-action (timeout 00:19:04) [common] Using /lava-1214 at stage 0 Fetching tests from ssh://gerrit.st.com:29418/oeivi/test/lava/lava-tests Running '/usr/bin/git clone ssh://gerrit.st.com:29418/oeivi/test/lava/lava-tests /var/lib/lava/dispatcher/tmp/lavafed-1214/lava-overlay-z8sn4a5k/lava-1214/0/tests/0_TC_SYS_1_1' Running '/usr/bin/git checkout master Removing '.git' directory in /var/lib/lava/dispatcher/tmp/lavafed-1214/lava-overlay-z8sn4a5k/lava-1214/0/tests/0_TC_SYS_1_1 Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/lavafed-1214/lava-overlay-z8sn4a5k/lava-1214/0/tests/0_TC_SYS_1_1/tests-def/system/TC_SYS_1_1.yaml uuid=1214_1.1.4.4.1 testdef=None end: 1.1.4.4.1 git-repo-action (duration 00:00:05) [common] case: git-repo-action
case_id: 24505
definition: lava
duration: 5.01
extra: ...
level: 1.1.4.4.1
namespace: common
result: pass
start: 1.1.4.4.2 test-overlay (timeout 00:18:59) [common] end: 1.1.4.4.2 test-overlay (duration 00:00:00) [common] case: test-overlay
case_id: 24506
definition: lava
duration: 0.01
extra: ...
level: 1.1.4.4.2
namespace: common
result: pass
start: 1.1.4.4.3 test-install-overlay (timeout 00:18:59) [common] end: 1.1.4.4.3 test-install-overlay (duration 00:00:00) [common] case: test-install-overlay
case_id: 24507
definition: lava
duration: 0.01
extra: ...
level: 1.1.4.4.3
namespace: common
result: pass
start: 1.1.4.4.4 test-runscript-overlay (timeout 00:18:59) [common] runner path: /var/lib/lava/dispatcher/tmp/lavafed-1214/lava-overlay-z8sn4a5k/lava-1214/0/tests/0_TC_SYS_1_1 test_uuid 1214_1.1.4.4.1 end: 1.1.4.4.4 test-runscript-overlay (duration 00:00:00) [common] case: test-runscript-overlay
case_id: 24508
definition: lava
duration: 0.01
extra: ...
level: 1.1.4.4.4
namespace: common
result: pass
Creating lava-test-runner.conf files Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/lavafed-1214/lava-overlay-z8sn4a5k/lava-1214/0 for stage 0 - 0_TC_SYS_1_1 end: 1.1.4.4 test-definition (duration 00:00:05) [common] start: 1.1.4.5 compress-overlay (timeout 00:18:59) [common] end: 1.1.4.5 compress-overlay (duration 00:00:01) [common] start: 1.1.4.6 persistent-nfs-overlay (timeout 00:18:59) [common] end: 1.1.4.6 persistent-nfs-overlay (duration 00:00:00) [common] end: 1.1.4 lava-overlay (duration 00:00:06) [common] Calling: 'nice' 'ln' '-s' 'layout/FlashLayout_sdcard_stm32mp157c-dk2-trusted.tsv' '.' Returned 0 in 0 seconds Calling: 'nice' 'ls' '-l' >> total 8 >> lrwxrwxrwx 1 root root 53 Oct 9 03:05 FlashLayout_sdcard_stm32mp157c-dk2-trusted.tsv -> layout/FlashLayout_sdcard_stm32mp157c-dk2-trusted.tsv >> drwxr-xr-x 2 root root 4096 Oct 9 03:04 layout >> drwxr-xr-x 2 root root 4096 Oct 9 03:04 tarball Returned 0 in 0 seconds Calling: 'nice' '/root/git/lava-config/scripts/boot_management.sh' '-l' 'layout/FlashLayout_sdcard_stm32mp157c-dk2-trusted.tsv' '-z' 'tarball/IMAGES-st-image-weston-openstlinux-weston-stm32mp1-openstlinux-4.19-thud-mp1-19-04-05.tar.xz' '-e' '{UPDATE}' '-b' 'ttyACM.nucleo_lmecxd0318_4' '-s' '12_13' '-u' 'epowerswitch_03.lme.st.com' '-p' '3' '-d' '0027003F3338510534383330' '-t' 'mp157-disco' '-f' 'on' >> [03:05:30] [DEBUG] Boot Pin >> [03:05:30] #### BootManagement script started with layout/FlashLayout_sdcard_stm32mp157c-dk2-trusted.tsv tarball/IMAGES-st-image-weston-openstlinux-weston-stm32mp1-openstlinux-4.19-thud-mp1-19-04-05.tar.xz >> [03:05:30] [DEBUG] md5sum computation start >> [03:05:30] [DEBUG] md5sum computation done. >> ######### MD5SUM for FlashLayout_sdcard_stm32mp157c-dk2-trusted.tsv file >> Calculated : 5c03e872ee3bdf365ea08e23e903b463 >> ################################# >> [03:05:30] Boot media : mmc0 >> [03:05:30] *** Start normal boot procedure *** >> [03:05:30] *** Binary preparation *** >> [03:05:30] Tarball file extraction procedure for tarball/IMAGES-st-image-weston-openstlinux-weston-stm32mp1-openstlinux-4.19-thud-mp1-19-04-05.tar.xz >> [03:05:30] [DEBUG] md5sum computation start >> [03:05:32] [DEBUG] md5sum computation done. >> ######### MD5SUM for IMAGES-st-image-weston-openstlinux-weston-stm32mp1-openstlinux-4.19-thud-mp1-19-04-05.tar.xz file >> Calculated : c771a25d5ee82ac96fbbc369e653a77d >> ################################# >> [03:05:32] Tarball extraction start: >> [03:06:07] Tarball extraction done. >> [03:06:07] Try launching flasher operation number 1 >> [03:06:07] Wait to get lock for board in DFU... >> [03:06:08] Activate lock for board in DFU done. >> [03:06:08] Configure bootpins: >> [03:06:08] [DEBUG] BOOT_PIN INPUT: 12_13 >> [03:06:08] [DEBUG] BOOT_PIN[0]: 12 >> [03:06:08] [DEBUG] BOOT_PIN[1]: 13 >> [03:06:08] Reset boot pin to flash mode >> [03:06:08] [DEBUG] D12=0 >> [03:06:08] Wait to get lock for /dev/ttyACM.nucleo_lmecxd0318_4... >> [03:06:08] Activate lock for /dev/ttyACM.nucleo_lmecxd0318_4. >> [03:06:09] D12=0 >> [03:06:09] Release lock for /dev/ttyACM.nucleo_lmecxd0318_4 done. >> [03:06:09] [DEBUG] D13=0 >> [03:06:09] Wait to get lock for /dev/ttyACM.nucleo_lmecxd0318_4... >> [03:06:09] Activate lock for /dev/ttyACM.nucleo_lmecxd0318_4. >> [03:06:09] D13=0 >> [03:06:09] Release lock for /dev/ttyACM.nucleo_lmecxd0318_4 done. >> [03:06:10] [DEBUG] off Media_boot mmc0 >> [03:06:10] [DEBUG] D12=0 >> [03:06:10] Wait to get lock for /dev/ttyACM.nucleo_lmecxd0318_4... >> [03:06:10] Activate lock for /dev/ttyACM.nucleo_lmecxd0318_4. >> [03:06:10] D12=0 >> [03:06:10] Release lock for /dev/ttyACM.nucleo_lmecxd0318_4 done. >> [03:06:11] [DEBUG] D13=0 >> [03:06:11] Wait to get lock for /dev/ttyACM.nucleo_lmecxd0318_4... >> [03:06:11] Activate lock for /dev/ttyACM.nucleo_lmecxd0318_4. >> [03:06:11] D13=0 >> [03:06:11] Release lock for /dev/ttyACM.nucleo_lmecxd0318_4 done. >> [03:06:12] Wait for Relay to be established: 1 ms >> [03:06:13] Bootpins configure done. >> [03:06:13] [DEBUG] /root/git/lava-config/scripts/epower_switch.sh -u epowerswitch_03.lme.st.com -p 3 -c REBOOT >> [03:06:13] [DEBUG] last digit correct >> ePowerSwitch epowerswitch_03.lme.st.com selected 3 >> Send reboot command switch epowerswitch_03.lme.st.com on prt 3 >> [03:06:13] [DEBUG] Switch OFF Power supply >> [03:06:13] [DEBUG] CMD: wget -O - http://epowerswitch_03.lme.st.com/hidden.htm?M0:O3=Off >> [03:06:28] [DEBUG] Switch ON Power supply >> [03:06:28] [DEBUG] CMD: wget -O - http://epowerswitch_03.lme.st.com/hidden.htm?M0:O3=On >> [03:06:28] [DEBUG] Check ser2net service >> checking Serial port to network proxy...done (ser2net not running). >> Restarting Serial port to network proxy: ser2net. >> checking Serial port to network proxy...done (ser2net running). >> Trying 127.0.0.1... >> Trying ::1... >> telnet: Unable to connect to remote host: Cannot assign requested address >> [03:06:30] Wait for 15 seconds... >> [03:06:45] *** Flashing Procedure *** >> [03:06:45] Launch Flashing operation number 1 >> [03:06:45] Wait for DFU to be up... >> [03:06:45] [DEBUG] DFU detection loop: 1 >> [03:06:46] [DEBUG] USB DFU device(s) available on host >> [03:06:46] [DEBUG] *** /root/git/programmer/x86_64-linux/bin/STM32_Programmer_CLI -l usb *** >> [03:06:46] ------------------------------------------------------------------- >> [03:06:46] STM32CubeProgrammer v2.1.1 >> [03:06:46] ------------------------------------------------------------------- >> [03:06:46] >> [03:06:46] ===== DFU Interface ===== >> [03:06:46] >> [03:06:46] Total number of available STM32 device in DFU mode: 1 >> [03:06:46] >> [03:06:46] Device Index : USB1 >> [03:06:46] USB Bus Number : 002 >> [03:06:46] USB Address Number : 005 >> [03:06:46] Product ID : DFU in HS Mode @Device ID /0x500, @Revision ID /0x0000 >> [03:06:46] Serial number : 0027003F3338510534383330 >> [03:06:46] Firmware version : 0x0110 >> [03:06:46] Device ID : 0x0500 >> [03:06:46] [DEBUG] *** >> [03:06:46] Found USB DFU device usb1 for serial number 0027003f3338510534383330 >> [03:06:46] Wait for DFU during 1 seconds >> [03:06:46] *********************** Use following command to flash: *********************** >> [03:06:46] ARTIFACTS_DIR=/var/lib/lava/dispatcher/tmp/lavafed-1214/deploy-flasher-qgvy6_ti EXTRA_SPACE=0x40000000 bash /root/git/meta-st-valid/recipes-devtools/flashing/flashing-stm32mp/STM32_Programmer_CLI.sh no-ui extra -w layout/FlashLayout_sdcard_stm32mp157c-dk2-trusted.tsv -c port=usb1 -q >> [ROOTFS EXTRA SPACE configuration] >> EXTRA_SPACE: 0x40000000 >> >> [ARTIFACTS_DIR configuration] >> ARTIFACTS_DIR: /var/lib/lava/dispatcher/tmp/lavafed-1214/deploy-flasher-qgvy6_ti >> >> >> ============================================================== >> *** Populate binaries to device with TSV file: >> layout/FlashLayout_sdcard_stm32mp157c-dk2-trusted.tsv >> ============================================================== >> ------------------------------------------------------------------- >> STM32CubeProgrammer v2.1.1 >> ------------------------------------------------------------------- >> >> >> >> Warning: Timeout is forced to 65535 ms >> >> >> USB speed : High Speed (480MBit/s) >> Manuf. ID : STMicroelectronics >> Product ID : DFU in HS Mode @Device ID /0x500, @Revision ID /0x0000 >> SN : 0027003F3338510534383330 >> FW version : 0x0110 >> Device ID : 0x0500 >> Device name : STM32MPxxx >> Device type : MPU >> Device CPU : Cortex-A7 >> >> >> Start Embedded Flashing service >> >> >> >> Memory Programming ... >> Opening and parsing file: tf-a-stm32mp157c-dk2-trusted.stm32 >> File : tf-a-stm32mp157c-dk2-trusted.stm32 >> Size : 245360 Bytes >> Partition ID : 0x01 >> >> Download in Progress: >> >> >> File download complete >> Time elapsed during download operation: 00:00:00.613 >> >> RUNNING Program ... >> PartID: :0x01 >> Start operation done successfully at partition 0x01 >> >> Flashlayout Programming ... >> >> Running Flashlayout Partition ... >> Flashlayout partition started successfully >> >> >> Memory Programming ... >> Opening and parsing file: u-boot-stm32mp157c-dk2-trusted.stm32 >> File : u-boot-stm32mp157c-dk2-trusted.stm32 >> Size : 749102 Bytes >> Partition ID : 0x03 >> >> Download in Progress: >> >> >> File download complete >> Time elapsed during download operation: 00:00:01.107 >> >> RUNNING Program ... >> PartID: :0x03 >> >> reconnecting the device ... >> >> Error: unable to reconnect the target device: time out expired >> >> >> Error: Start operation failed at partition 0x03 >> Error: TSV flashing service failed >> [03:07:55] Flashing operation failed... >> [03:07:55] Launch Flashing operation number 2 >> [03:07:55] Wait for DFU to be up... >> [03:07:55] [DEBUG] DFU detection loop: 1 >> [03:07:55] [DEBUG] USB DFU device(s) available on host >> [03:07:55] [DEBUG] *** /root/git/programmer/x86_64-linux/bin/STM32_Programmer_CLI -l usb *** >> [03:07:55] ------------------------------------------------------------------- >> [03:07:55] STM32CubeProgrammer v2.1.1 >> [03:07:55] ------------------------------------------------------------------- >> [03:07:55] >> [03:07:55] ===== DFU Interface ===== >> [03:07:55] >> [03:07:55] Total number of available STM32 device in DFU mode: 1 >> [03:07:55] >> [03:07:55] Device Index : USB1 >> [03:07:55] USB Bus Number : 002 >> [03:07:55] USB Address Number : 005 >> [03:07:55] Product ID : USB download gadget@Device ID /0x500, @Revision ID /0x0000 >> [03:07:55] Serial number : 0027003F3338510534383330 >> [03:07:55] Firmware version : 0x0110 >> [03:07:55] Device ID : 0x0500 >> [03:07:55] [DEBUG] *** >> [03:07:55] Found USB DFU device usb1 for serial number 0027003f3338510534383330 >> [03:07:55] Wait for DFU during 0 seconds >> [03:07:55] *********************** Use following command to flash: *********************** >> [03:07:55] ARTIFACTS_DIR=/var/lib/lava/dispatcher/tmp/lavafed-1214/deploy-flasher-qgvy6_ti EXTRA_SPACE=0x40000000 bash /root/git/meta-st-valid/recipes-devtools/flashing/flashing-stm32mp/STM32_Programmer_CLI.sh no-ui extra -w layout/FlashLayout_sdcard_stm32mp157c-dk2-trusted.tsv -c port=usb1 -q >> [ROOTFS EXTRA SPACE configuration] >> EXTRA_SPACE: 0x40000000 >> >> [ARTIFACTS_DIR configuration] >> ARTIFACTS_DIR: /var/lib/lava/dispatcher/tmp/lavafed-1214/deploy-flasher-qgvy6_ti >> >> >> ============================================================== >> *** Populate binaries to device with TSV file: >> layout/FlashLayout_sdcard_stm32mp157c-dk2-trusted.tsv >> ============================================================== >> ------------------------------------------------------------------- >> STM32CubeProgrammer v2.1.1 >> ------------------------------------------------------------------- >> >> >> >> Warning: Timeout is forced to 65535 ms >> >> >> USB speed : High Speed (480MBit/s) >> Manuf. ID : STMicroelectronics >> Product ID : USB download gadget@Device ID /0x500, @Revision ID /0x0000 >> SN : 0027003F3338510534383330 >> FW version : 0x0110 >> Device ID : 0x0500 >> Device name : STM32MPxxx >> Device type : MPU >> Device CPU : Cortex-A7 >> >> >> Start Embedded Flashing service >> >> >> >> Memory Programming ... >> Opening and parsing file: tf-a-stm32mp157c-dk2-trusted.stm32 >> File : tf-a-stm32mp157c-dk2-trusted.stm32 >> Size : 245360 Bytes >> Partition ID : 0x04 >> >> Download in Progress: >> >> >> File download complete >> Time elapsed during download operation: 00:00:00.106 >> >> RUNNING Program ... >> PartID: :0x04 >> Start operation done successfully at partition 0x04 >> >> >> Memory Programming ... >> Opening and parsing file: tf-a-stm32mp157c-dk2-trusted.stm32 >> File : tf-a-stm32mp157c-dk2-trusted.stm32 >> Size : 245360 Bytes >> Partition ID : 0x05 >> >> Download in Progress: >> >> >> File download complete >> Time elapsed during download operation: 00:00:00.115 >> >> RUNNING Program ... >> PartID: :0x05 >> Start operation done successfully at partition 0x05 >> >> >> Memory Programming ... >> Opening and parsing file: u-boot-stm32mp157c-dk2-trusted.stm32 >> File : u-boot-stm32mp157c-dk2-trusted.stm32 >> Size : 749102 Bytes >> Partition ID : 0x06 >> >> Download in Progress: >> >> >> File download complete >> Time elapsed during download operation: 00:00:00.334 >> >> RUNNING Program ... >> PartID: :0x06 >> Start operation done successfully at partition 0x06 >> >> >> Memory Programming ... >> Opening and parsing file: st-image-bootfs-openstlinux-weston-stm32mp1.ext4 >> File : st-image-bootfs-openstlinux-weston-stm32mp1.ext4 >> Size : 64 MBytes >> Partition ID : 0x21 >> >> Download in Progress: >> >> >> File download complete >> Time elapsed during download operation: 00:00:38.373 >> >> RUNNING Program ... >> PartID: :0x21 >> Start operation done successfully at partition 0x21 >> >> >> Memory Programming ... >> Opening and parsing file: st-image-vendorfs-openstlinux-weston-stm32mp1.ext4 >> File : st-image-vendorfs-openstlinux-weston-stm32mp1.ext4 >> Size : 16 MBytes >> Partition ID : 0x22 >> >> Download in Progress: >> >> >> File download complete >> Time elapsed during download operation: 00:00:10.122 >> >> RUNNING Program ... >> PartID: :0x22 >> Start operation done successfully at partition 0x22 >> >> >> Memory Programming ... >> Opening and parsing file: st-image-weston-openstlinux-weston-stm32mp1.ext4 >> File : st-image-weston-openstlinux-weston-stm32mp1.ext4 >> Size : 506293 KBytes >> Partition ID : 0x23 >> >> Download in Progress: >> >> >> File download complete >> Time elapsed during download operation: 00:04:56.364 >> >> RUNNING Program ... >> PartID: :0x23 >> Start operation done successfully at partition 0x23 >> >> >> Memory Programming ... >> Opening and parsing file: st-image-userfs-openstlinux-weston-stm32mp1.ext4 >> File : st-image-userfs-openstlinux-weston-stm32mp1.ext4 >> Size : 95836 KBytes >> Partition ID : 0x24 >> >> Download in Progress: >> >> >> File download complete >> Time elapsed during download operation: 00:00:56.423 >> >> RUNNING Program ... >> PartID: :0x24 >> Start operation done successfully at partition 0x24 >> Flashing service completed successfully >> *** PROGRAMMER DONE *** >> [03:14:45] Flashing operation success >> [03:14:45] *************** Flashing operation complete with SUCCESS status *************** >> [03:14:45] Release lock for board in DFU done. >> [03:14:45] [DEBUG] RETURNCODE: 0 >> [03:14:45] Configure bootpins: >> [03:14:45] [DEBUG] BOOT_PIN INPUT: 12_13 >> [03:14:45] [DEBUG] BOOT_PIN[0]: 12 >> [03:14:45] [DEBUG] BOOT_PIN[1]: 13 >> [03:14:45] Reset boot pin to flash mode >> [03:14:45] [DEBUG] D12=0 >> [03:14:45] Wait to get lock for /dev/ttyACM.nucleo_lmecxd0318_4... >> [03:14:45] Activate lock for /dev/ttyACM.nucleo_lmecxd0318_4. >> [03:14:45] D12=0 >> [03:14:45] Release lock for /dev/ttyACM.nucleo_lmecxd0318_4 done. >> [03:14:45] [DEBUG] D13=0 >> [03:14:45] Wait to get lock for /dev/ttyACM.nucleo_lmecxd0318_4... >> [03:14:45] Activate lock for /dev/ttyACM.nucleo_lmecxd0318_4. >> [03:14:45] D13=0 >> [03:14:45] Release lock for /dev/ttyACM.nucleo_lmecxd0318_4 done. >> [03:14:45] [DEBUG] on Media_boot mmc0 >> [03:14:45] [DEBUG] D12=1 >> [03:14:45] Wait to get lock for /dev/ttyACM.nucleo_lmecxd0318_4... >> [03:14:45] Activate lock for /dev/ttyACM.nucleo_lmecxd0318_4. >> [03:14:45] D12=1 >> [03:14:45] Release lock for /dev/ttyACM.nucleo_lmecxd0318_4 done. >> [03:14:45] [DEBUG] D13=1 >> [03:14:45] Wait to get lock for /dev/ttyACM.nucleo_lmecxd0318_4... >> [03:14:45] Activate lock for /dev/ttyACM.nucleo_lmecxd0318_4. >> [03:14:45] D13=1 >> [03:14:45] Release lock for /dev/ttyACM.nucleo_lmecxd0318_4 done. >> [03:14:45] Wait for Relay to be established: 1 ms >> [03:14:46] Bootpins configure done. >> [03:14:46] [DEBUG] /root/git/lava-config/scripts/epower_switch.sh -u epowerswitch_03.lme.st.com -p 3 -c OFF >> [03:14:46] [DEBUG] last digit correct >> ePowerSwitch epowerswitch_03.lme.st.com selected 3 >> Set pwrSwitch epowerswitch_03.lme.st.com on prt 3 to Off >> [03:14:46] [DEBUG] Switch Off Power supply >> [03:14:46] [DEBUG] CMD: wget -O - http://epowerswitch_03.lme.st.com/hidden.htm?M0:O3=Off >> checking Serial port to network proxy...done (ser2net running). >> [03:14:46] Boot_management script successfully executed Returned 0 in 556 seconds end: 1.1 deploy-flasher (duration 00:10:19) [common] end: 1 deploy-flasher-retry (duration 00:10:19) [common] start: 2 minimal-boot (timeout 00:09:41) [common] start: 2.1 connect-device (timeout 00:09:41) [common] [common] connect-device Connecting to device using 'telnet localhost 2001' end: 2.1 connect-device (duration 00:00:00) [common] start: 2.2 reset-device (timeout 00:09:41) [common] start: 2.2.1 pdu-reboot (timeout 00:09:41) [common] Calling: 'nice' '/root/git/lava-config/scripts/epower_switch.sh' '-u' 'epowerswitch_03.lme.st.com' '-p' '3' '-c' 'REBOOT' >> [03:14:47] [DEBUG] last digit correct >> ePowerSwitch epowerswitch_03.lme.st.com selected 3 >> Send reboot command switch epowerswitch_03.lme.st.com on prt 3 >> [03:14:47] [DEBUG] Switch OFF Power supply >> [03:14:47] [DEBUG] CMD: wget -O - http://epowerswitch_03.lme.st.com/hidden.htm?M0:O3=Off >> [03:15:02] [DEBUG] Switch ON Power supply >> [03:15:02] [DEBUG] CMD: wget -O - http://epowerswitch_03.lme.st.com/hidden.htm?M0:O3=On >> [03:15:02] [DEBUG] Check ser2net service >> checking Serial port to network proxy...done (ser2net running). Returned 0 in 15 seconds end: 2.2.1 pdu-reboot (duration 00:00:16) [common] case: pdu-reboot
case_id: 24509
definition: lava
duration: 15.53
extra: ...
level: 2.2.1
namespace: common
result: pass
end: 2.2 reset-device (duration 00:00:16) [common] start: 2.3 auto-login-action (timeout 00:09:26) [common] auto-login-action: Wait for prompt ['Linux version [0-9]'] (timeout 00:10:00) Trying 127.0.0.1... Connected to localhost. Escape character is '^]'. ser2net port 2001 device /dev/ttyACM.mp157revc_disco_048 [115200 N81] (Debian GNU/Linux) NOTICE: CPU: STM32MP157CAC Rev.B NOTICE: Model: STMicroelectronics STM32MP157C-DK2 Discovery Board NOTICE: Board: MB1272 Var2 Rev.C-01 INFO: Reset reason (0x10): INFO: Reset due to a failure of VDD_CORE INFO: Using USB INFO: Instance 2 INFO: Boot used partition fsbl1 NOTICE: BL2: v2.0-r1.2(debug): NOTICE: BL2: Built : 13:13:37, Oct 2 2018 INFO: BL2: Doing platform setup INFO: PMIC version = 0x10 INFO: RAM: DDR3-1066/888 bin G 1x4Gb 533MHz v1.44 INOTICE: CPU: STM32MP157CAC Rev.B NOTICE: Model: STMicroelectronics STM32MP157C-DK2 Discovery Board NOTICE: Board: MB1272 Var2 Rev.C-01 INFO: Reset reason (0x15): INFO: Power-on Reset (rst_por) INFO: Using SDMMC INFO: Instance 1 INFO: Boot used partition fsbl1 NOTICE: BL2: v2.0-r1.2(debug): NOTICE: BL2: Built : 13:13:37, Oct 2 2018 INFO: BL2: Doing platform setup INFO: PMIC version = 0x10 INFO: RAM: DDR3-1066/888 bin G 1x4Gb 533MHz v1.44 INFO: Memory size = 0x20000000 (512 MB) INFO: BL2 runs SP_MIN setup INFO: BL2: Loading image id 4 INFO: Loading image id=4 at address 0x2fff0000 INFO: Image id=4 loaded: 0x2fff0000 - 0x30000000 INFO: BL2: Loading image id 5 INFO: Loading image id=5 at address 0xc0100000 INFO: STM32 Image size : 748846 WARNING: Skip signature check (header option) INFO: Image id=5 loaded: 0xc0100000 - 0xc01b6d2e INFO: read version 0 current version 0 NOTICE: BL2: Booting BL32 INFO: Entry point address = 0x2fff0000 INFO: SPSR = 0x1d3 INFO: PMIC version = 0x10 NOTICE: SP_MIN: v2.0-r1.2(debug): NOTICE: SP_MIN: Built : 13:13:37, Oct 2 2018 INFO: ARM GICv2 driver initialized INFO: stm32mp HSI (18): Secure only INFO: stm32mp HSE (20): Secure only INFO: stm32mp PLL2 (27): Secure only INFO: stm32mp PLL2_R (30): Secure only INFO: SP_MIN: Initializing runtime services INFO: SP_MIN: Preparing exit to normal world U-Boot 2018.11-stm32mp-r2.2 (Nov 14 2018 - 16:10:06 +0000) CPU: STM32MP157CAC Rev.B Model: STMicroelectronics STM32MP157C-DK2 Discovery Board Board: stm32mp1 in trusted mode (st,stm32mp157c-dk2) Board: MB1272 Var2 Rev.C-01 Watchdog enabled DRAM: 512 MiB Clocks: - MPU : 650 MHz - MCU : 208.878 MHz - AXI : 266.500 MHz - PER : 24 MHz - DDR : 533 MHz NAND: 0 MiB MMC: STM32 SDMMC2: 0, STM32 SDMMC2: 1 In: serial Out: serial Err: serial Net: eth0: ethernet@5800a000 Hit any key to stop autoboot: 1  0 Boot over mmc0! switch to partitions #0, OK mmc0 is current device Scanning mmc 0:4... Found U-Boot script /boot.scr.uimg 1553 bytes read in 1 ms (1.5 MiB/s) ## Executing script at c4100000 Scanning mmc 0:4... Found /mmc0_stm32mp157c-dk2_extlinux/extlinux.conf Retrieving file: /mmc0_stm32mp157c-dk2_extlinux/extlinux.conf 616 bytes read in 1 ms (601.6 KiB/s) Retrieving file: /mmc0_stm32mp157c-dk2_extlinux/../splash.bmp 46180 bytes read in 3 ms (14.7 MiB/s) Select the boot mode 1: stm32mp157c-dk2-sdcard 2: stm32mp157c-dk2-a7-examples-sdcard 3: stm32mp157c-dk2-m4-examples-sdcard Enter choice: 1: stm32mp157c-dk2-sdcard Retrieving file: /uImage 6575912 bytes read in 288 ms (21.8 MiB/s) append: root=/dev/mmcblk0p6 rootwait rw console=ttySTM0,115200 Retrieving file: /stm32mp157c-dk2.dtb 69672 bytes read in 4 ms (16.6 MiB/s) ## Booting kernel from Legacy Image at c2000000 ... Image Name: Linux-4.19.26 Image Type: ARM Linux Kernel Image (uncompressed) Data Size: 6575848 Bytes = 6.3 MiB Load Address: c2000040 Entry Point: c2000040 Verifying Checksum ... OK ## Flattened Device Tree blob at c4000000 Booting using the fdt blob at 0xc4000000 XIP Kernel Image ... OK Using Device Tree in place at c4000000, end c4014027 Starting kernel ... [ 0.000000] 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*) \\]-+)', 'root@stm32mp1:|root@stm32mp1-\\S+:', 'stm32mp1 login:|stm32mp1-(.*) login:', 'Login incorrect'] [auto-login-action] Waiting for messages, (timeout 00:09:15) [ 0.000000] Linux version 4.19.26 (oe-user@oe-host) (gcc version 8.2.0 (GCC)) #1 SMP PREEMPT Wed Feb 27 09:09:03 UTC 2019 [ 0.000000] CPU: ARMv7 Processor [410fc075] revision 5 (ARMv7), cr=10c5387d [ 0.000000] CPU: div instructions available: patching division code [ 0.000000] CPU: PIPT / VIPT nonaliasing data cache, VIPT aliasing instruction cache [ 0.000000] OF: fdt: Machine model: STMicroelectronics STM32MP157C-DK2 Discovery Board [ 0.000000] Memory policy: Data cache writealloc [ 0.000000] Reserved memory: created DMA memory pool at 0x10000000, size 0 MiB [ 0.000000] OF: reserved mem: initialized node mcuram2@0x10000000, compatible id shared-dma-pool [ 0.000000] Reserved memory: created DMA memory pool at 0x10040000, size 0 MiB [ 0.000000] OF: reserved mem: initialized node vdev0vring0@10040000, compatible id shared-dma-pool [ 0.000000] Reserved memory: created DMA memory pool at 0x10042000, size 0 MiB [ 0.000000] OF: reserved mem: initialized node vdev0vring1@10042000, compatible id shared-dma-pool [ 0.000000] Reserved memory: created DMA memory pool at 0x10044000, size 0 MiB [ 0.000000] OF: reserved mem: initialized node vdev0buffer@10044000, compatible id shared-dma-pool [ 0.000000] Reserved memory: created DMA memory pool at 0x30000000, size 0 MiB [ 0.000000] OF: reserved mem: initialized node mcuram@0x30000000, compatible id shared-dma-pool [ 0.000000] Reserved memory: created DMA memory pool at 0x38000000, size 0 MiB [ 0.000000] OF: reserved mem: initialized node retram@0x38000000, compatible id shared-dma-pool [ 0.000000] cma: Reserved 128 MiB at 0xd4000000 [ 0.000000] psci: probing for conduit method from DT. [ 0.000000] psci: PSCIv1.1 detected in firmware. [ 0.000000] psci: Using standard PSCI v0.2 function IDs [ 0.000000] psci: MIGRATE_INFO_TYPE not supported. [ 0.000000] psci: SMC Calling Convention v1.0 [ 0.000000] random: get_random_bytes called from start_kernel+0xa0/0x494 with crng_init=0 [ 0.000000] percpu: Embedded 17 pages/cpu @(ptrval) s40204 r8192 d21236 u69632 [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 113680 [ 0.000000] Kernel command line: root=/dev/mmcblk0p6 rootwait rw console=ttySTM0,115200 [ 0.000000] Dentry cache hash table entries: 65536 (order: 6, 262144 bytes) [ 0.000000] Inode-cache hash table entries: 32768 (order: 5, 131072 bytes) [ 0.000000] Memory: 306140K/458752K available (10240K kernel code, 817K rwdata, 2864K rodata, 1024K init, 208K bss, 21540K reserved, 131072K cma-reserved, 0K highmem) [ 0.000000] Virtual kernel memory layout: [ 0.000000] vector : 0xffff0000 - 0xffff1000 ( 4 kB) [ 0.000000] fixmap : 0xffc00000 - 0xfff00000 (3072 kB) [ 0.000000] vmalloc : 0xdc800000 - 0xff800000 ( 560 MB) [ 0.000000] lowmem : 0xc0000000 - 0xdc000000 ( 448 MB) [ 0.000000] pkmap : 0xbfe00000 - 0xc0000000 ( 2 MB) [ 0.000000] modules : 0xbf000000 - 0xbfe00000 ( 14 MB) [ 0.000000] .text : 0x(ptrval) - 0x(ptrval) (11232 kB) [ 0.000000] .init : 0x(ptrval) - 0x(ptrval) (1024 kB) [ 0.000000] .data : 0x(ptrval) - 0x(ptrval) ( 818 kB) [ 0.000000] .bss : 0x(ptrval) - 0x(ptrval) ( 209 kB) [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1 [ 0.000000] rcu: Preemptible hierarchical RCU implementation. [ 0.000000] rcu: RCU event tracing is enabled. [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=4 to nr_cpu_ids=2. [ 0.000000] Tasks RCU enabled. [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=2 [ 0.000000] NR_IRQS: 16, nr_irqs: 16, preallocated irqs: 16 [ 0.000000] /soc/interrupt-controller@5000d000: bank0 [ 0.000000] /soc/interrupt-controller@5000d000: bank1 [ 0.000000] /soc/interrupt-controller@5000d000: bank2 [ 0.000000] arch_timer: cp15 timer(s) running at 24.00MHz (virt). [ 0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x588fe9dc0, max_idle_ns: 440795202592 ns [ 0.000009] sched_clock: 56 bits at 24MHz, resolution 41ns, wraps every 4398046511097ns [ 0.000028] Switching to timer-based delay loop, resolution 41ns [ 0.001924] Console: colour dummy device 80x30 [ 0.001975] Calibrating delay loop (skipped), value calculated using timer frequency.. 48.00 BogoMIPS (lpj=240000) [ 0.002000] pid_max: default: 32768 minimum: 301 [ 0.002234] Mount-cache hash table entries: 1024 (order: 0, 4096 bytes) [ 0.002256] Mountpoint-cache hash table entries: 1024 (order: 0, 4096 bytes) [ 0.003270] CPU: Testing write buffer coherency: ok [ 0.003774] CPU0: update cpu_capacity 1024 [ 0.003797] CPU0: thread -1, cpu 0, socket 0, mpidr 80000000 [ 0.059787] Setting up static identity map for 0xc0100000 - 0xc0100060 [ 0.079774] rcu: Hierarchical SRCU implementation. [ 0.119928] smp: Bringing up secondary CPUs ... [ 0.200530] CPU1: update cpu_capacity 1024 [ 0.200543] CPU1: thread -1, cpu 1, socket 0, mpidr 80000001 [ 0.200752] smp: Brought up 1 node, 2 CPUs [ 0.200784] SMP: Total of 2 processors activated (96.00 BogoMIPS). [ 0.200795] CPU: All CPU(s) started in SVC mode. [ 0.201952] devtmpfs: initialized [ 0.227847] VFP support v0.3: implementor 41 architecture 2 part 30 variant 7 rev 5 [ 0.228376] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns [ 0.228413] futex hash table entries: 512 (order: 3, 32768 bytes) [ 0.234497] pinctrl core: initialized pinctrl subsystem [ 0.236624] NET: Registered protocol family 16 [ 0.243167] DMA: preallocated 256 KiB pool for atomic coherent allocations [ 0.245668] hw-breakpoint: found 5 (+1 reserved) breakpoint and 4 watchpoint registers. [ 0.245690] hw-breakpoint: maximum watchpoint size is 8 bytes. [ 0.245977] Serial: AMBA PL011 UART driver [ 0.248142] stm32-pm-domain pm_domain: domain core-ret-power-domain registered [ 0.248181] stm32-pm-domain pm_domain: subdomain core-power-domain registered [ 0.248197] stm32-pm-domain pm_domain: domains probed [ 0.264028] stm32mp157-pinctrl soc:pin-controller@50002000: GPIOA bank added [ 0.264377] stm32mp157-pinctrl soc:pin-controller@50002000: GPIOB bank added [ 0.264665] stm32mp157-pinctrl soc:pin-controller@50002000: GPIOC bank added [ 0.264957] stm32mp157-pinctrl soc:pin-controller@50002000: GPIOD bank added [ 0.265233] stm32mp157-pinctrl soc:pin-controller@50002000: GPIOE bank added [ 0.265502] stm32mp157-pinctrl soc:pin-controller@50002000: GPIOF bank added [ 0.265763] stm32mp157-pinctrl soc:pin-controller@50002000: GPIOG bank added [ 0.266037] stm32mp157-pinctrl soc:pin-controller@50002000: GPIOH bank added [ 0.266329] stm32mp157-pinctrl soc:pin-controller@50002000: GPIOI bank added [ 0.266537] stm32mp157-pinctrl soc:pin-controller@50002000: Pinctrl STM32 initialized [ 0.267423] stm32mp157-pinctrl soc:pin-controller-z@54004000: GPIOZ bank added [ 0.267451] stm32mp157-pinctrl soc:pin-controller-z@54004000: Pinctrl STM32 initialized [ 0.287480] stm32-mdma 58000000.dma: STM32 MDMA driver registered [ 0.292767] SCSI subsystem initialized [ 0.293570] usbcore: registered new interface driver usbfs [ 0.293666] usbcore: registered new interface driver hub [ 0.293818] usbcore: registered new device driver usb [ 0.294136] pps_core: LinuxPPS API ver. 1 registered [ 0.294150] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it> [ 0.294183] PTP clock support registered [ 0.294473] EDAC MC: Ver: 3.0.0 [ 0.295362] Advanced Linux Sound Architecture Driver Initialized. [ 0.296978] clocksource: Switched to clocksource arch_sys_counter [ 0.392149] NET: Registered protocol family 2 [ 0.393201] tcp_listen_portaddr_hash hash table entries: 512 (order: 0, 6144 bytes) [ 0.393256] TCP established hash table entries: 4096 (order: 2, 16384 bytes) [ 0.393323] TCP bind hash table entries: 4096 (order: 3, 32768 bytes) [ 0.393421] TCP: Hash tables configured (established 4096 bind 4096) [ 0.393568] UDP hash table entries: 256 (order: 1, 8192 bytes) [ 0.393615] UDP-Lite hash table entries: 256 (order: 1, 8192 bytes) [ 0.394099] NET: Registered protocol family 1 [ 0.395020] RPC: Registered named UNIX socket transport module. [ 0.395043] RPC: Registered udp transport module. [ 0.395054] RPC: Registered tcp transport module. [ 0.395065] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.396144] hw perfevents: enabled with armv7_cortex_a7 PMU driver, 5 counters available [ 0.398541] Initialise system trusted keyrings [ 0.398927] workingset: timestamp_bits=14 max_order=17 bucket_order=3 [ 0.409924] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ 0.411083] NFS: Registering the id_resolver key type [ 0.411131] Key type id_resolver registered [ 0.411144] Key type id_legacy registered [ 0.411370] ntfs: driver 2.1.32 [Flags: R/O]. [ 0.411871] jffs2: version 2.2. (NAND) © 2001-2006 Red Hat, Inc. [ 0.412815] fuse init (API version 7.27) [ 0.413513] pstore: using deflate compression [ 0.416441] NET: Registered protocol family 38 [ 0.416469] Key type asymmetric registered [ 0.416482] Asymmetric key parser 'x509' registered [ 0.416580] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 0.416597] io scheduler noop registered [ 0.416609] io scheduler deadline registered [ 0.416870] io scheduler cfq registered (default) [ 0.416888] io scheduler mq-deadline registered [ 0.416900] io scheduler kyber registered [ 0.418414] stm32-usbphyc 5a006000.usbphyc: Linked as a consumer to regulator.1 [ 0.418538] stm32-usbphyc 5a006000.usbphyc: Linked as a consumer to regulator.2 [ 0.418665] stm32-usbphyc 5a006000.usbphyc: Dropping the link to regulator.2 [ 0.418774] stm32-usbphyc 5a006000.usbphyc: Dropping the link to regulator.1 [ 0.423510] stm32-dma 48000000.dma: STM32 DMA driver registered [ 0.425314] stm32-dma 48001000.dma: STM32 DMA driver registered [ 0.509309] STM32 USART driver initialized [ 0.510668] 4000e000.serial: ttySTM3 at MMIO 0x4000e000 (irq = 25, base_baud = 4000000) is a stm32-usart [ 0.510972] serial serial0: tty port ttySTM3 registered [ 0.511032] stm32-usart 4000e000.serial: interrupt mode used for rx (no dma) [ 0.511049] stm32-usart 4000e000.serial: interrupt mode used for tx (no dma) [ 0.512278] 40010000.serial: ttySTM0 at MMIO 0x40010000 (irq = 27, base_baud = 4000000) is a stm32-usart [ 1.457088] console [ttySTM0] enabled [ 1.461326] stm32-usart 40010000.serial: interrupt mode used for rx (no dma) [ 1.467790] stm32-usart 40010000.serial: interrupt mode used for tx (no dma) [ 1.482808] stm32-display-dsi 5a000000.dsi: Linked as a consumer to regulator.2 [ 1.493154] panel-orisetech-otm8009a 5a000000.dsi.0: 5a000000.dsi.0 supply power not found, using dummy regulator [ 1.498126] random: fast init done [ 1.502227] panel-orisetech-otm8009a 5a000000.dsi.0: Linked as a consumer to regulator.0 [ 1.513213] random: crng init done [ 1.532572] brd: module loaded [ 1.547316] loop: module loaded [ 1.555162] libphy: Fixed MDIO Bus: probed [ 1.559359] CAN device driver interface [ 1.564182] stm32-dwmac 5800a000.ethernet: PTP uses main clock [ 1.568655] stm32-dwmac 5800a000.ethernet: no reset control found [ 1.574703] stm32-dwmac 5800a000.ethernet: No phy clock provided... [ 1.582021] stm32-dwmac 5800a000.ethernet: User ID: 0x40, Synopsys ID: 0x42 [ 1.587988] stm32-dwmac 5800a000.ethernet: DWMAC4/5 [ 1.592864] stm32-dwmac 5800a000.ethernet: DMA HW capability register supported [ 1.600207] stm32-dwmac 5800a000.ethernet: RX Checksum Offload Engine supported [ 1.607517] stm32-dwmac 5800a000.ethernet: TX Checksum insertion supported [ 1.614372] stm32-dwmac 5800a000.ethernet: Wake-Up On Lan supported [ 1.620666] stm32-dwmac 5800a000.ethernet: TSO supported [ 1.625956] stm32-dwmac 5800a000.ethernet: Enable RX Mitigation via HW Watchdog Timer [ 1.633821] stm32-dwmac 5800a000.ethernet: TSO feature enabled [ 1.639899] libphy: stmmac: probed [ 1.645678] pegasus: v0.9.3 (2013/04/25), Pegasus/Pegasus II USB Ethernet driver [ 1.651818] usbcore: registered new interface driver pegasus [ 1.657459] usbcore: registered new interface driver asix [ 1.662755] usbcore: registered new interface driver ax88179_178a [ 1.668965] usbcore: registered new interface driver cdc_ether [ 1.674684] usbcore: registered new interface driver smsc75xx [ 1.680482] usbcore: registered new interface driver smsc95xx [ 1.686163] usbcore: registered new interface driver net1080 [ 1.691881] usbcore: registered new interface driver cdc_subset [ 1.697798] usbcore: registered new interface driver zaurus [ 1.703359] usbcore: registered new interface driver cdc_ncm [ 1.710207] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver [ 1.715505] ehci-platform: EHCI generic platform driver [ 1.721268] ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver [ 1.726902] ohci-platform: OHCI generic platform driver [ 1.732882] usbcore: registered new interface driver usb-storage [ 1.743103] stm32_rtc 5c004000.rtc: rtc core: registered 5c004000.rtc as rtc0 [ 1.749392] stm32_rtc 5c004000.rtc: Date/Time must be initialized [ 1.754888] stm32_rtc 5c004000.rtc: registered rev:1.2 [ 1.760648] i2c /dev entries driver [ 1.784309] stm32f7-i2c 40012000.i2c: can't use DMA [ 1.791515] sii902x 0-0039: Failed to get supply 'iovcc': -517 [ 1.796849] stm32f7-i2c 40012000.i2c: STM32F7 I2C-0 bus adapter [ 1.822857] stm32f7-i2c 5c002000.i2c: can't use DMA [ 1.828898] typec_stusb 1-0028: STUSB driver registered [ 1.834465] stpmic1 1-0033: PMIC Chip Version: 0x10 [ 1.843655] BUCK1: supplied by regulator-dummy [ 1.850685] BUCK2: supplied by regulator-dummy [ 1.857215] BUCK3: supplied by regulator-dummy [ 1.864089] BUCK4: supplied by regulator-dummy [ 1.870687] LDO1: supplied by v3v3 [ 1.878252] LDO2: supplied by regulator-dummy [ 1.884984] LDO3: supplied by vdd_ddr [ 1.891198] LDO4: supplied by regulator-dummy [ 1.895686] LDO5: supplied by regulator-dummy [ 1.902559] LDO6: supplied by v3v3 [ 1.908386] VREF_DDR: supplied by regulator-dummy [ 1.914857] BOOST: supplied by regulator-dummy [ 1.919088] VBUS_OTG: supplied by bst_out [ 1.922977] SW_OUT: supplied by bst_out [ 1.930300] input: pmic_onkey as /devices/platform/soc/5c002000.i2c/i2c-1/1-0033/5c002000.i2c:stpmic@33:onkey/input/input0 [ 1.940589] stm32f7-i2c 5c002000.i2c: STM32F7 I2C-1 bus adapter [ 1.949594] stm_thermal 50028000.thermal: stm_thermal_probe: Driver initialized successfully [ 1.960294] mmci-pl18x 58005000.sdmmc: Linked as a consumer to regulator.8 [ 1.965903] mmci-pl18x 58005000.sdmmc: mmc0: PL180 manf 53 rev2 at 0x58005000 irq 58,0 (pio) [ 2.003119] mmci-pl18x 58007000.sdmmc: allocated mmc-pwrseq [ 2.007667] mmci-pl18x 58007000.sdmmc: Linked as a consumer to regulator.8 [ 2.015355] mmci-pl18x 58007000.sdmmc: mmc1: PL180 manf 53 rev1 at 0x58007000 irq 59,0 (pio) [ 2.049314] sdhci: Secure Digital Host Controller Interface driver [ 2.054160] sdhci: Copyright(c) Pierre Ossman [ 2.058481] mmc0: host does not support reading read-only switch, assuming write-enable [ 2.067543] Synopsys Designware Multimedia Card Interface Driver [ 2.073072] sdhci-pltfm: SDHCI platform and OF driver helper [ 2.079311] mmc0: new high speed SDHC card at address aaaa [ 2.083631] ledtrig-cpu: registered to indicate activity on CPUs [ 2.090736] usbcore: registered new interface driver usbhid [ 2.095200] usbhid: USB HID core driver [ 2.095897] mmcblk0: mmc0:aaaa SS16G 14.8 GiB [ 2.100868] stm32-ipcc 4c001000.mailbox: ipcc rev:1.0 enabled, 6 chans, proc 0 [ 2.112189] stm32-rproc m4@0: wdg irq registered [ 2.115615] remoteproc remoteproc0: m4 is available [ 2.121113] vref: supplied by vdd [ 2.123831] stm32-adc-core 48003000.adc: Linked as a consumer to regulator.4 [ 2.131055] stm32-adc-core 48003000.adc: Linked as a consumer to regulator.7 [ 2.140828] mmc1: queuing unknown CIS tuple 0x80 (2 bytes) [ 2.147080] mmc1: queuing unknown CIS tuple 0x80 (3 bytes) [ 2.152828] mmc1: queuing unknown CIS tuple 0x80 (3 bytes) [ 2.158070] mmcblk0: p1 p2 p3 p4 p5 p6 p7 [ 2.164584] mmc1: queuing unknown CIS tuple 0x80 (7 bytes) [ 2.166838] iio iio:device2: Can't get offset/scale: -517 [ 2.175248] mmc1: queuing unknown CIS tuple 0x80 (3 bytes) [ 2.177527] optee: probing for conduit method from DT. [ 2.184673] optee: api uid mismatch [ 2.190035] mmc1: queuing unknown CIS tuple 0x80 (6 bytes) [ 2.198009] NET: Registered protocol family 17 [ 2.201072] can: controller area network core (rev 20170425 abi 9) [ 2.207607] NET: Registered protocol family 29 [ 2.211658] can: raw protocol (rev 20170425) [ 2.215918] can: broadcast manager protocol (rev 20170425 t) [ 2.221644] can: netlink gateway (rev 20170425) max_hops=1 [ 2.227685] Key type dns_resolver registered [ 2.231498] ThumbEE CPU extension supported. [ 2.235618] Registering SWP/SWPB emulation handler [ 2.241554] registered taskstats version 1 [ 2.244490] Loading compiled-in X.509 certificates [ 2.276880] stm32-usbphyc 5a006000.usbphyc: Linked as a consumer to regulator.1 [ 2.282987] stm32-usbphyc 5a006000.usbphyc: Linked as a consumer to regulator.2 [ 2.290250] stm32-usbphyc 5a006000.usbphyc: Linked as a consumer to regulator.12 [ 2.298341] stm32-usbphyc 5a006000.usbphyc: registered rev:1.0 [ 2.305474] dwc2 49000000.usb-otg: 49000000.usb-otg supply vusb_d not found, using dummy regulator [ 2.313239] dwc2 49000000.usb-otg: Linked as a consumer to regulator.0 [ 2.319619] dwc2 49000000.usb-otg: 49000000.usb-otg supply vusb_a not found, using dummy regulator [ 2.341080] dwc2 49000000.usb-otg: EPs: 9, dedicated fifos, 952 entries in SPRAM [ 2.348910] ehci-platform 5800d000.usbh-ehci: EHCI Host Controller [ 2.353702] ehci-platform 5800d000.usbh-ehci: new USB bus registered, assigned bus number 1 [ 2.362575] ehci-platform 5800d000.usbh-ehci: irq 63, io mem 0x5800d000 [ 2.397064] ehci-platform 5800d000.usbh-ehci: USB 2.0 started, EHCI 1.00 [ 2.404175] hub 1-0:1.0: USB hub found [ 2.406562] hub 1-0:1.0: 2 ports detected [ 2.411350] mmc1: new high speed SDIO card at address 0001 [ 2.413931] sii902x 0-0039: Linked as a consumer to regulator.10 [ 2.422157] sii902x 0-0039: Linked as a consumer to regulator.14 [ 2.432212] i2c i2c-0: Added multiplexed i2c bus 2 [ 2.438231] cs42l51 0-004a: Linked as a consumer to regulator.8 [ 2.442793] cs42l51 0-004a: Linked as a consumer to regulator.9 [ 2.451474] cs42l51 0-004a: Cirrus Logic CS42L51, Revision: 01 [ 2.459707] asoc-audio-graph-card sound: cs42l51-hifi0 <-> 4400b004.audio-controller mapping ok [ 2.467951] asoc-audio-graph-card sound: cs42l51-hifi1 <-> 4400b024.audio-controller mapping ok [ 2.476032] asoc-audio-graph-card sound: i2s-hifi <-> 4000b000.audio-controller mapping ok [ 2.485225] cs42l51 0-004a: ASoC: mux DAC Mux has no paths [ 2.730404] [drm] ltdc hw version 0x00010300 - ready [ 2.734005] [drm] Supports vblank timestamp caching Rev 2 (21.10.2013). [ 2.740603] [drm] Driver supports precise vblank timestamp query. [ 2.747561] [drm] Initialized stm 1.0.0 20170330 for 5a001000.display-controller on minor 0 [ 2.756155] stm32_rtc 5c004000.rtc: setting system clock to 2000-01-01 00:00:12 UTC (946684812) [ 2.764751] vdda: disabling [ 2.766918] ALSA device list: [ 2.769501] #0: STM32MP�[ 2.777099] usb 1-1: new high-speed USB device number 2 using ehci-platform [ 2.815005] EXT4-fs (mmcblk0p6): mounted filesystem with ordered data mode. Opts: (null) [ 2.821790] VFS: Mounted root (ext4 filesystem) on device 179:6. [ 2.833674] devtmpfs: mounted [ 2.837101] Freeing unused kernel memory: 1024K [ 2.840500] Run /sbin/init as init process [ 2.978831] hub 1-1:1.0: USB hub found [ 2.981391] hub 1-1:1.0: 4 ports detected [ 3.089992] systemd[1]: System time before build time, advancing clock. [ 3.190020] NET: Registered protocol family 10 [ 3.228083] Segment Routing with IPv6 [ 3.248591] systemd[1]: systemd 239 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) [ 3.269679] systemd[1]: Detected architecture arm. Welcome to [1mST OpenSTLinux - Weston - (A Yocto Project Based Distro) 2.6-openstlinux-4.19-thud-mp1-19-04-05 (thud)[0m! [ 3.330205] systemd[1]: Set hostname to <stm32mp1>. [ 3.344064] systemd[1]: Initializing machine ID from random generator. [ 3.370932] systemd[1]: Hardware watchdog 'STM32 Independent Watchdog', version 0 [ 3.378142] systemd[1]: Set hardware watchdog to 30s. [ 4.186419] systemd[1]: Unnecessary job for dev-ttySTM0.device was removed. [ 4.195111] systemd[1]: Listening on Journal Socket (/dev/log). [[0;32m OK [0m] Listening on Journal Socket (/dev/log). [ 4.236466] systemd[1]: Created slice User and Session Slice. [[0;32m OK [0m] Created slice User and Session Slice. [ 4.267797] systemd[1]: Listening on initctl Compatibility Named Pipe. [[0;32m OK [0m] Listening on initctl Compatibility Named Pipe. [[0;32m OK [0m] Listening on Journal Socket. Starting Create list of required st…ce nodes for the current kernel... Starting Load Kernel Modules... [[0;32m OK [0m] Reached target Remote File Systems. [[0;32m OK [0m] Listening on udev Control Socket. [[0;32m OK [0m] Reached target Swap. [[0;32m OK [0m] Listening on Network Service Netlink Socket. [[0;32m OK [0m] Started Forward Password Requests to Wall Directory Watch. Mounting Temporary Directory (/tmp)... Starting Remount Root and Kernel File Systems... Mounting POSIX Message Queue File System... [ 4.662474] EXT4-fs (mmcblk0p6): re-mounted. Opts: (null) [[0;32m OK [0m] Listening on Syslog Socket. Starting Journal Service... [[0;32m OK [0m] Listening on Process Core Dump Socket. [[0;32m OK [0m] Created slice system-serial\x2dgetty.slice. Starting Mount partitions... Mounting Kernel Debug File System... [[0;32m OK [0m] Started Hardware RNG Entropy Gatherer Daemon. [[0;32m OK [0m] Reached target Slices. [[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 Kernel Socket. Starting udev Coldplug all Devices... [[0;32m OK [0m] Created slice system-getty.slice. [[0;32m OK [0m] Started Journal Service. [[0;32m OK [0m] Started Create list of required sta…vice nodes for the current kernel. [[0;32m OK [0m] Started Load Kernel Modules. [[0;32m OK [0m] Mounted Temporary Directory (/tmp). [[0;32m OK [0m] Started Remount Root and Kernel File Systems. [ 5.228994] EXT4-fs (mmcblk0p4): mounted filesystem with ordered data mode. Opts: (null) [[0;32m OK [0m] Mounted POSIX Message Queue File System. [[0;32m OK [0m] Mounted Kernel Debug File System. [[0;32m OK [0m] Started Starts Psplash Boot screen. Starting Rebuild Hardware Database... Starting Create System Users... Mounting FUSE Control File System... Mounting Kernel Configuration File System... Starting Apply Kernel Variables... Starting Flush Journal to Persistent Storage... [[0;32m OK [0m] Started Create System Users. [[0;32m OK [0m] Mounted FUSE Control File System. [[0;32m OK [0m] Mounted Kernel Configuration File System. [[0;32m OK [0m] Started Apply Kernel Variables. [ 5.868256] systemd-journald[153]: Received request to flush runtime journal from PID 1 [ 5.875356] EXT4-fs (mmcblk0p7): mounted filesystem with ordered data mode. Opts: (null) Starting Create Static Device Nodes in /dev... [[0;32m OK [0m] Started Flush Journal to Persistent Storage. [[0;32m OK [0m] Started Create Static Device Nodes in /dev. [[0;32m OK [0m] Reached target Local File Systems (Pre). Mounting /var/volatile... [[0;32m OK [0m] Reached target Containers. [[0;32m OK [0m] Mounted /var/volatile. Starting Load/Save Random Seed... [[0;32m OK [0m] Started Load/Save Random Seed. [ 6.337655] EXT4-fs (mmcblk0p5): mounted filesystem with ordered data mode. Opts: (null) [[0;32m OK [0m] Started Mount partitions. [[0;32m OK [0m] Reached target Local File Systems. Starting Rebuild Journal Catalog... Starting Create Volatile Files and Directories... Starting Rebuild Dynamic Linker Cache... [[0;32m OK [0m] Started Rebuild Journal Catalog. [[0;32m OK [0m] Started Create Volatile Files and Directories. Starting Update UTMP about System Boot/Shutdown... Starting dpkg first boot configure... Starting Run pending postinsts... Starting Network Time Synchronization... [[0;32m OK [0m] Started Update UTMP about System Boot/Shutdown. [[0;32m OK [0m] Started Run pending postinsts. [[0;32m OK [0m] Started Network Time Synchronization. [[0;32m OK [0m] Reached target System Time Synchronized. [[0;32m OK [0m] Started dpkg first boot configure. [[0;32m OK [0m] Started udev Coldplug all Devices. [[0;32m OK [0m] Started Rebuild Dynamic Linker Cache. [[0;32m OK [0m] Started Rebuild Hardware Database. Starting Update is Completed... Starting udev Kernel Device Manager... [[0;32m OK [0m] Started Update is Completed. [[0;32m OK [0m] Started udev Kernel Device Manager. [[0;32m OK [0m] Reached target System Initialization. Starting Console System Startup Logging... [[0;32m OK [0m] Listening on Avahi mDNS/DNS-SD Stack Activation Socket. [[0;32m OK [0m] Listening on D-Bus System Message Bus Socket. [[0;32m OK [0m] Listening on RPCbind Server Activation Socket. [[0;32m OK [0m] Listening on dropbear.socket. [[0;32m OK [0m] Reached target Sockets. [[0;32m OK [0m] Started Daily Cleanup of Temporary Directories. [[0;32m OK [0m] Reached target Basic System. Starting Create link for gcnano-userland... Starting Netdata, Real-time performance monitoring... [[0;32m OK [0m] Started System Logging Service. [[0;32m OK [0m] Started D-Bus System Message Bus. Starting Sound Service... Starting Weston Wayland Compositor... [[0;32m OK [0m] Started ST Verify if eth0 network interface are already configured. Starting Network Service... [[0;32m OK [0m] Started Kernel Logging Service. Starting Login Service... Starting Enable USB Ethernet gadget... [[0;32m OK [0m] Started TEE Supplicant. Starting Resize root filesystem to fit available disk space... [ 12.436175] input: generic ft5x06 (11) as /devices/platform/soc/40012000.i2c/i2c-0/0-002a/input/input1 [[0;32m OK [0m] Started Console System Startup Logging. [[0;32m OK [0m] Started Sound Service. [ 12.522673] Bluetooth: Core ver 2.22 [ 12.522976] NET: Registered protocol family 31 [ 12.522985] Bluetooth: HCI device and connection manager initialized [ 12.523013] Bluetooth: HCI socket layer initialized [ 12.523028] Bluetooth: L2CAP socket layer initialized [ 12.523102] Bluetooth: SCO socket layer initialized [ 12.542897] Bluetooth: HCI UART driver ver 2.3 [ 12.542913] Bluetooth: HCI UART protocol H4 registered [ 12.543758] hci_uart_bcm serial0-0: No reset resource, using default baud rate [ 12.577400] Bluetooth: HCI UART protocol Broadcom registered [ 12.769039] Bluetooth: hci0: BCM: chip id 94 [ 12.772466] Bluetooth: hci0: BCM: features 0x2e [ 12.781292] Bluetooth: hci0: BCM43430A1 [ 12.783744] Bluetooth: hci0: BCM43430A1 (001.002.009) build 0000 [ 12.802852] using random self ethernet address [ 12.805854] using random host ethernet address [[0;32m OK [0m] Started Network Service. [[0;32m OK [0m] Started Netdata, Real-time performance monitoring. [ 13.032311] galcore: loading out-of-tree module taints kernel. [ 13.086006] usb0: HOST MAC 1e:29:4e:e9:3a:6f [ 13.089419] usb0: MAC 6a:fa:2e:4d:9a:b1 [ 13.092730] dwc2 49000000.usb-otg: bound driver configfs-gadget [ 13.130101] Generic PHY stmmac-0:00: attached PHY driver [Generic PHY] (mii_bus:phy_addr=stmmac-0:00, irq=POLL) [ 13.150420] Galcore version 6.2.4.174315 [ 13.268706] dwmac4: Master AXI performs any burst length [ 13.272597] stm32-dwmac 5800a000.ethernet eth0: No Safety Features support found [ 13.296156] stm32-dwmac 5800a000.ethernet eth0: IEEE 1588-2008 Advanced Timestamp supported [ 13.310616] stm32-dwmac 5800a000.ethernet eth0: registered PTP clock [ 13.317838] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready [ 13.324000] cfg80211: Loading compiled-in X.509 certificates for regulatory database [ 13.331243] dwc2 49000000.usb-otg: new device is high-speed [ 13.337892] Bluetooth: hci0: BCM43430A1 (001.002.009) build 0022 [ 13.459290] dwc2 49000000.usb-otg: new device is high-speed [ 13.482016] IPv6: ADDRCONF(NETDEV_UP): usb0: link is not ready [ 13.510289] cfg80211: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' [ 13.519218] dwc2 49000000.usb-otg: new address 88 [ 13.549655] configfs-gadget gadget: high-speed config #1: c [ 13.554198] IPv6: ADDRCONF(NETDEV_CHANGE): usb0: link becomes ready [[0;32m OK [0m] Started Enable USB Ethernet gadget. [ 13.707492] brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43430-sdio for chip BCM43430/1 [ 13.907568] brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43430-sdio for chip BCM43430/1 [ 13.997576] brcmfmac: brcmf_c_preinit_dcmds: Firmware: BCM43430/1 wl0: Sep 11 2018 09:22:09 version 7.45.98.65 (r707797 CY) FWID 01-b54727f [ 14.328546] stm32-dwmac 5800a000.ethernet eth0: Link is Up - 1Gbps/Full - flow control rx/tx [ 14.335735] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready [ 14.698703] Gcnano is present and activated [[0;32m OK [0m] Started Create link for gcnano-userland. [ 16.846663] EXT4-fs (mmcblk0p6): resizing filesystem from 506292 to 1817008 blocks [[0;32m OK [0m] Started Weston Wayland Compositor. [ 17.218304] EXT4-fs (mmcblk0p6): resized filesystem to 1817008 [ 17.518895] EXT4-fs (mmcblk0p7): resizing filesystem from 95836 to 13656620 blocks [ 21.513975] EXT4-fs (mmcblk0p7): resized filesystem to 13656620 [[0;32m OK [0m] Started Resize root filesystem to fit available disk space. Starting Hostname Service... [[0;32m OK [0m] Created slice system-systemd\x2dbacklight.slice. Starting Bluetooth service... Starting Load/Save Screen Backlight…ess of backlight:5a000000.dsi.0... Starting Save/Restore Sound Card State... [[0;32m OK [0m] Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. Stopping Network Service... [[0;32m OK [0m] Stopped Network Service. [[0;32m OK [0m] Started Load/Save Screen Backlight …tness of backlight:5a000000.dsi.0. [[0;32m OK [0m] Started Save/Restore Sound Card State. [[0;32m OK [0m] Started Bluetooth service. [[0;32m OK [0m] Reached target Bluetooth. Starting Load/Save RF Kill Switch Status... [[0;32m OK [0m] Reached target Sound Card. Starting Network Service... [[0;32m OK [0m] Started Load/Save RF Kill Switch Status. [[0;32m OK [0m] Started Login Service. [[0;32m OK [0m] Started Hostname Service. [[0;32m OK [0m] Started Network Service. Starting Network Name Resolution... Starting Wait for Network to be Configured... [[0;32m OK [0m] Started Network Name Resolution. [[0;32m OK [0m] Reached target Network. [[0;32m OK [0m] Started IIO Daemon. Starting Target Communication Framework agent... Starting Permit User Sessions... [[0;32m OK [0m] Reached target Host and Network Name Lookups. Starting Avahi mDNS/DNS-SD Stack... [[0;32m OK [0m] Started Target Communication Framework agent. [[0;32m OK [0m] Started Permit User Sessions. [[0;32m OK [0m] Started Avahi mDNS/DNS-SD Stack. [[0;32m OK [0m] Started Serial Getty on ttySTM0. [[0;32m OK [0m] Started Getty on tty1. [[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;32m OK [0m] Started Update UTMP about System Runlevel Changes. ST OpenSTLinux - Weston - (A Yocto Project Based Distro) 2.6-openstlinux-4.19-thud-mp1-19-04-05 stm32mp1 ttySTM0 Matched prompt #5: stm32mp1 login:|stm32mp1-(.*) login: case: kernel-messages
case_id: 24510
definition: lava
duration: 37.22
extra: ...
level: 2.3
namespace: common
result: pass
Sending username root root stm32mp1 login: root (auroot auto-login-action: Wait for prompt ['root@stm32mp1:|root@stm32mp1-\\S+:', 'Login incorrect', 'Login timed out'] (timeout 00:08:38) tomatic login) root end: 2.3 auto-login-action (duration 00:00:50) [common] case: auto-login-action
case_id: 24511
definition: lava
duration: 50.35
extra: ...
level: 2.3
namespace: common
result: pass
start: 2.4 expect-shell-connection (timeout 00:08:35) [common] Forcing a shell prompt, looking for ['root@stm32mp1:|root@stm32mp1-\\S+:'] 7[r[999;999H[6n8root@stm32mp1:~# expect-shell-connection: Wait for prompt ['root@stm32mp1:|root@stm32mp1-\\S+:'] (timeout 00:10:00) Waiting using forced prompt support. 299.9742341041565s timeout end: 2.4 expect-shell-connection (duration 00:00:00) [common] start: 2.5 overlay-unpack (timeout 00:08:35) [common] rm overlay-1.1.4.5.tar.gz root@stm32mp1:~#rm overlay-1.1.4.5.tar.gz rm overlay-1.1.4.5.tar.gz rm: cannot remove 'overlay-1.1.4.5.tar.gz': No such file or directory ifplugd ; sync ; sleep 10 ; cd /tmp ; wget http://10.48.2.186/tmp/lavafed-1214/compress-overlay-1x9kptd7/overlay-1.1.4.5.tar.gz root@stm32mp1:~#ifplugd ; sync ; sleep 10 ; cd /tmp ; wget http://10.48.2.186/tmp/lavafed-1214/compress-overlay-1x9kptd7/overlay-1.1.4.5.tar.gz ifplugd ; sync ; sleep 10 ; cd /tmp ; wget http://10.48.2.186/tmmp/lavafed-1214/compress-overlay-1x9kptd7/overlay-1.1.4.5.tar.gz Connecting to 10.48.2.186 (10.48.2.186:80) overlay-1.1.4.5.tar. 100% |********************************| 492k 0:00:00 ETA tar -C / -xzf overlay-1.1.4.5.tar.gz root@stm32mp1:tar -C / -xzf overlay-1.1.4.5.tar.gz /tmp# tar -C / -xzf overlay-1.1.4.5.tar.gz end: 2.5 overlay-unpack (duration 00:00:12) [common] start: 2.6 export-device-env (timeout 00:08:23) [common] end: 2.6 export-device-env (duration 00:00:00) [common] end: 2 minimal-boot (duration 00:01:18) [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@stm32mp1:/tmp# # lava-test-shell: Wait for prompt ['root@stm32mp1:|root@stm32mp1-\\S+:'] (timeout 00:05:00) # Using /lava-1214 export SHELL=/bin/sh root@stm32mp1:export SHELL=/bin/sh /tmp# export SHELL=/bin/sh . /lava-1214/environment root@stm32mp1:/tmp# . /lava-1214/environment . /lava-1214/environment /lava-1214/bin/lava-test-runner /lava-1214/0 root@stm32mp1:/tmp# /lava-1214/bin/lava-test-runner /lava-1214/0 Test shell timeout: 10s (minimum of the action and connection timeout) /lava-1214/bin/lava-test-runner /lava-1214/0 + export TESTRUN_ID=0_TC_SYS_1_1 + TESTRUN_ID=0_TC_SYS_1_1 + cd /lava-1214/0/tests/0_TC_SYS_1_1 ++ cat uuid + UUID=1214_1.1.4.4.1 + set +x <LAVA_SIGNAL_STARTRUN 0_TC_SYS_1_1 1214_1.1.4.4.1> Received signal: <STARTRUN> 0_TC_SYS_1_1 1214_1.1.4.4.1 Starting test lava.0_TC_SYS_1_1 (1214_1.1.4.4.1) Skipping test definition patterns. + ./tests-target/system/run_system_test.sh TC_SYS_1_1 Run command: 'cat /sys/firmware/devicetree/base/model' Run command: 'cat /sys/kernel/debug/clk/clk_summary' board detected: STM32MP15 Target for board STM32MP15 is 28.5 seconds Run command: 'memtester 5 1' test done in 28.790279055 seconds TC_SYS_1_1:pass /lava-1214/0/../bin/lava-test-case <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=TC_SYS_1_1 RESULT=pass> Received signal: <TESTCASE> TEST_CASE_ID=TC_SYS_1_1 RESULT=pass case: TC_SYS_1_1
case_id: 24512
definition: 0_TC_SYS_1_1
result: pass
TC_SYS_1_1 RESULT:pass + set +x <LAVA_SIGNAL_ENDRUN 0_TC_SYS_1_1 1214_1.1.4.4.1> Received signal: <ENDRUN> 0_TC_SYS_1_1 1214_1.1.4.4.1 Ending use of test pattern. Ending test lava.0_TC_SYS_1_1 (1214_1.1.4.4.1), duration 31.12 case: 0_TC_SYS_1_1
case_id: 24513
commit_id: 27896836d2e7cc14d3f06556d0683645f3c5bc43
definition: lava
duration: 31.12
namespace: common
path: tests-def/system/TC_SYS_1_1.yaml
repository: ssh://gerrit.st.com:29418/oeivi/test/lava/lava-tests
result: pass
revision: master
uuid: 1214_1.1.4.4.1
<LAVA_TEST_RUNNER EXIT> ok: lava_test_shell seems to have completed TC_SYS_1_1: pass end: 3.1 lava-test-shell (duration 00:00:32) [common] end: 3 lava-test-retry (duration 00:00:32) [common] start: 4 finalize (timeout 00:00:30) [common] start: 4.1 power-off (timeout 00:00:15) [common] Calling: 'nice' '/root/git/lava-config/scripts/epower_switch.sh' '-u' 'epowerswitch_03.lme.st.com' '-p' '3' '-c' 'OFF' >> [03:16:36] [DEBUG] last digit correct >> ePowerSwitch epowerswitch_03.lme.st.com selected 3 >> Set pwrSwitch epowerswitch_03.lme.st.com on prt 3 to Off >> [03:16:36] [DEBUG] Switch Off Power supply >> [03:16:36] [DEBUG] CMD: wget -O - http://epowerswitch_03.lme.st.com/hidden.htm?M0:O3=Off Returned 0 in 0 seconds end: 4.1 power-off (duration 00:00:00) [common] case: power-off
case_id: 24514
definition: lava
duration: 0.47
extra: ...
level: 4.1
namespace: common
result: pass
start: 4.2 read-feedback (timeout 00:00:30) [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:02) [common] Cleaning after the job Cleaning up download directory: /var/lib/lava/dispatcher/tmp/lavafed-1214/deploy-flasher-qgvy6_ti/layout Cleaning up download directory: /var/lib/lava/dispatcher/tmp/lavafed-1214/deploy-flasher-qgvy6_ti/tarball Root tmp directory removed at /var/lib/lava/dispatcher/tmp/lavafed-1214 Job finished correctly case: job
case_id: 24515
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.3.type
device
slave.version
2019.09.0089.gf1de999c2
features.3.name
test.definition.git
features.0.description
Deploy using a flashing tool
features.0.action
deploy
features.0.type
device
job.url
https://federation.lavasoftware.org/lava/scheduler/job/{id}
features.2.description
Non-interactive boot
features.1.description
Use transfer_overlay command
job.name
stm32mp157c-dk2 - health-check
features.2.type
device
features.1.name
deploy.transfer_overlay
slave.arch
amd64
features.2.name
boot.minimal
features.0.name
deploy.flasher
device.name
lmecxd0318-mp157revc_disco_048
features.3.action
test
features.1.type
device
features.1.action
deploy
job.type
test
features.2.action
boot
device.type
stm32mp157c-dk2
features.3.description
Test definition from git
lab.name
dispatcher-01.st.com
device type
hostname
timeouts
  • connections: {'minutes': 2}{'seconds': 30}{'seconds': 10}{'seconds': 30}{'seconds': 30}{'minutes': 10}{'seconds': 30} auto-login-actionu-boot-interruptlava-test-shellbootloader-interruptbootloader-commandsdd-imageuboot-retry
  • actions: {'minutes': 2}{'minutes': 3}{'seconds': 30}{'minutes': 3}{'seconds': 15}{'seconds': 45}{'minutes': 5}{'minutes': 2}{'minutes': 2}{'minutes': 10}{'minutes': 3}{'minutes': 3}{'minutes': 3}{'minutes': 20}{'minutes': 5}{'minutes': 3}{'seconds': 30}{'minutes': 10}{'minutes': 2} auto-login-actionlava-test-shellbootloader-interruptbootloader-commandspower-offumount-retryhttp-downloadapply-overlay-imageboot-qemu-imagedd-imagebootloader-actionuboot-retryuboot-actionflash-uboot-umsdownload-retrybootloader-retryu-boot-interruptnfs-deployboot-image-retry
device action methods
boot:
  • minimal:
  • ssh:
  • dfu:
    • enter-commands: None
    • command: dfu-util
  • u-boot:
    • needs_interrupt: True
    • interrupt_char:
    • bootloader_prompt: STM32MP>
    • interrupt_prompt: Hit any key to stop autoboot
    • mkimage_arch: arm
deploy:
  • tftp:
  • overlay:
  • usb:
  • image:
  • ssh:
  • nbd:
  • flasher:
  • lxc:
target
...
timeouts
  • job: {'seconds': 1200}

  1. os
    oe
    to
    flasher
    namespace
    common
    Timeout
    • 20 minutes
    deployment_data
    {'line_separator': '\n', 'lava_test_dir': '/lava-%s', 'lava_test_sh_cmd': '/bin/sh', 'distro': 'oe', 'TESTER_PS1': 'linaro-test [rc=$(echo \\$?)]# ', 'lava_test_shell_file': '~/.bashrc', 'TESTER_PS1_PATTERN': 'linaro-test \\[rc=(\\d+)\\]# ', 'lava_test_results_part_attr': 'root_part', 'TESTER_PS1_INCLUDES_RC': True, 'lava_test_results_dir': '/lava-%s', 'boot_cmds': 'boot_cmds_oe'}
    images
    {'layout': {'url': 'http://citools.st.com/artifacts/artifactory/oeivi-codex-st-com/oemanifest-int-mmdv/refs/tags/openstlinux-4.19-thud-mp1-19-04-05/openstlinux-weston/images/stm32mp1/flashlayout_st-image-weston/FlashLayout_sdcard_stm32mp157c-dk2-trusted.tsv'}, 'tarball': {'url': 'http://citools.st.com/artifacts/artifactory/oeivi-codex-st-com/oemanifest-int-mmdv/refs/tags/openstlinux-4.19-thud-mp1-19-04-05/openstlinux-weston/IMAGES-st-image-weston-openstlinux-weston-stm32mp1-openstlinux-4.19-thud-mp1-19-04-05.tar.xz'}}

  1. transfer_overlay
    {'unpack_command': 'tar -C / -xzf', 'download_command': 'ifplugd ; sync ; sleep 10 ; cd /tmp ; wget'}
    method
    minimal
    prompts
    ['root@stm32mp1:|root@stm32mp1-\\S+:']
    namespace
    common
    Timeout
    • 10 minutes
    auto_login
    {'login_prompt': 'stm32mp1 login:|stm32mp1-(.*) login:', 'username': 'root'}

  1. stage
    0
    namespace
    common
    Definitions
    • repository ssh://gerrit.st.com:29418/oeivi/test/lava/lava-tests
    • revision master
    • path tests-def/system/TC_SYS_1_1.yaml
    • history False
    • name TC_SYS_1_1
    • from git
    Timeout
    • 5 minutes

Job Timings

This section helps test writers to check for actions with a duration which is much shorter than the requested timeout. Reducing these timeouts will allow failures to be identified more quickly.

The graph only shows actions that are longer than 1 second. The full list is anyway available in the table.

Loading the data...