smartedge@ubuntu-18043:~/free5gc/bin$ smartedge@ubuntu-18043:~/free5gc/bin$ smartedge@ubuntu-18043:~/free5gc/bin$ smartedge@ubuntu-18043:~/free5gc/bin$ smartedge@ubuntu-18043:~/free5gc/bin$ smartedge@ubuntu-18043:~/free5gc/bin$ INFO[2020-09-21T07:40:01Z]/src/amf/ngap/sctp/sctp.go:42 free5gc/src/amf/ngap/sctp.Server.func1() [AMF] NGAP SCTP Accept from: 192.168.216.2:9487 AMF=NGAP INFO[2020-09-21T07:40:01Z]/src/amf/ngap/handler.go:49 free5gc/src/amf/ngap.HandleNGSetupRequest() [AMF] NG Setup request AMF=NGAP INFO[2020-09-21T07:40:01Z]/src/amf/ngap/message/send.go:79 free5gc/src/amf/ngap/message.SendNGSetupResponse() [AMF] Send NG-Setup response AMF=NGAP INFO[2020-09-21T07:40:02Z]/src/amf/ngap/handler.go:882 free5gc/src/amf/ngap.HandleInitialUEMessage() [AMF] Initial UE Message AMF=NGAP INFO[2020-09-21T07:40:02Z]/src/amf/gmm/handler.go:528 free5gc/src/amf/gmm.HandleRegistrationRequest() [AMF] Handle Registration Request AMF=Gmm INFO[2020-09-21T07:40:02Z]/src/amf/gmm/handler.go:1644 free5gc/src/amf/gmm.startAuthenticationProcedure() Start authentication procedure AMF=Gmm 2020/09/21 07:40:02 map[$and:[map[nfType:AUSF] map[$or:[map[allowedNfTypes:AMF] map[allowedNfTypes:map[$exists:false]]]]]] [GIN] 2020/09/21 - 07:40:02 | 200 | 1.940316ms | 127.0.0.1 | GET /nnrf-disc/v1/nf-instances?requester-nf-type=AMF&target-nf-type=AUSF INFO[2020-09-21T07:40:02Z]/src/ausf/producer/ue_authentication.go:64 free5gc/src/ausf/producer.HandleUeAuthPostRequest() HandleUeAuthPostRequest AUSF=UeAuthPost INFO[2020-09-21T07:40:02Z]/src/ausf/producer/ue_authentication.go:96 free5gc/src/ausf/producer.UeAuthPostRequestProcedure() Serving network authorized AUSF=UeAuthPost 2020/09/21 07:40:02 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/09/21 - 07:40:02 | 200 | 8.544594ms | 127.0.0.1 | GET /nnrf-disc/v1/nf-instances?requester-nf-type=AUSF&service-names=nudm-ueau&target-nf-type=UDM INFO[2020-09-21T07:40:02Z]/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-09-21T07:40:02Z]/lib/util_3gpp/suci/toSupi.go:278 free5gc/lib/util_3gpp/suci.ToSupi() scheme 0 UDM=UEAU INFO[2020-09-21T07:40:02Z]/lib/util_3gpp/suci/toSupi.go:285 free5gc/lib/util_3gpp/suci.ToSupi() SUPI type is IMSI UDM=UEAU INFO[2020-09-21T07:40:02Z]/src/udm/producer/generate_auth_data.go:35 free5gc/src/udm/producer.HandleGenerateAuthData() supi conversion => imsi-2089300007487 UDM=UEAU [GIN] 2020/09/21 - 07:40:02 | 200 | 913.847µs | 127.0.0.1 | GET /nudr-dr/v1/subscription-data/imsi-2089300007487/authentication-data/authentication-subscription [GIN] 2020/09/21 - 07:40:02 | 200 | 2.361865ms | 127.0.0.1 | POST /nudm-ueau/v1/suci-0-208-93-0-0-0-00007487/security-information/generate-auth-data INFO[2020-09-21T07:40:02Z]/src/ausf/producer/ue_authentication.go:124 free5gc/src/ausf/producer.UeAuthPostRequestProcedure() Add SuciSupiPair (suci-0-208-93-0-0-0-00007487, imsi-2089300007487) to map. AUSF=UeAuthPost INFO[2020-09-21T07:40:02Z]/src/ausf/producer/ue_authentication.go:130 free5gc/src/ausf/producer.UeAuthPostRequestProcedure() Use 5G AKA auth method AUSF=UeAuthPost INFO[2020-09-21T07:40:02Z]/src/ausf/producer/ue_authentication.go:138 free5gc/src/ausf/producer.UeAuthPostRequestProcedure() XresStar = 6664666139393164613565393539363438653166663165313332626165323865 AUSF=5gAkaComfirm [GIN] 2020/09/21 - 07:40:02 | 201 | 14.083894ms | 127.0.0.1 | POST /nausf-auth/v1/ue-authentications INFO[2020-09-21T07:40:02Z]/src/amf/gmm/message/send.go:65 free5gc/src/amf/gmm/message.SendAuthenticationRequest() [NAS] Send Authentication Request[Retry: 0] AMF=Gmm INFO[2020-09-21T07:40:02Z]/src/amf/ngap/message/send.go:138 free5gc/src/amf/ngap/message.SendDownlinkNasTransport() [AMF] Send Downlink Nas Transport AMF=NGAP INFO[2020-09-21T07:40:02Z]/src/amf/ngap/handler.go:180 free5gc/src/amf/ngap.HandleUplinkNasTransport() [AMF] Uplink Nas Transport AMF=NGAP INFO[2020-09-21T07:40:02Z]/src/amf/gmm/handler.go:1957 free5gc/src/amf/gmm.HandleAuthenticationResponse() [AMF] Handle Authentication Response AMF=Gmm INFO[2020-09-21T07:40:02Z]/src/ausf/producer/ue_authentication.go:46 free5gc/src/ausf/producer.HandleAuth5gAkaComfirmRequest() Auth5gAkaComfirmRequest AUSF=5gAkaComfirm INFO[2020-09-21T07:40:02Z]/src/ausf/producer/ue_authentication.go:241 free5gc/src/ausf/producer.Auth5gAkaComfirmRequestProcedure() res*: 6664666139393164613565393539363438653166663165313332626165323865 Xres*: 6664666139393164613565393539363438653166663165313332626165323865 AUSF=5gAkaComfirm INFO[2020-09-21T07:40:02Z]/src/ausf/producer/ue_authentication.go:246 free5gc/src/ausf/producer.Auth5gAkaComfirmRequestProcedure() 5G AKA confirmation succeeded AUSF=5gAkaComfirm [GIN] 2020/09/21 - 07:40:02 | 204 | 1.249723ms | 127.0.0.1 | PUT /nudr-dr/v1/subscription-data/imsi-2089300007487/authentication-data/authentication-status [GIN] 2020/09/21 - 07:40:02 | 201 | 2.450998ms | 127.0.0.1 | POST /nudm-ueau/v1/imsi-2089300007487/auth-events [GIN] 2020/09/21 - 07:40:02 | 200 | 3.692529ms | 127.0.0.1 | PUT /nausf-auth/v1/ue-authentications/suci-0-208-93-0-0-0-00007487/5g-aka-confirmation INFO[2020-09-21T07:40:02Z]/src/amf/gmm/message/send.go:165 free5gc/src/amf/gmm/message.SendSecurityModeCommand() [NAS] Send Security Mode Command AMF=Gmm INFO[2020-09-21T07:40:02Z]/src/amf/ngap/message/send.go:138 free5gc/src/amf/ngap/message.SendDownlinkNasTransport() [AMF] Send Downlink Nas Transport AMF=NGAP INFO[2020-09-21T07:40:02Z]/src/amf/ngap/handler.go:180 free5gc/src/amf/ngap.HandleUplinkNasTransport() [AMF] Uplink Nas Transport AMF=NGAP INFO[2020-09-21T07:40:02Z]/src/amf/gmm/handler.go:2162 free5gc/src/amf/gmm.HandleSecurityModeComplete() [AMF] Handle Security Mode Complete AMF=Gmm INFO[2020-09-21T07:40:02Z]/src/amf/gmm/handler.go:528 free5gc/src/amf/gmm.HandleRegistrationRequest() [AMF] Handle Registration Request AMF=Gmm 2020/09/21 07:40:02 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/09/21 - 07:40:02 | 200 | 8.573204ms | 127.0.0.1 | GET /nnrf-disc/v1/nf-instances?requester-nf-type=AMF&supi=imsi-2089300007487&target-nf-type=UDM INFO[2020-09-21T07:40:02Z]/src/udr/producer/data_repository.go:40 free5gc/src/udr/producer.HandleQueryAmData() HandleQueryAmData UDR=DataRepo [GIN] 2020/09/21 - 07:40:02 | 404 | 691.376µs | 127.0.0.1 | GET /nudr-dr/v1/subscription-data/imsi-2089300007487/20893/provisioned-data/am-data?supported-features= [GIN] 2020/09/21 - 07:40:02 | 404 | 1.480472ms | 127.0.0.1 | GET /nudm-sdm/v1/imsi-2089300007487/nssai?plmn-id=20893 ERRO[2020-09-21T07:40:02Z]/src/amf/gmm/handler.go:1343 free5gc/src/amf/gmm.getSubscribedNssai() SDM_Get Slice Selection Subscription Data Failed Problem[&{Type: Title: Status:0 Detail: Instance: Cause:USER_NOT_FOUND InvalidParams:[]}] AMF=Gmm 2020/09/21 07:40:02 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/09/21 - 07:40:02 | 200 | 9.629059ms | 127.0.0.1 | GET /nnrf-disc/v1/nf-instances?requester-nf-type=AMF&supi=imsi-2089300007487&target-nf-type=UDM [GIN] 2020/09/21 - 07:40:02 | 204 | 1.251471ms | 127.0.0.1 | PUT /nudr-dr/v1/subscription-data/imsi-2089300007487/context-data/amf-3gpp-access [GIN] 2020/09/21 - 07:40:02 | 201 | 2.317218ms | 127.0.0.1 | PUT /nudm-uecm/v1/imsi-2089300007487/registrations/amf-3gpp-access INFO[2020-09-21T07:40:02Z]/src/udr/producer/data_repository.go:40 free5gc/src/udr/producer.HandleQueryAmData() HandleQueryAmData UDR=DataRepo [GIN] 2020/09/21 - 07:40:02 | 404 | 734.072µs | 127.0.0.1 | GET /nudr-dr/v1/subscription-data/imsi-2089300007487/20893/provisioned-data/am-data?supported-features= [GIN] 2020/09/21 - 07:40:02 | 404 | 1.23327ms | 127.0.0.1 | GET /nudm-sdm/v1/imsi-2089300007487/am-data?plmn-id=20893 ERRO[2020-09-21T07:40:02Z]/src/amf/gmm/handler.go:852 free5gc/src/amf/gmm.HandleInitialRegistration() SDM_Get AmData Failed Problem[&{Type: Title: Status:0 Detail: Instance: Cause:USER_NOT_FOUND InvalidParams:[]}] AMF=Gmm [GIN] 2020/09/21 - 07:40:02 | 404 | 521.669µs | 127.0.0.1 | GET /nudr-dr/v1/subscription-data/imsi-2089300007487/20893/provisioned-data/smf-selection-subscription-data?supported-features= [GIN] 2020/09/21 - 07:40:02 | 404 | 913.984µs | 127.0.0.1 | GET /nudm-sdm/v1/imsi-2089300007487/smf-select-data?plmn-id=20893 ERRO[2020-09-21T07:40:02Z]/src/amf/gmm/handler.go:859 free5gc/src/amf/gmm.HandleInitialRegistration() SDM_Get SmfSelectData Failed Problem[&{Type: Title: Status:0 Detail: Instance: Cause:USER_NOT_FOUND InvalidParams:[]}] AMF=Gmm [GIN] 2020/09/21 - 07:40:02 | 200 | 483.301µs | 127.0.0.1 | GET /nudr-dr/v1/subscription-data/imsi-2089300007487/context-data/smf-registrations?supported-features= [GIN] 2020/09/21 - 07:40:02 | 200 | 891.293µs | 127.0.0.1 | GET /nudm-sdm/v1/imsi-2089300007487/ue-context-in-smf-data http://127.0.0.1:29510 2020/09/21 07:40:02 map[$and:[map[nfType:UDR] map[$or:[map[allowedNfTypes:UDM] map[allowedNfTypes:map[$exists:false]]]]]] [GIN] 2020/09/21 - 07:40:02 | 200 | 2.421031ms | 127.0.0.1 | GET /nnrf-disc/v1/nf-instances?requester-nf-type=UDM&target-nf-type=UDR [GIN] 2020/09/21 - 07:40:02 | 201 | 167.954µs | 127.0.0.1 | POST /nudr-dr/v1/subscription-data/imsi-2089300007487/context-data/sdm-subscriptions [GIN] 2020/09/21 - 07:40:02 | 201 | 4.817255ms | 127.0.0.1 | POST /nudm-sdm/v1/imsi-2089300007487/sdm-subscriptions 2020/09/21 07:40:02 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/09/21 - 07:40:02 | 200 | 3.54076ms | 127.0.0.1 | GET /nnrf-disc/v1/nf-instances?requester-nf-type=AMF&supi=imsi-2089300007487&target-nf-type=PCF [GIN] 2020/09/21 - 07:40:02 | 200 | 588.099µs | 127.0.0.1 | GET /nudr-dr/v1/policy-data/ues/imsi-2089300007487/am-data [GIN] 2020/09/21 - 07:40:02 | 201 | 1.536111ms | 127.0.0.1 | POST /npcf-am-policy-control/v1/policies INFO[2020-09-21T07:40:02Z]/src/amf/gmm/message/send.go:211 free5gc/src/amf/gmm/message.SendRegistrationAccept() [NAS] Send Registration Accept AMF=Gmm INFO[2020-09-21T07:40:02Z]/src/amf/ngap/message/send.go:340 free5gc/src/amf/ngap/message.SendInitialContextSetupRequest() [AMF] Send Initial Context Setup Request AMF=NGAP panic: runtime error: invalid memory address or nil pointer dereference [signal SIGSEGV: segmentation violation code=0x1 addr=0x58 pc=0x969b5b] goroutine 40 [running]: free5gc/src/amf/ngap/message.BuildIEMobilityRestrictionList(0xc000a66000, 0xc000b917d8, 0x3, 0x8, 0x0, 0x0, 0x0, 0x0, 0x0) /home/smartedge/free5gc/src/amf/ngap/message/forward_ie.go:77 +0xbb free5gc/src/amf/ngap/message.BuildInitialContextSetupRequest(0xc000a66000, 0xdaa054, 0xb, 0xc000ad8b70, 0x29, 0x30, 0x0, 0x0, 0x0, 0x0, ...) /home/smartedge/free5gc/src/amf/ngap/message/build.go:1066 +0x1be3 free5gc/src/amf/ngap/message.SendInitialContextSetupRequest(0xc000a66000, 0xdaa054, 0xb, 0xc000ad8b70, 0x29, 0x30, 0x0, 0x0, 0x0, 0x0) /home/smartedge/free5gc/src/amf/ngap/message/send.go:354 +0x131 free5gc/src/amf/gmm/message.SendRegistrationAccept(0xc000a66000, 0xdaa054, 0xb, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, ...) /home/smartedge/free5gc/src/amf/gmm/message/send.go:218 +0x23c free5gc/src/amf/gmm.HandleInitialRegistration(0xc000a66000, 0xdaa054, 0xb, 0x2, 0x2) /home/smartedge/free5gc/src/amf/gmm/handler.go:951 +0x93c free5gc/src/amf/gmm.register_event_3gpp(0xc000a47e60, 0xdaa11a, 0xb, 0xc00079d770, 0xd53500, 0xc0007e46c0) /home/smartedge/free5gc/src/amf/gmm/sm.go:75 +0x432 free5gc/src/amf/gmm.Registered_3gpp(0xc000a47e60, 0xdaa11a, 0xb, 0xc00079d770, 0xc000a634e0, 0x0) /home/smartedge/free5gc/src/amf/gmm/sm.go:24 +0x49 free5gc/lib/fsm.(*FSM).SendEvent(...) /home/smartedge/free5gc/lib/fsm/fsm.go:57 free5gc/src/amf/gmm.HandleSecurityModeComplete(0xc000a66000, 0xdaa054, 0xb, 0x2e, 0xc000c2ac60, 0xc0007e4503, 0x2c00000000d535c0, 0xc00073b738) /home/smartedge/free5gc/src/amf/gmm/handler.go:2193 +0x751 free5gc/src/amf/gmm.SecurityMode_3gpp(0xc000a47e60, 0xdaa11a, 0xb, 0xc00079d680, 0xc000a634f0, 0xeeaa80) /home/smartedge/free5gc/src/amf/gmm/sm.go:116 +0x31f free5gc/lib/fsm.(*FSM).SendEvent(...) /home/smartedge/free5gc/lib/fsm/fsm.go:57 free5gc/src/amf/nas.Dispatch(0xc000a66000, 0xdaa054, 0xb, 0x2e, 0xc000c2abc0, 0x31, 0x39) /home/smartedge/free5gc/src/amf/nas/dispatch.go:19 +0x2b5 free5gc/src/amf/nas.HandleNAS(0xc000a24120, 0x2e, 0xc0007c2d80, 0x38, 0x40) /home/smartedge/free5gc/src/amf/nas/handler.go:41 +0x11a free5gc/src/amf/ngap.HandleUplinkNasTransport(0xc000a94000, 0xc0007afaa0) /home/smartedge/free5gc/src/amf/ngap/handler.go:239 +0x731 free5gc/src/amf/ngap.Dispatch(0xc000a8c040, 0x12, 0xc000b1a000, 0x63, 0x2000) /home/smartedge/free5gc/src/amf/ngap/dispatcher.go:45 +0x51a free5gc/src/amf/handler.Handle() /home/smartedge/free5gc/src/amf/handler/handler.go:35 +0x1db created by free5gc/src/amf/service.(*AMF).Start /home/smartedge/free5gc/src/amf/service/amf_init.go:150 +0x518 [10]+ Exit 2 ./amf