4G模块ECM拨号失败求助

godblessjs · 发表于 2020-9-10 08:39:12

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=/init  mtdparts=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 [SUB0]
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 : [UNSL]< UNSOL_RIL_CONNECTED {12} [SUB0]
01-01 08:00:10.352 645 645 D RILJ : [3649]> GET_HARDWARE_CONFIG [SUB0]
01-01 08:00:10.352 645 784 D RILJ : [3648]> RADIO_POWER off [SUB0]
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 : [3650]> RIL_REQUEST_CDMA_SET_SUBSCRIPTION_SOURCE : 0 [SUB0]
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 [SUB0]
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 : [3651]> RIL_REQUEST_SET_CELL_INFO_LIST_RATE [SUB0]
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 : [UNSL]< UNSOL_RESPONSE_RADIO_STATE_CHANGED RADIO_UNAVAILABLE [SUB0]
01-01 08:00:10.359 645 784 D RilRequest: [3649]< GET_HARDWARE_CONFIG error: com.android.internal.telephony.CommandException: RADIO_NOT_AVAILABLE ret=
01-01 08:00:10.359 645 784 D RilRequest: [3648]< RADIO_POWER error: com.android.internal.telephony.CommandException: RADIO_NOT_AVAILABLE ret=
01-01 08:00:10.359 645 784 D RilRequest: [3650]< 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: [3651]< 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: [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 : [3652]> RIL_REQUEST_START_LCE [SUB0]
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: [3652]< 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 [SUB0]
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 : [3653]> SIGNAL_STRENGTH [SUB0]
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: [3653]< SIGNAL_STRENGTH error: com.android.internal.telephony.CommandException: RADIO_NOT_AVAILABLE ret=
01-01 08:00:10.524 645 645 D DCT    : [0]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: [getPhoneId] 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    : [0]update sub = -2
01-01 08:00:10.534 645 645 D DCT    : [0]update(): Active DDS, register for all events now!
01-01 08:00:10.536 645 645 D GsmCdmaPhone: [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    : [0]initApnContexts: E
01-01 08:00:10.542 645 645 D DCT    : [0]initApnContexts: skipping unknown type=1
01-01 08:00:10.542 645 645 D DCT    : [0]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    : [0]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    : [0]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    : [0]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    : [0]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    : [0]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    : [0]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    : [0]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    : [0]initApnContexts: skipping unknown type=7
01-01 08:00:10.543 645 645 D DCT    : [0]initApnContexts: skipping unknown type=9
01-01 08:00:10.543 645 645 D DCT    : [0]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: [getActiveSubInfoList] Sub Controller not ready
01-01 08:00:10.548 645 665 D SubscriptionController: [getPhoneId] 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: [getPhoneId] 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] 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: [getPhoneId]- 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: [getPhoneId]- 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[0]: 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 : [3654]> RIL_REQUEST_SET_TTY_MODE : 0 [SUB0]
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: [3654]< 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: [getPhoneId] 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 : [3655]> GET_CURRENT_CALLS [SUB0]
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: [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: [3655]< 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=[1 1 voice home data home null null null null null null  Unknown Unknown CSS not supported 0 0 RoamInd=0 DefRoamInd=0 EmergOnly=false IsDataRoamingFromRegistration=false IsUsingCarrierAggregation=false] newSS=[1 1 voice home data home null null null null null null  Unknown Unknown CSS not supported -1 -1 RoamInd=-1 DefRoamInd=-1 EmergOnly=false IsDataRoamingFromRegistration=false IsUsingCarrierAggregation=false] 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: [setPlmnSpn] 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: [getActiveSubInfoList] 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 null  Unknown 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 null  Unknown 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: [getPhoneId]- no sims, returning default phoneId=2147483647
01-01 08:00:10.791 645 645 D SubscriptionController: [getSlotId]- 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=[1 1 voice home data home null null null null null null  Unknown Unknown CSS not supported -1 -1 RoamInd=-1 DefRoamInd=-1 EmergOnly=false IsDataRoamingFromRegistration=false IsUsingCarrierAggregation=false] newSS=[1 1 voice home data home null null null null null null  Unknown Unknown CSS not supported -1 -1 RoamInd=-1 DefRoamInd=-1 EmergOnly=false IsDataRoamingFromRegistration=false IsUsingCarrierAggregation=false] oldMaxDataCalls=1 mNewMaxDataCalls=1 oldReasonDataDenied=-1 mNewReasonDataDenied=-1
01-01 08:00:10.798 645 645 D DCT    : [0]onRadioOffOrNotAvailable: is off and clean up all connections
01-01 08:00:10.798 645 645 D DCT    : [0]cleanUpAllConnections: tearDown=false reason=radioTurnedOff
01-01 08:00:10.798 645 645 D GsmCdmaPhone: [GsmCdmaPhone] getDataConnectionState apnType=default ret=DISCONNECTED
01-01 08:00:10.801 645 645 D DCT    : [0]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: [GsmCdmaPhone] getDataConnectionState apnType=mms ret=DISCONNECTED
01-01 08:00:10.803 645 645 D DCT    : [0]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: [GsmCdmaPhone] getDataConnectionState apnType=cbs ret=DISCONNECTED
01-01 08:00:10.805 645 645 D DCT    : [0]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: [GsmCdmaPhone] getDataConnectionState apnType=fota ret=DISCONNECTED
01-01 08:00:10.807 645 645 D DCT    : [0]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: [GsmCdmaPhone] getDataConnectionState apnType=supl ret=DISCONNECTED
01-01 08:00:10.811 645 645 D DCT    : [0]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: [GsmCdmaPhone] getDataConnectionState apnType=emergency ret=DISCONNECTED
01-01 08:00:10.813 645 645 D DCT    : [0]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: [GsmCdmaPhone] getDataConnectionState apnType=dun ret=DISCONNECTED
01-01 08:00:10.816 645 645 D DCT    : [0]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: [GsmCdmaPhone] getDataConnectionState apnType=hipri ret=DISCONNECTED
01-01 08:00:10.818 645 645 D DCT    : [0]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: [GsmCdmaPhone] getDataConnectionState apnType=ims ret=DISCONNECTED
01-01 08:00:10.825 645 645 D DCT    : [0]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    : [0]stopNetStatPoll
01-01 08:00:10.825 645 645 D DCT    : [0]cleanUpConnection: mDisconnectPendingCount = 0
01-01 08:00:10.827 645 645 D DCT    : [0]isDataAllowed: No - Not attached - SIM not loaded
01-01 08:00:10.850 645 645 D DCT    : [0]onDataConnectionDetached: stop polling and notify detached
01-01 08:00:10.850 645 645 D DCT    : [0]stopNetStatPoll
01-01 08:00:10.851 645 645 D DCT    : [0]notifyDataConnection: reason=dataDetached
01-01 08:00:10.852 645 645 D DCT    : [0]isDataAllowed: No - Not attached - SIM not loaded
01-01 08:00:10.870 645 645 D DCT    : [0]onRoamingOff
01-01 08:00:10.871 645 645 D DCT    : [0]setDataProfilesAsNeeded mSetDataProfileStatus: 0
01-01 08:00:10.871 645 645 D DCT    : [0]notifyDataConnection: reason=roamingOff
01-01 08:00:10.872 645 645 D DCT    : [0]isDataAllowed: No - Not attached - SIM not loaded
01-01 08:00:10.892 645 645 D DCT    : [0]setupDataOnConnectableApns: nwTypeChanged emergency:[state=IDLE,enabled=false] hipri:[state=IDLE,enabled=false] supl:[state=IDLE,enabled=false] mms:[state=IDLE,enabled=false] dun:[state=IDLE,enabled=false] fota:[state=IDLE,enabled=false] ims:[state=IDLE,enabled=false] default:[state=IDLE,enabled=false] cbs:[state=IDLE,enabled=false]
01-01 08:00:10.892 645 645 D SubscriptionInfoUpdater: [Receiver]+
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: [Receiver]-
01-01 08:00:10.895 645 645 D TelephonyNetworkFactory[0]: onSubIdChange -1->-2
01-01 08:00:10.895 645 645 D TelephonyNetworkFactory[0]: 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 : [3656]> RIL_REQUEST_QUERY_TTY_MODE [SUB0]
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: [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: [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    : [0]SubscriptionListener.onSubscriptionInfoChanged
01-01 08:00:10.902 645 645 D PhoneSwitcher: evaluating due to subChanged default 0->1 phone[0] 0->-2
01-01 08:00:10.902 645 784 D RilRequest: [3656]< 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: [getActiveSubInfoList] Sub Controller not ready
01-01 08:00:10.903 645 645 D SubscriptionController: [getActiveSubInfoList] 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[0]: 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: [getActiveSubInfoList] Sub Controller not ready
01-01 08:00:11.210 645 662 D SubscriptionController: [getActiveSubInfoList] Sub Controller not ready
01-01 08:00:11.212 645 662 D SubscriptionController: [getActiveSubInfoList] Sub Controller not ready
01-01 08:00:11.259 645 662 D SubscriptionController: [getPhoneId] asked for default subId=-1
01-01 08:00:11.280 645 662 D DCT    : [0]hasMatchedTetherApnSetting: APN=null
01-01 08:00:11.282 645 662 D DCT    : [0]hasMatchedTetherApnSetting: APN=null
01-01 08:00:11.324 645 662 D SubscriptionController: [getSlotId]- 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: [getSlotId]- 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: [getSlotId]- 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: [getActiveSubInfoList] 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 : [UNSL]< UNSOL_RESPONSE_RADIO_STATE_CHANGED RADIO_OFF [SUB0]
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 : [3657]> SCREEN_STATE: true [SUB0]
01-01 08:00:11.882 645 645 D RILJ : [3658]> RADIO_POWER on [SUB0]
01-01 08:00:11.883 645 784 D RILJ : [UNSL]< UNSOL_RESPONSE_SIM_STATUS_CHANGED [SUB0]
01-01 08:00:11.884 645 645 D RILJ : [3659]> OPERATOR [SUB0]
01-01 08:00:11.891 645 645 D RILJ : [3660]> DATA_REGISTRATION_STATE [SUB0]
01-01 08:00:11.893 645 645 D RILJ : [3661]> VOICE_REGISTRATION_STATE [SUB0]
01-01 08:00:11.894 645 645 D RILJ : [3662]> QUERY_NETWORK_SELECTION_MODE [SUB0]
01-01 08:00:11.895 645 645 D RILJ : [3663]> BASEBAND_VERSION [SUB0]
01-01 08:00:11.897 645 645 D RILJ : [3664]> GET_IMEI [SUB0]
01-01 08:00:11.903 257 587 D AT    : AT< OK
01-01 08:00:11.906 645 645 D RILJ : [3665]> GET_IMEISV [SUB0]
01-01 08:00:11.907 645 645 D RILJ : [3666]> RIL_REQUEST_GET_RADIO_CAPABILITY [SUB0]
01-01 08:00:11.908 645 645 D RILJ : [3667]> RIL_REQUEST_START_LCE [SUB0]
01-01 08:00:11.912 645 645 D DCT    : [0]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 : [3668]> GET_SIM_STATUS [SUB0]
01-01 08:00:11.994 645 665 D SubscriptionController: [getSlotId]- 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: [Receiver]+
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 645  1022 D SmsBroadcastUndelivered: scanning raw table for undelivered messages
01-01 08:00:12.091 645 645 D SubscriptionInfoUpdater: [Receiver]-
01-01 08:00:12.094 645  1022 D SmsBroadcastUndelivered: finished scanning raw table in 66 ms
01-01 08:00:12.096 645 645 D SubscriptionInfoUpdater: [Receiver]+
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: [Receiver]-
01-01 08:00:12.134 645 881 D SubscriptionController: [getActiveSubInfoList] Sub Controller not ready
01-01 08:00:12.146 645 881 D SubscriptionController: [getSlotId]- 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,[1]ppp,[0]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 645  1019 D SubscriptionController: [getSlotId]- 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 : [UNSL]< UNSOL_VOICE_RADIO_TECH_CHANGED {3} [SUB0]
01-01 08:00:12.566 645 784 D RILJ : [UNSL]< UNSOL_RESPONSE_RADIO_STATE_CHANGED RADIO_ON [SUB0]
01-01 08:00:12.566 645 784 D RILJ : [UNSL]< UNSOL_RESPONSE_SIM_STATUS_CHANGED [SUB0]
01-01 08:00:12.566 645 645 D GsmCdmaPhone: [GsmCdmaPhone] EVENT_VOICE_RADIO_TECH_CHANGED: newVoiceTech=3
01-01 08:00:12.566 645 645 D GsmCdmaPhone: [GsmCdmaPhone] phoneObjectUpdater: newVoiceRadioTech=3
01-01 08:00:12.566 645 645 D GsmCdmaPhone: [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 : [3669]> OPERATOR [SUB0]
01-01 08:00:12.568 645 645 D RILJ : [3670]> DATA_REGISTRATION_STATE [SUB0]
01-01 08:00:12.568 645 645 D RILJ : [3671]> VOICE_REGISTRATION_STATE [SUB0]
01-01 08:00:12.569 645 645 D RILJ : [3672]> QUERY_NETWORK_SELECTION_MODE [SUB0]
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 : [3673]> GET_SIM_STATUS [SUB0]
01-01 08:00:12.571 645 645 D RILJ : [3674]> GET_CURRENT_CALLS [SUB0]
01-01 08:00:12.572 645 645 D RILJ : [3675]> RIL_REQUEST_IMS_REGISTRATION_STATE [SUB0]
01-01 08:00:12.573 645 645 D RILJ : [3676]> RIL_REQUEST_CDMA_GET_SUBSCRIPTION_SOURCE [SUB0]
01-01 08:00:12.574 645 645 D GsmCdmaPhone: [GsmCdmaPhone] Event EVENT_RADIO_ON Received
01-01 08:00:12.574 645 645 D RILJ : [3677]> RIL_REQUEST_VOICE_RADIO_TECH [SUB0]
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 : [3678]> GET_SIM_STATUS [SUB0]
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 : [UNSL]< UNSOL_RESPONSE_SIM_STATUS_CHANGED [SUB0]
01-01 08:00:12.602 645 784 D RILJ : [UNSL]< UNSOL_RESPONSE_RADIO_STATE_CHANGED RADIO_ON [SUB0]
01-01 08:00:12.602 645 784 D RILJ : [UNSL]< UNSOL_RESPONSE_SIM_STATUS_CHANGED [SUB0]
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 : [3679]> GET_SIM_STATUS [SUB0]
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 : [3680]> GET_SIM_STATUS [SUB0]
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 : [UNSL]< UNSOL_RESPONSE_VOICE_NETWORK_STATE_CHANGED [SUB0]
01-01 08:00:12.658 645 784 D RILJ : [3657]< SCREEN_STATE  [SUB0]
01-01 08:00:12.658 645 784 D RILJ : [3658]< RADIO_POWER  [SUB0]
01-01 08:00:12.658 645 645 D RILJ : [3681]> OPERATOR [SUB0]
01-01 08:00:12.662 645 645 D RILJ : [3682]> DATA_REGISTRATION_STATE [SUB0]
01-01 08:00:12.663 645 645 D RILJ : [3683]> VOICE_REGISTRATION_STATE [SUB0]
01-01 08:00:12.666 645 645 D RILJ : [3684]> QUERY_NETWORK_SELECTION_MODE [SUB0]
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 : [3659]< OPERATOR {CHINA MOBILE, CMCC, 46000} [SUB0]
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 : [3660]< DATA_REGISTRATION_STATE {1, 2496, de92643, 14, null, null, null} [SUB0]
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 : [3661]< VOICE_REGISTRATION_STATE {1, 0, de92643, 14, null, null, null, null, null, null, null, null, null, null, null} [SUB0]
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 : [3662]< QUERY_NETWORK_SELECTION_MODE {0} [SUB0]
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 : [3663]< BASEBAND_VERSION ML302-DNPM_MBRH2S02 [SUB0]
01-01 08:00:12.770 645 645 D GsmCdmaPhone: [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 : [3664]< GET_IMEI  [SUB0]
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: [3665]< 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 [SUB0]
01-01 08:00:12.792 645 784 D RILJ : [3666]< RIL_REQUEST_GET_RADIO_CAPABILITY {mPhoneId = 0 mVersion=1 mSession=0 mPhase=0 mRadioAccessFamily=1 mLogicModemId= mStatus=1} [SUB0]
01-01 08:00:12.793 645 784 D RilRequest: [3667]< 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    : [Card type discovery]: 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 : [3668]< 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} [SUB0]
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 : [3669]< OPERATOR {CHINA MOBILE, CMCC, 46000} [SUB0]
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 : [3670]< DATA_REGISTRATION_STATE {1, 2496, de92643, 14, null, null, null} [SUB0]
01-01 08:00:12.901 645 645 D SIMRecords: [SIMRecords] setting0 mMncLength-1
01-01 08:00:12.901 645 645 D SIMRecords: [SIMRecords] SIMRecords: onRadioOffOrNotAvailable set 'gsm.sim.operator.numeric' to operator=null
01-01 08:00:12.901 645 645 D SIMRecords: [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: [SIMRecords] getOperatorNumeric: IMSI == null
01-01 08:00:12.904 645 645 D SIMRecords: [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 : [3685]> QUERY_FACILITY_LOCK [FD 7 null] [SUB0]
01-01 08:00:12.906 645 645 D RILJ : [3686]> QUERY_FACILITY_LOCK [SC 7 null] [SUB0]
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 : [3687]> RIL_REQUEST_SIM_OPEN_CHANNEL [SUB0]
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 : [3671]< VOICE_REGISTRATION_STATE {1, 0, de92643, 14, null, null, null, null, null, null, null, null, null, null, null} [SUB0]
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 : [3672]< QUERY_NETWORK_SELECTION_MODE {0} [SUB0]
01-01 08:00:12.944 645 645 D SIMRecords: [SIMRecords] fetchSimRecords 0
01-01 08:00:12.944 645 645 D RILJ : [3688]> getIMSI: GET_IMSI aid: null [SUB0]
01-01 08:00:12.945 645 645 D RILJ : [3689]> iccIO: SIM_IO 0xc0 0x2fe2  path: 3F00,0,0,15 aid: null [SUB0]
01-01 08:00:12.946 645 645 D RILJ : [3690]> iccIO: SIM_IO 0xc0 0x6f40  path: 3F007FFF,0,0,15 aid: null [SUB0]
01-01 08:00:12.946 645 645 D RILJ : [3691]> iccIO: SIM_IO 0xc0 0x6fc9  path: 3F007FFF,0,0,15 aid: null [SUB0]
01-01 08:00:12.947 645 645 D RILJ : [3692]> iccIO: SIM_IO 0xc0 0x6fad  path: 3F007FFF,0,0,15 aid: null [SUB0]
01-01 08:00:12.947 645 645 D RILJ : [3693]> iccIO: SIM_IO 0xc0 0x6fca  path: 3F007FFF,0,0,15 aid: null [SUB0]
01-01 08:00:12.948 645 645 D RILJ : [3694]> iccIO: SIM_IO 0xc0 0x6f11  path: 3F007FFF,0,0,15 aid: null [SUB0]
01-01 08:00:12.950 645 645 D RILJ : [3695]> iccIO: SIM_IO 0xc0 0x6fcb  path: 3F007FFF,0,0,15 aid: null [SUB0]
01-01 08:00:12.951 645 645 D RILJ : [3696]> iccIO: SIM_IO 0xc0 0x6f13  path: 3F007FFF,0,0,15 aid: null [SUB0]
01-01 08:00:12.952 645 645 D RILJ : [3697]> iccIO: SIM_IO 0xc0 0x6f46  path: 3F007FFF,0,0,15 aid: null [SUB0]
01-01 08:00:12.953 645 645 D RILJ : [3698]> iccIO: SIM_IO 0xc0 0x6fcd  path: 3F007FFF,0,0,15 aid: null [SUB0]
01-01 08:00:12.954 645 645 D RILJ : [3699]> iccIO: SIM_IO 0xc0 0x6fc5  path: 3F007FFF,0,0,15 aid: null [SUB0]
01-01 08:00:12.954 645 645 D RILJ : [3700]> iccIO: SIM_IO 0xc0 0x6f38  path: 3F007FFF,0,0,15 aid: null [SUB0]
01-01 08:00:12.955 645 645 D RILJ : [3701]> iccIO: SIM_IO 0xc0 0x6f16  path: 3F007FFF,0,0,15 aid: null [SUB0]
01-01 08:00:12.956 645  1019 D SubscriptionController: [getPhoneId]- no sims, returning default phoneId=2147483647
01-01 08:00:12.956 645 645 D RILJ : [3702]> iccIO: SIM_IO 0xc0 0x6f15  path: 3F007FFF,0,0,15 aid: null [SUB0]
01-01 08:00:12.957 645 645 D RILJ : [3703]> iccIO: SIM_IO 0xc0 0x6f3e  path: 3F007FFF,0,0,15 aid: null [SUB0]
01-01 08:00:12.957 645 645 D RILJ : [3704]> iccIO: SIM_IO 0xc0 0x6f3f  path: 3F007FFF,0,0,15 aid: null [SUB0]
01-01 08:00:12.959 645 645 D RILJ : [3705]> iccIO: SIM_IO 0xc0 0x6f60  path: 3F007FFF,0,0,15 aid: null [SUB0]
01-01 08:00:12.960 645 645 D RILJ : [3706]> iccIO: SIM_IO 0xc0 0x6f61  path: 3F007FFF,0,0,15 aid: null [SUB0]
01-01 08:00:12.961 645 645 D RILJ : [3707]> iccIO: SIM_IO 0xc0 0x6f62  path: 3F007FFF,0,0,15 aid: null [SUB0]
01-01 08:00:12.962 645 645 D RILJ : [3708]> iccIO: SIM_IO 0xc0 0x6fd9  path: 3F007FFF,0,0,15 aid: null [SUB0]
01-01 08:00:12.963 645 645 D RILJ : [3709]> iccIO: SIM_IO 0xc0 0x6f7b  path: 3F007FFF,0,0,15 aid: null [SUB0]
01-01 08:00:12.964 645 645 D RILJ : [3710]> iccIO: SIM_IO 0xc0 0x6f05  path: 3F007FFF,0,0,15 aid: null [SUB0]
01-01 08:00:12.965 645 645 D RILJ : [3711]> iccIO: SIM_IO 0xc0 0x2f05  path: 3F00,0,0,15 aid: null [SUB0]
01-01 08:00:12.965 645 645 D SIMRecords: [SIMRecords] fetchSimRecords 20 requested: true
01-01 08:00:12.966 645 645 D CAT    : CatService: handleMessage[8]
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 : [3712]> RIL_REQUEST_REPORT_STK_SERVICE_IS_RUNNING [SUB0]
01-01 08:00:12.967 645 645 D GsmCdmaPhone: [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[8]
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 : [3713]> OPERATOR [SUB0]
01-01 08:00:12.971 645 645 D RILJ : [3714]> DATA_REGISTRATION_STATE [SUB0]
01-01 08:00:12.973 645 645 D RILJ : [3715]> VOICE_REGISTRATION_STATE [SUB0]
01-01 08:00:12.974 645 881 D SubscriptionController: [getActiveSubInfoList] Sub Controller not ready
01-01 08:00:12.975 645 645 D RILJ : [3716]> QUERY_NETWORK_SELECTION_MODE [SUB0]
01-01 08:00:12.976 645 645 D SubscriptionInfoUpdater: [Receiver]+
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: [Receiver]-
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    : [Card type discovery]: 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 : [3673]< 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} [SUB0]
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: [getPhoneId]- 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[8]
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 : [3674]< GET_CURRENT_CALLS {} [SUB0]
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: [GsmCdmaCallTracker] update phone state, old=IDLE new=IDLE
01-01 08:00:13.001 645 784 D RilRequest: [3675]< 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 : [3676]< RIL_REQUEST_CDMA_GET_SUBSCRIPTION_SOURCE {0} [SUB0]
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 : [3677]< RIL_REQUEST_VOICE_RADIO_TECH {3} [SUB0]
01-01 08:00:13.001 645 645 D GsmCdmaPhone: [GsmCdmaPhone] EVENT_REQUEST_VOICE_RADIO_TECH_DONE: newVoiceTech=3
01-01 08:00:13.002 645 645 D GsmCdmaPhone: [GsmCdmaPhone] phoneObjectUpdater: newVoiceRadioTech=3
01-01 08:00:13.002 645 645 D GsmCdmaPhone: [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    : [Card type discovery]: 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 : [3678]< 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} [SUB0]
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[8]
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    : [Card type discovery]: 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 : [3679]< 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} [SUB0]
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[8]
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    : [Card type discovery]: 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 : [3680]< 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} [SUB0]
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[8]
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 : [3681]< OPERATOR {CHINA MOBILE, CMCC, 46000} [SUB0]
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 : [3682]< DATA_REGISTRATION_STATE {1, 2496, de92643, 14, null, null, null} [SUB0]
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 : [3683]< VOICE_REGISTRATION_STATE {1, 0, de92643, 14, null, null, null, null, null, null, null, null, null, null, null} [SUB0]
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 : [3684]< QUERY_NETWORK_SELECTION_MODE {0} [SUB0]
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: [3685]< 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: [3686]< 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: [3687]< 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 : [3717]> RIL_REQUEST_SIM_OPEN_CHANNEL [SUB0]
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 : [3688]< GET_IMSI  [SUB0]
01-01 08:00:13.250 645 645 D SIMRecords: [SIMRecords] IMSI: mMncLength=-1
01-01 08:00:13.250 645 645 D SIMRecords: [SIMRecords] IMSI: 460078[LJ_mh3Ih1a2vPAHaDcgRljfEKjY]
01-01 08:00:13.250 645 645 D SIMRecords: [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: [Receiver]+
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: [Receiver]-
01-01 08:00:13.257 645  1019 D SubscriptionController: [getActiveSubInfoList] Sub Controller not ready
01-01 08:00:13.258 645 881 D SubscriptionController: [getPhoneId]- no sims, returning default phoneId=2147483647
01-01 08:00:13.267 645 645 D SubscriptionController: [getPhoneId] 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: [3689]< SIM_IO error: com.android.internal.telephony.CommandException: GENERIC_FAILURE ret=
01-01 08:00:13.274 645 645 D SIMRecords: [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: [3690]< SIM_IO error: com.android.internal.telephony.CommandException: GENERIC_FAILURE ret=
01-01 08:00:13.293 645 645 D GsmCdmaPhone: [GsmCdmaPhone] broadcastEmergencyCallStateChanges = false
01-01 08:00:13.294 645 645 E GsmCdmaPhone: [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: [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: [3691]< SIM_IO error: com.android.internal.telephony.CommandException: GENERIC_FAILURE ret=
01-01 08:00:13.320 645 645 D SIMRecords: [SIMRecords] getSIMOperatorNumeric: bad mncLength
01-01 08:00:13.320 645 645 D RILJ : [3718]> iccIO: SIM_IO 0xc0 0x6f17  path: 3F007FFF,0,0,15 aid: null [SUB0]
01-01 08:00:13.321 645 645 D SIMRecords: [SIMRecords] onRecordLoaded 18 requested: true
01-01 08:00:13.321 645 645 D SIMRecords: [SIMRecords] Invalid or missing EF[MSISDN]
01-01 08:00:13.321 645 645 D SIMRecords: [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: [3692]< SIM_IO error: com.android.internal.telephony.CommandException: GENERIC_FAILURE ret=
01-01 08:00:13.337 645 645 D SIMRecords: [SIMRecords] mccmncCode=460078
01-01 08:00:13.339 645 645 D SIMRecords: [SIMRecords] setting7 mMncLength=2
01-01 08:00:13.339 645 645 D SIMRecords: [SIMRecords] update mccmnc=46007
01-01 08:00:13.340 645 645 D SubscriptionController: [getPhoneId]- 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: [3693]< SIM_IO error: com.android.internal.telephony.CommandException: GENERIC_FAILURE ret=
01-01 08:00:13.379 645 645 D SIMRecords: [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: [3694]< SIM_IO error: com.android.internal.telephony.CommandException: GENERIC_FAILURE ret=
01-01 08:00:13.394 645 881 D DCT    : [0]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: [3695]< SIM_IO error: com.android.internal.telephony.CommandException: GENERIC_FAILURE ret=
01-01 08:00:13.416 645 881 D DCT    : [0]hasMatchedTetherApnSetting: APN=null
01-01 08:00:13.419 645 645 D SIMRecords: [SIMRecords] EF_MWIS : null
01-01 08:00:13.419 645 645 D SIMRecords: [SIMRecords] EVENT_GET_MWIS_DONE exception = com.android.internal.telephony.CommandException: GENERIC_FAILURE
01-01 08:00:13.419 645 645 D SIMRecords: [SIMRecords] onRecordLoaded 15 requested: true
01-01 08:00:13.419 645 645 D SIMRecords: [SIMRecords] EF_CPHS_MWI: null
01-01 08:00:13.419 645 645 D SIMRecords: [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: [SIMRecords] onRecordLoaded 14 requested: true
01-01 08:00:13.419 645 645 D SIMRecords: [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: [3696]< SIM_IO error: com.android.internal.telephony.CommandException: GENERIC_FAILURE ret=
01-01 08:00:13.427 645 645 D SIMRecords: [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: [3697]< SIM_IO error: com.android.internal.telephony.CommandException: GENERIC_FAILURE ret=
01-01 08:00:13.449 645 645 D RILJ : [3719]> iccIO: SIM_IO 0xc0 0x6f14  path: 3F007FFF,0,0,15 aid: null [SUB0]
01-01 08:00:13.449 645 645 D SIMRecords: [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: [3698]< SIM_IO error: com.android.internal.telephony.CommandException: GENERIC_FAILURE ret=
01-01 08:00:13.470 645 645 D SIMRecords: [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: [3699]< SIM_IO error: com.android.internal.telephony.CommandException: GENERIC_FAILURE ret=
01-01 08:00:13.491 645 645 D SIMRecords: [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: [3700]< SIM_IO error: com.android.internal.telephony.CommandException: GENERIC_FAILURE ret=
01-01 08:00:13.513 645 645 D SIMRecords: [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: [3701]< SIM_IO error: com.android.internal.telephony.CommandException: GENERIC_FAILURE ret=
01-01 08:00:13.534 645 645 D SIMRecords: [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: [3702]< SIM_IO error: com.android.internal.telephony.CommandException: GENERIC_FAILURE ret=
01-01 08:00:13.555 645 645 E SIMRecords: [SIMRecords] Exception in fetching EF_CSP data com.android.internal.telephony.CommandException: GENERIC_FAILURE
01-01 08:00:13.555 645 645 D SIMRecords: [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: [3703]< SIM_IO error: com.android.internal.telephony.CommandException: GENERIC_FAILURE ret=
01-01 08:00:13.577 645 645 E SIMRecords: [SIMRecords] Exception in get GID1 com.android.internal.telephony.CommandException: GENERIC_FAILURE
01-01 08:00:13.577 645 645 D SIMRecords: [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: [3704]< SIM_IO error: com.android.internal.telephony.CommandException: GENERIC_FAILURE ret=
01-01 08:00:13.599 645 645 E SIMRecords: [SIMRecords] Exception in get GID2 com.android.internal.telephony.CommandException: GENERIC_FAILURE
01-01 08:00:13.599 645 645 D SIMRecords: [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: [3705]< SIM_IO error: com.android.internal.telephony.CommandException: GENERIC_FAILURE ret=
01-01 08:00:13.620 645 645 E SIMRecords: [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: [3706]< SIM_IO error: com.android.internal.telephony.CommandException: GENERIC_FAILURE ret=
01-01 08:00:13.641 645 645 E SIMRecords: [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: [3707]< SIM_IO error: com.android.internal.telephony.CommandException: GENERIC_FAILURE ret=
01-01 08:00:13.664 645 645 E SIMRecords: [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: [3708]< SIM_IO error: com.android.internal.telephony.CommandException: GENERIC_FAILURE ret=
01-01 08:00:13.686 645 645 E SIMRecords: [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: [3709]< SIM_IO error: com.android.internal.telephony.CommandException: GENERIC_FAILURE ret=
01-01 08:00:13.707 645 645 E SIMRecords: [SIMRecords] Failed getting Forbidden PLMNs: com.android.internal.telephony.CommandException: GENERIC_FAILURE
01-01 08:00:13.707 645 645 D SIMRecords: [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: [3710]< SIM_IO error: com.android.internal.telephony.CommandException: GENERIC_FAILURE ret=
01-01 08:00:13.729 645 645 D SIMRecords: [SIMRecords] EF_LI LOADED
01-01 08:00:13.729 645 645 E SIMRecords: [SIMRecords] Record Load Exception: com.android.internal.telephony.CommandException: GENERIC_FAILURE
01-01 08:00:13.729 645 645 D SIMRecords: [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: [3711]< SIM_IO error: com.android.internal.telephony.CommandException: GENERIC_FAILURE ret=
01-01 08:00:13.750 645 784 D RILJ : [3712]< RIL_REQUEST_REPORT_STK_SERVICE_IS_RUNNING  [SUB0]
01-01 08:00:13.751 645 645 D SIMRecords: [SIMRecords] EF_PL LOADED
01-01 08:00:13.751 645 645 E SIMRecords: [SIMRecords] Record Load Exception: com.android.internal.telephony.CommandException: GENERIC_FAILURE
01-01 08:00:13.751 645 645 D SIMRecords: [SIMRecords] onRecordLoaded 2 requested: true
01-01 08:00:13.760 645 881 D SubscriptionController: [getPhoneId] 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 : [3713]< OPERATOR {CHINA MOBILE, CMCC, 46000} [SUB0]
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 : [3714]< DATA_REGISTRATION_STATE {1, 2496, de92643, 14, null, null, null} [SUB0]
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: [getActiveSubInfoList] Sub Controller not ready
01-01 08:00:13.809 645 881 D SubscriptionController: [getActiveSubInfoList] 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 : [3715]< VOICE_REGISTRATION_STATE {1, 0, de92643, 14, null, null, null, null, null, null, null, null, null, null, null} [SUB0]
01-01 08:00:13.823 645 881 D SubscriptionController: [getActiveSubInfoList] Sub Controller not ready
01-01 08:00:13.827 645 881 D SubscriptionController: [getActiveSubInfoList] 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 : [3716]< QUERY_NETWORK_SELECTION_MODE {0} [SUB0]
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: [3717]< 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=[1 1 voice home data home null null null null null null  Unknown Unknown CSS not supported -1 -1 RoamInd=-1 DefRoamInd=-1 EmergOnly=false IsDataRoamingFromRegistration=false IsUsingCarrierAggregation=false] newSS=[0 0 voice home data home CHINA MOBILE CMCC 46000 CHINA MOBILE CMCC 46000  LTE LTE CSS not supported -1 -1 RoamInd=-1 DefRoamInd=-1 EmergOnly=false IsDataRoamingFromRegistration=false IsUsingCarrierAggregation=false] 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: [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: [SIMRecords] getServiceProviderName: no brandOverride, providerName=null
01-01 08:00:13.842 645 645 D SIMRecords: [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: [setPlmnSpn] No valid subscription to store info
01-01 08:00:13.844 645 645 D SubscriptionController: notifySubscriptionInfoChanged:
01-01 08:00:13.847 645  1019 D SubscriptionController: [getActiveSubInfoList] Sub Controller not ready
01-01 08:00:13.847 645 665 D SubscriptionController: [getActiveSubInfoList] 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: [getActiveSubInfoList] 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 645  1019 D SubscriptionController: [getActiveSubInfoList] Sub Controller not ready
01-01 08:00:13.854 645 645 D SubscriptionController: [getPhoneId]- no sims, returning default phoneId=2147483647
01-01 08:00:13.856 645  1019 D SubscriptionController: [getActiveSubInfoList] 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: [3718]< 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 46000  LTE 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 46000  LTE 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: [getSlotId]- 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: [getPhoneId]- 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    : [0]get all active apn types
01-01 08:00:13.874 645 645 D SST    : getCellLocation(): X good mCellLoc=[0,233383491,-1]
01-01 08:00:13.874 511 536 D TelephonyRegistry: notifyCellLocationForSubscriber: subId=-2 cellLocation=Bundle[mParcelledData.dataSize=64]
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: [GsmCdmaPhone] phoneObjectUpdater: newVoiceRadioTech=14
01-01 08:00:13.876 645 645 D GsmCdmaPhone: [GsmCdmaPhone] phoneObjectUpdater: volteReplacementRat=0
01-01 08:00:13.876 645 645 D GsmCdmaPhone: [GsmCdmaPhone] phoneObjectUpdater: No change ignore, newVoiceRadioTech=14 mActivePhone=GSM
01-01 08:00:13.876 645 645 D GsmCdmaPhone: [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: [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    : [0]SubscriptionListener.onSubscriptionInfoChanged
01-01 08:00:13.878 645 645 D SubscriptionController: [getActiveSubInfoList] 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: [3719]< SIM_IO error: com.android.internal.telephony.CommandException: GENERIC_FAILURE ret=
01-01 08:00:13.897 645 645 D SubscriptionController: [getActiveSubInfoList] Sub Controller not ready
01-01 08:00:13.898 645 645 D DCT    : [0]onDataConnectionAttached
01-01 08:00:13.899 645 645 D DCT    : [0]isDataAllowed: No - SIM not loaded
01-01 08:00:13.920 645 645 D DCT    : [0]setupDataOnConnectableApns: dataAttached emergency:[state=IDLE,enabled=false] hipri:[state=IDLE,enabled=false] supl:[state=IDLE,enabled=false] mms:[state=IDLE,enabled=false] dun:[state=IDLE,enabled=false] fota:[state=IDLE,enabled=false] ims:[state=IDLE,enabled=false] default:[state=IDLE,enabled=false] cbs:[state=IDLE,enabled=false]
01-01 08:00:13.920 645 645 D DCT    : [0]setupDataOnConnectableApns: nwTypeChanged emergency:[state=IDLE,enabled=false] hipri:[state=IDLE,enabled=false] supl:[state=IDLE,enabled=false] mms:[state=IDLE,enabled=false] dun:[state=IDLE,enabled=false] fota:[state=IDLE,enabled=false] ims:[state=IDLE,enabled=false] default:[state=IDLE,enabled=false] cbs:[state=IDLE,enabled=false]
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 645  1019 D SubscriptionController: [getActiveSubInfoList] Sub Controller not ready
01-01 08:00:13.925 645  1019 D SubscriptionController: [getActiveSubInfoForSubscriber]- subId=1 subList=null subInfo=null
01-01 08:00:13.925 645 665 D SubscriptionController: [getActiveSubInfoList] Sub Controller not ready
01-01 08:00:13.925 645 665 D SubscriptionController: [getActiveSubInfoForSubscriber]- subId=1 subList=null subInfo=null
01-01 08:00:13.928 645  1019 D SubscriptionController: [getActiveSubInfoList] Sub Controller not ready
01-01 08:00:13.928 645  1019 D SubscriptionController: [getActiveSubInfoForSubscriber]- subId=1 subList=null subInfo=null
01-01 08:00:13.929 645 665 D SubscriptionController: [getActiveSubInfoList] Sub Controller not ready
01-01 08:00:13.929 645 665 D SubscriptionController: [getActiveSubInfoForSubscriber]- 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 : [3720]> iccIO: SIM_IO 0xc0 0x6f18  path: 3F007FFF,0,0,15 aid: null [SUB0]
01-01 08:00:13.933 645 645 D SIMRecords: [SIMRecords] onRecordLoaded 2 requested: true
01-01 08:00:13.933 645 645 D SIMRecords: [SIMRecords] Invalid or missing EF[MAILBOX]
01-01 08:00:13.933 645 645 D SIMRecords: [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: [3720]< SIM_IO error: com.android.internal.telephony.CommandException: GENERIC_FAILURE ret=
01-01 08:00:13.955 645 645 D SIMRecords: [SIMRecords] No SPN loaded in either CHPS or 3GPP
01-01 08:00:13.955 645 645 D SIMRecords: [SIMRecords] onRecordLoaded 0 requested: true
01-01 08:00:13.955 645 645 D SIMRecords: [SIMRecords] record load complete
01-01 08:00:13.955 645 645 D SIMRecords: [SIMRecords] Not using EF LI/EF PL
01-01 08:00:13.955 645 645 D SIMRecords: [SIMRecords] EF_CFIS and EF_CFF not valid. callForwardingEnabled=-1
01-01 08:00:13.955 645 645 D SIMRecords: [SIMRecords] onAllRecordsLoaded set 'gsm.sim.operator.numeric' to operator='46007'
01-01 08:00:13.955 645 645 D SIMRecords: [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: [setMccMnc]+ 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: [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 645  1019 D SubscriptionController: [getActiveSubInfoList] Sub Controller not ready
01-01 08:00:13.963 645 881 D SubscriptionController: [getActiveSubInfoList] Sub Controller not ready
01-01 08:00:13.964 645 645 D SubscriptionController: [getActiveSubInfoList] Sub Controller not ready
01-01 08:00:13.965 645  1359 D SubscriptionController: [getActiveSubInfoList] Sub Controller not ready
01-01 08:00:13.967 645 645 D SubscriptionController: [getActiveSubInfoList] Sub Controller not ready
01-01 08:00:13.971 645 881 D SubscriptionController: [getActiveSubInfoList] Sub Controller not ready
01-01 08:00:13.982 645 665 D SubscriptionController: [getActiveSubInfoList] 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    : [0]SubscriptionListener.onSubscriptionInfoChanged
01-01 08:00:13.986 645 645 D CAT    : CatService: handleMessage[20]
01-01 08:00:13.986 645 645 D GsmCdmaPhone: [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: [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: [SIMRecords] getServiceProviderName: no brandOverride, providerName=null
01-01 08:00:13.989 645 645 D SIMRecords: [SIMRecords] getServiceProviderName: no brandOverride, providerName=null
01-01 08:00:13.995 645  1359 D SubscriptionController: [getPhoneId]- no sims, returning default phoneId=2147483647
01-01 08:00:13.997 645 645 D SubscriptionInfoUpdater: [Receiver]+
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: [Receiver]-
01-01 08:00:13.997 645 645 D GsmCdmaPhone: [GsmCdmaPhone] phoneObjectUpdater: newVoiceRadioTech=14
01-01 08:00:13.998 645 645 D GsmCdmaPhone: [GsmCdmaPhone] phoneObjectUpdater: volteReplacementRat=0
01-01 08:00:13.998 645 645 D GsmCdmaPhone: [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 645  1359 D SubscriptionController: [getActiveSubInfoList] Sub Controller not ready
01-01 08:00:14.012 645  1359 D SubscriptionController: [getActiveSubInfoForSubscriber]- subId=1 subList=null subInfo=null
01-01 08:00:14.018 645  1359 D SubscriptionController: [getActiveSubInfoList] Sub Controller not ready
01-01 08:00:14.018 645  1359 D SubscriptionController: [getActiveSubInfoForSubscriber]- subId=1 subList=null subInfo=null
01-01 08:00:14.023 645  1359 D SubscriptionController: [getPhoneId] asked for default subId=-1
01-01 08:00:14.220 645  1359 D SubscriptionController: [getActiveSubInfoList] Sub Controller not ready
01-01 08:00:14.220 645  1359 D SubscriptionController: [getActiveSubInfoForSubscriber]- subId=1 subList=null subInfo=null
01-01 08:00:14.220 645 665 D SubscriptionController: [getActiveSubInfoList] Sub Controller not ready
01-01 08:00:14.220 645 881 D SubscriptionController: [getActiveSubInfoList] Sub Controller not ready
01-01 08:00:14.221 645 665 D SubscriptionController: [getActiveSubInfoForSubscriber]- subId=1 subList=null subInfo=null
01-01 08:00:14.221 645 881 D SubscriptionController: [getActiveSubInfoForSubscriber]- subId=1 subList=null subInfo=null
01-01 08:00:14.222 645  1359 D SubscriptionController: [getActiveSubInfoList] Sub Controller not ready
01-01 08:00:14.222 645 665 D SubscriptionController: [getActiveSubInfoList] Sub Controller not ready
01-01 08:00:14.222 645  1359 D SubscriptionController: [getActiveSubInfoForSubscriber]- subId=1 subList=null subInfo=null
01-01 08:00:14.222 645 665 D SubscriptionController: [getActiveSubInfoForSubscriber]- subId=1 subList=null subInfo=null
01-01 08:00:14.223 645 881 D SubscriptionController: [getActiveSubInfoList] Sub Controller not ready
01-01 08:00:14.223 645 881 D SubscriptionController: [getActiveSubInfoForSubscriber]- subId=1 subList=null subInfo=null
01-01 08:00:32.988 645 645 D RILJ : [3721]> SIGNAL_STRENGTH [SUB0]
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 : [3721]< SIGNAL_STRENGTH SignalStrength: 31 99 -1 -1 -1 -1 -1 99 2147483647 2147483647 2147483647 2147483647 2147483647 gsm|lte [SUB0]
01-01 08:00:53.043 645 645 D RILJ : [3722]> SIGNAL_STRENGTH [SUB0]
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 : [3722]< SIGNAL_STRENGTH SignalStrength: 31 99 -1 -1 -1 -1 -1 99 2147483647 2147483647 2147483647 2147483647 2147483647 gsm|lte [SUB0]

godblessjs · 发表于 2020-9-14 15:43:49

没人吗没人吗

godblessjs · 发表于 2020-9-21 14:19:12

不要沉啊大神在不在求助

[Android] 4G模块ECM拨号失败求助

【Android】 4G模块ECM拨号失败求助