NFCのトピックRe: NFC not enabling in Android Pie

NFC not enabling in Android Pie

saschaseefelder — Wed, 15 Jan 2020 12:15:10 GMT

i am using the PN7150 in Android 9 on an i.mx8m Mini. When im trying to enable NFC in settings it turns itsself off immediately. I have been reading Application Note AN11690 but cant figure out whats wrong. Thanks in advance

01-01 00:58:53.923 3215 3215 D nxpnfc@1.0-service: NFC HAL Service 1.1 is starting.
01-01 00:58:53.923 3215 3215 I ServiceManagement: Removing namespace from process name android.hardware.nfc@1.1-service to nfc@1.1-service.
01-01 00:58:54.027 3037 3037 E SELinux : avc: denied { add } for interface=vendor.nxp.nxpnfc::INxpNfc pid=3215 scontext=u:r:hal_nfc_default:s 0 tcontext=u:object_r:default_android_hwservice:s0 tclass=hwservice_manager permissive=1
01-01 00:58:54.035 3215 3215 D nxpnfc@1.0-service: NFC service is ready
01-01 00:58:57.476 3351 3396 W PackageParser: Ignoring duplicate uses-permissions/uses-permissions-sdk-m: android.permission.CHANGE_WIFI_STATE in package: com.android.nfc at: Binary XML file line #47
01-01 00:58:57.480 3351 3396 W PackageParser: Ignoring duplicate uses-permissions/uses-permissions-sdk-m: android.permission.ACCESS_WIFI_STATE in package: com.android.nfc at: Binary XML file line #48
01-15 10:55:42.773 3351 3368 I ActivityManager: Start proc 3982:com.android.nfc/1027 for added application com.android.nfc
01-15 10:55:42.915 3982 3982 I NfcService: Starting NFC service
01-15 10:55:42.986 3982 3982 I : [0115/105542.986315:INFO:NfcJniUtil.cpp(47)] NFC Service: loading nci JNI
01-15 10:55:42.991 3982 3982 I com.android.nf: ConfigFile - Parsing file '/vendor/etc/libnfc-nci.conf'
01-15 10:55:42.991 3982 3982 I com.android.nf: ConfigFile - [NFC_DEBUG_ENABLED] = 0x01
01-15 10:55:42.991 3982 3982 I com.android.nf: ConfigFile - [NFA_STORAGE] = "/data/vendor/nfc"
01-15 10:55:42.991 3982 3982 I com.android.nf: ConfigFile - [NCI_HAL_MODULE] = "nfc_nci.pn54x"
01-15 10:55:42.992 3982 3982 I : [0115/105542.992095:INFO:NfcAdaptation.cc(460)] NfcAdaptation::InitializeHalDeviceContext: INfc::getSe rvice()
01-15 10:55:43.002 3982 3982 I : [0115/105543.002520:INFO:NfcAdaptation.cc(466)] NfcAdaptation::InitializeHalDeviceContext: INfc::getSe rvice() returned 0xeef0632e4600 (remote)
01-15 10:55:43.006 3982 3982 I : [0115/105543.006484:INFO:NativeNfcManager.cpp(190)] initializeGlobalDebugEnabledFlag: level=1
01-15 10:55:43.006 3982 3982 I : [0115/105543.006603:INFO:NativeNfcManager.cpp(622)] nfcManager_initNativeStruc: enter
01-15 10:55:43.006 3982 3982 I : [0115/105543.006873:INFO:NativeNfcManager.cpp(683)] nfcManager_initNativeStruc: exit
01-15 10:55:43.129 3982 3982 D RegisteredNfcFServicesCache: Dynamic System Code, NFCID2 file does not exist.
01-15 10:55:43.130 3982 3982 D RegisteredNfcFServicesCache: Service unchanged, not updating
01-15 10:55:43.133 3982 4074 D NfcService: checking on firmware download
01-15 10:55:43.133 3982 4074 D NfcService: NFC is on. Doing normal stuff
01-15 10:55:43.133 3982 4074 I NfcService: Enabling NFC
01-15 10:55:43.137 3982 4074 I : [0115/105543.137836:INFO:NativeNfcManager.cpp(190)] initializeGlobalDebugEnabledFlag: level=1
01-15 10:55:43.138 3982 4074 I libnfc_nci: [INFO:NfcAdaptation.cc(93)] initializeGlobalDebugEnabledFlag: level=1
01-15 10:55:43.138 3982 4074 I libnfc_nci: [INFO:libmain.cc(196)] verify_stack_non_volatile_store
01-15 10:55:43.138 3982 4074 I libnfc_nci: [INFO:CrcChecksum.cc(94)] crcChecksumVerifyIntegrity: filename=/data/vendor/nfc/nfaStorage.bin1
01-15 10:55:43.138 3982 4074 I libnfc_nci: [INFO:CrcChecksum.cc(94)] crcChecksumVerifyIntegrity: filename=/data/vendor/nfc/nfaStorage.bin4
01-15 10:55:43.138 3982 4074 I libnfc_nci: [INFO:CrcChecksum.cc(94)] crcChecksumVerifyIntegrity: filename=/data/vendor/nfc/nfaStorage.bin2
01-15 10:55:43.138 3982 4074 I libnfc_nci: [INFO:CrcChecksum.cc(94)] crcChecksumVerifyIntegrity: filename=/data/vendor/nfc/nfaStorage.bin3
01-15 10:55:43.138 3982 4074 I libnfc_nci: [INFO:CrcChecksum.cc(94)] crcChecksumVerifyIntegrity: filename=/data/vendor/nfc/nfaStorage.bin5
01-15 10:55:43.138 3982 4074 I libnfc_nci: [INFO:NfcAdaptation.cc(305)] Initialize: preserve stack NV store
01-15 10:55:43.140 3982 4074 I libnfc_nci: [INFO:gki_ulinux.cc(194)] GKI_create_task func=0x0xeef04ac02ca8 id=4 name=NFCA_TASK stack=0x0x0 stackSize=0
01-15 10:55:43.140 3982 4074 I libnfc_nci: [INFO:gki_ulinux.cc(220)] GKI creating task 4, pCond/pMutex=0x0/0x0
01-15 10:55:43.140 3982 4074 I libnfc_nci: [INFO:gki_ulinux.cc(262)] Leaving GKI_create_task 0xeef04ac02ca8 4 eef04a5354f0 NFCA_TASK 0x0 0
01-15 10:55:43.140 3982 4074 I libnfc_nci: [INFO:gki_ulinux.cc(194)] GKI_create_task func=0x0xeef04ac02e28 id=0 name=NFCA_THREAD stack=0x0x0 stackSize=0
01-15 10:55:43.140 3982 4078 I libnfc_nci: [INFO:gki_ulinux.cc(89)] gki_task_entry task_id=4, thread_id=eef04a5354f0/eef04a5354f0, pCond/pMutex =0x0/0x0
01-15 10:55:43.140 3982 4074 I libnfc_nci: [INFO:gki_ulinux.cc(220)] GKI creating task 0, pCond/pMutex=0x0/0x0
01-15 10:55:43.140 3982 4078 I libnfc_nci: [INFO:NfcAdaptation.cc(393)] NfcAdaptation::NFCA_TASK: enter
01-15 10:55:43.140 3982 4078 I libnfc_nci: [INFO:gki_ulinux.cc(450)] GKI_run enter
01-15 10:55:43.140 3982 4078 I libnfc_nci: [INFO:gki_ulinux.cc(460)] Start/Stop GKI_timer_update_registered!
01-15 10:55:43.140 3982 4078 I libnfc_nci: [INFO:gki_ulinux.cc(479)] GKI_run, run_cond(0xeef04ac45b70)=1
01-15 10:55:43.140 3982 4074 I libnfc_nci: [INFO:gki_ulinux.cc(262)] Leaving GKI_create_task 0xeef04ac02e28 0 eef04a4374f0 NFCA_THREAD 0x0 0
01-15 10:55:43.140 3982 4079 I libnfc_nci: [INFO:gki_ulinux.cc(89)] gki_task_entry task_id=0, thread_id=eef04a4374f0/eef04a4374f0, pCond/pMutex =0x0/0x0
01-15 10:55:43.140 3982 4079 I libnfc_nci: [INFO:NfcAdaptation.cc(410)] NfcAdaptation::Thread: enter
01-15 10:55:43.140 3982 4079 I libnfc_nci: [INFO:gki_ulinux.cc(194)] GKI_create_task func=0x0xeef04abdddb8 id=3 name=NFC_TASK stack=0x0x0 s tackSize=0
01-15 10:55:43.140 3982 4079 I libnfc_nci: [INFO:gki_ulinux.cc(220)] GKI creating task 3, pCond/pMutex=0xeef04a4371c0/0xeef04a437198
01-15 10:55:43.140 3982 4079 I libnfc_nci: [INFO:gki_ulinux.cc(262)] Leaving GKI_create_task 0xeef04abdddb8 3 eef04a3394f0 NFC_TASK 0x0 0
01-15 10:55:43.140 3982 4080 I libnfc_nci: [INFO:gki_ulinux.cc(89)] gki_task_entry task_id=3, thread_id=eef04a3394f0/eef04a3394f0, pCond/pMutex =0xeef04a4371c0/0xeef04a437198
01-15 10:55:43.141 3982 4080 I libnfc_nci: [INFO:nfc_task.cc(348)] NFC_TASK started.
01-15 10:55:43.141 3982 4080 I libnfc_nci: [INFO:gki_ulinux.cc(585)] GKI_wait task=3, pCond/pMutex = 0xeef04a4371c0/0xeef04a437198
01-15 10:55:43.141 3982 4079 I libnfc_nci: [INFO:gki_ulinux.cc(1117)] GKI_exit_task 0 done
01-15 10:55:43.141 3982 4079 I libnfc_nci: [INFO:NfcAdaptation.cc(423)] NfcAdaptation::Thread: exit
01-15 10:55:43.141 3982 4074 I libnfc_nci: [INFO:NfcAdaptation.cc(323)] NfcAdaptation::Initialize: exit
01-15 10:55:43.141 3982 4079 E libnfc_nci: [ERROR:gki_ulinux.cc(98)] gki_task task_id=0 terminating
01-15 10:55:43.141 3982 4074 I libnfc_nci: [INFO:nfa_dm_api.cc(59)] NFA_Init
01-15 10:55:43.141 3982 4074 I libnfc_nci: [INFO:nfa_dm_main.cc(97)] nfa_dm_init
01-15 10:55:43.141 3982 4074 I libnfc_nci: [INFO:nfa_sys_main.cc(133)] id=1, enable_cplt_mask=0x0
01-15 10:55:43.141 3982 4074 I libnfc_nci: [INFO:nfa_p2p_main.cc(477)] nfa_p2p_init
01-15 10:55:43.141 3982 4074 I libnfc_nci: [INFO:nfa_sys_main.cc(133)] id=3, enable_cplt_mask=0x8
01-15 10:55:43.141 3982 4074 I libnfc_nci: [INFO:nfa_rw_main.cc(70)] nfa_rw_init
01-15 10:55:43.141 3982 4074 I libnfc_nci: [INFO:nfa_sys_main.cc(133)] id=5, enable_cplt_mask=0x28
01-15 10:55:43.141 3982 4074 I libnfc_nci: [INFO:nfa_ce_main.cc(76)] nfa_ce_init
01-15 10:55:43.141 3982 4074 I libnfc_nci: [INFO:nfa_sys_main.cc(133)] id=6, enable_cplt_mask=0x68
01-15 10:55:43.141 3982 4074 I libnfc_nci: [INFO:nfa_ee_main.cc(97)] nfa_ee_init
01-15 10:55:43.141 3982 4074 I libnfc_nci: [INFO:nfa_sys_main.cc(133)] id=2, enable_cplt_mask=0x6c
01-15 10:55:43.141 3982 4074 I libnfc_nci: [INFO:nfa_hci_main.cc(192)] nfa_hci_init
01-15 10:55:43.141 3982 4074 I libnfc_nci: [INFO:nfa_sys_main.cc(133)] id=7, enable_cplt_mask=0xec
01-15 10:55:43.141 3982 4074 I libnfc_nci: [INFO:llcp_main.cc(57)] llcp_init
01-15 10:55:43.141 3982 4074 I libnfc_nci: [INFO:llcp_main.cc(89)] num_rx_buff = 9, rx_congest_start = 6, rx_congest_end = 4, max_num_ll_rx_buf f = 2
01-15 10:55:43.141 3982 4074 I libnfc_nci: [INFO:llcp_main.cc(102)] max_num_tx_buff = 21, max_num_ll_tx_buff = 6
01-15 10:55:43.141 3982 4074 I libnfc_nci: [INFO:llcp_api.cc(369)] SAP:0x1, link_type:0x2, ServiceName:<urn:nfc:sn:sdp>
01-15 10:55:43.141 3982 4074 I libnfc_nci: [INFO:llcp_api.cc(453)] Registered SAP = 0x01
01-15 10:55:43.141 3982 4074 I libnfc_nci: [INFO:nfa_dm_api.cc(101)] NFA_Enable
01-15 10:55:43.141 3982 4080 I libnfc_nci: [INFO:nfa_sys_main.cc(77)] NFA got event 0x0100
01-15 10:55:43.141 3982 4080 I libnfc_nci: [INFO:nfa_dm_main.cc(121)] event: NFA_DM_API_ENABLE_EVT (0x00)
01-15 10:55:43.141 3982 4080 I libnfc_nci: [INFO:nfa_dm_act.cc(433)] nfa_dm_enable
01-15 10:55:43.141 3982 4080 I libnfc_nci: [INFO:nfc_main.cc(664)] NFC_Enable
01-15 10:55:43.141 3982 4080 I libnfc_nci: [INFO:nfc_main.cc(326)] nfc_set_state 0 (NONE)->1 (W4_HAL_OPEN)
01-15 10:55:43.141 3982 4080 I libnfc_nci: [INFO:NfcAdaptation.cc(513)] NfcAdaptation::HalOpen
01-15 10:55:43.143 3215 3215 D android.hardware.nfc@1.1-impl: Nfc::open Enter
01-15 10:55:43.143 3215 3215 D NxpHal : nfc_debug_enabled : 1
01-15 10:55:43.144 3215 3215 D NxpNfcHal: phNxpLog_InitializeLogLevel: global =1, Fwdnld =3, extns =3, hal =3, tml =3, ncir =3, ncix =3
01-15 10:55:43.145 3037 3037 E SELinux : avc: denied { find } for interface=vendor.nxp.nxpese::INxpEse pid=3215 scontext=u:r:hal_nfc_default: s0 tcontext=u:object_r:default_android_hwservice:s0 tclass=hwservice_manager permissive=1
01-15 10:55:43.145 3215 3215 D NxpExtns: find found NXP_NFC_DEV_NODE=/dev/pn544
01-15 10:55:43.145 3215 3215 E NxpHal : phTmlNfc_Init Failed
01-15 10:55:43.145 3982 4080 I libnfc_nci: [INFO:nfc_main.cc(568)] nfc_main_hal_cback event: HAL_NFC_OPEN_CPLT_EVT(0x0), status=1
01-15 10:55:43.145 3215 3215 D android.hardware.nfc@1.1-impl: Nfc::open Exit
01-15 10:55:43.146 3982 4080 I libnfc_nci: [INFO:nfc_main.cc(379)] HAL event=0x0
01-15 10:55:43.146 3982 4080 I libnfc_nci: [INFO:nfc_main.cc(326)] nfc_set_state 1 (W4_HAL_OPEN)->0 (NONE)
01-15 10:55:43.146 3982 4080 I libnfc_nci: [INFO:nfa_dm_act.cc(268)] NFC_ENABLE_REVT(0x5000)
01-15 10:55:43.146 3982 4080 I libnfc_nci: [INFO:NativeNfcManager.cpp(700)] nfaDeviceManagementCallback: enter; event=0x0
01-15 10:55:43.146 3982 4080 I libnfc_nci: [INFO:NativeNfcManager.cpp(707)] nfaDeviceManagementCallback: NFA_DM_ENABLE_EVT; status=0x3
01-15 10:55:43.150 3982 4074 I libnfc_nci: [INFO:phNxpConfig.cpp(520)] find found NXPLOG_TML_LOGLEVEL=(0x3)
01-15 10:55:43.150 3982 4074 I libnfc_nci:
01-15 10:55:43.150 3982 4074 I libnfc_nci: [INFO:phNxpConfig.cpp(520)] find found NXPLOG_FWDNLD_LOGLEVEL=(0x3)
01-15 10:55:43.150 3982 4074 I libnfc_nci:
01-15 10:55:43.150 3982 4074 I libnfc_nci: [INFO:phNxpConfig.cpp(520)] find found NXPLOG_NCIX_LOGLEVEL=(0x3)
01-15 10:55:43.150 3982 4074 I libnfc_nci:
01-15 10:55:43.150 3982 4074 I libnfc_nci: [INFO:phNxpConfig.cpp(520)] find found NXPLOG_NCIR_LOGLEVEL=(0x3)
01-15 10:55:43.150 3982 4074 I libnfc_nci:
01-15 10:55:43.150 3982 4074 I libnfc_nci: [INFO:phNxpLog.cpp(256)] phNxpLog_InitializeLogLevel: global =1, Fwdnld =3, extns =3, hal =3, tml =3, ncir =3, ncix =3
01-15 10:55:43.150 3982 4074 E libnfc_nci: [ERROR:NativeNfcManager.cpp(1108)] nfcManager_doInitialize: fail nfa enable; error=0x0
01-15 10:55:43.150 3982 4074 I libnfc_nci: [INFO:NfcAdaptation.cc(339)] NfcAdaptation::Finalize: enter
01-15 12:07:56.729 3982 4074 I libnfc_nci: [INFO:gki_ulinux.cc(322)] task (null) dead
01-15 12:07:56.729 3982 4074 I libnfc_nci: [INFO:gki_ulinux.cc(1117)] GKI_exit_task 4 done
01-15 12:07:56.729 3982 4080 E libnfc_nci: [ERROR:gki_ulinux.cc(666)] GKI TASK_DEAD received. exit thread 3...
01-15 12:07:56.729 3982 4080 I libnfc_nci: [INFO:nfc_task.cc(426)] nfc_task terminated
01-15 12:07:56.729 3982 4080 E libnfc_nci: [ERROR:gki_ulinux.cc(98)] gki_task task_id=3 terminating
01-15 12:07:56.829 3982 4074 I libnfc_nci: [INFO:gki_ulinux.cc(322)] task NFCA_TASK dead
01-15 12:07:56.829 3982 4074 I libnfc_nci: [INFO:gki_ulinux.cc(1117)] GKI_exit_task 3 done
01-15 12:07:56.829 3982 4074 I libnfc_nci: [INFO:NfcAdaptation.cc(344)] NfcAdaptation::Finalize: exit
01-15 12:07:56.829 3982 4074 I libnfc_nci: [INFO:NativeNfcManager.cpp(1122)] nfcManager_doInitialize: exit
01-15 12:07:56.830 3982 4074 I com.android.nf: ConfigFile - Parsing file '/vendor/etc/libnfc-nci.conf'
01-15 12:07:56.830 3982 4074 I com.android.nf: ConfigFile - [NFC_DEBUG_ENABLED] = 0x01
01-15 12:07:56.830 3982 4074 I com.android.nf: ConfigFile - [NFA_STORAGE] = "/data/vendor/nfc"
01-15 12:07:56.830 3982 4074 I com.android.nf: ConfigFile - [NCI_HAL_MODULE] = "nfc_nci.pn54x"
01-15 12:07:56.830 3982 4074 I libnfc_nci: [INFO:NfcAdaptation.cc(460)] NfcAdaptation::InitializeHalDeviceContext: INfc::getService()
01-15 12:07:56.831 3982 4074 I libnfc_nci: [INFO:NfcAdaptation.cc(466)] NfcAdaptation::InitializeHalDeviceContext: INfc::getService() returned 0xeef0632e4980 (remote)
01-15 12:07:56.832 3982 4074 W NfcService: Error enabling NFC
01-15 12:07:56.836 3982 4078 I libnfc_nci: [INFO:gki_ulinux.cc(522)] GKI_run exit
01-15 12:07:56.836 3982 4078 I libnfc_nci: [INFO:NfcAdaptation.cc(395)] NfcAdaptation::NFCA_TASK: exit
01-15 12:07:56.836 3982 4078 E libnfc_nci: [ERROR:gki_ulinux.cc(98)] gki_task task_id=4 terminating

