search

rgrr / yapicoprobe

Yet Another Picoprobe
120 stars 12 forks source link

pyocd i.MX RT1010 connecting with (ya)picoprobe: Unexpected ACK value (5) #48

Closed IvoBCD closed 4 months ago

IvoBCD commented 1 year ago

When using (ya)picoprobe to program an NXP i.MX RT1010EVK, I get an "Unexpected ACK value (5)" with yapicoprobe v1.14.

Instead using LPC-Link2 (with DAPLink FW), JLink Pro (Segger FW) as SWD probes for pyocd (v0.35.0) works fine.

# pyocd flash -vv  -t mimxrt1010 blinky-mimxrt1010_evk-3.3.0.elf 
0004387 D Project directory: /home/root/nxp/mimxrt1010 [session]
0007796 D Project directory: /home/root/nxp/mimxrt1010 [session]
0007798 D Loading user script: /home/root/nxp/mimxrt1010/pyocd_user.py [session]
0007827 D CMSIS-DAP v2 probe E6609CB2D36A7B29: protocol version 2.0.0 [dap_access_cmsis_dap]
0007856 I Target type is mimxrt1010 [board]
<MemoryMap@0x7f90ae6f50 regions=[<RamRegion@0x7f91023590 name=itcm type=MemoryType.RAM start=0x0 end=0x7fff length=0x8000 access=rwx>, <RomRegion@0x7f8f186d90 name=romcp type=MemoryType.ROM start=0x200000 end=0x217fff length=0x18000 access=rx>, <RamRegion@0x7f8f196710 name=dtcm type=MemoryType.RAM start=0x20000000 end=0x20007fff length=0x8000 access=rwx>, <RamRegion@0x7f8f196750 name=ocram type=MemoryType.RAM start=0x20200000 end=0x2020ffff length=0x10000 access=rwx>, <FlashRegion@0x7f8f197250 name=flexspi type=MemoryType.FLASH start=0x60000000 end=0x60ffffff length=0x1000000 access=rx blocksize=0x1000>]>
0007884 D Running task load_svd [sequencer]
0007887 D Running task pre_connect [sequencer]
0007887 D Running task dp_init [sequencer]
0007888 D Running task lock_probe [sequencer]
0007889 D Running task get_probe_capabilities [sequencer]
0007889 D Running task connect [sequencer]
0007928 D Default wire protocol selected; using SWD [dap]
0007930 D Sending deprecated SWJ sequence to select SWD [swj]
0007962 D DP IDCODE read failed; resending SWJ sequence (use dormant=False) [dap]
0007981 D Sending deprecated SWJ sequence to select SWD [swj]
0008013 D DP IDCODE read failed; resending SWJ sequence (use dormant=False) [dap]
0008032 D Sending SWJ sequence to select SWD; using dormant state [swj]
0008082 D DP IDCODE read failed; resending SWJ sequence (use dormant=False) [dap]
0008084 D Sending SWJ sequence to select SWD; using dormant state [swj]
0008145 D DP IDCODE read failed; resending SWJ sequence (use dormant=False) [dap]
0008146 D uninit session <pyocd.core.session.Session object at 0x7f8f187310> [session]
0008162 C Unexpected ACK value (5) returned by probe [__main__]
Traceback (most recent call last):
  File "/usr/lib/python3.11/site-packages/pyocd/probe/cmsis_dap_probe.py", line 541, in read_dp_result_callback
    value = result()
            ^^^^^^^^
  File "/usr/lib/python3.11/site-packages/pyocd/probe/pydapaccess/dap_access_cmsis_dap.py", line 1071, in read_reg_cb
    res = transfer.get_result()
          ^^^^^^^^^^^^^^^^^^^^^
  File "/usr/lib/python3.11/site-packages/pyocd/probe/pydapaccess/dap_access_cmsis_dap.py", line 157, in get_result
    self.daplink.flush()
  File "/usr/lib/python3.11/site-packages/pyocd/utility/concurrency.py", line 29, in _locking
    return func(self, *args, **kwargs)
           ^^^^^^^^^^^^^^^^^^^^^^^^^^^
  File "/usr/lib/python3.11/site-packages/pyocd/probe/pydapaccess/dap_access_cmsis_dap.py", line 881, in flush
    self._read_packet()
  File "/usr/lib/python3.11/site-packages/pyocd/utility/concurrency.py", line 29, in _locking
    return func(self, *args, **kwargs)
           ^^^^^^^^^^^^^^^^^^^^^^^^^^^
  File "/usr/lib/python3.11/site-packages/pyocd/probe/pydapaccess/dap_access_cmsis_dap.py", line 1157, in _read_packet
    decoded_data = cmd.decode_data(raw_data)
                   ^^^^^^^^^^^^^^^^^^^^^^^^^
  File "/usr/lib/python3.11/site-packages/pyocd/probe/pydapaccess/dap_access_cmsis_dap.py", line 478, in decode_data
    data = self._decode_transfer_block_data(data)
           ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
  File "/usr/lib/python3.11/site-packages/pyocd/probe/pydapaccess/dap_access_cmsis_dap.py", line 447, in _decode_transfer_block_data
    self._check_response(data[3])
  File "/usr/lib/python3.11/site-packages/pyocd/probe/pydapaccess/dap_access_cmsis_dap.py", line 370, in _check_response
    raise DAPAccessIntf.TransferError("Unexpected ACK value (%d) returned by probe" % ack)
pyocd.probe.pydapaccess.dap_access_api.DAPAccessIntf.TransferError: Unexpected ACK value (5) returned by probe

The above exception was the direct cause of the following exception:

