search

espressif / openocd-esp32

OpenOCD branch with ESP32 JTAG support
Other
358 stars 131 forks source link

OpenOCD - GDB Session (OCD-755) #290

Closed Duolabs closed 1 year ago

Duolabs commented 1 year ago

Development Kit

Custom ESP32S3 board

Module or chip used

ESP32S3-WROOM-1-N8R2

Debug Adapter

Built in JTAG

OpenOCD version

pen On-Chip Debugger v0.12.0-esp32-20230313

Operating System

Ubuntu

Using an IDE ?

Eclipse

OpenOCD command line

-s ${openocd_path}/share/openocd/scripts -f board/esp32s3-builtin.cfg

JTAG Clock Speed

default

ESP-IDF version

5.0.1

Problem Description

Debug Large applications OpenOCD fails to startup due to a timeout error.

Scenario:

Debugger {tab} [X] Start GDB Sesssion

Startup {tab} OpenOCD Options: [X] Flash every time with application binaries

When the programming time of the module exceeds 25 seconds the error raises as follows: Error in services launch sequence Starting OpenOCD timed out.

This issue is widely spread on the net and many users are reporting it in the same way as we do.

If we short the source code and let the module program below 25 seconds everything works fine. Once you cross the boundary of 25 seconds it fails all of the times.

The problems seems to be on a HARDCODED 25 seconds timeout. We've tried to force the time out in different points of the Eclipse IDE:

GDB Client setup Commands: set mem inaccessible-by-default off set remotetime 50

NO EFFECT

Windows->Preferences->Espressif->GDB server launch timeous(s) 50

NO EFFECT

I am pretty sure that this damned 25 seconds is HARDCODED somewhere and I cannot find where it is.

To the best of my knownledge there is NO WAY to change this timeout and it always triggers when the Application Flashing time exceeds such limit. The problem is existing in IDF 5.0.1 such as in old 4.x.x versions.

Yet again please do not come up with suggestions like drivers or such as it is a matter of fact that with 24.899 ms it works and when I reach 26.243 it fails.

Please help us as it is extremely frustrating as our application is around 1.5MB which takes pretty much that time and please consider that this problem is running around since months on forums.

Debug Logs

No response

Expected behavior

OpenOCD should not timeout and work properly.

Screenshots

No response

erhankur commented 1 year ago

@alirana01 can you have a look? cc @kolipakakondal

Duolabs commented 1 year ago

Hallo. Anybody in charge of this?

igrr commented 1 year ago

This looks like a relevant issue: https://github.com/espressif/idf-eclipse-plugin/issues/423#issuecomment-1038997809

Eclipse > Prefernces > Espressif > GDB server launch timeout

Could you please try increasing the "GDB Server launch timeout" option value and see if that helps?

Duolabs commented 1 year ago

Hallo @igrr

Nice to see you again....

As I mentioned earlier we tested it over and over before posting. Of course I've tried this before and it never works.

On this comment by @kolipakakondal https://github.com/espressif/idf-eclipse-plugin/issues/423#issuecomment-1038997809 He says that: "Hello, We have increased the default timeout for the OpenOCD server from 15 sec to 25 sec with the v2.4.1" which makes me think that it is hardcoded.

Anyhow the test is easy to be carried. If you set it to 3 seconds you should have a timeout right away as the flasher usually does program the whole device on time. That being said you will notice that the timeout will trigger after 25 seconds (if not hooked) regardless of the time you set. Make the test yourself and you will notice that it won't trigger in 3 seconds. I am 100% sure that "GDB Server launch timeout" is not taken into consideration.

I've also tried to set it manually as it follows: Edit Configuration->Startup->Initialization commands: mon reset halt flushregs set remote hardware-watchpoint-limit 2 set remotetimeout 30 Regardless of the command sequence nothing happens

Then I've tried on: Edit Configuration->Debugger->GBD Client Setup [Commands] set mem inaccessible-by-default off set remotetimeout 30 Regardless of the command sequence nothing happens

Screen1

Screen2

Screen3

Duolabs commented 1 year ago

Sorry for closing it. Issue still pending

kolipakakondal commented 1 year ago

Hi @Duolabs We apologize for the inconvenience. Let us investigate and get back to you on this.

Could you please share the error log to see if anything else is reported there https://github.com/espressif/idf-eclipse-plugin#error-log

Duolabs commented 1 year ago

Hi @kolipakakondal

Thank you for your answer. Here comes the ErrorLog.txt

As you can see between the start time 09:34:17.798 and the end time 09:34:43.792 we have exactly 25.994 -> 26 secs.

I repeat one more time that if the flashing time is below 25 seconds (25.000) to be precise everything works fine.

In my machine (Ubuntu 22.04 running on VMPlayer) I reach that flashing time with an application of around 1.5/1.6MB. On course this Full Image size flashing time may vary under different PC performances however every flashing time over fixed 25 seconds will cause the OpenOCD error!

Just in case here are the full flash details.

Total sizes:
Used static IRAM:   97382 bytes ( 264858 remain, 26.9% used)
      .text size:   96355 bytes
   .vectors size:    1027 bytes
Used stat D/IRAM:   71084 bytes ( 274772 remain, 20.6% used)
      .data size:   18388 bytes
      .bss  size:   52696 bytes
Used Flash size : 1449971 bytes
      .text     :  863931 bytes
      .rodata   :  585784 bytes
Total image size: 1565741 bytes (.bin may be padded larger)

For your reference if unflag the Start GCB Client you can see the programming time:

Open On-Chip Debugger v0.12.0-esp32-20230313 (2023-03-13-09:07)
Licensed under GNU GPL v2
For bug reports, read
    http://openocd.org/doc/doxygen/bugs.html
