4G模块ECM拨号失败求助
3288 安卓7.1 调试 4G 模块 ECM拨号失败 总是卡在信号查询求大神帮助 radio log见下：
01-01 08:00:03.528 257 257 D RILD : **RIL Daemon Started**
01-01 08:00:03.528 257 257 D RILD : **RILd param count=1**
01-01 08:00:06.686 261 261 D TelephonyManager: No /proc/cmdline exception=java.io.FileNotFoundException: /proc/cmdline (Permission denied)
01-01 08:00:06.686 261 261 D TelephonyManager: /proc/cmdline=
01-01 08:00:08.529 257 257 D use-Rlog/RLOG-RILD: found the usb: vid=1a86
01-01 08:00:08.529 257 257 D use-Rlog/RLOG-RILD: pid=e2e4
01-01 08:00:08.530 257 257 D use-Rlog/RLOG-RILD: found the usb: vid=1782
01-01 08:00:08.530 257 257 D use-Rlog/RLOG-RILD: pid=4d11
01-01 08:00:08.531 257 257 D use-Rlog/RLOG-RILD: =========the module is ML302==========
01-01 08:00:08.534 257 257 D use-Rlog/RLOG-RILD: found the usb: vid=1a40
01-01 08:00:08.534 257 257 D use-Rlog/RLOG-RILD: pid=0801
01-01 08:00:08.537 257 257 D use-Rlog/RLOG-RILD: found the usb: vid=1d6b
01-01 08:00:08.537 257 257 D use-Rlog/RLOG-RILD: pid=0002
01-01 08:00:08.537 257 257 D use-Rlog/RLOG-RILD: found the usb: vid=1a40
01-01 08:00:08.537 257 257 D use-Rlog/RLOG-RILD: pid=0801
01-01 08:00:08.539 257 257 D use-Rlog/RLOG-RILD: found the usb: vid=1d6b
01-01 08:00:08.539 257 257 D use-Rlog/RLOG-RILD: pid=0002
01-01 08:00:08.542 257 257 D use-Rlog/RLOG-RILD: found the usb: vid=1d6b
01-01 08:00:08.542 257 257 D use-Rlog/RLOG-RILD: pid=0001
01-01 08:00:08.545 257 257 D use-Rlog/RLOG-RILD: found the usb: vid=1d6b
01-01 08:00:08.545 257 257 D use-Rlog/RLOG-RILD: pid=0002
01-01 08:00:08.548 257 257 D use-Rlog/RLOG-RILD: found the usb: vid=1a86
01-01 08:00:08.548 257 257 D use-Rlog/RLOG-RILD: pid=e2e4
01-01 08:00:08.555 257 257 D use-Rlog/RLOG-RILD: found the usb: vid=1782
01-01 08:00:08.555 257 257 D use-Rlog/RLOG-RILD: pid=4d11
01-01 08:00:08.555 257 257 D use-Rlog/RLOG-RILD: =========the module is ML302==========
01-01 08:00:08.581 257 257 D use-Rlog/RLOG-RILD:
01-01 08:00:08.581 257 257 D use-Rlog/RLOG-RILD: rilLibPath = libreference-ril-ML302.so
01-01 08:00:08.581 257 257 D use-Rlog/RLOG-RILD:
01-01 08:00:08.583 257 257 D RILD : RIL_Init argc = 5 clientId = 0
01-01 08:00:08.583 257 257 I RIL : Opening tty device /dev/ttyUSB0
01-01 08:00:08.583 257 257 I RIL : Client id received 0
01-01 08:00:08.583 257 257 D RILD : RIL_Init rilInit completed
01-01 08:00:08.583 257 586 I RIL : mainLoop() starting!
01-01 08:00:08.583 257 257 I RILC : SIM_COUNT: 1
01-01 08:00:08.583 257 257 E RILC : RIL_register: RIL version 12
01-01 08:00:08.583 257 257 I RILC : s_registerCalled flag set, 1
01-01 08:00:08.583 257 257 I RILC : Start to listen RIL_SOCKET_1
01-01 08:00:08.583 257 257 D RILD : RIL_Init RIL_register completed
01-01 08:00:08.583 257 257 D RILD : RIL_register_socket completed
01-01 08:00:08.583 257 257 D RILD : RIL_Init starting sleep loop
01-01 08:00:08.584 257 587 I AT : Entering readerLoop!
01-01 08:00:08.775 511 511 D TelephonyManager: /proc/cmdline=earlycon=uart8250,mmio32,0xff690000 vmalloc=496M console=ttyFIQ0 androidboot.baseband=N/A androidboot.selinux=permissive androidboot.hardware=rk30board androidboot.console=ttyFIQ0 init=/initmtdparts=rk29xxnand:0x00002000@0x00002000(uboot),0x00002000@0x00004000(trust),0x00002000@0x00006000(misc),0x00008000@0x00008000(resource),0x0000C000@0x00010000(kernel),0x00010000@0x0001C000(boot),0x00010000@0x0002C000(recovery),0x00038000@0x0003C000(backup),0x00040000@0x00074000(cache),0x00400000@0x000B4000(system),0x00008000@0x004B4000(metadata),0x00032000@0x004BC000(vendor0),0x00032000@0x004EE000(vendor1),-@0x00520000(userdata) storagemedia=emmc androidboot.oem_unlocked=0 uboot_logo=0x02000000@0x7dc00000 loader.timestamp=2020-09-08_14:19:22 SecureBootCheckOk=0 androidboot.mode=emmc
01-01 08:00:10.307 645 645 D TDC : updateOrInsert: inserting: Modem { uuid=modem, state=0, rilModel=0, rat={}, maxActiveVoiceCall=1, maxActiveDataCall=1, maxStandby=1 }
01-01 08:00:10.307 645 645 D TDC : updateOrInsert: inserting: Sim { uuid=sim, modemUuid=modem, state=0 }
01-01 08:00:10.315 645 645 D CdmaSSM : subscriptionSource from settings: 0
01-01 08:00:10.315 645 645 I PhoneFactory: Cdma Subscription set to 0
01-01 08:00:10.326 511 534 D TelephonyRegistry: listen oscl: hasNotifySubscriptionInfoChangedOccurred==false no callback
01-01 08:00:10.332 645 645 I PhoneFactory: Network Mode set to 9
01-01 08:00:10.338 645 645 D RILJ : RIL(context, preferredNetworkType=9 cdmaSubscription=0)
01-01 08:00:10.343 645 645 D RILJ : Starting RILReceiver0
01-01 08:00:10.350 257 585 I RILC : libril: new connection to RIL_SOCKET_1
01-01 08:00:10.350 257 585 I RILC : RIL Daemon version: Cmiot ML302 Android Ril 1.0 for Android7.1
01-01 08:00:10.351 645 784 I RILJ : (0) Connected to 'rild' socket
01-01 08:00:10.351 645 784 D RILJ : < UNSOL_RIL_CONNECTED {12}
01-01 08:00:10.352 645 645 D RILJ : > GET_HARDWARE_CONFIG
01-01 08:00:10.352 645 784 D RILJ : > RADIO_POWER off
01-01 08:00:10.353 645 645 I PhoneFactory: Creating SubscriptionController
01-01 08:00:10.353 257 585 D RIL : onRequest: GET_HARDWARE_CONFIG
01-01 08:00:10.353 257 585 D RIL : now sState is 1
01-01 08:00:10.354 645 784 D RILJ : > RIL_REQUEST_CDMA_SET_SUBSCRIPTION_SOURCE : 0
01-01 08:00:10.355 257 585 D RIL : onRequest: RADIO_POWER
01-01 08:00:10.355 257 585 D RIL : now sState is 1
01-01 08:00:10.356 645 784 D RILJ : setCellInfoListRate: 2147483647
01-01 08:00:10.356 257 585 D RIL : onRequest: CDMA_SET_SUBSCRIPTION_SOURCE
01-01 08:00:10.356 645 784 D RILJ : > RIL_REQUEST_SET_CELL_INFO_LIST_RATE
01-01 08:00:10.356 257 585 D RIL : now sState is 1
01-01 08:00:10.357 257 585 D RIL : onRequest: SET_UNSOL_CELL_INFO_LIST_RATE
01-01 08:00:10.357 257 585 D RIL : now sState is 1
01-01 08:00:10.357 645 784 D RILJ : < UNSOL_RESPONSE_RADIO_STATE_CHANGED RADIO_UNAVAILABLE
01-01 08:00:10.359 645 784 D RilRequest: < GET_HARDWARE_CONFIG error: com.android.internal.telephony.CommandException: RADIO_NOT_AVAILABLE ret=
01-01 08:00:10.359 645 784 D RilRequest: < RADIO_POWER error: com.android.internal.telephony.CommandException: RADIO_NOT_AVAILABLE ret=
01-01 08:00:10.359 645 784 D RilRequest: < RIL_REQUEST_CDMA_SET_SUBSCRIPTION_SOURCE error: com.android.internal.telephony.CommandException: RADIO_NOT_AVAILABLE ret=
01-01 08:00:10.359 645 784 D RilRequest: < RIL_REQUEST_SET_CELL_INFO_LIST_RATE error: com.android.internal.telephony.CommandException: RADIO_NOT_AVAILABLE ret=
01-01 08:00:10.371 645 645 D SubscriptionController: init by Context
01-01 08:00:10.390 645 645 D UiccController: Creating UiccController
01-01 08:00:10.392 645 645 D TelephonyTester: register for intent action=com.android.internal.telephony.action_detached
01-01 08:00:10.392 645 645 D TelephonyTester: register for intent action=com.android.internal.telephony.action_attached
01-01 08:00:10.412 645 645 D Phone : mDoesRilSendMultipleCallRing=true
01-01 08:00:10.412 645 645 D Phone : mCallRingDelay=3000
01-01 08:00:10.423 645 645 D RILJ : > RIL_REQUEST_START_LCE
01-01 08:00:10.425 257 585 D RIL : onRequest: <unknown request>
01-01 08:00:10.425 257 585 D RIL : now sState is 1
01-01 08:00:10.425 645 784 D RilRequest: < RIL_REQUEST_START_LCE error: com.android.internal.telephony.CommandException: RADIO_NOT_AVAILABLE ret=
01-01 08:00:10.440 645 645 E RIL_ImsSms: getFormat should never be called from here!
01-01 08:00:10.440 645 645 D SMSDispatcher: SMSDispatcher: ctor mSmsCapable=true format=unknown mSmsSendDisabled=false
01-01 08:00:10.440 645 645 D RIL_ImsSms: ImsSMSDispatcher created
01-01 08:00:10.442 645 645 D SMSDispatcher: SMSDispatcher: ctor mSmsCapable=true format=3gpp2 mSmsSendDisabled=false
01-01 08:00:10.442 645 645 D CdmaSMSDispatcher: CdmaSMSDispatcher created
01-01 08:00:10.468 645 645 D GsmInboundSmsHandler: created InboundSmsHandler
01-01 08:00:10.479 645 645 D CdmaInboundSmsHandler: created InboundSmsHandler
01-01 08:00:10.484 645 645 D SMSDispatcher: SMSDispatcher: ctor mSmsCapable=true format=3gpp mSmsSendDisabled=false
01-01 08:00:10.484 645 645 D GsmSMSDispatcher: GsmSMSDispatcher created
01-01 08:00:10.493 645 808 D GsmInboundSmsHandler: StartupState.processMessage:6
01-01 08:00:10.493 645 808 D GsmInboundSmsHandler: entering Idle state
01-01 08:00:10.493 645 813 D CdmaInboundSmsHandler: StartupState.processMessage:6
01-01 08:00:10.494 645 813 D CdmaInboundSmsHandler: entering Idle state
01-01 08:00:10.497 645 645 D IccCardProxy: ctor: ci=com.android.internal.telephony.RIL@3f40381 phoneId=0
01-01 08:00:10.497 645 645 D CdmaSSM : subscriptionSource from settings: 0
01-01 08:00:10.497 645 645 D CdmaSSM : cdmaSSM constructor: 0
01-01 08:00:10.497 645 645 D IccCardProxy: update icc_operator_numeric=
01-01 08:00:10.497 645 645 D TelephonyManager: setTelephonyProperty: success phoneId=0 property=gsm.sim.operator.numeric value:propVal=
01-01 08:00:10.498 645 645 D TelephonyManager: setTelephonyProperty: success phoneId=0 property=gsm.sim.operator.iso-country value:propVal=
01-01 08:00:10.498 645 645 D TelephonyManager: setTelephonyProperty: success phoneId=0 property=gsm.sim.operator.alpha value:propVal=
01-01 08:00:10.499 645 645 E IccCardProxy: setExternalState: set mPhoneId=0 mExternalState=NOT_READY
01-01 08:00:10.499 645 645 D TelephonyManager: setTelephonyProperty: success phoneId=0 property=gsm.sim.state value: NOT_READY propVal=NOT_READY
01-01 08:00:10.499 645 645 D IccCardProxy: broadcastIccStateChangedIntent intent ACTION_SIM_STATE_CHANGED value=NOT_READY reason=null for mPhoneId=0
01-01 08:00:10.502 645 645 D RILJ : setPhoneType=1 old value=0
01-01 08:00:10.502 645 645 D TelephonyManager: setTelephonyProperty: success phoneId=0 property=gsm.current.phone-type value: 1 propVal=1
01-01 08:00:10.503 645 645 D IccCardProxy: Setting radio tech UMTS
01-01 08:00:10.503 645 645 D IccCardProxy: updateQuietMode: 3GPP subscription -> newQuietMode=false
01-01 08:00:10.503 645 645 D IccCardProxy: updateQuietMode: no changes don't setExternalState
01-01 08:00:10.503 645 645 D IccCardProxy: updateQuietMode: QuietMode is false (app_type=1 cdmaSource=-1)
01-01 08:00:10.508 645 645 W CarrierConfigManager: Error getting config for subId -1 ICarrierConfigLoader is null
01-01 08:00:10.510 511 534 D TelephonyRegistry: listen oscl: hasNotifySubscriptionInfoChangedOccurred==false no callback
01-01 08:00:10.516 645 645 D TelephonyManager: setTelephonyProperty: success phoneId=0 property=gsm.network.type value: Unknown propVal=Unknown
01-01 08:00:10.516 645 645 D RILJ : > SIGNAL_STRENGTH
01-01 08:00:10.517 257 585 D RIL : onRequest: SIGNAL_STRENGTH
01-01 08:00:10.517 257 585 D RIL : now sState is 1
01-01 08:00:10.517 645 645 D SST : notifyDataRegStateRilRadioTechnologyChanged: drs=1 rat=0
01-01 08:00:10.517 645 645 D TelephonyManager: setTelephonyProperty: success phoneId=0 property=gsm.network.type value: Unknown propVal=Unknown
01-01 08:00:10.517 645 784 D RilRequest: < SIGNAL_STRENGTH error: com.android.internal.telephony.CommandException: RADIO_NOT_AVAILABLE ret=
01-01 08:00:10.524 645 645 D DCT : DCT.constructor
01-01 08:00:10.527 511 534 D TelephonyRegistry: listen oscl: hasNotifySubscriptionInfoChangedOccurred==false no callback
01-01 08:00:10.528 645 645 D Dcc : E ctor
01-01 08:00:10.528 645 645 D Dcc : X ctor
01-01 08:00:10.529 645 645 D SubscriptionController: asked for default subId=-1
01-01 08:00:10.531 645 645 D DcTesterFailBrinupAll: register for intent action=com.android.internal.telephony.dataconnection.action_fail_bringup
01-01 08:00:10.531 645 645 D DcTesterFailBrinupAll: register for intent action=com.android.internal.telephony.action_detached
01-01 08:00:10.532 645 645 D DcTesterFailBrinupAll: register for intent action=com.android.internal.telephony.action_attached
01-01 08:00:10.533 645 645 D SST : notifyDataRegStateRilRadioTechnologyChanged: drs=1 rat=0
01-01 08:00:10.533 645 645 D TelephonyManager: setTelephonyProperty: success phoneId=0 property=gsm.network.type value: Unknown propVal=Unknown
01-01 08:00:10.533 645 821 D DcTesterDeacativateAll: register for intent action=com.android.internal.telephony.dataconnection.action_deactivate_all
01-01 08:00:10.533 645 821 D DcTesterDeacativateAll: register for intent action=com.android.internal.telephony.action_detached
01-01 08:00:10.534 645 645 D DCT : update sub = -2
01-01 08:00:10.534 645 645 D DCT : update(): Active DDS, register for all events now!
01-01 08:00:10.536 645 645 D GsmCdmaPhone: updateCurrentCarrierInProvider: mSubId = -2 currentDds = 1 operatorNumeric = null
01-01 08:00:10.537 645 821 D Dcc : DccDefaultState: msg.what=EVENT_RIL_CONNECTED mRilVersion=12
01-01 08:00:10.542 645 645 D DCT : initApnContexts: E
01-01 08:00:10.542 645 645 D DCT : initApnContexts: skipping unknown type=1
01-01 08:00:10.542 645 645 D DCT : initApnContexts: apnContext={mApnType=default mState=IDLE mWaitingApns={null} mApnSetting={null} mReason=dataEnabled mDataEnabled=false mDependencyMet=true}
01-01 08:00:10.542 645 645 D DCT : initApnContexts: apnContext={mApnType=mms mState=IDLE mWaitingApns={null} mApnSetting={null} mReason=dataEnabled mDataEnabled=false mDependencyMet=false}
01-01 08:00:10.542 645 645 D DCT : initApnContexts: apnContext={mApnType=supl mState=IDLE mWaitingApns={null} mApnSetting={null} mReason=dataEnabled mDataEnabled=false mDependencyMet=true}
01-01 08:00:10.542 645 645 D DCT : initApnContexts: apnContext={mApnType=dun mState=IDLE mWaitingApns={null} mApnSetting={null} mReason=dataEnabled mDataEnabled=false mDependencyMet=true}
01-01 08:00:10.543 645 645 D DCT : initApnContexts: apnContext={mApnType=hipri mState=IDLE mWaitingApns={null} mApnSetting={null} mReason=dataEnabled mDataEnabled=false mDependencyMet=true}
01-01 08:00:10.543 645 645 D DCT : initApnContexts: apnContext={mApnType=fota mState=IDLE mWaitingApns={null} mApnSetting={null} mReason=dataEnabled mDataEnabled=false mDependencyMet=true}
01-01 08:00:10.543 645 645 D DCT : initApnContexts: apnContext={mApnType=ims mState=IDLE mWaitingApns={null} mApnSetting={null} mReason=dataEnabled mDataEnabled=false mDependencyMet=true}
01-01 08:00:10.543 645 645 D DCT : initApnContexts: apnContext={mApnType=cbs mState=IDLE mWaitingApns={null} mApnSetting={null} mReason=dataEnabled mDataEnabled=false mDependencyMet=true}
01-01 08:00:10.543 645 645 D DCT : initApnContexts: skipping unknown type=7
01-01 08:00:10.543 645 645 D DCT : initApnContexts: skipping unknown type=9
01-01 08:00:10.543 645 645 D DCT : initApnContexts: apnContext={mApnType=emergency mState=IDLE mWaitingApns={null} mApnSetting={null} mReason=dataEnabled mDataEnabled=false mDependencyMet=true}
01-01 08:00:10.548 645 662 D SubscriptionController: Sub Controller not ready
01-01 08:00:10.548 645 665 D SubscriptionController: asked for default subId=-1
01-01 08:00:10.550 511 570 D TelephonyRegistry: listen oscl: hasNotifySubscriptionInfoChangedOccurred==false no callback
01-01 08:00:10.568 645 827 D SubscriptionController: asked for default subId=-1
01-01 08:00:10.587 511 569 D TelephonyRegistry: listen oscl: hasNotifySubscriptionInfoChangedOccurred==false no callback
01-01 08:00:10.598 511 570 D TelephonyRegistry: systemRunning register for intents
01-01 08:00:10.613 645 645 D GsmCdmaPhone: GsmCdmaPhone: constructor: sub = 0
01-01 08:00:10.613 645 645 I PhoneFactory: Creating Phone with type = 1 sub = 0
01-01 08:00:10.617 645 645 I PhoneFactory: defaultSmsApplication: NONE
01-01 08:00:10.620 645 645 I PhoneFactory: Creating SubInfoRecordUpdater
01-01 08:00:10.620 645 645 D SubscriptionInfoUpdater: Constructor invoked
01-01 08:00:10.638 645 827 D SubscriptionController: - no sims, returning default phoneId=2147483647
01-01 08:00:10.706 511 688 D TelephonyRegistry: listen oscl: hasNotifySubscriptionInfoChangedOccurred==false no callback
01-01 08:00:10.706 645 645 D SubscriptionController: - no sims, returning default phoneId=2147483647
01-01 08:00:10.708 511 688 D TelephonyRegistry: listen oscl: hasNotifySubscriptionInfoChangedOccurred==false no callback
01-01 08:00:10.712 645 645 D PhoneSwitcher: PhoneSwitcher started
01-01 08:00:10.713 645 645 D ProxyController: Constructor - Enter
01-01 08:00:10.715 645 645 D ProxyController: clearTransaction
01-01 08:00:10.715 645 645 D ProxyController: clearTransaction: phoneId=0 status=IDLE
01-01 08:00:10.716 645 645 D ProxyController: Constructor - Exit
01-01 08:00:10.716 645 645 D TelephonyNetworkFactory: Registering NetworkFactory
01-01 08:00:10.720 645 645 D CallManager: registerPhone(GSM Handler (com.android.internal.telephony.GsmCdmaPhone) {b35692d})
01-01 08:00:10.723 511 534 D TelephonyRegistry: listen oscl: hasNotifySubscriptionInfoChangedOccurred==false no callback
01-01 08:00:10.740 511 785 D TelephonyRegistry: listen oscl: hasNotifySubscriptionInfoChangedOccurred==false no callback
01-01 08:00:10.765 645 645 D RILJ : > RIL_REQUEST_SET_TTY_MODE : 0
01-01 08:00:10.767 257 585 D RIL : onRequest: SET_TTY_MODE
01-01 08:00:10.767 257 585 D RIL : now sState is 1
01-01 08:00:10.767 645 784 D RilRequest: < RIL_REQUEST_SET_TTY_MODE error: com.android.internal.telephony.CommandException: RADIO_NOT_AVAILABLE ret=
01-01 08:00:10.771 511 534 D TelephonyRegistry: listen oscl: hasNotifySubscriptionInfoChangedOccurred==false no callback
01-01 08:00:10.773 645 645 D SubscriptionController: asked for default subId=-1
01-01 08:00:10.777 645 645 D UiccController: EVENT_RADIO_UNAVAILABLE, dispose card
01-01 08:00:10.777 645 645 D Phone : config LCE service failed: com.android.internal.telephony.CommandException: RADIO_NOT_AVAILABLE
01-01 08:00:10.777 645 645 D RILJ : > GET_CURRENT_CALLS
01-01 08:00:10.778 645 645 D GsmSMSDispatcher: GsmSMSDispatcher: subId = -2 slotId = 0
01-01 08:00:10.778 257 585 D RIL : onRequest: GET_CURRENT_CALLS
01-01 08:00:10.778 257 585 D RIL : now sState is 1
01-01 08:00:10.778 645 645 E IccCardProxy: setExternalState: !override and newstate unchanged from NOT_READY
01-01 08:00:10.778 645 645 E IccCardProxy: setExternalState: !override and newstate unchanged from NOT_READY
01-01 08:00:10.778 645 645 D GsmCdmaPhone: Event EVENT_RADIO_OFF_OR_NOT_AVAILABLE Received
01-01 08:00:10.778 645 645 E IccCardProxy: setExternalState: !override and newstate unchanged from NOT_READY
01-01 08:00:10.778 645 645 D SST : mDeviceShuttingDown=false, mDesiredPowerState=true, getRadioState=RADIO_UNAVAILABLE, mPowerOffDelayNeed=true, mAlarmSwitch=false, mRadioDisabledByCarrier=false
01-01 08:00:10.778 645 784 D RilRequest: < GET_CURRENT_CALLS error: com.android.internal.telephony.CommandException: RADIO_NOT_AVAILABLE ret=
01-01 08:00:10.779 645 645 D SST : Poll ServiceState done:oldSS= newSS= oldMaxDataCalls=1 mNewMaxDataCalls=1 oldReasonDataDenied=-1 mNewReasonDataDenied=-1
01-01 08:00:10.779 645 645 D SST : updateSpnDisplay: radio is on but out of service, set plmn='没有服务'
01-01 08:00:10.779 645 645 D SST : updateSpnDisplay: changed sending intent rule=0 showPlmn='true' plmn='没有服务' showSpn='false' spn='' dataSpn='' subId='-2'
01-01 08:00:10.781 645 645 D SubscriptionController: No valid subscription to store info
01-01 08:00:10.781 645 645 D SubscriptionController: notifySubscriptionInfoChanged:
01-01 08:00:10.782 511 785 D TelephonyRegistry: notifySubscriptionInfoChanged: first invocation mRecords.size=16
01-01 08:00:10.784 645 665 D SubscriptionController: Sub Controller not ready
01-01 08:00:10.785 645 645 D TelephonyManager: setTelephonyProperty: success phoneId=0 property=gsm.operator.alpha value:propVal=
01-01 08:00:10.785 645 645 D TelephonyManager: setTelephonyProperty: success phoneId=0 property=gsm.operator.numeric value:propVal=
01-01 08:00:10.787 645 645 D SST : operatorNumeric is null
01-01 08:00:10.787 645 645 D TelephonyManager: setTelephonyProperty: success phoneId=0 property=gsm.operator.iso-country value:propVal=
01-01 08:00:10.787 645 645 D TelephonyManager: setTelephonyProperty: success phoneId=0 property=gsm.operator.isroaming value: false propVal=false
01-01 08:00:10.788 645 645 D SST : Broadcasting ServiceState : 1 1 voice home data home null null null null null nullUnknown Unknown CSS not supported -1 -1 RoamInd=-1 DefRoamInd=-1 EmergOnly=false IsDataRoamingFromRegistration=false IsUsingCarrierAggregation=false
01-01 08:00:10.788 645 645 D DefaultPhoneNotifier: nofityServiceState: mRegistry=com.android.internal.telephony.ITelephonyRegistry$Stub$Proxy@47790e5 ss=1 1 voice home data home null null null null null nullUnknown Unknown CSS not supported -1 -1 RoamInd=-1 DefRoamInd=-1 EmergOnly=false IsDataRoamingFromRegistration=false IsUsingCarrierAggregation=false sender=Handler (com.android.internal.telephony.GsmCdmaPhone) {b35692d} phondId=0 subId=-2
01-01 08:00:10.789 645 665 D SubscriptionController: - no sims, returning default phoneId=2147483647
01-01 08:00:10.791 645 645 D SubscriptionController: - subId invalid
01-01 08:00:10.791 511 536 D TelephonyManager: getSimState: default sim:-1, sim state for slotIdx=0 is 6, return state as unknown
01-01 08:00:10.797 645 645 D SST : mDeviceShuttingDown=false, mDesiredPowerState=true, getRadioState=RADIO_UNAVAILABLE, mPowerOffDelayNeed=true, mAlarmSwitch=false, mRadioDisabledByCarrier=false
01-01 08:00:10.797 645 645 D SST : Poll ServiceState done:oldSS= newSS= oldMaxDataCalls=1 mNewMaxDataCalls=1 oldReasonDataDenied=-1 mNewReasonDataDenied=-1
01-01 08:00:10.798 645 645 D DCT : onRadioOffOrNotAvailable: is off and clean up all connections
01-01 08:00:10.798 645 645 D DCT : cleanUpAllConnections: tearDown=false reason=radioTurnedOff
01-01 08:00:10.798 645 645 D GsmCdmaPhone: getDataConnectionState apnType=default ret=DISCONNECTED
01-01 08:00:10.801 645 645 D DCT : cleanUpConnection: X tearDown=false reason=radioTurnedOff apnContext={mApnType=default mState=IDLE mWaitingApns={null} mApnSetting={null} mReason=radioTurnedOff mDataEnabled=false mDependencyMet=true} dcac=null
01-01 08:00:10.801 645 645 D GsmCdmaPhone: getDataConnectionState apnType=mms ret=DISCONNECTED
01-01 08:00:10.803 645 645 D DCT : cleanUpConnection: X tearDown=false reason=radioTurnedOff apnContext={mApnType=mms mState=IDLE mWaitingApns={null} mApnSetting={null} mReason=radioTurnedOff mDataEnabled=false mDependencyMet=false} dcac=null
01-01 08:00:10.803 645 645 D GsmCdmaPhone: getDataConnectionState apnType=cbs ret=DISCONNECTED
01-01 08:00:10.805 645 645 D DCT : cleanUpConnection: X tearDown=false reason=radioTurnedOff apnContext={mApnType=cbs mState=IDLE mWaitingApns={null} mApnSetting={null} mReason=radioTurnedOff mDataEnabled=false mDependencyMet=true} dcac=null
01-01 08:00:10.805 645 645 D GsmCdmaPhone: getDataConnectionState apnType=fota ret=DISCONNECTED
01-01 08:00:10.807 645 645 D DCT : cleanUpConnection: X tearDown=false reason=radioTurnedOff apnContext={mApnType=fota mState=IDLE mWaitingApns={null} mApnSetting={null} mReason=radioTurnedOff mDataEnabled=false mDependencyMet=true} dcac=null
01-01 08:00:10.808 645 645 D GsmCdmaPhone: getDataConnectionState apnType=supl ret=DISCONNECTED
01-01 08:00:10.811 645 645 D DCT : cleanUpConnection: X tearDown=false reason=radioTurnedOff apnContext={mApnType=supl mState=IDLE mWaitingApns={null} mApnSetting={null} mReason=radioTurnedOff mDataEnabled=false mDependencyMet=true} dcac=null
01-01 08:00:10.811 645 645 D GsmCdmaPhone: getDataConnectionState apnType=emergency ret=DISCONNECTED
01-01 08:00:10.813 645 645 D DCT : cleanUpConnection: X tearDown=false reason=radioTurnedOff apnContext={mApnType=emergency mState=IDLE mWaitingApns={null} mApnSetting={null} mReason=radioTurnedOff mDataEnabled=false mDependencyMet=true} dcac=null
01-01 08:00:10.813 645 645 D GsmCdmaPhone: getDataConnectionState apnType=dun ret=DISCONNECTED
01-01 08:00:10.816 645 645 D DCT : cleanUpConnection: X tearDown=false reason=radioTurnedOff apnContext={mApnType=dun mState=IDLE mWaitingApns={null} mApnSetting={null} mReason=radioTurnedOff mDataEnabled=false mDependencyMet=true} dcac=null
01-01 08:00:10.816 645 645 D GsmCdmaPhone: getDataConnectionState apnType=hipri ret=DISCONNECTED
01-01 08:00:10.818 645 645 D DCT : cleanUpConnection: X tearDown=false reason=radioTurnedOff apnContext={mApnType=hipri mState=IDLE mWaitingApns={null} mApnSetting={null} mReason=radioTurnedOff mDataEnabled=false mDependencyMet=true} dcac=null
01-01 08:00:10.818 645 645 D GsmCdmaPhone: getDataConnectionState apnType=ims ret=DISCONNECTED
01-01 08:00:10.825 645 645 D DCT : cleanUpConnection: X tearDown=false reason=radioTurnedOff apnContext={mApnType=ims mState=IDLE mWaitingApns={null} mApnSetting={null} mReason=radioTurnedOff mDataEnabled=false mDependencyMet=true} dcac=null
01-01 08:00:10.825 645 645 D DCT : stopNetStatPoll
01-01 08:00:10.825 645 645 D DCT : cleanUpConnection: mDisconnectPendingCount = 0
01-01 08:00:10.827 645 645 D DCT : isDataAllowed: No - Not attached - SIM not loaded
01-01 08:00:10.850 645 645 D DCT : onDataConnectionDetached: stop polling and notify detached
01-01 08:00:10.850 645 645 D DCT : stopNetStatPoll
01-01 08:00:10.851 645 645 D DCT : notifyDataConnection: reason=dataDetached
01-01 08:00:10.852 645 645 D DCT : isDataAllowed: No - Not attached - SIM not loaded
01-01 08:00:10.870 645 645 D DCT : onRoamingOff
01-01 08:00:10.871 645 645 D DCT : setDataProfilesAsNeeded mSetDataProfileStatus: 0
01-01 08:00:10.871 645 645 D DCT : notifyDataConnection: reason=roamingOff
01-01 08:00:10.872 645 645 D DCT : isDataAllowed: No - Not attached - SIM not loaded
01-01 08:00:10.892 645 645 D DCT : setupDataOnConnectableApns: nwTypeChanged emergency: hipri: supl: mms: dun: fota: ims: default: cbs:
01-01 08:00:10.892 645 645 D SubscriptionInfoUpdater: +
01-01 08:00:10.892 645 645 D SubscriptionInfoUpdater: Action: android.intent.action.SIM_STATE_CHANGED
01-01 08:00:10.892 645 645 D SubscriptionInfoUpdater: slotId: 0
01-01 08:00:10.892 645 645 D SubscriptionInfoUpdater: simStatus: NOT_READY
01-01 08:00:10.892 645 645 D SubscriptionInfoUpdater: Ignoring simStatus: NOT_READY
01-01 08:00:10.892 645 645 D SubscriptionInfoUpdater: -
01-01 08:00:10.895 645 645 D TelephonyNetworkFactory: onSubIdChange -1->-2
01-01 08:00:10.895 645 645 D TelephonyNetworkFactory: got request NetworkRequest [ REQUEST id=1, [ Capabilities: INTERNET&NOT_RESTRICTED&TRUSTED&NOT_VPN] ] with score 0
01-01 08:00:10.895 645 645 D DebugService: DebugService DebugService:
01-01 08:00:10.898 645 645 D RILJ : > RIL_REQUEST_QUERY_TTY_MODE
01-01 08:00:10.901 257 585 D RIL : onRequest: QUERY_TTY_MODE
01-01 08:00:10.901 257 585 D RIL : now sState is 1
01-01 08:00:10.901 645 645 D GsmSMSDispatcher: GsmSMSDispatcher: subId = -2 slotId = 0
01-01 08:00:10.901 645 645 E IccCardProxy: setExternalState: !override and newstate unchanged from NOT_READY
01-01 08:00:10.901 645 645 D GsmCdmaCallTracker: Event EVENT_POLL_CALLS_RESULT Received
01-01 08:00:10.901 645 645 D GsmCdmaCallTracker: update phone state, old=IDLE new=IDLE
01-01 08:00:10.901 645 645 D SST : SubscriptionListener.onSubscriptionInfoChanged
01-01 08:00:10.901 645 645 E Phone : getStoredVoiceMessageCount: invalid subId -2
01-01 08:00:10.901 645 645 D GsmCdmaPhone: updateVoiceMail countVoiceMessages = 0 subId -2
01-01 08:00:10.901 645 645 E Phone : setVoiceMessageCount in sharedPreference: invalid subId -2
01-01 08:00:10.902 645 645 D DCT : SubscriptionListener.onSubscriptionInfoChanged
01-01 08:00:10.902 645 645 D PhoneSwitcher: evaluating due to subChanged default 0->1 phone 0->-2
01-01 08:00:10.902 645 784 D RilRequest: < RIL_REQUEST_QUERY_TTY_MODE error: com.android.internal.telephony.CommandException: RADIO_NOT_AVAILABLE ret=
01-01 08:00:10.903 645 645 D SubscriptionController: Sub Controller not ready
01-01 08:00:10.903 645 645 D SubscriptionController: Sub Controller not ready
01-01 08:00:10.928 645 645 D PhoneSwitcher: evaluating due to netRequest
01-01 08:00:10.930 645 645 D TelephonyNetworkFactory: not acting - isApp=false, isAct=false NetworkRequest [ REQUEST id=1, [ Capabilities: INTERNET&NOT_RESTRICTED&TRUSTED&NOT_VPN] ]
01-01 08:00:11.204 645 662 D SubscriptionController: Sub Controller not ready
01-01 08:00:11.210 645 662 D SubscriptionController: Sub Controller not ready
01-01 08:00:11.212 645 662 D SubscriptionController: Sub Controller not ready
01-01 08:00:11.259 645 662 D SubscriptionController: asked for default subId=-1
01-01 08:00:11.280 645 662 D DCT : hasMatchedTetherApnSetting: APN=null
01-01 08:00:11.282 645 662 D DCT : hasMatchedTetherApnSetting: APN=null
01-01 08:00:11.324 645 662 D SubscriptionController: - subId invalid
01-01 08:00:11.325 651 925 D TelephonyManager: getSimState: default sim:-1, sim state for slotIdx=0 is 6, return state as unknown
01-01 08:00:11.333 645 662 D SubscriptionController: - subId invalid
01-01 08:00:11.334 651 925 D TelephonyManager: getSimState: default sim:-1, sim state for slotIdx=0 is 6, return state as unknown
01-01 08:00:11.344 645 662 D SubscriptionController: - subId invalid
01-01 08:00:11.345 651 925 D TelephonyManager: getSimState: default sim:-1, sim state for slotIdx=0 is 6, return state as unknown
01-01 08:00:11.491 645 662 D SubscriptionController: Sub Controller not ready
01-01 08:00:11.585 257 586 D AT : AT> ATE0Q0V1
01-01 08:00:11.586 257 587 D AT : AT< ATE0Q0V1
01-01 08:00:11.607 257 587 D AT : AT< OK
01-01 08:00:11.857 257 586 D AT : AT> AT+ZLANENABLE?
01-01 08:00:11.881 257 587 D AT : AT< +CME ERROR: 58
01-01 08:00:11.881 257 585 I RIL : add by tim initializeCallback()
01-01 08:00:11.881 257 585 D RIL : setRadioState:oldState=1, sState=1, newState=0
01-01 08:00:11.881 257 585 D AT : AT> ATE0Q0V1
01-01 08:00:11.882 645 784 D RILJ : < UNSOL_RESPONSE_RADIO_STATE_CHANGED RADIO_OFF
01-01 08:00:11.882 645 645 D SST : mDeviceShuttingDown=false, mDesiredPowerState=true, getRadioState=RADIO_OFF, mPowerOffDelayNeed=true, mAlarmSwitch=false, mRadioDisabledByCarrier=false
01-01 08:00:11.882 645 784 D RILJ : > SCREEN_STATE: true
01-01 08:00:11.882 645 645 D RILJ : > RADIO_POWER on
01-01 08:00:11.883 645 784 D RILJ : < UNSOL_RESPONSE_SIM_STATUS_CHANGED
01-01 08:00:11.884 645 645 D RILJ : > OPERATOR
01-01 08:00:11.891 645 645 D RILJ : > DATA_REGISTRATION_STATE
01-01 08:00:11.893 645 645 D RILJ : > VOICE_REGISTRATION_STATE
01-01 08:00:11.894 645 645 D RILJ : > QUERY_NETWORK_SELECTION_MODE
01-01 08:00:11.895 645 645 D RILJ : > BASEBAND_VERSION
01-01 08:00:11.897 645 645 D RILJ : > GET_IMEI
01-01 08:00:11.903 257 587 D AT : AT< OK
01-01 08:00:11.906 645 645 D RILJ : > GET_IMEISV
01-01 08:00:11.907 645 645 D RILJ : > RIL_REQUEST_GET_RADIO_CAPABILITY
01-01 08:00:11.908 645 645 D RILJ : > RIL_REQUEST_START_LCE
01-01 08:00:11.912 645 645 D DCT : onRadioAvailable
01-01 08:00:11.912 645 645 D UiccController: Received EVENT_ICC_STATUS_CHANGED, calling getIccCardStatus
01-01 08:00:11.912 645 645 D RILJ : > GET_SIM_STATUS
01-01 08:00:11.994 645 665 D SubscriptionController: - subId invalid
01-01 08:00:12.002 651 925 D TelephonyManager: getSimState: default sim:-1, sim state for slotIdx=0 is 6, return state as unknown
01-01 08:00:12.024 645 645 D WAP PUSH: Received broadcast android.intent.action.USER_UNLOCKED
01-01 08:00:12.025 645 645 D WAP PUSH: Received broadcast android.intent.action.USER_UNLOCKED
01-01 08:00:12.025 645 645 D SmsBroadcastUndelivered: Received broadcast android.intent.action.USER_UNLOCKED
01-01 08:00:12.026 645 645 D SubscriptionInfoUpdater: +
01-01 08:00:12.026 645 645 D SubscriptionInfoUpdater: Action: android.intent.action.USER_UNLOCKED
01-01 08:00:12.026 645 645 D SubscriptionInfoUpdater: Broadcasting intent ACTION_SIM_STATE_CHANGED for mCardIndex: 0
01-01 08:00:12.028 6451022 D SmsBroadcastUndelivered: scanning raw table for undelivered messages
01-01 08:00:12.091 645 645 D SubscriptionInfoUpdater: -
01-01 08:00:12.094 6451022 D SmsBroadcastUndelivered: finished scanning raw table in 66 ms
01-01 08:00:12.096 645 645 D SubscriptionInfoUpdater: +
01-01 08:00:12.096 645 645 D SubscriptionInfoUpdater: Action: android.intent.action.SIM_STATE_CHANGED
01-01 08:00:12.096 645 645 D SubscriptionInfoUpdater: slotId: 0
01-01 08:00:12.096 645 645 D SubscriptionInfoUpdater: simStatus: NOT_READY
01-01 08:00:12.096 645 645 D SubscriptionInfoUpdater: Ignoring simStatus: NOT_READY
01-01 08:00:12.096 645 645 D SubscriptionInfoUpdater: -
01-01 08:00:12.134 645 881 D SubscriptionController: Sub Controller not ready
01-01 08:00:12.146 645 881 D SubscriptionController: - subId invalid
01-01 08:00:12.148 651 925 D TelephonyManager: getSimState: default sim:-1, sim state for slotIdx=0 is 6, return state as unknown
01-01 08:00:12.154 257 585 D AT : AT> AT+SYSNV=0,"usbmode"
01-01 08:00:12.176 257 587 D AT : AT< +SYSNV: "usbmode",5
01-01 08:00:12.176 257 587 D AT : AT< OK
01-01 08:00:12.176 257 585 D RIL : usbmode:5
01-01 08:00:12.176 257 585 I RIL : dialmode=0,ppp,ECM
01-01 08:00:12.176 257 585 I RIL : Found GSM Modem
01-01 08:00:12.177 257 585 D RIL : ml302 port is usb0
01-01 08:00:12.177 257 585 D AT : AT> ATE0Q0V1
01-01 08:00:12.199 257 587 D AT : AT< OK
01-01 08:00:12.199 257 585 D AT : AT> ATS0=0
01-01 08:00:12.219 257 587 D AT : AT< OK
01-01 08:00:12.220 257 585 D AT : AT> AT+CMEE=1
01-01 08:00:12.240 257 587 D AT : AT< OK
01-01 08:00:12.240 257 585 D AT : AT> AT+CREG=2
01-01 08:00:12.262 257 587 D AT : AT< OK
01-01 08:00:12.262 257 585 D AT : AT> AT+CGREG=2
01-01 08:00:12.284 257 587 D AT : AT< OK
01-01 08:00:12.285 257 585 D AT : AT> AT+CEREG=2
01-01 08:00:12.306 257 587 D AT : AT< OK
01-01 08:00:12.306 257 585 D AT : AT> AT+CCWA=1
01-01 08:00:12.327 257 587 D AT : AT< OK
01-01 08:00:12.327 257 585 D AT : AT> AT+CMOD=0
01-01 08:00:12.342 6451019 D SubscriptionController: - subId invalid
01-01 08:00:12.348 257 587 D AT : AT< OK
01-01 08:00:12.348 257 585 D AT : AT> AT+CSSN=0,1
01-01 08:00:12.361 651 925 D TelephonyManager: getSimState: default sim:-1, sim state for slotIdx=0 is 6, return state as unknown
01-01 08:00:12.369 257 587 D AT : AT< +CME ERROR: 58
01-01 08:00:12.369 257 585 D AT : AT> AT+COLP=0
01-01 08:00:12.390 257 587 D AT : AT< +CME ERROR: 58
01-01 08:00:12.390 257 585 D AT : AT> AT+CSCS="HEX"
01-01 08:00:12.411 257 587 D AT : AT< OK
01-01 08:00:12.411 257 585 D AT : AT> AT+CUSD=1
01-01 08:00:12.432 257 587 D AT : AT< +CME ERROR: 58
01-01 08:00:12.432 257 585 D AT : AT> AT+CMGF=0
01-01 08:00:12.453 257 587 D AT : AT< OK
01-01 08:00:12.453 257 585 D AT : AT> AT+ZMMI=1
01-01 08:00:12.475 257 587 D AT : AT< +CME ERROR: 58
01-01 08:00:12.475 257 585 D AT : AT> AT+CEER=1
01-01 08:00:12.496 257 587 D AT : AT< +CME ERROR: 3
01-01 08:00:12.497 257 585 D AT : AT> AT+CVMOD=0
01-01 08:00:12.519 257 587 D AT : AT< +CME ERROR: 58
01-01 08:00:12.519 257 585 D AT : AT> AT+CCID
01-01 08:00:12.541 257 587 D AT : AT< +CCID: 898600B731AC85061088
01-01 08:00:12.541 257 587 D AT : AT< OK
01-01 08:00:12.543 257 585 D RIL : can't find property:ril.cmiot.cmvol
01-01 08:00:12.544 257 585 D RIL : can't find property:ril.cmiot.netled
01-01 08:00:12.544 257 585 D AT : AT> AT+CFUN?
01-01 08:00:12.566 257 587 D AT : AT< +CFUN: 1
01-01 08:00:12.566 257 587 D AT : AT< OK
01-01 08:00:12.566 257 585 D RIL : setRadioState:oldState=0, sState=0, newState=2
01-01 08:00:12.566 257 585 I RIL : onRadioPowerOn
01-01 08:00:12.566 257 585 D RIL : getSIMStatus(). sState: 2
01-01 08:00:12.566 257 585 D AT : AT> AT+CPIN?
01-01 08:00:12.566 645 784 D RILJ : < UNSOL_VOICE_RADIO_TECH_CHANGED {3}
01-01 08:00:12.566 645 784 D RILJ : < UNSOL_RESPONSE_RADIO_STATE_CHANGED RADIO_ON
01-01 08:00:12.566 645 784 D RILJ : < UNSOL_RESPONSE_SIM_STATUS_CHANGED
01-01 08:00:12.566 645 645 D GsmCdmaPhone: EVENT_VOICE_RADIO_TECH_CHANGED: newVoiceTech=3
01-01 08:00:12.566 645 645 D GsmCdmaPhone: phoneObjectUpdater: newVoiceRadioTech=3
01-01 08:00:12.566 645 645 D GsmCdmaPhone: phoneObjectUpdater: No change ignore, newVoiceRadioTech=3 mActivePhone=GSM
01-01 08:00:12.566 645 645 D SST : mDeviceShuttingDown=false, mDesiredPowerState=true, getRadioState=RADIO_ON, mPowerOffDelayNeed=true, mAlarmSwitch=false, mRadioDisabledByCarrier=false
01-01 08:00:12.567 645 645 D RILJ : > OPERATOR
01-01 08:00:12.568 645 645 D RILJ : > DATA_REGISTRATION_STATE
01-01 08:00:12.568 645 645 D RILJ : > VOICE_REGISTRATION_STATE
01-01 08:00:12.569 645 645 D RILJ : > QUERY_NETWORK_SELECTION_MODE
01-01 08:00:12.570 645 645 D UiccController: Received EVENT_ICC_STATUS_CHANGED, calling getIccCardStatus
01-01 08:00:12.570 645 645 D RILJ : > GET_SIM_STATUS
01-01 08:00:12.571 645 645 D RILJ : > GET_CURRENT_CALLS
01-01 08:00:12.572 645 645 D RILJ : > RIL_REQUEST_IMS_REGISTRATION_STATE
01-01 08:00:12.573 645 645 D RILJ : > RIL_REQUEST_CDMA_GET_SUBSCRIPTION_SOURCE
01-01 08:00:12.574 645 645 D GsmCdmaPhone: Event EVENT_RADIO_ON Received
01-01 08:00:12.574 645 645 D RILJ : > RIL_REQUEST_VOICE_RADIO_TECH
01-01 08:00:12.578 645 645 D UiccController: Received EVENT_ICC_STATUS_CHANGED, calling getIccCardStatus
01-01 08:00:12.578 645 645 D RILJ : > GET_SIM_STATUS
01-01 08:00:12.601 257 587 D AT : AT< +CPIN: READY
01-01 08:00:12.601 257 587 D AT : AT< OK
01-01 08:00:12.601 257 585 I RIL : getSIMStatus:READY
01-01 08:00:12.601 257 585 I RIL : pollSIMState:SIM_READY
01-01 08:00:12.601 257 585 D RIL : setRadioState:oldState=2, sState=2, newState=4
01-01 08:00:12.601 257 585 D AT : AT> AT+CSMS=1
01-01 08:00:12.601 645 784 D RILJ : < UNSOL_RESPONSE_SIM_STATUS_CHANGED
01-01 08:00:12.602 645 784 D RILJ : < UNSOL_RESPONSE_RADIO_STATE_CHANGED RADIO_ON
01-01 08:00:12.602 645 784 D RILJ : < UNSOL_RESPONSE_SIM_STATUS_CHANGED
01-01 08:00:12.602 645 645 D UiccController: Received EVENT_ICC_STATUS_CHANGED, calling getIccCardStatus
01-01 08:00:12.602 645 645 D RILJ : > GET_SIM_STATUS
01-01 08:00:12.604 645 645 D UiccController: Received EVENT_ICC_STATUS_CHANGED, calling getIccCardStatus
01-01 08:00:12.604 645 645 D RILJ : > GET_SIM_STATUS
01-01 08:00:12.635 257 587 D AT : AT< +CSMS: 1,1,1
01-01 08:00:12.636 257 587 D AT : AT< OK
01-01 08:00:12.636 257 585 D AT : AT> AT+CNMI=2,1,2,2,0
01-01 08:00:12.656 257 587 D AT : AT< +CMS ERROR: 518
01-01 08:00:12.657 257 585 D RIL : onRequest: SCREEN_STATE
01-01 08:00:12.657 257 585 D RIL : now sState is 4
01-01 08:00:12.657 257 585 D RIL : requestScreenState
01-01 08:00:12.657 257 585 D RIL : onRequest: RADIO_POWER
01-01 08:00:12.657 257 585 D RIL : now sState is 4
01-01 08:00:12.657 257 585 I RIL : requestRadioPower()
01-01 08:00:12.657 257 585 I RIL : requestRadioPower onOff=1
01-01 08:00:12.657 257 585 D RIL : onRequest: OPERATOR
01-01 08:00:12.657 257 585 D RIL : now sState is 4
01-01 08:00:12.657 257 585 D AT : AT> AT+COPS=3,0;+COPS?;+COPS=3,1;+COPS?;+COPS=3,2;+COPS?
01-01 08:00:12.657 645 784 D RILJ : < UNSOL_RESPONSE_VOICE_NETWORK_STATE_CHANGED
01-01 08:00:12.658 645 784 D RILJ : < SCREEN_STATE
01-01 08:00:12.658 645 784 D RILJ : < RADIO_POWER
01-01 08:00:12.658 645 645 D RILJ : > OPERATOR
01-01 08:00:12.662 645 645 D RILJ : > DATA_REGISTRATION_STATE
01-01 08:00:12.663 645 645 D RILJ : > VOICE_REGISTRATION_STATE
01-01 08:00:12.666 645 645 D RILJ : > QUERY_NETWORK_SELECTION_MODE
01-01 08:00:12.679 257 587 D AT : AT< +COPS: 0,0,"CHINA MOBILE",7
01-01 08:00:12.680 257 587 D AT : AT< +COPS: 0,1,"CMCC",7
01-01 08:00:12.681 257 587 D AT : AT< +COPS: 0,2,"46000",7
01-01 08:00:12.681 257 587 D AT : AT< OK
01-01 08:00:12.681 257 585 D RIL : onRequest: DATA_REGISTRATION_STATE
01-01 08:00:12.681 257 585 D RIL : now sState is 4
01-01 08:00:12.681 257 585 D RIL : requestRegistrationState
01-01 08:00:12.681 257 585 D AT : AT> AT+CEREG?
01-01 08:00:12.682 645 784 D RILJ : < OPERATOR {CHINA MOBILE, CMCC, 46000}
01-01 08:00:12.704 257 587 D AT : AT< +CEREG: 2,1,"2496","0DE92643",7
01-01 08:00:12.704 257 587 D AT : AT< OK
01-01 08:00:12.704 257 585 D RIL : parseRegistrationState. Parsing: +CEREG: 2,1,"2496","0DE92643",7
01-01 08:00:12.704 257 585 D RIL : requestRegistrationState ending
01-01 08:00:12.704 257 585 D RIL : onRequest: VOICE_REGISTRATION_STATE
01-01 08:00:12.704 257 585 D RIL : now sState is 4
01-01 08:00:12.704 257 585 D RIL : requestRegistrationState
01-01 08:00:12.704 257 585 D AT : AT> AT+CREG?
01-01 08:00:12.704 645 784 D RILJ : < DATA_REGISTRATION_STATE {1, 2496, de92643, 14, null, null, null}
01-01 08:00:12.726 257 587 D AT : AT< +CREG: 2,1,"0000","0DE92643",7
01-01 08:00:12.726 257 587 D AT : AT< OK
01-01 08:00:12.726 257 585 D RIL : parseRegistrationState. Parsing: +CREG: 2,1,"0000","0DE92643",7
01-01 08:00:12.726 257 585 D RIL : requestRegistrationState ending
01-01 08:00:12.726 257 585 D RIL : onRequest: QUERY_NETWORK_SELECTION_MODE
01-01 08:00:12.726 257 585 D RIL : now sState is 4
01-01 08:00:12.726 257 585 D AT : AT> AT+COPS?
01-01 08:00:12.727 645 784 D RILJ : < VOICE_REGISTRATION_STATE {1, 0, de92643, 14, null, null, null, null, null, null, null, null, null, null, null}
01-01 08:00:12.748 257 587 D AT : AT< +COPS: 0,2,"46000",7
01-01 08:00:12.748 257 587 D AT : AT< OK
01-01 08:00:12.748 257 585 D RIL : onRequest: BASEBAND_VERSION
01-01 08:00:12.748 257 585 D RIL : now sState is 4
01-01 08:00:12.749 257 585 D AT : AT> AT+GMR
01-01 08:00:12.751 645 784 D RILJ : < QUERY_NETWORK_SELECTION_MODE {0}
01-01 08:00:12.751 645 645 D SST : EVENT_POLL_STATE_NETWORK_SELECTION_MODE
01-01 08:00:12.770 257 587 D AT : AT< ML302-DNPM_MBRH2S02
01-01 08:00:12.770 257 587 D AT : AT< OK
01-01 08:00:12.770 257 585 D RIL : onRequest: GET_IMEI
01-01 08:00:12.770 257 585 D RIL : now sState is 4
01-01 08:00:12.770 257 585 D AT : AT> AT+GSN=1
01-01 08:00:12.770 645 784 D RILJ : < BASEBAND_VERSION ML302-DNPM_MBRH2S02
01-01 08:00:12.770 645 645 D GsmCdmaPhone: Baseband version: ML302-DNPM_MBRH2S02
01-01 08:00:12.791 257 587 D AT : AT< +GSN: 861193040256829
01-01 08:00:12.791 257 587 D AT : AT< OK
01-01 08:00:12.791 257 585 D RIL : onRequest: GET_IMEISV
01-01 08:00:12.791 257 585 D RIL : now sState is 4
01-01 08:00:12.791 257 585 D RIL : Request not supported. Tech: 0x20
01-01 08:00:12.791 257 585 D RIL : GET_IMEISV not supported
01-01 08:00:12.791 257 585 D RIL : onRequest: RIL_REQUEST_GET_RADIO_CAPABILITY
01-01 08:00:12.791 257 585 D RIL : now sState is 4
01-01 08:00:12.791 257 585 D RIL : Request not supported. Tech: 0x20
01-01 08:00:12.791 257 585 D RIL : RIL_REQUEST_GET_RADIO_CAPABILITY not supported
01-01 08:00:12.791 257 585 D RIL : onRequest: <unknown request>
01-01 08:00:12.791 257 585 D RIL : now sState is 4
01-01 08:00:12.791 645 784 D RILJ : < GET_IMEI
01-01 08:00:12.791 257 585 D RIL : Request not supported. Tech: 0x20
01-01 08:00:12.791 257 585 D RIL : <unknown request> not supported
01-01 08:00:12.792 257 585 D RIL : onRequest: GET_SIM_STATUS
01-01 08:00:12.792 257 585 D RIL : now sState is 4
01-01 08:00:12.792 257 585 I RIL : requestGetSimStatus
01-01 08:00:12.792 257 585 D RIL : getSIMStatus(). sState: 4
01-01 08:00:12.792 257 585 D AT : AT> AT+CPIN?
01-01 08:00:12.792 645 784 D RilRequest: < GET_IMEISV error: com.android.internal.telephony.CommandException: REQUEST_NOT_SUPPORTED ret=
01-01 08:00:12.792 645 784 D RILJ : Faking RIL_REQUEST_GET_RADIO_CAPABILITY response using 1
01-01 08:00:12.792 645 784 D RILJ : < RIL_REQUEST_GET_RADIO_CAPABILITY {mPhoneId = 0 mVersion=1 mSession=0 mPhase=0 mRadioAccessFamily=1 mLogicModemId= mStatus=1}
01-01 08:00:12.793 645 784 D RilRequest: < RIL_REQUEST_START_LCE error: com.android.internal.telephony.CommandException: REQUEST_NOT_SUPPORTED ret=
01-01 08:00:12.793 645 645 D GsmCdmaPhone: EVENT_GET_RADIO_CAPABILITY: phone rc: {mPhoneId = 0 mVersion=1 mSession=0 mPhase=0 mRadioAccessFamily=1 mLogicModemId= mStatus=1}
01-01 08:00:12.793 645 645 D Phone : config LCE service failed: com.android.internal.telephony.CommandException: REQUEST_NOT_SUPPORTED
01-01 08:00:12.827 257 587 D AT : AT< +CPIN: READY
01-01 08:00:12.827 257 587 D AT : AT< OK
01-01 08:00:12.827 257 585 I RIL : getSIMStatus:READY
01-01 08:00:12.827 257 585 D AT : AT> AT^SIMIF=1,0
01-01 08:00:12.849 257 587 D AT : AT< ^SIMIF: 1
01-01 08:00:12.849 257 587 D AT : AT< OK
01-01 08:00:12.849 257 585 I RIL : Detected card type :2
01-01 08:00:12.849 257 585 I RIL : : USIM
01-01 08:00:12.849 257 585 D RIL : requestGetSimStatus:0,,280
01-01 08:00:12.849 257 585 D RIL : onRequest: OPERATOR
01-01 08:00:12.849 257 585 D RIL : now sState is 4
01-01 08:00:12.849 257 585 D AT : AT> AT+COPS=3,0;+COPS?;+COPS=3,1;+COPS?;+COPS=3,2;+COPS?
01-01 08:00:12.851 645 784 D RILJ : < GET_SIM_STATUS IccCardState {CARDSTATE_PRESENT,PINSTATE_UNKNOWN,num_apps=1,gsm_id=0{APPTYPE_USIM,APPSTATE_READY,pin1=PINSTATE_UNKNOWN,pin2=PINSTATE_UNKNOWN},cdma_id=8,ims_id=8}
01-01 08:00:12.851 645 645 D UiccController: Received EVENT_GET_ICC_STATUS_DONE
01-01 08:00:12.851 645 645 D UiccCard: 1 applications
01-01 08:00:12.851 645 645 D UiccCardApplication: Creating UiccApp: {APPTYPE_USIM,APPSTATE_READY,pin1=PINSTATE_UNKNOWN,pin2=PINSTATE_UNKNOWN}
01-01 08:00:12.854 645 645 W VoiceMailConstants: Can't open /system/etc/voicemail-conf.xml
01-01 08:00:12.855 645 645 D SpnOverride: No SPN in OEM image = /oem/telephony/spn-conf.xml Load SPN from system image
01-01 08:00:12.872 257 587 D AT : AT< +COPS: 0,0,"CHINA MOBILE",7
01-01 08:00:12.872 257 587 D AT : AT< +COPS: 0,1,"CMCC",7
01-01 08:00:12.876 257 587 D AT : AT< +COPS: 0,2,"46000",7
01-01 08:00:12.876 257 587 D AT : AT< OK
01-01 08:00:12.876 257 585 D RIL : onRequest: DATA_REGISTRATION_STATE
01-01 08:00:12.876 257 585 D RIL : now sState is 4
01-01 08:00:12.877 257 585 D RIL : requestRegistrationState
01-01 08:00:12.877 257 585 D AT : AT> AT+CEREG?
01-01 08:00:12.877 645 784 D RILJ : < OPERATOR {CHINA MOBILE, CMCC, 46000}
01-01 08:00:12.881 257 586 I RIL : entering waitForClose
01-01 08:00:12.898 257 587 D AT : AT< +CEREG: 2,1,"2496","0DE92643",7
01-01 08:00:12.898 257 587 D AT : AT< OK
01-01 08:00:12.898 257 585 D RIL : parseRegistrationState. Parsing: +CEREG: 2,1,"2496","0DE92643",7
01-01 08:00:12.898 257 585 D RIL : requestRegistrationState ending
01-01 08:00:12.898 257 585 D RIL : onRequest: VOICE_REGISTRATION_STATE
01-01 08:00:12.898 257 585 D RIL : now sState is 4
01-01 08:00:12.898 257 585 D RIL : requestRegistrationState
01-01 08:00:12.898 257 585 D AT : AT> AT+CREG?
01-01 08:00:12.898 645 784 D RILJ : < DATA_REGISTRATION_STATE {1, 2496, de92643, 14, null, null, null}
01-01 08:00:12.901 645 645 D SIMRecords: setting0 mMncLength-1
01-01 08:00:12.901 645 645 D SIMRecords: SIMRecords: onRadioOffOrNotAvailable set 'gsm.sim.operator.numeric' to operator=null
01-01 08:00:12.901 645 645 D SIMRecords: update icc_operator_numeric=null
01-01 08:00:12.901 645 645 D TelephonyManager: setTelephonyProperty: success phoneId=0 property=gsm.sim.operator.numeric value:propVal=
01-01 08:00:12.902 645 645 D TelephonyManager: setTelephonyProperty: success phoneId=0 property=gsm.sim.operator.alpha value:propVal=
01-01 08:00:12.903 645 645 D TelephonyManager: setTelephonyProperty: success phoneId=0 property=gsm.sim.operator.iso-country value:propVal=
01-01 08:00:12.904 645 645 D UiccCardApplication: Notifying 1 registrant: READY
01-01 08:00:12.904 645 645 D SIMRecords: getOperatorNumeric: IMSI == null
01-01 08:00:12.904 645 645 D SIMRecords: SIMRecords X ctor this=SimRecords: mDestroyed=false mContext=com.android.phone.PhoneGlobals@ee85c36 mCi=com.android.internal.telephony.RIL@3f40381 mFh=Handler (com.android.internal.telephony.uicc.UsimFileHandler) {ccbe737} mParentApp=com.android.internal.telephony.uicc.UiccCardApplication@ece64a4 recordsLoadedRegistrants=android.os.RegistrantList@b63020d mImsiReadyRegistrants=android.os.RegistrantList@6d605c2 mRecordsEventsRegistrants=android.os.RegistrantList@4edbfd3 mNewSmsRegistrants=android.os.RegistrantList@9df2710 mNetworkSelectionModeAutomaticRegistrants=android.os.RegistrantList@84ab209 recordsToLoad=0 adnCache=Handler (com.android.internal.telephony.uicc.AdnRecordCache) {602bc0e} recordsRequested=false iccid=null msisdnTag=null voiceMailNum=null voiceMailTag=null voiceMailNum=null newVoiceMailTag=null isVoiceMailFixed=false mImsi=null mncLength=-1 mailboxIndex=0 spn=null mVmConfigcom.android.internal.telephony.uicc.VoiceMailConstants@54a862f mSpnOverride=mSpnOverride callForwardingEnabled=0 spnState=null mCphsInfo=null mCspPlmnEnabled=true efMWIS=null efCPHS_MWI=null mEfCff=null mEfCfis=null getOperatorNumeric=null
01-01 08:00:12.906 645 645 D RILJ : > QUERY_FACILITY_LOCK
01-01 08:00:12.906 645 645 D RILJ : > QUERY_FACILITY_LOCK
01-01 08:00:12.912 645 645 D CAT : CatService: registerForRecordsLoaded slotid=0 instance:Handler (com.android.internal.telephony.cat.CatService) {8999941}
01-01 08:00:12.913 645 645 D CAT : CatService: Running CAT service on Slotid: 0. STK app installed:false
01-01 08:00:12.913 645 645 D UiccCard: Before privilege rules: null : CARDSTATE_PRESENT
01-01 08:00:12.916 645 645 D RILJ : > RIL_REQUEST_SIM_OPEN_CHANNEL
01-01 08:00:12.917 645 645 E UiccCard: App index 8 is invalid since there are no applications
01-01 08:00:12.917 645 645 E UiccCard: App index 8 is invalid since there are no applications
01-01 08:00:12.917 645 645 D UiccCard: update: radioState=RADIO_ON mLastRadioState=RADIO_UNAVAILABLE
01-01 08:00:12.917 645 645 D UiccController: Notifying IccChangedRegistrants
01-01 08:00:12.919 257 587 D AT : AT< +CREG: 2,1,"0000","0DE92643",7
01-01 08:00:12.920 257 587 D AT : AT< OK
01-01 08:00:12.920 257 585 D RIL : parseRegistrationState. Parsing: +CREG: 2,1,"0000","0DE92643",7
01-01 08:00:12.920 257 585 D RIL : requestRegistrationState ending
01-01 08:00:12.920 257 585 D RIL : onRequest: QUERY_NETWORK_SELECTION_MODE
01-01 08:00:12.920 257 585 D RIL : now sState is 4
01-01 08:00:12.920 257 585 D AT : AT> AT+COPS?
01-01 08:00:12.920 645 784 D RILJ : < VOICE_REGISTRATION_STATE {1, 0, de92643, 14, null, null, null, null, null, null, null, null, null, null, null}
01-01 08:00:12.941 257 587 D AT : AT< +COPS: 0,2,"46000",7
01-01 08:00:12.941 257 587 D AT : AT< OK
01-01 08:00:12.942 257 585 D RIL : onRequest: GET_SIM_STATUS
01-01 08:00:12.942 257 585 D RIL : now sState is 4
01-01 08:00:12.942 257 585 I RIL : requestGetSimStatus
01-01 08:00:12.942 257 585 D RIL : getSIMStatus(). sState: 4
01-01 08:00:12.942 257 585 D AT : AT> AT+CPIN?
01-01 08:00:12.942 645 784 D RILJ : < QUERY_NETWORK_SELECTION_MODE {0}
01-01 08:00:12.944 645 645 D SIMRecords: fetchSimRecords 0
01-01 08:00:12.944 645 645 D RILJ : > getIMSI: GET_IMSI aid: null
01-01 08:00:12.945 645 645 D RILJ : > iccIO: SIM_IO 0xc0 0x2fe2path: 3F00,0,0,15 aid: null
01-01 08:00:12.946 645 645 D RILJ : > iccIO: SIM_IO 0xc0 0x6f40path: 3F007FFF,0,0,15 aid: null
01-01 08:00:12.946 645 645 D RILJ : > iccIO: SIM_IO 0xc0 0x6fc9path: 3F007FFF,0,0,15 aid: null
01-01 08:00:12.947 645 645 D RILJ : > iccIO: SIM_IO 0xc0 0x6fadpath: 3F007FFF,0,0,15 aid: null
01-01 08:00:12.947 645 645 D RILJ : > iccIO: SIM_IO 0xc0 0x6fcapath: 3F007FFF,0,0,15 aid: null
01-01 08:00:12.948 645 645 D RILJ : > iccIO: SIM_IO 0xc0 0x6f11path: 3F007FFF,0,0,15 aid: null
01-01 08:00:12.950 645 645 D RILJ : > iccIO: SIM_IO 0xc0 0x6fcbpath: 3F007FFF,0,0,15 aid: null
01-01 08:00:12.951 645 645 D RILJ : > iccIO: SIM_IO 0xc0 0x6f13path: 3F007FFF,0,0,15 aid: null
01-01 08:00:12.952 645 645 D RILJ : > iccIO: SIM_IO 0xc0 0x6f46path: 3F007FFF,0,0,15 aid: null
01-01 08:00:12.953 645 645 D RILJ : > iccIO: SIM_IO 0xc0 0x6fcdpath: 3F007FFF,0,0,15 aid: null
01-01 08:00:12.954 645 645 D RILJ : > iccIO: SIM_IO 0xc0 0x6fc5path: 3F007FFF,0,0,15 aid: null
01-01 08:00:12.954 645 645 D RILJ : > iccIO: SIM_IO 0xc0 0x6f38path: 3F007FFF,0,0,15 aid: null
01-01 08:00:12.955 645 645 D RILJ : > iccIO: SIM_IO 0xc0 0x6f16path: 3F007FFF,0,0,15 aid: null
01-01 08:00:12.956 6451019 D SubscriptionController: - no sims, returning default phoneId=2147483647
01-01 08:00:12.956 645 645 D RILJ : > iccIO: SIM_IO 0xc0 0x6f15path: 3F007FFF,0,0,15 aid: null
01-01 08:00:12.957 645 645 D RILJ : > iccIO: SIM_IO 0xc0 0x6f3epath: 3F007FFF,0,0,15 aid: null
01-01 08:00:12.957 645 645 D RILJ : > iccIO: SIM_IO 0xc0 0x6f3fpath: 3F007FFF,0,0,15 aid: null
01-01 08:00:12.959 645 645 D RILJ : > iccIO: SIM_IO 0xc0 0x6f60path: 3F007FFF,0,0,15 aid: null
01-01 08:00:12.960 645 645 D RILJ : > iccIO: SIM_IO 0xc0 0x6f61path: 3F007FFF,0,0,15 aid: null
01-01 08:00:12.961 645 645 D RILJ : > iccIO: SIM_IO 0xc0 0x6f62path: 3F007FFF,0,0,15 aid: null
01-01 08:00:12.962 645 645 D RILJ : > iccIO: SIM_IO 0xc0 0x6fd9path: 3F007FFF,0,0,15 aid: null
01-01 08:00:12.963 645 645 D RILJ : > iccIO: SIM_IO 0xc0 0x6f7bpath: 3F007FFF,0,0,15 aid: null
01-01 08:00:12.964 645 645 D RILJ : > iccIO: SIM_IO 0xc0 0x6f05path: 3F007FFF,0,0,15 aid: null
01-01 08:00:12.965 645 645 D RILJ : > iccIO: SIM_IO 0xc0 0x2f05path: 3F00,0,0,15 aid: null
01-01 08:00:12.965 645 645 D SIMRecords: fetchSimRecords 20 requested: true
01-01 08:00:12.966 645 645 D CAT : CatService: handleMessage
01-01 08:00:12.966 645 645 D CAT : CatService: MSG_ID_ICC_CHANGED
01-01 08:00:12.966 645 645 D CAT : CatService: New Card State = CARDSTATE_PRESENT Old Card State = CARDSTATE_ABSENT
01-01 08:00:12.966 645 645 D RILJ : > RIL_REQUEST_REPORT_STK_SERVICE_IS_RUNNING
01-01 08:00:12.967 645 645 D GsmCdmaPhone: New Uicc application found. type = APPTYPE_USIM
01-01 08:00:12.967 645 645 D GsmSMSDispatcher: GsmSMSDispatcher: subId = -2 slotId = 0
01-01 08:00:12.967 645 645 D GsmSMSDispatcher: New Uicc application found
01-01 08:00:12.967 645 645 D IccCardProxy: Icc changed. Reregestering.
01-01 08:00:12.967 645 645 D UiccCardApplication: Notifying 1 registrant: READY
01-01 08:00:12.967 645 645 E IccCardProxy: setExternalState: set mPhoneId=0 mExternalState=READY
01-01 08:00:12.967 645 645 D TelephonyManager: setTelephonyProperty: success phoneId=0 property=gsm.sim.state value: READY propVal=READY
01-01 08:00:12.968 645 645 D IccCardProxy: broadcastIccStateChangedIntent intent ACTION_SIM_STATE_CHANGED value=READY reason=null for mPhoneId=0
01-01 08:00:12.969 645 645 D SST : New card found
01-01 08:00:12.970 645 645 D UiccCardApplication: Notifying 1 registrant: READY
01-01 08:00:12.970 645 645 D CAT : CatService: handleMessage
01-01 08:00:12.970 645 645 D CAT : CatService: MSG_ID_ICC_CHANGED
01-01 08:00:12.970 645 645 D CAT : CatService: New Card State = CARDSTATE_PRESENT Old Card State = CARDSTATE_PRESENT
01-01 08:00:12.970 645 645 D SST : EVENT_POLL_STATE_NETWORK_SELECTION_MODE
01-01 08:00:12.970 645 645 E IccCardProxy: setExternalState: !override and newstate unchanged from READY
01-01 08:00:12.970 645 645 D RILJ : > OPERATOR
01-01 08:00:12.971 645 645 D RILJ : > DATA_REGISTRATION_STATE
01-01 08:00:12.973 645 645 D RILJ : > VOICE_REGISTRATION_STATE
01-01 08:00:12.974 645 881 D SubscriptionController: Sub Controller not ready
01-01 08:00:12.975 645 645 D RILJ : > QUERY_NETWORK_SELECTION_MODE
01-01 08:00:12.976 645 645 D SubscriptionInfoUpdater: +
01-01 08:00:12.976 645 645 D SubscriptionInfoUpdater: Action: android.intent.action.SIM_STATE_CHANGED
01-01 08:00:12.976 645 645 D SubscriptionInfoUpdater: slotId: 0
01-01 08:00:12.976 645 645 D SubscriptionInfoUpdater: simStatus: READY
01-01 08:00:12.976 645 645 D SubscriptionInfoUpdater: Ignoring simStatus: READY
01-01 08:00:12.976 645 645 D SubscriptionInfoUpdater: -
01-01 08:00:12.979 257 587 D AT : AT< +CPIN: READY
01-01 08:00:12.979 257 587 D AT : AT< OK
01-01 08:00:12.979 257 585 I RIL : getSIMStatus:READY
01-01 08:00:12.979 257 585 I RIL : Detected card type :2
01-01 08:00:12.979 257 585 I RIL : : USIM
01-01 08:00:12.979 257 585 D RIL : requestGetSimStatus:0,,280
01-01 08:00:12.979 257 585 D RIL : onRequest: GET_CURRENT_CALLS
01-01 08:00:12.979 257 585 D RIL : now sState is 4
01-01 08:00:12.979 257 585 D AT : AT> AT+CLCC
01-01 08:00:12.979 645 784 D RILJ : < GET_SIM_STATUS IccCardState {CARDSTATE_PRESENT,PINSTATE_UNKNOWN,num_apps=1,gsm_id=0{APPTYPE_USIM,APPSTATE_READY,pin1=PINSTATE_UNKNOWN,pin2=PINSTATE_UNKNOWN},cdma_id=8,ims_id=8}
01-01 08:00:12.979 645 645 D UiccController: Received EVENT_GET_ICC_STATUS_DONE
01-01 08:00:12.979 645 645 D UiccCard: 1 applications
01-01 08:00:12.979 645 645 D UiccCardApplication: APPTYPE_USIM update. New {APPTYPE_USIM,APPSTATE_READY,pin1=PINSTATE_UNKNOWN,pin2=PINSTATE_UNKNOWN}
01-01 08:00:12.979 645 881 D SubscriptionController: - no sims, returning default phoneId=2147483647
01-01 08:00:12.979 645 645 D UiccCard: Before privilege rules: Handler (com.android.internal.telephony.uicc.UiccCarrierPrivilegeRules) {f5e3ee6} : CARDSTATE_PRESENT
01-01 08:00:12.979 645 645 E UiccCard: App index 8 is invalid since there are no applications
01-01 08:00:12.979 645 645 E UiccCard: App index 8 is invalid since there are no applications
01-01 08:00:12.979 645 645 D UiccCard: update: radioState=RADIO_ON mLastRadioState=RADIO_ON
01-01 08:00:12.979 645 645 D UiccController: Notifying IccChangedRegistrants
01-01 08:00:12.980 645 645 D GsmSMSDispatcher: GsmSMSDispatcher: subId = -2 slotId = 0
01-01 08:00:12.980 645 645 E IccCardProxy: setExternalState: !override and newstate unchanged from READY
01-01 08:00:12.980 645 645 D CAT : CatService: handleMessage
01-01 08:00:12.980 645 645 D CAT : CatService: MSG_ID_ICC_CHANGED
01-01 08:00:12.980 645 645 D CAT : CatService: New Card State = CARDSTATE_PRESENT Old Card State = CARDSTATE_PRESENT
01-01 08:00:13.000 257 587 D AT : AT< OK
01-01 08:00:13.000 257 585 I RIL : Calls=0,Valid=0
01-01 08:00:13.000 257 585 D RIL : onRequest: IMS_REGISTRATION_STATE
01-01 08:00:13.000 257 585 D RIL : now sState is 4
01-01 08:00:13.000 257 585 D RIL : Request not supported. Tech: 0x20
01-01 08:00:13.000 257 585 D RIL : IMS_REGISTRATION_STATE not supported
01-01 08:00:13.000 257 585 D RIL : onRequest: GET_SIM_STATUS
01-01 08:00:13.000 257 585 D RIL : now sState is 4
01-01 08:00:13.000 257 585 I RIL : requestGetSimStatus
01-01 08:00:13.000 257 585 D RIL : getSIMStatus(). sState: 4
01-01 08:00:13.000 257 585 D AT : AT> AT+CPIN?
01-01 08:00:13.001 645 784 D RILJ : < GET_CURRENT_CALLS {}
01-01 08:00:13.001 645 645 D GsmCdmaCallTracker: Event EVENT_POLL_CALLS_RESULT Received
01-01 08:00:13.001 645 645 D GsmCdmaCallTracker: update phone state, old=IDLE new=IDLE
01-01 08:00:13.001 645 784 D RilRequest: < RIL_REQUEST_IMS_REGISTRATION_STATE error: com.android.internal.telephony.CommandException: REQUEST_NOT_SUPPORTED ret=
01-01 08:00:13.001 645 645 E RIL_ImsSms: IMS State query failed with exp com.android.internal.telephony.CommandException: REQUEST_NOT_SUPPORTED
01-01 08:00:13.001 645 784 D RILJ : < RIL_REQUEST_CDMA_GET_SUBSCRIPTION_SOURCE {0}
01-01 08:00:13.001 645 645 D CdmaSSM : CDMA_SUBSCRIPTION_SOURCE event = 2
01-01 08:00:13.001 645 784 D RILJ : < RIL_REQUEST_VOICE_RADIO_TECH {3}
01-01 08:00:13.001 645 645 D GsmCdmaPhone: EVENT_REQUEST_VOICE_RADIO_TECH_DONE: newVoiceTech=3
01-01 08:00:13.002 645 645 D GsmCdmaPhone: phoneObjectUpdater: newVoiceRadioTech=3
01-01 08:00:13.002 645 645 D GsmCdmaPhone: phoneObjectUpdater: No change ignore, newVoiceRadioTech=3 mActivePhone=GSM
01-01 08:00:13.036 257 587 D AT : AT< +CPIN: READY
01-01 08:00:13.036 257 587 D AT : AT< OK
01-01 08:00:13.036 257 585 I RIL : getSIMStatus:READY
01-01 08:00:13.036 257 585 I RIL : Detected card type :2
01-01 08:00:13.036 257 585 I RIL : : USIM
01-01 08:00:13.036 257 585 D RIL : requestGetSimStatus:0,,280
01-01 08:00:13.036 257 585 D RIL : onRequest: GET_SIM_STATUS
01-01 08:00:13.036 257 585 D RIL : now sState is 4
01-01 08:00:13.036 257 585 I RIL : requestGetSimStatus
01-01 08:00:13.036 257 585 D RIL : getSIMStatus(). sState: 4
01-01 08:00:13.036 257 585 D AT : AT> AT+CPIN?
01-01 08:00:13.037 645 784 D RILJ : < GET_SIM_STATUS IccCardState {CARDSTATE_PRESENT,PINSTATE_UNKNOWN,num_apps=1,gsm_id=0{APPTYPE_USIM,APPSTATE_READY,pin1=PINSTATE_UNKNOWN,pin2=PINSTATE_UNKNOWN},cdma_id=8,ims_id=8}
01-01 08:00:13.037 645 645 D UiccController: Received EVENT_GET_ICC_STATUS_DONE
01-01 08:00:13.037 645 645 D UiccCard: 1 applications
01-01 08:00:13.037 645 645 D UiccCardApplication: APPTYPE_USIM update. New {APPTYPE_USIM,APPSTATE_READY,pin1=PINSTATE_UNKNOWN,pin2=PINSTATE_UNKNOWN}
01-01 08:00:13.037 645 645 D UiccCard: Before privilege rules: Handler (com.android.internal.telephony.uicc.UiccCarrierPrivilegeRules) {f5e3ee6} : CARDSTATE_PRESENT
01-01 08:00:13.037 645 645 E UiccCard: App index 8 is invalid since there are no applications
01-01 08:00:13.037 645 645 E UiccCard: App index 8 is invalid since there are no applications
01-01 08:00:13.037 645 645 D UiccCard: update: radioState=RADIO_ON mLastRadioState=RADIO_ON
01-01 08:00:13.037 645 645 D UiccController: Notifying IccChangedRegistrants
01-01 08:00:13.037 645 645 D GsmSMSDispatcher: GsmSMSDispatcher: subId = -2 slotId = 0
01-01 08:00:13.037 645 645 E IccCardProxy: setExternalState: !override and newstate unchanged from READY
01-01 08:00:13.037 645 645 D CAT : CatService: handleMessage
01-01 08:00:13.037 645 645 D CAT : CatService: MSG_ID_ICC_CHANGED
01-01 08:00:13.037 645 645 D CAT : CatService: New Card State = CARDSTATE_PRESENT Old Card State = CARDSTATE_PRESENT
01-01 08:00:13.072 257 587 D AT : AT< +CPIN: READY
01-01 08:00:13.072 257 587 D AT : AT< OK
01-01 08:00:13.072 257 585 I RIL : getSIMStatus:READY
01-01 08:00:13.072 257 585 I RIL : Detected card type :2
01-01 08:00:13.072 257 585 I RIL : : USIM
01-01 08:00:13.072 257 585 D RIL : requestGetSimStatus:0,,280
01-01 08:00:13.072 257 585 D RIL : onRequest: GET_SIM_STATUS
01-01 08:00:13.072 257 585 D RIL : now sState is 4
01-01 08:00:13.072 257 585 I RIL : requestGetSimStatus
01-01 08:00:13.072 257 585 D RIL : getSIMStatus(). sState: 4
01-01 08:00:13.072 257 585 D AT : AT> AT+CPIN?
01-01 08:00:13.072 645 784 D RILJ : < GET_SIM_STATUS IccCardState {CARDSTATE_PRESENT,PINSTATE_UNKNOWN,num_apps=1,gsm_id=0{APPTYPE_USIM,APPSTATE_READY,pin1=PINSTATE_UNKNOWN,pin2=PINSTATE_UNKNOWN},cdma_id=8,ims_id=8}
01-01 08:00:13.072 645 645 D UiccController: Received EVENT_GET_ICC_STATUS_DONE
01-01 08:00:13.073 645 645 D UiccCard: 1 applications
01-01 08:00:13.073 645 645 D UiccCardApplication: APPTYPE_USIM update. New {APPTYPE_USIM,APPSTATE_READY,pin1=PINSTATE_UNKNOWN,pin2=PINSTATE_UNKNOWN}
01-01 08:00:13.073 645 645 D UiccCard: Before privilege rules: Handler (com.android.internal.telephony.uicc.UiccCarrierPrivilegeRules) {f5e3ee6} : CARDSTATE_PRESENT
01-01 08:00:13.073 645 645 E UiccCard: App index 8 is invalid since there are no applications
01-01 08:00:13.073 645 645 E UiccCard: App index 8 is invalid since there are no applications
01-01 08:00:13.073 645 645 D UiccCard: update: radioState=RADIO_ON mLastRadioState=RADIO_ON
01-01 08:00:13.073 645 645 D UiccController: Notifying IccChangedRegistrants
01-01 08:00:13.073 645 645 D GsmSMSDispatcher: GsmSMSDispatcher: subId = -2 slotId = 0
01-01 08:00:13.073 645 645 E IccCardProxy: setExternalState: !override and newstate unchanged from READY
01-01 08:00:13.073 645 645 D CAT : CatService: handleMessage
01-01 08:00:13.073 645 645 D CAT : CatService: MSG_ID_ICC_CHANGED
01-01 08:00:13.073 645 645 D CAT : CatService: New Card State = CARDSTATE_PRESENT Old Card State = CARDSTATE_PRESENT
01-01 08:00:13.108 257 587 D AT : AT< +CPIN: READY
01-01 08:00:13.108 257 587 D AT : AT< OK
01-01 08:00:13.108 257 585 I RIL : getSIMStatus:READY
01-01 08:00:13.108 257 585 I RIL : Detected card type :2
01-01 08:00:13.108 257 585 I RIL : : USIM
01-01 08:00:13.108 257 585 D RIL : requestGetSimStatus:0,,280
01-01 08:00:13.108 257 585 D RIL : onRequest: OPERATOR
01-01 08:00:13.108 257 585 D RIL : now sState is 4
01-01 08:00:13.108 257 585 D AT : AT> AT+COPS=3,0;+COPS?;+COPS=3,1;+COPS?;+COPS=3,2;+COPS?
01-01 08:00:13.108 645 784 D RILJ : < GET_SIM_STATUS IccCardState {CARDSTATE_PRESENT,PINSTATE_UNKNOWN,num_apps=1,gsm_id=0{APPTYPE_USIM,APPSTATE_READY,pin1=PINSTATE_UNKNOWN,pin2=PINSTATE_UNKNOWN},cdma_id=8,ims_id=8}
01-01 08:00:13.108 645 645 D UiccController: Received EVENT_GET_ICC_STATUS_DONE
01-01 08:00:13.109 645 645 D UiccCard: 1 applications
01-01 08:00:13.109 645 645 D UiccCardApplication: APPTYPE_USIM update. New {APPTYPE_USIM,APPSTATE_READY,pin1=PINSTATE_UNKNOWN,pin2=PINSTATE_UNKNOWN}
01-01 08:00:13.109 645 645 D UiccCard: Before privilege rules: Handler (com.android.internal.telephony.uicc.UiccCarrierPrivilegeRules) {f5e3ee6} : CARDSTATE_PRESENT
01-01 08:00:13.109 645 645 E UiccCard: App index 8 is invalid since there are no applications
01-01 08:00:13.109 645 645 E UiccCard: App index 8 is invalid since there are no applications
01-01 08:00:13.109 645 645 D UiccCard: update: radioState=RADIO_ON mLastRadioState=RADIO_ON
01-01 08:00:13.109 645 645 D UiccController: Notifying IccChangedRegistrants
01-01 08:00:13.109 645 645 D GsmSMSDispatcher: GsmSMSDispatcher: subId = -2 slotId = 0
01-01 08:00:13.109 645 645 E IccCardProxy: setExternalState: !override and newstate unchanged from READY
01-01 08:00:13.109 645 645 D CAT : CatService: handleMessage
01-01 08:00:13.109 645 645 D CAT : CatService: MSG_ID_ICC_CHANGED
01-01 08:00:13.109 645 645 D CAT : CatService: New Card State = CARDSTATE_PRESENT Old Card State = CARDSTATE_PRESENT
01-01 08:00:13.130 257 587 D AT : AT< +COPS: 0,0,"CHINA MOBILE",7
01-01 08:00:13.131 257 587 D AT : AT< +COPS: 0,1,"CMCC",7
01-01 08:00:13.132 257 587 D AT : AT< +COPS: 0,2,"46000",7
01-01 08:00:13.133 257 587 D AT : AT< OK
01-01 08:00:13.133 257 585 D RIL : onRequest: DATA_REGISTRATION_STATE
01-01 08:00:13.133 257 585 D RIL : now sState is 4
01-01 08:00:13.133 257 585 D RIL : requestRegistrationState
01-01 08:00:13.133 257 585 D AT : AT> AT+CEREG?
01-01 08:00:13.133 645 784 D RILJ : < OPERATOR {CHINA MOBILE, CMCC, 46000}
01-01 08:00:13.154 257 587 D AT : AT< +CEREG: 2,1,"2496","0DE92643",7
01-01 08:00:13.154 257 587 D AT : AT< OK
01-01 08:00:13.154 257 585 D RIL : parseRegistrationState. Parsing: +CEREG: 2,1,"2496","0DE92643",7
01-01 08:00:13.154 257 585 D RIL : requestRegistrationState ending
01-01 08:00:13.154 257 585 D RIL : onRequest: VOICE_REGISTRATION_STATE
01-01 08:00:13.154 257 585 D RIL : now sState is 4
01-01 08:00:13.154 257 585 D RIL : requestRegistrationState
01-01 08:00:13.154 257 585 D AT : AT> AT+CREG?
01-01 08:00:13.155 645 784 D RILJ : < DATA_REGISTRATION_STATE {1, 2496, de92643, 14, null, null, null}
01-01 08:00:13.176 257 587 D AT : AT< +CREG: 2,1,"0000","0DE92643",7
01-01 08:00:13.176 257 587 D AT : AT< OK
01-01 08:00:13.176 257 585 D RIL : parseRegistrationState. Parsing: +CREG: 2,1,"0000","0DE92643",7
01-01 08:00:13.176 257 585 D RIL : requestRegistrationState ending
01-01 08:00:13.176 257 585 D RIL : onRequest: QUERY_NETWORK_SELECTION_MODE
01-01 08:00:13.176 257 585 D RIL : now sState is 4
01-01 08:00:13.176 257 585 D AT : AT> AT+COPS?
01-01 08:00:13.176 645 784 D RILJ : < VOICE_REGISTRATION_STATE {1, 0, de92643, 14, null, null, null, null, null, null, null, null, null, null, null}
01-01 08:00:13.198 257 587 D AT : AT< +COPS: 0,2,"46000",7
01-01 08:00:13.198 257 587 D AT : AT< OK
01-01 08:00:13.198 257 585 D RIL : onRequest: QUERY_FACILITY_LOCK
01-01 08:00:13.198 257 585 D RIL : now sState is 4
01-01 08:00:13.198 257 585 D RIL : Request not supported. Tech: 0x20
01-01 08:00:13.198 257 585 D RIL : QUERY_FACILITY_LOCK not supported
01-01 08:00:13.198 257 585 D RIL : onRequest: QUERY_FACILITY_LOCK
01-01 08:00:13.198 257 585 D RIL : now sState is 4
01-01 08:00:13.198 257 585 D RIL : Request not supported. Tech: 0x20
01-01 08:00:13.198 645 784 D RILJ : < QUERY_NETWORK_SELECTION_MODE {0}
01-01 08:00:13.198 257 585 D RIL : QUERY_FACILITY_LOCK not supported
01-01 08:00:13.198 257 585 D RIL : onRequest: SIM_OPEN_CHANNEL
01-01 08:00:13.198 645 645 D SST : EVENT_POLL_STATE_NETWORK_SELECTION_MODE
01-01 08:00:13.198 257 585 D RIL : now sState is 4
01-01 08:00:13.198 257 585 D RIL : Request not supported. Tech: 0x20
01-01 08:00:13.198 257 585 D RIL : SIM_OPEN_CHANNEL not supported
01-01 08:00:13.198 257 585 D RIL : onRequest: GET_IMSI
01-01 08:00:13.198 257 585 D RIL : now sState is 4
01-01 08:00:13.198 257 585 D AT : AT> AT+CIMI
01-01 08:00:13.198 645 784 D RilRequest: < QUERY_FACILITY_LOCK error: com.android.internal.telephony.CommandException: REQUEST_NOT_SUPPORTED ret=
01-01 08:00:13.198 645 645 D UiccCardApplication: Error in querying facility lock:com.android.internal.telephony.CommandException: REQUEST_NOT_SUPPORTED
01-01 08:00:13.198 645 784 D RilRequest: < QUERY_FACILITY_LOCK error: com.android.internal.telephony.CommandException: REQUEST_NOT_SUPPORTED ret=
01-01 08:00:13.199 645 645 D UiccCardApplication: Error in querying facility lock:com.android.internal.telephony.CommandException: REQUEST_NOT_SUPPORTED
01-01 08:00:13.199 645 784 D RilRequest: < RIL_REQUEST_SIM_OPEN_CHANNEL error: com.android.internal.telephony.CommandException: REQUEST_NOT_SUPPORTED ret=
01-01 08:00:13.199 645 645 D UiccCard: Error in SIM access with exceptioncom.android.internal.telephony.CommandException: REQUEST_NOT_SUPPORTED
01-01 08:00:13.199 645 645 D UiccPkcs15: Creating UiccPkcs15
01-01 08:00:13.200 645 645 D RILJ : > RIL_REQUEST_SIM_OPEN_CHANNEL
01-01 08:00:13.249 257 587 D AT : AT< 460078750641088
01-01 08:00:13.249 257 587 D AT : AT< OK
01-01 08:00:13.249 257 585 D RIL : onRequest: SIM_IO
01-01 08:00:13.249 257 585 D RIL : now sState is 4
01-01 08:00:13.249 257 585 D AT : AT> AT+CRSM=192,12258,0,0,15,,"3F00"
01-01 08:00:13.250 645 784 D RILJ : < GET_IMSI
01-01 08:00:13.250 645 645 D SIMRecords: IMSI: mMncLength=-1
01-01 08:00:13.250 645 645 D SIMRecords: IMSI: 460078
01-01 08:00:13.250 645 645 D SIMRecords: onRecordLoaded 19 requested: true
01-01 08:00:13.251 645 645 D IccCardProxy: broadcastIccStateChangedIntent intent ACTION_SIM_STATE_CHANGED value=IMSI reason=null for mPhoneId=0
01-01 08:00:13.255 645 645 D SubscriptionInfoUpdater: +
01-01 08:00:13.255 645 645 D SubscriptionInfoUpdater: Action: android.intent.action.SIM_STATE_CHANGED
01-01 08:00:13.255 645 645 D SubscriptionInfoUpdater: slotId: 0
01-01 08:00:13.256 645 645 D SubscriptionInfoUpdater: simStatus: IMSI
01-01 08:00:13.256 645 645 D SubscriptionInfoUpdater: Ignoring simStatus: IMSI
01-01 08:00:13.256 645 645 D SubscriptionInfoUpdater: -
01-01 08:00:13.257 6451019 D SubscriptionController: Sub Controller not ready
01-01 08:00:13.258 645 881 D SubscriptionController: - no sims, returning default phoneId=2147483647
01-01 08:00:13.267 645 645 D SubscriptionController: asked for default subId=-1
01-01 08:00:13.271 257 587 D AT : AT< +CME ERROR: 53
01-01 08:00:13.271 257 585 D RIL : onRequest: SIM_IO
01-01 08:00:13.271 257 585 D RIL : now sState is 4
01-01 08:00:13.271 257 585 D AT : AT> AT+CRSM=192,28480,0,0,15,,"3F007FFF"
01-01 08:00:13.272 645 784 D RilRequest: < SIM_IO error: com.android.internal.telephony.CommandException: GENERIC_FAILURE ret=
01-01 08:00:13.274 645 645 D SIMRecords: onRecordLoaded 18 requested: true
01-01 08:00:13.277 645 645 D GsmCdmaPhone: mBroadcastReceiver: action android.telephony.action.CARRIER_CONFIG_CHANGED
01-01 08:00:13.282 645 645 I CSST : reading time to delay notification: -1
01-01 08:00:13.291 645 645 D ImsManager: updateVolteFeatureValue: available = false, enabled = true, nonTTY = true
01-01 08:00:13.292 645 645 E ImsManager: updateImsServiceConfig:
01-01 08:00:13.292 645 645 E ImsManager: com.android.ims.ImsException: Service is unavailable, code = 106
01-01 08:00:13.292 645 645 E ImsManager: at com.android.ims.ImsManager.checkAndThrowExceptionIfServiceUnavailable(ImsManager.java:1316)
01-01 08:00:13.292 645 645 E ImsManager: at com.android.ims.ImsManager.getConfigInterface(ImsManager.java:1198)
01-01 08:00:13.292 645 645 E ImsManager: at com.android.ims.ImsManager.updateVolteFeatureValue(ImsManager.java:780)
01-01 08:00:13.292 645 645 E ImsManager: at com.android.ims.ImsManager.updateImsServiceConfig(ImsManager.java:740)
01-01 08:00:13.292 645 645 E ImsManager: at com.android.internal.telephony.GsmCdmaPhone.handleMessage(GsmCdmaPhone.java:2084)
01-01 08:00:13.292 645 645 E ImsManager: at android.os.Handler.dispatchMessage(Handler.java:102)
01-01 08:00:13.292 645 645 E ImsManager: at android.os.Looper.loop(Looper.java:154)
01-01 08:00:13.292 645 645 E ImsManager: at android.app.ActivityThread.main(ActivityThread.java:6141)
01-01 08:00:13.292 645 645 E ImsManager: at java.lang.reflect.Method.invoke(Native Method)
01-01 08:00:13.292 645 645 E ImsManager: at com.android.internal.os.ZygoteInit$MethodAndArgsCaller.run(ZygoteInit.java:912)
01-01 08:00:13.292 645 645 E ImsManager: at com.android.internal.os.ZygoteInit.main(ZygoteInit.java:802)
01-01 08:00:13.292 257 587 D AT : AT< +CME ERROR: 53
01-01 08:00:13.293 257 585 D RIL : onRequest: SIM_IO
01-01 08:00:13.293 257 585 D RIL : now sState is 4
01-01 08:00:13.293 257 585 D AT : AT> AT+CRSM=192,28617,0,0,15,,"3F007FFF"
01-01 08:00:13.293 645 784 D RilRequest: < SIM_IO error: com.android.internal.telephony.CommandException: GENERIC_FAILURE ret=
01-01 08:00:13.293 645 645 D GsmCdmaPhone: broadcastEmergencyCallStateChanges = false
01-01 08:00:13.294 645 645 E GsmCdmaPhone: Invalid cdma_roaming_mode settings: -1
01-01 08:00:13.294 645 645 D EriManager: loadEriFileFromXml: check for alternate file
01-01 08:00:13.298 645 645 D EriManager: loadEriFileFromXml: no alternate file
01-01 08:00:13.299 645 645 D EriManager: eriFile = eri.xml
01-01 08:00:13.305 645 645 D EriManager: loadEriFileFromXml: eri parsing successful, file loaded. ver = 1357, # of entries = 176
01-01 08:00:13.306 645 645 D GsmCdmaPhone: ERI read, notify registrants
01-01 08:00:13.314 257 587 D AT : AT< +CME ERROR: 53
01-01 08:00:13.315 257 585 D RIL : onRequest: SIM_IO
01-01 08:00:13.315 257 585 D RIL : now sState is 4
01-01 08:00:13.315 257 585 D AT : AT> AT+CRSM=192,28589,0,0,15,,"3F007FFF"
01-01 08:00:13.315 645 784 D RilRequest: < SIM_IO error: com.android.internal.telephony.CommandException: GENERIC_FAILURE ret=
01-01 08:00:13.320 645 645 D SIMRecords: getSIMOperatorNumeric: bad mncLength
01-01 08:00:13.320 645 645 D RILJ : > iccIO: SIM_IO 0xc0 0x6f17path: 3F007FFF,0,0,15 aid: null
01-01 08:00:13.321 645 645 D SIMRecords: onRecordLoaded 18 requested: true
01-01 08:00:13.321 645 645 D SIMRecords: Invalid or missing EF
01-01 08:00:13.321 645 645 D SIMRecords: onRecordLoaded 17 requested: true
01-01 08:00:13.336 257 587 D AT : AT< +CME ERROR: 53
01-01 08:00:13.336 257 585 D RIL : onRequest: SIM_IO
01-01 08:00:13.336 257 585 D RIL : now sState is 4
01-01 08:00:13.336 257 585 D AT : AT> AT+CRSM=192,28618,0,0,15,,"3F007FFF"
01-01 08:00:13.337 645 784 D RilRequest: < SIM_IO error: com.android.internal.telephony.CommandException: GENERIC_FAILURE ret=
01-01 08:00:13.337 645 645 D SIMRecords: mccmncCode=460078
01-01 08:00:13.339 645 645 D SIMRecords: setting7 mMncLength=2
01-01 08:00:13.339 645 645 D SIMRecords: update mccmnc=46007
01-01 08:00:13.340 645 645 D SubscriptionController: - no sims, returning default phoneId=2147483647
01-01 08:00:13.358 257 587 D AT : AT< +CME ERROR: 53
01-01 08:00:13.358 257 585 D RIL : onRequest: SIM_IO
01-01 08:00:13.358 257 585 D RIL : now sState is 4
01-01 08:00:13.358 257 585 D AT : AT> AT+CRSM=192,28433,0,0,15,,"3F007FFF"
01-01 08:00:13.358 645 784 D RilRequest: < SIM_IO error: com.android.internal.telephony.CommandException: GENERIC_FAILURE ret=
01-01 08:00:13.379 645 645 D SIMRecords: onRecordLoaded 16 requested: true
01-01 08:00:13.379 257 587 D AT : AT< +CME ERROR: 53
01-01 08:00:13.382 257 585 D RIL : onRequest: SIM_IO
01-01 08:00:13.382 257 585 D RIL : now sState is 4
01-01 08:00:13.382 257 585 D AT : AT> AT+CRSM=192,28619,0,0,15,,"3F007FFF"
01-01 08:00:13.383 645 784 D RilRequest: < SIM_IO error: com.android.internal.telephony.CommandException: GENERIC_FAILURE ret=
01-01 08:00:13.394 645 881 D DCT : hasMatchedTetherApnSetting: APN=null
01-01 08:00:13.403 257 587 D AT : AT< +CME ERROR: 53
01-01 08:00:13.404 257 585 D RIL : onRequest: SIM_IO
01-01 08:00:13.404 257 585 D RIL : now sState is 4
01-01 08:00:13.404 257 585 D AT : AT> AT+CRSM=192,28435,0,0,15,,"3F007FFF"
01-01 08:00:13.404 645 784 D RilRequest: < SIM_IO error: com.android.internal.telephony.CommandException: GENERIC_FAILURE ret=
01-01 08:00:13.416 645 881 D DCT : hasMatchedTetherApnSetting: APN=null
01-01 08:00:13.419 645 645 D SIMRecords: EF_MWIS : null
01-01 08:00:13.419 645 645 D SIMRecords: EVENT_GET_MWIS_DONE exception = com.android.internal.telephony.CommandException: GENERIC_FAILURE
01-01 08:00:13.419 645 645 D SIMRecords: onRecordLoaded 15 requested: true
01-01 08:00:13.419 645 645 D SIMRecords: EF_CPHS_MWI: null
01-01 08:00:13.419 645 645 D SIMRecords: EVENT_GET_VOICE_MAIL_INDICATOR_CPHS_DONE exception = com.android.internal.telephony.CommandException: GENERIC_FAILURE
01-01 08:00:13.419 645 645 D SIMRecords: onRecordLoaded 14 requested: true
01-01 08:00:13.419 645 645 D SIMRecords: onRecordLoaded 13 requested: true
01-01 08:00:13.426 257 587 D AT : AT< +CME ERROR: 53
01-01 08:00:13.427 257 585 D RIL : onRequest: SIM_IO
01-01 08:00:13.427 257 585 D RIL : now sState is 4
01-01 08:00:13.427 257 585 D AT : AT> AT+CRSM=192,28486,0,0,15,,"3F007FFF"
01-01 08:00:13.427 645 784 D RilRequest: < SIM_IO error: com.android.internal.telephony.CommandException: GENERIC_FAILURE ret=
01-01 08:00:13.427 645 645 D SIMRecords: onRecordLoaded 12 requested: true
01-01 08:00:13.448 257 587 D AT : AT< +CME ERROR: 53
01-01 08:00:13.448 257 585 D RIL : onRequest: SIM_IO
01-01 08:00:13.448 257 585 D RIL : now sState is 4
01-01 08:00:13.448 257 585 D AT : AT> AT+CRSM=192,28621,0,0,15,,"3F007FFF"
01-01 08:00:13.448 645 784 D RilRequest: < SIM_IO error: com.android.internal.telephony.CommandException: GENERIC_FAILURE ret=
01-01 08:00:13.449 645 645 D RILJ : > iccIO: SIM_IO 0xc0 0x6f14path: 3F007FFF,0,0,15 aid: null
01-01 08:00:13.449 645 645 D SIMRecords: onRecordLoaded 12 requested: true
01-01 08:00:13.460 645 806 D GsmCellBroadcastHandler: mWakeLock released
01-01 08:00:13.469 257 587 D AT : AT< +CME ERROR: 53
01-01 08:00:13.470 257 585 D RIL : onRequest: SIM_IO
01-01 08:00:13.470 257 585 D RIL : now sState is 4
01-01 08:00:13.470 257 585 D AT : AT> AT+CRSM=192,28613,0,0,15,,"3F007FFF"
01-01 08:00:13.470 645 784 D RilRequest: < SIM_IO error: com.android.internal.telephony.CommandException: GENERIC_FAILURE ret=
01-01 08:00:13.470 645 645 D SIMRecords: onRecordLoaded 11 requested: true
01-01 08:00:13.477 645 812 D CellBroadcastHandler: mWakeLock released
01-01 08:00:13.488 645 815 D CdmaServiceCategoryProgramHandler: mWakeLock released
01-01 08:00:13.491 257 587 D AT : AT< +CME ERROR: 53
01-01 08:00:13.491 257 585 D RIL : onRequest: SIM_IO
01-01 08:00:13.491 257 585 D RIL : now sState is 4
01-01 08:00:13.491 257 585 D AT : AT> AT+CRSM=192,28472,0,0,15,,"3F007FFF"
01-01 08:00:13.491 645 784 D RilRequest: < SIM_IO error: com.android.internal.telephony.CommandException: GENERIC_FAILURE ret=
01-01 08:00:13.491 645 645 D SIMRecords: onRecordLoaded 10 requested: true
01-01 08:00:13.496 645 808 D GsmInboundSmsHandler: IdleState.processMessage:5
01-01 08:00:13.496 645 808 D GsmInboundSmsHandler: Idle state processing message type 5
01-01 08:00:13.497 645 813 D CdmaInboundSmsHandler: IdleState.processMessage:5
01-01 08:00:13.497 645 813 D CdmaInboundSmsHandler: Idle state processing message type 5
01-01 08:00:13.497 645 808 D GsmInboundSmsHandler: mWakeLock released
01-01 08:00:13.498 645 813 D CdmaInboundSmsHandler: mWakeLock released
01-01 08:00:13.512 257 587 D AT : AT< +CME ERROR: 53
01-01 08:00:13.512 257 585 D RIL : onRequest: SIM_IO
01-01 08:00:13.512 257 585 D RIL : now sState is 4
01-01 08:00:13.512 257 585 D AT : AT> AT+CRSM=192,28438,0,0,15,,"3F007FFF"
01-01 08:00:13.513 645 784 D RilRequest: < SIM_IO error: com.android.internal.telephony.CommandException: GENERIC_FAILURE ret=
01-01 08:00:13.513 645 645 D SIMRecords: onRecordLoaded 9 requested: true
01-01 08:00:13.534 257 587 D AT : AT< +CME ERROR: 53
01-01 08:00:13.534 257 585 D RIL : onRequest: SIM_IO
01-01 08:00:13.534 257 585 D RIL : now sState is 4
01-01 08:00:13.534 257 585 D AT : AT> AT+CRSM=192,28437,0,0,15,,"3F007FFF"
01-01 08:00:13.534 645 784 D RilRequest: < SIM_IO error: com.android.internal.telephony.CommandException: GENERIC_FAILURE ret=
01-01 08:00:13.534 645 645 D SIMRecords: onRecordLoaded 8 requested: true
01-01 08:00:13.555 257 587 D AT : AT< +CME ERROR: 53
01-01 08:00:13.555 257 585 D RIL : onRequest: SIM_IO
01-01 08:00:13.555 257 585 D RIL : now sState is 4
01-01 08:00:13.555 257 585 D AT : AT> AT+CRSM=192,28478,0,0,15,,"3F007FFF"
01-01 08:00:13.555 645 784 D RilRequest: < SIM_IO error: com.android.internal.telephony.CommandException: GENERIC_FAILURE ret=
01-01 08:00:13.555 645 645 E SIMRecords: Exception in fetching EF_CSP data com.android.internal.telephony.CommandException: GENERIC_FAILURE
01-01 08:00:13.555 645 645 D SIMRecords: onRecordLoaded 7 requested: true
01-01 08:00:13.576 257 587 D AT : AT< +CME ERROR: 53
01-01 08:00:13.577 257 585 D RIL : onRequest: SIM_IO
01-01 08:00:13.577 257 585 D RIL : now sState is 4
01-01 08:00:13.577 257 585 D AT : AT> AT+CRSM=192,28479,0,0,15,,"3F007FFF"
01-01 08:00:13.577 645 784 D RilRequest: < SIM_IO error: com.android.internal.telephony.CommandException: GENERIC_FAILURE ret=
01-01 08:00:13.577 645 645 E SIMRecords: Exception in get GID1 com.android.internal.telephony.CommandException: GENERIC_FAILURE
01-01 08:00:13.577 645 645 D SIMRecords: onRecordLoaded 6 requested: true
01-01 08:00:13.598 257 587 D AT : AT< +CME ERROR: 53
01-01 08:00:13.598 257 585 D RIL : onRequest: SIM_IO
01-01 08:00:13.598 257 585 D RIL : now sState is 4
01-01 08:00:13.598 257 585 D AT : AT> AT+CRSM=192,28512,0,0,15,,"3F007FFF"
01-01 08:00:13.598 645 784 D RilRequest: < SIM_IO error: com.android.internal.telephony.CommandException: GENERIC_FAILURE ret=
01-01 08:00:13.599 645 645 E SIMRecords: Exception in get GID2 com.android.internal.telephony.CommandException: GENERIC_FAILURE
01-01 08:00:13.599 645 645 D SIMRecords: onRecordLoaded 5 requested: true
01-01 08:00:13.619 257 587 D AT : AT< +CME ERROR: 53
01-01 08:00:13.619 257 585 D RIL : onRequest: SIM_IO
01-01 08:00:13.619 257 585 D RIL : now sState is 4
01-01 08:00:13.620 257 585 D AT : AT> AT+CRSM=192,28513,0,0,15,,"3F007FFF"
01-01 08:00:13.620 645 784 D RilRequest: < SIM_IO error: com.android.internal.telephony.CommandException: GENERIC_FAILURE ret=
01-01 08:00:13.620 645 645 E SIMRecords: Failed getting User PLMN with Access Tech Records: com.android.internal.telephony.CommandException: GENERIC_FAILURE
01-01 08:00:13.641 257 587 D AT : AT< +CME ERROR: 53
01-01 08:00:13.641 257 585 D RIL : onRequest: SIM_IO
01-01 08:00:13.641 257 585 D RIL : now sState is 4
01-01 08:00:13.641 257 585 D AT : AT> AT+CRSM=192,28514,0,0,15,,"3F007FFF"
01-01 08:00:13.641 645 784 D RilRequest: < SIM_IO error: com.android.internal.telephony.CommandException: GENERIC_FAILURE ret=
01-01 08:00:13.641 645 645 E SIMRecords: Failed getting Operator PLMN with Access Tech Records: com.android.internal.telephony.CommandException: GENERIC_FAILURE
01-01 08:00:13.664 257 587 D AT : AT< +CME ERROR: 53
01-01 08:00:13.664 257 585 D RIL : onRequest: SIM_IO
01-01 08:00:13.664 257 585 D RIL : now sState is 4
01-01 08:00:13.664 257 585 D AT : AT> AT+CRSM=192,28633,0,0,15,,"3F007FFF"
01-01 08:00:13.664 645 784 D RilRequest: < SIM_IO error: com.android.internal.telephony.CommandException: GENERIC_FAILURE ret=
01-01 08:00:13.664 645 645 E SIMRecords: Failed getting Home PLMN with Access Tech Records: com.android.internal.telephony.CommandException: GENERIC_FAILURE
01-01 08:00:13.685 257 587 D AT : AT< +CME ERROR: 53
01-01 08:00:13.685 257 585 D RIL : onRequest: SIM_IO
01-01 08:00:13.685 257 585 D RIL : now sState is 4
01-01 08:00:13.685 257 585 D AT : AT> AT+CRSM=192,28539,0,0,15,,"3F007FFF"
01-01 08:00:13.686 645 784 D RilRequest: < SIM_IO error: com.android.internal.telephony.CommandException: GENERIC_FAILURE ret=
01-01 08:00:13.686 645 645 E SIMRecords: Failed getting Equivalent Home PLMNs: com.android.internal.telephony.CommandException: GENERIC_FAILURE
01-01 08:00:13.707 257 587 D AT : AT< +CME ERROR: 53
01-01 08:00:13.707 257 585 D RIL : onRequest: SIM_IO
01-01 08:00:13.707 257 585 D RIL : now sState is 4
01-01 08:00:13.707 257 585 D AT : AT> AT+CRSM=192,28421,0,0,15,,"3F007FFF"
01-01 08:00:13.707 645 784 D RilRequest: < SIM_IO error: com.android.internal.telephony.CommandException: GENERIC_FAILURE ret=
01-01 08:00:13.707 645 645 E SIMRecords: Failed getting Forbidden PLMNs: com.android.internal.telephony.CommandException: GENERIC_FAILURE
01-01 08:00:13.707 645 645 D SIMRecords: onRecordLoaded 4 requested: true
01-01 08:00:13.728 257 587 D AT : AT< +CME ERROR: 53
01-01 08:00:13.728 257 585 D RIL : onRequest: SIM_IO
01-01 08:00:13.728 257 585 D RIL : now sState is 4
01-01 08:00:13.728 257 585 D AT : AT> AT+CRSM=192,12037,0,0,15,,"3F00"
01-01 08:00:13.729 645 784 D RilRequest: < SIM_IO error: com.android.internal.telephony.CommandException: GENERIC_FAILURE ret=
01-01 08:00:13.729 645 645 D SIMRecords: EF_LI LOADED
01-01 08:00:13.729 645 645 E SIMRecords: Record Load Exception: com.android.internal.telephony.CommandException: GENERIC_FAILURE
01-01 08:00:13.729 645 645 D SIMRecords: onRecordLoaded 3 requested: true
01-01 08:00:13.749 257 587 D AT : AT< +CME ERROR: 53
01-01 08:00:13.750 257 585 D RIL : onRequest: REPORT_STK_SERVICE_IS_RUNNING
01-01 08:00:13.750 257 585 D RIL : now sState is 4
01-01 08:00:13.750 257 585 D RIL : onRequest: OPERATOR
01-01 08:00:13.750 257 585 D RIL : now sState is 4
01-01 08:00:13.750 257 585 D AT : AT> AT+COPS=3,0;+COPS?;+COPS=3,1;+COPS?;+COPS=3,2;+COPS?
01-01 08:00:13.750 645 784 D RilRequest: < SIM_IO error: com.android.internal.telephony.CommandException: GENERIC_FAILURE ret=
01-01 08:00:13.750 645 784 D RILJ : < RIL_REQUEST_REPORT_STK_SERVICE_IS_RUNNING
01-01 08:00:13.751 645 645 D SIMRecords: EF_PL LOADED
01-01 08:00:13.751 645 645 E SIMRecords: Record Load Exception: com.android.internal.telephony.CommandException: GENERIC_FAILURE
01-01 08:00:13.751 645 645 D SIMRecords: onRecordLoaded 2 requested: true
01-01 08:00:13.760 645 881 D SubscriptionController: asked for default subId=-1
01-01 08:00:13.773 257 587 D AT : AT< +COPS: 0,0,"CHINA MOBILE",7
01-01 08:00:13.773 257 587 D AT : AT< +COPS: 0,1,"CMCC",7
01-01 08:00:13.774 257 587 D AT : AT< +COPS: 0,2,"46000",7
01-01 08:00:13.774 257 587 D AT : AT< OK
01-01 08:00:13.774 257 585 D RIL : onRequest: DATA_REGISTRATION_STATE
01-01 08:00:13.774 257 585 D RIL : now sState is 4
01-01 08:00:13.774 257 585 D RIL : requestRegistrationState
01-01 08:00:13.774 257 585 D AT : AT> AT+CEREG?
01-01 08:00:13.774 645 784 D RILJ : < OPERATOR {CHINA MOBILE, CMCC, 46000}
01-01 08:00:13.796 257 587 D AT : AT< +CEREG: 2,1,"2496","0DE92643",7
01-01 08:00:13.796 257 587 D AT : AT< OK
01-01 08:00:13.796 257 585 D RIL : parseRegistrationState. Parsing: +CEREG: 2,1,"2496","0DE92643",7
01-01 08:00:13.796 257 585 D RIL : requestRegistrationState ending
01-01 08:00:13.796 257 585 D RIL : onRequest: VOICE_REGISTRATION_STATE
01-01 08:00:13.796 257 585 D RIL : now sState is 4
01-01 08:00:13.796 257 585 D RIL : requestRegistrationState
01-01 08:00:13.796 257 585 D AT : AT> AT+CREG?
01-01 08:00:13.796 645 784 D RILJ : < DATA_REGISTRATION_STATE {1, 2496, de92643, 14, null, null, null}
01-01 08:00:13.796 645 645 E SST : error parsing GprsRegistrationState: java.lang.NumberFormatException: null
01-01 08:00:13.797 645 645 D SST : handlPollStateResultMessage: GsmSST setDataRegState=0 regState=1 dataRadioTechnology=14
01-01 08:00:13.809 645 827 D SubscriptionController: Sub Controller not ready
01-01 08:00:13.809 645 881 D SubscriptionController: Sub Controller not ready
01-01 08:00:13.817 257 587 D AT : AT< +CREG: 2,1,"0000","0DE92643",7
01-01 08:00:13.818 257 587 D AT : AT< OK
01-01 08:00:13.818 257 585 D RIL : parseRegistrationState. Parsing: +CREG: 2,1,"0000","0DE92643",7
01-01 08:00:13.818 257 585 D RIL : requestRegistrationState ending
01-01 08:00:13.818 257 585 D RIL : onRequest: QUERY_NETWORK_SELECTION_MODE
01-01 08:00:13.818 257 585 D RIL : now sState is 4
01-01 08:00:13.818 257 585 D AT : AT> AT+COPS?
01-01 08:00:13.818 645 784 D RILJ : < VOICE_REGISTRATION_STATE {1, 0, de92643, 14, null, null, null, null, null, null, null, null, null, null, null}
01-01 08:00:13.823 645 881 D SubscriptionController: Sub Controller not ready
01-01 08:00:13.827 645 881 D SubscriptionController: Sub Controller not ready
01-01 08:00:13.839 257 587 D AT : AT< +COPS: 0,2,"46000",7
01-01 08:00:13.840 257 587 D AT : AT< OK
01-01 08:00:13.840 257 585 D RIL : onRequest: SIM_OPEN_CHANNEL
01-01 08:00:13.840 257 585 D RIL : now sState is 4
01-01 08:00:13.840 257 585 D RIL : Request not supported. Tech: 0x20
01-01 08:00:13.840 257 585 D RIL : SIM_OPEN_CHANNEL not supported
01-01 08:00:13.840 257 585 D RIL : onRequest: SIM_IO
01-01 08:00:13.840 257 585 D RIL : now sState is 4
01-01 08:00:13.840 257 585 D AT : AT> AT+CRSM=192,28439,0,0,15,,"3F007FFF"
01-01 08:00:13.840 645 784 D RILJ : < QUERY_NETWORK_SELECTION_MODE {0}
01-01 08:00:13.840 645 645 D SST : EVENT_POLL_STATE_NETWORK_SELECTION_MODE
01-01 08:00:13.840 645 784 D RilRequest: < RIL_REQUEST_SIM_OPEN_CHANNEL error: com.android.internal.telephony.CommandException: REQUEST_NOT_SUPPORTED ret=
01-01 08:00:13.841 645 645 D SST : Poll ServiceState done:oldSS= newSS= oldMaxDataCalls=1 mNewMaxDataCalls=1 oldReasonDataDenied=-1 mNewReasonDataDenied=-1
01-01 08:00:13.841 645 645 D SST : RAT switched Unknown -> LTE at cell 233383491
01-01 08:00:13.841 645 645 D GsmCdmaPhone: updatePhoneObject: radioTechnology=14
01-01 08:00:13.841 645 645 D TelephonyManager: setTelephonyProperty: success phoneId=0 property=gsm.network.type value: LTE propVal=LTE
01-01 08:00:13.841 645 645 D SST : pollStateDone: registering current mNitzUpdatedTime=false changing to false
01-01 08:00:13.842 645 645 D SIMRecords: getServiceProviderName: no brandOverride, providerName=null
01-01 08:00:13.842 645 645 D SIMRecords: getServiceProviderName: no brandOverride, providerName=null
01-01 08:00:13.842 645 645 D SST : updateSpnDisplay: changed sending intent rule=2 showPlmn='true' plmn='CHINA MOBILE' showSpn='false' spn='null' dataSpn='null' subId='-2'
01-01 08:00:13.844 645 645 D SubscriptionController: No valid subscription to store info
01-01 08:00:13.844 645 645 D SubscriptionController: notifySubscriptionInfoChanged:
01-01 08:00:13.847 6451019 D SubscriptionController: Sub Controller not ready
01-01 08:00:13.847 645 665 D SubscriptionController: Sub Controller not ready
01-01 08:00:13.852 645 645 D TelephonyManager: setTelephonyProperty: success phoneId=0 property=gsm.operator.alpha value: CHINA MOBILE propVal=CHINA MOBILE
01-01 08:00:13.852 645 665 D SubscriptionController: Sub Controller not ready
01-01 08:00:13.852 645 645 D TelephonyManager: setTelephonyProperty: success phoneId=0 property=gsm.operator.numeric value: 46000 propVal=46000
01-01 08:00:13.853 645 645 D SST : update mccmnc=46000 fromServiceState=true
01-01 08:00:13.853 6451019 D SubscriptionController: Sub Controller not ready
01-01 08:00:13.854 645 645 D SubscriptionController: - no sims, returning default phoneId=2147483647
01-01 08:00:13.856 6451019 D SubscriptionController: Sub Controller not ready
01-01 08:00:13.857 645 645 D TelephonyManager: setTelephonyProperty: success phoneId=0 property=gsm.operator.iso-country value: cn propVal=cn
01-01 08:00:13.862 257 587 D AT : AT< +CME ERROR: 53
01-01 08:00:13.862 257 585 D RIL : onRequest: SIM_IO
01-01 08:00:13.862 257 585 D RIL : now sState is 4
01-01 08:00:13.862 257 585 D AT : AT> AT+CRSM=192,28436,0,0,15,,"3F007FFF"
01-01 08:00:13.863 645 784 D RilRequest: < SIM_IO error: com.android.internal.telephony.CommandException: GENERIC_FAILURE ret=
01-01 08:00:13.866 645 645 D SST : pollStateDone: there are 2 unique offsets for iso-cc='cn testOneUniqueOffsetPath=false', do nothing
01-01 08:00:13.867 645 645 D SST : shouldFixTimeZoneNow: retVal=true iccCardExist=true operatorNumeric=46000 mcc=460 prevOperatorNumeric= prevMcc=461 needToFixTimeZone=false ltod=01-01 08:00:13.866
01-01 08:00:13.867 645 645 D SST : fixTimeZone zoneName='Asia/Shanghai' mZoneOffset=0 mZoneDst=false iso-cc='cn' iso-cc-idx=-3
01-01 08:00:13.867 645 645 D SST : fixTimeZone: using default TimeZone
01-01 08:00:13.867 645 645 D SST : fixTimeZone: zone != null zone.getID=Asia/Shanghai
01-01 08:00:13.867 645 645 D SST : setAndBroadcastNetworkSetTimeZone: setTimeZone=Asia/Shanghai
01-01 08:00:13.868 645 645 D SST : setAndBroadcastNetworkSetTimeZone: call alarm.setTimeZone and broadcast zoneId=Asia/Shanghai
01-01 08:00:13.868 645 645 D TelephonyManager: setTelephonyProperty: success phoneId=0 property=gsm.operator.isroaming value: false propVal=false
01-01 08:00:13.869 645 645 D SST : Broadcasting ServiceState : 0 0 voice home data home CHINA MOBILE CMCC 46000 CHINA MOBILE CMCC 46000LTE LTE CSS not supported -1 -1 RoamInd=-1 DefRoamInd=-1 EmergOnly=false IsDataRoamingFromRegistration=false IsUsingCarrierAggregation=false
01-01 08:00:13.869 645 645 D DefaultPhoneNotifier: nofityServiceState: mRegistry=com.android.internal.telephony.ITelephonyRegistry$Stub$Proxy@47790e5 ss=0 0 voice home data home CHINA MOBILE CMCC 46000 CHINA MOBILE CMCC 46000LTE LTE CSS not supported -1 -1 RoamInd=-1 DefRoamInd=-1 EmergOnly=false IsDataRoamingFromRegistration=false IsUsingCarrierAggregation=false sender=Handler (com.android.internal.telephony.GsmCdmaPhone) {b35692d} phondId=0 subId=-2
01-01 08:00:13.870 645 645 D SubscriptionController: - subId invalid
01-01 08:00:13.870 511 956 D TelephonyManager: getSimState: default sim:-1, sim state for slotIdx=0 is 5, return state as unknown
01-01 08:00:13.872 645 665 D SubscriptionController: - no sims, returning default phoneId=2147483647
01-01 08:00:13.873 645 645 D SST : notifyDataRegStateRilRadioTechnologyChanged: drs=0 rat=14
01-01 08:00:13.873 645 645 D TelephonyManager: setTelephonyProperty: success phoneId=0 property=gsm.network.type value: LTE propVal=LTE
01-01 08:00:13.874 645 645 D DCT : get all active apn types
01-01 08:00:13.874 645 645 D SST : getCellLocation(): X good mCellLoc=
01-01 08:00:13.874 511 536 D TelephonyRegistry: notifyCellLocationForSubscriber: subId=-2 cellLocation=Bundle
01-01 08:00:13.875 645 645 D UiccCard: Error in SIM access with exceptioncom.android.internal.telephony.CommandException: REQUEST_NOT_SUPPORTED
01-01 08:00:13.875 645 645 D GsmCdmaPhone: phoneObjectUpdater: newVoiceRadioTech=14
01-01 08:00:13.876 645 645 D GsmCdmaPhone: phoneObjectUpdater: volteReplacementRat=0
01-01 08:00:13.876 645 645 D GsmCdmaPhone: phoneObjectUpdater: No change ignore, newVoiceRadioTech=14 mActivePhone=GSM
01-01 08:00:13.876 645 645 D GsmCdmaPhone: Event EVENT_REGISTERED_TO_NETWORK Received
01-01 08:00:13.876 645 645 D SST : SubscriptionListener.onSubscriptionInfoChanged
01-01 08:00:13.876 645 645 E Phone : getStoredVoiceMessageCount: invalid subId -2
01-01 08:00:13.877 645 645 D GsmCdmaPhone: updateVoiceMail countVoiceMessages = 0 subId -2
01-01 08:00:13.877 645 645 E Phone : setVoiceMessageCount in sharedPreference: invalid subId -2
01-01 08:00:13.877 645 645 D DCT : SubscriptionListener.onSubscriptionInfoChanged
01-01 08:00:13.878 645 645 D SubscriptionController: Sub Controller not ready
01-01 08:00:13.884 257 587 D AT : AT< +CME ERROR: 53
01-01 08:00:13.884 645 784 D RilRequest: < SIM_IO error: com.android.internal.telephony.CommandException: GENERIC_FAILURE ret=
01-01 08:00:13.897 645 645 D SubscriptionController: Sub Controller not ready
01-01 08:00:13.898 645 645 D DCT : onDataConnectionAttached
01-01 08:00:13.899 645 645 D DCT : isDataAllowed: No - SIM not loaded
01-01 08:00:13.920 645 645 D DCT : setupDataOnConnectableApns: dataAttached emergency: hipri: supl: mms: dun: fota: ims: default: cbs:
01-01 08:00:13.920 645 645 D DCT : setupDataOnConnectableApns: nwTypeChanged emergency: hipri: supl: mms: dun: fota: ims: default: cbs:
01-01 08:00:13.920 645 645 D UiccPkcs15: handleMessage: 201
01-01 08:00:13.920 645 645 D UiccPkcs15: error: com.android.internal.telephony.CommandException: REQUEST_NOT_SUPPORTED
01-01 08:00:13.925 6451019 D SubscriptionController: Sub Controller not ready
01-01 08:00:13.925 6451019 D SubscriptionController: - subId=1 subList=null subInfo=null
01-01 08:00:13.925 645 665 D SubscriptionController: Sub Controller not ready
01-01 08:00:13.925 645 665 D SubscriptionController: - subId=1 subList=null subInfo=null
01-01 08:00:13.928 6451019 D SubscriptionController: Sub Controller not ready
01-01 08:00:13.928 6451019 D SubscriptionController: - subId=1 subList=null subInfo=null
01-01 08:00:13.929 645 665 D SubscriptionController: Sub Controller not ready
01-01 08:00:13.929 645 665 D SubscriptionController: - subId=1 subList=null subInfo=null
01-01 08:00:13.932 645 645 D UiccPkcs15: handleMessage: 1
01-01 08:00:13.932 645 645 D UiccPkcs15: select pkcs15 failed: com.android.internal.telephony.CommandException: REQUEST_NOT_SUPPORTED
01-01 08:00:13.932 645 645 D RILJ : > iccIO: SIM_IO 0xc0 0x6f18path: 3F007FFF,0,0,15 aid: null
01-01 08:00:13.933 645 645 D SIMRecords: onRecordLoaded 2 requested: true
01-01 08:00:13.933 645 645 D SIMRecords: Invalid or missing EF
01-01 08:00:13.933 645 645 D SIMRecords: onRecordLoaded 1 requested: true
01-01 08:00:13.933 257 585 D RIL : onRequest: SIM_IO
01-01 08:00:13.933 257 585 D RIL : now sState is 4
01-01 08:00:13.933 257 585 D AT : AT> AT+CRSM=192,28440,0,0,15,,"3F007FFF"
01-01 08:00:13.954 257 587 D AT : AT< +CME ERROR: 53
01-01 08:00:13.955 645 784 D RilRequest: < SIM_IO error: com.android.internal.telephony.CommandException: GENERIC_FAILURE ret=
01-01 08:00:13.955 645 645 D SIMRecords: No SPN loaded in either CHPS or 3GPP
01-01 08:00:13.955 645 645 D SIMRecords: onRecordLoaded 0 requested: true
01-01 08:00:13.955 645 645 D SIMRecords: record load complete
01-01 08:00:13.955 645 645 D SIMRecords: Not using EF LI/EF PL
01-01 08:00:13.955 645 645 D SIMRecords: EF_CFIS and EF_CFF not valid. callForwardingEnabled=-1
01-01 08:00:13.955 645 645 D SIMRecords: onAllRecordsLoaded set 'gsm.sim.operator.numeric' to operator='46007'
01-01 08:00:13.955 645 645 D SIMRecords: update icc_operator_numeric=46007
01-01 08:00:13.956 645 645 D TelephonyManager: setTelephonyProperty: success phoneId=0 property=gsm.sim.operator.numeric value: 46007 propVal=46007
01-01 08:00:13.956 645 645 D SubscriptionController: + mcc/mnc:460/7 subId:-1
01-01 08:00:13.960 645 645 D SubscriptionController: notifySubscriptionInfoChanged:
01-01 08:00:13.962 645 645 D SIMRecords: onAllRecordsLoaded set mcc imsi
01-01 08:00:13.962 645 645 D TelephonyManager: setTelephonyProperty: success phoneId=0 property=gsm.sim.operator.iso-country value: cn propVal=cn
01-01 08:00:13.963 6451019 D SubscriptionController: Sub Controller not ready
01-01 08:00:13.963 645 881 D SubscriptionController: Sub Controller not ready
01-01 08:00:13.964 645 645 D SubscriptionController: Sub Controller not ready
01-01 08:00:13.965 6451359 D SubscriptionController: Sub Controller not ready
01-01 08:00:13.967 645 645 D SubscriptionController: Sub Controller not ready
01-01 08:00:13.971 645 881 D SubscriptionController: Sub Controller not ready
01-01 08:00:13.982 645 665 D SubscriptionController: Sub Controller not ready
01-01 08:00:13.986 645 645 D SST : SubscriptionListener.onSubscriptionInfoChanged
01-01 08:00:13.986 645 645 D DCT : SubscriptionListener.onSubscriptionInfoChanged
01-01 08:00:13.986 645 645 D CAT : CatService: handleMessage
01-01 08:00:13.986 645 645 D GsmCdmaPhone: updateCurrentCarrierInProvider: mSubId = -2 currentDds = 1 operatorNumeric = 46007
01-01 08:00:13.987 645 645 D IccCardProxy: operator=46007 mPhoneId=0
01-01 08:00:13.987 645 645 D TelephonyManager: setTelephonyProperty: success phoneId=0 property=gsm.sim.operator.numeric value: 46007 propVal=46007
01-01 08:00:13.987 645 645 D TelephonyManager: setTelephonyProperty: success phoneId=0 property=gsm.sim.operator.iso-country value: cn propVal=cn
01-01 08:00:13.987 645 645 D IccCardProxy: Sending intent ACTION_INTERNAL_SIM_STATE_CHANGED for mPhoneId : 0
01-01 08:00:13.988 645 645 D SST : EVENT_SIM_RECORDS_LOADED: what=16
01-01 08:00:13.988 645 645 D GsmCdmaPhone: updatePhoneObject: radioTechnology=14
01-01 08:00:13.988 645 645 D SST : getOtasp: otasp not needed for GSM
01-01 08:00:13.988 645 645 D SST : updateOtaspState: call notifyOtaspChanged old otaspMode=0 new otaspMode=3
01-01 08:00:13.989 645 645 D SIMRecords: getServiceProviderName: no brandOverride, providerName=null
01-01 08:00:13.989 645 645 D SIMRecords: getServiceProviderName: no brandOverride, providerName=null
01-01 08:00:13.995 6451359 D SubscriptionController: - no sims, returning default phoneId=2147483647
01-01 08:00:13.997 645 645 D SubscriptionInfoUpdater: +
01-01 08:00:13.997 645 645 D SubscriptionInfoUpdater: Action: android.intent.action.internal_sim_state_changed
01-01 08:00:13.997 645 645 D SubscriptionInfoUpdater: slotId: 0
01-01 08:00:13.997 645 645 D SubscriptionInfoUpdater: simStatus: LOADED
01-01 08:00:13.997 645 645 D SubscriptionInfoUpdater: -
01-01 08:00:13.997 645 645 D GsmCdmaPhone: phoneObjectUpdater: newVoiceRadioTech=14
01-01 08:00:13.998 645 645 D GsmCdmaPhone: phoneObjectUpdater: volteReplacementRat=0
01-01 08:00:13.998 645 645 D GsmCdmaPhone: phoneObjectUpdater: No change ignore, newVoiceRadioTech=14 mActivePhone=GSM
01-01 08:00:13.998 645 645 D SubscriptionInfoUpdater: handleSimStateLoadedInternal: slotId: 0
01-01 08:00:13.998 645 645 D SubscriptionInfoUpdater: onRecieve: IccID null
01-01 08:00:14.011 6451359 D SubscriptionController: Sub Controller not ready
01-01 08:00:14.012 6451359 D SubscriptionController: - subId=1 subList=null subInfo=null
01-01 08:00:14.018 6451359 D SubscriptionController: Sub Controller not ready
01-01 08:00:14.018 6451359 D SubscriptionController: - subId=1 subList=null subInfo=null
01-01 08:00:14.023 6451359 D SubscriptionController: asked for default subId=-1
01-01 08:00:14.220 6451359 D SubscriptionController: Sub Controller not ready
01-01 08:00:14.220 6451359 D SubscriptionController: - subId=1 subList=null subInfo=null
01-01 08:00:14.220 645 665 D SubscriptionController: Sub Controller not ready
01-01 08:00:14.220 645 881 D SubscriptionController: Sub Controller not ready
01-01 08:00:14.221 645 665 D SubscriptionController: - subId=1 subList=null subInfo=null
01-01 08:00:14.221 645 881 D SubscriptionController: - subId=1 subList=null subInfo=null
01-01 08:00:14.222 6451359 D SubscriptionController: Sub Controller not ready
01-01 08:00:14.222 645 665 D SubscriptionController: Sub Controller not ready
01-01 08:00:14.222 6451359 D SubscriptionController: - subId=1 subList=null subInfo=null
01-01 08:00:14.222 645 665 D SubscriptionController: - subId=1 subList=null subInfo=null
01-01 08:00:14.223 645 881 D SubscriptionController: Sub Controller not ready
01-01 08:00:14.223 645 881 D SubscriptionController: - subId=1 subList=null subInfo=null
01-01 08:00:32.988 645 645 D RILJ : > SIGNAL_STRENGTH
01-01 08:00:32.993 257 585 D RIL : onRequest: SIGNAL_STRENGTH
01-01 08:00:32.993 257 585 D RIL : now sState is 4
01-01 08:00:32.993 257 585 D AT : AT> AT+CSQ
01-01 08:00:33.016 257 587 D AT : AT< +CSQ: 31,99
01-01 08:00:33.016 257 587 D AT : AT< OK
01-01 08:00:33.016 257 585 I RIL : SignalStrength 31 BER: 99,280
01-01 08:00:33.018 645 784 D RILJ : < SIGNAL_STRENGTH SignalStrength: 31 99 -1 -1 -1 -1 -1 99 2147483647 2147483647 2147483647 2147483647 2147483647 gsm|lte
01-01 08:00:53.043 645 645 D RILJ : > SIGNAL_STRENGTH
01-01 08:00:53.048 257 585 D RIL : onRequest: SIGNAL_STRENGTH
01-01 08:00:53.048 257 585 D RIL : now sState is 4
01-01 08:00:53.048 257 585 D AT : AT> AT+CSQ
01-01 08:00:53.072 257 587 D AT : AT< +CSQ: 31,99
01-01 08:00:53.072 257 587 D AT : AT< OK
01-01 08:00:53.073 257 585 I RIL : SignalStrength 31 BER: 99,280
01-01 08:00:53.074 645 784 D RILJ : < SIGNAL_STRENGTH SignalStrength: 31 99 -1 -1 -1 -1 -1 99 2147483647 2147483647 2147483647 2147483647 2147483647 gsm|lte
没人吗 没人吗 不要沉啊 大神在不在 求助
页:
[1]