nfcpy

How to fix the 'Time out, the Target has not answered' error?

Asked by imueller

Hello,
I am trying to setup llcp-based connection-oriented data transfer between two identical PN533 USB devices.
Client-side: Raspberry Pi, DL533N USB adapter, python 2.7 with libusb1 and pyserial, libusb-1.0-0, nfcpy trunk
Server-side: Win7, DL533N USB adapter, python 2.7.11 with libusb1 and pyserial, nfcpy trunk, libusb1.dll

Both PN533 devices successfully run the tagtool.py example but I do not manage to set up a stable llcp connection. The following error shows up [nfc.llcp.llc] ProtocolError('unrecoverable NFC-DEP error in attention request',) when running llcp-test-client/server - typically on the server-side. I tried different tests and arguments on both, client and server side, e.g. increased lto or listen-time but did not find a configuration that works. I turned on debugging and it looks like a timeout issue but I did not find sufficient documentation to solve the problem myself.

May I ask for a pointer or two to get the llcp connection working reliably?

The server is started with: python llcp-test-server.py -d nfc.clf -f server.log
The client is started with: python llcp-test-client.py --test 01 -d nfc.clf -f client.log

Please note that the two devices are placed next to each other so that I do not have to physically move them to establish a connection.

The server logfile contains:
2016-03-22 17:21:46,312 [main] enable debug output for 'nfc.clf'
2016-03-22 17:21:46,312 [nfc.clf] searching for reader on path usb
2016-03-22 17:21:46,312 [nfc.clf.transport] using libusb-1.0.20
2016-03-22 17:21:46,312 [nfc.clf.transport] path matches '^(usb|)$'
2016-03-22 17:21:47,000 [nfc.clf.device] loading pn533 driver for usb:04cc:2533
2016-03-22 17:21:47,140 [nfc.clf.pn533] chipset is a PN533v2.7
2016-03-22 17:21:47,171 [nfc.clf.pn533] no eeprom attached
2016-03-22 17:21:47,171 [nfc.clf.pn533] no eeprom attached
2016-03-22 17:21:47,171 [nfc.clf.pn533] write analog settings for Type A
2016-03-22 17:21:47,171 [nfc.clf] using SensorID StickID PN533v2.7 at usb:003:003
2016-03-22 17:21:47,171 [nfc.clf] connect('llcp',)
2016-03-22 17:21:47,171 [main] bound connection-less echo server to port 16
2016-03-22 17:21:47,171 [main] bound connection-mode echo server to port 17
2016-03-22 17:21:47,171 [main] bound connection-mode dump server to port 18
2016-03-22 17:21:47,171 [nfc.clf] connect options after startup: llcp
2016-03-22 17:21:47,187 [nfc.clf] listen 1.000 seconds for DEP
2016-03-22 17:21:47,187 [nfc.clf.pn533] nfca_params 010183419440
2016-03-22 17:21:47,187 [nfc.clf.pn533] nfcf_params 01fe0e62d6c735bc0000000000000000ffff
2016-03-22 17:21:48,217 [nfc.clf] sense 106A atr_req=D400CE07BAE1A6994A1D4F440000003246666D010113020207FF03020003040132070103
2016-03-22 17:21:48,247 [nfc.clf.pn533] running DEP in 106 kbps active mode
2016-03-22 17:21:48,247 [nfc.clf] found 106A atr_req=D400CE07BAE1A6994A1D4F440000003246666D010113020207FF03020003040132070103 atr_res=D50101FEE105892DA02D5354000000083246666D010113020207FF03020003040132070107
2016-03-22 17:21:48,247 [nfc.clf] >>> f006d404001203 timeout=0.1
2016-03-22 17:21:48,279 [nfc.clf] <<< f004d50500 0.031s
2016-03-22 17:21:48,279 [nfc.dep] running as NFC-DEP Initiator 424F active mode MIU=251 RWT=0.077329
2016-03-22 17:21:48,279 [nfc.llcp.llc] LLCP Link established as NFC-DEP Initiator
Local LLCP Settings
  LLCP Version: 1.3
  Link Timeout: 500 ms
  Max Inf Unit: 2175 octet
  Link Service: connection-less and connection-oriented
  Data Protect: secure data transfer mode not supported
  Service List: 0000000000000011 (SDP, LLC)
Remote LLCP Settings
  LLCP Version: 1.3
  Link Timeout: 500 ms
  Max Inf Unit: 2175 octet
  Link Service: connection-less and connection-oriented
  Data Protect: secure data transfer mode is supported
  Service List: 0000000000000011 (SDP, LLC)