Info : only one transport option; autoselecting 'jtag'
Info : esp_usb_jtag: VID set to 0x303a and PID to 0x1001
Info : esp_usb_jtag: capabilities descriptor set to 0x2000
Warn : Transport "jtag" was already selected
Flashing /home/ubuntu/Desktop/_ESP32_WORKSPACE_/ESP32_Basic_Project_0.3.6/build/bootloader/bootloader.bin at 0x0
** program_esp input args <0x0 verify> **
Info : esp_usb_jtag: serial (F4:12:FA:C0:A5:5C)
Info : esp_usb_jtag: Device found. Base speed 40000KHz, div range 1 to 255
Info : clock speed 40000 kHz
Info : JTAG tap: esp32s3.cpu0 tap/device found: 0x120034e5 (mfg: 0x272 (Tensilica), part: 0x2003, ver: 0x1)
Info : JTAG tap: esp32s3.cpu1 tap/device found: 0x120034e5 (mfg: 0x272 (Tensilica), part: 0x2003, ver: 0x1)
Info : starting gdb server for esp32s3.cpu0 on 3333
Info : Listening on port 3333 for gdb connections
Info : JTAG tap: esp32s3.cpu0 tap/device found: 0x120034e5 (mfg: 0x272 (Tensilica), part: 0x2003, ver: 0x1)
Info : JTAG tap: esp32s3.cpu1 tap/device found: 0x120034e5 (mfg: 0x272 (Tensilica), part: 0x2003, ver: 0x1)
Info : [esp32s3.cpu0] requesting target halt and executing a soft reset
Info : [esp32s3.cpu0] Target halted, PC=0x4037BFEE, debug_reason=00000000
Info : [esp32s3.cpu0] Reset cause (3) - (Software core reset)
Info : Set GDB target to 'esp32s3.cpu0'
Info : [esp32s3.cpu1] Target halted, PC=0x4037BFEE, debug_reason=00000000
Info : [esp32s3.cpu1] Reset cause (3) - (Software core reset)
Info : [esp32s3.cpu0] Debug controller was reset.
Info : [esp32s3.cpu0] Core was reset.
Info : [esp32s3.cpu0] Target halted, PC=0x500000EF, debug_reason=00000000
Info : [esp32s3.cpu0] Reset cause (3) - (Software core reset)
Info : [esp32s3.cpu1] requesting target halt and executing a soft reset
Info : [esp32s3.cpu0] Core was reset.
Info : [esp32s3.cpu0] Target halted, PC=0x40000400, debug_reason=00000000
Info : [esp32s3.cpu1] Debug controller was reset.
Info : [esp32s3.cpu1] Core was reset.
Info : [esp32s3.cpu1] Target halted, PC=0x40000400, debug_reason=00000000
Info : [esp32s3.cpu1] Reset cause (3) - (Software core reset)
Info : [esp32s3.cpu0] Reset cause (3) - (Software core reset)
Info : [esp32s3.cpu0] Target halted, PC=0x403B244A, debug_reason=00000001
Info : Flash mapping 0: 0x20020 -> 0x3c090020, 496 KB
Info : Flash mapping 1: 0xa0020 -> 0x42000020, 566 KB
Info : [esp32s3.cpu0] Target halted, PC=0x403B244A, debug_reason=00000001
Info : Auto-detected flash bank 'esp32s3.cpu0.flash' size 8192 KB
Info : Using flash bank 'esp32s3.cpu0.flash' size 8192 KB
Info : [esp32s3.cpu0] Target halted, PC=0x403B244A, debug_reason=00000001
Info : Flash mapping 0: 0x20020 -> 0x3c090020, 496 KB
Info : Flash mapping 1: 0xa0020 -> 0x42000020, 566 KB
Info : [esp32s3.cpu0] Target halted, PC=0x403B244A, debug_reason=00000001
Info : Auto-detected flash bank 'esp32s3.cpu1.flash' size 8192 KB
Info : Using flash bank 'esp32s3.cpu1.flash' size 8192 KB
** Programming Started **
Info : [esp32s3.cpu0] Target halted, PC=0x403B244A, debug_reason=00000001
Info : [esp32s3.cpu0] Target halted, PC=0x403B244A, debug_reason=00000001
Info : PROF: Erased 24576 bytes in 655.759 ms
Info : PROF: Data transferred in 206.095 ms @ 116.451 KB/s
Info : [esp32s3.cpu0] Target halted, PC=0x403B244A, debug_reason=00000001
Info : PROF: Wrote 24576 bytes in 490.634 ms (data transfer time included)
** Programming Finished in 2012 ms **
** Verify Started **
Info : [esp32s3.cpu0] Target halted, PC=0x403B244A, debug_reason=00000001
Info : PROF: Flash verified in 124.301 ms 
** Verify OK **
** Flashing done for bootloader/bootloader.bin in 2143 ms **
Flashing /home/ubuntu/Desktop/_ESP32_WORKSPACE_/ESP32_Basic_Project_0.3.6/build/ESP32_Basic_Project_0.bin at 0x20000
** program_esp input args <0x20000 verify> **
Info : JTAG tap: esp32s3.cpu0 tap/device found: 0x120034e5 (mfg: 0x272 (Tensilica), part: 0x2003, ver: 0x1)
Info : JTAG tap: esp32s3.cpu1 tap/device found: 0x120034e5 (mfg: 0x272 (Tensilica), part: 0x2003, ver: 0x1)
Info : [esp32s3.cpu0] requesting target halt and executing a soft reset
Info : [esp32s3.cpu0] Debug controller was reset.
Info : [esp32s3.cpu0] Core was reset.
Info : [esp32s3.cpu0] Target halted, PC=0x500000EF, debug_reason=00000000
Info : [esp32s3.cpu0] Reset cause (3) - (Software core reset)
Info : [esp32s3.cpu1] requesting target halt and executing a soft reset
Info : [esp32s3.cpu0] Core was reset.
Info : [esp32s3.cpu0] Target halted, PC=0x40000400, debug_reason=00000000
Info : [esp32s3.cpu1] Debug controller was reset.
Info : [esp32s3.cpu1] Core was reset.
Info : [esp32s3.cpu1] Target halted, PC=0x40000400, debug_reason=00000000
Info : [esp32s3.cpu1] Reset cause (3) - (Software core reset)
Info : [esp32s3.cpu0] Reset cause (3) - (Software core reset)
** Programming Started **
Info : [esp32s3.cpu0] Target halted, PC=0x403B244A, debug_reason=00000001
Info : [esp32s3.cpu0] Target halted, PC=0x403B244A, debug_reason=00000001
Info : PROF: Erased 1568768 bytes in 7265.47 ms
Info : PROF: Data transferred in 12365.3 ms @ 123.895 KB/s
Info : [esp32s3.cpu0] Target halted, PC=0x403B244A, debug_reason=00000001
Info : PROF: Wrote 1568768 bytes in 12647.4 ms (data transfer time included)
** Programming Finished in 20239 ms **
** Verify Started **
Info : [esp32s3.cpu0] Target halted, PC=0x403B244A, debug_reason=00000001
Info : PROF: Flash verified in 971.075 ms 
** Verify OK **
** Flashing done for ESP32_Basic_Project_0.bin in 21227 ms **
Flashing /home/ubuntu/Desktop/_ESP32_WORKSPACE_/ESP32_Basic_Project_0.3.6/build/partition_table/partition-table.bin at 0x8000
** program_esp input args <0x8000 verify> **
Info : JTAG tap: esp32s3.cpu0 tap/device found: 0x120034e5 (mfg: 0x272 (Tensilica), part: 0x2003, ver: 0x1)
Info : JTAG tap: esp32s3.cpu1 tap/device found: 0x120034e5 (mfg: 0x272 (Tensilica), part: 0x2003, ver: 0x1)
Info : [esp32s3.cpu0] requesting target halt and executing a soft reset
Info : [esp32s3.cpu0] Debug controller was reset.
Info : [esp32s3.cpu0] Core was reset.
Info : [esp32s3.cpu0] Target halted, PC=0x500000EF, debug_reason=00000000
Info : [esp32s3.cpu0] Reset cause (3) - (Software core reset)
Info : [esp32s3.cpu1] requesting target halt and executing a soft reset
Info : [esp32s3.cpu0] Core was reset.
Info : [esp32s3.cpu0] Target halted, PC=0x40000400, debug_reason=00000000
Info : [esp32s3.cpu1] Debug controller was reset.
Info : [esp32s3.cpu1] Core was reset.
Info : [esp32s3.cpu1] Target halted, PC=0x40000400, debug_reason=00000000
Info : [esp32s3.cpu1] Reset cause (3) - (Software core reset)
Info : [esp32s3.cpu0] Reset cause (3) - (Software core reset)
** Programming Started **
Info : [esp32s3.cpu0] Target halted, PC=0x403B244A, debug_reason=00000001
Info : [esp32s3.cpu0] Target halted, PC=0x403B244A, debug_reason=00000001
Info : PROF: Erased 4096 bytes in 185.826 ms
Info : PROF: Data transferred in 39.011 ms @ 102.535 KB/s
Info : [esp32s3.cpu0] Target halted, PC=0x403B244A, debug_reason=00000001
Info : PROF: Wrote 4096 bytes in 284.333 ms (data transfer time included)
** Programming Finished in 800 ms **
** Verify Started **
Info : [esp32s3.cpu0] Target halted, PC=0x403B244A, debug_reason=00000001
Info : PROF: Flash verified in 109.655 ms 
** Verify OK **
** Flashing done for partition_table/partition-table.bin in 917 ms **
Flashing /home/ubuntu/Desktop/_ESP32_WORKSPACE_/ESP32_Basic_Project_0.3.6/build/ota_data_initial.bin at 0x10000
** program_esp input args <0x10000 verify> **
Info : JTAG tap: esp32s3.cpu0 tap/device found: 0x120034e5 (mfg: 0x272 (Tensilica), part: 0x2003, ver: 0x1)
Info : JTAG tap: esp32s3.cpu1 tap/device found: 0x120034e5 (mfg: 0x272 (Tensilica), part: 0x2003, ver: 0x1)
Info : [esp32s3.cpu0] requesting target halt and executing a soft reset
Info : [esp32s3.cpu0] Debug controller was reset.
Info : [esp32s3.cpu0] Core was reset.
Info : [esp32s3.cpu0] Target halted, PC=0x500000EF, debug_reason=00000000
Info : [esp32s3.cpu0] Reset cause (3) - (Software core reset)
Info : [esp32s3.cpu1] requesting target halt and executing a soft reset
Info : [esp32s3.cpu0] Core was reset.
Info : [esp32s3.cpu0] Target halted, PC=0x40000400, debug_reason=00000000
Info : [esp32s3.cpu1] Debug controller was reset.
Info : [esp32s3.cpu1] Core was reset.
Info : [esp32s3.cpu1] Target halted, PC=0x40000400, debug_reason=00000000
Info : [esp32s3.cpu1] Reset cause (3) - (Software core reset)
Info : [esp32s3.cpu0] Reset cause (3) - (Software core reset)
** Programming Started **
Info : [esp32s3.cpu0] Target halted, PC=0x403B244A, debug_reason=00000001
Info : [esp32s3.cpu0] Target halted, PC=0x403B244A, debug_reason=00000001
Info : PROF: Erased 8192 bytes in 283.821 ms
Info : PROF: Data transferred in 54.522 ms @ 146.73 KB/s
Info : [esp32s3.cpu0] Target halted, PC=0x403B244A, debug_reason=00000001
Info : PROF: Wrote 8192 bytes in 294.669 ms (data transfer time included)
** Programming Finished in 884 ms **
** Verify Started **
Info : [esp32s3.cpu0] Target halted, PC=0x403B244A, debug_reason=00000001
Info : PROF: Flash verified in 118.429 ms 
** Verify OK **
** Flashing done for ota_data_initial.bin in 1010 ms **
** Total programming time 25297 ms **
** Resetting Target **
Info : JTAG tap: esp32s3.cpu0 tap/device found: 0x120034e5 (mfg: 0x272 (Tensilica), part: 0x2003, ver: 0x1)
Info : JTAG tap: esp32s3.cpu1 tap/device found: 0x120034e5 (mfg: 0x272 (Tensilica), part: 0x2003, ver: 0x1)
Info : [esp32s3.cpu0] requesting target halt and executing a soft reset
Info : [esp32s3.cpu0] Debug controller was reset.
Info : [esp32s3.cpu0] Core was reset.
Info : [esp32s3.cpu0] Target halted, PC=0x500000EF, debug_reason=00000000
Info : [esp32s3.cpu0] Reset cause (3) - (Software core reset)
Info : [esp32s3.cpu1] requesting target halt and executing a soft reset
Info : [esp32s3.cpu0] Core was reset.
Info : [esp32s3.cpu1] Debug controller was reset.
Info : [esp32s3.cpu1] Core was reset.
0
Started by GNU MCU Eclipse
Info : Listening on port 6666 for tcl connections
Info : Listening on port 4444 for telnet connections
Info : [esp32s3.cpu0] Target halted, PC=0x4004883B, debug_reason=00000000
Info : [esp32s3.cpu0] Reset cause (3) - (Software core reset)
Info : [esp32s3.cpu1] Target halted, PC=0x40043A40, debug_reason=00000000
Info : [esp32s3.cpu1] Reset cause (3) - (Software core reset)
Info : [esp32s3.cpu1] Debug controller was reset.
Info : [esp32s3.cpu1] Core was reset.
Info : [esp32s3.cpu1] Target halted, PC=0x40041A76, debug_reason=00000000
Info : [esp32s3.cpu1] Reset cause (3) - (Software core reset)

