Did not insert enb_s1ap_id_key 60132695886 to enb_ue_s1ap_key2mme_ueid_map enb_ue_s1ap_ue_id 3153742 mme_ue_s1ap_id 3
Hello i get this when i try to add a UE to the network every think is well configured i will also add the MME logs below
[MAC] [eNB 0][RAPROC] CC_id 0 Frame 470 Activating RAR generation in Frame 470, subframe 6 for process 0, rnti 3b42, state 1 [RRC] [FRAME 00471][eNB][MOD 00][RNTI 3b42] Decoding UL CCCH 57.5a.d.84.a7.56 (0x79baaf5f2543) [RRC] [FRAME 00471][eNB][MOD 00][RNTI 3b42] Accept new connection from UE random UE identity (0x754ad8a075000000) MME code 0 TMSI 0 cause 3 [MAC] UE 0 RNTI 3b42 adding LC 1 idx 0 to scheduling control (total 1) [MAC] UE 0 RNTI 3b42 adding LC 2 idx 1 to scheduling control (total 2) [MAC] Added physicalConfigDedicated 0x79ba48004b00 for 0.0 [RRC] [FRAME 00471][eNB][MOD 00][RNTI 3b42] [RAPROC] Logical Channel DL-CCCH, Generating RRCConnectionSetup (bytes 29) [RRC] [FRAME 00471][eNB][MOD 00][RNTI 3b42]CALLING RLC CONFIG SRB1 (rbid 1) [PDCP] add new uid is 0 3b42 [PDCP] [FRAME 00471][eNB][MOD 00][RNTI 3b42][SRB 01] Action ADD LCID 1 (SRB id 1) configured with SN size 5 bits and RLC AM [MAC] [eNB 0][RAPROC] CC_id 0 Frame 471, subframeP 6: Generating Msg4 with RRC Piggyback (RNTI 3b42) [RRC] [FRAME 00000][eNB][MOD 00][RNTI 3b42] [RAPROC] Logical Channel UL-DCCH, processing LTE_RRCConnectionSetupComplete from UE (SRB1 Active) [NAS] AttachRequest.c:39 EMM - attach_request len = 64 [NAS] UeNetworkCapability.c:46 decode_ue_network_capability len = 5 [NAS] UeNetworkCapability.c:63 uenetworkcapability decoded UMTS
[NAS] UeNetworkCapability.c:74 uenetworkcapability decoded GPRS
[NAS] UeNetworkCapability.c:82 uenetworkcapability decoded=6
[NAS] UeNetworkCapability.c:86 uenetworkcapability then decoded=6
[RRC] [FRAME 00000][eNB][MOD 00][RNTI 3b42] UE State = RRC_CONNECTED [S1AP] [eNB 0] Chose MME '(null)' (assoc_id 10) through selected PLMN Identity index 0 MCC 222 MNC 1 [S1AP] Found usable eNB_ue_s1ap_id: 0x6a94ec 6984940(10) [RRC] [eNB 0] Received S1AP_DOWNLINK_NAS: ue_initial_id 1, eNB_uMME_LogsMME_Logse_s1ap_id 6984940 [RRC] sent RRC_DCCH_DATA_REQ to TASK_PDCP_ENB [RRC] [eNB 0] Received S1AP_DOWNLINK_NAS: ue_initial_id 1, eNB_ue_s1ap_id 6984940 [RRC] sent RRC_DCCH_DATA_REQ to TASK_PDCP_ENB [RRC] [eNB 0] Received S1AP_DOWNLINK_NAS: ue_initial_id 1, eNB_ue_s1ap_id 6984940 [RRC] sent RRC_DCCH_DATA_REQ to TASK_PDCP_ENB [RRC] [eNB 0] Received S1AP_DOWNLINK_NAS: ue_initial_id 1, eNB_ue_s1ap_id 6984940 [RRC] sent RRC_DCCH_DATA_REQ to TASK_PDCP_ENB [RRC] [eNB 0] Received S1AP_DOWNLINK_NAS: ue_initial_id 1, eNB_ue_s1ap_id 6984940 [RRC] sent RRC_DCCH_DATA_REQ to TASK_PDCP_ENB [RRC] [FRAME 00000][eNB][MOD 00][RNTI 3b42] Logical Channel DL-DCCH, Generate RRCConnectionRelease (bytes 3) [RRC] sent RRC_DCCH_DATA_REQ to TASK_PDCP_ENB [GTPU] [0] Delete Released GTP tunnels for rnti: 3b42, but no tunnel exits [RRC] S1AP removed entry in hashtable s1ap_id2_s1ap_ids for eNB_ue_s1ap_id 6984940 [RRC] S1AP removed entry in hashtable initial_id2_s1ap_ids for ue_initial_id 1 [S1AP] Removed UE context eNB_ue_s1ap_id 6984940 [RRC] Removing UE 3b42 instance after UE_CONTEXT_RELEASE_Complete (ue_release_timer_rrc timeout) [RRC] [eNB 0] Removing UE RNTI 3b42 [RRC] Put UE 3b42 into freeList [PHY] add ue 15170 in free list, context flag: 1 [MAC] clean eNb uci_vars[0] UE 3b42 [MAC] clean eNb uci_vars[1] UE 3b42 [MAC] Removing UE 0 from Primary CC_id 0 (rnti 3b42) [PDCP] remove uid is 0/0 3b42 [PHY] remove RNTI 3b42$
MME logs
000753 Sun Mar 31 21:06:40 2024 7119E6FFD700 DEBUG S1AP tasks/s1ap/s1ap_mme.cpp :0116 S1AP ZMQ latency: 61. 000754 Sun Mar 31 21:06:40 2024 7119E6FFD700 INFO S1AP tasks/s1ap/s1ap_mme_nas_procedur:0122 Received S1AP INITIAL_UE_MESSAGE ENB_UE_S1AP_ID 3153742 assoc-id:11 000755 Sun Mar 31 21:06:40 2024 7119E6FFD700 INFO S1AP tasks/s1ap/s1ap_mme_nas_procedur:0145 New Initial UE message received with eNB UE S1AP ID: 3153742 assoc-id :11 000756 Sun Mar 31 21:06:40 2024 7119E6FFD700 DEBUG S1AP tasks/s1ap/s1ap_mme.cpp :0436 Num ue associated: 1 on assoc id:11 000757 Sun Mar 31 21:06:40 2024 7119E6FFD700 DEBUG S1AP tasks/s1ap/s1ap_mme_nas_procedur:0170 Creating new UE Ref on S1ap 000758 Sun Mar 31 21:06:40 2024 7119E6FFD700 DEBUG S1AP tasks/s1ap/s1ap_mme_nas_procedur:0232 S1AP_FIND_PROTOCOLIE_BY_ID 96: lte/gateway/c/core/oai/tasks/s1ap/s1ap_mme_nas_procedures.cpp 232: Optional ie is NULL 000759 Sun Mar 31 21:06:40 2024 7119E6FFD700 DEBUG S1AP tasks/s1ap/s1ap_mme_nas_procedur:0241 S1AP_FIND_PROTOCOLIE_BY_ID 127: lte/gateway/c/core/oai/tasks/s1ap/s1ap_mme_nas_procedures.cpp 241: Optional ie is NULL 000760 Sun Mar 31 21:06:40 2024 7119E6FFD700 DEBUG S1AP tasks/s1ap/s1ap_mme_nas_procedur:0248 S1AP_FIND_PROTOCOLIE_BY_ID 75: lte/gateway/c/core/oai/tasks/s1ap/s1ap_mme_nas_procedures.cpp 248: Optional ie is NULL 000761 Sun Mar 31 21:06:40 2024 7119E6FFD700 INFO S1AP tasks/s1ap/s1ap_mme_itti_messagi:0183 Sending Initial UE Message to MME_APP, enb_ue_s1ap_id : 3153742 000762 Sun Mar 31 21:06:40 2024 711A00FF9700 DEBUG MME-AP tasks/mme_app/mme_app_state_mana:0101 Inside get_state with read_from_db 0 000763 Sun Mar 31 21:06:40 2024 711A00FF9700 DEBUG MME-AP tasks/mme_app/mme_app_main.cpp :0090 MME APP ZMQ latency: 174. 000764 Sun Mar 31 21:06:40 2024 711A00FF9700 INFO MME-AP tasks/mme_app/mme_app_bearer.cpp:0639 Received MME_APP_INITIAL_UE_MESSAGE from S1AP 000765 Sun Mar 31 21:06:40 2024 711A00FF9700 DEBUG MME-AP tasks/mme_app/mme_app_bearer.cpp:0740 MME_APP_INITIAL_UE_MESSAGE from S1AP,without S-TMSI. 000766 Sun Mar 31 21:06:40 2024 711A00FF9700 DEBUG MME-AP tasks/mme_app/mme_app_bearer.cpp:0744 UE context doesn't exist -> create one 000767 Sun Mar 31 21:06:40 2024 711A00FF9700 DEBUG MME-AP tasks/mme_app/mme_app_bearer.cpp:0766 [0] Allocated new MME UE context and new (mme_ue_s1ap_id = 3) 000768 Sun Mar 31 21:06:40 2024 711A00FF9700 DEBUG MME-AP tasks/mme_app/mme_app_bearer.cpp:2243 [0] Sent MME_APP_S1AP_MME_UE_ID_NOTIFICATION to S1AP for (ue_id = 3) 000769 Sun Mar 31 21:06:40 2024 711A00FF9700 INFO MME-AP tasks/mme_app/mme_app_bearer.cpp:0596 [0] INITIAL_UE_MESSAGE RCVD mme_ue_s1ap_id = 3 enb_ue_s1ap_id = 3153742 000770 Sun Mar 31 21:06:40 2024 711A00FF9700 DEBUG MME-AP tasks/mme_app/mme_app_bearer.cpp:0598 Is S-TMSI Valid - (0) 000771 Sun Mar 31 21:06:40 2024 711A00FF9700 INFO MME-AP tasks/mme_app/mme_app_bearer.cpp:0603 [0] Sending NAS Establishment Indication to NAS for ue_id = 3 000772 Sun Mar 31 21:06:40 2024 711A00FF9700 INFO NAS-EM tasks/nas/emm/sap/emm_as.cpp :0183 EMMAS-SAP - Received primitive EMMAS_ESTABLISH_REQ (205) 000773 Sun Mar 31 21:06:40 2024 711A00FF9700 INFO NAS-EM tasks/nas/emm/sap/emm_as.cpp :0760 EMMAS-SAP - Received AS connection establish request 000774 Sun Mar 31 21:06:40 2024 711A00FF9700 INFO NAS-EM tasks/nas/emm/sap/emm_as.cpp :0821 EMMAS-SAP - Message Type = ATTACH_REQUEST(0x41) for (ue_id = 3) 000775 Sun Mar 31 21:06:40 2024 711A00FF9700 INFO NAS-EM tasks/nas/emm/sap/emm_recv.cpp :0201 EMMAS-SAP - Received Attach Request message for ue id 3 000776 Sun Mar 31 21:06:40 2024 711A00FF9700 INFO NAS-EM tasks/nas/emm/Attach.cpp :0224 ATTACH REQ (ue_id = 3) (IMSI = 222010100001120) 000777 Sun Mar 31 21:06:40 2024 711A00FF9700 INFO NAS-EM tasks/nas/emm/Attach.cpp :0239 EMM-PROC: ATTACH - EPS attach type = IMSI (1) 000778 Sun Mar 31 21:06:40 2024 711A00FF9700 DEBUG MME-AP tasks/mme_app/mme_app_state_mana:0101 Inside get_state with read_from_db 0 000779 Sun Mar 31 21:06:40 2024 711A00FF9700 WARNI MME-AP tasks/mme_app/mme_app_context.cp:0363 [222010100001120] mme_ue_s1ap_id not found for this IMSI 000780 Sun Mar 31 21:06:40 2024 711A00FF9700 NOTIC NAS-EM tasks/nas/emm/Attach.cpp :0557 EMM-PROC - Create EMM context ue_id = 3 000781 Sun Mar 31 21:06:40 2024 711A00FF9700 NOTIC NAS tasks/nas/emm/Attach.cpp :1119 Hit 3GPP TS 24_301R10_5_5_1_2_3__1 : EMM common procedure initiation during attach procedure 000782 Sun Mar 31 21:06:40 2024 711A00FF9700 DEBUG MME-AP tasks/mme_app/mme_app_state_mana:0101 Inside get_state with read_from_db 0 000783 Sun Mar 31 21:06:40 2024 711A00FF9700 ERROR MME-AP tasks/mme_app/mme_app_context.cp:0469 [222010100001120] Did not insert enb_s1ap_id_key 60132695886 to enb_ue_s1ap_key2mme_ueid_map enb_ue_s1ap_ue_id 3153742 mme_ue_s1ap_id 3 000784 Sun Mar 31 21:06:40 2024 711A00FF9700 DEBUG MME-AP tasks/mme_app/mme_app_state_mana:0101 Inside get_state with read_from_db 0 000785 Sun Mar 31 21:06:40 2024 711A00FF9700 INFO NAS-EM tasks/nas/emm/Authentication.cpp:1497 Sending Authentication Information Request message to S6A for ue_id = 3 000786 Sun Mar 31 21:06:40 2024 7119E6FFD700 DEBUG S1AP tasks/s1ap/s1ap_mme.cpp :0116 S1AP ZMQ latency: 236. 000787 Sun Mar 31 21:06:40 2024 7119E6FFD700 DEBUG S1AP tasks/s1ap/s1ap_mme_nas_procedur:1270 Num elements in ue_id_coll 1 and num ue associated 1 000788 Sun Mar 31 21:06:40 2024 7119E6FFD700 DEBUG S1AP tasks/s1ap/s1ap_mme_nas_procedur:1276 Associated sctp_assoc_id 11, enb_ue_s1ap_id 3153742, mme_ue_s1ap_id 3:MAP_OK 000789 Sun Mar 31 21:06:40 2024 7119E5FFB700 DEBUG S6A tasks/s6a/s6a_auth_info.cpp :0394 s6a_generate_authentication_info_req plmn: 22F210 000790 Sun Mar 31 21:06:40 2024 7119E5FFB700 DEBUG S6A tasks/s6a/s6a_auth_info.cpp :0396 s6a_generate_authentication_info_req visited_plmn: 22F210 000791 Sun Mar 31 21:06:40 2024 7119E5FFB700 DEBUG S6A tasks/s6a/s6a_task.cpp :0096 Sending s6a AIR for imsi=222010100001120 000792 Sun Mar 31 21:06:40 2024 7119A77FE700 DEBUG S6A tasks/s6a/s6a_auth_info.cpp :0224 Received S6A Authentication Information Answer (AIA) 000793 Sun Mar 31 21:06:40 2024 7119A77FE700 DEBUG S6A tasks/s6a/s6a_auth_info.cpp :0253 Received S6A Result code 2001:DIAMETER_SUCCESS 000794 Sun Mar 31 21:06:40 2024 711A00FF9700 DEBUG MME-AP tasks/mme_app/mme_app_state_mana:0101 Inside get_state with read_from_db 0 000795 Sun Mar 31 21:06:40 2024 711A00FF9700 DEBUG MME-AP tasks/mme_app/mme_app_main.cpp :0090 MME APP ZMQ latency: 200. 000796 Sun Mar 31 21:06:40 2024 711A00FF9700 INFO NAS-EM tasks/nas/nas_proc.cpp :0376 [222010100001120] Received Authentication Information Answer from S6A for ue_id = 3 000797 Sun Mar 31 21:06:40 2024 711A00FF9700 INFO NAS-EM tasks/nas/emm/sap/emm_cn.cpp :1158 EMMCN-SAP - Received primitive EMM_CN_AUTHENTICATION_PARAM_RES (401) 000798 Sun Mar 31 21:06:40 2024 711A00FF9700 INFO NAS-EM tasks/nas/emm/sap/emm_fsm.cpp :0247 EMM-FSM - Received event COMMON_PROC_ABORT (4) in state EMM-DEREGISTERED 000799 Sun Mar 31 21:06:40 2024 711A00FF9700 WARNI NAS-EM tasks/nas/emm/sap/EmmDeregistere:0129 EMM-FSM state EMM_DEREGISTERED - Primitive _EMMREG_COMMON_PROC_ABORT is not valid 000800 Sun Mar 31 21:06:40 2024 711A00FF9700 INFO NAS-EM tasks/nas/emm/Authentication.cpp:0175 [222010100001120] ue_id=3 EMM-PROC - Initiate Authentication KSI = 0 000801 Sun Mar 31 21:06:40 2024 711A00FF9700 WARNI NAS-EM tasks/nas/emm/LowerLayer.cpp :0479 NO Valid Security Context Available 000802 Sun Mar 31 21:06:40 2024 711A00FF9700 NOTIC NAS tasks/nas/emm/Authentication.cpp:1270 Hit 3GPP TS 24_301R10_5_4_2_2 : Authentication initiation by the network 000803 Sun Mar 31 21:06:40 2024 711A00FF9700 INFO NAS-EM tasks/nas/emm/sap/emm_as.cpp :0183 EMMAS-SAP - Received primitive EMMAS_SECURITY_REQ (201) 000804 Sun Mar 31 21:06:40 2024 711A00FF9700 INFO NAS-EM tasks/nas/emm/sap/emm_as.cpp :1643 EMMAS-SAP - Send AS security request 000805 Sun Mar 31 21:06:40 2024 711A00FF9700 INFO NAS-EM tasks/nas/emm/sap/emm_send.cpp :1367 EMMAS-SAP - Send Authentication Request message for ue_id = 3 000806 Sun Mar 31 21:06:40 2024 711A00FF9700 DEBUG MME-AP tasks/mme_app/mme_app_state_mana:0101 Inside get_state with read_from_db 0 000807 Sun Mar 31 21:06:40 2024 711A00FF9700 DEBUG MME-AP tasks/mme_app/mme_app_transport.:0120 MME_APP:DOWNLINK NAS TRANSPORT. Establishing S1 sig connection. mme_ue_s1ap_id = 3 enb_ue_s1ap_id = 3153742 000808 Sun Mar 31 21:06:40 2024 711A00FF9700 DEBUG MME-AP tasks/mme_app/mme_app_context.cp:0960 [222010100001120] MME_APP: UE Connection State changed to CONNECTED.enb_ue_s1ap_id =3153742, mme_ue_s1ap_id = 3 000809 Sun Mar 31 21:06:40 2024 711A00FF9700 DEBUG MME-AP tasks/mme_app/mme_app_state_mana:0101 Inside get_state with read_from_db 0 000810 Sun Mar 31 21:06:40 2024 711A00FF9700 INFO MME-AP tasks/mme_app/mme_app_context.cp:0980 [222010100001120] UE STATE - CONNECTED. 000811 Sun Mar 31 21:06:40 2024 711A00FF9700 INFO NAS-EM tasks/nas/emm/sap/emm_fsm.cpp :0247 EMM-FSM - Received event COMMON_PROC_REQ (1) in state EMM-DEREGISTERED 000812 Sun Mar 31 21:06:40 2024 711A00FF9700 INFO NAS-EM tasks/nas/emm/sap/emm_fsm.cpp :0164 UE 3 EMM-FSM - Status changed: EMM-DEREGISTERED ===> EMM-COMMON-PROCEDURE-INITIATED 000813 Sun Mar 31 21:06:40 2024 7119E6FFD700 DEBUG S1AP tasks/s1ap/s1ap_mme.cpp :0116 S1AP ZMQ latency: 268. 000814 Sun Mar 31 21:06:40 2024 7119E6FFD700 NOTIC S1AP tasks/s1ap/s1ap_mme_nas_procedur:0634 [222010100001120] Send S1AP DOWNLINK_NAS_TRANSPORT message ue_id = 3 MME_UE_S1AP_ID = 3 eNB_UE_S1AP_ID = 3153742