Help
Invalid job definition:

Action timeout is larger than job timeout @ data['actions']['0']

Description
[lavafed 2021.10.0042.g47f100d97] stm32mp157c-dk2 - health-check
Device-type
stm32mp157c-dk2
Submitter
lavafed.st.com
Created
2 days, 10 hours ago
Priority
Medium
Visibility
Public
Required Tags
dispatcher-01.st.com
#3978
Incomplete
Device
lmecxd0318-mp157revc_disco_048@dispatcher-01.st.com
Started
2 days, 10 hours ago
Duration
7 minutes
Results
  

Infrastructure error: Connection closed

Action timeout for http-download exceeds Job timeout Action timeout for download-retry exceeds Job timeout Action timeout for http-download exceeds Job timeout Action timeout for download-retry exceeds Job timeout Action timeout for deploy-device-env exceeds Job timeout Action timeout for ssh-authorize exceeds Job timeout Action timeout for lava-vland-overlay exceeds Job timeout Action timeout for lava-multinode-overlay exceeds Job timeout Action timeout for git-repo-action exceeds Job timeout Action timeout for test-overlay exceeds Job timeout Action timeout for test-install-overlay exceeds Job timeout Action timeout for test-runscript-overlay exceeds Job timeout Action timeout for test-definition exceeds Job timeout Action timeout for compress-overlay exceeds Job timeout Action timeout for persistent-nfs-overlay exceeds Job timeout Action timeout for lava-overlay exceeds Job timeout Action timeout for deploy-flasher exceeds Job timeout Action timeout for deploy-flasher-retry exceeds Job timeout Action timeout for connect-device exceeds Job timeout Action timeout for pdu-reboot exceeds Job timeout Action timeout for reset-device exceeds Job timeout Action timeout for login-action exceeds Job timeout Action timeout for auto-login-action exceeds Job timeout Action timeout for expect-shell-connection exceeds Job timeout Action timeout for overlay-unpack exceeds Job timeout Action timeout for export-device-env exceeds Job timeout Action timeout for minimal-boot exceeds Job timeout lava-dispatcher, installed at version: 2021.10.0042.g47f100d97 start: 0 validate Start time: 2021-11-25 04:04:20.566358+00:00 (UTC) Validating that http://citools.st.com/artifacts/artifactory/oeivi-codex-st-com/oemanifest-v2/refs/tags/openstlinux-20-11-12/openstlinux-weston/images/stm32mp1/flashlayout_st-image-weston/trusted/FlashLayout_sdcard_stm32mp157c-dk2-trusted.tsv exists Validating that http://citools.st.com/artifacts/artifactory/oeivi-codex-st-com/oemanifest-v2/refs/tags/openstlinux-20-11-12/openstlinux-weston/IMAGES-st-image-weston-openstlinux-weston-stm32mp1-openstlinux-20-11-12.tar.xz exists validate duration: 0.08 case: validate
case_id: 74264
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-v2/refs/tags/openstlinux-20-11-12/openstlinux-weston/images/stm32mp1/flashlayout_st-image-weston/trusted/FlashLayout_sdcard_stm32mp157c-dk2-trusted.tsv saving as /var/lib/lava/dispatcher/tmp/lavafed-3978/deploy-flasher-fufok0rx/layout/FlashLayout_sdcard_stm32mp157c-dk2-trusted.tsv total size: 835 (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: 74265
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-v2/refs/tags/openstlinux-20-11-12/openstlinux-weston/IMAGES-st-image-weston-openstlinux-weston-stm32mp1-openstlinux-20-11-12.tar.xz saving as /var/lib/lava/dispatcher/tmp/lavafed-3978/deploy-flasher-fufok0rx/tarball/IMAGES-st-image-weston-openstlinux-weston-stm32mp1-openstlinux-20-11-12.tar.xz total size: 655472848 (625MB) No compression specified progress 0% (0MB) progress 5% (31MB) progress 10% (62MB) progress 15% (93MB) progress 20% (125MB) progress 25% (156MB) progress 30% (187MB) progress 35% (218MB) progress 40% (250MB) progress 45% (281MB) progress 50% (312MB) progress 55% (343MB) progress 60% (375MB) progress 65% (406MB) progress 70% (437MB) progress 75% (468MB) progress 80% (500MB) progress 85% (531MB) progress 90% (562MB) progress 95% (593MB) progress 100% (625MB) 625MB downloaded in 30.88s (20.24MB/s) end: 1.1.2.1 http-download (duration 00:00:31) [common] case: http-download
case_id: 74265
definition: lava
duration: 30.88
extra: ...
level: 1.1.2.1
namespace: common
result: pass
end: 1.1.2 download-retry (duration 00:00:31) [common] start: 1.1.3 deploy-device-env (timeout 00:19:29) [common] end: 1.1.3 deploy-device-env (duration 00:00:00) [common] start: 1.1.4 lava-overlay (timeout 00:19:29) [common] [common] Preparing overlay tarball in /var/lib/lava/dispatcher/tmp/lavafed-3978/lava-overlay-xptidfrr makedir: /var/lib/lava/dispatcher/tmp/lavafed-3978/lava-overlay-xptidfrr/lava-3978/bin makedir: /var/lib/lava/dispatcher/tmp/lavafed-3978/lava-overlay-xptidfrr/lava-3978/tests makedir: /var/lib/lava/dispatcher/tmp/lavafed-3978/lava-overlay-xptidfrr/lava-3978/results Creating /var/lib/lava/dispatcher/tmp/lavafed-3978/lava-overlay-xptidfrr/lava-3978/bin/lava-add-keys Creating /var/lib/lava/dispatcher/tmp/lavafed-3978/lava-overlay-xptidfrr/lava-3978/bin/lava-add-sources Creating /var/lib/lava/dispatcher/tmp/lavafed-3978/lava-overlay-xptidfrr/lava-3978/bin/lava-background-process-start Creating /var/lib/lava/dispatcher/tmp/lavafed-3978/lava-overlay-xptidfrr/lava-3978/bin/lava-background-process-stop Creating /var/lib/lava/dispatcher/tmp/lavafed-3978/lava-overlay-xptidfrr/lava-3978/bin/lava-common-functions Creating /var/lib/lava/dispatcher/tmp/lavafed-3978/lava-overlay-xptidfrr/lava-3978/bin/lava-echo-ipv4 Creating /var/lib/lava/dispatcher/tmp/lavafed-3978/lava-overlay-xptidfrr/lava-3978/bin/lava-install-packages Creating /var/lib/lava/dispatcher/tmp/lavafed-3978/lava-overlay-xptidfrr/lava-3978/bin/lava-installed-packages Creating /var/lib/lava/dispatcher/tmp/lavafed-3978/lava-overlay-xptidfrr/lava-3978/bin/lava-os-build Creating /var/lib/lava/dispatcher/tmp/lavafed-3978/lava-overlay-xptidfrr/lava-3978/bin/lava-probe-channel Creating /var/lib/lava/dispatcher/tmp/lavafed-3978/lava-overlay-xptidfrr/lava-3978/bin/lava-probe-ip Creating /var/lib/lava/dispatcher/tmp/lavafed-3978/lava-overlay-xptidfrr/lava-3978/bin/lava-target-ip Creating /var/lib/lava/dispatcher/tmp/lavafed-3978/lava-overlay-xptidfrr/lava-3978/bin/lava-target-mac Creating /var/lib/lava/dispatcher/tmp/lavafed-3978/lava-overlay-xptidfrr/lava-3978/bin/lava-target-storage Creating /var/lib/lava/dispatcher/tmp/lavafed-3978/lava-overlay-xptidfrr/lava-3978/bin/lava-test-case Creating /var/lib/lava/dispatcher/tmp/lavafed-3978/lava-overlay-xptidfrr/lava-3978/bin/lava-test-event Creating /var/lib/lava/dispatcher/tmp/lavafed-3978/lava-overlay-xptidfrr/lava-3978/bin/lava-test-feedback Creating /var/lib/lava/dispatcher/tmp/lavafed-3978/lava-overlay-xptidfrr/lava-3978/bin/lava-test-raise Creating /var/lib/lava/dispatcher/tmp/lavafed-3978/lava-overlay-xptidfrr/lava-3978/bin/lava-test-reference Creating /var/lib/lava/dispatcher/tmp/lavafed-3978/lava-overlay-xptidfrr/lava-3978/bin/lava-test-runner Creating /var/lib/lava/dispatcher/tmp/lavafed-3978/lava-overlay-xptidfrr/lava-3978/bin/lava-test-set Creating /var/lib/lava/dispatcher/tmp/lavafed-3978/lava-overlay-xptidfrr/lava-3978/bin/lava-test-shell Updating /var/lib/lava/dispatcher/tmp/lavafed-3978/lava-overlay-xptidfrr/lava-3978/bin/lava-install-packages (oe) Updating /var/lib/lava/dispatcher/tmp/lavafed-3978/lava-overlay-xptidfrr/lava-3978/bin/lava-installed-packages (oe) Creating /var/lib/lava/dispatcher/tmp/lavafed-3978/lava-overlay-xptidfrr/lava-3978/environment LAVA metadata - LAVA_JOB_ID=3978 start: 1.1.4.1 ssh-authorize (timeout 00:19:29) [common] end: 1.1.4.1 ssh-authorize (duration 00:00:00) [common] start: 1.1.4.2 lava-vland-overlay (timeout 00:19:29) [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:29) [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:29) [common] Loading test definitions start: 1.1.4.4.1 git-repo-action (timeout 00:19:29) [common] Using /lava-3978 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-3978/lava-overlay-xptidfrr/lava-3978/0/tests/0_TC_SYS_1_2' Running '/usr/bin/git checkout MPU_TEST-1.74bis Removing '.git' directory in /var/lib/lava/dispatcher/tmp/lavafed-3978/lava-overlay-xptidfrr/lava-3978/0/tests/0_TC_SYS_1_2 Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/lavafed-3978/lava-overlay-xptidfrr/lava-3978/0/tests/0_TC_SYS_1_2/tests-def/system/TC_SYS_1_2.yaml uuid=3978_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: 74267
definition: lava
duration: 5.29
extra: ...
level: 1.1.4.4.1
namespace: common
result: pass
start: 1.1.4.4.2 test-overlay (timeout 00:19:24) [common] end: 1.1.4.4.2 test-overlay (duration 00:00:00) [common] case: test-overlay
case_id: 74268
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:24) [common] end: 1.1.4.4.3 test-install-overlay (duration 00:00:00) [common] case: test-install-overlay
case_id: 74269
definition: lava
duration: 0.13
extra: ...
level: 1.1.4.4.3
namespace: common
result: pass
start: 1.1.4.4.4 test-runscript-overlay (timeout 00:19:24) [common] runner path: /var/lib/lava/dispatcher/tmp/lavafed-3978/lava-overlay-xptidfrr/lava-3978/0/tests/0_TC_SYS_1_2 test_uuid 3978_1.1.4.4.1 MD5SUM_BUILD='fd06fe125bbc782a6f9704f916e23c1c' end: 1.1.4.4.4 test-runscript-overlay (duration 00:00:00) [common] case: test-runscript-overlay
case_id: 74270
definition: lava
duration: 0.19
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-3978/lava-overlay-xptidfrr/lava-3978/0 for stage 0 - 0_TC_SYS_1_2 end: 1.1.4.4 test-definition (duration 00:00:06) [common] start: 1.1.4.5 compress-overlay (timeout 00:19:23) [common] end: 1.1.4.5 compress-overlay (duration 00:00:01) [common] start: 1.1.4.6 persistent-nfs-overlay (timeout 00:19:23) [common] end: 1.1.4.6 persistent-nfs-overlay (duration 00:00:00) [common] end: 1.1.4 lava-overlay (duration 00:00:07) [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 Nov 25 04:04 FlashLayout_sdcard_stm32mp157c-dk2-trusted.tsv -> layout/FlashLayout_sdcard_stm32mp157c-dk2-trusted.tsv >> drwxr-xr-x 2 root root 4096 Nov 25 04:04 layout >> drwxr-xr-x 2 root root 4096 Nov 25 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-20-11-12.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:04:59] [DEBUG] Boot Pin >> [04:04:59] #### BootManagement script started with layout/FlashLayout_sdcard_stm32mp157c-dk2-trusted.tsv tarball/IMAGES-st-image-weston-openstlinux-weston-stm32mp1-openstlinux-20-11-12.tar.xz >> [04:04:59] [DEBUG] md5sum computation start >> [04:04:59] [DEBUG] md5sum computation done. >> ######### MD5SUM for FlashLayout_sdcard_stm32mp157c-dk2-trusted.tsv file >> Calculated : 2c8df6859f370b6a764587c4c80fb6f8 >> ################################# >> [04:04:59] Boot media : mmc0 >> [04:04:59] *** Start normal boot procedure *** >> [04:04:59] *** Binary preparation *** >> [04:04:59] Tarball file extraction procedure for tarball/IMAGES-st-image-weston-openstlinux-weston-stm32mp1-openstlinux-20-11-12.tar.xz >> [04:04:59] [DEBUG] md5sum computation start >> [04:05:01] [DEBUG] md5sum computation done. >> ######### MD5SUM for IMAGES-st-image-weston-openstlinux-weston-stm32mp1-openstlinux-20-11-12.tar.xz file >> Calculated : 7ceb1485128ee599de7ef478f3c5dc0d >> ################################# >> [04:05:01] 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:44] Configure bootpins: >> [04:05:44] [DEBUG] BOOT_PIN INPUT: 12_13 >> [04:05:44] [DEBUG] BOOT_PIN[0]: 12 >> [04:05:44] [DEBUG] BOOT_PIN[1]: 13 >> [04:05:44] Reset boot pin to flash mode >> [04:05:44] [DEBUG] D12=0 >> [04:05:44] Wait to get lock for /dev/ttyACM.nucleo_lmecxd0318_4... >> [04:05:44] Activate lock for /dev/ttyACM.nucleo_lmecxd0318_4. >> [04:05:44] D12=0 >> [04:05:44] Release lock for /dev/ttyACM.nucleo_lmecxd0318_4 done. >> [04:05:44] [DEBUG] D13=0 >> [04:05:44] Wait to get lock for /dev/ttyACM.nucleo_lmecxd0318_4... >> [04:05:44] Activate lock for /dev/ttyACM.nucleo_lmecxd0318_4. >> [04:05:44] D13=0 >> [04:05:44] Release lock for /dev/ttyACM.nucleo_lmecxd0318_4 done. >> [04:05:44] [DEBUG] off Media_boot mmc0 >> [04:05:44] [DEBUG] D12=0 >> [04:05:44] Wait to get lock for /dev/ttyACM.nucleo_lmecxd0318_4... >> [04:05:44] Activate lock for /dev/ttyACM.nucleo_lmecxd0318_4. >> [04:05:44] D12=0 >> [04:05:44] Release lock for /dev/ttyACM.nucleo_lmecxd0318_4 done. >> [04:05:44] [DEBUG] D13=0 >> [04:05:44] Wait to get lock for /dev/ttyACM.nucleo_lmecxd0318_4... >> [04:05:44] Activate lock for /dev/ttyACM.nucleo_lmecxd0318_4. >> [04:05:44] D13=0 >> [04:05:44] Release lock for /dev/ttyACM.nucleo_lmecxd0318_4 done. >> [04:05:44] Wait for Relay to be established: 1 ms >> [04:05:45] Bootpins configure done. >> [04:05:45] [DEBUG] /root/git/lava-config/scripts/epower_switch.sh -u epowerswitch_03.lme.st.com -p 3 -c REBOOT -n 2001 >> [04:05:45] [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:45] [DEBUG] Switch OFF Power supply >> [04:05:45] [DEBUG] CMD: wget -O - http://epowerswitch_03.lme.st.com/hidden.htm?M0:O3=Off >> [04:06:02] [DEBUG] Switch ON Power supply >> [04:06:02] [DEBUG] CMD: wget -O - http://epowerswitch_03.lme.st.com/hidden.htm?M0:O3=On >> [04:06:02] Wait for 15 seconds... >> [04:06:17] *** Flashing Procedure *** >> [04:06:17] Launch Flashing operation number 1 >> [04:06:17] Wait for DFU to be up... >> [04:06:17] [DEBUG] DFU detection loop: 1 >> [04:06:17] [DEBUG] USB DFU device(s) available on host >> [04:06:18] [DEBUG] *** /root/git/programmer/x86_64-linux/bin/STM32_Programmer_CLI -l usb *** >> [04:06:18] ------------------------------------------------------------------- >> [04:06:18] STM32CubeProgrammer v2.7.0 >> [04:06:18] ------------------------------------------------------------------- >> [04:06:18] >> [04:06:18] ===== DFU Interface ===== >> [04:06:18] >> [04:06:18] Total number of available STM32 device in DFU mode: 1 >> [04:06:18] >> [04:06:18] Device Index : USB1 >> [04:06:18] USB Bus Number : 002 >> [04:06:18] USB Address Number : 005 >> [04:06:18] Product ID : DFU in HS Mode @Device ID /0x500, @Revision ID /0x0000 >> [04:06:18] Serial number : 0027003F3338510534383330 >> [04:06:18] Firmware version : 0x0110 >> [04:06:18] Device ID : 0x0500 >> [04:06:18] [DEBUG] *** >> [04:06:18] Found USB DFU device usb1 for serial number 0027003f3338510534383330 >> [04:06:18] Wait for DFU during 1 seconds >> [04:06:18] *********************** Use following command to flash: *********************** >> [04:06:18] TIMEOUT_VALUE=15m ARTIFACTS_DIR=/var/lib/lava/dispatcher/tmp/lavafed-3978/deploy-flasher-fufok0rx 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 >> >> New computed offset for userfs: >> >>> from : 0x33C84400 >> >>> to : 0x73c84400 >> [ARTIFACTS_DIR configuration] >> ARTIFACTS_DIR: /var/lib/lava/dispatcher/tmp/lavafed-3978/deploy-flasher-fufok0rx >> >> [TIMEOUT_VALUE configuration] >> TIMEOUT_VALUE: 15m >> >> >> ============================================================== >> *** Populate binaries to device with TSV file: >> layout/FlashLayout_sdcard_stm32mp157c-dk2-trusted.tsv >> ============================================================== >> #Opt Id Name Type IP Offset Binary >> - 0x01 fsbl1-boot Binary none 0x0 arm-trusted-firmware/tf-a-stm32mp157c-dk2-serialboot.stm32 >> - 0x03 ssbl-boot Binary none 0x0 bootloader/u-boot-stm32mp157c-dk2-trusted.stm32 >> P 0x04 fsbl1 Binary mmc0 0x00004400 arm-trusted-firmware/tf-a-stm32mp157c-dk2-trusted.stm32 >> P 0x05 fsbl2 Binary mmc0 0x00044400 arm-trusted-firmware/tf-a-stm32mp157c-dk2-trusted.stm32 >> PD 0x06 ssbl Binary mmc0 0x00084400 bootloader/u-boot-stm32mp157c-dk2-trusted.stm32 >> P 0x21 boot System mmc0 0x00284400 st-image-bootfs-openstlinux-weston-stm32mp1.ext4 >> P 0x22 vendorfs FileSystem mmc0 0x04284400 st-image-vendorfs-openstlinux-weston-stm32mp1.ext4 >> P 0x23 rootfs FileSystem mmc0 0x05284400 st-image-weston-openstlinux-weston-stm32mp1.ext4 >> P 0x24 userfs FileSystem mmc0 0x33C84400 st-image-userfs-openstlinux-weston-stm32mp1.ext4 >> ============================================================== >> ------------------------------------------------------------------- >> STM32CubeProgrammer v2.7.0 >> ------------------------------------------------------------------- >> >> >> >> 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-serialboot.stm32 >> File : tf-a-stm32mp157c-dk2-serialboot.stm32 >> Size : 237896 Bytes >> Partition ID : 0x01 >> >> Download in Progress: >> >> >> File download complete >> Time elapsed during download operation: 00:00:00.496 >> >> 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 : 904820 Bytes >> Partition ID : 0x03 >> >> Download in Progress: >> >> >> File download complete >> Time elapsed during download operation: 00:00:00.850 >> >> 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 >> >> ============================================================== >> *** Spent 67s for operation. >> *** (0h01m07s) >> ============================================================== >> >> [04:07:26] Flashing operation failed... >> [04:07:26] Launch Flashing operation number 2 >> [04:07:26] Wait for DFU to be up... >> [04:07:26] [DEBUG] DFU detection loop: 1 >> [04:07:26] [DEBUG] USB DFU device(s) available on host >> [04:07:26] [DEBUG] *** /root/git/programmer/x86_64-linux/bin/STM32_Programmer_CLI -l usb *** >> [04:07:26] ------------------------------------------------------------------- >> [04:07:26] STM32CubeProgrammer v2.7.0 >> [04:07:26] ------------------------------------------------------------------- >> [04:07:26] >> [04:07:26] ===== DFU Interface ===== >> [04:07:26] >> [04:07:26] Total number of available STM32 device in DFU mode: 1 >> [04:07:26] >> [04:07:26] Device Index : USB1 >> [04:07:26] USB Bus Number : 002 >> [04:07:26] USB Address Number : 005 >> [04:07:26] Product ID : USB download gadget@Device ID /0x500, @Revision ID /0x2000, @Name /STM32MP157CAC Rev.B, >> [04:07:26] Serial number : 0027003F3338510534383330 >> [04:07:26] Firmware version : 0x0110 >> [04:07:26] Device ID : 0x0500 >> [04:07:26] [DEBUG] *** >> [04:07:26] Found USB DFU device usb1 for serial number 0027003f3338510534383330 >> [04:07:26] Wait for DFU during 0 seconds >> [04:07:26] *********************** Use following command to flash: *********************** >> [04:07:26] TIMEOUT_VALUE=15m ARTIFACTS_DIR=/var/lib/lava/dispatcher/tmp/lavafed-3978/deploy-flasher-fufok0rx 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 >> >> New computed offset for userfs: >> >>> from : 0x33C84400 >> >>> to : 0x73c84400 >> [ARTIFACTS_DIR configuration] >> ARTIFACTS_DIR: /var/lib/lava/dispatcher/tmp/lavafed-3978/deploy-flasher-fufok0rx >> >> [TIMEOUT_VALUE configuration] >> TIMEOUT_VALUE: 15m >> >> >> ============================================================== >> *** Populate binaries to device with TSV file: >> layout/FlashLayout_sdcard_stm32mp157c-dk2-trusted.tsv >> ============================================================== >> #Opt Id Name Type IP Offset Binary >> - 0x01 fsbl1-boot Binary none 0x0 arm-trusted-firmware/tf-a-stm32mp157c-dk2-serialboot.stm32 >> - 0x03 ssbl-boot Binary none 0x0 bootloader/u-boot-stm32mp157c-dk2-trusted.stm32 >> P 0x04 fsbl1 Binary mmc0 0x00004400 arm-trusted-firmware/tf-a-stm32mp157c-dk2-trusted.stm32 >> P 0x05 fsbl2 Binary mmc0 0x00044400 arm-trusted-firmware/tf-a-stm32mp157c-dk2-trusted.stm32 >> PD 0x06 ssbl Binary mmc0 0x00084400 bootloader/u-boot-stm32mp157c-dk2-trusted.stm32 >> P 0x21 boot System mmc0 0x00284400 st-image-bootfs-openstlinux-weston-stm32mp1.ext4 >> P 0x22 vendorfs FileSystem mmc0 0x04284400 st-image-vendorfs-openstlinux-weston-stm32mp1.ext4 >> P 0x23 rootfs FileSystem mmc0 0x05284400 st-image-weston-openstlinux-weston-stm32mp1.ext4 >> P 0x24 userfs FileSystem mmc0 0x33C84400 st-image-userfs-openstlinux-weston-stm32mp1.ext4 >> ============================================================== >> ------------------------------------------------------------------- >> STM32CubeProgrammer v2.7.0 >> ------------------------------------------------------------------- >> >> >> >> 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 /0x2000, @Name /STM32MP157CAC Rev.B, >> SN : 0027003F3338510534383330 >> FW version : 0x0110 >> Device ID : 0x0500 >> Device name : STM32MP157CAC Rev.B >> 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 : 233800 Bytes >> Partition ID : 0x04 >> >> Download in Progress: >> >> >> File download complete >> Time elapsed during download operation: 00:00:00.058 >> >> 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 : 233800 Bytes >> Partition ID : 0x05 >> >> Download in Progress: >> >> >> File download complete >> Time elapsed during download operation: 00:00:00.065 >> >> 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 : 904820 Bytes >> Partition ID : 0x06 >> >> Download in Progress: >> >> >> File download complete >> Time elapsed during download operation: 00:00:00.246 >> >> 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:19.900 >> >> 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:05.505 >> >> 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 : 548556 KBytes >> Partition ID : 0x23 >> >> Download in Progress: >> >> >> File download complete >> Time elapsed during download operation: 00:02:44.699 >> >> 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 : 128 MBytes >> Partition ID : 0x24 >> >> Download in Progress: >> >> >> File download complete >> Time elapsed during download operation: 00:00:38.965 >> >> RUNNING Program ... >> PartID: :0x24 >> Start operation done successfully at partition 0x24 >> Flashing service completed successfully >> >> ============================================================== >> *** Spent 232s for operation. >> *** (0h03m52s) >> ============================================================== >> >> *** PROGRAMMER DONE *** >> [04:11:18] Flashing operation success >> [04:11:18] *************** Flashing operation complete with SUCCESS status *************** >> [04:11:18] Release lock for board in DFU done. >> [04:11:18] [DEBUG] RETURNCODE: 0 >> [04:11:18] Telnet connection available >> [04:11:18] Configure bootpins: >> [04:11:18] [DEBUG] BOOT_PIN INPUT: 12_13 >> [04:11:18] [DEBUG] BOOT_PIN[0]: 12 >> [04:11:18] [DEBUG] BOOT_PIN[1]: 13 >> [04:11:18] Reset boot pin to flash mode >> [04:11:18] [DEBUG] D12=0 >> [04:11:18] Wait to get lock for /dev/ttyACM.nucleo_lmecxd0318_4... >> [04:11:18] Activate lock for /dev/ttyACM.nucleo_lmecxd0318_4. >> [04:11:19] D12=0 >> [04:11:19] Release lock for /dev/ttyACM.nucleo_lmecxd0318_4 done. >> [04:11:19] [DEBUG] D13=0 >> [04:11:19] Wait to get lock for /dev/ttyACM.nucleo_lmecxd0318_4... >> [04:11:19] Activate lock for /dev/ttyACM.nucleo_lmecxd0318_4. >> [04:11:19] D13=0 >> [04:11:19] Release lock for /dev/ttyACM.nucleo_lmecxd0318_4 done. >> [04:11:19] [DEBUG] on Media_boot mmc0 >> [04:11:19] [DEBUG] D12=1 >> [04:11:19] Wait to get lock for /dev/ttyACM.nucleo_lmecxd0318_4... >> [04:11:19] Activate lock for /dev/ttyACM.nucleo_lmecxd0318_4. >> [04:11:19] D12=1 >> [04:11:19] Release lock for /dev/ttyACM.nucleo_lmecxd0318_4 done. >> [04:11:19] [DEBUG] D13=1 >> [04:11:19] Wait to get lock for /dev/ttyACM.nucleo_lmecxd0318_4... >> [04:11:19] Activate lock for /dev/ttyACM.nucleo_lmecxd0318_4. >> [04:11:19] D13=1 >> [04:11:19] Release lock for /dev/ttyACM.nucleo_lmecxd0318_4 done. >> [04:11:19] Wait for Relay to be established: 1 ms >> [04:11:20] Bootpins configure done. >> [04:11:20] [DEBUG] /root/git/lava-config/scripts/epower_switch.sh -u epowerswitch_03.lme.st.com -p 3 -c OFF -n 2001 >> [04:11: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:11:20] [DEBUG] Switch Off Power supply >> [04:11:20] [DEBUG] CMD: wget -O - http://epowerswitch_03.lme.st.com/hidden.htm?M0:O3=Off >> [04:11:20] Boot_management script successfully executed Returned 0 in 381 seconds end: 1.1 deploy-flasher (duration 00:07:00) [common] end: 1 deploy-flasher-retry (duration 00:07:00) [common] start: 2 minimal-boot (timeout 00:13:00) [common] start: 2.1 connect-device (timeout 00:13: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:13:00) [common] start: 2.2.1 pdu-reboot (timeout 00:13:00) [common] Calling: 'nice' '/root/git/lava-config/scripts/epower_switch.sh' '-u' 'epowerswitch_03.lme.st.com' '-p' '3' '-c' 'REBOOT' >> [04:11: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:11:21] [DEBUG] Switch OFF Power supply >> [04:11:21] [DEBUG] CMD: wget -O - http://epowerswitch_03.lme.st.com/hidden.htm?M0:O3=Off >> [04:11:36] [DEBUG] Switch ON Power supply >> [04:11: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: 74271
definition: lava
duration: 15.66
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:12:44) [common] Setting prompt string to ['Linux version [0-9]'] auto-login-action: Wait for prompt ['Linux version [0-9]'] (timeout 00:25:00) Trying 127.0.0.1... Connected to localhost. Escape character is '^]'. Device open failure: Internal I/O error Connection closed by foreign host. Connection closed end: 2.3 auto-login-action (duration 00:00:00) [common] case: auto-login-action
case_id: 74272
definition: lava
duration: 0.00
extra: ...
level: 2.3
namespace: common
result: fail
minimal-boot failed: 1 of 2 attempts. 'Connection closed' Retrying: 2 minimal-boot (timeout 00:13:00) start: 2.1 connect-device (timeout 00:13:00) [common] Dead connection, reconnecting [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:13:00) [common] start: 2.2.1 pdu-reboot (timeout 00:13:00) [common] Calling: 'nice' '/root/git/lava-config/scripts/epower_switch.sh' '-u' 'epowerswitch_03.lme.st.com' '-p' '3' '-c' 'REBOOT' >> [04:11:38] [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:11:38] [DEBUG] Switch OFF Power supply >> [04:11:38] [DEBUG] CMD: wget -O - http://epowerswitch_03.lme.st.com/hidden.htm?M0:O3=Off >> [04:11:53] [DEBUG] Switch ON Power supply >> [04:11:53] [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: 74271
definition: lava
duration: 15.63
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:12:44) [common] Setting prompt string to ['Linux version [0-9]'] auto-login-action: Wait for prompt ['Linux version [0-9]'] (timeout 00:25:00) Trying 127.0.0.1... Connected to localhost. Escape character is '^]'. Device open failure: Internal I/O error Connection closed by foreign host. Connection closed end: 2.3 auto-login-action (duration 00:00:00) [common] case: auto-login-action
case_id: 74272
definition: lava
duration: 0.00
extra: ...
level: 2.3
namespace: common
result: fail
minimal-boot failed: 2 of 2 attempts. 'Connection closed' Connection closed end: 2 minimal-boot (duration 00:00:16) [common] case: minimal-boot
case_id: 74275
definition: lava
duration: 15.93
extra: ...
level: 2
namespace: common
result: fail
Cleaning after the job Cleaning up download directory: /var/lib/lava/dispatcher/tmp/lavafed-3978/deploy-flasher-fufok0rx/layout Cleaning up download directory: /var/lib/lava/dispatcher/tmp/lavafed-3978/deploy-flasher-fufok0rx/tarball 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:11:54] [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:11:54] [DEBUG] Switch Off Power supply >> [04:11:54] [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: 74276
definition: lava
duration: 0.51
extra: ...
level: 4.1
namespace: common
result: pass
start: 4.2 read-feedback (timeout 00:00:29) [common] Finalising connection for namespace 'common' end: 4.2 read-feedback (duration 00:00:00) [common] Root tmp directory removed at /var/lib/lava/dispatcher/tmp/lavafed-3978 InfrastructureError: The Infrastructure is not working correctly. Please report this error to LAVA admins. case: job
case_id: 74277
definition: lava
error_msg: Connection closed
error_type: Infrastructure
result: fail

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

device.name
lmecxd0318-mp157revc_disco_048
device.type
stm32mp157c-dk2
features.0.action
deploy
features.0.description
Deploy using a flashing tool
features.0.name
deploy.flasher
features.0.type
device
features.1.action
deploy
features.1.description
Use transfer_overlay command
features.1.name
deploy.transfer_overlay
features.1.type
device
features.2.action
boot
features.2.description
Non-interactive boot
features.2.name
boot.minimal
features.2.type
device
features.3.action
test
features.3.description
Test definition from git
features.3.name
test.definition.git
features.3.type
device
job.name
stm32mp157c-dk2 - health-check
job.type
test
job.url
https://federation.lavasoftware.org/lava/scheduler/job/{id}
lab.name
dispatcher-01.st.com
slave.arch
amd64
slave.version
2021.10.0042.g47f100d97
device type
hostname
timeouts
  • actions: {'minutes': 2}{'minutes': 2}{'minutes': 2}{'minutes': 2}{'minutes': 3}{'minutes': 3}{'seconds': 30}{'minutes': 3}{'minutes': 10}{'minutes': 5}{'minutes': 20}{'minutes': 5}{'minutes': 3}{'minutes': 1}{'minutes': 3}{'minutes': 10}{'seconds': 30}{'seconds': 15}{'seconds': 30}{'seconds': 30}{'minutes': 3}{'minutes': 3}{'seconds': 45}{'seconds': 30} apply-overlay-imageauto-login-actionboot-image-retryboot-qemu-imagebootloader-actionbootloader-commandsbootloader-interruptbootloader-retrydd-imagedownload-retryflash-uboot-umshttp-downloadlava-test-shellmusca-bootmusca-deploynfs-deploypdu-rebootpower-offreset-deviceu-boot-interruptuboot-actionuboot-commandsumount-retryunmount-musca-usbmsd
  • connections: {'minutes': 2}{'seconds': 30}{'seconds': 30}{'minutes': 10}{'seconds': 10}{'seconds': 10}{'seconds': 30}{'seconds': 30} auto-login-actionbootloader-commandsbootloader-interruptdd-imagelava-docker-test-shelllava-test-shellu-boot-interruptuboot-commands
device action methods
boot:
  • dfu:
    • command: dfu-util
    • enter-commands: None
  • minimal:
  • ssh:
  • u-boot:
    • bootloader_prompt: STM32MP>
    • interrupt_char:
    • interrupt_prompt: Hit any key to stop autoboot
    • needs_interrupt: True
  • uuu:
deploy:
  • flasher:
  • image:
  • lxc:
  • nbd:
  • overlay:
  • ssh:
  • tftp:
  • usb:
target
...
timeouts
  • job: {'seconds': 1200}

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

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

  1. Definitions
    • from git
    • history False
    • name TC_SYS_1_2
    • params {'MD5SUM_BUILD': 'fd06fe125bbc782a6f9704f916e23c1c'}
    • path tests-def/system/TC_SYS_1_2.yaml
    • repository ssh://gerrit.st.com:29418/oeivi/test/lava/lava-tests
    • revision MPU_TEST-1.74bis
    namespace
    common
    stage
    0
    Timeout
    • 10 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...