Please note: Total programming time 25297 ms. My system exceeded of 297ms the 25 secs the flashing time and I fall into the Timeout error.

Here comes some info about a successfull startup of a debug session

Open On-Chip Debugger v0.12.0-esp32-20230313 (2023-03-13-09:07)
Licensed under GNU GPL v2
For bug reports, read
    http://openocd.org/doc/doxygen/bugs.html
Info : only one transport option; autoselecting 'jtag'
Info : esp_usb_jtag: VID set to 0x303a and PID to 0x1001
Info : esp_usb_jtag: capabilities descriptor set to 0x2000
Warn : Transport "jtag" was already selected
Flashing /home/ubuntu/Desktop/_ESP32_WORKSPACE_/ESP32_Basic_Project_0.3.6/build/bootloader/bootloader.bin at 0x0
** program_esp input args <0x0 verify> **
Info : esp_usb_jtag: serial (F4:12:FA:C0:A5:5C)
Info : esp_usb_jtag: Device found. Base speed 40000KHz, div range 1 to 255
Info : clock speed 40000 kHz
Info : JTAG tap: esp32s3.cpu0 tap/device found: 0x120034e5 (mfg: 0x272 (Tensilica), part: 0x2003, ver: 0x1)
Info : JTAG tap: esp32s3.cpu1 tap/device found: 0x120034e5 (mfg: 0x272 (Tensilica), part: 0x2003, ver: 0x1)
Info : starting gdb server for esp32s3.cpu0 on 3333
Info : Listening on port 3333 for gdb connections
Info : JTAG tap: esp32s3.cpu0 tap/device found: 0x120034e5 (mfg: 0x272 (Tensilica), part: 0x2003, ver: 0x1)
Info : JTAG tap: esp32s3.cpu1 tap/device found: 0x120034e5 (mfg: 0x272 (Tensilica), part: 0x2003, ver: 0x1)
Info : [esp32s3.cpu0] requesting target halt and executing a soft reset
Info : [esp32s3.cpu0] Target halted, PC=0x4037BFEE, debug_reason=00000000
Info : [esp32s3.cpu0] Reset cause (3) - (Software core reset)
Info : Set GDB target to 'esp32s3.cpu0'
Info : [esp32s3.cpu1] Debug controller was reset.
Info : [esp32s3.cpu1] Core was reset.
Info : [esp32s3.cpu1] Target halted, PC=0x4037BFEE, debug_reason=00000000
Info : [esp32s3.cpu1] Reset cause (3) - (Software core reset)
Info : [esp32s3.cpu0] Debug controller was reset.
Info : [esp32s3.cpu0] Core was reset.
Info : [esp32s3.cpu0] Target halted, PC=0x500000EF, debug_reason=00000000
Info : [esp32s3.cpu0] Reset cause (3) - (Software core reset)
Info : [esp32s3.cpu1] requesting target halt and executing a soft reset
Info : [esp32s3.cpu0] Core was reset.
Info : [esp32s3.cpu0] Target halted, PC=0x40000400, debug_reason=00000000
Info : [esp32s3.cpu1] Debug controller was reset.
Info : [esp32s3.cpu1] Core was reset.
Info : [esp32s3.cpu1] Target halted, PC=0x40000400, debug_reason=00000000
Info : [esp32s3.cpu1] Reset cause (3) - (Software core reset)
Info : [esp32s3.cpu0] Reset cause (3) - (Software core reset)
Info : [esp32s3.cpu0] Target halted, PC=0x403B244A, debug_reason=00000001
Info : Flash mapping 0: 0x20020 -> 0x3c090020, 496 KB
Info : Flash mapping 1: 0xa0020 -> 0x42000020, 566 KB
Info : [esp32s3.cpu0] Target halted, PC=0x403B244A, debug_reason=00000001
Info : Auto-detected flash bank 'esp32s3.cpu0.flash' size 8192 KB
Info : Using flash bank 'esp32s3.cpu0.flash' size 8192 KB
Info : [esp32s3.cpu0] Target halted, PC=0x403B244A, debug_reason=00000001
Info : Flash mapping 0: 0x20020 -> 0x3c090020, 496 KB
Info : Flash mapping 1: 0xa0020 -> 0x42000020, 566 KB
Info : [esp32s3.cpu0] Target halted, PC=0x403B244A, debug_reason=00000001
Info : Auto-detected flash bank 'esp32s3.cpu1.flash' size 8192 KB
Info : Using flash bank 'esp32s3.cpu1.flash' size 8192 KB
** Programming Started **
Info : [esp32s3.cpu0] Target halted, PC=0x403B244A, debug_reason=00000001
Info : [esp32s3.cpu0] Target halted, PC=0x403B244A, debug_reason=00000001
Info : PROF: Erased 24576 bytes in 661.934 ms
Info : PROF: Data transferred in 199.621 ms @ 120.228 KB/s
Info : [esp32s3.cpu0] Target halted, PC=0x403B244A, debug_reason=00000001
Info : PROF: Wrote 24576 bytes in 478.666 ms (data transfer time included)
** Programming Finished in 1985 ms **
** Verify Started **
Info : [esp32s3.cpu0] Target halted, PC=0x403B244A, debug_reason=00000001
Info : PROF: Flash verified in 120.664 ms 
** Verify OK **
** Flashing done for bootloader/bootloader.bin in 2112 ms **
Flashing /home/ubuntu/Desktop/_ESP32_WORKSPACE_/ESP32_Basic_Project_0.3.6/build/ESP32_Basic_Project_0.bin at 0x20000
** program_esp input args <0x20000 verify> **
Info : JTAG tap: esp32s3.cpu0 tap/device found: 0x120034e5 (mfg: 0x272 (Tensilica), part: 0x2003, ver: 0x1)
Info : JTAG tap: esp32s3.cpu1 tap/device found: 0x120034e5 (mfg: 0x272 (Tensilica), part: 0x2003, ver: 0x1)
Info : [esp32s3.cpu0] requesting target halt and executing a soft reset
Info : [esp32s3.cpu0] Debug controller was reset.
Info : [esp32s3.cpu0] Core was reset.
Info : [esp32s3.cpu0] Target halted, PC=0x500000EF, debug_reason=00000000
Info : [esp32s3.cpu0] Reset cause (3) - (Software core reset)
Info : [esp32s3.cpu1] requesting target halt and executing a soft reset
Info : [esp32s3.cpu0] Core was reset.
Info : [esp32s3.cpu0] Target halted, PC=0x40000400, debug_reason=00000000
Info : [esp32s3.cpu1] Debug controller was reset.
Info : [esp32s3.cpu1] Core was reset.
Info : [esp32s3.cpu1] Target halted, PC=0x40000400, debug_reason=00000000
Info : [esp32s3.cpu1] Reset cause (3) - (Software core reset)
Info : [esp32s3.cpu0] Reset cause (3) - (Software core reset)
** Programming Started **
Info : [esp32s3.cpu0] Target halted, PC=0x403B244A, debug_reason=00000001
Info : [esp32s3.cpu0] Target halted, PC=0x403B244A, debug_reason=00000001
Info : PROF: Erased 1187840 bytes in 5479.69 ms
Info : PROF: Data transferred in 9134.94 ms @ 126.985 KB/s
Info : [esp32s3.cpu0] Target halted, PC=0x403B244A, debug_reason=00000001
Info : PROF: Wrote 1187840 bytes in 9484.3 ms (data transfer time included)
** Programming Finished in 15279 ms **
** Verify Started **
Info : [esp32s3.cpu0] Target halted, PC=0x403B244A, debug_reason=00000001
Info : PROF: Flash verified in 761.714 ms 
** Verify OK **
** Flashing done for ESP32_Basic_Project_0.bin in 16058 ms **
Flashing /home/ubuntu/Desktop/_ESP32_WORKSPACE_/ESP32_Basic_Project_0.3.6/build/partition_table/partition-table.bin at 0x8000
** program_esp input args <0x8000 verify> **
Info : JTAG tap: esp32s3.cpu0 tap/device found: 0x120034e5 (mfg: 0x272 (Tensilica), part: 0x2003, ver: 0x1)
Info : JTAG tap: esp32s3.cpu1 tap/device found: 0x120034e5 (mfg: 0x272 (Tensilica), part: 0x2003, ver: 0x1)
Info : [esp32s3.cpu0] requesting target halt and executing a soft reset
Info : [esp32s3.cpu0] Debug controller was reset.
Info : [esp32s3.cpu0] Core was reset.
Info : [esp32s3.cpu0] Target halted, PC=0x500000EF, debug_reason=00000000
Info : [esp32s3.cpu0] Reset cause (3) - (Software core reset)
Info : [esp32s3.cpu1] requesting target halt and executing a soft reset
Info : [esp32s3.cpu0] Core was reset.
Info : [esp32s3.cpu0] Target halted, PC=0x40000400, debug_reason=00000000
Info : [esp32s3.cpu1] Debug controller was reset.
Info : [esp32s3.cpu1] Core was reset.
Info : [esp32s3.cpu1] Target halted, PC=0x40000400, debug_reason=00000000
Info : [esp32s3.cpu1] Reset cause (3) - (Software core reset)
Info : [esp32s3.cpu0] Reset cause (3) - (Software core reset)
** Programming Started **
Info : [esp32s3.cpu0] Target halted, PC=0x403B244A, debug_reason=00000001
Info : [esp32s3.cpu0] Target halted, PC=0x403B244A, debug_reason=00000001
Info : PROF: Erased 4096 bytes in 182.289 ms
Info : PROF: Data transferred in 35.228 ms @ 113.546 KB/s
Info : [esp32s3.cpu0] Target halted, PC=0x403B244A, debug_reason=00000001
Info : PROF: Wrote 4096 bytes in 279.14 ms (data transfer time included)
** Programming Finished in 793 ms **
** Verify Started **
Info : [esp32s3.cpu0] Target halted, PC=0x403B244A, debug_reason=00000001
Info : PROF: Flash verified in 117.897 ms 
** Verify OK **
** Flashing done for partition_table/partition-table.bin in 918 ms **
Flashing /home/ubuntu/Desktop/_ESP32_WORKSPACE_/ESP32_Basic_Project_0.3.6/build/ota_data_initial.bin at 0x10000
** program_esp input args <0x10000 verify> **
Info : JTAG tap: esp32s3.cpu0 tap/device found: 0x120034e5 (mfg: 0x272 (Tensilica), part: 0x2003, ver: 0x1)
Info : JTAG tap: esp32s3.cpu1 tap/device found: 0x120034e5 (mfg: 0x272 (Tensilica), part: 0x2003, ver: 0x1)
Info : [esp32s3.cpu0] requesting target halt and executing a soft reset
Info : [esp32s3.cpu0] Debug controller was reset.
Info : [esp32s3.cpu0] Core was reset.
Info : [esp32s3.cpu0] Target halted, PC=0x500000EF, debug_reason=00000000
Info : [esp32s3.cpu0] Reset cause (3) - (Software core reset)
Info : [esp32s3.cpu1] requesting target halt and executing a soft reset
Info : [esp32s3.cpu0] Core was reset.
Info : [esp32s3.cpu0] Target halted, PC=0x40000400, debug_reason=00000000
Info : [esp32s3.cpu1] Debug controller was reset.
Info : [esp32s3.cpu1] Core was reset.
Info : [esp32s3.cpu1] Target halted, PC=0x40000400, debug_reason=00000000
Info : [esp32s3.cpu1] Reset cause (3) - (Software core reset)
Info : [esp32s3.cpu0] Reset cause (3) - (Software core reset)
** Programming Started **
Info : [esp32s3.cpu0] Target halted, PC=0x403B244A, debug_reason=00000001
Info : [esp32s3.cpu0] Target halted, PC=0x403B244A, debug_reason=00000001
Info : PROF: Erased 8192 bytes in 315.792 ms
Info : PROF: Data transferred in 54.467 ms @ 146.878 KB/s
Info : [esp32s3.cpu0] Target halted, PC=0x403B244A, debug_reason=00000001
Info : PROF: Wrote 8192 bytes in 301.384 ms (data transfer time included)
** Programming Finished in 944 ms **
** Verify Started **
Info : [esp32s3.cpu0] Target halted, PC=0x403B244A, debug_reason=00000001
Info : PROF: Flash verified in 118.615 ms 
** Verify OK **
** Flashing done for ota_data_initial.bin in 1070 ms **
** Total programming time 20158 ms **
** Resetting Target **
Info : JTAG tap: esp32s3.cpu0 tap/device found: 0x120034e5 (mfg: 0x272 (Tensilica), part: 0x2003, ver: 0x1)
Info : JTAG tap: esp32s3.cpu1 tap/device found: 0x120034e5 (mfg: 0x272 (Tensilica), part: 0x2003, ver: 0x1)
Info : [esp32s3.cpu0] requesting target halt and executing a soft reset
Info : [esp32s3.cpu0] Debug controller was reset.
Info : [esp32s3.cpu0] Core was reset.
Info : [esp32s3.cpu0] Target halted, PC=0x500000EF, debug_reason=00000000
Info : [esp32s3.cpu0] Reset cause (3) - (Software core reset)
Info : [esp32s3.cpu1] requesting target halt and executing a soft reset
Info : [esp32s3.cpu0] Core was reset.
Info : [esp32s3.cpu1] Debug controller was reset.
Info : [esp32s3.cpu1] Core was reset.
0
Started by GNU MCU Eclipse
Info : Listening on port 6666 for tcl connections
Info : Listening on port 4444 for telnet connections
Info : [esp32s3.cpu0] Target halted, PC=0x40048836, debug_reason=00000000
Info : [esp32s3.cpu0] Reset cause (3) - (Software core reset)
Info : [esp32s3.cpu1] Target halted, PC=0x40043A3B, debug_reason=00000000
Info : [esp32s3.cpu1] Reset cause (3) - (Software core reset)
Info : [esp32s3.cpu1] Debug controller was reset.
Info : [esp32s3.cpu1] Core was reset.
Info : [esp32s3.cpu1] Target halted, PC=0x40041A79, debug_reason=00000000
Info : [esp32s3.cpu1] Reset cause (3) - (Software core reset)
Info : accepting 'gdb' connection on tcp/3333
Info : [esp32s3.cpu0] Target halted, PC=0x42008991, debug_reason=00000000
Info : [esp32s3.cpu1] Target halted, PC=0x40041A79, debug_reason=00000000
Warn : No symbols for FreeRTOS!
Info : [esp32s3.cpu0] Target halted, PC=0x403B244A, debug_reason=00000001
Info : Flash mapping 0: 0x20020 -> 0x3c090020, 496 KB
Info : Flash mapping 1: 0xa0020 -> 0x42000020, 566 KB
Info : Using flash bank 'esp32s3.cpu0.irom' size 568 KB
Info : [esp32s3.cpu0] Target halted, PC=0x403B244A, debug_reason=00000001
Info : Flash mapping 0: 0x20020 -> 0x3c090020, 496 KB
Info : Flash mapping 1: 0xa0020 -> 0x42000020, 566 KB
Info : Using flash bank 'esp32s3.cpu0.drom' size 500 KB
Info : New GDB Connection: 1, Target esp32s3.cpu0, state: halted
Warn : Prefer GDB command "target extended-remote :3333" instead of "target remote :3333"
Info : JTAG tap: esp32s3.cpu0 tap/device found: 0x120034e5 (mfg: 0x272 (Tensilica), part: 0x2003, ver: 0x1)
Info : JTAG tap: esp32s3.cpu1 tap/device found: 0x120034e5 (mfg: 0x272 (Tensilica), part: 0x2003, ver: 0x1)
Info : [esp32s3.cpu0] requesting target halt and executing a soft reset
Info : [esp32s3.cpu0] Debug controller was reset.
Info : [esp32s3.cpu0] Core was reset.
Info : [esp32s3.cpu0] Target halted, PC=0x500000EF, debug_reason=00000000
Info : [esp32s3.cpu0] Reset cause (3) - (Software core reset)
Info : [esp32s3.cpu1] requesting target halt and executing a soft reset
Info : [esp32s3.cpu0] Core was reset.
Info : [esp32s3.cpu0] Target halted, PC=0x40000400, debug_reason=00000000
Info : [esp32s3.cpu1] Debug controller was reset.
Info : [esp32s3.cpu1] Core was reset.
Info : [esp32s3.cpu1] Target halted, PC=0x40000400, debug_reason=00000000
Info : [esp32s3.cpu1] Reset cause (3) - (Software core reset)
Info : [esp32s3.cpu0] Reset cause (3) - (Software core reset)
Info : JTAG tap: esp32s3.cpu0 tap/device found: 0x120034e5 (mfg: 0x272 (Tensilica), part: 0x2003, ver: 0x1)
Info : JTAG tap: esp32s3.cpu1 tap/device found: 0x120034e5 (mfg: 0x272 (Tensilica), part: 0x2003, ver: 0x1)
Info : [esp32s3.cpu0] requesting target halt and executing a soft reset
Info : [esp32s3.cpu0] Debug controller was reset.
Info : [esp32s3.cpu0] Core was reset.
Info : [esp32s3.cpu0] Target halted, PC=0x500000EF, debug_reason=00000000
Info : [esp32s3.cpu0] Reset cause (3) - (Software core reset)
Info : [esp32s3.cpu1] requesting target halt and executing a soft reset
Info : [esp32s3.cpu0] Core was reset.
Info : [esp32s3.cpu0] Target halted, PC=0x40000400, debug_reason=00000000
Info : [esp32s3.cpu1] Debug controller was reset.
Info : [esp32s3.cpu1] Core was reset.
Info : [esp32s3.cpu1] Target halted, PC=0x40000400, debug_reason=00000000
Info : [esp32s3.cpu1] Reset cause (3) - (Software core reset)
Info : [esp32s3.cpu0] Reset cause (3) - (Software core reset)
Info : Detected FreeRTOS version: (10.4.3)
Info : JTAG tap: esp32s3.cpu0 tap/device found: 0x120034e5 (mfg: 0x272 (Tensilica), part: 0x2003, ver: 0x1)
Info : JTAG tap: esp32s3.cpu1 tap/device found: 0x120034e5 (mfg: 0x272 (Tensilica), part: 0x2003, ver: 0x1)
Info : [esp32s3.cpu0] requesting target halt and executing a soft reset
Info : [esp32s3.cpu0] Debug controller was reset.
Info : [esp32s3.cpu0] Core was reset.
Info : [esp32s3.cpu0] Target halted, PC=0x500000EF, debug_reason=00000000
Info : [esp32s3.cpu0] Reset cause (3) - (Software core reset)
Info : [esp32s3.cpu1] requesting target halt and executing a soft reset
Info : [esp32s3.cpu0] Core was reset.
Info : [esp32s3.cpu0] Target halted, PC=0x40000400, debug_reason=00000000
Info : [esp32s3.cpu1] Debug controller was reset.
Info : [esp32s3.cpu1] Core was reset.
Info : [esp32s3.cpu1] Target halted, PC=0x40000400, debug_reason=00000000
Info : [esp32s3.cpu1] Reset cause (3) - (Software core reset)
Info : [esp32s3.cpu0] Reset cause (3) - (Software core reset)
Info : [esp32s3.cpu1] Debug controller was reset.
Info : [esp32s3.cpu1] Core was reset.
Info : [esp32s3.cpu1] Target halted, PC=0x40041A79, debug_reason=00000000
Info : [esp32s3.cpu1] Reset cause (3) - (Software core reset)
Info : [esp32s3.cpu0] Target halted, PC=0x42009D18, debug_reason=00000001
Info : Set GDB target to 'esp32s3.cpu0'
Info : [esp32s3.cpu1] Target halted, PC=0x4037BFEE, debug_reason=00000000
Info : Detected FreeRTOS version: (10.4.3)