2016-03-22 17:21:48,279 [nfc.clf] connected LLC: Local(MIU=2175, LTO=500ms) Remote(MIU=2175, LTO=500ms)
2016-03-22 17:21:48,295 [nfc.clf] >>> 06d406000000 timeout=0.0773286135693
2016-03-22 17:21:48,418 [nfc.clf.pn533] Error 0x01: Time out, the Target has not answered
2016-03-22 17:21:48,418 [nfc.clf] >>> 04d40680 timeout=0.0773286135693
2016-03-22 17:21:48,543 [nfc.clf.pn533] Error 0x01: Time out, the Target has not answered
2016-03-22 17:21:48,543 [nfc.clf] >>> 04d40680 timeout=0.0773286135693
2016-03-22 17:21:48,653 [nfc.clf.pn533] Error 0x01: Time out, the Target has not answered
2016-03-22 17:21:48,653 [nfc.llcp.llc] ProtocolError('unrecoverable NFC-DEP error in attention request',)
2016-03-22 17:21:48,653 [nfc.clf] >>> 03d408 timeout=0.1
2016-03-22 17:21:48,778 [nfc.clf.pn533] Error 0x01: Time out, the Target has not answered
2016-03-22 17:21:48,778 [main] close connection-mode dump server socket
2016-03-22 17:21:48,778 [main] close connection-mode echo server socket
2016-03-22 17:21:48,778 [main] close connection-less echo server socket

the client logfile contains:
2016-03-22 17:21:46,855 [main] enable debug output for 'nfc.clf'
2016-03-22 17:21:46,856 [nfc.clf] searching for reader on path usb
2016-03-22 17:21:46,857 [nfc.clf.transport] using libusb-1.0.11
2016-03-22 17:21:46,863 [nfc.clf.transport] path matches '^(usb|)$'
2016-03-22 17:21:46,868 [nfc.clf.device] loading pn533 driver for usb:04cc:2533
2016-03-22 17:21:46,939 [nfc.clf.pn533] chipset is a PN533v2.7
2016-03-22 17:21:46,976 [nfc.clf.pn533] no eeprom attached
2016-03-22 17:21:46,977 [nfc.clf.pn533] no eeprom attached
2016-03-22 17:21:46,977 [nfc.clf.pn533] write analog settings for Type A
2016-03-22 17:21:46,983 [nfc.clf] using SensorID StickID PN533v2.7 at usb:001:004
2016-03-22 17:21:46,984 [nfc.clf] connect('llcp',)
2016-03-22 17:21:46,985 [nfc.clf] connect options after startup: llcp
2016-03-22 17:21:46,995 [nfc.clf] listen 1.000 seconds for DEP
2016-03-22 17:21:46,996 [nfc.clf.pn533] nfca_params 0101988f9240
2016-03-22 17:21:46,996 [nfc.clf.pn533] nfcf_params 01fee105892da02d0000000000000000ffff
2016-03-22 17:21:47,194 [nfc.clf.pn533] activated in 106A active communication mode
2016-03-22 17:21:47,195 [nfc.clf.pn533] 106A send ATR_RES d50101fee105892da02d5354000000083246666d010113020207ff03020003040132070107
2016-03-22 17:21:47,223 [nfc.clf.pn533] 106A rcvd PSL_REQ d404001203
2016-03-22 17:21:47,224 [nfc.clf.pn533] 106A send PSL_RES d50500
2016-03-22 17:21:47,229 [nfc.clf.pn533] set CIU_RxMode to 10100001
2016-03-22 17:21:47,234 [nfc.clf.pn533] send PSL_RES d50500
2016-03-22 17:21:47,243 [nfc.clf.pn533] set CIU_TxMode to 10100001
2016-03-22 17:21:47,262 [nfc.dep] running as NFC-DEP Target 424F active mode MIU=61 RWT=0.077329
2016-03-22 17:21:47,265 [nfc.llcp.llc] LLCP Link established as NFC-DEP Target
Local LLCP Settings
  LLCP Version: 1.3
  Link Timeout: 500 ms
  Max Inf Unit: 2175 octet
  Link Service: connection-less and connection-oriented
  Data Protect: secure data transfer mode is supported
  Service List: 0000000000000011 (SDP, LLC)
Remote LLCP Settings
  LLCP Version: 1.3
  Link Timeout: 500 ms
  Max Inf Unit: 2175 octet
  Link Service: connection-less and connection-oriented
  Data Protect: secure data transfer mode not supported
  Service List: 0000000000000011 (SDP, LLC)
