Closed mulles closed 1 year ago
hey! we've add issues with the fairphone in the past, may you try NXP tag reader app wit your fairphone and see if you can read the keycard? It will help identify if the issue comes from the hardware or the software https://play.google.com/store/apps/details?id=com.nxp.taginfolite&hl=en_US&gl=US
The NXP App gives me the following:
TagInfo scan: NXP Semiconductors Unknown IC implementing ISO/IEC 14443-4 tag with Java Card 04:84:6C:EA:DE:6A:80
Please be aware that there are no GooglePlayServices installed on /eOS/ only microG. The NXP TagInfo App is unable to save the full report, could be related to the missing GooglePlay.
Any specific info you are looking for? I could provide you screenshots of what the app is displaying?
There are a couple of FOSS apps on F-droid store, the ones I tried didn't output much more informations, any sugguestions (did not try them alll).
Always happy to see Fairphone users in the wild @mulles !
I've run into the same issue, but realize I didn't exhaust all suggestions to find the root cause. Based on the Status app and device logs it was concluded:
The scenario is using NFC in reader mode with an ISO7816-4 card crashes after the first APDU/R-APDU pair is exchanged
In order to confirm, I was suggested to try:
if you have an NFC Yubikey btw you could try that one too, it is also an ISO7816-4 tag so it should also crash if it is indeed a general firmware issue
I'll give that a try and update here this week (not my phone I have FP2 without NFC). Sharing as you might try that in the meantime if you have a Yubikey
@hesterbruikman thx for the information. You answer confuses me a little, but what I take from it, is that probably the FP3 has NFC Firmware issue causing it to be incompatible with ISO7816-4 cards?
I happen to have a yubikey with NFC. I will check with it in about a week and report back. I do not have access to it right now.
Looking forward to resolve this together for FP3 users.
@bitgamma @hesterbruikman just had the chance to try the same keycard on another Fairphone 3 (I have a FP3+), worked without any problems. So I guess it is a software issue. As I am running IMG-e-0.18-q-20210827132307-dev-FP3.zip (https://gitlab.e.foundation/e/os/releases/-/releases) based an android Q ASOP/LineageOS(https://wiki.lineageos.org/devices/FP3/) and not the usual operating system coming with the phone!
Here isadb logcat
while reproducing the error:
10-16 13:33:49.463 18631 18656 I libnfc_nci: [INFO:nfc_ncif.cc(458)] NFC received ntf gid:1
10-16 13:33:49.463 18631 18656 I libnfc_nci: [INFO:nfa_dm_discover.cc(2890)] returning from nfa_dm_p2p_prio_logic Disable p2p_prio_logic
10-16 13:33:49.463 18631 18656 I libnfc_nci: [INFO:nfc_main.cc(326)] nfc_set_state 4 (IDLE)->5 (OPEN)
10-16 13:33:49.463 18631 18656 I libnfc_nci: [INFO:nfc_ncif.cc(925)] nfc_ncif_proc_activate:33 10, mode:0x00
10-16 13:33:49.463 18631 18656 I libnfc_nci: [INFO:nfa_dm_discover.cc(694)] event:0x4004
10-16 13:33:49.463 18631 18656 I libnfc_nci: [INFO:nfa_dm_discover.cc(2518)] state: W4_HOST_SELECT (3), event: ACTIVATED_NTF(5) disc_flags: 0x9
10-16 13:33:49.463 18631 18656 I libnfc_nci: [INFO:nfa_dm_discover.cc(1685)] old_state: W4_HOST_SELECT (3), new_state: POLL_ACTIVE (4) disc_flags: 0x9
10-16 13:33:49.463 18631 18656 I libnfc_nci: [INFO:nfa_dm_discover.cc(1192)] tech_n_mode:0x0, proto:0x4
10-16 13:33:49.463 18631 18656 I libnfc_nci: [INFO:nfa_dm_discover.cc(674)] tech_n_mode:0x0, protocol:0x4, disc_mask:0x4
10-16 13:33:49.463 18631 18656 I libnfc_nci: [INFO:nfa_dm_discover.cc(1320)] activated_protocol:0x4, activated_handle: 0x1
10-16 13:33:49.463 18631 18656 I libnfc_nci: [INFO:nfa_dm_act.cc(1557)] event:0x01
10-16 13:33:49.463 18631 18656 I libnfc_nci: [INFO:nfa_rw_main.cc(179)] nfa_rw_handle_event event: NFA_RW_ACTIVATE_NTF_EVT (0x501), flags: 00000031
10-16 13:33:49.463 18631 18656 I libnfc_nci: [INFO:nfa_rw_act.cc(2538)] nfa_rw_activate_ntf
10-16 13:33:49.463 18631 18656 I libnfc_nci: [INFO:rw_main.cc(206)] RW_SetActivatedTagType protocol:4, technology:0, SAK:32
10-16 13:33:49.463 18631 18656 I libnfc_nci: [INFO:rw_t4t.cc(1909)] rw_t4t_select
10-16 13:33:49.464 18631 18656 I libnfc_nci: [INFO:nfa_dm_act.cc(1709)] status:0x0
10-16 13:33:49.464 18631 18656 I libnfc_nci: [INFO:nfa_rw_act.cc(448)] nfa_rw_handle_sleep_wakeup_rsp; Legacy presence check performed
10-16 13:33:49.464 18631 18656 I libnfc_nci: [INFO:nfa_sys_ptim.cc(156)] nfa_sys_ptim_stop_timer 0x7aad54f4f8
10-16 13:33:49.464 18631 18656 I libnfc_nci: [INFO:nfa_sys_ptim.cc(163)] ptim timer stop
10-16 13:33:49.464 18631 18656 I libnfc_nci: [INFO:nfa_rw_act.cc(192)] Stopped presence check timer (if started)
10-16 13:33:49.464 18631 18656 I libnfc_nci: [INFO:nfa_dm_act.cc(1378)] event:0xF
10-16 13:33:49.464 18631 18656 I libnfc_nci: [INFO:NativeNfcManager.cpp(258)] nfaConnectionCallback: event= 15
10-16 13:33:49.464 18631 18656 I libnfc_nci: [INFO:NativeNfcManager.cpp(582)] nfaConnectionCallback: NFA_PRESENCE_CHECK_EVT
10-16 13:33:49.464 18631 18656 I libnfc_nci: [INFO:nfa_dm_discover.cc(2571)] new state: POLL_ACTIVE (4), disc_flags: 0x1
10-16 13:33:49.466 724 18659 D NxpTml : PN54X - Read requested.....
10-16 13:33:49.466 724 18659 D NxpTml : PN54X - Invoking I2C Read.....
10-16 13:33:49.590 18631 22644 I libnfc_nci: [INFO:NativeNfcTag.cpp(1313)] nativeNfcTag_doPresenceCheck
10-16 13:33:49.590 18631 22644 I libnfc_nci: [INFO:nfa_rw_api.cc(192)] NFA_RwPresenceCheck
10-16 13:33:49.590 18631 18656 I libnfc_nci: [INFO:nfa_sys_main.cc(77)] NFA got event 0x0500
10-16 13:33:49.590 18631 18656 I libnfc_nci: [INFO:nfa_rw_main.cc(179)] nfa_rw_handle_event event: NFA_RW_OP_REQUEST_EVT (0x500), flags: 00000021
10-16 13:33:49.591 18631 18656 I libnfc_nci: [INFO:nfa_rw_act.cc(2806)] nfa_rw_handle_op_req: op=0x03
10-16 13:33:49.591 18631 18656 I libnfc_nci: [INFO:nfa_sys_ptim.cc(156)] nfa_sys_ptim_stop_timer 0x7aad54f4f8
10-16 13:33:49.591 18631 18656 I libnfc_nci: [INFO:nfa_sys_ptim.cc(163)] ptim timer stop
10-16 13:33:49.591 18631 18656 I libnfc_nci: [INFO:nfa_rw_act.cc(192)] Stopped presence check timer (if started)
10-16 13:33:49.591 18631 18656 I libnfc_nci: [INFO:nfc_main.cc(1127)] NFC_Deactivate 5 (OPEN) deactivate_type:1
10-16 13:33:49.591 18631 18656 I libnfc_nci: [INFO:nfc_main.cc(326)] nfc_set_state 5 (OPEN)->6 (CLOSING)
10-16 13:33:49.592 18631 18656 I libnfc_nci: [INFO:nfc_main.cc(1145)] act_protocol 4 credits:1/1
10-16 13:33:49.592 18631 18656 I libnfc_nci: [INFO:NfcAdaptation.cc(616)] NfcAdaptation::HalWrite
10-16 13:33:49.592 724 724 D NxpExtns: find found MIFARE_READER_ENABLE=(0x1)
10-16 13:33:49.592 724 724 D NxpHal : NAME_MIFARE_READER_ENABLE : 1
10-16 13:33:49.592 724 724 D : sem_timedwait() succeeded
10-16 13:33:49.592 724 18660 D NxpTml : PN54X - Write requested.....
10-16 13:33:49.593 724 18660 D NxpTml : PN54X - Invoking I2C Write.....
10-16 13:33:49.597 724 18659 D NxpTml : PN54X - I2C Read successful.....len = 4
10-16 13:33:49.597 724 18659 D NxpTml : Delay Read if write thread is busy
10-16 13:33:49.597 724 18660 D NxpNciX : len = 4 => 21060101
10-16 13:33:49.597 724 18660 D NxpTml : PN54X - I2C Write successful.....
10-16 13:33:49.597 724 18660 D NxpTml : PN54X - Posting Fresh Write message.....
10-16 13:33:49.597 724 18660 D NxpTml : PN54X - Tml Writer Thread Running................
10-16 13:33:49.598 724 18662 D NxpHal : write successful status = 0x0
10-16 13:33:49.600 724 18659 D NxpTml : PN54X - Posting read message.....
10-16 13:33:49.600 724 18659 D NxpNciR : len = 4 <= 41060100
10-16 13:33:49.600 18631 18656 I libnfc_nci: [INFO:nfa_sys_ptim.cc(128)] nfa_sys_ptim_start_timer 0x7aad54e4e0
10-16 13:33:49.601 724 18662 D NxpHal : read successful status = 0x0
10-16 13:33:49.600 18631 18656 I libnfc_nci: [INFO:nfa_sys_ptim.cc(133)] ptim timer start
10-16 13:33:49.602 18631 18656 I libnfc_nci: [INFO:nfc_ncif.cc(417)] NFC received rsp gid:1
10-16 13:33:49.602 724 18659 D NxpTml : PN54X - Read requested.....
10-16 13:33:49.602 724 18659 D NxpTml : PN54X - Invoking I2C Read.....
10-16 13:33:49.602 18631 18656 I libnfc_nci: [INFO:nfa_dm_discover.cc(2890)] returning from nfa_dm_p2p_prio_logic Disable p2p_prio_logic
10-16 13:33:49.603 18631 18656 I libnfc_nci: [INFO:nfc_main.cc(326)] nfc_set_state 6 (CLOSING)->4 (IDLE)
10-16 13:33:49.603 18631 18656 I libnfc_nci: [INFO:rw_t4t.cc(1760)] event = 0x6002
10-16 13:33:49.603 18631 18656 I libnfc_nci: [INFO:nfa_dm_discover.cc(694)] event:0x4005
10-16 13:33:49.603 18631 18656 I libnfc_nci: [INFO:nfa_dm_discover.cc(2518)] state: POLL_ACTIVE (4), event: DEACTIVATE_RSP(7) disc_flags: 0x69
10-16 13:33:49.603 18631 18656 I libnfc_nci: [INFO:nfa_dm_discover.cc(2571)] new state: POLL_ACTIVE (4), disc_flags: 0x49
10-16 13:33:49.604 724 18659 D NxpTml : PN54X - I2C Read successful.....len = 5
10-16 13:33:49.604 724 18659 D NxpTml : PN54X - Posting read message.....
10-16 13:33:49.604 724 18659 D NxpNciR : len = 5 <= 6106020100
10-16 13:33:49.604 724 18662 D NxpHal : read successful status = 0x0
10-16 13:33:49.605 18631 18656 I libnfc_nci: [INFO:nfc_ncif.cc(458)] NFC received ntf gid:1
10-16 13:33:49.605 18631 18656 I libnfc_nci: [INFO:nfa_dm_discover.cc(2890)] returning from nfa_dm_p2p_prio_logic Disable p2p_prio_logic
10-16 13:33:49.606 724 18659 D NxpTml : PN54X - Read requested.....
10-16 13:33:49.606 724 18659 D NxpTml : PN54X - Invoking I2C Read.....
10-16 13:33:49.606 18631 18656 I libnfc_nci: [INFO:nfc_main.cc(326)] nfc_set_state 4 (IDLE)->4 (IDLE)
10-16 13:33:49.606 18631 18656 I libnfc_nci: [INFO:nfa_dm_discover.cc(1662)] nfa_dm_disc_data_cback
10-16 13:33:49.606 18631 18656 I libnfc_nci: [INFO:nfa_dm_discover.cc(694)] event:0x4005
10-16 13:33:49.606 18631 18656 I libnfc_nci: [INFO:nfa_dm_discover.cc(2518)] state: POLL_ACTIVE (4), event: DEACTIVATE_NTF(8) disc_flags: 0x49
10-16 13:33:49.607 18631 18656 I libnfc_nci: [INFO:nfa_sys_ptim.cc(156)] nfa_sys_ptim_stop_timer 0x7aad54e4e0
10-16 13:33:49.607 18631 18656 I libnfc_nci: [INFO:nfa_sys_ptim.cc(163)] ptim timer stop
10-16 13:33:49.607 18631 18656 I libnfc_nci: [INFO:nfa_dm_discover.cc(1361)] activated_handle=1
10-16 13:33:49.607 18631 18656 I libnfc_nci: [INFO:nfa_dm_discover.cc(1365)] for sleep wakeup
10-16 13:33:49.607 18631 18656 I libnfc_nci: [INFO:nfa_dm_discover.cc(1685)] old_state: POLL_ACTIVE (4), new_state: W4_HOST_SELECT (3) disc_flags: 0x9
10-16 13:33:49.607 18631 18656 I libnfc_nci: [INFO:NfcAdaptation.cc(616)] NfcAdaptation::HalWrite
10-16 13:33:49.607 724 724 D NxpExtns: find found MIFARE_READER_ENABLE=(0x1)
10-16 13:33:49.608 724 724 D NxpHal : NAME_MIFARE_READER_ENABLE : 1
10-16 13:33:49.608 724 724 D : sem_timedwait() succeeded
10-16 13:33:49.608 724 18660 D NxpTml : PN54X - Write requested.....
10-16 13:33:49.608 724 18660 D NxpTml : PN54X - Invoking I2C Write.....
10-16 13:33:49.610 724 18660 D NxpNciX : len = 6 => 210403010402
10-16 13:33:49.610 724 18660 D NxpTml : PN54X - I2C Write successful.....
10-16 13:33:49.610 724 18660 D NxpTml : PN54X - Posting Fresh Write message.....
10-16 13:33:49.610 724 18660 D NxpTml : PN54X - Tml Writer Thread Running................
10-16 13:33:49.611 724 18659 D NxpTml : PN54X - I2C Read successful.....len = 4
10-16 13:33:49.611 724 18659 D NxpTml : Delay Read if write thread is busy
10-16 13:33:49.611 724 18662 D NxpHal : write successful status = 0x0
10-16 13:33:49.613 724 18659 D NxpTml : PN54X - Posting read message.....
10-16 13:33:49.613 724 18659 D NxpNciR : len = 4 <= 41040100
10-16 13:33:49.613 724 18662 D NxpHal : read successful status = 0x0
10-16 13:33:49.614 18631 18656 I libnfc_nci: [INFO:nfa_dm_discover.cc(2571)] new state: W4_HOST_SELECT (3), disc_flags: 0x9
10-16 13:33:49.615 18631 18656 I libnfc_nci: [INFO:nfc_ncif.cc(417)] NFC received rsp gid:1
10-16 13:33:49.615 724 18659 D NxpTml : PN54X - Read requested.....
10-16 13:33:49.615 724 18659 D NxpTml : PN54X - Invoking I2C Read.....
10-16 13:33:49.615 18631 18656 I libnfc_nci: [INFO:nfa_dm_discover.cc(694)] event:0x4003
10-16 13:33:49.615 18631 18656 I libnfc_nci: [INFO:nfa_dm_discover.cc(2518)] state: W4_HOST_SELECT (3), event: SELECT_RSP(4) disc_flags: 0x9
10-16 13:33:49.615 18631 18656 I libnfc_nci: [INFO:nfa_dm_discover.cc(2571)] new state: W4_HOST_SELECT (3), disc_flags: 0x9
10-16 13:33:49.619 724 18659 D NxpTml : PN54X - I2C Read successful.....len = 36
10-16 13:33:49.619 724 18659 D NxpTml : PN54X - Posting read message.....
10-16 13:33:49.619 724 18659 D NxpNciR : len = 36 <= 61052101020400FF010C48000704846CEADE6A8001200000000A09787791028073C82110
10-16 13:33:49.619 724 18662 D NxpHal : read successful status = 0x0
10-16 13:33:49.619 724 18662 D NxpHal : NxpNci: RF Interface = ISO-DEP
10-16 13:33:49.619 724 18662 D NxpHal : NxpNci: Protocol = ISO-DEP
10-16 13:33:49.620 724 18662 D NxpHal : NxpNci: Mode = A Passive Poll
10-16 13:33:49.620 724 18659 D NxpTml : PN54X - Read requested.....
10-16 13:33:49.620 724 18659 D NxpTml : PN54X - Invoking I2C Read.....
10-16 13:33:49.620 18631 18656 I libnfc_nci: [INFO:nfc_ncif.cc(458)] NFC received ntf gid:1
10-16 13:33:49.620 18631 18656 I libnfc_nci: [INFO:nfa_dm_discover.cc(2890)] returning from nfa_dm_p2p_prio_logic Disable p2p_prio_logic
10-16 13:33:49.621 18631 18656 I libnfc_nci: [INFO:nfc_main.cc(326)] nfc_set_state 4 (IDLE)->5 (OPEN)
10-16 13:33:49.621 18631 18656 I libnfc_nci: [INFO:nfc_ncif.cc(925)] nfc_ncif_proc_activate:33 10, mode:0x00
10-16 13:33:49.621 18631 18656 I libnfc_nci: [INFO:nfa_dm_discover.cc(694)] event:0x4004
10-16 13:33:49.621 18631 18656 I libnfc_nci: [INFO:nfa_dm_discover.cc(2518)] state: W4_HOST_SELECT (3), event: ACTIVATED_NTF(5) disc_flags: 0x9
10-16 13:33:49.621 18631 18656 I libnfc_nci: [INFO:nfa_dm_discover.cc(1685)] old_state: W4_HOST_SELECT (3), new_state: POLL_ACTIVE (4) disc_flags: 0x9
10-16 13:33:49.621 18631 18656 I libnfc_nci: [INFO:nfa_dm_discover.cc(1192)] tech_n_mode:0x0, proto:0x4
10-16 13:33:49.621 18631 18656 I libnfc_nci: [INFO:nfa_dm_discover.cc(674)] tech_n_mode:0x0, protocol:0x4, disc_mask:0x4
10-16 13:33:49.621 18631 18656 I libnfc_nci: [INFO:nfa_dm_discover.cc(1320)] activated_protocol:0x4, activated_handle: 0x1
10-16 13:33:49.621 18631 18656 I libnfc_nci: [INFO:nfa_dm_act.cc(1557)] event:0x01
10-16 13:33:49.621 18631 18656 I libnfc_nci: [INFO:nfa_rw_main.cc(179)] nfa_rw_handle_event event: NFA_RW_ACTIVATE_NTF_EVT (0x501), flags: 00000031
10-16 13:33:49.621 18631 18656 I libnfc_nci: [INFO:nfa_rw_act.cc(2538)] nfa_rw_activate_ntf
10-16 13:33:49.621 18631 18656 I libnfc_nci: [INFO:rw_main.cc(206)] RW_SetActivatedTagType protocol:4, technology:0, SAK:32
10-16 13:33:49.621 18631 18656 I libnfc_nci: [INFO:rw_t4t.cc(1909)] rw_t4t_select
10-16 13:33:49.621 18631 18656 I libnfc_nci: [INFO:nfa_dm_act.cc(1709)] status:0x0
10-16 13:33:49.621 18631 18656 I libnfc_nci: [INFO:nfa_rw_act.cc(448)] nfa_rw_handle_sleep_wakeup_rsp; Legacy presence check performed
10-16 13:33:49.621 18631 18656 I libnfc_nci: [INFO:nfa_sys_ptim.cc(156)] nfa_sys_ptim_stop_timer 0x7aad54f4f8
10-16 13:33:49.621 18631 18656 I libnfc_nci: [INFO:nfa_sys_ptim.cc(163)] ptim timer stop
10-16 13:33:49.622 18631 18656 I libnfc_nci: [INFO:nfa_rw_act.cc(192)] Stopped presence check timer (if started)
10-16 13:33:49.622 18631 18656 I libnfc_nci: [INFO:nfa_dm_act.cc(1378)] event:0xF
10-16 13:33:49.622 18631 18656 I libnfc_nci: [INFO:NativeNfcManager.cpp(258)] nfaConnectionCallback: event= 15
10-16 13:33:49.622 18631 18656 I libnfc_nci: [INFO:NativeNfcManager.cpp(582)] nfaConnectionCallback: NFA_PRESENCE_CHECK_EVT
10-16 13:33:49.622 18631 18656 I libnfc_nci: [INFO:nfa_dm_discover.cc(2571)] new state: POLL_ACTIVE (4), disc_flags: 0x1
10-16 13:33:49.748 18631 22644 I libnfc_nci: [INFO:NativeNfcTag.cpp(1313)] nativeNfcTag_doPresenceCheck
10-16 13:33:49.748 18631 22644 I libnfc_nci: [INFO:nfa_rw_api.cc(192)] NFA_RwPresenceCheck
10-16 13:33:49.748 18631 18656 I libnfc_nci: [INFO:nfa_sys_main.cc(77)] NFA got event 0x0500
10-16 13:33:49.748 18631 18656 I libnfc_nci: [INFO:nfa_rw_main.cc(179)] nfa_rw_handle_event event: NFA_RW_OP_REQUEST_EVT (0x500), flags: 00000021
10-16 13:33:49.749 18631 18656 I libnfc_nci: [INFO:nfa_rw_act.cc(2806)] nfa_rw_handle_op_req: op=0x03
10-16 13:33:49.749 18631 18656 I libnfc_nci: [INFO:nfa_sys_ptim.cc(156)] nfa_sys_ptim_stop_timer 0x7aad54f4f8
10-16 13:33:49.749 18631 18656 I libnfc_nci: [INFO:nfa_sys_ptim.cc(163)] ptim timer stop
10-16 13:33:49.749 18631 18656 I libnfc_nci: [INFO:nfa_rw_act.cc(192)] Stopped presence check timer (if started)
10-16 13:33:49.750 18631 18656 I libnfc_nci: [INFO:nfc_main.cc(1127)] NFC_Deactivate 5 (OPEN) deactivate_type:1
10-16 13:33:49.750 18631 18656 I libnfc_nci: [INFO:nfc_main.cc(326)] nfc_set_state 5 (OPEN)->6 (CLOSING)
10-16 13:33:49.750 18631 18656 I libnfc_nci: [INFO:nfc_main.cc(1145)] act_protocol 4 credits:1/1
10-16 13:33:49.750 18631 18656 I libnfc_nci: [INFO:NfcAdaptation.cc(616)] NfcAdaptation::HalWrite
10-16 13:33:49.751 724 724 D NxpExtns: find found MIFARE_READER_ENABLE=(0x1)
10-16 13:33:49.752 724 724 D NxpHal : NAME_MIFARE_READER_ENABLE : 1
10-16 13:33:49.752 724 724 D : sem_timedwait() succeeded
10-16 13:33:49.753 724 18660 D NxpTml : PN54X - Write requested.....
10-16 13:33:49.753 724 18660 D NxpTml : PN54X - Invoking I2C Write.....
10-16 13:33:49.755 724 18659 D NxpTml : PN54X - I2C Read successful.....len = 4
10-16 13:33:49.756 724 18659 D NxpTml : Delay Read if write thread is busy
10-16 13:33:49.756 724 18660 D NxpNciX : len = 4 => 21060101
10-16 13:33:49.756 724 18660 D NxpTml : PN54X - I2C Write successful.....
10-16 13:33:49.756 724 18660 D NxpTml : PN54X - Posting Fresh Write message.....
10-16 13:33:49.757 724 18660 D NxpTml : PN54X - Tml Writer Thread Running................
10-16 13:33:49.757 724 18662 D NxpHal : write successful status = 0x0
10-16 13:33:49.758 18631 18656 I libnfc_nci: [INFO:nfa_sys_ptim.cc(128)] nfa_sys_ptim_start_timer 0x7aad54e4e0
10-16 13:33:49.758 18631 18656 I libnfc_nci: [INFO:nfa_sys_ptim.cc(133)] ptim timer start
10-16 13:33:49.760 724 18659 D NxpTml : PN54X - Posting read message.....
10-16 13:33:49.760 724 18659 D NxpNciR : len = 4 <= 41060100
10-16 13:33:49.760 724 18662 D NxpHal : read successful status = 0x0
10-16 13:33:49.762 18631 18656 I libnfc_nci: [INFO:nfc_ncif.cc(417)] NFC received rsp gid:1
10-16 13:33:49.762 724 18659 D NxpTml : PN54X - Read requested.....
10-16 13:33:49.762 724 18659 D NxpTml : PN54X - Invoking I2C Read.....
10-16 13:33:49.762 18631 18656 I libnfc_nci: [INFO:nfa_dm_discover.cc(2890)] returning from nfa_dm_p2p_prio_logic Disable p2p_prio_logic
10-16 13:33:49.762 18631 18656 I libnfc_nci: [INFO:nfc_main.cc(326)] nfc_set_state 6 (CLOSING)->4 (IDLE)
10-16 13:33:49.762 18631 18656 I libnfc_nci: [INFO:rw_t4t.cc(1760)] event = 0x6002
10-16 13:33:49.762 18631 18656 I libnfc_nci: [INFO:nfa_dm_discover.cc(694)] event:0x4005
10-16 13:33:49.762 18631 18656 I libnfc_nci: [INFO:nfa_dm_discover.cc(2518)] state: POLL_ACTIVE (4), event: DEACTIVATE_RSP(7) disc_flags: 0x69
10-16 13:33:49.762 18631 18656 I libnfc_nci: [INFO:nfa_dm_discover.cc(2571)] new state: POLL_ACTIVE (4), disc_flags: 0x49
10-16 13:33:49.763 724 18659 D NxpTml : PN54X - I2C Read successful.....len = 5
10-16 13:33:49.763 724 18659 D NxpTml : PN54X - Posting read message.....
10-16 13:33:49.764 724 18659 D NxpNciR : len = 5 <= 6106020100
10-16 13:33:49.764 724 18662 D NxpHal : read successful status = 0x0
10-16 13:33:49.769 18631 18656 I libnfc_nci: [INFO:nfc_ncif.cc(458)] NFC received ntf gid:1
10-16 13:33:49.769 18631 18656 I libnfc_nci: [INFO:nfa_dm_discover.cc(2890)] returning from nfa_dm_p2p_prio_logic Disable p2p_prio_logic
10-16 13:33:49.769 18631 18656 I libnfc_nci: [INFO:nfc_main.cc(326)] nfc_set_state 4 (IDLE)->4 (IDLE)
10-16 13:33:49.770 18631 18656 I libnfc_nci: [INFO:nfa_dm_discover.cc(1662)] nfa_dm_disc_data_cback
10-16 13:33:49.770 18631 18656 I libnfc_nci: [INFO:nfa_dm_discover.cc(694)] event:0x4005
10-16 13:33:49.770 18631 18656 I libnfc_nci: [INFO:nfa_dm_discover.cc(2518)] state: POLL_ACTIVE (4), event: DEACTIVATE_NTF(8) disc_flags: 0x49
10-16 13:33:49.770 18631 18656 I libnfc_nci: [INFO:nfa_sys_ptim.cc(156)] nfa_sys_ptim_stop_timer 0x7aad54e4e0
10-16 13:33:49.770 18631 18656 I libnfc_nci: [INFO:nfa_sys_ptim.cc(163)] ptim timer stop
10-16 13:33:49.770 18631 18656 I libnfc_nci: [INFO:nfa_dm_discover.cc(1361)] activated_handle=1
10-16 13:33:49.770 18631 18656 I libnfc_nci: [INFO:nfa_dm_discover.cc(1365)] for sleep wakeup
10-16 13:33:49.770 18631 18656 I libnfc_nci: [INFO:nfa_dm_discover.cc(1685)] old_state: POLL_ACTIVE (4), new_state: W4_HOST_SELECT (3) disc_flags: 0x9
10-16 13:33:49.770 18631 18656 I libnfc_nci: [INFO:NfcAdaptation.cc(616)] NfcAdaptation::HalWrite
10-16 13:33:49.772 724 18659 D NxpTml : PN54X - Read requested.....
10-16 13:33:49.772 724 18659 D NxpTml : PN54X - Invoking I2C Read.....
10-16 13:33:49.771 724 724 D NxpExtns: find found MIFARE_READER_ENABLE=(0x1)
10-16 13:33:49.772 724 724 D NxpHal : NAME_MIFARE_READER_ENABLE : 1
10-16 13:33:49.772 724 724 D : sem_timedwait() succeeded
10-16 13:33:49.772 724 18660 D NxpTml : PN54X - Write requested.....
10-16 13:33:49.773 724 18660 D NxpTml : PN54X - Invoking I2C Write.....
10-16 13:33:49.774 724 18660 D NxpNciX : len = 6 => 210403010402
10-16 13:33:49.774 724 18660 D NxpTml : PN54X - I2C Write successful.....
10-16 13:33:49.774 724 18660 D NxpTml : PN54X - Posting Fresh Write message.....
10-16 13:33:49.775 724 18660 D NxpTml : PN54X - Tml Writer Thread Running................
10-16 13:33:49.775 724 18662 D NxpHal : write successful status = 0x0
10-16 13:33:49.775 18631 18656 I libnfc_nci: [INFO:nfa_dm_discover.cc(2571)] new state: W4_HOST_SELECT (3), disc_flags: 0x9
10-16 13:33:49.775 18631 18656 I libnfc_nci: [INFO:nfa_sys_ptim.cc(111)] ptim timer stop
10-16 13:33:49.776 724 18659 D NxpTml : PN54X - I2C Read successful.....len = 4
10-16 13:33:49.776 724 18659 D NxpTml : PN54X - Posting read message.....
10-16 13:33:49.776 724 18659 D NxpNciR : len = 4 <= 41040100
10-16 13:33:49.776 724 18662 D NxpHal : read successful status = 0x0
10-16 13:33:49.777 18631 18656 I libnfc_nci: [INFO:nfc_ncif.cc(417)] NFC received rsp gid:1
10-16 13:33:49.777 18631 18656 I libnfc_nci: [INFO:nfa_dm_discover.cc(694)] event:0x4003
10-16 13:33:49.777 18631 18656 I libnfc_nci: [INFO:nfa_dm_discover.cc(2518)] state: W4_HOST_SELECT (3), event: SELECT_RSP(4) disc_flags: 0x9
10-16 13:33:49.777 18631 18656 I libnfc_nci: [INFO:nfa_dm_discover.cc(2571)] new state: W4_HOST_SELECT (3), disc_flags: 0x9
10-16 13:33:49.778 724 18659 D NxpTml : PN54X - Read requested.....
10-16 13:33:49.778 724 18659 D NxpTml : PN54X - Invoking I2C Read.....
10-16 13:33:49.782 724 18659 D NxpTml : PN54X - I2C Read successful.....len = 36
10-16 13:33:49.782 724 18659 D NxpTml : PN54X - Posting read message.....
10-16 13:33:49.782 724 18659 D NxpNciR : len = 36 <= 61052101020400FF010C48000704846CEADE6A8001200000000A09787791028073C82110
10-16 13:33:49.782 724 18662 D NxpHal : read successful status = 0x0
10-16 13:33:49.782 724 18662 D NxpHal : NxpNci: RF Interface = ISO-DEP
10-16 13:33:49.782 724 18662 D NxpHal : NxpNci: Protocol = ISO-DEP
10-16 13:33:49.782 724 18662 D NxpHal : NxpNci: Mode = A Passive Poll
10-16 13:33:49.783 18631 18656 I libnfc_nci: [INFO:nfc_ncif.cc(458)] NFC received ntf gid:1
10-16 13:33:49.783 724 18659 D NxpTml : PN54X - Read requested.....
10-16 13:33:49.783 724 18659 D NxpTml : PN54X - Invoking I2C Read.....
10-16 13:33:49.783 18631 18656 I libnfc_nci: [INFO:nfa_dm_discover.cc(2890)] returning from nfa_dm_p2p_prio_logic Disable p2p_prio_logic
10-16 13:33:49.783 18631 18656 I libnfc_nci: [INFO:nfc_main.cc(326)] nfc_set_state 4 (IDLE)->5 (OPEN)
10-16 13:33:49.783 18631 18656 I libnfc_nci: [INFO:nfc_ncif.cc(925)] nfc_ncif_proc_activate:33 10, mode:0x00
10-16 13:33:49.783 18631 18656 I libnfc_nci: [INFO:nfa_dm_discover.cc(694)] event:0x4004
10-16 13:33:49.783 18631 18656 I libnfc_nci: [INFO:nfa_dm_discover.cc(2518)] state: W4_HOST_SELECT (3), event: ACTIVATED_NTF(5) disc_flags: 0x9
10-16 13:33:49.783 18631 18656 I libnfc_nci: [INFO:nfa_dm_discover.cc(1685)] old_state: W4_HOST_SELECT (3), new_state: POLL_ACTIVE (4) disc_flags: 0x9
10-16 13:33:49.783 18631 18656 I libnfc_nci: [INFO:nfa_dm_discover.cc(1192)] tech_n_mode:0x0, proto:0x4
10-16 13:33:49.783 18631 18656 I libnfc_nci: [INFO:nfa_dm_discover.cc(674)] tech_n_mode:0x0, protocol:0x4, disc_mask:0x4
10-16 13:33:49.783 18631 18656 I libnfc_nci: [INFO:nfa_dm_discover.cc(1320)] activated_protocol:0x4, activated_handle: 0x1
10-16 13:33:49.783 18631 18656 I libnfc_nci: [INFO:nfa_dm_act.cc(1557)] event:0x01
10-16 13:33:49.783 18631 18656 I libnfc_nci: [INFO:nfa_rw_main.cc(179)] nfa_rw_handle_event event: NFA_RW_ACTIVATE_NTF_EVT (0x501), flags: 00000031
10-16 13:33:49.783 18631 18656 I libnfc_nci: [INFO:nfa_rw_act.cc(2538)] nfa_rw_activate_ntf
10-16 13:33:49.783 18631 18656 I libnfc_nci: [INFO:rw_main.cc(206)] RW_SetActivatedTagType protocol:4, technology:0, SAK:32
10-16 13:33:49.783 18631 18656 I libnfc_nci: [INFO:rw_t4t.cc(1909)] rw_t4t_select
10-16 13:33:49.783 18631 18656 I libnfc_nci: [INFO:nfa_dm_act.cc(1709)] status:0x0
10-16 13:33:49.783 18631 18656 I libnfc_nci: [INFO:nfa_rw_act.cc(448)] nfa_rw_handle_sleep_wakeup_rsp; Legacy presence check performed
10-16 13:33:49.784 18631 18656 I libnfc_nci: [INFO:nfa_sys_ptim.cc(156)] nfa_sys_ptim_stop_timer 0x7aad54f4f8
10-16 13:33:49.784 18631 18656 I libnfc_nci: [INFO:nfa_sys_ptim.cc(163)] ptim timer stop
10-16 13:33:49.784 18631 18656 I libnfc_nci: [INFO:nfa_rw_act.cc(192)] Stopped presence check timer (if started)
10-16 13:33:49.784 18631 18656 I libnfc_nci: [INFO:nfa_dm_act.cc(1378)] event:0xF
10-16 13:33:49.784 18631 18656 I libnfc_nci: [INFO:NativeNfcManager.cpp(258)] nfaConnectionCallback: event= 15
10-16 13:33:49.784 18631 18656 I libnfc_nci: [INFO:NativeNfcManager.cpp(582)] nfaConnectionCallback: NFA_PRESENCE_CHECK_EVT
10-16 13:33:49.784 18631 18656 I libnfc_nci: [INFO:nfa_dm_discover.cc(2571)] new state: POLL_ACTIVE (4), disc_flags: 0x1
10-16 13:33:49.911 18631 22644 I libnfc_nci: [INFO:NativeNfcTag.cpp(1313)] nativeNfcTag_doPresenceCheck
10-16 13:33:49.911 18631 22644 I libnfc_nci: [INFO:nfa_rw_api.cc(192)] NFA_RwPresenceCheck
10-16 13:33:49.912 18631 18656 I libnfc_nci: [INFO:nfa_sys_main.cc(77)] NFA got event 0x0500
10-16 13:33:49.912 18631 18656 I libnfc_nci: [INFO:nfa_rw_main.cc(179)] nfa_rw_handle_event event: NFA_RW_OP_REQUEST_EVT (0x500), flags: 00000021
10-16 13:33:49.912 18631 18656 I libnfc_nci: [INFO:nfa_rw_act.cc(2806)] nfa_rw_handle_op_req: op=0x03
10-16 13:33:49.913 18631 18656 I libnfc_nci: [INFO:nfa_sys_ptim.cc(156)] nfa_sys_ptim_stop_timer 0x7aad54f4f8
10-16 13:33:49.913 18631 18656 I libnfc_nci: [INFO:nfa_sys_ptim.cc(163)] ptim timer stop
10-16 13:33:49.913 18631 18656 I libnfc_nci: [INFO:nfa_rw_act.cc(192)] Stopped presence check timer (if started)
10-16 13:33:49.913 18631 18656 I libnfc_nci: [INFO:nfc_main.cc(1127)] NFC_Deactivate 5 (OPEN) deactivate_type:1
10-16 13:33:49.913 18631 18656 I libnfc_nci: [INFO:nfc_main.cc(326)] nfc_set_state 5 (OPEN)->6 (CLOSING)
10-16 13:33:49.913 18631 18656 I libnfc_nci: [INFO:nfc_main.cc(1145)] act_protocol 4 credits:1/1
10-16 13:33:49.913 18631 18656 I libnfc_nci: [INFO:NfcAdaptation.cc(616)] NfcAdaptation::HalWrite
10-16 13:33:49.913 724 724 D NxpExtns: find found MIFARE_READER_ENABLE=(0x1)
10-16 13:33:49.913 724 724 D NxpHal : NAME_MIFARE_READER_ENABLE : 1
10-16 13:33:49.913 724 724 D : sem_timedwait() succeeded
10-16 13:33:49.914 724 18660 D NxpTml : PN54X - Write requested.....
10-16 13:33:49.914 724 18660 D NxpTml : PN54X - Invoking I2C Write.....
10-16 13:33:49.916 724 18659 D NxpTml : PN54X - I2C Read successful.....len = 4
10-16 13:33:49.916 724 18659 D NxpTml : Delay Read if write thread is busy
10-16 13:33:49.916 724 18660 D NxpNciX : len = 4 => 21060101
10-16 13:33:49.916 724 18660 D NxpTml : PN54X - I2C Write successful.....
10-16 13:33:49.916 724 18660 D NxpTml : PN54X - Posting Fresh Write message.....
10-16 13:33:49.916 724 18660 D NxpTml : PN54X - Tml Writer Thread Running................
10-16 13:33:49.916 724 18662 D NxpHal : write successful status = 0x0
10-16 13:33:49.917 18631 18656 I libnfc_nci: [INFO:nfa_sys_ptim.cc(128)] nfa_sys_ptim_start_timer 0x7aad54e4e0
10-16 13:33:49.917 18631 18656 I libnfc_nci: [INFO:nfa_sys_ptim.cc(133)] ptim timer start
10-16 13:33:49.920 724 18659 D NxpTml : PN54X - Posting read message.....
10-16 13:33:49.920 724 18659 D NxpNciR : len = 4 <= 41060100
10-16 13:33:49.920 724 18662 D NxpHal : read successful status = 0x0
10-16 13:33:49.922 18631 18656 I libnfc_nci: [INFO:nfc_ncif.cc(417)] NFC received rsp gid:1
10-16 13:33:49.922 18631 18656 I libnfc_nci: [INFO:nfa_dm_discover.cc(2890)] returning from nfa_dm_p2p_prio_logic Disable p2p_prio_logic
10-16 13:33:49.922 18631 18656 I libnfc_nci: [INFO:nfc_main.cc(326)] nfc_set_state 6 (CLOSING)->4 (IDLE)
10-16 13:33:49.923 18631 18656 I libnfc_nci: [INFO:rw_t4t.cc(1760)] event = 0x6002
10-16 13:33:49.923 18631 18656 I libnfc_nci: [INFO:nfa_dm_discover.cc(694)] event:0x4005
10-16 13:33:49.923 18631 18656 I libnfc_nci: [INFO:nfa_dm_discover.cc(2518)] state: POLL_ACTIVE (4), event: DEACTIVATE_RSP(7) disc_flags: 0x69
10-16 13:33:49.923 18631 18656 I libnfc_nci: [INFO:nfa_dm_discover.cc(2571)] new state: POLL_ACTIVE (4), disc_flags: 0x49
10-16 13:33:49.925 724 18659 D NxpTml : PN54X - Read requested.....
10-16 13:33:49.925 724 18659 D NxpTml : PN54X - Invoking I2C Read.....
10-16 13:33:49.926 724 18659 D NxpTml : PN54X - I2C Read successful.....len = 5
10-16 13:33:49.926 724 18659 D NxpTml : PN54X - Posting read message.....
10-16 13:33:49.926 724 18659 D NxpNciR : len = 5 <= 6106020100
10-16 13:33:49.926 724 18662 D NxpHal : read successful status = 0x0
10-16 13:33:49.926 18631 18656 I libnfc_nci: [INFO:nfc_ncif.cc(458)] NFC received ntf gid:1
10-16 13:33:49.927 18631 18656 I libnfc_nci: [INFO:nfa_dm_discover.cc(2890)] returning from nfa_dm_p2p_prio_logic Disable p2p_prio_logic
10-16 13:33:49.927 724 18659 D NxpTml : PN54X - Read requested.....
10-16 13:33:49.927 18631 18656 I libnfc_nci: [INFO:nfc_main.cc(326)] nfc_set_state 4 (IDLE)->4 (IDLE)
10-16 13:33:49.927 724 18659 D NxpTml : PN54X - Invoking I2C Read.....
10-16 13:33:49.927 18631 18656 I libnfc_nci: [INFO:nfa_dm_discover.cc(1662)] nfa_dm_disc_data_cback
10-16 13:33:49.927 18631 18656 I libnfc_nci: [INFO:nfa_dm_discover.cc(694)] event:0x4005
10-16 13:33:49.927 18631 18656 I libnfc_nci: [INFO:nfa_dm_discover.cc(2518)] state: POLL_ACTIVE (4), event: DEACTIVATE_NTF(8) disc_flags: 0x49
10-16 13:33:49.927 18631 18656 I libnfc_nci: [INFO:nfa_sys_ptim.cc(156)] nfa_sys_ptim_stop_timer 0x7aad54e4e0
10-16 13:33:49.927 18631 18656 I libnfc_nci: [INFO:nfa_sys_ptim.cc(163)] ptim timer stop
10-16 13:33:49.928 18631 18656 I libnfc_nci: [INFO:nfa_dm_discover.cc(1361)] activated_handle=1
10-16 13:33:49.928 18631 18656 I libnfc_nci: [INFO:nfa_dm_discover.cc(1365)] for sleep wakeup
10-16 13:33:49.928 18631 18656 I libnfc_nci: [INFO:nfa_dm_discover.cc(1685)] old_state: POLL_ACTIVE (4), new_state: W4_HOST_SELECT (3) disc_flags: 0x9
10-16 13:33:49.928 18631 18656 I libnfc_nci: [INFO:NfcAdaptation.cc(616)] NfcAdaptation::HalWrite
10-16 13:33:49.928 724 724 D NxpExtns: find found MIFARE_READER_ENABLE=(0x1)
10-16 13:33:49.928 724 724 D NxpHal : NAME_MIFARE_READER_ENABLE : 1
10-16 13:33:49.929 724 724 D : sem_timedwait() succeeded
10-16 13:33:49.929 724 18660 D NxpTml : PN54X - Write requested.....
10-16 13:33:49.929 724 18660 D NxpTml : PN54X - Invoking I2C Write.....
10-16 13:33:49.931 724 18660 D NxpNciX : len = 6 => 210403010402
10-16 13:33:49.931 724 18660 D NxpTml : PN54X - I2C Write successful.....
10-16 13:33:49.931 724 18660 D NxpTml : PN54X - Posting Fresh Write message.....
10-16 13:33:49.932 724 18660 D NxpTml : PN54X - Tml Writer Thread Running................
10-16 13:33:49.932 724 18662 D NxpHal : write successful status = 0x0
10-16 13:33:49.932 724 18659 D NxpTml : PN54X - I2C Read successful.....len = 4
10-16 13:33:49.932 724 18659 D NxpTml : PN54X - Posting read message.....
10-16 13:33:49.932 724 18659 D NxpNciR : len = 4 <= 41040100
10-16 13:33:49.932 18631 18656 I libnfc_nci: [INFO:nfa_dm_discover.cc(2571)] new state: W4_HOST_SELECT (3), disc_flags: 0x9
10-16 13:33:49.933 724 18662 D NxpHal : read successful status = 0x0
10-16 13:33:49.933 18631 18656 I libnfc_nci: [INFO:nfc_ncif.cc(417)] NFC received rsp gid:1
10-16 13:33:49.934 18631 18656 I libnfc_nci: [INFO:nfa_dm_discover.cc(694)] event:0x4003
10-16 13:33:49.934 18631 18656 I libnfc_nci: [INFO:nfa_dm_discover.cc(2518)] state: W4_HOST_SELECT (3), event: SELECT_RSP(4) disc_flags: 0x9
10-16 13:33:49.934 18631 18656 I libnfc_nci: [INFO:nfa_dm_discover.cc(2571)] new state: W4_HOST_SELECT (3), disc_flags: 0x9
10-16 13:33:49.934 724 18659 D NxpTml : PN54X - Read requested.....
10-16 13:33:49.934 724 18659 D NxpTml : PN54X - Invoking I2C Read.....
10-16 13:33:49.938 724 18659 D NxpTml : PN54X - I2C Read successful.....len = 36
10-16 13:33:49.938 724 18659 D NxpTml : PN54X - Posting read message.....
10-16 13:33:49.938 724 18659 D NxpNciR : len = 36 <= 61052101020400FF010C48000704846CEADE6A8001200000000A09787791028073C82110
10-16 13:33:49.939 724 18662 D NxpHal : read successful status = 0x0
10-16 13:33:49.939 724 18662 D NxpHal : NxpNci: RF Interface = ISO-DEP
10-16 13:33:49.939 724 18662 D NxpHal : NxpNci: Protocol = ISO-DEP
10-16 13:33:49.939 724 18662 D NxpHal : NxpNci: Mode = A Passive Poll
10-16 13:33:49.939 18631 18656 I libnfc_nci: [INFO:nfc_ncif.cc(458)] NFC received ntf gid:1
10-16 13:33:49.939 18631 18656 I libnfc_nci: [INFO:nfa_dm_discover.cc(2890)] returning from nfa_dm_p2p_prio_logic Disable p2p_prio_logic
10-16 13:33:49.939 18631 18656 I libnfc_nci: [INFO:nfc_main.cc(326)] nfc_set_state 4 (IDLE)->5 (OPEN)
10-16 13:33:49.939 18631 18656 I libnfc_nci: [INFO:nfc_ncif.cc(925)] nfc_ncif_proc_activate:33 10, mode:0x00
10-16 13:33:49.939 18631 18656 I libnfc_nci: [INFO:nfa_dm_discover.cc(694)] event:0x4004
10-16 13:33:49.940 18631 18656 I libnfc_nci: [INFO:nfa_dm_discover.cc(2518)] state: W4_HOST_SELECT (3), event: ACTIVATED_NTF(5) disc_flags: 0x9
10-16 13:33:49.940 18631 18656 I libnfc_nci: [INFO:nfa_dm_discover.cc(1685)] old_state: W4_HOST_SELECT (3), new_state: POLL_ACTIVE (4) disc_flags: 0x9
10-16 13:33:49.940 18631 18656 I libnfc_nci: [INFO:nfa_dm_discover.cc(1192)] tech_n_mode:0x0, proto:0x4
10-16 13:33:49.940 18631 18656 I libnfc_nci: [INFO:nfa_dm_discover.cc(674)] tech_n_mode:0x0, protocol:0x4, disc_mask:0x4
10-16 13:33:49.940 18631 18656 I libnfc_nci: [INFO:nfa_dm_discover.cc(1320)] activated_protocol:0x4, activated_handle: 0x1
10-16 13:33:49.940 18631 18656 I libnfc_nci: [INFO:nfa_dm_act.cc(1557)] event:0x01
10-16 13:33:49.940 18631 18656 I libnfc_nci: [INFO:nfa_rw_main.cc(179)] nfa_rw_handle_event event: NFA_RW_ACTIVATE_NTF_EVT (0x501), flags: 00000031
10-16 13:33:49.940 18631 18656 I libnfc_nci: [INFO:nfa_rw_act.cc(2538)] nfa_rw_activate_ntf
10-16 13:33:49.940 18631 18656 I libnfc_nci: [INFO:rw_main.cc(206)] RW_SetActivatedTagType protocol:4, technology:0, SAK:32
10-16 13:33:49.940 724 18659 D NxpTml : PN54X - Read requested.....
10-16 13:33:49.940 724 18659 D NxpTml : PN54X - Invoking I2C Read.....
10-16 13:33:49.940 18631 18656 I libnfc_nci: [INFO:rw_t4t.cc(1909)] rw_t4t_select
10-16 13:33:49.940 18631 18656 I libnfc_nci: [INFO:nfa_dm_act.cc(1709)] status:0x0
10-16 13:33:49.940 18631 18656 I libnfc_nci: [INFO:nfa_rw_act.cc(448)] nfa_rw_handle_sleep_wakeup_rsp; Legacy presence check performed
10-16 13:33:49.940 18631 18656 I libnfc_nci: [INFO:nfa_sys_ptim.cc(156)] nfa_sys_ptim_stop_timer 0x7aad54f4f8
10-16 13:33:49.940 18631 18656 I libnfc_nci: [INFO:nfa_sys_ptim.cc(163)] ptim timer stop
10-16 13:33:49.940 18631 18656 I libnfc_nci: [INFO:nfa_rw_act.cc(192)] Stopped presence check timer (if started)
10-16 13:33:49.940 18631 18656 I libnfc_nci: [INFO:nfa_dm_act.cc(1378)] event:0xF
10-16 13:33:49.940 18631 18656 I libnfc_nci: [INFO:NativeNfcManager.cpp(258)] nfaConnectionCallback: event= 15
10-16 13:33:49.940 18631 18656 I libnfc_nci: [INFO:NativeNfcManager.cpp(582)] nfaConnectionCallback: NFA_PRESENCE_CHECK_EVT
10-16 13:33:49.940 18631 18656 I libnfc_nci: [INFO:nfa_dm_discover.cc(2571)] new state: POLL_ACTIVE (4), disc_flags: 0x1
10-16 13:33:50.066 18631 22644 I libnfc_nci: [INFO:NativeNfcTag.cpp(1313)] nativeNfcTag_doPresenceCheck
10-16 13:33:50.066 18631 22644 I libnfc_nci: [INFO:nfa_rw_api.cc(192)] NFA_RwPresenceCheck
10-16 13:33:50.068 18631 18656 I libnfc_nci: [INFO:nfa_sys_main.cc(77)] NFA got event 0x0500
10-16 13:33:50.068 18631 18656 I libnfc_nci: [INFO:nfa_rw_main.cc(179)] nfa_rw_handle_event event: NFA_RW_OP_REQUEST_EVT (0x500), flags: 00000021
10-16 13:33:50.068 18631 18656 I libnfc_nci: [INFO:nfa_rw_act.cc(2806)] nfa_rw_handle_op_req: op=0x03
10-16 13:33:50.069 18631 18656 I libnfc_nci: [INFO:nfa_sys_ptim.cc(156)] nfa_sys_ptim_stop_timer 0x7aad54f4f8
10-16 13:33:50.069 18631 18656 I libnfc_nci: [INFO:nfa_sys_ptim.cc(163)] ptim timer stop
10-16 13:33:50.069 18631 18656 I libnfc_nci: [INFO:nfa_rw_act.cc(192)] Stopped presence check timer (if started)
10-16 13:33:50.069 18631 18656 I libnfc_nci: [INFO:nfc_main.cc(1127)] NFC_Deactivate 5 (OPEN) deactivate_type:1
10-16 13:33:50.069 18631 18656 I libnfc_nci: [INFO:nfc_main.cc(326)] nfc_set_state 5 (OPEN)->6 (CLOSING)
10-16 13:33:50.069 18631 18656 I libnfc_nci: [INFO:nfc_main.cc(1145)] act_protocol 4 credits:1/1
10-16 13:33:50.069 18631 18656 I libnfc_nci: [INFO:NfcAdaptation.cc(616)] NfcAdaptation::HalWrite
10-16 13:33:50.069 724 724 D NxpExtns: find found MIFARE_READER_ENABLE=(0x1)
10-16 13:33:50.069 724 724 D NxpHal : NAME_MIFARE_READER_ENABLE : 1
10-16 13:33:50.069 724 724 D : sem_timedwait() succeeded
10-16 13:33:50.070 724 18660 D NxpTml : PN54X - Write requested.....
10-16 13:33:50.070 724 18660 D NxpTml : PN54X - Invoking I2C Write.....
10-16 13:33:50.072 724 18660 D NxpNciX : len = 4 => 21060101
10-16 13:33:50.072 724 18660 D NxpTml : PN54X - I2C Write successful.....
10-16 13:33:50.072 724 18660 D NxpTml : PN54X - Posting Fresh Write message.....
10-16 13:33:50.072 724 18660 D NxpTml : PN54X - Tml Writer Thread Running................
10-16 13:33:50.072 724 18662 D NxpHal : write successful status = 0x0
10-16 13:33:50.073 18631 18656 I libnfc_nci: [INFO:nfa_sys_ptim.cc(128)] nfa_sys_ptim_start_timer 0x7aad54e4e0
10-16 13:33:50.073 18631 18656 I libnfc_nci: [INFO:nfa_sys_ptim.cc(133)] ptim timer start
10-16 13:33:50.073 724 18659 D NxpTml : PN54X - I2C Read successful.....len = 4
10-16 13:33:50.073 724 18659 D NxpTml : PN54X - Posting read message.....
10-16 13:33:50.073 724 18659 D NxpNciR : len = 4 <= 41060100
10-16 13:33:50.074 724 18662 D NxpHal : read successful status = 0x0
10-16 13:33:50.075 18631 18656 I libnfc_nci: [INFO:nfc_ncif.cc(417)] NFC received rsp gid:1
10-16 13:33:50.075 18631 18656 I libnfc_nci: [INFO:nfa_dm_discover.cc(2890)] returning from nfa_dm_p2p_prio_logic Disable p2p_prio_logic
10-16 13:33:50.075 18631 18656 I libnfc_nci: [INFO:nfc_main.cc(326)] nfc_set_state 6 (CLOSING)->4 (IDLE)
10-16 13:33:50.075 18631 18656 I libnfc_nci: [INFO:rw_t4t.cc(1760)] event = 0x6002
10-16 13:33:50.075 18631 18656 I libnfc_nci: [INFO:nfa_dm_discover.cc(694)] event:0x4005
10-16 13:33:50.075 18631 18656 I libnfc_nci: [INFO:nfa_dm_discover.cc(2518)] state: POLL_ACTIVE (4), event: DEACTIVATE_RSP(7) disc_flags: 0x69
10-16 13:33:50.075 18631 18656 I libnfc_nci: [INFO:nfa_dm_discover.cc(2571)] new state: POLL_ACTIVE (4), disc_flags: 0x49
10-16 13:33:50.075 724 18659 D NxpTml : PN54X - Read requested.....
10-16 13:33:50.076 724 18659 D NxpTml : PN54X - Invoking I2C Read.....
10-16 13:33:50.077 724 18659 D NxpTml : PN54X - I2C Read successful.....len = 5
10-16 13:33:50.077 724 18659 D NxpTml : PN54X - Posting read message.....
10-16 13:33:50.077 724 18659 D NxpNciR : len = 5 <= 6106020100
10-16 13:33:50.078 724 18662 D NxpHal : read successful status = 0x0
10-16 13:33:50.079 724 18659 D NxpTml : PN54X - Read requested.....
10-16 13:33:50.079 724 18659 D NxpTml : PN54X - Invoking I2C Read.....
10-16 13:33:50.079 18631 18656 I libnfc_nci: [INFO:nfc_ncif.cc(458)] NFC received ntf gid:1
10-16 13:33:50.079 18631 18656 I libnfc_nci: [INFO:nfa_dm_discover.cc(2890)] returning from nfa_dm_p2p_prio_logic Disable p2p_prio_logic
10-16 13:33:50.079 18631 18656 I libnfc_nci: [INFO:nfc_main.cc(326)] nfc_set_state 4 (IDLE)->4 (IDLE)
10-16 13:33:50.079 18631 18656 I libnfc_nci: [INFO:nfa_dm_discover.cc(1662)] nfa_dm_disc_data_cback
10-16 13:33:50.079 18631 18656 I libnfc_nci: [INFO:nfa_dm_discover.cc(694)] event:0x4005
10-16 13:33:50.080 18631 18656 I libnfc_nci: [INFO:nfa_dm_discover.cc(2518)] state: POLL_ACTIVE (4), event: DEACTIVATE_NTF(8) disc_flags: 0x49
10-16 13:33:50.080 18631 18656 I libnfc_nci: [INFO:nfa_sys_ptim.cc(156)] nfa_sys_ptim_stop_timer 0x7aad54e4e0
10-16 13:33:50.080 18631 18656 I libnfc_nci: [INFO:nfa_sys_ptim.cc(163)] ptim timer stop
10-16 13:33:50.080 18631 18656 I libnfc_nci: [INFO:nfa_dm_discover.cc(1361)] activated_handle=1
10-16 13:33:50.080 18631 18656 I libnfc_nci: [INFO:nfa_dm_discover.cc(1365)] for sleep wakeup
10-16 13:33:50.080 18631 18656 I libnfc_nci: [INFO:nfa_dm_discover.cc(1685)] old_state: POLL_ACTIVE (4), new_state: W4_HOST_SELECT (3) disc_flags: 0x9
10-16 13:33:50.080 18631 18656 I libnfc_nci: [INFO:NfcAdaptation.cc(616)] NfcAdaptation::HalWrite
10-16 13:33:50.080 724 724 D NxpExtns: find found MIFARE_READER_ENABLE=(0x1)
10-16 13:33:50.080 724 724 D NxpHal : NAME_MIFARE_READER_ENABLE : 1
10-16 13:33:50.080 724 724 D : sem_timedwait() succeeded
10-16 13:33:50.081 724 18660 D NxpTml : PN54X - Write requested.....
10-16 13:33:50.081 724 18660 D NxpTml : PN54X - Invoking I2C Write.....
10-16 13:33:50.083 724 18659 D NxpTml : PN54X - I2C Read successful.....len = 4
10-16 13:33:50.083 724 18659 D NxpTml : Delay Read if write thread is busy
10-16 13:33:50.084 724 18660 D NxpNciX : len = 6 => 210403010402
10-16 13:33:50.084 724 18660 D NxpTml : PN54X - I2C Write successful.....
10-16 13:33:50.084 724 18660 D NxpTml : PN54X - Posting Fresh Write message.....
10-16 13:33:50.084 724 18660 D NxpTml : PN54X - Tml Writer Thread Running................
10-16 13:33:50.085 724 18662 D NxpHal : write successful status = 0x0
10-16 13:33:50.086 724 18659 D NxpTml : PN54X - Posting read message.....
10-16 13:33:50.087 724 18659 D NxpNciR : len = 4 <= 41040100
10-16 13:33:50.088 724 18662 D NxpHal : read successful status = 0x0
10-16 13:33:50.089 18631 18656 I libnfc_nci: [INFO:nfa_dm_discover.cc(2571)] new state: W4_HOST_SELECT (3), disc_flags: 0x9
10-16 13:33:50.089 18631 18656 I libnfc_nci: [INFO:nfc_ncif.cc(417)] NFC received rsp gid:1
10-16 13:33:50.089 18631 18656 I libnfc_nci: [INFO:nfa_dm_discover.cc(694)] event:0x4003
10-16 13:33:50.089 18631 18656 I libnfc_nci: [INFO:nfa_dm_discover.cc(2518)] state: W4_HOST_SELECT (3), event: SELECT_RSP(4) disc_flags: 0x9
10-16 13:33:50.089 18631 18656 I libnfc_nci: [INFO:nfa_dm_discover.cc(2571)] new state: W4_HOST_SELECT (3), disc_flags: 0x9
10-16 13:33:50.092 724 18659 D NxpTml : PN54X - Read requested.....
10-16 13:33:50.092 724 18659 D NxpTml : PN54X - Invoking I2C Read.....
10-16 13:33:50.094 724 18659 D NxpTml : PN54X - I2C Read successful.....len = 36
10-16 13:33:50.094 724 18659 D NxpTml : PN54X - Posting read message.....
10-16 13:33:50.094 724 18659 D NxpNciR : len = 36 <= 61052101020400FF010C48000704846CEADE6A8001200000000A09787791028073C82110
10-16 13:33:50.094 724 18662 D NxpHal : read successful status = 0x0
10-16 13:33:50.094 724 18662 D NxpHal : NxpNci: RF Interface = ISO-DEP
10-16 13:33:50.094 724 18662 D NxpHal : NxpNci: Protocol = ISO-DEP
10-16 13:33:50.095 724 18662 D NxpHal : NxpNci: Mode = A Passive Poll
10-16 13:33:50.095 18631 18656 I libnfc_nci: [INFO:nfc_ncif.cc(458)] NFC received ntf gid:1
10-16 13:33:50.095 18631 18656 I libnfc_nci: [INFO:nfa_dm_discover.cc(2890)] returning from nfa_dm_p2p_prio_logic Disable p2p_prio_logic
10-16 13:33:50.095 724 18659 D NxpTml : PN54X - Read requested.....
10-16 13:33:50.095 724 18659 D NxpTml : PN54X - Invoking I2C Read.....
10-16 13:33:50.095 18631 18656 I libnfc_nci: [INFO:nfc_main.cc(326)] nfc_set_state 4 (IDLE)->5 (OPEN)
10-16 13:33:50.095 18631 18656 I libnfc_nci: [INFO:nfc_ncif.cc(925)] nfc_ncif_proc_activate:33 10, mode:0x00
10-16 13:33:50.095 18631 18656 I libnfc_nci: [INFO:nfa_dm_discover.cc(694)] event:0x4004
10-16 13:33:50.095 18631 18656 I libnfc_nci: [INFO:nfa_dm_discover.cc(2518)] state: W4_HOST_SELECT (3), event: ACTIVATED_NTF(5) disc_flags: 0x9
10-16 13:33:50.095 18631 18656 I libnfc_nci: [INFO:nfa_dm_discover.cc(1685)] old_state: W4_HOST_SELECT (3), new_state: POLL_ACTIVE (4) disc_flags: 0x9
10-16 13:33:50.095 18631 18656 I libnfc_nci: [INFO:nfa_dm_discover.cc(1192)] tech_n_mode:0x0, proto:0x4
10-16 13:33:50.095 18631 18656 I libnfc_nci: [INFO:nfa_dm_discover.cc(674)] tech_n_mode:0x0, protocol:0x4, disc_mask:0x4
10-16 13:33:50.096 18631 18656 I libnfc_nci: [INFO:nfa_dm_discover.cc(1320)] activated_protocol:0x4, activated_handle: 0x1
10-16 13:33:50.096 18631 18656 I libnfc_nci: [INFO:nfa_dm_act.cc(1557)] event:0x01
10-16 13:33:50.096 18631 18656 I libnfc_nci: [INFO:nfa_rw_main.cc(179)] nfa_rw_handle_event event: NFA_RW_ACTIVATE_NTF_EVT (0x501), flags: 00000031
10-16 13:33:50.096 18631 18656 I libnfc_nci: [INFO:nfa_rw_act.cc(2538)] nfa_rw_activate_ntf
10-16 13:33:50.096 18631 18656 I libnfc_nci: [INFO:rw_main.cc(206)] RW_SetActivatedTagType protocol:4, technology:0, SAK:32
10-16 13:33:50.096 18631 18656 I libnfc_nci: [INFO:rw_t4t.cc(1909)] rw_t4t_select
10-16 13:33:50.096 18631 18656 I libnfc_nci: [INFO:nfa_dm_act.cc(1709)] status:0x0
10-16 13:33:50.096 18631 18656 I libnfc_nci: [INFO:nfa_rw_act.cc(448)] nfa_rw_handle_sleep_wakeup_rsp; Legacy presence check performed
10-16 13:33:50.096 18631 18656 I libnfc_nci: [INFO:nfa_sys_ptim.cc(156)] nfa_sys_ptim_stop_timer 0x7aad54f4f8
10-16 13:33:50.096 18631 18656 I libnfc_nci: [INFO:nfa_sys_ptim.cc(163)] ptim timer stop
10-16 13:33:50.096 18631 18656 I libnfc_nci: [INFO:nfa_rw_act.cc(192)] Stopped presence check timer (if started)
10-16 13:33:50.096 18631 18656 I libnfc_nci: [INFO:nfa_dm_act.cc(1378)] event:0xF
10-16 13:33:50.096 18631 18656 I libnfc_nci: [INFO:NativeNfcManager.cpp(258)] nfaConnectionCallback: event= 15
10-16 13:33:50.096 18631 18656 I libnfc_nci: [INFO:NativeNfcManager.cpp(582)] nfaConnectionCallback: NFA_PRESENCE_CHECK_EVT
10-16 13:33:50.096 18631 18656 I libnfc_nci: [INFO:nfa_dm_discover.cc(2571)] new state: POLL_ACTIVE (4), disc_flags: 0x1
10-16 13:33:50.222 18631 22644 I libnfc_nci: [INFO:NativeNfcTag.cpp(1313)] nativeNfcTag_doPresenceCheck
10-16 13:33:50.222 18631 22644 I libnfc_nci: [INFO:nfa_rw_api.cc(192)] NFA_RwPresenceCheck
10-16 13:33:50.223 18631 18656 I libnfc_nci: [INFO:nfa_sys_main.cc(77)] NFA got event 0x0500
10-16 13:33:50.223 18631 18656 I libnfc_nci: [INFO:nfa_rw_main.cc(179)] nfa_rw_handle_event event: NFA_RW_OP_REQUEST_EVT (0x500), flags: 00000021
10-16 13:33:50.223 18631 18656 I libnfc_nci: [INFO:nfa_rw_act.cc(2806)] nfa_rw_handle_op_req: op=0x03
10-16 13:33:50.223 18631 18656 I libnfc_nci: [INFO:nfa_sys_ptim.cc(156)] nfa_sys_ptim_stop_timer 0x7aad54f4f8
10-16 13:33:50.223 18631 18656 I libnfc_nci: [INFO:nfa_sys_ptim.cc(163)] ptim timer stop
10-16 13:33:50.223 18631 18656 I libnfc_nci: [INFO:nfa_rw_act.cc(192)] Stopped presence check timer (if started)
10-16 13:33:50.223 18631 18656 I libnfc_nci: [INFO:nfc_main.cc(1127)] NFC_Deactivate 5 (OPEN) deactivate_type:1
10-16 13:33:50.223 18631 18656 I libnfc_nci: [INFO:nfc_main.cc(326)] nfc_set_state 5 (OPEN)->6 (CLOSING)
10-16 13:33:50.223 18631 18656 I libnfc_nci: [INFO:nfc_main.cc(1145)] act_protocol 4 credits:1/1
10-16 13:33:50.223 18631 18656 I libnfc_nci: [INFO:NfcAdaptation.cc(616)] NfcAdaptation::HalWrite
10-16 13:33:50.224 724 724 D NxpExtns: find found MIFARE_READER_ENABLE=(0x1)
10-16 13:33:50.224 724 724 D NxpHal : NAME_MIFARE_READER_ENABLE : 1
10-16 13:33:50.224 724 724 D : sem_timedwait() succeeded
10-16 13:33:50.224 724 18660 D NxpTml : PN54X - Write requested.....
10-16 13:33:50.224 724 18660 D NxpTml : PN54X - Invoking I2C Write.....
10-16 13:33:50.226 724 18660 D NxpNciX : len = 4 => 21060101
10-16 13:33:50.226 724 18660 D NxpTml : PN54X - I2C Write successful.....
10-16 13:33:50.226 724 18660 D NxpTml : PN54X - Posting Fresh Write message.....
10-16 13:33:50.226 724 18660 D NxpTml : PN54X - Tml Writer Thread Running................
10-16 13:33:50.226 724 18659 D NxpTml : PN54X - I2C Read successful.....len = 4
10-16 13:33:50.226 724 18659 D NxpTml : Delay Read if write thread is busy
10-16 13:33:50.226 724 18662 D NxpHal : write successful status = 0x0
10-16 13:33:50.227 18631 18656 I libnfc_nci: [INFO:nfa_sys_ptim.cc(128)] nfa_sys_ptim_start_timer 0x7aad54e4e0
10-16 13:33:50.227 18631 18656 I libnfc_nci: [INFO:nfa_sys_ptim.cc(133)] ptim timer start
10-16 13:33:50.229 724 18659 D NxpTml : PN54X - Posting read message.....
10-16 13:33:50.229 724 18659 D NxpNciR : len = 4 <= 41060100
10-16 13:33:50.229 724 18662 D NxpHal : read successful status = 0x0
10-16 13:33:50.230 18631 18656 I libnfc_nci: [INFO:nfc_ncif.cc(417)] NFC received rsp gid:1
10-16 13:33:50.230 18631 18656 I libnfc_nci: [INFO:nfa_dm_discover.cc(2890)] returning from nfa_dm_p2p_prio_logic Disable p2p_prio_logic
10-16 13:33:50.230 18631 18656 I libnfc_nci: [INFO:nfc_main.cc(326)] nfc_set_state 6 (CLOSING)->4 (IDLE)
10-16 13:33:50.230 724 18659 D NxpTml : PN54X - Read requested.....
10-16 13:33:50.230 724 18659 D NxpTml : PN54X - Invoking I2C Read.....
10-16 13:33:50.230 18631 18656 I libnfc_nci: [INFO:rw_t4t.cc(1760)] event = 0x6002
10-16 13:33:50.230 18631 18656 I libnfc_nci: [INFO:nfa_dm_discover.cc(694)] event:0x4005
10-16 13:33:50.230 18631 18656 I libnfc_nci: [INFO:nfa_dm_discover.cc(2518)] state: POLL_ACTIVE (4), event: DEACTIVATE_RSP(7) disc_flags: 0x69
10-16 13:33:50.230 18631 18656 I libnfc_nci: [INFO:nfa_dm_discover.cc(2571)] new state: POLL_ACTIVE (4), disc_flags: 0x49
10-16 13:33:50.231 724 18659 D NxpTml : PN54X - I2C Read successful.....len = 5
10-16 13:33:50.231 724 18659 D NxpTml : PN54X - Posting read message.....
10-16 13:33:50.231 724 18659 D NxpNciR : len = 5 <= 6106020100
10-16 13:33:50.232 724 18662 D NxpHal : read successful status = 0x0
10-16 13:33:50.232 18631 18656 I libnfc_nci: [INFO:nfc_ncif.cc(458)] NFC received ntf gid:1
10-16 13:33:50.233 18631 18656 I libnfc_nci: [INFO:nfa_dm_discover.cc(2890)] returning from nfa_dm_p2p_prio_logic Disable p2p_prio_logic
10-16 13:33:50.233 18631 18656 I libnfc_nci: [INFO:nfc_main.cc(326)] nfc_set_state 4 (IDLE)->4 (IDLE)
10-16 13:33:50.233 18631 18656 I libnfc_nci: [INFO:nfa_dm_discover.cc(1662)] nfa_dm_disc_data_cback
10-16 13:33:50.233 18631 18656 I libnfc_nci: [INFO:nfa_dm_discover.cc(694)] event:0x4005
10-16 13:33:50.233 18631 18656 I libnfc_nci: [INFO:nfa_dm_discover.cc(2518)] state: POLL_ACTIVE (4), event: DEACTIVATE_NTF(8) disc_flags: 0x49
10-16 13:33:50.233 18631 18656 I libnfc_nci: [INFO:nfa_sys_ptim.cc(156)] nfa_sys_ptim_stop_timer 0x7aad54e4e0
10-16 13:33:50.233 18631 18656 I libnfc_nci: [INFO:nfa_sys_ptim.cc(163)] ptim timer stop
10-16 13:33:50.233 18631 18656 I libnfc_nci: [INFO:nfa_dm_discover.cc(1361)] activated_handle=1
10-16 13:33:50.233 18631 18656 I libnfc_nci: [INFO:nfa_dm_discover.cc(1365)] for sleep wakeup
10-16 13:33:50.233 18631 18656 I libnfc_nci: [INFO:nfa_dm_discover.cc(1685)] old_state: POLL_ACTIVE (4), new_state: W4_HOST_SELECT (3) disc_flags: 0x9
10-16 13:33:50.233 18631 18656 I libnfc_nci: [INFO:NfcAdaptation.cc(616)] NfcAdaptation::HalWrite
10-16 13:33:50.234 724 724 D NxpExtns: find found MIFARE_READER_ENABLE=(0x1)
10-16 13:33:50.234 724 18659 D NxpTml : PN54X - Read requested.....
10-16 13:33:50.234 724 18659 D NxpTml : PN54X - Invoking I2C Read.....
10-16 13:33:50.234 724 724 D NxpHal : NAME_MIFARE_READER_ENABLE : 1
10-16 13:33:50.234 724 724 D : sem_timedwait() succeeded
10-16 13:33:50.234 724 18660 D NxpTml : PN54X - Write requested.....
10-16 13:33:50.234 724 18660 D NxpTml : PN54X - Invoking I2C Write.....
10-16 13:33:50.236 724 18660 D NxpNciX : len = 6 => 210403010402
10-16 13:33:50.236 724 18660 D NxpTml : PN54X - I2C Write successful.....
10-16 13:33:50.236 724 18660 D NxpTml : PN54X - Posting Fresh Write message.....
10-16 13:33:50.236 724 18660 D NxpTml : PN54X - Tml Writer Thread Running................
10-16 13:33:50.237 724 18662 D NxpHal : write successful status = 0x0
10-16 13:33:50.239 18631 18656 I libnfc_nci: [INFO:nfa_dm_discover.cc(2571)] new state: W4_HOST_SELECT (3), disc_flags: 0x9
10-16 13:33:50.239 724 18659 D NxpTml : PN54X - I2C Read successful.....len = 4
10-16 13:33:50.239 724 18659 D NxpTml : PN54X - Posting read message.....
10-16 13:33:50.239 724 18659 D NxpNciR : len = 4 <= 41040100
10-16 13:33:50.240 724 18662 D NxpHal : read successful status = 0x0
10-16 13:33:50.240 18631 18656 I libnfc_nci: [INFO:nfc_ncif.cc(417)] NFC received rsp gid:1
10-16 13:33:50.240 18631 18656 I libnfc_nci: [INFO:nfa_dm_discover.cc(694)] event:0x4003
10-16 13:33:50.240 18631 18656 I libnfc_nci: [INFO:nfa_dm_discover.cc(2518)] state: W4_HOST_SELECT (3), event: SELECT_RSP(4) disc_flags: 0x9
10-16 13:33:50.240 18631 18656 I libnfc_nci: [INFO:nfa_dm_discover.cc(2571)] new state: W4_HOST_SELECT (3), disc_flags: 0x9
10-16 13:33:50.246 724 18659 D NxpTml : PN54X - Read requested.....
10-16 13:33:50.246 724 18659 D NxpTml : PN54X - Invoking I2C Read.....
10-16 13:33:50.248 724 18659 D NxpTml : PN54X - I2C Read successful.....len = 36
10-16 13:33:50.248 724 18659 D NxpTml : PN54X - Posting read message.....
10-16 13:33:50.248 724 18659 D NxpNciR : len = 36 <= 61052101020400FF010C48000704846CEADE6A8001200000000A09787791028073C82110
10-16 13:33:50.248 724 18662 D NxpHal : read successful status = 0x0
10-16 13:33:50.248 724 18662 D NxpHal : NxpNci: RF Interface = ISO-DEP
10-16 13:33:50.248 724 18662 D NxpHal : NxpNci: Protocol = ISO-DEP
10-16 13:33:50.248 724 18662 D NxpHal : NxpNci: Mode = A Passive Poll
10-16 13:33:50.249 18631 18656 I libnfc_nci: [INFO:nfc_ncif.cc(458)] NFC received ntf gid:1
10-16 13:33:50.249 18631 18656 I libnfc_nci: [INFO:nfa_dm_discover.cc(2890)] returning from nfa_dm_p2p_prio_logic Disable p2p_prio_logic
10-16 13:33:50.249 18631 18656 I libnfc_nci: [INFO:nfc_main.cc(326)] nfc_set_state 4 (IDLE)->5 (OPEN)
10-16 13:33:50.249 18631 18656 I libnfc_nci: [INFO:nfc_ncif.cc(925)] nfc_ncif_proc_activate:33 10, mode:0x00
10-16 13:33:50.249 18631 18656 I libnfc_nci: [INFO:nfa_dm_discover.cc(694)] event:0x4004
10-16 13:33:50.249 18631 18656 I libnfc_nci: [INFO:nfa_dm_discover.cc(2518)] state: W4_HOST_SELECT (3), event: ACTIVATED_NTF(5) disc_flags: 0x9
10-16 13:33:50.249 18631 18656 I libnfc_nci: [INFO:nfa_dm_discover.cc(1685)] old_state: W4_HOST_SELECT (3), new_state: POLL_ACTIVE (4) disc_flags: 0x9
10-16 13:33:50.249 18631 18656 I libnfc_nci: [INFO:nfa_dm_discover.cc(1192)] tech_n_mode:0x0, proto:0x4
10-16 13:33:50.250 18631 18656 I libnfc_nci: [INFO:nfa_dm_discover.cc(674)] tech_n_mode:0x0, protocol:0x4, disc_mask:0x4
10-16 13:33:50.250 18631 18656 I libnfc_nci: [INFO:nfa_dm_discover.cc(1320)] activated_protocol:0x4, activated_handle: 0x1
10-16 13:33:50.250 18631 18656 I libnfc_nci: [INFO:nfa_dm_act.cc(1557)] event:0x01
10-16 13:33:50.250 18631 18656 I libnfc_nci: [INFO:nfa_rw_main.cc(179)] nfa_rw_handle_event event: NFA_RW_ACTIVATE_NTF_EVT (0x501), flags: 00000031
10-16 13:33:50.250 18631 18656 I libnfc_nci: [INFO:nfa_rw_act.cc(2538)] nfa_rw_activate_ntf
10-16 13:33:50.250 18631 18656 I libnfc_nci: [INFO:rw_main.cc(206)] RW_SetActivatedTagType protocol:4, technology:0, SAK:32
10-16 13:33:50.250 18631 18656 I libnfc_nci: [INFO:rw_t4t.cc(1909)] rw_t4t_select
10-16 13:33:50.250 18631 18656 I libnfc_nci: [INFO:nfa_dm_act.cc(1709)] status:0x0
10-16 13:33:50.253 18631 18656 I libnfc_nci: [INFO:nfa_rw_act.cc(448)] nfa_rw_handle_sleep_wakeup_rsp; Legacy presence check performed
10-16 13:33:50.253 18631 18656 I libnfc_nci: [INFO:nfa_sys_ptim.cc(156)] nfa_sys_ptim_stop_timer 0x7aad54f4f8
10-16 13:33:50.253 18631 18656 I libnfc_nci: [INFO:nfa_sys_ptim.cc(163)] ptim timer stop
10-16 13:33:50.253 18631 18656 I libnfc_nci: [INFO:nfa_rw_act.cc(192)] Stopped presence check timer (if started)
10-16 13:33:50.253 18631 18656 I libnfc_nci: [INFO:nfa_dm_act.cc(1378)] event:0xF
10-16 13:33:50.253 18631 18656 I libnfc_nci: [INFO:NativeNfcManager.cpp(258)] nfaConnectionCallback: event= 15
10-16 13:33:50.253 18631 18656 I libnfc_nci: [INFO:NativeNfcManager.cpp(582)] nfaConnectionCallback: NFA_PRESENCE_CHECK_EVT
10-16 13:33:50.253 18631 18656 I libnfc_nci: [INFO:nfa_dm_discover.cc(2571)] new state: POLL_ACTIVE (4), disc_flags: 0x1
10-16 13:33:50.254 724 18659 D NxpTml : PN54X - Read requested.....
10-16 13:33:50.254 724 18659 D NxpTml : PN54X - Invoking I2C Read.....
closing due to re-design, label recheck
added for future re-consideration
Bug Report
Problem
Processing... Try keeping the card still.
After entering the pin, it tells the message above, but never complete? How many minutes am I supposed to wait?
Beside: Cancel does not work properly.
Expected behavior
Complete within a couple of minutes <5min.
Actual behavior
Hangs and does not report back why. At least a timeout should happen at some point or even better a counter counting down 10min f.i.
Reproduction
1) Open Status 2) Generate keys 3) Choose chat name 4) Advance -> Keycard -> Next 5) Begin setup 6) Looking for cards.. -> put card on back of phone -> Step 1 of 3 -> enter pin -> Looking for cards.. -> Connected -> Processing... Try keeping the card still. -> never completes.
Additional Information
If you tell me show I can get you some logs from the phone. adb commands or commands I can run on the terminal of the android phone are preferred. It is the 3 update I am put my hope in that my issue is fixed, but still no luck. I am hoping to deploy 3 cards as test with some friends as soon as I get it working for myself ;)