Traceback (most recent call last):
  File "/usr/lib/python3.11/site-packages/pyocd/__main__.py", line 161, in run
    status = cmd.invoke()
             ^^^^^^^^^^^^
  File "/usr/lib/python3.11/site-packages/pyocd/subcommands/load_cmd.py", line 100, in invoke
    with session:
  File "/usr/lib/python3.11/site-packages/pyocd/core/session.py", line 402, in __enter__
    self.open()
  File "/usr/lib/python3.11/site-packages/pyocd/core/session.py", line 540, in open
    self._board.init()
  File "/usr/lib/python3.11/site-packages/pyocd/board/board.py", line 142, in init
    self.target.init()
  File "/usr/lib/python3.11/site-packages/pyocd/coresight/coresight_target.py", line 123, in init
    super().init()
  File "/usr/lib/python3.11/site-packages/pyocd/core/soc_target.py", line 172, in init
    seq.invoke()
  File "/usr/lib/python3.11/site-packages/pyocd/utility/sequencer.py", line 213, in invoke
    resultSequence.invoke()
  File "/usr/lib/python3.11/site-packages/pyocd/utility/sequencer.py", line 208, in invoke
    resultSequence = call()
                     ^^^^^^
  File "/usr/lib/python3.11/site-packages/pyocd/coresight/dap.py", line 466, in _connect
    connector.connect()
  File "/usr/lib/python3.11/site-packages/pyocd/coresight/dap.py", line 256, in connect
    self._idr = self.read_idr()
                ^^^^^^^^^^^^^^^
  File "/usr/lib/python3.11/site-packages/pyocd/coresight/dap.py", line 285, in read_idr
    dpidr = self._dp.read_dp(DP_IDR, now=True)
            ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
  File "/usr/lib/python3.11/site-packages/pyocd/coresight/dap.py", line 822, in read_dp
    return read_dp_cb()
           ^^^^^^^^^^^^
  File "/usr/lib/python3.11/site-packages/pyocd/coresight/dap.py", line 810, in read_dp_cb
    result = result_cb()
             ^^^^^^^^^^^
  File "/usr/lib/python3.11/site-packages/pyocd/probe/cmsis_dap_probe.py", line 546, in read_dp_result_callback
    raise self._convert_exception(error) from error
pyocd.core.exceptions.TransferError: Unexpected ACK value (5) returned by probe
rgrr commented 1 year ago

Hello Ivo, why do you think, that this is a problem of the probe? Does your hardware work with another probe and with pyocd? Which version of pyocd do you use?

IvoBCD commented 1 year ago 
# pyocd -V
0.35.0

@rgrr The very latest pyocd v0.35.0, my hardware does indeed work with other probes:

# pyocd list
  #   Probe/Board                                     Unique ID   Target  
--------------------------------------------------------------------------
  0   NXP Semiconductors LPC-LINK2 CMSIS-DAP V5.361   NRAQBQHR    n/a     
# # pyocd flash -v -t mimxrt1010 blinky-mimxrt1010_evk-3.3.0.elf 
0007895 I Target type is mimxrt1010 [board]
0008091 I DP IDR = 0x0bd11477 (v1 MINDP rev0) [dap]
0008263 I AHB-AP#0 IDR = 0x04770041 (AHB-AP var4 rev0) [discovery]
0008330 I AHB-AP#0 Class 0x1 ROM table #0 @ 0xe00fd000 (designer=00e:NXP part=88c) [rom_table]
0008366 I [0]<e00fe000:ROM class=1 designer=43b:Arm part=4c8> [rom_table]
0008367 I   AHB-AP#0 Class 0x1 ROM table #1 @ 0xe00fe000 (designer=43b:Arm part=4c8) [rom_table]
0008398 I   [0]<e00ff000:ROM class=1 designer=43b:Arm part=4c7> [rom_table]
0008399 I     AHB-AP#0 Class 0x1 ROM table #2 @ 0xe00ff000 (designer=43b:Arm part=4c7) [rom_table]
0008434 I     [0]<e000e000:SCS v7-M class=14 designer=43b:Arm part=00c> [rom_table]
0008449 I     [1]<e0001000:DWT v7-M class=14 designer=43b:Arm part=002> [rom_table]
0008603 I     [2]<e0002000:FPB v7-M class=14 designer=43b:Arm part=00e> [rom_table]
0008617 I     [3]<e0000000:ITM v7-M class=14 designer=43b:Arm part=001> [rom_table]
0008645 I   [1]<e0041000:ETM M7 class=9 designer=43b:Arm part=975 devtype=13 archid=4a13 devid=0:0:0> [rom_table]
0008678 I   [2]<e0042000:CTI CS-400 class=9 designer=43b:Arm part=906 devtype=14 archid=0000 devid=40800:0:0> [rom_table]
0008710 I [1]<e0040000:TPIU M7 class=9 designer=43b:Arm part=9a9 devtype=11 archid=0000 devid=ca1:0:0> [rom_table]
0008726 I [2]<e0043000:TSGEN class=15 designer=43b:Arm part=101> [rom_table]
0008745 I IMXRT Boot Mode: Internal Boot [target_imxrt]
0008781 I CPU core #0 is Cortex-M7 r1p2 [cortex_m]
0008816 I FPU present: FPv5-SP-D16-M [cortex_m]
0008875 I 4 hardware watchpoints [dwt]
0008894 I 8 hardware breakpoints, 1 literal comparators [fpb]
0008988 I Loading /home/root/nxp/mimxrt1010/blinky-mimxrt1010_evk-3.3.0.elf [load_cmd]
0009095 I IMXRT Boot Mode: Internal Boot [target_imxrt]
0009111 I IMXRT Boot Device: 0 [target_imxrt]
[==================================================] 100%
0020870 I IMXRT Boot Mode: Internal Boot [target_imxrt]
0020875 I IMXRT Boot Device: 0 [target_imxrt]
0021016 I Erased 20480 bytes (5 sectors), programmed 18688 bytes (73 pages), skipped 4096 bytes (16 pages) at 1.86 kB/s [loader]
#

and also with an old Segger JLink probe:

