Replies: 7 comments 3 replies
-
Same experience. Few times we had attach request storms caused MME service crash. (Looked like storms were related to big operator network issue, all his customers tried to attach to our net). |
Beta Was this translation helpful? Give feedback.
-
Hi guys, there is any solution for this problem? I'm running on Orchestration 1.8 and with same problem reported by yours. |
Beta Was this translation helpful? Give feedback.
-
Is this still coming on latest build. |
Beta Was this translation helpful? Give feedback.
-
Hi @panyogesh, unfortunately latest build is not working for us due to #15303 |
Beta Was this translation helpful? Give feedback.
-
I think this is a bug that needs to be documented, diagnosed and prioritized. Has anybody done a search in https://github.com/magma/magma/issues/? |
Beta Was this translation helpful? Give feedback.
-
I guess #15368 is raised for the same. |
Beta Was this translation helpful? Give feedback.
-
This will be a feature in the next release, after 1.9. |
Beta Was this translation helpful? Give feedback.
-
We have an AGW in a very busy area with Ue's trying to attach all the time. We have successfully enabled PLMN restrictions on the top 10 most active PLMN's, but our mme.log are wrapping daily at 1Gb and the syslog is 20Gb before wrapping.
looks like 80,000 attach requests per hour?
syslog example:
magma@csnovanet01:/var/log$ tail -f syslog
Aug 31 23:57:30 csnovanet01 sctpd[8579]: I0831 23:57:30.838629 42563 sctp_connection.cpp:195] Unhandled notification type 32777
Aug 31 23:57:30 csnovanet01 mme[3355894]: [INFO] Sending S6A-AUTHENTICATION_INFORMATION_REQUEST with IMSI: 744055610016778
Aug 31 23:57:30 csnovanet01 subscriberdb[3355625]: WARNING:root:Subscriber not found: IMSI744055610016778
Aug 31 23:57:30 csnovanet01 mme[3355894]: [DEBUG] Success logging event: attach_reject
Aug 31 23:57:30 csnovanet01 directoryd[3355644]: INFO:redis_lock.acquire:Got lock for 'lock:IMSI744055610016778:directory_record:lock'.
Aug 31 23:57:30 csnovanet01 mme[3355894]: Directoryd RPC failed with code 5, msg: Record for ID IMSI744055610016778 was not found.
Aug 31 23:57:30 csnovanet01 mme[3355894]: [DEBUG] Success logging event: attach_reject
Aug 31 23:57:30 csnovanet01 directoryd[3355644]: INFO:redis_lock.acquire:Got lock for 'lock:IMSI:directory_record:lock'.
Aug 31 23:57:30 csnovanet01 mme[3355894]: Directoryd RPC failed with code 5, msg: Record for ID IMSI was not found.
Aug 31 23:57:31 csnovanet01 sctpd[8579]: I0831 23:57:31.000789 42563 sctp_connection.cpp:195] Unhandled notification type 32777
Aug 31 23:57:31 csnovanet01 control_proxy[407457]: 2022-08-31T23:57:30.829-03:00 [127.0.0.1 -> subscriberdb-controller.radtonics.com,8443] "POST /magma.lte.SubscriberDBCloud/CheckInSync HTTP/2" 200 0bytes 0.197s
Aug 31 23:57:31 csnovanet01 subscriberdb[3355625]: ERROR:root:Internal Error in subscriberdb-Orc8r communication. If AGW and Orc8r are deployed at a commit hash, upgrade AGW and Orc8r to latest commit hash.
Aug 31 23:57:31 csnovanet01 mme[3355894]: [DEBUG] Success logging event: attach_reject
Aug 31 23:57:31 csnovanet01 directoryd[3355644]: INFO:redis_lock.acquire:Got lock for 'lock:IMSI:directory_record:lock'.
Aug 31 23:57:31 csnovanet01 mme[3355894]: Directoryd RPC failed with code 5, msg: Record for ID IMSI was not found.
Aug 31 23:57:31 csnovanet01 sctpd[8579]: I0831 23:57:31.168613 42563 sctp_connection.cpp:195] Unhandled notification type 32777
Aug 31 23:57:31 csnovanet01 mme[3355894]: [DEBUG] Success logging event: attach_reject
Aug 31 23:57:31 csnovanet01 directoryd[3355644]: INFO:redis_lock.acquire:Got lock for 'lock:IMSI:directory_record:lock'.
Aug 31 23:57:31 csnovanet01 mme[3355894]: Directoryd RPC failed with code 5, msg: Record for ID IMSI was not found.
Aug 31 23:57:31 csnovanet01 control_proxy[407457]: 2022-08-31T23:57:31.030-03:00 [127.0.0.1 -> subscriberdb-controller.radtonics.com,8443] "POST /magma.lte.SubscriberDBCloud/Sync HTTP/2" 200 0bytes 0.198s
Aug 31 23:57:31 csnovanet01 subscriberdb[3355625]: ERROR:root:Internal Error in subscriberdb-Orc8r communication. If AGW and Orc8r are deployed at a commit hash, upgrade AGW and Orc8r to latest commit hash.
Aug 31 23:57:31 csnovanet01 sctpd[8579]: I0831 23:57:31.403241 42563 sctp_connection.cpp:195] Unhandled notification type 32777
Aug 31 23:57:31 csnovanet01 sessiond[3355649]: I0831 23:57:31.407822 3355649 LocalEnforcer.cpp:431] Received stats for 9 active sessions and 0 stale sessions
Aug 31 23:57:31 csnovanet01 magmad[3355580]: WARNING:root:Shared Mconfig is missing in mconfig.configs_by_key. Returning configs unchanged.
Aug 31 23:57:31 csnovanet01 sctpd[8579]: I0831 23:57:31.708472 42563 sctp_connection.cpp:195] Unhandled notification type 32777
Aug 31 23:57:31 csnovanet01 mme[3355894]: [DEBUG] Success logging event: attach_reject
Aug 31 23:57:31 csnovanet01 directoryd[3355644]: INFO:redis_lock.acquire:Got lock for 'lock:IMSI:directory_record:lock'.
Aug 31 23:57:31 csnovanet01 mme[3355894]: Directoryd RPC failed with code 5, msg: Record for ID IMSI was not found.
Aug 31 23:57:31 csnovanet01 magmad[3355580]: INFO:root:Setting logging level to INFO
Aug 31 23:57:31 csnovanet01 sctpd[8579]: I0831 23:57:31.893752 42563 sctp_connection.cpp:195] Unhandled notification type 32777
Aug 31 23:57:32 csnovanet01 sctpd[8579]: I0831 23:57:32.365530 42563 sctp_connection.cpp:195] Unhandled notification type 32777
Aug 31 23:57:32 csnovanet01 mme[3355894]: [INFO] Sending S6A-AUTHENTICATION_INFORMATION_REQUEST with IMSI: 724171017600123
Aug 31 23:57:32 csnovanet01 subscriberdb[3355625]: WARNING:root:Subscriber not found: IMSI724171017600123
Aug 31 23:57:32 csnovanet01 mme[3355894]: [DEBUG] Success logging event: attach_reject
Aug 31 23:57:32 csnovanet01 directoryd[3355644]: INFO:redis_lock.acquire:Got lock for 'lock:IMSI724171017600123:directory_record:lock'.
Aug 31 23:57:32 csnovanet01 mme[3355894]: Directoryd RPC failed with code 5, msg: Record for ID IMSI724171017600123 was not found.
Aug 31 23:57:32 csnovanet01 sctpd[8579]: I0831 23:57:32.650981 42563 sctp_connection.cpp:195] Unhandled notification type 32777
Aug 31 23:57:32 csnovanet01 mme[3355894]: [DEBUG] Success logging event: attach_reject
Aug 31 23:57:32 csnovanet01 directoryd[3355644]: INFO:redis_lock.acquire:Got lock for 'lock:IMSI:directory_record:lock'.
Aug 31 23:57:32 csnovanet01 mme[3355894]: Directoryd RPC failed with code 5, msg: Record for ID IMSI was not found.
mme.log example:
magma@csnovanet01:/var/log$ tail -n 150 mme.log
1298039 Wed Aug 31 23:58:55 2022 7FB213AEF700 INFO NAS-EM tasks/nas/emm/sap/emm_as.c :1513 EMMAS-SAP - Send AS status indication (cause=11)
1298040 Wed Aug 31 23:58:55 2022 7FB213AEF700 WARNI NAS-EM tasks/nas/emm/sap/emm_send.c :0100 EMMAS-SAP - Send EMM Status message (cause=EMM_CAUSE_PLMN_NOT_ALLOWED)
1298041 Wed Aug 31 23:58:55 2022 7FB2102E8700 NOTIC S1AP tasks/s1ap/s1ap_mme_nas_procedur:0587 [0] Send S1AP DOWNLINK_NAS_TRANSPORT message ue_id = 597595 MME_UE_S1AP_ID = 597595 eNB_UE_S1AP_ID = 226
1298042 Wed Aug 31 23:58:55 2022 7FB213AEF700 INFO MME-AP tasks/mme_app/mme_app_context.c :1119 Received UE context release complete message for ue_id: 597595
1298043 Wed Aug 31 23:58:55 2022 7FB213AEF700 INFO MME-AP tasks/mme_app/mme_app_context.c :0116 [0] Deleted UE location from directoryd
1298044 Wed Aug 31 23:58:55 2022 7FB2102E8700 WARNI S1AP tasks/s1ap/s1ap_mme_nas_procedur:0508 Unknown UE MME ID 597595, This case is not handled right now
1298045 Wed Aug 31 23:58:55 2022 7FB213AEF700 ERROR MME-AP tasks/mme_app/mme_app_context.c :0759 UE Context not found!
enb_ue_s1ap_id 226 mme_ue_s1ap_id 597595, GUTI not in GUTI collection
1298046 Wed Aug 31 23:58:55 2022 7FB213AEF700 INFO NAS-ES tasks/nas/esm/sap/esm_sap.c :0139 ESM-SAP - Received primitive ESM_EPS_BEARER_CONTEXT_DEACTIVATE_REQ (10)
1298047 Wed Aug 31 23:58:55 2022 7FB213AEF700 WARNI NAS-ES tasks/nas/esm/PdnConnectivity.c :0521 ESM-PROC - PDN connection identifier is not valid
1298048 Wed Aug 31 23:58:55 2022 7FB2102E8700 INFO S1AP tasks/s1ap/s1ap_mme_nas_procedur:0101 Received S1AP INITIAL_UE_MESSAGE ENB_UE_S1AP_ID 224 assoc-id:3
1298049 Wed Aug 31 23:58:55 2022 7FB2102E8700 INFO S1AP tasks/s1ap/s1ap_mme_nas_procedur:0124 New Initial UE message received with eNB UE S1AP ID: 224 assoc-id :3
1298050 Wed Aug 31 23:58:55 2022 7FB2102E8700 INFO S1AP tasks/s1ap/s1ap_mme_itti_messagi:0163 Sending Initial UE Message to MME_APP, enb_ue_s1ap_id : 224
1298051 Wed Aug 31 23:58:55 2022 7FB213AEF700 INFO MME-AP tasks/mme_app/mme_app_bearer.c :0584 Received MME_APP_INITIAL_UE_MESSAGE from S1AP
1298052 Wed Aug 31 23:58:55 2022 7FB213AEF700 INFO MME-AP tasks/mme_app/mme_app_bearer.c :0726 [0] INITIAL_UE_MESSAGE RCVD
mme_ue_s1ap_id = 597596
enb_ue_s1ap_id = 224
1298053 Wed Aug 31 23:58:55 2022 7FB213AEF700 INFO MME-AP tasks/mme_app/mme_app_bearer.c :0734 [0] Sending NAS Establishment Indication to NAS for ue_id = 597596
1298054 Wed Aug 31 23:58:55 2022 7FB213AEF700 INFO NAS-EM tasks/nas/emm/sap/emm_as.c :0172 EMMAS-SAP - Received primitive EMMAS_ESTABLISH_REQ (205)
1298055 Wed Aug 31 23:58:55 2022 7FB213AEF700 INFO NAS-EM tasks/nas/emm/sap/emm_as.c :0749 EMMAS-SAP - Received AS connection establish request
1298056 Wed Aug 31 23:58:55 2022 7FB213AEF700 INFO NAS-EM tasks/nas/emm/sap/emm_as.c :0810 EMMAS-SAP - Message Type = ATTACH_REQUEST(0x41) for (ue_id = 597596)
1298057 Wed Aug 31 23:58:55 2022 7FB213AEF700 INFO NAS-EM tasks/nas/emm/sap/emm_recv.c :0189 EMMAS-SAP - Received Attach Request message for ue id 597596
1298058 Wed Aug 31 23:58:55 2022 7FB213AEF700 ERROR NAS-EM tasks/nas/emm/sap/emm_recv.c :0320 EMMAS-SAP - Sending Attach Reject for ue_id =597596 , emm_cause =(11)
1298059 Wed Aug 31 23:58:55 2022 7FB213AEF700 WARNI NAS-EM tasks/nas/emm/Attach.c :1016 EMM-PROC - EMM attach procedure not accepted by the network (ue_id=597596, cause=EMM_CAUSE_PLMN_NOT_ALLOWED)
1298060 Wed Aug 31 23:58:55 2022 7FB213AEF700 WARNI NAS-EM tasks/nas/emm/LowerLayer.c :0469 NO Valid Security Context Available
1298061 Wed Aug 31 23:58:55 2022 7FB213AEF700 INFO NAS-EM tasks/nas/emm/sap/emm_as.c :0172 EMMAS-SAP - Received primitive EMMAS_ESTABLISH_REJ (207)
1298062 Wed Aug 31 23:58:55 2022 7FB213AEF700 INFO NAS-EM tasks/nas/emm/sap/emm_as.c :2162 EMMAS-SAP - Send AS connection establish reject
1298063 Wed Aug 31 23:58:55 2022 7FB213AEF700 INFO NAS-EM tasks/nas/emm/sap/emm_send.c :0766 EMMAS-SAP - Send Attach Reject message (cause=EMM_CAUSE_PLMN_NOT_ALLOWED)
1298064 Wed Aug 31 23:58:55 2022 7FB213AEF700 INFO MME-AP tasks/mme_app/mme_app_context.c :0927 [0] UE STATE - CONNECTED.
1298065 Wed Aug 31 23:58:55 2022 7FB213AEF700 INFO MME-AP tasks/mme_app/mme_app_itti_messa:0087 [0] Sending UE Context Release Cmd to S1ap for (ue_id = 597596)
UE Context Release Cause = (1)
1298066 Wed Aug 31 23:58:55 2022 7FB213AEF700 WARNI NAS-EM tasks/nas/emm/sap/emm_as.c :0236 EMMAS-SAP - Received EMM message is not valid (cause=11) for ue id 597596
1298067 Wed Aug 31 23:58:55 2022 7FB213AEF700 INFO NAS-EM tasks/nas/emm/EmmStatusHdl.c :0117 [0] EMM-PROC - EMM status procedure requested
1298068 Wed Aug 31 23:58:55 2022 7FB213AEF700 WARNI NAS-EM tasks/nas/emm/LowerLayer.c :0469 NO Valid Security Context Available
1298069 Wed Aug 31 23:58:55 2022 7FB213AEF700 INFO NAS-EM tasks/nas/emm/sap/emm_as.c :0172 EMMAS-SAP - Received primitive EMMAS_STATUS_IND (216)
1298070 Wed Aug 31 23:58:55 2022 7FB213AEF700 INFO NAS-EM tasks/nas/emm/sap/emm_as.c :1513 EMMAS-SAP - Send AS status indication (cause=11)
1298071 Wed Aug 31 23:58:55 2022 7FB213AEF700 WARNI NAS-EM tasks/nas/emm/sap/emm_send.c :0100 EMMAS-SAP - Send EMM Status message (cause=EMM_CAUSE_PLMN_NOT_ALLOWED)
1298072 Wed Aug 31 23:58:55 2022 7FB2102E8700 NOTIC S1AP tasks/s1ap/s1ap_mme_nas_procedur:0587 [0] Send S1AP DOWNLINK_NAS_TRANSPORT message ue_id = 597596 MME_UE_S1AP_ID = 597596 eNB_UE_S1AP_ID = 224
1298073 Wed Aug 31 23:58:55 2022 7FB213AEF700 INFO MME-AP tasks/mme_app/mme_app_context.c :1119 Received UE context release complete message for ue_id: 597596
1298074 Wed Aug 31 23:58:55 2022 7FB213AEF700 INFO MME-AP tasks/mme_app/mme_app_context.c :0116 [0] Deleted UE location from directoryd
1298075 Wed Aug 31 23:58:55 2022 7FB213AEF700 ERROR MME-AP tasks/mme_app/mme_app_context.c :0759 UE Context not found!
enb_ue_s1ap_id 224 mme_ue_s1ap_id 597596, GUTI not in GUTI collection
1298076 Wed Aug 31 23:58:55 2022 7FB213AEF700 INFO NAS-ES tasks/nas/esm/sap/esm_sap.c :0139 ESM-SAP - Received primitive ESM_EPS_BEARER_CONTEXT_DEACTIVATE_REQ (10)
1298077 Wed Aug 31 23:58:55 2022 7FB213AEF700 WARNI NAS-ES tasks/nas/esm/PdnConnectivity.c :0521 ESM-PROC - PDN connection identifier is not valid
1298078 Wed Aug 31 23:58:55 2022 7FB2102E8700 WARNI S1AP tasks/s1ap/s1ap_mme_nas_procedur:0508 Unknown UE MME ID 597596, This case is not handled right now
1298079 Wed Aug 31 23:58:55 2022 7FB2102E8700 INFO S1AP tasks/s1ap/s1ap_mme_nas_procedur:0101 Received S1AP INITIAL_UE_MESSAGE ENB_UE_S1AP_ID 240 assoc-id:3
1298080 Wed Aug 31 23:58:55 2022 7FB2102E8700 INFO S1AP tasks/s1ap/s1ap_mme_nas_procedur:0124 New Initial UE message received with eNB UE S1AP ID: 240 assoc-id :3
1298081 Wed Aug 31 23:58:55 2022 7FB2102E8700 INFO S1AP tasks/s1ap/s1ap_mme_itti_messagi:0163 Sending Initial UE Message to MME_APP, enb_ue_s1ap_id : 240
1298082 Wed Aug 31 23:58:55 2022 7FB213AEF700 INFO MME-AP tasks/mme_app/mme_app_bearer.c :0584 Received MME_APP_INITIAL_UE_MESSAGE from S1AP
1298083 Wed Aug 31 23:58:55 2022 7FB213AEF700 INFO MME-AP tasks/mme_app/mme_app_bearer.c :0726 [0] INITIAL_UE_MESSAGE RCVD
mme_ue_s1ap_id = 597597
enb_ue_s1ap_id = 240
1298084 Wed Aug 31 23:58:55 2022 7FB213AEF700 INFO MME-AP tasks/mme_app/mme_app_bearer.c :0734 [0] Sending NAS Establishment Indication to NAS for ue_id = 597597
1298085 Wed Aug 31 23:58:55 2022 7FB213AEF700 INFO NAS-EM tasks/nas/emm/sap/emm_as.c :0172 EMMAS-SAP - Received primitive EMMAS_ESTABLISH_REQ (205)
1298086 Wed Aug 31 23:58:55 2022 7FB213AEF700 INFO NAS-EM tasks/nas/emm/sap/emm_as.c :0749 EMMAS-SAP - Received AS connection establish request
1298087 Wed Aug 31 23:58:55 2022 7FB213AEF700 INFO NAS-EM tasks/nas/emm/sap/emm_as.c :0810 EMMAS-SAP - Message Type = ATTACH_REQUEST(0x41) for (ue_id = 597597)
1298088 Wed Aug 31 23:58:55 2022 7FB213AEF700 INFO NAS-EM tasks/nas/emm/sap/emm_recv.c :0189 EMMAS-SAP - Received Attach Request message for ue id 597597
1298089 Wed Aug 31 23:58:55 2022 7FB213AEF700 ERROR NAS-EM tasks/nas/emm/sap/emm_recv.c :0320 EMMAS-SAP - Sending Attach Reject for ue_id =597597 , emm_cause =(11)
1298090 Wed Aug 31 23:58:55 2022 7FB213AEF700 WARNI NAS-EM tasks/nas/emm/Attach.c :1016 EMM-PROC - EMM attach procedure not accepted by the network (ue_id=597597, cause=EMM_CAUSE_PLMN_NOT_ALLOWED)
1298091 Wed Aug 31 23:58:55 2022 7FB213AEF700 WARNI NAS-EM tasks/nas/emm/LowerLayer.c :0469 NO Valid Security Context Available
1298092 Wed Aug 31 23:58:55 2022 7FB213AEF700 INFO NAS-EM tasks/nas/emm/sap/emm_as.c :0172 EMMAS-SAP - Received primitive EMMAS_ESTABLISH_REJ (207)
1298093 Wed Aug 31 23:58:55 2022 7FB213AEF700 INFO NAS-EM tasks/nas/emm/sap/emm_as.c :2162 EMMAS-SAP - Send AS connection establish reject
1298094 Wed Aug 31 23:58:55 2022 7FB213AEF700 INFO NAS-EM tasks/nas/emm/sap/emm_send.c :0766 EMMAS-SAP - Send Attach Reject message (cause=EMM_CAUSE_PLMN_NOT_ALLOWED)
1298095 Wed Aug 31 23:58:55 2022 7FB213AEF700 INFO MME-AP tasks/mme_app/mme_app_context.c :0927 [0] UE STATE - CONNECTED.
1298096 Wed Aug 31 23:58:55 2022 7FB213AEF700 INFO MME-AP tasks/mme_app/mme_app_itti_messa:0087 [0] Sending UE Context Release Cmd to S1ap for (ue_id = 597597)
UE Context Release Cause = (1)
1298097 Wed Aug 31 23:58:55 2022 7FB213AEF700 WARNI NAS-EM tasks/nas/emm/sap/emm_as.c :0236 EMMAS-SAP - Received EMM message is not valid (cause=11) for ue id 597597
1298098 Wed Aug 31 23:58:55 2022 7FB213AEF700 INFO NAS-EM tasks/nas/emm/EmmStatusHdl.c :0117 [0] EMM-PROC - EMM status procedure requested
1298099 Wed Aug 31 23:58:55 2022 7FB213AEF700 WARNI NAS-EM tasks/nas/emm/LowerLayer.c :0469 NO Valid Security Context Available
1298100 Wed Aug 31 23:58:55 2022 7FB213AEF700 INFO NAS-EM tasks/nas/emm/sap/emm_as.c :0172 EMMAS-SAP - Received primitive EMMAS_STATUS_IND (216)
1298101 Wed Aug 31 23:58:55 2022 7FB213AEF700 INFO NAS-EM tasks/nas/emm/sap/emm_as.c :1513 EMMAS-SAP - Send AS status indication (cause=11)
1298102 Wed Aug 31 23:58:55 2022 7FB213AEF700 WARNI NAS-EM tasks/nas/emm/sap/emm_send.c :0100 EMMAS-SAP - Send EMM Status message (cause=EMM_CAUSE_PLMN_NOT_ALLOWED)
1298103 Wed Aug 31 23:58:55 2022 7FB2102E8700 NOTIC S1AP tasks/s1ap/s1ap_mme_nas_procedur:0587 [0] Send S1AP DOWNLINK_NAS_TRANSPORT message ue_id = 597597 MME_UE_S1AP_ID = 597597 eNB_UE_S1AP_ID = 240
1298104 Wed Aug 31 23:58:55 2022 7FB2102E8700 WARNI S1AP tasks/s1ap/s1ap_mme_nas_procedur:0508 Unknown UE MME ID 597597, This case is not handled right now
1298105 Wed Aug 31 23:58:55 2022 7FB213AEF700 INFO MME-AP tasks/mme_app/mme_app_context.c :1119 Received UE context release complete message for ue_id: 597597
1298106 Wed Aug 31 23:58:55 2022 7FB213AEF700 INFO MME-AP tasks/mme_app/mme_app_context.c :0116 [0] Deleted UE location from directoryd
1298107 Wed Aug 31 23:58:55 2022 7FB213AEF700 ERROR MME-AP tasks/mme_app/mme_app_context.c :0759 UE Context not found!
enb_ue_s1ap_id 240 mme_ue_s1ap_id 597597, GUTI not in GUTI collection
1298108 Wed Aug 31 23:58:55 2022 7FB213AEF700 INFO NAS-ES tasks/nas/esm/sap/esm_sap.c :0139 ESM-SAP - Received primitive ESM_EPS_BEARER_CONTEXT_DEACTIVATE_REQ (10)
1298109 Wed Aug 31 23:58:55 2022 7FB213AEF700 WARNI NAS-ES tasks/nas/esm/PdnConnectivity.c :0521 ESM-PROC - PDN connection identifier is not valid
1298110 Wed Aug 31 23:58:55 2022 7FB2102E8700 INFO S1AP tasks/s1ap/s1ap_mme_nas_procedur:0101 Received S1AP INITIAL_UE_MESSAGE ENB_UE_S1AP_ID 111 assoc-id:3
1298111 Wed Aug 31 23:58:55 2022 7FB2102E8700 INFO S1AP tasks/s1ap/s1ap_mme_nas_procedur:0124 New Initial UE message received with eNB UE S1AP ID: 111 assoc-id :3
1298112 Wed Aug 31 23:58:55 2022 7FB2102E8700 INFO S1AP tasks/s1ap/s1ap_mme_itti_messagi:0163 Sending Initial UE Message to MME_APP, enb_ue_s1ap_id : 111
1298113 Wed Aug 31 23:58:55 2022 7FB213AEF700 INFO MME-AP tasks/mme_app/mme_app_bearer.c :0584 Received MME_APP_INITIAL_UE_MESSAGE from S1AP
1298114 Wed Aug 31 23:58:55 2022 7FB213AEF700 INFO MME-AP tasks/mme_app/mme_app_bearer.c :0726 [0] INITIAL_UE_MESSAGE RCVD
mme_ue_s1ap_id = 597598
enb_ue_s1ap_id = 111
1298115 Wed Aug 31 23:58:55 2022 7FB213AEF700 INFO MME-AP tasks/mme_app/mme_app_bearer.c :0734 [0] Sending NAS Establishment Indication to NAS for ue_id = 597598
1298116 Wed Aug 31 23:58:55 2022 7FB213AEF700 INFO NAS-EM tasks/nas/emm/sap/emm_as.c :0172 EMMAS-SAP - Received primitive EMMAS_ESTABLISH_REQ (205)
1298117 Wed Aug 31 23:58:55 2022 7FB213AEF700 INFO NAS-EM tasks/nas/emm/sap/emm_as.c :0749 EMMAS-SAP - Received AS connection establish request
1298118 Wed Aug 31 23:58:55 2022 7FB213AEF700 INFO NAS-EM tasks/nas/emm/sap/emm_as.c :0810 EMMAS-SAP - Message Type = ATTACH_REQUEST(0x41) for (ue_id = 597598)
1298119 Wed Aug 31 23:58:55 2022 7FB213AEF700 INFO NAS-EM tasks/nas/emm/sap/emm_recv.c :0189 EMMAS-SAP - Received Attach Request message for ue id 597598
1298120 Wed Aug 31 23:58:55 2022 7FB213AEF700 ERROR NAS-EM tasks/nas/emm/sap/emm_recv.c :0320 EMMAS-SAP - Sending Attach Reject for ue_id =597598 , emm_cause =(11)
1298121 Wed Aug 31 23:58:55 2022 7FB213AEF700 WARNI NAS-EM tasks/nas/emm/Attach.c :1016 EMM-PROC - EMM attach procedure not accepted by the network (ue_id=597598, cause=EMM_CAUSE_PLMN_NOT_ALLOWED)
1298122 Wed Aug 31 23:58:55 2022 7FB213AEF700 WARNI NAS-EM tasks/nas/emm/LowerLayer.c :0469 NO Valid Security Context Available
1298123 Wed Aug 31 23:58:55 2022 7FB213AEF700 INFO NAS-EM tasks/nas/emm/sap/emm_as.c :0172 EMMAS-SAP - Received primitive EMMAS_ESTABLISH_REJ (207)
1298124 Wed Aug 31 23:58:55 2022 7FB213AEF700 INFO NAS-EM tasks/nas/emm/sap/emm_as.c :2162 EMMAS-SAP - Send AS connection establish reject
1298125 Wed Aug 31 23:58:55 2022 7FB213AEF700 INFO NAS-EM tasks/nas/emm/sap/emm_send.c :0766 EMMAS-SAP - Send Attach Reject message (cause=EMM_CAUSE_PLMN_NOT_ALLOWED)
1298126 Wed Aug 31 23:58:55 2022 7FB213AEF700 INFO MME-AP tasks/mme_app/mme_app_context.c :0927 [0] UE STATE - CONNECTED.
1298127 Wed Aug 31 23:58:55 2022 7FB213AEF700 INFO MME-AP tasks/mme_app/mme_app_itti_messa:0087 [0] Sending UE Context Release Cmd to S1ap for (ue_id = 597598)
UE Context Release Cause = (1)
1298128 Wed Aug 31 23:58:55 2022 7FB213AEF700 WARNI NAS-EM tasks/nas/emm/sap/emm_as.c :0236 EMMAS-SAP - Received EMM message is not valid (cause=11) for ue id 597598
1298129 Wed Aug 31 23:58:55 2022 7FB213AEF700 INFO NAS-EM tasks/nas/emm/EmmStatusHdl.c :0117 [0] EMM-PROC - EMM status procedure requested
1298130 Wed Aug 31 23:58:55 2022 7FB213AEF700 WARNI NAS-EM tasks/nas/emm/LowerLayer.c :0469 NO Valid Security Context Available
1298131 Wed Aug 31 23:58:55 2022 7FB213AEF700 INFO NAS-EM tasks/nas/emm/sap/emm_as.c :0172 EMMAS-SAP - Received primitive EMMAS_STATUS_IND (216)
1298132 Wed Aug 31 23:58:55 2022 7FB213AEF700 INFO NAS-EM tasks/nas/emm/sap/emm_as.c :1513 EMMAS-SAP - Send AS status indication (cause=11)
1298133 Wed Aug 31 23:58:55 2022 7FB213AEF700 WARNI NAS-EM tasks/nas/emm/sap/emm_send.c :0100 EMMAS-SAP - Send EMM Status message (cause=EMM_CAUSE_PLMN_NOT_ALLOWED)
1298134 Wed Aug 31 23:58:55 2022 7FB2102E8700 NOTIC S1AP tasks/s1ap/s1ap_mme_nas_procedur:0587 [0] Send S1AP DOWNLINK_NAS_TRANSPORT message ue_id = 597598 MME_UE_S1AP_ID = 597598 eNB_UE_S1AP_ID = 111
1298135 Wed Aug 31 23:58:55 2022 7FB213AEF700 INFO MME-AP tasks/mme_app/mme_app_context.c :1119 Received UE context release complete message for ue_id: 597598
1298136 Wed Aug 31 23:58:55 2022 7FB213AEF700 INFO MME-AP tasks/mme_app/mme_app_context.c :0116 [0] Deleted UE location from directoryd
1298137 Wed Aug 31 23:58:55 2022 7FB2102E8700 WARNI S1AP tasks/s1ap/s1ap_mme_nas_procedur:0508 Unknown UE MME ID 597598, This case is not handled right now
1298138 Wed Aug 31 23:58:55 2022 7FB2102E8700 INFO S1AP tasks/s1ap/s1ap_mme_nas_procedur:0101 Received S1AP INITIAL_UE_MESSAGE ENB_UE_S1AP_ID 102 assoc-id:3
1298139 Wed Aug 31 23:58:55 2022 7FB2102E8700 INFO S1AP tasks/s1ap/s1ap_mme_nas_procedur:0124 New Initial UE message received with eNB UE S1AP ID: 102 assoc-id :3
1298140 Wed Aug 31 23:58:55 2022 7FB2102E8700 INFO S1AP tasks/s1ap/s1ap_mme_itti_messagi:0163 Sending Initial UE Message to MME_APP, enb_ue_s1ap_id : 102
1298141 Wed Aug 31 23:58:55 2022 7FB213AEF700 ERROR MME-AP tasks/mme_app/mme_app_context.c :0759 UE Context not found!
enb_ue_s1ap_id 111 mme_ue_s1ap_id 597598, GUTI not in GUTI collection
1298142 Wed Aug 31 23:58:55 2022 7FB213AEF700 INFO NAS-ES tasks/nas/esm/sap/esm_sap.c :0139 ESM-SAP - Received primitive ESM_EPS_BEARER_CONTEXT_DEACTIVATE_REQ (10)
1298143 Wed Aug 31 23:58:55 2022 7FB213AEF700 WARNI NAS-ES tasks/nas/esm/PdnConnectivity.c :0521 ESM-PROC - PDN connection identifier is not valid
1298144 Wed Aug 31 23:58:55 2022 7FB213AEF700 INFO MME-AP tasks/mme_app/mme_app_bearer.c :0584 Received MME_APP_INITIAL_UE_MESSAGE from S1AP
1298145 Wed Aug 31 23:58:55 2022 7FB213AEF700 INFO MME-AP tasks/mme_app/mme_app_bearer.c :0726 [0] INITIAL_UE_MESSAGE RCVD
mme_ue_s1ap_id = 597599
enb_ue_s1ap_id = 102
1298146 Wed Aug 31 23:58:55 2022 7FB213AEF700 INFO MME-AP tasks/mme_app/mme_app_bearer.c :0734 [0] Sending NAS Establishment Indication to NAS for ue_id = 597599
1298147 Wed Aug 31 23:58:55 2022 7FB213AEF700 INFO NAS-EM tasks/nas/emm/sap/emm_as.c :0172 EMMAS-SAP - Received primitive EMMAS_ESTABLISH_REQ (205)
1298148 Wed Aug 31 23:58:55 2022 7FB213AEF700 INFO NAS-EM tasks/nas/emm/sap/emm_as.c :0749 EMMAS-SAP - Received AS connection establish request
1298149 Wed Aug 31 23:58:55 2022 7FB213AEF700 INFO NAS-EM tasks/nas/emm/sap/emm_as.c :0810 EMMAS-SAP - Message Type = ATTACH_REQUEST(0x41) for (ue_id = 597599)
1298150 Wed Aug 31 23:58:55 2022 7FB213AEF700 INFO NAS-EM tasks/nas/emm/sap/emm_recv.c :0189 EMMAS-SAP - Received Attach Request message for ue id 597599
1298151 Wed Aug 31 23:58:55 2022 7FB213AEF700 ERROR NAS-EM tasks/nas/emm/sap/emm_recv.c :0320 EMMAS-SAP - Sending Attach Reject for ue_id =597599 , emm_cause =(11)
1298152 Wed Aug 31 23:58:55 2022 7FB213AEF700 WARNI NAS-EM tasks/nas/emm/Attach.c :1016 EMM-PROC - EMM attach procedure not accepted by the network (ue_id=597599, cause=EMM_CAUSE_PLMN_NOT_ALLOWED)
1298153 Wed Aug 31 23:58:55 2022 7FB213AEF700 WARNI NAS-EM tasks/nas/emm/LowerLayer.c :0469 NO Valid Security Context Available
1298154 Wed Aug 31 23:58:55 2022 7FB213AEF700 INFO NAS-EM tasks/nas/emm/sap/emm_as.c :0172 EMMAS-SAP - Received primitive EMMAS_ESTABLISH_REJ (207)
1298155 Wed Aug 31 23:58:55 2022 7FB213AEF700 INFO NAS-EM tasks/nas/emm/sap/emm_as.c :2162 EMMAS-SAP - Send AS connection establish reject
1298156 Wed Aug 31 23:58:55 2022 7FB213AEF700 INFO NAS-EM tasks/nas/emm/sap/emm_send.c :0766 EMMAS-SAP - Send Attach Reject message (cause=EMM_CAUSE_PLMN_NOT_ALLOWED)
1298157 Wed Aug 31 23:58:55 2022 7FB2102E8700 NOTIC S1AP tasks/s1ap/s1ap_mme_nas_procedur:0587 [0] Send S1AP DOWNLINK_NAS_TRANSPORT message ue_id = 597599 MME_UE_S1AP_ID = 597599 eNB_UE_S1AP_ID = 102
1298158 Wed Aug 31 23:58:55 2022 7FB213AEF700 INFO MME-AP tasks/mme_app/mme_app_context.c :0927 [0] UE STATE - CONNECTED.
1298159 Wed Aug 31 23:58:55 2022 7FB213AEF700 INFO MME-AP tasks/mme_app/mme_app_itti_messa:0087 [0] Sending UE Context Release Cmd to S1ap for (ue_id = 597599)
UE Context Release Cause = (1)
12 hour MME log:
magma@csnovanet01:/var/log$ sed -n "s/.[//;s/] ATTACH.//p" /var/log/mme.log | sort | uniq -c
10 724170000582004
10 724171016811252
15 724171017600123
25 724171017600124
30 724171017600175
30 724171017600176
4 724171032240729
5 724171032240733
111 724233406067501
74 724233406706387
90 724233591136077
95 724233591986896
3 724234200045553
95 724234293763487
5 724235302340786
63 724235304536948
67 724235401676680
10 724235404388918
22 724260000266092
100 724320202435662
95 724320203485508
12 724320204133753
7 724320204133756
22 724320204137016
12 724320204137095
65 724340305103955
85 724340305314363
5 724340305762600
6 724550000005467
3 724550000005678
6 724550000005679
18 724550000005681
95 744020011008939
6 744020999896389
20 744040051508394
13 744040051673548
15 744040051961944
86 744040057688117
15 744040059849264
21 744053730057443
15 744055440024623
11 744055610016778
as far as I can tell this is working correctly @ulaskozat
is there any reason for the 10 PLMN restriction limit? is that to keep the attach requests speedy when they are valid?
should we just compress the old mme.log files?
thanks for any help.
-Mark.
Beta Was this translation helpful? Give feedback.
All reactions