Where you can see the difference: Total programming time 20158 ms and here come the shorter application code:


Total sizes:
Used static IRAM:   81478 bytes ( 280762 remain, 22.5% used)
      .text size:   80451 bytes
   .vectors size:    1027 bytes
Used stat D/IRAM:   62236 bytes ( 283620 remain, 18.0% used)
      .data size:   14044 bytes
      .bss  size:   48192 bytes
Used Flash size : 1088647 bytes
      .text     :  580207 bytes
      .rodata   :  508184 bytes
Total image size: 1184169 bytes (.bin may be padded larger)

I guess this should suffice for you to take some actions....

kolipakakondal commented 1 year ago

Thanks @Duolabs for sharing logs.

Here are the build artifacts which would help you to navigate this issue, could you please download and update your current IDE and see if that helps?

This docoument could help in installing this.

Once you've udpated with the above build, set the server timeout (more than 25 sec) in the preference page and start with the debugging.

Duolabs commented 1 year ago

I will do it right away!

Duolabs commented 1 year ago

@kolipakakondal unfortunately the change did not make any effect. Still 25 seconds. Question: does it make any difference if we run Eclipse in Ubuntu in SUDO compared to normal user? FYI we are running it SUDO mode.

Same problem here come the log:

!ENTRY com.espressif.idf.core 1 0 2023-05-26 16:38:11.882
!MESSAGE Open On-Chip Debugger v0.12.0-esp32-20230313 (2023-03-13-09:07)
Licensed under GNU GPL v2
For bug reports, read
    http://openocd.org/doc/doxygen/bugs.html

