Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

Sign up for GitHub

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

Jump to bottom

Multiple NS and SMF AMF Errors #43

Closed
minziran opened this issue Mar 9, 2022 · 4 comments
Closed

Multiple NS and SMF AMF Errors #43

minziran opened this issue Mar 9, 2022 · 4 comments

Comments

@minziran
Copy link

minziran commented Mar 9, 2022 •
edited

Hi @abousselmi and @aligungr,
I have created 2 UPFs, one is connected to the DNN1(60.60.0.0/16) the other is connected to the DNN2(10.10.0.0/16)
I did 2 experiments.
In the first one, I created 2 UEs(using UERANSIM), which are assigned to different UPFs. Both uesimtun0 interfaces are up. Their ips are 60.60.0.1 and 10.10.0.1. I can use both interfaces to ping 8.8.8.8. However the two interfaces cannot ping each other.
Then, I did the second one. In the second one, I created 3 UEs. Two of them are assigned to UPF1(DNN1: 60.60.0.1) and the last one is assigned to UPF2(DNN2: 10.10.0.1).
I received the following errors;
From SMF, everything looks good except the highlight errors. Does this error mean the problem is from configuration file?

**[signal SIGSEGV: segmentation violation code=0x1 addr=0x40 pc=0xb3433b]

goroutine 214 [running]:
github.com/free5gc/smf/context.(SMContext).AllocateLocalSEIDForDataPath(0xc0000ee000, 0x0)
/go/src/free5gc/NFs/smf/context/sm_context.go:309 +0x8b
github.com/free5gc/smf/producer.AddPDUSessionAnchorAndULCL(0xc0000ee000, 0x2, 0xc00038dd20, 0x10, 0x10)
/go/src/free5gc/NFs/smf/producer/ulcl_procedure.go:23 +0x2c5
github.com/free5gc/smf/pfcp/handler.HandlePfcpSessionModificationResponse(0xc0004ecc70)
/go/src/free5gc/NFs/smf/pfcp/handler/handler.go:248 +0x6c4
github.com/free5gc/smf/pfcp.Dispatch(0xc0004ecc70)
/go/src/free5gc/NFs/smf/pfcp/dispatcher.go:45 +0x48a
created by github.com/free5gc/smf/pfcp/udp.Run.func1
/go/src/free5gc/NFs/smf/pfcp/udp/udp.go:44 +0x1d1*