# pyocd list
  #   Probe/Board          Unique ID   Target  
-----------------------------------------------
  0   Segger J-Link PLUS   609300812   n/a     
root@raspberrypi4-64:~/nxp/mimxrt1010# pyocd flash -v -t mimxrt1010 blinky-mimxrt1010_evk-3.3.0.elf 
0010792 I Target type is mimxrt1010 [board]
<MemoryMap@0x7f9b917890 regions=[<RamRegion@0x7f9b917350 name=itcm type=MemoryType.RAM start=0x0 end=0x7fff length=0x8000 access=rwx>, <RomRegion@0x7f9b9170d0 name=romcp type=MemoryType.ROM start=0x200000 end=0x217fff length=0x18000 access=rx>, <RamRegion@0x7f9b917610 name=dtcm type=MemoryType.RAM start=0x20000000 end=0x20007fff length=0x8000 access=rwx>, <RamRegion@0x7f9b9176d0 name=ocram type=MemoryType.RAM start=0x20200000 end=0x2020ffff length=0x10000 access=rwx>, <FlashRegion@0x7f9b9177d0 name=flexspi type=MemoryType.FLASH start=0x60000000 end=0x60ffffff length=0x1000000 access=rx blocksize=0x1000>]>
0012411 I DP IDR = 0x0bd11477 (v1 MINDP rev0) [dap]
0012927 I AHB-AP#0 IDR = 0x04770041 (AHB-AP var4 rev0) [discovery]
0013214 I AHB-AP#0 Class 0x1 ROM table #0 @ 0xe00fd000 (designer=00e:NXP part=88c) [rom_table]
0013909 I [0]<e00fe000:ROM class=1 designer=43b:Arm part=4c8> [rom_table]
0013916 I   AHB-AP#0 Class 0x1 ROM table #1 @ 0xe00fe000 (designer=43b:Arm part=4c8) [rom_table]
0015037 I   [0]<e00ff000:ROM class=1 designer=43b:Arm part=4c7> [rom_table]
0015043 I     AHB-AP#0 Class 0x1 ROM table #2 @ 0xe00ff000 (designer=43b:Arm part=4c7) [rom_table]
0016163 I     [0]<e000e000:SCS v7-M class=14 designer=43b:Arm part=00c> [rom_table]
0016173 I     [1]<e0001000:DWT v7-M class=14 designer=43b:Arm part=002> [rom_table]
0016182 I     [2]<e0002000:FPB v7-M class=14 designer=43b:Arm part=00e> [rom_table]
0016192 I     [3]<e0000000:ITM v7-M class=14 designer=43b:Arm part=001> [rom_table]
0016205 I   [1]<e0041000:ETM M7 class=9 designer=43b:Arm part=975 devtype=13 archid=4a13 devid=0:0:0> [rom_table]
0016219 I   [2]<e0042000:CTI CS-400 class=9 designer=43b:Arm part=906 devtype=14 archid=0000 devid=40800:0:0> [rom_table]
0016232 I [1]<e0040000:TPIU M7 class=9 designer=43b:Arm part=9a9 devtype=11 archid=0000 devid=ca1:0:0> [rom_table]
0016241 I [2]<e0043000:TSGEN class=15 designer=43b:Arm part=101> [rom_table]
0016247 I IMXRT Boot Mode: Internal Boot [target_imxrt]
0016252 I CPU core #0 is Cortex-M7 r1p2 [cortex_m]
0016256 I FPU present: FPv5-SP-D16-M [cortex_m]
0016267 I 4 hardware watchpoints [dwt]
0016276 I 8 hardware breakpoints, 1 literal comparators [fpb]
0016303 I Loading /home/root/nxp/mimxrt1010/blinky-mimxrt1010_evk-3.3.0.elf [load_cmd]
0016335 I IMXRT Boot Mode: Internal Boot [target_imxrt]
0016337 I IMXRT Boot Device: 0 [target_imxrt]
[==================================================] 100%
0026757 I IMXRT Boot Mode: Internal Boot [target_imxrt]
0026759 I IMXRT Boot Device: 0 [target_imxrt]
0026882 I Erased 24576 bytes (6 sectors), programmed 22784 bytes (89 pages), skipped 0 bytes (0 pages) at 2.11 kB/s [loader]
#
IvoBCD commented 1 year ago

I suspect the problem is https://github.com/rgrr/yapicoprobe/issues/39

rgrr commented 1 year ago

That's a good indication, that there is something with the probe ;-)

Could you try to set the used frequency via -f ? (but do not use 1MHz because this setup is ignored silently)

Which version of yapicoprobe do you use?

IvoBCD commented 1 year ago

I saw this with yapicoprobe-0114-pico-0bf7eee.uf2 and picoprobe_cmsis v1.0.2 Pico firmware. Having issues reproducing with yapicoprobe FW as I mostly run into RPi4 USB host controller issue:

[145641.902293] usb 1-1.1: new full-speed USB device number 5 using xhci_hcd
[145642.019939] usb 1-1.1: New USB device found, idVendor=2e8a, idProduct=000c, bcdDevice= 1.14
[145642.028560] usb 1-1.1: New USB device strings: Mfr=1, Product=2, SerialNumber=3
[145642.037662] usb 1-1.1: Product: YAPicoprobe CMSIS-DAP
[145642.042944] usb 1-1.1: Manufacturer: RaspberryPi
[145642.047755] usb 1-1.1: SerialNumber: E6609CB2D36A7B29
[145642.054107] usb 1-1.1: Not enough bandwidth for new device state.
[145642.060622] usb 1-1.1: can't set config #1, error -28
rgrr commented 1 year ago

Hmmm.... now I'm a little bit confused...

The above seems to be a different issue!?

IvoBCD commented 1 year ago

Sorry about the confusion - replacing a USB cable fixed this. So, trying again at 4 kHz:

