参考文档： 80-n9811-1_c_lte_cell_selection%2C_registration%2C_and_tau_overview.pdf 80-PG930-3_LTE Log Analysis and Debug Guide.pdf

关键字 //LTE： Processing service|System Scan|Acq req|LTE_CPHY_ACQ_REQ|Acq succeeded|Cell Identity|Cell is suitable|No Suitable|cell select|Serving Cell Meas|CSP: Camped|camped_ind|Service Ind

//SIM_AVAILABLE [  42/ 0/2]              QTRACE                   08:07:57.089             MMODE/STRM/High/CM       [          cmregprx.c   5008] CMREG->NAS: sub 1, Send SIM_AVAILABLE_REQ, gwl_subs_avail 1, mode_pref -1, session_type 2, nv_context 1 2 [  47/ 0/2]              QTRACE                   08:07:57.089             MM/LowFreq/High/REG      [         reg_state.c  11639] DS: SUB 1 =REG= CM_SIM_AVAILABLE_REQ mode_pref:-1, session_type:2 nv_index:1 2 [  47/ 0/2]              QTRACE                   08:07:57.095             MM/LowFreq/High/REG      [          reg_send.c   1045] DS: SUB 1 =REG= CM_SIM_AVAILABLE_CNF 2

[  42/ 0/2]              QTRACE                   08:07:57.095             MMODE/STRM/High/CM       [          cmregprx.c  14766] NAS->CMREG: sub 1 stk 0, SIM_AVAILABLE_CNF

//CM_SERVICE_REQ [  47/ 0/2]              QTRACE                   08:07:57.107             MM/LowFreq/High/REG      [         reg_state.c   2968] DS: SUB 1 =REG= CM_SERVICE_REQ Scan Scope type=1 network_selection_mode 0 Additional_info=0 RAT Enabled BM = 0x220, BST BM = 0x220  2 [  47/ 1/2]              QTRACE                   08:07:57.107             MM/HighFreq/High/REG     [          reg_mode.c   2455] DS: SUB 1 =REG= Rat priority list num_items = 2 2 [  47/ 1/2]              QTRACE                   08:07:57.107             MM/HighFreq/High/REG     [          reg_mode.c   2459] DS: SUB 1 =REG= sys_mode = 9 2 [  47/ 1/2]              QTRACE                   08:07:57.107             MM/HighFreq/High/REG     [          reg_mode.c   2459] DS: SUB 1 =REG= sys_mode = 5 2

[  47/ 1/2]              QTRACE                   08:07:57.107             MM/HighFreq/High/REG     [         reg_state.c   4160] DS: SUB 1 =REG= CM_SERVICE_REQ - AUTOMATIC type=2 2 [  47/ 1/2]              QTRACE                   08:07:57.108             MM/HighFreq/High/REG     [           reg_sim.c   3674] DS: SUB 1 =REG= EPS RPLMN(460-0) 2 [  47/ 1/2]              QTRACE                   08:07:57.108             MM/HighFreq/High/REG     [            reg_nv.c   1296] DS: SUB 1 =REG= Read RPLMNACT 64 0 from cache 2

//MMR_REG_REQ [  47/ 0/2]              QTRACE                   08:07:57.108             MM/LowFreq/High/REG      [          reg_send.c   2163] DS: SUB 1 =REG= MMR_REG_REQ PLMN(460-0) RAT(0-GSM,1-W,2-LTE,3-TDS,4-NR5G) = 2 trans_id 1,  scan_scope=1  2

[  47/ 0/2]              QTRACE                   08:07:57.108226          MM/LowFreq/High/MM       [        emm_rrc_if.c    646] DS: SUB 1 EMM: Sent LTE_RRC_SERVICE_REQ scan_scope 1, new_scan 1, use timer 0, timer value 0 ecall_pending 0 ims_emc_pending 0 RAT Enabled BM = 0x220  2

//LTE： Processing service|System Scan|Acq req|LTE_CPHY_ACQ_REQ|Acq succeeded|Cell Identity|Cell is suitable|No Suitable|cell select|Serving Cell Meas|CSP: Camped|camped_ind|Service Ind

Frequency Scan Using Frequency Scan, the UE selects the frequency/EARFCN for  camping.   There are two types of frequency scan:    （1）System scan, also known as List Frequency scan (similar to Acq DB scan)      Upper layers shall provide list of EARFCNs, requested bandwidth, and Duplex mode to L1    （2）Band scan, also known as Full Frequency scan      Upper layers shall provide band index and the allowed set of bandwidths to L1

//Service Request from NAS：

[  13/ 0/2]              QTRACE                   08:07:57.108240          LRRC/HighFreq/High/LRRC  [       lte_rrc_csp.c  29915] CSP: Processing service request   2