2022-03-09T06:46:16Z [INFO][SMF][App] SMF version:
free5GC version: v3.0.6
build time: 2022-03-08T19:56:36Z
commit hash: 46644f0a
commit time: 2021-09-13T13:23:12Z
go version: go1.14.4 linux/amd64
2022-03-09T06:46:16Z [INFO][SMF][Init] SMF Log level is set to [info] level
2022-03-09T06:46:16Z [INFO][LIB][NAS] set log level : info
2022-03-09T06:46:16Z [INFO][LIB][NAS] set report call : false
2022-03-09T06:46:16Z [INFO][LIB][NGAP] set log level : info
2022-03-09T06:46:16Z [INFO][LIB][NGAP] set report call : false
2022-03-09T06:46:16Z [INFO][LIB][Aper] set log level : info
2022-03-09T06:46:16Z [INFO][LIB][Aper] set report call : false
2022-03-09T06:46:16Z [INFO][LIB][Path] set log level : info
2022-03-09T06:46:16Z [INFO][LIB][Path] set report call : false
2022-03-09T06:46:16Z [INFO][LIB][OAPI] set log level : info
2022-03-09T06:46:16Z [INFO][LIB][OAPI] set report call : false
2022-03-09T06:46:16Z [INFO][LIB][PFCP] set log level : info
2022-03-09T06:46:16Z [INFO][LIB][PFCP] set report call : false
2022-03-09T06:46:16Z [INFO][SMF][CFG] SMF config version [1.0.2]
2022-03-09T06:46:16Z [INFO][SMF][CFG] UE-Routing config version [1.0.1]
2022-03-09T06:46:16Z [INFO][SMF][CTX] smfconfig Info: Version[1.0.2] Description[SMF initial local configuration]
2022-03-09T06:46:16Z [INFO][SMF][CTX] Endpoints: [upfb.free5gc.org]
2022-03-09T06:46:16Z [INFO][SMF][CTX] Endpoints: [upf2.free5gc.org]
2022-03-09T06:46:16Z [INFO][SMF][CTX] ue routing config Info: Version[1.0.1] Description[Routing information for UE]
2022-03-09T06:46:16Z [INFO][SMF][PduSess] In NewUEPreConfigPaths
2022-03-09T06:46:16Z [INFO][SMF][PduSess] In NewUEPreConfigPaths
2022-03-09T06:46:16Z [INFO][SMF][Init] Server started
2022-03-09T06:47:16Z [INFO][SMF][Init] SMF Registration to NRF {9dce9f09-8d85-4e59-988e-a2883ff8e784 SMF REGISTERED 0 0xc000388d00 0xc000388d40 [] [] [smf.free5gc.org] [] [] [] 0 0 0 area1 0xc0003906c0 map[] false 0xc0003882c0 false false []}
2022-03-09T06:47:16Z [INFO][SMF][PFCP] Listen on 10.100.200.10:8805
2022-03-09T06:47:16Z [INFO][SMF][App] Send PFCP Association Request to UPFupfb.free5gc.org
2022-03-09T06:47:16Z [INFO][SMF][App] Send PFCP Association Request to UPFupf2.free5gc.org
2022-03-09T06:47:16Z [INFO][LIB][PFCP] Remove Request Transaction [1]
2022-03-09T06:47:16Z [INFO][SMF][PFCP] In HandlePfcpAssociationSetupResponse
2022-03-09T06:47:16Z [INFO][SMF][PFCP] Handle PFCP Association Setup Response with NodeID[10.100.200.200]
2022-03-09T06:47:16Z [INFO][SMF][PFCP] In HandlePfcpAssociationSetupResponse
2022-03-09T06:47:16Z [INFO][SMF][PFCP] Handle PFCP Association Setup Response with NodeID[10.100.200.202]
2022-03-09T06:47:16Z [INFO][LIB][PFCP] Remove Request Transaction [2]
2022-03-09T06:47:16Z [INFO][SMF][PFCP] UPF(10.100.200.200)[internet] setup association
2022-03-09T06:47:16Z [INFO][SMF][PFCP] UPF(10.100.200.202)[internet2] setup association
2022-03-09T06:51:10Z [INFO][SMF][PduSess] Recieve Create SM Context Request
2022-03-09T06:51:10Z [INFO][SMF][PduSess] In HandlePDUSessionSMContextCreate
2022-03-09T06:51:10Z [INFO][SMF][PduSess] Send NF Discovery Serving UDM Successfully
2022-03-09T06:51:10Z [INFO][SMF][CTX] Allocated UE IP address: 60.60.0.1
2022-03-09T06:51:10Z [INFO][SMF][CTX] Selected UPF: BranchingUPF
2022-03-09T06:51:10Z [INFO][SMF][GSM] In HandlePDUSessionEstablishmentRequest
2022-03-09T06:51:10Z [INFO][NAS][Convert] ProtocolOrContainerList: [0xc00025d140 0xc00025d180]
2022-03-09T06:51:10Z [INFO][SMF][GSM] Protocol Configuration Options
2022-03-09T06:51:10Z [INFO][SMF][GSM] &{[0xc00025d140 0xc00025d180]}
2022-03-09T06:51:10Z [INFO][SMF][GSM] Didn't Implement container type IPAddressAllocationViaNASSignallingUL
2022-03-09T06:51:10Z [INFO][SMF][PduSess] PCF Selection for SMContext SUPI[imsi-208930000000003] PDUSessionID[1]
2022-03-09T06:51:10Z [INFO][SMF][PduSess] SUPI[imsi-208930000000003] has pre-config route
2022-03-09T06:51:10Z [INFO][SMF][Consumer] SendNFDiscoveryServingAMF ok
2022-03-09T06:51:10Z [INFO][SMF][GIN] | 201 | 10.100.200.102 | POST | /nsmf-pdusession/v1/sm-contexts |
2022-03-09T06:51:10Z [INFO][SMF][PFCP] In HandlePfcpSessionEstablishmentResponse
2022-03-09T06:51:10Z [INFO][LIB][PFCP] Remove Request Transaction [3]
&{200 Mbps 100 Mbps}
2022-03-09T06:51:10Z [INFO][SMF][PduSess] Recieve Update SM Context Request
2022-03-09T06:51:10Z [INFO][SMF][PduSess] In HandlePDUSessionSMContextUpdate
2022-03-09T06:51:10Z [INFO][SMF][PFCP] In HandlePfcpSessionModificationResponse
2022-03-09T06:51:10Z [INFO][SMF][PduSess] [SMF] PFCP Modification Resonse Accept
2022-03-09T06:51:10Z [INFO][LIB][PFCP] Remove Request Transaction [4]
2022-03-09T06:51:10Z [INFO][SMF][PFCP] Add PSAAndULCL
panic: runtime error: invalid memory address or nil pointer dereference
**[signal SIGSEGV: segmentation violation code=0x1 addr=0x40 pc=0xb3433b]

goroutine 214 [running]:
github.com/free5gc/smf/context.(SMContext).AllocateLocalSEIDForDataPath(0xc0000ee000, 0x0)
/go/src/free5gc/NFs/smf/context/sm_context.go:309 +0x8b
github.com/free5gc/smf/producer.AddPDUSessionAnchorAndULCL(0xc0000ee000, 0x2, 0xc00038dd20, 0x10, 0x10)
/go/src/free5gc/NFs/smf/producer/ulcl_procedure.go:23 +0x2c5
github.com/free5gc/smf/pfcp/handler.HandlePfcpSessionModificationResponse(0xc0004ecc70)
/go/src/free5gc/NFs/smf/pfcp/handler/handler.go:248 +0x6c4
github.com/free5gc/smf/pfcp.Dispatch(0xc0004ecc70)
/go/src/free5gc/NFs/smf/pfcp/dispatcher.go:45 +0x48a
created by github.com/free5gc/smf/pfcp/udp.Run.func1
/go/src/free5gc/NFs/smf/pfcp/udp/udp.go:44 +0x1d1*