Re: NFC not enabling in Android Pie

jeremygeslin — Wed, 15 Jan 2020 17:26:46 GMT

Did you have a look to this document https://community.nxp.com/docs/DOC-342420 ?

Re: NFC not enabling in Android Pie

saschaseefelder — Thu, 16 Jan 2020 10:36:15 GMT

Hi Jeremy, yes I have read that document too and I have applied its attached patches.

further investigation suggests there might be an i2c problem:

imx8mm-var-dart:/system/bin # ./NfcFactoryTestApp

[ 84.714128] pn54x_dev_open : 10,49
----------------------------
NFC Factory Test Application
----------------------------
[ 84.717764] pn54x_dev_ioctl, cmd=1074325761, arg=0
[ 84.725533] pn54x_dev_ioctl bad ioctl 1074325761
[ 84.742979] pn54x_dev_ioctl, cmd=1074325761, arg=1
[ 84.747820] pn54x_dev_ioctl bad ioctl 1074325761
[ 84.752761] pn54x_dev_write : i2c_master_send returned -6
[ 84.759476] pn54x_dev_write : i2c_master_send returned -6
[ 84.766230] pn54x_dev_write : i2c_master_send returned -6
[ 84.772999] pn54x_dev_write : i2c_master_send returned -6
Error communicating with PN71xx NFC Controller
[ 84.779929] pn54x_dev_release : closing 10,49

