search

aligungr / UERANSIM

Open source 5G UE and RAN (gNodeB) implementation.
GNU General Public License v3.0
793 stars 322 forks source link

Nausf_UEAU Authenticate Request Failed: &{Type: Title: Status:500 Detail: Instance: Cause:AV_GENERATION_PROBLEM #159

Closed yanivx closed 3 years ago

yanivx commented 4 years ago

Amf error logs

2020-10-19T10:30:57Z [INFO][UDR][DataRepo] Handle QueryAuthSubsData 2020-10-19T10:30:57Z [INFO][UDR][GIN] | 404 | 127.0.0.1 | GET | /nudr-dr/v1/subscription-data/imsi-20893000000uthentication-subscription | 2020-10-19T10:30:57Z [ERRO][UDM][UEAU] Return from UDR QueryAuthSubsData error 2020-10-19T10:30:57Z [INFO][UDM][GIN] | 403 | 127.0.0.1 | POST | /nudm-ueau/v1/suci-0-208-93-0000-0-0-000000000erate-auth-data | 2020-10-19T10:30:57Z [ERRO][AMF][Gmm] Nausf_UEAU Authenticate Request Failed: &{Type: Title: Status:500 Detail: InstanceLEM InvalidParams:[]} 2020-10-19T10:31:57Z [INFO][AMF][NGAP] [AMF] Uplink Nas Transport 2020-10-19T10:31:57Z [ERRO][AMF][Gmm] UE state mismatch: receieve gmm message[message type 0x41] at Authentication state 2020-10-19T10:44:37Z [INFO][AMF][NGAP] [AMF] SCTP Accept from: 127.0.0.1/143.110.190.57/10.47.0.5/10.122.0.2/172.17.0.1/192.188.3.1/192.187.3.1:46383 2020-10-19T10:44:38Z [INFO][AMF][NGAP] Create a new NG connection for: 127.0.0.1/143.110.190.57/10.47.0.5/10.122.0.2/172.17.0.1/192.188.3.1/192.187.3.1:46383 2020-10-19T10:44:38Z [INFO][AMF][NGAP] [AMF] NG Setup request 2020-10-19T10:44:38Z [INFO][AMF][NGAP] [AMF] Send NG-Setup response 2020-10-19T10:44:48Z [INFO][AMF][NGAP] [AMF] Initial UE Message 2020-10-19T10:44:48Z [INFO][AMF][Gmm] [AMF] Handle Registration Request 2020-10-19T10:44:48Z [INFO][AMF][Gmm] Authentication procedure 2020-10-19T10:44:48Z [INFO][UDM][UEAU] Handle GenerateAuthDataRequest 2020-10-19T10:44:48Z [INFO][UDM][UEAU] suciPart [suci 0 208 93 0000 0 0 0000000003] 2020-10-19T10:44:48Z [INFO][UDM][UEAU] scheme 0 2020-10-19T10:44:48Z [INFO][UDM][UEAU] SUPI type is IMSI 2020-10-19T10:44:48Z [INFO][UDR][DataRepo] Handle QueryAuthSubsData 2020-10-19T10:44:48Z [INFO][UDR][GIN] | 404 | 127.0.0.1 | GET | /nudr-dr/v1/subscription-data/imsi-208930000000003/authentication-data/authentication-subscription | 2020-10-19T10:44:48Z [ERRO][UDM][UEAU] Return from UDR QueryAuthSubsData error 2020-10-19T10:44:48Z [INFO][UDM][GIN] | 403 | 127.0.0.1 | POST | /nudm-ueau/v1/suci-0-208-93-0000-0-0-0000000003/security-information/generate-auth-data | 2020-10-19T10:44:48Z [ERRO][AMF][Gmm] Nausf_UEAU Authenticate Request Failed: &{Type: Title: Status:500 Detail: Instance: Cause:AV_GENERATION_PROBLEM InvalidParams:[]}

GNB and Imsi Logs

==> logs/gnb-1.log <== [2020-10-19 10:44:38.398] [FUNC_IN] Handling: NGSetupResponse [2020-10-19 10:44:38.399] [SUCCESS] [PROCEDURE_RESULT] NGSetup procedure is successful

==> logs/global.log <== [2020-10-19 10:44:38.400] [SUCCESS] [PROCEDURE_RESULT] [gnb-1] NGSetup procedure is successful

==> logs/gnb-1.log <== [2020-10-19 10:44:38.405] [FUNC_OUT]

==> logs/ue-imsi-208930000000003.log <== [2020-10-19 10:44:48.148] [FUNC_IN] Starting: Registration procedure (initial registration) [2020-10-19 10:44:48.154] [INFO] [STATE] UE switches to state: MM_REGISTERED_INITIATED/MM_REGISTERED_INITIATED__NA [2020-10-19 10:44:48.157] [FUNC_IN] Get or Generate SUCI [2020-10-19 10:44:48.159] [DEBUG] [PROC] T3519 is not running, new SUCI generated. [2020-10-19 10:44:48.159] [DEBUG] [NAS_TIMER] NAS Timer 3519 started with interval: 60s [2020-10-19 10:44:48.160] [FUNC_OUT] [2020-10-19 10:44:48.161] [DEBUG] [NAS_TIMER] NAS Timer 3510 started with interval: 15s [2020-10-19 10:44:48.162] [FUNC_IN] Sending NAS message: RegistrationRequest [2020-10-19 10:44:48.214] [DEBUG] [MESSAGING] Plain NAS as JSON: { "registrationType": { "followOnRequestPending": { "value": 0, "name": "No follow-on request pending" }, "registrationType": { "value": 1, "name": "initial registration" } }, "nasKeySetIdentifier": { "tsc": { "value": 0, "name": "Native security context (for KSI_AMF)" }, "nasKeySetIdentifier": 7 }, "mobileIdentity": { "mcc": { "value": 208, "name": "France" }, "mnc": { "value": 93, "name": "93" }, "routingIndicator": "0000", "protectionSchemaId": { "value": 0, "name": "Null scheme" }, "homeNetworkPublicKeyIdentifier": { "value": "0x00" }, "schemeOutput": "0000000003" }, "ueSecurityCapability": { "supported_5G_EA0": true, "supported_128_5G_EA1": true, "supported_128_5G_EA2": true, "supported_128_5G_EA3": true, "supported_5G_EA4": false, "supported_5G_EA5": false, "supported_5G_EA6": false, "supported_5G_EA7": false, "supported_5G_IA0": true, "supported_128_5G_IA1": true, "supported_128_5G_IA2": true, "supported_128_5G_IA3": true, "supported_5G_IA4": false, "supported_5G_IA5": false, "supported_5G_IA6": false, "supported_5G_IA7": false, "supported_EEA0": true, "supported_128_EEA1": true, "supported_128_EEA2": true, "supported_128_EEA3": true, "supported_EEA4": false, "supported_EEA5": false, "supported_EEA6": false, "supported_EEA7": false, "supported_EIA0": true, "supported_128_EIA1": true, "supported_128_EIA2": true, "supported_128_EIA3": true, "supported_EIA4": false, "supported_EIA5": false, "supported_EIA6": false, "supported_EIA7": false }, "mmCapability": { "s1Mode": { "value": 0, "name": "S1 mode not supported" }, "hoAttach": { "value": 0, "name": "not supported" }, "lpp": { "value": 0, "name": "LPP in N1 mode not supported" } }, "requestedNSSAI": { "sNssais": [ { "sst": { "value": "0x01" }, "sd": { "value": "0x010203" } } ] }, "updateType": { "smsRequested": { "value": 1, "name": "SMS over NAS supported" }, "ngRanRcu": { "value": 0, "name": "NG-RAN radio capability update not needed" } }, "securityHeaderType": { "value": 0, "name": "Plain 5GS NAS message, not security protected" }, "messageType": { "value": 65, "name": "Registration request" }, "extendedProtocolDiscriminator": { "value": 126, "name": "5GS mobility management messages" } } [2020-10-19 10:44:48.216] [DEBUG] [MESSAGING] Plain NAS PDU: 7e004171000d0102f8390000000000000000301001002e04f0f0f0f02f050401010203530101 [2020-10-19 10:44:48.218] [DEBUG] [MESSAGING] Secured NAS as JSON { "registrationType": { "followOnRequestPending": { "value": 0, "name": "No follow-on request pending" }, "registrationType": { "value": 1, "name": "initial registration" } }, "nasKeySetIdentifier": { "tsc": { "value": 0, "name": "Native security context (for KSI_AMF)" }, "nasKeySetIdentifier": 7 }, "mobileIdentity": { "mcc": { "value": 208, "name": "France" }, "mnc": { "value": 93, "name": "93" }, "routingIndicator": "0000", "protectionSchemaId": { "value": 0, "name": "Null scheme" }, "homeNetworkPublicKeyIdentifier": { "value": "0x00" }, "schemeOutput": "0000000003" }, "ueSecurityCapability": { "supported_5G_EA0": true, "supported_128_5G_EA1": true, "supported_128_5G_EA2": true, "supported_128_5G_EA3": true, "supported_5G_EA4": false, "supported_5G_EA5": false, "supported_5G_EA6": false, "supported_5G_EA7": false, "supported_5G_IA0": true, "supported_128_5G_IA1": true, "supported_128_5G_IA2": true, "supported_128_5G_IA3": true, "supported_5G_IA4": false, "supported_5G_IA5": false, "supported_5G_IA6": false, "supported_5G_IA7": false, "supported_EEA0": true, "supported_128_EEA1": true, "supported_128_EEA2": true, "supported_128_EEA3": true, "supported_EEA4": false, "supported_EEA5": false, "supported_EEA6": false, "supported_EEA7": false, "supported_EIA0": true, "supported_128_EIA1": true, "supported_128_EIA2": true, "supported_128_EIA3": true, "supported_EIA4": false, "supported_EIA5": false, "supported_EIA6": false, "supported_EIA7": false }, "mmCapability": { "s1Mode": { "value": 0, "name": "S1 mode not supported" }, "hoAttach": { "value": 0, "name": "not supported" }, "lpp": { "value": 0, "name": "LPP in N1 mode not supported" } }, "requestedNSSAI": { "sNssais": [ { "sst": { "value": "0x01" }, "sd": { "value": "0x010203" } } ] }, "updateType": { "smsRequested": { "value": 1, "name": "SMS over NAS supported" }, "ngRanRcu": { "value": 0, "name": "NG-RAN radio capability update not needed" } }, "securityHeaderType": { "value": 0, "name": "Plain 5GS NAS message, not security protected" }, "messageType": { "value": 65, "name": "Registration request" }, "extendedProtocolDiscriminator": { "value": 126, "name": "5GS mobility management messages" } } [2020-10-19 10:44:48.220] [DEBUG] [MESSAGING] Secured NAS PDU: 7e004171000d0102f8390000000000000000301001002e04f0f0f0f02f050401010203530101 [2020-10-19 10:44:48.225] [FUNC_OUT] [2020-10-19 10:44:48.225] [FUNC_OUT]

==> logs/gnb-1.log <== [2020-10-19 10:44:48.228] [FUNC_IN] Handling Uplink NAS Transport [2020-10-19 10:44:48.261] [DEBUG] [MESSAGING] Sending NGAP: NGAP_InitialUEMessage [2020-10-19 10:44:48.285] [DEBUG] [MESSAGING] {"NGAP-PDU": {"initiatingMessage": { "procedureCode": 15, "criticality": "ignore", "value": {"InitialUEMessage": {"protocolIEs": {"InitialUEMessage-IEs": [ { "criticality": "reject", "id": 85, "value": {"RAN-UE-NGAP-ID": 1} }, { "criticality": "reject", "id": 38, "value": {"NAS-PDU": "7e004171000d0102f8390000000000000000301001002e04f0f0f0f02f050401010203530101"} }, { "criticality": "reject", "id": 121, "value": {"UserLocationInformation": {"userLocationInformationNR": { "timeStamp": "e337eca0", "nR-CGI": { "pLMNIdentity": "02f839", "nRCellIdentity": "000000000000000000000000000000010000" }, "tAI": { "tAC": "000001", "pLMNIdentity": "02f839" } }}} }, { "criticality": "ignore", "id": 90, "value": {"RRCEstablishmentCause": "mo-Data"} } ]}}} }}} [2020-10-19 10:44:48.301] [FUNC_OUT]

==> logs/ue-imsi-208930000000003.log <==

omercello commented 4 years ago

Hello,

Problem may occur since ue is not registered. But please provide more info.

thnx