Test Registration
cd ~/free5gc
./test.sh TestRegistration |& tee testReg.log
Result Screen
2021-04-23T02:26:20Z [INFO][UPF][Util] Config: /home/free5gc/free5gc/NFs/upf/build/config/upfcfg.test.yaml
2021-04-23T02:26:20Z [INFO][UPF][Util] UPF config version [1.0.0]
2021-04-23T02:26:20Z [INFO][UPF][Util] Set log level: info
2021-04-23T02:26:20Z [INFO][UPF][Util] DNN routes added, main routing table:
2021-04-23T02:26:20Z [INFO][UPF][Util] DstIp Gateway Iface Priority RtProto Type
2021-04-23T02:26:20Z [INFO][UPF][Util] 60.60.0.0 /24 0.0.0.0 upfgtp 0 static unicast
2021-04-23T02:26:20Z [INFO][UPF][Util] 10.200.200.0 /24 0.0.0.0 veth1 0 kernel unicast
2021-04-23T02:26:25Z [INFO][NRF][Init] NRF Log level is set to [info] level
2021-04-23T02:26:25Z [INFO][LIB][Path] set log level : info
2021-04-23T02:26:25Z [INFO][LIB][Path] set report call : false
2021-04-23T02:26:25Z [INFO][LIB][OAPI] set log level : info
2021-04-23T02:26:25Z [INFO][LIB][OAPI] set report call : false
2021-04-23T02:26:25Z [INFO][LIB][MonDB] set log level : info
2021-04-23T02:26:25Z [INFO][LIB][MonDB] set report call : false
2021-04-23T02:26:25Z [INFO][NRF][CFG] config version [1.0.0]
2021-04-23T02:26:25Z [INFO][NRF][Init] Server started
2021-04-23T02:26:25Z [INFO][NRF][Init] nrfconfig Info: Version[1.0.0] Description[NRF initial local configuration]
2021-04-23T02:26:25Z [INFO][NRF][Init] Binding addr: [127.0.0.10:8000]
2021-04-23T02:26:25Z [INFO][AMF][Init] AMF Log level is set to [info] level
2021-04-23T02:26:25Z [INFO][LIB][NAS] set log level : info
2021-04-23T02:26:25Z [INFO][LIB][NAS] set report call : false
2021-04-23T02:26:25Z [INFO][LIB][NGAP] set log level : info
2021-04-23T02:26:25Z [INFO][LIB][NGAP] set report call : false
2021-04-23T02:26:25Z [INFO][LIB][FSM] set log level : info
2021-04-23T02:26:25Z [INFO][LIB][FSM] set report call : false
2021-04-23T02:26:25Z [INFO][LIB][Aper] set log level : info
2021-04-23T02:26:25Z [INFO][LIB][Aper] set report call : false
2021-04-23T02:26:25Z [INFO][LIB][Path] set log level : info
2021-04-23T02:26:25Z [INFO][LIB][Path] set report call : false
2021-04-23T02:26:25Z [INFO][LIB][OAPI] set log level : info
2021-04-23T02:26:25Z [INFO][LIB][OAPI] set report call : false
2021-04-23T02:26:25Z [INFO][AMF][CFG] config version [1.0.1]
2021-04-23T02:26:25Z [INFO][AMF][Init] Server started
2021-04-23T02:26:25Z [INFO][AMF][Util] amfconfig Info: Version[1.0.1] Description[AMF initial local configuration]
2021-04-23T02:26:25Z [INFO][AMF][NGAP] Listen on 127.0.0.1:38412
2021-04-23T02:26:25Z [INFO][NRF][MGMT] Handle NFRegisterRequest
2021-04-23T02:26:25Z [INFO][NRF][MGMT] urilist update
2021-04-23T02:26:25Z [INFO][NRF][MGMT] Create NF Profile
2021-04-23T02:26:25Z [INFO][NRF][MGMT] Location header: http://127.0.0.10:8000/nnrf-nfm/v1/nf-instances/87f4ba37-02c9-40a3-bb95-d0a05e287f7d
2021-04-23T02:26:25Z [INFO][NRF][GIN] | 201 | 127.0.0.1 | PUT | /nnrf-nfm/v1/nf-instances/87f4ba37-02c9-40a3-bb95-d0a05e287f7d |
2021-04-23T02:26:25Z [INFO][SMF][Init] SMF Log level is set to [info] level
2021-04-23T02:26:25Z [INFO][LIB][NAS] set log level : info
2021-04-23T02:26:25Z [INFO][LIB][NAS] set report call : false
2021-04-23T02:26:25Z [INFO][LIB][NGAP] set log level : info
2021-04-23T02:26:25Z [INFO][LIB][NGAP] set report call : false
2021-04-23T02:26:25Z [INFO][LIB][Aper] set log level : info
2021-04-23T02:26:25Z [INFO][LIB][Aper] set report call : false
2021-04-23T02:26:25Z [INFO][LIB][Path] set log level : info
2021-04-23T02:26:25Z [INFO][LIB][Path] set report call : false
2021-04-23T02:26:25Z [INFO][LIB][OAPI] set log level : info
2021-04-23T02:26:25Z [INFO][LIB][OAPI] set report call : false
2021-04-23T02:26:25Z [INFO][LIB][PFCP] set log level : info
2021-04-23T02:26:25Z [INFO][LIB][PFCP] set report call : false
2021-04-23T02:26:25Z [INFO][SMF][CFG] SMF config version [1.0.1]
2021-04-23T02:26:25Z [INFO][SMF][CFG] UE-Routing config version [1.0.1]
2021-04-23T02:26:25Z [INFO][SMF][CTX] smfconfig Info: Version[1.0.1] Description[SMF initial local configuration]
2021-04-23T02:26:25Z [INFO][SMF][CTX] Endpoints: [10.200.200.102]
2021-04-23T02:26:25Z [INFO][SMF][Init] Server started
2021-04-23T02:26:25Z [INFO][NRF][MGMT] Handle NFRegisterRequest
2021-04-23T02:26:25Z [INFO][NRF][MGMT] urilist update
2021-04-23T02:26:25Z [INFO][NRF][MGMT] Create NF Profile
2021-04-23T02:26:25Z [INFO][NRF][MGMT] Location header: http://127.0.0.10:8000/nnrf-nfm/v1/nf-instances/5d13db93-d97b-42c8-a60a-d0a0adf08132
2021-04-23T02:26:25Z [INFO][NRF][GIN] | 201 | 127.0.0.1 | PUT | /nnrf-nfm/v1/nf-instances/5d13db93-d97b-42c8-a60a-d0a0adf08132 |
2021-04-23T02:26:25Z [INFO][SMF][Init] SMF Registration to NRF {5d13db93-d97b-42c8-a60a-d0a0adf08132 SMF REGISTERED 0 0xc0000a4a20 0xc0000a4a60 [] [] [127.0.0.2] [] <nil> [] [] <nil> 0 0 0 <nil> <nil> <nil> <nil> 0xc000402cc0 <nil> <nil> <nil> <nil> <nil> map[] <nil> false 0xc0000a48a0 false false []}
2021-04-23T02:26:25Z [INFO][SMF][PFCP] Listen on 10.200.200.1:8805
2021-04-23T02:26:25Z [INFO][SMF][App] Send PFCP Association Request to UPF[10.200.200.101]
2021-04-23T02:26:25Z [INFO][UPF][Util] [PFCP] Handle PFCP association setup request
2021-04-23T02:26:25Z [INFO][UPF][Util] [PFCP] Association Setup Response
2021-04-23T02:26:25Z [INFO][SMF][PFCP] In HandlePfcpAssociationSetupResponse
2021-04-23T02:26:25Z [INFO][SMF][PFCP] Handle PFCP Association Setup Response with NodeID[10.200.200.101]
2021-04-23T02:26:25Z [INFO][SMF][PFCP] UPF(10.200.200.101)[internet] setup association
2021-04-23T02:26:25Z [INFO][LIB][PFCP] Remove Request Transaction [1]
2021-04-23T02:26:25Z [INFO][UDR][Init] UDR Log level is set to [info] level
2021-04-23T02:26:25Z [INFO][LIB][Path] set log level : info
2021-04-23T02:26:25Z [INFO][LIB][Path] set report call : false
2021-04-23T02:26:25Z [INFO][LIB][OAPI] set log level : info
2021-04-23T02:26:25Z [INFO][LIB][OAPI] set report call : false
2021-04-23T02:26:25Z [INFO][LIB][MonDB] set log level : info
2021-04-23T02:26:25Z [INFO][LIB][MonDB] set report call : false
2021-04-23T02:26:25Z [INFO][UDR][CFG] config version [1.0.0]
2021-04-23T02:26:25Z [INFO][UDR][Init] UDR Config Info: Version[1.0.0] Description[UDR initial local configuration]
2021-04-23T02:26:25Z [INFO][UDR][Init] Server started
2021-04-23T02:26:25Z [INFO][UDR][Util] udrconfig Info: Version[1.0.0] Description[UDR initial local configuration]
2021-04-23T02:26:25Z [INFO][NRF][MGMT] Handle NFRegisterRequest
2021-04-23T02:26:25Z [INFO][NRF][MGMT] urilist update
2021-04-23T02:26:25Z [INFO][NRF][MGMT] Create NF Profile
2021-04-23T02:26:25Z [INFO][NRF][MGMT] Location header: http://127.0.0.10:8000/nnrf-nfm/v1/nf-instances/62101698-d9ca-4539-9f55-d85fb3330bdd
2021-04-23T02:26:25Z [INFO][NRF][GIN] | 201 | 127.0.0.1 | PUT | /nnrf-nfm/v1/nf-instances/62101698-d9ca-4539-9f55-d85fb3330bdd |
2021-04-23T02:26:25Z [INFO][PCF][Init] PCF Log level is set to [info] level
2021-04-23T02:26:25Z [INFO][LIB][Path] set log level : info
2021-04-23T02:26:25Z [INFO][LIB][Path] set report call : false
2021-04-23T02:26:25Z [INFO][LIB][OAPI] set log level : info
2021-04-23T02:26:25Z [INFO][LIB][OAPI] set report call : false
2021-04-23T02:26:25Z [INFO][PCF][CFG] config version [1.0.0]
2021-04-23T02:26:25Z [INFO][PCF][Init] Server started
2021-04-23T02:26:25Z [INFO][PCF][Util] pcfconfig Info: Version[1.0.0] Description[PCF initial local configuration]
2021-04-23T02:26:25Z [INFO][NRF][MGMT] Handle NFRegisterRequest
2021-04-23T02:26:25Z [INFO][NRF][MGMT] urilist update
2021-04-23T02:26:25Z [INFO][NRF][MGMT] Create NF Profile
2021-04-23T02:26:25Z [INFO][NRF][MGMT] Location header: http://127.0.0.10:8000/nnrf-nfm/v1/nf-instances/396c129a-3dba-4041-8c82-4a745cd9dd90
2021-04-23T02:26:25Z [INFO][NRF][GIN] | 201 | 127.0.0.1 | PUT | /nnrf-nfm/v1/nf-instances/396c129a-3dba-4041-8c82-4a745cd9dd90 |
2021-04-23T02:26:25Z [INFO][NRF][DSCV] Handle NFDiscoveryRequest
2021-04-23T02:26:25Z [INFO][NRF][GIN] | 200 | 127.0.0.1 | GET | /nnrf-disc/v1/nf-instances?requester-nf-type=PCF&service-names=nudr-dr&target-nf-type=UDR |
2021-04-23T02:26:26Z [INFO][UDM][Init] UDM Log level is set to [info] level
2021-04-23T02:26:26Z [INFO][LIB][Path] set log level : info
2021-04-23T02:26:26Z [INFO][LIB][Path] set report call : false
2021-04-23T02:26:26Z [INFO][LIB][OAPI] set log level : info
2021-04-23T02:26:26Z [INFO][LIB][OAPI] set report call : false
2021-04-23T02:26:26Z [INFO][UDM][CFG] config version [1.0.0]
2021-04-23T02:26:26Z [INFO][UDM][Init] UDM Config Info: Version[1.0.0] Description[UDM initial local configuration]
2021-04-23T02:26:26Z [INFO][UDM][Init] Server started
2021-04-23T02:26:26Z [INFO][UDM][Util] udmconfig Info: Version[1.0.0] Description[UDM initial local configuration]
2021-04-23T02:26:26Z [INFO][NRF][MGMT] Handle NFRegisterRequest
2021-04-23T02:26:26Z [INFO][NRF][MGMT] urilist update
2021-04-23T02:26:26Z [INFO][NRF][MGMT] Create NF Profile
2021-04-23T02:26:26Z [INFO][NRF][MGMT] Location header: http://127.0.0.10:8000/nnrf-nfm/v1/nf-instances/e204d2ad-7342-4986-b3ec-0d949659c0ea
2021-04-23T02:26:26Z [INFO][NRF][GIN] | 201 | 127.0.0.1 | PUT | /nnrf-nfm/v1/nf-instances/e204d2ad-7342-4986-b3ec-0d949659c0ea |
2021-04-23T02:26:26Z [INFO][NSSF][Init] NSSF Log level is set to [info] level
2021-04-23T02:26:26Z [INFO][LIB][Path] set log level : info
2021-04-23T02:26:26Z [INFO][LIB][Path] set report call : false
2021-04-23T02:26:26Z [INFO][LIB][OAPI] set log level : info
2021-04-23T02:26:26Z [INFO][LIB][OAPI] set report call : false
2021-04-23T02:26:26Z [INFO][NSSF][CFG] config version [1.0.0]
2021-04-23T02:26:26Z [INFO][NSSF][Init] Server started
2021-04-23T02:26:26Z [INFO][NRF][MGMT] Handle NFRegisterRequest
2021-04-23T02:26:26Z [INFO][NRF][MGMT] urilist update
2021-04-23T02:26:26Z [INFO][NRF][MGMT] Create NF Profile
2021-04-23T02:26:26Z [INFO][NRF][MGMT] Location header: http://127.0.0.10:8000/nnrf-nfm/v1/nf-instances/3b7198b5-5cbe-4f85-b1fa-6264b33c9a14
2021-04-23T02:26:26Z [INFO][NRF][GIN] | 201 | 127.0.0.1 | PUT | /nnrf-nfm/v1/nf-instances/3b7198b5-5cbe-4f85-b1fa-6264b33c9a14 |
2021-04-23T02:26:26Z [INFO][AUSF][Init] AUSF Log level is set to [info] level
2021-04-23T02:26:26Z [INFO][LIB][Path] set log level : info
2021-04-23T02:26:26Z [INFO][LIB][Path] set report call : false
2021-04-23T02:26:26Z [INFO][LIB][OAPI] set log level : info
2021-04-23T02:26:26Z [INFO][LIB][OAPI] set report call : false
2021-04-23T02:26:26Z [INFO][AUSF][CFG] config version [1.0.0]
2021-04-23T02:26:26Z [INFO][AUSF][Init] Server started
2021-04-23T02:26:26Z [INFO][AUSF][Init] ausfconfig Info: Version[1.0.0] Description[AUSF initial local configuration]
ausf context = &{{{0 0} {<nil>} map[] 0} {{0 0} {<nil>} map[] 0} 2ad8073d-b263-4d55-bd4a-f13a54684946 ausfGroup001 8000 127.0.0.9 127.0.0.9 http://127.0.0.9:8000 http http://127.0.0.10:8000 map[nausf-auth:{2ad8073d-b263-4d55-bd4a-f13a54684946 nausf-auth 0xc0007fcd40 http REGISTERED 0xc0007fcd20 [] <nil> [] [] <nil> 0 0 0 <nil> <nil> }] [{208 93} {123 45}] 0xc00040f9a0}
2021-04-23T02:26:26Z [INFO][NRF][MGMT] Handle NFRegisterRequest
2021-04-23T02:26:26Z [INFO][NRF][MGMT] urilist update
2021-04-23T02:26:26Z [INFO][NRF][MGMT] Create NF Profile
2021-04-23T02:26:26Z [INFO][NRF][MGMT] Location header: http://127.0.0.10:8000/nnrf-nfm/v1/nf-instances/2ad8073d-b263-4d55-bd4a-f13a54684946
2021-04-23T02:26:26Z [INFO][NRF][GIN] | 201 | 127.0.0.1 | PUT | /nnrf-nfm/v1/nf-instances/2ad8073d-b263-4d55-bd4a-f13a54684946 |
=== RUN TestRegistration
2021-04-23T02:26:26Z [INFO][AMF][NGAP] [AMF] SCTP Accept from: 127.0.0.1:9487
2021-04-23T02:26:26Z [INFO][AMF][NGAP] Create a new NG connection for: 127.0.0.1:9487
2021-04-23T02:26:26Z [INFO][AMF][NGAP][127.0.0.1:9487] Handle NG Setup request
2021-04-23T02:26:26Z [INFO][AMF][NGAP][127.0.0.1:9487] Send NG-Setup response
2021-04-23T02:26:26Z [INFO][AMF][NGAP][127.0.0.1:9487] Handle Initial UE Message
2021-04-23T02:26:26Z [INFO][AMF][GMM][AMF_UE_NGAP_ID:1] Handle Registration Request
2021-04-23T02:26:26Z [INFO][AMF][GMM][AMF_UE_NGAP_ID:1] Authentication procedure
2021-04-23T02:26:26Z [INFO][NRF][DSCV] Handle NFDiscoveryRequest
2021-04-23T02:26:26Z [INFO][NRF][GIN] | 200 | 127.0.0.1 | GET | /nnrf-disc/v1/nf-instances?requester-nf-type=AMF&target-nf-type=AUSF |
2021-04-23T02:26:26Z [INFO][AUSF][UeAuthPost] HandleUeAuthPostRequest
2021-04-23T02:26:26Z [INFO][AUSF][UeAuthPost] Serving network authorized
2021-04-23T02:26:26Z [INFO][NRF][DSCV] Handle NFDiscoveryRequest
2021-04-23T02:26:26Z [INFO][NRF][GIN] | 200 | 127.0.0.1 | GET | /nnrf-disc/v1/nf-instances?requester-nf-type=AUSF&service-names=nudm-ueau&target-nf-type=UDM |
2021-04-23T02:26:26Z [INFO][UDM][UEAU] Handle GenerateAuthDataRequest
2021-04-23T02:26:26Z [INFO][LIB][3GPP] suciPart [suci 0 208 93 0 0 0 00007487]
2021-04-23T02:26:26Z [INFO][LIB][3GPP] scheme 0
2021-04-23T02:26:26Z [INFO][LIB][3GPP] SUPI type is IMSI
http://127.0.0.10:8000
2021-04-23T02:26:26Z [INFO][NRF][DSCV] Handle NFDiscoveryRequest
2021-04-23T02:26:26Z [INFO][NRF][GIN] | 200 | 127.0.0.1 | GET | /nnrf-disc/v1/nf-instances?requester-nf-type=UDM&target-nf-type=UDR |
2021-04-23T02:26:26Z [INFO][UDR][DRepo] Handle QueryAuthSubsData
2021-04-23T02:26:26Z [INFO][UDR][GIN] | 200 | 127.0.0.1 | GET | /nudr-dr/v1/subscription-data/imsi-2089300007487/authentication-data/authentication-subscription |
2021-04-23T02:26:26Z [INFO][UDR][DRepo] Handle ModifyAuthentication
2021-04-23T02:26:26Z [INFO][UDR][GIN] | 204 | 127.0.0.1 | PATCH | /nudr-dr/v1/subscription-data/imsi-2089300007487/authentication-data/authentication-subscription |
AUTN = c88d1a9368ef8000c57ead8acf361c20
2021-04-23T02:26:26Z [INFO][UDM][GIN] | 200 | 127.0.0.1 | POST | /nudm-ueau/v1/suci-0-208-93-0-0-0-00007487/security-information/generate-auth-data |
2021-04-23T02:26:26Z [INFO][AUSF][UeAuthPost] Add SuciSupiPair (suci-0-208-93-0-0-0-00007487, imsi-2089300007487) to map.
2021-04-23T02:26:26Z [INFO][AUSF][UeAuthPost] Use 5G AKA auth method
2021-04-23T02:26:26Z [INFO][AUSF][5gAkaAuth] XresStar = 3038386462623464326233376136323237643162366132316537333536326439
2021-04-23T02:26:26Z [INFO][AUSF][GIN] | 201 | 127.0.0.1 | POST | /nausf-auth/v1/ue-authentications |
2021-04-23T02:26:26Z [INFO][AMF][GMM][AMF_UE_NGAP_ID:1] Send Authentication Request
2021-04-23T02:26:26Z [INFO][AMF][NGAP][127.0.0.1:9487][AMF_UE_NGAP_ID:1] Send Downlink Nas Transport
2021-04-23T02:26:26Z [INFO][AMF][NGAP][127.0.0.1:9487] Handle Uplink Nas Transport
2021-04-23T02:26:26Z [INFO][AMF][NGAP][127.0.0.1:9487][AMF_UE_NGAP_ID:1] Uplink NAS Transport (RAN UE NGAP ID: 1)
2021-04-23T02:26:26Z [INFO][AMF][GMM][AMF_UE_NGAP_ID:1] Handle Authentication Response
2021-04-23T02:26:26Z [INFO][AUSF][5gAkaAuth] Auth5gAkaComfirmRequest
2021-04-23T02:26:26Z [INFO][AUSF][5gAkaAuth] res*: 3038386462623464326233376136323237643162366132316537333536326439
Xres*: 3038386462623464326233376136323237643162366132316537333536326439
2021-04-23T02:26:26Z [INFO][AUSF][5gAkaAuth] 5G AKA confirmation succeeded
2021-04-23T02:26:26Z [INFO][UDM][UEAU] Handle ConfirmAuthDataRequest
2021-04-23T02:26:26Z [INFO][UDR][DRepo] Handle CreateAuthenticationStatus
2021-04-23T02:26:26Z [INFO][UDR][GIN] | 204 | 127.0.0.1 | PUT | /nudr-dr/v1/subscription-data/imsi-2089300007487/authentication-data/authentication-status |
2021-04-23T02:26:26Z [INFO][UDM][GIN] | 201 | 127.0.0.1 | POST | /nudm-ueau/v1/imsi-2089300007487/auth-events |
2021-04-23T02:26:26Z [INFO][AUSF][GIN] | 200 | 127.0.0.1 | PUT | /nausf-auth/v1/ue-authentications/suci-0-208-93-0-0-0-00007487/5g-aka-confirmation |
2021-04-23T02:26:26Z [INFO][AMF][GMM][AMF_UE_NGAP_ID:1][SUPI:imsi-2089300007487] Send Security Mode Command
2021-04-23T02:26:26Z [INFO][AMF][NGAP][127.0.0.1:9487][AMF_UE_NGAP_ID:1] Send Downlink Nas Transport
Security Header Type Integrity Protected With New 5g Nas Security Context
cmac value: 0x5440f963
err <nil>
2021-04-23T02:26:26Z [INFO][AMF][NGAP][127.0.0.1:9487] Handle Uplink Nas Transport
2021-04-23T02:26:26Z [INFO][AMF][NGAP][127.0.0.1:9487][AMF_UE_NGAP_ID:1] Uplink NAS Transport (RAN UE NGAP ID: 1)
2021-04-23T02:26:26Z [INFO][AMF][GMM][AMF_UE_NGAP_ID:1][SUPI:imsi-2089300007487] Handle Security Mode Complete
2021-04-23T02:26:26Z [INFO][AMF][GMM][AMF_UE_NGAP_ID:1][SUPI:imsi-2089300007487] Handle InitialRegistration
2021-04-23T02:26:26Z [INFO][NRF][DSCV] Handle NFDiscoveryRequest
2021-04-23T02:26:26Z [INFO][NRF][GIN] | 200 | 127.0.0.1 | GET | /nnrf-disc/v1/nf-instances?requester-nf-type=AMF&supi=imsi-2089300007487&target-nf-type=UDM |
2021-04-23T02:26:26Z [INFO][UDM][SDM] Handle GetNssai
2021-04-23T02:26:26Z [INFO][UDR][DRepo] Handle QueryAmData
2021-04-23T02:26:26Z [INFO][UDR][GIN] | 200 | 127.0.0.1 | GET | /nudr-dr/v1/subscription-data/imsi-2089300007487/20893/provisioned-data/am-data?supported-features= |
2021-04-23T02:26:26Z [INFO][UDM][GIN] | 200 | 127.0.0.1 | GET | /nudm-sdm/v1/imsi-2089300007487/nssai?plmn-id=20893 |
2021-04-23T02:26:26Z [INFO][NRF][DSCV] Handle NFDiscoveryRequest
2021-04-23T02:26:26Z [INFO][NRF][GIN] | 200 | 127.0.0.1 | GET | /nnrf-disc/v1/nf-instances?requester-nf-type=AMF&supi=imsi-2089300007487&target-nf-type=UDM |
2021-04-23T02:26:26Z [INFO][UDM][UECM] Handle RegistrationAmf3gppAccess
2021-04-23T02:26:26Z [INFO][UDM][UECM] UEID: imsi-2089300007487
http://127.0.0.10:8000
2021-04-23T02:26:26Z [INFO][NRF][DSCV] Handle NFDiscoveryRequest
2021-04-23T02:26:26Z [INFO][NRF][GIN] | 200 | 127.0.0.1 | GET | /nnrf-disc/v1/nf-instances?requester-nf-type=UDM&target-nf-type=UDR |
2021-04-23T02:26:26Z [INFO][UDR][DRepo] Handle CreateAmfContext3gpp
2021-04-23T02:26:26Z [INFO][UDR][GIN] | 204 | 127.0.0.1 | PUT | /nudr-dr/v1/subscription-data/imsi-2089300007487/context-data/amf-3gpp-access |
2021-04-23T02:26:26Z [INFO][UDM][GIN] | 201 | 127.0.0.1 | PUT | /nudm-uecm/v1/imsi-2089300007487/registrations/amf-3gpp-access |
2021-04-23T02:26:26Z [INFO][UDM][SDM] Handle GetAmData
2021-04-23T02:26:26Z [INFO][UDR][DRepo] Handle QueryAmData
2021-04-23T02:26:26Z [INFO][UDR][GIN] | 200 | 127.0.0.1 | GET | /nudr-dr/v1/subscription-data/imsi-2089300007487/20893/provisioned-data/am-data?supported-features=20893 |
2021-04-23T02:26:26Z [INFO][UDM][GIN] | 200 | 127.0.0.1 | GET | /nudm-sdm/v1/imsi-2089300007487/am-data?plmn-id=20893 |
2021-04-23T02:26:26Z [INFO][UDM][SDM] Handle GetSmfSelectData
http://127.0.0.10:8000
2021-04-23T02:26:26Z [INFO][NRF][DSCV] Handle NFDiscoveryRequest
2021-04-23T02:26:26Z [INFO][NRF][GIN] | 200 | 127.0.0.1 | GET | /nnrf-disc/v1/nf-instances?requester-nf-type=UDM&target-nf-type=UDR |
2021-04-23T02:26:26Z [INFO][UDR][DRepo] Handle QuerySmfSelectData
2021-04-23T02:26:26Z [INFO][UDR][GIN] | 200 | 127.0.0.1 | GET | /nudr-dr/v1/subscription-data/imsi-2089300007487/20893/provisioned-data/smf-selection-subscription-data?supported-features= |
2021-04-23T02:26:26Z [INFO][UDM][GIN] | 200 | 127.0.0.1 | GET | /nudm-sdm/v1/imsi-2089300007487/smf-select-data?plmn-id=20893 |
2021-04-23T02:26:26Z [INFO][UDM][SDM] Handle GetUeContextInSmfData
http://127.0.0.10:8000
2021-04-23T02:26:26Z [INFO][NRF][DSCV] Handle NFDiscoveryRequest
2021-04-23T02:26:26Z [INFO][NRF][GIN] | 200 | 127.0.0.1 | GET | /nnrf-disc/v1/nf-instances?requester-nf-type=UDM&target-nf-type=UDR |
2021-04-23T02:26:26Z [INFO][UDR][DRepo] Handle QuerySmfRegList
2021-04-23T02:26:26Z [INFO][UDR][GIN] | 200 | 127.0.0.1 | GET | /nudr-dr/v1/subscription-data/imsi-2089300007487/context-data/smf-registrations?supported-features= |
2021-04-23T02:26:26Z [INFO][UDM][GIN] | 200 | 127.0.0.1 | GET | /nudm-sdm/v1/imsi-2089300007487/ue-context-in-smf-data |
2021-04-23T02:26:26Z [INFO][UDM][SDM] Handle Subscribe
http://127.0.0.10:8000
2021-04-23T02:26:26Z [INFO][NRF][DSCV] Handle NFDiscoveryRequest
2021-04-23T02:26:26Z [INFO][NRF][GIN] | 200 | 127.0.0.1 | GET | /nnrf-disc/v1/nf-instances?requester-nf-type=UDM&target-nf-type=UDR |
2021-04-23T02:26:26Z [INFO][UDR][DRepo] Handle CreateSdmSubscriptions
2021-04-23T02:26:26Z [INFO][UDR][GIN] | 201 | 127.0.0.1 | POST | /nudr-dr/v1/subscription-data/imsi-2089300007487/context-data/sdm-subscriptions |
2021-04-23T02:26:26Z [INFO][UDM][GIN] | 201 | 127.0.0.1 | POST | /nudm-sdm/v1/imsi-2089300007487/sdm-subscriptions |
2021-04-23T02:26:26Z [INFO][NRF][DSCV] Handle NFDiscoveryRequest
2021-04-23T02:26:27Z [INFO][NRF][GIN] | 200 | 127.0.0.1 | GET | /nnrf-disc/v1/nf-instances?requester-nf-type=AMF&supi=imsi-2089300007487&target-nf-type=PCF |
2021-04-23T02:26:27Z [INFO][PCF][Ampolicy] Handle AM Policy Create Request
2021-04-23T02:26:27Z [INFO][NRF][DSCV] Handle NFDiscoveryRequest
2021-04-23T02:26:27Z [INFO][NRF][GIN] | 200 | 127.0.0.1 | GET | /nnrf-disc/v1/nf-instances?requester-nf-type=PCF&target-nf-type=UDR |
2021-04-23T02:26:27Z [INFO][UDR][DRepo] Handle PolicyDataUesUeIdAmDataGet
2021-04-23T02:26:27Z [INFO][UDR][GIN] | 200 | 127.0.0.1 | GET | /nudr-dr/v1/policy-data/ues/imsi-2089300007487/am-data |
2021-04-23T02:26:27Z [INFO][NRF][DSCV] Handle NFDiscoveryRequest
2021-04-23T02:26:27Z [INFO][NRF][GIN] | 200 | 127.0.0.1 | GET | /nnrf-disc/v1/nf-instances?guami=%7B%22plmnId%22%3A%7B%22mcc%22%3A%22208%22%2C%22mnc%22%3A%2293%22%7D%2C%22amfId%22%3A%22cafe00%22%7D&requester-nf-type=PCF&target-nf-type=AMF |
2021-04-23T02:26:27Z [INFO][AMF][Comm] Handle AMF Status Change Subscribe Request
2021-04-23T02:26:27Z [INFO][AMF][Comm] new AMF Status Subscription[1]
2021-04-23T02:26:27Z [INFO][AMF][GIN] | 201 | 127.0.0.1 | POST | /namf-comm/v1/subscriptions |
2021-04-23T02:26:27Z [INFO][PCF][GIN] | 201 | 127.0.0.1 | POST | /npcf-am-policy-control/v1/policies |
2021-04-23T02:26:27Z [INFO][AMF][GMM][AMF_UE_NGAP_ID:1][SUPI:imsi-2089300007487] Send Registration Accept
2021-04-23T02:26:27Z [INFO][AMF][NGAP][127.0.0.1:9487][AMF_UE_NGAP_ID:1] Send Initial Context Setup Request
2021-04-23T02:26:27Z [INFO][AMF][NGAP][127.0.0.1:9487] Handle Initial Context Setup Response
2021-04-23T02:26:27Z [INFO][NAS][Message] Encode ExtendedProtocolConfigurationOptions in EncodePDUSessionEstablishmentRequest
2021-04-23T02:26:27Z [INFO][AMF][NGAP][127.0.0.1:9487] Handle Uplink Nas Transport
2021-04-23T02:26:27Z [INFO][AMF][NGAP][127.0.0.1:9487][AMF_UE_NGAP_ID:1] Uplink NAS Transport (RAN UE NGAP ID: 1)
2021-04-23T02:26:27Z [INFO][AMF][GMM][AMF_UE_NGAP_ID:1][SUPI:imsi-2089300007487] Handle Registration Complete
2021-04-23T02:26:27Z [INFO][AMF][NGAP][127.0.0.1:9487] Handle Uplink Nas Transport
2021-04-23T02:26:27Z [INFO][AMF][NGAP][127.0.0.1:9487][AMF_UE_NGAP_ID:1] Uplink NAS Transport (RAN UE NGAP ID: 1)
2021-04-23T02:26:27Z [INFO][AMF][GMM][AMF_UE_NGAP_ID:1][SUPI:imsi-2089300007487] Handle UL NAS Transport
2021-04-23T02:26:27Z [INFO][AMF][GMM][AMF_UE_NGAP_ID:1][SUPI:imsi-2089300007487] Transport 5GSM Message to SMF
2021-04-23T02:26:27Z [INFO][AMF][GMM][AMF_UE_NGAP_ID:1][SUPI:imsi-2089300007487] Select SMF [snssai: {Sst:1 Sd:010203}, dnn: internet]
2021-04-23T02:26:27Z [INFO][NRF][DSCV] Handle NFDiscoveryRequest
2021-04-23T02:26:27Z [INFO][NRF][GIN] | 200 | 127.0.0.1 | GET | /nnrf-disc/v1/nf-instances?requester-nf-type=AMF&target-nf-type=NSSF |
2021-04-23T02:26:27Z [INFO][NSSF][NsSelect] Handle NSSelectionGet
2021-04-23T02:26:27Z [INFO][NSSF][GIN] | 200 | 127.0.0.1 | GET | /nnssf-nsselection/v1/network-slice-information?nf-id=87f4ba37-02c9-40a3-bb95-d0a05e287f7d&nf-type=AMF&slice-info-request-for-pdu-session=%7B%22sNssai%22%3A%7B%22sst%22%3A1%2C%22sd%22%3A%22010203%22%7D%2C%22roamingIndication%22%3A%22NON_ROAMING%22%7D |
2021-04-23T02:26:27Z [INFO][NRF][DSCV] Handle NFDiscoveryRequest
2021-04-23T02:26:27Z [INFO][NRF][GIN] | 200 | 127.0.0.1 | GET | /nnrf-disc/v1/nf-instances?dnn=internet&requester-nf-type=AMF&service-names=nsmf-pdusession&snssais=%7B%22sst%22%3A1%2C%22sd%22%3A%22010203%22%7D&target-nf-type=SMF&target-plmn-list=%7B%22mcc%22%3A%22208%22%2C%22mnc%22%3A%2293%22%7D |
2021-04-23T02:26:27Z [INFO][SMF][PduSess] Recieve Create SM Context Request
2021-04-23T02:26:27Z [INFO][SMF][PduSess] In HandlePDUSessionSMContextCreate
2021-04-23T02:26:27Z [INFO][NRF][DSCV] Handle NFDiscoveryRequest
2021-04-23T02:26:27Z [INFO][NRF][GIN] | 200 | 127.0.0.1 | GET | /nnrf-disc/v1/nf-instances?requester-nf-type=SMF&target-nf-type=UDM |
2021-04-23T02:26:27Z [INFO][SMF][PduSess] Send NF Discovery Serving UDM Successfully
2021-04-23T02:26:27Z [INFO][SMF][CTX] Allocated UE IP address: 60.60.0.1
2021-04-23T02:26:27Z [INFO][SMF][CTX] Selected UPF: UPF
2021-04-23T02:26:27Z [INFO][SMF][PduSess] UE[imsi-2089300007487] PDUSessionID[10] IP[60.60.0.1]
2021-04-23T02:26:27Z [INFO][UDM][SDM] Handle GetSmData
2021-04-23T02:26:27Z [INFO][UDM][SDM] getSmDataProcedure: SUPI[imsi-2089300007487] PLMNID[20893] DNN[internet] SNssai[{"sst":1,"sd":"010203"}]
2021-04-23T02:26:27Z [INFO][UDR][DRepo] Handle QuerySmData
2021-04-23T02:26:27Z [INFO][UDR][GIN] | 200 | 127.0.0.1 | GET | /nudr-dr/v1/subscription-data/imsi-2089300007487/20893/provisioned-data/sm-data?single-nssai=%7B%22sst%22%3A1%2C%22sd%22%3A%22010203%22%7D |
2021-04-23T02:26:27Z [INFO][UDM][GIN] | 200 | 127.0.0.1 | GET | /nudm-sdm/v1/imsi-2089300007487/sm-data?dnn=internet&plmn-id=20893&single-nssai=%7B%22sst%22%3A1%2C%22sd%22%3A%22010203%22%7D |
2021-04-23T02:26:27Z [INFO][SMF][GSM] In HandlePDUSessionEstablishmentRequest
2021-04-23T02:26:27Z [INFO][NAS][Convert] ProtocolOrContainerList: [0xc000acdee0 0xc000acdf20 0xc000acdf60]
2021-04-23T02:26:27Z [INFO][SMF][GSM] Protocol Configuration Options
2021-04-23T02:26:27Z [INFO][SMF][GSM] &{[0xc000acdee0 0xc000acdf20 0xc000acdf60]}
2021-04-23T02:26:27Z [INFO][SMF][GSM] Didn't Implement container type IPAddressAllocationViaNASSignallingUL
2021-04-23T02:26:27Z [INFO][SMF][PduSess] PCF Selection for SMContext SUPI[imsi-2089300007487] PDUSessionID[10]
2021-04-23T02:26:27Z [INFO][NRF][DSCV] Handle NFDiscoveryRequest
2021-04-23T02:26:27Z [INFO][NRF][GIN] | 200 | 127.0.0.1 | GET | /nnrf-disc/v1/nf-instances?requester-nf-type=SMF&target-nf-type=PCF |
2021-04-23T02:26:27Z [INFO][PCF][SMpolicy] Handle CreateSmPolicy
2021-04-23T02:26:27Z [INFO][UDR][DRepo] Handle PolicyDataUesUeIdSmDataGet
2021-04-23T02:26:27Z [INFO][UDR][GIN] | 200 | 127.0.0.1 | GET | /nudr-dr/v1/policy-data/ues/imsi-2089300007487/sm-data?dnn=internet&snssai=%7B%22sst%22%3A1%2C%22sd%22%3A%22010203%22%7D |
2021-04-23T02:26:27Z [INFO][PCF][GIN] | 201 | 127.0.0.1 | POST | /npcf-smpolicycontrol/v1/sm-policies |
2021-04-23T02:26:27Z [INFO][SMF][PduSess] SUPI[imsi-2089300007487] has no pre-config route
2021-04-23T02:26:27Z [INFO][NRF][DSCV] Handle NFDiscoveryRequest
2021-04-23T02:26:27Z [INFO][NRF][GIN] | 200 | 127.0.0.1 | GET | /nnrf-disc/v1/nf-instances?requester-nf-type=SMF&target-nf-instance-id=87f4ba37-02c9-40a3-bb95-d0a05e287f7d&target-nf-type=AMF |
2021-04-23T02:26:27Z [INFO][SMF][Consumer] SendNFDiscoveryServingAMF ok
2021-04-23T02:26:27Z [INFO][SMF][GIN] | 201 | 127.0.0.1 | POST | /nsmf-pdusession/v1/sm-contexts |
2021-04-23T02:26:27Z [INFO][AMF][GMM][AMF_UE_NGAP_ID:1][SUPI:imsi-2089300007487] create smContext[pduSessionID: 10] Success
2021-04-23T02:26:27Z [INFO][UPF][Util] [PFCP] Handle PFCP session establishment request
2021-04-23T02:26:27Z [INFO][UPF][Util] [PFCP] Session Establishment Response
2021-04-23T02:26:27Z [INFO][SMF][PFCP] In HandlePfcpSessionEstablishmentResponse
&{1000 Kbps 1000 Kbps}
2021-04-23T02:26:27Z [INFO][LIB][PFCP] Remove Request Transaction [2]
2021-04-23T02:26:27Z [INFO][AMF][Producer] Handle N1N2 Message Transfer Request
2021-04-23T02:26:27Z [INFO][AMF][NGAP][127.0.0.1:9487][AMF_UE_NGAP_ID:1] Send PDU Session Resource Setup Request
2021-04-23T02:26:27Z [INFO][AMF][GIN] | 200 | 127.0.0.1 | POST | /namf-comm/v1/ue-contexts/imsi-2089300007487/n1-n2-messages |
2021-04-23T02:26:27Z [INFO][AMF][NGAP][127.0.0.1:9487] Handle PDU Session Resource Setup Response
2021-04-23T02:26:27Z [INFO][SMF][PduSess] Recieve Update SM Context Request
2021-04-23T02:26:27Z [INFO][SMF][PduSess] In HandlePDUSessionSMContextUpdate
2021-04-23T02:26:27Z [INFO][UPF][Util] [PFCP] Handle PFCP session modification request
2021-04-23T02:26:27Z [INFO][UPF][Util] [PFCP] Session Modification Response
2021-04-23T02:26:27Z [INFO][SMF][PFCP] In HandlePfcpSessionModificationResponse
2021-04-23T02:26:27Z [INFO][SMF][PduSess] [SMF] PFCP Modification Resonse Accept
2021-04-23T02:26:27Z [INFO][SMF][PFCP] PFCP Session Modification Success[1]
2021-04-23T02:26:27Z [INFO][SMF][GIN] | 200 | 127.0.0.1 | POST | /nsmf-pdusession/v1/sm-contexts/urn:uuid:3afd63a4-7336-4d9a-b98a-089d5dddafc4/modify |
2021-04-23T02:26:27Z [INFO][LIB][PFCP] Remove Request Transaction [3]
2021-04-23T02:26:29Z [INFO][NRF][Init] Terminating NRF...
2021-04-23T02:26:29Z [INFO][NRF][Init] NRF terminated
2021-04-23T02:26:29Z [INFO][AMF][Init] Terminating AMF...
2021-04-23T02:26:29Z [INFO][AMF][Consumer] [AMF] Send Deregister NFInstance
2021-04-23T02:26:29Z [INFO][AMF][NGAP] Handle SCTP Notification[addr: <nil>]
2021-04-23T02:26:29Z [INFO][AMF][NGAP][127.0.0.1:9487] SCTP_SHUTDOWN_EVENT notification, close the connection
2021-04-23T02:26:29Z [INFO][AMF][NGAP][127.0.0.1:9487] Remove RAN Context[ID: <PlmnID: {Mcc:208 Mnc:93}, GNbID: 000102>]
2021-04-23T02:26:29Z [INFO][AMF][NGAP] Handle SCTP Notification[addr: <nil>]
2021-04-23T02:26:29Z[33m [WARN][AMF][NGAP] RAN context has been removed[addr: <nil>]
2021-04-23T02:26:29Z [INFO][NRF][MGMT] Handle NFDeregisterRequest
2021-04-23T02:26:30Z [INFO][NRF][GIN] | 204 | 127.0.0.1 | DELETE | /nnrf-nfm/v1/nf-instances/87f4ba37-02c9-40a3-bb95-d0a05e287f7d |
2021-04-23T02:26:30Z [INFO][AMF][Init] [AMF] Deregister from NRF successfully
2021-04-23T02:26:30Z [INFO][AMF][Init] Send AMF Status Indication to Notify RANs due to AMF terminating
2021-04-23T02:26:30Z [INFO][AMF][NGAP] Close SCTP server...
2021-04-23T02:26:30Z [INFO][AMF][NGAP] SCTP server closed
2021-04-23T02:26:30Z [INFO][AMF][Producer] [AMF] Send Amf Status Change Notify to http://127.0.0.7:8000/npcf-callback/v1/amfstatus
2021-04-23T02:26:30Z[33m [WARN][PCF][Callback] [PCF] Handle Amf Status Change Notify is not implemented.
2021-04-23T02:26:30Z [INFO][PCF][GIN] | 204 | 127.0.0.1 | POST | /npcf-callback/v1/amfstatus |
2021-04-23T02:26:30Z [INFO][AMF][Init] AMF terminated
2021-04-23T02:26:30Z [INFO][SMF][Init] Terminating SMF...
2021-04-23T02:26:30Z [INFO][SMF][Consumer] Send Deregister NFInstance
2021-04-23T02:26:30Z [INFO][NRF][MGMT] Handle NFDeregisterRequest
2021-04-23T02:26:31Z [INFO][NRF][GIN] | 204 | 127.0.0.1 | DELETE | /nnrf-nfm/v1/nf-instances/5d13db93-d97b-42c8-a60a-d0a0adf08132 |
2021-04-23T02:26:31Z [INFO][SMF][Init] Deregister from NRF successfully
2021-04-23T02:26:31Z [INFO][UDR][Init] Terminating UDR...
2021-04-23T02:26:31Z [INFO][UDR][Consumer] Send Deregister NFInstance
2021-04-23T02:26:31Z [INFO][NRF][MGMT] Handle NFDeregisterRequest
2021-04-23T02:26:32Z [INFO][NRF][GIN] | 204 | 127.0.0.1 | DELETE | /nnrf-nfm/v1/nf-instances/62101698-d9ca-4539-9f55-d85fb3330bdd |
2021-04-23T02:26:32Z [INFO][UDR][Init] Deregister from NRF successfully
2021-04-23T02:26:32Z [INFO][UDR][Init] UDR terminated
2021-04-23T02:26:32Z [INFO][PCF][Init] Terminating PCF...
2021-04-23T02:26:32Z [INFO][PCF][Consumer] Send Deregister NFInstance
2021-04-23T02:26:32Z [INFO][NRF][MGMT] Handle NFDeregisterRequest
2021-04-23T02:26:33Z [INFO][NRF][GIN] | 204 | 127.0.0.1 | DELETE | /nnrf-nfm/v1/nf-instances/396c129a-3dba-4041-8c82-4a745cd9dd90 |
2021-04-23T02:26:33Z [INFO][PCF][Init] Deregister from NRF successfully
2021-04-23T02:26:33Z [INFO][PCF][Init] PCF terminated
2021-04-23T02:26:33Z [INFO][UDM][Init] Terminating UDM...
2021-04-23T02:26:33Z [INFO][UDM][Consumer] Send Deregister NFInstance
2021-04-23T02:26:33Z [INFO][NRF][MGMT] Handle NFDeregisterRequest
2021-04-23T02:26:34Z [INFO][NRF][GIN] | 204 | 127.0.0.1 | DELETE | /nnrf-nfm/v1/nf-instances/e204d2ad-7342-4986-b3ec-0d949659c0ea |
2021-04-23T02:26:34Z [INFO][UDM][Init] Deregister from NRF successfully
2021-04-23T02:26:34Z [INFO][UDM][Init] UDM terminated
2021-04-23T02:26:34Z [INFO][NSSF][Init] Terminating NSSF...
2021-04-23T02:26:34Z [INFO][NSSF][App] Send Deregister NFInstance
2021-04-23T02:26:34Z [INFO][NRF][MGMT] Handle NFDeregisterRequest
2021-04-23T02:26:35Z [INFO][NRF][GIN] | 204 | 127.0.0.1 | DELETE | /nnrf-nfm/v1/nf-instances/3b7198b5-5cbe-4f85-b1fa-6264b33c9a14 |
2021-04-23T02:26:35Z [INFO][NSSF][Init] Deregister from NRF successfully
2021-04-23T02:26:35Z [INFO][NSSF][Init] NSSF terminated
2021-04-23T02:26:35Z [INFO][AUSF][Init] Terminating AUSF...
2021-04-23T02:26:35Z [INFO][AUSF][App] Send Deregister NFInstance
2021-04-23T02:26:35Z [INFO][NRF][MGMT] Handle NFDeregisterRequest
2021-04-23T02:26:36Z [INFO][NRF][GIN] | 204 | 127.0.0.1 | DELETE | /nnrf-nfm/v1/nf-instances/2ad8073d-b263-4d55-bd4a-f13a54684946 |
2021-04-23T02:26:36Z [INFO][AUSF][Init] Deregister from NRF successfully
2021-04-23T02:26:36Z [INFO][AUSF][Init] AUSF terminated
--- PASS: TestRegistration (9.69s)
PASS
ok test 11.370s
2021-04-23T02:26:39Z [INFO][UPF][Util] Removing DNN routes
ls: cannot access '*sslkey.log': No such file or directory