2016-03-22 17:21:47,266 [nfc.clf] connected LLC: Local(MIU=2175, LTO=500ms) Remote(MIU=2175, LTO=500ms)
2016-03-22 17:21:47,271 [nfc.clf] >>> 06d507000000 timeout=0.509705066681
2016-03-22 17:21:47,391 [nfc.clf] <<< 04d40680 0.119s
2016-03-22 17:21:47,392 [nfc.clf] >>> 04d50780 timeout=0.388434886932
2016-03-22 17:21:47,511 [nfc.clf] <<< 04d40680 0.119s
2016-03-22 17:21:47,513 [nfc.clf] >>> 04d50780 timeout=0.267951965332
2016-03-22 17:21:47,633 [nfc.clf] <<< 03d408 0.120s
2016-03-22 17:21:47,634 [nfc.clf] >>> 03d509 timeout=0
2016-03-22 17:21:47,641 [nfc.clf] <<< None 0.007s
2016-03-22 17:21:47,642 [nfc.clf] >>> None timeout=0.99998497963
2016-03-22 17:21:47,747 [nfc.clf] <<< 0.105s

Question information

Language:
English Edit question
Status:
Solved
For:
nfcpy Edit question
Assignee:
No assignee Edit question
Solved by:
Stephen Tiedemann
Solved:
Last query:
Last reply:
Revision history for this message
Stephen Tiedemann (stephen-tiedemann) said : 		#1

One possibility is that the speed change from initial 106 kbps up to 424 kbps does not work. The speed can be fixed to 106 with "--bitrate 106" in the Initiator side , which can be forced with "--mode i". In the logged run you Bad the Windows sie running as Initiator. Could you test this.

Revision history for this message
imueller (ingo-mueller) said : 		#2

Hello Stephen, I had fixed the bitrate and mode for the server and also tried different values for lto, rwt and listen-time for both client and server, the result was the same. However, after closer inspection of my logs, I noticed a different error message when the bitrate is fixed to 106 on client and server side: 'first NFC-DEP frame byte must be F0h for 106A'. Please find a set of example logs below. Thanks for looking into this.

client: python llcp-test-client.py --test 01 -d nfc.clf -f client.log
server: python llcp-test-server.py -d nfc.clf -f server.log --bitrate 106 --mode i -v

client log:

2016-03-23 11:02:36,280 [main] enable debug output for 'nfc.clf'
2016-03-23 11:02:36,282 [nfc.clf] searching for reader on path usb
2016-03-23 11:02:36,283 [nfc.clf.transport] using libusb-1.0.11
2016-03-23 11:02:36,289 [nfc.clf.transport] path matches '^(usb|)$'
2016-03-23 11:02:36,294 [nfc.clf.device] loading pn533 driver for usb:04cc:2533
2016-03-23 11:02:36,365 [nfc.clf.pn533] chipset is a PN533v2.7
2016-03-23 11:02:36,402 [nfc.clf.pn533] no eeprom attached
2016-03-23 11:02:36,403 [nfc.clf.pn533] no eeprom attached
2016-03-23 11:02:36,403 [nfc.clf.pn533] write analog settings for Type A
2016-03-23 11:02:36,408 [nfc.clf] using SensorID StickID PN533v2.7 at usb:001:004
2016-03-23 11:02:36,410 [nfc.clf] connect('llcp',)
2016-03-23 11:02:36,411 [nfc.clf] connect options after startup: llcp
2016-03-23 11:02:36,421 [nfc.clf] listen 1.000 seconds for DEP
2016-03-23 11:02:36,421 [nfc.clf.pn533] nfca_params 010169deec40
2016-03-23 11:02:36,422 [nfc.clf.pn533] nfcf_params 01fe14d6467e4a7f0000000000000000ffff
2016-03-23 11:02:36,459 [nfc.clf.pn533] activated in 106A active communication mode
2016-03-23 11:02:36,459 [nfc.clf.pn533] 106A send ATR_RES d50101fe14d6467e4a7f5354000000083246666d010113020207ff03020003040132070107
2016-03-23 11:02:36,517 [nfc.dep] running as NFC-DEP Target 106A active mode MIU=61 RWT=0.077329
2016-03-23 11:02:36,520 [nfc.llcp.llc] LLCP Link established as NFC-DEP Target
Local LLCP Settings
  LLCP Version: 1.3
  Link Timeout: 500 ms
  Max Inf Unit: 2175 octet
  Link Service: connection-less and connection-oriented
  Data Protect: secure data transfer mode is supported
  Service List: 0000000000000011 (SDP, LLC)
Remote LLCP Settings
  LLCP Version: 1.3
  Link Timeout: 500 ms
  Max Inf Unit: 2175 octet
  Link Service: connection-less and connection-oriented
  Data Protect: secure data transfer mode not supported
  Service List: 0000000000000011 (SDP, LLC)