+ pyocd list
  #   Probe/Board                         Unique ID          Target  
---------------------------------------------------------------------
  0   RaspberryPi YAPicoprobe CMSIS-DAP   E6609CB2D36A7B29   n/a     
+ pyocd flash -v -t mimxrt1010 -f 4khz blinky-mimxrt1010_evk-3.3.0.elf
0007996 I Target type is mimxrt1010 [board]
<MemoryMap@0x7f9d293590 regions=[<RamRegion@0x7f9d292350 name=itcm type=MemoryType.RAM start=0x0 end=0x7fff length=0x8000 access=rwx>, <RomRegion@0x7f9d292bd0 name=romcp type=MemoryType.ROM start=0x200000 end=0x217fff length=0x18000 access=rx>, <RamRegion@0x7f9d2935d0 name=dtcm type=MemoryType.RAM start=0x20000000 end=0x20007fff length=0x8000 access=rwx>, <RamRegion@0x7f9d293690 name=ocram type=MemoryType.RAM start=0x20200000 end=0x2020ffff length=0x10000 access=rwx>, <FlashRegion@0x7f9d293410 name=flexspi type=MemoryType.FLASH start=0x60000000 end=0x60ffffff length=0x1000000 access=rx blocksize=0x1000>]>
0008731 C Unexpected ACK value (5) returned by probe [__main__]

And at 1kHz, with debug logging:


+ pyocd list
  #   Probe/Board                         Unique ID          Target  
---------------------------------------------------------------------
  0   RaspberryPi YAPicoprobe CMSIS-DAP   E6609CB2D36A7B29   n/a     
