Tcpdump no SBI found

Q/A
#1

Hi and Thank’s for your support
Now I think All test run successfully

root@free5g:~/go/src/free5gc#  ./test.sh TestRegistration
INFO[2020-06-15T22:55:59Z] c/upf/src/upf_init.c:35 UpfInit() Config: /root/go/src/free5gc/src/upf/build/config/upfcfg.test.yaml  UPF=Util
INFO[2020-06-15T22:55:59Z] Set log level: info                           UPF=Util
INFO[2020-06-15T22:55:59Z] c/upf/src/up/up_path.c:40 UpRouteInit() APN routes added, main routing table:  UPF=Util
INFO[2020-06-15T22:55:59Z] c/upf/lib/knet/src/route.c:202 KnetPrintRoutes() DstIp               Gateway        Iface     Priority RtProto   Type         UPF=Util
INFO[2020-06-15T22:55:59Z] c/upf/lib/knet/src/route.c:237 KnetPrintRoutes() 10.0.200.0 /24      0.0.0.0        veth1     0        kernel    unicast      UPF=Util
INFO[2020-06-15T22:55:59Z] c/upf/lib/knet/src/route.c:237 KnetPrintRoutes() 60.60.0.0 /24       0.0.0.0        upfgtp0   0        static    unicast      UPF=Util
INFO[2020-06-15T22:55:59Z] c/upf/src/upf_init.c:77 UpfInit() UPF initialized  UPF=Util
CommonConfig file: ../../config/free5GC.conf
INFO[2020-06-15T22:56:14Z]/root/go/src/free5gc/lib/nas/logger/logger.go:45 free5gc/lib/nas/logger.SetLogLevel() set log level : info                          NAS=message
.
.
.
free5gc/src/nrf/nrf_service.(*NRF).Start() Server started                                NRF=init
INFO[2020-06-15T22:56:14Z]/root/go/src/free5gc/src/nrf/nrf_context/nrf_context.go:19 free5gc/src/nrf/nrf_context.InitNrfContext() nrfconfig Info: Version[1.0.0] Description[NRF initial local configuration]  NRF=init