!ENTRY org.eclipse.embedcdt.debug.gdbjtag.core 4 1 2023-05-26 16:38:11.884
!MESSAGE /home/ubuntu/esp/openocd-esp32/bin/openocd -c gdb_port 3333 -c telnet_port 4444 -c tcl_port 6666 -s /home/ubuntu/esp/openocd-esp32/share/openocd/scripts -f board/esp32s3-builtin.cfg -c program_esp_bins /home/ubuntu/Desktop/_ESP32_WORKSPACE_/ESP32_Basic_Project_0.3.8/build flasher_args.json verify reset -c echo "Started by GNU MCU Eclipse"

!ENTRY org.eclipse.cdt.dsf 4 10005 2023-05-26 16:38:37.880
!MESSAGE Request for monitor: 'RequestMonitor (org.eclipse.cdt.dsf.concurrent.RequestMonitorWithProgress@1b44275): Status ERROR: org.eclipse.embedcdt.debug.gdbjtag.core code=5010 Starting OpenOCD timed out.' resulted in an error.
!SUBENTRY 1 org.eclipse.embedcdt.debug.gdbjtag.core 4 5010 2023-05-26 16:38:37.880
!MESSAGE Starting OpenOCD timed out.

!ENTRY org.eclipse.cdt.dsf.gdb 4 5012 2023-05-26 16:38:37.882
!MESSAGE Error in services launch sequence
!STACK 1
org.eclipse.core.runtime.CoreException: Starting OpenOCD timed out.
    at org.eclipse.cdt.dsf.concurrent.Sequence.abortExecution(Sequence.java:599)
    at org.eclipse.cdt.dsf.concurrent.Sequence$2.handleErrorOrWarning(Sequence.java:448)
    at org.eclipse.cdt.dsf.concurrent.RequestMonitor.handleFailure(RequestMonitor.java:433)
    at org.eclipse.cdt.dsf.concurrent.RequestMonitor.handleCompleted(RequestMonitor.java:393)
    at org.eclipse.cdt.dsf.concurrent.RequestMonitor$2.run(RequestMonitor.java:317)
    at java.base/java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:539)
    at java.base/java.util.concurrent.FutureTask.run(FutureTask.java:264)
    at java.base/java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:304)
    at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1136)
    at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:635)
    at java.base/java.lang.Thread.run(Thread.java:833)
