Menu
▾
▴
Re: [Openocd-development] Warn : Unexpected idcode after end of chain: 0x00000000 and Info : TAP does not have IDCODE
|
From: Drasko D. <dra...@gm...> - 2010-08-25 11:19:04
|
On Wed, Aug 25, 2010 at 8:38 AM, Laurent Gauch <lau...@am...> wrote: >> >> Hello David, >> I am not so sure about this. Still smells like reset problem to me. >> >> It should be noted that when I let small bootloader execute from the >> SPI flash and do the reset (and some other config), I can attach with >> OpenOCD, i.e. chain is well scanned. I was able after attachment to >> set-up SDRAM and launch eCos application (which would further >> configure SoC). Now I erased the flash and tried without bootloader. I >> do not exactly know what bootloader is doing (have not insight in the >> code yet), but obviously nothing expect reset is needed for a proper >> chain scanning. >> >> It should be also noted that Lauterbach jtag attaches without any >> problems. I do not think that it does anything special for this step. >> All what is needed after is few lines of SDRAM set-up and application >> can be uploaded and run. But that comes after attachment (as for >> OpenOCD). >> >> All this confirms that hardware is working properly. I am almost >> convinced that bug is either on OpenOCD side (I tried with both ftd >> libs) or I should configure reset in some special way... >> >> Would anybody have some hints after these additional explanations ? >> >> Best regards, >> Drasko >> > > It is not coming from your JTAGkey-2 since you can scan and detect the chain > after configuration of your system. > > It is really somewhere in the OpenOCD . I am almost sure. As I mentioned, Lautebach works fine. So is Amontec JTAG2 when bootloder passed before (probably doing reset correctly). > > But you could try to disable the use of RTCK feature from the Amontec > JTAGkey-2 and run at a super low frequency before doing your scan chain > detection. How to do this ? In configuration file ? > Also, could you please try to disconnect the JTAGkey from USB port before > doing this. OpenOCD is still not really clean regarding the finish using the > Amontec JTAGey as other FT2232 USB JTAG dongle before closing its handle in > mpsse mode. I noted this before. When reset (USB disconnected and re-connected) Amontec Jtag2 have only 2 green leds on. After running one session of OpenOCD all leds stay on (mentioned two gree + one yellow + one red). > Let us know the debug output in this case. OK, here is my procedure : 1) Disconnect/re-connect Amontec JtagKey2. Only two green leds are on. 2) Commented out #jtag_rclk 1 line in config. Now config has only 3 lines : source [find ../tcl/interface/jtagkey2.cfg] adapter_khz 1 scan_chain That's all. I do not think that we can get slower than 1KHz (adapter_khz 1). If yes, please tell me how. 3) launched openocd. It keeps getting all zeros : root@konj:~/openocd/openocd/src# ./openocd -f my_openocd.cfg Open On-Chip Debugger 0.5.0-dev-00494-g5c98e06-dirty (2010-08-24-20:22) Licensed under GNU GPL v2 For bug reports, read http://openocd.berlios.de/doc/doxygen/bugs.html Info : only one transport option; autoselect 'jtag' 1 kHz TapName Enabled IdCode Expected IrLen IrCap IrMask -- ------------------- -------- ---------- ---------- ----- ----- ------ Info : max TCK change to: 30000 kHz Info : clock speed 1 kHz Warn : There are no enabled taps. AUTO PROBING MIGHT NOT WORK!! Info : inter: 0.000079, inter2: 0.000079 end: 0.653373 Warn : AUTO auto0.tap - use "jtag newtap auto0 tap -expected-id 0x01273043 ..." Warn : AUTO auto1.tap - use "jtag newtap auto1 tap -expected-id 0x01273043 ..." Warn : AUTO auto2.tap - use "jtag newtap auto2 tap -expected-id 0x01273043 ..." Warn : AUTO auto3.tap - use "jtag newtap auto3 tap -expected-id 0x01273043 ..." Warn : AUTO auto4.tap - use "jtag newtap auto4 tap -expected-id 0x01273043 ..." Warn : AUTO auto5.tap - use "jtag newtap auto5 tap -expected-id 0x00000000 ..." Warn : AUTO auto6.tap - use "jtag newtap auto6 tap -expected-id 0x00000000 ..." Warn : AUTO auto7.tap - use "jtag newtap auto7 tap -expected-id 0x00000000 ..." Warn : AUTO auto8.tap - use "jtag newtap auto8 tap -expected-id 0x00000000 ..." Warn : AUTO auto9.tap - use "jtag newtap auto9 tap -expected-id 0x00000000 ..." Warn : AUTO auto10.tap - use "jtag newtap auto10 tap -expected-id 0x00000000 ..." Warn : AUTO auto11.tap - use "jtag newtap auto11 tap -expected-id 0x00000000 ..." Warn : AUTO auto12.tap - use "jtag newtap auto12 tap -expected-id 0x00000000 ..." Warn : AUTO auto13.tap - use "jtag newtap auto13 tap -expected-id 0x00000000 ..." ... Warn : AUTO auto450.tap - use "jtag newtap auto450 tap -expected-id 0x00000000 ..." Warn : AUTO auto451.tap - use "jtag newtap auto451 tap -expected-id 0x00000000 ..." Warn : AUTO auto452.tap - use "jtag newtap auto452 tap -expected-id 0x00000000 ..." Warn : AUTO auto453.tap - use "jtag newtap auto453 tap -expected-id 0x00000000 ..." Error: couldn't read enough bytes from FT2232 device (664 < 3407) Error: couldn't read from FT2232 Error: couldn't read enough bytes from FT2232 device (667 < 3407) Error: couldn't read from FT2232 Info : inter: 0.000156, inter2: 0.000157 end: 0.645141 Error: JTAG scan chain interrogation failed: all zeroes Error: Check JTAG interface, timings, target power, etc. Command handler execution failed in procedure 'transport' Command handler execution failed in procedure 'init' 4) Here is debug version : Open On-Chip Debugger 0.5.0-dev-00494-g5c98e06-dirty (2010-08-24-20:22) Licensed under GNU GPL v2 For bug reports, read http://openocd.berlios.de/doc/doxygen/bugs.html User : 11 1 command.c:543 command_print() 71184: debug_level: 3 Debug: 12 1 configuration.c:45 add_script_search_dir() 72424: adding /home/ddraskovic/.openocd Debug: 13 1 configuration.c:45 add_script_search_dir() 72408: adding /usr/local/share/openocd/site Debug: 14 1 configuration.c:45 add_script_search_dir() 72360: adding /usr/local/share/openocd/scripts Debug: 15 1 configuration.c:87 find_file() 69944: found my_openocd.cfg Debug: 16 1 configuration.c:87 find_file() 67920: found ../tcl/interface/jtagkey2.cfg Debug: 17 1 command.c:133 script_debug() 63432: command - ocd_command ocd_command type ocd_interface ft2232 Debug: 18 1 command.c:133 script_debug() 63512: command - interface ocd_interface ft2232 Debug: 20 1 command.c:350 register_command_handler() 63280: registering 'ocd_ft2232_device_desc'... Debug: 21 1 command.c:350 register_command_handler() 62600: registering 'ocd_ft2232_serial'... Debug: 22 1 command.c:350 register_command_handler() 62032: registering 'ocd_ft2232_layout'... Debug: 23 1 command.c:350 register_command_handler() 61488: registering 'ocd_ft2232_vid_pid'... Debug: 24 1 command.c:350 register_command_handler() 60976: registering 'ocd_ft2232_latency'... Info : 25 1 transport.c:123 allow_transports() 60616: only one transport option; autoselect 'jtag' Debug: 26 1 command.c:350 register_command_handler() 60256: registering 'ocd_jtag_flush_queue_sleep'... Debug: 27 1 command.c:350 register_command_handler() 59624: registering 'ocd_jtag_rclk'... Debug: 28 1 command.c:350 register_command_handler() 59112: registering 'ocd_jtag_ntrst_delay'... Debug: 29 1 command.c:350 register_command_handler() 58568: registering 'ocd_jtag_ntrst_assert_width'... Debug: 30 1 command.c:350 register_command_handler() 58088: registering 'ocd_scan_chain'... Debug: 31 1 command.c:350 register_command_handler() 57560: registering 'ocd_jtag_reset'... Debug: 32 1 command.c:350 register_command_handler() 57056: registering 'ocd_runtest'... Debug: 33 2 command.c:350 register_command_handler() 56456: registering 'ocd_irscan'... Debug: 34 2 command.c:350 register_command_handler() 55880: registering 'ocd_verify_ircapture'... Debug: 35 2 command.c:350 register_command_handler() 55312: registering 'ocd_verify_jtag'... Debug: 36 2 command.c:350 register_command_handler() 54712: registering 'ocd_tms_sequence'... Debug: 37 2 command.c:350 register_command_handler() 54168: registering 'ocd_jtag'... Debug: 38 2 command.c:350 register_command_handler() 53648: registering 'ocd_jtag'... Debug: 39 2 command.c:350 register_command_handler() 53344: registering 'ocd_jtag'... Debug: 40 2 command.c:350 register_command_handler() 53032: registering 'ocd_jtag'... Debug: 41 2 command.c:350 register_command_handler() 52856: registering 'ocd_jtag'... Debug: 42 2 command.c:350 register_command_handler() 52704: registering 'ocd_jtag'... Debug: 43 2 command.c:350 register_command_handler() 52552: registering 'ocd_jtag'... Debug: 44 2 command.c:350 register_command_handler() 52376: registering 'ocd_jtag'... Debug: 45 2 command.c:350 register_command_handler() 52216: registering 'ocd_jtag'... Debug: 46 2 command.c:350 register_command_handler() 52112: registering 'ocd_jtag'... Debug: 47 2 command.c:350 register_command_handler() 51904: registering 'ocd_jtag'... Debug: 48 2 command.c:350 register_command_handler() 51768: registering 'ocd_jtag'... Debug: 49 2 command.c:350 register_command_handler() 51552: registering 'ocd_jtag'... Debug: 50 2 command.c:350 register_command_handler() 50656: registering 'ocd_svf'... Debug: 51 2 command.c:350 register_command_handler() 50112: registering 'ocd_xsvf'... Debug: 52 2 command.c:133 script_debug() 49184: command - ocd_command ocd_command type ocd_ft2232_device_desc Amontec JTAGkey-2 Debug: 53 2 command.c:133 script_debug() 49232: command - ft2232_device_desc ocd_ft2232_device_desc Amontec JTAGkey-2 Debug: 55 2 command.c:133 script_debug() 48576: command - ocd_command ocd_command type ocd_ft2232_layout jtagkey Debug: 56 2 command.c:133 script_debug() 48664: command - ft2232_layout ocd_ft2232_layout jtagkey Debug: 58 2 command.c:133 script_debug() 47616: command - ocd_command ocd_command type ocd_ft2232_vid_pid 0x0403 0xCFF8 Debug: 59 2 command.c:133 script_debug() 47688: command - ft2232_vid_pid ocd_ft2232_vid_pid 0x0403 0xCFF8 Debug: 61 2 configuration.c:87 find_file() 50584: found ./my3010.cfg Debug: 62 2 command.c:133 script_debug() 47816: command - ocd_command ocd_command type ocd_adapter_khz 1 Debug: 63 2 command.c:133 script_debug() 47928: command - adapter_khz ocd_adapter_khz 1 Debug: 65 2 core.c:1628 jtag_config_khz() 47912: handle jtag khz Debug: 66 2 core.c:1591 adapter_khz_to_speed() 47880: convert khz to interface specific speed value User : 67 2 command.c:543 command_print() 47904: 1 kHz Debug: 68 2 command.c:133 script_debug() 47664: command - ocd_command ocd_command type ocd_scan_chain Debug: 69 2 command.c:133 script_debug() 47776: command - scan_chain ocd_scan_chain User : 71 2 command.c:543 command_print() 47640: TapName Enabled IdCode Expected IrLen IrCap IrMask User : 72 2 command.c:543 command_print() 47488: -- ------------------- -------- ---------- ---------- ----- ----- ------ Debug: 73 2 httpd_stubs.c:28 httpd_start() 52304: libocdserver was built without HTTPD support Debug: 74 2 command.c:133 script_debug() 50704: command - ocd_command ocd_command type ocd_init Debug: 75 2 command.c:133 script_debug() 50848: command - init ocd_init Debug: 77 2 command.c:133 script_debug() 48696: command - ocd_command ocd_command type ocd_target init Debug: 78 2 command.c:133 script_debug() 48760: command - ocd_target ocd_target init Debug: 80 2 target.c:888 handle_target_init_command() 48728: Initializing targets... Debug: 81 2 ft2232.c:2336 ft2232_init() 49984: ft2232 interface using shortest path jtag state transitions Debug: 82 2 ft2232.c:2249 ft2232_init_libftdi() 49984: 'ft2232' interface using libftdi with 'jtagkey' layout (0403:cff8) Debug: 83 285 ft2232.c:2296 ft2232_init_libftdi() 92200: current latency timer: 2 Debug: 84 286 ft2232.c:2307 ft2232_init_libftdi() 92200: FTDI chip type: 4 "2232H" Debug: 85 286 ft2232.c:2701 jtagkey_init() 92216: 80 08 1b Debug: 86 286 ft2232.c:2760 jtagkey_init() 92216: 82 09 0f Info : 87 286 ft2232.c:622 ft2232h_ft4232h_clk_divide_by_5() 92192: max TCK change to: 30000 kHz Debug: 88 286 core.c:1591 adapter_khz_to_speed() 92128: convert khz to interface specific speed value Debug: 89 286 core.c:1595 adapter_khz_to_speed() 92152: have interface set up Debug: 90 286 ft2232.c:590 ft2232h_ft4232h_adaptive_clocking() 92168: 97 Debug: 91 286 ft2232.c:651 ft2232_speed() 92168: 86 2f 75 Debug: 92 287 core.c:1591 adapter_khz_to_speed() 92128: convert khz to interface specific speed value Debug: 93 287 core.c:1595 adapter_khz_to_speed() 92152: have interface set up Info : 94 287 core.c:1418 adapter_init() 92160: clock speed 1 kHz Debug: 95 287 openocd.c:124 handle_init_command() 92144: Debug Adapter init complete Debug: 96 287 command.c:133 script_debug() 90192: command - ocd_command ocd_command type ocd_transport init Debug: 97 287 command.c:133 script_debug() 90256: command - ocd_transport ocd_transport init Debug: 99 287 transport.c:253 handle_transport_init() 90256: handle_transport_init Debug: 100 287 ft2232.c:1937 ft2232_execute_reset() 90240: reset trst: 0 srst 0 Debug: 101 287 ft2232.c:1455 jtagkey_reset() 90208: trst: 0, srst: 0, high_output: 0x09, high_direction: 0x0f Debug: 102 287 ft2232.c:1958 ft2232_execute_reset() 90248: trst: 0, srst: 0 Debug: 103 287 ft2232.c:790 ft2232_send_and_recv() 90240: write buffer (size 3): Debug: 104 287 ft2232.c:771 ft2232_debug_dump_buffer() 90256: 82 09 0f Debug: 105 287 core.c:714 jtag_add_reset() 90264: SRST line released Debug: 106 287 core.c:738 jtag_add_reset() 90264: TRST line released Debug: 107 287 core.c:330 jtag_call_event_callbacks() 90256: jtag event: TAP reset Debug: 108 287 command.c:133 script_debug() 85632: command - ocd_command ocd_command type ocd_jtag arp_init Debug: 109 287 command.c:133 script_debug() 84976: command - ocd_jtag ocd_jtag arp_init Debug: 110 287 core.c:1432 jtag_init_inner() 85048: Init JTAG chain Warn : 111 287 core.c:1448 jtag_init_inner() 85008: There are no enabled taps. AUTO PROBING MIGHT NOT WORK!! Debug: 112 287 core.c:330 jtag_call_event_callbacks() 84744: jtag event: TAP reset Debug: 113 287 ft2232.c:1749 ft2232_execute_statemove() 84744: statemove end in RESET Debug: 114 287 ft2232.c:419 clock_tms() 84712: mpsse cmd=4b, tms_bits = 0x000000ff, bit_count=5 Debug: 115 287 ft2232.c:429 clock_tms() 84744: tap_set_state(RESET) Debug: 116 287 ft2232.c:429 clock_tms() 84744: tap_set_state(RESET) Debug: 117 287 ft2232.c:429 clock_tms() 84744: tap_set_state(RESET) Debug: 118 287 ft2232.c:429 clock_tms() 84744: tap_set_state(RESET) Debug: 119 287 ft2232.c:429 clock_tms() 84744: tap_set_state(RESET) Debug: 120 287 ft2232.c:790 ft2232_send_and_recv() 84744: write buffer (size 3): Debug: 121 287 ft2232.c:771 ft2232_debug_dump_buffer() 84752: 4b 04 1f Debug: 122 288 core.c:1049 jtag_examine_chain() 84744: DR scan interrogation for IDCODE/BYPASS Debug: 123 288 core.c:330 jtag_call_event_callbacks() 84744: jtag event: TAP reset Debug: 124 288 ft2232.c:1884 ft2232_execute_scan() 84752: DRSCAN type:3 Debug: 125 288 commands.c:189 jtag_build_buffer() 84656: DRSCAN num_fields: 1 Debug: 126 288 commands.c:202 jtag_build_buffer() 84600: fields[0].out_value[640]: 0x000000FF000000FF Debug: 127 288 ft2232.c:480 move_to_state() 84656: start=RESET goal=DRSHIFT Debug: 128 288 ft2232.c:419 clock_tms() 84624: mpsse cmd=4b, tms_bits = 0x00000017, bit_count=7 Debug: 129 288 ft2232.c:429 clock_tms() 84656: tap_set_state(RESET) Debug: 130 288 ft2232.c:429 clock_tms() 84656: tap_set_state(RESET) Debug: 131 288 ft2232.c:429 clock_tms() 84656: tap_set_state(RESET) Debug: 132 288 ft2232.c:429 clock_tms() 84656: tap_set_state(RUN/IDLE) Debug: 133 288 ft2232.c:429 clock_tms() 84656: tap_set_state(DRSELECT) Debug: 134 288 ft2232.c:429 clock_tms() 84656: tap_set_state(DRCAPTURE) Debug: 135 288 ft2232.c:429 clock_tms() 84656: tap_set_state(DRSHIFT) Debug: 136 288 ft2232.c:1103 ft2232_add_scan() 84664: finish via PAUSE Debug: 137 288 ft2232.c:419 clock_tms() 84624: mpsse cmd=6b, tms_bits = 0x00000001, bit_count=2 Debug: 138 288 ft2232.c:429 clock_tms() 84656: tap_set_state(DREXIT1) Debug: 139 288 ft2232.c:429 clock_tms() 84656: tap_set_state(DRPAUSE) Debug: 140 288 ft2232.c:1925 ft2232_execute_scan() 84736: DR scan, 640 bits, end in DRPAUSE Debug: 141 288 ft2232.c:1749 ft2232_execute_statemove() 84744: statemove end in RESET Debug: 142 288 ft2232.c:419 clock_tms() 84720: mpsse cmd=4b, tms_bits = 0x000000ff, bit_count=5 Debug: 143 288 ft2232.c:429 clock_tms() 84744: tap_set_state(DREXIT2) Debug: 144 288 ft2232.c:429 clock_tms() 84744: tap_set_state(DRUPDATE) Debug: 145 288 ft2232.c:429 clock_tms() 84744: tap_set_state(DRSELECT) Debug: 146 288 ft2232.c:429 clock_tms() 84744: tap_set_state(IRSELECT) Debug: 147 288 ft2232.c:429 clock_tms() 84744: tap_set_state(RESET) Debug: 148 288 ft2232.c:790 ft2232_send_and_recv() 84744: write buffer (size 94): Debug: 149 288 ft2232.c:765 ft2232_debug_dump_buffer() 84720: 4b 06 17 39 4e 00 ff 00 00 00 ff 00 00 00 ff 00 Debug: 150 288 ft2232.c:765 ft2232_debug_dump_buffer() 84720: 00 00 ff 00 00 00 ff 00 00 00 ff 00 00 00 ff 00 Debug: 151 288 ft2232.c:765 ft2232_debug_dump_buffer() 84720: 00 00 ff 00 00 00 ff 00 00 00 ff 00 00 00 ff 00 Debug: 152 288 ft2232.c:765 ft2232_debug_dump_buffer() 84720: 00 00 ff 00 00 00 ff 00 00 00 ff 00 00 00 ff 00 Debug: 153 288 ft2232.c:765 ft2232_debug_dump_buffer() 84720: 00 00 ff 00 00 00 ff 00 00 00 ff 00 00 00 ff 00 Debug: 154 288 ft2232.c:771 ft2232_debug_dump_buffer() 84728: 00 00 ff 00 00 3b 06 00 6b 01 01 4b 04 1f Info : 155 942 ft2232.c:834 ft2232_send_and_recv() 84720: inter: 0.000056, inter2: 0.000057 end: 0.654478 Debug: 156 942 ft2232.c:855 ft2232_send_and_recv() 84736: read buffer (0 retries): 81 bytes Debug: 157 942 ft2232.c:765 ft2232_debug_dump_buffer() 84720: 43 30 27 01 43 30 27 01 43 30 27 01 43 30 27 01 Debug: 158 942 ft2232.c:765 ft2232_debug_dump_buffer() 84720: 43 30 27 01 00 00 00 00 00 00 00 00 00 00 00 00 Debug: 159 942 ft2232.c:765 ft2232_debug_dump_buffer() 84720: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Debug: 160 942 ft2232.c:765 ft2232_debug_dump_buffer() 84720: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Debug: 161 942 ft2232.c:765 ft2232_debug_dump_buffer() 84720: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Debug: 162 942 ft2232.c:771 ft2232_debug_dump_buffer() 84760: 00 Debug: 163 942 commands.c:248 jtag_read_buffer() 84520: fields[0].in_value[640]: 0x0127304301273043 Warn : 164 942 core.c:1150 jtag_examine_chain() 84552: AUTO auto0.tap - use "jtag newtap auto0 tap -expected-id 0x01273043 ..." Debug: 165 942 core.c:1349 jtag_tap_init() 84000: Created Tap: auto0.tap @ abs position 0, irlen 0, capture: 0x1 mask: 0x3 Warn : 166 942 core.c:1150 jtag_examine_chain() 83856: AUTO auto1.tap - use "jtag newtap auto1 tap -expected-id 0x01273043 ..." Debug: 167 942 core.c:1349 jtag_tap_init() 83792: Created Tap: auto1.tap @ abs position 0, irlen 0, capture: 0x1 mask: 0x3 Warn : 168 942 core.c:1150 jtag_examine_chain() 83648: AUTO auto2.tap - use "jtag newtap auto2 tap -expected-id 0x01273043 ..." Debug: 169 942 core.c:1349 jtag_tap_init() 83584: Created Tap: auto2.tap @ abs position 0, irlen 0, capture: 0x1 mask: 0x3 Warn : 170 942 core.c:1150 jtag_examine_chain() 83440: AUTO auto3.tap - use "jtag newtap auto3 tap -expected-id 0x01273043 ..." Debug: 171 942 core.c:1349 jtag_tap_init() 82928: Created Tap: auto3.tap @ abs position 0, irlen 0, capture: 0x1 mask: 0x3 Warn : 172 942 core.c:1150 jtag_examine_chain() 82784: AUTO auto4.tap - use "jtag newtap auto4 tap -expected-id 0x01273043 ..." Debug: 173 942 core.c:1349 jtag_tap_init() 82136: Created Tap: auto4.tap @ abs position 0, irlen 0, capture: 0x1 mask: 0x3 Warn : 174 942 core.c:1150 jtag_examine_chain() 82008: AUTO auto5.tap - use "jtag newtap auto5 tap -expected-id 0x00000000 ..." Debug: 175 942 core.c:1349 jtag_tap_init() 81944: Created Tap: auto5.tap @ abs position 0, irlen 0, capture: 0x1 mask: 0x3 Warn : 176 942 core.c:1150 jtag_examine_chain() 81816: AUTO auto6.tap - use "jtag newtap auto6 tap -expected-id 0x00000000 ..." Debug: 177 942 core.c:1349 jtag_tap_init() 81752: Created Tap: auto6.tap @ abs position 0, irlen 0, capture: 0x1 mask: 0x3 Warn : 178 942 core.c:1150 jtag_examine_chain() 81624: AUTO auto7.tap - use "jtag newtap auto7 tap -expected-id 0x00000000 ..." Debug: 179 942 core.c:1349 jtag_tap_init() 81560: Created Tap: auto7.tap @ abs position 0, irlen 0, capture: 0x1 mask: 0x3 Warn : 180 942 core.c:1150 jtag_examine_chain() 81432: AUTO auto8.tap - use "jtag newtap auto8 tap -expected-id 0x00000000 ..." Debug: 181 943 core.c:1349 jtag_tap_init() 81368: Created Tap: auto8.tap @ abs position 0, irlen 0, capture: 0x1 mask: 0x3 Warn : 182 943 core.c:1150 jtag_examine_chain() 81240: AUTO auto9.tap - use "jtag newtap auto9 tap -expected-id 0x00000000 ..." ... Debug: 1067 949 core.c:1349 jtag_tap_init() 48056: Created Tap: auto451.tap @ abs position 0, irlen 0, capture: 0x1 mask: 0x3 Warn : 1068 949 core.c:1150 jtag_examine_chain() 47920: AUTO auto452.tap - use "jtag newtap auto452 tap -expected-id 0x00000000 ..." Debug: 1069 949 core.c:1349 jtag_tap_init() 47864: Created Tap: auto452.tap @ abs position 0, irlen 0, capture: 0x1 mask: 0x3 Warn : 1070 949 core.c:1150 jtag_examine_chain() 47728: AUTO auto453.tap - use "jtag newtap auto453 tap -expected-id 0x00000000 ..." Debug: 1071 949 core.c:1349 jtag_tap_init() 47672: Created Tap: auto453.tap @ abs position 0, irlen 0, capture: 0x1 mask: 0x3 Debug: 1072 949 core.c:1213 jtag_validate_ircapture() 44288: IR capture validation scan Debug: 1073 949 ft2232.c:1884 ft2232_execute_scan() 44296: IRSCAN type:3 Debug: 1074 949 commands.c:189 jtag_build_buffer() 40872: IRSCAN num_fields: 1 Debug: 1075 949 commands.c:202 jtag_build_buffer() 40824: fields[0].out_value[27242]: 0xFFFFFFFFFFFFFFFF Debug: 1076 950 ft2232.c:480 move_to_state() 40872: start=RESET goal=IRSHIFT Debug: 1077 950 ft2232.c:419 clock_tms() 40848: mpsse cmd=4b, tms_bits = 0x0000001b, bit_count=7 Debug: 1078 950 ft2232.c:429 clock_tms() 40872: tap_set_state(RESET) Debug: 1079 950 ft2232.c:429 clock_tms() 40872: tap_set_state(RESET) Debug: 1080 950 ft2232.c:429 clock_tms() 40872: tap_set_state(RUN/IDLE) Debug: 1081 950 ft2232.c:429 clock_tms() 40872: tap_set_state(DRSELECT) Debug: 1082 950 ft2232.c:429 clock_tms() 40872: tap_set_state(IRSELECT) Debug: 1083 950 ft2232.c:429 clock_tms() 40872: tap_set_state(IRCAPTURE) Debug: 1084 950 ft2232.c:429 clock_tms() 40872: tap_set_state(IRSHIFT) Debug: 1085 950 ft2232.c:1103 ft2232_add_scan() 40880: finish via PAUSE Debug: 1086 950 ft2232.c:419 clock_tms() 40848: mpsse cmd=6b, tms_bits = 0x00000001, bit_count=2 Debug: 1087 950 ft2232.c:429 clock_tms() 40872: tap_set_state(IREXIT1) Debug: 1088 950 ft2232.c:429 clock_tms() 40872: tap_set_state(IRPAUSE) Debug: 1089 950 ft2232.c:480 move_to_state() 40864: start=IRPAUSE goal=RUN/IDLE Debug: 1090 950 ft2232.c:419 clock_tms() 40848: mpsse cmd=4b, tms_bits = 0x00000003, bit_count=3 Debug: 1091 950 ft2232.c:429 clock_tms() 40872: tap_set_state(IREXIT2) Debug: 1092 950 ft2232.c:429 clock_tms() 40872: tap_set_state(IRUPDATE) Debug: 1093 950 ft2232.c:429 clock_tms() 40872: tap_set_state(RUN/IDLE) Debug: 1094 950 ft2232.c:1925 ft2232_execute_scan() 44272: IR scan, 27242 bits, end in RUN/IDLE Debug: 1095 950 ft2232.c:790 ft2232_send_and_recv() 44288: write buffer (size 3420): Debug: 1096 950 ft2232.c:765 ft2232_debug_dump_buffer() 44264: 4b 06 1b 39 4c 0d ff ff ff ff ff ff ff ff ff ff Debug: 1097 950 ft2232.c:765 ft2232_debug_dump_buffer() 44264: ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff Debug: 1098 950 ft2232.c:765 ft2232_debug_dump_buffer() 44264: ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff Debug: 1099 950 ft2232.c:765 ft2232_debug_dump_buffer() 44264: ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff Debug: 1100 950 ft2232.c:765 ft2232_debug_dump_buffer() 44264: ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff Debug: 1101 950 ft2232.c:765 ft2232_debug_dump_buffer() 44264: ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff Debug: 1102 950 ft2232.c:765 ft2232_debug_dump_buffer() 44264: ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff Debug: 1103 950 ft2232.c:765 ft2232_debug_dump_buffer() 44264: ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff Debug: 1104 950 ft2232.c:765 ft2232_debug_dump_buffer() 44264: ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ... Debug: 1304 951 ft2232.c:765 ft2232_debug_dump_buffer() 44264: ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff Debug: 1305 951 ft2232.c:765 ft2232_debug_dump_buffer() 44264: ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff Debug: 1306 951 ft2232.c:765 ft2232_debug_dump_buffer() 44264: ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff Debug: 1307 951 ft2232.c:765 ft2232_debug_dump_buffer() 44264: ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff Debug: 1308 951 ft2232.c:765 ft2232_debug_dump_buffer() 44264: ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff Debug: 1309 951 ft2232.c:771 ft2232_debug_dump_buffer() 44272: ff ff ff 3b 00 03 6b 01 81 4b 02 03 Error: 1310 6283 ft2232.c:565 ft2232_read() 44256: couldn't read enough bytes from FT2232 device (665 < 3407) Error: 1311 6283 ft2232.c:820 ft2232_send_and_recv() 44288: couldn't read from FT2232 Debug: 1312 6283 ft2232.c:790 ft2232_send_and_recv() 44288: write buffer (size 0): Error: 1313 11617 ft2232.c:565 ft2232_read() 44256: couldn't read enough bytes from FT2232 device (667 < 3407) Error: 1314 11617 ft2232.c:820 ft2232_send_and_recv() 44288: couldn't read from FT2232 Debug: 1315 11617 core.c:330 jtag_call_event_callbacks() 47704: jtag event: TAP reset Debug: 1316 11617 ft2232.c:1749 ft2232_execute_statemove() 47704: statemove end in RESET Debug: 1317 11617 ft2232.c:419 clock_tms() 47680: mpsse cmd=4b, tms_bits = 0x000000ff, bit_count=5 Debug: 1318 11617 ft2232.c:429 clock_tms() 47704: tap_set_state(DRSELECT) Debug: 1319 11617 ft2232.c:429 clock_tms() 47704: tap_set_state(IRSELECT) Debug: 1320 11617 ft2232.c:429 clock_tms() 47704: tap_set_state(RESET) Debug: 1321 11617 ft2232.c:429 clock_tms() 47704: tap_set_state(RESET) Debug: 1322 11617 ft2232.c:429 clock_tms() 47704: tap_set_state(RESET) Debug: 1323 11617 ft2232.c:790 ft2232_send_and_recv() 47704: write buffer (size 3): Debug: 1324 11617 ft2232.c:771 ft2232_debug_dump_buffer() 47720: 4b 04 1f Debug: 1325 11617 command.c:133 script_debug() 45496: command - ocd_command ocd_command type ocd_jtag arp_init-reset Debug: 1326 11617 command.c:133 script_debug() 45576: command - ocd_jtag ocd_jtag arp_init-reset Debug: 1327 11617 core.c:1519 jtag_init_reset() 45624: Initializing with hard TRST+SRST reset Debug: 1328 11617 core.c:727 jtag_add_reset() 45624: JTAG reset with TLR instead of TRST Debug: 1329 11617 core.c:330 jtag_call_event_callbacks() 45616: jtag event: TAP reset Debug: 1330 11617 ft2232.c:1749 ft2232_execute_statemove() 45616: statemove end in RESET Debug: 1331 11617 ft2232.c:419 clock_tms() 45584: mpsse cmd=4b, tms_bits = 0x000000ff, bit_count=5 Debug: 1332 11617 ft2232.c:429 clock_tms() 45616: tap_set_state(RESET) Debug: 1333 11617 ft2232.c:429 clock_tms() 45616: tap_set_state(RESET) Debug: 1334 11617 ft2232.c:429 clock_tms() 45616: tap_set_state(RESET) Debug: 1335 11617 ft2232.c:429 clock_tms() 45616: tap_set_state(RESET) Debug: 1336 11617 ft2232.c:429 clock_tms() 45616: tap_set_state(RESET) Debug: 1337 11617 ft2232.c:790 ft2232_send_and_recv() 45616: write buffer (size 3): Debug: 1338 11617 ft2232.c:771 ft2232_debug_dump_buffer() 45632: 4b 04 1f Debug: 1339 11618 core.c:1432 jtag_init_inner() 45648: Init JTAG chain Debug: 1340 11618 core.c:330 jtag_call_event_callbacks() 45624: jtag event: TAP reset Debug: 1341 11618 ft2232.c:1749 ft2232_execute_statemove() 45624: statemove end in RESET Debug: 1342 11618 ft2232.c:419 clock_tms() 45592: mpsse cmd=4b, tms_bits = 0x000000ff, bit_count=5 Debug: 1343 11618 ft2232.c:429 clock_tms() 45624: tap_set_state(RESET) Debug: 1344 11618 ft2232.c:429 clock_tms() 45624: tap_set_state(RESET) Debug: 1345 11618 ft2232.c:429 clock_tms() 45624: tap_set_state(RESET) Debug: 1346 11618 ft2232.c:429 clock_tms() 45624: tap_set_state(RESET) Debug: 1347 11618 ft2232.c:429 clock_tms() 45624: tap_set_state(RESET) Debug: 1348 11618 ft2232.c:790 ft2232_send_and_recv() 45624: write buffer (size 3): Debug: 1349 11618 ft2232.c:771 ft2232_debug_dump_buffer() 45632: 4b 04 1f Debug: 1350 11618 core.c:1049 jtag_examine_chain() 45624: DR scan interrogation for IDCODE/BYPASS Debug: 1351 11618 core.c:330 jtag_call_event_callbacks() 45624: jtag event: TAP reset Debug: 1352 11618 ft2232.c:1884 ft2232_execute_scan() 45632: DRSCAN type:3 Debug: 1353 11618 commands.c:189 jtag_build_buffer() 45536: DRSCAN num_fields: 1 Debug: 1354 11618 commands.c:202 jtag_build_buffer() 45480: fields[0].out_value[640]: 0x000000FF000000FF Debug: 1355 11618 ft2232.c:480 move_to_state() 45536: start=RESET goal=DRSHIFT Debug: 1356 11618 ft2232.c:419 clock_tms() 45512: mpsse cmd=4b, tms_bits = 0x00000017, bit_count=7 Debug: 1357 11618 ft2232.c:429 clock_tms() 45536: tap_set_state(RESET) Debug: 1358 11618 ft2232.c:429 clock_tms() 45536: tap_set_state(RESET) Debug: 1359 11618 ft2232.c:429 clock_tms() 45536: tap_set_state(RESET) Debug: 1360 11618 ft2232.c:429 clock_tms() 45536: tap_set_state(RUN/IDLE) Debug: 1361 11618 ft2232.c:429 clock_tms() 45536: tap_set_state(DRSELECT) Debug: 1362 11618 ft2232.c:429 clock_tms() 45536: tap_set_state(DRCAPTURE) Debug: 1363 11618 ft2232.c:429 clock_tms() 45536: tap_set_state(DRSHIFT) Debug: 1364 11618 ft2232.c:1103 ft2232_add_scan() 45544: finish via PAUSE Debug: 1365 11618 ft2232.c:419 clock_tms() 45512: mpsse cmd=6b, tms_bits = 0x00000001, bit_count=2 Debug: 1366 11618 ft2232.c:429 clock_tms() 45536: tap_set_state(DREXIT1) Debug: 1367 11618 ft2232.c:429 clock_tms() 45536: tap_set_state(DRPAUSE) Debug: 1368 11618 ft2232.c:1925 ft2232_execute_scan() 45616: DR scan, 640 bits, end in DRPAUSE Debug: 1369 11618 ft2232.c:1749 ft2232_execute_statemove() 45624: statemove end in RESET Debug: 1370 11618 ft2232.c:419 clock_tms() 45600: mpsse cmd=4b, tms_bits = 0x000000ff, bit_count=5 Debug: 1371 11618 ft2232.c:429 clock_tms() 45624: tap_set_state(DREXIT2) Debug: 1372 11618 ft2232.c:429 clock_tms() 45624: tap_set_state(DRUPDATE) Debug: 1373 11618 ft2232.c:429 clock_tms() 45624: tap_set_state(DRSELECT) Debug: 1374 11618 ft2232.c:429 clock_tms() 45624: tap_set_state(IRSELECT) Debug: 1375 11618 ft2232.c:429 clock_tms() 45624: tap_set_state(RESET) Debug: 1376 11618 ft2232.c:790 ft2232_send_and_recv() 45624: write buffer (size 94): Debug: 1377 11618 ft2232.c:765 ft2232_debug_dump_buffer() 45600: 4b 06 17 39 4e 00 ff 00 00 00 ff 00 00 00 ff 00 Debug: 1378 11618 ft2232.c:765 ft2232_debug_dump_buffer() 45600: 00 00 ff 00 00 00 ff 00 00 00 ff 00 00 00 ff 00 Debug: 1379 11618 ft2232.c:765 ft2232_debug_dump_buffer() 45600: 00 00 ff 00 00 00 ff 00 00 00 ff 00 00 00 ff 00 Debug: 1380 11618 ft2232.c:765 ft2232_debug_dump_buffer() 45600: 00 00 ff 00 00 00 ff 00 00 00 ff 00 00 00 ff 00 Debug: 1381 11618 ft2232.c:765 ft2232_debug_dump_buffer() 45600: 00 00 ff 00 00 00 ff 00 00 00 ff 00 00 00 ff 00 Debug: 1382 11618 ft2232.c:771 ft2232_debug_dump_buffer() 45608: 00 00 ff 00 00 3b 06 00 6b 01 01 4b 04 1f Info : 1383 12263 ft2232.c:834 ft2232_send_and_recv() 45600: inter: 0.000054, inter2: 0.000055 end: 0.644714 Debug: 1384 12263 ft2232.c:855 ft2232_send_and_recv() 45616: read buffer (0 retries): 81 bytes Debug: 1385 12263 ft2232.c:765 ft2232_debug_dump_buffer() 45600: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Debug: 1386 12263 ft2232.c:765 ft2232_debug_dump_buffer() 45600: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Debug: 1387 12263 ft2232.c:765 ft2232_debug_dump_buffer() 45600: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Debug: 1388 12263 ft2232.c:765 ft2232_debug_dump_buffer() 45600: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Debug: 1389 12263 ft2232.c:765 ft2232_debug_dump_buffer() 45600: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Debug: 1390 12263 ft2232.c:771 ft2232_debug_dump_buffer() 45640: 00 Debug: 1391 12263 commands.c:248 jtag_read_buffer() 45400: fields[0].in_value[640]: 0x0000000000000000 Error: 1392 12263 core.c:939 jtag_examine_chain_check() 45616: JTAG scan chain interrogation failed: all zeroes Error: 1393 12263 core.c:940 jtag_examine_chain_check() 45616: Check JTAG interface, timings, target power, etc. Debug: 1394 12263 command.c:624 run_command() 46640: Command failed with error code -4 User : 1395 12263 command.c:828 openocd_jim_vfprintf() 105128: Command handler execution failed User : 1396 12263 command.c:828 openocd_jim_vfprintf() 105112: in procedure 'transport' User : 1397 12263 command.c:828 openocd_jim_vfprintf() 105144: Debug: 1398 12263 command.c:624 run_command() 105136: Command failed with error code -4 User : 1399 12263 command.c:828 openocd_jim_vfprintf() 142264: Command handler execution failed User : 1400 12263 command.c:828 openocd_jim_vfprintf() 142248: in procedure 'init' User : 1401 12263 command.c:828 openocd_jim_vfprintf() 142280: I am lost, do not know where to start looking. I can re-compile again with libftd2xx0.4.16, but that did not give good results before. I will also try to take a look at what Lauterbach is doing, but I am pretty sure that it does nothing except chain scan... Best regards, Drasko |