INFO[2020-06-15T22:56:15Z]/root/go/src/free5gc/src/smf/smf_service/smf_init.go:92 free5gc/src/smf/smf_service.(*SMF).Initialize() SMF debug level(string): info                 SMF=init
INFO[2020-06-15T22:56:15Z]/root/go/src/free5gc/src/smf/smf_context/smf_context.go:69 free5gc/src/smf/smf_context.InitSmfContext() smfconfig Info: Version[1.0.0] Description[AMF initial local configuration]  SMF=Context
INFO[2020-06-15T22:56:15Z]/root/go/src/free5gc/src/smf/smf_context/smf_context.go:142 free5gc/src/smf/smf_context.InitSMFUERouting() ue routing config Info: Version[1.0.0] Description[Routing information for UE]  SMF=Context
WARN[2020-06-15T22:56:15Z]/root/go/src/free5gc/src/smf/smf_context/smf_context.go:161 free5gc/src/smf/smf_context.InitSMFUERouting() UPNode BranchingUPF isn't exist in smfcfg.conf, but in UERouting.yaml!  SMF=Context
WARN[2020-06-15T22:56:15Z]/root/go/src/free5gc/src/smf/smf_context/smf_context.go:161 free5gc/src/smf/smf_context.InitSMFUERouting() UPNode BranchingUPF isn't exist in smfcfg.conf, but in UERouting.yaml!  SMF=Context
INFO[2020-06-15T22:56:15Z]/root/go/src/free5gc/src/smf/smf_service/smf_init.go:121 free5gc/src/smf/smf_service.(*SMF).Start() Server started                                SMF=init
INFO[2020-06-15T22:56:15Z]/root/go/src/free5gc/src/nrf/Management/api_management.go:698 free5gc/src/nrf/Management.setLocationHeader() UriList update                                nfig Info: 
.
.
.
Version[1.0.0] Description[UDR initial local configuration]  UDR=Util
INFO[2020-06-15T22:56:15Z]/root/go/src/free5gc/src/nrf/Management/api_management.go:698 free5gc/src/nrf/Management.setLocationHeader() UriList update                                NRF=ManagementLog
[GIN] 2020/06/15 - 22:56:15 | 201 |   12.729434ms |       127.0.0.1 | PUT      /nnrf-nfm/v1/nf-instances/453cc1d0-6968-4e9d-b8f6-5006d54a0247
INFO[2020-06-15T22:56:15Z]/root/go/src/free5gc/src/pcf/factory/factory.go:35 free5gc/src/pcf/factory.InitConfigFactory() Successfully initialize configuration ../../config/pcfcfg.conf  PCF=init
INFO[2020-06-15T22:56:15Z]/root/go/src/free5gc/src/pcf/pcf_service/pcf_init.go:78 free5gc/src/pcf/pcf_service.(*PCF).Initialize() PCF debug level(string): info                 PCF=init
INFO[2020-06-15T22:56:15Z]/root/go/src/free5gc/src/pcf/pcf_service/pcf_init.go:102 free5gc/src/pcf/pcf_service.(*PCF).Start() Server started                                PCF=init
INFO[2020-06-15T22:56:15Z]/root/go/src/free5gc/src/pcf/pcf_util/initContext.go:16 free5gc/src/pcf/pcf_util.InitpcfContext() pcfconfig Info: Version[1.0.0] Description[PCF initial local configuration]  PCF=Util
INFO[2020-06-15T22:56:15Z]/root/go/src/free5gc/src/nrf/Management/api_management.go:698 free5gc/src/nrf/Management.setLocationHeader() UriList update                                NRF=ManagementLog
[GIN] 2020/06/15 - 22:56:15 | 201 |   14.916666ms |       127.0.0.1 | PUT      /nnrf-nfm/v1/nf-instances/6f674fb8-d6d1-44d3-b07e-b2f921579c21
2020/06/15 22:56:15 map[$and:[map[nfType:AMF] map[$or:[map[allowedNfTypes:PCF] map[allowedNfTypes:map[$exists:false]]]]]]
[GIN] 2020/06/15 - 22:56:15 | 200 |  103.344582ms |       127.0.0.1 | GET      /nnrf-disc/v1/nf-instances?requester-nf-type=PCF&target-nf-type=AMF
[GIN] 2020/06/15 - 22:56:15 | 201 |     158.136µs |       127.0.0.1 | POST     /namf-comm/v1/subscriptions
2020/06/15 22:56:15 map[$and:[map[nfType:UDR] map[$or:[map[allowedNfTypes:PCF] map[allowedNfTypes:map[$exists:false]]]] map[nfServices:map[$elemMatch:map[nfServiceStatus:REGISTERED serviceName:map[$in:[nudr-dr]]]]]]]
INFO[2020-06-15T22:56:15Z]/root/go/src/free5gc/src/udm/factory/factory.go:35 free5gc/src/udm/factory.InitConfigFactory() Successfully initialize configuration ../../config/udmcfg.conf  UDM=init
INFO[2020-06-15T22:56:15Z]/root/go/src/free5gc/src/udm/udm_service/udm_init.go:75 free5gc/src/udm/udm_service.(*UDM).Initialize() UDM debug level(string): info                 UDM=init
INFO[2020-06-15T22:56:15Z]/root/go/src/free5gc/src/udm/udm_service/udm_init.go:105 free5gc/src/udm/udm_service.(*UDM).Start() UDM Config Info: Version[1.0.0] Description[UDM initial local configuration]  UDM=init
INFO[2020-06-15T22:56:15Z]/root/go/src/free5gc/src/udm/udm_service/udm_init.go:107 free5gc/src/udm/udm_service.(*UDM).Start() Server started                                UDM=init
INFO[2020-06-15T22:56:15Z]/root/go/src/free5gc/src/udm/udm_context/udm_context_init.go:21 free5gc/src/udm/udm_context.InitUDMContext() udmconfig Info: Version[1.0.0] Description[UDM initial local configuration]  UDM=Util
[GIN] 2020/06/15 - 22:56:15 | 200 |   40.929679ms |       127.0.0.1 | GET      /nnrf-disc/v1/nf-instances?requester-nf-type=PCF&service-names=nudr-dr&target-nf-type=UDR
INFO[2020-06-15T22:56:15Z]/root/go/src/free5gc/src/nrf/Management/api_management.go:698 free5gc/src/nrf/Management.setLocationHeader() UriList update                                NRF=ManagementLog
[GIN] 2020/06/15 - 22:56:15 | 201 |   30.542223ms |       127.0.0.1 | PUT      /nnrf-nfm/v1/nf-instances/8e98eda9-1359-4fe9-87c4-616a3cc94b17
WARN[2020-06-15T22:56:16Z]/root/go/src/free5gc/src/nssf/nssf_service/nssf_init.go:72 free5gc/src/nssf/nssf_service.(*NSSF).Initialize() No configuration file is provided             NSSF=init
INFO[2020-06-15T22:56:16Z]/root/go/src/free5gc/src/nssf/factory/factory.go:42 free5gc/src/nssf/factory.InitConfigFactory() Successfully initialize configuration ../../config/nssfcfg.conf  NSSF=factory
INFO[2020-06-15T22:56:16Z] Server started                                NSSF=init
INFO[2020-06-15T22:56:16Z]/root/go/src/free5gc/src/nrf/Management/api_management.go:698 free5gc/src/nrf/Management.setLocationHeader() UriList update                                NRF=ManagementLog
[GIN] 2020/06/15 - 22:56:16 | 201 |   10.853927ms |       127.0.0.1 | PUT      /nnrf-nfm/v1/nf-instances/cf3936cf-b482-400f-bb0d-74d6180dfaf0
INFO[2020-06-15T22:56:16Z]/root/go/src/free5gc/src/ausf/factory/factory.go:35 free5gc/src/ausf/factory.InitConfigFactory() Successfully initialize configuration ../../config/ausfcfg.conf  AUSF=init
INFO[2020-06-15T22:56:16Z]/root/go/src/free5gc/src/ausf/ausf_service/ausf_init.go:70 free5gc/src/ausf/ausf_service.(*AUSF).Initialize() AUSF debug level(string): info                AUSF=init
INFO[2020-06-15T22:56:16Z]/root/go/src/free5gc/src/ausf/ausf_service/ausf_init.go:95 free5gc/src/ausf/ausf_service.(*AUSF).Start() Server started                                AUSF=init
INFO[2020-06-15T22:56:16Z]/root/go/src/free5gc/src/ausf/ausf_context/ausf_context_init.go:22 free5gc/src/ausf/ausf_context.InitAusfContext() ausfconfig Info: Version[1.0.0] Description[AUSF initial local configuration]  AUSF=init
ausf context =  &{6c639ed9-c6e2-453d-a0aa-00436eec77fa ausfGroup001 29509 127.0.0.1 https://127.0.0.1:29509 https https://localhost:29510 map[nausf-auth:{6c639ed9-c6e2-453d-a0aa-00436eec77fa nausf-auth 0xc0027192c0 https REGISTERED   0xc0027192a0  [] <nil> [] [] <nil> 0 0 0 <nil> <nil> }] [{208 93} {123 45}] }
INFO[2020-06-15T22:56:16Z]/root/go/src/free5gc/src/smf/smf_pfcp/pfcp_handler/handler.go:75 free5gc/src/smf/smf_pfcp/pfcp_handler.HandlePfcpAssociationSetupResponse() UPF(10.0.200.101)[internet] setup association  SMF=pfcp
INFO[2020-06-15T22:56:16Z]/root/go/src/free5gc/src/nrf/Management/api_management.go:698 free5gc/src/nrf/Management.setLocationHeader() UriList update                                NRF=ManagementLog
[GIN] 2020/06/15 - 22:56:16 | 201 |   12.835402ms |       127.0.0.1 | PUT      /nnrf-nfm/v1/nf-instances/6c639ed9-c6e2-453d-a0aa-00436eec77fa
=== RUN   TestRegistration
INFO[2020-06-15T22:56:16Z]/root/go/src/free5gc/src/amf/amf_ngap/ngap_sctp/amf_ngap_sctp.go:42 free5gc/src/amf/amf_ngap/ngap_sctp.Server.func1() [AMF] NGAP SCTP Accept from: 127.0.0.1:9487   AMF=NGAP
INFO[2020-06-15T22:56:16Z]/root/go/src/free5gc/src/amf/amf_ngap/ngap_handler/handler.go:54 free5gc/src/amf/amf_ngap/ngap_handler.HandleNGSetupRequest() [AMF] NG Setup request                        AMF=NGAP
INFO[2020-06-15T22:56:16Z]/root/go/src/free5gc/src/amf/amf_ngap/ngap_message/send.go:79 free5gc/src/amf/amf_ngap/ngap_message.SendNGSetupResponse() [AMF] Send NG-Setup response                  AMF=NGAP
INFO[2020-06-15T22:56:16Z]/root/go/src/free5gc/src/amf/amf_ngap/ngap_handler/handler.go:887 free5gc/src/amf/amf_ngap/ngap_handler.HandleInitialUEMessage() [AMF] Initial UE Message                      AMF=NGAP
INFO[2020-06-15T22:56:16Z]/root/go/src/free5gc/src/amf/gmm/gmm_handler/handler.go:492 free5gc/src/amf/gmm/gmm_handler.HandleRegistrationRequest() [AMF] Handle Registration Request             AMF=Gmm
INFO[2020-06-15T22:56:16Z]/root/go/src/free5gc/src/amf/gmm/gmm_handler/handler.go:1470 free5gc/src/amf/gmm/gmm_handler.startAuthenticationProcedure() Start authentication procedure                AMF=Gmm
2020/06/15 22:56:16 map[$and:[map[nfType:AUSF] map[$or:[map[allowedNfTypes:AMF] map[allowedNfTypes:map[$exists:false]]]]]]
[GIN] 2020/06/15 - 22:56:16 | 200 |   20.698802ms |       127.0.0.1 | GET      /nnrf-disc/v1/nf-instances?requester-nf-type=AMF&target-nf-type=AUSF
INFO[2020-06-15T22:56:16Z]/root/go/src/free5gc/src/ausf/ausf_producer/UEAuthentication.go:182 free5gc/src/ausf/ausf_producer.HandleUeAuthPostRequest() Serving network authorized                    AUSF=UeAuthPost
2020/06/15 22:56:16 map[$and:[map[nfType:UDM] map[$or:[map[allowedNfTypes:AUSF] map[allowedNfTypes:map[$exists:false]]]] map[nfServices:map[$elemMatch:map[nfServiceStatus:REGISTERED serviceName:map[$in:[nudm-ueau]]]]]]]
[GIN] 2020/06/15 - 22:56:16 | 200 |   75.160552ms |       127.0.0.1 | GET      /nnrf-disc/v1/nf-instances?requester-nf-type=AUSF&service-names=nudm-ueau&target-nf-type=UDM
INFO[2020-06-15T22:56:16Z]/root/go/src/free5gc/lib/util_3gpp/suci/toSupi.go:260 free5gc/lib/util_3gpp/suci.ToSupi() suciPart [suci 0 208 93 0 0 0 00007487]       UDM=UEAU
INFO[2020-06-15T22:56:16Z]/root/go/src/free5gc/lib/util_3gpp/suci/toSupi.go:278 free5gc/lib/util_3gpp/suci.ToSupi() scheme 0                                      UDM=UEAU
INFO[2020-06-15T22:56:16Z]/root/go/src/free5gc/lib/util_3gpp/suci/toSupi.go:285 free5gc/lib/util_3gpp/suci.ToSupi() SUPI type is IMSI                             UDM=UEAU
INFO[2020-06-15T22:56:16Z]/root/go/src/free5gc/src/udm/udm_producer/generate_auth_data.go:34 free5gc/src/udm/udm_producer.HandleGenerateAuthData() supi conversion => imsi-2089300007487         UDM=UEAU
https://127.0.0.1:29510
2020/06/15 22:56:16 map[$and:[map[nfType:UDR] map[$or:[map[allowedNfTypes:UDM] map[allowedNfTypes:map[$exists:false]]]]]]
[GIN] 2020/06/15 - 22:56:16 | 200 |   18.538159ms |       127.0.0.1 | GET      /nnrf-disc/v1/nf-instances?requester-nf-type=UDM&target-nf-type=UDR
[GIN] 2020/06/15 - 22:56:16 | 200 |    1.747009ms |       127.0.0.1 | GET      /nudr-dr/v1/subscription-data/imsi-2089300007487/authentication-data/authentication-subscription
[GIN] 2020/06/15 - 22:56:16 | 200 |   48.937447ms |       127.0.0.1 | POST     /nudm-ueau/v1/suci-0-208-93-0-0-0-00007487/security-information/generate-auth-data
INFO[2020-06-15T22:56:16Z]/root/go/src/free5gc/src/ausf/ausf_producer/UEAuthentication.go:211 free5gc/src/ausf/ausf_producer.HandleUeAuthPostRequest() Add SuciSupiPair (suci-0-208-93-0-0-0-00007487, imsi-2089300007487) to map.  AUSF=UeAuthPost
INFO[2020-06-15T22:56:16Z]/root/go/src/free5gc/src/ausf/ausf_producer/UEAuthentication.go:217 free5gc/src/ausf/ausf_producer.HandleUeAuthPostRequest() Use 5G AKA auth method                        AUSF=UeAuthPost
INFO[2020-06-15T22:56:16Z]/root/go/src/free5gc/src/ausf/ausf_producer/UEAuthentication.go:226 free5gc/src/ausf/ausf_producer.HandleUeAuthPostRequest() XresStar = 3136306531346364356333396266303432643662616136393539663034666434  AUSF=5gAkaComfirm
resp:  &{map[Location:[https://127.0.0.1:29509/nausf-auth/v1/ue-authentications/suci-0-208-93-0-0-0-00007487]] 201 {5G_AKA {1b234b577a0ac0a443b8c8266f10cdf6 bdc86f653db7e90da7040d2627e304a9 9fe026a7640480009f9bb02926252829} map[link:{https://127.0.0.1:29509/nausf-auth/v1/ue-authentications/suci-0-208-93-0-0-0-00007487/5g-aka-confirmation}] 5G:mnc093.mcc208.3gppnetwork.org}}
[GIN] 2020/06/15 - 22:56:16 | 201 |  156.776741ms |       127.0.0.1 | POST     /nausf-auth/v1/ue-authentications
INFO[2020-06-15T22:56:16Z]/root/go/src/free5gc/src/amf/gmm/gmm_message/send.go:65 free5gc/src/amf/gmm/gmm_message.SendAuthenticationRequest() [NAS] Send Authentication Request[Retry: 0]   AMF=Gmm
INFO[2020-06-15T22:56:16Z]/root/go/src/free5gc/src/amf/amf_ngap/ngap_message/send.go:138 free5gc/src/amf/amf_ngap/ngap_message.SendDownlinkNasTransport() [AMF] Send Downlink Nas Transport             AMF=NGAP
INFO[2020-06-15T22:56:16Z]/root/go/src/free5gc/src/amf/amf_ngap/ngap_handler/handler.go:185 free5gc/src/amf/amf_ngap/ngap_handler.HandleUplinkNasTransport() [AMF] Uplink Nas Transport                    AMF=NGAP
INFO[2020-06-15T22:56:16Z]/root/go/src/free5gc/src/amf/gmm/gmm_handler/handler.go:1778 free5gc/src/amf/gmm/gmm_handler.HandleAuthenticationResponse() [AMF] Handle Authentication Response          AMF=Gmm
INFO[2020-06-15T22:56:16Z]/root/go/src/free5gc/src/ausf/ausf_producer/UEAuthentication.go:139 free5gc/src/ausf/ausf_producer.HandleAuth5gAkaComfirmRequest() res*: 3136306531346364356333396266303432643662616136393539663034666434
Xres*: 3136306531346364356333396266303432643662616136393539663034666434  AUSF=5gAkaComfirm
INFO[2020-06-15T22:56:16Z]/root/go/src/free5gc/src/ausf/ausf_producer/UEAuthentication.go:144 free5gc/src/ausf/ausf_producer.HandleAuth5gAkaComfirmRequest() 5G AKA confirmation succeeded                 AUSF=5gAkaComfirm
WARN[2020-06-15T22:56:16Z]/root/go/src/free5gc/src/udm/udm_producer/ue_context_management.go:31 free5gc/src/udm/udm_producer.createUDMClientToUDR() Use default UDR Uri bacause ID[suci-0-208-93-0-0-0-00007487] does not match any UDR  UDM=Handler
[GIN] 2020/06/15 - 22:56:16 | 204 |    2.849695ms |       127.0.0.1 | PUT      /nudr-dr/v1/subscription-data/suci-0-208-93-0-0-0-00007487/authentication-data/authentication-status
[GIN] 2020/06/15 - 22:56:16 | 201 |   14.173756ms |       127.0.0.1 | POST     /nudm-ueau/v1/suci-0-208-93-0-0-0-00007487/auth-events
resp:  &{map[] 200 {AUTHENTICATION_SUCCESS imsi-2089300007487 125dff29dbed0301acb73d733be849bb2bec7339df1df8062415e1e43077c233}}
[GIN] 2020/06/15 - 22:56:16 | 200 |   28.816573ms |       127.0.0.1 | PUT      /nausf-auth/v1/ue-authentications/suci-0-208-93-0-0-0-00007487/5g-aka-confirmation
INFO[2020-06-15T22:56:16Z]/root/go/src/free5gc/src/amf/gmm/gmm_message/send.go:165 free5gc/src/amf/gmm/gmm_message.SendSecurityModeCommand() [NAS] Send Security Mode Command              AMF=Gmm
INFO[2020-06-15T22:56:16Z]/root/go/src/free5gc/src/amf/amf_nas/nas_security/security.go:38 free5gc/src/amf/amf_nas/nas_security.Encode() NasPdu Security: Integrity Protected With New 5gNasSecurityContext  AMF=NAS
INFO[2020-06-15T22:56:16Z]/root/go/src/free5gc/src/amf/amf_ngap/ngap_message/send.go:138 free5gc/src/amf/amf_ngap/ngap_message.SendDownlinkNasTransport() [AMF] Send Downlink Nas Transport             AMF=NGAP
NasPdu Security: Integrity Protected And Ciphered
INFO[2020-06-15T22:56:16Z]/root/go/src/free5gc/src/amf/amf_ngap/ngap_handler/handler.go:185 free5gc/src/amf/amf_ngap/ngap_handler.HandleUplinkNasTransport() [AMF] Uplink Nas Transport                    AMF=NGAP
INFO[2020-06-15T22:56:16Z]/root/go/src/free5gc/src/amf/gmm/gmm_handler/handler.go:1982 free5gc/src/amf/gmm/gmm_handler.HandleSecurityModeComplete() [AMF] Handle Security Mode Complete           AMF=Gmm
INFO[2020-06-15T22:56:16Z]/root/go/src/free5gc/src/amf/gmm/gmm_handler/handler.go:492 free5gc/src/amf/gmm/gmm_handler.HandleRegistrationRequest() [AMF] Handle Registration Request             AMF=Gmm
2020/06/15 22:56:16 map[$and:[map[nfType:UDM] map[$or:[map[allowedNfTypes:AMF] map[allowedNfTypes:map[$exists:false]]]] map[$or:[map[udmInfo.supiRanges:map[$elemMatch:map[end:map[$gte:2089300007487] start:map[$lte:2089300007487]]]] map[udmInfo.externalGroupIdentifiersRanges:map[$exists:false] udmInfo.gpsiRanges:map[$exists:false] udmInfo.supiRanges:map[$exists:false]]]]]]
[GIN] 2020/06/15 - 22:56:16 | 200 |   58.449509ms |       127.0.0.1 | GET      /nnrf-disc/v1/nf-instances?requester-nf-type=AMF&supi=imsi-2089300007487&target-nf-type=UDM
[GIN] 2020/06/15 - 22:56:16 | 200 |    2.952903ms |       127.0.0.1 | GET      /nudr-dr/v1/subscription-data/imsi-2089300007487/20893/provisioned-data/am-data?supported-features=
[GIN] 2020/06/15 - 22:56:16 | 200 |   13.392685ms |       127.0.0.1 | GET      /nudm-sdm/v1/imsi-2089300007487/nssai?plmn-id=20893
2020/06/15 22:56:16 map[$and:[map[nfType:UDM] map[$or:[map[allowedNfTypes:AMF] map[allowedNfTypes:map[$exists:false]]]] map[$or:[map[udmInfo.supiRanges:map[$elemMatch:map[end:map[$gte:2089300007487] start:map[$lte:2089300007487]]]] map[udmInfo.externalGroupIdentifiersRanges:map[$exists:false] udmInfo.gpsiRanges:map[$exists:false] udmInfo.supiRanges:map[$exists:false]]]]]]
[GIN] 2020/06/15 - 22:56:17 | 200 |   62.270302ms |       127.0.0.1 | GET      /nnrf-disc/v1/nf-instances?requester-nf-type=AMF&supi=imsi-2089300007487&target-nf-type=UDM
https://127.0.0.1:29510
2020/06/15 22:56:17 map[$and:[map[nfType:UDR] map[$or:[map[allowedNfTypes:UDM] map[allowedNfTypes:map[$exists:false]]]]]]
[GIN] 2020/06/15 - 22:56:17 | 200 |   58.766395ms |       127.0.0.1 | GET      /nnrf-disc/v1/nf-instances?requester-nf-type=UDM&target-nf-type=UDR
[GIN] 2020/06/15 - 22:56:17 | 204 |    4.194217ms |       127.0.0.1 | PUT      /nudr-dr/v1/subscription-data/imsi-2089300007487/context-data/amf-3gpp-access
[GIN] 2020/06/15 - 22:56:17 | 201 |   97.322107ms |       127.0.0.1 | PUT      /nudm-uecm/v1/imsi-2089300007487/registrations/amf-3gpp-access
[GIN] 2020/06/15 - 22:56:17 | 200 |    1.116493ms |       127.0.0.1 | GET      /nudr-dr/v1/subscription-data/imsi-2089300007487/20893/provisioned-data/am-data?supported-features=
[GIN] 2020/06/15 - 22:56:17 | 200 |    13.35325ms |       127.0.0.1 | GET      /nudm-sdm/v1/imsi-2089300007487/am-data?plmn-id=20893
https://127.0.0.1:29510
2020/06/15 22:56:17 map[$and:[map[nfType:UDR] map[$or:[map[allowedNfTypes:UDM] map[allowedNfTypes:map[$exists:false]]]]]]
[GIN] 2020/06/15 - 22:56:17 | 200 |   22.119242ms |       127.0.0.1 | GET      /nnrf-disc/v1/nf-instances?requester-nf-type=UDM&target-nf-type=UDR
[GIN] 2020/06/15 - 22:56:17 | 200 |     1.82593ms |       127.0.0.1 | GET      /nudr-dr/v1/subscription-data/imsi-2089300007487/20893/provisioned-data/smf-selection-subscription-data?supported-features=
[GIN] 2020/06/15 - 22:56:17 | 200 |   50.460822ms |       127.0.0.1 | GET      /nudm-sdm/v1/imsi-2089300007487/smf-select-data?plmn-id=20893
https://127.0.0.1:29510
2020/06/15 22:56:17 map[$and:[map[nfType:UDR] map[$or:[map[allowedNfTypes:UDM] map[allowedNfTypes:map[$exists:false]]]]]]
[GIN] 2020/06/15 - 22:56:17 | 200 |   19.641793ms |       127.0.0.1 | GET      /nnrf-disc/v1/nf-instances?requester-nf-type=UDM&target-nf-type=UDR
[GIN] 2020/06/15 - 22:56:17 | 200 |     755.298µs |       127.0.0.1 | GET      /nudr-dr/v1/subscription-data/imsi-2089300007487/context-data/smf-registrations?supported-features=
[GIN] 2020/06/15 - 22:56:17 | 200 |   48.317515ms |       127.0.0.1 | GET      /nudm-sdm/v1/imsi-2089300007487/ue-context-in-smf-data
https://127.0.0.1:29510
2020/06/15 22:56:17 map[$and:[map[nfType:UDR] map[$or:[map[allowedNfTypes:UDM] map[allowedNfTypes:map[$exists:false]]]]]]
[GIN] 2020/06/15 - 22:56:17 | 200 |    19.27153ms |       127.0.0.1 | GET      /nnrf-disc/v1/nf-instances?requester-nf-type=UDM&target-nf-type=UDR
[GIN] 2020/06/15 - 22:56:17 | 201 |      127.41µs |       127.0.0.1 | POST     /nudr-dr/v1/subscription-data/imsi-2089300007487/context-data/sdm-subscriptions
[GIN] 2020/06/15 - 22:56:17 | 201 |   44.242939ms |       127.0.0.1 | POST     /nudm-sdm/v1/imsi-2089300007487/sdm-subscriptions
2020/06/15 22:56:17 map[$and:[map[nfType:PCF] map[$or:[map[allowedNfTypes:AMF] map[allowedNfTypes:map[$exists:false]]]] map[$or:[map[pcfInfo.supiRanges:map[$elemMatch:map[end:map[$gte:2089300007487] start:map[$lte:2089300007487]]]] map[pcfInfo.supiRanges:map[$exists:false]]]]]]
[GIN] 2020/06/15 - 22:56:17 | 200 |   60.898202ms |       127.0.0.1 | GET      /nnrf-disc/v1/nf-instances?requester-nf-type=AMF&supi=imsi-2089300007487&target-nf-type=PCF
2020/06/15 22:56:17 map[$and:[map[nfType:UDR] map[$or:[map[allowedNfTypes:PCF] map[allowedNfTypes:map[$exists:false]]]]]]
[GIN] 2020/06/15 - 22:56:17 | 200 |   20.799913ms |       127.0.0.1 | GET      /nnrf-disc/v1/nf-instances?requester-nf-type=PCF&target-nf-type=UDR
[GIN] 2020/06/15 - 22:56:17 | 200 |    2.135224ms |       127.0.0.1 | GET      /nudr-dr/v1/policy-data/ues/imsi-2089300007487/am-data
[GIN] 2020/06/15 - 22:56:17 | 201 |   50.950192ms |       127.0.0.1 | POST     /npcf-am-policy-control/v1/policies
INFO[2020-06-15T22:56:17Z]/root/go/src/free5gc/src/amf/gmm/gmm_message/send.go:211 free5gc/src/amf/gmm/gmm_message.SendRegistrationAccept() [NAS] Send Registration Accept                AMF=Gmm
INFO[2020-06-15T22:56:17Z]/root/go/src/free5gc/src/amf/amf_nas/nas_security/security.go:34 free5gc/src/amf/amf_nas/nas_security.Encode() NasPdu Security: Integrity Protected And Ciphered  AMF=NAS
INFO[2020-06-15T22:56:17Z]/root/go/src/free5gc/src/amf/amf_ngap/ngap_message/send.go:346 free5gc/src/amf/amf_ngap/ngap_message.SendInitialContextSetupRequest() [AMF] Send Initial Context Setup Request      AMF=NGAP
NasPdu Security: Integrity Protected And Ciphered
INFO[2020-06-15T22:56:17Z]/root/go/src/free5gc/src/amf/amf_ngap/ngap_handler/handler.go:1615 free5gc/src/amf/amf_ngap/ngap_handler.HandleInitialContextSetupResponse() [AMF] Initial Context Setup Response          AMF=NGAP
NasPdu Security: Integrity Protected And Ciphered
INFO[2020-06-15T22:56:17Z]/root/go/src/free5gc/src/amf/amf_ngap/ngap_handler/handler.go:185 free5gc/src/amf/amf_ngap/ngap_handler.HandleUplinkNasTransport() [AMF] Uplink Nas Transport                    AMF=NGAP
INFO[2020-06-15T22:56:17Z]/root/go/src/free5gc/src/amf/gmm/gmm_handler/handler.go:1957 free5gc/src/amf/gmm/gmm_handler.HandleRegistrationComplete() [AMF] Handle Registration Complete            AMF=Gmm
INFO[2020-06-15T22:56:17Z]/root/go/src/free5gc/src/amf/amf_ngap/ngap_handler/handler.go:185 free5gc/src/amf/amf_ngap/ngap_handler.HandleUplinkNasTransport() [AMF] Uplink Nas Transport                    AMF=NGAP
2020/06/15 22:56:17 map[$and:[map[nfType:NSSF] map[$or:[map[allowedNfTypes:AMF] map[allowedNfTypes:map[$exists:false]]]]]]
[GIN] 2020/06/15 - 22:56:17 | 200 |   27.603178ms |       127.0.0.1 | GET      /nnrf-disc/v1/nf-instances?requester-nf-type=AMF&target-nf-type=NSSF
INFO[2020-06-15T22:56:17Z] Request received - NSSelectionGet             NSSF=nsselection
[GIN] 2020/06/15 - 22:56:17 | 200 |     439.524µs |       127.0.0.1 | GET      /nnssf-nsselection/v1/network-slice-information?nf-id=4287072c-5c69-4c85-8f3a-0d88c61fcf0c&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
2020/06/15 22:56:17 {"mcc":"208","mnc":"93"}
2020/06/15 22:56:17 map[$and:[map[nfType:SMF] map[$or:[map[allowedNfTypes:AMF] map[allowedNfTypes:map[$exists:false]]]] map[nfServices:map[$elemMatch:map[nfServiceStatus:REGISTERED serviceName:map[$in:[nsmf-pdusession]]]]] map[$or:[map[plmnList:map[$elemMatch:map[mcc:208 mnc:93]]]]] map[$or:[map[sNssais:map[$elemMatch:map[sd:010203 sst:1]]] map[sNssais:map[$exists:false]]]] map[smfInfo.sNssaiSmfInfoList:map[$elemMatch:map[dnnSmfInfoList:map[$elemMatch:map[dnn:internet]]]]]]]
[GIN] 2020/06/15 - 22:56:17 | 200 |   43.769544ms |       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
INFO[2020-06-15T22:56:17Z]/root/go/src/free5gc/src/smf/PDUSession/api_sm_contexts_collection.go:25 free5gc/src/smf/PDUSession.PostSmContexts() Recieve Create SM Context Request             SMF=pdu_session
2020/06/15 22:56:17 map[$and:[map[nfType:UDM] map[$or:[map[allowedNfTypes:SMF] map[allowedNfTypes:map[$exists:false]]]]]]
[GIN] 2020/06/15 - 22:56:17 | 200 |   62.473167ms |       127.0.0.1 | GET      /nnrf-disc/v1/nf-instances?requester-nf-type=SMF&target-nf-type=UDM
INFO[2020-06-15T22:56:17Z]/root/go/src/free5gc/src/udm/udm_producer/subscriber_data_management.go:298 free5gc/src/udm/udm_producer.HandleGetSmData() HandleGetSmData SUPI[imsi-2089300007487] PLMNID[20893] DNN[internet] SNssai[{"sst":1,"sd":"010203"}]  UDM=Handler
[GIN] 2020/06/15 - 22:56:17 | 200 |    1.407498ms |       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
[GIN] 2020/06/15 - 22:56:17 | 200 |   13.477363ms |       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
INFO[2020-06-15T22:56:17Z]/root/go/src/free5gc/src/smf/smf_producer/pdu_session.go:84 free5gc/src/smf/smf_producer.HandlePDUSessionSMContextCreate() PCF Selection for SMContext SUPI[imsi-2089300007487] PDUSessionID[10]  SMF=pdu_session
2020/06/15 22:56:17 map[$and:[map[nfType:PCF] map[$or:[map[allowedNfTypes:SMF] map[allowedNfTypes:map[$exists:false]]]]]]
[GIN] 2020/06/15 - 22:56:18 | 200 |   156.33843ms |       127.0.0.1 | GET      /nnrf-disc/v1/nf-instances?requester-nf-type=SMF&target-nf-type=PCF
[GIN] 2020/06/15 - 22:56:18 | 200 |    1.727546ms |       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
[GIN] 2020/06/15 - 22:56:18 | 201 |   14.279208ms |       127.0.0.1 | POST     /npcf-smpolicycontrol/v1/sm-policies
calculate  10.0.200.101:8805
In GenerateDataPath
curDataPathNode IP:  10.0.200.101
Is anchor point:  true
DestEndPoint TEID 2
SrcEndPoint TEID 1
[GIN] 2020/06/15 - 22:56:18 | 201 |  316.022286ms |       127.0.0.1 | POST     /nsmf-pdusession/v1/sm-contexts
INFO[2020-06-15T22:56:18Z] c/upf/src/n4/n4_dispatcher.c:120 UpfDispatcher() [PFCP] Handle PFCP session establishment request  UPF=Util
INFO[2020-06-15T22:56:18Z]/root/go/src/free5gc/src/amf/gmm/gmm_handler/handler.go:197 free5gc/src/amf/gmm/gmm_handler.HandlePDUSessionEstablishmentRequest() smconetxt ef : urn:uuid:a6f2361b-b6c0-4ed7-95ea-9e33e65b5211  AMF=Gmm
INFO[2020-06-15T22:56:18Z]/root/go/src/free5gc/src/amf/gmm/gmm_handler/handler.go:204 free5gc/src/amf/gmm/gmm_handler.HandlePDUSessionEstablishmentRequest() Http create smContext[pduSessionID: 10] Success  AMF=Gmm
INFO[2020-06-15T22:56:18Z] c/upf/src/n4/n4_pfcp_handler.c:699 UpfN4HandleSessionEstablishmentRequest() [PFCP] Session Establishment Response  UPF=Util
2020/06/15 22:56:18 map[$and:[map[nfType:AMF] map[$or:[map[allowedNfTypes:SMF] map[allowedNfTypes:map[$exists:false]]]] map[nfInstanceId:4287072c-5c69-4c85-8f3a-0d88c61fcf0c]]]
[GIN] 2020/06/15 - 22:56:18 | 200 |    3.376032ms |       127.0.0.1 | GET      /nnrf-disc/v1/nf-instances?requester-nf-type=SMF&target-nf-instance-id=4287072c-5c69-4c85-8f3a-0d88c61fcf0c&target-nf-type=AMF
INFO[2020-06-15T22:56:18Z]/root/go/src/free5gc/src/smf/smf_consumer/Nnrf.go:170 free5gc/src/smf/smf_consumer.SendNFDiscoveryServingAMF() SendNFDiscoveryServingAMF ok                  SMF=app
&{1000 Kbps 1000 Kbps}
INFO[2020-06-15T22:56:18Z]/root/go/src/free5gc/src/amf/amf_nas/nas_security/security.go:34 free5gc/src/amf/amf_nas/nas_security.Encode() NasPdu Security: Integrity Protected And Ciphered  AMF=NAS
INFO[2020-06-15T22:56:18Z]/root/go/src/free5gc/src/amf/amf_ngap/ngap_message/send.go:260 free5gc/src/amf/amf_ngap/ngap_message.SendPDUSessionResourceSetupRequest() [AMF] Send PDU Session Resource Setup Request  AMF=NGAP
[GIN] 2020/06/15 - 22:56:18 | 200 |    2.971718ms |       127.0.0.1 | POST     /namf-comm/v1/ue-contexts/imsi-2089300007487/n1-n2-messages
INFO[2020-06-15T22:56:18Z]/root/go/src/free5gc/src/amf/amf_ngap/ngap_handler/handler.go:1059 free5gc/src/amf/amf_ngap/ngap_handler.HandlePDUSessionResourceSetupResponse() [AMF] PDU Session Resource Setup Response     AMF=NGAP
INFO[2020-06-15T22:56:18Z]/root/go/src/free5gc/src/smf/PDUSession/api_individual_sm_context.go:62 free5gc/src/smf/PDUSession.UpdateSmContext() Recieve Update SM Context Request             SMF=pdu_session
INFO[2020-06-15T22:56:18Z] c/upf/src/n4/n4_dispatcher.c:125 UpfDispatcher() [PFCP] Handle PFCP session modification request  UPF=Util
INFO[2020-06-15T22:56:18Z]/root/go/src/free5gc/src/smf/smf_pfcp/pfcp_handler/handler.go:230 free5gc/src/smf/smf_pfcp/pfcp_handler.HandlePfcpSessionModificationResponse() PFCP Session Modification Success[1]          SMF=pfcp
[GIN] 2020/06/15 - 22:56:18 | 200 |    4.532743ms |       127.0.0.1 | POST     /nsmf-pdusession/v1/sm-contexts/urn:uuid:a6f2361b-b6c0-4ed7-95ea-9e33e65b5211/modify
INFO[2020-06-15T22:56:18Z] c/upf/src/n4/n4_pfcp_handler.c:789 UpfN4HandleSessionModificationRequest() [PFCP] Session Modification Response  UPF=Util
**--- PASS: TestRegistration (2.65s)**
**PASS**
****ok      free5gc/src/test        4.456s****
INFO[2020-06-15T22:56:20Z] c/upf/src/upf_init.c:85 UpfTerminate() Terminating UPF...  UPF=Util
INFO[2020-06-15T22:56:20Z] c/upf/src/up/up_path.c:52 UpRouteTerminate() Removing APN routes  UPF=Util
INFO[2020-06-15T22:56:20Z] c/upf/src/upf_init.c:225 PacketReceiverThread() Packet receiver thread terminated  UPF=Util
INFO[2020-06-15T22:56:20Z] c/upf/src/upf_init.c:117 UpfTerminate() UPF terminated  UPF=Util

.
.
… root@free5g:~/go/src/free5gc# tcpdump -D
1.ip_vti0 [Up, Running]
2.veth0 [Up, Running]
3.ens3 [Up, Running]
4.ens4 [Up, Running]
5.any (Pseudo-device that captures on all interfaces) [Up, Running]
6.lo [Up, Running, Loopback]
7.nflog (Linux netfilter log (NFLOG) interface)
8.nfqueue (Linux netfilter queue (NFQUEUE) interface)
9.usbmon1 (USB bus number 1)
…
My Question is why I can not found https2 protocol in pcap file when I run NRF and AMF ?

#2

and i use tcpdump -i any -s0 -w /tmp/test_register.pcap

#3

Which free5GC version are you using? Have you modify any config when running?

#4

As we capture the SBI in wireshark, you may either filtering https for TLS version of SBI or http2 as H2C version of SBI

#5

I used the stage 2 Installation
so I just modified the private ip address in 10.200 to 10.0.0.0/16 range in config files.
And I replaced git.cs.nctu.edu.tw/calee/sctp with github.com/ishidawataru/sctp

#6

test_register.pcap (614.7 KB)

#7
#8

thank you so much. it work now