+ pyocd flash -v -L '*=debug' -t mimxrt1010 -f 1khz blinky-mimxrt1010_evk-3.3.0.elf
0004413 D Project directory: /home/root/nxp/mimxrt1010 [session]
0004414 D [cmd:0] New _Command [dap_access_cmsis_dap]
0007849 D There is no PEMICRO library. [pemicro]
0007851 D Not sending notification because no subscribers: event=target_override [notification]
0007851 D Not sending notification because no subscribers: event=frequency [notification]
0007852 D Project directory: /home/root/nxp/mimxrt1010 [session]
0007853 D Not sending notification because no subscribers: event=debug.traceback [notification]
0007855 D Loading user script: /home/root/nxp/mimxrt1010/pyocd_user.py [session]
0007858 D Not sending notification because no subscribers: event=debug.log_flm_info [notification]
0007858 D Not sending notification because no subscribers: event=allow_no_cores [notification]
0007883 D   USB OUT> (2) 00 fe [pyusb_v2_backend]
0007886 D   USB IN < (3) 00 01 01 [pyusb_v2_backend]
0007887 D   USB RD < (3) 00 01 01 [pyusb_v2_backend]
0007887 D   USB OUT> (2) 00 04 [pyusb_v2_backend]
0007888 D   USB IN < (8) 00 06 32 2e 30 2e 30 00 [pyusb_v2_backend]
0007889 D   USB RD < (8) 00 06 32 2e 30 2e 30 00 [pyusb_v2_backend]
0007890 D CMSIS-DAP v2 probe E6609CB2D36A7B29: protocol version 2.0.0 [dap_access_cmsis_dap]
0007890 D   USB OUT> (2) 00 ff [pyusb_v2_backend]
0007891 D   USB IN < (4) 00 02 00 04 [pyusb_v2_backend]
0007892 D   USB RD < (4) 00 02 00 04 [pyusb_v2_backend]
0007892 D   USB OUT> (2) 00 f0 [pyusb_v2_backend]
0007894 D   USB IN < (3) 00 01 31 [pyusb_v2_backend]
0007894 D   USB RD < (3) 00 01 31 [pyusb_v2_backend]
0007895 D [cmd:1] New _Command [dap_access_cmsis_dap]
0007896 D [cmd:2] New _Command [dap_access_cmsis_dap]
0007921 I Target type is mimxrt1010 [board]
0007922 D trace: open [cmsis_dap_probe]
0007945 D [cmd:3] New _Command [dap_access_cmsis_dap]
0007946 D trace: set_clock(freq=1000) [cmsis_dap_probe]
0007947 D   USB OUT> (5) 11 e8 03 00 00 [pyusb_v2_backend]
0007949 D   USB IN < (2) 11 00 [pyusb_v2_backend]
0007950 D   USB RD < (2) 11 00 [pyusb_v2_backend]
<MemoryMap@0x7f9b394b90 regions=[<RamRegion@0x7f9cc3bd50 name=itcm type=MemoryType.RAM start=0x0 end=0x7fff length=0x8000 access=rwx>, <RomRegion@0x7f9b38a850 name=romcp type=MemoryType.ROM start=0x200000 end=0x217fff length=0x18000 access=rx>, <RamRegion@0x7f9b38ba10 name=dtcm type=MemoryType.RAM start=0x20000000 end=0x20007fff length=0x8000 access=rwx>, <RamRegion@0x7f9b394950 name=ocram type=MemoryType.RAM start=0x20200000 end=0x2020ffff length=0x10000 access=rwx>, <FlashRegion@0x7f9b394a50 name=flexspi type=MemoryType.FLASH start=0x60000000 end=0x60ffffff length=0x1000000 access=rx blocksize=0x1000>]>
0007953 D Running task load_svd [sequencer]
0007955 D Running task pre_connect [sequencer]
0007956 D Running task dp_init [sequencer]
0007956 D Running task lock_probe [sequencer]
0007957 D Running task get_probe_capabilities [sequencer]
0007958 D Running task connect [sequencer]
0007970 D trace: connect(DEFAULT) [cmsis_dap_probe]
0007971 D   USB OUT> (2) 02 01 [pyusb_v2_backend]
0007974 D   USB IN < (2) 02 01 [pyusb_v2_backend]
0007995 D   USB RD < (2) 02 01 [pyusb_v2_backend]
0007996 D   USB OUT> (5) 11 e8 03 00 00 [pyusb_v2_backend]
0007997 D   USB IN < (2) 11 00 [pyusb_v2_backend]
0007998 D   USB RD < (2) 11 00 [pyusb_v2_backend]
0008011 D   USB OUT> (6) 04 02 50 00 00 00 [pyusb_v2_backend]
0008013 D   USB IN < (2) 04 00 [pyusb_v2_backend]
0008014 D   USB RD < (2) 04 00 [pyusb_v2_backend]
0008027 D   USB OUT> (2) 13 00 [pyusb_v2_backend]
0008029 D   USB IN < (2) 13 00 [pyusb_v2_backend]
0008030 D   USB RD < (2) 13 00 [pyusb_v2_backend]
0008030 D Default wire protocol selected; using SWD [dap]
0008031 D trace: swj_sequence(length=51, bits=ffffffffffffff) [cmsis_dap_probe]
0008032 D   USB OUT> (9) 12 33 ff ff ff ff ff ff ff [pyusb_v2_backend]
0008051 D   USB IN < (2) 12 00 [pyusb_v2_backend]
0008052 D   USB RD < (2) 12 00 [pyusb_v2_backend]
0008053 D Sending deprecated SWJ sequence to select SWD [swj]
0008053 D trace: swj_sequence(length=16, bits=e79e) [cmsis_dap_probe]
0008054 D   USB OUT> (4) 12 10 9e e7 [pyusb_v2_backend]
0008068 D   USB IN < (2) 12 00 [pyusb_v2_backend]
0008069 D   USB RD < (2) 12 00 [pyusb_v2_backend]
0008069 D trace: swj_sequence(length=51, bits=ffffffffffffff) [cmsis_dap_probe]
0008082 D   USB OUT> (9) 12 33 ff ff ff ff ff ff ff [pyusb_v2_backend]
0008084 D   USB IN < (2) 12 00 [pyusb_v2_backend]
0008085 D   USB RD < (2) 12 00 [pyusb_v2_backend]
0008085 D trace: swj_sequence(length=8, bits=0) [cmsis_dap_probe]
0008086 D   USB OUT> (3) 12 08 00 [pyusb_v2_backend]
0008105 D   USB IN < (2) 12 00 [pyusb_v2_backend]
0008106 D   USB RD < (2) 12 00 [pyusb_v2_backend]
0008106 D trace: read_dp(addr=0x00000000) -> ... [cmsis_dap_probe]
0008120 D [cmd:3] get_request_space(1, 02:r)[wc=0, rc=0, ba=1->1] -> (sz=1, free=255) [dap_access_cmsis_dap]
0008121 D [cmd:3] add(1, 02:r) -> [wc=0, rc=1, ba=1] [dap_access_cmsis_dap]
0008121 D flush: sending cmd:3; reading 0 outstanding [dap_access_cmsis_dap]
0008122 D [cmd:3] _send_packet: sending [dap_access_cmsis_dap]
0008122 D   USB OUT> (5) 06 00 01 00 02 [pyusb_v2_backend]
0008137 D [cmd:4] New _Command [dap_access_cmsis_dap]
0008137 D   USB IN < (4) 06 00 00 05 [pyusb_v2_backend]
0008138 D [cmd:3] _read_packet: reading [dap_access_cmsis_dap]
0008140 D   USB RD < (4) 06 00 00 05 [pyusb_v2_backend]
0008156 D [cmd:3] _read_packet: got exception TransferError('Unexpected ACK value (5) returned by probe'); aborting all transfers! [dap_access_cmsis_dap]
0008157 D aborting 0 pending reads after exception TransferError('Unexpected ACK value (5) returned by probe') [dap_access_cmsis_dap]
0008158 D [cmd:5] New _Command [dap_access_cmsis_dap]
0008158 D trace: ... read_dp(addr=0x00000000) -> error(Unexpected ACK value (5) returned by probe) [cmsis_dap_probe]
0008159 D read_dp:000001 (addr=0x00000000) -> error (Unexpected ACK value (5) returned by probe) [dap]
0008171 D error:000001 Unexpected ACK value (5) returned by probe [dap]
0008172 D DP IDCODE read failed; resending SWJ sequence (use dormant=False) [dap]
0008173 D trace: swj_sequence(length=51, bits=ffffffffffffff) [cmsis_dap_probe]
0008173 D   USB OUT> (9) 12 33 ff ff ff ff ff ff ff [pyusb_v2_backend]
0008175 D   USB IN < (2) 12 00 [pyusb_v2_backend]
0008188 D   USB RD < (2) 12 00 [pyusb_v2_backend]
0008189 D Sending deprecated SWJ sequence to select SWD [swj]
0008189 D trace: swj_sequence(length=16, bits=e79e) [cmsis_dap_probe]
0008190 D   USB OUT> (4) 12 10 9e e7 [pyusb_v2_backend]
0008207 D   USB IN < (2) 12 00 [pyusb_v2_backend]
0008208 D   USB RD < (2) 12 00 [pyusb_v2_backend]
0008208 D trace: swj_sequence(length=51, bits=ffffffffffffff) [cmsis_dap_probe]
0008221 D   USB OUT> (9) 12 33 ff ff ff ff ff ff ff [pyusb_v2_backend]
0008222 D   USB IN < (2) 12 00 [pyusb_v2_backend]
0008236 D   USB RD < (2) 12 00 [pyusb_v2_backend]
0008237 D trace: swj_sequence(length=8, bits=0) [cmsis_dap_probe]
0008237 D   USB OUT> (3) 12 08 00 [pyusb_v2_backend]
0008255 D   USB IN < (2) 12 00 [pyusb_v2_backend]
0008267 D   USB RD < (2) 12 00 [pyusb_v2_backend]
0008268 D trace: read_dp(addr=0x00000000) -> ... [cmsis_dap_probe]
0008269 D [cmd:5] get_request_space(1, 02:r)[wc=0, rc=0, ba=1->1] -> (sz=1, free=255) [dap_access_cmsis_dap]
0008269 D [cmd:5] add(1, 02:r) -> [wc=0, rc=1, ba=1] [dap_access_cmsis_dap]
0008270 D flush: sending cmd:5; reading 0 outstanding [dap_access_cmsis_dap]
0008270 D [cmd:5] _send_packet: sending [dap_access_cmsis_dap]
0008283 D   USB OUT> (5) 06 00 01 00 02 [pyusb_v2_backend]
0008285 D [cmd:6] New _Command [dap_access_cmsis_dap]
0008286 D   USB IN < (4) 06 00 00 05 [pyusb_v2_backend]
0008286 D [cmd:5] _read_packet: reading [dap_access_cmsis_dap]
0008304 D   USB RD < (4) 06 00 00 05 [pyusb_v2_backend]
0008305 D [cmd:5] _read_packet: got exception TransferError('Unexpected ACK value (5) returned by probe'); aborting all transfers! [dap_access_cmsis_dap]
0008318 D aborting 0 pending reads after exception TransferError('Unexpected ACK value (5) returned by probe') [dap_access_cmsis_dap]
0008318 D [cmd:7] New _Command [dap_access_cmsis_dap]
0008319 D trace: ... read_dp(addr=0x00000000) -> error(Unexpected ACK value (5) returned by probe) [cmsis_dap_probe]
0008319 D read_dp:000002 (addr=0x00000000) -> error (Unexpected ACK value (5) returned by probe) [dap]
0008333 D error:000002 Unexpected ACK value (5) returned by probe [dap]
0008333 D DP IDCODE read failed; resending SWJ sequence (use dormant=False) [dap]
0008334 D trace: swj_sequence(length=51, bits=ffffffffffffff) [cmsis_dap_probe]
0008334 D   USB OUT> (9) 12 33 ff ff ff ff ff ff ff [pyusb_v2_backend]
0008336 D   USB IN < (2) 12 00 [pyusb_v2_backend]
0008353 D   USB RD < (2) 12 00 [pyusb_v2_backend]
0008354 D Sending SWJ sequence to select SWD; using dormant state [swj]
0008354 D trace: swj_sequence(length=39, bits=33bbbbba) [cmsis_dap_probe]
0008355 D   USB OUT> (7) 12 27 ba bb bb 33 00 [pyusb_v2_backend]
0008356 D   USB IN < (2) 12 00 [pyusb_v2_backend]
0008368 D   USB RD < (2) 12 00 [pyusb_v2_backend]
0008369 D trace: swj_sequence(length=136, bits=19bc0ea2e3ddafe986852d956209f392ff) [cmsis_dap_probe]
0008370 D   USB OUT> (19) 12 88 ff 92 f3 09 62 95 2d 85 86 e9 af dd e3 a2 0e bc 19 [pyusb_v2_backend]
0008372 D   USB IN < (2) 12 00 [pyusb_v2_backend]
0008386 D   USB RD < (2) 12 00 [pyusb_v2_backend]
0008387 D trace: swj_sequence(length=12, bits=1a0) [cmsis_dap_probe]
0008387 D   USB OUT> (4) 12 0c a0 01 [pyusb_v2_backend]
0008405 D   USB IN < (2) 12 00 [pyusb_v2_backend]
0008406 D   USB RD < (2) 12 00 [pyusb_v2_backend]
0008407 D trace: swj_sequence(length=51, bits=ffffffffffffff) [cmsis_dap_probe]
0008420 D   USB OUT> (9) 12 33 ff ff ff ff ff ff ff [pyusb_v2_backend]
0008422 D   USB IN < (2) 12 00 [pyusb_v2_backend]
0008423 D   USB RD < (2) 12 00 [pyusb_v2_backend]
0008423 D trace: swj_sequence(length=2, bits=0) [cmsis_dap_probe]
0008424 D   USB OUT> (3) 12 02 00 [pyusb_v2_backend]
0008438 D   USB IN < (2) 12 00 [pyusb_v2_backend]
0008439 D   USB RD < (2) 12 00 [pyusb_v2_backend]
0008591 D trace: read_dp(addr=0x00000000) -> ... [cmsis_dap_probe]
0008592 D [cmd:7] get_request_space(1, 02:r)[wc=0, rc=0, ba=1->1] -> (sz=1, free=255) [dap_access_cmsis_dap]
0008593 D [cmd:7] add(1, 02:r) -> [wc=0, rc=1, ba=1] [dap_access_cmsis_dap]
0008593 D flush: sending cmd:7; reading 0 outstanding [dap_access_cmsis_dap]
0008604 D [cmd:7] _send_packet: sending [dap_access_cmsis_dap]
0008605 D   USB OUT> (5) 06 00 01 00 02 [pyusb_v2_backend]
0008606 D [cmd:8] New _Command [dap_access_cmsis_dap]
0008607 D [cmd:7] _read_packet: reading [dap_access_cmsis_dap]
0008607 D   USB IN < (4) 06 00 00 05 [pyusb_v2_backend]
0008619 D   USB RD < (4) 06 00 00 05 [pyusb_v2_backend]
0008620 D [cmd:7] _read_packet: got exception TransferError('Unexpected ACK value (5) returned by probe'); aborting all transfers! [dap_access_cmsis_dap]
0008631 D aborting 0 pending reads after exception TransferError('Unexpected ACK value (5) returned by probe') [dap_access_cmsis_dap]
0008633 D [cmd:9] New _Command [dap_access_cmsis_dap]
0008635 D trace: ... read_dp(addr=0x00000000) -> error(Unexpected ACK value (5) returned by probe) [cmsis_dap_probe]
0008636 D read_dp:000003 (addr=0x00000000) -> error (Unexpected ACK value (5) returned by probe) [dap]
0008652 D error:000003 Unexpected ACK value (5) returned by probe [dap]
0008652 D DP IDCODE read failed; resending SWJ sequence (use dormant=False) [dap]
0008653 D trace: swj_sequence(length=51, bits=ffffffffffffff) [cmsis_dap_probe]
0008654 D   USB OUT> (9) 12 33 ff ff ff ff ff ff ff [pyusb_v2_backend]
0008656 D   USB IN < (2) 12 00 [pyusb_v2_backend]
0008668 D   USB RD < (2) 12 00 [pyusb_v2_backend]
0008669 D Sending SWJ sequence to select SWD; using dormant state [swj]
0008669 D trace: swj_sequence(length=39, bits=33bbbbba) [cmsis_dap_probe]
0008681 D   USB OUT> (7) 12 27 ba bb bb 33 00 [pyusb_v2_backend]
0008683 D   USB IN < (2) 12 00 [pyusb_v2_backend]
0008699 D   USB RD < (2) 12 00 [pyusb_v2_backend]
0008700 D trace: swj_sequence(length=136, bits=19bc0ea2e3ddafe986852d956209f392ff) [cmsis_dap_probe]
0008700 D   USB OUT> (19) 12 88 ff 92 f3 09 62 95 2d 85 86 e9 af dd e3 a2 0e bc 19 [pyusb_v2_backend]
0008702 D   USB IN < (2) 12 00 [pyusb_v2_backend]
0008703 D   USB RD < (2) 12 00 [pyusb_v2_backend]
0008715 D trace: swj_sequence(length=12, bits=1a0) [cmsis_dap_probe]
0008716 D   USB OUT> (4) 12 0c a0 01 [pyusb_v2_backend]
0008717 D   USB IN < (2) 12 00 [pyusb_v2_backend]
0008718 D   USB RD < (2) 12 00 [pyusb_v2_backend]
0008732 D trace: swj_sequence(length=51, bits=ffffffffffffff) [cmsis_dap_probe]
0008733 D   USB OUT> (9) 12 33 ff ff ff ff ff ff ff [pyusb_v2_backend]
0008736 D   USB IN < (2) 12 00 [pyusb_v2_backend]
0008753 D   USB RD < (2) 12 00 [pyusb_v2_backend]
0008753 D trace: swj_sequence(length=2, bits=0) [cmsis_dap_probe]
0008754 D   USB OUT> (3) 12 02 00 [pyusb_v2_backend]
0008768 D   USB IN < (2) 12 00 [pyusb_v2_backend]
0008769 D   USB RD < (2) 12 00 [pyusb_v2_backend]
0008783 D trace: read_dp(addr=0x00000000) -> ... [cmsis_dap_probe]
0008784 D [cmd:9] get_request_space(1, 02:r)[wc=0, rc=0, ba=1->1] -> (sz=1, free=255) [dap_access_cmsis_dap]
0008785 D [cmd:9] add(1, 02:r) -> [wc=0, rc=1, ba=1] [dap_access_cmsis_dap]
0008785 D flush: sending cmd:9; reading 0 outstanding [dap_access_cmsis_dap]
0008786 D [cmd:9] _send_packet: sending [dap_access_cmsis_dap]
0008803 D   USB OUT> (5) 06 00 01 00 02 [pyusb_v2_backend]
0008805 D [cmd:10] New _Command [dap_access_cmsis_dap]
0008805 D [cmd:9] _read_packet: reading [dap_access_cmsis_dap]
0008806 D   USB IN < (4) 06 00 00 05 [pyusb_v2_backend]
0008820 D   USB RD < (4) 06 00 00 05 [pyusb_v2_backend]
0008821 D [cmd:9] _read_packet: got exception TransferError('Unexpected ACK value (5) returned by probe'); aborting all transfers! [dap_access_cmsis_dap]
0008821 D aborting 0 pending reads after exception TransferError('Unexpected ACK value (5) returned by probe') [dap_access_cmsis_dap]
0008822 D [cmd:11] New _Command [dap_access_cmsis_dap]
0008823 D trace: ... read_dp(addr=0x00000000) -> error(Unexpected ACK value (5) returned by probe) [cmsis_dap_probe]
0008836 D read_dp:000004 (addr=0x00000000) -> error (Unexpected ACK value (5) returned by probe) [dap]
0008837 D error:000004 Unexpected ACK value (5) returned by probe [dap]
0008837 D DP IDCODE read failed; resending SWJ sequence (use dormant=False) [dap]
0008838 D uninit session <pyocd.core.session.Session object at 0x7f9e0aa390> [session]
0008839 D trace: disconnect [cmsis_dap_probe]
0008857 D   USB OUT> (1) 03 [pyusb_v2_backend]
0008859 D   USB IN < (2) 03 00 [pyusb_v2_backend]
0008873 D   USB RD < (2) 03 00 [pyusb_v2_backend]
0008873 D trace: close [cmsis_dap_probe]
0008875 D [cmd:12] New _Command [dap_access_cmsis_dap]
0008876 C Unexpected ACK value (5) returned by probe [__main__]
Traceback (most recent call last):
  File "/usr/lib/python3.11/site-packages/pyocd/probe/cmsis_dap_probe.py", line 541, in read_dp_result_callback
    value = result()
            ^^^^^^^^
  File "/usr/lib/python3.11/site-packages/pyocd/probe/pydapaccess/dap_access_cmsis_dap.py", line 1071, in read_reg_cb
    res = transfer.get_result()
          ^^^^^^^^^^^^^^^^^^^^^
  File "/usr/lib/python3.11/site-packages/pyocd/probe/pydapaccess/dap_access_cmsis_dap.py", line 157, in get_result
    self.daplink.flush()
  File "/usr/lib/python3.11/site-packages/pyocd/utility/concurrency.py", line 29, in _locking
    return func(self, *args, **kwargs)
           ^^^^^^^^^^^^^^^^^^^^^^^^^^^
  File "/usr/lib/python3.11/site-packages/pyocd/probe/pydapaccess/dap_access_cmsis_dap.py", line 881, in flush
    self._read_packet()
  File "/usr/lib/python3.11/site-packages/pyocd/utility/concurrency.py", line 29, in _locking
    return func(self, *args, **kwargs)
           ^^^^^^^^^^^^^^^^^^^^^^^^^^^
  File "/usr/lib/python3.11/site-packages/pyocd/probe/pydapaccess/dap_access_cmsis_dap.py", line 1157, in _read_packet
    decoded_data = cmd.decode_data(raw_data)
                   ^^^^^^^^^^^^^^^^^^^^^^^^^
  File "/usr/lib/python3.11/site-packages/pyocd/probe/pydapaccess/dap_access_cmsis_dap.py", line 478, in decode_data
    data = self._decode_transfer_block_data(data)
           ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
  File "/usr/lib/python3.11/site-packages/pyocd/probe/pydapaccess/dap_access_cmsis_dap.py", line 447, in _decode_transfer_block_data
    self._check_response(data[3])
  File "/usr/lib/python3.11/site-packages/pyocd/probe/pydapaccess/dap_access_cmsis_dap.py", line 370, in _check_response
    raise DAPAccessIntf.TransferError("Unexpected ACK value (%d) returned by probe" % ack)
