class/오픈소스 free5gc

21/04/23 #ref-005 (free5gc) ./test.sh TestServiceRequest

tech-lover 2021. 4. 23. 11:37

Test GUTI Registration

cd ~/free5gc 
./test.sh TestServiceRequest |& tee testSvcReq.log

Result Screen

2021-04-23T02:39:19Z [INFO][UPF][Util] Config: /home/free5gc/free5gc/NFs/upf/build/config/upfcfg.test.yaml
2021-04-23T02:39:19Z [INFO][UPF][Util] UPF config version [1.0.0]
2021-04-23T02:39:19Z [INFO][UPF][Util] Set log level: info
2021-04-23T02:39:19Z [INFO][UPF][Util] DNN routes added, main routing table:
2021-04-23T02:39:19Z [INFO][UPF][Util] DstIp               Gateway        Iface     Priority RtProto   Type
2021-04-23T02:39:19Z [INFO][UPF][Util] 60.60.0.0 /24       0.0.0.0        upfgtp    0        static    unicast
2021-04-23T02:39:19Z [INFO][UPF][Util] 10.200.200.0 /24    0.0.0.0        veth1     0        kernel    unicast
2021-04-23T02:39:24Z [INFO][NRF][Init] NRF Log level is set to [info] level
2021-04-23T02:39:24Z [INFO][LIB][Path] set log level : info
2021-04-23T02:39:24Z [INFO][LIB][Path] set report call : false
2021-04-23T02:39:24Z [INFO][LIB][OAPI] set log level : info
2021-04-23T02:39:24Z [INFO][LIB][OAPI] set report call : false
2021-04-23T02:39:24Z [INFO][LIB][MonDB] set log level : info
2021-04-23T02:39:24Z [INFO][LIB][MonDB] set report call : false
2021-04-23T02:39:24Z [INFO][NRF][CFG] config version [1.0.0]
2021-04-23T02:39:24Z [INFO][NRF][Init] Server started
2021-04-23T02:39:24Z [INFO][NRF][Init] nrfconfig Info: Version[1.0.0] Description[NRF initial local configuration]
2021-04-23T02:39:24Z [INFO][NRF][Init] Binding addr: [127.0.0.10:8000]
2021-04-23T02:39:24Z [INFO][AMF][Init] AMF Log level is set to [info] level
2021-04-23T02:39:24Z [INFO][LIB][NAS] set log level : info
2021-04-23T02:39:24Z [INFO][LIB][NAS] set report call : false
2021-04-23T02:39:24Z [INFO][LIB][NGAP] set log level : info
2021-04-23T02:39:24Z [INFO][LIB][NGAP] set report call : false
2021-04-23T02:39:24Z [INFO][LIB][FSM] set log level : info
2021-04-23T02:39:24Z [INFO][LIB][FSM] set report call : false
2021-04-23T02:39:24Z [INFO][LIB][Aper] set log level : info
2021-04-23T02:39:24Z [INFO][LIB][Aper] set report call : false
2021-04-23T02:39:24Z [INFO][LIB][Path] set log level : info
2021-04-23T02:39:24Z [INFO][LIB][Path] set report call : false
2021-04-23T02:39:24Z [INFO][LIB][OAPI] set log level : info
2021-04-23T02:39:24Z [INFO][LIB][OAPI] set report call : false
2021-04-23T02:39:24Z [INFO][AMF][CFG] config version [1.0.1]
2021-04-23T02:39:24Z [INFO][AMF][Init] Server started
2021-04-23T02:39:24Z [INFO][AMF][Util] amfconfig Info: Version[1.0.1] Description[AMF initial local configuration]
2021-04-23T02:39:24Z [INFO][AMF][NGAP] Listen on 127.0.0.1:38412
2021-04-23T02:39:24Z [INFO][NRF][MGMT] Handle NFRegisterRequest
2021-04-23T02:39:24Z [INFO][NRF][MGMT] urilist update
2021-04-23T02:39:24Z [INFO][NRF][MGMT] Create NF Profile
2021-04-23T02:39:24Z [INFO][NRF][MGMT] Location header:  http://127.0.0.10:8000/nnrf-nfm/v1/nf-instances/81c4e7b6-bc4d-4276-9f45-5b4252f4b999
2021-04-23T02:39:24Z [INFO][NRF][GIN] | 201 |       127.0.0.1 | PUT     | /nnrf-nfm/v1/nf-instances/81c4e7b6-bc4d-4276-9f45-5b4252f4b999 |
2021-04-23T02:39:24Z [INFO][SMF][Init] SMF Log level is set to [info] level
2021-04-23T02:39:24Z [INFO][LIB][NAS] set log level : info
2021-04-23T02:39:24Z [INFO][LIB][NAS] set report call : false
2021-04-23T02:39:24Z [INFO][LIB][NGAP] set log level : info
2021-04-23T02:39:24Z [INFO][LIB][NGAP] set report call : false
2021-04-23T02:39:24Z [INFO][LIB][Aper] set log level : info
2021-04-23T02:39:24Z [INFO][LIB][Aper] set report call : false
2021-04-23T02:39:24Z [INFO][LIB][Path] set log level : info
2021-04-23T02:39:24Z [INFO][LIB][Path] set report call : false
2021-04-23T02:39:24Z [INFO][LIB][OAPI] set log level : info
2021-04-23T02:39:24Z [INFO][LIB][OAPI] set report call : false
2021-04-23T02:39:24Z [INFO][LIB][PFCP] set log level : info
2021-04-23T02:39:24Z [INFO][LIB][PFCP] set report call : false
2021-04-23T02:39:24Z [INFO][SMF][CFG] SMF config version [1.0.1]
2021-04-23T02:39:24Z [INFO][SMF][CFG] UE-Routing config version [1.0.1]
2021-04-23T02:39:24Z [INFO][SMF][CTX] smfconfig Info: Version[1.0.1] Description[SMF initial local configuration]
2021-04-23T02:39:24Z [INFO][SMF][CTX] Endpoints: [10.200.200.102]
2021-04-23T02:39:24Z [INFO][SMF][Init] Server started
2021-04-23T02:39:24Z [INFO][NRF][MGMT] Handle NFRegisterRequest
2021-04-23T02:39:24Z [INFO][NRF][MGMT] urilist update
2021-04-23T02:39:24Z [INFO][NRF][MGMT] Create NF Profile
2021-04-23T02:39:24Z [INFO][NRF][MGMT] Location header:  http://127.0.0.10:8000/nnrf-nfm/v1/nf-instances/580ac3d1-c467-433e-a4c2-191fc235f448
2021-04-23T02:39:24Z [INFO][NRF][GIN] | 201 |       127.0.0.1 | PUT     | /nnrf-nfm/v1/nf-instances/580ac3d1-c467-433e-a4c2-191fc235f448 |
2021-04-23T02:39:24Z [INFO][SMF][Init] SMF Registration to NRF {580ac3d1-c467-433e-a4c2-191fc235f448 SMF REGISTERED 0 0xc000578ba0 0xc000578c40 [] []   [127.0.0.2] [] <nil> [] [] <nil> 0 0 0  <nil> <nil> <nil> <nil> 0xc0003a89c0 <nil> <nil> <nil> <nil> <nil> map[] <nil> false 0xc000578a20 false false []}
2021-04-23T02:39:24Z [INFO][SMF][PFCP] Listen on 10.200.200.1:8805
2021-04-23T02:39:24Z [INFO][SMF][App] Send PFCP Association Request to UPF[10.200.200.101]
2021-04-23T02:39:24Z [INFO][UPF][Util] [PFCP] Handle PFCP association setup request
2021-04-23T02:39:24Z [INFO][UPF][Util] [PFCP] Association Setup Response
2021-04-23T02:39:24Z [INFO][SMF][PFCP] In HandlePfcpAssociationSetupResponse
2021-04-23T02:39:24Z [INFO][SMF][PFCP] Handle PFCP Association Setup Response with NodeID[10.200.200.101]
2021-04-23T02:39:24Z [INFO][SMF][PFCP] UPF(10.200.200.101)[internet] setup association
2021-04-23T02:39:24Z [INFO][LIB][PFCP] Remove Request Transaction [1]
2021-04-23T02:39:24Z [INFO][UDR][Init] UDR Log level is set to [info] level
2021-04-23T02:39:24Z [INFO][LIB][Path] set log level : info
2021-04-23T02:39:24Z [INFO][LIB][Path] set report call : false
2021-04-23T02:39:24Z [INFO][LIB][OAPI] set log level : info
2021-04-23T02:39:24Z [INFO][LIB][OAPI] set report call : false
2021-04-23T02:39:24Z [INFO][LIB][MonDB] set log level : info
2021-04-23T02:39:24Z [INFO][LIB][MonDB] set report call : false
2021-04-23T02:39:24Z [INFO][UDR][CFG] config version [1.0.0]
2021-04-23T02:39:24Z [INFO][UDR][Init] UDR Config Info: Version[1.0.0] Description[UDR initial local configuration]
2021-04-23T02:39:24Z [INFO][UDR][Init] Server started
2021-04-23T02:39:24Z [INFO][UDR][Util] udrconfig Info: Version[1.0.0] Description[UDR initial local configuration]
2021-04-23T02:39:24Z [INFO][NRF][MGMT] Handle NFRegisterRequest
2021-04-23T02:39:24Z [INFO][NRF][MGMT] urilist update
2021-04-23T02:39:24Z [INFO][NRF][MGMT] Create NF Profile
2021-04-23T02:39:24Z [INFO][NRF][MGMT] Location header:  http://127.0.0.10:8000/nnrf-nfm/v1/nf-instances/ca432ae6-de36-4512-b6aa-d80c03191dae
2021-04-23T02:39:24Z [INFO][NRF][GIN] | 201 |       127.0.0.1 | PUT     | /nnrf-nfm/v1/nf-instances/ca432ae6-de36-4512-b6aa-d80c03191dae |
2021-04-23T02:39:24Z [INFO][PCF][Init] PCF Log level is set to [info] level
2021-04-23T02:39:24Z [INFO][LIB][Path] set log level : info
2021-04-23T02:39:24Z [INFO][LIB][Path] set report call : false
2021-04-23T02:39:24Z [INFO][LIB][OAPI] set log level : info
2021-04-23T02:39:24Z [INFO][LIB][OAPI] set report call : false
2021-04-23T02:39:24Z [INFO][PCF][CFG] config version [1.0.0]
2021-04-23T02:39:24Z [INFO][PCF][Init] Server started
2021-04-23T02:39:24Z [INFO][PCF][Util] pcfconfig Info: Version[1.0.0] Description[PCF initial local configuration]
2021-04-23T02:39:24Z [INFO][NRF][MGMT] Handle NFRegisterRequest
2021-04-23T02:39:24Z [INFO][NRF][MGMT] urilist update
2021-04-23T02:39:24Z [INFO][NRF][MGMT] Create NF Profile
2021-04-23T02:39:24Z [INFO][NRF][MGMT] Location header:  http://127.0.0.10:8000/nnrf-nfm/v1/nf-instances/fd5b1f95-dcac-414b-b913-6d73b3b65368
2021-04-23T02:39:24Z [INFO][NRF][GIN] | 201 |       127.0.0.1 | PUT     | /nnrf-nfm/v1/nf-instances/fd5b1f95-dcac-414b-b913-6d73b3b65368 |
2021-04-23T02:39:24Z [INFO][NRF][DSCV] Handle NFDiscoveryRequest
2021-04-23T02:39:24Z [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:39:25Z [INFO][UDM][Init] UDM Log level is set to [info] level
2021-04-23T02:39:25Z [INFO][LIB][Path] set log level : info
2021-04-23T02:39:25Z [INFO][LIB][Path] set report call : false
2021-04-23T02:39:25Z [INFO][LIB][OAPI] set log level : info
2021-04-23T02:39:25Z [INFO][LIB][OAPI] set report call : false
2021-04-23T02:39:25Z [INFO][UDM][CFG] config version [1.0.0]
2021-04-23T02:39:25Z [INFO][UDM][Init] UDM Config Info: Version[1.0.0] Description[UDM initial local configuration]
2021-04-23T02:39:25Z [INFO][UDM][Init] Server started
2021-04-23T02:39:25Z [INFO][UDM][Util] udmconfig Info: Version[1.0.0] Description[UDM initial local configuration]
2021-04-23T02:39:25Z [INFO][NRF][MGMT] Handle NFRegisterRequest
2021-04-23T02:39:25Z [INFO][NRF][MGMT] urilist update
2021-04-23T02:39:25Z [INFO][NRF][MGMT] Create NF Profile
2021-04-23T02:39:25Z [INFO][NRF][MGMT] Location header:  http://127.0.0.10:8000/nnrf-nfm/v1/nf-instances/614fb3d8-eb2a-44b0-877c-8b8992132bac
2021-04-23T02:39:25Z [INFO][NRF][GIN] | 201 |       127.0.0.1 | PUT     | /nnrf-nfm/v1/nf-instances/614fb3d8-eb2a-44b0-877c-8b8992132bac |
2021-04-23T02:39:25Z [INFO][NSSF][Init] NSSF Log level is set to [info] level
2021-04-23T02:39:25Z [INFO][LIB][Path] set log level : info
2021-04-23T02:39:25Z [INFO][LIB][Path] set report call : false
2021-04-23T02:39:25Z [INFO][LIB][OAPI] set log level : info
2021-04-23T02:39:25Z [INFO][LIB][OAPI] set report call : false
2021-04-23T02:39:25Z [INFO][NSSF][CFG] config version [1.0.0]
2021-04-23T02:39:25Z [INFO][NSSF][Init] Server started
2021-04-23T02:39:25Z [INFO][NRF][MGMT] Handle NFRegisterRequest
2021-04-23T02:39:25Z [INFO][NRF][MGMT] urilist update
2021-04-23T02:39:25Z [INFO][NRF][MGMT] Create NF Profile
2021-04-23T02:39:25Z [INFO][NRF][MGMT] Location header:  http://127.0.0.10:8000/nnrf-nfm/v1/nf-instances/528288a7-1a69-464f-9790-20440404dcc0
2021-04-23T02:39:25Z [INFO][NRF][GIN] | 201 |       127.0.0.1 | PUT     | /nnrf-nfm/v1/nf-instances/528288a7-1a69-464f-9790-20440404dcc0 |
2021-04-23T02:39:25Z [INFO][AUSF][Init] AUSF Log level is set to [info] level
2021-04-23T02:39:25Z [INFO][LIB][Path] set log level : info
2021-04-23T02:39:25Z [INFO][LIB][Path] set report call : false
2021-04-23T02:39:25Z [INFO][LIB][OAPI] set log level : info
2021-04-23T02:39:25Z [INFO][LIB][OAPI] set report call : false
2021-04-23T02:39:25Z [INFO][AUSF][CFG] config version [1.0.0]
2021-04-23T02:39:25Z [INFO][AUSF][Init] Server started
2021-04-23T02:39:25Z [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} 9427f983-e48d-452c-bc90-3f8a45875360 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:{9427f983-e48d-452c-bc90-3f8a45875360 nausf-auth 0xc0006438c0 http REGISTERED   0xc0006438a0  [] <nil> [] [] <nil> 0 0 0 <nil> <nil> }] [{208 93} {123 45}]  0xc0001b5220}
2021-04-23T02:39:25Z [INFO][NRF][MGMT] Handle NFRegisterRequest
2021-04-23T02:39:25Z [INFO][NRF][MGMT] urilist update
2021-04-23T02:39:25Z [INFO][NRF][MGMT] Create NF Profile
2021-04-23T02:39:25Z [INFO][NRF][MGMT] Location header:  http://127.0.0.10:8000/nnrf-nfm/v1/nf-instances/9427f983-e48d-452c-bc90-3f8a45875360
2021-04-23T02:39:25Z [INFO][NRF][GIN] | 201 |       127.0.0.1 | PUT     | /nnrf-nfm/v1/nf-instances/9427f983-e48d-452c-bc90-3f8a45875360 |
=== RUN   TestServiceRequest
2021-04-23T02:39:25Z [INFO][AMF][NGAP] [AMF] SCTP Accept from: 127.0.0.1:9487
2021-04-23T02:39:25Z [INFO][AMF][NGAP] Create a new NG connection for: 127.0.0.1:9487
2021-04-23T02:39:25Z [INFO][AMF][NGAP][127.0.0.1:9487] Handle NG Setup request
2021-04-23T02:39:25Z [INFO][AMF][NGAP][127.0.0.1:9487] Send NG-Setup response
2021-04-23T02:39:25Z [INFO][AMF][NGAP][127.0.0.1:9487] Handle Initial UE Message
2021-04-23T02:39:25Z [INFO][AMF][GMM][AMF_UE_NGAP_ID:1] Handle Registration Request
2021-04-23T02:39:25Z [INFO][AMF][GMM][AMF_UE_NGAP_ID:1] Authentication procedure
2021-04-23T02:39:25Z [INFO][NRF][DSCV] Handle NFDiscoveryRequest
2021-04-23T02:39:25Z [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:39:25Z [INFO][AUSF][UeAuthPost] HandleUeAuthPostRequest
2021-04-23T02:39:25Z [INFO][AUSF][UeAuthPost] Serving network authorized
2021-04-23T02:39:25Z [INFO][NRF][DSCV] Handle NFDiscoveryRequest
2021-04-23T02:39:25Z [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:39:25Z [INFO][UDM][UEAU] Handle GenerateAuthDataRequest
2021-04-23T02:39:25Z [INFO][LIB][3GPP] suciPart [suci 0 208 93 0 0 0 00007487]
2021-04-23T02:39:25Z [INFO][LIB][3GPP] scheme 0
2021-04-23T02:39:25Z [INFO][LIB][3GPP] SUPI type is IMSI
http://127.0.0.10:8000
2021-04-23T02:39:25Z [INFO][NRF][DSCV] Handle NFDiscoveryRequest
2021-04-23T02:39:25Z [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:39:25Z [INFO][UDR][DRepo] Handle QueryAuthSubsData
2021-04-23T02:39:25Z [INFO][UDR][GIN] | 200 |       127.0.0.1 | GET     | /nudr-dr/v1/subscription-data/imsi-2089300007487/authentication-data/authentication-subscription |
2021-04-23T02:39:25Z [INFO][UDR][DRepo] Handle ModifyAuthentication
2021-04-23T02:39:25Z [INFO][UDR][GIN] | 204 |       127.0.0.1 | PATCH   | /nudr-dr/v1/subscription-data/imsi-2089300007487/authentication-data/authentication-subscription |
AUTN = 2b731cc415c9800018b8bd0a27fc8a57
2021-04-23T02:39:25Z [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:39:25Z [INFO][AUSF][UeAuthPost] Add SuciSupiPair (suci-0-208-93-0-0-0-00007487, imsi-2089300007487) to map.
2021-04-23T02:39:25Z [INFO][AUSF][UeAuthPost] Use 5G AKA auth method
2021-04-23T02:39:25Z [INFO][AUSF][5gAkaAuth] XresStar = 6261643737613038613139643565313463306336633363616331313232313162
2021-04-23T02:39:25Z [INFO][AUSF][GIN] | 201 |       127.0.0.1 | POST    | /nausf-auth/v1/ue-authentications |
2021-04-23T02:39:25Z [INFO][AMF][GMM][AMF_UE_NGAP_ID:1] Send Authentication Request
2021-04-23T02:39:25Z [INFO][AMF][NGAP][127.0.0.1:9487][AMF_UE_NGAP_ID:1] Send Downlink Nas Transport
2021-04-23T02:39:25Z [INFO][AMF][NGAP][127.0.0.1:9487] Handle Uplink Nas Transport
2021-04-23T02:39:25Z [INFO][AMF][NGAP][127.0.0.1:9487][AMF_UE_NGAP_ID:1] Uplink NAS Transport (RAN UE NGAP ID: 1)
2021-04-23T02:39:25Z [INFO][AMF][GMM][AMF_UE_NGAP_ID:1] Handle Authentication Response
2021/04/23 02:39:25 http2: server connection error from 127.0.0.1:57262: connection error: PROTOCOL_ERROR
2021-04-23T02:39:25Z [INFO][AUSF][5gAkaAuth] Auth5gAkaComfirmRequest
2021-04-23T02:39:25Z [INFO][AUSF][5gAkaAuth] res*: 6261643737613038613139643565313463306336633363616331313232313162
Xres*: 6261643737613038613139643565313463306336633363616331313232313162
2021-04-23T02:39:25Z [INFO][AUSF][5gAkaAuth] 5G AKA confirmation succeeded
2021-04-23T02:39:25Z [INFO][UDM][UEAU] Handle ConfirmAuthDataRequest
2021-04-23T02:39:25Z [INFO][UDR][DRepo] Handle CreateAuthenticationStatus
2021-04-23T02:39:25Z [INFO][UDR][GIN] | 204 |       127.0.0.1 | PUT     | /nudr-dr/v1/subscription-data/imsi-2089300007487/authentication-data/authentication-status |
2021-04-23T02:39:25Z [INFO][UDM][GIN] | 201 |       127.0.0.1 | POST    | /nudm-ueau/v1/imsi-2089300007487/auth-events |
2021-04-23T02:39:25Z [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:39:25Z [INFO][AMF][GMM][AMF_UE_NGAP_ID:1][SUPI:imsi-2089300007487] Send Security Mode Command
2021-04-23T02:39:25Z [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: 0x521ca638
err <nil>
2021-04-23T02:39:25Z [INFO][AMF][NGAP][127.0.0.1:9487] Handle Uplink Nas Transport
2021-04-23T02:39:25Z [INFO][AMF][NGAP][127.0.0.1:9487][AMF_UE_NGAP_ID:1] Uplink NAS Transport (RAN UE NGAP ID: 1)
2021-04-23T02:39:25Z [INFO][AMF][GMM][AMF_UE_NGAP_ID:1][SUPI:imsi-2089300007487] Handle Security Mode Complete
2021-04-23T02:39:25Z [INFO][AMF][GMM][AMF_UE_NGAP_ID:1][SUPI:imsi-2089300007487] Handle InitialRegistration
2021-04-23T02:39:25Z [INFO][NRF][DSCV] Handle NFDiscoveryRequest
2021-04-23T02:39:25Z [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:39:25Z [INFO][UDM][SDM] Handle GetNssai
2021-04-23T02:39:25Z [INFO][UDR][DRepo] Handle QueryAmData
2021-04-23T02:39:25Z [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:39:25Z [INFO][UDM][GIN] | 200 |       127.0.0.1 | GET     | /nudm-sdm/v1/imsi-2089300007487/nssai?plmn-id=20893 |
2021-04-23T02:39:25Z [INFO][NRF][DSCV] Handle NFDiscoveryRequest
2021-04-23T02:39:25Z [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:39:25Z [INFO][UDM][UECM] Handle RegistrationAmf3gppAccess
2021-04-23T02:39:25Z [INFO][UDM][UECM] UEID: imsi-2089300007487
http://127.0.0.10:8000
2021-04-23T02:39:25Z [INFO][NRF][DSCV] Handle NFDiscoveryRequest
2021-04-23T02:39:25Z [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:39:25Z [INFO][UDR][DRepo] Handle CreateAmfContext3gpp
2021-04-23T02:39:25Z [INFO][UDR][GIN] | 204 |       127.0.0.1 | PUT     | /nudr-dr/v1/subscription-data/imsi-2089300007487/context-data/amf-3gpp-access |
2021-04-23T02:39:25Z [INFO][UDM][GIN] | 201 |       127.0.0.1 | PUT     | /nudm-uecm/v1/imsi-2089300007487/registrations/amf-3gpp-access |
2021-04-23T02:39:25Z [INFO][UDM][SDM] Handle GetAmData
2021-04-23T02:39:25Z [INFO][UDR][DRepo] Handle QueryAmData
2021-04-23T02:39:25Z [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:39:25Z [INFO][UDM][GIN] | 200 |       127.0.0.1 | GET     | /nudm-sdm/v1/imsi-2089300007487/am-data?plmn-id=20893 |
2021-04-23T02:39:25Z [INFO][UDM][SDM] Handle GetSmfSelectData
http://127.0.0.10:8000
2021-04-23T02:39:25Z [INFO][NRF][DSCV] Handle NFDiscoveryRequest
2021-04-23T02:39:25Z [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:39:25Z [INFO][UDR][DRepo] Handle QuerySmfSelectData
2021-04-23T02:39:25Z [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:39:25Z [INFO][UDM][GIN] | 200 |       127.0.0.1 | GET     | /nudm-sdm/v1/imsi-2089300007487/smf-select-data?plmn-id=20893 |
2021-04-23T02:39:25Z [INFO][UDM][SDM] Handle GetUeContextInSmfData
http://127.0.0.10:8000
2021-04-23T02:39:25Z [INFO][NRF][DSCV] Handle NFDiscoveryRequest
2021-04-23T02:39:25Z [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:39:25Z [INFO][UDR][DRepo] Handle QuerySmfRegList
2021-04-23T02:39:25Z [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:39:25Z [INFO][UDM][GIN] | 200 |       127.0.0.1 | GET     | /nudm-sdm/v1/imsi-2089300007487/ue-context-in-smf-data |
2021-04-23T02:39:25Z [INFO][UDM][SDM] Handle Subscribe
http://127.0.0.10:8000
2021-04-23T02:39:25Z [INFO][NRF][DSCV] Handle NFDiscoveryRequest
2021-04-23T02:39:25Z [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:39:25Z [INFO][UDR][DRepo] Handle CreateSdmSubscriptions
2021-04-23T02:39:25Z [INFO][UDR][GIN] | 201 |       127.0.0.1 | POST    | /nudr-dr/v1/subscription-data/imsi-2089300007487/context-data/sdm-subscriptions |
2021-04-23T02:39:25Z [INFO][UDM][GIN] | 201 |       127.0.0.1 | POST    | /nudm-sdm/v1/imsi-2089300007487/sdm-subscriptions |
2021-04-23T02:39:25Z [INFO][NRF][DSCV] Handle NFDiscoveryRequest
2021-04-23T02:39:25Z [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:39:25Z [INFO][PCF][Ampolicy] Handle AM Policy Create Request
2021-04-23T02:39:25Z [INFO][NRF][DSCV] Handle NFDiscoveryRequest
2021-04-23T02:39:25Z [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:39:25Z [INFO][UDR][DRepo] Handle PolicyDataUesUeIdAmDataGet
2021-04-23T02:39:25Z [INFO][UDR][GIN] | 200 |       127.0.0.1 | GET     | /nudr-dr/v1/policy-data/ues/imsi-2089300007487/am-data |
2021-04-23T02:39:25Z [INFO][NRF][DSCV] Handle NFDiscoveryRequest
2021-04-23T02:39:25Z [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:39:25Z [INFO][AMF][Comm] Handle AMF Status Change Subscribe Request
2021-04-23T02:39:25Z [INFO][AMF][Comm] new AMF Status Subscription[1]
2021-04-23T02:39:25Z [INFO][AMF][GIN] | 201 |       127.0.0.1 | POST    | /namf-comm/v1/subscriptions |
2021-04-23T02:39:25Z [INFO][PCF][GIN] | 201 |       127.0.0.1 | POST    | /npcf-am-policy-control/v1/policies |
2021-04-23T02:39:25Z [INFO][AMF][GMM][AMF_UE_NGAP_ID:1][SUPI:imsi-2089300007487] Send Registration Accept
2021-04-23T02:39:25Z [INFO][AMF][NGAP][127.0.0.1:9487][AMF_UE_NGAP_ID:1] Send Initial Context Setup Request
2021-04-23T02:39:25Z [INFO][AMF][NGAP][127.0.0.1:9487] Handle Initial Context Setup Response
2021-04-23T02:39:25Z [INFO][NAS][Message] Encode ExtendedProtocolConfigurationOptions in EncodePDUSessionEstablishmentRequest
2021-04-23T02:39:26Z [INFO][AMF][NGAP][127.0.0.1:9487] Handle Uplink Nas Transport
2021-04-23T02:39: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:39:26Z [INFO][AMF][GMM][AMF_UE_NGAP_ID:1][SUPI:imsi-2089300007487] Handle Registration Complete
2021-04-23T02:39:26Z [INFO][AMF][NGAP][127.0.0.1:9487] Handle Uplink Nas Transport
2021-04-23T02:39: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:39:26Z [INFO][AMF][GMM][AMF_UE_NGAP_ID:1][SUPI:imsi-2089300007487] Handle UL NAS Transport
2021-04-23T02:39:26Z [INFO][AMF][GMM][AMF_UE_NGAP_ID:1][SUPI:imsi-2089300007487] Transport 5GSM Message to SMF
2021-04-23T02:39:26Z [INFO][AMF][GMM][AMF_UE_NGAP_ID:1][SUPI:imsi-2089300007487] Select SMF [snssai: {Sst:1 Sd:010203}, dnn: internet]
2021-04-23T02:39:26Z [INFO][NRF][DSCV] Handle NFDiscoveryRequest
2021-04-23T02:39:26Z [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:39:26Z [INFO][NSSF][NsSelect] Handle NSSelectionGet
2021-04-23T02:39:26Z [INFO][NSSF][GIN] | 200 |       127.0.0.1 | GET     | /nnssf-nsselection/v1/network-slice-information?nf-id=81c4e7b6-bc4d-4276-9f45-5b4252f4b999&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:39:26Z [INFO][NRF][DSCV] Handle NFDiscoveryRequest
2021-04-23T02:39:26Z [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:39:26Z [INFO][SMF][PduSess] Recieve Create SM Context Request
2021-04-23T02:39:26Z [INFO][SMF][PduSess] In HandlePDUSessionSMContextCreate
2021-04-23T02:39:26Z [INFO][NRF][DSCV] Handle NFDiscoveryRequest
2021-04-23T02:39:26Z [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:39:26Z [INFO][SMF][PduSess] Send NF Discovery Serving UDM Successfully
2021-04-23T02:39:26Z [INFO][SMF][CTX] Allocated UE IP address: 60.60.0.1
2021-04-23T02:39:26Z [INFO][SMF][CTX] Selected UPF: UPF
2021-04-23T02:39:26Z [INFO][SMF][PduSess] UE[imsi-2089300007487] PDUSessionID[10] IP[60.60.0.1]
2021-04-23T02:39:26Z [INFO][UDM][SDM] Handle GetSmData
2021-04-23T02:39:26Z [INFO][UDM][SDM] getSmDataProcedure: SUPI[imsi-2089300007487] PLMNID[20893] DNN[internet] SNssai[{"sst":1,"sd":"010203"}]
2021-04-23T02:39:26Z [INFO][UDR][DRepo] Handle QuerySmData
2021-04-23T02:39:26Z [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:39:26Z [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:39:26Z [INFO][SMF][GSM] In HandlePDUSessionEstablishmentRequest
2021-04-23T02:39:26Z [INFO][NAS][Convert] ProtocolOrContainerList:  [0xc0009a5fe0 0xc0009e4020 0xc0009e4060]
2021-04-23T02:39:26Z [INFO][SMF][GSM] Protocol Configuration Options
2021-04-23T02:39:26Z [INFO][SMF][GSM] &{[0xc0009a5fe0 0xc0009e4020 0xc0009e4060]}
2021-04-23T02:39:26Z [INFO][SMF][GSM] Didn't Implement container type IPAddressAllocationViaNASSignallingUL
2021-04-23T02:39:26Z [INFO][SMF][PduSess] PCF Selection for SMContext SUPI[imsi-2089300007487] PDUSessionID[10]
2021-04-23T02:39:26Z [INFO][NRF][DSCV] Handle NFDiscoveryRequest
2021-04-23T02:39:26Z [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:39:26Z [INFO][PCF][SMpolicy] Handle CreateSmPolicy
2021-04-23T02:39:26Z [INFO][UDR][DRepo] Handle PolicyDataUesUeIdSmDataGet
2021-04-23T02:39:26Z [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:39:26Z [INFO][PCF][GIN] | 201 |       127.0.0.1 | POST    | /npcf-smpolicycontrol/v1/sm-policies |
2021-04-23T02:39:26Z [INFO][SMF][PduSess] SUPI[imsi-2089300007487] has no pre-config route
2021-04-23T02:39:26Z [INFO][NRF][DSCV] Handle NFDiscoveryRequest
2021-04-23T02:39:26Z [INFO][NRF][GIN] | 200 |       127.0.0.1 | GET     | /nnrf-disc/v1/nf-instances?requester-nf-type=SMF&target-nf-instance-id=81c4e7b6-bc4d-4276-9f45-5b4252f4b999&target-nf-type=AMF |
2021-04-23T02:39:26Z [INFO][SMF][Consumer] SendNFDiscoveryServingAMF ok
2021-04-23T02:39:26Z [INFO][SMF][GIN] | 201 |       127.0.0.1 | POST    | /nsmf-pdusession/v1/sm-contexts |
2021-04-23T02:39:26Z [INFO][UPF][Util] [PFCP] Handle PFCP session establishment request
2021-04-23T02:39:26Z [INFO][UPF][Util] [PFCP] Session Establishment Response
2021-04-23T02:39:26Z [INFO][SMF][PFCP] In HandlePfcpSessionEstablishmentResponse
&{1000 Kbps 1000 Kbps}
2021-04-23T02:39:26Z [INFO][LIB][PFCP] Remove Request Transaction [2]
2021-04-23T02:39:26Z [INFO][AMF][GMM][AMF_UE_NGAP_ID:1][SUPI:imsi-2089300007487] create smContext[pduSessionID: 10] Success
2021-04-23T02:39:26Z [INFO][AMF][Producer] Handle N1N2 Message Transfer Request
2021-04-23T02:39:26Z [INFO][AMF][NGAP][127.0.0.1:9487][AMF_UE_NGAP_ID:1] Send PDU Session Resource Setup Request
2021-04-23T02:39:26Z [INFO][AMF][GIN] | 200 |       127.0.0.1 | POST    | /namf-comm/v1/ue-contexts/imsi-2089300007487/n1-n2-messages |
2021-04-23T02:39:26Z [INFO][AMF][NGAP][127.0.0.1:9487] Handle PDU Session Resource Setup Response
2021-04-23T02:39:26Z [INFO][SMF][PduSess] Recieve Update SM Context Request
2021-04-23T02:39:26Z [INFO][SMF][PduSess] In HandlePDUSessionSMContextUpdate
2021-04-23T02:39:26Z [INFO][UPF][Util] [PFCP] Handle PFCP session modification request
2021-04-23T02:39:26Z [INFO][UPF][Util] [PFCP] Session Modification Response
2021-04-23T02:39:26Z [INFO][SMF][PFCP] In HandlePfcpSessionModificationResponse
2021-04-23T02:39:26Z [INFO][SMF][PduSess] [SMF] PFCP Modification Resonse Accept
2021-04-23T02:39:26Z [INFO][SMF][PFCP] PFCP Session Modification Success[1]
2021-04-23T02:39:26Z [INFO][SMF][GIN] | 200 |       127.0.0.1 | POST    | /nsmf-pdusession/v1/sm-contexts/urn:uuid:2adbcba5-ae2c-4e76-a6a9-273758538182/modify |
2021-04-23T02:39:26Z [INFO][LIB][PFCP] Remove Request Transaction [3]
2021-04-23T02:39:26Z [INFO][AMF][NGAP][127.0.0.1:9487] UE Context Release Request
2021-04-23T02:39:26Z [WARN][AMF][NGAP][127.0.0.1:9487] Cause RadioNetwork[1]
2021-04-23T02:39:26Z [INFO][AMF][NGAP][127.0.0.1:9487][AMF_UE_NGAP_ID:1] Ue Context in GMM-Registered
2021-04-23T02:39:26Z [INFO][SMF][PduSess] Recieve Update SM Context Request
2021-04-23T02:39:26Z [INFO][SMF][PduSess] In HandlePDUSessionSMContextUpdate
2021-04-23T02:39:26Z [INFO][UPF][Util] [PFCP] Handle PFCP session modification request
2021-04-23T02:39:26Z [INFO][UPF][Util] [PFCP] Session Modification Response
2021-04-23T02:39:26Z [INFO][SMF][PFCP] In HandlePfcpSessionModificationResponse
2021-04-23T02:39:26Z [INFO][SMF][PduSess] [SMF] PFCP Modification Resonse Accept
2021-04-23T02:39:26Z [INFO][SMF][PFCP] PFCP Session Modification Success[1]
2021-04-23T02:39:26Z [INFO][SMF][GIN] | 200 |       127.0.0.1 | POST    | /nsmf-pdusession/v1/sm-contexts/urn:uuid:2adbcba5-ae2c-4e76-a6a9-273758538182/modify |
2021-04-23T02:39:26Z [INFO][LIB][PFCP] Remove Request Transaction [4]
2021-04-23T02:39:26Z [INFO][AMF][NGAP][127.0.0.1:9487][AMF_UE_NGAP_ID:1] Send UE Context Release Command
2021-04-23T02:39:26Z [INFO][AMF][NGAP][127.0.0.1:9487] Handle UE Context Release Complete
2021-04-23T02:39:26Z [INFO][AMF][NGAP][127.0.0.1:9487][AMF_UE_NGAP_ID:1] Rel Ue Context in GMM-Registered
2021-04-23T02:39:26Z [INFO][AMF][NGAP][127.0.0.1:9487] Release UE[imsi-2089300007487] Context : N2 Connection Release
2021-04-23T02:39:27Z [INFO][AMF][NGAP][127.0.0.1:9487] Handle Initial UE Message
2021-04-23T02:39:27Z [INFO][AMF][GMM][AMF_UE_NGAP_ID:1][SUPI:imsi-2089300007487] Handle Service Request
2021-04-23T02:39:27Z [INFO][SMF][PduSess] Recieve Update SM Context Request
2021-04-23T02:39:27Z [INFO][SMF][PduSess] In HandlePDUSessionSMContextUpdate
2021-04-23T02:39:27Z [INFO][SMF][GIN] | 200 |       127.0.0.1 | POST    | /nsmf-pdusession/v1/sm-contexts/urn:uuid:2adbcba5-ae2c-4e76-a6a9-273758538182/modify |
2021-04-23T02:39:27Z [INFO][AMF][NGAP][127.0.0.1:9487][AMF_UE_NGAP_ID:2] Send Initial Context Setup Request
2021-04-23T02:39:27Z [INFO][AMF][NGAP][127.0.0.1:9487] Handle Initial Context Setup Response
2021-04-23T02:39:27Z [INFO][SMF][PduSess] Recieve Update SM Context Request
2021-04-23T02:39:27Z [INFO][SMF][PduSess] In HandlePDUSessionSMContextUpdate
2021-04-23T02:39:27Z [INFO][UPF][Util] [PFCP] Handle PFCP session modification request
2021-04-23T02:39:27Z [INFO][UPF][Util] [PFCP] Session Modification Response
2021-04-23T02:39:27Z [INFO][SMF][PFCP] In HandlePfcpSessionModificationResponse
2021-04-23T02:39:27Z [INFO][SMF][PduSess] [SMF] PFCP Modification Resonse Accept
2021-04-23T02:39:27Z [INFO][SMF][PFCP] PFCP Session Modification Success[1]
2021-04-23T02:39:27Z [INFO][SMF][GIN] | 200 |       127.0.0.1 | POST    | /nsmf-pdusession/v1/sm-contexts/urn:uuid:2adbcba5-ae2c-4e76-a6a9-273758538182/modify |
2021-04-23T02:39:27Z [INFO][LIB][PFCP] Remove Request Transaction [5]
2021-04-23T02:39:28Z [INFO][NRF][Init] Terminating NRF...
2021-04-23T02:39:28Z [INFO][NRF][Init] NRF terminated
2021-04-23T02:39:28Z [INFO][AMF][Init] Terminating AMF...
2021-04-23T02:39:28Z [INFO][AMF][Consumer] [AMF] Send Deregister NFInstance
2021-04-23T02:39:28Z [INFO][AMF][NGAP] Handle SCTP Notification[addr: <nil>]
2021-04-23T02:39:28Z [INFO][AMF][NGAP][127.0.0.1:9487] SCTP_SHUTDOWN_EVENT notification, close the connection
2021-04-23T02:39:28Z [INFO][AMF][NGAP][127.0.0.1:9487] Remove RAN Context[ID: <PlmnID: {Mcc:208 Mnc:93}, GNbID: 000102>]
2021-04-23T02:39:28Z [INFO][AMF][NGAP] Handle SCTP Notification[addr: <nil>]
2021-04-23T02:39:28Z [WARN][AMF][NGAP] RAN context has been removed[addr: <nil>]
2021-04-23T02:39:28Z [INFO][NRF][MGMT] Handle NFDeregisterRequest
2021-04-23T02:39:29Z [INFO][NRF][GIN] | 204 |       127.0.0.1 | DELETE  | /nnrf-nfm/v1/nf-instances/81c4e7b6-bc4d-4276-9f45-5b4252f4b999 |
2021-04-23T02:39:29Z [INFO][AMF][Init] [AMF] Deregister from NRF successfully
2021-04-23T02:39:29Z [INFO][AMF][Init] Send AMF Status Indication to Notify RANs due to AMF terminating
2021-04-23T02:39:29Z [INFO][AMF][NGAP] Close SCTP server...
2021-04-23T02:39:29Z [INFO][AMF][NGAP] SCTP server closed
2021-04-23T02:39:29Z [INFO][AMF][Producer] [AMF] Send Amf Status Change Notify to http://127.0.0.7:8000/npcf-callback/v1/amfstatus
2021-04-23T02:39:29Z [WARN][PCF][Callback] [PCF] Handle Amf Status Change Notify is not implemented.
2021-04-23T02:39:29Z [INFO][PCF][GIN] | 204 |       127.0.0.1 | POST    | /npcf-callback/v1/amfstatus |
2021-04-23T02:39:29Z [INFO][AMF][Init] AMF terminated
2021-04-23T02:39:29Z [INFO][SMF][Init] Terminating SMF...
2021-04-23T02:39:29Z [INFO][SMF][Consumer] Send Deregister NFInstance
2021-04-23T02:39:29Z [INFO][NRF][MGMT] Handle NFDeregisterRequest
2021-04-23T02:39:30Z [INFO][NRF][GIN] | 204 |       127.0.0.1 | DELETE  | /nnrf-nfm/v1/nf-instances/580ac3d1-c467-433e-a4c2-191fc235f448 |
2021-04-23T02:39:30Z [INFO][SMF][Init] Deregister from NRF successfully
2021-04-23T02:39:30Z [INFO][UDR][Init] Terminating UDR...
2021-04-23T02:39:30Z [INFO][UDR][Consumer] Send Deregister NFInstance
2021-04-23T02:39:30Z [INFO][NRF][MGMT] Handle NFDeregisterRequest
2021-04-23T02:39:31Z [INFO][NRF][GIN] | 204 |       127.0.0.1 | DELETE  | /nnrf-nfm/v1/nf-instances/ca432ae6-de36-4512-b6aa-d80c03191dae |
2021-04-23T02:39:31Z [INFO][UDR][Init] Deregister from NRF successfully
2021-04-23T02:39:31Z [INFO][UDR][Init] UDR terminated
2021-04-23T02:39:31Z [INFO][PCF][Init] Terminating PCF...
2021-04-23T02:39:31Z [INFO][PCF][Consumer] Send Deregister NFInstance
2021-04-23T02:39:31Z [INFO][NRF][MGMT] Handle NFDeregisterRequest
2021-04-23T02:39:32Z [INFO][NRF][GIN] | 204 |       127.0.0.1 | DELETE  | /nnrf-nfm/v1/nf-instances/fd5b1f95-dcac-414b-b913-6d73b3b65368 |
2021-04-23T02:39:32Z [INFO][PCF][Init] Deregister from NRF successfully
2021-04-23T02:39:32Z [INFO][PCF][Init] PCF terminated
2021-04-23T02:39:32Z [INFO][UDM][Init] Terminating UDM...
2021-04-23T02:39:32Z [INFO][UDM][Consumer] Send Deregister NFInstance
2021-04-23T02:39:32Z [INFO][NRF][MGMT] Handle NFDeregisterRequest
2021-04-23T02:39:33Z [INFO][NRF][GIN] | 204 |       127.0.0.1 | DELETE  | /nnrf-nfm/v1/nf-instances/614fb3d8-eb2a-44b0-877c-8b8992132bac |
2021-04-23T02:39:33Z [INFO][UDM][Init] Deregister from NRF successfully
2021-04-23T02:39:33Z [INFO][UDM][Init] UDM terminated
2021-04-23T02:39:33Z [INFO][NSSF][Init] Terminating NSSF...
2021-04-23T02:39:33Z [INFO][NSSF][App] Send Deregister NFInstance
2021-04-23T02:39:33Z [INFO][NRF][MGMT] Handle NFDeregisterRequest
2021-04-23T02:39:34Z [INFO][NRF][GIN] | 204 |       127.0.0.1 | DELETE  | /nnrf-nfm/v1/nf-instances/528288a7-1a69-464f-9790-20440404dcc0 |
2021-04-23T02:39:34Z [INFO][NSSF][Init] Deregister from NRF successfully
2021-04-23T02:39:34Z [INFO][NSSF][Init] NSSF terminated
2021-04-23T02:39:34Z [INFO][AUSF][Init] Terminating AUSF...
2021-04-23T02:39:34Z [INFO][AUSF][App] Send Deregister NFInstance
2021-04-23T02:39:34Z [INFO][NRF][MGMT] Handle NFDeregisterRequest
2021-04-23T02:39:35Z [INFO][NRF][GIN] | 204 |       127.0.0.1 | DELETE  | /nnrf-nfm/v1/nf-instances/9427f983-e48d-452c-bc90-3f8a45875360 |
2021-04-23T02:39:35Z [INFO][AUSF][Init] Deregister from NRF successfully
2021-04-23T02:39:35Z [INFO][AUSF][Init] AUSF terminated
--- PASS: TestServiceRequest (9.84s)
PASS
ok      test    11.502s
2021-04-23T02:39:38Z [INFO][UPF][Util] Removing DNN routes
ls: cannot access '*sslkey.log': No such file or directory