!SUBENTRY 1 org.eclipse.embedcdt.debug.gdbjtag.core 4 5010 2023-05-26 16:38:37.883
!MESSAGE Starting OpenOCD timed out.
sigmaaa commented 1 year ago

Hi @Duolabs,

If you were to set the timeout value in the Espressif Preference Page to a lower value, such as 5 seconds, would you faster encounter a timeout error? It would be appreciated if you could test this scenario to confirm that the setting does not have any impact in your specific case.

Duolabs commented 1 year ago

@sigmaaa

Make the test yourself and you will notice that it won't trigger in 3 seconds.

That's what I did at first and of course it ALWAYS triggers at 25 seconds no matter what. I made further tests spreading set remotetimeout option here and there and nothing changes. I am a bit doubtful about the user priviledges as I am always running Eclipse in sudo mode but I still have no clue where this is coming from.

Question #1: when I set the GDB Timeout in preferences menu on which file is it stored? Question #2: do you have a 25 seconds value as default value somewhere in your code?

Please help as after 6+ plus of programming 24/7 on this chip this problem starts to become a nightmare!

Yet another extra info about my idf configuration:


Executing /usr/bin/python3 /home/ubuntu/esp/esp-idf-v5.0.1/tools/idf_tools.py list
* xtensa-esp-elf-gdb: GDB for Xtensa
  - 11.2_20220823 (recommended, installed)