From the AMF, the imsi-208930000000003(assigned to UPF1(DNN1:60.60.0.0)) seems good. I can use the uesimtun0 infeterface. However, it still shows the highlight error. The imsi-208930000000003 failed. I have no clue where the problem is.

2022-03-09T06:51:10Z [ERRO][AMF][NGAP][10.100.200.1:53492][AMF_UE_NGAP_ID:1] SendUpdateSmContextN2Info[PDUSessionResourceSetupResponseTransfer] Error: server no response

2022-03-09T06:46:16Z [INFO][AMF][App] amf
2022-03-09T06:46:16Z [INFO][AMF][App] AMF version:
free5GC version: v3.0.6
build time: 2022-03-08T19:56:06Z
commit hash: b3e99f97
commit time: 2021-09-24T09:44:32Z
go version: go1.14.4 linux/amd64
2022-03-09T06:46:16Z [INFO][AMF][Init] AMF Log level is set to [info] level
2022-03-09T06:46:16Z [INFO][LIB][NAS] set log level : info
2022-03-09T06:46:16Z [INFO][LIB][NAS] set report call : false
2022-03-09T06:46:16Z [INFO][LIB][NGAP] set log level : info
2022-03-09T06:46:16Z [INFO][LIB][NGAP] set report call : false
2022-03-09T06:46:16Z [INFO][LIB][FSM] set log level : info
2022-03-09T06:46:16Z [INFO][LIB][FSM] set report call : false
2022-03-09T06:46:16Z [INFO][LIB][Aper] set log level : info
2022-03-09T06:46:16Z [INFO][LIB][Aper] set report call : false
2022-03-09T06:46:16Z [INFO][LIB][Path] set log level : info
2022-03-09T06:46:16Z [INFO][LIB][Path] set report call : false
2022-03-09T06:46:16Z [INFO][LIB][OAPI] set log level : info
2022-03-09T06:46:16Z [INFO][LIB][OAPI] set report call : false
2022-03-09T06:46:16Z [INFO][AMF][CFG] config version [1.0.2]
2022-03-09T06:46:16Z [INFO][AMF][Init] Server started
2022-03-09T06:46:16Z [INFO][AMF][Util] amfconfig Info: Version[1.0.2] Description[AMF initial local configuration]
2022-03-09T06:46:16Z [INFO][AMF][NGAP] Listen on 10.100.200.102:38412
2022-03-09T06:46:47Z [INFO][AMF][NGAP] [AMF] SCTP Accept from: 10.100.200.1:53492
2022-03-09T06:46:47Z [INFO][AMF][NGAP] Create a new NG connection for: 10.100.200.1:53492
2022-03-09T06:46:47Z [INFO][AMF][NGAP][10.100.200.1:53492] Handle NG Setup request
2022-03-09T06:46:47Z [INFO][AMF][NGAP][10.100.200.1:53492] Send NG-Setup response
2022-03-09T06:47:03Z [INFO][AMF][NGAP] [AMF] SCTP Accept from: 10.100.200.11/10.0.0.1:54371
2022-03-09T06:47:03Z [INFO][AMF][NGAP] Create a new NG connection for: 10.100.200.11/10.0.0.1:54371
2022-03-09T06:47:03Z [INFO][AMF][NGAP][10.100.200.11/10.0.0.1:54371] Handle NG Setup request
2022-03-09T06:47:03Z [INFO][AMF][NGAP][10.100.200.11/10.0.0.1:54371] Send NG-Setup response
2022-03-09T06:51:10Z [INFO][AMF][NGAP][10.100.200.1:53492] Handle Initial UE Message
2022-03-09T06:51:10Z [INFO][AMF][GMM][AMF_UE_NGAP_ID:1] Handle Registration Request
2022-03-09T06:51:10Z [INFO][AMF][GMM][AMF_UE_NGAP_ID:1] Authentication procedure
2022-03-09T06:51:10Z [INFO][AMF][GMM][AMF_UE_NGAP_ID:1] Send Authentication Request
2022-03-09T06:51:10Z [INFO][AMF][NGAP][10.100.200.1:53492][AMF_UE_NGAP_ID:1] Send Downlink Nas Transport
2022-03-09T06:51:10Z [INFO][AMF][NGAP][10.100.200.1:53492] Handle Uplink Nas Transport
2022-03-09T06:51:10Z [INFO][AMF][NGAP][10.100.200.1:53492][AMF_UE_NGAP_ID:1] Uplink NAS Transport (RAN UE NGAP ID: 1)
2022-03-09T06:51:10Z [INFO][AMF][GMM][AMF_UE_NGAP_ID:1] Handle Authentication Response
2022-03-09T06:51:10Z [INFO][AMF][GMM][AMF_UE_NGAP_ID:1][SUPI:imsi-208930000000003] Send Security Mode Command
2022-03-09T06:51:10Z [INFO][AMF][NGAP][10.100.200.1:53492][AMF_UE_NGAP_ID:1] Send Downlink Nas Transport
2022-03-09T06:51:10Z [INFO][AMF][NGAP][10.100.200.1:53492] Handle Uplink Nas Transport
2022-03-09T06:51:10Z [INFO][AMF][NGAP][10.100.200.1:53492][AMF_UE_NGAP_ID:1] Uplink NAS Transport (RAN UE NGAP ID: 1)
2022-03-09T06:51:10Z [INFO][AMF][GMM][AMF_UE_NGAP_ID:1][SUPI:imsi-208930000000003] Handle Security Mode Complete
2022-03-09T06:51:10Z [INFO][AMF][GMM][AMF_UE_NGAP_ID:1][SUPI:imsi-208930000000003] Handle InitialRegistration
2022-03-09T06:51:10Z [INFO][AMF][GMM][AMF_UE_NGAP_ID:1][SUPI:imsi-208930000000003] RequestedNssai - ServingSnssai: &{Sst:1 Sd:010203}, HomeSnssai:
2022-03-09T06:51:10Z [INFO][AMF][Comm] Handle AMF Status Change Subscribe Request
2022-03-09T06:51:10Z [INFO][AMF][Comm] new AMF Status Subscription[1]
2022-03-09T06:51:10Z [INFO][AMF][GIN] | 201 | 10.100.200.7 | POST | /namf-comm/v1/subscriptions |
2022-03-09T06:51:10Z [INFO][AMF][GMM][AMF_UE_NGAP_ID:1][SUPI:imsi-208930000000003] Send Registration Accept
2022-03-09T06:51:10Z [INFO][AMF][NGAP][10.100.200.1:53492][AMF_UE_NGAP_ID:1] Send Initial Context Setup Request
2022-03-09T06:51:10Z [INFO][AMF][NGAP][10.100.200.1:53492] Handle Initial Context Setup Response
2022-03-09T06:51:10Z [INFO][AMF][NGAP][10.100.200.1:53492] Handle Uplink Nas Transport
2022-03-09T06:51:10Z [INFO][AMF][NGAP][10.100.200.1:53492][AMF_UE_NGAP_ID:1] Uplink NAS Transport (RAN UE NGAP ID: 1)
2022-03-09T06:51:10Z [INFO][AMF][GMM][AMF_UE_NGAP_ID:1][SUPI:imsi-208930000000003] Handle Registration Complete
2022-03-09T06:51:10Z [INFO][AMF][NGAP][10.100.200.1:53492] Handle Uplink Nas Transport
2022-03-09T06:51:10Z [INFO][AMF][NGAP][10.100.200.1:53492][AMF_UE_NGAP_ID:1] Uplink NAS Transport (RAN UE NGAP ID: 1)
2022-03-09T06:51:10Z [INFO][AMF][GMM][AMF_UE_NGAP_ID:1][SUPI:imsi-208930000000003] Handle UL NAS Transport
2022-03-09T06:51:10Z [INFO][AMF][GMM][AMF_UE_NGAP_ID:1][SUPI:imsi-208930000000003] Transport 5GSM Message to SMF
2022-03-09T06:51:10Z [INFO][AMF][GMM][AMF_UE_NGAP_ID:1][SUPI:imsi-208930000000003] Select SMF [snssai: {Sst:1 Sd:010203}, dnn: internet]
2022-03-09T06:51:10Z [INFO][AMF][GMM][AMF_UE_NGAP_ID:1][SUPI:imsi-208930000000003] create smContext[pduSessionID: 1] Success
2022-03-09T06:51:10Z [INFO][AMF][Producer] Handle N1N2 Message Transfer Request
2022-03-09T06:51:10Z [INFO][AMF][NGAP][10.100.200.1:53492][AMF_UE_NGAP_ID:1] Send PDU Session Resource Setup Request
2022-03-09T06:51:10Z [INFO][AMF][GIN] | 200 | 10.100.200.10 | POST | /namf-comm/v1/ue-contexts/imsi-208930000000003/n1-n2-messages |
2022-03-09T06:51:10Z [INFO][AMF][NGAP][10.100.200.1:53492] Handle PDU Session Resource Setup Response
2022-03-09T06:51:10Z [ERRO][AMF][NGAP][10.100.200.1:53492][AMF_UE_NGAP_ID:1] SendUpdateSmContextN2Info[PDUSessionResourceSetupResponseTransfer] Error: server no response
2022-03-09T06:51:20Z [INFO][AMF][NGAP][10.100.200.1:53492] Handle Initial UE Message
2022-03-09T06:51:20Z [INFO][AMF][GMM][AMF_UE_NGAP_ID:2] Handle Registration Request
2022-03-09T06:51:20Z [INFO][AMF][GMM][AMF_UE_NGAP_ID:2] Authentication procedure
2022-03-09T06:51:20Z [INFO][AMF][GMM][AMF_UE_NGAP_ID:2] Send Authentication Request
2022-03-09T06:51:20Z [INFO][AMF][NGAP][10.100.200.1:53492][AMF_UE_NGAP_ID:2] Send Downlink Nas Transport
2022-03-09T06:51:20Z [INFO][AMF][NGAP][10.100.200.1:53492] Handle Uplink Nas Transport
2022-03-09T06:51:20Z [INFO][AMF][NGAP][10.100.200.1:53492][AMF_UE_NGAP_ID:2] Uplink NAS Transport (RAN UE NGAP ID: 2)
2022-03-09T06:51:20Z [INFO][AMF][GMM][AMF_UE_NGAP_ID:2] Handle Authentication Response
2022-03-09T06:51:20Z [INFO][AMF][GMM][AMF_UE_NGAP_ID:2][SUPI:imsi-208930000000004] Send Security Mode Command
2022-03-09T06:51:20Z [INFO][AMF][NGAP][10.100.200.1:53492][AMF_UE_NGAP_ID:2] Send Downlink Nas Transport
2022-03-09T06:51:20Z [INFO][AMF][NGAP][10.100.200.1:53492] Handle Uplink Nas Transport
2022-03-09T06:51:20Z [INFO][AMF][NGAP][10.100.200.1:53492][AMF_UE_NGAP_ID:2] Uplink NAS Transport (RAN UE NGAP ID: 2)
2022-03-09T06:51:20Z [INFO][AMF][GMM][AMF_UE_NGAP_ID:2][SUPI:imsi-208930000000004] Handle Security Mode Complete
2022-03-09T06:51:20Z [INFO][AMF][GMM][AMF_UE_NGAP_ID:2][SUPI:imsi-208930000000004] Handle InitialRegistration
2022-03-09T06:51:20Z [INFO][AMF][GMM][AMF_UE_NGAP_ID:2][SUPI:imsi-208930000000004] RequestedNssai - ServingSnssai: &{Sst:1 Sd:112233}, HomeSnssai:
2022-03-09T06:51:20Z [INFO][AMF][GMM][AMF_UE_NGAP_ID:2][SUPI:imsi-208930000000004] Send Registration Accept
2022-03-09T06:51:20Z [INFO][AMF][NGAP][10.100.200.1:53492][AMF_UE_NGAP_ID:2] Send Initial Context Setup Request
2022-03-09T06:51:20Z [INFO][AMF][NGAP][10.100.200.1:53492] Handle Initial Context Setup Response
2022-03-09T06:51:20Z [INFO][AMF][NGAP][10.100.200.1:53492] Handle Uplink Nas Transport
2022-03-09T06:51:20Z [INFO][AMF][NGAP][10.100.200.1:53492][AMF_UE_NGAP_ID:2] Uplink NAS Transport (RAN UE NGAP ID: 2)
2022-03-09T06:51:20Z [INFO][AMF][GMM][AMF_UE_NGAP_ID:2][SUPI:imsi-208930000000004] Handle Registration Complete
2022-03-09T06:51:20Z [INFO][AMF][NGAP][10.100.200.1:53492] Handle Uplink Nas Transport
2022-03-09T06:51:20Z [INFO][AMF][NGAP][10.100.200.1:53492][AMF_UE_NGAP_ID:2] Uplink NAS Transport (RAN UE NGAP ID: 2)
2022-03-09T06:51:20Z [INFO][AMF][GMM][AMF_UE_NGAP_ID:2][SUPI:imsi-208930000000004] Handle UL NAS Transport
2022-03-09T06:51:20Z [INFO][AMF][GMM][AMF_UE_NGAP_ID:2][SUPI:imsi-208930000000004] Transport 5GSM Message to SMF
2022-03-09T06:51:20Z [INFO][AMF][GMM][AMF_UE_NGAP_ID:2][SUPI:imsi-208930000000004] Select SMF [snssai: {Sst:1 Sd:112233}, dnn: internet2]
2022-03-09T06:51:20Z [ERRO][AMF][GMM][AMF_UE_NGAP_ID:2][SUPI:imsi-208930000000004] CreateSmContextRequest Error: server no response
2022-03-09T06:51:30Z [INFO][AMF][NGAP][10.100.200.1:53492] Handle Initial UE Message
2022-03-09T06:51:30Z [INFO][AMF][GMM][AMF_UE_NGAP_ID:3] Handle Registration Request
2022-03-09T06:51:30Z [INFO][AMF][GMM][AMF_UE_NGAP_ID:3] Authentication procedure
2022-03-09T06:51:30Z [INFO][AMF][GMM][AMF_UE_NGAP_ID:3] Send Authentication Request
2022-03-09T06:51:30Z [INFO][AMF][NGAP][10.100.200.1:53492][AMF_UE_NGAP_ID:3] Send Downlink Nas Transport
2022-03-09T06:51:30Z [INFO][AMF][NGAP][10.100.200.1:53492] Handle Uplink Nas Transport
2022-03-09T06:51:30Z [INFO][AMF][NGAP][10.100.200.1:53492][AMF_UE_NGAP_ID:3] Uplink NAS Transport (RAN UE NGAP ID: 3)
2022-03-09T06:51:30Z [INFO][AMF][GMM][AMF_UE_NGAP_ID:3] Handle Authentication Failure
2022-03-09T06:51:30Z [WARN][AMF][GMM][AMF_UE_NGAP_ID:3] Authentication Failure 5GMM Cause: Synch Failure
2022-03-09T06:51:30Z [ERRO][AMF][GMM][AMF_UE_NGAP_ID:3] Nausf_UEAU Authenticate Request Error[Problem Detail: &{Type: Title: Status:500 Detail: Instance: Cause:AV_GENERATION_PROBLEM InvalidParams:[]}]
2022-03-09T06:51:37Z [INFO][AMF][NGAP][10.100.200.1:53492] Handle Uplink Nas Transport
2022-03-09T06:51:37Z [INFO][AMF][NGAP][10.100.200.1:53492][AMF_UE_NGAP_ID:2] Uplink NAS Transport (RAN UE NGAP ID: 2)
2022-03-09T06:51:37Z [INFO][AMF][GMM][AMF_UE_NGAP_ID:2][SUPI:imsi-208930000000004] Handle UL NAS Transport
2022-03-09T06:51:37Z [INFO][AMF][GMM][AMF_UE_NGAP_ID:2][SUPI:imsi-208930000000004] Transport 5GSM Message to SMF
2022-03-09T06:51:37Z [INFO][AMF][GMM][AMF_UE_NGAP_ID:2][SUPI:imsi-208930000000004] Select SMF [snssai: {Sst:1 Sd:112233}, dnn: internet2]
2022-03-09T06:51:37Z [ERRO][AMF][GMM][AMF_UE_NGAP_ID:2][SUPI:imsi-208930000000004] CreateSmContextRequest Error: server no response
2022-03-09T06:51:53Z [INFO][AMF][NGAP][10.100.200.1:53492] Handle Uplink Nas Transport
2022-03-09T06:51:53Z [INFO][AMF][NGAP][10.100.200.1:53492][AMF_UE_NGAP_ID:2] Uplink NAS Transport (RAN UE NGAP ID: 2)
2022-03-09T06:51:53Z [INFO][AMF][GMM][AMF_UE_NGAP_ID:2][SUPI:imsi-208930000000004] Handle UL NAS Transport
2022-03-09T06:51:53Z [INFO][AMF][GMM][AMF_UE_NGAP_ID:2][SUPI:imsi-208930000000004] Transport 5GSM Message to SMF
2022-03-09T06:51:53Z [INFO][AMF][GMM][AMF_UE_NGAP_ID:2][SUPI:imsi-208930000000004] Select SMF [snssai: {Sst:1 Sd:112233}, dnn: internet2]
2022-03-09T06:52:20Z [ERRO][AMF][GMM][AMF_UE_NGAP_ID:2][SUPI:imsi-208930000000004] CreateSmContextRequest Error: server no response
2022-03-09T06:52:20Z [INFO][AMF][NGAP][10.100.200.1:53492] Handle Initial UE Message
2022-03-09T06:52:20Z [INFO][AMF][GMM][AMF_UE_NGAP_ID:4] Handle Registration Request
2022-03-09T06:52:20Z [INFO][AMF][GMM][AMF_UE_NGAP_ID:4] Authentication procedure
2022-03-09T06:52:20Z [INFO][AMF][GMM][AMF_UE_NGAP_ID:4] Send Authentication Request
2022-03-09T06:52:20Z [INFO][AMF][NGAP][10.100.200.1:53492][AMF_UE_NGAP_ID:4] Send Downlink Nas Transport
2022-03-09T06:52:20Z [INFO][AMF][NGAP][10.100.200.1:53492] Handle Uplink Nas Transport
2022-03-09T06:52:20Z [INFO][AMF][NGAP][10.100.200.1:53492][AMF_UE_NGAP_ID:2] Uplink NAS Transport (RAN UE NGAP ID: 2)
2022-03-09T06:52:20Z [INFO][AMF][GMM][AMF_UE_NGAP_ID:2][SUPI:imsi-208930000000004] Handle UL NAS Transport
2022-03-09T06:52:20Z [INFO][AMF][GMM][AMF_UE_NGAP_ID:2][SUPI:imsi-208930000000004] Transport 5GSM Message to SMF
2022-03-09T06:52:20Z [INFO][AMF][GMM][AMF_UE_NGAP_ID:2][SUPI:imsi-208930000000004] Select SMF [snssai: {Sst:1 Sd:112233}, dnn: internet2]
2022-03-09T06:52:20Z [ERRO][AMF][GMM][AMF_UE_NGAP_ID:2][SUPI:imsi-208930000000004] CreateSmContextRequest Error: server no response
2022-03-09T06:52:20Z [INFO][AMF][NGAP][10.100.200.1:53492] Handle Initial UE Message
2022-03-09T06:52:20Z [INFO][AMF][GMM][AMF_UE_NGAP_ID:5] Handle Registration Request
2022-03-09T06:52:20Z [INFO][AMF][GMM][AMF_UE_NGAP_ID:5] Authentication procedure
2022-03-09T06:52:20Z [INFO][AMF][GMM][AMF_UE_NGAP_ID:5] Send Authentication Request
2022-03-09T06:52:20Z [INFO][AMF][NGAP][10.100.200.1:53492][AMF_UE_NGAP_ID:5] Send Downlink Nas Transport
2022-03-09T06:52:20Z [INFO][AMF][NGAP][10.100.200.1:53492] Handle Uplink Nas Transport
2022-03-09T06:52:20Z [INFO][AMF][NGAP][10.100.200.1:53492][AMF_UE_NGAP_ID:5] Uplink NAS Transport (RAN UE NGAP ID: 5)
2022-03-09T06:52:20Z [INFO][AMF][GMM][AMF_UE_NGAP_ID:5] Handle Authentication Failure
2022-03-09T06:52:20Z [WARN][AMF][GMM][AMF_UE_NGAP_ID:5] Authentication Failure 5GMM Cause: Synch Failure
2022-03-09T06:52:20Z [ERRO][AMF][GMM][AMF_UE_NGAP_ID:5] Nausf_UEAU Authenticate Request Error[Problem Detail: &{Type: Title: Status:500 Detail: Instance: Cause:AV_GENERATION_PROBLEM InvalidParams:[]}]
2022-03-09T06:52:25Z [INFO][AMF][NGAP][10.100.200.1:53492] Handle Uplink Nas Transport
2022-03-09T06:52:25Z [INFO][AMF][NGAP][10.100.200.1:53492][AMF_UE_NGAP_ID:2] Uplink NAS Transport (RAN UE NGAP ID: 2)
2022-03-09T06:52:25Z [INFO][AMF][GMM][AMF_UE_NGAP_ID:2][SUPI:imsi-208930000000004] Handle UL NAS Transport
2022-03-09T06:52:25Z [INFO][AMF][GMM][AMF_UE_NGAP_ID:2][SUPI:imsi-208930000000004] Transport 5GSM Message to SMF
2022-03-09T06:52:25Z [INFO][AMF][GMM][AMF_UE_NGAP_ID:2][SUPI:imsi-208930000000004] Select SMF [snssai: {Sst:1 Sd:112233}, dnn: internet2]
2022-03-09T06:52:25Z [INFO][AMF][GMM][AMF_UE_NGAP_ID:2][SUPI:imsi-208930000000004] create smContext[pduSessionID: 1] Success
2022-03-09T06:52:25Z [INFO][AMF][Producer] Handle N1N2 Message Transfer Request
2022-03-09T06:52:25Z [INFO][AMF][NGAP][10.100.200.1:53492][AMF_UE_NGAP_ID:2] Send PDU Session Resource Setup Request
2022-03-09T06:52:25Z [INFO][AMF][GIN] | 200 | 10.100.200.10 | POST | /namf-comm/v1/ue-contexts/imsi-208930000000004/n1-n2-messages |
2022-03-09T06:52:25Z [INFO][AMF][NGAP][10.100.200.1:53492] Handle PDU Session Resource Setup Response
2022-03-09T06:52:25Z [ERRO][AMF][NGAP][10.100.200.1:53492][AMF_UE_NGAP_ID:2] SendUpdateSmContextN2Info[PDUSessionResourceSetupResponseTransfer] Error: server no response
2022-03-09T06:52:26Z [WARN][AMF][GMM][AMF_UE_NGAP_ID:4] T3560 expires, retransmit Authentication Request (retry: 1)
2022-03-09T06:52:26Z [INFO][AMF][NGAP][10.100.200.1:53492][AMF_UE_NGAP_ID:4] Send Downlink Nas Transport
2022-03-09T06:52:32Z [WARN][AMF][GMM][AMF_UE_NGAP_ID:4] T3560 expires, retransmit Authentication Request (retry: 2)
2022-03-09T06:52:32Z [INFO][AMF][NGAP][10.100.200.1:53492][AMF_UE_NGAP_ID:4] Send Downlink Nas Transport
2022-03-09T06:52:38Z [WARN][AMF][GMM][AMF_UE_NGAP_ID:4] T3560 expires, retransmit Authentication Request (retry: 3)
2022-03-09T06:52:38Z [INFO][AMF][NGAP][10.100.200.1:53492][AMF_UE_NGAP_ID:4] Send Downlink Nas Transport
2022-03-09T06:52:44Z [WARN][AMF][GMM][AMF_UE_NGAP_ID:4] T3560 expires, retransmit Authentication Request (retry: 4)
2022-03-09T06:52:44Z [INFO][AMF][NGAP][10.100.200.1:53492][AMF_UE_NGAP_ID:4] Send Downlink Nas Transport
2022-03-09T06:52:45Z [INFO][AMF][NGAP][10.100.200.1:53492] Handle Initial UE Message
2022-03-09T06:52:45Z [INFO][AMF][GMM][AMF_UE_NGAP_ID:6] Handle Registration Request
2022-03-09T06:52:45Z [INFO][AMF][GMM][AMF_UE_NGAP_ID:6] Authentication procedure
2022-03-09T06:52:45Z [INFO][AMF][GMM][AMF_UE_NGAP_ID:6] Send Authentication Request
2022-03-09T06:52:45Z [INFO][AMF][NGAP][10.100.200.1:53492][AMF_UE_NGAP_ID:6] Send Downlink Nas Transport
2022-03-09T06:52:45Z [INFO][AMF][NGAP][10.100.200.1:53492] Handle Uplink Nas Transport
2022-03-09T06:52:45Z [INFO][AMF][NGAP][10.100.200.1:53492][AMF_UE_NGAP_ID:6] Uplink NAS Transport (RAN UE NGAP ID: 6)
2022-03-09T06:52:45Z [INFO][AMF][GMM][AMF_UE_NGAP_ID:6] Handle Authentication Failure
2022-03-09T06:52:45Z [WARN][AMF][GMM][AMF_UE_NGAP_ID:6] Authentication Failure 5GMM Cause: Synch Failure
2022-03-09T06:52:45Z [ERRO][AMF][GMM][AMF_UE_NGAP_ID:6] Nausf_UEAU Authenticate Request Error[Problem Detail: &{Type: Title: Status:500 Detail: Instance: Cause:AV_GENERATION_PROBLEM InvalidParams:[]}]
2022-03-09T06:52:50Z [WARN][AMF][GMM][AMF_UE_NGAP_ID:4] T3560 Expires 4 times, abort authentication procedure & ongoing 5GMM procedure
2022-03-09T06:53:10Z [INFO][AMF][NGAP][10.100.200.1:53492] Handle Initial UE Message
2022-03-09T06:53:10Z [INFO][AMF][GMM][AMF_UE_NGAP_ID:7] Handle Registration Request
2022-03-09T06:53:10Z [INFO][AMF][GMM][AMF_UE_NGAP_ID:7] Authentication procedure
2022-03-09T06:53:10Z [INFO][AMF][GMM][AMF_UE_NGAP_ID:7] Send Authentication Request
2022-03-09T06:53:10Z [INFO][AMF][NGAP][10.100.200.1:53492][AMF_UE_NGAP_ID:7] Send Downlink Nas Transport
2022-03-09T06:53:16Z [WARN][AMF][GMM][AMF_UE_NGAP_ID:7] T3560 expires, retransmit Authentication Request (retry: 1)
2022-03-09T06:53:16Z [INFO][AMF][NGAP][10.100.200.1:53492][AMF_UE_NGAP_ID:7] Send Downlink Nas Transport
2022-03-09T06:53:22Z [WARN][AMF][GMM][AMF_UE_NGAP_ID:7] T3560 expires, retransmit Authentication Request (retry: 2)
2022-03-09T06:53:22Z [INFO][AMF][NGAP][10.100.200.1:53492][AMF_UE_NGAP_ID:7] Send Downlink Nas Transport
2022-03-09T06:53:28Z [WARN][AMF][GMM][AMF_UE_NGAP_ID:7] T3560 expires, retransmit Authentication Request (retry: 3)
2022-03-09T06:53:28Z [INFO][AMF][NGAP][10.100.200.1:53492][AMF_UE_NGAP_ID:7] Send Downlink Nas Transport
2022-03-09T06:53:34Z [WARN][AMF][GMM][AMF_UE_NGAP_ID:7] T3560 expires, retransmit Authentication Request (retry: 4)
2022-03-09T06:53:34Z [INFO][AMF][NGAP][10.100.200.1:53492][AMF_UE_NGAP_ID:7] Send Downlink Nas Transport
2022-03-09T06:53:40Z [WARN][AMF][GMM][AMF_UE_NGAP_ID:7] T3560 Expires 4 times, abort authentication procedure & ongoing 5GMM procedure

Please let me know if I didn't explain myself well.
I would appreciate any comments and advice.
Thank you in advance!
@nathalie21005
Copy link

Hi @minziran , did you manage to fix this error?

Thank you

@abousselmi
Copy link
Collaborator

Hi @minziran ,

Regarding UEs not being able to ping each other, I think thats normal because their IP addresses are not routable. You need to confugure manually some routes to be able to do that.

Regarding the SMF error, I think you thought right, it's probably a config error.

For the last error on the AMF, it looks like the UEs are crashing. Did you check the logs on the UERANSIM container?

Regards,

@abousselmi
Copy link
Collaborator

No updates, closing the issue.

@free5gc-org
Copy link
Contributor

This issue has been mentioned on free5GC. There might be relevant details there:

http://forum.free5gc.org/t/erro-udr-drepo-queryauthsubsdataprocedure-err-erro-udm-ueau-return-from-udr-queryauthsubsdata-error/2013/3

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Assignees
No one assigned
Labels
None yet
Projects
None yet
Milestone
No milestone
Development

No branches or pull requests
4 participants
@abousselmi @nathalie21005 @minziran @free5gc-org