//ML1 initiates System Scan request： [  13/ 0/2]              QTRACE                   08:07:57.118435          LRRC/HighFreq/High/LRRC  [       lte_rrc_csp.c   9205] CSP: Sent System Scan Request for 10 systems, disable_init_search_for_lfs is 02 [   9/ 1/2]              QTRACE                   08:07:57.118439          LML1/LowFreq/High/LML1   [   lte_ml1_sm_main.c   3065] SM: Sys Scan Req module 1 num_sys 10 min_sys 0 early_abort 0 sys[0] band 41 earfcn 40936 bw 100  2 //System Scan Result [0xB18E]                 LOG                      08:07:57.713021          LTE ML1 System Scan Results Length:  180             2 [  13/ 0/2]              QTRACE                   08:07:57.713079          LRRC/HighFreq/High/LRRC  [       lte_rrc_csp.c  38441] CSP: System Scan Cnf returned 10 systems  2

[  13/ 0/2]              QTRACE                   08:07:57.713156          LRRC/HighFreq/High/LRRC  [       lte_rrc_csp.c  38761] CSP: detected system number: 10 2

//ACQ: [  13/ 0/2]              QTRACE                   08:07:57.713176          LRRC/HighFreq/High/LRRC  [       lte_rrc_csp.c   9956] CSP: Acq requested on earfcn 40936, bw 100 2 [  13/ 0/2]              QTRACE                   08:07:57.713202          LRRC/HighFreq/High/LRRC  [       lte_rrc_llc.c   2010] Sent Acq Request 2 [  13/ 0/2]              QTRACE                   08:07:57.713215          LRRC/HighFreq/High/LRRC  [       lte_rrc_csp.c  20344] CSP: Starting acquisition on next freq 2 [  13/ 0/2]              QTRACE                   08:07:57.815276          LRRC/HighFreq/High/LRRC  [       lte_rrc_llc.c   2010] Sent Acq Request2 [   9/ 1/2]              QTRACE                   08:07:57.815852          LML1/LowFreq/High/LML1   [   lte_ml1_mgr_stm.c   4198] LTE_CPHY_ACQ_REQ Trans id 3 Band 41

earfcn 40936 rest_cfg 0 # black_cells 0 acq_type 1 acq_cell_id 259,num earfcn 12 [  13/ 0/2]              QTRACE                   08:07:57.893229          LRRC/HighFreq/High/LRRC  [       lte_rrc_csp.c  40109] CSP: Acq succeeded on physical cell ID 259 on earfcn 40936, MIB SFN 402

//Cell is suitable [  13/ 0/2]              QTRACE                   08:07:57.909648          LRRC/HighFreq/High/LRRC  [       lte_rrc_csp.c  42998] CSP: Cell Identity = 174982600 2 [  13/ 0/2]              QTRACE                   08:07:57.909671          LRRC/HighFreq/High/LRRC  [       lte_rrc_csp.c  25249] CSP: Cell is suitable 2

//cell select [  13/ 0/2]              QTRACE                   08:07:57.909681          LRRC/HighFreq/High/LRRC  [       lte_rrc_csp.c  43525] CSP: Proceeding to cell select request 2 [  13/ 0/2]              QTRACE                   08:07:57.909701          LRRC/HighFreq/High/LRRC  [       lte_rrc_csp.c  10122] CSP: Sent Cell Selection Request 2[   9/ 1/2]              QTRACE                   08:07:57.912989          LML1/LowFreq/High/LML1   [        lte_ml1_md.c   6396] Serving Cell Meas: (40936, 259) LL RSRP (used=rx1 rx0 -106.2953 rx1 -99.2149) use primary RX: 0 2 [   9/ 1/2]              QTRACE                   08:07:57.912991          LML1/LowFreq/High/LML1   [        lte_ml1_md.c   6417] Serving Cell Meas: LL RSRQ (used=rx1 rx0 41.7046 rx1 48.7850), serv cell offset 183500 2 [   9/ 1/2]              QTRACE                   08:07:57.912992          LML1/LowFreq/High/LML1   [        lte_ml1_md.c   6431] Serving Cell Meas: LL RSSI (comb -128.0000 rx0 -128.0000 rx1 -128.0000) inst RSSI (rx0 -128.0000 rx1 -128.0000) 2 [  13/ 0/2]              QTRACE                   08:07:57.913037          LRRC/HighFreq/High/LRRC  [       lte_rrc_csp.c  45067] CSP: Received Cell Select Cnf 2

[  13/ 0/2]              QTRACE                   08:07:57.913039          LRRC/HighFreq/High/LRRC  [       lte_rrc_csp.c  45240] CSP: Cell select req is success 2