Re: NFC not enabling in Android Pie

jeremygeslin — Fri, 17 Jan 2020 08:28:26 GMT

Hi,

Indeed you can use "NfcFactoryTestApp" te verify basic communication with PN7150, but make sure the NFC service is not accessing the device in parallel (just make sure NFC service is stopped before running it).

You should then check the following:

- PN7150 is properly supplied (VBAT > 3.3V)

- PN7150 VEN pin is HIGH

- I2C request @ address 0x28 is observed on PN7150 I2C SCL/SDA pins

- PN7150 acks I2C request @ address 0x28

Regards,

Jeremy.

Re: NFC not enabling in Android Pie

saschaseefelder — Fri, 31 Jan 2020 12:23:38 GMT

Hi Jeremy,

I have checked what you suggested and there was indeed a problem with VEN. now VBAT is stable, VEN is high and I2C communication works. However, NFC still cant be enabled in settings:

01-01 00:15:33.923 3201 3201 D nxpnfc@1.0-service: NFC HAL Service 1.1 is starting.
01-01 00:15:33.935 3204 3204 I android.hardware.wifi@1.0-service: Wifi Hal is booting up...
01-01 00:15:33.937 3201 3201 I ServiceManagement: Removing namespace from process name android.hardware.nfc@1.1-service to nfc@1.1-service.
01-01 00:15:33.941 3204 3204 I ServiceManagement: Removing namespace from process name android.hardware.wifi@1.0-service to wifi@1.0-service.
01-01 00:15:33.997 3201 3201 D nxpnfc@1.0-service: NFC service is ready

01-01 00:15:37.850 3325 3369 W PackageParser: Ignoring duplicate uses-permissions/uses-permissions-sdk-m: android.permission.CHANGE_WIFI_STATE in package: com.android.nfc at: Binary XML file line #47
01-01 00:15:37.850 3325 3369 W PackageParser: Ignoring duplicate uses-permissions/uses-permissions-sdk-m: android.permission.ACCESS_WIFI_STATE in package: com.android.nfc at: Binary XML file line #48

01-30 22:46:12.622 3325 3342 I ActivityManager: Start proc 3932:com.android.nfc/1027 for added application com.android.nfc

01-30 22:46:12.747 3932 3932 I NfcService: Starting NFC service

01-30 22:46:12.817 3932 3932 I : [0130/224612.817167:INFO:NfcJniUtil.cpp(47)] NFC Service: loading nci JNI
01-30 22:46:12.820 3932 3932 I com.android.nf: ConfigFile - Parsing file '/vendor/etc/libnfc-nci.conf'
01-30 22:46:12.821 3932 3932 I com.android.nf: ConfigFile - [APPL_TRACE_LEVEL] = 0xFF
01-30 22:46:12.821 3932 3932 I com.android.nf: ConfigFile - [PROTOCOL_TRACE_LEVEL] = 0xFFFFFFFF
01-30 22:46:12.821 3932 3932 I com.android.nf: ConfigFile - [NFC_DEBUG_ENABLED] = 0x01
01-30 22:46:12.821 3932 3932 I com.android.nf: ConfigFile - [NFA_STORAGE] = "/data/vendor/nfc"
01-30 22:46:12.821 3932 3932 I com.android.nf: ConfigFile - [HOST_LISTEN_TECH_MASK] = 0x01
01-30 22:46:12.821 3932 3932 I com.android.nf: ConfigFile - [SCREEN_OFF_POWER_STATE] = 1
01-30 22:46:12.821 3932 3932 I com.android.nf: ConfigFile - [NCI_HAL_MODULE] = "nfc_nci.pn54x"
01-30 22:46:12.821 3932 3932 I com.android.nf: ConfigFile - [POLLING_TECH_MASK] = 0x41
01-30 22:46:12.821 3932 3932 I com.android.nf: ConfigFile - [P2P_LISTEN_TECH_MASK] = 0x0
01-30 22:46:12.821 3932 3932 I com.android.nf: ConfigFile - [PRESERVE_STORAGE] = 0x01
01-30 22:46:12.821 3932 3932 I : [0130/224612.821492:INFO:NfcAdaptation.cc(460)] NfcAdaptation::InitializeHalDeviceContext: INfc::getService()
01-30 22:46:12.826 3932 3932 I : [0130/224612.826732:INFO:NfcAdaptation.cc(466)] NfcAdaptation::InitializeHalDeviceContext: INfc::getService() returned 0xfc070e4e4360 (remote)
01-30 22:46:12.874 3201 3201 D NxpHal : persist_uicc_enabled : 0