* riscv32-esp-elf-gdb: GDB for RISC-V
  - 11.2_20220823 (recommended, installed)
* xtensa-esp32-elf: Toolchain for Xtensa (ESP32) based on GCC
  - esp-2022r1-11.2.0 (recommended, installed)
* xtensa-esp32s2-elf: Toolchain for Xtensa (ESP32-S2) based on GCC
  - esp-2022r1-11.2.0 (recommended, installed)
* xtensa-esp32s3-elf: Toolchain for Xtensa (ESP32-S3) based on GCC
  - esp-2022r1-11.2.0 (recommended, installed)
* xtensa-clang: LLVM for Xtensa (ESP32, ESP32-S2) based on clang (optional)
  - 14.0.0-38679f0333 (recommended, installed)
* riscv32-esp-elf: Toolchain for 32-bit RISC-V based on GCC
  - esp-2022r1-11.2.0 (recommended, installed)
* esp32ulp-elf: Toolchain for ESP32 ULP coprocessor
  - 2.35_20220830 (recommended, installed)
* cmake: CMake build system (optional)
  - 3.24.0 (recommended, installed)
  - 3.16.3 (supported)
* openocd-esp32: OpenOCD for ESP32
  - v0.11.0-esp32-20221026 (recommended, installed)
* ninja: Ninja build system (optional)
  - 1.10.2 (recommended, installed)
* esp-rom-elfs: ESP ROM ELFs
  - 20220823 (recommended, installed)