//camped: [  13/ 0/2]              QTRACE                   08:07:57.964592          LRRC/HighFreq/High/LRRC  [       lte_rrc_csp.c  42377] CSP: Proceeding to camped 2 [  13/ 0/2]              QTRACE                   08:07:57.971683          LRRC/HighFreq/High/LRRC  [       lte_rrc_csp.c  37490] CSP: Camped after cell selection 2 [  13/ 0/2]              QTRACE                   08:07:57.971683          LRRC/HighFreq/High/LRRC  [       lte_rrc_csp.c  37549] CSP: Camped on physical cell ID 259 on earfcn 409362  [  13/ 0/2]              QTRACE                   08:07:57.971760          LRRC/HighFreq/High/LRRC  [       lte_rrc_sib.c  18879] Received camped_ind for phy_cell_id = 259 & freq = 40936 2

[  13/ 0/2]              QTRACE                   08:07:57.971838          LRRC/HighFreq/High/LRRC  [       lte_rrc_cep.c   8430] RRC CEP: Received Camped indication 2 [  13/ 0/2]              QTRACE                   08:07:57.971862          LRRC/HighFreq/High/LRRC  [       lte_rrc_csp.c  10431] CSP: Sent RRC synch Camped Ind 2 [  13/ 0/2]              QTRACE                   08:07:57.971911          LRRC/HighFreq/High/LRRC  [       lte_rrc_csp.c  10706] CSP: Camped PLMN: 2

//Service Ind [  13/ 0/2]              QTRACE                   08:07:57.971921          LRRC/HighFreq/High/LRRC  [       lte_rrc_csp.c  10465] CSP: Sending RRC synch Pre Service Ind 2 //mmcp Service Ind [  42/ 0/2]              QTRACE                   08:07:57.973688          MMODE/STRM/High/CM       [            cmtask.c  10803] MSGR RXD: LTE_RRC_SERVICE_IND, sub 1 2

[  47/ 0/2]              QTRACE                   08:07:57.973715          MM/LowFreq/High/MM       [   emm_rrc_handler.c   3602] DS: SUB 1 =EMM= RRC_SERVICE_IND -PLMN (460 - 0), TAC 37250  SRV REQ pending 16973825,   state = 1  sub_state = 2 2 [  47/ 1/2]              QTRACE                   08:07:57.973716          MM/HighFreq/High/MM      [   emm_rrc_handler.c   3614] DS: SUB 1 =EMM= RRC_SERVICE_IND - RRC active 0, Cell ID 174982600, IMS EMC spt 0 Div Duplex 2, Detach Reqd 0CSG Id = -1,  Hybrid cell = 0,  shared num_plmn 1 2 [  47/ 1/2]              QTRACE                   08:07:57.973718          MM/HighFreq/High/MM      [   emm_rrc_handler.c   3625] DS: SUB 1 =EMM= RRC_SERVICE_IND - blind_redir_after_csfb 0, search done 1 fake cell = 0 lte 3gpp release ver 0XC0  ACQ Status = 0tau_pending_gw_attach 0, radio_cap_update_req 0 2

[  13/ 0/2]              QTRACE                   08:07:57.974081          LRRC/HighFreq/High/LRRC  [   lte_rrc_csg_asf.c   3950] CSG_ASF: Processing Service Ind 2

//PDN_CONNECT and Attach [  47/ 1/2]              QTRACE                   08:08:23.710737          MM/HighFreq/High/SM      [         esm_utils.c   1288] DS: SUB 0 ESM: Sends NAS_EMM_DATA_REQ carrying PDN_CONNECTIVITY_REQUEST 1 [  47/ 1/2]              QTRACE                   08:08:23.710983          MM/HighFreq/High/MM      [    emm_update_lib.c   1906] DS: SUB 0 =EMM= Start Attach procedure, invalidate the security context 1 [0xB0EC]                 OTA LOG                  08:08:01.454728          LTE NAS EMM Plain OTA Incoming MessageAttach accept Msg        2 [  47/ 1/2]              QTRACE                   08:08:01.454789          MM/HighFreq/High/MM      [ lte_nas_msg_parse.c    715] DS: SUB 1 =NAS_MSG_LIB= ATTACH ACCEPT(SHDR = 2) decoding  PASSED(1)/FAILED(0) = 1 2[  47/ 1/2]              QTRACE                   08:08:01.457449          MM/HighFreq/High/MM      [   emm_esm_handler.c    763] DS: SUB 1 =EMM= Sending ATTACH_COMPLETE 2 [  47/ 1/2]              QTRACE                   08:08:01.457516          MM/HighFreq/High/MM      [   emm_reg_handler.c   4486] DS: SUB 1 =EMM= sent MMR_REG_CNF 2 [  47/ 1/2]              QTRACE                   08:08:01.457517          MM/HighFreq/High/MM      [   emm_reg_handler.c   4495] DS: SUB 1 =EMM= cause = 0 sim_state = 1 svc_capability = 3 plmn_forbidden = 0 svc_status = 2 reg_svc_domain = 3 active_rat = 2 ps_data_suspend = 0 2 [  47/ 1/2]              QTRACE                   08:08:01.458703          MM/HighFreq/High/REG     [         reg_state.c  13024] DS: SUB 1 =REG= CS_PS_SERVICE on VPLMN(460-0) 2