Unable to establish PDU session, [WARN][AMF][GMM][AMF_UE_NGAP_ID:1][SUPI:imsi-208930000000003] Duplicated PDU session ID[1]
Shivasai011 opened this issue · 0 comments
UE was connected able to see in free5gc GUI, Tunnel IP was assigned but PDU sessions was not establishing.
AMF logs:
root@free:~# kubectl logs core-free5gc-amf-amf-0
Defaulted container "amf" out of: amf, wait-nrf (init)
2023-07-04T15:51:53Z [INFO][AMF][CFG] config version [1.0.3]
2023-07-04T15:51:53Z [INFO][AMF][Init] AMF Log level is set to [info] level
2023-07-04T15:51:53Z [INFO][LIB][NAS] set log level : info
2023-07-04T15:51:53Z [INFO][LIB][NAS] set report call : false
2023-07-04T15:51:53Z [INFO][LIB][NGAP] set log level : info
2023-07-04T15:51:53Z [INFO][LIB][NGAP] set report call : false
2023-07-04T15:51:53Z [INFO][LIB][FSM] set log level : info
2023-07-04T15:51:53Z [INFO][LIB][FSM] set report call : false
2023-07-04T15:51:53Z [INFO][LIB][Aper] set log level : info
2023-07-04T15:51:53Z [INFO][LIB][Aper] set report call : false
2023-07-04T15:51:53Z [INFO][AMF][App] amf
2023-07-04T15:51:53Z [INFO][AMF][App] AMF version:
free5GC version: v3.1.1
build time: 2022-04-28T12:50:19Z
commit hash: 03f9848e
commit time: 2022-04-07T14:37:30Z
go version: go1.14.4 linux/amd64
2023-07-04T15:51:53Z [INFO][AMF][Init] Server started
2023-07-04T15:51:53Z [INFO][AMF][Util] amfconfig Info: Version[1.0.3] Description[AMF initial local configuration]
2023-07-04T15:51:53Z [INFO][AMF][NGAP] Listen on 10.244.120.89:38412
2023-07-04T15:52:50Z [INFO][AMF][Producer] [OAM] Handle Registered UE Context
2023-07-04T15:52:50Z [INFO][AMF][GIN] | 200 | 10.244.120.116 | GET | /namf-oam/v1/registered-ue-context |
2023-07-04T15:53:08Z [INFO][AMF][NGAP] [AMF] SCTP Accept from: 10.244.120.103:52107
2023-07-04T15:53:08Z [INFO][AMF][NGAP] Create a new NG connection for: 10.244.120.103:52107
2023-07-04T15:53:08Z [INFO][AMF][NGAP][10.244.120.103:52107] Handle NG Setup request
2023-07-04T15:53:08Z [INFO][AMF][NGAP][10.244.120.103:52107] Send NG-Setup response
2023-07-04T15:53:10Z [INFO][AMF][NGAP][10.244.120.103:52107] Handle Initial UE Message
2023-07-04T15:53:10Z [INFO][LIB][FSM] Handle event[Gmm Message], transition from [Deregistered] to [Deregistered]
2023-07-04T15:53:10Z [INFO][AMF][GMM][AMF_UE_NGAP_ID:1] Handle Registration Request
2023-07-04T15:53:10Z [INFO][LIB][FSM] Handle event[Start Authentication], transition from [Deregistered] to [Authentication]
2023-07-04T15:53:10Z [INFO][AMF][GMM][AMF_UE_NGAP_ID:1] Authentication procedure
2023-07-04T15:53:10Z [INFO][AMF][GMM][AMF_UE_NGAP_ID:1] Send Authentication Request
2023-07-04T15:53:10Z [INFO][AMF][NGAP][10.244.120.103:52107][AMF_UE_NGAP_ID:1] Send Downlink Nas Transport
2023-07-04T15:53:10Z [INFO][AMF][NGAP][10.244.120.103:52107][AMF_UE_NGAP_ID:1] Uplink NAS Transport (RAN UE NGAP ID: 1)
2023-07-04T15:53:10Z [INFO][LIB][FSM] Handle event[Gmm Message], transition from [Authentication] to [Authentication]
2023-07-04T15:53:10Z [INFO][AMF][GMM][AMF_UE_NGAP_ID:1] Handle Authentication Response
2023-07-04T15:53:11Z [INFO][LIB][FSM] Handle event[Authentication Success], transition from [Authentication] to [SecurityMode]
2023-07-04T15:53:11Z [INFO][AMF][GMM][AMF_UE_NGAP_ID:1][SUPI:imsi-208930000000003] Send Security Mode Command
2023-07-04T15:53:11Z [INFO][AMF][NGAP][10.244.120.103:52107][AMF_UE_NGAP_ID:1] Send Downlink Nas Transport
2023-07-04T15:53:11Z [INFO][AMF][NGAP][10.244.120.103:52107][AMF_UE_NGAP_ID:1] Uplink NAS Transport (RAN UE NGAP ID: 1)
2023-07-04T15:53:11Z [INFO][LIB][FSM] Handle event[Gmm Message], transition from [SecurityMode] to [SecurityMode]
2023-07-04T15:53:11Z [INFO][AMF][GMM][AMF_UE_NGAP_ID:1][SUPI:imsi-208930000000003] Handle Security Mode Complete
2023-07-04T15:53:11Z [INFO][LIB][FSM] Handle event[SecurityMode Success], transition from [SecurityMode] to [ContextSetup]
2023-07-04T15:53:11Z [INFO][AMF][GMM][AMF_UE_NGAP_ID:1][SUPI:imsi-208930000000003] Handle InitialRegistration
2023-07-04T15:53:11Z [INFO][AMF][GMM][AMF_UE_NGAP_ID:1][SUPI:imsi-208930000000003] RequestedNssai - ServingSnssai: &{Sst:1 Sd:010203}, HomeSnssai:
2023-07-04T15:53:12Z [INFO][AMF][Comm] Handle AMF Status Change Subscribe Request
2023-07-04T15:53:12Z [INFO][AMF][Comm] new AMF Status Subscription[1]
2023-07-04T15:53:12Z [INFO][AMF][GIN] | 201 | 10.244.120.106 | POST | /namf-comm/v1/subscriptions |
2023-07-04T15:53:12Z [INFO][AMF][GMM][AMF_UE_NGAP_ID:1][SUPI:imsi-208930000000003] Send Registration Accept
2023-07-04T15:53:12Z [INFO][AMF][NGAP][10.244.120.103:52107][AMF_UE_NGAP_ID:1] Send Initial Context Setup Request
2023-07-04T15:53:12Z [INFO][AMF][NGAP][10.244.120.103:52107][AMF_UE_NGAP_ID:1] Handle Initial Context Setup Response
2023-07-04T15:53:12Z [INFO][AMF][NGAP][10.244.120.103:52107][AMF_UE_NGAP_ID:1] Uplink NAS Transport (RAN UE NGAP ID: 1)
2023-07-04T15:53:12Z [INFO][LIB][FSM] Handle event[Gmm Message], transition from [ContextSetup] to [ContextSetup]
2023-07-04T15:53:12Z [INFO][AMF][GMM][AMF_UE_NGAP_ID:1][SUPI:imsi-208930000000003] Handle Registration Complete
2023-07-04T15:53:12Z [INFO][LIB][FSM] Handle event[ContextSetup Success], transition from [ContextSetup] to [Registered]
2023-07-04T15:53:12Z [INFO][AMF][NGAP][10.244.120.103:52107][AMF_UE_NGAP_ID:1] Uplink NAS Transport (RAN UE NGAP ID: 1)
2023-07-04T15:53:12Z [INFO][LIB][FSM] Handle event[Gmm Message], transition from [Registered] to [Registered]
2023-07-04T15:53:12Z [INFO][AMF][GMM][AMF_UE_NGAP_ID:1][SUPI:imsi-208930000000003] Handle UL NAS Transport
2023-07-04T15:53:12Z [INFO][AMF][GMM][AMF_UE_NGAP_ID:1][SUPI:imsi-208930000000003] Transport 5GSM Message to SMF
2023-07-04T15:53:12Z [INFO][AMF][GMM][AMF_UE_NGAP_ID:1][SUPI:imsi-208930000000003] Select SMF [snssai: {Sst:1 Sd:010203}, dnn: internet]
2023-07-04T15:53:13Z [INFO][AMF][GMM][AMF_UE_NGAP_ID:1][SUPI:imsi-208930000000003] create smContext[pduSessionID: 1] Success
2023-07-04T15:53:22Z [INFO][AMF][Producer] [OAM] Handle Registered UE Context
2023-07-04T15:53:22Z [INFO][AMF][GIN] | 200 | 10.244.120.116 | GET | /namf-oam/v1/registered-ue-context |
2023-07-04T15:53:24Z [INFO][AMF][Producer] [OAM] Handle Registered UE Context
2023-07-04T15:53:24Z [INFO][AMF][GIN] | 200 | 10.244.120.116 | GET | /namf-oam/v1/registered-ue-context/imsi-208930000000003 |
2023-07-04T15:53:28Z [INFO][AMF][NGAP][10.244.120.103:52107][AMF_UE_NGAP_ID:1] Uplink NAS Transport (RAN UE NGAP ID: 1)
2023-07-04T15:53:28Z [INFO][LIB][FSM] Handle event[Gmm Message], transition from [Registered] to [Registered]
2023-07-04T15:53:28Z [INFO][AMF][GMM][AMF_UE_NGAP_ID:1][SUPI:imsi-208930000000003] Handle UL NAS Transport
2023-07-04T15:53:28Z [INFO][AMF][GMM][AMF_UE_NGAP_ID:1][SUPI:imsi-208930000000003] Transport 5GSM Message to SMF
2023-07-04T15:53:28Z [WARN][AMF][GMM][AMF_UE_NGAP_ID:1][SUPI:imsi-208930000000003] Duplicated PDU session ID[1]
2023-07-04T15:53:34Z [INFO][AMF][Producer] [OAM] Handle Registered UE Context
2023-07-04T15:53:34Z [INFO][AMF][GIN] | 200 | 10.244.120.116 | GET | /namf-oam/v1/registered-ue-context |
SMF logs :
root@free:~# kubectl logs core-free5gc-smf-smf-0
Defaulted container "smf" out of: smf, wait-nrf (init)
2023-07-04T15:52:06Z [INFO][SMF][CFG] SMF config version [1.0.2]
2023-07-04T15:52:06Z [INFO][SMF][CFG] UE-Routing config version [1.0.1]
2023-07-04T15:52:07Z [INFO][SMF][Init] SMF Log level is set to [info] level
2023-07-04T15:52:07Z [INFO][LIB][NAS] set log level : info
2023-07-04T15:52:07Z [INFO][LIB][NAS] set report call : false
2023-07-04T15:52:07Z [INFO][LIB][NGAP] set log level : info
2023-07-04T15:52:07Z [INFO][LIB][NGAP] set report call : false
2023-07-04T15:52:07Z [INFO][LIB][Aper] set log level : info
2023-07-04T15:52:07Z [INFO][LIB][Aper] set report call : false
2023-07-04T15:52:07Z [INFO][LIB][PFCP] set log level : info
2023-07-04T15:52:07Z [INFO][LIB][PFCP] set report call : false
2023-07-04T15:52:07Z [INFO][SMF][App] smf
2023-07-04T15:52:07Z [INFO][SMF][App] SMF version:
free5GC version: v3.1.1
build time: 2022-04-28T12:50:59Z
commit hash: free5gc/free5gc@84c979a
commit time: 2022-04-09T08:38:26Z
go version: go1.14.4 linux/amd64
2023-07-04T15:52:07Z [INFO][SMF][CTX] smfconfig Info: Version[1.0.2] Description[SMF initial local configuration]
2023-07-04T15:52:07Z [INFO][SMF][CTX] Endpoints: [core-free5gc-upf-upf-0.upf-service]
2023-07-04T15:52:07Z [INFO][SMF][Init] Server started
2023-07-04T15:52:07Z [INFO][SMF][Init] SMF Registration to NRF {7e1b2cf7-ac04-4e58-916d-03ea4e19ddae SMF REGISTERED 0 0xc0004082c0 0xc000408300 [] [] [smf-nsmf] [] [] [] 0 0 0 area1 0xc000486200 map[] false 0xc000408080 false false []}
2023-07-04T15:52:07Z [INFO][SMF][PFCP] Listen on 10.244.120.73:8805
2023-07-04T15:52:07Z [INFO][SMF][App] Send PFCP Association Request to UPFcore-free5gc-upf-upf-0.upf-service
2023-07-04T15:52:07Z [INFO][SMF][PFCP] In HandlePfcpAssociationSetupResponse
2023-07-04T15:52:07Z [INFO][SMF][PFCP] Handle PFCP Association Setup Response with NodeID[10.244.120.104]
2023-07-04T15:52:07Z [INFO][LIB][PFCP] Remove Request Transaction [1]
2023-07-04T15:52:07Z [INFO][SMF][PFCP] UPF(10.244.120.104)[{internet}] setup association
2023-07-04T15:53:12Z [INFO][SMF][PduSess] Receive Create SM Context Request
2023-07-04T15:53:12Z [INFO][SMF][PduSess] In HandlePDUSessionSMContextCreate
2023-07-04T15:53:12Z [INFO][SMF][PduSess] Send NF Discovery Serving UDM Successfully
2023-07-04T15:53:12Z [INFO][SMF][CTX] Allocated UE IP address: 10.1.0.1
2023-07-04T15:53:12Z [INFO][SMF][CTX] Selected UPF: UPF
2023-07-04T15:53:12Z [INFO][SMF][PduSess] UE[imsi-208930000000003] PDUSessionID[1] IP[10.1.0.1]
2023-07-04T15:53:12Z [INFO][SMF][GSM] In HandlePDUSessionEstablishmentRequest
2023-07-04T15:53:12Z [INFO][NAS][Convert] ProtocolOrContainerList: [0xc0003d9700 0xc0003d9740]
2023-07-04T15:53:12Z [INFO][SMF][GSM] Protocol Configuration Options
2023-07-04T15:53:12Z [INFO][SMF][GSM] &{[0xc0003d9700 0xc0003d9740]}
2023-07-04T15:53:12Z [INFO][SMF][GSM] Didn't Implement container type IPAddressAllocationViaNASSignallingUL
2023-07-04T15:53:12Z [INFO][SMF][PduSess] PCF Selection for SMContext SUPI[imsi-208930000000003] PDUSessionID[1]
2023-07-04T15:53:13Z [INFO][SMF][PduSess] SUPI[imsi-208930000000003] has no pre-config route
2023-07-04T15:53:13Z [INFO][SMF][Consumer] SendNFDiscoveryServingAMF ok
2023-07-04T15:53:13Z [INFO][SMF][GIN] | 201 | 10.244.120.89 | POST | /nsmf-pdusession/v1/sm-contexts |
2023-07-04T15:53:13Z [INFO][SMF][PFCP] In HandlePfcpSessionEstablishmentResponse
2023-07-04T15:53:13Z [INFO][LIB][PFCP] Remove Request Transaction [2]
2023-07-04T15:53:25Z [INFO][SMF][GIN] | 200 | 10.244.120.116 | GET | /nsmf-oam/v1/ue-pdu-session-info/urn:uuid:8415bd3a-4063-45b0-a2f9-cc4d29c6953d |
2023-07-04T15:53:28Z [INFO][SMF][PduSess] Receive Update SM Context Request
2023-07-04T15:53:28Z [INFO][SMF][PduSess] In HandlePDUSessionSMContextUpdate
2023-07-04T15:53:28Z [WARN][SMF][PduSess] SMContext[imsi-208930000000003-01] should be Active, but actual ModificationPending
2023-07-04T15:53:28Z [INFO][SMF][CTX] [SMF] Cause_REL_DUE_TO_DUPLICATE_SESSION_ID
2023-07-04T15:53:28Z [INFO][SMF][PduSess] Send PFCP Deletion from HandlePDUSessionSMContextUpdate
2023-07-04T15:53:37Z [INFO][LIB][PFCP] Remove Request Transaction [3]
GNB logs:
root@free:~# kubectl logs ran-ueransim-gnb-0
UERANSIM v3.2.6
[2023-07-04 15:53:08.587] [sctp] [info] Trying to establish SCTP connection... (10.244.120.89:38412)
[2023-07-04 15:53:08.669] [sctp] [info] SCTP connection established (10.244.120.89:38412)
[2023-07-04 15:53:08.670] [sctp] [debug] SCTP association setup ascId[11]
[2023-07-04 15:53:08.671] [ngap] [debug] Sending NG Setup Request
[2023-07-04 15:53:08.684] [ngap] [debug] NG Setup Response received
[2023-07-04 15:53:08.685] [ngap] [info] NG Setup procedure is successful
[2023-07-04 15:53:10.588] [rrc] [debug] UE[1] new signal detected
[2023-07-04 15:53:10.589] [rrc] [info] RRC Setup for UE[1]
[2023-07-04 15:53:10.590] [ngap] [debug] Initial NAS message received from UE[1]
[2023-07-04 15:53:12.076] [ngap] [debug] Initial Context Setup Request received
AMF config:
amfName: AMF
serviceNameList:
namf-comm
namf-evts
namf-mt
namf-loc
namf-oam
servedGuamiList:
plmnId:
mcc: 208
mnc: 93
amfId: cafe00
supportTaiList:
plmnId:
mcc: 208
mnc: 93
tac: 1
plmnSupportList:
plmnId:
mcc: 208
mnc: 93
snssaiList:
sst: 1
sd: 010203
sst: 1
sd: 112233
supportDnnList:
internet
security:
integrityOrder:
NIA2
cipheringOrder:
NEA0
SMF config:
smfName: SMF
snssaiInfos:
sNssai:
sst: 1
sd: 010203
dnnInfos: # DNN information list
dnn: internet # Data Network Name
dns: # the IP address of DNS
ipv4: 8.8.8.8
sNssai:
sst: 1
sd: 112233
dnnInfos: # DNN information list
dnn: internet # Data Network Name
dns: # the IP address of DNS
ipv4: 8.8.8.8
sNssai:
sst: 2
sd: 112234
dnnInfos:
dnn: internet
dns:
ipv4: 8.8.8.8
plmnList: # the list of PLMN IDs that this SMF belongs to (optional, remove this key when unnecessary)
mcc: "208" # Mobile Country Code (3 digits string, digit: 09)
mnc: "93" # Mobile Network Code (2 or 3 digits string, digit: 09)
userplaneInformation: # list of userplane information
upNodes: # information of userplane node (AN or UPF)
gNB1: # the name of the node
type: AN # the type of the node (AN or UPF)
UPF: # the name of the node
type: UPF # the type of the node (AN or UPF)
nodeID: core-free5gc-upf-upf-0.upf-service # the IP/FQDN of N4 interface on this UPF (PFCP)
sNssaiUpfInfos: # S-NSSAI information list for this UPF
- sNssai: # S-NSSAI (Single Network Slice Selection Assistance Information)
sst: 1 # Slice/Service Type (uinteger, range: 0255)
sd: 010203 # Slice Differentiator (3 bytes hex string, range: 000000FFFFFF)
dnnUpfInfoList: # DNN information list for this S-NSSAI - dnn: internet
pools: - cidr: 10.1.0.0/17
- sNssai: # S-NSSAI (Single Network Slice Selection Assistance Information)
sst: 1 # Slice/Service Type (uinteger, range: 0255)
sd: 112233 # Slice Differentiator (3 bytes hex string, range: 000000FFFFFF)
dnnUpfInfoList: # DNN information list for this S-NSSAI - dnn: internet
GNB config:
configuration: |-
mcc: '208' # Mobile Country Code value
mnc: '93' # Mobile Network Code value (2 or 3 digits)
nci: '0x000000010' # NR Cell Identity (36-bit)
idLength: 32 # NR gNB ID length in bits [22...32]
tac: 1 # Tracking Area Code
List of supported S-NSSAIs by this gNB
slices:
- sst: 0x1
sd: 0x010203
UE config:
configuration: |-
supi: "imsi-208930000000003" # IMSI number
mcc: '208' # Mobile Country Code value
mnc: '93' # Mobile Network Code value (2 or 3 digits)
key: "8baf473f2f8fd09487cccbd7097c6862" # Operator code (OP or OPC) of the UE
op: "8e27b6af0e692e750f32667a3b14605d" # This value specifies the OP type and it can be either 'OP' or 'OPC'
opType: "OPC" # This value specifies the OP type and it can be either 'OP' or 'OPC'
amf: '8000' # Authentication Management Field (AMF) value
imei: '356938035643803' # IMEI number of the device
imeiSv: '4370816125816151'
UAC Access Identities Configuration
uacAic:
mps: false
mcs: false
UAC Access Control Class
uacAcc:
normalClass: 0
class11: false
class12: false
class13: false
class14: false
class15: false
sessions:
- type: "IPv4"
apn: "internet"
slice:
sst: 0x01
sd: 0x010203
Configured NSSAI for this UE by HPLMN
configured-nssai:
- sst: 0x01
sd: 0x010203
Default Configured NSSAI for this UE
default-nssai:
- sst: 1
sd: 1