libftdi Archives

Subject: ft2232_read() error: couldn't read enough bytes from FT2232 device (0 < 5)

From: Drasko DRASKOVIC <drasko.draskovic@xxxxxxxxx>
To: libftdi@xxxxxxxxxxxxxxxxxxxxxxx
Date: Tue, 29 Mar 2011 15:58:11 +0200
Hi all,
I am using libftdi 0.18 with OpenOCD, and when trying to load some
longer file via Amontec USB dongle to my MIPS based board,
I am getting errors like this :

Error: 713075 301022 ft2232.c:584 ft2232_read() 172120: couldn't read
enough bytes from FT2232 device (0 < 5)
Error: 713076 301022 ft2232.c:839 ft2232_send_and_recv() 172152:
couldn't read from FT2232
Error: 713077 301022 mips_ejtag.c:115 mips_ejtag_drscan_32() 172168:
register read failed

Old libftd2xx (v.0.46) shows the same errors.  New libftd2xx (v.1.0.4)
does not have these problems - they are corrected.


What do you think that is the source of the problems like this in libftdi ?

I got some responses on OpenOCD mailing list that new libftdi build
with libusb-1.0 correct these issues. Is this true and why ?

I pasted the log of transaction with error below.


Best regards,
Drasko




Info : 712881 297014 ft2232.c:853 ft2232_send_and_recv() 172128:
inter: 0.000126, inter2: 0.000127 end: 0.001629
Debug: 712882 297014 ft2232.c:874 ft2232_send_and_recv() 172136: read
buffer (0 retries): 5 bytes
Debug: 712883 297014 ft2232.c:790 ft2232_debug_dump_buffer() 172160:
08 c0 04 80 20
Debug: 712884 297014 commands.c:248 jtag_read_buffer() 172088:
fields[0].in_value[32]: 0x4004C008
Debug: 712885 297014 ft2232.c:1933 ft2232_execute_scan() 172160: IRSCAN type:2
Debug: 712886 297014 commands.c:189 jtag_build_buffer() 172136: IRSCAN
num_fields: 1
Debug: 712887 297014 commands.c:202 jtag_build_buffer() 172104:
fields[0].out_value[5]: 0x0E
Debug: 712888 297014 ft2232.c:499 move_to_state() 172120:
start=RUN/IDLE goal=IRSHIFT
Debug: 712889 297014 ft2232.c:438 clock_tms() 172112: mpsse cmd=4b,
tms_bits = 0x00000003, bit_count=4
Debug: 712890 297014 ft2232.c:448 clock_tms() 172136: tap_set_state(DRSELECT)
Debug: 712891 297014 ft2232.c:448 clock_tms() 172136: tap_set_state(IRSELECT)
Debug: 712892 297014 ft2232.c:448 clock_tms() 172136: tap_set_state(IRCAPTURE)
Debug: 712893 297014 ft2232.c:448 clock_tms() 172136: tap_set_state(IRSHIFT)
Debug: 712894 297014 ft2232.c:1122 ft2232_add_scan() 172136: finish without read
Debug: 712895 297014 ft2232.c:438 clock_tms() 172112: mpsse cmd=4b,
tms_bits = 0x00000003, bit_count=3
Debug: 712896 297014 ft2232.c:448 clock_tms() 172136: tap_set_state(IREXIT1)
Debug: 712897 297014 ft2232.c:448 clock_tms() 172136: tap_set_state(IRUPDATE)
Debug: 712898 297014 ft2232.c:448 clock_tms() 172136: tap_set_state(RUN/IDLE)
Debug: 712899 297014 ft2232.c:1974 ft2232_execute_scan() 172136: IR
scan, 5 bits, end in RUN/IDLE
Debug: 712900 297014 ft2232.c:1933 ft2232_execute_scan() 172160: DRSCAN type:2
Debug: 712901 297014 commands.c:189 jtag_build_buffer() 172136: DRSCAN
num_fields: 2
Debug: 712902 297014 commands.c:202 jtag_build_buffer() 172104:
fields[0].out_value[1]: 0x00
Debug: 712903 297014 commands.c:202 jtag_build_buffer() 172104:
fields[1].out_value[32]: 0x8FA20038
Debug: 712904 297014 ft2232.c:499 move_to_state() 172120:
start=RUN/IDLE goal=DRSHIFT
Debug: 712905 297014 ft2232.c:438 clock_tms() 172112: mpsse cmd=4b,
tms_bits = 0x00000001, bit_count=3
Debug: 712906 297014 ft2232.c:448 clock_tms() 172136: tap_set_state(DRSELECT)
Debug: 712907 297014 ft2232.c:448 clock_tms() 172136: tap_set_state(DRCAPTURE)
Debug: 712908 297014 ft2232.c:448 clock_tms() 172136: tap_set_state(DRSHIFT)
Debug: 712909 297014 ft2232.c:1122 ft2232_add_scan() 172136: finish without read
Debug: 712910 297014 ft2232.c:438 clock_tms() 172112: mpsse cmd=4b,
tms_bits = 0x00000003, bit_count=3
Debug: 712911 297014 ft2232.c:448 clock_tms() 172136: tap_set_state(DREXIT1)
Debug: 712912 297014 ft2232.c:448 clock_tms() 172136: tap_set_state(DRUPDATE)
Debug: 712913 297014 ft2232.c:448 clock_tms() 172136: tap_set_state(RUN/IDLE)
Debug: 712914 297014 ft2232.c:1974 ft2232_execute_scan() 172136: DR
scan, 33 bits, end in RUN/IDLE
Debug: 712915 297014 ft2232.c:1933 ft2232_execute_scan() 172160: IRSCAN type:2
Debug: 712916 297014 commands.c:189 jtag_build_buffer() 172136: IRSCAN
num_fields: 1
Debug: 712917 297014 commands.c:202 jtag_build_buffer() 172104:
fields[0].out_value[5]: 0x0A
Debug: 712918 297014 ft2232.c:499 move_to_state() 172120:
start=RUN/IDLE goal=IRSHIFT
Debug: 712919 297014 ft2232.c:438 clock_tms() 172112: mpsse cmd=4b,
tms_bits = 0x00000003, bit_count=4
Debug: 712920 297014 ft2232.c:448 clock_tms() 172136: tap_set_state(DRSELECT)
Debug: 712921 297014 ft2232.c:448 clock_tms() 172136: tap_set_state(IRSELECT)
Debug: 712922 297014 ft2232.c:448 clock_tms() 172136: tap_set_state(IRCAPTURE)
Debug: 712923 297014 ft2232.c:448 clock_tms() 172136: tap_set_state(IRSHIFT)
Debug: 712924 297014 ft2232.c:1122 ft2232_add_scan() 172136: finish without read
Debug: 712925 297014 ft2232.c:438 clock_tms() 172112: mpsse cmd=4b,
tms_bits = 0x00000003, bit_count=3
Debug: 712926 297014 ft2232.c:448 clock_tms() 172136: tap_set_state(IREXIT1)
Debug: 712927 297014 ft2232.c:448 clock_tms() 172136: tap_set_state(IRUPDATE)
Debug: 712928 297014 ft2232.c:448 clock_tms() 172136: tap_set_state(RUN/IDLE)
Debug: 712929 297014 ft2232.c:1974 ft2232_execute_scan() 172136: IR
scan, 5 bits, end in RUN/IDLE
Debug: 712930 297014 ft2232.c:1933 ft2232_execute_scan() 172160: DRSCAN type:3
Debug: 712931 297014 commands.c:189 jtag_build_buffer() 172136: DRSCAN
num_fields: 1
Debug: 712932 297014 commands.c:202 jtag_build_buffer() 172104:
fields[0].out_value[32]: 0x8004C000
Debug: 712933 297014 ft2232.c:499 move_to_state() 172120:
start=RUN/IDLE goal=DRSHIFT
Debug: 712934 297014 ft2232.c:438 clock_tms() 172112: mpsse cmd=4b,
tms_bits = 0x00000001, bit_count=3
Debug: 712935 297014 ft2232.c:448 clock_tms() 172136: tap_set_state(DRSELECT)
Debug: 712936 297014 ft2232.c:448 clock_tms() 172136: tap_set_state(DRCAPTURE)
Debug: 712937 297014 ft2232.c:448 clock_tms() 172136: tap_set_state(DRSHIFT)
Debug: 712938 297014 ft2232.c:1122 ft2232_add_scan() 172144: finish via PAUSE
Debug: 712939 297015 ft2232.c:438 clock_tms() 172112: mpsse cmd=6b,
tms_bits = 0x00000001, bit_count=2
Debug: 712940 297015 ft2232.c:448 clock_tms() 172136: tap_set_state(DREXIT1)
Debug: 712941 297015 ft2232.c:448 clock_tms() 172136: tap_set_state(DRPAUSE)
Debug: 712942 297015 ft2232.c:499 move_to_state() 172120:
start=DRPAUSE goal=RUN/IDLE
Debug: 712943 297015 ft2232.c:438 clock_tms() 172112: mpsse cmd=4b,
tms_bits = 0x00000003, bit_count=3
Debug: 712944 297015 ft2232.c:448 clock_tms() 172136: tap_set_state(DREXIT2)
Debug: 712945 297015 ft2232.c:448 clock_tms() 172136: tap_set_state(DRUPDATE)
Debug: 712946 297015 ft2232.c:448 clock_tms() 172136: tap_set_state(RUN/IDLE)
Debug: 712947 297015 ft2232.c:1974 ft2232_execute_scan() 172136: DR
scan, 32 bits, end in RUN/IDLE
Debug: 712948 297015 ft2232.c:809 ft2232_send_and_recv() 172152: write
buffer (size 49):
Debug: 712949 297015 ft2232.c:784 ft2232_debug_dump_buffer() 172128:
4b 03 03 1b 03 0e 4b 02 03 4b 02 01 19 03 00 70
Debug: 712950 297015 ft2232.c:784 ft2232_debug_dump_buffer() 172128:
00 44 1f 4b 02 83 4b 03 03 1b 03 0a 4b 02 03 4b
Debug: 712951 297015 ft2232.c:784 ft2232_debug_dump_buffer() 172128:
02 01 39 02 00 00 c0 04 3b 06 80 6b 01 81 4b 02
Debug: 712952 297015 ft2232.c:790 ft2232_debug_dump_buffer() 172168: 03
Info : 712953 297016 ft2232.c:853 ft2232_send_and_recv() 172128:
inter: 0.000124, inter2: 0.000125 end: 0.001498
Debug: 712954 297016 ft2232.c:874 ft2232_send_and_recv() 172136: read
buffer (0 retries): 5 bytes
Debug: 712955 297016 ft2232.c:790 ft2232_debug_dump_buffer() 172160:
08 c0 00 00 00
Debug: 712956 297016 commands.c:248 jtag_read_buffer() 172088:
fields[0].in_value[32]: 0x0000C008
Debug: 712957 297016 ft2232.c:1933 ft2232_execute_scan() 172160: DRSCAN type:3
Debug: 712958 297016 commands.c:189 jtag_build_buffer() 172136: DRSCAN
num_fields: 1
Debug: 712959 297016 commands.c:202 jtag_build_buffer() 172104:
fields[0].out_value[32]: 0x8004C000
Debug: 712960 297016 ft2232.c:499 move_to_state() 172120:
start=RUN/IDLE goal=DRSHIFT
Debug: 712961 297016 ft2232.c:438 clock_tms() 172112: mpsse cmd=4b,
tms_bits = 0x00000001, bit_count=3
Debug: 712962 297016 ft2232.c:448 clock_tms() 172136: tap_set_state(DRSELECT)
Debug: 712963 297016 ft2232.c:448 clock_tms() 172136: tap_set_state(DRCAPTURE)
Debug: 712964 297016 ft2232.c:448 clock_tms() 172136: tap_set_state(DRSHIFT)
Debug: 712965 297016 ft2232.c:1122 ft2232_add_scan() 172144: finish via PAUSE
Debug: 712966 297016 ft2232.c:438 clock_tms() 172112: mpsse cmd=6b,
tms_bits = 0x00000001, bit_count=2
Debug: 712967 297016 ft2232.c:448 clock_tms() 172136: tap_set_state(DREXIT1)
Debug: 712968 297016 ft2232.c:448 clock_tms() 172136: tap_set_state(DRPAUSE)
Debug: 712969 297016 ft2232.c:499 move_to_state() 172120:
start=DRPAUSE goal=RUN/IDLE
Debug: 712970 297016 ft2232.c:438 clock_tms() 172112: mpsse cmd=4b,
tms_bits = 0x00000003, bit_count=3
Debug: 712971 297016 ft2232.c:448 clock_tms() 172136: tap_set_state(DREXIT2)
Debug: 712972 297016 ft2232.c:448 clock_tms() 172136: tap_set_state(DRUPDATE)
Debug: 712973 297016 ft2232.c:448 clock_tms() 172136: tap_set_state(RUN/IDLE)
Debug: 712974 297016 ft2232.c:1974 ft2232_execute_scan() 172136: DR
scan, 32 bits, end in RUN/IDLE
Debug: 712975 297016 ft2232.c:809 ft2232_send_and_recv() 172152: write
buffer (size 18):
Debug: 712976 297016 ft2232.c:784 ft2232_debug_dump_buffer() 172128:
4b 02 01 39 02 00 00 c0 04 3b 06 80 6b 01 81 4b
Debug: 712977 297016 ft2232.c:790 ft2232_debug_dump_buffer() 172168: 02 03
Info : 712978 297018 ft2232.c:853 ft2232_send_and_recv() 172128:
inter: 0.000126, inter2: 0.000126 end: 0.001683
Debug: 712979 297018 ft2232.c:874 ft2232_send_and_recv() 172136: read
buffer (0 retries): 5 bytes
Debug: 712980 297018 ft2232.c:790 ft2232_debug_dump_buffer() 172160:
08 c0 04 80 20
Debug: 712981 297018 commands.c:248 jtag_read_buffer() 172088:
fields[0].in_value[32]: 0x4004C008
Debug: 712982 297018 ft2232.c:1933 ft2232_execute_scan() 172160: IRSCAN type:2
Debug: 712983 297018 commands.c:189 jtag_build_buffer() 172136: IRSCAN
num_fields: 1
Debug: 712984 297018 commands.c:202 jtag_build_buffer() 172104:
fields[0].out_value[5]: 0x0E
Debug: 712985 297018 ft2232.c:499 move_to_state() 172120:
start=RUN/IDLE goal=IRSHIFT
Debug: 712986 297018 ft2232.c:438 clock_tms() 172112: mpsse cmd=4b,
tms_bits = 0x00000003, bit_count=4
Debug: 712987 297018 ft2232.c:448 clock_tms() 172136: tap_set_state(DRSELECT)
Debug: 712988 297018 ft2232.c:448 clock_tms() 172136: tap_set_state(IRSELECT)
Debug: 712989 297018 ft2232.c:448 clock_tms() 172136: tap_set_state(IRCAPTURE)
Debug: 712990 297018 ft2232.c:448 clock_tms() 172136: tap_set_state(IRSHIFT)
Debug: 712991 297018 ft2232.c:1122 ft2232_add_scan() 172136: finish without read
Debug: 712992 297018 ft2232.c:438 clock_tms() 172112: mpsse cmd=4b,
tms_bits = 0x00000003, bit_count=3
Debug: 712993 297018 ft2232.c:448 clock_tms() 172136: tap_set_state(IREXIT1)
Debug: 712994 297018 ft2232.c:448 clock_tms() 172136: tap_set_state(IRUPDATE)
Debug: 712995 297018 ft2232.c:448 clock_tms() 172136: tap_set_state(RUN/IDLE)
Debug: 712996 297018 ft2232.c:1974 ft2232_execute_scan() 172136: IR
scan, 5 bits, end in RUN/IDLE
Debug: 712997 297018 ft2232.c:1933 ft2232_execute_scan() 172160: DRSCAN type:2
Debug: 712998 297018 commands.c:189 jtag_build_buffer() 172136: DRSCAN
num_fields: 2
Debug: 712999 297018 commands.c:202 jtag_build_buffer() 172104:
fields[0].out_value[1]: 0x00
Debug: 713000 297018 commands.c:202 jtag_build_buffer() 172104:
fields[1].out_value[32]: 0x8FA40018
Debug: 713001 297018 ft2232.c:499 move_to_state() 172120:
start=RUN/IDLE goal=DRSHIFT
Debug: 713002 297018 ft2232.c:438 clock_tms() 172112: mpsse cmd=4b,
tms_bits = 0x00000001, bit_count=3
Debug: 713003 297018 ft2232.c:448 clock_tms() 172136: tap_set_state(DRSELECT)
Debug: 713004 297018 ft2232.c:448 clock_tms() 172136: tap_set_state(DRCAPTURE)
Debug: 713005 297018 ft2232.c:448 clock_tms() 172136: tap_set_state(DRSHIFT)
Debug: 713006 297018 ft2232.c:1122 ft2232_add_scan() 172136: finish without read
Debug: 713007 297018 ft2232.c:438 clock_tms() 172112: mpsse cmd=4b,
tms_bits = 0x00000003, bit_count=3
Debug: 713008 297018 ft2232.c:448 clock_tms() 172136: tap_set_state(DREXIT1)
Debug: 713009 297018 ft2232.c:448 clock_tms() 172136: tap_set_state(DRUPDATE)
Debug: 713010 297018 ft2232.c:448 clock_tms() 172136: tap_set_state(RUN/IDLE)
Debug: 713011 297018 ft2232.c:1974 ft2232_execute_scan() 172136: DR
scan, 33 bits, end in RUN/IDLE
Debug: 713012 297018 ft2232.c:1933 ft2232_execute_scan() 172160: IRSCAN type:2
Debug: 713013 297018 commands.c:189 jtag_build_buffer() 172136: IRSCAN
num_fields: 1
Debug: 713014 297018 commands.c:202 jtag_build_buffer() 172104:
fields[0].out_value[5]: 0x0A
Debug: 713015 297018 ft2232.c:499 move_to_state() 172120:
start=RUN/IDLE goal=IRSHIFT
Debug: 713016 297018 ft2232.c:438 clock_tms() 172112: mpsse cmd=4b,
tms_bits = 0x00000003, bit_count=4
Debug: 713017 297018 ft2232.c:448 clock_tms() 172136: tap_set_state(DRSELECT)
Debug: 713018 297018 ft2232.c:448 clock_tms() 172136: tap_set_state(IRSELECT)
Debug: 713019 297018 ft2232.c:448 clock_tms() 172136: tap_set_state(IRCAPTURE)
Debug: 713020 297018 ft2232.c:448 clock_tms() 172136: tap_set_state(IRSHIFT)
Debug: 713021 297018 ft2232.c:1122 ft2232_add_scan() 172136: finish without read
Debug: 713022 297018 ft2232.c:438 clock_tms() 172112: mpsse cmd=4b,
tms_bits = 0x00000003, bit_count=3
Debug: 713023 297019 ft2232.c:448 clock_tms() 172136: tap_set_state(IREXIT1)
Debug: 713024 297019 ft2232.c:448 clock_tms() 172136: tap_set_state(IRUPDATE)
Debug: 713025 297019 ft2232.c:448 clock_tms() 172136: tap_set_state(RUN/IDLE)
Debug: 713026 297019 ft2232.c:1974 ft2232_execute_scan() 172136: IR
scan, 5 bits, end in RUN/IDLE
Debug: 713027 297019 ft2232.c:1933 ft2232_execute_scan() 172160: DRSCAN type:3
Debug: 713028 297019 commands.c:189 jtag_build_buffer() 172136: DRSCAN
num_fields: 1
Debug: 713029 297019 commands.c:202 jtag_build_buffer() 172104:
fields[0].out_value[32]: 0x8004C000
Debug: 713030 297019 ft2232.c:499 move_to_state() 172120:
start=RUN/IDLE goal=DRSHIFT
Debug: 713031 297019 ft2232.c:438 clock_tms() 172112: mpsse cmd=4b,
tms_bits = 0x00000001, bit_count=3
Debug: 713032 297019 ft2232.c:448 clock_tms() 172136: tap_set_state(DRSELECT)
Debug: 713033 297019 ft2232.c:448 clock_tms() 172136: tap_set_state(DRCAPTURE)
Debug: 713034 297019 ft2232.c:448 clock_tms() 172136: tap_set_state(DRSHIFT)
Debug: 713035 297019 ft2232.c:1122 ft2232_add_scan() 172144: finish via PAUSE
Debug: 713036 297019 ft2232.c:438 clock_tms() 172112: mpsse cmd=6b,
tms_bits = 0x00000001, bit_count=2
Debug: 713037 297019 ft2232.c:448 clock_tms() 172136: tap_set_state(DREXIT1)
Debug: 713038 297019 ft2232.c:448 clock_tms() 172136: tap_set_state(DRPAUSE)
Debug: 713039 297019 ft2232.c:499 move_to_state() 172120:
start=DRPAUSE goal=RUN/IDLE
Debug: 713040 297019 ft2232.c:438 clock_tms() 172112: mpsse cmd=4b,
tms_bits = 0x00000003, bit_count=3
Debug: 713041 297019 ft2232.c:448 clock_tms() 172136: tap_set_state(DREXIT2)
Debug: 713042 297019 ft2232.c:448 clock_tms() 172136: tap_set_state(DRUPDATE)
Debug: 713043 297019 ft2232.c:448 clock_tms() 172136: tap_set_state(RUN/IDLE)
Debug: 713044 297019 ft2232.c:1974 ft2232_execute_scan() 172136: DR
scan, 32 bits, end in RUN/IDLE
Debug: 713045 297019 ft2232.c:809 ft2232_send_and_recv() 172152: write
buffer (size 49):
Debug: 713046 297019 ft2232.c:784 ft2232_debug_dump_buffer() 172128:
4b 03 03 1b 03 0e 4b 02 03 4b 02 01 19 03 00 30
Debug: 713047 297019 ft2232.c:784 ft2232_debug_dump_buffer() 172128:
00 48 1f 4b 02 83 4b 03 03 1b 03 0a 4b 02 03 4b
Debug: 713048 297019 ft2232.c:784 ft2232_debug_dump_buffer() 172128:
02 01 39 02 00 00 c0 04 3b 06 80 6b 01 81 4b 02
Debug: 713049 297019 ft2232.c:790 ft2232_debug_dump_buffer() 172168: 03
Info : 713050 297020 ft2232.c:853 ft2232_send_and_recv() 172128:
inter: 0.000201, inter2: 0.000202 end: 0.001452
Debug: 713051 297020 ft2232.c:874 ft2232_send_and_recv() 172136: read
buffer (0 retries): 5 bytes
Debug: 713052 297020 ft2232.c:790 ft2232_debug_dump_buffer() 172160:
08 c0 00 00 00
Debug: 713053 297020 commands.c:248 jtag_read_buffer() 172088:
fields[0].in_value[32]: 0x0000C008
Debug: 713054 297020 ft2232.c:1933 ft2232_execute_scan() 172160: DRSCAN type:3
Debug: 713055 297020 commands.c:189 jtag_build_buffer() 172136: DRSCAN
num_fields: 1
Debug: 713056 297020 commands.c:202 jtag_build_buffer() 172104:
fields[0].out_value[32]: 0x8004C000
Debug: 713057 297020 ft2232.c:499 move_to_state() 172120:
start=RUN/IDLE goal=DRSHIFT
Debug: 713058 297020 ft2232.c:438 clock_tms() 172112: mpsse cmd=4b,
tms_bits = 0x00000001, bit_count=3
Debug: 713059 297020 ft2232.c:448 clock_tms() 172136: tap_set_state(DRSELECT)
Debug: 713060 297020 ft2232.c:448 clock_tms() 172136: tap_set_state(DRCAPTURE)
Debug: 713061 297020 ft2232.c:448 clock_tms() 172136: tap_set_state(DRSHIFT)
Debug: 713062 297020 ft2232.c:1122 ft2232_add_scan() 172144: finish via PAUSE
Debug: 713063 297020 ft2232.c:438 clock_tms() 172112: mpsse cmd=6b,
tms_bits = 0x00000001, bit_count=2
Debug: 713064 297020 ft2232.c:448 clock_tms() 172136: tap_set_state(DREXIT1)
Debug: 713065 297020 ft2232.c:448 clock_tms() 172136: tap_set_state(DRPAUSE)
Debug: 713066 297020 ft2232.c:499 move_to_state() 172120:
start=DRPAUSE goal=RUN/IDLE
Debug: 713067 297020 ft2232.c:438 clock_tms() 172112: mpsse cmd=4b,
tms_bits = 0x00000003, bit_count=3
Debug: 713068 297020 ft2232.c:448 clock_tms() 172136: tap_set_state(DREXIT2)
Debug: 713069 297020 ft2232.c:448 clock_tms() 172136: tap_set_state(DRUPDATE)
Debug: 713070 297020 ft2232.c:448 clock_tms() 172136: tap_set_state(RUN/IDLE)
Debug: 713071 297020 ft2232.c:1974 ft2232_execute_scan() 172136: DR
scan, 32 bits, end in RUN/IDLE
Debug: 713072 297020 ft2232.c:809 ft2232_send_and_recv() 172152: write
buffer (size 18):
Debug: 713073 297020 ft2232.c:784 ft2232_debug_dump_buffer() 172128:
4b 02 01 39 02 00 00 c0 04 3b 06 80 6b 01 81 4b
Debug: 713074 297020 ft2232.c:790 ft2232_debug_dump_buffer() 172168: 02 03
Error: 713075 301022 ft2232.c:584 ft2232_read() 172120: couldn't read
enough bytes from FT2232 device (0 < 5)
Error: 713076 301022 ft2232.c:839 ft2232_send_and_recv() 172152:
couldn't read from FT2232
Error: 713077 301022 mips_ejtag.c:115 mips_ejtag_drscan_32() 172168:
register read failed
Debug: 713078 301022 log.c:437 keep_alive() 172064: keep_alive() was
not invoked in the 1000ms timelimit (4158). This may cause trouble
with GDB connections.
Debug: 713080 301022 ft2232.c:1933 ft2232_execute_scan() 172160: IRSCAN type:2
Debug: 713081 301022 commands.c:189 jtag_build_buffer() 172136: IRSCAN
num_fields: 1
Debug: 713082 301022 commands.c:202 jtag_build_buffer() 172104:
fields[0].out_value[5]: 0x0E
Debug: 713083 301022 ft2232.c:499 move_to_state() 172120:
start=RUN/IDLE goal=IRSHIFT
Debug: 713084 301022 ft2232.c:438 clock_tms() 172112: mpsse cmd=4b,
tms_bits = 0x00000003, bit_count=4
Debug: 713085 301022 ft2232.c:448 clock_tms() 172136: tap_set_state(DRSELECT)
Debug: 713086 301022 ft2232.c:448 clock_tms() 172136: tap_set_state(IRSELECT)
Debug: 713087 301022 ft2232.c:448 clock_tms() 172136: tap_set_state(IRCAPTURE)
Debug: 713088 301022 ft2232.c:448 clock_tms() 172136: tap_set_state(IRSHIFT)
Debug: 713089 301022 ft2232.c:1122 ft2232_add_scan() 172136: finish without read
Debug: 713090 301022 ft2232.c:438 clock_tms() 172112: mpsse cmd=4b,
tms_bits = 0x00000003, bit_count=3
Debug: 713091 301022 ft2232.c:448 clock_tms() 172136: tap_set_state(IREXIT1)
Debug: 713092 301022 ft2232.c:448 clock_tms() 172136: tap_set_state(IRUPDATE)
Debug: 713093 301022 ft2232.c:448 clock_tms() 172136: tap_set_state(RUN/IDLE)
Debug: 713094 301022 ft2232.c:1974 ft2232_execute_scan() 172136: IR
scan, 5 bits, end in RUN/IDLE
Debug: 713095 301022 ft2232.c:1933 ft2232_execute_scan() 172160: DRSCAN type:2
Debug: 713096 301022 commands.c:189 jtag_build_buffer() 172136: DRSCAN
num_fields: 2
Debug: 713097 301022 commands.c:202 jtag_build_buffer() 172104:
fields[0].out_value[1]: 0x00
Debug: 713098 301022 commands.c:202 jtag_build_buffer() 172104:
fields[1].out_value[32]: 0x10800005
Debug: 713099 301022 ft2232.c:499 move_to_state() 172120:
start=RUN/IDLE goal=DRSHIFT
Debug: 713100 301022 ft2232.c:438 clock_tms() 172112: mpsse cmd=4b,
tms_bits = 0x00000001, bit_count=3
Debug: 713101 301022 ft2232.c:448 clock_tms() 172136: tap_set_state(DRSELECT)
Debug: 713102 301022 ft2232.c:448 clock_tms() 172136: tap_set_state(DRCAPTURE)
Debug: 713103 301022 ft2232.c:448 clock_tms() 172136: tap_set_state(DRSHIFT)
Debug: 713104 301022 ft2232.c:1122 ft2232_add_scan() 172136: finish without read
Debug: 713105 301022 ft2232.c:438 clock_tms() 172112: mpsse cmd=4b,
tms_bits = 0x00000003, bit_count=3
Debug: 713106 301022 ft2232.c:448 clock_tms() 172136: tap_set_state(DREXIT1)
Debug: 713107 301022 ft2232.c:448 clock_tms() 172136: tap_set_state(DRUPDATE)
Debug: 713108 301022 ft2232.c:448 clock_tms() 172136: tap_set_state(RUN/IDLE)
Debug: 713109 301022 ft2232.c:1974 ft2232_execute_scan() 172136: DR
scan, 33 bits, end in RUN/IDLE
Debug: 713110 301022 ft2232.c:1933 ft2232_execute_scan() 172160: IRSCAN type:2
Debug: 713111 301022 commands.c:189 jtag_build_buffer() 172136: IRSCAN
num_fields: 1
Debug: 713112 301022 commands.c:202 jtag_build_buffer() 172104:
fields[0].out_value[5]: 0x0A
Debug: 713113 301022 ft2232.c:499 move_to_state() 172120:
start=RUN/IDLE goal=IRSHIFT
Debug: 713114 301022 ft2232.c:438 clock_tms() 172112: mpsse cmd=4b,
tms_bits = 0x00000003, bit_count=4
Debug: 713115 301022 ft2232.c:448 clock_tms() 172136: tap_set_state(DRSELECT)
Debug: 713116 301022 ft2232.c:448 clock_tms() 172136: tap_set_state(IRSELECT)
Debug: 713117 301022 ft2232.c:448 clock_tms() 172136: tap_set_state(IRCAPTURE)
Debug: 713118 301022 ft2232.c:448 clock_tms() 172136: tap_set_state(IRSHIFT)
Debug: 713119 301022 ft2232.c:1122 ft2232_add_scan() 172136: finish without read
Debug: 713120 301022 ft2232.c:438 clock_tms() 172112: mpsse cmd=4b,
tms_bits = 0x00000003, bit_count=3
Debug: 713121 301022 ft2232.c:448 clock_tms() 172136: tap_set_state(IREXIT1)
Debug: 713122 301022 ft2232.c:448 clock_tms() 172136: tap_set_state(IRUPDATE)
Debug: 713123 301022 ft2232.c:448 clock_tms() 172136: tap_set_state(RUN/IDLE)
Debug: 713124 301022 ft2232.c:1974 ft2232_execute_scan() 172136: IR
scan, 5 bits, end in RUN/IDLE
Debug: 713125 301022 ft2232.c:1933 ft2232_execute_scan() 172160: DRSCAN type:3
Debug: 713126 301022 commands.c:189 jtag_build_buffer() 172136: DRSCAN
num_fields: 1
Debug: 713127 301022 commands.c:202 jtag_build_buffer() 172104:
fields[0].out_value[32]: 0x8004C000
Debug: 713128 301022 ft2232.c:499 move_to_state() 172120:
start=RUN/IDLE goal=DRSHIFT
Debug: 713129 301022 ft2232.c:438 clock_tms() 172112: mpsse cmd=4b,
tms_bits = 0x00000001, bit_count=3
Debug: 713130 301022 ft2232.c:448 clock_tms() 172136: tap_set_state(DRSELECT)
Debug: 713131 301022 ft2232.c:448 clock_tms() 172136: tap_set_state(DRCAPTURE)
Debug: 713132 301022 ft2232.c:448 clock_tms() 172136: tap_set_state(DRSHIFT)
Debug: 713133 301022 ft2232.c:1122 ft2232_add_scan() 172144: finish via PAUSE
Debug: 713134 301022 ft2232.c:438 clock_tms() 172112: mpsse cmd=6b,
tms_bits = 0x00000001, bit_count=2
Debug: 713135 301022 ft2232.c:448 clock_tms() 172136: tap_set_state(DREXIT1)
Debug: 713136 301022 ft2232.c:448 clock_tms() 172136: tap_set_state(DRPAUSE)
Debug: 713137 301022 ft2232.c:499 move_to_state() 172120:
start=DRPAUSE goal=RUN/IDLE
Debug: 713138 301022 ft2232.c:438 clock_tms() 172112: mpsse cmd=4b,
tms_bits = 0x00000003, bit_count=3
Debug: 713139 301022 ft2232.c:448 clock_tms() 172136: tap_set_state(DREXIT2)
Debug: 713140 301022 ft2232.c:448 clock_tms() 172136: tap_set_state(DRUPDATE)
Debug: 713141 301022 ft2232.c:448 clock_tms() 172136: tap_set_state(RUN/IDLE)
Debug: 713142 301022 ft2232.c:1974 ft2232_execute_scan() 172136: DR
scan, 32 bits, end in RUN/IDLE
Debug: 713143 301023 ft2232.c:809 ft2232_send_and_recv() 172152: write
buffer (size 49):
Debug: 713144 301023 ft2232.c:784 ft2232_debug_dump_buffer() 172128:
4b 03 03 1b 03 0e 4b 02 03 4b 02 01 19 03 00 0a
Debug: 713145 301023 ft2232.c:784 ft2232_debug_dump_buffer() 172128:
00 00 21 4b 02 03 4b 03 03 1b 03 0a 4b 02 03 4b
Debug: 713146 301023 ft2232.c:784 ft2232_debug_dump_buffer() 172128:
02 01 39 02 00 00 c0 04 3b 06 80 6b 01 81 4b 02
Debug: 713147 301023 ft2232.c:790 ft2232_debug_dump_buffer() 172168: 03
Info : 713148 301024 ft2232.c:853 ft2232_send_and_recv() 172128:
inter: 0.000062, inter2: 0.000063 end: 0.001440
Debug: 713149 301024 ft2232.c:874 ft2232_send_and_recv() 172136: read
buffer (0 retries): 5 bytes
Debug: 713150 301024 ft2232.c:790 ft2232_debug_dump_buffer() 172160:
08 c0 00 00 00
Debug: 713151 301024 commands.c:248 jtag_read_buffer() 172088:
fields[0].in_value[32]: 0x0000C008
Debug: 713152 301024 ft2232.c:1933 ft2232_execute_scan() 172160: DRSCAN type:3
Debug: 713153 301024 commands.c:189 jtag_build_buffer() 172136: DRSCAN
num_fields: 1
Debug: 713154 301024 commands.c:202 jtag_build_buffer() 172104:
fields[0].out_value[32]: 0x8004C000
Debug: 713155 301024 ft2232.c:499 move_to_state() 172120:
start=RUN/IDLE goal=DRSHIFT
Debug: 713156 301024 ft2232.c:438 clock_tms() 172112: mpsse cmd=4b,
tms_bits = 0x00000001, bit_count=3
Debug: 713157 301024 ft2232.c:448 clock_tms() 172136: tap_set_state(DRSELECT)
Debug: 713158 301024 ft2232.c:448 clock_tms() 172136: tap_set_state(DRCAPTURE)
Debug: 713159 301024 ft2232.c:448 clock_tms() 172136: tap_set_state(DRSHIFT)
Debug: 713160 301024 ft2232.c:1122 ft2232_add_scan() 172144: finish via PAUSE
Debug: 713161 301024 ft2232.c:438 clock_tms() 172112: mpsse cmd=6b,
tms_bits = 0x00000001, bit_count=2
Debug: 713162 301024 ft2232.c:448 clock_tms() 172136: tap_set_state(DREXIT1)
Debug: 713163 301024 ft2232.c:448 clock_tms() 172136: tap_set_state(DRPAUSE)
Debug: 713164 301024 ft2232.c:499 move_to_state() 172120:
start=DRPAUSE goal=RUN/IDLE
Debug: 713165 301024 ft2232.c:438 clock_tms() 172112: mpsse cmd=4b,
tms_bits = 0x00000003, bit_count=3
Debug: 713166 301024 ft2232.c:448 clock_tms() 172136: tap_set_state(DREXIT2)
Debug: 713167 301024 ft2232.c:448 clock_tms() 172136: tap_set_state(DRUPDATE)
Debug: 713168 301024 ft2232.c:448 clock_tms() 172136: tap_set_state(RUN/IDLE)
Debug: 713169 301024 ft2232.c:1974 ft2232_execute_scan() 172136: DR
scan, 32 bits, end in RUN/IDLE
Debug: 713170 301024 ft2232.c:809 ft2232_send_and_recv() 172152: write
buffer (size 18):
Debug: 713171 301024 ft2232.c:784 ft2232_debug_dump_buffer() 172128:
4b 02 01 39 02 00 00 c0 04 3b 06 80 6b 01 81 4b
Debug: 713172 301024 ft2232.c:790 ft2232_debug_dump_buffer() 172168: 02 03
Info : 713173 301026 ft2232.c:853 ft2232_send_and_recv() 172128:
inter: 0.000168, inter2: 0.000169 end: 0.001792
Debug: 713174 301026 ft2232.c:874 ft2232_send_and_recv() 172136: read
buffer (0 retries): 5 bytes
Debug: 713175 301026 ft2232.c:790 ft2232_debug_dump_buffer() 172160:
08 c0 04 80 20
Debug: 713176 301026 commands.c:248 jtag_read_buffer() 172088:
fields[0].in_value[32]: 0x4004C008
Debug: 713177 301026 ft2232.c:1933 ft2232_execute_scan() 172160: IRSCAN type:2
Debug: 713178 301026 commands.c:189 jtag_build_buffer() 172136: IRSCAN
num_fields: 1
Debug: 713179 301026 commands.c:202 jtag_build_buffer() 172104:
fields[0].out_value[5]: 0x0E
Debug: 713180 301026 ft2232.c:499 move_to_state() 172120:
start=RUN/IDLE goal=IRSHIFT
Debug: 713181 301026 ft2232.c:438 clock_tms() 172112: mpsse cmd=4b,
tms_bits = 0x00000003, bit_count=4
Debug: 713182 301026 ft2232.c:448 clock_tms() 172136: tap_set_state(DRSELECT)
Debug: 713183 301026 ft2232.c:448 clock_tms() 172136: tap_set_state(IRSELECT)
Debug: 713184 301026 ft2232.c:448 clock_tms() 172136: tap_set_state(IRCAPTURE)
Debug: 713185 301026 ft2232.c:448 clock_tms() 172136: tap_set_state(IRSHIFT)
Debug: 713186 301026 ft2232.c:1122 ft2232_add_scan() 172136: finish without read
Debug: 713187 301026 ft2232.c:438 clock_tms() 172112: mpsse cmd=4b,
tms_bits = 0x00000003, bit_count=3
Debug: 713188 301026 ft2232.c:448 clock_tms() 172136: tap_set_state(IREXIT1)
Debug: 713189 301026 ft2232.c:448 clock_tms() 172136: tap_set_state(IRUPDATE)
Debug: 713190 301026 ft2232.c:448 clock_tms() 172136: tap_set_state(RUN/IDLE)
Debug: 713191 301026 ft2232.c:1974 ft2232_execute_scan() 172136: IR
scan, 5 bits, end in RUN/IDLE
Debug: 713192 301026 ft2232.c:1933 ft2232_execute_scan() 172160: DRSCAN type:2
Debug: 713193 301026 commands.c:189 jtag_build_buffer() 172136: DRSCAN
num_fields: 2
Debug: 713194 301026 commands.c:202 jtag_build_buffer() 172104:
fields[0].out_value[1]: 0x00
Debug: 713195 301026 commands.c:202 jtag_build_buffer() 172104:
fields[1].out_value[32]: 0x8FBF0054
Debug: 713196 301026 ft2232.c:499 move_to_state() 172120:
start=RUN/IDLE goal=DRSHIFT
Debug: 713197 301026 ft2232.c:438 clock_tms() 172112: mpsse cmd=4b,
tms_bits = 0x00000001, bit_count=3
Debug: 713198 301026 ft2232.c:448 clock_tms() 172136: tap_set_state(DRSELECT)
Debug: 713199 301026 ft2232.c:448 clock_tms() 172136: tap_set_state(DRCAPTURE)
Debug: 713200 301026 ft2232.c:448 clock_tms() 172136: tap_set_state(DRSHIFT)
Debug: 713201 301026 ft2232.c:1122 ft2232_add_scan() 172136: finish without read
Debug: 713202 301026 ft2232.c:438 clock_tms() 172112: mpsse cmd=4b,
tms_bits = 0x00000003, bit_count=3
Debug: 713203 301026 ft2232.c:448 clock_tms() 172136: tap_set_state(DREXIT1)
Debug: 713204 301026 ft2232.c:448 clock_tms() 172136: tap_set_state(DRUPDATE)
Debug: 713205 301026 ft2232.c:448 clock_tms() 172136: tap_set_state(RUN/IDLE)
Debug: 713206 301026 ft2232.c:1974 ft2232_execute_scan() 172136: DR
scan, 33 bits, end in RUN/IDLE
Debug: 713207 301026 ft2232.c:1933 ft2232_execute_scan() 172160: IRSCAN type:2
Debug: 713208 301026 commands.c:189 jtag_build_buffer() 172136: IRSCAN
num_fields: 1
Debug: 713209 301026 commands.c:202 jtag_build_buffer() 172104:
fields[0].out_value[5]: 0x0A
Debug: 713210 301026 ft2232.c:499 move_to_state() 172120:
start=RUN/IDLE goal=IRSHIFT
Debug: 713211 301026 ft2232.c:438 clock_tms() 172112: mpsse cmd=4b,
tms_bits = 0x00000003, bit_count=4
Debug: 713212 301026 ft2232.c:448 clock_tms() 172136: tap_set_state(DRSELECT)
Debug: 713213 301026 ft2232.c:448 clock_tms() 172136: tap_set_state(IRSELECT)
Debug: 713214 301026 ft2232.c:448 clock_tms() 172136: tap_set_state(IRCAPTURE)
Debug: 713215 301026 ft2232.c:448 clock_tms() 172136: tap_set_state(IRSHIFT)
Debug: 713216 301026 ft2232.c:1122 ft2232_add_scan() 172136: finish without read
Debug: 713217 301026 ft2232.c:438 clock_tms() 172112: mpsse cmd=4b,
tms_bits = 0x00000003, bit_count=3
Debug: 713218 301026 ft2232.c:448 clock_tms() 172136: tap_set_state(IREXIT1)
Debug: 713219 301026 ft2232.c:448 clock_tms() 172136: tap_set_state(IRUPDATE)
Debug: 713220 301026 ft2232.c:448 clock_tms() 172136: tap_set_state(RUN/IDLE)
Debug: 713221 301026 ft2232.c:1974 ft2232_execute_scan() 172136: IR
scan, 5 bits, end in RUN/IDLE
Debug: 713222 301026 ft2232.c:1933 ft2232_execute_scan() 172160: DRSCAN type:3
Debug: 713223 301026 commands.c:189 jtag_build_buffer() 172136: DRSCAN
num_fields: 1
Debug: 713224 301026 commands.c:202 jtag_build_buffer() 172104:
fields[0].out_value[32]: 0x8004C000
Debug: 713225 301026 ft2232.c:499 move_to_state() 172120:
start=RUN/IDLE goal=DRSHIFT
Debug: 713226 301026 ft2232.c:438 clock_tms() 172112: mpsse cmd=4b,
tms_bits = 0x00000001, bit_count=3
Debug: 713227 301026 ft2232.c:448 clock_tms() 172136: tap_set_state(DRSELECT)
Debug: 713228 301026 ft2232.c:448 clock_tms() 172136: tap_set_state(DRCAPTURE)
Debug: 713229 301026 ft2232.c:448 clock_tms() 172136: tap_set_state(DRSHIFT)
Debug: 713230 301026 ft2232.c:1122 ft2232_add_scan() 172144: finish via PAUSE
Debug: 713231 301026 ft2232.c:438 clock_tms() 172112: mpsse cmd=6b,
tms_bits = 0x00000001, bit_count=2
Debug: 713232 301026 ft2232.c:448 clock_tms() 172136: tap_set_state(DREXIT1)
Debug: 713233 301026 ft2232.c:448 clock_tms() 172136: tap_set_state(DRPAUSE)
Debug: 713234 301026 ft2232.c:499 move_to_state() 172120:
start=DRPAUSE goal=RUN/IDLE
Debug: 713235 301026 ft2232.c:438 clock_tms() 172112: mpsse cmd=4b,
tms_bits = 0x00000003, bit_count=3
Debug: 713236 301026 ft2232.c:448 clock_tms() 172136: tap_set_state(DREXIT2)
Debug: 713237 301026 ft2232.c:448 clock_tms() 172136: tap_set_state(DRUPDATE)
Debug: 713238 301026 ft2232.c:448 clock_tms() 172136: tap_set_state(RUN/IDLE)
Debug: 713239 301026 ft2232.c:1974 ft2232_execute_scan() 172136: DR
scan, 32 bits, end in RUN/IDLE
Debug: 713240 301026 ft2232.c:809 ft2232_send_and_recv() 172152: write
buffer (size 49):
Debug: 713241 301026 ft2232.c:784 ft2232_debug_dump_buffer() 172128:
4b 03 03 1b 03 0e 4b 02 03 4b 02 01 19 03 00 a8
Debug: 713242 301026 ft2232.c:784 ft2232_debug_dump_buffer() 172128:
00 7e 1f 4b 02 83 4b 03 03 1b 03 0a 4b 02 03 4b
Debug: 713243 301026 ft2232.c:784 ft2232_debug_dump_buffer() 172128:
02 01 39 02 00 00 c0 04 3b 06 80 6b 01 81 4b 02
Debug: 713244 301026 ft2232.c:790 ft2232_debug_dump_buffer() 172168: 03
Info : 713245 301028 ft2232.c:853 ft2232_send_and_recv() 172128:
inter: 0.000135, inter2: 0.000136 end: 0.001513
Debug: 713246 301028 ft2232.c:874 ft2232_send_and_recv() 172136: read
buffer (0 retries): 5 bytes

--
libftdi - see http://www.intra2net.com/en/developer/libftdi for details.
To unsubscribe send a mail to libftdi+unsubscribe@xxxxxxxxxxxxxxxxxxxxxxx   

Current Thread