CDT Build environment variables
PATH: /root/.espressif/tools/xtensa-esp-elf-gdb/11.2_20220823/xtensa-esp-elf-gdb/bin:/root/.espressif/tools/riscv32-esp-elf-gdb/11.2_20220823/riscv32-esp-elf-gdb/bin:/root/.espressif/tools/xtensa-esp32-elf/esp-2022r1-11.2.0/xtensa-esp32-elf/bin:/root/.espressif/tools/xtensa-esp32s2-elf/esp-2022r1-11.2.0/xtensa-esp32s2-elf/bin:/root/.espressif/tools/xtensa-esp32s3-elf/esp-2022r1-11.2.0/xtensa-esp32s3-elf/bin:/root/.espressif/tools/xtensa-clang/14.0.0-38679f0333/xtensa-esp32-elf-clang/bin:/root/.espressif/tools/riscv32-esp-elf/esp-2022r1-11.2.0/riscv32-esp-elf/bin:/root/.espressif/tools/esp32ulp-elf/2.35_20220830/esp32ulp-elf/bin:/root/.espressif/tools/openocd-esp32/v0.11.0-esp32-20221026/openocd-esp32/bin:/root/.espressif/tools/ninja/1.10.2/:/root/.espressif/python_env/idf5.0_py3.8_env/bin:/home/ubuntu/esp/esp-idf-v5.0.1/tools:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/snap/bin
XAUTHORITY: /run/user/1000/gdm/Xauthority
OXYGEN_DISABLE_INNER_SHADOWS_HACK: 1
TERM: xterm-256color
LANG: en_US.UTF-8
ESP_IDF_VERSION: 5.0
SUDO_USER: ubuntu
OPENOCD_SCRIPTS: /home/ubuntu/esp/openocd-esp32/share/openocd/scripts
SUDO_GID: 1000
DISPLAY: :0
IDF_DEACTIVATE_FILE_PATH: /tmp/tmpa0pw_28ridf_5445
MAIL: /var/mail/root
COLORTERM: truecolor
LOGNAME: root
SUDO_UID: 1000
SHELL: /bin/bash
LIBOVERLAY_SCROLLBAR: 0
IDF_PATH: /home/ubuntu/esp/esp-idf-v5.0.1
GDK_CORE_DEVICE_EVENTS: 1
USER: root
SUDO_COMMAND: /home/ubuntu/eclipse/cpp-2022-09/eclipse/eclipse
IDF_COMPONENT_MANAGER: 1
IDF_MAINTAINER: 1
LS_COLORS: rs=0:di=01;34:ln=01;36:mh=00:pi=40;33:so=01;35:do=01;35:bd=40;33;01:cd=40;33;01:or=40;31;01:mi=00:su=37;41:sg=30;43:ca=30;41:tw=30;42:ow=34;42:st=37;44:ex=01;32:*.tar=01;31:*.tgz=01;31:*.arc=01;31:*.arj=01;31:*.taz=01;31:*.lha=01;31:*.lz4=01;31:*.lzh=01;31:*.lzma=01;31:*.tlz=01;31:*.txz=01;31:*.tzo=01;31:*.t7z=01;31:*.zip=01;31:*.z=01;31:*.dz=01;31:*.gz=01;31:*.lrz=01;31:*.lz=01;31:*.lzo=01;31:*.xz=01;31:*.zst=01;31:*.tzst=01;31:*.bz2=01;31:*.bz=01;31:*.tbz=01;31:*.tbz2=01;31:*.tz=01;31:*.deb=01;31:*.rpm=01;31:*.jar=01;31:*.war=01;31:*.ear=01;31:*.sar=01;31:*.rar=01;31:*.alz=01;31:*.ace=01;31:*.zoo=01;31:*.cpio=01;31:*.7z=01;31:*.rz=01;31:*.cab=01;31:*.wim=01;31:*.swm=01;31:*.dwm=01;31:*.esd=01;31:*.jpg=01;35:*.jpeg=01;35:*.mjpg=01;35:*.mjpeg=01;35:*.gif=01;35:*.bmp=01;35:*.pbm=01;35:*.pgm=01;35:*.ppm=01;35:*.tga=01;35:*.xbm=01;35:*.xpm=01;35:*.tif=01;35:*.tiff=01;35:*.png=01;35:*.svg=01;35:*.svgz=01;35:*.mng=01;35:*.pcx=01;35:*.mov=01;35:*.mpg=01;35:*.mpeg=01;35:*.m2v=01;35:*.mkv=01;35:*.webm=01;35:*.ogm=01;35:*.mp4=01;35:*.m4v=01;35:*.mp4v=01;35:*.vob=01;35:*.qt=01;35:*.nuv=01;35:*.wmv=01;35:*.asf=01;35:*.rm=01;35:*.rmvb=01;35:*.flc=01;35:*.avi=01;35:*.fli=01;35:*.flv=01;35:*.gl=01;35:*.dl=01;35:*.xcf=01;35:*.xwd=01;35:*.yuv=01;35:*.cgm=01;35:*.emf=01;35:*.ogv=01;35:*.ogx=01;35:*.aac=00;36:*.au=00;36:*.flac=00;36:*.m4a=00;36:*.mid=00;36:*.midi=00;36:*.mka=00;36:*.mp3=00;36:*.mpc=00;36:*.ogg=00;36:*.ra=00;36:*.wav=00;36:*.oga=00;36:*.opus=00;36:*.spx=00;36:*.xspf=00;36
IDF_PYTHON_ENV_PATH: /root/.espressif/python_env/idf5.0_py3.8_env
HOME: /root
ESP_ROM_ELF_DIR: /root/.espressif/tools/esp-rom-elfs/20220823/

Operating System: linux
Java Runtime Version: 17.0.5+8
Eclipse Version: 4.25.0.v20220831-1800
Eclipse CDT Version: 10.7.1.202208160035
IDF Eclipse Plugin Version: 2.7.0.202211101524
ESP-IDF v5.0.1-dirty
Python set for IDF_PYTHON_ENV: Python 3.8.10
Duolabs commented 1 year ago

Addition: I exported Eclipse preferences to a file and the value is written correctly: /instance/com.espressif.idf.core/fGdbServerLaunchTimeout=60

Suggestion: can you please tell me where to set the default value (25) that come us when you "Restore default values". I am not much familiar with Eclipse plugs-ins and I don't know if it is hardcoded in the .jar file or on an external file. Just in case it is hardcoded can you provide me with a default value of 60 seconds just to test?

sigmaaa commented 1 year ago

That's what I did at first and of course it ALWAYS triggers at 25 seconds no matter what.

I mean to try to set it to a lower value with the installed new artifact that @kolipakakondal has provided.

Addition: I exported Eclipse preferences to a file and the value is written correctly: /instance/com.espressif.idf.core/fGdbServerLaunchTimeout=60

in the artifact, that @kolipakakondal sent, fGdbServerLaunchTimeout should be related to com.espressif.idf.ui (instead of com.espressif.idf.com). Do you have this /instance/com.espressif.idf.ui/fGdbServerLaunchTimeout = * in exported preferences?

in product log I noticed, that IDF Eclipse Plugin Version: 2.7.0.202211101524, but with provided artifact it should be IDF Eclipse Plugin Version: 2.10.0.202305261108. Did you already revert a patch?

Just in case it is hardcoded can you provide me with a default value of 60 seconds just to test?

The default value is pre-set and cannot be easily changed. However, we can provide you a customized build with a modified default value for you to test. Meanwhile, I was conducting some tests using the artifact available at https://github.com/espressif/idf-eclipse-plugin/suites/13176286359/artifacts/717024151 (It's slightly different from the one shared by @kolipakakondal). Based on my findings, when I set the server gdb timeout too low using this artifact, I encountered a timeout exception (exactly the same that you have). However, I was able to resolve this issue by adjusting the setting to its normal value.

also, I can confirm that this problem (bug) exists on the master branch. @Duolabs can you check with this artifact, please: https://github.com/espressif/idf-eclipse-plugin/suites/13176286359/artifacts/717024151 also, if you want, the same artifact but as Espressif-IDE for linux: https://github.com/espressif/idf-eclipse-plugin/suites/13176286359/artifacts/717024152

If it doesn't work I will send you a build with a modified default value to test. Thank you

Duolabs commented 1 year ago

OK... there we go! It worked.

It was my bad at the beginning as IDF Eclipse Plugin Version: 2.10.0.202305261108 was not installed properly and it was still running on my old 2.7.0.202211101524.

I deeply tested it also with a large application of 3.2MB and it works like a charm. The whole flashing cycle takes around 45secs and I was never been able to debug it in one shot.

I can also FLASH_IN_PROJECT the SPIFFS with spiffs_create_partition_image which was unimmaginable since the early days of that project.

I would like to deeply thank you guys for the support and thought I’d give you my two pence worth on this tweak.

Although the problem has been solved my vivid recommendation is to keep an eye on such troublesome issues as it seems like the tools versions as misaligned with the whole toolchain. To give you a better idea we moved from version 4.x.x of IDF to never 5.0.1 and I bet the plugin got stuck to version 2.7.0 which was causing the problem. We'd never been able to get this to work without your support and whatever happened during the update (to 5.0.1) I guess it did not affect the plugin. The bottom line is that I am so freaking happy to be finally able to debug without facing hurdes now! Thank you and long life to ESP32!