pyocd.probe.pydapaccess.dap_access_api.DAPAccessIntf.TransferError: Unexpected ACK value (5) returned by probe

The above exception was the direct cause of the following exception:

Traceback (most recent call last):
  File "/usr/lib/python3.11/site-packages/pyocd/__main__.py", line 161, in run
    status = cmd.invoke()
             ^^^^^^^^^^^^
  File "/usr/lib/python3.11/site-packages/pyocd/subcommands/load_cmd.py", line 100, in invoke
    with session:
  File "/usr/lib/python3.11/site-packages/pyocd/core/session.py", line 402, in __enter__
    self.open()
  File "/usr/lib/python3.11/site-packages/pyocd/core/session.py", line 540, in open
    self._board.init()
  File "/usr/lib/python3.11/site-packages/pyocd/board/board.py", line 142, in init
    self.target.init()
  File "/usr/lib/python3.11/site-packages/pyocd/coresight/coresight_target.py", line 123, in init
    super().init()
  File "/usr/lib/python3.11/site-packages/pyocd/core/soc_target.py", line 172, in init
    seq.invoke()
  File "/usr/lib/python3.11/site-packages/pyocd/utility/sequencer.py", line 213, in invoke
    resultSequence.invoke()
  File "/usr/lib/python3.11/site-packages/pyocd/utility/sequencer.py", line 208, in invoke
    resultSequence = call()
                     ^^^^^^
  File "/usr/lib/python3.11/site-packages/pyocd/coresight/dap.py", line 466, in _connect
    connector.connect()
  File "/usr/lib/python3.11/site-packages/pyocd/coresight/dap.py", line 256, in connect
    self._idr = self.read_idr()
                ^^^^^^^^^^^^^^^
  File "/usr/lib/python3.11/site-packages/pyocd/coresight/dap.py", line 285, in read_idr
    dpidr = self._dp.read_dp(DP_IDR, now=True)
            ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
  File "/usr/lib/python3.11/site-packages/pyocd/coresight/dap.py", line 822, in read_dp
    return read_dp_cb()
           ^^^^^^^^^^^^
  File "/usr/lib/python3.11/site-packages/pyocd/coresight/dap.py", line 810, in read_dp_cb
    result = result_cb()
             ^^^^^^^^^^^
  File "/usr/lib/python3.11/site-packages/pyocd/probe/cmsis_dap_probe.py", line 546, in read_dp_result_callback
    raise self._convert_exception(error) from error