2016-03-23 11:02:36,521 [nfc.clf] connected LLC: Local(MIU=2175, LTO=500ms) Remote(MIU=2175, LTO=500ms)
2016-03-23 11:02:36,526 [nfc.clf] >>> f006d507000000 timeout=0.509696960449
2016-03-23 11:02:36,548 [nfc.clf] <<< 03d408 0.022s
2016-03-23 11:02:36,549 [nfc.llcp.llc] ProtocolError('first NFC-DEP frame byte must be F0h for 106A',)
2016-03-23 11:02:36,550 [nfc.clf] >>> None timeout=0.999980926514
2016-03-23 11:02:37,525 [main] link running for 1 second, sent/rcvd 1/1 SYMM 1/1
2016-03-23 11:02:37,554 [nfc.clf.pn533] no data received within 1.000 s

server log:

2016-03-23 11:02:35,776 [main] enable debug output for 'nfc.clf'
2016-03-23 11:02:35,776 [nfc.clf] searching for reader on path usb
2016-03-23 11:02:35,792 [nfc.clf.transport] using libusb-1.0.20
2016-03-23 11:02:35,792 [nfc.clf.transport] path matches '^(usb|)$'
2016-03-23 11:02:36,479 [nfc.clf.device] loading pn533 driver for usb:04cc:2533
2016-03-23 11:02:36,588 [nfc.clf.transport] >>> 0000ff00ff00
2016-03-23 11:02:36,588 [nfc.clf.pn533] Diagnose 00000102030405060708090a0b0c0d0e0f101112131415161718191a1b1c1d1e1f202122232425262728292a2b2c2d2e2f303132333435363738393a3b3c3d3e3f404142434445464748494a4b4c4d4e4f505152535455565758595a5b5c5d5e5f606162636465666768696a6b6c6d6e6f707172737475767778797a7b7c7d7e7f808182838485868788898a8b8c8d8e8f909192939495969798999a9b9c9d9e9fa0a1a2a3a4a5a6a7a8a9aaabacadaeafb0b1b2b3b4b5b6b7b8b9babbbcbdbebfc0c1c2c3c4c5c6c7c8c9cacbcccdcecfd0d1d2d3d4d5d6d7d8d9dadbdcdddedfe0e1e2e3e4e5e6e7e8e9eaebecedeeeff0f1f2f3f4f5f6f7f8f9fafbfcfdfeff000102030405 1.000s
2016-03-23 11:02:36,588 [nfc.clf.transport] >>> 0000ffffff0109f6d40000000102030405060708090a0b0c0d0e0f101112131415161718191a1b1c1d1e1f202122232425262728292a2b2c2d2e2f303132333435363738393a3b3c3d3e3f404142434445464748494a4b4c4d4e4f505152535455565758595a5b5c5d5e5f606162636465666768696a6b6c6d6e6f707172737475767778797a7b7c7d7e7f808182838485868788898a8b8c8d8e8f909192939495969798999a9b9c9d9e9fa0a1a2a3a4a5a6a7a8a9aaabacadaeafb0b1b2b3b4b5b6b7b8b9babbbcbdbebfc0c1c2c3c4c5c6c7c8c9cacbcccdcecfd0d1d2d3d4d5d6d7d8d9dadbdcdddedfe0e1e2e3e4e5e6e7e8e9eaebecedeeeff0f1f2f3f4f5f6f7f8f9fafbfcfdfeff0001020304059d00
2016-03-23 11:02:36,604 [nfc.clf.transport] <<< 0000ff00ff00
2016-03-23 11:02:36,619 [nfc.clf.transport] <<< 0000ffffff0109f6d50100000102030405060708090a0b0c0d0e0f101112131415161718191a1b1c1d1e1f202122232425262728292a2b2c2d2e2f303132333435363738393a3b3c3d3e3f404142434445464748494a4b4c4d4e4f505152535455565758595a5b5c5d5e5f606162636465666768696a6b6c6d6e6f707172737475767778797a7b7c7d7e7f808182838485868788898a8b8c8d8e8f909192939495969798999a9b9c9d9e9fa0a1a2a3a4a5a6a7a8a9aaabacadaeafb0b1b2b3b4b5b6b7b8b9babbbcbdbebfc0c1c2c3c4c5c6c7c8c9cacbcccdcecfd0d1d2d3d4d5d6d7d8d9dadbdcdddedfe0e1e2e3e4e5e6e7e8e9eaebecedeeeff0f1f2f3f4f5f6f7f8f9fafbfcfdfeff0001020304059b00
2016-03-23 11:02:36,619 [nfc.clf.pn533] GetFirmwareVersion 0.100s
2016-03-23 11:02:36,619 [nfc.clf.transport] >>> 0000ff02fed4022a00
2016-03-23 11:02:36,619 [nfc.clf.transport] <<< 0000ff00ff00
2016-03-23 11:02:36,619 [nfc.clf.transport] <<< 0000ff06fad50333020707e500
2016-03-23 11:02:36,619 [nfc.clf.pn533] chipset is a PN533v2.7
2016-03-23 11:02:36,619 [nfc.clf.pn533] RFConfiguration 0102 0.100s
2016-03-23 11:02:36,619 [nfc.clf.transport] >>> 0000ff04fcd4320102f700
2016-03-23 11:02:36,619 [nfc.clf.transport] <<< 0000ff00ff00
2016-03-23 11:02:36,619 [nfc.clf.transport] <<< 0000ff02fed533f800
2016-03-23 11:02:36,619 [nfc.clf.pn533] RFConfiguration 02000b0a 0.100s
2016-03-23 11:02:36,619 [nfc.clf.transport] >>> 0000ff06fad43202000b0ae300
2016-03-23 11:02:36,619 [nfc.clf.transport] <<< 0000ff00ff00
2016-03-23 11:02:36,619 [nfc.clf.transport] <<< 0000ff02fed533f800
2016-03-23 11:02:36,619 [nfc.clf.pn533] RFConfiguration 0400 0.100s
2016-03-23 11:02:36,619 [nfc.clf.transport] >>> 0000ff04fcd4320400f600
2016-03-23 11:02:36,634 [nfc.clf.transport] <<< 0000ff00ff00
2016-03-23 11:02:36,634 [nfc.clf.transport] <<< 0000ff02fed533f800
2016-03-23 11:02:36,634 [nfc.clf.pn533] RFConfiguration 05010001 0.100s
2016-03-23 11:02:36,634 [nfc.clf.transport] >>> 0000ff06fad43205010001f300
2016-03-23 11:02:36,634 [nfc.clf.transport] <<< 0000ff00ff00
2016-03-23 11:02:36,634 [nfc.clf.transport] <<< 0000ff02fed533f800
2016-03-23 11:02:36,634 [nfc.clf.pn533] SetParameters 00 0.100s
2016-03-23 11:02:36,634 [nfc.clf.transport] >>> 0000ff03fdd412001a00
2016-03-23 11:02:36,634 [nfc.clf.transport] <<< 0000ff00ff00
2016-03-23 11:02:36,634 [nfc.clf.transport] <<< 0000ff02fed5131800
2016-03-23 11:02:36,634 [nfc.clf.pn533] ReadRegister a000 0.250s
2016-03-23 11:02:36,634 [nfc.clf.transport] >>> 0000ff04fcd406a0008600
2016-03-23 11:02:36,634 [nfc.clf.transport] <<< 0000ff00ff00
2016-03-23 11:02:36,651 [nfc.clf.transport] <<< 0000ff01ff7f8100
2016-03-23 11:02:36,651 [nfc.clf.pn533] no eeprom attached
2016-03-23 11:02:36,651 [nfc.clf.pn533] no eeprom attached
2016-03-23 11:02:36,651 [nfc.clf.pn533] write analog settings for Type A
2016-03-23 11:02:36,651 [nfc.clf.pn533] RFConfiguration 0a5af43f114d85616f266287 0.100s
2016-03-23 11:02:36,651 [nfc.clf.transport] >>> 0000ff0ef2d4320a5af43f114d85616f266287a100
2016-03-23 11:02:36,651 [nfc.clf.transport] <<< 0000ff00ff00
2016-03-23 11:02:36,651 [nfc.clf.transport] <<< 0000ff02fed533f800
2016-03-23 11:02:36,651 [nfc.clf] using SensorID StickID PN533v2.7 at usb:003:005
2016-03-23 11:02:36,651 [nfc.clf] connect('llcp',)
2016-03-23 11:02:36,651 [main] bound connection-less echo server to port 16
2016-03-23 11:02:36,651 [main] bound connection-mode echo server to port 17
2016-03-23 11:02:36,651 [main] bound connection-mode dump server to port 18
2016-03-23 11:02:36,651 [nfc.clf] connect options after startup: llcp
2016-03-23 11:02:36,651 [nfc.clf.pn533] RFConfiguration 0102 0.100s
2016-03-23 11:02:36,651 [nfc.clf.transport] >>> 0000ff04fcd4320102f700
2016-03-23 11:02:36,651 [nfc.clf.transport] <<< 0000ff00ff00
2016-03-23 11:02:36,667 [nfc.clf.transport] <<< 0000ff02fed533f800
2016-03-23 11:02:36,667 [nfc.clf] sense 106A atr_req=D4005037F973A53160BB7AF90000003246666D010113020207FF03020003040132070103
2016-03-23 11:02:36,667 [nfc.clf.pn533] InJumpForPSL 0100065037f973a53160bb7af946666d010113020207ff03020003040132070103 3.000s
2016-03-23 11:02:36,667 [nfc.clf.transport] >>> 0000ff23ddd4460100065037f973a53160bb7af946666d010113020207ff030200030401320701030600
2016-03-23 11:02:36,667 [nfc.clf.transport] <<< 0000ff00ff00
2016-03-23 11:02:36,884 [nfc.clf.transport] <<< 0000ff03fdd54701e300
2016-03-23 11:02:36,884 [nfc.clf.pn533] RFConfiguration 0102 0.100s
2016-03-23 11:02:36,884 [nfc.clf.transport] >>> 0000ff04fcd4320102f700
2016-03-23 11:02:36,901 [nfc.clf.transport] <<< 0000ff00ff00
2016-03-23 11:02:36,901 [nfc.clf.transport] <<< 0000ff02fed533f800
2016-03-23 11:02:36,901 [nfc.clf] sense 106A atr_req=D4005037F973A53160BB7AF90000003246666D010113020207FF03020003040132070103
2016-03-23 11:02:36,901 [nfc.clf.pn533] InJumpForPSL 0100065037f973a53160bb7af946666d010113020207ff03020003040132070103 3.000s
2016-03-23 11:02:36,901 [nfc.clf.transport] >>> 0000ff23ddd4460100065037f973a53160bb7af946666d010113020207ff030200030401320701030600
2016-03-23 11:02:36,901 [nfc.clf.transport] <<< 0000ff00ff00
2016-03-23 11:02:37,118 [nfc.clf.transport] <<< 0000ff03fdd54701e300
2016-03-23 11:02:37,118 [nfc.clf.pn533] RFConfiguration 0102 0.100s
2016-03-23 11:02:37,118 [nfc.clf.transport] >>> 0000ff04fcd4320102f700
2016-03-23 11:02:37,118 [nfc.clf.transport] <<< 0000ff00ff00
2016-03-23 11:02:37,134 [nfc.clf.transport] <<< 0000ff02fed533f800
2016-03-23 11:02:37,134 [nfc.clf.pn533] RFConfiguration 0102 0.100s
2016-03-23 11:02:37,134 [nfc.clf.transport] >>> 0000ff04fcd4320102f700
2016-03-23 11:02:37,134 [nfc.clf.transport] <<< 0000ff00ff00
2016-03-23 11:02:37,134 [nfc.clf.transport] <<< 0000ff02fed533f800
2016-03-23 11:02:37,134 [nfc.clf] sense 106A
2016-03-23 11:02:37,134 [nfc.clf.pn533] InListPassiveTarget 0100 1.000s
2016-03-23 11:02:37,134 [nfc.clf.transport] >>> 0000ff04fcd44a0100e100
2016-03-23 11:02:37,134 [nfc.clf.transport] <<< 0000ff00ff00
2016-03-23 11:02:37,150 [nfc.clf.transport] <<< 0000ff03fdd54b00e000
2016-03-23 11:02:37,150 [nfc.clf.pn533] ReadRegister 6339 0.250s
2016-03-23 11:02:37,150 [nfc.clf.transport] >>> 0000ff04fcd40663398a00
2016-03-23 11:02:37,150 [nfc.clf.transport] <<< 0000ff00ff00
2016-03-23 11:02:37,165 [nfc.clf.transport] <<< 0000ff04fcd5070026fe00
2016-03-23 11:02:37,165 [nfc.clf] found None
2016-03-23 11:02:37,165 [nfc.clf.pn533] RFConfiguration 0102 0.100s
2016-03-23 11:02:37,165 [nfc.clf.transport] >>> 0000ff04fcd4320102f700
2016-03-23 11:02:37,165 [nfc.clf.transport] <<< 0000ff00ff00
2016-03-23 11:02:37,165 [nfc.clf.transport] <<< 0000ff02fed533f800
2016-03-23 11:02:37,243 [nfc.clf] sense 106A
2016-03-23 11:02:37,243 [nfc.clf.pn533] InListPassiveTarget 0100 1.000s
2016-03-23 11:02:37,243 [nfc.clf.transport] >>> 0000ff04fcd44a0100e100
2016-03-23 11:02:37,243 [nfc.clf.transport] <<< 0000ff00ff00
2016-03-23 11:02:37,259 [nfc.clf.transport] <<< 0000ff03fdd54b00e000
2016-03-23 11:02:37,259 [nfc.clf.pn533] ReadRegister 6339 0.250s
2016-03-23 11:02:37,259 [nfc.clf.transport] >>> 0000ff04fcd40663398a00
2016-03-23 11:02:37,259 [nfc.clf.transport] <<< 0000ff00ff00
2016-03-23 11:02:37,259 [nfc.clf.transport] <<< 0000ff04fcd5070026fe00
2016-03-23 11:02:37,259 [nfc.clf] found None
2016-03-23 11:02:37,259 [nfc.clf.pn533] RFConfiguration 0102 0.100s
2016-03-23 11:02:37,259 [nfc.clf.transport] >>> 0000ff04fcd4320102f700
2016-03-23 11:02:37,259 [nfc.clf.transport] <<< 0000ff00ff00
2016-03-23 11:02:37,259 [nfc.clf.transport] <<< 0000ff02fed533f800
2016-03-23 11:02:37,259 [nfc.clf.pn533] RFConfiguration 0102 0.100s
2016-03-23 11:02:37,259 [nfc.clf.transport] >>> 0000ff04fcd4320102f700
2016-03-23 11:02:37,259 [nfc.clf.transport] <<< 0000ff00ff00
2016-03-23 11:02:37,275 [nfc.clf.transport] <<< 0000ff02fed533f800
2016-03-23 11:02:37,275 [nfc.clf] sense 106A atr_req=D4008A18026CAA209F5566430000003246666D010113020207FF03020003040132070103
2016-03-23 11:02:37,275 [nfc.clf.pn533] InJumpForPSL 0100068a18026caa209f55664346666d010113020207ff03020003040132070103 3.000s
2016-03-23 11:02:37,275 [nfc.clf.transport] >>> 0000ff23ddd4460100068a18026caa209f55664346666d010113020207ff03020003040132070103e600
2016-03-23 11:02:37,275 [nfc.clf.transport] <<< 0000ff00ff00
2016-03-23 11:02:37,305 [nfc.clf.transport] <<< 0000ff27d9d547000101fe14d6467e4a7f5354000000083246666d010113020207ff030200030401320701070600
2016-03-23 11:02:37,305 [nfc.clf.pn533] WriteRegister 63013b 0.250s
2016-03-23 11:02:37,305 [nfc.clf.transport] >>> 0000ff05fbd40863013b8500
2016-03-23 11:02:37,305 [nfc.clf.transport] <<< 0000ff00ff00
2016-03-23 11:02:37,305 [nfc.clf.transport] <<< 0000ff03fdd509002200
2016-03-23 11:02:37,305 [nfc.clf.pn533] running DEP in 106 kbps active mode
2016-03-23 11:02:37,305 [nfc.clf] found 106A atr_req=D4008A18026CAA209F5566430000003246666D010113020207FF03020003040132070103 atr_res=D50101FE14D6467E4A7F5354000000083246666D010113020207FF03020003040132070107
2016-03-23 11:02:37,305 [nfc.dep] running as NFC-DEP Initiator 106A active mode MIU=251 RWT=0.077329
2016-03-23 11:02:37,305 [nfc.llcp.llc] LLCP Link established as NFC-DEP Initiator
Local LLCP Settings
  LLCP Version: 1.3
  Link Timeout: 500 ms
  Max Inf Unit: 2175 octet
  Link Service: connection-less and connection-oriented
  Data Protect: secure data transfer mode not supported
  Service List: 0000000000000011 (SDP, LLC)