01-30 22:46:12.878 3201 3201 D NxpExtns: find found NFA_PROPRIETARY_CFG= üÇp
01-30 22:46:12.880 3932 3932 I : [0130/224612.880132:INFO:NativeNfcManager.cpp(190)] initializeGlobalDebugEnabledFlag: level=1
01-30 22:46:12.880 3932 3932 I : [0130/224612.880266:INFO:NativeNfcManager.cpp(622)] nfcManager_initNativeStruc: enter
01-30 22:46:12.880 3932 3932 I : [0130/224612.880603:INFO:NativeNfcManager.cpp(683)] nfcManager_initNativeStruc: exit
01-30 22:46:13.100 3932 3932 D RegisteredNfcFServicesCache: Dynamic System Code, NFCID2 file does not exist.
01-30 22:46:13.102 3932 3932 D RegisteredNfcFServicesCache: Service unchanged, not updating
01-30 22:46:13.109 3932 4026 D NfcService: checking on firmware download
01-30 22:46:13.109 3932 4026 D NfcService: NFC is on. Doing normal stuff
01-30 22:46:13.110 3932 4026 I NfcService: Enabling NFC
01-30 22:46:13.122 3932 4026 I : [0130/224613.122647:INFO:NativeNfcManager.cpp(190)] initializeGlobalDebugEnabledFlag: level=1
01-30 22:46:13.122 3932 4026 I libnfc_nci: [INFO:NfcAdaptation.cc(93)] initializeGlobalDebugEnabledFlag: level=1
01-30 22:46:13.123 3932 4026 I libnfc_nci: [INFO:libmain.cc(196)] verify_stack_non_volatile_store
01-30 22:46:13.123 3932 4026 I libnfc_nci: [INFO:CrcChecksum.cc(94)] crcChecksumVerifyIntegrity: filename=/data/vendor/nfc/nfaStorage.bin1
01-30 22:46:13.123 3932 4026 I libnfc_nci: [INFO:CrcChecksum.cc(94)] crcChecksumVerifyIntegrity: filename=/data/vendor/nfc/nfaStorage.bin4
01-30 22:46:13.123 3932 4026 I libnfc_nci: [INFO:CrcChecksum.cc(94)] crcChecksumVerifyIntegrity: filename=/data/vendor/nfc/nfaStorage.bin2
01-30 22:46:13.123 3932 4026 I libnfc_nci: [INFO:CrcChecksum.cc(94)] crcChecksumVerifyIntegrity: filename=/data/vendor/nfc/nfaStorage.bin3
01-30 22:46:13.123 3932 4026 I libnfc_nci: [INFO:CrcChecksum.cc(94)] crcChecksumVerifyIntegrity: filename=/data/vendor/nfc/nfaStorage.bin5
01-30 22:46:13.123 3932 4026 I libnfc_nci: [INFO:NfcAdaptation.cc(305)] Initialize: preserve stack NV store
01-30 22:46:13.124 3932 4026 I libnfc_nci: [INFO:gki_ulinux.cc(194)] GKI_create_task func=0x0xfc06f5dcfca8 id=4 name=NFCA_TASK stack=0x0x0 stackSize=0
01-30 22:46:13.124 3932 4026 I libnfc_nci: [INFO:gki_ulinux.cc(220)] GKI creating task 4, pCond/pMutex=0x0/0x0
01-30 22:46:13.125 3932 4026 I libnfc_nci: [INFO:gki_ulinux.cc(262)] Leaving GKI_create_task 0xfc06f5dcfca8 4 fc06f56b74f0 NFCA_TASK 0x0 0
01-30 22:46:13.125 3932 4026 I libnfc_nci: [INFO:gki_ulinux.cc(194)] GKI_create_task func=0x0xfc06f5dcfe28 id=0 name=NFCA_THREAD stack=0x0x0 stackSize=0
01-30 22:46:13.125 3932 4026 I libnfc_nci: [INFO:gki_ulinux.cc(220)] GKI creating task 0, pCond/pMutex=0x0/0x0
01-30 22:46:13.125 3932 4026 I libnfc_nci: [INFO:gki_ulinux.cc(262)] Leaving GKI_create_task 0xfc06f5dcfe28 0 fc06f55b94f0 NFCA_THREAD 0x0 0
01-30 22:46:13.127 3932 4031 I libnfc_nci: [INFO:gki_ulinux.cc(89)] gki_task_entry task_id=0, thread_id=fc06f55b94f0/fc06f55b94f0, pCond/pMutex=0x0/0x0
01-30 22:46:13.129 3932 4030 I libnfc_nci: [INFO:gki_ulinux.cc(89)] gki_task_entry task_id=4, thread_id=fc06f56b74f0/fc06f56b74f0, pCond/pMutex=0x0/0x0
01-30 22:46:13.129 3932 4030 I libnfc_nci: [INFO:NfcAdaptation.cc(393)] NfcAdaptation::NFCA_TASK: enter
01-30 22:46:13.129 3932 4030 I libnfc_nci: [INFO:gki_ulinux.cc(450)] GKI_run enter
01-30 22:46:13.129 3932 4030 I libnfc_nci: [INFO:gki_ulinux.cc(460)] Start/Stop GKI_timer_update_registered!
01-30 22:46:13.129 3932 4030 I libnfc_nci: [INFO:gki_ulinux.cc(479)] GKI_run, run_cond(0xfc06f5e12b70)=1
01-30 22:46:13.129 3932 4031 I libnfc_nci: [INFO:NfcAdaptation.cc(410)] NfcAdaptation::Thread: enter
01-30 22:46:13.129 3932 4031 I libnfc_nci: [INFO:gki_ulinux.cc(194)] GKI_create_task func=0x0xfc06f5daadb8 id=3 name=NFC_TASK stack=0x0x0 stackSize=0
01-30 22:46:13.130 3932 4031 I libnfc_nci: [INFO:gki_ulinux.cc(220)] GKI creating task 3, pCond/pMutex=0xfc06f55b91c0/0xfc06f55b9198
01-30 22:46:13.130 3932 4031 I libnfc_nci: [INFO:gki_ulinux.cc(262)] Leaving GKI_create_task 0xfc06f5daadb8 3 fc06f54bb4f0 NFC_TASK 0x0 0
01-30 22:46:13.130 3932 4032 I libnfc_nci: [INFO:gki_ulinux.cc(89)] gki_task_entry task_id=3, thread_id=fc06f54bb4f0/fc06f54bb4f0, pCond/pMutex=0xfc06f55b91c0/0xfc06f55b9198
01-30 22:46:13.130 3932 4032 I libnfc_nci: [INFO:nfc_task.cc(348)] NFC_TASK started.
01-30 22:46:13.130 3932 4032 I libnfc_nci: [INFO:gki_ulinux.cc(585)] GKI_wait task=3, pCond/pMutex = 0xfc06f55b91c0/0xfc06f55b9198
01-30 22:46:13.130 3932 4031 I libnfc_nci: [INFO:gki_ulinux.cc(1117)] GKI_exit_task 0 done
01-30 22:46:13.130 3932 4031 I libnfc_nci: [INFO:NfcAdaptation.cc(423)] NfcAdaptation::Thread: exit
01-30 22:46:13.130 3932 4026 I libnfc_nci: [INFO:NfcAdaptation.cc(323)] NfcAdaptation::Initialize: exit
01-30 22:46:13.130 3932 4031 E libnfc_nci: [ERROR:gki_ulinux.cc(98)] gki_task task_id=0 terminating
01-30 22:46:13.130 3932 4026 I libnfc_nci: [INFO:nfa_dm_api.cc(59)] NFA_Init
01-30 22:46:13.130 3932 4026 I libnfc_nci: [INFO:nfa_dm_main.cc(97)] nfa_dm_init
01-30 22:46:13.130 3932 4026 I libnfc_nci: [INFO:nfa_sys_main.cc(133)] id=1, enable_cplt_mask=0x0
01-30 22:46:13.130 3932 4026 I libnfc_nci: [INFO:nfa_p2p_main.cc(477)] nfa_p2p_init
01-30 22:46:13.130 3932 4026 I libnfc_nci: [INFO:nfa_sys_main.cc(133)] id=3, enable_cplt_mask=0x8
01-30 22:46:13.130 3932 4026 I libnfc_nci: [INFO:nfa_rw_main.cc(70)] nfa_rw_init
01-30 22:46:13.130 3932 4026 I libnfc_nci: [INFO:nfa_sys_main.cc(133)] id=5, enable_cplt_mask=0x28
01-30 22:46:13.130 3932 4026 I libnfc_nci: [INFO:nfa_ce_main.cc(76)] nfa_ce_init
01-30 22:46:13.130 3932 4026 I libnfc_nci: [INFO:nfa_sys_main.cc(133)] id=6, enable_cplt_mask=0x68
01-30 22:46:13.130 3932 4026 I libnfc_nci: [INFO:nfa_ee_main.cc(97)] nfa_ee_init
01-30 22:46:13.130 3932 4026 I libnfc_nci: [INFO:nfa_sys_main.cc(133)] id=2, enable_cplt_mask=0x6c
01-30 22:46:13.130 3932 4026 I libnfc_nci: [INFO:nfa_hci_main.cc(192)] nfa_hci_init
01-30 22:46:13.130 3932 4026 I libnfc_nci: [INFO:nfa_sys_main.cc(133)] id=7, enable_cplt_mask=0xec
01-30 22:46:13.130 3932 4026 I libnfc_nci: [INFO:llcp_main.cc(57)] llcp_init
01-30 22:46:13.130 3932 4026 I libnfc_nci: [INFO:llcp_main.cc(89)] num_rx_buff = 9, rx_congest_start = 6, rx_congest_end = 4, max_num_ll_rx_buff = 2
01-30 22:46:13.130 3932 4026 I libnfc_nci: [INFO:llcp_main.cc(102)] max_num_tx_buff = 21, max_num_ll_tx_buff = 6
01-30 22:46:13.130 3932 4026 I libnfc_nci: [INFO:llcp_api.cc(369)] SAP:0x1, link_type:0x2, ServiceName:<urn:nfc:sn:sdp>
01-30 22:46:13.130 3932 4026 I libnfc_nci: [INFO:llcp_api.cc(453)] Registered SAP = 0x01
01-30 22:46:13.130 3932 4026 I libnfc_nci: [INFO:nfa_dm_api.cc(101)] NFA_Enable
01-30 22:46:13.131 3932 4032 I libnfc_nci: [INFO:nfa_sys_main.cc(77)] NFA got event 0x0100
01-30 22:46:13.131 3932 4032 I libnfc_nci: [INFO:nfa_dm_main.cc(121)] event: NFA_DM_API_ENABLE_EVT (0x00)
01-30 22:46:13.131 3932 4032 I libnfc_nci: [INFO:nfa_dm_act.cc(433)] nfa_dm_enable
01-30 22:46:13.131 3932 4032 I libnfc_nci: [INFO:nfc_main.cc(664)] NFC_Enable
01-30 22:46:13.131 3932 4032 I libnfc_nci: [INFO:nfc_main.cc(326)] nfc_set_state 0 (NONE)->1 (W4_HAL_OPEN)
01-30 22:46:13.131 3932 4032 I libnfc_nci: [INFO:NfcAdaptation.cc(513)] NfcAdaptation::HalOpen
01-30 22:46:13.131 3201 3201 D android.hardware.nfc@1.1-impl: Nfc::open Enter
01-30 22:46:13.131 3201 3201 D NxpHal : phNxpNci_MinOpen(): enter
01-30 22:46:13.132 3201 3201 D NxpHal : nfc_debug_enabled : 1
01-30 22:46:13.132 3201 3201 D NxpExtns: find found NXPLOG_NCIHAL_LOGLEVEL=(0x3)
01-30 22:46:13.132 3201 3201 D NxpExtns: find found NXPLOG_EXTNS_LOGLEVEL=(0x3)
01-30 22:46:13.132 3201 3201 D NxpExtns: find found NXPLOG_TML_LOGLEVEL=(0x3)
01-30 22:46:13.132 3201 3201 D NxpExtns: find found NXPLOG_FWDNLD_LOGLEVEL=(0x3)
01-30 22:46:13.132 3201 3201 D NxpExtns: find found NXPLOG_NCIX_LOGLEVEL=(0x3)
01-30 22:46:13.132 3201 3201 D NxpExtns: find found NXPLOG_NCIR_LOGLEVEL=(0x3)
01-30 22:46:13.132 3201 3201 D NxpNfcHal: phNxpLog_InitializeLogLevel: global =1, Fwdnld =3, extns =3, hal =3, tml =3, ncir =3, ncix =3
01-30 22:46:13.132 3201 3201 D NxpHal : Entering phNxpNciHal_init_monitor
01-30 22:46:13.132 3201 3201 D NxpHal : Returning with SUCCESS
01-30 22:46:13.132 3201 3201 D EseAdaptation: EseAdaptation::Initialize: enter
01-30 22:46:13.132 3201 3201 D EseAdaptation: EseAdaptation::InitializeHalDeviceContext: enter
01-30 22:46:13.132 3201 3201 D EseAdaptation: EseAdaptation::InitializeHalDeviceContext: INxpEse::tryGetService()
01-30 22:46:13.136 3050 3050 W hwservicemanager: getTransport: Cannot find entry vendor.nxp.nxpese@1.0::INxpEse/default in either framework or device manifest.
01-30 22:46:13.136 3201 3201 D EseAdaptation: EseAdaptation::InitializeHalDeviceContext: Failed to retrieve the NXP ESE HAL!
01-30 22:46:13.136 3201 3201 D EseAdaptation: EseAdaptation::InitializeHalDeviceContext: exit
01-30 22:46:13.136 3201 3201 D EseAdaptation: EseAdaptation::Initialize: exit
01-30 22:46:13.136 3201 3201 D NxpExtns: find found NXP_NFC_DEV_NODE=/dev/pn544
01-30 22:46:13.136 3201 3201 D NxpTml : Opening port=/dev/pn544
01-30 22:46:13.140 3201 3201 D NxpTml : phTmlNfc_i2c_reset(), VEN level 0
01-30 22:46:13.263 3201 3201 D NxpTml : phTmlNfc_i2c_reset(), VEN level 1
01-30 22:46:13.379 3201 4096 D NxpTml : PN54X - Tml Reader Thread Started................
01-30 22:46:13.379 3201 4096 D NxpTml : PN54X - Read requested.....
01-30 22:46:13.379 3201 4096 D NxpTml : PN54X - Invoking I2C Read.....
01-30 22:46:13.379 3201 4099 D NxpHal : thread started
01-30 22:46:13.383 3201 4097 D NxpTml : PN54X - Tml Writer Thread Started................
01-30 22:46:13.383 3201 4097 D NxpTml : PN54X - Tml Writer Thread Running................
01-30 22:46:13.383 3201 4097 D NxpTml : PN54X - Write requested.....
01-30 22:46:13.383 3201 4097 D NxpTml : PN54X - Invoking I2C Write.....
01-30 22:46:13.385 3201 4097 D NxpNciX : len = 4 > 20000100
01-30 22:46:13.385 3201 4097 D NxpTml : PN54X - I2C Write successful.....
01-30 22:46:13.385 3201 4097 D NxpTml : PN54X - Posting Fresh Write message.....
01-30 22:46:13.385 3201 4097 D NxpTml : PN54X - Tml Writer Thread Running................
01-30 22:46:13.385 3201 4099 D NxpHal : write successful status = 0x0
01-30 22:46:13.385 3201 3201 D NxpHal : Response timer started
01-30 22:46:13.385 3201 3201 D NxpHal : Waiting after ext cmd sent
01-30 22:46:13.390 3201 4096 D NxpTml : PN54X - I2C Read successful.....
01-30 22:46:13.390 3201 4096 D NxpNciR : len = 6 > 400003001100
01-30 22:46:13.390 3201 4096 D NxpTml : PN54X - Posting read message.....
01-30 22:46:13.390 3201 4099 D NxpHal : read successful status = 0x0
01-30 22:46:13.390 3201 4099 D NxpHal : CORE_RESET_RSP NCI1.0
01-30 22:46:13.390 3201 3201 D NxpHal : Response timer stopped
01-30 22:46:13.390 3201 3201 D NxpHal : Checking response
01-30 22:46:13.390 3201 4096 D NxpTml : PN54X - Read requested.....
01-30 22:46:13.390 3201 4096 D NxpTml : PN54X - Invoking I2C Read.....
01-30 22:46:13.390 3201 4097 D NxpTml : PN54X - Write requested.....
01-30 22:46:13.390 3201 4097 D NxpTml : PN54X - Invoking I2C Write.....
01-30 22:46:13.392 3201 4097 D NxpNciX : len = 3 > 200100
01-30 22:46:13.392 3201 4097 D NxpTml : PN54X - I2C Write successful.....
01-30 22:46:13.392 3201 4097 D NxpTml : PN54X - Posting Fresh Write message.....
01-30 22:46:13.392 3201 4097 D NxpTml : PN54X - Tml Writer Thread Running................
01-30 22:46:13.393 3201 4099 D NxpHal : write successful status = 0x0
01-30 22:46:13.393 3201 3201 D NxpHal : Response timer started
01-30 22:46:13.393 3201 3201 D NxpHal : Waiting after ext cmd sent
01-30 22:46:13.399 3201 4096 D NxpTml : PN54X - I2C Read successful.....
01-30 22:46:13.399 3201 4096 D NxpNciR : len = 28 > 40011900031E030008000102038081828302D002FF020004881001A0
01-30 22:46:13.399 3201 4096 D NxpTml : PN54X - Posting read message.....
01-30 22:46:13.399 3201 4099 D NxpHal : read successful status = 0x0
01-30 22:46:13.399 3201 4099 D NxpHal : CORE_INIT_RSP NCI1.0 received !
01-30 22:46:13.399 3201 4099 D NxpHal : getChipType HwVersion : 0x88
01-30 22:46:13.399 3201 4099 D NxpHal : getChipType Product : PN548C2
01-30 22:46:13.399 3201 4099 D NxpHal : phNxpNciHal_configFeatureList chipType = 3
01-30 22:46:13.399 3201 4099 D NxpHal : NxpNci> FW Version: 10.1.a0
01-30 22:46:13.399 3201 3201 D NxpHal : Response timer stopped
01-30 22:46:13.399 3201 3201 D NxpHal : Checking response
01-30 22:46:13.399 3201 3201 D NxpExtns: find found NXP_I2C_FRAGMENTATION_ENABLED=(0x0)
01-30 22:46:13.399 3201 4097 D NxpTml : PN54X - Write requested.....
01-30 22:46:13.399 3201 4097 D NxpTml : PN54X - Invoking I2C Write.....
01-30 22:46:13.399 3201 4096 D NxpTml : PN54X - Read requested.....
01-30 22:46:13.399 3201 4096 D NxpTml : PN54X - Invoking I2C Read.....
01-30 22:46:13.401 3201 4097 D NxpNciX : len = 6 > 20030301A005
01-30 22:46:13.401 3201 4097 D NxpTml : PN54X - I2C Write successful.....
01-30 22:46:13.401 3201 4097 D NxpTml : PN54X - Posting Fresh Write message.....
01-30 22:46:13.401 3201 4097 D NxpTml : PN54X - Tml Writer Thread Running................
01-30 22:46:13.402 3201 4099 D NxpHal : write successful status = 0x0
01-30 22:46:13.402 3201 3201 D NxpHal : Response timer started
01-30 22:46:13.402 3201 3201 D NxpHal : Waiting after ext cmd sent
01-30 22:46:13.407 3201 4096 D NxpTml : PN54X - I2C Read successful.....
01-30 22:46:13.407 3201 4096 D NxpNciR : len = 9 > 4003060001A0050100
01-30 22:46:13.407 3201 4096 D NxpTml : PN54X - Posting read message.....
01-30 22:46:13.408 3201 4099 D NxpHal : read successful status = 0x0
01-30 22:46:13.408 3201 4099 D NxpHal : phNxpNciHal_print_res_status: response status =STATUS_OK
01-30 22:46:13.408 3201 3201 D NxpHal : Response timer stopped
01-30 22:46:13.408 3201 3201 D NxpHal : Checking response
01-30 22:46:13.408 3201 3201 D NxpFwDnld: Allocating Mem for Dnld Context..
01-30 22:46:13.408 3201 3201 W NxpFwDnld: firmware type not found. Taking default value: 1
01-30 22:46:13.408 3201 3201 D NxpFwDnld: @@@
01-30 22:46:13.408 3201 3201 E NxpFwDnld: NULL handler : unable to load the library file, specify correct path
01-30 22:46:13.408 3201 3201 E NxpFwDnld: FW Image Length - ImageInfoLen 0
01-30 22:46:13.408 3201 3201 E NxpFwDnld: FW Image Info Pointer - pImageInfo 0x0
01-30 22:46:13.408 3201 3201 E NxpFwDnld: Image extraction Failed - invalid imginfo or imginfolen!!
01-30 22:46:13.408 3201 3201 E NxpFwDnld: Error loading libpn54x_fw !!
01-30 22:46:13.408 3201 3201 E NxpHal : FW version for FW file = 0x0
01-30 22:46:13.408 3201 3201 E NxpHal : FW version from device = 0x1001a0
01-30 22:46:13.408 3201 3201 D NxpHal : phNxpNciHal_ioctl : enter - arg = 25
01-30 22:46:13.408 3201 3201 D NxpTml : phPalEse_spi_ioctl(), ioctl 8008 , level 3
01-30 22:46:13.412 3201 4096 D NxpTml : PN54X - Read requested.....
01-30 22:46:13.412 3201 4096 D NxpTml : PN54X - Invoking I2C Read.....
01-30 22:46:13.422 3201 3201 D NxpHal : phNxpNciHal_ioctl : exit - ret = -1
01-30 22:46:13.422 3201 3201 D NxpHal : eSE Power GPIO value = -1
01-30 22:46:13.422 3201 3201 E NxpHal : FW download denied while SPI in use, Continue NFC init
01-30 22:46:13.422 3201 3201 D NxpHal : FW download Rejected. Continuiing Nfc Init
01-30 22:46:13.422 3201 3201 D NxpHal : phNxpNciHal_MinOpen(): exit
01-30 22:46:13.423 3932 4033 I libnfc_nci: [INFO:nfc_main.cc(568)] nfc_main_hal_cback event: HAL_NFC_OPEN_CPLT_EVT(0x0), status=0
01-30 22:46:13.423 3201 3201 D android.hardware.nfc@1.1-impl: Nfc::open Exit
01-30 22:46:13.423 3932 4032 I libnfc_nci: [INFO:nfc_task.cc(358)] NFC_TASK got NFC_TASK_EVT_TRANSPORT_READY.
01-30 22:46:13.423 3932 4032 I libnfc_nci: [INFO:nfc_main.cc(326)] nfc_set_state 1 (W4_HAL_OPEN)->2 (CORE_INIT)
01-30 22:46:13.423 3932 4032 I libnfc_nci: [INFO:NfcAdaptation.cc(583)] NfcAdaptation::HalWrite
01-30 22:46:13.423 3201 3201 D NxpExtns: find found MIFARE_READER_ENABLE=(0x1)
01-30 22:46:13.424 3201 4097 D NxpTml : PN54X - Write requested.....
01-30 22:46:13.424 3201 4097 D NxpTml : PN54X - Invoking I2C Write.....
01-30 22:46:13.426 3201 4097 D NxpNciX : len = 4 > 20000101
01-30 22:46:13.426 3201 4097 D NxpTml : PN54X - I2C Write successful.....
01-30 22:46:13.426 3201 4097 D NxpTml : PN54X - Posting Fresh Write message.....
01-30 22:46:13.426 3201 4097 D NxpTml : PN54X - Tml Writer Thread Running................
01-30 22:46:13.426 3201 4099 D NxpHal : write successful status = 0x0
01-30 22:46:13.430 3201 4096 D NxpTml : PN54X - I2C Read successful.....
01-30 22:46:13.430 3201 4096 D NxpNciR : len = 6 > 400003001101
01-30 22:46:13.430 3201 4096 D NxpTml : PN54X - Posting read message.....
01-30 22:46:13.430 3201 4099 D NxpHal : read successful status = 0x0
01-30 22:46:13.430 3201 4099 D NxpHal : CORE_RESET_RSP NCI1.0
01-30 22:46:13.431 3201 4096 D NxpTml : PN54X - Read requested.....
01-30 22:46:13.431 3201 4096 D NxpTml : PN54X - Invoking I2C Read.....
01-30 22:46:13.431 3932 4032 I libnfc_nci: [INFO:nfc_ncif.cc(382)] NFC received rsp gid:0
01-30 22:46:13.431 3932 4032 I libnfc_nci: [INFO:nci_hrcv.cc(62)] nci_proc_core_rsp opcode:0x0
01-30 22:46:13.431 3932 4032 I libnfc_nci: [INFO:nfc_ncif.cc(1344)] CORE_RESET_RSP len :0x3
01-30 22:46:13.431 3932 4032 I libnfc_nci: [INFO:NfcAdaptation.cc(583)] NfcAdaptation::HalWrite
01-30 22:46:13.431 3201 3201 D NxpExtns: find found MIFARE_READER_ENABLE=(0x1)
01-30 22:46:13.431 3201 4097 D NxpTml : PN54X - Write requested.....
01-30 22:46:13.431 3201 4097 D NxpTml : PN54X - Invoking I2C Write.....
01-30 22:46:13.433 3201 4097 D NxpNciX : len = 3 > 200100
01-30 22:46:13.433 3201 4097 D NxpTml : PN54X - I2C Write successful.....
01-30 22:46:13.433 3201 4097 D NxpTml : PN54X - Posting Fresh Write message.....
01-30 22:46:13.434 3201 4097 D NxpTml : PN54X - Tml Writer Thread Running................
01-30 22:46:13.434 3201 4099 D NxpHal : write successful status = 0x0
01-30 22:46:13.443 3201 4096 D NxpTml : PN54X - I2C Read successful.....
01-30 22:46:13.443 3201 4096 D NxpNciR : len = 28 > 40011900031E030008000102038081828302D002FF020004881001A0
01-30 22:46:13.443 3201 4096 D NxpTml : PN54X - Posting read message.....
01-30 22:46:13.443 3201 4099 D NxpHal : read successful status = 0x0
01-30 22:46:13.443 3201 4099 D NxpHal : CORE_INIT_RSP NCI1.0 received !
01-30 22:46:13.443 3201 4099 D NxpHal : NxpNci> FW Version: 10.1.a0
01-30 22:46:13.443 3932 4032 I libnfc_nci: [INFO:nfc_ncif.cc(382)] NFC received rsp gid:0
01-30 22:46:13.443 3201 4096 D NxpTml : PN54X - Read requested.....
01-30 22:46:13.443 3201 4096 D NxpTml : PN54X - Invoking I2C Read.....
01-30 22:46:13.443 3932 4032 I libnfc_nci: [INFO:nci_hrcv.cc(62)] nci_proc_core_rsp opcode:0x1
01-30 22:46:13.443 3932 4032 I libnfc_nci: [INFO:nfc_ncif.cc(1400)] scbr support: 0x1
01-30 22:46:13.443 3932 4032 I libnfc_nci: [INFO:nfc_main.cc(326)] nfc_set_state 2 (CORE_INIT)->3 (W4_POST_INIT_CPLT)
01-30 22:46:13.443 3932 4032 I libnfc_nci: [INFO:NfcAdaptation.cc(601)] NfcAdaptation::HalCoreInitialized
01-30 22:46:13.444 3201 3201 D NxpExtns: find found NXP_ACT_PROP_EXTN=/
01-30 22:46:13.444 3201 4097 D NxpTml : PN54X - Write requested.....
01-30 22:46:13.444 3201 4097 D NxpTml : PN54X - Invoking I2C Write.....
01-30 22:46:13.445 3201 4097 D NxpNciX : len = 3 > 2F0200
01-30 22:46:13.445 3201 4097 D NxpTml : PN54X - I2C Write successful.....
01-30 22:46:13.445 3201 4097 D NxpTml : PN54X - Posting Fresh Write message.....
01-30 22:46:13.445 3201 4097 D NxpTml : PN54X - Tml Writer Thread Running................
01-30 22:46:13.446 3201 4099 D NxpHal : write successful status = 0x0
01-30 22:46:13.446 3201 3201 D NxpHal : Response timer started
01-30 22:46:13.446 3201 3201 D NxpHal : Waiting after ext cmd sent
01-30 22:46:13.448 3201 4096 D NxpTml : PN54X - I2C Read successful.....
01-30 22:46:13.448 3201 4096 D NxpNciR : len = 8 > 4F02050000009F97
01-30 22:46:13.448 3201 4096 D NxpTml : PN54X - Posting read message.....
01-30 22:46:13.448 3201 4099 D NxpHal : read successful status = 0x0
01-30 22:46:13.448 3201 3201 D NxpHal : Response timer stopped
01-30 22:46:13.448 3201 3201 D NxpHal : Checking response
01-30 22:46:13.448 3201 4096 D NxpTml : PN54X - Read requested.....
01-30 22:46:13.448 3201 4096 D NxpTml : PN54X - Invoking I2C Read.....
01-30 22:46:13.451 3201 4097 D NxpTml : PN54X - Write requested.....
01-30 22:46:13.451 3201 4097 D NxpTml : PN54X - Invoking I2C Write.....
01-30 22:46:13.453 3201 4097 D NxpNciX : len = 8 > 20020501A0070103
01-30 22:46:13.453 3201 4097 D NxpTml : PN54X - I2C Write successful.....
01-30 22:46:13.453 3201 4097 D NxpTml : PN54X - Posting Fresh Write message.....
01-30 22:46:13.454 3201 4099 D NxpHal : write successful status = 0x0
01-30 22:46:13.454 3201 3201 D NxpHal : Response timer started
01-30 22:46:13.454 3201 3201 D NxpHal : Waiting after ext cmd sent
01-30 22:46:13.454 3201 4097 D NxpTml : PN54X - Tml Writer Thread Running................
01-30 22:46:13.456 3201 4096 D NxpTml : PN54X - I2C Read successful.....
01-30 22:46:13.456 3201 4096 D NxpNciR : len = 5 > 4002020000
01-30 22:46:13.456 3201 4096 D NxpTml : PN54X - Posting read message.....
01-30 22:46:13.456 3201 4099 D NxpHal : read successful status = 0x0
01-30 22:46:13.456 3201 4099 D NxpHal : phNxpNciHal_print_res_status: response status =STATUS_OK
01-30 22:46:13.456 3201 3201 D NxpHal : Response timer stopped
01-30 22:46:13.456 3201 3201 D NxpHal : Checking response
01-30 22:46:13.456 3201 4097 D NxpTml : PN54X - Write requested.....
01-30 22:46:13.456 3201 4097 D NxpTml : PN54X - Invoking I2C Write.....
01-30 22:46:13.457 3201 4096 D NxpTml : PN54X - Read requested.....
01-30 22:46:13.458 3201 4097 D NxpNciX : len = 6 > 20030301A00F
01-30 22:46:13.458 3201 4097 D NxpTml : PN54X - I2C Write successful.....
01-30 22:46:13.458 3201 4097 D NxpTml : PN54X - Posting Fresh Write message.....
01-30 22:46:13.458 3201 4097 D NxpTml : PN54X - Tml Writer Thread Running................
01-30 22:46:13.459 3201 4099 D NxpHal : write successful status = 0x0
01-30 22:46:13.459 3201 3201 D NxpHal : Response timer started
01-30 22:46:13.459 3201 3201 D NxpHal : Waiting after ext cmd sent
01-30 22:46:13.463 3201 4096 D NxpTml : PN54X - Invoking I2C Read.....
01-30 22:46:13.476 3201 4096 D NxpTml : PN54X - I2C Read successful.....
01-30 22:46:13.476 3201 4096 D NxpNciR : len = 40 > 4003250001A00F200000000000000000000000000000000000000000000000000000000000000000
01-30 22:46:13.476 3201 4096 D NxpTml : PN54X - Posting read message.....
01-30 22:46:13.476 3201 4099 D NxpHal : read successful status = 0x0
01-30 22:46:13.476 3201 4099 D NxpHal : phNxpNciHal_print_res_status: response status =STATUS_OK
01-30 22:46:13.476 3201 3201 D NxpHal : Response timer stopped
01-30 22:46:13.476 3201 3201 D NxpHal : Checking response
01-30 22:46:13.476 3201 3201 D NxpExtns: find found NXP_SYS_CLK_SRC_SEL=(0x1)
01-30 22:46:13.476 3201 3201 D NxpExtns: find found NXP_SYS_CLK_FREQ_SEL=(0x0)
01-30 22:46:13.476 3201 3201 D NxpExtns: find found NXP_SYS_CLOCK_TO_CFG=(0x1)
01-30 22:46:13.476 3201 3201 D NxpFwDnld: gphNxpNciHal_fw_IoctlCtx.bClkSrcVal = 0x1
01-30 22:46:13.476 3201 3201 D NxpFwDnld: gphNxpNciHal_fw_IoctlCtx.bClkFreqVal = 0x0
01-30 22:46:13.476 3201 3201 D NxpFwDnld: gphNxpNciHal_fw_IoctlCtx.bClkFreqVal = 0x1
01-30 22:46:13.476 3201 4097 D NxpTml : PN54X - Write requested.....
01-30 22:46:13.476 3201 4097 D NxpTml : PN54X - Invoking I2C Write.....
01-30 22:46:13.476 3201 4096 D NxpTml : PN54X - Read requested.....
01-30 22:46:13.476 3201 4096 D NxpTml : PN54X - Invoking I2C Read.....
01-30 22:46:13.479 3201 4097 D NxpNciX : len = 10 > 20030703A002A003A004
01-30 22:46:13.479 3201 4097 D NxpTml : PN54X - I2C Write successful.....
01-30 22:46:13.479 3201 4097 D NxpTml : PN54X - Posting Fresh Write message.....
01-30 22:46:13.479 3201 4097 D NxpTml : PN54X - Tml Writer Thread Running................
01-30 22:46:13.479 3201 4099 D NxpHal : write successful status = 0x0
01-30 22:46:13.479 3201 3201 D NxpHal : Response timer started
01-30 22:46:13.479 3201 3201 D NxpHal : Waiting after ext cmd sent
01-30 22:46:13.484 3201 4096 D NxpTml : PN54X - I2C Read successful.....
01-30 22:46:13.484 3201 4096 D NxpNciR : len = 17 > 40030E0003A0020101A0030108A0040101
01-30 22:46:13.484 3201 4096 D NxpTml : PN54X - Posting read message.....
01-30 22:46:13.484 3201 4099 D NxpHal : read successful status = 0x0
01-30 22:46:13.484 3201 4099 D NxpHal : phNxpNciHal_print_res_status: response status =STATUS_OK
01-30 22:46:13.484 3201 3201 D NxpHal : Response timer stopped
01-30 22:46:13.484 3201 3201 D NxpHal : Checking response
01-30 22:46:13.484 3201 3201 E : isModified Unable to open file '/data/vendor/nfc/libnfc-nxpConfigState.bin' - assuming modified
01-30 22:46:13.484 3201 4097 D NxpTml : PN54X - Write requested.....
01-30 22:46:13.484 3201 4097 D NxpTml : PN54X - Invoking I2C Write.....
01-30 22:46:13.484 3201 4096 D NxpTml : PN54X - Read requested.....
01-30 22:46:13.484 3201 4096 D NxpTml : PN54X - Invoking I2C Read.....
01-30 22:46:13.486 3201 4097 D NxpNciX : len = 8 > 20020501A0030108
01-30 22:46:13.486 3201 4097 D NxpTml : PN54X - I2C Write successful.....
01-30 22:46:13.486 3201 4097 D NxpTml : PN54X - Posting Fresh Write message.....
01-30 22:46:13.486 3201 4097 D NxpTml : PN54X - Tml Writer Thread Running................
01-30 22:46:13.486 3201 4099 D NxpHal : write successful status = 0x0
01-30 22:46:13.486 3201 3201 D NxpHal : Response timer started
01-30 22:46:13.486 3201 3201 D NxpHal : Waiting after ext cmd sent
01-30 22:46:13.489 3201 4096 D NxpTml : PN54X - I2C Read successful.....
01-30 22:46:13.489 3201 4096 D NxpNciR : len = 5 > 4002020000
01-30 22:46:13.489 3201 4096 D NxpTml : PN54X - Posting read message.....
01-30 22:46:13.489 3201 4099 D NxpHal : read successful status = 0x0
01-30 22:46:13.489 3201 4099 D NxpHal : phNxpNciHal_print_res_status: response status =STATUS_OK
01-30 22:46:13.489 3201 4096 D NxpTml : PN54X - Read requested.....
01-30 22:46:13.489 3201 3201 D NxpHal : Response timer stopped
01-30 22:46:13.489 3201 4096 D NxpTml : PN54X - Invoking I2C Read.....
01-30 22:46:13.489 3201 3201 D NxpHal : Checking response
01-30 22:46:13.489 3201 3201 D NxpExtns: find found NXP_NFC_PROFILE_EXTN= áD
01-30 22:46:13.489 3201 4097 D NxpTml : PN54X - Write requested.....
01-30 22:46:13.489 3201 4097 D NxpTml : PN54X - Invoking I2C Write.....
01-30 22:46:13.491 3201 4097 D NxpNciX : len = 8 > 20020501A0440100
01-30 22:46:13.491 3201 4097 D NxpTml : PN54X - I2C Write successful.....
01-30 22:46:13.491 3201 4097 D NxpTml : PN54X - Posting Fresh Write message.....
01-30 22:46:13.492 3201 4097 D NxpTml : PN54X - Tml Writer Thread Running................
01-30 22:46:13.492 3201 4099 D NxpHal : write successful status = 0x0
01-30 22:46:13.492 3201 3201 D NxpHal : Response timer started
01-30 22:46:13.492 3201 3201 D NxpHal : Waiting after ext cmd sent
01-30 22:46:13.495 3201 4096 D NxpTml : PN54X - I2C Read successful.....
01-30 22:46:13.495 3201 4096 D NxpNciR : len = 5 > 4002020000
01-30 22:46:13.495 3201 4096 D NxpTml : PN54X - Posting read message.....
01-30 22:46:13.495 3201 4099 D NxpHal : read successful status = 0x0
01-30 22:46:13.495 3201 4099 D NxpHal : phNxpNciHal_print_res_status: response status =STATUS_OK
01-30 22:46:13.495 3201 3201 D NxpHal : Response timer stopped
01-30 22:46:13.495 3201 3201 D NxpHal : Checking response
01-30 22:46:13.495 3201 3201 E : isModified Unable to open file '/data/vendor/nfc/libnfc-nxpConfigState.bin' - assuming modified
01-30 22:46:13.495 3201 3201 D NxpHal : Performing TVDD Settings
01-30 22:46:13.495 3201 3201 D NxpExtns: find found NXP_EXT_TVDD_CFG=(0x2)
01-30 22:46:13.495 3201 3201 D NxpExtns: find found NXP_EXT_TVDD_CFG_2= á d
01-30 22:46:13.495 3201 4097 D NxpTml : PN54X - Write requested.....
01-30 22:46:13.495 3201 4097 D NxpTml : PN54X - Invoking I2C Write.....
01-30 22:46:13.495 3201 4096 D NxpTml : PN54X - Read requested.....
01-30 22:46:13.495 3201 4096 D NxpTml : PN54X - Invoking I2C Read.....
01-30 22:46:13.498 3201 4097 D NxpNciX : len = 10 > 20020701A00E03066400
01-30 22:46:13.498 3201 4097 D NxpTml : PN54X - I2C Write successful.....
01-30 22:46:13.498 3201 4097 D NxpTml : PN54X - Posting Fresh Write message.....
01-30 22:46:13.498 3201 4099 D NxpHal : write successful status = 0x0
01-30 22:46:13.498 3201 3201 D NxpHal : Response timer started
01-30 22:46:13.498 3201 3201 D NxpHal : Waiting after ext cmd sent
01-30 22:46:13.498 3201 4097 D NxpTml : PN54X - Tml Writer Thread Running................
01-30 22:46:13.501 3201 4096 D NxpTml : PN54X - I2C Read successful.....
01-30 22:46:13.501 3201 4096 D NxpNciR : len = 5 > 4002020000
01-30 22:46:13.501 3201 4096 D NxpTml : PN54X - Posting read message.....
01-30 22:46:13.501 3201 4099 D NxpHal : read successful status = 0x0
01-30 22:46:13.501 3201 4099 D NxpHal : phNxpNciHal_print_res_status: response status =STATUS_OK
01-30 22:46:13.501 3201 3201 D NxpHal : Response timer stopped
01-30 22:46:13.501 3201 3201 D NxpHal : Checking response
01-30 22:46:13.501 3201 3201 D NxpHal : Performing RF Settings BLK 1
D É á:46:13.501 3201 3201 D NxpExtns: find found NXP_RF_CONF_BLK_1= ú á
01-30 22:46:13.501 3201 4097 D NxpTml : PN54X - Write requested.....
01-30 22:46:13.501 3201 4097 D NxpTml : PN54X - Invoking I2C Write.....
01-30 22:46:13.501 3201 4096 D NxpTml : PN54X - Read requested.....
01-30 22:46:13.501 3201 4096 D NxpTml : PN54X - Invoking I2C Read.....
01-31 08:28:15.568 3201 4097 D NxpNciX : len = 166 > 2002A313A00D0604359001F401A00D06064401900300A00D060630B0011000A00D0606420200FFFFA00D03063F04A00D0620428800FFFFA00D0422442200A00D06222D50340C00A00D063242F800FFFFA00D06342D24370C00A00D06343386800070A00D0434442200A00D06422D15450D00A00D0446442200A00D06462D05590E00A00D0644428800FFFFA00D06562D059F0C00A00D0654428800FFFFA00D060A3380860070
01-31 08:28:15.568 3201 4097 D NxpTml : PN54X - I2C Write successful.....
01-31 08:28:15.568 3201 4097 D NxpTml : PN54X - Posting Fresh Write message.....
01-31 08:28:15.568 3201 4097 D NxpTml : PN54X - Tml Writer Thread Running................
01-31 08:28:15.568 3201 4099 D NxpHal : write successful status = 0x0
01-31 08:28:15.568 3201 3201 D NxpHal : Response timer started
01-31 08:28:15.568 3201 3201 D NxpHal : Waiting after ext cmd sent
01-31 08:28:15.581 3201 4096 D NxpTml : PN54X - I2C Read successful.....
01-31 08:28:15.581 3201 4096 D NxpNciR : len = 41 > 4002260912A00DA00DA00DA00DA00DA00DA00DA00DA00DA00DA00DA00DA00DA00DA00DA00DA00DA00D
01-31 08:28:15.581 3201 4096 D NxpTml : PN54X - Posting read message.....
01-31 08:28:15.584 3201 4099 D NxpHal : read successful status = 0x0
01-31 08:28:15.584 3201 4099 D NxpHal : phNxpNciHal_print_res_status: response status =STATUS_INVALID_PARAM
01-31 08:28:15.584 3201 3201 D NxpHal : Response timer stopped
01-31 08:28:15.584 3201 3201 D NxpHal : Checking response
01-31 08:28:15.584 3201 3201 D NxpTml : phTmlNfc_i2c_reset(), VEN level 1
01-31 08:28:15.589 3201 4096 D NxpTml : PN54X - Read requested.....
01-31 08:28:15.589 3201 4096 D NxpTml : PN54X - Invoking I2C Read.....
01-31 08:28:15.835 3201 3201 D NxpTml : phTmlNfc_i2c_reset(), VEN level 0
01-31 08:28:15.854 3201 4096 E NxpTml : _i2c_read() [hdr] errno : 6
01-31 08:28:15.854 3201 4096 E NxpTml : PN54X - Error in I2C Read.....
01-31 08:28:15.854 3201 4096 D NxpTml : PN54X - Read requested.....
01-31 08:28:15.854 3201 4096 D NxpTml : PN54X - Invoking I2C Read.....
01-31 08:28:15.861 3201 4096 E NxpTml : _i2c_read() [hdr] errno : 6
01-31 08:28:15.861 3201 4096 E NxpTml : PN54X - Error in I2C Read.....
01-31 08:28:15.861 3201 4096 D NxpTml : PN54X - Read requested.....
01-31 08:28:15.861 3201 4096 D NxpTml : PN54X - Invoking I2C Read.....
01-31 08:28:15.868 3201 4096 E NxpTml : _i2c_read() [hdr] errno : 6
01-31 08:28:15.868 3201 4096 E NxpTml : PN54X - Error in I2C Read.....
01-31 08:28:15.868 3201 4096 D NxpTml : PN54X - Read requested.....
01-31 08:28:15.868 3201 4096 D NxpTml : PN54X - Invoking I2C Read.....
01-31 08:28:15.875 3201 4096 E NxpTml : _i2c_read() [hdr] errno : 6
01-31 08:28:15.875 3201 4096 E NxpTml : PN54X - Error in I2C Read.....
01-31 08:28:15.875 3201 4096 D NxpTml : PN54X - Read requested.....
01-31 08:28:15.875 3201 4096 D NxpTml : PN54X - Invoking I2C Read.....
01-31 08:28:15.883 3201 4096 E NxpTml : _i2c_read() [hdr] errno : 6
01-31 08:28:15.883 3201 4096 E NxpTml : PN54X - Error in I2C Read.....
01-31 08:28:15.883 3201 4096 D NxpTml : PN54X - Read requested.....
01-31 08:28:15.883 3201 4096 D NxpTml : PN54X - Invoking I2C Read.....
01-31 08:28:15.890 3201 4096 E NxpTml : _i2c_read() [hdr] errno : 6
01-31 08:28:15.890 3201 4096 E NxpTml : PN54X - Error in I2C Read.....
01-31 08:28:15.890 3201 4096 D NxpTml : PN54X - Read requested.....
01-31 08:28:15.890 3201 4096 D NxpTml : PN54X - Invoking I2C Read.....
01-31 08:28:15.899 3201 4096 E NxpTml : _i2c_read() [hdr] errno : 6
01-31 08:28:15.899 3201 4096 E NxpTml : PN54X - Error in I2C Read.....
01-31 08:28:15.899 3201 4096 D NxpTml : PN54X - Read requested.....
01-31 08:28:15.899 3201 4096 D NxpTml : PN54X - Invoking I2C Read.....
01-31 08:28:15.911 4188 4188 I Zygote : seccomp disabled by setenforce 0
01-31 08:28:15.912 3201 4096 E NxpTml : _i2c_read() [hdr] errno : 6
01-31 08:28:15.912 3201 4096 E NxpTml : PN54X - Error in I2C Read.....
01-31 08:28:15.912 3201 4096 D NxpTml : PN54X - Read requested.....
01-31 08:28:15.912 3201 4096 D NxpTml : PN54X - Invoking I2C Read.....
01-31 08:28:15.920 3201 4096 E NxpTml : _i2c_read() [hdr] errno : 6
01-31 08:28:15.920 3201 4096 E NxpTml : PN54X - Error in I2C Read.....
01-31 08:28:15.920 3201 4096 D NxpTml : PN54X - Read requested.....
01-31 08:28:15.920 3201 4096 D NxpTml : PN54X - Invoking I2C Read.....
01-31 08:28:15.927 3201 4096 E NxpTml : _i2c_read() [hdr] errno : 6
01-31 08:28:15.927 3201 4096 E NxpTml : PN54X - Error in I2C Read.....
01-31 08:28:15.927 3201 4096 D NxpTml : PN54X - Read requested.....
01-31 08:28:15.927 3201 4096 D NxpTml : PN54X - Invoking I2C Read.....
01-31 08:28:15.934 3201 4096 E NxpTml : _i2c_read() [hdr] errno : 6
01-31 08:28:15.935 3201 4096 E NxpTml : PN54X - Error in I2C Read.....
01-31 08:28:15.935 3201 4096 D NxpTml : PN54X - Read requested.....
01-31 08:28:15.935 3201 4096 D NxpTml : PN54X - Invoking I2C Read.....
01-31 08:28:15.942 3201 4096 E NxpTml : _i2c_read() [hdr] errno : 6
01-31 08:28:15.942 3201 4096 E NxpTml : PN54X - Error in I2C Read.....
01-31 08:28:15.942 3201 4096 D NxpTml : PN54X - Read requested.....
01-31 08:28:15.942 3201 4096 D NxpTml : PN54X - Invoking I2C Read.....
01-31 08:28:15.949 3201 4096 E NxpTml : _i2c_read() [hdr] errno : 6
01-31 08:28:15.949 3201 4096 E NxpTml : PN54X - Error in I2C Read.....
01-31 08:28:15.949 3201 4096 D NxpTml : PN54X - Read requested.....
01-31 08:28:15.949 3201 4096 D NxpTml : PN54X - Invoking I2C Read.....
01-31 08:28:15.956 3201 4096 E NxpTml : _i2c_read() [hdr] errno : 6
01-31 08:28:15.956 3201 4096 E NxpTml : PN54X - Error in I2C Read.....
01-31 08:28:15.956 3201 4096 D NxpTml : PN54X - Read requested.....
01-31 08:28:15.956 3201 4096 D NxpTml : PN54X - Invoking I2C Read.....
01-31 08:28:15.963 3201 4096 E NxpTml : _i2c_read() [hdr] errno : 6
01-31 08:28:15.963 3201 4096 E NxpTml : PN54X - Error in I2C Read.....
01-31 08:28:15.964 3201 4096 D NxpTml : PN54X - Read requested.....
01-31 08:28:15.964 3201 4096 D NxpTml : PN54X - Invoking I2C Read.....
01-31 08:28:15.971 3201 4096 E NxpTml : _i2c_read() [hdr] errno : 6
01-31 08:28:15.971 3201 4096 E NxpTml : PN54X - Error in I2C Read.....
01-31 08:28:15.971 3201 4096 D NxpTml : PN54X - Read requested.....
01-31 08:28:15.971 3201 4096 D NxpTml : PN54X - Invoking I2C Read.....
01-31 08:28:15.978 3201 4096 E NxpTml : _i2c_read() [hdr] errno : 6
01-31 08:28:15.978 3201 4096 E NxpTml : PN54X - Error in I2C Read.....
01-31 08:28:15.978 3201 4096 D NxpTml : PN54X - Read requested.....
01-31 08:28:15.978 3201 4096 D NxpTml : PN54X - Invoking I2C Read.....
01-31 08:28:15.985 3201 4096 E NxpTml : _i2c_read() [hdr] errno : 6
01-31 08:28:15.985 3201 4096 E NxpTml : PN54X - Error in I2C Read.....
01-31 08:28:15.985 3201 4096 D NxpTml : PN54X - Read requested.....
01-31 08:28:15.985 3201 4096 D NxpTml : PN54X - Invoking I2C Read.....
01-31 08:28:15.992 3201 4096 E NxpTml : _i2c_read() [hdr] errno : 6
01-31 08:28:15.992 3201 4096 E NxpTml : PN54X - Error in I2C Read.....
01-31 08:28:15.992 3201 4096 D NxpTml : PN54X - Read requested.....
01-31 08:28:15.992 3201 4096 D NxpTml : PN54X - Invoking I2C Read.....
01-31 08:28:15.999 3201 4096 E NxpTml : _i2c_read() [hdr] errno : 6
01-31 08:28:15.999 3201 4096 E NxpTml : PN54X - Error in I2C Read.....
01-31 08:28:15.999 3201 4096 D NxpTml : PN54X - Read requested.....
01-31 08:28:15.999 3201 4096 D NxpTml : PN54X - Invoking I2C Read.....
01-31 08:28:16.005 3201 4096 E NxpTml : _i2c_read() [hdr] errno : 6
01-31 08:28:16.006 3201 4096 E NxpTml : PN54X - Error in I2C Read.....
01-31 08:28:16.006 3201 4096 D NxpTml : PN54X - Read requested.....
01-31 08:28:16.006 3201 4096 D NxpTml : PN54X - Invoking I2C Read.....
01-31 08:28:16.013 3201 4096 E NxpTml : _i2c_read() [hdr] errno : 6
01-31 08:28:16.013 3201 4096 E NxpTml : PN54X - Error in I2C Read.....
01-31 08:28:16.013 3201 4096 D NxpTml : PN54X - Read requested.....
01-31 08:28:16.013 3201 4096 D NxpTml : PN54X - Invoking I2C Read.....
01-31 08:28:16.020 3201 4096 E NxpTml : _i2c_read() [hdr] errno : 6
01-31 08:28:16.020 3201 4096 E NxpTml : PN54X - Error in I2C Read.....
01-31 08:28:16.021 3201 4096 D NxpTml : PN54X - Read requested.....
01-31 08:28:16.021 3201 4096 D NxpTml : PN54X - Invoking I2C Read.....
01-31 08:28:16.027 3201 4096 E NxpTml : _i2c_read() [hdr] errno : 6
01-31 08:28:16.027 3201 4096 E NxpTml : PN54X - Error in I2C Read.....
01-31 08:28:16.027 3201 4096 D NxpTml : PN54X - Read requested.....
01-31 08:28:16.027 3201 4096 D NxpTml : PN54X - Invoking I2C Read.....
01-31 08:28:16.034 3201 4096 E NxpTml : _i2c_read() [hdr] errno : 6
01-31 08:28:16.034 3201 4096 E NxpTml : PN54X - Error in I2C Read.....
01-31 08:28:16.034 3201 4096 D NxpTml : PN54X - Read requested.....
01-31 08:28:16.035 3201 4096 D NxpTml : PN54X - Invoking I2C Read.....
01-31 08:28:16.042 3201 4096 E NxpTml : _i2c_read() [hdr] errno : 6
01-31 08:28:16.042 3201 4096 E NxpTml : PN54X - Error in I2C Read.....
01-31 08:28:16.042 3201 4096 D NxpTml : PN54X - Read requested.....
01-31 08:28:16.042 3201 4096 D NxpTml : PN54X - Invoking I2C Read.....
01-31 08:28:16.053 3201 4096 E NxpTml : _i2c_read() [hdr] errno : 6
01-31 08:28:16.053 3201 4096 E NxpTml : PN54X - Error in I2C Read.....
01-31 08:28:16.053 3201 4096 D NxpTml : PN54X - Read requested.....
01-31 08:28:16.053 3201 4096 D NxpTml : PN54X - Invoking I2C Read.....
01-31 08:28:16.055 3201 3201 D NxpTml : phTmlNfc_i2c_reset(), VEN level 1
01-31 08:28:16.069 3201 4096 E NxpTml : _i2c_read() [hdr] errno : 6
01-31 08:28:16.069 3201 4096 E NxpTml : PN54X - Error in I2C Read.....
01-31 08:28:16.069 3201 4096 D NxpTml : PN54X - Read requested.....
01-31 08:28:16.069 3201 4096 D NxpTml : PN54X - Invoking I2C Read.....
01-31 08:28:16.167 3201 3201 W NxpFwDnld: firmware type not found. Taking default value: 1
01-31 08:28:16.168 3201 3201 D NxpFwDnld: phDnldNfc_LoadRecoveryFW
01-31 08:28:16.168 3201 3201 E NxpFwDnld: NULL handler : unable to load the library file, specify correct path
01-31 08:28:16.168 3201 3201 E NxpFwDnld: FW Image Length - ImageInfoLen 0
01-31 08:28:16.168 3201 3201 E NxpFwDnld: FW Image Info Pointer - pImageInfo 0x0
01-31 08:28:16.168 3201 3201 E NxpFwDnld: Image extraction Failed - invalid imginfo or imginfolen!!
01-31 08:28:16.168 3201 3201 E NxpFwDnld: Error loading libpn54x_fw !!
01-31 08:28:16.168 3201 3201 D NxpHal : phNxpNciHal_ioctl : enter - arg = 25
01-31 08:28:16.168 3201 3201 D NxpTml : phPalEse_spi_ioctl(), ioctl 8008 , level 3
01-31 08:28:16.178 3201 3201 D NxpHal : phNxpNciHal_ioctl : exit - ret = -1
01-31 08:28:16.178 3201 3201 D NxpHal : eSE Power GPIO value = -1
01-31 08:28:16.178 3201 3201 E NxpHal : FW download denied while SPI in use, Continue NFC init
01-31 08:28:16.178 3201 3201 E NxpHal : TML Read status error status = 16f
01-31 08:28:16.180 3201 4097 D NxpTml : PN54X - Write request NOT enabled

Re: NFC not enabling in Android Pie

jeremygeslin — Mon, 03 Feb 2020 08:40:24 GMT

Hi,

Your logs shows you are facing internal EEPROM memory issue (see similar discussion here). Unfortunately there is no way to recover from this, the PN7150 IC has to be replaced.

Regards,

Jeremy.

Re: NFC not enabling in Android Pie

saschaseefelder — Tue, 04 Feb 2020 12:33:36 GMT

we have tried our implementation with a second (unused) NFC controller, with the same result. We also attached both NFC controllers to a Raspberry Pi Model B and followed the instructions on https://community.nxp.com/docs/DOC-341231, with no success either, the nfcDemoApp outputs "NfcService Init Failed"

Re: NFC not enabling in Android Pie

jeremygeslin — Wed, 05 Feb 2020 13:57:40 GMT

Indeed, whatever the host SW used (Android, Linux, or even OS independent implementation like NXP-NCI examples) if PN7150 memory is corrupted you'll always face this issue. Seems both NFC controller boards you have are corrupted, usually this corruption occurs when the RF setting parameter (only done the first time the NFC initialization is done by the Linux or Android stack) is interrupted by a power off.