2012/07/02

[snep]nfcpy v.s. 自作SNEP(PUT/Initiator)

nfcpyをtarget、自作SNEPをinitiatorにして、自分からSNEP PUTさせた。
週末の作業はこれでおしまい。

   1: $ python snep-test-server.py -d nfc.llcp --mode=initiator
   2: enable debug output for module 'nfc.llcp'
   3: searching for a usable reader
   4: searching for a usb bus reader
   5: path match for 'usb' (or no path given)
   6: trying usb:bus-0:\\.\libusb0-0001--0x054c-0x02e1
   7: import nfc.dev.rcs956_usb
   8: chipset is a PN533 version 1.48
   9: using Sony RC-S370/P at usb:bus-0:\\.\libusb0-0001--0x054c-0x02e1
  10: activated a p2p target in 424 kbps active mode
  11: llc cfg {'recv-lto': 2000, 'send-miu': 128, 'send-agf': True, 'send-lto': 1000, 'send-lsc': 2, 'recv-miu': 1024, 'send-wks': 17, 'rcvd-ver': (1, 0)}
  12: LLCP Link established, I'm the DEP Initiator
  13: Local LLCP Settings
  14:   LLCP Version: 1.1
  15:   Link Timeout: 1000 ms
  16:   Max Inf Unit: 1024 octet
  17:   Service List: 0000000000010011
  18: Remote LLCP Settings
  19:   LLCP Version: 1.0
  20:   Link Timeout: 2000 ms
  21:   Max Inf Unit: 128 octet
  22:   Service List: 0000000000010001
  23: snep server bound to port 4
  24: snep server bound to port 16
  25: SEND  0 ->  0 SYMM
  26: RECV  4 ->  1 CONN MIU=128 RW=1 SN=urn:nfc:sn:snep
  27: DLC (4,None) LISTEN enqueue CONNECT PDU
  28: accepting CONNECT from SAP 4
  29: DLC (4,None) LISTEN dequeue CC PDU
  30: SEND  4 ->  4 CC   MIU=128 RW=2
  31: new data link connection (4 <=== 4)
  32: serving snep client on remote sap 4
  33: RECV  4 ->  4 I    N(S)=0 N(R)=0 LEN=17 SDU=10020000000bd101045402656e75656e6f
  34: DLC (4,4) ESTABLISHED enqueue I PDU
  35: default snep server got put request
  36: ndef message length is 11 octets
  37: DLC (4,4) ESTABLISHED voluntary ack DLC  4 <->  4 ESTABLISHED RW(R)=1 V(S)=0 V(SA)=0 RW(L)=2 V(R)=1 V(RA)=0
  38: type is 'urn:nfc:wkt:T', id is ''
  39: DLC (4,4) ESTABLISHED send() DLC  4 <->  4 ESTABLISHED RW(R)=1 V(S)=0 V(SA)=0 RW(L)=2 V(R)=1 V(RA)=1
  40: SEND  4 ->  4 RR   N(R)=1
  41: RECV  4 ->  4 RR   N(R)=0
  42: DLC (4,4) ESTABLISHED enqueue RR PDU
  43: DLC (4,4) ESTABLISHED dequeue I PDU
  44: SEND  4 ->  4 I    N(S)=0 N(R)=1 LEN=6 SDU=108100000000
  45: RECV  4 ->  4 DISC
  46: DLC (4,4) ESTABLISHED enqueue DISC PDU
  47: DLC (4,4) CLOSE_WAIT dequeue DM PDU
  48: DLC (4,4) CLOSE_WAIT close()
  49: SEND  4 ->  4 DM   REASON=0
  50: DLC (4,4) SHUTDOWN close()
  51: in exchange => IOError [Errno 10] rf field not activated in time by active mode peer
  52: shutdown on link disruption
  53: closing service access point 16
  54: shutdown socket DLC 16 <-> None LISTEN RW(R)=None V(S)=0 V(SA)=0 RW(L)=2 V(R)=0 V(RA)=0
  55: DLC (None,None) LISTEN close()
  56: closing service access point 4
  57: nfc.llcp.Error: [EPIPE] Broken pipe
  58: shutdown socket DLC  4 <-> None LISTEN RW(R)=None V(S)=0 V(SA)=0 RW(L)=2 V(R)=0 V(RA)=0
  59: DLC (None,None) SHUTDOWN close()
  60: DLC (None,None) LISTEN close()
  61: closing service access point 1
  62: nfc.llcp.Error: [EPIPE] Broken pipe
  63: closing service access point 0
  64: DLC (None,None) SHUTDOWN close()
  65: llc run thread terminated
  66: shutdown requested
  67: I was the Initiator

まあ、ここに至るまでに何度か失敗しているのだけど、それはいいとしよう。