Remote LLCP Settings
  LLCP Version: 1.3
  Link Timeout: 500 ms
  Max Inf Unit: 2175 octet
  Link Service: connection-less and connection-oriented
  Data Protect: secure data transfer mode is supported
  Service List: 0000000000000011 (SDP, LLC)
2016-03-23 11:02:37,322 [nfc.clf] connected LLC: Local(MIU=2175, LTO=500ms) Remote(MIU=2175, LTO=500ms)
2016-03-23 11:02:37,336 [nfc.clf] >>> f006d406000000 timeout=0.0773286135693
2016-03-23 11:02:37,336 [nfc.clf.pn533] ReadRegister 630263036305 0.250s
2016-03-23 11:02:37,336 [nfc.clf.transport] >>> 0000ff08f8d406630263036305f300
2016-03-23 11:02:37,336 [nfc.clf.transport] <<< 0000ff00ff00
2016-03-23 11:02:37,336 [nfc.clf.transport] <<< 0000ff06fad507008181c35f00
2016-03-23 11:02:37,336 [nfc.clf.pn533] WriteRegister 6302816303816305c3 0.250s
2016-03-23 11:02:37,336 [nfc.clf.transport] >>> 0000ff0bf5d4086302816303816305c32c00
2016-03-23 11:02:37,336 [nfc.clf.transport] <<< 0000ff00ff00
2016-03-23 11:02:37,336 [nfc.clf.transport] <<< 0000ff03fdd509002200
2016-03-23 11:02:37,336 [nfc.clf.pn533] set response timeout 0.102400 sec
2016-03-23 11:02:37,336 [nfc.clf.pn533] RFConfiguration 020a0b0b 0.100s
2016-03-23 11:02:37,336 [nfc.clf.transport] >>> 0000ff06fad432020a0b0bd800
2016-03-23 11:02:37,336 [nfc.clf.transport] <<< 0000ff00ff00
2016-03-23 11:02:37,336 [nfc.clf.transport] <<< 0000ff02fed533f800
2016-03-23 11:02:37,336 [nfc.clf.pn533] InCommunicateThru f006d406000000 0.202s
2016-03-23 11:02:37,336 [nfc.clf.transport] >>> 0000ff09f7d442f006d4060000001a00
2016-03-23 11:02:37,336 [nfc.clf.transport] <<< 0000ff00ff00
2016-03-23 11:02:37,352 [nfc.clf.transport] <<< 0000ff0bf5d54300f0f006d5070000002600
2016-03-23 11:02:37,352 [nfc.clf] <<< f0f006d507000000 0.016s
2016-03-23 11:02:37,352 [nfc.llcp.llc] ProtocolError('NFC-DEP frame length byte must be data length + 1',)
2016-03-23 11:02:37,368 [nfc.clf] >>> f003d408 timeout=0.1
2016-03-23 11:02:37,368 [nfc.clf.pn533] ReadRegister 630263036305 0.250s
2016-03-23 11:02:37,368 [nfc.clf.transport] >>> 0000ff08f8d406630263036305f300
2016-03-23 11:02:37,368 [nfc.clf.transport] <<< 0000ff00ff00
2016-03-23 11:02:37,368 [nfc.clf.transport] <<< 0000ff06fad507008181c35f00
2016-03-23 11:02:37,368 [nfc.clf.pn533] WriteRegister 6302816303816305c3 0.250s
2016-03-23 11:02:37,368 [nfc.clf.transport] >>> 0000ff0bf5d4086302816303816305c32c00
2016-03-23 11:02:37,368 [nfc.clf.transport] <<< 0000ff00ff00
2016-03-23 11:02:37,368 [nfc.clf.transport] <<< 0000ff03fdd509002200
2016-03-23 11:02:37,368 [nfc.clf.pn533] set response timeout 0.102400 sec
2016-03-23 11:02:37,368 [nfc.clf.pn533] RFConfiguration 020a0b0b 0.100s
2016-03-23 11:02:37,368 [nfc.clf.transport] >>> 0000ff06fad432020a0b0bd800
2016-03-23 11:02:37,368 [nfc.clf.transport] <<< 0000ff00ff00
2016-03-23 11:02:37,368 [nfc.clf.transport] <<< 0000ff02fed533f800
2016-03-23 11:02:37,368 [nfc.clf.pn533] InCommunicateThru f003d408 0.202s
2016-03-23 11:02:37,368 [nfc.clf.transport] >>> 0000ff06fad442f003d4081b00
2016-03-23 11:02:37,368 [nfc.clf.transport] <<< 0000ff00ff00
2016-03-23 11:02:37,477 [nfc.clf.transport] <<< 0000ff03fdd54301e700
2016-03-23 11:02:37,477 [nfc.clf.pn533] Error 0x01: Time out, the Target has not answered
2016-03-23 11:02:37,477 [nfc.clf.pn533] RFConfiguration 0102 0.100s
2016-03-23 11:02:37,477 [nfc.clf.transport] >>> 0000ff04fcd4320102f700
2016-03-23 11:02:37,477 [main] close connection-mode dump server socket
2016-03-23 11:02:37,477 [nfc.clf.transport] <<< 0000ff00ff00
2016-03-23 11:02:37,477 [main] close connection-mode echo server socket
2016-03-23 11:02:37,477 [main] close connection-less echo server socket
2016-03-23 11:02:37,493 [nfc.clf.transport] <<< 0000ff02fed533f800