pyocd.core.exceptions.TransferError: Unexpected ACK value (5) returned by probe
rgrr commented 1 year ago

hmmm, different voltage levels of target and probe?

I would try 500kHz at least.

rgrr commented 1 year ago

Hello Ivo, any progress on this issue?

IvoBCD commented 1 year ago

@rgrr I did check voltages (found it to be same 3.3V as on the other probes where it worked). Will retest with pyocd 0.35.1 shortly, having also swapped to a Pico with newer chiprev.

rgrr commented 1 year ago

Thanks for the fast response. Did not know, that there are different RP2040 revisions around.

rgrr commented 1 year ago

I suspect the problem is #39

You could give https://github.com/rgrr/yapicoprobe/releases/tag/rg-1.06 (up to 1.08) a try. Those used CMSIS-DAP 2.1.2.

JustAnother1 commented 1 year ago

@rgrr The RP2040 has chiprevs B0 to B2 (until now) the RP2040 datasheet specifies the bugs that each newer revision fixed. The revision is also printed on the chip. The upper text line (right below the Raspberry logo read RP2- ...

rgrr commented 1 year ago

@rgrr The RP2040 has chiprevs B0 to B2 (until now) the RP2040 datasheet specifies the bugs that each newer revision fixed. The revision is also printed on the chip. The upper text line (right below the Raspberry logo read RP2- ...

@JustAnother1 Have you found any erratum which might affect PIO operation?

@IvoBCD any news on this issue? Otherwise I will close it as "not reproducible"

JustAnother1 commented 1 year ago

The RP2040 Datasheet Appendix B Does not list an errata that relates to PIO.

rgrr commented 1 year ago

cannot reproduce

rgrr commented 1 year ago

Hello @IvoBCD ,

a problem similar to yours appeared with STM32. The good thing is, that this has been resolved with #63.

If you are in the mood and have time it would be very kind if you could verify if this also helps with your issue.

I will later on provide some images.