lava-dispatcher, installed at version: 2021.01.0018.gb56370de5 start: 0 validate Start time: 2021-02-23 04:04:31.705228+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.08 case: validate
case_id: 66117
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-3513/deploy-flasher-jc44pfzb/layout/FlashLayout_sdcard_stm32mp157c-dk2-trusted.tsv total size: 748 (0MB) No compression specified progress 100% (0MB) 0MB downloaded in 0.04s (0.02MB/s) end: 1.1.1.1 http-download (duration 00:00:00) [common] case: http-download
case_id: 66118
definition: lava
duration: 0.04
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-3513/deploy-flasher-jc44pfzb/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 29.01s (20.34MB/s) end: 1.1.2.1 http-download (duration 00:00:29) [common] case: http-download
case_id: 66118
definition: lava
duration: 29.01
extra: ...
level: 1.1.2.1
namespace: common
result: pass
end: 1.1.2 download-retry (duration 00:00:29) [common] start: 1.1.3 deploy-device-env (timeout 00:19:31) [common] end: 1.1.3 deploy-device-env (duration 00:00:00) [common] start: 1.1.4 lava-overlay (timeout 00:19:31) [common] [common] Preparing overlay tarball in /var/lib/lava/dispatcher/tmp/lavafed-3513/lava-overlay-8d3ikxq5 makedir: /var/lib/lava/dispatcher/tmp/lavafed-3513/lava-overlay-8d3ikxq5/lava-3513/bin makedir: /var/lib/lava/dispatcher/tmp/lavafed-3513/lava-overlay-8d3ikxq5/lava-3513/tests makedir: /var/lib/lava/dispatcher/tmp/lavafed-3513/lava-overlay-8d3ikxq5/lava-3513/results Creating /var/lib/lava/dispatcher/tmp/lavafed-3513/lava-overlay-8d3ikxq5/lava-3513/bin/lava-add-keys Creating /var/lib/lava/dispatcher/tmp/lavafed-3513/lava-overlay-8d3ikxq5/lava-3513/bin/lava-add-sources Creating /var/lib/lava/dispatcher/tmp/lavafed-3513/lava-overlay-8d3ikxq5/lava-3513/bin/lava-background-process-start Creating /var/lib/lava/dispatcher/tmp/lavafed-3513/lava-overlay-8d3ikxq5/lava-3513/bin/lava-background-process-stop Creating /var/lib/lava/dispatcher/tmp/lavafed-3513/lava-overlay-8d3ikxq5/lava-3513/bin/lava-common-functions Creating /var/lib/lava/dispatcher/tmp/lavafed-3513/lava-overlay-8d3ikxq5/lava-3513/bin/lava-echo-ipv4 Creating /var/lib/lava/dispatcher/tmp/lavafed-3513/lava-overlay-8d3ikxq5/lava-3513/bin/lava-install-packages Creating /var/lib/lava/dispatcher/tmp/lavafed-3513/lava-overlay-8d3ikxq5/lava-3513/bin/lava-installed-packages Creating /var/lib/lava/dispatcher/tmp/lavafed-3513/lava-overlay-8d3ikxq5/lava-3513/bin/lava-os-build Creating /var/lib/lava/dispatcher/tmp/lavafed-3513/lava-overlay-8d3ikxq5/lava-3513/bin/lava-probe-channel Creating /var/lib/lava/dispatcher/tmp/lavafed-3513/lava-overlay-8d3ikxq5/lava-3513/bin/lava-probe-ip Creating /var/lib/lava/dispatcher/tmp/lavafed-3513/lava-overlay-8d3ikxq5/lava-3513/bin/lava-target-ip Creating /var/lib/lava/dispatcher/tmp/lavafed-3513/lava-overlay-8d3ikxq5/lava-3513/bin/lava-target-mac Creating /var/lib/lava/dispatcher/tmp/lavafed-3513/lava-overlay-8d3ikxq5/lava-3513/bin/lava-target-storage Creating /var/lib/lava/dispatcher/tmp/lavafed-3513/lava-overlay-8d3ikxq5/lava-3513/bin/lava-test-case Creating /var/lib/lava/dispatcher/tmp/lavafed-3513/lava-overlay-8d3ikxq5/lava-3513/bin/lava-test-event Creating /var/lib/lava/dispatcher/tmp/lavafed-3513/lava-overlay-8d3ikxq5/lava-3513/bin/lava-test-feedback Creating /var/lib/lava/dispatcher/tmp/lavafed-3513/lava-overlay-8d3ikxq5/lava-3513/bin/lava-test-raise Creating /var/lib/lava/dispatcher/tmp/lavafed-3513/lava-overlay-8d3ikxq5/lava-3513/bin/lava-test-reference Creating /var/lib/lava/dispatcher/tmp/lavafed-3513/lava-overlay-8d3ikxq5/lava-3513/bin/lava-test-runner Creating /var/lib/lava/dispatcher/tmp/lavafed-3513/lava-overlay-8d3ikxq5/lava-3513/bin/lava-test-set Creating /var/lib/lava/dispatcher/tmp/lavafed-3513/lava-overlay-8d3ikxq5/lava-3513/bin/lava-test-shell Updating /var/lib/lava/dispatcher/tmp/lavafed-3513/lava-overlay-8d3ikxq5/lava-3513/bin/lava-install-packages (oe) Updating /var/lib/lava/dispatcher/tmp/lavafed-3513/lava-overlay-8d3ikxq5/lava-3513/bin/lava-installed-packages (oe) Creating /var/lib/lava/dispatcher/tmp/lavafed-3513/lava-overlay-8d3ikxq5/lava-3513/environment LAVA metadata - LAVA_JOB_ID=3513 start: 1.1.4.1 ssh-authorize (timeout 00:19:31) [common] end: 1.1.4.1 ssh-authorize (duration 00:00:00) [common] start: 1.1.4.2 lava-vland-overlay (timeout 00:19:31) [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:31) [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:31) [common] Loading test definitions start: 1.1.4.4.1 git-repo-action (timeout 00:19:31) [common] Using /lava-3513 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-3513/lava-overlay-8d3ikxq5/lava-3513/0/tests/0_TC_SYS_1_1' Running '/usr/bin/git checkout master Removing '.git' directory in /var/lib/lava/dispatcher/tmp/lavafed-3513/lava-overlay-8d3ikxq5/lava-3513/0/tests/0_TC_SYS_1_1 Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/lavafed-3513/lava-overlay-8d3ikxq5/lava-3513/0/tests/0_TC_SYS_1_1/tests-def/system/TC_SYS_1_1.yaml uuid=3513_1.1.4.4.1 testdef=None end: 1.1.4.4.1 git-repo-action (duration 00:00:03) [common] case: git-repo-action
case_id: 66120
definition: lava
duration: 3.07
extra: ...
level: 1.1.4.4.1
namespace: common
result: pass
start: 1.1.4.4.2 test-overlay (timeout 00:19:28) [common] end: 1.1.4.4.2 test-overlay (duration 00:00:00) [common] case: test-overlay
case_id: 66121
definition: lava
duration: 0.00
extra: ...
level: 1.1.4.4.2
namespace: common
result: pass
start: 1.1.4.4.3 test-install-overlay (timeout 00:19:28) [common] end: 1.1.4.4.3 test-install-overlay (duration 00:00:00) [common] case: test-install-overlay
case_id: 66122
definition: lava
duration: 0.00
extra: ...
level: 1.1.4.4.3
namespace: common
result: pass
start: 1.1.4.4.4 test-runscript-overlay (timeout 00:19:28) [common] runner path: /var/lib/lava/dispatcher/tmp/lavafed-3513/lava-overlay-8d3ikxq5/lava-3513/0/tests/0_TC_SYS_1_1 test_uuid 3513_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: 66123
definition: lava
duration: 0.00
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-3513/lava-overlay-8d3ikxq5/lava-3513/0 for stage 0 - 0_TC_SYS_1_1 end: 1.1.4.4 test-definition (duration 00:00:03) [common] start: 1.1.4.5 compress-overlay (timeout 00:19:28) [common] end: 1.1.4.5 compress-overlay (duration 00:00:01) [common] start: 1.1.4.6 persistent-nfs-overlay (timeout 00:19:27) [common] end: 1.1.4.6 persistent-nfs-overlay (duration 00:00:00) [common] end: 1.1.4 lava-overlay (duration 00:00:04) [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 Feb 23 04:05 FlashLayout_sdcard_stm32mp157c-dk2-trusted.tsv -> layout/FlashLayout_sdcard_stm32mp157c-dk2-trusted.tsv >> drwxr-xr-x 2 root root 4096 Feb 23 04:04 layout >> drwxr-xr-x 2 root root 4096 Feb 23 04: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' >> [04:05:06] [DEBUG] Boot Pin >> [04:05:06] #### 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 >> [04:05:06] [DEBUG] md5sum computation start >> [04:05:06] [DEBUG] md5sum computation done. >> ######### MD5SUM for FlashLayout_sdcard_stm32mp157c-dk2-trusted.tsv file >> Calculated : 5c03e872ee3bdf365ea08e23e903b463 >> ################################# >> [04:05:06] Boot media : mmc0 >> [04:05:06] *** Start normal boot procedure *** >> [04:05:06] *** Binary preparation *** >> [04:05:06] Tarball file extraction procedure for tarball/IMAGES-st-image-weston-openstlinux-weston-stm32mp1-openstlinux-4.19-thud-mp1-19-04-05.tar.xz >> [04:05:06] [DEBUG] md5sum computation start >> [04:05:07] [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 >> ################################# >> [04:05:07] Tarball extraction start: >> [04:05:44] Tarball extraction done. >> [04:05:44] Try launching flasher operation number 1 >> [04:05:44] Wait to get lock for board in DFU... >> [04:05:44] Activate lock for board in DFU done. >> [04:05:45] Configure bootpins: >> [04:05:45] [DEBUG] BOOT_PIN INPUT: 12_13 >> [04:05:45] [DEBUG] BOOT_PIN[0]: 12 >> [04:05:45] [DEBUG] BOOT_PIN[1]: 13 >> [04:05:45] Reset boot pin to flash mode >> [04:05:45] [DEBUG] D12=0 >> [04:05:45] Wait to get lock for /dev/ttyACM.nucleo_lmecxd0318_4... >> [04:05:45] Activate lock for /dev/ttyACM.nucleo_lmecxd0318_4. >> [04:05:45] D12=0 >> [04:05:45] Release lock for /dev/ttyACM.nucleo_lmecxd0318_4 done. >> [04:05:46] [DEBUG] D13=0 >> [04:05:46] Wait to get lock for /dev/ttyACM.nucleo_lmecxd0318_4... >> [04:05:46] Activate lock for /dev/ttyACM.nucleo_lmecxd0318_4. >> [04:05:46] D13=0 >> [04:05:46] Release lock for /dev/ttyACM.nucleo_lmecxd0318_4 done. >> [04:05:47] [DEBUG] off Media_boot mmc0 >> [04:05:47] [DEBUG] D12=0 >> [04:05:47] Wait to get lock for /dev/ttyACM.nucleo_lmecxd0318_4... >> [04:05:47] Activate lock for /dev/ttyACM.nucleo_lmecxd0318_4. >> [04:05:47] D12=0 >> [04:05:47] Release lock for /dev/ttyACM.nucleo_lmecxd0318_4 done. >> [04:05:48] [DEBUG] D13=0 >> [04:05:48] Wait to get lock for /dev/ttyACM.nucleo_lmecxd0318_4... >> [04:05:48] Activate lock for /dev/ttyACM.nucleo_lmecxd0318_4. >> [04:05:49] D13=0 >> [04:05:49] Release lock for /dev/ttyACM.nucleo_lmecxd0318_4 done. >> [04:05:49] Wait for Relay to be established: 1 ms >> [04:05:50] Bootpins configure done. >> [04:05:50] [DEBUG] /root/git/lava-config/scripts/epower_switch.sh -u epowerswitch_03.lme.st.com -p 3 -c REBOOT -n 2001 >> [04:05:50] [DEBUG] last digit correct >> ePowerSwitch epowerswitch_03.lme.st.com selected 3 >> Send reboot command switch epowerswitch_03.lme.st.com on prt 3 >> [04:05:50] [DEBUG] Switch OFF Power supply >> [04:05:50] [DEBUG] CMD: wget -O - http://epowerswitch_03.lme.st.com/hidden.htm?M0:O3=Off >> [04:06:07] [DEBUG] Switch ON Power supply >> [04:06:07] [DEBUG] CMD: wget -O - http://epowerswitch_03.lme.st.com/hidden.htm?M0:O3=On >> [04:06:07] Wait for 15 seconds... >> [04:06:22] *** Flashing Procedure *** >> [04:06:22] Launch Flashing operation number 1 >> [04:06:22] Wait for DFU to be up... >> [04:06:22] [DEBUG] DFU detection loop: 1 >> [04:06:22] No DFU device found >> [04:06:32] [DEBUG] DFU detection loop: 2 >> [04:06:32] No DFU device found >> [04:06:42] [DEBUG] DFU detection loop: 3 >> [04:06:42] No DFU device found >> [04:06:52] [DEBUG] DFU detection loop: 4 >> [04:06:52] [DEBUG] USB DFU device(s) available on host >> [04:06:53] [DEBUG] *** /root/git/programmer/x86_64-linux/bin/STM32_Programmer_CLI -l usb *** >> [04:06:53] ------------------------------------------------------------------- >> [04:06:53] STM32CubeProgrammer v2.7.0-RC1 >> [04:06:53] ------------------------------------------------------------------- >> [04:06:53] >> [04:06:53] ===== DFU Interface ===== >> [04:06:53] >> [04:06:53] Total number of available STM32 device in DFU mode: 1 >> [04:06:53] >> [04:06:53] Device Index : USB1 >> [04:06:53] USB Bus Number : 002 >> [04:06:53] USB Address Number : 001 >> [04:06:53] Product ID : DFU in HS Mode @Device ID /0x500, @Revision ID /0x0000 >> [04:06:53] Serial number : 0027003F3338510534383330 >> [04:06:53] Firmware version : 0x0110 >> [04:06:53] Device ID : 0x0500 >> [04:06:53] [DEBUG] *** >> [04:06:53] Found USB DFU device usb1 for serial number 0027003f3338510534383330 >> [04:06:53] Wait for DFU during 31 seconds >> [04:06:53] *********************** Use following command to flash: *********************** >> [04:06:53] ARTIFACTS_DIR=/var/lib/lava/dispatcher/tmp/lavafed-3513/deploy-flasher-jc44pfzb 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-3513/deploy-flasher-jc44pfzb >> >> >> ============================================================== >> *** Populate binaries to device with TSV file: >> layout/FlashLayout_sdcard_stm32mp157c-dk2-trusted.tsv >> ============================================================== >> ------------------------------------------------------------------- >> STM32CubeProgrammer v2.7.0-RC1 >> ------------------------------------------------------------------- >> >> >> >> 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 : STM32MP1 >> 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.499 >> >> RUNNING Program ... >> PartID: :0x01 >> Request detach value = 0 >> Start operation done successfully at partition 0x01 >> >> Flashlayout Programming ... >> >> Running Flashlayout Partition ... >> Request detach value = 0 >> 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:00.764 >> >> RUNNING Program ... >> PartID: :0x03 >> Request detach value = 1 >> >> 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 >> [04:08:00] Flashing operation failed... >> [04:08:00] Launch Flashing operation number 2 >> [04:08:00] Wait for DFU to be up... >> [04:08:00] [DEBUG] DFU detection loop: 1 >> [04:08:00] [DEBUG] USB DFU device(s) available on host >> [04:08:00] [DEBUG] *** /root/git/programmer/x86_64-linux/bin/STM32_Programmer_CLI -l usb *** >> [04:08:00] ------------------------------------------------------------------- >> [04:08:00] STM32CubeProgrammer v2.7.0-RC1 >> [04:08:00] ------------------------------------------------------------------- >> [04:08:00] >> [04:08:00] ===== DFU Interface ===== >> [04:08:00] >> [04:08:00] Total number of available STM32 device in DFU mode: 1 >> [04:08:00] >> [04:08:00] Device Index : USB1 >> [04:08:00] USB Bus Number : 002 >> [04:08:00] USB Address Number : 001 >> [04:08:00] Product ID : USB download gadget@Device ID /0x500, @Revision ID /0x0000 >> [04:08:00] Serial number : 0027003F3338510534383330 >> [04:08:00] Firmware version : 0x0110 >> [04:08:00] Device ID : 0x0500 >> [04:08:00] [DEBUG] *** >> [04:08:00] Found USB DFU device usb1 for serial number 0027003f3338510534383330 >> [04:08:00] Wait for DFU during 0 seconds >> [04:08:00] *********************** Use following command to flash: *********************** >> [04:08:00] ARTIFACTS_DIR=/var/lib/lava/dispatcher/tmp/lavafed-3513/deploy-flasher-jc44pfzb 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-3513/deploy-flasher-jc44pfzb >> >> >> ============================================================== >> *** Populate binaries to device with TSV file: >> layout/FlashLayout_sdcard_stm32mp157c-dk2-trusted.tsv >> ============================================================== >> ------------------------------------------------------------------- >> STM32CubeProgrammer v2.7.0-RC1 >> ------------------------------------------------------------------- >> >> >> >> 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 : STM32MP1 >> 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.066 >> >> RUNNING Program ... >> PartID: :0x04 >> Request detach value = 0 >> 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.071 >> >> RUNNING Program ... >> PartID: :0x05 >> Request detach value = 0 >> 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.203 >> >> RUNNING Program ... >> PartID: :0x06 >> Request detach value = 0 >> 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:25.277 >> >> RUNNING Program ... >> PartID: :0x21 >> Request detach value = 0 >> 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:06.232 >> >> RUNNING Program ... >> PartID: :0x22 >> Request detach value = 0 >> 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:03:02.336 >> >> RUNNING Program ... >> PartID: :0x23 >> Request detach value = 0 >> 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:36.695 >> >> RUNNING Program ... >> PartID: :0x24 >> Request detach value = 0 >> Start operation done successfully at partition 0x24 >> Flashing service completed successfully >> *** PROGRAMMER DONE *** >> [04:12:19] Flashing operation success >> [04:12:19] *************** Flashing operation complete with SUCCESS status *************** >> [04:12:19] Release lock for board in DFU done. >> [04:12:19] [DEBUG] RETURNCODE: 0 >> [04:12:19] Configure bootpins: >> [04:12:19] [DEBUG] BOOT_PIN INPUT: 12_13 >> [04:12:19] [DEBUG] BOOT_PIN[0]: 12 >> [04:12:19] [DEBUG] BOOT_PIN[1]: 13 >> [04:12:19] Reset boot pin to flash mode >> [04:12:19] [DEBUG] D12=0 >> [04:12:19] Wait to get lock for /dev/ttyACM.nucleo_lmecxd0318_4... >> [04:12:19] Activate lock for /dev/ttyACM.nucleo_lmecxd0318_4. >> [04:12:19] D12=0 >> [04:12:19] Release lock for /dev/ttyACM.nucleo_lmecxd0318_4 done. >> [04:12:19] [DEBUG] D13=0 >> [04:12:19] Wait to get lock for /dev/ttyACM.nucleo_lmecxd0318_4... >> [04:12:19] Activate lock for /dev/ttyACM.nucleo_lmecxd0318_4. >> [04:12:19] D13=0 >> [04:12:19] Release lock for /dev/ttyACM.nucleo_lmecxd0318_4 done. >> [04:12:19] [DEBUG] on Media_boot mmc0 >> [04:12:19] [DEBUG] D12=1 >> [04:12:19] Wait to get lock for /dev/ttyACM.nucleo_lmecxd0318_4... >> [04:12:19] Activate lock for /dev/ttyACM.nucleo_lmecxd0318_4. >> [04:12:19] D12=1 >> [04:12:19] Release lock for /dev/ttyACM.nucleo_lmecxd0318_4 done. >> [04:12:19] [DEBUG] D13=1 >> [04:12:19] Wait to get lock for /dev/ttyACM.nucleo_lmecxd0318_4... >> [04:12:19] Activate lock for /dev/ttyACM.nucleo_lmecxd0318_4. >> [04:12:19] D13=1 >> [04:12:19] Release lock for /dev/ttyACM.nucleo_lmecxd0318_4 done. >> [04:12:19] Wait for Relay to be established: 1 ms >> [04:12:20] Bootpins configure done. >> [04:12:20] [DEBUG] /root/git/lava-config/scripts/epower_switch.sh -u epowerswitch_03.lme.st.com -p 3 -c OFF -n 2001 >> [04:12:20] [DEBUG] last digit correct >> ePowerSwitch epowerswitch_03.lme.st.com selected 3 >> Set pwrSwitch epowerswitch_03.lme.st.com on prt 3 to Off >> [04:12:20] [DEBUG] Switch Off Power supply >> [04:12:20] [DEBUG] CMD: wget -O - http://epowerswitch_03.lme.st.com/hidden.htm?M0:O3=Off >> [04:12:20] Boot_management script successfully executed Returned 0 in 434 seconds end: 1.1 deploy-flasher (duration 00:07:49) [common] end: 1 deploy-flasher-retry (duration 00:07:49) [common] start: 2 minimal-boot (timeout 00:10:00) [common] start: 2.1 connect-device (timeout 00:10:00) [common] [common] connect-device Connecting to device using 'telnet localhost 2001' Setting prompt string to ['lava-test: # '] end: 2.1 connect-device (duration 00:00:00) [common] start: 2.2 reset-device (timeout 00:10:00) [common] start: 2.2.1 pdu-reboot (timeout 00:10:00) [common] Calling: 'nice' '/root/git/lava-config/scripts/epower_switch.sh' '-u' 'epowerswitch_03.lme.st.com' '-p' '3' '-c' 'REBOOT' >> [04:12:21] [DEBUG] last digit correct >> ePowerSwitch epowerswitch_03.lme.st.com selected 3 >> Send reboot command switch epowerswitch_03.lme.st.com on prt 3 >> [04:12:21] [DEBUG] Switch OFF Power supply >> [04:12:21] [DEBUG] CMD: wget -O - http://epowerswitch_03.lme.st.com/hidden.htm?M0:O3=Off >> [04:12:36] [DEBUG] Switch ON Power supply >> [04:12:36] [DEBUG] CMD: wget -O - http://epowerswitch_03.lme.st.com/hidden.htm?M0:O3=On Returned 0 in 15 seconds end: 2.2.1 pdu-reboot (duration 00:00:16) [common] case: pdu-reboot
case_id: 66124
definition: lava
duration: 15.65
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:44) [common] Setting prompt string to ['Linux version [0-9]'] 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 (0x15): INFO: Power-on Reset (rst_por) 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 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: GETSTATUS : INFO: DFU_STATE_IDLE INFO: Receive DFU abort INFO: GETSTATUS : INFO: DFU_STATE_IDLE INFO: UPLOAD : INFO: Phase ID : 0 INFO: address 0x2ffee774 INFO: GETSTATUS : INFO: DFU_STATE_IDLE INFO: GETSTATUS : INFO: DFU_STATE_IDLE INFO: UPLOAD : INFO: Phase ID : 0 INFO: address 0x2ffee774 INFO: GETSTATUS : INFO: DFU_STATE_IDLE INFO: Start Download partition 0 to address 0xc0000000 length 0 INFO: USB : DFU : end of download partition : 0 INFO: Loading image id=5 at address 0xc0100000 INFO: GETSTATUS : INFO: DFU_STATE_IDLE INFO: UPLOAD : INFO: Phase ID : 3 INFO: addrNOTICE: 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 290 ms (21.6 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 start: 2.3.1 login-action (timeout 00:09:33) [common] Setting prompt string to ['-+\\[ cut here \\]-+\\s+(.*\\s+-+\\[ end trace (\\w*) \\]-+)', '(Unhandled fault.*)\\r\\n', 'Kernel panic - (.*) end Kernel panic', 'Stack:\\s+(.*\\s+-+\\[ end trace (\\w*) \\]-+)'] 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'] [login-action] Waiting for messages, (timeout 00:09:33) [ 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.001925] Console: colour dummy device 80x30 [ 0.001977] Calibrating delay loop (skipped), value calculated using timer frequency.. 48.00 BogoMIPS (lpj=240000) [ 0.002001] pid_max: default: 32768 minimum: 301 [ 0.002236] Mount-cache hash table entries: 1024 (order: 0, 4096 bytes) [ 0.002260] Mountpoint-cache hash table entries: 1024 (order: 0, 4096 bytes) [ 0.003273] CPU: Testing write buffer coherency: ok [ 0.003776] CPU0: update cpu_capacity 1024 [ 0.003797] CPU0: thread -1, cpu 0, socket 0, mpidr 80000000 [ 0.059781] Setting up static identity map for 0xc0100000 - 0xc0100060 [ 0.079768] rcu: Hierarchical SRCU implementation. [ 0.119923] smp: Bringing up secondary CPUs ... [ 0.200527] CPU1: update cpu_capacity 1024 [ 0.200540] CPU1: thread -1, cpu 1, socket 0, mpidr 80000001 [ 0.200751] smp: Brought up 1 node, 2 CPUs [ 0.200781] SMP: Total of 2 processors activated (96.00 BogoMIPS). [ 0.200792] CPU: All CPU(s) started in SVC mode. [ 0.201951] devtmpfs: initialized [ 0.227900] VFP support v0.3: implementor 41 architecture 2 part 30 variant 7 rev 5 [ 0.228465] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns [ 0.228501] futex hash table entries: 512 (order: 3, 32768 bytes) [ 0.234583] pinctrl core: initialized pinctrl subsystem [ 0.236722] NET: Registered protocol family 16 [ 0.243166] DMA: preallocated 256 KiB pool for atomic coherent allocations [ 0.245658] hw-breakpoint: found 5 (+1 reserved) breakpoint and 4 watchpoint registers. [ 0.245679] hw-breakpoint: maximum watchpoint size is 8 bytes. [ 0.245971] Serial: AMBA PL011 UART driver [ 0.248154] stm32-pm-domain pm_domain: domain core-ret-power-domain registered [ 0.248192] stm32-pm-domain pm_domain: subdomain core-power-domain registered [ 0.248208] stm32-pm-domain pm_domain: domains probed [ 0.264072] stm32mp157-pinctrl soc:pin-controller@50002000: GPIOA bank added [ 0.264420] stm32mp157-pinctrl soc:pin-controller@50002000: GPIOB bank added [ 0.264708] stm32mp157-pinctrl soc:pin-controller@50002000: GPIOC bank added [ 0.264996] stm32mp157-pinctrl soc:pin-controller@50002000: GPIOD bank added [ 0.265272] stm32mp157-pinctrl soc:pin-controller@50002000: GPIOE bank added [ 0.265542] stm32mp157-pinctrl soc:pin-controller@50002000: GPIOF bank added [ 0.265809] stm32mp157-pinctrl soc:pin-controller@50002000: GPIOG bank added [ 0.266082] stm32mp157-pinctrl soc:pin-controller@50002000: GPIOH bank added [ 0.266369] stm32mp157-pinctrl soc:pin-controller@50002000: GPIOI bank added [ 0.266579] stm32mp157-pinctrl soc:pin-controller@50002000: Pinctrl STM32 initialized [ 0.267465] stm32mp157-pinctrl soc:pin-controller-z@54004000: GPIOZ bank added [ 0.267494] stm32mp157-pinctrl soc:pin-controller-z@54004000: Pinctrl STM32 initialized [ 0.287564] stm32-mdma 58000000.dma: STM32 MDMA driver registered [ 0.292857] SCSI subsystem initialized [ 0.293653] usbcore: registered new interface driver usbfs [ 0.293745] usbcore: registered new interface driver hub [ 0.293895] usbcore: registered new device driver usb [ 0.294213] pps_core: LinuxPPS API ver. 1 registered [ 0.294227] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it> [ 0.294261] PTP clock support registered [ 0.294547] EDAC MC: Ver: 3.0.0 [ 0.295460] Advanced Linux Sound Architecture Driver Initialized. [ 0.297147] clocksource: Switched to clocksource arch_sys_counter [ 0.392375] NET: Registered protocol family 2 [ 0.393431] tcp_listen_portaddr_hash hash table entries: 512 (order: 0, 6144 bytes) [ 0.393484] TCP established hash table entries: 4096 (order: 2, 16384 bytes) [ 0.393552] TCP bind hash table entries: 4096 (order: 3, 32768 bytes) [ 0.393649] TCP: Hash tables configured (established 4096 bind 4096) [ 0.393802] UDP hash table entries: 256 (order: 1, 8192 bytes) [ 0.393849] UDP-Lite hash table entries: 256 (order: 1, 8192 bytes) [ 0.394325] NET: Registered protocol family 1 [ 0.395288] RPC: Registered named UNIX socket transport module. [ 0.395310] RPC: Registered udp transport module. [ 0.395321] RPC: Registered tcp transport module. [ 0.395332] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.396410] hw perfevents: enabled with armv7_cortex_a7 PMU driver, 5 counters available [ 0.398785] Initialise system trusted keyrings [ 0.399172] workingset: timestamp_bits=14 max_order=17 bucket_order=3 [ 0.410183] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ 0.411343] NFS: Registering the id_resolver key type [ 0.411393] Key type id_resolver registered [ 0.411406] Key type id_legacy registered [ 0.411635] ntfs: driver 2.1.32 [Flags: R/O]. [ 0.412134] jffs2: version 2.2. (NAND) © 2001-2006 Red Hat, Inc. [ 0.413074] fuse init (API version 7.27) [ 0.413769] pstore: using deflate compression [ 0.416691] NET: Registered protocol family 38 [ 0.416719] Key type asymmetric registered [ 0.416732] Asymmetric key parser 'x509' registered [ 0.416828] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 0.416846] io scheduler noop registered [ 0.416857] io scheduler deadline registered [ 0.417224] io scheduler cfq registered (default) [ 0.417247] io scheduler mq-deadline registered [ 0.417260] io scheduler kyber registered [ 0.418628] stm32-usbphyc 5a006000.usbphyc: Linked as a consumer to regulator.1 [ 0.418751] stm32-usbphyc 5a006000.usbphyc: Linked as a consumer to regulator.2 [ 0.418880] stm32-usbphyc 5a006000.usbphyc: Dropping the link to regulator.2 [ 0.418988] stm32-usbphyc 5a006000.usbphyc: Dropping the link to regulator.1 [ 0.423728] stm32-dma 48000000.dma: STM32 DMA driver registered [ 0.425521] stm32-dma 48001000.dma: STM32 DMA driver registered [ 0.506228] STM32 USART driver initialized [ 0.507669] 4000e000.serial: ttySTM3 at MMIO 0x4000e000 (irq = 25, base_baud = 4000000) is a stm32-usart [ 0.507966] serial serial0: tty port ttySTM3 registered [ 0.508028] stm32-usart 4000e000.serial: interrupt mode used for rx (no dma) [ 0.508045] stm32-usart 4000e000.serial: interrupt mode used for tx (no dma) [ 0.509334] 40010000.serial: ttySTM0 at MMIO 0x40010000 (irq = 27, base_baud = 4000000) is a stm32-usart [ 1.453818] console [ttySTM0] enabled [ 1.458054] stm32-usart 40010000.serial: interrupt mode used for rx (no dma) [ 1.464459] stm32-usart 40010000.serial: interrupt mode used for tx (no dma) [ 1.479514] stm32-display-dsi 5a000000.dsi: Linked as a consumer to regulator.2 [ 1.489949] panel-orisetech-otm8009a 5a000000.dsi.0: 5a000000.dsi.0 supply power not found, using dummy regulator [ 1.494822] random: fast init done [ 1.498926] panel-orisetech-otm8009a 5a000000.dsi.0: Linked as a consumer to regulator.0 [ 1.510085] random: crng init done [ 1.526232] brd: module loaded [ 1.541024] loop: module loaded [ 1.548990] libphy: Fixed MDIO Bus: probed [ 1.553091] CAN device driver interface [ 1.557950] stm32-dwmac 5800a000.ethernet: PTP uses main clock [ 1.562337] stm32-dwmac 5800a000.ethernet: no reset control found [ 1.568561] stm32-dwmac 5800a000.ethernet: No phy clock provided... [ 1.575768] stm32-dwmac 5800a000.ethernet: User ID: 0x40, Synopsys ID: 0x42 [ 1.581718] stm32-dwmac 5800a000.ethernet: DWMAC4/5 [ 1.586620] stm32-dwmac 5800a000.ethernet: DMA HW capability register supported [ 1.593974] stm32-dwmac 5800a000.ethernet: RX Checksum Offload Engine supported [ 1.601270] stm32-dwmac 5800a000.ethernet: TX Checksum insertion supported [ 1.608144] stm32-dwmac 5800a000.ethernet: Wake-Up On Lan supported [ 1.614389] stm32-dwmac 5800a000.ethernet: TSO supported [ 1.619726] stm32-dwmac 5800a000.ethernet: Enable RX Mitigation via HW Watchdog Timer [ 1.627559] stm32-dwmac 5800a000.ethernet: TSO feature enabled [ 1.633610] libphy: stmmac: probed [ 1.639399] pegasus: v0.9.3 (2013/04/25), Pegasus/Pegasus II USB Ethernet driver [ 1.645416] usbcore: registered new interface driver pegasus [ 1.651211] usbcore: registered new interface driver asix [ 1.656452] usbcore: registered new interface driver ax88179_178a [ 1.662647] usbcore: registered new interface driver cdc_ether [ 1.668511] usbcore: registered new interface driver smsc75xx [ 1.674155] usbcore: registered new interface driver smsc95xx [ 1.679916] usbcore: registered new interface driver net1080 [ 1.685532] usbcore: registered new interface driver cdc_subset [ 1.691495] usbcore: registered new interface driver zaurus [ 1.697044] usbcore: registered new interface driver cdc_ncm [ 1.703873] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver [ 1.709288] ehci-platform: EHCI generic platform driver [ 1.714913] ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver [ 1.720638] ohci-platform: OHCI generic platform driver [ 1.726505] usbcore: registered new interface driver usb-storage [ 1.736690] stm32_rtc 5c004000.rtc: rtc core: registered 5c004000.rtc as rtc0 [ 1.742934] stm32_rtc 5c004000.rtc: Date/Time must be initialized [ 1.748552] stm32_rtc 5c004000.rtc: registered rev:1.2 [ 1.754191] i2c /dev entries driver [ 1.777872] stm32f7-i2c 40012000.i2c: can't use DMA [ 1.785035] sii902x 0-0039: Failed to get supply 'iovcc': -517 [ 1.790500] stm32f7-i2c 40012000.i2c: STM32F7 I2C-0 bus adapter [ 1.816251] stm32f7-i2c 5c002000.i2c: can't use DMA [ 1.822232] typec_stusb 1-0028: STUSB driver registered [ 1.827839] stpmic1 1-0033: PMIC Chip Version: 0x10 [ 1.837009] BUCK1: supplied by regulator-dummy [ 1.844009] BUCK2: supplied by regulator-dummy [ 1.850497] BUCK3: supplied by regulator-dummy [ 1.857370] BUCK4: supplied by regulator-dummy [ 1.863884] LDO1: supplied by v3v3 [ 1.871451] LDO2: supplied by regulator-dummy [ 1.878173] LDO3: supplied by vdd_ddr [ 1.884350] LDO4: supplied by regulator-dummy [ 1.888956] LDO5: supplied by regulator-dummy [ 1.895802] LDO6: supplied by v3v3 [ 1.901667] VREF_DDR: supplied by regulator-dummy [ 1.908119] BOOST: supplied by regulator-dummy [ 1.912291] VBUS_OTG: supplied by bst_out [ 1.916242] SW_OUT: supplied by bst_out [ 1.923667] input: pmic_onkey as /devices/platform/soc/5c002000.i2c/i2c-1/1-0033/5c002000.i2c:stpmic@33:onkey/input/input0 [ 1.933926] stm32f7-i2c 5c002000.i2c: STM32F7 I2C-1 bus adapter [ 1.942910] stm_thermal 50028000.thermal: stm_thermal_probe: Driver initialized successfully [ 1.953438] mmci-pl18x 58005000.sdmmc: Linked as a consumer to regulator.8 [ 1.959212] mmci-pl18x 58005000.sdmmc: mmc0: PL180 manf 53 rev2 at 0x58005000 irq 58,0 (pio) [ 1.996314] mmci-pl18x 58007000.sdmmc: allocated mmc-pwrseq [ 2.000912] mmci-pl18x 58007000.sdmmc: Linked as a consumer to regulator.8 [ 2.008611] mmci-pl18x 58007000.sdmmc: mmc1: PL180 manf 53 rev1 at 0x58007000 irq 59,0 (pio) [ 2.042459] sdhci: Secure Digital Host Controller Interface driver [ 2.047431] sdhci: Copyright(c) Pierre Ossman [ 2.051582] mmc0: host does not support reading read-only switch, assuming write-enable [ 2.059620] Synopsys Designware Multimedia Card Interface Driver [ 2.066229] sdhci-pltfm: SDHCI platform and OF driver helper [ 2.070254] mmc0: new high speed SDHC card at address aaaa [ 2.076829] ledtrig-cpu: registered to indicate activity on CPUs [ 2.084150] mmc1: queuing unknown CIS tuple 0x80 (2 bytes) [ 2.084210] usbcore: registered new interface driver usbhid [ 2.089054] mmcblk0: mmc0:aaaa SS16G 14.8 GiB [ 2.093819] usbhid: USB HID core driver [ 2.098897] mmc1: queuing unknown CIS tuple 0x80 (3 bytes) [ 2.103946] stm32-ipcc 4c001000.mailbox: ipcc rev:1.0 enabled, 6 chans, proc 0 [ 2.109601] mmc1: queuing unknown CIS tuple 0x80 (3 bytes) [ 2.116014] stm32-rproc m4@0: wdg irq registered [ 2.123036] mmc1: queuing unknown CIS tuple 0x80 (7 bytes) [ 2.125087] remoteproc remoteproc0: m4 is available [ 2.131883] mmc1: queuing unknown CIS tuple 0x80 (3 bytes) [ 2.135981] vref: supplied by vdd [ 2.143567] mmc1: queuing unknown CIS tuple 0x80 (6 bytes) [ 2.144235] stm32-adc-core 48003000.adc: Linked as a consumer to regulator.4 [ 2.154756] mmcblk0: p1 p2 p3 p4 p5 p6 p7 [ 2.156731] stm32-adc-core 48003000.adc: Linked as a consumer to regulator.7 [ 2.190260] iio iio:device2: Can't get offset/scale: -517 [ 2.197543] optee: probing for conduit method from DT. [ 2.201229] optee: api uid mismatch [ 2.214371] NET: Registered protocol family 17 [ 2.217525] can: controller area network core (rev 20170425 abi 9) [ 2.223665] NET: Registered protocol family 29 [ 2.228032] can: raw protocol (rev 20170425) [ 2.232245] can: broadcast manager protocol (rev 20170425 t) [ 2.237944] can: netlink gateway (rev 20170425) max_hops=1 [ 2.243978] Key type dns_resolver registered [ 2.247863] ThumbEE CPU extension supported. [ 2.248033] mmc1: new high speed SDIO card at address 0001 [ 2.251932] Registering SWP/SWPB emulation handler [ 2.263196] registered taskstats version 1 [ 2.266285] Loading compiled-in X.509 certificates [ 2.296990] stm32-usbphyc 5a006000.usbphyc: Linked as a consumer to regulator.1 [ 2.303058] stm32-usbphyc 5a006000.usbphyc: Linked as a consumer to regulator.2 [ 2.310327] stm32-usbphyc 5a006000.usbphyc: Linked as a consumer to regulator.12 [ 2.318401] stm32-usbphyc 5a006000.usbphyc: registered rev:1.0 [ 2.325464] dwc2 49000000.usb-otg: 49000000.usb-otg supply vusb_d not found, using dummy regulator [ 2.333220] dwc2 49000000.usb-otg: Linked as a consumer to regulator.0 [ 2.339585] dwc2 49000000.usb-otg: 49000000.usb-otg supply vusb_a not found, using dummy regulator [ 2.361090] dwc2 49000000.usb-otg: EPs: 9, dedicated fifos, 952 entries in SPRAM [ 2.368777] ehci-platform 5800d000.usbh-ehci: EHCI Host Controller [ 2.373558] ehci-platform 5800d000.usbh-ehci: new USB bus registered, assigned bus number 1 [ 2.382375] ehci-platform 5800d000.usbh-ehci: irq 63, io mem 0x5800d000 [ 2.417189] ehci-platform 5800d000.usbh-ehci: USB 2.0 started, EHCI 1.00 [ 2.423762] hub 1-0:1.0: USB hub found [ 2.426238] hub 1-0:1.0: 2 ports detected [ 2.433284] sii902x 0-0039: Linked as a consumer to regulator.10 [ 2.438018] sii902x 0-0039: Linked as a consumer to regulator.14 [ 2.448152] i2c i2c-0: Added multiplexed i2c bus 2 [ 2.454097] cs42l51 0-004a: Linked as a consumer to regulator.8 [ 2.458755] cs42l51 0-004a: Linked as a consumer to regulator.9 [ 2.467223] cs42l51 0-004a: Cirrus Logic CS42L51, Revision: 01 [ 2.475301] asoc-audio-graph-card sound: cs42l51-hifi0 <-> 4400b004.audio-controller mapping ok [ 2.483648] asoc-audio-graph-card sound: cs42l51-hifi1 <-> 4400b024.audio-controller mapping ok [ 2.491761] asoc-audio-graph-card sound: i2s-hifi <-> 4000b000.audio-controller mapping ok [ 2.500853] cs42l51 0-004a: ASoC: mux DAC Mux has no paths [ 2.740579] [drm] ltdc hw version 0x00010300 - ready [ 2.744181] [drm] Supports vblank timestamp caching Rev 2 (21.10.2013). [ 2.750774] [drm] Driver supports precise vblank timestamp query. [ 2.757765] [drm] Initialized stm 1.0.0 20170330 for 5a001000.display-controller on minor 0 [ 2.766293] stm32_rtc 5c004000.rtc: setting system clock to 2000-01-01 00:00:12 UTC (946684812) [ 2.775004] vdda: disabling [ 2.777072] ALSA device list: [ 2.779690] #0: STM32MP�[ 2.797243] usb 1-1: new high-speed USB device number 2 using ehci-platform [ 2.998583] hub 1-1:1.0: USB hub found [ 3.001092] hub 1-1:1.0: 4 ports detected [ 3.003101] EXT4-fs (mmcblk0p6): mounted filesystem with ordered data mode. Opts: (null) [ 3.013144] VFS: Mounted root (ext4 filesystem) on device 179:6. [ 3.026292] devtmpfs: mounted [ 3.029780] Freeing unused kernel memory: 1024K [ 3.033176] Run /sbin/init as init process [ 3.287267] systemd[1]: System time before build time, advancing clock. [ 3.389200] NET: Registered protocol family 10 [ 3.428070] Segment Routing with IPv6 [ 3.449512] 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.470633] 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.531221] systemd[1]: Set hostname to <stm32mp1>. [ 3.544760] systemd[1]: Initializing machine ID from random generator. [ 3.737318] systemd[1]: Hardware watchdog 'STM32 Independent Watchdog', version 0 [ 3.744435] systemd[1]: Set hardware watchdog to 30s. [ 4.571372] systemd[1]: Unnecessary job for dev-ttySTM0.device was removed. [ 4.579902] systemd[1]: Started Forward Password Requests to Wall Directory Watch. [[0;32m OK [0m] Started Forward Password Requests to Wall Directory Watch. [ 4.622109] systemd[1]: Created slice system-serial\x2dgetty.slice. [[0;32m OK [0m] Created slice system-serial\x2dgetty.slice. [ 4.657506] systemd[1]: Reached target Swap. [[0;32m OK [0m] Reached target Swap. [[0;32m OK [0m] Listening on udev Kernel Socket. [[0;32m OK [0m] Listening on Syslog Socket. [[0;32m OK [0m] Created slice system-getty.slice. [[0;32m OK [0m] Listening on Journal Socket (/dev/log). [[0;32m OK [0m] Listening on Network Service Netlink Socket. [[0;32m OK [0m] Started Dispatch Password Requests to Console Directory Watch. [[0;32m OK [0m] Reached target Paths. [[0;32m OK [0m] Listening on Journal Socket. Starting Load Kernel Modules... Mounting POSIX Message Queue File System... Mounting Kernel Debug File System... Starting Create list of required st…ce nodes for the current kernel... Starting Mount partitions... Mounting Temporary Directory (/tmp)... [[0;32m OK [0m] Started Hardware RNG Entropy Gatherer Daemon. [[0;32m OK [0m] Created slice User and Session Slice. [[0;32m OK [0m] Reached target Slices. [[0;32m OK [0m] Reached target Remote File Systems. Starting Remount Root and Kernel File Systems... Starting Journal Service... [[0;32m OK [0m] Listening on udev Control Socket. [ 5.359499] EXT4-fs (mmcblk0p6): re-mounted. Opts: (null) Starting udev Coldplug all Devices... [[0;32m OK [0m] Listening on Process Core Dump Socket. [ 5.484566] EXT4-fs (mmcblk0p4): mounted filesystem with ordered data mode. Opts: (null) [[0;32m OK [0m] Listening on initctl Compatibility Named Pipe. [[0;32m OK [0m] Started Load Kernel Modules. [[0;32m OK [0m] Mounted POSIX Message Queue File System. [[0;32m OK [0m] Mounted Kernel Debug File System. [[0;32m OK [0m] Started Create list of required sta…vice nodes for the current kernel. [[0;32m OK [0m] Mounted Temporary Directory (/tmp). [[0;32m OK [0m] Started Remount Root and Kernel File Systems. Starting Rebuild Hardware Database... [[0;32m OK [0m] Started Starts Psplash Boot screen. Starting Create System Users... Mounting Kernel Configuration File System... Mounting FUSE Control File System... Starting Apply Kernel Variables... [[0;32m OK [0m] Started Journal Service. [ 6.080674] EXT4-fs (mmcblk0p7): mounted filesystem with ordered data mode. Opts: (null) [[0;32m OK [0m] Mounted Kernel Configuration File System. [[0;32m OK [0m] Mounted FUSE Control File System. [[0;32m OK [0m] Started Apply Kernel Variables. Starting Flush Journal to Persistent Storage... [[0;32m OK [0m] Started Create System Users. Starting Create Static Device Nodes in /dev... [ 6.477930] systemd-journald[178]: Received request to flush runtime journal from PID 1 [[0;32m OK [0m] Started Create Static Device Nodes in /dev. [[0;32m OK [0m] Started Flush Journal to Persistent Storage. [[0;32m OK [0m] Reached target Local File Systems ([ 6.611686] EXT4-fs (mmcblk0p5): mounted filesystem with ordered data mode. Opts: (null) Pre). Mounting /var/volatile... [[0;32m OK [0m] Reached target Containers. [[0;32m OK [0m] Started Mount partitions. [[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... Starting Rebuild Dynamic Linker Cache... [[0;32m OK [0m] Started Load/Save Random Seed. [[0;32m OK [0m] Started Rebuild Journal Catalog. [[0;32m OK [0m] Started Create Volatile Files and Directories. Starting dpkg first boot configure... Starting Network Time Synchronization... Starting Run pending postinsts... Starting Update UTMP about System Boot/Shutdown... [[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. [[0;32m OK [0m] Listening on dropbear.socket. Starting Console System Startup Logging... [[0;32m OK [0m] Started Daily Cleanup of Temporary Directories. [[0;32m OK [0m] Listening on RPCbind Server Activation Socket. [[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] Reached target Sockets. [[0;32m OK [0m] Reached target Basic System. Starting Resize root filesystem to fit available disk space... [[0;32m OK [0m] Started ST Verify if eth0 network interface are already configured. Starting Network Service... Starting Create link for gcnano-userland... [[0;32m OK [0m] Started System Logging Service. Starting Enable USB Ethernet gadget... Starting Netdata, Real-time performance monitoring... [[0;32m OK [0m] Started TEE Supplicant. [[0;32m OK [0m] Started Kernel Logging Service. Starting Weston Wayland Compositor... [[0;32m OK [0m] Started D-Bus System Message Bus. [ 12.601929] using random self ethernet address [ 12.604930] using random host ethernet address Starting Sound Service... Starting Login Service... [[0;32m OK [0m] Started Network Service. [[0;32m OK [0m] Started Console System Startup Logging. [ 12.775425] usb0: HOST MAC 92:bc:57:0c:c1:ff [ 12.781766] usb0: MAC 8e:70:32:d3:5f:dc [ 12.784233] dwc2 49000000.usb-otg: bound driver configfs-gadget [ 12.971766] input: generic ft5x06 (11) as /devices/platform/soc/40012000.i2c/i2c-0/0-002a/input/input1 [ 13.050983] dwc2 49000000.usb-otg: new device is high-speed [ 13.110971] dwc2 49000000.usb-otg: new address 94 [ 13.141003] configfs-gadget gadget: high-speed config #1: c [ 13.165338] IPv6: ADDRCONF(NETDEV_UP): usb0: link is not ready [[0;32m OK [0m] Started Sound Service. [ 13.300538] Bluetooth: Core ver 2.22 [ 13.304568] NET: Registered protocol family 31 [[0;32m OK [0m] Started Enable USB Ethernet gadget. [ 13.319043] Bluetooth: HCI device and connection manager initialized [ 13.334850] Bluetooth: HCI socket layer initialized [ 13.347347] Bluetooth: L2CAP socket layer initialized [ 13.351043] Bluetooth: SCO socket layer initialized [ 13.453226] galcore: loading out-of-tree module taints kernel. [ 13.466621] Bluetooth: HCI UART driver ver 2.3 [ 13.496711] Bluetooth: HCI UART protocol H4 registered [[0;32m OK [0m] Started Netdata, Real-time performance monitoring. [ 13.538571] Galcore version 6.2.4.174315 [ 13.549318] hci_uart_bcm serial0-0: No reset resource, using default baud rate [ 13.555959] Bluetooth: HCI UART protocol Broadcom registered [ 13.683852] Generic PHY stmmac-0:00: attached PHY driver [Generic PHY] (mii_bus:phy_addr=stmmac-0:00, irq=POLL) [ 13.738258] Bluetooth: hci0: BCM: chip id 94 [ 13.741600] Bluetooth: hci0: BCM: features 0x2e [ 13.747103] Bluetooth: hci0: BCM43430A1 [ 13.749803] Bluetooth: hci0: BCM43430A1 (001.002.009) build 0000 [ 13.834834] dwmac4: Master AXI performs any burst length [ 13.846094] stm32-dwmac 5800a000.ethernet eth0: No Safety Features support found [ 13.871577] stm32-dwmac 5800a000.ethernet eth0: IEEE 1588-2008 Advanced Timestamp supported [ 13.911231] stm32-dwmac 5800a000.ethernet eth0: registered PTP clock [ 13.958638] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready [ 14.006961] cfg80211: Loading compiled-in X.509 certificates for regulatory database [ 14.018070] IPv6: ADDRCONF(NETDEV_CHANGE): usb0: link becomes ready [ 14.130984] cfg80211: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' [ 14.274240] brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43430-sdio for chip BCM43430/1 [ 14.338095] Bluetooth: hci0: BCM43430A1 (001.002.009) build 0022 [ 14.391909] Gcnano is present and activated [ 14.508115] brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43430-sdio for chip BCM43430/1 [ 14.597906] 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.968718] stm32-dwmac 5800a000.ethernet eth0: Link is Up - 1Gbps/Full - flow control rx/tx [ 14.983426] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready [[0;32m OK [0m] Started Create link for gcnano-userland. [[0;32m OK [0m] Started Weston Wayland Compositor. [ 18.056653] EXT4-fs (mmcblk0p6): resizing filesystem from 506292 to 1817008 blocks [ 18.596278] EXT4-fs (mmcblk0p6): resized filesystem to 1817008 [ 19.591311] EXT4-fs (mmcblk0p7): resizing filesystem from 95836 to 13656620 blocks [ 23.676261] EXT4-fs (mmcblk0p7): resized filesystem to 13656620 [[0;32m OK [0m] Started Resize root filesystem to fit available disk space. Starting Hostname Service... Starting Bluetooth service... [[0;32m OK [0m] Created slice system-systemd\x2dbacklight.slice. [[0;32m OK [0m] Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. Starting Load/Save Screen Backlight…ess of backlight:5a000000.dsi.0... Stopping Network Service... Starting Save/Restore Sound Card State... [[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. Starting Load/Save RF Kill Switch Status... [[0;32m OK [0m] Reached target Bluetooth. [[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 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 Login Service. [[0;32m OK [0m] Started Network Name Resolution. [[0;32m OK [0m] Reached target Network. Starting Target Communication Framework agent... Starting Permit User Sessions... [[0;32m OK [0m] Started IIO Daemon. Starting Avahi mDNS/DNS-SD Stack... [[0;32m OK [0m] Reached target Host and Network Name Lookups. [[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: 66125
definition: lava
duration: 37.31
extra: ...
level: 2.3.1
namespace: common
result: pass
Sending username root root stm32mp1 login: root (automaticroot Setting prompt string to ['root@stm32mp1:|root@stm32mp1-\\S+:'] login-action: Wait for prompt ['root@stm32mp1:|root@stm32mp1-\\S+:', 'Login incorrect', 'Login timed out'] (timeout 00:08:56) login) root Setting prompt string to ['root@stm32mp1:|root@stm32mp1-\\S+:'] end: 2.3.1 login-action (duration 00:00:41) [common] case: login-action
case_id: 66126
definition: lava
duration: 40.89
extra: ...
level: 2.3.1
namespace: common
result: pass
end: 2.3 auto-login-action (duration 00:00:52) [common] start: 2.4 expect-shell-connection (timeout 00:08:52) [common] Setting prompt string to ['root@stm32mp1:|root@stm32mp1-\\S+:'] 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 (timeout 00:05:00) end: 2.4 expect-shell-connection (duration 00:00:00) [common] start: 2.5 overlay-unpack (timeout 00:08:52) [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-3513/compress-overlay-tj1ikt3m/overlay-1.1.4.5.tar.gz root@stm32mp1:~# ifplugd ; sync ; sleep 10 ; cd /tmp ; wget http://10.48.2.186/tmp/lavafed-3513/compress-overlay-tj1ikt3m/overlay-1.1.4.5.tar.gz ifplugd ; sync ; sleep 10 ; cd /tmp ; wget http://10.48.2.186/tmmp/lavafed-3513/compress-overlay-tj1ikt3m/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% |********************************| 835k 0:00:00 ETA tar -C / -xzf overlay-1.1.4.5.tar.gz root@stm32mp1:/tmp# tar -C / -xzf overlay-1.1.4.5.tar.gz 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:40) [common] end: 2.6 export-device-env (duration 00:00:00) [common] end: 2 minimal-boot (duration 00:01:20) [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-3513 export SHELL=/bin/sh root@stm32mp1:/tmp# export SHELL=/bin/sh export SHELL=/bin/sh . /lava-3513/environment root@stm32mp1:/tmp# . /lava-3513/environment . /lava-3513/environment /lava-3513/bin/lava-test-runner /lava-3513/0 root@stm32mp1:/tmp# /lava-3513/bin/lava-test-runner /lava-3513/0 Test shell timeout: 10s (minimum of the action and connection timeout) /lava-3513/bin/lava-test-runner /lava-3513/0 + export TESTRUN_ID=0_TC_SYS_1_1 + TESTRUN_ID=0_TC_SYS_1_1 + cd /lava-3513/0/tests/0_TC_SYS_1_1 ++ cat uuid + UUID=3513_1.1.4.4.1 + set +x Received signal: <STARTRUN> 0_TC_SYS_1_1 3513_1.1.4.4.1 Starting test lava.0_TC_SYS_1_1 (3513_1.1.4.4.1) Skipping test definition patterns. <LAVA_SIGNAL_STARTRUN 0_TC_SYS_1_1 3513_1.1.4.4.1> + ./tests-target/system/run_system_test.sh TC_SYS_1_1 get_board_info - board name: STM32MP157C-DK2 get_board_info - nb cpu(s): 2 get_board_info - min cpu frequency: 650000 get_board_info - max cpu frequency: 650000 set_scaling_governor - scaling_governor governor not present **** Test performed with performance **** test done in 28.014067847 seconds param_test: mp157_2cpu_650 Target value: 28.3 seconds Time is OK set_scaling_governor - scaling_governor governor not present /lava-3513/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: 66127
definition: 0_TC_SYS_1_1
endtc: 1309
result: pass
starttc: 1309
TC_SYS_1_1 RESULT:pass + set +x <LAVA_SIGNAL_ENDRUN 0_TC_SYS_1_1 3513_1.1.4.4.1> Received signal: <ENDRUN> 0_TC_SYS_1_1 3513_1.1.4.4.1 Ending use of test pattern. Ending test lava.0_TC_SYS_1_1 (3513_1.1.4.4.1), duration 29.82 case: 0_TC_SYS_1_1
case_id: 66128
commit_id: 4fa3a85eb6b22fd12c25ef7674c175c400938e82
definition: lava
duration: 29.82
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: 3513_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:30) [common] end: 3 lava-test-retry (duration 00:00:30) [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' >> [04:14:11] [DEBUG] last digit correct >> ePowerSwitch epowerswitch_03.lme.st.com selected 3 >> Set pwrSwitch epowerswitch_03.lme.st.com on prt 3 to Off >> [04:14:11] [DEBUG] Switch Off Power supply >> [04:14:11] [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:01) [common] case: power-off
case_id: 66129
definition: lava
duration: 0.54
extra: ...
level: 4.1
namespace: common
result: pass
start: 4.2 read-feedback (timeout 00:00:29) [common] Listened to connection for namespace 'common' for 1s Finalising connection for namespace 'common' root@stm32mp1:/tmp# 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-3513/deploy-flasher-jc44pfzb/layout Cleaning up download directory: /var/lib/lava/dispatcher/tmp/lavafed-3513/deploy-flasher-jc44pfzb/tarball Root tmp directory removed at /var/lib/lava/dispatcher/tmp/lavafed-3513 Job finished correctly case: job
case_id: 66130
definition: lava
result: pass