Revision history for this message
Best Stephen Tiedemann (stephen-tiedemann) said : 		#3

Thanks a lot, that pointed to a real bug. Most of it should be fixed with trunk revision 277 but there is still some chance of weired behaviour when switching back and forth between passive/active communication mode and the different speeds. I'll hopefully find that last one after the Easter vacation.

Revision history for this message
Stephen Tiedemann (stephen-tiedemann) said : 		#4

Note that you can, and probably should, usw "--passive-only" activation. Between two native PN533 devices this worked Mord reliable in my experiments.

Revision history for this message
imueller (ingo-mueller) said : 		#5

Success. I managed to connect both PN533 adapters with 106kbits bitrate and run the test cases of the llcp-test-client.py program.
However, there are still a couple of warnings I guess that pop up on the server side. I'll inspect and create another question/bug report if required.

I also noticed that the snep-test-client.py program cannot be run due to a missing error class definition. I had a quick check and noticed that the definition was still in the expected place on the 0.10 branch. This may require an additional bug report. Let me know.

Thanks for your speedy replies.

Revision history for this message
Stephen Tiedemann (stephen-tiedemann) said : 		#6

Thanks for reporting the problem with TestError class. It's been renamed to TestFail while working on encrypted LLCP (while adding a TestSkip error class) and forgotten to change in the other places. Done now.