main log :
1: send sms --> 对应radio log 时间点
//接收者不支持RCS或者没sign up RCS server
//11-19 22:17:34.948163 8940 8940 V Bugle : ConversationData: No RCS because participant doesn't have RCS.
//11-19 22:17:34.953443 8940 8940 V Bugle : ConversationFragment{id:3}: conversation is RCS: false, RCS sending enabled: false
//11-19 22:17:34.972690 8940 9066 I BugleDataModel: SendMessageAction: Sending SMS message{id:5} in conversation{id:3}
//11-19 22:17:34.973968 8940 9066 I Bugle : SmsSender: sending message. dest: MSISDN{7866142836}, message: messageBody{Test7}, serviceCenter: null, requireDeliveryReport: false, messageUri: content://sms/2, subId: 3
//11-19 22:17:35.907012 8940 9066 V BugleDataModel: SendMessageAction: Updated SMS message{id:5} in telephony. uri: content://sms/2
//11-19 22:17:35.950243 8940 9066 I BugleDataModel: SentMessageProcessor: Done sending SMS message{id:5} conversation{id:3}, status: SUCCEEDED
2： receive sms--> 对应radio log 时间点
//11-19 22:17:36.283767 8940 8978 D BugleDataModel: ReceiveSmsMessageHelper: Inserted SMS message into telephony. inbox uri: content://sms/inbox/3, messageUri: content://sms/3, read: true, notified: true
//11-19 22:17:36.368195 8940 8978 I BugleDataModel: ReceiveSmsMessageHelper: Received SMS message. message{id:6}, conversation{id:3}, uri: content://sms/3
radio log:
1： ims setup ,connect
//11-19 21:59:52.938098 1543 1543 I ImsManager: Creating ImsService
//11-19 21:59:54.395566 751 857 I AT : [0] AT> AT+EDRETRY=1,"ims" (RIL_CMD_READER_4 tid:530865886448)
//11-19 21:59:54.396437 1170 1170 D DCT : [0]apnSetting: [ApnSettingV5] TFWAP, 2032, 310260, wap.tracfone, , http://TF.mmsmvno.com/mms/wapenc, , , , -1, default | mms | supl, IPV6, IP, true, 0, 0, 0, false, 0, 0, 0, 1440, gid, DEFF, false, 0, 0, 0
//11-19 21:59:54.420915 1170 1458 D MtkDC-1 : getApnType: apnType = default
//11-19 21:59:54.443210 751 857 I AT : [0] AT> AT+EAPNACT=1,"ims","ims",0 (RIL_CMD_READER_4 tid:530865886448)
//11-19 21:59:54.468850 1170 1372 D RILJ : [3849]< SETUP_DATA_CALL {.status = NONE, .suggestedRetryTime = -1, .cid = 201, .active = 2, .type = IPV6, .ifname = ccmni1, .addresses = 2607:FC20:91E1:D824:0000:0007:D72C:6C01/64, .dnses = FD00:976A:0000:0000:0000:0000:0000:0009 FD00:976A:0000:0000:0000:0000:0000:0010, .gateways = ::, .pcscf = FD00:976A:14FB:0031:0000:0000:0000:0001 FD00:976A:C006:CF07:0000:0000:0000:0001 FD00:976A:C206:0058:0000:0000:0000:0001, .mtu = 1500} [SUB0]
//11-19 21:59:54.469823 1170 1458 D MtkDC-2 : DcActivatingState: msg={what=EVENT_SETUP_DATA_CONNECTION_DONE when=0 obj={mTag=2 mApnContext={mApnType=ims mState=CONNECTING mWaitingApns={[[ApnSettingV5] IMS, 2039, 310260, ims, , , , , , -1, ims, IPV4V6, IPV4V6, true, 0, 524287, 0, false, 0, 0, 0, 1440, gid, DEFF, false, 524287, 0, 0]} mApnSetting={[ApnSettingV5] IMS, 2039, 310260, ims, , , , , , -1, ims, IPV4V6, IPV4V6, true, 0, 524287, 0, false, 0, 0, 0, 1440, gid, DEFF, false, 524287, 0, 0} mReason=dataEnabled mDataEnabled=true mDependencyMet=true} mWifiApns={[]} mProfileId=2 mRat=14 mUnmeteredUseOnly=false mOnCompletedMsg={what=0x42000 when=-24s227ms obj=Pair{{mApnType=ims mState=CONNECTING mWaitingApns={[[ApnSettingV5] IMS, 2039, 310260, ims, , , , , , -1, ims, IPV4V6, IPV4V6, true, 0, 524287, 0, false, 0, 0, 0, 1440, gid, DEFF, false, 524287, 0, 0]} mApnSetting={[ApnSettingV5] IMS, 2039, 310260, ims, , , , , , -1, ims, IPV4V6, IPV4V6, true, 0, 524287, 0, false, 0, 0, 0, 1440, gid, DEFF,...
//11-19 21:59:54.469952 1170 1458 D MtkDC-2 : onSetupConnectionCompleted received successful DataCallResponse
//11-19 21:59:54.470029 1170 1458 D MtkDC-2 : setConnectionRat newRat: 1 mRat: 1 reason: data call response
//11-19 21:59:54.556594 1170 1458 D MtkDC-2 : getApnType: mApnContexts.size() = 1
//11-19 21:59:54.557629 1170 1458 D MtkDC-2 : getApnType: apnType = ims
//11-19 21:59:54.984207 1170 1170 D DCT : [0]onDataSetupComplete: success apn=ims
//11-19 21:59:54.990560 1170 1170 D MtkGsmCdmaPhone: [MtkGsmCdmaPhone] getDataConnectionState apnType=ims ret=CONNECTED
//11-19 21:59:55.009606 1170 1170 D DCT : [0]onDataSetupComplete: SETUP complete type=ims, reason:connected
//11-19 22:17:35.110314 1543 1564 D MtkImsSmsImpl: [0] mPhoneId:0,is3GPP2Format:false,format:
2: send sms
//11-19 22:17:35.113127 1543 1564 D ImsService: sendSms, token 1, messageRef 0
//11-19 22:17:35.120733 1543 1564 D IMS_RILA: [3775]> RIL_REQUEST_IMS_SEND_SMS_EX [SUB0]
//11-19 22:17:35.133282 751 751 D RILC : sendImsSmsEx: serial 127
//11-19 22:17:35.137528 751 838 D RtcGsmSmsCtrl: [0] smsc=null, type=01, pdu=01000A818766418263000005D4F29C7E03
//11-19 22:17:35.137603 751 838 D RtcGsmSmsCtrl: [0] Don't need status report
//11-19 22:17:35.138636 751 863 D RmcGsmSmsBase: [0] pdu len=36
//11-19 22:17:35.138670 751 863 D RmcGsmSmsBase: [0] pdu 0001000A818766418263000005D4F29C7E03
//11-19 22:17:35.138688 751 863 D RmcGsmSmsBase: [0] smsSubmitPduCheck, pdu_len=36
//11-19 22:17:35.138707 751 863 D RmcGsmSmsBase: [0] SMSC address len =0
//11-19 22:17:35.138724 751 863 D RmcGsmSmsBase: [0] First Octet =1, off=1
//11-19 22:17:35.138740 751 863 D RmcGsmSmsBase: [0] DA length =10, off=3
//11-19 22:17:35.138756 751 863 D RmcGsmSmsBase: [0] pid =0, off=10
//11-19 22:17:35.138775 751 863 D RmcGsmSmsBase: [0] smsPidCheck, pid=0
//11-19 22:17:35.138791 751 863 D RmcGsmSmsBase: [0] dcs =0, off=11
//11-19 22:17:35.138807 751 863 D RmcGsmSmsBase: [0] smsDcsCheck, dcs=0
//11-19 22:17:35.138823 751 863 D RmcGsmSmsBase: [0] udl=5, off=12
//11-19 22:17:35.138839 751 863 D RmcGsmSmsBase: [0] user_data_len=5
//11-19 22:17:35.138855 751 863 D RmcGsmSmsBase: [0] smsPackPdu pass
//11-19 22:17:35.138893 751 863 I AT : [0] AT> AT+CMGS=*** (RIL_CMD_NRT_8, tid:530859644144)
//11-19 22:17:35.637014 751 863 D RmcGsmSmsRequest: [0] message reference number: 53
//11-19 22:17:35.689688 1543 1574 D IMS_RILA: [3775]< RIL_REQUEST_IMS_SEND_SMS_EX { mMessageRef = 53, mErrorCode = 0, mAckPdu = } [SUB0]
//11-19 22:17:35.689703 1543 1543 D ImsService: handleMessage() : SMS send complete, messageRef: 53
//11-19 22:17:35.844727 1170 1170 D SMSDispatcher: message reference number : 53
3: receive sms
//11-19 22:17:35.921438 751 841 D RfxMclStatusMgr: setValue() slot(0) key = RFX_STATUS_KEY_GSM_INBOUND_SMS_TYPE, old = [0], new = [1],is_force = false, is_default = false
//11-19 22:17:35.921498 751 841 D RmcGsmSmsBase: [0] smsDeliverPduCheck, pdu_len=64
//11-19 22:17:35.921524 751 841 D RmcGsmSmsBase: [0] SMSC address len =7
//11-19 22:17:35.921691 751 841 D RmcGsmSmsBase: [0] rild_sms_deliver_pdu_check pass
//11-19 22:17:35.926095 751 838 D RILC : newSmsInd
//11-19 22:17:35.936229 1170 1372 D RILJ : Unsol response received; Sending ack to ril.cpp [SUB0]
//11-19 22:17:35.936350 1170 1372 D RILJ : [UNSL]< UNSOL_RESPONSE_NEW_SMS [SUB0]
//11-19 22:17:35.937978 1170 1443 D MtkGsmInboundSmsHandler-0: IdleState.processMessage:1
//11-19 22:17:35.938029 1170 1443 D MtkGsmInboundSmsHandler-0: Idle state processing message type 1
//11-19 22:17:35.941895 1170 1443 D MtkGsmInboundSmsHandler-0: acquired wakelock, leaving Idle state
//11-19 22:17:35.941934 1170 1443 D MtkGsmInboundSmsHandler-0: entering Delivering state
//11-19 22:17:35.942073 1170 1443 D MtkGsmInboundSmsHandler-0: DeliveringState.processMessage:1
//11-19 22:17:35.992323 1170 1443 D MtkGsmInboundSmsHandler-0: URI of new row -> content://sms/raw/28
//11-19 22:17:35.995763 1170 1443 D RILJ : [4190]> SMS_ACKNOWLEDGE success = true cause = 0 [SUB0]
//11-19 22:17:35.996354 751 751 D RILC : acknowledgeLastIncomingGsmSms: serial 542
//11-19 22:17:35.998179 751 855 I AT : [0] AT> AT+CNMA=1, 2, "0000" (RIL_CMD_READER_3 tid:530867967216)
//11-19 22:17:36.018016 1170 1443 D MtkGsmInboundSmsHandler-0: DeliveringState.processMessage:2
//11-19 22:17:36.019282 751 838 D RILC : acknowledgeLastIncomingGsmSmsResponse: serial 542
//11-19 22:17:36.019677 1170 1372 D RILJ : [4190]< SMS_ACKNOWLEDGE [SUB0]
联系客服