nfcpyが、終わっても搬送波を出しっ放しになるのが気にくわない。
何か処理が足りてないのだろうか?
エラーっぽいのは、L.51で「搬送波が出てないぞ-」っていわれているところくらいだ。
Active communication modeでやってるからだろうか?

 

   1: activated as p2p target in 424 kbps passive mode
   2: llc cfg {'recv-lto': 2000, 'send-miu': 128, 'send-agf': True, 'send-lto': 1000, 'send-lsc': 2, 'recv-miu': 1024, 'send-wks': 19, 'rcvd-ver': (1, 0)}
   3: LLCP Link established, I'm the DEP Target
   4: Local LLCP Settings
   5:   LLCP Version: 1.1
   6:   Link Timeout: 1000 ms
   7:   Max Inf Unit: 1024 octet
   8:   Service List: 0000000000010011
   9: Remote LLCP Settings
  10:   LLCP Version: 1.0
  11:   Link Timeout: 2000 ms
  12:   Max Inf Unit: 128 octet
  13:   Service List: 0000000000010011
  14: snep server bound to port 4
  15: snep server bound to port 16
  16: RECV  4 ->  1 CONN MIU=128 RW=1 SN=urn:nfc:sn:snep
  17: DLC (4,None) LISTEN enqueue CONNECT PDU
  18: accepting CONNECT from SAP 4
  19: DLC (4,None) LISTEN dequeue CC PDU
  20: new data link connection (4 <=== 4)
  21: SEND  4 ->  4 CC   MIU=128 RW=2
  22: serving snep client on remote sap 4
  23: RECV  4 ->  4 I    N(S)=0 N(R)=0 LEN=21 SDU=10020000000fd101085402656e6869726f39396d61
  24: DLC (4,4) ESTABLISHED enqueue I PDU
  25: default snep server got put request
  26: ndef message length is 15 octets
  27: DLC (4,4) ESTABLISHED voluntary ack DLC  4 <->  4 ESTABLISHED RW(R)=1 V(S)=0 V(SA)=0 RW(L)=2 V(R)=1 V(RA)=0
  28: type is 'urn:nfc:wkt:T', id is ''
  29: SEND  4 ->  4 RR   N(R)=1
  30: DLC (4,4) ESTABLISHED send() DLC  4 <->  4 ESTABLISHED RW(R)=1 V(S)=0 V(SA)=0 RW(L)=2 V(R)=1 V(RA)=1
  31: RECV  4 ->  4 RR   N(R)=0
  32: DLC (4,4) ESTABLISHED enqueue RR PDU
  33: DLC (4,4) ESTABLISHED dequeue I PDU
  34: SEND  4 ->  4 I    N(S)=0 N(R)=1 LEN=6 SDU=108100000000
  35: RECV  4 ->  4 DISC
  36: DLC (4,4) ESTABLISHED enqueue DISC PDU
  37: DLC (4,4) CLOSE_WAIT dequeue DM PDU
  38: DLC (4,4) CLOSE_WAIT close()
  39: SEND  4 ->  4 DM   REASON=0
  40: DLC (4,4) SHUTDOWN close()
  41: libusb0-dll:err [_usb_reap_async] timeout error
  42:  
  43: send_response: IOError no response from pn53x
  44: shutdown on link disruption
  45: closing service access point 16
  46: shutdown socket DLC 16 <-> None LISTEN RW(R)=None V(S)=0 V(SA)=0 RW(L)=2 V(R)=0 V(RA)=0
  47: DLC (None,None) LISTEN close()
  48: closing service access point 4
  49: nfc.llcp.Error: [EPIPE] Broken pipe
  50: shutdown socket DLC  4 <-> None LISTEN RW(R)=None V(S)=0 V(SA)=0 RW(L)=2 V(R)=0 V(RA)=0
  51: DLC (None,None) SHUTDOWN close()
  52: DLC (None,None) LISTEN close()
  53: closing service access point 1
  54: nfc.llcp.Error: [EPIPE] Broken pipe
  55: closing service access point 0
  56: DLC (None,None) SHUTDOWN close()
  57: llc run thread terminated
  58: shutdown requested
  59: I was the Target

これはこれで終わるんだけど、もう1回実行するとR/Wがアプリケーションレベルのエラーを返している。

RC-S956にはResetというコマンドがあって、それを実行すると状態が初期化される。
それを実行してやれば直りそうなんだけどなぁ。
最初のRFConfigurationで怒られてるからその前にResetすればよさそうなのだけど、pn53xのコンストラクタで呼び出して、そのあとrcs956のコンストラクタに戻ってくるようになってるみたい。
Resetコマンドはpn53xには存在しないから投げるようにはできていない。
コンストラクタでこけるから、そのあとに進めない。

でも、見た感じだとrcs956のclose()でResetしてるようだから、それが呼び出せていないだけかもしれん。


ごにょごにょと書いたが、nfcpyを動かそうとしているわけじゃないので、深入りしない。
自作SNEP/LLCPの実装が間違っているという可能性も十分ある。

だから、別の環境でも動かしてみたいのよねぇ。
環境というのは、nfcpyじゃなくて、ということ。

 

まあ、今度考えよう。

0 件のコメント:

コメントを投稿

コメントありがとうございます。
スパムかもしれない、と私が思ったら、
申し訳ないですが勝手に削除することもあります。

注: コメントを投稿できるのは、このブログのメンバーだけです。